D0305 13:23:47.879405 58310 sampler.go:168] Time: Adjusting syscall overhead up to 8000 I0305 13:23:47.879628 58310 calibrated_clock.go:79] CalibratedClock(Monotonic): ready D0305 13:23:47.879803 58310 sampler.go:168] Time: Adjusting syscall overhead up to 8000 I0305 13:23:47.879930 58310 calibrated_clock.go:79] CalibratedClock(Realtime): ready D0305 13:23:47.880021 58310 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9904141870933398 monotonicBaseRef:1000568409 monotonicFrequency:2299773722 realtimeReady:1 realtimeBaseCycles:9904141871635977 realtimeBaseRef:1583414627879925716 realtimeFrequency:2299773704} D0305 13:23:48.880155 58310 sampler.go:168] Time: Adjusting syscall overhead up to 16000 D0305 13:23:48.880819 58310 parameters.go:238] Clock(Monotonic): error: 444 ns, adjusted frequency from 2299773722 Hz to 2299773490 Hz D0305 13:23:48.881165 58310 sampler.go:168] Time: Adjusting syscall overhead up to 16000 D0305 13:23:48.881374 58310 parameters.go:238] Clock(Realtime): error: 3043 ns, adjusted frequency from 2299773704 Hz to 2299782712 Hz D0305 13:23:48.881599 58310 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9904144173354711 monotonicBaseRef:2001719648 monotonicFrequency:2299773490 realtimeReady:1 realtimeBaseCycles:9904144174716785 realtimeBaseRef:1583414628881363728 realtimeFrequency:2299782712} I0305 13:23:49.836890 58310 loader.go:566] Received external signal 23, mode: Process D0305 13:23:49.837072 58310 task_signals.go:446] [ 1] Notified of signal 23 D0305 13:23:49.837211 58310 task_block.go:223] [ 1] Interrupt queued I0305 13:23:49.837434 58310 loader.go:566] Received external signal 23, mode: Process D0305 13:23:49.837603 58310 task_signals.go:434] [ 1] Discarding duplicate signal 23 D0305 13:23:49.837407 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:23:49.837807 58310 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0305 13:23:49.837939 58310 task_signals.go:220] [ 1] Signal 23: delivering to handler D0305 13:23:49.879831 58310 parameters.go:238] Clock(Monotonic): error: 1375 ns, adjusted frequency from 2299773490 Hz to 2299777420 Hz D0305 13:23:49.880724 58310 parameters.go:238] Clock(Realtime): error: -469 ns, adjusted frequency from 2299782712 Hz to 2299774300 Hz D0305 13:23:49.881307 58310 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9904146470926092 monotonicBaseRef:3000762115 monotonicFrequency:2299777420 realtimeReady:1 realtimeBaseCycles:9904146472955553 realtimeBaseRef:1583414629880692384 realtimeFrequency:2299774300} I0305 13:23:50.835547 58310 loader.go:566] Received external signal 23, mode: Process D0305 13:23:50.835797 58310 task_signals.go:446] [ 1] Notified of signal 23 D0305 13:23:50.835933 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:23:50.836112 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:23:50.836393 58310 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0305 13:23:50.836504 58310 task_signals.go:220] [ 1] Signal 23: delivering to handler D0305 13:23:50.879379 58310 parameters.go:238] Clock(Monotonic): error: 261 ns, adjusted frequency from 2299777420 Hz to 2299774829 Hz I0305 13:23:50.879614 58310 loader.go:566] Received external signal 23, mode: Process D0305 13:23:50.879671 58310 parameters.go:238] Clock(Realtime): error: -2541 ns, adjusted frequency from 2299774300 Hz to 2299768379 Hz D0305 13:23:50.879895 58310 task_signals.go:446] [ 1] Notified of signal 23 D0305 13:23:50.880063 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:23:50.879964 58310 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9904148769640810 monotonicBaseRef:4000300025 monotonicFrequency:2299774829 realtimeReady:1 realtimeBaseCycles:9904148770355952 realtimeBaseRef:1583414630879660151 realtimeFrequency:2299768379} I0305 13:23:50.880244 58310 loader.go:566] Received external signal 23, mode: Process D0305 13:23:50.880366 58310 task_signals.go:434] [ 1] Discarding duplicate signal 23 D0305 13:23:50.880201 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:23:50.880616 58310 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0305 13:23:50.880713 58310 task_signals.go:220] [ 1] Signal 23: delivering to handler D0305 13:23:51.879335 58310 parameters.go:238] Clock(Monotonic): error: -130 ns, adjusted frequency from 2299774829 Hz to 2299773874 Hz I0305 13:23:51.879399 58310 loader.go:566] Received external signal 23, mode: Process D0305 13:23:51.879554 58310 parameters.go:238] Clock(Realtime): error: -105 ns, adjusted frequency from 2299768379 Hz to 2299774174 Hz D0305 13:23:51.879685 58310 task_signals.go:446] [ 1] Notified of signal 23 D0305 13:23:51.879696 58310 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9904151069229323 monotonicBaseRef:5000219010 monotonicFrequency:2299773874 realtimeReady:1 realtimeBaseCycles:9904151069867568 realtimeBaseRef:1583414631879548503 realtimeFrequency:2299774174} D0305 13:23:51.879798 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:23:51.879917 58310 task_block.go:223] [ 1] Interrupt queued I0305 13:23:51.880053 58310 loader.go:566] Received external signal 23, mode: Process D0305 13:23:51.880117 58310 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0305 13:23:51.880171 58310 task_signals.go:446] [ 1] Notified of signal 23 D0305 13:23:51.880222 58310 task_signals.go:220] [ 1] Signal 23: delivering to handler D0305 13:23:51.880269 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:23:51.880567 58310 task_block.go:223] [ 2] Interrupt queued D0305 13:23:51.880598 58310 task_block.go:223] [ 2] Interrupt queued D0305 13:23:51.880637 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:23:51.880730 58310 task_signals.go:176] [ 2] Not restarting syscall 202 after errno 516: interrupted by signal 23 D0305 13:23:51.880893 58310 task_signals.go:220] [ 2] Signal 23: delivering to handler D0305 13:23:52.879336 58310 parameters.go:238] Clock(Monotonic): error: 68 ns, adjusted frequency from 2299773874 Hz to 2299774507 Hz I0305 13:23:52.879429 58310 loader.go:566] Received external signal 23, mode: Process D0305 13:23:52.879566 58310 parameters.go:238] Clock(Realtime): error: 95 ns, adjusted frequency from 2299774174 Hz to 2299774675 Hz D0305 13:23:52.879669 58310 task_signals.go:446] [ 1] Notified of signal 23 D0305 13:23:52.879788 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:23:52.879706 58310 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9904153369119492 monotonicBaseRef:6000269578 monotonicFrequency:2299774507 realtimeReady:1 realtimeBaseCycles:9904153369671047 realtimeBaseRef:1583414632879561245 realtimeFrequency:2299774675} D0305 13:23:52.879839 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:23:52.880110 58310 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0305 13:23:52.880310 58310 task_signals.go:220] [ 1] Signal 23: delivering to handler I0305 13:23:53.835505 58310 loader.go:566] Received external signal 23, mode: Process D0305 13:23:53.835712 58310 task_signals.go:446] [ 1] Notified of signal 23 D0305 13:23:53.835810 58310 task_block.go:223] [ 1] Interrupt queued I0305 13:23:53.835964 58310 loader.go:566] Received external signal 23, mode: Process D0305 13:23:53.835966 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:23:53.836076 58310 task_signals.go:434] [ 1] Discarding duplicate signal 23 D0305 13:23:53.836175 58310 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0305 13:23:53.836320 58310 task_signals.go:220] [ 1] Signal 23: delivering to handler D0305 13:23:53.879349 58310 parameters.go:238] Clock(Monotonic): error: 264 ns, adjusted frequency from 2299774507 Hz to 2299774862 Hz D0305 13:23:53.879515 58310 sampler.go:197] Time: Adjusting syscall overhead down to 14000 D0305 13:23:53.879638 58310 parameters.go:238] Clock(Realtime): error: 35 ns, adjusted frequency from 2299774675 Hz to 2299774551 Hz D0305 13:23:53.879771 58310 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9904155668921091 monotonicBaseRef:7000281358 monotonicFrequency:2299774862 realtimeReady:1 realtimeBaseCycles:9904155669332491 realtimeBaseRef:1583414633879512009 realtimeFrequency:2299774551} D0305 13:23:54.879367 58310 parameters.go:238] Clock(Monotonic): error: -73 ns, adjusted frequency from 2299774862 Hz to 2299774176 Hz I0305 13:23:54.879468 58310 loader.go:566] Received external signal 23, mode: Process D0305 13:23:54.879566 58310 sampler.go:197] Time: Adjusting syscall overhead down to 12250 D0305 13:23:54.879663 58310 task_signals.go:446] [ 1] Notified of signal 23 D0305 13:23:54.879692 58310 parameters.go:238] Clock(Realtime): error: 156 ns, adjusted frequency from 2299774551 Hz to 2299774830 Hz D0305 13:23:54.879768 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:23:54.879792 58310 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9904157968696930 monotonicBaseRef:8000281782 monotonicFrequency:2299774176 realtimeReady:1 realtimeBaseCycles:9904157969221393 realtimeBaseRef:1583414634879561731 realtimeFrequency:2299774830} D0305 13:23:54.879842 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:23:54.880133 58310 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0305 13:23:54.880229 58310 task_signals.go:220] [ 1] Signal 23: delivering to handler I0305 13:23:55.835442 58310 loader.go:566] Received external signal 23, mode: Process D0305 13:23:55.835644 58310 task_signals.go:446] [ 1] Notified of signal 23 D0305 13:23:55.835782 58310 task_block.go:223] [ 1] Interrupt queued I0305 13:23:55.835931 58310 loader.go:566] Received external signal 23, mode: Process D0305 13:23:55.835929 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:23:55.836021 58310 task_signals.go:434] [ 1] Discarding duplicate signal 23 D0305 13:23:55.836144 58310 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0305 13:23:55.836316 58310 task_signals.go:220] [ 1] Signal 23: delivering to handler D0305 13:23:55.879292 58310 parameters.go:238] Clock(Monotonic): error: 281 ns, adjusted frequency from 2299774176 Hz to 2299775084 Hz D0305 13:23:55.879478 58310 sampler.go:197] Time: Adjusting syscall overhead down to 10719 D0305 13:23:55.879540 58310 parameters.go:238] Clock(Realtime): error: 24 ns, adjusted frequency from 2299774830 Hz to 2299774579 Hz D0305 13:23:55.879632 58310 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9904160268341814 monotonicBaseRef:9000225562 monotonicFrequency:2299775084 realtimeReady:1 realtimeBaseCycles:9904160268796066 realtimeBaseRef:1583414635879474697 realtimeFrequency:2299774579} I0305 13:23:56.835423 58310 loader.go:566] Received external signal 23, mode: Process D0305 13:23:56.835667 58310 task_signals.go:446] [ 1] Notified of signal 23 D0305 13:23:56.835772 58310 task_block.go:223] [ 1] Interrupt queued I0305 13:23:56.835944 58310 loader.go:566] Received external signal 23, mode: Process D0305 13:23:56.836054 58310 task_signals.go:434] [ 1] Discarding duplicate signal 23 D0305 13:23:56.835998 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:23:56.836302 58310 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0305 13:23:56.836463 58310 task_signals.go:220] [ 1] Signal 23: delivering to handler D0305 13:23:56.879340 58310 parameters.go:238] Clock(Monotonic): error: 370 ns, adjusted frequency from 2299775084 Hz to 2299775373 Hz D0305 13:23:56.879559 58310 parameters.go:238] Clock(Realtime): error: 37 ns, adjusted frequency from 2299774579 Hz to 2299774694 Hz D0305 13:23:56.879691 58310 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9904162568215690 monotonicBaseRef:10000268519 monotonicFrequency:2299775373 realtimeReady:1 realtimeBaseCycles:9904162568754465 realtimeBaseRef:1583414636879554626 realtimeFrequency:2299774694} I0305 13:23:57.835316 58310 loader.go:566] Received external signal 23, mode: Process D0305 13:23:57.835568 58310 task_signals.go:446] [ 1] Notified of signal 23 D0305 13:23:57.835727 58310 task_block.go:223] [ 1] Interrupt queued I0305 13:23:57.835903 58310 loader.go:566] Received external signal 23, mode: Process D0305 13:23:57.835993 58310 task_signals.go:434] [ 1] Discarding duplicate signal 23 D0305 13:23:57.835908 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:23:57.836202 58310 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0305 13:23:57.836298 58310 task_signals.go:220] [ 1] Signal 23: delivering to handler D0305 13:23:57.879352 58310 parameters.go:238] Clock(Monotonic): error: 994 ns, adjusted frequency from 2299775373 Hz to 2299777306 Hz D0305 13:23:57.879667 58310 parameters.go:238] Clock(Realtime): error: 1111 ns, adjusted frequency from 2299774694 Hz to 2299776904 Hz D0305 13:23:57.879845 58310 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9904164868013910 monotonicBaseRef:11000278453 monotonicFrequency:2299777306 realtimeReady:1 realtimeBaseCycles:9904164868770791 realtimeBaseRef:1583414637879659693 realtimeFrequency:2299776904} I0305 13:23:58.835433 58310 loader.go:566] Received external signal 23, mode: Process D0305 13:23:58.835657 58310 task_signals.go:446] [ 1] Notified of signal 23 D0305 13:23:58.835819 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:23:58.836072 58310 task_block.go:223] [ 1] Interrupt queued I0305 13:23:58.836033 58310 loader.go:566] Received external signal 23, mode: Process D0305 13:23:58.836227 58310 task_signals.go:446] [ 1] Notified of signal 23 D0305 13:23:58.836317 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:23:58.836223 58310 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0305 13:23:58.836429 58310 task_signals.go:220] [ 1] Signal 23: delivering to handler D0305 13:23:58.836796 58310 task_block.go:223] [ 2] Interrupt queued D0305 13:23:58.836825 58310 task_block.go:223] [ 2] Interrupt queued D0305 13:23:58.836890 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:23:58.836975 58310 task_signals.go:176] [ 2] Not restarting syscall 202 after errno 516: interrupted by signal 23 D0305 13:23:58.837074 58310 task_signals.go:220] [ 2] Signal 23: delivering to handler D0305 13:23:58.879304 58310 parameters.go:238] Clock(Monotonic): error: -1390 ns, adjusted frequency from 2299777306 Hz to 2299771350 Hz D0305 13:23:58.879523 58310 parameters.go:238] Clock(Realtime): error: -957 ns, adjusted frequency from 2299776904 Hz to 2299772041 Hz D0305 13:23:58.879661 58310 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9904167167695371 monotonicBaseRef:12000236777 monotonicFrequency:2299771350 realtimeReady:1 realtimeBaseCycles:9904167168219296 realtimeBaseRef:1583414638879516896 realtimeFrequency:2299772041} I0305 13:23:59.835331 58310 loader.go:566] Received external signal 23, mode: Process D0305 13:23:59.835521 58310 task_signals.go:446] [ 1] Notified of signal 23 D0305 13:23:59.835621 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:23:59.835702 58310 task_block.go:223] [ 1] Interrupt queued I0305 13:23:59.835763 58310 loader.go:566] Received external signal 23, mode: Process D0305 13:23:59.835862 58310 task_signals.go:434] [ 1] Discarding duplicate signal 23 D0305 13:23:59.836044 58310 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0305 13:23:59.836242 58310 task_signals.go:220] [ 1] Signal 23: delivering to handler D0305 13:23:59.879317 58310 parameters.go:238] Clock(Monotonic): error: 69 ns, adjusted frequency from 2299771350 Hz to 2299774734 Hz I0305 13:23:59.879432 58310 loader.go:566] Received external signal 23, mode: Process D0305 13:23:59.879519 58310 parameters.go:238] Clock(Realtime): error: 139 ns, adjusted frequency from 2299772041 Hz to 2299775009 Hz D0305 13:23:59.879703 58310 task_signals.go:446] [ 1] Notified of signal 23 D0305 13:23:59.879787 58310 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9904169467451565 monotonicBaseRef:13000230186 monotonicFrequency:2299774734 realtimeReady:1 realtimeBaseCycles:9904169467985149 realtimeBaseRef:1583414639879514205 realtimeFrequency:2299775009} D0305 13:23:59.879904 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:23:59.879975 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:23:59.880259 58310 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0305 13:23:59.880371 58310 task_signals.go:220] [ 1] Signal 23: delivering to handler D0305 13:24:00.879303 58310 parameters.go:238] Clock(Monotonic): error: 2 ns, adjusted frequency from 2299774734 Hz to 2299774644 Hz D0305 13:24:00.879547 58310 parameters.go:238] Clock(Realtime): error: 230 ns, adjusted frequency from 2299775009 Hz to 2299775154 Hz D0305 13:24:00.879668 58310 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9904171767211940 monotonicBaseRef:14000223942 monotonicFrequency:2299774644 realtimeReady:1 realtimeBaseCycles:9904171767811259 realtimeBaseRef:1583414640879536424 realtimeFrequency:2299775154} I0305 13:24:01.835388 58310 loader.go:566] Received external signal 23, mode: Process D0305 13:24:01.835595 58310 task_signals.go:446] [ 1] Notified of signal 23 D0305 13:24:01.835758 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:24:01.835806 58310 task_block.go:223] [ 1] Interrupt queued I0305 13:24:01.835931 58310 loader.go:566] Received external signal 23, mode: Process D0305 13:24:01.836088 58310 task_signals.go:446] [ 1] Notified of signal 23 D0305 13:24:01.836034 58310 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0305 13:24:01.836350 58310 task_signals.go:220] [ 1] Signal 23: delivering to handler D0305 13:24:01.836202 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:24:01.836890 58310 task_block.go:223] [ 2] Interrupt queued D0305 13:24:01.836947 58310 task_block.go:223] [ 2] Interrupt queued D0305 13:24:01.837035 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:24:01.837153 58310 task_signals.go:176] [ 2] Not restarting syscall 202 after errno 516: interrupted by signal 23 D0305 13:24:01.837328 58310 task_signals.go:220] [ 2] Signal 23: delivering to handler D0305 13:24:01.879304 58310 parameters.go:238] Clock(Monotonic): error: 263 ns, adjusted frequency from 2299774644 Hz to 2299775212 Hz D0305 13:24:01.879568 58310 parameters.go:238] Clock(Realtime): error: -180 ns, adjusted frequency from 2299775154 Hz to 2299774187 Hz D0305 13:24:01.879700 58310 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9904174067009090 monotonicBaseRef:15000233728 monotonicFrequency:2299775212 realtimeReady:1 realtimeBaseCycles:9904174067648987 realtimeBaseRef:1583414641879563632 realtimeFrequency:2299774187} D0305 13:24:02.879399 58310 parameters.go:238] Clock(Monotonic): error: 84 ns, adjusted frequency from 2299775212 Hz to 2299774928 Hz D0305 13:24:02.879768 58310 parameters.go:238] Clock(Realtime): error: 65 ns, adjusted frequency from 2299774187 Hz to 2299774737 Hz D0305 13:24:02.879942 58310 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9904176366968196 monotonicBaseRef:16000313689 monotonicFrequency:2299774928 realtimeReady:1 realtimeBaseCycles:9904176367859864 realtimeBaseRef:1583414642879753515 realtimeFrequency:2299774737} D0305 13:24:03.879342 58310 parameters.go:238] Clock(Monotonic): error: -120 ns, adjusted frequency from 2299774928 Hz to 2299774421 Hz I0305 13:24:03.879420 58310 loader.go:566] Received external signal 23, mode: Process D0305 13:24:03.879575 58310 parameters.go:238] Clock(Realtime): error: 39 ns, adjusted frequency from 2299774737 Hz to 2299774689 Hz D0305 13:24:03.879702 58310 task_signals.go:446] [ 1] Notified of signal 23 D0305 13:24:03.879822 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:24:03.879754 58310 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9904178666642355 monotonicBaseRef:17000269872 monotonicFrequency:2299774421 realtimeReady:1 realtimeBaseCycles:9904178667208844 realtimeBaseRef:1583414643879568385 realtimeFrequency:2299774689} D0305 13:24:03.879955 58310 task_block.go:223] [ 1] Interrupt queued I0305 13:24:03.880014 58310 loader.go:566] Received external signal 23, mode: Process D0305 13:24:03.880113 58310 task_signals.go:434] [ 1] Discarding duplicate signal 23 D0305 13:24:03.880229 58310 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0305 13:24:03.880356 58310 task_signals.go:220] [ 1] Signal 23: delivering to handler I0305 13:24:04.835306 58310 loader.go:566] Received external signal 23, mode: Process D0305 13:24:04.835526 58310 task_signals.go:446] [ 1] Notified of signal 23 D0305 13:24:04.835630 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:24:04.835711 58310 task_block.go:223] [ 1] Interrupt queued I0305 13:24:04.835867 58310 loader.go:566] Received external signal 23, mode: Process D0305 13:24:04.835978 58310 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0305 13:24:04.836022 58310 task_signals.go:446] [ 1] Notified of signal 23 D0305 13:24:04.836151 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:24:04.836137 58310 task_signals.go:220] [ 1] Signal 23: delivering to handler D0305 13:24:04.836637 58310 task_block.go:223] [ 2] Interrupt queued D0305 13:24:04.836667 58310 task_block.go:223] [ 2] Interrupt queued D0305 13:24:04.836730 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:24:04.836947 58310 task_signals.go:176] [ 2] Not restarting syscall 202 after errno 516: interrupted by signal 23 D0305 13:24:04.837062 58310 task_signals.go:220] [ 2] Signal 23: delivering to handler D0305 13:24:04.879293 58310 parameters.go:238] Clock(Monotonic): error: -245 ns, adjusted frequency from 2299774421 Hz to 2299774098 Hz D0305 13:24:04.879492 58310 parameters.go:238] Clock(Realtime): error: -99 ns, adjusted frequency from 2299774689 Hz to 2299774386 Hz D0305 13:24:04.879577 58310 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9904180966310442 monotonicBaseRef:18000223635 monotonicFrequency:2299774098 realtimeReady:1 realtimeBaseCycles:9904180966797776 realtimeBaseRef:1583414644879487613 realtimeFrequency:2299774386} D0305 13:24:05.879334 58310 parameters.go:238] Clock(Monotonic): error: 106 ns, adjusted frequency from 2299774098 Hz to 2299774846 Hz I0305 13:24:05.879553 58310 loader.go:566] Received external signal 23, mode: Process D0305 13:24:05.879567 58310 parameters.go:238] Clock(Realtime): error: 54 ns, adjusted frequency from 2299774386 Hz to 2299774726 Hz D0305 13:24:05.879723 58310 task_signals.go:446] [ 1] Notified of signal 23 D0305 13:24:05.879713 58310 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9904183266176025 monotonicBaseRef:19000263414 monotonicFrequency:2299774846 realtimeReady:1 realtimeBaseCycles:9904183266743795 realtimeBaseRef:1583414645879562243 realtimeFrequency:2299774726} D0305 13:24:05.879851 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:24:05.880026 58310 task_block.go:223] [ 1] Interrupt queued I0305 13:24:05.880111 58310 loader.go:566] Received external signal 23, mode: Process D0305 13:24:05.880268 58310 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0305 13:24:05.880275 58310 task_signals.go:446] [ 1] Notified of signal 23 D0305 13:24:05.880394 58310 task_signals.go:220] [ 1] Signal 23: delivering to handler D0305 13:24:05.880422 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:24:05.880892 58310 task_block.go:223] [ 2] Interrupt queued D0305 13:24:05.880838 58310 task_block.go:223] [ 2] Interrupt queued D0305 13:24:05.881054 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:24:05.881166 58310 task_signals.go:176] [ 2] Not restarting syscall 202 after errno 516: interrupted by signal 23 D0305 13:24:05.881330 58310 task_signals.go:220] [ 2] Signal 23: delivering to handler D0305 13:24:06.879274 58310 parameters.go:238] Clock(Monotonic): error: -96 ns, adjusted frequency from 2299774846 Hz to 2299774292 Hz I0305 13:24:06.879426 58310 loader.go:566] Received external signal 23, mode: Process D0305 13:24:06.879497 58310 parameters.go:238] Clock(Realtime): error: -21 ns, adjusted frequency from 2299774726 Hz to 2299774502 Hz D0305 13:24:06.879630 58310 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9904185565823600 monotonicBaseRef:20000208073 monotonicFrequency:2299774292 realtimeReady:1 realtimeBaseCycles:9904185566357523 realtimeBaseRef:1583414646879492237 realtimeFrequency:2299774502} D0305 13:24:06.879655 58310 task_signals.go:446] [ 1] Notified of signal 23 D0305 13:24:06.879821 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:24:06.879916 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:24:06.880230 58310 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0305 13:24:06.880384 58310 task_signals.go:220] [ 1] Signal 23: delivering to handler D0305 13:24:07.879302 58310 parameters.go:238] Clock(Monotonic): error: 342 ns, adjusted frequency from 2299774292 Hz to 2299775420 Hz D0305 13:24:07.879501 58310 parameters.go:238] Clock(Realtime): error: 102 ns, adjusted frequency from 2299774502 Hz to 2299774877 Hz D0305 13:24:07.879635 58310 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9904187865646950 monotonicBaseRef:21000229404 monotonicFrequency:2299775420 realtimeReady:1 realtimeBaseCycles:9904187866123659 realtimeBaseRef:1583414647879488599 realtimeFrequency:2299774877} D0305 13:24:08.879399 58310 parameters.go:238] Clock(Monotonic): error: -175 ns, adjusted frequency from 2299775420 Hz to 2299774207 Hz I0305 13:24:08.879515 58310 loader.go:566] Received external signal 23, mode: Process D0305 13:24:08.879647 58310 parameters.go:238] Clock(Realtime): error: 154 ns, adjusted frequency from 2299774877 Hz to 2299774930 Hz D0305 13:24:08.879860 58310 task_signals.go:446] [ 1] Notified of signal 23 D0305 13:24:08.879845 58310 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9904190165634839 monotonicBaseRef:22000321790 monotonicFrequency:2299774207 realtimeReady:1 realtimeBaseCycles:9904190166244793 realtimeBaseRef:1583414648879639160 realtimeFrequency:2299774930} D0305 13:24:08.880012 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:24:08.880149 58310 task_block.go:223] [ 1] Interrupt queued I0305 13:24:08.880241 58310 loader.go:566] Received external signal 23, mode: Process D0305 13:24:08.880455 58310 task_signals.go:434] [ 1] Discarding duplicate signal 23 D0305 13:24:08.880798 58310 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0305 13:24:08.881042 58310 task_signals.go:220] [ 1] Signal 23: delivering to handler I0305 13:24:09.835522 58310 loader.go:566] Received external signal 23, mode: Process D0305 13:24:09.835807 58310 task_signals.go:446] [ 1] Notified of signal 23 D0305 13:24:09.836017 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:24:09.836117 58310 task_block.go:223] [ 1] Interrupt queued I0305 13:24:09.836236 58310 loader.go:566] Received external signal 23, mode: Process D0305 13:24:09.836327 58310 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0305 13:24:09.836418 58310 task_signals.go:446] [ 1] Notified of signal 23 D0305 13:24:09.836442 58310 task_signals.go:220] [ 1] Signal 23: delivering to handler D0305 13:24:09.836557 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:24:09.836923 58310 task_block.go:223] [ 2] Interrupt queued D0305 13:24:09.836972 58310 task_block.go:223] [ 2] Interrupt queued D0305 13:24:09.837053 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:24:09.837178 58310 task_signals.go:176] [ 2] Not restarting syscall 202 after errno 516: interrupted by signal 23 D0305 13:24:09.837391 58310 task_signals.go:220] [ 2] Signal 23: delivering to handler D0305 13:24:09.879225 58310 parameters.go:238] Clock(Monotonic): error: 1052 ns, adjusted frequency from 2299774207 Hz to 2299777179 Hz D0305 13:24:09.879464 58310 parameters.go:238] Clock(Realtime): error: 104 ns, adjusted frequency from 2299774930 Hz to 2299774845 Hz D0305 13:24:09.879630 58310 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9904192465022515 monotonicBaseRef:23000153716 monotonicFrequency:2299777179 realtimeReady:1 realtimeBaseCycles:9904192465599306 realtimeBaseRef:1583414649879456352 realtimeFrequency:2299774845} D0305 13:24:10.879291 58310 parameters.go:238] Clock(Monotonic): error: -1224 ns, adjusted frequency from 2299777179 Hz to 2299771841 Hz D0305 13:24:10.879511 58310 parameters.go:238] Clock(Realtime): error: 607 ns, adjusted frequency from 2299774845 Hz to 2299776105 Hz D0305 13:24:10.879625 58310 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9904194764955107 monotonicBaseRef:24000221293 monotonicFrequency:2299771841 realtimeReady:1 realtimeBaseCycles:9904194765488231 realtimeBaseRef:1583414650879505956 realtimeFrequency:2299776105} D0305 13:24:11.879326 58310 parameters.go:238] Clock(Monotonic): error: 824 ns, adjusted frequency from 2299771841 Hz to 2299776516 Hz I0305 13:24:11.879523 58310 loader.go:566] Received external signal 23, mode: Process D0305 13:24:11.879596 58310 parameters.go:238] Clock(Realtime): error: -723 ns, adjusted frequency from 2299776105 Hz to 2299773002 Hz D0305 13:24:11.879841 58310 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9904197064809227 monotonicBaseRef:25000257070 monotonicFrequency:2299776516 realtimeReady:1 realtimeBaseCycles:9904197065422359 realtimeBaseRef:1583414651879574668 realtimeFrequency:2299773002} D0305 13:24:11.879831 58310 task_signals.go:446] [ 1] Notified of signal 23 D0305 13:24:11.880059 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:24:11.880128 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:24:11.880387 58310 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0305 13:24:11.880519 58310 task_signals.go:220] [ 1] Signal 23: delivering to handler I0305 13:24:11.881727 58310 loader.go:566] Received external signal 23, mode: Process D0305 13:24:11.882376 58310 task_signals.go:452] [ 2] Notified of group signal 23 D0305 13:24:11.883718 58310 task_block.go:223] [ 2] Interrupt queued D0305 13:24:11.883831 58310 task_block.go:223] [ 2] Interrupt queued D0305 13:24:11.883935 58310 task_block.go:223] [ 1] Interrupt queued I0305 13:24:11.883965 58310 loader.go:566] Received external signal 23, mode: Process D0305 13:24:11.884167 58310 task_signals.go:220] [ 1] Signal 23: delivering to handler D0305 13:24:11.884228 58310 task_signals.go:446] [ 1] Notified of signal 23 D0305 13:24:11.884219 58310 task_run.go:152] [ 2] Restarting syscall 202 with restart block after errno 516: not interrupted by handled signal D0305 13:24:11.884338 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:24:11.884732 58310 task_block.go:223] [ 2] Interrupt queued D0305 13:24:11.884866 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:24:11.886196 58310 task_signals.go:220] [ 2] Signal 23: delivering to handler D0305 13:24:12.879341 58310 parameters.go:238] Clock(Monotonic): error: -972 ns, adjusted frequency from 2299776516 Hz to 2299772385 Hz I0305 13:24:12.879410 58310 loader.go:566] Received external signal 23, mode: Process D0305 13:24:12.879597 58310 parameters.go:238] Clock(Realtime): error: 138 ns, adjusted frequency from 2299773002 Hz to 2299774951 Hz D0305 13:24:12.879674 58310 task_signals.go:446] [ 1] Notified of signal 23 D0305 13:24:12.879797 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:24:12.879746 58310 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9904199364581800 monotonicBaseRef:26000255355 monotonicFrequency:2299772385 realtimeReady:1 realtimeBaseCycles:9904199365199745 realtimeBaseRef:1583414652879576574 realtimeFrequency:2299774951} D0305 13:24:12.879991 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:24:12.880270 58310 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0305 13:24:12.880414 58310 task_signals.go:220] [ 1] Signal 23: delivering to handler D0305 13:24:13.879361 58310 parameters.go:238] Clock(Monotonic): error: 471 ns, adjusted frequency from 2299772385 Hz to 2299775718 Hz I0305 13:24:13.879546 58310 loader.go:566] Received external signal 23, mode: Process D0305 13:24:13.879644 58310 parameters.go:238] Clock(Realtime): error: -217 ns, adjusted frequency from 2299774951 Hz to 2299774154 Hz D0305 13:24:13.879769 58310 task_signals.go:446] [ 1] Notified of signal 23 D0305 13:24:13.879863 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:24:13.879805 58310 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9904201664432248 monotonicBaseRef:27000289298 monotonicFrequency:2299775718 realtimeReady:1 realtimeBaseCycles:9904201665112830 realtimeBaseRef:1583414653879636638 realtimeFrequency:2299774154} D0305 13:24:13.880003 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:24:13.880218 58310 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0305 13:24:13.880311 58310 task_signals.go:220] [ 1] Signal 23: delivering to handler D0305 13:24:14.879316 58310 parameters.go:238] Clock(Monotonic): error: 104 ns, adjusted frequency from 2299775718 Hz to 2299774945 Hz D0305 13:24:14.879516 58310 parameters.go:238] Clock(Realtime): error: 18 ns, adjusted frequency from 2299774154 Hz to 2299774687 Hz I0305 13:24:14.879492 58310 loader.go:566] Received external signal 23, mode: Process D0305 13:24:14.879675 58310 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9904203964104073 monotonicBaseRef:28000244122 monotonicFrequency:2299774945 realtimeReady:1 realtimeBaseCycles:9904203964592743 realtimeBaseRef:1583414654879508694 realtimeFrequency:2299774687} D0305 13:24:14.879765 58310 task_signals.go:446] [ 1] Notified of signal 23 D0305 13:24:14.879969 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:24:14.879960 58310 task_block.go:223] [ 1] Interrupt queued I0305 13:24:14.880220 58310 loader.go:566] Received external signal 23, mode: Process D0305 13:24:14.880239 58310 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0305 13:24:14.880331 58310 task_signals.go:446] [ 1] Notified of signal 23 D0305 13:24:14.880421 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:24:14.880400 58310 task_signals.go:220] [ 1] Signal 23: delivering to handler D0305 13:24:14.880822 58310 task_block.go:223] [ 2] Interrupt queued D0305 13:24:14.880892 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:24:14.880858 58310 task_block.go:223] [ 2] Interrupt queued D0305 13:24:14.881052 58310 task_signals.go:176] [ 2] Not restarting syscall 202 after errno 516: interrupted by signal 23 D0305 13:24:14.881215 58310 task_signals.go:220] [ 2] Signal 23: delivering to handler D0305 13:24:15.879304 58310 parameters.go:238] Clock(Monotonic): error: -546 ns, adjusted frequency from 2299774945 Hz to 2299773526 Hz I0305 13:24:15.879378 58310 loader.go:566] Received external signal 23, mode: Process D0305 13:24:15.879578 58310 parameters.go:238] Clock(Realtime): error: -197 ns, adjusted frequency from 2299774687 Hz to 2299774173 Hz D0305 13:24:15.879696 58310 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9904206263853586 monotonicBaseRef:29000233063 monotonicFrequency:2299773526 realtimeReady:1 realtimeBaseCycles:9904206264515022 realtimeBaseRef:1583414655879572870 realtimeFrequency:2299774173} D0305 13:24:15.879812 58310 task_signals.go:446] [ 1] Notified of signal 23 D0305 13:24:15.880091 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:24:15.880207 58310 task_block.go:223] [ 1] Interrupt queued I0305 13:24:15.880280 58310 loader.go:566] Received external signal 23, mode: Process D0305 13:24:15.880410 58310 task_signals.go:446] [ 1] Notified of signal 23 D0305 13:24:15.880404 58310 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0305 13:24:15.880482 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:24:15.880515 58310 task_signals.go:220] [ 1] Signal 23: delivering to handler D0305 13:24:15.880950 58310 task_block.go:223] [ 2] Interrupt queued D0305 13:24:15.881036 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:24:15.881054 58310 task_block.go:223] [ 2] Interrupt queued D0305 13:24:15.881224 58310 task_signals.go:176] [ 2] Not restarting syscall 202 after errno 516: interrupted by signal 23 D0305 13:24:15.881389 58310 task_signals.go:220] [ 2] Signal 23: delivering to handler I0305 13:24:16.835353 58310 loader.go:566] Received external signal 23, mode: Process D0305 13:24:16.835615 58310 task_signals.go:446] [ 1] Notified of signal 23 D0305 13:24:16.835709 58310 task_block.go:223] [ 1] Interrupt queued I0305 13:24:16.835852 58310 loader.go:566] Received external signal 23, mode: Process D0305 13:24:16.836016 58310 task_signals.go:434] [ 1] Discarding duplicate signal 23 D0305 13:24:16.835796 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:24:16.836200 58310 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0305 13:24:16.836526 58310 task_signals.go:220] [ 1] Signal 23: delivering to handler D0305 13:24:16.879328 58310 parameters.go:238] Clock(Monotonic): error: 170 ns, adjusted frequency from 2299773526 Hz to 2299775025 Hz I0305 13:24:16.879459 58310 loader.go:566] Received external signal 23, mode: Process D0305 13:24:16.879546 58310 parameters.go:238] Clock(Realtime): error: 19 ns, adjusted frequency from 2299774173 Hz to 2299774657 Hz D0305 13:24:16.879672 58310 task_signals.go:446] [ 1] Notified of signal 23 D0305 13:24:16.879700 58310 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9904208563690819 monotonicBaseRef:30000260764 monotonicFrequency:2299775025 realtimeReady:1 realtimeBaseCycles:9904208564214473 realtimeBaseRef:1583414656879540378 realtimeFrequency:2299774657} D0305 13:24:16.879803 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:24:16.879832 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:24:16.880072 58310 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0305 13:24:16.880181 58310 task_signals.go:220] [ 1] Signal 23: delivering to handler I0305 13:24:17.837036 58310 loader.go:566] Received external signal 23, mode: Process D0305 13:24:17.837359 58310 task_signals.go:446] [ 1] Notified of signal 23 D0305 13:24:17.837585 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:24:17.837697 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:24:17.837856 58310 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0305 13:24:17.837956 58310 task_signals.go:220] [ 1] Signal 23: delivering to handler D0305 13:24:17.879211 58310 parameters.go:238] Clock(Monotonic): error: 136 ns, adjusted frequency from 2299775025 Hz to 2299774999 Hz D0305 13:24:17.879458 58310 parameters.go:238] Clock(Realtime): error: 337 ns, adjusted frequency from 2299774657 Hz to 2299775464 Hz D0305 13:24:17.879629 58310 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9904210863194420 monotonicBaseRef:31000142742 monotonicFrequency:2299774999 realtimeReady:1 realtimeBaseCycles:9904210863784717 realtimeBaseRef:1583414657879451494 realtimeFrequency:2299775464} D0305 13:24:18.879389 58310 parameters.go:238] Clock(Monotonic): error: 8 ns, adjusted frequency from 2299774999 Hz to 2299774604 Hz D0305 13:24:18.879681 58310 parameters.go:238] Clock(Realtime): error: 21 ns, adjusted frequency from 2299775464 Hz to 2299774699 Hz D0305 13:24:18.879856 58310 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9904213163314776 monotonicBaseRef:32000292911 monotonicFrequency:2299774604 realtimeReady:1 realtimeBaseCycles:9904213164073191 realtimeBaseRef:1583414658879674563 realtimeFrequency:2299774699} I0305 13:24:19.404388 58310 loader.go:566] Received external signal 23, mode: Process D0305 13:24:19.404542 58310 task_signals.go:446] [ 1] Notified of signal 23 D0305 13:24:19.404668 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:24:19.404735 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:24:19.404984 58310 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0305 13:24:19.404949 58310 urpc.go:577] urpc: unmarshal success. D0305 13:24:19.405090 58310 task_signals.go:220] [ 1] Signal 23: delivering to handler D0305 13:24:19.405218 58310 controller.go:501] containerManager.Signal &{CID:ci-gvisor-ptrace-proxy-sandbox-race-1 Signo:0 PID:0 Mode:Process} D0305 13:24:19.405589 58310 urpc.go:534] urpc: successfully marshalled 37 bytes. I0305 13:24:19.835354 58310 loader.go:566] Received external signal 23, mode: Process D0305 13:24:19.835556 58310 task_signals.go:446] [ 1] Notified of signal 23 D0305 13:24:19.835664 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:24:19.835710 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:24:19.835937 58310 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0305 13:24:19.836047 58310 task_signals.go:220] [ 1] Signal 23: delivering to handler D0305 13:24:19.879256 58310 parameters.go:238] Clock(Monotonic): error: -259 ns, adjusted frequency from 2299774604 Hz to 2299774103 Hz D0305 13:24:19.879433 58310 parameters.go:238] Clock(Realtime): error: -209 ns, adjusted frequency from 2299774699 Hz to 2299774086 Hz D0305 13:24:19.879559 58310 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9904215462853269 monotonicBaseRef:33000190243 monotonicFrequency:2299774103 realtimeReady:1 realtimeBaseCycles:9904215463278559 realtimeBaseRef:1583414659879427003 realtimeFrequency:2299774086} D0305 13:24:20.879269 58310 parameters.go:238] Clock(Monotonic): error: 4 ns, adjusted frequency from 2299774103 Hz to 2299774684 Hz D0305 13:24:20.879458 58310 parameters.go:238] Clock(Realtime): error: 95 ns, adjusted frequency from 2299774086 Hz to 2299774822 Hz D0305 13:24:20.879641 58310 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9904217762657480 monotonicBaseRef:34000203334 monotonicFrequency:2299774684 realtimeReady:1 realtimeBaseCycles:9904217763110186 realtimeBaseRef:1583414660879452023 realtimeFrequency:2299774822} D0305 13:24:21.879320 58310 parameters.go:238] Clock(Monotonic): error: -60 ns, adjusted frequency from 2299774684 Hz to 2299774512 Hz D0305 13:24:21.879491 58310 parameters.go:238] Clock(Realtime): error: -240 ns, adjusted frequency from 2299774822 Hz to 2299774141 Hz D0305 13:24:21.879603 58310 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9904220062544961 monotonicBaseRef:35000252380 monotonicFrequency:2299774512 realtimeReady:1 realtimeBaseCycles:9904220062963699 realtimeBaseRef:1583414661879486239 realtimeFrequency:2299774141} D0305 13:24:22.879390 58310 parameters.go:238] Clock(Monotonic): error: -61 ns, adjusted frequency from 2299774512 Hz to 2299774399 Hz D0305 13:24:22.879659 58310 parameters.go:238] Clock(Realtime): error: 310 ns, adjusted frequency from 2299774141 Hz to 2299775316 Hz D0305 13:24:22.879795 58310 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9904222362454778 monotonicBaseRef:36000311214 monotonicFrequency:2299774399 realtimeReady:1 realtimeBaseCycles:9904222363111253 realtimeBaseRef:1583414662879648608 realtimeFrequency:2299775316} D0305 13:24:23.879302 58310 parameters.go:238] Clock(Monotonic): error: 38 ns, adjusted frequency from 2299774399 Hz to 2299774697 Hz D0305 13:24:23.879439 58310 parameters.go:238] Clock(Realtime): error: -359 ns, adjusted frequency from 2299775316 Hz to 2299773825 Hz D0305 13:24:23.879557 58310 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9904224662059389 monotonicBaseRef:37000237385 monotonicFrequency:2299774697 realtimeReady:1 realtimeBaseCycles:9904224662394625 realtimeBaseRef:1583414663879434698 realtimeFrequency:2299773825} I0305 13:24:24.835401 58310 loader.go:566] Received external signal 23, mode: Process D0305 13:24:24.835594 58310 task_signals.go:446] [ 1] Notified of signal 23 D0305 13:24:24.835700 58310 task_block.go:223] [ 1] Interrupt queued I0305 13:24:24.835807 58310 loader.go:566] Received external signal 23, mode: Process D0305 13:24:24.835945 58310 task_signals.go:434] [ 1] Discarding duplicate signal 23 D0305 13:24:24.836000 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:24:24.836253 58310 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0305 13:24:24.836365 58310 task_signals.go:220] [ 1] Signal 23: delivering to handler D0305 13:24:24.879270 58310 parameters.go:238] Clock(Monotonic): error: 71 ns, adjusted frequency from 2299774697 Hz to 2299774643 Hz I0305 13:24:24.879441 58310 loader.go:566] Received external signal 23, mode: Process D0305 13:24:24.879626 58310 task_signals.go:446] [ 1] Notified of signal 23 D0305 13:24:24.879460 58310 parameters.go:238] Clock(Realtime): error: 278 ns, adjusted frequency from 2299773825 Hz to 2299775295 Hz D0305 13:24:24.879719 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:24:24.879831 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:24:24.879779 58310 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9904226961755430 monotonicBaseRef:38000203183 monotonicFrequency:2299774643 realtimeReady:1 realtimeBaseCycles:9904226962211968 realtimeBaseRef:1583414664879453620 realtimeFrequency:2299775295} D0305 13:24:24.880026 58310 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0305 13:24:24.880175 58310 task_signals.go:220] [ 1] Signal 23: delivering to handler I0305 13:24:25.835303 58310 loader.go:566] Received external signal 23, mode: Process D0305 13:24:25.835503 58310 task_signals.go:446] [ 1] Notified of signal 23 D0305 13:24:25.835611 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:24:25.835640 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:24:25.835789 58310 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0305 13:24:25.835899 58310 task_signals.go:220] [ 1] Signal 23: delivering to handler I0305 13:24:25.835795 58310 loader.go:566] Received external signal 23, mode: Process D0305 13:24:25.836059 58310 task_signals.go:446] [ 1] Notified of signal 23 D0305 13:24:25.836153 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:24:25.836281 58310 task_block.go:223] [ 2] Interrupt queued D0305 13:24:25.836284 58310 task_block.go:223] [ 2] Interrupt queued D0305 13:24:25.836385 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:24:25.836641 58310 task_signals.go:176] [ 2] Not restarting syscall 202 after errno 516: interrupted by signal 23 D0305 13:24:25.836793 58310 task_signals.go:220] [ 2] Signal 23: delivering to handler D0305 13:24:25.879336 58310 parameters.go:238] Clock(Monotonic): error: 250 ns, adjusted frequency from 2299774643 Hz to 2299775131 Hz D0305 13:24:25.879702 58310 parameters.go:238] Clock(Realtime): error: 204 ns, adjusted frequency from 2299775295 Hz to 2299775111 Hz D0305 13:24:25.879854 58310 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9904229261679266 monotonicBaseRef:39000268055 monotonicFrequency:2299775131 realtimeReady:1 realtimeBaseCycles:9904229262536010 realtimeBaseRef:1583414665879692228 realtimeFrequency:2299775111} D0305 13:24:26.879290 58310 parameters.go:238] Clock(Monotonic): error: -83 ns, adjusted frequency from 2299775131 Hz to 2299774431 Hz D0305 13:24:26.879498 58310 parameters.go:238] Clock(Realtime): error: -301 ns, adjusted frequency from 2299775111 Hz to 2299773920 Hz D0305 13:24:26.879719 58310 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9904231561348506 monotonicBaseRef:40000222010 monotonicFrequency:2299774431 realtimeReady:1 realtimeBaseCycles:9904231561847807 realtimeBaseRef:1583414666879490767 realtimeFrequency:2299773920} I0305 13:24:27.863344 58310 loader.go:566] Received external signal 23, mode: Process D0305 13:24:27.863502 58310 task_signals.go:446] [ 1] Notified of signal 23 D0305 13:24:27.863612 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:24:27.863652 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:24:27.863911 58310 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0305 13:24:27.864065 58310 task_signals.go:220] [ 1] Signal 23: delivering to handler D0305 13:24:27.879325 58310 parameters.go:238] Clock(Monotonic): error: -64 ns, adjusted frequency from 2299774431 Hz to 2299774491 Hz D0305 13:24:27.879518 58310 parameters.go:238] Clock(Realtime): error: 8 ns, adjusted frequency from 2299773920 Hz to 2299774653 Hz D0305 13:24:27.879667 58310 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9904233861213263 monotonicBaseRef:41000261286 monotonicFrequency:2299774491 realtimeReady:1 realtimeBaseCycles:9904233861673813 realtimeBaseRef:1583414667879513415 realtimeFrequency:2299774653} D0305 13:24:28.879331 58310 parameters.go:238] Clock(Monotonic): error: -54 ns, adjusted frequency from 2299774491 Hz to 2299774396 Hz D0305 13:24:28.879482 58310 parameters.go:238] Clock(Realtime): error: -201 ns, adjusted frequency from 2299774653 Hz to 2299774187 Hz D0305 13:24:28.879566 58310 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9904236160996509 monotonicBaseRef:42000265092 monotonicFrequency:2299774396 realtimeReady:1 realtimeBaseCycles:9904236161367109 realtimeBaseRef:1583414668879478038 realtimeFrequency:2299774187} D0305 13:24:29.879350 58310 parameters.go:238] Clock(Monotonic): error: 560 ns, adjusted frequency from 2299774396 Hz to 2299775935 Hz I0305 13:24:29.879519 58310 loader.go:566] Received external signal 23, mode: Process D0305 13:24:29.879581 58310 parameters.go:238] Clock(Realtime): error: 428 ns, adjusted frequency from 2299774187 Hz to 2299775697 Hz D0305 13:24:29.879838 58310 task_signals.go:446] [ 1] Notified of signal 23 D0305 13:24:29.879819 58310 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9904238460808197 monotonicBaseRef:43000281307 monotonicFrequency:2299775935 realtimeReady:1 realtimeBaseCycles:9904238461361628 realtimeBaseRef:1583414669879573843 realtimeFrequency:2299775697} D0305 13:24:29.880105 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:24:29.880229 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:24:29.880544 58310 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0305 13:24:29.880711 58310 task_signals.go:220] [ 1] Signal 23: delivering to handler D0305 13:24:30.879425 58310 parameters.go:238] Clock(Monotonic): error: -409 ns, adjusted frequency from 2299775935 Hz to 2299773692 Hz I0305 13:24:30.879594 58310 loader.go:566] Received external signal 23, mode: Process D0305 13:24:30.879748 58310 task_signals.go:446] [ 1] Notified of signal 23 D0305 13:24:30.879702 58310 parameters.go:238] Clock(Realtime): error: -209 ns, adjusted frequency from 2299775697 Hz to 2299774135 Hz D0305 13:24:30.879898 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:24:30.879945 58310 task_block.go:223] [ 1] Interrupt queued I0305 13:24:30.880010 58310 loader.go:566] Received external signal 23, mode: Process D0305 13:24:30.879918 58310 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9904240760739026 monotonicBaseRef:44000348658 monotonicFrequency:2299773692 realtimeReady:1 realtimeBaseCycles:9904240761414760 realtimeBaseRef:1583414670879694478 realtimeFrequency:2299774135} D0305 13:24:30.880115 58310 task_signals.go:434] [ 1] Discarding duplicate signal 23 D0305 13:24:30.880261 58310 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0305 13:24:30.880395 58310 task_signals.go:220] [ 1] Signal 23: delivering to handler D0305 13:24:31.879402 58310 parameters.go:238] Clock(Monotonic): error: 154 ns, adjusted frequency from 2299773692 Hz to 2299775045 Hz I0305 13:24:31.879565 58310 loader.go:566] Received external signal 23, mode: Process D0305 13:24:31.879641 58310 parameters.go:238] Clock(Realtime): error: 136 ns, adjusted frequency from 2299774135 Hz to 2299774949 Hz D0305 13:24:31.879737 58310 task_signals.go:446] [ 1] Notified of signal 23 D0305 13:24:31.879861 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:24:31.879909 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:24:31.879821 58310 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9904243060478448 monotonicBaseRef:45000333756 monotonicFrequency:2299775045 realtimeReady:1 realtimeBaseCycles:9904243061049126 realtimeBaseRef:1583414671879633702 realtimeFrequency:2299774949} I0305 13:24:31.880126 58310 loader.go:566] Received external signal 23, mode: Process D0305 13:24:31.880282 58310 task_signals.go:446] [ 1] Notified of signal 23 D0305 13:24:31.880380 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:24:31.880216 58310 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0305 13:24:31.880705 58310 task_signals.go:220] [ 1] Signal 23: delivering to handler D0305 13:24:31.881490 58310 task_block.go:223] [ 2] Interrupt queued D0305 13:24:31.881533 58310 task_block.go:223] [ 2] Interrupt queued D0305 13:24:31.881611 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:24:31.881755 58310 task_signals.go:176] [ 2] Not restarting syscall 202 after errno 516: interrupted by signal 23 D0305 13:24:31.881887 58310 task_signals.go:220] [ 2] Signal 23: delivering to handler I0305 13:24:32.046499 58310 loader.go:566] Received external signal 23, mode: Process D0305 13:24:32.046753 58310 task_signals.go:446] [ 1] Notified of signal 23 I0305 13:24:32.046721 58310 watchdog.go:277] Watchdog starting loop, tasks: 15, discount: 0s D0305 13:24:32.046904 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:24:32.047024 58310 task_block.go:223] [ 1] Interrupt queued I0305 13:24:32.047090 58310 loader.go:566] Received external signal 23, mode: Process D0305 13:24:32.047269 58310 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0305 13:24:32.047368 58310 task_signals.go:446] [ 1] Notified of signal 23 D0305 13:24:32.047386 58310 task_signals.go:220] [ 1] Signal 23: delivering to handler D0305 13:24:32.047559 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:24:32.048144 58310 task_block.go:223] [ 2] Interrupt queued D0305 13:24:32.048263 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:24:32.048263 58310 task_block.go:223] [ 2] Interrupt queued D0305 13:24:32.048437 58310 task_signals.go:176] [ 2] Not restarting syscall 202 after errno 516: interrupted by signal 23 D0305 13:24:32.048549 58310 task_signals.go:220] [ 2] Signal 23: delivering to handler I0305 13:24:32.059317 58310 loader.go:566] Received external signal 23, mode: Process D0305 13:24:32.059539 58310 task_signals.go:446] [ 1] Notified of signal 23 D0305 13:24:32.059706 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:24:32.059866 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:24:32.060070 58310 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0305 13:24:32.060199 58310 task_signals.go:220] [ 1] Signal 23: delivering to handler D0305 13:24:32.879317 58310 parameters.go:238] Clock(Monotonic): error: -257 ns, adjusted frequency from 2299775045 Hz to 2299774018 Hz D0305 13:24:32.879469 58310 parameters.go:238] Clock(Realtime): error: -339 ns, adjusted frequency from 2299774949 Hz to 2299773848 Hz D0305 13:24:32.879564 58310 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9904245360035822 monotonicBaseRef:46000239107 monotonicFrequency:2299774018 realtimeReady:1 realtimeBaseCycles:9904245360435675 realtimeBaseRef:1583414672879464815 realtimeFrequency:2299773848} I0305 13:24:33.835379 58310 loader.go:566] Received external signal 23, mode: Process D0305 13:24:33.835566 58310 task_signals.go:446] [ 1] Notified of signal 23 D0305 13:24:33.835694 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:24:33.835809 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:24:33.835991 58310 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0305 13:24:33.836118 58310 task_signals.go:220] [ 1] Signal 23: delivering to handler D0305 13:24:33.879329 58310 parameters.go:238] Clock(Monotonic): error: -148 ns, adjusted frequency from 2299774018 Hz to 2299774302 Hz I0305 13:24:33.879404 58310 loader.go:566] Received external signal 23, mode: Process D0305 13:24:33.879500 58310 parameters.go:238] Clock(Realtime): error: -3 ns, adjusted frequency from 2299773848 Hz to 2299774617 Hz D0305 13:24:33.879546 58310 task_signals.go:446] [ 1] Notified of signal 23 D0305 13:24:33.879630 58310 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9904247659871296 monotonicBaseRef:47000265829 monotonicFrequency:2299774302 realtimeReady:1 realtimeBaseCycles:9904247660281178 realtimeBaseRef:1583414673879495972 realtimeFrequency:2299774617} D0305 13:24:33.879671 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:24:33.879706 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:24:33.879967 58310 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0305 13:24:33.880083 58310 task_signals.go:220] [ 1] Signal 23: delivering to handler I0305 13:24:34.836151 58310 loader.go:566] Received external signal 23, mode: Process D0305 13:24:34.836329 58310 task_signals.go:446] [ 1] Notified of signal 23 D0305 13:24:34.836447 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:24:34.836549 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:24:34.836665 58310 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0305 13:24:34.836794 58310 task_signals.go:220] [ 1] Signal 23: delivering to handler D0305 13:24:34.879344 58310 parameters.go:238] Clock(Monotonic): error: 325 ns, adjusted frequency from 2299774302 Hz to 2299775506 Hz D0305 13:24:34.879530 58310 parameters.go:238] Clock(Realtime): error: -33 ns, adjusted frequency from 2299774617 Hz to 2299774567 Hz I0305 13:24:34.879544 58310 loader.go:566] Received external signal 23, mode: Process D0305 13:24:34.879748 58310 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9904249959672591 monotonicBaseRef:48000277566 monotonicFrequency:2299775506 realtimeReady:1 realtimeBaseCycles:9904249960123731 realtimeBaseRef:1583414674879525512 realtimeFrequency:2299774567} D0305 13:24:34.879847 58310 task_signals.go:446] [ 1] Notified of signal 23 D0305 13:24:34.880029 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:24:34.880134 58310 task_block.go:223] [ 1] Interrupt queued I0305 13:24:34.880173 58310 loader.go:566] Received external signal 23, mode: Process D0305 13:24:34.880320 58310 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0305 13:24:34.880362 58310 task_signals.go:446] [ 1] Notified of signal 23 D0305 13:24:34.880428 58310 task_signals.go:220] [ 1] Signal 23: delivering to handler D0305 13:24:34.880544 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:24:34.880841 58310 task_block.go:223] [ 2] Interrupt queued D0305 13:24:34.880947 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:24:34.880891 58310 task_block.go:223] [ 2] Interrupt queued D0305 13:24:34.881200 58310 task_signals.go:176] [ 2] Not restarting syscall 202 after errno 516: interrupted by signal 23 D0305 13:24:34.881394 58310 task_signals.go:220] [ 2] Signal 23: delivering to handler D0305 13:24:35.879334 58310 parameters.go:238] Clock(Monotonic): error: 176 ns, adjusted frequency from 2299775506 Hz to 2299775098 Hz D0305 13:24:35.879580 58310 parameters.go:238] Clock(Realtime): error: 342 ns, adjusted frequency from 2299774567 Hz to 2299775455 Hz D0305 13:24:35.879721 58310 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9904252259421672 monotonicBaseRef:49000266075 monotonicFrequency:2299775098 realtimeReady:1 realtimeBaseCycles:9904252260009076 realtimeBaseRef:1583414675879573681 realtimeFrequency:2299775455} D0305 13:24:36.879321 58310 parameters.go:238] Clock(Monotonic): error: -213 ns, adjusted frequency from 2299775098 Hz to 2299774173 Hz D0305 13:24:36.879533 58310 parameters.go:238] Clock(Realtime): error: -29 ns, adjusted frequency from 2299775455 Hz to 2299774561 Hz D0305 13:24:36.879690 58310 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9904254559165143 monotonicBaseRef:50000252322 monotonicFrequency:2299774173 realtimeReady:1 realtimeBaseCycles:9904254559671860 realtimeBaseRef:1583414676879524688 realtimeFrequency:2299774561} I0305 13:24:37.863752 58310 loader.go:566] Received external signal 23, mode: Process D0305 13:24:37.863965 58310 task_signals.go:446] [ 1] Notified of signal 23 D0305 13:24:37.864070 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:24:37.864106 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:24:37.864298 58310 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0305 13:24:37.864412 58310 task_signals.go:220] [ 1] Signal 23: delivering to handler D0305 13:24:37.879295 58310 parameters.go:238] Clock(Monotonic): error: -188 ns, adjusted frequency from 2299774173 Hz to 2299774220 Hz D0305 13:24:37.879555 58310 parameters.go:238] Clock(Realtime): error: -21 ns, adjusted frequency from 2299774561 Hz to 2299774553 Hz I0305 13:24:37.879567 58310 loader.go:566] Received external signal 23, mode: Process D0305 13:24:37.879796 58310 task_signals.go:446] [ 1] Notified of signal 23 D0305 13:24:37.879696 58310 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9904256858885463 monotonicBaseRef:51000228905 monotonicFrequency:2299774220 realtimeReady:1 realtimeBaseCycles:9904256859495561 realtimeBaseRef:1583414677879546055 realtimeFrequency:2299774553} D0305 13:24:37.879920 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:24:37.879953 58310 task_block.go:223] [ 1] Interrupt queued I0305 13:24:37.880067 58310 loader.go:566] Received external signal 23, mode: Process D0305 13:24:37.880157 58310 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0305 13:24:37.880224 58310 task_signals.go:446] [ 1] Notified of signal 23 D0305 13:24:37.880257 58310 task_signals.go:220] [ 1] Signal 23: delivering to handler D0305 13:24:37.880319 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:24:37.880689 58310 task_block.go:223] [ 2] Interrupt queued D0305 13:24:37.880795 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:24:37.880735 58310 task_block.go:223] [ 2] Interrupt queued D0305 13:24:37.881006 58310 task_signals.go:176] [ 2] Not restarting syscall 202 after errno 516: interrupted by signal 23 D0305 13:24:37.881139 58310 task_signals.go:220] [ 2] Signal 23: delivering to handler I0305 13:24:38.835264 58310 loader.go:566] Received external signal 23, mode: Process D0305 13:24:38.835526 58310 task_signals.go:446] [ 1] Notified of signal 23 D0305 13:24:38.835663 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:24:38.835718 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:24:38.835938 58310 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0305 13:24:38.836082 58310 task_signals.go:220] [ 1] Signal 23: delivering to handler D0305 13:24:38.879291 58310 parameters.go:238] Clock(Monotonic): error: 408 ns, adjusted frequency from 2299774220 Hz to 2299775562 Hz D0305 13:24:38.879678 58310 parameters.go:238] Clock(Realtime): error: 273 ns, adjusted frequency from 2299774553 Hz to 2299775219 Hz D0305 13:24:38.879914 58310 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9904259158649106 monotonicBaseRef:52000224305 monotonicFrequency:2299775562 realtimeReady:1 realtimeBaseCycles:9904259159555010 realtimeBaseRef:1583414678879669934 realtimeFrequency:2299775219} I0305 13:24:39.835453 58310 loader.go:566] Received external signal 23, mode: Process D0305 13:24:39.835724 58310 task_signals.go:446] [ 1] Notified of signal 23 D0305 13:24:39.835851 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:24:39.836021 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:24:39.836176 58310 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0305 13:24:39.836330 58310 task_signals.go:220] [ 1] Signal 23: delivering to handler D0305 13:24:39.879287 58310 parameters.go:238] Clock(Monotonic): error: -488 ns, adjusted frequency from 2299775562 Hz to 2299773529 Hz D0305 13:24:39.879477 58310 parameters.go:238] Clock(Realtime): error: -563 ns, adjusted frequency from 2299775219 Hz to 2299773343 Hz D0305 13:24:39.879573 58310 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9904261458424009 monotonicBaseRef:53000224018 monotonicFrequency:2299773529 realtimeReady:1 realtimeBaseCycles:9904261458858436 realtimeBaseRef:1583414679879464786 realtimeFrequency:2299773343} I0305 13:24:40.835464 58310 loader.go:566] Received external signal 23, mode: Process D0305 13:24:40.835657 58310 task_signals.go:446] [ 1] Notified of signal 23 D0305 13:24:40.835800 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:24:40.835851 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:24:40.836108 58310 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0305 13:24:40.836245 58310 task_signals.go:220] [ 1] Signal 23: delivering to handler D0305 13:24:40.879349 58310 parameters.go:238] Clock(Monotonic): error: 599 ns, adjusted frequency from 2299773529 Hz to 2299776047 Hz D0305 13:24:40.879554 58310 parameters.go:238] Clock(Realtime): error: 235 ns, adjusted frequency from 2299773343 Hz to 2299775169 Hz D0305 13:24:40.879707 58310 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9904263758321763 monotonicBaseRef:54000278034 monotonicFrequency:2299776047 realtimeReady:1 realtimeBaseCycles:9904263758822875 realtimeBaseRef:1583414680879547879 realtimeFrequency:2299775169} I0305 13:24:41.835268 58310 loader.go:566] Received external signal 23, mode: Process D0305 13:24:41.835540 58310 task_signals.go:446] [ 1] Notified of signal 23 D0305 13:24:41.835627 58310 task_block.go:223] [ 1] Interrupt queued I0305 13:24:41.835760 58310 loader.go:566] Received external signal 23, mode: Process D0305 13:24:41.835864 58310 task_signals.go:434] [ 1] Discarding duplicate signal 23 D0305 13:24:41.835779 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:24:41.836351 58310 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0305 13:24:41.836560 58310 task_signals.go:220] [ 1] Signal 23: delivering to handler I0305 13:24:41.836597 58310 loader.go:566] Received external signal 23, mode: Process D0305 13:24:41.837303 58310 task_signals.go:446] [ 1] Notified of signal 23 D0305 13:24:41.838208 58310 task_block.go:223] [ 1] Interrupt queued I0305 13:24:41.838409 58310 loader.go:566] Received external signal 23, mode: Process D0305 13:24:41.838494 58310 task_block.go:223] [ 2] Interrupt queued D0305 13:24:41.838521 58310 task_block.go:223] [ 2] Interrupt queued D0305 13:24:41.838667 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:24:41.838928 58310 task_signals.go:434] [ 1] Discarding duplicate signal 23 D0305 13:24:41.839148 58310 task_signals.go:176] [ 2] Not restarting syscall 202 after errno 516: interrupted by signal 23 D0305 13:24:41.839245 58310 task_signals.go:220] [ 2] Signal 23: delivering to handler D0305 13:24:41.879347 58310 parameters.go:238] Clock(Monotonic): error: -433 ns, adjusted frequency from 2299776047 Hz to 2299773610 Hz D0305 13:24:41.879649 58310 parameters.go:238] Clock(Realtime): error: 97 ns, adjusted frequency from 2299775169 Hz to 2299774879 Hz D0305 13:24:41.879771 58310 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9904266058083575 monotonicBaseRef:55000271844 monotonicFrequency:2299773610 realtimeReady:1 realtimeBaseCycles:9904266058815175 realtimeBaseRef:1583414681879642293 realtimeFrequency:2299774879} I0305 13:24:42.835252 58310 loader.go:566] Received external signal 23, mode: Process D0305 13:24:42.835530 58310 task_signals.go:446] [ 1] Notified of signal 23 D0305 13:24:42.835705 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:24:42.835730 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:24:42.835943 58310 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0305 13:24:42.836051 58310 task_signals.go:220] [ 1] Signal 23: delivering to handler D0305 13:24:42.879271 58310 parameters.go:238] Clock(Monotonic): error: -145 ns, adjusted frequency from 2299773610 Hz to 2299774325 Hz I0305 13:24:42.879353 58310 loader.go:566] Received external signal 23, mode: Process D0305 13:24:42.879440 58310 parameters.go:238] Clock(Realtime): error: -327 ns, adjusted frequency from 2299774879 Hz to 2299773869 Hz D0305 13:24:42.879516 58310 task_signals.go:446] [ 1] Notified of signal 23 D0305 13:24:42.879528 58310 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9904268357572177 monotonicBaseRef:56000147915 monotonicFrequency:2299774325 realtimeReady:1 realtimeBaseCycles:9904268358110405 realtimeBaseRef:1583414682879433729 realtimeFrequency:2299773869} D0305 13:24:42.879646 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:24:42.879786 58310 task_block.go:223] [ 1] Interrupt queued I0305 13:24:42.879808 58310 loader.go:566] Received external signal 23, mode: Process D0305 13:24:42.880056 58310 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0305 13:24:42.880064 58310 task_signals.go:446] [ 1] Notified of signal 23 D0305 13:24:42.880179 58310 task_signals.go:220] [ 1] Signal 23: delivering to handler D0305 13:24:42.880249 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:24:42.880696 58310 task_block.go:223] [ 2] Interrupt queued D0305 13:24:42.880741 58310 task_block.go:223] [ 2] Interrupt queued D0305 13:24:42.880868 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:24:42.881162 58310 task_signals.go:176] [ 2] Not restarting syscall 202 after errno 516: interrupted by signal 23 D0305 13:24:42.881329 58310 task_signals.go:220] [ 2] Signal 23: delivering to handler I0305 13:24:43.835276 58310 loader.go:566] Received external signal 23, mode: Process D0305 13:24:43.835522 58310 task_signals.go:446] [ 1] Notified of signal 23 D0305 13:24:43.835652 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:24:43.835695 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:24:43.835926 58310 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0305 13:24:43.836053 58310 task_signals.go:220] [ 1] Signal 23: delivering to handler D0305 13:24:43.879306 58310 parameters.go:238] Clock(Monotonic): error: -30 ns, adjusted frequency from 2299774325 Hz to 2299774551 Hz D0305 13:24:43.879466 58310 parameters.go:238] Clock(Realtime): error: -11 ns, adjusted frequency from 2299773869 Hz to 2299774617 Hz D0305 13:24:43.879550 58310 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9904270657554188 monotonicBaseRef:57000238222 monotonicFrequency:2299774551 realtimeReady:1 realtimeBaseCycles:9904270657947820 realtimeBaseRef:1583414683879461360 realtimeFrequency:2299774617} I0305 13:24:44.835309 58310 loader.go:566] Received external signal 23, mode: Process D0305 13:24:44.835530 58310 task_signals.go:446] [ 1] Notified of signal 23 D0305 13:24:44.835648 58310 task_block.go:223] [ 1] Interrupt queued I0305 13:24:44.835772 58310 loader.go:566] Received external signal 23, mode: Process D0305 13:24:44.835769 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:24:44.835895 58310 task_signals.go:434] [ 1] Discarding duplicate signal 23 D0305 13:24:44.835994 58310 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0305 13:24:44.836116 58310 task_signals.go:220] [ 1] Signal 23: delivering to handler D0305 13:24:44.879313 58310 parameters.go:238] Clock(Monotonic): error: 16 ns, adjusted frequency from 2299774551 Hz to 2299774659 Hz I0305 13:24:44.879471 58310 loader.go:566] Received external signal 23, mode: Process D0305 13:24:44.879516 58310 parameters.go:238] Clock(Realtime): error: 90 ns, adjusted frequency from 2299774617 Hz to 2299774823 Hz D0305 13:24:44.879695 58310 task_signals.go:446] [ 1] Notified of signal 23 D0305 13:24:44.879810 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:24:44.879762 58310 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9904272957341716 monotonicBaseRef:58000243864 monotonicFrequency:2299774659 realtimeReady:1 realtimeBaseCycles:9904272957836807 realtimeBaseRef:1583414684879511090 realtimeFrequency:2299774823} D0305 13:24:44.879931 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:24:44.880131 58310 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0305 13:24:44.880251 58310 task_signals.go:220] [ 1] Signal 23: delivering to handler D0305 13:24:45.879321 58310 parameters.go:238] Clock(Monotonic): error: 296 ns, adjusted frequency from 2299774659 Hz to 2299775168 Hz I0305 13:24:45.879427 58310 loader.go:566] Received external signal 23, mode: Process D0305 13:24:45.879538 58310 parameters.go:238] Clock(Realtime): error: -61 ns, adjusted frequency from 2299774823 Hz to 2299774498 Hz D0305 13:24:45.879686 58310 task_signals.go:446] [ 1] Notified of signal 23 D0305 13:24:45.879684 58310 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9904275257072077 monotonicBaseRef:59000224602 monotonicFrequency:2299775168 realtimeReady:1 realtimeBaseCycles:9904275257661282 realtimeBaseRef:1583414685879532679 realtimeFrequency:2299774498} D0305 13:24:45.879815 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:24:45.879918 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:24:45.880257 58310 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0305 13:24:45.880406 58310 task_signals.go:220] [ 1] Signal 23: delivering to handler I0305 13:24:46.835371 58310 loader.go:566] Received external signal 23, mode: Process D0305 13:24:46.835594 58310 task_signals.go:446] [ 1] Notified of signal 23 D0305 13:24:46.835676 58310 task_block.go:223] [ 1] Interrupt queued I0305 13:24:46.835825 58310 loader.go:566] Received external signal 23, mode: Process D0305 13:24:46.835727 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:24:46.835949 58310 task_signals.go:434] [ 1] Discarding duplicate signal 23 D0305 13:24:46.836084 58310 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0305 13:24:46.836227 58310 task_signals.go:220] [ 1] Signal 23: delivering to handler D0305 13:24:46.879321 58310 parameters.go:238] Clock(Monotonic): error: -191 ns, adjusted frequency from 2299775168 Hz to 2299774255 Hz I0305 13:24:46.879414 58310 loader.go:566] Received external signal 23, mode: Process D0305 13:24:46.879567 58310 parameters.go:238] Clock(Realtime): error: 87 ns, adjusted frequency from 2299774498 Hz to 2299774859 Hz D0305 13:24:46.879638 58310 task_signals.go:446] [ 1] Notified of signal 23 D0305 13:24:46.879729 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:24:46.879702 58310 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9904277556912528 monotonicBaseRef:60000252988 monotonicFrequency:2299774255 realtimeReady:1 realtimeBaseCycles:9904277557502393 realtimeBaseRef:1583414686879561644 realtimeFrequency:2299774859} D0305 13:24:46.879789 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:24:46.880090 58310 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0305 13:24:46.880209 58310 task_signals.go:220] [ 1] Signal 23: delivering to handler D0305 13:24:47.879271 58310 parameters.go:238] Clock(Monotonic): error: -61 ns, adjusted frequency from 2299774255 Hz to 2299774472 Hz D0305 13:24:47.879454 58310 parameters.go:238] Clock(Realtime): error: -77 ns, adjusted frequency from 2299774859 Hz to 2299774474 Hz I0305 13:24:47.879520 58310 loader.go:566] Received external signal 23, mode: Process D0305 13:24:47.879600 58310 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9904279856577552 monotonicBaseRef:61000205491 monotonicFrequency:2299774472 realtimeReady:1 realtimeBaseCycles:9904279857019231 realtimeBaseRef:1583414687879449449 realtimeFrequency:2299774474} D0305 13:24:47.879806 58310 task_signals.go:446] [ 1] Notified of signal 23 D0305 13:24:47.879925 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:24:47.879977 58310 task_block.go:223] [ 1] Interrupt queued I0305 13:24:47.880166 58310 loader.go:566] Received external signal 23, mode: Process D0305 13:24:47.880250 58310 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0305 13:24:47.880366 58310 task_signals.go:446] [ 1] Notified of signal 23 D0305 13:24:47.880370 58310 task_signals.go:220] [ 1] Signal 23: delivering to handler D0305 13:24:47.880481 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:24:47.880852 58310 task_block.go:223] [ 2] Interrupt queued D0305 13:24:47.880937 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:24:47.880900 58310 task_block.go:223] [ 2] Interrupt queued D0305 13:24:47.881094 58310 task_signals.go:176] [ 2] Not restarting syscall 202 after errno 516: interrupted by signal 23 D0305 13:24:47.881235 58310 task_signals.go:220] [ 2] Signal 23: delivering to handler I0305 13:24:48.835459 58310 loader.go:566] Received external signal 23, mode: Process D0305 13:24:48.835650 58310 task_signals.go:446] [ 1] Notified of signal 23 D0305 13:24:48.835745 58310 task_block.go:223] [ 1] Interrupt queued I0305 13:24:48.835895 58310 loader.go:566] Received external signal 23, mode: Process D0305 13:24:48.835899 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:24:48.835970 58310 task_signals.go:434] [ 1] Discarding duplicate signal 23 D0305 13:24:48.836067 58310 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0305 13:24:48.836185 58310 task_signals.go:220] [ 1] Signal 23: delivering to handler D0305 13:24:48.879378 58310 parameters.go:238] Clock(Monotonic): error: -2 ns, adjusted frequency from 2299774472 Hz to 2299774612 Hz I0305 13:24:48.879486 58310 loader.go:566] Received external signal 23, mode: Process D0305 13:24:48.879739 58310 parameters.go:238] Clock(Realtime): error: 558 ns, adjusted frequency from 2299774474 Hz to 2299775942 Hz D0305 13:24:48.879751 58310 task_signals.go:446] [ 1] Notified of signal 23 D0305 13:24:48.880078 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:24:48.879953 58310 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9904282156590424 monotonicBaseRef:62000309153 monotonicFrequency:2299774612 realtimeReady:1 realtimeBaseCycles:9904282157261231 realtimeBaseRef:1583414688879652741 realtimeFrequency:2299775942} D0305 13:24:48.880246 58310 task_block.go:223] [ 1] Interrupt queued I0305 13:24:48.880315 58310 loader.go:566] Received external signal 23, mode: Process D0305 13:24:48.880487 58310 task_signals.go:434] [ 1] Discarding duplicate signal 23 D0305 13:24:48.880645 58310 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0305 13:24:48.880757 58310 task_signals.go:220] [ 1] Signal 23: delivering to handler I0305 13:24:49.835445 58310 loader.go:566] Received external signal 23, mode: Process D0305 13:24:49.835617 58310 task_signals.go:446] [ 1] Notified of signal 23 D0305 13:24:49.835729 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:24:49.835744 58310 task_block.go:223] [ 1] Interrupt queued I0305 13:24:49.835859 58310 loader.go:566] Received external signal 23, mode: Process D0305 13:24:49.835963 58310 task_signals.go:446] [ 1] Notified of signal 23 D0305 13:24:49.835948 58310 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0305 13:24:49.836047 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:24:49.836074 58310 task_signals.go:220] [ 1] Signal 23: delivering to handler D0305 13:24:49.836404 58310 task_block.go:223] [ 2] Interrupt queued D0305 13:24:49.836427 58310 task_block.go:223] [ 2] Interrupt queued D0305 13:24:49.836475 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:24:49.836570 58310 task_signals.go:176] [ 2] Not restarting syscall 202 after errno 516: interrupted by signal 23 D0305 13:24:49.836675 58310 task_signals.go:220] [ 2] Signal 23: delivering to handler D0305 13:24:49.879345 58310 sampler.go:197] Time: Adjusting syscall overhead down to 14000 I0305 13:24:49.879562 58310 loader.go:566] Received external signal 23, mode: Process D0305 13:24:49.879636 58310 parameters.go:238] Clock(Monotonic): error: 60 ns, adjusted frequency from 2299774612 Hz to 2299774715 Hz D0305 13:24:49.879774 58310 task_signals.go:446] [ 1] Notified of signal 23 D0305 13:24:49.879800 58310 parameters.go:238] Clock(Realtime): error: -285 ns, adjusted frequency from 2299775942 Hz to 2299773991 Hz D0305 13:24:49.879873 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:24:49.879940 58310 task_block.go:223] [ 1] Interrupt queued I0305 13:24:49.880029 58310 loader.go:566] Received external signal 23, mode: Process D0305 13:24:49.879982 58310 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9904284456303814 monotonicBaseRef:63000282532 monotonicFrequency:2299774715 realtimeReady:1 realtimeBaseCycles:9904284457357240 realtimeBaseRef:1583414689879791914 realtimeFrequency:2299773991} D0305 13:24:49.880138 58310 task_signals.go:434] [ 1] Discarding duplicate signal 23 D0305 13:24:49.880278 58310 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0305 13:24:49.880397 58310 task_signals.go:220] [ 1] Signal 23: delivering to handler I0305 13:24:50.240532 58310 loader.go:566] Received external signal 23, mode: Process D0305 13:24:50.240711 58310 task_signals.go:446] [ 1] Notified of signal 23 D0305 13:24:50.240845 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:24:50.240898 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:24:50.240946 58310 urpc.go:577] urpc: unmarshal success. D0305 13:24:50.241110 58310 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0305 13:24:50.241269 58310 task_signals.go:220] [ 1] Signal 23: delivering to handler D0305 13:24:50.241194 58310 controller.go:501] containerManager.Signal &{CID:ci-gvisor-ptrace-proxy-sandbox-race-1 Signo:0 PID:0 Mode:Process} D0305 13:24:50.241524 58310 urpc.go:534] urpc: successfully marshalled 37 bytes. D0305 13:24:50.879292 58310 parameters.go:238] Clock(Monotonic): error: -74 ns, adjusted frequency from 2299774715 Hz to 2299774592 Hz D0305 13:24:50.879476 58310 parameters.go:238] Clock(Realtime): error: -350 ns, adjusted frequency from 2299773991 Hz to 2299773811 Hz D0305 13:24:50.879581 58310 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9904286755950891 monotonicBaseRef:64000227031 monotonicFrequency:2299774592 realtimeReady:1 realtimeBaseCycles:9904286756393531 realtimeBaseRef:1583414690879471143 realtimeFrequency:2299773811} D0305 13:24:51.879349 58310 parameters.go:238] Clock(Monotonic): error: -350 ns, adjusted frequency from 2299774592 Hz to 2299773932 Hz I0305 13:24:51.879436 58310 loader.go:566] Received external signal 23, mode: Process D0305 13:24:51.879550 58310 parameters.go:238] Clock(Realtime): error: 73 ns, adjusted frequency from 2299773811 Hz to 2299774813 Hz D0305 13:24:51.879633 58310 task_signals.go:446] [ 1] Notified of signal 23 D0305 13:24:51.879747 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:24:51.879671 58310 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9904289055849101 monotonicBaseRef:65000280783 monotonicFrequency:2299773932 realtimeReady:1 realtimeBaseCycles:9904289056336710 realtimeBaseRef:1583414691879544788 realtimeFrequency:2299774813} D0305 13:24:51.879796 58310 task_block.go:223] [ 1] Interrupt queued I0305 13:24:51.879907 58310 loader.go:566] Received external signal 23, mode: Process D0305 13:24:51.880045 58310 task_signals.go:434] [ 1] Discarding duplicate signal 23 D0305 13:24:51.880144 58310 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0305 13:24:51.880252 58310 task_signals.go:220] [ 1] Signal 23: delivering to handler D0305 13:24:52.879316 58310 parameters.go:238] Clock(Monotonic): error: 454 ns, adjusted frequency from 2299773932 Hz to 2299775643 Hz D0305 13:24:52.879563 58310 parameters.go:238] Clock(Realtime): error: 129 ns, adjusted frequency from 2299774813 Hz to 2299774918 Hz D0305 13:24:52.879744 58310 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9904291355512294 monotonicBaseRef:66000232630 monotonicFrequency:2299775643 realtimeReady:1 realtimeBaseCycles:9904291356138447 realtimeBaseRef:1583414692879556495 realtimeFrequency:2299774918} D0305 13:24:53.879312 58310 parameters.go:238] Clock(Monotonic): error: -176 ns, adjusted frequency from 2299775643 Hz to 2299774233 Hz D0305 13:24:53.879510 58310 parameters.go:238] Clock(Realtime): error: -209 ns, adjusted frequency from 2299774918 Hz to 2299774156 Hz D0305 13:24:53.880071 58310 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9904293655318616 monotonicBaseRef:67000245969 monotonicFrequency:2299774233 realtimeReady:1 realtimeBaseCycles:9904293655795698 realtimeBaseRef:1583414693879505330 realtimeFrequency:2299774156} I0305 13:24:54.835505 58310 loader.go:566] Received external signal 23, mode: Process D0305 13:24:54.835783 58310 task_signals.go:446] [ 1] Notified of signal 23 D0305 13:24:54.835900 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:24:54.835927 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:24:54.836109 58310 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0305 13:24:54.836259 58310 task_signals.go:220] [ 1] Signal 23: delivering to handler D0305 13:24:54.879317 58310 parameters.go:238] Clock(Monotonic): error: 68 ns, adjusted frequency from 2299774233 Hz to 2299774860 Hz D0305 13:24:54.879533 58310 parameters.go:238] Clock(Realtime): error: 9 ns, adjusted frequency from 2299774156 Hz to 2299774668 Hz D0305 13:24:54.879685 58310 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9904295955096320 monotonicBaseRef:68000247478 monotonicFrequency:2299774860 realtimeReady:1 realtimeBaseCycles:9904295955622414 realtimeBaseRef:1583414694879528184 realtimeFrequency:2299774668} I0305 13:24:55.835661 58310 loader.go:566] Received external signal 23, mode: Process D0305 13:24:55.835960 58310 task_signals.go:446] [ 1] Notified of signal 23 D0305 13:24:55.836102 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:24:55.836225 58310 task_block.go:223] [ 1] Interrupt queued I0305 13:24:55.836276 58310 loader.go:566] Received external signal 23, mode: Process D0305 13:24:55.836419 58310 task_signals.go:446] [ 1] Notified of signal 23 D0305 13:24:55.836418 58310 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0305 13:24:55.836527 58310 task_signals.go:220] [ 1] Signal 23: delivering to handler D0305 13:24:55.836504 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:24:55.836897 58310 task_block.go:223] [ 2] Interrupt queued D0305 13:24:55.836996 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:24:55.837017 58310 task_block.go:223] [ 2] Interrupt queued D0305 13:24:55.837288 58310 task_signals.go:176] [ 2] Not restarting syscall 270 after errno 514: interrupted by signal 23 D0305 13:24:55.837420 58310 task_signals.go:220] [ 2] Signal 23: delivering to handler D0305 13:24:55.879328 58310 parameters.go:238] Clock(Monotonic): error: -359 ns, adjusted frequency from 2299774860 Hz to 2299773720 Hz D0305 13:24:55.879506 58310 parameters.go:238] Clock(Realtime): error: -26 ns, adjusted frequency from 2299774668 Hz to 2299774570 Hz I0305 13:24:55.879442 58310 loader.go:566] Received external signal 23, mode: Process D0305 13:24:55.879607 58310 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9904298254900163 monotonicBaseRef:69000260080 monotonicFrequency:2299773720 realtimeReady:1 realtimeBaseCycles:9904298255334906 realtimeBaseRef:1583414695879501148 realtimeFrequency:2299774570} D0305 13:24:55.879788 58310 task_signals.go:446] [ 1] Notified of signal 23 D0305 13:24:55.879970 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:24:55.880108 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:24:55.880351 58310 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0305 13:24:55.880450 58310 task_signals.go:220] [ 1] Signal 23: delivering to handler D0305 13:24:56.879363 58310 parameters.go:238] Clock(Monotonic): error: 660 ns, adjusted frequency from 2299773720 Hz to 2299776129 Hz D0305 13:24:56.879620 58310 sampler.go:197] Time: Adjusting syscall overhead down to 9380 D0305 13:24:56.879692 58310 parameters.go:238] Clock(Realtime): error: 115 ns, adjusted frequency from 2299774570 Hz to 2299774883 Hz D0305 13:24:56.879821 58310 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9904300554745432 monotonicBaseRef:70000291191 monotonicFrequency:2299776129 realtimeReady:1 realtimeBaseCycles:9904300555374020 realtimeBaseRef:1583414696879616178 realtimeFrequency:2299774883} I0305 13:24:57.879428 58310 loader.go:566] Received external signal 23, mode: Process D0305 13:24:57.879358 58310 parameters.go:238] Clock(Monotonic): error: -409 ns, adjusted frequency from 2299776129 Hz to 2299773699 Hz D0305 13:24:57.879614 58310 task_signals.go:446] [ 1] Notified of signal 23 D0305 13:24:57.879710 58310 parameters.go:238] Clock(Realtime): error: 62 ns, adjusted frequency from 2299774883 Hz to 2299774773 Hz D0305 13:24:57.879829 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:24:57.879849 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:24:57.879845 58310 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9904302854520374 monotonicBaseRef:71000290674 monotonicFrequency:2299773699 realtimeReady:1 realtimeBaseCycles:9904302855349808 realtimeBaseRef:1583414697879703536 realtimeFrequency:2299774773} D0305 13:24:57.880100 58310 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0305 13:24:57.880278 58310 task_signals.go:220] [ 1] Signal 23: delivering to handler I0305 13:24:58.835452 58310 loader.go:566] Received external signal 23, mode: Process D0305 13:24:58.835705 58310 task_signals.go:446] [ 1] Notified of signal 23 D0305 13:24:58.835806 58310 task_block.go:223] [ 1] Interrupt queued I0305 13:24:58.835973 58310 loader.go:566] Received external signal 23, mode: Process D0305 13:24:58.836113 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:24:58.836125 58310 task_signals.go:434] [ 1] Discarding duplicate signal 23 D0305 13:24:58.836365 58310 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0305 13:24:58.836545 58310 task_signals.go:220] [ 1] Signal 23: delivering to handler D0305 13:24:58.879311 58310 parameters.go:238] Clock(Monotonic): error: 54 ns, adjusted frequency from 2299773699 Hz to 2299774829 Hz D0305 13:24:58.879474 58310 parameters.go:238] Clock(Realtime): error: -117 ns, adjusted frequency from 2299774773 Hz to 2299774359 Hz D0305 13:24:58.879570 58310 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9904305154185172 monotonicBaseRef:72000243321 monotonicFrequency:2299774829 realtimeReady:1 realtimeBaseCycles:9904305154585966 realtimeBaseRef:1583414698879469332 realtimeFrequency:2299774359} D0305 13:24:59.879365 58310 parameters.go:238] Clock(Monotonic): error: 458 ns, adjusted frequency from 2299774829 Hz to 2299775636 Hz D0305 13:24:59.879624 58310 parameters.go:238] Clock(Realtime): error: 67 ns, adjusted frequency from 2299774359 Hz to 2299774765 Hz D0305 13:24:59.879751 58310 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9904307454051760 monotonicBaseRef:73000283220 monotonicFrequency:2299775636 realtimeReady:1 realtimeBaseCycles:9904307454705167 realtimeBaseRef:1583414699879619278 realtimeFrequency:2299774765} D0305 13:25:00.879357 58310 parameters.go:238] Clock(Monotonic): error: -444 ns, adjusted frequency from 2299775636 Hz to 2299773525 Hz D0305 13:25:00.879612 58310 parameters.go:238] Clock(Realtime): error: 854 ns, adjusted frequency from 2299774765 Hz to 2299776626 Hz D0305 13:25:00.879790 58310 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9904309753804212 monotonicBaseRef:74000273139 monotonicFrequency:2299773525 realtimeReady:1 realtimeBaseCycles:9904309754446252 realtimeBaseRef:1583414700879604633 realtimeFrequency:2299776626} I0305 13:25:01.835257 58310 loader.go:566] Received external signal 23, mode: Process D0305 13:25:01.835500 58310 task_signals.go:446] [ 1] Notified of signal 23 D0305 13:25:01.835617 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:25:01.835738 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:25:01.835927 58310 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0305 13:25:01.836027 58310 task_signals.go:220] [ 1] Signal 23: delivering to handler D0305 13:25:01.879375 58310 parameters.go:238] Clock(Monotonic): error: 416 ns, adjusted frequency from 2299773525 Hz to 2299775649 Hz I0305 13:25:01.879622 58310 loader.go:566] Received external signal 23, mode: Process D0305 13:25:01.879778 58310 task_signals.go:446] [ 1] Notified of signal 23 D0305 13:25:01.879727 58310 parameters.go:238] Clock(Realtime): error: -553 ns, adjusted frequency from 2299776626 Hz to 2299773334 Hz D0305 13:25:01.879874 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:25:01.879956 58310 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9904312053650155 monotonicBaseRef:75000304628 monotonicFrequency:2299775649 realtimeReady:1 realtimeBaseCycles:9904312054478796 realtimeBaseRef:1583414701879715912 realtimeFrequency:2299773334} D0305 13:25:01.879979 58310 task_block.go:223] [ 1] Interrupt queued I0305 13:25:01.880067 58310 loader.go:566] Received external signal 23, mode: Process D0305 13:25:01.880251 58310 task_signals.go:434] [ 1] Discarding duplicate signal 23 D0305 13:25:01.880386 58310 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0305 13:25:01.880551 58310 task_signals.go:220] [ 1] Signal 23: delivering to handler D0305 13:25:02.879378 58310 parameters.go:238] Clock(Monotonic): error: -545 ns, adjusted frequency from 2299775649 Hz to 2299773465 Hz I0305 13:25:02.879453 58310 loader.go:566] Received external signal 23, mode: Process D0305 13:25:02.879672 58310 parameters.go:238] Clock(Realtime): error: -17 ns, adjusted frequency from 2299773334 Hz to 2299774626 Hz D0305 13:25:02.879821 58310 task_signals.go:446] [ 1] Notified of signal 23 D0305 13:25:02.879838 58310 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9904314353374419 monotonicBaseRef:76000282284 monotonicFrequency:2299773465 realtimeReady:1 realtimeBaseCycles:9904314354126342 realtimeBaseRef:1583414702879661216 realtimeFrequency:2299774626} D0305 13:25:02.880001 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:25:02.880099 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:25:02.880344 58310 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0305 13:25:02.880466 58310 task_signals.go:220] [ 1] Signal 23: delivering to handler D0305 13:25:03.879328 58310 parameters.go:238] Clock(Monotonic): error: 131 ns, adjusted frequency from 2299773465 Hz to 2299774962 Hz D0305 13:25:03.879526 58310 parameters.go:238] Clock(Realtime): error: -313 ns, adjusted frequency from 2299774626 Hz to 2299773925 Hz D0305 13:25:03.879662 58310 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9904316653098267 monotonicBaseRef:77000260709 monotonicFrequency:2299774962 realtimeReady:1 realtimeBaseCycles:9904316653579304 realtimeBaseRef:1583414703879521348 realtimeFrequency:2299773925} I0305 13:25:04.835315 58310 loader.go:566] Received external signal 23, mode: Process D0305 13:25:04.835530 58310 task_signals.go:446] [ 1] Notified of signal 23 D0305 13:25:04.835637 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:25:04.835759 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:25:04.835994 58310 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0305 13:25:04.836084 58310 task_signals.go:220] [ 1] Signal 23: delivering to handler D0305 13:25:04.879340 58310 parameters.go:238] Clock(Monotonic): error: 11 ns, adjusted frequency from 2299774962 Hz to 2299774708 Hz D0305 13:25:04.879546 58310 parameters.go:238] Clock(Realtime): error: -32 ns, adjusted frequency from 2299773925 Hz to 2299774584 Hz D0305 13:25:04.879657 58310 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9904318952900136 monotonicBaseRef:78000272408 monotonicFrequency:2299774708 realtimeReady:1 realtimeBaseCycles:9904318953399084 realtimeBaseRef:1583414704879541286 realtimeFrequency:2299774584} I0305 13:25:05.835365 58310 loader.go:566] Received external signal 23, mode: Process D0305 13:25:05.835596 58310 task_signals.go:446] [ 1] Notified of signal 23 D0305 13:25:05.835676 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:25:05.835745 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:25:05.835958 58310 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 I0305 13:25:05.835906 58310 loader.go:566] Received external signal 23, mode: Process D0305 13:25:05.836065 58310 task_signals.go:220] [ 1] Signal 23: delivering to handler D0305 13:25:05.836118 58310 task_signals.go:446] [ 1] Notified of signal 23 D0305 13:25:05.836262 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:25:05.836502 58310 task_block.go:223] [ 2] Interrupt queued D0305 13:25:05.836604 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:25:05.836635 58310 task_block.go:223] [ 2] Interrupt queued D0305 13:25:05.836857 58310 task_signals.go:176] [ 2] Not restarting syscall 202 after errno 516: interrupted by signal 23 D0305 13:25:05.837018 58310 task_signals.go:220] [ 2] Signal 23: delivering to handler D0305 13:25:05.879337 58310 parameters.go:238] Clock(Monotonic): error: 55 ns, adjusted frequency from 2299774708 Hz to 2299774772 Hz D0305 13:25:05.879550 58310 parameters.go:238] Clock(Realtime): error: 37 ns, adjusted frequency from 2299774584 Hz to 2299774715 Hz D0305 13:25:05.879673 58310 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9904321252667751 monotonicBaseRef:79000269323 monotonicFrequency:2299774772 realtimeReady:1 realtimeBaseCycles:9904321253181459 realtimeBaseRef:1583414705879544673 realtimeFrequency:2299774715} I0305 13:25:06.835261 58310 loader.go:566] Received external signal 23, mode: Process D0305 13:25:06.835550 58310 task_signals.go:446] [ 1] Notified of signal 23 D0305 13:25:06.835731 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:25:06.835777 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:25:06.835995 58310 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0305 13:25:06.836096 58310 task_signals.go:220] [ 1] Signal 23: delivering to handler D0305 13:25:06.879354 58310 parameters.go:238] Clock(Monotonic): error: -148 ns, adjusted frequency from 2299774772 Hz to 2299774322 Hz I0305 13:25:06.879473 58310 loader.go:566] Received external signal 23, mode: Process D0305 13:25:06.879570 58310 parameters.go:238] Clock(Realtime): error: -84 ns, adjusted frequency from 2299774715 Hz to 2299774392 Hz D0305 13:25:06.879659 58310 task_signals.go:446] [ 1] Notified of signal 23 D0305 13:25:06.879711 58310 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9904323552370172 monotonicBaseRef:80000237862 monotonicFrequency:2299774322 realtimeReady:1 realtimeBaseCycles:9904323553003451 realtimeBaseRef:1583414706879565230 realtimeFrequency:2299774392} D0305 13:25:06.879816 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:25:06.879866 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:25:06.880087 58310 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0305 13:25:06.880216 58310 task_signals.go:220] [ 1] Signal 23: delivering to handler I0305 13:25:07.835320 58310 loader.go:566] Received external signal 23, mode: Process D0305 13:25:07.835530 58310 task_signals.go:446] [ 1] Notified of signal 23 D0305 13:25:07.835616 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:25:07.835744 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:25:07.835927 58310 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0305 13:25:07.836029 58310 task_signals.go:220] [ 1] Signal 23: delivering to handler D0305 13:25:07.879335 58310 parameters.go:238] Clock(Monotonic): error: 209 ns, adjusted frequency from 2299774322 Hz to 2299775076 Hz D0305 13:25:07.880107 58310 parameters.go:238] Clock(Realtime): error: 104 ns, adjusted frequency from 2299774392 Hz to 2299774858 Hz I0305 13:25:07.880108 58310 loader.go:566] Received external signal 23, mode: Process D0305 13:25:07.880263 58310 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9904325852168705 monotonicBaseRef:81000248389 monotonicFrequency:2299775076 realtimeReady:1 realtimeBaseCycles:9904325854005233 realtimeBaseRef:1583414707880098930 realtimeFrequency:2299774858} D0305 13:25:07.880910 58310 task_signals.go:446] [ 1] Notified of signal 23 D0305 13:25:07.881608 58310 task_block.go:223] [ 1] Interrupt queued I0305 13:25:07.881745 58310 loader.go:566] Received external signal 23, mode: Process D0305 13:25:07.881828 58310 task_signals.go:434] [ 1] Discarding duplicate signal 23 D0305 13:25:07.881763 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:25:07.882072 58310 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0305 13:25:07.882184 58310 task_signals.go:220] [ 1] Signal 23: delivering to handler I0305 13:25:08.835490 58310 loader.go:566] Received external signal 23, mode: Process D0305 13:25:08.835699 58310 task_signals.go:446] [ 1] Notified of signal 23 D0305 13:25:08.835787 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:25:08.835947 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:25:08.836265 58310 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0305 13:25:08.836493 58310 task_signals.go:220] [ 1] Signal 23: delivering to handler D0305 13:25:08.879310 58310 parameters.go:238] Clock(Monotonic): error: 36 ns, adjusted frequency from 2299775076 Hz to 2299774638 Hz D0305 13:25:08.879567 58310 parameters.go:238] Clock(Realtime): error: 278 ns, adjusted frequency from 2299774858 Hz to 2299775297 Hz D0305 13:25:08.879708 58310 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9904328151922217 monotonicBaseRef:82000239012 monotonicFrequency:2299774638 realtimeReady:1 realtimeBaseCycles:9904328152545127 realtimeBaseRef:1583414708879561936 realtimeFrequency:2299775297} I0305 13:25:09.222236 60027 main.go:305] *************************** I0305 13:25:09.222367 60027 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-1 /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 /syzkaller236345622] I0305 13:25:09.222752 60027 main.go:307] Version release-20200219.0-72-gce4d1e45bb88 I0305 13:25:09.222824 60027 main.go:308] PID: 60027 I0305 13:25:09.222948 60027 main.go:309] UID: 0, GID: 0 I0305 13:25:09.223023 60027 main.go:310] Configuration: I0305 13:25:09.223076 60027 main.go:311] RootDir: /syzkaller/managers/ptrace-proxy-sandbox-race/workdir/gvisor_root I0305 13:25:09.223150 60027 main.go:312] Platform: ptrace I0305 13:25:09.223214 60027 main.go:313] FileAccess: shared, overlay: false I0305 13:25:09.223303 60027 main.go:314] Network: sandbox, logging: false I0305 13:25:09.223401 60027 main.go:315] Strace: false, max size: 1024, syscalls: [] I0305 13:25:09.223472 60027 main.go:316] *************************** W0305 13:25:09.223537 60027 main.go:321] Block the TERM signal. This is only safe in tests! D0305 13:25:09.223855 60027 container.go:159] Load container "/syzkaller/managers/ptrace-proxy-sandbox-race/workdir/gvisor_root" "ci-gvisor-ptrace-proxy-sandbox-race-1" D0305 13:25:09.229305 60027 container.go:592] Signal container "ci-gvisor-ptrace-proxy-sandbox-race-1": signal 0 D0305 13:25:09.229505 60027 sandbox.go:806] Signal sandbox "ci-gvisor-ptrace-proxy-sandbox-race-1" D0305 13:25:09.229599 60027 sandbox.go:318] Connecting to sandbox "ci-gvisor-ptrace-proxy-sandbox-race-1" D0305 13:25:09.230153 60027 urpc.go:534] urpc: successfully marshalled 117 bytes. I0305 13:25:09.230181 58310 loader.go:566] Received external signal 23, mode: Process D0305 13:25:09.230405 58310 task_signals.go:446] [ 1] Notified of signal 23 D0305 13:25:09.230522 58310 urpc.go:577] urpc: unmarshal success. D0305 13:25:09.230653 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:25:09.230726 58310 controller.go:501] containerManager.Signal &{CID:ci-gvisor-ptrace-proxy-sandbox-race-1 Signo:0 PID:0 Mode:Process} D0305 13:25:09.230786 58310 task_block.go:223] [ 1] Interrupt queued I0305 13:25:09.230805 58310 loader.go:566] Received external signal 23, mode: Process D0305 13:25:09.230997 58310 urpc.go:534] urpc: successfully marshalled 37 bytes. D0305 13:25:09.231057 58310 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0305 13:25:09.231093 58310 task_signals.go:446] [ 1] Notified of signal 23 D0305 13:25:09.231188 58310 task_signals.go:220] [ 1] Signal 23: delivering to handler D0305 13:25:09.231220 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:25:09.231239 60027 urpc.go:577] urpc: unmarshal success. D0305 13:25:09.231458 60027 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 /syzkaller236345622 D0305 13:25:09.231612 60027 exec.go:121] Exec capablities: &{PermittedCaps:274877898751 InheritableCaps:274877898751 EffectiveCaps:274877898751 BoundingCaps:274877898751 AmbientCaps:0} D0305 13:25:09.231734 60027 container.go:533] Execute in container "ci-gvisor-ptrace-proxy-sandbox-race-1", 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 /syzkaller236345622 D0305 13:25:09.231840 58310 task_block.go:223] [ 2] Interrupt queued D0305 13:25:09.231867 60027 sandbox.go:283] Executing new process in container "ci-gvisor-ptrace-proxy-sandbox-race-1" in sandbox "ci-gvisor-ptrace-proxy-sandbox-race-1" D0305 13:25:09.231906 58310 task_block.go:223] [ 2] Interrupt queued D0305 13:25:09.232022 60027 sandbox.go:318] Connecting to sandbox "ci-gvisor-ptrace-proxy-sandbox-race-1" D0305 13:25:09.232006 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:25:09.232139 58310 task_signals.go:176] [ 2] Not restarting syscall 202 after errno 516: interrupted by signal 23 D0305 13:25:09.232284 58310 task_signals.go:220] [ 2] Signal 23: delivering to handler D0305 13:25:09.232698 60027 urpc.go:534] urpc: successfully marshalled 634 bytes. D0305 13:25:09.233049 58310 urpc.go:577] urpc: unmarshal success. D0305 13:25:09.234233 58310 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 /syzkaller236345622 D0305 13:25:09.234509 58310 transport_flipcall.go:127] send [channel @0xc000138180] Twalkgetattr{FID: 1, NewFID: 5, Names: [etc]} D0305 13:25:09.235139 58310 transport_flipcall.go:234] recv [channel @0xc000138180] Rlerror{Error: 2} D0305 13:25:09.235328 58310 transport_flipcall.go:127] send [channel @0xc000138180] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.etc, Size: 1} D0305 13:25:09.235688 58310 transport_flipcall.go:234] recv [channel @0xc000138180] Rlerror{Error: 95} I0305 13:25:09.236280 58310 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 /syzkaller236345622] D0305 13:25:09.237189 58310 transport_flipcall.go:127] send [channel @0xc000138180] Twalkgetattr{FID: 1, NewFID: 5, Names: [syz-execprog]} D0305 13:25:09.238340 58310 transport_flipcall.go:234] recv [channel @0xc000138180] 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: 1583414627, NanoSec: 898174469}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}, QIDs: [QID{Type: 0, Version: 0, Path: 16384245}]} D0305 13:25:09.238536 58310 transport_flipcall.go:127] send [channel @0xc000138180] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syz-execprog, Size: 1} D0305 13:25:09.238993 58310 transport_flipcall.go:234] recv [channel @0xc000138180] Rlerror{Error: 95} D0305 13:25:09.239367 58310 transport_flipcall.go:127] send [channel @0xc000138180] Tgetattr{FID: 5, AttrMask: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime INo Size Blocks BTime Gen DataVersion}} D0305 13:25:09.240338 58310 transport_flipcall.go:234] recv [channel @0xc000138180] Rgetattr{Valid: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime Size Blocks}, QID: QID{Type: 0, Version: 0, Path: 16384245}, 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: 1583414627, NanoSec: 898174469}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}} D0305 13:25:09.240569 58310 transport_flipcall.go:127] send [channel @0xc000138180] Twalk{FID: 5, NewFID: 6, Names: []} D0305 13:25:09.241248 58310 transport_flipcall.go:234] recv [channel @0xc000138180] Rwalk{QIDs: []} D0305 13:25:09.241458 58310 transport_flipcall.go:127] send [channel @0xc000138180] Tlopen{FID: 6, Flags: ReadOnly} D0305 13:25:09.242373 58310 transport_flipcall.go:234] recv [channel @0xc000138180] Rlopen{QID: QID{Type: 0, Version: 0, Path: 16384245}, IoUnit: 0, File: &{{36}}} D0305 13:25:09.245891 58310 syscalls.go:266] Allocating stack with size of 8388608 bytes I0305 13:25:09.246186 58310 loader.go:566] Received external signal 23, mode: Process D0305 13:25:09.247872 58310 loader.go:832] updated processes: map[{ci-gvisor-ptrace-proxy-sandbox-race-1 0}:0xc0004807a0 {ci-gvisor-ptrace-proxy-sandbox-race-1 21}:0xc0004f5280] D0305 13:25:09.248283 58310 urpc.go:534] urpc: successfully marshalled 37 bytes. D0305 13:25:09.248382 58310 task_signals.go:446] [ 1] Notified of signal 23 D0305 13:25:09.248440 60027 urpc.go:577] urpc: unmarshal success. D0305 13:25:09.248557 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:25:09.248612 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:25:09.248632 60027 container.go:580] Wait on PID 21 in container "ci-gvisor-ptrace-proxy-sandbox-race-1" D0305 13:25:09.248811 58310 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0305 13:25:09.248754 60027 sandbox.go:761] Waiting for PID 21 in sandbox "ci-gvisor-ptrace-proxy-sandbox-race-1" D0305 13:25:09.248920 58310 task_signals.go:220] [ 1] Signal 23: delivering to handler D0305 13:25:09.248915 60027 sandbox.go:318] Connecting to sandbox "ci-gvisor-ptrace-proxy-sandbox-race-1" D0305 13:25:09.249299 60027 urpc.go:534] urpc: successfully marshalled 100 bytes. D0305 13:25:09.249796 58310 urpc.go:577] urpc: unmarshal success. D0305 13:25:09.250133 58310 controller.go:443] containerManager.Wait D0305 13:25:09.252534 58310 task_block.go:223] [ 21] Interrupt queued I0305 13:25:09.269337 58310 loader.go:566] Received external signal 23, mode: Process D0305 13:25:09.269624 58310 task_signals.go:446] [ 1] Notified of signal 23 D0305 13:25:09.269754 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:25:09.269812 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:25:09.270051 58310 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0305 13:25:09.270164 58310 task_signals.go:220] [ 1] Signal 23: delivering to handler I0305 13:25:09.329442 58310 loader.go:566] Received external signal 23, mode: Process D0305 13:25:09.329719 58310 task_signals.go:446] [ 1] Notified of signal 23 D0305 13:25:09.329899 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:25:09.330033 58310 task_block.go:223] [ 1] Interrupt queued I0305 13:25:09.330052 58310 loader.go:566] Received external signal 23, mode: Process D0305 13:25:09.330200 58310 task_signals.go:446] [ 1] Notified of signal 23 D0305 13:25:09.330190 58310 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0305 13:25:09.330296 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:25:09.330384 58310 task_signals.go:220] [ 1] Signal 23: delivering to handler D0305 13:25:09.330779 58310 task_block.go:223] [ 2] Interrupt queued D0305 13:25:09.330870 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:25:09.330876 58310 task_block.go:223] [ 2] Interrupt queued D0305 13:25:09.331164 58310 task_signals.go:176] [ 2] Not restarting syscall 202 after errno 516: interrupted by signal 23 D0305 13:25:09.331344 58310 task_signals.go:220] [ 2] Signal 23: delivering to handler I0305 13:25:09.418579 58310 loader.go:566] Received external signal 23, mode: Process D0305 13:25:09.418775 58310 task_signals.go:446] [ 1] Notified of signal 23 D0305 13:25:09.418860 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:25:09.418911 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:25:09.419214 58310 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0305 13:25:09.419389 58310 task_signals.go:220] [ 1] Signal 23: delivering to handler I0305 13:25:09.429907 58310 loader.go:566] Received external signal 23, mode: Process D0305 13:25:09.430121 58310 task_signals.go:446] [ 1] Notified of signal 23 D0305 13:25:09.430233 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:25:09.430287 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:25:09.430447 58310 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0305 13:25:09.430562 58310 task_signals.go:220] [ 1] Signal 23: delivering to handler D0305 13:25:09.437790 58310 task_block.go:223] [ 22] Interrupt queued D0305 13:25:09.438382 58310 task_block.go:223] [ 23] Interrupt queued D0305 13:25:09.441742 58310 task_block.go:223] [ 24] Interrupt queued D0305 13:25:09.446146 58310 task_block.go:223] [ 25] Interrupt queued D0305 13:25:09.456520 58310 task_block.go:223] [ 26] Interrupt queued D0305 13:25:09.462338 58310 task_block.go:223] [ 27] Interrupt queued D0305 13:25:09.466417 58310 task_block.go:223] [ 28] Interrupt queued D0305 13:25:09.504570 58310 urpc.go:577] urpc: unmarshal success. D0305 13:25:09.504768 58310 controller.go:501] containerManager.Signal &{CID:ci-gvisor-ptrace-proxy-sandbox-race-1 Signo:0 PID:0 Mode:Process} D0305 13:25:09.504829 58310 transport_flipcall.go:127] send [channel @0xc000138180] Twalkgetattr{FID: 1, NewFID: 7, Names: [etc]} D0305 13:25:09.504964 58310 urpc.go:534] urpc: successfully marshalled 37 bytes. D0305 13:25:09.505456 58310 transport_flipcall.go:234] recv [channel @0xc000138180] Rlerror{Error: 2} D0305 13:25:09.505637 58310 transport_flipcall.go:127] send [channel @0xc000138180] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.etc, Size: 1} D0305 13:25:09.506060 58310 transport_flipcall.go:234] recv [channel @0xc000138180] Rlerror{Error: 95} I0305 13:25:09.515785 58310 loader.go:566] Received external signal 23, mode: Process D0305 13:25:09.516039 58310 task_signals.go:446] [ 1] Notified of signal 23 D0305 13:25:09.516128 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:25:09.516238 58310 task_block.go:223] [ 1] Interrupt queued I0305 13:25:09.516271 58310 loader.go:566] Received external signal 23, mode: Process D0305 13:25:09.516412 58310 task_signals.go:446] [ 1] Notified of signal 23 D0305 13:25:09.516405 58310 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0305 13:25:09.516501 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:25:09.516549 58310 task_signals.go:220] [ 1] Signal 23: delivering to handler D0305 13:25:09.516890 58310 task_block.go:223] [ 2] Interrupt queued D0305 13:25:09.516964 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:25:09.516942 58310 task_block.go:223] [ 2] Interrupt queued D0305 13:25:09.517210 58310 task_signals.go:176] [ 2] Not restarting syscall 202 after errno 516: interrupted by signal 23 D0305 13:25:09.517339 58310 task_signals.go:220] [ 2] Signal 23: delivering to handler D0305 13:25:09.531004 58310 task_block.go:223] [ 29] Interrupt queued I0305 13:25:09.556651 58310 loader.go:566] Received external signal 23, mode: Process D0305 13:25:09.556974 58310 task_signals.go:446] [ 1] Notified of signal 23 D0305 13:25:09.557154 58310 task_block.go:223] [ 1] Interrupt queued I0305 13:25:09.557350 58310 loader.go:566] Received external signal 23, mode: Process D0305 13:25:09.557349 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:25:09.557483 58310 task_signals.go:434] [ 1] Discarding duplicate signal 23 D0305 13:25:09.557618 58310 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0305 13:25:09.557687 58310 task_block.go:223] [ 30] Interrupt queued D0305 13:25:09.557753 58310 task_signals.go:220] [ 1] Signal 23: delivering to handler I0305 13:25:09.559752 58310 loader.go:566] Received external signal 23, mode: Process D0305 13:25:09.560036 58310 task_signals.go:446] [ 1] Notified of signal 23 D0305 13:25:09.561157 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:25:09.561298 58310 task_block.go:223] [ 1] Interrupt queued I0305 13:25:09.561377 58310 loader.go:566] Received external signal 23, mode: Process D0305 13:25:09.561447 58310 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0305 13:25:09.561560 58310 task_signals.go:220] [ 1] Signal 23: delivering to handler D0305 13:25:09.561475 58310 task_signals.go:446] [ 1] Notified of signal 23 D0305 13:25:09.561676 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:25:09.561890 58310 task_block.go:223] [ 2] Interrupt queued D0305 13:25:09.561966 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:25:09.561970 58310 task_block.go:223] [ 2] Interrupt queued D0305 13:25:09.562273 58310 task_signals.go:176] [ 2] Not restarting syscall 202 after errno 516: interrupted by signal 23 D0305 13:25:09.562379 58310 task_signals.go:220] [ 2] Signal 23: delivering to handler D0305 13:25:09.569313 58310 task_block.go:223] [ 31] Interrupt queued D0305 13:25:09.596244 58310 task_block.go:223] [ 32] Interrupt queued D0305 13:25:09.599142 58310 task_block.go:223] [ 33] Interrupt queued D0305 13:25:09.603304 58310 task_block.go:223] [ 34] Interrupt queued D0305 13:25:09.630933 58310 task_block.go:223] [ 35] Interrupt queued D0305 13:25:09.635154 58310 transport_flipcall.go:127] send [channel @0xc000138180] Twalkgetattr{FID: 1, NewFID: 7, Names: [syzkaller236345622]} D0305 13:25:09.635637 58310 task_block.go:223] [ 36] Interrupt queued D0305 13:25:09.636547 58310 transport_flipcall.go:234] recv [channel @0xc000138180] 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: 409, BlockSize: 4096, Blocks: 8, ATime: {Sec: 1583414709, NanoSec: 194140290}, MTime: {Sec: 1583414709, NanoSec: 194140290}, CTime: {Sec: 1583414709, NanoSec: 194140290}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}, QIDs: [QID{Type: 0, Version: 0, Path: 16384235}]} D0305 13:25:09.636811 58310 transport_flipcall.go:127] send [channel @0xc000138180] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzkaller236345622, Size: 1} D0305 13:25:09.637452 58310 transport_flipcall.go:234] recv [channel @0xc000138180] Rlerror{Error: 95} D0305 13:25:09.637803 58310 transport_flipcall.go:127] send [channel @0xc000138180] Tgetattr{FID: 7, AttrMask: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime INo Size Blocks BTime Gen DataVersion}} D0305 13:25:09.638735 58310 transport_flipcall.go:234] recv [channel @0xc000138180] Rgetattr{Valid: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime Size Blocks}, QID: QID{Type: 0, Version: 0, Path: 16384235}, Attr: Attr{Mode: 0o100600, UID: 0, GID: 0, NLink: 1, RDev: 0, Size: 409, BlockSize: 4096, Blocks: 8, ATime: {Sec: 1583414709, NanoSec: 194140290}, MTime: {Sec: 1583414709, NanoSec: 194140290}, CTime: {Sec: 1583414709, NanoSec: 194140290}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}} D0305 13:25:09.638907 58310 transport_flipcall.go:127] send [channel @0xc000138180] Twalk{FID: 7, NewFID: 8, Names: []} D0305 13:25:09.639514 58310 transport_flipcall.go:234] recv [channel @0xc000138180] Rwalk{QIDs: []} D0305 13:25:09.639748 58310 transport_flipcall.go:127] send [channel @0xc000138180] Tlopen{FID: 8, Flags: ReadOnly} D0305 13:25:09.640441 58310 transport_flipcall.go:234] recv [channel @0xc000138180] Rlopen{QID: QID{Type: 0, Version: 0, Path: 16384235}, IoUnit: 0, File: &{{30}}} D0305 13:25:09.641401 58310 transport_flipcall.go:127] send [channel @0xc000138180] Tgetattr{FID: 8, AttrMask: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime INo Size Blocks BTime Gen DataVersion}} D0305 13:25:09.642363 58310 transport_flipcall.go:234] recv [channel @0xc000138180] Rgetattr{Valid: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime Size Blocks}, QID: QID{Type: 0, Version: 0, Path: 16384235}, Attr: Attr{Mode: 0o100600, UID: 0, GID: 0, NLink: 1, RDev: 0, Size: 409, BlockSize: 4096, Blocks: 8, ATime: {Sec: 1583414709, NanoSec: 194140290}, MTime: {Sec: 1583414709, NanoSec: 194140290}, CTime: {Sec: 1583414709, NanoSec: 194140290}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}} 2020/03/05 13:25:09 parsed 1 programs I0305 13:25:09.647492 58310 loader.go:566] Received external signal 23, mode: Process D0305 13:25:09.647644 58310 task_signals.go:446] [ 1] Notified of signal 23 D0305 13:25:09.647764 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:25:09.647804 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:25:09.648014 58310 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0305 13:25:09.648102 58310 task_signals.go:220] [ 1] Signal 23: delivering to handler D0305 13:25:09.652952 58310 task_stop.go:118] [ 25] Entering internal stop (*kernel.vforkStop)(nil) D0305 13:25:09.653882 58310 task_block.go:223] [ 37] Interrupt queued D0305 13:25:09.664251 58310 transport_flipcall.go:127] send [channel @0xc000138180] Twalkgetattr{FID: 1, NewFID: 9, Names: [syz-executor]} D0305 13:25:09.684790 58310 transport_flipcall.go:234] recv [channel @0xc000138180] 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: 1583414627, NanoSec: 902174861}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}, QIDs: [QID{Type: 0, Version: 0, Path: 16384250}]} D0305 13:25:09.685105 58310 transport_flipcall.go:127] send [channel @0xc000138180] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syz-executor, Size: 1} D0305 13:25:09.685581 58310 transport_flipcall.go:234] recv [channel @0xc000138180] Rlerror{Error: 95} D0305 13:25:09.685859 58310 transport_flipcall.go:127] send [channel @0xc000138180] Tgetattr{FID: 9, AttrMask: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime INo Size Blocks BTime Gen DataVersion}} D0305 13:25:09.686766 58310 transport_flipcall.go:234] recv [channel @0xc000138180] Rgetattr{Valid: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime Size Blocks}, QID: QID{Type: 0, Version: 0, Path: 16384250}, 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: 1583414627, NanoSec: 902174861}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}} D0305 13:25:09.686950 58310 transport_flipcall.go:127] send [channel @0xc000138180] Twalk{FID: 9, NewFID: 10, Names: []} D0305 13:25:09.687528 58310 transport_flipcall.go:234] recv [channel @0xc000138180] Rwalk{QIDs: []} D0305 13:25:09.687647 58310 transport_flipcall.go:127] send [channel @0xc000138180] Tlopen{FID: 10, Flags: ReadOnly} D0305 13:25:09.688292 58310 transport_flipcall.go:234] recv [channel @0xc000138180] Rlopen{QID: QID{Type: 0, Version: 0, Path: 16384250}, IoUnit: 0, File: &{{31}}} D0305 13:25:09.689230 58310 syscalls.go:266] [ 37] Allocating stack with size of 8388608 bytes D0305 13:25:09.690045 58310 task_stop.go:137] [ 25] Leaving internal stop (*kernel.vforkStop)(nil) D0305 13:25:09.707174 58310 transport_flipcall.go:127] send [channel @0xc000138180] Twalkgetattr{FID: 1, NewFID: 11, Names: [etc]} D0305 13:25:09.707757 58310 transport_flipcall.go:234] recv [channel @0xc000138180] Rlerror{Error: 2} D0305 13:25:09.707916 58310 transport_flipcall.go:127] send [channel @0xc000138180] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.etc, Size: 1} D0305 13:25:09.708432 58310 transport_flipcall.go:234] recv [channel @0xc000138180] Rlerror{Error: 95} D0305 13:25:09.709975 58310 task_exit.go:221] [ 37] Transitioning from exit state TaskExitNone to TaskExitInitiated I0305 13:25:09.710023 58310 loader.go:566] Received external signal 23, mode: Process D0305 13:25:09.710204 58310 task_signals.go:446] [ 1] Notified of signal 23 D0305 13:25:09.710309 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:25:09.710414 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:25:09.710540 58310 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0305 13:25:09.710639 58310 task_signals.go:220] [ 1] Signal 23: delivering to handler D0305 13:25:09.711392 58310 task_exit.go:221] [ 37] Transitioning from exit state TaskExitInitiated to TaskExitZombie D0305 13:25:09.711560 58310 task_signals.go:446] [ 21] Notified of signal 17 D0305 13:25:09.711695 58310 task_block.go:223] [ 21] Interrupt queued D0305 13:25:09.711919 58310 task_block.go:223] [ 21] Interrupt queued D0305 13:25:09.712089 58310 task_signals.go:179] [ 21] Restarting syscall 202 after errno 512: interrupted by signal 17 D0305 13:25:09.712214 58310 task_signals.go:220] [ 21] Signal 17: delivering to handler D0305 13:25:09.712216 58310 task_exit.go:221] [ 37] Transitioning from exit state TaskExitZombie to TaskExitDead D0305 13:25:09.713862 58310 task_block.go:223] [ 38] Interrupt queued D0305 13:25:09.717800 58310 transport_flipcall.go:127] send [channel @0xc000138180] Twalkgetattr{FID: 1, NewFID: 11, Names: [syz-executor]} D0305 13:25:09.718870 58310 transport_flipcall.go:234] recv [channel @0xc000138180] 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: 1583414627, NanoSec: 902174861}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}, QIDs: [QID{Type: 0, Version: 0, Path: 16384250}]} D0305 13:25:09.719098 58310 transport_flipcall.go:127] send [channel @0xc000138180] Tclunk{FID: 11} D0305 13:25:09.719611 58310 transport_flipcall.go:234] recv [channel @0xc000138180] Rclunk{} D0305 13:25:09.719811 58310 transport_flipcall.go:127] send [channel @0xc000138180] Tgetattr{FID: 9, AttrMask: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime INo Size Blocks BTime Gen DataVersion}} D0305 13:25:09.720895 58310 transport_flipcall.go:234] recv [channel @0xc000138180] Rgetattr{Valid: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime Size Blocks}, QID: QID{Type: 0, Version: 0, Path: 16384250}, 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: 1583414627, NanoSec: 902174861}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}} 2020/03/05 13:25:09 executed programs: 0 D0305 13:25:09.726848 58310 task_stop.go:118] [ 32] Entering internal stop (*kernel.vforkStop)(nil) D0305 13:25:09.727642 58310 task_block.go:223] [ 39] Interrupt queued I0305 13:25:09.730245 58310 loader.go:566] Received external signal 23, mode: Process D0305 13:25:09.730456 58310 task_signals.go:446] [ 1] Notified of signal 23 D0305 13:25:09.730609 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:25:09.730768 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:25:09.731039 58310 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0305 13:25:09.731168 58310 task_signals.go:220] [ 1] Signal 23: delivering to handler D0305 13:25:09.732618 58310 transport_flipcall.go:127] send [channel @0xc000138180] Twalkgetattr{FID: 1, NewFID: 11, Names: [syz-executor]} D0305 13:25:09.733687 58310 transport_flipcall.go:234] recv [channel @0xc000138180] 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: 1583414627, NanoSec: 902174861}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}, QIDs: [QID{Type: 0, Version: 0, Path: 16384250}]} D0305 13:25:09.733870 58310 transport_flipcall.go:127] send [channel @0xc000138180] Tclunk{FID: 11} D0305 13:25:09.734328 58310 transport_flipcall.go:234] recv [channel @0xc000138180] Rclunk{} D0305 13:25:09.734601 58310 transport_flipcall.go:127] send [channel @0xc000138180] Tgetattr{FID: 9, AttrMask: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime INo Size Blocks BTime Gen DataVersion}} D0305 13:25:09.735508 58310 transport_flipcall.go:234] recv [channel @0xc000138180] Rgetattr{Valid: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime Size Blocks}, QID: QID{Type: 0, Version: 0, Path: 16384250}, 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: 1583414627, NanoSec: 902174861}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}} D0305 13:25:09.736614 58310 syscalls.go:266] [ 39] Allocating stack with size of 8388608 bytes I0305 13:25:09.738061 58310 loader.go:566] Received external signal 23, mode: Process D0305 13:25:09.738790 58310 task_stop.go:137] [ 32] Leaving internal stop (*kernel.vforkStop)(nil) D0305 13:25:09.739330 58310 task_signals.go:446] [ 1] Notified of signal 23 D0305 13:25:09.740638 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:25:09.740736 58310 task_block.go:223] [ 1] Interrupt queued I0305 13:25:09.740804 58310 loader.go:566] Received external signal 23, mode: Process D0305 13:25:09.740921 58310 task_signals.go:434] [ 1] Discarding duplicate signal 23 D0305 13:25:09.741095 58310 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0305 13:25:09.741287 58310 task_signals.go:220] [ 1] Signal 23: delivering to handler D0305 13:25:09.748566 58310 transport_flipcall.go:127] send [channel @0xc000138180] Twalkgetattr{FID: 1, NewFID: 11, Names: [etc]} D0305 13:25:09.749213 58310 transport_flipcall.go:234] recv [channel @0xc000138180] Rlerror{Error: 2} D0305 13:25:09.749360 58310 transport_flipcall.go:127] send [channel @0xc000138180] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.etc, Size: 1} D0305 13:25:09.749939 58310 transport_flipcall.go:234] recv [channel @0xc000138180] Rlerror{Error: 95} D0305 13:25:09.760824 58310 task_block.go:223] [ 40] Interrupt queued I0305 13:25:09.760915 58310 loader.go:566] Received external signal 23, mode: Process D0305 13:25:09.761125 58310 task_signals.go:446] [ 1] Notified of signal 23 D0305 13:25:09.761242 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:25:09.761345 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:25:09.761588 58310 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0305 13:25:09.761733 58310 task_signals.go:220] [ 1] Signal 23: delivering to handler D0305 13:25:09.764613 58310 transport_flipcall.go:127] send [channel @0xc000138180] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0305 13:25:09.765202 58310 transport_flipcall.go:234] recv [channel @0xc000138180] Rlerror{Error: 2} D0305 13:25:09.765368 58310 transport_flipcall.go:127] send [channel @0xc000138180] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0305 13:25:09.765849 58310 transport_flipcall.go:234] recv [channel @0xc000138180] Rlerror{Error: 95} D0305 13:25:09.766220 58310 transport_flipcall.go:127] send [channel @0xc000138180] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0305 13:25:09.766737 58310 transport_flipcall.go:234] recv [channel @0xc000138180] Rlerror{Error: 2} D0305 13:25:09.766961 58310 transport_flipcall.go:127] send [channel @0xc000138180] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0305 13:25:09.767324 58310 transport_flipcall.go:234] recv [channel @0xc000138180] Rlerror{Error: 95} D0305 13:25:09.767895 58310 transport_flipcall.go:127] send [channel @0xc000138180] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0305 13:25:09.768433 58310 transport_flipcall.go:234] recv [channel @0xc000138180] Rlerror{Error: 2} D0305 13:25:09.768541 58310 transport_flipcall.go:127] send [channel @0xc000138180] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0305 13:25:09.768922 58310 transport_flipcall.go:234] recv [channel @0xc000138180] Rlerror{Error: 95} D0305 13:25:09.769297 58310 transport_flipcall.go:127] send [channel @0xc000138180] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0305 13:25:09.769819 58310 transport_flipcall.go:234] recv [channel @0xc000138180] Rlerror{Error: 2} D0305 13:25:09.770069 58310 transport_flipcall.go:127] send [channel @0xc000138180] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0305 13:25:09.770537 58310 transport_flipcall.go:234] recv [channel @0xc000138180] Rlerror{Error: 95} D0305 13:25:09.770875 58310 transport_flipcall.go:127] send [channel @0xc000138180] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0305 13:25:09.771295 58310 transport_flipcall.go:234] recv [channel @0xc000138180] Rlerror{Error: 2} D0305 13:25:09.771466 58310 transport_flipcall.go:127] send [channel @0xc000138180] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0305 13:25:09.771846 58310 transport_flipcall.go:234] recv [channel @0xc000138180] Rlerror{Error: 95} D0305 13:25:09.772275 58310 transport_flipcall.go:127] send [channel @0xc000138180] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0305 13:25:09.772743 58310 transport_flipcall.go:234] recv [channel @0xc000138180] Rlerror{Error: 2} D0305 13:25:09.773052 58310 transport_flipcall.go:127] send [channel @0xc000138180] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0305 13:25:09.773532 58310 transport_flipcall.go:234] recv [channel @0xc000138180] Rlerror{Error: 95} D0305 13:25:09.773864 58310 transport_flipcall.go:127] send [channel @0xc000138180] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0305 13:25:09.774365 58310 transport_flipcall.go:234] recv [channel @0xc000138180] Rlerror{Error: 2} D0305 13:25:09.774496 58310 transport_flipcall.go:127] send [channel @0xc000138180] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0305 13:25:09.774871 58310 transport_flipcall.go:234] recv [channel @0xc000138180] Rlerror{Error: 95} D0305 13:25:09.775169 58310 transport_flipcall.go:127] send [channel @0xc000138180] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0305 13:25:09.775622 58310 transport_flipcall.go:234] recv [channel @0xc000138180] Rlerror{Error: 2} D0305 13:25:09.775764 58310 transport_flipcall.go:127] send [channel @0xc000138180] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0305 13:25:09.776247 58310 transport_flipcall.go:234] recv [channel @0xc000138180] Rlerror{Error: 95} D0305 13:25:09.776717 58310 transport_flipcall.go:127] send [channel @0xc000138180] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0305 13:25:09.777401 58310 transport_flipcall.go:234] recv [channel @0xc000138180] Rlerror{Error: 2} D0305 13:25:09.777536 58310 transport_flipcall.go:127] send [channel @0xc000138180] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0305 13:25:09.778050 58310 transport_flipcall.go:234] recv [channel @0xc000138180] Rlerror{Error: 95} I0305 13:25:09.781172 58310 loader.go:566] Received external signal 23, mode: Process D0305 13:25:09.781400 58310 task_signals.go:446] [ 1] Notified of signal 23 D0305 13:25:09.781553 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:25:09.781581 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:25:09.781816 58310 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0305 13:25:09.782028 58310 task_signals.go:220] [ 1] Signal 23: delivering to handler I0305 13:25:09.801313 58310 loader.go:566] Received external signal 23, mode: Process D0305 13:25:09.801534 58310 task_signals.go:446] [ 1] Notified of signal 23 D0305 13:25:09.801723 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:25:09.801778 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:25:09.802037 58310 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0305 13:25:09.802198 58310 task_signals.go:220] [ 1] Signal 23: delivering to handler D0305 13:25:09.805996 58310 netfilter.go:54] netfilter: convert to binary: current offset: 0 D0305 13:25:09.806141 58310 netfilter.go:54] netfilter: convert to binary: found hook 0 at offset 0 D0305 13:25:09.806206 58310 netfilter.go:54] netfilter: convert to binary: found underflow 0 at offset 0 D0305 13:25:09.806261 58310 netfilter.go:54] netfilter: convert to binary: marshalling standard target D0305 13:25:09.806347 58310 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]} D0305 13:25:09.806839 58310 netfilter.go:54] netfilter: convert to binary: current offset: 152 D0305 13:25:09.806919 58310 netfilter.go:54] netfilter: convert to binary: found hook 1 at offset 152 D0305 13:25:09.806993 58310 netfilter.go:54] netfilter: convert to binary: found underflow 1 at offset 152 D0305 13:25:09.807083 58310 netfilter.go:54] netfilter: convert to binary: marshalling standard target D0305 13:25:09.807168 58310 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]} D0305 13:25:09.807613 58310 netfilter.go:54] netfilter: convert to binary: current offset: 304 D0305 13:25:09.807665 58310 netfilter.go:54] netfilter: convert to binary: found hook 3 at offset 304 D0305 13:25:09.807748 58310 netfilter.go:54] netfilter: convert to binary: found underflow 3 at offset 304 D0305 13:25:09.807851 58310 netfilter.go:54] netfilter: convert to binary: marshalling standard target D0305 13:25:09.807962 58310 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]} D0305 13:25:09.808389 58310 netfilter.go:54] netfilter: convert to binary: current offset: 456 D0305 13:25:09.808472 58310 netfilter.go:54] netfilter: convert to binary: found hook 4 at offset 456 D0305 13:25:09.808554 58310 netfilter.go:54] netfilter: convert to binary: found underflow 4 at offset 456 D0305 13:25:09.808612 58310 netfilter.go:54] netfilter: convert to binary: marshalling standard target D0305 13:25:09.808703 58310 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]} D0305 13:25:09.809143 58310 netfilter.go:54] netfilter: convert to binary: current offset: 608 D0305 13:25:09.809249 58310 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]} D0305 13:25:09.809722 58310 netfilter.go:54] netfilter: convert to binary: finished with an marshalled size of 0 D0305 13:25:09.809815 58310 netfilter.go:54] netfilter: convert to binary: current offset: 0 D0305 13:25:09.809870 58310 netfilter.go:54] netfilter: convert to binary: found hook 0 at offset 0 D0305 13:25:09.809935 58310 netfilter.go:54] netfilter: convert to binary: found underflow 0 at offset 0 D0305 13:25:09.810006 58310 netfilter.go:54] netfilter: convert to binary: marshalling standard target D0305 13:25:09.810096 58310 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]} D0305 13:25:09.810530 58310 netfilter.go:54] netfilter: convert to binary: current offset: 152 D0305 13:25:09.810584 58310 netfilter.go:54] netfilter: convert to binary: found hook 3 at offset 152 D0305 13:25:09.810657 58310 netfilter.go:54] netfilter: convert to binary: found underflow 3 at offset 152 D0305 13:25:09.810730 58310 netfilter.go:54] netfilter: convert to binary: marshalling standard target D0305 13:25:09.810790 58310 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]} D0305 13:25:09.811202 58310 netfilter.go:54] netfilter: convert to binary: current offset: 304 D0305 13:25:09.811277 58310 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]} D0305 13:25:09.811756 58310 netfilter.go:54] netfilter: convert to binary: finished with an marshalled size of 0 D0305 13:25:09.811823 58310 netfilter.go:54] netfilter: convert to binary: current offset: 0 D0305 13:25:09.811934 58310 netfilter.go:54] netfilter: convert to binary: found hook 1 at offset 0 D0305 13:25:09.812016 58310 netfilter.go:54] netfilter: convert to binary: found underflow 1 at offset 0 D0305 13:25:09.812084 58310 netfilter.go:54] netfilter: convert to binary: marshalling standard target D0305 13:25:09.812175 58310 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]} D0305 13:25:09.812596 58310 netfilter.go:54] netfilter: convert to binary: current offset: 152 D0305 13:25:09.812667 58310 netfilter.go:54] netfilter: convert to binary: found hook 2 at offset 152 D0305 13:25:09.812772 58310 netfilter.go:54] netfilter: convert to binary: found underflow 2 at offset 152 D0305 13:25:09.812842 58310 netfilter.go:54] netfilter: convert to binary: marshalling standard target D0305 13:25:09.812927 58310 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]} D0305 13:25:09.813337 58310 netfilter.go:54] netfilter: convert to binary: current offset: 304 D0305 13:25:09.813415 58310 netfilter.go:54] netfilter: convert to binary: found hook 3 at offset 304 D0305 13:25:09.813498 58310 netfilter.go:54] netfilter: convert to binary: found underflow 3 at offset 304 D0305 13:25:09.813552 58310 netfilter.go:54] netfilter: convert to binary: marshalling standard target D0305 13:25:09.813611 58310 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]} D0305 13:25:09.814028 58310 netfilter.go:54] netfilter: convert to binary: current offset: 456 D0305 13:25:09.814133 58310 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]} D0305 13:25:09.814603 58310 netfilter.go:54] netfilter: convert to binary: finished with an marshalled size of 0 I0305 13:25:09.821542 58310 loader.go:566] Received external signal 23, mode: Process D0305 13:25:09.821807 58310 task_signals.go:446] [ 1] Notified of signal 23 D0305 13:25:09.821949 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:25:09.822077 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:25:09.822299 58310 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0305 13:25:09.822447 58310 task_signals.go:220] [ 1] Signal 23: delivering to handler I0305 13:25:09.841833 58310 loader.go:566] Received external signal 23, mode: Process D0305 13:25:09.842020 58310 task_signals.go:446] [ 1] Notified of signal 23 D0305 13:25:09.842151 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:25:09.842211 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:25:09.842344 58310 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0305 13:25:09.842480 58310 task_signals.go:220] [ 1] Signal 23: delivering to handler I0305 13:25:09.861951 58310 loader.go:566] Received external signal 23, mode: Process D0305 13:25:09.862184 58310 task_signals.go:446] [ 1] Notified of signal 23 D0305 13:25:09.862330 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:25:09.862464 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:25:09.862623 58310 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0305 13:25:09.862722 58310 task_signals.go:220] [ 1] Signal 23: delivering to handler I0305 13:25:09.864604 58310 loader.go:566] Received external signal 23, mode: Process D0305 13:25:09.866162 58310 task_signals.go:446] [ 1] Notified of signal 23 D0305 13:25:09.866284 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:25:09.866340 58310 task_block.go:223] [ 1] Interrupt queued I0305 13:25:09.866458 58310 loader.go:566] Received external signal 23, mode: Process D0305 13:25:09.866542 58310 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0305 13:25:09.866616 58310 task_signals.go:446] [ 1] Notified of signal 23 D0305 13:25:09.866691 58310 task_signals.go:220] [ 1] Signal 23: delivering to handler D0305 13:25:09.866737 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:25:09.867018 58310 task_block.go:223] [ 2] Interrupt queued D0305 13:25:09.867090 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:25:09.867070 58310 task_block.go:223] [ 2] Interrupt queued D0305 13:25:09.867261 58310 task_signals.go:176] [ 2] Not restarting syscall 202 after errno 516: interrupted by signal 23 D0305 13:25:09.867388 58310 task_signals.go:220] [ 2] Signal 23: delivering to handler D0305 13:25:09.880245 58310 parameters.go:238] Clock(Monotonic): error: 52 ns, adjusted frequency from 2299774638 Hz to 2299774817 Hz D0305 13:25:09.880465 58310 parameters.go:238] Clock(Realtime): error: -111 ns, adjusted frequency from 2299775297 Hz to 2299774339 Hz D0305 13:25:09.880622 58310 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9904330453865554 monotonicBaseRef:83001182017 monotonicFrequency:2299774817 realtimeReady:1 realtimeBaseCycles:9904330454382010 realtimeBaseRef:1583414709880458365 realtimeFrequency:2299774339} I0305 13:25:09.882172 58310 loader.go:566] Received external signal 23, mode: Process D0305 13:25:09.882312 58310 task_signals.go:446] [ 1] Notified of signal 23 D0305 13:25:09.882433 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:25:09.882455 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:25:09.882608 58310 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0305 13:25:09.882712 58310 task_signals.go:220] [ 1] Signal 23: delivering to handler I0305 13:25:09.902458 58310 loader.go:566] Received external signal 23, mode: Process D0305 13:25:09.902660 58310 task_signals.go:446] [ 1] Notified of signal 23 D0305 13:25:09.902778 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:25:09.902897 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:25:09.903078 58310 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0305 13:25:09.903179 58310 task_signals.go:220] [ 1] Signal 23: delivering to handler I0305 13:25:09.922607 58310 loader.go:566] Received external signal 23, mode: Process D0305 13:25:09.922785 58310 task_signals.go:446] [ 1] Notified of signal 23 D0305 13:25:09.922916 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:25:09.923021 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:25:09.923154 58310 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0305 13:25:09.923246 58310 task_signals.go:220] [ 1] Signal 23: delivering to handler I0305 13:25:09.942919 58310 loader.go:566] Received external signal 23, mode: Process D0305 13:25:09.943151 58310 task_signals.go:446] [ 1] Notified of signal 23 D0305 13:25:09.943276 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:25:09.943405 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:25:09.943622 58310 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0305 13:25:09.943773 58310 task_signals.go:220] [ 1] Signal 23: delivering to handler D0305 13:25:09.960133 58310 transport_flipcall.go:127] send [channel @0xc000138180] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0305 13:25:09.960752 58310 transport_flipcall.go:234] recv [channel @0xc000138180] Rlerror{Error: 2} D0305 13:25:09.960900 58310 transport_flipcall.go:127] send [channel @0xc000138180] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0305 13:25:09.961362 58310 transport_flipcall.go:234] recv [channel @0xc000138180] Rlerror{Error: 95} D0305 13:25:09.961785 58310 transport_flipcall.go:127] send [channel @0xc000138180] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0305 13:25:09.962412 58310 transport_flipcall.go:234] recv [channel @0xc000138180] Rlerror{Error: 2} D0305 13:25:09.962519 58310 transport_flipcall.go:127] send [channel @0xc000138180] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0305 13:25:09.962829 58310 transport_flipcall.go:234] recv [channel @0xc000138180] Rlerror{Error: 95} I0305 13:25:09.963060 58310 loader.go:566] Received external signal 23, mode: Process D0305 13:25:09.963179 58310 transport_flipcall.go:127] send [channel @0xc000138180] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0305 13:25:09.963268 58310 task_signals.go:446] [ 1] Notified of signal 23 D0305 13:25:09.963371 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:25:09.963506 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:25:09.963641 58310 transport_flipcall.go:234] recv [channel @0xc000138180] Rlerror{Error: 2} D0305 13:25:09.963712 58310 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0305 13:25:09.963819 58310 task_signals.go:220] [ 1] Signal 23: delivering to handler D0305 13:25:09.963818 58310 transport_flipcall.go:127] send [channel @0xc000138180] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0305 13:25:09.964253 58310 transport_flipcall.go:234] recv [channel @0xc000138180] Rlerror{Error: 95} D0305 13:25:09.964590 58310 transport_flipcall.go:127] send [channel @0xc000138180] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0305 13:25:09.965070 58310 transport_flipcall.go:234] recv [channel @0xc000138180] Rlerror{Error: 2} D0305 13:25:09.965203 58310 transport_flipcall.go:127] send [channel @0xc000138180] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0305 13:25:09.965586 58310 transport_flipcall.go:234] recv [channel @0xc000138180] Rlerror{Error: 95} D0305 13:25:09.965938 58310 transport_flipcall.go:127] send [channel @0xc000138180] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0305 13:25:09.966352 58310 transport_flipcall.go:234] recv [channel @0xc000138180] Rlerror{Error: 2} D0305 13:25:09.966519 58310 transport_flipcall.go:127] send [channel @0xc000138180] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0305 13:25:09.966995 58310 transport_flipcall.go:234] recv [channel @0xc000138180] Rlerror{Error: 95} D0305 13:25:09.967335 58310 transport_flipcall.go:127] send [channel @0xc000138180] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0305 13:25:09.967805 58310 transport_flipcall.go:234] recv [channel @0xc000138180] Rlerror{Error: 2} D0305 13:25:09.967964 58310 transport_flipcall.go:127] send [channel @0xc000138180] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0305 13:25:09.968352 58310 transport_flipcall.go:234] recv [channel @0xc000138180] Rlerror{Error: 95} D0305 13:25:09.968645 58310 transport_flipcall.go:127] send [channel @0xc000138180] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0305 13:25:09.969166 58310 transport_flipcall.go:234] recv [channel @0xc000138180] Rlerror{Error: 2} D0305 13:25:09.969339 58310 transport_flipcall.go:127] send [channel @0xc000138180] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0305 13:25:09.969732 58310 transport_flipcall.go:234] recv [channel @0xc000138180] Rlerror{Error: 95} D0305 13:25:09.970128 58310 transport_flipcall.go:127] send [channel @0xc000138180] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0305 13:25:09.970851 58310 transport_flipcall.go:234] recv [channel @0xc000138180] Rlerror{Error: 2} D0305 13:25:09.971032 58310 transport_flipcall.go:127] send [channel @0xc000138180] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0305 13:25:09.971533 58310 transport_flipcall.go:234] recv [channel @0xc000138180] Rlerror{Error: 95} D0305 13:25:09.972009 58310 transport_flipcall.go:127] send [channel @0xc000138180] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0305 13:25:09.972673 58310 transport_flipcall.go:234] recv [channel @0xc000138180] Rlerror{Error: 2} D0305 13:25:09.972817 58310 transport_flipcall.go:127] send [channel @0xc000138180] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0305 13:25:09.973283 58310 transport_flipcall.go:234] recv [channel @0xc000138180] Rlerror{Error: 95} D0305 13:25:09.973604 58310 transport_flipcall.go:127] send [channel @0xc000138180] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0305 13:25:09.974205 58310 transport_flipcall.go:234] recv [channel @0xc000138180] Rlerror{Error: 2} D0305 13:25:09.974422 58310 transport_flipcall.go:127] send [channel @0xc000138180] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0305 13:25:09.975086 58310 transport_flipcall.go:234] recv [channel @0xc000138180] Rlerror{Error: 95} I0305 13:25:09.983436 58310 loader.go:566] Received external signal 23, mode: Process D0305 13:25:09.983718 58310 task_signals.go:446] [ 1] Notified of signal 23 D0305 13:25:09.983869 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:25:09.983990 58310 task_block.go:223] [ 1] Interrupt queued D0305 13:25:09.984159 58310 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0305 13:25:09.984290 58310 task_signals.go:220] [ 1] Signal 23: delivering to handler D0305 13:25:09.991834 58310 task_block.go:223] [ 41] Interrupt queued D0305 13:25:10.001762 58310 task_block.go:223] [ 42] Interrupt queued D0305 13:25:10.021377 58310 task_block.go:223] [ 43] Interrupt queued ================== WARNING: DATA RACE Write at 0x00c0001b8a3a by goroutine 352: gvisor.dev/gvisor/pkg/tcpip/transport/udp.(*endpoint).SetSockOptBool() pkg/tcpip/transport/udp/endpoint.go:533 +0x2fd gvisor.dev/gvisor/pkg/sentry/socket/netstack.setSockOptIPv6() pkg/sentry/socket/netstack/netstack.go:1815 +0x282 gvisor.dev/gvisor/pkg/sentry/socket/netstack.SetSockOpt() pkg/sentry/socket/netstack/netstack.go:1541 +0x3b3 gvisor.dev/gvisor/pkg/sentry/socket/netstack.(*SocketOperations).SetSockOpt() pkg/sentry/socket/netstack/netstack.go:1527 +0x6a0 gvisor.dev/gvisor/pkg/sentry/syscalls/linux.SetSockOpt() pkg/sentry/syscalls/linux/sys_socket.go:541 +0x2fd gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).executeSyscall() pkg/sentry/kernel/task_syscall.go:170 +0x44d gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscallInvoke() pkg/sentry/kernel/task_syscall.go:291 +0xb4 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscallEnter() pkg/sentry/kernel/task_syscall.go:252 +0x109 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscall() pkg/sentry/kernel/task_syscall.go:227 +0x1b3 gvisor.dev/gvisor/pkg/sentry/kernel.(*runApp).execute() pkg/sentry/kernel/task_run.go:232 +0x1aaa gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).run() pkg/sentry/kernel/task_run.go:92 +0x320 Previous read at 0x00c0001b8a3a by goroutine 340: gvisor.dev/gvisor/pkg/tcpip/transport/udp.(*endpoint).checkV4Mapped() pkg/tcpip/transport/udp/endpoint.go:931 +0xa9 DIAGNOSIS: I0305 13:25:10.051259 60119 main.go:305] *************************** I0305 13:25:10.051396 60119 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-1] I0305 13:25:10.051608 60119 main.go:307] Version release-20200219.0-72-gce4d1e45bb88 I0305 13:25:10.051723 60119 main.go:308] PID: 60119 I0305 13:25:10.051807 60119 main.go:309] UID: 0, GID: 0 I0305 13:25:10.051875 60119 main.go:310] Configuration: I0305 13:25:10.051958 60119 main.go:311] RootDir: /syzkaller/managers/ptrace-proxy-sandbox-race/workdir/gvisor_root I0305 13:25:10.052023 60119 main.go:312] Platform: ptrace I0305 13:25:10.052108 60119 main.go:313] FileAccess: shared, overlay: false I0305 13:25:10.052191 60119 main.go:314] Network: sandbox, logging: false I0305 13:25:10.052270 60119 main.go:315] Strace: false, max size: 1024, syscalls: [] I0305 13:25:10.052339 60119 main.go:316] *************************** W0305 13:25:10.052412 60119 main.go:321] Block the TERM signal. This is only safe in tests! D0305 13:25:10.052596 60119 container.go:159] Load container "/syzkaller/managers/ptrace-proxy-sandbox-race/workdir/gvisor_root" "ci-gvisor-ptrace-proxy-sandbox-race-1" D0305 13:25:10.057851 60119 container.go:592] Signal container "ci-gvisor-ptrace-proxy-sandbox-race-1": signal 0 D0305 13:25:10.057999 60119 sandbox.go:806] Signal sandbox "ci-gvisor-ptrace-proxy-sandbox-race-1" D0305 13:25:10.058068 60119 sandbox.go:318] Connecting to sandbox "ci-gvisor-ptrace-proxy-sandbox-race-1" D0305 13:25:10.058452 60119 urpc.go:534] urpc: successfully marshalled 117 bytes. I0305 13:25:10.146924 60119 debug.go:122] Found sandbox "ci-gvisor-ptrace-proxy-sandbox-race-1", PID: 58310 I0305 13:25:10.147118 60119 debug.go:131] Retrieving sandbox stacks D0305 13:25:10.147264 60119 sandbox.go:924] Stacks sandbox "ci-gvisor-ptrace-proxy-sandbox-race-1" D0305 13:25:10.147385 60119 sandbox.go:318] Connecting to sandbox "ci-gvisor-ptrace-proxy-sandbox-race-1" W0305 13:25:10.147539 60119 error.go:48] FATAL ERROR: retrieving stacks: connecting to control server at PID 58310: connection refused retrieving stacks: connecting to control server at PID 58310: connection refused W0305 13:25:10.147921 60119 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-1"]: exit status 128 I0305 13:25:10.051259 60119 main.go:305] *************************** I0305 13:25:10.051396 60119 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-1] I0305 13:25:10.051608 60119 main.go:307] Version release-20200219.0-72-gce4d1e45bb88 I0305 13:25:10.051723 60119 main.go:308] PID: 60119 I0305 13:25:10.051807 60119 main.go:309] UID: 0, GID: 0 I0305 13:25:10.051875 60119 main.go:310] Configuration: I0305 13:25:10.051958 60119 main.go:311] RootDir: /syzkaller/managers/ptrace-proxy-sandbox-race/workdir/gvisor_root I0305 13:25:10.052023 60119 main.go:312] Platform: ptrace I0305 13:25:10.052108 60119 main.go:313] FileAccess: shared, overlay: false I0305 13:25:10.052191 60119 main.go:314] Network: sandbox, logging: false I0305 13:25:10.052270 60119 main.go:315] Strace: false, max size: 1024, syscalls: [] I0305 13:25:10.052339 60119 main.go:316] *************************** W0305 13:25:10.052412 60119 main.go:321] Block the TERM signal. This is only safe in tests! D0305 13:25:10.052596 60119 container.go:159] Load container "/syzkaller/managers/ptrace-proxy-sandbox-race/workdir/gvisor_root" "ci-gvisor-ptrace-proxy-sandbox-race-1" D0305 13:25:10.057851 60119 container.go:592] Signal container "ci-gvisor-ptrace-proxy-sandbox-race-1": signal 0 D0305 13:25:10.057999 60119 sandbox.go:806] Signal sandbox "ci-gvisor-ptrace-proxy-sandbox-race-1" D0305 13:25:10.058068 60119 sandbox.go:318] Connecting to sandbox "ci-gvisor-ptrace-proxy-sandbox-race-1" D0305 13:25:10.058452 60119 urpc.go:534] urpc: successfully marshalled 117 bytes. I0305 13:25:10.146924 60119 debug.go:122] Found sandbox "ci-gvisor-ptrace-proxy-sandbox-race-1", PID: 58310 I0305 13:25:10.147118 60119 debug.go:131] Retrieving sandbox stacks D0305 13:25:10.147264 60119 sandbox.go:924] Stacks sandbox "ci-gvisor-ptrace-proxy-sandbox-race-1" D0305 13:25:10.147385 60119 sandbox.go:318] Connecting to sandbox "ci-gvisor-ptrace-proxy-sandbox-race-1" W0305 13:25:10.147539 60119 error.go:48] FATAL ERROR: retrieving stacks: connecting to control server at PID 58310: connection refused retrieving stacks: connecting to control server at PID 58310: connection refused W0305 13:25:10.147921 60119 main.go:338] Failure to execute command, err: 1 gvisor.dev/gvisor/pkg/tcpip/transport/udp.(*endpoint).Connect() pkg/tcpip/transport/udp/endpoint.go:984 +0x6c gvisor.dev/gvisor/pkg/sentry/socket/netstack.(*SocketOperations).Connect() pkg/sentry/socket/netstack/netstack.go:698 +0x4c5 gvisor.dev/gvisor/pkg/sentry/syscalls/linux.Connect() pkg/sentry/syscalls/linux/sys_socket.go:280 +0x251 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).executeSyscall() pkg/sentry/kernel/task_syscall.go:170 +0x44d gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscallInvoke() pkg/sentry/kernel/task_syscall.go:291 +0xb4 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscallEnter() pkg/sentry/kernel/task_syscall.go:252 +0x109 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscall() pkg/sentry/kernel/task_syscall.go:227 +0x1b3 gvisor.dev/gvisor/pkg/sentry/kernel.(*runApp).execute() pkg/sentry/kernel/task_run.go:232 +0x1aaa gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).run() pkg/sentry/kernel/task_run.go:92 +0x320 Goroutine 352 (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 340 (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 ================== W0305 13:25:10.140203 60027 error.go:48] FATAL ERROR: waiting on pid 21: waiting on PID 21 in sandbox "ci-gvisor-ptrace-proxy-sandbox-race-1": urpc method "containerManager.WaitPID" failed: EOF waiting on pid 21: waiting on PID 21 in sandbox "ci-gvisor-ptrace-proxy-sandbox-race-1": urpc method "containerManager.WaitPID" failed: EOF W0305 13:25:10.140608 60027 main.go:338] Failure to execute command, err: 1 W0305 13:25:10.146965 58302 sandbox.go:742] Wait RPC to container "ci-gvisor-ptrace-proxy-sandbox-race-1" failed: urpc method "containerManager.Wait" failed: EOF. Will try waiting on the sandbox process instead. D0305 13:25:10.147409 58302 container.go:714] Destroy container "ci-gvisor-ptrace-proxy-sandbox-race-1" D0305 13:25:10.147664 58302 container.go:801] Destroying container "ci-gvisor-ptrace-proxy-sandbox-race-1" D0305 13:25:10.147792 58302 sandbox.go:1058] Destroying root container "ci-gvisor-ptrace-proxy-sandbox-race-1" by destroying sandbox D0305 13:25:10.147989 58302 sandbox.go:788] Destroy sandbox "ci-gvisor-ptrace-proxy-sandbox-race-1" D0305 13:25:10.148129 58302 container.go:815] Killing gofer for container "ci-gvisor-ptrace-proxy-sandbox-race-1", PID: 58308 I0305 13:25:10.249806 58302 main.go:329] Exiting with status: 16896