D0227 11:19:36.372629 51338 parameters.go:238] Clock(Monotonic): error: 805 ns, adjusted frequency from 2299774071 Hz to 2299776729 Hz D0227 11:19:36.373012 51338 parameters.go:238] Clock(Realtime): error: 523 ns, adjusted frequency from 2299774047 Hz to 2299775726 Hz D0227 11:19:36.373168 51338 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8496101391699797 monotonicBaseRef:2000796458 monotonicFrequency:2299776729 realtimeReady:1 realtimeBaseCycles:8496101392683452 realtimeBaseRef:1582802376373003885 realtimeFrequency:2299775726} D0227 11:19:37.372680 51338 parameters.go:238] Clock(Monotonic): error: -1058 ns, adjusted frequency from 2299776729 Hz to 2299772112 Hz D0227 11:19:37.372935 51338 parameters.go:238] Clock(Realtime): error: -512 ns, adjusted frequency from 2299775726 Hz to 2299773137 Hz D0227 11:19:37.373067 51338 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8496103691675932 monotonicBaseRef:3000883164 monotonicFrequency:2299772112 realtimeReady:1 realtimeBaseCycles:8496103692287962 realtimeBaseRef:1582802377372929436 realtimeFrequency:2299773137} D0227 11:19:38.373456 51338 parameters.go:238] Clock(Monotonic): error: 93 ns, adjusted frequency from 2299772112 Hz to 2299774801 Hz D0227 11:19:38.373698 51338 parameters.go:238] Clock(Realtime): error: 105 ns, adjusted frequency from 2299773137 Hz to 2299774706 Hz D0227 11:19:38.373841 51338 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8496105993177241 monotonicBaseRef:4001635063 monotonicFrequency:2299774801 realtimeReady:1 realtimeBaseCycles:8496105993814102 realtimeBaseRef:1582802378373691686 realtimeFrequency:2299774706} D0227 11:19:39.372343 51338 parameters.go:238] Clock(Monotonic): error: 5 ns, adjusted frequency from 2299774801 Hz to 2299774611 Hz D0227 11:19:39.372617 51338 parameters.go:238] Clock(Realtime): error: 216 ns, adjusted frequency from 2299774706 Hz to 2299775127 Hz D0227 11:19:39.372771 51338 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8496108290440084 monotonicBaseRef:5000542800 monotonicFrequency:2299774611 realtimeReady:1 realtimeBaseCycles:8496108291093703 realtimeBaseRef:1582802379372606751 realtimeFrequency:2299775127} D0227 11:19:40.372769 51338 sampler.go:197] Time: Adjusting syscall overhead down to 7000 D0227 11:19:40.372957 51338 parameters.go:238] Clock(Monotonic): error: 64 ns, adjusted frequency from 2299774611 Hz to 2299774764 Hz D0227 11:19:40.373091 51338 parameters.go:238] Clock(Realtime): error: -96 ns, adjusted frequency from 2299775127 Hz to 2299774328 Hz D0227 11:19:40.373209 51338 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8496110591200385 monotonicBaseRef:6000971402 monotonicFrequency:2299774764 realtimeReady:1 realtimeBaseCycles:8496110591970966 realtimeBaseRef:1582802380373085987 realtimeFrequency:2299774328} D0227 11:19:41.373926 51338 parameters.go:238] Clock(Monotonic): error: -25 ns, adjusted frequency from 2299774764 Hz to 2299774565 Hz D0227 11:19:41.374128 51338 parameters.go:238] Clock(Realtime): error: 63 ns, adjusted frequency from 2299774328 Hz to 2299774678 Hz D0227 11:19:41.374220 51338 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8496112893639215 monotonicBaseRef:7002129805 monotonicFrequency:2299774565 realtimeReady:1 realtimeBaseCycles:8496112894129652 realtimeBaseRef:1582802381374122766 realtimeFrequency:2299774678} D0227 11:19:42.372479 51338 parameters.go:238] Clock(Monotonic): error: 663 ns, adjusted frequency from 2299774565 Hz to 2299776171 Hz D0227 11:19:42.372746 51338 parameters.go:238] Clock(Realtime): error: -28 ns, adjusted frequency from 2299774678 Hz to 2299774561 Hz D0227 11:19:42.372895 51338 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8496115190044560 monotonicBaseRef:8000664783 monotonicFrequency:2299776171 realtimeReady:1 realtimeBaseCycles:8496115190718745 realtimeBaseRef:1582802382372737593 realtimeFrequency:2299774561} D0227 11:19:43.372138 51338 parameters.go:238] Clock(Monotonic): error: -700 ns, adjusted frequency from 2299776171 Hz to 2299773018 Hz D0227 11:19:43.372374 51338 parameters.go:238] Clock(Realtime): error: 300 ns, adjusted frequency from 2299774561 Hz to 2299775274 Hz D0227 11:19:43.372486 51338 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8496117489076449 monotonicBaseRef:9000341150 monotonicFrequency:2299773018 realtimeReady:1 realtimeBaseCycles:8496117489643044 realtimeBaseRef:1582802383372367877 realtimeFrequency:2299775274} D0227 11:19:44.372140 51338 parameters.go:238] Clock(Monotonic): error: 198 ns, adjusted frequency from 2299773018 Hz to 2299775169 Hz D0227 11:19:44.372368 51338 parameters.go:238] Clock(Realtime): error: -125 ns, adjusted frequency from 2299775274 Hz to 2299774367 Hz D0227 11:19:44.372488 51338 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8496119788822016 monotonicBaseRef:10000329213 monotonicFrequency:2299775169 realtimeReady:1 realtimeBaseCycles:8496119789406784 realtimeBaseRef:1582802384372362861 realtimeFrequency:2299774367} D0227 11:19:45.373227 51338 parameters.go:238] Clock(Monotonic): error: 16 ns, adjusted frequency from 2299775169 Hz to 2299774634 Hz D0227 11:19:45.373459 51338 parameters.go:238] Clock(Realtime): error: -34 ns, adjusted frequency from 2299774367 Hz to 2299774553 Hz D0227 11:19:45.373581 51338 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8496122091132735 monotonicBaseRef:11001431733 monotonicFrequency:2299774634 realtimeReady:1 realtimeBaseCycles:8496122091689558 realtimeBaseRef:1582802385373453579 realtimeFrequency:2299774553} D0227 11:19:46.372127 51338 parameters.go:238] Clock(Monotonic): error: 272 ns, adjusted frequency from 2299774634 Hz to 2299775326 Hz D0227 11:19:46.372396 51338 parameters.go:238] Clock(Realtime): error: 216 ns, adjusted frequency from 2299774553 Hz to 2299775273 Hz D0227 11:19:46.372571 51338 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8496124388371432 monotonicBaseRef:12000329043 monotonicFrequency:2299775326 realtimeReady:1 realtimeBaseCycles:8496124389012014 realtimeBaseRef:1582802386372387345 realtimeFrequency:2299775273} D0227 11:19:47.373097 51338 parameters.go:238] Clock(Monotonic): error: -352 ns, adjusted frequency from 2299775326 Hz to 2299773870 Hz D0227 11:19:47.373322 51338 parameters.go:238] Clock(Realtime): error: -196 ns, adjusted frequency from 2299775273 Hz to 2299774232 Hz D0227 11:19:47.373445 51338 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8496126690378222 monotonicBaseRef:13001299339 monotonicFrequency:2299773870 realtimeReady:1 realtimeBaseCycles:8496126690900048 realtimeBaseRef:1582802387373306026 realtimeFrequency:2299774232} D0227 11:19:48.372835 51338 parameters.go:238] Clock(Monotonic): error: 384 ns, adjusted frequency from 2299773870 Hz to 2299775488 Hz D0227 11:19:48.373069 51338 parameters.go:238] Clock(Realtime): error: -187 ns, adjusted frequency from 2299774232 Hz to 2299774189 Hz D0227 11:19:48.373210 51338 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8496128989553806 monotonicBaseRef:14001039189 monotonicFrequency:2299775488 realtimeReady:1 realtimeBaseCycles:8496128990114809 realtimeBaseRef:1582802388373062753 realtimeFrequency:2299774189} D0227 11:19:49.374486 51338 parameters.go:238] Clock(Monotonic): error: -170 ns, adjusted frequency from 2299775488 Hz to 2299774299 Hz D0227 11:19:49.374762 51338 parameters.go:238] Clock(Realtime): error: 297 ns, adjusted frequency from 2299774189 Hz to 2299775319 Hz D0227 11:19:49.374915 51338 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8496131293098152 monotonicBaseRef:15002677982 monotonicFrequency:2299774299 realtimeReady:1 realtimeBaseCycles:8496131293780017 realtimeBaseRef:1582802389374754666 realtimeFrequency:2299775319} D0227 11:19:50.372784 51338 parameters.go:238] Clock(Monotonic): error: -155 ns, adjusted frequency from 2299774299 Hz to 2299774334 Hz D0227 11:19:50.373007 51338 parameters.go:238] Clock(Realtime): error: 20 ns, adjusted frequency from 2299775319 Hz to 2299774708 Hz D0227 11:19:50.373136 51338 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8496133588948011 monotonicBaseRef:16000971536 monotonicFrequency:2299774334 realtimeReady:1 realtimeBaseCycles:8496133589518482 realtimeBaseRef:1582802390372999340 realtimeFrequency:2299774708} D0227 11:19:51.372369 51338 parameters.go:238] Clock(Monotonic): error: 177 ns, adjusted frequency from 2299774334 Hz to 2299775087 Hz D0227 11:19:51.372630 51338 parameters.go:238] Clock(Realtime): error: -217 ns, adjusted frequency from 2299774708 Hz to 2299774202 Hz D0227 11:19:51.372803 51338 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8496135887796341 monotonicBaseRef:17000568886 monotonicFrequency:2299775087 realtimeReady:1 realtimeBaseCycles:8496135888426286 realtimeBaseRef:1582802391372622388 realtimeFrequency:2299774202} D0227 11:19:51.926080 51338 urpc.go:577] urpc: unmarshal success. D0227 11:19:51.926318 51338 controller.go:501] containerManager.Signal &{CID:ci-gvisor-ptrace-proxy-sandbox-race-2 Signo:0 PID:0 Mode:Process} D0227 11:19:51.926557 51338 urpc.go:534] urpc: successfully marshalled 37 bytes. D0227 11:19:52.373159 51338 parameters.go:238] Clock(Monotonic): error: 110 ns, adjusted frequency from 2299775087 Hz to 2299774887 Hz D0227 11:19:52.373409 51338 parameters.go:238] Clock(Realtime): error: 133 ns, adjusted frequency from 2299774202 Hz to 2299774990 Hz D0227 11:19:52.373501 51338 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8496138189350359 monotonicBaseRef:18001342409 monotonicFrequency:2299774887 realtimeReady:1 realtimeBaseCycles:8496138189995165 realtimeBaseRef:1582802392373402758 realtimeFrequency:2299774990} D0227 11:19:53.372338 51338 parameters.go:238] Clock(Monotonic): error: -238 ns, adjusted frequency from 2299774887 Hz to 2299774123 Hz D0227 11:19:53.372587 51338 parameters.go:238] Clock(Realtime): error: -251 ns, adjusted frequency from 2299774990 Hz to 2299774158 Hz D0227 11:19:53.372702 51338 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8496140487282213 monotonicBaseRef:19000541011 monotonicFrequency:2299774123 realtimeReady:1 realtimeBaseCycles:8496140487879490 realtimeBaseRef:1582802393372580649 realtimeFrequency:2299774158} D0227 11:19:54.372288 51338 parameters.go:238] Clock(Monotonic): error: -9 ns, adjusted frequency from 2299774123 Hz to 2299774668 Hz D0227 11:19:54.372495 51338 parameters.go:238] Clock(Realtime): error: 5 ns, adjusted frequency from 2299774158 Hz to 2299774736 Hz D0227 11:19:54.372639 51338 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8496142786932452 monotonicBaseRef:20000487143 monotonicFrequency:2299774668 realtimeReady:1 realtimeBaseCycles:8496142787442808 realtimeBaseRef:1582802394372488970 realtimeFrequency:2299774736} D0227 11:19:55.373964 51338 parameters.go:238] Clock(Monotonic): error: 83 ns, adjusted frequency from 2299774668 Hz to 2299774870 Hz D0227 11:19:55.374177 51338 parameters.go:238] Clock(Realtime): error: 243 ns, adjusted frequency from 2299774736 Hz to 2299775171 Hz D0227 11:19:55.374281 51338 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8496145090553182 monotonicBaseRef:21002159507 monotonicFrequency:2299774870 realtimeReady:1 realtimeBaseCycles:8496145091086153 realtimeBaseRef:1582802395374171138 realtimeFrequency:2299775171} D0227 11:19:56.372386 51338 parameters.go:238] Clock(Monotonic): error: -88 ns, adjusted frequency from 2299774870 Hz to 2299774478 Hz D0227 11:19:56.372613 51338 parameters.go:238] Clock(Realtime): error: 144 ns, adjusted frequency from 2299775171 Hz to 2299775022 Hz D0227 11:19:56.372762 51338 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8496147386714217 monotonicBaseRef:22000588120 monotonicFrequency:2299774478 realtimeReady:1 realtimeBaseCycles:8496147387263649 realtimeBaseRef:1582802396372606778 realtimeFrequency:2299775022} D0227 11:19:57.372782 51338 parameters.go:238] Clock(Monotonic): error: 19 ns, adjusted frequency from 2299774478 Hz to 2299774819 Hz D0227 11:19:57.373057 51338 parameters.go:238] Clock(Realtime): error: 81 ns, adjusted frequency from 2299775022 Hz to 2299774853 Hz D0227 11:19:57.373204 51338 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8496149687381586 monotonicBaseRef:23000976371 monotonicFrequency:2299774819 realtimeReady:1 realtimeBaseCycles:8496149688057713 realtimeBaseRef:1582802397373049883 realtimeFrequency:2299774853} D0227 11:19:58.372169 51338 parameters.go:238] Clock(Monotonic): error: 3 ns, adjusted frequency from 2299774819 Hz to 2299774699 Hz D0227 11:19:58.372380 51338 parameters.go:238] Clock(Realtime): error: -119 ns, adjusted frequency from 2299774853 Hz to 2299774453 Hz D0227 11:19:58.372545 51338 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8496151985764553 monotonicBaseRef:24000371158 monotonicFrequency:2299774699 realtimeReady:1 realtimeBaseCycles:8496151986277888 realtimeBaseRef:1582802398372373869 realtimeFrequency:2299774453} D0227 11:19:59.372411 51338 parameters.go:238] Clock(Monotonic): error: -44 ns, adjusted frequency from 2299774699 Hz to 2299774591 Hz D0227 11:19:59.372649 51338 parameters.go:238] Clock(Realtime): error: -60 ns, adjusted frequency from 2299774453 Hz to 2299774571 Hz D0227 11:19:59.372771 51338 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8496154286051447 monotonicBaseRef:25000593873 monotonicFrequency:2299774591 realtimeReady:1 realtimeBaseCycles:8496154286669597 realtimeBaseRef:1582802399372642267 realtimeFrequency:2299774571} D0227 11:20:00.372221 51338 parameters.go:238] Clock(Monotonic): error: -3 ns, adjusted frequency from 2299774591 Hz to 2299774683 Hz D0227 11:20:00.372434 51338 parameters.go:238] Clock(Realtime): error: -296 ns, adjusted frequency from 2299774571 Hz to 2299773995 Hz D0227 11:20:00.372575 51338 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8496156585396021 monotonicBaseRef:26000406890 monotonicFrequency:2299774683 realtimeReady:1 realtimeBaseCycles:8496156585949604 realtimeBaseRef:1582802400372427218 realtimeFrequency:2299773995} D0227 11:20:01.372137 51338 parameters.go:238] Clock(Monotonic): error: -17 ns, adjusted frequency from 2299774683 Hz to 2299774712 Hz D0227 11:20:01.372373 51338 parameters.go:238] Clock(Realtime): error: 344 ns, adjusted frequency from 2299773995 Hz to 2299775459 Hz D0227 11:20:01.372503 51338 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8496158885017335 monotonicBaseRef:27000340201 monotonicFrequency:2299774712 realtimeReady:1 realtimeBaseCycles:8496158885577489 realtimeBaseRef:1582802401372363685 realtimeFrequency:2299775459} D0227 11:20:02.372719 51338 parameters.go:238] Clock(Monotonic): error: 87 ns, adjusted frequency from 2299774712 Hz to 2299774817 Hz D0227 11:20:02.372941 51338 parameters.go:238] Clock(Realtime): error: 3 ns, adjusted frequency from 2299775459 Hz to 2299774671 Hz D0227 11:20:02.373054 51338 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8496161186129143 monotonicBaseRef:28000921604 monotonicFrequency:2299774817 realtimeReady:1 realtimeBaseCycles:8496161186664388 realtimeBaseRef:1582802402372933931 realtimeFrequency:2299774671} D0227 11:20:03.373037 51338 parameters.go:238] Clock(Monotonic): error: 404 ns, adjusted frequency from 2299774817 Hz to 2299775616 Hz D0227 11:20:03.373252 51338 parameters.go:238] Clock(Realtime): error: -28 ns, adjusted frequency from 2299774671 Hz to 2299774579 Hz D0227 11:20:03.373402 51338 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8496163486634017 monotonicBaseRef:29001239051 monotonicFrequency:2299775616 realtimeReady:1 realtimeBaseCycles:8496163487157754 realtimeBaseRef:1582802403373246437 realtimeFrequency:2299774579} D0227 11:20:04.372903 51338 parameters.go:238] Clock(Monotonic): error: -488 ns, adjusted frequency from 2299775616 Hz to 2299773563 Hz D0227 11:20:04.373168 51338 parameters.go:238] Clock(Realtime): error: 710 ns, adjusted frequency from 2299774579 Hz to 2299776421 Hz D0227 11:20:04.373299 51338 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8496165786101975 monotonicBaseRef:30001105273 monotonicFrequency:2299773563 realtimeReady:1 realtimeBaseCycles:8496165786737401 realtimeBaseRef:1582802404373161675 realtimeFrequency:2299776421} D0227 11:20:05.373386 51338 parameters.go:238] Clock(Monotonic): error: 54 ns, adjusted frequency from 2299773563 Hz to 2299774833 Hz D0227 11:20:05.373603 51338 parameters.go:238] Clock(Realtime): error: -781 ns, adjusted frequency from 2299776421 Hz to 2299772878 Hz D0227 11:20:05.373728 51338 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8496168086941170 monotonicBaseRef:31001568636 monotonicFrequency:2299774833 realtimeReady:1 realtimeBaseCycles:8496168087513577 realtimeBaseRef:1582802405373596393 realtimeFrequency:2299772878} D0227 11:20:06.372903 51338 parameters.go:238] Clock(Monotonic): error: -67 ns, adjusted frequency from 2299774833 Hz to 2299774532 Hz D0227 11:20:06.373160 51338 parameters.go:238] Clock(Realtime): error: -239 ns, adjusted frequency from 2299772878 Hz to 2299774205 Hz D0227 11:20:06.373330 51338 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8496170385609695 monotonicBaseRef:32001087585 monotonicFrequency:2299774532 realtimeReady:1 realtimeBaseCycles:8496170386269894 realtimeBaseRef:1582802406373154366 realtimeFrequency:2299774205} D0227 11:20:07.372771 51338 parameters.go:238] Clock(Monotonic): error: 31 ns, adjusted frequency from 2299774532 Hz to 2299774759 Hz D0227 11:20:07.372980 51338 parameters.go:238] Clock(Realtime): error: 36 ns, adjusted frequency from 2299774205 Hz to 2299774788 Hz D0227 11:20:07.373145 51338 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8496172685114238 monotonicBaseRef:33000970186 monotonicFrequency:2299774759 realtimeReady:1 realtimeBaseCycles:8496172685628942 realtimeBaseRef:1582802407372973845 realtimeFrequency:2299774788} D0227 11:20:08.373011 51338 parameters.go:238] Clock(Monotonic): error: 4 ns, adjusted frequency from 2299774759 Hz to 2299774695 Hz D0227 11:20:08.373244 51338 parameters.go:238] Clock(Realtime): error: 264 ns, adjusted frequency from 2299774788 Hz to 2299775307 Hz D0227 11:20:08.373347 51338 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8496174985430377 monotonicBaseRef:34001205591 monotonicFrequency:2299774695 realtimeReady:1 realtimeBaseCycles:8496174986012613 realtimeBaseRef:1582802408373238602 realtimeFrequency:2299775307} D0227 11:20:09.372942 51338 parameters.go:238] Clock(Monotonic): error: -7 ns, adjusted frequency from 2299774695 Hz to 2299774671 Hz D0227 11:20:09.373205 51338 parameters.go:238] Clock(Realtime): error: -109 ns, adjusted frequency from 2299775307 Hz to 2299774404 Hz D0227 11:20:09.373327 51338 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8496177285063176 monotonicBaseRef:35001143891 monotonicFrequency:2299774671 realtimeReady:1 realtimeBaseCycles:8496177285692850 realtimeBaseRef:1582802409373197263 realtimeFrequency:2299774404} D0227 11:20:10.372964 51338 parameters.go:238] Clock(Monotonic): error: 11 ns, adjusted frequency from 2299774671 Hz to 2299774721 Hz D0227 11:20:10.373436 51338 parameters.go:238] Clock(Realtime): error: 239 ns, adjusted frequency from 2299774404 Hz to 2299775216 Hz D0227 11:20:10.373565 51338 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8496179584826987 monotonicBaseRef:36001139168 monotonicFrequency:2299774721 realtimeReady:1 realtimeBaseCycles:8496179585984988 realtimeBaseRef:1582802410373422386 realtimeFrequency:2299775216} D0227 11:20:11.372344 51338 parameters.go:238] Clock(Monotonic): error: 51 ns, adjusted frequency from 2299774721 Hz to 2299774899 Hz D0227 11:20:11.372604 51338 parameters.go:238] Clock(Realtime): error: -171 ns, adjusted frequency from 2299775216 Hz to 2299774298 Hz D0227 11:20:11.372736 51338 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8496181883235198 monotonicBaseRef:37000544975 monotonicFrequency:2299774899 realtimeReady:1 realtimeBaseCycles:8496181883853228 realtimeBaseRef:1582802411372593184 realtimeFrequency:2299774298} D0227 11:20:12.373189 51338 parameters.go:238] Clock(Monotonic): error: 249 ns, adjusted frequency from 2299774899 Hz to 2299775294 Hz D0227 11:20:12.373454 51338 parameters.go:238] Clock(Realtime): error: -67 ns, adjusted frequency from 2299774298 Hz to 2299774526 Hz D0227 11:20:12.373614 51338 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8496184184936213 monotonicBaseRef:38001382498 monotonicFrequency:2299775294 realtimeReady:1 realtimeBaseCycles:8496184185583786 realtimeBaseRef:1582802412373443815 realtimeFrequency:2299774526} D0227 11:20:13.373411 51338 parameters.go:238] Clock(Monotonic): error: -357 ns, adjusted frequency from 2299775294 Hz to 2299773871 Hz D0227 11:20:13.373628 51338 parameters.go:238] Clock(Realtime): error: 55 ns, adjusted frequency from 2299774526 Hz to 2299774740 Hz D0227 11:20:13.373737 51338 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8496186485230945 monotonicBaseRef:39001608362 monotonicFrequency:2299773871 realtimeReady:1 realtimeBaseCycles:8496186485769371 realtimeBaseRef:1582802413373622553 realtimeFrequency:2299774740} D0227 11:20:14.372180 51338 parameters.go:238] Clock(Monotonic): error: -16 ns, adjusted frequency from 2299773871 Hz to 2299774646 Hz D0227 11:20:14.372450 51338 parameters.go:238] Clock(Realtime): error: -173 ns, adjusted frequency from 2299774740 Hz to 2299774316 Hz D0227 11:20:14.372617 51338 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8496188782187347 monotonicBaseRef:40000383255 monotonicFrequency:2299774646 realtimeReady:1 realtimeBaseCycles:8496188782832312 realtimeBaseRef:1582802414372443394 realtimeFrequency:2299774316} D0227 11:20:15.372050 51338 parameters.go:238] Clock(Monotonic): error: 24 ns, adjusted frequency from 2299774646 Hz to 2299774754 Hz D0227 11:20:15.372294 51338 parameters.go:238] Clock(Realtime): error: 189 ns, adjusted frequency from 2299774316 Hz to 2299775079 Hz D0227 11:20:15.372428 51338 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8496191081650529 monotonicBaseRef:41000247822 monotonicFrequency:2299774754 realtimeReady:1 realtimeBaseCycles:8496191082251771 realtimeBaseRef:1582802415372289093 realtimeFrequency:2299775079} D0227 11:20:16.372911 51338 parameters.go:238] Clock(Monotonic): error: 272 ns, adjusted frequency from 2299774754 Hz to 2299775225 Hz D0227 11:20:16.373121 51338 parameters.go:238] Clock(Realtime): error: 6 ns, adjusted frequency from 2299775079 Hz to 2299774708 Hz D0227 11:20:16.373823 51338 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8496193383418237 monotonicBaseRef:42001114408 monotonicFrequency:2299775225 realtimeReady:1 realtimeBaseCycles:8496193383929696 realtimeBaseRef:1582802416373116498 realtimeFrequency:2299774708} D0227 11:20:17.372089 51338 parameters.go:238] Clock(Monotonic): error: -221 ns, adjusted frequency from 2299775225 Hz to 2299774184 Hz D0227 11:20:17.372325 51338 parameters.go:238] Clock(Realtime): error: -220 ns, adjusted frequency from 2299774708 Hz to 2299774245 Hz D0227 11:20:17.372433 51338 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8496195681300235 monotonicBaseRef:43000291185 monotonicFrequency:2299774184 realtimeReady:1 realtimeBaseCycles:8496195681869774 realtimeBaseRef:1582802417372318754 realtimeFrequency:2299774245} D0227 11:20:18.372835 51338 parameters.go:238] Clock(Monotonic): error: 22 ns, adjusted frequency from 2299774184 Hz to 2299774745 Hz D0227 11:20:18.373076 51338 parameters.go:238] Clock(Realtime): error: -50 ns, adjusted frequency from 2299774245 Hz to 2299774612 Hz D0227 11:20:18.373210 51338 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8496197982786850 monotonicBaseRef:44001035793 monotonicFrequency:2299774745 realtimeReady:1 realtimeBaseCycles:8496197983373346 realtimeBaseRef:1582802418373070709 realtimeFrequency:2299774612} D0227 11:20:19.372576 51338 parameters.go:238] Clock(Monotonic): error: 16 ns, adjusted frequency from 2299774745 Hz to 2299774736 Hz D0227 11:20:19.372852 51338 parameters.go:238] Clock(Realtime): error: 27 ns, adjusted frequency from 2299774612 Hz to 2299774850 Hz D0227 11:20:19.372983 51338 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8496200281960344 monotonicBaseRef:45000774353 monotonicFrequency:2299774736 realtimeReady:1 realtimeBaseCycles:8496200282622766 realtimeBaseRef:1582802419372842342 realtimeFrequency:2299774850} I0227 11:20:19.704668 51338 watchdog.go:277] Watchdog starting loop, tasks: 14, discount: 0s D0227 11:20:20.373176 51338 parameters.go:238] Clock(Monotonic): error: 93 ns, adjusted frequency from 2299774736 Hz to 2299774908 Hz D0227 11:20:20.373441 51338 parameters.go:238] Clock(Realtime): error: 240 ns, adjusted frequency from 2299774850 Hz to 2299775197 Hz D0227 11:20:20.373570 51338 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8496202582448008 monotonicBaseRef:46001084352 monotonicFrequency:2299774908 realtimeReady:1 realtimeBaseCycles:8496202583748892 realtimeBaseRef:1582802420373429910 realtimeFrequency:2299775197} D0227 11:20:21.372155 51338 parameters.go:238] Clock(Monotonic): error: -136 ns, adjusted frequency from 2299774908 Hz to 2299774348 Hz D0227 11:20:21.372432 51338 parameters.go:238] Clock(Realtime): error: -62 ns, adjusted frequency from 2299775197 Hz to 2299774569 Hz D0227 11:20:21.372572 51338 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8496204880539922 monotonicBaseRef:47000352543 monotonicFrequency:2299774348 realtimeReady:1 realtimeBaseCycles:8496204881215759 realtimeBaseRef:1582802421372426190 realtimeFrequency:2299774569} D0227 11:20:22.372970 51338 parameters.go:238] Clock(Monotonic): error: 25 ns, adjusted frequency from 2299774348 Hz to 2299774753 Hz D0227 11:20:22.373214 51338 parameters.go:238] Clock(Realtime): error: -180 ns, adjusted frequency from 2299774569 Hz to 2299774320 Hz D0227 11:20:22.373359 51338 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8496207182168519 monotonicBaseRef:48001158817 monotonicFrequency:2299774753 realtimeReady:1 realtimeBaseCycles:8496207182778640 realtimeBaseRef:1582802422373203793 realtimeFrequency:2299774320} D0227 11:20:23.372455 51338 parameters.go:238] Clock(Monotonic): error: -19 ns, adjusted frequency from 2299774753 Hz to 2299774655 Hz D0227 11:20:23.372689 51338 parameters.go:238] Clock(Realtime): error: 294 ns, adjusted frequency from 2299774320 Hz to 2299775352 Hz D0227 11:20:23.372797 51338 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8496209480794572 monotonicBaseRef:49000659333 monotonicFrequency:2299774655 realtimeReady:1 realtimeBaseCycles:8496209481354919 realtimeBaseRef:1582802423372682854 realtimeFrequency:2299775352} D0227 11:20:24.372122 51338 parameters.go:238] Clock(Monotonic): error: 92 ns, adjusted frequency from 2299774655 Hz to 2299774902 Hz D0227 11:20:24.372330 51338 parameters.go:238] Clock(Realtime): error: 301 ns, adjusted frequency from 2299775352 Hz to 2299775404 Hz D0227 11:20:24.372512 51338 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8496211779796173 monotonicBaseRef:50000323189 monotonicFrequency:2299774902 realtimeReady:1 realtimeBaseCycles:8496211780290312 realtimeBaseRef:1582802424372317618 realtimeFrequency:2299775404} D0227 11:20:25.372476 51338 parameters.go:238] Clock(Monotonic): error: -76 ns, adjusted frequency from 2299774902 Hz to 2299774571 Hz D0227 11:20:25.372688 51338 parameters.go:238] Clock(Realtime): error: -375 ns, adjusted frequency from 2299775404 Hz to 2299773824 Hz D0227 11:20:25.372828 51338 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8496214080380288 monotonicBaseRef:51000675055 monotonicFrequency:2299774571 realtimeReady:1 realtimeBaseCycles:8496214080903604 realtimeBaseRef:1582802425372681952 realtimeFrequency:2299773824} D0227 11:20:26.372181 51338 parameters.go:238] Clock(Monotonic): error: 279 ns, adjusted frequency from 2299774571 Hz to 2299775240 Hz D0227 11:20:26.372822 51338 parameters.go:238] Clock(Realtime): error: -273 ns, adjusted frequency from 2299773824 Hz to 2299774063 Hz D0227 11:20:26.372941 51338 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8496216379473365 monotonicBaseRef:52000378724 monotonicFrequency:2299775240 realtimeReady:1 realtimeBaseCycles:8496216380984004 realtimeBaseRef:1582802426372815259 realtimeFrequency:2299774063} D0227 11:20:27.372463 51338 parameters.go:238] Clock(Monotonic): error: 4 ns, adjusted frequency from 2299775240 Hz to 2299774600 Hz D0227 11:20:27.372688 51338 parameters.go:238] Clock(Realtime): error: -6 ns, adjusted frequency from 2299774063 Hz to 2299774683 Hz D0227 11:20:27.372841 51338 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8496218679909674 monotonicBaseRef:53000666173 monotonicFrequency:2299774600 realtimeReady:1 realtimeBaseCycles:8496218680452276 realtimeBaseRef:1582802427372682293 realtimeFrequency:2299774683} D0227 11:20:28.127479 51338 urpc.go:577] urpc: unmarshal success. D0227 11:20:28.127729 51338 controller.go:501] containerManager.Signal &{CID:ci-gvisor-ptrace-proxy-sandbox-race-2 Signo:0 PID:0 Mode:Process} D0227 11:20:28.127985 51338 urpc.go:534] urpc: successfully marshalled 37 bytes. D0227 11:20:28.372220 51338 parameters.go:238] Clock(Monotonic): error: 40 ns, adjusted frequency from 2299774600 Hz to 2299774698 Hz D0227 11:20:28.372431 51338 parameters.go:238] Clock(Realtime): error: 318 ns, adjusted frequency from 2299774683 Hz to 2299775370 Hz D0227 11:20:28.372564 51338 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8496220979114671 monotonicBaseRef:54000418495 monotonicFrequency:2299774698 realtimeReady:1 realtimeBaseCycles:8496220979636695 realtimeBaseRef:1582802428372425631 realtimeFrequency:2299775370} D0227 11:20:29.373681 51338 parameters.go:238] Clock(Monotonic): error: 80 ns, adjusted frequency from 2299774698 Hz to 2299774785 Hz D0227 11:20:29.373878 51338 parameters.go:238] Clock(Realtime): error: -97 ns, adjusted frequency from 2299775370 Hz to 2299774525 Hz D0227 11:20:29.373975 51338 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8496223282259015 monotonicBaseRef:55001883702 monotonicFrequency:2299774785 realtimeReady:1 realtimeBaseCycles:8496223282741071 realtimeBaseRef:1582802429373873166 realtimeFrequency:2299774525} D0227 11:20:30.372778 51338 parameters.go:238] Clock(Monotonic): error: -215 ns, adjusted frequency from 2299774785 Hz to 2299774200 Hz D0227 11:20:30.373259 51338 parameters.go:238] Clock(Realtime): error: -253 ns, adjusted frequency from 2299774525 Hz to 2299774143 Hz D0227 11:20:30.373388 51338 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8496225579877999 monotonicBaseRef:56000946305 monotonicFrequency:2299774200 realtimeReady:1 realtimeBaseCycles:8496225581076519 realtimeBaseRef:1582802430373247419 realtimeFrequency:2299774143} D0227 11:20:31.372147 51338 parameters.go:238] Clock(Monotonic): error: 196 ns, adjusted frequency from 2299774200 Hz to 2299775105 Hz D0227 11:20:31.372335 51338 parameters.go:238] Clock(Realtime): error: 305 ns, adjusted frequency from 2299774143 Hz to 2299775349 Hz D0227 11:20:31.372469 51338 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8496227878284575 monotonicBaseRef:57000351627 monotonicFrequency:2299775105 realtimeReady:1 realtimeBaseCycles:8496227878738863 realtimeBaseRef:1582802431372329155 realtimeFrequency:2299775349} D0227 11:20:32.372725 51338 parameters.go:238] Clock(Monotonic): error: 187 ns, adjusted frequency from 2299775105 Hz to 2299775111 Hz D0227 11:20:32.372965 51338 parameters.go:238] Clock(Realtime): error: 63 ns, adjusted frequency from 2299775349 Hz to 2299774839 Hz D0227 11:20:32.373084 51338 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8496230179381103 monotonicBaseRef:58000926214 monotonicFrequency:2299775111 realtimeReady:1 realtimeBaseCycles:8496230179953528 realtimeBaseRef:1582802432372955005 realtimeFrequency:2299774839} D0227 11:20:33.373190 51338 parameters.go:238] Clock(Monotonic): error: -403 ns, adjusted frequency from 2299775111 Hz to 2299773770 Hz D0227 11:20:33.373426 51338 parameters.go:238] Clock(Realtime): error: -15 ns, adjusted frequency from 2299774839 Hz to 2299774652 Hz D0227 11:20:33.373572 51338 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8496232480229262 monotonicBaseRef:59001392802 monotonicFrequency:2299773770 realtimeReady:1 realtimeBaseCycles:8496232480796717 realtimeBaseRef:1582802433373419550 realtimeFrequency:2299774652} D0227 11:20:34.373173 51338 parameters.go:238] Clock(Monotonic): error: 254 ns, adjusted frequency from 2299773770 Hz to 2299775273 Hz D0227 11:20:34.373364 51338 parameters.go:238] Clock(Realtime): error: -67 ns, adjusted frequency from 2299774652 Hz to 2299774497 Hz D0227 11:20:34.373495 51338 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8496234779967508 monotonicBaseRef:60001377355 monotonicFrequency:2299775273 realtimeReady:1 realtimeBaseCycles:8496234780431357 realtimeBaseRef:1582802434373358669 realtimeFrequency:2299774497} D0227 11:20:35.373174 51338 parameters.go:238] Clock(Monotonic): error: -238 ns, adjusted frequency from 2299775273 Hz to 2299774244 Hz D0227 11:20:35.373418 51338 parameters.go:238] Clock(Realtime): error: -12 ns, adjusted frequency from 2299774497 Hz to 2299774633 Hz D0227 11:20:35.373515 51338 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8496237079709894 monotonicBaseRef:61001363054 monotonicFrequency:2299774244 realtimeReady:1 realtimeBaseCycles:8496237080329309 realtimeBaseRef:1582802435373412350 realtimeFrequency:2299774633} D0227 11:20:36.372045 51338 parameters.go:238] Clock(Monotonic): error: 370 ns, adjusted frequency from 2299774244 Hz to 2299775610 Hz D0227 11:20:36.372295 51338 parameters.go:238] Clock(Realtime): error: 113 ns, adjusted frequency from 2299774633 Hz to 2299774894 Hz D0227 11:20:36.372417 51338 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8496239376921123 monotonicBaseRef:62000248590 monotonicFrequency:2299775610 realtimeReady:1 realtimeBaseCycles:8496239377519709 realtimeBaseRef:1582802436372288660 realtimeFrequency:2299774894} D0227 11:20:37.373008 51338 parameters.go:238] Clock(Monotonic): error: -363 ns, adjusted frequency from 2299775610 Hz to 2299773928 Hz D0227 11:20:37.373257 51338 parameters.go:238] Clock(Realtime): error: 97 ns, adjusted frequency from 2299774894 Hz to 2299774918 Hz D0227 11:20:37.373392 51338 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8496241678896299 monotonicBaseRef:63001205016 monotonicFrequency:2299773928 realtimeReady:1 realtimeBaseCycles:8496241679504748 realtimeBaseRef:1582802437373249686 realtimeFrequency:2299774918} D0227 11:20:38.372111 51338 parameters.go:238] Clock(Monotonic): error: 181 ns, adjusted frequency from 2299773928 Hz to 2299775109 Hz D0227 11:20:38.372361 51338 parameters.go:238] Clock(Realtime): error: -304 ns, adjusted frequency from 2299774918 Hz to 2299773956 Hz D0227 11:20:38.372502 51338 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8496243976573423 monotonicBaseRef:64000293272 monotonicFrequency:2299775109 realtimeReady:1 realtimeBaseCycles:8496243977220913 realtimeBaseRef:1582802438372354488 realtimeFrequency:2299773956} D0227 11:20:39.372540 51338 parameters.go:238] Clock(Monotonic): error: -5 ns, adjusted frequency from 2299775109 Hz to 2299774635 Hz D0227 11:20:39.372784 51338 parameters.go:238] Clock(Realtime): error: -61 ns, adjusted frequency from 2299773956 Hz to 2299774559 Hz D0227 11:20:39.372910 51338 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8496246277387177 monotonicBaseRef:65000744900 monotonicFrequency:2299774635 realtimeReady:1 realtimeBaseCycles:8496246277970051 realtimeBaseRef:1582802439372778521 realtimeFrequency:2299774559} D0227 11:20:40.372182 51338 parameters.go:238] Clock(Monotonic): error: -317 ns, adjusted frequency from 2299774635 Hz to 2299773950 Hz D0227 11:20:40.372392 51338 parameters.go:238] Clock(Realtime): error: 3 ns, adjusted frequency from 2299774559 Hz to 2299774740 Hz D0227 11:20:40.372558 51338 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8496248576327515 monotonicBaseRef:66000382126 monotonicFrequency:2299773950 realtimeReady:1 realtimeBaseCycles:8496248576840309 realtimeBaseRef:1582802440372385308 realtimeFrequency:2299774740} D0227 11:20:41.373455 51338 parameters.go:238] Clock(Monotonic): error: 388 ns, adjusted frequency from 2299773950 Hz to 2299775522 Hz D0227 11:20:41.373718 51338 parameters.go:238] Clock(Realtime): error: 262 ns, adjusted frequency from 2299774740 Hz to 2299775302 Hz D0227 11:20:41.373825 51338 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8496250879032291 monotonicBaseRef:67001656523 monotonicFrequency:2299775522 realtimeReady:1 realtimeBaseCycles:8496250879660208 realtimeBaseRef:1582802441373709419 realtimeFrequency:2299775302} D0227 11:20:42.373160 51338 parameters.go:238] Clock(Monotonic): error: -371 ns, adjusted frequency from 2299775522 Hz to 2299773828 Hz D0227 11:20:42.373407 51338 parameters.go:238] Clock(Realtime): error: -36 ns, adjusted frequency from 2299775302 Hz to 2299774608 Hz D0227 11:20:42.373521 51338 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8496253178133348 monotonicBaseRef:68001363248 monotonicFrequency:2299773828 realtimeReady:1 realtimeBaseCycles:8496253178725147 realtimeBaseRef:1582802442373400535 realtimeFrequency:2299774608} D0227 11:20:43.374622 51338 parameters.go:238] Clock(Monotonic): error: 30 ns, adjusted frequency from 2299773828 Hz to 2299774804 Hz D0227 11:20:43.374836 51338 parameters.go:238] Clock(Realtime): error: -75 ns, adjusted frequency from 2299774608 Hz to 2299774563 Hz D0227 11:20:43.374958 51338 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8496255481266651 monotonicBaseRef:69002824032 monotonicFrequency:2299774804 realtimeReady:1 realtimeBaseCycles:8496255481787198 realtimeBaseRef:1582802443374829998 realtimeFrequency:2299774563} D0227 11:20:44.372012 51338 parameters.go:238] Clock(Monotonic): error: 82 ns, adjusted frequency from 2299774804 Hz to 2299774874 Hz D0227 11:20:44.372235 51338 parameters.go:238] Clock(Realtime): error: -142 ns, adjusted frequency from 2299774563 Hz to 2299774405 Hz D0227 11:20:44.372410 51338 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8496257775041265 monotonicBaseRef:70000214998 monotonicFrequency:2299774874 realtimeReady:1 realtimeBaseCycles:8496257775577949 realtimeBaseRef:1582802444372228085 realtimeFrequency:2299774405} D0227 11:20:45.372149 51338 parameters.go:238] Clock(Monotonic): error: 310 ns, adjusted frequency from 2299774874 Hz to 2299775393 Hz D0227 11:20:45.372338 51338 parameters.go:238] Clock(Realtime): error: 236 ns, adjusted frequency from 2299774405 Hz to 2299775224 Hz D0227 11:20:45.372475 51338 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8496260075133612 monotonicBaseRef:71000353043 monotonicFrequency:2299775393 realtimeReady:1 realtimeBaseCycles:8496260075592091 realtimeBaseRef:1582802445372332328 realtimeFrequency:2299775224} D0227 11:20:46.372688 51338 parameters.go:238] Clock(Monotonic): error: -348 ns, adjusted frequency from 2299775393 Hz to 2299773890 Hz D0227 11:20:46.372990 51338 parameters.go:238] Clock(Realtime): error: 122 ns, adjusted frequency from 2299775224 Hz to 2299774982 Hz D0227 11:20:46.373165 51338 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8496262376106490 monotonicBaseRef:72000873739 monotonicFrequency:2299773890 realtimeReady:1 realtimeBaseCycles:8496262376863098 realtimeBaseRef:1582802446372982731 realtimeFrequency:2299774982} D0227 11:20:47.372058 51338 parameters.go:238] Clock(Monotonic): error: 282 ns, adjusted frequency from 2299773890 Hz to 2299775350 Hz D0227 11:20:47.372286 51338 parameters.go:238] Clock(Realtime): error: -171 ns, adjusted frequency from 2299774982 Hz to 2299774301 Hz D0227 11:20:47.372437 51338 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8496264674473508 monotonicBaseRef:73000261995 monotonicFrequency:2299775350 realtimeReady:1 realtimeBaseCycles:8496264675022627 realtimeBaseRef:1582802447372280291 realtimeFrequency:2299774301} D0227 11:20:48.373104 51338 parameters.go:238] Clock(Monotonic): error: -378 ns, adjusted frequency from 2299775350 Hz to 2299773868 Hz D0227 11:20:48.373308 51338 parameters.go:238] Clock(Realtime): error: -282 ns, adjusted frequency from 2299774301 Hz to 2299774042 Hz D0227 11:20:48.373443 51338 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8496266976547357 monotonicBaseRef:74001261440 monotonicFrequency:2299773868 realtimeReady:1 realtimeBaseCycles:8496266977145683 realtimeBaseRef:1582802448373301589 realtimeFrequency:2299774042} D0227 11:20:49.372293 51338 parameters.go:238] Clock(Monotonic): error: 184 ns, adjusted frequency from 2299773868 Hz to 2299775173 Hz D0227 11:20:49.373927 51338 parameters.go:238] Clock(Realtime): error: 356 ns, adjusted frequency from 2299774042 Hz to 2299775425 Hz D0227 11:20:49.374050 51338 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8496269274548046 monotonicBaseRef:75000490416 monotonicFrequency:2299775173 realtimeReady:1 realtimeBaseCycles:8496269275195635 realtimeBaseRef:1582802449372551911 realtimeFrequency:2299775425} D0227 11:20:50.372072 51338 parameters.go:238] Clock(Monotonic): error: -183 ns, adjusted frequency from 2299775173 Hz to 2299774345 Hz D0227 11:20:50.372274 51338 parameters.go:238] Clock(Realtime): error: 0 ns, adjusted frequency from 2299775425 Hz to 2299774621 Hz D0227 11:20:50.372408 51338 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8496271573814586 monotonicBaseRef:76000269249 monotonicFrequency:2299774345 realtimeReady:1 realtimeBaseCycles:8496271574315775 realtimeBaseRef:1582802450372266976 realtimeFrequency:2299774621} D0227 11:20:51.372992 51338 parameters.go:238] Clock(Monotonic): error: 131 ns, adjusted frequency from 2299774345 Hz to 2299775006 Hz D0227 11:20:51.373194 51338 parameters.go:238] Clock(Realtime): error: -44 ns, adjusted frequency from 2299774621 Hz to 2299774584 Hz D0227 11:20:51.373312 51338 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8496273875714326 monotonicBaseRef:77001193424 monotonicFrequency:2299775006 realtimeReady:1 realtimeBaseCycles:8496273876209769 realtimeBaseRef:1582802451373188532 realtimeFrequency:2299774584} D0227 11:20:52.372294 51338 parameters.go:238] Clock(Monotonic): error: 110 ns, adjusted frequency from 2299775006 Hz to 2299774858 Hz D0227 11:20:52.372538 51338 parameters.go:238] Clock(Realtime): error: -263 ns, adjusted frequency from 2299774584 Hz to 2299774118 Hz D0227 11:20:52.372695 51338 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8496276173515412 monotonicBaseRef:78000335113 monotonicFrequency:2299774858 realtimeReady:1 realtimeBaseCycles:8496276174472176 realtimeBaseRef:1582802452372530999 realtimeFrequency:2299774118} D0227 11:20:53.372479 51338 parameters.go:238] Clock(Monotonic): error: -248 ns, adjusted frequency from 2299774858 Hz to 2299774120 Hz D0227 11:20:53.372813 51338 parameters.go:238] Clock(Realtime): error: 65 ns, adjusted frequency from 2299774118 Hz to 2299774840 Hz D0227 11:20:53.372986 51338 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8496278474079260 monotonicBaseRef:79000678185 monotonicFrequency:2299774120 realtimeReady:1 realtimeBaseCycles:8496278474867827 realtimeBaseRef:1582802453372801257 realtimeFrequency:2299774840} D0227 11:20:54.372549 51338 parameters.go:238] Clock(Monotonic): error: 8 ns, adjusted frequency from 2299774120 Hz to 2299774754 Hz D0227 11:20:54.372794 51338 parameters.go:238] Clock(Realtime): error: 331 ns, adjusted frequency from 2299774840 Hz to 2299775481 Hz D0227 11:20:54.372928 51338 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8496280774020211 monotonicBaseRef:80000750727 monotonicFrequency:2299774754 realtimeReady:1 realtimeBaseCycles:8496280774598977 realtimeBaseRef:1582802454372782259 realtimeFrequency:2299775481} D0227 11:20:55.373374 51338 parameters.go:238] Clock(Monotonic): error: 405 ns, adjusted frequency from 2299774754 Hz to 2299775571 Hz D0227 11:20:55.373609 51338 parameters.go:238] Clock(Realtime): error: -130 ns, adjusted frequency from 2299775481 Hz to 2299774382 Hz D0227 11:20:55.373756 51338 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8496283075688037 monotonicBaseRef:81001573882 monotonicFrequency:2299775571 realtimeReady:1 realtimeBaseCycles:8496283076220706 realtimeBaseRef:1582802455373585053 realtimeFrequency:2299774382} D0227 11:20:56.372858 51338 parameters.go:238] Clock(Monotonic): error: -524 ns, adjusted frequency from 2299775571 Hz to 2299773545 Hz D0227 11:20:56.373113 51338 parameters.go:238] Clock(Realtime): error: 217 ns, adjusted frequency from 2299774382 Hz to 2299775181 Hz D0227 11:20:56.373290 51338 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8496285374285848 monotonicBaseRef:82001061762 monotonicFrequency:2299773545 realtimeReady:1 realtimeBaseCycles:8496285374888930 realtimeBaseRef:1582802456373104067 realtimeFrequency:2299775181} D0227 11:20:57.373113 51338 parameters.go:238] Clock(Monotonic): error: 59 ns, adjusted frequency from 2299773545 Hz to 2299774827 Hz D0227 11:20:57.373344 51338 parameters.go:238] Clock(Realtime): error: -149 ns, adjusted frequency from 2299775181 Hz to 2299774311 Hz D0227 11:20:57.373486 51338 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8496287674643716 monotonicBaseRef:83001315840 monotonicFrequency:2299774827 realtimeReady:1 realtimeBaseCycles:8496287675201159 realtimeBaseRef:1582802457373337588 realtimeFrequency:2299774311} D0227 11:20:58.372234 51338 parameters.go:238] Clock(Monotonic): error: 108 ns, adjusted frequency from 2299774827 Hz to 2299774925 Hz D0227 11:20:58.372544 51338 parameters.go:238] Clock(Realtime): error: -110 ns, adjusted frequency from 2299774311 Hz to 2299774409 Hz D0227 11:20:58.372698 51338 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8496289972371316 monotonicBaseRef:84000425654 monotonicFrequency:2299774925 realtimeReady:1 realtimeBaseCycles:8496289973134929 realtimeBaseRef:1582802458372537274 realtimeFrequency:2299774409} D0227 11:20:59.372694 51338 parameters.go:238] Clock(Monotonic): error: 63 ns, adjusted frequency from 2299774925 Hz to 2299774828 Hz D0227 11:20:59.372951 51338 parameters.go:238] Clock(Realtime): error: 255 ns, adjusted frequency from 2299774409 Hz to 2299775352 Hz D0227 11:20:59.373118 51338 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8496292273225645 monotonicBaseRef:85000895006 monotonicFrequency:2299774828 realtimeReady:1 realtimeBaseCycles:8496292273831712 realtimeBaseRef:1582802459372938345 realtimeFrequency:2299775352} D0227 11:21:00.374370 51338 parameters.go:238] Clock(Monotonic): error: 155 ns, adjusted frequency from 2299774828 Hz to 2299774961 Hz D0227 11:21:00.374580 51338 parameters.go:238] Clock(Realtime): error: -98 ns, adjusted frequency from 2299775352 Hz to 2299774469 Hz D0227 11:21:00.374710 51338 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8496294576817065 monotonicBaseRef:86002554556 monotonicFrequency:2299774961 realtimeReady:1 realtimeBaseCycles:8496294577367126 realtimeBaseRef:1582802460374573314 realtimeFrequency:2299774469} D0227 11:21:01.214276 51338 urpc.go:577] urpc: unmarshal success. D0227 11:21:01.214482 51338 controller.go:501] containerManager.Signal &{CID:ci-gvisor-ptrace-proxy-sandbox-race-2 Signo:0 PID:0 Mode:Process} D0227 11:21:01.214761 51338 urpc.go:534] urpc: successfully marshalled 37 bytes. D0227 11:21:01.373029 51338 parameters.go:238] Clock(Monotonic): error: -165 ns, adjusted frequency from 2299774961 Hz to 2299774359 Hz D0227 11:21:01.373300 51338 parameters.go:238] Clock(Realtime): error: -100 ns, adjusted frequency from 2299774469 Hz to 2299774415 Hz D0227 11:21:01.373413 51338 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8496296873547499 monotonicBaseRef:87001230719 monotonicFrequency:2299774359 realtimeReady:1 realtimeBaseCycles:8496296874200239 realtimeBaseRef:1582802461373294338 realtimeFrequency:2299774415} D0227 11:21:02.372761 51338 parameters.go:238] Clock(Monotonic): error: 69 ns, adjusted frequency from 2299774359 Hz to 2299774862 Hz D0227 11:21:02.373068 51338 parameters.go:238] Clock(Realtime): error: -107 ns, adjusted frequency from 2299774415 Hz to 2299774450 Hz D0227 11:21:02.373247 51338 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8496299172705526 monotonicBaseRef:88000962722 monotonicFrequency:2299774862 realtimeReady:1 realtimeBaseCycles:8496299173437524 realtimeBaseRef:1582802462373060780 realtimeFrequency:2299774450} I0227 11:21:02.533420 61382 main.go:296] *************************** I0227 11:21:02.533572 61382 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-2 /syz-execprog -executor=/syz-executor -arch=amd64 -sandbox=none -procs=1 -repeat=1 -threaded=true -collide=false -cover=0 -fault_call=-1 -fault_nth=-1 /syzkaller177165948] I0227 11:21:02.533982 61382 main.go:298] Version release-20200219.0-47-gde0b2ebf8635 I0227 11:21:02.534054 61382 main.go:299] PID: 61382 I0227 11:21:02.534151 61382 main.go:300] UID: 0, GID: 0 I0227 11:21:02.534229 61382 main.go:301] Configuration: I0227 11:21:02.534334 61382 main.go:302] RootDir: /syzkaller/managers/ptrace-proxy-sandbox-race/workdir/gvisor_root I0227 11:21:02.534403 61382 main.go:303] Platform: ptrace I0227 11:21:02.534491 61382 main.go:304] FileAccess: shared, overlay: false I0227 11:21:02.534597 61382 main.go:305] Network: sandbox, logging: false I0227 11:21:02.534694 61382 main.go:306] Strace: false, max size: 1024, syscalls: [] I0227 11:21:02.534766 61382 main.go:307] *************************** W0227 11:21:02.534836 61382 main.go:312] Block the TERM signal. This is only safe in tests! D0227 11:21:02.535254 61382 container.go:158] Load container "/syzkaller/managers/ptrace-proxy-sandbox-race/workdir/gvisor_root" "ci-gvisor-ptrace-proxy-sandbox-race-2" D0227 11:21:02.544433 51338 urpc.go:577] urpc: unmarshal success. D0227 11:21:02.544681 51338 controller.go:501] containerManager.Signal &{CID:ci-gvisor-ptrace-proxy-sandbox-race-2 Signo:0 PID:0 Mode:Process} D0227 11:21:02.544935 51338 urpc.go:534] urpc: successfully marshalled 37 bytes. D0227 11:21:02.543041 61382 container.go:591] Signal container "ci-gvisor-ptrace-proxy-sandbox-race-2": signal 0 D0227 11:21:02.543196 61382 sandbox.go:788] Signal sandbox "ci-gvisor-ptrace-proxy-sandbox-race-2" D0227 11:21:02.543302 61382 sandbox.go:318] Connecting to sandbox "ci-gvisor-ptrace-proxy-sandbox-race-2" D0227 11:21:02.545051 61382 urpc.go:534] urpc: successfully marshalled 117 bytes. D0227 11:21:02.545323 61382 urpc.go:577] urpc: unmarshal success. D0227 11:21:02.545428 61382 exec.go:120] Exec arguments: /syz-execprog -executor=/syz-executor -arch=amd64 -sandbox=none -procs=1 -repeat=1 -threaded=true -collide=false -cover=0 -fault_call=-1 -fault_nth=-1 /syzkaller177165948 D0227 11:21:02.545546 61382 exec.go:121] Exec capablities: &{PermittedCaps:274877898751 InheritableCaps:274877898751 EffectiveCaps:274877898751 BoundingCaps:274877898751 AmbientCaps:0} D0227 11:21:02.545679 61382 container.go:532] Execute in container "ci-gvisor-ptrace-proxy-sandbox-race-2", args: /syz-execprog -executor=/syz-executor -arch=amd64 -sandbox=none -procs=1 -repeat=1 -threaded=true -collide=false -cover=0 -fault_call=-1 -fault_nth=-1 /syzkaller177165948 D0227 11:21:02.545798 61382 sandbox.go:283] Executing new process in container "ci-gvisor-ptrace-proxy-sandbox-race-2" in sandbox "ci-gvisor-ptrace-proxy-sandbox-race-2" D0227 11:21:02.545904 61382 sandbox.go:318] Connecting to sandbox "ci-gvisor-ptrace-proxy-sandbox-race-2" D0227 11:21:02.547509 51338 urpc.go:577] urpc: unmarshal success. D0227 11:21:02.548654 51338 controller.go:267] containerManager.ExecuteAsync: /syz-execprog -executor=/syz-executor -arch=amd64 -sandbox=none -procs=1 -repeat=1 -threaded=true -collide=false -cover=0 -fault_call=-1 -fault_nth=-1 /syzkaller177165948 D0227 11:21:02.548927 51338 transport_flipcall.go:127] send [channel @0xc000554180] Twalkgetattr{FID: 1, NewFID: 5, Names: [etc]} D0227 11:21:02.549211 61382 urpc.go:534] urpc: successfully marshalled 635 bytes. D0227 11:21:02.550963 51338 transport_flipcall.go:234] recv [channel @0xc000554180] Rlerror{Error: 2} D0227 11:21:02.551111 51338 transport_flipcall.go:127] send [channel @0xc000554180] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.etc, Size: 1} D0227 11:21:02.552203 51338 transport_flipcall.go:234] recv [channel @0xc000554180] Rlerror{Error: 95} I0227 11:21:02.552705 51338 kernel.go:795] EXEC: [/syz-execprog -executor=/syz-executor -arch=amd64 -sandbox=none -procs=1 -repeat=1 -threaded=true -collide=false -cover=0 -fault_call=-1 -fault_nth=-1 /syzkaller177165948] D0227 11:21:02.553793 51338 transport_flipcall.go:127] send [channel @0xc000554180] Twalkgetattr{FID: 1, NewFID: 5, Names: [syz-execprog]} D0227 11:21:02.554871 51338 transport_flipcall.go:234] recv [channel @0xc000554180] 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: 1582802375, NanoSec: 871718968}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}, QIDs: [QID{Type: 0, Version: 0, Path: 15206651}]} D0227 11:21:02.555277 51338 transport_flipcall.go:127] send [channel @0xc000554180] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syz-execprog, Size: 1} D0227 11:21:02.555797 51338 transport_flipcall.go:234] recv [channel @0xc000554180] Rlerror{Error: 95} D0227 11:21:02.556167 51338 transport_flipcall.go:127] send [channel @0xc000554180] Tgetattr{FID: 5, AttrMask: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime INo Size Blocks BTime Gen DataVersion}} D0227 11:21:02.557070 51338 transport_flipcall.go:234] recv [channel @0xc000554180] Rgetattr{Valid: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime Size Blocks}, QID: QID{Type: 0, Version: 0, Path: 15206651}, 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: 1582802375, NanoSec: 871718968}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}} D0227 11:21:02.557217 51338 transport_flipcall.go:127] send [channel @0xc000554180] Twalk{FID: 5, NewFID: 6, Names: []} D0227 11:21:02.557815 51338 transport_flipcall.go:234] recv [channel @0xc000554180] Rwalk{QIDs: []} D0227 11:21:02.557939 51338 transport_flipcall.go:127] send [channel @0xc000554180] Tlopen{FID: 6, Flags: ReadOnly} D0227 11:21:02.558574 51338 transport_flipcall.go:234] recv [channel @0xc000554180] Rlopen{QID: QID{Type: 0, Version: 0, Path: 15206651}, IoUnit: 0, File: &{{33}}} D0227 11:21:02.563521 51338 syscalls.go:266] Allocating stack with size of 8388608 bytes D0227 11:21:02.564725 51338 loader.go:832] updated processes: map[{ci-gvisor-ptrace-proxy-sandbox-race-2 0}:0xc000394280 {ci-gvisor-ptrace-proxy-sandbox-race-2 20}:0xc000579500] D0227 11:21:02.565397 61382 urpc.go:577] urpc: unmarshal success. D0227 11:21:02.565531 61382 container.go:579] Wait on PID 20 in container "ci-gvisor-ptrace-proxy-sandbox-race-2" D0227 11:21:02.565661 61382 sandbox.go:743] Waiting for PID 20 in sandbox "ci-gvisor-ptrace-proxy-sandbox-race-2" D0227 11:21:02.565764 61382 sandbox.go:318] Connecting to sandbox "ci-gvisor-ptrace-proxy-sandbox-race-2" D0227 11:21:02.566037 61382 urpc.go:534] urpc: successfully marshalled 100 bytes. D0227 11:21:02.566249 51338 urpc.go:534] urpc: successfully marshalled 37 bytes. D0227 11:21:02.566548 51338 urpc.go:577] urpc: unmarshal success. D0227 11:21:02.566954 51338 controller.go:443] containerManager.Wait D0227 11:21:02.568940 51338 task_block.go:223] [ 20] Interrupt queued D0227 11:21:02.814557 51338 task_block.go:223] [ 21] Interrupt queued D0227 11:21:02.816385 51338 task_block.go:223] [ 22] Interrupt queued D0227 11:21:02.818375 51338 task_block.go:223] [ 23] Interrupt queued D0227 11:21:02.834466 51338 task_block.go:223] [ 24] Interrupt queued D0227 11:21:02.853121 51338 task_block.go:223] [ 25] Interrupt queued D0227 11:21:02.868384 51338 task_block.go:223] [ 26] Interrupt queued D0227 11:21:02.870033 51338 task_block.go:223] [ 27] Interrupt queued D0227 11:21:02.949123 51338 transport_flipcall.go:127] send [channel @0xc000554180] Twalkgetattr{FID: 1, NewFID: 7, Names: [etc]} D0227 11:21:02.949884 51338 transport_flipcall.go:234] recv [channel @0xc000554180] Rlerror{Error: 2} D0227 11:21:02.950026 51338 transport_flipcall.go:127] send [channel @0xc000554180] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.etc, Size: 1} D0227 11:21:02.950478 51338 transport_flipcall.go:234] recv [channel @0xc000554180] Rlerror{Error: 95} D0227 11:21:02.992524 51338 task_block.go:223] [ 28] Interrupt queued D0227 11:21:02.994682 51338 task_block.go:223] [ 29] Interrupt queued D0227 11:21:03.012774 51338 task_block.go:223] [ 30] Interrupt queued D0227 11:21:03.032811 51338 task_block.go:223] [ 31] Interrupt queued D0227 11:21:03.092710 51338 task_block.go:223] [ 32] Interrupt queued D0227 11:21:03.115042 51338 task_block.go:223] [ 33] Interrupt queued D0227 11:21:03.184935 51338 task_block.go:223] [ 34] Interrupt queued D0227 11:21:03.211759 51338 task_block.go:223] [ 35] Interrupt queued D0227 11:21:03.213108 51338 transport_flipcall.go:127] send [channel @0xc000554180] Twalkgetattr{FID: 1, NewFID: 7, Names: [syzkaller177165948]} D0227 11:21:03.214219 51338 transport_flipcall.go:234] recv [channel @0xc000554180] Rwalkgetattr{Valid: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime Size Blocks}, Attr: Attr{Mode: 0o100600, UID: 0, GID: 0, NLink: 1, RDev: 0, Size: 199, BlockSize: 4096, Blocks: 8, ATime: {Sec: 1582802462, NanoSec: 468201943}, MTime: {Sec: 1582802462, NanoSec: 468201943}, CTime: {Sec: 1582802462, NanoSec: 468201943}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}, QIDs: [QID{Type: 0, Version: 0, Path: 15204387}]} D0227 11:21:03.214448 51338 transport_flipcall.go:127] send [channel @0xc000554180] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzkaller177165948, Size: 1} D0227 11:21:03.215070 51338 transport_flipcall.go:234] recv [channel @0xc000554180] Rlerror{Error: 95} D0227 11:21:03.215358 51338 transport_flipcall.go:127] send [channel @0xc000554180] Tgetattr{FID: 7, AttrMask: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime INo Size Blocks BTime Gen DataVersion}} D0227 11:21:03.216560 51338 transport_flipcall.go:234] recv [channel @0xc000554180] Rgetattr{Valid: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime Size Blocks}, QID: QID{Type: 0, Version: 0, Path: 15204387}, Attr: Attr{Mode: 0o100600, UID: 0, GID: 0, NLink: 1, RDev: 0, Size: 199, BlockSize: 4096, Blocks: 8, ATime: {Sec: 1582802462, NanoSec: 468201943}, MTime: {Sec: 1582802462, NanoSec: 468201943}, CTime: {Sec: 1582802462, NanoSec: 468201943}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}} D0227 11:21:03.216767 51338 transport_flipcall.go:127] send [channel @0xc000554180] Twalk{FID: 7, NewFID: 8, Names: []} D0227 11:21:03.218262 51338 transport_flipcall.go:234] recv [channel @0xc000554180] Rwalk{QIDs: []} D0227 11:21:03.218409 51338 transport_flipcall.go:127] send [channel @0xc000554180] Tlopen{FID: 8, Flags: ReadOnly} D0227 11:21:03.220570 51338 transport_flipcall.go:234] recv [channel @0xc000554180] Rlopen{QID: QID{Type: 0, Version: 0, Path: 15204387}, IoUnit: 0, File: &{{25}}} D0227 11:21:03.228206 51338 transport_flipcall.go:127] send [channel @0xc000554180] Tgetattr{FID: 8, AttrMask: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime INo Size Blocks BTime Gen DataVersion}} D0227 11:21:03.229921 51338 transport_flipcall.go:234] recv [channel @0xc000554180] Rgetattr{Valid: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime Size Blocks}, QID: QID{Type: 0, Version: 0, Path: 15204387}, Attr: Attr{Mode: 0o100600, UID: 0, GID: 0, NLink: 1, RDev: 0, Size: 199, BlockSize: 4096, Blocks: 8, ATime: {Sec: 1582802462, NanoSec: 468201943}, MTime: {Sec: 1582802462, NanoSec: 468201943}, CTime: {Sec: 1582802462, NanoSec: 468201943}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}} 2020/02/27 11:21:03 parsed 1 programs D0227 11:21:03.253408 51338 task_stop.go:118] [ 34] Entering internal stop (*kernel.vforkStop)(nil) D0227 11:21:03.254018 51338 task_block.go:223] [ 36] Interrupt queued D0227 11:21:03.268814 51338 transport_flipcall.go:127] send [channel @0xc000554180] Twalkgetattr{FID: 1, NewFID: 9, Names: [syz-executor]} D0227 11:21:03.277601 51338 transport_flipcall.go:234] recv [channel @0xc000554180] 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: 1582802375, NanoSec: 875719360}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}, QIDs: [QID{Type: 0, Version: 0, Path: 15206769}]} D0227 11:21:03.277910 51338 transport_flipcall.go:127] send [channel @0xc000554180] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syz-executor, Size: 1} D0227 11:21:03.281538 51338 transport_flipcall.go:234] recv [channel @0xc000554180] Rlerror{Error: 95} D0227 11:21:03.281815 51338 transport_flipcall.go:127] send [channel @0xc000554180] Tgetattr{FID: 9, AttrMask: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime INo Size Blocks BTime Gen DataVersion}} D0227 11:21:03.282892 51338 transport_flipcall.go:234] recv [channel @0xc000554180] Rgetattr{Valid: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime Size Blocks}, QID: QID{Type: 0, Version: 0, Path: 15206769}, 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: 1582802375, NanoSec: 875719360}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}} D0227 11:21:03.284895 51338 transport_flipcall.go:127] send [channel @0xc000554180] Twalk{FID: 9, NewFID: 10, Names: []} D0227 11:21:03.285661 51338 transport_flipcall.go:234] recv [channel @0xc000554180] Rwalk{QIDs: []} D0227 11:21:03.285799 51338 transport_flipcall.go:127] send [channel @0xc000554180] Tlopen{FID: 10, Flags: ReadOnly} D0227 11:21:03.286399 51338 transport_flipcall.go:234] recv [channel @0xc000554180] Rlopen{QID: QID{Type: 0, Version: 0, Path: 15206769}, IoUnit: 0, File: &{{26}}} D0227 11:21:03.287754 51338 syscalls.go:266] [ 36] Allocating stack with size of 8388608 bytes D0227 11:21:03.288669 51338 task_stop.go:137] [ 34] Leaving internal stop (*kernel.vforkStop)(nil) D0227 11:21:03.345818 51338 transport_flipcall.go:127] send [channel @0xc000554180] Twalkgetattr{FID: 1, NewFID: 11, Names: [etc]} D0227 11:21:03.347384 51338 transport_flipcall.go:234] recv [channel @0xc000554180] Rlerror{Error: 2} D0227 11:21:03.347647 51338 transport_flipcall.go:127] send [channel @0xc000554180] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.etc, Size: 1} D0227 11:21:03.348597 51338 transport_flipcall.go:234] recv [channel @0xc000554180] Rlerror{Error: 95} D0227 11:21:03.349754 51338 task_exit.go:221] [ 36] Transitioning from exit state TaskExitNone to TaskExitInitiated D0227 11:21:03.350880 51338 task_exit.go:221] [ 36] Transitioning from exit state TaskExitInitiated to TaskExitZombie D0227 11:21:03.351015 51338 task_signals.go:446] [ 20] Notified of signal 17 D0227 11:21:03.351127 51338 task_block.go:223] [ 20] Interrupt queued D0227 11:21:03.351702 51338 task_block.go:223] [ 20] Interrupt queued D0227 11:21:03.351857 51338 task_signals.go:179] [ 20] Restarting syscall 202 after errno 512: interrupted by signal 17 D0227 11:21:03.352008 51338 task_signals.go:220] [ 20] Signal 17: delivering to handler D0227 11:21:03.355173 51338 task_exit.go:221] [ 36] Transitioning from exit state TaskExitZombie to TaskExitDead D0227 11:21:03.357417 51338 task_block.go:223] [ 37] Interrupt queued D0227 11:21:03.364326 51338 transport_flipcall.go:127] send [channel @0xc000554180] Twalkgetattr{FID: 1, NewFID: 11, Names: [syz-executor]} D0227 11:21:03.365604 51338 transport_flipcall.go:234] recv [channel @0xc000554180] 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: 1582802375, NanoSec: 875719360}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}, QIDs: [QID{Type: 0, Version: 0, Path: 15206769}]} D0227 11:21:03.365754 51338 transport_flipcall.go:127] send [channel @0xc000554180] Tclunk{FID: 11} D0227 11:21:03.366726 51338 transport_flipcall.go:234] recv [channel @0xc000554180] Rclunk{} D0227 11:21:03.366937 51338 transport_flipcall.go:127] send [channel @0xc000554180] Tgetattr{FID: 9, AttrMask: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime INo Size Blocks BTime Gen DataVersion}} D0227 11:21:03.368056 51338 transport_flipcall.go:234] recv [channel @0xc000554180] Rgetattr{Valid: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime Size Blocks}, QID: QID{Type: 0, Version: 0, Path: 15206769}, 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: 1582802375, NanoSec: 875719360}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}} 2020/02/27 11:21:03 executed programs: 0 D0227 11:21:03.371994 51338 parameters.go:238] Clock(Monotonic): error: -158 ns, adjusted frequency from 2299774862 Hz to 2299774330 Hz D0227 11:21:03.372291 51338 parameters.go:238] Clock(Realtime): error: -127 ns, adjusted frequency from 2299774450 Hz to 2299774440 Hz D0227 11:21:03.372446 51338 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:8496301470728454 monotonicBaseRef:89000200936 monotonicFrequency:2299774330 realtimeReady:1 realtimeBaseCycles:8496301471424881 realtimeBaseRef:1582802463372283706 realtimeFrequency:2299774440} D0227 11:21:03.379638 51338 task_stop.go:118] [ 34] Entering internal stop (*kernel.vforkStop)(nil) D0227 11:21:03.380426 51338 task_block.go:223] [ 38] Interrupt queued D0227 11:21:03.385940 51338 transport_flipcall.go:127] send [channel @0xc000554180] Twalkgetattr{FID: 1, NewFID: 11, Names: [syz-executor]} D0227 11:21:03.387289 51338 transport_flipcall.go:234] recv [channel @0xc000554180] 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: 1582802375, NanoSec: 875719360}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}, QIDs: [QID{Type: 0, Version: 0, Path: 15206769}]} D0227 11:21:03.387454 51338 transport_flipcall.go:127] send [channel @0xc000554180] Tclunk{FID: 11} D0227 11:21:03.387964 51338 transport_flipcall.go:234] recv [channel @0xc000554180] Rclunk{} D0227 11:21:03.388223 51338 transport_flipcall.go:127] send [channel @0xc000554180] Tgetattr{FID: 9, AttrMask: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime INo Size Blocks BTime Gen DataVersion}} D0227 11:21:03.389193 51338 transport_flipcall.go:234] recv [channel @0xc000554180] Rgetattr{Valid: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime Size Blocks}, QID: QID{Type: 0, Version: 0, Path: 15206769}, 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: 1582802375, NanoSec: 875719360}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}} D0227 11:21:03.390263 51338 syscalls.go:266] [ 38] Allocating stack with size of 8388608 bytes D0227 11:21:03.391153 51338 task_stop.go:137] [ 34] Leaving internal stop (*kernel.vforkStop)(nil) D0227 11:21:03.398346 51338 transport_flipcall.go:127] send [channel @0xc000554180] Twalkgetattr{FID: 1, NewFID: 11, Names: [etc]} D0227 11:21:03.398866 51338 transport_flipcall.go:234] recv [channel @0xc000554180] Rlerror{Error: 2} D0227 11:21:03.399007 51338 transport_flipcall.go:127] send [channel @0xc000554180] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.etc, Size: 1} D0227 11:21:03.399475 51338 transport_flipcall.go:234] recv [channel @0xc000554180] Rlerror{Error: 95} D0227 11:21:03.410791 51338 task_block.go:223] [ 39] Interrupt queued D0227 11:21:03.417650 51338 transport_flipcall.go:127] send [channel @0xc000554180] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0227 11:21:03.418289 51338 transport_flipcall.go:234] recv [channel @0xc000554180] Rlerror{Error: 2} D0227 11:21:03.418479 51338 transport_flipcall.go:127] send [channel @0xc000554180] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0227 11:21:03.418901 51338 transport_flipcall.go:234] recv [channel @0xc000554180] Rlerror{Error: 95} D0227 11:21:03.419436 51338 transport_flipcall.go:127] send [channel @0xc000554180] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0227 11:21:03.419941 51338 transport_flipcall.go:234] recv [channel @0xc000554180] Rlerror{Error: 2} D0227 11:21:03.420078 51338 transport_flipcall.go:127] send [channel @0xc000554180] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0227 11:21:03.420789 51338 transport_flipcall.go:234] recv [channel @0xc000554180] Rlerror{Error: 95} D0227 11:21:03.421223 51338 transport_flipcall.go:127] send [channel @0xc000554180] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0227 11:21:03.422440 51338 transport_flipcall.go:234] recv [channel @0xc000554180] Rlerror{Error: 2} D0227 11:21:03.422618 51338 transport_flipcall.go:127] send [channel @0xc000554180] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0227 11:21:03.423064 51338 transport_flipcall.go:234] recv [channel @0xc000554180] Rlerror{Error: 95} D0227 11:21:03.425222 51338 transport_flipcall.go:127] send [channel @0xc000554180] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0227 11:21:03.425877 51338 transport_flipcall.go:234] recv [channel @0xc000554180] Rlerror{Error: 2} D0227 11:21:03.426318 51338 transport_flipcall.go:127] send [channel @0xc000554180] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0227 11:21:03.426855 51338 transport_flipcall.go:234] recv [channel @0xc000554180] Rlerror{Error: 95} D0227 11:21:03.427233 51338 transport_flipcall.go:127] send [channel @0xc000554180] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0227 11:21:03.427733 51338 transport_flipcall.go:234] recv [channel @0xc000554180] Rlerror{Error: 2} D0227 11:21:03.427942 51338 transport_flipcall.go:127] send [channel @0xc000554180] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0227 11:21:03.428389 51338 transport_flipcall.go:234] recv [channel @0xc000554180] Rlerror{Error: 95} D0227 11:21:03.428826 51338 transport_flipcall.go:127] send [channel @0xc000554180] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0227 11:21:03.429342 51338 transport_flipcall.go:234] recv [channel @0xc000554180] Rlerror{Error: 2} D0227 11:21:03.429536 51338 transport_flipcall.go:127] send [channel @0xc000554180] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0227 11:21:03.430134 51338 transport_flipcall.go:234] recv [channel @0xc000554180] Rlerror{Error: 95} D0227 11:21:03.430443 51338 transport_flipcall.go:127] send [channel @0xc000554180] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0227 11:21:03.431014 51338 transport_flipcall.go:234] recv [channel @0xc000554180] Rlerror{Error: 2} D0227 11:21:03.433401 51338 transport_flipcall.go:127] send [channel @0xc000554180] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0227 11:21:03.434210 51338 transport_flipcall.go:234] recv [channel @0xc000554180] Rlerror{Error: 95} D0227 11:21:03.436706 51338 transport_flipcall.go:127] send [channel @0xc000554180] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0227 11:21:03.437607 51338 transport_flipcall.go:234] recv [channel @0xc000554180] Rlerror{Error: 2} D0227 11:21:03.437804 51338 transport_flipcall.go:127] send [channel @0xc000554180] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0227 11:21:03.438422 51338 transport_flipcall.go:234] recv [channel @0xc000554180] Rlerror{Error: 95} D0227 11:21:03.439217 51338 transport_flipcall.go:127] send [channel @0xc000554180] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0227 11:21:03.439756 51338 transport_flipcall.go:234] recv [channel @0xc000554180] Rlerror{Error: 2} D0227 11:21:03.440101 51338 transport_flipcall.go:127] send [channel @0xc000554180] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0227 11:21:03.441193 51338 transport_flipcall.go:234] recv [channel @0xc000554180] Rlerror{Error: 95} D0227 11:21:03.472946 51338 netfilter.go:54] netfilter: convert to binary: current offset: 0 D0227 11:21:03.473278 51338 netfilter.go:54] netfilter: convert to binary: found hook 0 at offset 0 D0227 11:21:03.473465 51338 netfilter.go:54] netfilter: convert to binary: found underflow 0 at offset 0 D0227 11:21:03.473568 51338 netfilter.go:54] netfilter: convert to binary: marshalling standard target D0227 11:21:03.473660 51338 netfilter.go:54] netfilter: convert to binary: adding entry: {IPTEntry:{IP:{Src:[0 0 0 0] Dst:[0 0 0 0] SrcMask:[0 0 0 0] DstMask:[0 0 0 0] InputInterface:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] OutputInterface:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] InputInterfaceMask:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] OutputInterfaceMask:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] Protocol:0 Flags:0 InverseFlags:0} NFCache:0 TargetOffset:112 NextOffset:152 Comeback:0 Counters:{Pcnt:0 Bcnt:0}} Elems:[40 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 254 255 255 255 0 0 0 0]} D0227 11:21:03.474616 51338 netfilter.go:54] netfilter: convert to binary: current offset: 152 D0227 11:21:03.474718 51338 netfilter.go:54] netfilter: convert to binary: found hook 1 at offset 152 D0227 11:21:03.474788 51338 netfilter.go:54] netfilter: convert to binary: found underflow 1 at offset 152 D0227 11:21:03.474871 51338 netfilter.go:54] netfilter: convert to binary: marshalling standard target D0227 11:21:03.474971 51338 netfilter.go:54] netfilter: convert to binary: adding entry: {IPTEntry:{IP:{Src:[0 0 0 0] Dst:[0 0 0 0] SrcMask:[0 0 0 0] DstMask:[0 0 0 0] InputInterface:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] OutputInterface:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] InputInterfaceMask:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] OutputInterfaceMask:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] Protocol:0 Flags:0 InverseFlags:0} NFCache:0 TargetOffset:112 NextOffset:152 Comeback:0 Counters:{Pcnt:0 Bcnt:0}} Elems:[40 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 254 255 255 255 0 0 0 0]} D0227 11:21:03.475484 51338 netfilter.go:54] netfilter: convert to binary: current offset: 304 D0227 11:21:03.475973 51338 netfilter.go:54] netfilter: convert to binary: found hook 3 at offset 304 D0227 11:21:03.476118 51338 netfilter.go:54] netfilter: convert to binary: found underflow 3 at offset 304 D0227 11:21:03.476206 51338 netfilter.go:54] netfilter: convert to binary: marshalling standard target D0227 11:21:03.476300 51338 netfilter.go:54] netfilter: convert to binary: adding entry: {IPTEntry:{IP:{Src:[0 0 0 0] Dst:[0 0 0 0] SrcMask:[0 0 0 0] DstMask:[0 0 0 0] InputInterface:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] OutputInterface:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] InputInterfaceMask:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] OutputInterfaceMask:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] Protocol:0 Flags:0 InverseFlags:0} NFCache:0 TargetOffset:112 NextOffset:152 Comeback:0 Counters:{Pcnt:0 Bcnt:0}} Elems:[40 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 254 255 255 255 0 0 0 0]} D0227 11:21:03.476909 51338 netfilter.go:54] netfilter: convert to binary: current offset: 456 D0227 11:21:03.477045 51338 netfilter.go:54] netfilter: convert to binary: found hook 4 at offset 456 D0227 11:21:03.477127 51338 netfilter.go:54] netfilter: convert to binary: found underflow 4 at offset 456 D0227 11:21:03.477194 51338 netfilter.go:54] netfilter: convert to binary: marshalling standard target D0227 11:21:03.477292 51338 netfilter.go:54] netfilter: convert to binary: adding entry: {IPTEntry:{IP:{Src:[0 0 0 0] Dst:[0 0 0 0] SrcMask:[0 0 0 0] DstMask:[0 0 0 0] InputInterface:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] OutputInterface:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] InputInterfaceMask:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] OutputInterfaceMask:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] Protocol:0 Flags:0 InverseFlags:0} NFCache:0 TargetOffset:112 NextOffset:152 Comeback:0 Counters:{Pcnt:0 Bcnt:0}} Elems:[40 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 254 255 255 255 0 0 0 0]} D0227 11:21:03.478005 51338 netfilter.go:54] netfilter: convert to binary: current offset: 608 D0227 11:21:03.478107 51338 netfilter.go:54] netfilter: convert to binary: adding entry: {IPTEntry:{IP:{Src:[0 0 0 0] Dst:[0 0 0 0] SrcMask:[0 0 0 0] DstMask:[0 0 0 0] InputInterface:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] OutputInterface:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] InputInterfaceMask:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] OutputInterfaceMask:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] Protocol:0 Flags:0 InverseFlags:0} NFCache:0 TargetOffset:112 NextOffset:176 Comeback:0 Counters:{Pcnt:0 Bcnt:0}} Elems:[64 0 69 82 82 79 82 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 69 82 82 79 82 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0]} D0227 11:21:03.478765 51338 netfilter.go:54] netfilter: convert to binary: finished with an marshalled size of 0 D0227 11:21:03.478932 51338 netfilter.go:54] netfilter: convert to binary: current offset: 0 D0227 11:21:03.479095 51338 netfilter.go:54] netfilter: convert to binary: found hook 0 at offset 0 D0227 11:21:03.479205 51338 netfilter.go:54] netfilter: convert to binary: found underflow 0 at offset 0 D0227 11:21:03.479282 51338 netfilter.go:54] netfilter: convert to binary: marshalling standard target D0227 11:21:03.479356 51338 netfilter.go:54] netfilter: convert to binary: adding entry: {IPTEntry:{IP:{Src:[0 0 0 0] Dst:[0 0 0 0] SrcMask:[0 0 0 0] DstMask:[0 0 0 0] InputInterface:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] OutputInterface:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] InputInterfaceMask:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] OutputInterfaceMask:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] Protocol:0 Flags:0 InverseFlags:0} NFCache:0 TargetOffset:112 NextOffset:152 Comeback:0 Counters:{Pcnt:0 Bcnt:0}} Elems:[40 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 254 255 255 255 0 0 0 0]} D0227 11:21:03.479872 51338 netfilter.go:54] netfilter: convert to binary: current offset: 152 D0227 11:21:03.479991 51338 netfilter.go:54] netfilter: convert to binary: found hook 3 at offset 152 D0227 11:21:03.480069 51338 netfilter.go:54] netfilter: convert to binary: found underflow 3 at offset 152 D0227 11:21:03.480155 51338 netfilter.go:54] netfilter: convert to binary: marshalling standard target D0227 11:21:03.480264 51338 netfilter.go:54] netfilter: convert to binary: adding entry: {IPTEntry:{IP:{Src:[0 0 0 0] Dst:[0 0 0 0] SrcMask:[0 0 0 0] DstMask:[0 0 0 0] InputInterface:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] OutputInterface:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] InputInterfaceMask:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] OutputInterfaceMask:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] Protocol:0 Flags:0 InverseFlags:0} NFCache:0 TargetOffset:112 NextOffset:152 Comeback:0 Counters:{Pcnt:0 Bcnt:0}} Elems:[40 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 254 255 255 255 0 0 0 0]} D0227 11:21:03.480837 51338 netfilter.go:54] netfilter: convert to binary: current offset: 304 D0227 11:21:03.480933 51338 netfilter.go:54] netfilter: convert to binary: adding entry: {IPTEntry:{IP:{Src:[0 0 0 0] Dst:[0 0 0 0] SrcMask:[0 0 0 0] DstMask:[0 0 0 0] InputInterface:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] OutputInterface:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] InputInterfaceMask:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] OutputInterfaceMask:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] Protocol:0 Flags:0 InverseFlags:0} NFCache:0 TargetOffset:112 NextOffset:176 Comeback:0 Counters:{Pcnt:0 Bcnt:0}} Elems:[64 0 69 82 82 79 82 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 69 82 82 79 82 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0]} D0227 11:21:03.481433 51338 netfilter.go:54] netfilter: convert to binary: finished with an marshalled size of 0 D0227 11:21:03.481525 51338 netfilter.go:54] netfilter: convert to binary: current offset: 0 D0227 11:21:03.481602 51338 netfilter.go:54] netfilter: convert to binary: found hook 1 at offset 0 D0227 11:21:03.481689 51338 netfilter.go:54] netfilter: convert to binary: found underflow 1 at offset 0 D0227 11:21:03.481773 51338 netfilter.go:54] netfilter: convert to binary: marshalling standard target D0227 11:21:03.481835 51338 netfilter.go:54] netfilter: convert to binary: adding entry: {IPTEntry:{IP:{Src:[0 0 0 0] Dst:[0 0 0 0] SrcMask:[0 0 0 0] DstMask:[0 0 0 0] InputInterface:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] OutputInterface:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] InputInterfaceMask:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] OutputInterfaceMask:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] Protocol:0 Flags:0 InverseFlags:0} NFCache:0 TargetOffset:112 NextOffset:152 Comeback:0 Counters:{Pcnt:0 Bcnt:0}} Elems:[40 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 254 255 255 255 0 0 0 0]} D0227 11:21:03.482661 51338 netfilter.go:54] netfilter: convert to binary: current offset: 152 D0227 11:21:03.482730 51338 netfilter.go:54] netfilter: convert to binary: found hook 2 at offset 152 D0227 11:21:03.482791 51338 netfilter.go:54] netfilter: convert to binary: found underflow 2 at offset 152 D0227 11:21:03.482849 51338 netfilter.go:54] netfilter: convert to binary: marshalling standard target D0227 11:21:03.482918 51338 netfilter.go:54] netfilter: convert to binary: adding entry: {IPTEntry:{IP:{Src:[0 0 0 0] Dst:[0 0 0 0] SrcMask:[0 0 0 0] DstMask:[0 0 0 0] InputInterface:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] OutputInterface:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] InputInterfaceMask:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] OutputInterfaceMask:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] Protocol:0 Flags:0 InverseFlags:0} NFCache:0 TargetOffset:112 NextOffset:152 Comeback:0 Counters:{Pcnt:0 Bcnt:0}} Elems:[40 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 254 255 255 255 0 0 0 0]} D0227 11:21:03.483356 51338 netfilter.go:54] netfilter: convert to binary: current offset: 304 D0227 11:21:03.483427 51338 netfilter.go:54] netfilter: convert to binary: found hook 3 at offset 304 D0227 11:21:03.483490 51338 netfilter.go:54] netfilter: convert to binary: found underflow 3 at offset 304 D0227 11:21:03.483577 51338 netfilter.go:54] netfilter: convert to binary: marshalling standard target D0227 11:21:03.483644 51338 netfilter.go:54] netfilter: convert to binary: adding entry: {IPTEntry:{IP:{Src:[0 0 0 0] Dst:[0 0 0 0] SrcMask:[0 0 0 0] DstMask:[0 0 0 0] InputInterface:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] OutputInterface:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] InputInterfaceMask:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] OutputInterfaceMask:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] Protocol:0 Flags:0 InverseFlags:0} NFCache:0 TargetOffset:112 NextOffset:152 Comeback:0 Counters:{Pcnt:0 Bcnt:0}} Elems:[40 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 254 255 255 255 0 0 0 0]} D0227 11:21:03.484155 51338 netfilter.go:54] netfilter: convert to binary: current offset: 456 D0227 11:21:03.484245 51338 netfilter.go:54] netfilter: convert to binary: adding entry: {IPTEntry:{IP:{Src:[0 0 0 0] Dst:[0 0 0 0] SrcMask:[0 0 0 0] DstMask:[0 0 0 0] InputInterface:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] OutputInterface:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] InputInterfaceMask:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] OutputInterfaceMask:[0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0] Protocol:0 Flags:0 InverseFlags:0} NFCache:0 TargetOffset:112 NextOffset:176 Comeback:0 Counters:{Pcnt:0 Bcnt:0}} Elems:[64 0 69 82 82 79 82 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 69 82 82 79 82 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0]} D0227 11:21:03.484780 51338 netfilter.go:54] netfilter: convert to binary: finished with an marshalled size of 0 D0227 11:21:03.655552 51338 transport_flipcall.go:127] send [channel @0xc000554180] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0227 11:21:03.660818 51338 transport_flipcall.go:234] recv [channel @0xc000554180] Rlerror{Error: 2} D0227 11:21:03.661005 51338 transport_flipcall.go:127] send [channel @0xc000554180] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0227 11:21:03.662040 51338 transport_flipcall.go:234] recv [channel @0xc000554180] Rlerror{Error: 95} D0227 11:21:03.662343 51338 transport_flipcall.go:127] send [channel @0xc000554180] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0227 11:21:03.662795 51338 transport_flipcall.go:234] recv [channel @0xc000554180] Rlerror{Error: 2} D0227 11:21:03.662934 51338 transport_flipcall.go:127] send [channel @0xc000554180] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0227 11:21:03.663307 51338 transport_flipcall.go:234] recv [channel @0xc000554180] Rlerror{Error: 95} D0227 11:21:03.663538 51338 transport_flipcall.go:127] send [channel @0xc000554180] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0227 11:21:03.664051 51338 transport_flipcall.go:234] recv [channel @0xc000554180] Rlerror{Error: 2} D0227 11:21:03.675509 51338 transport_flipcall.go:127] send [channel @0xc000554180] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0227 11:21:03.676749 51338 transport_flipcall.go:234] recv [channel @0xc000554180] Rlerror{Error: 95} D0227 11:21:03.677383 51338 transport_flipcall.go:127] send [channel @0xc000554180] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0227 11:21:03.678183 51338 transport_flipcall.go:234] recv [channel @0xc000554180] Rlerror{Error: 2} D0227 11:21:03.678485 51338 transport_flipcall.go:127] send [channel @0xc000554180] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0227 11:21:03.679869 51338 transport_flipcall.go:234] recv [channel @0xc000554180] Rlerror{Error: 95} D0227 11:21:03.680466 51338 transport_flipcall.go:127] send [channel @0xc000554180] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0227 11:21:03.681220 51338 transport_flipcall.go:234] recv [channel @0xc000554180] Rlerror{Error: 2} D0227 11:21:03.681571 51338 transport_flipcall.go:127] send [channel @0xc000554180] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0227 11:21:03.683209 51338 transport_flipcall.go:234] recv [channel @0xc000554180] Rlerror{Error: 95} D0227 11:21:03.683508 51338 transport_flipcall.go:127] send [channel @0xc000554180] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0227 11:21:03.684511 51338 transport_flipcall.go:234] recv [channel @0xc000554180] Rlerror{Error: 2} D0227 11:21:03.684713 51338 transport_flipcall.go:127] send [channel @0xc000554180] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0227 11:21:03.685366 51338 transport_flipcall.go:234] recv [channel @0xc000554180] Rlerror{Error: 95} D0227 11:21:03.685934 51338 transport_flipcall.go:127] send [channel @0xc000554180] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0227 11:21:03.686408 51338 transport_flipcall.go:234] recv [channel @0xc000554180] Rlerror{Error: 2} D0227 11:21:03.686591 51338 transport_flipcall.go:127] send [channel @0xc000554180] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0227 11:21:03.687746 51338 transport_flipcall.go:234] recv [channel @0xc000554180] Rlerror{Error: 95} D0227 11:21:03.688179 51338 transport_flipcall.go:127] send [channel @0xc000554180] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0227 11:21:03.689414 51338 transport_flipcall.go:234] recv [channel @0xc000554180] Rlerror{Error: 2} D0227 11:21:03.689623 51338 transport_flipcall.go:127] send [channel @0xc000554180] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0227 11:21:03.690168 51338 transport_flipcall.go:234] recv [channel @0xc000554180] Rlerror{Error: 95} D0227 11:21:03.690479 51338 transport_flipcall.go:127] send [channel @0xc000554180] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0227 11:21:03.691100 51338 transport_flipcall.go:234] recv [channel @0xc000554180] Rlerror{Error: 2} D0227 11:21:03.691344 51338 transport_flipcall.go:127] send [channel @0xc000554180] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0227 11:21:03.691797 51338 transport_flipcall.go:234] recv [channel @0xc000554180] Rlerror{Error: 95} D0227 11:21:03.692394 51338 transport_flipcall.go:127] send [channel @0xc000554180] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0227 11:21:03.692945 51338 transport_flipcall.go:234] recv [channel @0xc000554180] Rlerror{Error: 2} D0227 11:21:03.693093 51338 transport_flipcall.go:127] send [channel @0xc000554180] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0227 11:21:03.693546 51338 transport_flipcall.go:234] recv [channel @0xc000554180] Rlerror{Error: 95} D0227 11:21:03.716593 51338 task_block.go:223] [ 40] Interrupt queued D0227 11:21:03.746796 51338 task_block.go:223] [ 41] Interrupt queued D0227 11:21:03.802378 51338 task_block.go:223] [ 42] Interrupt queued ================== WARNING: DATA RACE Read at 0x00c0002fc470 by goroutine 353: gvisor.dev/gvisor/pkg/tcpip/transport/tcp.(*endpoint).windowCrossedACKThreshold() pkg/tcpip/transport/tcp/endpoint.go:1312 +0x9e gvisor.dev/gvisor/pkg/tcpip/transport/tcp.(*endpoint).SetSockOptInt() pkg/tcpip/transport/tcp/endpoint.go:1394 +0x408 gvisor.dev/gvisor/pkg/sentry/socket/netstack.setSockOptSocket() pkg/sentry/socket/netstack/netstack.go:1549 +0x84c gvisor.dev/gvisor/pkg/sentry/socket/netstack.SetSockOpt() pkg/sentry/socket/netstack/netstack.go:1509 +0x1ad gvisor.dev/gvisor/pkg/sentry/socket/netstack.(*SocketOperations).SetSockOpt() pkg/sentry/socket/netstack/netstack.go:1501 +0x69d gvisor.dev/gvisor/pkg/sentry/syscalls/linux.SetSockOpt() pkg/sentry/syscalls/linux/sys_socket.go:541 +0x303 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).executeSyscall() pkg/sentry/kernel/task_syscall.go:170 +0x455 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscallInvoke() pkg/sentry/kernel/task_syscall.go:291 +0xb4 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscallEnter() pkg/sentry/kernel/task_syscall.go:252 +0x109 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscall() pkg/sentry/kernel/task_syscall.go:227 +0x1b3 gvisor.dev/gvisor/pkg/sentry/kernel.(*runApp).execute() pkg/sentry/kernel/task_run.go:232 +0x1aaf gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).run() pkg/sentry/kernel/task_run.go:92 +0x308 Previous write at 0x00c0002fc470 by goroutine 340: gvisor.dev/gvisor/pkg/tcpip/transport/tcp.(*handshake).execute() pkg/tcpip/transport/tcp/connect.go:533 +0x631 gvisor.dev/gvisor/pkg/tcpip/transport/tcp.(*endpoint).protocolMainLoop() pkg/tcpip/transport/tcp/connect.go:1280 +0xfc6 Goroutine 353 (running) created at: gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).Start() pkg/sentry/kernel/task_start.go:315 +0x19d gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).Clone() pkg/sentry/kernel/task_clone.go:361 +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 DIAGNOSIS: I0227 11:21:03.873377 63033 main.go:296] *************************** I0227 11:21:03.873704 63033 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-2] I0227 11:21:03.874013 63033 main.go:298] Version release-20200219.0-47-gde0b2ebf8635 I0227 11:21:03.874126 63033 main.go:299] PID: 63033 I0227 11:21:03.874256 63033 main.go:300] UID: 0, GID: 0 I0227 11:21:03.874355 63033 main.go:301] Configuration: I0227 11:21:03.874451 63033 main.go:302] RootDir: /syzkaller/managers/ptrace-proxy-sandbox-race/workdir/gvisor_root I0227 11:21:03.874568 63033 main.go:303] Platform: ptrace I0227 11:21:03.874681 63033 main.go:304] FileAccess: shared, overlay: false I0227 11:21:03.874847 63033 main.go:305] Network: sandbox, logging: false I0227 11:21:03.875053 63033 main.go:306] Strace: false, max size: 1024, syscalls: [] I0227 11:21:03.875236 63033 main.go:307] *************************** W0227 11:21:03.875472 63033 main.go:312] Block the TERM signal. This is only safe in tests! D0227 11:21:03.875849 63033 container.go:158] Load container "/syzkaller/managers/ptrace-proxy-sandbox-race/workdir/gvisor_root" "ci-gvisor-ptrace-proxy-sandbox-race-2" D0227 11:21:03.884704 63033 container.go:591] Signal container "ci-gvisor-ptrace-proxy-sandbox-race-2": signal 0 D0227 11:21:03.885044 63033 sandbox.go:788] Signal sandbox "ci-gvisor-ptrace-proxy-sandbox-race-2" D0227 11:21:03.885225 63033 sandbox.go:318] Connecting to sandbox "ci-gvisor-ptrace-proxy-sandbox-race-2" D0227 11:21:03.885716 63033 urpc.go:534] urpc: successfully marshalled 117 bytes. W0227 11:21:03.931259 63033 error.go:48] FATAL ERROR: container sandbox is not running container sandbox is not running W0227 11:21:03.931631 63033 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-2"]: exit status 128 I0227 11:21:03.873377 63033 main.go:296] *************************** I0227 11:21:03.873704 63033 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-2] I0227 11:21:03.874013 63033 main.go:298] Version release-20200219.0-47-gde0b2ebf8635 I0227 11:21:03.874126 63033 main.go:299] PID: 63033 I0227 11:21:03.874256 63033 main.go:300] UID: 0, GID: 0 I0227 11:21:03.874355 63033 main.go:301] Configuration: I0227 11:21:03.874451 63033 main.go:302] RootDir: /syzkaller/managers/ptrace-proxy-sandbox-race/workdir/gvisor_root I0227 11:21:03.874568 63033 main.go:303] Platform: ptrace I0227 11:21:03.874681 63033 main.go:304] FileAccess: shared, overlay: false I0227 11:21:03.874847 63033 main.go:305] Network: sandbox, logging: false I0227 11:21:03.875053 63033 main.go:306] Strace: false, max size: 1024, syscalls: [] I0227 11:21:03.875236 63033 main.go:307] *************************** W0227 11:21:03.875472 63033 main.go:312] Block the TERM signal. This is only safe in tests! D0227 11:21:03.875849 63033 container.go:158] Load container "/syzkaller/managers/ptrace-proxy-sandbox-race/workdir/gvisor_root" "ci-gvisor-ptrace-proxy-sandbox-race-2" D0227 11:21:03.884704 63033 container.go:591] Signal container "ci-gvisor-ptrace-proxy-sandbox-race-2": signal 0 D0227 11:21:03.885044 63033 sandbox.go:788] Signal sandbox "ci-gvisor-ptrace-proxy-sandbox-race-2" D0227 11:21:03.885225 63033 sandbox.go:318] Connecting to sandbox "ci-gvisor-ptrace-proxy-sandbox-race-2" D0227 11:21:03.885716 63033 urpc.go:534] urpc: successfully marshalled 117 bytes. W0227 11:21:03.931259 63033 error.go:48] FATAL ERROR: container sandbox is not running container sandbox is not running W0227 11:21:03.931631 63033 main.go:329] Failure to execute command, err: 1 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 340 (running) created at: gvisor.dev/gvisor/pkg/tcpip/transport/tcp.(*endpoint).connect() pkg/tcpip/transport/tcp/endpoint.go:2044 +0xf2d gvisor.dev/gvisor/pkg/tcpip/transport/tcp.(*endpoint).Connect() pkg/tcpip/transport/tcp/endpoint.go:1873 +0x8c gvisor.dev/gvisor/pkg/sentry/socket/netstack.(*SocketOperations).Connect() pkg/sentry/socket/netstack/netstack.go:698 +0x4c5 gvisor.dev/gvisor/pkg/sentry/syscalls/linux.Connect() pkg/sentry/syscalls/linux/sys_socket.go:280 +0x251 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).executeSyscall() pkg/sentry/kernel/task_syscall.go:170 +0x455 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscallInvoke() pkg/sentry/kernel/task_syscall.go:291 +0xb4 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscallEnter() pkg/sentry/kernel/task_syscall.go:252 +0x109 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscall() pkg/sentry/kernel/task_syscall.go:227 +0x1b3 gvisor.dev/gvisor/pkg/sentry/kernel.(*runApp).execute() pkg/sentry/kernel/task_run.go:232 +0x1aaf gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).run() pkg/sentry/kernel/task_run.go:92 +0x308 ================== W0227 11:21:03.910769 61382 error.go:48] FATAL ERROR: waiting on pid 20: waiting on PID 20 in sandbox "ci-gvisor-ptrace-proxy-sandbox-race-2": urpc method "containerManager.WaitPID" failed: EOF waiting on pid 20: waiting on PID 20 in sandbox "ci-gvisor-ptrace-proxy-sandbox-race-2": urpc method "containerManager.WaitPID" failed: EOF W0227 11:21:03.911100 61382 main.go:329] Failure to execute command, err: 1 W0227 11:21:03.924492 51328 sandbox.go:724] Wait RPC to container "ci-gvisor-ptrace-proxy-sandbox-race-2" failed: urpc method "containerManager.Wait" failed: EOF. Will try waiting on the sandbox process instead. D0227 11:21:03.925129 51328 container.go:713] Destroy container "ci-gvisor-ptrace-proxy-sandbox-race-2" D0227 11:21:03.925748 51328 container.go:800] Destroying container "ci-gvisor-ptrace-proxy-sandbox-race-2" D0227 11:21:03.925850 51328 sandbox.go:1040] Destroying root container "ci-gvisor-ptrace-proxy-sandbox-race-2" by destroying sandbox D0227 11:21:03.925942 51328 sandbox.go:770] Destroy sandbox "ci-gvisor-ptrace-proxy-sandbox-race-2" D0227 11:21:03.926034 51328 container.go:814] Killing gofer for container "ci-gvisor-ptrace-proxy-sandbox-race-2", PID: 51337 I0227 11:21:04.027439 51328 main.go:320] Exiting with status: 16896