D0229 22:09:42.622852 58032 parameters.go:238] Clock(Monotonic): error: 84 ns, adjusted frequency from 2299774276 Hz to 2299774632 Hz D0229 22:09:42.623149 58032 parameters.go:238] Clock(Realtime): error: 172 ns, adjusted frequency from 2299774048 Hz to 2299774661 Hz D0229 22:09:42.623296 58032 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8983208031404936 monotonicBaseRef:2000241203 monotonicFrequency:2299774632 realtimeReady:1 realtimeBaseCycles:8983208032117310 realtimeBaseRef:1583014182623144470 realtimeFrequency:2299774661} I0229 22:09:42.778640 58032 loader.go:566] Received external signal 23, mode: Process D0229 22:09:42.779053 58032 task_signals.go:446] [ 1] Notified of signal 23 D0229 22:09:42.779228 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:09:42.779290 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:09:42.779529 58032 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 22:09:42.779659 58032 task_signals.go:220] [ 1] Signal 23: delivering to handler D0229 22:09:43.623130 58032 sampler.go:168] Time: Adjusting syscall overhead up to 8000 D0229 22:09:43.623353 58032 parameters.go:238] Clock(Monotonic): error: 308 ns, adjusted frequency from 2299774632 Hz to 2299775121 Hz D0229 22:09:43.623495 58032 parameters.go:238] Clock(Realtime): error: 130 ns, adjusted frequency from 2299774661 Hz to 2299774689 Hz D0229 22:09:43.623643 58032 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8983210332347823 monotonicBaseRef:3000749189 monotonicFrequency:2299775121 realtimeReady:1 realtimeBaseCycles:8983210332682027 realtimeBaseRef:1583014183623488006 realtimeFrequency:2299774689} D0229 22:09:44.622963 58032 parameters.go:238] Clock(Monotonic): error: 142 ns, adjusted frequency from 2299775121 Hz to 2299774895 Hz D0229 22:09:44.623181 58032 parameters.go:238] Clock(Realtime): error: 119 ns, adjusted frequency from 2299774689 Hz to 2299774736 Hz D0229 22:09:44.623841 58032 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8983212631186003 monotonicBaseRef:4000341783 monotonicFrequency:2299774895 realtimeReady:1 realtimeBaseCycles:8983212631738769 realtimeBaseRef:1583014184623175824 realtimeFrequency:2299774736} D0229 22:09:45.622730 58032 parameters.go:238] Clock(Monotonic): error: 97 ns, adjusted frequency from 2299774895 Hz to 2299774788 Hz D0229 22:09:45.622912 58032 parameters.go:238] Clock(Realtime): error: 147 ns, adjusted frequency from 2299774736 Hz to 2299774847 Hz D0229 22:09:45.623027 58032 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8983214930452595 monotonicBaseRef:5000120760 monotonicFrequency:2299774788 realtimeReady:1 realtimeBaseCycles:8983214930896325 realtimeBaseRef:1583014185622907458 realtimeFrequency:2299774847} I0229 22:09:46.622991 58032 loader.go:566] Received external signal 23, mode: Process D0229 22:09:46.622935 58032 parameters.go:238] Clock(Monotonic): error: 47 ns, adjusted frequency from 2299774788 Hz to 2299774720 Hz D0229 22:09:46.623230 58032 sampler.go:168] Time: Adjusting syscall overhead up to 8000 D0229 22:09:46.623234 58032 task_signals.go:446] [ 1] Notified of signal 23 D0229 22:09:46.623333 58032 parameters.go:238] Clock(Realtime): error: 276 ns, adjusted frequency from 2299774847 Hz to 2299775137 Hz D0229 22:09:46.623443 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:09:46.623496 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:09:46.623533 58032 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8983217230691735 monotonicBaseRef:6000322671 monotonicFrequency:2299774720 realtimeReady:1 realtimeBaseCycles:8983217231634900 realtimeBaseRef:1583014186623326511 realtimeFrequency:2299775137} I0229 22:09:46.623654 58032 loader.go:566] Received external signal 23, mode: Process D0229 22:09:46.623906 58032 task_signals.go:446] [ 1] Notified of signal 23 D0229 22:09:46.623917 58032 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 22:09:46.624023 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:09:46.624083 58032 task_signals.go:220] [ 1] Signal 23: delivering to handler D0229 22:09:46.624709 58032 task_block.go:223] [ 2] Interrupt queued D0229 22:09:46.624843 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:09:46.624809 58032 task_block.go:223] [ 2] Interrupt queued D0229 22:09:46.625036 58032 task_signals.go:176] [ 2] Not restarting syscall 202 after errno 516: interrupted by signal 23 D0229 22:09:46.625161 58032 task_signals.go:220] [ 2] Signal 23: delivering to handler D0229 22:09:47.623200 58032 parameters.go:238] Clock(Monotonic): error: 283 ns, adjusted frequency from 2299774720 Hz to 2299775140 Hz D0229 22:09:47.623538 58032 parameters.go:238] Clock(Realtime): error: 392 ns, adjusted frequency from 2299775137 Hz to 2299775415 Hz I0229 22:09:47.623638 58032 loader.go:566] Received external signal 23, mode: Process D0229 22:09:47.623711 58032 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8983219531072522 monotonicBaseRef:7000586204 monotonicFrequency:2299775140 realtimeReady:1 realtimeBaseCycles:8983219531876915 realtimeBaseRef:1583014187623529521 realtimeFrequency:2299775415} D0229 22:09:47.623860 58032 task_signals.go:446] [ 1] Notified of signal 23 D0229 22:09:47.623993 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:09:47.624016 58032 task_block.go:223] [ 1] Interrupt queued I0229 22:09:47.624140 58032 loader.go:566] Received external signal 23, mode: Process D0229 22:09:47.624262 58032 task_signals.go:446] [ 1] Notified of signal 23 D0229 22:09:47.624218 58032 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 22:09:47.624367 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:09:47.624422 58032 task_signals.go:220] [ 1] Signal 23: delivering to handler D0229 22:09:47.624970 58032 task_block.go:223] [ 2] Interrupt queued D0229 22:09:47.625061 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:09:47.625095 58032 task_block.go:223] [ 2] Interrupt queued D0229 22:09:47.625249 58032 task_signals.go:176] [ 2] Not restarting syscall 202 after errno 516: interrupted by signal 23 D0229 22:09:47.625358 58032 task_signals.go:220] [ 2] Signal 23: delivering to handler I0229 22:09:48.623925 58032 loader.go:566] Received external signal 23, mode: Process D0229 22:09:48.624627 58032 task_signals.go:446] [ 1] Notified of signal 23 D0229 22:09:48.624300 58032 parameters.go:238] Clock(Monotonic): error: -175 ns, adjusted frequency from 2299775140 Hz to 2299774204 Hz D0229 22:09:48.625599 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:09:48.625646 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:09:48.625677 58032 parameters.go:238] Clock(Realtime): error: -283 ns, adjusted frequency from 2299775415 Hz to 2299773823 Hz I0229 22:09:48.625804 58032 loader.go:566] Received external signal 23, mode: Process D0229 22:09:48.625817 58032 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8983221831078306 monotonicBaseRef:8000686493 monotonicFrequency:2299774204 realtimeReady:1 realtimeBaseCycles:8983221836572215 realtimeBaseRef:1583014188625668810 realtimeFrequency:2299773823} D0229 22:09:48.625972 58032 task_signals.go:446] [ 1] Notified of signal 23 D0229 22:09:48.625914 58032 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 22:09:48.626049 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:09:48.626075 58032 task_signals.go:220] [ 1] Signal 23: delivering to handler D0229 22:09:48.626555 58032 task_block.go:223] [ 2] Interrupt queued D0229 22:09:48.626657 58032 task_block.go:223] [ 2] Interrupt queued D0229 22:09:48.626884 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:09:48.627167 58032 task_signals.go:176] [ 2] Not restarting syscall 202 after errno 516: interrupted by signal 23 D0229 22:09:48.627325 58032 task_signals.go:220] [ 2] Signal 23: delivering to handler D0229 22:09:49.622889 58032 parameters.go:238] Clock(Monotonic): error: -400 ns, adjusted frequency from 2299774204 Hz to 2299773661 Hz D0229 22:09:49.623117 58032 parameters.go:238] Clock(Realtime): error: 8 ns, adjusted frequency from 2299773823 Hz to 2299774504 Hz D0229 22:09:49.623226 58032 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8983224129911573 monotonicBaseRef:9000277349 monotonicFrequency:2299773661 realtimeReady:1 realtimeBaseCycles:8983224130465456 realtimeBaseRef:1583014189623111783 realtimeFrequency:2299774504} I0229 22:09:49.778794 58032 loader.go:566] Received external signal 23, mode: Process D0229 22:09:49.779012 58032 task_signals.go:446] [ 1] Notified of signal 23 D0229 22:09:49.779113 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:09:49.779230 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:09:49.779340 58032 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 22:09:49.779435 58032 task_signals.go:220] [ 1] Signal 23: delivering to handler D0229 22:09:50.622951 58032 parameters.go:238] Clock(Monotonic): error: 602 ns, adjusted frequency from 2299773661 Hz to 2299775922 Hz D0229 22:09:50.623212 58032 parameters.go:238] Clock(Realtime): error: 266 ns, adjusted frequency from 2299774504 Hz to 2299775197 Hz D0229 22:09:50.623368 58032 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8983226429821077 monotonicBaseRef:10000336416 monotonicFrequency:2299775922 realtimeReady:1 realtimeBaseCycles:8983226430457365 realtimeBaseRef:1583014190623206316 realtimeFrequency:2299775197} I0229 22:09:50.778921 58032 loader.go:566] Received external signal 23, mode: Process D0229 22:09:50.779205 58032 task_signals.go:446] [ 1] Notified of signal 23 D0229 22:09:50.779369 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:09:50.779495 58032 task_block.go:223] [ 1] Interrupt queued I0229 22:09:50.779609 58032 loader.go:566] Received external signal 23, mode: Process D0229 22:09:50.779762 58032 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 22:09:50.779971 58032 task_signals.go:220] [ 1] Signal 23: delivering to handler D0229 22:09:50.779794 58032 task_signals.go:446] [ 1] Notified of signal 23 D0229 22:09:50.780147 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:09:50.780453 58032 task_block.go:223] [ 2] Interrupt queued D0229 22:09:50.780498 58032 task_block.go:223] [ 2] Interrupt queued D0229 22:09:50.780592 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:09:50.780906 58032 task_signals.go:176] [ 2] Not restarting syscall 202 after errno 516: interrupted by signal 23 D0229 22:09:50.781097 58032 task_signals.go:220] [ 2] Signal 23: delivering to handler D0229 22:09:51.622894 58032 parameters.go:238] Clock(Monotonic): error: -483 ns, adjusted frequency from 2299775922 Hz to 2299773516 Hz D0229 22:09:51.623123 58032 parameters.go:238] Clock(Realtime): error: -167 ns, adjusted frequency from 2299775197 Hz to 2299774204 Hz D0229 22:09:51.623236 58032 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8983228729473876 monotonicBaseRef:11000282879 monotonicFrequency:2299773516 realtimeReady:1 realtimeBaseCycles:8983228730016298 realtimeBaseRef:1583014191623112278 realtimeFrequency:2299774204} I0229 22:09:51.779164 58032 loader.go:566] Received external signal 23, mode: Process D0229 22:09:51.779474 58032 task_signals.go:446] [ 1] Notified of signal 23 D0229 22:09:51.779664 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:09:51.779784 58032 task_block.go:223] [ 1] Interrupt queued I0229 22:09:51.779831 58032 loader.go:566] Received external signal 23, mode: Process D0229 22:09:51.779985 58032 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 22:09:51.779997 58032 task_signals.go:446] [ 1] Notified of signal 23 D0229 22:09:51.780115 58032 task_signals.go:220] [ 1] Signal 23: delivering to handler D0229 22:09:51.780139 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:09:51.780501 58032 task_block.go:223] [ 2] Interrupt queued D0229 22:09:51.780618 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:09:51.780664 58032 task_block.go:223] [ 2] Interrupt queued D0229 22:09:51.780933 58032 task_signals.go:176] [ 2] Not restarting syscall 202 after errno 516: interrupted by signal 23 D0229 22:09:51.781074 58032 task_signals.go:220] [ 2] Signal 23: delivering to handler D0229 22:09:52.622860 58032 parameters.go:238] Clock(Monotonic): error: -79 ns, adjusted frequency from 2299773516 Hz to 2299774469 Hz D0229 22:09:52.623088 58032 parameters.go:238] Clock(Realtime): error: 166 ns, adjusted frequency from 2299774204 Hz to 2299774969 Hz D0229 22:09:52.623243 58032 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8983231029166784 monotonicBaseRef:12000247828 monotonicFrequency:2299774469 realtimeReady:1 realtimeBaseCycles:8983231029718397 realtimeBaseRef:1583014192623080924 realtimeFrequency:2299774969} D0229 22:09:53.622850 58032 parameters.go:238] Clock(Monotonic): error: 310 ns, adjusted frequency from 2299774469 Hz to 2299775341 Hz D0229 22:09:53.623065 58032 parameters.go:238] Clock(Realtime): error: -12 ns, adjusted frequency from 2299774969 Hz to 2299774541 Hz D0229 22:09:53.623232 58032 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8983233328919425 monotonicBaseRef:13000238336 monotonicFrequency:2299775341 realtimeReady:1 realtimeBaseCycles:8983233329442923 realtimeBaseRef:1583014193623058990 realtimeFrequency:2299774541} I0229 22:09:53.778869 58032 loader.go:566] Received external signal 23, mode: Process D0229 22:09:53.779129 58032 task_signals.go:446] [ 1] Notified of signal 23 D0229 22:09:53.779357 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:09:53.779449 58032 task_block.go:223] [ 1] Interrupt queued I0229 22:09:53.779568 58032 loader.go:566] Received external signal 23, mode: Process D0229 22:09:53.779721 58032 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 22:09:53.779791 58032 task_signals.go:446] [ 1] Notified of signal 23 D0229 22:09:53.779920 58032 task_signals.go:220] [ 1] Signal 23: delivering to handler D0229 22:09:53.779994 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:09:53.780418 58032 task_block.go:223] [ 2] Interrupt queued D0229 22:09:53.780540 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:09:53.780540 58032 task_block.go:223] [ 2] Interrupt queued D0229 22:09:53.780750 58032 task_signals.go:176] [ 2] Not restarting syscall 202 after errno 516: interrupted by signal 23 D0229 22:09:53.780850 58032 task_signals.go:220] [ 2] Signal 23: delivering to handler D0229 22:09:54.622897 58032 parameters.go:238] Clock(Monotonic): error: -222 ns, adjusted frequency from 2299775341 Hz to 2299774108 Hz D0229 22:09:54.623081 58032 parameters.go:238] Clock(Realtime): error: -69 ns, adjusted frequency from 2299774541 Hz to 2299774399 Hz I0229 22:09:54.623099 58032 loader.go:566] Received external signal 23, mode: Process D0229 22:09:54.623188 58032 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8983235628805485 monotonicBaseRef:14000286479 monotonicFrequency:2299774108 realtimeReady:1 realtimeBaseCycles:8983235629256071 realtimeBaseRef:1583014194623075777 realtimeFrequency:2299774399} D0229 22:09:54.623350 58032 task_signals.go:446] [ 1] Notified of signal 23 D0229 22:09:54.623514 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:09:54.623560 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:09:54.623765 58032 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 22:09:54.623903 58032 task_signals.go:220] [ 1] Signal 23: delivering to handler D0229 22:09:55.622902 58032 parameters.go:238] Clock(Monotonic): error: 211 ns, adjusted frequency from 2299774108 Hz to 2299774900 Hz I0229 22:09:55.623037 58032 loader.go:566] Received external signal 23, mode: Process D0229 22:09:55.623074 58032 parameters.go:238] Clock(Realtime): error: 235 ns, adjusted frequency from 2299774399 Hz to 2299775155 Hz D0229 22:09:55.623201 58032 task_signals.go:446] [ 1] Notified of signal 23 D0229 22:09:55.623371 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:09:55.623276 58032 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8983237928584270 monotonicBaseRef:15000288512 monotonicFrequency:2299774900 realtimeReady:1 realtimeBaseCycles:8983237929013401 realtimeBaseRef:1583014195623068354 realtimeFrequency:2299775155} D0229 22:09:55.623494 58032 task_block.go:223] [ 1] Interrupt queued I0229 22:09:55.623523 58032 loader.go:566] Received external signal 23, mode: Process D0229 22:09:55.623714 58032 task_signals.go:434] [ 1] Discarding duplicate signal 23 D0229 22:09:55.623898 58032 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 22:09:55.624069 58032 task_signals.go:220] [ 1] Signal 23: delivering to handler I0229 22:09:55.779075 58032 loader.go:566] Received external signal 23, mode: Process D0229 22:09:55.779279 58032 task_signals.go:446] [ 1] Notified of signal 23 D0229 22:09:55.779403 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:09:55.779468 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:09:55.779853 58032 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 22:09:55.780015 58032 task_signals.go:220] [ 1] Signal 23: delivering to handler D0229 22:09:56.622895 58032 parameters.go:238] Clock(Monotonic): error: 96 ns, adjusted frequency from 2299774900 Hz to 2299774924 Hz D0229 22:09:56.623203 58032 parameters.go:238] Clock(Realtime): error: 139 ns, adjusted frequency from 2299775155 Hz to 2299774914 Hz I0229 22:09:56.623328 58032 loader.go:566] Received external signal 23, mode: Process D0229 22:09:56.623349 58032 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8983240228282509 monotonicBaseRef:16000255177 monotonicFrequency:2299774924 realtimeReady:1 realtimeBaseCycles:8983240228994423 realtimeBaseRef:1583014196623157870 realtimeFrequency:2299774914} D0229 22:09:56.623644 58032 task_signals.go:446] [ 1] Notified of signal 23 D0229 22:09:56.623829 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:09:56.623865 58032 task_block.go:223] [ 1] Interrupt queued I0229 22:09:56.624006 58032 loader.go:566] Received external signal 23, mode: Process D0229 22:09:56.624117 58032 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 22:09:56.624192 58032 task_signals.go:446] [ 1] Notified of signal 23 D0229 22:09:56.624327 58032 task_signals.go:220] [ 1] Signal 23: delivering to handler D0229 22:09:56.624364 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:09:56.624858 58032 task_block.go:223] [ 2] Interrupt queued D0229 22:09:56.624999 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:09:56.624987 58032 task_block.go:223] [ 2] Interrupt queued D0229 22:09:56.625222 58032 task_signals.go:176] [ 2] Not restarting syscall 202 after errno 516: interrupted by signal 23 D0229 22:09:56.625367 58032 task_signals.go:220] [ 2] Signal 23: delivering to handler D0229 22:09:57.622942 58032 parameters.go:238] Clock(Monotonic): error: -136 ns, adjusted frequency from 2299774924 Hz to 2299774104 Hz I0229 22:09:57.623091 58032 loader.go:566] Received external signal 23, mode: Process D0229 22:09:57.623194 58032 parameters.go:238] Clock(Realtime): error: -306 ns, adjusted frequency from 2299774914 Hz to 2299773915 Hz D0229 22:09:57.623349 58032 task_signals.go:446] [ 1] Notified of signal 23 D0229 22:09:57.623481 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:09:57.623328 58032 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8983242528227651 monotonicBaseRef:17000329192 monotonicFrequency:2299774104 realtimeReady:1 realtimeBaseCycles:8983242528836431 realtimeBaseRef:1583014197623187044 realtimeFrequency:2299773915} D0229 22:09:57.623670 58032 task_block.go:223] [ 1] Interrupt queued I0229 22:09:57.623685 58032 loader.go:566] Received external signal 23, mode: Process D0229 22:09:57.623957 58032 task_signals.go:446] [ 1] Notified of signal 23 D0229 22:09:57.624050 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:09:57.623939 58032 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 22:09:57.624271 58032 task_signals.go:220] [ 1] Signal 23: delivering to handler D0229 22:09:57.624905 58032 task_block.go:223] [ 2] Interrupt queued D0229 22:09:57.624982 58032 task_block.go:223] [ 2] Interrupt queued D0229 22:09:57.625066 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:09:57.625327 58032 task_signals.go:176] [ 2] Not restarting syscall 202 after errno 516: interrupted by signal 23 D0229 22:09:57.625453 58032 task_signals.go:220] [ 2] Signal 23: delivering to handler D0229 22:09:58.623482 58032 parameters.go:238] Clock(Monotonic): error: 254 ns, adjusted frequency from 2299774104 Hz to 2299775056 Hz D0229 22:09:58.623706 58032 parameters.go:238] Clock(Realtime): error: -87 ns, adjusted frequency from 2299773915 Hz to 2299774500 Hz D0229 22:09:58.623826 58032 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8983244829250434 monotonicBaseRef:18000872149 monotonicFrequency:2299775056 realtimeReady:1 realtimeBaseCycles:8983244829789067 realtimeBaseRef:1583014198623699581 realtimeFrequency:2299774500} D0229 22:09:59.622853 58032 parameters.go:238] Clock(Monotonic): error: 80 ns, adjusted frequency from 2299775056 Hz to 2299774736 Hz D0229 22:09:59.623046 58032 parameters.go:238] Clock(Realtime): error: -21 ns, adjusted frequency from 2299774500 Hz to 2299774556 Hz D0229 22:09:59.623147 58032 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8983247127581747 monotonicBaseRef:19000244373 monotonicFrequency:2299774736 realtimeReady:1 realtimeBaseCycles:8983247128048487 realtimeBaseRef:1583014199623040785 realtimeFrequency:2299774556} I0229 22:09:59.778984 58032 loader.go:566] Received external signal 23, mode: Process D0229 22:09:59.779267 58032 task_signals.go:446] [ 1] Notified of signal 23 D0229 22:09:59.779434 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:09:59.779505 58032 task_block.go:223] [ 1] Interrupt queued I0229 22:09:59.779684 58032 loader.go:566] Received external signal 23, mode: Process D0229 22:09:59.779859 58032 task_signals.go:446] [ 1] Notified of signal 23 D0229 22:09:59.779989 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:09:59.779856 58032 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 22:09:59.780086 58032 task_signals.go:220] [ 1] Signal 23: delivering to handler D0229 22:09:59.780782 58032 task_block.go:223] [ 2] Interrupt queued D0229 22:09:59.780900 58032 task_block.go:223] [ 2] Interrupt queued D0229 22:09:59.780948 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:09:59.781137 58032 task_signals.go:176] [ 2] Not restarting syscall 202 after errno 516: interrupted by signal 23 D0229 22:09:59.781328 58032 task_signals.go:220] [ 2] Signal 23: delivering to handler D0229 22:10:00.622862 58032 parameters.go:238] Clock(Monotonic): error: -139 ns, adjusted frequency from 2299774736 Hz to 2299774311 Hz D0229 22:10:00.623144 58032 parameters.go:238] Clock(Realtime): error: 293 ns, adjusted frequency from 2299774556 Hz to 2299775293 Hz D0229 22:10:00.623267 58032 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8983249427342817 monotonicBaseRef:20000238430 monotonicFrequency:2299774311 realtimeReady:1 realtimeBaseCycles:8983249428044170 realtimeBaseRef:1583014200623136936 realtimeFrequency:2299775293} I0229 22:10:00.779366 58032 loader.go:566] Received external signal 23, mode: Process D0229 22:10:00.779683 58032 task_signals.go:446] [ 1] Notified of signal 23 D0229 22:10:00.779895 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:10:00.780025 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:10:00.780152 58032 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 22:10:00.780246 58032 task_signals.go:220] [ 1] Signal 23: delivering to handler D0229 22:10:01.623931 58032 parameters.go:238] Clock(Monotonic): error: -17 ns, adjusted frequency from 2299774311 Hz to 2299774589 Hz D0229 22:10:01.624193 58032 parameters.go:238] Clock(Realtime): error: 93 ns, adjusted frequency from 2299775293 Hz to 2299774831 Hz D0229 22:10:01.624374 58032 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8983251729596571 monotonicBaseRef:21001316554 monotonicFrequency:2299774589 realtimeReady:1 realtimeBaseCycles:8983251730231177 realtimeBaseRef:1583014201624185609 realtimeFrequency:2299774831} I0229 22:10:01.778864 58032 loader.go:566] Received external signal 23, mode: Process D0229 22:10:01.779147 58032 task_signals.go:446] [ 1] Notified of signal 23 D0229 22:10:01.779323 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:10:01.779343 58032 task_block.go:223] [ 1] Interrupt queued I0229 22:10:01.779449 58032 loader.go:566] Received external signal 23, mode: Process D0229 22:10:01.779588 58032 task_signals.go:434] [ 1] Discarding duplicate signal 23 D0229 22:10:01.779720 58032 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 22:10:01.779944 58032 task_signals.go:220] [ 1] Signal 23: delivering to handler D0229 22:10:02.623212 58032 parameters.go:238] Clock(Monotonic): error: 165 ns, adjusted frequency from 2299774589 Hz to 2299774838 Hz D0229 22:10:02.623424 58032 parameters.go:238] Clock(Realtime): error: -438 ns, adjusted frequency from 2299774831 Hz to 2299773636 Hz I0229 22:10:02.623466 58032 loader.go:566] Received external signal 23, mode: Process D0229 22:10:02.623569 58032 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8983254027722786 monotonicBaseRef:22000599799 monotonicFrequency:2299774838 realtimeReady:1 realtimeBaseCycles:8983254028243018 realtimeBaseRef:1583014202623419016 realtimeFrequency:2299773636} D0229 22:10:02.623724 58032 task_signals.go:446] [ 1] Notified of signal 23 D0229 22:10:02.623947 58032 task_block.go:223] [ 1] Interrupt queued I0229 22:10:02.624150 58032 loader.go:566] Received external signal 23, mode: Process D0229 22:10:02.624117 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:10:02.624335 58032 task_signals.go:434] [ 1] Discarding duplicate signal 23 D0229 22:10:02.624569 58032 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 22:10:02.624750 58032 task_signals.go:220] [ 1] Signal 23: delivering to handler D0229 22:10:03.623443 58032 parameters.go:238] Clock(Monotonic): error: -235 ns, adjusted frequency from 2299774838 Hz to 2299774076 Hz D0229 22:10:03.623647 58032 parameters.go:238] Clock(Realtime): error: 145 ns, adjusted frequency from 2299773636 Hz to 2299774961 Hz D0229 22:10:03.623766 58032 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8983256328025822 monotonicBaseRef:23000829472 monotonicFrequency:2299774076 realtimeReady:1 realtimeBaseCycles:8983256328529931 realtimeBaseRef:1583014203623642201 realtimeFrequency:2299774961} I0229 22:10:03.779090 58032 loader.go:566] Received external signal 23, mode: Process D0229 22:10:03.779359 58032 task_signals.go:446] [ 1] Notified of signal 23 D0229 22:10:03.779491 58032 task_block.go:223] [ 1] Interrupt queued I0229 22:10:03.779647 58032 loader.go:566] Received external signal 23, mode: Process D0229 22:10:03.779620 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:10:03.779786 58032 task_signals.go:434] [ 1] Discarding duplicate signal 23 D0229 22:10:03.779957 58032 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 22:10:03.780124 58032 task_signals.go:220] [ 1] Signal 23: delivering to handler D0229 22:10:04.624966 58032 parameters.go:238] Clock(Monotonic): error: 23 ns, adjusted frequency from 2299774076 Hz to 2299774854 Hz D0229 22:10:04.625257 58032 parameters.go:238] Clock(Realtime): error: -7 ns, adjusted frequency from 2299774961 Hz to 2299774590 Hz I0229 22:10:04.625284 58032 loader.go:566] Received external signal 23, mode: Process D0229 22:10:04.625446 58032 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8983258631290265 monotonicBaseRef:24002347171 monotonicFrequency:2299774854 realtimeReady:1 realtimeBaseCycles:8983258632004731 realtimeBaseRef:1583014204625250984 realtimeFrequency:2299774590} D0229 22:10:04.625612 58032 task_signals.go:446] [ 1] Notified of signal 23 D0229 22:10:04.625778 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:10:04.625813 58032 task_block.go:223] [ 1] Interrupt queued I0229 22:10:04.625996 58032 loader.go:566] Received external signal 23, mode: Process D0229 22:10:04.626184 58032 task_signals.go:446] [ 1] Notified of signal 23 D0229 22:10:04.626297 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:10:04.626127 58032 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 22:10:04.626460 58032 task_signals.go:220] [ 1] Signal 23: delivering to handler D0229 22:10:04.626968 58032 task_block.go:223] [ 2] Interrupt queued D0229 22:10:04.627074 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:10:04.627103 58032 task_block.go:223] [ 2] Interrupt queued D0229 22:10:04.627309 58032 task_signals.go:176] [ 2] Not restarting syscall 202 after errno 516: interrupted by signal 23 D0229 22:10:04.627456 58032 task_signals.go:220] [ 2] Signal 23: delivering to handler D0229 22:10:05.622911 58032 parameters.go:238] Clock(Monotonic): error: 406 ns, adjusted frequency from 2299774854 Hz to 2299775460 Hz I0229 22:10:05.623025 58032 loader.go:566] Received external signal 23, mode: Process D0229 22:10:05.623138 58032 parameters.go:238] Clock(Realtime): error: 844 ns, adjusted frequency from 2299774590 Hz to 2299776660 Hz D0229 22:10:05.623269 58032 task_signals.go:446] [ 1] Notified of signal 23 D0229 22:10:05.623314 58032 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8983260926282977 monotonicBaseRef:25000267775 monotonicFrequency:2299775460 realtimeReady:1 realtimeBaseCycles:8983260926901097 realtimeBaseRef:1583014205623129809 realtimeFrequency:2299776660} D0229 22:10:05.623420 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:10:05.623477 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:10:05.623734 58032 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 22:10:05.623863 58032 task_signals.go:220] [ 1] Signal 23: delivering to handler D0229 22:10:06.622908 58032 parameters.go:238] Clock(Monotonic): error: -179 ns, adjusted frequency from 2299775460 Hz to 2299774398 Hz D0229 22:10:06.623138 58032 parameters.go:238] Clock(Realtime): error: -805 ns, adjusted frequency from 2299776660 Hz to 2299772786 Hz D0229 22:10:06.623266 58032 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8983263226076586 monotonicBaseRef:26000275666 monotonicFrequency:2299774398 realtimeReady:1 realtimeBaseCycles:8983263226678930 realtimeBaseRef:1583014206623130319 realtimeFrequency:2299772786} I0229 22:10:06.779211 58032 loader.go:566] Received external signal 23, mode: Process D0229 22:10:06.779470 58032 task_signals.go:446] [ 1] Notified of signal 23 D0229 22:10:06.779670 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:10:06.779833 58032 task_block.go:223] [ 1] Interrupt queued I0229 22:10:06.780082 58032 loader.go:566] Received external signal 23, mode: Process D0229 22:10:06.780136 58032 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 22:10:06.780328 58032 task_signals.go:446] [ 1] Notified of signal 23 D0229 22:10:06.780425 58032 task_signals.go:220] [ 1] Signal 23: delivering to handler D0229 22:10:06.780440 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:10:06.780995 58032 task_block.go:223] [ 2] Interrupt queued D0229 22:10:06.781110 58032 task_block.go:223] [ 2] Interrupt queued D0229 22:10:06.781157 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:10:06.781409 58032 task_signals.go:176] [ 2] Not restarting syscall 202 after errno 516: interrupted by signal 23 D0229 22:10:06.781630 58032 task_signals.go:220] [ 2] Signal 23: delivering to handler D0229 22:10:07.623339 58032 parameters.go:238] Clock(Monotonic): error: -275 ns, adjusted frequency from 2299774398 Hz to 2299774144 Hz D0229 22:10:07.623626 58032 parameters.go:238] Clock(Realtime): error: -61 ns, adjusted frequency from 2299772786 Hz to 2299774398 Hz D0229 22:10:07.623788 58032 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8983265526888358 monotonicBaseRef:27000726742 monotonicFrequency:2299774144 realtimeReady:1 realtimeBaseCycles:8983265527566981 realtimeBaseRef:1583014207623615264 realtimeFrequency:2299774398} I0229 22:10:07.623770 58032 loader.go:566] Received external signal 23, mode: Process D0229 22:10:07.624055 58032 task_signals.go:446] [ 1] Notified of signal 23 D0229 22:10:07.624216 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:10:07.624320 58032 task_block.go:223] [ 1] Interrupt queued I0229 22:10:07.624387 58032 loader.go:566] Received external signal 23, mode: Process D0229 22:10:07.624565 58032 task_signals.go:434] [ 1] Discarding duplicate signal 23 D0229 22:10:07.624756 58032 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 22:10:07.624905 58032 task_signals.go:220] [ 1] Signal 23: delivering to handler D0229 22:10:08.622870 58032 parameters.go:238] Clock(Monotonic): error: -5 ns, adjusted frequency from 2299774144 Hz to 2299774776 Hz D0229 22:10:08.623099 58032 parameters.go:238] Clock(Realtime): error: 210 ns, adjusted frequency from 2299774398 Hz to 2299775112 Hz D0229 22:10:08.623248 58032 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8983267825586615 monotonicBaseRef:28000258919 monotonicFrequency:2299774776 realtimeReady:1 realtimeBaseCycles:8983267826138947 realtimeBaseRef:1583014208623092416 realtimeFrequency:2299775112} D0229 22:10:09.622920 58032 parameters.go:238] Clock(Monotonic): error: 63 ns, adjusted frequency from 2299774776 Hz to 2299774786 Hz D0229 22:10:09.623186 58032 parameters.go:238] Clock(Realtime): error: -155 ns, adjusted frequency from 2299775112 Hz to 2299774280 Hz I0229 22:10:09.623144 58032 loader.go:566] Received external signal 23, mode: Process D0229 22:10:09.623368 58032 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8983270125470035 monotonicBaseRef:29000306160 monotonicFrequency:2299774786 realtimeReady:1 realtimeBaseCycles:8983270126113942 realtimeBaseRef:1583014209623179330 realtimeFrequency:2299774280} D0229 22:10:09.623441 58032 task_signals.go:446] [ 1] Notified of signal 23 D0229 22:10:09.623612 58032 task_block.go:223] [ 1] Interrupt queued I0229 22:10:09.623834 58032 loader.go:566] Received external signal 23, mode: Process D0229 22:10:09.623729 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:10:09.624093 58032 task_signals.go:434] [ 1] Discarding duplicate signal 23 D0229 22:10:09.624377 58032 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 22:10:09.624660 58032 task_signals.go:220] [ 1] Signal 23: delivering to handler I0229 22:10:09.779032 58032 loader.go:566] Received external signal 23, mode: Process D0229 22:10:09.779290 58032 task_signals.go:446] [ 1] Notified of signal 23 D0229 22:10:09.779450 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:10:09.779498 58032 task_block.go:223] [ 1] Interrupt queued I0229 22:10:09.779681 58032 loader.go:566] Received external signal 23, mode: Process D0229 22:10:09.779698 58032 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 22:10:09.779809 58032 task_signals.go:446] [ 1] Notified of signal 23 D0229 22:10:09.779890 58032 task_signals.go:220] [ 1] Signal 23: delivering to handler D0229 22:10:09.779968 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:10:09.780422 58032 task_block.go:223] [ 2] Interrupt queued D0229 22:10:09.780558 58032 task_block.go:223] [ 2] Interrupt queued D0229 22:10:09.780569 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:10:09.780819 58032 task_signals.go:176] [ 2] Not restarting syscall 202 after errno 516: interrupted by signal 23 D0229 22:10:09.780943 58032 task_signals.go:220] [ 2] Signal 23: delivering to handler D0229 22:10:10.623003 58032 parameters.go:238] Clock(Monotonic): error: -152 ns, adjusted frequency from 2299774786 Hz to 2299774272 Hz I0229 22:10:10.623133 58032 loader.go:566] Received external signal 23, mode: Process D0229 22:10:10.623206 58032 parameters.go:238] Clock(Realtime): error: -44 ns, adjusted frequency from 2299774280 Hz to 2299774539 Hz D0229 22:10:10.623366 58032 task_signals.go:446] [ 1] Notified of signal 23 D0229 22:10:10.623377 58032 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8983272425440620 monotonicBaseRef:30000391298 monotonicFrequency:2299774272 realtimeReady:1 realtimeBaseCycles:8983272425933667 realtimeBaseRef:1583014210623199090 realtimeFrequency:2299774539} D0229 22:10:10.623528 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:10:10.623573 58032 task_block.go:223] [ 1] Interrupt queued I0229 22:10:10.623753 58032 loader.go:566] Received external signal 23, mode: Process D0229 22:10:10.623834 58032 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 22:10:10.623980 58032 task_signals.go:446] [ 1] Notified of signal 23 D0229 22:10:10.623981 58032 task_signals.go:220] [ 1] Signal 23: delivering to handler D0229 22:10:10.624098 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:10:10.624403 58032 task_block.go:223] [ 2] Interrupt queued D0229 22:10:10.624528 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:10:10.624541 58032 task_block.go:223] [ 2] Interrupt queued D0229 22:10:10.624761 58032 task_signals.go:176] [ 2] Not restarting syscall 202 after errno 516: interrupted by signal 23 D0229 22:10:10.624905 58032 task_signals.go:220] [ 2] Signal 23: delivering to handler I0229 22:10:10.780062 58032 loader.go:566] Received external signal 23, mode: Process D0229 22:10:10.780247 58032 task_signals.go:446] [ 1] Notified of signal 23 D0229 22:10:10.780355 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:10:10.780426 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:10:10.780702 58032 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 22:10:10.780943 58032 task_signals.go:220] [ 1] Signal 23: delivering to handler D0229 22:10:11.622876 58032 parameters.go:238] Clock(Monotonic): error: 71 ns, adjusted frequency from 2299774272 Hz to 2299774830 Hz I0229 22:10:11.622999 58032 loader.go:566] Received external signal 23, mode: Process D0229 22:10:11.623105 58032 parameters.go:238] Clock(Realtime): error: 137 ns, adjusted frequency from 2299774539 Hz to 2299774947 Hz D0229 22:10:11.623230 58032 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8983274724927159 monotonicBaseRef:31000266184 monotonicFrequency:2299774830 realtimeReady:1 realtimeBaseCycles:8983274725475821 realtimeBaseRef:1583014211623098043 realtimeFrequency:2299774947} D0229 22:10:11.623281 58032 task_signals.go:446] [ 1] Notified of signal 23 D0229 22:10:11.623490 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:10:11.623448 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:10:11.624580 58032 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 22:10:11.624745 58032 task_signals.go:220] [ 1] Signal 23: delivering to handler I0229 22:10:11.780054 58032 loader.go:566] Received external signal 23, mode: Process D0229 22:10:11.780299 58032 task_signals.go:446] [ 1] Notified of signal 23 D0229 22:10:11.780439 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:10:11.780536 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:10:11.780726 58032 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 22:10:11.780836 58032 task_signals.go:220] [ 1] Signal 23: delivering to handler I0229 22:10:11.800988 58032 loader.go:566] Received external signal 23, mode: Process D0229 22:10:11.801315 58032 task_signals.go:446] [ 1] Notified of signal 23 D0229 22:10:11.801505 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:10:11.801550 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:10:11.801759 58032 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 22:10:11.801879 58032 task_signals.go:220] [ 1] Signal 23: delivering to handler D0229 22:10:12.622886 58032 parameters.go:238] Clock(Monotonic): error: 13 ns, adjusted frequency from 2299774830 Hz to 2299774678 Hz D0229 22:10:12.623116 58032 parameters.go:238] Clock(Realtime): error: -196 ns, adjusted frequency from 2299774947 Hz to 2299774250 Hz D0229 22:10:12.623233 58032 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8983277024729277 monotonicBaseRef:32000278049 monotonicFrequency:2299774678 realtimeReady:1 realtimeBaseCycles:8983277025277058 realtimeBaseRef:1583014212623109474 realtimeFrequency:2299774250} I0229 22:10:12.779086 58032 loader.go:566] Received external signal 23, mode: Process D0229 22:10:12.779332 58032 task_signals.go:446] [ 1] Notified of signal 23 D0229 22:10:12.779458 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:10:12.779480 58032 task_block.go:223] [ 1] Interrupt queued I0229 22:10:12.779614 58032 loader.go:566] Received external signal 23, mode: Process D0229 22:10:12.779776 58032 task_signals.go:446] [ 1] Notified of signal 23 D0229 22:10:12.779711 58032 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 22:10:12.779859 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:10:12.779891 58032 task_signals.go:220] [ 1] Signal 23: delivering to handler D0229 22:10:12.780481 58032 task_block.go:223] [ 2] Interrupt queued D0229 22:10:12.780527 58032 task_block.go:223] [ 2] Interrupt queued D0229 22:10:12.780627 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:10:12.780822 58032 task_signals.go:176] [ 2] Not restarting syscall 202 after errno 516: interrupted by signal 23 D0229 22:10:12.780967 58032 task_signals.go:220] [ 2] Signal 23: delivering to handler I0229 22:10:12.781758 58032 loader.go:566] Received external signal 23, mode: Process D0229 22:10:12.782746 58032 task_signals.go:446] [ 1] Notified of signal 23 D0229 22:10:12.783972 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:10:12.784071 58032 task_block.go:223] [ 1] Interrupt queued I0229 22:10:12.784233 58032 loader.go:566] Received external signal 23, mode: Process D0229 22:10:12.784348 58032 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 22:10:12.784426 58032 task_signals.go:446] [ 1] Notified of signal 23 D0229 22:10:12.784499 58032 task_signals.go:220] [ 1] Signal 23: delivering to handler D0229 22:10:12.784583 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:10:12.784904 58032 task_block.go:223] [ 2] Interrupt queued D0229 22:10:12.785079 58032 task_signals.go:220] [ 2] Signal 23: delivering to handler D0229 22:10:12.887605 58032 urpc.go:577] urpc: unmarshal success. D0229 22:10:12.887870 58032 controller.go:501] containerManager.Signal &{CID:ci-gvisor-ptrace-proxy-sandbox-race-2 Signo:0 PID:0 Mode:Process} D0229 22:10:12.888219 58032 urpc.go:534] urpc: successfully marshalled 37 bytes. D0229 22:10:13.623112 58032 parameters.go:238] Clock(Monotonic): error: 294 ns, adjusted frequency from 2299774678 Hz to 2299775230 Hz D0229 22:10:13.623375 58032 parameters.go:238] Clock(Realtime): error: 115 ns, adjusted frequency from 2299774250 Hz to 2299774842 Hz D0229 22:10:13.623540 58032 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8983279325001826 monotonicBaseRef:33000494535 monotonicFrequency:2299775230 realtimeReady:1 realtimeBaseCycles:8983279325644222 realtimeBaseRef:1583014213623367288 realtimeFrequency:2299774842} D0229 22:10:14.622872 58032 parameters.go:238] Clock(Monotonic): error: -323 ns, adjusted frequency from 2299775230 Hz to 2299773965 Hz D0229 22:10:14.623075 58032 parameters.go:238] Clock(Realtime): error: 126 ns, adjusted frequency from 2299774842 Hz to 2299774832 Hz D0229 22:10:14.623181 58032 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8983281624172543 monotonicBaseRef:34000231677 monotonicFrequency:2299773965 realtimeReady:1 realtimeBaseCycles:8983281624709209 realtimeBaseRef:1583014214623058625 realtimeFrequency:2299774832} I0229 22:10:14.623269 58032 loader.go:566] Received external signal 23, mode: Process D0229 22:10:14.623534 58032 task_signals.go:446] [ 1] Notified of signal 23 D0229 22:10:14.623707 58032 task_block.go:223] [ 1] Interrupt queued I0229 22:10:14.623872 58032 loader.go:566] Received external signal 23, mode: Process D0229 22:10:14.623837 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:10:14.623997 58032 task_signals.go:434] [ 1] Discarding duplicate signal 23 D0229 22:10:14.624102 58032 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 22:10:14.624203 58032 task_signals.go:220] [ 1] Signal 23: delivering to handler I0229 22:10:14.778846 58032 loader.go:566] Received external signal 23, mode: Process D0229 22:10:14.779086 58032 task_signals.go:446] [ 1] Notified of signal 23 D0229 22:10:14.779216 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:10:14.779511 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:10:14.779752 58032 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 I0229 22:10:14.779330 58032 loader.go:566] Received external signal 23, mode: Process D0229 22:10:14.779865 58032 task_signals.go:220] [ 1] Signal 23: delivering to handler D0229 22:10:14.779968 58032 task_signals.go:446] [ 1] Notified of signal 23 D0229 22:10:14.780061 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:10:14.780370 58032 task_block.go:223] [ 2] Interrupt queued D0229 22:10:14.780495 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:10:14.780400 58032 task_block.go:223] [ 2] Interrupt queued D0229 22:10:14.780714 58032 task_signals.go:176] [ 2] Not restarting syscall 270 after errno 514: interrupted by signal 23 D0229 22:10:14.780851 58032 task_signals.go:220] [ 2] Signal 23: delivering to handler D0229 22:10:15.622917 58032 parameters.go:238] Clock(Monotonic): error: -197 ns, adjusted frequency from 2299773965 Hz to 2299774154 Hz D0229 22:10:15.623096 58032 parameters.go:238] Clock(Realtime): error: -201 ns, adjusted frequency from 2299774832 Hz to 2299774176 Hz D0229 22:10:15.623213 58032 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8983283924115023 monotonicBaseRef:35000304951 monotonicFrequency:2299774154 realtimeReady:1 realtimeBaseCycles:8983283924557666 realtimeBaseRef:1583014215623090639 realtimeFrequency:2299774176} D0229 22:10:16.622831 58032 parameters.go:238] Clock(Monotonic): error: 140 ns, adjusted frequency from 2299774154 Hz to 2299774957 Hz D0229 22:10:16.623011 58032 parameters.go:238] Clock(Realtime): error: -139 ns, adjusted frequency from 2299774176 Hz to 2299774314 Hz D0229 22:10:16.623110 58032 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8983286223686336 monotonicBaseRef:36000216750 monotonicFrequency:2299774957 realtimeReady:1 realtimeBaseCycles:8983286224123381 realtimeBaseRef:1583014216622999994 realtimeFrequency:2299774314} D0229 22:10:17.622841 58032 parameters.go:238] Clock(Monotonic): error: 252 ns, adjusted frequency from 2299774957 Hz to 2299775073 Hz I0229 22:10:17.622938 58032 loader.go:566] Received external signal 23, mode: Process D0229 22:10:17.623047 58032 parameters.go:238] Clock(Realtime): error: 166 ns, adjusted frequency from 2299774314 Hz to 2299774983 Hz D0229 22:10:17.623168 58032 task_signals.go:446] [ 1] Notified of signal 23 D0229 22:10:17.623157 58032 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8983288523446617 monotonicBaseRef:37000210368 monotonicFrequency:2299775073 realtimeReady:1 realtimeBaseCycles:8983288523990438 realtimeBaseRef:1583014217623040321 realtimeFrequency:2299774983} D0229 22:10:17.623352 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:10:17.623375 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:10:17.623978 58032 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 22:10:17.624110 58032 task_signals.go:220] [ 1] Signal 23: delivering to handler D0229 22:10:18.622841 58032 parameters.go:238] Clock(Monotonic): error: 68 ns, adjusted frequency from 2299775073 Hz to 2299774634 Hz D0229 22:10:18.623080 58032 parameters.go:238] Clock(Realtime): error: 36 ns, adjusted frequency from 2299774983 Hz to 2299774692 Hz D0229 22:10:18.623202 58032 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8983290823258810 monotonicBaseRef:38000226508 monotonicFrequency:2299774634 realtimeReady:1 realtimeBaseCycles:8983290823843314 realtimeBaseRef:1583014218623074190 realtimeFrequency:2299774692} D0229 22:10:19.622933 58032 parameters.go:238] Clock(Monotonic): error: -171 ns, adjusted frequency from 2299774634 Hz to 2299774246 Hz D0229 22:10:19.623147 58032 parameters.go:238] Clock(Realtime): error: 126 ns, adjusted frequency from 2299774692 Hz to 2299774886 Hz D0229 22:10:19.623296 58032 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8983293123257355 monotonicBaseRef:39000323870 monotonicFrequency:2299774246 realtimeReady:1 realtimeBaseCycles:8983293123768599 realtimeBaseRef:1583014219623139671 realtimeFrequency:2299774886} I0229 22:10:19.780451 58032 loader.go:566] Received external signal 23, mode: Process D0229 22:10:19.780707 58032 task_signals.go:446] [ 1] Notified of signal 23 D0229 22:10:19.780841 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:10:19.780892 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:10:19.781079 58032 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 22:10:19.781279 58032 task_signals.go:220] [ 1] Signal 23: delivering to handler I0229 22:10:19.793007 58032 loader.go:566] Received external signal 23, mode: Process D0229 22:10:19.793242 58032 task_signals.go:446] [ 1] Notified of signal 23 D0229 22:10:19.793394 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:10:19.793604 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:10:19.793824 58032 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 22:10:19.793953 58032 task_signals.go:220] [ 1] Signal 23: delivering to handler D0229 22:10:20.622864 58032 parameters.go:238] Clock(Monotonic): error: -158 ns, adjusted frequency from 2299774246 Hz to 2299774352 Hz D0229 22:10:20.623097 58032 parameters.go:238] Clock(Realtime): error: 47 ns, adjusted frequency from 2299774886 Hz to 2299774729 Hz D0229 22:10:20.623304 58032 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8983295422860791 monotonicBaseRef:40000249597 monotonicFrequency:2299774352 realtimeReady:1 realtimeBaseCycles:8983295423429369 realtimeBaseRef:1583014220623090050 realtimeFrequency:2299774729} I0229 22:10:20.778794 58032 loader.go:566] Received external signal 23, mode: Process D0229 22:10:20.778984 58032 task_signals.go:446] [ 1] Notified of signal 23 D0229 22:10:20.779092 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:10:20.779215 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:10:20.779362 58032 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 22:10:20.779453 58032 task_signals.go:220] [ 1] Signal 23: delivering to handler D0229 22:10:21.623128 58032 parameters.go:238] Clock(Monotonic): error: 295 ns, adjusted frequency from 2299774352 Hz to 2299775217 Hz D0229 22:10:21.623343 58032 parameters.go:238] Clock(Realtime): error: -24 ns, adjusted frequency from 2299774729 Hz to 2299774537 Hz D0229 22:10:21.623439 58032 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8983297723259181 monotonicBaseRef:41000520944 monotonicFrequency:2299775217 realtimeReady:1 realtimeBaseCycles:8983297723771840 realtimeBaseRef:1583014221623336918 realtimeFrequency:2299774537} I0229 22:10:21.778988 58032 loader.go:566] Received external signal 23, mode: Process D0229 22:10:21.779151 58032 task_signals.go:446] [ 1] Notified of signal 23 D0229 22:10:21.779277 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:10:21.779377 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:10:21.779622 58032 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 22:10:21.779759 58032 task_signals.go:220] [ 1] Signal 23: delivering to handler D0229 22:10:22.623048 58032 parameters.go:238] Clock(Monotonic): error: -206 ns, adjusted frequency from 2299775217 Hz to 2299774224 Hz D0229 22:10:22.623274 58032 parameters.go:238] Clock(Realtime): error: 403 ns, adjusted frequency from 2299774537 Hz to 2299775638 Hz D0229 22:10:22.623411 58032 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8983300022845764 monotonicBaseRef:42000438921 monotonicFrequency:2299774224 realtimeReady:1 realtimeBaseCycles:8983300023386970 realtimeBaseRef:1583014222623267603 realtimeFrequency:2299775638} I0229 22:10:22.779197 58032 loader.go:566] Received external signal 23, mode: Process D0229 22:10:22.779493 58032 task_signals.go:446] [ 1] Notified of signal 23 D0229 22:10:22.779636 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:10:22.779681 58032 task_block.go:223] [ 1] Interrupt queued I0229 22:10:22.779804 58032 loader.go:566] Received external signal 23, mode: Process D0229 22:10:22.779906 58032 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 22:10:22.780004 58032 task_signals.go:446] [ 1] Notified of signal 23 D0229 22:10:22.780110 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:10:22.780070 58032 task_signals.go:220] [ 1] Signal 23: delivering to handler D0229 22:10:22.780588 58032 task_block.go:223] [ 2] Interrupt queued D0229 22:10:22.780726 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:10:22.780783 58032 task_block.go:223] [ 2] Interrupt queued D0229 22:10:22.780952 58032 task_signals.go:176] [ 2] Not restarting syscall 270 after errno 514: interrupted by signal 23 D0229 22:10:22.781075 58032 task_signals.go:220] [ 2] Signal 23: delivering to handler D0229 22:10:23.622900 58032 parameters.go:238] Clock(Monotonic): error: 520 ns, adjusted frequency from 2299774224 Hz to 2299775871 Hz I0229 22:10:23.622998 58032 loader.go:566] Received external signal 23, mode: Process D0229 22:10:23.623103 58032 parameters.go:238] Clock(Realtime): error: -448 ns, adjusted frequency from 2299775638 Hz to 2299773575 Hz D0229 22:10:23.623272 58032 task_signals.go:446] [ 1] Notified of signal 23 D0229 22:10:23.623347 58032 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8983302322187332 monotonicBaseRef:43000250791 monotonicFrequency:2299775871 realtimeReady:1 realtimeBaseCycles:8983302322764023 realtimeBaseRef:1583014223623094288 realtimeFrequency:2299773575} D0229 22:10:23.623508 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:10:23.623644 58032 task_block.go:223] [ 1] Interrupt queued I0229 22:10:23.623733 58032 loader.go:566] Received external signal 23, mode: Process D0229 22:10:23.623914 58032 task_signals.go:434] [ 1] Discarding duplicate signal 23 D0229 22:10:23.624103 58032 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 22:10:23.624240 58032 task_signals.go:220] [ 1] Signal 23: delivering to handler I0229 22:10:23.778916 58032 loader.go:566] Received external signal 23, mode: Process D0229 22:10:23.779141 58032 task_signals.go:446] [ 1] Notified of signal 23 D0229 22:10:23.779243 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:10:23.779395 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:10:23.779650 58032 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 22:10:23.779834 58032 task_signals.go:220] [ 1] Signal 23: delivering to handler D0229 22:10:24.622878 58032 parameters.go:238] Clock(Monotonic): error: -602 ns, adjusted frequency from 2299775871 Hz to 2299773120 Hz D0229 22:10:24.623065 58032 parameters.go:238] Clock(Realtime): error: -206 ns, adjusted frequency from 2299773575 Hz to 2299774128 Hz I0229 22:10:24.623088 58032 loader.go:566] Received external signal 23, mode: Process D0229 22:10:24.623182 58032 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8983304622005785 monotonicBaseRef:44000269306 monotonicFrequency:2299773120 realtimeReady:1 realtimeBaseCycles:8983304622456003 realtimeBaseRef:1583014224623058808 realtimeFrequency:2299774128} D0229 22:10:24.623309 58032 task_signals.go:446] [ 1] Notified of signal 23 D0229 22:10:24.623476 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:10:24.623606 58032 task_block.go:223] [ 1] Interrupt queued I0229 22:10:24.623608 58032 loader.go:566] Received external signal 23, mode: Process D0229 22:10:24.623758 58032 task_signals.go:434] [ 1] Discarding duplicate signal 23 D0229 22:10:24.623917 58032 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 22:10:24.624077 58032 task_signals.go:220] [ 1] Signal 23: delivering to handler I0229 22:10:24.779195 58032 loader.go:566] Received external signal 23, mode: Process D0229 22:10:24.779432 58032 task_signals.go:446] [ 1] Notified of signal 23 D0229 22:10:24.779599 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:10:24.779783 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:10:24.779965 58032 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 22:10:24.780111 58032 task_signals.go:220] [ 1] Signal 23: delivering to handler D0229 22:10:25.622899 58032 parameters.go:238] Clock(Monotonic): error: 145 ns, adjusted frequency from 2299773120 Hz to 2299774951 Hz I0229 22:10:25.623076 58032 loader.go:566] Received external signal 23, mode: Process D0229 22:10:25.623179 58032 parameters.go:238] Clock(Realtime): error: -12 ns, adjusted frequency from 2299774128 Hz to 2299774666 Hz D0229 22:10:25.623290 58032 task_signals.go:446] [ 1] Notified of signal 23 D0229 22:10:25.623484 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:10:25.623439 58032 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8983306921828749 monotonicBaseRef:45000290979 monotonicFrequency:2299774951 realtimeReady:1 realtimeBaseCycles:8983306922486408 realtimeBaseRef:1583014225623170243 realtimeFrequency:2299774666} D0229 22:10:25.623593 58032 task_block.go:223] [ 1] Interrupt queued I0229 22:10:25.623663 58032 loader.go:566] Received external signal 23, mode: Process D0229 22:10:25.623912 58032 task_signals.go:446] [ 1] Notified of signal 23 D0229 22:10:25.623895 58032 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 22:10:25.624057 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:10:25.624090 58032 task_signals.go:220] [ 1] Signal 23: delivering to handler D0229 22:10:25.624762 58032 task_block.go:223] [ 2] Interrupt queued D0229 22:10:25.624897 58032 task_block.go:223] [ 2] Interrupt queued D0229 22:10:25.624900 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:10:25.625075 58032 task_signals.go:176] [ 2] Not restarting syscall 202 after errno 516: interrupted by signal 23 D0229 22:10:25.625189 58032 task_signals.go:220] [ 2] Signal 23: delivering to handler I0229 22:10:25.801789 58032 watchdog.go:277] Watchdog starting loop, tasks: 16, discount: 0s D0229 22:10:26.622919 58032 parameters.go:238] Clock(Monotonic): error: -212 ns, adjusted frequency from 2299774951 Hz to 2299774257 Hz D0229 22:10:26.623153 58032 parameters.go:238] Clock(Realtime): error: 226 ns, adjusted frequency from 2299774666 Hz to 2299775131 Hz D0229 22:10:26.623294 58032 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8983309221580688 monotonicBaseRef:46000280972 monotonicFrequency:2299774257 realtimeReady:1 realtimeBaseCycles:8983309222200224 realtimeBaseRef:1583014226623143783 realtimeFrequency:2299775131} D0229 22:10:27.622889 58032 parameters.go:238] Clock(Monotonic): error: 224 ns, adjusted frequency from 2299774257 Hz to 2299775247 Hz D0229 22:10:27.623082 58032 parameters.go:238] Clock(Realtime): error: 19 ns, adjusted frequency from 2299775131 Hz to 2299774675 Hz D0229 22:10:27.623244 58032 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8983311521351251 monotonicBaseRef:47000279365 monotonicFrequency:2299775247 realtimeReady:1 realtimeBaseCycles:8983311521817395 realtimeBaseRef:1583014227623075098 realtimeFrequency:2299774675} D0229 22:10:28.622863 58032 parameters.go:238] Clock(Monotonic): error: -346 ns, adjusted frequency from 2299775247 Hz to 2299773740 Hz D0229 22:10:28.623076 58032 parameters.go:238] Clock(Realtime): error: -208 ns, adjusted frequency from 2299774675 Hz to 2299774180 Hz D0229 22:10:28.623177 58032 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8983313821072748 monotonicBaseRef:48000255993 monotonicFrequency:2299773740 realtimeReady:1 realtimeBaseCycles:8983313821582208 realtimeBaseRef:1583014228623070809 realtimeFrequency:2299774180} I0229 22:10:28.778902 58032 loader.go:566] Received external signal 23, mode: Process D0229 22:10:28.779136 58032 task_signals.go:446] [ 1] Notified of signal 23 D0229 22:10:28.779337 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:10:28.779447 58032 task_block.go:223] [ 1] Interrupt queued I0229 22:10:28.779544 58032 loader.go:566] Received external signal 23, mode: Process D0229 22:10:28.779726 58032 task_signals.go:446] [ 1] Notified of signal 23 D0229 22:10:28.779681 58032 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 22:10:28.779851 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:10:28.779874 58032 task_signals.go:220] [ 1] Signal 23: delivering to handler D0229 22:10:28.780494 58032 task_block.go:223] [ 2] Interrupt queued D0229 22:10:28.780523 58032 task_block.go:223] [ 2] Interrupt queued D0229 22:10:28.780627 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:10:28.780844 58032 task_signals.go:176] [ 2] Not restarting syscall 202 after errno 516: interrupted by signal 23 D0229 22:10:28.780971 58032 task_signals.go:220] [ 2] Signal 23: delivering to handler D0229 22:10:29.622921 58032 parameters.go:238] Clock(Monotonic): error: 486 ns, adjusted frequency from 2299773740 Hz to 2299775665 Hz I0229 22:10:29.622984 58032 loader.go:566] Received external signal 23, mode: Process D0229 22:10:29.623187 58032 parameters.go:238] Clock(Realtime): error: 163 ns, adjusted frequency from 2299774180 Hz to 2299774989 Hz D0229 22:10:29.623256 58032 task_signals.go:446] [ 1] Notified of signal 23 D0229 22:10:29.623333 58032 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8983316120971360 monotonicBaseRef:49000310290 monotonicFrequency:2299775665 realtimeReady:1 realtimeBaseCycles:8983316121604875 realtimeBaseRef:1583014229623178857 realtimeFrequency:2299774989} D0229 22:10:29.623586 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:10:29.623621 58032 task_block.go:223] [ 1] Interrupt queued I0229 22:10:29.623791 58032 loader.go:566] Received external signal 23, mode: Process D0229 22:10:29.623941 58032 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 22:10:29.624029 58032 task_signals.go:446] [ 1] Notified of signal 23 D0229 22:10:29.624067 58032 task_signals.go:220] [ 1] Signal 23: delivering to handler D0229 22:10:29.624204 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:10:29.624606 58032 task_block.go:223] [ 2] Interrupt queued D0229 22:10:29.624657 58032 task_block.go:223] [ 2] Interrupt queued D0229 22:10:29.624771 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:10:29.624954 58032 task_signals.go:176] [ 2] Not restarting syscall 202 after errno 516: interrupted by signal 23 D0229 22:10:29.625120 58032 task_signals.go:220] [ 2] Signal 23: delivering to handler I0229 22:10:29.779184 58032 loader.go:566] Received external signal 23, mode: Process D0229 22:10:29.779405 58032 task_signals.go:446] [ 1] Notified of signal 23 D0229 22:10:29.779519 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:10:29.779533 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:10:29.779729 58032 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 22:10:29.779944 58032 task_signals.go:220] [ 1] Signal 23: delivering to handler D0229 22:10:30.622870 58032 parameters.go:238] Clock(Monotonic): error: -343 ns, adjusted frequency from 2299775665 Hz to 2299773901 Hz I0229 22:10:30.623037 58032 loader.go:566] Received external signal 23, mode: Process D0229 22:10:30.623095 58032 parameters.go:238] Clock(Realtime): error: -42 ns, adjusted frequency from 2299774989 Hz to 2299774456 Hz D0229 22:10:30.623284 58032 task_signals.go:446] [ 1] Notified of signal 23 D0229 22:10:30.623410 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:10:30.623438 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:10:30.623374 58032 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8983318420635278 monotonicBaseRef:50000261699 monotonicFrequency:2299773901 realtimeReady:1 realtimeBaseCycles:8983318421171280 realtimeBaseRef:1583014230623088159 realtimeFrequency:2299774456} D0229 22:10:30.623714 58032 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 22:10:30.623867 58032 task_signals.go:220] [ 1] Signal 23: delivering to handler I0229 22:10:30.778925 58032 loader.go:566] Received external signal 23, mode: Process D0229 22:10:30.779196 58032 task_signals.go:446] [ 1] Notified of signal 23 D0229 22:10:30.779316 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:10:30.779509 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:10:30.779760 58032 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 22:10:30.779933 58032 task_signals.go:220] [ 1] Signal 23: delivering to handler I0229 22:10:30.779521 58032 loader.go:566] Received external signal 23, mode: Process D0229 22:10:30.781110 58032 task_signals.go:446] [ 1] Notified of signal 23 D0229 22:10:30.781246 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:10:30.781360 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:10:30.781508 58032 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 22:10:30.781630 58032 task_signals.go:220] [ 1] Signal 23: delivering to handler D0229 22:10:31.622867 58032 parameters.go:238] Clock(Monotonic): error: 436 ns, adjusted frequency from 2299773901 Hz to 2299775512 Hz D0229 22:10:31.623086 58032 parameters.go:238] Clock(Realtime): error: -29 ns, adjusted frequency from 2299774456 Hz to 2299774588 Hz D0229 22:10:31.623213 58032 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8983320720395037 monotonicBaseRef:51000255549 monotonicFrequency:2299775512 realtimeReady:1 realtimeBaseCycles:8983320720923417 realtimeBaseRef:1583014231623078454 realtimeFrequency:2299774588} I0229 22:10:31.779540 58032 loader.go:566] Received external signal 23, mode: Process D0229 22:10:31.779809 58032 task_signals.go:446] [ 1] Notified of signal 23 D0229 22:10:31.779982 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:10:31.780114 58032 task_block.go:223] [ 1] Interrupt queued I0229 22:10:31.780241 58032 loader.go:566] Received external signal 23, mode: Process D0229 22:10:31.780358 58032 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 22:10:31.780412 58032 task_signals.go:446] [ 1] Notified of signal 23 D0229 22:10:31.780518 58032 task_signals.go:220] [ 1] Signal 23: delivering to handler D0229 22:10:31.780558 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:10:31.781114 58032 task_block.go:223] [ 2] Interrupt queued D0229 22:10:31.781051 58032 task_block.go:223] [ 2] Interrupt queued D0229 22:10:31.781275 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:10:31.781453 58032 task_signals.go:176] [ 2] Not restarting syscall 202 after errno 516: interrupted by signal 23 D0229 22:10:31.781602 58032 task_signals.go:220] [ 2] Signal 23: delivering to handler D0229 22:10:32.622859 58032 parameters.go:238] Clock(Monotonic): error: -167 ns, adjusted frequency from 2299775512 Hz to 2299774043 Hz D0229 22:10:32.623053 58032 parameters.go:238] Clock(Realtime): error: -215 ns, adjusted frequency from 2299774588 Hz to 2299774108 Hz D0229 22:10:32.623172 58032 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8983323020155842 monotonicBaseRef:52000249154 monotonicFrequency:2299774043 realtimeReady:1 realtimeBaseCycles:8983323020628395 realtimeBaseRef:1583014232623048185 realtimeFrequency:2299774108} D0229 22:10:33.622886 58032 parameters.go:238] Clock(Monotonic): error: 432 ns, adjusted frequency from 2299774043 Hz to 2299775677 Hz D0229 22:10:33.623105 58032 parameters.go:238] Clock(Realtime): error: 211 ns, adjusted frequency from 2299774108 Hz to 2299775134 Hz D0229 22:10:33.623251 58032 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8983325319989945 monotonicBaseRef:53000275269 monotonicFrequency:2299775677 realtimeReady:1 realtimeBaseCycles:8983325320517663 realtimeBaseRef:1583014233623098259 realtimeFrequency:2299775134} I0229 22:10:33.779084 58032 loader.go:566] Received external signal 23, mode: Process D0229 22:10:33.779304 58032 task_signals.go:446] [ 1] Notified of signal 23 D0229 22:10:33.779420 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:10:33.779540 58032 task_block.go:223] [ 1] Interrupt queued I0229 22:10:33.779597 58032 loader.go:566] Received external signal 23, mode: Process D0229 22:10:33.779743 58032 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 22:10:33.779760 58032 task_signals.go:446] [ 1] Notified of signal 23 D0229 22:10:33.779863 58032 task_signals.go:220] [ 1] Signal 23: delivering to handler D0229 22:10:33.779924 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:10:33.780327 58032 task_block.go:223] [ 2] Interrupt queued D0229 22:10:33.780358 58032 task_block.go:223] [ 2] Interrupt queued D0229 22:10:33.780425 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:10:33.780519 58032 task_signals.go:176] [ 2] Not restarting syscall 202 after errno 516: interrupted by signal 23 D0229 22:10:33.780637 58032 task_signals.go:220] [ 2] Signal 23: delivering to handler D0229 22:10:34.622852 58032 parameters.go:238] Clock(Monotonic): error: -471 ns, adjusted frequency from 2299775677 Hz to 2299773535 Hz I0229 22:10:34.622926 58032 loader.go:566] Received external signal 23, mode: Process D0229 22:10:34.623050 58032 parameters.go:238] Clock(Realtime): error: -329 ns, adjusted frequency from 2299775134 Hz to 2299773808 Hz D0229 22:10:34.623200 58032 task_signals.go:446] [ 1] Notified of signal 23 D0229 22:10:34.623242 58032 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8983327619691214 monotonicBaseRef:54000242914 monotonicFrequency:2299773535 realtimeReady:1 realtimeBaseCycles:8983327620165798 realtimeBaseRef:1583014234623043036 realtimeFrequency:2299773808} D0229 22:10:34.623341 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:10:34.623400 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:10:34.623721 58032 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 22:10:34.623907 58032 task_signals.go:220] [ 1] Signal 23: delivering to handler D0229 22:10:35.622850 58032 parameters.go:238] Clock(Monotonic): error: 32 ns, adjusted frequency from 2299773535 Hz to 2299774718 Hz D0229 22:10:35.623120 58032 parameters.go:238] Clock(Realtime): error: 380 ns, adjusted frequency from 2299773808 Hz to 2299775532 Hz D0229 22:10:35.623245 58032 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8983329919454781 monotonicBaseRef:55000238579 monotonicFrequency:2299774718 realtimeReady:1 realtimeBaseCycles:8983329920104187 realtimeBaseRef:1583014235623114599 realtimeFrequency:2299775532} D0229 22:10:36.622920 58032 parameters.go:238] Clock(Monotonic): error: -228 ns, adjusted frequency from 2299774718 Hz to 2299774086 Hz D0229 22:10:36.623125 58032 parameters.go:238] Clock(Realtime): error: -155 ns, adjusted frequency from 2299775532 Hz to 2299774242 Hz D0229 22:10:36.623301 58032 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8983332219347867 monotonicBaseRef:56000290048 monotonicFrequency:2299774086 realtimeReady:1 realtimeBaseCycles:8983332219888564 realtimeBaseRef:1583014236623118445 realtimeFrequency:2299774242} I0229 22:10:36.778827 58032 loader.go:566] Received external signal 23, mode: Process D0229 22:10:36.779074 58032 task_signals.go:446] [ 1] Notified of signal 23 D0229 22:10:36.779257 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:10:36.779373 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:10:36.779615 58032 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 22:10:36.779750 58032 task_signals.go:220] [ 1] Signal 23: delivering to handler D0229 22:10:37.622891 58032 parameters.go:238] Clock(Monotonic): error: -66 ns, adjusted frequency from 2299774086 Hz to 2299774537 Hz D0229 22:10:37.623122 58032 parameters.go:238] Clock(Realtime): error: 268 ns, adjusted frequency from 2299774242 Hz to 2299775197 Hz D0229 22:10:37.623270 58032 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8983334519103293 monotonicBaseRef:57000281934 monotonicFrequency:2299774537 realtimeReady:1 realtimeBaseCycles:8983334519653102 realtimeBaseRef:1583014237623114225 realtimeFrequency:2299775197} D0229 22:10:38.622895 58032 parameters.go:238] Clock(Monotonic): error: 115 ns, adjusted frequency from 2299774537 Hz to 2299774967 Hz D0229 22:10:38.623201 58032 parameters.go:238] Clock(Realtime): error: -239 ns, adjusted frequency from 2299775197 Hz to 2299774029 Hz D0229 22:10:38.623304 58032 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8983336818871314 monotonicBaseRef:58000279100 monotonicFrequency:2299774967 realtimeReady:1 realtimeBaseCycles:8983336819615367 realtimeBaseRef:1583014238623195566 realtimeFrequency:2299774029} D0229 22:10:39.622885 58032 parameters.go:238] Clock(Monotonic): error: 32 ns, adjusted frequency from 2299774967 Hz to 2299774699 Hz D0229 22:10:39.623090 58032 parameters.go:238] Clock(Realtime): error: -23 ns, adjusted frequency from 2299774029 Hz to 2299774603 Hz D0229 22:10:39.623211 58032 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8983339118635829 monotonicBaseRef:59000274555 monotonicFrequency:2299774699 realtimeReady:1 realtimeBaseCycles:8983339119135211 realtimeBaseRef:1583014239623085039 realtimeFrequency:2299774603} D0229 22:10:40.622932 58032 parameters.go:238] Clock(Monotonic): error: -31 ns, adjusted frequency from 2299774699 Hz to 2299774631 Hz D0229 22:10:40.623225 58032 parameters.go:238] Clock(Realtime): error: 196 ns, adjusted frequency from 2299774603 Hz to 2299774994 Hz D0229 22:10:40.623375 58032 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8983341418509732 monotonicBaseRef:60000317691 monotonicFrequency:2299774631 realtimeReady:1 realtimeBaseCycles:8983341419215340 realtimeBaseRef:1583014240623217889 realtimeFrequency:2299774994} I0229 22:10:40.778983 58032 loader.go:566] Received external signal 23, mode: Process D0229 22:10:40.779188 58032 task_signals.go:446] [ 1] Notified of signal 23 D0229 22:10:40.779322 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:10:40.779359 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:10:40.779585 58032 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 22:10:40.779766 58032 task_signals.go:220] [ 1] Signal 23: delivering to handler D0229 22:10:41.622906 58032 parameters.go:238] Clock(Monotonic): error: -188 ns, adjusted frequency from 2299774631 Hz to 2299774321 Hz I0229 22:10:41.623006 58032 loader.go:566] Received external signal 23, mode: Process D0229 22:10:41.623122 58032 parameters.go:238] Clock(Realtime): error: -158 ns, adjusted frequency from 2299774994 Hz to 2299774254 Hz D0229 22:10:41.623230 58032 task_signals.go:446] [ 1] Notified of signal 23 D0229 22:10:41.623378 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:10:41.623236 58032 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8983343718239948 monotonicBaseRef:61000298378 monotonicFrequency:2299774321 realtimeReady:1 realtimeBaseCycles:8983343718757333 realtimeBaseRef:1583014241623116574 realtimeFrequency:2299774254} I0229 22:10:41.623514 58032 loader.go:566] Received external signal 23, mode: Process D0229 22:10:41.623396 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:10:41.623630 58032 task_signals.go:434] [ 1] Discarding duplicate signal 23 D0229 22:10:41.624167 58032 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 22:10:41.624352 58032 task_signals.go:220] [ 1] Signal 23: delivering to handler I0229 22:10:41.801665 58032 loader.go:566] Received external signal 23, mode: Process D0229 22:10:41.801922 58032 task_signals.go:446] [ 1] Notified of signal 23 D0229 22:10:41.802102 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:10:41.802148 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:10:41.802409 58032 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 22:10:41.802541 58032 task_signals.go:220] [ 1] Signal 23: delivering to handler D0229 22:10:42.622919 58032 parameters.go:238] Clock(Monotonic): error: 323 ns, adjusted frequency from 2299774321 Hz to 2299775326 Hz D0229 22:10:42.623240 58032 parameters.go:238] Clock(Realtime): error: -64 ns, adjusted frequency from 2299774254 Hz to 2299774482 Hz I0229 22:10:42.623283 58032 loader.go:566] Received external signal 23, mode: Process D0229 22:10:42.623372 58032 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8983346018038913 monotonicBaseRef:62000309093 monotonicFrequency:2299775326 realtimeReady:1 realtimeBaseCycles:8983346018802040 realtimeBaseRef:1583014242623234173 realtimeFrequency:2299774482} D0229 22:10:42.623506 58032 task_signals.go:446] [ 1] Notified of signal 23 D0229 22:10:42.623642 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:10:42.623685 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:10:42.623942 58032 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 22:10:42.624066 58032 task_signals.go:220] [ 1] Signal 23: delivering to handler I0229 22:10:42.625447 58032 loader.go:566] Received external signal 23, mode: Process D0229 22:10:42.626114 58032 task_signals.go:452] [ 2] Notified of group signal 23 D0229 22:10:42.627097 58032 task_block.go:223] [ 2] Interrupt queued D0229 22:10:42.627128 58032 task_block.go:223] [ 2] Interrupt queued I0229 22:10:42.627306 58032 loader.go:566] Received external signal 23, mode: Process D0229 22:10:42.627311 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:10:42.627494 58032 task_signals.go:220] [ 1] Signal 23: delivering to handler D0229 22:10:42.627513 58032 task_run.go:152] [ 2] Restarting syscall 202 with restart block after errno 516: not interrupted by handled signal D0229 22:10:42.627626 58032 task_signals.go:446] [ 1] Notified of signal 23 D0229 22:10:42.627825 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:10:42.627974 58032 task_block.go:223] [ 2] Interrupt queued D0229 22:10:42.628049 58032 task_block.go:223] [ 2] Interrupt queued D0229 22:10:42.628087 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:10:42.628244 58032 task_signals.go:176] [ 2] Not restarting syscall 219 after errno 516: interrupted by signal 23 D0229 22:10:42.628378 58032 task_signals.go:220] [ 2] Signal 23: delivering to handler D0229 22:10:43.622815 58032 parameters.go:238] Clock(Monotonic): error: -292 ns, adjusted frequency from 2299775326 Hz to 2299773953 Hz D0229 22:10:43.623051 58032 parameters.go:238] Clock(Realtime): error: 173 ns, adjusted frequency from 2299774482 Hz to 2299775018 Hz D0229 22:10:43.623206 58032 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8983348317563439 monotonicBaseRef:63000200038 monotonicFrequency:2299773953 realtimeReady:1 realtimeBaseCycles:8983348318142589 realtimeBaseRef:1583014243623045487 realtimeFrequency:2299775018} I0229 22:10:43.779038 58032 loader.go:566] Received external signal 23, mode: Process D0229 22:10:43.779272 58032 task_signals.go:446] [ 1] Notified of signal 23 D0229 22:10:43.779369 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:10:43.779485 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:10:43.779727 58032 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 22:10:43.779917 58032 task_signals.go:220] [ 1] Signal 23: delivering to handler I0229 22:10:43.907380 58032 loader.go:566] Received external signal 23, mode: Process D0229 22:10:43.907621 58032 task_signals.go:446] [ 1] Notified of signal 23 D0229 22:10:43.907670 58032 urpc.go:577] urpc: unmarshal success. D0229 22:10:43.907787 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:10:43.907859 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:10:43.907912 58032 controller.go:501] containerManager.Signal &{CID:ci-gvisor-ptrace-proxy-sandbox-race-2 Signo:0 PID:0 Mode:Process} I0229 22:10:43.907969 58032 loader.go:566] Received external signal 23, mode: Process D0229 22:10:43.908162 58032 urpc.go:534] urpc: successfully marshalled 37 bytes. D0229 22:10:43.908158 58032 task_signals.go:446] [ 1] Notified of signal 23 D0229 22:10:43.908146 58032 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 22:10:43.908269 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:10:43.908334 58032 task_signals.go:220] [ 1] Signal 23: delivering to handler D0229 22:10:43.908851 58032 task_block.go:223] [ 2] Interrupt queued D0229 22:10:43.908976 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:10:43.908950 58032 task_block.go:223] [ 2] Interrupt queued D0229 22:10:43.909273 58032 task_signals.go:176] [ 2] Not restarting syscall 202 after errno 516: interrupted by signal 23 D0229 22:10:43.909385 58032 task_signals.go:220] [ 2] Signal 23: delivering to handler D0229 22:10:44.622907 58032 parameters.go:238] Clock(Monotonic): error: 1 ns, adjusted frequency from 2299773953 Hz to 2299774598 Hz I0229 22:10:44.623001 58032 loader.go:566] Received external signal 23, mode: Process D0229 22:10:44.623087 58032 parameters.go:238] Clock(Realtime): error: -148 ns, adjusted frequency from 2299775018 Hz to 2299774280 Hz D0229 22:10:44.623174 58032 task_signals.go:446] [ 1] Notified of signal 23 D0229 22:10:44.623262 58032 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8983350617563288 monotonicBaseRef:64000298263 monotonicFrequency:2299774598 realtimeReady:1 realtimeBaseCycles:8983350617999656 realtimeBaseRef:1583014244623081163 realtimeFrequency:2299774280} D0229 22:10:44.623301 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:10:44.623352 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:10:44.623660 58032 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 22:10:44.623790 58032 task_signals.go:220] [ 1] Signal 23: delivering to handler D0229 22:10:45.622899 58032 parameters.go:238] Clock(Monotonic): error: 275 ns, adjusted frequency from 2299774598 Hz to 2299775121 Hz D0229 22:10:45.623103 58032 parameters.go:238] Clock(Realtime): error: 281 ns, adjusted frequency from 2299774280 Hz to 2299775306 Hz D0229 22:10:45.623224 58032 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8983352917319010 monotonicBaseRef:65000290055 monotonicFrequency:2299775121 realtimeReady:1 realtimeBaseCycles:8983352917807340 realtimeBaseRef:1583014245623095687 realtimeFrequency:2299775306} D0229 22:10:46.623906 58032 parameters.go:238] Clock(Monotonic): error: -242 ns, adjusted frequency from 2299775121 Hz to 2299774073 Hz D0229 22:10:46.624146 58032 parameters.go:238] Clock(Realtime): error: -233 ns, adjusted frequency from 2299775306 Hz to 2299774085 Hz I0229 22:10:46.624178 58032 loader.go:566] Received external signal 23, mode: Process D0229 22:10:46.624272 58032 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8983355219360805 monotonicBaseRef:66001275661 monotonicFrequency:2299774073 realtimeReady:1 realtimeBaseCycles:8983355219983690 realtimeBaseRef:1583014246624139721 realtimeFrequency:2299774085} D0229 22:10:46.624374 58032 task_signals.go:446] [ 1] Notified of signal 23 D0229 22:10:46.624496 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:10:46.624636 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:10:46.624916 58032 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 22:10:46.625060 58032 task_signals.go:220] [ 1] Signal 23: delivering to handler I0229 22:10:46.778820 58032 loader.go:566] Received external signal 23, mode: Process D0229 22:10:46.779116 58032 task_signals.go:446] [ 1] Notified of signal 23 D0229 22:10:46.779258 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:10:46.779395 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:10:46.779596 58032 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 22:10:46.779750 58032 task_signals.go:220] [ 1] Signal 23: delivering to handler D0229 22:10:47.622831 58032 parameters.go:238] Clock(Monotonic): error: 174 ns, adjusted frequency from 2299774073 Hz to 2299775024 Hz D0229 22:10:47.623066 58032 parameters.go:238] Clock(Realtime): error: -69 ns, adjusted frequency from 2299774085 Hz to 2299774539 Hz D0229 22:10:47.623243 58032 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8983357516707610 monotonicBaseRef:67000220223 monotonicFrequency:2299775024 realtimeReady:1 realtimeBaseCycles:8983357517270848 realtimeBaseRef:1583014247623058342 realtimeFrequency:2299774539} D0229 22:10:48.622878 58032 parameters.go:238] Clock(Monotonic): error: -204 ns, adjusted frequency from 2299775024 Hz to 2299774136 Hz D0229 22:10:48.623131 58032 parameters.go:238] Clock(Realtime): error: -248 ns, adjusted frequency from 2299774539 Hz to 2299774095 Hz D0229 22:10:48.623312 58032 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8983359816568359 monotonicBaseRef:68000257498 monotonicFrequency:2299774136 realtimeReady:1 realtimeBaseCycles:8983359817200238 realtimeBaseRef:1583014248623125675 realtimeFrequency:2299774095} I0229 22:10:48.778756 58032 loader.go:566] Received external signal 23, mode: Process D0229 22:10:48.779005 58032 task_signals.go:446] [ 1] Notified of signal 23 D0229 22:10:48.779110 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:10:48.779164 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:10:48.779416 58032 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 22:10:48.779540 58032 task_signals.go:220] [ 1] Signal 23: delivering to handler D0229 22:10:49.622780 58032 parameters.go:238] Clock(Monotonic): error: 239 ns, adjusted frequency from 2299774136 Hz to 2299775215 Hz D0229 22:10:49.622993 58032 parameters.go:238] Clock(Realtime): error: 466 ns, adjusted frequency from 2299774095 Hz to 2299775691 Hz D0229 22:10:49.623110 58032 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8983362116139180 monotonicBaseRef:69000169091 monotonicFrequency:2299775215 realtimeReady:1 realtimeBaseCycles:8983362116641563 realtimeBaseRef:1583014249622980978 realtimeFrequency:2299775691} I0229 22:10:49.778922 58032 loader.go:566] Received external signal 23, mode: Process D0229 22:10:49.779215 58032 task_signals.go:446] [ 1] Notified of signal 23 D0229 22:10:49.779339 58032 task_block.go:223] [ 1] Interrupt queued I0229 22:10:49.779479 58032 loader.go:566] Received external signal 23, mode: Process D0229 22:10:49.779448 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:10:49.779618 58032 task_signals.go:434] [ 1] Discarding duplicate signal 23 D0229 22:10:49.779773 58032 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 22:10:49.779984 58032 task_signals.go:220] [ 1] Signal 23: delivering to handler D0229 22:10:50.622866 58032 parameters.go:238] Clock(Monotonic): error: 132 ns, adjusted frequency from 2299775215 Hz to 2299774792 Hz D0229 22:10:50.623068 58032 parameters.go:238] Clock(Realtime): error: -158 ns, adjusted frequency from 2299775691 Hz to 2299774315 Hz D0229 22:10:50.623199 58032 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8983364416116821 monotonicBaseRef:70000257110 monotonicFrequency:2299774792 realtimeReady:1 realtimeBaseCycles:8983364416604610 realtimeBaseRef:1583014250623062445 realtimeFrequency:2299774315} I0229 22:10:50.778827 58032 loader.go:566] Received external signal 23, mode: Process D0229 22:10:50.779122 58032 task_signals.go:446] [ 1] Notified of signal 23 D0229 22:10:50.779285 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:10:50.779434 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:10:50.779687 58032 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 22:10:50.779831 58032 task_signals.go:220] [ 1] Signal 23: delivering to handler D0229 22:10:51.622902 58032 parameters.go:238] Clock(Monotonic): error: -257 ns, adjusted frequency from 2299774792 Hz to 2299774140 Hz D0229 22:10:51.623136 58032 parameters.go:238] Clock(Realtime): error: -10 ns, adjusted frequency from 2299774315 Hz to 2299774556 Hz D0229 22:10:51.623244 58032 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8983366715971285 monotonicBaseRef:71000291753 monotonicFrequency:2299774140 realtimeReady:1 realtimeBaseCycles:8983366716535656 realtimeBaseRef:1583014251623130595 realtimeFrequency:2299774556} I0229 22:10:51.778855 58032 loader.go:566] Received external signal 23, mode: Process D0229 22:10:51.779180 58032 task_signals.go:446] [ 1] Notified of signal 23 D0229 22:10:51.779322 58032 task_block.go:223] [ 1] Interrupt queued I0229 22:10:51.779442 58032 loader.go:566] Received external signal 23, mode: Process D0229 22:10:51.779596 58032 task_signals.go:434] [ 1] Discarding duplicate signal 23 D0229 22:10:51.779440 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:10:51.779742 58032 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 22:10:51.779848 58032 task_signals.go:220] [ 1] Signal 23: delivering to handler D0229 22:10:52.622892 58032 parameters.go:238] Clock(Monotonic): error: 63 ns, adjusted frequency from 2299774140 Hz to 2299774600 Hz I0229 22:10:52.622968 58032 loader.go:566] Received external signal 23, mode: Process D0229 22:10:52.623118 58032 parameters.go:238] Clock(Realtime): error: -6 ns, adjusted frequency from 2299774556 Hz to 2299774632 Hz D0229 22:10:52.623274 58032 task_signals.go:446] [ 1] Notified of signal 23 D0229 22:10:52.623263 58032 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8983369015717350 monotonicBaseRef:72000279545 monotonicFrequency:2299774600 realtimeReady:1 realtimeBaseCycles:8983369016267185 realtimeBaseRef:1583014252623111885 realtimeFrequency:2299774632} D0229 22:10:52.623458 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:10:52.623515 58032 task_block.go:223] [ 1] Interrupt queued I0229 22:10:52.623640 58032 loader.go:566] Received external signal 23, mode: Process D0229 22:10:52.623768 58032 task_signals.go:434] [ 1] Discarding duplicate signal 23 D0229 22:10:52.624024 58032 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 22:10:52.624222 58032 task_signals.go:220] [ 1] Signal 23: delivering to handler D0229 22:10:53.622881 58032 parameters.go:238] Clock(Monotonic): error: -128 ns, adjusted frequency from 2299774600 Hz to 2299774331 Hz D0229 22:10:53.623071 58032 parameters.go:238] Clock(Realtime): error: -125 ns, adjusted frequency from 2299774632 Hz to 2299774353 Hz D0229 22:10:53.623209 58032 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8983371315477102 monotonicBaseRef:73000273088 monotonicFrequency:2299774331 realtimeReady:1 realtimeBaseCycles:8983371315939587 realtimeBaseRef:1583014253623067432 realtimeFrequency:2299774353} I0229 22:10:53.778953 58032 loader.go:566] Received external signal 23, mode: Process D0229 22:10:53.779149 58032 task_signals.go:446] [ 1] Notified of signal 23 D0229 22:10:53.779271 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:10:53.779392 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:10:53.779563 58032 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 22:10:53.779690 58032 task_signals.go:220] [ 1] Signal 23: delivering to handler D0229 22:10:54.622777 58032 parameters.go:238] Clock(Monotonic): error: 209 ns, adjusted frequency from 2299774331 Hz to 2299775270 Hz I0229 22:10:54.622872 58032 loader.go:566] Received external signal 23, mode: Process D0229 22:10:54.623027 58032 parameters.go:238] Clock(Realtime): error: 27 ns, adjusted frequency from 2299774353 Hz to 2299774664 Hz D0229 22:10:54.623085 58032 task_signals.go:446] [ 1] Notified of signal 23 D0229 22:10:54.623154 58032 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8983373614997622 monotonicBaseRef:74000162724 monotonicFrequency:2299775270 realtimeReady:1 realtimeBaseCycles:8983373615607727 realtimeBaseRef:1583014254623021247 realtimeFrequency:2299774664} D0229 22:10:54.623236 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:10:54.623325 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:10:54.623620 58032 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 22:10:54.623788 58032 task_signals.go:220] [ 1] Signal 23: delivering to handler D0229 22:10:55.622844 58032 parameters.go:238] Clock(Monotonic): error: 95 ns, adjusted frequency from 2299775270 Hz to 2299774688 Hz D0229 22:10:55.623032 58032 parameters.go:238] Clock(Realtime): error: 201 ns, adjusted frequency from 2299774664 Hz to 2299775118 Hz D0229 22:10:55.623245 58032 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8983375914936977 monotonicBaseRef:75000234072 monotonicFrequency:2299774688 realtimeReady:1 realtimeBaseCycles:8983375915391836 realtimeBaseRef:1583014255623025353 realtimeFrequency:2299775118} D0229 22:10:56.622943 58032 parameters.go:238] Clock(Monotonic): error: 143 ns, adjusted frequency from 2299774688 Hz to 2299774853 Hz D0229 22:10:56.623189 58032 parameters.go:238] Clock(Realtime): error: -288 ns, adjusted frequency from 2299775118 Hz to 2299773919 Hz D0229 22:10:56.623321 58032 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8983378214863345 monotonicBaseRef:76000300026 monotonicFrequency:2299774853 realtimeReady:1 realtimeBaseCycles:8983378215525319 realtimeBaseRef:1583014256623181179 realtimeFrequency:2299773919} I0229 22:10:56.623316 58032 loader.go:566] Received external signal 23, mode: Process D0229 22:10:56.623584 58032 task_signals.go:446] [ 1] Notified of signal 23 D0229 22:10:56.623729 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:10:56.623825 58032 task_block.go:223] [ 1] Interrupt queued I0229 22:10:56.623933 58032 loader.go:566] Received external signal 23, mode: Process D0229 22:10:56.624039 58032 task_signals.go:434] [ 1] Discarding duplicate signal 23 D0229 22:10:56.624221 58032 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 22:10:56.624502 58032 task_signals.go:220] [ 1] Signal 23: delivering to handler D0229 22:10:57.622902 58032 parameters.go:238] Clock(Monotonic): error: -229 ns, adjusted frequency from 2299774853 Hz to 2299774085 Hz D0229 22:10:57.623143 58032 parameters.go:238] Clock(Realtime): error: 214 ns, adjusted frequency from 2299773919 Hz to 2299775100 Hz D0229 22:10:57.623294 58032 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8983380514616789 monotonicBaseRef:77000290716 monotonicFrequency:2299774085 realtimeReady:1 realtimeBaseCycles:8983380515200541 realtimeBaseRef:1583014257623138263 realtimeFrequency:2299775100} D0229 22:10:58.622864 58032 parameters.go:238] Clock(Monotonic): error: -182 ns, adjusted frequency from 2299774085 Hz to 2299774189 Hz D0229 22:10:58.623087 58032 parameters.go:238] Clock(Realtime): error: -49 ns, adjusted frequency from 2299775100 Hz to 2299774533 Hz D0229 22:10:58.623183 58032 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8983382814299928 monotonicBaseRef:78000251170 monotonicFrequency:2299774189 realtimeReady:1 realtimeBaseCycles:8983382814844310 realtimeBaseRef:1583014258623081156 realtimeFrequency:2299774533} D0229 22:10:59.622898 58032 parameters.go:238] Clock(Monotonic): error: 10 ns, adjusted frequency from 2299774189 Hz to 2299774785 Hz D0229 22:10:59.623113 58032 parameters.go:238] Clock(Realtime): error: -17 ns, adjusted frequency from 2299774533 Hz to 2299774569 Hz D0229 22:10:59.623213 58032 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8983385114156227 monotonicBaseRef:79000286873 monotonicFrequency:2299774785 realtimeReady:1 realtimeBaseCycles:8983385114677643 realtimeBaseRef:1583014259623106723 realtimeFrequency:2299774569} I0229 22:10:59.778975 58032 loader.go:566] Received external signal 23, mode: Process D0229 22:10:59.779257 58032 task_signals.go:446] [ 1] Notified of signal 23 D0229 22:10:59.779431 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:10:59.779572 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:10:59.779804 58032 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 22:10:59.779984 58032 task_signals.go:220] [ 1] Signal 23: delivering to handler D0229 22:11:00.622947 58032 parameters.go:238] Clock(Monotonic): error: 29 ns, adjusted frequency from 2299774785 Hz to 2299774697 Hz I0229 22:11:00.623156 58032 loader.go:566] Received external signal 23, mode: Process D0229 22:11:00.623194 58032 parameters.go:238] Clock(Realtime): error: -152 ns, adjusted frequency from 2299774569 Hz to 2299774356 Hz D0229 22:11:00.623408 58032 task_signals.go:446] [ 1] Notified of signal 23 D0229 22:11:00.623503 58032 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8983387414035702 monotonicBaseRef:80000332394 monotonicFrequency:2299774697 realtimeReady:1 realtimeBaseCycles:8983387414634380 realtimeBaseRef:1583014260623185934 realtimeFrequency:2299774356} D0229 22:11:00.623591 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:11:00.623690 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:11:00.623960 58032 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 22:11:00.624095 58032 task_signals.go:220] [ 1] Signal 23: delivering to handler D0229 22:11:01.623577 58032 parameters.go:238] Clock(Monotonic): error: 144 ns, adjusted frequency from 2299774697 Hz to 2299775131 Hz D0229 22:11:01.623802 58032 parameters.go:238] Clock(Realtime): error: 107 ns, adjusted frequency from 2299774356 Hz to 2299774813 Hz D0229 22:11:01.623986 58032 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8983389715271054 monotonicBaseRef:81000967523 monotonicFrequency:2299775131 realtimeReady:1 realtimeBaseCycles:8983389715810254 realtimeBaseRef:1583014261623795349 realtimeFrequency:2299774813} D0229 22:11:02.622940 58032 parameters.go:238] Clock(Monotonic): error: -14 ns, adjusted frequency from 2299775131 Hz to 2299774615 Hz D0229 22:11:02.623163 58032 parameters.go:238] Clock(Realtime): error: 302 ns, adjusted frequency from 2299774813 Hz to 2299775319 Hz D0229 22:11:02.623295 58032 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8983392013572088 monotonicBaseRef:82000326548 monotonicFrequency:2299774615 realtimeReady:1 realtimeBaseCycles:8983392014116266 realtimeBaseRef:1583014262623156677 realtimeFrequency:2299775319} I0229 22:11:03.108824 60882 main.go:305] *************************** I0229 22:11:03.108979 60882 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 /syzkaller387441011] I0229 22:11:03.109410 60882 main.go:307] Version release-20200219.0-72-gce4d1e45bb88 I0229 22:11:03.109512 60882 main.go:308] PID: 60882 I0229 22:11:03.109600 60882 main.go:309] UID: 0, GID: 0 I0229 22:11:03.109687 60882 main.go:310] Configuration: I0229 22:11:03.109784 60882 main.go:311] RootDir: /syzkaller/managers/ptrace-proxy-sandbox-race/workdir/gvisor_root I0229 22:11:03.109862 60882 main.go:312] Platform: ptrace I0229 22:11:03.109946 60882 main.go:313] FileAccess: shared, overlay: false I0229 22:11:03.110103 60882 main.go:314] Network: sandbox, logging: false I0229 22:11:03.110218 60882 main.go:315] Strace: false, max size: 1024, syscalls: [] I0229 22:11:03.110347 60882 main.go:316] *************************** W0229 22:11:03.110444 60882 main.go:321] Block the TERM signal. This is only safe in tests! D0229 22:11:03.110796 60882 container.go:159] Load container "/syzkaller/managers/ptrace-proxy-sandbox-race/workdir/gvisor_root" "ci-gvisor-ptrace-proxy-sandbox-race-2" D0229 22:11:03.118007 60882 container.go:592] Signal container "ci-gvisor-ptrace-proxy-sandbox-race-2": signal 0 D0229 22:11:03.118166 60882 sandbox.go:806] Signal sandbox "ci-gvisor-ptrace-proxy-sandbox-race-2" D0229 22:11:03.118285 60882 sandbox.go:318] Connecting to sandbox "ci-gvisor-ptrace-proxy-sandbox-race-2" D0229 22:11:03.118711 60882 urpc.go:534] urpc: successfully marshalled 117 bytes. I0229 22:11:03.118768 58032 loader.go:566] Received external signal 23, mode: Process D0229 22:11:03.118974 58032 task_signals.go:446] [ 1] Notified of signal 23 D0229 22:11:03.119106 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:11:03.119252 58032 task_block.go:223] [ 1] Interrupt queued I0229 22:11:03.119280 58032 loader.go:566] Received external signal 23, mode: Process D0229 22:11:03.119444 58032 urpc.go:577] urpc: unmarshal success. D0229 22:11:03.119456 58032 task_signals.go:434] [ 1] Discarding duplicate signal 23 D0229 22:11:03.119642 58032 controller.go:501] containerManager.Signal &{CID:ci-gvisor-ptrace-proxy-sandbox-race-2 Signo:0 PID:0 Mode:Process} D0229 22:11:03.119698 58032 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 22:11:03.119909 58032 urpc.go:534] urpc: successfully marshalled 37 bytes. D0229 22:11:03.119891 58032 task_signals.go:220] [ 1] Signal 23: delivering to handler D0229 22:11:03.120242 60882 urpc.go:577] urpc: unmarshal success. D0229 22:11:03.120431 60882 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 /syzkaller387441011 D0229 22:11:03.120544 60882 exec.go:121] Exec capablities: &{PermittedCaps:274877898751 InheritableCaps:274877898751 EffectiveCaps:274877898751 BoundingCaps:274877898751 AmbientCaps:0} D0229 22:11:03.120697 60882 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 /syzkaller387441011 D0229 22:11:03.120855 60882 sandbox.go:283] Executing new process in container "ci-gvisor-ptrace-proxy-sandbox-race-2" in sandbox "ci-gvisor-ptrace-proxy-sandbox-race-2" D0229 22:11:03.121014 60882 sandbox.go:318] Connecting to sandbox "ci-gvisor-ptrace-proxy-sandbox-race-2" D0229 22:11:03.121971 60882 urpc.go:534] urpc: successfully marshalled 634 bytes. D0229 22:11:03.122269 58032 urpc.go:577] urpc: unmarshal success. D0229 22:11:03.123511 58032 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 /syzkaller387441011 D0229 22:11:03.123777 58032 transport_flipcall.go:127] send [channel @0xc0003303c0] Twalkgetattr{FID: 1, NewFID: 5, Names: [etc]} D0229 22:11:03.124473 58032 transport_flipcall.go:234] recv [channel @0xc0003303c0] Rlerror{Error: 2} D0229 22:11:03.124647 58032 transport_flipcall.go:127] send [channel @0xc0003303c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.etc, Size: 1} D0229 22:11:03.125116 58032 transport_flipcall.go:234] recv [channel @0xc0003303c0] Rlerror{Error: 95} I0229 22:11:03.125582 58032 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 /syzkaller387441011] D0229 22:11:03.126457 58032 transport_flipcall.go:127] send [channel @0xc0003303c0] Twalkgetattr{FID: 1, NewFID: 5, Names: [syz-execprog]} D0229 22:11:03.127969 58032 transport_flipcall.go:234] recv [channel @0xc0003303c0] 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: 1583014181, NanoSec: 846450532}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}, QIDs: [QID{Type: 0, Version: 0, Path: 16384268}]} D0229 22:11:03.128196 58032 transport_flipcall.go:127] send [channel @0xc0003303c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syz-execprog, Size: 1} D0229 22:11:03.128756 58032 transport_flipcall.go:234] recv [channel @0xc0003303c0] Rlerror{Error: 95} D0229 22:11:03.129030 58032 transport_flipcall.go:127] send [channel @0xc0003303c0] Tgetattr{FID: 5, AttrMask: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime INo Size Blocks BTime Gen DataVersion}} D0229 22:11:03.129899 58032 transport_flipcall.go:234] recv [channel @0xc0003303c0] Rgetattr{Valid: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime Size Blocks}, QID: QID{Type: 0, Version: 0, Path: 16384268}, 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: 1583014181, NanoSec: 846450532}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}} D0229 22:11:03.130120 58032 transport_flipcall.go:127] send [channel @0xc0003303c0] Twalk{FID: 5, NewFID: 6, Names: []} D0229 22:11:03.130816 58032 transport_flipcall.go:234] recv [channel @0xc0003303c0] Rwalk{QIDs: []} D0229 22:11:03.131015 58032 transport_flipcall.go:127] send [channel @0xc0003303c0] Tlopen{FID: 6, Flags: ReadOnly} D0229 22:11:03.131784 58032 transport_flipcall.go:234] recv [channel @0xc0003303c0] Rlopen{QID: QID{Type: 0, Version: 0, Path: 16384268}, IoUnit: 0, File: &{{36}}} I0229 22:11:03.134600 58032 loader.go:566] Received external signal 23, mode: Process D0229 22:11:03.135641 58032 syscalls.go:266] Allocating stack with size of 8388608 bytes D0229 22:11:03.137299 58032 loader.go:832] updated processes: map[{ci-gvisor-ptrace-proxy-sandbox-race-2 0}:0xc000306ae0 {ci-gvisor-ptrace-proxy-sandbox-race-2 22}:0xc00000ff00] D0229 22:11:03.137702 58032 urpc.go:534] urpc: successfully marshalled 37 bytes. D0229 22:11:03.137732 58032 task_signals.go:446] [ 1] Notified of signal 23 D0229 22:11:03.137855 60882 urpc.go:577] urpc: unmarshal success. D0229 22:11:03.137890 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:11:03.137998 60882 container.go:580] Wait on PID 22 in container "ci-gvisor-ptrace-proxy-sandbox-race-2" I0229 22:11:03.138000 58032 loader.go:566] Received external signal 23, mode: Process D0229 22:11:03.137992 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:11:03.138113 58032 task_signals.go:434] [ 1] Discarding duplicate signal 23 D0229 22:11:03.138124 60882 sandbox.go:761] Waiting for PID 22 in sandbox "ci-gvisor-ptrace-proxy-sandbox-race-2" D0229 22:11:03.138219 58032 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 22:11:03.138237 60882 sandbox.go:318] Connecting to sandbox "ci-gvisor-ptrace-proxy-sandbox-race-2" D0229 22:11:03.138363 58032 task_signals.go:220] [ 1] Signal 23: delivering to handler D0229 22:11:03.138582 60882 urpc.go:534] urpc: successfully marshalled 100 bytes. D0229 22:11:03.139013 58032 urpc.go:577] urpc: unmarshal success. D0229 22:11:03.139273 58032 controller.go:443] containerManager.Wait D0229 22:11:03.142663 58032 task_block.go:223] [ 22] Interrupt queued I0229 22:11:03.202993 58032 loader.go:566] Received external signal 23, mode: Process D0229 22:11:03.203309 58032 task_signals.go:446] [ 1] Notified of signal 23 D0229 22:11:03.203466 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:11:03.203605 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:11:03.203816 58032 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 22:11:03.203967 58032 task_signals.go:220] [ 1] Signal 23: delivering to handler I0229 22:11:03.204816 58032 loader.go:566] Received external signal 23, mode: Process D0229 22:11:03.205627 58032 task_signals.go:446] [ 1] Notified of signal 23 D0229 22:11:03.208702 58032 task_block.go:223] [ 1] Interrupt queued I0229 22:11:03.208857 58032 loader.go:566] Received external signal 23, mode: Process D0229 22:11:03.208902 58032 task_block.go:223] [ 2] Interrupt queued D0229 22:11:03.208937 58032 task_block.go:223] [ 2] Interrupt queued D0229 22:11:03.209080 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:11:03.209307 58032 task_signals.go:434] [ 1] Discarding duplicate signal 23 D0229 22:11:03.209563 58032 task_signals.go:176] [ 2] Not restarting syscall 202 after errno 516: interrupted by signal 23 D0229 22:11:03.209695 58032 task_signals.go:220] [ 2] Signal 23: delivering to handler I0229 22:11:03.341134 58032 loader.go:566] Received external signal 23, mode: Process D0229 22:11:03.341334 58032 task_signals.go:446] [ 1] Notified of signal 23 D0229 22:11:03.341454 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:11:03.341584 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:11:03.341758 58032 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 22:11:03.341871 58032 task_signals.go:220] [ 1] Signal 23: delivering to handler D0229 22:11:03.351996 58032 task_block.go:223] [ 23] Interrupt queued D0229 22:11:03.355134 58032 task_block.go:223] [ 24] Interrupt queued D0229 22:11:03.355913 58032 task_block.go:223] [ 25] Interrupt queued D0229 22:11:03.364277 58032 task_block.go:223] [ 26] Interrupt queued D0229 22:11:03.377998 58032 task_block.go:223] [ 27] Interrupt queued D0229 22:11:03.381555 58032 task_block.go:223] [ 28] Interrupt queued D0229 22:11:03.422785 58032 transport_flipcall.go:127] send [channel @0xc0003303c0] Twalkgetattr{FID: 1, NewFID: 7, Names: [etc]} D0229 22:11:03.423388 58032 transport_flipcall.go:234] recv [channel @0xc0003303c0] Rlerror{Error: 2} D0229 22:11:03.423554 58032 transport_flipcall.go:127] send [channel @0xc0003303c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.etc, Size: 1} D0229 22:11:03.423999 58032 transport_flipcall.go:234] recv [channel @0xc0003303c0] Rlerror{Error: 95} D0229 22:11:03.449594 58032 task_block.go:223] [ 29] Interrupt queued D0229 22:11:03.452764 58032 urpc.go:577] urpc: unmarshal success. D0229 22:11:03.453003 58032 controller.go:501] containerManager.Signal &{CID:ci-gvisor-ptrace-proxy-sandbox-race-2 Signo:0 PID:0 Mode:Process} D0229 22:11:03.453337 58032 urpc.go:534] urpc: successfully marshalled 37 bytes. D0229 22:11:03.460332 58032 task_block.go:223] [ 30] Interrupt queued D0229 22:11:03.473706 58032 task_block.go:223] [ 31] Interrupt queued D0229 22:11:03.478455 58032 task_block.go:223] [ 32] Interrupt queued D0229 22:11:03.482971 58032 task_block.go:223] [ 33] Interrupt queued D0229 22:11:03.509598 58032 task_block.go:223] [ 34] Interrupt queued D0229 22:11:03.515037 58032 task_block.go:223] [ 35] Interrupt queued I0229 22:11:03.529250 58032 loader.go:566] Received external signal 23, mode: Process D0229 22:11:03.529480 58032 task_signals.go:446] [ 1] Notified of signal 23 D0229 22:11:03.529602 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:11:03.529755 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:11:03.529951 58032 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 22:11:03.530070 58032 task_signals.go:220] [ 1] Signal 23: delivering to handler D0229 22:11:03.543019 58032 task_block.go:223] [ 36] Interrupt queued I0229 22:11:03.550294 58032 loader.go:566] Received external signal 23, mode: Process D0229 22:11:03.551108 58032 task_signals.go:446] [ 1] Notified of signal 23 D0229 22:11:03.552150 58032 task_block.go:223] [ 37] Interrupt queued D0229 22:11:03.552338 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:11:03.552368 58032 transport_flipcall.go:127] send [channel @0xc0003303c0] Twalkgetattr{FID: 1, NewFID: 7, Names: [syzkaller387441011]} D0229 22:11:03.552455 58032 task_block.go:223] [ 1] Interrupt queued I0229 22:11:03.552569 58032 loader.go:566] Received external signal 23, mode: Process D0229 22:11:03.552647 58032 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 22:11:03.552725 58032 task_signals.go:446] [ 1] Notified of signal 23 D0229 22:11:03.552765 58032 task_signals.go:220] [ 1] Signal 23: delivering to handler D0229 22:11:03.552836 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:11:03.553250 58032 task_block.go:223] [ 2] Interrupt queued D0229 22:11:03.553379 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:11:03.553398 58032 task_block.go:223] [ 2] Interrupt queued D0229 22:11:03.553509 58032 transport_flipcall.go:234] recv [channel @0xc0003303c0] 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: 354, BlockSize: 4096, Blocks: 8, ATime: {Sec: 1583014263, NanoSec: 78410407}, MTime: {Sec: 1583014263, NanoSec: 78410407}, CTime: {Sec: 1583014263, NanoSec: 78410407}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}, QIDs: [QID{Type: 0, Version: 0, Path: 16384232}]} D0229 22:11:03.553584 58032 task_signals.go:176] [ 2] Not restarting syscall 202 after errno 516: interrupted by signal 23 D0229 22:11:03.553726 58032 task_signals.go:220] [ 2] Signal 23: delivering to handler D0229 22:11:03.553777 58032 transport_flipcall.go:127] send [channel @0xc0003303c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzkaller387441011, Size: 1} D0229 22:11:03.554326 58032 transport_flipcall.go:234] recv [channel @0xc0003303c0] Rlerror{Error: 95} D0229 22:11:03.554654 58032 transport_flipcall.go:127] send [channel @0xc0003303c0] Tgetattr{FID: 7, AttrMask: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime INo Size Blocks BTime Gen DataVersion}} D0229 22:11:03.555586 58032 transport_flipcall.go:234] recv [channel @0xc0003303c0] Rgetattr{Valid: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime Size Blocks}, QID: QID{Type: 0, Version: 0, Path: 16384232}, Attr: Attr{Mode: 0o100600, UID: 0, GID: 0, NLink: 1, RDev: 0, Size: 354, BlockSize: 4096, Blocks: 8, ATime: {Sec: 1583014263, NanoSec: 78410407}, MTime: {Sec: 1583014263, NanoSec: 78410407}, CTime: {Sec: 1583014263, NanoSec: 78410407}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}} D0229 22:11:03.555762 58032 transport_flipcall.go:127] send [channel @0xc0003303c0] Twalk{FID: 7, NewFID: 8, Names: []} D0229 22:11:03.556388 58032 transport_flipcall.go:234] recv [channel @0xc0003303c0] Rwalk{QIDs: []} D0229 22:11:03.556533 58032 transport_flipcall.go:127] send [channel @0xc0003303c0] Tlopen{FID: 8, Flags: ReadOnly} D0229 22:11:03.557430 58032 transport_flipcall.go:234] recv [channel @0xc0003303c0] Rlopen{QID: QID{Type: 0, Version: 0, Path: 16384232}, IoUnit: 0, File: &{{30}}} D0229 22:11:03.559473 58032 transport_flipcall.go:127] send [channel @0xc0003303c0] Tgetattr{FID: 8, AttrMask: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime INo Size Blocks BTime Gen DataVersion}} D0229 22:11:03.560280 58032 transport_flipcall.go:234] recv [channel @0xc0003303c0] Rgetattr{Valid: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime Size Blocks}, QID: QID{Type: 0, Version: 0, Path: 16384232}, Attr: Attr{Mode: 0o100600, UID: 0, GID: 0, NLink: 1, RDev: 0, Size: 354, BlockSize: 4096, Blocks: 8, ATime: {Sec: 1583014263, NanoSec: 78410407}, MTime: {Sec: 1583014263, NanoSec: 78410407}, CTime: {Sec: 1583014263, NanoSec: 78410407}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}} 2020/02/29 22:11:03 parsed 1 programs I0229 22:11:03.565791 58032 loader.go:566] Received external signal 23, mode: Process D0229 22:11:03.566001 58032 task_signals.go:446] [ 1] Notified of signal 23 D0229 22:11:03.566097 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:11:03.566249 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:11:03.566434 58032 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 22:11:03.566523 58032 task_signals.go:220] [ 1] Signal 23: delivering to handler D0229 22:11:03.574953 58032 task_stop.go:118] [ 31] Entering internal stop (*kernel.vforkStop)(nil) D0229 22:11:03.575700 58032 task_block.go:223] [ 38] Interrupt queued I0229 22:11:03.586081 58032 loader.go:566] Received external signal 23, mode: Process D0229 22:11:03.586290 58032 task_signals.go:446] [ 1] Notified of signal 23 D0229 22:11:03.586408 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:11:03.586511 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:11:03.586923 58032 transport_flipcall.go:127] send [channel @0xc0003303c0] Twalkgetattr{FID: 1, NewFID: 9, Names: [syz-executor]} D0229 22:11:03.586782 58032 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 22:11:03.587790 58032 task_signals.go:220] [ 1] Signal 23: delivering to handler D0229 22:11:03.588387 58032 transport_flipcall.go:234] recv [channel @0xc0003303c0] 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: 1583014181, NanoSec: 850450924}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}, QIDs: [QID{Type: 0, Version: 0, Path: 16384279}]} D0229 22:11:03.588627 58032 transport_flipcall.go:127] send [channel @0xc0003303c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syz-executor, Size: 1} D0229 22:11:03.589118 58032 transport_flipcall.go:234] recv [channel @0xc0003303c0] Rlerror{Error: 95} D0229 22:11:03.589510 58032 transport_flipcall.go:127] send [channel @0xc0003303c0] Tgetattr{FID: 9, AttrMask: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime INo Size Blocks BTime Gen DataVersion}} D0229 22:11:03.590569 58032 transport_flipcall.go:234] recv [channel @0xc0003303c0] Rgetattr{Valid: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime Size Blocks}, QID: QID{Type: 0, Version: 0, Path: 16384279}, 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: 1583014181, NanoSec: 850450924}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}} D0229 22:11:03.590749 58032 transport_flipcall.go:127] send [channel @0xc0003303c0] Twalk{FID: 9, NewFID: 10, Names: []} D0229 22:11:03.591478 58032 transport_flipcall.go:234] recv [channel @0xc0003303c0] Rwalk{QIDs: []} D0229 22:11:03.591602 58032 transport_flipcall.go:127] send [channel @0xc0003303c0] Tlopen{FID: 10, Flags: ReadOnly} D0229 22:11:03.592469 58032 transport_flipcall.go:234] recv [channel @0xc0003303c0] Rlopen{QID: QID{Type: 0, Version: 0, Path: 16384279}, IoUnit: 0, File: &{{31}}} D0229 22:11:03.593538 58032 syscalls.go:266] [ 38] Allocating stack with size of 8388608 bytes D0229 22:11:03.594459 58032 task_stop.go:137] [ 31] Leaving internal stop (*kernel.vforkStop)(nil) I0229 22:11:03.616297 58032 loader.go:566] Received external signal 23, mode: Process D0229 22:11:03.616519 58032 task_signals.go:446] [ 1] Notified of signal 23 D0229 22:11:03.616655 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:11:03.616605 58032 transport_flipcall.go:127] send [channel @0xc0003303c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [etc]} D0229 22:11:03.616705 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:11:03.616938 58032 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 22:11:03.617074 58032 task_signals.go:220] [ 1] Signal 23: delivering to handler D0229 22:11:03.617377 58032 transport_flipcall.go:234] recv [channel @0xc0003303c0] Rlerror{Error: 2} D0229 22:11:03.617635 58032 transport_flipcall.go:127] send [channel @0xc0003303c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.etc, Size: 1} D0229 22:11:03.618382 58032 transport_flipcall.go:234] recv [channel @0xc0003303c0] Rlerror{Error: 95} D0229 22:11:03.620508 58032 task_exit.go:221] [ 38] Transitioning from exit state TaskExitNone to TaskExitInitiated D0229 22:11:03.621852 58032 task_exit.go:221] [ 38] Transitioning from exit state TaskExitInitiated to TaskExitZombie D0229 22:11:03.622045 58032 task_signals.go:446] [ 22] Notified of signal 17 D0229 22:11:03.622211 58032 task_block.go:223] [ 22] Interrupt queued D0229 22:11:03.622367 58032 task_block.go:223] [ 22] Interrupt queued D0229 22:11:03.622572 58032 task_signals.go:179] [ 22] Restarting syscall 202 after errno 512: interrupted by signal 17 D0229 22:11:03.622664 58032 task_signals.go:220] [ 22] Signal 17: delivering to handler D0229 22:11:03.622919 58032 task_exit.go:221] [ 38] Transitioning from exit state TaskExitZombie to TaskExitDead D0229 22:11:03.623107 58032 parameters.go:238] Clock(Monotonic): error: -266 ns, adjusted frequency from 2299774615 Hz to 2299773993 Hz D0229 22:11:03.623359 58032 parameters.go:238] Clock(Realtime): error: -184 ns, adjusted frequency from 2299775319 Hz to 2299774187 Hz D0229 22:11:03.623471 58032 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8983394313751103 monotonicBaseRef:83000502391 monotonicFrequency:2299773993 realtimeReady:1 realtimeBaseCycles:8983394314342147 realtimeBaseRef:1583014263623352592 realtimeFrequency:2299774187} D0229 22:11:03.624612 58032 task_block.go:223] [ 39] Interrupt queued D0229 22:11:03.629084 58032 transport_flipcall.go:127] send [channel @0xc0003303c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syz-executor]} D0229 22:11:03.630370 58032 transport_flipcall.go:234] recv [channel @0xc0003303c0] 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: 1583014181, NanoSec: 850450924}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}, QIDs: [QID{Type: 0, Version: 0, Path: 16384279}]} D0229 22:11:03.630532 58032 transport_flipcall.go:127] send [channel @0xc0003303c0] Tclunk{FID: 11} D0229 22:11:03.631059 58032 transport_flipcall.go:234] recv [channel @0xc0003303c0] Rclunk{} D0229 22:11:03.631334 58032 transport_flipcall.go:127] send [channel @0xc0003303c0] Tgetattr{FID: 9, AttrMask: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime INo Size Blocks BTime Gen DataVersion}} D0229 22:11:03.632547 58032 transport_flipcall.go:234] recv [channel @0xc0003303c0] Rgetattr{Valid: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime Size Blocks}, QID: QID{Type: 0, Version: 0, Path: 16384279}, 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: 1583014181, NanoSec: 850450924}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}} 2020/02/29 22:11:03 executed programs: 0 D0229 22:11:03.640194 58032 task_stop.go:118] [ 37] Entering internal stop (*kernel.vforkStop)(nil) D0229 22:11:03.640846 58032 task_block.go:223] [ 40] Interrupt queued D0229 22:11:03.645446 58032 transport_flipcall.go:127] send [channel @0xc0003303c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syz-executor]} D0229 22:11:03.646821 58032 transport_flipcall.go:234] recv [channel @0xc0003303c0] 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: 1583014181, NanoSec: 850450924}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}, QIDs: [QID{Type: 0, Version: 0, Path: 16384279}]} D0229 22:11:03.647220 58032 transport_flipcall.go:127] send [channel @0xc0003303c0] Tclunk{FID: 11} D0229 22:11:03.647762 58032 transport_flipcall.go:234] recv [channel @0xc0003303c0] Rclunk{} D0229 22:11:03.647996 58032 transport_flipcall.go:127] send [channel @0xc0003303c0] Tgetattr{FID: 9, AttrMask: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime INo Size Blocks BTime Gen DataVersion}} D0229 22:11:03.649044 58032 transport_flipcall.go:234] recv [channel @0xc0003303c0] Rgetattr{Valid: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime Size Blocks}, QID: QID{Type: 0, Version: 0, Path: 16384279}, 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: 1583014181, NanoSec: 850450924}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}} D0229 22:11:03.650457 58032 syscalls.go:266] [ 40] Allocating stack with size of 8388608 bytes D0229 22:11:03.651533 58032 task_stop.go:137] [ 37] Leaving internal stop (*kernel.vforkStop)(nil) I0229 22:11:03.658652 58032 loader.go:566] Received external signal 23, mode: Process D0229 22:11:03.658995 58032 task_signals.go:446] [ 1] Notified of signal 23 D0229 22:11:03.659301 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:11:03.659233 58032 task_block.go:223] [ 1] Interrupt queued I0229 22:11:03.660851 58032 loader.go:566] Received external signal 23, mode: Process D0229 22:11:03.661011 58032 task_signals.go:446] [ 1] Notified of signal 23 D0229 22:11:03.660993 58032 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 22:11:03.661129 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:11:03.661166 58032 task_signals.go:220] [ 1] Signal 23: delivering to handler D0229 22:11:03.661593 58032 task_block.go:223] [ 2] Interrupt queued D0229 22:11:03.661732 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:11:03.661657 58032 task_block.go:223] [ 2] Interrupt queued D0229 22:11:03.662073 58032 task_signals.go:176] [ 2] Not restarting syscall 202 after errno 516: interrupted by signal 23 D0229 22:11:03.662171 58032 task_signals.go:220] [ 2] Signal 23: delivering to handler D0229 22:11:03.664514 58032 transport_flipcall.go:127] send [channel @0xc0003303c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [etc]} D0229 22:11:03.665094 58032 transport_flipcall.go:234] recv [channel @0xc0003303c0] Rlerror{Error: 2} D0229 22:11:03.665340 58032 transport_flipcall.go:127] send [channel @0xc0003303c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.etc, Size: 1} D0229 22:11:03.665813 58032 transport_flipcall.go:234] recv [channel @0xc0003303c0] Rlerror{Error: 95} D0229 22:11:03.676857 58032 task_block.go:223] [ 41] Interrupt queued D0229 22:11:03.680626 58032 transport_flipcall.go:127] send [channel @0xc0003303c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0229 22:11:03.681317 58032 transport_flipcall.go:234] recv [channel @0xc0003303c0] Rlerror{Error: 2} D0229 22:11:03.681491 58032 transport_flipcall.go:127] send [channel @0xc0003303c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0229 22:11:03.682047 58032 transport_flipcall.go:234] recv [channel @0xc0003303c0] Rlerror{Error: 95} D0229 22:11:03.682407 58032 transport_flipcall.go:127] send [channel @0xc0003303c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0229 22:11:03.682996 58032 transport_flipcall.go:234] recv [channel @0xc0003303c0] Rlerror{Error: 2} D0229 22:11:03.683168 58032 transport_flipcall.go:127] send [channel @0xc0003303c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0229 22:11:03.683784 58032 transport_flipcall.go:234] recv [channel @0xc0003303c0] Rlerror{Error: 95} D0229 22:11:03.684798 58032 transport_flipcall.go:127] send [channel @0xc0003303c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0229 22:11:03.685482 58032 transport_flipcall.go:234] recv [channel @0xc0003303c0] Rlerror{Error: 2} D0229 22:11:03.685626 58032 transport_flipcall.go:127] send [channel @0xc0003303c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0229 22:11:03.686152 58032 transport_flipcall.go:234] recv [channel @0xc0003303c0] Rlerror{Error: 95} D0229 22:11:03.686530 58032 transport_flipcall.go:127] send [channel @0xc0003303c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0229 22:11:03.687157 58032 transport_flipcall.go:234] recv [channel @0xc0003303c0] Rlerror{Error: 2} D0229 22:11:03.687323 58032 transport_flipcall.go:127] send [channel @0xc0003303c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0229 22:11:03.687910 58032 transport_flipcall.go:234] recv [channel @0xc0003303c0] Rlerror{Error: 95} D0229 22:11:03.688262 58032 transport_flipcall.go:127] send [channel @0xc0003303c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0229 22:11:03.688908 58032 transport_flipcall.go:234] recv [channel @0xc0003303c0] Rlerror{Error: 2} D0229 22:11:03.689058 58032 transport_flipcall.go:127] send [channel @0xc0003303c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0229 22:11:03.689558 58032 transport_flipcall.go:234] recv [channel @0xc0003303c0] Rlerror{Error: 95} D0229 22:11:03.690107 58032 transport_flipcall.go:127] send [channel @0xc0003303c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0229 22:11:03.690759 58032 transport_flipcall.go:234] recv [channel @0xc0003303c0] Rlerror{Error: 2} D0229 22:11:03.690954 58032 transport_flipcall.go:127] send [channel @0xc0003303c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0229 22:11:03.691396 58032 transport_flipcall.go:234] recv [channel @0xc0003303c0] Rlerror{Error: 95} D0229 22:11:03.691798 58032 transport_flipcall.go:127] send [channel @0xc0003303c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0229 22:11:03.692433 58032 transport_flipcall.go:234] recv [channel @0xc0003303c0] Rlerror{Error: 2} D0229 22:11:03.692579 58032 transport_flipcall.go:127] send [channel @0xc0003303c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0229 22:11:03.692977 58032 transport_flipcall.go:234] recv [channel @0xc0003303c0] Rlerror{Error: 95} D0229 22:11:03.693320 58032 transport_flipcall.go:127] send [channel @0xc0003303c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0229 22:11:03.693951 58032 transport_flipcall.go:234] recv [channel @0xc0003303c0] Rlerror{Error: 2} D0229 22:11:03.694115 58032 transport_flipcall.go:127] send [channel @0xc0003303c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0229 22:11:03.694629 58032 transport_flipcall.go:234] recv [channel @0xc0003303c0] Rlerror{Error: 95} D0229 22:11:03.695291 58032 transport_flipcall.go:127] send [channel @0xc0003303c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0229 22:11:03.695870 58032 transport_flipcall.go:234] recv [channel @0xc0003303c0] Rlerror{Error: 2} D0229 22:11:03.696111 58032 transport_flipcall.go:127] send [channel @0xc0003303c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0229 22:11:03.696539 58032 transport_flipcall.go:234] recv [channel @0xc0003303c0] Rlerror{Error: 95} I0229 22:11:03.710791 58032 loader.go:566] Received external signal 23, mode: Process D0229 22:11:03.710990 58032 task_signals.go:446] [ 1] Notified of signal 23 D0229 22:11:03.711195 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:11:03.711384 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:11:03.711586 58032 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 22:11:03.711764 58032 task_signals.go:220] [ 1] Signal 23: delivering to handler I0229 22:11:03.731818 58032 loader.go:566] Received external signal 23, mode: Process D0229 22:11:03.732062 58032 task_signals.go:446] [ 1] Notified of signal 23 D0229 22:11:03.732214 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:11:03.732316 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:11:03.732619 58032 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 22:11:03.732779 58032 task_signals.go:220] [ 1] Signal 23: delivering to handler D0229 22:11:03.733981 58032 netfilter.go:54] netfilter: convert to binary: current offset: 0 D0229 22:11:03.734102 58032 netfilter.go:54] netfilter: convert to binary: found hook 0 at offset 0 D0229 22:11:03.734193 58032 netfilter.go:54] netfilter: convert to binary: found underflow 0 at offset 0 D0229 22:11:03.734281 58032 netfilter.go:54] netfilter: convert to binary: marshalling standard target D0229 22:11:03.734399 58032 netfilter.go:54] netfilter: convert to binary: adding entry: {IPTEntry:{IP:{Src:[0 0 0 0] Dst:[0 0 0 0] SrcMask:[0 0 0 0] DstMask:[0 0 0 0] InputInterface:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] OutputInterface:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] InputInterfaceMask:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] OutputInterfaceMask:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] Protocol:0 Flags:0 InverseFlags:0} NFCache:0 TargetOffset:112 NextOffset:152 Comeback:0 Counters:{Pcnt:0 Bcnt:0}} Elems:[40 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 254 255 255 255 0 0 0 0]} D0229 22:11:03.735090 58032 netfilter.go:54] netfilter: convert to binary: current offset: 152 D0229 22:11:03.735178 58032 netfilter.go:54] netfilter: convert to binary: found hook 1 at offset 152 D0229 22:11:03.735292 58032 netfilter.go:54] netfilter: convert to binary: found underflow 1 at offset 152 D0229 22:11:03.735410 58032 netfilter.go:54] netfilter: convert to binary: marshalling standard target D0229 22:11:03.735528 58032 netfilter.go:54] netfilter: convert to binary: adding entry: {IPTEntry:{IP:{Src:[0 0 0 0] Dst:[0 0 0 0] SrcMask:[0 0 0 0] DstMask:[0 0 0 0] InputInterface:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] OutputInterface:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] InputInterfaceMask:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] OutputInterfaceMask:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] Protocol:0 Flags:0 InverseFlags:0} NFCache:0 TargetOffset:112 NextOffset:152 Comeback:0 Counters:{Pcnt:0 Bcnt:0}} Elems:[40 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 254 255 255 255 0 0 0 0]} D0229 22:11:03.736325 58032 netfilter.go:54] netfilter: convert to binary: current offset: 304 D0229 22:11:03.736445 58032 netfilter.go:54] netfilter: convert to binary: found hook 3 at offset 304 D0229 22:11:03.736548 58032 netfilter.go:54] netfilter: convert to binary: found underflow 3 at offset 304 D0229 22:11:03.736657 58032 netfilter.go:54] netfilter: convert to binary: marshalling standard target D0229 22:11:03.736773 58032 netfilter.go:54] netfilter: convert to binary: adding entry: {IPTEntry:{IP:{Src:[0 0 0 0] Dst:[0 0 0 0] SrcMask:[0 0 0 0] DstMask:[0 0 0 0] InputInterface:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] OutputInterface:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] InputInterfaceMask:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] OutputInterfaceMask:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] Protocol:0 Flags:0 InverseFlags:0} NFCache:0 TargetOffset:112 NextOffset:152 Comeback:0 Counters:{Pcnt:0 Bcnt:0}} Elems:[40 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 254 255 255 255 0 0 0 0]} D0229 22:11:03.737498 58032 netfilter.go:54] netfilter: convert to binary: current offset: 456 D0229 22:11:03.737608 58032 netfilter.go:54] netfilter: convert to binary: found hook 4 at offset 456 D0229 22:11:03.737739 58032 netfilter.go:54] netfilter: convert to binary: found underflow 4 at offset 456 D0229 22:11:03.737841 58032 netfilter.go:54] netfilter: convert to binary: marshalling standard target D0229 22:11:03.737981 58032 netfilter.go:54] netfilter: convert to binary: adding entry: {IPTEntry:{IP:{Src:[0 0 0 0] Dst:[0 0 0 0] SrcMask:[0 0 0 0] DstMask:[0 0 0 0] InputInterface:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] OutputInterface:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] InputInterfaceMask:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] OutputInterfaceMask:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] Protocol:0 Flags:0 InverseFlags:0} NFCache:0 TargetOffset:112 NextOffset:152 Comeback:0 Counters:{Pcnt:0 Bcnt:0}} Elems:[40 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 254 255 255 255 0 0 0 0]} D0229 22:11:03.738710 58032 netfilter.go:54] netfilter: convert to binary: current offset: 608 D0229 22:11:03.738892 58032 netfilter.go:54] netfilter: convert to binary: adding entry: {IPTEntry:{IP:{Src:[0 0 0 0] Dst:[0 0 0 0] SrcMask:[0 0 0 0] DstMask:[0 0 0 0] InputInterface:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] OutputInterface:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] InputInterfaceMask:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] OutputInterfaceMask:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] Protocol:0 Flags:0 InverseFlags:0} NFCache:0 TargetOffset:112 NextOffset:176 Comeback:0 Counters:{Pcnt:0 Bcnt:0}} Elems:[64 0 69 82 82 79 82 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 69 82 82 79 82 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0]} D0229 22:11:03.739671 58032 netfilter.go:54] netfilter: convert to binary: finished with an marshalled size of 0 D0229 22:11:03.739785 58032 netfilter.go:54] netfilter: convert to binary: current offset: 0 D0229 22:11:03.739939 58032 netfilter.go:54] netfilter: convert to binary: found hook 0 at offset 0 D0229 22:11:03.740072 58032 netfilter.go:54] netfilter: convert to binary: found underflow 0 at offset 0 D0229 22:11:03.740180 58032 netfilter.go:54] netfilter: convert to binary: marshalling standard target D0229 22:11:03.740291 58032 netfilter.go:54] netfilter: convert to binary: adding entry: {IPTEntry:{IP:{Src:[0 0 0 0] Dst:[0 0 0 0] SrcMask:[0 0 0 0] DstMask:[0 0 0 0] InputInterface:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] OutputInterface:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] InputInterfaceMask:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] OutputInterfaceMask:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] Protocol:0 Flags:0 InverseFlags:0} NFCache:0 TargetOffset:112 NextOffset:152 Comeback:0 Counters:{Pcnt:0 Bcnt:0}} Elems:[40 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 254 255 255 255 0 0 0 0]} D0229 22:11:03.740940 58032 netfilter.go:54] netfilter: convert to binary: current offset: 152 D0229 22:11:03.741061 58032 netfilter.go:54] netfilter: convert to binary: found hook 3 at offset 152 D0229 22:11:03.741155 58032 netfilter.go:54] netfilter: convert to binary: found underflow 3 at offset 152 D0229 22:11:03.741238 58032 netfilter.go:54] netfilter: convert to binary: marshalling standard target D0229 22:11:03.741380 58032 netfilter.go:54] netfilter: convert to binary: adding entry: {IPTEntry:{IP:{Src:[0 0 0 0] Dst:[0 0 0 0] SrcMask:[0 0 0 0] DstMask:[0 0 0 0] InputInterface:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] OutputInterface:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] InputInterfaceMask:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] OutputInterfaceMask:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] Protocol:0 Flags:0 InverseFlags:0} NFCache:0 TargetOffset:112 NextOffset:152 Comeback:0 Counters:{Pcnt:0 Bcnt:0}} Elems:[40 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 254 255 255 255 0 0 0 0]} D0229 22:11:03.742067 58032 netfilter.go:54] netfilter: convert to binary: current offset: 304 D0229 22:11:03.742243 58032 netfilter.go:54] netfilter: convert to binary: adding entry: {IPTEntry:{IP:{Src:[0 0 0 0] Dst:[0 0 0 0] SrcMask:[0 0 0 0] DstMask:[0 0 0 0] InputInterface:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] OutputInterface:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] InputInterfaceMask:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] OutputInterfaceMask:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] Protocol:0 Flags:0 InverseFlags:0} NFCache:0 TargetOffset:112 NextOffset:176 Comeback:0 Counters:{Pcnt:0 Bcnt:0}} Elems:[64 0 69 82 82 79 82 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 69 82 82 79 82 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0]} D0229 22:11:03.742990 58032 netfilter.go:54] netfilter: convert to binary: finished with an marshalled size of 0 D0229 22:11:03.743114 58032 netfilter.go:54] netfilter: convert to binary: current offset: 0 D0229 22:11:03.743168 58032 netfilter.go:54] netfilter: convert to binary: found hook 1 at offset 0 D0229 22:11:03.743239 58032 netfilter.go:54] netfilter: convert to binary: found underflow 1 at offset 0 D0229 22:11:03.743315 58032 netfilter.go:54] netfilter: convert to binary: marshalling standard target D0229 22:11:03.743375 58032 netfilter.go:54] netfilter: convert to binary: adding entry: {IPTEntry:{IP:{Src:[0 0 0 0] Dst:[0 0 0 0] SrcMask:[0 0 0 0] DstMask:[0 0 0 0] InputInterface:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] OutputInterface:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] InputInterfaceMask:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] OutputInterfaceMask:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] Protocol:0 Flags:0 InverseFlags:0} NFCache:0 TargetOffset:112 NextOffset:152 Comeback:0 Counters:{Pcnt:0 Bcnt:0}} Elems:[40 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 254 255 255 255 0 0 0 0]} D0229 22:11:03.743938 58032 netfilter.go:54] netfilter: convert to binary: current offset: 152 D0229 22:11:03.744059 58032 netfilter.go:54] netfilter: convert to binary: found hook 2 at offset 152 D0229 22:11:03.744216 58032 netfilter.go:54] netfilter: convert to binary: found underflow 2 at offset 152 D0229 22:11:03.744301 58032 netfilter.go:54] netfilter: convert to binary: marshalling standard target D0229 22:11:03.744425 58032 netfilter.go:54] netfilter: convert to binary: adding entry: {IPTEntry:{IP:{Src:[0 0 0 0] Dst:[0 0 0 0] SrcMask:[0 0 0 0] DstMask:[0 0 0 0] InputInterface:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] OutputInterface:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] InputInterfaceMask:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] OutputInterfaceMask:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] Protocol:0 Flags:0 InverseFlags:0} NFCache:0 TargetOffset:112 NextOffset:152 Comeback:0 Counters:{Pcnt:0 Bcnt:0}} Elems:[40 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 254 255 255 255 0 0 0 0]} D0229 22:11:03.745054 58032 netfilter.go:54] netfilter: convert to binary: current offset: 304 D0229 22:11:03.745137 58032 netfilter.go:54] netfilter: convert to binary: found hook 3 at offset 304 D0229 22:11:03.745238 58032 netfilter.go:54] netfilter: convert to binary: found underflow 3 at offset 304 D0229 22:11:03.745325 58032 netfilter.go:54] netfilter: convert to binary: marshalling standard target D0229 22:11:03.745451 58032 netfilter.go:54] netfilter: convert to binary: adding entry: {IPTEntry:{IP:{Src:[0 0 0 0] Dst:[0 0 0 0] SrcMask:[0 0 0 0] DstMask:[0 0 0 0] InputInterface:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] OutputInterface:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] InputInterfaceMask:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] OutputInterfaceMask:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] Protocol:0 Flags:0 InverseFlags:0} NFCache:0 TargetOffset:112 NextOffset:152 Comeback:0 Counters:{Pcnt:0 Bcnt:0}} Elems:[40 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 254 255 255 255 0 0 0 0]} D0229 22:11:03.745930 58032 netfilter.go:54] netfilter: convert to binary: current offset: 456 D0229 22:11:03.746017 58032 netfilter.go:54] netfilter: convert to binary: adding entry: {IPTEntry:{IP:{Src:[0 0 0 0] Dst:[0 0 0 0] SrcMask:[0 0 0 0] DstMask:[0 0 0 0] InputInterface:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] OutputInterface:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] InputInterfaceMask:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] OutputInterfaceMask:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] Protocol:0 Flags:0 InverseFlags:0} NFCache:0 TargetOffset:112 NextOffset:176 Comeback:0 Counters:{Pcnt:0 Bcnt:0}} Elems:[64 0 69 82 82 79 82 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 69 82 82 79 82 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0]} D0229 22:11:03.746741 58032 netfilter.go:54] netfilter: convert to binary: finished with an marshalled size of 0 I0229 22:11:03.752007 58032 loader.go:566] Received external signal 23, mode: Process D0229 22:11:03.752283 58032 task_signals.go:446] [ 1] Notified of signal 23 D0229 22:11:03.752429 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:11:03.752546 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:11:03.752719 58032 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 22:11:03.752848 58032 task_signals.go:220] [ 1] Signal 23: delivering to handler I0229 22:11:03.772352 58032 loader.go:566] Received external signal 23, mode: Process D0229 22:11:03.772567 58032 task_signals.go:446] [ 1] Notified of signal 23 D0229 22:11:03.772722 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:11:03.772867 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:11:03.773025 58032 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 22:11:03.773164 58032 task_signals.go:220] [ 1] Signal 23: delivering to handler I0229 22:11:03.792554 58032 loader.go:566] Received external signal 23, mode: Process D0229 22:11:03.792806 58032 task_signals.go:446] [ 1] Notified of signal 23 D0229 22:11:03.792923 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:11:03.793052 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:11:03.793286 58032 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 22:11:03.793396 58032 task_signals.go:220] [ 1] Signal 23: delivering to handler I0229 22:11:03.794364 58032 loader.go:566] Received external signal 23, mode: Process D0229 22:11:03.795612 58032 task_signals.go:452] [ 2] Notified of group signal 23 D0229 22:11:03.795715 58032 task_block.go:223] [ 2] Interrupt queued D0229 22:11:03.796584 58032 task_block.go:223] [ 2] Interrupt queued I0229 22:11:03.796636 58032 loader.go:566] Received external signal 23, mode: Process D0229 22:11:03.796856 58032 task_signals.go:176] [ 2] Not restarting syscall 270 after errno 514: interrupted by signal 23 D0229 22:11:03.796880 58032 task_signals.go:452] [ 2] Notified of group signal 23 D0229 22:11:03.797071 58032 task_signals.go:220] [ 2] Signal 23: delivering to handler D0229 22:11:03.797213 58032 task_block.go:223] [ 2] Interrupt queued D0229 22:11:03.797456 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:11:03.797631 58032 task_signals.go:220] [ 1] Signal 23: delivering to handler I0229 22:11:03.823769 58032 loader.go:566] Received external signal 23, mode: Process D0229 22:11:03.823971 58032 task_signals.go:446] [ 1] Notified of signal 23 D0229 22:11:03.824119 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:11:03.824257 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:11:03.824462 58032 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 22:11:03.824613 58032 task_signals.go:220] [ 1] Signal 23: delivering to handler I0229 22:11:03.839129 58032 loader.go:566] Received external signal 23, mode: Process D0229 22:11:03.839309 58032 task_signals.go:446] [ 1] Notified of signal 23 D0229 22:11:03.839427 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:11:03.839489 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:11:03.839755 58032 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 22:11:03.839864 58032 task_signals.go:220] [ 1] Signal 23: delivering to handler I0229 22:11:03.860030 58032 loader.go:566] Received external signal 23, mode: Process D0229 22:11:03.860240 58032 task_signals.go:446] [ 1] Notified of signal 23 D0229 22:11:03.860349 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:11:03.860480 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:11:03.860649 58032 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 22:11:03.860787 58032 task_signals.go:220] [ 1] Signal 23: delivering to handler I0229 22:11:03.880291 58032 loader.go:566] Received external signal 23, mode: Process D0229 22:11:03.880500 58032 task_signals.go:446] [ 1] Notified of signal 23 D0229 22:11:03.880596 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:11:03.880702 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:11:03.880933 58032 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 22:11:03.881043 58032 task_signals.go:220] [ 1] Signal 23: delivering to handler I0229 22:11:03.896598 58032 loader.go:566] Received external signal 23, mode: Process D0229 22:11:03.896823 58032 task_signals.go:446] [ 1] Notified of signal 23 D0229 22:11:03.896962 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:11:03.896975 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:11:03.897171 58032 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 22:11:03.897283 58032 task_signals.go:220] [ 1] Signal 23: delivering to handler I0229 22:11:03.916943 58032 loader.go:566] Received external signal 23, mode: Process D0229 22:11:03.917121 58032 task_signals.go:446] [ 1] Notified of signal 23 D0229 22:11:03.917298 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:11:03.917363 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:11:03.917642 58032 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 22:11:03.917769 58032 task_signals.go:220] [ 1] Signal 23: delivering to handler I0229 22:11:03.937401 58032 loader.go:566] Received external signal 23, mode: Process D0229 22:11:03.937578 58032 task_signals.go:446] [ 1] Notified of signal 23 D0229 22:11:03.937735 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:11:03.937778 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:11:03.937955 58032 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 22:11:03.938076 58032 task_signals.go:220] [ 1] Signal 23: delivering to handler D0229 22:11:03.943110 58032 transport_flipcall.go:127] send [channel @0xc0003303c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0229 22:11:03.943788 58032 transport_flipcall.go:234] recv [channel @0xc0003303c0] Rlerror{Error: 2} D0229 22:11:03.944045 58032 transport_flipcall.go:127] send [channel @0xc0003303c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0229 22:11:03.944499 58032 transport_flipcall.go:234] recv [channel @0xc0003303c0] Rlerror{Error: 95} D0229 22:11:03.944956 58032 transport_flipcall.go:127] send [channel @0xc0003303c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0229 22:11:03.945459 58032 transport_flipcall.go:234] recv [channel @0xc0003303c0] Rlerror{Error: 2} D0229 22:11:03.945659 58032 transport_flipcall.go:127] send [channel @0xc0003303c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0229 22:11:03.946146 58032 transport_flipcall.go:234] recv [channel @0xc0003303c0] Rlerror{Error: 95} D0229 22:11:03.946575 58032 transport_flipcall.go:127] send [channel @0xc0003303c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0229 22:11:03.947017 58032 transport_flipcall.go:234] recv [channel @0xc0003303c0] Rlerror{Error: 2} D0229 22:11:03.947183 58032 transport_flipcall.go:127] send [channel @0xc0003303c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0229 22:11:03.947721 58032 transport_flipcall.go:234] recv [channel @0xc0003303c0] Rlerror{Error: 95} D0229 22:11:03.948181 58032 transport_flipcall.go:127] send [channel @0xc0003303c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0229 22:11:03.948699 58032 transport_flipcall.go:234] recv [channel @0xc0003303c0] Rlerror{Error: 2} D0229 22:11:03.948849 58032 transport_flipcall.go:127] send [channel @0xc0003303c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0229 22:11:03.949164 58032 transport_flipcall.go:234] recv [channel @0xc0003303c0] Rlerror{Error: 95} D0229 22:11:03.949462 58032 transport_flipcall.go:127] send [channel @0xc0003303c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0229 22:11:03.949958 58032 transport_flipcall.go:234] recv [channel @0xc0003303c0] Rlerror{Error: 2} D0229 22:11:03.950137 58032 transport_flipcall.go:127] send [channel @0xc0003303c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0229 22:11:03.950548 58032 transport_flipcall.go:234] recv [channel @0xc0003303c0] Rlerror{Error: 95} D0229 22:11:03.950918 58032 transport_flipcall.go:127] send [channel @0xc0003303c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0229 22:11:03.975757 58032 transport_flipcall.go:234] recv [channel @0xc0003303c0] Rlerror{Error: 2} I0229 22:11:03.975856 58032 loader.go:566] Received external signal 23, mode: Process D0229 22:11:03.976086 58032 task_signals.go:446] [ 1] Notified of signal 23 D0229 22:11:03.976051 58032 transport_flipcall.go:127] send [channel @0xc0003303c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0229 22:11:03.976231 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:11:03.976276 58032 task_block.go:223] [ 1] Interrupt queued D0229 22:11:03.976517 58032 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0229 22:11:03.976682 58032 task_signals.go:220] [ 1] Signal 23: delivering to handler D0229 22:11:03.976892 58032 transport_flipcall.go:234] recv [channel @0xc0003303c0] Rlerror{Error: 95} D0229 22:11:03.977412 58032 transport_flipcall.go:127] send [channel @0xc0003303c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0229 22:11:03.978003 58032 transport_flipcall.go:234] recv [channel @0xc0003303c0] Rlerror{Error: 2} D0229 22:11:03.978197 58032 transport_flipcall.go:127] send [channel @0xc0003303c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0229 22:11:03.978797 58032 transport_flipcall.go:234] recv [channel @0xc0003303c0] Rlerror{Error: 95} D0229 22:11:03.979167 58032 transport_flipcall.go:127] send [channel @0xc0003303c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0229 22:11:03.979669 58032 transport_flipcall.go:234] recv [channel @0xc0003303c0] Rlerror{Error: 2} D0229 22:11:03.979874 58032 transport_flipcall.go:127] send [channel @0xc0003303c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0229 22:11:03.980428 58032 transport_flipcall.go:234] recv [channel @0xc0003303c0] Rlerror{Error: 95} D0229 22:11:03.980880 58032 transport_flipcall.go:127] send [channel @0xc0003303c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0229 22:11:03.981452 58032 transport_flipcall.go:234] recv [channel @0xc0003303c0] Rlerror{Error: 2} D0229 22:11:03.981612 58032 transport_flipcall.go:127] send [channel @0xc0003303c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0229 22:11:03.982102 58032 transport_flipcall.go:234] recv [channel @0xc0003303c0] Rlerror{Error: 95} D0229 22:11:03.982660 58032 transport_flipcall.go:127] send [channel @0xc0003303c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0229 22:11:03.983313 58032 transport_flipcall.go:234] recv [channel @0xc0003303c0] Rlerror{Error: 2} D0229 22:11:03.983513 58032 transport_flipcall.go:127] send [channel @0xc0003303c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0229 22:11:03.984008 58032 transport_flipcall.go:234] recv [channel @0xc0003303c0] Rlerror{Error: 95} D0229 22:11:04.000521 58032 task_block.go:223] [ 42] Interrupt queued D0229 22:11:04.011260 58032 task_block.go:223] [ 43] Interrupt queued D0229 22:11:04.033003 58032 task_block.go:223] [ 44] Interrupt queued ================== WARNING: DATA RACE Write at 0x00c0001506d0 by goroutine 342: 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 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 DIAGNOSIS: I0229 22:11:04.070441 61000 main.go:305] *************************** I0229 22:11:04.070557 61000 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] I0229 22:11:04.070726 61000 main.go:307] Version release-20200219.0-72-gce4d1e45bb88 I0229 22:11:04.070830 61000 main.go:308] PID: 61000 I0229 22:11:04.070940 61000 main.go:309] UID: 0, GID: 0 I0229 22:11:04.071048 61000 main.go:310] Configuration: I0229 22:11:04.071184 61000 main.go:311] RootDir: /syzkaller/managers/ptrace-proxy-sandbox-race/workdir/gvisor_root I0229 22:11:04.071319 61000 main.go:312] Platform: ptrace I0229 22:11:04.071455 61000 main.go:313] FileAccess: shared, overlay: false I0229 22:11:04.071605 61000 main.go:314] Network: sandbox, logging: false I0229 22:11:04.071810 61000 main.go:315] Strace: false, max size: 1024, syscalls: [] I0229 22:11:04.071971 61000 main.go:316] *************************** W0229 22:11:04.072077 61000 main.go:321] Block the TERM signal. This is only safe in tests! D0229 22:11:04.072264 61000 container.go:159] Load container "/syzkaller/managers/ptrace-proxy-sandbox-race/workdir/gvisor_root" "ci-gvisor-ptrace-proxy-sandbox-race-2" D0229 22:11:04.080524 61000 container.go:592] Signal container "ci-gvisor-ptrace-proxy-sandbox-race-2": signal 0 D0229 22:11:04.080671 61000 sandbox.go:806] Signal sandbox "ci-gvisor-ptrace-proxy-sandbox-race-2" D0229 22:11:04.080758 61000 sandbox.go:318] Connecting to sandbox "ci-gvisor-ptrace-proxy-sandbox-race-2" D0229 22:11:04.081144 61000 urpc.go:534] urpc: successfully marshalled 117 bytes. I0229 22:11:04.152729 61000 debug.go:122] Found sandbox "ci-gvisor-ptrace-proxy-sandbox-race-2", PID: 58032 I0229 22:11:04.152934 61000 debug.go:131] Retrieving sandbox stacks D0229 22:11:04.153593 61000 sandbox.go:924] Stacks sandbox "ci-gvisor-ptrace-proxy-sandbox-race-2" D0229 22:11:04.153717 61000 sandbox.go:318] Connecting to sandbox "ci-gvisor-ptrace-proxy-sandbox-race-2" W0229 22:11:04.153913 61000 error.go:48] FATAL ERROR: retrieving stacks: connecting to control server at PID 58032: connection refused retrieving stacks: connecting to control server at PID 58032: connection refused W0229 22:11:04.154245 61000 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 I0229 22:11:04.070441 61000 main.go:305] *************************** I0229 22:11:04.070557 61000 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] I0229 22:11:04.070726 61000 main.go:307] Version release-20200219.0-72-gce4d1e45bb88 I0229 22:11:04.070830 61000 main.go:308] PID: 61000 I0229 22:11:04.070940 61000 main.go:309] UID: 0, GID: 0 I0229 22:11:04.071048 61000 main.go:310] Configuration: I0229 22:11:04.071184 61000 main.go:311] RootDir: /syzkaller/managers/ptrace-proxy-sandbox-race/workdir/gvisor_root I0229 22:11:04.071319 61000 main.go:312] Platform: ptrace I0229 22:11:04.071455 61000 main.go:313] FileAccess: shared, overlay: false I0229 22:11:04.071605 61000 main.go:314] Network: sandbox, logging: false I0229 22:11:04.071810 61000 main.go:315] Strace: false, max size: 1024, syscalls: [] I0229 22:11:04.071971 61000 main.go:316] *************************** W0229 22:11:04.072077 61000 main.go:321] Block the TERM signal. This is only safe in tests! D0229 22:11:04.072264 61000 container.go:159] Load container "/syzkaller/managers/ptrace-proxy-sandbox-race/workdir/gvisor_root" "ci-gvisor-ptrace-proxy-sandbox-race-2" D0229 22:11:04.080524 61000 container.go:592] Signal container "ci-gvisor-ptrace-proxy-sandbox-race-2": signal 0 D0229 22:11:04.080671 61000 sandbox.go:806] Signal sandbox "ci-gvisor-ptrace-proxy-sandbox-race-2" D0229 22:11:04.080758 61000 sandbox.go:318] Connecting to sandbox "ci-gvisor-ptrace-proxy-sandbox-race-2" D0229 22:11:04.081144 61000 urpc.go:534] urpc: successfully marshalled 117 bytes. I0229 22:11:04.152729 61000 debug.go:122] Found sandbox "ci-gvisor-ptrace-proxy-sandbox-race-2", PID: 58032 I0229 22:11:04.152934 61000 debug.go:131] Retrieving sandbox stacks D0229 22:11:04.153593 61000 sandbox.go:924] Stacks sandbox "ci-gvisor-ptrace-proxy-sandbox-race-2" D0229 22:11:04.153717 61000 sandbox.go:318] Connecting to sandbox "ci-gvisor-ptrace-proxy-sandbox-race-2" W0229 22:11:04.153913 61000 error.go:48] FATAL ERROR: retrieving stacks: connecting to control server at PID 58032: connection refused retrieving stacks: connecting to control server at PID 58032: connection refused W0229 22:11:04.154245 61000 main.go:338] Failure to execute command, err: 1 Previous read at 0x00c0001506d0 by goroutine 353: 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 342 (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 353 (running) created at: gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).Start() pkg/sentry/kernel/task_start.go:315 +0x19d gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).Clone() pkg/sentry/kernel/task_clone.go:361 +0x1297 gvisor.dev/gvisor/pkg/sentry/syscalls/linux.clone() pkg/sentry/syscalls/linux/sys_thread.go:226 +0x171 gvisor.dev/gvisor/pkg/sentry/syscalls/linux.Clone() pkg/sentry/syscalls/linux/sys_clone_amd64.go:34 +0x80 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).executeSyscall() pkg/sentry/kernel/task_syscall.go:170 +0x44d gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscallInvoke() pkg/sentry/kernel/task_syscall.go:291 +0xb4 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscallEnter() pkg/sentry/kernel/task_syscall.go:252 +0x109 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscall() pkg/sentry/kernel/task_syscall.go:227 +0x1b3 gvisor.dev/gvisor/pkg/sentry/kernel.(*runApp).execute() pkg/sentry/kernel/task_run.go:232 +0x1aaa gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).run() pkg/sentry/kernel/task_run.go:92 +0x320 ================== W0229 22:11:04.144227 60882 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 W0229 22:11:04.144891 60882 main.go:338] Failure to execute command, err: 1 W0229 22:11:04.153110 58023 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. D0229 22:11:04.153596 58023 container.go:714] Destroy container "ci-gvisor-ptrace-proxy-sandbox-race-2" D0229 22:11:04.154111 58023 container.go:801] Destroying container "ci-gvisor-ptrace-proxy-sandbox-race-2" D0229 22:11:04.154217 58023 sandbox.go:1058] Destroying root container "ci-gvisor-ptrace-proxy-sandbox-race-2" by destroying sandbox D0229 22:11:04.154326 58023 sandbox.go:788] Destroy sandbox "ci-gvisor-ptrace-proxy-sandbox-race-2" D0229 22:11:04.154443 58023 container.go:815] Killing gofer for container "ci-gvisor-ptrace-proxy-sandbox-race-2", PID: 58031 I0229 22:11:04.255662 58023 main.go:329] Exiting with status: 16896