D0229 21:18:09.798117 5995 parameters.go:238] Clock(Monotonic): error: 189 ns, adjusted frequency from 2299774173 Hz to 2299774829 Hz D0229 21:18:09.798390 5995 parameters.go:238] Clock(Realtime): error: 154 ns, adjusted frequency from 2299774270 Hz to 2299774812 Hz D0229 21:18:09.798531 5995 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8976095231476243 monotonicBaseRef:2000250246 monotonicFrequency:2299774829 realtimeReady:1 realtimeBaseCycles:8976095232119281 realtimeBaseRef:1583011089798383434 realtimeFrequency:2299774812} D0229 21:18:10.798159 5995 parameters.go:238] Clock(Monotonic): error: 105 ns, adjusted frequency from 2299774829 Hz to 2299774724 Hz I0229 21:18:10.798253 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:18:10.798325 5995 parameters.go:238] Clock(Realtime): error: -85 ns, adjusted frequency from 2299774812 Hz to 2299774276 Hz D0229 21:18:10.798430 5995 task_signals.go:446] [ 1] Notified of signal 23 D0229 21:18:10.798424 5995 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8976097531279863 monotonicBaseRef:3000262765 monotonicFrequency:2299774724 realtimeReady:1 realtimeBaseCycles:8976097531745209 realtimeBaseRef:1583011090798318695 realtimeFrequency:2299774276} D0229 21:18:10.798537 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:18:10.798607 5995 task_block.go:223] [ 1] Interrupt queued I0229 21:18:10.798701 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:18:10.798793 5995 task_signals.go:434] [ 1] Discarding duplicate signal 23 D0229 21:18:10.798989 5995 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 21:18:10.799103 5995 task_signals.go:220] [ 1] Signal 23: delivering to handler I0229 21:18:10.869505 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:18:10.869687 5995 task_signals.go:446] [ 1] Notified of signal 23 D0229 21:18:10.869794 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:18:10.869897 5995 task_block.go:223] [ 1] Interrupt queued I0229 21:18:10.869940 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:18:10.870111 5995 task_signals.go:446] [ 1] Notified of signal 23 D0229 21:18:10.870074 5995 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 21:18:10.870233 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:18:10.870293 5995 task_signals.go:220] [ 1] Signal 23: delivering to handler D0229 21:18:10.871010 5995 task_block.go:223] [ 2] Interrupt queued D0229 21:18:10.871122 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:18:10.871188 5995 task_block.go:223] [ 2] Interrupt queued D0229 21:18:10.871442 5995 task_signals.go:176] [ 2] Not restarting syscall 270 after errno 514: interrupted by signal 23 D0229 21:18:10.871580 5995 task_signals.go:220] [ 2] Signal 23: delivering to handler I0229 21:18:11.798447 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:18:11.798325 5995 sampler.go:168] Time: Adjusting syscall overhead up to 8000 D0229 21:18:11.798626 5995 task_signals.go:446] [ 1] Notified of signal 23 D0229 21:18:11.798749 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:18:11.798764 5995 parameters.go:238] Clock(Monotonic): error: 286 ns, adjusted frequency from 2299774724 Hz to 2299775199 Hz D0229 21:18:11.798838 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:18:11.799026 5995 parameters.go:238] Clock(Realtime): error: 68 ns, adjusted frequency from 2299774276 Hz to 2299774666 Hz D0229 21:18:11.799144 5995 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 21:18:11.799186 5995 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8976099832403413 monotonicBaseRef:4000849268 monotonicFrequency:2299775199 realtimeReady:1 realtimeBaseCycles:8976099833132382 realtimeBaseRef:1583011091799020023 realtimeFrequency:2299774666} D0229 21:18:11.799365 5995 task_signals.go:220] [ 1] Signal 23: delivering to handler I0229 21:18:11.869459 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:18:11.869652 5995 task_signals.go:446] [ 1] Notified of signal 23 D0229 21:18:11.869782 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:18:11.869862 5995 task_block.go:223] [ 1] Interrupt queued I0229 21:18:11.869933 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:18:11.870038 5995 task_signals.go:446] [ 1] Notified of signal 23 D0229 21:18:11.870044 5995 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 21:18:11.870140 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:18:11.870172 5995 task_signals.go:220] [ 1] Signal 23: delivering to handler D0229 21:18:11.870653 5995 task_block.go:223] [ 2] Interrupt queued D0229 21:18:11.870715 5995 task_block.go:223] [ 2] Interrupt queued D0229 21:18:11.870761 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:18:11.870893 5995 task_signals.go:176] [ 2] Not restarting syscall 202 after errno 516: interrupted by signal 23 D0229 21:18:11.871007 5995 task_signals.go:220] [ 2] Signal 23: delivering to handler D0229 21:18:12.798149 5995 parameters.go:238] Clock(Monotonic): error: 199 ns, adjusted frequency from 2299775199 Hz to 2299774791 Hz D0229 21:18:12.798387 5995 parameters.go:238] Clock(Realtime): error: 68 ns, adjusted frequency from 2299774666 Hz to 2299774690 Hz D0229 21:18:12.798510 5995 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8976102130875503 monotonicBaseRef:5000282643 monotonicFrequency:2299774791 realtimeReady:1 realtimeBaseCycles:8976102131438862 realtimeBaseRef:1583011092798381618 realtimeFrequency:2299774690} I0229 21:18:12.869469 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:18:12.869753 5995 task_signals.go:446] [ 1] Notified of signal 23 D0229 21:18:12.869866 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:18:12.869911 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:18:12.870296 5995 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 21:18:12.871010 5995 task_signals.go:220] [ 1] Signal 23: delivering to handler D0229 21:18:13.798266 5995 parameters.go:238] Clock(Monotonic): error: 219 ns, adjusted frequency from 2299774791 Hz to 2299774927 Hz D0229 21:18:13.798467 5995 parameters.go:238] Clock(Realtime): error: 122 ns, adjusted frequency from 2299774690 Hz to 2299774839 Hz D0229 21:18:13.798623 5995 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8976104430911922 monotonicBaseRef:6000396405 monotonicFrequency:2299774927 realtimeReady:1 realtimeBaseCycles:8976104431397393 realtimeBaseRef:1583011093798461556 realtimeFrequency:2299774839} D0229 21:18:14.798222 5995 parameters.go:238] Clock(Monotonic): error: 133 ns, adjusted frequency from 2299774927 Hz to 2299774792 Hz I0229 21:18:14.798312 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:18:14.798510 5995 sampler.go:168] Time: Adjusting syscall overhead up to 8000 D0229 21:18:14.798662 5995 task_signals.go:446] [ 1] Notified of signal 23 D0229 21:18:14.798641 5995 parameters.go:238] Clock(Realtime): error: 396 ns, adjusted frequency from 2299774839 Hz to 2299775509 Hz D0229 21:18:14.798804 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:18:14.798851 5995 task_block.go:223] [ 1] Interrupt queued I0229 21:18:14.798968 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:18:14.798855 5995 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8976106730584611 monotonicBaseRef:7000351949 monotonicFrequency:2299774792 realtimeReady:1 realtimeBaseCycles:8976106731572029 realtimeBaseRef:1583011094798635397 realtimeFrequency:2299775509} D0229 21:18:14.799084 5995 task_signals.go:434] [ 1] Discarding duplicate signal 23 D0229 21:18:14.799348 5995 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 21:18:14.799508 5995 task_signals.go:220] [ 1] Signal 23: delivering to handler I0229 21:18:14.869548 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:18:14.869761 5995 task_signals.go:446] [ 1] Notified of signal 23 D0229 21:18:14.869949 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:18:14.870186 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:18:14.870538 5995 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 21:18:14.870687 5995 task_signals.go:220] [ 1] Signal 23: delivering to handler D0229 21:18:15.798156 5995 parameters.go:238] Clock(Monotonic): error: -66 ns, adjusted frequency from 2299774792 Hz to 2299774337 Hz I0229 21:18:15.798324 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:18:15.798345 5995 parameters.go:238] Clock(Realtime): error: -234 ns, adjusted frequency from 2299775509 Hz to 2299773990 Hz D0229 21:18:15.798502 5995 task_signals.go:446] [ 1] Notified of signal 23 D0229 21:18:15.798487 5995 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8976109030158576 monotonicBaseRef:8000264624 monotonicFrequency:2299774337 realtimeReady:1 realtimeBaseCycles:8976109030665340 realtimeBaseRef:1583011095798338760 realtimeFrequency:2299773990} D0229 21:18:15.798636 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:18:15.798741 5995 task_block.go:223] [ 1] Interrupt queued I0229 21:18:15.798822 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:18:15.798928 5995 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 21:18:15.798941 5995 task_signals.go:446] [ 1] Notified of signal 23 D0229 21:18:15.799037 5995 task_signals.go:220] [ 1] Signal 23: delivering to handler D0229 21:18:15.799054 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:18:15.799404 5995 task_block.go:223] [ 2] Interrupt queued D0229 21:18:15.799490 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:18:15.799522 5995 task_block.go:223] [ 2] Interrupt queued D0229 21:18:15.799685 5995 task_signals.go:176] [ 2] Not restarting syscall 202 after errno 516: interrupted by signal 23 D0229 21:18:15.799776 5995 task_signals.go:220] [ 2] Signal 23: delivering to handler I0229 21:18:15.869506 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:18:15.869841 5995 task_signals.go:446] [ 1] Notified of signal 23 D0229 21:18:15.870067 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:18:15.870204 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:18:15.870453 5995 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 21:18:15.870614 5995 task_signals.go:220] [ 1] Signal 23: delivering to handler D0229 21:18:16.798183 5995 parameters.go:238] Clock(Monotonic): error: 727 ns, adjusted frequency from 2299774337 Hz to 2299776286 Hz D0229 21:18:16.798418 5995 parameters.go:238] Clock(Realtime): error: 79 ns, adjusted frequency from 2299773990 Hz to 2299774708 Hz I0229 21:18:16.798418 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:18:16.798558 5995 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8976111330047524 monotonicBaseRef:9000314459 monotonicFrequency:2299776286 realtimeReady:1 realtimeBaseCycles:8976111330607158 realtimeBaseRef:1583011096798411735 realtimeFrequency:2299774708} D0229 21:18:16.798651 5995 task_signals.go:446] [ 1] Notified of signal 23 D0229 21:18:16.798846 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:18:16.798884 5995 task_block.go:223] [ 1] Interrupt queued I0229 21:18:16.799012 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:18:16.799111 5995 task_signals.go:446] [ 1] Notified of signal 23 D0229 21:18:16.799101 5995 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 21:18:16.799217 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:18:16.799245 5995 task_signals.go:220] [ 1] Signal 23: delivering to handler D0229 21:18:16.799677 5995 task_block.go:223] [ 2] Interrupt queued D0229 21:18:16.799801 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:18:16.799796 5995 task_block.go:223] [ 2] Interrupt queued D0229 21:18:16.800058 5995 task_signals.go:176] [ 2] Not restarting syscall 202 after errno 516: interrupted by signal 23 D0229 21:18:16.800199 5995 task_signals.go:220] [ 2] Signal 23: delivering to handler D0229 21:18:17.798099 5995 parameters.go:238] Clock(Monotonic): error: -810 ns, adjusted frequency from 2299776286 Hz to 2299772669 Hz D0229 21:18:17.798279 5995 parameters.go:238] Clock(Realtime): error: 98 ns, adjusted frequency from 2299774708 Hz to 2299774789 Hz D0229 21:18:17.798391 5995 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8976113629632668 monotonicBaseRef:10000231345 monotonicFrequency:2299772669 realtimeReady:1 realtimeBaseCycles:8976113630064554 realtimeBaseRef:1583011097798273759 realtimeFrequency:2299774789} D0229 21:18:18.798122 5995 parameters.go:238] Clock(Monotonic): error: 246 ns, adjusted frequency from 2299772669 Hz to 2299775111 Hz D0229 21:18:18.798362 5995 parameters.go:238] Clock(Realtime): error: 32 ns, adjusted frequency from 2299774789 Hz to 2299774636 Hz D0229 21:18:18.798449 5995 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8976115929462579 monotonicBaseRef:11000256235 monotonicFrequency:2299775111 realtimeReady:1 realtimeBaseCycles:8976115930030303 realtimeBaseRef:1583011098798356793 realtimeFrequency:2299774636} D0229 21:18:19.798188 5995 parameters.go:238] Clock(Monotonic): error: -188 ns, adjusted frequency from 2299775111 Hz to 2299774208 Hz D0229 21:18:19.798391 5995 parameters.go:238] Clock(Realtime): error: 73 ns, adjusted frequency from 2299774636 Hz to 2299774791 Hz I0229 21:18:19.798292 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:18:19.798510 5995 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8976118229377724 monotonicBaseRef:12000317125 monotonicFrequency:2299774208 realtimeReady:1 realtimeBaseCycles:8976118229869692 realtimeBaseRef:1583011099798384949 realtimeFrequency:2299774791} D0229 21:18:19.798612 5995 task_signals.go:446] [ 1] Notified of signal 23 D0229 21:18:19.798761 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:18:19.798888 5995 task_block.go:223] [ 1] Interrupt queued I0229 21:18:19.798997 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:18:19.799106 5995 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 21:18:19.799169 5995 task_signals.go:446] [ 1] Notified of signal 23 D0229 21:18:19.799277 5995 task_signals.go:220] [ 1] Signal 23: delivering to handler D0229 21:18:19.799306 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:18:19.799731 5995 task_block.go:223] [ 2] Interrupt queued D0229 21:18:19.799741 5995 task_block.go:223] [ 2] Interrupt queued D0229 21:18:19.799830 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:18:19.799984 5995 task_signals.go:176] [ 2] Not restarting syscall 202 after errno 516: interrupted by signal 23 D0229 21:18:19.800115 5995 task_signals.go:220] [ 2] Signal 23: delivering to handler I0229 21:18:19.869650 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:18:19.869916 5995 task_signals.go:446] [ 1] Notified of signal 23 D0229 21:18:19.870059 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:18:19.870102 5995 task_block.go:223] [ 1] Interrupt queued I0229 21:18:19.870311 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:18:19.870489 5995 task_signals.go:446] [ 1] Notified of signal 23 D0229 21:18:19.870324 5995 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 21:18:19.870598 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:18:19.870669 5995 task_signals.go:220] [ 1] Signal 23: delivering to handler D0229 21:18:19.871047 5995 task_block.go:223] [ 2] Interrupt queued D0229 21:18:19.871126 5995 task_block.go:223] [ 2] Interrupt queued D0229 21:18:19.871163 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:18:19.871430 5995 task_signals.go:176] [ 2] Not restarting syscall 202 after errno 516: interrupted by signal 23 D0229 21:18:19.871574 5995 task_signals.go:220] [ 2] Signal 23: delivering to handler D0229 21:18:20.798209 5995 parameters.go:238] Clock(Monotonic): error: -197 ns, adjusted frequency from 2299774208 Hz to 2299774155 Hz I0229 21:18:20.798316 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:18:20.798421 5995 parameters.go:238] Clock(Realtime): error: -43 ns, adjusted frequency from 2299774791 Hz to 2299774462 Hz D0229 21:18:20.798495 5995 task_signals.go:446] [ 1] Notified of signal 23 D0229 21:18:20.798622 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:18:20.798562 5995 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8976120529209401 monotonicBaseRef:13000342113 monotonicFrequency:2299774155 realtimeReady:1 realtimeBaseCycles:8976120529704103 realtimeBaseRef:1583011100798410873 realtimeFrequency:2299774462} D0229 21:18:20.798665 5995 task_block.go:223] [ 1] Interrupt queued I0229 21:18:20.798761 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:18:20.798877 5995 task_signals.go:434] [ 1] Discarding duplicate signal 23 D0229 21:18:20.799006 5995 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 21:18:20.799145 5995 task_signals.go:220] [ 1] Signal 23: delivering to handler D0229 21:18:21.798212 5995 parameters.go:238] Clock(Monotonic): error: 210 ns, adjusted frequency from 2299774155 Hz to 2299775249 Hz I0229 21:18:21.798328 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:18:21.798510 5995 parameters.go:238] Clock(Realtime): error: 235 ns, adjusted frequency from 2299774462 Hz to 2299775092 Hz D0229 21:18:21.798565 5995 task_signals.go:446] [ 1] Notified of signal 23 D0229 21:18:21.798708 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:18:21.798655 5995 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8976122828932428 monotonicBaseRef:14000319881 monotonicFrequency:2299775249 realtimeReady:1 realtimeBaseCycles:8976122829689615 realtimeBaseRef:1583011101798502642 realtimeFrequency:2299775092} D0229 21:18:21.798920 5995 task_block.go:223] [ 1] Interrupt queued I0229 21:18:21.798923 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:18:21.799180 5995 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 21:18:21.799208 5995 task_signals.go:446] [ 1] Notified of signal 23 D0229 21:18:21.799310 5995 task_signals.go:220] [ 1] Signal 23: delivering to handler D0229 21:18:21.799335 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:18:21.799757 5995 task_block.go:223] [ 2] Interrupt queued D0229 21:18:21.799874 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:18:21.799941 5995 task_block.go:223] [ 2] Interrupt queued D0229 21:18:21.800165 5995 task_signals.go:176] [ 2] Not restarting syscall 202 after errno 516: interrupted by signal 23 D0229 21:18:21.800310 5995 task_signals.go:220] [ 2] Signal 23: delivering to handler I0229 21:18:21.869360 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:18:21.869573 5995 task_signals.go:446] [ 1] Notified of signal 23 D0229 21:18:21.869698 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:18:21.869754 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:18:21.869957 5995 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 21:18:21.870103 5995 task_signals.go:220] [ 1] Signal 23: delivering to handler D0229 21:18:22.798200 5995 parameters.go:238] Clock(Monotonic): error: 293 ns, adjusted frequency from 2299775249 Hz to 2299775344 Hz I0229 21:18:22.798398 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:18:22.798398 5995 parameters.go:238] Clock(Realtime): error: 76 ns, adjusted frequency from 2299775092 Hz to 2299774760 Hz D0229 21:18:22.798570 5995 task_signals.go:446] [ 1] Notified of signal 23 D0229 21:18:22.798601 5995 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8976125128651577 monotonicBaseRef:15000295487 monotonicFrequency:2299775344 realtimeReady:1 realtimeBaseCycles:8976125129207226 realtimeBaseRef:1583011102798390682 realtimeFrequency:2299774760} D0229 21:18:22.798684 5995 task_block.go:223] [ 1] Interrupt queued I0229 21:18:22.798810 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:18:22.798809 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:18:22.798916 5995 task_signals.go:434] [ 1] Discarding duplicate signal 23 D0229 21:18:22.799113 5995 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 21:18:22.799268 5995 task_signals.go:220] [ 1] Signal 23: delivering to handler D0229 21:18:23.798214 5995 parameters.go:238] Clock(Monotonic): error: -227 ns, adjusted frequency from 2299775344 Hz to 2299774106 Hz D0229 21:18:23.798408 5995 parameters.go:238] Clock(Realtime): error: -127 ns, adjusted frequency from 2299774760 Hz to 2299774242 Hz D0229 21:18:23.798567 5995 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8976127428488965 monotonicBaseRef:16000322465 monotonicFrequency:2299774106 realtimeReady:1 realtimeBaseCycles:8976127429004330 realtimeBaseRef:1583011103798400397 realtimeFrequency:2299774242} D0229 21:18:24.798190 5995 parameters.go:238] Clock(Monotonic): error: 48 ns, adjusted frequency from 2299774106 Hz to 2299774723 Hz D0229 21:18:24.798358 5995 parameters.go:238] Clock(Realtime): error: -131 ns, adjusted frequency from 2299774242 Hz to 2299774325 Hz D0229 21:18:24.798495 5995 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8976129728263044 monotonicBaseRef:17000322453 monotonicFrequency:2299774723 realtimeReady:1 realtimeBaseCycles:8976129728669644 realtimeBaseRef:1583011104798353032 realtimeFrequency:2299774325} I0229 21:18:24.869576 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:18:24.869852 5995 task_signals.go:446] [ 1] Notified of signal 23 D0229 21:18:24.870001 5995 task_block.go:223] [ 1] Interrupt queued I0229 21:18:24.870120 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:18:24.870246 5995 task_signals.go:434] [ 1] Discarding duplicate signal 23 D0229 21:18:24.870160 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:18:24.870480 5995 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 21:18:24.870585 5995 task_signals.go:220] [ 1] Signal 23: delivering to handler D0229 21:18:25.798137 5995 parameters.go:238] Clock(Monotonic): error: 323 ns, adjusted frequency from 2299774723 Hz to 2299775284 Hz D0229 21:18:25.798493 5995 parameters.go:238] Clock(Realtime): error: 553 ns, adjusted frequency from 2299774325 Hz to 2299775937 Hz D0229 21:18:25.798660 5995 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8976132027911894 monotonicBaseRef:18000267720 monotonicFrequency:2299775284 realtimeReady:1 realtimeBaseCycles:8976132028746146 realtimeBaseRef:1583011105798484426 realtimeFrequency:2299775937} D0229 21:18:26.798225 5995 parameters.go:238] Clock(Monotonic): error: 33 ns, adjusted frequency from 2299775284 Hz to 2299774770 Hz D0229 21:18:26.798434 5995 parameters.go:238] Clock(Realtime): error: -358 ns, adjusted frequency from 2299775937 Hz to 2299773821 Hz D0229 21:18:26.798600 5995 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8976134327865275 monotonicBaseRef:19000345161 monotonicFrequency:2299774770 realtimeReady:1 realtimeBaseCycles:8976134328388076 realtimeBaseRef:1583011106798426156 realtimeFrequency:2299773821} D0229 21:18:27.798143 5995 parameters.go:238] Clock(Monotonic): error: -711 ns, adjusted frequency from 2299774770 Hz to 2299773084 Hz D0229 21:18:27.798342 5995 parameters.go:238] Clock(Realtime): error: -183 ns, adjusted frequency from 2299773821 Hz to 2299774194 Hz D0229 21:18:27.798469 5995 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8976136627479819 monotonicBaseRef:20000275490 monotonicFrequency:2299773084 realtimeReady:1 realtimeBaseCycles:8976136627945997 realtimeBaseRef:1583011107798332277 realtimeFrequency:2299774194} D0229 21:18:28.798204 5995 parameters.go:238] Clock(Monotonic): error: 249 ns, adjusted frequency from 2299773084 Hz to 2299775087 Hz D0229 21:18:28.798426 5995 parameters.go:238] Clock(Realtime): error: 10 ns, adjusted frequency from 2299774194 Hz to 2299774574 Hz I0229 21:18:28.798385 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:18:28.798532 5995 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8976138927394798 monotonicBaseRef:21000337189 monotonicFrequency:2299775087 realtimeReady:1 realtimeBaseCycles:8976138927924174 realtimeBaseRef:1583011108798420973 realtimeFrequency:2299774574} D0229 21:18:28.798748 5995 task_signals.go:446] [ 1] Notified of signal 23 D0229 21:18:28.798966 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:18:28.799046 5995 task_block.go:223] [ 1] Interrupt queued I0229 21:18:28.799189 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:18:28.799301 5995 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 21:18:28.799355 5995 task_signals.go:446] [ 1] Notified of signal 23 D0229 21:18:28.799447 5995 task_signals.go:220] [ 1] Signal 23: delivering to handler D0229 21:18:28.799517 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:18:28.799932 5995 task_block.go:223] [ 2] Interrupt queued D0229 21:18:28.800044 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:18:28.800102 5995 task_block.go:223] [ 2] Interrupt queued D0229 21:18:28.800345 5995 task_signals.go:176] [ 2] Not restarting syscall 202 after errno 516: interrupted by signal 23 D0229 21:18:28.800500 5995 task_signals.go:220] [ 2] Signal 23: delivering to handler I0229 21:18:28.801745 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:18:28.802214 5995 task_signals.go:446] [ 1] Notified of signal 23 D0229 21:18:28.803385 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:18:28.803412 5995 task_block.go:223] [ 1] Interrupt queued I0229 21:18:28.803498 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:18:28.803592 5995 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 21:18:28.803626 5995 task_signals.go:446] [ 1] Notified of signal 23 D0229 21:18:28.803722 5995 task_signals.go:220] [ 1] Signal 23: delivering to handler D0229 21:18:28.803743 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:18:28.804273 5995 task_block.go:223] [ 2] Interrupt queued D0229 21:18:28.804401 5995 task_block.go:223] [ 2] Interrupt queued D0229 21:18:28.804415 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:18:28.804755 5995 task_signals.go:176] [ 2] Not restarting syscall 202 after errno 516: interrupted by signal 23 D0229 21:18:28.804956 5995 task_signals.go:220] [ 2] Signal 23: delivering to handler D0229 21:18:29.798208 5995 parameters.go:238] Clock(Monotonic): error: 335 ns, adjusted frequency from 2299775087 Hz to 2299775367 Hz I0229 21:18:29.798349 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:18:29.798583 5995 task_signals.go:446] [ 1] Notified of signal 23 D0229 21:18:29.798530 5995 parameters.go:238] Clock(Realtime): error: -175 ns, adjusted frequency from 2299774574 Hz to 2299774283 Hz D0229 21:18:29.798666 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:18:29.798700 5995 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8976141226900039 monotonicBaseRef:22000219853 monotonicFrequency:2299775367 realtimeReady:1 realtimeBaseCycles:8976141227924149 realtimeBaseRef:1583011109798518983 realtimeFrequency:2299774283} D0229 21:18:29.798797 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:18:29.799116 5995 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 21:18:29.799322 5995 task_signals.go:220] [ 1] Signal 23: delivering to handler D0229 21:18:30.798238 5995 parameters.go:238] Clock(Monotonic): error: -166 ns, adjusted frequency from 2299775367 Hz to 2299774087 Hz D0229 21:18:30.798554 5995 parameters.go:238] Clock(Realtime): error: 450 ns, adjusted frequency from 2299774283 Hz to 2299775710 Hz D0229 21:18:30.798725 5995 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8976143527009329 monotonicBaseRef:23000365051 monotonicFrequency:2299774087 realtimeReady:1 realtimeBaseCycles:8976143527742221 realtimeBaseRef:1583011110798538023 realtimeFrequency:2299775710} I0229 21:18:30.869601 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:18:30.869842 5995 task_signals.go:446] [ 1] Notified of signal 23 D0229 21:18:30.869968 5995 task_block.go:223] [ 1] Interrupt queued I0229 21:18:30.870090 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:18:30.870079 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:18:30.870217 5995 task_signals.go:434] [ 1] Discarding duplicate signal 23 D0229 21:18:30.870329 5995 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 21:18:30.870458 5995 task_signals.go:220] [ 1] Signal 23: delivering to handler D0229 21:18:31.798341 5995 parameters.go:238] Clock(Monotonic): error: -177 ns, adjusted frequency from 2299774087 Hz to 2299774103 Hz I0229 21:18:31.798442 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:18:31.798575 5995 parameters.go:238] Clock(Realtime): error: -22 ns, adjusted frequency from 2299775710 Hz to 2299774594 Hz D0229 21:18:31.798702 5995 task_signals.go:446] [ 1] Notified of signal 23 D0229 21:18:31.798750 5995 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8976145827028111 monotonicBaseRef:24000471450 monotonicFrequency:2299774103 realtimeReady:1 realtimeBaseCycles:8976145827585280 realtimeBaseRef:1583011111798567308 realtimeFrequency:2299774594} D0229 21:18:31.798901 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:18:31.798996 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:18:31.799211 5995 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 I0229 21:18:31.799215 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:18:31.799315 5995 task_signals.go:220] [ 1] Signal 23: delivering to handler D0229 21:18:31.799443 5995 task_signals.go:446] [ 1] Notified of signal 23 D0229 21:18:31.799611 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:18:31.799915 5995 task_block.go:223] [ 2] Interrupt queued D0229 21:18:31.800052 5995 task_block.go:223] [ 2] Interrupt queued D0229 21:18:31.800060 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:18:31.800350 5995 task_signals.go:176] [ 2] Not restarting syscall 202 after errno 516: interrupted by signal 23 D0229 21:18:31.800505 5995 task_signals.go:220] [ 2] Signal 23: delivering to handler D0229 21:18:32.798162 5995 parameters.go:238] Clock(Monotonic): error: 334 ns, adjusted frequency from 2299774103 Hz to 2299775391 Hz D0229 21:18:32.798352 5995 parameters.go:238] Clock(Realtime): error: -264 ns, adjusted frequency from 2299774594 Hz to 2299774070 Hz D0229 21:18:32.798452 5995 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8976148126387053 monotonicBaseRef:25000290927 monotonicFrequency:2299775391 realtimeReady:1 realtimeBaseCycles:8976148126853482 realtimeBaseRef:1583011112798347115 realtimeFrequency:2299774070} D0229 21:18:33.798054 5995 parameters.go:238] Clock(Monotonic): error: -482 ns, adjusted frequency from 2299775391 Hz to 2299773609 Hz D0229 21:18:33.798264 5995 parameters.go:238] Clock(Realtime): error: -273 ns, adjusted frequency from 2299774070 Hz to 2299774044 Hz D0229 21:18:33.798341 5995 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8976150425861433 monotonicBaseRef:26000160039 monotonicFrequency:2299773609 realtimeReady:1 realtimeBaseCycles:8976150426422192 realtimeBaseRef:1583011113798257819 realtimeFrequency:2299774044} D0229 21:18:34.798230 5995 parameters.go:238] Clock(Monotonic): error: 157 ns, adjusted frequency from 2299773609 Hz to 2299775050 Hz D0229 21:18:34.798422 5995 parameters.go:238] Clock(Realtime): error: 281 ns, adjusted frequency from 2299774044 Hz to 2299775262 Hz D0229 21:18:34.798533 5995 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8976152726027111 monotonicBaseRef:27000330520 monotonicFrequency:2299775050 realtimeReady:1 realtimeBaseCycles:8976152726562689 realtimeBaseRef:1583011114798417162 realtimeFrequency:2299775262} I0229 21:18:34.869537 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:18:34.869844 5995 task_signals.go:446] [ 1] Notified of signal 23 D0229 21:18:34.869959 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:18:34.870000 5995 task_block.go:223] [ 1] Interrupt queued I0229 21:18:34.870095 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:18:34.870173 5995 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 21:18:34.870232 5995 task_signals.go:446] [ 1] Notified of signal 23 D0229 21:18:34.870308 5995 task_signals.go:220] [ 1] Signal 23: delivering to handler D0229 21:18:34.870347 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:18:34.870695 5995 task_block.go:223] [ 2] Interrupt queued D0229 21:18:34.870817 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:18:34.870716 5995 task_block.go:223] [ 2] Interrupt queued D0229 21:18:34.870968 5995 task_signals.go:176] [ 2] Not restarting syscall 202 after errno 516: interrupted by signal 23 D0229 21:18:34.871072 5995 task_signals.go:220] [ 2] Signal 23: delivering to handler D0229 21:18:35.798594 5995 parameters.go:238] Clock(Monotonic): error: 147 ns, adjusted frequency from 2299775050 Hz to 2299774948 Hz D0229 21:18:35.798803 5995 parameters.go:238] Clock(Realtime): error: -288 ns, adjusted frequency from 2299775262 Hz to 2299774010 Hz D0229 21:18:35.798946 5995 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8976155026707773 monotonicBaseRef:28000724302 monotonicFrequency:2299774948 realtimeReady:1 realtimeBaseCycles:8976155027212571 realtimeBaseRef:1583011115798797468 realtimeFrequency:2299774010} I0229 21:18:35.869580 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:18:35.869879 5995 task_signals.go:446] [ 1] Notified of signal 23 D0229 21:18:35.870023 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:18:35.870092 5995 task_block.go:223] [ 1] Interrupt queued I0229 21:18:35.870178 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:18:35.870299 5995 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 21:18:35.870352 5995 task_signals.go:446] [ 1] Notified of signal 23 D0229 21:18:35.870447 5995 task_signals.go:220] [ 1] Signal 23: delivering to handler D0229 21:18:35.870540 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:18:35.870797 5995 task_block.go:223] [ 2] Interrupt queued D0229 21:18:35.870904 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:18:35.870854 5995 task_block.go:223] [ 2] Interrupt queued D0229 21:18:35.871211 5995 task_signals.go:176] [ 2] Not restarting syscall 202 after errno 516: interrupted by signal 23 D0229 21:18:35.871371 5995 task_signals.go:220] [ 2] Signal 23: delivering to handler D0229 21:18:36.798232 5995 parameters.go:238] Clock(Monotonic): error: -134 ns, adjusted frequency from 2299774948 Hz to 2299774321 Hz D0229 21:18:36.798487 5995 parameters.go:238] Clock(Realtime): error: 170 ns, adjusted frequency from 2299774010 Hz to 2299775099 Hz D0229 21:18:36.798682 5995 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8976157325645100 monotonicBaseRef:29000360083 monotonicFrequency:2299774321 realtimeReady:1 realtimeBaseCycles:8976157326256274 realtimeBaseRef:1583011116798479912 realtimeFrequency:2299775099} I0229 21:18:36.869622 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:18:36.869866 5995 task_signals.go:446] [ 1] Notified of signal 23 D0229 21:18:36.869967 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:18:36.870038 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:18:36.870187 5995 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 21:18:36.870317 5995 task_signals.go:220] [ 1] Signal 23: delivering to handler I0229 21:18:36.870349 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:18:36.870489 5995 task_signals.go:446] [ 1] Notified of signal 23 D0229 21:18:36.870588 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:18:36.870731 5995 task_block.go:223] [ 2] Interrupt queued D0229 21:18:36.870812 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:18:36.871033 5995 task_signals.go:220] [ 2] Signal 23: delivering to handler D0229 21:18:37.798167 5995 parameters.go:238] Clock(Monotonic): error: -56 ns, adjusted frequency from 2299774321 Hz to 2299774475 Hz I0229 21:18:37.798249 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:18:37.798372 5995 parameters.go:238] Clock(Realtime): error: -51 ns, adjusted frequency from 2299775099 Hz to 2299774571 Hz D0229 21:18:37.798459 5995 task_signals.go:446] [ 1] Notified of signal 23 D0229 21:18:37.798474 5995 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8976159625277552 monotonicBaseRef:30000298394 monotonicFrequency:2299774475 realtimeReady:1 realtimeBaseCycles:8976159625774642 realtimeBaseRef:1583011117798368278 realtimeFrequency:2299774571} D0229 21:18:37.798629 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:18:37.798692 5995 task_block.go:223] [ 1] Interrupt queued I0229 21:18:37.798797 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:18:37.798875 5995 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 21:18:37.798946 5995 task_signals.go:446] [ 1] Notified of signal 23 D0229 21:18:37.798993 5995 task_signals.go:220] [ 1] Signal 23: delivering to handler D0229 21:18:37.799100 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:18:37.799443 5995 task_block.go:223] [ 2] Interrupt queued D0229 21:18:37.799551 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:18:37.799489 5995 task_block.go:223] [ 2] Interrupt queued D0229 21:18:37.799716 5995 task_signals.go:176] [ 2] Not restarting syscall 202 after errno 516: interrupted by signal 23 D0229 21:18:37.799870 5995 task_signals.go:220] [ 2] Signal 23: delivering to handler D0229 21:18:38.798197 5995 parameters.go:238] Clock(Monotonic): error: 122 ns, adjusted frequency from 2299774475 Hz to 2299774962 Hz D0229 21:18:38.798458 5995 parameters.go:238] Clock(Realtime): error: 222 ns, adjusted frequency from 2299774571 Hz to 2299775112 Hz I0229 21:18:38.798494 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:18:38.798706 5995 task_signals.go:446] [ 1] Notified of signal 23 D0229 21:18:38.798596 5995 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8976161925121283 monotonicBaseRef:31000328508 monotonicFrequency:2299774962 realtimeReady:1 realtimeBaseCycles:8976161925741921 realtimeBaseRef:1583011118798452072 realtimeFrequency:2299775112} D0229 21:18:38.798821 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:18:38.798882 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:18:38.799238 5995 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 21:18:38.799395 5995 task_signals.go:220] [ 1] Signal 23: delivering to handler I0229 21:18:38.828345 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:18:38.828576 5995 task_signals.go:446] [ 1] Notified of signal 23 D0229 21:18:38.828712 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:18:38.828798 5995 task_block.go:223] [ 1] Interrupt queued I0229 21:18:38.828867 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:18:38.828992 5995 task_signals.go:434] [ 1] Discarding duplicate signal 23 D0229 21:18:38.829169 5995 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 21:18:38.829269 5995 task_signals.go:220] [ 1] Signal 23: delivering to handler D0229 21:18:38.829165 5995 urpc.go:577] urpc: unmarshal success. D0229 21:18:38.829538 5995 controller.go:501] containerManager.Signal &{CID:ci-gvisor-ptrace-proxy-sandbox-race-0 Signo:0 PID:0 Mode:Process} D0229 21:18:38.829966 5995 urpc.go:534] urpc: successfully marshalled 37 bytes. I0229 21:18:38.869644 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:18:38.869823 5995 task_signals.go:446] [ 1] Notified of signal 23 D0229 21:18:38.869910 5995 task_block.go:223] [ 1] Interrupt queued I0229 21:18:38.870058 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:18:38.870118 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:18:38.870192 5995 task_signals.go:434] [ 1] Discarding duplicate signal 23 D0229 21:18:38.870326 5995 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 21:18:38.870461 5995 task_signals.go:220] [ 1] Signal 23: delivering to handler D0229 21:18:39.798133 5995 parameters.go:238] Clock(Monotonic): error: 139 ns, adjusted frequency from 2299774962 Hz to 2299775013 Hz D0229 21:18:39.798321 5995 parameters.go:238] Clock(Realtime): error: -220 ns, adjusted frequency from 2299775112 Hz to 2299774145 Hz D0229 21:18:39.798429 5995 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8976164224750288 monotonicBaseRef:32000265042 monotonicFrequency:2299775013 realtimeReady:1 realtimeBaseCycles:8976164225205411 realtimeBaseRef:1583011119798316570 realtimeFrequency:2299774145} D0229 21:18:40.798526 5995 parameters.go:238] Clock(Monotonic): error: 71 ns, adjusted frequency from 2299775013 Hz to 2299774893 Hz I0229 21:18:40.798598 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:18:40.798756 5995 parameters.go:238] Clock(Realtime): error: 127 ns, adjusted frequency from 2299774145 Hz to 2299774918 Hz D0229 21:18:40.798809 5995 task_signals.go:446] [ 1] Notified of signal 23 D0229 21:18:40.798910 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:18:40.798885 5995 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8976166525416167 monotonicBaseRef:33000652412 monotonicFrequency:2299774893 realtimeReady:1 realtimeBaseCycles:8976166525977532 realtimeBaseRef:1583011120798750515 realtimeFrequency:2299774918} D0229 21:18:40.798945 5995 task_block.go:223] [ 1] Interrupt queued I0229 21:18:40.799008 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:18:40.799124 5995 task_signals.go:434] [ 1] Discarding duplicate signal 23 D0229 21:18:40.799215 5995 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 21:18:40.799378 5995 task_signals.go:220] [ 1] Signal 23: delivering to handler I0229 21:18:40.870597 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:18:40.870801 5995 task_signals.go:446] [ 1] Notified of signal 23 D0229 21:18:40.870943 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:18:40.871095 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:18:40.871336 5995 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 21:18:40.871523 5995 task_signals.go:220] [ 1] Signal 23: delivering to handler D0229 21:18:41.798196 5995 parameters.go:238] Clock(Monotonic): error: -491 ns, adjusted frequency from 2299774893 Hz to 2299773577 Hz D0229 21:18:41.798381 5995 parameters.go:238] Clock(Realtime): error: -91 ns, adjusted frequency from 2299774918 Hz to 2299774423 Hz I0229 21:18:41.798312 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:18:41.798475 5995 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8976168824359006 monotonicBaseRef:34000290613 monotonicFrequency:2299773577 realtimeReady:1 realtimeBaseCycles:8976168824892342 realtimeBaseRef:1583011121798376518 realtimeFrequency:2299774423} D0229 21:18:41.798557 5995 task_signals.go:446] [ 1] Notified of signal 23 D0229 21:18:41.798725 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:18:41.798885 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:18:41.799086 5995 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 21:18:41.799178 5995 task_signals.go:220] [ 1] Signal 23: delivering to handler I0229 21:18:41.799385 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:18:41.799503 5995 task_signals.go:446] [ 1] Notified of signal 23 D0229 21:18:41.799598 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:18:41.799762 5995 task_block.go:223] [ 2] Interrupt queued D0229 21:18:41.799891 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:18:41.799920 5995 task_block.go:223] [ 2] Interrupt queued D0229 21:18:41.800144 5995 task_signals.go:176] [ 2] Not restarting syscall 202 after errno 516: interrupted by signal 23 D0229 21:18:41.800305 5995 task_signals.go:220] [ 2] Signal 23: delivering to handler D0229 21:18:42.798155 5995 parameters.go:238] Clock(Monotonic): error: 279 ns, adjusted frequency from 2299773577 Hz to 2299775201 Hz D0229 21:18:42.798334 5995 parameters.go:238] Clock(Realtime): error: 43 ns, adjusted frequency from 2299774423 Hz to 2299774662 Hz D0229 21:18:42.798496 5995 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8976171124119188 monotonicBaseRef:35000284788 monotonicFrequency:2299775201 realtimeReady:1 realtimeBaseCycles:8976171124558291 realtimeBaseRef:1583011122798329350 realtimeFrequency:2299774662} I0229 21:18:42.869551 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:18:42.869762 5995 task_signals.go:446] [ 1] Notified of signal 23 D0229 21:18:42.869875 5995 task_block.go:223] [ 1] Interrupt queued I0229 21:18:42.870066 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:18:42.870023 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:18:42.870219 5995 task_signals.go:434] [ 1] Discarding duplicate signal 23 D0229 21:18:42.870360 5995 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 I0229 21:18:42.870357 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:18:42.870508 5995 task_signals.go:220] [ 1] Signal 23: delivering to handler D0229 21:18:42.870548 5995 task_signals.go:446] [ 1] Notified of signal 23 D0229 21:18:42.870682 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:18:42.870950 5995 task_block.go:223] [ 2] Interrupt queued D0229 21:18:42.871075 5995 task_block.go:223] [ 2] Interrupt queued D0229 21:18:42.871141 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:18:42.871338 5995 task_signals.go:176] [ 2] Not restarting syscall 202 after errno 516: interrupted by signal 23 D0229 21:18:42.871514 5995 task_signals.go:220] [ 2] Signal 23: delivering to handler D0229 21:18:43.798643 5995 parameters.go:238] Clock(Monotonic): error: -275 ns, adjusted frequency from 2299775201 Hz to 2299773997 Hz D0229 21:18:43.798836 5995 parameters.go:238] Clock(Realtime): error: 79 ns, adjusted frequency from 2299774662 Hz to 2299774814 Hz D0229 21:18:43.798958 5995 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8976173424972223 monotonicBaseRef:36000753457 monotonicFrequency:2299773997 realtimeReady:1 realtimeBaseCycles:8976173425469648 realtimeBaseRef:1583011123798823613 realtimeFrequency:2299774814} D0229 21:18:44.798218 5995 parameters.go:238] Clock(Monotonic): error: -107 ns, adjusted frequency from 2299773997 Hz to 2299774433 Hz I0229 21:18:44.798444 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:18:44.798485 5995 parameters.go:238] Clock(Realtime): error: 137 ns, adjusted frequency from 2299774814 Hz to 2299774909 Hz D0229 21:18:44.798691 5995 task_signals.go:446] [ 1] Notified of signal 23 D0229 21:18:44.798853 5995 task_block.go:223] [ 1] Interrupt queued I0229 21:18:44.798985 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:18:44.798975 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:18:44.799083 5995 task_signals.go:434] [ 1] Discarding duplicate signal 23 D0229 21:18:44.799211 5995 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 21:18:44.799346 5995 task_signals.go:220] [ 1] Signal 23: delivering to handler D0229 21:18:44.798719 5995 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8976175723811439 monotonicBaseRef:37000346990 monotonicFrequency:2299774433 realtimeReady:1 realtimeBaseCycles:8976175724447819 realtimeBaseRef:1583011124798477212 realtimeFrequency:2299774909} D0229 21:18:45.798236 5995 parameters.go:238] Clock(Monotonic): error: 89 ns, adjusted frequency from 2299774433 Hz to 2299774837 Hz D0229 21:18:45.798458 5995 parameters.go:238] Clock(Realtime): error: 42 ns, adjusted frequency from 2299774909 Hz to 2299774672 Hz D0229 21:18:45.798643 5995 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8976178023628138 monotonicBaseRef:38000365368 monotonicFrequency:2299774837 realtimeReady:1 realtimeBaseCycles:8976178024162884 realtimeBaseRef:1583011125798451190 realtimeFrequency:2299774672} D0229 21:18:46.798458 5995 parameters.go:238] Clock(Monotonic): error: 435 ns, adjusted frequency from 2299774837 Hz to 2299775670 Hz I0229 21:18:46.798588 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:18:46.798668 5995 parameters.go:238] Clock(Realtime): error: -120 ns, adjusted frequency from 2299774672 Hz to 2299774354 Hz D0229 21:18:46.798809 5995 task_signals.go:446] [ 1] Notified of signal 23 D0229 21:18:46.798822 5995 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8976180323827871 monotonicBaseRef:39000550123 monotonicFrequency:2299775670 realtimeReady:1 realtimeBaseCycles:8976180324421282 realtimeBaseRef:1583011126798661526 realtimeFrequency:2299774354} D0229 21:18:46.798984 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:18:46.799033 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:18:46.799293 5995 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 21:18:46.799399 5995 task_signals.go:220] [ 1] Signal 23: delivering to handler D0229 21:18:47.798173 5995 parameters.go:238] Clock(Monotonic): error: -190 ns, adjusted frequency from 2299775670 Hz to 2299774124 Hz D0229 21:18:47.798379 5995 parameters.go:238] Clock(Realtime): error: 437 ns, adjusted frequency from 2299774354 Hz to 2299775718 Hz D0229 21:18:47.798493 5995 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8976182623039483 monotonicBaseRef:40000304856 monotonicFrequency:2299774124 realtimeReady:1 realtimeBaseCycles:8976182623532394 realtimeBaseRef:1583011127798373131 realtimeFrequency:2299775718} D0229 21:18:48.798191 5995 parameters.go:238] Clock(Monotonic): error: 91 ns, adjusted frequency from 2299774124 Hz to 2299774803 Hz D0229 21:18:48.798405 5995 parameters.go:238] Clock(Realtime): error: -531 ns, adjusted frequency from 2299775718 Hz to 2299773396 Hz D0229 21:18:48.798491 5995 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8976184922850644 monotonicBaseRef:41000320960 monotonicFrequency:2299774803 realtimeReady:1 realtimeBaseCycles:8976184923369588 realtimeBaseRef:1583011128798399862 realtimeFrequency:2299773396} I0229 21:18:48.869373 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:18:48.869601 5995 task_signals.go:446] [ 1] Notified of signal 23 D0229 21:18:48.869708 5995 task_block.go:223] [ 1] Interrupt queued I0229 21:18:48.869870 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:18:48.869873 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:18:48.869986 5995 task_signals.go:434] [ 1] Discarding duplicate signal 23 D0229 21:18:48.870096 5995 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 21:18:48.870245 5995 task_signals.go:220] [ 1] Signal 23: delivering to handler D0229 21:18:49.798410 5995 parameters.go:238] Clock(Monotonic): error: -20 ns, adjusted frequency from 2299774803 Hz to 2299774596 Hz D0229 21:18:49.798665 5995 parameters.go:238] Clock(Realtime): error: -289 ns, adjusted frequency from 2299773396 Hz to 2299773992 Hz I0229 21:18:49.798618 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:18:49.798822 5995 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8976187223129333 monotonicBaseRef:42000540062 monotonicFrequency:2299774596 realtimeReady:1 realtimeBaseCycles:8976187223737865 realtimeBaseRef:1583011129798658531 realtimeFrequency:2299773992} D0229 21:18:49.798996 5995 task_signals.go:446] [ 1] Notified of signal 23 D0229 21:18:49.799115 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:18:49.799157 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:18:49.799394 5995 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 21:18:49.799516 5995 task_signals.go:220] [ 1] Signal 23: delivering to handler D0229 21:18:50.798152 5995 parameters.go:238] Clock(Monotonic): error: -361 ns, adjusted frequency from 2299774596 Hz to 2299773794 Hz D0229 21:18:50.798405 5995 parameters.go:238] Clock(Realtime): error: 74 ns, adjusted frequency from 2299773992 Hz to 2299774818 Hz D0229 21:18:50.798509 5995 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8976189522316333 monotonicBaseRef:43000284560 monotonicFrequency:2299773794 realtimeReady:1 realtimeBaseCycles:8976189522840313 realtimeBaseRef:1583011130798366526 realtimeFrequency:2299774818} I0229 21:18:50.869760 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:18:50.869993 5995 task_signals.go:446] [ 1] Notified of signal 23 D0229 21:18:50.870139 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:18:50.870238 5995 task_block.go:223] [ 1] Interrupt queued I0229 21:18:50.870325 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:18:50.870449 5995 task_signals.go:446] [ 1] Notified of signal 23 D0229 21:18:50.870400 5995 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 21:18:50.870583 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:18:50.870616 5995 task_signals.go:220] [ 1] Signal 23: delivering to handler D0229 21:18:50.871183 5995 task_block.go:223] [ 2] Interrupt queued D0229 21:18:50.871323 5995 task_block.go:223] [ 2] Interrupt queued D0229 21:18:50.871319 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:18:50.871487 5995 task_signals.go:176] [ 2] Not restarting syscall 202 after errno 516: interrupted by signal 23 D0229 21:18:50.871590 5995 task_signals.go:220] [ 2] Signal 23: delivering to handler D0229 21:18:51.798056 5995 parameters.go:238] Clock(Monotonic): error: 367 ns, adjusted frequency from 2299773794 Hz to 2299775430 Hz D0229 21:18:51.798275 5995 parameters.go:238] Clock(Realtime): error: 135 ns, adjusted frequency from 2299774818 Hz to 2299774941 Hz D0229 21:18:51.798356 5995 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8976191821845962 monotonicBaseRef:44000178390 monotonicFrequency:2299775430 realtimeReady:1 realtimeBaseCycles:8976191822390970 realtimeBaseRef:1583011131798269055 realtimeFrequency:2299774941} I0229 21:18:51.869534 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:18:51.869704 5995 task_signals.go:446] [ 1] Notified of signal 23 D0229 21:18:51.869813 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:18:51.869933 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:18:51.870112 5995 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 21:18:51.870247 5995 task_signals.go:220] [ 1] Signal 23: delivering to handler D0229 21:18:52.798203 5995 parameters.go:238] Clock(Monotonic): error: -2 ns, adjusted frequency from 2299775430 Hz to 2299774560 Hz D0229 21:18:52.798441 5995 parameters.go:238] Clock(Realtime): error: 149 ns, adjusted frequency from 2299774941 Hz to 2299774972 Hz I0229 21:18:52.798459 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:18:52.798542 5995 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8976194121964272 monotonicBaseRef:45000327482 monotonicFrequency:2299774560 realtimeReady:1 realtimeBaseCycles:8976194122540367 realtimeBaseRef:1583011132798431877 realtimeFrequency:2299774972} D0229 21:18:52.798672 5995 task_signals.go:446] [ 1] Notified of signal 23 D0229 21:18:52.798799 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:18:52.798846 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:18:52.799067 5995 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 21:18:52.799212 5995 task_signals.go:220] [ 1] Signal 23: delivering to handler I0229 21:18:52.969209 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:18:52.969479 5995 task_signals.go:446] [ 1] Notified of signal 23 D0229 21:18:52.969634 5995 task_block.go:223] [ 1] Interrupt queued I0229 21:18:52.969623 5995 watchdog.go:277] Watchdog starting loop, tasks: 16, discount: 0s D0229 21:18:52.969743 5995 task_block.go:223] [ 1] Interrupt queued I0229 21:18:52.969815 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:18:52.969943 5995 task_signals.go:434] [ 1] Discarding duplicate signal 23 D0229 21:18:52.970089 5995 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 21:18:52.970261 5995 task_signals.go:220] [ 1] Signal 23: delivering to handler D0229 21:18:53.798339 5995 parameters.go:238] Clock(Monotonic): error: 155 ns, adjusted frequency from 2299774560 Hz to 2299774899 Hz D0229 21:18:53.798596 5995 parameters.go:238] Clock(Realtime): error: 198 ns, adjusted frequency from 2299774972 Hz to 2299775077 Hz D0229 21:18:53.798750 5995 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8976196422000178 monotonicBaseRef:46000441121 monotonicFrequency:2299774899 realtimeReady:1 realtimeBaseCycles:8976196422673276 realtimeBaseRef:1583011133798587517 realtimeFrequency:2299775077} I0229 21:18:53.869503 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:18:53.869674 5995 task_signals.go:446] [ 1] Notified of signal 23 D0229 21:18:53.869812 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:18:53.869861 5995 task_block.go:223] [ 1] Interrupt queued I0229 21:18:53.869978 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:18:53.870129 5995 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 21:18:53.870144 5995 task_signals.go:446] [ 1] Notified of signal 23 D0229 21:18:53.870267 5995 task_signals.go:220] [ 1] Signal 23: delivering to handler D0229 21:18:53.870285 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:18:53.870752 5995 task_block.go:223] [ 2] Interrupt queued D0229 21:18:53.870916 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:18:53.870788 5995 task_block.go:223] [ 2] Interrupt queued D0229 21:18:53.871075 5995 task_signals.go:176] [ 2] Not restarting syscall 202 after errno 516: interrupted by signal 23 D0229 21:18:53.871180 5995 task_signals.go:220] [ 2] Signal 23: delivering to handler I0229 21:18:53.871975 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:18:53.873366 5995 task_signals.go:446] [ 1] Notified of signal 23 D0229 21:18:53.873593 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:18:53.873618 5995 task_block.go:223] [ 1] Interrupt queued I0229 21:18:53.873764 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:18:53.873804 5995 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 21:18:53.873965 5995 task_signals.go:446] [ 1] Notified of signal 23 D0229 21:18:53.874068 5995 task_signals.go:220] [ 1] Signal 23: delivering to handler D0229 21:18:53.874132 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:18:53.874344 5995 task_block.go:223] [ 2] Interrupt queued D0229 21:18:53.874625 5995 task_signals.go:220] [ 2] Signal 23: delivering to handler D0229 21:18:54.798180 5995 parameters.go:238] Clock(Monotonic): error: -54 ns, adjusted frequency from 2299774899 Hz to 2299774392 Hz I0229 21:18:54.798304 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:18:54.798413 5995 parameters.go:238] Clock(Realtime): error: -590 ns, adjusted frequency from 2299775077 Hz to 2299773309 Hz D0229 21:18:54.798522 5995 task_signals.go:446] [ 1] Notified of signal 23 D0229 21:18:54.798692 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:18:54.798575 5995 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8976198721409361 monotonicBaseRef:47000282098 monotonicFrequency:2299774392 realtimeReady:1 realtimeBaseCycles:8976198722034334 realtimeBaseRef:1583011134798407491 realtimeFrequency:2299773309} D0229 21:18:54.798880 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:18:54.799317 5995 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 21:18:54.799595 5995 task_signals.go:220] [ 1] Signal 23: delivering to handler D0229 21:18:55.798502 5995 parameters.go:238] Clock(Monotonic): error: -333 ns, adjusted frequency from 2299774392 Hz to 2299773955 Hz I0229 21:18:55.798636 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:18:55.798730 5995 parameters.go:238] Clock(Realtime): error: 254 ns, adjusted frequency from 2299773309 Hz to 2299775193 Hz D0229 21:18:55.798837 5995 task_signals.go:446] [ 1] Notified of signal 23 D0229 21:18:55.798869 5995 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8976201021942389 monotonicBaseRef:48000611972 monotonicFrequency:2299773955 realtimeReady:1 realtimeBaseCycles:8976201022534354 realtimeBaseRef:1583011135798723483 realtimeFrequency:2299775193} D0229 21:18:55.798972 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:18:55.799025 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:18:55.799305 5995 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 21:18:55.799463 5995 task_signals.go:220] [ 1] Signal 23: delivering to handler D0229 21:18:56.798226 5995 parameters.go:238] Clock(Monotonic): error: 463 ns, adjusted frequency from 2299773955 Hz to 2299775814 Hz D0229 21:18:56.798439 5995 parameters.go:238] Clock(Realtime): error: -6 ns, adjusted frequency from 2299775193 Hz to 2299774544 Hz D0229 21:18:56.798567 5995 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8976203321117814 monotonicBaseRef:49000351715 monotonicFrequency:2299775814 realtimeReady:1 realtimeBaseCycles:8976203321628024 realtimeBaseRef:1583011136798427139 realtimeFrequency:2299774544} I0229 21:18:56.798334 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:18:56.799061 5995 task_signals.go:446] [ 1] Notified of signal 23 D0229 21:18:56.799241 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:18:56.799275 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:18:56.799546 5995 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 21:18:56.799702 5995 task_signals.go:220] [ 1] Signal 23: delivering to handler D0229 21:18:57.798188 5995 parameters.go:238] Clock(Monotonic): error: -513 ns, adjusted frequency from 2299775814 Hz to 2299773534 Hz I0229 21:18:57.798327 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:18:57.798464 5995 parameters.go:238] Clock(Realtime): error: 258 ns, adjusted frequency from 2299774544 Hz to 2299775178 Hz D0229 21:18:57.798601 5995 task_signals.go:446] [ 1] Notified of signal 23 D0229 21:18:57.798633 5995 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8976205620814019 monotonicBaseRef:50000317099 monotonicFrequency:2299773534 realtimeReady:1 realtimeBaseCycles:8976205621470526 realtimeBaseRef:1583011137798456688 realtimeFrequency:2299775178} D0229 21:18:57.798761 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:18:57.798883 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:18:57.799213 5995 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 21:18:57.799375 5995 task_signals.go:220] [ 1] Signal 23: delivering to handler D0229 21:18:58.798186 5995 parameters.go:238] Clock(Monotonic): error: 345 ns, adjusted frequency from 2299773534 Hz to 2299775362 Hz D0229 21:18:58.798410 5995 parameters.go:238] Clock(Realtime): error: -182 ns, adjusted frequency from 2299775178 Hz to 2299774190 Hz D0229 21:18:58.798564 5995 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8976207920575810 monotonicBaseRef:51000311992 monotonicFrequency:2299775362 realtimeReady:1 realtimeBaseCycles:8976207921124638 realtimeBaseRef:1583011138798404045 realtimeFrequency:2299774190} D0229 21:18:59.798249 5995 parameters.go:238] Clock(Monotonic): error: -209 ns, adjusted frequency from 2299775362 Hz to 2299774157 Hz D0229 21:18:59.798633 5995 parameters.go:238] Clock(Realtime): error: 274 ns, adjusted frequency from 2299774190 Hz to 2299775233 Hz D0229 21:18:59.798794 5995 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8976210220437060 monotonicBaseRef:52000349338 monotonicFrequency:2299774157 realtimeReady:1 realtimeBaseCycles:8976210221409778 realtimeBaseRef:1583011139798626218 realtimeFrequency:2299775233} D0229 21:19:00.798141 5995 parameters.go:238] Clock(Monotonic): error: 868 ns, adjusted frequency from 2299774157 Hz to 2299776791 Hz D0229 21:19:00.798344 5995 parameters.go:238] Clock(Realtime): error: -158 ns, adjusted frequency from 2299775233 Hz to 2299774279 Hz D0229 21:19:00.798451 5995 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8976212520027408 monotonicBaseRef:53000269413 monotonicFrequency:2299776791 realtimeReady:1 realtimeBaseCycles:8976212520522633 realtimeBaseRef:1583011140798338199 realtimeFrequency:2299774279} D0229 21:19:01.798723 5995 parameters.go:238] Clock(Monotonic): error: -816 ns, adjusted frequency from 2299776791 Hz to 2299772823 Hz D0229 21:19:01.798965 5995 parameters.go:238] Clock(Realtime): error: -41 ns, adjusted frequency from 2299774279 Hz to 2299774532 Hz D0229 21:19:01.799060 5995 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8976214821140398 monotonicBaseRef:54000850425 monotonicFrequency:2299772823 realtimeReady:1 realtimeBaseCycles:8976214821724056 realtimeBaseRef:1583011141798958757 realtimeFrequency:2299774532} D0229 21:19:02.798167 5995 parameters.go:238] Clock(Monotonic): error: -321 ns, adjusted frequency from 2299772823 Hz to 2299773972 Hz I0229 21:19:02.798280 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:19:02.798372 5995 parameters.go:238] Clock(Realtime): error: -121 ns, adjusted frequency from 2299774532 Hz to 2299774365 Hz D0229 21:19:02.798457 5995 task_signals.go:446] [ 1] Notified of signal 23 D0229 21:19:02.798606 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:19:02.798643 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:19:02.798466 5995 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8976217119641743 monotonicBaseRef:55000297553 monotonicFrequency:2299773972 realtimeReady:1 realtimeBaseCycles:8976217120138087 realtimeBaseRef:1583011142798367176 realtimeFrequency:2299774365} D0229 21:19:02.798918 5995 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 21:19:02.799077 5995 task_signals.go:220] [ 1] Signal 23: delivering to handler D0229 21:19:03.798559 5995 parameters.go:238] Clock(Monotonic): error: -26 ns, adjusted frequency from 2299773972 Hz to 2299774664 Hz D0229 21:19:03.798796 5995 parameters.go:238] Clock(Realtime): error: 263 ns, adjusted frequency from 2299774365 Hz to 2299775213 Hz D0229 21:19:03.798937 5995 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8976219420305042 monotonicBaseRef:56000684254 monotonicFrequency:2299774664 realtimeReady:1 realtimeBaseCycles:8976219420880839 realtimeBaseRef:1583011143798788255 realtimeFrequency:2299775213} D0229 21:19:04.798224 5995 parameters.go:238] Clock(Monotonic): error: -90 ns, adjusted frequency from 2299774664 Hz to 2299774423 Hz D0229 21:19:04.798467 5995 parameters.go:238] Clock(Realtime): error: -156 ns, adjusted frequency from 2299775213 Hz to 2299774295 Hz I0229 21:19:04.798495 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:19:04.798551 5995 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8976221719319186 monotonicBaseRef:57000353560 monotonicFrequency:2299774423 realtimeReady:1 realtimeBaseCycles:8976221719902452 realtimeBaseRef:1583011144798460570 realtimeFrequency:2299774295} D0229 21:19:04.798717 5995 task_signals.go:446] [ 1] Notified of signal 23 D0229 21:19:04.798887 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:19:04.799005 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:19:04.799195 5995 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 21:19:04.799379 5995 task_signals.go:220] [ 1] Signal 23: delivering to handler I0229 21:19:04.869480 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:19:04.869684 5995 task_signals.go:446] [ 1] Notified of signal 23 D0229 21:19:04.869799 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:19:04.869877 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:19:04.870199 5995 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 21:19:04.870480 5995 task_signals.go:220] [ 1] Signal 23: delivering to handler D0229 21:19:05.798186 5995 parameters.go:238] Clock(Monotonic): error: 392 ns, adjusted frequency from 2299774423 Hz to 2299775390 Hz I0229 21:19:05.798407 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:19:05.798438 5995 parameters.go:238] Clock(Realtime): error: -293 ns, adjusted frequency from 2299774295 Hz to 2299773993 Hz D0229 21:19:05.798648 5995 task_signals.go:446] [ 1] Notified of signal 23 D0229 21:19:05.798665 5995 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8976224018996527 monotonicBaseRef:58000311346 monotonicFrequency:2299775390 realtimeReady:1 realtimeBaseCycles:8976224019611294 realtimeBaseRef:1583011145798432109 realtimeFrequency:2299773993} D0229 21:19:05.798870 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:19:05.798972 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:19:05.799171 5995 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 21:19:05.799294 5995 task_signals.go:220] [ 1] Signal 23: delivering to handler I0229 21:19:06.798356 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:19:06.798566 5995 task_signals.go:446] [ 1] Notified of signal 23 D0229 21:19:06.798203 5995 parameters.go:238] Clock(Monotonic): error: -151 ns, adjusted frequency from 2299775390 Hz to 2299774301 Hz D0229 21:19:06.798681 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:19:06.798723 5995 task_block.go:223] [ 1] Interrupt queued I0229 21:19:06.798802 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:19:06.798757 5995 parameters.go:238] Clock(Realtime): error: 615 ns, adjusted frequency from 2299773993 Hz to 2299776078 Hz D0229 21:19:06.798877 5995 task_signals.go:434] [ 1] Discarding duplicate signal 23 D0229 21:19:06.798995 5995 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 21:19:06.798931 5995 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8976226318812189 monotonicBaseRef:59000328857 monotonicFrequency:2299774301 realtimeReady:1 realtimeBaseCycles:8976226320101806 realtimeBaseRef:1583011146798743669 realtimeFrequency:2299776078} D0229 21:19:06.799122 5995 task_signals.go:220] [ 1] Signal 23: delivering to handler D0229 21:19:07.798607 5995 parameters.go:238] Clock(Monotonic): error: 243 ns, adjusted frequency from 2299774301 Hz to 2299775240 Hz D0229 21:19:07.798808 5995 parameters.go:238] Clock(Realtime): error: -385 ns, adjusted frequency from 2299776078 Hz to 2299773753 Hz D0229 21:19:07.798942 5995 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8976228619519890 monotonicBaseRef:60000734722 monotonicFrequency:2299775240 realtimeReady:1 realtimeBaseCycles:8976228620014182 realtimeBaseRef:1583011147798802934 realtimeFrequency:2299773753} I0229 21:19:07.798963 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:19:07.799210 5995 task_signals.go:446] [ 1] Notified of signal 23 D0229 21:19:07.799316 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:19:07.799436 5995 task_block.go:223] [ 1] Interrupt queued I0229 21:19:07.799545 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:19:07.799681 5995 task_signals.go:446] [ 1] Notified of signal 23 D0229 21:19:07.799677 5995 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 21:19:07.799783 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:19:07.799846 5995 task_signals.go:220] [ 1] Signal 23: delivering to handler D0229 21:19:07.800273 5995 task_block.go:223] [ 2] Interrupt queued D0229 21:19:07.800390 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:19:07.800427 5995 task_block.go:223] [ 2] Interrupt queued D0229 21:19:07.800583 5995 task_signals.go:176] [ 2] Not restarting syscall 202 after errno 516: interrupted by signal 23 D0229 21:19:07.800707 5995 task_signals.go:220] [ 2] Signal 23: delivering to handler D0229 21:19:08.798099 5995 parameters.go:238] Clock(Monotonic): error: -58 ns, adjusted frequency from 2299775240 Hz to 2299774362 Hz D0229 21:19:08.798283 5995 parameters.go:238] Clock(Realtime): error: -42 ns, adjusted frequency from 2299773753 Hz to 2299774510 Hz D0229 21:19:08.798393 5995 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8976230918134140 monotonicBaseRef:61000229894 monotonicFrequency:2299774362 realtimeReady:1 realtimeBaseCycles:8976230918582508 realtimeBaseRef:1583011148798278783 realtimeFrequency:2299774510} I0229 21:19:08.869538 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:19:08.869754 5995 task_signals.go:446] [ 1] Notified of signal 23 D0229 21:19:08.869898 5995 task_block.go:223] [ 1] Interrupt queued I0229 21:19:08.870049 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:19:08.870063 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:19:08.870147 5995 task_signals.go:434] [ 1] Discarding duplicate signal 23 D0229 21:19:08.870303 5995 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 21:19:08.870449 5995 task_signals.go:220] [ 1] Signal 23: delivering to handler I0229 21:19:08.893541 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:19:08.893822 5995 task_signals.go:446] [ 1] Notified of signal 23 D0229 21:19:08.893930 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:19:08.893982 5995 task_block.go:223] [ 1] Interrupt queued I0229 21:19:08.894109 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:19:08.894154 5995 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 21:19:08.894232 5995 task_signals.go:446] [ 1] Notified of signal 23 D0229 21:19:08.894263 5995 task_signals.go:220] [ 1] Signal 23: delivering to handler D0229 21:19:08.894347 5995 task_block.go:223] [ 1] Interrupt queued I0229 21:19:08.894441 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:19:08.894545 5995 task_signals.go:434] [ 1] Discarding duplicate signal 23 D0229 21:19:08.894780 5995 task_block.go:223] [ 2] Interrupt queued D0229 21:19:08.894883 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:19:08.894846 5995 task_block.go:223] [ 2] Interrupt queued D0229 21:19:08.895152 5995 task_signals.go:176] [ 2] Not restarting syscall 202 after errno 516: interrupted by signal 23 D0229 21:19:08.895261 5995 task_signals.go:220] [ 2] Signal 23: delivering to handler D0229 21:19:09.798214 5995 parameters.go:238] Clock(Monotonic): error: -231 ns, adjusted frequency from 2299774362 Hz to 2299774062 Hz D0229 21:19:09.798455 5995 parameters.go:238] Clock(Realtime): error: 155 ns, adjusted frequency from 2299774510 Hz to 2299774991 Hz I0229 21:19:09.798377 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:19:09.798596 5995 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8976233218167703 monotonicBaseRef:62000342601 monotonicFrequency:2299774062 realtimeReady:1 realtimeBaseCycles:8976233218744624 realtimeBaseRef:1583011149798447323 realtimeFrequency:2299774991} D0229 21:19:09.798688 5995 task_signals.go:446] [ 1] Notified of signal 23 D0229 21:19:09.799010 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:19:09.799048 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:19:09.799367 5995 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 21:19:09.799489 5995 task_signals.go:220] [ 1] Signal 23: delivering to handler I0229 21:19:10.644935 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:19:10.645193 5995 task_signals.go:446] [ 1] Notified of signal 23 D0229 21:19:10.645316 5995 urpc.go:577] urpc: unmarshal success. D0229 21:19:10.645407 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:19:10.645335 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:19:10.645528 5995 controller.go:501] containerManager.Signal &{CID:ci-gvisor-ptrace-proxy-sandbox-race-0 Signo:0 PID:0 Mode:Process} D0229 21:19:10.645761 5995 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 21:19:10.645860 5995 urpc.go:534] urpc: successfully marshalled 37 bytes. I0229 21:19:10.645811 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:19:10.645949 5995 task_signals.go:220] [ 1] Signal 23: delivering to handler D0229 21:19:10.646018 5995 task_signals.go:446] [ 1] Notified of signal 23 D0229 21:19:10.646164 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:19:10.646433 5995 task_block.go:223] [ 2] Interrupt queued D0229 21:19:10.646549 5995 task_block.go:223] [ 2] Interrupt queued D0229 21:19:10.646551 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:19:10.646731 5995 task_signals.go:176] [ 2] Not restarting syscall 202 after errno 516: interrupted by signal 23 D0229 21:19:10.646841 5995 task_signals.go:220] [ 2] Signal 23: delivering to handler D0229 21:19:10.798248 5995 parameters.go:238] Clock(Monotonic): error: 170 ns, adjusted frequency from 2299774062 Hz to 2299775029 Hz D0229 21:19:10.798475 5995 parameters.go:238] Clock(Realtime): error: -10 ns, adjusted frequency from 2299774991 Hz to 2299774609 Hz D0229 21:19:10.798641 5995 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8976235518013788 monotonicBaseRef:63000373918 monotonicFrequency:2299775029 realtimeReady:1 realtimeBaseCycles:8976235518570303 realtimeBaseRef:1583011150798469363 realtimeFrequency:2299774609} I0229 21:19:10.869873 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:19:10.870108 5995 task_signals.go:446] [ 1] Notified of signal 23 D0229 21:19:10.870268 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:19:10.870394 5995 task_block.go:223] [ 1] Interrupt queued I0229 21:19:10.870461 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:19:10.870607 5995 task_signals.go:434] [ 1] Discarding duplicate signal 23 D0229 21:19:10.870810 5995 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 21:19:10.870974 5995 task_signals.go:220] [ 1] Signal 23: delivering to handler D0229 21:19:11.800311 5995 parameters.go:238] Clock(Monotonic): error: 243 ns, adjusted frequency from 2299775029 Hz to 2299775119 Hz D0229 21:19:11.800484 5995 parameters.go:238] Clock(Realtime): error: -59 ns, adjusted frequency from 2299774609 Hz to 2299774495 Hz D0229 21:19:11.800586 5995 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8976237822542690 monotonicBaseRef:64002441021 monotonicFrequency:2299775119 realtimeReady:1 realtimeBaseCycles:8976237822967190 realtimeBaseRef:1583011151800479246 realtimeFrequency:2299774495} D0229 21:19:12.798291 5995 parameters.go:238] Clock(Monotonic): error: -92 ns, adjusted frequency from 2299775119 Hz to 2299774353 Hz I0229 21:19:12.798389 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:19:12.798500 5995 parameters.go:238] Clock(Realtime): error: 173 ns, adjusted frequency from 2299774495 Hz to 2299775025 Hz D0229 21:19:12.798630 5995 task_signals.go:446] [ 1] Notified of signal 23 D0229 21:19:12.798651 5995 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8976240117676192 monotonicBaseRef:65000422729 monotonicFrequency:2299774353 realtimeReady:1 realtimeBaseCycles:8976240118176087 realtimeBaseRef:1583011152798494008 realtimeFrequency:2299775025} D0229 21:19:12.798726 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:19:12.798772 5995 task_block.go:223] [ 1] Interrupt queued I0229 21:19:12.798867 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:19:12.798973 5995 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 21:19:12.799075 5995 task_signals.go:220] [ 1] Signal 23: delivering to handler D0229 21:19:12.798986 5995 task_signals.go:446] [ 1] Notified of signal 23 D0229 21:19:12.799259 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:19:12.799459 5995 task_block.go:223] [ 2] Interrupt queued D0229 21:19:12.799603 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:19:12.799573 5995 task_block.go:223] [ 2] Interrupt queued D0229 21:19:12.799792 5995 task_signals.go:176] [ 2] Not restarting syscall 202 after errno 516: interrupted by signal 23 D0229 21:19:12.799943 5995 task_signals.go:220] [ 2] Signal 23: delivering to handler D0229 21:19:13.798182 5995 parameters.go:238] Clock(Monotonic): error: 16 ns, adjusted frequency from 2299774353 Hz to 2299774525 Hz D0229 21:19:13.798370 5995 parameters.go:238] Clock(Realtime): error: -75 ns, adjusted frequency from 2299775025 Hz to 2299774433 Hz D0229 21:19:13.798484 5995 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8976242417112619 monotonicBaseRef:66000275790 monotonicFrequency:2299774525 realtimeReady:1 realtimeBaseCycles:8976242417652236 realtimeBaseRef:1583011153798364049 realtimeFrequency:2299774433} I0229 21:19:13.869373 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:19:13.869612 5995 task_signals.go:446] [ 1] Notified of signal 23 D0229 21:19:13.869725 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:19:13.869779 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:19:13.870035 5995 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 21:19:13.870203 5995 task_signals.go:220] [ 1] Signal 23: delivering to handler D0229 21:19:14.798133 5995 parameters.go:238] Clock(Monotonic): error: 105 ns, adjusted frequency from 2299774525 Hz to 2299774870 Hz D0229 21:19:14.798311 5995 parameters.go:238] Clock(Realtime): error: -416 ns, adjusted frequency from 2299774433 Hz to 2299773667 Hz D0229 21:19:14.798473 5995 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8976244716861615 monotonicBaseRef:67000264689 monotonicFrequency:2299774870 realtimeReady:1 realtimeBaseCycles:8976244717290447 realtimeBaseRef:1583011154798304816 realtimeFrequency:2299773667} D0229 21:19:15.798793 5995 parameters.go:238] Clock(Monotonic): error: -37 ns, adjusted frequency from 2299774870 Hz to 2299774436 Hz D0229 21:19:15.798979 5995 parameters.go:238] Clock(Realtime): error: 326 ns, adjusted frequency from 2299773667 Hz to 2299775395 Hz D0229 21:19:15.799126 5995 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8976247018155884 monotonicBaseRef:68000925361 monotonicFrequency:2299774436 realtimeReady:1 realtimeBaseCycles:8976247018603261 realtimeBaseRef:1583011155798974076 realtimeFrequency:2299775395} I0229 21:19:15.869515 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:19:15.869782 5995 task_signals.go:446] [ 1] Notified of signal 23 D0229 21:19:15.869979 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:19:15.870012 5995 task_block.go:223] [ 1] Interrupt queued I0229 21:19:15.870147 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:19:15.870280 5995 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 21:19:15.870374 5995 task_signals.go:446] [ 1] Notified of signal 23 D0229 21:19:15.870464 5995 task_signals.go:220] [ 1] Signal 23: delivering to handler D0229 21:19:15.870555 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:19:15.871064 5995 task_block.go:223] [ 2] Interrupt queued D0229 21:19:15.871150 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:19:15.871215 5995 task_block.go:223] [ 2] Interrupt queued D0229 21:19:15.871438 5995 task_signals.go:176] [ 2] Not restarting syscall 202 after errno 516: interrupted by signal 23 D0229 21:19:15.871566 5995 task_signals.go:220] [ 2] Signal 23: delivering to handler D0229 21:19:16.798209 5995 parameters.go:238] Clock(Monotonic): error: 85 ns, adjusted frequency from 2299774436 Hz to 2299774805 Hz D0229 21:19:16.798376 5995 parameters.go:238] Clock(Realtime): error: -143 ns, adjusted frequency from 2299775395 Hz to 2299774280 Hz D0229 21:19:16.798504 5995 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8976249316572298 monotonicBaseRef:69000334858 monotonicFrequency:2299774805 realtimeReady:1 realtimeBaseCycles:8976249316995358 realtimeBaseRef:1583011156798372583 realtimeFrequency:2299774280} D0229 21:19:17.798338 5995 parameters.go:238] Clock(Monotonic): error: -158 ns, adjusted frequency from 2299774805 Hz to 2299774405 Hz D0229 21:19:17.798563 5995 parameters.go:238] Clock(Realtime): error: 332 ns, adjusted frequency from 2299774280 Hz to 2299775418 Hz D0229 21:19:17.798667 5995 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8976251616640257 monotonicBaseRef:70000462328 monotonicFrequency:2299774405 realtimeReady:1 realtimeBaseCycles:8976251617192421 realtimeBaseRef:1583011157798556419 realtimeFrequency:2299775418} D0229 21:19:18.798186 5995 parameters.go:238] Clock(Monotonic): error: 712 ns, adjusted frequency from 2299774405 Hz to 2299776380 Hz D0229 21:19:18.798409 5995 parameters.go:238] Clock(Realtime): error: -346 ns, adjusted frequency from 2299775418 Hz to 2299773890 Hz D0229 21:19:18.798575 5995 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8976253916079082 monotonicBaseRef:71000316409 monotonicFrequency:2299776380 realtimeReady:1 realtimeBaseCycles:8976253916611668 realtimeBaseRef:1583011158798401546 realtimeFrequency:2299773890} I0229 21:19:18.869412 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:19:18.869638 5995 task_signals.go:446] [ 1] Notified of signal 23 D0229 21:19:18.869776 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:19:18.869816 5995 task_block.go:223] [ 1] Interrupt queued I0229 21:19:18.869916 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:19:18.870004 5995 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 21:19:18.870031 5995 task_signals.go:446] [ 1] Notified of signal 23 D0229 21:19:18.870112 5995 task_signals.go:220] [ 1] Signal 23: delivering to handler D0229 21:19:18.870164 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:19:18.870844 5995 task_block.go:223] [ 2] Interrupt queued D0229 21:19:18.870947 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:19:18.870960 5995 task_block.go:223] [ 2] Interrupt queued D0229 21:19:18.871164 5995 task_signals.go:176] [ 2] Not restarting syscall 202 after errno 516: interrupted by signal 23 D0229 21:19:18.871266 5995 task_signals.go:220] [ 2] Signal 23: delivering to handler D0229 21:19:19.798173 5995 parameters.go:238] Clock(Monotonic): error: -312 ns, adjusted frequency from 2299776380 Hz to 2299773849 Hz I0229 21:19:19.798382 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:19:19.798411 5995 parameters.go:238] Clock(Realtime): error: 363 ns, adjusted frequency from 2299773890 Hz to 2299775466 Hz D0229 21:19:19.798590 5995 task_signals.go:446] [ 1] Notified of signal 23 D0229 21:19:19.798631 5995 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8976256215809371 monotonicBaseRef:72000296367 monotonicFrequency:2299773849 realtimeReady:1 realtimeBaseCycles:8976256216394013 realtimeBaseRef:1583011159798405222 realtimeFrequency:2299775466} D0229 21:19:19.798736 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:19:19.798800 5995 task_block.go:223] [ 1] Interrupt queued I0229 21:19:19.798922 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:19:19.799019 5995 task_signals.go:434] [ 1] Discarding duplicate signal 23 D0229 21:19:19.799185 5995 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 21:19:19.799313 5995 task_signals.go:220] [ 1] Signal 23: delivering to handler D0229 21:19:20.798259 5995 parameters.go:238] Clock(Monotonic): error: -417 ns, adjusted frequency from 2299773849 Hz to 2299773662 Hz D0229 21:19:20.798473 5995 parameters.go:238] Clock(Realtime): error: -70 ns, adjusted frequency from 2299775466 Hz to 2299774490 Hz D0229 21:19:20.798585 5995 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8976258515802147 monotonicBaseRef:73000391562 monotonicFrequency:2299773662 realtimeReady:1 realtimeBaseCycles:8976258516310354 realtimeBaseRef:1583011160798466477 realtimeFrequency:2299774490} D0229 21:19:21.798090 5995 parameters.go:238] Clock(Monotonic): error: 34 ns, adjusted frequency from 2299773662 Hz to 2299774711 Hz D0229 21:19:21.798302 5995 parameters.go:238] Clock(Realtime): error: 50 ns, adjusted frequency from 2299774490 Hz to 2299774737 Hz D0229 21:19:21.798448 5995 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8976260815192323 monotonicBaseRef:74000224812 monotonicFrequency:2299774711 realtimeReady:1 realtimeBaseCycles:8976260815688384 realtimeBaseRef:1583011161798294086 realtimeFrequency:2299774737} D0229 21:19:22.798171 5995 parameters.go:238] Clock(Monotonic): error: -353 ns, adjusted frequency from 2299774711 Hz to 2299773958 Hz D0229 21:19:22.798360 5995 parameters.go:238] Clock(Realtime): error: -109 ns, adjusted frequency from 2299774737 Hz to 2299774404 Hz D0229 21:19:22.798485 5995 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8976263115148696 monotonicBaseRef:75000303803 monotonicFrequency:2299773958 realtimeReady:1 realtimeBaseCycles:8976263115603394 realtimeBaseRef:1583011162798355080 realtimeFrequency:2299774404} I0229 21:19:22.869491 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:19:22.869712 5995 task_signals.go:446] [ 1] Notified of signal 23 D0229 21:19:22.869894 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:19:22.870038 5995 task_block.go:223] [ 1] Interrupt queued I0229 21:19:22.870052 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:19:22.870230 5995 task_signals.go:446] [ 1] Notified of signal 23 D0229 21:19:22.870212 5995 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 21:19:22.870348 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:19:22.870371 5995 task_signals.go:220] [ 1] Signal 23: delivering to handler D0229 21:19:22.870823 5995 task_block.go:223] [ 2] Interrupt queued D0229 21:19:22.871024 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:19:22.871362 5995 task_signals.go:220] [ 2] Signal 23: delivering to handler D0229 21:19:23.798187 5995 parameters.go:238] Clock(Monotonic): error: 24 ns, adjusted frequency from 2299773958 Hz to 2299774759 Hz D0229 21:19:23.798400 5995 parameters.go:238] Clock(Realtime): error: -100 ns, adjusted frequency from 2299774404 Hz to 2299774362 Hz D0229 21:19:23.798530 5995 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8976265414929923 monotonicBaseRef:76000306963 monotonicFrequency:2299774759 realtimeReady:1 realtimeBaseCycles:8976265415465973 realtimeBaseRef:1583011163798393420 realtimeFrequency:2299774362} I0229 21:19:23.869463 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:19:23.869674 5995 task_signals.go:446] [ 1] Notified of signal 23 D0229 21:19:23.869806 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:19:23.869883 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:19:23.870071 5995 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 21:19:23.870183 5995 task_signals.go:220] [ 1] Signal 23: delivering to handler D0229 21:19:24.798179 5995 parameters.go:238] Clock(Monotonic): error: 309 ns, adjusted frequency from 2299774759 Hz to 2299775380 Hz D0229 21:19:24.798802 5995 parameters.go:238] Clock(Realtime): error: 76 ns, adjusted frequency from 2299774362 Hz to 2299774789 Hz D0229 21:19:24.798930 5995 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8976267714716277 monotonicBaseRef:77000312004 monotonicFrequency:2299775380 realtimeReady:1 realtimeBaseCycles:8976267716161248 realtimeBaseRef:1583011164798793856 realtimeFrequency:2299774789} D0229 21:19:25.798160 5995 parameters.go:238] Clock(Monotonic): error: 48 ns, adjusted frequency from 2299775380 Hz to 2299774732 Hz D0229 21:19:25.798414 5995 parameters.go:238] Clock(Realtime): error: -22 ns, adjusted frequency from 2299774789 Hz to 2299774578 Hz D0229 21:19:25.798576 5995 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8976270014441778 monotonicBaseRef:78000290315 monotonicFrequency:2299774732 realtimeReady:1 realtimeBaseCycles:8976270015048205 realtimeBaseRef:1583011165798407804 realtimeFrequency:2299774578} I0229 21:19:25.869979 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:19:25.870238 5995 task_signals.go:446] [ 1] Notified of signal 23 D0229 21:19:25.870352 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:19:25.870444 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:19:25.870652 5995 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 21:19:25.870751 5995 task_signals.go:220] [ 1] Signal 23: delivering to handler I0229 21:19:25.871661 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:19:25.872115 5995 task_signals.go:446] [ 1] Notified of signal 23 D0229 21:19:25.872343 5995 task_block.go:223] [ 1] Interrupt queued I0229 21:19:25.872543 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:19:25.872678 5995 task_signals.go:434] [ 1] Discarding duplicate signal 23 D0229 21:19:25.874312 5995 task_block.go:223] [ 2] Interrupt queued I0229 21:19:25.874300 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:19:25.874410 5995 task_block.go:223] [ 2] Interrupt queued D0229 21:19:25.874517 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:19:25.874801 5995 task_signals.go:434] [ 1] Discarding duplicate signal 23 I0229 21:19:25.875015 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:19:25.875019 5995 task_signals.go:176] [ 2] Not restarting syscall 202 after errno 516: interrupted by signal 23 D0229 21:19:25.875173 5995 task_signals.go:452] [ 2] Notified of group signal 23 D0229 21:19:25.875230 5995 task_signals.go:220] [ 2] Signal 23: delivering to handler D0229 21:19:25.875295 5995 task_block.go:223] [ 2] Interrupt queued D0229 21:19:25.875756 5995 task_block.go:223] [ 3] Interrupt queued D0229 21:19:25.875762 5995 task_block.go:223] [ 3] Interrupt queued D0229 21:19:25.875948 5995 task_block.go:223] [ 2] Interrupt queued D0229 21:19:25.876212 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:19:25.876362 5995 task_signals.go:220] [ 1] Signal 23: delivering to handler D0229 21:19:25.876405 5995 task_run.go:155] [ 3] Restarting syscall 202 after errno 512: not interrupted by handled signal D0229 21:19:26.798008 5995 parameters.go:238] Clock(Monotonic): error: -132 ns, adjusted frequency from 2299774732 Hz to 2299774173 Hz D0229 21:19:26.798194 5995 parameters.go:238] Clock(Realtime): error: -91 ns, adjusted frequency from 2299774578 Hz to 2299774405 Hz D0229 21:19:26.798272 5995 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8976272313871277 monotonicBaseRef:79000140198 monotonicFrequency:2299774173 realtimeReady:1 realtimeBaseCycles:8976272314318602 realtimeBaseRef:1583011166798188573 realtimeFrequency:2299774405} D0229 21:19:27.798126 5995 parameters.go:238] Clock(Monotonic): error: 251 ns, adjusted frequency from 2299774173 Hz to 2299775041 Hz I0229 21:19:27.798277 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:19:27.798336 5995 parameters.go:238] Clock(Realtime): error: 67 ns, adjusted frequency from 2299774405 Hz to 2299774765 Hz D0229 21:19:27.798541 5995 task_signals.go:446] [ 1] Notified of signal 23 D0229 21:19:27.798519 5995 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8976274613908184 monotonicBaseRef:80000254441 monotonicFrequency:2299775041 realtimeReady:1 realtimeBaseCycles:8976274614397480 realtimeBaseRef:1583011167798320965 realtimeFrequency:2299774765} D0229 21:19:27.798653 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:19:27.798689 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:19:27.798995 5995 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 21:19:27.799133 5995 task_signals.go:220] [ 1] Signal 23: delivering to handler I0229 21:19:27.869471 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:19:27.869643 5995 task_signals.go:446] [ 1] Notified of signal 23 D0229 21:19:27.869746 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:19:27.869910 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:19:27.870028 5995 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 21:19:27.870158 5995 task_signals.go:220] [ 1] Signal 23: delivering to handler D0229 21:19:28.798192 5995 parameters.go:238] Clock(Monotonic): error: -70 ns, adjusted frequency from 2299775041 Hz to 2299774404 Hz D0229 21:19:28.798401 5995 parameters.go:238] Clock(Realtime): error: -87 ns, adjusted frequency from 2299774765 Hz to 2299774404 Hz D0229 21:19:28.798554 5995 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8976276913842868 monotonicBaseRef:81000323857 monotonicFrequency:2299774404 realtimeReady:1 realtimeBaseCycles:8976276914343053 realtimeBaseRef:1583011168798395236 realtimeFrequency:2299774404} D0229 21:19:29.798181 5995 parameters.go:238] Clock(Monotonic): error: -5 ns, adjusted frequency from 2299774404 Hz to 2299774694 Hz I0229 21:19:29.798366 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:19:29.798442 5995 parameters.go:238] Clock(Realtime): error: 3 ns, adjusted frequency from 2299774404 Hz to 2299774662 Hz D0229 21:19:29.798642 5995 task_signals.go:446] [ 1] Notified of signal 23 D0229 21:19:29.798636 5995 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8976279213575986 monotonicBaseRef:82000305904 monotonicFrequency:2299774694 realtimeReady:1 realtimeBaseCycles:8976279214208857 realtimeBaseRef:1583011169798434979 realtimeFrequency:2299774662} D0229 21:19:29.798824 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:19:29.798991 5995 task_block.go:223] [ 1] Interrupt queued I0229 21:19:29.799154 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:19:29.799281 5995 task_signals.go:446] [ 1] Notified of signal 23 D0229 21:19:29.799272 5995 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 21:19:29.799408 5995 task_signals.go:220] [ 1] Signal 23: delivering to handler D0229 21:19:29.799392 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:19:29.799848 5995 task_block.go:223] [ 2] Interrupt queued D0229 21:19:29.799972 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:19:29.800024 5995 task_block.go:223] [ 2] Interrupt queued D0229 21:19:29.800214 5995 task_signals.go:176] [ 2] Not restarting syscall 202 after errno 516: interrupted by signal 23 D0229 21:19:29.800344 5995 task_signals.go:220] [ 2] Signal 23: delivering to handler D0229 21:19:30.798369 5995 parameters.go:238] Clock(Monotonic): error: -389 ns, adjusted frequency from 2299774694 Hz to 2299773808 Hz D0229 21:19:30.798541 5995 parameters.go:238] Clock(Realtime): error: 318 ns, adjusted frequency from 2299774662 Hz to 2299775368 Hz D0229 21:19:30.798696 5995 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8976281513801180 monotonicBaseRef:83000501792 monotonicFrequency:2299773808 realtimeReady:1 realtimeBaseCycles:8976281514213792 realtimeBaseRef:1583011170798535107 realtimeFrequency:2299775368} I0229 21:19:30.869459 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:19:30.869745 5995 task_signals.go:446] [ 1] Notified of signal 23 D0229 21:19:30.869990 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:19:30.870023 5995 task_block.go:223] [ 1] Interrupt queued I0229 21:19:30.870156 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:19:30.870277 5995 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 21:19:30.870297 5995 task_signals.go:446] [ 1] Notified of signal 23 D0229 21:19:30.870448 5995 task_signals.go:220] [ 1] Signal 23: delivering to handler D0229 21:19:30.870519 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:19:30.870798 5995 task_block.go:223] [ 2] Interrupt queued D0229 21:19:30.870914 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:19:30.870935 5995 task_block.go:223] [ 2] Interrupt queued D0229 21:19:30.871105 5995 task_signals.go:176] [ 2] Not restarting syscall 202 after errno 516: interrupted by signal 23 D0229 21:19:30.871244 5995 task_signals.go:220] [ 2] Signal 23: delivering to handler D0229 21:19:31.798184 5995 parameters.go:238] Clock(Monotonic): error: 480 ns, adjusted frequency from 2299773808 Hz to 2299775628 Hz D0229 21:19:31.798427 5995 parameters.go:238] Clock(Realtime): error: -78 ns, adjusted frequency from 2299775368 Hz to 2299774428 Hz D0229 21:19:31.798583 5995 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8976283813134489 monotonicBaseRef:84000310251 monotonicFrequency:2299775628 realtimeReady:1 realtimeBaseCycles:8976283813723777 realtimeBaseRef:1583011171798419711 realtimeFrequency:2299774428} I0229 21:19:31.869464 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:19:31.869665 5995 task_signals.go:446] [ 1] Notified of signal 23 D0229 21:19:31.869807 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:19:31.869822 5995 task_block.go:223] [ 1] Interrupt queued I0229 21:19:31.869946 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:19:31.870088 5995 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 21:19:31.870101 5995 task_signals.go:446] [ 1] Notified of signal 23 D0229 21:19:31.870248 5995 task_signals.go:220] [ 1] Signal 23: delivering to handler D0229 21:19:31.870386 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:19:31.870801 5995 task_block.go:223] [ 2] Interrupt queued D0229 21:19:31.870841 5995 task_block.go:223] [ 2] Interrupt queued D0229 21:19:31.871017 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:19:31.871365 5995 task_signals.go:176] [ 2] Not restarting syscall 202 after errno 516: interrupted by signal 23 D0229 21:19:31.871545 5995 task_signals.go:220] [ 2] Signal 23: delivering to handler D0229 21:19:32.798172 5995 parameters.go:238] Clock(Monotonic): error: 33 ns, adjusted frequency from 2299775628 Hz to 2299774687 Hz D0229 21:19:32.798376 5995 parameters.go:238] Clock(Realtime): error: -192 ns, adjusted frequency from 2299774428 Hz to 2299774207 Hz I0229 21:19:32.798450 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:19:32.798522 5995 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8976286112895991 monotonicBaseRef:85000304108 monotonicFrequency:2299774687 realtimeReady:1 realtimeBaseCycles:8976286113385243 realtimeBaseRef:1583011172798370592 realtimeFrequency:2299774207} D0229 21:19:32.798624 5995 task_signals.go:446] [ 1] Notified of signal 23 D0229 21:19:32.798705 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:19:32.798801 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:19:32.799081 5995 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 21:19:32.799213 5995 task_signals.go:220] [ 1] Signal 23: delivering to handler I0229 21:19:32.869490 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:19:32.869647 5995 task_signals.go:446] [ 1] Notified of signal 23 D0229 21:19:32.869759 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:19:32.869892 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:19:32.870138 5995 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 21:19:32.870304 5995 task_signals.go:220] [ 1] Signal 23: delivering to handler D0229 21:19:33.798141 5995 parameters.go:238] Clock(Monotonic): error: -99 ns, adjusted frequency from 2299774687 Hz to 2299774356 Hz D0229 21:19:33.798315 5995 parameters.go:238] Clock(Realtime): error: -95 ns, adjusted frequency from 2299774207 Hz to 2299774417 Hz D0229 21:19:33.798408 5995 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8976288412506674 monotonicBaseRef:86000232794 monotonicFrequency:2299774356 realtimeReady:1 realtimeBaseCycles:8976288413018588 realtimeBaseRef:1583011173798309341 realtimeFrequency:2299774417} I0229 21:19:33.798437 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:19:33.798670 5995 task_signals.go:446] [ 1] Notified of signal 23 D0229 21:19:33.798807 5995 task_block.go:223] [ 1] Interrupt queued I0229 21:19:33.798927 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:19:33.798953 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:19:33.799086 5995 task_signals.go:434] [ 1] Discarding duplicate signal 23 D0229 21:19:33.799265 5995 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 21:19:33.799432 5995 task_signals.go:220] [ 1] Signal 23: delivering to handler I0229 21:19:33.869571 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:19:33.869848 5995 task_signals.go:446] [ 1] Notified of signal 23 D0229 21:19:33.869997 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:19:33.870128 5995 task_block.go:223] [ 1] Interrupt queued I0229 21:19:33.870177 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:19:33.870299 5995 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 21:19:33.870333 5995 task_signals.go:446] [ 1] Notified of signal 23 D0229 21:19:33.870440 5995 task_signals.go:220] [ 1] Signal 23: delivering to handler D0229 21:19:33.870483 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:19:33.870894 5995 task_block.go:223] [ 2] Interrupt queued D0229 21:19:33.871026 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:19:33.871290 5995 task_signals.go:220] [ 2] Signal 23: delivering to handler D0229 21:19:34.798205 5995 parameters.go:238] Clock(Monotonic): error: -189 ns, adjusted frequency from 2299774356 Hz to 2299774152 Hz D0229 21:19:34.798365 5995 parameters.go:238] Clock(Realtime): error: -33 ns, adjusted frequency from 2299774417 Hz to 2299774566 Hz I0229 21:19:34.798341 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:19:34.798518 5995 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8976290712518113 monotonicBaseRef:87000335883 monotonicFrequency:2299774152 realtimeReady:1 realtimeBaseCycles:8976290712910006 realtimeBaseRef:1583011174798360215 realtimeFrequency:2299774566} D0229 21:19:34.798723 5995 task_signals.go:446] [ 1] Notified of signal 23 D0229 21:19:34.798915 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:19:34.798942 5995 task_block.go:223] [ 1] Interrupt queued I0229 21:19:34.799096 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:19:34.799232 5995 task_signals.go:434] [ 1] Discarding duplicate signal 23 D0229 21:19:34.799517 5995 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 21:19:34.799796 5995 task_signals.go:220] [ 1] Signal 23: delivering to handler I0229 21:19:34.869962 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:19:34.870171 5995 task_signals.go:446] [ 1] Notified of signal 23 D0229 21:19:34.870344 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:19:34.870403 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:19:34.870590 5995 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 21:19:34.870748 5995 task_signals.go:220] [ 1] Signal 23: delivering to handler D0229 21:19:35.798194 5995 parameters.go:238] Clock(Monotonic): error: 85 ns, adjusted frequency from 2299774152 Hz to 2299774972 Hz D0229 21:19:35.798408 5995 parameters.go:238] Clock(Realtime): error: 73 ns, adjusted frequency from 2299774566 Hz to 2299774817 Hz D0229 21:19:35.798531 5995 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8976293012265232 monotonicBaseRef:88000324128 monotonicFrequency:2299774972 realtimeReady:1 realtimeBaseCycles:8976293012780952 realtimeBaseRef:1583011175798402123 realtimeFrequency:2299774817} D0229 21:19:36.798154 5995 parameters.go:238] Clock(Monotonic): error: 38 ns, adjusted frequency from 2299774972 Hz to 2299774632 Hz D0229 21:19:36.798351 5995 parameters.go:238] Clock(Realtime): error: -74 ns, adjusted frequency from 2299774817 Hz to 2299774433 Hz D0229 21:19:36.798456 5995 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8976295311949121 monotonicBaseRef:89000284522 monotonicFrequency:2299774632 realtimeReady:1 realtimeBaseCycles:8976295312428277 realtimeBaseRef:1583011176798346686 realtimeFrequency:2299774433} I0229 21:19:36.869530 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:19:36.869793 5995 task_signals.go:446] [ 1] Notified of signal 23 D0229 21:19:36.869986 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:19:36.870030 5995 task_block.go:223] [ 1] Interrupt queued I0229 21:19:36.870145 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:19:36.870286 5995 task_signals.go:446] [ 1] Notified of signal 23 D0229 21:19:36.870251 5995 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 21:19:36.870371 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:19:36.870390 5995 task_signals.go:220] [ 1] Signal 23: delivering to handler D0229 21:19:36.870893 5995 task_block.go:223] [ 2] Interrupt queued D0229 21:19:36.870877 5995 task_block.go:223] [ 2] Interrupt queued D0229 21:19:36.871065 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:19:36.871352 5995 task_signals.go:176] [ 2] Not restarting syscall 202 after errno 516: interrupted by signal 23 D0229 21:19:36.871533 5995 task_signals.go:220] [ 2] Signal 23: delivering to handler D0229 21:19:37.798713 5995 parameters.go:238] Clock(Monotonic): error: 49 ns, adjusted frequency from 2299774632 Hz to 2299774791 Hz D0229 21:19:37.798925 5995 parameters.go:238] Clock(Realtime): error: 83 ns, adjusted frequency from 2299774433 Hz to 2299774859 Hz I0229 21:19:37.799064 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:19:37.799070 5995 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8976297613009536 monotonicBaseRef:90000843612 monotonicFrequency:2299774791 realtimeReady:1 realtimeBaseCycles:8976297613522123 realtimeBaseRef:1583011177798920400 realtimeFrequency:2299774859} D0229 21:19:37.799296 5995 task_signals.go:446] [ 1] Notified of signal 23 D0229 21:19:37.799437 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:19:37.799597 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:19:37.799912 5995 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 21:19:37.800032 5995 task_signals.go:220] [ 1] Signal 23: delivering to handler I0229 21:19:37.971062 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:19:37.971356 5995 task_signals.go:446] [ 1] Notified of signal 23 D0229 21:19:37.971517 5995 task_block.go:223] [ 1] Interrupt queued I0229 21:19:37.971703 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:19:37.971636 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:19:37.971786 5995 task_signals.go:434] [ 1] Discarding duplicate signal 23 I0229 21:19:37.971910 5995 watchdog.go:277] Watchdog starting loop, tasks: 16, discount: 0s D0229 21:19:37.972023 5995 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 21:19:37.972157 5995 task_signals.go:220] [ 1] Signal 23: delivering to handler D0229 21:19:38.798164 5995 parameters.go:238] Clock(Monotonic): error: 59 ns, adjusted frequency from 2299774791 Hz to 2299774655 Hz I0229 21:19:38.798391 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:19:38.798464 5995 parameters.go:238] Clock(Realtime): error: 341 ns, adjusted frequency from 2299774859 Hz to 2299775344 Hz D0229 21:19:38.798654 5995 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8976299911515986 monotonicBaseRef:91000292105 monotonicFrequency:2299774655 realtimeReady:1 realtimeBaseCycles:8976299912216481 realtimeBaseRef:1583011178798450570 realtimeFrequency:2299775344} D0229 21:19:38.799497 5995 task_signals.go:446] [ 1] Notified of signal 23 D0229 21:19:38.799706 5995 task_block.go:223] [ 1] Interrupt queued I0229 21:19:41.239982 14562 main.go:305] *************************** I0229 21:19:41.240140 14562 main.go:306] Args: [/syzkaller/managers/ptrace-proxy-sandbox-race/current/image -root /syzkaller/managers/ptrace-proxy-sandbox-race/workdir/gvisor_root -watchdog-action=panic -network=none -debug -alsologtostderr -platform=ptrace -file-access=shared -network=sandbox -TESTONLY-unsafe-nonroot exec -user=0:0 -cap CAP_CHOWN -cap CAP_DAC_OVERRIDE -cap CAP_DAC_READ_SEARCH -cap CAP_FOWNER -cap CAP_FSETID -cap CAP_KILL -cap CAP_SETGID -cap CAP_SETUID -cap CAP_SETPCAP -cap CAP_LINUX_IMMUTABLE -cap CAP_NET_BIND_SERVICE -cap CAP_NET_BROADCAST -cap CAP_NET_ADMIN -cap CAP_NET_RAW -cap CAP_IPC_LOCK -cap CAP_IPC_OWNER -cap CAP_SYS_MODULE -cap CAP_SYS_RAWIO -cap CAP_SYS_CHROOT -cap CAP_SYS_PTRACE -cap CAP_SYS_PACCT -cap CAP_SYS_ADMIN -cap CAP_SYS_BOOT -cap CAP_SYS_NICE -cap CAP_SYS_RESOURCE -cap CAP_SYS_TIME -cap CAP_SYS_TTY_CONFIG -cap CAP_MKNOD -cap CAP_LEASE -cap CAP_AUDIT_WRITE -cap CAP_AUDIT_CONTROL -cap CAP_SETFCAP -cap CAP_MAC_OVERRIDE -cap CAP_MAC_ADMIN -cap CAP_SYSLOG -cap CAP_WAKE_ALARM -cap CAP_BLOCK_SUSPEND -cap CAP_AUDIT_READ ci-gvisor-ptrace-proxy-sandbox-race-0 /syz-execprog -executor=/syz-executor -arch=amd64 -sandbox=none -procs=1 -repeat=1 -threaded=true -collide=true -cover=0 -fault_call=-1 -fault_nth=-1 /syzkaller161088748] I0229 21:19:41.240659 14562 main.go:307] Version release-20200219.0-72-gce4d1e45bb88 I0229 21:19:41.240743 14562 main.go:308] PID: 14562 I0229 21:19:41.240821 14562 main.go:309] UID: 0, GID: 0 I0229 21:19:41.240881 14562 main.go:310] Configuration: I0229 21:19:41.240953 14562 main.go:311] RootDir: /syzkaller/managers/ptrace-proxy-sandbox-race/workdir/gvisor_root I0229 21:19:41.241040 14562 main.go:312] Platform: ptrace I0229 21:19:41.241098 14562 main.go:313] FileAccess: shared, overlay: false I0229 21:19:41.241201 14562 main.go:314] Network: sandbox, logging: false I0229 21:19:41.241293 14562 main.go:315] Strace: false, max size: 1024, syscalls: [] I0229 21:19:41.241399 14562 main.go:316] *************************** W0229 21:19:41.241562 14562 main.go:321] Block the TERM signal. This is only safe in tests! D0229 21:19:41.242491 14562 container.go:159] Load container "/syzkaller/managers/ptrace-proxy-sandbox-race/workdir/gvisor_root" "ci-gvisor-ptrace-proxy-sandbox-race-0" D0229 21:19:41.252448 14562 container.go:592] Signal container "ci-gvisor-ptrace-proxy-sandbox-race-0": signal 0 D0229 21:19:41.252589 14562 sandbox.go:806] Signal sandbox "ci-gvisor-ptrace-proxy-sandbox-race-0" D0229 21:19:41.252752 14562 sandbox.go:318] Connecting to sandbox "ci-gvisor-ptrace-proxy-sandbox-race-0" D0229 21:19:41.253270 14562 urpc.go:534] urpc: successfully marshalled 117 bytes. D0229 21:19:38.799765 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:19:38.800714 5995 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 21:19:38.800946 5995 task_signals.go:220] [ 1] Signal 23: delivering to handler I0229 21:19:38.893942 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:19:38.894175 5995 task_signals.go:446] [ 1] Notified of signal 23 D0229 21:19:38.894359 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:19:38.894558 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:19:38.894818 5995 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 21:19:38.894961 5995 task_signals.go:220] [ 1] Signal 23: delivering to handler I0229 21:19:38.895028 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:19:38.895243 5995 task_signals.go:446] [ 1] Notified of signal 23 D0229 21:19:38.895334 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:19:38.895629 5995 task_block.go:223] [ 2] Interrupt queued D0229 21:19:38.895536 5995 task_block.go:223] [ 2] Interrupt queued D0229 21:19:38.896245 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:19:38.896592 5995 task_signals.go:176] [ 2] Not restarting syscall 202 after errno 516: interrupted by signal 23 D0229 21:19:38.896757 5995 task_signals.go:220] [ 2] Signal 23: delivering to handler D0229 21:19:39.798952 5995 parameters.go:238] Clock(Monotonic): error: -12 ns, adjusted frequency from 2299774655 Hz to 2299774614 Hz D0229 21:19:39.799192 5995 parameters.go:238] Clock(Realtime): error: -269 ns, adjusted frequency from 2299775344 Hz to 2299774017 Hz D0229 21:19:39.799340 5995 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8976302213096775 monotonicBaseRef:92001077457 monotonicFrequency:2299774614 realtimeReady:1 realtimeBaseCycles:8976302213684549 realtimeBaseRef:1583011179799186608 realtimeFrequency:2299774017} I0229 21:19:39.869648 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:19:39.869954 5995 task_signals.go:446] [ 1] Notified of signal 23 D0229 21:19:39.870077 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:19:39.870162 5995 task_block.go:223] [ 1] Interrupt queued I0229 21:19:39.870552 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:19:39.870621 5995 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 21:19:39.870759 5995 task_signals.go:446] [ 1] Notified of signal 23 D0229 21:19:39.870778 5995 task_signals.go:220] [ 1] Signal 23: delivering to handler D0229 21:19:39.870892 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:19:39.871340 5995 task_block.go:223] [ 2] Interrupt queued D0229 21:19:39.871383 5995 task_block.go:223] [ 2] Interrupt queued D0229 21:19:39.871498 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:19:39.871777 5995 task_signals.go:176] [ 2] Not restarting syscall 202 after errno 516: interrupted by signal 23 D0229 21:19:39.871923 5995 task_signals.go:220] [ 2] Signal 23: delivering to handler D0229 21:19:40.798182 5995 parameters.go:238] Clock(Monotonic): error: -170 ns, adjusted frequency from 2299774614 Hz to 2299774360 Hz I0229 21:19:40.798304 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:19:40.798422 5995 parameters.go:238] Clock(Realtime): error: -54 ns, adjusted frequency from 2299774017 Hz to 2299774497 Hz D0229 21:19:40.798765 5995 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8976304511049307 monotonicBaseRef:93000285169 monotonicFrequency:2299774360 realtimeReady:1 realtimeBaseCycles:8976304511684385 realtimeBaseRef:1583011180798415149 realtimeFrequency:2299774497} D0229 21:19:40.798633 5995 task_signals.go:446] [ 1] Notified of signal 23 D0229 21:19:40.799107 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:19:40.799226 5995 task_block.go:223] [ 1] Interrupt queued I0229 21:19:40.799274 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:19:40.799417 5995 task_signals.go:434] [ 1] Discarding duplicate signal 23 D0229 21:19:40.799591 5995 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 21:19:40.799709 5995 task_signals.go:220] [ 1] Signal 23: delivering to handler I0229 21:19:41.253164 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:19:41.253595 5995 task_signals.go:446] [ 1] Notified of signal 23 D0229 21:19:41.253689 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:19:41.253752 5995 urpc.go:577] urpc: unmarshal success. D0229 21:19:41.253826 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:19:41.254004 5995 controller.go:501] containerManager.Signal &{CID:ci-gvisor-ptrace-proxy-sandbox-race-0 Signo:0 PID:0 Mode:Process} D0229 21:19:41.254059 5995 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 21:19:41.254263 5995 task_signals.go:220] [ 1] Signal 23: delivering to handler D0229 21:19:41.254390 5995 urpc.go:534] urpc: successfully marshalled 37 bytes. D0229 21:19:41.254621 14562 urpc.go:577] urpc: unmarshal success. D0229 21:19:41.254800 14562 exec.go:120] Exec arguments: /syz-execprog -executor=/syz-executor -arch=amd64 -sandbox=none -procs=1 -repeat=1 -threaded=true -collide=true -cover=0 -fault_call=-1 -fault_nth=-1 /syzkaller161088748 D0229 21:19:41.254937 14562 exec.go:121] Exec capablities: &{PermittedCaps:274877898751 InheritableCaps:274877898751 EffectiveCaps:274877898751 BoundingCaps:274877898751 AmbientCaps:0} D0229 21:19:41.255148 14562 container.go:533] Execute in container "ci-gvisor-ptrace-proxy-sandbox-race-0", args: /syz-execprog -executor=/syz-executor -arch=amd64 -sandbox=none -procs=1 -repeat=1 -threaded=true -collide=true -cover=0 -fault_call=-1 -fault_nth=-1 /syzkaller161088748 D0229 21:19:41.255333 14562 sandbox.go:283] Executing new process in container "ci-gvisor-ptrace-proxy-sandbox-race-0" in sandbox "ci-gvisor-ptrace-proxy-sandbox-race-0" D0229 21:19:41.255507 14562 sandbox.go:318] Connecting to sandbox "ci-gvisor-ptrace-proxy-sandbox-race-0" D0229 21:19:41.256461 14562 urpc.go:534] urpc: successfully marshalled 634 bytes. D0229 21:19:41.256928 5995 urpc.go:577] urpc: unmarshal success. D0229 21:19:41.259080 5995 controller.go:267] containerManager.ExecuteAsync: /syz-execprog -executor=/syz-executor -arch=amd64 -sandbox=none -procs=1 -repeat=1 -threaded=true -collide=true -cover=0 -fault_call=-1 -fault_nth=-1 /syzkaller161088748 D0229 21:19:41.259472 5995 transport_flipcall.go:127] send [channel @0xc00045c3c0] Twalkgetattr{FID: 1, NewFID: 5, Names: [etc]} D0229 21:19:41.261913 5995 transport_flipcall.go:234] recv [channel @0xc00045c3c0] Rlerror{Error: 2} D0229 21:19:41.262120 5995 transport_flipcall.go:127] send [channel @0xc00045c3c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.etc, Size: 1} D0229 21:19:41.262766 5995 transport_flipcall.go:234] recv [channel @0xc00045c3c0] Rlerror{Error: 95} I0229 21:19:41.263448 5995 kernel.go:797] EXEC: [/syz-execprog -executor=/syz-executor -arch=amd64 -sandbox=none -procs=1 -repeat=1 -threaded=true -collide=true -cover=0 -fault_call=-1 -fault_nth=-1 /syzkaller161088748] D0229 21:19:41.265541 5995 transport_flipcall.go:127] send [channel @0xc00045c3c0] Twalkgetattr{FID: 1, NewFID: 5, Names: [syz-execprog]} D0229 21:19:41.266989 5995 transport_flipcall.go:234] recv [channel @0xc00045c3c0] Rwalkgetattr{Valid: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime Size Blocks}, Attr: Attr{Mode: 0o100755, UID: 0, GID: 0, NLink: 1, RDev: 0, Size: 24119712, BlockSize: 4096, Blocks: 47112, ATime: {Sec: 1582836029, NanoSec: 349298839}, MTime: {Sec: 1582836029, NanoSec: 349298839}, CTime: {Sec: 1583011088, NanoSec: 943419903}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}, QIDs: [QID{Type: 0, Version: 0, Path: 16384267}]} D0229 21:19:41.267258 5995 transport_flipcall.go:127] send [channel @0xc00045c3c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syz-execprog, Size: 1} D0229 21:19:41.267865 5995 transport_flipcall.go:234] recv [channel @0xc00045c3c0] Rlerror{Error: 95} I0229 21:19:41.269099 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:19:41.268259 5995 transport_flipcall.go:127] send [channel @0xc00045c3c0] Tgetattr{FID: 5, AttrMask: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime INo Size Blocks BTime Gen DataVersion}} D0229 21:19:41.270512 5995 transport_flipcall.go:234] recv [channel @0xc00045c3c0] Rgetattr{Valid: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime Size Blocks}, QID: QID{Type: 0, Version: 0, Path: 16384267}, Attr: Attr{Mode: 0o100755, UID: 0, GID: 0, NLink: 1, RDev: 0, Size: 24119712, BlockSize: 4096, Blocks: 47112, ATime: {Sec: 1582836029, NanoSec: 349298839}, MTime: {Sec: 1582836029, NanoSec: 349298839}, CTime: {Sec: 1583011088, NanoSec: 943419903}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}} D0229 21:19:41.270757 5995 transport_flipcall.go:127] send [channel @0xc00045c3c0] Twalk{FID: 5, NewFID: 6, Names: []} D0229 21:19:41.272227 5995 transport_flipcall.go:234] recv [channel @0xc00045c3c0] Rwalk{QIDs: []} D0229 21:19:41.272432 5995 transport_flipcall.go:127] send [channel @0xc00045c3c0] Tlopen{FID: 6, Flags: ReadOnly} D0229 21:19:41.273295 5995 transport_flipcall.go:234] recv [channel @0xc00045c3c0] Rlopen{QID: QID{Type: 0, Version: 0, Path: 16384267}, IoUnit: 0, File: &{{36}}} D0229 21:19:41.279407 5995 syscalls.go:266] Allocating stack with size of 8388608 bytes D0229 21:19:41.281549 5995 loader.go:832] updated processes: map[{ci-gvisor-ptrace-proxy-sandbox-race-0 0}:0xc00041e8c0 {ci-gvisor-ptrace-proxy-sandbox-race-0 22}:0xc000378680] D0229 21:19:41.282098 5995 task_signals.go:446] [ 1] Notified of signal 23 D0229 21:19:41.282220 5995 urpc.go:534] urpc: successfully marshalled 37 bytes. D0229 21:19:41.282338 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:19:41.282438 14562 urpc.go:577] urpc: unmarshal success. D0229 21:19:41.282485 5995 task_block.go:223] [ 1] Interrupt queued I0229 21:19:41.282553 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:19:41.282661 14562 container.go:580] Wait on PID 22 in container "ci-gvisor-ptrace-proxy-sandbox-race-0" D0229 21:19:41.282720 5995 task_signals.go:446] [ 1] Notified of signal 23 D0229 21:19:41.282707 5995 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 21:19:41.282820 14562 sandbox.go:761] Waiting for PID 22 in sandbox "ci-gvisor-ptrace-proxy-sandbox-race-0" D0229 21:19:41.282839 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:19:41.282862 5995 task_signals.go:220] [ 1] Signal 23: delivering to handler D0229 21:19:41.282948 14562 sandbox.go:318] Connecting to sandbox "ci-gvisor-ptrace-proxy-sandbox-race-0" D0229 21:19:41.283378 14562 urpc.go:534] urpc: successfully marshalled 100 bytes. D0229 21:19:41.283448 5995 task_block.go:223] [ 2] Interrupt queued D0229 21:19:41.283640 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:19:41.283795 5995 urpc.go:577] urpc: unmarshal success. D0229 21:19:41.284145 5995 controller.go:443] containerManager.Wait D0229 21:19:41.284361 5995 task_block.go:223] [ 2] Interrupt queued D0229 21:19:41.284551 5995 task_signals.go:176] [ 2] Not restarting syscall 202 after errno 516: interrupted by signal 23 D0229 21:19:41.284688 5995 task_signals.go:220] [ 2] Signal 23: delivering to handler D0229 21:19:41.293162 5995 task_block.go:223] [ 22] Interrupt queued I0229 21:19:41.499226 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:19:41.499542 5995 task_signals.go:446] [ 1] Notified of signal 23 D0229 21:19:41.499688 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:19:41.499751 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:19:41.500019 5995 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 21:19:41.500182 5995 task_signals.go:220] [ 1] Signal 23: delivering to handler I0229 21:19:41.516626 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:19:41.516919 5995 task_signals.go:446] [ 1] Notified of signal 23 D0229 21:19:41.517052 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:19:41.517189 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:19:41.517414 5995 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 21:19:41.517568 5995 task_signals.go:220] [ 1] Signal 23: delivering to handler D0229 21:19:41.518338 5995 task_block.go:223] [ 23] Interrupt queued D0229 21:19:41.521125 5995 task_block.go:223] [ 24] Interrupt queued D0229 21:19:41.522728 5995 task_block.go:223] [ 25] Interrupt queued D0229 21:19:41.526534 5995 task_block.go:223] [ 26] Interrupt queued D0229 21:19:41.545596 5995 task_block.go:223] [ 27] Interrupt queued D0229 21:19:41.550956 5995 task_block.go:223] [ 28] Interrupt queued D0229 21:19:41.573774 5995 task_block.go:223] [ 29] Interrupt queued D0229 21:19:41.604684 5995 transport_flipcall.go:127] send [channel @0xc00045c3c0] Twalkgetattr{FID: 1, NewFID: 7, Names: [etc]} D0229 21:19:41.605362 5995 transport_flipcall.go:234] recv [channel @0xc00045c3c0] Rlerror{Error: 2} D0229 21:19:41.605603 5995 transport_flipcall.go:127] send [channel @0xc00045c3c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.etc, Size: 1} D0229 21:19:41.606373 5995 transport_flipcall.go:234] recv [channel @0xc00045c3c0] Rlerror{Error: 95} I0229 21:19:41.618045 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:19:41.618353 5995 task_signals.go:446] [ 1] Notified of signal 23 D0229 21:19:41.618563 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:19:41.618645 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:19:41.618814 5995 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 21:19:41.618904 5995 task_signals.go:220] [ 1] Signal 23: delivering to handler D0229 21:19:41.623136 5995 urpc.go:577] urpc: unmarshal success. D0229 21:19:41.623468 5995 controller.go:501] containerManager.Signal &{CID:ci-gvisor-ptrace-proxy-sandbox-race-0 Signo:0 PID:0 Mode:Process} D0229 21:19:41.623800 5995 urpc.go:534] urpc: successfully marshalled 37 bytes. D0229 21:19:41.649134 5995 task_block.go:223] [ 30] Interrupt queued D0229 21:19:41.654758 5995 task_block.go:223] [ 31] Interrupt queued D0229 21:19:41.661141 5995 task_block.go:223] [ 32] Interrupt queued D0229 21:19:41.661895 5995 task_block.go:223] [ 33] Interrupt queued D0229 21:19:41.670959 5995 task_block.go:223] [ 34] Interrupt queued D0229 21:19:41.671136 5995 task_block.go:223] [ 35] Interrupt queued D0229 21:19:41.702350 5995 task_block.go:223] [ 36] Interrupt queued I0229 21:19:41.709931 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:19:41.710532 5995 task_signals.go:446] [ 1] Notified of signal 23 D0229 21:19:41.710693 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:19:41.710681 5995 task_block.go:223] [ 1] Interrupt queued I0229 21:19:41.712388 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:19:41.712501 5995 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 21:19:41.712589 5995 task_signals.go:446] [ 1] Notified of signal 23 D0229 21:19:41.712698 5995 task_signals.go:220] [ 1] Signal 23: delivering to handler D0229 21:19:41.712757 5995 task_block.go:223] [ 37] Interrupt queued D0229 21:19:41.712752 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:19:41.713253 5995 task_block.go:223] [ 2] Interrupt queued D0229 21:19:41.713453 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:19:41.713351 5995 task_block.go:223] [ 2] Interrupt queued D0229 21:19:41.713663 5995 task_signals.go:176] [ 2] Not restarting syscall 202 after errno 516: interrupted by signal 23 D0229 21:19:41.713834 5995 task_signals.go:220] [ 2] Signal 23: delivering to handler I0229 21:19:41.744568 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:19:41.744822 5995 task_signals.go:446] [ 1] Notified of signal 23 D0229 21:19:41.744965 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:19:41.745090 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:19:41.745347 5995 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 21:19:41.745525 5995 task_signals.go:220] [ 1] Signal 23: delivering to handler D0229 21:19:41.746112 5995 task_block.go:223] [ 38] Interrupt queued D0229 21:19:41.754407 5995 task_block.go:223] [ 39] Interrupt queued D0229 21:19:41.754830 5995 transport_flipcall.go:127] send [channel @0xc00045c3c0] Twalkgetattr{FID: 1, NewFID: 7, Names: [syzkaller161088748]} D0229 21:19:41.756437 5995 transport_flipcall.go:234] recv [channel @0xc00045c3c0] Rwalkgetattr{Valid: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime Size Blocks}, Attr: Attr{Mode: 0o100600, UID: 0, GID: 0, NLink: 1, RDev: 0, Size: 273, BlockSize: 4096, Blocks: 8, ATime: {Sec: 1583011181, NanoSec: 196459462}, MTime: {Sec: 1583011181, NanoSec: 196459462}, CTime: {Sec: 1583011181, NanoSec: 196459462}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}, QIDs: [QID{Type: 0, Version: 0, Path: 16384349}]} D0229 21:19:41.756724 5995 transport_flipcall.go:127] send [channel @0xc00045c3c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzkaller161088748, Size: 1} D0229 21:19:41.757541 5995 transport_flipcall.go:234] recv [channel @0xc00045c3c0] Rlerror{Error: 95} D0229 21:19:41.757997 5995 transport_flipcall.go:127] send [channel @0xc00045c3c0] Tgetattr{FID: 7, AttrMask: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime INo Size Blocks BTime Gen DataVersion}} D0229 21:19:41.759247 5995 transport_flipcall.go:234] recv [channel @0xc00045c3c0] Rgetattr{Valid: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime Size Blocks}, QID: QID{Type: 0, Version: 0, Path: 16384349}, Attr: Attr{Mode: 0o100600, UID: 0, GID: 0, NLink: 1, RDev: 0, Size: 273, BlockSize: 4096, Blocks: 8, ATime: {Sec: 1583011181, NanoSec: 196459462}, MTime: {Sec: 1583011181, NanoSec: 196459462}, CTime: {Sec: 1583011181, NanoSec: 196459462}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}} D0229 21:19:41.759496 5995 transport_flipcall.go:127] send [channel @0xc00045c3c0] Twalk{FID: 7, NewFID: 8, Names: []} D0229 21:19:41.760394 5995 transport_flipcall.go:234] recv [channel @0xc00045c3c0] Rwalk{QIDs: []} D0229 21:19:41.760658 5995 transport_flipcall.go:127] send [channel @0xc00045c3c0] Tlopen{FID: 8, Flags: ReadOnly} D0229 21:19:41.761652 5995 transport_flipcall.go:234] recv [channel @0xc00045c3c0] Rlopen{QID: QID{Type: 0, Version: 0, Path: 16384349}, IoUnit: 0, File: &{{30}}} D0229 21:19:41.763378 5995 transport_flipcall.go:127] send [channel @0xc00045c3c0] Tgetattr{FID: 8, AttrMask: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime INo Size Blocks BTime Gen DataVersion}} D0229 21:19:41.764353 5995 transport_flipcall.go:234] recv [channel @0xc00045c3c0] Rgetattr{Valid: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime Size Blocks}, QID: QID{Type: 0, Version: 0, Path: 16384349}, Attr: Attr{Mode: 0o100600, UID: 0, GID: 0, NLink: 1, RDev: 0, Size: 273, BlockSize: 4096, Blocks: 8, ATime: {Sec: 1583011181, NanoSec: 196459462}, MTime: {Sec: 1583011181, NanoSec: 196459462}, CTime: {Sec: 1583011181, NanoSec: 196459462}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}} 2020/02/29 21:19:41 parsed 1 programs I0229 21:19:41.771961 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:19:41.772151 5995 task_signals.go:446] [ 1] Notified of signal 23 D0229 21:19:41.772250 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:19:41.772388 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:19:41.772643 5995 task_stop.go:118] [ 32] Entering internal stop (*kernel.vforkStop)(nil) D0229 21:19:41.772703 5995 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 21:19:41.772823 5995 task_signals.go:220] [ 1] Signal 23: delivering to handler D0229 21:19:41.773432 5995 task_block.go:223] [ 40] Interrupt queued D0229 21:19:41.777998 5995 transport_flipcall.go:127] send [channel @0xc00045c3c0] Twalkgetattr{FID: 1, NewFID: 9, Names: [syz-executor]} D0229 21:19:41.779508 5995 transport_flipcall.go:234] recv [channel @0xc00045c3c0] Rwalkgetattr{Valid: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime Size Blocks}, Attr: Attr{Mode: 0o100755, UID: 0, GID: 0, NLink: 1, RDev: 0, Size: 1579448, BlockSize: 4096, Blocks: 3088, ATime: {Sec: 1582836029, NanoSec: 449308645}, MTime: {Sec: 1582836029, NanoSec: 449308645}, CTime: {Sec: 1583011088, NanoSec: 947420295}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}, QIDs: [QID{Type: 0, Version: 0, Path: 16384343}]} D0229 21:19:41.779807 5995 transport_flipcall.go:127] send [channel @0xc00045c3c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syz-executor, Size: 1} D0229 21:19:41.780430 5995 transport_flipcall.go:234] recv [channel @0xc00045c3c0] Rlerror{Error: 95} D0229 21:19:41.780867 5995 transport_flipcall.go:127] send [channel @0xc00045c3c0] Tgetattr{FID: 9, AttrMask: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime INo Size Blocks BTime Gen DataVersion}} D0229 21:19:41.781804 5995 transport_flipcall.go:234] recv [channel @0xc00045c3c0] Rgetattr{Valid: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime Size Blocks}, QID: QID{Type: 0, Version: 0, Path: 16384343}, Attr: Attr{Mode: 0o100755, UID: 0, GID: 0, NLink: 1, RDev: 0, Size: 1579448, BlockSize: 4096, Blocks: 3088, ATime: {Sec: 1582836029, NanoSec: 449308645}, MTime: {Sec: 1582836029, NanoSec: 449308645}, CTime: {Sec: 1583011088, NanoSec: 947420295}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}} D0229 21:19:41.782055 5995 transport_flipcall.go:127] send [channel @0xc00045c3c0] Twalk{FID: 9, NewFID: 10, Names: []} D0229 21:19:41.783080 5995 transport_flipcall.go:234] recv [channel @0xc00045c3c0] Rwalk{QIDs: []} D0229 21:19:41.783302 5995 transport_flipcall.go:127] send [channel @0xc00045c3c0] Tlopen{FID: 10, Flags: ReadOnly} D0229 21:19:41.784270 5995 transport_flipcall.go:234] recv [channel @0xc00045c3c0] Rlopen{QID: QID{Type: 0, Version: 0, Path: 16384343}, IoUnit: 0, File: &{{31}}} D0229 21:19:41.785399 5995 syscalls.go:266] [ 40] Allocating stack with size of 8388608 bytes D0229 21:19:41.786773 5995 task_stop.go:137] [ 32] Leaving internal stop (*kernel.vforkStop)(nil) D0229 21:19:41.798303 5995 parameters.go:238] Clock(Monotonic): error: -261 ns, adjusted frequency from 2299774360 Hz to 2299774050 Hz D0229 21:19:41.798575 5995 parameters.go:238] Clock(Realtime): error: 10 ns, adjusted frequency from 2299774497 Hz to 2299774623 Hz D0229 21:19:41.798681 5995 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8976306811161547 monotonicBaseRef:94000432087 monotonicFrequency:2299774050 realtimeReady:1 realtimeBaseCycles:8976306811802577 realtimeBaseRef:1583011181798564596 realtimeFrequency:2299774623} D0229 21:19:41.814056 5995 transport_flipcall.go:127] send [channel @0xc00045c3c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [etc]} D0229 21:19:41.814932 5995 transport_flipcall.go:234] recv [channel @0xc00045c3c0] Rlerror{Error: 2} D0229 21:19:41.815151 5995 transport_flipcall.go:127] send [channel @0xc00045c3c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.etc, Size: 1} D0229 21:19:41.815771 5995 transport_flipcall.go:234] recv [channel @0xc00045c3c0] Rlerror{Error: 95} D0229 21:19:41.817135 5995 task_exit.go:221] [ 40] Transitioning from exit state TaskExitNone to TaskExitInitiated I0229 21:19:41.817410 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:19:41.817670 5995 task_signals.go:446] [ 1] Notified of signal 23 D0229 21:19:41.817856 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:19:41.818090 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:19:41.818286 5995 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 21:19:41.818465 5995 task_signals.go:220] [ 1] Signal 23: delivering to handler D0229 21:19:41.818681 5995 task_exit.go:221] [ 40] Transitioning from exit state TaskExitInitiated to TaskExitZombie D0229 21:19:41.818839 5995 task_signals.go:446] [ 22] Notified of signal 17 D0229 21:19:41.819030 5995 task_block.go:223] [ 22] Interrupt queued D0229 21:19:41.819118 5995 task_block.go:223] [ 22] Interrupt queued D0229 21:19:41.819346 5995 task_signals.go:179] [ 22] Restarting syscall 202 after errno 512: interrupted by signal 17 D0229 21:19:41.819533 5995 task_signals.go:220] [ 22] Signal 17: delivering to handler D0229 21:19:41.819637 5995 task_exit.go:221] [ 40] Transitioning from exit state TaskExitZombie to TaskExitDead D0229 21:19:41.821512 5995 task_block.go:223] [ 41] Interrupt queued D0229 21:19:41.827018 5995 transport_flipcall.go:127] send [channel @0xc00045c3c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syz-executor]} D0229 21:19:41.828324 5995 transport_flipcall.go:234] recv [channel @0xc00045c3c0] Rwalkgetattr{Valid: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime Size Blocks}, Attr: Attr{Mode: 0o100755, UID: 0, GID: 0, NLink: 1, RDev: 0, Size: 1579448, BlockSize: 4096, Blocks: 3088, ATime: {Sec: 1582836029, NanoSec: 449308645}, MTime: {Sec: 1582836029, NanoSec: 449308645}, CTime: {Sec: 1583011088, NanoSec: 947420295}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}, QIDs: [QID{Type: 0, Version: 0, Path: 16384343}]} D0229 21:19:41.828502 5995 transport_flipcall.go:127] send [channel @0xc00045c3c0] Tclunk{FID: 11} D0229 21:19:41.829062 5995 transport_flipcall.go:234] recv [channel @0xc00045c3c0] Rclunk{} D0229 21:19:41.829308 5995 transport_flipcall.go:127] send [channel @0xc00045c3c0] Tgetattr{FID: 9, AttrMask: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime INo Size Blocks BTime Gen DataVersion}} D0229 21:19:41.830414 5995 transport_flipcall.go:234] recv [channel @0xc00045c3c0] Rgetattr{Valid: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime Size Blocks}, QID: QID{Type: 0, Version: 0, Path: 16384343}, Attr: Attr{Mode: 0o100755, UID: 0, GID: 0, NLink: 1, RDev: 0, Size: 1579448, BlockSize: 4096, Blocks: 3088, ATime: {Sec: 1582836029, NanoSec: 449308645}, MTime: {Sec: 1582836029, NanoSec: 449308645}, CTime: {Sec: 1583011088, NanoSec: 947420295}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}} 2020/02/29 21:19:41 executed programs: 0 I0229 21:19:41.838635 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:19:41.839711 5995 task_stop.go:118] [ 25] Entering internal stop (*kernel.vforkStop)(nil) D0229 21:19:41.841166 5995 task_signals.go:446] [ 1] Notified of signal 23 D0229 21:19:41.841415 5995 task_block.go:223] [ 1] Interrupt queued I0229 21:19:41.841561 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:19:41.841579 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:19:41.841717 5995 task_signals.go:434] [ 1] Discarding duplicate signal 23 D0229 21:19:41.841943 5995 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 21:19:41.841928 5995 task_block.go:223] [ 42] Interrupt queued D0229 21:19:41.842095 5995 task_signals.go:220] [ 1] Signal 23: delivering to handler D0229 21:19:41.849911 5995 transport_flipcall.go:127] send [channel @0xc00045c3c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syz-executor]} D0229 21:19:41.851289 5995 transport_flipcall.go:234] recv [channel @0xc00045c3c0] Rwalkgetattr{Valid: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime Size Blocks}, Attr: Attr{Mode: 0o100755, UID: 0, GID: 0, NLink: 1, RDev: 0, Size: 1579448, BlockSize: 4096, Blocks: 3088, ATime: {Sec: 1582836029, NanoSec: 449308645}, MTime: {Sec: 1582836029, NanoSec: 449308645}, CTime: {Sec: 1583011088, NanoSec: 947420295}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}, QIDs: [QID{Type: 0, Version: 0, Path: 16384343}]} D0229 21:19:41.851468 5995 transport_flipcall.go:127] send [channel @0xc00045c3c0] Tclunk{FID: 11} D0229 21:19:41.851927 5995 transport_flipcall.go:234] recv [channel @0xc00045c3c0] Rclunk{} D0229 21:19:41.852121 5995 transport_flipcall.go:127] send [channel @0xc00045c3c0] Tgetattr{FID: 9, AttrMask: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime INo Size Blocks BTime Gen DataVersion}} D0229 21:19:41.853204 5995 transport_flipcall.go:234] recv [channel @0xc00045c3c0] Rgetattr{Valid: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime Size Blocks}, QID: QID{Type: 0, Version: 0, Path: 16384343}, Attr: Attr{Mode: 0o100755, UID: 0, GID: 0, NLink: 1, RDev: 0, Size: 1579448, BlockSize: 4096, Blocks: 3088, ATime: {Sec: 1582836029, NanoSec: 449308645}, MTime: {Sec: 1582836029, NanoSec: 449308645}, CTime: {Sec: 1583011088, NanoSec: 947420295}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}} D0229 21:19:41.854261 5995 syscalls.go:266] [ 42] Allocating stack with size of 8388608 bytes D0229 21:19:41.855135 5995 task_stop.go:137] [ 25] Leaving internal stop (*kernel.vforkStop)(nil) D0229 21:19:41.863504 5995 transport_flipcall.go:127] send [channel @0xc00045c3c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [etc]} D0229 21:19:41.864223 5995 transport_flipcall.go:234] recv [channel @0xc00045c3c0] Rlerror{Error: 2} D0229 21:19:41.864416 5995 transport_flipcall.go:127] send [channel @0xc00045c3c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.etc, Size: 1} D0229 21:19:41.864884 5995 transport_flipcall.go:234] recv [channel @0xc00045c3c0] Rlerror{Error: 95} I0229 21:19:41.869131 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:19:41.869342 5995 task_signals.go:446] [ 1] Notified of signal 23 D0229 21:19:41.869509 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:19:41.869545 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:19:41.869757 5995 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 21:19:41.869904 5995 task_signals.go:220] [ 1] Signal 23: delivering to handler D0229 21:19:41.875679 5995 task_block.go:223] [ 43] Interrupt queued D0229 21:19:41.881034 5995 transport_flipcall.go:127] send [channel @0xc00045c3c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0229 21:19:41.882215 5995 transport_flipcall.go:234] recv [channel @0xc00045c3c0] Rlerror{Error: 2} D0229 21:19:41.882501 5995 transport_flipcall.go:127] send [channel @0xc00045c3c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0229 21:19:41.883056 5995 transport_flipcall.go:234] recv [channel @0xc00045c3c0] Rlerror{Error: 95} D0229 21:19:41.883707 5995 transport_flipcall.go:127] send [channel @0xc00045c3c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0229 21:19:41.884215 5995 transport_flipcall.go:234] recv [channel @0xc00045c3c0] Rlerror{Error: 2} D0229 21:19:41.884393 5995 transport_flipcall.go:127] send [channel @0xc00045c3c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0229 21:19:41.884869 5995 transport_flipcall.go:234] recv [channel @0xc00045c3c0] Rlerror{Error: 95} D0229 21:19:41.885623 5995 transport_flipcall.go:127] send [channel @0xc00045c3c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0229 21:19:41.886153 5995 transport_flipcall.go:234] recv [channel @0xc00045c3c0] Rlerror{Error: 2} D0229 21:19:41.886358 5995 transport_flipcall.go:127] send [channel @0xc00045c3c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0229 21:19:41.886961 5995 transport_flipcall.go:234] recv [channel @0xc00045c3c0] Rlerror{Error: 95} D0229 21:19:41.887579 5995 transport_flipcall.go:127] send [channel @0xc00045c3c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0229 21:19:41.888290 5995 transport_flipcall.go:234] recv [channel @0xc00045c3c0] Rlerror{Error: 2} D0229 21:19:41.888653 5995 transport_flipcall.go:127] send [channel @0xc00045c3c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0229 21:19:41.889171 5995 transport_flipcall.go:234] recv [channel @0xc00045c3c0] Rlerror{Error: 95} I0229 21:19:41.889362 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:19:41.889594 5995 task_signals.go:446] [ 1] Notified of signal 23 D0229 21:19:41.889739 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:19:41.889922 5995 transport_flipcall.go:127] send [channel @0xc00045c3c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0229 21:19:41.889890 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:19:41.890217 5995 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 21:19:41.890420 5995 task_signals.go:220] [ 1] Signal 23: delivering to handler D0229 21:19:41.890601 5995 transport_flipcall.go:234] recv [channel @0xc00045c3c0] Rlerror{Error: 2} D0229 21:19:41.890757 5995 transport_flipcall.go:127] send [channel @0xc00045c3c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0229 21:19:41.891187 5995 transport_flipcall.go:234] recv [channel @0xc00045c3c0] Rlerror{Error: 95} D0229 21:19:41.891814 5995 transport_flipcall.go:127] send [channel @0xc00045c3c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0229 21:19:41.892386 5995 transport_flipcall.go:234] recv [channel @0xc00045c3c0] Rlerror{Error: 2} D0229 21:19:41.892553 5995 transport_flipcall.go:127] send [channel @0xc00045c3c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0229 21:19:41.893280 5995 transport_flipcall.go:234] recv [channel @0xc00045c3c0] Rlerror{Error: 95} D0229 21:19:41.893755 5995 transport_flipcall.go:127] send [channel @0xc00045c3c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0229 21:19:41.920902 5995 transport_flipcall.go:234] recv [channel @0xc00045c3c0] Rlerror{Error: 2} I0229 21:19:41.921131 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:19:41.921254 5995 transport_flipcall.go:127] send [channel @0xc00045c3c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0229 21:19:41.921363 5995 task_signals.go:446] [ 1] Notified of signal 23 D0229 21:19:41.921532 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:19:41.921590 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:19:41.921847 5995 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 21:19:41.921959 5995 task_signals.go:220] [ 1] Signal 23: delivering to handler D0229 21:19:41.922004 5995 transport_flipcall.go:234] recv [channel @0xc00045c3c0] Rlerror{Error: 95} D0229 21:19:41.922440 5995 transport_flipcall.go:127] send [channel @0xc00045c3c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0229 21:19:41.924631 5995 transport_flipcall.go:234] recv [channel @0xc00045c3c0] Rlerror{Error: 2} D0229 21:19:41.924864 5995 transport_flipcall.go:127] send [channel @0xc00045c3c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0229 21:19:41.925406 5995 transport_flipcall.go:234] recv [channel @0xc00045c3c0] Rlerror{Error: 95} D0229 21:19:41.925815 5995 transport_flipcall.go:127] send [channel @0xc00045c3c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0229 21:19:41.926478 5995 transport_flipcall.go:234] recv [channel @0xc00045c3c0] Rlerror{Error: 2} D0229 21:19:41.926712 5995 transport_flipcall.go:127] send [channel @0xc00045c3c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0229 21:19:41.927199 5995 transport_flipcall.go:234] recv [channel @0xc00045c3c0] Rlerror{Error: 95} I0229 21:19:41.932513 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:19:41.932732 5995 task_signals.go:446] [ 1] Notified of signal 23 D0229 21:19:41.932857 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:19:41.932885 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:19:41.933026 5995 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 21:19:41.933161 5995 task_signals.go:220] [ 1] Signal 23: delivering to handler I0229 21:19:41.947684 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:19:41.947916 5995 task_signals.go:446] [ 1] Notified of signal 23 D0229 21:19:41.948015 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:19:41.948060 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:19:41.948237 5995 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 21:19:41.948345 5995 task_signals.go:220] [ 1] Signal 23: delivering to handler D0229 21:19:41.958163 5995 netfilter.go:54] netfilter: convert to binary: current offset: 0 D0229 21:19:41.958291 5995 netfilter.go:54] netfilter: convert to binary: found hook 0 at offset 0 D0229 21:19:41.958411 5995 netfilter.go:54] netfilter: convert to binary: found underflow 0 at offset 0 D0229 21:19:41.958492 5995 netfilter.go:54] netfilter: convert to binary: marshalling standard target D0229 21:19:41.958592 5995 netfilter.go:54] netfilter: convert to binary: adding entry: {IPTEntry:{IP:{Src:[0 0 0 0] Dst:[0 0 0 0] SrcMask:[0 0 0 0] DstMask:[0 0 0 0] InputInterface:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] OutputInterface:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] InputInterfaceMask:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] OutputInterfaceMask:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] Protocol:0 Flags:0 InverseFlags:0} NFCache:0 TargetOffset:112 NextOffset:152 Comeback:0 Counters:{Pcnt:0 Bcnt:0}} Elems:[40 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 254 255 255 255 0 0 0 0]} D0229 21:19:41.959248 5995 netfilter.go:54] netfilter: convert to binary: current offset: 152 D0229 21:19:41.959315 5995 netfilter.go:54] netfilter: convert to binary: found hook 1 at offset 152 D0229 21:19:41.959415 5995 netfilter.go:54] netfilter: convert to binary: found underflow 1 at offset 152 D0229 21:19:41.959480 5995 netfilter.go:54] netfilter: convert to binary: marshalling standard target D0229 21:19:41.959548 5995 netfilter.go:54] netfilter: convert to binary: adding entry: {IPTEntry:{IP:{Src:[0 0 0 0] Dst:[0 0 0 0] SrcMask:[0 0 0 0] DstMask:[0 0 0 0] InputInterface:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] OutputInterface:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] InputInterfaceMask:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] OutputInterfaceMask:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] Protocol:0 Flags:0 InverseFlags:0} NFCache:0 TargetOffset:112 NextOffset:152 Comeback:0 Counters:{Pcnt:0 Bcnt:0}} Elems:[40 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 254 255 255 255 0 0 0 0]} D0229 21:19:41.960203 5995 netfilter.go:54] netfilter: convert to binary: current offset: 304 D0229 21:19:41.960291 5995 netfilter.go:54] netfilter: convert to binary: found hook 3 at offset 304 D0229 21:19:41.960372 5995 netfilter.go:54] netfilter: convert to binary: found underflow 3 at offset 304 D0229 21:19:41.960443 5995 netfilter.go:54] netfilter: convert to binary: marshalling standard target D0229 21:19:41.960506 5995 netfilter.go:54] netfilter: convert to binary: adding entry: {IPTEntry:{IP:{Src:[0 0 0 0] Dst:[0 0 0 0] SrcMask:[0 0 0 0] DstMask:[0 0 0 0] InputInterface:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] OutputInterface:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] InputInterfaceMask:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] OutputInterfaceMask:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] Protocol:0 Flags:0 InverseFlags:0} NFCache:0 TargetOffset:112 NextOffset:152 Comeback:0 Counters:{Pcnt:0 Bcnt:0}} Elems:[40 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 254 255 255 255 0 0 0 0]} D0229 21:19:41.960966 5995 netfilter.go:54] netfilter: convert to binary: current offset: 456 D0229 21:19:41.961045 5995 netfilter.go:54] netfilter: convert to binary: found hook 4 at offset 456 D0229 21:19:41.961117 5995 netfilter.go:54] netfilter: convert to binary: found underflow 4 at offset 456 D0229 21:19:41.961190 5995 netfilter.go:54] netfilter: convert to binary: marshalling standard target D0229 21:19:41.961272 5995 netfilter.go:54] netfilter: convert to binary: adding entry: {IPTEntry:{IP:{Src:[0 0 0 0] Dst:[0 0 0 0] SrcMask:[0 0 0 0] DstMask:[0 0 0 0] InputInterface:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] OutputInterface:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] InputInterfaceMask:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] OutputInterfaceMask:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] Protocol:0 Flags:0 InverseFlags:0} NFCache:0 TargetOffset:112 NextOffset:152 Comeback:0 Counters:{Pcnt:0 Bcnt:0}} Elems:[40 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 254 255 255 255 0 0 0 0]} D0229 21:19:41.961757 5995 netfilter.go:54] netfilter: convert to binary: current offset: 608 D0229 21:19:41.961872 5995 netfilter.go:54] netfilter: convert to binary: adding entry: {IPTEntry:{IP:{Src:[0 0 0 0] Dst:[0 0 0 0] SrcMask:[0 0 0 0] DstMask:[0 0 0 0] InputInterface:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] OutputInterface:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] InputInterfaceMask:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] OutputInterfaceMask:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] Protocol:0 Flags:0 InverseFlags:0} NFCache:0 TargetOffset:112 NextOffset:176 Comeback:0 Counters:{Pcnt:0 Bcnt:0}} Elems:[64 0 69 82 82 79 82 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 69 82 82 79 82 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0]} D0229 21:19:41.962417 5995 netfilter.go:54] netfilter: convert to binary: finished with an marshalled size of 0 D0229 21:19:41.962508 5995 netfilter.go:54] netfilter: convert to binary: current offset: 0 D0229 21:19:41.962585 5995 netfilter.go:54] netfilter: convert to binary: found hook 0 at offset 0 D0229 21:19:41.962692 5995 netfilter.go:54] netfilter: convert to binary: found underflow 0 at offset 0 D0229 21:19:41.962744 5995 netfilter.go:54] netfilter: convert to binary: marshalling standard target D0229 21:19:41.962851 5995 netfilter.go:54] netfilter: convert to binary: adding entry: {IPTEntry:{IP:{Src:[0 0 0 0] Dst:[0 0 0 0] SrcMask:[0 0 0 0] DstMask:[0 0 0 0] InputInterface:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] OutputInterface:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] InputInterfaceMask:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] OutputInterfaceMask:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] Protocol:0 Flags:0 InverseFlags:0} NFCache:0 TargetOffset:112 NextOffset:152 Comeback:0 Counters:{Pcnt:0 Bcnt:0}} Elems:[40 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 254 255 255 255 0 0 0 0]} D0229 21:19:41.963294 5995 netfilter.go:54] netfilter: convert to binary: current offset: 152 D0229 21:19:41.963404 5995 netfilter.go:54] netfilter: convert to binary: found hook 3 at offset 152 D0229 21:19:41.963464 5995 netfilter.go:54] netfilter: convert to binary: found underflow 3 at offset 152 D0229 21:19:41.963512 5995 netfilter.go:54] netfilter: convert to binary: marshalling standard target D0229 21:19:41.963602 5995 netfilter.go:54] netfilter: convert to binary: adding entry: {IPTEntry:{IP:{Src:[0 0 0 0] Dst:[0 0 0 0] SrcMask:[0 0 0 0] DstMask:[0 0 0 0] InputInterface:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] OutputInterface:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] InputInterfaceMask:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] OutputInterfaceMask:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] Protocol:0 Flags:0 InverseFlags:0} NFCache:0 TargetOffset:112 NextOffset:152 Comeback:0 Counters:{Pcnt:0 Bcnt:0}} Elems:[40 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 254 255 255 255 0 0 0 0]} D0229 21:19:41.964076 5995 netfilter.go:54] netfilter: convert to binary: current offset: 304 D0229 21:19:41.964146 5995 netfilter.go:54] netfilter: convert to binary: adding entry: {IPTEntry:{IP:{Src:[0 0 0 0] Dst:[0 0 0 0] SrcMask:[0 0 0 0] DstMask:[0 0 0 0] InputInterface:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] OutputInterface:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] InputInterfaceMask:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] OutputInterfaceMask:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] Protocol:0 Flags:0 InverseFlags:0} NFCache:0 TargetOffset:112 NextOffset:176 Comeback:0 Counters:{Pcnt:0 Bcnt:0}} Elems:[64 0 69 82 82 79 82 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 69 82 82 79 82 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0]} D0229 21:19:41.964769 5995 netfilter.go:54] netfilter: convert to binary: finished with an marshalled size of 0 D0229 21:19:41.964845 5995 netfilter.go:54] netfilter: convert to binary: current offset: 0 D0229 21:19:41.964916 5995 netfilter.go:54] netfilter: convert to binary: found hook 1 at offset 0 D0229 21:19:41.965009 5995 netfilter.go:54] netfilter: convert to binary: found underflow 1 at offset 0 D0229 21:19:41.965082 5995 netfilter.go:54] netfilter: convert to binary: marshalling standard target D0229 21:19:41.965170 5995 netfilter.go:54] netfilter: convert to binary: adding entry: {IPTEntry:{IP:{Src:[0 0 0 0] Dst:[0 0 0 0] SrcMask:[0 0 0 0] DstMask:[0 0 0 0] InputInterface:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] OutputInterface:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] InputInterfaceMask:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] OutputInterfaceMask:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] Protocol:0 Flags:0 InverseFlags:0} NFCache:0 TargetOffset:112 NextOffset:152 Comeback:0 Counters:{Pcnt:0 Bcnt:0}} Elems:[40 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 254 255 255 255 0 0 0 0]} D0229 21:19:41.965713 5995 netfilter.go:54] netfilter: convert to binary: current offset: 152 D0229 21:19:41.965793 5995 netfilter.go:54] netfilter: convert to binary: found hook 2 at offset 152 D0229 21:19:41.965894 5995 netfilter.go:54] netfilter: convert to binary: found underflow 2 at offset 152 D0229 21:19:41.965993 5995 netfilter.go:54] netfilter: convert to binary: marshalling standard target D0229 21:19:41.966091 5995 netfilter.go:54] netfilter: convert to binary: adding entry: {IPTEntry:{IP:{Src:[0 0 0 0] Dst:[0 0 0 0] SrcMask:[0 0 0 0] DstMask:[0 0 0 0] InputInterface:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] OutputInterface:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] InputInterfaceMask:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] OutputInterfaceMask:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] Protocol:0 Flags:0 InverseFlags:0} NFCache:0 TargetOffset:112 NextOffset:152 Comeback:0 Counters:{Pcnt:0 Bcnt:0}} Elems:[40 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 254 255 255 255 0 0 0 0]} D0229 21:19:41.966681 5995 netfilter.go:54] netfilter: convert to binary: current offset: 304 D0229 21:19:41.966794 5995 netfilter.go:54] netfilter: convert to binary: found hook 3 at offset 304 D0229 21:19:41.966860 5995 netfilter.go:54] netfilter: convert to binary: found underflow 3 at offset 304 D0229 21:19:41.966930 5995 netfilter.go:54] netfilter: convert to binary: marshalling standard target D0229 21:19:41.967012 5995 netfilter.go:54] netfilter: convert to binary: adding entry: {IPTEntry:{IP:{Src:[0 0 0 0] Dst:[0 0 0 0] SrcMask:[0 0 0 0] DstMask:[0 0 0 0] InputInterface:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] OutputInterface:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] InputInterfaceMask:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] OutputInterfaceMask:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] Protocol:0 Flags:0 InverseFlags:0} NFCache:0 TargetOffset:112 NextOffset:152 Comeback:0 Counters:{Pcnt:0 Bcnt:0}} Elems:[40 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 254 255 255 255 0 0 0 0]} D0229 21:19:41.967449 5995 netfilter.go:54] netfilter: convert to binary: current offset: 456 I0229 21:19:41.967709 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:19:41.967964 5995 task_signals.go:446] [ 1] Notified of signal 23 D0229 21:19:41.967585 5995 netfilter.go:54] netfilter: convert to binary: adding entry: {IPTEntry:{IP:{Src:[0 0 0 0] Dst:[0 0 0 0] SrcMask:[0 0 0 0] DstMask:[0 0 0 0] InputInterface:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] OutputInterface:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] InputInterfaceMask:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] OutputInterfaceMask:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] Protocol:0 Flags:0 InverseFlags:0} NFCache:0 TargetOffset:112 NextOffset:176 Comeback:0 Counters:{Pcnt:0 Bcnt:0}} Elems:[64 0 69 82 82 79 82 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 69 82 82 79 82 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0]} D0229 21:19:41.968205 5995 netfilter.go:54] netfilter: convert to binary: finished with an marshalled size of 0 D0229 21:19:41.968154 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:19:41.968101 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:19:41.968597 5995 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 21:19:41.968805 5995 task_signals.go:220] [ 1] Signal 23: delivering to handler I0229 21:19:41.970963 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:19:41.971455 5995 task_signals.go:446] [ 1] Notified of signal 23 D0229 21:19:41.972036 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:19:41.971906 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:19:41.972324 5995 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 I0229 21:19:41.972506 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:19:41.972781 5995 task_signals.go:446] [ 1] Notified of signal 23 D0229 21:19:41.972752 5995 task_signals.go:220] [ 1] Signal 23: delivering to handler D0229 21:19:41.972896 5995 task_block.go:223] [ 1] Interrupt queued I0229 21:19:41.973068 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:19:41.974232 5995 task_signals.go:434] [ 1] Discarding duplicate signal 23 I0229 21:19:41.974373 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:19:41.974480 5995 task_block.go:223] [ 2] Interrupt queued D0229 21:19:41.974490 5995 task_block.go:223] [ 2] Interrupt queued D0229 21:19:41.974639 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:19:41.974779 5995 task_signals.go:176] [ 2] Not restarting syscall 202 after errno 516: interrupted by signal 23 D0229 21:19:41.974906 5995 task_signals.go:220] [ 2] Signal 23: delivering to handler D0229 21:19:41.975028 5995 task_signals.go:452] [ 2] Notified of group signal 23 D0229 21:19:41.975244 5995 task_block.go:223] [ 2] Interrupt queued D0229 21:19:41.975542 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:19:41.975721 5995 task_signals.go:220] [ 1] Signal 23: delivering to handler I0229 21:19:42.000578 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:19:42.000820 5995 task_signals.go:446] [ 1] Notified of signal 23 D0229 21:19:42.000953 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:19:42.001041 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:19:42.001322 5995 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 21:19:42.001483 5995 task_signals.go:220] [ 1] Signal 23: delivering to handler I0229 21:19:42.020808 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:19:42.021045 5995 task_signals.go:446] [ 1] Notified of signal 23 D0229 21:19:42.021178 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:19:42.021292 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:19:42.021656 5995 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 21:19:42.021849 5995 task_signals.go:220] [ 1] Signal 23: delivering to handler I0229 21:19:42.040980 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:19:42.041231 5995 task_signals.go:446] [ 1] Notified of signal 23 D0229 21:19:42.041381 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:19:42.041486 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:19:42.041718 5995 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 21:19:42.041927 5995 task_signals.go:220] [ 1] Signal 23: delivering to handler I0229 21:19:42.061275 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:19:42.061459 5995 task_signals.go:446] [ 1] Notified of signal 23 D0229 21:19:42.061547 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:19:42.061606 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:19:42.061800 5995 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 21:19:42.061949 5995 task_signals.go:220] [ 1] Signal 23: delivering to handler I0229 21:19:42.081817 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:19:42.082049 5995 task_signals.go:446] [ 1] Notified of signal 23 D0229 21:19:42.082155 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:19:42.082213 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:19:42.082816 5995 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 21:19:42.082979 5995 task_signals.go:220] [ 1] Signal 23: delivering to handler I0229 21:19:42.108098 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:19:42.108368 5995 task_signals.go:446] [ 1] Notified of signal 23 D0229 21:19:42.108491 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:19:42.108535 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:19:42.108784 5995 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 21:19:42.108928 5995 task_signals.go:220] [ 1] Signal 23: delivering to handler I0229 21:19:42.128343 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:19:42.128531 5995 task_signals.go:446] [ 1] Notified of signal 23 D0229 21:19:42.128616 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:19:42.128651 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:19:42.128819 5995 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 21:19:42.128947 5995 task_signals.go:220] [ 1] Signal 23: delivering to handler I0229 21:19:42.148640 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:19:42.148949 5995 task_signals.go:446] [ 1] Notified of signal 23 D0229 21:19:42.149087 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:19:42.149250 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:19:42.149483 5995 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 21:19:42.149638 5995 task_signals.go:220] [ 1] Signal 23: delivering to handler I0229 21:19:42.168930 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:19:42.169244 5995 task_signals.go:446] [ 1] Notified of signal 23 D0229 21:19:42.169397 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:19:42.169449 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:19:42.169603 5995 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 21:19:42.169693 5995 task_signals.go:220] [ 1] Signal 23: delivering to handler I0229 21:19:42.178981 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:19:42.179202 5995 task_signals.go:446] [ 1] Notified of signal 23 D0229 21:19:42.179307 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:19:42.179441 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:19:42.179718 5995 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 21:19:42.179920 5995 task_signals.go:220] [ 1] Signal 23: delivering to handler I0229 21:19:42.199175 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:19:42.199395 5995 task_signals.go:446] [ 1] Notified of signal 23 D0229 21:19:42.199588 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:19:42.199768 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:19:42.200052 5995 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 21:19:42.200229 5995 task_signals.go:220] [ 1] Signal 23: delivering to handler D0229 21:19:42.207682 5995 transport_flipcall.go:127] send [channel @0xc00045c3c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0229 21:19:42.208324 5995 transport_flipcall.go:234] recv [channel @0xc00045c3c0] Rlerror{Error: 2} D0229 21:19:42.208510 5995 transport_flipcall.go:127] send [channel @0xc00045c3c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0229 21:19:42.209038 5995 transport_flipcall.go:234] recv [channel @0xc00045c3c0] Rlerror{Error: 95} D0229 21:19:42.209453 5995 transport_flipcall.go:127] send [channel @0xc00045c3c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0229 21:19:42.210029 5995 transport_flipcall.go:234] recv [channel @0xc00045c3c0] Rlerror{Error: 2} D0229 21:19:42.210181 5995 transport_flipcall.go:127] send [channel @0xc00045c3c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0229 21:19:42.210651 5995 transport_flipcall.go:234] recv [channel @0xc00045c3c0] Rlerror{Error: 95} D0229 21:19:42.211009 5995 transport_flipcall.go:127] send [channel @0xc00045c3c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0229 21:19:42.211527 5995 transport_flipcall.go:234] recv [channel @0xc00045c3c0] Rlerror{Error: 2} D0229 21:19:42.211686 5995 transport_flipcall.go:127] send [channel @0xc00045c3c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0229 21:19:42.212145 5995 transport_flipcall.go:234] recv [channel @0xc00045c3c0] Rlerror{Error: 95} D0229 21:19:42.212488 5995 transport_flipcall.go:127] send [channel @0xc00045c3c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0229 21:19:42.213128 5995 transport_flipcall.go:234] recv [channel @0xc00045c3c0] Rlerror{Error: 2} D0229 21:19:42.213264 5995 transport_flipcall.go:127] send [channel @0xc00045c3c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0229 21:19:42.213837 5995 transport_flipcall.go:234] recv [channel @0xc00045c3c0] Rlerror{Error: 95} D0229 21:19:42.214614 5995 transport_flipcall.go:127] send [channel @0xc00045c3c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0229 21:19:42.215229 5995 transport_flipcall.go:234] recv [channel @0xc00045c3c0] Rlerror{Error: 2} D0229 21:19:42.215451 5995 transport_flipcall.go:127] send [channel @0xc00045c3c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0229 21:19:42.216145 5995 transport_flipcall.go:234] recv [channel @0xc00045c3c0] Rlerror{Error: 95} D0229 21:19:42.216529 5995 transport_flipcall.go:127] send [channel @0xc00045c3c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0229 21:19:42.217088 5995 transport_flipcall.go:234] recv [channel @0xc00045c3c0] Rlerror{Error: 2} D0229 21:19:42.217218 5995 transport_flipcall.go:127] send [channel @0xc00045c3c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0229 21:19:42.217743 5995 transport_flipcall.go:234] recv [channel @0xc00045c3c0] Rlerror{Error: 95} D0229 21:19:42.218129 5995 transport_flipcall.go:127] send [channel @0xc00045c3c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0229 21:19:42.218876 5995 transport_flipcall.go:234] recv [channel @0xc00045c3c0] Rlerror{Error: 2} D0229 21:19:42.219032 5995 transport_flipcall.go:127] send [channel @0xc00045c3c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0229 21:19:42.219547 5995 transport_flipcall.go:234] recv [channel @0xc00045c3c0] Rlerror{Error: 95} D0229 21:19:42.220077 5995 transport_flipcall.go:127] send [channel @0xc00045c3c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0229 21:19:42.220790 5995 transport_flipcall.go:234] recv [channel @0xc00045c3c0] Rlerror{Error: 2} D0229 21:19:42.221008 5995 transport_flipcall.go:127] send [channel @0xc00045c3c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0229 21:19:42.221460 5995 transport_flipcall.go:234] recv [channel @0xc00045c3c0] Rlerror{Error: 95} D0229 21:19:42.221896 5995 transport_flipcall.go:127] send [channel @0xc00045c3c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0229 21:19:42.222482 5995 transport_flipcall.go:234] recv [channel @0xc00045c3c0] Rlerror{Error: 2} D0229 21:19:42.222702 5995 transport_flipcall.go:127] send [channel @0xc00045c3c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0229 21:19:42.223208 5995 transport_flipcall.go:234] recv [channel @0xc00045c3c0] Rlerror{Error: 95} D0229 21:19:42.223686 5995 transport_flipcall.go:127] send [channel @0xc00045c3c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0229 21:19:42.224423 5995 transport_flipcall.go:234] recv [channel @0xc00045c3c0] Rlerror{Error: 2} D0229 21:19:42.224571 5995 transport_flipcall.go:127] send [channel @0xc00045c3c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0229 21:19:42.225154 5995 transport_flipcall.go:234] recv [channel @0xc00045c3c0] Rlerror{Error: 95} I0229 21:19:42.231790 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:19:42.232023 5995 task_signals.go:446] [ 1] Notified of signal 23 D0229 21:19:42.232184 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:19:42.232235 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:19:42.232492 5995 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 21:19:42.232620 5995 task_signals.go:220] [ 1] Signal 23: delivering to handler D0229 21:19:42.248313 5995 task_block.go:223] [ 44] Interrupt queued D0229 21:19:42.259663 5995 task_block.go:223] [ 45] Interrupt queued I0229 21:19:42.260062 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:19:42.260311 5995 task_signals.go:446] [ 1] Notified of signal 23 D0229 21:19:42.260431 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:19:42.260568 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:19:42.260776 5995 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 21:19:42.260930 5995 task_signals.go:220] [ 1] Signal 23: delivering to handler I0229 21:19:42.263614 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:19:42.264606 5995 task_signals.go:446] [ 1] Notified of signal 23 D0229 21:19:42.266159 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:19:42.265049 5995 task_block.go:223] [ 1] Interrupt queued I0229 21:19:42.266386 5995 loader.go:566] Received external signal 23, mode: Process D0229 21:19:42.266376 5995 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 21:19:42.266531 5995 task_signals.go:446] [ 1] Notified of signal 23 D0229 21:19:42.266661 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:19:42.266533 5995 task_signals.go:220] [ 1] Signal 23: delivering to handler I0229 21:19:42.261432 5995 compat.go:122] Unsupported syscall: perf_event_open, regs: amd64: D0229 21:19:42.267298 5995 task_block.go:223] [ 2] Interrupt queued D0229 21:19:42.267394 5995 task_block.go:223] [ 2] Interrupt queued D0229 21:19:42.267491 5995 task_block.go:223] [ 1] Interrupt queued D0229 21:19:42.267633 5995 task_signals.go:176] [ 2] Not restarting syscall 202 after errno 516: interrupted by signal 23 D0229 21:19:42.267767 5995 task_signals.go:220] [ 2] Signal 23: delivering to handler D0229 21:19:42.292019 5995 task_block.go:223] [ 46] Interrupt queued ================== WARNING: DATA RACE Read at 0x00c00038cb50 by goroutine 349: gvisor.dev/gvisor/pkg/tcpip/stack.(*TransportEndpointInfo).AddrNetProto() pkg/tcpip/stack/stack.go:573 +0x79 gvisor.dev/gvisor/pkg/tcpip/transport/udp.(*endpoint).checkV4Mapped() pkg/tcpip/transport/udp/endpoint.go:931 +0xee DIAGNOSIS: I0229 21:19:42.337549 14856 main.go:305] *************************** I0229 21:19:42.337657 14856 main.go:306] Args: [/syzkaller/managers/ptrace-proxy-sandbox-race/current/image -root /syzkaller/managers/ptrace-proxy-sandbox-race/workdir/gvisor_root -watchdog-action=panic -network=none -debug -alsologtostderr -platform=ptrace -file-access=shared -network=sandbox -TESTONLY-unsafe-nonroot debug -stacks --ps ci-gvisor-ptrace-proxy-sandbox-race-0] I0229 21:19:42.338565 14856 main.go:307] Version release-20200219.0-72-gce4d1e45bb88 I0229 21:19:42.338654 14856 main.go:308] PID: 14856 I0229 21:19:42.338740 14856 main.go:309] UID: 0, GID: 0 I0229 21:19:42.338832 14856 main.go:310] Configuration: I0229 21:19:42.338912 14856 main.go:311] RootDir: /syzkaller/managers/ptrace-proxy-sandbox-race/workdir/gvisor_root I0229 21:19:42.339007 14856 main.go:312] Platform: ptrace I0229 21:19:42.339090 14856 main.go:313] FileAccess: shared, overlay: false I0229 21:19:42.339184 14856 main.go:314] Network: sandbox, logging: false I0229 21:19:42.339437 14856 main.go:315] Strace: false, max size: 1024, syscalls: [] I0229 21:19:42.339539 14856 main.go:316] *************************** W0229 21:19:42.339651 14856 main.go:321] Block the TERM signal. This is only safe in tests! D0229 21:19:42.339831 14856 container.go:159] Load container "/syzkaller/managers/ptrace-proxy-sandbox-race/workdir/gvisor_root" "ci-gvisor-ptrace-proxy-sandbox-race-0" D0229 21:19:42.346511 14856 container.go:592] Signal container "ci-gvisor-ptrace-proxy-sandbox-race-0": signal 0 D0229 21:19:42.346683 14856 sandbox.go:806] Signal sandbox "ci-gvisor-ptrace-proxy-sandbox-race-0" D0229 21:19:42.346785 14856 sandbox.go:318] Connecting to sandbox "ci-gvisor-ptrace-proxy-sandbox-race-0" D0229 21:19:42.347227 14856 urpc.go:534] urpc: successfully marshalled 117 bytes. I0229 21:19:42.435861 14856 debug.go:122] Found sandbox "ci-gvisor-ptrace-proxy-sandbox-race-0", PID: 5995 I0229 21:19:42.436055 14856 debug.go:131] Retrieving sandbox stacks D0229 21:19:42.436187 14856 sandbox.go:924] Stacks sandbox "ci-gvisor-ptrace-proxy-sandbox-race-0" D0229 21:19:42.436726 14856 sandbox.go:318] Connecting to sandbox "ci-gvisor-ptrace-proxy-sandbox-race-0" W0229 21:19:42.436964 14856 error.go:48] FATAL ERROR: retrieving stacks: connecting to control server at PID 5995: connection refused retrieving stacks: connecting to control server at PID 5995: connection refused W0229 21:19:42.437483 14856 main.go:338] Failure to execute command, err: 1 Error collecting stacks: failed to run ["/syzkaller/managers/ptrace-proxy-sandbox-race/current/image" "-root" "/syzkaller/managers/ptrace-proxy-sandbox-race/workdir/gvisor_root" "-watchdog-action=panic" "-network=none" "-debug" "-alsologtostderr" "-platform=ptrace" "-file-access=shared" "-network=sandbox" "-TESTONLY-unsafe-nonroot" "debug" "-stacks" "--ps" "ci-gvisor-ptrace-proxy-sandbox-race-0"]: exit status 128 I0229 21:19:42.337549 14856 main.go:305] *************************** I0229 21:19:42.337657 14856 main.go:306] Args: [/syzkaller/managers/ptrace-proxy-sandbox-race/current/image -root /syzkaller/managers/ptrace-proxy-sandbox-race/workdir/gvisor_root -watchdog-action=panic -network=none -debug -alsologtostderr -platform=ptrace -file-access=shared -network=sandbox -TESTONLY-unsafe-nonroot debug -stacks --ps ci-gvisor-ptrace-proxy-sandbox-race-0] I0229 21:19:42.338565 14856 main.go:307] Version release-20200219.0-72-gce4d1e45bb88 I0229 21:19:42.338654 14856 main.go:308] PID: 14856 I0229 21:19:42.338740 14856 main.go:309] UID: 0, GID: 0 I0229 21:19:42.338832 14856 main.go:310] Configuration: I0229 21:19:42.338912 14856 main.go:311] RootDir: /syzkaller/managers/ptrace-proxy-sandbox-race/workdir/gvisor_root I0229 21:19:42.339007 14856 main.go:312] Platform: ptrace I0229 21:19:42.339090 14856 main.go:313] FileAccess: shared, overlay: false I0229 21:19:42.339184 14856 main.go:314] Network: sandbox, logging: false I0229 21:19:42.339437 14856 main.go:315] Strace: false, max size: 1024, syscalls: [] I0229 21:19:42.339539 14856 main.go:316] *************************** W0229 21:19:42.339651 14856 main.go:321] Block the TERM signal. This is only safe in tests! D0229 21:19:42.339831 14856 container.go:159] Load container "/syzkaller/managers/ptrace-proxy-sandbox-race/workdir/gvisor_root" "ci-gvisor-ptrace-proxy-sandbox-race-0" D0229 21:19:42.346511 14856 container.go:592] Signal container "ci-gvisor-ptrace-proxy-sandbox-race-0": signal 0 D0229 21:19:42.346683 14856 sandbox.go:806] Signal sandbox "ci-gvisor-ptrace-proxy-sandbox-race-0" D0229 21:19:42.346785 14856 sandbox.go:318] Connecting to sandbox "ci-gvisor-ptrace-proxy-sandbox-race-0" D0229 21:19:42.347227 14856 urpc.go:534] urpc: successfully marshalled 117 bytes. I0229 21:19:42.435861 14856 debug.go:122] Found sandbox "ci-gvisor-ptrace-proxy-sandbox-race-0", PID: 5995 I0229 21:19:42.436055 14856 debug.go:131] Retrieving sandbox stacks D0229 21:19:42.436187 14856 sandbox.go:924] Stacks sandbox "ci-gvisor-ptrace-proxy-sandbox-race-0" D0229 21:19:42.436726 14856 sandbox.go:318] Connecting to sandbox "ci-gvisor-ptrace-proxy-sandbox-race-0" W0229 21:19:42.436964 14856 error.go:48] FATAL ERROR: retrieving stacks: connecting to control server at PID 5995: connection refused retrieving stacks: connecting to control server at PID 5995: connection refused W0229 21:19:42.437483 14856 main.go:338] Failure to execute command, err: 1 gvisor.dev/gvisor/pkg/tcpip/transport/udp.(*endpoint).Connect() pkg/tcpip/transport/udp/endpoint.go:984 +0x6c gvisor.dev/gvisor/pkg/sentry/socket/netstack.(*SocketOperations).Connect() pkg/sentry/socket/netstack/netstack.go:698 +0x4c5 gvisor.dev/gvisor/pkg/sentry/syscalls/linux.Connect() pkg/sentry/syscalls/linux/sys_socket.go:280 +0x251 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).executeSyscall() pkg/sentry/kernel/task_syscall.go:170 +0x44d gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscallInvoke() pkg/sentry/kernel/task_syscall.go:291 +0xb4 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscallEnter() pkg/sentry/kernel/task_syscall.go:252 +0x109 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscall() pkg/sentry/kernel/task_syscall.go:227 +0x1b3 gvisor.dev/gvisor/pkg/sentry/kernel.(*runApp).execute() pkg/sentry/kernel/task_run.go:232 +0x1aaa gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).run() pkg/sentry/kernel/task_run.go:92 +0x320 Previous write at 0x00c00038cb50 by goroutine 362: gvisor.dev/gvisor/pkg/tcpip/transport/udp.(*endpoint).bindLocked() pkg/tcpip/transport/udp/endpoint.go:1176 +0x27c gvisor.dev/gvisor/pkg/tcpip/transport/udp.(*endpoint).prepareForWrite() pkg/tcpip/transport/udp/endpoint.go:323 +0x26d gvisor.dev/gvisor/pkg/tcpip/transport/udp.(*endpoint).write() pkg/tcpip/transport/udp/endpoint.go:400 +0x12b gvisor.dev/gvisor/pkg/tcpip/transport/udp.(*endpoint).Write() pkg/tcpip/transport/udp/endpoint.go:360 +0x81 gvisor.dev/gvisor/pkg/sentry/socket/netstack.(*SocketOperations).SendMsg() pkg/sentry/socket/netstack/netstack.go:2593 +0x1d5 gvisor.dev/gvisor/pkg/sentry/syscalls/linux.sendSingleMsg() pkg/sentry/syscalls/linux/sys_socket.go:1055 +0x489 gvisor.dev/gvisor/pkg/sentry/syscalls/linux.SendMMsg() pkg/sentry/syscalls/linux/sys_socket.go:979 +0x300 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).executeSyscall() pkg/sentry/kernel/task_syscall.go:170 +0x44d gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscallInvoke() pkg/sentry/kernel/task_syscall.go:291 +0xb4 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscallEnter() pkg/sentry/kernel/task_syscall.go:252 +0x109 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscall() pkg/sentry/kernel/task_syscall.go:227 +0x1b3 gvisor.dev/gvisor/pkg/sentry/kernel.(*runApp).execute() pkg/sentry/kernel/task_run.go:232 +0x1aaa gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).run() pkg/sentry/kernel/task_run.go:92 +0x320 Goroutine 349 (running) created at: gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).Start() pkg/sentry/kernel/task_start.go:315 +0x19d gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).Clone() pkg/sentry/kernel/task_clone.go:361 +0x1297 gvisor.dev/gvisor/pkg/sentry/syscalls/linux.clone() pkg/sentry/syscalls/linux/sys_thread.go:226 +0x171 gvisor.dev/gvisor/pkg/sentry/syscalls/linux.Clone() pkg/sentry/syscalls/linux/sys_clone_amd64.go:34 +0x80 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).executeSyscall() pkg/sentry/kernel/task_syscall.go:170 +0x44d gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscallInvoke() pkg/sentry/kernel/task_syscall.go:291 +0xb4 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscallEnter() pkg/sentry/kernel/task_syscall.go:252 +0x109 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscall() pkg/sentry/kernel/task_syscall.go:227 +0x1b3 gvisor.dev/gvisor/pkg/sentry/kernel.(*runApp).execute() pkg/sentry/kernel/task_run.go:232 +0x1aaa gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).run() pkg/sentry/kernel/task_run.go:92 +0x320 Goroutine 362 (running) created at: gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).Start() pkg/sentry/kernel/task_start.go:315 +0x19d gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).Clone() pkg/sentry/kernel/task_clone.go:361 +0x1297 gvisor.dev/gvisor/pkg/sentry/syscalls/linux.clone() pkg/sentry/syscalls/linux/sys_thread.go:226 +0x171 gvisor.dev/gvisor/pkg/sentry/syscalls/linux.Clone() pkg/sentry/syscalls/linux/sys_clone_amd64.go:34 +0x80 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).executeSyscall() pkg/sentry/kernel/task_syscall.go:170 +0x44d gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscallInvoke() pkg/sentry/kernel/task_syscall.go:291 +0xb4 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscallEnter() pkg/sentry/kernel/task_syscall.go:252 +0x109 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscall() pkg/sentry/kernel/task_syscall.go:227 +0x1b3 gvisor.dev/gvisor/pkg/sentry/kernel.(*runApp).execute() pkg/sentry/kernel/task_run.go:232 +0x1aaa gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).run() pkg/sentry/kernel/task_run.go:92 +0x320 ================== W0229 21:19:42.428287 14562 error.go:48] FATAL ERROR: waiting on pid 22: waiting on PID 22 in sandbox "ci-gvisor-ptrace-proxy-sandbox-race-0": urpc method "containerManager.WaitPID" failed: EOF waiting on pid 22: waiting on PID 22 in sandbox "ci-gvisor-ptrace-proxy-sandbox-race-0": urpc method "containerManager.WaitPID" failed: EOF W0229 21:19:42.428785 14562 main.go:338] Failure to execute command, err: 1 W0229 21:19:42.435766 5988 sandbox.go:742] Wait RPC to container "ci-gvisor-ptrace-proxy-sandbox-race-0" failed: urpc method "containerManager.Wait" failed: EOF. Will try waiting on the sandbox process instead. D0229 21:19:42.436165 5988 container.go:714] Destroy container "ci-gvisor-ptrace-proxy-sandbox-race-0" D0229 21:19:42.436824 5988 container.go:801] Destroying container "ci-gvisor-ptrace-proxy-sandbox-race-0" D0229 21:19:42.436950 5988 sandbox.go:1058] Destroying root container "ci-gvisor-ptrace-proxy-sandbox-race-0" by destroying sandbox D0229 21:19:42.437053 5988 sandbox.go:788] Destroy sandbox "ci-gvisor-ptrace-proxy-sandbox-race-0" D0229 21:19:42.437161 5988 container.go:815] Killing gofer for container "ci-gvisor-ptrace-proxy-sandbox-race-0", PID: 5994 I0229 21:19:42.539061 5988 main.go:329] Exiting with status: 16896