D0227 13:41:36.374798 11074 parameters.go:238] Clock(Monotonic): error: 82 ns, adjusted frequency from 2299774330 Hz to 2299774650 Hz D0227 13:41:36.375068 11074 parameters.go:238] Clock(Realtime): error: 175 ns, adjusted frequency from 2299774128 Hz to 2299774767 Hz D0227 13:41:36.375179 11074 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8515695476040103 monotonicBaseRef:2002078266 monotonicFrequency:2299774650 realtimeReady:1 realtimeBaseCycles:8515695476742539 realtimeBaseRef:1582810896375063682 realtimeFrequency:2299774767} D0227 13:41:37.373951 11074 parameters.go:238] Clock(Monotonic): error: 158 ns, adjusted frequency from 2299774650 Hz to 2299774926 Hz D0227 13:41:37.374161 11074 parameters.go:238] Clock(Realtime): error: 124 ns, adjusted frequency from 2299774767 Hz to 2299774750 Hz D0227 13:41:37.374267 11074 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8515697773877158 monotonicBaseRef:3001235750 monotonicFrequency:2299774926 realtimeReady:1 realtimeBaseCycles:8515697774433317 realtimeBaseRef:1582810897374157511 realtimeFrequency:2299774750} D0227 13:41:38.374281 11074 parameters.go:238] Clock(Monotonic): error: -1 ns, adjusted frequency from 2299774926 Hz to 2299774576 Hz D0227 13:41:38.374503 11074 parameters.go:238] Clock(Realtime): error: 129 ns, adjusted frequency from 2299774750 Hz to 2299774828 Hz D0227 13:41:38.374639 11074 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8515700074413102 monotonicBaseRef:4001566659 monotonicFrequency:2299774576 realtimeReady:1 realtimeBaseCycles:8515700074990571 realtimeBaseRef:1582810898374497763 realtimeFrequency:2299774828} D0227 13:41:39.374645 11074 parameters.go:238] Clock(Monotonic): error: 34 ns, adjusted frequency from 2299774576 Hz to 2299774668 Hz D0227 13:41:39.374845 11074 parameters.go:238] Clock(Realtime): error: 15 ns, adjusted frequency from 2299774828 Hz to 2299774585 Hz D0227 13:41:39.374969 11074 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8515702375053541 monotonicBaseRef:5001943158 monotonicFrequency:2299774668 realtimeReady:1 realtimeBaseCycles:8515702375554174 realtimeBaseRef:1582810899374840742 realtimeFrequency:2299774585} D0227 13:41:40.373868 11074 parameters.go:238] Clock(Monotonic): error: 108 ns, adjusted frequency from 2299774668 Hz to 2299774869 Hz D0227 13:41:40.374060 11074 parameters.go:238] Clock(Realtime): error: 111 ns, adjusted frequency from 2299774585 Hz to 2299774836 Hz D0227 13:41:40.374150 11074 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8515704673046284 monotonicBaseRef:6001168332 monotonicFrequency:2299774869 realtimeReady:1 realtimeBaseCycles:8515704673521364 realtimeBaseRef:1582810900374054841 realtimeFrequency:2299774836} D0227 13:41:41.373295 11074 parameters.go:238] Clock(Monotonic): error: -29 ns, adjusted frequency from 2299774869 Hz to 2299774556 Hz D0227 13:41:41.373488 11074 parameters.go:238] Clock(Realtime): error: 58 ns, adjusted frequency from 2299774836 Hz to 2299774730 Hz D0227 13:41:41.373617 11074 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8515706971487278 monotonicBaseRef:7000588329 monotonicFrequency:2299774556 realtimeReady:1 realtimeBaseCycles:8515706971980580 realtimeBaseRef:1582810901373482776 realtimeFrequency:2299774730} D0227 13:41:42.375419 11074 sampler.go:168] Time: Adjusting syscall overhead up to 8000 D0227 13:41:42.375693 11074 parameters.go:238] Clock(Monotonic): error: 359 ns, adjusted frequency from 2299774556 Hz to 2299775462 Hz D0227 13:41:42.375872 11074 sampler.go:168] Time: Adjusting syscall overhead up to 8000 D0227 13:41:42.376036 11074 parameters.go:238] Clock(Realtime): error: 504 ns, adjusted frequency from 2299774730 Hz to 2299775749 Hz D0227 13:41:42.376183 11074 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8515709276747185 monotonicBaseRef:8002973498 monotonicFrequency:2299775462 realtimeReady:1 realtimeBaseCycles:8515709277609529 realtimeBaseRef:1582810902376028337 realtimeFrequency:2299775749} D0227 13:41:43.373149 11074 parameters.go:238] Clock(Monotonic): error: -336 ns, adjusted frequency from 2299775462 Hz to 2299773851 Hz D0227 13:41:43.373356 11074 parameters.go:238] Clock(Realtime): error: -118 ns, adjusted frequency from 2299775749 Hz to 2299774278 Hz D0227 13:41:43.373929 11074 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8515711570715455 monotonicBaseRef:9000448385 monotonicFrequency:2299773851 realtimeReady:1 realtimeBaseCycles:8515711571225757 realtimeBaseRef:1582810903373350023 realtimeFrequency:2299774278} D0227 13:41:44.374052 11074 parameters.go:238] Clock(Monotonic): error: 364 ns, adjusted frequency from 2299773851 Hz to 2299775415 Hz D0227 13:41:44.374427 11074 parameters.go:238] Clock(Realtime): error: -316 ns, adjusted frequency from 2299774278 Hz to 2299773922 Hz D0227 13:41:44.374639 11074 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8515713872569879 monotonicBaseRef:10001353070 monotonicFrequency:2299775415 realtimeReady:1 realtimeBaseCycles:8515713873438609 realtimeBaseRef:1582810904374410376 realtimeFrequency:2299773922} D0227 13:41:45.374087 11074 parameters.go:238] Clock(Monotonic): error: -21 ns, adjusted frequency from 2299775415 Hz to 2299774553 Hz D0227 13:41:45.374417 11074 parameters.go:238] Clock(Realtime): error: 97 ns, adjusted frequency from 2299773922 Hz to 2299774903 Hz D0227 13:41:45.374625 11074 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8515716172434523 monotonicBaseRef:11001391869 monotonicFrequency:2299774553 realtimeReady:1 realtimeBaseCycles:8515716173182660 realtimeBaseRef:1582810905374397387 realtimeFrequency:2299774903} D0227 13:41:46.373660 11074 parameters.go:238] Clock(Monotonic): error: 80 ns, adjusted frequency from 2299774553 Hz to 2299774790 Hz D0227 13:41:46.373855 11074 parameters.go:238] Clock(Realtime): error: 95 ns, adjusted frequency from 2299774903 Hz to 2299774871 Hz D0227 13:41:46.373965 11074 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8515718471202258 monotonicBaseRef:12000954079 monotonicFrequency:2299774790 realtimeReady:1 realtimeBaseCycles:8515718471697512 realtimeBaseRef:1582810906373849485 realtimeFrequency:2299774871} D0227 13:41:47.373485 11074 parameters.go:238] Clock(Monotonic): error: -287 ns, adjusted frequency from 2299774790 Hz to 2299774014 Hz D0227 13:41:47.373814 11074 parameters.go:238] Clock(Realtime): error: -143 ns, adjusted frequency from 2299774871 Hz to 2299774338 Hz D0227 13:41:47.374013 11074 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8515720770594057 monotonicBaseRef:13000787544 monotonicFrequency:2299774014 realtimeReady:1 realtimeBaseCycles:8515720771269018 realtimeBaseRef:1582810907373761056 realtimeFrequency:2299774338} D0227 13:41:48.375706 11074 parameters.go:238] Clock(Monotonic): error: 26 ns, adjusted frequency from 2299774014 Hz to 2299774704 Hz D0227 13:41:48.375908 11074 parameters.go:238] Clock(Realtime): error: 287 ns, adjusted frequency from 2299774338 Hz to 2299775308 Hz D0227 13:41:48.375999 11074 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8515723075476028 monotonicBaseRef:14003008613 monotonicFrequency:2299774704 realtimeReady:1 realtimeBaseCycles:8515723075967582 realtimeBaseRef:1582810908375902233 realtimeFrequency:2299775308} D0227 13:41:49.381425 11074 parameters.go:238] Clock(Monotonic): error: 196 ns, adjusted frequency from 2299774704 Hz to 2299775125 Hz D0227 13:41:49.381670 11074 parameters.go:238] Clock(Realtime): error: 181 ns, adjusted frequency from 2299775308 Hz to 2299775032 Hz D0227 13:41:49.381799 11074 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8515725388394832 monotonicBaseRef:15008723998 monotonicFrequency:2299775125 realtimeReady:1 realtimeBaseCycles:8515725388989789 realtimeBaseRef:1582810909381662317 realtimeFrequency:2299775032} D0227 13:41:50.373391 11074 parameters.go:238] Clock(Monotonic): error: 549 ns, adjusted frequency from 2299775125 Hz to 2299776019 Hz D0227 13:41:50.373628 11074 parameters.go:238] Clock(Realtime): error: -393 ns, adjusted frequency from 2299775032 Hz to 2299773755 Hz D0227 13:41:50.373783 11074 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8515727669630183 monotonicBaseRef:16000662438 monotonicFrequency:2299776019 realtimeReady:1 realtimeBaseCycles:8515727670271776 realtimeBaseRef:1582810910373621076 realtimeFrequency:2299773755} D0227 13:41:51.373083 11074 parameters.go:238] Clock(Monotonic): error: -511 ns, adjusted frequency from 2299776019 Hz to 2299773500 Hz D0227 13:41:51.373368 11074 parameters.go:238] Clock(Realtime): error: 261 ns, adjusted frequency from 2299773755 Hz to 2299775197 Hz D0227 13:41:51.373531 11074 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8515729968758828 monotonicBaseRef:17000380943 monotonicFrequency:2299773500 realtimeReady:1 realtimeBaseCycles:8515729969446313 realtimeBaseRef:1582810911373360520 realtimeFrequency:2299775197} D0227 13:41:52.374346 11074 parameters.go:238] Clock(Monotonic): error: 48 ns, adjusted frequency from 2299773500 Hz to 2299774724 Hz D0227 13:41:52.374876 11074 parameters.go:238] Clock(Realtime): error: -248 ns, adjusted frequency from 2299775197 Hz to 2299774164 Hz D0227 13:41:52.375013 11074 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8515732271404410 monotonicBaseRef:18001629797 monotonicFrequency:2299774724 realtimeReady:1 realtimeBaseCycles:8515732272684916 realtimeBaseRef:1582810912374866495 realtimeFrequency:2299774164} D0227 13:41:53.374147 11074 parameters.go:238] Clock(Monotonic): error: -256 ns, adjusted frequency from 2299774724 Hz to 2299774183 Hz D0227 13:41:53.374392 11074 parameters.go:238] Clock(Realtime): error: 245 ns, adjusted frequency from 2299774164 Hz to 2299775195 Hz D0227 13:41:53.374521 11074 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8515734570679912 monotonicBaseRef:19001412722 monotonicFrequency:2299774183 realtimeReady:1 realtimeBaseCycles:8515734571355823 realtimeBaseRef:1582810913374386770 realtimeFrequency:2299775195} D0227 13:41:54.373875 11074 parameters.go:238] Clock(Monotonic): error: 223 ns, adjusted frequency from 2299774183 Hz to 2299775165 Hz D0227 13:41:54.374103 11074 parameters.go:238] Clock(Realtime): error: 120 ns, adjusted frequency from 2299775195 Hz to 2299774844 Hz D0227 13:41:54.374218 11074 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8515736869884006 monotonicBaseRef:20001164832 monotonicFrequency:2299775165 realtimeReady:1 realtimeBaseCycles:8515736870433917 realtimeBaseRef:1582810914374083652 realtimeFrequency:2299774844} D0227 13:41:55.374941 11074 parameters.go:238] Clock(Monotonic): error: -100 ns, adjusted frequency from 2299775165 Hz to 2299774401 Hz D0227 13:41:55.375108 11074 parameters.go:238] Clock(Realtime): error: -5 ns, adjusted frequency from 2299774844 Hz to 2299774640 Hz D0227 13:41:55.375222 11074 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8515739172146658 monotonicBaseRef:21002246453 monotonicFrequency:2299774401 realtimeReady:1 realtimeBaseCycles:8515739172550305 realtimeBaseRef:1582810915375101814 realtimeFrequency:2299774640} D0227 13:41:56.374971 11074 parameters.go:238] Clock(Monotonic): error: -60 ns, adjusted frequency from 2299774401 Hz to 2299774541 Hz D0227 13:41:56.375254 11074 parameters.go:238] Clock(Realtime): error: 102 ns, adjusted frequency from 2299774640 Hz to 2299774878 Hz D0227 13:41:56.375423 11074 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8515741471972816 monotonicBaseRef:22002268958 monotonicFrequency:2299774541 realtimeReady:1 realtimeBaseCycles:8515741472660853 realtimeBaseRef:1582810916375247875 realtimeFrequency:2299774878} D0227 13:41:57.374177 11074 parameters.go:238] Clock(Monotonic): error: 313 ns, adjusted frequency from 2299774541 Hz to 2299775344 Hz D0227 13:41:57.374381 11074 parameters.go:238] Clock(Realtime): error: -231 ns, adjusted frequency from 2299774878 Hz to 2299774123 Hz D0227 13:41:57.374477 11074 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8515743769936503 monotonicBaseRef:23001481552 monotonicFrequency:2299775344 realtimeReady:1 realtimeBaseCycles:8515743770430624 realtimeBaseRef:1582810917374376004 realtimeFrequency:2299774123} D0227 13:41:58.373099 11074 parameters.go:238] Clock(Monotonic): error: 16 ns, adjusted frequency from 2299775344 Hz to 2299774581 Hz D0227 13:41:58.373380 11074 parameters.go:238] Clock(Realtime): error: -167 ns, adjusted frequency from 2299774123 Hz to 2299774347 Hz D0227 13:41:58.373510 11074 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8515746067220283 monotonicBaseRef:24000398157 monotonicFrequency:2299774581 realtimeReady:1 realtimeBaseCycles:8515746067898379 realtimeBaseRef:1582810918373373136 realtimeFrequency:2299774347} D0227 13:41:59.373763 11074 parameters.go:238] Clock(Monotonic): error: -82 ns, adjusted frequency from 2299774581 Hz to 2299774297 Hz D0227 13:41:59.373971 11074 parameters.go:238] Clock(Realtime): error: 188 ns, adjusted frequency from 2299774347 Hz to 2299775090 Hz D0227 13:41:59.374082 11074 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8515748368509282 monotonicBaseRef:25001056664 monotonicFrequency:2299774297 realtimeReady:1 realtimeBaseCycles:8515748369035884 realtimeBaseRef:1582810919373965871 realtimeFrequency:2299775090} D0227 13:42:00.373627 11074 parameters.go:238] Clock(Monotonic): error: -204 ns, adjusted frequency from 2299774297 Hz to 2299774176 Hz D0227 13:42:00.373854 11074 parameters.go:238] Clock(Realtime): error: 54 ns, adjusted frequency from 2299775090 Hz to 2299774834 Hz D0227 13:42:00.373948 11074 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8515750667901580 monotonicBaseRef:26000890561 monotonicFrequency:2299774176 realtimeReady:1 realtimeBaseCycles:8515750668535727 realtimeBaseRef:1582810920373846186 realtimeFrequency:2299774834} D0227 13:42:01.374876 11074 parameters.go:238] Clock(Monotonic): error: 341 ns, adjusted frequency from 2299774176 Hz to 2299775543 Hz D0227 13:42:01.375494 11074 parameters.go:238] Clock(Realtime): error: -52 ns, adjusted frequency from 2299774834 Hz to 2299774561 Hz D0227 13:42:01.375675 11074 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8515752970638643 monotonicBaseRef:27002178899 monotonicFrequency:2299775543 realtimeReady:1 realtimeBaseCycles:8515752972076119 realtimeBaseRef:1582810921375483545 realtimeFrequency:2299774561} D0227 13:42:02.374259 11074 parameters.go:238] Clock(Monotonic): error: -357 ns, adjusted frequency from 2299775543 Hz to 2299773845 Hz D0227 13:42:02.374493 11074 parameters.go:238] Clock(Realtime): error: 20 ns, adjusted frequency from 2299774561 Hz to 2299774734 Hz D0227 13:42:02.374622 11074 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8515755268987191 monotonicBaseRef:28001558405 monotonicFrequency:2299773845 realtimeReady:1 realtimeBaseCycles:8515755269560067 realtimeBaseRef:1582810922374487528 realtimeFrequency:2299774734} D0227 13:42:03.373615 11074 parameters.go:238] Clock(Monotonic): error: 351 ns, adjusted frequency from 2299773845 Hz to 2299775489 Hz D0227 13:42:03.374001 11074 parameters.go:238] Clock(Realtime): error: -228 ns, adjusted frequency from 2299774734 Hz to 2299774255 Hz D0227 13:42:03.374102 11074 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8515757567286758 monotonicBaseRef:29000917351 monotonicFrequency:2299775489 realtimeReady:1 realtimeBaseCycles:8515757568196924 realtimeBaseRef:1582810923373992750 realtimeFrequency:2299774255} D0227 13:42:04.374246 11074 parameters.go:238] Clock(Monotonic): error: -9 ns, adjusted frequency from 2299775489 Hz to 2299774691 Hz D0227 13:42:04.374473 11074 parameters.go:238] Clock(Realtime): error: 167 ns, adjusted frequency from 2299774255 Hz to 2299775060 Hz D0227 13:42:04.374613 11074 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8515759868510057 monotonicBaseRef:30001546895 monotonicFrequency:2299774691 realtimeReady:1 realtimeBaseCycles:8515759869061517 realtimeBaseRef:1582810924374466856 realtimeFrequency:2299775060} D0227 13:42:05.373143 11074 parameters.go:238] Clock(Monotonic): error: 121 ns, adjusted frequency from 2299774691 Hz to 2299774888 Hz D0227 13:42:05.373356 11074 parameters.go:238] Clock(Realtime): error: 329 ns, adjusted frequency from 2299775060 Hz to 2299775423 Hz D0227 13:42:05.373534 11074 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8515762165753469 monotonicBaseRef:31000446231 monotonicFrequency:2299774888 realtimeReady:1 realtimeBaseCycles:8515762166264462 realtimeBaseRef:1582810925373348435 realtimeFrequency:2299775423} D0227 13:42:06.374399 11074 parameters.go:238] Clock(Monotonic): error: -160 ns, adjusted frequency from 2299774888 Hz to 2299774303 Hz D0227 13:42:06.374589 11074 parameters.go:238] Clock(Realtime): error: -357 ns, adjusted frequency from 2299775423 Hz to 2299773829 Hz D0227 13:42:06.374721 11074 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8515764468379429 monotonicBaseRef:32001685948 monotonicFrequency:2299774303 realtimeReady:1 realtimeBaseCycles:8515764468879890 realtimeBaseRef:1582810926374583340 realtimeFrequency:2299773829} D0227 13:42:07.374656 11074 parameters.go:238] Clock(Monotonic): error: 127 ns, adjusted frequency from 2299774303 Hz to 2299775025 Hz D0227 13:42:07.374860 11074 parameters.go:238] Clock(Realtime): error: -10 ns, adjusted frequency from 2299773829 Hz to 2299774625 Hz D0227 13:42:07.374981 11074 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8515766768779365 monotonicBaseRef:33001957989 monotonicFrequency:2299775025 realtimeReady:1 realtimeBaseCycles:8515766769276703 realtimeBaseRef:1582810927374854229 realtimeFrequency:2299774625} D0227 13:42:08.374387 11074 parameters.go:238] Clock(Monotonic): error: -180 ns, adjusted frequency from 2299775025 Hz to 2299774385 Hz D0227 13:42:08.374644 11074 parameters.go:238] Clock(Realtime): error: 130 ns, adjusted frequency from 2299774625 Hz to 2299774943 Hz D0227 13:42:08.374771 11074 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8515769067926815 monotonicBaseRef:34001685103 monotonicFrequency:2299774385 realtimeReady:1 realtimeBaseCycles:8515769068552920 realtimeBaseRef:1582810928374637508 realtimeFrequency:2299774943} D0227 13:42:09.373096 11074 parameters.go:238] Clock(Monotonic): error: 66 ns, adjusted frequency from 2299774385 Hz to 2299774781 Hz D0227 13:42:09.373305 11074 parameters.go:238] Clock(Realtime): error: -303 ns, adjusted frequency from 2299774943 Hz to 2299774026 Hz D0227 13:42:09.373472 11074 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8515771364744247 monotonicBaseRef:35000399345 monotonicFrequency:2299774781 realtimeReady:1 realtimeBaseCycles:8515771365246577 realtimeBaseRef:1582810929373297687 realtimeFrequency:2299774026} D0227 13:42:10.373271 11074 parameters.go:238] Clock(Monotonic): error: -101 ns, adjusted frequency from 2299774781 Hz to 2299774320 Hz D0227 13:42:10.373471 11074 parameters.go:238] Clock(Realtime): error: 172 ns, adjusted frequency from 2299774026 Hz to 2299775007 Hz D0227 13:42:10.373583 11074 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8515773664812444 monotonicBaseRef:36000526929 monotonicFrequency:2299774320 realtimeReady:1 realtimeBaseCycles:8515773665406005 realtimeBaseRef:1582810930373465269 realtimeFrequency:2299775007} D0227 13:42:10.771875 11074 urpc.go:577] urpc: unmarshal success. D0227 13:42:10.772150 11074 controller.go:501] containerManager.Signal &{CID:ci-gvisor-ptrace-proxy-sandbox-race-1 Signo:0 PID:0 Mode:Process} D0227 13:42:10.772455 11074 urpc.go:534] urpc: successfully marshalled 37 bytes. D0227 13:42:11.376325 11074 parameters.go:238] Clock(Monotonic): error: 172 ns, adjusted frequency from 2299774320 Hz to 2299775058 Hz D0227 13:42:11.376560 11074 parameters.go:238] Clock(Realtime): error: -148 ns, adjusted frequency from 2299775007 Hz to 2299774385 Hz D0227 13:42:11.376678 11074 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8515775971717584 monotonicBaseRef:37003627589 monotonicFrequency:2299775058 realtimeReady:1 realtimeBaseCycles:8515775972276181 realtimeBaseRef:1582810931376550426 realtimeFrequency:2299774385} D0227 13:42:12.373382 11074 parameters.go:238] Clock(Monotonic): error: -246 ns, adjusted frequency from 2299775058 Hz to 2299774197 Hz D0227 13:42:12.373648 11074 parameters.go:238] Clock(Realtime): error: 503 ns, adjusted frequency from 2299774385 Hz to 2299775803 Hz D0227 13:42:12.373796 11074 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8515778264707011 monotonicBaseRef:38000677026 monotonicFrequency:2299774197 realtimeReady:1 realtimeBaseCycles:8515778265355350 realtimeBaseRef:1582810932373639176 realtimeFrequency:2299775803} D0227 13:42:13.374276 11074 parameters.go:238] Clock(Monotonic): error: 209 ns, adjusted frequency from 2299774197 Hz to 2299775194 Hz D0227 13:42:13.374448 11074 parameters.go:238] Clock(Realtime): error: -195 ns, adjusted frequency from 2299775803 Hz to 2299774254 Hz D0227 13:42:13.374580 11074 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8515780566492605 monotonicBaseRef:39001551632 monotonicFrequency:2299775194 realtimeReady:1 realtimeBaseCycles:8515780566976325 realtimeBaseRef:1582810933374441502 realtimeFrequency:2299774254} D0227 13:42:14.374312 11074 parameters.go:238] Clock(Monotonic): error: -104 ns, adjusted frequency from 2299775194 Hz to 2299774442 Hz D0227 13:42:14.374550 11074 parameters.go:238] Clock(Realtime): error: -397 ns, adjusted frequency from 2299774254 Hz to 2299773784 Hz D0227 13:42:14.374690 11074 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8515782866387434 monotonicBaseRef:40001603652 monotonicFrequency:2299774442 realtimeReady:1 realtimeBaseCycles:8515782866970224 realtimeBaseRef:1582810934374537009 realtimeFrequency:2299773784} D0227 13:42:15.375615 11074 parameters.go:238] Clock(Monotonic): error: -41 ns, adjusted frequency from 2299774442 Hz to 2299774600 Hz D0227 13:42:15.375845 11074 parameters.go:238] Clock(Realtime): error: 274 ns, adjusted frequency from 2299773784 Hz to 2299775310 Hz D0227 13:42:15.375947 11074 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8515785169159598 monotonicBaseRef:41002907137 monotonicFrequency:2299774600 realtimeReady:1 realtimeBaseCycles:8515785169739070 realtimeBaseRef:1582810935375839337 realtimeFrequency:2299775310} D0227 13:42:16.375098 11074 parameters.go:238] Clock(Monotonic): error: 57 ns, adjusted frequency from 2299774600 Hz to 2299774812 Hz D0227 13:42:16.375319 11074 parameters.go:238] Clock(Realtime): error: 56 ns, adjusted frequency from 2299775310 Hz to 2299774767 Hz D0227 13:42:16.375430 11074 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8515787467776796 monotonicBaseRef:42002403869 monotonicFrequency:2299774812 realtimeReady:1 realtimeBaseCycles:8515787468300165 realtimeBaseRef:1582810936375311365 realtimeFrequency:2299774767} D0227 13:42:17.372996 11074 parameters.go:238] Clock(Monotonic): error: -277 ns, adjusted frequency from 2299774812 Hz to 2299774021 Hz D0227 13:42:17.373242 11074 parameters.go:238] Clock(Realtime): error: -204 ns, adjusted frequency from 2299774767 Hz to 2299774248 Hz D0227 13:42:17.373358 11074 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8515789762705665 monotonicBaseRef:43000296730 monotonicFrequency:2299774021 realtimeReady:1 realtimeBaseCycles:8515789763298397 realtimeBaseRef:1582810937373234407 realtimeFrequency:2299774248} D0227 13:42:18.373725 11074 parameters.go:238] Clock(Monotonic): error: 355 ns, adjusted frequency from 2299774021 Hz to 2299775513 Hz D0227 13:42:18.373948 11074 parameters.go:238] Clock(Realtime): error: 259 ns, adjusted frequency from 2299774248 Hz to 2299775237 Hz D0227 13:42:18.374088 11074 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8515792064167132 monotonicBaseRef:44001030474 monotonicFrequency:2299775513 realtimeReady:1 realtimeBaseCycles:8515792064681419 realtimeBaseRef:1582810938373933942 realtimeFrequency:2299775237} D0227 13:42:19.374139 11074 parameters.go:238] Clock(Monotonic): error: 8 ns, adjusted frequency from 2299775513 Hz to 2299774722 Hz D0227 13:42:19.374343 11074 parameters.go:238] Clock(Realtime): error: -58 ns, adjusted frequency from 2299775237 Hz to 2299774501 Hz D0227 13:42:19.374453 11074 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8515794364887502 monotonicBaseRef:45001441321 monotonicFrequency:2299774722 realtimeReady:1 realtimeBaseCycles:8515794365382112 realtimeBaseRef:1582810939374336353 realtimeFrequency:2299774501} I0227 13:42:19.541458 11074 watchdog.go:277] Watchdog starting loop, tasks: 14, discount: 0s D0227 13:42:20.373662 11074 parameters.go:238] Clock(Monotonic): error: -64 ns, adjusted frequency from 2299774722 Hz to 2299774514 Hz D0227 13:42:20.373826 11074 parameters.go:238] Clock(Realtime): error: 33 ns, adjusted frequency from 2299774501 Hz to 2299774687 Hz D0227 13:42:20.373958 11074 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8515796663516554 monotonicBaseRef:46000943154 monotonicFrequency:2299774514 realtimeReady:1 realtimeBaseCycles:8515796663965833 realtimeBaseRef:1582810940373818571 realtimeFrequency:2299774687} D0227 13:42:21.373376 11074 parameters.go:238] Clock(Monotonic): error: -331 ns, adjusted frequency from 2299774514 Hz to 2299773910 Hz D0227 13:42:21.373579 11074 parameters.go:238] Clock(Realtime): error: -29 ns, adjusted frequency from 2299774687 Hz to 2299774588 Hz D0227 13:42:21.373689 11074 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8515798962692717 monotonicBaseRef:47000682975 monotonicFrequency:2299773910 realtimeReady:1 realtimeBaseCycles:8515798963175667 realtimeBaseRef:1582810941373572958 realtimeFrequency:2299774588} D0227 13:42:22.372984 11074 parameters.go:238] Clock(Monotonic): error: 89 ns, adjusted frequency from 2299773910 Hz to 2299774912 Hz D0227 13:42:22.373178 11074 parameters.go:238] Clock(Realtime): error: 101 ns, adjusted frequency from 2299774588 Hz to 2299774868 Hz D0227 13:42:22.373303 11074 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8515801261562932 monotonicBaseRef:48000290025 monotonicFrequency:2299774912 realtimeReady:1 realtimeBaseCycles:8515801262028986 realtimeBaseRef:1582810942373172367 realtimeFrequency:2299774868} D0227 13:42:23.375475 11074 parameters.go:238] Clock(Monotonic): error: 143 ns, adjusted frequency from 2299774912 Hz to 2299775022 Hz D0227 13:42:23.375653 11074 parameters.go:238] Clock(Realtime): error: -82 ns, adjusted frequency from 2299774868 Hz to 2299774468 Hz D0227 13:42:23.375796 11074 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8515803567041466 monotonicBaseRef:49002770103 monotonicFrequency:2299775022 realtimeReady:1 realtimeBaseCycles:8515803567496155 realtimeBaseRef:1582810943375647522 realtimeFrequency:2299774468} D0227 13:42:24.373341 11074 parameters.go:238] Clock(Monotonic): error: 17 ns, adjusted frequency from 2299775022 Hz to 2299774736 Hz D0227 13:42:24.373522 11074 parameters.go:238] Clock(Realtime): error: 63 ns, adjusted frequency from 2299774468 Hz to 2299774822 Hz D0227 13:42:24.373639 11074 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8515805861911375 monotonicBaseRef:50000637236 monotonicFrequency:2299774736 realtimeReady:1 realtimeBaseCycles:8515805862368653 realtimeBaseRef:1582810944373516021 realtimeFrequency:2299774822} D0227 13:42:25.375421 11074 parameters.go:238] Clock(Monotonic): error: 91 ns, adjusted frequency from 2299774736 Hz to 2299774864 Hz D0227 13:42:25.375644 11074 parameters.go:238] Clock(Realtime): error: -1 ns, adjusted frequency from 2299774822 Hz to 2299774686 Hz D0227 13:42:25.375731 11074 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8515808166489626 monotonicBaseRef:51002725925 monotonicFrequency:2299774864 realtimeReady:1 realtimeBaseCycles:8515808167021309 realtimeBaseRef:1582810945375637026 realtimeFrequency:2299774686} D0227 13:42:26.380881 11074 parameters.go:238] Clock(Monotonic): error: -404 ns, adjusted frequency from 2299774864 Hz to 2299773737 Hz D0227 13:42:26.381103 11074 parameters.go:238] Clock(Realtime): error: 14 ns, adjusted frequency from 2299774686 Hz to 2299774671 Hz D0227 13:42:26.381218 11074 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8515810478788292 monotonicBaseRef:52008171589 monotonicFrequency:2299773737 realtimeReady:1 realtimeBaseCycles:8515810479353749 realtimeBaseRef:1582810946381097453 realtimeFrequency:2299774671} D0227 13:42:27.373321 11074 parameters.go:238] Clock(Monotonic): error: 251 ns, adjusted frequency from 2299773737 Hz to 2299775284 Hz D0227 13:42:27.373520 11074 parameters.go:238] Clock(Realtime): error: -57 ns, adjusted frequency from 2299774671 Hz to 2299774553 Hz D0227 13:42:27.373612 11074 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8515812761209284 monotonicBaseRef:53000626174 monotonicFrequency:2299775284 realtimeReady:1 realtimeBaseCycles:8515812761680310 realtimeBaseRef:1582810947373510574 realtimeFrequency:2299774553} D0227 13:42:28.373193 11074 parameters.go:238] Clock(Monotonic): error: 46 ns, adjusted frequency from 2299775284 Hz to 2299774814 Hz D0227 13:42:28.373390 11074 parameters.go:238] Clock(Realtime): error: -254 ns, adjusted frequency from 2299774553 Hz to 2299774179 Hz D0227 13:42:28.373547 11074 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8515815060689832 monotonicBaseRef:54000498015 monotonicFrequency:2299774814 realtimeReady:1 realtimeBaseCycles:8515815061165853 realtimeBaseRef:1582810948373384905 realtimeFrequency:2299774179} D0227 13:42:29.374640 11074 parameters.go:238] Clock(Monotonic): error: 11 ns, adjusted frequency from 2299774814 Hz to 2299774667 Hz D0227 13:42:29.374821 11074 parameters.go:238] Clock(Realtime): error: 238 ns, adjusted frequency from 2299774179 Hz to 2299775241 Hz D0227 13:42:29.374928 11074 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8515817363791301 monotonicBaseRef:55001944528 monotonicFrequency:2299774667 realtimeReady:1 realtimeBaseCycles:8515817364230923 realtimeBaseRef:1582810949374815867 realtimeFrequency:2299775241} D0227 13:42:30.376965 11074 parameters.go:238] Clock(Monotonic): error: -342 ns, adjusted frequency from 2299774667 Hz to 2299773888 Hz D0227 13:42:30.377173 11074 parameters.go:238] Clock(Realtime): error: -311 ns, adjusted frequency from 2299775241 Hz to 2299773996 Hz D0227 13:42:30.377295 11074 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8515819668846107 monotonicBaseRef:56004240465 monotonicFrequency:2299773888 realtimeReady:1 realtimeBaseCycles:8515819669411168 realtimeBaseRef:1582810950377166098 realtimeFrequency:2299773996} D0227 13:42:31.373538 11074 parameters.go:238] Clock(Monotonic): error: 382 ns, adjusted frequency from 2299773888 Hz to 2299775494 Hz D0227 13:42:31.373727 11074 parameters.go:238] Clock(Realtime): error: 325 ns, adjusted frequency from 2299773996 Hz to 2299775407 Hz D0227 13:42:31.373827 11074 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8515821960783897 monotonicBaseRef:57000833130 monotonicFrequency:2299775494 realtimeReady:1 realtimeBaseCycles:8515821961264069 realtimeBaseRef:1582810951373721805 realtimeFrequency:2299775407} D0227 13:42:32.373605 11074 parameters.go:238] Clock(Monotonic): error: -8 ns, adjusted frequency from 2299775494 Hz to 2299774645 Hz D0227 13:42:32.373781 11074 parameters.go:238] Clock(Realtime): error: -335 ns, adjusted frequency from 2299775407 Hz to 2299773921 Hz D0227 13:42:32.373890 11074 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8515824260714462 monotonicBaseRef:58000900558 monotonicFrequency:2299774645 realtimeReady:1 realtimeBaseCycles:8515824261161090 realtimeBaseRef:1582810952373774685 realtimeFrequency:2299773921} D0227 13:42:33.374225 11074 parameters.go:238] Clock(Monotonic): error: 229 ns, adjusted frequency from 2299774645 Hz to 2299775173 Hz D0227 13:42:33.374454 11074 parameters.go:238] Clock(Realtime): error: 99 ns, adjusted frequency from 2299773921 Hz to 2299774949 Hz D0227 13:42:33.374616 11074 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8515826561906051 monotonicBaseRef:59001516680 monotonicFrequency:2299775173 realtimeReady:1 realtimeBaseCycles:8515826562482676 realtimeBaseRef:1582810953374447648 realtimeFrequency:2299774949} D0227 13:42:34.374185 11074 parameters.go:238] Clock(Monotonic): error: -347 ns, adjusted frequency from 2299775173 Hz to 2299773974 Hz D0227 13:42:34.374369 11074 parameters.go:238] Clock(Realtime): error: 786 ns, adjusted frequency from 2299774949 Hz to 2299776537 Hz D0227 13:42:34.374470 11074 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8515828861620088 monotonicBaseRef:60001490096 monotonicFrequency:2299773974 realtimeReady:1 realtimeBaseCycles:8515828862061902 realtimeBaseRef:1582810954374362542 realtimeFrequency:2299776537} D0227 13:42:35.373046 11074 parameters.go:238] Clock(Monotonic): error: -202 ns, adjusted frequency from 2299773974 Hz to 2299774213 Hz D0227 13:42:35.373299 11074 parameters.go:238] Clock(Realtime): error: -582 ns, adjusted frequency from 2299776537 Hz to 2299773305 Hz D0227 13:42:35.373561 11074 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8515831158765937 monotonicBaseRef:61000347320 monotonicFrequency:2299774213 realtimeReady:1 realtimeBaseCycles:8515831159367905 realtimeBaseRef:1582810955373288292 realtimeFrequency:2299773305} D0227 13:42:36.373062 11074 parameters.go:238] Clock(Monotonic): error: 4 ns, adjusted frequency from 2299774213 Hz to 2299774693 Hz D0227 13:42:36.373287 11074 parameters.go:238] Clock(Realtime): error: 110 ns, adjusted frequency from 2299773305 Hz to 2299774912 Hz D0227 13:42:36.373406 11074 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8515833458574428 monotonicBaseRef:62000362224 monotonicFrequency:2299774693 realtimeReady:1 realtimeBaseCycles:8515833459122837 realtimeBaseRef:1582810956373280302 realtimeFrequency:2299774912} D0227 13:42:37.373076 11074 parameters.go:238] Clock(Monotonic): error: -77 ns, adjusted frequency from 2299774693 Hz to 2299774514 Hz D0227 13:42:37.373351 11074 parameters.go:238] Clock(Realtime): error: -455 ns, adjusted frequency from 2299774912 Hz to 2299773622 Hz D0227 13:42:37.373465 11074 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8515835758357904 monotonicBaseRef:63000366043 monotonicFrequency:2299774514 realtimeReady:1 realtimeBaseCycles:8515835759046509 realtimeBaseRef:1582810957373344986 realtimeFrequency:2299773622} D0227 13:42:38.373060 11074 parameters.go:238] Clock(Monotonic): error: 176 ns, adjusted frequency from 2299774514 Hz to 2299775130 Hz D0227 13:42:38.373337 11074 parameters.go:238] Clock(Realtime): error: 499 ns, adjusted frequency from 2299773622 Hz to 2299775825 Hz D0227 13:42:38.373473 11074 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8515838058116315 monotonicBaseRef:64000359041 monotonicFrequency:2299775130 realtimeReady:1 realtimeBaseCycles:8515838058764670 realtimeBaseRef:1582810958373320870 realtimeFrequency:2299775825} D0227 13:42:39.374213 11074 parameters.go:238] Clock(Monotonic): error: 285 ns, adjusted frequency from 2299775130 Hz to 2299775274 Hz D0227 13:42:39.374457 11074 parameters.go:238] Clock(Realtime): error: -105 ns, adjusted frequency from 2299775825 Hz to 2299774399 Hz D0227 13:42:39.374568 11074 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8515840360517703 monotonicBaseRef:65001501003 monotonicFrequency:2299775274 realtimeReady:1 realtimeBaseCycles:8515840361127953 realtimeBaseRef:1582810959374445961 realtimeFrequency:2299774399} D0227 13:42:40.374923 11074 parameters.go:238] Clock(Monotonic): error: -222 ns, adjusted frequency from 2299775274 Hz to 2299774219 Hz D0227 13:42:40.375190 11074 parameters.go:238] Clock(Realtime): error: -246 ns, adjusted frequency from 2299774399 Hz to 2299774176 Hz D0227 13:42:40.375378 11074 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8515842661866661 monotonicBaseRef:66002185280 monotonicFrequency:2299774219 realtimeReady:1 realtimeBaseCycles:8515842662594898 realtimeBaseRef:1582810960375181922 realtimeFrequency:2299774176} D0227 13:42:41.375454 11074 parameters.go:238] Clock(Monotonic): error: -235 ns, adjusted frequency from 2299774219 Hz to 2299774174 Hz D0227 13:42:41.375688 11074 parameters.go:238] Clock(Realtime): error: 135 ns, adjusted frequency from 2299774176 Hz to 2299774968 Hz D0227 13:42:41.375807 11074 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8515844962956830 monotonicBaseRef:67002757488 monotonicFrequency:2299774174 realtimeReady:1 realtimeBaseCycles:8515844963522282 realtimeBaseRef:1582810961375683366 realtimeFrequency:2299774968} D0227 13:42:42.374753 11074 parameters.go:238] Clock(Monotonic): error: 241 ns, adjusted frequency from 2299774174 Hz to 2299775231 Hz D0227 13:42:42.374962 11074 parameters.go:238] Clock(Realtime): error: -98 ns, adjusted frequency from 2299774968 Hz to 2299774414 Hz D0227 13:42:42.375086 11074 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8515847261118733 monotonicBaseRef:68002056431 monotonicFrequency:2299775231 realtimeReady:1 realtimeBaseCycles:8515847261601281 realtimeBaseRef:1582810962374945916 realtimeFrequency:2299774414} D0227 13:42:43.373294 11074 parameters.go:238] Clock(Monotonic): error: -225 ns, adjusted frequency from 2299775231 Hz to 2299774132 Hz D0227 13:42:43.373493 11074 parameters.go:238] Clock(Realtime): error: -113 ns, adjusted frequency from 2299774414 Hz to 2299774404 Hz D0227 13:42:43.373588 11074 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8515849557514089 monotonicBaseRef:69000586776 monotonicFrequency:2299774132 realtimeReady:1 realtimeBaseCycles:8515849558020969 realtimeBaseRef:1582810963373487196 realtimeFrequency:2299774404} D0227 13:42:43.836186 11074 urpc.go:577] urpc: unmarshal success. D0227 13:42:43.836482 11074 controller.go:501] containerManager.Signal &{CID:ci-gvisor-ptrace-proxy-sandbox-race-1 Signo:0 PID:0 Mode:Process} D0227 13:42:43.836776 11074 urpc.go:534] urpc: successfully marshalled 37 bytes. D0227 13:42:44.374881 11074 parameters.go:238] Clock(Monotonic): error: 364 ns, adjusted frequency from 2299774132 Hz to 2299775455 Hz D0227 13:42:44.375087 11074 parameters.go:238] Clock(Realtime): error: 224 ns, adjusted frequency from 2299774404 Hz to 2299775198 Hz D0227 13:42:44.375184 11074 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8515851860960653 monotonicBaseRef:70002183642 monotonicFrequency:2299775455 realtimeReady:1 realtimeBaseCycles:8515851861461036 realtimeBaseRef:1582810964375081118 realtimeFrequency:2299775198} D0227 13:42:45.374783 11074 parameters.go:238] Clock(Monotonic): error: -157 ns, adjusted frequency from 2299775455 Hz to 2299774175 Hz D0227 13:42:45.375006 11074 parameters.go:238] Clock(Realtime): error: -78 ns, adjusted frequency from 2299775198 Hz to 2299774507 Hz D0227 13:42:45.375126 11074 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8515854160491382 monotonicBaseRef:71002077229 monotonicFrequency:2299774175 realtimeReady:1 realtimeBaseCycles:8515854161054608 realtimeBaseRef:1582810965375002142 realtimeFrequency:2299774507} D0227 13:42:46.373021 11074 parameters.go:238] Clock(Monotonic): error: 245 ns, adjusted frequency from 2299774175 Hz to 2299775225 Hz D0227 13:42:46.373231 11074 parameters.go:238] Clock(Realtime): error: 137 ns, adjusted frequency from 2299774507 Hz to 2299774931 Hz D0227 13:42:46.373363 11074 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8515856456230656 monotonicBaseRef:72000322751 monotonicFrequency:2299775225 realtimeReady:1 realtimeBaseCycles:8515856456741576 realtimeBaseRef:1582810966373224776 realtimeFrequency:2299774931} D0227 13:42:47.373656 11074 parameters.go:238] Clock(Monotonic): error: -79 ns, adjusted frequency from 2299775225 Hz to 2299774442 Hz D0227 13:42:47.373906 11074 parameters.go:238] Clock(Realtime): error: -62 ns, adjusted frequency from 2299774931 Hz to 2299774504 Hz D0227 13:42:47.374040 11074 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8515858757445093 monotonicBaseRef:73000948556 monotonicFrequency:2299774442 realtimeReady:1 realtimeBaseCycles:8515858758068776 realtimeBaseRef:1582810967373899741 realtimeFrequency:2299774504} D0227 13:42:48.373268 11074 parameters.go:238] Clock(Monotonic): error: -244 ns, adjusted frequency from 2299774442 Hz to 2299774176 Hz D0227 13:42:48.373495 11074 parameters.go:238] Clock(Realtime): error: 105 ns, adjusted frequency from 2299774504 Hz to 2299774902 Hz D0227 13:42:48.373624 11074 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8515861056343184 monotonicBaseRef:74000567496 monotonicFrequency:2299774176 realtimeReady:1 realtimeBaseCycles:8515861056898421 realtimeBaseRef:1582810968373488892 realtimeFrequency:2299774902} D0227 13:42:49.374165 11074 parameters.go:238] Clock(Monotonic): error: 204 ns, adjusted frequency from 2299774176 Hz to 2299775029 Hz D0227 13:42:49.374395 11074 parameters.go:238] Clock(Realtime): error: -74 ns, adjusted frequency from 2299774902 Hz to 2299774448 Hz D0227 13:42:49.374486 11074 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8515863358158470 monotonicBaseRef:75001455022 monotonicFrequency:2299775029 realtimeReady:1 realtimeBaseCycles:8515863358742967 realtimeBaseRef:1582810969374388825 realtimeFrequency:2299774448} D0227 13:42:50.373628 11074 parameters.go:238] Clock(Monotonic): error: 367 ns, adjusted frequency from 2299775029 Hz to 2299775465 Hz D0227 13:42:50.373836 11074 parameters.go:238] Clock(Realtime): error: -70 ns, adjusted frequency from 2299774448 Hz to 2299774505 Hz D0227 13:42:50.374041 11074 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8515865656688419 monotonicBaseRef:76000913629 monotonicFrequency:2299775465 realtimeReady:1 realtimeBaseCycles:8515865657231488 realtimeBaseRef:1582810970373829671 realtimeFrequency:2299774505} D0227 13:42:51.373305 11074 parameters.go:238] Clock(Monotonic): error: -242 ns, adjusted frequency from 2299775465 Hz to 2299774107 Hz D0227 13:42:51.373539 11074 parameters.go:238] Clock(Realtime): error: 122 ns, adjusted frequency from 2299774505 Hz to 2299774938 Hz D0227 13:42:51.373648 11074 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8515867955719849 monotonicBaseRef:77000590103 monotonicFrequency:2299774107 realtimeReady:1 realtimeBaseCycles:8515867956325119 realtimeBaseRef:1582810971373533609 realtimeFrequency:2299774938} D0227 13:42:52.373310 11074 parameters.go:238] Clock(Monotonic): error: -325 ns, adjusted frequency from 2299774107 Hz to 2299773923 Hz D0227 13:42:52.373546 11074 parameters.go:238] Clock(Realtime): error: 85 ns, adjusted frequency from 2299774938 Hz to 2299774784 Hz D0227 13:42:52.373665 11074 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8515870255536327 monotonicBaseRef:78000608526 monotonicFrequency:2299773923 realtimeReady:1 realtimeBaseCycles:8515870256103462 realtimeBaseRef:1582810972373535089 realtimeFrequency:2299774784} D0227 13:42:53.374056 11074 parameters.go:238] Clock(Monotonic): error: 300 ns, adjusted frequency from 2299773923 Hz to 2299775334 Hz D0227 13:42:53.374235 11074 parameters.go:238] Clock(Realtime): error: -119 ns, adjusted frequency from 2299774784 Hz to 2299774404 Hz D0227 13:42:53.374344 11074 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8515872557008739 monotonicBaseRef:79001347072 monotonicFrequency:2299775334 realtimeReady:1 realtimeBaseCycles:8515872557476042 realtimeBaseRef:1582810973374229850 realtimeFrequency:2299774404} D0227 13:42:54.374134 11074 parameters.go:238] Clock(Monotonic): error: -327 ns, adjusted frequency from 2299775334 Hz to 2299774047 Hz D0227 13:42:54.374345 11074 parameters.go:238] Clock(Realtime): error: 43 ns, adjusted frequency from 2299774404 Hz to 2299774741 Hz D0227 13:42:54.374460 11074 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8515874856978678 monotonicBaseRef:80001431691 monotonicFrequency:2299774047 realtimeReady:1 realtimeBaseCycles:8515874857502312 realtimeBaseRef:1582810974374339367 realtimeFrequency:2299774741} D0227 13:42:55.373791 11074 parameters.go:238] Clock(Monotonic): error: 297 ns, adjusted frequency from 2299774047 Hz to 2299775277 Hz D0227 13:42:55.374007 11074 parameters.go:238] Clock(Realtime): error: 46 ns, adjusted frequency from 2299774741 Hz to 2299774808 Hz D0227 13:42:55.375056 11074 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8515877155977587 monotonicBaseRef:81001094641 monotonicFrequency:2299775277 realtimeReady:1 realtimeBaseCycles:8515877156500286 realtimeBaseRef:1582810975374001609 realtimeFrequency:2299774808} D0227 13:42:56.373071 11074 parameters.go:238] Clock(Monotonic): error: -286 ns, adjusted frequency from 2299775277 Hz to 2299774070 Hz D0227 13:42:56.373309 11074 parameters.go:238] Clock(Realtime): error: -252 ns, adjusted frequency from 2299774808 Hz to 2299774141 Hz D0227 13:42:56.373427 11074 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8515879454088101 monotonicBaseRef:82000370760 monotonicFrequency:2299774070 realtimeReady:1 realtimeBaseCycles:8515879454666375 realtimeBaseRef:1582810976373302097 realtimeFrequency:2299774141} D0227 13:42:57.373784 11074 parameters.go:238] Clock(Monotonic): error: 311 ns, adjusted frequency from 2299774070 Hz to 2299775236 Hz D0227 13:42:57.374022 11074 parameters.go:238] Clock(Realtime): error: 257 ns, adjusted frequency from 2299774141 Hz to 2299775266 Hz D0227 13:42:57.374114 11074 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8515881755503743 monotonicBaseRef:83001084557 monotonicFrequency:2299775236 realtimeReady:1 realtimeBaseCycles:8515881756083374 realtimeBaseRef:1582810977374016453 realtimeFrequency:2299775266} D0227 13:42:58.374149 11074 parameters.go:238] Clock(Monotonic): error: 39 ns, adjusted frequency from 2299775236 Hz to 2299774767 Hz D0227 13:42:58.374381 11074 parameters.go:238] Clock(Realtime): error: -330 ns, adjusted frequency from 2299775266 Hz to 2299773964 Hz D0227 13:42:58.374481 11074 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8515884056124402 monotonicBaseRef:84001452168 monotonicFrequency:2299774767 realtimeReady:1 realtimeBaseCycles:8515884056681237 realtimeBaseRef:1582810978374374138 realtimeFrequency:2299773964} D0227 13:42:59.373029 11074 parameters.go:238] Clock(Monotonic): error: 111 ns, adjusted frequency from 2299774767 Hz to 2299774917 Hz D0227 13:42:59.373243 11074 parameters.go:238] Clock(Realtime): error: -20 ns, adjusted frequency from 2299773964 Hz to 2299774658 Hz D0227 13:42:59.373357 11074 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8515886353291581 monotonicBaseRef:85000318323 monotonicFrequency:2299774917 realtimeReady:1 realtimeBaseCycles:8515886353833911 realtimeBaseRef:1582810979373234334 realtimeFrequency:2299774658} D0227 13:43:00.376204 11074 parameters.go:238] Clock(Monotonic): error: -413 ns, adjusted frequency from 2299774917 Hz to 2299773796 Hz D0227 13:43:00.376511 11074 parameters.go:238] Clock(Realtime): error: 98 ns, adjusted frequency from 2299774658 Hz to 2299774966 Hz D0227 13:43:00.376697 11074 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8515888660362790 monotonicBaseRef:86003490934 monotonicFrequency:2299773796 realtimeReady:1 realtimeBaseCycles:8515888661120980 realtimeBaseRef:1582810980376500919 realtimeFrequency:2299774966} D0227 13:43:01.374518 11074 parameters.go:238] Clock(Monotonic): error: -84 ns, adjusted frequency from 2299773796 Hz to 2299774475 Hz D0227 13:43:01.374734 11074 parameters.go:238] Clock(Realtime): error: 11 ns, adjusted frequency from 2299774966 Hz to 2299774735 Hz D0227 13:43:01.374873 11074 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8515890956288626 monotonicBaseRef:87001817743 monotonicFrequency:2299774475 realtimeReady:1 realtimeBaseCycles:8515890956820012 realtimeBaseRef:1582810981374728600 realtimeFrequency:2299774735} D0227 13:43:02.376092 11074 parameters.go:238] Clock(Monotonic): error: 144 ns, adjusted frequency from 2299774475 Hz to 2299775038 Hz D0227 13:43:02.376349 11074 parameters.go:238] Clock(Realtime): error: -163 ns, adjusted frequency from 2299774735 Hz to 2299774356 Hz D0227 13:43:02.376485 11074 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8515893259676328 monotonicBaseRef:88003388865 monotonicFrequency:2299775038 realtimeReady:1 realtimeBaseCycles:8515893260305707 realtimeBaseRef:1582810982376342218 realtimeFrequency:2299774356} D0227 13:43:03.375309 11074 parameters.go:238] Clock(Monotonic): error: -109 ns, adjusted frequency from 2299775038 Hz to 2299774425 Hz D0227 13:43:03.375550 11074 parameters.go:238] Clock(Realtime): error: 163 ns, adjusted frequency from 2299774356 Hz to 2299775054 Hz D0227 13:43:03.375650 11074 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8515895557655166 monotonicBaseRef:89002607832 monotonicFrequency:2299774425 realtimeReady:1 realtimeBaseCycles:8515895558245617 realtimeBaseRef:1582810983375544554 realtimeFrequency:2299775054} D0227 13:43:04.373453 11074 parameters.go:238] Clock(Monotonic): error: 462 ns, adjusted frequency from 2299774425 Hz to 2299775793 Hz D0227 13:43:04.373703 11074 parameters.go:238] Clock(Realtime): error: 283 ns, adjusted frequency from 2299775054 Hz to 2299775249 Hz D0227 13:43:04.373847 11074 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8515897853158049 monotonicBaseRef:90000750457 monotonicFrequency:2299775793 realtimeReady:1 realtimeBaseCycles:8515897853769414 realtimeBaseRef:1582810984373696000 realtimeFrequency:2299775249} I0227 13:43:04.543092 11074 watchdog.go:277] Watchdog starting loop, tasks: 14, discount: 0s D0227 13:43:05.374972 11074 parameters.go:238] Clock(Monotonic): error: -167 ns, adjusted frequency from 2299775793 Hz to 2299774214 Hz D0227 13:43:05.375194 11074 parameters.go:238] Clock(Realtime): error: -166 ns, adjusted frequency from 2299775249 Hz to 2299774259 Hz D0227 13:43:05.375353 11074 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8515900156417163 monotonicBaseRef:91002265092 monotonicFrequency:2299774214 realtimeReady:1 realtimeBaseCycles:8515900156976160 realtimeBaseRef:1582810985375188101 realtimeFrequency:2299774259} D0227 13:43:06.373171 11074 parameters.go:238] Clock(Monotonic): error: -275 ns, adjusted frequency from 2299774214 Hz to 2299774185 Hz D0227 13:43:06.373568 11074 parameters.go:238] Clock(Realtime): error: -137 ns, adjusted frequency from 2299774259 Hz to 2299774352 Hz D0227 13:43:06.373682 11074 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8515902452030442 monotonicBaseRef:92000455812 monotonicFrequency:2299774185 realtimeReady:1 realtimeBaseCycles:8515902453009845 realtimeBaseRef:1582810986373561604 realtimeFrequency:2299774352} D0227 13:43:07.373104 11074 parameters.go:238] Clock(Monotonic): error: 320 ns, adjusted frequency from 2299774185 Hz to 2299775453 Hz D0227 13:43:07.373398 11074 parameters.go:238] Clock(Realtime): error: 183 ns, adjusted frequency from 2299774352 Hz to 2299775037 Hz D0227 13:43:07.373509 11074 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8515904751695951 monotonicBaseRef:93000408556 monotonicFrequency:2299775453 realtimeReady:1 realtimeBaseCycles:8515904752389497 realtimeBaseRef:1582810987373389978 realtimeFrequency:2299775037} D0227 13:43:07.455146 11074 urpc.go:577] urpc: unmarshal success. D0227 13:43:07.455368 11074 controller.go:501] containerManager.Signal &{CID:ci-gvisor-ptrace-proxy-sandbox-race-1 Signo:0 PID:0 Mode:Process} D0227 13:43:07.456329 11074 urpc.go:534] urpc: successfully marshalled 37 bytes. D0227 13:43:08.373310 11074 parameters.go:238] Clock(Monotonic): error: -416 ns, adjusted frequency from 2299775453 Hz to 2299773776 Hz D0227 13:43:08.373668 11074 parameters.go:238] Clock(Realtime): error: 83 ns, adjusted frequency from 2299775037 Hz to 2299774785 Hz D0227 13:43:08.373787 11074 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8515907051945449 monotonicBaseRef:94000614682 monotonicFrequency:2299773776 realtimeReady:1 realtimeBaseCycles:8515907052779426 realtimeBaseRef:1582810988373657348 realtimeFrequency:2299774785} I0227 13:43:08.918822 21770 main.go:296] *************************** I0227 13:43:08.918988 21770 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-1 /syz-execprog -executor=/syz-executor -arch=amd64 -sandbox=none -procs=1 -repeat=0 -threaded=true -collide=true -cover=0 -fault_call=-1 -fault_nth=-1 /syzkaller726846751] I0227 13:43:08.919429 21770 main.go:298] Version release-20200219.0-47-gde0b2ebf8635 I0227 13:43:08.919506 21770 main.go:299] PID: 21770 I0227 13:43:08.919710 21770 main.go:300] UID: 0, GID: 0 I0227 13:43:08.919810 21770 main.go:301] Configuration: I0227 13:43:08.919915 21770 main.go:302] RootDir: /syzkaller/managers/ptrace-proxy-sandbox-race/workdir/gvisor_root I0227 13:43:08.920010 21770 main.go:303] Platform: ptrace I0227 13:43:08.920104 21770 main.go:304] FileAccess: shared, overlay: false I0227 13:43:08.920203 21770 main.go:305] Network: sandbox, logging: false I0227 13:43:08.920290 21770 main.go:306] Strace: false, max size: 1024, syscalls: [] I0227 13:43:08.920413 21770 main.go:307] *************************** W0227 13:43:08.920526 21770 main.go:312] Block the TERM signal. This is only safe in tests! D0227 13:43:08.921111 21770 container.go:158] Load container "/syzkaller/managers/ptrace-proxy-sandbox-race/workdir/gvisor_root" "ci-gvisor-ptrace-proxy-sandbox-race-1" D0227 13:43:08.928029 21770 container.go:591] Signal container "ci-gvisor-ptrace-proxy-sandbox-race-1": signal 0 D0227 13:43:08.928258 21770 sandbox.go:788] Signal sandbox "ci-gvisor-ptrace-proxy-sandbox-race-1" D0227 13:43:08.928447 21770 sandbox.go:318] Connecting to sandbox "ci-gvisor-ptrace-proxy-sandbox-race-1" D0227 13:43:08.929527 11074 urpc.go:577] urpc: unmarshal success. D0227 13:43:08.929773 11074 controller.go:501] containerManager.Signal &{CID:ci-gvisor-ptrace-proxy-sandbox-race-1 Signo:0 PID:0 Mode:Process} D0227 13:43:08.929995 11074 urpc.go:534] urpc: successfully marshalled 37 bytes. D0227 13:43:08.930123 21770 urpc.go:534] urpc: successfully marshalled 117 bytes. D0227 13:43:08.930348 21770 urpc.go:577] urpc: unmarshal success. D0227 13:43:08.930529 21770 exec.go:120] Exec arguments: /syz-execprog -executor=/syz-executor -arch=amd64 -sandbox=none -procs=1 -repeat=0 -threaded=true -collide=true -cover=0 -fault_call=-1 -fault_nth=-1 /syzkaller726846751 D0227 13:43:08.930666 21770 exec.go:121] Exec capablities: &{PermittedCaps:274877898751 InheritableCaps:274877898751 EffectiveCaps:274877898751 BoundingCaps:274877898751 AmbientCaps:0} D0227 13:43:08.930782 21770 container.go:532] Execute in container "ci-gvisor-ptrace-proxy-sandbox-race-1", args: /syz-execprog -executor=/syz-executor -arch=amd64 -sandbox=none -procs=1 -repeat=0 -threaded=true -collide=true -cover=0 -fault_call=-1 -fault_nth=-1 /syzkaller726846751 D0227 13:43:08.930901 21770 sandbox.go:283] Executing new process in container "ci-gvisor-ptrace-proxy-sandbox-race-1" in sandbox "ci-gvisor-ptrace-proxy-sandbox-race-1" D0227 13:43:08.931020 21770 sandbox.go:318] Connecting to sandbox "ci-gvisor-ptrace-proxy-sandbox-race-1" D0227 13:43:08.932174 21770 urpc.go:534] urpc: successfully marshalled 634 bytes. D0227 13:43:08.932660 11074 urpc.go:577] urpc: unmarshal success. D0227 13:43:08.935349 11074 controller.go:267] containerManager.ExecuteAsync: /syz-execprog -executor=/syz-executor -arch=amd64 -sandbox=none -procs=1 -repeat=0 -threaded=true -collide=true -cover=0 -fault_call=-1 -fault_nth=-1 /syzkaller726846751 D0227 13:43:08.935760 11074 transport_flipcall.go:127] send [channel @0xc0003a03c0] Twalkgetattr{FID: 1, NewFID: 5, Names: [etc]} D0227 13:43:08.937356 11074 transport_flipcall.go:234] recv [channel @0xc0003a03c0] Rlerror{Error: 2} D0227 13:43:08.937494 11074 transport_flipcall.go:127] send [channel @0xc0003a03c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.etc, Size: 1} D0227 13:43:08.938008 11074 transport_flipcall.go:234] recv [channel @0xc0003a03c0] Rlerror{Error: 95} I0227 13:43:08.938475 11074 kernel.go:795] EXEC: [/syz-execprog -executor=/syz-executor -arch=amd64 -sandbox=none -procs=1 -repeat=0 -threaded=true -collide=true -cover=0 -fault_call=-1 -fault_nth=-1 /syzkaller726846751] D0227 13:43:08.940362 11074 transport_flipcall.go:127] send [channel @0xc0003a03c0] Twalkgetattr{FID: 1, NewFID: 5, Names: [syz-execprog]} D0227 13:43:08.941538 11074 transport_flipcall.go:234] recv [channel @0xc0003a03c0] 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: 1582810895, NanoSec: 702780192}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}, QIDs: [QID{Type: 0, Version: 0, Path: 16515325}]} D0227 13:43:08.941864 11074 transport_flipcall.go:127] send [channel @0xc0003a03c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syz-execprog, Size: 1} D0227 13:43:08.942593 11074 transport_flipcall.go:234] recv [channel @0xc0003a03c0] Rlerror{Error: 95} D0227 13:43:08.942912 11074 transport_flipcall.go:127] send [channel @0xc0003a03c0] Tgetattr{FID: 5, AttrMask: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime INo Size Blocks BTime Gen DataVersion}} D0227 13:43:08.943976 11074 transport_flipcall.go:234] recv [channel @0xc0003a03c0] Rgetattr{Valid: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime Size Blocks}, QID: QID{Type: 0, Version: 0, Path: 16515325}, 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: 1582810895, NanoSec: 702780192}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}} D0227 13:43:08.944900 11074 transport_flipcall.go:127] send [channel @0xc0003a03c0] Twalk{FID: 5, NewFID: 6, Names: []} D0227 13:43:08.945516 11074 transport_flipcall.go:234] recv [channel @0xc0003a03c0] Rwalk{QIDs: []} D0227 13:43:08.945671 11074 transport_flipcall.go:127] send [channel @0xc0003a03c0] Tlopen{FID: 6, Flags: ReadOnly} D0227 13:43:08.946516 11074 transport_flipcall.go:234] recv [channel @0xc0003a03c0] Rlopen{QID: QID{Type: 0, Version: 0, Path: 16515325}, IoUnit: 0, File: &{{33}}} D0227 13:43:08.956769 11074 syscalls.go:266] Allocating stack with size of 8388608 bytes D0227 13:43:08.958126 11074 loader.go:832] updated processes: map[{ci-gvisor-ptrace-proxy-sandbox-race-1 0}:0xc00000e7c0 {ci-gvisor-ptrace-proxy-sandbox-race-1 20}:0xc00000e040] D0227 13:43:08.958485 11074 urpc.go:534] urpc: successfully marshalled 37 bytes. D0227 13:43:08.960118 21770 urpc.go:577] urpc: unmarshal success. D0227 13:43:08.960301 21770 container.go:579] Wait on PID 20 in container "ci-gvisor-ptrace-proxy-sandbox-race-1" D0227 13:43:08.960424 21770 sandbox.go:743] Waiting for PID 20 in sandbox "ci-gvisor-ptrace-proxy-sandbox-race-1" D0227 13:43:08.960551 21770 sandbox.go:318] Connecting to sandbox "ci-gvisor-ptrace-proxy-sandbox-race-1" D0227 13:43:08.961012 21770 urpc.go:534] urpc: successfully marshalled 100 bytes. D0227 13:43:08.962255 11074 urpc.go:577] urpc: unmarshal success. D0227 13:43:08.962521 11074 controller.go:443] containerManager.Wait D0227 13:43:08.964719 11074 task_block.go:223] [ 20] Interrupt queued D0227 13:43:09.195085 11074 task_block.go:223] [ 21] Interrupt queued D0227 13:43:09.196565 11074 task_block.go:223] [ 22] Interrupt queued D0227 13:43:09.211098 11074 task_block.go:223] [ 23] Interrupt queued D0227 13:43:09.225717 11074 task_block.go:223] [ 24] Interrupt queued D0227 13:43:09.239309 11074 task_block.go:223] [ 25] Interrupt queued D0227 13:43:09.244009 11074 task_block.go:223] [ 26] Interrupt queued D0227 13:43:09.297981 11074 transport_flipcall.go:127] send [channel @0xc0003a03c0] Twalkgetattr{FID: 1, NewFID: 7, Names: [etc]} D0227 13:43:09.298760 11074 transport_flipcall.go:234] recv [channel @0xc0003a03c0] Rlerror{Error: 2} D0227 13:43:09.299548 11074 transport_flipcall.go:127] send [channel @0xc0003a03c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.etc, Size: 1} D0227 13:43:09.300030 11074 transport_flipcall.go:234] recv [channel @0xc0003a03c0] Rlerror{Error: 95} D0227 13:43:09.336556 11074 task_block.go:223] [ 27] Interrupt queued D0227 13:43:09.340848 11074 task_block.go:223] [ 28] Interrupt queued D0227 13:43:09.359327 11074 task_block.go:223] [ 29] Interrupt queued D0227 13:43:09.366461 11074 task_block.go:223] [ 30] Interrupt queued D0227 13:43:09.374215 11074 parameters.go:238] Clock(Monotonic): error: 909 ns, adjusted frequency from 2299773776 Hz to 2299776783 Hz D0227 13:43:09.374477 11074 parameters.go:238] Clock(Realtime): error: -166 ns, adjusted frequency from 2299774785 Hz to 2299774228 Hz D0227 13:43:09.374582 11074 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8515909353786683 monotonicBaseRef:95001513665 monotonicFrequency:2299776783 realtimeReady:1 realtimeBaseCycles:8515909354428049 realtimeBaseRef:1582810989374472139 realtimeFrequency:2299774228} D0227 13:43:09.399977 11074 task_block.go:223] [ 31] Interrupt queued D0227 13:43:09.411131 11074 task_block.go:223] [ 32] Interrupt queued D0227 13:43:09.448944 11074 task_block.go:223] [ 33] Interrupt queued D0227 13:43:09.455218 11074 transport_flipcall.go:127] send [channel @0xc0003a03c0] Twalkgetattr{FID: 1, NewFID: 7, Names: [syzkaller726846751]} D0227 13:43:09.455507 11074 task_block.go:223] [ 34] Interrupt queued D0227 13:43:09.457054 11074 transport_flipcall.go:234] recv [channel @0xc0003a03c0] 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: 205, BlockSize: 4096, Blocks: 8, ATime: {Sec: 1582810988, NanoSec: 871907774}, MTime: {Sec: 1582810988, NanoSec: 871907774}, CTime: {Sec: 1582810988, NanoSec: 871907774}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}, QIDs: [QID{Type: 0, Version: 0, Path: 16515232}]} D0227 13:43:09.457413 11074 transport_flipcall.go:127] send [channel @0xc0003a03c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzkaller726846751, Size: 1} D0227 13:43:09.459019 11074 transport_flipcall.go:234] recv [channel @0xc0003a03c0] Rlerror{Error: 95} D0227 13:43:09.459467 11074 transport_flipcall.go:127] send [channel @0xc0003a03c0] Tgetattr{FID: 7, AttrMask: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime INo Size Blocks BTime Gen DataVersion}} D0227 13:43:09.460444 11074 transport_flipcall.go:234] recv [channel @0xc0003a03c0] Rgetattr{Valid: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime Size Blocks}, QID: QID{Type: 0, Version: 0, Path: 16515232}, Attr: Attr{Mode: 0o100600, UID: 0, GID: 0, NLink: 1, RDev: 0, Size: 205, BlockSize: 4096, Blocks: 8, ATime: {Sec: 1582810988, NanoSec: 871907774}, MTime: {Sec: 1582810988, NanoSec: 871907774}, CTime: {Sec: 1582810988, NanoSec: 871907774}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}} D0227 13:43:09.460655 11074 transport_flipcall.go:127] send [channel @0xc0003a03c0] Twalk{FID: 7, NewFID: 8, Names: []} D0227 13:43:09.461414 11074 transport_flipcall.go:234] recv [channel @0xc0003a03c0] Rwalk{QIDs: []} D0227 13:43:09.461614 11074 transport_flipcall.go:127] send [channel @0xc0003a03c0] Tlopen{FID: 8, Flags: ReadOnly} D0227 13:43:09.462571 11074 transport_flipcall.go:234] recv [channel @0xc0003a03c0] Rlopen{QID: QID{Type: 0, Version: 0, Path: 16515232}, IoUnit: 0, File: &{{27}}} D0227 13:43:09.463311 11074 transport_flipcall.go:127] send [channel @0xc0003a03c0] Tgetattr{FID: 8, AttrMask: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime INo Size Blocks BTime Gen DataVersion}} D0227 13:43:09.464433 11074 transport_flipcall.go:234] recv [channel @0xc0003a03c0] Rgetattr{Valid: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime Size Blocks}, QID: QID{Type: 0, Version: 0, Path: 16515232}, Attr: Attr{Mode: 0o100600, UID: 0, GID: 0, NLink: 1, RDev: 0, Size: 205, BlockSize: 4096, Blocks: 8, ATime: {Sec: 1582810988, NanoSec: 871907774}, MTime: {Sec: 1582810988, NanoSec: 871907774}, CTime: {Sec: 1582810988, NanoSec: 871907774}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}} 2020/02/27 13:43:09 parsed 1 programs D0227 13:43:09.473153 11074 task_stop.go:118] [ 29] Entering internal stop (*kernel.vforkStop)(nil) D0227 13:43:09.473809 11074 task_block.go:223] [ 35] Interrupt queued D0227 13:43:09.478063 11074 transport_flipcall.go:127] send [channel @0xc0003a03c0] Twalkgetattr{FID: 1, NewFID: 9, Names: [syz-executor]} D0227 13:43:09.479383 11074 transport_flipcall.go:234] recv [channel @0xc0003a03c0] 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: 1582810895, NanoSec: 706780584}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}, QIDs: [QID{Type: 0, Version: 0, Path: 16515338}]} D0227 13:43:09.479604 11074 transport_flipcall.go:127] send [channel @0xc0003a03c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syz-executor, Size: 1} D0227 13:43:09.480063 11074 transport_flipcall.go:234] recv [channel @0xc0003a03c0] Rlerror{Error: 95} D0227 13:43:09.480294 11074 transport_flipcall.go:127] send [channel @0xc0003a03c0] Tgetattr{FID: 9, AttrMask: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime INo Size Blocks BTime Gen DataVersion}} D0227 13:43:09.481154 11074 transport_flipcall.go:234] recv [channel @0xc0003a03c0] Rgetattr{Valid: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime Size Blocks}, QID: QID{Type: 0, Version: 0, Path: 16515338}, 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: 1582810895, NanoSec: 706780584}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}} D0227 13:43:09.481323 11074 transport_flipcall.go:127] send [channel @0xc0003a03c0] Twalk{FID: 9, NewFID: 10, Names: []} D0227 13:43:09.482125 11074 transport_flipcall.go:234] recv [channel @0xc0003a03c0] Rwalk{QIDs: []} D0227 13:43:09.482321 11074 transport_flipcall.go:127] send [channel @0xc0003a03c0] Tlopen{FID: 10, Flags: ReadOnly} D0227 13:43:09.483104 11074 transport_flipcall.go:234] recv [channel @0xc0003a03c0] Rlopen{QID: QID{Type: 0, Version: 0, Path: 16515338}, IoUnit: 0, File: &{{28}}} D0227 13:43:09.484171 11074 syscalls.go:266] [ 35] Allocating stack with size of 8388608 bytes D0227 13:43:09.485108 11074 task_stop.go:137] [ 29] Leaving internal stop (*kernel.vforkStop)(nil) D0227 13:43:09.506194 11074 transport_flipcall.go:127] send [channel @0xc0003a03c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [etc]} D0227 13:43:09.506757 11074 transport_flipcall.go:234] recv [channel @0xc0003a03c0] Rlerror{Error: 2} D0227 13:43:09.506902 11074 transport_flipcall.go:127] send [channel @0xc0003a03c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.etc, Size: 1} D0227 13:43:09.507341 11074 transport_flipcall.go:234] recv [channel @0xc0003a03c0] Rlerror{Error: 95} D0227 13:43:09.508750 11074 task_exit.go:221] [ 35] Transitioning from exit state TaskExitNone to TaskExitInitiated D0227 13:43:09.509981 11074 task_exit.go:221] [ 35] Transitioning from exit state TaskExitInitiated to TaskExitZombie D0227 13:43:09.510142 11074 task_signals.go:446] [ 20] Notified of signal 17 D0227 13:43:09.510253 11074 task_block.go:223] [ 20] Interrupt queued D0227 13:43:09.510372 11074 task_block.go:223] [ 20] Interrupt queued D0227 13:43:09.510568 11074 task_signals.go:179] [ 20] Restarting syscall 202 after errno 512: interrupted by signal 17 D0227 13:43:09.510701 11074 task_signals.go:220] [ 20] Signal 17: delivering to handler D0227 13:43:09.510945 11074 task_exit.go:221] [ 35] Transitioning from exit state TaskExitZombie to TaskExitDead D0227 13:43:09.513495 11074 task_block.go:223] [ 36] Interrupt queued D0227 13:43:09.519072 11074 transport_flipcall.go:127] send [channel @0xc0003a03c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syz-executor]} D0227 13:43:09.520462 11074 transport_flipcall.go:234] recv [channel @0xc0003a03c0] 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: 1582810895, NanoSec: 706780584}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}, QIDs: [QID{Type: 0, Version: 0, Path: 16515338}]} D0227 13:43:09.520689 11074 transport_flipcall.go:127] send [channel @0xc0003a03c0] Tclunk{FID: 11} D0227 13:43:09.521182 11074 transport_flipcall.go:234] recv [channel @0xc0003a03c0] Rclunk{} D0227 13:43:09.521410 11074 transport_flipcall.go:127] send [channel @0xc0003a03c0] Tgetattr{FID: 9, AttrMask: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime INo Size Blocks BTime Gen DataVersion}} D0227 13:43:09.522209 11074 transport_flipcall.go:234] recv [channel @0xc0003a03c0] Rgetattr{Valid: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime Size Blocks}, QID: QID{Type: 0, Version: 0, Path: 16515338}, 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: 1582810895, NanoSec: 706780584}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}} 2020/02/27 13:43:09 executed programs: 0 D0227 13:43:09.527399 11074 task_stop.go:118] [ 33] Entering internal stop (*kernel.vforkStop)(nil) D0227 13:43:09.528090 11074 task_block.go:223] [ 37] Interrupt queued D0227 13:43:09.533597 11074 transport_flipcall.go:127] send [channel @0xc0003a03c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syz-executor]} D0227 13:43:09.534570 11074 transport_flipcall.go:234] recv [channel @0xc0003a03c0] 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: 1582810895, NanoSec: 706780584}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}, QIDs: [QID{Type: 0, Version: 0, Path: 16515338}]} D0227 13:43:09.534717 11074 transport_flipcall.go:127] send [channel @0xc0003a03c0] Tclunk{FID: 11} D0227 13:43:09.535219 11074 transport_flipcall.go:234] recv [channel @0xc0003a03c0] Rclunk{} D0227 13:43:09.535417 11074 transport_flipcall.go:127] send [channel @0xc0003a03c0] Tgetattr{FID: 9, AttrMask: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime INo Size Blocks BTime Gen DataVersion}} D0227 13:43:09.536524 11074 transport_flipcall.go:234] recv [channel @0xc0003a03c0] Rgetattr{Valid: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime Size Blocks}, QID: QID{Type: 0, Version: 0, Path: 16515338}, 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: 1582810895, NanoSec: 706780584}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}} D0227 13:43:09.537722 11074 syscalls.go:266] [ 37] Allocating stack with size of 8388608 bytes D0227 13:43:09.538673 11074 task_stop.go:137] [ 33] Leaving internal stop (*kernel.vforkStop)(nil) D0227 13:43:09.546008 11074 transport_flipcall.go:127] send [channel @0xc0003a03c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [etc]} D0227 13:43:09.546681 11074 transport_flipcall.go:234] recv [channel @0xc0003a03c0] Rlerror{Error: 2} D0227 13:43:09.546894 11074 transport_flipcall.go:127] send [channel @0xc0003a03c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.etc, Size: 1} D0227 13:43:09.547370 11074 transport_flipcall.go:234] recv [channel @0xc0003a03c0] Rlerror{Error: 95} D0227 13:43:09.559126 11074 task_block.go:223] [ 38] Interrupt queued D0227 13:43:09.567336 11074 transport_flipcall.go:127] send [channel @0xc0003a03c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0227 13:43:09.568078 11074 transport_flipcall.go:234] recv [channel @0xc0003a03c0] Rlerror{Error: 2} D0227 13:43:09.568301 11074 transport_flipcall.go:127] send [channel @0xc0003a03c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0227 13:43:09.568794 11074 transport_flipcall.go:234] recv [channel @0xc0003a03c0] Rlerror{Error: 95} D0227 13:43:09.569232 11074 transport_flipcall.go:127] send [channel @0xc0003a03c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0227 13:43:09.569728 11074 transport_flipcall.go:234] recv [channel @0xc0003a03c0] Rlerror{Error: 2} D0227 13:43:09.569929 11074 transport_flipcall.go:127] send [channel @0xc0003a03c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0227 13:43:09.570419 11074 transport_flipcall.go:234] recv [channel @0xc0003a03c0] Rlerror{Error: 95} D0227 13:43:09.570926 11074 transport_flipcall.go:127] send [channel @0xc0003a03c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0227 13:43:09.571489 11074 transport_flipcall.go:234] recv [channel @0xc0003a03c0] Rlerror{Error: 2} D0227 13:43:09.571661 11074 transport_flipcall.go:127] send [channel @0xc0003a03c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0227 13:43:09.572080 11074 transport_flipcall.go:234] recv [channel @0xc0003a03c0] Rlerror{Error: 95} D0227 13:43:09.572515 11074 transport_flipcall.go:127] send [channel @0xc0003a03c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0227 13:43:09.573161 11074 transport_flipcall.go:234] recv [channel @0xc0003a03c0] Rlerror{Error: 2} D0227 13:43:09.573394 11074 transport_flipcall.go:127] send [channel @0xc0003a03c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0227 13:43:09.573813 11074 transport_flipcall.go:234] recv [channel @0xc0003a03c0] Rlerror{Error: 95} D0227 13:43:09.574131 11074 transport_flipcall.go:127] send [channel @0xc0003a03c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0227 13:43:09.574612 11074 transport_flipcall.go:234] recv [channel @0xc0003a03c0] Rlerror{Error: 2} D0227 13:43:09.574745 11074 transport_flipcall.go:127] send [channel @0xc0003a03c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0227 13:43:09.575104 11074 transport_flipcall.go:234] recv [channel @0xc0003a03c0] Rlerror{Error: 95} D0227 13:43:09.575564 11074 transport_flipcall.go:127] send [channel @0xc0003a03c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0227 13:43:09.576068 11074 transport_flipcall.go:234] recv [channel @0xc0003a03c0] Rlerror{Error: 2} D0227 13:43:09.576210 11074 transport_flipcall.go:127] send [channel @0xc0003a03c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0227 13:43:09.576618 11074 transport_flipcall.go:234] recv [channel @0xc0003a03c0] Rlerror{Error: 95} D0227 13:43:09.576959 11074 transport_flipcall.go:127] send [channel @0xc0003a03c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0227 13:43:09.577442 11074 transport_flipcall.go:234] recv [channel @0xc0003a03c0] Rlerror{Error: 2} D0227 13:43:09.577622 11074 transport_flipcall.go:127] send [channel @0xc0003a03c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0227 13:43:09.577978 11074 transport_flipcall.go:234] recv [channel @0xc0003a03c0] Rlerror{Error: 95} D0227 13:43:09.578311 11074 transport_flipcall.go:127] send [channel @0xc0003a03c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0227 13:43:09.578793 11074 transport_flipcall.go:234] recv [channel @0xc0003a03c0] Rlerror{Error: 2} D0227 13:43:09.578962 11074 transport_flipcall.go:127] send [channel @0xc0003a03c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0227 13:43:09.579640 11074 transport_flipcall.go:234] recv [channel @0xc0003a03c0] Rlerror{Error: 95} D0227 13:43:09.580207 11074 transport_flipcall.go:127] send [channel @0xc0003a03c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0227 13:43:09.580987 11074 transport_flipcall.go:234] recv [channel @0xc0003a03c0] Rlerror{Error: 2} D0227 13:43:09.581131 11074 transport_flipcall.go:127] send [channel @0xc0003a03c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0227 13:43:09.581669 11074 transport_flipcall.go:234] recv [channel @0xc0003a03c0] Rlerror{Error: 95} D0227 13:43:09.611562 11074 netfilter.go:54] netfilter: convert to binary: current offset: 0 D0227 13:43:09.611764 11074 netfilter.go:54] netfilter: convert to binary: found hook 0 at offset 0 D0227 13:43:09.611890 11074 netfilter.go:54] netfilter: convert to binary: found underflow 0 at offset 0 D0227 13:43:09.611960 11074 netfilter.go:54] netfilter: convert to binary: marshalling standard target D0227 13:43:09.612048 11074 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 13:43:09.612752 11074 netfilter.go:54] netfilter: convert to binary: current offset: 152 D0227 13:43:09.612861 11074 netfilter.go:54] netfilter: convert to binary: found hook 1 at offset 152 D0227 13:43:09.612950 11074 netfilter.go:54] netfilter: convert to binary: found underflow 1 at offset 152 D0227 13:43:09.613050 11074 netfilter.go:54] netfilter: convert to binary: marshalling standard target D0227 13:43:09.613154 11074 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 13:43:09.613693 11074 netfilter.go:54] netfilter: convert to binary: current offset: 304 D0227 13:43:09.613820 11074 netfilter.go:54] netfilter: convert to binary: found hook 3 at offset 304 D0227 13:43:09.613897 11074 netfilter.go:54] netfilter: convert to binary: found underflow 3 at offset 304 D0227 13:43:09.613998 11074 netfilter.go:54] netfilter: convert to binary: marshalling standard target D0227 13:43:09.614109 11074 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 13:43:09.614687 11074 netfilter.go:54] netfilter: convert to binary: current offset: 456 D0227 13:43:09.614859 11074 netfilter.go:54] netfilter: convert to binary: found hook 4 at offset 456 D0227 13:43:09.615020 11074 netfilter.go:54] netfilter: convert to binary: found underflow 4 at offset 456 D0227 13:43:09.615147 11074 netfilter.go:54] netfilter: convert to binary: marshalling standard target D0227 13:43:09.615258 11074 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 13:43:09.615783 11074 netfilter.go:54] netfilter: convert to binary: current offset: 608 D0227 13:43:09.615893 11074 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 13:43:09.616469 11074 netfilter.go:54] netfilter: convert to binary: finished with an marshalled size of 0 D0227 13:43:09.616550 11074 netfilter.go:54] netfilter: convert to binary: current offset: 0 D0227 13:43:09.616643 11074 netfilter.go:54] netfilter: convert to binary: found hook 0 at offset 0 D0227 13:43:09.616717 11074 netfilter.go:54] netfilter: convert to binary: found underflow 0 at offset 0 D0227 13:43:09.616806 11074 netfilter.go:54] netfilter: convert to binary: marshalling standard target D0227 13:43:09.616896 11074 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 13:43:09.617358 11074 netfilter.go:54] netfilter: convert to binary: current offset: 152 D0227 13:43:09.617420 11074 netfilter.go:54] netfilter: convert to binary: found hook 3 at offset 152 D0227 13:43:09.617475 11074 netfilter.go:54] netfilter: convert to binary: found underflow 3 at offset 152 D0227 13:43:09.617526 11074 netfilter.go:54] netfilter: convert to binary: marshalling standard target D0227 13:43:09.617634 11074 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 13:43:09.618101 11074 netfilter.go:54] netfilter: convert to binary: current offset: 304 D0227 13:43:09.618173 11074 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 13:43:09.618730 11074 netfilter.go:54] netfilter: convert to binary: finished with an marshalled size of 0 D0227 13:43:09.618792 11074 netfilter.go:54] netfilter: convert to binary: current offset: 0 D0227 13:43:09.618852 11074 netfilter.go:54] netfilter: convert to binary: found hook 1 at offset 0 D0227 13:43:09.618924 11074 netfilter.go:54] netfilter: convert to binary: found underflow 1 at offset 0 D0227 13:43:09.619003 11074 netfilter.go:54] netfilter: convert to binary: marshalling standard target D0227 13:43:09.619064 11074 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 13:43:09.619594 11074 netfilter.go:54] netfilter: convert to binary: current offset: 152 D0227 13:43:09.619692 11074 netfilter.go:54] netfilter: convert to binary: found hook 2 at offset 152 D0227 13:43:09.619760 11074 netfilter.go:54] netfilter: convert to binary: found underflow 2 at offset 152 D0227 13:43:09.619831 11074 netfilter.go:54] netfilter: convert to binary: marshalling standard target D0227 13:43:09.619939 11074 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 13:43:09.620429 11074 netfilter.go:54] netfilter: convert to binary: current offset: 304 D0227 13:43:09.620502 11074 netfilter.go:54] netfilter: convert to binary: found hook 3 at offset 304 D0227 13:43:09.620597 11074 netfilter.go:54] netfilter: convert to binary: found underflow 3 at offset 304 D0227 13:43:09.620691 11074 netfilter.go:54] netfilter: convert to binary: marshalling standard target D0227 13:43:09.620784 11074 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 13:43:09.621305 11074 netfilter.go:54] netfilter: convert to binary: current offset: 456 D0227 13:43:09.621488 11074 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 13:43:09.622137 11074 netfilter.go:54] netfilter: convert to binary: finished with an marshalled size of 0 D0227 13:43:09.726562 11074 transport_flipcall.go:127] send [channel @0xc0003a03c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0227 13:43:09.727207 11074 transport_flipcall.go:234] recv [channel @0xc0003a03c0] Rlerror{Error: 2} D0227 13:43:09.727361 11074 transport_flipcall.go:127] send [channel @0xc0003a03c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0227 13:43:09.727831 11074 transport_flipcall.go:234] recv [channel @0xc0003a03c0] Rlerror{Error: 95} D0227 13:43:09.728111 11074 transport_flipcall.go:127] send [channel @0xc0003a03c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0227 13:43:09.728659 11074 transport_flipcall.go:234] recv [channel @0xc0003a03c0] Rlerror{Error: 2} D0227 13:43:09.729029 11074 transport_flipcall.go:127] send [channel @0xc0003a03c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0227 13:43:09.729550 11074 transport_flipcall.go:234] recv [channel @0xc0003a03c0] Rlerror{Error: 95} D0227 13:43:09.729815 11074 transport_flipcall.go:127] send [channel @0xc0003a03c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0227 13:43:09.730229 11074 transport_flipcall.go:234] recv [channel @0xc0003a03c0] Rlerror{Error: 2} D0227 13:43:09.730398 11074 transport_flipcall.go:127] send [channel @0xc0003a03c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0227 13:43:09.730847 11074 transport_flipcall.go:234] recv [channel @0xc0003a03c0] Rlerror{Error: 95} D0227 13:43:09.731102 11074 transport_flipcall.go:127] send [channel @0xc0003a03c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0227 13:43:09.731624 11074 transport_flipcall.go:234] recv [channel @0xc0003a03c0] Rlerror{Error: 2} D0227 13:43:09.731932 11074 transport_flipcall.go:127] send [channel @0xc0003a03c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0227 13:43:09.732508 11074 transport_flipcall.go:234] recv [channel @0xc0003a03c0] Rlerror{Error: 95} D0227 13:43:09.732771 11074 transport_flipcall.go:127] send [channel @0xc0003a03c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0227 13:43:09.733302 11074 transport_flipcall.go:234] recv [channel @0xc0003a03c0] Rlerror{Error: 2} D0227 13:43:09.733533 11074 transport_flipcall.go:127] send [channel @0xc0003a03c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0227 13:43:09.733985 11074 transport_flipcall.go:234] recv [channel @0xc0003a03c0] Rlerror{Error: 95} D0227 13:43:09.734241 11074 transport_flipcall.go:127] send [channel @0xc0003a03c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0227 13:43:09.734807 11074 transport_flipcall.go:234] recv [channel @0xc0003a03c0] Rlerror{Error: 2} D0227 13:43:09.734998 11074 transport_flipcall.go:127] send [channel @0xc0003a03c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0227 13:43:09.735540 11074 transport_flipcall.go:234] recv [channel @0xc0003a03c0] Rlerror{Error: 95} D0227 13:43:09.735976 11074 transport_flipcall.go:127] send [channel @0xc0003a03c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0227 13:43:09.759097 11074 transport_flipcall.go:234] recv [channel @0xc0003a03c0] Rlerror{Error: 2} D0227 13:43:09.759414 11074 transport_flipcall.go:127] send [channel @0xc0003a03c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0227 13:43:09.761526 11074 transport_flipcall.go:234] recv [channel @0xc0003a03c0] Rlerror{Error: 95} D0227 13:43:09.762064 11074 transport_flipcall.go:127] send [channel @0xc0003a03c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0227 13:43:09.762756 11074 transport_flipcall.go:234] recv [channel @0xc0003a03c0] Rlerror{Error: 2} D0227 13:43:09.762987 11074 transport_flipcall.go:127] send [channel @0xc0003a03c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0227 13:43:09.763535 11074 transport_flipcall.go:234] recv [channel @0xc0003a03c0] Rlerror{Error: 95} D0227 13:43:09.764158 11074 transport_flipcall.go:127] send [channel @0xc0003a03c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0227 13:43:09.764855 11074 transport_flipcall.go:234] recv [channel @0xc0003a03c0] Rlerror{Error: 2} D0227 13:43:09.765083 11074 transport_flipcall.go:127] send [channel @0xc0003a03c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0227 13:43:09.765591 11074 transport_flipcall.go:234] recv [channel @0xc0003a03c0] Rlerror{Error: 95} D0227 13:43:09.765985 11074 transport_flipcall.go:127] send [channel @0xc0003a03c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0227 13:43:09.766594 11074 transport_flipcall.go:234] recv [channel @0xc0003a03c0] Rlerror{Error: 2} D0227 13:43:09.766753 11074 transport_flipcall.go:127] send [channel @0xc0003a03c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0227 13:43:09.767198 11074 transport_flipcall.go:234] recv [channel @0xc0003a03c0] Rlerror{Error: 95} D0227 13:43:09.787747 11074 task_block.go:223] [ 39] Interrupt queued D0227 13:43:09.797479 11074 task_block.go:223] [ 40] Interrupt queued D0227 13:43:09.817916 11074 task_block.go:223] [ 41] Interrupt queued D0227 13:43:09.830490 11074 task_block.go:223] [ 40] Interrupt queued D0227 13:43:09.830518 11074 task_block.go:223] [ 40] Interrupt queued D0227 13:43:09.830667 11074 task_block.go:223] [ 41] Interrupt queued D0227 13:43:09.830832 11074 task_exit.go:221] [ 39] Transitioning from exit state TaskExitNone to TaskExitInitiated D0227 13:43:09.830825 11074 task_signals.go:189] [ 40] Signal 9: terminating thread group D0227 13:43:09.830700 11074 task_block.go:223] [ 41] Interrupt queued D0227 13:43:09.831315 11074 task_signals.go:189] [ 41] Signal 9: terminating thread group I0227 13:43:09.831287 11074 compat.go:129] Uncaught signal: "killed" (9), PID: 39, TID: 40, fault addr: 0x0 D0227 13:43:09.831336 11074 task_exit.go:221] [ 39] Transitioning from exit state TaskExitInitiated to TaskExitZombie D0227 13:43:09.831589 11074 task_exit.go:221] [ 40] Transitioning from exit state TaskExitNone to TaskExitInitiated I0227 13:43:09.831577 11074 compat.go:129] Uncaught signal: "killed" (9), PID: 39, TID: 41, fault addr: 0x0 D0227 13:43:09.831831 11074 task_exit.go:221] [ 40] Transitioning from exit state TaskExitInitiated to TaskExitZombie D0227 13:43:09.831968 11074 task_exit.go:221] [ 40] Transitioning from exit state TaskExitZombie to TaskExitDead D0227 13:43:09.832215 11074 task_exit.go:221] [ 41] Transitioning from exit state TaskExitNone to TaskExitInitiated D0227 13:43:09.834177 11074 task_exit.go:221] [ 41] Transitioning from exit state TaskExitInitiated to TaskExitZombie D0227 13:43:09.834304 11074 task_exit.go:221] [ 41] Transitioning from exit state TaskExitZombie to TaskExitDead D0227 13:43:09.834448 11074 task_signals.go:419] [ 38] Discarding ignored signal 17 D0227 13:43:09.834631 11074 task_exit.go:221] [ 39] Transitioning from exit state TaskExitZombie to TaskExitDead D0227 13:43:09.839700 11074 transport_flipcall.go:127] send [channel @0xc0003a03c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0227 13:43:09.840351 11074 transport_flipcall.go:234] recv [channel @0xc0003a03c0] Rlerror{Error: 2} D0227 13:43:09.840501 11074 transport_flipcall.go:127] send [channel @0xc0003a03c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0227 13:43:09.840971 11074 transport_flipcall.go:234] recv [channel @0xc0003a03c0] Rlerror{Error: 95} D0227 13:43:09.841755 11074 transport_flipcall.go:127] send [channel @0xc0003a03c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0227 13:43:09.842300 11074 transport_flipcall.go:234] recv [channel @0xc0003a03c0] Rlerror{Error: 2} D0227 13:43:09.842456 11074 transport_flipcall.go:127] send [channel @0xc0003a03c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0227 13:43:09.843012 11074 transport_flipcall.go:234] recv [channel @0xc0003a03c0] Rlerror{Error: 95} D0227 13:43:09.843819 11074 transport_flipcall.go:127] send [channel @0xc0003a03c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0227 13:43:09.844507 11074 transport_flipcall.go:234] recv [channel @0xc0003a03c0] Rlerror{Error: 2} D0227 13:43:09.844634 11074 transport_flipcall.go:127] send [channel @0xc0003a03c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0227 13:43:09.845269 11074 transport_flipcall.go:234] recv [channel @0xc0003a03c0] Rlerror{Error: 95} D0227 13:43:09.853014 11074 task_block.go:223] [ 42] Interrupt queued D0227 13:43:09.863026 11074 task_block.go:223] [ 43] Interrupt queued D0227 13:43:09.876304 11074 task_block.go:223] [ 44] Interrupt queued ================== WARNING: DATA RACE Read at 0x00c00040e010 by goroutine 355: gvisor.dev/gvisor/pkg/tcpip/stack.(*TransportEndpointInfo).AddrNetProto() pkg/tcpip/stack/stack.go:573 +0x79 gvisor.dev/gvisor/pkg/tcpip/transport/udp.(*endpoint).checkV4Mapped() pkg/tcpip/transport/udp/endpoint.go:931 +0xee gvisor.dev/gvisor/pkg/tcpip/transport/udp.(*endpoint).Connect() pkg/tcpip/transport/udp/endpoint.go:984 +0x6c gvisor.dev/gvisor/pkg/sentry/socket/netstack.(*SocketOperations).Connect() pkg/sentry/socket/netstack/netstack.go:698 +0x4c5 gvisor.dev/gvisor/pkg/sentry/syscalls/linux.Connect() pkg/sentry/syscalls/linux/sys_socket.go:280 +0x251 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).executeSyscall() pkg/sentry/kernel/task_syscall.go:170 +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 13:43:09.918080 21866 main.go:296] *************************** I0227 13:43:09.918263 21866 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-1] I0227 13:43:09.918450 21866 main.go:298] Version release-20200219.0-47-gde0b2ebf8635 I0227 13:43:09.918533 21866 main.go:299] PID: 21866 I0227 13:43:09.918628 21866 main.go:300] UID: 0, GID: 0 I0227 13:43:09.918731 21866 main.go:301] Configuration: I0227 13:43:09.918864 21866 main.go:302] RootDir: /syzkaller/managers/ptrace-proxy-sandbox-race/workdir/gvisor_root I0227 13:43:09.918968 21866 main.go:303] Platform: ptrace I0227 13:43:09.919059 21866 main.go:304] FileAccess: shared, overlay: false I0227 13:43:09.919158 21866 main.go:305] Network: sandbox, logging: false I0227 13:43:09.919278 21866 main.go:306] Strace: false, max size: 1024, syscalls: [] I0227 13:43:09.919369 21866 main.go:307] *************************** W0227 13:43:09.919443 21866 main.go:312] Block the TERM signal. This is only safe in tests! D0227 13:43:09.919623 21866 container.go:158] Load container "/syzkaller/managers/ptrace-proxy-sandbox-race/workdir/gvisor_root" "ci-gvisor-ptrace-proxy-sandbox-race-1" D0227 13:43:09.927738 21866 container.go:591] Signal container "ci-gvisor-ptrace-proxy-sandbox-race-1": signal 0 D0227 13:43:09.927947 21866 sandbox.go:788] Signal sandbox "ci-gvisor-ptrace-proxy-sandbox-race-1" D0227 13:43:09.928037 21866 sandbox.go:318] Connecting to sandbox "ci-gvisor-ptrace-proxy-sandbox-race-1" D0227 13:43:09.928657 21866 urpc.go:534] urpc: successfully marshalled 117 bytes. W0227 13:43:10.064783 21866 error.go:48] FATAL ERROR: container sandbox is not running container sandbox is not running W0227 13:43:10.065111 21866 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-1"]: exit status 128 I0227 13:43:09.918080 21866 main.go:296] *************************** I0227 13:43:09.918263 21866 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-1] I0227 13:43:09.918450 21866 main.go:298] Version release-20200219.0-47-gde0b2ebf8635 I0227 13:43:09.918533 21866 main.go:299] PID: 21866 I0227 13:43:09.918628 21866 main.go:300] UID: 0, GID: 0 I0227 13:43:09.918731 21866 main.go:301] Configuration: I0227 13:43:09.918864 21866 main.go:302] RootDir: /syzkaller/managers/ptrace-proxy-sandbox-race/workdir/gvisor_root I0227 13:43:09.918968 21866 main.go:303] Platform: ptrace I0227 13:43:09.919059 21866 main.go:304] FileAccess: shared, overlay: false I0227 13:43:09.919158 21866 main.go:305] Network: sandbox, logging: false I0227 13:43:09.919278 21866 main.go:306] Strace: false, max size: 1024, syscalls: [] I0227 13:43:09.919369 21866 main.go:307] *************************** W0227 13:43:09.919443 21866 main.go:312] Block the TERM signal. This is only safe in tests! D0227 13:43:09.919623 21866 container.go:158] Load container "/syzkaller/managers/ptrace-proxy-sandbox-race/workdir/gvisor_root" "ci-gvisor-ptrace-proxy-sandbox-race-1" D0227 13:43:09.927738 21866 container.go:591] Signal container "ci-gvisor-ptrace-proxy-sandbox-race-1": signal 0 D0227 13:43:09.927947 21866 sandbox.go:788] Signal sandbox "ci-gvisor-ptrace-proxy-sandbox-race-1" D0227 13:43:09.928037 21866 sandbox.go:318] Connecting to sandbox "ci-gvisor-ptrace-proxy-sandbox-race-1" D0227 13:43:09.928657 21866 urpc.go:534] urpc: successfully marshalled 117 bytes. W0227 13:43:10.064783 21866 error.go:48] FATAL ERROR: container sandbox is not running container sandbox is not running W0227 13:43:10.065111 21866 main.go:329] Failure to execute command, err: 1 Previous write at 0x00c00040e010 by goroutine 364: gvisor.dev/gvisor/pkg/tcpip/transport/udp.(*endpoint).bindLocked() pkg/tcpip/transport/udp/endpoint.go:1176 +0x28a gvisor.dev/gvisor/pkg/tcpip/transport/udp.(*endpoint).prepareForWrite() pkg/tcpip/transport/udp/endpoint.go:323 +0x267 gvisor.dev/gvisor/pkg/tcpip/transport/udp.(*endpoint).write() pkg/tcpip/transport/udp/endpoint.go:400 +0x12b gvisor.dev/gvisor/pkg/tcpip/transport/udp.(*endpoint).Write() pkg/tcpip/transport/udp/endpoint.go:360 +0x81 gvisor.dev/gvisor/pkg/sentry/socket/netstack.(*SocketOperations).Write() pkg/sentry/socket/netstack/netstack.go:522 +0x152 gvisor.dev/gvisor/pkg/sentry/fs.(*File).Writev() pkg/sentry/fs/file.go:336 +0x308 gvisor.dev/gvisor/pkg/sentry/syscalls/linux.writev() pkg/sentry/syscalls/linux/sys_write.go:262 +0xe0 gvisor.dev/gvisor/pkg/sentry/syscalls/linux.Write() pkg/sentry/syscalls/linux/sys_write.go:72 +0x2ab gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).executeSyscall() pkg/sentry/kernel/task_syscall.go:170 +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 355 (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 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 ================== W0227 13:43:10.060185 21770 error.go:48] FATAL ERROR: waiting on pid 20: waiting on PID 20 in sandbox "ci-gvisor-ptrace-proxy-sandbox-race-1": urpc method "containerManager.WaitPID" failed: EOF waiting on pid 20: waiting on PID 20 in sandbox "ci-gvisor-ptrace-proxy-sandbox-race-1": urpc method "containerManager.WaitPID" failed: EOF W0227 13:43:10.060594 21770 main.go:329] Failure to execute command, err: 1 W0227 13:43:10.063850 11039 sandbox.go:724] Wait RPC to container "ci-gvisor-ptrace-proxy-sandbox-race-1" failed: urpc method "containerManager.Wait" failed: EOF. Will try waiting on the sandbox process instead. D0227 13:43:10.064599 11039 container.go:713] Destroy container "ci-gvisor-ptrace-proxy-sandbox-race-1" D0227 13:43:10.065191 11039 container.go:800] Destroying container "ci-gvisor-ptrace-proxy-sandbox-race-1" D0227 13:43:10.065306 11039 sandbox.go:1040] Destroying root container "ci-gvisor-ptrace-proxy-sandbox-race-1" by destroying sandbox D0227 13:43:10.065394 11039 sandbox.go:770] Destroy sandbox "ci-gvisor-ptrace-proxy-sandbox-race-1" D0227 13:43:10.065498 11039 container.go:814] Killing gofer for container "ci-gvisor-ptrace-proxy-sandbox-race-1", PID: 11066 I0227 13:43:10.166850 11039 main.go:320] Exiting with status: 16896