D0316 08:00:30.258064 30644 sampler.go:168] Time: Adjusting syscall overhead up to 8000 D0316 08:00:30.258161 30644 parameters.go:238] Clock(Monotonic): error: 82 ns, adjusted frequency from 2299774371 Hz to 2299774606 Hz D0316 08:00:30.258225 30644 parameters.go:238] Clock(Realtime): error: -137 ns, adjusted frequency from 2299774620 Hz to 2299774072 Hz D0316 08:00:30.258263 30644 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:682882101571709 monotonicBaseRef:2000285982 monotonicFrequency:2299774606 realtimeReady:1 realtimeBaseCycles:682882101725840 realtimeBaseRef:1584345630258222168 realtimeFrequency:2299774072} D0316 08:00:31.258263 30644 parameters.go:238] Clock(Monotonic): error: 482 ns, adjusted frequency from 2299774606 Hz to 2299775284 Hz D0316 08:00:31.258360 30644 parameters.go:238] Clock(Realtime): error: 73 ns, adjusted frequency from 2299774072 Hz to 2299774625 Hz D0316 08:00:31.258376 30644 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:682884401573355 monotonicBaseRef:3000384704 monotonicFrequency:2299775284 realtimeReady:1 realtimeBaseCycles:682884401812780 realtimeBaseRef:1584345631258358210 realtimeFrequency:2299774625} D0316 08:00:32.258261 30644 parameters.go:238] Clock(Monotonic): error: 327 ns, adjusted frequency from 2299775284 Hz to 2299775186 Hz D0316 08:00:32.258344 30644 parameters.go:238] Clock(Realtime): error: 80 ns, adjusted frequency from 2299774625 Hz to 2299774767 Hz D0316 08:00:32.258364 30644 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:682886701266906 monotonicBaseRef:4000349164 monotonicFrequency:2299775186 realtimeReady:1 realtimeBaseCycles:682886701551949 realtimeBaseRef:1584345632258342792 realtimeFrequency:2299774767} D0316 08:00:33.258091 30644 parameters.go:238] Clock(Monotonic): error: -38 ns, adjusted frequency from 2299775186 Hz to 2299774627 Hz D0316 08:00:33.258189 30644 parameters.go:238] Clock(Realtime): error: 351 ns, adjusted frequency from 2299774767 Hz to 2299775448 Hz D0316 08:00:33.258209 30644 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:682889000623815 monotonicBaseRef:5000167286 monotonicFrequency:2299774627 realtimeReady:1 realtimeBaseCycles:682889000970952 realtimeBaseRef:1584345633258188096 realtimeFrequency:2299775448} D0316 08:00:34.259241 30644 parameters.go:238] Clock(Monotonic): error: 220 ns, adjusted frequency from 2299774627 Hz to 2299775112 Hz D0316 08:00:34.259337 30644 parameters.go:238] Clock(Realtime): error: -201 ns, adjusted frequency from 2299775448 Hz to 2299774213 Hz D0316 08:00:34.259353 30644 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:682891303137299 monotonicBaseRef:6001358210 monotonicFrequency:2299775112 realtimeReady:1 realtimeBaseCycles:682891303386291 realtimeBaseRef:1584345634259335986 realtimeFrequency:2299774213} D0316 08:00:35.258401 30644 parameters.go:238] Clock(Monotonic): error: -155 ns, adjusted frequency from 2299775112 Hz to 2299774393 Hz D0316 08:00:35.258513 30644 parameters.go:238] Clock(Realtime): error: 176 ns, adjusted frequency from 2299774213 Hz to 2299775033 Hz D0316 08:00:35.258530 30644 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:682893600988810 monotonicBaseRef:7000521779 monotonicFrequency:2299774393 realtimeReady:1 realtimeBaseCycles:682893601262814 realtimeBaseRef:1584345635258510822 realtimeFrequency:2299775033} D0316 08:00:36.258296 30644 parameters.go:238] Clock(Monotonic): error: 224 ns, adjusted frequency from 2299774393 Hz to 2299775117 Hz D0316 08:00:36.258473 30644 parameters.go:238] Clock(Realtime): error: 60 ns, adjusted frequency from 2299775033 Hz to 2299774818 Hz D0316 08:00:36.258517 30644 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:682895900493538 monotonicBaseRef:8000404521 monotonicFrequency:2299775117 realtimeReady:1 realtimeBaseCycles:682895900945154 realtimeBaseRef:1584345636258470516 realtimeFrequency:2299774818} D0316 08:00:37.258103 30644 parameters.go:238] Clock(Monotonic): error: -124 ns, adjusted frequency from 2299775117 Hz to 2299774477 Hz D0316 08:00:37.258182 30644 parameters.go:238] Clock(Realtime): error: 16 ns, adjusted frequency from 2299774818 Hz to 2299774741 Hz D0316 08:00:37.258197 30644 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:682898199853885 monotonicBaseRef:9000224168 monotonicFrequency:2299774477 realtimeReady:1 realtimeBaseCycles:682898200052744 realtimeBaseRef:1584345637258180388 realtimeFrequency:2299774741} D0316 08:00:38.259721 30644 parameters.go:238] Clock(Monotonic): error: 124 ns, adjusted frequency from 2299774477 Hz to 2299775099 Hz D0316 08:00:38.259837 30644 parameters.go:238] Clock(Realtime): error: 203 ns, adjusted frequency from 2299774741 Hz to 2299775205 Hz D0316 08:00:38.259857 30644 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:682900503349669 monotonicBaseRef:10001842286 monotonicFrequency:2299775099 realtimeReady:1 realtimeBaseCycles:682900503631244 realtimeBaseRef:1584345638259834358 realtimeFrequency:2299775205} D0316 08:00:39.258880 30644 parameters.go:238] Clock(Monotonic): error: -13 ns, adjusted frequency from 2299775099 Hz to 2299774829 Hz D0316 08:00:39.259013 30644 parameters.go:238] Clock(Realtime): error: 47 ns, adjusted frequency from 2299775205 Hz to 2299774901 Hz D0316 08:00:39.259041 30644 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:682902801190926 monotonicBaseRef:11001001402 monotonicFrequency:2299774829 realtimeReady:1 realtimeBaseCycles:682902801511995 realtimeBaseRef:1584345639259010601 realtimeFrequency:2299774901} D0316 08:00:40.258033 30644 parameters.go:238] Clock(Monotonic): error: 172 ns, adjusted frequency from 2299774829 Hz to 2299775233 Hz D0316 08:00:40.258144 30644 parameters.go:238] Clock(Realtime): error: -144 ns, adjusted frequency from 2299774901 Hz to 2299774492 Hz D0316 08:00:40.258160 30644 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:682905099015332 monotonicBaseRef:12000153308 monotonicFrequency:2299775233 realtimeReady:1 realtimeBaseCycles:682905099288502 realtimeBaseRef:1584345640258141648 realtimeFrequency:2299774492} D0316 08:00:41.258225 30644 parameters.go:238] Clock(Monotonic): error: -162 ns, adjusted frequency from 2299775233 Hz to 2299774562 Hz D0316 08:00:41.258325 30644 parameters.go:238] Clock(Realtime): error: 257 ns, adjusted frequency from 2299774492 Hz to 2299775445 Hz D0316 08:00:41.258345 30644 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:682907399225221 monotonicBaseRef:13000342307 monotonicFrequency:2299774562 realtimeReady:1 realtimeBaseCycles:682907399481375 realtimeBaseRef:1584345641258323570 realtimeFrequency:2299775445} D0316 08:00:42.259538 30644 parameters.go:238] Clock(Monotonic): error: 48 ns, adjusted frequency from 2299774562 Hz to 2299774953 Hz D0316 08:00:42.259617 30644 parameters.go:238] Clock(Realtime): error: -116 ns, adjusted frequency from 2299775445 Hz to 2299774549 Hz D0316 08:00:42.259638 30644 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:682909702026765 monotonicBaseRef:14001658515 monotonicFrequency:2299774953 realtimeReady:1 realtimeBaseCycles:682909702227174 realtimeBaseRef:1584345642259615154 realtimeFrequency:2299774549} D0316 08:00:43.258136 30644 parameters.go:238] Clock(Monotonic): error: -113 ns, adjusted frequency from 2299774953 Hz to 2299774500 Hz D0316 08:00:43.258231 30644 parameters.go:238] Clock(Realtime): error: -204 ns, adjusted frequency from 2299774549 Hz to 2299774332 Hz D0316 08:00:43.258243 30644 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:682911998582508 monotonicBaseRef:15000258721 monotonicFrequency:2299774500 realtimeReady:1 realtimeBaseCycles:682911998815213 realtimeBaseRef:1584345643258229579 realtimeFrequency:2299774332} D0316 08:00:44.259781 30644 parameters.go:238] Clock(Monotonic): error: 9 ns, adjusted frequency from 2299774500 Hz to 2299774671 Hz D0316 08:00:44.259905 30644 parameters.go:238] Clock(Realtime): error: 225 ns, adjusted frequency from 2299774332 Hz to 2299775369 Hz D0316 08:00:44.259923 30644 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:682914302068023 monotonicBaseRef:16001872363 monotonicFrequency:2299774671 realtimeReady:1 realtimeBaseCycles:682914302436118 realtimeBaseRef:1584345644259902166 realtimeFrequency:2299775369} D0316 08:00:45.258053 30644 parameters.go:238] Clock(Monotonic): error: 259 ns, adjusted frequency from 2299774671 Hz to 2299775411 Hz D0316 08:00:45.258141 30644 parameters.go:238] Clock(Realtime): error: -220 ns, adjusted frequency from 2299775369 Hz to 2299774324 Hz D0316 08:00:45.258156 30644 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:682916597911921 monotonicBaseRef:17000163163 monotonicFrequency:2299775411 realtimeReady:1 realtimeBaseCycles:682916598158342 realtimeBaseRef:1584345645258139756 realtimeFrequency:2299774324} D0316 08:00:46.258071 30644 parameters.go:238] Clock(Monotonic): error: -276 ns, adjusted frequency from 2299775411 Hz to 2299774172 Hz D0316 08:00:46.258186 30644 parameters.go:238] Clock(Realtime): error: 345 ns, adjusted frequency from 2299774324 Hz to 2299775565 Hz D0316 08:00:46.258224 30644 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:682918897751700 monotonicBaseRef:18000191151 monotonicFrequency:2299774172 realtimeReady:1 realtimeBaseCycles:682918898032367 realtimeBaseRef:1584345646258183108 realtimeFrequency:2299775565} D0316 08:00:47.258560 30644 parameters.go:238] Clock(Monotonic): error: 51 ns, adjusted frequency from 2299774172 Hz to 2299774927 Hz D0316 08:00:47.258652 30644 parameters.go:238] Clock(Realtime): error: 55 ns, adjusted frequency from 2299775565 Hz to 2299774900 Hz D0316 08:00:47.258672 30644 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:682921198201051 monotonicBaseRef:19000484735 monotonicFrequency:2299774927 realtimeReady:1 realtimeBaseCycles:682921198879841 realtimeBaseRef:1584345647258649200 realtimeFrequency:2299774900} D0316 08:00:48.258590 30644 parameters.go:238] Clock(Monotonic): error: 55 ns, adjusted frequency from 2299774927 Hz to 2299774906 Hz D0316 08:00:48.258703 30644 parameters.go:238] Clock(Realtime): error: -167 ns, adjusted frequency from 2299774900 Hz to 2299774447 Hz D0316 08:00:48.258722 30644 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:682923498493520 monotonicBaseRef:20000709775 monotonicFrequency:2299774906 realtimeReady:1 realtimeBaseCycles:682923498769079 realtimeBaseRef:1584345648258698917 realtimeFrequency:2299774447} D0316 08:00:49.259237 30644 parameters.go:238] Clock(Monotonic): error: -10 ns, adjusted frequency from 2299774906 Hz to 2299774931 Hz D0316 08:00:49.259347 30644 parameters.go:238] Clock(Realtime): error: -271 ns, adjusted frequency from 2299774447 Hz to 2299774185 Hz D0316 08:00:49.259362 30644 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:682925799760813 monotonicBaseRef:21001358702 monotonicFrequency:2299774931 realtimeReady:1 realtimeBaseCycles:682925800029836 realtimeBaseRef:1584345649259345202 realtimeFrequency:2299774185} D0316 08:00:50.258551 30644 parameters.go:238] Clock(Monotonic): error: 101 ns, adjusted frequency from 2299774931 Hz to 2299774904 Hz D0316 08:00:50.258650 30644 parameters.go:238] Clock(Realtime): error: 187 ns, adjusted frequency from 2299774185 Hz to 2299775166 Hz D0316 08:00:50.258678 30644 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:682928097956311 monotonicBaseRef:22000671924 monotonicFrequency:2299774904 realtimeReady:1 realtimeBaseCycles:682928098199676 realtimeBaseRef:1584345650258647592 realtimeFrequency:2299775166} D0316 08:00:51.258165 30644 parameters.go:238] Clock(Monotonic): error: 41 ns, adjusted frequency from 2299774904 Hz to 2299774739 Hz D0316 08:00:51.258256 30644 parameters.go:238] Clock(Realtime): error: -129 ns, adjusted frequency from 2299775166 Hz to 2299774509 Hz D0316 08:00:51.258270 30644 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:682930396846134 monotonicBaseRef:23000287068 monotonicFrequency:2299774739 realtimeReady:1 realtimeBaseCycles:682930397069139 realtimeBaseRef:1584345651258253769 realtimeFrequency:2299774509} D0316 08:00:52.259716 30644 parameters.go:238] Clock(Monotonic): error: -206 ns, adjusted frequency from 2299774739 Hz to 2299774477 Hz D0316 08:00:52.259803 30644 parameters.go:238] Clock(Realtime): error: 1 ns, adjusted frequency from 2299774509 Hz to 2299774782 Hz D0316 08:00:52.259817 30644 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:682932700186557 monotonicBaseRef:24001837517 monotonicFrequency:2299774477 realtimeReady:1 realtimeBaseCycles:682932700402563 realtimeBaseRef:1584345652259801275 realtimeFrequency:2299774782} D0316 08:00:53.258081 30644 parameters.go:238] Clock(Monotonic): error: -110 ns, adjusted frequency from 2299774477 Hz to 2299774714 Hz D0316 08:00:53.258164 30644 parameters.go:238] Clock(Realtime): error: 137 ns, adjusted frequency from 2299774782 Hz to 2299775142 Hz D0316 08:00:53.258182 30644 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:682934996201213 monotonicBaseRef:25000202651 monotonicFrequency:2299774714 realtimeReady:1 realtimeBaseCycles:682934996408186 realtimeBaseRef:1584345653258162349 realtimeFrequency:2299775142} D0316 08:00:54.258103 30644 parameters.go:238] Clock(Monotonic): error: 182 ns, adjusted frequency from 2299774714 Hz to 2299775374 Hz D0316 08:00:54.258194 30644 parameters.go:238] Clock(Realtime): error: 89 ns, adjusted frequency from 2299775142 Hz to 2299775017 Hz D0316 08:00:54.258213 30644 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:682937295950719 monotonicBaseRef:26000191689 monotonicFrequency:2299775374 realtimeReady:1 realtimeBaseCycles:682937296252516 realtimeBaseRef:1584345654258192433 realtimeFrequency:2299775017} D0316 08:00:55.258554 30644 parameters.go:238] Clock(Monotonic): error: 52 ns, adjusted frequency from 2299775374 Hz to 2299774806 Hz D0316 08:00:55.258636 30644 parameters.go:238] Clock(Realtime): error: -194 ns, adjusted frequency from 2299775017 Hz to 2299774402 Hz D0316 08:00:55.258650 30644 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:682939596812808 monotonicBaseRef:27000664219 monotonicFrequency:2299774806 realtimeReady:1 realtimeBaseCycles:682939597043360 realtimeBaseRef:1584345655258634140 realtimeFrequency:2299774402} D0316 08:00:56.258105 30644 parameters.go:238] Clock(Monotonic): error: -267 ns, adjusted frequency from 2299774806 Hz to 2299774197 Hz D0316 08:00:56.258199 30644 parameters.go:238] Clock(Realtime): error: 116 ns, adjusted frequency from 2299774402 Hz to 2299775063 Hz D0316 08:00:56.258223 30644 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:682941895546936 monotonicBaseRef:28000211705 monotonicFrequency:2299774197 realtimeReady:1 realtimeBaseCycles:682941895810502 realtimeBaseRef:1584345656258196157 realtimeFrequency:2299775063} D0316 08:00:57.258162 30644 parameters.go:238] Clock(Monotonic): error: 62 ns, adjusted frequency from 2299774197 Hz to 2299774982 Hz D0316 08:00:57.258254 30644 parameters.go:238] Clock(Realtime): error: -84 ns, adjusted frequency from 2299775063 Hz to 2299774588 Hz D0316 08:00:57.258269 30644 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:682944195486160 monotonicBaseRef:29000283462 monotonicFrequency:2299774982 realtimeReady:1 realtimeBaseCycles:682944195713990 realtimeBaseRef:1584345657258251999 realtimeFrequency:2299774588} D0316 08:00:58.258076 30644 parameters.go:238] Clock(Monotonic): error: 183 ns, adjusted frequency from 2299774982 Hz to 2299775057 Hz D0316 08:00:58.258168 30644 parameters.go:238] Clock(Realtime): error: -21 ns, adjusted frequency from 2299774588 Hz to 2299774812 Hz D0316 08:00:58.258183 30644 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:682946495060552 monotonicBaseRef:30000196240 monotonicFrequency:2299775057 realtimeReady:1 realtimeBaseCycles:682946495291404 realtimeBaseRef:1584345658258166262 realtimeFrequency:2299774812} D0316 08:00:59.258067 30644 parameters.go:238] Clock(Monotonic): error: -12 ns, adjusted frequency from 2299775057 Hz to 2299774779 Hz D0316 08:00:59.258145 30644 parameters.go:238] Clock(Realtime): error: -77 ns, adjusted frequency from 2299774812 Hz to 2299774657 Hz D0316 08:00:59.258158 30644 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:682948794822760 monotonicBaseRef:31000190652 monotonicFrequency:2299774779 realtimeReady:1 realtimeBaseCycles:682948795013568 realtimeBaseRef:1584345659258143369 realtimeFrequency:2299774657} D0316 08:01:00.258078 30644 parameters.go:238] Clock(Monotonic): error: 41 ns, adjusted frequency from 2299774779 Hz to 2299775064 Hz D0316 08:01:00.258199 30644 parameters.go:238] Clock(Realtime): error: 215 ns, adjusted frequency from 2299774657 Hz to 2299775281 Hz D0316 08:01:00.258215 30644 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:682951094614842 monotonicBaseRef:32000198175 monotonicFrequency:2299775064 realtimeReady:1 realtimeBaseCycles:682951094910575 realtimeBaseRef:1584345660258196569 realtimeFrequency:2299775281} D0316 08:01:01.258358 30644 parameters.go:238] Clock(Monotonic): error: -37 ns, adjusted frequency from 2299775064 Hz to 2299774741 Hz D0316 08:01:01.258460 30644 parameters.go:238] Clock(Realtime): error: -232 ns, adjusted frequency from 2299775281 Hz to 2299774296 Hz D0316 08:01:01.258473 30644 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:682953395035673 monotonicBaseRef:33000478970 monotonicFrequency:2299774741 realtimeReady:1 realtimeBaseCycles:682953395289635 realtimeBaseRef:1584345661258459107 realtimeFrequency:2299774296} D0316 08:01:02.258134 30644 parameters.go:238] Clock(Monotonic): error: 70 ns, adjusted frequency from 2299774741 Hz to 2299775011 Hz D0316 08:01:02.258239 30644 parameters.go:238] Clock(Realtime): error: 557 ns, adjusted frequency from 2299774296 Hz to 2299776125 Hz D0316 08:01:02.258257 30644 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:682955694237290 monotonicBaseRef:34000229761 monotonicFrequency:2299775011 realtimeReady:1 realtimeBaseCycles:682955694552686 realtimeBaseRef:1584345662258236804 realtimeFrequency:2299776125} D0316 08:01:03.106563 30644 urpc.go:577] urpc: unmarshal success. D0316 08:01:03.106626 30644 controller.go:504] containerManager.Signal &{CID:ci-gvisor-main-0 Signo:0 PID:0 Mode:Process} D0316 08:01:03.106699 30644 urpc.go:534] urpc: successfully marshalled 37 bytes. D0316 08:01:03.260603 30644 parameters.go:238] Clock(Monotonic): error: -306 ns, adjusted frequency from 2299775011 Hz to 2299774101 Hz D0316 08:01:03.260692 30644 parameters.go:238] Clock(Realtime): error: -484 ns, adjusted frequency from 2299776125 Hz to 2299773689 Hz D0316 08:01:03.260707 30644 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:682957999751359 monotonicBaseRef:35002725247 monotonicFrequency:2299774101 realtimeReady:1 realtimeBaseCycles:682957999969842 realtimeBaseRef:1584345663260689664 realtimeFrequency:2299773689} D0316 08:01:04.258162 30644 parameters.go:238] Clock(Monotonic): error: 37 ns, adjusted frequency from 2299774101 Hz to 2299775011 Hz D0316 08:01:04.258239 30644 parameters.go:238] Clock(Realtime): error: 9 ns, adjusted frequency from 2299773689 Hz to 2299774835 Hz D0316 08:01:04.258255 30644 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:682960293858858 monotonicBaseRef:36000261264 monotonicFrequency:2299775011 realtimeReady:1 realtimeBaseCycles:682960294104520 realtimeBaseRef:1584345664258237678 realtimeFrequency:2299774835} D0316 08:01:05.258056 30644 parameters.go:238] Clock(Monotonic): error: 189 ns, adjusted frequency from 2299775011 Hz to 2299775111 Hz D0316 08:01:05.258149 30644 parameters.go:238] Clock(Realtime): error: 132 ns, adjusted frequency from 2299774835 Hz to 2299775141 Hz D0316 08:01:05.258165 30644 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:682962593442435 monotonicBaseRef:37000178023 monotonicFrequency:2299775111 realtimeReady:1 realtimeBaseCycles:682962593670789 realtimeBaseRef:1584345665258146988 realtimeFrequency:2299775141} D0316 08:01:06.258086 30644 parameters.go:238] Clock(Monotonic): error: 433 ns, adjusted frequency from 2299775111 Hz to 2299775813 Hz D0316 08:01:06.258193 30644 parameters.go:238] Clock(Realtime): error: 53 ns, adjusted frequency from 2299775141 Hz to 2299774918 Hz D0316 08:01:06.258210 30644 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:682964893282741 monotonicBaseRef:38000206371 monotonicFrequency:2299775813 realtimeReady:1 realtimeBaseCycles:682964893546687 realtimeBaseRef:1584345666258190799 realtimeFrequency:2299774918} D0316 08:01:07.260632 30644 parameters.go:238] Clock(Monotonic): error: -361 ns, adjusted frequency from 2299775813 Hz to 2299774025 Hz D0316 08:01:07.260729 30644 parameters.go:238] Clock(Realtime): error: -181 ns, adjusted frequency from 2299774918 Hz to 2299774361 Hz D0316 08:01:07.260745 30644 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:682967198915448 monotonicBaseRef:39002753094 monotonicFrequency:2299774025 realtimeReady:1 realtimeBaseCycles:682967199155582 realtimeBaseRef:1584345667260727558 realtimeFrequency:2299774361} D0316 08:01:08.258181 30644 parameters.go:238] Clock(Monotonic): error: -37 ns, adjusted frequency from 2299774025 Hz to 2299774727 Hz D0316 08:01:08.258273 30644 parameters.go:238] Clock(Realtime): error: -35 ns, adjusted frequency from 2299774361 Hz to 2299774733 Hz D0316 08:01:08.258300 30644 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:682969493052826 monotonicBaseRef:40000302137 monotonicFrequency:2299774727 realtimeReady:1 realtimeBaseCycles:682969493280899 realtimeBaseRef:1584345668258271210 realtimeFrequency:2299774733} D0316 08:01:09.258004 30644 parameters.go:238] Clock(Monotonic): error: -79 ns, adjusted frequency from 2299774727 Hz to 2299774431 Hz D0316 08:01:09.258125 30644 parameters.go:238] Clock(Realtime): error: -59 ns, adjusted frequency from 2299774733 Hz to 2299774669 Hz D0316 08:01:09.258152 30644 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:682971792418206 monotonicBaseRef:41000124142 monotonicFrequency:2299774431 realtimeReady:1 realtimeBaseCycles:682971792714212 realtimeBaseRef:1584345669258122751 realtimeFrequency:2299774669} D0316 08:01:10.258072 30644 parameters.go:238] Clock(Monotonic): error: 509 ns, adjusted frequency from 2299774431 Hz to 2299775990 Hz D0316 08:01:10.258185 30644 parameters.go:238] Clock(Realtime): error: 396 ns, adjusted frequency from 2299774669 Hz to 2299775690 Hz D0316 08:01:10.258203 30644 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:682974092323508 monotonicBaseRef:42000181048 monotonicFrequency:2299775990 realtimeReady:1 realtimeBaseCycles:682974092625799 realtimeBaseRef:1584345670258182286 realtimeFrequency:2299775690} D0316 08:01:11.258080 30644 parameters.go:238] Clock(Monotonic): error: -412 ns, adjusted frequency from 2299775990 Hz to 2299773720 Hz D0316 08:01:11.258159 30644 parameters.go:238] Clock(Realtime): error: -159 ns, adjusted frequency from 2299775690 Hz to 2299774397 Hz D0316 08:01:11.258187 30644 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:682976392147301 monotonicBaseRef:43000201833 monotonicFrequency:2299773720 realtimeReady:1 realtimeBaseCycles:682976392337838 realtimeBaseRef:1584345671258154608 realtimeFrequency:2299774397} D0316 08:01:12.258048 30644 parameters.go:238] Clock(Monotonic): error: -150 ns, adjusted frequency from 2299773720 Hz to 2299774466 Hz D0316 08:01:12.258160 30644 parameters.go:238] Clock(Realtime): error: -115 ns, adjusted frequency from 2299774397 Hz to 2299774567 Hz D0316 08:01:12.258174 30644 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:682978691843954 monotonicBaseRef:44000168322 monotonicFrequency:2299774466 realtimeReady:1 realtimeBaseCycles:682978692120263 realtimeBaseRef:1584345672258158098 realtimeFrequency:2299774567} D0316 08:01:13.258077 30644 parameters.go:238] Clock(Monotonic): error: 160 ns, adjusted frequency from 2299774466 Hz to 2299775025 Hz D0316 08:01:13.258196 30644 parameters.go:238] Clock(Realtime): error: -2 ns, adjusted frequency from 2299774567 Hz to 2299774787 Hz D0316 08:01:13.258220 30644 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:682980991684826 monotonicBaseRef:45000197197 monotonicFrequency:2299775025 realtimeReady:1 realtimeBaseCycles:682980991978502 realtimeBaseRef:1584345673258194480 realtimeFrequency:2299774787} I0316 08:01:13.381130 30644 watchdog.go:277] Watchdog starting loop, tasks: 14, discount: 0s D0316 08:01:14.260677 30644 parameters.go:238] Clock(Monotonic): error: 120 ns, adjusted frequency from 2299775025 Hz to 2299775064 Hz D0316 08:01:14.260860 30644 parameters.go:238] Clock(Realtime): error: 39 ns, adjusted frequency from 2299774787 Hz to 2299774850 Hz D0316 08:01:14.260874 30644 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:682983297434232 monotonicBaseRef:46002795008 monotonicFrequency:2299775064 realtimeReady:1 realtimeBaseCycles:682983297873179 realtimeBaseRef:1584345674260855562 realtimeFrequency:2299774850} D0316 08:01:15.259807 30644 parameters.go:238] Clock(Monotonic): error: -139 ns, adjusted frequency from 2299775064 Hz to 2299774489 Hz D0316 08:01:15.259896 30644 parameters.go:238] Clock(Realtime): error: 144 ns, adjusted frequency from 2299774850 Hz to 2299775194 Hz D0316 08:01:15.259915 30644 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:682985595216153 monotonicBaseRef:47001928339 monotonicFrequency:2299774489 realtimeReady:1 realtimeBaseCycles:682985595437667 realtimeBaseRef:1584345675259894440 realtimeFrequency:2299775194} D0316 08:01:16.258152 30644 parameters.go:238] Clock(Monotonic): error: 80 ns, adjusted frequency from 2299774489 Hz to 2299775127 Hz D0316 08:01:16.258259 30644 parameters.go:238] Clock(Realtime): error: -3 ns, adjusted frequency from 2299775194 Hz to 2299774843 Hz D0316 08:01:16.258280 30644 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:682987891185595 monotonicBaseRef:48000273808 monotonicFrequency:2299775127 realtimeReady:1 realtimeBaseCycles:682987891447698 realtimeBaseRef:1584345676258257252 realtimeFrequency:2299774843} D0316 08:01:17.258184 30644 parameters.go:238] Clock(Monotonic): error: -75 ns, adjusted frequency from 2299775127 Hz to 2299774784 Hz D0316 08:01:17.258271 30644 parameters.go:238] Clock(Realtime): error: -2 ns, adjusted frequency from 2299774843 Hz to 2299774829 Hz D0316 08:01:17.258290 30644 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:682990191035268 monotonicBaseRef:49000306222 monotonicFrequency:2299774784 realtimeReady:1 realtimeBaseCycles:682990191248913 realtimeBaseRef:1584345677258268719 realtimeFrequency:2299774829} D0316 08:01:17.617746 30644 urpc.go:577] urpc: unmarshal success. D0316 08:01:17.617818 30644 controller.go:504] containerManager.Signal &{CID:ci-gvisor-main-0 Signo:0 PID:0 Mode:Process} D0316 08:01:17.618781 30644 urpc.go:534] urpc: successfully marshalled 37 bytes. D0316 08:01:18.258586 30644 parameters.go:238] Clock(Monotonic): error: 66 ns, adjusted frequency from 2299774784 Hz to 2299775067 Hz D0316 08:01:18.258694 30644 parameters.go:238] Clock(Realtime): error: -218 ns, adjusted frequency from 2299774829 Hz to 2299774354 Hz D0316 08:01:18.258729 30644 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:682992491731953 monotonicBaseRef:50000707087 monotonicFrequency:2299775067 realtimeReady:1 realtimeBaseCycles:682992491997696 realtimeBaseRef:1584345678258692218 realtimeFrequency:2299774354} D0316 08:01:19.258065 30644 parameters.go:238] Clock(Monotonic): error: -51 ns, adjusted frequency from 2299775067 Hz to 2299774561 Hz D0316 08:01:19.258157 30644 parameters.go:238] Clock(Realtime): error: -22 ns, adjusted frequency from 2299774354 Hz to 2299774812 Hz D0316 08:01:19.258175 30644 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:682994790308958 monotonicBaseRef:51000186139 monotonicFrequency:2299774561 realtimeReady:1 realtimeBaseCycles:682994790538411 realtimeBaseRef:1584345679258155800 realtimeFrequency:2299774812} D0316 08:01:20.258046 30644 parameters.go:238] Clock(Monotonic): error: 52 ns, adjusted frequency from 2299774561 Hz to 2299774904 Hz D0316 08:01:20.258164 30644 parameters.go:238] Clock(Realtime): error: 40 ns, adjusted frequency from 2299774812 Hz to 2299774927 Hz D0316 08:01:20.258183 30644 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:682997090042115 monotonicBaseRef:52000168135 monotonicFrequency:2299774904 realtimeReady:1 realtimeBaseCycles:682997090319643 realtimeBaseRef:1584345680258158591 realtimeFrequency:2299774927} D0316 08:01:21.258069 30644 parameters.go:238] Clock(Monotonic): error: 52 ns, adjusted frequency from 2299774904 Hz to 2299774793 Hz D0316 08:01:21.258157 30644 parameters.go:238] Clock(Realtime): error: -28 ns, adjusted frequency from 2299774927 Hz to 2299774751 Hz D0316 08:01:21.258172 30644 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:682999389868222 monotonicBaseRef:53000190399 monotonicFrequency:2299774793 realtimeReady:1 realtimeBaseCycles:682999390087531 realtimeBaseRef:1584345681258155530 realtimeFrequency:2299774751} D0316 08:01:22.258571 30644 parameters.go:238] Clock(Monotonic): error: -56 ns, adjusted frequency from 2299774793 Hz to 2299774762 Hz D0316 08:01:22.258660 30644 parameters.go:238] Clock(Realtime): error: -87 ns, adjusted frequency from 2299774751 Hz to 2299774618 Hz D0316 08:01:22.258681 30644 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:683001690800664 monotonicBaseRef:54000693773 monotonicFrequency:2299774762 realtimeReady:1 realtimeBaseCycles:683001691019880 realtimeBaseRef:1584345682258658882 realtimeFrequency:2299774618} D0316 08:01:23.258594 30644 parameters.go:238] Clock(Monotonic): error: -167 ns, adjusted frequency from 2299774762 Hz to 2299774588 Hz D0316 08:01:23.258695 30644 parameters.go:238] Clock(Realtime): error: 90 ns, adjusted frequency from 2299774618 Hz to 2299775065 Hz D0316 08:01:23.258719 30644 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:683003990611634 monotonicBaseRef:55000709517 monotonicFrequency:2299774588 realtimeReady:1 realtimeBaseCycles:683003990874254 realtimeBaseRef:1584345683258693561 realtimeFrequency:2299775065} D0316 08:01:24.258396 30644 parameters.go:238] Clock(Monotonic): error: 202 ns, adjusted frequency from 2299774588 Hz to 2299775296 Hz D0316 08:01:24.258482 30644 parameters.go:238] Clock(Realtime): error: -116 ns, adjusted frequency from 2299775065 Hz to 2299774540 Hz D0316 08:01:24.258497 30644 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:683006289939858 monotonicBaseRef:56000515426 monotonicFrequency:2299775296 realtimeReady:1 realtimeBaseCycles:683006290158905 realtimeBaseRef:1584345684258480316 realtimeFrequency:2299774540} D0316 08:01:25.258105 30644 parameters.go:238] Clock(Monotonic): error: -1 ns, adjusted frequency from 2299775296 Hz to 2299774679 Hz D0316 08:01:25.258192 30644 parameters.go:238] Clock(Realtime): error: 34 ns, adjusted frequency from 2299774540 Hz to 2299774848 Hz D0316 08:01:25.258206 30644 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:683008589050954 monotonicBaseRef:57000226615 monotonicFrequency:2299774679 realtimeReady:1 realtimeBaseCycles:683008589267208 realtimeBaseRef:1584345685258190619 realtimeFrequency:2299774848} D0316 08:01:26.258105 30644 parameters.go:238] Clock(Monotonic): error: -5 ns, adjusted frequency from 2299774679 Hz to 2299774811 Hz D0316 08:01:26.258189 30644 parameters.go:238] Clock(Realtime): error: -23 ns, adjusted frequency from 2299774848 Hz to 2299774743 Hz D0316 08:01:26.258203 30644 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:683010888787574 monotonicBaseRef:58000210065 monotonicFrequency:2299774811 realtimeReady:1 realtimeBaseCycles:683010889035876 realtimeBaseRef:1584345686258187931 realtimeFrequency:2299774743} D0316 08:01:27.258206 30644 parameters.go:238] Clock(Monotonic): error: -82 ns, adjusted frequency from 2299774811 Hz to 2299774718 Hz D0316 08:01:27.258330 30644 parameters.go:238] Clock(Realtime): error: 271 ns, adjusted frequency from 2299774743 Hz to 2299775443 Hz D0316 08:01:27.258362 30644 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:683013188831003 monotonicBaseRef:59000326866 monotonicFrequency:2299774718 realtimeReady:1 realtimeBaseCycles:683013189133845 realtimeBaseRef:1584345687258328477 realtimeFrequency:2299775443} D0316 08:01:28.258094 30644 parameters.go:238] Clock(Monotonic): error: 79 ns, adjusted frequency from 2299774718 Hz to 2299774973 Hz D0316 08:01:28.258193 30644 parameters.go:238] Clock(Realtime): error: -104 ns, adjusted frequency from 2299775443 Hz to 2299774570 Hz D0316 08:01:28.258209 30644 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:683015488350792 monotonicBaseRef:60000216016 monotonicFrequency:2299774973 realtimeReady:1 realtimeBaseCycles:683015488593184 realtimeBaseRef:1584345688258191027 realtimeFrequency:2299774570} D0316 08:01:29.258079 30644 parameters.go:238] Clock(Monotonic): error: -154 ns, adjusted frequency from 2299774973 Hz to 2299774456 Hz D0316 08:01:29.258169 30644 parameters.go:238] Clock(Realtime): error: -111 ns, adjusted frequency from 2299774570 Hz to 2299774556 Hz D0316 08:01:29.258186 30644 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:683017788049963 monotonicBaseRef:61000183055 monotonicFrequency:2299774456 realtimeReady:1 realtimeBaseCycles:683017788313959 realtimeBaseRef:1584345689258167635 realtimeFrequency:2299774556} D0316 08:01:30.258183 30644 parameters.go:238] Clock(Monotonic): error: -96 ns, adjusted frequency from 2299774456 Hz to 2299774736 Hz D0316 08:01:30.258277 30644 parameters.go:238] Clock(Realtime): error: 2 ns, adjusted frequency from 2299774556 Hz to 2299774788 Hz D0316 08:01:30.258292 30644 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:683020088101214 monotonicBaseRef:62000303412 monotonicFrequency:2299774736 realtimeReady:1 realtimeBaseCycles:683020088336549 realtimeBaseRef:1584345690258275486 realtimeFrequency:2299774788} D0316 08:01:31.258126 30644 parameters.go:238] Clock(Monotonic): error: 31 ns, adjusted frequency from 2299774736 Hz to 2299774958 Hz D0316 08:01:31.258230 30644 parameters.go:238] Clock(Realtime): error: 315 ns, adjusted frequency from 2299774788 Hz to 2299775577 Hz D0316 08:01:31.258252 30644 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:683022387744351 monotonicBaseRef:63000246189 monotonicFrequency:2299774958 realtimeReady:1 realtimeBaseCycles:683022388004087 realtimeBaseRef:1584345691258228850 realtimeFrequency:2299775577} D0316 08:01:32.258107 30644 parameters.go:238] Clock(Monotonic): error: 27 ns, adjusted frequency from 2299774958 Hz to 2299774882 Hz D0316 08:01:32.258196 30644 parameters.go:238] Clock(Realtime): error: -68 ns, adjusted frequency from 2299775577 Hz to 2299774710 Hz D0316 08:01:32.258215 30644 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:683024687438170 monotonicBaseRef:64000210907 monotonicFrequency:2299774882 realtimeReady:1 realtimeBaseCycles:683024687699735 realtimeBaseRef:1584345692258194094 realtimeFrequency:2299774710} D0316 08:01:33.258624 30644 parameters.go:238] Clock(Monotonic): error: -114 ns, adjusted frequency from 2299774882 Hz to 2299774528 Hz D0316 08:01:33.258732 30644 parameters.go:238] Clock(Realtime): error: -39 ns, adjusted frequency from 2299774710 Hz to 2299774711 Hz D0316 08:01:33.258761 30644 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:683026988430546 monotonicBaseRef:65000740304 monotonicFrequency:2299774528 realtimeReady:1 realtimeBaseCycles:683026988701402 realtimeBaseRef:1584345693258727605 realtimeFrequency:2299774711} D0316 08:01:34.258148 30644 parameters.go:238] Clock(Monotonic): error: 952 ns, adjusted frequency from 2299774528 Hz to 2299777194 Hz D0316 08:01:34.258301 30644 parameters.go:238] Clock(Realtime): error: 367 ns, adjusted frequency from 2299774711 Hz to 2299775698 Hz D0316 08:01:34.258321 30644 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:683029287028937 monotonicBaseRef:66000228889 monotonicFrequency:2299777194 realtimeReady:1 realtimeBaseCycles:683029287489602 realtimeBaseRef:1584345694258298645 realtimeFrequency:2299775698} D0316 08:01:35.258134 30644 parameters.go:238] Clock(Monotonic): error: -810 ns, adjusted frequency from 2299777194 Hz to 2299772866 Hz D0316 08:01:35.258279 30644 parameters.go:238] Clock(Realtime): error: -318 ns, adjusted frequency from 2299775698 Hz to 2299774112 Hz D0316 08:01:35.258302 30644 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:683031586829742 monotonicBaseRef:67000239155 monotonicFrequency:2299772866 realtimeReady:1 realtimeBaseCycles:683031587213940 realtimeBaseRef:1584345695258276312 realtimeFrequency:2299774112} D0316 08:01:36.259746 30644 parameters.go:238] Clock(Monotonic): error: 37 ns, adjusted frequency from 2299772866 Hz to 2299774751 Hz D0316 08:01:36.259850 30644 parameters.go:238] Clock(Realtime): error: -296 ns, adjusted frequency from 2299774112 Hz to 2299774116 Hz D0316 08:01:36.259866 30644 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:683033890348799 monotonicBaseRef:68001868094 monotonicFrequency:2299774751 realtimeReady:1 realtimeBaseCycles:683033890603679 realtimeBaseRef:1584345696259848478 realtimeFrequency:2299774116} D0316 08:01:37.258098 30644 parameters.go:238] Clock(Monotonic): error: 105 ns, adjusted frequency from 2299774751 Hz to 2299775087 Hz D0316 08:01:37.258190 30644 parameters.go:238] Clock(Realtime): error: 76 ns, adjusted frequency from 2299774116 Hz to 2299775004 Hz D0316 08:01:37.258204 30644 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:683036186328108 monotonicBaseRef:69000217740 monotonicFrequency:2299775087 realtimeReady:1 realtimeBaseCycles:683036186560431 realtimeBaseRef:1584345697258188591 realtimeFrequency:2299775004} D0316 08:01:38.259427 30644 parameters.go:238] Clock(Monotonic): error: -320 ns, adjusted frequency from 2299775087 Hz to 2299774216 Hz D0316 08:01:38.259518 30644 parameters.go:238] Clock(Realtime): error: -78 ns, adjusted frequency from 2299775004 Hz to 2299774603 Hz D0316 08:01:38.259534 30644 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:683038489161646 monotonicBaseRef:70001547631 monotonicFrequency:2299774216 realtimeReady:1 realtimeBaseCycles:683038489389807 realtimeBaseRef:1584345698259516708 realtimeFrequency:2299774603} D0316 08:01:39.258965 30644 parameters.go:238] Clock(Monotonic): error: 9 ns, adjusted frequency from 2299774216 Hz to 2299774840 Hz D0316 08:01:39.259085 30644 parameters.go:238] Clock(Realtime): error: 9 ns, adjusted frequency from 2299774603 Hz to 2299774841 Hz D0316 08:01:39.259116 30644 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:683040787870978 monotonicBaseRef:71001084592 monotonicFrequency:2299774840 realtimeReady:1 realtimeBaseCycles:683040788168349 realtimeBaseRef:1584345699259083595 realtimeFrequency:2299774841} D0316 08:01:40.258074 30644 parameters.go:238] Clock(Monotonic): error: 109 ns, adjusted frequency from 2299774840 Hz to 2299775095 Hz D0316 08:01:40.258174 30644 parameters.go:238] Clock(Realtime): error: 165 ns, adjusted frequency from 2299774841 Hz to 2299775190 Hz D0316 08:01:40.258210 30644 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:683043085599974 monotonicBaseRef:72000195007 monotonicFrequency:2299775095 realtimeReady:1 realtimeBaseCycles:683043085848794 realtimeBaseRef:1584345700258172898 realtimeFrequency:2299775190} D0316 08:01:41.257976 30644 parameters.go:238] Clock(Monotonic): error: 256 ns, adjusted frequency from 2299775095 Hz to 2299775336 Hz D0316 08:01:41.258087 30644 parameters.go:238] Clock(Realtime): error: 126 ns, adjusted frequency from 2299775190 Hz to 2299775073 Hz D0316 08:01:41.258105 30644 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:683045385147098 monotonicBaseRef:73000095879 monotonicFrequency:2299775336 realtimeReady:1 realtimeBaseCycles:683045385422664 realtimeBaseRef:1584345701258085359 realtimeFrequency:2299775073} D0316 08:01:42.258397 30644 parameters.go:238] Clock(Monotonic): error: -404 ns, adjusted frequency from 2299775336 Hz to 2299773878 Hz D0316 08:01:42.258490 30644 parameters.go:238] Clock(Realtime): error: -270 ns, adjusted frequency from 2299775073 Hz to 2299774200 Hz D0316 08:01:42.258505 30644 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:683047685893823 monotonicBaseRef:74000518263 monotonicFrequency:2299773878 realtimeReady:1 realtimeBaseCycles:683047686125199 realtimeBaseRef:1584345702258488642 realtimeFrequency:2299774200} D0316 08:01:43.258059 30644 parameters.go:238] Clock(Monotonic): error: 84 ns, adjusted frequency from 2299773878 Hz to 2299774953 Hz D0316 08:01:43.258179 30644 parameters.go:238] Clock(Realtime): error: 234 ns, adjusted frequency from 2299774200 Hz to 2299775319 Hz D0316 08:01:43.258198 30644 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:683049984886110 monotonicBaseRef:75000178407 monotonicFrequency:2299774953 realtimeReady:1 realtimeBaseCycles:683049985184290 realtimeBaseRef:1584345703258177694 realtimeFrequency:2299775319} D0316 08:01:44.258290 30644 parameters.go:238] Clock(Monotonic): error: -73 ns, adjusted frequency from 2299774953 Hz to 2299774721 Hz D0316 08:01:44.258375 30644 parameters.go:238] Clock(Realtime): error: -187 ns, adjusted frequency from 2299775319 Hz to 2299774373 Hz D0316 08:01:44.258391 30644 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:683052285185219 monotonicBaseRef:76000406323 monotonicFrequency:2299774721 realtimeReady:1 realtimeBaseCycles:683052285410015 realtimeBaseRef:1584345704258373541 realtimeFrequency:2299774373} D0316 08:01:45.258543 30644 parameters.go:238] Clock(Monotonic): error: -72 ns, adjusted frequency from 2299774721 Hz to 2299774777 Hz D0316 08:01:45.258686 30644 parameters.go:238] Clock(Realtime): error: 143 ns, adjusted frequency from 2299774373 Hz to 2299775122 Hz D0316 08:01:45.258743 30644 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:683054585552868 monotonicBaseRef:77000664143 monotonicFrequency:2299774777 realtimeReady:1 realtimeBaseCycles:683054585892080 realtimeBaseRef:1584345705258681263 realtimeFrequency:2299775122} D0316 08:01:46.258076 30644 parameters.go:238] Clock(Monotonic): error: 169 ns, adjusted frequency from 2299774777 Hz to 2299775311 Hz D0316 08:01:46.258161 30644 parameters.go:238] Clock(Realtime): error: 9 ns, adjusted frequency from 2299775122 Hz to 2299774813 Hz D0316 08:01:46.258180 30644 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:683056884252210 monotonicBaseRef:78000196516 monotonicFrequency:2299775311 realtimeReady:1 realtimeBaseCycles:683056884467381 realtimeBaseRef:1584345706258159550 realtimeFrequency:2299774813} D0316 08:01:47.258044 30644 parameters.go:238] Clock(Monotonic): error: -105 ns, adjusted frequency from 2299775311 Hz to 2299774441 Hz D0316 08:01:47.258125 30644 parameters.go:238] Clock(Realtime): error: -172 ns, adjusted frequency from 2299774813 Hz to 2299774427 Hz D0316 08:01:47.258140 30644 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:683059183955550 monotonicBaseRef:79000165221 monotonicFrequency:2299774441 realtimeReady:1 realtimeBaseCycles:683059184159699 realtimeBaseRef:1584345707258123679 realtimeFrequency:2299774427} D0316 08:01:48.258265 30644 parameters.go:238] Clock(Monotonic): error: 249 ns, adjusted frequency from 2299774441 Hz to 2299775229 Hz D0316 08:01:48.258359 30644 parameters.go:238] Clock(Realtime): error: 32 ns, adjusted frequency from 2299774427 Hz to 2299774914 Hz D0316 08:01:48.258402 30644 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:683061484238652 monotonicBaseRef:80000386399 monotonicFrequency:2299775229 realtimeReady:1 realtimeBaseCycles:683061484472335 realtimeBaseRef:1584345708258357705 realtimeFrequency:2299774914} D0316 08:01:49.258844 30644 parameters.go:238] Clock(Monotonic): error: 113 ns, adjusted frequency from 2299775229 Hz to 2299775094 Hz D0316 08:01:49.258952 30644 parameters.go:238] Clock(Realtime): error: 273 ns, adjusted frequency from 2299774914 Hz to 2299775453 Hz D0316 08:01:49.258970 30644 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:683063785339702 monotonicBaseRef:81000962899 monotonicFrequency:2299775094 realtimeReady:1 realtimeBaseCycles:683063785608483 realtimeBaseRef:1584345709258949603 realtimeFrequency:2299775453} D0316 08:01:50.258087 30644 parameters.go:238] Clock(Monotonic): error: -423 ns, adjusted frequency from 2299775094 Hz to 2299773895 Hz D0316 08:01:50.258173 30644 parameters.go:238] Clock(Realtime): error: -337 ns, adjusted frequency from 2299775453 Hz to 2299774041 Hz D0316 08:01:50.258188 30644 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:683066083380777 monotonicBaseRef:82000208903 monotonicFrequency:2299773895 realtimeReady:1 realtimeBaseCycles:683066083593756 realtimeBaseRef:1584345710258171187 realtimeFrequency:2299774041} D0316 08:01:51.258120 30644 parameters.go:238] Clock(Monotonic): error: 167 ns, adjusted frequency from 2299773895 Hz to 2299775191 Hz D0316 08:01:51.258224 30644 parameters.go:238] Clock(Realtime): error: 169 ns, adjusted frequency from 2299774041 Hz to 2299775223 Hz D0316 08:01:51.258246 30644 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:683068383226703 monotonicBaseRef:83000240223 monotonicFrequency:2299775191 realtimeReady:1 realtimeBaseCycles:683068383462465 realtimeBaseRef:1584345711258212351 realtimeFrequency:2299775223} D0316 08:01:52.258703 30644 parameters.go:238] Clock(Monotonic): error: -20 ns, adjusted frequency from 2299775191 Hz to 2299774754 Hz D0316 08:01:52.258807 30644 parameters.go:238] Clock(Realtime): error: -250 ns, adjusted frequency from 2299775223 Hz to 2299774228 Hz D0316 08:01:52.258820 30644 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:683070684343931 monotonicBaseRef:84000823774 monotonicFrequency:2299774754 realtimeReady:1 realtimeBaseCycles:683070684602417 realtimeBaseRef:1584345712258805769 realtimeFrequency:2299774228} I0316 08:01:52.381763 31638 main.go:305] *************************** I0316 08:01:52.381841 31638 main.go:306] Args: [/syzkaller/managers/main/current/image -root /syzkaller/managers/main/workdir/gvisor_root -watchdog-action=panic -network=none -debug -alsologtostderr -platform=ptrace -file-access=shared -network=none 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-main-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 /syzkaller874025636] I0316 08:01:52.381937 31638 main.go:307] Version release-20200219.0-178-g97127750289b I0316 08:01:52.381959 31638 main.go:308] PID: 31638 I0316 08:01:52.381988 31638 main.go:309] UID: 0, GID: 0 I0316 08:01:52.382016 31638 main.go:310] Configuration: I0316 08:01:52.382036 31638 main.go:311] RootDir: /syzkaller/managers/main/workdir/gvisor_root I0316 08:01:52.382068 31638 main.go:312] Platform: ptrace I0316 08:01:52.382088 31638 main.go:313] FileAccess: shared, overlay: false I0316 08:01:52.382132 31638 main.go:314] Network: none, logging: false I0316 08:01:52.382166 31638 main.go:315] Strace: false, max size: 1024, syscalls: [] I0316 08:01:52.382193 31638 main.go:316] *************************** D0316 08:01:52.382297 31638 container.go:159] Load container "/syzkaller/managers/main/workdir/gvisor_root" "ci-gvisor-main-0" D0316 08:01:52.383686 31638 container.go:592] Signal container "ci-gvisor-main-0": signal 0 D0316 08:01:52.383723 31638 sandbox.go:814] Signal sandbox "ci-gvisor-main-0" D0316 08:01:52.383735 31638 sandbox.go:318] Connecting to sandbox "ci-gvisor-main-0" D0316 08:01:52.383853 31638 urpc.go:534] urpc: successfully marshalled 96 bytes. D0316 08:01:52.384022 30644 urpc.go:577] urpc: unmarshal success. D0316 08:01:52.384101 30644 controller.go:504] containerManager.Signal &{CID:ci-gvisor-main-0 Signo:0 PID:0 Mode:Process} D0316 08:01:52.384165 30644 urpc.go:534] urpc: successfully marshalled 37 bytes. D0316 08:01:52.384295 31638 urpc.go:577] urpc: unmarshal success. D0316 08:01:52.384367 31638 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 /syzkaller874025636 D0316 08:01:52.384418 31638 exec.go:121] Exec capablities: &{PermittedCaps:274877898751 InheritableCaps:274877898751 EffectiveCaps:274877898751 BoundingCaps:274877898751 AmbientCaps:0} D0316 08:01:52.384446 31638 container.go:533] Execute in container "ci-gvisor-main-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 /syzkaller874025636 D0316 08:01:52.384490 31638 sandbox.go:283] Executing new process in container "ci-gvisor-main-0" in sandbox "ci-gvisor-main-0" D0316 08:01:52.384507 31638 sandbox.go:318] Connecting to sandbox "ci-gvisor-main-0" D0316 08:01:52.384764 31638 urpc.go:534] urpc: successfully marshalled 615 bytes. D0316 08:01:52.384864 30644 urpc.go:577] urpc: unmarshal success. D0316 08:01:52.385231 30644 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 /syzkaller874025636 D0316 08:01:52.385305 30644 transport_flipcall.go:127] send [channel @0xc0004d0300] Twalkgetattr{FID: 1, NewFID: 5, Names: [etc]} D0316 08:01:52.385876 30644 transport_flipcall.go:234] recv [channel @0xc0004d0300] Rlerror{Error: 2} D0316 08:01:52.385933 30644 transport_flipcall.go:127] send [channel @0xc0004d0300] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.etc, Size: 1} D0316 08:01:52.386126 30644 transport_flipcall.go:234] recv [channel @0xc0004d0300] Rlerror{Error: 95} I0316 08:01:52.387420 30644 kernel.go:807] 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 /syzkaller874025636] D0316 08:01:52.387568 30644 transport_flipcall.go:127] send [channel @0xc0004d0300] Twalkgetattr{FID: 1, NewFID: 5, Names: [syz-execprog]} D0316 08:01:52.387921 30644 transport_flipcall.go:234] recv [channel @0xc0004d0300] 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: 24174144, BlockSize: 4096, Blocks: 47216, ATime: {Sec: 1584145784, NanoSec: 773295336}, MTime: {Sec: 1584145784, NanoSec: 773295336}, CTime: {Sec: 1584345629, NanoSec: 776689665}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}, QIDs: [QID{Type: 0, Version: 0, Path: 16384761}]} D0316 08:01:52.388008 30644 transport_flipcall.go:127] send [channel @0xc0004d0300] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syz-execprog, Size: 1} D0316 08:01:52.388140 30644 transport_flipcall.go:234] recv [channel @0xc0004d0300] Rlerror{Error: 95} D0316 08:01:52.388239 30644 transport_flipcall.go:127] send [channel @0xc0004d0300] Tgetattr{FID: 5, AttrMask: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime INo Size Blocks BTime Gen DataVersion}} D0316 08:01:52.388448 30644 transport_flipcall.go:234] recv [channel @0xc0004d0300] Rgetattr{Valid: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime Size Blocks}, QID: QID{Type: 0, Version: 0, Path: 16384761}, Attr: Attr{Mode: 0o100755, UID: 0, GID: 0, NLink: 1, RDev: 0, Size: 24174144, BlockSize: 4096, Blocks: 47216, ATime: {Sec: 1584145784, NanoSec: 773295336}, MTime: {Sec: 1584145784, NanoSec: 773295336}, CTime: {Sec: 1584345629, NanoSec: 776689665}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}} D0316 08:01:52.388501 30644 transport_flipcall.go:127] send [channel @0xc0004d0300] Twalk{FID: 5, NewFID: 6, Names: []} D0316 08:01:52.388686 30644 transport_flipcall.go:234] recv [channel @0xc0004d0300] Rwalk{QIDs: []} D0316 08:01:52.388756 30644 transport_flipcall.go:127] send [channel @0xc0004d0300] Tlopen{FID: 6, Flags: ReadOnly} D0316 08:01:52.388967 30644 transport_flipcall.go:234] recv [channel @0xc0004d0300] Rlopen{QID: QID{Type: 0, Version: 0, Path: 16384761}, IoUnit: 0, File: &{{37}}} D0316 08:01:52.394924 30644 syscalls.go:266] Allocating stack with size of 8388608 bytes D0316 08:01:52.396164 30644 loader.go:832] updated processes: map[{ci-gvisor-main-0 0}:0xc00038e6c0 {ci-gvisor-main-0 20}:0xc0001b2e80] D0316 08:01:52.396279 30644 urpc.go:534] urpc: successfully marshalled 37 bytes. D0316 08:01:52.396378 31638 urpc.go:577] urpc: unmarshal success. D0316 08:01:52.396450 31638 container.go:580] Wait on PID 20 in container "ci-gvisor-main-0" D0316 08:01:52.396479 31638 sandbox.go:769] Waiting for PID 20 in sandbox "ci-gvisor-main-0" D0316 08:01:52.396494 31638 sandbox.go:318] Connecting to sandbox "ci-gvisor-main-0" D0316 08:01:52.396713 31638 urpc.go:534] urpc: successfully marshalled 79 bytes. D0316 08:01:52.396819 30644 urpc.go:577] urpc: unmarshal success. D0316 08:01:52.396911 30644 controller.go:446] containerManager.Wait D0316 08:01:52.400095 30644 task_block.go:223] [ 20] Interrupt queued D0316 08:01:52.631946 30644 task_block.go:223] [ 21] Interrupt queued D0316 08:01:52.634277 30644 task_block.go:223] [ 22] Interrupt queued D0316 08:01:52.635716 30644 task_block.go:223] [ 23] Interrupt queued D0316 08:01:52.700095 30644 urpc.go:577] urpc: unmarshal success. D0316 08:01:52.700161 30644 controller.go:504] containerManager.Signal &{CID:ci-gvisor-main-0 Signo:0 PID:0 Mode:Process} D0316 08:01:52.700237 30644 urpc.go:534] urpc: successfully marshalled 37 bytes. D0316 08:01:52.708423 30644 task_block.go:223] [ 24] Interrupt queued D0316 08:01:52.709641 30644 task_block.go:223] [ 25] Interrupt queued D0316 08:01:52.711674 30644 task_block.go:223] [ 26] Interrupt queued D0316 08:01:52.711799 30644 task_block.go:223] [ 27] Interrupt queued D0316 08:01:52.747293 30644 transport_flipcall.go:127] send [channel @0xc0004d0300] Twalkgetattr{FID: 1, NewFID: 7, Names: [etc]} D0316 08:01:52.747653 30644 transport_flipcall.go:234] recv [channel @0xc0004d0300] Rlerror{Error: 2} D0316 08:01:52.747827 30644 transport_flipcall.go:127] send [channel @0xc0004d0300] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.etc, Size: 1} D0316 08:01:52.748036 30644 transport_flipcall.go:234] recv [channel @0xc0004d0300] Rlerror{Error: 95} D0316 08:01:52.790228 30644 task_block.go:223] [ 28] Interrupt queued D0316 08:01:52.806895 30644 task_block.go:223] [ 29] Interrupt queued D0316 08:01:52.807208 30644 task_block.go:223] [ 30] Interrupt queued D0316 08:01:52.813909 30644 task_block.go:223] [ 31] Interrupt queued D0316 08:01:52.846819 30644 task_block.go:223] [ 32] Interrupt queued D0316 08:01:52.851046 30644 task_block.go:223] [ 33] Interrupt queued D0316 08:01:52.885974 30644 task_block.go:223] [ 34] Interrupt queued D0316 08:01:52.893507 30644 task_block.go:223] [ 35] Interrupt queued D0316 08:01:52.895553 30644 transport_flipcall.go:127] send [channel @0xc0004d0300] Twalkgetattr{FID: 1, NewFID: 7, Names: [syzkaller874025636]} D0316 08:01:52.895961 30644 transport_flipcall.go:234] recv [channel @0xc0004d0300] 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: 387, BlockSize: 4096, Blocks: 8, ATime: {Sec: 1584345712, NanoSec: 364775619}, MTime: {Sec: 1584345712, NanoSec: 364775619}, CTime: {Sec: 1584345712, NanoSec: 364775619}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}, QIDs: [QID{Type: 0, Version: 0, Path: 16384039}]} D0316 08:01:52.896048 30644 transport_flipcall.go:127] send [channel @0xc0004d0300] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzkaller874025636, Size: 1} D0316 08:01:52.896241 30644 transport_flipcall.go:234] recv [channel @0xc0004d0300] Rlerror{Error: 95} D0316 08:01:52.896346 30644 transport_flipcall.go:127] send [channel @0xc0004d0300] Tgetattr{FID: 7, AttrMask: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime INo Size Blocks BTime Gen DataVersion}} D0316 08:01:52.896551 30644 transport_flipcall.go:234] recv [channel @0xc0004d0300] Rgetattr{Valid: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime Size Blocks}, QID: QID{Type: 0, Version: 0, Path: 16384039}, Attr: Attr{Mode: 0o100600, UID: 0, GID: 0, NLink: 1, RDev: 0, Size: 387, BlockSize: 4096, Blocks: 8, ATime: {Sec: 1584345712, NanoSec: 364775619}, MTime: {Sec: 1584345712, NanoSec: 364775619}, CTime: {Sec: 1584345712, NanoSec: 364775619}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}} D0316 08:01:52.896624 30644 transport_flipcall.go:127] send [channel @0xc0004d0300] Twalk{FID: 7, NewFID: 8, Names: []} D0316 08:01:52.896855 30644 transport_flipcall.go:234] recv [channel @0xc0004d0300] Rwalk{QIDs: []} D0316 08:01:52.896890 30644 transport_flipcall.go:127] send [channel @0xc0004d0300] Tlopen{FID: 8, Flags: ReadOnly} D0316 08:01:52.897176 30644 transport_flipcall.go:234] recv [channel @0xc0004d0300] Rlopen{QID: QID{Type: 0, Version: 0, Path: 16384039}, IoUnit: 0, File: &{{31}}} D0316 08:01:52.898887 30644 transport_flipcall.go:127] send [channel @0xc0004d0300] Tgetattr{FID: 8, AttrMask: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime INo Size Blocks BTime Gen DataVersion}} D0316 08:01:52.899120 30644 transport_flipcall.go:234] recv [channel @0xc0004d0300] Rgetattr{Valid: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime Size Blocks}, QID: QID{Type: 0, Version: 0, Path: 16384039}, Attr: Attr{Mode: 0o100600, UID: 0, GID: 0, NLink: 1, RDev: 0, Size: 387, BlockSize: 4096, Blocks: 8, ATime: {Sec: 1584345712, NanoSec: 364775619}, MTime: {Sec: 1584345712, NanoSec: 364775619}, CTime: {Sec: 1584345712, NanoSec: 364775619}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}} 2020/03/16 08:01:52 parsed 1 programs D0316 08:01:52.912100 30644 task_stop.go:118] [ 20] Entering internal stop (*kernel.vforkStop)(nil) D0316 08:01:52.912163 30644 task_block.go:223] [ 36] Interrupt queued D0316 08:01:52.917598 30644 transport_flipcall.go:127] send [channel @0xc0004d0300] Twalkgetattr{FID: 1, NewFID: 9, Names: [syz-executor]} D0316 08:01:52.918101 30644 transport_flipcall.go:234] recv [channel @0xc0004d0300] 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: 1579696, BlockSize: 4096, Blocks: 3088, ATime: {Sec: 1584145786, NanoSec: 513465808}, MTime: {Sec: 1584145786, NanoSec: 513465808}, CTime: {Sec: 1584345629, NanoSec: 784690449}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}, QIDs: [QID{Type: 0, Version: 0, Path: 16384762}]} D0316 08:01:52.918210 30644 transport_flipcall.go:127] send [channel @0xc0004d0300] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syz-executor, Size: 1} D0316 08:01:52.918452 30644 transport_flipcall.go:234] recv [channel @0xc0004d0300] Rlerror{Error: 95} D0316 08:01:52.918553 30644 transport_flipcall.go:127] send [channel @0xc0004d0300] Tgetattr{FID: 9, AttrMask: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime INo Size Blocks BTime Gen DataVersion}} D0316 08:01:52.918864 30644 transport_flipcall.go:234] recv [channel @0xc0004d0300] Rgetattr{Valid: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime Size Blocks}, QID: QID{Type: 0, Version: 0, Path: 16384762}, Attr: Attr{Mode: 0o100755, UID: 0, GID: 0, NLink: 1, RDev: 0, Size: 1579696, BlockSize: 4096, Blocks: 3088, ATime: {Sec: 1584145786, NanoSec: 513465808}, MTime: {Sec: 1584145786, NanoSec: 513465808}, CTime: {Sec: 1584345629, NanoSec: 784690449}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}} D0316 08:01:52.918911 30644 transport_flipcall.go:127] send [channel @0xc0004d0300] Twalk{FID: 9, NewFID: 10, Names: []} D0316 08:01:52.919110 30644 transport_flipcall.go:234] recv [channel @0xc0004d0300] Rwalk{QIDs: []} D0316 08:01:52.919151 30644 transport_flipcall.go:127] send [channel @0xc0004d0300] Tlopen{FID: 10, Flags: ReadOnly} D0316 08:01:52.919322 30644 transport_flipcall.go:234] recv [channel @0xc0004d0300] Rlopen{QID: QID{Type: 0, Version: 0, Path: 16384762}, IoUnit: 0, File: &{{32}}} D0316 08:01:52.919777 30644 syscalls.go:266] [ 36] Allocating stack with size of 8388608 bytes D0316 08:01:52.919900 30644 task_stop.go:137] [ 20] Leaving internal stop (*kernel.vforkStop)(nil) D0316 08:01:52.950686 30644 transport_flipcall.go:127] send [channel @0xc0004d0300] Twalkgetattr{FID: 1, NewFID: 11, Names: [etc]} D0316 08:01:52.950973 30644 transport_flipcall.go:234] recv [channel @0xc0004d0300] Rlerror{Error: 2} D0316 08:01:52.951029 30644 transport_flipcall.go:127] send [channel @0xc0004d0300] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.etc, Size: 1} D0316 08:01:52.951201 30644 transport_flipcall.go:234] recv [channel @0xc0004d0300] Rlerror{Error: 95} D0316 08:01:52.959299 30644 task_exit.go:221] [ 36] Transitioning from exit state TaskExitNone to TaskExitInitiated D0316 08:01:52.959663 30644 task_exit.go:221] [ 36] Transitioning from exit state TaskExitInitiated to TaskExitZombie D0316 08:01:52.959747 30644 task_signals.go:446] [ 20] Notified of signal 17 D0316 08:01:52.959781 30644 task_block.go:223] [ 20] Interrupt queued D0316 08:01:52.959808 30644 task_block.go:223] [ 20] Interrupt queued D0316 08:01:52.959828 30644 task_signals.go:179] [ 20] Restarting syscall 247 after errno 512: interrupted by signal 17 D0316 08:01:52.959868 30644 task_signals.go:220] [ 20] Signal 17: delivering to handler D0316 08:01:52.960355 30644 task_exit.go:221] [ 36] Transitioning from exit state TaskExitZombie to TaskExitDead D0316 08:01:52.960903 30644 task_block.go:223] [ 37] Interrupt queued D0316 08:01:52.963919 30644 transport_flipcall.go:127] send [channel @0xc0004d0300] Twalkgetattr{FID: 1, NewFID: 11, Names: [syz-executor]} D0316 08:01:52.964323 30644 transport_flipcall.go:234] recv [channel @0xc0004d0300] 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: 1579696, BlockSize: 4096, Blocks: 3088, ATime: {Sec: 1584145786, NanoSec: 513465808}, MTime: {Sec: 1584145786, NanoSec: 513465808}, CTime: {Sec: 1584345629, NanoSec: 784690449}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}, QIDs: [QID{Type: 0, Version: 0, Path: 16384762}]} D0316 08:01:52.964373 30644 transport_flipcall.go:127] send [channel @0xc0004d0300] Tclunk{FID: 11} D0316 08:01:52.964555 30644 transport_flipcall.go:234] recv [channel @0xc0004d0300] Rclunk{} D0316 08:01:52.964620 30644 transport_flipcall.go:127] send [channel @0xc0004d0300] Tgetattr{FID: 9, AttrMask: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime INo Size Blocks BTime Gen DataVersion}} D0316 08:01:52.964934 30644 transport_flipcall.go:234] recv [channel @0xc0004d0300] Rgetattr{Valid: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime Size Blocks}, QID: QID{Type: 0, Version: 0, Path: 16384762}, Attr: Attr{Mode: 0o100755, UID: 0, GID: 0, NLink: 1, RDev: 0, Size: 1579696, BlockSize: 4096, Blocks: 3088, ATime: {Sec: 1584145786, NanoSec: 513465808}, MTime: {Sec: 1584145786, NanoSec: 513465808}, CTime: {Sec: 1584345629, NanoSec: 784690449}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}} 2020/03/16 08:01:52 executed programs: 0 D0316 08:01:52.967791 30644 task_stop.go:118] [ 25] Entering internal stop (*kernel.vforkStop)(nil) D0316 08:01:52.968925 30644 task_block.go:223] [ 38] Interrupt queued D0316 08:01:52.973383 30644 transport_flipcall.go:127] send [channel @0xc0004d0300] Twalkgetattr{FID: 1, NewFID: 11, Names: [syz-executor]} D0316 08:01:52.973705 30644 transport_flipcall.go:234] recv [channel @0xc0004d0300] 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: 1579696, BlockSize: 4096, Blocks: 3088, ATime: {Sec: 1584145786, NanoSec: 513465808}, MTime: {Sec: 1584145786, NanoSec: 513465808}, CTime: {Sec: 1584345629, NanoSec: 784690449}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}, QIDs: [QID{Type: 0, Version: 0, Path: 16384762}]} D0316 08:01:52.973735 30644 transport_flipcall.go:127] send [channel @0xc0004d0300] Tclunk{FID: 11} D0316 08:01:52.973907 30644 transport_flipcall.go:234] recv [channel @0xc0004d0300] Rclunk{} D0316 08:01:52.974036 30644 transport_flipcall.go:127] send [channel @0xc0004d0300] Tgetattr{FID: 9, AttrMask: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime INo Size Blocks BTime Gen DataVersion}} D0316 08:01:52.974367 30644 transport_flipcall.go:234] recv [channel @0xc0004d0300] Rgetattr{Valid: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime Size Blocks}, QID: QID{Type: 0, Version: 0, Path: 16384762}, Attr: Attr{Mode: 0o100755, UID: 0, GID: 0, NLink: 1, RDev: 0, Size: 1579696, BlockSize: 4096, Blocks: 3088, ATime: {Sec: 1584145786, NanoSec: 513465808}, MTime: {Sec: 1584145786, NanoSec: 513465808}, CTime: {Sec: 1584345629, NanoSec: 784690449}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}} D0316 08:01:52.974862 30644 syscalls.go:266] [ 38] Allocating stack with size of 8388608 bytes D0316 08:01:52.975012 30644 task_stop.go:137] [ 25] Leaving internal stop (*kernel.vforkStop)(nil) D0316 08:01:52.981190 30644 transport_flipcall.go:127] send [channel @0xc0004d0300] Twalkgetattr{FID: 1, NewFID: 11, Names: [etc]} D0316 08:01:52.981410 30644 transport_flipcall.go:234] recv [channel @0xc0004d0300] Rlerror{Error: 2} D0316 08:01:52.981479 30644 transport_flipcall.go:127] send [channel @0xc0004d0300] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.etc, Size: 1} D0316 08:01:52.981649 30644 transport_flipcall.go:234] recv [channel @0xc0004d0300] Rlerror{Error: 95} D0316 08:01:53.010294 30644 task_block.go:223] [ 39] Interrupt queued D0316 08:01:53.029284 30644 transport_flipcall.go:127] send [channel @0xc0004d0300] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0316 08:01:53.029554 30644 transport_flipcall.go:234] recv [channel @0xc0004d0300] Rlerror{Error: 2} D0316 08:01:53.029616 30644 transport_flipcall.go:127] send [channel @0xc0004d0300] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0316 08:01:53.029752 30644 transport_flipcall.go:234] recv [channel @0xc0004d0300] Rlerror{Error: 95} D0316 08:01:53.029942 30644 transport_flipcall.go:127] send [channel @0xc0004d0300] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0316 08:01:53.030157 30644 transport_flipcall.go:234] recv [channel @0xc0004d0300] Rlerror{Error: 2} D0316 08:01:53.030212 30644 transport_flipcall.go:127] send [channel @0xc0004d0300] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0316 08:01:53.030336 30644 transport_flipcall.go:234] recv [channel @0xc0004d0300] Rlerror{Error: 95} D0316 08:01:53.030438 30644 transport_flipcall.go:127] send [channel @0xc0004d0300] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0316 08:01:53.030590 30644 transport_flipcall.go:234] recv [channel @0xc0004d0300] Rlerror{Error: 2} D0316 08:01:53.030654 30644 transport_flipcall.go:127] send [channel @0xc0004d0300] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0316 08:01:53.030803 30644 transport_flipcall.go:234] recv [channel @0xc0004d0300] Rlerror{Error: 95} D0316 08:01:53.030966 30644 transport_flipcall.go:127] send [channel @0xc0004d0300] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0316 08:01:53.031103 30644 transport_flipcall.go:234] recv [channel @0xc0004d0300] Rlerror{Error: 2} D0316 08:01:53.031123 30644 transport_flipcall.go:127] send [channel @0xc0004d0300] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0316 08:01:53.031234 30644 transport_flipcall.go:234] recv [channel @0xc0004d0300] Rlerror{Error: 95} D0316 08:01:53.031292 30644 transport_flipcall.go:127] send [channel @0xc0004d0300] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0316 08:01:53.031742 30644 transport_flipcall.go:234] recv [channel @0xc0004d0300] Rlerror{Error: 2} D0316 08:01:53.031776 30644 transport_flipcall.go:127] send [channel @0xc0004d0300] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0316 08:01:53.031910 30644 transport_flipcall.go:234] recv [channel @0xc0004d0300] Rlerror{Error: 95} D0316 08:01:53.032101 30644 transport_flipcall.go:127] send [channel @0xc0004d0300] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0316 08:01:53.032267 30644 transport_flipcall.go:234] recv [channel @0xc0004d0300] Rlerror{Error: 2} D0316 08:01:53.032332 30644 transport_flipcall.go:127] send [channel @0xc0004d0300] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0316 08:01:53.032496 30644 transport_flipcall.go:234] recv [channel @0xc0004d0300] Rlerror{Error: 95} D0316 08:01:53.032615 30644 transport_flipcall.go:127] send [channel @0xc0004d0300] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0316 08:01:53.032741 30644 transport_flipcall.go:234] recv [channel @0xc0004d0300] Rlerror{Error: 2} D0316 08:01:53.032779 30644 transport_flipcall.go:127] send [channel @0xc0004d0300] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0316 08:01:53.032936 30644 transport_flipcall.go:234] recv [channel @0xc0004d0300] Rlerror{Error: 95} D0316 08:01:53.033056 30644 transport_flipcall.go:127] send [channel @0xc0004d0300] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0316 08:01:53.033191 30644 transport_flipcall.go:234] recv [channel @0xc0004d0300] Rlerror{Error: 2} D0316 08:01:53.033216 30644 transport_flipcall.go:127] send [channel @0xc0004d0300] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0316 08:01:53.033408 30644 transport_flipcall.go:234] recv [channel @0xc0004d0300] Rlerror{Error: 95} D0316 08:01:53.033561 30644 transport_flipcall.go:127] send [channel @0xc0004d0300] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0316 08:01:53.033750 30644 transport_flipcall.go:234] recv [channel @0xc0004d0300] Rlerror{Error: 2} D0316 08:01:53.033796 30644 transport_flipcall.go:127] send [channel @0xc0004d0300] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0316 08:01:53.033921 30644 transport_flipcall.go:234] recv [channel @0xc0004d0300] Rlerror{Error: 95} D0316 08:01:53.039249 30644 netfilter.go:60] netfilter: convert to binary: current offset: 0 D0316 08:01:53.039293 30644 netfilter.go:60] netfilter: convert to binary: found hook 0 at offset 0 D0316 08:01:53.039311 30644 netfilter.go:60] netfilter: convert to binary: found underflow 0 at offset 0 D0316 08:01:53.039328 30644 netfilter.go:60] netfilter: convert to binary: marshalling standard target D0316 08:01:53.039347 30644 netfilter.go:60] 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]} D0316 08:01:53.039484 30644 netfilter.go:60] netfilter: convert to binary: current offset: 152 D0316 08:01:53.039502 30644 netfilter.go:60] netfilter: convert to binary: found hook 1 at offset 152 D0316 08:01:53.039523 30644 netfilter.go:60] netfilter: convert to binary: found underflow 1 at offset 152 D0316 08:01:53.039538 30644 netfilter.go:60] netfilter: convert to binary: marshalling standard target D0316 08:01:53.039554 30644 netfilter.go:60] 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]} D0316 08:01:53.039630 30644 netfilter.go:60] netfilter: convert to binary: current offset: 304 D0316 08:01:53.039646 30644 netfilter.go:60] netfilter: convert to binary: found hook 3 at offset 304 D0316 08:01:53.039660 30644 netfilter.go:60] netfilter: convert to binary: found underflow 3 at offset 304 D0316 08:01:53.039676 30644 netfilter.go:60] netfilter: convert to binary: marshalling standard target D0316 08:01:53.039692 30644 netfilter.go:60] 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]} D0316 08:01:53.039772 30644 netfilter.go:60] netfilter: convert to binary: current offset: 456 D0316 08:01:53.039788 30644 netfilter.go:60] netfilter: convert to binary: found hook 4 at offset 456 D0316 08:01:53.039802 30644 netfilter.go:60] netfilter: convert to binary: found underflow 4 at offset 456 D0316 08:01:53.039817 30644 netfilter.go:60] netfilter: convert to binary: marshalling standard target D0316 08:01:53.039833 30644 netfilter.go:60] 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]} D0316 08:01:53.039919 30644 netfilter.go:60] netfilter: convert to binary: current offset: 608 D0316 08:01:53.039938 30644 netfilter.go:60] 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]} D0316 08:01:53.040032 30644 netfilter.go:60] netfilter: convert to binary: finished with an marshalled size of 0 D0316 08:01:53.040047 30644 netfilter.go:60] netfilter: convert to binary: current offset: 0 D0316 08:01:53.040072 30644 netfilter.go:60] netfilter: convert to binary: found hook 0 at offset 0 D0316 08:01:53.040112 30644 netfilter.go:60] netfilter: convert to binary: found underflow 0 at offset 0 D0316 08:01:53.040125 30644 netfilter.go:60] netfilter: convert to binary: marshalling standard target D0316 08:01:53.040142 30644 netfilter.go:60] 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]} D0316 08:01:53.040222 30644 netfilter.go:60] netfilter: convert to binary: current offset: 152 D0316 08:01:53.040234 30644 netfilter.go:60] netfilter: convert to binary: found hook 3 at offset 152 D0316 08:01:53.040246 30644 netfilter.go:60] netfilter: convert to binary: found underflow 3 at offset 152 D0316 08:01:53.040258 30644 netfilter.go:60] netfilter: convert to binary: marshalling standard target D0316 08:01:53.040271 30644 netfilter.go:60] 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]} D0316 08:01:53.040334 30644 netfilter.go:60] netfilter: convert to binary: current offset: 304 D0316 08:01:53.040349 30644 netfilter.go:60] 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]} D0316 08:01:53.040421 30644 netfilter.go:60] netfilter: convert to binary: finished with an marshalled size of 0 D0316 08:01:53.040436 30644 netfilter.go:60] netfilter: convert to binary: current offset: 0 D0316 08:01:53.040453 30644 netfilter.go:60] netfilter: convert to binary: found hook 1 at offset 0 D0316 08:01:53.040467 30644 netfilter.go:60] netfilter: convert to binary: found underflow 1 at offset 0 D0316 08:01:53.040491 30644 netfilter.go:60] netfilter: convert to binary: marshalling standard target D0316 08:01:53.040506 30644 netfilter.go:60] 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]} D0316 08:01:53.040599 30644 netfilter.go:60] netfilter: convert to binary: current offset: 152 D0316 08:01:53.040613 30644 netfilter.go:60] netfilter: convert to binary: found hook 2 at offset 152 D0316 08:01:53.040626 30644 netfilter.go:60] netfilter: convert to binary: found underflow 2 at offset 152 D0316 08:01:53.040637 30644 netfilter.go:60] netfilter: convert to binary: marshalling standard target D0316 08:01:53.041214 30644 netfilter.go:60] 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]} D0316 08:01:53.041351 30644 netfilter.go:60] netfilter: convert to binary: current offset: 304 D0316 08:01:53.041376 30644 netfilter.go:60] netfilter: convert to binary: found hook 3 at offset 304 D0316 08:01:53.041392 30644 netfilter.go:60] netfilter: convert to binary: found underflow 3 at offset 304 D0316 08:01:53.041434 30644 netfilter.go:60] netfilter: convert to binary: marshalling standard target D0316 08:01:53.041468 30644 netfilter.go:60] 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]} D0316 08:01:53.041550 30644 netfilter.go:60] netfilter: convert to binary: current offset: 456 D0316 08:01:53.041577 30644 netfilter.go:60] 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]} D0316 08:01:53.041671 30644 netfilter.go:60] netfilter: convert to binary: finished with an marshalled size of 0 D0316 08:01:53.133300 30644 transport_flipcall.go:127] send [channel @0xc0004d0300] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0316 08:01:53.133575 30644 transport_flipcall.go:234] recv [channel @0xc0004d0300] Rlerror{Error: 2} D0316 08:01:53.133608 30644 transport_flipcall.go:127] send [channel @0xc0004d0300] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0316 08:01:53.133726 30644 transport_flipcall.go:234] recv [channel @0xc0004d0300] Rlerror{Error: 95} D0316 08:01:53.133857 30644 transport_flipcall.go:127] send [channel @0xc0004d0300] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0316 08:01:53.134011 30644 transport_flipcall.go:234] recv [channel @0xc0004d0300] Rlerror{Error: 2} D0316 08:01:53.134039 30644 transport_flipcall.go:127] send [channel @0xc0004d0300] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0316 08:01:53.134121 30644 transport_flipcall.go:234] recv [channel @0xc0004d0300] Rlerror{Error: 95} D0316 08:01:53.134305 30644 transport_flipcall.go:127] send [channel @0xc0004d0300] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0316 08:01:53.134454 30644 transport_flipcall.go:234] recv [channel @0xc0004d0300] Rlerror{Error: 2} D0316 08:01:53.134490 30644 transport_flipcall.go:127] send [channel @0xc0004d0300] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0316 08:01:53.134603 30644 transport_flipcall.go:234] recv [channel @0xc0004d0300] Rlerror{Error: 95} D0316 08:01:53.134727 30644 transport_flipcall.go:127] send [channel @0xc0004d0300] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0316 08:01:53.134872 30644 transport_flipcall.go:234] recv [channel @0xc0004d0300] Rlerror{Error: 2} D0316 08:01:53.134895 30644 transport_flipcall.go:127] send [channel @0xc0004d0300] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0316 08:01:53.134982 30644 transport_flipcall.go:234] recv [channel @0xc0004d0300] Rlerror{Error: 95} D0316 08:01:53.135134 30644 transport_flipcall.go:127] send [channel @0xc0004d0300] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0316 08:01:53.135347 30644 transport_flipcall.go:234] recv [channel @0xc0004d0300] Rlerror{Error: 2} D0316 08:01:53.135395 30644 transport_flipcall.go:127] send [channel @0xc0004d0300] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0316 08:01:53.135534 30644 transport_flipcall.go:234] recv [channel @0xc0004d0300] Rlerror{Error: 95} D0316 08:01:53.135661 30644 transport_flipcall.go:127] send [channel @0xc0004d0300] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0316 08:01:53.135849 30644 transport_flipcall.go:234] recv [channel @0xc0004d0300] Rlerror{Error: 2} D0316 08:01:53.135885 30644 transport_flipcall.go:127] send [channel @0xc0004d0300] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0316 08:01:53.136000 30644 transport_flipcall.go:234] recv [channel @0xc0004d0300] Rlerror{Error: 95} D0316 08:01:53.136168 30644 transport_flipcall.go:127] send [channel @0xc0004d0300] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0316 08:01:53.136303 30644 transport_flipcall.go:234] recv [channel @0xc0004d0300] Rlerror{Error: 2} D0316 08:01:53.136354 30644 transport_flipcall.go:127] send [channel @0xc0004d0300] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0316 08:01:53.136599 30644 transport_flipcall.go:234] recv [channel @0xc0004d0300] Rlerror{Error: 95} D0316 08:01:53.136754 30644 transport_flipcall.go:127] send [channel @0xc0004d0300] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0316 08:01:53.136906 30644 transport_flipcall.go:234] recv [channel @0xc0004d0300] Rlerror{Error: 2} D0316 08:01:53.136939 30644 transport_flipcall.go:127] send [channel @0xc0004d0300] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0316 08:01:53.137075 30644 transport_flipcall.go:234] recv [channel @0xc0004d0300] Rlerror{Error: 95} D0316 08:01:53.137251 30644 transport_flipcall.go:127] send [channel @0xc0004d0300] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0316 08:01:53.137386 30644 transport_flipcall.go:234] recv [channel @0xc0004d0300] Rlerror{Error: 2} D0316 08:01:53.137426 30644 transport_flipcall.go:127] send [channel @0xc0004d0300] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0316 08:01:53.137542 30644 transport_flipcall.go:234] recv [channel @0xc0004d0300] Rlerror{Error: 95} D0316 08:01:53.137707 30644 transport_flipcall.go:127] send [channel @0xc0004d0300] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0316 08:01:53.137952 30644 transport_flipcall.go:234] recv [channel @0xc0004d0300] Rlerror{Error: 2} D0316 08:01:53.137992 30644 transport_flipcall.go:127] send [channel @0xc0004d0300] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0316 08:01:53.138116 30644 transport_flipcall.go:234] recv [channel @0xc0004d0300] Rlerror{Error: 95} D0316 08:01:53.146790 30644 task_block.go:223] [ 40] Interrupt queued panic: Start 0x55ede31cffd6 + offset 0x55ede31d0000 overflows? DIAGNOSIS: I0316 08:01:53.175427 31717 main.go:305] *************************** I0316 08:01:53.175475 31717 main.go:306] Args: [/syzkaller/managers/main/current/image -root /syzkaller/managers/main/workdir/gvisor_root -watchdog-action=panic -network=none -debug -alsologtostderr -platform=ptrace -file-access=shared -network=none debug -stacks --ps ci-gvisor-main-0] I0316 08:01:53.175511 31717 main.go:307] Version release-20200219.0-178-g97127750289b I0316 08:01:53.175532 31717 main.go:308] PID: 31717 I0316 08:01:53.175572 31717 main.go:309] UID: 0, GID: 0 I0316 08:01:53.175591 31717 main.go:310] Configuration: I0316 08:01:53.175611 31717 main.go:311] RootDir: /syzkaller/managers/main/workdir/gvisor_root I0316 08:01:53.175634 31717 main.go:312] Platform: ptrace I0316 08:01:53.175654 31717 main.go:313] FileAccess: shared, overlay: false I0316 08:01:53.175691 31717 main.go:314] Network: none, logging: false I0316 08:01:53.175718 31717 main.go:315] Strace: false, max size: 1024, syscalls: [] I0316 08:01:53.175743 31717 main.go:316] *************************** D0316 08:01:53.175804 31717 container.go:159] Load container "/syzkaller/managers/main/workdir/gvisor_root" "ci-gvisor-main-0" D0316 08:01:53.177046 31717 container.go:592] Signal container "ci-gvisor-main-0": signal 0 D0316 08:01:53.177078 31717 sandbox.go:814] Signal sandbox "ci-gvisor-main-0" D0316 08:01:53.177106 31717 sandbox.go:318] Connecting to sandbox "ci-gvisor-main-0" D0316 08:01:53.177214 31717 urpc.go:534] urpc: successfully marshalled 96 bytes. I0316 08:01:53.465957 31717 debug.go:128] Found sandbox "ci-gvisor-main-0", PID: 30644 I0316 08:01:53.467511 31717 debug.go:137] Retrieving sandbox stacks D0316 08:01:53.467571 31717 sandbox.go:932] Stacks sandbox "ci-gvisor-main-0" D0316 08:01:53.467584 31717 sandbox.go:318] Connecting to sandbox "ci-gvisor-main-0" W0316 08:01:53.467625 31717 error.go:48] FATAL ERROR: retrieving stacks: connecting to control server at PID 30644: connection refused retrieving stacks: connecting to control server at PID 30644: connection refused W0316 08:01:53.467748 31717 main.go:338] Failure to execute command, err: 1 Error collecting stacks: failed to run ["/syzkaller/managers/main/current/image" "-root" "/syzkaller/managers/main/workdir/gvisor_root" "-watchdog-action=panic" "-network=none" "-debug" "-alsologtostderr" "-platform=ptrace" "-file-access=shared" "-network=none" "debug" "-stacks" "--ps" "ci-gvisor-main-0"]: exit status 128 I0316 08:01:53.175427 31717 main.go:305] *************************** I0316 08:01:53.175475 31717 main.go:306] Args: [/syzkaller/managers/main/current/image -root /syzkaller/managers/main/workdir/gvisor_root -watchdog-action=panic -network=none -debug -alsologtostderr -platform=ptrace -file-access=shared -network=none debug -stacks --ps ci-gvisor-main-0] I0316 08:01:53.175511 31717 main.go:307] Version release-20200219.0-178-g97127750289b I0316 08:01:53.175532 31717 main.go:308] PID: 31717 I0316 08:01:53.175572 31717 main.go:309] UID: 0, GID: 0 I0316 08:01:53.175591 31717 main.go:310] Configuration: I0316 08:01:53.175611 31717 main.go:311] RootDir: /syzkaller/managers/main/workdir/gvisor_root I0316 08:01:53.175634 31717 main.go:312] Platform: ptrace I0316 08:01:53.175654 31717 main.go:313] FileAccess: shared, overlay: false I0316 08:01:53.175691 31717 main.go:314] Network: none, logging: false I0316 08:01:53.175718 31717 main.go:315] Strace: false, max size: 1024, syscalls: [] I0316 08:01:53.175743 31717 main.go:316] *************************** D0316 08:01:53.175804 31717 container.go:159] Load container "/syzkaller/managers/main/workdir/gvisor_root" "ci-gvisor-main-0" D0316 08:01:53.177046 31717 container.go:592] Signal container "ci-gvisor-main-0": signal 0 D0316 08:01:53.177078 31717 sandbox.go:814] Signal sandbox "ci-gvisor-main-0" D0316 08:01:53.177106 31717 sandbox.go:318] Connecting to sandbox "ci-gvisor-main-0" D0316 08:01:53.177214 31717 urpc.go:534] urpc: successfully marshalled 96 bytes. I0316 08:01:53.465957 31717 debug.go:128] Found sandbox "ci-gvisor-main-0", PID: 30644 I0316 08:01:53.467511 31717 debug.go:137] Retrieving sandbox stacks D0316 08:01:53.467571 31717 sandbox.go:932] Stacks sandbox "ci-gvisor-main-0" D0316 08:01:53.467584 31717 sandbox.go:318] Connecting to sandbox "ci-gvisor-main-0" W0316 08:01:53.467625 31717 error.go:48] FATAL ERROR: retrieving stacks: connecting to control server at PID 30644: connection refused retrieving stacks: connecting to control server at PID 30644: connection refused W0316 08:01:53.467748 31717 main.go:338] Failure to execute command, err: 1 [81582.955693] lowmem_reserve[]: 0 0 117862 117862 117862 [81582.961821] Node 0 Normal free:65900kB min:65908kB low:186596kB high:307284kB active_anon:118563480kB inactive_anon:971292kB active_file:2288kB inactive_file:0kB unevictable:0kB writepending:0kB present:122683392kB managed:120696220kB mlocked:0kB slab_reclaimable:39016kB slab_unreclaimable:171952kB kernel_stack:136356kB pagetables:260492kB bounce:0kB free_pcp:0kB local_pcp:0kB free_cma:0kB [81582.997481] lowmem_reserve[]: 0 0 0 0 0 [81583.004015] Node 0 DMA: 1*4kB (U) 0*8kB 0*16kB 1*32kB (U) 2*64kB (U) 1*128kB (U) 1*256kB (U) 0*512kB 1*1024kB (U) 1*2048kB (M) 3*4096kB (M) = 15908kB [81583.020860] Node 0 DMA32: 747*4kB (UME) 615*8kB (UME) 1262*16kB (UME) 344*32kB (UMEH) 100*64kB (UMEH) 64*128kB (UMH) 41*256kB (UMEH) 19*512kB (UMEH) 8*1024kB (UME) 9*2048kB (UME) 91*4096kB (M) = 473284kB [81583.042508] Node 0 Normal: 16944*4kB (UME) 8*8kB (UM) 0*16kB 0*32kB 0*64kB 0*128kB 0*256kB 0*512kB 0*1024kB 0*2048kB 0*4096kB = 67840kB [81583.057167] Node 0 hugepages_total=0 hugepages_free=0 hugepages_surp=0 hugepages_size=1048576kB [81583.066082] Node 0 hugepages_total=0 hugepages_free=0 hugepages_surp=0 hugepages_size=2048kB [81583.074824] 297601 total pagecache pages [81583.078875] 0 pages in swap cache [81583.082324] Swap cache stats: add 0, delete 0, find 0/0 [81583.087676] Free swap = 0kB [81583.090675] Total swap = 0kB [81583.093694] 31457176 pages RAM [81583.096889] 0 pages HighMem/MovableOnly [81583.100861] 513206 pages reserved [81583.104506] 0 pages hwpoisoned [81583.107699] [ pid ] uid tgid total_vm rss nr_ptes nr_pmds swapents oom_score_adj name [81583.116536] [ 935] 0 935 91555 147 176 4 0 0 systemd-journal [81583.126170] [ 960] 0 960 11548 333 23 3 0 -1000 systemd-udevd [81583.135606] [ 1125] 0 1125 13686 112 26 3 0 -1000 auditd [81583.144886] [ 1137] 0 1137 21093 40 12 3 0 0 audispd [81583.155457] [ 1151] 109 1151 11282 111 28 3 0 -900 dbus-daemon [81583.164724] [ 1155] 0 1155 1051 21 8 3 0 0 acpid [81583.173467] [ 1157] 0 1157 7409 61 19 3 0 0 cron [81583.182144] [ 1158] 0 1158 9495 122 22 3 0 0 systemd-logind [81583.191675] [ 1180] 0 1180 34588 3145 65 5 0 0 syz-ci [81583.200592] [ 1660] 0 1660 5119 275 12 3 0 0 dhclient [81583.210130] [ 1707] 0 1707 90807 12331 116 4 0 0 puppet [81583.219592] [ 1714] 0 1714 3575 34 12 3 0 0 agetty [81583.230289] [ 1716] 0 1716 3631 33 12 3 0 0 agetty [81583.239127] [ 1743] 106 1743 24474 132 23 4 0 0 ntpd [81583.247784] [ 1746] 0 1746 366819 41661 606 4 0 0 ruby [81583.256444] [ 2124] 0 2124 17077 3619 39 4 0 0 google_network_ [81583.266057] [ 2195] 0 2195 17175 3730 40 3 0 -999 google_accounts [81583.275661] [ 2354] 0 2354 17072 3580 39 3 0 0 google_clock_sk [81583.285259] [ 2355] 0 2355 17489 187 37 4 0 -1000 sshd [81583.293928] [13916] 0 13916 113255 50119 190 5 0 0 syz-manager [81583.303198] [14009] 0 14009 95098 56175 177 5 0 0 syz-manager [81583.313884] [14172] 0 14172 91827 51282 170 5 0 0 syz-manager [81583.324574] [42519] 0 42519 62528 356 28 3 0 0 rsyslogd [81583.334567] [49384] 0 49384 46286 17255 92 5 0 0 syz-manager [81583.343852] [34145] 0 34145 315315 3934 60 10 0 0 image [81583.352735] [34171] 0 34171 440947 11084 178 11 0 0 exe [81583.361327] [34177] 0 34177 42954605 29900237 61462 169 0 0 exe [81583.370454] [34215] 0 34215 1 1 1 1 0 0 exe [81583.385638] [34282] 0 34282 23665 3807 52 6 0 0 exe [81583.409177] [34422] 0 34422 296594 3712 58 9 0 0 image [81583.417923] [34448] 0 34448 82436 54603 133 6 0 0 exe [81583.427201] [34562] 0 34562 656 1 5 4 0 0 exe [81583.435807] [34935] 0 34935 4810 1 6 4 0 0 exe [81583.444476] [34982] 0 34982 1 1 1 1 0 0 exe [81583.453139] [34996] 0 34996 834 1 5 4 0 0 exe [81583.461719] [35017] 0 35017 1 1 1 1 0 0 exe [81583.470310] [35022] 0 35022 439 1 5 4 0 0 exe [81583.478892] [35035] 0 35035 4990 1 6 4 0 0 exe [81583.487463] [35069] 0 35069 4595 1 6 4 0 0 exe [81583.496165] [35255] 0 35255 416 1 5 3 0 0 exe [81583.504736] [35308] 0 35308 1 1 1 1 0 0 exe [81583.513299] [35369] 0 35369 6806 1 9 5 0 0 exe [81583.521912] [35432] 0 35432 1 1 1 1 0 0 exe [81583.530474] [39273] 0 39273 6411 1 9 5 0 0 exe [81583.539042] [40852] 0 40852 180167 660 22 4 0 0 image [81583.547788] [40860] 0 40860 181675 1476 28 4 0 0 exe [81583.556354] [40884] 0 40884 380550 63733 313 7 0 0 exe [81583.564918] [40946] 0 40946 1 1 1 1 0 0 exe [81583.573482] [41015] 0 41015 24120 4046 52 6 0 0 exe [81583.582232] [41132] 0 41132 179815 596 19 5 0 0 image [81583.590973] [41142] 0 41142 73327 45898 116 6 0 0 exe [81583.600005] [41253] 0 41253 530 1 5 4 0 0 exe [81583.608596] [41592] 0 41592 440 1 5 4 0 0 exe [81583.617160] [41593] 0 41593 709 1 5 4 0 0 exe [81583.625725] [41597] 0 41597 400 1 5 4 0 0 exe [81583.634774] [41604] 0 41604 4624 1 5 4 0 0 exe [81583.643347] [41607] 0 41607 4905 1 6 4 0 0 exe [81583.651943] [41610] 0 41610 4494 1 5 4 0 0 exe [81583.660512] [41611] 0 41611 4534 1 5 4 0 0 exe [81583.669093] [41633] 0 41633 6022 1 8 5 0 0 exe [81583.677660] [41648] 0 41648 6410 1 9 5 0 0 exe [81583.686235] [41663] 0 41663 6681 1 9 5 0 0 exe [81583.694841] [41674] 0 41674 5828 1 8 5 0 0 exe [81583.704400] [41790] 0 41790 6681 1 9 5 0 0 exe [81583.712973] [41797] 0 41797 6502 1 9 5 0 0 exe [81583.721616] [41841] 0 41841 6502 1 9 5 0 0 exe [81583.730223] [43725] 0 43725 6713 1 9 5 0 0 exe [81583.738806] [43767] 0 43767 416 1 5 3 0 0 exe [81583.747375] [43802] 0 43802 442 1 5 3 0 0 exe [81583.756028] [43955] 0 43955 6681 1 9 5 0 0 exe [81583.764599] [43971] 0 43971 414 1 5 3 0 0 exe [81583.773518] [43976] 0 43976 414 1 5 3 0 0 exe [81583.782089] [44373] 0 44373 414 1 5 3 0 0 exe [81583.790681] [45846] 0 45846 414 1 5 3 0 0 exe [81583.799424] [45898] 0 45898 442 1 5 3 0 0 exe [81583.808051] [51374] 0 51374 6443 1 9 5 0 0 exe [81583.816808] [53889] 0 53889 179879 688 20 5 0 0 image [81583.825563] [53895] 0 53895 180907 731 21 5 0 0 exe [81583.834146] [53900] 65534 53900 17181731352 36258 229 6 0 0 exe [81583.842977] [54215] 0 54215 180295 645 22 4 0 0 image [81583.855142] [63711] 0 63711 5899 1 8 5 0 0 exe [81583.863893] [63805] 0 63805 1 1 1 1 0 0 exe [81583.872447] [64117] 0 64117 1 1 1 1 0 0 exe [81583.881087] [64324] 0 64324 6411 1 9 5 0 0 exe [81583.889661] [64368] 0 64368 1 1 1 1 0 0 exe [81583.898361] [64397] 0 64397 6411 1 9 5 0 0 exe [81583.906941] [64474] 0 64474 416 1 5 3 0 0 exe [81583.915518] [64503] 0 64503 1 1 1 1 0 0 exe [81583.924079] [64626] 0 64626 6294 1 8 5 0 0 exe [81583.932640] [64729] 0 64729 416 1 5 3 0 0 exe [81583.941203] [64746] 0 64746 414 1 5 3 0 0 exe [81583.949762] [64770] 0 64770 1 1 1 1 0 0 exe [81583.958330] [64863] 0 64863 414 1 5 3 0 0 exe [81583.966936] [64969] 0 64969 414 1 5 3 0 0 exe [81583.975511] [65000] 0 65000 6806 1 9 5 0 0 exe [81583.984077] [65007] 0 65007 416 1 5 3 0 0 exe [81583.992646] [65023] 0 65023 1 1 1 1 0 0 exe [81584.001201] [65141] 0 65141 416 1 5 3 0 0 exe [81584.009757] [65147] 0 65147 414 1 5 3 0 0 exe [81584.018354] [ 456] 0 456 1 1 1 1 0 0 exe [81584.026911] [ 621] 0 621 1 1 1 1 0 0 exe [81584.035463] [ 996] 0 996 416 1 5 3 0 0 exe [81584.044064] [ 1059] 0 1059 6523 1 9 5 0 0 exe [81584.052626] [ 2305] 0 2305 442 1 5 3 0 0 exe [81584.061185] [ 2310] 0 2310 442 1 5 3 0 0 exe [81584.069755] [ 2513] 0 2513 5828 1 8 5 0 0 exe [81584.078316] [ 2519] 0 2519 416 1 5 3 0 0 exe [81584.086878] [ 2561] 0 2561 416 1 5 3 0 0 exe [81584.095444] [ 2641] 0 2641 414 1 5 3 0 0 exe [81584.104013] [ 2697] 0 2697 414 1 5 3 0 0 exe [81584.112683] [ 2859] 0 2859 6404 1 9 5 0 0 exe [81584.121810] [ 2865] 0 2865 6410 1 9 5 0 0 exe [81584.130381] [ 3027] 0 3027 416 1 5 3 0 0 exe [81584.140050] [ 3139] 0 3139 6378 1 9 5 0 0 exe [81584.148717] [ 3146] 0 3146 1 1 1 1 0 0 exe [81584.157281] [ 3230] 0 3230 6410 1 9 5 0 0 exe [81584.165864] [ 3386] 0 3386 416 1 5 3 0 0 exe [81584.174429] [ 3439] 0 3439 416 1 5 3 0 0 exe [81584.182999] [ 3482] 0 3482 5860 1 8 5 0 0 exe [81584.191572] [ 4127] 0 4127 1 1 1 1 0 0 exe [81584.200218] [ 4139] 0 4139 6502 1 9 5 0 0 exe [81584.208848] [ 4587] 0 4587 6372 1 9 5 0 0 exe [81584.217561] [ 4630] 0 4630 1 1 1 1 0 0 exe [81584.226197] [15062] 0 15062 180167 670 21 4 0 0 image [81584.234933] [15744] 0 15744 179879 662 20 5 0 0 image [81584.243715] [15764] 0 15764 181419 1260 26 4 0 0 exe [81584.252312] [15765] 0 15765 297517 17229 152 6 0 0 exe [81584.260872] [15839] 0 15839 1 1 1 1 0 0 exe [81584.269436] [15942] 0 15942 23713 3436 31 6 0 0 exe [81584.278007] [16430] 0 16430 179879 652 20 5 0 0 image [81584.286787] [16440] 0 16440 180167 669 21 4 0 0 image [81584.295522] [16442] 0 16442 72628 45365 115 7 0 0 exe [81584.304084] [16648] 0 16648 658 1 5 4 0 0 exe [81584.312643] [17756] 0 17756 663 1 5 4 0 0 exe [81584.321204] [17777] 0 17777 4814 1 6 4 0 0 exe [81584.329756] [17780] 0 17780 352 1 5 4 0 0 exe [81584.338321] [17792] 0 17792 517 1 5 4 0 0 exe [81584.346874] [17812] 0 17812 4757 1 5 4 0 0 exe [81584.355438] [17837] 0 17837 4671 1 6 4 0 0 exe [81584.364003] [17842] 0 17842 4506 1 6 4 0 0 exe [81584.372554] [17927] 0 17927 1 1 1 1 0 0 exe [81584.381120] [17963] 0 17963 6603 1 9 5 0 0 exe [81584.389685] [17976] 0 17976 1 1 1 1 0 0 exe [81584.398237] [18019] 0 18019 1 1 1 1 0 0 exe [81584.406791] [20490] 0 20490 1 1 1 1 0 0 exe [81584.415347] [26212] 0 26212 296882 3644 57 10 0 0 image [81584.424075] [26385] 0 26385 34478 11246 71 5 0 0 syz-manager [81584.433331] [26386] 0 26386 44991 21388 92 5 0 0 syz-manager [81584.442590] [27214] 0 27214 180231 732 21 5 0 0 image [81584.451361] [27220] 0 27220 180971 713 24 5 0 0 exe [81584.459914] [27223] 65534 27223 17181652865 5780 59 6 0 0 exe [81584.468742] [27256] 0 27256 180231 679 20 5 0 0 image [81584.477475] [27270] 0 27270 180971 745 23 5 0 0 exe [81584.486035] [27276] 65534 27276 211053 4952 55 6 0 0 exe [81584.494631] [27309] 65534 27309 1 1 1 1 0 0 exe [81584.503215] [27361] 65534 27361 23838 3717 30 6 0 0 exe [81584.511769] [27536] 0 27536 179879 670 21 5 0 0 image [81584.520495] [27548] 0 27548 180971 668 23 5 0 0 exe [81584.529047] [27549] 0 27549 206685 1494 33 6 0 0 exe [81584.537602] [27558] 0 27558 315315 3661 59 10 0 0 image [81584.546336] [27577] 0 27577 1 1 1 1 0 0 exe [81584.555322] [27599] 0 27599 2171 1 2 2 0 0 exe [81584.563877] [27602] 0 27602 296882 3629 55 10 0 0 image [81584.572610] Out of memory: Kill process 34177 (exe) score 939 or sacrifice child [81584.580479] Killed process 34215 (exe) total-vm:4kB, anon-rss:4kB, file-rss:0kB, shmem-rss:0kB [81584.599385] oom_reaper: reaped process 34215 (exe), now anon-rss:0kB, file-rss:0kB, shmem-rss:0kB [81585.327454] syz-manager invoked oom-killer: gfp_mask=0x24201ca(GFP_HIGHUSER_MOVABLE|__GFP_COLD), nodemask=0, order=0, oom_score_adj=0 [81585.339673] syz-manager cpuset=/ mems_allowed=0 [81585.344915] CPU: 63 PID: 49385 Comm: syz-manager Not tainted 4.9.0-11-amd64 #1 Debian 4.9.189-3+deb9u2 [81585.354342] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 [81585.363685] 0000000000000000 ffffffffa1136404 ffffba1950ec3c10 ffff9fc5a6796140 [81585.371731] ffffffffa1009690 0000000000000000 0000000000000000 ffff9fca7848a0c0 [81585.379764] ffff9fca787de100 ffff9fca73253690 0000000000000000 ffffffffa10acee0 [81585.387799] Call Trace: [81585.390377] [] ? dump_stack+0x5c/0x78 [81585.395817] [] ? dump_header+0x78/0x1fd [81585.401430] [] ? cap_inode_killpriv+0x20/0x20 [81585.407675] [] ? oom_kill_process+0x22a/0x3f0 [81585.413811] [] ? out_of_memory+0x111/0x470 [81585.419684] [] ? __alloc_pages_slowpath+0xa1f/0xb30 [81585.426333] [] ? __alloc_pages_nodemask+0x201/0x260 [81585.432981] [] ? alloc_pages_current+0x91/0x140 [81585.439377] [] ? filemap_fault+0x326/0x5d0 [81585.445284] [] ? ext4_filemap_fault+0x31/0x50 [ext4] [81585.452018] [] ? __do_fault+0xa9/0x1c0 [81585.457535] [] ? handle_mm_fault+0xda4/0x1350 [81585.463671] [] ? poll_select_copy_remaining+0x11b/0x150 [81585.470663] [] ? __do_page_fault+0x255/0x4f0 [81585.476826] [] ? page_fault+0x28/0x30 [81585.482803] Mem-Info: [81585.485206] active_anon:30285919 inactive_anon:243098 isolated_anon:0 active_file:100 inactive_file:431 isolated_file:0 unevictable:0 dirty:0 writeback:0 unstable:0 slab_reclaimable:9474 slab_unreclaimable:41816 mapped:242882 shmem:297093 pagetables:66348 bounce:0 free:138713 free_pcp:0 free_cma:0 [81585.519047] Node 0 active_anon:121143676kB inactive_anon:972392kB active_file:1040kB inactive_file:0kB unevictable:0kB isolated(anon):0kB isolated(file):0kB mapped:971528kB dirty:0kB writeback:0kB shmem:1188372kB shmem_thp: 0kB shmem_pmdmapped: 0kB anon_thp: 77824kB writeback_tmp:0kB unstable:0kB pages_scanned:22883 all_unreclaimable? no [81585.549064] Node 0 DMA free:15908kB min:8kB low:20kB high:32kB active_anon:0kB inactive_anon:0kB active_file:0kB inactive_file:0kB unevictable:0kB writepending:0kB present:15992kB managed:15908kB mlocked:0kB slab_reclaimable:0kB slab_unreclaimable:0kB kernel_stack:0kB pagetables:0kB bounce:0kB free_pcp:0kB local_pcp:0kB free_cma:0kB [81585.579333] lowmem_reserve[]: 0 2974 120837 120837 120837 [81585.585795] Node 0 DMA32 free:473100kB min:1660kB low:4704kB high:7748kB active_anon:2580192kB inactive_anon:1100kB active_file:28kB inactive_file:0kB unevictable:0kB writepending:0kB present:3129320kB managed:3063752kB mlocked:0kB slab_reclaimable:16kB slab_unreclaimable:596kB kernel_stack:108kB pagetables:4896kB bounce:0kB free_pcp:0kB local_pcp:0kB free_cma:0kB [81585.618297] lowmem_reserve[]: 0 0 117862 117862 117862 [81585.624338] Node 0 Normal free:65904kB min:65908kB low:186596kB high:307284kB active_anon:118563484kB inactive_anon:971292kB active_file:760kB inactive_file:560kB unevictable:0kB writepending:0kB present:122683392kB managed:120696220kB mlocked:0kB slab_reclaimable:37848kB slab_unreclaimable:166652kB kernel_stack:136356kB pagetables:260496kB bounce:0kB free_pcp:0kB local_pcp:0kB free_cma:0kB [81585.659512] lowmem_reserve[]: 0 0 0 0 0 [81585.664254] Node 0 DMA: 1*4kB (U) 0*8kB 0*16kB 1*32kB (U) 2*64kB (U) 1*128kB (U) 1*256kB (U) 0*512kB 1*1024kB (U) 1*2048kB (M) 3*4096kB (M) = 15908kB [81585.680575] Node 0 DMA32: 748*4kB (UME) 615*8kB (UME) 1262*16kB (UME) 345*32kB (UMEH) 100*64kB (UMEH) 64*128kB (UMH) 41*256kB (UMEH) 19*512kB (UMEH) 8*1024kB (UME) 9*2048kB (UME) 91*4096kB (M) = 473320kB [81585.701900] Node 0 Normal: 17664*4kB (UME) 124*8kB (UME) 18*16kB (UM) 66*32kB (UM) 0*64kB 0*128kB 0*256kB 0*512kB 0*1024kB 0*2048kB 0*4096kB = 74048kB [81585.717799] Node 0 hugepages_total=0 hugepages_free=0 hugepages_surp=0 hugepages_size=1048576kB [81585.726790] Node 0 hugepages_total=0 hugepages_free=0 hugepages_surp=0 hugepages_size=2048kB [81585.735550] 298621 total pagecache pages [81585.739596] 0 pages in swap cache [81585.743040] Swap cache stats: add 0, delete 0, find 0/0 [81585.748424] Free swap = 0kB [81585.751448] Total swap = 0kB [81585.754503] 31457176 pages RAM [81585.757712] 0 pages HighMem/MovableOnly [81585.761672] 513206 pages reserved [81585.765115] 0 pages hwpoisoned [81585.768307] [ pid ] uid tgid total_vm rss nr_ptes nr_pmds swapents oom_score_adj name [81585.777225] [ 935] 0 935 91555 147 176 4 0 0 systemd-journal [81585.787062] [ 960] 0 960 11548 333 23 3 0 -1000 systemd-udevd [81585.803711] [ 1125] 0 1125 13686 112 26 3 0 -1000 auditd [81585.812529] [ 1137] 0 1137 21093 75 12 3 0 0 audispd [81585.821438] [ 1151] 109 1151 11282 111 28 3 0 -900 dbus-daemon [81585.830696] [ 1155] 0 1155 1051 21 8 3 0 0 acpid [81585.840119] [ 1157] 0 1157 7409 61 19 3 0 0 cron [81585.848762] [ 1158] 0 1158 9495 122 22 3 0 0 systemd-logind [81585.858276] [ 1180] 0 1180 34588 3145 65 5 0 0 syz-ci [81585.867459] [ 1660] 0 1660 5119 275 12 3 0 0 dhclient [81585.876450] [ 1707] 0 1707 90807 12331 116 4 0 0 puppet [81585.885262] [ 1714] 0 1714 3575 34 12 3 0 0 agetty [81585.894098] [ 1716] 0 1716 3631 33 12 3 0 0 agetty [81585.902928] [ 1743] 106 1743 24474 132 23 4 0 0 ntpd [81585.911664] [ 1746] 0 1746 366819 41661 606 4 0 0 ruby [81585.921010] [ 2124] 0 2124 17077 3619 39 4 0 0 google_network_ [81585.930638] [ 2195] 0 2195 17175 3730 40 3 0 -999 google_accounts [81585.940246] [ 2354] 0 2354 17072 3580 39 3 0 0 google_clock_sk [81585.949859] [ 2355] 0 2355 17489 187 37 4 0 -1000 sshd [81585.958602] [13916] 0 13916 113255 50141 190 5 0 0 syz-manager [81585.967869] [14009] 0 14009 95098 56175 177 5 0 0 syz-manager [81585.977258] [14172] 0 14172 91827 51282 170 5 0 0 syz-manager [81585.986546] [42519] 0 42519 62528 361 28 3 0 0 rsyslogd [81585.995572] [49384] 0 49384 46286 17363 92 5 0 0 syz-manager [81586.004857] [34145] 0 34145 315315 3933 60 10 0 0 image [81586.017424] [34171] 0 34171 440947 11084 178 11 0 0 exe [81586.025979] [34177] 0 34177 42954605 29900237 61462 169 0 0 exe [81586.034533] [34215] 0 34215 1 0 1 1 0 0 exe [81586.043100] [34282] 0 34282 23665 3807 52 6 0 0 exe [81586.051658] [34422] 0 34422 296594 3711 58 9 0 0 image [81586.060384] [34448] 0 34448 82436 54603 133 6 0 0 exe [81586.068950] [34562] 0 34562 656 1 5 4 0 0 exe [81586.077507] [34935] 0 34935 4810 1 6 4 0 0 exe [81586.086077] [34982] 0 34982 1 1 1 1 0 0 exe [81586.094646] [34996] 0 34996 834 1 5 4 0 0 exe [81586.104526] [35017] 0 35017 1 1 1 1 0 0 exe [81586.113085] [35022] 0 35022 439 1 5 4 0 0 exe [81586.121647] [35035] 0 35035 4990 1 6 4 0 0 exe [81586.130232] [35069] 0 35069 4595 1 6 4 0 0 exe [81586.138791] [35255] 0 35255 416 1 5 3 0 0 exe [81586.147790] [35308] 0 35308 1 1 1 1 0 0 exe [81586.156454] [35369] 0 35369 6806 1 9 5 0 0 exe [81586.165015] [35432] 0 35432 1 1 1 1 0 0 exe [81586.173568] [39273] 0 39273 6411 1 9 5 0 0 exe [81586.182210] [40852] 0 40852 180167 660 22 4 0 0 image [81586.190948] [40860] 0 40860 181675 1476 28 4 0 0 exe [81586.199512] [40884] 0 40884 380550 63733 313 7 0 0 exe [81586.208061] [40946] 0 40946 1 1 1 1 0 0 exe [81586.217765] [41015] 0 41015 24120 4046 52 6 0 0 exe [81586.227557] [41132] 0 41132 179815 596 19 5 0 0 image [81586.236399] [41142] 0 41142 73327 45898 116 6 0 0 exe [81586.244983] [41253] 0 41253 530 1 5 4 0 0 exe [81586.253557] [41592] 0 41592 440 1 5 4 0 0 exe [81586.262159] [41593] 0 41593 709 1 5 4 0 0 exe [81586.270725] [41597] 0 41597 400 1 5 4 0 0 exe [81586.279414] [41604] 0 41604 4624 1 5 4 0 0 exe [81586.287974] [41607] 0 41607 4905 1 6 4 0 0 exe [81586.296527] [41610] 0 41610 4494 1 5 4 0 0 exe [81586.305079] [41611] 0 41611 4534 1 5 4 0 0 exe [81586.316321] [41633] 0 41633 6022 1 8 5 0 0 exe [81586.324873] [41648] 0 41648 6410 1 9 5 0 0 exe [81586.333424] [41663] 0 41663 6681 1 9 5 0 0 exe [81586.342016] [41674] 0 41674 5828 1 8 5 0 0 exe [81586.350568] [41790] 0 41790 6681 1 9 5 0 0 exe [81586.363118] [41797] 0 41797 6502 1 9 5 0 0 exe [81586.371666] [41841] 0 41841 6502 1 9 5 0 0 exe [81586.380218] [43725] 0 43725 6713 1 9 5 0 0 exe [81586.388769] [43767] 0 43767 416 1 5 3 0 0 exe [81586.397368] [43802] 0 43802 442 1 5 3 0 0 exe [81586.405920] [43955] 0 43955 6681 1 9 5 0 0 exe [81586.414662] [43971] 0 43971 414 1 5 3 0 0 exe [81586.423217] [43976] 0 43976 414 1 5 3 0 0 exe [81586.431781] [44373] 0 44373 414 1 5 3 0 0 exe [81586.440335] [45846] 0 45846 414 1 5 3 0 0 exe [81586.448886] [45898] 0 45898 442 1 5 3 0 0 exe [81586.457446] [51374] 0 51374 6443 1 9 5 0 0 exe [81586.466001] [53889] 0 53889 179879 688 20 5 0 0 image [81586.474829] [53895] 0 53895 180907 731 21 5 0 0 exe [81586.483388] [53900] 65534 53900 17181731352 36258 229 6 0 0 exe [81586.492200] [54215] 0 54215 180295 645 22 4 0 0 image [81586.500925] [63711] 0 63711 5899 1 8 5 0 0 exe [81586.509482] [63805] 0 63805 1 1 1 1 0 0 exe [81586.518137] [64117] 0 64117 1 1 1 1 0 0 exe [81586.526689] [64324] 0 64324 6411 1 9 5 0 0 exe [81586.535244] [64368] 0 64368 1 1 1 1 0 0 exe [81586.543882] [64397] 0 64397 6411 1 9 5 0 0 exe [81586.552439] [64474] 0 64474 416 1 5 3 0 0 exe [81586.561000] [64503] 0 64503 1 1 1 1 0 0 exe [81586.569553] [64626] 0 64626 6294 1 8 5 0 0 exe [81586.578104] [64729] 0 64729 416 1 5 3 0 0 exe [81586.586656] [64746] 0 64746 414 1 5 3 0 0 exe [81586.595208] [64770] 0 64770 1 1 1 1 0 0 exe [81586.603759] [64863] 0 64863 414 1 5 3 0 0 exe [81586.612313] [64969] 0 64969 414 1 5 3 0 0 exe [81586.620863] [65000] 0 65000 6806 1 9 5 0 0 exe [81586.629419] [65007] 0 65007 416 1 5 3 0 0 exe [81586.637977] [65023] 0 65023 1 1 1 1 0 0 exe [81586.646586] [65141] 0 65141 416 1 5 3 0 0 exe [81586.655148] [65147] 0 65147 414 1 5 3 0 0 exe [81586.663703] [ 456] 0 456 1 1 1 1 0 0 exe [81586.672254] [ 621] 0 621 1 1 1 1 0 0 exe [81586.680806] [ 996] 0 996 416 1 5 3 0 0 exe [81586.692569] [ 1059] 0 1059 6523 1 9 5 0 0 exe [81586.701135] [ 2305] 0 2305 442 1 5 3 0 0 exe [81586.712033] [ 2310] 0 2310 442 1 5 3 0 0 exe [81586.720587] [ 2513] 0 2513 5828 1 8 5 0 0 exe [81586.729182] [ 2519] 0 2519 416 1 5 3 0 0 exe [81586.737734] [ 2561] 0 2561 416 1 5 3 0 0 exe [81586.746288] [ 2641] 0 2641 414 1 5 3 0 0 exe [81586.754838] [ 2697] 0 2697 414 1 5 3 0 0 exe [81586.763391] [ 2859] 0 2859 6404 1 9 5 0 0 exe [81586.774726] [ 2865] 0 2865 6410 1 9 5 0 0 exe [81586.783282] [ 3027] 0 3027 416 1 5 3 0 0 exe [81586.791832] [ 3139] 0 3139 6378 1 9 5 0 0 exe [81586.800425] [ 3146] 0 3146 1 1 1 1 0 0 exe [81586.809113] [ 3230] 0 3230 6410 1 9 5 0 0 exe [81586.817715] [ 3386] 0 3386 416 1 5 3 0 0 exe [81586.826267] [ 3439] 0 3439 416 1 5 3 0 0 exe [81586.834819] [ 3482] 0 3482 5860 1 8 5 0 0 exe [81586.843372] [ 4127] 0 4127 1 1 1 1 0 0 exe [81586.851942] [ 4139] 0 4139 6502 1 9 5 0 0 exe [81586.860491] [ 4587] 0 4587 6372 1 9 5 0 0 exe [81586.869043] [ 4630] 0 4630 1 1 1 1 0 0 exe [81586.877607] [15062] 0 15062 180167 670 21 4 0 0 image [81586.886335] [15744] 0 15744 179879 662 20 5 0 0 image [81586.895061] [15764] 0 15764 181419 1260 26 4 0 0 exe [81586.903613] [15765] 0 15765 297517 17267 152 6 0 0 exe [81586.912205] [15839] 0 15839 1 1 1 1 0 0 exe [81586.920796] [15942] 0 15942 23713 3436 31 6 0 0 exe [81586.929346] [16430] 0 16430 179879 652 20 5 0 0 image [81586.938102] [16440] 0 16440 180167 669 21 4 0 0 image [81586.946832] [16442] 0 16442 72628 45365 115 7 0 0 exe [81586.955422] [16648] 0 16648 658 1 5 4 0 0 exe [81586.963976] [17756] 0 17756 663 1 5 4 0 0 exe [81586.973534] [17777] 0 17777 4814 1 6 4 0 0 exe [81586.982095] [17780] 0 17780 352 1 5 4 0 0 exe [81586.990676] [17792] 0 17792 517 1 5 4 0 0 exe [81586.999230] [17812] 0 17812 4757 1 5 4 0 0 exe [81587.007791] [17837] 0 17837 4671 1 6 4 0 0 exe [81587.016358] [17842] 0 17842 4506 1 6 4 0 0 exe [81587.024916] [17927] 0 17927 1 1 1 1 0 0 exe [81587.033470] [17963] 0 17963 6603 1 9 5 0 0 exe [81587.042035] [17976] 0 17976 1 1 1 1 0 0 exe [81587.050630] [18019] 0 18019 1 1 1 1 0 0 exe [81587.059184] [20490] 0 20490 1 1 1 1 0 0 exe [81587.067787] [26212] 0 26212 296882 3644 57 10 0 0 image [81587.076565] [26385] 0 26385 34478 11246 71 5 0 0 syz-manager [81587.085814] [26386] 0 26386 44991 21388 92 5 0 0 syz-manager [81587.095074] [27214] 0 27214 180231 732 21 5 0 0 image [81587.103801] [27220] 0 27220 180971 713 24 5 0 0 exe [81587.112352] [27223] 65534 27223 17181652865 5780 59 6 0 0 exe [81587.121168] [27256] 0 27256 180231 679 20 5 0 0 image [81587.129908] [27270] 0 27270 180971 745 23 5 0 0 exe [81587.138461] [27276] 65534 27276 211053 4952 55 6 0 0 exe [81587.147169] [27309] 65534 27309 1 1 1 1 0 0 exe [81587.155793] [27361] 65534 27361 23838 3717 30 6 0 0 exe [81587.164373] [27536] 0 27536 179879 670 21 5 0 0 image [81587.173130] [27548] 0 27548 180971 668 23 5 0 0 exe [81587.181721] [27549] 0 27549 206685 1495 33 6 0 0 exe [81587.190596] [27558] 0 27558 315315 3661 59 10 0 0 image [81587.199475] [27577] 0 27577 1 1 1 1 0 0 exe [81587.209418] [27599] 0 27599 2171 1 2 2 0 0 exe [81587.218209] [27602] 0 27602 296882 3629 55 10 0 0 image [81587.227774] Out of memory: Kill process 34177 (exe) score 939 or sacrifice child [81587.236452] Killed process 34177 (exe) total-vm:171818420kB, anon-rss:119445228kB, file-rss:0kB, shmem-rss:156744kB [81591.574780] kvm [28160]: vcpu0, guest rIP: 0xa74064 unhandled rdmsr: 0xce [81592.535632] kvm [28786]: vcpu0, guest rIP: 0xa74064 unhandled rdmsr: 0xce [81596.132172] oom_reaper: reaped process 34177 (exe), now anon-rss:0kB, file-rss:0kB, shmem-rss:157164kB [97099.041207] can: request_module (can-proto-0) failed. [97099.056539] can: request_module (can-proto-0) failed. [97099.072025] can: request_module (can-proto-0) failed. [97120.192202] can: request_module (can-proto-0) failed. [97120.207534] can: request_module (can-proto-0) failed. [97120.234555] can: request_module (can-proto-0) failed. [97124.804225] can: request_module (can-proto-0) failed. [97124.814929] can: request_module (can-proto-0) failed. [97124.828872] can: request_module (can-proto-0) failed. [97307.124852] kvm [41621]: vcpu0, guest rIP: 0xa75294 unhandled rdmsr: 0xce [97307.125552] kvm [41622]: vcpu0, guest rIP: 0xa75294 unhandled rdmsr: 0xce [97307.125896] kvm [41616]: vcpu0, guest rIP: 0xa75294 unhandled rdmsr: 0xce [97482.648808] kvm [42563]: vcpu0, guest rIP: 0xa75294 unhandled rdmsr: 0xce [175346.089220] exe invoked oom-killer: gfp_mask=0x24201ca(GFP_HIGHUSER_MOVABLE|__GFP_COLD), nodemask=0, order=0, oom_score_adj=0 [175346.100888] exe cpuset=/ mems_allowed=0 [175346.105224] CPU: 60 PID: 28325 Comm: exe Not tainted 4.9.0-11-amd64 #1 Debian 4.9.189-3+deb9u2 [175346.114037] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 [175346.123471] 0000000000000000 ffffffffa1136404 ffffba194e00bc10 ffff9fbc7ca2b040 [175346.131570] ffffffffa1009690 0000000000000000 0000000000000000 0000000ca141e4f1 [175346.139670] ffff9fba8277e040 ffffffffa0f8d207 0000004200000000 ffffffffa10acee0 [175346.147838] Call Trace: [175346.150527] [] ? dump_stack+0x5c/0x78 [175346.156061] [] ? dump_header+0x78/0x1fd [175346.161754] [] ? get_page_from_freelist+0x3f7/0xb20 [175346.168482] [] ? cap_inode_killpriv+0x20/0x20 [175346.174695] [] ? oom_kill_process+0x22a/0x3f0 [175346.180905] [] ? out_of_memory+0x111/0x470 [175346.186872] [] ? __alloc_pages_slowpath+0xa1f/0xb30 [175346.193601] [] ? __alloc_pages_nodemask+0x201/0x260 [175346.200471] [] ? alloc_pages_current+0x91/0x140 [175346.206853] [] ? filemap_fault+0x326/0x5d0 [175346.212841] [] ? ext4_filemap_fault+0x31/0x50 [ext4] [175346.219670] [] ? __do_fault+0xa9/0x1c0 [175346.225274] [] ? handle_mm_fault+0xda4/0x1350 [175346.231491] [] ? do_nanosleep+0x8d/0xe0 [175346.237184] [] ? __do_page_fault+0x255/0x4f0 [175346.243410] [] ? page_fault+0x28/0x30 [175346.248954] Mem-Info: [175346.251465] active_anon:30503324 inactive_anon:48047 isolated_anon:0 active_file:648 inactive_file:12 isolated_file:0 unevictable:0 dirty:224 writeback:0 unstable:0 slab_reclaimable:7196 slab_unreclaimable:32177 mapped:52160 shmem:55216 pagetables:72402 bounce:0 free:138700 free_pcp:155 free_cma:0 [175346.286329] Node 0 active_anon:122013296kB inactive_anon:192188kB active_file:3116kB inactive_file:1524kB unevictable:0kB isolated(anon):0kB isolated(file):0kB mapped:210184kB dirty:916kB writeback:0kB shmem:220864kB shmem_thp: 0kB shmem_pmdmapped: 0kB anon_thp: 116736kB writeback_tmp:0kB unstable:0kB pages_scanned:935 all_unreclaimable? no [175346.316693] Node 0 DMA free:15908kB min:8kB low:20kB high:32kB active_anon:0kB inactive_anon:0kB active_file:0kB inactive_file:0kB unevictable:0kB writepending:0kB present:15992kB managed:15908kB mlocked:0kB slab_reclaimable:0kB slab_unreclaimable:0kB kernel_stack:0kB pagetables:0kB bounce:0kB free_pcp:0kB local_pcp:0kB free_cma:0kB [175346.346491] lowmem_reserve[]: 0 2974 120837 120837 120837 [175346.352924] Node 0 DMA32 free:473060kB min:1660kB low:4704kB high:7748kB active_anon:2578804kB inactive_anon:36kB active_file:20kB inactive_file:44kB unevictable:0kB writepending:0kB present:3129320kB managed:3063752kB mlocked:0kB slab_reclaimable:68kB slab_unreclaimable:440kB kernel_stack:80kB pagetables:5300kB bounce:0kB free_pcp:0kB local_pcp:0kB free_cma:0kB [175346.385390] lowmem_reserve[]: 0 0 117862 117862 117862 [175346.391603] Node 0 Normal free:65664kB min:65908kB low:186596kB high:307284kB active_anon:119434492kB inactive_anon:192152kB active_file:3416kB inactive_file:0kB unevictable:0kB writepending:0kB present:122683392kB managed:120696220kB mlocked:0kB slab_reclaimable:28712kB slab_unreclaimable:128264kB kernel_stack:37344kB pagetables:284308kB bounce:0kB free_pcp:1728kB local_pcp:0kB free_cma:0kB [175346.426606] lowmem_reserve[]: 0 0 0 0 0 [175346.431425] Node 0 DMA: 1*4kB (U) 0*8kB 0*16kB 1*32kB (U) 2*64kB (U) 1*128kB (U) 1*256kB (U) 0*512kB 1*1024kB (U) 1*2048kB (M) 3*4096kB (M) = 15908kB [175346.448182] Node 0 DMA32: 580*4kB (UMEH) 287*8kB (UME) 307*16kB (UME) 188*32kB (UME) 82*64kB (UME) 29*128kB (UME) 7*256kB (UMEH) 3*512kB (UM) 7*1024kB (UME) 4*2048kB (UME) 105*4096kB (ME) = 473272kB [175346.469466] Node 0 Normal: 16732*4kB (UMEH) 64*8kB (UMH) 11*16kB (H) 14*32kB (H) 9*64kB (H) 2*128kB (H) 1*256kB (H) 0*512kB 0*1024kB 0*2048kB 0*4096kB = 69152kB [175346.486730] Node 0 hugepages_total=0 hugepages_free=0 hugepages_surp=0 hugepages_size=1048576kB [175346.495642] Node 0 hugepages_total=0 hugepages_free=0 hugepages_surp=0 hugepages_size=2048kB [175346.504297] 56888 total pagecache pages [175346.508341] 0 pages in swap cache [175346.512017] Swap cache stats: add 0, delete 0, find 0/0 [175346.517450] Free swap = 0kB [175346.520537] Total swap = 0kB [175346.523627] 31457176 pages RAM [175346.526889] 0 pages HighMem/MovableOnly [175346.530960] 513206 pages reserved [175346.534473] 0 pages hwpoisoned [175346.537733] [ pid ] uid tgid total_vm rss nr_ptes nr_pmds swapents oom_score_adj name [175346.546554] [ 935] 0 935 113037 147 219 4 0 0 systemd-journal [175346.556310] [ 960] 0 960 11548 333 23 3 0 -1000 systemd-udevd [175346.565866] [ 1125] 0 1125 13686 112 26 3 0 -1000 auditd [175346.574773] [ 1137] 0 1137 21093 16 12 3 0 0 audispd [175346.583766] [ 1151] 109 1151 11282 112 28 3 0 -900 dbus-daemon [175346.593102] [ 1155] 0 1155 1051 21 8 3 0 0 acpid [175346.602216] [ 1157] 0 1157 7409 61 19 3 0 0 cron [175346.610966] [ 1158] 0 1158 9495 122 22 3 0 0 systemd-logind [175346.620698] [ 1180] 0 1180 32028 3099 62 5 0 0 syz-ci [175346.629649] [ 1660] 0 1660 5119 275 12 3 0 0 dhclient [175346.638724] [ 1707] 0 1707 90842 12358 116 4 0 0 puppet [175346.647631] [ 1714] 0 1714 3575 34 12 3 0 0 agetty [175346.656619] [ 1716] 0 1716 3631 33 12 3 0 0 agetty [175346.665538] [ 1743] 106 1743 24474 132 23 4 0 0 ntpd [175346.674432] [ 1746] 0 1746 361781 41111 583 4 0 0 ruby [175346.683290] [ 2124] 0 2124 17077 3622 39 4 0 0 google_network_ [175346.692995] [ 2195] 0 2195 17239 3739 40 3 0 -999 google_accounts [175346.702697] [ 2354] 0 2354 17072 3580 39 3 0 0 google_clock_sk [175346.712581] [ 2355] 0 2355 17489 187 37 4 0 -1000 sshd [175346.721348] [42133] 0 42133 50358 23840 101 5 0 0 syz-manager [175346.730877] [45406] 0 45406 53614 24006 109 5 0 0 syz-manager [175346.740256] [63756] 0 63756 121165 62344 170 5 0 0 syz-manager [175346.749614] [13118] 0 13118 94500 57666 188 5 0 0 syz-manager [175346.758963] [25776] 0 25776 111726 55609 190 5 0 0 syz-manager [175346.768430] [ 9893] 0 9893 62528 353 28 4 0 0 rsyslogd [175346.777739] [62996] 0 62996 76602 52378 155 5 0 0 syz-manager [175346.787110] [24856] 0 24856 180231 726 22 5 0 0 image [175346.795940] [24862] 0 24862 181323 713 23 4 0 0 exe [175346.804704] [24864] 65534 24864 17181731394 40859 231 6 0 0 exe [175346.813623] [25464] 0 25464 180167 648 21 4 0 0 image [175346.822632] [28312] 0 28312 315400 4068 65 9 0 0 image [175346.831678] [28319] 0 28319 442694 7615 175 11 0 0 exe [175346.840331] [28320] 0 28320 50715370 30112456 68984 170 0 0 exe [175346.848989] [28346] 0 28346 1 1 1 1 0 0 exe [175346.857667] [28360] 0 28360 23855 4021 52 6 0 0 exe [175346.866452] [38106] 0 38106 315048 3710 60 10 0 0 image [175346.875292] [38116] 0 38116 29440 4194 31 6 0 0 exe [175346.883951] [38152] 0 38152 4539 1 5 4 0 0 exe [175346.892609] [38163] 0 38163 773 1 5 4 0 0 exe [175346.901360] [38167] 0 38167 4576 1 5 4 0 0 exe [175346.910018] [38170] 0 38170 482 1 5 4 0 0 exe [175346.918685] [38178] 0 38178 475 1 5 4 0 0 exe [175346.927349] [38180] 0 38180 443 1 5 4 0 0 exe [175346.936017] [38197] 0 38197 4569 1 5 4 0 0 exe [175346.944758] [38204] 0 38204 4867 1 5 4 0 0 exe [175346.953467] [38250] 0 38250 6582 1 9 5 0 0 exe [175346.962116] [38257] 0 38257 6936 1 9 5 0 0 exe [175346.970770] [38267] 0 38267 6638 1 9 5 0 0 exe [175346.979442] [38335] 0 38335 6801 1 10 5 0 0 exe [175346.988147] [43215] 0 43215 179815 653 20 5 0 0 image [175346.996960] [43220] 0 43220 181323 762 23 5 0 0 exe [175347.005648] [43226] 65534 43226 17181666072 8587 113 6 0 0 exe [175347.014690] [44668] 0 44668 180167 672 21 4 0 0 image [175347.023510] [44724] 0 44724 179882 685 21 4 0 0 image [175347.032324] [44733] 0 44733 180974 685 23 5 0 0 exe [175347.040964] [44738] 65534 44738 17181653458 6848 89 6 0 0 exe [175347.049871] [45141] 0 45141 180167 668 20 5 0 0 image [175347.058699] [55571] 0 55571 179946 615 20 5 0 0 image [175347.067511] [55577] 0 55577 180234 686 21 5 0 0 image [175347.076328] Out of memory: Kill process 28320 (exe) score 946 or sacrifice child [175347.084083] Killed process 28346 (exe) total-vm:4kB, anon-rss:4kB, file-rss:0kB, shmem-rss:0kB [175347.102603] oom_reaper: reaped process 28346 (exe), now anon-rss:0kB, file-rss:0kB, shmem-rss:0kB [175357.442129] syz-manager invoked oom-killer: gfp_mask=0x24201ca(GFP_HIGHUSER_MOVABLE|__GFP_COLD), nodemask=0, order=0, oom_score_adj=0 [175357.454769] syz-manager cpuset=/ mems_allowed=0 [175357.459771] CPU: 29 PID: 25777 Comm: syz-manager Not tainted 4.9.0-11-amd64 #1 Debian 4.9.189-3+deb9u2 [175357.469274] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 [175357.478692] 0000000000000000 ffffffffa1136404 ffffba195b2efc10 ffff9fbc7ca2b040 [175357.486886] ffffffffa1009690 0000000000000000 0000000000000000 0000000c5b2efb00 [175357.496385] ffff9fbb9f2cd0c0 ffffffffa0f8d207 0000004200000000 ffffffffa10acee0 [175357.504494] Call Trace: [175357.507153] [] ? dump_stack+0x5c/0x78 [175357.514047] [] ? dump_header+0x78/0x1fd [175357.519744] [] ? get_page_from_freelist+0x3f7/0xb20 [175357.526472] [] ? cap_inode_killpriv+0x20/0x20 [175357.534064] [] ? oom_kill_process+0x22a/0x3f0 [175357.540452] [] ? out_of_memory+0x111/0x470 [175357.546399] [] ? __alloc_pages_slowpath+0xa1f/0xb30 [175357.553126] [] ? __alloc_pages_nodemask+0x201/0x260 [175357.559862] [] ? alloc_pages_current+0x91/0x140 [175357.566248] [] ? filemap_fault+0x326/0x5d0 [175357.572242] [] ? ext4_filemap_fault+0x31/0x50 [ext4] [175357.580429] [] ? __do_fault+0xa9/0x1c0 [175357.586037] [] ? handle_mm_fault+0xda4/0x1350 [175357.592245] [] ? poll_select_copy_remaining+0x11b/0x150 [175357.600703] [] ? __do_page_fault+0x255/0x4f0 [175357.606830] [] ? page_fault+0x28/0x30 [175357.613763] Mem-Info: [175357.617611] active_anon:30503330 inactive_anon:48047 isolated_anon:0 active_file:1928 inactive_file:1979 isolated_file:0 unevictable:0 dirty:5 writeback:0 unstable:0 slab_reclaimable:6547 slab_unreclaimable:28753 mapped:55189 shmem:55216 pagetables:72418 bounce:0 free:138860 free_pcp:700 free_cma:0 [175357.652063] Node 0 active_anon:122013824kB inactive_anon:192188kB active_file:6668kB inactive_file:8044kB unevictable:0kB isolated(anon):0kB isolated(file):0kB mapped:220052kB dirty:20kB writeback:0kB shmem:220864kB shmem_thp: 0kB shmem_pmdmapped: 0kB anon_thp: 116736kB writeback_tmp:0kB unstable:0kB pages_scanned:2331 all_unreclaimable? no [175357.682428] Node 0 DMA free:15908kB min:8kB low:20kB high:32kB active_anon:0kB inactive_anon:0kB active_file:0kB inactive_file:0kB unevictable:0kB writepending:0kB present:15992kB managed:15908kB mlocked:0kB slab_reclaimable:0kB slab_unreclaimable:0kB kernel_stack:0kB pagetables:0kB bounce:0kB free_pcp:0kB local_pcp:0kB free_cma:0kB [175357.713621] lowmem_reserve[]: 0 2974 120837 120837 120837 [175357.722812] Node 0 DMA32 free:473164kB min:1660kB low:4704kB high:7748kB active_anon:2578904kB inactive_anon:36kB active_file:16kB inactive_file:20kB unevictable:0kB writepending:0kB present:3129320kB managed:3063752kB mlocked:0kB slab_reclaimable:28kB slab_unreclaimable:412kB kernel_stack:80kB pagetables:5300kB bounce:0kB free_pcp:0kB local_pcp:0kB free_cma:0kB [175357.755235] lowmem_reserve[]: 0 0 117862 117862 117862 [175357.763026] Node 0 Normal free:66104kB min:65908kB low:186596kB high:307284kB active_anon:119444800kB inactive_anon:192152kB active_file:3444kB inactive_file:3332kB unevictable:0kB writepending:52kB present:122683392kB managed:120696220kB mlocked:0kB slab_reclaimable:26076kB slab_unreclaimable:114392kB kernel_stack:37376kB pagetables:284372kB bounce:0kB free_pcp:2332kB local_pcp:0kB free_cma:0kB [175357.799781] lowmem_reserve[]: 0 0 0 0 0 [175357.806090] Node 0 DMA: 1*4kB (U) 0*8kB 0*16kB 1*32kB (U) 2*64kB (U) 1*128kB (U) 1*256kB (U) 0*512kB 1*1024kB (U) 1*2048kB (M) 3*4096kB (M) = 15908kB [175357.825340] Node 0 DMA32: 583*4kB (UMEH) 282*8kB (UME) 308*16kB (UME) 186*32kB (UME) 82*64kB (UME) 29*128kB (UME) 7*256kB (UMEH) 3*512kB (UM) 7*1024kB (UME) 4*2048kB (UME) 105*4096kB (ME) = 473196kB [175357.850478] Node 0 Normal: 17231*4kB (UMEH) 243*8kB (UMEH) 42*16kB (UMEH) 17*32kB (UH) 9*64kB (H) 2*128kB (H) 1*256kB (H) 0*512kB 0*1024kB 0*2048kB 0*4096kB = 73172kB [175357.869731] Node 0 hugepages_total=0 hugepages_free=0 hugepages_surp=0 hugepages_size=1048576kB [175357.878660] Node 0 hugepages_total=0 hugepages_free=0 hugepages_surp=0 hugepages_size=2048kB [175357.887317] 56801 total pagecache pages [175357.891375] 0 pages in swap cache [175357.894926] Swap cache stats: add 0, delete 0, find 0/0 [175357.900368] Free swap = 0kB [175357.903462] Total swap = 0kB [175357.906560] 31457176 pages RAM [175357.909837] 0 pages HighMem/MovableOnly [175357.913885] 513206 pages reserved [175357.917418] 0 pages hwpoisoned [175357.920676] [ pid ] uid tgid total_vm rss nr_ptes nr_pmds swapents oom_score_adj name [175357.929530] [ 935] 0 935 113037 147 219 4 0 0 systemd-journal [175357.939378] [ 960] 0 960 11548 333 23 3 0 -1000 systemd-udevd [175357.948909] [ 1125] 0 1125 13686 112 26 3 0 -1000 auditd [175357.957855] [ 1137] 0 1137 21093 117 12 3 0 0 audispd [175357.966904] [ 1151] 109 1151 11282 112 28 3 0 -900 dbus-daemon [175357.976256] [ 1155] 0 1155 1051 21 8 3 0 0 acpid [175357.985251] [ 1157] 0 1157 7409 61 19 3 0 0 cron [175357.994022] [ 1158] 0 1158 9495 122 22 3 0 0 systemd-logind [175358.003632] [ 1180] 0 1180 32028 3149 62 5 0 0 syz-ci [175358.012549] [ 1660] 0 1660 5119 275 12 3 0 0 dhclient [175358.021646] [ 1707] 0 1707 90842 12359 116 4 0 0 puppet [175358.030567] [ 1714] 0 1714 3575 34 12 3 0 0 agetty [175358.039482] [ 1716] 0 1716 3631 33 12 3 0 0 agetty [175358.048399] [ 1743] 106 1743 24474 132 23 4 0 0 ntpd [175358.058525] [ 1746] 0 1746 361781 41111 583 4 0 0 ruby [175358.067334] [ 2124] 0 2124 17077 3622 39 4 0 0 google_network_ [175358.078433] [ 2195] 0 2195 17239 3739 40 3 0 -999 google_accounts [175358.088324] [ 2354] 0 2354 17072 3580 39 3 0 0 google_clock_sk [175358.099411] [ 2355] 0 2355 17489 187 37 4 0 -1000 sshd [175358.108176] [42133] 0 42133 50358 23840 101 5 0 0 syz-manager [175358.118901] [45406] 0 45406 53614 24087 109 5 0 0 syz-manager [175358.128247] [63756] 0 63756 121165 62399 170 5 0 0 syz-manager [175358.137678] [13118] 0 13118 94500 57666 188 5 0 0 syz-manager [175358.147018] [25776] 0 25776 111726 55645 190 5 0 0 syz-manager [175358.156387] [ 9893] 0 9893 62528 399 28 4 0 0 rsyslogd [175358.165527] [62996] 0 62996 76602 52378 155 5 0 0 syz-manager [175358.176208] [24856] 0 24856 180231 726 22 5 0 0 image [175358.185034] [24862] 0 24862 181323 713 23 4 0 0 exe [175358.195101] [24864] 65534 24864 17181731394 40859 231 6 0 0 exe [175358.204007] [25464] 0 25464 180167 648 21 4 0 0 image [175358.214244] [28312] 0 28312 315400 4068 65 9 0 0 image [175358.224581] [28319] 0 28319 442694 7615 175 11 0 0 exe [175358.234618] [28320] 0 28320 50715370 30115796 68984 170 0 0 exe [175358.243262] [28346] 0 28346 1 0 1 1 0 0 exe [175358.251914] [28360] 0 28360 23855 4021 52 6 0 0 exe [175358.260568] [38106] 0 38106 315048 3710 60 10 0 0 image [175358.269398] [38116] 0 38116 29440 4194 31 6 0 0 exe [175358.278055] [38152] 0 38152 4539 1 5 4 0 0 exe [175358.286703] [38163] 0 38163 773 1 5 4 0 0 exe [175358.296127] [38167] 0 38167 4576 1 5 4 0 0 exe [175358.304838] [38170] 0 38170 482 1 5 4 0 0 exe [175358.313573] [38178] 0 38178 475 1 5 4 0 0 exe [175358.322218] [38180] 0 38180 443 1 5 4 0 0 exe [175358.330862] [38197] 0 38197 4569 1 5 4 0 0 exe [175358.339526] [38204] 0 38204 4867 1 5 4 0 0 exe [175358.348193] [38250] 0 38250 6582 1 9 5 0 0 exe [175358.356840] [38257] 0 38257 6936 1 9 5 0 0 exe [175358.366869] [38267] 0 38267 6638 1 9 5 0 0 exe [175358.375527] [38335] 0 38335 6801 1 10 5 0 0 exe [175358.384220] [43215] 0 43215 179815 653 20 5 0 0 image [175358.394424] [43220] 0 43220 181323 762 23 5 0 0 exe [175358.403071] [43226] 65534 43226 17181666072 8587 113 6 0 0 exe [175358.411980] [44668] 0 44668 180167 672 21 4 0 0 image [175358.420803] [44724] 0 44724 179882 685 21 4 0 0 image [175358.429621] [44733] 0 44733 180974 685 23 5 0 0 exe [175358.438446] [44738] 65534 44738 17181653458 6848 89 6 0 0 exe [175358.447428] [45141] 0 45141 180167 668 20 5 0 0 image [175358.457626] [55571] 0 55571 179946 615 20 5 0 0 image [175358.466445] [55577] 0 55577 180234 686 21 5 0 0 image [175358.476670] [55862] 0 55862 121165 62399 170 5 0 0 syz-manager [175358.486015] [55863] 0 55863 76602 52378 155 5 0 0 syz-manager [175358.495360] [55864] 0 55864 111726 55645 190 5 0 0 syz-manager [175358.504783] Out of memory: Kill process 28320 (exe) score 946 or sacrifice child [175358.512927] Killed process 28320 (exe) total-vm:202861480kB, anon-rss:120420108kB, file-rss:0kB, shmem-rss:45188kB [175366.645578] oom_reaper: reaped process 28320 (exe), now anon-rss:0kB, file-rss:0kB, shmem-rss:45196kB [175366.693167] kvm [57642]: vcpu0, guest rIP: 0xa74064 unhandled rdmsr: 0xce [178598.208219] kvm [26880]: vcpu0, guest rIP: 0xa74064 unhandled rdmsr: 0xce [196368.150210] INFO: rcu_sched self-detected stall on CPU [196368.155762] 8-...: (5248 ticks this GP) idle=333/140000000000001/0 softirq=21787059/21787059 fqs=2382 [196368.158205] INFO: rcu_sched detected stalls on CPUs/tasks: [196368.158208] 8-...: (5248 ticks this GP) idle=333/140000000000001/0 softirq=21787059/21787059 fqs=2382 [196368.158215] (detected by 20, t=5252 jiffies, g=7979813, c=7979812, q=71359) [196368.158215] Task dump for CPU 8: [196368.158218] exe R running task 0 39721 39117 0x00000188 [196368.158222] ffff9fb081a9ac80 ffffba19a5d2be58 000000001046f000 ffffffffa0fbac26 [196368.158224] ffff9fca6fae3200 000000000001044e 000000000001046e 0000000000000000 [196368.158225] 4fd5c60d50c52f3b ffff9fca6fae3088 ffffba19a5d2bec8 ffff9fca6fae3130 [196368.158226] Call Trace: [196368.158234] [] ? unmap_mapping_range+0x116/0x140 [196368.158238] [] ? shmem_fallocate+0x30d/0x4e0 [196368.158241] [] ? vfs_fallocate+0x15a/0x220 [196368.158243] [] ? SyS_fallocate+0x43/0x80 [196368.158246] [] ? do_syscall_64+0x8d/0x100 [196368.158250] [] ? entry_SYSCALL_64_after_swapgs+0x58/0xc6 [196368.262941] (t=5278 jiffies g=7979813 c=7979812 q=71916) [196368.268784] Task dump for CPU 8: [196368.272221] exe R running task 0 39721 39117 0x00000188 [196368.279738] ffffffffa1b19a00 ffffffffa0ea953b 0000000000000008 ffffffffa1b19a00 [196368.287983] ffffffffa0f830ad ffff9fca868196c0 ffffffffa1a4fd80 0000000000000000 [196368.296116] ffffffffa1b19a00 00000000ffffffff ffffffffa0ee51ca 0000000002ac8e6b [196368.304324] Call Trace: [196368.306974] [196368.309103] [] ? sched_show_task+0xcb/0x130 [196368.315161] [] ? rcu_dump_cpu_stacks+0x92/0xb2 [196368.321451] [] ? rcu_check_callbacks+0x75a/0x8b0 [196368.328093] [] ? tick_sched_do_timer+0x30/0x30 [196368.334385] [] ? update_process_times+0x28/0x50 [196368.340767] [] ? tick_sched_handle.isra.12+0x20/0x50 [196368.347585] [] ? tick_sched_timer+0x38/0x70 [196368.362574] [] ? __hrtimer_run_queues+0xde/0x250 [196368.369055] [] ? hrtimer_interrupt+0x9c/0x1a0 [196368.375272] [] ? 0xffffffffc08da000 [196368.380617] [] ? smp_apic_timer_interrupt+0x47/0x60 [196368.387344] [] ? apic_timer_interrupt+0x9e/0xb0 [196368.393726] [196368.395858] [] ? 0xffffffffc08da000 [196368.401219] [] ? smp_call_function_many+0x1f2/0x250 [196368.407997] [] ? kvm_make_all_cpus_request+0xdd/0x110 [kvm] [196368.415435] [] ? kvm_flush_remote_tlbs+0x18/0x40 [kvm] [196368.422450] [] ? kvm_mmu_notifier_invalidate_range_start+0x56/0x80 [kvm] [196368.431005] [] ? __mmu_notifier_invalidate_range_start+0x52/0x80 [196368.438877] [] ? zap_page_range_single+0xf7/0x110 [196368.445432] [] ? unmap_mapping_range+0x116/0x140 [196368.451900] [] ? shmem_fallocate+0x30d/0x4e0 [196368.458019] [] ? vfs_fallocate+0x15a/0x220 [196368.464092] [] ? SyS_fallocate+0x43/0x80 [196368.469879] [] ? do_syscall_64+0x8d/0x100 [196368.475745] [] ? entry_SYSCALL_64_after_swapgs+0x58/0xc6 [196431.164035] INFO: rcu_sched self-detected stall on CPU [196431.169578] 8-...: (20921 ticks this GP) idle=333/140000000000001/0 softirq=21787059/21787059 fqs=9514 [196431.172026] INFO: rcu_sched detected stalls on CPUs/tasks: [196431.172031] 8-...: (20921 ticks this GP) idle=333/140000000000001/0 softirq=21787059/21787059 fqs=9514 [196431.172037] (detected by 20, t=21007 jiffies, g=7979813, c=7979812, q=254885) [196431.172038] Task dump for CPU 8: [196431.172041] exe R running task 0 39721 39117 0x00000188 [196431.172045] ffff9fb081a9ac80 ffffba19a5d2be58 000000001046f000 ffffffffa0fbac26 [196431.172047] ffff9fca6fae3200 000000000001044e 000000000001046e 0000000000000000 [196431.172049] 4fd5c60d50c52f3b ffff9fca6fae3088 ffffba19a5d2bec8 ffff9fca6fae3130 [196431.172050] Call Trace: [196431.172063] [] ? unmap_mapping_range+0x116/0x140 [196431.172067] [] ? shmem_fallocate+0x30d/0x4e0 [196431.172071] [] ? vfs_fallocate+0x15a/0x220 [196431.172073] [] ? SyS_fallocate+0x43/0x80 [196431.172076] [] ? do_syscall_64+0x8d/0x100 [196431.172079] [] ? entry_SYSCALL_64_after_swapgs+0x58/0xc6 [196431.289258] (t=21036 jiffies g=7979813 c=7979812 q=255141) [196431.295268] Task dump for CPU 8: [196431.298703] exe R running task 0 39721 39117 0x00000188 [196431.306333] ffffffffa1b19a00 ffffffffa0ea953b 0000000000000008 ffffffffa1b19a00 [196431.314473] ffffffffa0f830ad ffff9fca868196c0 ffffffffa1a4fd80 0000000000000000 [196431.322690] ffffffffa1b19a00 00000000ffffffff ffffffffa0ee51ca 0000000002bb80ac [196431.330900] Call Trace: [196431.333550] [196431.335685] [] ? sched_show_task+0xcb/0x130 [196431.341756] [] ? rcu_dump_cpu_stacks+0x92/0xb2 [196431.348149] [] ? rcu_check_callbacks+0x75a/0x8b0 [196431.354633] [] ? tick_sched_do_timer+0x30/0x30 [196431.360935] [] ? update_process_times+0x28/0x50 [196431.367322] [] ? tick_sched_handle.isra.12+0x20/0x50 [196431.374145] [] ? tick_sched_timer+0x38/0x70 [196431.380556] [] ? __hrtimer_run_queues+0xde/0x250 [196431.387029] [] ? hrtimer_interrupt+0x9c/0x1a0 [196431.393247] [] ? 0xffffffffc08da000 [196431.398850] [] ? smp_apic_timer_interrupt+0x47/0x60 [196431.405588] [] ? apic_timer_interrupt+0x9e/0xb0 [196431.412059] [196431.414304] [] ? 0xffffffffc08da000 [196431.419671] [] ? smp_call_function_many+0x1f5/0x250 [196431.426448] [] ? kvm_make_all_cpus_request+0xdd/0x110 [kvm] [196431.433897] [] ? kvm_flush_remote_tlbs+0x18/0x40 [kvm] [196431.440934] [] ? kvm_mmu_notifier_invalidate_range_start+0x56/0x80 [kvm] [196431.449700] [] ? __mmu_notifier_invalidate_range_start+0x52/0x80 [196431.460434] [] ? zap_page_range_single+0xf7/0x110 [196431.467112] [] ? unmap_mapping_range+0x116/0x140 [196431.473592] [] ? shmem_fallocate+0x30d/0x4e0 [196431.479809] [] ? vfs_fallocate+0x15a/0x220 [196431.485853] [] ? SyS_fallocate+0x43/0x80 [196431.491638] [] ? do_syscall_64+0x8d/0x100 [196431.497509] [] ? entry_SYSCALL_64_after_swapgs+0x58/0xc6 [196494.181856] INFO: rcu_sched self-detected stall on CPU [196494.185849] INFO: rcu_sched detected stalls on CPUs/tasks: [196494.185857] 8-...: (36593 ticks this GP) idle=333/140000000000001/0 softirq=21787059/21787059 fqs=16616 [196494.185858] [196494.185863] (detected by 20, t=36762 jiffies, g=7979813, c=7979812, q=392287) [196494.185864] Task dump for CPU 8: [196494.185865] exe R [196494.185866] running task [196494.185867] 0 39721 39117 0x00000188 [196494.185870] ffff9fb081a9ac80 [196494.185870] ffffba19a5d2be58 [196494.185871] 000000001046f000 [196494.185871] ffffffffa0fbac26 [196494.185872] ffff9fca6fae3200 [196494.185872] 000000000001044e [196494.185873] 000000000001046e [196494.185874] 0000000000000000 [196494.185874] 4fd5c60d50c52f3b [196494.185875] ffff9fca6fae3088 [196494.185875] ffffba19a5d2bec8 [196494.185876] ffff9fca6fae3130 [196494.185876] Call Trace: [196494.185885] [] ? unmap_mapping_range+0x116/0x140 [196494.185889] [] ? shmem_fallocate+0x30d/0x4e0 [196494.185893] [] ? vfs_fallocate+0x15a/0x220 [196494.185895] [] ? SyS_fallocate+0x43/0x80 [196494.185898] [] ? do_syscall_64+0x8d/0x100 [196494.185901] [] ? entry_SYSCALL_64_after_swapgs+0x58/0xc6 [196494.287979] 8-...: (36593 ticks this GP) idle=333/140000000000001/0 softirq=21787059/21787059 fqs=16629 [196494.297867] (t=36790 jiffies g=7979813 c=7979812 q=392551) [196494.303875] Task dump for CPU 8: [196494.307577] exe R running task 0 39721 39117 0x00000188 [196494.315108] ffffffffa1b19a00 ffffffffa0ea953b 0000000000000008 ffffffffa1b19a00 [196494.323350] ffffffffa0f830ad ffff9fca868196c0 ffffffffa1a4fd80 0000000000000000 [196494.331613] ffffffffa1b19a00 00000000ffffffff ffffffffa0ee51ca 0000000002ac840c [196494.339801] Call Trace: [196494.342550] [196494.344685] [] ? sched_show_task+0xcb/0x130 [196494.350832] [] ? rcu_dump_cpu_stacks+0x92/0xb2 [196494.357135] [] ? rcu_check_callbacks+0x75a/0x8b0 [196494.364020] [] ? tick_sched_do_timer+0x30/0x30 [196494.370416] [] ? update_process_times+0x28/0x50 [196494.376886] [] ? tick_sched_handle.isra.12+0x20/0x50 [196494.383713] [] ? tick_sched_timer+0x38/0x70 [196494.389755] [] ? __hrtimer_run_queues+0xde/0x250 [196494.396249] [] ? hrtimer_interrupt+0x9c/0x1a0 [196494.402468] [] ? 0xffffffffc08da000 [196494.408232] [] ? smp_apic_timer_interrupt+0x47/0x60 [196494.416227] [] ? apic_timer_interrupt+0x9e/0xb0 [196494.423892] [196494.426496] [] ? 0xffffffffc08da000 [196494.434556] [] ? smp_call_function_many+0x1f2/0x250 [196494.444472] [] ? kvm_make_all_cpus_request+0xdd/0x110 [kvm] [196494.452214] [] ? kvm_flush_remote_tlbs+0x18/0x40 [kvm] [196494.459553] [] ? kvm_mmu_notifier_invalidate_range_start+0x56/0x80 [kvm] [196494.469276] [] ? __mmu_notifier_invalidate_range_start+0x52/0x80 [196494.478218] [] ? zap_page_range_single+0xf7/0x110 [196494.484786] [] ? unmap_mapping_range+0x116/0x140 [196494.491262] [] ? shmem_fallocate+0x30d/0x4e0 [196494.497477] [] ? vfs_fallocate+0x15a/0x220 [196494.503432] [] ? SyS_fallocate+0x43/0x80 [196494.509211] [] ? do_syscall_64+0x8d/0x100 [196494.515070] [] ? entry_SYSCALL_64_after_swapgs+0x58/0xc6 [196497.973446] NMI watchdog: BUG: soft lockup - CPU#11 stuck for 134s! [exe:39453] [196497.981117] Modules linked in: rose nfc netrom llc2 ax25 vmw_vsock_vmci_transport vsock vmw_vmci fcrypt pcbc af_rxrpc pptp gre l2tp_ppp l2tp_netlink pppoe pppox pn_pep phonet xfrm_user nfnetlink l2tp_ip6 l2tp_ip l2tp_core ip6_udp_tunnel udp_tunnel af_key xfrm_algo ipx p8023 p8022 psnap llc sctp libcrc32c can_raw can_bcm can rfcomm hidp cmtp kernelcapi bnep af_alg vhost_net vhost macvtap macvlan hci_vhci bluetooth rfkill vfio_iommu_type1 vfio uinput uhid hid tun ppp_generic slhc loop cuse fuse btrfs xor raid6_pq sb_edac edac_core kvm_intel kvm irqbypass crct10dif_pclmul crc32_pclmul ghash_clmulni_intel intel_rapl_perf sg pvpanic evdev serio_raw button ip_tables x_tables autofs4 ext4 crc16 jbd2 crc32c_generic fscrypto ecb mbcache sd_mod virtio_scsi scsi_mod virtio_net crc32c_intel aesni_intel aes_x86_64 [196498.066846] glue_helper lrw gf128mul virtio_pci ablk_helper virtio_ring cryptd psmouse virtio i2c_piix4 [196498.076432] CPU: 11 PID: 39453 Comm: exe Not tainted 4.9.0-11-amd64 #1 Debian 4.9.189-3+deb9u2 [196498.085241] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 [196498.094660] task: ffff9fb7dd77d100 task.stack: ffffba1990ce4000 [196498.100789] RIP: 0010:[] [] native_queued_spin_lock_slowpath+0xf/0x1a0 [196498.110987] RSP: 0018:ffffba1990ce7bf0 EFLAGS: 00000202 [196498.117831] RAX: 0000000000000001 RBX: 0000000000000002 RCX: 00000000003ed5cd [196498.126114] RDX: 0000000000000001 RSI: 0000000000000001 RDI: ffff9fb2d6a48000 [196498.133553] RBP: ffff9faf3e981000 R08: 0000000000000007 R09: 8000000000000000 [196498.141217] R10: 00000000322e631a R11: 0000000000000001 R12: 00003fb12b6da000 [196498.148823] R13: 00000003fb12b6da R14: 0000000000000000 R15: 0000000000000000 [196498.156170] FS: 000000c0002c8090(0000) GS:ffff9fca868c0000(0000) knlGS:0000000000000000 [196498.164468] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 [196498.170551] CR2: 00007faef3ceda68 CR3: 00000016f14bc000 CR4: 0000000000162670 [196498.177915] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000 [196498.185872] DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400 [196498.193501] Stack: [196498.195812] ffffffffa141e2fd ffffffffc0908f1b ffffba1990ce7c27 ffffffffc09cf00c [196498.209893] 0000000000000000 000000000000225f 0100ffffc09cf018 0000000100000000 [196498.218866] 00000000003ed5cd 982c609cbd0a1ac5 ffff9faf3e981000 00003fb12b6da000 [196498.227298] Call Trace: [196498.230179] [] ? _raw_spin_lock+0x1d/0x20 [196498.241555] [] ? tdp_page_fault+0x22b/0x2c0 [kvm] [196498.248149] [] ? vmexit_fill_RSB+0xc/0x30 [kvm_intel] [196498.255093] [] ? kvm_mmu_page_fault+0x5f/0x120 [kvm] [196498.261923] [] ? vmx_handle_exit+0x1d1/0x1680 [kvm_intel] [196498.269188] [] ? vmexit_fill_RSB+0x18/0x30 [kvm_intel] [196498.276194] [] ? vmexit_fill_RSB+0xc/0x30 [kvm_intel] [196498.283104] [] ? vmexit_fill_RSB+0x18/0x30 [kvm_intel] [196498.290116] [] ? vmexit_fill_RSB+0xc/0x30 [kvm_intel] [196498.302521] [] ? vmexit_fill_RSB+0x18/0x30 [kvm_intel] [196498.309631] [] ? vmexit_fill_RSB+0xc/0x30 [kvm_intel] [196498.349360] [] ? vmexit_fill_RSB+0x18/0x30 [kvm_intel] [196498.356471] [] ? vmexit_fill_RSB+0xc/0x30 [kvm_intel] [196498.386596] [] ? vmx_vcpu_run+0x33f/0x4f0 [kvm_intel] [196498.393541] [] ? kvm_arch_vcpu_ioctl_run+0x801/0x16d0 [kvm] [196498.401003] [] ? kvm_arch_vcpu_load+0x61/0x290 [kvm] [196498.407855] [] ? recalc_sigpending+0x17/0x50 [196498.414017] [] ? kvm_vcpu_ioctl+0x315/0x5e0 [kvm] [196498.420587] [] ? signal_setup_done+0x67/0xb0 [196498.426852] [] ? __seccomp_filter+0x74/0x270 [196498.432990] [] ? do_vfs_ioctl+0xa2/0x620 [196498.438773] [] ? __audit_syscall_entry+0xaa/0xf0 [196498.445401] [] ? syscall_trace_enter+0x1ae/0x2c0 [196498.451883] [] ? SyS_ioctl+0x74/0x80 [196498.457318] [] ? do_syscall_64+0x8d/0x100 [196498.463276] [] ? entry_SYSCALL_64_after_swapgs+0x58/0xc6 [196498.470447] Code: 75 ce 48 8b 04 24 c6 03 01 48 c7 00 00 00 00 00 e9 90 fe ff ff 0f 1f 80 00 00 00 00 0f 1f 44 00 00 0f 1f 44 00 00 ba 01 00 00 00 <8b> 07 85 c0 75 0a f0 0f b1 17 85 c0 75 f2 f3 c3 f3 90 eb ec 81 [196498.498810] Sending NMI from CPU 11 to CPUs 0-10,12-63: [196498.504256] NMI backtrace for cpu 1 skipped: idling at pc 0xffffffffa141dc3e [196498.511531] NMI backtrace for cpu 33 [196498.515345] CPU: 33 PID: 39691 Comm: exe Not tainted 4.9.0-11-amd64 #1 Debian 4.9.189-3+deb9u2 [196498.524156] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 [196498.533573] task: ffff9fbfdfa0b140 task.stack: ffffba199f2fc000 [196498.539690] RIP: 0010:[] c [] native_queued_spin_lock_slowpath+0x21/0x1a0 [196498.549907] RSP: 0018:ffffba199f2ffbf0 EFLAGS: 00000202 [196498.555432] RAX: 0000000000000001 RBX: 0000000000000002 RCX: 0000000000b26eb2 [196498.562785] RDX: 0000000000000001 RSI: 0000000000000001 RDI: ffff9fb2d6a48000 [196498.570116] RBP: ffff9fca3da29b80 R08: 0000000000000007 R09: 8000000000000000 [196498.577448] R10: 0000000037aa5555 R11: 0000000000000001 R12: 00003fb12c143020 [196498.584778] R13: 00000003fb12c143 R14: 0000000000000000 R15: 0000000000000000 [196498.592110] FS: 000000c0006b6e90(0000) GS:ffff9fca86e40000(0000) knlGS:0000000000000000 [196498.600404] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 [196498.606355] CR2: 00007f102bf69a68 CR3: 00000016f14bc000 CR4: 0000000000162670 [196498.613820] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000 [196498.621155] DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400 [196498.628493] Stack: [196498.630704] ffffffffa141e2fdc ffffffffc0908f1bc ffffba199f2ffc27c ffffffffc09cf00cc [196498.639001] 0000000000000000c 000000000000225fc 0100ffffc09cf018c 0000000100000000c [196498.647303] 0000000000b26eb2c 47cbf6fcbbd154e0c ffff9fca3da29b80c 00003fb12c143020c [196498.655588] Call Trace: [196498.658233] [] ? _raw_spin_lock+0x1d/0x20 [196498.664100] [] ? tdp_page_fault+0x22b/0x2c0 [kvm] [196498.670656] [] ? vmexit_fill_RSB+0xc/0x30 [kvm_intel] [196498.677572] [] ? kvm_mmu_page_fault+0x5f/0x120 [kvm] [196498.684388] [] ? vmx_handle_exit+0x1d1/0x1680 [kvm_intel] [196498.691634] [] ? vmexit_fill_RSB+0x18/0x30 [kvm_intel] [196498.698721] [] ? vmexit_fill_RSB+0xc/0x30 [kvm_intel] [196498.705625] [] ? vmexit_fill_RSB+0x18/0x30 [kvm_intel] [196498.712608] [] ? vmexit_fill_RSB+0xc/0x30 [kvm_intel] [196498.719506] [] ? vmexit_fill_RSB+0x18/0x30 [kvm_intel] [196498.726505] [] ? vmexit_fill_RSB+0xc/0x30 [kvm_intel] [196498.733409] [] ? vmexit_fill_RSB+0x18/0x30 [kvm_intel] [196498.740407] [] ? vmexit_fill_RSB+0xc/0x30 [kvm_intel] [196498.747304] [] ? vmx_vcpu_run+0x33f/0x4f0 [kvm_intel] [196498.754214] [] ? kvm_arch_vcpu_ioctl_run+0x801/0x16d0 [kvm] [196498.761657] [] ? kvm_arch_vcpu_load+0x61/0x290 [kvm] [196498.768474] [] ? recalc_sigpending+0x17/0x50 [196498.774601] [] ? kvm_vcpu_ioctl+0x315/0x5e0 [kvm] [196498.781154] [] ? signal_setup_done+0x67/0xb0 [196498.787272] [] ? __seccomp_filter+0x74/0x270 [196498.793409] [] ? do_vfs_ioctl+0xa2/0x620 [196498.799183] [] ? __audit_syscall_entry+0xaa/0xf0 [196498.805650] [] ? syscall_trace_enter+0x1ae/0x2c0 [196498.812134] [] ? SyS_ioctl+0x74/0x80 [196498.817567] [] ? do_syscall_64+0x8d/0x100 [196498.823424] [] ? entry_SYSCALL_64_after_swapgs+0x58/0xc6 [196498.830594] 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 [196498.860934] NMI backtrace for cpu 5 skipped: idling at pc 0xffffffffa141dc3e [196498.868217] NMI backtrace for cpu 41 skipped: idling at pc 0xffffffffa141dc3e [196498.875596] NMI backtrace for cpu 24 [196498.879402] CPU: 24 PID: 47509 Comm: exe Not tainted 4.9.0-11-amd64 #1 Debian 4.9.189-3+deb9u2 [196498.888221] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 [196498.897663] task: ffff9faf3d775040 task.stack: ffffba194dfa4000 [196498.903785] RIP: 0033:[<0000000001025674>] c [<0000000001025674>] 0x1025674 [196498.911037] RSP: 002b:00007fe8807f7ca0 EFLAGS: 00000206 [196498.916648] RAX: 00007fe8b028aeb0 RBX: 00007fe7cf83cb18 RCX: 0000000000000000 [196498.923985] RDX: 00007fe8b028b000 RSI: 00007fe7cf83cb18 RDI: 0000000000000000 [196498.931323] RBP: 00007fe7700309a8 R08: 00000000023d8100 R09: 0000000000000001 [196498.938661] R10: 0000000000000000 R11: 0000000000000001 R12: 00007fe8807f7cb0 [196498.946009] R13: fffffc0000000000 R14: 00007fe770030c78 R15: 000003ffffffffff [196498.953549] FS: 00007fe8807f8700(0000) GS:ffff9fca86c00000(0000) knlGS:0000000000000000 [196498.961871] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 [196498.967813] CR2: 00007ffce97d8a00 CR3: 0000001b66d92000 CR4: 0000000000162670 [196498.975143] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000 [196498.982478] DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400 [196498.989903] NMI backtrace for cpu 56 [196498.993695] CPU: 56 PID: 39148 Comm: kvm-nx-lpage-re Not tainted 4.9.0-11-amd64 #1 Debian 4.9.189-3+deb9u2 [196499.003660] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 [196499.013195] task: ffff9fc5a591e0c0 task.stack: ffffba197e894000 [196499.019431] RIP: 0010:[] c [] native_queued_spin_lock_slowpath+0x21/0x1a0 [196499.029643] RSP: 0018:ffffba197e897e28 EFLAGS: 00000202 [196499.035151] RAX: 0000000000000001 RBX: ffff9fb2d6a48000 RCX: 00003a4eb8815ba8 [196499.042482] RDX: 0000000000000001 RSI: 0000000000000001 RDI: ffff9fb2d6a48000 [196499.049816] RBP: ffff9fc5a591e0c0 R08: 0000b299f5aebbe3 R09: 0100000001000000 [196499.057145] R10: 0000000000000000 R11: 0000000102ec07ff R12: 0000000102ec0699 [196499.064479] R13: ffffba197e897e80 R14: ffff9fc5a591e0c0 R15: ffffffffc0945984 [196499.071919] FS: 0000000000000000(0000) GS:ffff9fca87400000(0000) knlGS:0000000000000000 [196499.080338] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 [196499.086294] CR2: 00006001b05b9000 CR3: 00000017b5208000 CR4: 0000000000162670 [196499.093771] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000 [196499.101343] DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400 [196499.108718] Stack: [196499.110936] ffffffffa141e2fdc ffffffffc0904264c ffff9fb2d6a4ac10c ffff9fc5a591e0c0c [196499.119438] ffff9fb2d6a48048c 0000000100000000c ffff9fc5a591e0c0c ffffffffa1419af2c [196499.127724] 0000000000000000c 0000000000000000c 0000000000000001c ffffba197e897e80c [196499.136107] Call Trace: [196499.138750] [] ? _raw_spin_lock+0x1d/0x20 [196499.144703] [] ? kvm_nx_lpage_recovery_worker+0x144/0x2c0 [kvm] [196499.152477] [] ? schedule+0x32/0x80 [196499.158096] [] ? kvm_exit+0x80/0x80 [kvm] [196499.163953] [] ? kthread+0xd9/0xf0 [196499.169211] [] ? __switch_to_asm+0x41/0x70 [196499.175151] [] ? kthread_park+0x60/0x60 [196499.180831] [] ? ret_from_fork+0x57/0x70 [196499.186608] 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 [196499.220242] NMI backtrace for cpu 3 [196499.223949] CPU: 3 PID: 47098 Comm: exe Not tainted 4.9.0-11-amd64 #1 Debian 4.9.189-3+deb9u2 [196499.232669] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 [196499.242083] task: ffff9fb836707040 task.stack: ffffba1950358000 [196499.248205] RIP: 0010:[] c [] smp_call_function_many+0x1f2/0x250 [196499.257620] RSP: 0018:ffffba195035bca8 EFLAGS: 00000202 [196499.263129] RAX: 0000000000000003 RBX: 0000000000000200 RCX: 0000000000000012 [196499.270476] RDX: ffffda193f282c68 RSI: 0000000000000200 RDI: ffff9fca866d98c8 [196499.277806] RBP: ffff9fca866d98c8 R08: ffffffffffffffff R09: 00400000050c0000 [196499.285148] R10: 0000000000000008 R11: 0000000000000000 R12: ffff9fca866d98c0 [196499.292502] R13: ffffffffa0e6a890 R14: ffffba195035bcf0 R15: 0000000000000001 [196499.299846] FS: 00007fe8c67fc700(0000) GS:ffff9fca866c0000(0000) knlGS:0000000000000000 [196499.308131] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 [196499.314086] CR2: 00006002cb8e0000 CR3: 0000001b66d92000 CR4: 0000000000162670 [196499.321424] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000 [196499.328765] DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400 [196499.336105] Stack: [196499.338319] 0000000000019880c 0000000100000000c ffff9fb04ce04ed8c 000060019a2f1000c [196499.347563] ffff9fb04ce04ed8c ffff9fb04ce04c00c 000060019a2f0000c 0000000000000000c [196499.355855] ffffffffa0e6ad34c ffff9fb04ce04c00c 000060019a2f0000c 000060019a2f1000c [196499.364234] Call Trace: [196499.366882] [] ? native_flush_tlb_others+0x54/0x120 [196499.373612] [] ? flush_tlb_mm_range+0x92/0x140 [196499.379910] [] ? tlb_flush_mmu_tlbonly+0x5e/0xc0 [196499.386373] [] ? tlb_finish_mmu+0xf/0x40 [196499.392148] [] ? zap_page_range+0xe0/0x130 [196499.398089] [] ? __switch_to_asm+0x40/0x70 [196499.404043] [] ? __schedule+0x241/0x6f0 [196499.409810] [] ? hrtimer_start_range_ns+0x194/0x360 [196499.416537] [] ? SyS_madvise+0x3cc/0x900 [196499.422326] [] ? do_nanosleep+0x8d/0xe0 [196499.428021] [] ? do_syscall_64+0x8d/0x100 [196499.433887] [] ? entry_SYSCALL_64_after_swapgs+0x58/0xc6 [196499.441054] 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 [196499.474355] NMI backtrace for cpu 30 skipped: idling at pc 0xffffffffa141dc3e [196499.481724] NMI backtrace for cpu 52 [196499.485522] CPU: 52 PID: 39718 Comm: exe Not tainted 4.9.0-11-amd64 #1 Debian 4.9.189-3+deb9u2 [196499.494335] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 [196499.503755] task: ffff9fc2f9bf2000 task.stack: ffffba19847f0000 [196499.509868] RIP: 0010:[] c [] smp_call_function_single+0xd3/0x130 [196499.519371] RSP: 0018:ffffba19847f3c30 EFLAGS: 00000202 [196499.524891] RAX: 0000000000000000 RBX: 000000000000002a RCX: ffffda193f883420 [196499.532221] RDX: 0000000000000001 RSI: ffffba19847f3c40 RDI: ffffba19847f3c40 [196499.539638] RBP: ffffffffc09d4a50 R08: 0000000000000002 R09: ffff9faf6e527400 [196499.546968] R10: 000000000000002b R11: ffff9faf40e21338 R12: 000000000000002a [196499.554297] R13: ffff9fb535d21dc0 R14: ffff9faf40e21300 R15: 0000000000000034 [196499.561628] FS: 000000c000620790(0000) GS:ffff9fca87300000(0000) knlGS:0000000000000000 [196499.570000] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 [196499.575941] CR2: 00007ffc68de2a00 CR3: 00000016f14bc000 CR4: 0000000000162670 [196499.584331] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000 [196499.591662] DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400 [196499.598994] Stack: [196499.601203] ffff9fc2f9bf2000c 0000000000000000c ffffda193f883420c ffffffffc09d4a50c [196499.609489] ffff9fb535d26358c 0000000000000003c 5a830a4d7bad0390c 0000000000000034c [196499.618751] ffffba19847f3d90c ffffffffc09d3e9fc 0000000000000000c 0000000000000000c [196499.627039] Call Trace: [196499.629952] [] ? update_debugctlmsr+0x20/0x20 [kvm_intel] [196499.637290] [] ? vmx_vcpu_load+0x9f/0x360 [kvm_intel] [196499.644287] [] ? futex_wait+0x223/0x250 [196499.649969] [] ? __switch_to_asm+0x41/0x70 [196499.656036] [] ? __switch_to_asm+0x35/0x70 [196499.661993] [] ? __switch_to_asm+0x41/0x70 [196499.667937] [] ? __switch_to_asm+0x35/0x70 [196499.673902] [] ? __switch_to_asm+0x41/0x70 [196499.679845] [] ? __switch_to_asm+0x35/0x70 [196499.685797] [] ? __switch_to_asm+0x41/0x70 [196499.691739] [] ? __switch_to_asm+0x35/0x70 [196499.697682] [] ? __switch_to_asm+0x41/0x70 [196499.703629] [] ? __switch_to_asm+0x35/0x70 [196499.709581] [] ? __switch_to_asm+0x41/0x70 [196499.715526] [] ? __switch_to_asm+0x35/0x70 [196499.721467] [] ? __switch_to_asm+0x41/0x70 [196499.727409] [] ? __switch_to_asm+0x35/0x70 [196499.733353] [] ? __switch_to_asm+0x41/0x70 [196499.739299] [] ? __switch_to_asm+0x35/0x70 [196499.745244] [] ? __switch_to_asm+0x41/0x70 [196499.751186] [] ? kvm_arch_vcpu_load+0x46/0x290 [kvm] [196499.758000] [] ? recalc_sigpending+0x17/0x50 [196499.764126] [] ? vcpu_load+0x3c/0x50 [kvm] [196499.770090] [] ? kvm_vcpu_ioctl+0x77/0x5e0 [kvm] [196499.776552] [] ? signal_setup_done+0x67/0xb0 [196499.782675] [] ? __seccomp_filter+0x74/0x270 [196499.788789] [] ? do_vfs_ioctl+0xa2/0x620 [196499.794556] [] ? __audit_syscall_entry+0xaa/0xf0 [196499.801017] [] ? syscall_trace_enter+0x1ae/0x2c0 [196499.807488] [] ? SyS_ioctl+0x74/0x80 [196499.812912] [] ? do_syscall_64+0x8d/0x100 [196499.818767] [] ? entry_SYSCALL_64_after_swapgs+0x58/0xc6 [196499.825938] 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 [196499.856707] NMI backtrace for cpu 37 [196499.860521] CPU: 37 PID: 65501 Comm: exe Not tainted 4.9.0-11-amd64 #1 Debian 4.9.189-3+deb9u2 [196499.869765] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 [196499.879297] task: ffff9fc5a3a950c0 task.stack: ffffba194e990000 [196499.885426] RIP: 0033:[<00000000010177ab>] c [<00000000010177ab>] 0x10177ab [196499.892678] RSP: 002b:00007fed8a41cb28 EFLAGS: 00000297 [196499.898287] RAX: 00007fed7450ccd8 RBX: 00007fed7450cc58 RCX: 00007fed745113c5 [196499.905883] RDX: 000000000000fe00 RSI: 0000000000000000 RDI: 00007fed7450ccd8 [196499.913319] RBP: 0000000000000304 R08: 00007fed7451cad8 R09: 00000000000005b2 [196499.920663] R10: 0000000000000000 R11: 000000000000000b R12: 0000000000034d5b [196499.928003] R13: 00000000000005b2 R14: 0000000000000000 R15: 00007fed8a41cbe0 [196499.935351] FS: 00007fed8a41d700(0000) GS:ffff9fca86f40000(0000) knlGS:0000000000000000 [196499.943645] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 [196499.949684] CR2: 00006002eacc0000 CR3: 000000019bb22000 CR4: 0000000000162670 [196499.957023] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000 [196499.964363] DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400 [196499.971766] NMI backtrace for cpu 40 [196499.975575] CPU: 40 PID: 10785 Comm: exe Not tainted 4.9.0-11-amd64 #1 Debian 4.9.189-3+deb9u2 [196499.984818] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 [196499.994245] task: ffff9fb600394140 task.stack: ffffba197c23c000 [196500.001934] RIP: 0033:[<00000000004827fd>] c [<00000000004827fd>] 0x4827fd [196500.009108] RSP: 002b:000000c001569ca0 EFLAGS: 00000217 [196500.014619] RAX: 0000000000000000 RBX: 0000000000000001 RCX: 00000000004827d5 [196500.022041] RDX: 0000000000000000 RSI: 0000000000002a59 RDI: 000000000000001f [196500.029561] RBP: 000000c001569da0 R08: 0000000000000000 R09: 0000000000000000 [196500.036992] R10: 0000000000000000 R11: 0000000000000246 R12: 0000000000000001 [196500.044419] R13: 0000000000000001 R14: 0000000000ef9cd8 R15: 0000000000000000 [196500.052620] FS: 000000c0011ba090(0000) GS:ffff9fca87000000(0000) knlGS:0000000000000000 [196500.061012] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 [196500.066959] CR2: 00007ffce97d8a00 CR3: 00000006ee902000 CR4: 0000000000162670 [196500.074646] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000 [196500.082512] DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400 [196500.090210] NMI backtrace for cpu 8 [196500.094002] CPU: 8 PID: 39721 Comm: exe Not tainted 4.9.0-11-amd64 #1 Debian 4.9.189-3+deb9u2 [196500.102832] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 [196500.112248] task: ffff9fc397af90c0 task.stack: ffffba19a5d28000 [196500.118375] RIP: 0010:[] c [] smp_call_function_many+0x1f2/0x250 [196500.127797] RSP: 0018:ffffba19a5d2bbe0 EFLAGS: 00000202 [196500.133304] RAX: 0000000000000003 RBX: 0000000000000200 RCX: 000000000000002a [196500.140633] RDX: ffffda193f883420 RSI: 0000000000000200 RDI: ffff9fca868198c8 [196500.147966] RBP: ffff9fca868198c8 R08: ffffffff00000000 R09: 2800040080002800 [196500.155295] R10: 0000000000000008 R11: 0000000000000008 R12: ffff9fca868198c0 [196500.162632] R13: ffffffffc08da000 R14: 0000000000000000 R15: 0000000000000001 [196500.169970] FS: 000000c000620e90(0000) GS:ffff9fca86800000(0000) knlGS:0000000000000000 [196500.178262] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 [196500.184225] CR2: 0000000020006660 CR3: 00000016f14bc000 CR4: 0000000000162670 [196500.191556] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000 [196500.198888] DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400 [196500.206225] Stack: [196500.208438] 0000000000019880c 00000001c0900f40c ffffba19a5d2bc28c 0000000000000001c [196500.217596] ffff9fb2d6a48048c 00003fb02c800000c 00003fb02c821000c 0000000000000001c [196500.225886] ffffffffc08dfe3dc 2800040080002800c 0000000000000000c 0000000000000000c [196500.234183] Call Trace: [196500.236827] [] ? kvm_make_all_cpus_request+0xdd/0x110 [kvm] [196500.244264] [] ? kvm_flush_remote_tlbs+0x18/0x40 [kvm] [196500.251431] [] ? kvm_mmu_notifier_invalidate_range_start+0x56/0x80 [kvm] [196500.260111] [] ? __mmu_notifier_invalidate_range_start+0x52/0x80 [196500.267969] [] ? zap_page_range_single+0xf7/0x110 [196500.274524] [] ? unmap_mapping_range+0x116/0x140 [196500.280990] [] ? shmem_fallocate+0x30d/0x4e0 [196500.287113] [] ? vfs_fallocate+0x15a/0x220 [196500.293059] [] ? SyS_fallocate+0x43/0x80 [196500.298848] [] ? do_syscall_64+0x8d/0x100 [196500.304817] [] ? entry_SYSCALL_64_after_swapgs+0x58/0xc6 [196500.311986] 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 [196500.342518] NMI backtrace for cpu 20 [196500.346336] CPU: 20 PID: 40448 Comm: exe Not tainted 4.9.0-11-amd64 #1 Debian 4.9.189-3+deb9u2 [196500.355155] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 [196500.367350] task: ffff9fbc1d77a100 task.stack: ffffba19a9de8000 [196500.378344] RIP: 0010:[] c [] native_queued_spin_lock_slowpath+0x21/0x1a0 [196500.388551] RSP: 0018:ffffba19a9debbf0 EFLAGS: 00000202 [196500.394072] RAX: 0000000000000001 RBX: 0000000000000002 RCX: 00000000003d4482 [196500.401419] RDX: 0000000000000001 RSI: 0000000000000001 RDI: ffff9fb2d6a48000 [196500.408753] RBP: ffff9faf3e1ca000 R08: 0000000000000007 R09: 8000000000000000 [196500.416089] R10: 00000000133c80a0 R11: 0000000000000001 R12: 00003fb13d7f8d60 [196500.423421] R13: 00000003fb13d7f8 R14: 0000000000000000 R15: 0000000000000000 [196500.430776] FS: 000000c0002c9210(0000) GS:ffff9fca86b00000(0000) knlGS:ffff80c0009db200 [196500.439075] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 [196500.445018] CR2: 00007ffc68de2a00 CR3: 00000016f14bc000 CR4: 0000000000162670 [196500.452364] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000 [196500.459784] DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400 [196500.467139] Stack: [196500.469348] ffffffffa141e2fdc ffffffffc0908f1bc ffffba19a9debc27c ffffffffc09cf00cc [196500.477808] 0000000000000000c 000000000000225fc 0100ffffc09cf018c 0000000100000000c [196500.486117] 00000000003d4482c cdb988f5365913ebc ffff9faf3e1ca000c 00003fb13d7f8d60c [196500.494408] Call Trace: [196500.497053] [] ? _raw_spin_lock+0x1d/0x20 [196500.502918] [] ? tdp_page_fault+0x22b/0x2c0 [kvm] [196500.509477] [] ? vmexit_fill_RSB+0xc/0x30 [kvm_intel] [196500.516377] [] ? kvm_mmu_page_fault+0x5f/0x120 [kvm] [196500.523191] [] ? vmx_handle_exit+0x1d1/0x1680 [kvm_intel] [196500.530541] [] ? vmexit_fill_RSB+0x18/0x30 [kvm_intel] [196500.537532] [] ? vmexit_fill_RSB+0xc/0x30 [kvm_intel] [196500.544430] [] ? vmexit_fill_RSB+0x18/0x30 [kvm_intel] [196500.551430] [] ? vmexit_fill_RSB+0xc/0x30 [kvm_intel] [196500.558330] [] ? vmexit_fill_RSB+0x18/0x30 [kvm_intel] [196500.565339] [] ? vmexit_fill_RSB+0xc/0x30 [kvm_intel] [196500.572332] [] ? vmexit_fill_RSB+0x18/0x30 [kvm_intel] [196500.579336] [] ? vmexit_fill_RSB+0xc/0x30 [kvm_intel] [196500.586251] [] ? vmx_vcpu_run+0x33f/0x4f0 [kvm_intel] [196500.593154] [] ? kvm_arch_vcpu_ioctl_run+0x801/0x16d0 [kvm] [196500.600572] [] ? kvm_arch_vcpu_load+0x61/0x290 [kvm] [196500.607382] [] ? recalc_sigpending+0x17/0x50 [196500.613498] [] ? kvm_vcpu_ioctl+0x315/0x5e0 [kvm] [196500.620161] [] ? signal_setup_done+0x67/0xb0 [196500.626280] [] ? __seccomp_filter+0x74/0x270 [196500.632405] [] ? do_vfs_ioctl+0xa2/0x620 [196500.638175] [] ? __audit_syscall_entry+0xaa/0xf0 [196500.644639] [] ? syscall_trace_enter+0x1ae/0x2c0 [196500.651107] [] ? SyS_ioctl+0x74/0x80 [196500.656529] [] ? do_syscall_64+0x8d/0x100 [196500.662390] [] ? entry_SYSCALL_64_after_swapgs+0x58/0xc6 [196500.669553] 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 [196500.699893] NMI backtrace for cpu 23 [196500.703708] CPU: 23 PID: 56341 Comm: exe Not tainted 4.9.0-11-amd64 #1 Debian 4.9.189-3+deb9u2 [196500.712521] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 [196500.721947] task: ffff9fca75d4d080 task.stack: ffffba1964b34000 [196500.728062] RIP: 0010:[] c [] do_syscall_64+0xd1/0x100 [196500.736608] RSP: 0018:ffffba1964b37f40 EFLAGS: 00000046 [196500.742119] RAX: 0000000000000180 RBX: ffffba1964b37f58 RCX: 00000000c0000100 [196500.749450] RDX: 00000000000001c2 RSI: ffff9fca75d4dac0 RDI: ffff9fca86bd8980 [196500.756820] RBP: ffff9fca75d4d080 R08: ffff9fbd0536cc40 R09: 0000000000001000 [196500.764173] R10: 0000000000000000 R11: ffff9fbd0536c180 R12: 0000000000000000 [196500.771504] R13: 0000000000000000 R14: 0000000000000000 R15: 0000000000000000 [196500.778833] FS: 000000c001183210(0000) GS:ffff9fca86bc0000(0000) knlGS:0000000000000000 [196500.787133] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 [196500.793074] CR2: 00006001eacf0000 CR3: 000000027feec000 CR4: 0000000000162670 [196500.800403] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000 [196500.807732] DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400 [196500.815062] Stack: [196500.817297] 0000000000000000c 0000000000000000c ffffffffa141e3cec 0000000000000000c [196500.825583] 0000000000ef9cd8c 0000000000000000c 0000000000000018c 000000c001609da0c [196500.833879] 0000000000000001c 0000000000000246c 0000000000000000c 0000000000000000c [196500.842165] Call Trace: [196500.844809] [] ? entry_SYSCALL_64_after_swapgs+0x58/0xc6 [196500.851965] Code: c66 c0f c1f c44 c00 c00 c65 c48 c8b c2c c25 cc0 cfb c00 c00 c48 c8b c75 c00 cf7 cc6 c0e c18 c00 c00 c75 c20 c83 c65 c08 cf9 c0f c1f c44 c00 c00 c0f c00 c2d c5f cd7 c7f c00 c<5b> c5d cc3 ce8 c57 cf6 cff cff c48 c89 cc2 ce9 c4b cff cff cff c48 c89 cdf ce8 cd7 c [196500.882303] NMI backtrace for cpu 0 [196500.886024] CPU: 0 PID: 5932 Comm: exe Not tainted 4.9.0-11-amd64 #1 Debian 4.9.189-3+deb9u2 [196500.894668] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 [196500.904091] task: ffff9fc3061ef100 task.stack: ffffba1961e44000 [196500.910213] RIP: 0033:[<000000000102515f>] c [<000000000102515f>] 0x102515f [196500.917469] RSP: 002b:00007fecafffec80 EFLAGS: 00000246 [196500.922982] RAX: 0000000000002001 RBX: 00007fed3c081aa8 RCX: 00007fec85487000 [196500.930315] RDX: 00007fec85487200 RSI: 0000000000000000 RDI: 00007fecafffec80 [196500.937644] RBP: 00007fecafffec80 R08: 00007fed043545d8 R09: 0000000000000001 [196500.944977] R10: 0000000000000000 R11: 0000000000000001 R12: 00007fed3c081ac8 [196500.952311] R13: 00007fed043545d8 R14: fffffc0000000000 R15: 000003ffffffffff [196500.959649] FS: 00007fecaffff700(0000) GS:ffff9fca86600000(0000) knlGS:0000000000000000 [196500.967962] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 [196500.973907] CR2: 0000000000c72958 CR3: 000000019bb22000 CR4: 0000000000162670 [196500.981248] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000 [196500.988619] DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400 [196500.995960] NMI backtrace for cpu 61 [196500.999779] CPU: 61 PID: 39700 Comm: exe Not tainted 4.9.0-11-amd64 #1 Debian 4.9.189-3+deb9u2 [196501.008608] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 [196501.018034] task: ffff9fbfbb401140 task.stack: ffffba19a02dc000 [196501.024152] RIP: 0010:[] c [] native_queued_spin_lock_slowpath+0x21/0x1a0 [196501.034347] RSP: 0018:ffffba19a02dfbf0 EFLAGS: 00000202 [196501.039855] RAX: 0000000000000001 RBX: 0000000000000002 RCX: 0000000000c7fb3f [196501.047186] RDX: 0000000000000001 RSI: 0000000000000001 RDI: ffff9fb2d6a48000 [196501.055564] RBP: ffff9fc302c82180 R08: 0000000000000007 R09: 8000000000000000 [196501.062900] R10: 00000003ffffffff R11: 0400000000000000 R12: 00003fb12ca79000 [196501.070229] R13: 00000003fb12ca79 R14: 0000000000000000 R15: 0000000000000000 [196501.077568] FS: 000000c000980090(0000) GS:ffff9fca87540000(0000) knlGS:0000000000000000 [196501.085853] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 [196501.091795] CR2: 0000000000c67210 CR3: 00000016f14bc000 CR4: 0000000000162670 [196501.099124] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000 [196501.106458] DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400 [196501.113787] Stack: [196501.116005] ffffffffa141e2fdc ffffffffc0908f1bc ffffba19a02dfc27c ffffffffc09cf00cc [196501.124290] 0000000000000000c 000000000000225fc 0100ffffc09cf018c 0000000100000000c [196501.132599] 0000000000c7fb3fc df381d5320de05c4c ffff9fc302c82180c 00003fb12ca79000c [196501.140885] Call Trace: [196501.143534] [] ? _raw_spin_lock+0x1d/0x20 [196501.149394] [] ? tdp_page_fault+0x22b/0x2c0 [kvm] [196501.155955] [] ? vmexit_fill_RSB+0xc/0x30 [kvm_intel] [196501.162969] [] ? kvm_mmu_page_fault+0x5f/0x120 [kvm] [196501.169784] [] ? vmx_handle_exit+0x1d1/0x1680 [kvm_intel] [196501.177029] [] ? vmexit_fill_RSB+0x18/0x30 [kvm_intel] [196501.184020] [] ? vmexit_fill_RSB+0xc/0x30 [kvm_intel] [196501.190918] [] ? vmexit_fill_RSB+0x18/0x30 [kvm_intel] [196501.197908] [] ? vmexit_fill_RSB+0xc/0x30 [kvm_intel] [196501.204815] [] ? vmexit_fill_RSB+0x18/0x30 [kvm_intel] [196501.211798] [] ? vmexit_fill_RSB+0xc/0x30 [kvm_intel] [196501.218694] [] ? vmexit_fill_RSB+0x18/0x30 [kvm_intel] [196501.225677] [] ? vmexit_fill_RSB+0xc/0x30 [kvm_intel] [196501.232574] [] ? vmx_vcpu_run+0x33f/0x4f0 [kvm_intel] [196501.239483] [] ? kvm_arch_vcpu_ioctl_run+0x801/0x16d0 [kvm] [196501.246993] [] ? kvm_arch_vcpu_load+0x100/0x290 [kvm] [196501.253889] [] ? __switch_to_asm+0x35/0x70 [196501.259834] [] ? __switch_to_asm+0x41/0x70 [196501.265775] [] ? kvm_vcpu_ioctl+0x315/0x5e0 [kvm] [196501.272326] [] ? __switch_to_asm+0x41/0x70 [196501.278799] [] ? __switch_to_asm+0x35/0x70 [196501.284753] [] ? __switch_to_asm+0x41/0x70 [196501.290699] [] ? __switch_to_asm+0x35/0x70 [196501.296648] [] ? __seccomp_filter+0x74/0x270 [196501.302767] [] ? __switch_to+0x1ef/0x670 [196501.308540] [] ? do_vfs_ioctl+0xa2/0x620 [196501.314312] [] ? __audit_syscall_entry+0xaa/0xf0 [196501.320774] [] ? syscall_trace_enter+0x1ae/0x2c0 [196501.327238] [] ? SyS_ioctl+0x74/0x80 [196501.332659] [] ? do_syscall_64+0x8d/0x100 [196501.338602] [] ? entry_SYSCALL_64_after_swapgs+0x58/0xc6 [196501.345764] 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 [196501.398500] NMI backtrace for cpu 29 [196501.402290] CPU: 29 PID: 57111 Comm: exe Not tainted 4.9.0-11-amd64 #1 Debian 4.9.189-3+deb9u2 [196501.411111] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 [196501.420524] task: ffff9fc2f19de0c0 task.stack: ffffba1967548000 [196501.426652] RIP: 0033:[<00007ffc7f7cca5d>] c [<00007ffc7f7cca5d>] 0x7ffc7f7cca5d [196501.434352] RSP: 002b:000000c0000f3ee8 EFLAGS: 00000246 [196501.439859] RAX: 0000000000000000 RBX: 00007ffc7f7ca080 RCX: 0000000000000017 [196501.447189] RDX: 0000000000000000 RSI: 000000c0000f3f10 RDI: 000000c0000f3ee4 [196501.454517] RBP: 000000c0000f3f00 R08: 0000000000000528 R09: 0000cd85dd729d58 [196501.461848] R10: 0002bfa06c23c262 R11: 0000000000000001 R12: 0000000005bdaaa4 [196501.469199] R13: 000000c0000f3ee4 R14: 0000000000efa234 R15: 0000000000000000 [196501.476529] FS: 000000c0000e4090(0000) GS:ffff9fca86d40000(0000) knlGS:0000000000000000 [196501.484900] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 [196501.490843] CR2: 00006000bc790000 CR3: 00000006ee902000 CR4: 0000000000162670 [196501.498172] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000 [196501.505501] DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400 [196501.512839] NMI backtrace for cpu 47 skipped: idling at pc 0xffffffffa141dc3e [196501.520216] NMI backtrace for cpu 25 [196501.524018] CPU: 25 PID: 54322 Comm: exe Not tainted 4.9.0-11-amd64 #1 Debian 4.9.189-3+deb9u2 [196501.532827] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 [196501.542243] task: ffff9fb5d6e68000 task.stack: ffffba19527f0000 [196501.548361] RIP: 0010:[] c [] migrate_task_rq_fair+0x27/0x50 [196501.558124] RSP: 0018:ffff9fca86c43eb8 EFLAGS: 00000083 [196501.563637] RAX: 00006626deffec38 RBX: ffff9fb2faa8a0c0 RCX: 0000000000000207 [196501.570970] RDX: ffffffffffffff1d RSI: 000000000000001d RDI: ffff9fb2faa8a0c0 [196501.578302] RBP: 000000000000001d R08: 0000cd85dd727a9e R09: ffffffffffffffff [196501.585723] R10: 0000000000000008 R11: 0000000000000000 R12: 000000000000001d [196501.593160] R13: 0000000000000004 R14: 0000000000000046 R15: ffff9fb2faa8a3f0 [196501.600506] FS: 000000c000726090(0000) GS:ffff9fca86c40000(0000) knlGS:0000000000000000 [196501.608792] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 [196501.614736] CR2: 00006001a4440000 CR3: 000000027feec000 CR4: 0000000000162670 [196501.622066] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000 [196501.629402] DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400 [196501.636822] Stack: [196501.639046] ffff9fb2faa8a0c0c ffffffffa0ea6648c ffff9fb2faa8a0c0c 000000000000001dc [196501.647332] ffff9fb2faa8a7e4c 0000000000000004c ffffffffa0ea6de8c 0000000000000020c [196501.655617] 0000000000018980c 0000000000000282c 75f6cd511d62e616c ffffba199e87bda0c [196501.663904] Call Trace: [196501.666549] d [] ? set_task_cpu+0x68/0x190 [196501.673099] [] ? try_to_wake_up+0x128/0x3c0 [196501.679126] [] ? __hrtimer_init+0xa0/0xa0 [196501.684980] [] ? hrtimer_wakeup+0x1e/0x30 [196501.690834] [] ? __hrtimer_run_queues+0xde/0x250 [196501.697297] [] ? hrtimer_interrupt+0x9c/0x1a0 [196501.703585] [] ? smp_apic_timer_interrupt+0x47/0x60 [196501.710341] [] ? apic_timer_interrupt+0x9e/0xb0 [196501.716720] d [] ? wait_consider_task+0x29/0xb70 [196501.723803] [] ? wait_consider_task+0x104/0xb70 [196501.730195] [] ? audit_comparator+0x1b/0x60 [196501.736227] [] ? do_wait+0x173/0x240 [196501.741649] [] ? SyS_wait4+0x87/0x110 [196501.747422] [] ? task_stopped_code+0x50/0x50 [196501.753538] [] ? do_syscall_64+0x8d/0x100 [196501.759395] [] ? entry_SYSCALL_64_after_swapgs+0x58/0xc6 [196501.766572] Code: cff cff c66 c90 c0f c1f c44 c00 c00 c53 c48 c8b c47 c10 c48 c89 cfb c48 c3d c00 c01 c00 c00 c75 c12 c48 c8b c87 cd0 c01 c00 c00 c48 c8b c40 c20 c48 c29 c87 cd0 c00 c00 c00 c<48> c8d cbb c80 c00 c00 c00 ce8 c1d cfe cff cff c48 cc7 c83 c00 c02 c00 c00 c00 c00 c [196501.796912] NMI backtrace for cpu 60 skipped: idling at pc 0xffffffffa141dc3e [196501.804290] NMI backtrace for cpu 17 [196501.808093] CPU: 17 PID: 8176 Comm: exe Not tainted 4.9.0-11-amd64 #1 Debian 4.9.189-3+deb9u2 [196501.816813] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 [196501.826226] task: ffff9faf4abfd000 task.stack: ffffba1974320000 [196501.832354] RIP: 0010:[] c [] vmx_complete_atomic_exit.constprop.93+0x5e/0x80 [kvm_intel] [196501.843948] RSP: 0018:ffffba1974323ce0 EFLAGS: 00000046 [196501.849477] RAX: 0000000080000200 RBX: ffff9fc6b5dea300 RCX: ffff9fc6b5dea300 [196501.856807] RDX: 0000000080000202 RSI: 0000000000000000 RDI: ffff9fc6b5dea300 [196501.864137] RBP: ffff9fc6b5dea300 R08: 0000000000000000 R09: 0000000000000000 [196501.871477] R10: 0000000000000000 R11: 0000000000000000 R12: 0000000000000000 [196501.878815] R13: 0000000000000000 R14: 0000000000000000 R15: 0000000000000000 [196501.886148] FS: 000000c0015ee090(0000) GS:ffff9fca86a40000(0000) knlGS:ffff80c001e5a480 [196501.894441] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 [196501.900386] CR2: 00000000000002d8 CR3: 000000022c3c8000 CR4: 0000000000162670 [196501.907718] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000 [196501.915047] DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400 [196501.922382] Stack: [196501.924592] ffffffffc09cf018c 0000000000000000c ffffffffc09e0ba7c ffff9fc6b5dea300c [196501.932877] 0000000000000000c ffff9fc6b5dea300c ffffba1974323de8c 0000000000000000c [196501.941170] 0000000000231788c 0000000000000000c 0000000000000000c ffffffffc08fa25fc [196501.949465] Call Trace: [196501.952110] [] ? vmexit_fill_RSB+0x18/0x30 [kvm_intel] [196501.959109] [] ? vmx_vcpu_run+0x337/0x4f0 [kvm_intel] [196501.966003] [] ? kvm_arch_vcpu_ioctl_run+0x6df/0x16d0 [kvm] [196501.973420] [] ? kvm_arch_vcpu_load+0x61/0x290 [kvm] [196501.980229] [] ? recalc_sigpending+0x17/0x50 [196501.986345] [] ? kvm_vcpu_ioctl+0x315/0x5e0 [kvm] [196501.992904] [] ? signal_setup_done+0x67/0xb0 [196501.999120] [] ? __seccomp_filter+0x74/0x270 [196502.005281] [] ? do_vfs_ioctl+0xa2/0x620 [196502.011048] [] ? __audit_syscall_entry+0xaa/0xf0 [196502.017535] [] ? syscall_trace_enter+0x1ae/0x2c0 [196502.024002] [] ? SyS_ioctl+0x74/0x80 [196502.029426] [] ? do_syscall_64+0x8d/0x100 [196502.035287] [] ? entry_SYSCALL_64_after_swapgs+0x58/0xc6 [196502.042445] Code: c00 c81 ce2 cff c07 c00 c80 c81 cfa c12 c03 c00 c80 c74 c29 c25 c00 c07 c00 c80 c3d c00 c02 c00 c80 c74 c06 c48 c83 cc4 c08 c5b cc3 c48 c89 cdf ce8 c14 c21 cf1 cff ccd c02 c<48> c83 cc4 c08 c48 c89 cdf c5b ce9 c15 c21 cf1 cff c48 c89 c04 c24 ce8 c4c cd7 cff c [196502.073047] NMI backtrace for cpu 59 [196502.076850] CPU: 59 PID: 63962 Comm: exe Not tainted 4.9.0-11-amd64 #1 Debian 4.9.189-3+deb9u2 [196502.085858] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 [196502.095290] task: ffff9fc4ffdef100 task.stack: ffffba1964bb4000 [196502.101416] RIP: 0010:[] c [] native_queued_spin_lock_slowpath+0xf/0x1a0 [196502.111525] RSP: 0018:ffffba1964bb7bf0 EFLAGS: 00000202 [196502.117043] RAX: 0000000000000001 RBX: 0000000000000002 RCX: 0000000000ff6052 [196502.124480] RDX: 0000000000000001 RSI: 0000000000000001 RDI: ffff9fb2d6a48000 [196502.131833] RBP: ffff9fb1b6168440 R08: 0000000000000007 R09: 8000000000000000 [196502.139169] R10: 0000000000000000 R11: 0000000000000001 R12: 00003fb12ca0b000 [196502.146503] R13: 00000003fb12ca0b R14: 0000000000000000 R15: 0000000000000000 [196502.153835] FS: 000000c0017b6410(0000) GS:ffff9fca874c0000(0000) knlGS:0000000000000000 [196502.162121] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 [196502.168690] CR2: 0000000000c68448 CR3: 00000016f14bc000 CR4: 0000000000162670 [196502.176028] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000 [196502.183368] DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400 [196502.190698] Stack: [196502.192906] ffffffffa141e2fdc ffffffffc0908f1bc ffffba1964bb7c27c ffffffffc09cf00cc [196502.201203] 0000000000000000c 000000000000225fc 0100ffffc09cf018c 0000000100000000c [196502.209488] 0000000000ff6052c ff880369fcb7f303c ffff9fb1b6168440c 00003fb12ca0b000c [196502.218121] Call Trace: [196502.220764] [] ? _raw_spin_lock+0x1d/0x20 [196502.226618] [] ? tdp_page_fault+0x22b/0x2c0 [kvm] [196502.243330] [] ? vmexit_fill_RSB+0xc/0x30 [kvm_intel] [196502.250237] [] ? kvm_mmu_page_fault+0x5f/0x120 [kvm] [196502.257053] [] ? vmx_handle_exit+0x1d1/0x1680 [kvm_intel] [196502.264303] [] ? vmexit_fill_RSB+0x18/0x30 [kvm_intel] [196502.271290] [] ? vmexit_fill_RSB+0xc/0x30 [kvm_intel] [196502.278187] [] ? vmexit_fill_RSB+0x18/0x30 [kvm_intel] [196502.285180] [] ? vmexit_fill_RSB+0xc/0x30 [kvm_intel] [196502.292097] [] ? vmexit_fill_RSB+0x18/0x30 [kvm_intel] [196502.299082] [] ? vmexit_fill_RSB+0xc/0x30 [kvm_intel] [196502.306074] [] ? vmexit_fill_RSB+0x18/0x30 [kvm_intel] [196502.313056] [] ? vmexit_fill_RSB+0xc/0x30 [kvm_intel] [196502.319960] [] ? vmx_vcpu_run+0x33f/0x4f0 [kvm_intel] [196502.326865] [] ? kvm_arch_vcpu_ioctl_run+0x801/0x16d0 [kvm] [196502.334290] [] ? kvm_arch_vcpu_load+0x61/0x290 [kvm] [196502.341198] [] ? recalc_sigpending+0x17/0x50 [196502.347314] [] ? kvm_vcpu_ioctl+0x315/0x5e0 [kvm] [196502.353873] [] ? signal_setup_done+0x67/0xb0 [196502.359986] [] ? __seccomp_filter+0x74/0x270 [196502.366099] [] ? do_vfs_ioctl+0xa2/0x620 [196502.371867] [] ? __audit_syscall_entry+0xaa/0xf0 [196502.378330] [] ? syscall_trace_enter+0x1ae/0x2c0 [196502.384792] [] ? SyS_ioctl+0x74/0x80 [196502.390226] [] ? do_syscall_64+0x8d/0x100 [196502.396079] [] ? entry_SYSCALL_64_after_swapgs+0x58/0xc6 [196502.403236] Code: c75 cce c48 c8b c04 c24 cc6 c03 c01 c48 cc7 c00 c00 c00 c00 c00 ce9 c90 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 c<8b> c07 c85 cc0 c75 c0a cf0 c0f cb1 c17 c85 cc0 c75 cf2 cf3 cc3 cf3 c90 ceb cec c81 c [196502.433576] NMI backtrace for cpu 55 [196502.437404] CPU: 55 PID: 39705 Comm: exe Not tainted 4.9.0-11-amd64 #1 Debian 4.9.189-3+deb9u2 [196502.446211] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 [196502.455892] task: ffff9fca771d70c0 task.stack: ffffba19a0998000 [196502.462125] RIP: 0010:[] c [] native_queued_spin_lock_slowpath+0x21/0x1a0 [196502.472324] RSP: 0018:ffffba19a099bbf0 EFLAGS: 00000202 [196502.477831] RAX: 0000000000000001 RBX: 0000000000000000 RCX: 0000000000c21918 [196502.485255] RDX: 0000000000000001 RSI: 0000000000000001 RDI: ffff9fb2d6a48000 [196502.492774] RBP: ffff9fc0b7ab1f00 R08: 0000000000000007 R09: 8000000000000000 [196502.500104] R10: 00000003ffffffff R11: 0400000000000000 R12: 00003fb13d1891d0 [196502.507435] R13: 00000003fb13d189 R14: 0000000000000000 R15: 0000000000000000 [196502.514766] FS: 000000c000a98090(0000) GS:ffff9fca873c0000(0000) knlGS:ffff80c0005c1200 [196502.523065] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 [196502.529006] CR2: 00007ffc68de2a00 CR3: 00000016f14bc000 CR4: 0000000000162670 [196502.536337] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000 [196502.543683] DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400 [196502.551026] Stack: [196502.553248] ffffffffa141e2fdc ffffffffc0908f1bc ffffba19a099bc27c ffffffffc09cf00cc [196502.561542] 0000000100000000c 000000000000225fc 0001ffffc09cf018c 0000000100000000c [196502.569944] 0000000000c21918c 27c937d4df5c0bc9c ffff9fc0b7ab1f00c 00003fb13d1891d0c [196502.578236] Call Trace: [196502.580907] [] ? _raw_spin_lock+0x1d/0x20 [196502.586772] [] ? tdp_page_fault+0x22b/0x2c0 [kvm] [196502.593438] [] ? vmexit_fill_RSB+0xc/0x30 [kvm_intel] [196502.600342] [] ? kvm_mmu_page_fault+0x5f/0x120 [kvm] [196502.607336] [] ? vmx_handle_exit+0x1d1/0x1680 [kvm_intel] [196502.614593] [] ? vmexit_fill_RSB+0x18/0x30 [kvm_intel] [196502.621586] [] ? vmexit_fill_RSB+0xc/0x30 [kvm_intel] [196502.628488] [] ? vmexit_fill_RSB+0x18/0x30 [kvm_intel] [196502.635472] [] ? vmexit_fill_RSB+0xc/0x30 [kvm_intel] [196502.642370] [] ? vmexit_fill_RSB+0x18/0x30 [kvm_intel] [196502.649354] [] ? vmexit_fill_RSB+0xc/0x30 [kvm_intel] [196502.656249] [] ? vmexit_fill_RSB+0x18/0x30 [kvm_intel] [196502.663235] [] ? vmexit_fill_RSB+0xc/0x30 [kvm_intel] [196502.670159] [] ? vmx_vcpu_run+0x33f/0x4f0 [kvm_intel] [196502.677058] [] ? kvm_arch_vcpu_ioctl_run+0x801/0x16d0 [kvm] [196502.684477] [] ? kvm_arch_vcpu_load+0x100/0x290 [kvm] [196502.691392] [] ? recalc_sigpending+0x17/0x50 [196502.697509] [] ? kvm_vcpu_ioctl+0x315/0x5e0 [kvm] [196502.704072] [] ? signal_setup_done+0x67/0xb0 [196502.710192] [] ? __seccomp_filter+0x74/0x270 [196502.716306] [] ? do_vfs_ioctl+0xa2/0x620 [196502.722087] [] ? __audit_syscall_entry+0xaa/0xf0 [196502.728581] [] ? syscall_trace_enter+0x1ae/0x2c0 [196502.735047] [] ? SyS_ioctl+0x74/0x80 [196502.740469] [] ? do_syscall_64+0x8d/0x100 [196502.746325] [] ? entry_SYSCALL_64_after_swapgs+0x58/0xc6 [196502.753505] 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 [196502.784127] NMI backtrace for cpu 35 [196502.788096] CPU: 35 PID: 55099 Comm: exe Not tainted 4.9.0-11-amd64 #1 Debian 4.9.189-3+deb9u2 [196502.796914] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 [196502.806361] task: ffff9fc6f08b7100 task.stack: ffffba195fb98000 [196502.812500] RIP: 0033:[<00000000006fd975>] c [<00000000006fd975>] 0x6fd975 [196502.819670] RSP: 002b:000000c42d735198 EFLAGS: 00000216 [196502.825178] RAX: 0000000000000000 RBX: 0000000000000000 RCX: 000000c421410140 [196502.832513] RDX: 0000000001ca2e60 RSI: 0000000000000038 RDI: 000000c424d60f40 [196502.839849] RBP: 000000c42d7352d0 R08: 0000000000000000 R09: 0000000000000000 [196502.847416] R10: 00000000009ee77b R11: 0000000000000008 R12: 0000000000000000 [196502.854747] R13: 00000000000000f3 R14: 0000000000000033 R15: 0000000000000080 [196502.862078] FS: 000000c42d90a890(0000) GS:ffff9fca86ec0000(0000) knlGS:0000000000000000 [196502.870374] CS: 0010 DS: 0000 ES: 0000 CR0: 000000008