D0408 04:59:32.130002 1 parameters.go:238] Clock(Monotonic): error: -176 ns, adjusted frequency from 2299808446 Hz to 2299807170 Hz D0408 04:59:32.130149 1 parameters.go:238] Clock(Realtime): error: -10 ns, adjusted frequency from 2299807877 Hz to 2299807553 Hz D0408 04:59:32.130167 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5228048750988397 monotonicBaseRef:5000182596 monotonicFrequency:2299807170 realtimeReady:1 realtimeBaseCycles:5228048751344961 realtimeBaseRef:1586321972130146611 realtimeFrequency:2299807553} D0408 04:59:33.130057 1 parameters.go:238] Clock(Monotonic): error: 181 ns, adjusted frequency from 2299807170 Hz to 2299808021 Hz D0408 04:59:33.130184 1 parameters.go:238] Clock(Realtime): error: -35 ns, adjusted frequency from 2299807553 Hz to 2299807490 Hz D0408 04:59:33.130204 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5228051050924462 monotonicBaseRef:6000238642 monotonicFrequency:2299808021 realtimeReady:1 realtimeBaseCycles:5228051051234324 realtimeBaseRef:1586321973130182183 realtimeFrequency:2299807490} D0408 04:59:34.130012 1 parameters.go:238] Clock(Monotonic): error: 240 ns, adjusted frequency from 2299808021 Hz to 2299808049 Hz D0408 04:59:34.130120 1 parameters.go:238] Clock(Realtime): error: 184 ns, adjusted frequency from 2299807490 Hz to 2299808036 Hz D0408 04:59:34.130138 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5228053350595654 monotonicBaseRef:7000179146 monotonicFrequency:2299808049 realtimeReady:1 realtimeBaseCycles:5228053350895162 realtimeBaseRef:1586321974130118415 realtimeFrequency:2299808036} D0408 04:59:35.129953 1 parameters.go:238] Clock(Monotonic): error: 38 ns, adjusted frequency from 2299808049 Hz to 2299807604 Hz D0408 04:59:35.130052 1 sampler.go:168] Time: Adjusting syscall overhead up to 8000 D0408 04:59:35.130073 1 parameters.go:238] Clock(Realtime): error: 211 ns, adjusted frequency from 2299808036 Hz to 2299808105 Hz D0408 04:59:35.130093 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5228055650278407 monotonicBaseRef:8000124664 monotonicFrequency:2299807604 realtimeReady:1 realtimeBaseCycles:5228055650595930 realtimeBaseRef:1586321975130071772 realtimeFrequency:2299808105} D0408 04:59:36.130046 1 parameters.go:238] Clock(Monotonic): error: 92 ns, adjusted frequency from 2299807604 Hz to 2299807702 Hz D0408 04:59:36.130161 1 parameters.go:238] Clock(Realtime): error: -72 ns, adjusted frequency from 2299808105 Hz to 2299807386 Hz D0408 04:59:36.130179 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5228057950320565 monotonicBaseRef:9000226652 monotonicFrequency:2299807702 realtimeReady:1 realtimeBaseCycles:5228057950603731 realtimeBaseRef:1586321976130158603 realtimeFrequency:2299807386} D0408 04:59:37.130012 1 parameters.go:238] Clock(Monotonic): error: -255 ns, adjusted frequency from 2299807702 Hz to 2299807054 Hz D0408 04:59:37.130116 1 parameters.go:238] Clock(Realtime): error: 327 ns, adjusted frequency from 2299807386 Hz to 2299808382 Hz D0408 04:59:37.130138 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5228060250050666 monotonicBaseRef:10000192909 monotonicFrequency:2299807054 realtimeReady:1 realtimeBaseCycles:5228060250308898 realtimeBaseRef:1586321977130114156 realtimeFrequency:2299808382} D0408 04:59:38.130006 1 parameters.go:238] Clock(Monotonic): error: 340 ns, adjusted frequency from 2299807054 Hz to 2299808322 Hz D0408 04:59:38.130117 1 parameters.go:238] Clock(Realtime): error: -116 ns, adjusted frequency from 2299808382 Hz to 2299807340 Hz D0408 04:59:38.130137 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5228062549849364 monotonicBaseRef:11000189275 monotonicFrequency:2299808322 realtimeReady:1 realtimeBaseCycles:5228062550117950 realtimeBaseRef:1586321978130114447 realtimeFrequency:2299807340} D0408 04:59:39.130035 1 parameters.go:238] Clock(Monotonic): error: -306 ns, adjusted frequency from 2299808322 Hz to 2299806848 Hz D0408 04:59:39.130112 1 parameters.go:238] Clock(Realtime): error: -380 ns, adjusted frequency from 2299807340 Hz to 2299806729 Hz D0408 04:59:39.130129 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5228064849726778 monotonicBaseRef:12000219317 monotonicFrequency:2299806848 realtimeReady:1 realtimeBaseCycles:5228064849916687 realtimeBaseRef:1586321979130110706 realtimeFrequency:2299806729} D0408 04:59:40.130010 1 parameters.go:238] Clock(Monotonic): error: -29 ns, adjusted frequency from 2299806848 Hz to 2299807558 Hz D0408 04:59:40.130110 1 parameters.go:238] Clock(Realtime): error: 420 ns, adjusted frequency from 2299806729 Hz to 2299808564 Hz D0408 04:59:40.130125 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5228067149469981 monotonicBaseRef:13000191642 monotonicFrequency:2299807558 realtimeReady:1 realtimeBaseCycles:5228067149717659 realtimeBaseRef:1586321980130108202 realtimeFrequency:2299808564} D0408 04:59:41.130110 1 parameters.go:238] Clock(Monotonic): error: 190 ns, adjusted frequency from 2299807558 Hz to 2299808102 Hz D0408 04:59:41.130259 1 parameters.go:238] Clock(Realtime): error: -102 ns, adjusted frequency from 2299808564 Hz to 2299807381 Hz D0408 04:59:41.130279 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5228069449447077 monotonicBaseRef:14000265360 monotonicFrequency:2299808102 realtimeReady:1 realtimeBaseCycles:5228069449868400 realtimeBaseRef:1586321981130256986 realtimeFrequency:2299807381} D0408 04:59:42.130013 1 parameters.go:238] Clock(Monotonic): error: -80 ns, adjusted frequency from 2299808102 Hz to 2299807374 Hz D0408 04:59:42.130092 1 parameters.go:238] Clock(Realtime): error: 122 ns, adjusted frequency from 2299807381 Hz to 2299807924 Hz D0408 04:59:42.130109 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5228071749097010 monotonicBaseRef:15000196585 monotonicFrequency:2299807374 realtimeReady:1 realtimeBaseCycles:5228071749291767 realtimeBaseRef:1586321982130090009 realtimeFrequency:2299807924} D0408 04:59:43.130030 1 parameters.go:238] Clock(Monotonic): error: 469 ns, adjusted frequency from 2299807374 Hz to 2299808719 Hz D0408 04:59:43.130139 1 parameters.go:238] Clock(Realtime): error: 114 ns, adjusted frequency from 2299807924 Hz to 2299807859 Hz D0408 04:59:43.130158 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5228074048907207 monotonicBaseRef:16000197812 monotonicFrequency:2299808719 realtimeReady:1 realtimeBaseCycles:5228074049206359 realtimeBaseRef:1586321983130136390 realtimeFrequency:2299807859} D0408 04:59:44.130061 1 parameters.go:238] Clock(Monotonic): error: -353 ns, adjusted frequency from 2299808719 Hz to 2299806934 Hz D0408 04:59:44.130173 1 parameters.go:238] Clock(Realtime): error: -197 ns, adjusted frequency from 2299807859 Hz to 2299807146 Hz D0408 04:59:44.130190 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5228076348810036 monotonicBaseRef:17000238732 monotonicFrequency:2299806934 realtimeReady:1 realtimeBaseCycles:5228076349092845 realtimeBaseRef:1586321984130170578 realtimeFrequency:2299807146} D0408 04:59:45.130042 1 parameters.go:238] Clock(Monotonic): error: -164 ns, adjusted frequency from 2299806934 Hz to 2299807395 Hz D0408 04:59:45.130140 1 parameters.go:238] Clock(Realtime): error: -6 ns, adjusted frequency from 2299807146 Hz to 2299807641 Hz D0408 04:59:45.130154 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5228078648583351 monotonicBaseRef:18000224113 monotonicFrequency:2299807395 realtimeReady:1 realtimeBaseCycles:5228078648824338 realtimeBaseRef:1586321985130137682 realtimeFrequency:2299807641} D0408 04:59:46.130042 1 parameters.go:238] Clock(Monotonic): error: 0 ns, adjusted frequency from 2299807395 Hz to 2299807669 Hz D0408 04:59:46.130213 1 parameters.go:238] Clock(Realtime): error: 91 ns, adjusted frequency from 2299807641 Hz to 2299807824 Hz D0408 04:59:46.130228 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5228080948383106 monotonicBaseRef:19000220790 monotonicFrequency:2299807669 realtimeReady:1 realtimeBaseCycles:5228080948799298 realtimeBaseRef:1586321986130210435 realtimeFrequency:2299807824} D0408 04:59:47.130099 1 parameters.go:238] Clock(Monotonic): error: 260 ns, adjusted frequency from 2299807669 Hz to 2299808226 Hz D0408 04:59:47.130213 1 parameters.go:238] Clock(Realtime): error: 362 ns, adjusted frequency from 2299807824 Hz to 2299808520 Hz D0408 04:59:47.130231 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5228083248318399 monotonicBaseRef:20000276283 monotonicFrequency:2299808226 realtimeReady:1 realtimeBaseCycles:5228083248607055 realtimeBaseRef:1586321987130210405 realtimeFrequency:2299808520} D0408 04:59:48.130084 1 parameters.go:238] Clock(Monotonic): error: 64 ns, adjusted frequency from 2299808226 Hz to 2299807828 Hz D0408 04:59:48.130192 1 parameters.go:238] Clock(Realtime): error: -365 ns, adjusted frequency from 2299808520 Hz to 2299806800 Hz D0408 04:59:48.130208 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5228085548104947 monotonicBaseRef:21000266856 monotonicFrequency:2299807828 realtimeReady:1 realtimeBaseCycles:5228085548366184 realtimeBaseRef:1586321988130188928 realtimeFrequency:2299806800} D0408 04:59:49.130018 1 parameters.go:238] Clock(Monotonic): error: -107 ns, adjusted frequency from 2299807828 Hz to 2299807278 Hz D0408 04:59:49.130114 1 parameters.go:238] Clock(Realtime): error: -137 ns, adjusted frequency from 2299806800 Hz to 2299807319 Hz D0408 04:59:49.130134 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5228087847757504 monotonicBaseRef:22000199341 monotonicFrequency:2299807278 realtimeReady:1 realtimeBaseCycles:5228087847997190 realtimeBaseRef:1586321989130112489 realtimeFrequency:2299807319} D0408 04:59:50.130014 1 parameters.go:238] Clock(Monotonic): error: 120 ns, adjusted frequency from 2299807278 Hz to 2299807787 Hz D0408 04:59:50.130123 1 parameters.go:238] Clock(Realtime): error: 180 ns, adjusted frequency from 2299807319 Hz to 2299808062 Hz D0408 04:59:50.130142 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5228090147555866 monotonicBaseRef:23000195464 monotonicFrequency:2299807787 realtimeReady:1 realtimeBaseCycles:5228090147825130 realtimeBaseRef:1586321990130121455 realtimeFrequency:2299808062} D0408 04:59:51.130029 1 parameters.go:238] Clock(Monotonic): error: -301 ns, adjusted frequency from 2299807787 Hz to 2299806897 Hz D0408 04:59:51.130121 1 parameters.go:238] Clock(Realtime): error: -126 ns, adjusted frequency from 2299808062 Hz to 2299807345 Hz D0408 04:59:51.130146 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5228092447403761 monotonicBaseRef:24000212903 monotonicFrequency:2299806897 realtimeReady:1 realtimeBaseCycles:5228092447627394 realtimeBaseRef:1586321991130118933 realtimeFrequency:2299807345} D0408 04:59:52.130011 1 parameters.go:238] Clock(Monotonic): error: 321 ns, adjusted frequency from 2299806897 Hz to 2299808353 Hz D0408 04:59:52.130097 1 parameters.go:238] Clock(Realtime): error: -279 ns, adjusted frequency from 2299807345 Hz to 2299806988 Hz D0408 04:59:52.130110 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5228094747165874 monotonicBaseRef:25000193430 monotonicFrequency:2299808353 realtimeReady:1 realtimeBaseCycles:5228094747379871 realtimeBaseRef:1586321992130095075 realtimeFrequency:2299806988} D0408 04:59:53.129964 1 parameters.go:238] Clock(Monotonic): error: 183 ns, adjusted frequency from 2299808353 Hz to 2299807985 Hz D0408 04:59:53.130064 1 parameters.go:238] Clock(Realtime): error: 147 ns, adjusted frequency from 2299806988 Hz to 2299807969 Hz D0408 04:59:53.130080 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5228097046783057 monotonicBaseRef:26000110305 monotonicFrequency:2299807985 realtimeReady:1 realtimeBaseCycles:5228097047112521 realtimeBaseRef:1586321993130062751 realtimeFrequency:2299807969} D0408 04:59:54.129945 1 parameters.go:238] Clock(Monotonic): error: -437 ns, adjusted frequency from 2299807985 Hz to 2299806641 Hz D0408 04:59:54.130043 1 parameters.go:238] Clock(Realtime): error: 398 ns, adjusted frequency from 2299807969 Hz to 2299808595 Hz D0408 04:59:54.130063 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5228099346627576 monotonicBaseRef:27000126190 monotonicFrequency:2299806641 realtimeReady:1 realtimeBaseCycles:5228099346871011 realtimeBaseRef:1586321994130041236 realtimeFrequency:2299808595} D0408 04:59:55.130013 1 parameters.go:238] Clock(Monotonic): error: 270 ns, adjusted frequency from 2299806641 Hz to 2299808154 Hz D0408 04:59:55.130105 1 parameters.go:238] Clock(Realtime): error: -318 ns, adjusted frequency from 2299808595 Hz to 2299806935 Hz D0408 04:59:55.130170 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5228101646592047 monotonicBaseRef:28000194817 monotonicFrequency:2299808154 realtimeReady:1 realtimeBaseCycles:5228101646822740 realtimeBaseRef:1586321995130103473 realtimeFrequency:2299806935} D0408 04:59:56.130040 1 parameters.go:238] Clock(Monotonic): error: 83 ns, adjusted frequency from 2299808154 Hz to 2299807850 Hz D0408 04:59:56.130131 1 parameters.go:238] Clock(Realtime): error: 118 ns, adjusted frequency from 2299806935 Hz to 2299807866 Hz D0408 04:59:56.130149 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5228103946458152 monotonicBaseRef:29000220015 monotonicFrequency:2299807850 realtimeReady:1 realtimeBaseCycles:5228103946687187 realtimeBaseRef:1586321996130128480 realtimeFrequency:2299807866} D0408 04:59:57.130021 1 parameters.go:238] Clock(Monotonic): error: -71 ns, adjusted frequency from 2299807850 Hz to 2299807439 Hz D0408 04:59:57.130146 1 parameters.go:238] Clock(Realtime): error: 403 ns, adjusted frequency from 2299807866 Hz to 2299808621 Hz D0408 04:59:57.130168 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5228106246224722 monotonicBaseRef:30000202065 monotonicFrequency:2299807439 realtimeReady:1 realtimeBaseCycles:5228106246526567 realtimeBaseRef:1586321997130142182 realtimeFrequency:2299808621} D0408 04:59:58.130011 1 parameters.go:238] Clock(Monotonic): error: -88 ns, adjusted frequency from 2299807439 Hz to 2299807590 Hz D0408 04:59:58.130110 1 parameters.go:238] Clock(Realtime): error: -80 ns, adjusted frequency from 2299808621 Hz to 2299807528 Hz D0408 04:59:58.130149 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5228108546009593 monotonicBaseRef:31000192252 monotonicFrequency:2299807590 realtimeReady:1 realtimeBaseCycles:5228108546254412 realtimeBaseRef:1586321998130107059 realtimeFrequency:2299807528} D0408 04:59:59.130009 1 parameters.go:238] Clock(Monotonic): error: -196 ns, adjusted frequency from 2299807590 Hz to 2299807326 Hz D0408 04:59:59.130082 1 parameters.go:238] Clock(Realtime): error: -419 ns, adjusted frequency from 2299807528 Hz to 2299806682 Hz D0408 04:59:59.130100 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5228110845787864 monotonicBaseRef:32000179503 monotonicFrequency:2299807326 realtimeReady:1 realtimeBaseCycles:5228110846000463 realtimeBaseRef:1586321999130080327 realtimeFrequency:2299806682} D0408 05:00:00.129981 1 parameters.go:238] Clock(Monotonic): error: 259 ns, adjusted frequency from 2299807326 Hz to 2299808295 Hz D0408 05:00:00.130077 1 parameters.go:238] Clock(Realtime): error: 274 ns, adjusted frequency from 2299806682 Hz to 2299808297 Hz D0408 05:00:00.130099 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5228113145556566 monotonicBaseRef:33000162708 monotonicFrequency:2299808295 realtimeReady:1 realtimeBaseCycles:5228113145794895 realtimeBaseRef:1586322000130075000 realtimeFrequency:2299808297} D0408 05:00:01.130005 1 parameters.go:238] Clock(Monotonic): error: -529 ns, adjusted frequency from 2299808295 Hz to 2299806550 Hz D0408 05:00:01.130079 1 parameters.go:238] Clock(Realtime): error: -516 ns, adjusted frequency from 2299808297 Hz to 2299806492 Hz D0408 05:00:01.130103 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5228115445426107 monotonicBaseRef:34000189338 monotonicFrequency:2299806550 realtimeReady:1 realtimeBaseCycles:5228115445608162 realtimeBaseRef:1586322001130077161 realtimeFrequency:2299806492} D0408 05:00:02.130026 1 parameters.go:238] Clock(Monotonic): error: 225 ns, adjusted frequency from 2299806550 Hz to 2299808249 Hz D0408 05:00:02.130121 1 parameters.go:238] Clock(Realtime): error: 389 ns, adjusted frequency from 2299806492 Hz to 2299808558 Hz D0408 05:00:02.130179 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5228117745273791 monotonicBaseRef:35000207223 monotonicFrequency:2299808249 realtimeReady:1 realtimeBaseCycles:5228117745510691 realtimeBaseRef:1586322002130118919 realtimeFrequency:2299808558} D0408 05:00:02.215833 1 urpc.go:577] urpc: unmarshal success. D0408 05:00:02.216582 1 controller.go:504] containerManager.Signal &{CID:ci-gvisor-kvm-direct-sandbox-2 Signo:0 PID:0 Mode:Process} D0408 05:00:02.216707 1 urpc.go:534] urpc: successfully marshalled 37 bytes. D0408 05:00:03.130035 1 parameters.go:238] Clock(Monotonic): error: 379 ns, adjusted frequency from 2299808249 Hz to 2299808494 Hz D0408 05:00:03.130139 1 parameters.go:238] Clock(Realtime): error: -299 ns, adjusted frequency from 2299808558 Hz to 2299806989 Hz D0408 05:00:03.130158 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5228120045056670 monotonicBaseRef:36000196191 monotonicFrequency:2299808494 realtimeReady:1 realtimeBaseCycles:5228120045357906 realtimeBaseRef:1586322003130135727 realtimeFrequency:2299806989} D0408 05:00:04.130015 1 parameters.go:238] Clock(Monotonic): error: -352 ns, adjusted frequency from 2299808494 Hz to 2299806974 Hz D0408 05:00:04.130118 1 parameters.go:238] Clock(Realtime): error: 344 ns, adjusted frequency from 2299806989 Hz to 2299808423 Hz D0408 05:00:04.130137 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5228122344868542 monotonicBaseRef:37000197659 monotonicFrequency:2299806974 realtimeReady:1 realtimeBaseCycles:5228122345117995 realtimeBaseRef:1586322004130115333 realtimeFrequency:2299808423} D0408 05:00:05.130057 1 parameters.go:238] Clock(Monotonic): error: -21 ns, adjusted frequency from 2299806974 Hz to 2299807750 Hz D0408 05:00:05.130204 1 parameters.go:238] Clock(Realtime): error: -82 ns, adjusted frequency from 2299808423 Hz to 2299807459 Hz D0408 05:00:05.130222 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5228124644771715 monotonicBaseRef:38000239488 monotonicFrequency:2299807750 realtimeReady:1 realtimeBaseCycles:5228124645124317 realtimeBaseRef:1586322005130201383 realtimeFrequency:2299807459} D0408 05:00:06.130262 1 parameters.go:238] Clock(Monotonic): error: -213 ns, adjusted frequency from 2299807750 Hz to 2299807308 Hz D0408 05:00:06.130345 1 parameters.go:238] Clock(Realtime): error: 43 ns, adjusted frequency from 2299807459 Hz to 2299807703 Hz D0408 05:00:06.130365 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5228126945051974 monotonicBaseRef:39000444943 monotonicFrequency:2299807308 realtimeReady:1 realtimeBaseCycles:5228126945256820 realtimeBaseRef:1586322006130342718 realtimeFrequency:2299807703} D0408 05:00:07.130006 1 parameters.go:238] Clock(Monotonic): error: 8 ns, adjusted frequency from 2299807308 Hz to 2299807662 Hz D0408 05:00:07.130131 1 parameters.go:238] Clock(Realtime): error: -89 ns, adjusted frequency from 2299807703 Hz to 2299807400 Hz D0408 05:00:07.130153 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5228129244270327 monotonicBaseRef:40000188854 monotonicFrequency:2299807662 realtimeReady:1 realtimeBaseCycles:5228129244570990 realtimeBaseRef:1586322007130128120 realtimeFrequency:2299807400} D0408 05:00:08.130133 1 parameters.go:238] Clock(Monotonic): error: 321 ns, adjusted frequency from 2299807662 Hz to 2299808317 Hz D0408 05:00:08.130233 1 parameters.go:238] Clock(Realtime): error: 167 ns, adjusted frequency from 2299807400 Hz to 2299808031 Hz D0408 05:00:08.130251 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5228131544370261 monotonicBaseRef:41000315939 monotonicFrequency:2299808317 realtimeReady:1 realtimeBaseCycles:5228131544614275 realtimeBaseRef:1586322008130230687 realtimeFrequency:2299808031} D0408 05:00:09.130138 1 parameters.go:238] Clock(Monotonic): error: -369 ns, adjusted frequency from 2299808317 Hz to 2299806900 Hz D0408 05:00:09.130233 1 parameters.go:238] Clock(Realtime): error: -218 ns, adjusted frequency from 2299808031 Hz to 2299807125 Hz D0408 05:00:09.130261 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5228133844185285 monotonicBaseRef:42000318855 monotonicFrequency:2299806900 realtimeReady:1 realtimeBaseCycles:5228133844424214 realtimeBaseRef:1586322009130231516 realtimeFrequency:2299807125} D0408 05:00:10.130633 1 parameters.go:238] Clock(Monotonic): error: 364 ns, adjusted frequency from 2299806900 Hz to 2299808394 Hz D0408 05:00:10.130721 1 parameters.go:238] Clock(Realtime): error: 64 ns, adjusted frequency from 2299807125 Hz to 2299807781 Hz D0408 05:00:10.130739 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5228136145134714 monotonicBaseRef:43000815648 monotonicFrequency:2299808394 realtimeReady:1 realtimeBaseCycles:5228136145352908 realtimeBaseRef:1586322010130719195 realtimeFrequency:2299807781} D0408 05:00:11.129981 1 parameters.go:238] Clock(Monotonic): error: -95 ns, adjusted frequency from 2299808394 Hz to 2299807357 Hz D0408 05:00:11.130062 1 parameters.go:238] Clock(Realtime): error: -268 ns, adjusted frequency from 2299807781 Hz to 2299807035 Hz D0408 05:00:11.130074 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5228138443441693 monotonicBaseRef:44000162804 monotonicFrequency:2299807357 realtimeReady:1 realtimeBaseCycles:5228138443645700 realtimeBaseRef:1586322011130060449 realtimeFrequency:2299807035} D0408 05:00:12.129981 1 parameters.go:238] Clock(Monotonic): error: -249 ns, adjusted frequency from 2299807357 Hz to 2299807107 Hz D0408 05:00:12.130073 1 parameters.go:238] Clock(Realtime): error: 248 ns, adjusted frequency from 2299807035 Hz to 2299808156 Hz D0408 05:00:12.130095 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5228140743247486 monotonicBaseRef:45000162123 monotonicFrequency:2299807107 realtimeReady:1 realtimeBaseCycles:5228140743477027 realtimeBaseRef:1586322012130071011 realtimeFrequency:2299808156} I0408 05:00:12.174899 1 watchdog.go:277] Watchdog starting loop, tasks: 21, discount: 0s D0408 05:00:13.129995 1 parameters.go:238] Clock(Monotonic): error: 1 ns, adjusted frequency from 2299807107 Hz to 2299807639 Hz D0408 05:00:13.130089 1 parameters.go:238] Clock(Realtime): error: -86 ns, adjusted frequency from 2299808156 Hz to 2299807470 Hz D0408 05:00:13.130107 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5228143043082962 monotonicBaseRef:46000174458 monotonicFrequency:2299807639 realtimeReady:1 realtimeBaseCycles:5228143043322423 realtimeBaseRef:1586322013130087203 realtimeFrequency:2299807470} D0408 05:00:14.130081 1 parameters.go:238] Clock(Monotonic): error: 344 ns, adjusted frequency from 2299807639 Hz to 2299808301 Hz D0408 05:00:14.130200 1 parameters.go:238] Clock(Realtime): error: 120 ns, adjusted frequency from 2299807470 Hz to 2299807962 Hz D0408 05:00:14.130214 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5228145343091998 monotonicBaseRef:47000262029 monotonicFrequency:2299808301 realtimeReady:1 realtimeBaseCycles:5228145343385419 realtimeBaseRef:1586322014130198310 realtimeFrequency:2299807962} D0408 05:00:15.130023 1 parameters.go:238] Clock(Monotonic): error: 86 ns, adjusted frequency from 2299808301 Hz to 2299807737 Hz D0408 05:00:15.130139 1 parameters.go:238] Clock(Realtime): error: -72 ns, adjusted frequency from 2299807962 Hz to 2299807496 Hz D0408 05:00:15.130155 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5228147642766182 monotonicBaseRef:48000203712 monotonicFrequency:2299807737 realtimeReady:1 realtimeBaseCycles:5228147643052929 realtimeBaseRef:1586322015130137238 realtimeFrequency:2299807496} D0408 05:00:16.130049 1 parameters.go:238] Clock(Monotonic): error: -341 ns, adjusted frequency from 2299807737 Hz to 2299806850 Hz D0408 05:00:16.130155 1 parameters.go:238] Clock(Realtime): error: 11 ns, adjusted frequency from 2299807496 Hz to 2299807670 Hz D0408 05:00:16.130197 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5228149942626333 monotonicBaseRef:49000226502 monotonicFrequency:2299806850 realtimeReady:1 realtimeBaseCycles:5228149942897260 realtimeBaseRef:1586322016130153254 realtimeFrequency:2299807670} D0408 05:00:17.130048 1 parameters.go:238] Clock(Monotonic): error: 194 ns, adjusted frequency from 2299806850 Hz to 2299808192 Hz D0408 05:00:17.130132 1 parameters.go:238] Clock(Realtime): error: 3 ns, adjusted frequency from 2299807670 Hz to 2299807651 Hz D0408 05:00:17.130150 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5228152242439863 monotonicBaseRef:50000229406 monotonicFrequency:2299808192 realtimeReady:1 realtimeBaseCycles:5228152242652229 realtimeBaseRef:1586322017130130338 realtimeFrequency:2299807651} D0408 05:00:18.130013 1 parameters.go:238] Clock(Monotonic): error: 269 ns, adjusted frequency from 2299808192 Hz to 2299808239 Hz D0408 05:00:18.130112 1 parameters.go:238] Clock(Realtime): error: 202 ns, adjusted frequency from 2299807651 Hz to 2299808140 Hz D0408 05:00:18.130132 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5228154542167274 monotonicBaseRef:51000194280 monotonicFrequency:2299808239 realtimeReady:1 realtimeBaseCycles:5228154542413521 realtimeBaseRef:1586322018130110180 realtimeFrequency:2299808140} D0408 05:00:19.129981 1 parameters.go:238] Clock(Monotonic): error: -275 ns, adjusted frequency from 2299808239 Hz to 2299806997 Hz D0408 05:00:19.130099 1 parameters.go:238] Clock(Realtime): error: -122 ns, adjusted frequency from 2299808140 Hz to 2299807363 Hz D0408 05:00:19.130116 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5228156841906669 monotonicBaseRef:52000164345 monotonicFrequency:2299806997 realtimeReady:1 realtimeBaseCycles:5228156842191999 realtimeBaseRef:1586322019130097282 realtimeFrequency:2299807363} D0408 05:00:20.129994 1 parameters.go:238] Clock(Monotonic): error: 163 ns, adjusted frequency from 2299806997 Hz to 2299807974 Hz D0408 05:00:20.130099 1 parameters.go:238] Clock(Realtime): error: 272 ns, adjusted frequency from 2299807363 Hz to 2299808291 Hz D0408 05:00:20.130138 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5228159141735476 monotonicBaseRef:53000173828 monotonicFrequency:2299807974 realtimeReady:1 realtimeBaseCycles:5228159141999385 realtimeBaseRef:1586322020130097292 realtimeFrequency:2299808291} D0408 05:00:21.130030 1 parameters.go:238] Clock(Monotonic): error: -209 ns, adjusted frequency from 2299807974 Hz to 2299807173 Hz D0408 05:00:21.130116 1 parameters.go:238] Clock(Realtime): error: -269 ns, adjusted frequency from 2299808291 Hz to 2299807002 Hz D0408 05:00:21.130134 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5228161441597312 monotonicBaseRef:54000197248 monotonicFrequency:2299807173 realtimeReady:1 realtimeBaseCycles:5228161441845211 realtimeBaseRef:1586322021130113612 realtimeFrequency:2299807002} D0408 05:00:22.130142 1 parameters.go:238] Clock(Monotonic): error: 263 ns, adjusted frequency from 2299807173 Hz to 2299808135 Hz D0408 05:00:22.130221 1 parameters.go:238] Clock(Realtime): error: 69 ns, adjusted frequency from 2299807002 Hz to 2299807816 Hz D0408 05:00:22.130243 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5228163741696703 monotonicBaseRef:55000324309 monotonicFrequency:2299808135 realtimeReady:1 realtimeBaseCycles:5228163741895588 realtimeBaseRef:1586322022130219436 realtimeFrequency:2299807816} D0408 05:00:22.392458 1 urpc.go:577] urpc: unmarshal success. D0408 05:00:22.392545 1 controller.go:504] containerManager.Signal &{CID:ci-gvisor-kvm-direct-sandbox-2 Signo:0 PID:0 Mode:Process} D0408 05:00:22.392624 1 urpc.go:534] urpc: successfully marshalled 37 bytes. D0408 05:00:23.129889 1 parameters.go:238] Clock(Monotonic): error: -286 ns, adjusted frequency from 2299808135 Hz to 2299806983 Hz D0408 05:00:23.129973 1 parameters.go:238] Clock(Realtime): error: 306 ns, adjusted frequency from 2299807816 Hz to 2299808318 Hz D0408 05:00:23.129992 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5228166040925589 monotonicBaseRef:56000072440 monotonicFrequency:2299806983 realtimeReady:1 realtimeBaseCycles:5228166041133080 realtimeBaseRef:1586322023129971448 realtimeFrequency:2299808318} D0408 05:00:24.130040 1 parameters.go:238] Clock(Monotonic): error: 7 ns, adjusted frequency from 2299806983 Hz to 2299807767 Hz D0408 05:00:24.130167 1 parameters.go:238] Clock(Realtime): error: -471 ns, adjusted frequency from 2299808318 Hz to 2299806616 Hz D0408 05:00:24.130206 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5228168341066098 monotonicBaseRef:57000217463 monotonicFrequency:2299807767 realtimeReady:1 realtimeBaseCycles:5228168341383279 realtimeBaseRef:1586322024130163586 realtimeFrequency:2299806616} D0408 05:00:25.130061 1 parameters.go:238] Clock(Monotonic): error: 6 ns, adjusted frequency from 2299807767 Hz to 2299807659 Hz D0408 05:00:25.130167 1 parameters.go:238] Clock(Realtime): error: 107 ns, adjusted frequency from 2299806616 Hz to 2299807894 Hz D0408 05:00:25.130190 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5228170640928069 monotonicBaseRef:58000241031 monotonicFrequency:2299807659 realtimeReady:1 realtimeBaseCycles:5228170641192606 realtimeBaseRef:1586322025130164764 realtimeFrequency:2299807894} D0408 05:00:26.130014 1 parameters.go:238] Clock(Monotonic): error: -142 ns, adjusted frequency from 2299807659 Hz to 2299807298 Hz D0408 05:00:26.130112 1 parameters.go:238] Clock(Realtime): error: -67 ns, adjusted frequency from 2299807894 Hz to 2299807512 Hz D0408 05:00:26.130145 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5228172940632461 monotonicBaseRef:59000196128 monotonicFrequency:2299807298 realtimeReady:1 realtimeBaseCycles:5228172940875382 realtimeBaseRef:1586322026130110360 realtimeFrequency:2299807512} D0408 05:00:27.130021 1 parameters.go:238] Clock(Monotonic): error: 331 ns, adjusted frequency from 2299807298 Hz to 2299808337 Hz D0408 05:00:27.130161 1 parameters.go:238] Clock(Realtime): error: 84 ns, adjusted frequency from 2299807512 Hz to 2299807806 Hz D0408 05:00:27.130187 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5228175240456118 monotonicBaseRef:60000203241 monotonicFrequency:2299808337 realtimeReady:1 realtimeBaseCycles:5228175240792507 realtimeBaseRef:1586322027130158021 realtimeFrequency:2299807806} D0408 05:00:28.130019 1 parameters.go:238] Clock(Monotonic): error: 68 ns, adjusted frequency from 2299808337 Hz to 2299807847 Hz D0408 05:00:28.130103 1 parameters.go:238] Clock(Realtime): error: -30 ns, adjusted frequency from 2299807806 Hz to 2299807595 Hz D0408 05:00:28.130121 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5228177540261964 monotonicBaseRef:61000202157 monotonicFrequency:2299807847 realtimeReady:1 realtimeBaseCycles:5228177540469050 realtimeBaseRef:1586322028130100945 realtimeFrequency:2299807595} D0408 05:00:29.130016 1 parameters.go:238] Clock(Monotonic): error: -275 ns, adjusted frequency from 2299807847 Hz to 2299807014 Hz D0408 05:00:29.130082 1 parameters.go:238] Clock(Realtime): error: -337 ns, adjusted frequency from 2299807595 Hz to 2299806833 Hz D0408 05:00:29.130094 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5228179840063475 monotonicBaseRef:62000199401 monotonicFrequency:2299807014 realtimeReady:1 realtimeBaseCycles:5228179840230057 realtimeBaseRef:1586322029130080687 realtimeFrequency:2299806833} D0408 05:00:30.130002 1 parameters.go:238] Clock(Monotonic): error: 18 ns, adjusted frequency from 2299807014 Hz to 2299807701 Hz D0408 05:00:30.130104 1 parameters.go:238] Clock(Realtime): error: 405 ns, adjusted frequency from 2299806833 Hz to 2299808623 Hz D0408 05:00:30.130122 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5228182139832080 monotonicBaseRef:63000182700 monotonicFrequency:2299807701 realtimeReady:1 realtimeBaseCycles:5228182140087972 realtimeBaseRef:1586322030130102898 realtimeFrequency:2299808623} D0408 05:00:31.130013 1 parameters.go:238] Clock(Monotonic): error: -89 ns, adjusted frequency from 2299807701 Hz to 2299807568 Hz D0408 05:00:31.130143 1 parameters.go:238] Clock(Realtime): error: -279 ns, adjusted frequency from 2299808623 Hz to 2299806974 Hz D0408 05:00:31.130156 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5228184439669509 monotonicBaseRef:64000195626 monotonicFrequency:2299807568 realtimeReady:1 realtimeBaseCycles:5228184439983658 realtimeBaseRef:1586322031130140754 realtimeFrequency:2299806974} D0408 05:00:32.130097 1 parameters.go:238] Clock(Monotonic): error: -10 ns, adjusted frequency from 2299807568 Hz to 2299807766 Hz D0408 05:00:32.130178 1 parameters.go:238] Clock(Realtime): error: 151 ns, adjusted frequency from 2299806974 Hz to 2299807999 Hz D0408 05:00:32.130199 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5228186739604868 monotonicBaseRef:65000251191 monotonicFrequency:2299807766 realtimeReady:1 realtimeBaseCycles:5228186739871800 realtimeBaseRef:1586322032130176047 realtimeFrequency:2299807999} D0408 05:00:33.130075 1 parameters.go:238] Clock(Monotonic): error: 185 ns, adjusted frequency from 2299807766 Hz to 2299807954 Hz D0408 05:00:33.130156 1 parameters.go:238] Clock(Realtime): error: -237 ns, adjusted frequency from 2299807999 Hz to 2299807045 Hz D0408 05:00:33.130169 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5228189039421088 monotonicBaseRef:66000254866 monotonicFrequency:2299807954 realtimeReady:1 realtimeBaseCycles:5228189039629954 realtimeBaseRef:1586322033130154373 realtimeFrequency:2299807045} D0408 05:00:34.129970 1 parameters.go:238] Clock(Monotonic): error: 360 ns, adjusted frequency from 2299807954 Hz to 2299808420 Hz D0408 05:00:34.130071 1 parameters.go:238] Clock(Realtime): error: 378 ns, adjusted frequency from 2299807045 Hz to 2299808536 Hz D0408 05:00:34.130092 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5228191338987830 monotonicBaseRef:67000149982 monotonicFrequency:2299808420 realtimeReady:1 realtimeBaseCycles:5228191339242430 realtimeBaseRef:1586322034130069770 realtimeFrequency:2299808536} D0408 05:00:35.130022 1 parameters.go:238] Clock(Monotonic): error: -478 ns, adjusted frequency from 2299808420 Hz to 2299806433 Hz D0408 05:00:35.130125 1 parameters.go:238] Clock(Realtime): error: -98 ns, adjusted frequency from 2299808536 Hz to 2299807419 Hz D0408 05:00:35.130144 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5228193638920710 monotonicBaseRef:68000204099 monotonicFrequency:2299806433 realtimeReady:1 realtimeBaseCycles:5228193639172024 realtimeBaseRef:1586322035130122408 realtimeFrequency:2299807419} D0408 05:00:36.129945 1 parameters.go:238] Clock(Monotonic): error: 146 ns, adjusted frequency from 2299806433 Hz to 2299807967 Hz D0408 05:00:36.130054 1 parameters.go:238] Clock(Realtime): error: -241 ns, adjusted frequency from 2299807419 Hz to 2299807092 Hz D0408 05:00:36.130066 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5228195938551602 monotonicBaseRef:69000127770 monotonicFrequency:2299807967 realtimeReady:1 realtimeBaseCycles:5228195938817515 realtimeBaseRef:1586322036130051998 realtimeFrequency:2299807092} D0408 05:00:37.130139 1 parameters.go:238] Clock(Monotonic): error: -62 ns, adjusted frequency from 2299807967 Hz to 2299807607 Hz D0408 05:00:37.130245 1 parameters.go:238] Clock(Realtime): error: 224 ns, adjusted frequency from 2299807092 Hz to 2299808167 Hz D0408 05:00:37.130262 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5228198238802518 monotonicBaseRef:70000320372 monotonicFrequency:2299807607 realtimeReady:1 realtimeBaseCycles:5228198239064664 realtimeBaseRef:1586322037130243343 realtimeFrequency:2299808167} D0408 05:00:38.129935 1 parameters.go:238] Clock(Monotonic): error: 313 ns, adjusted frequency from 2299807607 Hz to 2299808540 Hz D0408 05:00:38.130014 1 parameters.go:238] Clock(Realtime): error: 34 ns, adjusted frequency from 2299808167 Hz to 2299807760 Hz D0408 05:00:38.130047 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5228200538139222 monotonicBaseRef:71000115614 monotonicFrequency:2299808540 realtimeReady:1 realtimeBaseCycles:5228200538342177 realtimeBaseRef:1586322038130012604 realtimeFrequency:2299807760} D0408 05:00:39.130072 1 parameters.go:238] Clock(Monotonic): error: -349 ns, adjusted frequency from 2299808540 Hz to 2299806706 Hz D0408 05:00:39.130188 1 parameters.go:238] Clock(Realtime): error: 19 ns, adjusted frequency from 2299807760 Hz to 2299807691 Hz D0408 05:00:39.130208 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5228202838263020 monotonicBaseRef:72000252694 monotonicFrequency:2299806706 realtimeReady:1 realtimeBaseCycles:5228202838546920 realtimeBaseRef:1586322039130185219 realtimeFrequency:2299807691} D0408 05:00:40.130048 1 parameters.go:238] Clock(Monotonic): error: -47 ns, adjusted frequency from 2299806706 Hz to 2299807546 Hz D0408 05:00:40.130137 1 parameters.go:238] Clock(Realtime): error: -80 ns, adjusted frequency from 2299807691 Hz to 2299807494 Hz D0408 05:00:40.130157 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5228205138011693 monotonicBaseRef:73000227460 monotonicFrequency:2299807546 realtimeReady:1 realtimeBaseCycles:5228205138239388 realtimeBaseRef:1586322040130135117 realtimeFrequency:2299807494} D0408 05:00:41.130094 1 parameters.go:238] Clock(Monotonic): error: 273 ns, adjusted frequency from 2299807546 Hz to 2299808136 Hz D0408 05:00:41.130180 1 parameters.go:238] Clock(Realtime): error: 98 ns, adjusted frequency from 2299807494 Hz to 2299807894 Hz D0408 05:00:41.130198 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5228207437829072 monotonicBaseRef:74000231735 monotonicFrequency:2299808136 realtimeReady:1 realtimeBaseCycles:5228207438146048 realtimeBaseRef:1586322041130178236 realtimeFrequency:2299807894} D0408 05:00:42.130052 1 parameters.go:238] Clock(Monotonic): error: 164 ns, adjusted frequency from 2299808136 Hz to 2299808057 Hz D0408 05:00:42.130198 1 parameters.go:238] Clock(Realtime): error: 6 ns, adjusted frequency from 2299807894 Hz to 2299807655 Hz D0408 05:00:42.130217 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5228209737631378 monotonicBaseRef:75000229200 monotonicFrequency:2299808057 realtimeReady:1 realtimeBaseCycles:5228209737993766 realtimeBaseRef:1586322042130195552 realtimeFrequency:2299807655} D0408 05:00:43.129992 1 parameters.go:238] Clock(Monotonic): error: -207 ns, adjusted frequency from 2299808057 Hz to 2299807118 Hz D0408 05:00:43.130069 1 parameters.go:238] Clock(Realtime): error: -321 ns, adjusted frequency from 2299807655 Hz to 2299806887 Hz D0408 05:00:43.130083 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5228212037233201 monotonicBaseRef:76000139525 monotonicFrequency:2299807118 realtimeReady:1 realtimeBaseCycles:5228212037505724 realtimeBaseRef:1586322043130066977 realtimeFrequency:2299806887} D0408 05:00:44.130046 1 parameters.go:238] Clock(Monotonic): error: -198 ns, adjusted frequency from 2299807118 Hz to 2299807320 Hz D0408 05:00:44.130130 1 parameters.go:238] Clock(Realtime): error: 475 ns, adjusted frequency from 2299806887 Hz to 2299808751 Hz D0408 05:00:44.130155 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5228214337242502 monotonicBaseRef:77000227438 monotonicFrequency:2299807320 realtimeReady:1 realtimeBaseCycles:5228214337454942 realtimeBaseRef:1586322044130128865 realtimeFrequency:2299808751} D0408 05:00:45.130086 1 parameters.go:238] Clock(Monotonic): error: -64 ns, adjusted frequency from 2299807320 Hz to 2299807622 Hz D0408 05:00:45.130715 1 parameters.go:238] Clock(Realtime): error: 89 ns, adjusted frequency from 2299808751 Hz to 2299807824 Hz D0408 05:00:45.130735 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5228216637136775 monotonicBaseRef:78000265246 monotonicFrequency:2299807622 realtimeReady:1 realtimeBaseCycles:5228216638603884 realtimeBaseRef:1586322045130711605 realtimeFrequency:2299807824} D0408 05:00:46.130087 1 parameters.go:238] Clock(Monotonic): error: 215 ns, adjusted frequency from 2299807622 Hz to 2299808047 Hz D0408 05:00:46.130214 1 parameters.go:238] Clock(Realtime): error: 278 ns, adjusted frequency from 2299807824 Hz to 2299808312 Hz D0408 05:00:46.130233 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5228218936949550 monotonicBaseRef:79000267486 monotonicFrequency:2299808047 realtimeReady:1 realtimeBaseCycles:5228218937261358 realtimeBaseRef:1586322046130211411 realtimeFrequency:2299808312} D0408 05:00:47.130026 1 parameters.go:238] Clock(Monotonic): error: 242 ns, adjusted frequency from 2299808047 Hz to 2299808085 Hz D0408 05:00:47.130137 1 parameters.go:238] Clock(Realtime): error: -534 ns, adjusted frequency from 2299808312 Hz to 2299806398 Hz D0408 05:00:47.130155 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5228221236618109 monotonicBaseRef:80000206833 monotonicFrequency:2299808085 realtimeReady:1 realtimeBaseCycles:5228221236891738 realtimeBaseRef:1586322047130134042 realtimeFrequency:2299806398} D0408 05:00:48.130038 1 parameters.go:238] Clock(Monotonic): error: -253 ns, adjusted frequency from 2299808085 Hz to 2299807230 Hz D0408 05:00:48.130144 1 parameters.go:238] Clock(Realtime): error: -39 ns, adjusted frequency from 2299806398 Hz to 2299807557 Hz D0408 05:00:48.130162 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5228223536395276 monotonicBaseRef:81000193389 monotonicFrequency:2299807230 realtimeReady:1 realtimeBaseCycles:5228223536716140 realtimeBaseRef:1586322048130141870 realtimeFrequency:2299807557} D0408 05:00:49.130129 1 parameters.go:238] Clock(Monotonic): error: 389 ns, adjusted frequency from 2299807230 Hz to 2299808498 Hz D0408 05:00:49.130288 1 parameters.go:238] Clock(Realtime): error: 66 ns, adjusted frequency from 2299807557 Hz to 2299807792 Hz D0408 05:00:49.130308 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5228225836475114 monotonicBaseRef:82000311924 monotonicFrequency:2299808498 realtimeReady:1 realtimeBaseCycles:5228225836854485 realtimeBaseRef:1586322049130285702 realtimeFrequency:2299807792} D0408 05:00:50.130023 1 parameters.go:238] Clock(Monotonic): error: -722 ns, adjusted frequency from 2299808498 Hz to 2299806113 Hz D0408 05:00:50.130143 1 parameters.go:238] Clock(Realtime): error: -67 ns, adjusted frequency from 2299807792 Hz to 2299807572 Hz D0408 05:00:50.130180 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5228228136032580 monotonicBaseRef:83000202770 monotonicFrequency:2299806113 realtimeReady:1 realtimeBaseCycles:5228228136326206 realtimeBaseRef:1586322050130139571 realtimeFrequency:2299807572} D0408 05:00:51.130018 1 parameters.go:238] Clock(Monotonic): error: 133 ns, adjusted frequency from 2299806113 Hz to 2299808068 Hz D0408 05:00:51.130143 1 parameters.go:238] Clock(Realtime): error: 119 ns, adjusted frequency from 2299807572 Hz to 2299807921 Hz D0408 05:00:51.130181 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5228230435832520 monotonicBaseRef:84000200085 monotonicFrequency:2299808068 realtimeReady:1 realtimeBaseCycles:5228230436135287 realtimeBaseRef:1586322051130140227 realtimeFrequency:2299807921} D0408 05:00:52.130072 1 parameters.go:238] Clock(Monotonic): error: 7 ns, adjusted frequency from 2299808068 Hz to 2299807804 Hz D0408 05:00:52.164361 1 parameters.go:238] Clock(Realtime): error: 27 ns, adjusted frequency from 2299807921 Hz to 2299807707 Hz D0408 05:00:52.164460 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5228232735765690 monotonicBaseRef:85000254481 monotonicFrequency:2299807804 realtimeReady:1 realtimeBaseCycles:5228232814616602 realtimeBaseRef:1586322052164348907 realtimeFrequency:2299807707} D0408 05:00:53.130024 1 parameters.go:238] Clock(Monotonic): error: -182 ns, adjusted frequency from 2299807804 Hz to 2299807219 Hz D0408 05:00:53.130167 1 parameters.go:238] Clock(Realtime): error: -129 ns, adjusted frequency from 2299807707 Hz to 2299807335 Hz D0408 05:00:53.130190 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5228235035448570 monotonicBaseRef:86000200161 monotonicFrequency:2299807219 realtimeReady:1 realtimeBaseCycles:5228235035794799 realtimeBaseRef:1586322053130159305 realtimeFrequency:2299807335} D0408 05:00:54.130026 1 parameters.go:238] Clock(Monotonic): error: -17 ns, adjusted frequency from 2299807219 Hz to 2299807611 Hz D0408 05:00:54.130111 1 parameters.go:238] Clock(Realtime): error: 19 ns, adjusted frequency from 2299807335 Hz to 2299807749 Hz D0408 05:00:54.130129 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5228237335276176 monotonicBaseRef:87000209025 monotonicFrequency:2299807611 realtimeReady:1 realtimeBaseCycles:5228237335486723 realtimeBaseRef:1586322054130109122 realtimeFrequency:2299807749} D0408 05:00:55.130058 1 parameters.go:238] Clock(Monotonic): error: 126 ns, adjusted frequency from 2299807611 Hz to 2299808032 Hz D0408 05:00:55.130166 1 parameters.go:238] Clock(Realtime): error: -135 ns, adjusted frequency from 2299807749 Hz to 2299807304 Hz D0408 05:00:55.130185 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5228239635153047 monotonicBaseRef:88000239140 monotonicFrequency:2299808032 realtimeReady:1 realtimeBaseCycles:5228239635420461 realtimeBaseRef:1586322055130163904 realtimeFrequency:2299807304} D0408 05:00:56.130042 1 parameters.go:238] Clock(Monotonic): error: -193 ns, adjusted frequency from 2299808032 Hz to 2299807269 Hz D0408 05:00:56.130142 1 parameters.go:238] Clock(Realtime): error: 177 ns, adjusted frequency from 2299807304 Hz to 2299808078 Hz D0408 05:00:56.130180 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5228241934928047 monotonicBaseRef:89000224777 monotonicFrequency:2299807269 realtimeReady:1 realtimeBaseCycles:5228241935173209 realtimeBaseRef:1586322056130140182 realtimeFrequency:2299808078} I0408 05:00:56.972167 6771 main.go:305] *************************** I0408 05:00:56.972250 6771 main.go:306] Args: [/syzkaller/managers/kvm-direct-sandbox/current/image -root /syzkaller/managers/kvm-direct-sandbox/workdir/gvisor_root -watchdog-action=panic -network=none -debug -alsologtostderr -platform=kvm -file-access=exclusive -network=sandbox 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-kvm-direct-sandbox-2 /syz-execprog -executor=/syz-executor -arch=amd64 -sandbox=none -procs=1 -repeat=1 -threaded=false -collide=false -cover=0 -fault_call=-1 -fault_nth=-1 /syzkaller187715950] I0408 05:00:56.972344 6771 main.go:307] Version release-20200323.0-77-g51e461cf9c49 I0408 05:00:56.972371 6771 main.go:308] PID: 6771 I0408 05:00:56.972402 6771 main.go:309] UID: 0, GID: 0 I0408 05:00:56.972430 6771 main.go:310] Configuration: I0408 05:00:56.972457 6771 main.go:311] RootDir: /syzkaller/managers/kvm-direct-sandbox/workdir/gvisor_root I0408 05:00:56.972484 6771 main.go:312] Platform: kvm I0408 05:00:56.972538 6771 main.go:313] FileAccess: exclusive, overlay: false I0408 05:00:56.972570 6771 main.go:314] Network: sandbox, logging: false I0408 05:00:56.972608 6771 main.go:315] Strace: false, max size: 1024, syscalls: [] I0408 05:00:56.972646 6771 main.go:316] *************************** D0408 05:00:56.972772 6771 container.go:159] Load container "/syzkaller/managers/kvm-direct-sandbox/workdir/gvisor_root" "ci-gvisor-kvm-direct-sandbox-2" D0408 05:00:56.974290 6771 container.go:592] Signal container "ci-gvisor-kvm-direct-sandbox-2": signal 0 D0408 05:00:56.974341 6771 sandbox.go:814] Signal sandbox "ci-gvisor-kvm-direct-sandbox-2" D0408 05:00:56.974361 6771 sandbox.go:318] Connecting to sandbox "ci-gvisor-kvm-direct-sandbox-2" D0408 05:00:56.974504 6771 urpc.go:534] urpc: successfully marshalled 110 bytes. D0408 05:00:56.974834 1 urpc.go:577] urpc: unmarshal success. D0408 05:00:56.974954 1 controller.go:504] containerManager.Signal &{CID:ci-gvisor-kvm-direct-sandbox-2 Signo:0 PID:0 Mode:Process} D0408 05:00:56.975053 1 urpc.go:534] urpc: successfully marshalled 37 bytes. D0408 05:00:56.975201 6771 urpc.go:577] urpc: unmarshal success. D0408 05:00:56.975291 6771 exec.go:120] Exec arguments: /syz-execprog -executor=/syz-executor -arch=amd64 -sandbox=none -procs=1 -repeat=1 -threaded=false -collide=false -cover=0 -fault_call=-1 -fault_nth=-1 /syzkaller187715950 D0408 05:00:56.975334 6771 exec.go:121] Exec capablities: &{PermittedCaps:274877898751 InheritableCaps:274877898751 EffectiveCaps:274877898751 BoundingCaps:274877898751 AmbientCaps:0} D0408 05:00:56.975367 6771 container.go:533] Execute in container "ci-gvisor-kvm-direct-sandbox-2", args: /syz-execprog -executor=/syz-executor -arch=amd64 -sandbox=none -procs=1 -repeat=1 -threaded=false -collide=false -cover=0 -fault_call=-1 -fault_nth=-1 /syzkaller187715950 D0408 05:00:56.975394 6771 sandbox.go:283] Executing new process in container "ci-gvisor-kvm-direct-sandbox-2" in sandbox "ci-gvisor-kvm-direct-sandbox-2" D0408 05:00:56.975414 6771 sandbox.go:318] Connecting to sandbox "ci-gvisor-kvm-direct-sandbox-2" D0408 05:00:56.975679 6771 urpc.go:534] urpc: successfully marshalled 629 bytes. D0408 05:00:56.975861 1 urpc.go:577] urpc: unmarshal success. D0408 05:00:56.976492 1 controller.go:270] containerManager.ExecuteAsync: /syz-execprog -executor=/syz-executor -arch=amd64 -sandbox=none -procs=1 -repeat=1 -threaded=false -collide=false -cover=0 -fault_call=-1 -fault_nth=-1 /syzkaller187715950 I0408 05:00:56.976725 1 kernel.go:836] EXEC: [/syz-execprog -executor=/syz-executor -arch=amd64 -sandbox=none -procs=1 -repeat=1 -threaded=false -collide=false -cover=0 -fault_call=-1 -fault_nth=-1 /syzkaller187715950] D0408 05:00:56.976866 1 transport_flipcall.go:127] send [channel @0xc00031e3c0] Twalkgetattr{FID: 1, NewFID: 5, Names: [syz-execprog]} D0408 05:00:56.977360 1 transport_flipcall.go:234] recv [channel @0xc00031e3c0] 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: 24176384, BlockSize: 4096, Blocks: 47224, ATime: {Sec: 1586276915, NanoSec: 811888027}, MTime: {Sec: 1586276915, NanoSec: 811888027}, CTime: {Sec: 1586321971, NanoSec: 347947730}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}, QIDs: [QID{Type: 0, Version: 0, Path: 3277948}]} D0408 05:00:56.977446 1 transport_flipcall.go:127] send [channel @0xc00031e3c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syz-execprog, Size: 1} D0408 05:00:56.977652 1 transport_flipcall.go:234] recv [channel @0xc00031e3c0] Rlerror{Error: 95} D0408 05:00:56.977771 1 transport_flipcall.go:127] send [channel @0xc00031e3c0] Twalk{FID: 5, NewFID: 6, Names: []} D0408 05:00:56.978047 1 transport_flipcall.go:234] recv [channel @0xc00031e3c0] Rwalk{QIDs: []} D0408 05:00:56.978133 1 transport_flipcall.go:127] send [channel @0xc00031e3c0] Tlopen{FID: 6, Flags: ReadOnly} D0408 05:00:56.978401 1 transport_flipcall.go:234] recv [channel @0xc00031e3c0] Rlopen{QID: QID{Type: 0, Version: 0, Path: 3277948}, IoUnit: 0, File: &{{54}}} D0408 05:00:56.981315 1 syscalls.go:266] Allocating stack with size of 8388608 bytes D0408 05:00:56.981816 1 loader.go:832] updated processes: map[{ci-gvisor-kvm-direct-sandbox-2 0}:0xc0007203e0 {ci-gvisor-kvm-direct-sandbox-2 27}:0xc0009d13c0] D0408 05:00:56.981939 1 urpc.go:534] urpc: successfully marshalled 37 bytes. D0408 05:00:56.982056 6771 urpc.go:577] urpc: unmarshal success. D0408 05:00:56.982140 6771 container.go:580] Wait on PID 27 in container "ci-gvisor-kvm-direct-sandbox-2" D0408 05:00:56.982178 6771 sandbox.go:769] Waiting for PID 27 in sandbox "ci-gvisor-kvm-direct-sandbox-2" D0408 05:00:56.982201 6771 sandbox.go:318] Connecting to sandbox "ci-gvisor-kvm-direct-sandbox-2" D0408 05:00:56.982331 6771 urpc.go:534] urpc: successfully marshalled 93 bytes. D0408 05:00:56.982478 1 urpc.go:577] urpc: unmarshal success. D0408 05:00:56.982562 1 controller.go:446] containerManager.Wait D0408 05:00:56.986131 1 task_block.go:223] [ 27] Interrupt queued D0408 05:00:57.130066 1 parameters.go:238] Clock(Monotonic): error: -179 ns, adjusted frequency from 2299807269 Hz to 2299807196 Hz D0408 05:00:57.130145 1 parameters.go:238] Clock(Realtime): error: -251 ns, adjusted frequency from 2299808078 Hz to 2299807097 Hz D0408 05:00:57.130165 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5228244234794695 monotonicBaseRef:90000250596 monotonicFrequency:2299807196 realtimeReady:1 realtimeBaseCycles:5228244234985918 realtimeBaseRef:1586322057130142195 realtimeFrequency:2299807097} I0408 05:00:57.175685 1 watchdog.go:277] Watchdog starting loop, tasks: 22, discount: 0s D0408 05:00:57.320333 1 task_block.go:223] [ 28] Interrupt queued D0408 05:00:57.332740 1 task_block.go:223] [ 29] Interrupt queued D0408 05:00:57.351192 1 task_block.go:223] [ 30] Interrupt queued D0408 05:00:57.351308 1 task_block.go:223] [ 31] Interrupt queued D0408 05:00:57.359479 1 urpc.go:577] urpc: unmarshal success. D0408 05:00:57.359575 1 controller.go:504] containerManager.Signal &{CID:ci-gvisor-kvm-direct-sandbox-2 Signo:0 PID:0 Mode:Process} D0408 05:00:57.359657 1 urpc.go:534] urpc: successfully marshalled 37 bytes. D0408 05:00:57.698988 1 task_block.go:223] [ 32] Interrupt queued D0408 05:00:58.130090 1 parameters.go:238] Clock(Monotonic): error: 631 ns, adjusted frequency from 2299807196 Hz to 2299809061 Hz D0408 05:00:58.130233 1 parameters.go:238] Clock(Realtime): error: -19 ns, adjusted frequency from 2299807097 Hz to 2299807659 Hz D0408 05:00:58.130253 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5228246534658402 monotonicBaseRef:91000275168 monotonicFrequency:2299809061 realtimeReady:1 realtimeBaseCycles:5228246534995271 realtimeBaseRef:1586322058130230139 realtimeFrequency:2299807659} D0408 05:00:58.921437 1 task_block.go:223] [ 33] Interrupt queued D0408 05:00:58.966611 1 task_block.go:223] [ 34] Interrupt queued D0408 05:00:59.008285 1 task_block.go:223] [ 35] Interrupt queued D0408 05:00:59.021403 1 task_block.go:223] [ 36] Interrupt queued D0408 05:00:59.056414 1 task_block.go:223] [ 37] Interrupt queued D0408 05:00:59.096639 1 task_block.go:223] [ 38] Interrupt queued D0408 05:00:59.115493 1 task_block.go:223] [ 39] Interrupt queued D0408 05:00:59.132627 1 parameters.go:238] Clock(Monotonic): error: -70 ns, adjusted frequency from 2299809061 Hz to 2299807537 Hz D0408 05:00:59.132722 1 parameters.go:238] Clock(Realtime): error: 214 ns, adjusted frequency from 2299807659 Hz to 2299808058 Hz D0408 05:00:59.132741 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5228248840291174 monotonicBaseRef:92002807426 monotonicFrequency:2299807537 realtimeReady:1 realtimeBaseCycles:5228248840530559 realtimeBaseRef:1586322059132720620 realtimeFrequency:2299808058} D0408 05:00:59.146523 1 task_block.go:223] [ 40] Interrupt queued D0408 05:00:59.162806 1 task_block.go:223] [ 41] Interrupt queued D0408 05:00:59.198518 1 task_block.go:223] [ 42] Interrupt queued D0408 05:00:59.237293 1 task_block.go:223] [ 43] Interrupt queued D0408 05:00:59.425971 1 task_block.go:223] [ 44] Interrupt queued D0408 05:00:59.729433 1 task_block.go:223] [ 45] Interrupt queued D0408 05:00:59.759091 1 task_block.go:223] [ 46] Interrupt queued D0408 05:00:59.934308 1 task_block.go:223] [ 47] Interrupt queued D0408 05:01:00.070427 1 transport_flipcall.go:127] send [channel @0xc00031e3c0] Twalkgetattr{FID: 1, NewFID: 7, Names: [syzkaller187715950]} D0408 05:01:00.076742 1 transport_flipcall.go:234] recv [channel @0xc00031e3c0] 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: 273, BlockSize: 4096, Blocks: 8, ATime: {Sec: 1586322056, NanoSec: 947106351}, MTime: {Sec: 1586322056, NanoSec: 947106351}, CTime: {Sec: 1586322056, NanoSec: 947106351}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}, QIDs: [QID{Type: 0, Version: 0, Path: 3277960}]} D0408 05:01:00.076889 1 transport_flipcall.go:127] send [channel @0xc00031e3c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzkaller187715950, Size: 1} D0408 05:01:00.077169 1 transport_flipcall.go:234] recv [channel @0xc00031e3c0] Rlerror{Error: 95} D0408 05:01:00.077262 1 transport_flipcall.go:127] send [channel @0xc00031e3c0] Twalk{FID: 7, NewFID: 8, Names: []} D0408 05:01:00.077596 1 transport_flipcall.go:234] recv [channel @0xc00031e3c0] Rwalk{QIDs: []} D0408 05:01:00.077668 1 transport_flipcall.go:127] send [channel @0xc00031e3c0] Tlopen{FID: 8, Flags: ReadOnly} D0408 05:01:00.077972 1 transport_flipcall.go:234] recv [channel @0xc00031e3c0] Rlopen{QID: QID{Type: 0, Version: 0, Path: 3277960}, IoUnit: 0, File: &{{48}}} 2020/04/08 05:01:00 parsed 1 programs D0408 05:01:00.130667 1 parameters.go:238] Clock(Monotonic): error: -342 ns, adjusted frequency from 2299807537 Hz to 2299806857 Hz D0408 05:01:00.130756 1 parameters.go:238] Clock(Realtime): error: 58 ns, adjusted frequency from 2299808058 Hz to 2299807815 Hz D0408 05:01:00.130777 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5228251135545918 monotonicBaseRef:93000827785 monotonicFrequency:2299806857 realtimeReady:1 realtimeBaseCycles:5228251135816516 realtimeBaseRef:1586322060130754325 realtimeFrequency:2299807815} D0408 05:01:00.165408 1 task_block.go:223] [ 48] Interrupt queued D0408 05:01:00.258751 1 task_stop.go:118] [ 47] Entering internal stop (*kernel.vforkStop)(nil) D0408 05:01:00.258896 1 task_block.go:223] [ 49] Interrupt queued D0408 05:01:00.274805 1 transport_flipcall.go:127] send [channel @0xc00031e3c0] Twalkgetattr{FID: 1, NewFID: 9, Names: [syz-executor]} D0408 05:01:00.275252 1 transport_flipcall.go:234] recv [channel @0xc00031e3c0] 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: 1579736, BlockSize: 4096, Blocks: 3088, ATime: {Sec: 1586276916, NanoSec: 695961954}, MTime: {Sec: 1586276916, NanoSec: 695961954}, CTime: {Sec: 1586321971, NanoSec: 351948065}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}, QIDs: [QID{Type: 0, Version: 0, Path: 3278093}]} D0408 05:01:00.275348 1 transport_flipcall.go:127] send [channel @0xc00031e3c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syz-executor, Size: 1} D0408 05:01:00.275508 1 transport_flipcall.go:234] recv [channel @0xc00031e3c0] Rlerror{Error: 95} D0408 05:01:00.275591 1 transport_flipcall.go:127] send [channel @0xc00031e3c0] Twalk{FID: 9, NewFID: 10, Names: []} D0408 05:01:00.275859 1 transport_flipcall.go:234] recv [channel @0xc00031e3c0] Rwalk{QIDs: []} D0408 05:01:00.275961 1 transport_flipcall.go:127] send [channel @0xc00031e3c0] Tlopen{FID: 10, Flags: ReadOnly} D0408 05:01:00.276407 1 transport_flipcall.go:234] recv [channel @0xc00031e3c0] Rlopen{QID: QID{Type: 0, Version: 0, Path: 3278093}, IoUnit: 0, File: &{{49}}} D0408 05:01:00.276898 1 syscalls.go:266] [ 49] Allocating stack with size of 8388608 bytes D0408 05:01:00.277076 1 task_stop.go:137] [ 47] Leaving internal stop (*kernel.vforkStop)(nil) D0408 05:01:00.366042 1 task_exit.go:221] [ 49] Transitioning from exit state TaskExitNone to TaskExitInitiated D0408 05:01:00.385002 1 task_exit.go:221] [ 49] Transitioning from exit state TaskExitInitiated to TaskExitZombie D0408 05:01:00.385077 1 task_signals.go:446] [ 27] Notified of signal 17 D0408 05:01:00.385100 1 task_block.go:223] [ 27] Interrupt queued D0408 05:01:00.385123 1 task_block.go:223] [ 27] Interrupt queued D0408 05:01:00.385141 1 task_signals.go:179] [ 27] Restarting syscall 202 after errno 512: interrupted by signal 17 D0408 05:01:00.385171 1 task_signals.go:220] [ 27] Signal 17: delivering to handler D0408 05:01:00.414490 1 task_block.go:223] [ 50] Interrupt queued D0408 05:01:00.425271 1 task_exit.go:221] [ 49] Transitioning from exit state TaskExitZombie to TaskExitDead D0408 05:01:00.450412 1 task_block.go:223] [ 51] Interrupt queued 2020/04/08 05:01:00 executed programs: 0 D0408 05:01:00.540432 1 task_stop.go:118] [ 38] Entering internal stop (*kernel.vforkStop)(nil) D0408 05:01:00.540553 1 task_block.go:223] [ 52] Interrupt queued D0408 05:01:00.550650 1 syscalls.go:266] [ 52] Allocating stack with size of 8388608 bytes D0408 05:01:00.550916 1 task_stop.go:137] [ 38] Leaving internal stop (*kernel.vforkStop)(nil) D0408 05:01:00.689311 1 task_block.go:223] [ 53] Interrupt queued D0408 05:01:00.863889 1 transport_flipcall.go:127] send [channel @0xc00031e3c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0408 05:01:00.864238 1 transport_flipcall.go:234] recv [channel @0xc00031e3c0] Rlerror{Error: 2} D0408 05:01:00.864369 1 transport_flipcall.go:127] send [channel @0xc00031e3c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0408 05:01:00.865347 1 transport_flipcall.go:234] recv [channel @0xc00031e3c0] Rlerror{Error: 95} D0408 05:01:01.010347 1 netfilter.go:59] netfilter: convert to binary: current offset: 0 D0408 05:01:01.010429 1 netfilter.go:59] netfilter: convert to binary: found hook 0 at offset 0 D0408 05:01:01.010451 1 netfilter.go:59] netfilter: convert to binary: found underflow 0 at offset 0 D0408 05:01:01.010473 1 netfilter.go:59] netfilter: convert to binary: marshalling standard target D0408 05:01:01.010498 1 netfilter.go:59] 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]} D0408 05:01:01.010605 1 netfilter.go:59] netfilter: convert to binary: current offset: 152 D0408 05:01:01.010621 1 netfilter.go:59] netfilter: convert to binary: found hook 1 at offset 152 D0408 05:01:01.010636 1 netfilter.go:59] netfilter: convert to binary: found underflow 1 at offset 152 D0408 05:01:01.010650 1 netfilter.go:59] netfilter: convert to binary: marshalling standard target D0408 05:01:01.010666 1 netfilter.go:59] 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]} D0408 05:01:01.010751 1 netfilter.go:59] netfilter: convert to binary: current offset: 304 D0408 05:01:01.010767 1 netfilter.go:59] netfilter: convert to binary: found hook 3 at offset 304 D0408 05:01:01.010778 1 netfilter.go:59] netfilter: convert to binary: found underflow 3 at offset 304 D0408 05:01:01.010796 1 netfilter.go:59] netfilter: convert to binary: marshalling standard target D0408 05:01:01.010807 1 netfilter.go:59] 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]} D0408 05:01:01.010877 1 netfilter.go:59] netfilter: convert to binary: current offset: 456 D0408 05:01:01.010892 1 netfilter.go:59] netfilter: convert to binary: found hook 4 at offset 456 D0408 05:01:01.010905 1 netfilter.go:59] netfilter: convert to binary: found underflow 4 at offset 456 D0408 05:01:01.010920 1 netfilter.go:59] netfilter: convert to binary: marshalling standard target D0408 05:01:01.010934 1 netfilter.go:59] 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]} D0408 05:01:01.011007 1 netfilter.go:59] netfilter: convert to binary: current offset: 608 D0408 05:01:01.011028 1 netfilter.go:59] 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]} D0408 05:01:01.011095 1 netfilter.go:59] netfilter: convert to binary: finished with an marshalled size of 0 D0408 05:01:01.011109 1 netfilter.go:59] netfilter: convert to binary: current offset: 0 D0408 05:01:01.011131 1 netfilter.go:59] netfilter: convert to binary: found hook 0 at offset 0 D0408 05:01:01.011148 1 netfilter.go:59] netfilter: convert to binary: found underflow 0 at offset 0 D0408 05:01:01.011164 1 netfilter.go:59] netfilter: convert to binary: marshalling standard target D0408 05:01:01.011186 1 netfilter.go:59] 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]} D0408 05:01:01.011252 1 netfilter.go:59] netfilter: convert to binary: current offset: 152 D0408 05:01:01.011266 1 netfilter.go:59] netfilter: convert to binary: found hook 3 at offset 152 D0408 05:01:01.011279 1 netfilter.go:59] netfilter: convert to binary: found underflow 3 at offset 152 D0408 05:01:01.011292 1 netfilter.go:59] netfilter: convert to binary: marshalling standard target D0408 05:01:01.011305 1 netfilter.go:59] 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]} D0408 05:01:01.011395 1 netfilter.go:59] netfilter: convert to binary: current offset: 304 D0408 05:01:01.011419 1 netfilter.go:59] 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]} D0408 05:01:01.011507 1 netfilter.go:59] netfilter: convert to binary: finished with an marshalled size of 0 D0408 05:01:01.011523 1 netfilter.go:59] netfilter: convert to binary: current offset: 0 D0408 05:01:01.011538 1 netfilter.go:59] netfilter: convert to binary: found hook 1 at offset 0 D0408 05:01:01.011555 1 netfilter.go:59] netfilter: convert to binary: found underflow 1 at offset 0 D0408 05:01:01.011571 1 netfilter.go:59] netfilter: convert to binary: marshalling standard target D0408 05:01:01.011589 1 netfilter.go:59] 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]} D0408 05:01:01.011699 1 netfilter.go:59] netfilter: convert to binary: current offset: 152 D0408 05:01:01.011716 1 netfilter.go:59] netfilter: convert to binary: found hook 2 at offset 152 D0408 05:01:01.011734 1 netfilter.go:59] netfilter: convert to binary: found underflow 2 at offset 152 D0408 05:01:01.011749 1 netfilter.go:59] netfilter: convert to binary: marshalling standard target D0408 05:01:01.011767 1 netfilter.go:59] 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]} D0408 05:01:01.011849 1 netfilter.go:59] netfilter: convert to binary: current offset: 304 D0408 05:01:01.011865 1 netfilter.go:59] netfilter: convert to binary: found hook 3 at offset 304 D0408 05:01:01.011879 1 netfilter.go:59] netfilter: convert to binary: found underflow 3 at offset 304 D0408 05:01:01.011893 1 netfilter.go:59] netfilter: convert to binary: marshalling standard target D0408 05:01:01.011916 1 netfilter.go:59] 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]} D0408 05:01:01.011994 1 netfilter.go:59] netfilter: convert to binary: current offset: 456 D0408 05:01:01.012011 1 netfilter.go:59] 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]} D0408 05:01:01.012086 1 netfilter.go:59] netfilter: convert to binary: finished with an marshalled size of 0 D0408 05:01:01.130301 1 parameters.go:238] Clock(Monotonic): error: 20 ns, adjusted frequency from 2299806857 Hz to 2299807694 Hz D0408 05:01:01.130438 1 parameters.go:238] Clock(Realtime): error: 100 ns, adjusted frequency from 2299807815 Hz to 2299807935 Hz D0408 05:01:01.130480 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5228253434549759 monotonicBaseRef:94000478618 monotonicFrequency:2299807694 realtimeReady:1 realtimeBaseCycles:5228253434891545 realtimeBaseRef:1586322061130435695 realtimeFrequency:2299807935} D0408 05:01:01.431408 1 task_block.go:223] [ 54] Interrupt queued panic: runtime error: makeslice: len out of range goroutine 452 [running]: gvisor.dev/gvisor/pkg/tcpip/buffer.NewView(...) pkg/tcpip/buffer/view.go:29 gvisor.dev/gvisor/pkg/sentry/socket/netstack.(*readerPayload).Payload(0xc000b6e100, 0x8000000000004, 0xc000576430, 0x4328c6, 0xc000576430, 0xc0001cac20) pkg/sentry/socket/netstack/netstack.go:578 +0x55 gvisor.dev/gvisor/pkg/sentry/socket/netstack.(*readerPayload).FullPayload(0xc000b6e100, 0x0, 0x0, 0x0, 0x10) pkg/sentry/socket/netstack/netstack.go:570 +0x34 gvisor.dev/gvisor/pkg/tcpip/transport/udp.(*endpoint).write(0xc0001cab40, 0xf54400, 0xc000b6e100, 0x0, 0x10000, 0x0, 0x0, 0x0) pkg/tcpip/transport/udp/endpoint.go:472 +0x3b2 gvisor.dev/gvisor/pkg/tcpip/transport/udp.(*endpoint).Write(0xc0001cab40, 0xf54400, 0xc000b6e100, 0x0, 0x8000000010000, 0x0, 0x0, 0xf50520) pkg/tcpip/transport/udp/endpoint.go:362 +0x68 gvisor.dev/gvisor/pkg/sentry/socket/netstack.(*SocketOperations).ReadFrom(0xc000cce000, 0xf6d460, 0xc000c8b500, 0xc000c96210, 0xf4eaa0, 0xc000c98200, 0x8000000000004, 0xc000298000, 0x0, 0xf50520) pkg/sentry/socket/netstack/netstack.go:601 +0xc7 gvisor.dev/gvisor/pkg/sentry/fs.Splice(0xf6d460, 0xc000c8b500, 0xc000c96210, 0xc000c960b0, 0x8000000000004, 0x0, 0x0, 0x0, 0x0, 0x0, ...) pkg/sentry/fs/splice.go:146 +0x7c7 gvisor.dev/gvisor/pkg/sentry/syscalls/linux.doSplice(0xc000c8b500, 0xc000c96210, 0xc000c960b0, 0x8000000000004, 0x0, 0x0, 0x0, 0x0, 0xc000901000, 0x0, ...) pkg/sentry/syscalls/linux/sys_splice.go:40 +0x149 gvisor.dev/gvisor/pkg/sentry/syscalls/linux.Sendfile(0xc000c8b500, 0x4, 0x3, 0x0, 0x8000000000004, 0x0, 0x0, 0x0, 0x0, 0x0, ...) pkg/sentry/syscalls/linux/sys_splice.go:151 +0x68c gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).executeSyscall(0xc000c8b500, 0x28, 0x4, 0x3, 0x0, 0x8000000000004, 0x0, 0x0, 0xc000738410, 0xe03180, ...) pkg/sentry/kernel/task_syscall.go:170 +0x122 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscallInvoke(0xc000c8b500, 0x28, 0x4, 0x3, 0x0, 0x8000000000004, 0x0, 0x0, 0x0, 0x0) pkg/sentry/kernel/task_syscall.go:291 +0x66 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscallEnter(0xc000c8b500, 0x28, 0x4, 0x3, 0x0, 0x8000000000004, 0x0, 0x0, 0x0, 0x0) pkg/sentry/kernel/task_syscall.go:252 +0x96 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscall(0xc000c8b500, 0x2, 0xc0005b8480) pkg/sentry/kernel/task_syscall.go:227 +0x139 gvisor.dev/gvisor/pkg/sentry/kernel.(*runApp).execute(0x0, 0xc000c8b500, 0xf4eea0, 0x0) pkg/sentry/kernel/task_run.go:258 +0xed5 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).run(0xc000c8b500, 0x36) pkg/sentry/kernel/task_run.go:92 +0x18b created by gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).Start pkg/sentry/kernel/task_start.go:315 +0xfe goroutine 1 [semacquire, 1 minutes]: sync.runtime_Semacquire(0xc0002d2304) GOROOT/src/runtime/sema.go:56 +0x42 sync.(*WaitGroup).Wait(0xc0002d2304) GOROOT/src/sync/waitgroup.go:130 +0x64 gvisor.dev/gvisor/pkg/sentry/kernel.(*Kernel).WaitExited(...) pkg/sentry/kernel/kernel.go:1196 gvisor.dev/gvisor/runsc/boot.(*Loader).WaitExit(0xc0001ce000, 0x0, 0x0) runsc/boot/loader.go:905 +0x38 gvisor.dev/gvisor/runsc/cmd.(*Boot).Execute(0xc000232360, 0xf5e700, 0xc0001ae000, 0xc00023a300, 0xc00026ed40, 0x2, 0x2, 0x0) runsc/cmd/boot.go:260 +0x73f github.com/google/subcommands.(*Commander).Execute(0xc0001c4000, 0xf5e700, 0xc0001ae000, 0xc00026ed40, 0x2, 0x2, 0x0) external/com_github_google_subcommands/subcommands.go:200 +0x2f9 github.com/google/subcommands.Execute(...) external/com_github_google_subcommands/subcommands.go:481 main.main() runsc/main.go:327 +0x18d7 goroutine 20 [sync.Cond.Wait]: runtime.goparkunlock(...) GOROOT/src/runtime/proc.go:310 sync.runtime_notifyListWait(0xc000310948, 0x3) GOROOT/src/runtime/sema.go:513 +0xf8 sync.(*Cond).Wait(0xc000310938) GOROOT/src/sync/cond.go:56 +0x9d gvisor.dev/gvisor/pkg/sentry/pgalloc.(*MemoryFile).findReclaimable(0xc000310500, 0x0, 0x0, 0x0) pkg/sentry/pgalloc/pgalloc.go:1090 +0x83 gvisor.dev/gvisor/pkg/sentry/pgalloc.(*MemoryFile).runReclaim(0xc000310500) pkg/sentry/pgalloc/pgalloc.go:1019 +0x77 created by gvisor.dev/gvisor/pkg/sentry/pgalloc.NewMemoryFile pkg/sentry/pgalloc/pgalloc.go:330 +0x1fd goroutine 21 [select]: gvisor.dev/gvisor/pkg/sentry/kernel.(*Timekeeper).startUpdater.func1(0xc00023a960, 0xc0002d2780) pkg/sentry/kernel/timekeeper.go:216 +0x164 created by gvisor.dev/gvisor/pkg/sentry/kernel.(*Timekeeper).startUpdater pkg/sentry/kernel/timekeeper.go:184 +0xbf goroutine 22 [select, 1 minutes]: gvisor.dev/gvisor/pkg/sleep.(*Sleeper).nextWaker(0xc000516220, 0x1, 0xc000516220) pkg/sleep/sleep_unsafe.go:190 +0x6a gvisor.dev/gvisor/pkg/sleep.(*Sleeper).Fetch(0xc000516220, 0xc0002d5b01, 0x2, 0x0) pkg/sleep/sleep_unsafe.go:225 +0x38 gvisor.dev/gvisor/pkg/tcpip/transport/tcp.(*processor).handleSegments(0xc0002d5b20) pkg/tcpip/transport/tcp/dispatcher.go:106 +0x266 created by gvisor.dev/gvisor/pkg/tcpip/transport/tcp.newProcessor pkg/tcpip/transport/tcp/dispatcher.go:80 +0x78 goroutine 23 [select, 1 minutes]: gvisor.dev/gvisor/pkg/sleep.(*Sleeper).nextWaker(0xc000516240, 0x1, 0xc000516240) pkg/sleep/sleep_unsafe.go:190 +0x6a gvisor.dev/gvisor/pkg/sleep.(*Sleeper).Fetch(0xc000516240, 0xc0002d5b01, 0x2, 0x0) pkg/sleep/sleep_unsafe.go:225 +0x38 gvisor.dev/gvisor/pkg/tcpip/transport/tcp.(*processor).handleSegments(0xc0002d5b90) pkg/tcpip/transport/tcp/dispatcher.go:106 +0x266 created by gvisor.dev/gvisor/pkg/tcpip/transport/tcp.newProcessor pkg/tcpip/transport/tcp/dispatcher.go:80 +0x78 goroutine 24 [select, 1 minutes]: gvisor.dev/gvisor/pkg/sleep.(*Sleeper).nextWaker(0xc000516260, 0x1, 0xc000516260) pkg/sleep/sleep_unsafe.go:190 +0x6a gvisor.dev/gvisor/pkg/sleep.(*Sleeper).Fetch(0xc000516260, 0xc0002d5c01, 0x2, 0x0) pkg/sleep/sleep_unsafe.go:225 +0x38 gvisor.dev/gvisor/pkg/tcpip/transport/tcp.(*processor).handleSegments(0xc0002d5c00) pkg/tcpip/transport/tcp/dispatcher.go:106 +0x266 created by gvisor.dev/gvisor/pkg/tcpip/transport/tcp.newProcessor pkg/tcpip/transport/tcp/dispatcher.go:80 +0x78 goroutine 25 [select, 1 minutes]: gvisor.dev/gvisor/pkg/sleep.(*Sleeper).nextWaker(0xc000516280, 0x1, 0xc000516280) pkg/sleep/sleep_unsafe.go:190 +0x6a gvisor.dev/gvisor/pkg/sleep.(*Sleeper).Fetch(0xc000516280, 0xc0002d5c01, 0x2, 0x0) pkg/sleep/sleep_unsafe.go:225 +0x38 gvisor.dev/gvisor/pkg/tcpip/transport/tcp.(*processor).handleSegments(0xc0002d5c70) pkg/tcpip/transport/tcp/dispatcher.go:106 +0x266 created by gvisor.dev/gvisor/pkg/tcpip/transport/tcp.newProcessor pkg/tcpip/transport/tcp/dispatcher.go:80 +0x78 goroutine 26 [select, 1 minutes]: gvisor.dev/gvisor/pkg/sleep.(*Sleeper).nextWaker(0xc0005162a0, 0x1, 0xc0005162a0) pkg/sleep/sleep_unsafe.go:190 +0x6a gvisor.dev/gvisor/pkg/sleep.(*Sleeper).Fetch(0xc0005162a0, 0xc0002d5d01, 0x2, 0x0) pkg/sleep/sleep_unsafe.go:225 +0x38 gvisor.dev/gvisor/pkg/tcpip/transport/tcp.(*processor).handleSegments(0xc0002d5ce0) pkg/tcpip/transport/tcp/dispatcher.go:106 +0x266 created by gvisor.dev/gvisor/pkg/tcpip/transport/tcp.newProcessor pkg/tcpip/transport/tcp/dispatcher.go:80 +0x78 goroutine 27 [select, 1 minutes]: gvisor.dev/gvisor/pkg/sleep.(*Sleeper).nextWaker(0xc0005162c0, 0x1, 0xc0005162c0) pkg/sleep/sleep_unsafe.go:190 +0x6a gvisor.dev/gvisor/pkg/sleep.(*Sleeper).Fetch(0xc0005162c0, 0xc0002d5d01, 0x2, 0x0) pkg/sleep/sleep_unsafe.go:225 +0x38 gvisor.dev/gvisor/pkg/tcpip/transport/tcp.(*processor).handleSegments(0xc0002d5d50) pkg/tcpip/transport/tcp/dispatcher.go:106 +0x266 created by gvisor.dev/gvisor/pkg/tcpip/transport/tcp.newProcessor pkg/tcpip/transport/tcp/dispatcher.go:80 +0x78 goroutine 28 [select, 1 minutes]: gvisor.dev/gvisor/pkg/sleep.(*Sleeper).nextWaker(0xc0005162e0, 0x1, 0xc0005162e0) pkg/sleep/sleep_unsafe.go:190 +0x6a gvisor.dev/gvisor/pkg/sleep.(*Sleeper).Fetch(0xc0005162e0, 0xc0002d5d01, 0x2, 0x0) pkg/sleep/sleep_unsafe.go:225 +0x38 gvisor.dev/gvisor/pkg/tcpip/transport/tcp.(*processor).handleSegments(0xc0002d5dc0) pkg/tcpip/transport/tcp/dispatcher.go:106 +0x266 created by gvisor.dev/gvisor/pkg/tcpip/transport/tcp.newProcessor pkg/tcpip/transport/tcp/dispatcher.go:80 +0x78 goroutine 29 [select, 1 minutes]: gvisor.dev/gvisor/pkg/sleep.(*Sleeper).nextWaker(0xc000516300, 0x1, 0xc000516300) pkg/sleep/sleep_unsafe.go:190 +0x6a gvisor.dev/gvisor/pkg/sleep.(*Sleeper).Fetch(0xc000516300, 0xc0002d5e01, 0x2, 0x0) pkg/sleep/sleep_unsafe.go:225 +0x38 gvisor.dev/gvisor/pkg/tcpip/transport/tcp.(*processor).handleSegments(0xc0002d5e30) pkg/tcpip/transport/tcp/dispatcher.go:106 +0x266 created by gvisor.dev/gvisor/pkg/tcpip/transport/tcp.newProcessor pkg/tcpip/transport/tcp/dispatcher.go:80 +0x78 goroutine 30 [select, 1 minutes]: gvisor.dev/gvisor/pkg/sleep.(*Sleeper).nextWaker(0xc000516320, 0x1, 0xc000516320) pkg/sleep/sleep_unsafe.go:190 +0x6a gvisor.dev/gvisor/pkg/sleep.(*Sleeper).Fetch(0xc000516320, 0xc0002d5e01, 0x2, 0x0) pkg/sleep/sleep_unsafe.go:225 +0x38 gvisor.dev/gvisor/pkg/tcpip/transport/tcp.(*processor).handleSegments(0xc0002d5ea0) pkg/tcpip/transport/tcp/dispatcher.go:106 +0x266 created by gvisor.dev/gvisor/pkg/tcpip/transport/tcp.newProcessor pkg/tcpip/transport/tcp/dispatcher.go:80 +0x78 goroutine 31 [select, 1 minutes]: gvisor.dev/gvisor/pkg/sleep.(*Sleeper).nextWaker(0xc000516340, 0x1, 0xc000516340) pkg/sleep/sleep_unsafe.go:190 +0x6a gvisor.dev/gvisor/pkg/sleep.(*Sleeper).Fetch(0xc000516340, 0xc0002d5f01, 0x2, 0x0) pkg/sleep/sleep_unsafe.go:225 +0x38 gvisor.dev/gvisor/pkg/tcpip/transport/tcp.(*processor).handleSegments(0xc0002d5f10) pkg/tcpip/transport/tcp/dispatcher.go:106 +0x266 created by gvisor.dev/gvisor/pkg/tcpip/transport/tcp.newProcessor pkg/tcpip/transport/tcp/dispatcher.go:80 +0x78 goroutine 32 [select, 1 minutes]: gvisor.dev/gvisor/pkg/sleep.(*Sleeper).nextWaker(0xc000516360, 0x1, 0xc000516360) pkg/sleep/sleep_unsafe.go:190 +0x6a gvisor.dev/gvisor/pkg/sleep.(*Sleeper).Fetch(0xc000516360, 0xc0002d5f01, 0x2, 0x0) pkg/sleep/sleep_unsafe.go:225 +0x38 gvisor.dev/gvisor/pkg/tcpip/transport/tcp.(*processor).handleSegments(0xc0002d5f80) pkg/tcpip/transport/tcp/dispatcher.go:106 +0x266 created by gvisor.dev/gvisor/pkg/tcpip/transport/tcp.newProcessor pkg/tcpip/transport/tcp/dispatcher.go:80 +0x78 goroutine 33 [select, 1 minutes]: gvisor.dev/gvisor/pkg/sleep.(*Sleeper).nextWaker(0xc000516380, 0x1, 0xc000516380) pkg/sleep/sleep_unsafe.go:190 +0x6a gvisor.dev/gvisor/pkg/sleep.(*Sleeper).Fetch(0xc000516380, 0xc0004b6001, 0x2, 0x0) pkg/sleep/sleep_unsafe.go:225 +0x38 gvisor.dev/gvisor/pkg/tcpip/transport/tcp.(*processor).handleSegments(0xc0004b6000) pkg/tcpip/transport/tcp/dispatcher.go:106 +0x266 created by gvisor.dev/gvisor/pkg/tcpip/transport/tcp.newProcessor pkg/tcpip/transport/tcp/dispatcher.go:80 +0x78 goroutine 34 [select, 1 minutes]: gvisor.dev/gvisor/pkg/sleep.(*Sleeper).nextWaker(0xc0005163a0, 0x1, 0xc0005163a0) pkg/sleep/sleep_unsafe.go:190 +0x6a gvisor.dev/gvisor/pkg/sleep.(*Sleeper).Fetch(0xc0005163a0, 0xc0004b6001, 0x2, 0x0) pkg/sleep/sleep_unsafe.go:225 +0x38 gvisor.dev/gvisor/pkg/tcpip/transport/tcp.(*processor).handleSegments(0xc0004b6070) pkg/tcpip/transport/tcp/dispatcher.go:106 +0x266 created by gvisor.dev/gvisor/pkg/tcpip/transport/tcp.newProcessor pkg/tcpip/transport/tcp/dispatcher.go:80 +0x78 goroutine 35 [select, 1 minutes]: gvisor.dev/gvisor/pkg/sleep.(*Sleeper).nextWaker(0xc0005163c0, 0x1, 0xc0005163c0) pkg/sleep/sleep_unsafe.go:190 +0x6a gvisor.dev/gvisor/pkg/sleep.(*Sleeper).Fetch(0xc0005163c0, 0xc0004b6101, 0x2, 0x0) pkg/sleep/sleep_unsafe.go:225 +0x38 gvisor.dev/gvisor/pkg/tcpip/transport/tcp.(*processor).handleSegments(0xc0004b60e0) pkg/tcpip/transport/tcp/dispatcher.go:106 +0x266 created by gvisor.dev/gvisor/pkg/tcpip/transport/tcp.newProcessor pkg/tcpip/transport/tcp/dispatcher.go:80 +0x78 goroutine 36 [select, 1 minutes]: gvisor.dev/gvisor/pkg/sleep.(*Sleeper).nextWaker(0xc0005163e0, 0x1, 0xc0005163e0) pkg/sleep/sleep_unsafe.go:190 +0x6a gvisor.dev/gvisor/pkg/sleep.(*Sleeper).Fetch(0xc0005163e0, 0xc0004b6101, 0x2, 0x0) pkg/sleep/sleep_unsafe.go:225 +0x38 gvisor.dev/gvisor/pkg/tcpip/transport/tcp.(*processor).handleSegments(0xc0004b6150) pkg/tcpip/transport/tcp/dispatcher.go:106 +0x266 created by gvisor.dev/gvisor/pkg/tcpip/transport/tcp.newProcessor pkg/tcpip/transport/tcp/dispatcher.go:80 +0x78 goroutine 37 [select, 1 minutes]: gvisor.dev/gvisor/pkg/sleep.(*Sleeper).nextWaker(0xc000516200, 0x1, 0xc000516200) pkg/sleep/sleep_unsafe.go:190 +0x6a gvisor.dev/gvisor/pkg/sleep.(*Sleeper).Fetch(0xc000516200, 0xc0004b6101, 0x2, 0x0) pkg/sleep/sleep_unsafe.go:225 +0x38 gvisor.dev/gvisor/pkg/tcpip/transport/tcp.(*processor).handleSegments(0xc0004b61c0) pkg/tcpip/transport/tcp/dispatcher.go:106 +0x266 created by gvisor.dev/gvisor/pkg/tcpip/transport/tcp.newProcessor pkg/tcpip/transport/tcp/dispatcher.go:80 +0x78 goroutine 38 [select, 1 minutes]: gvisor.dev/gvisor/pkg/sleep.(*Sleeper).nextWaker(0xc0005165e0, 0x1, 0xc0005165e0) pkg/sleep/sleep_unsafe.go:190 +0x6a gvisor.dev/gvisor/pkg/sleep.(*Sleeper).Fetch(0xc0005165e0, 0xc0004b6201, 0x2, 0x0) pkg/sleep/sleep_unsafe.go:225 +0x38 gvisor.dev/gvisor/pkg/tcpip/transport/tcp.(*processor).handleSegments(0xc0004b6230) pkg/tcpip/transport/tcp/dispatcher.go:106 +0x266 created by gvisor.dev/gvisor/pkg/tcpip/transport/tcp.newProcessor pkg/tcpip/transport/tcp/dispatcher.go:80 +0x78 goroutine 39 [select, 1 minutes]: gvisor.dev/gvisor/pkg/sleep.(*Sleeper).nextWaker(0xc000516600, 0x1, 0xc000516600) pkg/sleep/sleep_unsafe.go:190 +0x6a gvisor.dev/gvisor/pkg/sleep.(*Sleeper).Fetch(0xc000516600, 0xc0004b6201, 0x2, 0x0) pkg/sleep/sleep_unsafe.go:225 +0x38 gvisor.dev/gvisor/pkg/tcpip/transport/tcp.(*processor).handleSegments(0xc0004b62a0) pkg/tcpip/transport/tcp/dispatcher.go:106 +0x266 created by gvisor.dev/gvisor/pkg/tcpip/transport/tcp.newProcessor pkg/tcpip/transport/tcp/dispatcher.go:80 +0x78 goroutine 40 [select, 1 minutes]: gvisor.dev/gvisor/pkg/sleep.(*Sleeper).nextWaker(0xc000516620, 0x1, 0xc000516620) pkg/sleep/sleep_unsafe.go:190 +0x6a gvisor.dev/gvisor/pkg/sleep.(*Sleeper).Fetch(0xc000516620, 0xc0004b6301, 0x2, 0x0) pkg/sleep/sleep_unsafe.go:225 +0x38 gvisor.dev/gvisor/pkg/tcpip/transport/tcp.(*processor).handleSegments(0xc0004b6310) pkg/tcpip/transport/tcp/dispatcher.go:106 +0x266 created by gvisor.dev/gvisor/pkg/tcpip/transport/tcp.newProcessor pkg/tcpip/transport/tcp/dispatcher.go:80 +0x78 goroutine 41 [select, 1 minutes]: gvisor.dev/gvisor/pkg/sleep.(*Sleeper).nextWaker(0xc000516640, 0x1, 0xc000516640) pkg/sleep/sleep_unsafe.go:190 +0x6a gvisor.dev/gvisor/pkg/sleep.(*Sleeper).Fetch(0xc000516640, 0xc0004b6301, 0x2, 0x0) pkg/sleep/sleep_unsafe.go:225 +0x38 gvisor.dev/gvisor/pkg/tcpip/transport/tcp.(*processor).handleSegments(0xc0004b6380) pkg/tcpip/transport/tcp/dispatcher.go:106 +0x266 created by gvisor.dev/gvisor/pkg/tcpip/transport/tcp.newProcessor pkg/tcpip/transport/tcp/dispatcher.go:80 +0x78 goroutine 42 [select, 1 minutes]: gvisor.dev/gvisor/pkg/sleep.(*Sleeper).nextWaker(0xc000516660, 0x1, 0xc000516660) pkg/sleep/sleep_unsafe.go:190 +0x6a gvisor.dev/gvisor/pkg/sleep.(*Sleeper).Fetch(0xc000516660, 0xc0004b6401, 0x2, 0x0) pkg/sleep/sleep_unsafe.go:225 +0x38 gvisor.dev/gvisor/pkg/tcpip/transport/tcp.(*processor).handleSegments(0xc0004b63f0) pkg/tcpip/transport/tcp/dispatcher.go:106 +0x266 created by gvisor.dev/gvisor/pkg/tcpip/transport/tcp.newProcessor pkg/tcpip/transport/tcp/dispatcher.go:80 +0x78 goroutine 43 [select, 1 minutes]: gvisor.dev/gvisor/pkg/sleep.(*Sleeper).nextWaker(0xc000516680, 0x1, 0xc000516680) pkg/sleep/sleep_unsafe.go:190 +0x6a gvisor.dev/gvisor/pkg/sleep.(*Sleeper).Fetch(0xc000516680, 0xc0004b6401, 0x2, 0x0) pkg/sleep/sleep_unsafe.go:225 +0x38 gvisor.dev/gvisor/pkg/tcpip/transport/tcp.(*processor).handleSegments(0xc0004b6460) pkg/tcpip/transport/tcp/dispatcher.go:106 +0x266 created by gvisor.dev/gvisor/pkg/tcpip/transport/tcp.newProcessor pkg/tcpip/transport/tcp/dispatcher.go:80 +0x78 goroutine 44 [select, 1 minutes]: gvisor.dev/gvisor/pkg/sleep.(*Sleeper).nextWaker(0xc0005166a0, 0x1, 0xc0005166a0) pkg/sleep/sleep_unsafe.go:190 +0x6a gvisor.dev/gvisor/pkg/sleep.(*Sleeper).Fetch(0xc0005166a0, 0xc0004b6501, 0x2, 0x0) pkg/sleep/sleep_unsafe.go:225 +0x38 gvisor.dev/gvisor/pkg/tcpip/transport/tcp.(*processor).handleSegments(0xc0004b64d0) pkg/tcpip/transport/tcp/dispatcher.go:106 +0x266 created by gvisor.dev/gvisor/pkg/tcpip/transport/tcp.newProcessor pkg/tcpip/transport/tcp/dispatcher.go:80 +0x78 goroutine 45 [select, 1 minutes]: gvisor.dev/gvisor/pkg/sleep.(*Sleeper).nextWaker(0xc0005165c0, 0x1, 0xc0005165c0) pkg/sleep/sleep_unsafe.go:190 +0x6a gvisor.dev/gvisor/pkg/sleep.(*Sleeper).Fetch(0xc0005165c0, 0xc0004b6501, 0x2, 0x0) pkg/sleep/sleep_unsafe.go:225 +0x38 gvisor.dev/gvisor/pkg/tcpip/transport/tcp.(*processor).handleSegments(0xc0004b6540) pkg/tcpip/transport/tcp/dispatcher.go:106 +0x266 created by gvisor.dev/gvisor/pkg/tcpip/transport/tcp.newProcessor pkg/tcpip/transport/tcp/dispatcher.go:80 +0x78 goroutine 46 [select, 1 minutes]: gvisor.dev/gvisor/pkg/sleep.(*Sleeper).nextWaker(0xc00000c040, 0x1, 0xc00000c040) pkg/sleep/sleep_unsafe.go:190 +0x6a gvisor.dev/gvisor/pkg/sleep.(*Sleeper).Fetch(0xc00000c040, 0xc0004b6501, 0x2, 0x0) pkg/sleep/sleep_unsafe.go:225 +0x38 gvisor.dev/gvisor/pkg/tcpip/transport/tcp.(*processor).handleSegments(0xc0004b65b0) pkg/tcpip/transport/tcp/dispatcher.go:106 +0x266 created by gvisor.dev/gvisor/pkg/tcpip/transport/tcp.newProcessor pkg/tcpip/transport/tcp/dispatcher.go:80 +0x78 goroutine 47 [select, 1 minutes]: gvisor.dev/gvisor/pkg/sleep.(*Sleeper).nextWaker(0xc00000c060, 0x1, 0xc00000c060) pkg/sleep/sleep_unsafe.go:190 +0x6a gvisor.dev/gvisor/pkg/sleep.(*Sleeper).Fetch(0xc00000c060, 0xc0004b6601, 0x2, 0x0) pkg/sleep/sleep_unsafe.go:225 +0x38 gvisor.dev/gvisor/pkg/tcpip/transport/tcp.(*processor).handleSegments(0xc0004b6620) pkg/tcpip/transport/tcp/dispatcher.go:106 +0x266 created by gvisor.dev/gvisor/pkg/tcpip/transport/tcp.newProcessor pkg/tcpip/transport/tcp/dispatcher.go:80 +0x78 goroutine 48 [select, 1 minutes]: gvisor.dev/gvisor/pkg/sleep.(*Sleeper).nextWaker(0xc00000c080, 0x1, 0xc00000c080) pkg/sleep/sleep_unsafe.go:190 +0x6a gvisor.dev/gvisor/pkg/sleep.(*Sleeper).Fetch(0xc00000c080, 0xc0004b6601, 0x2, 0x0) pkg/sleep/sleep_unsafe.go:225 +0x38 gvisor.dev/gvisor/pkg/tcpip/transport/tcp.(*processor).handleSegments(0xc0004b6690) pkg/tcpip/transport/tcp/dispatcher.go:106 +0x266 created by gvisor.dev/gvisor/pkg/tcpip/transport/tcp.newProcessor pkg/tcpip/transport/tcp/dispatcher.go:80 +0x78 goroutine 49 [select, 1 minutes]: gvisor.dev/gvisor/pkg/sleep.(*Sleeper).nextWaker(0xc00000c0a0, 0x1, 0xc00000c0a0) pkg/sleep/sleep_unsafe.go:190 +0x6a gvisor.dev/gvisor/pkg/sleep.(*Sleeper).Fetch(0xc00000c0a0, 0xc0004b6701, 0x2, 0x0) pkg/sleep/sleep_unsafe.go:225 +0x38 gvisor.dev/gvisor/pkg/tcpip/transport/tcp.(*processor).handleSegments(0xc0004b6700) pkg/tcpip/transport/tcp/dispatcher.go:106 +0x266 created by gvisor.dev/gvisor/pkg/tcpip/transport/tcp.newProcessor pkg/tcpip/transport/tcp/dispatcher.go:80 +0x78 goroutine 50 [select, 1 minutes]: gvisor.dev/gvisor/pkg/sleep.(*Sleeper).nextWaker(0xc00000c0c0, 0x1, 0xc00000c0c0) pkg/sleep/sleep_unsafe.go:190 +0x6a gvisor.dev/gvisor/pkg/sleep.(*Sleeper).Fetch(0xc00000c0c0, 0xc0004b6701, 0x2, 0x0) pkg/sleep/sleep_unsafe.go:225 +0x38 gvisor.dev/gvisor/pkg/tcpip/transport/tcp.(*processor).handleSegments(0xc0004b6770) pkg/tcpip/transport/tcp/dispatcher.go:106 +0x266 created by gvisor.dev/gvisor/pkg/tcpip/transport/tcp.newProcessor pkg/tcpip/transport/tcp/dispatcher.go:80 +0x78 goroutine 51 [select, 1 minutes]: gvisor.dev/gvisor/pkg/sleep.(*Sleeper).nextWaker(0xc00000c0e0, 0x1, 0xc00000c0e0) pkg/sleep/sleep_unsafe.go:190 +0x6a gvisor.dev/gvisor/pkg/sleep.(*Sleeper).Fetch(0xc00000c0e0, 0xc0004b6801, 0x2, 0x0) pkg/sleep/sleep_unsafe.go:225 +0x38 gvisor.dev/gvisor/pkg/tcpip/transport/tcp.(*processor).handleSegments(0xc0004b67e0) pkg/tcpip/transport/tcp/dispatcher.go:106 +0x266 created by gvisor.dev/gvisor/pkg/tcpip/transport/tcp.newProcessor pkg/tcpip/transport/tcp/dispatcher.go:80 +0x78 goroutine 52 [select, 1 minutes]: gvisor.dev/gvisor/pkg/sleep.(*Sleeper).nextWaker(0xc00000c100, 0x1, 0xc00000c100) pkg/sleep/sleep_unsafe.go:190 +0x6a gvisor.dev/gvisor/pkg/sleep.(*Sleeper).Fetch(0xc00000c100, 0xc0004b6801, 0x2, 0x0) pkg/sleep/sleep_unsafe.go:225 +0x38 gvisor.dev/gvisor/pkg/tcpip/transport/tcp.(*processor).handleSegments(0xc0004b6850) pkg/tcpip/transport/tcp/dispatcher.go:106 +0x266 created by gvisor.dev/gvisor/pkg/tcpip/transport/tcp.newProcessor pkg/tcpip/transport/tcp/dispatcher.go:80 +0x78 goroutine 53 [select, 1 minutes]: gvisor.dev/gvisor/pkg/sleep.(*Sleeper).nextWaker(0xc00000c020, 0x1, 0xc00000c020) pkg/sleep/sleep_unsafe.go:190 +0x6a gvisor.dev/gvisor/pkg/sleep.(*Sleeper).Fetch(0xc00000c020, 0xc0004b6801, 0x2, 0x0) pkg/sleep/sleep_unsafe.go:225 +0x38 gvisor.dev/gvisor/pkg/tcpip/transport/tcp.(*processor).handleSegments(0xc0004b68c0) pkg/tcpip/transport/tcp/dispatcher.go:106 +0x266 created by gvisor.dev/gvisor/pkg/tcpip/transport/tcp.newProcessor pkg/tcpip/transport/tcp/dispatcher.go:80 +0x78 goroutine 54 [select, 1 minutes]: gvisor.dev/gvisor/pkg/sleep.(*Sleeper).nextWaker(0xc000516020, 0x1, 0xc000516020) pkg/sleep/sleep_unsafe.go:190 +0x6a gvisor.dev/gvisor/pkg/sleep.(*Sleeper).Fetch(0xc000516020, 0xc0004b6901, 0x2, 0x0) pkg/sleep/sleep_unsafe.go:225 +0x38 gvisor.dev/gvisor/pkg/tcpip/transport/tcp.(*processor).handleSegments(0xc0004b6930) pkg/tcpip/transport/tcp/dispatcher.go:106 +0x266 created by gvisor.dev/gvisor/pkg/tcpip/transport/tcp.newProcessor pkg/tcpip/transport/tcp/dispatcher.go:80 +0x78 goroutine 55 [select, 1 minutes]: gvisor.dev/gvisor/pkg/sleep.(*Sleeper).nextWaker(0xc000516040, 0x1, 0xc000516040) pkg/sleep/sleep_unsafe.go:190 +0x6a gvisor.dev/gvisor/pkg/sleep.(*Sleeper).Fetch(0xc000516040, 0xc0004b6901, 0x2, 0x0) pkg/sleep/sleep_unsafe.go:225 +0x38 gvisor.dev/gvisor/pkg/tcpip/transport/tcp.(*processor).handleSegments(0xc0004b69a0) pkg/tcpip/transport/tcp/dispatcher.go:106 +0x266 created by gvisor.dev/gvisor/pkg/tcpip/transport/tcp.newProcessor pkg/tcpip/transport/tcp/dispatcher.go:80 +0x78 goroutine 56 [select, 1 minutes]: gvisor.dev/gvisor/pkg/sleep.(*Sleeper).nextWaker(0xc000516060, 0x1, 0xc000516060) pkg/sleep/sleep_unsafe.go:190 +0x6a gvisor.dev/gvisor/pkg/sleep.(*Sleeper).Fetch(0xc000516060, 0xc0004b6a01, 0x2, 0x0) pkg/sleep/sleep_unsafe.go:225 +0x38 gvisor.dev/gvisor/pkg/tcpip/transport/tcp.(*processor).handleSegments(0xc0004b6a10) pkg/tcpip/transport/tcp/dispatcher.go:106 +0x266 created by gvisor.dev/gvisor/pkg/tcpip/transport/tcp.newProcessor pkg/tcpip/transport/tcp/dispatcher.go:80 +0x78 goroutine 57 [select, 1 minutes]: gvisor.dev/gvisor/pkg/sleep.(*Sleeper).nextWaker(0xc000516080, 0x1, 0xc000516080) pkg/sleep/sleep_unsafe.go:190 +0x6a gvisor.dev/gvisor/pkg/sleep.(*Sleeper).Fetch(0xc000516080, 0xc0004b6a01, 0x2, 0x0) pkg/sleep/sleep_unsafe.go:225 +0x38 gvisor.dev/gvisor/pkg/tcpip/transport/tcp.(*processor).handleSegments(0xc0004b6a80) pkg/tcpip/transport/tcp/dispatcher.go:106 +0x266 created by gvisor.dev/gvisor/pkg/tcpip/transport/tcp.newProcessor pkg/tcpip/transport/tcp/dispatcher.go:80 +0x78 goroutine 58 [select, 1 minutes]: gvisor.dev/gvisor/pkg/sleep.(*Sleeper).nextWaker(0xc0005160a0, 0x1, 0xc0005160a0) pkg/sleep/sleep_unsafe.go:190 +0x6a gvisor.dev/gvisor/pkg/sleep.(*Sleeper).Fetch(0xc0005160a0, 0xc0004b6b01, 0x2, 0x0) pkg/sleep/sleep_unsafe.go:225 +0x38 gvisor.dev/gvisor/pkg/tcpip/transport/tcp.(*processor).handleSegments(0xc0004b6af0) pkg/tcpip/transport/tcp/dispatcher.go:106 +0x266 created by gvisor.dev/gvisor/pkg/tcpip/transport/tcp.newProcessor pkg/tcpip/transport/tcp/dispatcher.go:80 +0x78 goroutine 59 [select, 1 minutes]: gvisor.dev/gvisor/pkg/sleep.(*Sleeper).nextWaker(0xc0005160c0, 0x1, 0xc0005160c0) pkg/sleep/sleep_unsafe.go:190 +0x6a gvisor.dev/gvisor/pkg/sleep.(*Sleeper).Fetch(0xc0005160c0, 0xc0004b6b01, 0x2, 0x0) pkg/sleep/sleep_unsafe.go:225 +0x38 gvisor.dev/gvisor/pkg/tcpip/transport/tcp.(*processor).handleSegments(0xc0004b6b60) pkg/tcpip/transport/tcp/dispatcher.go:106 +0x266 created by gvisor.dev/gvisor/pkg/tcpip/transport/tcp.newProcessor pkg/tcpip/transport/tcp/dispatcher.go:80 +0x78 goroutine 60 [select, 1 minutes]: gvisor.dev/gvisor/pkg/sleep.(*Sleeper).nextWaker(0xc0005160e0, 0x1, 0xc0005160e0) pkg/sleep/sleep_unsafe.go:190 +0x6a gvisor.dev/gvisor/pkg/sleep.(*Sleeper).Fetch(0xc0005160e0, 0xc0004b6c01, 0x2, 0x0) pkg/sleep/sleep_unsafe.go:225 +0x38 gvisor.dev/gvisor/pkg/tcpip/transport/tcp.(*processor).handleSegments(0xc0004b6bd0) pkg/tcpip/transport/tcp/dispatcher.go:106 +0x266 created by gvisor.dev/gvisor/pkg/tcpip/transport/tcp.newProcessor pkg/tcpip/transport/tcp/dispatcher.go:80 +0x78 goroutine 61 [select, 1 minutes]: gvisor.dev/gvisor/pkg/sleep.(*Sleeper).nextWaker(0xc000516100, 0x1, 0xc000516100) pkg/sleep/sleep_unsafe.go:190 +0x6a gvisor.dev/gvisor/pkg/sleep.(*Sleeper).Fetch(0xc000516100, 0xc0004b6c01, 0x2, 0x0) pkg/sleep/sleep_unsafe.go:225 +0x38 gvisor.dev/gvisor/pkg/tcpip/transport/tcp.(*processor).handleSegments(0xc0004b6c40) pkg/tcpip/transport/tcp/dispatcher.go:106 +0x266 created by gvisor.dev/gvisor/pkg/tcpip/transport/tcp.newProcessor pkg/tcpip/transport/tcp/dispatcher.go:80 +0x78 goroutine 62 [select, 1 minutes]: gvisor.dev/gvisor/pkg/sleep.(*Sleeper).nextWaker(0xc000516120, 0x1, 0xc000516120) pkg/sleep/sleep_unsafe.go:190 +0x6a gvisor.dev/gvisor/pkg/sleep.(*Sleeper).Fetch(0xc000516120, 0xc0004b6c01, 0x2, 0x0) pkg/sleep/sleep_unsafe.go:225 +0x38 gvisor.dev/gvisor/pkg/tcpip/transport/tcp.(*processor).handleSegments(0xc0004b6cb0) pkg/tcpip/transport/tcp/dispatcher.go:106 +0x266 created by gvisor.dev/gvisor/pkg/tcpip/transport/tcp.newProcessor pkg/tcpip/transport/tcp/dispatcher.go:80 +0x78 goroutine 63 [select, 1 minutes]: gvisor.dev/gvisor/pkg/sleep.(*Sleeper).nextWaker(0xc000516140, 0x1, 0xc000516140) pkg/sleep/sleep_unsafe.go:190 +0x6a gvisor.dev/gvisor/pkg/sleep.(*Sleeper).Fetch(0xc000516140, 0xc0004b6d01, 0x2, 0x0) pkg/sleep/sleep_unsafe.go:225 +0x38 gvisor.dev/gvisor/pkg/tcpip/transport/tcp.(*processor).handleSegments(0xc0004b6d20) pkg/tcpip/transport/tcp/dispatcher.go:106 +0x266 created by gvisor.dev/gvisor/pkg/tcpip/transport/tcp.newProcessor pkg/tcpip/transport/tcp/dispatcher.go:80 +0x78 goroutine 64 [select, 1 minutes]: gvisor.dev/gvisor/pkg/sleep.(*Sleeper).nextWaker(0xc000516160, 0x1, 0xc000516160) pkg/sleep/sleep_unsafe.go:190 +0x6a gvisor.dev/gvisor/pkg/sleep.(*Sleeper).Fetch(0xc000516160, 0xc0004b6d01, 0x2, 0x0) pkg/sleep/sleep_unsafe.go:225 +0x38 gvisor.dev/gvisor/pkg/tcpip/transport/tcp.(*processor).handleSegments(0xc0004b6d90) pkg/tcpip/transport/tcp/dispatcher.go:106 +0x266 created by gvisor.dev/gvisor/pkg/tcpip/transport/tcp.newProcessor pkg/tcpip/transport/tcp/dispatcher.go:80 +0x78 goroutine 65 [select, 1 minutes]: gvisor.dev/gvisor/pkg/sleep.(*Sleeper).nextWaker(0xc000516180, 0x1, 0xc000516180) pkg/sleep/sleep_unsafe.go:190 +0x6a gvisor.dev/gvisor/pkg/sleep.(*Sleeper).Fetch(0xc000516180, 0xc0004b6e01, 0x2, 0x0) pkg/sleep/sleep_unsafe.go:225 +0x38 gvisor.dev/gvisor/pkg/tcpip/transport/tcp.(*processor).handleSegments(0xc0004b6e00) pkg/tcpip/transport/tcp/dispatcher.go:106 +0x266 created by gvisor.dev/gvisor/pkg/tcpip/transport/tcp.newProcessor pkg/tcpip/transport/tcp/dispatcher.go:80 +0x78 goroutine 66 [select, 1 minutes]: gvisor.dev/gvisor/pkg/sleep.(*Sleeper).nextWaker(0xc0005161a0, 0x1, 0xc0005161a0) pkg/sleep/sleep_unsafe.go:190 +0x6a gvisor.dev/gvisor/pkg/sleep.(*Sleeper).Fetch(0xc0005161a0, 0xc0004b6e01, 0x2, 0x0) pkg/sleep/sleep_unsafe.go:225 +0x38 gvisor.dev/gvisor/pkg/tcpip/transport/tcp.(*processor).handleSegments(0xc0004b6e70) pkg/tcpip/transport/tcp/dispatcher.go:106 +0x266 created by gvisor.dev/gvisor/pkg/tcpip/transport/tcp.newProcessor pkg/tcpip/transport/tcp/dispatcher.go:80 +0x78 goroutine 67 [select, 1 minutes]: gvisor.dev/gvisor/pkg/sleep.(*Sleeper).nextWaker(0xc0005161c0, 0x1, 0xc0005161c0) pkg/sleep/sleep_unsafe.go:190 +0x6a gvisor.dev/gvisor/pkg/sleep.(*Sleeper).Fetch(0xc0005161c0, 0xc0004b6f01, 0x2, 0x0) pkg/sleep/sleep_unsafe.go:225 +0x38 gvisor.dev/gvisor/pkg/tcpip/transport/tcp.(*processor).handleSegments(0xc0004b6ee0) pkg/tcpip/transport/tcp/dispatcher.go:106 +0x266 created by gvisor.dev/gvisor/pkg/tcpip/transport/tcp.newProcessor pkg/tcpip/transport/tcp/dispatcher.go:80 +0x78 goroutine 68 [select, 1 minutes]: gvisor.dev/gvisor/pkg/sleep.(*Sleeper).nextWaker(0xc0005161e0, 0x1, 0xc0005161e0) pkg/sleep/sleep_unsafe.go:190 +0x6a gvisor.dev/gvisor/pkg/sleep.(*Sleeper).Fetch(0xc0005161e0, 0xc0004b6f01, 0x2, 0x0) pkg/sleep/sleep_unsafe.go:225 +0x38 gvisor.dev/gvisor/pkg/tcpip/transport/tcp.(*processor).handleSegments(0xc0004b6f50) pkg/tcpip/transport/tcp/dispatcher.go:106 +0x266 created by gvisor.dev/gvisor/pkg/tcpip/transport/tcp.newProcessor pkg/tcpip/transport/tcp/dispatcher.go:80 +0x78 goroutine 69 [select, 1 minutes]: gvisor.dev/gvisor/pkg/sleep.(*Sleeper).nextWaker(0xc000516000, 0x1, 0xc000516000) pkg/sleep/sleep_unsafe.go:190 +0x6a gvisor.dev/gvisor/pkg/sleep.(*Sleeper).Fetch(0xc000516000, 0xc0004b6f01, 0x2, 0x0) pkg/sleep/sleep_unsafe.go:225 +0x38 gvisor.dev/gvisor/pkg/tcpip/transport/tcp.(*processor).handleSegments(0xc0004b6fc0) pkg/tcpip/transport/tcp/dispatcher.go:106 +0x266 created by gvisor.dev/gvisor/pkg/tcpip/transport/tcp.newProcessor pkg/tcpip/transport/tcp/dispatcher.go:80 +0x78 goroutine 70 [select, 1 minutes]: gvisor.dev/gvisor/pkg/sleep.(*Sleeper).nextWaker(0xc000516420, 0x1, 0xc000516420) pkg/sleep/sleep_unsafe.go:190 +0x6a gvisor.dev/gvisor/pkg/sleep.(*Sleeper).Fetch(0xc000516420, 0xc0004b7001, 0x2, 0x0) pkg/sleep/sleep_unsafe.go:225 +0x38 gvisor.dev/gvisor/pkg/tcpip/transport/tcp.(*processor).handleSegments(0xc0004b7030) pkg/tcpip/transport/tcp/dispatcher.go:106 +0x266 created by gvisor.dev/gvisor/pkg/tcpip/transport/tcp.newProcessor pkg/tcpip/transport/tcp/dispatcher.go:80 +0x78 goroutine 71 [select, 1 minutes]: gvisor.dev/gvisor/pkg/sleep.(*Sleeper).nextWaker(0xc000516440, 0x1, 0xc000516440) pkg/sleep/sleep_unsafe.go:190 +0x6a gvisor.dev/gvisor/pkg/sleep.(*Sleeper).Fetch(0xc000516440, 0xc0004b7001, 0x2, 0x0) pkg/sleep/sleep_unsafe.go:225 +0x38 gvisor.dev/gvisor/pkg/tcpip/transport/tcp.(*processor).handleSegments(0xc0004b70a0) pkg/tcpip/transport/tcp/dispatcher.go:106 +0x266 created by gvisor.dev/gvisor/pkg/tcpip/transport/tcp.newProcessor pkg/tcpip/transport/tcp/dispatcher.go:80 +0x78 goroutine 72 [select, 1 minutes]: gvisor.dev/gvisor/pkg/sleep.(*Sleeper).nextWaker(0xc000516460, 0x1, 0xc000516460) pkg/sleep/sleep_unsafe.go:190 +0x6a gvisor.dev/gvisor/pkg/sleep.(*Sleeper).Fetch(0xc000516460, 0xc0004b7101, 0x2, 0x0) pkg/sleep/sleep_unsafe.go:225 +0x38 gvisor.dev/gvisor/pkg/tcpip/transport/tcp.(*processor).handleSegments(0xc0004b7110) pkg/tcpip/transport/tcp/dispatcher.go:106 +0x266 created by gvisor.dev/gvisor/pkg/tcpip/transport/tcp.newProcessor pkg/tcpip/transport/tcp/dispatcher.go:80 +0x78 goroutine 73 [select, 1 minutes]: gvisor.dev/gvisor/pkg/sleep.(*Sleeper).nextWaker(0xc000516480, 0x1, 0xc000516480) pkg/sleep/sleep_unsafe.go:190 +0x6a gvisor.dev/gvisor/pkg/sleep.(*Sleeper).Fetch(0xc000516480, 0xc0004b7101, 0x2, 0x0) pkg/sleep/sleep_unsafe.go:225 +0x38 gvisor.dev/gvisor/pkg/tcpip/transport/tcp.(*processor).handleSegments(0xc0004b7180) pkg/tcpip/transport/tcp/dispatcher.go:106 +0x266 created by gvisor.dev/gvisor/pkg/tcpip/transport/tcp.newProcessor pkg/tcpip/transport/tcp/dispatcher.go:80 +0x78 goroutine 74 [select, 1 minutes]: gvisor.dev/gvisor/pkg/sleep.(*Sleeper).nextWaker(0xc0005164a0, 0x1, 0xc0005164a0) pkg/sleep/sleep_unsafe.go:190 +0x6a gvisor.dev/gvisor/pkg/sleep.(*Sleeper).Fetch(0xc0005164a0, 0xc0004b7201, 0x2, 0x0) pkg/sleep/sleep_unsafe.go:225 +0x38 gvisor.dev/gvisor/pkg/tcpip/transport/tcp.(*processor).handleSegments(0xc0004b71f0) pkg/tcpip/transport/tcp/dispatcher.go:106 +0x266 created by gvisor.dev/gvisor/pkg/tcpip/transport/tcp.newProcessor pkg/tcpip/transport/tcp/dispatcher.go:80 +0x78 goroutine 75 [select, 1 minutes]: gvisor.dev/gvisor/pkg/sleep.(*Sleeper).nextWaker(0xc0005164c0, 0x1, 0xc0005164c0) pkg/sleep/sleep_unsafe.go:190 +0x6a gvisor.dev/gvisor/pkg/sleep.(*Sleeper).Fetch(0xc0005164c0, 0xc0004b7201, 0x2, 0x0) pkg/sleep/sleep_unsafe.go:225 +0x38 gvisor.dev/gvisor/pkg/tcpip/transport/tcp.(*processor).handleSegments(0xc0004b7260) pkg/tcpip/transport/tcp/dispatcher.go:106 +0x266 created by gvisor.dev/gvisor/pkg/tcpip/transport/tcp.newProcessor pkg/tcpip/transport/tcp/dispatcher.go:80 +0x78 goroutine 76 [select, 1 minutes]: gvisor.dev/gvisor/pkg/sleep.(*Sleeper).nextWaker(0xc0005164e0, 0x1, 0xc0005164e0) pkg/sleep/sleep_unsafe.go:190 +0x6a gvisor.dev/gvisor/pkg/sleep.(*Sleeper).Fetch(0xc0005164e0, 0xc0004b7301, 0x2, 0x0) pkg/sleep/sleep_unsafe.go:225 +0x38 gvisor.dev/gvisor/pkg/tcpip/transport/tcp.(*processor).handleSegments(0xc0004b72d0) pkg/tcpip/transport/tcp/dispatcher.go:106 +0x266 created by gvisor.dev/gvisor/pkg/tcpip/transport/tcp.newProcessor pkg/tcpip/transport/tcp/dispatcher.go:80 +0x78 goroutine 77 [select, 1 minutes]: gvisor.dev/gvisor/pkg/sleep.(*Sleeper).nextWaker(0xc000516400, 0x1, 0xc000516400) pkg/sleep/sleep_unsafe.go:190 +0x6a gvisor.dev/gvisor/pkg/sleep.(*Sleeper).Fetch(0xc000516400, 0xc0004b7301, 0x2, 0x0) pkg/sleep/sleep_unsafe.go:225 +0x38 gvisor.dev/gvisor/pkg/tcpip/transport/tcp.(*processor).handleSegments(0xc0004b7340) pkg/tcpip/transport/tcp/dispatcher.go:106 +0x266 created by gvisor.dev/gvisor/pkg/tcpip/transport/tcp.newProcessor pkg/tcpip/transport/tcp/dispatcher.go:80 +0x78 goroutine 78 [select, 1 minutes]: gvisor.dev/gvisor/pkg/sleep.(*Sleeper).nextWaker(0xc000516520, 0x1, 0xc000516520) pkg/sleep/sleep_unsafe.go:190 +0x6a gvisor.dev/gvisor/pkg/sleep.(*Sleeper).Fetch(0xc000516520, 0xc0004b7301, 0x2, 0x0) pkg/sleep/sleep_unsafe.go:225 +0x38 gvisor.dev/gvisor/pkg/tcpip/transport/tcp.(*processor).handleSegments(0xc0004b73b0) pkg/tcpip/transport/tcp/dispatcher.go:106 +0x266 created by gvisor.dev/gvisor/pkg/tcpip/transport/tcp.newProcessor pkg/tcpip/transport/tcp/dispatcher.go:80 +0x78 goroutine 79 [select, 1 minutes]: gvisor.dev/gvisor/pkg/sleep.(*Sleeper).nextWaker(0xc000516540, 0x1, 0xc000516540) pkg/sleep/sleep_unsafe.go:190 +0x6a gvisor.dev/gvisor/pkg/sleep.(*Sleeper).Fetch(0xc000516540, 0xc0004b7401, 0x2, 0x0) pkg/sleep/sleep_unsafe.go:225 +0x38 gvisor.dev/gvisor/pkg/tcpip/transport/tcp.(*processor).handleSegments(0xc0004b7420) pkg/tcpip/transport/tcp/dispatcher.go:106 +0x266 created by gvisor.dev/gvisor/pkg/tcpip/transport/tcp.newProcessor pkg/tcpip/transport/tcp/dispatcher.go:80 +0x78 goroutine 80 [select, 1 minutes]: gvisor.dev/gvisor/pkg/sleep.(*Sleeper).nextWaker(0xc000516560, 0x1, 0xc000516560) pkg/sleep/sleep_unsafe.go:190 +0x6a gvisor.dev/gvisor/pkg/sleep.(*Sleeper).Fetch(0xc000516560, 0xc0004b7401, 0x2, 0x0) pkg/sleep/sleep_unsafe.go:225 +0x38 gvisor.dev/gvisor/pkg/tcpip/transport/tcp.(*processor).handleSegments(0xc0004b7490) pkg/tcpip/transport/tcp/dispatcher.go:106 +0x266 created by gvisor.dev/gvisor/pkg/tcpip/transport/tcp.newProcessor pkg/tcpip/transport/tcp/dispatcher.go:80 +0x78 goroutine 81 [select, 1 minutes]: gvisor.dev/gvisor/pkg/sleep.(*Sleeper).nextWaker(0xc000516500, 0x1, 0xc000516500) pkg/sleep/sleep_unsafe.go:190 +0x6a gvisor.dev/gvisor/pkg/sleep.(*Sleeper).Fetch(0xc000516500, 0xc0004b7501, 0x2, 0x0) pkg/sleep/sleep_unsafe.go:225 +0x38 gvisor.dev/gvisor/pkg/tcpip/transport/tcp.(*processor).handleSegments(0xc0004b7500) pkg/tcpip/transport/tcp/dispatcher.go:106 +0x266 created by gvisor.dev/gvisor/pkg/tcpip/transport/tcp.newProcessor pkg/tcpip/transport/tcp/dispatcher.go:80 +0x78 goroutine 82 [select, 1 minutes]: gvisor.dev/gvisor/pkg/sleep.(*Sleeper).nextWaker(0xc0005165a0, 0x1, 0xc0005165a0) pkg/sleep/sleep_unsafe.go:190 +0x6a gvisor.dev/gvisor/pkg/sleep.(*Sleeper).Fetch(0xc0005165a0, 0xc0004b7501, 0x2, 0x0) pkg/sleep/sleep_unsafe.go:225 +0x38 gvisor.dev/gvisor/pkg/tcpip/transport/tcp.(*processor).handleSegments(0xc0004b7570) pkg/tcpip/transport/tcp/dispatcher.go:106 +0x266 created by gvisor.dev/gvisor/pkg/tcpip/transport/tcp.newProcessor pkg/tcpip/transport/tcp/dispatcher.go:80 +0x78 goroutine 83 [select, 1 minutes]: gvisor.dev/gvisor/pkg/sleep.(*Sleeper).nextWaker(0xc000516580, 0x1, 0xc000516580) pkg/sleep/sleep_unsafe.go:190 +0x6a gvisor.dev/gvisor/pkg/sleep.(*Sleeper).Fetch(0xc000516580, 0xc0004b7601, 0x2, 0x0) pkg/sleep/sleep_unsafe.go:225 +0x38 gvisor.dev/gvisor/pkg/tcpip/transport/tcp.(*processor).handleSegments(0xc0004b75e0) pkg/tcpip/transport/tcp/dispatcher.go:106 +0x266 created by gvisor.dev/gvisor/pkg/tcpip/transport/tcp.newProcessor pkg/tcpip/transport/tcp/dispatcher.go:80 +0x78 goroutine 84 [select, 1 minutes]: gvisor.dev/gvisor/pkg/sleep.(*Sleeper).nextWaker(0xc0005166c0, 0x1, 0xc0005166c0) pkg/sleep/sleep_unsafe.go:190 +0x6a gvisor.dev/gvisor/pkg/sleep.(*Sleeper).Fetch(0xc0005166c0, 0xc0004b7601, 0x2, 0x0) pkg/sleep/sleep_unsafe.go:225 +0x38 gvisor.dev/gvisor/pkg/tcpip/transport/tcp.(*processor).handleSegments(0xc0004b7650) pkg/tcpip/transport/tcp/dispatcher.go:106 +0x266 created by gvisor.dev/gvisor/pkg/tcpip/transport/tcp.newProcessor pkg/tcpip/transport/tcp/dispatcher.go:80 +0x78 goroutine 85 [select, 1 minutes]: gvisor.dev/gvisor/pkg/sleep.(*Sleeper).nextWaker(0xc00034f7a0, 0x1, 0xc00034f7a0) pkg/sleep/sleep_unsafe.go:190 +0x6a gvisor.dev/gvisor/pkg/sleep.(*Sleeper).Fetch(0xc00034f7a0, 0xc0004b7601, 0x2, 0x0) pkg/sleep/sleep_unsafe.go:225 +0x38 gvisor.dev/gvisor/pkg/tcpip/transport/tcp.(*processor).handleSegments(0xc0004b76c0) pkg/tcpip/transport/tcp/dispatcher.go:106 +0x266 created by gvisor.dev/gvisor/pkg/tcpip/transport/tcp.newProcessor pkg/tcpip/transport/tcp/dispatcher.go:80 +0x78 goroutine 261 [select]: gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).block(0xc000924a80, 0xc0009d3740, 0xc0008347e0, 0x0, 0x0) pkg/sentry/kernel/task_block.go:138 +0x16c gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).BlockWithDeadline(0xc000924a80, 0xc0009d3740, 0x825c01, 0x23ea959411, 0x0, 0x0) pkg/sentry/kernel/task_block.go:80 +0x81 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).BlockWithTimeout(0xc000924a80, 0xc0009d3740, 0xf5fd01, 0xdf8475800, 0x1b0f710, 0x924a00, 0xc4ffffffff) pkg/sentry/kernel/task_block.go:42 +0xaa gvisor.dev/gvisor/pkg/sentry/syscalls/linux.futexWaitDuration(0xc000924a80, 0xdf8475800, 0x0, 0x1b0f710, 0x0, 0xffffffff, 0x0, 0x4e20, 0x15f24aff1d) pkg/sentry/syscalls/linux/sys_futex.go:97 +0xd2 gvisor.dev/gvisor/pkg/sentry/syscalls/linux.Futex(0xc000924a80, 0x1b0f710, 0x0, 0x0, 0xc4200cbea8, 0x0, 0x0, 0x0, 0x0, 0x0, ...) pkg/sentry/syscalls/linux/sys_futex.go:206 +0x100 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).executeSyscall(0xc000924a80, 0xca, 0x1b0f710, 0x0, 0x0, 0xc4200cbea8, 0x0, 0x0, 0xc000628b10, 0xe03180, ...) pkg/sentry/kernel/task_syscall.go:170 +0x122 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscallInvoke(0xc000924a80, 0xca, 0x1b0f710, 0x0, 0x0, 0xc4200cbea8, 0x0, 0x0, 0x0, 0x0) pkg/sentry/kernel/task_syscall.go:291 +0x66 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscallEnter(0xc000924a80, 0xca, 0x1b0f710, 0x0, 0x0, 0xc4200cbea8, 0x0, 0x0, 0x0, 0x0) pkg/sentry/kernel/task_syscall.go:252 +0x96 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscall(0xc000924a80, 0x2, 0xc00000d3e0) pkg/sentry/kernel/task_syscall.go:227 +0x139 gvisor.dev/gvisor/pkg/sentry/kernel.(*runApp).execute(0x0, 0xc000924a80, 0xf4eea0, 0x0) pkg/sentry/kernel/task_run.go:258 +0xed5 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).run(0xc000924a80, 0x1c) pkg/sentry/kernel/task_run.go:92 +0x18b created by gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).Start pkg/sentry/kernel/task_start.go:315 +0xfe goroutine 12 [syscall, 1 minutes]: syscall.Syscall6(0x10f, 0xc00070bed0, 0x2, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0) src/syscall/asm_linux_amd64.s:41 +0x5 gvisor.dev/gvisor/pkg/unet.(*Socket).wait(0xc0002decc0, 0xffffffffffffff00, 0x0, 0x0) pkg/unet/unet_unsafe.go:55 +0x9a gvisor.dev/gvisor/pkg/unet.(*ServerSocket).Accept(0xc00000e020, 0x0, 0x0, 0x0) pkg/unet/unet.go:539 +0x11a gvisor.dev/gvisor/pkg/control/server.(*Server).serve(0xc000720400) pkg/control/server/server.go:99 +0x32 gvisor.dev/gvisor/pkg/control/server.(*Server).StartServing.func1(0xc000720400) pkg/control/server/server.go:87 +0x2b created by gvisor.dev/gvisor/pkg/control/server.(*Server).StartServing pkg/control/server/server.go:86 +0x92 goroutine 216 [select, 1 minutes]: gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).block(0xc0001ed500, 0xc00050ad20, 0x0, 0x1, 0x0) pkg/sentry/kernel/task_block.go:138 +0x16c gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).BlockWithTimeout(0xc0001ed500, 0xc00050ad20, 0xf5fd00, 0x0, 0xc42056c148, 0x0, 0xffffffff) pkg/sentry/kernel/task_block.go:37 +0x1ec gvisor.dev/gvisor/pkg/sentry/syscalls/linux.futexWaitDuration(0xc0001ed500, 0x0, 0xc0001ed501, 0xc42056c148, 0x673a00, 0xffffffff, 0x0, 0x0, 0x0) pkg/sentry/syscalls/linux/sys_futex.go:97 +0xd2 gvisor.dev/gvisor/pkg/sentry/syscalls/linux.Futex(0xc0001ed500, 0xc42056c148, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, ...) pkg/sentry/syscalls/linux/sys_futex.go:206 +0x100 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).executeSyscall(0xc0001ed500, 0xca, 0xc42056c148, 0x0, 0x0, 0x0, 0x0, 0x0, 0xc0004a4410, 0xe03180, ...) pkg/sentry/kernel/task_syscall.go:170 +0x122 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscallInvoke(0xc0001ed500, 0xca, 0xc42056c148, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0) pkg/sentry/kernel/task_syscall.go:291 +0x66 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscallEnter(0xc0001ed500, 0xca, 0xc42056c148, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0) pkg/sentry/kernel/task_syscall.go:252 +0x96 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscall(0xc0001ed500, 0x2, 0xc00026eaa0) pkg/sentry/kernel/task_syscall.go:227 +0x139 gvisor.dev/gvisor/pkg/sentry/kernel.(*runApp).execute(0x0, 0xc0001ed500, 0xf4eea0, 0x0) pkg/sentry/kernel/task_run.go:258 +0xed5 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).run(0xc0001ed500, 0x17) pkg/sentry/kernel/task_run.go:92 +0x18b created by gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).Start pkg/sentry/kernel/task_start.go:315 +0xfe goroutine 106 [syscall, 1 minutes]: syscall.Syscall6(0x119, 0x15, 0xc0004ffb00, 0x64, 0xffffffffffffffff, 0x0, 0x0, 0x0, 0x0, 0x0) src/syscall/asm_linux_amd64.s:41 +0x5 gvisor.dev/gvisor/pkg/fdnotifier.epollWait(0x15, 0xc0004ffb00, 0x64, 0x64, 0xffffffffffffffff, 0x0, 0x0, 0x0) pkg/fdnotifier/poll_unsafe.go:77 +0x74 gvisor.dev/gvisor/pkg/fdnotifier.(*notifier).waitAndNotify(0xc00000d200, 0x0, 0x43e0c8) pkg/fdnotifier/fdnotifier.go:149 +0x7a created by gvisor.dev/gvisor/pkg/fdnotifier.newNotifier pkg/fdnotifier/fdnotifier.go:64 +0xaa goroutine 107 [syscall, 1 minutes]: syscall.Syscall6(0x10f, 0xc00072c090, 0x1, 0x0, 0x0, 0x0, 0x0, 0x40c416, 0x28, 0xc00035c010) src/syscall/asm_linux_amd64.s:41 +0x5 golang.org/x/sys/unix.ppoll(0xc00072c090, 0x1, 0x0, 0x0, 0x7f73ee828560, 0x0, 0x15aa3c0) external/org_golang_x_sys/unix/zsyscall_linux.go:87 +0x88 golang.org/x/sys/unix.Ppoll(0xc00072c090, 0x1, 0x1, 0x0, 0x0, 0x0, 0xc00072c090, 0x8) external/org_golang_x_sys/unix/syscall_linux.go:142 +0x9d gvisor.dev/gvisor/runsc/boot.(*Loader).startGoferMonitor.func1.1(0x8, 0x1, 0xc00072c090, 0xc00072c090) runsc/boot/loader.go:710 +0x44 gvisor.dev/gvisor/runsc/specutils.RetryEintr(0xc0004fbf80, 0x0, 0x0, 0x0, 0x1) runsc/specutils/specutils.go:475 +0x27 gvisor.dev/gvisor/runsc/boot.(*Loader).startGoferMonitor.func1(0x7fff6dceefca, 0x1e, 0xc000322328, 0x1, 0x1, 0xc0001ce000) runsc/boot/loader.go:708 +0x1b9 created by gvisor.dev/gvisor/runsc/boot.(*Loader).startGoferMonitor runsc/boot/loader.go:699 +0x71 goroutine 108 [syscall, 1 minutes]: syscall.Syscall6(0x10f, 0xc0004f3770, 0x1, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0) src/syscall/asm_linux_amd64.s:41 +0x5 golang.org/x/sys/unix.ppoll(0xc0004f3770, 0x1, 0x0, 0x0, 0x0, 0x0, 0x0) external/org_golang_x_sys/unix/zsyscall_linux.go:87 +0x88 golang.org/x/sys/unix.Ppoll(0xc0004f3770, 0x1, 0x1, 0x0, 0x0, 0x0, 0x0, 0x0) external/org_golang_x_sys/unix/syscall_linux.go:142 +0x9d gvisor.dev/gvisor/pkg/p9.(*Client).watch(0xc00026c200, 0xc0005548d0) pkg/p9/client.go:252 +0xb3 created by gvisor.dev/gvisor/pkg/p9.NewClient pkg/p9/client.go:232 +0x417 goroutine 109 [select, 1 minutes]: gvisor.dev/gvisor/pkg/sentry/kernel/time.(*Timer).runGoroutine(0xc00034cea0) pkg/sentry/kernel/time/time.go:515 +0xb9 created by gvisor.dev/gvisor/pkg/sentry/kernel/time.(*Timer).init pkg/sentry/kernel/time/time.go:494 +0x139 goroutine 110 [syscall, 1 minutes]: os/signal.signal_recv(0x0) GOROOT/src/runtime/sigqueue.go:147 +0x9c os/signal.loop() GOROOT/src/os/signal/signal_unix.go:23 +0x22 created by os/signal.Notify.func1 GOROOT/src/os/signal/signal.go:127 +0x44 goroutine 127 [select, 1 minutes]: reflect.rselect(0xc0001ccd80, 0x22, 0x22, 0xc0001ccd80, 0x40c416) GOROOT/src/runtime/select.go:544 +0x38a reflect.Select(0xc000326000, 0x22, 0x49, 0x20, 0x21, 0xc000326000, 0x20, 0x49) GOROOT/src/reflect/value.go:2229 +0x170 gvisor.dev/gvisor/pkg/sentry/sighandling.handleSignals(0xc0005b4000, 0x21, 0x40, 0xc0002b65d0, 0xc00050aea0, 0xc00050af00) pkg/sentry/sighandling/sighandling.go:44 +0x376 created by gvisor.dev/gvisor/pkg/sentry/sighandling.StartSignalForwarding pkg/sentry/sighandling/sighandling.go:96 +0x23d goroutine 128 [select, 1 minutes]: gvisor.dev/gvisor/pkg/sentry/watchdog.(*Watchdog).loop(0xc0001c4100) pkg/sentry/watchdog/watchdog.go:232 +0xce created by gvisor.dev/gvisor/pkg/sentry/watchdog.(*Watchdog).Start pkg/sentry/watchdog/watchdog.go:191 +0x1b7 goroutine 209 [select]: gvisor.dev/gvisor/pkg/sentry/kernel/time.(*Timer).runGoroutine(0xc000488000) pkg/sentry/kernel/time/time.go:515 +0xb9 created by gvisor.dev/gvisor/pkg/sentry/kernel/time.(*Timer).init pkg/sentry/kernel/time/time.go:494 +0x139 goroutine 210 [select, 1 minutes]: gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).block(0xc0002b0000, 0xc00050ae40, 0x0, 0x1, 0x0) pkg/sentry/kernel/task_block.go:138 +0x16c gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).BlockWithTimeout(0xc0002b0000, 0xc00050ae40, 0xf5fd00, 0x0, 0x548a688, 0x0, 0xc0ffffffff) pkg/sentry/kernel/task_block.go:37 +0x1ec gvisor.dev/gvisor/pkg/sentry/syscalls/linux.futexWaitDuration(0xc0002b0000, 0x0, 0xc0002b0001, 0x548a688, 0x0, 0xffffffff, 0x1, 0x0, 0x0) pkg/sentry/syscalls/linux/sys_futex.go:97 +0xd2 gvisor.dev/gvisor/pkg/sentry/syscalls/linux.Futex(0xc0002b0000, 0x548a688, 0x0, 0x0, 0x0, 0x0, 0x0, 0x1, 0x0, 0x0, ...) pkg/sentry/syscalls/linux/sys_futex.go:206 +0x100 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).executeSyscall(0xc0002b0000, 0xca, 0x548a688, 0x0, 0x0, 0x0, 0x0, 0x0, 0xc00092e090, 0xe03180, ...) pkg/sentry/kernel/task_syscall.go:170 +0x122 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscallInvoke(0xc0002b0000, 0xca, 0x548a688, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0) pkg/sentry/kernel/task_syscall.go:291 +0x66 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscallEnter(0xc0002b0000, 0xca, 0x548a688, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0) pkg/sentry/kernel/task_syscall.go:252 +0x96 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscall(0xc0002b0000, 0x2, 0xc00026eaa0) pkg/sentry/kernel/task_syscall.go:227 +0x139 gvisor.dev/gvisor/pkg/sentry/kernel.(*runApp).execute(0x0, 0xc0002b0000, 0xf4eea0, 0x0) pkg/sentry/kernel/task_run.go:258 +0xed5 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).run(0xc0002b0000, 0x1) pkg/sentry/kernel/task_run.go:92 +0x18b created by gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).Start pkg/sentry/kernel/task_start.go:315 +0xfe goroutine 112 [select, 1 minutes]: gvisor.dev/gvisor/pkg/sentry/kernel/time.(*Timer).runGoroutine(0xc00034d050) pkg/sentry/kernel/time/time.go:515 +0xb9 created by gvisor.dev/gvisor/pkg/sentry/kernel/time.(*Timer).init pkg/sentry/kernel/time/time.go:494 +0x139 goroutine 152 [select]: gvisor.dev/gvisor/pkg/sentry/kernel/time.(*Timer).runGoroutine(0xc0004881b0) pkg/sentry/kernel/time/time.go:515 +0xb9 created by gvisor.dev/gvisor/pkg/sentry/kernel/time.(*Timer).init pkg/sentry/kernel/time/time.go:494 +0x139 goroutine 179 [semacquire, 1 minutes]: sync.runtime_Semacquire(0xc000684eb8) GOROOT/src/runtime/sema.go:56 +0x42 sync.(*WaitGroup).Wait(0xc000684eb0) GOROOT/src/sync/waitgroup.go:130 +0x64 gvisor.dev/gvisor/pkg/sentry/kernel.(*ThreadGroup).WaitExited(...) pkg/sentry/kernel/task_run.go:372 gvisor.dev/gvisor/runsc/boot.(*Loader).wait(0xc0001ce000, 0xc000684800, 0x1e) runsc/boot/loader.go:893 +0x35 gvisor.dev/gvisor/runsc/boot.(*Loader).waitContainer(0xc0001ce000, 0xc000356680, 0x1e, 0xc00035e3f8, 0x0, 0x198) runsc/boot/loader.go:848 +0x143 gvisor.dev/gvisor/runsc/boot.(*containerManager).Wait(0xc000720420, 0xc000504230, 0xc00035e3f8, 0x0, 0x0) runsc/boot/controller.go:430 +0x7d reflect.Value.call(0xc000126540, 0xc00000e178, 0x13, 0xe048ea, 0x4, 0xc0001acea8, 0x3, 0x3, 0xc0001acca0, 0x4c151d, ...) GOROOT/src/reflect/value.go:460 +0x8ab reflect.Value.Call(0xc000126540, 0xc00000e178, 0x13, 0xc0001acea8, 0x3, 0x3, 0x0, 0xc000720420, 0x16) GOROOT/src/reflect/value.go:321 +0xb4 gvisor.dev/gvisor/pkg/urpc.(*Server).handleOne(0xc0002ded80, 0xc00034a060, 0x0, 0x0) pkg/urpc/urpc.go:325 +0x56d gvisor.dev/gvisor/pkg/urpc.(*Server).handleRegistered(0xc0002ded80, 0xc00034a060, 0x0, 0x0) pkg/urpc/urpc.go:420 +0x35 gvisor.dev/gvisor/pkg/urpc.(*Server).StartHandling.func1(0xc0002ded80, 0xc00034a060) pkg/urpc/urpc.go:440 +0x66 created by gvisor.dev/gvisor/pkg/urpc.(*Server).StartHandling pkg/urpc/urpc.go:438 +0x61 goroutine 225 [select]: gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).block(0xc0002b1500, 0xc00019c420, 0xc0005c2120, 0x0, 0x0) pkg/sentry/kernel/task_block.go:138 +0x16c gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).BlockWithDeadline(0xc0002b1500, 0xc00019c420, 0x825c01, 0x23d92a3cf8, 0x0, 0x0) pkg/sentry/kernel/task_block.go:80 +0x81 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).BlockWithTimeout(0xc0002b1500, 0xc00019c420, 0xf5fd01, 0xdf8475800, 0x54898d0, 0x2b1500, 0xc4ffffffff) pkg/sentry/kernel/task_block.go:42 +0xaa gvisor.dev/gvisor/pkg/sentry/syscalls/linux.futexWaitDuration(0xc0002b1500, 0xdf8475800, 0x0, 0x54898d0, 0x0, 0xffffffff, 0x0, 0x4e20, 0x15df62788e) pkg/sentry/syscalls/linux/sys_futex.go:97 +0xd2 gvisor.dev/gvisor/pkg/sentry/syscalls/linux.Futex(0xc0002b1500, 0x54898d0, 0x0, 0x0, 0xc4200d9ea8, 0x0, 0x0, 0x0, 0x0, 0x0, ...) pkg/sentry/syscalls/linux/sys_futex.go:206 +0x100 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).executeSyscall(0xc0002b1500, 0xca, 0x54898d0, 0x0, 0x0, 0xc4200d9ea8, 0x0, 0x0, 0xc000180090, 0xe03180, ...) pkg/sentry/kernel/task_syscall.go:170 +0x122 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscallInvoke(0xc0002b1500, 0xca, 0x54898d0, 0x0, 0x0, 0xc4200d9ea8, 0x0, 0x0, 0x0, 0x0) pkg/sentry/kernel/task_syscall.go:291 +0x66 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscallEnter(0xc0002b1500, 0xca, 0x54898d0, 0x0, 0x0, 0xc4200d9ea8, 0x0, 0x0, 0x0, 0x0) pkg/sentry/kernel/task_syscall.go:252 +0x96 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscall(0xc0002b1500, 0x2, 0xc00026eaa0) pkg/sentry/kernel/task_syscall.go:227 +0x139 gvisor.dev/gvisor/pkg/sentry/kernel.(*runApp).execute(0x0, 0xc0002b1500, 0xf4eea0, 0x0) pkg/sentry/kernel/task_run.go:258 +0xed5 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).run(0xc0002b1500, 0x2) pkg/sentry/kernel/task_run.go:92 +0x18b created by gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).Start pkg/sentry/kernel/task_start.go:315 +0xfe goroutine 226 [select, 1 minutes]: gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).block(0xc000562000, 0xc00019c600, 0x0, 0x1, 0x0) pkg/sentry/kernel/task_block.go:138 +0x16c gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).BlockWithTimeout(0xc000562000, 0xc00019c600, 0xf5fd00, 0x0, 0xc4200ca548, 0x0, 0xffffffff) pkg/sentry/kernel/task_block.go:37 +0x1ec gvisor.dev/gvisor/pkg/sentry/syscalls/linux.futexWaitDuration(0xc000562000, 0x0, 0xc000562001, 0xc4200ca548, 0x0, 0xffffffff, 0x0, 0x0, 0x0) pkg/sentry/syscalls/linux/sys_futex.go:97 +0xd2 gvisor.dev/gvisor/pkg/sentry/syscalls/linux.Futex(0xc000562000, 0xc4200ca548, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, ...) pkg/sentry/syscalls/linux/sys_futex.go:206 +0x100 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).executeSyscall(0xc000562000, 0xca, 0xc4200ca548, 0x0, 0x0, 0x0, 0x0, 0x0, 0xc0000e4790, 0xe03180, ...) pkg/sentry/kernel/task_syscall.go:170 +0x122 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscallInvoke(0xc000562000, 0xca, 0xc4200ca548, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0) pkg/sentry/kernel/task_syscall.go:291 +0x66 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscallEnter(0xc000562000, 0xca, 0xc4200ca548, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0) pkg/sentry/kernel/task_syscall.go:252 +0x96 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscall(0xc000562000, 0x2, 0xc00026eaa0) pkg/sentry/kernel/task_syscall.go:227 +0x139 gvisor.dev/gvisor/pkg/sentry/kernel.(*runApp).execute(0x0, 0xc000562000, 0xf4eea0, 0x0) pkg/sentry/kernel/task_run.go:258 +0xed5 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).run(0xc000562000, 0x3) pkg/sentry/kernel/task_run.go:92 +0x18b created by gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).Start pkg/sentry/kernel/task_start.go:315 +0xfe goroutine 153 [select, 1 minutes]: gvisor.dev/gvisor/pkg/sentry/kernel/time.(*Timer).runGoroutine(0xc000488240) pkg/sentry/kernel/time/time.go:515 +0xb9 created by gvisor.dev/gvisor/pkg/sentry/kernel/time.(*Timer).init pkg/sentry/kernel/time/time.go:494 +0x139 goroutine 227 [select, 1 minutes]: gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).block(0xc000562a80, 0xc00019c840, 0x0, 0x1, 0x0) pkg/sentry/kernel/task_block.go:138 +0x16c gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).BlockWithTimeout(0xc000562a80, 0xc00019c840, 0xf5fd00, 0x0, 0xc4200ca948, 0x0, 0xffffffff) pkg/sentry/kernel/task_block.go:37 +0x1ec gvisor.dev/gvisor/pkg/sentry/syscalls/linux.futexWaitDuration(0xc000562a80, 0x0, 0xc000562a01, 0xc4200ca948, 0x5000000, 0xffffffff, 0x0, 0x0, 0x0) pkg/sentry/syscalls/linux/sys_futex.go:97 +0xd2 gvisor.dev/gvisor/pkg/sentry/syscalls/linux.Futex(0xc000562a80, 0xc4200ca948, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, ...) pkg/sentry/syscalls/linux/sys_futex.go:206 +0x100 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).executeSyscall(0xc000562a80, 0xca, 0xc4200ca948, 0x0, 0x0, 0x0, 0x0, 0x0, 0xc0004a4410, 0xe03180, ...) pkg/sentry/kernel/task_syscall.go:170 +0x122 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscallInvoke(0xc000562a80, 0xca, 0xc4200ca948, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0) pkg/sentry/kernel/task_syscall.go:291 +0x66 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscallEnter(0xc000562a80, 0xca, 0xc4200ca948, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0) pkg/sentry/kernel/task_syscall.go:252 +0x96 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscall(0xc000562a80, 0x2, 0xc00026eaa0) pkg/sentry/kernel/task_syscall.go:227 +0x139 gvisor.dev/gvisor/pkg/sentry/kernel.(*runApp).execute(0x0, 0xc000562a80, 0xf4eea0, 0x0) pkg/sentry/kernel/task_run.go:258 +0xed5 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).run(0xc000562a80, 0x4) pkg/sentry/kernel/task_run.go:92 +0x18b created by gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).Start pkg/sentry/kernel/task_start.go:315 +0xfe goroutine 154 [select, 1 minutes]: gvisor.dev/gvisor/pkg/sentry/kernel/time.(*Timer).runGoroutine(0xc0004882d0) pkg/sentry/kernel/time/time.go:515 +0xb9 created by gvisor.dev/gvisor/pkg/sentry/kernel/time.(*Timer).init pkg/sentry/kernel/time/time.go:494 +0x139 goroutine 14 [select, 1 minutes]: gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).block(0xc0005ba000, 0xc000364240, 0x0, 0x1, 0x0) pkg/sentry/kernel/task_block.go:138 +0x16c gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).BlockWithTimeout(0xc0005ba000, 0xc000364240, 0xf5fd00, 0x0, 0x54a8a00, 0x0, 0xc0ffffffff) pkg/sentry/kernel/task_block.go:37 +0x1ec gvisor.dev/gvisor/pkg/sentry/syscalls/linux.futexWaitDuration(0xc0005ba000, 0x0, 0xc0005ba001, 0x54a8a00, 0x0, 0xffffffff, 0x1, 0x0, 0x0) pkg/sentry/syscalls/linux/sys_futex.go:97 +0xd2 gvisor.dev/gvisor/pkg/sentry/syscalls/linux.Futex(0xc0005ba000, 0x54a8a00, 0x0, 0x0, 0x0, 0x0, 0x0, 0x1, 0x0, 0x0, ...) pkg/sentry/syscalls/linux/sys_futex.go:206 +0x100 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).executeSyscall(0xc0005ba000, 0xca, 0x54a8a00, 0x0, 0x0, 0x0, 0x0, 0x0, 0xc0000e4b10, 0xe03180, ...) pkg/sentry/kernel/task_syscall.go:170 +0x122 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscallInvoke(0xc0005ba000, 0xca, 0x54a8a00, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0) pkg/sentry/kernel/task_syscall.go:291 +0x66 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscallEnter(0xc0005ba000, 0xca, 0x54a8a00, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0) pkg/sentry/kernel/task_syscall.go:252 +0x96 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscall(0xc0005ba000, 0x2, 0xc00026eaa0) pkg/sentry/kernel/task_syscall.go:227 +0x139 gvisor.dev/gvisor/pkg/sentry/kernel.(*runApp).execute(0x0, 0xc0005ba000, 0xf4eea0, 0x0) pkg/sentry/kernel/task_run.go:258 +0xed5 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).run(0xc0005ba000, 0x5) pkg/sentry/kernel/task_run.go:92 +0x18b created by gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).Start pkg/sentry/kernel/task_start.go:315 +0xfe goroutine 155 [select, 1 minutes]: gvisor.dev/gvisor/pkg/sentry/kernel/time.(*Timer).runGoroutine(0xc000488360) pkg/sentry/kernel/time/time.go:515 +0xb9 created by gvisor.dev/gvisor/pkg/sentry/kernel/time.(*Timer).init pkg/sentry/kernel/time/time.go:494 +0x139 goroutine 156 [select, 1 minutes]: gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).block(0xc0001ec000, 0xc0005c3560, 0x0, 0x1, 0x0) pkg/sentry/kernel/task_block.go:138 +0x16c gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).BlockWithTimeout(0xc0001ec000, 0xc0005c3560, 0xf5fd00, 0x0, 0xc4200cad48, 0x0, 0xffffffff) pkg/sentry/kernel/task_block.go:37 +0x1ec gvisor.dev/gvisor/pkg/sentry/syscalls/linux.futexWaitDuration(0xc0001ec000, 0x0, 0xc0001ec001, 0xc4200cad48, 0x0, 0xffffffff, 0x0, 0x0, 0x0) pkg/sentry/syscalls/linux/sys_futex.go:97 +0xd2 gvisor.dev/gvisor/pkg/sentry/syscalls/linux.Futex(0xc0001ec000, 0xc4200cad48, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, ...) pkg/sentry/syscalls/linux/sys_futex.go:206 +0x100 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).executeSyscall(0xc0001ec000, 0xca, 0xc4200cad48, 0x0, 0x0, 0x0, 0x0, 0x0, 0xc0004a4410, 0xe03180, ...) pkg/sentry/kernel/task_syscall.go:170 +0x122 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscallInvoke(0xc0001ec000, 0xca, 0xc4200cad48, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0) pkg/sentry/kernel/task_syscall.go:291 +0x66 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscallEnter(0xc0001ec000, 0xca, 0xc4200cad48, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0) W0408 05:01:01.877043 5833 sandbox.go:750] Wait RPC to container "ci-gvisor-kvm-direct-sandbox-2" failed: urpc method "containerManager.Wait" failed: EOF. Will try waiting on the sandbox process instead. D0408 05:01:01.880752 5833 container.go:714] Destroy container "ci-gvisor-kvm-direct-sandbox-2" D0408 05:01:01.880871 5833 container.go:801] Destroying container "ci-gvisor-kvm-direct-sandbox-2" D0408 05:01:01.880889 5833 sandbox.go:1126] Destroying root container "ci-gvisor-kvm-direct-sandbox-2" by destroying sandbox D0408 05:01:01.880904 5833 sandbox.go:796] Destroy sandbox "ci-gvisor-kvm-direct-sandbox-2" D0408 05:01:01.880918 5833 container.go:815] Killing gofer for container "ci-gvisor-kvm-direct-sandbox-2", PID: 5839 I0408 05:01:02.182494 5833 main.go:329] Exiting with status: 512 VM DIAGNOSIS: I0408 05:01:01.626394 6836 main.go:305] *************************** I0408 05:01:01.626455 6836 main.go:306] Args: [/syzkaller/managers/kvm-direct-sandbox/current/image -root /syzkaller/managers/kvm-direct-sandbox/workdir/gvisor_root -watchdog-action=panic -network=none -debug -alsologtostderr -platform=kvm -file-access=exclusive -network=sandbox debug -stacks --ps ci-gvisor-kvm-direct-sandbox-2] I0408 05:01:01.626522 6836 main.go:307] Version release-20200323.0-77-g51e461cf9c49 I0408 05:01:01.626548 6836 main.go:308] PID: 6836 I0408 05:01:01.626588 6836 main.go:309] UID: 0, GID: 0 I0408 05:01:01.626625 6836 main.go:310] Configuration: I0408 05:01:01.626656 6836 main.go:311] RootDir: /syzkaller/managers/kvm-direct-sandbox/workdir/gvisor_root I0408 05:01:01.626690 6836 main.go:312] Platform: kvm I0408 05:01:01.626718 6836 main.go:313] FileAccess: exclusive, overlay: false I0408 05:01:01.626750 6836 main.go:314] Network: sandbox, logging: false I0408 05:01:01.626777 6836 main.go:315] Strace: false, max size: 1024, syscalls: [] I0408 05:01:01.626804 6836 main.go:316] *************************** D0408 05:01:01.626881 6836 container.go:159] Load container "/syzkaller/managers/kvm-direct-sandbox/workdir/gvisor_root" "ci-gvisor-kvm-direct-sandbox-2" D0408 05:01:01.628461 6836 container.go:592] Signal container "ci-gvisor-kvm-direct-sandbox-2": signal 0 D0408 05:01:01.628536 6836 sandbox.go:814] Signal sandbox "ci-gvisor-kvm-direct-sandbox-2" D0408 05:01:01.628551 6836 sandbox.go:318] Connecting to sandbox "ci-gvisor-kvm-direct-sandbox-2" D0408 05:01:01.628709 6836 urpc.go:534] urpc: successfully marshalled 110 bytes. I0408 05:01:01.876986 6836 debug.go:128] Found sandbox "ci-gvisor-kvm-direct-sandbox-2", PID: 5844 I0408 05:01:01.877073 6836 debug.go:137] Retrieving sandbox stacks D0408 05:01:01.877100 6836 sandbox.go:932] Stacks sandbox "ci-gvisor-kvm-direct-sandbox-2" D0408 05:01:01.877113 6836 sandbox.go:318] Connecting to sandbox "ci-gvisor-kvm-direct-sandbox-2" W0408 05:01:01.877167 6836 error.go:48] FATAL ERROR: retrieving stacks: connecting to control server at PID 5844: connection refused retrieving stacks: connecting to control server at PID 5844: connection refused W0408 05:01:01.877317 6836 main.go:338] Failure to execute command, err: 1 Error collecting stacks: failed to run ["/syzkaller/managers/kvm-direct-sandbox/current/image" "-root" "/syzkaller/managers/kvm-direct-sandbox/workdir/gvisor_root" "-watchdog-action=panic" "-network=none" "-debug" "-alsologtostderr" "-platform=kvm" "-file-access=exclusive" "-network=sandbox" "debug" "-stacks" "--ps" "ci-gvisor-kvm-direct-sandbox-2"]: exit status 128 I0408 05:01:01.626394 6836 main.go:305] *************************** I0408 05:01:01.626455 6836 main.go:306] Args: [/syzkaller/managers/kvm-direct-sandbox/current/image -root /syzkaller/managers/kvm-direct-sandbox/workdir/gvisor_root -watchdog-action=panic -network=none -debug -alsologtostderr -platform=kvm -file-access=exclusive -network=sandbox debug -stacks --ps ci-gvisor-kvm-direct-sandbox-2] I0408 05:01:01.626522 6836 main.go:307] Version release-20200323.0-77-g51e461cf9c49 I0408 05:01:01.626548 6836 main.go:308] PID: 6836 I0408 05:01:01.626588 6836 main.go:309] UID: 0, GID: 0 I0408 05:01:01.626625 6836 main.go:310] Configuration: I0408 05:01:01.626656 6836 main.go:311] RootDir: /syzkaller/managers/kvm-direct-sandbox/workdir/gvisor_root I0408 05:01:01.626690 6836 main.go:312] Platform: kvm I0408 05:01:01.626718 6836 main.go:313] FileAccess: exclusive, overlay: false I0408 05:01:01.626750 6836 main.go:314] Network: sandbox, logging: false I0408 05:01:01.626777 6836 main.go:315] Strace: false, max size: 1024, syscalls: [] I0408 05:01:01.626804 6836 main.go:316] *************************** D0408 05:01:01.626881 6836 container.go:159] Load container "/syzkaller/managers/kvm-direct-sandbox/workdir/gvisor_root" "ci-gvisor-kvm-direct-sandbox-2" D0408 05:01:01.628461 6836 container.go:592] Signal container "ci-gvisor-kvm-direct-sandbox-2": signal 0 D0408 05:01:01.628536 6836 sandbox.go:814] Signal sandbox "ci-gvisor-kvm-direct-sandbox-2" D0408 05:01:01.628551 6836 sandbox.go:318] Connecting to sandbox "ci-gvisor-kvm-direct-sandbox-2" D0408 05:01:01.628709 6836 urpc.go:534] urpc: successfully marshalled 110 bytes. I0408 05:01:01.876986 6836 debug.go:128] Found sandbox "ci-gvisor-kvm-direct-sandbox-2", PID: 5844 I0408 05:01:01.877073 6836 debug.go:137] Retrieving sandbox stacks D0408 05:01:01.877100 6836 sandbox.go:932] Stacks sandbox "ci-gvisor-kvm-direct-sandbox-2" D0408 05:01:01.877113 6836 sandbox.go:318] Connecting to sandbox "ci-gvisor-kvm-direct-sandbox-2" W0408 05:01:01.877167 6836 error.go:48] FATAL ERROR: retrieving stacks: connecting to control server at PID 5844: connection refused retrieving stacks: connecting to control server at PID 5844: connection refused W0408 05:01:01.877317 6836 main.go:338] Failure to execute command, err: 1 [774726.025151] RAX: 0000000000000000 RBX: 0000000000000000 RCX: 0000000000000000 [774726.032495] RDX: ffff9fc2f1bd2100 RSI: 0000000000000001 RDI: ffffba1961693eb8 [774726.039838] RBP: ffff9fbe40542518 R08: ffff9fc59587a9e8 R09: 0000000000000000 [774726.047171] R10: 0000000000000000 R11: 0000000000000000 R12: ffff9fbe40542040 [774726.054588] R13: ffff9fc6f2817140 R14: ffff9fc6f2817140 R15: ffff9fc2f1bd2100 [774726.061918] FS: 00007f05e243e700(0000) GS:ffff9fca86880000(0000) knlGS:0000000000000000 [774726.070206] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 [774726.076171] CR2: 00006004457c0000 CR3: 0000000251e9e000 CR4: 0000000000162670 [774726.083509] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000 [774726.090847] DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400 [774726.098182] Stack: [774726.100404] 0000000000000000c 0000000000000000c 0000000000000000c ffffffffa0f2278bc [774726.108698] cc897557f0c52006c ffff9fbc8e2c88e0c cc897557f0c52006c ffffba1961693eb8c [774726.116999] ffff9fbe40542518c ffff9fbe40542040c ffff9fc6f2817140c ffff9fc6f2817140c [774726.125419] Call Trace: [774726.128068] [] ? audit_comparator+0x1b/0x60 [774726.134102] [] ? do_wait+0x173/0x240 [774726.139529] [] ? SyS_wait4+0x87/0x110 [774726.145179] [] ? task_stopped_code+0x50/0x50 [774726.151298] [] ? do_syscall_64+0x8d/0x100 [774726.157164] [] ? entry_SYSCALL_64_after_swapgs+0x58/0xc6 [774726.164320] Code: cff cff c0f c1f c44 c00 c00 c41 c57 c41 c56 c41 c55 c41 c54 c55 c53 c48 c83 cec c38 c65 c48 c8b c04 c25 c28 c00 c00 c00 c48 c89 c44 c24 c30 c31 cc0 c8b c9a c2c c04 c00 c00 c<83> cfb c10 c74 c2b c8b c07 c49 c89 cfc c41 c89 cf6 c48 c89 cd5 c83 cf8 c03 c74 c44 c [774726.194654] NMI backtrace for cpu 19 skipped: idling at pc 0xffffffffa141dc3e [774726.202027] NMI backtrace for cpu 41 [774726.205819] CPU: 41 PID: 52902 Comm: exe Tainted: G L 4.9.0-11-amd64 #1 Debian 4.9.189-3+deb9u2 [774726.215847] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 [774726.225266] task: ffff9fb75747e040 task.stack: ffffba19616d0000 [774726.231528] RIP: 0033:[<000000000101d814>] c [<000000000101d814>] 0x101d814 [774726.238775] RSP: 002b:00007f081fffeca0 EFLAGS: 00000202 [774726.244288] RAX: 00007f08d43e1750 RBX: 00007f093ccd3e98 RCX: 000003ffffffffff [774726.251629] RDX: 00007f08d43e1800 RSI: 00007f093ccd3e98 RDI: fffffc0000000000 [774726.258959] RBP: 00007f0894030ca8 R08: 00000000023cc100 R09: 0000000000000001 [774726.266299] R10: 0000000000000000 R11: 0000000000000001 R12: 00007f081fffecb0 [774726.273633] R13: fffffc0000000000 R14: 00007f0894031018 R15: 000003ffffffffff [774726.280965] FS: 00007f081ffff700(0000) GS:ffff9fca87040000(0000) knlGS:0000000000000000 [774726.289263] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 [774726.295205] CR2: 000060060e07f000 CR3: 00000019f0e5e000 CR4: 0000000000162670 [774726.302538] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000 [774726.309995] DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400 [774726.317639] NMI backtrace for cpu 9 skipped: idling at pc 0xffffffffa141dc3e [774726.325004] NMI backtrace for cpu 16 skipped: idling at pc 0xffffffffa141dc3e [774726.332501] NMI backtrace for cpu 30 [774726.336319] CPU: 30 PID: 57275 Comm: exe Tainted: G L 4.9.0-11-amd64 #1 Debian 4.9.189-3+deb9u2 [774726.346355] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 [774726.355780] task: ffff9fc301077100 task.stack: ffffba195aeb0000 [774726.361907] RIP: 0010:[] c [] __bpf_prog_run+0xc/0x1110 [774726.370550] RSP: 0018:ffffba195aeb3e20 EFLAGS: 00000246 [774726.376091] RAX: ffffffffa0f624f0 RBX: ffff9fc4b5f11e40 RCX: 0000000000000023 [774726.383440] RDX: 0000000000000000 RSI: ffffba194ddfd028 RDI: ffffba195aeb3ec8 [774726.390782] RBP: 000000007fff0000 R08: 000000000000d6d4 R09: 00032a4c2dfba49c [774726.398126] R10: 0000000000000000 R11: 0000000000000000 R12: 0000000000000000 [774726.405519] R13: 0000000000000023 R14: 0000000000000000 R15: 000000007fff0000 [774726.412861] FS: 00007f7079bac700(0000) GS:ffff9fca86d80000(0000) knlGS:0000000000000000 [774726.421161] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 [774726.427114] CR2: 000060060e060000 CR3: 0000001568c56000 CR4: 0000000000162670 [774726.434453] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000 [774726.441804] DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400 [774726.449147] Stack: [774726.451366] ffff9fc4b5f11e40c 000000007fff0000c ffffba195aeb3ec8c ffffffffa0f2d784c [774726.459665] 0000000101077100c ffffba195aeb3ea4c ffffffffa141d13dc 0000000000000000c [774726.467961] 22632dfa5c59b323c ffff9fca719dec00c ffff9fc301077100c 0000000000000001c [774726.476253] Call Trace: [774726.478906] [] ? __seccomp_filter+0x74/0x270 [774726.485209] [] ? do_nanosleep+0x8d/0xe0 [774726.490898] [] ? syscall_trace_enter+0x117/0x2c0 [774726.497364] [] ? do_syscall_64+0xd9/0x100 [774726.503226] [] ? entry_SYSCALL_64_after_swapgs+0x58/0xc6 [774726.510388] Code: c1f c44 c00 c00 c31 cc0 cc3 c0f c1f c84 c00 c00 c00 c00 c00 c0f c1f c44 c00 c00 cb8 cf2 cff cff cff cc3 c0f c1f c44 c00 c00 c0f c1f c44 c00 c00 c41 c54 c55 c45 c31 ce4 c53 c<48> cc7 cc5 ce0 c01 c62 ca1 c48 c89 cf3 c48 c81 cec c68 c02 c00 c00 c65 c48 c8b c04 c [774726.540908] NMI backtrace for cpu 35 skipped: idling at pc 0xffffffffa141dc3e [774726.548286] NMI backtrace for cpu 3 [774726.551997] CPU: 3 PID: 21864 Comm: exe Tainted: G L 4.9.0-11-amd64 #1 Debian 4.9.189-3+deb9u2 [774726.561942] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 [774726.571368] task: ffff9fbd03b2e000 task.stack: ffffba1972380000 [774726.577603] RIP: 0010:[] c [] native_queued_spin_lock_slowpath+0x21/0x1a0 [774726.587824] RSP: 0018:ffffba1972383bf0 EFLAGS: 00000202 [774726.593397] RAX: 0000000000000001 RBX: 0000000000000000 RCX: 0000000001b026d0 [774726.600734] RDX: 0000000000000001 RSI: 0000000000000001 RDI: ffff9fbd0365c000 [774726.608074] RBP: ffff9fc7ffb28180 R08: 0000000000000007 R09: 8000000000000000 [774726.615522] R10: 00000003ffffffff R11: 0400000000000000 R12: 000000c101cf3000 [774726.622861] R13: 000000000c101cf3 R14: 0000000000000000 R15: 0000000000000000 [774726.630201] FS: 000000c0004f3210(0000) GS:ffff9fca866c0000(0000) knlGS:0000000000000000 [774726.638513] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 [774726.644455] CR2: 00007fa2ab35aa08 CR3: 0000001db1f38000 CR4: 0000000000162670 [774726.651791] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000 [774726.659140] DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400 [774726.666476] Stack: [774726.668703] ffffffffa141e2fdc ffffffffc0908f1bc ffffba1972383c27c ffffffffc09cf00cc [774726.677096] 0000000000000000c 00000000000008cec 0100ffffc09cf018c 0000000100000000c [774726.685403] 0000000001b026d0c e5cefa7aefccd0adc ffff9fc7ffb28180c 000000c101cf3000c [774726.693710] Call Trace: [774726.696362] [] ? _raw_spin_lock+0x1d/0x20 [774726.702220] [] ? tdp_page_fault+0x22b/0x2c0 [kvm] [774726.708769] [] ? vmexit_fill_RSB+0xc/0x30 [kvm_intel] [774726.715671] [] ? kvm_mmu_page_fault+0x5f/0x120 [kvm] [774726.722480] [] ? vmx_handle_exit+0x1d1/0x1680 [kvm_intel] [774726.730080] [] ? vmexit_fill_RSB+0x18/0x30 [kvm_intel] [774726.737069] [] ? vmexit_fill_RSB+0xc/0x30 [kvm_intel] [774726.743974] [] ? vmexit_fill_RSB+0x18/0x30 [kvm_intel] [774726.750961] [] ? vmexit_fill_RSB+0xc/0x30 [kvm_intel] [774726.757862] [] ? vmexit_fill_RSB+0x18/0x30 [kvm_intel] [774726.764845] [] ? vmexit_fill_RSB+0xc/0x30 [kvm_intel] [774726.771760] [] ? vmexit_fill_RSB+0x18/0x30 [kvm_intel] [774726.778744] [] ? vmexit_fill_RSB+0xc/0x30 [kvm_intel] [774726.785659] [] ? vmx_vcpu_run+0x33f/0x4f0 [kvm_intel] [774726.792561] [] ? kvm_arch_vcpu_ioctl_run+0x801/0x16d0 [kvm] [774726.799988] [] ? kvm_arch_vcpu_load+0x61/0x290 [kvm] [774726.806808] [] ? recalc_sigpending+0x17/0x50 [774726.813040] [] ? kvm_vcpu_ioctl+0x315/0x5e0 [kvm] [774726.819725] [] ? signal_setup_done+0x67/0xb0 [774726.825933] [] ? __seccomp_filter+0x74/0x270 [774726.832054] [] ? do_vfs_ioctl+0xa2/0x620 [774726.837853] [] ? __audit_syscall_entry+0xaa/0xf0 [774726.844385] [] ? syscall_trace_enter+0x1ae/0x2c0 [774726.850863] [] ? SyS_ioctl+0x74/0x80 [774726.856307] [] ? do_syscall_64+0x8d/0x100 [774726.862175] [] ? entry_SYSCALL_64_after_swapgs+0x58/0xc6 [774726.869346] Code: cfe cff cff c0f c1f c80 c00 c00 c00 c00 c0f c1f c44 c00 c00 c0f c1f c44 c00 c00 cba c01 c00 c00 c00 c8b c07 c85 cc0 c75 c0a cf0 c0f cb1 c17 c85 cc0 c75 cf2 cf3 cc3 cf3 c90 c cec c81 cfe c00 c01 c00 c00 c0f c84 c1c c01 c00 c00 c40 c30 cf6 c85 cf6 c75 c42 c [774726.899693] NMI backtrace for cpu 25 skipped: idling at pc 0xffffffffa141dc3e [774726.907063] NMI backtrace for cpu 57 skipped: idling at pc 0xffffffffa141dc3e [774726.914505] NMI backtrace for cpu 38 [774726.918302] CPU: 38 PID: 2873 Comm: exe Tainted: G L 4.9.0-11-amd64 #1 Debian 4.9.189-3+deb9u2 [774726.928238] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 [774726.937768] task: ffff9fbf8af66080 task.stack: ffffba196ebb0000 [774726.944026] RIP: 0033:[<0000000001013370>] c [<0000000001013370>] 0x1013370 [774726.951278] RSP: 002b:00007fc67f7fdc40 EFLAGS: 00000246 [774726.956786] RAX: 00007fc6a8c86440 RBX: 00007fc610030fe8 RCX: 0000000000000000 [774726.964118] RDX: 00007fc6f82ed798 RSI: 000003ffffffffff RDI: 00007fc67f7fdc50 [774726.971456] RBP: 0000000000000000 R08: 00000000023cc100 R09: 0000000000000000 [774726.978790] R10: 0000000000000000 R11: 0000000000000000 R12: 00007fc67f7fdc50 [774726.986138] R13: 00007fc6f82ed798 R14: 00007fc610030d88 R15: 00000000000001dc [774726.993473] FS: 00007fc67f7fe700(0000) GS:ffff9fca86f80000(0000) knlGS:0000000000000000 [774727.001767] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 [774727.007712] CR2: 00006000d5aaf000 CR3: 000000082f37c000 CR4: 0000000000162670 [774727.015053] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000 [774727.022388] DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400 [774727.029726] NMI backtrace for cpu 39 [774727.033528] CPU: 39 PID: 21830 Comm: exe Tainted: G L 4.9.0-11-amd64 #1 Debian 4.9.189-3+deb9u2 [774727.043555] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 [774727.052973] task: ffff9fb698b1a0c0 task.stack: ffffba1975f5c000 [774727.059093] RIP: 0010:[] c [] smp_call_function_many+0x1f2/0x250 [774727.068900] RSP: 0018:ffffba1975f5f7b0 EFLAGS: 00000202 [774727.074413] RAX: 0000000000000003 RBX: 0000000000000200 RCX: 000000000000003d [774727.081760] RDX: ffffda193fd43800 RSI: 0000000000000200 RDI: ffff9fca86fd98c8 [774727.089093] RBP: ffff9fca86fd98c8 R08: f800000000000000 R09: 2400050000000818 [774727.096424] R10: 0000000000000008 R11: 0000000000000027 R12: ffff9fca86fd98c0 [774727.103755] R13: ffffffffc08da000 R14: 0000000000000000 R15: 0000000000000001 [774727.111099] FS: 000000c0002c3590(0000) GS:ffff9fca86fc0000(0000) knlGS:0000000000000000 [774727.119394] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 [774727.125344] CR2: 00007fffb7bf1a00 CR3: 0000001db1f38000 CR4: 0000000000162670 [774727.132683] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000 [774727.140016] DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400 [774727.147348] Stack: [774727.149557] 0000000000019880c 00000001c0900f40c ffffba1975f5f7f8c 0000000000000001c [774727.157847] ffff9fbd0365c048c 000000c002304000c 000000c002305000c 0000000000000001c [774727.166136] ffffffffc08dfe3dc 2400050000000818c 0000000000000000c 0000000000000000c [774727.174426] Call Trace: [774727.177094] [] ? kvm_make_all_cpus_request+0xdd/0x110 [kvm] [774727.184514] [] ? kvm_flush_remote_tlbs+0x18/0x40 [kvm] [774727.191507] [] ? kvm_mmu_notifier_invalidate_range_start+0x56/0x80 [kvm] [774727.200060] [] ? __mmu_notifier_invalidate_range_start+0x52/0x80 [774727.207919] [] ? wp_page_copy+0x653/0x770 [774727.213994] [] ? do_wp_page+0x396/0x830 [774727.219676] [] ? handle_mm_fault+0x87d/0x1350 [774727.225876] [] ? follow_page_pte+0x12c/0x6a0 [774727.232002] [] ? __get_user_pages+0x11b/0x6c0 [774727.238205] [] ? get_user_pages+0x4b/0x50 [774727.244060] [] ? __gfn_to_pfn_memslot+0x119/0x410 [kvm] [774727.251136] [] ? __switch_to_asm+0x35/0x70 [774727.257077] [] ? vmx_vcpu_load+0x116/0x360 [kvm_intel] [774727.264075] [] ? try_async_pf+0x67/0x200 [kvm] [774727.270452] [] ? tdp_page_fault+0x150/0x2c0 [kvm] [774727.277003] [] ? vmexit_fill_RSB+0xc/0x30 [kvm_intel] [774727.283920] [] ? vmexit_fill_RSB+0x18/0x30 [kvm_intel] [774727.290902] [] ? kvm_mmu_page_fault+0x5f/0x120 [kvm] [774727.297723] [] ? vmx_handle_exit+0x1d1/0x1680 [kvm_intel] [774727.304988] [] ? vmexit_fill_RSB+0x18/0x30 [kvm_intel] [774727.311991] [] ? vmexit_fill_RSB+0xc/0x30 [kvm_intel] [774727.318892] [] ? vmexit_fill_RSB+0x18/0x30 [kvm_intel] [774727.325883] [] ? vmexit_fill_RSB+0xc/0x30 [kvm_intel] [774727.332783] [] ? vmexit_fill_RSB+0x18/0x30 [kvm_intel] [774727.339771] [] ? vmexit_fill_RSB+0xc/0x30 [kvm_intel] [774727.346680] [] ? vmexit_fill_RSB+0x18/0x30 [kvm_intel] [774727.353669] [] ? vmexit_fill_RSB+0xc/0x30 [kvm_intel] [774727.360590] [] ? vmx_vcpu_run+0x33f/0x4f0 [kvm_intel] [774727.367503] [] ? kvm_arch_vcpu_ioctl_run+0x801/0x16d0 [kvm] [774727.375790] [] ? kvm_arch_vcpu_load+0x100/0x290 [kvm] [774727.382690] [] ? recalc_sigpending+0x17/0x50 [774727.388825] [] ? kvm_vcpu_ioctl+0x315/0x5e0 [kvm] [774727.395461] [] ? signal_setup_done+0x67/0xb0 [774727.401601] [] ? __seccomp_filter+0x74/0x270 [774727.407726] [] ? do_vfs_ioctl+0xa2/0x620 [774727.413521] [] ? __audit_syscall_entry+0xaa/0xf0 [774727.419982] [] ? syscall_trace_enter+0x1ae/0x2c0 [774727.426454] [] ? SyS_ioctl+0x74/0x80 [774727.431875] [] ? do_syscall_64+0x8d/0x100 [774727.437730] [] ? entry_SYSCALL_64_after_swapgs+0x58/0xc6 [774727.444888] Code: cd2 ce8 cf3 cc4 c24 c00 c3b c05 ce1 c98 cc1 c00 c89 cc1 c0f c8d c93 cfe cff cff c48 c98 c49 c8b c14 c24 c48 c03 c14 cc5 c00 c04 c87 ca1 c8b c42 c18 ca8 c01 c74 c09 cf3 c90 c<8b> c42 c18 ca8 c01 c75 cf7 ceb cbf c0f cb6 c4c c24 c0c c48 c83 cc4 c10 c4c c89 cf2 c [774727.475222] NMI backtrace for cpu 7 skipped: idling at pc 0xffffffffa141dc3e [774727.482493] NMI backtrace for cpu 40 skipped: idling at pc 0xffffffffa141dc3e [774727.489878] NMI backtrace for cpu 48 [774727.493809] CPU: 48 PID: 0 Comm: swapper/48 Tainted: G L 4.9.0-11-amd64 #1 Debian 4.9.189-3+deb9u2 [774727.504142] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 [774727.513604] task: ffff9fca795da140 task.stack: ffffba194c648000 [774727.519764] RIP: 0010:[] c [] native_queued_spin_lock_slowpath+0x21/0x1a0 [774727.529983] RSP: 0018:ffffba194c64be60 EFLAGS: 00000002 [774727.535509] RAX: 0000000000000001 RBX: 0000000000000000 RCX: 0000000000000000 [774727.542888] RDX: 0000000000000001 RSI: 0000000000000001 RDI: ffff9fca87218980 [774727.550269] RBP: ffffba194c64beb8 R08: 0000000000000000 R09: 0000000000000001 [774727.557646] R10: 0000000000000001 R11: 000000010b8a77cd R12: ffff9fca87218980 [774727.564993] R13: ffff9fca795da140 R14: ffff9fca795da700 R15: ffff9fca795da140 [774727.572339] FS: 0000000000000000(0000) GS:ffff9fca87200000(0000) knlGS:0000000000000000 [774727.580644] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 [774727.586599] CR2: 00006003b0540000 CR3: 000000095b934000 CR4: 0000000000162670 [774727.593943] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000 [774727.601303] DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400 [774727.608660] Stack: [774727.610883] ffffffffa141e2fdc ffffffffa1419464c 0000000000000000c 0000000000000000c [774727.619184] ffff9fca87218980c ffffffffa141dc3ec ffff9fca795da140c ffffffffa1b199c0c [774727.627481] 0000000000000000c 0000000000000000c ffff9fca795da140c 0000000000000030c [774727.635777] Call Trace: [774727.638431] [] ? _raw_spin_lock+0x1d/0x20 [774727.644299] [] ? __schedule+0x94/0x6f0 [774727.649903] [] ? native_safe_halt+0xe/0x10 [774727.655858] [] ? schedule+0x32/0x80 [774727.661204] [] ? schedule_preempt_disabled+0xa/0x10 [774727.667940] [] ? cpu_startup_entry+0x1b1/0x240 [774727.674246] [] ? start_secondary+0x170/0x1b0 [774727.680374] Code: cfe cff cff c0f c1f c80 c00 c00 c00 c00 c0f c1f c44 c00 c00 c0f c1f c44 c00 c00 cba c01 c00 c00 c00 c8b c07 c85 cc0 c75 c0a cf0 c0f cb1 c17 c85 cc0 c75 cf2 cf3 cc3 cf3 c90 c cec c81 cfe c00 c01 c00 c00 c0f c84 c1c c01 c00 c00 c40 c30 cf6 c85 cf6 c75 c42 c [774727.710723] NMI backtrace for cpu 6 [774727.714475] CPU: 6 PID: 51136 Comm: exe Tainted: G L 4.9.0-11-amd64 #1 Debian 4.9.189-3+deb9u2 [774727.725825] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 [774727.735257] task: ffff9fc0ba62e100 task.stack: ffffba195c0a0000 [774727.741482] RIP: 0033:[<0000000001013370>] c [<0000000001013370>] 0x1013370 [774727.748739] RSP: 002b:00007feaeaffcc40 EFLAGS: 00000202 [774727.754268] RAX: 00007feafad38b40 RBX: 00007feb88141f98 RCX: 000000000003451a [774727.761620] RDX: 00007fea8375ec38 RSI: 000003ffffffffff RDI: 00007feaeaffcc50 [774727.769020] RBP: 0000000000000000 R08: 00000000023cc100 R09: 0000000000000000 [774727.776716] R10: 0000000000000000 R11: 0000000000000000 R12: 00007feaeaffcc50 [774727.784152] R13: 00007fea8375ec38 R14: 00007feb88141a38 R15: 0000000000000343 [774727.792933] FS: 00007feaeaffd700(0000) GS:ffff9fca86780000(0000) knlGS:0000000000000000 [774727.801241] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 [774727.807328] CR2: 00006004deab0000 CR3: 0000001a33630000 CR4: 0000000000162670 [774727.814680] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000 [774727.822026] DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400 [774727.829391] NMI backtrace for cpu 18 [774727.833196] CPU: 18 PID: 24496 Comm: exe Tainted: G L 4.9.0-11-amd64 #1 Debian 4.9.189-3+deb9u2 [774727.844625] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 [774727.854061] task: ffff9fc9bc5ec040 task.stack: ffffba197c398000 [774727.860228] RIP: 0010:[] c [] smp_call_function_single+0xd3/0x130 [774727.869837] RSP: 0018:ffffba197c39bc30 EFLAGS: 00000202 [774727.875356] RAX: 0000000000000000 RBX: 000000000000003d RCX: ffffba197d81bc40 [774727.882698] RDX: 0000000000000001 RSI: ffffba197c39bc40 RDI: ffffba197c39bc40 [774727.890270] RBP: ffffffffc09d4a50 R08: 0000000000000002 R09: ffff9fb080a17680 [774727.897631] R10: 0000000000000020 R11: ffff9fb1f9276938 R12: 000000000000003d [774727.905057] R13: ffff9fc458669780 R14: ffff9fb1f9276900 R15: 0000000000000012 [774727.912396] FS: 000000c0002b2090(0000) GS:ffff9fca86a80000(0000) knlGS:0000000000000000 [774727.920712] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 [774727.926676] CR2: 00007fffb25b0a00 CR3: 00000018e33f4000 CR4: 0000000000162670 [774727.934018] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000 [774727.941372] DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400 [774727.948708] Stack: [774727.950925] 0000000000000246c ffff9fc9bc5ec0c0c ffffba197d81bc40c ffffffffc09d4a50c [774727.959221] ffff9fc45866dd18c 0000000000000003c 21698c2af4f2a933c 0000000000000012c [774727.967643] ffffba197c39bd90c ffffffffc09d3e9fc ffff9fca05832000c ffff9fca86a98980c [774727.975967] Call Trace: [774727.978618] [] ? update_debugctlmsr+0x20/0x20 [kvm_intel] [774727.985882] [] ? vmx_vcpu_load+0x9f/0x360 [kvm_intel] [774727.992802] [] ? check_preempt_curr+0x7a/0x90 [774727.999030] [] ? ttwu_do_wakeup+0x14/0xe0 [774728.004904] [] ? try_to_wake_up+0x54/0x3c0 [774728.010865] [] ? wake_up_q+0x3f/0x70 [774728.016312] [] ? kvm_arch_vcpu_load+0x46/0x290 [kvm] [774728.023151] [] ? recalc_sigpending+0x17/0x50 [774728.029271] [] ? vcpu_load+0x3c/0x50 [kvm] [774728.035226] [] ? kvm_vcpu_ioctl+0x77/0x5e0 [kvm] [774728.041697] [] ? signal_setup_done+0x67/0xb0 [774728.047833] [] ? __seccomp_filter+0x74/0x270 [774728.053972] [] ? do_vfs_ioctl+0xa2/0x620 [774728.059755] [] ? __audit_syscall_entry+0xaa/0xf0 [774728.066239] [] ? syscall_trace_enter+0x1ae/0x2c0 [774728.073008] [] ? SyS_ioctl+0x74/0x80 [774728.078443] [] ? do_syscall_64+0x8d/0x100 [774728.084312] [] ? entry_SYSCALL_64_after_swapgs+0x58/0xc6 [774728.091481] Code: c25 c28 c00 c00 c00 c75 c73 c48 c83 cc4 c38 c5b c5d cc3 c48 c8d c74 c24 c10 c48 c89 cd1 c89 cdf c48 c89 cea ce8 c28 cfe cff cff c8b c54 c24 c28 c83 ce2 c01 c74 c0b cf3 c90 c<8b> c54 c24 c28 c83 ce2 c01 c75 cf5 ceb cc1 c8b c05 cc4 c85 cdb c00 c85 cc0 c75 c83 c [774728.122635] NMI backtrace for cpu 52 [774728.126450] CPU: 52 PID: 46988 Comm: exe Tainted: G L 4.9.0-11-amd64 #1 Debian 4.9.189-3+deb9u2 [774728.138050] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 [774728.147490] task: ffff9fc28ad40040 task.stack: ffffba194f258000 [774728.153702] RIP: 0033:[<00000000010280b1>] c [<00000000010280b1>] 0x10280b1 [774728.160988] RSP: 002b:00007f0581b5ecf0 EFLAGS: 00000202 [774728.166504] RAX: 16dc000000a46168 RBX: 000008c0022b4920 RCX: 16dc040000a46168 [774728.173847] RDX: 0000000000b181e1 RSI: 16dc0c0000a46168 RDI: 00007f060c2c1b68 [774728.181199] RBP: 000000c000c536f0 R08: ffffffffffffffff R09: 0000000000000001 [774728.188892] R10: 000000c000cb4180 R11: 0000000000b0cc7b R12: 0000230008ad2480 [774728.196232] R13: 000000c000cb2000 R14: 000000c001273e00 R15: 0000000000000431 [774728.203579] FS: 00007f0581b5f700(0000) GS:ffff9fca87300000(0000) knlGS:0000000000000000 [774728.212146] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 [774728.218110] CR2: 00006005293e0000 CR3: 0000000f74de4000 CR4: 0000000000162670 [774728.225463] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000 [774728.232810] DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400 [774728.240242] NMI backtrace for cpu 20 skipped: idling at pc 0xffffffffa141dc3e [774728.247616] NMI backtrace for cpu 51 [774728.252809] CPU: 51 PID: 46594 Comm: exe Tainted: G L 4.9.0-11-amd64 #1 Debian 4.9.189-3+deb9u2 [774728.262859] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 [774728.272301] task: ffff9fc228d9f000 task.stack: ffffba1965a7c000 [774728.278442] RIP: 0033:[<000000000101d2e8>] c [<000000000101d2e8>] 0x101d2e8 [774728.285708] RSP: 002b:00007f05cfffec70 EFLAGS: 00000206 [774728.291244] RAX: 000003ffffffffff RBX: 00007f060c040d38 RCX: 00007f0502ef6e18 [774728.298598] RDX: 00007f0502ef7000 RSI: fffffc0000000000 RDI: 00007f05cfffec70 [774728.305954] RBP: 00007f05cfffec70 R08: 00007f06500c00b8 R09: 000000000046d28c [774728.313312] R10: 0000000000000000 R11: 0000000000000001 R12: 00007f060c043170 [774728.320777] R13: 00007f06500c00b8 R14: fffffc0000000000 R15: 000003ffffffffff [774728.328403] FS: 00007f05cffff700(0000) GS:ffff9fca872c0000(0000) knlGS:0000000000000000 [774728.336861] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 [774728.343192] CR2: 00007ffeff2ffa00 CR3: 0000000f74de4000 CR4: 0000000000162670 [774728.350564] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000 [774728.357930] DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400 [774728.365292] NMI backtrace for cpu 2 skipped: idling at pc 0xffffffffa141dc3e [774728.372594] NMI backtrace for cpu 8 [774728.377708] CPU: 8 PID: 21828 Comm: exe Tainted: G L 4.9.0-11-amd64 #1 Debian 4.9.189-3+deb9u2 [774728.388066] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 [774728.397600] task: ffff9fb8130a3040 task.stack: ffffba1975e04000 [774728.404196] RIP: 0010:[] c [] native_queued_spin_lock_slowpath+0x21/0x1a0 [774728.414426] RSP: 0018:ffffba1975e07bf0 EFLAGS: 00000202 [774728.420061] RAX: 0000000000000001 RBX: 0000000000000000 RCX: 00000000017635c6 [774728.427939] RDX: 0000000000000001 RSI: 0000000000000001 RDI: ffff9fbd0365c000 [774728.435302] RBP: ffff9fc4287a3340 R08: 0000000000000007 R09: 8000000000000000 [774728.442688] R10: 00000003ffffffff R11: 0400000000000000 R12: 000000c101f3b000 [774728.450068] R13: 000000000c101f3b R14: 0000000000000000 R15: 0000000000000000 [774728.457694] FS: 000000c0002c3210(0000) GS:ffff9fca86800000(0000) knlGS:0000000000000000 [774728.466030] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 [774728.472013] CR2: 00007fffb7bf1a00 CR3: 0000001db1f38000 CR4: 0000000000162670 [774728.479376] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000 [774728.486737] DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400 [774728.494104] Stack: [774728.496343] ffffffffa141e2fdc ffffffffc0908f1bc ffffba1975e07c27c ffffffffc09cf00cc [774728.504657] 0000000000000000c 00000000000008cec 0100ffffc09cf018c 0000000100000000c [774728.512992] 00000000017635c6c 50d8436e9e4a7c34c ffff9fc4287a3340c 000000c101f3b000c [774728.521310] Call Trace: [774728.523977] [] ? _raw_spin_lock+0x1d/0x20 [774728.529856] [] ? tdp_page_fault+0x22b/0x2c0 [kvm] [774728.536432] [] ? vmexit_fill_RSB+0xc/0x30 [kvm_intel] [774728.543399] [] ? kvm_mmu_page_fault+0x5f/0x120 [kvm] [774728.550244] [] ? vmx_handle_exit+0x1d1/0x1680 [kvm_intel] [774728.557533] [] ? vmexit_fill_RSB+0x18/0x30 [kvm_intel] [774728.564560] [] ? vmexit_fill_RSB+0xc/0x30 [kvm_intel] [774728.571485] [] ? vmexit_fill_RSB+0x18/0x30 [kvm_intel] [774728.578597] [] ? vmexit_fill_RSB+0xc/0x30 [kvm_intel] [774728.585521] [] ? vmexit_fill_RSB+0x18/0x30 [kvm_intel] [774728.592532] [] ? vmexit_fill_RSB+0xc/0x30 [kvm_intel] [774728.599461] [] ? vmexit_fill_RSB+0x18/0x30 [kvm_intel] [774728.606495] [] ? vmexit_fill_RSB+0xc/0x30 [kvm_intel] [774728.613420] [] ? vmx_vcpu_run+0x33f/0x4f0 [kvm_intel] [774728.620344] [] ? kvm_arch_vcpu_ioctl_run+0x801/0x16d0 [kvm] [774728.627785] [] ? kvm_arch_vcpu_load+0x61/0x290 [kvm] [774728.634623] [] ? recalc_sigpending+0x17/0x50 [774728.640833] [] ? kvm_vcpu_ioctl+0x315/0x5e0 [kvm] [774728.647400] [] ? signal_setup_done+0x67/0xb0 [774728.653544] [] ? __seccomp_filter+0x74/0x270 [774728.659681] [] ? do_vfs_ioctl+0xa2/0x620 [774728.665535] [] ? __audit_syscall_entry+0xaa/0xf0 [774728.672017] [] ? syscall_trace_enter+0x1ae/0x2c0 [774728.678515] [] ? SyS_ioctl+0x74/0x80 [774728.683959] [] ? do_syscall_64+0x8d/0x100 [774728.689914] [] ? entry_SYSCALL_64_after_swapgs+0x58/0xc6 [774728.697097] Code: cfe cff cff c0f c1f c80 c00 c00 c00 c00 c0f c1f c44 c00 c00 c0f c1f c44 c00 c00 cba c01 c00 c00 c00 c8b c07 c85 cc0 c75 c0a cf0 c0f cb1 c17 c85 cc0 c75 cf2 cf3 cc3 cf3 c90 c cec c81 cfe c00 c01 c00 c00 c0f c84 c1c c01 c00 c00 c40 c30 cf6 c85 cf6 c75 c42 c [774728.727461] NMI backtrace for cpu 47 skipped: idling at pc 0xffffffffa141dc3e [774728.738836] NMI backtrace for cpu 15 skipped: idling at pc 0xffffffffa141dc3e [774728.746213] NMI backtrace for cpu 43 skipped: idling at pc 0xffffffffa141dc3e [774728.753598] NMI backtrace for cpu 27 [774728.758783] CPU: 27 PID: 0 Comm: swapper/27 Tainted: G L 4.9.0-11-amd64 #1 Debian 4.9.189-3+deb9u2 [774728.769077] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 [774728.778516] task: ffff9fca79552080 task.stack: ffffba194c5a0000 [774728.784644] RIP: 0010:[] c [] try_to_wake_up+0x10/0x3c0 [774728.793288] RSP: 0018:ffff9fca86cc3f20 EFLAGS: 00000046 [774728.798801] RAX: ffffba19757b7cd0 RBX: ffffba19757b7cd0 RCX: 0000000000000000 [774728.806142] RDX: 0000000000000000 RSI: 0000000000000003 RDI: ffff9fc228d5c140 [774728.813479] RBP: ffff9fca86cd4bc0 R08: 0000000000000000 R09: 0000000000000001 [774728.820818] R10: 0000000000000000 R11: 000000010b8a77cd R12: ffff9fca86cd4c00 [774728.828170] R13: ffffffffa0eec3e0 R14: 0000000000000001 R15: 0000000000000003 [774728.835512] FS: 0000000000000000(0000) GS:ffff9fca86cc0000(0000) knlGS:0000000000000000 [774728.843815] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 [774728.849766] CR2: 000060055ba40000 CR3: 00000003e257e000 CR4: 0000000000162670 [774728.857114] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000 [774728.864453] DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400 [774728.871804] Stack: [774728.874023] ffff9fca86cd4c00c ffffffffa0eec3e0c 0000000000000001c ffff9fca86cd4ce8c [774728.882317] ffffffffa0eec3fec ffffffffa0eec87ec 0002c0ac43885045c 0002c0ac43885045c [774728.890623] 451edfd81da300b7c 0000000000000003c 7fffffffffffffffc ffff9fca86cd4bc0c [774728.898950] Call Trace: [774728.901618] d [] ? __hrtimer_init+0xa0/0xa0 [774728.908283] [] ? hrtimer_wakeup+0x1e/0x30 [774728.914163] [] ? __hrtimer_run_queues+0xde/0x250 [774728.920634] [] ? hrtimer_interrupt+0x9c/0x1a0 [774728.926845] [] ? smp_apic_timer_interrupt+0x47/0x60 [774728.933586] [] ? apic_timer_interrupt+0x9e/0xb0 [774728.940058] d [] ? __cpuidle_text_start+0x8/0x8 [774728.947060] [] ? native_safe_halt+0xe/0x10 [774728.953024] [] ? default_idle+0x1a/0xd0 [774728.958720] [] ? cpu_startup_entry+0x1ca/0x240 [774728.965030] [] ? start_secondary+0x170/0x1b0 [774728.971168] Code: cff cff c31 cd2 ce9 c38 cff cff cff ce8 cfd cfd cff cff ceb cc5 c90 c66 c2e c0f c1f c84 c00 c00 c00 c00 c00 c0f c1f c44 c00 c00 c41 c57 c41 c56 c41 c89 cf7 c41 c55 c41 c54 c<41> c89 cd5 c55 c53 c48 c89 cfb c48 c83 cec c20 c65 c48 c8b c04 c25 c28 c00 c00 c00 c [774729.001838] NMI backtrace for cpu 59 [774729.005653] CPU: 59 PID: 46593 Comm: exe Tainted: G L 4.9.0-11-amd64 #1 Debian 4.9.189-3+deb9u2 [774729.015697] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 [774729.025142] task: ffff9fae985d5140 task.stack: ffffba1961ca0000 [774729.031282] RIP: 0033:[<0000000001013370>] c [<0000000001013370>] 0x1013370 [774729.038552] RSP: 002b:00007f05e0dfcc40 EFLAGS: 00000206 [774729.044087] RAX: 00007f0505b73400 RBX: 00007f05dc172888 RCX: 000000000209803f [774729.051444] RDX: 00007f04fff3e238 RSI: 000003ffffffffff RDI: 00007f05e0dfcc50 [774729.058890] RBP: 0000000000000000 R08: 00000000023cc100 R09: 0000000000000000 [774729.066342] R10: 0000000000000000 R11: 0000000000000000 R12: 00007f05e0dfcc50 [774729.073699] R13: 00007f04fff3e238 R14: 00007f05dc172268 R15: 00000000000004c4 [774729.081064] FS: 00007f05e0dfd700(0000) GS:ffff9fca874c0000(0000) knlGS:0000000000000000 [774729.089375] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 [774729.095344] CR2: 000060054374f000 CR3: 0000000f74de4000 CR4: 0000000000162670 [774729.102701] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000 [774729.110153] DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400 [774729.117514] NMI backtrace for cpu 5 skipped: idling at pc 0xffffffffa141dc3e [774729.124807] NMI backtrace for cpu 36 skipped: idling at pc 0xffffffffa141dc3e [774729.133571] NMI backtrace for cpu 11 skipped: idling at pc 0xffffffffa141dc3e [817767.265266] INFO: rcu_sched self-detected stall on CPU [817767.266928] INFO: rcu_sched detected stalls on CPUs/tasks: [817767.266933] 56-...: (5249 ticks this GP) idle=391/140000000000001/0 softirq=105617112/105617112 fqs=2410 [817767.266933] [817767.266936] (detected by 13, t=5252 jiffies, g=32272108, c=32272107, q=76507) [817767.266938] Task dump for CPU 56: [817767.266939] kworker/56:1 R [817767.266939] running task [817767.266940] 0 31026 2 0x00000088 [817767.266947] Workqueue: events wait_rcu_exp_gp [817767.266948] 0000000000012670 [817767.266949] ffffffffa0ee2660 [817767.266949] ffffffffa1a4fd80 [817767.266950] 0000000000045ffe [817767.266950] ffff9fca87418280 [817767.266951] ffffda193fc02d00 [817767.266951] 0000000000000000 [817767.266951] ffffba197b0d3c70 [817767.266952] ffffffffa0ee241b [817767.266952] ffffba197b0d3c68 [817767.266953] ffff9fc4b5ee1780 [817767.266953] ffffffffa0e9589a [817767.266954] Call Trace: [817767.266960] [] ? rcu_stall_kick_kthreads.part.55+0xa0/0xa0 [817767.266962] [] ? wait_rcu_exp_gp+0x1b/0x30 [817767.266965] [] ? process_one_work+0x18a/0x430 [817767.266967] [] ? worker_thread+0x4d/0x490 [817767.266968] [] ? process_one_work+0x430/0x430 [817767.266971] [] ? kthread+0xd9/0xf0 [817767.266974] [] ? __switch_to_asm+0x41/0x70 [817767.266975] [] ? kthread_park+0x60/0x60 [817767.266977] [] ? ret_from_fork+0x57/0x70 [817767.393572] 56-...: (5249 ticks this GP) idle=391/140000000000001/0 softirq=105617112/105617112 fqs=2425 [817767.403419] (t=5286 jiffies g=32272108 c=32272107 q=76974) [817767.409422] Task dump for CPU 56: [817767.412945] kworker/56:1 R running task 0 31026 2 0x00000088 [817767.420477] Workqueue: events wait_rcu_exp_gp [817767.425181] ffffffffa1b19a00 ffffffffa0ea953b 0000000000000038 ffffffffa1b19a00 [817767.433293] ffffffffa0f830ad ffff9fca874196c0 ffffffffa1a4fd80 0000000000000000 [817767.441455] ffffffffa1b19a00 00000000ffffffff ffffffffa0ee51ca 0000000002bb80a6 [817767.449619] Call Trace: [817767.452274] [817767.454409] [] ? sched_show_task+0xcb/0x130 [817767.460566] [] ? rcu_dump_cpu_stacks+0x92/0xb2 [817767.466865] [] ? rcu_check_callbacks+0x75a/0x8b0 [817767.473861] [] ? tick_sched_do_timer+0x30/0x30 [817767.480154] [] ? update_process_times+0x28/0x50 [817767.486751] [] ? tick_sched_handle.isra.12+0x20/0x50 [817767.493568] [] ? tick_sched_timer+0x38/0x70 [817767.499716] [] ? __hrtimer_run_queues+0xde/0x250 [817767.506330] [] ? hrtimer_interrupt+0x9c/0x1a0 [817767.512540] [] ? rcu_stall_kick_kthreads.part.55+0xa0/0xa0 [817767.519886] [] ? smp_apic_timer_interrupt+0x47/0x60 [817767.526611] [] ? apic_timer_interrupt+0x9e/0xb0 [817767.532986] [817767.535116] [] ? rcu_stall_kick_kthreads.part.55+0xa0/0xa0 [817767.542472] [] ? smp_call_function_single+0x87/0x130 [817767.549288] [] ? __switch_to_asm+0x35/0x70 [817767.555236] [] ? __switch_to_asm+0x41/0x70 [817767.561198] [] ? sync_rcu_exp_select_cpus+0x1f3/0x390 [817767.568111] [] ? rcu_stall_kick_kthreads.part.55+0xa0/0xa0 [817767.575446] [] ? wait_rcu_exp_gp+0x1b/0x30 [817767.581392] [] ? process_one_work+0x18a/0x430 [817767.587620] [] ? worker_thread+0x4d/0x490 [817767.593498] [] ? process_one_work+0x430/0x430 [817767.599707] [] ? kthread+0xd9/0xf0 [817767.604962] [] ? __switch_to_asm+0x41/0x70 [817767.610911] [] ? kthread_park+0x60/0x60 [817767.616600] [] ? ret_from_fork+0x57/0x70 [817830.275093] INFO: rcu_sched self-detected stall on CPU [817830.280750] 56-...: (20915 ticks this GP) idle=391/140000000000001/0 softirq=105617112/105617112 fqs=9602 [817830.280869] INFO: rcu_sched detected stalls on CPUs/tasks: [817830.280873] 56-...: (20915 ticks this GP) idle=391/140000000000001/0 softirq=105617112/105617112 fqs=9602 [817830.280879] (detected by 13, t=21007 jiffies, g=32272108, c=32272107, q=279230) [817830.280881] Task dump for CPU 56: [817830.280884] kworker/56:1 R running task 0 31026 2 0x00000088 [817830.280892] Workqueue: events wait_rcu_exp_gp [817830.280896] 0000000000012670 ffffffffa0ee2660 ffffffffa1a4fd80 0000000000045ffe [817830.280898] ffff9fca87418280 ffffda193fc02d00 0000000000000000 ffffba197b0d3c70 [817830.280900] ffffffffa0ee241b ffffba197b0d3c68 ffff9fc4b5ee1780 ffffffffa0e9589a [817830.280901] Call Trace: [817830.280907] [] ? rcu_stall_kick_kthreads.part.55+0xa0/0xa0 [817830.280909] [] ? wait_rcu_exp_gp+0x1b/0x30 [817830.280913] [] ? process_one_work+0x18a/0x430 [817830.280915] [] ? worker_thread+0x4d/0x490 [817830.280917] [] ? process_one_work+0x430/0x430 [817830.280920] [] ? kthread+0xd9/0xf0 [817830.280923] [] ? __switch_to_asm+0x41/0x70 [817830.280926] [] ? kthread_park+0x60/0x60 [817830.280929] [] ? ret_from_fork+0x57/0x70 [817830.409131] (t=21039 jiffies g=32272108 c=32272107 q=279986) [817830.415308] Task dump for CPU 56: [817830.418829] kworker/56:1 R running task 0 31026 2 0x00000088 [817830.426352] Workqueue: events wait_rcu_exp_gp [817830.431055] ffffffffa1b19a00 ffffffffa0ea953b 0000000000000038 ffffffffa1b19a00 [817830.439142] ffffffffa0f830ad ffff9fca874196c0 ffffffffa1a4fd80 0000000000000000 [817830.447290] ffffffffa1b19a00 00000000ffffffff ffffffffa0ee51ca 0000000002ac847f [817830.455437] Call Trace: [817830.458087] [817830.460222] [] ? sched_show_task+0xcb/0x130 [817830.466285] [] ? rcu_dump_cpu_stacks+0x92/0xb2 [817830.472583] [] ? rcu_check_callbacks+0x75a/0x8b0 [817830.479048] [] ? tick_sched_do_timer+0x30/0x30 [817830.485342] [] ? update_process_times+0x28/0x50 [817830.491739] [] ? tick_sched_handle.isra.12+0x20/0x50 [817830.498553] [] ? tick_sched_timer+0x38/0x70 [817830.504657] [] ? __hrtimer_run_queues+0xde/0x250 [817830.511126] [] ? hrtimer_interrupt+0x9c/0x1a0 [817830.517384] [] ? rcu_stall_kick_kthreads.part.55+0xa0/0xa0 [817830.524731] [] ? smp_apic_timer_interrupt+0x47/0x60 [817830.531468] [] ? apic_timer_interrupt+0x9e/0xb0 [817830.537890] [817830.540015] [] ? rcu_stall_kick_kthreads.part.55+0xa0/0xa0 [817830.547368] [] ? smp_call_function_single+0x87/0x130 [817830.554224] [] ? __switch_to_asm+0x35/0x70 [817830.560187] [] ? __switch_to_asm+0x41/0x70 [817830.566151] [] ? sync_rcu_exp_select_cpus+0x1f3/0x390 [817830.573197] [] ? rcu_stall_kick_kthreads.part.55+0xa0/0xa0 [817830.580534] [] ? wait_rcu_exp_gp+0x1b/0x30 [817830.586505] [] ? process_one_work+0x18a/0x430 [817830.592714] [] ? worker_thread+0x4d/0x490 [817830.598575] [] ? process_one_work+0x430/0x430 [817830.605139] [] ? kthread+0xd9/0xf0 [817830.610398] [] ? __switch_to_asm+0x41/0x70 [817830.616404] [] ? kthread_park+0x60/0x60 [817830.622108] [] ? ret_from_fork+0x57/0x70 [818641.867562] INFO: rcu_sched detected stalls on CPUs/tasks: [818641.873585] 41-...: (1 GPs behind) idle=1ed/140000000000000/0 softirq=94499738/94499739 fqs=2315 [818641.882900] (detected by 34, t=5256 jiffies, g=32302910, c=32302909, q=66402) [818641.890583] Task dump for CPU 41: [818641.894106] exe R running task 0 7708 7589 0x00000988 [818641.901685] 0000000000000000 0000000000000030 ffffffffc09dc3b1 ffffffffc09cf018 [818641.909800] ffffffffc09cf00c ffffffffc09cf018 ffffffffc09cf00c ffffffffc09cf018 [818641.918039] ffffffffc09cf00c ffffffffc09cf018 ffffffffc09cf00c ffffffffc09e0baf [818641.926174] Call Trace: [818641.928847] [] ? vmx_handle_exit+0x1d1/0x1680 [kvm_intel] [818641.936121] [] ? vmexit_fill_RSB+0x18/0x30 [kvm_intel] [818641.943135] [] ? vmexit_fill_RSB+0xc/0x30 [kvm_intel] [818641.950058] [] ? vmexit_fill_RSB+0x18/0x30 [kvm_intel] [818641.957074] [] ? vmexit_fill_RSB+0xc/0x30 [kvm_intel] [818641.964005] [] ? vmexit_fill_RSB+0x18/0x30 [kvm_intel] [818641.971629] [] ? vmexit_fill_RSB+0xc/0x30 [kvm_intel] [818641.978563] [] ? vmexit_fill_RSB+0x18/0x30 [kvm_intel] [818641.985651] [] ? vmexit_fill_RSB+0xc/0x30 [kvm_intel] [818641.992567] [] ? vmx_vcpu_run+0x33f/0x4f0 [kvm_intel] [818641.999784] [] ? kvm_arch_vcpu_ioctl_run+0x504/0x16d0 [kvm] [818642.007269] [] ? kvm_arch_vcpu_load+0x61/0x290 [kvm] [818642.014097] [] ? recalc_sigpending+0x17/0x50 [818642.020256] [] ? kvm_vcpu_ioctl+0x315/0x5e0 [kvm] [818642.026824] [] ? signal_setup_done+0x67/0xb0 [818642.032952] [] ? __seccomp_filter+0x74/0x270 [818642.039092] [] ? do_vfs_ioctl+0xa2/0x620 [818642.044873] [] ? __audit_syscall_entry+0xaa/0xf0 [818642.051350] [] ? syscall_trace_enter+0x1ae/0x2c0 [818642.057856] [] ? SyS_ioctl+0x74/0x80 [818642.063292] [] ? do_syscall_64+0x8d/0x100 [818642.069280] [] ? entry_SYSCALL_64_after_swapgs+0x58/0xc6 [822176.693164] INFO: rcu_sched detected stalls on CPUs/tasks: [822176.699079] 36-...: (1 GPs behind) idle=def/140000000000000/0 softirq=94649810/94649811 fqs=2329 [822176.708248] (detected by 46, t=5256 jiffies, g=32440531, c=32440530, q=64749) [822176.715830] Task dump for CPU 36: [822176.719352] exe R running task 0 49334 49301 0x00000988 [822176.726887] 0000000000000000 0000000000000030 ffffffffc09dc3b1 ffffffffc09cf018 [822176.735047] ffffffffc09cf00c ffffffffc09cf018 ffffffffc09cf00c ffffffffc09cf018 [822176.743173] ffffffffc09cf00c ffffffffc09cf018 ffffffffc09cf00c ffffffffc09e0baf [822176.751322] Call Trace: [822176.753995] [] ? vmx_handle_exit+0x1d1/0x1680 [kvm_intel] [822176.761256] [] ? vmexit_fill_RSB+0x18/0x30 [kvm_intel] [822176.768263] [] ? vmexit_fill_RSB+0xc/0x30 [kvm_intel] [822176.775176] [] ? vmexit_fill_RSB+0x18/0x30 [kvm_intel] [822176.782181] [] ? vmexit_fill_RSB+0xc/0x30 [kvm_intel] [822176.789100] [] ? vmexit_fill_RSB+0x18/0x30 [kvm_intel] [822176.796103] [] ? vmexit_fill_RSB+0xc/0x30 [kvm_intel] [822176.803104] [] ? vmexit_fill_RSB+0x18/0x30 [kvm_intel] [822176.810099] [] ? vmexit_fill_RSB+0xc/0x30 [kvm_intel] [822176.817010] [] ? vmx_vcpu_run+0x33f/0x4f0 [kvm_intel] [822176.823957] [] ? kvm_arch_vcpu_ioctl_run+0x504/0x16d0 [kvm] [822176.831420] [] ? kvm_arch_vcpu_load+0x61/0x290 [kvm] [822176.839204] [] ? recalc_sigpending+0x17/0x50 [822176.845359] [] ? kvm_vcpu_ioctl+0x315/0x5e0 [kvm] [822176.852047] [] ? signal_setup_done+0x67/0xb0 [822176.858174] [] ? __seccomp_filter+0x74/0x270 [822176.864302] [] ? do_vfs_ioctl+0xa2/0x620 [822176.870090] [] ? __audit_syscall_entry+0xaa/0xf0 [822176.876573] [] ? syscall_trace_enter+0x1ae/0x2c0 [822176.883138] [] ? SyS_ioctl+0x74/0x80 [822176.888572] [] ? do_syscall_64+0x8d/0x100 [822176.894443] [] ? entry_SYSCALL_64_after_swapgs+0x58/0xc6 [832579.859556] kvm [42992]: vcpu0, guest rIP: 0xa6f744 unhandled rdmsr: 0xce [856399.340048] kvm [11464]: vcpu0, guest rIP: 0xa6f744 unhandled rdmsr: 0xce [857616.680118] INFO: rcu_sched detected stalls on CPUs/tasks: [857616.686153] 50-...: (1 GPs behind) idle=ab7/140000000000000/0 softirq=99237393/99237394 fqs=2269 [857616.695346] (detected by 23, t=5256 jiffies, g=33790061, c=33790060, q=85753) [857616.702950] Task dump for CPU 50: [857616.706504] exe R running task 0 42191 42166 0x00000988 [857616.714052] 0000000000000000 0000000000000030 ffffffffc09dc3b1 ffffffffc09cf018 [857616.722347] ffffffffc09cf00c ffffffffc09cf018 ffffffffc09cf00c ffffffffc09cf018 [857616.730731] ffffffffc09cf00c ffffffffc09cf018 ffffffffc09cf00c ffffffffc09e0baf [857616.738912] Call Trace: [857616.741630] [] ? vmx_handle_exit+0x1d1/0x1680 [kvm_intel] [857616.748904] [] ? vmexit_fill_RSB+0x18/0x30 [kvm_intel] [857616.755912] [] ? vmexit_fill_RSB+0xc/0x30 [kvm_intel] [857616.762854] [] ? vmexit_fill_RSB+0x18/0x30 [kvm_intel] [857616.769977] [] ? vmexit_fill_RSB+0xc/0x30 [kvm_intel] [857616.776958] [] ? vmexit_fill_RSB+0x18/0x30 [kvm_intel] [857616.784340] [] ? vmexit_fill_RSB+0xc/0x30 [kvm_intel] [857616.791872] [] ? vmexit_fill_RSB+0x18/0x30 [kvm_intel] [857616.799070] [] ? vmexit_fill_RSB+0xc/0x30 [kvm_intel] [857616.806001] [] ? vmx_vcpu_run+0x33f/0x4f0 [kvm_intel] [857616.813314] [] ? kvm_arch_vcpu_ioctl_run+0x625/0x16d0 [kvm] [857616.820797] [] ? kvm_arch_vcpu_load+0x61/0x290 [kvm] [857616.827630] [] ? recalc_sigpending+0x17/0x50 [857616.833892] [] ? kvm_vcpu_ioctl+0x315/0x5e0 [kvm] [857616.840467] [] ? signal_setup_done+0x67/0xb0 [857616.846606] [] ? __seccomp_filter+0x74/0x270 [857616.852862] [] ? do_vfs_ioctl+0xa2/0x620 [857616.858865] [] ? __audit_syscall_entry+0xaa/0xf0 [857616.865490] [] ? syscall_trace_enter+0x1ae/0x2c0 [857616.873356] [] ? SyS_ioctl+0x74/0x80 [857616.878802] [] ? do_syscall_64+0x8d/0x100 [857616.884677] [] ? entry_SYSCALL_64_after_swapgs+0x58/0xc6 [858558.522373] kvm [51926]: vcpu0, guest rIP: 0xa6f744 unhandled rdmsr: 0xce [858958.092649] INFO: rcu_sched detected stalls on CPUs/tasks: [858958.099138] 46-...: (1 GPs behind) idle=56b/140000000000000/0 softirq=104721549/104721550 fqs=2378 [858958.108860] (detected by 26, t=5256 jiffies, g=33835275, c=33835274, q=78888) [858958.117941] Task dump for CPU 46: [858958.121467] exe R running task 0 61832 61753 0x00000988 [858958.129086] 0000000000000000 0000000000000030 ffffffffc09dc3b1 ffffffffc09cf018 [858958.137252] ffffffffc09cf00c ffffffffc09cf018 ffffffffc09cf00c ffffffffc09cf018 [858958.145487] ffffffffc09cf00c ffffffffc09cf018 ffffffffc09cf00c ffffffffc09e0baf [858958.154113] Call Trace: [858958.156881] [] ? vmx_handle_exit+0x1d1/0x1680 [kvm_intel] [858958.164162] [] ? vmexit_fill_RSB+0x18/0x30 [kvm_intel] [858958.171263] [] ? vmexit_fill_RSB+0xc/0x30 [kvm_intel] [858958.178279] [] ? vmexit_fill_RSB+0x18/0x30 [kvm_intel] [858958.185469] [] ? vmexit_fill_RSB+0xc/0x30 [kvm_intel] [858958.192403] [] ? vmexit_fill_RSB+0x18/0x30 [kvm_intel] [858958.200065] [] ? vmexit_fill_RSB+0xc/0x30 [kvm_intel] [858958.207082] [] ? vmexit_fill_RSB+0x18/0x30 [kvm_intel] [858958.214105] [] ? vmexit_fill_RSB+0xc/0x30 [kvm_intel] [858958.221040] [] ? vmx_vcpu_run+0x33f/0x4f0 [kvm_intel] [858958.227999] [] ? kvm_arch_vcpu_ioctl_run+0x625/0x16d0 [kvm] [858958.235511] [] ? kvm_arch_vcpu_load+0x61/0x290 [kvm] [858958.242352] [] ? recalc_sigpending+0x17/0x50 [858958.248546] [] ? kvm_vcpu_ioctl+0x315/0x5e0 [kvm] [858958.255124] [] ? signal_setup_done+0x67/0xb0 [858958.261275] [] ? __seccomp_filter+0x74/0x270 [858958.267419] [] ? do_vfs_ioctl+0xa2/0x620 [858958.273217] [] ? __audit_syscall_entry+0xaa/0xf0 [858958.279713] [] ? syscall_trace_enter+0x1ae/0x2c0 [858958.286293] [] ? SyS_ioctl+0x74/0x80 [858958.291740] [] ? do_syscall_64+0x8d/0x100 [858958.297624] [] ? entry_SYSCALL_64_after_swapgs+0x58/0xc6 [863709.327262] INFO: rcu_sched detected stalls on CPUs/tasks: [863709.333140] 39-...: (0 ticks this GP) idle=2ab/140000000000000/0 softirq=99934054/99934054 fqs=2536 [863709.342661] (detected by 46, t=5252 jiffies, g=34008426, c=34008425, q=113137) [863709.351465] Task dump for CPU 39: [863709.354997] exe R running task 0 54937 54811 0x00000988 [863709.362535] 0000000000000000 0000000000000030 ffffffffc09dc3b1 ffffffffc09cf018 [863709.371247] ffffffffc09cf00c ffffffffc09cf018 ffffffffc09cf00c ffffffffc09cf018 [863709.379532] ffffffffc09cf00c ffffffffc09cf018 0003119855649362 0000000000000000 [863709.388223] Call Trace: [863709.390932] [] ? vmx_handle_exit+0x1d1/0x1680 [kvm_intel] [863709.398297] [] ? vmexit_fill_RSB+0x18/0x30 [kvm_intel] [863709.405488] [] ? vmexit_fill_RSB+0xc/0x30 [kvm_intel] [863709.412556] [] ? vmexit_fill_RSB+0x18/0x30 [kvm_intel] [863709.419717] [] ? vmexit_fill_RSB+0xc/0x30 [kvm_intel] [863709.427092] [] ? vmexit_fill_RSB+0x18/0x30 [kvm_intel] [863709.434108] [] ? vmexit_fill_RSB+0xc/0x30 [kvm_intel] [863709.441368] [] ? vmexit_fill_RSB+0x18/0x30 [kvm_intel] [863709.448481] [] ? kvm_clock_get_cycles+0x1a/0x20 [863709.454981] [] ? ktime_get_with_offset+0x54/0xc0 [863709.461864] [] ? kvm_arch_vcpu_ioctl_run+0xc7b/0x16d0 [kvm] [863709.469346] [] ? kvm_arch_vcpu_load+0x61/0x290 [kvm] [863709.476311] [] ? recalc_sigpending+0x17/0x50 [863709.482494] [] ? kvm_vcpu_ioctl+0x315/0x5e0 [kvm] [863709.489065] [] ? signal_setup_done+0x67/0xb0 [863709.495247] [] ? __seccomp_filter+0x74/0x270 [863709.501393] [] ? do_vfs_ioctl+0xa2/0x620 [863709.507273] [] ? __audit_syscall_entry+0xaa/0xf0 [863709.514290] [] ? syscall_trace_enter+0x1ae/0x2c0 [863709.520779] [] ? SyS_ioctl+0x74/0x80 [863709.526340] [] ? do_syscall_64+0x8d/0x100 [863709.532926] [] ? entry_SYSCALL_64_after_swapgs+0x58/0xc6 [907752.278662] INFO: rcu_sched detected stalls on CPUs/tasks: [907752.285015] 35-...: (1 GPs behind) idle=845/140000000000000/0 softirq=105079216/105079217 fqs=2295 [907752.295171] (detected by 52, t=5256 jiffies, g=35711423, c=35711422, q=87289) [907752.303199] Task dump for CPU 35: [907752.306712] exe R running task 0 16354 16207 0x00000988 [907752.314332] 0000000000000000 0000000000000030 ffffffffc09dc3b1 ffffffffc09cf018 [907752.325240] ffffffffc09cf00c ffffffffc09cf018 ffffffffc09cf00c ffffffffc09cf018 [907752.333988] ffffffffc09cf00c ffffffffc09cf018 ffffffffc09cf00c ffffffffc09e0baf [907752.343504] Call Trace: [907752.346620] [] ? vmx_handle_exit+0x1d1/0x1680 [kvm_intel] [907752.354972] [] ? vmexit_fill_RSB+0x18/0x30 [kvm_intel] [907752.362459] [] ? vmexit_fill_RSB+0xc/0x30 [kvm_intel] [907752.369406] [] ? vmexit_fill_RSB+0x18/0x30 [kvm_intel] [907752.376406] [] ? vmexit_fill_RSB+0xc/0x30 [kvm_intel] [907752.383412] [] ? vmexit_fill_RSB+0x18/0x30 [kvm_intel] [907752.390709] [] ? vmexit_fill_RSB+0xc/0x30 [kvm_intel] [907752.397671] [] ? vmexit_fill_RSB+0x18/0x30 [kvm_intel] [907752.404763] [] ? vmexit_fill_RSB+0xc/0x30 [kvm_intel] [907752.411677] [] ? vmx_vcpu_run+0x33f/0x4f0 [kvm_intel] [907752.418730] [] ? kvm_arch_vcpu_ioctl_run+0x504/0x16d0 [kvm] [907752.426173] [] ? kvm_arch_vcpu_load+0x100/0x290 [kvm] [907752.433076] [] ? recalc_sigpending+0x17/0x50 [907752.439227] [] ? kvm_vcpu_ioctl+0x315/0x5e0 [kvm] [907752.445794] [] ? signal_setup_done+0x67/0xb0 [907752.451926] [] ? __seccomp_filter+0x74/0x270 [907752.458057] [] ? do_vfs_ioctl+0xa2/0x620 [907752.463837] [] ? __audit_syscall_entry+0xaa/0xf0 [907752.470316] [] ? syscall_trace_enter+0x1ae/0x2c0 [907752.476798] [] ? SyS_ioctl+0x74/0x80 [907752.482236] [] ? do_syscall_64+0x8d/0x100 [907752.488282] [] ? entry_SYSCALL_64_after_swapgs+0x58/0xc6 [907797.910810] kvm [17559]: vcpu0, guest rIP: 0xa713d4 unhandled rdmsr: 0xce [913855.356589] INFO: rcu_sched detected stalls on CPUs/tasks: [913855.362567] 59-...: (1 GPs behind) idle=cc7/140000000000000/0 softirq=105808057/105808058 fqs=2302 [913855.372102] (detected by 25, t=5256 jiffies, g=35945432, c=35945431, q=67534) [913855.379716] Task dump for CPU 59: [913855.383871] exe R running task 0 12369 12333 0x00000988 [913855.391438] 0000000000000000 0000000000000030 ffffffffc09dc3b1 ffffffffc09cf018 [913855.399615] ffffffffc09cf00c ffffffffc09cf018 ffffffffc09cf00c ffffffffc09cf018 [913855.407895] ffffffffc09cf00c ffffffffc09cf018 ffffffffc09cf00c ffffffffc09e0baf [913855.417145] Call Trace: [913855.420259] [] ? vmx_handle_exit+0x1d1/0x1680 [kvm_intel] [913855.427638] [] ? vmexit_fill_RSB+0x18/0x30 [kvm_intel] [913855.436228] [] ? vmexit_fill_RSB+0xc/0x30 [kvm_intel] [913855.443184] [] ? vmexit_fill_RSB+0x18/0x30 [kvm_intel] [913855.450412] [] ? vmexit_fill_RSB+0xc/0x30 [kvm_intel] [913855.457337] [] ? vmexit_fill_RSB+0x18/0x30 [kvm_intel] [913855.464363] [] ? vmexit_fill_RSB+0xc/0x30 [kvm_intel] [913855.471385] [] ? vmexit_fill_RSB+0x18/0x30 [kvm_intel] [913855.478435] [] ? vmexit_fill_RSB+0xc/0x30 [kvm_intel] [913855.485381] [] ? vmx_vcpu_run+0x33f/0x4f0 [kvm_intel] [913855.492506] [] ? kvm_arch_vcpu_ioctl_run+0x504/0x16d0 [kvm] [913855.500002] [] ? kvm_arch_vcpu_load+0x61/0x290 [kvm] [913855.506959] [] ? recalc_sigpending+0x17/0x50 [913855.513127] [] ? kvm_vcpu_ioctl+0x315/0x5e0 [kvm] [913855.519705] [] ? signal_setup_done+0x67/0xb0 [913855.525997] [] ? __seccomp_filter+0x74/0x270 [913855.532402] [] ? do_vfs_ioctl+0xa2/0x620 [913855.538298] [] ? __audit_syscall_entry+0xaa/0xf0 [913855.544866] [] ? syscall_trace_enter+0x1ae/0x2c0 [913855.551889] [] ? SyS_ioctl+0x74/0x80 [913855.557504] [] ? do_syscall_64+0x8d/0x100 [913855.563469] [] ? entry_SYSCALL_64_after_swapgs+0x58/0xc6 [928372.885524] can: request_module (can-proto-0) failed. [928372.894247] can: request_module (can-proto-0) failed. [928372.921253] can: request_module (can-proto-0) failed. [928392.922276] can: request_module (can-proto-0) failed. [928392.934926] can: request_module (can-proto-0) failed. [928392.970357] can: request_module (can-proto-0) failed. [928396.427155] can: request_module (can-proto-0) failed. [928396.436709] can: request_module (can-proto-0) failed. [928396.449579] can: request_module (can-proto-0) failed. [938409.531029] INFO: rcu_sched detected stalls on CPUs/tasks: [938409.536952] 47-...: (1 GPs behind) idle=cf9/140000000000000/0 softirq=110501574/110501577 fqs=2519 [938409.546299] (detected by 4, t=5252 jiffies, g=36918769, c=36918768, q=58184) [938409.553793] Task dump for CPU 47: [938409.557321] exe R running task 0 59932 59912 0x00000988 [938409.564847] 0000000000000000 0000000000000030 ffffffffc09dc3b1 ffffffffc09cf018 [938409.573008] ffffffffc09cf00c ffffffffc09cf018 ffffffffc09cf00c ffffffffc09cf018 [938409.581219] ffffffffc09cf00c ffffffffc09cf018 ffffffffc09cf00c ffffffffc09e0baf [938409.589361] Call Trace: [938409.592028] [] ? vmx_handle_exit+0x1d1/0x1680 [kvm_intel] [938409.599318] [] ? vmexit_fill_RSB+0x18/0x30 [kvm_intel] [938409.606328] [] ? vmexit_fill_RSB+0xc/0x30 [kvm_intel] [938409.613252] [] ? vmexit_fill_RSB+0x18/0x30 [kvm_intel] [938409.620255] [] ? vmexit_fill_RSB+0xc/0x30 [kvm_intel] [938409.627362] [] ? vmexit_fill_RSB+0x18/0x30 [kvm_intel] [938409.634381] [] ? vmexit_fill_RSB+0xc/0x30 [kvm_intel] [938409.641320] [] ? vmexit_fill_RSB+0x18/0x30 [kvm_intel] [938409.648326] [] ? vmexit_fill_RSB+0xc/0x30 [kvm_intel] [938409.655249] [] ? vmx_vcpu_run+0x33f/0x4f0 [kvm_intel] [938409.662201] [] ? kvm_arch_vcpu_ioctl_run+0x504/0x16d0 [kvm] [938409.669664] [] ? kvm_arch_vcpu_load+0x61/0x290 [kvm] [938409.676507] [] ? recalc_sigpending+0x17/0x50 [938409.682937] [] ? kvm_vcpu_ioctl+0x315/0x5e0 [kvm] [938409.689535] [] ? signal_setup_done+0x67/0xb0 [938409.695686] [] ? __seccomp_filter+0x74/0x270 [938409.701832] [] ? do_vfs_ioctl+0xa2/0x620 [938409.707666] [] ? __audit_syscall_entry+0xaa/0xf0 [938409.714410] [] ? syscall_trace_enter+0x1ae/0x2c0 [938409.721096] [] ? SyS_ioctl+0x74/0x80 [938409.726663] [] ? do_syscall_64+0x8d/0x100 [938409.732572] [] ? entry_SYSCALL_64_after_swapgs+0x58/0xc6 [938472.544363] INFO: rcu_sched detected stalls on CPUs/tasks: [938472.550408] 47-...: (1 GPs behind) idle=cf9/140000000000000/0 softirq=110501574/110501577 fqs=10050 [938472.559833] (detected by 4, t=21007 jiffies, g=36918769, c=36918768, q=240899) [938472.567570] Task dump for CPU 47: [938472.571089] exe R running task 0 59932 59912 0x00000988 [938472.578812] 0000000000000000 0000000000000030 ffffffffc09dc3b1 ffffffffc09cf018 [938472.587150] ffffffffc09cf00c ffffffffc09cf018 ffffffffc09cf00c ffffffffc09cf018 [938472.595293] ffffffffc09cf00c ffffffffc09cf018 ffffffffc09cf00c ffffffffc09e0baf [938472.604567] Call Trace: [938472.607267] [] ? vmx_handle_exit+0x1d1/0x1680 [kvm_intel] [938472.614647] [] ? vmexit_fill_RSB+0x18/0x30 [kvm_intel] [938472.621660] [] ? vmexit_fill_RSB+0xc/0x30 [kvm_intel] [938472.628596] [] ? vmexit_fill_RSB+0x18/0x30 [kvm_intel] [938472.635618] [] ? vmexit_fill_RSB+0xc/0x30 [kvm_intel] [938472.642686] [] ? vmexit_fill_RSB+0x18/0x30 [kvm_intel] [938472.649796] [] ? vmexit_fill_RSB+0xc/0x30 [kvm_intel] [938472.656715] [] ? vmexit_fill_RSB+0x18/0x30 [kvm_intel] [938472.663721] [] ? vmexit_fill_RSB+0xc/0x30 [kvm_intel] [938472.670648] [] ? vmx_vcpu_run+0x33f/0x4f0 [kvm_intel] [938472.677594] [] ? kvm_arch_vcpu_ioctl_run+0xc7b/0x16d0 [kvm] [938472.685056] [] ? kvm_arch_vcpu_load+0x61/0x290 [kvm] [938472.692362] [] ? recalc_sigpending+0x17/0x50 [938472.698805] [] ? kvm_vcpu_ioctl+0x315/0x5e0 [kvm] [938472.705487] [] ? signal_setup_done+0x67/0xb0 [938472.711709] [] ? __seccomp_filter+0x74/0x270 [938472.717837] [] ? do_vfs_ioctl+0xa2/0x620 [938472.723627] [] ? __audit_syscall_entry+0xaa/0xf0 [938472.730109] [] ? syscall_trace_enter+0x1ae/0x2c0 [938472.736589] [] ? SyS_ioctl+0x74/0x80 [938472.742028] [] ? do_syscall_64+0x8d/0x100 [938472.747910] [] ? entry_SYSCALL_64_after_swapgs+0x58/0xc6 [941279.289183] INFO: rcu_sched detected stalls on CPUs/tasks: [941279.295366] 13-...: (1 GPs behind) idle=471/140000000000000/0 softirq=108695954/108695955 fqs=2316 [941279.305646] (detected by 28, t=5256 jiffies, g=37028678, c=37028677, q=69203) [941279.314713] Task dump for CPU 13: [941279.318235] exe R running task 0 32300 32260 0x00000988 [941279.325757] 0000000000000000 0000000000000030 ffffffffc09dc3b1 ffffffffc09cf018 [941279.333843] ffffffffc09cf00c ffffffffc09cf018 ffffffffc09cf00c ffffffffc09cf018 [941279.342121] ffffffffc09cf00c ffffffffc09cf018 ffffffffc09cf00c ffffffffc09e0baf [941279.350221] Call Trace: [941279.352881] [] ? vmx_handle_exit+0x1d1/0x1680 [kvm_intel] [941279.360137] [] ? vmexit_fill_RSB+0x18/0x30 [kvm_intel] [941279.367567] [] ? vmexit_fill_RSB+0xc/0x30 [kvm_intel] [941279.374478] [] ? vmexit_fill_RSB+0x18/0x30 [kvm_intel] [941279.381478] [] ? vmexit_fill_RSB+0xc/0x30 [kvm_intel] [941279.388389] [] ? vmexit_fill_RSB+0x18/0x30 [kvm_intel] [941279.395586] [] ? vmexit_fill_RSB+0xc/0x30 [kvm_intel] [941279.402700] [] ? vmexit_fill_RSB+0x18/0x30 [kvm_intel] [941279.409711] [] ? vmexit_fill_RSB+0xc/0x30 [kvm_intel] [941279.416749] [] ? vmx_vcpu_run+0x33f/0x4f0 [kvm_intel] [941279.423714] [] ? kvm_arch_vcpu_ioctl_run+0x504/0x16d0 [kvm] [941279.431306] [] ? synchronize_sched+0x59/0x80 [941279.437561] [] ? call_rcu_bh+0x20/0x20 [941279.443248] [] ? kvm_vcpu_ioctl+0x315/0x5e0 [kvm] [941279.449809] [] ? signal_setup_done+0x67/0xb0 [941279.455929] [] ? __seccomp_filter+0x74/0x270 [941279.462061] [] ? do_vfs_ioctl+0xa2/0x620 [941279.467844] [] ? __audit_syscall_entry+0xaa/0xf0 [941279.474572] [] ? syscall_trace_enter+0x1ae/0x2c0 [941279.481644] [] ? SyS_ioctl+0x74/0x80 [941279.487068] [] ? do_syscall_64+0x8d/0x100 [941279.493187] [] ? entry_SYSCALL_64_after_swapgs+0x58/0xc6 [941824.391767] INFO: rcu_sched detected stalls on CPUs/tasks: [941824.397768] 39-...: (1 GPs behind) idle=3f9/140000000000000/0 softirq=109349815/109349816 fqs=2294 [941824.407127] (detected by 34, t=5256 jiffies, g=37046974, c=37046973, q=61353) [941824.414723] Task dump for CPU 39: [941824.418310] exe R running task 0 41547 41521 0x00000988 [941824.425828] 0000000000000000 ffff9fc52b5d0540 0000000101