D0227 11:37:34.308087 52095 parameters.go:238] Clock(Monotonic): error: 236 ns, adjusted frequency from 2299774172 Hz to 2299774939 Hz D0227 11:37:34.308362 52095 parameters.go:238] Clock(Realtime): error: 269 ns, adjusted frequency from 2299774058 Hz to 2299774962 Hz D0227 11:37:34.308515 52095 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8498580400361751 monotonicBaseRef:2001218479 monotonicFrequency:2299774939 realtimeReady:1 realtimeBaseCycles:8498580401025769 realtimeBaseRef:1582803454308355381 realtimeFrequency:2299774962} D0227 11:37:35.307189 52095 parameters.go:238] Clock(Monotonic): error: 35 ns, adjusted frequency from 2299774939 Hz to 2299774532 Hz D0227 11:37:35.307427 52095 parameters.go:238] Clock(Realtime): error: 45 ns, adjusted frequency from 2299774962 Hz to 2299774506 Hz D0227 11:37:35.307568 52095 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8498582698068549 monotonicBaseRef:3000319199 monotonicFrequency:2299774532 realtimeReady:1 realtimeBaseCycles:8498582698654598 realtimeBaseRef:1582803455307422188 realtimeFrequency:2299774506} D0227 11:37:36.308374 52095 parameters.go:238] Clock(Monotonic): error: 87 ns, adjusted frequency from 2299774532 Hz to 2299774693 Hz D0227 11:37:36.308583 52095 parameters.go:238] Clock(Realtime): error: 100 ns, adjusted frequency from 2299774506 Hz to 2299774686 Hz D0227 11:37:36.308721 52095 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8498585000566090 monotonicBaseRef:4001503232 monotonicFrequency:2299774693 realtimeReady:1 realtimeBaseCycles:8498585001087472 realtimeBaseRef:1582803456308578113 realtimeFrequency:2299774686} D0227 11:37:37.307992 52095 parameters.go:238] Clock(Monotonic): error: 74 ns, adjusted frequency from 2299774693 Hz to 2299774683 Hz D0227 11:37:37.308232 52095 parameters.go:238] Clock(Realtime): error: 46 ns, adjusted frequency from 2299774686 Hz to 2299774585 Hz D0227 11:37:37.308359 52095 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8498587299440712 monotonicBaseRef:5001111858 monotonicFrequency:2299774683 realtimeReady:1 realtimeBaseCycles:8498587300033677 realtimeBaseRef:1582803457308217868 realtimeFrequency:2299774585} D0227 11:37:38.307704 52095 parameters.go:238] Clock(Monotonic): error: 1 ns, adjusted frequency from 2299774683 Hz to 2299774525 Hz D0227 11:37:38.307930 52095 parameters.go:238] Clock(Realtime): error: 59 ns, adjusted frequency from 2299774585 Hz to 2299774632 Hz D0227 11:37:38.308055 52095 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8498589598558307 monotonicBaseRef:6000826139 monotonicFrequency:2299774525 realtimeReady:1 realtimeBaseCycles:8498589599133216 realtimeBaseRef:1582803458307924340 realtimeFrequency:2299774632} D0227 11:37:39.308858 52095 parameters.go:238] Clock(Monotonic): error: 5 ns, adjusted frequency from 2299774525 Hz to 2299774539 Hz D0227 11:37:39.309547 52095 parameters.go:238] Clock(Realtime): error: 24 ns, adjusted frequency from 2299774632 Hz to 2299774566 Hz D0227 11:37:39.309662 52095 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8498591900211971 monotonicBaseRef:7001643236 monotonicFrequency:2299774539 realtimeReady:1 realtimeBaseCycles:8498591902624922 realtimeBaseRef:1582803459309540617 realtimeFrequency:2299774566} D0227 11:37:40.307279 52095 parameters.go:238] Clock(Monotonic): error: 22 ns, adjusted frequency from 2299774539 Hz to 2299774585 Hz D0227 11:37:40.307496 52095 parameters.go:238] Clock(Realtime): error: 63 ns, adjusted frequency from 2299774566 Hz to 2299774669 Hz D0227 11:37:40.307626 52095 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8498594197124319 monotonicBaseRef:8000398683 monotonicFrequency:2299774585 realtimeReady:1 realtimeBaseCycles:8498594197685344 realtimeBaseRef:1582803460307490788 realtimeFrequency:2299774669} D0227 11:37:41.307423 52095 parameters.go:238] Clock(Monotonic): error: 52 ns, adjusted frequency from 2299774585 Hz to 2299774664 Hz D0227 11:37:41.307651 52095 parameters.go:238] Clock(Realtime): error: 57 ns, adjusted frequency from 2299774669 Hz to 2299774663 Hz D0227 11:37:41.307786 52095 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8498596497256600 monotonicBaseRef:9000554218 monotonicFrequency:2299774664 realtimeReady:1 realtimeBaseCycles:8498596497813113 realtimeBaseRef:1582803461307644324 realtimeFrequency:2299774663} D0227 11:37:42.307157 52095 parameters.go:238] Clock(Monotonic): error: 29 ns, adjusted frequency from 2299774664 Hz to 2299774658 Hz D0227 11:37:42.307367 52095 parameters.go:238] Clock(Realtime): error: -42 ns, adjusted frequency from 2299774663 Hz to 2299774489 Hz D0227 11:37:42.307488 52095 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8498598796407183 monotonicBaseRef:10000282851 monotonicFrequency:2299774658 realtimeReady:1 realtimeBaseCycles:8498598796939523 realtimeBaseRef:1582803462307362447 realtimeFrequency:2299774489} D0227 11:37:43.308011 52095 parameters.go:238] Clock(Monotonic): error: 14 ns, adjusted frequency from 2299774658 Hz to 2299774625 Hz D0227 11:37:43.308257 52095 parameters.go:238] Clock(Realtime): error: 15 ns, adjusted frequency from 2299774489 Hz to 2299774618 Hz D0227 11:37:43.308376 52095 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8498601098148032 monotonicBaseRef:11001137800 monotonicFrequency:2299774625 realtimeReady:1 realtimeBaseCycles:8498601098759394 realtimeBaseRef:1582803463308251830 realtimeFrequency:2299774618} D0227 11:37:44.308240 52095 parameters.go:238] Clock(Monotonic): error: 37 ns, adjusted frequency from 2299774625 Hz to 2299774685 Hz D0227 11:37:44.308491 52095 parameters.go:238] Clock(Realtime): error: -65 ns, adjusted frequency from 2299774618 Hz to 2299774435 Hz D0227 11:37:44.308641 52095 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8498603398399192 monotonicBaseRef:12001345009 monotonicFrequency:2299774685 realtimeReady:1 realtimeBaseCycles:8498603399073610 realtimeBaseRef:1582803464308486460 realtimeFrequency:2299774435} D0227 11:37:45.307279 52095 parameters.go:238] Clock(Monotonic): error: -59 ns, adjusted frequency from 2299774685 Hz to 2299774455 Hz D0227 11:37:45.307507 52095 parameters.go:238] Clock(Realtime): error: 77 ns, adjusted frequency from 2299774435 Hz to 2299774767 Hz D0227 11:37:45.307629 52095 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8498605696005881 monotonicBaseRef:13000402309 monotonicFrequency:2299774455 realtimeReady:1 realtimeBaseCycles:8498605696580912 realtimeBaseRef:1582803465307500653 realtimeFrequency:2299774767} D0227 11:37:46.308800 52095 parameters.go:238] Clock(Monotonic): error: 23 ns, adjusted frequency from 2299774455 Hz to 2299774643 Hz D0227 11:37:46.309041 52095 parameters.go:238] Clock(Realtime): error: 37 ns, adjusted frequency from 2299774767 Hz to 2299774681 Hz D0227 11:37:46.309192 52095 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8498607999302900 monotonicBaseRef:14001934008 monotonicFrequency:2299774643 realtimeReady:1 realtimeBaseCycles:8498607999886387 realtimeBaseRef:1582803466309035893 realtimeFrequency:2299774681} D0227 11:37:47.308753 52095 sampler.go:168] Time: Adjusting syscall overhead up to 8000 D0227 11:37:47.309005 52095 parameters.go:238] Clock(Monotonic): error: 105 ns, adjusted frequency from 2299774643 Hz to 2299774837 Hz D0227 11:37:47.309188 52095 parameters.go:238] Clock(Realtime): error: 17 ns, adjusted frequency from 2299774681 Hz to 2299774636 Hz D0227 11:37:47.309331 52095 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8498610299549746 monotonicBaseRef:15002139333 monotonicFrequency:2299774837 realtimeReady:1 realtimeBaseCycles:8498610299993088 realtimeBaseRef:1582803467309180263 realtimeFrequency:2299774636} D0227 11:37:48.308463 52095 parameters.go:238] Clock(Monotonic): error: -103 ns, adjusted frequency from 2299774837 Hz to 2299774363 Hz D0227 11:37:48.308709 52095 parameters.go:238] Clock(Realtime): error: -44 ns, adjusted frequency from 2299774636 Hz to 2299774492 Hz D0227 11:37:48.308846 52095 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8498612598006984 monotonicBaseRef:16001566407 monotonicFrequency:2299774363 realtimeReady:1 realtimeBaseCycles:8498612598667403 realtimeBaseRef:1582803468308701815 realtimeFrequency:2299774492} D0227 11:37:49.307592 52095 parameters.go:238] Clock(Monotonic): error: 971 ns, adjusted frequency from 2299774363 Hz to 2299776869 Hz D0227 11:37:49.307803 52095 parameters.go:238] Clock(Realtime): error: -28 ns, adjusted frequency from 2299774492 Hz to 2299774523 Hz D0227 11:37:49.307935 52095 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8498614895811451 monotonicBaseRef:17000709846 monotonicFrequency:2299776869 realtimeReady:1 realtimeBaseCycles:8498614896362677 realtimeBaseRef:1582803469307797718 realtimeFrequency:2299774523} D0227 11:37:50.176107 52095 urpc.go:577] urpc: unmarshal success. D0227 11:37:50.176398 52095 controller.go:501] containerManager.Signal &{CID:ci-gvisor-ptrace-proxy-sandbox-race-0 Signo:0 PID:0 Mode:Process} D0227 11:37:50.176718 52095 urpc.go:534] urpc: successfully marshalled 37 bytes. D0227 11:37:50.307904 52095 parameters.go:238] Clock(Monotonic): error: -656 ns, adjusted frequency from 2299776869 Hz to 2299772980 Hz D0227 11:37:50.308149 52095 parameters.go:238] Clock(Realtime): error: 12 ns, adjusted frequency from 2299774523 Hz to 2299774614 Hz D0227 11:37:50.308262 52095 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8498617196257930 monotonicBaseRef:18001001009 monotonicFrequency:2299772980 realtimeReady:1 realtimeBaseCycles:8498617196932502 realtimeBaseRef:1582803470308143535 realtimeFrequency:2299774614} D0227 11:37:51.308194 52095 parameters.go:238] Clock(Monotonic): error: 244 ns, adjusted frequency from 2299772980 Hz to 2299775093 Hz D0227 11:37:51.308511 52095 sampler.go:168] Time: Adjusting syscall overhead up to 8000 D0227 11:37:51.308659 52095 parameters.go:238] Clock(Realtime): error: 390 ns, adjusted frequency from 2299774614 Hz to 2299775400 Hz D0227 11:37:51.309411 52095 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8498619496739107 monotonicBaseRef:19001308951 monotonicFrequency:2299775093 realtimeReady:1 realtimeBaseCycles:8498619497876497 realtimeBaseRef:1582803471308652011 realtimeFrequency:2299775400} D0227 11:37:52.307413 52095 parameters.go:238] Clock(Monotonic): error: -271 ns, adjusted frequency from 2299775093 Hz to 2299773891 Hz D0227 11:37:52.307621 52095 parameters.go:238] Clock(Realtime): error: -151 ns, adjusted frequency from 2299775400 Hz to 2299774189 Hz D0227 11:37:52.307720 52095 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8498621794743162 monotonicBaseRef:20000538859 monotonicFrequency:2299773891 realtimeReady:1 realtimeBaseCycles:8498621795268912 realtimeBaseRef:1582803472307615829 realtimeFrequency:2299774189} D0227 11:37:53.307666 52095 parameters.go:238] Clock(Monotonic): error: -190 ns, adjusted frequency from 2299773891 Hz to 2299774142 Hz D0227 11:37:53.307915 52095 parameters.go:238] Clock(Realtime): error: -81 ns, adjusted frequency from 2299774189 Hz to 2299774373 Hz D0227 11:37:53.308055 52095 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8498624095114210 monotonicBaseRef:21000798518 monotonicFrequency:2299774142 realtimeReady:1 realtimeBaseCycles:8498624095714665 realtimeBaseRef:1582803473307907842 realtimeFrequency:2299774373} D0227 11:37:54.307594 52095 parameters.go:238] Clock(Monotonic): error: 261 ns, adjusted frequency from 2299774142 Hz to 2299775113 Hz D0227 11:37:54.307808 52095 parameters.go:238] Clock(Realtime): error: 153 ns, adjusted frequency from 2299774373 Hz to 2299774871 Hz D0227 11:37:54.307947 52095 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8498626394730955 monotonicBaseRef:22000730077 monotonicFrequency:2299775113 realtimeReady:1 realtimeBaseCycles:8498626395214508 realtimeBaseRef:1582803474307788469 realtimeFrequency:2299774871} D0227 11:37:55.307166 52095 parameters.go:238] Clock(Monotonic): error: -219 ns, adjusted frequency from 2299775113 Hz to 2299774083 Hz D0227 11:37:55.307429 52095 parameters.go:238] Clock(Realtime): error: -137 ns, adjusted frequency from 2299774871 Hz to 2299774278 Hz D0227 11:37:55.307538 52095 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8498628693511118 monotonicBaseRef:23000297447 monotonicFrequency:2299774083 realtimeReady:1 realtimeBaseCycles:8498628694150154 realtimeBaseRef:1582803475307423552 realtimeFrequency:2299774278} D0227 11:37:56.307357 52095 parameters.go:238] Clock(Monotonic): error: -58 ns, adjusted frequency from 2299774083 Hz to 2299774451 Hz D0227 11:37:56.307563 52095 parameters.go:238] Clock(Realtime): error: 250 ns, adjusted frequency from 2299774278 Hz to 2299775127 Hz D0227 11:37:56.307706 52095 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8498630993725156 monotonicBaseRef:24000488750 monotonicFrequency:2299774451 realtimeReady:1 realtimeBaseCycles:8498630994234772 realtimeBaseRef:1582803476307558495 realtimeFrequency:2299775127} D0227 11:37:57.308011 52095 parameters.go:238] Clock(Monotonic): error: 342 ns, adjusted frequency from 2299774451 Hz to 2299775287 Hz D0227 11:37:57.308253 52095 parameters.go:238] Clock(Realtime): error: -294 ns, adjusted frequency from 2299775127 Hz to 2299773899 Hz D0227 11:37:57.308371 52095 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8498633295001027 monotonicBaseRef:25001141605 monotonicFrequency:2299775287 realtimeReady:1 realtimeBaseCycles:8498633295590542 realtimeBaseRef:1582803477308245798 realtimeFrequency:2299773899} D0227 11:37:58.307902 52095 parameters.go:238] Clock(Monotonic): error: 51 ns, adjusted frequency from 2299775287 Hz to 2299774601 Hz D0227 11:37:58.308102 52095 parameters.go:238] Clock(Realtime): error: 304 ns, adjusted frequency from 2299773899 Hz to 2299775246 Hz D0227 11:37:58.308220 52095 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8498635594444343 monotonicBaseRef:26000997255 monotonicFrequency:2299774601 realtimeReady:1 realtimeBaseCycles:8498635595017930 realtimeBaseRef:1582803478308095126 realtimeFrequency:2299775246} D0227 11:37:59.307229 52095 parameters.go:238] Clock(Monotonic): error: -298 ns, adjusted frequency from 2299774601 Hz to 2299773999 Hz D0227 11:37:59.307441 52095 parameters.go:238] Clock(Realtime): error: 39 ns, adjusted frequency from 2299775246 Hz to 2299774621 Hz D0227 11:37:59.307578 52095 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8498637892756118 monotonicBaseRef:27000361181 monotonicFrequency:2299773999 realtimeReady:1 realtimeBaseCycles:8498637893265665 realtimeBaseRef:1582803479307430925 realtimeFrequency:2299774621} D0227 11:38:00.307369 52095 parameters.go:238] Clock(Monotonic): error: -33 ns, adjusted frequency from 2299773999 Hz to 2299774575 Hz D0227 11:38:00.307591 52095 parameters.go:238] Clock(Realtime): error: -27 ns, adjusted frequency from 2299774621 Hz to 2299774558 Hz D0227 11:38:00.307691 52095 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8498640192860187 monotonicBaseRef:28000504703 monotonicFrequency:2299774575 realtimeReady:1 realtimeBaseCycles:8498640193394673 realtimeBaseRef:1582803480307585021 realtimeFrequency:2299774558} D0227 11:38:01.307445 52095 parameters.go:238] Clock(Monotonic): error: -16 ns, adjusted frequency from 2299774575 Hz to 2299774629 Hz D0227 11:38:01.307654 52095 parameters.go:238] Clock(Realtime): error: -75 ns, adjusted frequency from 2299774558 Hz to 2299774441 Hz D0227 11:38:01.307771 52095 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8498642492804526 monotonicBaseRef:29000578520 monotonicFrequency:2299774629 realtimeReady:1 realtimeBaseCycles:8498642493315379 realtimeBaseRef:1582803481307648569 realtimeFrequency:2299774441} D0227 11:38:02.308509 52095 sampler.go:197] Time: Adjusting syscall overhead down to 7000 D0227 11:38:02.308751 52095 parameters.go:238] Clock(Monotonic): error: -61 ns, adjusted frequency from 2299774629 Hz to 2299774452 Hz D0227 11:38:02.308923 52095 parameters.go:238] Clock(Realtime): error: 74 ns, adjusted frequency from 2299774441 Hz to 2299774724 Hz D0227 11:38:02.309092 52095 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8498644795019938 monotonicBaseRef:30001639833 monotonicFrequency:2299774452 realtimeReady:1 realtimeBaseCycles:8498644796005306 realtimeBaseRef:1582803482308916295 realtimeFrequency:2299774724} D0227 11:38:03.307191 52095 parameters.go:238] Clock(Monotonic): error: 23 ns, adjusted frequency from 2299774452 Hz to 2299774723 Hz D0227 11:38:03.307419 52095 parameters.go:238] Clock(Realtime): error: 22 ns, adjusted frequency from 2299774724 Hz to 2299774656 Hz D0227 11:38:03.307513 52095 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8498647091757841 monotonicBaseRef:31000319464 monotonicFrequency:2299774723 realtimeReady:1 realtimeBaseCycles:8498647092323369 realtimeBaseRef:1582803483307413251 realtimeFrequency:2299774656} D0227 11:38:04.308236 52095 parameters.go:238] Clock(Monotonic): error: -16 ns, adjusted frequency from 2299774723 Hz to 2299774548 Hz D0227 11:38:04.308508 52095 parameters.go:238] Clock(Realtime): error: 23 ns, adjusted frequency from 2299774656 Hz to 2299774582 Hz D0227 11:38:04.308650 52095 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8498649393945906 monotonicBaseRef:32001368845 monotonicFrequency:2299774548 realtimeReady:1 realtimeBaseCycles:8498649394591520 realtimeBaseRef:1582803484308497485 realtimeFrequency:2299774582} D0227 11:38:05.307922 52095 parameters.go:238] Clock(Monotonic): error: 18 ns, adjusted frequency from 2299774548 Hz to 2299774637 Hz D0227 11:38:05.308161 52095 parameters.go:238] Clock(Realtime): error: -7 ns, adjusted frequency from 2299774582 Hz to 2299774544 Hz D0227 11:38:05.308300 52095 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8498651692986958 monotonicBaseRef:33001049902 monotonicFrequency:2299774637 realtimeReady:1 realtimeBaseCycles:8498651693578527 realtimeBaseRef:1582803485308155027 realtimeFrequency:2299774544} D0227 11:38:06.307688 52095 parameters.go:238] Clock(Monotonic): error: -43 ns, adjusted frequency from 2299774637 Hz to 2299774574 Hz D0227 11:38:06.307946 52095 parameters.go:238] Clock(Realtime): error: 809 ns, adjusted frequency from 2299774544 Hz to 2299776531 Hz D0227 11:38:06.308060 52095 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8498653992238954 monotonicBaseRef:34000822644 monotonicFrequency:2299774574 realtimeReady:1 realtimeBaseCycles:8498653992856257 realtimeBaseRef:1582803486307938999 realtimeFrequency:2299776531} D0227 11:38:07.308453 52095 parameters.go:238] Clock(Monotonic): error: 36 ns, adjusted frequency from 2299774574 Hz to 2299774735 Hz D0227 11:38:07.308689 52095 parameters.go:238] Clock(Realtime): error: -1121 ns, adjusted frequency from 2299776531 Hz to 2299772058 Hz D0227 11:38:07.308812 52095 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8498656293768957 monotonicBaseRef:35001585948 monotonicFrequency:2299774735 realtimeReady:1 realtimeBaseCycles:8498656294343501 realtimeBaseRef:1582803487308682859 realtimeFrequency:2299772058} D0227 11:38:08.309176 52095 parameters.go:238] Clock(Monotonic): error: 108 ns, adjusted frequency from 2299774735 Hz to 2299774845 Hz D0227 11:38:08.309478 52095 parameters.go:238] Clock(Realtime): error: 186 ns, adjusted frequency from 2299772058 Hz to 2299775089 Hz D0227 11:38:08.309624 52095 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8498658595107426 monotonicBaseRef:36002265898 monotonicFrequency:2299774845 realtimeReady:1 realtimeBaseCycles:8498658595887894 realtimeBaseRef:1582803488309453515 realtimeFrequency:2299775089} D0227 11:38:09.307888 52095 parameters.go:238] Clock(Monotonic): error: 152 ns, adjusted frequency from 2299774845 Hz to 2299774867 Hz D0227 11:38:09.308114 52095 parameters.go:238] Clock(Realtime): error: -224 ns, adjusted frequency from 2299775089 Hz to 2299774138 Hz D0227 11:38:09.308274 52095 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8498660891995582 monotonicBaseRef:37001010692 monotonicFrequency:2299774867 realtimeReady:1 realtimeBaseCycles:8498660892559013 realtimeBaseRef:1582803489308103830 realtimeFrequency:2299774138} D0227 11:38:10.307662 52095 parameters.go:238] Clock(Monotonic): error: -18 ns, adjusted frequency from 2299774867 Hz to 2299774457 Hz D0227 11:38:10.307897 52095 parameters.go:238] Clock(Realtime): error: 23 ns, adjusted frequency from 2299774138 Hz to 2299774664 Hz D0227 11:38:10.308026 52095 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8498663191269509 monotonicBaseRef:38000792870 monotonicFrequency:2299774457 realtimeReady:1 realtimeBaseCycles:8498663191840893 realtimeBaseRef:1582803490307889783 realtimeFrequency:2299774664} D0227 11:38:11.308582 52095 parameters.go:238] Clock(Monotonic): error: -271 ns, adjusted frequency from 2299774457 Hz to 2299773962 Hz D0227 11:38:11.308836 52095 parameters.go:238] Clock(Realtime): error: 273 ns, adjusted frequency from 2299774664 Hz to 2299775272 Hz D0227 11:38:11.308973 52095 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8498665493163999 monotonicBaseRef:39001714713 monotonicFrequency:2299773962 realtimeReady:1 realtimeBaseCycles:8498665493775576 realtimeBaseRef:1582803491308829013 realtimeFrequency:2299775272} D0227 11:38:12.308472 52095 parameters.go:238] Clock(Monotonic): error: -13 ns, adjusted frequency from 2299773962 Hz to 2299774558 Hz D0227 11:38:12.308714 52095 parameters.go:238] Clock(Realtime): error: -322 ns, adjusted frequency from 2299775272 Hz to 2299773913 Hz D0227 11:38:12.308840 52095 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8498667792682068 monotonicBaseRef:40001603444 monotonicFrequency:2299774558 realtimeReady:1 realtimeBaseCycles:8498667793271803 realtimeBaseRef:1582803492308707677 realtimeFrequency:2299773913} D0227 11:38:13.309767 52095 parameters.go:238] Clock(Monotonic): error: 32 ns, adjusted frequency from 2299774558 Hz to 2299774679 Hz D0227 11:38:13.309990 52095 parameters.go:238] Clock(Realtime): error: 283 ns, adjusted frequency from 2299773913 Hz to 2299775240 Hz D0227 11:38:13.310110 52095 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8498670095442924 monotonicBaseRef:41002901961 monotonicFrequency:2299774679 realtimeReady:1 realtimeBaseCycles:8498670095979602 realtimeBaseRef:1582803493309983405 realtimeFrequency:2299775240} D0227 11:38:14.307143 52095 parameters.go:238] Clock(Monotonic): error: -6 ns, adjusted frequency from 2299774679 Hz to 2299774567 Hz D0227 11:38:14.307379 52095 parameters.go:238] Clock(Realtime): error: 134 ns, adjusted frequency from 2299775240 Hz to 2299774947 Hz D0227 11:38:14.307520 52095 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8498672389174762 monotonicBaseRef:42000274381 monotonicFrequency:2299774567 realtimeReady:1 realtimeBaseCycles:8498672389750133 realtimeBaseRef:1582803494307372406 realtimeFrequency:2299774947} D0227 11:38:15.307198 52095 parameters.go:238] Clock(Monotonic): error: 331 ns, adjusted frequency from 2299774567 Hz to 2299775277 Hz D0227 11:38:15.307406 52095 parameters.go:238] Clock(Realtime): error: -265 ns, adjusted frequency from 2299774947 Hz to 2299773887 Hz D0227 11:38:15.307527 52095 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8498674689077067 monotonicBaseRef:43000329924 monotonicFrequency:2299775277 realtimeReady:1 realtimeBaseCycles:8498674689588438 realtimeBaseRef:1582803495307399955 realtimeFrequency:2299773887} D0227 11:38:16.307409 52095 parameters.go:238] Clock(Monotonic): error: -219 ns, adjusted frequency from 2299775277 Hz to 2299774104 Hz D0227 11:38:16.307657 52095 parameters.go:238] Clock(Realtime): error: 98 ns, adjusted frequency from 2299773887 Hz to 2299774738 Hz D0227 11:38:16.307838 52095 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8498676989333619 monotonicBaseRef:44000539194 monotonicFrequency:2299774104 realtimeReady:1 realtimeBaseCycles:8498676989930019 realtimeBaseRef:1582803496307646802 realtimeFrequency:2299774738} D0227 11:38:17.309003 52095 parameters.go:238] Clock(Monotonic): error: -100 ns, adjusted frequency from 2299774104 Hz to 2299774365 Hz D0227 11:38:17.309224 52095 parameters.go:238] Clock(Realtime): error: 159 ns, adjusted frequency from 2299774738 Hz to 2299774928 Hz D0227 11:38:17.309338 52095 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8498679292786381 monotonicBaseRef:45002138767 monotonicFrequency:2299774365 realtimeReady:1 realtimeBaseCycles:8498679293316401 realtimeBaseRef:1582803497309217235 realtimeFrequency:2299774928} I0227 11:38:17.604593 52095 watchdog.go:277] Watchdog starting loop, tasks: 13, discount: 0s D0227 11:38:18.307194 52095 parameters.go:238] Clock(Monotonic): error: 68 ns, adjusted frequency from 2299774365 Hz to 2299774829 Hz D0227 11:38:18.307973 52095 parameters.go:238] Clock(Realtime): error: 306 ns, adjusted frequency from 2299774928 Hz to 2299775304 Hz D0227 11:38:18.308105 52095 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8498681588383113 monotonicBaseRef:46000322226 monotonicFrequency:2299774829 realtimeReady:1 realtimeBaseCycles:8498681590210603 realtimeBaseRef:1582803498307964622 realtimeFrequency:2299775304} D0227 11:38:19.307186 52095 parameters.go:238] Clock(Monotonic): error: -67 ns, adjusted frequency from 2299774829 Hz to 2299774520 Hz D0227 11:38:19.307403 52095 parameters.go:238] Clock(Realtime): error: -399 ns, adjusted frequency from 2299775304 Hz to 2299773640 Hz D0227 11:38:19.307526 52095 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8498683888150969 monotonicBaseRef:47000319193 monotonicFrequency:2299774520 realtimeReady:1 realtimeBaseCycles:8498683888677412 realtimeBaseRef:1582803499307395655 realtimeFrequency:2299773640} D0227 11:38:20.307183 52095 parameters.go:238] Clock(Monotonic): error: -77 ns, adjusted frequency from 2299774520 Hz to 2299774426 Hz D0227 11:38:20.307415 52095 parameters.go:238] Clock(Realtime): error: -203 ns, adjusted frequency from 2299773640 Hz to 2299774143 Hz D0227 11:38:20.307557 52095 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8498686187917859 monotonicBaseRef:48000315875 monotonicFrequency:2299774426 realtimeReady:1 realtimeBaseCycles:8498686188480126 realtimeBaseRef:1582803500307408297 realtimeFrequency:2299774143} D0227 11:38:21.307190 52095 parameters.go:238] Clock(Monotonic): error: 339 ns, adjusted frequency from 2299774426 Hz to 2299775298 Hz D0227 11:38:21.307408 52095 parameters.go:238] Clock(Realtime): error: 219 ns, adjusted frequency from 2299774143 Hz to 2299775024 Hz D0227 11:38:21.307559 52095 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8498688487714490 monotonicBaseRef:49000325530 monotonicFrequency:2299775298 realtimeReady:1 realtimeBaseCycles:8498688488240614 realtimeBaseRef:1582803501307402359 realtimeFrequency:2299775024} D0227 11:38:22.308483 52095 parameters.go:238] Clock(Monotonic): error: 80 ns, adjusted frequency from 2299775298 Hz to 2299774710 Hz D0227 11:38:22.308726 52095 parameters.go:238] Clock(Realtime): error: 8 ns, adjusted frequency from 2299775024 Hz to 2299774612 Hz D0227 11:38:22.308876 52095 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8498690790431763 monotonicBaseRef:50001604774 monotonicFrequency:2299774710 realtimeReady:1 realtimeBaseCycles:8498690791046305 realtimeBaseRef:1582803502308720169 realtimeFrequency:2299774612} D0227 11:38:23.307239 52095 parameters.go:238] Clock(Monotonic): error: 18 ns, adjusted frequency from 2299774710 Hz to 2299774567 Hz D0227 11:38:23.307467 52095 parameters.go:238] Clock(Realtime): error: 47 ns, adjusted frequency from 2299774612 Hz to 2299774671 Hz D0227 11:38:23.307581 52095 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8498693087374146 monotonicBaseRef:51000373206 monotonicFrequency:2299774567 realtimeReady:1 realtimeBaseCycles:8498693087925011 realtimeBaseRef:1582803503307460956 realtimeFrequency:2299774671} D0227 11:38:24.307514 52095 parameters.go:238] Clock(Monotonic): error: -307 ns, adjusted frequency from 2299774567 Hz to 2299773972 Hz D0227 11:38:24.307752 52095 parameters.go:238] Clock(Realtime): error: -37 ns, adjusted frequency from 2299774671 Hz to 2299774475 Hz D0227 11:38:24.307894 52095 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8498695387782830 monotonicBaseRef:52000648936 monotonicFrequency:2299773972 realtimeReady:1 realtimeBaseCycles:8498695388355690 realtimeBaseRef:1582803504307746204 realtimeFrequency:2299774475} D0227 11:38:25.307414 52095 parameters.go:238] Clock(Monotonic): error: 343 ns, adjusted frequency from 2299773972 Hz to 2299775339 Hz D0227 11:38:25.307690 52095 parameters.go:238] Clock(Realtime): error: -58 ns, adjusted frequency from 2299774475 Hz to 2299774534 Hz D0227 11:38:25.307815 52095 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8498697687324230 monotonicBaseRef:53000547807 monotonicFrequency:2299775339 realtimeReady:1 realtimeBaseCycles:8498697687983520 realtimeBaseRef:1582803505307682439 realtimeFrequency:2299774534} D0227 11:38:26.036964 52095 urpc.go:577] urpc: unmarshal success. D0227 11:38:26.037204 52095 controller.go:501] containerManager.Signal &{CID:ci-gvisor-ptrace-proxy-sandbox-race-0 Signo:0 PID:0 Mode:Process} D0227 11:38:26.037485 52095 urpc.go:534] urpc: successfully marshalled 37 bytes. D0227 11:38:26.307175 52095 parameters.go:238] Clock(Monotonic): error: -374 ns, adjusted frequency from 2299775339 Hz to 2299773737 Hz D0227 11:38:26.307390 52095 parameters.go:238] Clock(Realtime): error: 122 ns, adjusted frequency from 2299774534 Hz to 2299774857 Hz D0227 11:38:26.307502 52095 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8498699986552821 monotonicBaseRef:54000310067 monotonicFrequency:2299773737 realtimeReady:1 realtimeBaseCycles:8498699987070850 realtimeBaseRef:1582803506307383625 realtimeFrequency:2299774857} D0227 11:38:27.307116 52095 parameters.go:238] Clock(Monotonic): error: -34 ns, adjusted frequency from 2299773737 Hz to 2299774511 Hz D0227 11:38:27.307320 52095 parameters.go:238] Clock(Realtime): error: -141 ns, adjusted frequency from 2299774857 Hz to 2299774244 Hz D0227 11:38:27.307410 52095 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8498702286191853 monotonicBaseRef:55000251493 monotonicFrequency:2299774511 realtimeReady:1 realtimeBaseCycles:8498702286685132 realtimeBaseRef:1582803507307313802 realtimeFrequency:2299774244} D0227 11:38:28.310745 52095 parameters.go:238] Clock(Monotonic): error: 75 ns, adjusted frequency from 2299774511 Hz to 2299774781 Hz D0227 11:38:28.310939 52095 parameters.go:238] Clock(Realtime): error: 76 ns, adjusted frequency from 2299774244 Hz to 2299774783 Hz D0227 11:38:28.311059 52095 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8498704594306470 monotonicBaseRef:56003877981 monotonicFrequency:2299774781 realtimeReady:1 realtimeBaseCycles:8498704594778666 realtimeBaseRef:1582803508310931239 realtimeFrequency:2299774783} D0227 11:38:29.307179 52095 parameters.go:238] Clock(Monotonic): error: 302 ns, adjusted frequency from 2299774781 Hz to 2299775249 Hz D0227 11:38:29.307384 52095 parameters.go:238] Clock(Realtime): error: -89 ns, adjusted frequency from 2299774783 Hz to 2299774370 Hz D0227 11:38:29.307494 52095 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8498706885888605 monotonicBaseRef:57000315612 monotonicFrequency:2299775249 realtimeReady:1 realtimeBaseCycles:8498706886385406 realtimeBaseRef:1582803509307379568 realtimeFrequency:2299774370} D0227 11:38:30.307291 52095 parameters.go:238] Clock(Monotonic): error: 2 ns, adjusted frequency from 2299775249 Hz to 2299774617 Hz D0227 11:38:30.307527 52095 parameters.go:238] Clock(Realtime): error: -143 ns, adjusted frequency from 2299774370 Hz to 2299774333 Hz D0227 11:38:30.307674 52095 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8498709185917343 monotonicBaseRef:58000425835 monotonicFrequency:2299774617 realtimeReady:1 realtimeBaseCycles:8498709186483427 realtimeBaseRef:1582803510307520299 realtimeFrequency:2299774333} D0227 11:38:31.307643 52095 parameters.go:238] Clock(Monotonic): error: 5 ns, adjusted frequency from 2299774617 Hz to 2299774652 Hz D0227 11:38:31.307863 52095 parameters.go:238] Clock(Realtime): error: 149 ns, adjusted frequency from 2299774333 Hz to 2299774959 Hz D0227 11:38:31.308009 52095 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8498711486501658 monotonicBaseRef:59000777912 monotonicFrequency:2299774652 realtimeReady:1 realtimeBaseCycles:8498711487009885 realtimeBaseRef:1582803511307847341 realtimeFrequency:2299774959} D0227 11:38:32.307136 52095 parameters.go:238] Clock(Monotonic): error: -354 ns, adjusted frequency from 2299774652 Hz to 2299773862 Hz D0227 11:38:32.307401 52095 parameters.go:238] Clock(Realtime): error: -20 ns, adjusted frequency from 2299774959 Hz to 2299774560 Hz D0227 11:38:32.307509 52095 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8498713785041441 monotonicBaseRef:60000240959 monotonicFrequency:2299773862 realtimeReady:1 realtimeBaseCycles:8498713785743873 realtimeBaseRef:1582803512307394700 realtimeFrequency:2299774560} D0227 11:38:33.307959 52095 parameters.go:238] Clock(Monotonic): error: 99 ns, adjusted frequency from 2299773862 Hz to 2299774811 Hz D0227 11:38:33.308231 52095 parameters.go:238] Clock(Realtime): error: 26 ns, adjusted frequency from 2299774560 Hz to 2299774718 Hz D0227 11:38:33.308486 52095 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8498716086735754 monotonicBaseRef:61001076019 monotonicFrequency:2299774811 realtimeReady:1 realtimeBaseCycles:8498716087410973 realtimeBaseRef:1582803513308217624 realtimeFrequency:2299774718} D0227 11:38:34.307459 52095 parameters.go:238] Clock(Monotonic): error: 194 ns, adjusted frequency from 2299774811 Hz to 2299774986 Hz D0227 11:38:34.307694 52095 parameters.go:238] Clock(Realtime): error: -248 ns, adjusted frequency from 2299774718 Hz to 2299774032 Hz D0227 11:38:34.307819 52095 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8498718385396738 monotonicBaseRef:62000591698 monotonicFrequency:2299774986 realtimeReady:1 realtimeBaseCycles:8498718385965778 realtimeBaseRef:1582803514307687175 realtimeFrequency:2299774032} D0227 11:38:35.307125 52095 parameters.go:238] Clock(Monotonic): error: -147 ns, adjusted frequency from 2299774986 Hz to 2299774259 Hz D0227 11:38:35.307853 52095 parameters.go:238] Clock(Realtime): error: 253 ns, adjusted frequency from 2299774032 Hz to 2299775163 Hz D0227 11:38:35.308000 52095 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8498720684390460 monotonicBaseRef:63000251984 monotonicFrequency:2299774259 realtimeReady:1 realtimeBaseCycles:8498720686108219 realtimeBaseRef:1582803515307847368 realtimeFrequency:2299775163} D0227 11:38:36.308314 52095 parameters.go:238] Clock(Monotonic): error: 31 ns, adjusted frequency from 2299774259 Hz to 2299774674 Hz D0227 11:38:36.308515 52095 parameters.go:238] Clock(Realtime): error: 156 ns, adjusted frequency from 2299775163 Hz to 2299774938 Hz D0227 11:38:36.308700 52095 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8498722986859793 monotonicBaseRef:64001423870 monotonicFrequency:2299774674 realtimeReady:1 realtimeBaseCycles:8498722987405097 realtimeBaseRef:1582803516308509047 realtimeFrequency:2299774938} D0227 11:38:37.307926 52095 parameters.go:238] Clock(Monotonic): error: 72 ns, adjusted frequency from 2299774674 Hz to 2299774709 Hz D0227 11:38:37.308179 52095 parameters.go:238] Clock(Realtime): error: -123 ns, adjusted frequency from 2299774938 Hz to 2299774319 Hz D0227 11:38:37.308293 52095 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8498725285801337 monotonicBaseRef:65001061604 monotonicFrequency:2299774709 realtimeReady:1 realtimeBaseCycles:8498725286404906 realtimeBaseRef:1582803517308172001 realtimeFrequency:2299774319} D0227 11:38:38.308557 52095 parameters.go:238] Clock(Monotonic): error: -101 ns, adjusted frequency from 2299774709 Hz to 2299774410 Hz D0227 11:38:38.308810 52095 parameters.go:238] Clock(Realtime): error: -311 ns, adjusted frequency from 2299774319 Hz to 2299773903 Hz D0227 11:38:38.308939 52095 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8498727587004251 monotonicBaseRef:66001682623 monotonicFrequency:2299774410 realtimeReady:1 realtimeBaseCycles:8498727587627071 realtimeBaseRef:1582803518308801561 realtimeFrequency:2299773903} D0227 11:38:39.307196 52095 parameters.go:238] Clock(Monotonic): error: 86 ns, adjusted frequency from 2299774410 Hz to 2299774797 Hz D0227 11:38:39.307404 52095 parameters.go:238] Clock(Realtime): error: 193 ns, adjusted frequency from 2299773903 Hz to 2299775003 Hz D0227 11:38:39.307493 52095 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8498729883647789 monotonicBaseRef:67000321240 monotonicFrequency:2299774797 realtimeReady:1 realtimeBaseCycles:8498729884176321 realtimeBaseRef:1582803519307399400 realtimeFrequency:2299775003} D0227 11:38:40.307210 52095 parameters.go:238] Clock(Monotonic): error: 53 ns, adjusted frequency from 2299774797 Hz to 2299774668 Hz D0227 11:38:40.307463 52095 parameters.go:238] Clock(Realtime): error: -152 ns, adjusted frequency from 2299775003 Hz to 2299774282 Hz D0227 11:38:40.307590 52095 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8498732183477192 monotonicBaseRef:68000344984 monotonicFrequency:2299774668 realtimeReady:1 realtimeBaseCycles:8498732184084266 realtimeBaseRef:1582803520307457206 realtimeFrequency:2299774282} D0227 11:38:41.308301 52095 parameters.go:238] Clock(Monotonic): error: -55 ns, adjusted frequency from 2299774668 Hz to 2299774431 Hz D0227 11:38:41.308543 52095 parameters.go:238] Clock(Realtime): error: -39 ns, adjusted frequency from 2299774282 Hz to 2299774542 Hz D0227 11:38:41.308646 52095 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8498734485762168 monotonicBaseRef:69001436529 monotonicFrequency:2299774431 realtimeReady:1 realtimeBaseCycles:8498734486340586 realtimeBaseRef:1582803521308536458 realtimeFrequency:2299774542} D0227 11:38:42.308725 52095 parameters.go:238] Clock(Monotonic): error: 86 ns, adjusted frequency from 2299774431 Hz to 2299774725 Hz D0227 11:38:42.308927 52095 parameters.go:238] Clock(Realtime): error: 256 ns, adjusted frequency from 2299774542 Hz to 2299775152 Hz D0227 11:38:42.309028 52095 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8498736786505508 monotonicBaseRef:70001857835 monotonicFrequency:2299774725 realtimeReady:1 realtimeBaseCycles:8498736787002121 realtimeBaseRef:1582803522308922144 realtimeFrequency:2299775152} D0227 11:38:43.308051 52095 parameters.go:238] Clock(Monotonic): error: -300 ns, adjusted frequency from 2299774725 Hz to 2299773986 Hz D0227 11:38:43.308295 52095 parameters.go:238] Clock(Realtime): error: 93 ns, adjusted frequency from 2299775152 Hz to 2299774702 Hz D0227 11:38:43.308446 52095 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8498739084738837 monotonicBaseRef:71001187597 monotonicFrequency:2299773986 realtimeReady:1 realtimeBaseCycles:8498739085319456 realtimeBaseRef:1582803523308288248 realtimeFrequency:2299774702} D0227 11:38:44.308214 52095 parameters.go:238] Clock(Monotonic): error: 63 ns, adjusted frequency from 2299773986 Hz to 2299774729 Hz D0227 11:38:44.308411 52095 parameters.go:238] Clock(Realtime): error: -92 ns, adjusted frequency from 2299774702 Hz to 2299774370 Hz D0227 11:38:44.308594 52095 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8498741384885500 monotonicBaseRef:72001349646 monotonicFrequency:2299774729 realtimeReady:1 realtimeBaseCycles:8498741385343491 realtimeBaseRef:1582803524308396664 realtimeFrequency:2299774370} D0227 11:38:45.307130 52095 parameters.go:238] Clock(Monotonic): error: 233 ns, adjusted frequency from 2299774729 Hz to 2299775065 Hz D0227 11:38:45.307369 52095 parameters.go:238] Clock(Realtime): error: 127 ns, adjusted frequency from 2299774370 Hz to 2299774880 Hz D0227 11:38:45.307465 52095 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8498743682172155 monotonicBaseRef:73000267768 monotonicFrequency:2299775065 realtimeReady:1 realtimeBaseCycles:8498743682741260 realtimeBaseRef:1582803525307363257 realtimeFrequency:2299774880} D0227 11:38:46.307134 52095 parameters.go:238] Clock(Monotonic): error: -246 ns, adjusted frequency from 2299775065 Hz to 2299774061 Hz D0227 11:38:46.307374 52095 parameters.go:238] Clock(Realtime): error: -249 ns, adjusted frequency from 2299774880 Hz to 2299774044 Hz D0227 11:38:46.307495 52095 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8498745981952533 monotonicBaseRef:74000270078 monotonicFrequency:2299774061 realtimeReady:1 realtimeBaseCycles:8498745982525755 realtimeBaseRef:1582803526307367437 realtimeFrequency:2299774044} D0227 11:38:47.307120 52095 parameters.go:238] Clock(Monotonic): error: 166 ns, adjusted frequency from 2299774061 Hz to 2299774892 Hz D0227 11:38:47.307300 52095 parameters.go:238] Clock(Realtime): error: 57 ns, adjusted frequency from 2299774044 Hz to 2299774651 Hz D0227 11:38:47.307436 52095 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8498748281702674 monotonicBaseRef:75000259676 monotonicFrequency:2299774892 realtimeReady:1 realtimeBaseCycles:8498748282132417 realtimeBaseRef:1582803527307294655 realtimeFrequency:2299774651} D0227 11:38:48.308845 52095 parameters.go:238] Clock(Monotonic): error: -199 ns, adjusted frequency from 2299774892 Hz to 2299774188 Hz D0227 11:38:48.309049 52095 parameters.go:238] Clock(Realtime): error: 148 ns, adjusted frequency from 2299774651 Hz to 2299774897 Hz D0227 11:38:48.309191 52095 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8498750585425386 monotonicBaseRef:76001976287 monotonicFrequency:2299774188 realtimeReady:1 realtimeBaseCycles:8498750585925889 realtimeBaseRef:1582803528309042139 realtimeFrequency:2299774897} D0227 11:38:49.307172 52095 parameters.go:238] Clock(Monotonic): error: 35 ns, adjusted frequency from 2299774188 Hz to 2299774730 Hz D0227 11:38:49.307426 52095 parameters.go:238] Clock(Realtime): error: 60 ns, adjusted frequency from 2299774897 Hz to 2299774647 Hz D0227 11:38:49.307547 52095 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8498752881360962 monotonicBaseRef:77000307161 monotonicFrequency:2299774730 realtimeReady:1 realtimeBaseCycles:8498752881967390 realtimeBaseRef:1582803529307418764 realtimeFrequency:2299774647} D0227 11:38:50.307214 52095 parameters.go:238] Clock(Monotonic): error: -52 ns, adjusted frequency from 2299774730 Hz to 2299774506 Hz D0227 11:38:50.307430 52095 parameters.go:238] Clock(Realtime): error: 38 ns, adjusted frequency from 2299774647 Hz to 2299774607 Hz D0227 11:38:50.307582 52095 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8498755181200689 monotonicBaseRef:78000335423 monotonicFrequency:2299774506 realtimeReady:1 realtimeBaseCycles:8498755181754955 realtimeBaseRef:1582803530307424381 realtimeFrequency:2299774607} D0227 11:38:51.307613 52095 parameters.go:238] Clock(Monotonic): error: 38 ns, adjusted frequency from 2299774506 Hz to 2299774720 Hz D0227 11:38:51.307834 52095 parameters.go:238] Clock(Realtime): error: -124 ns, adjusted frequency from 2299774607 Hz to 2299774296 Hz D0227 11:38:51.308053 52095 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8498757481900165 monotonicBaseRef:79000737623 monotonicFrequency:2299774720 realtimeReady:1 realtimeBaseCycles:8498757482457723 realtimeBaseRef:1582803531307827968 realtimeFrequency:2299774296} D0227 11:38:52.307242 52095 parameters.go:238] Clock(Monotonic): error: 35 ns, adjusted frequency from 2299774720 Hz to 2299774634 Hz D0227 11:38:52.307509 52095 parameters.go:238] Clock(Realtime): error: 169 ns, adjusted frequency from 2299774296 Hz to 2299775010 Hz D0227 11:38:52.307640 52095 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8498759780762959 monotonicBaseRef:80000341094 monotonicFrequency:2299774634 realtimeReady:1 realtimeBaseCycles:8498759781478814 realtimeBaseRef:1582803532307500455 realtimeFrequency:2299775010} D0227 11:38:53.308272 52095 parameters.go:238] Clock(Monotonic): error: 218 ns, adjusted frequency from 2299774634 Hz to 2299775056 Hz D0227 11:38:53.308518 52095 parameters.go:238] Clock(Realtime): error: -353 ns, adjusted frequency from 2299775010 Hz to 2299773846 Hz D0227 11:38:53.308651 52095 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8498762082993398 monotonicBaseRef:81001408939 monotonicFrequency:2299775056 realtimeReady:1 realtimeBaseCycles:8498762083579652 realtimeBaseRef:1582803533308511783 realtimeFrequency:2299773846} D0227 11:38:54.308061 52095 parameters.go:238] Clock(Monotonic): error: -242 ns, adjusted frequency from 2299775056 Hz to 2299774056 Hz D0227 11:38:54.308302 52095 parameters.go:238] Clock(Realtime): error: 661 ns, adjusted frequency from 2299773846 Hz to 2299776165 Hz D0227 11:38:54.308481 52095 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8498764382281123 monotonicBaseRef:82001197035 monotonicFrequency:2299774056 realtimeReady:1 realtimeBaseCycles:8498764382854149 realtimeBaseRef:1582803534308294653 realtimeFrequency:2299776165} D0227 11:38:55.308247 52095 parameters.go:238] Clock(Monotonic): error: 18 ns, adjusted frequency from 2299774056 Hz to 2299774648 Hz D0227 11:38:55.308478 52095 parameters.go:238] Clock(Realtime): error: -368 ns, adjusted frequency from 2299776165 Hz to 2299773643 Hz D0227 11:38:55.308601 52095 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8498766682478326 monotonicBaseRef:83001381030 monotonicFrequency:2299774648 realtimeReady:1 realtimeBaseCycles:8498766683035902 realtimeBaseRef:1582803535308471012 realtimeFrequency:2299773643} D0227 11:38:56.307434 52095 parameters.go:238] Clock(Monotonic): error: -57 ns, adjusted frequency from 2299774648 Hz to 2299774537 Hz D0227 11:38:56.307813 52095 parameters.go:238] Clock(Realtime): error: -314 ns, adjusted frequency from 2299773643 Hz to 2299773944 Hz D0227 11:38:56.307993 52095 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8498768980376029 monotonicBaseRef:84000564886 monotonicFrequency:2299774537 realtimeReady:1 realtimeBaseCycles:8498768981278688 realtimeBaseRef:1582803536307805356 realtimeFrequency:2299773944} D0227 11:38:57.307496 52095 parameters.go:238] Clock(Monotonic): error: -57 ns, adjusted frequency from 2299774537 Hz to 2299774465 Hz D0227 11:38:57.307743 52095 parameters.go:238] Clock(Realtime): error: 123 ns, adjusted frequency from 2299773944 Hz to 2299774961 Hz D0227 11:38:57.307931 52095 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8498771280301065 monotonicBaseRef:85000630326 monotonicFrequency:2299774465 realtimeReady:1 realtimeBaseCycles:8498771280893577 realtimeBaseRef:1582803537307736194 realtimeFrequency:2299774961} D0227 11:38:58.308127 52095 parameters.go:238] Clock(Monotonic): error: 245 ns, adjusted frequency from 2299774465 Hz to 2299775071 Hz D0227 11:38:58.308421 52095 parameters.go:238] Clock(Realtime): error: -135 ns, adjusted frequency from 2299774961 Hz to 2299774367 Hz D0227 11:38:58.308547 52095 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8498773581522577 monotonicBaseRef:86001259538 monotonicFrequency:2299775071 realtimeReady:1 realtimeBaseCycles:8498773582218323 realtimeBaseRef:1582803538308410079 realtimeFrequency:2299774367} D0227 11:38:58.999002 52095 urpc.go:577] urpc: unmarshal success. D0227 11:38:58.999288 52095 controller.go:501] containerManager.Signal &{CID:ci-gvisor-ptrace-proxy-sandbox-race-0 Signo:0 PID:0 Mode:Process} D0227 11:38:58.999566 52095 urpc.go:534] urpc: successfully marshalled 37 bytes. D0227 11:38:59.307606 52095 parameters.go:238] Clock(Monotonic): error: -189 ns, adjusted frequency from 2299775071 Hz to 2299774158 Hz D0227 11:38:59.307805 52095 parameters.go:238] Clock(Realtime): error: 97 ns, adjusted frequency from 2299774367 Hz to 2299774807 Hz D0227 11:38:59.307918 52095 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8498775880107109 monotonicBaseRef:87000741861 monotonicFrequency:2299774158 realtimeReady:1 realtimeBaseCycles:8498775880590129 realtimeBaseRef:1582803539307800210 realtimeFrequency:2299774807} D0227 11:39:00.307818 52095 parameters.go:238] Clock(Monotonic): error: 135 ns, adjusted frequency from 2299774158 Hz to 2299774948 Hz D0227 11:39:00.308651 52095 parameters.go:238] Clock(Realtime): error: -139 ns, adjusted frequency from 2299774807 Hz to 2299774317 Hz D0227 11:39:00.308803 52095 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8498778178976280 monotonicBaseRef:88000348349 monotonicFrequency:2299774948 realtimeReady:1 realtimeBaseCycles:8498778182298882 realtimeBaseRef:1582803540308641138 realtimeFrequency:2299774317} I0227 11:39:00.323992 61084 main.go:296] *************************** I0227 11:39:00.324180 61084 main.go:297] Args: [/syzkaller/managers/ptrace-proxy-sandbox-race/current/image -root /syzkaller/managers/ptrace-proxy-sandbox-race/workdir/gvisor_root -watchdog-action=panic -network=none -debug -alsologtostderr -platform=ptrace -file-access=shared -network=sandbox -TESTONLY-unsafe-nonroot exec -user=0:0 -cap CAP_CHOWN -cap CAP_DAC_OVERRIDE -cap CAP_DAC_READ_SEARCH -cap CAP_FOWNER -cap CAP_FSETID -cap CAP_KILL -cap CAP_SETGID -cap CAP_SETUID -cap CAP_SETPCAP -cap CAP_LINUX_IMMUTABLE -cap CAP_NET_BIND_SERVICE -cap CAP_NET_BROADCAST -cap CAP_NET_ADMIN -cap CAP_NET_RAW -cap CAP_IPC_LOCK -cap CAP_IPC_OWNER -cap CAP_SYS_MODULE -cap CAP_SYS_RAWIO -cap CAP_SYS_CHROOT -cap CAP_SYS_PTRACE -cap CAP_SYS_PACCT -cap CAP_SYS_ADMIN -cap CAP_SYS_BOOT -cap CAP_SYS_NICE -cap CAP_SYS_RESOURCE -cap CAP_SYS_TIME -cap CAP_SYS_TTY_CONFIG -cap CAP_MKNOD -cap CAP_LEASE -cap CAP_AUDIT_WRITE -cap CAP_AUDIT_CONTROL -cap CAP_SETFCAP -cap CAP_MAC_OVERRIDE -cap CAP_MAC_ADMIN -cap CAP_SYSLOG -cap CAP_WAKE_ALARM -cap CAP_BLOCK_SUSPEND -cap CAP_AUDIT_READ ci-gvisor-ptrace-proxy-sandbox-race-0 /syz-execprog -executor=/syz-executor -arch=amd64 -sandbox=none -procs=1 -repeat=1 -threaded=true -collide=false -cover=0 -fault_call=-1 -fault_nth=-1 /syzkaller236421679] I0227 11:39:00.324714 61084 main.go:298] Version release-20200219.0-47-gde0b2ebf8635 I0227 11:39:00.324781 61084 main.go:299] PID: 61084 I0227 11:39:00.324894 61084 main.go:300] UID: 0, GID: 0 I0227 11:39:00.324991 61084 main.go:301] Configuration: I0227 11:39:00.325094 61084 main.go:302] RootDir: /syzkaller/managers/ptrace-proxy-sandbox-race/workdir/gvisor_root I0227 11:39:00.325191 61084 main.go:303] Platform: ptrace I0227 11:39:00.325304 61084 main.go:304] FileAccess: shared, overlay: false I0227 11:39:00.325427 61084 main.go:305] Network: sandbox, logging: false I0227 11:39:00.325540 61084 main.go:306] Strace: false, max size: 1024, syscalls: [] I0227 11:39:00.325642 61084 main.go:307] *************************** W0227 11:39:00.325731 61084 main.go:312] Block the TERM signal. This is only safe in tests! D0227 11:39:00.326183 61084 container.go:158] Load container "/syzkaller/managers/ptrace-proxy-sandbox-race/workdir/gvisor_root" "ci-gvisor-ptrace-proxy-sandbox-race-0" D0227 11:39:00.334897 61084 container.go:591] Signal container "ci-gvisor-ptrace-proxy-sandbox-race-0": signal 0 D0227 11:39:00.335115 61084 sandbox.go:788] Signal sandbox "ci-gvisor-ptrace-proxy-sandbox-race-0" D0227 11:39:00.335274 61084 sandbox.go:318] Connecting to sandbox "ci-gvisor-ptrace-proxy-sandbox-race-0" D0227 11:39:00.335938 61084 urpc.go:534] urpc: successfully marshalled 117 bytes. D0227 11:39:00.336178 52095 urpc.go:577] urpc: unmarshal success. D0227 11:39:00.336450 52095 controller.go:501] containerManager.Signal &{CID:ci-gvisor-ptrace-proxy-sandbox-race-0 Signo:0 PID:0 Mode:Process} D0227 11:39:00.336726 52095 urpc.go:534] urpc: successfully marshalled 37 bytes. D0227 11:39:00.336960 61084 urpc.go:577] urpc: unmarshal success. D0227 11:39:00.337183 61084 exec.go:120] Exec arguments: /syz-execprog -executor=/syz-executor -arch=amd64 -sandbox=none -procs=1 -repeat=1 -threaded=true -collide=false -cover=0 -fault_call=-1 -fault_nth=-1 /syzkaller236421679 D0227 11:39:00.337360 61084 exec.go:121] Exec capablities: &{PermittedCaps:274877898751 InheritableCaps:274877898751 EffectiveCaps:274877898751 BoundingCaps:274877898751 AmbientCaps:0} D0227 11:39:00.337548 61084 container.go:532] Execute in container "ci-gvisor-ptrace-proxy-sandbox-race-0", args: /syz-execprog -executor=/syz-executor -arch=amd64 -sandbox=none -procs=1 -repeat=1 -threaded=true -collide=false -cover=0 -fault_call=-1 -fault_nth=-1 /syzkaller236421679 D0227 11:39:00.337707 61084 sandbox.go:283] Executing new process in container "ci-gvisor-ptrace-proxy-sandbox-race-0" in sandbox "ci-gvisor-ptrace-proxy-sandbox-race-0" D0227 11:39:00.337849 61084 sandbox.go:318] Connecting to sandbox "ci-gvisor-ptrace-proxy-sandbox-race-0" D0227 11:39:00.339000 61084 urpc.go:534] urpc: successfully marshalled 635 bytes. D0227 11:39:00.339378 52095 urpc.go:577] urpc: unmarshal success. D0227 11:39:00.340595 52095 controller.go:267] containerManager.ExecuteAsync: /syz-execprog -executor=/syz-executor -arch=amd64 -sandbox=none -procs=1 -repeat=1 -threaded=true -collide=false -cover=0 -fault_call=-1 -fault_nth=-1 /syzkaller236421679 D0227 11:39:00.340887 52095 transport_flipcall.go:127] send [channel @0xc00018a240] Twalkgetattr{FID: 1, NewFID: 5, Names: [etc]} D0227 11:39:00.342041 52095 transport_flipcall.go:234] recv [channel @0xc00018a240] Rlerror{Error: 2} D0227 11:39:00.342195 52095 transport_flipcall.go:127] send [channel @0xc00018a240] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.etc, Size: 1} D0227 11:39:00.343411 52095 transport_flipcall.go:234] recv [channel @0xc00018a240] Rlerror{Error: 95} I0227 11:39:00.343895 52095 kernel.go:795] EXEC: [/syz-execprog -executor=/syz-executor -arch=amd64 -sandbox=none -procs=1 -repeat=1 -threaded=true -collide=false -cover=0 -fault_call=-1 -fault_nth=-1 /syzkaller236421679] D0227 11:39:00.344743 52095 transport_flipcall.go:127] send [channel @0xc00018a240] Twalkgetattr{FID: 1, NewFID: 5, Names: [syz-execprog]} D0227 11:39:00.346449 52095 transport_flipcall.go:234] recv [channel @0xc00018a240] 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: 1582792705, NanoSec: 196725805}, MTime: {Sec: 1582792705, NanoSec: 196725805}, CTime: {Sec: 1582803453, NanoSec: 885363554}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}, QIDs: [QID{Type: 0, Version: 0, Path: 16123479}]} D0227 11:39:00.347159 52095 transport_flipcall.go:127] send [channel @0xc00018a240] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syz-execprog, Size: 1} D0227 11:39:00.347730 52095 transport_flipcall.go:234] recv [channel @0xc00018a240] Rlerror{Error: 95} D0227 11:39:00.348120 52095 transport_flipcall.go:127] send [channel @0xc00018a240] Tgetattr{FID: 5, AttrMask: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime INo Size Blocks BTime Gen DataVersion}} D0227 11:39:00.349061 52095 transport_flipcall.go:234] recv [channel @0xc00018a240] Rgetattr{Valid: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime Size Blocks}, QID: QID{Type: 0, Version: 0, Path: 16123479}, Attr: Attr{Mode: 0o100755, UID: 0, GID: 0, NLink: 1, RDev: 0, Size: 24119712, BlockSize: 4096, Blocks: 47112, ATime: {Sec: 1582792705, NanoSec: 196725805}, MTime: {Sec: 1582792705, NanoSec: 196725805}, CTime: {Sec: 1582803453, NanoSec: 885363554}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}} D0227 11:39:00.349236 52095 transport_flipcall.go:127] send [channel @0xc00018a240] Twalk{FID: 5, NewFID: 6, Names: []} D0227 11:39:00.349876 52095 transport_flipcall.go:234] recv [channel @0xc00018a240] Rwalk{QIDs: []} D0227 11:39:00.350012 52095 transport_flipcall.go:127] send [channel @0xc00018a240] Tlopen{FID: 6, Flags: ReadOnly} D0227 11:39:00.350739 52095 transport_flipcall.go:234] recv [channel @0xc00018a240] Rlopen{QID: QID{Type: 0, Version: 0, Path: 16123479}, IoUnit: 0, File: &{{33}}} D0227 11:39:00.355681 52095 syscalls.go:266] Allocating stack with size of 8388608 bytes D0227 11:39:00.357048 52095 loader.go:832] updated processes: map[{ci-gvisor-ptrace-proxy-sandbox-race-0 0}:0xc000406080 {ci-gvisor-ptrace-proxy-sandbox-race-0 19}:0xc00060a080] D0227 11:39:00.357396 52095 urpc.go:534] urpc: successfully marshalled 37 bytes. D0227 11:39:00.357537 61084 urpc.go:577] urpc: unmarshal success. D0227 11:39:00.357681 61084 container.go:579] Wait on PID 19 in container "ci-gvisor-ptrace-proxy-sandbox-race-0" D0227 11:39:00.357790 61084 sandbox.go:743] Waiting for PID 19 in sandbox "ci-gvisor-ptrace-proxy-sandbox-race-0" D0227 11:39:00.357885 61084 sandbox.go:318] Connecting to sandbox "ci-gvisor-ptrace-proxy-sandbox-race-0" D0227 11:39:00.358205 61084 urpc.go:534] urpc: successfully marshalled 100 bytes. D0227 11:39:00.358537 52095 urpc.go:577] urpc: unmarshal success. D0227 11:39:00.358821 52095 controller.go:443] containerManager.Wait D0227 11:39:00.360139 52095 task_block.go:223] [ 19] Interrupt queued D0227 11:39:00.592956 52095 task_block.go:223] [ 20] Interrupt queued D0227 11:39:00.601257 52095 task_block.go:223] [ 21] Interrupt queued D0227 11:39:00.602102 52095 task_block.go:223] [ 22] Interrupt queued D0227 11:39:00.605702 52095 task_block.go:223] [ 23] Interrupt queued D0227 11:39:00.631052 52095 task_block.go:223] [ 24] Interrupt queued D0227 11:39:00.637235 52095 task_block.go:223] [ 25] Interrupt queued D0227 11:39:00.638964 52095 task_block.go:223] [ 26] Interrupt queued D0227 11:39:00.691054 52095 transport_flipcall.go:127] send [channel @0xc00018a240] Twalkgetattr{FID: 1, NewFID: 7, Names: [etc]} D0227 11:39:00.691671 52095 transport_flipcall.go:234] recv [channel @0xc00018a240] Rlerror{Error: 2} D0227 11:39:00.691929 52095 transport_flipcall.go:127] send [channel @0xc00018a240] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.etc, Size: 1} D0227 11:39:00.692486 52095 transport_flipcall.go:234] recv [channel @0xc00018a240] Rlerror{Error: 95} D0227 11:39:00.741334 52095 task_block.go:223] [ 27] Interrupt queued D0227 11:39:00.753118 52095 task_block.go:223] [ 28] Interrupt queued D0227 11:39:00.759729 52095 task_block.go:223] [ 29] Interrupt queued D0227 11:39:00.798396 52095 task_block.go:223] [ 30] Interrupt queued D0227 11:39:00.805663 52095 task_block.go:223] [ 31] Interrupt queued D0227 11:39:00.845203 52095 task_block.go:223] [ 32] Interrupt queued D0227 11:39:00.854846 52095 transport_flipcall.go:127] send [channel @0xc00018a240] Twalkgetattr{FID: 1, NewFID: 7, Names: [syzkaller236421679]} D0227 11:39:00.855297 52095 task_block.go:223] [ 33] Interrupt queued D0227 11:39:00.855967 52095 transport_flipcall.go:234] recv [channel @0xc00018a240] 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: 199, BlockSize: 4096, Blocks: 8, ATime: {Sec: 1582803540, NanoSec: 285831041}, MTime: {Sec: 1582803540, NanoSec: 285831041}, CTime: {Sec: 1582803540, NanoSec: 289831433}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}, QIDs: [QID{Type: 0, Version: 0, Path: 16121864}]} D0227 11:39:00.856212 52095 transport_flipcall.go:127] send [channel @0xc00018a240] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzkaller236421679, Size: 1} D0227 11:39:00.856701 52095 transport_flipcall.go:234] recv [channel @0xc00018a240] Rlerror{Error: 95} D0227 11:39:00.857027 52095 transport_flipcall.go:127] send [channel @0xc00018a240] Tgetattr{FID: 7, AttrMask: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime INo Size Blocks BTime Gen DataVersion}} D0227 11:39:00.857906 52095 transport_flipcall.go:234] recv [channel @0xc00018a240] Rgetattr{Valid: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime Size Blocks}, QID: QID{Type: 0, Version: 0, Path: 16121864}, Attr: Attr{Mode: 0o100600, UID: 0, GID: 0, NLink: 1, RDev: 0, Size: 199, BlockSize: 4096, Blocks: 8, ATime: {Sec: 1582803540, NanoSec: 285831041}, MTime: {Sec: 1582803540, NanoSec: 285831041}, CTime: {Sec: 1582803540, NanoSec: 289831433}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}} D0227 11:39:00.858167 52095 transport_flipcall.go:127] send [channel @0xc00018a240] Twalk{FID: 7, NewFID: 8, Names: []} D0227 11:39:00.858781 52095 transport_flipcall.go:234] recv [channel @0xc00018a240] Rwalk{QIDs: []} D0227 11:39:00.858979 52095 transport_flipcall.go:127] send [channel @0xc00018a240] Tlopen{FID: 8, Flags: ReadOnly} D0227 11:39:00.859660 52095 transport_flipcall.go:234] recv [channel @0xc00018a240] Rlopen{QID: QID{Type: 0, Version: 0, Path: 16121864}, IoUnit: 0, File: &{{27}}} D0227 11:39:00.864816 52095 transport_flipcall.go:127] send [channel @0xc00018a240] Tgetattr{FID: 8, AttrMask: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime INo Size Blocks BTime Gen DataVersion}} D0227 11:39:00.865928 52095 transport_flipcall.go:234] recv [channel @0xc00018a240] Rgetattr{Valid: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime Size Blocks}, QID: QID{Type: 0, Version: 0, Path: 16121864}, Attr: Attr{Mode: 0o100600, UID: 0, GID: 0, NLink: 1, RDev: 0, Size: 199, BlockSize: 4096, Blocks: 8, ATime: {Sec: 1582803540, NanoSec: 285831041}, MTime: {Sec: 1582803540, NanoSec: 285831041}, CTime: {Sec: 1582803540, NanoSec: 289831433}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}} 2020/02/27 11:39:00 parsed 1 programs D0227 11:39:00.874489 52095 task_stop.go:118] [ 27] Entering internal stop (*kernel.vforkStop)(nil) D0227 11:39:00.875215 52095 task_block.go:223] [ 34] Interrupt queued D0227 11:39:00.892726 52095 transport_flipcall.go:127] send [channel @0xc00018a240] Twalkgetattr{FID: 1, NewFID: 9, Names: [syz-executor]} D0227 11:39:00.894340 52095 transport_flipcall.go:234] recv [channel @0xc00018a240] 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: 1582792705, NanoSec: 424748149}, MTime: {Sec: 1582792705, NanoSec: 424748149}, CTime: {Sec: 1582803453, NanoSec: 889363946}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}, QIDs: [QID{Type: 0, Version: 0, Path: 16123486}]} D0227 11:39:00.894744 52095 transport_flipcall.go:127] send [channel @0xc00018a240] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syz-executor, Size: 1} D0227 11:39:00.895193 52095 transport_flipcall.go:234] recv [channel @0xc00018a240] Rlerror{Error: 95} D0227 11:39:00.895502 52095 transport_flipcall.go:127] send [channel @0xc00018a240] Tgetattr{FID: 9, AttrMask: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime INo Size Blocks BTime Gen DataVersion}} D0227 11:39:00.896534 52095 transport_flipcall.go:234] recv [channel @0xc00018a240] Rgetattr{Valid: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime Size Blocks}, QID: QID{Type: 0, Version: 0, Path: 16123486}, Attr: Attr{Mode: 0o100755, UID: 0, GID: 0, NLink: 1, RDev: 0, Size: 1579448, BlockSize: 4096, Blocks: 3088, ATime: {Sec: 1582792705, NanoSec: 424748149}, MTime: {Sec: 1582792705, NanoSec: 424748149}, CTime: {Sec: 1582803453, NanoSec: 889363946}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}} D0227 11:39:00.896755 52095 transport_flipcall.go:127] send [channel @0xc00018a240] Twalk{FID: 9, NewFID: 10, Names: []} D0227 11:39:00.897523 52095 transport_flipcall.go:234] recv [channel @0xc00018a240] Rwalk{QIDs: []} D0227 11:39:00.897856 52095 transport_flipcall.go:127] send [channel @0xc00018a240] Tlopen{FID: 10, Flags: ReadOnly} D0227 11:39:00.898751 52095 transport_flipcall.go:234] recv [channel @0xc00018a240] Rlopen{QID: QID{Type: 0, Version: 0, Path: 16123486}, IoUnit: 0, File: &{{28}}} D0227 11:39:00.905901 52095 syscalls.go:266] [ 34] Allocating stack with size of 8388608 bytes D0227 11:39:00.906775 52095 task_stop.go:137] [ 27] Leaving internal stop (*kernel.vforkStop)(nil) D0227 11:39:00.937454 52095 transport_flipcall.go:127] send [channel @0xc00018a240] Twalkgetattr{FID: 1, NewFID: 11, Names: [etc]} D0227 11:39:00.938148 52095 transport_flipcall.go:234] recv [channel @0xc00018a240] Rlerror{Error: 2} D0227 11:39:00.938334 52095 transport_flipcall.go:127] send [channel @0xc00018a240] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.etc, Size: 1} D0227 11:39:00.938874 52095 transport_flipcall.go:234] recv [channel @0xc00018a240] Rlerror{Error: 95} D0227 11:39:00.940710 52095 task_exit.go:221] [ 34] Transitioning from exit state TaskExitNone to TaskExitInitiated D0227 11:39:00.942205 52095 task_exit.go:221] [ 34] Transitioning from exit state TaskExitInitiated to TaskExitZombie D0227 11:39:00.942374 52095 task_signals.go:446] [ 19] Notified of signal 17 D0227 11:39:00.942491 52095 task_block.go:223] [ 19] Interrupt queued D0227 11:39:00.942604 52095 task_block.go:223] [ 19] Interrupt queued D0227 11:39:00.942835 52095 task_signals.go:179] [ 19] Restarting syscall 202 after errno 512: interrupted by signal 17 D0227 11:39:00.942968 52095 task_signals.go:220] [ 19] Signal 17: delivering to handler D0227 11:39:00.943055 52095 task_exit.go:221] [ 34] Transitioning from exit state TaskExitZombie to TaskExitDead D0227 11:39:00.945051 52095 task_block.go:223] [ 35] Interrupt queued D0227 11:39:00.947918 52095 transport_flipcall.go:127] send [channel @0xc00018a240] Twalkgetattr{FID: 1, NewFID: 11, Names: [syz-executor]} D0227 11:39:00.948901 52095 transport_flipcall.go:234] recv [channel @0xc00018a240] 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: 1582792705, NanoSec: 424748149}, MTime: {Sec: 1582792705, NanoSec: 424748149}, CTime: {Sec: 1582803453, NanoSec: 889363946}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}, QIDs: [QID{Type: 0, Version: 0, Path: 16123486}]} D0227 11:39:00.949038 52095 transport_flipcall.go:127] send [channel @0xc00018a240] Tclunk{FID: 11} D0227 11:39:00.949447 52095 transport_flipcall.go:234] recv [channel @0xc00018a240] Rclunk{} D0227 11:39:00.949633 52095 transport_flipcall.go:127] send [channel @0xc00018a240] Tgetattr{FID: 9, AttrMask: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime INo Size Blocks BTime Gen DataVersion}} D0227 11:39:00.950441 52095 transport_flipcall.go:234] recv [channel @0xc00018a240] Rgetattr{Valid: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime Size Blocks}, QID: QID{Type: 0, Version: 0, Path: 16123486}, Attr: Attr{Mode: 0o100755, UID: 0, GID: 0, NLink: 1, RDev: 0, Size: 1579448, BlockSize: 4096, Blocks: 3088, ATime: {Sec: 1582792705, NanoSec: 424748149}, MTime: {Sec: 1582792705, NanoSec: 424748149}, CTime: {Sec: 1582803453, NanoSec: 889363946}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}} 2020/02/27 11:39:00 executed programs: 0 D0227 11:39:00.955850 52095 task_stop.go:118] [ 24] Entering internal stop (*kernel.vforkStop)(nil) D0227 11:39:00.956526 52095 task_block.go:223] [ 36] Interrupt queued D0227 11:39:00.961742 52095 transport_flipcall.go:127] send [channel @0xc00018a240] Twalkgetattr{FID: 1, NewFID: 11, Names: [syz-executor]} D0227 11:39:00.962786 52095 transport_flipcall.go:234] recv [channel @0xc00018a240] 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: 1582792705, NanoSec: 424748149}, MTime: {Sec: 1582792705, NanoSec: 424748149}, CTime: {Sec: 1582803453, NanoSec: 889363946}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}, QIDs: [QID{Type: 0, Version: 0, Path: 16123486}]} D0227 11:39:00.962915 52095 transport_flipcall.go:127] send [channel @0xc00018a240] Tclunk{FID: 11} D0227 11:39:00.963450 52095 transport_flipcall.go:234] recv [channel @0xc00018a240] Rclunk{} D0227 11:39:00.963656 52095 transport_flipcall.go:127] send [channel @0xc00018a240] Tgetattr{FID: 9, AttrMask: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime INo Size Blocks BTime Gen DataVersion}} D0227 11:39:00.964900 52095 transport_flipcall.go:234] recv [channel @0xc00018a240] Rgetattr{Valid: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime Size Blocks}, QID: QID{Type: 0, Version: 0, Path: 16123486}, Attr: Attr{Mode: 0o100755, UID: 0, GID: 0, NLink: 1, RDev: 0, Size: 1579448, BlockSize: 4096, Blocks: 3088, ATime: {Sec: 1582792705, NanoSec: 424748149}, MTime: {Sec: 1582792705, NanoSec: 424748149}, CTime: {Sec: 1582803453, NanoSec: 889363946}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}} D0227 11:39:00.966501 52095 syscalls.go:266] [ 36] Allocating stack with size of 8388608 bytes D0227 11:39:00.967841 52095 task_stop.go:137] [ 24] Leaving internal stop (*kernel.vforkStop)(nil) D0227 11:39:00.975232 52095 transport_flipcall.go:127] send [channel @0xc00018a240] Twalkgetattr{FID: 1, NewFID: 11, Names: [etc]} D0227 11:39:00.975755 52095 transport_flipcall.go:234] recv [channel @0xc00018a240] Rlerror{Error: 2} D0227 11:39:00.975964 52095 transport_flipcall.go:127] send [channel @0xc00018a240] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.etc, Size: 1} D0227 11:39:00.976410 52095 transport_flipcall.go:234] recv [channel @0xc00018a240] Rlerror{Error: 95} D0227 11:39:00.987199 52095 task_block.go:223] [ 37] Interrupt queued D0227 11:39:00.998206 52095 transport_flipcall.go:127] send [channel @0xc00018a240] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0227 11:39:00.998881 52095 transport_flipcall.go:234] recv [channel @0xc00018a240] Rlerror{Error: 2} D0227 11:39:00.999141 52095 transport_flipcall.go:127] send [channel @0xc00018a240] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0227 11:39:00.999673 52095 transport_flipcall.go:234] recv [channel @0xc00018a240] Rlerror{Error: 95} D0227 11:39:01.000044 52095 transport_flipcall.go:127] send [channel @0xc00018a240] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0227 11:39:01.000759 52095 transport_flipcall.go:234] recv [channel @0xc00018a240] Rlerror{Error: 2} D0227 11:39:01.000968 52095 transport_flipcall.go:127] send [channel @0xc00018a240] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0227 11:39:01.001512 52095 transport_flipcall.go:234] recv [channel @0xc00018a240] Rlerror{Error: 95} D0227 11:39:01.002022 52095 transport_flipcall.go:127] send [channel @0xc00018a240] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0227 11:39:01.002666 52095 transport_flipcall.go:234] recv [channel @0xc00018a240] Rlerror{Error: 2} D0227 11:39:01.002899 52095 transport_flipcall.go:127] send [channel @0xc00018a240] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0227 11:39:01.003505 52095 transport_flipcall.go:234] recv [channel @0xc00018a240] Rlerror{Error: 95} D0227 11:39:01.004008 52095 transport_flipcall.go:127] send [channel @0xc00018a240] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0227 11:39:01.004585 52095 transport_flipcall.go:234] recv [channel @0xc00018a240] Rlerror{Error: 2} D0227 11:39:01.004775 52095 transport_flipcall.go:127] send [channel @0xc00018a240] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0227 11:39:01.005240 52095 transport_flipcall.go:234] recv [channel @0xc00018a240] Rlerror{Error: 95} D0227 11:39:01.005597 52095 transport_flipcall.go:127] send [channel @0xc00018a240] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0227 11:39:01.005997 52095 transport_flipcall.go:234] recv [channel @0xc00018a240] Rlerror{Error: 2} D0227 11:39:01.006116 52095 transport_flipcall.go:127] send [channel @0xc00018a240] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0227 11:39:01.006834 52095 transport_flipcall.go:234] recv [channel @0xc00018a240] Rlerror{Error: 95} D0227 11:39:01.007341 52095 transport_flipcall.go:127] send [channel @0xc00018a240] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0227 11:39:01.008142 52095 transport_flipcall.go:234] recv [channel @0xc00018a240] Rlerror{Error: 2} D0227 11:39:01.008312 52095 transport_flipcall.go:127] send [channel @0xc00018a240] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0227 11:39:01.010883 52095 transport_flipcall.go:234] recv [channel @0xc00018a240] Rlerror{Error: 95} D0227 11:39:01.011323 52095 transport_flipcall.go:127] send [channel @0xc00018a240] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0227 11:39:01.011838 52095 transport_flipcall.go:234] recv [channel @0xc00018a240] Rlerror{Error: 2} D0227 11:39:01.012076 52095 transport_flipcall.go:127] send [channel @0xc00018a240] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0227 11:39:01.013931 52095 transport_flipcall.go:234] recv [channel @0xc00018a240] Rlerror{Error: 95} D0227 11:39:01.014329 52095 transport_flipcall.go:127] send [channel @0xc00018a240] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0227 11:39:01.015149 52095 transport_flipcall.go:234] recv [channel @0xc00018a240] Rlerror{Error: 2} D0227 11:39:01.015295 52095 transport_flipcall.go:127] send [channel @0xc00018a240] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0227 11:39:01.015670 52095 transport_flipcall.go:234] recv [channel @0xc00018a240] Rlerror{Error: 95} D0227 11:39:01.022096 52095 transport_flipcall.go:127] send [channel @0xc00018a240] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0227 11:39:01.022602 52095 transport_flipcall.go:234] recv [channel @0xc00018a240] Rlerror{Error: 2} D0227 11:39:01.022773 52095 transport_flipcall.go:127] send [channel @0xc00018a240] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0227 11:39:01.023205 52095 transport_flipcall.go:234] recv [channel @0xc00018a240] Rlerror{Error: 95} D0227 11:39:01.051623 52095 netfilter.go:54] netfilter: convert to binary: current offset: 0 D0227 11:39:01.051775 52095 netfilter.go:54] netfilter: convert to binary: found hook 0 at offset 0 D0227 11:39:01.051911 52095 netfilter.go:54] netfilter: convert to binary: found underflow 0 at offset 0 D0227 11:39:01.051995 52095 netfilter.go:54] netfilter: convert to binary: marshalling standard target D0227 11:39:01.052098 52095 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]} D0227 11:39:01.052657 52095 netfilter.go:54] netfilter: convert to binary: current offset: 152 D0227 11:39:01.052769 52095 netfilter.go:54] netfilter: convert to binary: found hook 1 at offset 152 D0227 11:39:01.052876 52095 netfilter.go:54] netfilter: convert to binary: found underflow 1 at offset 152 D0227 11:39:01.052978 52095 netfilter.go:54] netfilter: convert to binary: marshalling standard target D0227 11:39:01.053079 52095 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]} D0227 11:39:01.053581 52095 netfilter.go:54] netfilter: convert to binary: current offset: 304 D0227 11:39:01.053670 52095 netfilter.go:54] netfilter: convert to binary: found hook 3 at offset 304 D0227 11:39:01.053738 52095 netfilter.go:54] netfilter: convert to binary: found underflow 3 at offset 304 D0227 11:39:01.053809 52095 netfilter.go:54] netfilter: convert to binary: marshalling standard target D0227 11:39:01.053884 52095 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]} D0227 11:39:01.054331 52095 netfilter.go:54] netfilter: convert to binary: current offset: 456 D0227 11:39:01.054398 52095 netfilter.go:54] netfilter: convert to binary: found hook 4 at offset 456 D0227 11:39:01.054471 52095 netfilter.go:54] netfilter: convert to binary: found underflow 4 at offset 456 D0227 11:39:01.054539 52095 netfilter.go:54] netfilter: convert to binary: marshalling standard target D0227 11:39:01.054619 52095 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]} D0227 11:39:01.055110 52095 netfilter.go:54] netfilter: convert to binary: current offset: 608 D0227 11:39:01.055193 52095 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]} D0227 11:39:01.055679 52095 netfilter.go:54] netfilter: convert to binary: finished with an marshalled size of 0 D0227 11:39:01.055757 52095 netfilter.go:54] netfilter: convert to binary: current offset: 0 D0227 11:39:01.055831 52095 netfilter.go:54] netfilter: convert to binary: found hook 0 at offset 0 D0227 11:39:01.055920 52095 netfilter.go:54] netfilter: convert to binary: found underflow 0 at offset 0 D0227 11:39:01.055995 52095 netfilter.go:54] netfilter: convert to binary: marshalling standard target D0227 11:39:01.056069 52095 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]} D0227 11:39:01.056625 52095 netfilter.go:54] netfilter: convert to binary: current offset: 152 D0227 11:39:01.056721 52095 netfilter.go:54] netfilter: convert to binary: found hook 3 at offset 152 D0227 11:39:01.056798 52095 netfilter.go:54] netfilter: convert to binary: found underflow 3 at offset 152 D0227 11:39:01.056871 52095 netfilter.go:54] netfilter: convert to binary: marshalling standard target D0227 11:39:01.056953 52095 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]} D0227 11:39:01.057505 52095 netfilter.go:54] netfilter: convert to binary: current offset: 304 D0227 11:39:01.057617 52095 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]} D0227 11:39:01.058151 52095 netfilter.go:54] netfilter: convert to binary: finished with an marshalled size of 0 D0227 11:39:01.058249 52095 netfilter.go:54] netfilter: convert to binary: current offset: 0 D0227 11:39:01.058312 52095 netfilter.go:54] netfilter: convert to binary: found hook 1 at offset 0 D0227 11:39:01.058398 52095 netfilter.go:54] netfilter: convert to binary: found underflow 1 at offset 0 D0227 11:39:01.058469 52095 netfilter.go:54] netfilter: convert to binary: marshalling standard target D0227 11:39:01.058549 52095 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]} D0227 11:39:01.059003 52095 netfilter.go:54] netfilter: convert to binary: current offset: 152 D0227 11:39:01.059081 52095 netfilter.go:54] netfilter: convert to binary: found hook 2 at offset 152 D0227 11:39:01.059151 52095 netfilter.go:54] netfilter: convert to binary: found underflow 2 at offset 152 D0227 11:39:01.059246 52095 netfilter.go:54] netfilter: convert to binary: marshalling standard target D0227 11:39:01.059310 52095 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]} D0227 11:39:01.060394 52095 netfilter.go:54] netfilter: convert to binary: current offset: 304 D0227 11:39:01.060483 52095 netfilter.go:54] netfilter: convert to binary: found hook 3 at offset 304 D0227 11:39:01.060572 52095 netfilter.go:54] netfilter: convert to binary: found underflow 3 at offset 304 D0227 11:39:01.060655 52095 netfilter.go:54] netfilter: convert to binary: marshalling standard target D0227 11:39:01.060732 52095 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]} D0227 11:39:01.061249 52095 netfilter.go:54] netfilter: convert to binary: current offset: 456 D0227 11:39:01.061333 52095 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]} D0227 11:39:01.061820 52095 netfilter.go:54] netfilter: convert to binary: finished with an marshalled size of 0 D0227 11:39:01.206061 52095 transport_flipcall.go:127] send [channel @0xc00018a240] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0227 11:39:01.206752 52095 transport_flipcall.go:234] recv [channel @0xc00018a240] Rlerror{Error: 2} D0227 11:39:01.206947 52095 transport_flipcall.go:127] send [channel @0xc00018a240] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0227 11:39:01.207501 52095 transport_flipcall.go:234] recv [channel @0xc00018a240] Rlerror{Error: 95} D0227 11:39:01.207779 52095 transport_flipcall.go:127] send [channel @0xc00018a240] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0227 11:39:01.208946 52095 transport_flipcall.go:234] recv [channel @0xc00018a240] Rlerror{Error: 2} D0227 11:39:01.209253 52095 transport_flipcall.go:127] send [channel @0xc00018a240] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0227 11:39:01.210636 52095 transport_flipcall.go:234] recv [channel @0xc00018a240] Rlerror{Error: 95} D0227 11:39:01.210954 52095 transport_flipcall.go:127] send [channel @0xc00018a240] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0227 11:39:01.211560 52095 transport_flipcall.go:234] recv [channel @0xc00018a240] Rlerror{Error: 2} D0227 11:39:01.211733 52095 transport_flipcall.go:127] send [channel @0xc00018a240] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0227 11:39:01.212241 52095 transport_flipcall.go:234] recv [channel @0xc00018a240] Rlerror{Error: 95} D0227 11:39:01.212629 52095 transport_flipcall.go:127] send [channel @0xc00018a240] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0227 11:39:01.213183 52095 transport_flipcall.go:234] recv [channel @0xc00018a240] Rlerror{Error: 2} D0227 11:39:01.213360 52095 transport_flipcall.go:127] send [channel @0xc00018a240] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0227 11:39:01.213833 52095 transport_flipcall.go:234] recv [channel @0xc00018a240] Rlerror{Error: 95} D0227 11:39:01.214075 52095 transport_flipcall.go:127] send [channel @0xc00018a240] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0227 11:39:01.223706 52095 transport_flipcall.go:234] recv [channel @0xc00018a240] Rlerror{Error: 2} D0227 11:39:01.224000 52095 transport_flipcall.go:127] send [channel @0xc00018a240] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0227 11:39:01.232248 52095 transport_flipcall.go:234] recv [channel @0xc00018a240] Rlerror{Error: 95} D0227 11:39:01.232763 52095 transport_flipcall.go:127] send [channel @0xc00018a240] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0227 11:39:01.233378 52095 transport_flipcall.go:234] recv [channel @0xc00018a240] Rlerror{Error: 2} D0227 11:39:01.233612 52095 transport_flipcall.go:127] send [channel @0xc00018a240] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0227 11:39:01.234157 52095 transport_flipcall.go:234] recv [channel @0xc00018a240] Rlerror{Error: 95} D0227 11:39:01.234517 52095 transport_flipcall.go:127] send [channel @0xc00018a240] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0227 11:39:01.235545 52095 transport_flipcall.go:234] recv [channel @0xc00018a240] Rlerror{Error: 2} D0227 11:39:01.235814 52095 transport_flipcall.go:127] send [channel @0xc00018a240] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0227 11:39:01.236425 52095 transport_flipcall.go:234] recv [channel @0xc00018a240] Rlerror{Error: 95} D0227 11:39:01.236793 52095 transport_flipcall.go:127] send [channel @0xc00018a240] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0227 11:39:01.237736 52095 transport_flipcall.go:234] recv [channel @0xc00018a240] Rlerror{Error: 2} D0227 11:39:01.237948 52095 transport_flipcall.go:127] send [channel @0xc00018a240] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0227 11:39:01.238895 52095 transport_flipcall.go:234] recv [channel @0xc00018a240] Rlerror{Error: 95} D0227 11:39:01.239301 52095 transport_flipcall.go:127] send [channel @0xc00018a240] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0227 11:39:01.239796 52095 transport_flipcall.go:234] recv [channel @0xc00018a240] Rlerror{Error: 2} D0227 11:39:01.239988 52095 transport_flipcall.go:127] send [channel @0xc00018a240] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0227 11:39:01.240437 52095 transport_flipcall.go:234] recv [channel @0xc00018a240] Rlerror{Error: 95} D0227 11:39:01.240787 52095 transport_flipcall.go:127] send [channel @0xc00018a240] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0227 11:39:01.241465 52095 transport_flipcall.go:234] recv [channel @0xc00018a240] Rlerror{Error: 2} D0227 11:39:01.241610 52095 transport_flipcall.go:127] send [channel @0xc00018a240] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0227 11:39:01.242247 52095 transport_flipcall.go:234] recv [channel @0xc00018a240] Rlerror{Error: 95} D0227 11:39:01.264142 52095 task_block.go:223] [ 38] Interrupt queued D0227 11:39:01.301260 52095 task_block.go:223] [ 39] Interrupt queued D0227 11:39:01.307447 52095 parameters.go:238] Clock(Monotonic): error: 176 ns, adjusted frequency from 2299774948 Hz to 2299774963 Hz D0227 11:39:01.307689 52095 parameters.go:238] Clock(Realtime): error: 333 ns, adjusted frequency from 2299774317 Hz to 2299775387 Hz D0227 11:39:01.307835 52095 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8498780479283928 monotonicBaseRef:89000579980 monotonicFrequency:2299774963 realtimeReady:1 realtimeBaseCycles:8498780479869349 realtimeBaseRef:1582803541307682848 realtimeFrequency:2299775387} D0227 11:39:01.362812 52095 task_block.go:223] [ 40] Interrupt queued ================== WARNING: DATA RACE Read at 0x00c0007c6470 by goroutine 364: gvisor.dev/gvisor/pkg/tcpip/transport/tcp.(*endpoint).windowCrossedACKThreshold() pkg/tcpip/transport/tcp/endpoint.go:1312 +0x9e gvisor.dev/gvisor/pkg/tcpip/transport/tcp.(*endpoint).SetSockOptInt() pkg/tcpip/transport/tcp/endpoint.go:1394 +0x408 gvisor.dev/gvisor/pkg/sentry/socket/netstack.setSockOptSocket() pkg/sentry/socket/netstack/netstack.go:1549 +0x84c gvisor.dev/gvisor/pkg/sentry/socket/netstack.SetSockOpt() pkg/sentry/socket/netstack/netstack.go:1509 +0x1ad gvisor.dev/gvisor/pkg/sentry/socket/netstack.(*SocketOperations).SetSockOpt() pkg/sentry/socket/netstack/netstack.go:1501 +0x69d gvisor.dev/gvisor/pkg/sentry/syscalls/linux.SetSockOpt() pkg/sentry/syscalls/linux/sys_socket.go:541 +0x303 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).executeSyscall() pkg/sentry/kernel/task_syscall.go:170 +0x455 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 +0x1aaf gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).run() pkg/sentry/kernel/task_run.go:92 +0x308 Previous write at 0x00c0007c6470 by goroutine 342: gvisor.dev/gvisor/pkg/tcpip/transport/tcp.(*handshake).execute() pkg/tcpip/transport/tcp/connect.go:533 +0x631 gvisor.dev/gvisor/pkg/tcpip/transport/tcp.(*endpoint).protocolMainLoop() pkg/tcpip/transport/tcp/connect.go:1280 +0xfc6 Goroutine 364 (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 +0x12af gvisor.dev/gvisor/pkg/sentry/syscalls/linux.clone() pkg/sentry/syscalls/linux/sys_thread.go:226 +0x1fe 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 +0x455 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 +0x1aaf gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).run() pkg/sentry/kernel/task_run.go:92 +0x308 Goroutine 342 (running) created at: gvisor.dev/gvisor/pkg/tcpip/transport/tcp.(*endpoint).connect() pkg/tcpip/transport/tcp/endpoint.go:2044 +0xf2d gvisor.dev/gvisor/pkg/tcpip/transport/tcp.(*endpoint).Connect() pkg/tcpip/transport/tcp/endpoint.go:1873 +0x8c 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 +0x455 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 +0x1aaf gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).run() pkg/sentry/kernel/task_run.go:92 +0x308 DIAGNOSIS: I0227 11:39:01.402513 61253 main.go:296] *************************** I0227 11:39:01.402755 61253 main.go:297] Args: [/syzkaller/managers/ptrace-proxy-sandbox-race/current/image -root /syzkaller/managers/ptrace-proxy-sandbox-race/workdir/gvisor_root -watchdog-action=panic -network=none -debug -alsologtostderr -platform=ptrace -file-access=shared -network=sandbox -TESTONLY-unsafe-nonroot debug -stacks --ps ci-gvisor-ptrace-proxy-sandbox-race-0] I0227 11:39:01.402963 61253 main.go:298] Version release-20200219.0-47-gde0b2ebf8635 I0227 11:39:01.403907 61253 main.go:299] PID: 61253 I0227 11:39:01.404062 61253 main.go:300] UID: 0, GID: 0 I0227 11:39:01.404188 61253 main.go:301] Configuration: I0227 11:39:01.404314 61253 main.go:302] RootDir: /syzkaller/managers/ptrace-proxy-sandbox-race/workdir/gvisor_root I0227 11:39:01.404461 61253 main.go:303] Platform: ptrace I0227 11:39:01.404605 61253 main.go:304] FileAccess: shared, overlay: false I0227 11:39:01.404740 61253 main.go:305] Network: sandbox, logging: false I0227 11:39:01.404869 61253 main.go:306] Strace: false, max size: 1024, syscalls: [] I0227 11:39:01.404969 61253 main.go:307] *************************** W0227 11:39:01.405058 61253 main.go:312] Block the TERM signal. This is only safe in tests! D0227 11:39:01.405249 61253 container.go:158] Load container "/syzkaller/managers/ptrace-proxy-sandbox-race/workdir/gvisor_root" "ci-gvisor-ptrace-proxy-sandbox-race-0" D0227 11:39:01.411593 61253 container.go:591] Signal container "ci-gvisor-ptrace-proxy-sandbox-race-0": signal 0 D0227 11:39:01.411775 61253 sandbox.go:788] Signal sandbox "ci-gvisor-ptrace-proxy-sandbox-race-0" D0227 11:39:01.411933 61253 sandbox.go:318] Connecting to sandbox "ci-gvisor-ptrace-proxy-sandbox-race-0" D0227 11:39:01.412423 61253 urpc.go:534] urpc: successfully marshalled 117 bytes. W0227 11:39:01.505379 61253 error.go:48] FATAL ERROR: container sandbox is not running container sandbox is not running W0227 11:39:01.505661 61253 main.go:329] Failure to execute command, err: 1 Error collecting stacks: failed to run ["/syzkaller/managers/ptrace-proxy-sandbox-race/current/image" "-root" "/syzkaller/managers/ptrace-proxy-sandbox-race/workdir/gvisor_root" "-watchdog-action=panic" "-network=none" "-debug" "-alsologtostderr" "-platform=ptrace" "-file-access=shared" "-network=sandbox" "-TESTONLY-unsafe-nonroot" "debug" "-stacks" "--ps" "ci-gvisor-ptrace-proxy-sandbox-race-0"]: exit status 128 I0227 11:39:01.402513 61253 main.go:296] *************************** I0227 11:39:01.402755 61253 main.go:297] Args: [/syzkaller/managers/ptrace-proxy-sandbox-race/current/image -root /syzkaller/managers/ptrace-proxy-sandbox-race/workdir/gvisor_root -watchdog-action=panic -network=none -debug -alsologtostderr -platform=ptrace -file-access=shared -network=sandbox -TESTONLY-unsafe-nonroot debug -stacks --ps ci-gvisor-ptrace-proxy-sandbox-race-0] I0227 11:39:01.402963 61253 main.go:298] Version release-20200219.0-47-gde0b2ebf8635 I0227 11:39:01.403907 61253 main.go:299] PID: 61253 I0227 11:39:01.404062 61253 main.go:300] UID: 0, GID: 0 I0227 11:39:01.404188 61253 main.go:301] Configuration: I0227 11:39:01.404314 61253 main.go:302] RootDir: /syzkaller/managers/ptrace-proxy-sandbox-race/workdir/gvisor_root I0227 11:39:01.404461 61253 main.go:303] Platform: ptrace I0227 11:39:01.404605 61253 main.go:304] FileAccess: shared, overlay: false I0227 11:39:01.404740 61253 main.go:305] Network: sandbox, logging: false I0227 11:39:01.404869 61253 main.go:306] Strace: false, max size: 1024, syscalls: [] I0227 11:39:01.404969 61253 main.go:307] *************************** W0227 11:39:01.405058 61253 main.go:312] Block the TERM signal. This is only safe in tests! D0227 11:39:01.405249 61253 container.go:158] Load container "/syzkaller/managers/ptrace-proxy-sandbox-race/workdir/gvisor_root" "ci-gvisor-ptrace-proxy-sandbox-race-0" D0227 11:39:01.411593 61253 container.go:591] Signal container "ci-gvisor-ptrace-proxy-sandbox-race-0": signal 0 D0227 11:39:01.411775 61253 sandbox.go:788] Signal sandbox "ci-gvisor-ptrace-proxy-sandbox-race-0" D0227 11:39:01.411933 61253 sandbox.go:318] Connecting to sandbox "ci-gvisor-ptrace-proxy-sandbox-race-0" D0227 11:39:01.412423 61253 urpc.go:534] urpc: successfully marshalled 117 bytes. W0227 11:39:01.505379 61253 error.go:48] FATAL ERROR: container sandbox is not running container sandbox is not running W0227 11:39:01.505661 61253 main.go:329] Failure to execute command, err: 1 ================== W0227 11:39:01.492778 61084 error.go:48] FATAL ERROR: waiting on pid 19: waiting on PID 19 in sandbox "ci-gvisor-ptrace-proxy-sandbox-race-0": urpc method "containerManager.WaitPID" failed: EOF waiting on pid 19: waiting on PID 19 in sandbox "ci-gvisor-ptrace-proxy-sandbox-race-0": urpc method "containerManager.WaitPID" failed: EOF W0227 11:39:01.493170 61084 main.go:329] Failure to execute command, err: 1 W0227 11:39:01.501250 52067 sandbox.go:724] Wait RPC to container "ci-gvisor-ptrace-proxy-sandbox-race-0" failed: urpc method "containerManager.Wait" failed: EOF. Will try waiting on the sandbox process instead. D0227 11:39:01.502843 52067 container.go:713] Destroy container "ci-gvisor-ptrace-proxy-sandbox-race-0" D0227 11:39:01.503330 52067 container.go:800] Destroying container "ci-gvisor-ptrace-proxy-sandbox-race-0" D0227 11:39:01.503426 52067 sandbox.go:1040] Destroying root container "ci-gvisor-ptrace-proxy-sandbox-race-0" by destroying sandbox D0227 11:39:01.503560 52067 sandbox.go:770] Destroy sandbox "ci-gvisor-ptrace-proxy-sandbox-race-0" D0227 11:39:01.503653 52067 container.go:814] Killing gofer for container "ci-gvisor-ptrace-proxy-sandbox-race-0", PID: 52094 I0227 11:39:01.707132 52067 main.go:320] Exiting with status: 16896