D0408 04:39:32.566855 1 parameters.go:238] Clock(Monotonic): error: 202 ns, adjusted frequency from 2299807475 Hz to 2299808005 Hz D0408 04:39:32.566982 1 parameters.go:238] Clock(Realtime): error: 155 ns, adjusted frequency from 2299807494 Hz to 2299807898 Hz D0408 04:39:32.567003 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5225289986470415 monotonicBaseRef:4000195029 monotonicFrequency:2299808005 realtimeReady:1 realtimeBaseCycles:5225289986790392 realtimeBaseRef:1586320772566978214 realtimeFrequency:2299807898} D0408 04:39:33.566860 1 parameters.go:238] Clock(Monotonic): error: 23 ns, adjusted frequency from 2299808005 Hz to 2299807606 Hz D0408 04:39:33.566973 1 parameters.go:238] Clock(Realtime): error: 32 ns, adjusted frequency from 2299807898 Hz to 2299807627 Hz D0408 04:39:33.566993 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5225292286303031 monotonicBaseRef:5000205730 monotonicFrequency:2299807606 realtimeReady:1 realtimeBaseCycles:5225292286583228 realtimeBaseRef:1586320773566971664 realtimeFrequency:2299807627} D0408 04:39:34.566836 1 parameters.go:238] Clock(Monotonic): error: -126 ns, adjusted frequency from 2299807606 Hz to 2299807222 Hz D0408 04:39:34.566934 1 parameters.go:238] Clock(Realtime): error: -118 ns, adjusted frequency from 2299807627 Hz to 2299807287 Hz D0408 04:39:34.566948 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5225294586062758 monotonicBaseRef:6000184911 monotonicFrequency:2299807222 realtimeReady:1 realtimeBaseCycles:5225294586301315 realtimeBaseRef:1586320774566932730 realtimeFrequency:2299807287} D0408 04:39:35.566925 1 parameters.go:238] Clock(Monotonic): error: 220 ns, adjusted frequency from 2299807222 Hz to 2299808084 Hz D0408 04:39:35.567018 1 parameters.go:238] Clock(Realtime): error: 214 ns, adjusted frequency from 2299807287 Hz to 2299808083 Hz D0408 04:39:35.567036 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5225296885989434 monotonicBaseRef:7000236851 monotonicFrequency:2299808084 realtimeReady:1 realtimeBaseCycles:5225296886301713 realtimeBaseRef:1586320775567016698 realtimeFrequency:2299808083} D0408 04:39:36.566840 1 parameters.go:238] Clock(Monotonic): error: 41 ns, adjusted frequency from 2299808084 Hz to 2299807695 Hz D0408 04:39:36.566982 1 parameters.go:238] Clock(Realtime): error: 14 ns, adjusted frequency from 2299808083 Hz to 2299807628 Hz D0408 04:39:36.567001 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5225299185646829 monotonicBaseRef:8000171328 monotonicFrequency:2299807695 realtimeReady:1 realtimeBaseCycles:5225299186024209 realtimeBaseRef:1586320776566979483 realtimeFrequency:2299807628} D0408 04:39:37.567031 1 parameters.go:238] Clock(Monotonic): error: -196 ns, adjusted frequency from 2299807695 Hz to 2299807134 Hz D0408 04:39:37.567105 1 parameters.go:238] Clock(Realtime): error: 27 ns, adjusted frequency from 2299807628 Hz to 2299807664 Hz D0408 04:39:37.567124 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5225301485934102 monotonicBaseRef:9000379857 monotonicFrequency:2299807134 realtimeReady:1 realtimeBaseCycles:5225301486118409 realtimeBaseRef:1586320777567104089 realtimeFrequency:2299807664} D0408 04:39:38.566885 1 parameters.go:238] Clock(Monotonic): error: 197 ns, adjusted frequency from 2299807134 Hz to 2299808098 Hz D0408 04:39:38.566975 1 parameters.go:238] Clock(Realtime): error: -183 ns, adjusted frequency from 2299807664 Hz to 2299807215 Hz D0408 04:39:38.566989 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5225303785402723 monotonicBaseRef:10000232665 monotonicFrequency:2299808098 realtimeReady:1 realtimeBaseCycles:5225303785625395 realtimeBaseRef:1586320778566973348 realtimeFrequency:2299807215} D0408 04:39:39.566840 1 parameters.go:238] Clock(Monotonic): error: 43 ns, adjusted frequency from 2299808098 Hz to 2299807744 Hz D0408 04:39:39.566970 1 parameters.go:238] Clock(Realtime): error: 250 ns, adjusted frequency from 2299807215 Hz to 2299808234 Hz D0408 04:39:39.566986 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5225306085063221 monotonicBaseRef:11000168485 monotonicFrequency:2299807744 realtimeReady:1 realtimeBaseCycles:5225306085422547 realtimeBaseRef:1586320779566968972 realtimeFrequency:2299808234} D0408 04:39:40.566839 1 parameters.go:238] Clock(Monotonic): error: -18 ns, adjusted frequency from 2299807744 Hz to 2299807605 Hz D0408 04:39:40.566965 1 sampler.go:168] Time: Adjusting syscall overhead up to 8000 D0408 04:39:40.567005 1 parameters.go:238] Clock(Realtime): error: -21 ns, adjusted frequency from 2299808234 Hz to 2299807567 Hz D0408 04:39:40.567025 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5225308384908736 monotonicBaseRef:12000184908 monotonicFrequency:2299807605 realtimeReady:1 realtimeBaseCycles:5225308385307035 realtimeBaseRef:1586320780567002128 realtimeFrequency:2299807567} D0408 04:39:41.566911 1 parameters.go:238] Clock(Monotonic): error: 61 ns, adjusted frequency from 2299807605 Hz to 2299807794 Hz D0408 04:39:41.566996 1 parameters.go:238] Clock(Realtime): error: 365 ns, adjusted frequency from 2299807567 Hz to 2299808397 Hz D0408 04:39:41.567017 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5225310684882228 monotonicBaseRef:13000257038 monotonicFrequency:2299807794 realtimeReady:1 realtimeBaseCycles:5225310685096451 realtimeBaseRef:1586320781566994235 realtimeFrequency:2299808397} D0408 04:39:42.566905 1 parameters.go:238] Clock(Monotonic): error: -238 ns, adjusted frequency from 2299807794 Hz to 2299807082 Hz D0408 04:39:42.567030 1 parameters.go:238] Clock(Realtime): error: -322 ns, adjusted frequency from 2299808397 Hz to 2299806908 Hz D0408 04:39:42.567051 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5225312984677044 monotonicBaseRef:14000251394 monotonicFrequency:2299807082 realtimeReady:1 realtimeBaseCycles:5225312984981803 realtimeBaseRef:1586320782567027696 realtimeFrequency:2299806908} D0408 04:39:43.566948 1 parameters.go:238] Clock(Monotonic): error: 305 ns, adjusted frequency from 2299807082 Hz to 2299808414 Hz D0408 04:39:43.567056 1 parameters.go:238] Clock(Realtime): error: 219 ns, adjusted frequency from 2299806908 Hz to 2299808138 Hz D0408 04:39:43.567072 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5225315284578992 monotonicBaseRef:15000292643 monotonicFrequency:2299808414 realtimeReady:1 realtimeBaseCycles:5225315284848469 realtimeBaseRef:1586320783567053679 realtimeFrequency:2299808138} D0408 04:39:44.566868 1 parameters.go:238] Clock(Monotonic): error: -142 ns, adjusted frequency from 2299808414 Hz to 2299807326 Hz D0408 04:39:44.567069 1 parameters.go:238] Clock(Realtime): error: 37 ns, adjusted frequency from 2299808138 Hz to 2299807671 Hz D0408 04:39:44.567093 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5225317584143445 monotonicBaseRef:16000186564 monotonicFrequency:2299807326 realtimeReady:1 realtimeBaseCycles:5225317584656364 realtimeBaseRef:1586320784567053573 realtimeFrequency:2299807671} D0408 04:39:45.566800 1 parameters.go:238] Clock(Monotonic): error: 24 ns, adjusted frequency from 2299807326 Hz to 2299807697 Hz D0408 04:39:45.566889 1 parameters.go:238] Clock(Realtime): error: 28 ns, adjusted frequency from 2299807671 Hz to 2299807659 Hz D0408 04:39:45.566908 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5225319883859536 monotonicBaseRef:17000146893 monotonicFrequency:2299807697 realtimeReady:1 realtimeBaseCycles:5225319884081351 realtimeBaseRef:1586320785566887174 realtimeFrequency:2299807659} D0408 04:39:46.566836 1 parameters.go:238] Clock(Monotonic): error: -175 ns, adjusted frequency from 2299807697 Hz to 2299807236 Hz D0408 04:39:46.566934 1 parameters.go:238] Clock(Realtime): error: -39 ns, adjusted frequency from 2299807659 Hz to 2299807528 Hz D0408 04:39:46.566952 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5225322183751179 monotonicBaseRef:18000183394 monotonicFrequency:2299807236 realtimeReady:1 realtimeBaseCycles:5225322183991518 realtimeBaseRef:1586320786566931746 realtimeFrequency:2299807528} D0408 04:39:47.566895 1 parameters.go:238] Clock(Monotonic): error: 191 ns, adjusted frequency from 2299807236 Hz to 2299808097 Hz D0408 04:39:47.566990 1 parameters.go:238] Clock(Realtime): error: -273 ns, adjusted frequency from 2299807528 Hz to 2299806964 Hz D0408 04:39:47.567009 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5225324483690705 monotonicBaseRef:19000240916 monotonicFrequency:2299808097 realtimeReady:1 realtimeBaseCycles:5225324483929943 realtimeBaseRef:1586320787566988662 realtimeFrequency:2299806964} D0408 04:39:48.566896 1 sampler.go:168] Time: Adjusting syscall overhead up to 8000 D0408 04:39:48.566980 1 parameters.go:238] Clock(Monotonic): error: 77 ns, adjusted frequency from 2299808097 Hz to 2299807802 Hz D0408 04:39:48.567008 1 parameters.go:238] Clock(Realtime): error: 237 ns, adjusted frequency from 2299806964 Hz to 2299808150 Hz D0408 04:39:48.567026 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5225326783708915 monotonicBaseRef:20000332277 monotonicFrequency:2299807802 realtimeReady:1 realtimeBaseCycles:5225326783777355 realtimeBaseRef:1586320788567006249 realtimeFrequency:2299808150} D0408 04:39:49.566914 1 parameters.go:238] Clock(Monotonic): error: -60 ns, adjusted frequency from 2299807802 Hz to 2299807396 Hz D0408 04:39:49.567015 1 parameters.go:238] Clock(Realtime): error: 159 ns, adjusted frequency from 2299808150 Hz to 2299808017 Hz D0408 04:39:49.567036 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5225329083330942 monotonicBaseRef:21000251498 monotonicFrequency:2299807396 realtimeReady:1 realtimeBaseCycles:5225329083599702 realtimeBaseRef:1586320789567012422 realtimeFrequency:2299808017} D0408 04:39:50.566866 1 parameters.go:238] Clock(Monotonic): error: 329 ns, adjusted frequency from 2299807396 Hz to 2299808287 Hz D0408 04:39:50.566971 1 parameters.go:238] Clock(Realtime): error: 18 ns, adjusted frequency from 2299808017 Hz to 2299807734 Hz D0408 04:39:50.566992 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5225331383052113 monotonicBaseRef:22000214005 monotonicFrequency:2299808287 realtimeReady:1 realtimeBaseCycles:5225331383308137 realtimeBaseRef:1586320790566969121 realtimeFrequency:2299807734} D0408 04:39:51.566775 1 parameters.go:238] Clock(Monotonic): error: -146 ns, adjusted frequency from 2299808287 Hz to 2299807210 Hz D0408 04:39:51.566874 1 parameters.go:238] Clock(Realtime): error: -348 ns, adjusted frequency from 2299807734 Hz to 2299806802 Hz D0408 04:39:51.566887 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5225333682651473 monotonicBaseRef:23000123159 monotonicFrequency:2299807210 realtimeReady:1 realtimeBaseCycles:5225333682892606 realtimeBaseRef:1586320791566872041 realtimeFrequency:2299806802} D0408 04:39:52.566821 1 parameters.go:238] Clock(Monotonic): error: 124 ns, adjusted frequency from 2299807210 Hz to 2299807784 Hz D0408 04:39:52.566942 1 parameters.go:238] Clock(Realtime): error: 234 ns, adjusted frequency from 2299806802 Hz to 2299808181 Hz D0408 04:39:52.566960 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5225335982560783 monotonicBaseRef:24000167554 monotonicFrequency:2299807784 realtimeReady:1 realtimeBaseCycles:5225335982856862 realtimeBaseRef:1586320792566940505 realtimeFrequency:2299808181} D0408 04:39:53.566898 1 parameters.go:238] Clock(Monotonic): error: 264 ns, adjusted frequency from 2299807784 Hz to 2299808131 Hz D0408 04:39:53.566993 1 parameters.go:238] Clock(Realtime): error: 43 ns, adjusted frequency from 2299808181 Hz to 2299807760 Hz D0408 04:39:53.567011 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5225338282545530 monotonicBaseRef:25000244500 monotonicFrequency:2299808131 realtimeReady:1 realtimeBaseCycles:5225338282781771 realtimeBaseRef:1586320793566991260 realtimeFrequency:2299807760} D0408 04:39:54.566871 1 parameters.go:238] Clock(Monotonic): error: 64 ns, adjusted frequency from 2299808131 Hz to 2299807715 Hz D0408 04:39:54.566960 1 parameters.go:238] Clock(Realtime): error: -37 ns, adjusted frequency from 2299807760 Hz to 2299807556 Hz D0408 04:39:54.566978 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5225340582246353 monotonicBaseRef:26000197840 monotonicFrequency:2299807715 realtimeReady:1 realtimeBaseCycles:5225340582511840 realtimeBaseRef:1586320794566957478 realtimeFrequency:2299807556} D0408 04:39:55.566866 1 parameters.go:238] Clock(Monotonic): error: -553 ns, adjusted frequency from 2299807715 Hz to 2299806388 Hz D0408 04:39:55.566948 1 parameters.go:238] Clock(Realtime): error: -237 ns, adjusted frequency from 2299807556 Hz to 2299807031 Hz D0408 04:39:55.566960 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5225342882086677 monotonicBaseRef:27000212019 monotonicFrequency:2299806388 realtimeReady:1 realtimeBaseCycles:5225342882293462 realtimeBaseRef:1586320795566946201 realtimeFrequency:2299807031} D0408 04:39:56.566947 1 parameters.go:238] Clock(Monotonic): error: 368 ns, adjusted frequency from 2299806388 Hz to 2299808519 Hz D0408 04:39:56.567042 1 parameters.go:238] Clock(Realtime): error: 225 ns, adjusted frequency from 2299807031 Hz to 2299808192 Hz D0408 04:39:56.567063 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5225345182082634 monotonicBaseRef:28000294447 monotonicFrequency:2299808519 realtimeReady:1 realtimeBaseCycles:5225345182317210 realtimeBaseRef:1586320796567040433 realtimeFrequency:2299808192} D0408 04:39:57.566849 1 parameters.go:238] Clock(Monotonic): error: -129 ns, adjusted frequency from 2299808519 Hz to 2299807373 Hz D0408 04:39:57.566941 1 parameters.go:238] Clock(Realtime): error: -184 ns, adjusted frequency from 2299808192 Hz to 2299807189 Hz D0408 04:39:57.566953 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5225347481664461 monotonicBaseRef:29000195877 monotonicFrequency:2299807373 realtimeReady:1 realtimeBaseCycles:5225347481891783 realtimeBaseRef:1586320797566938851 realtimeFrequency:2299807189} D0408 04:39:58.566843 1 parameters.go:238] Clock(Monotonic): error: 288 ns, adjusted frequency from 2299807373 Hz to 2299808312 Hz D0408 04:39:58.566938 1 parameters.go:238] Clock(Realtime): error: 122 ns, adjusted frequency from 2299807189 Hz to 2299807874 Hz D0408 04:39:58.566958 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5225349781458839 monotonicBaseRef:30000190226 monotonicFrequency:2299808312 realtimeReady:1 realtimeBaseCycles:5225349781694213 realtimeBaseRef:1586320798566936781 realtimeFrequency:2299807874} D0408 04:39:59.566799 1 parameters.go:238] Clock(Monotonic): error: 139 ns, adjusted frequency from 2299808312 Hz to 2299807998 Hz D0408 04:39:59.566893 1 parameters.go:238] Clock(Realtime): error: 166 ns, adjusted frequency from 2299807874 Hz to 2299808051 Hz D0408 04:39:59.566910 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5225352081129544 monotonicBaseRef:31000130391 monotonicFrequency:2299807998 realtimeReady:1 realtimeBaseCycles:5225352081396170 realtimeBaseRef:1586320799566890726 realtimeFrequency:2299808051} D0408 04:40:00.566961 1 parameters.go:238] Clock(Monotonic): error: -521 ns, adjusted frequency from 2299807998 Hz to 2299806551 Hz D0408 04:40:00.567049 1 parameters.go:238] Clock(Realtime): error: 114 ns, adjusted frequency from 2299808051 Hz to 2299807910 Hz D0408 04:40:00.567065 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5225354381337531 monotonicBaseRef:32000304313 monotonicFrequency:2299806551 realtimeReady:1 realtimeBaseCycles:5225354381564141 realtimeBaseRef:1586320800567047226 realtimeFrequency:2299807910} D0408 04:40:01.566846 1 parameters.go:238] Clock(Monotonic): error: 156 ns, adjusted frequency from 2299806551 Hz to 2299808147 Hz D0408 04:40:01.566941 1 parameters.go:238] Clock(Realtime): error: -305 ns, adjusted frequency from 2299807910 Hz to 2299806878 Hz D0408 04:40:01.566957 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5225356680889313 monotonicBaseRef:33000193534 monotonicFrequency:2299808147 realtimeReady:1 realtimeBaseCycles:5225356681122681 realtimeBaseRef:1586320801566938795 realtimeFrequency:2299806878} D0408 04:40:02.566864 1 parameters.go:238] Clock(Monotonic): error: -187 ns, adjusted frequency from 2299808147 Hz to 2299807234 Hz D0408 04:40:02.566969 1 parameters.go:238] Clock(Realtime): error: 153 ns, adjusted frequency from 2299806878 Hz to 2299807971 Hz D0408 04:40:02.567010 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5225358980733837 monotonicBaseRef:34000209351 monotonicFrequency:2299807234 realtimeReady:1 realtimeBaseCycles:5225358980990095 realtimeBaseRef:1586320802566965117 realtimeFrequency:2299807971} D0408 04:40:03.516669 1 urpc.go:577] urpc: unmarshal success. D0408 04:40:03.516735 1 controller.go:504] containerManager.Signal &{CID:ci-gvisor-kvm-direct-sandbox-0 Signo:0 PID:0 Mode:Process} D0408 04:40:03.516834 1 urpc.go:534] urpc: successfully marshalled 37 bytes. D0408 04:40:03.566903 1 parameters.go:238] Clock(Monotonic): error: -254 ns, adjusted frequency from 2299807234 Hz to 2299807133 Hz D0408 04:40:03.566997 1 parameters.go:238] Clock(Realtime): error: 156 ns, adjusted frequency from 2299807971 Hz to 2299808034 Hz D0408 04:40:03.567017 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5225361280641109 monotonicBaseRef:35000252849 monotonicFrequency:2299807133 realtimeReady:1 realtimeBaseCycles:5225361280865931 realtimeBaseRef:1586320803566994625 realtimeFrequency:2299808034} D0408 04:40:04.566846 1 parameters.go:238] Clock(Monotonic): error: 244 ns, adjusted frequency from 2299807133 Hz to 2299808225 Hz D0408 04:40:04.566948 1 parameters.go:238] Clock(Realtime): error: -23 ns, adjusted frequency from 2299808034 Hz to 2299807618 Hz D0408 04:40:04.566967 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5225363580310120 monotonicBaseRef:36000192790 monotonicFrequency:2299808225 realtimeReady:1 realtimeBaseCycles:5225363580560204 realtimeBaseRef:1586320804566945159 realtimeFrequency:2299807618} D0408 04:40:05.567729 1 parameters.go:238] Clock(Monotonic): error: 288 ns, adjusted frequency from 2299808225 Hz to 2299808192 Hz D0408 04:40:05.567817 1 parameters.go:238] Clock(Realtime): error: -94 ns, adjusted frequency from 2299807618 Hz to 2299807382 Hz D0408 04:40:05.567856 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5225365882152408 monotonicBaseRef:37001077238 monotonicFrequency:2299808192 realtimeReady:1 realtimeBaseCycles:5225365882368496 realtimeBaseRef:1586320805567815090 realtimeFrequency:2299807382} D0408 04:40:06.566838 1 parameters.go:238] Clock(Monotonic): error: -351 ns, adjusted frequency from 2299808192 Hz to 2299806838 Hz D0408 04:40:06.566954 1 parameters.go:238] Clock(Realtime): error: 77 ns, adjusted frequency from 2299807382 Hz to 2299807831 Hz D0408 04:40:06.566972 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5225368179904032 monotonicBaseRef:38000183003 monotonicFrequency:2299806838 realtimeReady:1 realtimeBaseCycles:5225368180187826 realtimeBaseRef:1586320806566950647 realtimeFrequency:2299807831} D0408 04:40:07.566846 1 parameters.go:238] Clock(Monotonic): error: -8 ns, adjusted frequency from 2299806838 Hz to 2299807730 Hz D0408 04:40:07.566935 1 parameters.go:238] Clock(Realtime): error: -57 ns, adjusted frequency from 2299807831 Hz to 2299807575 Hz D0408 04:40:07.566952 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5225370479737128 monotonicBaseRef:39000194420 monotonicFrequency:2299807730 realtimeReady:1 realtimeBaseCycles:5225370479952563 realtimeBaseRef:1586320807566931908 realtimeFrequency:2299807575} D0408 04:40:08.566821 1 parameters.go:238] Clock(Monotonic): error: 382 ns, adjusted frequency from 2299807730 Hz to 2299808493 Hz D0408 04:40:08.566910 1 parameters.go:238] Clock(Realtime): error: 87 ns, adjusted frequency from 2299807575 Hz to 2299807865 Hz D0408 04:40:08.566927 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5225372779484869 monotonicBaseRef:40000168335 monotonicFrequency:2299808493 realtimeReady:1 realtimeBaseCycles:5225372779705490 realtimeBaseRef:1586320808566908146 realtimeFrequency:2299807865} D0408 04:40:09.566854 1 parameters.go:238] Clock(Monotonic): error: 22 ns, adjusted frequency from 2299808493 Hz to 2299807602 Hz D0408 04:40:09.566958 1 parameters.go:238] Clock(Realtime): error: -15 ns, adjusted frequency from 2299807865 Hz to 2299807595 Hz D0408 04:40:09.566979 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5225375079367742 monotonicBaseRef:41000200676 monotonicFrequency:2299807602 realtimeReady:1 realtimeBaseCycles:5225375079624366 realtimeBaseRef:1586320809566956415 realtimeFrequency:2299807595} D0408 04:40:10.566818 1 parameters.go:238] Clock(Monotonic): error: -102 ns, adjusted frequency from 2299807602 Hz to 2299807273 Hz D0408 04:40:10.566916 1 parameters.go:238] Clock(Realtime): error: -230 ns, adjusted frequency from 2299807595 Hz to 2299807168 Hz D0408 04:40:10.566935 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5225377379089873 monotonicBaseRef:42000163511 monotonicFrequency:2299807273 realtimeReady:1 realtimeBaseCycles:5225377379335152 realtimeBaseRef:1586320810566914320 realtimeFrequency:2299807168} D0408 04:40:11.566798 1 parameters.go:238] Clock(Monotonic): error: 3 ns, adjusted frequency from 2299807273 Hz to 2299807790 Hz D0408 04:40:11.566913 1 parameters.go:238] Clock(Realtime): error: 103 ns, adjusted frequency from 2299807168 Hz to 2299807940 Hz D0408 04:40:11.566933 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5225379678849003 monotonicBaseRef:43000142577 monotonicFrequency:2299807790 realtimeReady:1 realtimeBaseCycles:5225379679137189 realtimeBaseRef:1586320811566912088 realtimeFrequency:2299807940} D0408 04:40:12.566889 1 parameters.go:238] Clock(Monotonic): error: 139 ns, adjusted frequency from 2299807790 Hz to 2299807864 Hz D0408 04:40:12.566992 1 parameters.go:238] Clock(Realtime): error: 26 ns, adjusted frequency from 2299807940 Hz to 2299807694 Hz D0408 04:40:12.567011 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5225381978866601 monotonicBaseRef:44000233805 monotonicFrequency:2299807864 realtimeReady:1 realtimeBaseCycles:5225381979124483 realtimeBaseRef:1586320812566990074 realtimeFrequency:2299807694} D0408 04:40:13.566812 1 parameters.go:238] Clock(Monotonic): error: 98 ns, adjusted frequency from 2299807864 Hz to 2299807787 Hz D0408 04:40:13.566916 1 parameters.go:238] Clock(Realtime): error: -1 ns, adjusted frequency from 2299807694 Hz to 2299807666 Hz D0408 04:40:13.566955 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5225384278500644 monotonicBaseRef:45000158224 monotonicFrequency:2299807787 realtimeReady:1 realtimeBaseCycles:5225384278756911 realtimeBaseRef:1586320813566913865 realtimeFrequency:2299807666} I0408 04:40:13.601331 1 watchdog.go:277] Watchdog starting loop, tasks: 27, discount: 0s D0408 04:40:14.566852 1 parameters.go:238] Clock(Monotonic): error: -418 ns, adjusted frequency from 2299807787 Hz to 2299806782 Hz D0408 04:40:14.566949 1 parameters.go:238] Clock(Realtime): error: -95 ns, adjusted frequency from 2299807666 Hz to 2299807463 Hz D0408 04:40:14.566966 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5225386578397803 monotonicBaseRef:46000197084 monotonicFrequency:2299806782 realtimeReady:1 realtimeBaseCycles:5225386578639966 realtimeBaseRef:1586320814566946645 realtimeFrequency:2299807463} D0408 04:40:15.566856 1 parameters.go:238] Clock(Monotonic): error: 474 ns, adjusted frequency from 2299806782 Hz to 2299808677 Hz D0408 04:40:15.566980 1 parameters.go:238] Clock(Realtime): error: 93 ns, adjusted frequency from 2299807463 Hz to 2299807904 Hz D0408 04:40:15.567000 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5225388878211011 monotonicBaseRef:47000199878 monotonicFrequency:2299808677 realtimeReady:1 realtimeBaseCycles:5225388878519144 realtimeBaseRef:1586320815566977828 realtimeFrequency:2299807904} D0408 04:40:16.566851 1 parameters.go:238] Clock(Monotonic): error: -76 ns, adjusted frequency from 2299808677 Hz to 2299807410 Hz D0408 04:40:16.566951 1 parameters.go:238] Clock(Realtime): error: 62 ns, adjusted frequency from 2299807904 Hz to 2299807782 Hz D0408 04:40:16.566969 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5225391178012098 monotonicBaseRef:48000196577 monotonicFrequency:2299807410 realtimeReady:1 realtimeBaseCycles:5225391178261464 realtimeBaseRef:1586320816566949310 realtimeFrequency:2299807782} D0408 04:40:17.566909 1 parameters.go:238] Clock(Monotonic): error: 20 ns, adjusted frequency from 2299807410 Hz to 2299807740 Hz D0408 04:40:17.567005 1 parameters.go:238] Clock(Realtime): error: -34 ns, adjusted frequency from 2299807782 Hz to 2299807559 Hz D0408 04:40:17.567026 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5225393477954263 monotonicBaseRef:49000255171 monotonicFrequency:2299807740 realtimeReady:1 realtimeBaseCycles:5225393478193240 realtimeBaseRef:1586320817567003224 realtimeFrequency:2299807559} D0408 04:40:18.566829 1 parameters.go:238] Clock(Monotonic): error: -422 ns, adjusted frequency from 2299807740 Hz to 2299806802 Hz D0408 04:40:18.566927 1 parameters.go:238] Clock(Realtime): error: -81 ns, adjusted frequency from 2299807559 Hz to 2299807477 Hz D0408 04:40:18.566947 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5225395777579620 monotonicBaseRef:50000175867 monotonicFrequency:2299806802 realtimeReady:1 realtimeBaseCycles:5225395777821161 realtimeBaseRef:1586320818566925113 realtimeFrequency:2299807477} D0408 04:40:19.566833 1 parameters.go:238] Clock(Monotonic): error: 319 ns, adjusted frequency from 2299806802 Hz to 2299808402 Hz D0408 04:40:19.566916 1 parameters.go:238] Clock(Realtime): error: 9 ns, adjusted frequency from 2299807477 Hz to 2299807663 Hz D0408 04:40:19.566933 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5225398077392829 monotonicBaseRef:51000178652 monotonicFrequency:2299808402 realtimeReady:1 realtimeBaseCycles:5225398077603577 realtimeBaseRef:1586320819566914216 realtimeFrequency:2299807663} D0408 04:40:20.566800 1 parameters.go:238] Clock(Monotonic): error: -116 ns, adjusted frequency from 2299808402 Hz to 2299807257 Hz D0408 04:40:20.566899 1 parameters.go:238] Clock(Realtime): error: -309 ns, adjusted frequency from 2299807663 Hz to 2299806886 Hz D0408 04:40:20.566911 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5225400377128563 monotonicBaseRef:52000147054 monotonicFrequency:2299807257 realtimeReady:1 realtimeBaseCycles:5225400377372018 realtimeBaseRef:1586320820566897161 realtimeFrequency:2299806886} D0408 04:40:21.566907 1 parameters.go:238] Clock(Monotonic): error: -229 ns, adjusted frequency from 2299807257 Hz to 2299807237 Hz D0408 04:40:21.567013 1 parameters.go:238] Clock(Realtime): error: 518 ns, adjusted frequency from 2299806886 Hz to 2299808802 Hz D0408 04:40:21.567031 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5225402677172975 monotonicBaseRef:53000250173 monotonicFrequency:2299807237 realtimeReady:1 realtimeBaseCycles:5225402677441613 realtimeBaseRef:1586320821567011391 realtimeFrequency:2299808802} D0408 04:40:22.566875 1 parameters.go:238] Clock(Monotonic): error: 50 ns, adjusted frequency from 2299807237 Hz to 2299807808 Hz D0408 04:40:22.566982 1 parameters.go:238] Clock(Realtime): error: -157 ns, adjusted frequency from 2299808802 Hz to 2299807294 Hz D0408 04:40:22.567000 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5225404976920053 monotonicBaseRef:54000224014 monotonicFrequency:2299807808 realtimeReady:1 realtimeBaseCycles:5225404977176776 realtimeBaseRef:1586320822566979371 realtimeFrequency:2299807294} D0408 04:40:23.566833 1 parameters.go:238] Clock(Monotonic): error: 137 ns, adjusted frequency from 2299807808 Hz to 2299807994 Hz D0408 04:40:23.566937 1 parameters.go:238] Clock(Realtime): error: 72 ns, adjusted frequency from 2299807294 Hz to 2299807815 Hz D0408 04:40:23.566985 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5225407276622417 monotonicBaseRef:55000178164 monotonicFrequency:2299807994 realtimeReady:1 realtimeBaseCycles:5225407276883031 realtimeBaseRef:1586320823566935437 realtimeFrequency:2299807815} D0408 04:40:23.699800 1 urpc.go:577] urpc: unmarshal success. D0408 04:40:23.699870 1 controller.go:504] containerManager.Signal &{CID:ci-gvisor-kvm-direct-sandbox-0 Signo:0 PID:0 Mode:Process} D0408 04:40:23.699977 1 urpc.go:534] urpc: successfully marshalled 37 bytes. D0408 04:40:24.566989 1 parameters.go:238] Clock(Monotonic): error: -185 ns, adjusted frequency from 2299807994 Hz to 2299807299 Hz D0408 04:40:24.567079 1 parameters.go:238] Clock(Realtime): error: -4 ns, adjusted frequency from 2299807815 Hz to 2299807620 Hz D0408 04:40:24.567098 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5225409576707666 monotonicBaseRef:56000298719 monotonicFrequency:2299807299 realtimeReady:1 realtimeBaseCycles:5225409577014582 realtimeBaseRef:1586320824567076203 realtimeFrequency:2299807620} D0408 04:40:25.566908 1 parameters.go:238] Clock(Monotonic): error: 243 ns, adjusted frequency from 2299807299 Hz to 2299808190 Hz D0408 04:40:25.567027 1 parameters.go:238] Clock(Realtime): error: 62 ns, adjusted frequency from 2299807620 Hz to 2299807818 Hz D0408 04:40:25.567048 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5225411876408054 monotonicBaseRef:57000252232 monotonicFrequency:2299808190 realtimeReady:1 realtimeBaseCycles:5225411876705815 realtimeBaseRef:1586320825567025595 realtimeFrequency:2299807818} D0408 04:40:26.566931 1 parameters.go:238] Clock(Monotonic): error: -147 ns, adjusted frequency from 2299808190 Hz to 2299807371 Hz D0408 04:40:26.567025 1 parameters.go:238] Clock(Realtime): error: -51 ns, adjusted frequency from 2299807818 Hz to 2299807529 Hz D0408 04:40:26.567043 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5225414176247622 monotonicBaseRef:58000265875 monotonicFrequency:2299807371 realtimeReady:1 realtimeBaseCycles:5225414176508958 realtimeBaseRef:1586320826567023562 realtimeFrequency:2299807529} D0408 04:40:27.566938 1 parameters.go:238] Clock(Monotonic): error: -279 ns, adjusted frequency from 2299807371 Hz to 2299807000 Hz D0408 04:40:27.567048 1 parameters.go:238] Clock(Realtime): error: -398 ns, adjusted frequency from 2299807529 Hz to 2299806775 Hz D0408 04:40:27.567077 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5225416476098841 monotonicBaseRef:59000284940 monotonicFrequency:2299807000 realtimeReady:1 realtimeBaseCycles:5225416476365179 realtimeBaseRef:1586320827567044734 realtimeFrequency:2299806775} D0408 04:40:28.566884 1 parameters.go:238] Clock(Monotonic): error: 508 ns, adjusted frequency from 2299807000 Hz to 2299808831 Hz D0408 04:40:28.566972 1 parameters.go:238] Clock(Realtime): error: 215 ns, adjusted frequency from 2299806775 Hz to 2299808132 Hz D0408 04:40:28.566989 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5225418775782530 monotonicBaseRef:60000231322 monotonicFrequency:2299808831 realtimeReady:1 realtimeBaseCycles:5225418776001287 realtimeBaseRef:1586320828566970524 realtimeFrequency:2299808132} D0408 04:40:29.567031 1 parameters.go:238] Clock(Monotonic): error: -198 ns, adjusted frequency from 2299808831 Hz to 2299807205 Hz D0408 04:40:29.567123 1 parameters.go:238] Clock(Realtime): error: -176 ns, adjusted frequency from 2299808132 Hz to 2299807267 Hz D0408 04:40:29.567136 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5225421075930088 monotonicBaseRef:61000378606 monotonicFrequency:2299807205 realtimeReady:1 realtimeBaseCycles:5225421076157677 realtimeBaseRef:1586320829567121953 realtimeFrequency:2299807267} D0408 04:40:30.567009 1 parameters.go:238] Clock(Monotonic): error: -45 ns, adjusted frequency from 2299807205 Hz to 2299807542 Hz D0408 04:40:30.567109 1 parameters.go:238] Clock(Realtime): error: 626 ns, adjusted frequency from 2299807267 Hz to 2299809186 Hz D0408 04:40:30.567128 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5225423375681361 monotonicBaseRef:62000354285 monotonicFrequency:2299807542 realtimeReady:1 realtimeBaseCycles:5225423375932629 realtimeBaseRef:1586320830567107901 realtimeFrequency:2299809186} D0408 04:40:31.566900 1 parameters.go:238] Clock(Monotonic): error: -145 ns, adjusted frequency from 2299807542 Hz to 2299807364 Hz D0408 04:40:31.566997 1 parameters.go:238] Clock(Realtime): error: -413 ns, adjusted frequency from 2299809186 Hz to 2299806722 Hz D0408 04:40:31.567017 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5225425675243844 monotonicBaseRef:63000247728 monotonicFrequency:2299807364 realtimeReady:1 realtimeBaseCycles:5225425675479917 realtimeBaseRef:1586320831566994022 realtimeFrequency:2299806722} D0408 04:40:32.566854 1 parameters.go:238] Clock(Monotonic): error: 273 ns, adjusted frequency from 2299807364 Hz to 2299808156 Hz D0408 04:40:32.566946 1 parameters.go:238] Clock(Realtime): error: 70 ns, adjusted frequency from 2299806722 Hz to 2299807834 Hz D0408 04:40:32.566981 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5225427974939310 monotonicBaseRef:64000199072 monotonicFrequency:2299808156 realtimeReady:1 realtimeBaseCycles:5225427975171855 realtimeBaseRef:1586320832566944111 realtimeFrequency:2299807834} D0408 04:40:33.566856 1 parameters.go:238] Clock(Monotonic): error: -234 ns, adjusted frequency from 2299808156 Hz to 2299807009 Hz D0408 04:40:33.566920 1 parameters.go:238] Clock(Realtime): error: -424 ns, adjusted frequency from 2299807834 Hz to 2299806661 Hz D0408 04:40:33.566933 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5225430274761758 monotonicBaseRef:65000205286 monotonicFrequency:2299807009 realtimeReady:1 realtimeBaseCycles:5225430274921420 realtimeBaseRef:1586320833566918774 realtimeFrequency:2299806661} D0408 04:40:34.566905 1 parameters.go:238] Clock(Monotonic): error: 377 ns, adjusted frequency from 2299807009 Hz to 2299808574 Hz D0408 04:40:34.567039 1 parameters.go:238] Clock(Realtime): error: 216 ns, adjusted frequency from 2299806661 Hz to 2299808160 Hz D0408 04:40:34.567064 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5225432574659587 monotonicBaseRef:66000244776 monotonicFrequency:2299808574 realtimeReady:1 realtimeBaseCycles:5225432575000676 realtimeBaseRef:1586320834567037303 realtimeFrequency:2299808160} D0408 04:40:35.566943 1 parameters.go:238] Clock(Monotonic): error: -14 ns, adjusted frequency from 2299808574 Hz to 2299807514 Hz D0408 04:40:35.567026 1 parameters.go:238] Clock(Realtime): error: 198 ns, adjusted frequency from 2299808160 Hz to 2299808101 Hz D0408 04:40:35.567045 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5225434874543060 monotonicBaseRef:67000277343 monotonicFrequency:2299807514 realtimeReady:1 realtimeBaseCycles:5225434874778131 realtimeBaseRef:1586320835567023951 realtimeFrequency:2299808101} D0408 04:40:36.566863 1 parameters.go:238] Clock(Monotonic): error: 52 ns, adjusted frequency from 2299807514 Hz to 2299807655 Hz D0408 04:40:36.566965 1 parameters.go:238] Clock(Realtime): error: 0 ns, adjusted frequency from 2299808101 Hz to 2299807614 Hz D0408 04:40:36.567011 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5225437174192372 monotonicBaseRef:68000208553 monotonicFrequency:2299807655 realtimeReady:1 realtimeBaseCycles:5225437174445684 realtimeBaseRef:1586320836566962838 realtimeFrequency:2299807614} D0408 04:40:37.566792 1 parameters.go:238] Clock(Monotonic): error: -350 ns, adjusted frequency from 2299807655 Hz to 2299806810 Hz D0408 04:40:37.566883 1 parameters.go:238] Clock(Realtime): error: -328 ns, adjusted frequency from 2299807614 Hz to 2299806928 Hz D0408 04:40:37.566897 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5225439473844218 monotonicBaseRef:69000140804 monotonicFrequency:2299806810 realtimeReady:1 realtimeBaseCycles:5225439474065719 realtimeBaseRef:1586320837566881275 realtimeFrequency:2299806928} D0408 04:40:38.566880 1 parameters.go:238] Clock(Monotonic): error: 462 ns, adjusted frequency from 2299806810 Hz to 2299808726 Hz D0408 04:40:38.567010 1 parameters.go:238] Clock(Realtime): error: 272 ns, adjusted frequency from 2299806928 Hz to 2299808284 Hz D0408 04:40:38.567029 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5225441773838551 monotonicBaseRef:70000222342 monotonicFrequency:2299808726 realtimeReady:1 realtimeBaseCycles:5225441774164080 realtimeBaseRef:1586320838567007995 realtimeFrequency:2299808284} D0408 04:40:39.566916 1 parameters.go:238] Clock(Monotonic): error: -147 ns, adjusted frequency from 2299808726 Hz to 2299807198 Hz D0408 04:40:39.567014 1 parameters.go:238] Clock(Realtime): error: 107 ns, adjusted frequency from 2299808284 Hz to 2299807879 Hz D0408 04:40:39.567033 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5225444073664371 monotonicBaseRef:71000229774 monotonicFrequency:2299807198 realtimeReady:1 realtimeBaseCycles:5225444073981494 realtimeBaseRef:1586320839567011964 realtimeFrequency:2299807879} D0408 04:40:40.566839 1 parameters.go:238] Clock(Monotonic): error: 169 ns, adjusted frequency from 2299807198 Hz to 2299807934 Hz D0408 04:40:40.566954 1 parameters.go:238] Clock(Realtime): error: 122 ns, adjusted frequency from 2299807879 Hz to 2299807903 Hz D0408 04:40:40.566993 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5225446373368273 monotonicBaseRef:72000184858 monotonicFrequency:2299807934 realtimeReady:1 realtimeBaseCycles:5225446373651945 realtimeBaseRef:1586320840566952207 realtimeFrequency:2299807903} D0408 04:40:41.566853 1 parameters.go:238] Clock(Monotonic): error: -78 ns, adjusted frequency from 2299807934 Hz to 2299807429 Hz D0408 04:40:41.566948 1 parameters.go:238] Clock(Realtime): error: -216 ns, adjusted frequency from 2299807903 Hz to 2299807141 Hz D0408 04:40:41.566967 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5225448673211702 monotonicBaseRef:73000200291 monotonicFrequency:2299807429 realtimeReady:1 realtimeBaseCycles:5225448673445140 realtimeBaseRef:1586320841566945811 realtimeFrequency:2299807141} D0408 04:40:42.566878 1 parameters.go:238] Clock(Monotonic): error: 94 ns, adjusted frequency from 2299807429 Hz to 2299808009 Hz D0408 04:40:42.566993 1 parameters.go:238] Clock(Realtime): error: 75 ns, adjusted frequency from 2299807141 Hz to 2299807832 Hz D0408 04:40:42.567014 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5225450973073245 monotonicBaseRef:74000223820 monotonicFrequency:2299808009 realtimeReady:1 realtimeBaseCycles:5225450973356385 realtimeBaseRef:1586320842566991077 realtimeFrequency:2299807832} D0408 04:40:43.566851 1 parameters.go:238] Clock(Monotonic): error: -68 ns, adjusted frequency from 2299808009 Hz to 2299807415 Hz D0408 04:40:43.566949 1 parameters.go:238] Clock(Realtime): error: 48 ns, adjusted frequency from 2299807832 Hz to 2299807729 Hz D0408 04:40:43.566970 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5225453272819010 monotonicBaseRef:75000196755 monotonicFrequency:2299807415 realtimeReady:1 realtimeBaseCycles:5225453273033205 realtimeBaseRef:1586320843566934110 realtimeFrequency:2299807729} D0408 04:40:44.566849 1 parameters.go:238] Clock(Monotonic): error: -24 ns, adjusted frequency from 2299807415 Hz to 2299807614 Hz D0408 04:40:44.566962 1 parameters.go:238] Clock(Realtime): error: -88 ns, adjusted frequency from 2299807729 Hz to 2299807442 Hz D0408 04:40:44.566982 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5225455572611657 monotonicBaseRef:76000190333 monotonicFrequency:2299807614 realtimeReady:1 realtimeBaseCycles:5225455572898887 realtimeBaseRef:1586320844566959309 realtimeFrequency:2299807442} D0408 04:40:45.566834 1 parameters.go:238] Clock(Monotonic): error: 165 ns, adjusted frequency from 2299807614 Hz to 2299808072 Hz D0408 04:40:45.566915 1 parameters.go:238] Clock(Realtime): error: 16 ns, adjusted frequency from 2299807442 Hz to 2299807704 Hz D0408 04:40:45.566932 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5225457872395301 monotonicBaseRef:77000179910 monotonicFrequency:2299808072 realtimeReady:1 realtimeBaseCycles:5225457872600959 realtimeBaseRef:1586320845566913492 realtimeFrequency:2299807704} D0408 04:40:46.566862 1 parameters.go:238] Clock(Monotonic): error: -516 ns, adjusted frequency from 2299808072 Hz to 2299806593 Hz D0408 04:40:46.566954 1 parameters.go:238] Clock(Realtime): error: -129 ns, adjusted frequency from 2299807704 Hz to 2299807331 Hz D0408 04:40:46.566972 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5225460172267689 monotonicBaseRef:78000207875 monotonicFrequency:2299806593 realtimeReady:1 realtimeBaseCycles:5225460172497849 realtimeBaseRef:1586320846566952271 realtimeFrequency:2299807331} D0408 04:40:47.566928 1 parameters.go:238] Clock(Monotonic): error: 450 ns, adjusted frequency from 2299806593 Hz to 2299808719 Hz D0408 04:40:47.567041 1 parameters.go:238] Clock(Realtime): error: 87 ns, adjusted frequency from 2299807331 Hz to 2299807812 Hz D0408 04:40:47.567069 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5225462472058579 monotonicBaseRef:79000201047 monotonicFrequency:2299808719 realtimeReady:1 realtimeBaseCycles:5225462472499438 realtimeBaseRef:1586320847567036738 realtimeFrequency:2299807812} D0408 04:40:48.566905 1 parameters.go:238] Clock(Monotonic): error: -279 ns, adjusted frequency from 2299808719 Hz to 2299807150 Hz D0408 04:40:48.567026 1 parameters.go:238] Clock(Realtime): error: -121 ns, adjusted frequency from 2299807812 Hz to 2299807332 Hz D0408 04:40:48.567044 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5225464771970513 monotonicBaseRef:80000245926 monotonicFrequency:2299807150 realtimeReady:1 realtimeBaseCycles:5225464772279229 realtimeBaseRef:1586320848567024553 realtimeFrequency:2299807332} D0408 04:40:49.566784 1 parameters.go:238] Clock(Monotonic): error: 34 ns, adjusted frequency from 2299807150 Hz to 2299807721 Hz D0408 04:40:49.566876 1 parameters.go:238] Clock(Realtime): error: 115 ns, adjusted frequency from 2299807332 Hz to 2299807930 Hz D0408 04:40:49.566894 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5225467071510928 monotonicBaseRef:81000129944 monotonicFrequency:2299807721 realtimeReady:1 realtimeBaseCycles:5225467071740341 realtimeBaseRef:1586320849566874009 realtimeFrequency:2299807930} D0408 04:40:50.566907 1 parameters.go:238] Clock(Monotonic): error: -258 ns, adjusted frequency from 2299807721 Hz to 2299807189 Hz D0408 04:40:50.567013 1 parameters.go:238] Clock(Realtime): error: -19 ns, adjusted frequency from 2299807930 Hz to 2299807629 Hz D0408 04:40:50.567036 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5225469371601304 monotonicBaseRef:82000252847 monotonicFrequency:2299807189 realtimeReady:1 realtimeBaseCycles:5225469371862964 realtimeBaseRef:1586320850567010843 realtimeFrequency:2299807629} D0408 04:40:51.566895 1 parameters.go:238] Clock(Monotonic): error: 109 ns, adjusted frequency from 2299807189 Hz to 2299807715 Hz D0408 04:40:51.567001 1 parameters.go:238] Clock(Realtime): error: 12 ns, adjusted frequency from 2299807629 Hz to 2299807690 Hz D0408 04:40:51.567021 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5225471671375242 monotonicBaseRef:83000238388 monotonicFrequency:2299807715 realtimeReady:1 realtimeBaseCycles:5225471671644715 realtimeBaseRef:1586320851566999590 realtimeFrequency:2299807690} D0408 04:40:52.566715 1 parameters.go:238] Clock(Monotonic): error: 249 ns, adjusted frequency from 2299807715 Hz to 2299808226 Hz D0408 04:40:52.566826 1 parameters.go:238] Clock(Realtime): error: -50 ns, adjusted frequency from 2299807690 Hz to 2299807513 Hz D0408 04:40:52.566841 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5225473970778422 monotonicBaseRef:84000062488 monotonicFrequency:2299808226 realtimeReady:1 realtimeBaseCycles:5225473971047977 realtimeBaseRef:1586320852566823737 realtimeFrequency:2299807513} D0408 04:40:53.566827 1 parameters.go:238] Clock(Monotonic): error: -239 ns, adjusted frequency from 2299808226 Hz to 2299807044 Hz D0408 04:40:53.566912 1 parameters.go:238] Clock(Realtime): error: -3 ns, adjusted frequency from 2299807513 Hz to 2299807613 Hz D0408 04:40:53.566946 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5225476270838076 monotonicBaseRef:85000171813 monotonicFrequency:2299807044 realtimeReady:1 realtimeBaseCycles:5225476271053764 realtimeBaseRef:1586320853566909950 realtimeFrequency:2299807613} D0408 04:40:54.566805 1 parameters.go:238] Clock(Monotonic): error: -66 ns, adjusted frequency from 2299807044 Hz to 2299807431 Hz D0408 04:40:54.566903 1 parameters.go:238] Clock(Realtime): error: -187 ns, adjusted frequency from 2299807613 Hz to 2299807185 Hz D0408 04:40:54.566928 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5225478570600290 monotonicBaseRef:86000152320 monotonicFrequency:2299807431 realtimeReady:1 realtimeBaseCycles:5225478570813712 realtimeBaseRef:1586320854566889224 realtimeFrequency:2299807185} D0408 04:40:55.566865 1 parameters.go:238] Clock(Monotonic): error: -51 ns, adjusted frequency from 2299807431 Hz to 2299807410 Hz D0408 04:40:55.567019 1 parameters.go:238] Clock(Realtime): error: 318 ns, adjusted frequency from 2299807185 Hz to 2299808388 Hz D0408 04:40:55.567035 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5225480870491102 monotonicBaseRef:87000188575 monotonicFrequency:2299807410 realtimeReady:1 realtimeBaseCycles:5225480870915048 realtimeBaseRef:1586320855567017126 realtimeFrequency:2299808388} D0408 04:40:56.566719 1 parameters.go:238] Clock(Monotonic): error: 330 ns, adjusted frequency from 2299807410 Hz to 2299808325 Hz D0408 04:40:56.566814 1 parameters.go:238] Clock(Realtime): error: -51 ns, adjusted frequency from 2299808388 Hz to 2299807584 Hz D0408 04:40:56.566850 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5225483170012809 monotonicBaseRef:88000064345 monotonicFrequency:2299808325 realtimeReady:1 realtimeBaseCycles:5225483170253008 realtimeBaseRef:1586320856566812575 realtimeFrequency:2299807584} D0408 04:40:57.566838 1 parameters.go:238] Clock(Monotonic): error: 20 ns, adjusted frequency from 2299808325 Hz to 2299807538 Hz D0408 04:40:57.566935 1 parameters.go:238] Clock(Realtime): error: -52 ns, adjusted frequency from 2299807584 Hz to 2299807521 Hz D0408 04:40:57.566953 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5225485470097129 monotonicBaseRef:89000184352 monotonicFrequency:2299807538 realtimeReady:1 realtimeBaseCycles:5225485470337834 realtimeBaseRef:1586320857566933125 realtimeFrequency:2299807521} I0408 04:40:58.229996 59603 main.go:305] *************************** I0408 04:40:58.230078 59603 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-0 /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 /syzkaller776472478] I0408 04:40:58.230200 59603 main.go:307] Version release-20200323.0-77-g51e461cf9c49 I0408 04:40:58.230227 59603 main.go:308] PID: 59603 I0408 04:40:58.230257 59603 main.go:309] UID: 0, GID: 0 I0408 04:40:58.230283 59603 main.go:310] Configuration: I0408 04:40:58.230312 59603 main.go:311] RootDir: /syzkaller/managers/kvm-direct-sandbox/workdir/gvisor_root I0408 04:40:58.230346 59603 main.go:312] Platform: kvm I0408 04:40:58.230379 59603 main.go:313] FileAccess: exclusive, overlay: false I0408 04:40:58.230429 59603 main.go:314] Network: sandbox, logging: false I0408 04:40:58.230470 59603 main.go:315] Strace: false, max size: 1024, syscalls: [] I0408 04:40:58.230504 59603 main.go:316] *************************** D0408 04:40:58.230632 59603 container.go:159] Load container "/syzkaller/managers/kvm-direct-sandbox/workdir/gvisor_root" "ci-gvisor-kvm-direct-sandbox-0" D0408 04:40:58.232029 59603 container.go:592] Signal container "ci-gvisor-kvm-direct-sandbox-0": signal 0 D0408 04:40:58.232079 59603 sandbox.go:814] Signal sandbox "ci-gvisor-kvm-direct-sandbox-0" D0408 04:40:58.232093 59603 sandbox.go:318] Connecting to sandbox "ci-gvisor-kvm-direct-sandbox-0" D0408 04:40:58.232242 59603 urpc.go:534] urpc: successfully marshalled 110 bytes. D0408 04:40:58.232454 1 urpc.go:577] urpc: unmarshal success. D0408 04:40:58.232569 1 controller.go:504] containerManager.Signal &{CID:ci-gvisor-kvm-direct-sandbox-0 Signo:0 PID:0 Mode:Process} D0408 04:40:58.232677 1 urpc.go:534] urpc: successfully marshalled 37 bytes. D0408 04:40:58.232754 59603 urpc.go:577] urpc: unmarshal success. D0408 04:40:58.232810 59603 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 /syzkaller776472478 D0408 04:40:58.232847 59603 exec.go:121] Exec capablities: &{PermittedCaps:274877898751 InheritableCaps:274877898751 EffectiveCaps:274877898751 BoundingCaps:274877898751 AmbientCaps:0} D0408 04:40:58.232873 59603 container.go:533] Execute in container "ci-gvisor-kvm-direct-sandbox-0", 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 /syzkaller776472478 D0408 04:40:58.232901 59603 sandbox.go:283] Executing new process in container "ci-gvisor-kvm-direct-sandbox-0" in sandbox "ci-gvisor-kvm-direct-sandbox-0" D0408 04:40:58.232918 59603 sandbox.go:318] Connecting to sandbox "ci-gvisor-kvm-direct-sandbox-0" D0408 04:40:58.233130 59603 urpc.go:534] urpc: successfully marshalled 629 bytes. D0408 04:40:58.233259 1 urpc.go:577] urpc: unmarshal success. D0408 04:40:58.233600 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 /syzkaller776472478 I0408 04:40:58.233777 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 /syzkaller776472478] D0408 04:40:58.233888 1 transport_flipcall.go:127] send [channel @0xc0003903c0] Twalkgetattr{FID: 1, NewFID: 5, Names: [syz-execprog]} D0408 04:40:58.234291 1 transport_flipcall.go:234] recv [channel @0xc0003903c0] 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: 1586320772, NanoSec: 255668228}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}, QIDs: [QID{Type: 0, Version: 0, Path: 3277948}]} D0408 04:40:58.234349 1 transport_flipcall.go:127] send [channel @0xc0003903c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syz-execprog, Size: 1} D0408 04:40:58.234517 1 transport_flipcall.go:234] recv [channel @0xc0003903c0] Rlerror{Error: 95} D0408 04:40:58.234652 1 transport_flipcall.go:127] send [channel @0xc0003903c0] Twalk{FID: 5, NewFID: 6, Names: []} D0408 04:40:58.234882 1 transport_flipcall.go:234] recv [channel @0xc0003903c0] Rwalk{QIDs: []} D0408 04:40:58.234936 1 transport_flipcall.go:127] send [channel @0xc0003903c0] Tlopen{FID: 6, Flags: ReadOnly} D0408 04:40:58.235216 1 transport_flipcall.go:234] recv [channel @0xc0003903c0] Rlopen{QID: QID{Type: 0, Version: 0, Path: 3277948}, IoUnit: 0, File: &{{60}}} D0408 04:40:58.237998 1 syscalls.go:266] Allocating stack with size of 8388608 bytes D0408 04:40:58.238293 1 loader.go:832] updated processes: map[{ci-gvisor-kvm-direct-sandbox-0 0}:0xc00000c5a0 {ci-gvisor-kvm-direct-sandbox-0 33}:0xc000802fa0] D0408 04:40:58.238395 1 urpc.go:534] urpc: successfully marshalled 37 bytes. D0408 04:40:58.238477 59603 urpc.go:577] urpc: unmarshal success. D0408 04:40:58.238543 59603 container.go:580] Wait on PID 33 in container "ci-gvisor-kvm-direct-sandbox-0" D0408 04:40:58.238566 59603 sandbox.go:769] Waiting for PID 33 in sandbox "ci-gvisor-kvm-direct-sandbox-0" D0408 04:40:58.238590 59603 sandbox.go:318] Connecting to sandbox "ci-gvisor-kvm-direct-sandbox-0" D0408 04:40:58.238686 59603 urpc.go:534] urpc: successfully marshalled 93 bytes. D0408 04:40:58.238902 1 urpc.go:577] urpc: unmarshal success. D0408 04:40:58.239015 1 controller.go:446] containerManager.Wait D0408 04:40:58.242715 1 task_block.go:223] [ 33] Interrupt queued D0408 04:40:58.493554 1 task_block.go:223] [ 34] Interrupt queued D0408 04:40:58.503727 1 task_block.go:223] [ 35] Interrupt queued D0408 04:40:58.523856 1 task_block.go:223] [ 36] Interrupt queued D0408 04:40:58.525107 1 task_block.go:223] [ 37] Interrupt queued D0408 04:40:58.556502 1 urpc.go:577] urpc: unmarshal success. D0408 04:40:58.556590 1 controller.go:504] containerManager.Signal &{CID:ci-gvisor-kvm-direct-sandbox-0 Signo:0 PID:0 Mode:Process} D0408 04:40:58.556707 1 urpc.go:534] urpc: successfully marshalled 37 bytes. D0408 04:40:58.567181 1 parameters.go:238] Clock(Monotonic): error: 49 ns, adjusted frequency from 2299807538 Hz to 2299807785 Hz D0408 04:40:58.567295 1 parameters.go:238] Clock(Realtime): error: 34 ns, adjusted frequency from 2299807521 Hz to 2299807747 Hz D0408 04:40:58.567314 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5225487770696174 monotonicBaseRef:90000528514 monotonicFrequency:2299807785 realtimeReady:1 realtimeBaseCycles:5225487770970994 realtimeBaseRef:1586320858567292128 realtimeFrequency:2299807747} I0408 04:40:58.602520 1 watchdog.go:277] Watchdog starting loop, tasks: 32, discount: 0s D0408 04:40:58.820138 1 task_block.go:223] [ 38] Interrupt queued D0408 04:40:58.856495 1 task_block.go:223] [ 39] Interrupt queued D0408 04:40:59.567172 1 parameters.go:238] Clock(Monotonic): error: -84 ns, adjusted frequency from 2299807785 Hz to 2299807365 Hz D0408 04:40:59.567241 1 parameters.go:238] Clock(Realtime): error: -315 ns, adjusted frequency from 2299807747 Hz to 2299806888 Hz D0408 04:40:59.567254 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5225490070491058 monotonicBaseRef:91000522904 monotonicFrequency:2299807365 realtimeReady:1 realtimeBaseCycles:5225490070657169 realtimeBaseRef:1586320859567239266 realtimeFrequency:2299806888} D0408 04:40:59.636551 1 task_block.go:223] [ 40] Interrupt queued D0408 04:40:59.682078 1 task_block.go:223] [ 41] Interrupt queued D0408 04:40:59.726224 1 task_block.go:223] [ 42] Interrupt queued D0408 04:40:59.736885 1 task_block.go:223] [ 43] Interrupt queued D0408 04:40:59.748638 1 task_block.go:223] [ 44] Interrupt queued D0408 04:40:59.822904 1 task_block.go:223] [ 45] Interrupt queued D0408 04:40:59.840247 1 task_block.go:223] [ 46] Interrupt queued D0408 04:40:59.855404 1 task_block.go:223] [ 47] Interrupt queued D0408 04:40:59.877089 1 task_block.go:223] [ 48] Interrupt queued D0408 04:40:59.917559 1 task_block.go:223] [ 49] Interrupt queued D0408 04:41:00.058878 1 task_block.go:223] [ 50] Interrupt queued D0408 04:41:00.327112 1 task_block.go:223] [ 51] Interrupt queued D0408 04:41:00.377455 1 task_block.go:223] [ 52] Interrupt queued D0408 04:41:00.477075 1 task_block.go:223] [ 53] Interrupt queued D0408 04:41:00.566897 1 parameters.go:238] Clock(Monotonic): error: 171 ns, adjusted frequency from 2299807365 Hz to 2299808098 Hz D0408 04:41:00.566989 1 parameters.go:238] Clock(Realtime): error: 315 ns, adjusted frequency from 2299806888 Hz to 2299808360 Hz D0408 04:41:00.567007 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5225492369656829 monotonicBaseRef:92000243926 monotonicFrequency:2299808098 realtimeReady:1 realtimeBaseCycles:5225492369884579 realtimeBaseRef:1586320860566987297 realtimeFrequency:2299808360} D0408 04:41:00.821672 1 task_block.go:223] [ 54] Interrupt queued D0408 04:41:00.900163 1 transport_flipcall.go:127] send [channel @0xc0003903c0] Twalkgetattr{FID: 1, NewFID: 7, Names: [syzkaller776472478]} D0408 04:41:00.900799 1 transport_flipcall.go:234] recv [channel @0xc0003903c0] 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: 1586320858, NanoSec: 206856364}, MTime: {Sec: 1586320858, NanoSec: 206856364}, CTime: {Sec: 1586320858, NanoSec: 206856364}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}, QIDs: [QID{Type: 0, Version: 0, Path: 3277949}]} D0408 04:41:00.900899 1 transport_flipcall.go:127] send [channel @0xc0003903c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzkaller776472478, Size: 1} D0408 04:41:00.901090 1 transport_flipcall.go:234] recv [channel @0xc0003903c0] Rlerror{Error: 95} D0408 04:41:00.901225 1 transport_flipcall.go:127] send [channel @0xc0003903c0] Twalk{FID: 7, NewFID: 8, Names: []} D0408 04:41:00.901549 1 transport_flipcall.go:234] recv [channel @0xc0003903c0] Rwalk{QIDs: []} D0408 04:41:00.901593 1 transport_flipcall.go:127] send [channel @0xc0003903c0] Tlopen{FID: 8, Flags: ReadOnly} D0408 04:41:00.901924 1 transport_flipcall.go:234] recv [channel @0xc0003903c0] Rlopen{QID: QID{Type: 0, Version: 0, Path: 3277949}, IoUnit: 0, File: &{{54}}} D0408 04:41:00.922840 1 task_block.go:223] [ 55] Interrupt queued 2020/04/08 04:41:00 parsed 1 programs D0408 04:41:01.016175 1 task_stop.go:118] [ 55] Entering internal stop (*kernel.vforkStop)(nil) D0408 04:41:01.016260 1 task_block.go:223] [ 56] Interrupt queued D0408 04:41:01.024631 1 transport_flipcall.go:127] send [channel @0xc0003903c0] Twalkgetattr{FID: 1, NewFID: 9, Names: [syz-executor]} D0408 04:41:01.025162 1 transport_flipcall.go:234] recv [channel @0xc0003903c0] 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: 1586320772, NanoSec: 259668562}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}, QIDs: [QID{Type: 0, Version: 0, Path: 3277957}]} D0408 04:41:01.025256 1 transport_flipcall.go:127] send [channel @0xc0003903c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syz-executor, Size: 1} D0408 04:41:01.025411 1 transport_flipcall.go:234] recv [channel @0xc0003903c0] Rlerror{Error: 95} D0408 04:41:01.025467 1 transport_flipcall.go:127] send [channel @0xc0003903c0] Twalk{FID: 9, NewFID: 10, Names: []} D0408 04:41:01.025720 1 transport_flipcall.go:234] recv [channel @0xc0003903c0] Rwalk{QIDs: []} D0408 04:41:01.025794 1 transport_flipcall.go:127] send [channel @0xc0003903c0] Tlopen{FID: 10, Flags: ReadOnly} D0408 04:41:01.026056 1 transport_flipcall.go:234] recv [channel @0xc0003903c0] Rlopen{QID: QID{Type: 0, Version: 0, Path: 3277957}, IoUnit: 0, File: &{{55}}} D0408 04:41:01.026469 1 syscalls.go:266] [ 56] Allocating stack with size of 8388608 bytes D0408 04:41:01.026586 1 task_stop.go:137] [ 55] Leaving internal stop (*kernel.vforkStop)(nil) D0408 04:41:01.117097 1 task_exit.go:221] [ 56] Transitioning from exit state TaskExitNone to TaskExitInitiated D0408 04:41:01.128948 1 task_exit.go:221] [ 56] Transitioning from exit state TaskExitInitiated to TaskExitZombie D0408 04:41:01.129033 1 task_signals.go:446] [ 33] Notified of signal 17 D0408 04:41:01.129060 1 task_block.go:223] [ 33] Interrupt queued D0408 04:41:01.129088 1 task_block.go:223] [ 33] Interrupt queued D0408 04:41:01.129109 1 task_signals.go:179] [ 33] Restarting syscall 202 after errno 512: interrupted by signal 17 D0408 04:41:01.129146 1 task_signals.go:220] [ 33] Signal 17: delivering to handler D0408 04:41:01.138953 1 task_exit.go:221] [ 56] Transitioning from exit state TaskExitZombie to TaskExitDead D0408 04:41:01.160433 1 task_block.go:223] [ 57] Interrupt queued 2020/04/08 04:41:01 executed programs: 0 D0408 04:41:01.292908 1 task_stop.go:118] [ 48] Entering internal stop (*kernel.vforkStop)(nil) D0408 04:41:01.293091 1 task_block.go:223] [ 58] Interrupt queued D0408 04:41:01.308663 1 syscalls.go:266] [ 58] Allocating stack with size of 8388608 bytes D0408 04:41:01.309315 1 task_stop.go:137] [ 48] Leaving internal stop (*kernel.vforkStop)(nil) D0408 04:41:01.388887 1 task_block.go:223] [ 59] Interrupt queued D0408 04:41:01.462504 1 transport_flipcall.go:127] send [channel @0xc0003903c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0408 04:41:01.462933 1 transport_flipcall.go:234] recv [channel @0xc0003903c0] Rlerror{Error: 2} D0408 04:41:01.463018 1 transport_flipcall.go:127] send [channel @0xc0003903c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0408 04:41:01.463211 1 transport_flipcall.go:234] recv [channel @0xc0003903c0] Rlerror{Error: 95} D0408 04:41:01.566795 1 parameters.go:238] Clock(Monotonic): error: 205 ns, adjusted frequency from 2299808098 Hz to 2299808182 Hz D0408 04:41:01.566920 1 parameters.go:238] Clock(Realtime): error: -87 ns, adjusted frequency from 2299808360 Hz to 2299807436 Hz D0408 04:41:01.566937 1 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:5225494669230757 monotonicBaseRef:93000142104 monotonicFrequency:2299808182 realtimeReady:1 realtimeBaseCycles:5225494669534270 realtimeBaseRef:1586320861566918304 realtimeFrequency:2299807436} D0408 04:41:01.596549 1 netfilter.go:59] netfilter: convert to binary: current offset: 0 D0408 04:41:01.596633 1 netfilter.go:59] netfilter: convert to binary: found hook 0 at offset 0 D0408 04:41:01.596654 1 netfilter.go:59] netfilter: convert to binary: found underflow 0 at offset 0 D0408 04:41:01.596670 1 netfilter.go:59] netfilter: convert to binary: marshalling standard target D0408 04:41:01.596691 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 04:41:01.596796 1 netfilter.go:59] netfilter: convert to binary: current offset: 152 D0408 04:41:01.596811 1 netfilter.go:59] netfilter: convert to binary: found hook 1 at offset 152 D0408 04:41:01.596826 1 netfilter.go:59] netfilter: convert to binary: found underflow 1 at offset 152 D0408 04:41:01.596840 1 netfilter.go:59] netfilter: convert to binary: marshalling standard target D0408 04:41:01.596856 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 04:41:01.596928 1 netfilter.go:59] netfilter: convert to binary: current offset: 304 D0408 04:41:01.596942 1 netfilter.go:59] netfilter: convert to binary: found hook 3 at offset 304 D0408 04:41:01.596957 1 netfilter.go:59] netfilter: convert to binary: found underflow 3 at offset 304 D0408 04:41:01.596971 1 netfilter.go:59] netfilter: convert to binary: marshalling standard target D0408 04:41:01.596986 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 04:41:01.597062 1 netfilter.go:59] netfilter: convert to binary: current offset: 456 D0408 04:41:01.597077 1 netfilter.go:59] netfilter: convert to binary: found hook 4 at offset 456 D0408 04:41:01.597106 1 netfilter.go:59] netfilter: convert to binary: found underflow 4 at offset 456 D0408 04:41:01.597120 1 netfilter.go:59] netfilter: convert to binary: marshalling standard target D0408 04:41:01.597135 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 04:41:01.597216 1 netfilter.go:59] netfilter: convert to binary: current offset: 608 D0408 04:41:01.597235 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 04:41:01.597321 1 netfilter.go:59] netfilter: convert to binary: finished with an marshalled size of 0 D0408 04:41:01.597336 1 netfilter.go:59] netfilter: convert to binary: current offset: 0 D0408 04:41:01.597350 1 netfilter.go:59] netfilter: convert to binary: found hook 0 at offset 0 D0408 04:41:01.597365 1 netfilter.go:59] netfilter: convert to binary: found underflow 0 at offset 0 D0408 04:41:01.597391 1 netfilter.go:59] netfilter: convert to binary: marshalling standard target D0408 04:41:01.597424 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 04:41:01.597521 1 netfilter.go:59] netfilter: convert to binary: current offset: 152 D0408 04:41:01.597537 1 netfilter.go:59] netfilter: convert to binary: found hook 3 at offset 152 D0408 04:41:01.597552 1 netfilter.go:59] netfilter: convert to binary: found underflow 3 at offset 152 D0408 04:41:01.597566 1 netfilter.go:59] netfilter: convert to binary: marshalling standard target D0408 04:41:01.597582 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 04:41:01.597665 1 netfilter.go:59] netfilter: convert to binary: current offset: 304 D0408 04:41:01.597684 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 04:41:01.597768 1 netfilter.go:59] netfilter: convert to binary: finished with an marshalled size of 0 D0408 04:41:01.597783 1 netfilter.go:59] netfilter: convert to binary: current offset: 0 D0408 04:41:01.597797 1 netfilter.go:59] netfilter: convert to binary: found hook 1 at offset 0 D0408 04:41:01.597812 1 netfilter.go:59] netfilter: convert to binary: found underflow 1 at offset 0 D0408 04:41:01.597825 1 netfilter.go:59] netfilter: convert to binary: marshalling standard target D0408 04:41:01.597847 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 04:41:01.597931 1 netfilter.go:59] netfilter: convert to binary: current offset: 152 D0408 04:41:01.597955 1 netfilter.go:59] netfilter: convert to binary: found hook 2 at offset 152 D0408 04:41:01.597970 1 netfilter.go:59] netfilter: convert to binary: found underflow 2 at offset 152 D0408 04:41:01.597984 1 netfilter.go:59] netfilter: convert to binary: marshalling standard target D0408 04:41:01.597999 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 04:41:01.598068 1 netfilter.go:59] netfilter: convert to binary: current offset: 304 D0408 04:41:01.598092 1 netfilter.go:59] netfilter: convert to binary: found hook 3 at offset 304 D0408 04:41:01.598114 1 netfilter.go:59] netfilter: convert to binary: found underflow 3 at offset 304 D0408 04:41:01.598130 1 netfilter.go:59] netfilter: convert to binary: marshalling standard target D0408 04:41:01.598146 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 04:41:01.598221 1 netfilter.go:59] netfilter: convert to binary: current offset: 456 D0408 04:41:01.598238 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 04:41:01.598332 1 netfilter.go:59] netfilter: convert to binary: finished with an marshalled size of 0 D0408 04:41:02.036298 1 task_block.go:223] [ 60] Interrupt queued panic: runtime error: makeslice: len out of range goroutine 555 [running]: gvisor.dev/gvisor/pkg/tcpip/buffer.NewView(...) pkg/tcpip/buffer/view.go:29 gvisor.dev/gvisor/pkg/sentry/socket/netstack.(*readerPayload).Payload(0xc0005fe100, 0x8000000000004, 0xc000b9a570, 0x4328c6, 0xc000b9a570, 0xc0001b09e0) pkg/sentry/socket/netstack/netstack.go:578 +0x55 gvisor.dev/gvisor/pkg/sentry/socket/netstack.(*readerPayload).FullPayload(0xc0005fe100, 0x0, 0x0, 0x0, 0x10) pkg/sentry/socket/netstack/netstack.go:570 +0x34 gvisor.dev/gvisor/pkg/tcpip/transport/udp.(*endpoint).write(0xc0001b0900, 0xf54400, 0xc0005fe100, 0x0, 0x10000, 0x0, 0x0, 0x0) pkg/tcpip/transport/udp/endpoint.go:472 +0x3b2 gvisor.dev/gvisor/pkg/tcpip/transport/udp.(*endpoint).Write(0xc0001b0900, 0xf54400, 0xc0005fe100, 0x0, 0x8000000010000, 0x0, 0x0, 0xf50520) pkg/tcpip/transport/udp/endpoint.go:362 +0x68 gvisor.dev/gvisor/pkg/sentry/socket/netstack.(*SocketOperations).ReadFrom(0xc0012ae000, 0xf6d460, 0xc000ab7500, 0xc001284b00, 0xf4eaa0, 0xc0007126a0, 0x8000000000004, 0xc0003b8300, 0x0, 0xf50520) pkg/sentry/socket/netstack/netstack.go:601 +0xc7 gvisor.dev/gvisor/pkg/sentry/fs.Splice(0xf6d460, 0xc000ab7500, 0xc001284b00, 0xc0012849a0, 0x8000000000004, 0x0, 0x0, 0x0, 0x0, 0x0, ...) pkg/sentry/fs/splice.go:146 +0x7c7 gvisor.dev/gvisor/pkg/sentry/syscalls/linux.doSplice(0xc000ab7500, 0xc001284b00, 0xc0012849a0, 0x8000000000004, 0x0, 0x0, 0x0, 0x0, 0xc00086f000, 0x0, ...) pkg/sentry/syscalls/linux/sys_splice.go:40 +0x149 gvisor.dev/gvisor/pkg/sentry/syscalls/linux.Sendfile(0xc000ab7500, 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(0xc000ab7500, 0x28, 0x4, 0x3, 0x0, 0x8000000000004, 0x0, 0x0, 0xc000156090, 0xe03180, ...) pkg/sentry/kernel/task_syscall.go:170 +0x122 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscallInvoke(0xc000ab7500, 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(0xc000ab7500, 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(0xc000ab7500, 0x2, 0xc000a92320) pkg/sentry/kernel/task_syscall.go:227 +0x139 gvisor.dev/gvisor/pkg/sentry/kernel.(*runApp).execute(0x0, 0xc000ab7500, 0xf4eea0, 0x0) pkg/sentry/kernel/task_run.go:258 +0xed5 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).run(0xc000ab7500, 0x3c) 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(0xc000130084) GOROOT/src/runtime/sema.go:56 +0x42 sync.(*WaitGroup).Wait(0xc000130084) 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(0xc000144160, 0x0, 0x0) runsc/boot/loader.go:905 +0x38 gvisor.dev/gvisor/runsc/cmd.(*Boot).Execute(0xc0002a63f0, 0xf5e700, 0xc000212000, 0xc0002b0300, 0xc0002c4da0, 0x2, 0x2, 0x0) runsc/cmd/boot.go:260 +0x73f github.com/google/subcommands.(*Commander).Execute(0xc0001a6000, 0xf5e700, 0xc000212000, 0xc0002c4da0, 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(0xc000382948, 0x4) GOROOT/src/runtime/sema.go:513 +0xf8 sync.(*Cond).Wait(0xc000382938) GOROOT/src/sync/cond.go:56 +0x9d gvisor.dev/gvisor/pkg/sentry/pgalloc.(*MemoryFile).findReclaimable(0xc000382500, 0x0, 0x0, 0x0) pkg/sentry/pgalloc/pgalloc.go:1090 +0x83 gvisor.dev/gvisor/pkg/sentry/pgalloc.(*MemoryFile).runReclaim(0xc000382500) 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(0xc0002b0960, 0xc0003466e0) 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(0xc0003bd660, 0x1, 0xc0003bd660) pkg/sleep/sleep_unsafe.go:190 +0x6a gvisor.dev/gvisor/pkg/sleep.(*Sleeper).Fetch(0xc0003bd660, 0xc00034bb01, 0x2, 0x0) pkg/sleep/sleep_unsafe.go:225 +0x38 gvisor.dev/gvisor/pkg/tcpip/transport/tcp.(*processor).handleSegments(0xc00034bb20) 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(0xc0003bd680, 0x1, 0xc0003bd680) pkg/sleep/sleep_unsafe.go:190 +0x6a gvisor.dev/gvisor/pkg/sleep.(*Sleeper).Fetch(0xc0003bd680, 0xc00034bb01, 0x2, 0x0) pkg/sleep/sleep_unsafe.go:225 +0x38 gvisor.dev/gvisor/pkg/tcpip/transport/tcp.(*processor).handleSegments(0xc00034bb90) 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(0xc0003bd6a0, 0x1, 0xc0003bd6a0) pkg/sleep/sleep_unsafe.go:190 +0x6a gvisor.dev/gvisor/pkg/sleep.(*Sleeper).Fetch(0xc0003bd6a0, 0xc00034bc01, 0x2, 0x0) pkg/sleep/sleep_unsafe.go:225 +0x38 gvisor.dev/gvisor/pkg/tcpip/transport/tcp.(*processor).handleSegments(0xc00034bc00) 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(0xc0003bd6c0, 0x1, 0xc0003bd6c0) pkg/sleep/sleep_unsafe.go:190 +0x6a gvisor.dev/gvisor/pkg/sleep.(*Sleeper).Fetch(0xc0003bd6c0, 0xc00034bc01, 0x2, 0x0) pkg/sleep/sleep_unsafe.go:225 +0x38 gvisor.dev/gvisor/pkg/tcpip/transport/tcp.(*processor).handleSegments(0xc00034bc70) 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(0xc0003bd6e0, 0x1, 0xc0003bd6e0) pkg/sleep/sleep_unsafe.go:190 +0x6a gvisor.dev/gvisor/pkg/sleep.(*Sleeper).Fetch(0xc0003bd6e0, 0xc00034bd01, 0x2, 0x0) pkg/sleep/sleep_unsafe.go:225 +0x38 gvisor.dev/gvisor/pkg/tcpip/transport/tcp.(*processor).handleSegments(0xc00034bce0) 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(0xc0003bd700, 0x1, 0xc0003bd700) pkg/sleep/sleep_unsafe.go:190 +0x6a gvisor.dev/gvisor/pkg/sleep.(*Sleeper).Fetch(0xc0003bd700, 0xc00034bd01, 0x2, 0x0) pkg/sleep/sleep_unsafe.go:225 +0x38 gvisor.dev/gvisor/pkg/tcpip/transport/tcp.(*processor).handleSegments(0xc00034bd50) 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(0xc0003bd720, 0x1, 0xc0003bd720) pkg/sleep/sleep_unsafe.go:190 +0x6a gvisor.dev/gvisor/pkg/sleep.(*Sleeper).Fetch(0xc0003bd720, 0xc00034bd01, 0x2, 0x0) pkg/sleep/sleep_unsafe.go:225 +0x38 gvisor.dev/gvisor/pkg/tcpip/transport/tcp.(*processor).handleSegments(0xc00034bdc0) 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(0xc0003bd740, 0x1, 0xc0003bd740) pkg/sleep/sleep_unsafe.go:190 +0x6a gvisor.dev/gvisor/pkg/sleep.(*Sleeper).Fetch(0xc0003bd740, 0xc00034be01, 0x2, 0x0) pkg/sleep/sleep_unsafe.go:225 +0x38 gvisor.dev/gvisor/pkg/tcpip/transport/tcp.(*processor).handleSegments(0xc00034be30) 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(0xc0003bd760, 0x1, 0xc0003bd760) pkg/sleep/sleep_unsafe.go:190 +0x6a gvisor.dev/gvisor/pkg/sleep.(*Sleeper).Fetch(0xc0003bd760, 0xc00034be01, 0x2, 0x0) pkg/sleep/sleep_unsafe.go:225 +0x38 gvisor.dev/gvisor/pkg/tcpip/transport/tcp.(*processor).handleSegments(0xc00034bea0) 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(0xc0003bd780, 0x1, 0xc0003bd780) pkg/sleep/sleep_unsafe.go:190 +0x6a gvisor.dev/gvisor/pkg/sleep.(*Sleeper).Fetch(0xc0003bd780, 0xc00034bf01, 0x2, 0x0) pkg/sleep/sleep_unsafe.go:225 +0x38 gvisor.dev/gvisor/pkg/tcpip/transport/tcp.(*processor).handleSegments(0xc00034bf10) 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(0xc0003bd7a0, 0x1, 0xc0003bd7a0) pkg/sleep/sleep_unsafe.go:190 +0x6a gvisor.dev/gvisor/pkg/sleep.(*Sleeper).Fetch(0xc0003bd7a0, 0xc00034bf01, 0x2, 0x0) pkg/sleep/sleep_unsafe.go:225 +0x38 gvisor.dev/gvisor/pkg/tcpip/transport/tcp.(*processor).handleSegments(0xc00034bf80) 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(0xc0003bd7c0, 0x1, 0xc0003bd7c0) pkg/sleep/sleep_unsafe.go:190 +0x6a gvisor.dev/gvisor/pkg/sleep.(*Sleeper).Fetch(0xc0003bd7c0, 0xc000524001, 0x2, 0x0) pkg/sleep/sleep_unsafe.go:225 +0x38 gvisor.dev/gvisor/pkg/tcpip/transport/tcp.(*processor).handleSegments(0xc000524000) 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(0xc0003bd7e0, 0x1, 0xc0003bd7e0) pkg/sleep/sleep_unsafe.go:190 +0x6a gvisor.dev/gvisor/pkg/sleep.(*Sleeper).Fetch(0xc0003bd7e0, 0xc000524001, 0x2, 0x0) pkg/sleep/sleep_unsafe.go:225 +0x38 gvisor.dev/gvisor/pkg/tcpip/transport/tcp.(*processor).handleSegments(0xc000524070) 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(0xc0003bd800, 0x1, 0xc0003bd800) pkg/sleep/sleep_unsafe.go:190 +0x6a gvisor.dev/gvisor/pkg/sleep.(*Sleeper).Fetch(0xc0003bd800, 0xc000524101, 0x2, 0x0) pkg/sleep/sleep_unsafe.go:225 +0x38 gvisor.dev/gvisor/pkg/tcpip/transport/tcp.(*processor).handleSegments(0xc0005240e0) 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(0xc0003bd820, 0x1, 0xc0003bd820) pkg/sleep/sleep_unsafe.go:190 +0x6a gvisor.dev/gvisor/pkg/sleep.(*Sleeper).Fetch(0xc0003bd820, 0xc000524101, 0x2, 0x0) pkg/sleep/sleep_unsafe.go:225 +0x38 gvisor.dev/gvisor/pkg/tcpip/transport/tcp.(*processor).handleSegments(0xc000524150) 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(0xc0003bd840, 0x1, 0xc0003bd840) pkg/sleep/sleep_unsafe.go:190 +0x6a gvisor.dev/gvisor/pkg/sleep.(*Sleeper).Fetch(0xc0003bd840, 0xc000524101, 0x2, 0x0) pkg/sleep/sleep_unsafe.go:225 +0x38 gvisor.dev/gvisor/pkg/tcpip/transport/tcp.(*processor).handleSegments(0xc0005241c0) 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(0xc0003bd860, 0x1, 0xc0003bd860) pkg/sleep/sleep_unsafe.go:190 +0x6a gvisor.dev/gvisor/pkg/sleep.(*Sleeper).Fetch(0xc0003bd860, 0xc000524201, 0x2, 0x0) pkg/sleep/sleep_unsafe.go:225 +0x38 gvisor.dev/gvisor/pkg/tcpip/transport/tcp.(*processor).handleSegments(0xc000524230) 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(0xc0003bd880, 0x1, 0xc0003bd880) pkg/sleep/sleep_unsafe.go:190 +0x6a gvisor.dev/gvisor/pkg/sleep.(*Sleeper).Fetch(0xc0003bd880, 0xc000524201, 0x2, 0x0) pkg/sleep/sleep_unsafe.go:225 +0x38 gvisor.dev/gvisor/pkg/tcpip/transport/tcp.(*processor).handleSegments(0xc0005242a0) 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(0xc0003bd8a0, 0x1, 0xc0003bd8a0) pkg/sleep/sleep_unsafe.go:190 +0x6a gvisor.dev/gvisor/pkg/sleep.(*Sleeper).Fetch(0xc0003bd8a0, 0xc000524301, 0x2, 0x0) pkg/sleep/sleep_unsafe.go:225 +0x38 gvisor.dev/gvisor/pkg/tcpip/transport/tcp.(*processor).handleSegments(0xc000524310) 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(0xc0003bd8c0, 0x1, 0xc0003bd8c0) pkg/sleep/sleep_unsafe.go:190 +0x6a gvisor.dev/gvisor/pkg/sleep.(*Sleeper).Fetch(0xc0003bd8c0, 0xc000524301, 0x2, 0x0) pkg/sleep/sleep_unsafe.go:225 +0x38 gvisor.dev/gvisor/pkg/tcpip/transport/tcp.(*processor).handleSegments(0xc000524380) 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(0xc0003bd8e0, 0x1, 0xc0003bd8e0) pkg/sleep/sleep_unsafe.go:190 +0x6a gvisor.dev/gvisor/pkg/sleep.(*Sleeper).Fetch(0xc0003bd8e0, 0xc000524401, 0x2, 0x0) pkg/sleep/sleep_unsafe.go:225 +0x38 gvisor.dev/gvisor/pkg/tcpip/transport/tcp.(*processor).handleSegments(0xc0005243f0) 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(0xc0003bd900, 0x1, 0xc0003bd900) pkg/sleep/sleep_unsafe.go:190 +0x6a gvisor.dev/gvisor/pkg/sleep.(*Sleeper).Fetch(0xc0003bd900, 0xc000524401, 0x2, 0x0) pkg/sleep/sleep_unsafe.go:225 +0x38 gvisor.dev/gvisor/pkg/tcpip/transport/tcp.(*processor).handleSegments(0xc000524460) 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(0xc0003bd920, 0x1, 0xc0003bd920) pkg/sleep/sleep_unsafe.go:190 +0x6a gvisor.dev/gvisor/pkg/sleep.(*Sleeper).Fetch(0xc0003bd920, 0xc000524501, 0x2, 0x0) pkg/sleep/sleep_unsafe.go:225 +0x38 gvisor.dev/gvisor/pkg/tcpip/transport/tcp.(*processor).handleSegments(0xc0005244d0) 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(0xc0003bd940, 0x1, 0xc0003bd940) pkg/sleep/sleep_unsafe.go:190 +0x6a gvisor.dev/gvisor/pkg/sleep.(*Sleeper).Fetch(0xc0003bd940, 0xc000524501, 0x2, 0x0) pkg/sleep/sleep_unsafe.go:225 +0x38 gvisor.dev/gvisor/pkg/tcpip/transport/tcp.(*processor).handleSegments(0xc000524540) 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(0xc0003bd960, 0x1, 0xc0003bd960) pkg/sleep/sleep_unsafe.go:190 +0x6a gvisor.dev/gvisor/pkg/sleep.(*Sleeper).Fetch(0xc0003bd960, 0xc000524501, 0x2, 0x0) pkg/sleep/sleep_unsafe.go:225 +0x38 gvisor.dev/gvisor/pkg/tcpip/transport/tcp.(*processor).handleSegments(0xc0005245b0) 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(0xc0003bd980, 0x1, 0xc0003bd980) pkg/sleep/sleep_unsafe.go:190 +0x6a gvisor.dev/gvisor/pkg/sleep.(*Sleeper).Fetch(0xc0003bd980, 0xc000524601, 0x2, 0x0) pkg/sleep/sleep_unsafe.go:225 +0x38 gvisor.dev/gvisor/pkg/tcpip/transport/tcp.(*processor).handleSegments(0xc000524620) 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(0xc0003bd9a0, 0x1, 0xc0003bd9a0) pkg/sleep/sleep_unsafe.go:190 +0x6a gvisor.dev/gvisor/pkg/sleep.(*Sleeper).Fetch(0xc0003bd9a0, 0xc000524601, 0x2, 0x0) pkg/sleep/sleep_unsafe.go:225 +0x38 gvisor.dev/gvisor/pkg/tcpip/transport/tcp.(*processor).handleSegments(0xc000524690) 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(0xc0003bd9c0, 0x1, 0xc0003bd9c0) pkg/sleep/sleep_unsafe.go:190 +0x6a gvisor.dev/gvisor/pkg/sleep.(*Sleeper).Fetch(0xc0003bd9c0, 0xc000524701, 0x2, 0x0) pkg/sleep/sleep_unsafe.go:225 +0x38 gvisor.dev/gvisor/pkg/tcpip/transport/tcp.(*processor).handleSegments(0xc000524700) 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(0xc0003bd9e0, 0x1, 0xc0003bd9e0) pkg/sleep/sleep_unsafe.go:190 +0x6a gvisor.dev/gvisor/pkg/sleep.(*Sleeper).Fetch(0xc0003bd9e0, 0xc000524701, 0x2, 0x0) pkg/sleep/sleep_unsafe.go:225 +0x38 gvisor.dev/gvisor/pkg/tcpip/transport/tcp.(*processor).handleSegments(0xc000524770) 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(0xc0003bda00, 0x1, 0xc0003bda00) pkg/sleep/sleep_unsafe.go:190 +0x6a gvisor.dev/gvisor/pkg/sleep.(*Sleeper).Fetch(0xc0003bda00, 0xc000524801, 0x2, 0x0) pkg/sleep/sleep_unsafe.go:225 +0x38 gvisor.dev/gvisor/pkg/tcpip/transport/tcp.(*processor).handleSegments(0xc0005247e0) 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(0xc0003bda20, 0x1, 0xc0003bda20) pkg/sleep/sleep_unsafe.go:190 +0x6a gvisor.dev/gvisor/pkg/sleep.(*Sleeper).Fetch(0xc0003bda20, 0xc000524801, 0x2, 0x0) pkg/sleep/sleep_unsafe.go:225 +0x38 gvisor.dev/gvisor/pkg/tcpip/transport/tcp.(*processor).handleSegments(0xc000524850) 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(0xc0003bda40, 0x1, 0xc0003bda40) pkg/sleep/sleep_unsafe.go:190 +0x6a gvisor.dev/gvisor/pkg/sleep.(*Sleeper).Fetch(0xc0003bda40, 0xc000524801, 0x2, 0x0) pkg/sleep/sleep_unsafe.go:225 +0x38 gvisor.dev/gvisor/pkg/tcpip/transport/tcp.(*processor).handleSegments(0xc0005248c0) 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(0xc0003bda60, 0x1, 0xc0003bda60) pkg/sleep/sleep_unsafe.go:190 +0x6a gvisor.dev/gvisor/pkg/sleep.(*Sleeper).Fetch(0xc0003bda60, 0xc000524901, 0x2, 0x0) pkg/sleep/sleep_unsafe.go:225 +0x38 gvisor.dev/gvisor/pkg/tcpip/transport/tcp.(*processor).handleSegments(0xc000524930) 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(0xc0003bda80, 0x1, 0xc0003bda80) pkg/sleep/sleep_unsafe.go:190 +0x6a gvisor.dev/gvisor/pkg/sleep.(*Sleeper).Fetch(0xc0003bda80, 0xc000524901, 0x2, 0x0) pkg/sleep/sleep_unsafe.go:225 +0x38 gvisor.dev/gvisor/pkg/tcpip/transport/tcp.(*processor).handleSegments(0xc0005249a0) 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(0xc0003bdaa0, 0x1, 0xc0003bdaa0) pkg/sleep/sleep_unsafe.go:190 +0x6a gvisor.dev/gvisor/pkg/sleep.(*Sleeper).Fetch(0xc0003bdaa0, 0xc000524a01, 0x2, 0x0) pkg/sleep/sleep_unsafe.go:225 +0x38 gvisor.dev/gvisor/pkg/tcpip/transport/tcp.(*processor).handleSegments(0xc000524a10) 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(0xc0003bdac0, 0x1, 0xc0003bdac0) pkg/sleep/sleep_unsafe.go:190 +0x6a gvisor.dev/gvisor/pkg/sleep.(*Sleeper).Fetch(0xc0003bdac0, 0xc000524a01, 0x2, 0x0) pkg/sleep/sleep_unsafe.go:225 +0x38 gvisor.dev/gvisor/pkg/tcpip/transport/tcp.(*processor).handleSegments(0xc000524a80) 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(0xc0003bdae0, 0x1, 0xc0003bdae0) pkg/sleep/sleep_unsafe.go:190 +0x6a gvisor.dev/gvisor/pkg/sleep.(*Sleeper).Fetch(0xc0003bdae0, 0xc000524b01, 0x2, 0x0) pkg/sleep/sleep_unsafe.go:225 +0x38 gvisor.dev/gvisor/pkg/tcpip/transport/tcp.(*processor).handleSegments(0xc000524af0) 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(0xc0003bdb00, 0x1, 0xc0003bdb00) pkg/sleep/sleep_unsafe.go:190 +0x6a gvisor.dev/gvisor/pkg/sleep.(*Sleeper).Fetch(0xc0003bdb00, 0xc000524b01, 0x2, 0x0) pkg/sleep/sleep_unsafe.go:225 +0x38 gvisor.dev/gvisor/pkg/tcpip/transport/tcp.(*processor).handleSegments(0xc000524b60) 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(0xc0003bdb20, 0x1, 0xc0003bdb20) pkg/sleep/sleep_unsafe.go:190 +0x6a gvisor.dev/gvisor/pkg/sleep.(*Sleeper).Fetch(0xc0003bdb20, 0xc000524c01, 0x2, 0x0) pkg/sleep/sleep_unsafe.go:225 +0x38 gvisor.dev/gvisor/pkg/tcpip/transport/tcp.(*processor).handleSegments(0xc000524bd0) 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(0xc0003bdb40, 0x1, 0xc0003bdb40) pkg/sleep/sleep_unsafe.go:190 +0x6a gvisor.dev/gvisor/pkg/sleep.(*Sleeper).Fetch(0xc0003bdb40, 0xc000524c01, 0x2, 0x0) pkg/sleep/sleep_unsafe.go:225 +0x38 gvisor.dev/gvisor/pkg/tcpip/transport/tcp.(*processor).handleSegments(0xc000524c40) 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(0xc0003bdb60, 0x1, 0xc0003bdb60) pkg/sleep/sleep_unsafe.go:190 +0x6a gvisor.dev/gvisor/pkg/sleep.(*Sleeper).Fetch(0xc0003bdb60, 0xc000524c01, 0x2, 0x0) pkg/sleep/sleep_unsafe.go:225 +0x38 gvisor.dev/gvisor/pkg/tcpip/transport/tcp.(*processor).handleSegments(0xc000524cb0) 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(0xc0003bdb80, 0x1, 0xc0003bdb80) pkg/sleep/sleep_unsafe.go:190 +0x6a gvisor.dev/gvisor/pkg/sleep.(*Sleeper).Fetch(0xc0003bdb80, 0xc000524d01, 0x2, 0x0) pkg/sleep/sleep_unsafe.go:225 +0x38 gvisor.dev/gvisor/pkg/tcpip/transport/tcp.(*processor).handleSegments(0xc000524d20) 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(0xc0003bdba0, 0x1, 0xc0003bdba0) pkg/sleep/sleep_unsafe.go:190 +0x6a gvisor.dev/gvisor/pkg/sleep.(*Sleeper).Fetch(0xc0003bdba0, 0xc000524d01, 0x2, 0x0) pkg/sleep/sleep_unsafe.go:225 +0x38 gvisor.dev/gvisor/pkg/tcpip/transport/tcp.(*processor).handleSegments(0xc000524d90) 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(0xc0003bdbc0, 0x1, 0xc0003bdbc0) pkg/sleep/sleep_unsafe.go:190 +0x6a gvisor.dev/gvisor/pkg/sleep.(*Sleeper).Fetch(0xc0003bdbc0, 0xc000524e01, 0x2, 0x0) pkg/sleep/sleep_unsafe.go:225 +0x38 gvisor.dev/gvisor/pkg/tcpip/transport/tcp.(*processor).handleSegments(0xc000524e00) 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(0xc0003bdbe0, 0x1, 0xc0003bdbe0) pkg/sleep/sleep_unsafe.go:190 +0x6a gvisor.dev/gvisor/pkg/sleep.(*Sleeper).Fetch(0xc0003bdbe0, 0xc000524e01, 0x2, 0x0) pkg/sleep/sleep_unsafe.go:225 +0x38 gvisor.dev/gvisor/pkg/tcpip/transport/tcp.(*processor).handleSegments(0xc000524e70) 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(0xc0003bdc00, 0x1, 0xc0003bdc00) pkg/sleep/sleep_unsafe.go:190 +0x6a gvisor.dev/gvisor/pkg/sleep.(*Sleeper).Fetch(0xc0003bdc00, 0xc000524f01, 0x2, 0x0) pkg/sleep/sleep_unsafe.go:225 +0x38 gvisor.dev/gvisor/pkg/tcpip/transport/tcp.(*processor).handleSegments(0xc000524ee0) 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(0xc0003bdc20, 0x1, 0xc0003bdc20) pkg/sleep/sleep_unsafe.go:190 +0x6a gvisor.dev/gvisor/pkg/sleep.(*Sleeper).Fetch(0xc0003bdc20, 0xc000524f01, 0x2, 0x0) pkg/sleep/sleep_unsafe.go:225 +0x38 gvisor.dev/gvisor/pkg/tcpip/transport/tcp.(*processor).handleSegments(0xc000524f50) 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(0xc0003bdc40, 0x1, 0xc0003bdc40) pkg/sleep/sleep_unsafe.go:190 +0x6a gvisor.dev/gvisor/pkg/sleep.(*Sleeper).Fetch(0xc0003bdc40, 0xc000524f01, 0x2, 0x0) pkg/sleep/sleep_unsafe.go:225 +0x38 gvisor.dev/gvisor/pkg/tcpip/transport/tcp.(*processor).handleSegments(0xc000524fc0) 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(0xc0003bdc60, 0x1, 0xc0003bdc60) pkg/sleep/sleep_unsafe.go:190 +0x6a gvisor.dev/gvisor/pkg/sleep.(*Sleeper).Fetch(0xc0003bdc60, 0xc000525001, 0x2, 0x0) pkg/sleep/sleep_unsafe.go:225 +0x38 gvisor.dev/gvisor/pkg/tcpip/transport/tcp.(*processor).handleSegments(0xc000525030) 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(0xc0003bdc80, 0x1, 0xc0003bdc80) pkg/sleep/sleep_unsafe.go:190 +0x6a gvisor.dev/gvisor/pkg/sleep.(*Sleeper).Fetch(0xc0003bdc80, 0xc000525001, 0x2, 0x0) pkg/sleep/sleep_unsafe.go:225 +0x38 gvisor.dev/gvisor/pkg/tcpip/transport/tcp.(*processor).handleSegments(0xc0005250a0) 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(0xc0003bdca0, 0x1, 0xc0003bdca0) pkg/sleep/sleep_unsafe.go:190 +0x6a gvisor.dev/gvisor/pkg/sleep.(*Sleeper).Fetch(0xc0003bdca0, 0xc000525101, 0x2, 0x0) pkg/sleep/sleep_unsafe.go:225 +0x38 gvisor.dev/gvisor/pkg/tcpip/transport/tcp.(*processor).handleSegments(0xc000525110) 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(0xc0003bdcc0, 0x1, 0xc0003bdcc0) pkg/sleep/sleep_unsafe.go:190 +0x6a gvisor.dev/gvisor/pkg/sleep.(*Sleeper).Fetch(0xc0003bdcc0, 0xc000525101, 0x2, 0x0) pkg/sleep/sleep_unsafe.go:225 +0x38 gvisor.dev/gvisor/pkg/tcpip/transport/tcp.(*processor).handleSegments(0xc000525180) 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(0xc0003bdce0, 0x1, 0xc0003bdce0) pkg/sleep/sleep_unsafe.go:190 +0x6a gvisor.dev/gvisor/pkg/sleep.(*Sleeper).Fetch(0xc0003bdce0, 0xc000525201, 0x2, 0x0) pkg/sleep/sleep_unsafe.go:225 +0x38 gvisor.dev/gvisor/pkg/tcpip/transport/tcp.(*processor).handleSegments(0xc0005251f0) 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(0xc0003bdd00, 0x1, 0xc0003bdd00) pkg/sleep/sleep_unsafe.go:190 +0x6a gvisor.dev/gvisor/pkg/sleep.(*Sleeper).Fetch(0xc0003bdd00, 0xc000525201, 0x2, 0x0) pkg/sleep/sleep_unsafe.go:225 +0x38 gvisor.dev/gvisor/pkg/tcpip/transport/tcp.(*processor).handleSegments(0xc000525260) 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(0xc0003bdd20, 0x1, 0xc0003bdd20) pkg/sleep/sleep_unsafe.go:190 +0x6a gvisor.dev/gvisor/pkg/sleep.(*Sleeper).Fetch(0xc0003bdd20, 0xc000525301, 0x2, 0x0) pkg/sleep/sleep_unsafe.go:225 +0x38 gvisor.dev/gvisor/pkg/tcpip/transport/tcp.(*processor).handleSegments(0xc0005252d0) 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(0xc0003bdd40, 0x1, 0xc0003bdd40) pkg/sleep/sleep_unsafe.go:190 +0x6a gvisor.dev/gvisor/pkg/sleep.(*Sleeper).Fetch(0xc0003bdd40, 0xc000525301, 0x2, 0x0) pkg/sleep/sleep_unsafe.go:225 +0x38 gvisor.dev/gvisor/pkg/tcpip/transport/tcp.(*processor).handleSegments(0xc000525340) 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(0xc0003bdd60, 0x1, 0xc0003bdd60) pkg/sleep/sleep_unsafe.go:190 +0x6a gvisor.dev/gvisor/pkg/sleep.(*Sleeper).Fetch(0xc0003bdd60, 0xc000525301, 0x2, 0x0) pkg/sleep/sleep_unsafe.go:225 +0x38 gvisor.dev/gvisor/pkg/tcpip/transport/tcp.(*processor).handleSegments(0xc0005253b0) 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(0xc0003bdd80, 0x1, 0xc0003bdd80) pkg/sleep/sleep_unsafe.go:190 +0x6a gvisor.dev/gvisor/pkg/sleep.(*Sleeper).Fetch(0xc0003bdd80, 0xc000525401, 0x2, 0x0) pkg/sleep/sleep_unsafe.go:225 +0x38 gvisor.dev/gvisor/pkg/tcpip/transport/tcp.(*processor).handleSegments(0xc000525420) 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(0xc0003bdda0, 0x1, 0xc0003bdda0) pkg/sleep/sleep_unsafe.go:190 +0x6a gvisor.dev/gvisor/pkg/sleep.(*Sleeper).Fetch(0xc0003bdda0, 0xc000525401, 0x2, 0x0) pkg/sleep/sleep_unsafe.go:225 +0x38 gvisor.dev/gvisor/pkg/tcpip/transport/tcp.(*processor).handleSegments(0xc000525490) 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(0xc0003bddc0, 0x1, 0xc0003bddc0) pkg/sleep/sleep_unsafe.go:190 +0x6a gvisor.dev/gvisor/pkg/sleep.(*Sleeper).Fetch(0xc0003bddc0, 0xc000525501, 0x2, 0x0) pkg/sleep/sleep_unsafe.go:225 +0x38 gvisor.dev/gvisor/pkg/tcpip/transport/tcp.(*processor).handleSegments(0xc000525500) 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(0xc0003bdde0, 0x1, 0xc0003bdde0) pkg/sleep/sleep_unsafe.go:190 +0x6a gvisor.dev/gvisor/pkg/sleep.(*Sleeper).Fetch(0xc0003bdde0, 0xc000525501, 0x2, 0x0) pkg/sleep/sleep_unsafe.go:225 +0x38 gvisor.dev/gvisor/pkg/tcpip/transport/tcp.(*processor).handleSegments(0xc000525570) 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(0xc0003bde00, 0x1, 0xc0003bde00) pkg/sleep/sleep_unsafe.go:190 +0x6a gvisor.dev/gvisor/pkg/sleep.(*Sleeper).Fetch(0xc0003bde00, 0xc000525601, 0x2, 0x0) pkg/sleep/sleep_unsafe.go:225 +0x38 gvisor.dev/gvisor/pkg/tcpip/transport/tcp.(*processor).handleSegments(0xc0005255e0) 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(0xc0003bde20, 0x1, 0xc0003bde20) pkg/sleep/sleep_unsafe.go:190 +0x6a gvisor.dev/gvisor/pkg/sleep.(*Sleeper).Fetch(0xc0003bde20, 0xc000525601, 0x2, 0x0) pkg/sleep/sleep_unsafe.go:225 +0x38 gvisor.dev/gvisor/pkg/tcpip/transport/tcp.(*processor).handleSegments(0xc000525650) 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(0xc0003bd640, 0x1, 0xc0003bd640) pkg/sleep/sleep_unsafe.go:190 +0x6a gvisor.dev/gvisor/pkg/sleep.(*Sleeper).Fetch(0xc0003bd640, 0xc000525601, 0x2, 0x0) pkg/sleep/sleep_unsafe.go:225 +0x38 gvisor.dev/gvisor/pkg/tcpip/transport/tcp.(*processor).handleSegments(0xc0005256c0) 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 417 [select]: gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).block(0xc000c1e000, 0xc0006264e0, 0x0, 0x1, 0x0) pkg/sentry/kernel/task_block.go:138 +0x16c gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).BlockWithTimeout(0xc000c1e000, 0xc0006264e0, 0xf5fd00, 0x0, 0xc4204f4548, 0x0, 0xffffffff) pkg/sentry/kernel/task_block.go:37 +0x1ec gvisor.dev/gvisor/pkg/sentry/syscalls/linux.futexWaitDuration(0xc000c1e000, 0x0, 0xc000c1e001, 0xc4204f4548, 0x16aa00, 0xffffffff, 0x0, 0x0, 0x0) pkg/sentry/syscalls/linux/sys_futex.go:97 +0xd2 gvisor.dev/gvisor/pkg/sentry/syscalls/linux.Futex(0xc000c1e000, 0xc4204f4548, 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(0xc000c1e000, 0xca, 0xc4204f4548, 0x0, 0x0, 0x0, 0x0, 0x0, 0x15ab4d0, 0xe03180, ...) pkg/sentry/kernel/task_syscall.go:170 +0x122 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscallInvoke(0xc000c1e000, 0xca, 0xc4204f4548, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0) pkg/sentry/kernel/task_syscall.go:291 +0x66 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscallEnter(0xc000c1e000, 0xca, 0xc4204f4548, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0) pkg/sentry/kernel/task_syscall.go:252 +0x96 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscall(0xc000c1e000, 0x2, 0xc0002c5e60) pkg/sentry/kernel/task_syscall.go:227 +0x139 gvisor.dev/gvisor/pkg/sentry/kernel.(*runApp).execute(0x0, 0xc000c1e000, 0xf4eea0, 0x0) pkg/sentry/kernel/task_run.go:258 +0xed5 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).run(0xc000c1e000, 0x34) 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 164 [syscall]: syscall.Syscall6(0x10f, 0xc00058f6d0, 0x2, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0) src/syscall/asm_linux_amd64.s:41 +0x5 gvisor.dev/gvisor/pkg/unet.(*Socket).wait(0xc000350b70, 0xffffffffffffff00, 0x0, 0x0) pkg/unet/unet_unsafe.go:55 +0x9a gvisor.dev/gvisor/pkg/unet.(*ServerSocket).Accept(0xc00000e028, 0x0, 0x0, 0x0) pkg/unet/unet.go:539 +0x11a gvisor.dev/gvisor/pkg/control/server.(*Server).serve(0xc00000c5c0) pkg/control/server/server.go:99 +0x32 gvisor.dev/gvisor/pkg/control/server.(*Server).StartServing.func1(0xc00000c5c0) 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 209 [select, 1 minutes]: gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).block(0xc000636000, 0xc000626240, 0x0, 0x1, 0x0) pkg/sentry/kernel/task_block.go:138 +0x16c gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).BlockWithTimeout(0xc000636000, 0xc000626240, 0xf5fd00, 0x0, 0xc4200f6148, 0x0, 0xffffffff) pkg/sentry/kernel/task_block.go:37 +0x1ec gvisor.dev/gvisor/pkg/sentry/syscalls/linux.futexWaitDuration(0xc000636000, 0x0, 0xc000636001, 0xc4200f6148, 0x247a00, 0xffffffff, 0x0, 0x0, 0x0) pkg/sentry/syscalls/linux/sys_futex.go:97 +0xd2 gvisor.dev/gvisor/pkg/sentry/syscalls/linux.Futex(0xc000636000, 0xc4200f6148, 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(0xc000636000, 0xca, 0xc4200f6148, 0x0, 0x0, 0x0, 0x0, 0x0, 0xc000720410, 0xe03180, ...) pkg/sentry/kernel/task_syscall.go:170 +0x122 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscallInvoke(0xc000636000, 0xca, 0xc4200f6148, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0) pkg/sentry/kernel/task_syscall.go:291 +0x66 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscallEnter(0xc000636000, 0xca, 0xc4200f6148, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0) pkg/sentry/kernel/task_syscall.go:252 +0x96 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscall(0xc000636000, 0x2, 0xc0003bc020) pkg/sentry/kernel/task_syscall.go:227 +0x139 gvisor.dev/gvisor/pkg/sentry/kernel.(*runApp).execute(0x0, 0xc000636000, 0xf4eea0, 0x0) pkg/sentry/kernel/task_run.go:258 +0xed5 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).run(0xc000636000, 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 138 [syscall, 1 minutes]: syscall.Syscall6(0x119, 0x15, 0xc000235b00, 0x64, 0xffffffffffffffff, 0x0, 0x0, 0x0, 0x0, 0x0) src/syscall/asm_linux_amd64.s:41 +0x5 gvisor.dev/gvisor/pkg/fdnotifier.epollWait(0x15, 0xc000235b00, 0x64, 0x64, 0xffffffffffffffff, 0x0, 0x0, 0x0) pkg/fdnotifier/poll_unsafe.go:77 +0x74 gvisor.dev/gvisor/pkg/fdnotifier.(*notifier).waitAndNotify(0xc00021f160, 0x0, 0x43e0c8) pkg/fdnotifier/fdnotifier.go:149 +0x7a created by gvisor.dev/gvisor/pkg/fdnotifier.newNotifier pkg/fdnotifier/fdnotifier.go:64 +0xaa goroutine 139 [syscall, 1 minutes]: syscall.Syscall6(0x10f, 0xc0005a0090, 0x1, 0x0, 0x0, 0x0, 0x0, 0x40c416, 0x28, 0xc0002021c0) src/syscall/asm_linux_amd64.s:41 +0x5 golang.org/x/sys/unix.ppoll(0xc0005a0090, 0x1, 0x0, 0x0, 0x7fc09f3e9560, 0x0, 0x15aa3c0) external/org_golang_x_sys/unix/zsyscall_linux.go:87 +0x88 golang.org/x/sys/unix.Ppoll(0xc0005a0090, 0x1, 0x1, 0x0, 0x0, 0x0, 0xc0005a0090, 0x8) external/org_golang_x_sys/unix/syscall_linux.go:142 +0x9d gvisor.dev/gvisor/runsc/boot.(*Loader).startGoferMonitor.func1.1(0x8, 0x1, 0xc0005a0090, 0xc0005a0090) runsc/boot/loader.go:710 +0x44 gvisor.dev/gvisor/runsc/specutils.RetryEintr(0xc000231f80, 0x0, 0x0, 0x0, 0x1) runsc/specutils/specutils.go:475 +0x27 gvisor.dev/gvisor/runsc/boot.(*Loader).startGoferMonitor.func1(0x7ffe95927fca, 0x1e, 0xc000396308, 0x1, 0x1, 0xc000144160) runsc/boot/loader.go:708 +0x1b9 created by gvisor.dev/gvisor/runsc/boot.(*Loader).startGoferMonitor runsc/boot/loader.go:699 +0x71 goroutine 140 [syscall, 1 minutes]: syscall.Syscall6(0x10f, 0xc0001f8f70, 0x1, 0x0, 0x0, 0x0, 0x0, 0x737020656420656d, 0x736d206373742065, 0x636d206561702072) src/syscall/asm_linux_amd64.s:41 +0x5 golang.org/x/sys/unix.ppoll(0xc0001f8f70, 0x1, 0x0, 0x0, 0x647020786e206c6c, 0x6472206267316570, 0x206d6c2070637374) external/org_golang_x_sys/unix/zsyscall_linux.go:87 +0x88 golang.org/x/sys/unix.Ppoll(0xc0001f8f70, 0x1, 0x1, 0x0, 0x0, 0x76207164716c756d, 0x336573737320786d, 0x31786320616d6620) external/org_golang_x_sys/unix/syscall_linux.go:142 +0x9d gvisor.dev/gvisor/pkg/p9.(*Client).watch(0xc0002e4200, 0xc00012fec0) pkg/p9/client.go:252 +0xb3 created by gvisor.dev/gvisor/pkg/p9.NewClient pkg/p9/client.go:232 +0x417 goroutine 141 [select, 1 minutes]: gvisor.dev/gvisor/pkg/sentry/kernel/time.(*Timer).runGoroutine(0xc0003bae10) 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 142 [syscall, 1 minutes]: os/signal.signal_recv(0x6e776f64746c656d) 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 177 [select, 1 minutes]: reflect.rselect(0xc0001b2900, 0x22, 0x22, 0xc0001b2900, 0x40c416) GOROOT/src/runtime/select.go:544 +0x38a reflect.Select(0xc000162000, 0x22, 0x49, 0x20, 0x21, 0xc000162000, 0x20, 0x49) GOROOT/src/reflect/value.go:2229 +0x170 gvisor.dev/gvisor/pkg/sentry/sighandling.handleSignals(0xc000710000, 0x21, 0x40, 0xc0007a04c0, 0xc000201080, 0xc0002010e0) pkg/sentry/sighandling/sighandling.go:44 +0x376 created by gvisor.dev/gvisor/pkg/sentry/sighandling.StartSignalForwarding pkg/sentry/sighandling/sighandling.go:96 +0x23d goroutine 178 [select]: gvisor.dev/gvisor/pkg/sentry/watchdog.(*Watchdog).loop(0xc0001a6100) 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 179 [select]: gvisor.dev/gvisor/pkg/sentry/kernel/time.(*Timer).runGoroutine(0xc00013a1b0) 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 180 [select, 1 minutes]: gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).block(0xc000326000, 0xc000201020, 0x0, 0x1, 0x0) pkg/sentry/kernel/task_block.go:138 +0x16c gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).BlockWithTimeout(0xc000326000, 0xc000201020, 0xf5fd00, 0x0, 0x548a688, 0x0, 0xc0ffffffff) pkg/sentry/kernel/task_block.go:37 +0x1ec gvisor.dev/gvisor/pkg/sentry/syscalls/linux.futexWaitDuration(0xc000326000, 0x0, 0xc000326001, 0x548a688, 0x0, 0xffffffff, 0x1, 0x0, 0x0) pkg/sentry/syscalls/linux/sys_futex.go:97 +0xd2 gvisor.dev/gvisor/pkg/sentry/syscalls/linux.Futex(0xc000326000, 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(0xc000326000, 0xca, 0x548a688, 0x0, 0x0, 0x0, 0x0, 0x0, 0xc000aa0090, 0xe03180, ...) pkg/sentry/kernel/task_syscall.go:170 +0x122 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscallInvoke(0xc000326000, 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(0xc000326000, 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(0xc000326000, 0x2, 0xc0003bc020) pkg/sentry/kernel/task_syscall.go:227 +0x139 gvisor.dev/gvisor/pkg/sentry/kernel.(*runApp).execute(0x0, 0xc000326000, 0xf4eea0, 0x0) pkg/sentry/kernel/task_run.go:258 +0xed5 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).run(0xc000326000, 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 144 [select, 1 minutes]: gvisor.dev/gvisor/pkg/sentry/kernel/time.(*Timer).runGoroutine(0xc0003bb0e0) 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 193 [semacquire, 1 minutes]: sync.runtime_Semacquire(0xc00055ceb8) GOROOT/src/runtime/sema.go:56 +0x42 sync.(*WaitGroup).Wait(0xc00055ceb0) 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(0xc000144160, 0xc00055c800, 0x1e) runsc/boot/loader.go:893 +0x35 gvisor.dev/gvisor/runsc/boot.(*Loader).waitContainer(0xc000144160, 0xc0000d60c0, 0x1e, 0xc00058a1d8, 0x0, 0x198) runsc/boot/loader.go:848 +0x143 gvisor.dev/gvisor/runsc/boot.(*containerManager).Wait(0xc00000c5e0, 0xc000612050, 0xc00058a1d8, 0x0, 0x0) runsc/boot/controller.go:430 +0x7d reflect.Value.call(0xc0006944e0, 0xc00000e180, 0x13, 0xe048ea, 0x4, 0xc00020dea8, 0x3, 0x3, 0xc00020dca0, 0x4c151d, ...) GOROOT/src/reflect/value.go:460 +0x8ab reflect.Value.Call(0xc0006944e0, 0xc00000e180, 0x13, 0xc00020dea8, 0x3, 0x3, 0x0, 0xc00000c5e0, 0x16) GOROOT/src/reflect/value.go:321 +0xb4 gvisor.dev/gvisor/pkg/urpc.(*Server).handleOne(0xc000350c30, 0xc0003c0f00, 0x0, 0x0) pkg/urpc/urpc.go:325 +0x56d gvisor.dev/gvisor/pkg/urpc.(*Server).handleRegistered(0xc000350c30, 0xc0003c0f00, 0x0, 0x0) pkg/urpc/urpc.go:420 +0x35 gvisor.dev/gvisor/pkg/urpc.(*Server).StartHandling.func1(0xc000350c30, 0xc0003c0f00) pkg/urpc/urpc.go:440 +0x66 created by gvisor.dev/gvisor/pkg/urpc.(*Server).StartHandling pkg/urpc/urpc.go:438 +0x61 goroutine 166 [select]: gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).block(0xc000327500, 0xc0003ca1e0, 0xc00010e000, 0x0, 0x0) pkg/sentry/kernel/task_block.go:138 +0x16c gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).BlockWithDeadline(0xc000327500, 0xc0003ca1e0, 0x825c01, 0x23c30ad6bc, 0x0, 0x0) pkg/sentry/kernel/task_block.go:80 +0x81 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).BlockWithTimeout(0xc000327500, 0xc0003ca1e0, 0xf5fd01, 0xdf8475800, 0x54898d0, 0x327500, 0xc4ffffffff) pkg/sentry/kernel/task_block.go:42 +0xaa gvisor.dev/gvisor/pkg/sentry/syscalls/linux.futexWaitDuration(0xc000327500, 0xdf8475800, 0x0, 0x54898d0, 0x0, 0xffffffff, 0x0, 0x4e20, 0x15ca9c9b03) pkg/sentry/syscalls/linux/sys_futex.go:97 +0xd2 gvisor.dev/gvisor/pkg/sentry/syscalls/linux.Futex(0xc000327500, 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(0xc000327500, 0xca, 0x54898d0, 0x0, 0x0, 0xc4200d9ea8, 0x0, 0x0, 0xc000702090, 0xe03180, ...) pkg/sentry/kernel/task_syscall.go:170 +0x122 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscallInvoke(0xc000327500, 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(0xc000327500, 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(0xc000327500, 0x2, 0xc0003bc020) pkg/sentry/kernel/task_syscall.go:227 +0x139 gvisor.dev/gvisor/pkg/sentry/kernel.(*runApp).execute(0x0, 0xc000327500, 0xf4eea0, 0x0) pkg/sentry/kernel/task_run.go:258 +0xed5 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).run(0xc000327500, 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 194 [select]: gvisor.dev/gvisor/pkg/sentry/kernel/time.(*Timer).runGoroutine(0xc00013a000) 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 104 [select, 1 minutes]: gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).block(0xc0003d8000, 0xc000200840, 0x0, 0x1, 0x0) pkg/sentry/kernel/task_block.go:138 +0x16c gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).BlockWithTimeout(0xc0003d8000, 0xc000200840, 0xf5fd00, 0x0, 0x54a8a00, 0x0, 0xc0ffffffff) pkg/sentry/kernel/task_block.go:37 +0x1ec gvisor.dev/gvisor/pkg/sentry/syscalls/linux.futexWaitDuration(0xc0003d8000, 0x0, 0xc0003d8001, 0x54a8a00, 0x18fa00, 0xffffffff, 0x1, 0x0, 0x0) pkg/sentry/syscalls/linux/sys_futex.go:97 +0xd2 gvisor.dev/gvisor/pkg/sentry/syscalls/linux.Futex(0xc0003d8000, 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(0xc0003d8000, 0xca, 0x54a8a00, 0x0, 0x0, 0x0, 0x0, 0x0, 0xc000236090, 0xe03180, ...) pkg/sentry/kernel/task_syscall.go:170 +0x122 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscallInvoke(0xc0003d8000, 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(0xc0003d8000, 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(0xc0003d8000, 0x2, 0xc0003bc020) pkg/sentry/kernel/task_syscall.go:227 +0x139 gvisor.dev/gvisor/pkg/sentry/kernel.(*runApp).execute(0x0, 0xc0003d8000, 0xf4eea0, 0x0) pkg/sentry/kernel/task_run.go:258 +0xed5 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).run(0xc0003d8000, 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 167 [select, 1 minutes]: gvisor.dev/gvisor/pkg/sentry/kernel/time.(*Timer).runGoroutine(0xc0002a6000) 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 105 [select]: gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).block(0xc0003d8a80, 0xc000200b40, 0x0, 0x1, 0x0) pkg/sentry/kernel/task_block.go:138 +0x16c gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).BlockWithTimeout(0xc0003d8a80, 0xc000200b40, 0xf5fd00, 0x0, 0xc4200ca948, 0x0, 0xc0ffffffff) pkg/sentry/kernel/task_block.go:37 +0x1ec gvisor.dev/gvisor/pkg/sentry/syscalls/linux.futexWaitDuration(0xc0003d8a80, 0x0, 0xc0003d8a01, 0xc4200ca948, 0x0, 0xffffffff, 0x1, 0x0, 0x0) pkg/sentry/syscalls/linux/sys_futex.go:97 +0xd2 gvisor.dev/gvisor/pkg/sentry/syscalls/linux.Futex(0xc0003d8a80, 0xc4200ca948, 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(0xc0003d8a80, 0xca, 0xc4200ca948, 0x0, 0x0, 0x0, 0x0, 0x0, 0xc000980410, 0xe03180, ...) pkg/sentry/kernel/task_syscall.go:170 +0x122 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscallInvoke(0xc0003d8a80, 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(0xc0003d8a80, 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(0xc0003d8a80, 0x2, 0xc0003bc020) pkg/sentry/kernel/task_syscall.go:227 +0x139 gvisor.dev/gvisor/pkg/sentry/kernel.(*runApp).execute(0x0, 0xc0003d8a80, 0xf4eea0, 0x0) pkg/sentry/kernel/task_run.go:258 +0xed5 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).run(0xc0003d8a80, 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 195 [select]: gvisor.dev/gvisor/pkg/sentry/kernel/time.(*Timer).runGoroutine(0xc00013a090) 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/time.(*Timer).runGoroutine(0xc0003ba000) 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 225 [select, 1 minutes]: gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).block(0xc0006cc000, 0xc0006b2300, 0x0, 0x1, 0x0) pkg/sentry/kernel/task_block.go:138 +0x16c gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).BlockWithTimeout(0xc0006cc000, 0xc0006b2300, 0xf5fd00, 0x0, 0xc42015e148, 0x0, 0xc0ffffffff) pkg/sentry/kernel/task_block.go:37 +0x1ec gvisor.dev/gvisor/pkg/sentry/syscalls/linux.futexWaitDuration(0xc0006cc000, 0x0, 0xc0006cc001, 0xc42015e148, 0x5000000, 0xffffffff, 0x1, 0x0, 0x0) pkg/sentry/syscalls/linux/sys_futex.go:97 +0xd2 gvisor.dev/gvisor/pkg/sentry/syscalls/linux.Futex(0xc0006cc000, 0xc42015e148, 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(0xc0006cc000, 0xca, 0xc42015e148, 0x0, 0x0, 0x0, 0x0, 0x0, 0xc000156790, 0xe03180, ...) pkg/sentry/kernel/task_syscall.go:170 +0x122 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscallInvoke(0xc0006cc000, 0xca, 0xc42015e148, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0) pkg/sentry/kernel/task_syscall.go:291 +0x66 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscallEnter(0xc0006cc000, 0xca, 0xc42015e148, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0) pkg/sentry/kernel/task_syscall.go:252 +0x96 gvisor.dev/gvisor/pkg/sentry/kernel.(*Task).doSyscall(0xc0006cc000, 0x2, 0xc0003bc020) pkg/sentry/kernel/task_syscall.go:227 +0x139 gvisor.dev/gvisor/pkg/sentry/kernel.(*runApp).execute(0x0, 0xc0006cc000, 0xf4eea0, 0x0) W0408 04:41:02.409886 58878 sandbox.go:750] Wait RPC to container "ci-gvisor-kvm-direct-sandbox-0" failed: urpc method "containerManager.Wait" failed: EOF. Will try waiting on the sandbox process instead. D0408 04:41:02.410224 58878 container.go:714] Destroy container "ci-gvisor-kvm-direct-sandbox-0" D0408 04:41:02.410300 58878 container.go:801] Destroying container "ci-gvisor-kvm-direct-sandbox-0" D0408 04:41:02.410317 58878 sandbox.go:1126] Destroying root container "ci-gvisor-kvm-direct-sandbox-0" by destroying sandbox D0408 04:41:02.410340 58878 sandbox.go:796] Destroy sandbox "ci-gvisor-kvm-direct-sandbox-0" D0408 04:41:02.410354 58878 container.go:815] Killing gofer for container "ci-gvisor-kvm-direct-sandbox-0", PID: 58884 I0408 04:41:02.612568 58878 main.go:329] Exiting with status: 512 VM DIAGNOSIS: I0408 04:41:02.223008 59635 main.go:305] *************************** I0408 04:41:02.223072 59635 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-0] I0408 04:41:02.223127 59635 main.go:307] Version release-20200323.0-77-g51e461cf9c49 I0408 04:41:02.223154 59635 main.go:308] PID: 59635 I0408 04:41:02.223183 59635 main.go:309] UID: 0, GID: 0 I0408 04:41:02.223221 59635 main.go:310] Configuration: I0408 04:41:02.223262 59635 main.go:311] RootDir: /syzkaller/managers/kvm-direct-sandbox/workdir/gvisor_root I0408 04:41:02.223297 59635 main.go:312] Platform: kvm I0408 04:41:02.223320 59635 main.go:313] FileAccess: exclusive, overlay: false I0408 04:41:02.223354 59635 main.go:314] Network: sandbox, logging: false I0408 04:41:02.223386 59635 main.go:315] Strace: false, max size: 1024, syscalls: [] I0408 04:41:02.223415 59635 main.go:316] *************************** D0408 04:41:02.223495 59635 container.go:159] Load container "/syzkaller/managers/kvm-direct-sandbox/workdir/gvisor_root" "ci-gvisor-kvm-direct-sandbox-0" D0408 04:41:02.225265 59635 container.go:592] Signal container "ci-gvisor-kvm-direct-sandbox-0": signal 0 D0408 04:41:02.225337 59635 sandbox.go:814] Signal sandbox "ci-gvisor-kvm-direct-sandbox-0" D0408 04:41:02.225353 59635 sandbox.go:318] Connecting to sandbox "ci-gvisor-kvm-direct-sandbox-0" D0408 04:41:02.225561 59635 urpc.go:534] urpc: successfully marshalled 110 bytes. I0408 04:41:02.409934 59635 debug.go:128] Found sandbox "ci-gvisor-kvm-direct-sandbox-0", PID: 58889 I0408 04:41:02.409966 59635 debug.go:137] Retrieving sandbox stacks D0408 04:41:02.409988 59635 sandbox.go:932] Stacks sandbox "ci-gvisor-kvm-direct-sandbox-0" D0408 04:41:02.410016 59635 sandbox.go:318] Connecting to sandbox "ci-gvisor-kvm-direct-sandbox-0" W0408 04:41:02.410071 59635 error.go:48] FATAL ERROR: retrieving stacks: connecting to control server at PID 58889: connection refused retrieving stacks: connecting to control server at PID 58889: connection refused W0408 04:41:02.410213 59635 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-0"]: exit status 128 I0408 04:41:02.223008 59635 main.go:305] *************************** I0408 04:41:02.223072 59635 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-0] I0408 04:41:02.223127 59635 main.go:307] Version release-20200323.0-77-g51e461cf9c49 I0408 04:41:02.223154 59635 main.go:308] PID: 59635 I0408 04:41:02.223183 59635 main.go:309] UID: 0, GID: 0 I0408 04:41:02.223221 59635 main.go:310] Configuration: I0408 04:41:02.223262 59635 main.go:311] RootDir: /syzkaller/managers/kvm-direct-sandbox/workdir/gvisor_root I0408 04:41:02.223297 59635 main.go:312] Platform: kvm I0408 04:41:02.223320 59635 main.go:313] FileAccess: exclusive, overlay: false I0408 04:41:02.223354 59635 main.go:314] Network: sandbox, logging: false I0408 04:41:02.223386 59635 main.go:315] Strace: false, max size: 1024, syscalls: [] I0408 04:41:02.223415 59635 main.go:316] *************************** D0408 04:41:02.223495 59635 container.go:159] Load container "/syzkaller/managers/kvm-direct-sandbox/workdir/gvisor_root" "ci-gvisor-kvm-direct-sandbox-0" D0408 04:41:02.225265 59635 container.go:592] Signal container "ci-gvisor-kvm-direct-sandbox-0": signal 0 D0408 04:41:02.225337 59635 sandbox.go:814] Signal sandbox "ci-gvisor-kvm-direct-sandbox-0" D0408 04:41:02.225353 59635 sandbox.go:318] Connecting to sandbox "ci-gvisor-kvm-direct-sandbox-0" D0408 04:41:02.225561 59635 urpc.go:534] urpc: successfully marshalled 110 bytes. I0408 04:41:02.409934 59635 debug.go:128] Found sandbox "ci-gvisor-kvm-direct-sandbox-0", PID: 58889 I0408 04:41:02.409966 59635 debug.go:137] Retrieving sandbox stacks D0408 04:41:02.409988 59635 sandbox.go:932] Stacks sandbox "ci-gvisor-kvm-direct-sandbox-0" D0408 04:41:02.410016 59635 sandbox.go:318] Connecting to sandbox "ci-gvisor-kvm-direct-sandbox-0" W0408 04:41:02.410071 59635 error.go:48] FATAL ERROR: retrieving stacks: connecting to control server at PID 58889: connection refused retrieving stacks: connecting to control server at PID 58889: connection refused W0408 04:41:02.410213 59635 main.go:338] Failure to execute command, err: 1 [774725.876442] Stack: [774725.878670] ffffffffa114c2bfc 0000000000000000c 000000c00170d88bc ffff9fca74832e08c [774725.886961] 0000000000000000c ffff9fca73219400c 0000000000000000c ffff9fca74825600c [774725.895250] 0000000000000000c ffffffffa1016200c ffffba198e8b7e88c fffffa17c7d51280c [774725.905823] Call Trace: [774725.908898] [] ? copy_page_from_iter+0x9f/0x1e0 [774725.915281] [] ? pipe_write+0xf0/0x3e0 [774725.920880] [] ? new_sync_write+0xe0/0x130 [774725.926824] [] ? vfs_write+0xb0/0x190 [774725.932420] [] ? SyS_write+0x5a/0xd0 [774725.937839] [] ? do_syscall_64+0x8d/0x100 [774725.943693] [] ? entry_SYSCALL_64_after_swapgs+0x58/0xc6 [774725.950852] Code: c40 cff cc9 c75 cb6 c89 cd1 c83 ce2 c07 cc1 ce9 c03 c74 c12 c4c c8b c06 c4c c89 c07 c48 c8d c76 c08 c48 c8d c7f c08 cff cc9 c75 cee c21 cd2 c74 c10 c89 cd1 c8a c06 c88 c07 c<48> cff cc6 c48 cff cc7 cff cc9 c75 cf2 c31 cc0 c0f c1f c00 cc3 c0f c1f c40 c00 c66 c [774725.981198] NMI backtrace for cpu 10 [774725.985089] CPU: 10 PID: 64837 Comm: exe Tainted: G L 4.9.0-11-amd64 #1 Debian 4.9.189-3+deb9u2 [774725.995111] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 [774726.004537] task: ffff9fc6f2817140 task.stack: ffffba1961690000 [774726.010652] RIP: 0010:[] c [] wait_consider_task+0x29/0xb70 [774726.019645] RSP: 0018:ffffba1961693df8 EFLAGS: 00000246 [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 4