D0301 09:40:22.861572 34015 parameters.go:238] Clock(Monotonic): error: 141 ns, adjusted frequency from 2299774150 Hz to 2299774692 Hz D0301 09:40:22.862270 34015 parameters.go:238] Clock(Realtime): error: 106 ns, adjusted frequency from 2299774206 Hz to 2299774629 Hz D0301 09:40:22.862387 34015 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9078511240812687 monotonicBaseRef:2000304007 monotonicFrequency:2299774692 realtimeReady:1 realtimeBaseCycles:9078511242453334 realtimeBaseRef:1583055622862264463 realtimeFrequency:2299774629} D0301 09:40:23.861546 34015 parameters.go:238] Clock(Monotonic): error: 98 ns, adjusted frequency from 2299774692 Hz to 2299774664 Hz D0301 09:40:23.861805 34015 parameters.go:238] Clock(Realtime): error: 55 ns, adjusted frequency from 2299774629 Hz to 2299774569 Hz I0301 09:40:23.861793 34015 loader.go:566] Received external signal 23, mode: Process D0301 09:40:23.861957 34015 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9078513540540936 monotonicBaseRef:3000283812 monotonicFrequency:2299774664 realtimeReady:1 realtimeBaseCycles:9078513541158886 realtimeBaseRef:1583055623861799601 realtimeFrequency:2299774569} D0301 09:40:23.862130 34015 task_signals.go:446] [ 1] Notified of signal 23 D0301 09:40:23.862317 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:40:23.862287 34015 task_block.go:223] [ 1] Interrupt queued I0301 09:40:23.862620 34015 loader.go:566] Received external signal 23, mode: Process D0301 09:40:23.862764 34015 task_signals.go:446] [ 1] Notified of signal 23 D0301 09:40:23.862666 34015 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0301 09:40:23.862879 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:40:23.862903 34015 task_signals.go:220] [ 1] Signal 23: delivering to handler D0301 09:40:23.863438 34015 task_block.go:223] [ 2] Interrupt queued D0301 09:40:23.863473 34015 task_block.go:223] [ 2] Interrupt queued D0301 09:40:23.863587 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:40:23.863810 34015 task_signals.go:176] [ 2] Not restarting syscall 202 after errno 516: interrupted by signal 23 D0301 09:40:23.863938 34015 task_signals.go:220] [ 2] Signal 23: delivering to handler D0301 09:40:24.861603 34015 parameters.go:238] Clock(Monotonic): error: 46 ns, adjusted frequency from 2299774664 Hz to 2299774591 Hz I0301 09:40:24.861686 34015 loader.go:566] Received external signal 23, mode: Process D0301 09:40:24.861814 34015 parameters.go:238] Clock(Realtime): error: 75 ns, adjusted frequency from 2299774569 Hz to 2299774662 Hz D0301 09:40:24.861913 34015 task_signals.go:446] [ 1] Notified of signal 23 D0301 09:40:24.862042 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:40:24.861931 34015 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9078515840391879 monotonicBaseRef:4000316980 monotonicFrequency:2299774591 realtimeReady:1 realtimeBaseCycles:9078515840929796 realtimeBaseRef:1583055624861798009 realtimeFrequency:2299774662} D0301 09:40:24.862132 34015 task_block.go:223] [ 1] Interrupt queued I0301 09:40:24.862195 34015 loader.go:566] Received external signal 23, mode: Process D0301 09:40:24.862304 34015 task_signals.go:434] [ 1] Discarding duplicate signal 23 D0301 09:40:24.862515 34015 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0301 09:40:24.862676 34015 task_signals.go:220] [ 1] Signal 23: delivering to handler D0301 09:40:25.861570 34015 sampler.go:168] Time: Adjusting syscall overhead up to 8000 I0301 09:40:25.861775 34015 loader.go:566] Received external signal 23, mode: Process D0301 09:40:25.861766 34015 parameters.go:238] Clock(Monotonic): error: 298 ns, adjusted frequency from 2299774591 Hz to 2299775149 Hz D0301 09:40:25.861953 34015 task_signals.go:446] [ 1] Notified of signal 23 D0301 09:40:25.862046 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:40:25.861953 34015 parameters.go:238] Clock(Realtime): error: 74 ns, adjusted frequency from 2299774662 Hz to 2299774692 Hz D0301 09:40:25.862119 34015 task_block.go:223] [ 1] Interrupt queued I0301 09:40:25.862181 34015 loader.go:566] Received external signal 23, mode: Process D0301 09:40:25.862194 34015 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9078518140609377 monotonicBaseRef:5000509567 monotonicFrequency:2299775149 realtimeReady:1 realtimeBaseCycles:9078518141050207 realtimeBaseRef:1583055625861948349 realtimeFrequency:2299774692} D0301 09:40:25.862341 34015 task_signals.go:434] [ 1] Discarding duplicate signal 23 D0301 09:40:25.862528 34015 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0301 09:40:25.862656 34015 task_signals.go:220] [ 1] Signal 23: delivering to handler D0301 09:40:26.861523 34015 parameters.go:238] Clock(Monotonic): error: 193 ns, adjusted frequency from 2299775149 Hz to 2299774807 Hz D0301 09:40:26.861768 34015 parameters.go:238] Clock(Realtime): error: 40 ns, adjusted frequency from 2299774692 Hz to 2299774629 Hz D0301 09:40:26.861885 34015 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9078520439808658 monotonicBaseRef:6000259165 monotonicFrequency:2299774807 realtimeReady:1 realtimeBaseCycles:9078520440398686 realtimeBaseRef:1583055626861763020 realtimeFrequency:2299774629} I0301 09:40:26.896241 34015 loader.go:566] Received external signal 23, mode: Process D0301 09:40:26.896479 34015 task_signals.go:446] [ 1] Notified of signal 23 D0301 09:40:26.896651 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:40:26.896740 34015 task_block.go:223] [ 1] Interrupt queued I0301 09:40:26.896844 34015 loader.go:566] Received external signal 23, mode: Process D0301 09:40:26.896956 34015 task_signals.go:434] [ 1] Discarding duplicate signal 23 D0301 09:40:26.897145 34015 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0301 09:40:26.897335 34015 task_signals.go:220] [ 1] Signal 23: delivering to handler D0301 09:40:27.861547 34015 parameters.go:238] Clock(Monotonic): error: -194 ns, adjusted frequency from 2299774807 Hz to 2299774097 Hz I0301 09:40:27.861609 34015 loader.go:566] Received external signal 23, mode: Process D0301 09:40:27.861828 34015 parameters.go:238] Clock(Realtime): error: 74 ns, adjusted frequency from 2299774629 Hz to 2299774725 Hz D0301 09:40:27.861897 34015 task_signals.go:446] [ 1] Notified of signal 23 D0301 09:40:27.861926 34015 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9078522739567833 monotonicBaseRef:7000252367 monotonicFrequency:2299774097 realtimeReady:1 realtimeBaseCycles:9078522740302765 realtimeBaseRef:1583055627861819308 realtimeFrequency:2299774725} D0301 09:40:27.862092 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:40:27.862201 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:40:27.862414 34015 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0301 09:40:27.862537 34015 task_signals.go:220] [ 1] Signal 23: delivering to handler I0301 09:40:27.862364 34015 loader.go:566] Received external signal 23, mode: Process D0301 09:40:27.862667 34015 task_signals.go:446] [ 1] Notified of signal 23 D0301 09:40:27.862768 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:40:27.862960 34015 task_block.go:223] [ 2] Interrupt queued D0301 09:40:27.863069 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:40:27.863072 34015 task_block.go:223] [ 2] Interrupt queued D0301 09:40:27.863265 34015 task_signals.go:176] [ 2] Not restarting syscall 202 after errno 516: interrupted by signal 23 D0301 09:40:27.863413 34015 task_signals.go:220] [ 2] Signal 23: delivering to handler I0301 09:40:27.896216 34015 loader.go:566] Received external signal 23, mode: Process D0301 09:40:27.896389 34015 task_signals.go:446] [ 1] Notified of signal 23 D0301 09:40:27.896505 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:40:27.896641 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:40:27.896825 34015 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0301 09:40:27.896985 34015 task_signals.go:220] [ 1] Signal 23: delivering to handler D0301 09:40:28.861735 34015 parameters.go:238] Clock(Monotonic): error: 6 ns, adjusted frequency from 2299774097 Hz to 2299774570 Hz D0301 09:40:28.861952 34015 parameters.go:238] Clock(Realtime): error: -10 ns, adjusted frequency from 2299774725 Hz to 2299774536 Hz D0301 09:40:28.862064 34015 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9078525039745104 monotonicBaseRef:8000427677 monotonicFrequency:2299774570 realtimeReady:1 realtimeBaseCycles:9078525040368646 realtimeBaseRef:1583055628861945909 realtimeFrequency:2299774536} D0301 09:40:29.861724 34015 parameters.go:238] Clock(Monotonic): error: 114 ns, adjusted frequency from 2299774570 Hz to 2299774813 Hz D0301 09:40:29.861950 34015 parameters.go:238] Clock(Realtime): error: 130 ns, adjusted frequency from 2299774536 Hz to 2299774875 Hz D0301 09:40:29.862082 34015 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9078527339594264 monotonicBaseRef:9000460110 monotonicFrequency:2299774813 realtimeReady:1 realtimeBaseCycles:9078527340139398 realtimeBaseRef:1583055629861944263 realtimeFrequency:2299774875} I0301 09:40:29.896063 34015 loader.go:566] Received external signal 23, mode: Process D0301 09:40:29.896340 34015 task_signals.go:446] [ 1] Notified of signal 23 D0301 09:40:29.896450 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:40:29.896498 34015 task_block.go:223] [ 1] Interrupt queued I0301 09:40:29.896628 34015 loader.go:566] Received external signal 23, mode: Process D0301 09:40:29.896640 34015 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0301 09:40:29.896768 34015 task_signals.go:220] [ 1] Signal 23: delivering to handler D0301 09:40:29.896751 34015 task_signals.go:446] [ 1] Notified of signal 23 D0301 09:40:29.897118 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:40:29.897284 34015 task_block.go:223] [ 2] Interrupt queued D0301 09:40:29.897288 34015 task_block.go:223] [ 2] Interrupt queued D0301 09:40:29.897402 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:40:29.897539 34015 task_signals.go:176] [ 2] Not restarting syscall 270 after errno 514: interrupted by signal 23 D0301 09:40:29.897676 34015 task_signals.go:220] [ 2] Signal 23: delivering to handler D0301 09:40:30.861566 34015 parameters.go:238] Clock(Monotonic): error: 1147 ns, adjusted frequency from 2299774813 Hz to 2299777317 Hz D0301 09:40:30.861806 34015 parameters.go:238] Clock(Realtime): error: 17 ns, adjusted frequency from 2299774875 Hz to 2299774648 Hz D0301 09:40:30.861954 34015 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9078529639005503 monotonicBaseRef:10000302018 monotonicFrequency:2299777317 realtimeReady:1 realtimeBaseCycles:9078529639585451 realtimeBaseRef:1583055630861801282 realtimeFrequency:2299774648} D0301 09:40:31.861668 34015 parameters.go:238] Clock(Monotonic): error: -651 ns, adjusted frequency from 2299777317 Hz to 2299773056 Hz I0301 09:40:31.861749 34015 loader.go:566] Received external signal 23, mode: Process D0301 09:40:31.861942 34015 parameters.go:238] Clock(Realtime): error: 6 ns, adjusted frequency from 2299774648 Hz to 2299774639 Hz D0301 09:40:31.862006 34015 task_signals.go:446] [ 1] Notified of signal 23 D0301 09:40:31.862135 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:40:31.862090 34015 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9078531939010795 monotonicBaseRef:11000401147 monotonicFrequency:2299773056 realtimeReady:1 realtimeBaseCycles:9078531939666872 realtimeBaseRef:1583055631861934674 realtimeFrequency:2299774639} D0301 09:40:31.862301 34015 task_block.go:223] [ 1] Interrupt queued I0301 09:40:31.862364 34015 loader.go:566] Received external signal 23, mode: Process D0301 09:40:31.862626 34015 task_signals.go:434] [ 1] Discarding duplicate signal 23 D0301 09:40:31.862887 34015 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0301 09:40:31.863029 34015 task_signals.go:220] [ 1] Signal 23: delivering to handler I0301 09:40:31.896215 34015 loader.go:566] Received external signal 23, mode: Process D0301 09:40:31.896447 34015 task_signals.go:446] [ 1] Notified of signal 23 D0301 09:40:31.896526 34015 task_block.go:223] [ 1] Interrupt queued I0301 09:40:31.896685 34015 loader.go:566] Received external signal 23, mode: Process D0301 09:40:31.896779 34015 task_signals.go:434] [ 1] Discarding duplicate signal 23 D0301 09:40:31.896812 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:40:31.897186 34015 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0301 09:40:31.897354 34015 task_signals.go:220] [ 1] Signal 23: delivering to handler I0301 09:40:31.924619 34015 loader.go:566] Received external signal 23, mode: Process D0301 09:40:31.924885 34015 task_signals.go:446] [ 1] Notified of signal 23 D0301 09:40:31.925048 34015 task_block.go:223] [ 1] Interrupt queued I0301 09:40:31.925211 34015 loader.go:566] Received external signal 23, mode: Process D0301 09:40:31.925216 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:40:31.925313 34015 task_signals.go:434] [ 1] Discarding duplicate signal 23 I0301 09:40:31.925518 34015 loader.go:566] Received external signal 23, mode: Process D0301 09:40:31.925669 34015 task_signals.go:446] [ 1] Notified of signal 23 D0301 09:40:31.925478 34015 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0301 09:40:31.925776 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:40:31.925822 34015 task_signals.go:220] [ 1] Signal 23: delivering to handler D0301 09:40:31.926243 34015 task_block.go:223] [ 2] Interrupt queued D0301 09:40:31.926357 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:40:31.926538 34015 task_block.go:223] [ 2] Interrupt queued D0301 09:40:31.926732 34015 task_signals.go:176] [ 2] Not restarting syscall 202 after errno 516: interrupted by signal 23 D0301 09:40:31.926879 34015 task_signals.go:220] [ 2] Signal 23: delivering to handler D0301 09:40:32.861581 34015 parameters.go:238] Clock(Monotonic): error: -338 ns, adjusted frequency from 2299773056 Hz to 2299773854 Hz D0301 09:40:32.861854 34015 parameters.go:238] Clock(Realtime): error: -92 ns, adjusted frequency from 2299774639 Hz to 2299774415 Hz D0301 09:40:32.861986 34015 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9078534238578814 monotonicBaseRef:12000311991 monotonicFrequency:2299773854 realtimeReady:1 realtimeBaseCycles:9078534239242380 realtimeBaseRef:1583055632861848086 realtimeFrequency:2299774415} D0301 09:40:33.861539 34015 parameters.go:238] Clock(Monotonic): error: 140 ns, adjusted frequency from 2299773854 Hz to 2299774829 Hz D0301 09:40:33.861797 34015 parameters.go:238] Clock(Realtime): error: 32 ns, adjusted frequency from 2299774415 Hz to 2299774706 Hz I0301 09:40:33.861709 34015 loader.go:566] Received external signal 23, mode: Process D0301 09:40:33.861968 34015 task_signals.go:446] [ 1] Notified of signal 23 D0301 09:40:33.862099 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:40:33.861907 34015 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9078536538269290 monotonicBaseRef:13000275736 monotonicFrequency:2299774829 realtimeReady:1 realtimeBaseCycles:9078536538887308 realtimeBaseRef:1583055633861791781 realtimeFrequency:2299774706} D0301 09:40:33.862215 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:40:33.862392 34015 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0301 09:40:33.862578 34015 task_signals.go:220] [ 1] Signal 23: delivering to handler I0301 09:40:33.896301 34015 loader.go:566] Received external signal 23, mode: Process D0301 09:40:33.896554 34015 task_signals.go:446] [ 1] Notified of signal 23 D0301 09:40:33.896639 34015 task_block.go:223] [ 1] Interrupt queued I0301 09:40:33.896745 34015 loader.go:566] Received external signal 23, mode: Process D0301 09:40:33.896760 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:40:33.896873 34015 task_signals.go:434] [ 1] Discarding duplicate signal 23 D0301 09:40:33.897126 34015 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0301 09:40:33.897273 34015 task_signals.go:220] [ 1] Signal 23: delivering to handler D0301 09:40:34.861519 34015 parameters.go:238] Clock(Monotonic): error: 131 ns, adjusted frequency from 2299774829 Hz to 2299774817 Hz D0301 09:40:34.861739 34015 parameters.go:238] Clock(Realtime): error: -16 ns, adjusted frequency from 2299774706 Hz to 2299774572 Hz D0301 09:40:34.861873 34015 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9078538837997316 monotonicBaseRef:14000255384 monotonicFrequency:2299774817 realtimeReady:1 realtimeBaseCycles:9078538838521352 realtimeBaseRef:1583055634861730617 realtimeFrequency:2299774572} D0301 09:40:35.861559 34015 parameters.go:238] Clock(Monotonic): error: -175 ns, adjusted frequency from 2299774817 Hz to 2299774267 Hz I0301 09:40:35.861638 34015 loader.go:566] Received external signal 23, mode: Process D0301 09:40:35.861779 34015 parameters.go:238] Clock(Realtime): error: 5 ns, adjusted frequency from 2299774572 Hz to 2299774639 Hz D0301 09:40:35.861844 34015 task_signals.go:446] [ 1] Notified of signal 23 D0301 09:40:35.861894 34015 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9078541137868416 monotonicBaseRef:15000297250 monotonicFrequency:2299774267 realtimeReady:1 realtimeBaseCycles:9078541138394936 realtimeBaseRef:1583055635861773669 realtimeFrequency:2299774639} D0301 09:40:35.861998 34015 task_block.go:223] [ 1] Interrupt queued I0301 09:40:35.862142 34015 loader.go:566] Received external signal 23, mode: Process D0301 09:40:35.862147 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:40:35.862268 34015 task_signals.go:434] [ 1] Discarding duplicate signal 23 D0301 09:40:35.862401 34015 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0301 09:40:35.862508 34015 task_signals.go:220] [ 1] Signal 23: delivering to handler I0301 09:40:36.861647 34015 loader.go:566] Received external signal 23, mode: Process D0301 09:40:36.861611 34015 parameters.go:238] Clock(Monotonic): error: 337 ns, adjusted frequency from 2299774267 Hz to 2299775310 Hz D0301 09:40:36.861935 34015 task_signals.go:446] [ 1] Notified of signal 23 D0301 09:40:36.862047 34015 sampler.go:168] Time: Adjusting syscall overhead up to 8000 D0301 09:40:36.862127 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:40:36.862181 34015 parameters.go:238] Clock(Realtime): error: 673 ns, adjusted frequency from 2299774639 Hz to 2299776187 Hz D0301 09:40:36.862283 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:40:36.862371 34015 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9078543437695765 monotonicBaseRef:16000320331 monotonicFrequency:2299775310 realtimeReady:1 realtimeBaseCycles:9078543439076520 realtimeBaseRef:1583055636862168031 realtimeFrequency:2299776187} D0301 09:40:36.862518 34015 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0301 09:40:36.862651 34015 task_signals.go:220] [ 1] Signal 23: delivering to handler D0301 09:40:37.862393 34015 parameters.go:238] Clock(Monotonic): error: -230 ns, adjusted frequency from 2299775310 Hz to 2299774021 Hz I0301 09:40:37.862587 34015 loader.go:566] Received external signal 23, mode: Process D0301 09:40:37.862604 34015 parameters.go:238] Clock(Realtime): error: -415 ns, adjusted frequency from 2299776187 Hz to 2299773644 Hz D0301 09:40:37.862799 34015 task_signals.go:446] [ 1] Notified of signal 23 D0301 09:40:37.862912 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:40:37.862802 34015 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9078545738331701 monotonicBaseRef:17000694552 monotonicFrequency:2299774021 realtimeReady:1 realtimeBaseCycles:9078545739840091 realtimeBaseRef:1583055637862597370 realtimeFrequency:2299773644} D0301 09:40:37.862963 34015 task_block.go:223] [ 1] Interrupt queued I0301 09:40:37.863036 34015 loader.go:566] Received external signal 23, mode: Process D0301 09:40:37.863358 34015 task_signals.go:446] [ 1] Notified of signal 23 D0301 09:40:37.863361 34015 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0301 09:40:37.863525 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:40:37.863635 34015 task_signals.go:220] [ 1] Signal 23: delivering to handler D0301 09:40:37.864294 34015 task_block.go:223] [ 2] Interrupt queued D0301 09:40:37.864455 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:40:37.864410 34015 task_block.go:223] [ 2] Interrupt queued D0301 09:40:37.864711 34015 task_signals.go:176] [ 2] Not restarting syscall 202 after errno 516: interrupted by signal 23 D0301 09:40:37.864856 34015 task_signals.go:220] [ 2] Signal 23: delivering to handler D0301 09:40:38.861608 34015 parameters.go:238] Clock(Monotonic): error: -81 ns, adjusted frequency from 2299774021 Hz to 2299774453 Hz D0301 09:40:38.861950 34015 parameters.go:238] Clock(Realtime): error: -96 ns, adjusted frequency from 2299773644 Hz to 2299774404 Hz I0301 09:40:38.862034 34015 loader.go:566] Received external signal 23, mode: Process D0301 09:40:38.862154 34015 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9078548037296191 monotonicBaseRef:18000342547 monotonicFrequency:2299774453 realtimeReady:1 realtimeBaseCycles:9078548038103525 realtimeBaseRef:1583055638861940692 realtimeFrequency:2299774404} D0301 09:40:38.862376 34015 task_signals.go:446] [ 1] Notified of signal 23 D0301 09:40:38.862568 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:40:38.862689 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:40:38.862976 34015 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0301 09:40:38.863136 34015 task_signals.go:220] [ 1] Signal 23: delivering to handler I0301 09:40:38.896242 34015 loader.go:566] Received external signal 23, mode: Process D0301 09:40:38.896479 34015 task_signals.go:446] [ 1] Notified of signal 23 D0301 09:40:38.896607 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:40:38.896671 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:40:38.896850 34015 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0301 09:40:38.896987 34015 task_signals.go:220] [ 1] Signal 23: delivering to handler D0301 09:40:39.861569 34015 parameters.go:238] Clock(Monotonic): error: 14 ns, adjusted frequency from 2299774453 Hz to 2299774607 Hz D0301 09:40:39.861828 34015 parameters.go:238] Clock(Realtime): error: 162 ns, adjusted frequency from 2299774404 Hz to 2299774934 Hz D0301 09:40:39.861982 34015 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9078550336980039 monotonicBaseRef:19000303149 monotonicFrequency:2299774607 realtimeReady:1 realtimeBaseCycles:9078550337605451 realtimeBaseRef:1583055639861822211 realtimeFrequency:2299774934} D0301 09:40:40.861489 34015 parameters.go:238] Clock(Monotonic): error: 189 ns, adjusted frequency from 2299774607 Hz to 2299775008 Hz D0301 09:40:40.861677 34015 parameters.go:238] Clock(Realtime): error: -55 ns, adjusted frequency from 2299774934 Hz to 2299774468 Hz D0301 09:40:40.861824 34015 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9078552636579876 monotonicBaseRef:20000227154 monotonicFrequency:2299775008 realtimeReady:1 realtimeBaseCycles:9078552637037489 realtimeBaseRef:1583055640861673111 realtimeFrequency:2299774468} I0301 09:40:40.896176 34015 loader.go:566] Received external signal 23, mode: Process D0301 09:40:40.896444 34015 task_signals.go:446] [ 1] Notified of signal 23 D0301 09:40:40.896613 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:40:40.896666 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:40:40.896848 34015 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0301 09:40:40.896989 34015 task_signals.go:220] [ 1] Signal 23: delivering to handler D0301 09:40:41.861569 34015 parameters.go:238] Clock(Monotonic): error: -242 ns, adjusted frequency from 2299775008 Hz to 2299774074 Hz D0301 09:40:41.861817 34015 parameters.go:238] Clock(Realtime): error: -129 ns, adjusted frequency from 2299774468 Hz to 2299774340 Hz D0301 09:40:41.861962 34015 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9078554936536265 monotonicBaseRef:21000306023 monotonicFrequency:2299774074 realtimeReady:1 realtimeBaseCycles:9078554937127305 realtimeBaseRef:1583055641861810232 realtimeFrequency:2299774340} I0301 09:40:41.925050 34015 loader.go:566] Received external signal 23, mode: Process D0301 09:40:41.925371 34015 task_signals.go:446] [ 1] Notified of signal 23 D0301 09:40:41.925515 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:40:41.925652 34015 task_block.go:223] [ 1] Interrupt queued I0301 09:40:41.925716 34015 loader.go:566] Received external signal 23, mode: Process D0301 09:40:41.925875 34015 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0301 09:40:41.925995 34015 task_signals.go:220] [ 1] Signal 23: delivering to handler D0301 09:40:41.925903 34015 task_signals.go:446] [ 1] Notified of signal 23 D0301 09:40:41.926497 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:40:41.926669 34015 task_block.go:223] [ 2] Interrupt queued D0301 09:40:41.926771 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:40:41.927079 34015 task_signals.go:220] [ 2] Signal 23: delivering to handler I0301 09:40:42.861712 34015 loader.go:566] Received external signal 23, mode: Process D0301 09:40:42.862047 34015 task_signals.go:446] [ 1] Notified of signal 23 D0301 09:40:42.861592 34015 parameters.go:238] Clock(Monotonic): error: -4 ns, adjusted frequency from 2299774074 Hz to 2299774719 Hz D0301 09:40:42.862188 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:40:42.862234 34015 parameters.go:238] Clock(Realtime): error: 437 ns, adjusted frequency from 2299774340 Hz to 2299775615 Hz D0301 09:40:42.862257 34015 task_block.go:223] [ 1] Interrupt queued I0301 09:40:42.862390 34015 loader.go:566] Received external signal 23, mode: Process D0301 09:40:42.862518 34015 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0301 09:40:42.862549 34015 task_signals.go:446] [ 1] Notified of signal 23 D0301 09:40:42.862648 34015 task_signals.go:220] [ 1] Signal 23: delivering to handler D0301 09:40:42.862683 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:40:42.863129 34015 task_block.go:223] [ 2] Interrupt queued D0301 09:40:42.863172 34015 task_block.go:223] [ 2] Interrupt queued D0301 09:40:42.863249 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:40:42.862350 34015 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9078557236355680 monotonicBaseRef:22000325738 monotonicFrequency:2299774719 realtimeReady:1 realtimeBaseCycles:9078557237856471 realtimeBaseRef:1583055642862225414 realtimeFrequency:2299775615} D0301 09:40:42.863345 34015 task_signals.go:176] [ 2] Not restarting syscall 202 after errno 516: interrupted by signal 23 D0301 09:40:42.863516 34015 task_signals.go:220] [ 2] Signal 23: delivering to handler I0301 09:40:42.896231 34015 loader.go:566] Received external signal 23, mode: Process D0301 09:40:42.896611 34015 task_signals.go:446] [ 1] Notified of signal 23 D0301 09:40:42.896765 34015 task_block.go:223] [ 1] Interrupt queued I0301 09:40:42.896969 34015 loader.go:566] Received external signal 23, mode: Process D0301 09:40:42.897094 34015 task_signals.go:434] [ 1] Discarding duplicate signal 23 D0301 09:40:42.896930 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:40:42.897589 34015 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0301 09:40:42.897762 34015 task_signals.go:220] [ 1] Signal 23: delivering to handler D0301 09:40:43.861558 34015 parameters.go:238] Clock(Monotonic): error: 321 ns, adjusted frequency from 2299774719 Hz to 2299775424 Hz D0301 09:40:43.861800 34015 parameters.go:238] Clock(Realtime): error: -267 ns, adjusted frequency from 2299775615 Hz to 2299773982 Hz D0301 09:40:43.861927 34015 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9078559536052245 monotonicBaseRef:23000291754 monotonicFrequency:2299775424 realtimeReady:1 realtimeBaseCycles:9078559536642561 realtimeBaseRef:1583055643861795143 realtimeFrequency:2299773982} D0301 09:40:44.861530 34015 parameters.go:238] Clock(Monotonic): error: -168 ns, adjusted frequency from 2299775424 Hz to 2299774324 Hz D0301 09:40:44.861782 34015 parameters.go:238] Clock(Realtime): error: 44 ns, adjusted frequency from 2299773982 Hz to 2299774601 Hz D0301 09:40:44.861946 34015 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9078561835763203 monotonicBaseRef:24000263722 monotonicFrequency:2299774324 realtimeReady:1 realtimeBaseCycles:9078561836366675 realtimeBaseRef:1583055644861773459 realtimeFrequency:2299774601} D0301 09:40:45.861479 34015 parameters.go:238] Clock(Monotonic): error: -162 ns, adjusted frequency from 2299774324 Hz to 2299774349 Hz I0301 09:40:45.861669 34015 loader.go:566] Received external signal 23, mode: Process D0301 09:40:45.861777 34015 parameters.go:238] Clock(Realtime): error: 291 ns, adjusted frequency from 2299774601 Hz to 2299775245 Hz D0301 09:40:45.861921 34015 task_signals.go:446] [ 1] Notified of signal 23 D0301 09:40:45.862205 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:40:45.862067 34015 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9078564135416951 monotonicBaseRef:25000211292 monotonicFrequency:2299774349 realtimeReady:1 realtimeBaseCycles:9078564136128230 realtimeBaseRef:1583055645861767786 realtimeFrequency:2299775245} D0301 09:40:45.862316 34015 task_block.go:223] [ 1] Interrupt queued I0301 09:40:45.862387 34015 loader.go:566] Received external signal 23, mode: Process D0301 09:40:45.862528 34015 task_signals.go:434] [ 1] Discarding duplicate signal 23 D0301 09:40:45.862727 34015 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0301 09:40:45.862893 34015 task_signals.go:220] [ 1] Signal 23: delivering to handler I0301 09:40:45.897053 34015 loader.go:566] Received external signal 23, mode: Process D0301 09:40:45.897255 34015 task_signals.go:446] [ 1] Notified of signal 23 D0301 09:40:45.897495 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:40:45.897589 34015 task_block.go:223] [ 1] Interrupt queued I0301 09:40:45.897696 34015 loader.go:566] Received external signal 23, mode: Process D0301 09:40:45.897886 34015 task_signals.go:446] [ 1] Notified of signal 23 D0301 09:40:45.897862 34015 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0301 09:40:45.898021 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:40:45.898054 34015 task_signals.go:220] [ 1] Signal 23: delivering to handler D0301 09:40:45.898598 34015 task_block.go:223] [ 2] Interrupt queued D0301 09:40:45.898777 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:40:45.898669 34015 task_block.go:223] [ 2] Interrupt queued D0301 09:40:45.899003 34015 task_signals.go:176] [ 2] Not restarting syscall 202 after errno 516: interrupted by signal 23 D0301 09:40:45.899172 34015 task_signals.go:220] [ 2] Signal 23: delivering to handler I0301 09:40:46.862355 34015 loader.go:566] Received external signal 23, mode: Process D0301 09:40:46.862117 34015 parameters.go:238] Clock(Monotonic): error: 358 ns, adjusted frequency from 2299774349 Hz to 2299775429 Hz D0301 09:40:46.862972 34015 task_signals.go:446] [ 1] Notified of signal 23 D0301 09:40:46.863160 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:40:46.862962 34015 parameters.go:238] Clock(Realtime): error: 16 ns, adjusted frequency from 2299775245 Hz to 2299774602 Hz I0301 09:40:46.863362 34015 loader.go:566] Received external signal 23, mode: Process D0301 09:40:46.863488 34015 task_signals.go:434] [ 1] Discarding duplicate signal 23 D0301 09:40:46.863263 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:40:46.863452 34015 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9078566435345354 monotonicBaseRef:26000278278 monotonicFrequency:2299775429 realtimeReady:1 realtimeBaseCycles:9078566438558774 realtimeBaseRef:1583055646862922376 realtimeFrequency:2299774602} D0301 09:40:46.863737 34015 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0301 09:40:46.865304 34015 task_signals.go:220] [ 1] Signal 23: delivering to handler I0301 09:40:46.865291 34015 loader.go:566] Received external signal 23, mode: Process D0301 09:40:46.865541 34015 task_signals.go:446] [ 1] Notified of signal 23 D0301 09:40:46.865743 34015 task_block.go:223] [ 1] Interrupt queued I0301 09:40:46.865945 34015 loader.go:566] Received external signal 23, mode: Process D0301 09:40:46.866128 34015 task_block.go:223] [ 2] Interrupt queued D0301 09:40:46.866237 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:40:46.866234 34015 task_block.go:223] [ 2] Interrupt queued D0301 09:40:46.866365 34015 task_signals.go:434] [ 1] Discarding duplicate signal 23 D0301 09:40:46.866557 34015 task_signals.go:176] [ 2] Not restarting syscall 202 after errno 516: interrupted by signal 23 D0301 09:40:46.866688 34015 task_signals.go:220] [ 2] Signal 23: delivering to handler D0301 09:40:47.861507 34015 parameters.go:238] Clock(Monotonic): error: -258 ns, adjusted frequency from 2299775429 Hz to 2299774053 Hz D0301 09:40:47.861761 34015 parameters.go:238] Clock(Realtime): error: -108 ns, adjusted frequency from 2299774602 Hz to 2299774336 Hz D0301 09:40:47.861887 34015 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9078568735036566 monotonicBaseRef:27000241658 monotonicFrequency:2299774053 realtimeReady:1 realtimeBaseCycles:9078568735646673 realtimeBaseRef:1583055647861754129 realtimeFrequency:2299774336} D0301 09:40:48.861590 34015 parameters.go:238] Clock(Monotonic): error: -181 ns, adjusted frequency from 2299774053 Hz to 2299774196 Hz D0301 09:40:48.861830 34015 parameters.go:238] Clock(Realtime): error: 129 ns, adjusted frequency from 2299774336 Hz to 2299774927 Hz D0301 09:40:48.862013 34015 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9078571034972609 monotonicBaseRef:28000312095 monotonicFrequency:2299774196 realtimeReady:1 realtimeBaseCycles:9078571035578471 realtimeBaseRef:1583055648861822597 realtimeFrequency:2299774927} D0301 09:40:49.861899 34015 parameters.go:238] Clock(Monotonic): error: 261 ns, adjusted frequency from 2299774196 Hz to 2299775227 Hz D0301 09:40:49.862130 34015 parameters.go:238] Clock(Realtime): error: -237 ns, adjusted frequency from 2299774927 Hz to 2299774066 Hz D0301 09:40:49.862270 34015 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9078573335469830 monotonicBaseRef:29000626484 monotonicFrequency:2299775227 realtimeReady:1 realtimeBaseCycles:9078573336044955 realtimeBaseRef:1583055649862123303 realtimeFrequency:2299774066} D0301 09:40:50.861538 34015 parameters.go:238] Clock(Monotonic): error: -405 ns, adjusted frequency from 2299775227 Hz to 2299773686 Hz D0301 09:40:50.861747 34015 parameters.go:238] Clock(Realtime): error: -50 ns, adjusted frequency from 2299774066 Hz to 2299774456 Hz D0301 09:40:50.861863 34015 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9078575634436203 monotonicBaseRef:30000274773 monotonicFrequency:2299773686 realtimeReady:1 realtimeBaseCycles:9078575634941622 realtimeBaseRef:1583055650861741787 realtimeFrequency:2299774456} D0301 09:40:51.861515 34015 parameters.go:238] Clock(Monotonic): error: 307 ns, adjusted frequency from 2299773686 Hz to 2299775371 Hz D0301 09:40:51.861896 34015 parameters.go:238] Clock(Realtime): error: 109 ns, adjusted frequency from 2299774456 Hz to 2299774794 Hz D0301 09:40:51.862025 34015 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9078577934150477 monotonicBaseRef:31000248939 monotonicFrequency:2299775371 realtimeReady:1 realtimeBaseCycles:9078577935055755 realtimeBaseRef:1583055651861889487 realtimeFrequency:2299774794} I0301 09:40:51.925768 34015 loader.go:566] Received external signal 23, mode: Process D0301 09:40:51.926099 34015 task_signals.go:446] [ 1] Notified of signal 23 D0301 09:40:51.926232 34015 task_block.go:223] [ 1] Interrupt queued I0301 09:40:51.926389 34015 loader.go:566] Received external signal 23, mode: Process D0301 09:40:51.926500 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:40:51.926569 34015 task_signals.go:434] [ 1] Discarding duplicate signal 23 D0301 09:40:51.926776 34015 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0301 09:40:51.926925 34015 task_signals.go:220] [ 1] Signal 23: delivering to handler D0301 09:40:52.861542 34015 parameters.go:238] Clock(Monotonic): error: 195 ns, adjusted frequency from 2299775371 Hz to 2299775105 Hz I0301 09:40:52.861662 34015 loader.go:566] Received external signal 23, mode: Process D0301 09:40:52.861761 34015 parameters.go:238] Clock(Realtime): error: 144 ns, adjusted frequency from 2299774794 Hz to 2299774991 Hz D0301 09:40:52.861899 34015 task_signals.go:446] [ 1] Notified of signal 23 D0301 09:40:52.861864 34015 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9078580233928311 monotonicBaseRef:32000250009 monotonicFrequency:2299775105 realtimeReady:1 realtimeBaseCycles:9078580234520699 realtimeBaseRef:1583055652861754756 realtimeFrequency:2299774991} D0301 09:40:52.862106 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:40:52.862129 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:40:52.862343 34015 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0301 09:40:52.862458 34015 task_signals.go:220] [ 1] Signal 23: delivering to handler I0301 09:40:52.896521 34015 loader.go:566] Received external signal 23, mode: Process D0301 09:40:52.896765 34015 task_signals.go:446] [ 1] Notified of signal 23 D0301 09:40:52.896869 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:40:52.896978 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:40:52.897192 34015 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0301 09:40:52.897374 34015 task_signals.go:220] [ 1] Signal 23: delivering to handler D0301 09:40:52.906445 34015 urpc.go:577] urpc: unmarshal success. D0301 09:40:52.906744 34015 controller.go:501] containerManager.Signal &{CID:ci-gvisor-ptrace-proxy-sandbox-race-1 Signo:0 PID:0 Mode:Process} D0301 09:40:52.907044 34015 urpc.go:534] urpc: successfully marshalled 37 bytes. D0301 09:40:53.861539 34015 parameters.go:238] Clock(Monotonic): error: -287 ns, adjusted frequency from 2299775105 Hz to 2299773967 Hz I0301 09:40:53.861779 34015 loader.go:566] Received external signal 23, mode: Process D0301 09:40:53.861858 34015 parameters.go:238] Clock(Realtime): error: 95 ns, adjusted frequency from 2299774991 Hz to 2299774940 Hz D0301 09:40:53.861987 34015 task_signals.go:446] [ 1] Notified of signal 23 D0301 09:40:53.862133 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:40:53.862041 34015 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9078582533751213 monotonicBaseRef:33000270792 monotonicFrequency:2299773967 realtimeReady:1 realtimeBaseCycles:9078582534511835 realtimeBaseRef:1583055653861848741 realtimeFrequency:2299774940} D0301 09:40:53.862249 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:40:53.862515 34015 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0301 09:40:53.862670 34015 task_signals.go:220] [ 1] Signal 23: delivering to handler D0301 09:40:54.861529 34015 parameters.go:238] Clock(Monotonic): error: -35 ns, adjusted frequency from 2299773967 Hz to 2299774559 Hz D0301 09:40:54.861816 34015 parameters.go:238] Clock(Realtime): error: -238 ns, adjusted frequency from 2299774940 Hz to 2299774080 Hz D0301 09:40:54.861941 34015 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9078584833513027 monotonicBaseRef:34000265507 monotonicFrequency:2299774559 realtimeReady:1 realtimeBaseCycles:9078584834187114 realtimeBaseRef:1583055654861805405 realtimeFrequency:2299774080} D0301 09:40:55.861532 34015 parameters.go:238] Clock(Monotonic): error: 107 ns, adjusted frequency from 2299774559 Hz to 2299774973 Hz D0301 09:40:55.861787 34015 parameters.go:238] Clock(Realtime): error: 306 ns, adjusted frequency from 2299774080 Hz to 2299775388 Hz D0301 09:40:55.861934 34015 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9078587133288190 monotonicBaseRef:35000265769 monotonicFrequency:2299774973 realtimeReady:1 realtimeBaseCycles:9078587133899560 realtimeBaseRef:1583055655861778604 realtimeFrequency:2299775388} D0301 09:40:56.861493 34015 parameters.go:238] Clock(Monotonic): error: 136 ns, adjusted frequency from 2299774973 Hz to 2299774875 Hz D0301 09:40:56.861717 34015 parameters.go:238] Clock(Realtime): error: -237 ns, adjusted frequency from 2299775388 Hz to 2299774095 Hz D0301 09:40:56.861854 34015 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9078589432978576 monotonicBaseRef:36000228988 monotonicFrequency:2299774875 realtimeReady:1 realtimeBaseCycles:9078589433505263 realtimeBaseRef:1583055656861704820 realtimeFrequency:2299774095} D0301 09:40:57.861523 34015 parameters.go:238] Clock(Monotonic): error: -472 ns, adjusted frequency from 2299774875 Hz to 2299773543 Hz D0301 09:40:57.861734 34015 parameters.go:238] Clock(Realtime): error: -130 ns, adjusted frequency from 2299774095 Hz to 2299774310 Hz D0301 09:40:57.861893 34015 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9078591732824242 monotonicBaseRef:37000259769 monotonicFrequency:2299773543 realtimeReady:1 realtimeBaseCycles:9078591733333732 realtimeBaseRef:1583055657861728463 realtimeFrequency:2299774310} I0301 09:40:57.896202 34015 loader.go:566] Received external signal 23, mode: Process D0301 09:40:57.896508 34015 task_signals.go:446] [ 1] Notified of signal 23 D0301 09:40:57.896675 34015 task_block.go:223] [ 1] Interrupt queued I0301 09:40:57.896875 34015 loader.go:566] Received external signal 23, mode: Process D0301 09:40:57.896922 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:40:57.897079 34015 task_signals.go:434] [ 1] Discarding duplicate signal 23 D0301 09:40:57.897322 34015 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0301 09:40:57.897499 34015 task_signals.go:220] [ 1] Signal 23: delivering to handler D0301 09:40:58.862216 34015 parameters.go:238] Clock(Monotonic): error: 183 ns, adjusted frequency from 2299773543 Hz to 2299775174 Hz D0301 09:40:58.863126 34015 parameters.go:238] Clock(Realtime): error: 208 ns, adjusted frequency from 2299774310 Hz to 2299775075 Hz D0301 09:40:58.863315 34015 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9078594034193280 monotonicBaseRef:38000953530 monotonicFrequency:2299775174 realtimeReady:1 realtimeBaseCycles:9078594036303466 realtimeBaseRef:1583055658863117914 realtimeFrequency:2299775075} D0301 09:40:59.861478 34015 parameters.go:238] Clock(Monotonic): error: 248 ns, adjusted frequency from 2299775174 Hz to 2299775158 Hz I0301 09:40:59.861635 34015 loader.go:566] Received external signal 23, mode: Process D0301 09:40:59.861699 34015 parameters.go:238] Clock(Realtime): error: -39 ns, adjusted frequency from 2299775075 Hz to 2299774508 Hz D0301 09:40:59.861864 34015 task_signals.go:446] [ 1] Notified of signal 23 D0301 09:40:59.861982 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:40:59.861928 34015 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9078596332265745 monotonicBaseRef:39000213149 monotonicFrequency:2299775158 realtimeReady:1 realtimeBaseCycles:9078596332798966 realtimeBaseRef:1583055659861691872 realtimeFrequency:2299774508} D0301 09:40:59.862025 34015 task_block.go:223] [ 1] Interrupt queued I0301 09:40:59.862101 34015 loader.go:566] Received external signal 23, mode: Process D0301 09:40:59.862261 34015 task_signals.go:434] [ 1] Discarding duplicate signal 23 D0301 09:40:59.862423 34015 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0301 09:40:59.862570 34015 task_signals.go:220] [ 1] Signal 23: delivering to handler D0301 09:41:00.861501 34015 parameters.go:238] Clock(Monotonic): error: 173 ns, adjusted frequency from 2299775158 Hz to 2299775000 Hz I0301 09:41:00.861659 34015 loader.go:566] Received external signal 23, mode: Process D0301 09:41:00.861755 34015 parameters.go:238] Clock(Realtime): error: 101 ns, adjusted frequency from 2299774508 Hz to 2299774942 Hz D0301 09:41:00.861884 34015 task_signals.go:446] [ 1] Notified of signal 23 D0301 09:41:00.861865 34015 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9078598632093452 monotonicBaseRef:40000235998 monotonicFrequency:2299775000 realtimeReady:1 realtimeBaseCycles:9078598632704128 realtimeBaseRef:1583055660861748683 realtimeFrequency:2299774942} D0301 09:41:00.862008 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:41:00.862081 34015 task_block.go:223] [ 1] Interrupt queued I0301 09:41:00.862211 34015 loader.go:566] Received external signal 23, mode: Process D0301 09:41:00.862375 34015 task_signals.go:434] [ 1] Discarding duplicate signal 23 D0301 09:41:00.862567 34015 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0301 09:41:00.862732 34015 task_signals.go:220] [ 1] Signal 23: delivering to handler I0301 09:41:00.896801 34015 loader.go:566] Received external signal 23, mode: Process D0301 09:41:00.897035 34015 task_signals.go:446] [ 1] Notified of signal 23 D0301 09:41:00.897195 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:41:00.897341 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:41:00.897602 34015 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0301 09:41:00.897729 34015 task_signals.go:220] [ 1] Signal 23: delivering to handler D0301 09:41:01.861547 34015 parameters.go:238] Clock(Monotonic): error: -434 ns, adjusted frequency from 2299775000 Hz to 2299773692 Hz I0301 09:41:01.861735 34015 loader.go:566] Received external signal 23, mode: Process D0301 09:41:01.861788 34015 parameters.go:238] Clock(Realtime): error: -10 ns, adjusted frequency from 2299774942 Hz to 2299774566 Hz D0301 09:41:01.861967 34015 task_signals.go:446] [ 1] Notified of signal 23 D0301 09:41:01.861948 34015 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9078600931979011 monotonicBaseRef:41000284071 monotonicFrequency:2299773692 realtimeReady:1 realtimeBaseCycles:9078600932551282 realtimeBaseRef:1583055661861780082 realtimeFrequency:2299774566} D0301 09:41:01.862143 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:41:01.862203 34015 task_block.go:223] [ 1] Interrupt queued I0301 09:41:01.862322 34015 loader.go:566] Received external signal 23, mode: Process D0301 09:41:01.862436 34015 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0301 09:41:01.862457 34015 task_signals.go:446] [ 1] Notified of signal 23 D0301 09:41:01.862649 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:41:01.862628 34015 task_signals.go:220] [ 1] Signal 23: delivering to handler D0301 09:41:01.863161 34015 task_block.go:223] [ 2] Interrupt queued D0301 09:41:01.863284 34015 task_block.go:223] [ 2] Interrupt queued D0301 09:41:01.863298 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:41:01.863534 34015 task_signals.go:176] [ 2] Not restarting syscall 202 after errno 516: interrupted by signal 23 D0301 09:41:01.863654 34015 task_signals.go:220] [ 2] Signal 23: delivering to handler D0301 09:41:02.861499 34015 parameters.go:238] Clock(Monotonic): error: 324 ns, adjusted frequency from 2299773692 Hz to 2299775292 Hz D0301 09:41:02.861731 34015 parameters.go:238] Clock(Realtime): error: 5 ns, adjusted frequency from 2299774566 Hz to 2299774587 Hz D0301 09:41:02.861902 34015 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9078603231632377 monotonicBaseRef:42000231750 monotonicFrequency:2299775292 realtimeReady:1 realtimeBaseCycles:9078603232195891 realtimeBaseRef:1583055662861723573 realtimeFrequency:2299774587} D0301 09:41:03.861499 34015 parameters.go:238] Clock(Monotonic): error: -121 ns, adjusted frequency from 2299775292 Hz to 2299774216 Hz D0301 09:41:03.861702 34015 parameters.go:238] Clock(Realtime): error: -310 ns, adjusted frequency from 2299774587 Hz to 2299773927 Hz I0301 09:41:03.861707 34015 loader.go:566] Received external signal 23, mode: Process D0301 09:41:03.861835 34015 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9078605531426104 monotonicBaseRef:43000239766 monotonicFrequency:2299774216 realtimeReady:1 realtimeBaseCycles:9078605531909670 realtimeBaseRef:1583055663861697132 realtimeFrequency:2299773927} D0301 09:41:03.861918 34015 task_signals.go:446] [ 1] Notified of signal 23 D0301 09:41:03.862044 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:41:03.862068 34015 task_block.go:223] [ 1] Interrupt queued I0301 09:41:03.862234 34015 loader.go:566] Received external signal 23, mode: Process D0301 09:41:03.862347 34015 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0301 09:41:03.862445 34015 task_signals.go:220] [ 1] Signal 23: delivering to handler D0301 09:41:03.862405 34015 task_signals.go:446] [ 1] Notified of signal 23 D0301 09:41:03.862645 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:41:03.862935 34015 task_block.go:223] [ 2] Interrupt queued D0301 09:41:03.863026 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:41:03.863042 34015 task_block.go:223] [ 2] Interrupt queued D0301 09:41:03.863211 34015 task_signals.go:176] [ 2] Not restarting syscall 202 after errno 516: interrupted by signal 23 D0301 09:41:03.863310 34015 task_signals.go:220] [ 2] Signal 23: delivering to handler D0301 09:41:04.861534 34015 parameters.go:238] Clock(Monotonic): error: 90 ns, adjusted frequency from 2299774216 Hz to 2299774729 Hz D0301 09:41:04.861824 34015 parameters.go:238] Clock(Realtime): error: 39 ns, adjusted frequency from 2299773927 Hz to 2299774758 Hz D0301 09:41:04.861975 34015 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9078607831276501 monotonicBaseRef:44000272891 monotonicFrequency:2299774729 realtimeReady:1 realtimeBaseCycles:9078607831957154 realtimeBaseRef:1583055664861816081 realtimeFrequency:2299774758} D0301 09:41:05.861571 34015 parameters.go:238] Clock(Monotonic): error: -46 ns, adjusted frequency from 2299774729 Hz to 2299774476 Hz I0301 09:41:05.861786 34015 loader.go:566] Received external signal 23, mode: Process D0301 09:41:05.861838 34015 parameters.go:238] Clock(Realtime): error: 18 ns, adjusted frequency from 2299774758 Hz to 2299774610 Hz D0301 09:41:05.862007 34015 task_signals.go:446] [ 1] Notified of signal 23 D0301 09:41:05.862123 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:41:05.862014 34015 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9078610131126367 monotonicBaseRef:45000305562 monotonicFrequency:2299774476 realtimeReady:1 realtimeBaseCycles:9078610131766992 realtimeBaseRef:1583055665861831334 realtimeFrequency:2299774610} D0301 09:41:05.862261 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:41:05.862560 34015 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0301 09:41:05.862755 34015 task_signals.go:220] [ 1] Signal 23: delivering to handler I0301 09:41:06.019729 34015 watchdog.go:277] Watchdog starting loop, tasks: 17, discount: 0s D0301 09:41:06.861573 34015 parameters.go:238] Clock(Monotonic): error: -124 ns, adjusted frequency from 2299774476 Hz to 2299774354 Hz I0301 09:41:06.861782 34015 loader.go:566] Received external signal 23, mode: Process D0301 09:41:06.861836 34015 parameters.go:238] Clock(Realtime): error: 249 ns, adjusted frequency from 2299774610 Hz to 2299775236 Hz D0301 09:41:06.862028 34015 task_signals.go:446] [ 1] Notified of signal 23 D0301 09:41:06.862250 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:41:06.862161 34015 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9078612430805677 monotonicBaseRef:46000264181 monotonicFrequency:2299774354 realtimeReady:1 realtimeBaseCycles:9078612431529364 realtimeBaseRef:1583055666861826012 realtimeFrequency:2299775236} D0301 09:41:06.862273 34015 task_block.go:223] [ 1] Interrupt queued I0301 09:41:06.862414 34015 loader.go:566] Received external signal 23, mode: Process D0301 09:41:06.862636 34015 task_signals.go:446] [ 1] Notified of signal 23 D0301 09:41:06.862583 34015 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0301 09:41:06.862721 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:41:06.862776 34015 task_signals.go:220] [ 1] Signal 23: delivering to handler D0301 09:41:06.863329 34015 task_block.go:223] [ 2] Interrupt queued D0301 09:41:06.863380 34015 task_block.go:223] [ 2] Interrupt queued D0301 09:41:06.863505 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:41:06.863771 34015 task_signals.go:176] [ 2] Not restarting syscall 202 after errno 516: interrupted by signal 23 D0301 09:41:06.863941 34015 task_signals.go:220] [ 2] Signal 23: delivering to handler I0301 09:41:06.896214 34015 loader.go:566] Received external signal 23, mode: Process D0301 09:41:06.896439 34015 task_signals.go:446] [ 1] Notified of signal 23 D0301 09:41:06.896565 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:41:06.896644 34015 task_block.go:223] [ 1] Interrupt queued I0301 09:41:06.896689 34015 loader.go:566] Received external signal 23, mode: Process D0301 09:41:06.896901 34015 task_signals.go:446] [ 1] Notified of signal 23 D0301 09:41:06.896891 34015 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0301 09:41:06.897008 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:41:06.897017 34015 task_signals.go:220] [ 1] Signal 23: delivering to handler D0301 09:41:06.897507 34015 task_block.go:223] [ 2] Interrupt queued D0301 09:41:06.897534 34015 task_block.go:223] [ 2] Interrupt queued D0301 09:41:06.897612 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:41:06.897776 34015 task_signals.go:176] [ 2] Not restarting syscall 270 after errno 514: interrupted by signal 23 D0301 09:41:06.897936 34015 task_signals.go:220] [ 2] Signal 23: delivering to handler D0301 09:41:07.861587 34015 parameters.go:238] Clock(Monotonic): error: 44 ns, adjusted frequency from 2299774354 Hz to 2299774738 Hz D0301 09:41:07.861849 34015 parameters.go:238] Clock(Realtime): error: -38 ns, adjusted frequency from 2299775236 Hz to 2299774542 Hz D0301 09:41:07.861983 34015 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9078614730716051 monotonicBaseRef:47000323325 monotonicFrequency:2299774738 realtimeReady:1 realtimeBaseCycles:9078614731342403 realtimeBaseRef:1583055667861842449 realtimeFrequency:2299774542} I0301 09:41:07.896226 34015 loader.go:566] Received external signal 23, mode: Process D0301 09:41:07.896435 34015 task_signals.go:446] [ 1] Notified of signal 23 D0301 09:41:07.896582 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:41:07.896609 34015 task_block.go:223] [ 1] Interrupt queued I0301 09:41:07.896822 34015 loader.go:566] Received external signal 23, mode: Process D0301 09:41:07.896817 34015 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0301 09:41:07.897032 34015 task_signals.go:220] [ 1] Signal 23: delivering to handler D0301 09:41:07.896990 34015 task_signals.go:446] [ 1] Notified of signal 23 D0301 09:41:07.897241 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:41:07.897455 34015 task_block.go:223] [ 2] Interrupt queued D0301 09:41:07.897494 34015 task_block.go:223] [ 2] Interrupt queued D0301 09:41:07.897557 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:41:07.897662 34015 task_signals.go:176] [ 2] Not restarting syscall 202 after errno 516: interrupted by signal 23 D0301 09:41:07.897755 34015 task_signals.go:220] [ 2] Signal 23: delivering to handler D0301 09:41:08.861528 34015 parameters.go:238] Clock(Monotonic): error: 42 ns, adjusted frequency from 2299774738 Hz to 2299774648 Hz D0301 09:41:08.861749 34015 parameters.go:238] Clock(Realtime): error: -6 ns, adjusted frequency from 2299774542 Hz to 2299774663 Hz D0301 09:41:08.862102 34015 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9078617030358810 monotonicBaseRef:48000265937 monotonicFrequency:2299774648 realtimeReady:1 realtimeBaseCycles:9078617030887248 realtimeBaseRef:1583055668861742570 realtimeFrequency:2299774663} D0301 09:41:09.861505 34015 parameters.go:238] Clock(Monotonic): error: 239 ns, adjusted frequency from 2299774648 Hz to 2299775113 Hz D0301 09:41:09.861720 34015 parameters.go:238] Clock(Realtime): error: -189 ns, adjusted frequency from 2299774663 Hz to 2299774172 Hz D0301 09:41:09.861847 34015 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9078619330081002 monotonicBaseRef:49000243127 monotonicFrequency:2299775113 realtimeReady:1 realtimeBaseCycles:9078619330597966 realtimeBaseRef:1583055669861714765 realtimeFrequency:2299774172} D0301 09:41:10.861545 34015 parameters.go:238] Clock(Monotonic): error: 329 ns, adjusted frequency from 2299775113 Hz to 2299775343 Hz I0301 09:41:10.861757 34015 loader.go:566] Received external signal 23, mode: Process D0301 09:41:10.861822 34015 parameters.go:238] Clock(Realtime): error: 369 ns, adjusted frequency from 2299774172 Hz to 2299775515 Hz D0301 09:41:10.862020 34015 task_signals.go:446] [ 1] Notified of signal 23 D0301 09:41:10.862038 34015 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9078621629942176 monotonicBaseRef:50000280548 monotonicFrequency:2299775343 realtimeReady:1 realtimeBaseCycles:9078621630602764 realtimeBaseRef:1583055670861815047 realtimeFrequency:2299775515} D0301 09:41:10.862186 34015 task_block.go:223] [ 1] Interrupt queued I0301 09:41:10.862363 34015 loader.go:566] Received external signal 23, mode: Process D0301 09:41:10.862397 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:41:10.862525 34015 task_signals.go:434] [ 1] Discarding duplicate signal 23 D0301 09:41:10.862650 34015 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0301 09:41:10.862825 34015 task_signals.go:220] [ 1] Signal 23: delivering to handler I0301 09:41:10.896186 34015 loader.go:566] Received external signal 23, mode: Process D0301 09:41:10.896488 34015 task_signals.go:446] [ 1] Notified of signal 23 D0301 09:41:10.896652 34015 task_block.go:223] [ 1] Interrupt queued I0301 09:41:10.896817 34015 loader.go:566] Received external signal 23, mode: Process D0301 09:41:10.896761 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:41:10.896991 34015 task_signals.go:434] [ 1] Discarding duplicate signal 23 D0301 09:41:10.897218 34015 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0301 09:41:10.897356 34015 task_signals.go:220] [ 1] Signal 23: delivering to handler D0301 09:41:11.861573 34015 parameters.go:238] Clock(Monotonic): error: -384 ns, adjusted frequency from 2299775343 Hz to 2299773840 Hz D0301 09:41:11.861833 34015 parameters.go:238] Clock(Realtime): error: -232 ns, adjusted frequency from 2299775515 Hz to 2299774152 Hz D0301 09:41:11.862058 34015 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9078623929780031 monotonicBaseRef:51000307729 monotonicFrequency:2299773840 realtimeReady:1 realtimeBaseCycles:9078623930402251 realtimeBaseRef:1583055671861825470 realtimeFrequency:2299774152} I0301 09:41:11.924993 34015 loader.go:566] Received external signal 23, mode: Process D0301 09:41:11.925223 34015 task_signals.go:446] [ 1] Notified of signal 23 D0301 09:41:11.925353 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:41:11.925404 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:41:11.925608 34015 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0301 09:41:11.925809 34015 task_signals.go:220] [ 1] Signal 23: delivering to handler D0301 09:41:12.861543 34015 parameters.go:238] Clock(Monotonic): error: 25 ns, adjusted frequency from 2299773840 Hz to 2299774717 Hz D0301 09:41:12.861785 34015 parameters.go:238] Clock(Realtime): error: -107 ns, adjusted frequency from 2299774152 Hz to 2299774344 Hz D0301 09:41:12.861902 34015 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9078626229490731 monotonicBaseRef:52000280274 monotonicFrequency:2299774717 realtimeReady:1 realtimeBaseCycles:9078626230067940 realtimeBaseRef:1583055672861778307 realtimeFrequency:2299774344} I0301 09:41:12.896754 34015 loader.go:566] Received external signal 23, mode: Process D0301 09:41:12.897006 34015 task_signals.go:446] [ 1] Notified of signal 23 D0301 09:41:12.897125 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:41:12.897228 34015 task_block.go:223] [ 1] Interrupt queued I0301 09:41:12.897332 34015 loader.go:566] Received external signal 23, mode: Process D0301 09:41:12.897413 34015 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0301 09:41:12.897503 34015 task_signals.go:446] [ 1] Notified of signal 23 D0301 09:41:12.897574 34015 task_signals.go:220] [ 1] Signal 23: delivering to handler D0301 09:41:12.897664 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:41:12.898089 34015 task_block.go:223] [ 2] Interrupt queued D0301 09:41:12.898154 34015 task_block.go:223] [ 2] Interrupt queued D0301 09:41:12.898242 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:41:12.898374 34015 task_signals.go:176] [ 2] Not restarting syscall 202 after errno 516: interrupted by signal 23 D0301 09:41:12.898562 34015 task_signals.go:220] [ 2] Signal 23: delivering to handler D0301 09:41:13.861828 34015 parameters.go:238] Clock(Monotonic): error: 119 ns, adjusted frequency from 2299774717 Hz to 2299774913 Hz D0301 09:41:13.862059 34015 parameters.go:238] Clock(Realtime): error: -153 ns, adjusted frequency from 2299774344 Hz to 2299774274 Hz D0301 09:41:13.862201 34015 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9078628529921584 monotonicBaseRef:53000565578 monotonicFrequency:2299774913 realtimeReady:1 realtimeBaseCycles:9078628530468765 realtimeBaseRef:1583055673862050716 realtimeFrequency:2299774274} I0301 09:41:13.896223 34015 loader.go:566] Received external signal 23, mode: Process D0301 09:41:13.896447 34015 task_signals.go:446] [ 1] Notified of signal 23 D0301 09:41:13.896606 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:41:13.896638 34015 task_block.go:223] [ 1] Interrupt queued I0301 09:41:13.896810 34015 loader.go:566] Received external signal 23, mode: Process D0301 09:41:13.896865 34015 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0301 09:41:13.897102 34015 task_signals.go:220] [ 1] Signal 23: delivering to handler D0301 09:41:13.896991 34015 task_signals.go:446] [ 1] Notified of signal 23 D0301 09:41:13.897222 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:41:13.897529 34015 task_block.go:223] [ 2] Interrupt queued D0301 09:41:13.897626 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:41:13.897646 34015 task_block.go:223] [ 2] Interrupt queued D0301 09:41:13.897895 34015 task_signals.go:176] [ 2] Not restarting syscall 202 after errno 516: interrupted by signal 23 D0301 09:41:13.898060 34015 task_signals.go:220] [ 2] Signal 23: delivering to handler D0301 09:41:14.861541 34015 parameters.go:238] Clock(Monotonic): error: -113 ns, adjusted frequency from 2299774913 Hz to 2299774377 Hz D0301 09:41:14.861731 34015 parameters.go:238] Clock(Realtime): error: 45 ns, adjusted frequency from 2299774274 Hz to 2299774786 Hz D0301 09:41:14.861825 34015 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9078630829035320 monotonicBaseRef:54000278081 monotonicFrequency:2299774377 realtimeReady:1 realtimeBaseCycles:9078630829494325 realtimeBaseRef:1583055674861725156 realtimeFrequency:2299774786} I0301 09:41:14.896232 34015 loader.go:566] Received external signal 23, mode: Process D0301 09:41:14.896416 34015 task_signals.go:446] [ 1] Notified of signal 23 D0301 09:41:14.896581 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:41:14.896622 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:41:14.896858 34015 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0301 09:41:14.897021 34015 task_signals.go:220] [ 1] Signal 23: delivering to handler D0301 09:41:15.861442 34015 parameters.go:238] Clock(Monotonic): error: -229 ns, adjusted frequency from 2299774377 Hz to 2299774107 Hz D0301 09:41:15.861686 34015 parameters.go:238] Clock(Realtime): error: 239 ns, adjusted frequency from 2299774786 Hz to 2299775183 Hz D0301 09:41:15.861811 34015 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9078633128577364 monotonicBaseRef:55000177056 monotonicFrequency:2299774107 realtimeReady:1 realtimeBaseCycles:9078633129164558 realtimeBaseRef:1583055675861679693 realtimeFrequency:2299775183} D0301 09:41:16.861573 34015 parameters.go:238] Clock(Monotonic): error: -261 ns, adjusted frequency from 2299774107 Hz to 2299774013 Hz I0301 09:41:16.861645 34015 loader.go:566] Received external signal 23, mode: Process D0301 09:41:16.861813 34015 parameters.go:238] Clock(Realtime): error: -61 ns, adjusted frequency from 2299775183 Hz to 2299774525 Hz D0301 09:41:16.861888 34015 task_signals.go:446] [ 1] Notified of signal 23 D0301 09:41:16.861979 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:41:16.861946 34015 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9078635428621209 monotonicBaseRef:56000294344 monotonicFrequency:2299774013 realtimeReady:1 realtimeBaseCycles:9078635429223591 realtimeBaseRef:1583055676861803118 realtimeFrequency:2299774525} I0301 09:41:16.862194 34015 loader.go:566] Received external signal 23, mode: Process D0301 09:41:16.862222 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:41:16.862430 34015 task_signals.go:434] [ 1] Discarding duplicate signal 23 D0301 09:41:16.862643 34015 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0301 09:41:16.862755 34015 task_signals.go:220] [ 1] Signal 23: delivering to handler I0301 09:41:16.896396 34015 loader.go:566] Received external signal 23, mode: Process D0301 09:41:16.896673 34015 task_signals.go:446] [ 1] Notified of signal 23 D0301 09:41:16.896799 34015 task_block.go:223] [ 1] Interrupt queued I0301 09:41:16.896963 34015 loader.go:566] Received external signal 23, mode: Process D0301 09:41:16.896887 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:41:16.897063 34015 task_signals.go:434] [ 1] Discarding duplicate signal 23 D0301 09:41:16.897233 34015 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0301 09:41:16.897378 34015 task_signals.go:220] [ 1] Signal 23: delivering to handler D0301 09:41:17.861721 34015 parameters.go:238] Clock(Monotonic): error: 313 ns, adjusted frequency from 2299774013 Hz to 2299775345 Hz D0301 09:41:17.861975 34015 parameters.go:238] Clock(Realtime): error: -181 ns, adjusted frequency from 2299774525 Hz to 2299774183 Hz D0301 09:41:17.862072 34015 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9078637728770908 monotonicBaseRef:57000457701 monotonicFrequency:2299775345 realtimeReady:1 realtimeBaseCycles:9078637729380742 realtimeBaseRef:1583055677861969493 realtimeFrequency:2299774183} I0301 09:41:17.862045 34015 loader.go:566] Received external signal 23, mode: Process D0301 09:41:17.862468 34015 task_signals.go:446] [ 1] Notified of signal 23 D0301 09:41:17.862709 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:41:17.862678 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:41:17.862892 34015 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0301 09:41:17.863265 34015 task_signals.go:220] [ 1] Signal 23: delivering to handler I0301 09:41:17.896200 34015 loader.go:566] Received external signal 23, mode: Process D0301 09:41:17.896456 34015 task_signals.go:446] [ 1] Notified of signal 23 D0301 09:41:17.896583 34015 task_block.go:223] [ 1] Interrupt queued I0301 09:41:17.896720 34015 loader.go:566] Received external signal 23, mode: Process D0301 09:41:17.896736 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:41:17.896836 34015 task_signals.go:434] [ 1] Discarding duplicate signal 23 D0301 09:41:17.897042 34015 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0301 09:41:17.897198 34015 task_signals.go:220] [ 1] Signal 23: delivering to handler D0301 09:41:18.861547 34015 parameters.go:238] Clock(Monotonic): error: 105 ns, adjusted frequency from 2299775345 Hz to 2299774845 Hz D0301 09:41:18.861746 34015 parameters.go:238] Clock(Realtime): error: -57 ns, adjusted frequency from 2299774183 Hz to 2299774482 Hz D0301 09:41:18.861869 34015 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9078640028147906 monotonicBaseRef:58000284489 monotonicFrequency:2299774845 realtimeReady:1 realtimeBaseCycles:9078640028621144 realtimeBaseRef:1583055678861737391 realtimeFrequency:2299774482} D0301 09:41:19.861542 34015 parameters.go:238] Clock(Monotonic): error: 176 ns, adjusted frequency from 2299774845 Hz to 2299774993 Hz D0301 09:41:19.861758 34015 parameters.go:238] Clock(Realtime): error: 18 ns, adjusted frequency from 2299774482 Hz to 2299774634 Hz D0301 09:41:19.861868 34015 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9078642327903162 monotonicBaseRef:59000275971 monotonicFrequency:2299774993 realtimeReady:1 realtimeBaseCycles:9078642328431809 realtimeBaseRef:1583055679861753124 realtimeFrequency:2299774634} D0301 09:41:20.861573 34015 parameters.go:238] Clock(Monotonic): error: -527 ns, adjusted frequency from 2299774993 Hz to 2299773382 Hz D0301 09:41:20.861878 34015 parameters.go:238] Clock(Realtime): error: 887 ns, adjusted frequency from 2299774634 Hz to 2299776744 Hz D0301 09:41:20.862001 34015 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9078644627739823 monotonicBaseRef:60000302785 monotonicFrequency:2299773382 realtimeReady:1 realtimeBaseCycles:9078644628478529 realtimeBaseRef:1583055680861871433 realtimeFrequency:2299776744} D0301 09:41:21.861582 34015 parameters.go:238] Clock(Monotonic): error: 233 ns, adjusted frequency from 2299773382 Hz to 2299775266 Hz D0301 09:41:21.861848 34015 parameters.go:238] Clock(Realtime): error: -568 ns, adjusted frequency from 2299776744 Hz to 2299773341 Hz D0301 09:41:21.862006 34015 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9078646927541449 monotonicBaseRef:61000315066 monotonicFrequency:2299775266 realtimeReady:1 realtimeBaseCycles:9078646928182283 realtimeBaseRef:1583055681861839695 realtimeFrequency:2299773341} I0301 09:41:21.896513 34015 loader.go:566] Received external signal 23, mode: Process D0301 09:41:21.896769 34015 task_signals.go:446] [ 1] Notified of signal 23 D0301 09:41:21.896892 34015 task_block.go:223] [ 1] Interrupt queued I0301 09:41:21.896991 34015 loader.go:566] Received external signal 23, mode: Process D0301 09:41:21.896953 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:41:21.897071 34015 task_signals.go:434] [ 1] Discarding duplicate signal 23 D0301 09:41:21.897160 34015 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0301 09:41:21.897332 34015 task_signals.go:220] [ 1] Signal 23: delivering to handler D0301 09:41:22.861588 34015 parameters.go:238] Clock(Monotonic): error: -1 ns, adjusted frequency from 2299775266 Hz to 2299774731 Hz D0301 09:41:22.861877 34015 parameters.go:238] Clock(Realtime): error: -300 ns, adjusted frequency from 2299773341 Hz to 2299773900 Hz D0301 09:41:22.861989 34015 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9078649227316673 monotonicBaseRef:62000315047 monotonicFrequency:2299774731 realtimeReady:1 realtimeBaseCycles:9078649228026991 realtimeBaseRef:1583055682861870727 realtimeFrequency:2299773900} I0301 09:41:22.896431 34015 loader.go:566] Received external signal 23, mode: Process D0301 09:41:22.896609 34015 task_signals.go:446] [ 1] Notified of signal 23 D0301 09:41:22.896772 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:41:22.896821 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:41:22.897316 34015 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0301 09:41:22.897461 34015 task_signals.go:220] [ 1] Signal 23: delivering to handler D0301 09:41:23.861708 34015 parameters.go:238] Clock(Monotonic): error: 100 ns, adjusted frequency from 2299774731 Hz to 2299774803 Hz D0301 09:41:23.863593 34015 parameters.go:238] Clock(Realtime): error: 279 ns, adjusted frequency from 2299773900 Hz to 2299775223 Hz D0301 09:41:23.863786 34015 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9078651527392074 monotonicBaseRef:63000445785 monotonicFrequency:2299774803 realtimeReady:1 realtimeBaseCycles:9078651531724821 realtimeBaseRef:1583055683863576951 realtimeFrequency:2299775223} D0301 09:41:23.884329 34015 urpc.go:577] urpc: unmarshal success. D0301 09:41:23.884572 34015 controller.go:501] containerManager.Signal &{CID:ci-gvisor-ptrace-proxy-sandbox-race-1 Signo:0 PID:0 Mode:Process} D0301 09:41:23.884847 34015 urpc.go:534] urpc: successfully marshalled 37 bytes. D0301 09:41:24.861597 34015 parameters.go:238] Clock(Monotonic): error: 254 ns, adjusted frequency from 2299774803 Hz to 2299775161 Hz D0301 09:41:24.861891 34015 parameters.go:238] Clock(Realtime): error: -21 ns, adjusted frequency from 2299775223 Hz to 2299774579 Hz D0301 09:41:24.862151 34015 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9078653826905908 monotonicBaseRef:64000332309 monotonicFrequency:2299775161 realtimeReady:1 realtimeBaseCycles:9078653827603457 realtimeBaseRef:1583055684861882617 realtimeFrequency:2299774579} I0301 09:41:24.896229 34015 loader.go:566] Received external signal 23, mode: Process D0301 09:41:24.896747 34015 task_signals.go:446] [ 1] Notified of signal 23 D0301 09:41:24.896919 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:41:24.897012 34015 task_block.go:223] [ 1] Interrupt queued I0301 09:41:24.897129 34015 loader.go:566] Received external signal 23, mode: Process D0301 09:41:24.897244 34015 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0301 09:41:24.897401 34015 task_signals.go:220] [ 1] Signal 23: delivering to handler D0301 09:41:24.897292 34015 task_signals.go:446] [ 1] Notified of signal 23 D0301 09:41:24.898010 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:41:24.898524 34015 task_block.go:223] [ 2] Interrupt queued D0301 09:41:24.898633 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:41:24.898691 34015 task_block.go:223] [ 2] Interrupt queued D0301 09:41:24.898932 34015 task_signals.go:176] [ 2] Not restarting syscall 202 after errno 516: interrupted by signal 23 D0301 09:41:24.899074 34015 task_signals.go:220] [ 2] Signal 23: delivering to handler D0301 09:41:25.861429 34015 parameters.go:238] Clock(Monotonic): error: -648 ns, adjusted frequency from 2299775161 Hz to 2299773138 Hz D0301 09:41:25.861594 34015 parameters.go:238] Clock(Realtime): error: -301 ns, adjusted frequency from 2299774579 Hz to 2299773960 Hz D0301 09:41:25.861710 34015 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9078656126296176 monotonicBaseRef:65000164947 monotonicFrequency:2299773138 realtimeReady:1 realtimeBaseCycles:9078656126702364 realtimeBaseRef:1583055685861588817 realtimeFrequency:2299773960} D0301 09:41:26.861883 34015 parameters.go:238] Clock(Monotonic): error: 313 ns, adjusted frequency from 2299773138 Hz to 2299775353 Hz D0301 09:41:26.862116 34015 parameters.go:238] Clock(Realtime): error: -212 ns, adjusted frequency from 2299773960 Hz to 2299774185 Hz D0301 09:41:26.862229 34015 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9078658427093586 monotonicBaseRef:66000610326 monotonicFrequency:2299775353 realtimeReady:1 realtimeBaseCycles:9078658427675427 realtimeBaseRef:1583055686862110217 realtimeFrequency:2299774185} I0301 09:41:26.862277 34015 loader.go:566] Received external signal 23, mode: Process D0301 09:41:26.862559 34015 task_signals.go:446] [ 1] Notified of signal 23 D0301 09:41:26.862687 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:41:26.862810 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:41:26.863066 34015 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0301 09:41:26.863181 34015 task_signals.go:220] [ 1] Signal 23: delivering to handler D0301 09:41:27.861524 34015 parameters.go:238] Clock(Monotonic): error: -224 ns, adjusted frequency from 2299775353 Hz to 2299774212 Hz D0301 09:41:27.861767 34015 parameters.go:238] Clock(Realtime): error: 218 ns, adjusted frequency from 2299774185 Hz to 2299775118 Hz D0301 09:41:27.861858 34015 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9078660726055003 monotonicBaseRef:67000256406 monotonicFrequency:2299774212 realtimeReady:1 realtimeBaseCycles:9078660726649943 realtimeBaseRef:1583055687861762500 realtimeFrequency:2299775118} D0301 09:41:28.861512 34015 parameters.go:238] Clock(Monotonic): error: 392 ns, adjusted frequency from 2299774212 Hz to 2299775498 Hz D0301 09:41:28.861752 34015 parameters.go:238] Clock(Realtime): error: 174 ns, adjusted frequency from 2299775118 Hz to 2299775034 Hz I0301 09:41:28.861765 34015 loader.go:566] Received external signal 23, mode: Process D0301 09:41:28.861894 34015 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9078663025807453 monotonicBaseRef:68000246943 monotonicFrequency:2299775498 realtimeReady:1 realtimeBaseCycles:9078663026387580 realtimeBaseRef:1583055688861746202 realtimeFrequency:2299775034} D0301 09:41:28.862000 34015 task_signals.go:446] [ 1] Notified of signal 23 D0301 09:41:28.862120 34015 task_block.go:223] [ 1] Interrupt queued I0301 09:41:28.862247 34015 loader.go:566] Received external signal 23, mode: Process D0301 09:41:28.862309 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:41:28.862359 34015 task_signals.go:434] [ 1] Discarding duplicate signal 23 D0301 09:41:28.862626 34015 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0301 09:41:28.862767 34015 task_signals.go:220] [ 1] Signal 23: delivering to handler D0301 09:41:29.862005 34015 parameters.go:238] Clock(Monotonic): error: 4 ns, adjusted frequency from 2299775498 Hz to 2299774563 Hz D0301 09:41:29.862286 34015 parameters.go:238] Clock(Realtime): error: 383 ns, adjusted frequency from 2299775034 Hz to 2299775411 Hz D0301 09:41:29.862414 34015 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9078665326716080 monotonicBaseRef:69000739655 monotonicFrequency:2299774563 realtimeReady:1 realtimeBaseCycles:9078665327386812 realtimeBaseRef:1583055689862278514 realtimeFrequency:2299775411} D0301 09:41:30.864531 34015 parameters.go:238] Clock(Monotonic): error: -328 ns, adjusted frequency from 2299774563 Hz to 2299773870 Hz D0301 09:41:30.864775 34015 parameters.go:238] Clock(Realtime): error: -264 ns, adjusted frequency from 2299775411 Hz to 2299773998 Hz D0301 09:41:30.864908 34015 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9078667632218799 monotonicBaseRef:70003230401 monotonicFrequency:2299773870 realtimeReady:1 realtimeBaseCycles:9078667632887390 realtimeBaseRef:1583055690864767960 realtimeFrequency:2299773998} D0301 09:41:31.862207 34015 parameters.go:238] Clock(Monotonic): error: 330 ns, adjusted frequency from 2299773870 Hz to 2299775269 Hz D0301 09:41:31.862428 34015 parameters.go:238] Clock(Realtime): error: -11 ns, adjusted frequency from 2299773998 Hz to 2299774602 Hz D0301 09:41:31.862546 34015 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9078669926729502 monotonicBaseRef:71000941842 monotonicFrequency:2299775269 realtimeReady:1 realtimeBaseCycles:9078669927266402 realtimeBaseRef:1583055691862422083 realtimeFrequency:2299774602} D0301 09:41:32.861466 34015 parameters.go:238] Clock(Monotonic): error: 121 ns, adjusted frequency from 2299775269 Hz to 2299774980 Hz D0301 09:41:32.861735 34015 parameters.go:238] Clock(Realtime): error: -132 ns, adjusted frequency from 2299774602 Hz to 2299774368 Hz D0301 09:41:32.861836 34015 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9078672224799381 monotonicBaseRef:72000200295 monotonicFrequency:2299774980 realtimeReady:1 realtimeBaseCycles:9078672225445473 realtimeBaseRef:1583055692861728305 realtimeFrequency:2299774368} D0301 09:41:33.862939 34015 parameters.go:238] Clock(Monotonic): error: 126 ns, adjusted frequency from 2299774980 Hz to 2299774887 Hz D0301 09:41:33.863238 34015 parameters.go:238] Clock(Realtime): error: 130 ns, adjusted frequency from 2299774368 Hz to 2299774935 Hz D0301 09:41:33.863413 34015 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9078674527962473 monotonicBaseRef:73001673531 monotonicFrequency:2299774887 realtimeReady:1 realtimeBaseCycles:9078674528674681 realtimeBaseRef:1583055693863230556 realtimeFrequency:2299774935} D0301 09:41:34.861513 34015 parameters.go:238] Clock(Monotonic): error: -323 ns, adjusted frequency from 2299774887 Hz to 2299773832 Hz D0301 09:41:34.861671 34015 parameters.go:238] Clock(Realtime): error: -71 ns, adjusted frequency from 2299774935 Hz to 2299774461 Hz D0301 09:41:34.861846 34015 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9078676824462950 monotonicBaseRef:74000249735 monotonicFrequency:2299773832 realtimeReady:1 realtimeBaseCycles:9078676824849848 realtimeBaseRef:1583055694861665286 realtimeFrequency:2299774461} D0301 09:41:35.861460 34015 parameters.go:238] Clock(Monotonic): error: -273 ns, adjusted frequency from 2299773832 Hz to 2299774116 Hz D0301 09:41:35.861658 34015 parameters.go:238] Clock(Realtime): error: -141 ns, adjusted frequency from 2299774461 Hz to 2299774260 Hz D0301 09:41:35.861795 34015 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9078679124108982 monotonicBaseRef:75000194164 monotonicFrequency:2299774116 realtimeReady:1 realtimeBaseCycles:9078679124594623 realtimeBaseRef:1583055695861652377 realtimeFrequency:2299774260} I0301 09:41:35.896139 34015 loader.go:566] Received external signal 23, mode: Process D0301 09:41:35.896428 34015 task_signals.go:446] [ 1] Notified of signal 23 D0301 09:41:35.896619 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:41:35.896737 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:41:35.896926 34015 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0301 09:41:35.897077 34015 task_signals.go:220] [ 1] Signal 23: delivering to handler D0301 09:41:36.861940 34015 parameters.go:238] Clock(Monotonic): error: 281 ns, adjusted frequency from 2299774116 Hz to 2299775130 Hz I0301 09:41:36.862109 34015 loader.go:566] Received external signal 23, mode: Process D0301 09:41:36.862166 34015 parameters.go:238] Clock(Realtime): error: 294 ns, adjusted frequency from 2299774260 Hz to 2299775286 Hz D0301 09:41:36.862342 34015 task_signals.go:446] [ 1] Notified of signal 23 D0301 09:41:36.862328 34015 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9078681424938603 monotonicBaseRef:76000653124 monotonicFrequency:2299775130 realtimeReady:1 realtimeBaseCycles:9078681425528700 realtimeBaseRef:1583055696862156694 realtimeFrequency:2299775286} D0301 09:41:36.862466 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:41:36.862633 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:41:36.862970 34015 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0301 09:41:36.863144 34015 task_signals.go:220] [ 1] Signal 23: delivering to handler D0301 09:41:37.861564 34015 parameters.go:238] Clock(Monotonic): error: 13 ns, adjusted frequency from 2299775130 Hz to 2299774627 Hz I0301 09:41:37.861711 34015 loader.go:566] Received external signal 23, mode: Process D0301 09:41:37.861828 34015 parameters.go:238] Clock(Realtime): error: -460 ns, adjusted frequency from 2299775286 Hz to 2299773618 Hz D0301 09:41:37.861996 34015 task_signals.go:446] [ 1] Notified of signal 23 D0301 09:41:37.862001 34015 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9078683723898279 monotonicBaseRef:77000298544 monotonicFrequency:2299774627 realtimeReady:1 realtimeBaseCycles:9078683724531372 realtimeBaseRef:1583055697861820742 realtimeFrequency:2299773618} D0301 09:41:37.862114 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:41:37.862161 34015 task_block.go:223] [ 1] Interrupt queued I0301 09:41:37.862307 34015 loader.go:566] Received external signal 23, mode: Process D0301 09:41:37.862378 34015 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0301 09:41:37.862476 34015 task_signals.go:220] [ 1] Signal 23: delivering to handler D0301 09:41:37.862457 34015 task_signals.go:446] [ 1] Notified of signal 23 D0301 09:41:37.862597 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:41:37.862856 34015 task_block.go:223] [ 2] Interrupt queued D0301 09:41:37.862946 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:41:37.862967 34015 task_block.go:223] [ 2] Interrupt queued D0301 09:41:37.863174 34015 task_signals.go:176] [ 2] Not restarting syscall 202 after errno 516: interrupted by signal 23 D0301 09:41:37.863288 34015 task_signals.go:220] [ 2] Signal 23: delivering to handler D0301 09:41:38.861547 34015 parameters.go:238] Clock(Monotonic): error: -134 ns, adjusted frequency from 2299774627 Hz to 2299774403 Hz I0301 09:41:38.861624 34015 loader.go:566] Received external signal 23, mode: Process D0301 09:41:38.861820 34015 parameters.go:238] Clock(Realtime): error: 388 ns, adjusted frequency from 2299773618 Hz to 2299775517 Hz D0301 09:41:38.861882 34015 task_signals.go:446] [ 1] Notified of signal 23 D0301 09:41:38.861986 34015 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9078686023637503 monotonicBaseRef:78000283149 monotonicFrequency:2299774403 realtimeReady:1 realtimeBaseCycles:9078686024286506 realtimeBaseRef:1583055698861812704 realtimeFrequency:2299775517} D0301 09:41:38.862040 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:41:38.862105 34015 task_block.go:223] [ 1] Interrupt queued I0301 09:41:38.862284 34015 loader.go:566] Received external signal 23, mode: Process D0301 09:41:38.862456 34015 task_signals.go:434] [ 1] Discarding duplicate signal 23 D0301 09:41:38.862590 34015 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0301 09:41:38.862739 34015 task_signals.go:220] [ 1] Signal 23: delivering to handler I0301 09:41:38.897068 34015 loader.go:566] Received external signal 23, mode: Process D0301 09:41:38.897320 34015 task_signals.go:446] [ 1] Notified of signal 23 D0301 09:41:38.897450 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:41:38.897498 34015 task_block.go:223] [ 1] Interrupt queued I0301 09:41:38.897616 34015 loader.go:566] Received external signal 23, mode: Process D0301 09:41:38.897710 34015 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0301 09:41:38.897839 34015 task_signals.go:446] [ 1] Notified of signal 23 D0301 09:41:38.897858 34015 task_signals.go:220] [ 1] Signal 23: delivering to handler D0301 09:41:38.897986 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:41:38.899184 34015 task_block.go:223] [ 2] Interrupt queued D0301 09:41:38.899294 34015 task_block.go:223] [ 2] Interrupt queued D0301 09:41:38.899333 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:41:38.899517 34015 task_signals.go:176] [ 2] Not restarting syscall 202 after errno 516: interrupted by signal 23 D0301 09:41:38.899720 34015 task_signals.go:220] [ 2] Signal 23: delivering to handler D0301 09:41:39.861574 34015 parameters.go:238] Clock(Monotonic): error: -266 ns, adjusted frequency from 2299774403 Hz to 2299773989 Hz D0301 09:41:39.861869 34015 parameters.go:238] Clock(Realtime): error: 46 ns, adjusted frequency from 2299775517 Hz to 2299774754 Hz D0301 09:41:39.862001 34015 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9078688323478825 monotonicBaseRef:79000312247 monotonicFrequency:2299773989 realtimeReady:1 realtimeBaseCycles:9078688324172937 realtimeBaseRef:1583055699861860932 realtimeFrequency:2299774754} D0301 09:41:40.861598 34015 parameters.go:238] Clock(Monotonic): error: 460 ns, adjusted frequency from 2299773989 Hz to 2299775810 Hz D0301 09:41:40.861854 34015 parameters.go:238] Clock(Realtime): error: -246 ns, adjusted frequency from 2299774754 Hz to 2299774012 Hz D0301 09:41:40.861939 34015 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9078690623295828 monotonicBaseRef:80000330950 monotonicFrequency:2299775810 realtimeReady:1 realtimeBaseCycles:9078690623918508 realtimeBaseRef:1583055700861848242 realtimeFrequency:2299774012} D0301 09:41:41.861580 34015 parameters.go:238] Clock(Monotonic): error: -78 ns, adjusted frequency from 2299775810 Hz to 2299774466 Hz D0301 09:41:41.861858 34015 parameters.go:238] Clock(Realtime): error: -25 ns, adjusted frequency from 2299774012 Hz to 2299774606 Hz D0301 09:41:41.862084 34015 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9078692923013924 monotonicBaseRef:81000305854 monotonicFrequency:2299774466 realtimeReady:1 realtimeBaseCycles:9078692923684982 realtimeBaseRef:1583055701861844964 realtimeFrequency:2299774606} D0301 09:41:42.861514 34015 parameters.go:238] Clock(Monotonic): error: -91 ns, adjusted frequency from 2299774466 Hz to 2299774327 Hz D0301 09:41:42.861737 34015 parameters.go:238] Clock(Realtime): error: -58 ns, adjusted frequency from 2299774606 Hz to 2299774471 Hz I0301 09:41:42.861770 34015 loader.go:566] Received external signal 23, mode: Process D0301 09:41:42.861864 34015 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9078695222659522 monotonicBaseRef:82000249818 monotonicFrequency:2299774327 realtimeReady:1 realtimeBaseCycles:9078695223198977 realtimeBaseRef:1583055702861731643 realtimeFrequency:2299774471} D0301 09:41:42.862078 34015 task_signals.go:446] [ 1] Notified of signal 23 D0301 09:41:42.862242 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:41:42.862363 34015 task_block.go:223] [ 1] Interrupt queued I0301 09:41:42.862423 34015 loader.go:566] Received external signal 23, mode: Process D0301 09:41:42.862597 34015 task_signals.go:446] [ 1] Notified of signal 23 D0301 09:41:42.862574 34015 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0301 09:41:42.862753 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:41:42.862916 34015 task_signals.go:220] [ 1] Signal 23: delivering to handler D0301 09:41:42.863401 34015 task_block.go:223] [ 2] Interrupt queued D0301 09:41:42.863469 34015 task_block.go:223] [ 2] Interrupt queued D0301 09:41:42.863511 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:41:42.863745 34015 task_signals.go:176] [ 2] Not restarting syscall 202 after errno 516: interrupted by signal 23 D0301 09:41:42.863975 34015 task_signals.go:220] [ 2] Signal 23: delivering to handler I0301 09:41:43.585600 42558 main.go:305] *************************** I0301 09:41:43.585747 42558 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 /syzkaller495701230] I0301 09:41:43.586399 42558 main.go:307] Version release-20200219.0-72-gce4d1e45bb88 I0301 09:41:43.586495 42558 main.go:308] PID: 42558 I0301 09:41:43.586647 42558 main.go:309] UID: 0, GID: 0 I0301 09:41:43.586754 42558 main.go:310] Configuration: I0301 09:41:43.586873 42558 main.go:311] RootDir: /syzkaller/managers/ptrace-proxy-sandbox-race/workdir/gvisor_root I0301 09:41:43.587003 42558 main.go:312] Platform: ptrace I0301 09:41:43.587104 42558 main.go:313] FileAccess: shared, overlay: false I0301 09:41:43.587231 42558 main.go:314] Network: sandbox, logging: false I0301 09:41:43.587369 42558 main.go:315] Strace: false, max size: 1024, syscalls: [] I0301 09:41:43.587466 42558 main.go:316] *************************** W0301 09:41:43.587548 42558 main.go:321] Block the TERM signal. This is only safe in tests! D0301 09:41:43.588057 42558 container.go:159] Load container "/syzkaller/managers/ptrace-proxy-sandbox-race/workdir/gvisor_root" "ci-gvisor-ptrace-proxy-sandbox-race-1" D0301 09:41:43.596299 42558 container.go:592] Signal container "ci-gvisor-ptrace-proxy-sandbox-race-1": signal 0 D0301 09:41:43.596463 42558 sandbox.go:806] Signal sandbox "ci-gvisor-ptrace-proxy-sandbox-race-1" D0301 09:41:43.596552 42558 sandbox.go:318] Connecting to sandbox "ci-gvisor-ptrace-proxy-sandbox-race-1" D0301 09:41:43.596972 42558 urpc.go:534] urpc: successfully marshalled 117 bytes. I0301 09:41:43.597059 34015 loader.go:566] Received external signal 23, mode: Process D0301 09:41:43.597252 34015 task_signals.go:446] [ 1] Notified of signal 23 D0301 09:41:43.597366 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:41:43.597315 34015 urpc.go:577] urpc: unmarshal success. I0301 09:41:43.597477 34015 loader.go:566] Received external signal 23, mode: Process D0301 09:41:43.597487 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:41:43.597632 34015 task_signals.go:434] [ 1] Discarding duplicate signal 23 D0301 09:41:43.597644 34015 controller.go:501] containerManager.Signal &{CID:ci-gvisor-ptrace-proxy-sandbox-race-1 Signo:0 PID:0 Mode:Process} D0301 09:41:43.597851 34015 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0301 09:41:43.597973 34015 task_signals.go:220] [ 1] Signal 23: delivering to handler D0301 09:41:43.597993 34015 urpc.go:534] urpc: successfully marshalled 37 bytes. D0301 09:41:43.598199 42558 urpc.go:577] urpc: unmarshal success. D0301 09:41:43.598359 42558 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 /syzkaller495701230 D0301 09:41:43.598466 42558 exec.go:121] Exec capablities: &{PermittedCaps:274877898751 InheritableCaps:274877898751 EffectiveCaps:274877898751 BoundingCaps:274877898751 AmbientCaps:0} D0301 09:41:43.598583 42558 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 /syzkaller495701230 D0301 09:41:43.598708 42558 sandbox.go:283] Executing new process in container "ci-gvisor-ptrace-proxy-sandbox-race-1" in sandbox "ci-gvisor-ptrace-proxy-sandbox-race-1" D0301 09:41:43.598807 42558 sandbox.go:318] Connecting to sandbox "ci-gvisor-ptrace-proxy-sandbox-race-1" D0301 09:41:43.599550 42558 urpc.go:534] urpc: successfully marshalled 634 bytes. D0301 09:41:43.600048 34015 urpc.go:577] urpc: unmarshal success. D0301 09:41:43.602304 34015 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 /syzkaller495701230 D0301 09:41:43.602540 34015 transport_flipcall.go:127] send [channel @0xc0004e2000] Twalkgetattr{FID: 1, NewFID: 5, Names: [etc]} D0301 09:41:43.603902 34015 transport_flipcall.go:234] recv [channel @0xc0004e2000] Rlerror{Error: 2} D0301 09:41:43.604130 34015 transport_flipcall.go:127] send [channel @0xc0004e2000] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.etc, Size: 1} D0301 09:41:43.604711 34015 transport_flipcall.go:234] recv [channel @0xc0004e2000] Rlerror{Error: 95} I0301 09:41:43.605108 34015 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 /syzkaller495701230] D0301 09:41:43.606754 34015 transport_flipcall.go:127] send [channel @0xc0004e2000] Twalkgetattr{FID: 1, NewFID: 5, Names: [syz-execprog]} D0301 09:41:43.607838 34015 transport_flipcall.go:234] recv [channel @0xc0004e2000] 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: 1583055621, NanoSec: 995159616}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}, QIDs: [QID{Type: 0, Version: 0, Path: 16515130}]} D0301 09:41:43.608170 34015 transport_flipcall.go:127] send [channel @0xc0004e2000] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syz-execprog, Size: 1} D0301 09:41:43.608705 34015 transport_flipcall.go:234] recv [channel @0xc0004e2000] Rlerror{Error: 95} D0301 09:41:43.609050 34015 transport_flipcall.go:127] send [channel @0xc0004e2000] Tgetattr{FID: 5, AttrMask: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime INo Size Blocks BTime Gen DataVersion}} D0301 09:41:43.610588 34015 transport_flipcall.go:234] recv [channel @0xc0004e2000] Rgetattr{Valid: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime Size Blocks}, QID: QID{Type: 0, Version: 0, Path: 16515130}, 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: 1583055621, NanoSec: 995159616}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}} D0301 09:41:43.610797 34015 transport_flipcall.go:127] send [channel @0xc0004e2000] Twalk{FID: 5, NewFID: 6, Names: []} D0301 09:41:43.611509 34015 transport_flipcall.go:234] recv [channel @0xc0004e2000] Rwalk{QIDs: []} D0301 09:41:43.611676 34015 transport_flipcall.go:127] send [channel @0xc0004e2000] Tlopen{FID: 6, Flags: ReadOnly} D0301 09:41:43.613309 34015 transport_flipcall.go:234] recv [channel @0xc0004e2000] Rlopen{QID: QID{Type: 0, Version: 0, Path: 16515130}, IoUnit: 0, File: &{{36}}} I0301 09:41:43.613351 34015 loader.go:566] Received external signal 23, mode: Process D0301 09:41:43.618467 34015 syscalls.go:266] Allocating stack with size of 8388608 bytes D0301 09:41:43.620331 34015 loader.go:832] updated processes: map[{ci-gvisor-ptrace-proxy-sandbox-race-1 0}:0xc0003f6aa0 {ci-gvisor-ptrace-proxy-sandbox-race-1 23}:0xc0004b1500] D0301 09:41:43.620798 34015 urpc.go:534] urpc: successfully marshalled 37 bytes. D0301 09:41:43.620991 34015 task_signals.go:446] [ 1] Notified of signal 23 D0301 09:41:43.621005 42558 urpc.go:577] urpc: unmarshal success. D0301 09:41:43.621113 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:41:43.621210 42558 container.go:580] Wait on PID 23 in container "ci-gvisor-ptrace-proxy-sandbox-race-1" I0301 09:41:43.621218 34015 loader.go:566] Received external signal 23, mode: Process D0301 09:41:43.621362 34015 task_signals.go:434] [ 1] Discarding duplicate signal 23 D0301 09:41:43.621230 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:41:43.621352 42558 sandbox.go:761] Waiting for PID 23 in sandbox "ci-gvisor-ptrace-proxy-sandbox-race-1" D0301 09:41:43.621559 42558 sandbox.go:318] Connecting to sandbox "ci-gvisor-ptrace-proxy-sandbox-race-1" D0301 09:41:43.621557 34015 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0301 09:41:43.621670 34015 task_signals.go:220] [ 1] Signal 23: delivering to handler D0301 09:41:43.621922 42558 urpc.go:534] urpc: successfully marshalled 100 bytes. D0301 09:41:43.622519 34015 urpc.go:577] urpc: unmarshal success. D0301 09:41:43.622863 34015 controller.go:443] containerManager.Wait D0301 09:41:43.624580 34015 task_block.go:223] [ 23] Interrupt queued I0301 09:41:43.641614 34015 loader.go:566] Received external signal 23, mode: Process D0301 09:41:43.641803 34015 task_signals.go:446] [ 1] Notified of signal 23 D0301 09:41:43.641895 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:41:43.642019 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:41:43.642156 34015 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0301 09:41:43.642310 34015 task_signals.go:220] [ 1] Signal 23: delivering to handler I0301 09:41:43.661792 34015 loader.go:566] Received external signal 23, mode: Process D0301 09:41:43.662060 34015 task_signals.go:446] [ 1] Notified of signal 23 D0301 09:41:43.662269 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:41:43.662508 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:41:43.662764 34015 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0301 09:41:43.662934 34015 task_signals.go:220] [ 1] Signal 23: delivering to handler I0301 09:41:43.681592 34015 loader.go:566] Received external signal 23, mode: Process D0301 09:41:43.681883 34015 task_signals.go:446] [ 1] Notified of signal 23 D0301 09:41:43.682048 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:41:43.682176 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:41:43.682513 34015 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0301 09:41:43.682679 34015 task_signals.go:220] [ 1] Signal 23: delivering to handler I0301 09:41:43.721620 34015 loader.go:566] Received external signal 23, mode: Process D0301 09:41:43.721840 34015 task_signals.go:446] [ 1] Notified of signal 23 D0301 09:41:43.721969 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:41:43.722106 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:41:43.722317 34015 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0301 09:41:43.722443 34015 task_signals.go:220] [ 1] Signal 23: delivering to handler I0301 09:41:43.772593 34015 loader.go:566] Received external signal 23, mode: Process D0301 09:41:43.772868 34015 task_signals.go:446] [ 1] Notified of signal 23 D0301 09:41:43.773067 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:41:43.773135 34015 task_block.go:223] [ 1] Interrupt queued I0301 09:41:43.773228 34015 loader.go:566] Received external signal 23, mode: Process D0301 09:41:43.773383 34015 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0301 09:41:43.773422 34015 task_signals.go:446] [ 1] Notified of signal 23 D0301 09:41:43.773559 34015 task_signals.go:220] [ 1] Signal 23: delivering to handler D0301 09:41:43.773567 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:41:43.774090 34015 task_block.go:223] [ 2] Interrupt queued D0301 09:41:43.774119 34015 task_block.go:223] [ 2] Interrupt queued D0301 09:41:43.774230 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:41:43.774502 34015 task_signals.go:176] [ 2] Not restarting syscall 202 after errno 516: interrupted by signal 23 D0301 09:41:43.774676 34015 task_signals.go:220] [ 2] Signal 23: delivering to handler I0301 09:41:43.811484 34015 loader.go:566] Received external signal 23, mode: Process D0301 09:41:43.811708 34015 task_signals.go:446] [ 1] Notified of signal 23 D0301 09:41:43.811841 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:41:43.811920 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:41:43.812171 34015 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0301 09:41:43.812304 34015 task_signals.go:220] [ 1] Signal 23: delivering to handler I0301 09:41:43.831802 34015 loader.go:566] Received external signal 23, mode: Process D0301 09:41:43.832084 34015 task_signals.go:446] [ 1] Notified of signal 23 D0301 09:41:43.832208 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:41:43.832249 34015 task_block.go:223] [ 1] Interrupt queued I0301 09:41:43.832346 34015 loader.go:566] Received external signal 23, mode: Process D0301 09:41:43.832455 34015 task_signals.go:446] [ 1] Notified of signal 23 D0301 09:41:43.832440 34015 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0301 09:41:43.832573 34015 task_signals.go:220] [ 1] Signal 23: delivering to handler D0301 09:41:43.832549 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:41:43.833046 34015 task_block.go:223] [ 2] Interrupt queued D0301 09:41:43.833175 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:41:43.833165 34015 task_block.go:223] [ 2] Interrupt queued D0301 09:41:43.833369 34015 task_signals.go:176] [ 2] Not restarting syscall 202 after errno 516: interrupted by signal 23 D0301 09:41:43.833523 34015 task_signals.go:220] [ 2] Signal 23: delivering to handler I0301 09:41:43.834374 34015 loader.go:566] Received external signal 23, mode: Process D0301 09:41:43.834738 34015 task_signals.go:446] [ 1] Notified of signal 23 D0301 09:41:43.834850 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:41:43.834866 34015 task_block.go:223] [ 1] Interrupt queued I0301 09:41:43.835048 34015 loader.go:566] Received external signal 23, mode: Process D0301 09:41:43.835125 34015 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0301 09:41:43.835232 34015 task_signals.go:446] [ 1] Notified of signal 23 D0301 09:41:43.835280 34015 task_signals.go:220] [ 1] Signal 23: delivering to handler D0301 09:41:43.835377 34015 task_block.go:223] [ 1] Interrupt queued I0301 09:41:43.836150 34015 loader.go:566] Received external signal 23, mode: Process D0301 09:41:43.835970 34015 task_block.go:223] [ 2] Interrupt queued D0301 09:41:43.836683 34015 task_signals.go:452] [ 2] Notified of group signal 23 D0301 09:41:43.836477 34015 task_signals.go:220] [ 2] Signal 23: delivering to handler D0301 09:41:43.836829 34015 task_block.go:223] [ 2] Interrupt queued I0301 09:41:43.837986 34015 loader.go:566] Received external signal 23, mode: Process D0301 09:41:43.838084 34015 task_signals.go:434] [ 1] Discarding duplicate signal 23 D0301 09:41:43.838319 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:41:43.838491 34015 task_signals.go:220] [ 1] Signal 23: delivering to handler I0301 09:41:43.853739 34015 loader.go:566] Received external signal 23, mode: Process D0301 09:41:43.853923 34015 task_signals.go:446] [ 1] Notified of signal 23 D0301 09:41:43.854059 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:41:43.854121 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:41:43.854336 34015 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0301 09:41:43.854467 34015 task_signals.go:220] [ 1] Signal 23: delivering to handler D0301 09:41:43.861933 34015 parameters.go:238] Clock(Monotonic): error: -28 ns, adjusted frequency from 2299774327 Hz to 2299774483 Hz D0301 09:41:43.862171 34015 parameters.go:238] Clock(Realtime): error: 46 ns, adjusted frequency from 2299774471 Hz to 2299774664 Hz D0301 09:41:43.862178 34015 task_block.go:223] [ 24] Interrupt queued D0301 09:41:43.862326 34015 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9078697523406279 monotonicBaseRef:83000672655 monotonicFrequency:2299774483 realtimeReady:1 realtimeBaseCycles:9078697523972697 realtimeBaseRef:1583055703862166141 realtimeFrequency:2299774664} D0301 09:41:43.863321 34015 task_block.go:223] [ 25] Interrupt queued D0301 09:41:43.866995 34015 task_block.go:223] [ 26] Interrupt queued D0301 09:41:43.872270 34015 task_block.go:223] [ 27] Interrupt queued D0301 09:41:43.899138 34015 task_block.go:223] [ 28] Interrupt queued D0301 09:41:43.905339 34015 task_block.go:223] [ 29] Interrupt queued D0301 09:41:43.922561 34015 urpc.go:577] urpc: unmarshal success. D0301 09:41:43.922885 34015 controller.go:501] containerManager.Signal &{CID:ci-gvisor-ptrace-proxy-sandbox-race-1 Signo:0 PID:0 Mode:Process} D0301 09:41:43.923327 34015 urpc.go:534] urpc: successfully marshalled 37 bytes. D0301 09:41:43.943431 34015 task_block.go:223] [ 30] Interrupt queued D0301 09:41:43.961262 34015 transport_flipcall.go:127] send [channel @0xc0004e2000] Twalkgetattr{FID: 1, NewFID: 7, Names: [etc]} D0301 09:41:43.961951 34015 transport_flipcall.go:234] recv [channel @0xc0004e2000] Rlerror{Error: 2} D0301 09:41:43.962225 34015 transport_flipcall.go:127] send [channel @0xc0004e2000] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.etc, Size: 1} D0301 09:41:43.962963 34015 transport_flipcall.go:234] recv [channel @0xc0004e2000] Rlerror{Error: 95} I0301 09:41:43.972047 34015 loader.go:566] Received external signal 23, mode: Process D0301 09:41:43.972255 34015 task_signals.go:446] [ 1] Notified of signal 23 D0301 09:41:43.972382 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:41:43.972511 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:41:43.972737 34015 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0301 09:41:43.972921 34015 task_signals.go:220] [ 1] Signal 23: delivering to handler D0301 09:41:43.988524 34015 task_block.go:223] [ 31] Interrupt queued D0301 09:41:43.999183 34015 task_block.go:223] [ 32] Interrupt queued D0301 09:41:44.008157 34015 task_block.go:223] [ 33] Interrupt queued D0301 09:41:44.017079 34015 task_block.go:223] [ 34] Interrupt queued D0301 09:41:44.024860 34015 task_block.go:223] [ 35] Interrupt queued D0301 09:41:44.062488 34015 task_block.go:223] [ 36] Interrupt queued D0301 09:41:44.075119 34015 task_block.go:223] [ 37] Interrupt queued I0301 09:41:44.101701 34015 loader.go:566] Received external signal 23, mode: Process D0301 09:41:44.101952 34015 task_signals.go:446] [ 1] Notified of signal 23 D0301 09:41:44.102079 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:41:44.102228 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:41:44.102446 34015 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0301 09:41:44.102556 34015 task_signals.go:220] [ 1] Signal 23: delivering to handler I0301 09:41:44.112970 34015 loader.go:566] Received external signal 23, mode: Process D0301 09:41:44.113245 34015 task_signals.go:446] [ 1] Notified of signal 23 D0301 09:41:44.113483 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:41:44.113522 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:41:44.113785 34015 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 I0301 09:41:44.114428 34015 loader.go:566] Received external signal 23, mode: Process D0301 09:41:44.114785 34015 task_signals.go:220] [ 1] Signal 23: delivering to handler D0301 09:41:44.114741 34015 task_signals.go:446] [ 1] Notified of signal 23 D0301 09:41:44.116036 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:41:44.116025 34015 task_block.go:223] [ 38] Interrupt queued I0301 09:41:44.117699 34015 loader.go:566] Received external signal 23, mode: Process D0301 09:41:44.117756 34015 task_block.go:223] [ 2] Interrupt queued D0301 09:41:44.117817 34015 task_block.go:223] [ 2] Interrupt queued D0301 09:41:44.117932 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:41:44.118077 34015 task_signals.go:176] [ 2] Not restarting syscall 202 after errno 516: interrupted by signal 23 D0301 09:41:44.118181 34015 task_signals.go:452] [ 2] Notified of group signal 23 D0301 09:41:44.118333 34015 task_block.go:223] [ 2] Interrupt queued D0301 09:41:44.118230 34015 task_signals.go:220] [ 2] Signal 23: delivering to handler I0301 09:41:44.118496 34015 loader.go:566] Received external signal 23, mode: Process D0301 09:41:44.118697 34015 task_signals.go:434] [ 1] Discarding duplicate signal 23 D0301 09:41:44.118841 34015 task_block.go:223] [ 3] Interrupt queued D0301 09:41:44.118935 34015 task_block.go:223] [ 3] Interrupt queued D0301 09:41:44.119030 34015 task_block.go:223] [ 2] Interrupt queued D0301 09:41:44.119249 34015 task_signals.go:176] [ 3] Not restarting syscall 202 after errno 516: interrupted by signal 23 D0301 09:41:44.119386 34015 task_signals.go:220] [ 3] Signal 23: delivering to handler D0301 09:41:44.134236 34015 transport_flipcall.go:127] send [channel @0xc0004e2000] Twalkgetattr{FID: 1, NewFID: 7, Names: [syzkaller495701230]} D0301 09:41:44.135614 34015 transport_flipcall.go:234] recv [channel @0xc0004e2000] 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: 261, BlockSize: 4096, Blocks: 8, ATime: {Sec: 1583055703, NanoSec: 543150330}, MTime: {Sec: 1583055703, NanoSec: 543150330}, CTime: {Sec: 1583055703, NanoSec: 543150330}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}, QIDs: [QID{Type: 0, Version: 0, Path: 16515134}]} D0301 09:41:44.136004 34015 transport_flipcall.go:127] send [channel @0xc0004e2000] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzkaller495701230, Size: 1} D0301 09:41:44.136681 34015 transport_flipcall.go:234] recv [channel @0xc0004e2000] Rlerror{Error: 95} D0301 09:41:44.137020 34015 task_block.go:223] [ 39] Interrupt queued D0301 09:41:44.137159 34015 transport_flipcall.go:127] send [channel @0xc0004e2000] Tgetattr{FID: 7, AttrMask: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime INo Size Blocks BTime Gen DataVersion}} D0301 09:41:44.138620 34015 transport_flipcall.go:234] recv [channel @0xc0004e2000] Rgetattr{Valid: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime Size Blocks}, QID: QID{Type: 0, Version: 0, Path: 16515134}, Attr: Attr{Mode: 0o100600, UID: 0, GID: 0, NLink: 1, RDev: 0, Size: 261, BlockSize: 4096, Blocks: 8, ATime: {Sec: 1583055703, NanoSec: 543150330}, MTime: {Sec: 1583055703, NanoSec: 543150330}, CTime: {Sec: 1583055703, NanoSec: 543150330}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}} D0301 09:41:44.138861 34015 transport_flipcall.go:127] send [channel @0xc0004e2000] Twalk{FID: 7, NewFID: 8, Names: []} D0301 09:41:44.139835 34015 transport_flipcall.go:234] recv [channel @0xc0004e2000] Rwalk{QIDs: []} D0301 09:41:44.140013 34015 transport_flipcall.go:127] send [channel @0xc0004e2000] Tlopen{FID: 8, Flags: ReadOnly} D0301 09:41:44.141121 34015 transport_flipcall.go:234] recv [channel @0xc0004e2000] Rlopen{QID: QID{Type: 0, Version: 0, Path: 16515134}, IoUnit: 0, File: &{{30}}} D0301 09:41:44.142996 34015 transport_flipcall.go:127] send [channel @0xc0004e2000] Tgetattr{FID: 8, AttrMask: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime INo Size Blocks BTime Gen DataVersion}} I0301 09:41:44.143391 34015 loader.go:566] Received external signal 23, mode: Process D0301 09:41:44.143676 34015 task_signals.go:446] [ 1] Notified of signal 23 D0301 09:41:44.143833 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:41:44.143906 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:41:44.144145 34015 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0301 09:41:44.144193 34015 transport_flipcall.go:234] recv [channel @0xc0004e2000] Rgetattr{Valid: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime Size Blocks}, QID: QID{Type: 0, Version: 0, Path: 16515134}, Attr: Attr{Mode: 0o100600, UID: 0, GID: 0, NLink: 1, RDev: 0, Size: 261, BlockSize: 4096, Blocks: 8, ATime: {Sec: 1583055703, NanoSec: 543150330}, MTime: {Sec: 1583055703, NanoSec: 543150330}, CTime: {Sec: 1583055703, NanoSec: 543150330}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}} D0301 09:41:44.144288 34015 task_signals.go:220] [ 1] Signal 23: delivering to handler 2020/03/01 09:41:44 parsed 1 programs D0301 09:41:44.161660 34015 task_stop.go:118] [ 35] Entering internal stop (*kernel.vforkStop)(nil) D0301 09:41:44.162672 34015 task_block.go:223] [ 40] Interrupt queued I0301 09:41:44.163541 34015 loader.go:566] Received external signal 23, mode: Process D0301 09:41:44.163808 34015 task_signals.go:446] [ 1] Notified of signal 23 D0301 09:41:44.164060 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:41:44.164113 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:41:44.164360 34015 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0301 09:41:44.164551 34015 task_signals.go:220] [ 1] Signal 23: delivering to handler D0301 09:41:44.169888 34015 transport_flipcall.go:127] send [channel @0xc0004e2000] Twalkgetattr{FID: 1, NewFID: 9, Names: [syz-executor]} D0301 09:41:44.171791 34015 transport_flipcall.go:234] recv [channel @0xc0004e2000] 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: 1583055621, NanoSec: 999160008}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}, QIDs: [QID{Type: 0, Version: 0, Path: 16515150}]} D0301 09:41:44.172171 34015 transport_flipcall.go:127] send [channel @0xc0004e2000] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syz-executor, Size: 1} D0301 09:41:44.173108 34015 transport_flipcall.go:234] recv [channel @0xc0004e2000] Rlerror{Error: 95} I0301 09:41:44.173653 34015 loader.go:566] Received external signal 23, mode: Process D0301 09:41:44.173741 34015 transport_flipcall.go:127] send [channel @0xc0004e2000] Tgetattr{FID: 9, AttrMask: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime INo Size Blocks BTime Gen DataVersion}} D0301 09:41:44.173853 34015 task_signals.go:446] [ 1] Notified of signal 23 D0301 09:41:44.173973 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:41:44.174021 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:41:44.174305 34015 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0301 09:41:44.174461 34015 task_signals.go:220] [ 1] Signal 23: delivering to handler D0301 09:41:44.174760 34015 transport_flipcall.go:234] recv [channel @0xc0004e2000] Rgetattr{Valid: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime Size Blocks}, QID: QID{Type: 0, Version: 0, Path: 16515150}, 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: 1583055621, NanoSec: 999160008}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}} D0301 09:41:44.175038 34015 transport_flipcall.go:127] send [channel @0xc0004e2000] Twalk{FID: 9, NewFID: 10, Names: []} D0301 09:41:44.175903 34015 transport_flipcall.go:234] recv [channel @0xc0004e2000] Rwalk{QIDs: []} D0301 09:41:44.176088 34015 transport_flipcall.go:127] send [channel @0xc0004e2000] Tlopen{FID: 10, Flags: ReadOnly} D0301 09:41:44.177034 34015 transport_flipcall.go:234] recv [channel @0xc0004e2000] Rlopen{QID: QID{Type: 0, Version: 0, Path: 16515150}, IoUnit: 0, File: &{{31}}} D0301 09:41:44.178184 34015 syscalls.go:266] [ 40] Allocating stack with size of 8388608 bytes D0301 09:41:44.179046 34015 task_stop.go:137] [ 35] Leaving internal stop (*kernel.vforkStop)(nil) I0301 09:41:44.204263 34015 loader.go:566] Received external signal 23, mode: Process D0301 09:41:44.204550 34015 task_signals.go:446] [ 1] Notified of signal 23 D0301 09:41:44.204701 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:41:44.204737 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:41:44.204965 34015 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0301 09:41:44.205095 34015 task_signals.go:220] [ 1] Signal 23: delivering to handler D0301 09:41:44.206771 34015 transport_flipcall.go:127] send [channel @0xc0004e2000] Twalkgetattr{FID: 1, NewFID: 11, Names: [etc]} D0301 09:41:44.207511 34015 transport_flipcall.go:234] recv [channel @0xc0004e2000] Rlerror{Error: 2} D0301 09:41:44.207741 34015 transport_flipcall.go:127] send [channel @0xc0004e2000] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.etc, Size: 1} D0301 09:41:44.208319 34015 transport_flipcall.go:234] recv [channel @0xc0004e2000] Rlerror{Error: 95} D0301 09:41:44.209849 34015 task_exit.go:221] [ 40] Transitioning from exit state TaskExitNone to TaskExitInitiated D0301 09:41:44.211167 34015 task_exit.go:221] [ 40] Transitioning from exit state TaskExitInitiated to TaskExitZombie D0301 09:41:44.211346 34015 task_signals.go:446] [ 23] Notified of signal 17 D0301 09:41:44.211532 34015 task_block.go:223] [ 23] Interrupt queued D0301 09:41:44.211734 34015 task_block.go:223] [ 23] Interrupt queued D0301 09:41:44.212009 34015 task_signals.go:179] [ 23] Restarting syscall 202 after errno 512: interrupted by signal 17 D0301 09:41:44.212142 34015 task_signals.go:220] [ 23] Signal 17: delivering to handler D0301 09:41:44.212537 34015 task_exit.go:221] [ 40] Transitioning from exit state TaskExitZombie to TaskExitDead D0301 09:41:44.215145 34015 task_block.go:223] [ 41] Interrupt queued D0301 09:41:44.220903 34015 transport_flipcall.go:127] send [channel @0xc0004e2000] Twalkgetattr{FID: 1, NewFID: 11, Names: [syz-executor]} D0301 09:41:44.222114 34015 transport_flipcall.go:234] recv [channel @0xc0004e2000] 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: 1583055621, NanoSec: 999160008}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}, QIDs: [QID{Type: 0, Version: 0, Path: 16515150}]} D0301 09:41:44.222332 34015 transport_flipcall.go:127] send [channel @0xc0004e2000] Tclunk{FID: 11} D0301 09:41:44.222985 34015 transport_flipcall.go:234] recv [channel @0xc0004e2000] Rclunk{} D0301 09:41:44.223333 34015 transport_flipcall.go:127] send [channel @0xc0004e2000] Tgetattr{FID: 9, AttrMask: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime INo Size Blocks BTime Gen DataVersion}} I0301 09:41:44.224537 34015 loader.go:566] Received external signal 23, mode: Process D0301 09:41:44.224642 34015 transport_flipcall.go:234] recv [channel @0xc0004e2000] Rgetattr{Valid: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime Size Blocks}, QID: QID{Type: 0, Version: 0, Path: 16515150}, 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: 1583055621, NanoSec: 999160008}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}} D0301 09:41:44.224757 34015 task_signals.go:446] [ 1] Notified of signal 23 D0301 09:41:44.224905 34015 task_block.go:223] [ 1] Interrupt queued 2020/03/01 09:41:44 executed programs: 0 D0301 09:41:44.225097 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:41:44.225387 34015 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0301 09:41:44.225613 34015 task_signals.go:220] [ 1] Signal 23: delivering to handler D0301 09:41:44.233736 34015 task_stop.go:118] [ 26] Entering internal stop (*kernel.vforkStop)(nil) I0301 09:41:44.235132 34015 loader.go:566] Received external signal 23, mode: Process D0301 09:41:44.236688 34015 task_signals.go:446] [ 1] Notified of signal 23 D0301 09:41:44.235924 34015 task_block.go:223] [ 42] Interrupt queued D0301 09:41:44.238084 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:41:44.238227 34015 task_block.go:223] [ 1] Interrupt queued I0301 09:41:44.238279 34015 loader.go:566] Received external signal 23, mode: Process D0301 09:41:44.238513 34015 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0301 09:41:44.238594 34015 task_signals.go:446] [ 1] Notified of signal 23 D0301 09:41:44.238681 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:41:44.238648 34015 task_signals.go:220] [ 1] Signal 23: delivering to handler D0301 09:41:44.239104 34015 task_block.go:223] [ 2] Interrupt queued D0301 09:41:44.239225 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:41:44.239229 34015 task_block.go:223] [ 2] Interrupt queued D0301 09:41:44.239499 34015 task_signals.go:176] [ 2] Not restarting syscall 202 after errno 516: interrupted by signal 23 D0301 09:41:44.239639 34015 task_signals.go:220] [ 2] Signal 23: delivering to handler D0301 09:41:44.246839 34015 transport_flipcall.go:127] send [channel @0xc0004e2000] Twalkgetattr{FID: 1, NewFID: 11, Names: [syz-executor]} D0301 09:41:44.248360 34015 transport_flipcall.go:234] recv [channel @0xc0004e2000] 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: 1583055621, NanoSec: 999160008}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}, QIDs: [QID{Type: 0, Version: 0, Path: 16515150}]} D0301 09:41:44.248579 34015 transport_flipcall.go:127] send [channel @0xc0004e2000] Tclunk{FID: 11} D0301 09:41:44.249128 34015 transport_flipcall.go:234] recv [channel @0xc0004e2000] Rclunk{} D0301 09:41:44.249360 34015 transport_flipcall.go:127] send [channel @0xc0004e2000] Tgetattr{FID: 9, AttrMask: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime INo Size Blocks BTime Gen DataVersion}} D0301 09:41:44.250278 34015 transport_flipcall.go:234] recv [channel @0xc0004e2000] Rgetattr{Valid: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime Size Blocks}, QID: QID{Type: 0, Version: 0, Path: 16515150}, 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: 1583055621, NanoSec: 999160008}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}} D0301 09:41:44.251826 34015 syscalls.go:266] [ 42] Allocating stack with size of 8388608 bytes D0301 09:41:44.252823 34015 task_stop.go:137] [ 26] Leaving internal stop (*kernel.vforkStop)(nil) D0301 09:41:44.261671 34015 transport_flipcall.go:127] send [channel @0xc0004e2000] Twalkgetattr{FID: 1, NewFID: 11, Names: [etc]} D0301 09:41:44.262395 34015 transport_flipcall.go:234] recv [channel @0xc0004e2000] Rlerror{Error: 2} D0301 09:41:44.262680 34015 transport_flipcall.go:127] send [channel @0xc0004e2000] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.etc, Size: 1} D0301 09:41:44.263298 34015 transport_flipcall.go:234] recv [channel @0xc0004e2000] Rlerror{Error: 95} I0301 09:41:44.271554 34015 loader.go:566] Received external signal 23, mode: Process D0301 09:41:44.271755 34015 task_signals.go:446] [ 1] Notified of signal 23 D0301 09:41:44.271926 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:41:44.271945 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:41:44.272179 34015 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0301 09:41:44.272306 34015 task_signals.go:220] [ 1] Signal 23: delivering to handler D0301 09:41:44.279303 34015 task_block.go:223] [ 43] Interrupt queued D0301 09:41:44.286368 34015 transport_flipcall.go:127] send [channel @0xc0004e2000] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0301 09:41:44.287199 34015 transport_flipcall.go:234] recv [channel @0xc0004e2000] Rlerror{Error: 2} D0301 09:41:44.287436 34015 transport_flipcall.go:127] send [channel @0xc0004e2000] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0301 09:41:44.287998 34015 transport_flipcall.go:234] recv [channel @0xc0004e2000] Rlerror{Error: 95} D0301 09:41:44.288616 34015 transport_flipcall.go:127] send [channel @0xc0004e2000] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0301 09:41:44.289319 34015 transport_flipcall.go:234] recv [channel @0xc0004e2000] Rlerror{Error: 2} D0301 09:41:44.289467 34015 transport_flipcall.go:127] send [channel @0xc0004e2000] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0301 09:41:44.290289 34015 transport_flipcall.go:234] recv [channel @0xc0004e2000] Rlerror{Error: 95} D0301 09:41:44.291189 34015 transport_flipcall.go:127] send [channel @0xc0004e2000] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0301 09:41:44.317069 34015 transport_flipcall.go:234] recv [channel @0xc0004e2000] Rlerror{Error: 2} D0301 09:41:44.317458 34015 transport_flipcall.go:127] send [channel @0xc0004e2000] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0301 09:41:44.318833 34015 transport_flipcall.go:234] recv [channel @0xc0004e2000] Rlerror{Error: 95} D0301 09:41:44.319489 34015 transport_flipcall.go:127] send [channel @0xc0004e2000] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0301 09:41:44.320160 34015 transport_flipcall.go:234] recv [channel @0xc0004e2000] Rlerror{Error: 2} D0301 09:41:44.320400 34015 transport_flipcall.go:127] send [channel @0xc0004e2000] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0301 09:41:44.320903 34015 transport_flipcall.go:234] recv [channel @0xc0004e2000] Rlerror{Error: 95} D0301 09:41:44.321363 34015 transport_flipcall.go:127] send [channel @0xc0004e2000] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} I0301 09:41:44.321790 34015 loader.go:566] Received external signal 23, mode: Process D0301 09:41:44.321843 34015 transport_flipcall.go:234] recv [channel @0xc0004e2000] Rlerror{Error: 2} D0301 09:41:44.321960 34015 task_signals.go:446] [ 1] Notified of signal 23 D0301 09:41:44.322125 34015 transport_flipcall.go:127] send [channel @0xc0004e2000] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0301 09:41:44.322163 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:41:44.322305 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:41:44.322556 34015 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0301 09:41:44.322707 34015 task_signals.go:220] [ 1] Signal 23: delivering to handler D0301 09:41:44.322737 34015 transport_flipcall.go:234] recv [channel @0xc0004e2000] Rlerror{Error: 95} D0301 09:41:44.324267 34015 transport_flipcall.go:127] send [channel @0xc0004e2000] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0301 09:41:44.324832 34015 transport_flipcall.go:234] recv [channel @0xc0004e2000] Rlerror{Error: 2} D0301 09:41:44.324985 34015 transport_flipcall.go:127] send [channel @0xc0004e2000] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0301 09:41:44.325422 34015 transport_flipcall.go:234] recv [channel @0xc0004e2000] Rlerror{Error: 95} D0301 09:41:44.325835 34015 transport_flipcall.go:127] send [channel @0xc0004e2000] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0301 09:41:44.326444 34015 transport_flipcall.go:234] recv [channel @0xc0004e2000] Rlerror{Error: 2} D0301 09:41:44.326590 34015 transport_flipcall.go:127] send [channel @0xc0004e2000] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0301 09:41:44.327044 34015 transport_flipcall.go:234] recv [channel @0xc0004e2000] Rlerror{Error: 95} D0301 09:41:44.327414 34015 transport_flipcall.go:127] send [channel @0xc0004e2000] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0301 09:41:44.328024 34015 transport_flipcall.go:234] recv [channel @0xc0004e2000] Rlerror{Error: 2} D0301 09:41:44.328219 34015 transport_flipcall.go:127] send [channel @0xc0004e2000] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0301 09:41:44.328781 34015 transport_flipcall.go:234] recv [channel @0xc0004e2000] Rlerror{Error: 95} D0301 09:41:44.329238 34015 transport_flipcall.go:127] send [channel @0xc0004e2000] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0301 09:41:44.329817 34015 transport_flipcall.go:234] recv [channel @0xc0004e2000] Rlerror{Error: 2} D0301 09:41:44.329979 34015 transport_flipcall.go:127] send [channel @0xc0004e2000] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0301 09:41:44.330418 34015 transport_flipcall.go:234] recv [channel @0xc0004e2000] Rlerror{Error: 95} I0301 09:41:44.333060 34015 loader.go:566] Received external signal 23, mode: Process D0301 09:41:44.333303 34015 task_signals.go:446] [ 1] Notified of signal 23 D0301 09:41:44.333454 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:41:44.333494 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:41:44.333799 34015 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0301 09:41:44.333982 34015 task_signals.go:220] [ 1] Signal 23: delivering to handler I0301 09:41:44.353313 34015 loader.go:566] Received external signal 23, mode: Process D0301 09:41:44.353533 34015 task_signals.go:446] [ 1] Notified of signal 23 D0301 09:41:44.353667 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:41:44.353833 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:41:44.354022 34015 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0301 09:41:44.354132 34015 task_signals.go:220] [ 1] Signal 23: delivering to handler D0301 09:41:44.372072 34015 netfilter.go:54] netfilter: convert to binary: current offset: 0 D0301 09:41:44.372579 34015 netfilter.go:54] netfilter: convert to binary: found hook 1 at offset 0 D0301 09:41:44.372712 34015 netfilter.go:54] netfilter: convert to binary: found underflow 1 at offset 0 D0301 09:41:44.372820 34015 netfilter.go:54] netfilter: convert to binary: marshalling standard target I0301 09:41:44.373764 34015 loader.go:566] Received external signal 23, mode: Process D0301 09:41:44.372949 34015 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]} D0301 09:41:44.373959 34015 task_signals.go:446] [ 1] Notified of signal 23 D0301 09:41:44.373989 34015 netfilter.go:54] netfilter: convert to binary: current offset: 152 D0301 09:41:44.374105 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:41:44.374122 34015 netfilter.go:54] netfilter: convert to binary: found hook 2 at offset 152 D0301 09:41:44.374161 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:41:44.374223 34015 netfilter.go:54] netfilter: convert to binary: found underflow 2 at offset 152 D0301 09:41:44.374360 34015 netfilter.go:54] netfilter: convert to binary: marshalling standard target D0301 09:41:44.374396 34015 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0301 09:41:44.374524 34015 task_signals.go:220] [ 1] Signal 23: delivering to handler D0301 09:41:44.374477 34015 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]} D0301 09:41:44.375218 34015 netfilter.go:54] netfilter: convert to binary: current offset: 304 D0301 09:41:44.375321 34015 netfilter.go:54] netfilter: convert to binary: found hook 3 at offset 304 D0301 09:41:44.375443 34015 netfilter.go:54] netfilter: convert to binary: found underflow 3 at offset 304 D0301 09:41:44.375561 34015 netfilter.go:54] netfilter: convert to binary: marshalling standard target D0301 09:41:44.375687 34015 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]} D0301 09:41:44.376387 34015 netfilter.go:54] netfilter: convert to binary: current offset: 456 D0301 09:41:44.376504 34015 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]} D0301 09:41:44.377239 34015 netfilter.go:54] netfilter: convert to binary: finished with an marshalled size of 0 D0301 09:41:44.377323 34015 netfilter.go:54] netfilter: convert to binary: current offset: 0 D0301 09:41:44.377426 34015 netfilter.go:54] netfilter: convert to binary: found hook 0 at offset 0 D0301 09:41:44.377529 34015 netfilter.go:54] netfilter: convert to binary: found underflow 0 at offset 0 D0301 09:41:44.377649 34015 netfilter.go:54] netfilter: convert to binary: marshalling standard target D0301 09:41:44.377762 34015 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]} D0301 09:41:44.378453 34015 netfilter.go:54] netfilter: convert to binary: current offset: 152 D0301 09:41:44.378549 34015 netfilter.go:54] netfilter: convert to binary: found hook 1 at offset 152 D0301 09:41:44.378633 34015 netfilter.go:54] netfilter: convert to binary: found underflow 1 at offset 152 D0301 09:41:44.378789 34015 netfilter.go:54] netfilter: convert to binary: marshalling standard target D0301 09:41:44.378923 34015 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]} D0301 09:41:44.379569 34015 netfilter.go:54] netfilter: convert to binary: current offset: 304 D0301 09:41:44.379716 34015 netfilter.go:54] netfilter: convert to binary: found hook 3 at offset 304 D0301 09:41:44.379842 34015 netfilter.go:54] netfilter: convert to binary: found underflow 3 at offset 304 D0301 09:41:44.379964 34015 netfilter.go:54] netfilter: convert to binary: marshalling standard target D0301 09:41:44.380053 34015 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]} D0301 09:41:44.380815 34015 netfilter.go:54] netfilter: convert to binary: current offset: 456 D0301 09:41:44.380882 34015 netfilter.go:54] netfilter: convert to binary: found hook 4 at offset 456 D0301 09:41:44.381037 34015 netfilter.go:54] netfilter: convert to binary: found underflow 4 at offset 456 D0301 09:41:44.381149 34015 netfilter.go:54] netfilter: convert to binary: marshalling standard target D0301 09:41:44.381284 34015 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]} D0301 09:41:44.381909 34015 netfilter.go:54] netfilter: convert to binary: current offset: 608 D0301 09:41:44.382053 34015 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]} D0301 09:41:44.382762 34015 netfilter.go:54] netfilter: convert to binary: finished with an marshalled size of 0 D0301 09:41:44.382861 34015 netfilter.go:54] netfilter: convert to binary: current offset: 0 D0301 09:41:44.382978 34015 netfilter.go:54] netfilter: convert to binary: found hook 0 at offset 0 D0301 09:41:44.383112 34015 netfilter.go:54] netfilter: convert to binary: found underflow 0 at offset 0 D0301 09:41:44.383203 34015 netfilter.go:54] netfilter: convert to binary: marshalling standard target D0301 09:41:44.383352 34015 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]} D0301 09:41:44.384318 34015 netfilter.go:54] netfilter: convert to binary: current offset: 152 D0301 09:41:44.384449 34015 netfilter.go:54] netfilter: convert to binary: found hook 3 at offset 152 D0301 09:41:44.384524 34015 netfilter.go:54] netfilter: convert to binary: found underflow 3 at offset 152 D0301 09:41:44.384761 34015 netfilter.go:54] netfilter: convert to binary: marshalling standard target D0301 09:41:44.384951 34015 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]} D0301 09:41:44.385897 34015 netfilter.go:54] netfilter: convert to binary: current offset: 304 D0301 09:41:44.386133 34015 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]} D0301 09:41:44.387028 34015 netfilter.go:54] netfilter: convert to binary: finished with an marshalled size of 0 I0301 09:41:44.404105 34015 loader.go:566] Received external signal 23, mode: Process D0301 09:41:44.404296 34015 task_signals.go:446] [ 1] Notified of signal 23 D0301 09:41:44.404420 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:41:44.404562 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:41:44.404770 34015 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0301 09:41:44.404909 34015 task_signals.go:220] [ 1] Signal 23: delivering to handler I0301 09:41:44.424327 34015 loader.go:566] Received external signal 23, mode: Process D0301 09:41:44.424566 34015 task_signals.go:446] [ 1] Notified of signal 23 D0301 09:41:44.424698 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:41:44.424846 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:41:44.425117 34015 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0301 09:41:44.425282 34015 task_signals.go:220] [ 1] Signal 23: delivering to handler I0301 09:41:44.435741 34015 loader.go:566] Received external signal 23, mode: Process D0301 09:41:44.437722 34015 task_signals.go:446] [ 1] Notified of signal 23 D0301 09:41:44.437956 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:41:44.438078 34015 task_block.go:223] [ 1] Interrupt queued I0301 09:41:44.438119 34015 loader.go:566] Received external signal 23, mode: Process D0301 09:41:44.438276 34015 task_signals.go:434] [ 1] Discarding duplicate signal 23 D0301 09:41:44.438431 34015 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0301 09:41:44.438596 34015 task_signals.go:220] [ 1] Signal 23: delivering to handler I0301 09:41:44.454722 34015 loader.go:566] Received external signal 23, mode: Process D0301 09:41:44.454963 34015 task_signals.go:446] [ 1] Notified of signal 23 D0301 09:41:44.455102 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:41:44.455180 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:41:44.455339 34015 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0301 09:41:44.455502 34015 task_signals.go:220] [ 1] Signal 23: delivering to handler I0301 09:41:44.465807 34015 loader.go:566] Received external signal 23, mode: Process D0301 09:41:44.465969 34015 task_signals.go:446] [ 1] Notified of signal 23 D0301 09:41:44.466108 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:41:44.466138 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:41:44.466339 34015 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0301 09:41:44.466458 34015 task_signals.go:220] [ 1] Signal 23: delivering to handler I0301 09:41:44.481142 34015 loader.go:566] Received external signal 23, mode: Process D0301 09:41:44.481334 34015 task_signals.go:446] [ 1] Notified of signal 23 D0301 09:41:44.481485 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:41:44.481578 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:41:44.481806 34015 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0301 09:41:44.481928 34015 task_signals.go:220] [ 1] Signal 23: delivering to handler I0301 09:41:44.501343 34015 loader.go:566] Received external signal 23, mode: Process D0301 09:41:44.501574 34015 task_signals.go:446] [ 1] Notified of signal 23 D0301 09:41:44.501709 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:41:44.501780 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:41:44.502028 34015 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0301 09:41:44.502165 34015 task_signals.go:220] [ 1] Signal 23: delivering to handler I0301 09:41:44.521645 34015 loader.go:566] Received external signal 23, mode: Process D0301 09:41:44.521867 34015 task_signals.go:446] [ 1] Notified of signal 23 D0301 09:41:44.522007 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:41:44.522037 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:41:44.522224 34015 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0301 09:41:44.522327 34015 task_signals.go:220] [ 1] Signal 23: delivering to handler I0301 09:41:44.537758 34015 loader.go:566] Received external signal 23, mode: Process D0301 09:41:44.537980 34015 task_signals.go:446] [ 1] Notified of signal 23 D0301 09:41:44.538125 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:41:44.538185 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:41:44.538453 34015 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0301 09:41:44.538614 34015 task_signals.go:220] [ 1] Signal 23: delivering to handler I0301 09:41:44.557921 34015 loader.go:566] Received external signal 23, mode: Process D0301 09:41:44.558168 34015 task_signals.go:446] [ 1] Notified of signal 23 D0301 09:41:44.558397 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:41:44.558319 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:41:44.558628 34015 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0301 09:41:44.558763 34015 task_signals.go:220] [ 1] Signal 23: delivering to handler I0301 09:41:44.578168 34015 loader.go:566] Received external signal 23, mode: Process D0301 09:41:44.578385 34015 task_signals.go:446] [ 1] Notified of signal 23 D0301 09:41:44.578516 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:41:44.578564 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:41:44.578839 34015 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0301 09:41:44.579056 34015 task_signals.go:220] [ 1] Signal 23: delivering to handler I0301 09:41:44.594154 34015 loader.go:566] Received external signal 23, mode: Process D0301 09:41:44.594332 34015 task_signals.go:446] [ 1] Notified of signal 23 D0301 09:41:44.594451 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:41:44.594548 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:41:44.594754 34015 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0301 09:41:44.594926 34015 task_signals.go:220] [ 1] Signal 23: delivering to handler I0301 09:41:44.610139 34015 loader.go:566] Received external signal 23, mode: Process D0301 09:41:44.610370 34015 task_signals.go:446] [ 1] Notified of signal 23 D0301 09:41:44.610536 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:41:44.610603 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:41:44.610846 34015 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0301 09:41:44.611026 34015 task_signals.go:220] [ 1] Signal 23: delivering to handler I0301 09:41:44.630330 34015 loader.go:566] Received external signal 23, mode: Process D0301 09:41:44.630575 34015 task_signals.go:446] [ 1] Notified of signal 23 D0301 09:41:44.630739 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:41:44.630894 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:41:44.631065 34015 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0301 09:41:44.631178 34015 task_signals.go:220] [ 1] Signal 23: delivering to handler D0301 09:41:44.646450 34015 transport_flipcall.go:127] send [channel @0xc0004e2000] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0301 09:41:44.647318 34015 transport_flipcall.go:234] recv [channel @0xc0004e2000] Rlerror{Error: 2} D0301 09:41:44.647501 34015 transport_flipcall.go:127] send [channel @0xc0004e2000] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0301 09:41:44.648434 34015 transport_flipcall.go:234] recv [channel @0xc0004e2000] Rlerror{Error: 95} D0301 09:41:44.649029 34015 transport_flipcall.go:127] send [channel @0xc0004e2000] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0301 09:41:44.649705 34015 transport_flipcall.go:234] recv [channel @0xc0004e2000] Rlerror{Error: 2} D0301 09:41:44.649883 34015 transport_flipcall.go:127] send [channel @0xc0004e2000] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0301 09:41:44.650390 34015 transport_flipcall.go:234] recv [channel @0xc0004e2000] Rlerror{Error: 95} I0301 09:41:44.650542 34015 loader.go:566] Received external signal 23, mode: Process D0301 09:41:44.650719 34015 task_signals.go:446] [ 1] Notified of signal 23 D0301 09:41:44.650847 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:41:44.650808 34015 transport_flipcall.go:127] send [channel @0xc0004e2000] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0301 09:41:44.650896 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:41:44.651115 34015 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0301 09:41:44.651228 34015 task_signals.go:220] [ 1] Signal 23: delivering to handler D0301 09:41:44.651347 34015 transport_flipcall.go:234] recv [channel @0xc0004e2000] Rlerror{Error: 2} D0301 09:41:44.651510 34015 transport_flipcall.go:127] send [channel @0xc0004e2000] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0301 09:41:44.652123 34015 transport_flipcall.go:234] recv [channel @0xc0004e2000] Rlerror{Error: 95} D0301 09:41:44.652478 34015 transport_flipcall.go:127] send [channel @0xc0004e2000] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0301 09:41:44.653033 34015 transport_flipcall.go:234] recv [channel @0xc0004e2000] Rlerror{Error: 2} D0301 09:41:44.653176 34015 transport_flipcall.go:127] send [channel @0xc0004e2000] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0301 09:41:44.653624 34015 transport_flipcall.go:234] recv [channel @0xc0004e2000] Rlerror{Error: 95} D0301 09:41:44.653969 34015 transport_flipcall.go:127] send [channel @0xc0004e2000] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0301 09:41:44.654564 34015 transport_flipcall.go:234] recv [channel @0xc0004e2000] Rlerror{Error: 2} D0301 09:41:44.654753 34015 transport_flipcall.go:127] send [channel @0xc0004e2000] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0301 09:41:44.655296 34015 transport_flipcall.go:234] recv [channel @0xc0004e2000] Rlerror{Error: 95} D0301 09:41:44.655628 34015 transport_flipcall.go:127] send [channel @0xc0004e2000] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0301 09:41:44.656184 34015 transport_flipcall.go:234] recv [channel @0xc0004e2000] Rlerror{Error: 2} D0301 09:41:44.656384 34015 transport_flipcall.go:127] send [channel @0xc0004e2000] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0301 09:41:44.656953 34015 transport_flipcall.go:234] recv [channel @0xc0004e2000] Rlerror{Error: 95} D0301 09:41:44.657263 34015 transport_flipcall.go:127] send [channel @0xc0004e2000] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0301 09:41:44.657797 34015 transport_flipcall.go:234] recv [channel @0xc0004e2000] Rlerror{Error: 2} D0301 09:41:44.657950 34015 transport_flipcall.go:127] send [channel @0xc0004e2000] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0301 09:41:44.658417 34015 transport_flipcall.go:234] recv [channel @0xc0004e2000] Rlerror{Error: 95} D0301 09:41:44.658647 34015 transport_flipcall.go:127] send [channel @0xc0004e2000] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0301 09:41:44.659117 34015 transport_flipcall.go:234] recv [channel @0xc0004e2000] Rlerror{Error: 2} D0301 09:41:44.659249 34015 transport_flipcall.go:127] send [channel @0xc0004e2000] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0301 09:41:44.659799 34015 transport_flipcall.go:234] recv [channel @0xc0004e2000] Rlerror{Error: 95} D0301 09:41:44.660229 34015 transport_flipcall.go:127] send [channel @0xc0004e2000] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0301 09:41:44.660818 34015 transport_flipcall.go:234] recv [channel @0xc0004e2000] Rlerror{Error: 2} D0301 09:41:44.661003 34015 transport_flipcall.go:127] send [channel @0xc0004e2000] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0301 09:41:44.661441 34015 transport_flipcall.go:234] recv [channel @0xc0004e2000] Rlerror{Error: 95} D0301 09:41:44.662120 34015 transport_flipcall.go:127] send [channel @0xc0004e2000] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0301 09:41:44.662868 34015 transport_flipcall.go:234] recv [channel @0xc0004e2000] Rlerror{Error: 2} D0301 09:41:44.663069 34015 transport_flipcall.go:127] send [channel @0xc0004e2000] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0301 09:41:44.663596 34015 transport_flipcall.go:234] recv [channel @0xc0004e2000] Rlerror{Error: 95} I0301 09:41:44.670743 34015 loader.go:566] Received external signal 23, mode: Process D0301 09:41:44.670907 34015 task_signals.go:446] [ 1] Notified of signal 23 D0301 09:41:44.671025 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:41:44.671147 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:41:44.671267 34015 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0301 09:41:44.671385 34015 task_signals.go:220] [ 1] Signal 23: delivering to handler D0301 09:41:44.684627 34015 task_block.go:223] [ 44] Interrupt queued D0301 09:41:44.696260 34015 task_block.go:223] [ 45] Interrupt queued I0301 09:41:44.722301 34015 loader.go:566] Received external signal 23, mode: Process D0301 09:41:44.722886 34015 task_signals.go:446] [ 1] Notified of signal 23 D0301 09:41:44.723184 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:41:44.723619 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:41:44.724827 34015 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 I0301 09:41:44.724822 34015 loader.go:566] Received external signal 23, mode: Process D0301 09:41:44.725079 34015 task_signals.go:220] [ 1] Signal 23: delivering to handler D0301 09:41:44.726481 34015 task_signals.go:446] [ 1] Notified of signal 23 D0301 09:41:44.726631 34015 task_block.go:223] [ 1] Interrupt queued I0301 09:41:44.726800 34015 loader.go:566] Received external signal 23, mode: Process D0301 09:41:44.726821 34015 task_block.go:223] [ 2] Interrupt queued D0301 09:41:44.727000 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:41:44.726947 34015 task_block.go:223] [ 2] Interrupt queued D0301 09:41:44.727154 34015 task_signals.go:434] [ 1] Discarding duplicate signal 23 D0301 09:41:44.727384 34015 task_signals.go:176] [ 2] Not restarting syscall 202 after errno 516: interrupted by signal 23 D0301 09:41:44.727544 34015 task_signals.go:220] [ 2] Signal 23: delivering to handler D0301 09:41:44.734758 34015 task_block.go:223] [ 46] Interrupt queued D0301 09:41:44.744160 34015 task_block.go:223] [ 45] Interrupt queued D0301 09:41:44.744339 34015 task_block.go:223] [ 46] Interrupt queued D0301 09:41:44.744278 34015 task_block.go:223] [ 45] Interrupt queued D0301 09:41:44.744415 34015 task_exit.go:221] [ 44] Transitioning from exit state TaskExitNone to TaskExitInitiated D0301 09:41:44.744423 34015 task_block.go:223] [ 46] Interrupt queued D0301 09:41:44.744615 34015 task_signals.go:189] [ 45] Signal 9: terminating thread group D0301 09:41:44.744628 34015 task_exit.go:221] [ 44] Transitioning from exit state TaskExitInitiated to TaskExitZombie D0301 09:41:44.744669 34015 task_signals.go:189] [ 46] Signal 9: terminating thread group I0301 09:41:44.744800 34015 compat.go:129] Uncaught signal: "killed" (9), PID: 44, TID: 46, fault addr: 0x0 D0301 09:41:44.745019 34015 task_exit.go:221] [ 46] Transitioning from exit state TaskExitNone to TaskExitInitiated I0301 09:41:44.745123 34015 compat.go:129] Uncaught signal: "killed" (9), PID: 44, TID: 45, fault addr: 0x0 D0301 09:41:44.745271 34015 task_exit.go:221] [ 46] Transitioning from exit state TaskExitInitiated to TaskExitZombie D0301 09:41:44.745393 34015 task_exit.go:221] [ 46] Transitioning from exit state TaskExitZombie to TaskExitDead D0301 09:41:44.745574 34015 task_exit.go:221] [ 45] Transitioning from exit state TaskExitNone to TaskExitInitiated D0301 09:41:44.746940 34015 task_exit.go:221] [ 45] Transitioning from exit state TaskExitInitiated to TaskExitZombie D0301 09:41:44.747079 34015 task_exit.go:221] [ 45] Transitioning from exit state TaskExitZombie to TaskExitDead D0301 09:41:44.747269 34015 task_signals.go:419] [ 43] Discarding ignored signal 17 D0301 09:41:44.747699 34015 task_exit.go:221] [ 44] Transitioning from exit state TaskExitZombie to TaskExitDead D0301 09:41:44.752380 34015 transport_flipcall.go:127] send [channel @0xc0004e2000] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0301 09:41:44.753102 34015 transport_flipcall.go:234] recv [channel @0xc0004e2000] Rlerror{Error: 2} D0301 09:41:44.753285 34015 transport_flipcall.go:127] send [channel @0xc0004e2000] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0301 09:41:44.754017 34015 transport_flipcall.go:234] recv [channel @0xc0004e2000] Rlerror{Error: 95} D0301 09:41:44.755424 34015 transport_flipcall.go:127] send [channel @0xc0004e2000] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0301 09:41:44.756048 34015 transport_flipcall.go:234] recv [channel @0xc0004e2000] Rlerror{Error: 2} D0301 09:41:44.756178 34015 transport_flipcall.go:127] send [channel @0xc0004e2000] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0301 09:41:44.756633 34015 transport_flipcall.go:234] recv [channel @0xc0004e2000] Rlerror{Error: 95} D0301 09:41:44.757815 34015 transport_flipcall.go:127] send [channel @0xc0004e2000] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0301 09:41:44.758494 34015 transport_flipcall.go:234] recv [channel @0xc0004e2000] Rlerror{Error: 2} D0301 09:41:44.758628 34015 transport_flipcall.go:127] send [channel @0xc0004e2000] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0301 09:41:44.759120 34015 transport_flipcall.go:234] recv [channel @0xc0004e2000] Rlerror{Error: 95} I0301 09:41:44.762007 34015 loader.go:566] Received external signal 23, mode: Process D0301 09:41:44.762231 34015 task_signals.go:446] [ 1] Notified of signal 23 D0301 09:41:44.762437 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:41:44.762490 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:41:44.762776 34015 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0301 09:41:44.762923 34015 task_signals.go:220] [ 1] Signal 23: delivering to handler D0301 09:41:44.769748 34015 task_block.go:223] [ 47] Interrupt queued D0301 09:41:44.781579 34015 task_block.go:223] [ 48] Interrupt queued D0301 09:41:44.796377 34015 task_block.go:223] [ 49] Interrupt queued D0301 09:41:44.803585 34015 task_block.go:223] [ 48] Interrupt queued D0301 09:41:44.803718 34015 task_block.go:223] [ 49] Interrupt queued D0301 09:41:44.803747 34015 task_block.go:223] [ 49] Interrupt queued D0301 09:41:44.803724 34015 task_block.go:223] [ 48] Interrupt queued D0301 09:41:44.803905 34015 task_exit.go:221] [ 47] Transitioning from exit state TaskExitNone to TaskExitInitiated D0301 09:41:44.804045 34015 task_signals.go:189] [ 48] Signal 9: terminating thread group D0301 09:41:44.804141 34015 task_exit.go:221] [ 47] Transitioning from exit state TaskExitInitiated to TaskExitZombie D0301 09:41:44.804278 34015 task_signals.go:189] [ 49] Signal 9: terminating thread group I0301 09:41:44.804425 34015 compat.go:129] Uncaught signal: "killed" (9), PID: 47, TID: 48, fault addr: 0x0 D0301 09:41:44.804582 34015 task_exit.go:221] [ 48] Transitioning from exit state TaskExitNone to TaskExitInitiated D0301 09:41:44.804750 34015 task_exit.go:221] [ 48] Transitioning from exit state TaskExitInitiated to TaskExitZombie I0301 09:41:44.804690 34015 compat.go:129] Uncaught signal: "killed" (9), PID: 47, TID: 49, fault addr: 0x0 D0301 09:41:44.804827 34015 task_exit.go:221] [ 48] Transitioning from exit state TaskExitZombie to TaskExitDead D0301 09:41:44.805001 34015 task_exit.go:221] [ 49] Transitioning from exit state TaskExitNone to TaskExitInitiated D0301 09:41:44.807054 34015 task_exit.go:221] [ 49] Transitioning from exit state TaskExitInitiated to TaskExitZombie D0301 09:41:44.807288 34015 task_exit.go:221] [ 49] Transitioning from exit state TaskExitZombie to TaskExitDead D0301 09:41:44.807517 34015 task_signals.go:419] [ 43] Discarding ignored signal 17 D0301 09:41:44.808810 34015 task_exit.go:221] [ 47] Transitioning from exit state TaskExitZombie to TaskExitDead D0301 09:41:44.811950 34015 transport_flipcall.go:127] send [channel @0xc0004e2000] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0301 09:41:44.812731 34015 transport_flipcall.go:234] recv [channel @0xc0004e2000] Rlerror{Error: 2} D0301 09:41:44.812946 34015 transport_flipcall.go:127] send [channel @0xc0004e2000] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0301 09:41:44.813495 34015 transport_flipcall.go:234] recv [channel @0xc0004e2000] Rlerror{Error: 95} D0301 09:41:44.814290 34015 transport_flipcall.go:127] send [channel @0xc0004e2000] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0301 09:41:44.814834 34015 transport_flipcall.go:234] recv [channel @0xc0004e2000] Rlerror{Error: 2} D0301 09:41:44.814996 34015 transport_flipcall.go:127] send [channel @0xc0004e2000] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0301 09:41:44.815420 34015 transport_flipcall.go:234] recv [channel @0xc0004e2000] Rlerror{Error: 95} D0301 09:41:44.816134 34015 transport_flipcall.go:127] send [channel @0xc0004e2000] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0301 09:41:44.816584 34015 transport_flipcall.go:234] recv [channel @0xc0004e2000] Rlerror{Error: 2} D0301 09:41:44.816731 34015 transport_flipcall.go:127] send [channel @0xc0004e2000] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0301 09:41:44.817240 34015 transport_flipcall.go:234] recv [channel @0xc0004e2000] Rlerror{Error: 95} I0301 09:41:44.818792 34015 loader.go:566] Received external signal 23, mode: Process D0301 09:41:44.819007 34015 task_signals.go:446] [ 1] Notified of signal 23 D0301 09:41:44.819114 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:41:44.819256 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:41:44.819453 34015 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0301 09:41:44.819613 34015 task_signals.go:220] [ 1] Signal 23: delivering to handler D0301 09:41:44.824500 34015 task_block.go:223] [ 50] Interrupt queued D0301 09:41:44.832496 34015 task_block.go:223] [ 51] Interrupt queued D0301 09:41:44.847850 34015 task_block.go:223] [ 52] Interrupt queued D0301 09:41:44.854888 34015 task_block.go:223] [ 51] Interrupt queued D0301 09:41:44.855041 34015 task_block.go:223] [ 52] Interrupt queued D0301 09:41:44.855041 34015 task_block.go:223] [ 51] Interrupt queued D0301 09:41:44.855162 34015 task_block.go:223] [ 52] Interrupt queued D0301 09:41:44.855203 34015 task_exit.go:221] [ 50] Transitioning from exit state TaskExitNone to TaskExitInitiated D0301 09:41:44.855433 34015 task_signals.go:189] [ 51] Signal 9: terminating thread group D0301 09:41:44.855469 34015 task_exit.go:221] [ 50] Transitioning from exit state TaskExitInitiated to TaskExitZombie D0301 09:41:44.855471 34015 task_signals.go:189] [ 52] Signal 9: terminating thread group I0301 09:41:44.855656 34015 compat.go:129] Uncaught signal: "killed" (9), PID: 50, TID: 51, fault addr: 0x0 D0301 09:41:44.855840 34015 task_exit.go:221] [ 51] Transitioning from exit state TaskExitNone to TaskExitInitiated I0301 09:41:44.855925 34015 compat.go:129] Uncaught signal: "killed" (9), PID: 50, TID: 52, fault addr: 0x0 D0301 09:41:44.856106 34015 task_exit.go:221] [ 51] Transitioning from exit state TaskExitInitiated to TaskExitZombie D0301 09:41:44.856207 34015 task_exit.go:221] [ 51] Transitioning from exit state TaskExitZombie to TaskExitDead D0301 09:41:44.856430 34015 task_exit.go:221] [ 52] Transitioning from exit state TaskExitNone to TaskExitInitiated D0301 09:41:44.858064 34015 task_exit.go:221] [ 52] Transitioning from exit state TaskExitInitiated to TaskExitZombie D0301 09:41:44.858290 34015 task_exit.go:221] [ 52] Transitioning from exit state TaskExitZombie to TaskExitDead D0301 09:41:44.858552 34015 task_signals.go:419] [ 43] Discarding ignored signal 17 D0301 09:41:44.859922 34015 task_exit.go:221] [ 50] Transitioning from exit state TaskExitZombie to TaskExitDead D0301 09:41:44.861408 34015 parameters.go:238] Clock(Monotonic): error: -221 ns, adjusted frequency from 2299774483 Hz to 2299774112 Hz D0301 09:41:44.861664 34015 parameters.go:238] Clock(Realtime): error: 347 ns, adjusted frequency from 2299774664 Hz to 2299775428 Hz D0301 09:41:44.861798 34015 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:9078699821982459 monotonicBaseRef:84000151602 monotonicFrequency:2299774112 realtimeReady:1 realtimeBaseCycles:9078699822574032 realtimeBaseRef:1583055704861655947 realtimeFrequency:2299775428} D0301 09:41:44.864363 34015 transport_flipcall.go:127] send [channel @0xc0004e2000] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0301 09:41:44.865026 34015 transport_flipcall.go:234] recv [channel @0xc0004e2000] Rlerror{Error: 2} D0301 09:41:44.865215 34015 transport_flipcall.go:127] send [channel @0xc0004e2000] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0301 09:41:44.865633 34015 transport_flipcall.go:234] recv [channel @0xc0004e2000] Rlerror{Error: 95} D0301 09:41:44.866350 34015 transport_flipcall.go:127] send [channel @0xc0004e2000] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0301 09:41:44.866821 34015 transport_flipcall.go:234] recv [channel @0xc0004e2000] Rlerror{Error: 2} D0301 09:41:44.866954 34015 transport_flipcall.go:127] send [channel @0xc0004e2000] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0301 09:41:44.867624 34015 transport_flipcall.go:234] recv [channel @0xc0004e2000] Rlerror{Error: 95} D0301 09:41:44.868422 34015 transport_flipcall.go:127] send [channel @0xc0004e2000] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0301 09:41:44.868980 34015 transport_flipcall.go:234] recv [channel @0xc0004e2000] Rlerror{Error: 2} D0301 09:41:44.869161 34015 transport_flipcall.go:127] send [channel @0xc0004e2000] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0301 09:41:44.869722 34015 transport_flipcall.go:234] recv [channel @0xc0004e2000] Rlerror{Error: 95} I0301 09:41:44.875360 34015 loader.go:566] Received external signal 23, mode: Process D0301 09:41:44.875647 34015 task_signals.go:446] [ 1] Notified of signal 23 D0301 09:41:44.875904 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:41:44.876007 34015 task_block.go:223] [ 1] Interrupt queued D0301 09:41:44.876190 34015 task_signals.go:179] [ 1] Restarting syscall 202 after errno 512: interrupted by signal 23 D0301 09:41:44.876332 34015 task_signals.go:220] [ 1] Signal 23: delivering to handler D0301 09:41:44.876774 34015 task_block.go:223] [ 53] Interrupt queued D0301 09:41:44.891252 34015 task_block.go:223] [ 54] Interrupt queued D0301 09:41:44.904357 34015 task_block.go:223] [ 55] Interrupt queued ================== WARNING: DATA RACE Read at 0x00c0003b8d90 by goroutine 354: gvisor.dev/gvisor/pkg/tcpip/stack.(*TransportEndpointInfo).AddrNetProto() pkg/tcpip/stack/stack.go:573 +0x79 gvisor.dev/gvisor/pkg/tcpip/transport/udp.(*endpoint).checkV4Mapped() pkg/tcpip/transport/udp/endpoint.go:931 +0xee gvisor.dev/gvisor/pkg/tcpip/transport/udp.(*endpoint).Connect() pkg/tcpip/transport/udp/endpoint.go:984 +0x6c gvisor.dev/gvisor/pkg/sentry/socket/netstack.(*SocketOperations).Connect() pkg/sentry/socket/netstack/netstack.go:698 +0x4c5 gvisor.dev/gvisor/pkg/sentry/syscalls/linux.Connect() pkg/sentry/syscalls/linux/sys_socket.go:280 +0x251 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).executeSyscall() pkg/sentry/kernel/task_syscall.go:170 +0x44d gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscallInvoke() pkg/sentry/kernel/task_syscall.go:291 +0xb4 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscallEnter() pkg/sentry/kernel/task_syscall.go:252 +0x109 DIAGNOSIS: I0301 09:41:44.946032 42808 main.go:305] *************************** I0301 09:41:44.946182 42808 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] I0301 09:41:44.946380 42808 main.go:307] Version release-20200219.0-72-gce4d1e45bb88 I0301 09:41:44.946459 42808 main.go:308] PID: 42808 I0301 09:41:44.946546 42808 main.go:309] UID: 0, GID: 0 I0301 09:41:44.946648 42808 main.go:310] Configuration: I0301 09:41:44.946755 42808 main.go:311] RootDir: /syzkaller/managers/ptrace-proxy-sandbox-race/workdir/gvisor_root I0301 09:41:44.946894 42808 main.go:312] Platform: ptrace I0301 09:41:44.946962 42808 main.go:313] FileAccess: shared, overlay: false I0301 09:41:44.947093 42808 main.go:314] Network: sandbox, logging: false I0301 09:41:44.947211 42808 main.go:315] Strace: false, max size: 1024, syscalls: [] I0301 09:41:44.947292 42808 main.go:316] *************************** W0301 09:41:44.947397 42808 main.go:321] Block the TERM signal. This is only safe in tests! D0301 09:41:44.947573 42808 container.go:159] Load container "/syzkaller/managers/ptrace-proxy-sandbox-race/workdir/gvisor_root" "ci-gvisor-ptrace-proxy-sandbox-race-1" D0301 09:41:44.956962 42808 container.go:592] Signal container "ci-gvisor-ptrace-proxy-sandbox-race-1": signal 0 D0301 09:41:44.957138 42808 sandbox.go:806] Signal sandbox "ci-gvisor-ptrace-proxy-sandbox-race-1" D0301 09:41:44.957245 42808 sandbox.go:318] Connecting to sandbox "ci-gvisor-ptrace-proxy-sandbox-race-1" D0301 09:41:44.957675 42808 urpc.go:534] urpc: successfully marshalled 117 bytes. I0301 09:41:45.040940 42808 debug.go:122] Found sandbox "ci-gvisor-ptrace-proxy-sandbox-race-1", PID: 34015 I0301 09:41:45.041064 42808 debug.go:131] Retrieving sandbox stacks D0301 09:41:45.041150 42808 sandbox.go:924] Stacks sandbox "ci-gvisor-ptrace-proxy-sandbox-race-1" D0301 09:41:45.041546 42808 sandbox.go:318] Connecting to sandbox "ci-gvisor-ptrace-proxy-sandbox-race-1" W0301 09:41:45.041733 42808 error.go:48] FATAL ERROR: retrieving stacks: connecting to control server at PID 34015: connection refused retrieving stacks: connecting to control server at PID 34015: connection refused W0301 09:41:45.042171 42808 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 I0301 09:41:44.946032 42808 main.go:305] *************************** I0301 09:41:44.946182 42808 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] I0301 09:41:44.946380 42808 main.go:307] Version release-20200219.0-72-gce4d1e45bb88 I0301 09:41:44.946459 42808 main.go:308] PID: 42808 I0301 09:41:44.946546 42808 main.go:309] UID: 0, GID: 0 I0301 09:41:44.946648 42808 main.go:310] Configuration: I0301 09:41:44.946755 42808 main.go:311] RootDir: /syzkaller/managers/ptrace-proxy-sandbox-race/workdir/gvisor_root I0301 09:41:44.946894 42808 main.go:312] Platform: ptrace I0301 09:41:44.946962 42808 main.go:313] FileAccess: shared, overlay: false I0301 09:41:44.947093 42808 main.go:314] Network: sandbox, logging: false I0301 09:41:44.947211 42808 main.go:315] Strace: false, max size: 1024, syscalls: [] I0301 09:41:44.947292 42808 main.go:316] *************************** W0301 09:41:44.947397 42808 main.go:321] Block the TERM signal. This is only safe in tests! D0301 09:41:44.947573 42808 container.go:159] Load container "/syzkaller/managers/ptrace-proxy-sandbox-race/workdir/gvisor_root" "ci-gvisor-ptrace-proxy-sandbox-race-1" D0301 09:41:44.956962 42808 container.go:592] Signal container "ci-gvisor-ptrace-proxy-sandbox-race-1": signal 0 D0301 09:41:44.957138 42808 sandbox.go:806] Signal sandbox "ci-gvisor-ptrace-proxy-sandbox-race-1" D0301 09:41:44.957245 42808 sandbox.go:318] Connecting to sandbox "ci-gvisor-ptrace-proxy-sandbox-race-1" D0301 09:41:44.957675 42808 urpc.go:534] urpc: successfully marshalled 117 bytes. I0301 09:41:45.040940 42808 debug.go:122] Found sandbox "ci-gvisor-ptrace-proxy-sandbox-race-1", PID: 34015 I0301 09:41:45.041064 42808 debug.go:131] Retrieving sandbox stacks D0301 09:41:45.041150 42808 sandbox.go:924] Stacks sandbox "ci-gvisor-ptrace-proxy-sandbox-race-1" D0301 09:41:45.041546 42808 sandbox.go:318] Connecting to sandbox "ci-gvisor-ptrace-proxy-sandbox-race-1" W0301 09:41:45.041733 42808 error.go:48] FATAL ERROR: retrieving stacks: connecting to control server at PID 34015: connection refused retrieving stacks: connecting to control server at PID 34015: connection refused W0301 09:41:45.042171 42808 main.go:338] Failure to execute command, err: 1 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscall() pkg/sentry/kernel/task_syscall.go:227 +0x1b3 gvisor.dev/gvisor/pkg/sentry/kernel.(*runApp).execute() pkg/sentry/kernel/task_run.go:232 +0x1aaa gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).run() pkg/sentry/kernel/task_run.go:92 +0x320 Previous write at 0x00c0003b8d90 by goroutine 365: gvisor.dev/gvisor/pkg/tcpip/transport/udp.(*endpoint).bindLocked() pkg/tcpip/transport/udp/endpoint.go:1176 +0x27c gvisor.dev/gvisor/pkg/tcpip/transport/udp.(*endpoint).prepareForWrite() pkg/tcpip/transport/udp/endpoint.go:323 +0x26d gvisor.dev/gvisor/pkg/tcpip/transport/udp.(*endpoint).write() pkg/tcpip/transport/udp/endpoint.go:400 +0x12b gvisor.dev/gvisor/pkg/tcpip/transport/udp.(*endpoint).Write() pkg/tcpip/transport/udp/endpoint.go:360 +0x81 gvisor.dev/gvisor/pkg/sentry/socket/netstack.(*SocketOperations).Write() pkg/sentry/socket/netstack/netstack.go:522 +0x152 gvisor.dev/gvisor/pkg/sentry/fs.(*File).Writev() pkg/sentry/fs/file.go:336 +0x308 gvisor.dev/gvisor/pkg/sentry/syscalls/linux.writev() pkg/sentry/syscalls/linux/sys_write.go:262 +0xe0 gvisor.dev/gvisor/pkg/sentry/syscalls/linux.Write() pkg/sentry/syscalls/linux/sys_write.go:72 +0x2ab 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 354 (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 365 (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 ================== W0301 09:41:45.035216 42558 error.go:48] FATAL ERROR: waiting on pid 23: waiting on PID 23 in sandbox "ci-gvisor-ptrace-proxy-sandbox-race-1": urpc method "containerManager.WaitPID" failed: EOF waiting on pid 23: waiting on PID 23 in sandbox "ci-gvisor-ptrace-proxy-sandbox-race-1": urpc method "containerManager.WaitPID" failed: EOF W0301 09:41:45.036018 42558 main.go:338] Failure to execute command, err: 1 W0301 09:41:45.041330 34008 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. D0301 09:41:45.041682 34008 container.go:714] Destroy container "ci-gvisor-ptrace-proxy-sandbox-race-1" D0301 09:41:45.041859 34008 container.go:801] Destroying container "ci-gvisor-ptrace-proxy-sandbox-race-1" D0301 09:41:45.041967 34008 sandbox.go:1058] Destroying root container "ci-gvisor-ptrace-proxy-sandbox-race-1" by destroying sandbox D0301 09:41:45.042117 34008 sandbox.go:788] Destroy sandbox "ci-gvisor-ptrace-proxy-sandbox-race-1" D0301 09:41:45.042197 34008 container.go:815] Killing gofer for container "ci-gvisor-ptrace-proxy-sandbox-race-1", PID: 34014 I0301 09:41:45.144641 34008 main.go:329] Exiting with status: 16896