D0302 23:01:57.322865 60862 parameters.go:238] Clock(Monotonic): error: 265 ns, adjusted frequency from 2299774041 Hz to 2299774943 Hz D0302 23:01:57.323215 60862 parameters.go:238] Clock(Realtime): error: 240 ns, adjusted frequency from 2299774037 Hz to 2299774883 Hz D0302 23:01:57.323376 60862 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9387818189705896 monotonicBaseRef:2000296597 monotonicFrequency:2299774943 realtimeReady:1 realtimeBaseCycles:9387818190547285 realtimeBaseRef:1583190117323210178 realtimeFrequency:2299774883} I0302 23:01:58.322949 60862 loader.go:566] Received external signal 23, mode: Process D0302 23:01:58.322808 60862 parameters.go:238] Clock(Monotonic): error: 141 ns, adjusted frequency from 2299774943 Hz to 2299774737 Hz D0302 23:01:58.324493 60862 task_signals.go:446] [ 1] Notified of signal 23 D0302 23:01:58.324504 60862 parameters.go:238] Clock(Realtime): error: 98 ns, adjusted frequency from 2299774883 Hz to 2299774641 Hz D0302 23:01:58.324604 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:01:58.324731 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:01:58.324679 60862 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9387820489358088 monotonicBaseRef:3000243221 monotonicFrequency:2299774737 realtimeReady:1 realtimeBaseCycles:9387820493280894 realtimeBaseRef:1583190118324496706 realtimeFrequency:2299774641} D0302 23:01:58.324938 60862 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0302 23:01:58.325059 60862 task_signals.go:220] [ 1] Signal 23: delivering to handler D0302 23:01:59.322862 60862 parameters.go:238] Clock(Monotonic): error: -17 ns, adjusted frequency from 2299774737 Hz to 2299774412 Hz D0302 23:01:59.323163 60862 sampler.go:168] Time: Adjusting syscall overhead up to 8000 D0302 23:01:59.323305 60862 parameters.go:238] Clock(Realtime): error: 219 ns, adjusted frequency from 2299774641 Hz to 2299774878 Hz D0302 23:01:59.323423 60862 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9387822789249665 monotonicBaseRef:4000294025 monotonicFrequency:2299774412 realtimeReady:1 realtimeBaseCycles:9387822790300687 realtimeBaseRef:1583190119323298828 realtimeFrequency:2299774878} I0302 23:01:59.548881 60862 loader.go:566] Received external signal 23, mode: Process D0302 23:01:59.549077 60862 task_signals.go:446] [ 1] Notified of signal 23 D0302 23:01:59.549233 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:01:59.549272 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:01:59.549468 60862 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0302 23:01:59.549656 60862 task_signals.go:220] [ 1] Signal 23: delivering to handler I0302 23:02:00.322897 60862 loader.go:566] Received external signal 23, mode: Process D0302 23:02:00.322867 60862 parameters.go:238] Clock(Monotonic): error: 140 ns, adjusted frequency from 2299774412 Hz to 2299774812 Hz D0302 23:02:00.323092 60862 parameters.go:238] Clock(Realtime): error: 187 ns, adjusted frequency from 2299774878 Hz to 2299774890 Hz D0302 23:02:00.323094 60862 task_signals.go:446] [ 1] Notified of signal 23 D0302 23:02:00.323251 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:02:00.323218 60862 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9387825089047280 monotonicBaseRef:5000304114 monotonicFrequency:2299774812 realtimeReady:1 realtimeBaseCycles:9387825089582320 realtimeBaseRef:1583190120323084352 realtimeFrequency:2299774890} D0302 23:02:00.323367 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:02:00.323591 60862 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0302 23:02:00.323772 60862 task_signals.go:220] [ 1] Signal 23: delivering to handler D0302 23:02:01.322793 60862 parameters.go:238] Clock(Monotonic): error: 40 ns, adjusted frequency from 2299774812 Hz to 2299774603 Hz D0302 23:02:01.323622 60862 parameters.go:238] Clock(Realtime): error: 432 ns, adjusted frequency from 2299774890 Hz to 2299775382 Hz D0302 23:02:01.323765 60862 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9387827388644176 monotonicBaseRef:6000226751 monotonicFrequency:2299774603 realtimeReady:1 realtimeBaseCycles:9387827390552414 realtimeBaseRef:1583190121323604056 realtimeFrequency:2299775382} D0302 23:02:02.322933 60862 parameters.go:238] Clock(Monotonic): error: 52 ns, adjusted frequency from 2299774603 Hz to 2299774643 Hz D0302 23:02:02.323177 60862 parameters.go:238] Clock(Realtime): error: -302 ns, adjusted frequency from 2299775382 Hz to 2299773786 Hz D0302 23:02:02.323304 60862 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9387829688741325 monotonicBaseRef:7000367002 monotonicFrequency:2299774643 realtimeReady:1 realtimeBaseCycles:9387829689332960 realtimeBaseRef:1583190122323171476 realtimeFrequency:2299773786} I0302 23:02:02.548991 60862 loader.go:566] Received external signal 23, mode: Process D0302 23:02:02.549309 60862 task_signals.go:446] [ 1] Notified of signal 23 D0302 23:02:02.549468 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:02:02.549510 60862 task_block.go:223] [ 1] Interrupt queued I0302 23:02:02.549673 60862 loader.go:566] Received external signal 23, mode: Process D0302 23:02:02.549748 60862 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0302 23:02:02.549875 60862 task_signals.go:446] [ 1] Notified of signal 23 D0302 23:02:02.549989 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:02:02.549872 60862 task_signals.go:220] [ 1] Signal 23: delivering to handler D0302 23:02:02.550564 60862 task_block.go:223] [ 2] Interrupt queued D0302 23:02:02.550695 60862 task_block.go:223] [ 2] Interrupt queued D0302 23:02:02.550707 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:02:02.550863 60862 task_signals.go:176] [ 2] Not restarting syscall 202 after errno 516: interrupted by signal 23 D0302 23:02:02.551025 60862 task_signals.go:220] [ 2] Signal 23: delivering to handler D0302 23:02:03.322828 60862 parameters.go:238] Clock(Monotonic): error: 20 ns, adjusted frequency from 2299774643 Hz to 2299774573 Hz D0302 23:02:03.323002 60862 parameters.go:238] Clock(Realtime): error: 36 ns, adjusted frequency from 2299773786 Hz to 2299774560 Hz D0302 23:02:03.323100 60862 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9387831988216461 monotonicBaseRef:8000236768 monotonicFrequency:2299774573 realtimeReady:1 realtimeBaseCycles:9387831988705382 realtimeBaseRef:1583190123322996952 realtimeFrequency:2299774560} I0302 23:02:03.548992 60862 loader.go:566] Received external signal 23, mode: Process D0302 23:02:03.549256 60862 task_signals.go:446] [ 1] Notified of signal 23 D0302 23:02:03.549388 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:02:03.549439 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:02:03.549650 60862 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0302 23:02:03.549771 60862 task_signals.go:220] [ 1] Signal 23: delivering to handler I0302 23:02:03.551075 60862 loader.go:566] Received external signal 23, mode: Process D0302 23:02:03.552107 60862 task_signals.go:452] [ 2] Notified of group signal 23 D0302 23:02:03.554135 60862 task_block.go:223] [ 2] Interrupt queued D0302 23:02:03.554165 60862 task_block.go:223] [ 2] Interrupt queued I0302 23:02:03.554371 60862 loader.go:566] Received external signal 23, mode: Process D0302 23:02:03.554410 60862 task_signals.go:176] [ 2] Not restarting syscall 202 after errno 516: interrupted by signal 23 D0302 23:02:03.554530 60862 task_signals.go:220] [ 2] Signal 23: delivering to handler D0302 23:02:03.554518 60862 task_signals.go:446] [ 1] Notified of signal 23 D0302 23:02:03.554648 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:02:03.554690 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:02:03.554859 60862 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0302 23:02:03.554991 60862 task_signals.go:220] [ 1] Signal 23: delivering to handler D0302 23:02:04.323303 60862 parameters.go:238] Clock(Monotonic): error: 60 ns, adjusted frequency from 2299774573 Hz to 2299774675 Hz D0302 23:02:04.323564 60862 parameters.go:238] Clock(Realtime): error: 259 ns, adjusted frequency from 2299774560 Hz to 2299775123 Hz D0302 23:02:04.323693 60862 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9387834289143059 monotonicBaseRef:9000737697 monotonicFrequency:2299774675 realtimeReady:1 realtimeBaseCycles:9387834289772933 realtimeBaseRef:1583190124323559177 realtimeFrequency:2299775123} D0302 23:02:05.322818 60862 parameters.go:238] Clock(Monotonic): error: -3 ns, adjusted frequency from 2299774675 Hz to 2299774597 Hz D0302 23:02:05.322996 60862 parameters.go:238] Clock(Realtime): error: -107 ns, adjusted frequency from 2299775123 Hz to 2299774340 Hz D0302 23:02:05.323137 60862 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9387836587804629 monotonicBaseRef:10000253690 monotonicFrequency:2299774597 realtimeReady:1 realtimeBaseCycles:9387836588241423 realtimeBaseRef:1583190125322991020 realtimeFrequency:2299774340} I0302 23:02:05.549225 60862 loader.go:566] Received external signal 23, mode: Process D0302 23:02:05.549504 60862 task_signals.go:446] [ 1] Notified of signal 23 D0302 23:02:05.549673 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:02:05.549813 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:02:05.550015 60862 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0302 23:02:05.550142 60862 task_signals.go:220] [ 1] Signal 23: delivering to handler I0302 23:02:05.550937 60862 loader.go:566] Received external signal 23, mode: Process D0302 23:02:05.551062 60862 task_signals.go:452] [ 2] Notified of group signal 23 D0302 23:02:05.551170 60862 task_block.go:223] [ 2] Interrupt queued D0302 23:02:05.551518 60862 task_signals.go:220] [ 2] Signal 23: delivering to handler D0302 23:02:06.323059 60862 parameters.go:238] Clock(Monotonic): error: 36 ns, adjusted frequency from 2299774597 Hz to 2299774680 Hz D0302 23:02:06.323346 60862 parameters.go:238] Clock(Realtime): error: 119 ns, adjusted frequency from 2299774340 Hz to 2299774796 Hz D0302 23:02:06.323499 60862 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9387838888116699 monotonicBaseRef:11000487396 monotonicFrequency:2299774680 realtimeReady:1 realtimeBaseCycles:9387838888817178 realtimeBaseRef:1583190126323339495 realtimeFrequency:2299774796} D0302 23:02:07.322805 60862 parameters.go:238] Clock(Monotonic): error: -85 ns, adjusted frequency from 2299774680 Hz to 2299774403 Hz D0302 23:02:07.323024 60862 parameters.go:238] Clock(Realtime): error: -60 ns, adjusted frequency from 2299774796 Hz to 2299774422 Hz I0302 23:02:07.323027 60862 loader.go:566] Received external signal 23, mode: Process D0302 23:02:07.323146 60862 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9387841187321134 monotonicBaseRef:12000239439 monotonicFrequency:2299774403 realtimeReady:1 realtimeBaseCycles:9387841187853072 realtimeBaseRef:1583190127323018201 realtimeFrequency:2299774422} D0302 23:02:07.323293 60862 task_signals.go:446] [ 1] Notified of signal 23 D0302 23:02:07.323530 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:02:07.323560 60862 task_block.go:223] [ 1] Interrupt queued I0302 23:02:07.323687 60862 loader.go:566] Received external signal 23, mode: Process D0302 23:02:07.323812 60862 task_signals.go:434] [ 1] Discarding duplicate signal 23 D0302 23:02:07.323974 60862 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0302 23:02:07.324114 60862 task_signals.go:220] [ 1] Signal 23: delivering to handler D0302 23:02:08.323680 60862 sampler.go:168] Time: Adjusting syscall overhead up to 8000 D0302 23:02:08.323841 60862 parameters.go:238] Clock(Monotonic): error: 133 ns, adjusted frequency from 2299774403 Hz to 2299774882 Hz D0302 23:02:08.323984 60862 parameters.go:238] Clock(Realtime): error: -69 ns, adjusted frequency from 2299774422 Hz to 2299774460 Hz D0302 23:02:08.324084 60862 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9387843489505342 monotonicBaseRef:13001287283 monotonicFrequency:2299774882 realtimeReady:1 realtimeBaseCycles:9387843489837588 realtimeBaseRef:1583190128323979205 realtimeFrequency:2299774460} I0302 23:02:08.548928 60862 loader.go:566] Received external signal 23, mode: Process D0302 23:02:08.549200 60862 task_signals.go:446] [ 1] Notified of signal 23 D0302 23:02:08.549339 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:02:08.549383 60862 task_block.go:223] [ 1] Interrupt queued I0302 23:02:08.549464 60862 loader.go:566] Received external signal 23, mode: Process D0302 23:02:08.549557 60862 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0302 23:02:08.549614 60862 task_signals.go:446] [ 1] Notified of signal 23 D0302 23:02:08.549687 60862 task_signals.go:220] [ 1] Signal 23: delivering to handler D0302 23:02:08.549759 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:02:08.550139 60862 task_block.go:223] [ 2] Interrupt queued D0302 23:02:08.550188 60862 task_block.go:223] [ 2] Interrupt queued D0302 23:02:08.550259 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:02:08.550390 60862 task_signals.go:176] [ 2] Not restarting syscall 202 after errno 516: interrupted by signal 23 D0302 23:02:08.550508 60862 task_signals.go:220] [ 2] Signal 23: delivering to handler D0302 23:02:09.322891 60862 parameters.go:238] Clock(Monotonic): error: -13 ns, adjusted frequency from 2299774882 Hz to 2299774579 Hz I0302 23:02:09.322974 60862 loader.go:566] Received external signal 23, mode: Process D0302 23:02:09.323090 60862 parameters.go:238] Clock(Realtime): error: 147 ns, adjusted frequency from 2299774460 Hz to 2299774947 Hz D0302 23:02:09.323216 60862 task_signals.go:446] [ 1] Notified of signal 23 D0302 23:02:09.323195 60862 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9387845786920835 monotonicBaseRef:14000261361 monotonicFrequency:2299774579 realtimeReady:1 realtimeBaseCycles:9387845787555915 realtimeBaseRef:1583190129323085146 realtimeFrequency:2299774947} D0302 23:02:09.323381 60862 task_block.go:223] [ 1] Interrupt queued I0302 23:02:09.323518 60862 loader.go:566] Received external signal 23, mode: Process D0302 23:02:09.323501 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:02:09.323619 60862 task_signals.go:434] [ 1] Discarding duplicate signal 23 D0302 23:02:09.323833 60862 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0302 23:02:09.324027 60862 task_signals.go:220] [ 1] Signal 23: delivering to handler D0302 23:02:10.322719 60862 parameters.go:238] Clock(Monotonic): error: 404 ns, adjusted frequency from 2299774579 Hz to 2299775419 Hz D0302 23:02:10.323001 60862 parameters.go:238] Clock(Realtime): error: -98 ns, adjusted frequency from 2299774947 Hz to 2299774391 Hz D0302 23:02:10.323118 60862 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9387848086447626 monotonicBaseRef:15000153616 monotonicFrequency:2299775419 realtimeReady:1 realtimeBaseCycles:9387848087124611 realtimeBaseRef:1583190130322995462 realtimeFrequency:2299774391} I0302 23:02:10.323175 60862 loader.go:566] Received external signal 23, mode: Process D0302 23:02:10.323438 60862 task_signals.go:446] [ 1] Notified of signal 23 D0302 23:02:10.323536 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:02:10.323558 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:02:10.323772 60862 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0302 23:02:10.323894 60862 task_signals.go:220] [ 1] Signal 23: delivering to handler I0302 23:02:10.549125 60862 loader.go:566] Received external signal 23, mode: Process D0302 23:02:10.549417 60862 task_signals.go:446] [ 1] Notified of signal 23 D0302 23:02:10.549580 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:02:10.549726 60862 task_block.go:223] [ 1] Interrupt queued I0302 23:02:10.549811 60862 loader.go:566] Received external signal 23, mode: Process D0302 23:02:10.549945 60862 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0302 23:02:10.550018 60862 task_signals.go:446] [ 1] Notified of signal 23 D0302 23:02:10.550089 60862 task_signals.go:220] [ 1] Signal 23: delivering to handler D0302 23:02:10.550156 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:02:10.550491 60862 task_block.go:223] [ 2] Interrupt queued D0302 23:02:10.550581 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:02:10.550603 60862 task_block.go:223] [ 2] Interrupt queued D0302 23:02:10.550767 60862 task_signals.go:176] [ 2] Not restarting syscall 270 after errno 514: interrupted by signal 23 D0302 23:02:10.550875 60862 task_signals.go:220] [ 2] Signal 23: delivering to handler D0302 23:02:11.323147 60862 parameters.go:238] Clock(Monotonic): error: 53 ns, adjusted frequency from 2299775419 Hz to 2299774627 Hz D0302 23:02:11.323396 60862 parameters.go:238] Clock(Realtime): error: 26 ns, adjusted frequency from 2299774391 Hz to 2299774647 Hz D0302 23:02:11.323511 60862 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9387850387174877 monotonicBaseRef:16000567496 monotonicFrequency:2299774627 realtimeReady:1 realtimeBaseCycles:9387850387758475 realtimeBaseRef:1583190131323369182 realtimeFrequency:2299774647} I0302 23:02:11.323575 60862 loader.go:566] Received external signal 23, mode: Process D0302 23:02:11.323797 60862 task_signals.go:446] [ 1] Notified of signal 23 D0302 23:02:11.323917 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:02:11.324048 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:02:11.324292 60862 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0302 23:02:11.324448 60862 task_signals.go:220] [ 1] Signal 23: delivering to handler D0302 23:02:12.322853 60862 parameters.go:238] Clock(Monotonic): error: -10 ns, adjusted frequency from 2299774627 Hz to 2299774488 Hz I0302 23:02:12.323055 60862 loader.go:566] Received external signal 23, mode: Process D0302 23:02:12.323114 60862 parameters.go:238] Clock(Realtime): error: -80 ns, adjusted frequency from 2299774647 Hz to 2299774424 Hz D0302 23:02:12.323288 60862 task_signals.go:446] [ 1] Notified of signal 23 D0302 23:02:12.323278 60862 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9387852686286661 monotonicBaseRef:17000279275 monotonicFrequency:2299774488 realtimeReady:1 realtimeBaseCycles:9387852686932796 realtimeBaseRef:1583190132323108145 realtimeFrequency:2299774424} D0302 23:02:12.323457 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:02:12.323595 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:02:12.323831 60862 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0302 23:02:12.323952 60862 task_signals.go:220] [ 1] Signal 23: delivering to handler D0302 23:02:13.323491 60862 parameters.go:238] Clock(Monotonic): error: -17 ns, adjusted frequency from 2299774488 Hz to 2299774490 Hz D0302 23:02:13.323749 60862 parameters.go:238] Clock(Realtime): error: 22 ns, adjusted frequency from 2299774424 Hz to 2299774635 Hz D0302 23:02:13.323900 60862 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9387854987537894 monotonicBaseRef:18000921401 monotonicFrequency:2299774490 realtimeReady:1 realtimeBaseCycles:9387854988162727 realtimeBaseRef:1583190133323741036 realtimeFrequency:2299774635} I0302 23:02:13.323967 60862 loader.go:566] Received external signal 23, mode: Process D0302 23:02:13.324198 60862 task_signals.go:446] [ 1] Notified of signal 23 D0302 23:02:13.324363 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:02:13.324501 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:02:13.324794 60862 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0302 23:02:13.324897 60862 task_signals.go:220] [ 1] Signal 23: delivering to handler I0302 23:02:13.548975 60862 loader.go:566] Received external signal 23, mode: Process D0302 23:02:13.549263 60862 task_signals.go:446] [ 1] Notified of signal 23 D0302 23:02:13.549406 60862 task_block.go:223] [ 1] Interrupt queued I0302 23:02:13.549577 60862 loader.go:566] Received external signal 23, mode: Process D0302 23:02:13.549568 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:02:13.549680 60862 task_signals.go:434] [ 1] Discarding duplicate signal 23 D0302 23:02:13.549816 60862 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0302 23:02:13.549972 60862 task_signals.go:220] [ 1] Signal 23: delivering to handler D0302 23:02:14.323539 60862 parameters.go:238] Clock(Monotonic): error: 72 ns, adjusted frequency from 2299774490 Hz to 2299774670 Hz D0302 23:02:14.323762 60862 parameters.go:238] Clock(Realtime): error: 52 ns, adjusted frequency from 2299774635 Hz to 2299774692 Hz D0302 23:02:14.323904 60862 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9387857287429819 monotonicBaseRef:19000972464 monotonicFrequency:2299774670 realtimeReady:1 realtimeBaseCycles:9387857287973814 realtimeBaseRef:1583190134323756886 realtimeFrequency:2299774692} I0302 23:02:14.324249 60862 loader.go:566] Received external signal 23, mode: Process D0302 23:02:14.324668 60862 task_signals.go:446] [ 1] Notified of signal 23 D0302 23:02:14.324849 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:02:14.324911 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:02:14.325193 60862 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0302 23:02:14.325333 60862 task_signals.go:220] [ 1] Signal 23: delivering to handler D0302 23:02:15.324710 60862 parameters.go:238] Clock(Monotonic): error: 25 ns, adjusted frequency from 2299774670 Hz to 2299774679 Hz D0302 23:02:15.324930 60862 parameters.go:238] Clock(Realtime): error: -48 ns, adjusted frequency from 2299774692 Hz to 2299774546 Hz D0302 23:02:15.325036 60862 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9387859589896120 monotonicBaseRef:20002142853 monotonicFrequency:2299774679 realtimeReady:1 realtimeBaseCycles:9387859590433294 realtimeBaseRef:1583190135324924299 realtimeFrequency:2299774546} D0302 23:02:16.322817 60862 parameters.go:238] Clock(Monotonic): error: -5 ns, adjusted frequency from 2299774679 Hz to 2299774472 Hz D0302 23:02:16.323033 60862 parameters.go:238] Clock(Realtime): error: 47 ns, adjusted frequency from 2299774546 Hz to 2299774737 Hz D0302 23:02:16.323158 60862 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9387861885323140 monotonicBaseRef:21000252381 monotonicFrequency:2299774472 realtimeReady:1 realtimeBaseCycles:9387861885846291 realtimeBaseRef:1583190136323027787 realtimeFrequency:2299774737} D0302 23:02:17.323265 60862 parameters.go:238] Clock(Monotonic): error: -53 ns, adjusted frequency from 2299774472 Hz to 2299774536 Hz D0302 23:02:17.323512 60862 parameters.go:238] Clock(Realtime): error: 201 ns, adjusted frequency from 2299774737 Hz to 2299775089 Hz D0302 23:02:17.323673 60862 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9387864186123669 monotonicBaseRef:22000698536 monotonicFrequency:2299774536 realtimeReady:1 realtimeBaseCycles:9387864186722912 realtimeBaseRef:1583190137323506913 realtimeFrequency:2299775089} I0302 23:02:17.549521 60862 loader.go:566] Received external signal 23, mode: Process D0302 23:02:17.549810 60862 task_signals.go:446] [ 1] Notified of signal 23 D0302 23:02:17.549981 60862 task_block.go:223] [ 1] Interrupt queued I0302 23:02:17.550120 60862 loader.go:566] Received external signal 23, mode: Process D0302 23:02:17.550074 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:02:17.550237 60862 task_signals.go:434] [ 1] Discarding duplicate signal 23 D0302 23:02:17.550426 60862 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0302 23:02:17.550541 60862 task_signals.go:220] [ 1] Signal 23: delivering to handler D0302 23:02:18.322825 60862 parameters.go:238] Clock(Monotonic): error: -287 ns, adjusted frequency from 2299774536 Hz to 2299773936 Hz D0302 23:02:18.323025 60862 parameters.go:238] Clock(Realtime): error: -223 ns, adjusted frequency from 2299775089 Hz to 2299774068 Hz D0302 23:02:18.323148 60862 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9387866484889547 monotonicBaseRef:23000259946 monotonicFrequency:2299773936 realtimeReady:1 realtimeBaseCycles:9387866485378300 realtimeBaseRef:1583190138323020038 realtimeFrequency:2299774068} I0302 23:02:18.549704 60862 loader.go:566] Received external signal 23, mode: Process D0302 23:02:18.549990 60862 task_signals.go:446] [ 1] Notified of signal 23 D0302 23:02:18.550110 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:02:18.550125 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:02:18.550305 60862 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 I0302 23:02:18.550311 60862 loader.go:566] Received external signal 23, mode: Process D0302 23:02:18.550422 60862 task_signals.go:220] [ 1] Signal 23: delivering to handler D0302 23:02:18.550499 60862 task_signals.go:446] [ 1] Notified of signal 23 D0302 23:02:18.550650 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:02:18.550940 60862 task_block.go:223] [ 2] Interrupt queued D0302 23:02:18.550970 60862 task_block.go:223] [ 2] Interrupt queued D0302 23:02:18.551036 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:02:18.551212 60862 task_signals.go:176] [ 2] Not restarting syscall 202 after errno 516: interrupted by signal 23 D0302 23:02:18.551341 60862 task_signals.go:220] [ 2] Signal 23: delivering to handler D0302 23:02:19.325401 60862 parameters.go:238] Clock(Monotonic): error: 81 ns, adjusted frequency from 2299773936 Hz to 2299774912 Hz D0302 23:02:19.325691 60862 parameters.go:238] Clock(Realtime): error: 77 ns, adjusted frequency from 2299774068 Hz to 2299774780 Hz D0302 23:02:19.325836 60862 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9387868790581628 monotonicBaseRef:24002833305 monotonicFrequency:2299774912 realtimeReady:1 realtimeBaseCycles:9387868791281320 realtimeBaseRef:1583190139325685061 realtimeFrequency:2299774780} D0302 23:02:20.325292 60862 parameters.go:238] Clock(Monotonic): error: 68 ns, adjusted frequency from 2299774912 Hz to 2299774868 Hz D0302 23:02:20.325543 60862 parameters.go:238] Clock(Realtime): error: 162 ns, adjusted frequency from 2299774780 Hz to 2299774964 Hz D0302 23:02:20.325723 60862 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9387871090104988 monotonicBaseRef:25002723923 monotonicFrequency:2299774868 realtimeReady:1 realtimeBaseCycles:9387871090711719 realtimeBaseRef:1583190140325535315 realtimeFrequency:2299774964} D0302 23:02:21.323053 60862 parameters.go:238] Clock(Monotonic): error: 34 ns, adjusted frequency from 2299774868 Hz to 2299774790 Hz D0302 23:02:21.323329 60862 parameters.go:238] Clock(Realtime): error: 82 ns, adjusted frequency from 2299774964 Hz to 2299774788 Hz D0302 23:02:21.323514 60862 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9387873384622799 monotonicBaseRef:26000438022 monotonicFrequency:2299774790 realtimeReady:1 realtimeBaseCycles:9387873385392469 realtimeBaseRef:1583190141323320222 realtimeFrequency:2299774788} D0302 23:02:22.325232 60862 parameters.go:238] Clock(Monotonic): error: -29 ns, adjusted frequency from 2299774790 Hz to 2299774518 Hz I0302 23:02:22.325398 60862 loader.go:566] Received external signal 23, mode: Process D0302 23:02:22.325480 60862 parameters.go:238] Clock(Realtime): error: -230 ns, adjusted frequency from 2299774788 Hz to 2299774078 Hz D0302 23:02:22.325671 60862 task_signals.go:446] [ 1] Notified of signal 23 D0302 23:02:22.325664 60862 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9387875689526105 monotonicBaseRef:27002668029 monotonicFrequency:2299774518 realtimeReady:1 realtimeBaseCycles:9387875690118352 realtimeBaseRef:1583190142325473082 realtimeFrequency:2299774078} D0302 23:02:22.325778 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:02:22.325869 60862 task_block.go:223] [ 1] Interrupt queued I0302 23:02:22.325929 60862 loader.go:566] Received external signal 23, mode: Process D0302 23:02:22.326052 60862 task_signals.go:434] [ 1] Discarding duplicate signal 23 D0302 23:02:22.326304 60862 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0302 23:02:22.326438 60862 task_signals.go:220] [ 1] Signal 23: delivering to handler D0302 23:02:23.324475 60862 parameters.go:238] Clock(Monotonic): error: -95 ns, adjusted frequency from 2299774518 Hz to 2299774496 Hz D0302 23:02:23.324756 60862 parameters.go:238] Clock(Realtime): error: -137 ns, adjusted frequency from 2299774078 Hz to 2299774289 Hz D0302 23:02:23.324909 60862 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9387877987551937 monotonicBaseRef:28001907656 monotonicFrequency:2299774496 realtimeReady:1 realtimeBaseCycles:9387877988230931 realtimeBaseRef:1583190143324750620 realtimeFrequency:2299774289} D0302 23:02:24.322895 60862 parameters.go:238] Clock(Monotonic): error: 301 ns, adjusted frequency from 2299774496 Hz to 2299775192 Hz I0302 23:02:24.323083 60862 loader.go:566] Received external signal 23, mode: Process D0302 23:02:24.323149 60862 parameters.go:238] Clock(Realtime): error: 6 ns, adjusted frequency from 2299774289 Hz to 2299774649 Hz D0302 23:02:24.323291 60862 task_signals.go:446] [ 1] Notified of signal 23 D0302 23:02:24.323306 60862 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9387880283573398 monotonicBaseRef:29000275741 monotonicFrequency:2299775192 realtimeReady:1 realtimeBaseCycles:9387880284292130 realtimeBaseRef:1583190144323136074 realtimeFrequency:2299774649} D0302 23:02:24.323442 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:02:24.323569 60862 task_block.go:223] [ 1] Interrupt queued I0302 23:02:24.323598 60862 loader.go:566] Received external signal 23, mode: Process D0302 23:02:24.323756 60862 task_signals.go:434] [ 1] Discarding duplicate signal 23 D0302 23:02:24.323870 60862 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0302 23:02:24.324029 60862 task_signals.go:220] [ 1] Signal 23: delivering to handler D0302 23:02:25.323671 60862 parameters.go:238] Clock(Monotonic): error: -36 ns, adjusted frequency from 2299775192 Hz to 2299774542 Hz D0302 23:02:25.323951 60862 parameters.go:238] Clock(Realtime): error: 258 ns, adjusted frequency from 2299774649 Hz to 2299775168 Hz D0302 23:02:25.324084 60862 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9387882585255554 monotonicBaseRef:30001104936 monotonicFrequency:2299774542 realtimeReady:1 realtimeBaseCycles:9387882585907103 realtimeBaseRef:1583190145323936293 realtimeFrequency:2299775168} I0302 23:02:25.548942 60862 loader.go:566] Received external signal 23, mode: Process D0302 23:02:25.549139 60862 task_signals.go:446] [ 1] Notified of signal 23 D0302 23:02:25.549261 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:02:25.549368 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:02:25.549564 60862 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0302 23:02:25.549731 60862 task_signals.go:220] [ 1] Signal 23: delivering to handler D0302 23:02:26.322842 60862 parameters.go:238] Clock(Monotonic): error: -130 ns, adjusted frequency from 2299774542 Hz to 2299774186 Hz I0302 23:02:26.322988 60862 loader.go:566] Received external signal 23, mode: Process D0302 23:02:26.323050 60862 parameters.go:238] Clock(Realtime): error: -165 ns, adjusted frequency from 2299775168 Hz to 2299774219 Hz D0302 23:02:26.323199 60862 task_signals.go:446] [ 1] Notified of signal 23 D0302 23:02:26.323197 60862 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9387884883116736 monotonicBaseRef:31000272958 monotonicFrequency:2299774186 realtimeReady:1 realtimeBaseCycles:9387884883630803 realtimeBaseRef:1583190146323044263 realtimeFrequency:2299774219} D0302 23:02:26.323323 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:02:26.323364 60862 task_block.go:223] [ 1] Interrupt queued I0302 23:02:26.323434 60862 loader.go:566] Received external signal 23, mode: Process D0302 23:02:26.323694 60862 task_signals.go:446] [ 1] Notified of signal 23 D0302 23:02:26.323672 60862 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0302 23:02:26.323789 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:02:26.323808 60862 task_signals.go:220] [ 1] Signal 23: delivering to handler D0302 23:02:26.324288 60862 task_block.go:223] [ 2] Interrupt queued D0302 23:02:26.324392 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:02:26.324412 60862 task_block.go:223] [ 2] Interrupt queued D0302 23:02:26.324840 60862 task_signals.go:176] [ 2] Not restarting syscall 202 after errno 516: interrupted by signal 23 D0302 23:02:26.325019 60862 task_signals.go:220] [ 2] Signal 23: delivering to handler I0302 23:02:26.549677 60862 loader.go:566] Received external signal 23, mode: Process D0302 23:02:26.549885 60862 task_signals.go:446] [ 1] Notified of signal 23 D0302 23:02:26.550017 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:02:26.550113 60862 task_block.go:223] [ 1] Interrupt queued I0302 23:02:26.550178 60862 loader.go:566] Received external signal 23, mode: Process D0302 23:02:26.550267 60862 task_signals.go:434] [ 1] Discarding duplicate signal 23 D0302 23:02:26.550445 60862 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0302 23:02:26.550586 60862 task_signals.go:220] [ 1] Signal 23: delivering to handler D0302 23:02:27.322829 60862 parameters.go:238] Clock(Monotonic): error: 108 ns, adjusted frequency from 2299774186 Hz to 2299774740 Hz I0302 23:02:27.322947 60862 loader.go:566] Received external signal 23, mode: Process D0302 23:02:27.323054 60862 parameters.go:238] Clock(Realtime): error: -256 ns, adjusted frequency from 2299774219 Hz to 2299774054 Hz D0302 23:02:27.323193 60862 task_signals.go:446] [ 1] Notified of signal 23 D0302 23:02:27.323195 60862 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9387887182820378 monotonicBaseRef:32000242283 monotonicFrequency:2299774740 realtimeReady:1 realtimeBaseCycles:9387887183409212 realtimeBaseRef:1583190147323046084 realtimeFrequency:2299774054} D0302 23:02:27.323386 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:02:27.323442 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:02:27.323685 60862 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0302 23:02:27.323796 60862 task_signals.go:220] [ 1] Signal 23: delivering to handler D0302 23:02:28.324599 60862 parameters.go:238] Clock(Monotonic): error: -121 ns, adjusted frequency from 2299774740 Hz to 2299774329 Hz D0302 23:02:28.324868 60862 parameters.go:238] Clock(Realtime): error: 135 ns, adjusted frequency from 2299774054 Hz to 2299774924 Hz D0302 23:02:28.325004 60862 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9387889486705302 monotonicBaseRef:33002029494 monotonicFrequency:2299774329 realtimeReady:1 realtimeBaseCycles:9387889487360784 realtimeBaseRef:1583190148324862574 realtimeFrequency:2299774924} D0302 23:02:29.322781 60862 parameters.go:238] Clock(Monotonic): error: 235 ns, adjusted frequency from 2299774329 Hz to 2299775069 Hz I0302 23:02:29.322892 60862 loader.go:566] Received external signal 23, mode: Process D0302 23:02:29.322970 60862 parameters.go:238] Clock(Realtime): error: -43 ns, adjusted frequency from 2299774924 Hz to 2299774523 Hz D0302 23:02:29.323143 60862 task_signals.go:446] [ 1] Notified of signal 23 D0302 23:02:29.323135 60862 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9387891782254102 monotonicBaseRef:34000192127 monotonicFrequency:2299775069 realtimeReady:1 realtimeBaseCycles:9387891782770917 realtimeBaseRef:1583190149322964653 realtimeFrequency:2299774523} D0302 23:02:29.323289 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:02:29.323416 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:02:29.324370 60862 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0302 23:02:29.324513 60862 task_signals.go:220] [ 1] Signal 23: delivering to handler I0302 23:02:29.499646 60862 loader.go:566] Received external signal 23, mode: Process D0302 23:02:29.499853 60862 task_signals.go:446] [ 1] Notified of signal 23 D0302 23:02:29.499984 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:02:29.500172 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:02:29.500319 60862 urpc.go:577] urpc: unmarshal success. D0302 23:02:29.500363 60862 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 I0302 23:02:29.500432 60862 loader.go:566] Received external signal 23, mode: Process D0302 23:02:29.500538 60862 controller.go:501] containerManager.Signal &{CID:ci-gvisor-ptrace-proxy-sandbox-race-2 Signo:0 PID:0 Mode:Process} D0302 23:02:29.500598 60862 task_signals.go:220] [ 1] Signal 23: delivering to handler D0302 23:02:29.500684 60862 task_signals.go:446] [ 1] Notified of signal 23 D0302 23:02:29.500818 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:02:29.501189 60862 urpc.go:534] urpc: successfully marshalled 37 bytes. D0302 23:02:29.501206 60862 task_block.go:223] [ 2] Interrupt queued D0302 23:02:29.501248 60862 task_block.go:223] [ 2] Interrupt queued D0302 23:02:29.501378 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:02:29.501623 60862 task_signals.go:176] [ 2] Not restarting syscall 202 after errno 516: interrupted by signal 23 D0302 23:02:29.501795 60862 task_signals.go:220] [ 2] Signal 23: delivering to handler I0302 23:02:29.512905 60862 loader.go:566] Received external signal 23, mode: Process D0302 23:02:29.513121 60862 task_signals.go:446] [ 1] Notified of signal 23 D0302 23:02:29.513220 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:02:29.513366 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:02:29.513567 60862 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0302 23:02:29.513726 60862 task_signals.go:220] [ 1] Signal 23: delivering to handler I0302 23:02:29.549054 60862 loader.go:566] Received external signal 23, mode: Process D0302 23:02:29.549245 60862 task_signals.go:446] [ 1] Notified of signal 23 D0302 23:02:29.549405 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:02:29.549430 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:02:29.549609 60862 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0302 23:02:29.549727 60862 task_signals.go:220] [ 1] Signal 23: delivering to handler D0302 23:02:30.322835 60862 parameters.go:238] Clock(Monotonic): error: -26 ns, adjusted frequency from 2299775069 Hz to 2299774415 Hz D0302 23:02:30.323038 60862 parameters.go:238] Clock(Realtime): error: 215 ns, adjusted frequency from 2299774523 Hz to 2299775100 Hz D0302 23:02:30.323169 60862 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9387894082213711 monotonicBaseRef:35000272369 monotonicFrequency:2299774415 realtimeReady:1 realtimeBaseCycles:9387894082698895 realtimeBaseRef:1583190150323031379 realtimeFrequency:2299775100} D0302 23:02:31.322845 60862 parameters.go:238] Clock(Monotonic): error: 97 ns, adjusted frequency from 2299774415 Hz to 2299774855 Hz D0302 23:02:31.323070 60862 parameters.go:238] Clock(Realtime): error: -39 ns, adjusted frequency from 2299775100 Hz to 2299774472 Hz D0302 23:02:31.323163 60862 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9387896381936296 monotonicBaseRef:36000249832 monotonicFrequency:2299774855 realtimeReady:1 realtimeBaseCycles:9387896382546953 realtimeBaseRef:1583190151323063102 realtimeFrequency:2299774472} I0302 23:02:31.549129 60862 loader.go:566] Received external signal 23, mode: Process D0302 23:02:31.549630 60862 task_signals.go:446] [ 1] Notified of signal 23 D0302 23:02:31.549776 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:02:31.549846 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:02:31.550100 60862 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0302 23:02:31.550200 60862 task_signals.go:220] [ 1] Signal 23: delivering to handler D0302 23:02:32.322852 60862 parameters.go:238] Clock(Monotonic): error: 73 ns, adjusted frequency from 2299774855 Hz to 2299774737 Hz I0302 23:02:32.322949 60862 loader.go:566] Received external signal 23, mode: Process D0302 23:02:32.323042 60862 parameters.go:238] Clock(Realtime): error: 79 ns, adjusted frequency from 2299774472 Hz to 2299774808 Hz D0302 23:02:32.323109 60862 task_signals.go:446] [ 1] Notified of signal 23 D0302 23:02:32.323263 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:02:32.323175 60862 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9387898681798602 monotonicBaseRef:37000287857 monotonicFrequency:2299774737 realtimeReady:1 realtimeBaseCycles:9387898682258713 realtimeBaseRef:1583190152323035833 realtimeFrequency:2299774808} D0302 23:02:32.323406 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:02:32.323555 60862 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0302 23:02:32.323698 60862 task_signals.go:220] [ 1] Signal 23: delivering to handler D0302 23:02:33.323010 60862 parameters.go:238] Clock(Monotonic): error: -246 ns, adjusted frequency from 2299774737 Hz to 2299774039 Hz I0302 23:02:33.323108 60862 loader.go:566] Received external signal 23, mode: Process D0302 23:02:33.324296 60862 parameters.go:238] Clock(Realtime): error: -93 ns, adjusted frequency from 2299774808 Hz to 2299774329 Hz D0302 23:02:33.324580 60862 task_signals.go:446] [ 1] Notified of signal 23 D0302 23:02:33.325810 60862 task_block.go:223] [ 1] Interrupt queued I0302 23:02:33.325954 60862 loader.go:566] Received external signal 23, mode: Process D0302 23:02:33.326079 60862 task_signals.go:434] [ 1] Discarding duplicate signal 23 D0302 23:02:33.325585 60862 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9387900981935774 monotonicBaseRef:38000445452 monotonicFrequency:2299774039 realtimeReady:1 realtimeBaseCycles:9387900984915953 realtimeBaseRef:1583190153324289187 realtimeFrequency:2299774329} D0302 23:02:33.326253 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:02:33.326474 60862 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0302 23:02:33.326582 60862 task_signals.go:220] [ 1] Signal 23: delivering to handler D0302 23:02:34.324613 60862 parameters.go:238] Clock(Monotonic): error: -90 ns, adjusted frequency from 2299774039 Hz to 2299774514 Hz D0302 23:02:34.324907 60862 parameters.go:238] Clock(Realtime): error: -82 ns, adjusted frequency from 2299774329 Hz to 2299774469 Hz D0302 23:02:34.325060 60862 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9387903285385929 monotonicBaseRef:39002043920 monotonicFrequency:2299774514 realtimeReady:1 realtimeBaseCycles:9387903286090821 realtimeBaseRef:1583190154324898176 realtimeFrequency:2299774469} D0302 23:02:35.322805 60862 parameters.go:238] Clock(Monotonic): error: 223 ns, adjusted frequency from 2299774514 Hz to 2299775143 Hz D0302 23:02:35.322997 60862 parameters.go:238] Clock(Realtime): error: 101 ns, adjusted frequency from 2299774469 Hz to 2299774759 Hz D0302 23:02:35.323108 60862 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9387905581017960 monotonicBaseRef:40000242663 monotonicFrequency:2299775143 realtimeReady:1 realtimeBaseCycles:9387905581480674 realtimeBaseRef:1583190155322991634 realtimeFrequency:2299774759} D0302 23:02:36.322888 60862 parameters.go:238] Clock(Monotonic): error: -268 ns, adjusted frequency from 2299775143 Hz to 2299773969 Hz I0302 23:02:36.322989 60862 loader.go:566] Received external signal 23, mode: Process D0302 23:02:36.323116 60862 parameters.go:238] Clock(Realtime): error: -1 ns, adjusted frequency from 2299774759 Hz to 2299774529 Hz D0302 23:02:36.323275 60862 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9387907880973567 monotonicBaseRef:41000321133 monotonicFrequency:2299773969 realtimeReady:1 realtimeBaseCycles:9387907881526355 realtimeBaseRef:1583190156323109437 realtimeFrequency:2299774529} D0302 23:02:36.323323 60862 task_signals.go:446] [ 1] Notified of signal 23 D0302 23:02:36.323565 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:02:36.323660 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:02:36.326615 60862 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0302 23:02:36.326856 60862 task_signals.go:220] [ 1] Signal 23: delivering to handler D0302 23:02:37.325222 60862 parameters.go:238] Clock(Monotonic): error: 298 ns, adjusted frequency from 2299773969 Hz to 2299775194 Hz D0302 23:02:37.325451 60862 parameters.go:238] Clock(Realtime): error: 51 ns, adjusted frequency from 2299774529 Hz to 2299774752 Hz D0302 23:02:37.325623 60862 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9387910186119513 monotonicBaseRef:42002657004 monotonicFrequency:2299775194 realtimeReady:1 realtimeBaseCycles:9387910186672600 realtimeBaseRef:1583190157325445194 realtimeFrequency:2299774752} I0302 23:02:37.548903 60862 loader.go:566] Received external signal 23, mode: Process D0302 23:02:37.549103 60862 task_signals.go:446] [ 1] Notified of signal 23 D0302 23:02:37.549223 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:02:37.549395 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:02:37.549540 60862 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0302 23:02:37.549629 60862 task_signals.go:220] [ 1] Signal 23: delivering to handler D0302 23:02:38.322822 60862 parameters.go:238] Clock(Monotonic): error: 67 ns, adjusted frequency from 2299775194 Hz to 2299774742 Hz D0302 23:02:38.323011 60862 parameters.go:238] Clock(Realtime): error: -46 ns, adjusted frequency from 2299774752 Hz to 2299774570 Hz D0302 23:02:38.323140 60862 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9387912480383077 monotonicBaseRef:43000260408 monotonicFrequency:2299774742 realtimeReady:1 realtimeBaseCycles:9387912480835496 realtimeBaseRef:1583190158323005017 realtimeFrequency:2299774570} I0302 23:02:38.323525 60862 loader.go:566] Received external signal 23, mode: Process D0302 23:02:38.323688 60862 task_signals.go:446] [ 1] Notified of signal 23 D0302 23:02:38.323832 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:02:38.323893 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:02:38.324133 60862 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0302 23:02:38.325309 60862 task_signals.go:220] [ 1] Signal 23: delivering to handler I0302 23:02:38.327195 60862 loader.go:566] Received external signal 23, mode: Process D0302 23:02:38.328437 60862 task_signals.go:452] [ 2] Notified of group signal 23 D0302 23:02:38.329636 60862 task_block.go:223] [ 2] Interrupt queued I0302 23:02:38.329805 60862 loader.go:566] Received external signal 23, mode: Process D0302 23:02:38.329850 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:02:38.330059 60862 task_signals.go:220] [ 1] Signal 23: delivering to handler D0302 23:02:38.331679 60862 task_signals.go:446] [ 1] Notified of signal 23 D0302 23:02:38.332037 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:02:38.332263 60862 task_block.go:223] [ 3] Interrupt queued D0302 23:02:38.332392 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:02:38.329749 60862 task_block.go:223] [ 2] Interrupt queued D0302 23:02:38.332687 60862 task_block.go:223] [ 3] Interrupt queued D0302 23:02:38.332856 60862 task_signals.go:176] [ 2] Not restarting syscall 202 after errno 516: interrupted by signal 23 D0302 23:02:38.332894 60862 task_run.go:155] [ 3] Restarting syscall 202 after errno 512: not interrupted by handled signal D0302 23:02:38.333031 60862 task_signals.go:220] [ 2] Signal 23: delivering to handler D0302 23:02:39.322809 60862 parameters.go:238] Clock(Monotonic): error: -4 ns, adjusted frequency from 2299774742 Hz to 2299774681 Hz D0302 23:02:39.323071 60862 parameters.go:238] Clock(Realtime): error: -23 ns, adjusted frequency from 2299774570 Hz to 2299774563 Hz D0302 23:02:39.323168 60862 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9387914780117048 monotonicBaseRef:44000242679 monotonicFrequency:2299774681 realtimeReady:1 realtimeBaseCycles:9387914780748261 realtimeBaseRef:1583190159323065107 realtimeFrequency:2299774563} D0302 23:02:40.322837 60862 parameters.go:238] Clock(Monotonic): error: -301 ns, adjusted frequency from 2299774681 Hz to 2299773978 Hz I0302 23:02:40.322946 60862 loader.go:566] Received external signal 23, mode: Process D0302 23:02:40.323015 60862 parameters.go:238] Clock(Realtime): error: -4 ns, adjusted frequency from 2299774563 Hz to 2299774629 Hz D0302 23:02:40.323174 60862 task_signals.go:446] [ 1] Notified of signal 23 D0302 23:02:40.323306 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:02:40.323224 60862 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9387917079958789 monotonicBaseRef:45000271838 monotonicFrequency:2299773978 realtimeReady:1 realtimeBaseCycles:9387917080394371 realtimeBaseRef:1583190160323009252 realtimeFrequency:2299774629} D0302 23:02:40.323477 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:02:40.323696 60862 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0302 23:02:40.323806 60862 task_signals.go:220] [ 1] Signal 23: delivering to handler I0302 23:02:40.502476 60862 watchdog.go:277] Watchdog starting loop, tasks: 16, discount: 0s I0302 23:02:40.503034 60862 loader.go:566] Received external signal 23, mode: Process D0302 23:02:40.503282 60862 task_signals.go:446] [ 1] Notified of signal 23 D0302 23:02:40.503430 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:02:40.503491 60862 task_block.go:223] [ 1] Interrupt queued I0302 23:02:40.503556 60862 loader.go:566] Received external signal 23, mode: Process D0302 23:02:40.503677 60862 task_signals.go:434] [ 1] Discarding duplicate signal 23 D0302 23:02:40.503839 60862 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0302 23:02:40.503982 60862 task_signals.go:220] [ 1] Signal 23: delivering to handler D0302 23:02:41.323719 60862 parameters.go:238] Clock(Monotonic): error: 97 ns, adjusted frequency from 2299773978 Hz to 2299774747 Hz D0302 23:02:41.324101 60862 parameters.go:238] Clock(Realtime): error: -202 ns, adjusted frequency from 2299774629 Hz to 2299774069 Hz D0302 23:02:41.324224 60862 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9387919381751903 monotonicBaseRef:46001149809 monotonicFrequency:2299774747 realtimeReady:1 realtimeBaseCycles:9387919382653520 realtimeBaseRef:1583190161324089583 realtimeFrequency:2299774069} I0302 23:02:41.325788 60862 loader.go:566] Received external signal 23, mode: Process D0302 23:02:41.326040 60862 task_signals.go:446] [ 1] Notified of signal 23 D0302 23:02:41.326190 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:02:41.326384 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:02:41.326603 60862 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0302 23:02:41.326779 60862 task_signals.go:220] [ 1] Signal 23: delivering to handler D0302 23:02:42.322784 60862 parameters.go:238] Clock(Monotonic): error: 178 ns, adjusted frequency from 2299774747 Hz to 2299775052 Hz D0302 23:02:42.322959 60862 parameters.go:238] Clock(Realtime): error: 359 ns, adjusted frequency from 2299774069 Hz to 2299775443 Hz D0302 23:02:42.323073 60862 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9387921679389772 monotonicBaseRef:47000220640 monotonicFrequency:2299775052 realtimeReady:1 realtimeBaseCycles:9387921679815219 realtimeBaseRef:1583190162322953658 realtimeFrequency:2299775443} D0302 23:02:43.322836 60862 parameters.go:238] Clock(Monotonic): error: -209 ns, adjusted frequency from 2299775052 Hz to 2299774127 Hz D0302 23:02:43.323979 60862 parameters.go:238] Clock(Realtime): error: 39 ns, adjusted frequency from 2299775443 Hz to 2299774655 Hz D0302 23:02:43.324181 60862 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9387923979283357 monotonicBaseRef:48000272181 monotonicFrequency:2299774127 realtimeReady:1 realtimeBaseCycles:9387923981927033 realtimeBaseRef:1583190163323969570 realtimeFrequency:2299774655} I0302 23:02:43.549081 60862 loader.go:566] Received external signal 23, mode: Process D0302 23:02:43.549351 60862 task_signals.go:446] [ 1] Notified of signal 23 D0302 23:02:43.549515 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:02:43.549593 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:02:43.549804 60862 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0302 23:02:43.549959 60862 task_signals.go:220] [ 1] Signal 23: delivering to handler D0302 23:02:44.322769 60862 parameters.go:238] Clock(Monotonic): error: 2 ns, adjusted frequency from 2299774127 Hz to 2299774613 Hz I0302 23:02:44.322910 60862 loader.go:566] Received external signal 23, mode: Process D0302 23:02:44.322940 60862 parameters.go:238] Clock(Realtime): error: -162 ns, adjusted frequency from 2299774655 Hz to 2299774279 Hz D0302 23:02:44.323073 60862 task_signals.go:446] [ 1] Notified of signal 23 D0302 23:02:44.323071 60862 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9387926278906624 monotonicBaseRef:49000206583 monotonicFrequency:2299774613 realtimeReady:1 realtimeBaseCycles:9387926279322552 realtimeBaseRef:1583190164322935061 realtimeFrequency:2299774279} D0302 23:02:44.323187 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:02:44.323369 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:02:44.323577 60862 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0302 23:02:44.323700 60862 task_signals.go:220] [ 1] Signal 23: delivering to handler D0302 23:02:45.323255 60862 parameters.go:238] Clock(Monotonic): error: 91 ns, adjusted frequency from 2299774613 Hz to 2299774860 Hz D0302 23:02:45.323454 60862 parameters.go:238] Clock(Realtime): error: -8 ns, adjusted frequency from 2299774279 Hz to 2299774629 Hz D0302 23:02:45.323576 60862 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9387928579795202 monotonicBaseRef:50000690963 monotonicFrequency:2299774860 realtimeReady:1 realtimeBaseCycles:9387928580278773 realtimeBaseRef:1583190165323448999 realtimeFrequency:2299774629} I0302 23:02:45.550363 60862 loader.go:566] Received external signal 23, mode: Process D0302 23:02:45.550603 60862 task_signals.go:446] [ 1] Notified of signal 23 D0302 23:02:45.550750 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:02:45.550830 60862 task_block.go:223] [ 1] Interrupt queued I0302 23:02:45.550937 60862 loader.go:566] Received external signal 23, mode: Process D0302 23:02:45.551134 60862 task_signals.go:446] [ 1] Notified of signal 23 D0302 23:02:45.551121 60862 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0302 23:02:45.551236 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:02:45.551297 60862 task_signals.go:220] [ 1] Signal 23: delivering to handler D0302 23:02:45.551776 60862 task_block.go:223] [ 2] Interrupt queued D0302 23:02:45.551928 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:02:45.552268 60862 task_block.go:223] [ 2] Interrupt queued D0302 23:02:45.552483 60862 task_signals.go:176] [ 2] Not restarting syscall 202 after errno 516: interrupted by signal 23 D0302 23:02:45.552605 60862 task_signals.go:220] [ 2] Signal 23: delivering to handler D0302 23:02:46.323243 60862 parameters.go:238] Clock(Monotonic): error: -69 ns, adjusted frequency from 2299774860 Hz to 2299774414 Hz D0302 23:02:46.323436 60862 parameters.go:238] Clock(Realtime): error: 36 ns, adjusted frequency from 2299774629 Hz to 2299774692 Hz D0302 23:02:46.323565 60862 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9387930879495341 monotonicBaseRef:51000658472 monotonicFrequency:2299774414 realtimeReady:1 realtimeBaseCycles:9387930880013225 realtimeBaseRef:1583190166323431529 realtimeFrequency:2299774692} I0302 23:02:46.602929 60862 loader.go:566] Received external signal 23, mode: Process D0302 23:02:46.603129 60862 task_signals.go:446] [ 1] Notified of signal 23 D0302 23:02:46.603280 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:02:46.603427 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:02:46.603630 60862 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0302 23:02:46.603747 60862 task_signals.go:220] [ 1] Signal 23: delivering to handler D0302 23:02:47.322977 60862 parameters.go:238] Clock(Monotonic): error: -131 ns, adjusted frequency from 2299774414 Hz to 2299774392 Hz D0302 23:02:47.323190 60862 parameters.go:238] Clock(Realtime): error: 829 ns, adjusted frequency from 2299774692 Hz to 2299776567 Hz D0302 23:02:47.323313 60862 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9387933178709198 monotonicBaseRef:52000414727 monotonicFrequency:2299774392 realtimeReady:1 realtimeBaseCycles:9387933179216641 realtimeBaseRef:1583190167323183123 realtimeFrequency:2299776567} I0302 23:02:47.549959 60862 loader.go:566] Received external signal 23, mode: Process D0302 23:02:47.550154 60862 task_signals.go:446] [ 1] Notified of signal 23 D0302 23:02:47.550269 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:02:47.550431 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:02:47.550596 60862 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0302 23:02:47.550686 60862 task_signals.go:220] [ 1] Signal 23: delivering to handler D0302 23:02:48.323169 60862 parameters.go:238] Clock(Monotonic): error: -224 ns, adjusted frequency from 2299774392 Hz to 2299774123 Hz D0302 23:02:48.323379 60862 parameters.go:238] Clock(Realtime): error: -963 ns, adjusted frequency from 2299776567 Hz to 2299772350 Hz I0302 23:02:48.323369 60862 loader.go:566] Received external signal 23, mode: Process D0302 23:02:48.323471 60862 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9387935478924967 monotonicBaseRef:53000606648 monotonicFrequency:2299774123 realtimeReady:1 realtimeBaseCycles:9387935479427889 realtimeBaseRef:1583190168323372133 realtimeFrequency:2299772350} D0302 23:02:48.323605 60862 task_signals.go:446] [ 1] Notified of signal 23 D0302 23:02:48.323729 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:02:48.323864 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:02:48.324075 60862 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0302 23:02:48.324232 60862 task_signals.go:220] [ 1] Signal 23: delivering to handler D0302 23:02:49.323080 60862 parameters.go:238] Clock(Monotonic): error: 367 ns, adjusted frequency from 2299774123 Hz to 2299775352 Hz D0302 23:02:49.323282 60862 parameters.go:238] Clock(Realtime): error: 338 ns, adjusted frequency from 2299772350 Hz to 2299775374 Hz D0302 23:02:49.323431 60862 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9387937778493037 monotonicBaseRef:54000517050 monotonicFrequency:2299775352 realtimeReady:1 realtimeBaseCycles:9387937778976730 realtimeBaseRef:1583190169323274945 realtimeFrequency:2299775374} D0302 23:02:50.322784 60862 parameters.go:238] Clock(Monotonic): error: -18 ns, adjusted frequency from 2299775352 Hz to 2299774528 Hz D0302 23:02:50.322962 60862 parameters.go:238] Clock(Realtime): error: -249 ns, adjusted frequency from 2299775374 Hz to 2299774079 Hz D0302 23:02:50.323064 60862 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9387940077580367 monotonicBaseRef:55000217880 monotonicFrequency:2299774528 realtimeReady:1 realtimeBaseCycles:9387940078018702 realtimeBaseRef:1583190170322956043 realtimeFrequency:2299774079} I0302 23:02:50.550421 60862 loader.go:566] Received external signal 23, mode: Process D0302 23:02:50.550613 60862 task_signals.go:446] [ 1] Notified of signal 23 D0302 23:02:50.550717 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:02:50.550772 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:02:50.551012 60862 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0302 23:02:50.551130 60862 task_signals.go:220] [ 1] Signal 23: delivering to handler D0302 23:02:51.322959 60862 parameters.go:238] Clock(Monotonic): error: 150 ns, adjusted frequency from 2299774528 Hz to 2299774952 Hz D0302 23:02:51.323109 60862 parameters.go:238] Clock(Realtime): error: -144 ns, adjusted frequency from 2299774079 Hz to 2299774261 Hz D0302 23:02:51.323201 60862 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9387942377758901 monotonicBaseRef:56000393552 monotonicFrequency:2299774952 realtimeReady:1 realtimeBaseCycles:9387942378130020 realtimeBaseRef:1583190171323102683 realtimeFrequency:2299774261} D0302 23:02:52.322869 60862 parameters.go:238] Clock(Monotonic): error: 74 ns, adjusted frequency from 2299774952 Hz to 2299774672 Hz D0302 23:02:52.323098 60862 parameters.go:238] Clock(Realtime): error: 159 ns, adjusted frequency from 2299774261 Hz to 2299774909 Hz D0302 23:02:52.323223 60862 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9387944677319786 monotonicBaseRef:57000300470 monotonicFrequency:2299774672 realtimeReady:1 realtimeBaseCycles:9387944677878737 realtimeBaseRef:1583190172323091575 realtimeFrequency:2299774909} D0302 23:02:53.322887 60862 parameters.go:238] Clock(Monotonic): error: -388 ns, adjusted frequency from 2299774672 Hz to 2299773781 Hz D0302 23:02:53.323151 60862 parameters.go:238] Clock(Realtime): error: 123 ns, adjusted frequency from 2299774909 Hz to 2299774891 Hz D0302 23:02:53.323291 60862 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9387946977135372 monotonicBaseRef:58000318260 monotonicFrequency:2299773781 realtimeReady:1 realtimeBaseCycles:9387946977776729 realtimeBaseRef:1583190173323145094 realtimeFrequency:2299774891} I0302 23:02:53.548825 60862 loader.go:566] Received external signal 23, mode: Process D0302 23:02:53.549108 60862 task_signals.go:446] [ 1] Notified of signal 23 D0302 23:02:53.549232 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:02:53.549381 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:02:53.549549 60862 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0302 23:02:53.549643 60862 task_signals.go:220] [ 1] Signal 23: delivering to handler D0302 23:02:54.322834 60862 parameters.go:238] Clock(Monotonic): error: -45 ns, adjusted frequency from 2299773781 Hz to 2299774590 Hz D0302 23:02:54.323110 60862 parameters.go:238] Clock(Realtime): error: 103 ns, adjusted frequency from 2299774891 Hz to 2299774831 Hz I0302 23:02:54.323274 60862 loader.go:566] Received external signal 23, mode: Process D0302 23:02:54.323303 60862 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9387949276793405 monotonicBaseRef:59000267929 monotonicFrequency:2299774590 realtimeReady:1 realtimeBaseCycles:9387949277456522 realtimeBaseRef:1583190174323103742 realtimeFrequency:2299774831} D0302 23:02:54.323486 60862 task_signals.go:446] [ 1] Notified of signal 23 D0302 23:02:54.323624 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:02:54.323698 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:02:54.323975 60862 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0302 23:02:54.324100 60862 task_signals.go:220] [ 1] Signal 23: delivering to handler D0302 23:02:55.323073 60862 parameters.go:238] Clock(Monotonic): error: 338 ns, adjusted frequency from 2299774590 Hz to 2299775395 Hz D0302 23:02:55.323273 60862 parameters.go:238] Clock(Realtime): error: -267 ns, adjusted frequency from 2299774831 Hz to 2299773983 Hz D0302 23:02:55.323387 60862 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9387951577116606 monotonicBaseRef:60000506478 monotonicFrequency:2299775395 realtimeReady:1 realtimeBaseCycles:9387951577608475 realtimeBaseRef:1583190175323267724 realtimeFrequency:2299773983} D0302 23:02:56.324168 60862 parameters.go:238] Clock(Monotonic): error: -90 ns, adjusted frequency from 2299775395 Hz to 2299774295 Hz D0302 23:02:56.324371 60862 parameters.go:238] Clock(Realtime): error: -36 ns, adjusted frequency from 2299773983 Hz to 2299774409 Hz D0302 23:02:56.324473 60862 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9387953879413607 monotonicBaseRef:61001602935 monotonicFrequency:2299774295 realtimeReady:1 realtimeBaseCycles:9387953879905800 realtimeBaseRef:1583190176324364937 realtimeFrequency:2299774409} I0302 23:02:56.595429 60862 loader.go:566] Received external signal 23, mode: Process D0302 23:02:56.595650 60862 task_signals.go:446] [ 1] Notified of signal 23 D0302 23:02:56.595767 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:02:56.595934 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:02:56.596067 60862 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0302 23:02:56.596194 60862 task_signals.go:220] [ 1] Signal 23: delivering to handler D0302 23:02:57.322831 60862 parameters.go:238] Clock(Monotonic): error: -136 ns, adjusted frequency from 2299774295 Hz to 2299774306 Hz D0302 23:02:57.323063 60862 parameters.go:238] Clock(Realtime): error: 262 ns, adjusted frequency from 2299774409 Hz to 2299775223 Hz D0302 23:02:57.323240 60862 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9387956176106326 monotonicBaseRef:62000262987 monotonicFrequency:2299774306 realtimeReady:1 realtimeBaseCycles:9387956176670516 realtimeBaseRef:1583190177323056246 realtimeFrequency:2299775223} I0302 23:02:57.549077 60862 loader.go:566] Received external signal 23, mode: Process D0302 23:02:57.549316 60862 task_signals.go:446] [ 1] Notified of signal 23 D0302 23:02:57.549415 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:02:57.549551 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:02:57.549660 60862 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0302 23:02:57.549780 60862 task_signals.go:220] [ 1] Signal 23: delivering to handler D0302 23:02:58.322992 60862 parameters.go:238] Clock(Monotonic): error: 213 ns, adjusted frequency from 2299774306 Hz to 2299775101 Hz D0302 23:02:58.323213 60862 parameters.go:238] Clock(Realtime): error: -116 ns, adjusted frequency from 2299775223 Hz to 2299774345 Hz D0302 23:02:58.323355 60862 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9387958476254432 monotonicBaseRef:63000425524 monotonicFrequency:2299775101 realtimeReady:1 realtimeBaseCycles:9387958476792117 realtimeBaseRef:1583190178323206859 realtimeFrequency:2299774345} D0302 23:02:59.323276 60862 parameters.go:238] Clock(Monotonic): error: -128 ns, adjusted frequency from 2299775101 Hz to 2299774471 Hz D0302 23:02:59.323498 60862 parameters.go:238] Clock(Realtime): error: -2 ns, adjusted frequency from 2299774345 Hz to 2299774607 Hz D0302 23:02:59.323700 60862 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9387960776684991 monotonicBaseRef:64000710533 monotonicFrequency:2299774471 realtimeReady:1 realtimeBaseCycles:9387960777223568 realtimeBaseRef:1583190179323492585 realtimeFrequency:2299774607} D0302 23:03:00.323304 60862 parameters.go:238] Clock(Monotonic): error: -222 ns, adjusted frequency from 2299774471 Hz to 2299774153 Hz D0302 23:03:00.323588 60862 parameters.go:238] Clock(Realtime): error: 113 ns, adjusted frequency from 2299774607 Hz to 2299774866 Hz D0302 23:03:00.323699 60862 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9387963076525992 monotonicBaseRef:65000739461 monotonicFrequency:2299774153 realtimeReady:1 realtimeBaseCycles:9387963077200959 realtimeBaseRef:1583190180323580760 realtimeFrequency:2299774866} D0302 23:03:01.322850 60862 parameters.go:238] Clock(Monotonic): error: 79 ns, adjusted frequency from 2299774153 Hz to 2299774737 Hz D0302 23:03:01.323029 60862 parameters.go:238] Clock(Realtime): error: -302 ns, adjusted frequency from 2299774866 Hz to 2299773975 Hz D0302 23:03:01.323121 60862 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9387965375252666 monotonicBaseRef:66000283990 monotonicFrequency:2299774737 realtimeReady:1 realtimeBaseCycles:9387965375695393 realtimeBaseRef:1583190181323023995 realtimeFrequency:2299773975} D0302 23:03:02.322839 60862 parameters.go:238] Clock(Monotonic): error: -242 ns, adjusted frequency from 2299774737 Hz to 2299774045 Hz I0302 23:03:02.323032 60862 loader.go:566] Received external signal 23, mode: Process D0302 23:03:02.323123 60862 parameters.go:238] Clock(Realtime): error: 62 ns, adjusted frequency from 2299773975 Hz to 2299774727 Hz D0302 23:03:02.323285 60862 task_signals.go:446] [ 1] Notified of signal 23 D0302 23:03:02.323278 60862 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9387967675005077 monotonicBaseRef:67000274282 monotonicFrequency:2299774045 realtimeReady:1 realtimeBaseCycles:9387967675683984 realtimeBaseRef:1583190182323117315 realtimeFrequency:2299774727} D0302 23:03:02.323456 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:03:02.323516 60862 task_block.go:223] [ 1] Interrupt queued I0302 23:03:02.323611 60862 loader.go:566] Received external signal 23, mode: Process D0302 23:03:02.323787 60862 task_signals.go:434] [ 1] Discarding duplicate signal 23 D0302 23:03:02.323991 60862 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0302 23:03:02.324147 60862 task_signals.go:220] [ 1] Signal 23: delivering to handler I0302 23:03:02.550310 60862 loader.go:566] Received external signal 23, mode: Process D0302 23:03:02.551335 60862 task_signals.go:446] [ 1] Notified of signal 23 D0302 23:03:02.551532 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:03:02.551599 60862 task_block.go:223] [ 1] Interrupt queued I0302 23:03:02.551822 60862 loader.go:566] Received external signal 23, mode: Process D0302 23:03:02.551948 60862 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0302 23:03:02.552076 60862 task_signals.go:446] [ 1] Notified of signal 23 D0302 23:03:02.552200 60862 task_signals.go:220] [ 1] Signal 23: delivering to handler D0302 23:03:02.552333 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:03:02.552808 60862 task_block.go:223] [ 2] Interrupt queued D0302 23:03:02.552842 60862 task_block.go:223] [ 2] Interrupt queued D0302 23:03:02.552983 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:03:02.553283 60862 task_signals.go:176] [ 2] Not restarting syscall 202 after errno 516: interrupted by signal 23 D0302 23:03:02.553504 60862 task_signals.go:220] [ 2] Signal 23: delivering to handler D0302 23:03:03.156254 60862 urpc.go:577] urpc: unmarshal success. D0302 23:03:03.156449 60862 controller.go:501] containerManager.Signal &{CID:ci-gvisor-ptrace-proxy-sandbox-race-2 Signo:0 PID:0 Mode:Process} D0302 23:03:03.156721 60862 urpc.go:534] urpc: successfully marshalled 37 bytes. D0302 23:03:03.322813 60862 parameters.go:238] Clock(Monotonic): error: 472 ns, adjusted frequency from 2299774045 Hz to 2299775628 Hz D0302 23:03:03.323002 60862 parameters.go:238] Clock(Realtime): error: 243 ns, adjusted frequency from 2299774727 Hz to 2299775124 Hz D0302 23:03:03.323126 60862 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9387969974725183 monotonicBaseRef:68000250827 monotonicFrequency:2299775628 realtimeReady:1 realtimeBaseCycles:9387969975179280 realtimeBaseRef:1583190183322995811 realtimeFrequency:2299775124} D0302 23:03:04.323868 60862 parameters.go:238] Clock(Monotonic): error: 232 ns, adjusted frequency from 2299775628 Hz to 2299775252 Hz D0302 23:03:04.324147 60862 parameters.go:238] Clock(Realtime): error: -75 ns, adjusted frequency from 2299775124 Hz to 2299774446 Hz D0302 23:03:04.324316 60862 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9387972276917802 monotonicBaseRef:69001301795 monotonicFrequency:2299775252 realtimeReady:1 realtimeBaseCycles:9387972277585413 realtimeBaseRef:1583190184324139839 realtimeFrequency:2299774446} D0302 23:03:05.324986 60862 parameters.go:238] Clock(Monotonic): error: -719 ns, adjusted frequency from 2299775252 Hz to 2299773046 Hz D0302 23:03:05.325201 60862 parameters.go:238] Clock(Realtime): error: -256 ns, adjusted frequency from 2299774446 Hz to 2299773977 Hz D0302 23:03:05.325328 60862 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9387974579265715 monotonicBaseRef:70002420452 monotonicFrequency:2299773046 realtimeReady:1 realtimeBaseCycles:9387974579788548 realtimeBaseRef:1583190185325195894 realtimeFrequency:2299773977} D0302 23:03:06.322870 60862 parameters.go:238] Clock(Monotonic): error: 191 ns, adjusted frequency from 2299773046 Hz to 2299774938 Hz I0302 23:03:06.322969 60862 loader.go:566] Received external signal 23, mode: Process D0302 23:03:06.323100 60862 parameters.go:238] Clock(Realtime): error: 520 ns, adjusted frequency from 2299773977 Hz to 2299775805 Hz D0302 23:03:06.323204 60862 task_signals.go:446] [ 1] Notified of signal 23 D0302 23:03:06.323328 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:03:06.323274 60862 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9387976874168693 monotonicBaseRef:71000302822 monotonicFrequency:2299774938 realtimeReady:1 realtimeBaseCycles:9387976874725907 realtimeBaseRef:1583190186323092809 realtimeFrequency:2299775805} D0302 23:03:06.323465 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:03:06.323763 60862 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0302 23:03:06.323979 60862 task_signals.go:220] [ 1] Signal 23: delivering to handler I0302 23:03:06.596991 60862 loader.go:566] Received external signal 23, mode: Process D0302 23:03:06.597235 60862 task_signals.go:446] [ 1] Notified of signal 23 D0302 23:03:06.597378 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:03:06.597502 60862 task_block.go:223] [ 1] Interrupt queued I0302 23:03:06.597612 60862 loader.go:566] Received external signal 23, mode: Process D0302 23:03:06.597701 60862 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0302 23:03:06.597776 60862 task_signals.go:446] [ 1] Notified of signal 23 D0302 23:03:06.597843 60862 task_signals.go:220] [ 1] Signal 23: delivering to handler D0302 23:03:06.597923 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:03:06.598254 60862 task_block.go:223] [ 2] Interrupt queued D0302 23:03:06.598380 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:03:06.598772 60862 task_signals.go:220] [ 2] Signal 23: delivering to handler D0302 23:03:07.322854 60862 parameters.go:238] Clock(Monotonic): error: -59 ns, adjusted frequency from 2299774938 Hz to 2299774568 Hz D0302 23:03:07.323135 60862 parameters.go:238] Clock(Realtime): error: -162 ns, adjusted frequency from 2299775805 Hz to 2299774300 Hz D0302 23:03:07.323278 60862 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9387979173896795 monotonicBaseRef:72000282456 monotonicFrequency:2299774568 realtimeReady:1 realtimeBaseCycles:9387979174580134 realtimeBaseRef:1583190187323126908 realtimeFrequency:2299774300} D0302 23:03:08.324735 60862 parameters.go:238] Clock(Monotonic): error: -175 ns, adjusted frequency from 2299774568 Hz to 2299774179 Hz D0302 23:03:08.324945 60862 parameters.go:238] Clock(Realtime): error: -198 ns, adjusted frequency from 2299774300 Hz to 2299774147 Hz D0302 23:03:08.325050 60862 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9387981478003056 monotonicBaseRef:73002165985 monotonicFrequency:2299774179 realtimeReady:1 realtimeBaseCycles:9387981478521580 realtimeBaseRef:1583190188324938888 realtimeFrequency:2299774147} D0302 23:03:09.322883 60862 parameters.go:238] Clock(Monotonic): error: 212 ns, adjusted frequency from 2299774179 Hz to 2299775093 Hz I0302 23:03:09.323098 60862 loader.go:566] Received external signal 23, mode: Process D0302 23:03:09.323180 60862 parameters.go:238] Clock(Realtime): error: -139 ns, adjusted frequency from 2299774147 Hz to 2299774296 Hz D0302 23:03:09.323315 60862 task_signals.go:446] [ 1] Notified of signal 23 D0302 23:03:09.323442 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:03:09.323427 60862 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9387983773518827 monotonicBaseRef:74000314321 monotonicFrequency:2299775093 realtimeReady:1 realtimeBaseCycles:9387983774237734 realtimeBaseRef:1583190189323174369 realtimeFrequency:2299774296} D0302 23:03:09.323510 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:03:09.323804 60862 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0302 23:03:09.323959 60862 task_signals.go:220] [ 1] Signal 23: delivering to handler D0302 23:03:10.322877 60862 parameters.go:238] Clock(Monotonic): error: -242 ns, adjusted frequency from 2299775093 Hz to 2299774136 Hz I0302 23:03:10.322968 60862 loader.go:566] Received external signal 23, mode: Process D0302 23:03:10.323067 60862 parameters.go:238] Clock(Realtime): error: -13 ns, adjusted frequency from 2299774296 Hz to 2299774586 Hz D0302 23:03:10.323193 60862 task_signals.go:446] [ 1] Notified of signal 23 D0302 23:03:10.323206 60862 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9387986073291602 monotonicBaseRef:75000313313 monotonicFrequency:2299774136 realtimeReady:1 realtimeBaseCycles:9387986073752489 realtimeBaseRef:1583190190323061514 realtimeFrequency:2299774586} D0302 23:03:10.323343 60862 task_block.go:223] [ 1] Interrupt queued I0302 23:03:10.323511 60862 loader.go:566] Received external signal 23, mode: Process D0302 23:03:10.323408 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:03:10.323616 60862 task_signals.go:434] [ 1] Discarding duplicate signal 23 D0302 23:03:10.323934 60862 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0302 23:03:10.324118 60862 task_signals.go:220] [ 1] Signal 23: delivering to handler D0302 23:03:11.322867 60862 parameters.go:238] Clock(Monotonic): error: 197 ns, adjusted frequency from 2299774136 Hz to 2299774923 Hz I0302 23:03:11.322948 60862 loader.go:566] Received external signal 23, mode: Process D0302 23:03:11.323080 60862 parameters.go:238] Clock(Realtime): error: -31 ns, adjusted frequency from 2299774586 Hz to 2299774544 Hz D0302 23:03:11.323156 60862 task_signals.go:446] [ 1] Notified of signal 23 D0302 23:03:11.323300 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:03:11.323458 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:03:11.323644 60862 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0302 23:03:11.323754 60862 task_signals.go:220] [ 1] Signal 23: delivering to handler D0302 23:03:11.323188 60862 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9387988372999191 monotonicBaseRef:76000284376 monotonicFrequency:2299774923 realtimeReady:1 realtimeBaseCycles:9387988373557102 realtimeBaseRef:1583190191323074570 realtimeFrequency:2299774544} D0302 23:03:12.322853 60862 parameters.go:238] Clock(Monotonic): error: 0 ns, adjusted frequency from 2299774923 Hz to 2299774663 Hz D0302 23:03:12.323048 60862 parameters.go:238] Clock(Realtime): error: 163 ns, adjusted frequency from 2299774544 Hz to 2299774965 Hz D0302 23:03:12.323160 60862 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9387990672766916 monotonicBaseRef:77000281246 monotonicFrequency:2299774663 realtimeReady:1 realtimeBaseCycles:9387990673256186 realtimeBaseRef:1583190192323041758 realtimeFrequency:2299774965} D0302 23:03:13.322831 60862 parameters.go:238] Clock(Monotonic): error: 131 ns, adjusted frequency from 2299774663 Hz to 2299774910 Hz D0302 23:03:13.323048 60862 parameters.go:238] Clock(Realtime): error: -149 ns, adjusted frequency from 2299774965 Hz to 2299774241 Hz D0302 23:03:13.323193 60862 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9387992972505622 monotonicBaseRef:78000265610 monotonicFrequency:2299774910 realtimeReady:1 realtimeBaseCycles:9387992973034773 realtimeBaseRef:1583190193323043332 realtimeFrequency:2299774241} D0302 23:03:14.323191 60862 parameters.go:238] Clock(Monotonic): error: -205 ns, adjusted frequency from 2299774910 Hz to 2299774136 Hz D0302 23:03:14.323399 60862 parameters.go:238] Clock(Realtime): error: 54 ns, adjusted frequency from 2299774241 Hz to 2299774725 Hz D0302 23:03:14.323555 60862 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9387995273112340 monotonicBaseRef:79000627301 monotonicFrequency:2299774136 realtimeReady:1 realtimeBaseCycles:9387995273611370 realtimeBaseRef:1583190194323392216 realtimeFrequency:2299774725} I0302 23:03:14.549357 60862 loader.go:566] Received external signal 23, mode: Process D0302 23:03:14.549665 60862 task_signals.go:446] [ 1] Notified of signal 23 D0302 23:03:14.549824 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:03:14.549972 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:03:14.550166 60862 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0302 23:03:14.550310 60862 task_signals.go:220] [ 1] Signal 23: delivering to handler I0302 23:03:14.562868 60862 loader.go:566] Received external signal 23, mode: Process D0302 23:03:14.563152 60862 task_signals.go:446] [ 1] Notified of signal 23 D0302 23:03:14.563329 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:03:14.563449 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:03:14.563692 60862 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0302 23:03:14.563790 60862 task_signals.go:220] [ 1] Signal 23: delivering to handler D0302 23:03:15.322765 60862 parameters.go:238] Clock(Monotonic): error: -100 ns, adjusted frequency from 2299774136 Hz to 2299774463 Hz D0302 23:03:15.323488 60862 parameters.go:238] Clock(Realtime): error: 18 ns, adjusted frequency from 2299774725 Hz to 2299774624 Hz I0302 23:03:15.323927 60862 loader.go:566] Received external signal 23, mode: Process D0302 23:03:15.323980 60862 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9387997571902996 monotonicBaseRef:80000199659 monotonicFrequency:2299774463 realtimeReady:1 realtimeBaseCycles:9387997573588531 realtimeBaseRef:1583190195323480240 realtimeFrequency:2299774624} D0302 23:03:15.324159 60862 task_signals.go:446] [ 1] Notified of signal 23 D0302 23:03:15.325701 60862 task_block.go:223] [ 1] Interrupt queued I0302 23:03:15.325883 60862 loader.go:566] Received external signal 23, mode: Process D0302 23:03:15.326024 60862 task_signals.go:434] [ 1] Discarding duplicate signal 23 D0302 23:03:15.326222 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:03:15.326408 60862 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0302 23:03:15.326600 60862 task_signals.go:220] [ 1] Signal 23: delivering to handler D0302 23:03:16.324207 60862 parameters.go:238] Clock(Monotonic): error: 367 ns, adjusted frequency from 2299774463 Hz to 2299775326 Hz D0302 23:03:16.324437 60862 parameters.go:238] Clock(Realtime): error: 9 ns, adjusted frequency from 2299774624 Hz to 2299774526 Hz D0302 23:03:16.324538 60862 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9387999874988850 monotonicBaseRef:81001639535 monotonicFrequency:2299775326 realtimeReady:1 realtimeBaseCycles:9387999875552219 realtimeBaseRef:1583190196324432100 realtimeFrequency:2299774526} D0302 23:03:17.324820 60862 parameters.go:238] Clock(Monotonic): error: 98 ns, adjusted frequency from 2299775326 Hz to 2299774712 Hz D0302 23:03:17.325016 60862 parameters.go:238] Clock(Realtime): error: 174 ns, adjusted frequency from 2299774526 Hz to 2299775018 Hz D0302 23:03:17.325150 60862 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9388002176180699 monotonicBaseRef:82002255474 monotonicFrequency:2299774712 realtimeReady:1 realtimeBaseCycles:9388002176656584 realtimeBaseRef:1583190197325010347 realtimeFrequency:2299775018} I0302 23:03:17.325380 60862 loader.go:566] Received external signal 23, mode: Process D0302 23:03:17.325703 60862 task_signals.go:446] [ 1] Notified of signal 23 D0302 23:03:17.325880 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:03:17.326080 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:03:17.326342 60862 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0302 23:03:17.326519 60862 task_signals.go:220] [ 1] Signal 23: delivering to handler D0302 23:03:18.325541 60862 parameters.go:238] Clock(Monotonic): error: -385 ns, adjusted frequency from 2299774712 Hz to 2299773705 Hz D0302 23:03:18.325759 60862 parameters.go:238] Clock(Realtime): error: 3 ns, adjusted frequency from 2299775018 Hz to 2299774521 Hz D0302 23:03:18.325885 60862 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9388004477597604 monotonicBaseRef:83002969540 monotonicFrequency:2299773705 realtimeReady:1 realtimeBaseCycles:9388004478128856 realtimeBaseRef:1583190198325748355 realtimeFrequency:2299774521} D0302 23:03:19.322814 60862 parameters.go:238] Clock(Monotonic): error: 285 ns, adjusted frequency from 2299773705 Hz to 2299775323 Hz I0302 23:03:19.322921 60862 loader.go:566] Received external signal 23, mode: Process D0302 23:03:19.323070 60862 parameters.go:238] Clock(Realtime): error: 240 ns, adjusted frequency from 2299774521 Hz to 2299775155 Hz D0302 23:03:19.323175 60862 task_signals.go:446] [ 1] Notified of signal 23 D0302 23:03:19.323318 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:03:19.323208 60862 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9388006771050693 monotonicBaseRef:84000221175 monotonicFrequency:2299775323 realtimeReady:1 realtimeBaseCycles:9388006771728558 realtimeBaseRef:1583190199323063387 realtimeFrequency:2299775155} D0302 23:03:19.323437 60862 task_block.go:223] [ 1] Interrupt queued I0302 23:03:19.323490 60862 loader.go:566] Received external signal 23, mode: Process D0302 23:03:19.323646 60862 task_signals.go:434] [ 1] Discarding duplicate signal 23 D0302 23:03:19.323835 60862 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0302 23:03:19.324074 60862 task_signals.go:220] [ 1] Signal 23: delivering to handler I0302 23:03:19.548785 60862 loader.go:566] Received external signal 23, mode: Process D0302 23:03:19.549071 60862 task_signals.go:446] [ 1] Notified of signal 23 D0302 23:03:19.549207 60862 task_block.go:223] [ 1] Interrupt queued I0302 23:03:19.549346 60862 loader.go:566] Received external signal 23, mode: Process D0302 23:03:19.549309 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:03:19.549469 60862 task_signals.go:434] [ 1] Discarding duplicate signal 23 D0302 23:03:19.549657 60862 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0302 23:03:19.549854 60862 task_signals.go:220] [ 1] Signal 23: delivering to handler D0302 23:03:20.322820 60862 parameters.go:238] Clock(Monotonic): error: 10 ns, adjusted frequency from 2299775323 Hz to 2299774660 Hz D0302 23:03:20.323043 60862 parameters.go:238] Clock(Realtime): error: -275 ns, adjusted frequency from 2299775155 Hz to 2299773951 Hz D0302 23:03:20.323167 60862 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9388009070903859 monotonicBaseRef:85000255023 monotonicFrequency:2299774660 realtimeReady:1 realtimeBaseCycles:9388009071442487 realtimeBaseRef:1583190200323036764 realtimeFrequency:2299773951} D0302 23:03:21.323299 60862 parameters.go:238] Clock(Monotonic): error: 204 ns, adjusted frequency from 2299774660 Hz to 2299775007 Hz D0302 23:03:21.323478 60862 parameters.go:238] Clock(Realtime): error: -29 ns, adjusted frequency from 2299773951 Hz to 2299774586 Hz D0302 23:03:21.323593 60862 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9388011371765520 monotonicBaseRef:86000727678 monotonicFrequency:2299775007 realtimeReady:1 realtimeBaseCycles:9388011372218712 realtimeBaseRef:1583190201323472578 realtimeFrequency:2299774586} D0302 23:03:22.322824 60862 parameters.go:238] Clock(Monotonic): error: -83 ns, adjusted frequency from 2299775007 Hz to 2299774343 Hz D0302 23:03:22.323026 60862 parameters.go:238] Clock(Realtime): error: -32 ns, adjusted frequency from 2299774586 Hz to 2299774512 Hz D0302 23:03:22.323125 60862 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9388013670465858 monotonicBaseRef:87000260384 monotonicFrequency:2299774343 realtimeReady:1 realtimeBaseCycles:9388013670956319 realtimeBaseRef:1583190202323021673 realtimeFrequency:2299774512} I0302 23:03:22.539742 2425 main.go:305] *************************** I0302 23:03:22.539903 2425 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-2 /syz-execprog -executor=/syz-executor -arch=amd64 -sandbox=none -procs=1 -repeat=0 -threaded=true -collide=true -cover=0 -fault_call=-1 -fault_nth=-1 /syzkaller918683384] I0302 23:03:22.541873 2425 main.go:307] Version release-20200219.0-72-gce4d1e45bb88 I0302 23:03:22.541975 2425 main.go:308] PID: 2425 I0302 23:03:22.542080 2425 main.go:309] UID: 0, GID: 0 I0302 23:03:22.542182 2425 main.go:310] Configuration: I0302 23:03:22.542355 2425 main.go:311] RootDir: /syzkaller/managers/ptrace-proxy-sandbox-race/workdir/gvisor_root I0302 23:03:22.542510 2425 main.go:312] Platform: ptrace I0302 23:03:22.542607 2425 main.go:313] FileAccess: shared, overlay: false I0302 23:03:22.542817 2425 main.go:314] Network: sandbox, logging: false I0302 23:03:22.542935 2425 main.go:315] Strace: false, max size: 1024, syscalls: [] I0302 23:03:22.543098 2425 main.go:316] *************************** W0302 23:03:22.543191 2425 main.go:321] Block the TERM signal. This is only safe in tests! D0302 23:03:22.543616 2425 container.go:159] Load container "/syzkaller/managers/ptrace-proxy-sandbox-race/workdir/gvisor_root" "ci-gvisor-ptrace-proxy-sandbox-race-2" I0302 23:03:22.548880 60862 loader.go:566] Received external signal 23, mode: Process D0302 23:03:22.549075 60862 task_signals.go:446] [ 1] Notified of signal 23 D0302 23:03:22.549327 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:03:22.549513 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:03:22.549671 60862 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0302 23:03:22.549839 60862 task_signals.go:220] [ 1] Signal 23: delivering to handler D0302 23:03:22.556717 2425 container.go:592] Signal container "ci-gvisor-ptrace-proxy-sandbox-race-2": signal 0 D0302 23:03:22.556936 2425 sandbox.go:806] Signal sandbox "ci-gvisor-ptrace-proxy-sandbox-race-2" D0302 23:03:22.557025 2425 sandbox.go:318] Connecting to sandbox "ci-gvisor-ptrace-proxy-sandbox-race-2" D0302 23:03:22.558015 2425 urpc.go:534] urpc: successfully marshalled 117 bytes. D0302 23:03:22.558245 60862 urpc.go:577] urpc: unmarshal success. D0302 23:03:22.558459 60862 controller.go:501] containerManager.Signal &{CID:ci-gvisor-ptrace-proxy-sandbox-race-2 Signo:0 PID:0 Mode:Process} D0302 23:03:22.558738 60862 urpc.go:534] urpc: successfully marshalled 37 bytes. D0302 23:03:22.559233 2425 urpc.go:577] urpc: unmarshal success. D0302 23:03:22.559403 2425 exec.go:120] Exec arguments: /syz-execprog -executor=/syz-executor -arch=amd64 -sandbox=none -procs=1 -repeat=0 -threaded=true -collide=true -cover=0 -fault_call=-1 -fault_nth=-1 /syzkaller918683384 D0302 23:03:22.559571 2425 exec.go:121] Exec capablities: &{PermittedCaps:274877898751 InheritableCaps:274877898751 EffectiveCaps:274877898751 BoundingCaps:274877898751 AmbientCaps:0} D0302 23:03:22.559756 2425 container.go:533] Execute in container "ci-gvisor-ptrace-proxy-sandbox-race-2", args: /syz-execprog -executor=/syz-executor -arch=amd64 -sandbox=none -procs=1 -repeat=0 -threaded=true -collide=true -cover=0 -fault_call=-1 -fault_nth=-1 /syzkaller918683384 D0302 23:03:22.561001 2425 sandbox.go:283] Executing new process in container "ci-gvisor-ptrace-proxy-sandbox-race-2" in sandbox "ci-gvisor-ptrace-proxy-sandbox-race-2" D0302 23:03:22.561192 2425 sandbox.go:318] Connecting to sandbox "ci-gvisor-ptrace-proxy-sandbox-race-2" I0302 23:03:22.561583 60862 loader.go:566] Received external signal 23, mode: Process D0302 23:03:22.561848 60862 task_signals.go:446] [ 1] Notified of signal 23 D0302 23:03:22.562026 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:03:22.562297 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:03:22.562514 60862 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0302 23:03:22.562664 60862 task_signals.go:220] [ 1] Signal 23: delivering to handler D0302 23:03:22.562787 2425 urpc.go:534] urpc: successfully marshalled 634 bytes. D0302 23:03:22.563256 60862 urpc.go:577] urpc: unmarshal success. I0302 23:03:22.563389 60862 loader.go:566] Received external signal 23, mode: Process D0302 23:03:22.563591 60862 task_signals.go:452] [ 2] Notified of group signal 23 D0302 23:03:22.563735 60862 task_block.go:223] [ 2] Interrupt queued I0302 23:03:22.563854 60862 loader.go:566] Received external signal 23, mode: Process D0302 23:03:22.563927 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:03:22.563810 60862 task_block.go:223] [ 2] Interrupt queued D0302 23:03:22.564059 60862 task_signals.go:220] [ 1] Signal 23: delivering to handler D0302 23:03:22.564172 60862 task_signals.go:446] [ 1] Notified of signal 23 D0302 23:03:22.564302 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:03:22.564416 60862 task_block.go:223] [ 2] Interrupt queued D0302 23:03:22.564527 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:03:22.564728 60862 controller.go:267] containerManager.ExecuteAsync: /syz-execprog -executor=/syz-executor -arch=amd64 -sandbox=none -procs=1 -repeat=0 -threaded=true -collide=true -cover=0 -fault_call=-1 -fault_nth=-1 /syzkaller918683384 D0302 23:03:22.564753 60862 task_signals.go:176] [ 2] Not restarting syscall 202 after errno 516: interrupted by signal 23 D0302 23:03:22.564971 60862 task_signals.go:220] [ 2] Signal 23: delivering to handler D0302 23:03:22.565539 60862 transport_flipcall.go:127] send [channel @0xc0006b2300] Twalkgetattr{FID: 1, NewFID: 5, Names: [etc]} D0302 23:03:22.567789 60862 transport_flipcall.go:234] recv [channel @0xc0006b2300] Rlerror{Error: 2} D0302 23:03:22.567981 60862 transport_flipcall.go:127] send [channel @0xc0006b2300] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.etc, Size: 1} D0302 23:03:22.569384 60862 transport_flipcall.go:234] recv [channel @0xc0006b2300] Rlerror{Error: 95} I0302 23:03:22.572412 60862 kernel.go:797] EXEC: [/syz-execprog -executor=/syz-executor -arch=amd64 -sandbox=none -procs=1 -repeat=0 -threaded=true -collide=true -cover=0 -fault_call=-1 -fault_nth=-1 /syzkaller918683384] D0302 23:03:22.573318 60862 transport_flipcall.go:127] send [channel @0xc0006b2300] Twalkgetattr{FID: 1, NewFID: 5, Names: [syz-execprog]} I0302 23:03:22.574389 60862 loader.go:566] Received external signal 23, mode: Process D0302 23:03:22.574462 60862 transport_flipcall.go:234] recv [channel @0xc0006b2300] 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: 1583190116, NanoSec: 690363860}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}, QIDs: [QID{Type: 0, Version: 0, Path: 18350332}]} D0302 23:03:22.574755 60862 transport_flipcall.go:127] send [channel @0xc0006b2300] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syz-execprog, Size: 1} D0302 23:03:22.575635 60862 transport_flipcall.go:234] recv [channel @0xc0006b2300] Rlerror{Error: 95} D0302 23:03:22.575937 60862 transport_flipcall.go:127] send [channel @0xc0006b2300] Tgetattr{FID: 5, AttrMask: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime INo Size Blocks BTime Gen DataVersion}} D0302 23:03:22.576880 60862 transport_flipcall.go:234] recv [channel @0xc0006b2300] Rgetattr{Valid: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime Size Blocks}, QID: QID{Type: 0, Version: 0, Path: 18350332}, 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: 1583190116, NanoSec: 690363860}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}} D0302 23:03:22.577075 60862 transport_flipcall.go:127] send [channel @0xc0006b2300] Twalk{FID: 5, NewFID: 6, Names: []} D0302 23:03:22.578033 60862 transport_flipcall.go:234] recv [channel @0xc0006b2300] Rwalk{QIDs: []} D0302 23:03:22.578171 60862 transport_flipcall.go:127] send [channel @0xc0006b2300] Tlopen{FID: 6, Flags: ReadOnly} D0302 23:03:22.579079 60862 transport_flipcall.go:234] recv [channel @0xc0006b2300] Rlopen{QID: QID{Type: 0, Version: 0, Path: 18350332}, IoUnit: 0, File: &{{36}}} D0302 23:03:22.597982 60862 syscalls.go:266] Allocating stack with size of 8388608 bytes D0302 23:03:22.601838 60862 loader.go:832] updated processes: map[{ci-gvisor-ptrace-proxy-sandbox-race-2 0}:0xc00000e800 {ci-gvisor-ptrace-proxy-sandbox-race-2 22}:0xc000694e60] D0302 23:03:22.602485 60862 task_signals.go:446] [ 1] Notified of signal 23 D0302 23:03:22.602654 60862 task_block.go:223] [ 1] Interrupt queued I0302 23:03:22.602807 60862 loader.go:566] Received external signal 23, mode: Process D0302 23:03:22.602927 60862 task_signals.go:434] [ 1] Discarding duplicate signal 23 D0302 23:03:22.603068 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:03:22.602492 2425 urpc.go:577] urpc: unmarshal success. D0302 23:03:22.602634 2425 container.go:580] Wait on PID 22 in container "ci-gvisor-ptrace-proxy-sandbox-race-2" D0302 23:03:22.602755 2425 sandbox.go:761] Waiting for PID 22 in sandbox "ci-gvisor-ptrace-proxy-sandbox-race-2" D0302 23:03:22.602856 2425 sandbox.go:318] Connecting to sandbox "ci-gvisor-ptrace-proxy-sandbox-race-2" D0302 23:03:22.603171 2425 urpc.go:534] urpc: successfully marshalled 100 bytes. D0302 23:03:22.603266 60862 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0302 23:03:22.603378 60862 urpc.go:534] urpc: successfully marshalled 37 bytes. D0302 23:03:22.603418 60862 task_signals.go:220] [ 1] Signal 23: delivering to handler D0302 23:03:22.603867 60862 urpc.go:577] urpc: unmarshal success. D0302 23:03:22.604318 60862 controller.go:443] containerManager.Wait D0302 23:03:22.615612 60862 task_block.go:223] [ 22] Interrupt queued I0302 23:03:22.633018 60862 loader.go:566] Received external signal 23, mode: Process D0302 23:03:22.633253 60862 task_signals.go:446] [ 1] Notified of signal 23 D0302 23:03:22.633361 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:03:22.633562 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:03:22.633795 60862 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0302 23:03:22.634039 60862 task_signals.go:220] [ 1] Signal 23: delivering to handler I0302 23:03:22.652908 60862 loader.go:566] Received external signal 23, mode: Process D0302 23:03:22.653252 60862 task_signals.go:446] [ 1] Notified of signal 23 D0302 23:03:22.653404 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:03:22.653476 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:03:22.653663 60862 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0302 23:03:22.653782 60862 task_signals.go:220] [ 1] Signal 23: delivering to handler I0302 23:03:22.712914 60862 loader.go:566] Received external signal 23, mode: Process D0302 23:03:22.713130 60862 task_signals.go:446] [ 1] Notified of signal 23 D0302 23:03:22.713235 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:03:22.713397 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:03:22.713712 60862 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0302 23:03:22.713906 60862 task_signals.go:220] [ 1] Signal 23: delivering to handler I0302 23:03:22.832557 60862 loader.go:566] Received external signal 23, mode: Process D0302 23:03:22.832811 60862 task_signals.go:446] [ 1] Notified of signal 23 D0302 23:03:22.832959 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:03:22.833140 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:03:22.833316 60862 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0302 23:03:22.833443 60862 task_signals.go:220] [ 1] Signal 23: delivering to handler I0302 23:03:22.850003 60862 loader.go:566] Received external signal 23, mode: Process D0302 23:03:22.850249 60862 task_signals.go:446] [ 1] Notified of signal 23 D0302 23:03:22.850356 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:03:22.850471 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:03:22.850592 60862 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0302 23:03:22.850697 60862 task_signals.go:220] [ 1] Signal 23: delivering to handler I0302 23:03:22.871064 60862 loader.go:566] Received external signal 23, mode: Process D0302 23:03:22.871286 60862 task_signals.go:446] [ 1] Notified of signal 23 D0302 23:03:22.871438 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:03:22.871603 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:03:22.871771 60862 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0302 23:03:22.871962 60862 task_signals.go:220] [ 1] Signal 23: delivering to handler D0302 23:03:22.880199 60862 task_block.go:223] [ 23] Interrupt queued D0302 23:03:22.881190 60862 task_block.go:223] [ 24] Interrupt queued D0302 23:03:22.885928 60862 task_block.go:223] [ 25] Interrupt queued D0302 23:03:22.892293 60862 task_block.go:223] [ 26] Interrupt queued D0302 23:03:22.918890 60862 task_block.go:223] [ 27] Interrupt queued I0302 23:03:22.929963 60862 loader.go:566] Received external signal 23, mode: Process D0302 23:03:22.930167 60862 task_signals.go:446] [ 1] Notified of signal 23 D0302 23:03:22.930371 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:03:22.930395 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:03:22.930640 60862 task_block.go:223] [ 28] Interrupt queued D0302 23:03:22.930786 60862 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0302 23:03:22.930982 60862 task_signals.go:220] [ 1] Signal 23: delivering to handler I0302 23:03:22.931099 60862 loader.go:566] Received external signal 23, mode: Process D0302 23:03:22.931282 60862 task_signals.go:446] [ 1] Notified of signal 23 D0302 23:03:22.931380 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:03:22.931578 60862 task_block.go:223] [ 2] Interrupt queued D0302 23:03:22.931644 60862 task_block.go:223] [ 2] Interrupt queued D0302 23:03:22.931741 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:03:22.931896 60862 task_signals.go:176] [ 2] Not restarting syscall 202 after errno 516: interrupted by signal 23 D0302 23:03:22.932033 60862 task_signals.go:220] [ 2] Signal 23: delivering to handler I0302 23:03:22.935927 60862 loader.go:566] Received external signal 23, mode: Process D0302 23:03:22.936437 60862 task_signals.go:446] [ 1] Notified of signal 23 D0302 23:03:22.936659 60862 task_block.go:223] [ 1] Interrupt queued I0302 23:03:22.936886 60862 loader.go:566] Received external signal 23, mode: Process D0302 23:03:22.937057 60862 task_signals.go:434] [ 1] Discarding duplicate signal 23 D0302 23:03:22.936957 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:03:22.937356 60862 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 I0302 23:03:22.938381 60862 loader.go:566] Received external signal 23, mode: Process D0302 23:03:22.938483 60862 task_signals.go:220] [ 1] Signal 23: delivering to handler D0302 23:03:22.938540 60862 task_signals.go:446] [ 1] Notified of signal 23 D0302 23:03:22.938641 60862 task_block.go:223] [ 1] Interrupt queued I0302 23:03:22.938737 60862 loader.go:566] Received external signal 23, mode: Process D0302 23:03:22.938838 60862 task_signals.go:434] [ 1] Discarding duplicate signal 23 D0302 23:03:22.938979 60862 task_block.go:223] [ 2] Interrupt queued D0302 23:03:22.939071 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:03:22.939120 60862 task_block.go:223] [ 2] Interrupt queued D0302 23:03:22.939386 60862 task_signals.go:176] [ 2] Not restarting syscall 202 after errno 516: interrupted by signal 23 D0302 23:03:22.939514 60862 task_signals.go:220] [ 2] Signal 23: delivering to handler D0302 23:03:22.954804 60862 task_block.go:223] [ 29] Interrupt queued D0302 23:03:22.976221 60862 urpc.go:577] urpc: unmarshal success. D0302 23:03:22.976497 60862 controller.go:501] containerManager.Signal &{CID:ci-gvisor-ptrace-proxy-sandbox-race-2 Signo:0 PID:0 Mode:Process} D0302 23:03:22.976864 60862 urpc.go:534] urpc: successfully marshalled 37 bytes. I0302 23:03:23.003743 60862 loader.go:566] Received external signal 23, mode: Process D0302 23:03:23.004008 60862 task_signals.go:446] [ 1] Notified of signal 23 D0302 23:03:23.004142 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:03:23.004172 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:03:23.004367 60862 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0302 23:03:23.004471 60862 task_signals.go:220] [ 1] Signal 23: delivering to handler D0302 23:03:23.019396 60862 transport_flipcall.go:127] send [channel @0xc0006b2300] Twalkgetattr{FID: 1, NewFID: 7, Names: [etc]} D0302 23:03:23.020138 60862 transport_flipcall.go:234] recv [channel @0xc0006b2300] Rlerror{Error: 2} D0302 23:03:23.020375 60862 transport_flipcall.go:127] send [channel @0xc0006b2300] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.etc, Size: 1} D0302 23:03:23.021066 60862 transport_flipcall.go:234] recv [channel @0xc0006b2300] Rlerror{Error: 95} I0302 23:03:23.029694 60862 loader.go:566] Received external signal 23, mode: Process D0302 23:03:23.029979 60862 task_signals.go:446] [ 1] Notified of signal 23 D0302 23:03:23.030155 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:03:23.030166 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:03:23.030431 60862 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0302 23:03:23.030650 60862 task_signals.go:220] [ 1] Signal 23: delivering to handler D0302 23:03:23.046062 60862 task_block.go:223] [ 30] Interrupt queued D0302 23:03:23.084069 60862 task_block.go:223] [ 31] Interrupt queued D0302 23:03:23.085574 60862 task_block.go:223] [ 32] Interrupt queued D0302 23:03:23.091783 60862 task_block.go:223] [ 33] Interrupt queued I0302 23:03:23.123819 60862 loader.go:566] Received external signal 23, mode: Process D0302 23:03:23.124090 60862 task_signals.go:446] [ 1] Notified of signal 23 D0302 23:03:23.124294 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:03:23.124323 60862 task_block.go:223] [ 1] Interrupt queued I0302 23:03:23.124446 60862 loader.go:566] Received external signal 23, mode: Process D0302 23:03:23.124567 60862 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0302 23:03:23.124588 60862 task_signals.go:446] [ 1] Notified of signal 23 D0302 23:03:23.124759 60862 task_signals.go:220] [ 1] Signal 23: delivering to handler D0302 23:03:23.124863 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:03:23.125355 60862 task_block.go:223] [ 2] Interrupt queued D0302 23:03:23.125411 60862 task_block.go:223] [ 2] Interrupt queued D0302 23:03:23.125492 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:03:23.125636 60862 task_signals.go:176] [ 2] Not restarting syscall 202 after errno 516: interrupted by signal 23 D0302 23:03:23.125774 60862 task_signals.go:220] [ 2] Signal 23: delivering to handler D0302 23:03:23.132214 60862 task_block.go:223] [ 34] Interrupt queued I0302 23:03:23.134303 60862 loader.go:566] Received external signal 23, mode: Process D0302 23:03:23.134636 60862 task_signals.go:446] [ 1] Notified of signal 23 D0302 23:03:23.134832 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:03:23.134873 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:03:23.134912 60862 task_block.go:223] [ 35] Interrupt queued D0302 23:03:23.135110 60862 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0302 23:03:23.135332 60862 task_signals.go:220] [ 1] Signal 23: delivering to handler D0302 23:03:23.140922 60862 task_block.go:223] [ 36] Interrupt queued I0302 23:03:23.152974 60862 loader.go:566] Received external signal 23, mode: Process D0302 23:03:23.153261 60862 task_signals.go:446] [ 1] Notified of signal 23 D0302 23:03:23.153429 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:03:23.153501 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:03:23.153775 60862 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0302 23:03:23.153950 60862 task_signals.go:220] [ 1] Signal 23: delivering to handler D0302 23:03:23.174641 60862 task_block.go:223] [ 37] Interrupt queued D0302 23:03:23.183594 60862 task_block.go:223] [ 38] Interrupt queued D0302 23:03:23.183770 60862 transport_flipcall.go:127] send [channel @0xc0006b2300] Twalkgetattr{FID: 1, NewFID: 7, Names: [syzkaller918683384]} D0302 23:03:23.185238 60862 transport_flipcall.go:234] recv [channel @0xc0006b2300] 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: 307, BlockSize: 4096, Blocks: 8, ATime: {Sec: 1583190202, NanoSec: 494772524}, MTime: {Sec: 1583190202, NanoSec: 494772524}, CTime: {Sec: 1583190202, NanoSec: 494772524}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}, QIDs: [QID{Type: 0, Version: 0, Path: 18350326}]} I0302 23:03:23.186525 60862 loader.go:566] Received external signal 23, mode: Process D0302 23:03:23.185566 60862 transport_flipcall.go:127] send [channel @0xc0006b2300] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzkaller918683384, Size: 1} D0302 23:03:23.186800 60862 task_signals.go:446] [ 1] Notified of signal 23 D0302 23:03:23.187714 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:03:23.187752 60862 transport_flipcall.go:234] recv [channel @0xc0006b2300] Rlerror{Error: 95} D0302 23:03:23.187825 60862 task_block.go:223] [ 1] Interrupt queued I0302 23:03:23.187927 60862 loader.go:566] Received external signal 23, mode: Process D0302 23:03:23.188084 60862 task_signals.go:446] [ 1] Notified of signal 23 D0302 23:03:23.188070 60862 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0302 23:03:23.188175 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:03:23.188185 60862 transport_flipcall.go:127] send [channel @0xc0006b2300] Tgetattr{FID: 7, AttrMask: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime INo Size Blocks BTime Gen DataVersion}} D0302 23:03:23.188263 60862 task_signals.go:220] [ 1] Signal 23: delivering to handler D0302 23:03:23.188851 60862 task_block.go:223] [ 2] Interrupt queued D0302 23:03:23.189015 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:03:23.189027 60862 task_block.go:223] [ 2] Interrupt queued D0302 23:03:23.189269 60862 task_signals.go:176] [ 2] Not restarting syscall 202 after errno 516: interrupted by signal 23 D0302 23:03:23.189393 60862 task_signals.go:220] [ 2] Signal 23: delivering to handler D0302 23:03:23.189394 60862 transport_flipcall.go:234] recv [channel @0xc0006b2300] Rgetattr{Valid: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime Size Blocks}, QID: QID{Type: 0, Version: 0, Path: 18350326}, Attr: Attr{Mode: 0o100600, UID: 0, GID: 0, NLink: 1, RDev: 0, Size: 307, BlockSize: 4096, Blocks: 8, ATime: {Sec: 1583190202, NanoSec: 494772524}, MTime: {Sec: 1583190202, NanoSec: 494772524}, CTime: {Sec: 1583190202, NanoSec: 494772524}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}} D0302 23:03:23.189632 60862 transport_flipcall.go:127] send [channel @0xc0006b2300] Twalk{FID: 7, NewFID: 8, Names: []} D0302 23:03:23.190265 60862 transport_flipcall.go:234] recv [channel @0xc0006b2300] Rwalk{QIDs: []} D0302 23:03:23.190450 60862 transport_flipcall.go:127] send [channel @0xc0006b2300] Tlopen{FID: 8, Flags: ReadOnly} D0302 23:03:23.191498 60862 transport_flipcall.go:234] recv [channel @0xc0006b2300] Rlopen{QID: QID{Type: 0, Version: 0, Path: 18350326}, IoUnit: 0, File: &{{28}}} D0302 23:03:23.192355 60862 transport_flipcall.go:127] send [channel @0xc0006b2300] Tgetattr{FID: 8, AttrMask: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime INo Size Blocks BTime Gen DataVersion}} D0302 23:03:23.193360 60862 transport_flipcall.go:234] recv [channel @0xc0006b2300] Rgetattr{Valid: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime Size Blocks}, QID: QID{Type: 0, Version: 0, Path: 18350326}, Attr: Attr{Mode: 0o100600, UID: 0, GID: 0, NLink: 1, RDev: 0, Size: 307, BlockSize: 4096, Blocks: 8, ATime: {Sec: 1583190202, NanoSec: 494772524}, MTime: {Sec: 1583190202, NanoSec: 494772524}, CTime: {Sec: 1583190202, NanoSec: 494772524}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}} 2020/03/02 23:03:23 parsed 1 programs I0302 23:03:23.203855 60862 loader.go:566] Received external signal 23, mode: Process D0302 23:03:23.204067 60862 task_signals.go:446] [ 1] Notified of signal 23 D0302 23:03:23.204181 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:03:23.204232 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:03:23.204458 60862 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0302 23:03:23.204615 60862 task_signals.go:220] [ 1] Signal 23: delivering to handler D0302 23:03:23.204807 60862 task_stop.go:118] [ 31] Entering internal stop (*kernel.vforkStop)(nil) D0302 23:03:23.205748 60862 task_block.go:223] [ 39] Interrupt queued D0302 23:03:23.213108 60862 transport_flipcall.go:127] send [channel @0xc0006b2300] Twalkgetattr{FID: 1, NewFID: 9, Names: [syz-executor]} D0302 23:03:23.214585 60862 transport_flipcall.go:234] recv [channel @0xc0006b2300] 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: 1583190116, NanoSec: 694364252}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}, QIDs: [QID{Type: 0, Version: 0, Path: 18350334}]} D0302 23:03:23.214879 60862 transport_flipcall.go:127] send [channel @0xc0006b2300] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syz-executor, Size: 1} D0302 23:03:23.215445 60862 transport_flipcall.go:234] recv [channel @0xc0006b2300] Rlerror{Error: 95} D0302 23:03:23.215767 60862 transport_flipcall.go:127] send [channel @0xc0006b2300] Tgetattr{FID: 9, AttrMask: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime INo Size Blocks BTime Gen DataVersion}} D0302 23:03:23.216693 60862 transport_flipcall.go:234] recv [channel @0xc0006b2300] Rgetattr{Valid: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime Size Blocks}, QID: QID{Type: 0, Version: 0, Path: 18350334}, 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: 1583190116, NanoSec: 694364252}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}} D0302 23:03:23.216942 60862 transport_flipcall.go:127] send [channel @0xc0006b2300] Twalk{FID: 9, NewFID: 10, Names: []} D0302 23:03:23.239501 60862 transport_flipcall.go:234] recv [channel @0xc0006b2300] Rwalk{QIDs: []} D0302 23:03:23.239689 60862 transport_flipcall.go:127] send [channel @0xc0006b2300] Tlopen{FID: 10, Flags: ReadOnly} D0302 23:03:23.242524 60862 transport_flipcall.go:234] recv [channel @0xc0006b2300] Rlopen{QID: QID{Type: 0, Version: 0, Path: 18350334}, IoUnit: 0, File: &{{29}}} D0302 23:03:23.243523 60862 syscalls.go:266] [ 39] Allocating stack with size of 8388608 bytes D0302 23:03:23.244411 60862 task_stop.go:137] [ 31] Leaving internal stop (*kernel.vforkStop)(nil) I0302 23:03:23.266150 60862 loader.go:566] Received external signal 23, mode: Process D0302 23:03:23.266363 60862 task_signals.go:446] [ 1] Notified of signal 23 D0302 23:03:23.266466 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:03:23.266582 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:03:23.266734 60862 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0302 23:03:23.266858 60862 task_signals.go:220] [ 1] Signal 23: delivering to handler D0302 23:03:23.277112 60862 transport_flipcall.go:127] send [channel @0xc0006b2300] Twalkgetattr{FID: 1, NewFID: 11, Names: [etc]} D0302 23:03:23.277812 60862 transport_flipcall.go:234] recv [channel @0xc0006b2300] Rlerror{Error: 2} D0302 23:03:23.277960 60862 transport_flipcall.go:127] send [channel @0xc0006b2300] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.etc, Size: 1} D0302 23:03:23.278541 60862 transport_flipcall.go:234] recv [channel @0xc0006b2300] Rlerror{Error: 95} I0302 23:03:23.281929 60862 loader.go:566] Received external signal 23, mode: Process D0302 23:03:23.282140 60862 task_signals.go:446] [ 1] Notified of signal 23 D0302 23:03:23.282279 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:03:23.282388 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:03:23.282586 60862 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0302 23:03:23.282689 60862 task_signals.go:220] [ 1] Signal 23: delivering to handler D0302 23:03:23.284105 60862 task_exit.go:221] [ 39] Transitioning from exit state TaskExitNone to TaskExitInitiated D0302 23:03:23.285316 60862 task_exit.go:221] [ 39] Transitioning from exit state TaskExitInitiated to TaskExitZombie D0302 23:03:23.285502 60862 task_signals.go:446] [ 22] Notified of signal 17 D0302 23:03:23.285620 60862 task_block.go:223] [ 22] Interrupt queued D0302 23:03:23.285802 60862 task_block.go:223] [ 22] Interrupt queued D0302 23:03:23.286025 60862 task_signals.go:179] [ 22] Restarting syscall 202 after errno 512: interrupted by signal 17 D0302 23:03:23.286215 60862 task_signals.go:220] [ 22] Signal 17: delivering to handler D0302 23:03:23.286230 60862 task_exit.go:221] [ 39] Transitioning from exit state TaskExitZombie to TaskExitDead D0302 23:03:23.287982 60862 task_block.go:223] [ 40] Interrupt queued D0302 23:03:23.294780 60862 transport_flipcall.go:127] send [channel @0xc0006b2300] Twalkgetattr{FID: 1, NewFID: 11, Names: [syz-executor]} D0302 23:03:23.296223 60862 transport_flipcall.go:234] recv [channel @0xc0006b2300] 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: 1583190116, NanoSec: 694364252}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}, QIDs: [QID{Type: 0, Version: 0, Path: 18350334}]} D0302 23:03:23.296392 60862 transport_flipcall.go:127] send [channel @0xc0006b2300] Tclunk{FID: 11} D0302 23:03:23.296952 60862 transport_flipcall.go:234] recv [channel @0xc0006b2300] Rclunk{} D0302 23:03:23.297243 60862 transport_flipcall.go:127] send [channel @0xc0006b2300] Tgetattr{FID: 9, AttrMask: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime INo Size Blocks BTime Gen DataVersion}} D0302 23:03:23.298234 60862 transport_flipcall.go:234] recv [channel @0xc0006b2300] Rgetattr{Valid: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime Size Blocks}, QID: QID{Type: 0, Version: 0, Path: 18350334}, 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: 1583190116, NanoSec: 694364252}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}} 2020/03/02 23:03:23 executed programs: 0 I0302 23:03:23.302049 60862 loader.go:566] Received external signal 23, mode: Process D0302 23:03:23.302286 60862 task_signals.go:446] [ 1] Notified of signal 23 D0302 23:03:23.302437 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:03:23.302474 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:03:23.302802 60862 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0302 23:03:23.303007 60862 task_signals.go:220] [ 1] Signal 23: delivering to handler D0302 23:03:23.305376 60862 task_stop.go:118] [ 26] Entering internal stop (*kernel.vforkStop)(nil) D0302 23:03:23.306172 60862 task_block.go:223] [ 41] Interrupt queued D0302 23:03:23.311140 60862 transport_flipcall.go:127] send [channel @0xc0006b2300] Twalkgetattr{FID: 1, NewFID: 11, Names: [syz-executor]} D0302 23:03:23.312482 60862 transport_flipcall.go:234] recv [channel @0xc0006b2300] 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: 1583190116, NanoSec: 694364252}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}, QIDs: [QID{Type: 0, Version: 0, Path: 18350334}]} D0302 23:03:23.312649 60862 transport_flipcall.go:127] send [channel @0xc0006b2300] Tclunk{FID: 11} D0302 23:03:23.313132 60862 transport_flipcall.go:234] recv [channel @0xc0006b2300] Rclunk{} D0302 23:03:23.313379 60862 transport_flipcall.go:127] send [channel @0xc0006b2300] Tgetattr{FID: 9, AttrMask: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime INo Size Blocks BTime Gen DataVersion}} D0302 23:03:23.314408 60862 transport_flipcall.go:234] recv [channel @0xc0006b2300] Rgetattr{Valid: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime Size Blocks}, QID: QID{Type: 0, Version: 0, Path: 18350334}, 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: 1583190116, NanoSec: 694364252}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}} D0302 23:03:23.315306 60862 syscalls.go:266] [ 41] Allocating stack with size of 8388608 bytes D0302 23:03:23.316188 60862 task_stop.go:137] [ 26] Leaving internal stop (*kernel.vforkStop)(nil) D0302 23:03:23.323417 60862 parameters.go:238] Clock(Monotonic): error: 425 ns, adjusted frequency from 2299774343 Hz to 2299775578 Hz D0302 23:03:23.323732 60862 parameters.go:238] Clock(Realtime): error: -5 ns, adjusted frequency from 2299774512 Hz to 2299774677 Hz D0302 23:03:23.323914 60862 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9388015971613137 monotonicBaseRef:88000857371 monotonicFrequency:2299775578 realtimeReady:1 realtimeBaseCycles:9388015972339231 realtimeBaseRef:1583190203323721045 realtimeFrequency:2299774677} D0302 23:03:23.324994 60862 transport_flipcall.go:127] send [channel @0xc0006b2300] Twalkgetattr{FID: 1, NewFID: 11, Names: [etc]} D0302 23:03:23.325806 60862 transport_flipcall.go:234] recv [channel @0xc0006b2300] Rlerror{Error: 2} D0302 23:03:23.326002 60862 transport_flipcall.go:127] send [channel @0xc0006b2300] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.etc, Size: 1} D0302 23:03:23.326969 60862 transport_flipcall.go:234] recv [channel @0xc0006b2300] Rlerror{Error: 95} I0302 23:03:23.328443 60862 loader.go:566] Received external signal 23, mode: Process D0302 23:03:23.329124 60862 task_signals.go:446] [ 1] Notified of signal 23 D0302 23:03:23.330426 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:03:23.330411 60862 task_block.go:223] [ 1] Interrupt queued I0302 23:03:23.330608 60862 loader.go:566] Received external signal 23, mode: Process D0302 23:03:23.330657 60862 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0302 23:03:23.330756 60862 task_signals.go:446] [ 1] Notified of signal 23 D0302 23:03:23.330845 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:03:23.330813 60862 task_signals.go:220] [ 1] Signal 23: delivering to handler D0302 23:03:23.331329 60862 task_block.go:223] [ 2] Interrupt queued D0302 23:03:23.331494 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:03:23.331959 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:03:23.332105 60862 task_signals.go:220] [ 1] Signal 23: delivering to handler D0302 23:03:23.332109 60862 task_block.go:223] [ 2] Interrupt queued D0302 23:03:23.332316 60862 task_run.go:152] [ 2] Restarting syscall 202 with restart block after errno 516: not interrupted by handled signal I0302 23:03:23.342468 60862 loader.go:566] Received external signal 23, mode: Process D0302 23:03:23.342713 60862 task_signals.go:446] [ 1] Notified of signal 23 D0302 23:03:23.342846 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:03:23.342896 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:03:23.343135 60862 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0302 23:03:23.343277 60862 task_signals.go:220] [ 1] Signal 23: delivering to handler D0302 23:03:23.344107 60862 task_block.go:223] [ 42] Interrupt queued D0302 23:03:23.348870 60862 transport_flipcall.go:127] send [channel @0xc0006b2300] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0302 23:03:23.349730 60862 transport_flipcall.go:234] recv [channel @0xc0006b2300] Rlerror{Error: 2} D0302 23:03:23.349923 60862 transport_flipcall.go:127] send [channel @0xc0006b2300] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0302 23:03:23.350443 60862 transport_flipcall.go:234] recv [channel @0xc0006b2300] Rlerror{Error: 95} D0302 23:03:23.350898 60862 transport_flipcall.go:127] send [channel @0xc0006b2300] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0302 23:03:23.351485 60862 transport_flipcall.go:234] recv [channel @0xc0006b2300] Rlerror{Error: 2} D0302 23:03:23.351652 60862 transport_flipcall.go:127] send [channel @0xc0006b2300] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0302 23:03:23.352174 60862 transport_flipcall.go:234] recv [channel @0xc0006b2300] Rlerror{Error: 95} D0302 23:03:23.352786 60862 transport_flipcall.go:127] send [channel @0xc0006b2300] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0302 23:03:23.353463 60862 transport_flipcall.go:234] recv [channel @0xc0006b2300] Rlerror{Error: 2} D0302 23:03:23.353651 60862 transport_flipcall.go:127] send [channel @0xc0006b2300] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0302 23:03:23.354221 60862 transport_flipcall.go:234] recv [channel @0xc0006b2300] Rlerror{Error: 95} D0302 23:03:23.354736 60862 transport_flipcall.go:127] send [channel @0xc0006b2300] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0302 23:03:23.355271 60862 transport_flipcall.go:234] recv [channel @0xc0006b2300] Rlerror{Error: 2} D0302 23:03:23.355453 60862 transport_flipcall.go:127] send [channel @0xc0006b2300] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0302 23:03:23.355934 60862 transport_flipcall.go:234] recv [channel @0xc0006b2300] Rlerror{Error: 95} D0302 23:03:23.356266 60862 transport_flipcall.go:127] send [channel @0xc0006b2300] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0302 23:03:23.356723 60862 transport_flipcall.go:234] recv [channel @0xc0006b2300] Rlerror{Error: 2} D0302 23:03:23.356912 60862 transport_flipcall.go:127] send [channel @0xc0006b2300] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0302 23:03:23.357632 60862 transport_flipcall.go:234] recv [channel @0xc0006b2300] Rlerror{Error: 95} D0302 23:03:23.358250 60862 transport_flipcall.go:127] send [channel @0xc0006b2300] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0302 23:03:23.358769 60862 transport_flipcall.go:234] recv [channel @0xc0006b2300] Rlerror{Error: 2} D0302 23:03:23.358956 60862 transport_flipcall.go:127] send [channel @0xc0006b2300] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0302 23:03:23.359424 60862 transport_flipcall.go:234] recv [channel @0xc0006b2300] Rlerror{Error: 95} D0302 23:03:23.359840 60862 transport_flipcall.go:127] send [channel @0xc0006b2300] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0302 23:03:23.360394 60862 transport_flipcall.go:234] recv [channel @0xc0006b2300] Rlerror{Error: 2} D0302 23:03:23.360579 60862 transport_flipcall.go:127] send [channel @0xc0006b2300] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0302 23:03:23.361476 60862 transport_flipcall.go:234] recv [channel @0xc0006b2300] Rlerror{Error: 95} D0302 23:03:23.361897 60862 transport_flipcall.go:127] send [channel @0xc0006b2300] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0302 23:03:23.362369 60862 transport_flipcall.go:234] recv [channel @0xc0006b2300] Rlerror{Error: 2} D0302 23:03:23.362605 60862 transport_flipcall.go:127] send [channel @0xc0006b2300] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} I0302 23:03:23.362601 60862 loader.go:566] Received external signal 23, mode: Process D0302 23:03:23.362794 60862 task_signals.go:446] [ 1] Notified of signal 23 D0302 23:03:23.362904 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:03:23.363061 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:03:23.363116 60862 transport_flipcall.go:234] recv [channel @0xc0006b2300] Rlerror{Error: 95} D0302 23:03:23.363234 60862 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0302 23:03:23.363330 60862 task_signals.go:220] [ 1] Signal 23: delivering to handler D0302 23:03:23.365267 60862 transport_flipcall.go:127] send [channel @0xc0006b2300] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0302 23:03:23.365824 60862 transport_flipcall.go:234] recv [channel @0xc0006b2300] Rlerror{Error: 2} D0302 23:03:23.365964 60862 transport_flipcall.go:127] send [channel @0xc0006b2300] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0302 23:03:23.366440 60862 transport_flipcall.go:234] recv [channel @0xc0006b2300] Rlerror{Error: 95} I0302 23:03:23.392922 60862 loader.go:566] Received external signal 23, mode: Process D0302 23:03:23.393163 60862 task_signals.go:446] [ 1] Notified of signal 23 D0302 23:03:23.393270 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:03:23.393401 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:03:23.393712 60862 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0302 23:03:23.393866 60862 task_signals.go:220] [ 1] Signal 23: delivering to handler D0302 23:03:23.404459 60862 netfilter.go:54] netfilter: convert to binary: current offset: 0 D0302 23:03:23.404618 60862 netfilter.go:54] netfilter: convert to binary: found hook 0 at offset 0 D0302 23:03:23.404699 60862 netfilter.go:54] netfilter: convert to binary: found underflow 0 at offset 0 D0302 23:03:23.404818 60862 netfilter.go:54] netfilter: convert to binary: marshalling standard target D0302 23:03:23.404928 60862 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]} D0302 23:03:23.405527 60862 netfilter.go:54] netfilter: convert to binary: current offset: 152 D0302 23:03:23.405611 60862 netfilter.go:54] netfilter: convert to binary: found hook 1 at offset 152 D0302 23:03:23.405692 60862 netfilter.go:54] netfilter: convert to binary: found underflow 1 at offset 152 D0302 23:03:23.405766 60862 netfilter.go:54] netfilter: convert to binary: marshalling standard target D0302 23:03:23.405834 60862 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]} D0302 23:03:23.406382 60862 netfilter.go:54] netfilter: convert to binary: current offset: 304 D0302 23:03:23.406459 60862 netfilter.go:54] netfilter: convert to binary: found hook 3 at offset 304 D0302 23:03:23.406573 60862 netfilter.go:54] netfilter: convert to binary: found underflow 3 at offset 304 D0302 23:03:23.406651 60862 netfilter.go:54] netfilter: convert to binary: marshalling standard target D0302 23:03:23.406740 60862 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]} D0302 23:03:23.407271 60862 netfilter.go:54] netfilter: convert to binary: current offset: 456 D0302 23:03:23.407346 60862 netfilter.go:54] netfilter: convert to binary: found hook 4 at offset 456 D0302 23:03:23.407413 60862 netfilter.go:54] netfilter: convert to binary: found underflow 4 at offset 456 D0302 23:03:23.407518 60862 netfilter.go:54] netfilter: convert to binary: marshalling standard target D0302 23:03:23.407604 60862 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]} D0302 23:03:23.408132 60862 netfilter.go:54] netfilter: convert to binary: current offset: 608 D0302 23:03:23.408218 60862 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]} D0302 23:03:23.408753 60862 netfilter.go:54] netfilter: convert to binary: finished with an marshalled size of 0 D0302 23:03:23.408857 60862 netfilter.go:54] netfilter: convert to binary: current offset: 0 D0302 23:03:23.408942 60862 netfilter.go:54] netfilter: convert to binary: found hook 0 at offset 0 D0302 23:03:23.409051 60862 netfilter.go:54] netfilter: convert to binary: found underflow 0 at offset 0 D0302 23:03:23.409151 60862 netfilter.go:54] netfilter: convert to binary: marshalling standard target D0302 23:03:23.409232 60862 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]} D0302 23:03:23.409748 60862 netfilter.go:54] netfilter: convert to binary: current offset: 152 D0302 23:03:23.409843 60862 netfilter.go:54] netfilter: convert to binary: found hook 3 at offset 152 D0302 23:03:23.409913 60862 netfilter.go:54] netfilter: convert to binary: found underflow 3 at offset 152 D0302 23:03:23.409974 60862 netfilter.go:54] netfilter: convert to binary: marshalling standard target D0302 23:03:23.410083 60862 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]} D0302 23:03:23.410606 60862 netfilter.go:54] netfilter: convert to binary: current offset: 304 D0302 23:03:23.410719 60862 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]} D0302 23:03:23.411349 60862 netfilter.go:54] netfilter: convert to binary: finished with an marshalled size of 0 D0302 23:03:23.411467 60862 netfilter.go:54] netfilter: convert to binary: current offset: 0 D0302 23:03:23.411524 60862 netfilter.go:54] netfilter: convert to binary: found hook 1 at offset 0 D0302 23:03:23.411628 60862 netfilter.go:54] netfilter: convert to binary: found underflow 1 at offset 0 D0302 23:03:23.411718 60862 netfilter.go:54] netfilter: convert to binary: marshalling standard target D0302 23:03:23.411798 60862 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]} D0302 23:03:23.412318 60862 netfilter.go:54] netfilter: convert to binary: current offset: 152 D0302 23:03:23.412397 60862 netfilter.go:54] netfilter: convert to binary: found hook 2 at offset 152 D0302 23:03:23.412487 60862 netfilter.go:54] netfilter: convert to binary: found underflow 2 at offset 152 D0302 23:03:23.412564 60862 netfilter.go:54] netfilter: convert to binary: marshalling standard target I0302 23:03:23.413123 60862 loader.go:566] Received external signal 23, mode: Process D0302 23:03:23.412646 60862 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]} D0302 23:03:23.413313 60862 task_signals.go:446] [ 1] Notified of signal 23 D0302 23:03:23.413436 60862 netfilter.go:54] netfilter: convert to binary: current offset: 304 D0302 23:03:23.413466 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:03:23.413558 60862 netfilter.go:54] netfilter: convert to binary: found hook 3 at offset 304 D0302 23:03:23.413675 60862 netfilter.go:54] netfilter: convert to binary: found underflow 3 at offset 304 D0302 23:03:23.413757 60862 netfilter.go:54] netfilter: convert to binary: marshalling standard target D0302 23:03:23.413511 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:03:23.414027 60862 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0302 23:03:23.414180 60862 task_signals.go:220] [ 1] Signal 23: delivering to handler D0302 23:03:23.413855 60862 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]} D0302 23:03:23.414410 60862 netfilter.go:54] netfilter: convert to binary: current offset: 456 D0302 23:03:23.414528 60862 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]} D0302 23:03:23.415111 60862 netfilter.go:54] netfilter: convert to binary: finished with an marshalled size of 0 I0302 23:03:23.433341 60862 loader.go:566] Received external signal 23, mode: Process D0302 23:03:23.433524 60862 task_signals.go:446] [ 1] Notified of signal 23 D0302 23:03:23.433650 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:03:23.433819 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:03:23.433995 60862 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0302 23:03:23.434097 60862 task_signals.go:220] [ 1] Signal 23: delivering to handler I0302 23:03:23.453621 60862 loader.go:566] Received external signal 23, mode: Process D0302 23:03:23.453819 60862 task_signals.go:446] [ 1] Notified of signal 23 D0302 23:03:23.453936 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:03:23.454068 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:03:23.454331 60862 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0302 23:03:23.454482 60862 task_signals.go:220] [ 1] Signal 23: delivering to handler I0302 23:03:23.473791 60862 loader.go:566] Received external signal 23, mode: Process D0302 23:03:23.474039 60862 task_signals.go:446] [ 1] Notified of signal 23 D0302 23:03:23.474243 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:03:23.474316 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:03:23.474508 60862 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0302 23:03:23.474717 60862 task_signals.go:220] [ 1] Signal 23: delivering to handler I0302 23:03:23.494047 60862 loader.go:566] Received external signal 23, mode: Process D0302 23:03:23.494281 60862 task_signals.go:446] [ 1] Notified of signal 23 D0302 23:03:23.494443 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:03:23.494795 60862 task_block.go:223] [ 1] Interrupt queued I0302 23:03:23.495220 60862 loader.go:566] Received external signal 23, mode: Process D0302 23:03:23.495387 60862 task_signals.go:434] [ 1] Discarding duplicate signal 23 D0302 23:03:23.496033 60862 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0302 23:03:23.497237 60862 task_signals.go:220] [ 1] Signal 23: delivering to handler I0302 23:03:23.497253 60862 loader.go:566] Received external signal 23, mode: Process D0302 23:03:23.497439 60862 task_signals.go:446] [ 1] Notified of signal 23 D0302 23:03:23.497526 60862 task_block.go:223] [ 1] Interrupt queued I0302 23:03:23.497635 60862 loader.go:566] Received external signal 23, mode: Process D0302 23:03:23.497682 60862 task_block.go:223] [ 2] Interrupt queued D0302 23:03:23.497839 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:03:23.497789 60862 task_block.go:223] [ 2] Interrupt queued D0302 23:03:23.498027 60862 task_signals.go:434] [ 1] Discarding duplicate signal 23 D0302 23:03:23.498248 60862 task_signals.go:176] [ 2] Not restarting syscall 219 after errno 516: interrupted by signal 23 D0302 23:03:23.498420 60862 task_signals.go:220] [ 2] Signal 23: delivering to handler I0302 23:03:23.514235 60862 loader.go:566] Received external signal 23, mode: Process D0302 23:03:23.514424 60862 task_signals.go:446] [ 1] Notified of signal 23 D0302 23:03:23.514582 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:03:23.514713 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:03:23.514965 60862 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0302 23:03:23.515115 60862 task_signals.go:220] [ 1] Signal 23: delivering to handler I0302 23:03:23.534430 60862 loader.go:566] Received external signal 23, mode: Process D0302 23:03:23.534609 60862 task_signals.go:446] [ 1] Notified of signal 23 D0302 23:03:23.534741 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:03:23.534844 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:03:23.535056 60862 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0302 23:03:23.535164 60862 task_signals.go:220] [ 1] Signal 23: delivering to handler I0302 23:03:23.554751 60862 loader.go:566] Received external signal 23, mode: Process D0302 23:03:23.554931 60862 task_signals.go:446] [ 1] Notified of signal 23 D0302 23:03:23.555045 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:03:23.555146 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:03:23.555275 60862 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0302 23:03:23.555349 60862 task_signals.go:220] [ 1] Signal 23: delivering to handler I0302 23:03:23.565808 60862 loader.go:566] Received external signal 23, mode: Process D0302 23:03:23.566048 60862 task_signals.go:446] [ 1] Notified of signal 23 D0302 23:03:23.566287 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:03:23.566410 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:03:23.566667 60862 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0302 23:03:23.566841 60862 task_signals.go:220] [ 1] Signal 23: delivering to handler I0302 23:03:23.577387 60862 loader.go:566] Received external signal 23, mode: Process D0302 23:03:23.577601 60862 task_signals.go:446] [ 1] Notified of signal 23 D0302 23:03:23.577710 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:03:23.577838 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:03:23.578116 60862 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0302 23:03:23.578296 60862 task_signals.go:220] [ 1] Signal 23: delivering to handler I0302 23:03:23.582553 60862 loader.go:566] Received external signal 23, mode: Process D0302 23:03:23.582745 60862 task_signals.go:446] [ 1] Notified of signal 23 D0302 23:03:23.582838 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:03:23.582959 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:03:23.583107 60862 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0302 23:03:23.583228 60862 task_signals.go:220] [ 1] Signal 23: delivering to handler I0302 23:03:23.602796 60862 loader.go:566] Received external signal 23, mode: Process D0302 23:03:23.603009 60862 task_signals.go:446] [ 1] Notified of signal 23 D0302 23:03:23.603221 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:03:23.603380 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:03:23.603626 60862 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0302 23:03:23.603778 60862 task_signals.go:220] [ 1] Signal 23: delivering to handler D0302 23:03:23.619467 60862 transport_flipcall.go:127] send [channel @0xc0006b2300] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0302 23:03:23.620280 60862 transport_flipcall.go:234] recv [channel @0xc0006b2300] Rlerror{Error: 2} D0302 23:03:23.620469 60862 transport_flipcall.go:127] send [channel @0xc0006b2300] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0302 23:03:23.621047 60862 transport_flipcall.go:234] recv [channel @0xc0006b2300] Rlerror{Error: 95} D0302 23:03:23.621409 60862 transport_flipcall.go:127] send [channel @0xc0006b2300] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0302 23:03:23.622049 60862 transport_flipcall.go:234] recv [channel @0xc0006b2300] Rlerror{Error: 2} D0302 23:03:23.622232 60862 transport_flipcall.go:127] send [channel @0xc0006b2300] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0302 23:03:23.622859 60862 transport_flipcall.go:234] recv [channel @0xc0006b2300] Rlerror{Error: 95} I0302 23:03:23.623016 60862 loader.go:566] Received external signal 23, mode: Process D0302 23:03:23.623171 60862 task_signals.go:446] [ 1] Notified of signal 23 D0302 23:03:23.623275 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:03:23.623399 60862 transport_flipcall.go:127] send [channel @0xc0006b2300] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0302 23:03:23.623416 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:03:23.623634 60862 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0302 23:03:23.623794 60862 task_signals.go:220] [ 1] Signal 23: delivering to handler D0302 23:03:23.624123 60862 transport_flipcall.go:234] recv [channel @0xc0006b2300] Rlerror{Error: 2} D0302 23:03:23.624341 60862 transport_flipcall.go:127] send [channel @0xc0006b2300] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0302 23:03:23.624969 60862 transport_flipcall.go:234] recv [channel @0xc0006b2300] Rlerror{Error: 95} D0302 23:03:23.625324 60862 transport_flipcall.go:127] send [channel @0xc0006b2300] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0302 23:03:23.625875 60862 transport_flipcall.go:234] recv [channel @0xc0006b2300] Rlerror{Error: 2} D0302 23:03:23.626030 60862 transport_flipcall.go:127] send [channel @0xc0006b2300] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0302 23:03:23.626527 60862 transport_flipcall.go:234] recv [channel @0xc0006b2300] Rlerror{Error: 95} D0302 23:03:23.626825 60862 transport_flipcall.go:127] send [channel @0xc0006b2300] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0302 23:03:23.627412 60862 transport_flipcall.go:234] recv [channel @0xc0006b2300] Rlerror{Error: 2} D0302 23:03:23.627593 60862 transport_flipcall.go:127] send [channel @0xc0006b2300] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0302 23:03:23.628130 60862 transport_flipcall.go:234] recv [channel @0xc0006b2300] Rlerror{Error: 95} D0302 23:03:23.628520 60862 transport_flipcall.go:127] send [channel @0xc0006b2300] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0302 23:03:23.629114 60862 transport_flipcall.go:234] recv [channel @0xc0006b2300] Rlerror{Error: 2} D0302 23:03:23.629353 60862 transport_flipcall.go:127] send [channel @0xc0006b2300] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0302 23:03:23.629880 60862 transport_flipcall.go:234] recv [channel @0xc0006b2300] Rlerror{Error: 95} D0302 23:03:23.630207 60862 transport_flipcall.go:127] send [channel @0xc0006b2300] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0302 23:03:23.630850 60862 transport_flipcall.go:234] recv [channel @0xc0006b2300] Rlerror{Error: 2} D0302 23:03:23.631053 60862 transport_flipcall.go:127] send [channel @0xc0006b2300] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0302 23:03:23.631610 60862 transport_flipcall.go:234] recv [channel @0xc0006b2300] Rlerror{Error: 95} D0302 23:03:23.631977 60862 transport_flipcall.go:127] send [channel @0xc0006b2300] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0302 23:03:23.632474 60862 transport_flipcall.go:234] recv [channel @0xc0006b2300] Rlerror{Error: 2} D0302 23:03:23.632606 60862 transport_flipcall.go:127] send [channel @0xc0006b2300] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0302 23:03:23.633084 60862 transport_flipcall.go:234] recv [channel @0xc0006b2300] Rlerror{Error: 95} D0302 23:03:23.633453 60862 transport_flipcall.go:127] send [channel @0xc0006b2300] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0302 23:03:23.634041 60862 transport_flipcall.go:234] recv [channel @0xc0006b2300] Rlerror{Error: 2} D0302 23:03:23.634218 60862 transport_flipcall.go:127] send [channel @0xc0006b2300] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0302 23:03:23.634597 60862 transport_flipcall.go:234] recv [channel @0xc0006b2300] Rlerror{Error: 95} D0302 23:03:23.634841 60862 transport_flipcall.go:127] send [channel @0xc0006b2300] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0302 23:03:23.635299 60862 transport_flipcall.go:234] recv [channel @0xc0006b2300] Rlerror{Error: 2} D0302 23:03:23.635444 60862 transport_flipcall.go:127] send [channel @0xc0006b2300] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0302 23:03:23.635898 60862 transport_flipcall.go:234] recv [channel @0xc0006b2300] Rlerror{Error: 95} I0302 23:03:23.643122 60862 loader.go:566] Received external signal 23, mode: Process D0302 23:03:23.643351 60862 task_signals.go:446] [ 1] Notified of signal 23 D0302 23:03:23.643474 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:03:23.643600 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:03:23.643842 60862 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0302 23:03:23.644006 60862 task_signals.go:220] [ 1] Signal 23: delivering to handler D0302 23:03:23.649919 60862 task_block.go:223] [ 43] Interrupt queued D0302 23:03:23.663137 60862 task_block.go:223] [ 44] Interrupt queued D0302 23:03:23.687135 60862 task_block.go:223] [ 45] Interrupt queued D0302 23:03:23.694517 60862 task_block.go:223] [ 44] Interrupt queued D0302 23:03:23.694394 60862 task_block.go:223] [ 44] Interrupt queued D0302 23:03:23.694852 60862 task_block.go:223] [ 45] Interrupt queued D0302 23:03:23.694963 60862 task_block.go:223] [ 45] Interrupt queued D0302 23:03:23.695004 60862 task_exit.go:221] [ 43] Transitioning from exit state TaskExitNone to TaskExitInitiated D0302 23:03:23.695006 60862 task_signals.go:189] [ 44] Signal 9: terminating thread group D0302 23:03:23.695188 60862 task_signals.go:189] [ 45] Signal 9: terminating thread group I0302 23:03:23.695256 60862 compat.go:129] Uncaught signal: "killed" (9), PID: 43, TID: 44, fault addr: 0x0 D0302 23:03:23.695385 60862 task_exit.go:221] [ 43] Transitioning from exit state TaskExitInitiated to TaskExitZombie I0302 23:03:23.695459 60862 compat.go:129] Uncaught signal: "killed" (9), PID: 43, TID: 45, fault addr: 0x0 D0302 23:03:23.695621 60862 task_exit.go:221] [ 44] Transitioning from exit state TaskExitNone to TaskExitInitiated D0302 23:03:23.695923 60862 task_exit.go:221] [ 45] Transitioning from exit state TaskExitNone to TaskExitInitiated D0302 23:03:23.696277 60862 task_exit.go:221] [ 44] Transitioning from exit state TaskExitInitiated to TaskExitZombie D0302 23:03:23.696427 60862 task_exit.go:221] [ 44] Transitioning from exit state TaskExitZombie to TaskExitDead D0302 23:03:23.698015 60862 task_exit.go:221] [ 45] Transitioning from exit state TaskExitInitiated to TaskExitZombie D0302 23:03:23.698259 60862 task_exit.go:221] [ 45] Transitioning from exit state TaskExitZombie to TaskExitDead D0302 23:03:23.698509 60862 task_signals.go:419] [ 42] Discarding ignored signal 17 D0302 23:03:23.698659 60862 task_exit.go:221] [ 43] Transitioning from exit state TaskExitZombie to TaskExitDead D0302 23:03:23.702598 60862 transport_flipcall.go:127] send [channel @0xc0006b2300] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0302 23:03:23.703309 60862 transport_flipcall.go:234] recv [channel @0xc0006b2300] Rlerror{Error: 2} D0302 23:03:23.703582 60862 transport_flipcall.go:127] send [channel @0xc0006b2300] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0302 23:03:23.704084 60862 transport_flipcall.go:234] recv [channel @0xc0006b2300] Rlerror{Error: 95} D0302 23:03:23.704815 60862 transport_flipcall.go:127] send [channel @0xc0006b2300] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0302 23:03:23.705341 60862 transport_flipcall.go:234] recv [channel @0xc0006b2300] Rlerror{Error: 2} D0302 23:03:23.705535 60862 transport_flipcall.go:127] send [channel @0xc0006b2300] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0302 23:03:23.706178 60862 transport_flipcall.go:234] recv [channel @0xc0006b2300] Rlerror{Error: 95} D0302 23:03:23.706786 60862 transport_flipcall.go:127] send [channel @0xc0006b2300] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0302 23:03:23.708768 60862 transport_flipcall.go:234] recv [channel @0xc0006b2300] Rlerror{Error: 2} D0302 23:03:23.709000 60862 transport_flipcall.go:127] send [channel @0xc0006b2300] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0302 23:03:23.709651 60862 transport_flipcall.go:234] recv [channel @0xc0006b2300] Rlerror{Error: 95} I0302 23:03:23.718748 60862 loader.go:566] Received external signal 23, mode: Process D0302 23:03:23.718980 60862 task_signals.go:446] [ 1] Notified of signal 23 D0302 23:03:23.719116 60862 task_block.go:223] [ 1] Interrupt queued I0302 23:03:23.719266 60862 loader.go:566] Received external signal 23, mode: Process D0302 23:03:23.719282 60862 task_block.go:223] [ 1] Interrupt queued D0302 23:03:23.719379 60862 task_signals.go:434] [ 1] Discarding duplicate signal 23 D0302 23:03:23.719641 60862 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0302 23:03:23.719815 60862 task_signals.go:220] [ 1] Signal 23: delivering to handler D0302 23:03:23.720134 60862 task_block.go:223] [ 46] Interrupt queued D0302 23:03:23.733986 60862 task_block.go:223] [ 47] Interrupt queued D0302 23:03:23.753978 60862 task_block.go:223] [ 48] Interrupt queued ================== WARNING: DATA RACE Write at 0x00c0001c0b50 by goroutine 157: gvisor.dev/gvisor/pkg/tcpip/transport/udp.(*endpoint).Connect() pkg/tcpip/transport/udp/endpoint.go:1053 +0x46a 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 DIAGNOSIS: I0302 23:03:23.806212 2573 main.go:305] *************************** I0302 23:03:23.806355 2573 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-2] I0302 23:03:23.806516 2573 main.go:307] Version release-20200219.0-72-gce4d1e45bb88 I0302 23:03:23.806618 2573 main.go:308] PID: 2573 I0302 23:03:23.806728 2573 main.go:309] UID: 0, GID: 0 I0302 23:03:23.806825 2573 main.go:310] Configuration: I0302 23:03:23.806911 2573 main.go:311] RootDir: /syzkaller/managers/ptrace-proxy-sandbox-race/workdir/gvisor_root I0302 23:03:23.807023 2573 main.go:312] Platform: ptrace I0302 23:03:23.807093 2573 main.go:313] FileAccess: shared, overlay: false I0302 23:03:23.807186 2573 main.go:314] Network: sandbox, logging: false I0302 23:03:23.807291 2573 main.go:315] Strace: false, max size: 1024, syscalls: [] I0302 23:03:23.807383 2573 main.go:316] *************************** W0302 23:03:23.807468 2573 main.go:321] Block the TERM signal. This is only safe in tests! D0302 23:03:23.807648 2573 container.go:159] Load container "/syzkaller/managers/ptrace-proxy-sandbox-race/workdir/gvisor_root" "ci-gvisor-ptrace-proxy-sandbox-race-2" D0302 23:03:23.814091 2573 container.go:592] Signal container "ci-gvisor-ptrace-proxy-sandbox-race-2": signal 0 D0302 23:03:23.814274 2573 sandbox.go:806] Signal sandbox "ci-gvisor-ptrace-proxy-sandbox-race-2" D0302 23:03:23.814391 2573 sandbox.go:318] Connecting to sandbox "ci-gvisor-ptrace-proxy-sandbox-race-2" D0302 23:03:23.814804 2573 urpc.go:534] urpc: successfully marshalled 117 bytes. I0302 23:03:23.869045 2573 debug.go:122] Found sandbox "ci-gvisor-ptrace-proxy-sandbox-race-2", PID: 60862 I0302 23:03:23.869274 2573 debug.go:131] Retrieving sandbox stacks D0302 23:03:23.869452 2573 sandbox.go:924] Stacks sandbox "ci-gvisor-ptrace-proxy-sandbox-race-2" D0302 23:03:23.869573 2573 sandbox.go:318] Connecting to sandbox "ci-gvisor-ptrace-proxy-sandbox-race-2" W0302 23:03:23.869748 2573 error.go:48] FATAL ERROR: retrieving stacks: connecting to control server at PID 60862: connection refused retrieving stacks: connecting to control server at PID 60862: connection refused W0302 23:03:23.870129 2573 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-2"]: exit status 128 I0302 23:03:23.806212 2573 main.go:305] *************************** I0302 23:03:23.806355 2573 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-2] I0302 23:03:23.806516 2573 main.go:307] Version release-20200219.0-72-gce4d1e45bb88 I0302 23:03:23.806618 2573 main.go:308] PID: 2573 I0302 23:03:23.806728 2573 main.go:309] UID: 0, GID: 0 I0302 23:03:23.806825 2573 main.go:310] Configuration: I0302 23:03:23.806911 2573 main.go:311] RootDir: /syzkaller/managers/ptrace-proxy-sandbox-race/workdir/gvisor_root I0302 23:03:23.807023 2573 main.go:312] Platform: ptrace I0302 23:03:23.807093 2573 main.go:313] FileAccess: shared, overlay: false I0302 23:03:23.807186 2573 main.go:314] Network: sandbox, logging: false I0302 23:03:23.807291 2573 main.go:315] Strace: false, max size: 1024, syscalls: [] I0302 23:03:23.807383 2573 main.go:316] *************************** W0302 23:03:23.807468 2573 main.go:321] Block the TERM signal. This is only safe in tests! D0302 23:03:23.807648 2573 container.go:159] Load container "/syzkaller/managers/ptrace-proxy-sandbox-race/workdir/gvisor_root" "ci-gvisor-ptrace-proxy-sandbox-race-2" D0302 23:03:23.814091 2573 container.go:592] Signal container "ci-gvisor-ptrace-proxy-sandbox-race-2": signal 0 D0302 23:03:23.814274 2573 sandbox.go:806] Signal sandbox "ci-gvisor-ptrace-proxy-sandbox-race-2" D0302 23:03:23.814391 2573 sandbox.go:318] Connecting to sandbox "ci-gvisor-ptrace-proxy-sandbox-race-2" D0302 23:03:23.814804 2573 urpc.go:534] urpc: successfully marshalled 117 bytes. I0302 23:03:23.869045 2573 debug.go:122] Found sandbox "ci-gvisor-ptrace-proxy-sandbox-race-2", PID: 60862 I0302 23:03:23.869274 2573 debug.go:131] Retrieving sandbox stacks D0302 23:03:23.869452 2573 sandbox.go:924] Stacks sandbox "ci-gvisor-ptrace-proxy-sandbox-race-2" D0302 23:03:23.869573 2573 sandbox.go:318] Connecting to sandbox "ci-gvisor-ptrace-proxy-sandbox-race-2" W0302 23:03:23.869748 2573 error.go:48] FATAL ERROR: retrieving stacks: connecting to control server at PID 60862: connection refused retrieving stacks: connecting to control server at PID 60862: connection refused W0302 23:03:23.870129 2573 main.go:338] Failure to execute command, err: 1 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 read at 0x00c0001c0b50 by goroutine 368: 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 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 Goroutine 157 (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 368 (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 ================== W0302 23:03:23.861355 2425 error.go:48] FATAL ERROR: waiting on pid 22: waiting on PID 22 in sandbox "ci-gvisor-ptrace-proxy-sandbox-race-2": urpc method "containerManager.WaitPID" failed: EOF waiting on pid 22: waiting on PID 22 in sandbox "ci-gvisor-ptrace-proxy-sandbox-race-2": urpc method "containerManager.WaitPID" failed: EOF W0302 23:03:23.861912 2425 main.go:338] Failure to execute command, err: 1 W0302 23:03:23.869423 60852 sandbox.go:742] Wait RPC to container "ci-gvisor-ptrace-proxy-sandbox-race-2" failed: urpc method "containerManager.Wait" failed: EOF. Will try waiting on the sandbox process instead. D0302 23:03:23.870358 60852 container.go:714] Destroy container "ci-gvisor-ptrace-proxy-sandbox-race-2" D0302 23:03:23.870591 60852 container.go:801] Destroying container "ci-gvisor-ptrace-proxy-sandbox-race-2" D0302 23:03:23.871717 60852 sandbox.go:1058] Destroying root container "ci-gvisor-ptrace-proxy-sandbox-race-2" by destroying sandbox D0302 23:03:23.871840 60852 sandbox.go:788] Destroy sandbox "ci-gvisor-ptrace-proxy-sandbox-race-2" D0302 23:03:23.871976 60852 container.go:815] Killing gofer for container "ci-gvisor-ptrace-proxy-sandbox-race-2", PID: 60861 I0302 23:03:23.974017 60852 main.go:329] Exiting with status: 16896