D0314 13:02:00.758344 40179 parameters.go:238] Clock(Monotonic): error: 14 ns, adjusted frequency from 2299774457 Hz to 2299774520 Hz D0314 13:02:00.758682 40179 parameters.go:238] Clock(Realtime): error: 41 ns, adjusted frequency from 2299774474 Hz to 2299774595 Hz D0314 13:02:00.758827 40179 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:327085121241002 monotonicBaseRef:3000173815 monotonicFrequency:2299774520 realtimeReady:1 realtimeBaseCycles:327085122058490 realtimeBaseRef:1584190920758676017 realtimeFrequency:2299774595} D0314 13:02:01.758853 40179 parameters.go:238] Clock(Monotonic): error: 434 ns, adjusted frequency from 2299774520 Hz to 2299775250 Hz D0314 13:02:01.759096 40179 parameters.go:238] Clock(Realtime): error: 289 ns, adjusted frequency from 2299774595 Hz to 2299775065 Hz D0314 13:02:01.759212 40179 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:327087422160103 monotonicBaseRef:4000671507 monotonicFrequency:2299775250 realtimeReady:1 realtimeBaseCycles:327087422783673 realtimeBaseRef:1584190921759089356 realtimeFrequency:2299775065} D0314 13:02:02.758595 40179 parameters.go:238] Clock(Monotonic): error: 227 ns, adjusted frequency from 2299775250 Hz to 2299774857 Hz D0314 13:02:02.758863 40179 parameters.go:238] Clock(Realtime): error: 282 ns, adjusted frequency from 2299775065 Hz to 2299775099 Hz D0314 13:02:02.759037 40179 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:327089721381843 monotonicBaseRef:5000430826 monotonicFrequency:2299774857 realtimeReady:1 realtimeBaseCycles:327089722001752 realtimeBaseRef:1584190922758847164 realtimeFrequency:2299775099} D0314 13:02:03.760571 40179 parameters.go:238] Clock(Monotonic): error: -119 ns, adjusted frequency from 2299774857 Hz to 2299774256 Hz D0314 13:02:03.760845 40179 parameters.go:238] Clock(Realtime): error: -88 ns, adjusted frequency from 2299775099 Hz to 2299774266 Hz D0314 13:02:03.760949 40179 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:327092025656022 monotonicBaseRef:6002387244 monotonicFrequency:2299774256 realtimeReady:1 realtimeBaseCycles:327092026354190 realtimeBaseRef:1584190923760837506 realtimeFrequency:2299774266} D0314 13:02:04.759244 40179 parameters.go:238] Clock(Monotonic): error: 11 ns, adjusted frequency from 2299774256 Hz to 2299774501 Hz D0314 13:02:04.759504 40179 parameters.go:238] Clock(Realtime): error: -3 ns, adjusted frequency from 2299774266 Hz to 2299774480 Hz D0314 13:02:04.759626 40179 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:327094322413117 monotonicBaseRef:7001075306 monotonicFrequency:2299774501 realtimeReady:1 realtimeBaseCycles:327094323050113 realtimeBaseRef:1584190924759498964 realtimeFrequency:2299774480} D0314 13:02:05.758465 40179 parameters.go:238] Clock(Monotonic): error: 58 ns, adjusted frequency from 2299774501 Hz to 2299774701 Hz D0314 13:02:05.758743 40179 parameters.go:238] Clock(Realtime): error: 82 ns, adjusted frequency from 2299774480 Hz to 2299774638 Hz D0314 13:02:05.758918 40179 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:327096620353094 monotonicBaseRef:8000277608 monotonicFrequency:2299774701 realtimeReady:1 realtimeBaseCycles:327096621064798 realtimeBaseRef:1584190925758733760 realtimeFrequency:2299774638} D0314 13:02:06.759345 40179 parameters.go:238] Clock(Monotonic): error: 114 ns, adjusted frequency from 2299774701 Hz to 2299774694 Hz D0314 13:02:06.759580 40179 parameters.go:238] Clock(Realtime): error: 56 ns, adjusted frequency from 2299774638 Hz to 2299774596 Hz D0314 13:02:06.759711 40179 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:327098922207956 monotonicBaseRef:9001182114 monotonicFrequency:2299774694 realtimeReady:1 realtimeBaseCycles:327098922772505 realtimeBaseRef:1584190926759574307 realtimeFrequency:2299774596} D0314 13:02:07.758437 40179 parameters.go:238] Clock(Monotonic): error: -220 ns, adjusted frequency from 2299774694 Hz to 2299774100 Hz D0314 13:02:07.758771 40179 parameters.go:238] Clock(Realtime): error: -160 ns, adjusted frequency from 2299774596 Hz to 2299774247 Hz D0314 13:02:07.758932 40179 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:327101219873947 monotonicBaseRef:10000265196 monotonicFrequency:2299774100 realtimeReady:1 realtimeBaseCycles:327101220631808 realtimeBaseRef:1584190927758741489 realtimeFrequency:2299774247} D0314 13:02:08.759144 40179 parameters.go:238] Clock(Monotonic): error: 344 ns, adjusted frequency from 2299774100 Hz to 2299775337 Hz D0314 13:02:08.759373 40179 parameters.go:238] Clock(Realtime): error: -119 ns, adjusted frequency from 2299774247 Hz to 2299774317 Hz D0314 13:02:08.759492 40179 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:327103521293131 monotonicBaseRef:11000980520 monotonicFrequency:2299775337 realtimeReady:1 realtimeBaseCycles:327103521843760 realtimeBaseRef:1584190928759366639 realtimeFrequency:2299774317} D0314 13:02:09.758672 40179 parameters.go:238] Clock(Monotonic): error: -396 ns, adjusted frequency from 2299775337 Hz to 2299773679 Hz D0314 13:02:09.758890 40179 parameters.go:238] Clock(Realtime): error: 312 ns, adjusted frequency from 2299774317 Hz to 2299775210 Hz D0314 13:02:09.759014 40179 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:327105819971446 monotonicBaseRef:12000503507 monotonicFrequency:2299773679 realtimeReady:1 realtimeBaseCycles:327105820508917 realtimeBaseRef:1584190929758884348 realtimeFrequency:2299775210} D0314 13:02:10.758579 40179 parameters.go:238] Clock(Monotonic): error: 5 ns, adjusted frequency from 2299773679 Hz to 2299774713 Hz D0314 13:02:10.758789 40179 parameters.go:238] Clock(Realtime): error: 64 ns, adjusted frequency from 2299775210 Hz to 2299774728 Hz D0314 13:02:10.758954 40179 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:327108119535917 monotonicBaseRef:13000412538 monotonicFrequency:2299774713 realtimeReady:1 realtimeBaseCycles:327108120035306 realtimeBaseRef:1584190930758776154 realtimeFrequency:2299774728} D0314 13:02:11.759831 40179 parameters.go:238] Clock(Monotonic): error: -107 ns, adjusted frequency from 2299774713 Hz to 2299774443 Hz D0314 13:02:11.760142 40179 parameters.go:238] Clock(Realtime): error: 12 ns, adjusted frequency from 2299774728 Hz to 2299774567 Hz D0314 13:02:11.760307 40179 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:327110422179669 monotonicBaseRef:14001660068 monotonicFrequency:2299774443 realtimeReady:1 realtimeBaseCycles:327110422933542 realtimeBaseRef:1584190931760134333 realtimeFrequency:2299774567} D0314 13:02:12.759147 40179 parameters.go:238] Clock(Monotonic): error: 420 ns, adjusted frequency from 2299774443 Hz to 2299775494 Hz D0314 13:02:12.759334 40179 parameters.go:238] Clock(Realtime): error: -109 ns, adjusted frequency from 2299774567 Hz to 2299774347 Hz D0314 13:02:12.759462 40179 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:327112720395099 monotonicBaseRef:15000982169 monotonicFrequency:2299775494 realtimeReady:1 realtimeBaseCycles:327112720855843 realtimeBaseRef:1584190932759328921 realtimeFrequency:2299774347} D0314 13:02:13.758542 40179 parameters.go:238] Clock(Monotonic): error: 50 ns, adjusted frequency from 2299775494 Hz to 2299774696 Hz D0314 13:02:13.758776 40179 parameters.go:238] Clock(Realtime): error: 139 ns, adjusted frequency from 2299774347 Hz to 2299774876 Hz D0314 13:02:13.758911 40179 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:327115018721236 monotonicBaseRef:16000351952 monotonicFrequency:2299774696 realtimeReady:1 realtimeBaseCycles:327115019342920 realtimeBaseRef:1584190933758769183 realtimeFrequency:2299774876} D0314 13:02:14.758393 40179 parameters.go:238] Clock(Monotonic): error: -43 ns, adjusted frequency from 2299774696 Hz to 2299774405 Hz D0314 13:02:14.758597 40179 parameters.go:238] Clock(Realtime): error: -169 ns, adjusted frequency from 2299774876 Hz to 2299774240 Hz D0314 13:02:14.758708 40179 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:327117318212241 monotonicBaseRef:17000228596 monotonicFrequency:2299774405 realtimeReady:1 realtimeBaseCycles:327117318710848 realtimeBaseRef:1584190934758592231 realtimeFrequency:2299774240} D0314 13:02:15.759035 40179 parameters.go:238] Clock(Monotonic): error: -41 ns, adjusted frequency from 2299774405 Hz to 2299774486 Hz D0314 13:02:15.759281 40179 parameters.go:238] Clock(Realtime): error: -219 ns, adjusted frequency from 2299774240 Hz to 2299774165 Hz D0314 13:02:15.759405 40179 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:327119619463297 monotonicBaseRef:18000870681 monotonicFrequency:2299774486 realtimeReady:1 realtimeBaseCycles:327119620054703 realtimeBaseRef:1584190935759274739 realtimeFrequency:2299774165} D0314 13:02:16.758561 40179 parameters.go:238] Clock(Monotonic): error: -185 ns, adjusted frequency from 2299774486 Hz to 2299774166 Hz D0314 13:02:16.758870 40179 parameters.go:238] Clock(Realtime): error: 75 ns, adjusted frequency from 2299774165 Hz to 2299774753 Hz D0314 13:02:16.759002 40179 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:327121918130679 monotonicBaseRef:19000389284 monotonicFrequency:2299774166 realtimeReady:1 realtimeBaseCycles:327121918869295 realtimeBaseRef:1584190936758857492 realtimeFrequency:2299774753} D0314 13:02:17.759335 40179 parameters.go:238] Clock(Monotonic): error: -136 ns, adjusted frequency from 2299774166 Hz to 2299774360 Hz D0314 13:02:17.759602 40179 parameters.go:238] Clock(Realtime): error: 135 ns, adjusted frequency from 2299774753 Hz to 2299774838 Hz D0314 13:02:17.759745 40179 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:327124219697235 monotonicBaseRef:20001168660 monotonicFrequency:2299774360 realtimeReady:1 realtimeBaseCycles:327124220343727 realtimeBaseRef:1584190937759596555 realtimeFrequency:2299774838} D0314 13:02:18.759072 40179 parameters.go:238] Clock(Monotonic): error: 509 ns, adjusted frequency from 2299774360 Hz to 2299775676 Hz D0314 13:02:18.759405 40179 parameters.go:238] Clock(Realtime): error: -21 ns, adjusted frequency from 2299774838 Hz to 2299774615 Hz D0314 13:02:18.759574 40179 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:327126518864358 monotonicBaseRef:21000904618 monotonicFrequency:2299775676 realtimeReady:1 realtimeBaseCycles:327126519661547 realtimeBaseRef:1584190938759397832 realtimeFrequency:2299774615} D0314 13:02:19.759080 40179 parameters.go:238] Clock(Monotonic): error: -397 ns, adjusted frequency from 2299775676 Hz to 2299773679 Hz D0314 13:02:19.759317 40179 parameters.go:238] Clock(Realtime): error: 99 ns, adjusted frequency from 2299774615 Hz to 2299774825 Hz D0314 13:02:19.759502 40179 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:327128818648777 monotonicBaseRef:22000908419 monotonicFrequency:2299773679 realtimeReady:1 realtimeBaseCycles:327128819236489 realtimeBaseRef:1584190939759311009 realtimeFrequency:2299774825} D0314 13:02:20.759261 40179 parameters.go:238] Clock(Monotonic): error: 430 ns, adjusted frequency from 2299773679 Hz to 2299775492 Hz D0314 13:02:20.759486 40179 parameters.go:238] Clock(Realtime): error: -20 ns, adjusted frequency from 2299774825 Hz to 2299774548 Hz D0314 13:02:20.759648 40179 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:327131118837529 monotonicBaseRef:23001088903 monotonicFrequency:2299775492 realtimeReady:1 realtimeBaseCycles:327131119400279 realtimeBaseRef:1584190940759480140 realtimeFrequency:2299774548} D0314 13:02:21.759842 40179 parameters.go:238] Clock(Monotonic): error: -123 ns, adjusted frequency from 2299775492 Hz to 2299774242 Hz D0314 13:02:21.760058 40179 parameters.go:238] Clock(Realtime): error: -99 ns, adjusted frequency from 2299774548 Hz to 2299774341 Hz D0314 13:02:21.760191 40179 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:327133419966391 monotonicBaseRef:24001677382 monotonicFrequency:2299774242 realtimeReady:1 realtimeBaseCycles:327133420492734 realtimeBaseRef:1584190941760053199 realtimeFrequency:2299774341} D0314 13:02:22.758727 40179 parameters.go:238] Clock(Monotonic): error: 286 ns, adjusted frequency from 2299774242 Hz to 2299775301 Hz D0314 13:02:22.758937 40179 parameters.go:238] Clock(Realtime): error: 87 ns, adjusted frequency from 2299774341 Hz to 2299774816 Hz D0314 13:02:22.759052 40179 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:327135717178002 monotonicBaseRef:25000563085 monotonicFrequency:2299775301 realtimeReady:1 realtimeBaseCycles:327135717687991 realtimeBaseRef:1584190942758931748 realtimeFrequency:2299774816} D0314 13:02:23.759660 40179 parameters.go:238] Clock(Monotonic): error: -591 ns, adjusted frequency from 2299775301 Hz to 2299773304 Hz D0314 13:02:23.760013 40179 parameters.go:238] Clock(Realtime): error: -153 ns, adjusted frequency from 2299774816 Hz to 2299774297 Hz D0314 13:02:23.760177 40179 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:327138018991078 monotonicBaseRef:26001449160 monotonicFrequency:2299773304 realtimeReady:1 realtimeBaseCycles:327138019929641 realtimeBaseRef:1584190943760004389 realtimeFrequency:2299774297} D0314 13:02:24.758360 40179 parameters.go:238] Clock(Monotonic): error: -90 ns, adjusted frequency from 2299773304 Hz to 2299774513 Hz D0314 13:02:24.758581 40179 parameters.go:238] Clock(Realtime): error: 43 ns, adjusted frequency from 2299774297 Hz to 2299774648 Hz D0314 13:02:24.758706 40179 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:327140315872225 monotonicBaseRef:27000191576 monotonicFrequency:2299774513 realtimeReady:1 realtimeBaseCycles:327140316419555 realtimeBaseRef:1584190944758576256 realtimeFrequency:2299774648} D0314 13:02:25.760822 40179 parameters.go:238] Clock(Monotonic): error: -96 ns, adjusted frequency from 2299774513 Hz to 2299774361 Hz D0314 13:02:25.761108 40179 parameters.go:238] Clock(Realtime): error: -176 ns, adjusted frequency from 2299774648 Hz to 2299774185 Hz D0314 13:02:25.761218 40179 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:327142621303080 monotonicBaseRef:28002651096 monotonicFrequency:2299774361 realtimeReady:1 realtimeBaseCycles:327142622002377 realtimeBaseRef:1584190945761101796 realtimeFrequency:2299774185} D0314 13:02:26.759808 40179 sampler.go:197] Time: Adjusting syscall overhead down to 7000 D0314 13:02:26.760044 40179 parameters.go:238] Clock(Monotonic): error: 45 ns, adjusted frequency from 2299774361 Hz to 2299774693 Hz D0314 13:02:26.760213 40179 parameters.go:238] Clock(Realtime): error: 359 ns, adjusted frequency from 2299774185 Hz to 2299775400 Hz D0314 13:02:26.760322 40179 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:327144918760587 monotonicBaseRef:29001643669 monotonicFrequency:2299774693 realtimeReady:1 realtimeBaseCycles:327144919718402 realtimeBaseRef:1584190946760206855 realtimeFrequency:2299775400} D0314 13:02:27.759341 40179 parameters.go:238] Clock(Monotonic): error: 58 ns, adjusted frequency from 2299774693 Hz to 2299774784 Hz D0314 13:02:27.759632 40179 parameters.go:238] Clock(Realtime): error: 72 ns, adjusted frequency from 2299775400 Hz to 2299774704 Hz D0314 13:02:27.759790 40179 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:327147217463199 monotonicBaseRef:30001177501 monotonicFrequency:2299774784 realtimeReady:1 realtimeBaseCycles:327147218152357 realtimeBaseRef:1584190947759623561 realtimeFrequency:2299774704} D0314 13:02:28.758806 40179 parameters.go:238] Clock(Monotonic): error: 173 ns, adjusted frequency from 2299774784 Hz to 2299775008 Hz D0314 13:02:28.759168 40179 parameters.go:238] Clock(Realtime): error: -426 ns, adjusted frequency from 2299774704 Hz to 2299773645 Hz D0314 13:02:28.759325 40179 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:327149515995134 monotonicBaseRef:31000637078 monotonicFrequency:2299775008 realtimeReady:1 realtimeBaseCycles:327149516859890 realtimeBaseRef:1584190948759159528 realtimeFrequency:2299773645} D0314 13:02:29.758689 40179 parameters.go:238] Clock(Monotonic): error: -341 ns, adjusted frequency from 2299775008 Hz to 2299773883 Hz D0314 13:02:29.758929 40179 parameters.go:238] Clock(Realtime): error: 208 ns, adjusted frequency from 2299773645 Hz to 2299775109 Hz D0314 13:02:29.759038 40179 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:327151815516302 monotonicBaseRef:32000526701 monotonicFrequency:2299773883 realtimeReady:1 realtimeBaseCycles:327151816090459 realtimeBaseRef:1584190949758923384 realtimeFrequency:2299775109} D0314 13:02:30.758465 40179 parameters.go:238] Clock(Monotonic): error: 123 ns, adjusted frequency from 2299773883 Hz to 2299775003 Hz D0314 13:02:30.758708 40179 parameters.go:238] Clock(Realtime): error: 47 ns, adjusted frequency from 2299775109 Hz to 2299774682 Hz D0314 13:02:30.758856 40179 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:327154114761535 monotonicBaseRef:33000296830 monotonicFrequency:2299775003 realtimeReady:1 realtimeBaseCycles:327154115359048 realtimeBaseRef:1584190950758703136 realtimeFrequency:2299774682} D0314 13:02:31.759520 40179 parameters.go:238] Clock(Monotonic): error: -101 ns, adjusted frequency from 2299775003 Hz to 2299774357 Hz D0314 13:02:31.759818 40179 parameters.go:238] Clock(Realtime): error: -60 ns, adjusted frequency from 2299774682 Hz to 2299774506 Hz D0314 13:02:31.759951 40179 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:327156416968953 monotonicBaseRef:34001354505 monotonicFrequency:2299774357 realtimeReady:1 realtimeBaseCycles:327156417676770 realtimeBaseRef:1584190951759808913 realtimeFrequency:2299774506} D0314 13:02:32.758888 40179 parameters.go:238] Clock(Monotonic): error: -81 ns, adjusted frequency from 2299774357 Hz to 2299774391 Hz D0314 13:02:32.759132 40179 parameters.go:238] Clock(Realtime): error: -227 ns, adjusted frequency from 2299774506 Hz to 2299774048 Hz D0314 13:02:32.759256 40179 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:327158715285200 monotonicBaseRef:35000720481 monotonicFrequency:2299774391 realtimeReady:1 realtimeBaseCycles:327158715870286 realtimeBaseRef:1584190952759121458 realtimeFrequency:2299774048} D0314 13:02:33.758461 40179 parameters.go:238] Clock(Monotonic): error: 96 ns, adjusted frequency from 2299774391 Hz to 2299774803 Hz D0314 13:02:33.758744 40179 parameters.go:238] Clock(Realtime): error: 126 ns, adjusted frequency from 2299774048 Hz to 2299774928 Hz D0314 13:02:33.758908 40179 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:327161014044946 monotonicBaseRef:36000279287 monotonicFrequency:2299774803 realtimeReady:1 realtimeBaseCycles:327161014737810 realtimeBaseRef:1584190953758727278 realtimeFrequency:2299774928} D0314 13:02:34.119407 40179 urpc.go:577] urpc: unmarshal success. D0314 13:02:34.119686 40179 controller.go:504] containerManager.Signal &{CID:ci-gvisor-ptrace-proxy-sandbox-race-3 Signo:0 PID:0 Mode:Process} D0314 13:02:34.119993 40179 urpc.go:534] urpc: successfully marshalled 37 bytes. D0314 13:02:34.759279 40179 parameters.go:238] Clock(Monotonic): error: 385 ns, adjusted frequency from 2299774803 Hz to 2299775422 Hz D0314 13:02:34.759539 40179 parameters.go:238] Clock(Realtime): error: -118 ns, adjusted frequency from 2299774928 Hz to 2299774318 Hz D0314 13:02:34.759676 40179 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:327163315749906 monotonicBaseRef:37001118567 monotonicFrequency:2299775422 realtimeReady:1 realtimeBaseCycles:327163316365956 realtimeBaseRef:1584190954759533103 realtimeFrequency:2299774318} D0314 13:02:35.758384 40179 parameters.go:238] Clock(Monotonic): error: -53 ns, adjusted frequency from 2299775422 Hz to 2299774397 Hz D0314 13:02:35.758596 40179 parameters.go:238] Clock(Realtime): error: 252 ns, adjusted frequency from 2299774318 Hz to 2299775162 Hz D0314 13:02:35.758726 40179 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:327165613466136 monotonicBaseRef:38000223178 monotonicFrequency:2299774397 realtimeReady:1 realtimeBaseCycles:327165613931293 realtimeBaseRef:1584190955758572582 realtimeFrequency:2299775162} D0314 13:02:36.758688 40179 parameters.go:238] Clock(Monotonic): error: 99 ns, adjusted frequency from 2299774397 Hz to 2299774778 Hz D0314 13:02:36.758918 40179 parameters.go:238] Clock(Realtime): error: -1 ns, adjusted frequency from 2299775162 Hz to 2299774542 Hz D0314 13:02:36.759056 40179 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:327167913939075 monotonicBaseRef:39000526921 monotonicFrequency:2299774778 realtimeReady:1 realtimeBaseCycles:327167914485746 realtimeBaseRef:1584190956758911437 realtimeFrequency:2299774542} D0314 13:02:37.758624 40179 parameters.go:238] Clock(Monotonic): error: -380 ns, adjusted frequency from 2299774778 Hz to 2299773665 Hz D0314 13:02:37.758885 40179 parameters.go:238] Clock(Realtime): error: 78 ns, adjusted frequency from 2299774542 Hz to 2299774698 Hz D0314 13:02:37.759032 40179 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:327170213552603 monotonicBaseRef:40000456805 monotonicFrequency:2299773665 realtimeReady:1 realtimeBaseCycles:327170214184461 realtimeBaseRef:1584190957758878465 realtimeFrequency:2299774698} D0314 13:02:38.758705 40179 parameters.go:238] Clock(Monotonic): error: -47 ns, adjusted frequency from 2299773665 Hz to 2299774483 Hz D0314 13:02:38.758950 40179 parameters.go:238] Clock(Realtime): error: -44 ns, adjusted frequency from 2299774698 Hz to 2299774440 Hz D0314 13:02:38.759070 40179 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:327172513502836 monotonicBaseRef:41000533581 monotonicFrequency:2299774483 realtimeReady:1 realtimeBaseCycles:327172514110588 realtimeBaseRef:1584190958758944310 realtimeFrequency:2299774440} D0314 13:02:39.758791 40179 parameters.go:238] Clock(Monotonic): error: 139 ns, adjusted frequency from 2299774483 Hz to 2299774903 Hz D0314 13:02:39.759118 40179 parameters.go:238] Clock(Realtime): error: 206 ns, adjusted frequency from 2299774440 Hz to 2299775075 Hz D0314 13:02:39.759300 40179 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:327174813481317 monotonicBaseRef:42000622284 monotonicFrequency:2299774903 realtimeReady:1 realtimeBaseCycles:327174814265763 realtimeBaseRef:1584190959759109863 realtimeFrequency:2299775075} D0314 13:02:40.758469 40179 parameters.go:238] Clock(Monotonic): error: -123 ns, adjusted frequency from 2299774903 Hz to 2299774306 Hz D0314 13:02:40.758693 40179 parameters.go:238] Clock(Realtime): error: -216 ns, adjusted frequency from 2299775075 Hz to 2299774002 Hz D0314 13:02:40.758830 40179 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:327177112518808 monotonicBaseRef:43000301638 monotonicFrequency:2299774306 realtimeReady:1 realtimeBaseCycles:327177113059842 realtimeBaseRef:1584190960758683301 realtimeFrequency:2299774002} D0314 13:02:41.759148 40179 parameters.go:238] Clock(Monotonic): error: 125 ns, adjusted frequency from 2299774306 Hz to 2299774890 Hz D0314 13:02:41.759380 40179 parameters.go:238] Clock(Realtime): error: 358 ns, adjusted frequency from 2299774002 Hz to 2299775417 Hz D0314 13:02:41.759531 40179 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:327179413858008 monotonicBaseRef:44000982093 monotonicFrequency:2299774890 realtimeReady:1 realtimeBaseCycles:327179414424677 realtimeBaseRef:1584190961759375035 realtimeFrequency:2299775417} D0314 13:02:42.758338 40179 parameters.go:238] Clock(Monotonic): error: 161 ns, adjusted frequency from 2299774890 Hz to 2299774962 Hz D0314 13:02:42.758579 40179 parameters.go:238] Clock(Realtime): error: -333 ns, adjusted frequency from 2299775417 Hz to 2299773766 Hz D0314 13:02:42.758721 40179 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:327181711772398 monotonicBaseRef:45000173100 monotonicFrequency:2299774962 realtimeReady:1 realtimeBaseCycles:327181712356175 realtimeBaseRef:1584190962758573252 realtimeFrequency:2299773766} I0314 13:02:43.150680 40179 watchdog.go:277] Watchdog starting loop, tasks: 13, discount: 0s D0314 13:02:43.759903 40179 parameters.go:238] Clock(Monotonic): error: -306 ns, adjusted frequency from 2299774962 Hz to 2299773938 Hz D0314 13:02:43.760141 40179 parameters.go:238] Clock(Realtime): error: -199 ns, adjusted frequency from 2299773766 Hz to 2299774127 Hz D0314 13:02:43.760246 40179 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:327184015040711 monotonicBaseRef:46001692096 monotonicFrequency:2299773938 realtimeReady:1 realtimeBaseCycles:327184015719920 realtimeBaseRef:1584190963760134265 realtimeFrequency:2299774127} D0314 13:02:44.758419 40179 parameters.go:238] Clock(Monotonic): error: 153 ns, adjusted frequency from 2299773938 Hz to 2299774989 Hz D0314 13:02:44.758680 40179 parameters.go:238] Clock(Realtime): error: 341 ns, adjusted frequency from 2299774127 Hz to 2299775388 Hz D0314 13:02:44.758871 40179 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:327186311488583 monotonicBaseRef:47000245838 monotonicFrequency:2299774989 realtimeReady:1 realtimeBaseCycles:327186312133812 realtimeBaseRef:1584190964758673149 realtimeFrequency:2299775388} D0314 13:02:45.758603 40179 parameters.go:238] Clock(Monotonic): error: -201 ns, adjusted frequency from 2299774989 Hz to 2299774175 Hz D0314 13:02:45.758873 40179 parameters.go:238] Clock(Realtime): error: -119 ns, adjusted frequency from 2299775388 Hz to 2299774363 Hz D0314 13:02:45.759008 40179 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:327188611709915 monotonicBaseRef:48000439919 monotonicFrequency:2299774175 realtimeReady:1 realtimeBaseCycles:327188612356511 realtimeBaseRef:1584190965758867651 realtimeFrequency:2299774363} D0314 13:02:46.758861 40179 parameters.go:238] Clock(Monotonic): error: 31 ns, adjusted frequency from 2299774175 Hz to 2299774667 Hz D0314 13:02:46.759145 40179 parameters.go:238] Clock(Realtime): error: 205 ns, adjusted frequency from 2299774363 Hz to 2299775032 Hz D0314 13:02:46.759262 40179 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:327190912074540 monotonicBaseRef:49000696661 monotonicFrequency:2299774667 realtimeReady:1 realtimeBaseCycles:327190912751097 realtimeBaseRef:1584190966759137339 realtimeFrequency:2299775032} D0314 13:02:47.758692 40179 parameters.go:238] Clock(Monotonic): error: 344 ns, adjusted frequency from 2299774667 Hz to 2299775303 Hz D0314 13:02:47.759015 40179 parameters.go:238] Clock(Realtime): error: -2 ns, adjusted frequency from 2299775032 Hz to 2299774575 Hz D0314 13:02:47.759184 40179 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:327193211454679 monotonicBaseRef:50000525110 monotonicFrequency:2299775303 realtimeReady:1 realtimeBaseCycles:327193212226093 realtimeBaseRef:1584190967759006875 realtimeFrequency:2299774575} D0314 13:02:48.759042 40179 parameters.go:238] Clock(Monotonic): error: -305 ns, adjusted frequency from 2299775303 Hz to 2299773881 Hz D0314 13:02:48.759382 40179 parameters.go:238] Clock(Realtime): error: -166 ns, adjusted frequency from 2299774575 Hz to 2299774282 Hz D0314 13:02:48.759591 40179 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:327195512039068 monotonicBaseRef:51000876920 monotonicFrequency:2299773881 realtimeReady:1 realtimeBaseCycles:327195512840777 realtimeBaseRef:1584190968759372175 realtimeFrequency:2299774282} D0314 13:02:49.758610 40179 parameters.go:238] Clock(Monotonic): error: 32 ns, adjusted frequency from 2299773881 Hz to 2299774657 Hz D0314 13:02:49.758858 40179 parameters.go:238] Clock(Realtime): error: -189 ns, adjusted frequency from 2299774282 Hz to 2299774170 Hz D0314 13:02:49.758986 40179 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:327197810815068 monotonicBaseRef:52000443016 monotonicFrequency:2299774657 realtimeReady:1 realtimeBaseCycles:327197811415436 realtimeBaseRef:1584190969758850548 realtimeFrequency:2299774170} D0314 13:02:50.760255 40179 parameters.go:238] Clock(Monotonic): error: -115 ns, adjusted frequency from 2299774657 Hz to 2299774307 Hz D0314 13:02:50.760499 40179 parameters.go:238] Clock(Realtime): error: 72 ns, adjusted frequency from 2299774170 Hz to 2299774696 Hz D0314 13:02:50.760608 40179 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:327200114372160 monotonicBaseRef:53002087714 monotonicFrequency:2299774307 realtimeReady:1 realtimeBaseCycles:327200114966801 realtimeBaseRef:1584190970760492967 realtimeFrequency:2299774696} D0314 13:02:51.758881 40179 parameters.go:238] Clock(Monotonic): error: -2 ns, adjusted frequency from 2299774307 Hz to 2299774558 Hz D0314 13:02:51.759147 40179 parameters.go:238] Clock(Realtime): error: -92 ns, adjusted frequency from 2299774696 Hz to 2299774411 Hz D0314 13:02:51.759282 40179 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:327202410986036 monotonicBaseRef:54000713478 monotonicFrequency:2299774558 realtimeReady:1 realtimeBaseCycles:327202411630178 realtimeBaseRef:1584190971759140087 realtimeFrequency:2299774411} D0314 13:02:52.229402 40179 urpc.go:577] urpc: unmarshal success. D0314 13:02:52.229687 40179 controller.go:504] containerManager.Signal &{CID:ci-gvisor-ptrace-proxy-sandbox-race-3 Signo:0 PID:0 Mode:Process} D0314 13:02:52.230013 40179 urpc.go:534] urpc: successfully marshalled 37 bytes. D0314 13:02:52.758487 40179 parameters.go:238] Clock(Monotonic): error: 39 ns, adjusted frequency from 2299774558 Hz to 2299774673 Hz D0314 13:02:52.758756 40179 parameters.go:238] Clock(Realtime): error: 257 ns, adjusted frequency from 2299774411 Hz to 2299775113 Hz D0314 13:02:52.758879 40179 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:327204709847956 monotonicBaseRef:55000316639 monotonicFrequency:2299774673 realtimeReady:1 realtimeBaseCycles:327204710504567 realtimeBaseRef:1584190972758748734 realtimeFrequency:2299775113} D0314 13:02:53.758422 40179 parameters.go:238] Clock(Monotonic): error: 368 ns, adjusted frequency from 2299774673 Hz to 2299775374 Hz D0314 13:02:53.758668 40179 parameters.go:238] Clock(Realtime): error: -42 ns, adjusted frequency from 2299775113 Hz to 2299774504 Hz D0314 13:02:53.758858 40179 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:327207009456902 monotonicBaseRef:56000244576 monotonicFrequency:2299775374 realtimeReady:1 realtimeBaseCycles:327207010077179 realtimeBaseRef:1584190973758660681 realtimeFrequency:2299774504} D0314 13:02:54.758573 40179 parameters.go:238] Clock(Monotonic): error: -18 ns, adjusted frequency from 2299775374 Hz to 2299774460 Hz D0314 13:02:54.758823 40179 parameters.go:238] Clock(Realtime): error: -331 ns, adjusted frequency from 2299774504 Hz to 2299773871 Hz D0314 13:02:54.758968 40179 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:327209309613802 monotonicBaseRef:57000410473 monotonicFrequency:2299774460 realtimeReady:1 realtimeBaseCycles:327209310152478 realtimeBaseRef:1584190974758791474 realtimeFrequency:2299773871} D0314 13:02:55.758436 40179 parameters.go:238] Clock(Monotonic): error: -294 ns, adjusted frequency from 2299774460 Hz to 2299773920 Hz D0314 13:02:55.758687 40179 parameters.go:238] Clock(Realtime): error: 296 ns, adjusted frequency from 2299773871 Hz to 2299775311 Hz D0314 13:02:55.758809 40179 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:327211609070466 monotonicBaseRef:58000272287 monotonicFrequency:2299773920 realtimeReady:1 realtimeBaseCycles:327211609671089 realtimeBaseRef:1584190975758680480 realtimeFrequency:2299775311} D0314 13:02:56.759957 40179 parameters.go:238] Clock(Monotonic): error: -49 ns, adjusted frequency from 2299773920 Hz to 2299774543 Hz D0314 13:02:56.760209 40179 parameters.go:238] Clock(Realtime): error: -327 ns, adjusted frequency from 2299775311 Hz to 2299773917 Hz D0314 13:02:56.760327 40179 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:327213912343731 monotonicBaseRef:59001793890 monotonicFrequency:2299774543 realtimeReady:1 realtimeBaseCycles:327213912938007 realtimeBaseRef:1584190976760198718 realtimeFrequency:2299773917} D0314 13:02:57.758367 40179 parameters.go:238] Clock(Monotonic): error: 27 ns, adjusted frequency from 2299774543 Hz to 2299774630 Hz D0314 13:02:57.758568 40179 parameters.go:238] Clock(Realtime): error: 262 ns, adjusted frequency from 2299773917 Hz to 2299775081 Hz D0314 13:02:57.758746 40179 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:327216208438658 monotonicBaseRef:60000193900 monotonicFrequency:2299774630 realtimeReady:1 realtimeBaseCycles:327216208946660 realtimeBaseRef:1584190977758561485 realtimeFrequency:2299775081} D0314 13:02:58.758912 40179 parameters.go:238] Clock(Monotonic): error: 211 ns, adjusted frequency from 2299774630 Hz to 2299775087 Hz D0314 13:02:58.759508 40179 parameters.go:238] Clock(Realtime): error: -55 ns, adjusted frequency from 2299775081 Hz to 2299774520 Hz D0314 13:02:58.759818 40179 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:327218509478474 monotonicBaseRef:61000744034 monotonicFrequency:2299775087 realtimeReady:1 realtimeBaseCycles:327218510845554 realtimeBaseRef:1584190978759484972 realtimeFrequency:2299774520} D0314 13:02:59.759320 40179 parameters.go:238] Clock(Monotonic): error: -115 ns, adjusted frequency from 2299775087 Hz to 2299774323 Hz D0314 13:02:59.759720 40179 parameters.go:238] Clock(Realtime): error: 76 ns, adjusted frequency from 2299774520 Hz to 2299774835 Hz D0314 13:02:59.759889 40179 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:327220810170741 monotonicBaseRef:62001142846 monotonicFrequency:2299774323 realtimeReady:1 realtimeBaseCycles:327220811142402 realtimeBaseRef:1584190979759712093 realtimeFrequency:2299774835} D0314 13:03:00.761311 40179 parameters.go:238] Clock(Monotonic): error: 264 ns, adjusted frequency from 2299774323 Hz to 2299775123 Hz D0314 13:03:00.761520 40179 parameters.go:238] Clock(Realtime): error: -58 ns, adjusted frequency from 2299774835 Hz to 2299774395 Hz D0314 13:03:00.761612 40179 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:327223114540952 monotonicBaseRef:63003141254 monotonicFrequency:2299775123 realtimeReady:1 realtimeBaseCycles:327223115061324 realtimeBaseRef:1584190980761514046 realtimeFrequency:2299774395} D0314 13:03:01.759263 40179 parameters.go:238] Clock(Monotonic): error: -318 ns, adjusted frequency from 2299775123 Hz to 2299773860 Hz D0314 13:03:01.759476 40179 parameters.go:238] Clock(Realtime): error: -45 ns, adjusted frequency from 2299774395 Hz to 2299774497 Hz D0314 13:03:01.759617 40179 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:327225409606436 monotonicBaseRef:64001093384 monotonicFrequency:2299773860 realtimeReady:1 realtimeBaseCycles:327225410136853 realtimeBaseRef:1584190981759470860 realtimeFrequency:2299774497} D0314 13:03:02.759240 40179 parameters.go:238] Clock(Monotonic): error: 0 ns, adjusted frequency from 2299773860 Hz to 2299774660 Hz D0314 13:03:02.759469 40179 parameters.go:238] Clock(Realtime): error: 44 ns, adjusted frequency from 2299774497 Hz to 2299774690 Hz D0314 13:03:02.759678 40179 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:327227709341065 monotonicBaseRef:65001076325 monotonicFrequency:2299774660 realtimeReady:1 realtimeBaseCycles:327227709893958 realtimeBaseRef:1584190982759463297 realtimeFrequency:2299774690} D0314 13:03:03.759490 40179 parameters.go:238] Clock(Monotonic): error: 260 ns, adjusted frequency from 2299774660 Hz to 2299775302 Hz D0314 13:03:03.759921 40179 parameters.go:238] Clock(Realtime): error: -131 ns, adjusted frequency from 2299774690 Hz to 2299774299 Hz D0314 13:03:03.760088 40179 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:327230008096744 monotonicBaseRef:66000633246 monotonicFrequency:2299775302 realtimeReady:1 realtimeBaseCycles:327230010693560 realtimeBaseRef:1584190983759908954 realtimeFrequency:2299774299} D0314 13:03:04.758431 40179 parameters.go:238] Clock(Monotonic): error: -341 ns, adjusted frequency from 2299775302 Hz to 2299773805 Hz D0314 13:03:04.758677 40179 parameters.go:238] Clock(Realtime): error: -97 ns, adjusted frequency from 2299774299 Hz to 2299774413 Hz D0314 13:03:04.759392 40179 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:327232307030858 monotonicBaseRef:67000267476 monotonicFrequency:2299773805 realtimeReady:1 realtimeBaseCycles:327232307618901 realtimeBaseRef:1584190984758670155 realtimeFrequency:2299774413} D0314 13:03:05.758704 40179 parameters.go:238] Clock(Monotonic): error: 114 ns, adjusted frequency from 2299773805 Hz to 2299774862 Hz D0314 13:03:05.758977 40179 parameters.go:238] Clock(Realtime): error: 414 ns, adjusted frequency from 2299774413 Hz to 2299775529 Hz D0314 13:03:05.759127 40179 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:327234607426325 monotonicBaseRef:68000537790 monotonicFrequency:2299774862 realtimeReady:1 realtimeBaseCycles:327234608085119 realtimeBaseRef:1584190985758970969 realtimeFrequency:2299775529} D0314 13:03:06.758804 40179 parameters.go:238] Clock(Monotonic): error: -170 ns, adjusted frequency from 2299774862 Hz to 2299774212 Hz D0314 13:03:06.759020 40179 parameters.go:238] Clock(Realtime): error: -177 ns, adjusted frequency from 2299775529 Hz to 2299774171 Hz D0314 13:03:06.759140 40179 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:327236907438562 monotonicBaseRef:69000641006 monotonicFrequency:2299774212 realtimeReady:1 realtimeBaseCycles:327236907949292 realtimeBaseRef:1584190986759009513 realtimeFrequency:2299774171} D0314 13:03:07.758994 40179 parameters.go:238] Clock(Monotonic): error: 74 ns, adjusted frequency from 2299774212 Hz to 2299774747 Hz D0314 13:03:07.759187 40179 parameters.go:238] Clock(Realtime): error: -49 ns, adjusted frequency from 2299774171 Hz to 2299774425 Hz D0314 13:03:07.759321 40179 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:327239207652080 monotonicBaseRef:70000832027 monotonicFrequency:2299774747 realtimeReady:1 realtimeBaseCycles:327239208117355 realtimeBaseRef:1584190987759180787 realtimeFrequency:2299774425} D0314 13:03:08.758770 40179 parameters.go:238] Clock(Monotonic): error: 90 ns, adjusted frequency from 2299774747 Hz to 2299774795 Hz D0314 13:03:08.759015 40179 parameters.go:238] Clock(Realtime): error: 185 ns, adjusted frequency from 2299774425 Hz to 2299774931 Hz D0314 13:03:08.759457 40179 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:327241506893047 monotonicBaseRef:71000599926 monotonicFrequency:2299774795 realtimeReady:1 realtimeBaseCycles:327241507492648 realtimeBaseRef:1584190988759007234 realtimeFrequency:2299774931} D0314 13:03:09.758588 40179 parameters.go:238] Clock(Monotonic): error: -157 ns, adjusted frequency from 2299774795 Hz to 2299774308 Hz D0314 13:03:09.758829 40179 parameters.go:238] Clock(Realtime): error: -363 ns, adjusted frequency from 2299774931 Hz to 2299773812 Hz D0314 13:03:09.758967 40179 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:327243806238080 monotonicBaseRef:72000413054 monotonicFrequency:2299774308 realtimeReady:1 realtimeBaseCycles:327243806844188 realtimeBaseRef:1584190989758823132 realtimeFrequency:2299773812} D0314 13:03:10.758644 40179 parameters.go:238] Clock(Monotonic): error: 129 ns, adjusted frequency from 2299774308 Hz to 2299774868 Hz D0314 13:03:10.758915 40179 parameters.go:238] Clock(Realtime): error: 440 ns, adjusted frequency from 2299773812 Hz to 2299775558 Hz D0314 13:03:10.759054 40179 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:327246106138217 monotonicBaseRef:73000467767 monotonicFrequency:2299774868 realtimeReady:1 realtimeBaseCycles:327246106814332 realtimeBaseRef:1584190990758908502 realtimeFrequency:2299775558} D0314 13:03:11.758403 40179 parameters.go:238] Clock(Monotonic): error: -131 ns, adjusted frequency from 2299774868 Hz to 2299774278 Hz D0314 13:03:11.758597 40179 parameters.go:238] Clock(Realtime): error: -238 ns, adjusted frequency from 2299775558 Hz to 2299774025 Hz D0314 13:03:11.758695 40179 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:327248405385531 monotonicBaseRef:74000238373 monotonicFrequency:2299774278 realtimeReady:1 realtimeBaseCycles:327248405859565 realtimeBaseRef:1584190991758590938 realtimeFrequency:2299774025} D0314 13:03:12.758866 40179 parameters.go:238] Clock(Monotonic): error: 395 ns, adjusted frequency from 2299774278 Hz to 2299775392 Hz D0314 13:03:12.759086 40179 parameters.go:238] Clock(Realtime): error: 13 ns, adjusted frequency from 2299774025 Hz to 2299774575 Hz D0314 13:03:12.759180 40179 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:327250706227653 monotonicBaseRef:75000702698 monotonicFrequency:2299775392 realtimeReady:1 realtimeBaseCycles:327250706760113 realtimeBaseRef:1584190992759080778 realtimeFrequency:2299774575} D0314 13:03:13.761604 40179 parameters.go:238] Clock(Monotonic): error: -225 ns, adjusted frequency from 2299775392 Hz to 2299774074 Hz D0314 13:03:13.761943 40179 parameters.go:238] Clock(Realtime): error: 86 ns, adjusted frequency from 2299774575 Hz to 2299774703 Hz D0314 13:03:13.762049 40179 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:327253012238916 monotonicBaseRef:76003414211 monotonicFrequency:2299774074 realtimeReady:1 realtimeBaseCycles:327253013098653 realtimeBaseRef:1584190993761934955 realtimeFrequency:2299774703} D0314 13:03:14.759727 40179 parameters.go:238] Clock(Monotonic): error: 96 ns, adjusted frequency from 2299774074 Hz to 2299774814 Hz D0314 13:03:14.759963 40179 parameters.go:238] Clock(Realtime): error: 389 ns, adjusted frequency from 2299774703 Hz to 2299775453 Hz D0314 13:03:14.760094 40179 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:327255307736668 monotonicBaseRef:77001554757 monotonicFrequency:2299774814 realtimeReady:1 realtimeBaseCycles:327255308320641 realtimeBaseRef:1584190994759955319 realtimeFrequency:2299775453} D0314 13:03:15.758573 40179 parameters.go:238] Clock(Monotonic): error: -243 ns, adjusted frequency from 2299774814 Hz to 2299774026 Hz D0314 13:03:15.758787 40179 parameters.go:238] Clock(Realtime): error: -149 ns, adjusted frequency from 2299775453 Hz to 2299774290 Hz D0314 13:03:15.758914 40179 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:327257604876084 monotonicBaseRef:78000408819 monotonicFrequency:2299774026 realtimeReady:1 realtimeBaseCycles:327257605395670 realtimeBaseRef:1584190995758781106 realtimeFrequency:2299774290} D0314 13:03:16.758736 40179 parameters.go:238] Clock(Monotonic): error: -13 ns, adjusted frequency from 2299774026 Hz to 2299774550 Hz D0314 13:03:16.759011 40179 parameters.go:238] Clock(Realtime): error: -294 ns, adjusted frequency from 2299774290 Hz to 2299773912 Hz D0314 13:03:16.759126 40179 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:327259905024204 monotonicBaseRef:79000571484 monotonicFrequency:2299774550 realtimeReady:1 realtimeBaseCycles:327259905682443 realtimeBaseRef:1584190996759003946 realtimeFrequency:2299773912} D0314 13:03:17.758966 40179 parameters.go:238] Clock(Monotonic): error: 38 ns, adjusted frequency from 2299774550 Hz to 2299774673 Hz D0314 13:03:17.759213 40179 parameters.go:238] Clock(Realtime): error: 118 ns, adjusted frequency from 2299773912 Hz to 2299774862 Hz D0314 13:03:17.759315 40179 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:327262205323793 monotonicBaseRef:80000799784 monotonicFrequency:2299774673 realtimeReady:1 realtimeBaseCycles:327262205923059 realtimeBaseRef:1584190997759206880 realtimeFrequency:2299774862} D0314 13:03:18.759657 40179 parameters.go:238] Clock(Monotonic): error: 107 ns, adjusted frequency from 2299774673 Hz to 2299774833 Hz D0314 13:03:18.759919 40179 parameters.go:238] Clock(Realtime): error: 64 ns, adjusted frequency from 2299774862 Hz to 2299774673 Hz D0314 13:03:18.760102 40179 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:327264506675651 monotonicBaseRef:81001485583 monotonicFrequency:2299774833 realtimeReady:1 realtimeBaseCycles:327264507319372 realtimeBaseRef:1584190998759911927 realtimeFrequency:2299774673} D0314 13:03:19.758925 40179 parameters.go:238] Clock(Monotonic): error: -148 ns, adjusted frequency from 2299774833 Hz to 2299774249 Hz D0314 13:03:19.759215 40179 parameters.go:238] Clock(Realtime): error: -17 ns, adjusted frequency from 2299774673 Hz to 2299774540 Hz D0314 13:03:19.759324 40179 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:327266804764934 monotonicBaseRef:82000752663 monotonicFrequency:2299774249 realtimeReady:1 realtimeBaseCycles:327266805477091 realtimeBaseRef:1584190999759208834 realtimeFrequency:2299774540} D0314 13:03:20.759414 40179 parameters.go:238] Clock(Monotonic): error: 13 ns, adjusted frequency from 2299774249 Hz to 2299774606 Hz D0314 13:03:20.759679 40179 parameters.go:238] Clock(Realtime): error: -135 ns, adjusted frequency from 2299774540 Hz to 2299774326 Hz D0314 13:03:20.759871 40179 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:327269105659408 monotonicBaseRef:83001239765 monotonicFrequency:2299774606 realtimeReady:1 realtimeBaseCycles:327269106312464 realtimeBaseRef:1584191000759670110 realtimeFrequency:2299774326} D0314 13:03:21.758910 40179 parameters.go:238] Clock(Monotonic): error: -47 ns, adjusted frequency from 2299774606 Hz to 2299774540 Hz D0314 13:03:21.759164 40179 parameters.go:238] Clock(Realtime): error: -219 ns, adjusted frequency from 2299774326 Hz to 2299774112 Hz D0314 13:03:21.759327 40179 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:327271404287200 monotonicBaseRef:84000741101 monotonicFrequency:2299774540 realtimeReady:1 realtimeBaseCycles:327271404893225 realtimeBaseRef:1584191001759151117 realtimeFrequency:2299774112} D0314 13:03:22.759264 40179 parameters.go:238] Clock(Monotonic): error: 200 ns, adjusted frequency from 2299774540 Hz to 2299775051 Hz D0314 13:03:22.759595 40179 parameters.go:238] Clock(Realtime): error: 501 ns, adjusted frequency from 2299774112 Hz to 2299775743 Hz D0314 13:03:22.759774 40179 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:327273704865929 monotonicBaseRef:85001090782 monotonicFrequency:2299775051 realtimeReady:1 realtimeBaseCycles:327273705668982 realtimeBaseRef:1584191002759586657 realtimeFrequency:2299775743} D0314 13:03:23.758448 40179 parameters.go:238] Clock(Monotonic): error: -39 ns, adjusted frequency from 2299775051 Hz to 2299774486 Hz D0314 13:03:23.758762 40179 parameters.go:238] Clock(Realtime): error: 17 ns, adjusted frequency from 2299775743 Hz to 2299774619 Hz D0314 13:03:23.759235 40179 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:327276002753728 monotonicBaseRef:86000270157 monotonicFrequency:2299774486 realtimeReady:1 realtimeBaseCycles:327276003527050 realtimeBaseRef:1584191003758752803 realtimeFrequency:2299774619} D0314 13:03:24.758502 40179 parameters.go:238] Clock(Monotonic): error: -139 ns, adjusted frequency from 2299774486 Hz to 2299774255 Hz D0314 13:03:24.758748 40179 parameters.go:238] Clock(Realtime): error: -244 ns, adjusted frequency from 2299774619 Hz to 2299773978 Hz D0314 13:03:24.758880 40179 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:327278302685561 monotonicBaseRef:87000338575 monotonicFrequency:2299774255 realtimeReady:1 realtimeBaseCycles:327278303276773 realtimeBaseRef:1584191004758741977 realtimeFrequency:2299773978} D0314 13:03:25.758393 40179 parameters.go:238] Clock(Monotonic): error: -5 ns, adjusted frequency from 2299774255 Hz to 2299774573 Hz D0314 13:03:25.758597 40179 parameters.go:238] Clock(Realtime): error: 74 ns, adjusted frequency from 2299773978 Hz to 2299774674 Hz D0314 13:03:25.758737 40179 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:327280602207290 monotonicBaseRef:88000228770 monotonicFrequency:2299774573 realtimeReady:1 realtimeBaseCycles:327280602705530 realtimeBaseRef:1584191005758591866 realtimeFrequency:2299774674} D0314 13:03:26.758892 40179 parameters.go:238] Clock(Monotonic): error: 506 ns, adjusted frequency from 2299774573 Hz to 2299775742 Hz D0314 13:03:26.759151 40179 parameters.go:238] Clock(Realtime): error: 117 ns, adjusted frequency from 2299774674 Hz to 2299774800 Hz D0314 13:03:26.759277 40179 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:327282903130098 monotonicBaseRef:89000728051 monotonicFrequency:2299775742 realtimeReady:1 realtimeBaseCycles:327282903751890 realtimeBaseRef:1584191006759144827 realtimeFrequency:2299774800} I0314 13:03:27.354202 62727 main.go:305] *************************** I0314 13:03:27.360039 62727 main.go:306] Args: [/syzkaller/managers/ptrace-proxy-sandbox-race/current/image -root /syzkaller/managers/ptrace-proxy-sandbox-race/workdir/gvisor_root -watchdog-action=panic -network=none -debug -alsologtostderr -platform=ptrace -file-access=shared -network=sandbox -TESTONLY-unsafe-nonroot exec -user=0:0 -cap CAP_CHOWN -cap CAP_DAC_OVERRIDE -cap CAP_DAC_READ_SEARCH -cap CAP_FOWNER -cap CAP_FSETID -cap CAP_KILL -cap CAP_SETGID -cap CAP_SETUID -cap CAP_SETPCAP -cap CAP_LINUX_IMMUTABLE -cap CAP_NET_BIND_SERVICE -cap CAP_NET_BROADCAST -cap CAP_NET_ADMIN -cap CAP_NET_RAW -cap CAP_IPC_LOCK -cap CAP_IPC_OWNER -cap CAP_SYS_MODULE -cap CAP_SYS_RAWIO -cap CAP_SYS_CHROOT -cap CAP_SYS_PTRACE -cap CAP_SYS_PACCT -cap CAP_SYS_ADMIN -cap CAP_SYS_BOOT -cap CAP_SYS_NICE -cap CAP_SYS_RESOURCE -cap CAP_SYS_TIME -cap CAP_SYS_TTY_CONFIG -cap CAP_MKNOD -cap CAP_LEASE -cap CAP_AUDIT_WRITE -cap CAP_AUDIT_CONTROL -cap CAP_SETFCAP -cap CAP_MAC_OVERRIDE -cap CAP_MAC_ADMIN -cap CAP_SYSLOG -cap CAP_WAKE_ALARM -cap CAP_BLOCK_SUSPEND -cap CAP_AUDIT_READ ci-gvisor-ptrace-proxy-sandbox-race-3 /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 /syzkaller287851874] I0314 13:03:27.361051 62727 main.go:307] Version release-20200219.0-175-g829beebf0b67 I0314 13:03:27.361225 62727 main.go:308] PID: 62727 I0314 13:03:27.361334 62727 main.go:309] UID: 0, GID: 0 I0314 13:03:27.361476 62727 main.go:310] Configuration: I0314 13:03:27.361626 62727 main.go:311] RootDir: /syzkaller/managers/ptrace-proxy-sandbox-race/workdir/gvisor_root I0314 13:03:27.361792 62727 main.go:312] Platform: ptrace I0314 13:03:27.361959 62727 main.go:313] FileAccess: shared, overlay: false I0314 13:03:27.362158 62727 main.go:314] Network: sandbox, logging: false I0314 13:03:27.362361 62727 main.go:315] Strace: false, max size: 1024, syscalls: [] I0314 13:03:27.362686 62727 main.go:316] *************************** W0314 13:03:27.362824 62727 main.go:321] Block the TERM signal. This is only safe in tests! D0314 13:03:27.363366 62727 container.go:159] Load container "/syzkaller/managers/ptrace-proxy-sandbox-race/workdir/gvisor_root" "ci-gvisor-ptrace-proxy-sandbox-race-3" D0314 13:03:27.371230 62727 container.go:592] Signal container "ci-gvisor-ptrace-proxy-sandbox-race-3": signal 0 D0314 13:03:27.371574 62727 sandbox.go:814] Signal sandbox "ci-gvisor-ptrace-proxy-sandbox-race-3" D0314 13:03:27.371796 62727 sandbox.go:318] Connecting to sandbox "ci-gvisor-ptrace-proxy-sandbox-race-3" D0314 13:03:27.373493 40179 urpc.go:577] urpc: unmarshal success. D0314 13:03:27.373763 40179 controller.go:504] containerManager.Signal &{CID:ci-gvisor-ptrace-proxy-sandbox-race-3 Signo:0 PID:0 Mode:Process} D0314 13:03:27.374026 40179 urpc.go:534] urpc: successfully marshalled 37 bytes. D0314 13:03:27.374298 62727 urpc.go:534] urpc: successfully marshalled 117 bytes. D0314 13:03:27.374820 62727 urpc.go:577] urpc: unmarshal success. D0314 13:03:27.375074 62727 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 /syzkaller287851874 D0314 13:03:27.375253 62727 exec.go:121] Exec capablities: &{PermittedCaps:274877898751 InheritableCaps:274877898751 EffectiveCaps:274877898751 BoundingCaps:274877898751 AmbientCaps:0} D0314 13:03:27.375587 62727 container.go:533] Execute in container "ci-gvisor-ptrace-proxy-sandbox-race-3", 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 /syzkaller287851874 D0314 13:03:27.375770 62727 sandbox.go:283] Executing new process in container "ci-gvisor-ptrace-proxy-sandbox-race-3" in sandbox "ci-gvisor-ptrace-proxy-sandbox-race-3" D0314 13:03:27.375984 62727 sandbox.go:318] Connecting to sandbox "ci-gvisor-ptrace-proxy-sandbox-race-3" D0314 13:03:27.377676 40179 urpc.go:577] urpc: unmarshal success. D0314 13:03:27.379006 40179 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 /syzkaller287851874 D0314 13:03:27.379345 40179 transport_flipcall.go:127] send [channel @0xc000510240] Twalkgetattr{FID: 1, NewFID: 5, Names: [etc]} D0314 13:03:27.380731 40179 transport_flipcall.go:234] recv [channel @0xc000510240] Rlerror{Error: 2} D0314 13:03:27.380921 40179 transport_flipcall.go:127] send [channel @0xc000510240] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.etc, Size: 1} D0314 13:03:27.381676 40179 transport_flipcall.go:234] recv [channel @0xc000510240] Rlerror{Error: 95} I0314 13:03:27.382488 40179 kernel.go:797] 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 /syzkaller287851874] D0314 13:03:27.383335 40179 transport_flipcall.go:127] send [channel @0xc000510240] Twalkgetattr{FID: 1, NewFID: 5, Names: [syz-execprog]} D0314 13:03:27.384609 62727 urpc.go:534] urpc: successfully marshalled 636 bytes. D0314 13:03:27.384798 40179 transport_flipcall.go:234] recv [channel @0xc000510240] 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: 1584190920, NanoSec: 380120961}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}, QIDs: [QID{Type: 0, Version: 0, Path: 7209227}]} D0314 13:03:27.385090 40179 transport_flipcall.go:127] send [channel @0xc000510240] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syz-execprog, Size: 1} D0314 13:03:27.385625 40179 transport_flipcall.go:234] recv [channel @0xc000510240] Rlerror{Error: 95} D0314 13:03:27.385875 40179 transport_flipcall.go:127] send [channel @0xc000510240] Tgetattr{FID: 5, AttrMask: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime INo Size Blocks BTime Gen DataVersion}} D0314 13:03:27.386865 40179 transport_flipcall.go:234] recv [channel @0xc000510240] Rgetattr{Valid: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime Size Blocks}, QID: QID{Type: 0, Version: 0, Path: 7209227}, 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: 1584190920, NanoSec: 380120961}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}} D0314 13:03:27.387056 40179 transport_flipcall.go:127] send [channel @0xc000510240] Twalk{FID: 5, NewFID: 6, Names: []} D0314 13:03:27.387812 40179 transport_flipcall.go:234] recv [channel @0xc000510240] Rwalk{QIDs: []} D0314 13:03:27.388075 40179 transport_flipcall.go:127] send [channel @0xc000510240] Tlopen{FID: 6, Flags: ReadOnly} D0314 13:03:27.388985 40179 transport_flipcall.go:234] recv [channel @0xc000510240] Rlopen{QID: QID{Type: 0, Version: 0, Path: 7209227}, IoUnit: 0, File: &{{37}}} D0314 13:03:27.393130 40179 syscalls.go:266] Allocating stack with size of 8388608 bytes D0314 13:03:27.395827 40179 loader.go:832] updated processes: map[{ci-gvisor-ptrace-proxy-sandbox-race-3 0}:0xc000370a80 {ci-gvisor-ptrace-proxy-sandbox-race-3 19}:0xc000206b40] D0314 13:03:27.396296 40179 urpc.go:534] urpc: successfully marshalled 37 bytes. D0314 13:03:27.396621 62727 urpc.go:577] urpc: unmarshal success. D0314 13:03:27.397025 62727 container.go:580] Wait on PID 19 in container "ci-gvisor-ptrace-proxy-sandbox-race-3" D0314 13:03:27.397221 62727 sandbox.go:769] Waiting for PID 19 in sandbox "ci-gvisor-ptrace-proxy-sandbox-race-3" D0314 13:03:27.397412 62727 sandbox.go:318] Connecting to sandbox "ci-gvisor-ptrace-proxy-sandbox-race-3" D0314 13:03:27.398258 62727 urpc.go:534] urpc: successfully marshalled 100 bytes. D0314 13:03:27.398686 40179 urpc.go:577] urpc: unmarshal success. D0314 13:03:27.399223 40179 controller.go:446] containerManager.Wait D0314 13:03:27.468549 40179 task_block.go:223] [ 19] Interrupt queued D0314 13:03:27.758529 40179 parameters.go:238] Clock(Monotonic): error: -482 ns, adjusted frequency from 2299775742 Hz to 2299773481 Hz D0314 13:03:27.758779 40179 parameters.go:238] Clock(Realtime): error: -150 ns, adjusted frequency from 2299774800 Hz to 2299774269 Hz D0314 13:03:27.758924 40179 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:327285202074920 monotonicBaseRef:90000366746 monotonicFrequency:2299773481 realtimeReady:1 realtimeBaseCycles:327285202672608 realtimeBaseRef:1584191007758773450 realtimeFrequency:2299774269} D0314 13:03:27.910874 40179 task_block.go:223] [ 20] Interrupt queued D0314 13:03:27.922162 40179 task_block.go:223] [ 22] Interrupt queued D0314 13:03:27.922419 40179 task_block.go:223] [ 21] Interrupt queued D0314 13:03:28.009288 40179 task_block.go:223] [ 23] Interrupt queued D0314 13:03:28.086901 40179 task_block.go:223] [ 24] Interrupt queued D0314 13:03:28.147836 40179 task_block.go:223] [ 25] Interrupt queued I0314 13:03:28.156144 40179 watchdog.go:277] Watchdog starting loop, tasks: 20, discount: 0s D0314 13:03:28.231635 40179 task_block.go:223] [ 26] Interrupt queued D0314 13:03:28.285196 40179 urpc.go:577] urpc: unmarshal success. D0314 13:03:28.285428 40179 controller.go:504] containerManager.Signal &{CID:ci-gvisor-ptrace-proxy-sandbox-race-3 Signo:0 PID:0 Mode:Process} D0314 13:03:28.285770 40179 urpc.go:534] urpc: successfully marshalled 37 bytes. D0314 13:03:28.409515 40179 task_block.go:223] [ 27] Interrupt queued D0314 13:03:28.599070 40179 transport_flipcall.go:127] send [channel @0xc000510240] Twalkgetattr{FID: 1, NewFID: 7, Names: [etc]} D0314 13:03:28.601257 40179 transport_flipcall.go:234] recv [channel @0xc000510240] Rlerror{Error: 2} D0314 13:03:28.601509 40179 transport_flipcall.go:127] send [channel @0xc000510240] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.etc, Size: 1} D0314 13:03:28.602665 40179 transport_flipcall.go:234] recv [channel @0xc000510240] Rlerror{Error: 95} D0314 13:03:28.759845 40179 parameters.go:238] Clock(Monotonic): error: 22 ns, adjusted frequency from 2299773481 Hz to 2299774647 Hz D0314 13:03:28.760492 40179 parameters.go:238] Clock(Realtime): error: 124 ns, adjusted frequency from 2299774269 Hz to 2299774831 Hz D0314 13:03:28.760762 40179 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:327287501493882 monotonicBaseRef:91000212592 monotonicFrequency:2299774647 realtimeReady:1 realtimeBaseCycles:327287506375643 realtimeBaseRef:1584191008760481776 realtimeFrequency:2299774831} D0314 13:03:28.842113 40179 task_block.go:223] [ 28] Interrupt queued D0314 13:03:29.018723 40179 task_block.go:223] [ 29] Interrupt queued D0314 13:03:29.043845 40179 task_block.go:223] [ 30] Interrupt queued D0314 13:03:29.126543 40179 task_block.go:223] [ 31] Interrupt queued D0314 13:03:29.214619 40179 task_block.go:223] [ 32] Interrupt queued D0314 13:03:29.289188 40179 transport_flipcall.go:127] send [channel @0xc000510240] Twalkgetattr{FID: 1, NewFID: 7, Names: [syzkaller287851874]} D0314 13:03:29.289487 40179 task_block.go:223] [ 33] Interrupt queued D0314 13:03:29.290935 40179 transport_flipcall.go:234] recv [channel @0xc000510240] Rwalkgetattr{Valid: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime Size Blocks}, Attr: Attr{Mode: 0o100600, UID: 0, GID: 0, NLink: 1, RDev: 0, Size: 273, BlockSize: 4096, Blocks: 8, ATime: {Sec: 1584191007, NanoSec: 272636913}, MTime: {Sec: 1584191007, NanoSec: 272636913}, CTime: {Sec: 1584191007, NanoSec: 272636913}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}, QIDs: [QID{Type: 0, Version: 0, Path: 7209355}]} D0314 13:03:29.291283 40179 transport_flipcall.go:127] send [channel @0xc000510240] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzkaller287851874, Size: 1} D0314 13:03:29.291959 40179 transport_flipcall.go:234] recv [channel @0xc000510240] Rlerror{Error: 95} D0314 13:03:29.292341 40179 transport_flipcall.go:127] send [channel @0xc000510240] Tgetattr{FID: 7, AttrMask: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime INo Size Blocks BTime Gen DataVersion}} D0314 13:03:29.293866 40179 transport_flipcall.go:234] recv [channel @0xc000510240] Rgetattr{Valid: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime Size Blocks}, QID: QID{Type: 0, Version: 0, Path: 7209355}, Attr: Attr{Mode: 0o100600, UID: 0, GID: 0, NLink: 1, RDev: 0, Size: 273, BlockSize: 4096, Blocks: 8, ATime: {Sec: 1584191007, NanoSec: 272636913}, MTime: {Sec: 1584191007, NanoSec: 272636913}, CTime: {Sec: 1584191007, NanoSec: 272636913}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}} D0314 13:03:29.294067 40179 transport_flipcall.go:127] send [channel @0xc000510240] Twalk{FID: 7, NewFID: 8, Names: []} D0314 13:03:29.294972 40179 transport_flipcall.go:234] recv [channel @0xc000510240] Rwalk{QIDs: []} D0314 13:03:29.295172 40179 transport_flipcall.go:127] send [channel @0xc000510240] Tlopen{FID: 8, Flags: ReadOnly} D0314 13:03:29.296263 40179 transport_flipcall.go:234] recv [channel @0xc000510240] Rlopen{QID: QID{Type: 0, Version: 0, Path: 7209355}, IoUnit: 0, File: &{{31}}} D0314 13:03:29.320908 40179 transport_flipcall.go:127] send [channel @0xc000510240] Tgetattr{FID: 8, AttrMask: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime INo Size Blocks BTime Gen DataVersion}} D0314 13:03:29.327570 40179 transport_flipcall.go:234] recv [channel @0xc000510240] Rgetattr{Valid: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime Size Blocks}, QID: QID{Type: 0, Version: 0, Path: 7209355}, Attr: Attr{Mode: 0o100600, UID: 0, GID: 0, NLink: 1, RDev: 0, Size: 273, BlockSize: 4096, Blocks: 8, ATime: {Sec: 1584191007, NanoSec: 272636913}, MTime: {Sec: 1584191007, NanoSec: 272636913}, CTime: {Sec: 1584191007, NanoSec: 272636913}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}} D0314 13:03:29.328285 40179 task_block.go:223] [ 34] Interrupt queued 2020/03/14 13:03:29 parsed 1 programs D0314 13:03:29.431392 40179 task_stop.go:118] [ 25] Entering internal stop (*kernel.vforkStop)(nil) D0314 13:03:29.434041 40179 task_block.go:223] [ 35] Interrupt queued D0314 13:03:29.465937 40179 transport_flipcall.go:127] send [channel @0xc000510240] Twalkgetattr{FID: 1, NewFID: 9, Names: [syz-executor]} D0314 13:03:29.468081 40179 transport_flipcall.go:234] recv [channel @0xc000510240] 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: 1584190920, NanoSec: 380120961}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}, QIDs: [QID{Type: 0, Version: 0, Path: 7209372}]} D0314 13:03:29.468330 40179 transport_flipcall.go:127] send [channel @0xc000510240] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syz-executor, Size: 1} D0314 13:03:29.470399 40179 transport_flipcall.go:234] recv [channel @0xc000510240] Rlerror{Error: 95} D0314 13:03:29.470945 40179 transport_flipcall.go:127] send [channel @0xc000510240] Tgetattr{FID: 9, AttrMask: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime INo Size Blocks BTime Gen DataVersion}} D0314 13:03:29.473333 40179 transport_flipcall.go:234] recv [channel @0xc000510240] Rgetattr{Valid: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime Size Blocks}, QID: QID{Type: 0, Version: 0, Path: 7209372}, 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: 1584190920, NanoSec: 380120961}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}} D0314 13:03:29.473504 40179 transport_flipcall.go:127] send [channel @0xc000510240] Twalk{FID: 9, NewFID: 10, Names: []} D0314 13:03:29.476223 40179 transport_flipcall.go:234] recv [channel @0xc000510240] Rwalk{QIDs: []} D0314 13:03:29.476553 40179 transport_flipcall.go:127] send [channel @0xc000510240] Tlopen{FID: 10, Flags: ReadOnly} D0314 13:03:29.477627 40179 transport_flipcall.go:234] recv [channel @0xc000510240] Rlopen{QID: QID{Type: 0, Version: 0, Path: 7209372}, IoUnit: 0, File: &{{32}}} D0314 13:03:29.479363 40179 syscalls.go:266] [ 35] Allocating stack with size of 8388608 bytes D0314 13:03:29.480541 40179 task_stop.go:137] [ 25] Leaving internal stop (*kernel.vforkStop)(nil) D0314 13:03:29.664749 40179 transport_flipcall.go:127] send [channel @0xc000510240] Twalkgetattr{FID: 1, NewFID: 11, Names: [etc]} D0314 13:03:29.665872 40179 transport_flipcall.go:234] recv [channel @0xc000510240] Rlerror{Error: 2} D0314 13:03:29.666166 40179 transport_flipcall.go:127] send [channel @0xc000510240] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.etc, Size: 1} D0314 13:03:29.666858 40179 transport_flipcall.go:234] recv [channel @0xc000510240] Rlerror{Error: 95} D0314 13:03:29.707447 40179 task_exit.go:221] [ 35] Transitioning from exit state TaskExitNone to TaskExitInitiated D0314 13:03:29.721173 40179 task_exit.go:221] [ 35] Transitioning from exit state TaskExitInitiated to TaskExitZombie D0314 13:03:29.722790 40179 task_signals.go:446] [ 19] Notified of signal 17 D0314 13:03:29.723509 40179 task_block.go:223] [ 19] Interrupt queued D0314 13:03:29.723425 40179 task_block.go:223] [ 19] Interrupt queued D0314 13:03:29.724210 40179 task_signals.go:179] [ 19] Restarting syscall 202 after errno 512: interrupted by signal 17 D0314 13:03:29.724384 40179 task_signals.go:220] [ 19] Signal 17: delivering to handler D0314 13:03:29.728833 40179 task_exit.go:221] [ 35] Transitioning from exit state TaskExitZombie to TaskExitDead D0314 13:03:29.739566 40179 transport_flipcall.go:127] send [channel @0xc000510240] Twalkgetattr{FID: 1, NewFID: 11, Names: [syz-executor]} D0314 13:03:29.740284 40179 task_block.go:223] [ 36] Interrupt queued D0314 13:03:29.743158 40179 transport_flipcall.go:234] recv [channel @0xc000510240] 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: 1584190920, NanoSec: 380120961}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}, QIDs: [QID{Type: 0, Version: 0, Path: 7209372}]} D0314 13:03:29.743619 40179 transport_flipcall.go:127] send [channel @0xc000510240] Tclunk{FID: 11} D0314 13:03:29.744294 40179 transport_flipcall.go:234] recv [channel @0xc000510240] Rclunk{} D0314 13:03:29.744693 40179 transport_flipcall.go:127] send [channel @0xc000510240] Tgetattr{FID: 9, AttrMask: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime INo Size Blocks BTime Gen DataVersion}} D0314 13:03:29.745772 40179 transport_flipcall.go:234] recv [channel @0xc000510240] Rgetattr{Valid: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime Size Blocks}, QID: QID{Type: 0, Version: 0, Path: 7209372}, 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: 1584190920, NanoSec: 380120961}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}} 2020/03/14 13:03:29 executed programs: 0 D0314 13:03:29.758312 40179 parameters.go:238] Clock(Monotonic): error: 497 ns, adjusted frequency from 2299774647 Hz to 2299775715 Hz D0314 13:03:29.758730 40179 parameters.go:238] Clock(Realtime): error: 43 ns, adjusted frequency from 2299774831 Hz to 2299774669 Hz D0314 13:03:29.758859 40179 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:327289801125552 monotonicBaseRef:92000150421 monotonicFrequency:2299775715 realtimeReady:1 realtimeBaseCycles:327289802106418 realtimeBaseRef:1584191009758723318 realtimeFrequency:2299774669} D0314 13:03:29.815703 40179 task_stop.go:118] [ 33] Entering internal stop (*kernel.vforkStop)(nil) D0314 13:03:29.816735 40179 task_block.go:223] [ 37] Interrupt queued D0314 13:03:29.849013 40179 transport_flipcall.go:127] send [channel @0xc000510240] Twalkgetattr{FID: 1, NewFID: 11, Names: [syz-executor]} D0314 13:03:29.852910 40179 transport_flipcall.go:234] recv [channel @0xc000510240] 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: 1584190920, NanoSec: 380120961}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}, QIDs: [QID{Type: 0, Version: 0, Path: 7209372}]} D0314 13:03:29.853163 40179 transport_flipcall.go:127] send [channel @0xc000510240] Tclunk{FID: 11} D0314 13:03:29.854588 40179 transport_flipcall.go:234] recv [channel @0xc000510240] Rclunk{} D0314 13:03:29.855290 40179 transport_flipcall.go:127] send [channel @0xc000510240] Tgetattr{FID: 9, AttrMask: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime INo Size Blocks BTime Gen DataVersion}} D0314 13:03:29.856303 40179 transport_flipcall.go:234] recv [channel @0xc000510240] Rgetattr{Valid: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime Size Blocks}, QID: QID{Type: 0, Version: 0, Path: 7209372}, 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: 1584190920, NanoSec: 380120961}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}} D0314 13:03:29.857502 40179 syscalls.go:266] [ 37] Allocating stack with size of 8388608 bytes D0314 13:03:29.859818 40179 task_stop.go:137] [ 33] Leaving internal stop (*kernel.vforkStop)(nil) D0314 13:03:29.919078 40179 transport_flipcall.go:127] send [channel @0xc000510240] Twalkgetattr{FID: 1, NewFID: 11, Names: [etc]} D0314 13:03:29.921077 40179 transport_flipcall.go:234] recv [channel @0xc000510240] Rlerror{Error: 2} D0314 13:03:29.921449 40179 transport_flipcall.go:127] send [channel @0xc000510240] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.etc, Size: 1} D0314 13:03:29.922724 40179 transport_flipcall.go:234] recv [channel @0xc000510240] Rlerror{Error: 95} D0314 13:03:30.027905 40179 task_block.go:223] [ 38] Interrupt queued D0314 13:03:30.060963 40179 transport_flipcall.go:127] send [channel @0xc000510240] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0314 13:03:30.061829 40179 transport_flipcall.go:234] recv [channel @0xc000510240] Rlerror{Error: 2} D0314 13:03:30.062000 40179 transport_flipcall.go:127] send [channel @0xc000510240] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0314 13:03:30.065499 40179 transport_flipcall.go:234] recv [channel @0xc000510240] Rlerror{Error: 95} D0314 13:03:30.066114 40179 transport_flipcall.go:127] send [channel @0xc000510240] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0314 13:03:30.067005 40179 transport_flipcall.go:234] recv [channel @0xc000510240] Rlerror{Error: 2} D0314 13:03:30.067190 40179 transport_flipcall.go:127] send [channel @0xc000510240] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0314 13:03:30.067641 40179 transport_flipcall.go:234] recv [channel @0xc000510240] Rlerror{Error: 95} D0314 13:03:30.068821 40179 transport_flipcall.go:127] send [channel @0xc000510240] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0314 13:03:30.069423 40179 transport_flipcall.go:234] recv [channel @0xc000510240] Rlerror{Error: 2} D0314 13:03:30.069646 40179 transport_flipcall.go:127] send [channel @0xc000510240] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0314 13:03:30.070218 40179 transport_flipcall.go:234] recv [channel @0xc000510240] Rlerror{Error: 95} D0314 13:03:30.070644 40179 transport_flipcall.go:127] send [channel @0xc000510240] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0314 13:03:30.071314 40179 transport_flipcall.go:234] recv [channel @0xc000510240] Rlerror{Error: 2} D0314 13:03:30.071510 40179 transport_flipcall.go:127] send [channel @0xc000510240] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0314 13:03:30.071956 40179 transport_flipcall.go:234] recv [channel @0xc000510240] Rlerror{Error: 95} D0314 13:03:30.075158 40179 transport_flipcall.go:127] send [channel @0xc000510240] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0314 13:03:30.075808 40179 transport_flipcall.go:234] recv [channel @0xc000510240] Rlerror{Error: 2} D0314 13:03:30.075962 40179 transport_flipcall.go:127] send [channel @0xc000510240] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0314 13:03:30.078206 40179 transport_flipcall.go:234] recv [channel @0xc000510240] Rlerror{Error: 95} D0314 13:03:30.078687 40179 transport_flipcall.go:127] send [channel @0xc000510240] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0314 13:03:30.079325 40179 transport_flipcall.go:234] recv [channel @0xc000510240] Rlerror{Error: 2} D0314 13:03:30.079574 40179 transport_flipcall.go:127] send [channel @0xc000510240] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0314 13:03:30.080130 40179 transport_flipcall.go:234] recv [channel @0xc000510240] Rlerror{Error: 95} D0314 13:03:30.083368 40179 transport_flipcall.go:127] send [channel @0xc000510240] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0314 13:03:30.092216 40179 transport_flipcall.go:234] recv [channel @0xc000510240] Rlerror{Error: 2} D0314 13:03:30.092445 40179 transport_flipcall.go:127] send [channel @0xc000510240] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0314 13:03:30.093058 40179 transport_flipcall.go:234] recv [channel @0xc000510240] Rlerror{Error: 95} D0314 13:03:30.101123 40179 transport_flipcall.go:127] send [channel @0xc000510240] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0314 13:03:30.105915 40179 transport_flipcall.go:234] recv [channel @0xc000510240] Rlerror{Error: 2} D0314 13:03:30.106128 40179 transport_flipcall.go:127] send [channel @0xc000510240] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0314 13:03:30.110932 40179 transport_flipcall.go:234] recv [channel @0xc000510240] Rlerror{Error: 95} D0314 13:03:30.111383 40179 transport_flipcall.go:127] send [channel @0xc000510240] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0314 13:03:30.170564 40179 transport_flipcall.go:234] recv [channel @0xc000510240] Rlerror{Error: 2} D0314 13:03:30.170836 40179 transport_flipcall.go:127] send [channel @0xc000510240] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0314 13:03:30.175090 40179 transport_flipcall.go:234] recv [channel @0xc000510240] Rlerror{Error: 95} D0314 13:03:30.313974 40179 netfilter.go:60] netfilter: convert to binary: current offset: 0 D0314 13:03:30.314271 40179 netfilter.go:60] netfilter: convert to binary: found hook 0 at offset 0 D0314 13:03:30.314458 40179 netfilter.go:60] netfilter: convert to binary: found underflow 0 at offset 0 D0314 13:03:30.327055 40179 netfilter.go:60] netfilter: convert to binary: marshalling standard target D0314 13:03:30.327280 40179 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]} D0314 13:03:30.328172 40179 netfilter.go:60] netfilter: convert to binary: current offset: 152 D0314 13:03:30.328349 40179 netfilter.go:60] netfilter: convert to binary: found hook 1 at offset 152 D0314 13:03:30.328616 40179 netfilter.go:60] netfilter: convert to binary: found underflow 1 at offset 152 D0314 13:03:30.329100 40179 netfilter.go:60] netfilter: convert to binary: marshalling standard target D0314 13:03:30.329296 40179 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]} D0314 13:03:30.330053 40179 netfilter.go:60] netfilter: convert to binary: current offset: 304 D0314 13:03:30.330206 40179 netfilter.go:60] netfilter: convert to binary: found hook 3 at offset 304 D0314 13:03:30.330371 40179 netfilter.go:60] netfilter: convert to binary: found underflow 3 at offset 304 D0314 13:03:30.330519 40179 netfilter.go:60] netfilter: convert to binary: marshalling standard target D0314 13:03:30.330699 40179 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]} D0314 13:03:30.332579 40179 netfilter.go:60] netfilter: convert to binary: current offset: 456 D0314 13:03:30.332841 40179 netfilter.go:60] netfilter: convert to binary: found hook 4 at offset 456 D0314 13:03:30.333053 40179 netfilter.go:60] netfilter: convert to binary: found underflow 4 at offset 456 D0314 13:03:30.333515 40179 netfilter.go:60] netfilter: convert to binary: marshalling standard target D0314 13:03:30.333934 40179 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]} D0314 13:03:30.336191 40179 netfilter.go:60] netfilter: convert to binary: current offset: 608 D0314 13:03:30.338722 40179 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]} D0314 13:03:30.341455 40179 netfilter.go:60] netfilter: convert to binary: finished with an marshalled size of 0 D0314 13:03:30.341641 40179 netfilter.go:60] netfilter: convert to binary: current offset: 0 D0314 13:03:30.341800 40179 netfilter.go:60] netfilter: convert to binary: found hook 0 at offset 0 D0314 13:03:30.341937 40179 netfilter.go:60] netfilter: convert to binary: found underflow 0 at offset 0 D0314 13:03:30.342106 40179 netfilter.go:60] netfilter: convert to binary: marshalling standard target D0314 13:03:30.345413 40179 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]} D0314 13:03:30.346251 40179 netfilter.go:60] netfilter: convert to binary: current offset: 152 D0314 13:03:30.346393 40179 netfilter.go:60] netfilter: convert to binary: found hook 3 at offset 152 D0314 13:03:30.346560 40179 netfilter.go:60] netfilter: convert to binary: found underflow 3 at offset 152 D0314 13:03:30.346658 40179 netfilter.go:60] netfilter: convert to binary: marshalling standard target D0314 13:03:30.346732 40179 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]} D0314 13:03:30.347260 40179 netfilter.go:60] netfilter: convert to binary: current offset: 304 D0314 13:03:30.347412 40179 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]} D0314 13:03:30.347981 40179 netfilter.go:60] netfilter: convert to binary: finished with an marshalled size of 0 D0314 13:03:30.348079 40179 netfilter.go:60] netfilter: convert to binary: current offset: 0 D0314 13:03:30.348182 40179 netfilter.go:60] netfilter: convert to binary: found hook 1 at offset 0 D0314 13:03:30.348270 40179 netfilter.go:60] netfilter: convert to binary: found underflow 1 at offset 0 D0314 13:03:30.348343 40179 netfilter.go:60] netfilter: convert to binary: marshalling standard target D0314 13:03:30.348449 40179 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]} D0314 13:03:30.351826 40179 netfilter.go:60] netfilter: convert to binary: current offset: 152 D0314 13:03:30.352411 40179 netfilter.go:60] netfilter: convert to binary: found hook 2 at offset 152 D0314 13:03:30.352623 40179 netfilter.go:60] netfilter: convert to binary: found underflow 2 at offset 152 D0314 13:03:30.353083 40179 netfilter.go:60] netfilter: convert to binary: marshalling standard target D0314 13:03:30.353397 40179 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]} D0314 13:03:30.354920 40179 netfilter.go:60] netfilter: convert to binary: current offset: 304 D0314 13:03:30.355288 40179 netfilter.go:60] netfilter: convert to binary: found hook 3 at offset 304 D0314 13:03:30.355505 40179 netfilter.go:60] netfilter: convert to binary: found underflow 3 at offset 304 D0314 13:03:30.356025 40179 netfilter.go:60] netfilter: convert to binary: marshalling standard target D0314 13:03:30.356471 40179 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]} D0314 13:03:30.357268 40179 netfilter.go:60] netfilter: convert to binary: current offset: 456 D0314 13:03:30.361872 40179 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]} D0314 13:03:30.362837 40179 netfilter.go:60] netfilter: convert to binary: finished with an marshalled size of 0 D0314 13:03:30.759709 40179 parameters.go:238] Clock(Monotonic): error: -406 ns, adjusted frequency from 2299775715 Hz to 2299773603 Hz D0314 13:03:30.760055 40179 parameters.go:238] Clock(Realtime): error: -74 ns, adjusted frequency from 2299774669 Hz to 2299774353 Hz D0314 13:03:30.760199 40179 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:327292104115834 monotonicBaseRef:93001548195 monotonicFrequency:2299773603 realtimeReady:1 realtimeBaseCycles:327292104927411 realtimeBaseRef:1584191010760047936 realtimeFrequency:2299774353} D0314 13:03:31.250778 40179 transport_flipcall.go:127] send [channel @0xc000510240] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0314 13:03:31.263491 40179 transport_flipcall.go:234] recv [channel @0xc000510240] Rlerror{Error: 2} D0314 13:03:31.264025 40179 transport_flipcall.go:127] send [channel @0xc000510240] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0314 13:03:31.266223 40179 transport_flipcall.go:234] recv [channel @0xc000510240] Rlerror{Error: 95} D0314 13:03:31.269060 40179 transport_flipcall.go:127] send [channel @0xc000510240] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0314 13:03:31.271153 40179 transport_flipcall.go:234] recv [channel @0xc000510240] Rlerror{Error: 2} D0314 13:03:31.271464 40179 transport_flipcall.go:127] send [channel @0xc000510240] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0314 13:03:31.272141 40179 transport_flipcall.go:234] recv [channel @0xc000510240] Rlerror{Error: 95} D0314 13:03:31.272817 40179 transport_flipcall.go:127] send [channel @0xc000510240] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0314 13:03:31.275727 40179 transport_flipcall.go:234] recv [channel @0xc000510240] Rlerror{Error: 2} D0314 13:03:31.276058 40179 transport_flipcall.go:127] send [channel @0xc000510240] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0314 13:03:31.276711 40179 transport_flipcall.go:234] recv [channel @0xc000510240] Rlerror{Error: 95} D0314 13:03:31.281690 40179 transport_flipcall.go:127] send [channel @0xc000510240] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0314 13:03:31.282286 40179 transport_flipcall.go:234] recv [channel @0xc000510240] Rlerror{Error: 2} D0314 13:03:31.287397 40179 transport_flipcall.go:127] send [channel @0xc000510240] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0314 13:03:31.287951 40179 transport_flipcall.go:234] recv [channel @0xc000510240] Rlerror{Error: 95} D0314 13:03:31.290617 40179 transport_flipcall.go:127] send [channel @0xc000510240] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0314 13:03:31.291084 40179 transport_flipcall.go:234] recv [channel @0xc000510240] Rlerror{Error: 2} D0314 13:03:31.291220 40179 transport_flipcall.go:127] send [channel @0xc000510240] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0314 13:03:31.304899 40179 transport_flipcall.go:234] recv [channel @0xc000510240] Rlerror{Error: 95} D0314 13:03:31.306293 40179 transport_flipcall.go:127] send [channel @0xc000510240] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0314 13:03:31.306968 40179 transport_flipcall.go:234] recv [channel @0xc000510240] Rlerror{Error: 2} D0314 13:03:31.307241 40179 transport_flipcall.go:127] send [channel @0xc000510240] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0314 13:03:31.309198 40179 transport_flipcall.go:234] recv [channel @0xc000510240] Rlerror{Error: 95} D0314 13:03:31.313585 40179 transport_flipcall.go:127] send [channel @0xc000510240] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0314 13:03:31.314265 40179 transport_flipcall.go:234] recv [channel @0xc000510240] Rlerror{Error: 2} D0314 13:03:31.314426 40179 transport_flipcall.go:127] send [channel @0xc000510240] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0314 13:03:31.314926 40179 transport_flipcall.go:234] recv [channel @0xc000510240] Rlerror{Error: 95} D0314 13:03:31.315297 40179 transport_flipcall.go:127] send [channel @0xc000510240] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0314 13:03:31.315840 40179 transport_flipcall.go:234] recv [channel @0xc000510240] Rlerror{Error: 2} D0314 13:03:31.315968 40179 transport_flipcall.go:127] send [channel @0xc000510240] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0314 13:03:31.316582 40179 transport_flipcall.go:234] recv [channel @0xc000510240] Rlerror{Error: 95} D0314 13:03:31.317148 40179 transport_flipcall.go:127] send [channel @0xc000510240] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0314 13:03:31.317754 40179 transport_flipcall.go:234] recv [channel @0xc000510240] Rlerror{Error: 2} D0314 13:03:31.317861 40179 transport_flipcall.go:127] send [channel @0xc000510240] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0314 13:03:31.318413 40179 transport_flipcall.go:234] recv [channel @0xc000510240] Rlerror{Error: 95} D0314 13:03:31.318777 40179 transport_flipcall.go:127] send [channel @0xc000510240] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0314 13:03:31.319381 40179 transport_flipcall.go:234] recv [channel @0xc000510240] Rlerror{Error: 2} D0314 13:03:31.319664 40179 transport_flipcall.go:127] send [channel @0xc000510240] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0314 13:03:31.320305 40179 transport_flipcall.go:234] recv [channel @0xc000510240] Rlerror{Error: 95} D0314 13:03:31.514769 40179 task_block.go:223] [ 39] Interrupt queued panic: runtime error: makeslice: len out of range DIAGNOSIS: I0314 13:03:31.783554 63817 main.go:305] *************************** I0314 13:03:31.783798 63817 main.go:306] Args: [/syzkaller/managers/ptrace-proxy-sandbox-race/current/image -root /syzkaller/managers/ptrace-proxy-sandbox-race/workdir/gvisor_root -watchdog-action=panic -network=none -debug -alsologtostderr -platform=ptrace -file-access=shared -network=sandbox -TESTONLY-unsafe-nonroot debug -stacks --ps ci-gvisor-ptrace-proxy-sandbox-race-3] I0314 13:03:31.784099 63817 main.go:307] Version release-20200219.0-175-g829beebf0b67 I0314 13:03:31.784262 63817 main.go:308] PID: 63817 I0314 13:03:31.784427 63817 main.go:309] UID: 0, GID: 0 I0314 13:03:31.784618 63817 main.go:310] Configuration: I0314 13:03:31.784817 63817 main.go:311] RootDir: /syzkaller/managers/ptrace-proxy-sandbox-race/workdir/gvisor_root I0314 13:03:31.784992 63817 main.go:312] Platform: ptrace I0314 13:03:31.785145 63817 main.go:313] FileAccess: shared, overlay: false I0314 13:03:31.785367 63817 main.go:314] Network: sandbox, logging: false I0314 13:03:31.785542 63817 main.go:315] Strace: false, max size: 1024, syscalls: [] I0314 13:03:31.785728 63817 main.go:316] *************************** W0314 13:03:31.785882 63817 main.go:321] Block the TERM signal. This is only safe in tests! D0314 13:03:31.786132 63817 container.go:159] Load container "/syzkaller/managers/ptrace-proxy-sandbox-race/workdir/gvisor_root" "ci-gvisor-ptrace-proxy-sandbox-race-3" D0314 13:03:31.793176 63817 container.go:592] Signal container "ci-gvisor-ptrace-proxy-sandbox-race-3": signal 0 D0314 13:03:31.793389 63817 sandbox.go:814] Signal sandbox "ci-gvisor-ptrace-proxy-sandbox-race-3" D0314 13:03:31.793540 63817 sandbox.go:318] Connecting to sandbox "ci-gvisor-ptrace-proxy-sandbox-race-3" D0314 13:03:31.794118 63817 urpc.go:534] urpc: successfully marshalled 117 bytes. I0314 13:03:31.983125 63817 debug.go:128] Found sandbox "ci-gvisor-ptrace-proxy-sandbox-race-3", PID: 40179 I0314 13:03:31.983343 63817 debug.go:137] Retrieving sandbox stacks D0314 13:03:31.983468 63817 sandbox.go:932] Stacks sandbox "ci-gvisor-ptrace-proxy-sandbox-race-3" D0314 13:03:31.983613 63817 sandbox.go:318] Connecting to sandbox "ci-gvisor-ptrace-proxy-sandbox-race-3" W0314 13:03:31.983834 63817 error.go:48] FATAL ERROR: retrieving stacks: connecting to control server at PID 40179: connection refused retrieving stacks: connecting to control server at PID 40179: connection refused W0314 13:03:31.984259 63817 main.go:338] Failure to execute command, err: 1 Error collecting stacks: failed to run ["/syzkaller/managers/ptrace-proxy-sandbox-race/current/image" "-root" "/syzkaller/managers/ptrace-proxy-sandbox-race/workdir/gvisor_root" "-watchdog-action=panic" "-network=none" "-debug" "-alsologtostderr" "-platform=ptrace" "-file-access=shared" "-network=sandbox" "-TESTONLY-unsafe-nonroot" "debug" "-stacks" "--ps" "ci-gvisor-ptrace-proxy-sandbox-race-3"]: exit status 128 I0314 13:03:31.783554 63817 main.go:305] *************************** I0314 13:03:31.783798 63817 main.go:306] Args: [/syzkaller/managers/ptrace-proxy-sandbox-race/current/image -root /syzkaller/managers/ptrace-proxy-sandbox-race/workdir/gvisor_root -watchdog-action=panic -network=none -debug -alsologtostderr -platform=ptrace -file-access=shared -network=sandbox -TESTONLY-unsafe-nonroot debug -stacks --ps ci-gvisor-ptrace-proxy-sandbox-race-3] I0314 13:03:31.784099 63817 main.go:307] Version release-20200219.0-175-g829beebf0b67 I0314 13:03:31.784262 63817 main.go:308] PID: 63817 I0314 13:03:31.784427 63817 main.go:309] UID: 0, GID: 0 I0314 13:03:31.784618 63817 main.go:310] Configuration: I0314 13:03:31.784817 63817 main.go:311] RootDir: /syzkaller/managers/ptrace-proxy-sandbox-race/workdir/gvisor_root I0314 13:03:31.784992 63817 main.go:312] Platform: ptrace I0314 13:03:31.785145 63817 main.go:313] FileAccess: shared, overlay: false I0314 13:03:31.785367 63817 main.go:314] Network: sandbox, logging: false I0314 13:03:31.785542 63817 main.go:315] Strace: false, max size: 1024, syscalls: [] I0314 13:03:31.785728 63817 main.go:316] *************************** W0314 13:03:31.785882 63817 main.go:321] Block the TERM signal. This is only safe in tests! D0314 13:03:31.786132 63817 container.go:159] Load container "/syzkaller/managers/ptrace-proxy-sandbox-race/workdir/gvisor_root" "ci-gvisor-ptrace-proxy-sandbox-race-3" D0314 13:03:31.793176 63817 container.go:592] Signal container "ci-gvisor-ptrace-proxy-sandbox-race-3": signal 0 D0314 13:03:31.793389 63817 sandbox.go:814] Signal sandbox "ci-gvisor-ptrace-proxy-sandbox-race-3" D0314 13:03:31.793540 63817 sandbox.go:318] Connecting to sandbox "ci-gvisor-ptrace-proxy-sandbox-race-3" D0314 13:03:31.794118 63817 urpc.go:534] urpc: successfully marshalled 117 bytes. I0314 13:03:31.983125 63817 debug.go:128] Found sandbox "ci-gvisor-ptrace-proxy-sandbox-race-3", PID: 40179 I0314 13:03:31.983343 63817 debug.go:137] Retrieving sandbox stacks D0314 13:03:31.983468 63817 sandbox.go:932] Stacks sandbox "ci-gvisor-ptrace-proxy-sandbox-race-3" D0314 13:03:31.983613 63817 sandbox.go:318] Connecting to sandbox "ci-gvisor-ptrace-proxy-sandbox-race-3" W0314 13:03:31.983834 63817 error.go:48] FATAL ERROR: retrieving stacks: connecting to control server at PID 40179: connection refused retrieving stacks: connecting to control server at PID 40179: connection refused W0314 13:03:31.984259 63817 main.go:338] Failure to execute command, err: 1 [41254.930854] [ 1743] 106 1743 24474 131 23 4 0 0 ntpd [41254.941091] [ 1746] 0 1746 345015 41660 576 4 0 0 ruby [41254.949949] [ 2124] 0 2124 17077 3607 39 4 0 0 google_network_ [41254.959790] [ 2195] 0 2195 17175 3717 40 3 0 -999 google_accounts [41254.969413] [ 2354] 0 2354 17072 3579 39 3 0 0 google_clock_sk [41254.979221] [ 2355] 0 2355 17489 187 37 4 0 -1000 sshd [41254.988253] [14088] 0 14088 105835 40182 152 5 0 0 syz-manager [41254.997631] [28925] 0 28925 114556 53129 168 5 0 0 syz-manager [41255.006989] [43361] 0 43361 126322 68372 208 5 0 0 syz-manager [41255.016342] [29507] 0 29507 102023 53033 193 5 0 0 syz-manager [41255.025721] [39690] 0 39690 62528 532 30 3 0 0 rsyslogd [41255.034774] [18693] 0 18693 315379 4077 63 10 0 0 image [41255.043514] [18730] 0 18730 434283 7168 169 11 0 0 exe [41255.052094] [18735] 0 18735 147779953 28524891 63022 166 0 0 exe [41255.060772] [18777] 0 18777 1 0 1 1 0 0 exe [41255.070137] [18848] 0 18848 23773 5364 52 6 0 0 exe [41255.078712] [20160] 0 20160 68776 46465 139 5 0 0 syz-manager [41255.088070] [35794] 0 35794 315315 4027 61 10 0 0 image [41255.096809] [35833] 0 35833 370258 4855 83 10 0 0 exe [41255.105368] [35840] 0 35840 946531 20692 439 17 0 0 exe [41255.113933] [35913] 0 35913 1 1 1 1 0 0 exe [41255.122517] [35971] 0 35971 23370 4992 52 6 0 0 exe [41255.131070] [38807] 0 38807 315379 4070 64 10 0 0 image [41255.139804] [38816] 0 38816 370258 4784 80 10 0 0 exe [41255.148367] [38817] 0 38817 1175428 21760 500 18 0 0 exe [41255.156925] [38837] 0 38837 1 1 1 1 0 0 exe [41255.165487] [38857] 0 38857 23734 5332 52 6 0 0 exe [41255.174687] [47956] 0 47956 179879 650 20 5 0 0 image [41255.183425] [47968] 0 47968 180231 653 21 5 0 0 image [41255.194381] [47999] 0 47999 181675 1168 28 5 0 0 exe [41255.202966] [48000] 0 48000 181803 1477 27 5 0 0 exe [41255.211529] [48005] 65534 48005 397864 37310 341 7 0 0 exe [41255.220106] [48010] 65534 48010 347959 25921 238 6 0 0 exe [41255.229203] [48065] 65534 48065 1 1 1 1 0 0 exe [41255.237787] [48066] 65534 48066 1 1 1 1 0 0 exe [41255.246436] [48106] 65534 48106 23487 4020 51 6 0 0 exe [41255.255004] [48108] 65534 48108 23639 3599 52 6 0 0 exe [41255.263560] [48362] 0 48362 180231 647 20 4 0 0 image [41255.272418] [48373] 65534 48373 83296 55097 136 6 0 0 exe [41255.280976] [48382] 0 48382 296946 3952 59 9 0 0 image [41255.289729] [48403] 0 48403 432283 8893 168 11 0 0 exe [41255.298286] [48404] 0 48404 2023124 527260 1875 22 0 0 exe [41255.306935] [48405] 0 48405 179879 606 20 4 0 0 image [41255.315779] [48422] 65534 48422 83607 55203 136 7 0 0 exe [41255.324340] [48437] 0 48437 1 1 1 1 0 0 exe [41255.332899] [48557] 65534 48557 755 1 5 4 0 0 exe [41255.341458] [48566] 0 48566 23509 4724 52 6 0 0 exe [41255.350035] [48584] 65534 48584 844 1 5 4 0 0 exe [41255.391754] [48872] 0 48872 296594 3691 56 9 0 0 image [41255.400494] [48890] 0 48890 62261 34973 94 6 0 0 exe [41255.409060] [48980] 0 48980 718 1 5 4 0 0 exe [41255.418402] [50531] 0 50531 363 1 5 4 0 0 exe [41255.427002] [50539] 0 50539 4332 1 3 3 0 0 exe [41255.435785] [50549] 0 50549 515 1 5 4 0 0 exe [41255.444344] [50556] 0 50556 4332 1 3 3 0 0 exe [41255.453258] [50566] 0 50566 670 1 5 4 0 0 exe [41255.461842] [50577] 0 50577 4609 1 5 4 0 0 exe [41255.470419] [50585] 0 50585 4332 1 3 3 0 0 exe [41255.478992] [50809] 0 50809 1 1 1 1 0 0 exe [41255.487573] [50822] 0 50822 1 1 1 1 0 0 exe [41255.496148] [50874] 0 50874 1 1 1 1 0 0 exe [41255.505117] [50881] 0 50881 1 1 1 1 0 0 exe [41255.513684] [51374] 0 51374 294545 3955 56 10 0 0 image [41255.522446] [51411] 0 51411 370386 4777 82 10 0 0 exe [41255.531031] [51412] 0 51412 764025 18992 388 16 0 0 exe [41255.539760] [51482] 65534 51482 618 1 5 4 0 0 exe [41255.548333] [51495] 65534 51495 4849 1 5 4 0 0 exe [41255.556896] [51508] 65534 51508 460 1 5 4 0 0 exe [41255.565480] [51519] 65534 51519 4772 1 6 4 0 0 exe [41255.574056] [51525] 65534 51525 353 1 5 4 0 0 exe [41255.582768] [51528] 65534 51528 4616 1 6 4 0 0 exe [41255.591353] [51539] 65534 51539 4509 1 6 4 0 0 exe [41255.600437] [51541] 0 51541 1 1 1 1 0 0 exe [41255.609141] [51604] 65534 51604 1 1 1 1 0 0 exe [41255.617876] [51633] 65534 51633 5000 1 6 4 0 0 exe [41255.626438] [51635] 65534 51635 403 1 5 4 0 0 exe [41255.634998] [51648] 65534 51648 6693 1 9 5 0 0 exe [41255.643557] [51660] 65534 51660 801 1 5 4 0 0 exe [41255.652110] [51670] 65534 51670 4559 1 6 4 0 0 exe [41255.660704] [51679] 65534 51679 1 1 1 1 0 0 exe [41255.669269] [51691] 65534 51691 466 1 5 4 0 0 exe [41255.677844] [51696] 65534 51696 4955 1 6 4 0 0 exe [41255.686400] [51713] 65534 51713 4560 1 5 4 0 0 exe [41255.694959] [51760] 65534 51760 1 1 1 1 0 0 exe [41255.703554] [51903] 65534 51903 1 1 1 1 0 0 exe [41255.712115] [51922] 65534 51922 1 1 1 1 0 0 exe [41255.720676] [51944] 65534 51944 5894 1 8 5 0 0 exe [41255.729270] [51959] 65534 51959 1 1 1 1 0 0 exe [41255.737879] [52093] 0 52093 23714 4783 52 6 0 0 exe [41255.746436] [52202] 0 52202 296594 4009 58 9 0 0 image [41255.755164] [52242] 0 52242 432234 7028 166 11 0 0 exe [41255.763744] [52246] 0 52246 2222281 569445 2044 23 0 0 exe [41255.772305] [52347] 0 52347 1 1 1 1 0 0 exe [41255.780873] [52407] 0 52407 23683 4520 52 6 0 0 exe [41255.789431] [52588] 0 52588 180231 711 21 5 0 0 image [41255.798172] [52599] 0 52599 181611 1250 27 5 0 0 exe [41255.806735] [52620] 65534 52620 372599 35525 288 6 0 0 exe [41255.815304] [52651] 65534 52651 1 1 1 1 0 0 exe [41255.823884] [52696] 65534 52696 23531 4016 52 6 0 0 exe [41255.832446] [52714] 0 52714 296658 3706 58 10 0 0 image [41255.841336] [52758] 0 52758 65696 18869 101 6 0 0 exe [41255.849933] [52932] 0 52932 1 1 1 1 0 0 exe [41255.858489] [52968] 0 52968 315379 3761 63 10 0 0 image [41255.867220] [52985] 0 52985 29066 3199 29 6 0 0 exe [41255.876648] [52993] 0 52993 179879 653 20 5 0 0 image [41255.885439] [53009] 65534 53009 89076 60175 147 6 0 0 exe [41255.894167] [53145] 65534 53145 349 1 5 4 0 0 exe [41255.902723] [53153] 0 53153 1 1 1 1 0 0 exe [41255.911289] [53188] 0 53188 1 1 1 1 0 0 exe [41255.919906] [53192] 0 53192 1 1 1 1 0 0 exe [41255.928519] [53217] 0 53217 1 1 1 1 0 0 exe [41255.937211] [53223] 0 53223 1 1 1 1 0 0 exe [41255.945808] [53231] 0 53231 1 1 1 1 0 0 exe [41255.954404] [53236] 0 53236 1 1 1 1 0 0 exe [41255.962962] [53247] 0 53247 1 1 1 1 0 0 exe [41255.971513] [53338] 0 53338 6859 1 10 5 0 0 exe [41255.980063] [53362] 0 53362 6588 1 9 5 0 0 exe [41255.988617] [53570] 0 53570 694 1 5 4 0 0 exe [41255.997168] [53574] 0 53574 498 1 5 4 0 0 exe [41256.005758] [53581] 0 53581 553 1 5 4 0 0 exe [41256.014311] [53585] 0 53585 4848 1 6 4 0 0 exe [41256.022871] [53591] 0 53591 512 1 5 4 0 0 exe [41256.031540] [53597] 0 53597 4707 1 6 4 0 0 exe [41256.040100] [53610] 0 53610 4668 1 6 4 0 0 exe [41256.048661] [53657] 0 53657 4592 1 5 4 0 0 exe [41256.057214] [53675] 0 53675 1 1 1 1 0 0 exe [41256.065768] [53680] 0 53680 1 1 1 1 0 0 exe [41256.074367] [53703] 0 53703 1 1 1 1 0 0 exe [41256.082925] [54274] 0 54274 6564 1 9 5 0 0 exe [41256.091842] [54310] 0 54310 6367 1 9 5 0 0 exe [41256.100439] [54686] 65534 54686 356 1 5 4 0 0 exe [41256.109037] [54689] 65534 54689 4332 1 3 3 0 0 exe [41256.117593] [54693] 65534 54693 361 1 5 4 0 0 exe [41256.126847] [54702] 65534 54702 362 1 5 4 0 0 exe [41256.135410] [54708] 65534 54708 4555 1 6 4 0 0 exe [41256.144327] [54717] 65534 54717 4332 1 3 3 0 0 exe [41256.153059] [54721] 65534 54721 4332 1 3 3 0 0 exe [41256.162087] [54738] 65534 54738 1 1 1 1 0 0 exe [41256.170777] [54764] 65534 54764 415 1 5 3 0 0 exe [41256.179362] [54799] 65534 54799 1 1 1 1 0 0 exe [41256.188110] [54830] 65534 54830 1 1 1 1 0 0 exe [41256.196781] [55334] 65534 55334 1 1 1 1 0 0 exe [41256.205361] [55553] 65534 55553 1 1 1 1 0 0 exe [41256.213928] [57809] 65534 57809 415 1 5 3 0 0 exe [41256.222511] [57821] 65534 57821 1 1 1 1 0 0 exe [41256.231218] [58345] 65534 58345 1 1 1 1 0 0 exe [41256.239792] [61655] 0 61655 180231 723 23 5 0 0 image [41256.248841] [61662] 0 61662 181387 782 23 5 0 0 exe [41256.257402] [61663] 0 61663 210413 5722 58 6 0 0 exe [41256.265975] [61692] 0 61692 1 1 1 1 0 0 exe [41256.274537] [61727] 0 61727 23331 4002 32 6 0 0 exe [41256.283101] [62637] 0 62637 180231 678 21 4 0 0 image [41256.291832] [62644] 0 62644 180971 728 23 5 0 0 exe [41256.300390] [62649] 65534 62649 17181669830 9724 126 6 0 0 exe [41256.309217] [62736] 0 62736 180167 720 21 4 0 0 image [41256.317955] [62742] 0 62742 181451 728 25 5 0 0 exe [41256.326505] [62747] 65534 62747 17181701324 48511 188 6 0 0 exe [41256.335324] [63057] 0 63057 179879 652 21 4 0 0 image [41256.344064] [63072] 0 63072 179815 651 21 4 0 0 image [41256.352823] [63078] 0 63078 181387 768 24 5 0 0 exe [41256.361477] [63080] 0 63080 210605 5727 60 6 0 0 exe [41256.370065] [63120] 0 63120 1 1 1 1 0 0 exe [41256.378649] [63168] 0 63168 23677 3786 32 6 0 0 exe [41256.387234] [64271] 0 64271 179879 672 21 4 0 0 image [41256.395974] [64278] 0 64278 181323 705 24 5 0 0 exe [41256.404741] [64279] 0 64279 210605 5841 58 5 0 0 exe [41256.413461] [64309] 0 64309 1 1 1 1 0 0 exe [41256.422034] [64338] 0 64338 24223 3981 32 6 0 0 exe [41256.430614] Out of memory: Kill process 18735 (exe) score 896 or sacrifice child [41256.438892] Killed process 18735 (exe) total-vm:591119812kB, anon-rss:114067896kB, file-rss:0kB, shmem-rss:37780kB [41263.042844] oom_reaper: reaped process 18735 (exe), now anon-rss:0kB, file-rss:24kB, shmem-rss:37780kB [41265.885758] kvm [1396]: vcpu0, guest rIP: 0xa74064 unhandled rdmsr: 0xce [41267.159132] kvm [1525]: vcpu0, guest rIP: 0xa74064 unhandled rdmsr: 0xce [41271.308251] kvm [2512]: vcpu0, guest rIP: 0xa74064 unhandled rdmsr: 0xce [41477.675752] kvm [14464]: vcpu0, guest rIP: 0xa74064 unhandled rdmsr: 0xce [41479.024945] kvm [14514]: vcpu0, guest rIP: 0xa74064 unhandled rdmsr: 0xce [41480.522907] syz-manager invoked oom-killer: gfp_mask=0x24201ca(GFP_HIGHUSER_MOVABLE|__GFP_COLD), nodemask=0, order=0, oom_score_adj=0 [41480.535209] syz-manager cpuset=/ mems_allowed=0 [41480.540253] CPU: 7 PID: 29511 Comm: syz-manager Not tainted 4.9.0-11-amd64 #1 Debian 4.9.189-3+deb9u2 [41480.549593] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 [41480.559200] 0000000000000000 ffffffffa1136404 ffffba19565fbc10 ffff9fca40f53000 [41480.567261] ffffffffa1009690 0000000000000000 0000000000000000 0000000c00000001 [41480.575465] ffff9fc6f3dbc040 ffffffffa0f8d207 0000004200000000 ffffffffa10acee0 [41480.583707] Call Trace: [41480.586295] [] ? dump_stack+0x5c/0x78 [41480.591743] [] ? dump_header+0x78/0x1fd [41480.597448] [] ? get_page_from_freelist+0x3f7/0xb20 [41480.604456] [] ? cap_inode_killpriv+0x20/0x20 [41480.610598] [] ? oom_kill_process+0x22a/0x3f0 [41480.616753] [] ? out_of_memory+0x111/0x470 [41480.622623] [] ? __alloc_pages_slowpath+0xa1f/0xb30 [41480.629275] [] ? __alloc_pages_nodemask+0x201/0x260 [41480.635925] [] ? alloc_pages_current+0x91/0x140 [41480.642256] [] ? filemap_fault+0x326/0x5d0 [41480.648156] [] ? ext4_filemap_fault+0x31/0x50 [ext4] [41480.655032] [] ? __do_fault+0xa9/0x1c0 [41480.660562] [] ? handle_mm_fault+0xda4/0x1350 [41480.666800] [] ? poll_select_copy_remaining+0x11b/0x150 [41480.673799] [] ? __do_page_fault+0x255/0x4f0 [41480.679843] [] ? page_fault+0x28/0x30 [41480.685324] Mem-Info: [41480.687740] active_anon:30387408 inactive_anon:161534 isolated_anon:0 active_file:216 inactive_file:414 isolated_file:0 unevictable:0 dirty:0 writeback:0 unstable:0 slab_reclaimable:6679 slab_unreclaimable:29323 mapped:156905 shmem:169483 pagetables:77420 bounce:0 free:138702 free_pcp:172 free_cma:0 [41480.722576] Node 0 active_anon:121549632kB inactive_anon:646136kB active_file:528kB inactive_file:1056kB unevictable:0kB isolated(anon):0kB isolated(file):0kB mapped:627640kB dirty:0kB writeback:0kB shmem:677932kB shmem_thp: 0kB shmem_pmdmapped: 0kB anon_thp: 139264kB writeback_tmp:0kB unstable:0kB pages_scanned:2348 all_unreclaimable? no [41480.752672] 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 [41480.782565] lowmem_reserve[]: 0 2974 120837 120837 120837 [41480.788862] Node 0 DMA32 free:473108kB min:1660kB low:4704kB high:7748kB active_anon:2583048kB inactive_anon:0kB active_file:4kB inactive_file:28kB unevictable:0kB writepending:0kB present:3129320kB managed:3063752kB mlocked:0kB slab_reclaimable:76kB slab_unreclaimable:656kB kernel_stack:16kB pagetables:4860kB bounce:0kB free_pcp:0kB local_pcp:0kB free_cma:0kB [41480.821022] lowmem_reserve[]: 0 0 117862 117862 117862 [41480.827042] Node 0 Normal free:65816kB min:65908kB low:186596kB high:307284kB active_anon:118966588kB inactive_anon:646136kB active_file:0kB inactive_file:956kB unevictable:0kB writepending:0kB present:122683392kB managed:120696220kB mlocked:0kB slab_reclaimable:26640kB slab_unreclaimable:116636kB kernel_stack:39904kB pagetables:304820kB bounce:0kB free_pcp:0kB local_pcp:0kB free_cma:0kB [41480.861596] lowmem_reserve[]: 0 0 0 0 0 [41480.866375] 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 [41480.882723] Node 0 DMA32: 576*4kB (UME) 482*8kB (UME) 162*16kB (UME) 60*32kB (UMEH) 89*64kB (UMEH) 69*128kB (UMEH) 22*256kB (UMEH) 8*512kB (U) 4*1024kB (UE) 0*2048kB 106*4096kB (M) = 473200kB [41480.903195] Node 0 Normal: 10937*4kB (UME) 3003*8kB (UME) 11*16kB (UM) 0*32kB 0*64kB 0*128kB 0*256kB 0*512kB 0*1024kB 0*2048kB 0*4096kB = 67948kB [41480.918595] Node 0 hugepages_total=0 hugepages_free=0 hugepages_surp=0 hugepages_size=1048576kB [41480.927432] Node 0 hugepages_total=0 hugepages_free=0 hugepages_surp=0 hugepages_size=2048kB [41480.936261] 170257 total pagecache pages [41480.940320] 0 pages in swap cache [41480.943772] Swap cache stats: add 0, delete 0, find 0/0 [41480.949509] Free swap = 0kB [41480.952623] Total swap = 0kB [41480.955638] 31457176 pages RAM [41480.958829] 0 pages HighMem/MovableOnly [41480.962803] 513206 pages reserved [41480.966253] 0 pages hwpoisoned [41480.969441] [ pid ] uid tgid total_vm rss nr_ptes nr_pmds swapents oom_score_adj name [41480.978275] [ 935] 0 935 90410 145 176 4 0 0 systemd-journal [41480.987906] [ 960] 0 960 11548 333 23 3 0 -1000 systemd-udevd [41480.997349] [ 1125] 0 1125 13686 112 26 3 0 -1000 auditd [41481.006215] [ 1137] 0 1137 21093 340 12 3 0 0 audispd [41481.015158] [ 1151] 109 1151 11282 108 28 3 0 -900 dbus-daemon [41481.024419] [ 1155] 0 1155 1051 21 8 3 0 0 acpid [41481.033165] [ 1157] 0 1157 7409 61 19 3 0 0 cron [41481.041876] [ 1158] 0 1158 9495 122 22 3 0 0 systemd-logind [41481.051399] [ 1180] 0 1180 31178 3538 63 5 0 0 syz-ci [41481.060218] [ 1660] 0 1660 5119 274 12 3 0 0 dhclient [41481.069207] [ 1707] 0 1707 90807 12312 116 4 0 0 puppet [41481.078035] [ 1714] 0 1714 3575 34 12 3 0 0 agetty [41481.086854] [ 1716] 0 1716 3631 33 12 3 0 0 agetty [41481.095686] [ 1743] 106 1743 24474 131 23 4 0 0 ntpd [41481.104326] [ 1746] 0 1746 344820 41660 575 4 0 0 ruby [41481.112969] [ 2124] 0 2124 17077 3608 39 4 0 0 google_network_ [41481.122567] [ 2195] 0 2195 17175 3717 40 3 0 -999 google_accounts [41481.132171] [ 2354] 0 2354 17072 3579 39 3 0 0 google_clock_sk [41481.141771] [ 2355] 0 2355 17489 187 37 4 0 -1000 sshd [41481.150445] [14088] 0 14088 105835 47959 154 5 0 0 syz-manager [41481.159702] [28925] 0 28925 114556 52365 168 5 0 0 syz-manager [41481.168978] [43361] 0 43361 126322 81958 233 5 0 0 syz-manager [41481.178252] [29507] 0 29507 102023 60040 193 5 0 0 syz-manager [41481.187530] [39690] 0 39690 62528 550 30 3 0 0 rsyslogd [41481.196561] [20160] 0 20160 80813 57798 162 5 0 0 syz-manager [41481.205830] [35794] 0 35794 315315 4027 61 10 0 0 image [41481.214574] [35833] 0 35833 430074 7223 166 11 0 0 exe [41481.223145] [35840] 0 35840 147777232 29884563 71873 179 0 0 exe [41481.231790] [35913] 0 35913 1 1 1 1 0 0 exe [41481.240359] [35971] 0 35971 23370 4868 52 6 0 0 exe [41481.248920] [38807] 0 38807 315379 4070 64 10 0 0 image [41481.257654] [38816] 0 38816 370258 4784 80 10 0 0 exe [41481.266226] [38817] 0 38817 1267400 23303 535 19 0 0 exe [41481.274797] [38837] 0 38837 1 1 1 1 0 0 exe [41481.283364] [38857] 0 38857 23734 5354 52 6 0 0 exe [41481.291961] [ 1383] 0 1383 179943 694 21 4 0 0 image [41481.300796] [ 1390] 0 1390 181451 703 26 5 0 0 exe [41481.309361] [ 1396] 65534 1396 17181738953 65195 238 6 0 0 exe [41481.323491] [ 2359] 0 2359 179879 594 21 4 0 0 image [41481.332219] [ 2402] 0 2402 180231 692 22 5 0 0 image [41481.340952] [ 2510] 0 2510 181387 761 25 5 0 0 exe [41481.349501] [ 2512] 65534 2512 17181747360 68349 261 6 0 0 exe [41481.358660] [ 3145] 0 3145 296946 3697 57 10 0 0 image [41481.367430] [ 3221] 0 3221 28810 3596 30 7 0 0 exe [41481.375980] [ 3433] 0 3433 1 1 1 1 0 0 exe [41481.384527] [ 3495] 0 3495 1 1 1 1 0 0 exe [41481.393347] [ 3502] 0 3502 1 1 1 1 0 0 exe [41481.421609] [ 3529] 0 3529 1 1 1 1 0 0 exe [41481.430167] [ 3540] 0 3540 1 1 1 1 0 0 exe [41481.438722] [ 3565] 0 3565 4644 1 5 4 0 0 exe [41481.447281] [ 3566] 0 3566 802 1 5 4 0 0 exe [41481.455832] [ 3592] 0 3592 4898 1 5 4 0 0 exe [41481.464425] [ 3697] 0 3697 6417 1 9 5 0 0 exe [41481.473024] [ 3714] 0 3714 6664 1 9 5 0 0 exe [41481.481613] [ 3744] 0 3744 6520 1 9 5 0 0 exe [41481.490168] [ 3762] 0 3762 6774 1 9 5 0 0 exe [41481.498720] [ 5512] 0 5512 179879 607 21 4 0 0 image [41481.507486] [11402] 0 11402 180231 707 22 5 0 0 image [41481.516298] [11409] 0 11409 181323 764 25 5 0 0 exe [41481.524850] [12229] 0 12229 180231 698 22 5 0 0 image [41481.533617] [12235] 0 12235 181035 732 23 5 0 0 exe [41481.542199] [12236] 0 12236 210477 5887 58 6 0 0 exe [41481.550750] [12266] 0 12266 1 1 1 1 0 0 exe [41481.559300] [12300] 0 12300 23701 3566 32 6 0 0 exe [41481.567859] [13311] 0 13311 180231 685 22 5 0 0 image [41481.576584] [13318] 0 13318 181323 710 24 5 0 0 exe [41481.585133] [13319] 0 13319 210541 5700 60 6 0 0 exe [41481.593773] [13357] 0 13357 1 1 1 1 0 0 exe [41481.602324] [13378] 0 13378 23471 3325 32 6 0 0 exe [41481.610873] [14365] 0 14365 180231 668 21 4 0 0 image [41481.619596] [14372] 0 14372 181035 729 23 4 0 0 exe [41481.628146] [14373] 0 14373 210573 5490 57 6 0 0 exe [41481.636701] [14394] 0 14394 1 1 1 1 0 0 exe [41481.645251] [14411] 0 14411 23554 3613 32 6 0 0 exe [41481.653800] [14414] 0 14414 179879 666 21 5 0 0 image [41481.662522] [14459] 0 14459 181035 738 23 5 0 0 exe [41481.671081] [14464] 65534 14464 17181652539 5321 57 6 0 0 exe [41481.679900] [14501] 0 14501 179879 683 20 4 0 0 image [41481.688624] [14506] 0 14506 313042 3903 62 10 0 0 image [41481.697350] [14508] 0 14508 181387 711 23 4 0 0 exe [41481.705899] [14514] 65534 14514 17181647951 2529 46 5 0 0 exe [41481.714712] [14524] 0 14524 296594 3874 56 10 0 0 image [41481.723439] [14525] 0 14525 386770 4721 84 10 0 0 exe [41481.731996] [14526] 0 14526 543732 8978 193 12 0 0 exe [41481.740554] [14548] 0 14548 354098 4921 82 10 0 0 exe [41481.749109] [14550] 0 14550 527412 8942 197 13 0 0 exe [41481.757700] [14578] 0 14578 1 1 1 1 0 0 exe [41481.766262] [14600] 0 14600 1 1 1 1 0 0 exe [41481.774941] [14603] 0 14603 179879 651 19 5 0 0 image [41481.783761] [14631] 0 14631 296594 3673 56 9 0 0 image [41481.792485] [14641] 0 14641 179879 642 20 5 0 0 image [41481.801208] [14642] 0 14642 179879 643 20 4 0 0 image [41481.809933] [14653] 0 14653 313254 4193 64 9 0 0 exe [41481.818484] [14691] 0 14691 1 1 1 1 0 0 exe [41481.827036] [14692] 0 14692 1 1 1 1 0 0 exe [41481.835700] Out of memory: Kill process 35840 (exe) score 939 or sacrifice child [41481.843437] Killed process 35913 (exe) total-vm:4kB, anon-rss:4kB, file-rss:0kB, shmem-rss:0kB [41481.857842] oom_reaper: reaped process 35913 (exe), now anon-rss:0kB, file-rss:0kB, shmem-rss:0kB [41482.179451] cron invoked oom-killer: gfp_mask=0x24201ca(GFP_HIGHUSER_MOVABLE|__GFP_COLD), nodemask=0, order=0, oom_score_adj=0 [41482.191032] cron cpuset=/ mems_allowed=0 [41482.195378] CPU: 49 PID: 1157 Comm: cron Not tainted 4.9.0-11-amd64 #1 Debian 4.9.189-3+deb9u2 [41482.204123] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 [41482.214084] 0000000000000000 ffffffffa1136404 ffffba194e693c10 ffff9fca40f53000 [41482.222145] ffffffffa1009690 0000000000000000 0000000000000000 0000000c00000002 [41482.230785] ffff9fca71cd6040 ffffffffa0f8d207 0000004200000000 ffffffffa10acee0 [41482.239228] Call Trace: [41482.241802] [] ? dump_stack+0x5c/0x78 [41482.247319] [] ? dump_header+0x78/0x1fd [41482.252933] [] ? get_page_from_freelist+0x3f7/0xb20 [41482.259678] [] ? cap_inode_killpriv+0x20/0x20 [41482.266004] [] ? oom_kill_process+0x22a/0x3f0 [41482.272500] [] ? out_of_memory+0x111/0x470 [41482.278797] [] ? __alloc_pages_slowpath+0xa1f/0xb30 [41482.285436] [] ? __alloc_pages_nodemask+0x201/0x260 [41482.292071] [] ? alloc_pages_current+0x91/0x140 [41482.298356] [] ? filemap_fault+0x326/0x5d0 [41482.304229] [] ? ext4_filemap_fault+0x31/0x50 [ext4] [41482.310949] [] ? __do_fault+0xa9/0x1c0 [41482.316453] [] ? handle_mm_fault+0xda4/0x1350 [41482.322569] [] ? do_nanosleep+0x8d/0xe0 [41482.328165] [] ? __do_page_fault+0x255/0x4f0 [41482.334228] [] ? page_fault+0x28/0x30 [41482.339663] Mem-Info: [41482.342055] active_anon:30387716 inactive_anon:161534 isolated_anon:0 active_file:210 inactive_file:0 isolated_file:97 unevictable:0 dirty:0 writeback:0 unstable:0 slab_reclaimable:6661 slab_unreclaimable:29047 mapped:156799 shmem:169483 pagetables:77420 bounce:0 free:138684 free_pcp:533 free_cma:0 [41482.375956] Node 0 active_anon:121550864kB inactive_anon:646136kB active_file:868kB inactive_file:0kB unevictable:0kB isolated(anon):0kB isolated(file):388kB mapped:627196kB dirty:0kB writeback:0kB shmem:677932kB shmem_thp: 0kB shmem_pmdmapped: 0kB anon_thp: 139264kB writeback_tmp:0kB unstable:0kB pages_scanned:0 all_unreclaimable? no [41482.405696] 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 [41482.435384] lowmem_reserve[]: 0 2974 120837 120837 120837 [41482.441733] Node 0 DMA32 free:473024kB min:1660kB low:4704kB high:7748kB active_anon:2583068kB inactive_anon:0kB active_file:0kB inactive_file:28kB unevictable:0kB writepending:0kB present:3129320kB managed:3063752kB mlocked:0kB slab_reclaimable:76kB slab_unreclaimable:656kB kernel_stack:16kB pagetables:4860kB bounce:0kB free_pcp:120kB local_pcp:0kB free_cma:0kB [41482.474015] lowmem_reserve[]: 0 0 117862 117862 117862 [41482.480000] Node 0 Normal free:65944kB min:65908kB low:186596kB high:307284kB active_anon:118967796kB inactive_anon:646136kB active_file:1352kB inactive_file:0kB unevictable:0kB writepending:0kB present:122683392kB managed:120696220kB mlocked:0kB slab_reclaimable:26568kB slab_unreclaimable:115512kB kernel_stack:39904kB pagetables:304820kB bounce:0kB free_pcp:1952kB local_pcp:0kB free_cma:0kB [41482.515014] lowmem_reserve[]: 0 0 0 0 0 [41482.519689] 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 [41482.535801] Node 0 DMA32: 569*4kB (UE) 468*8kB (UME) 162*16kB (UME) 60*32kB (UMEH) 89*64kB (UMEH) 69*128kB (UMEH) 22*256kB (UMEH) 8*512kB (U) 4*1024kB (UE) 0*2048kB 106*4096kB (M) = 473060kB [41482.555652] Node 0 Normal: 10849*4kB (UME) 2798*8kB (UM) 1*16kB (M) 0*32kB 0*64kB 0*128kB 0*256kB 0*512kB 0*1024kB 0*2048kB 0*4096kB = 65796kB [41482.570530] Node 0 hugepages_total=0 hugepages_free=0 hugepages_surp=0 hugepages_size=1048576kB [41482.579511] Node 0 hugepages_total=0 hugepages_free=0 hugepages_surp=0 hugepages_size=2048kB [41482.588070] 170112 total pagecache pages [41482.592103] 0 pages in swap cache [41482.595527] Swap cache stats: add 0, delete 0, find 0/0 [41482.600860] Free swap = 0kB [41482.603851] Total swap = 0kB [41482.606888] 31457176 pages RAM [41482.610051] 0 pages HighMem/MovableOnly [41482.613997] 513206 pages reserved [41482.617417] 0 pages hwpoisoned [41482.620593] [ pid ] uid tgid total_vm rss nr_ptes nr_pmds swapents oom_score_adj name [41482.629316] [ 935] 0 935 90410 145 176 4 0 0 systemd-journal [41482.638908] [ 960] 0 960 11548 333 23 3 0 -1000 systemd-udevd [41482.648325] [ 1125] 0 1125 13686 112 26 3 0 -1000 auditd [41482.657134] [ 1137] 0 1137 21093 340 12 3 0 0 audispd [41482.666030] [ 1151] 109 1151 11282 108 28 3 0 -900 dbus-daemon [41482.675274] [ 1155] 0 1155 1051 21 8 3 0 0 acpid [41482.683997] [ 1157] 0 1157 7409 61 19 3 0 0 cron [41482.692644] [ 1158] 0 1158 9495 122 22 3 0 0 systemd-logind [41482.702149] [ 1180] 0 1180 31178 3538 63 5 0 0 syz-ci [41482.710959] [ 1660] 0 1660 5119 274 12 3 0 0 dhclient [41482.719949] [ 1707] 0 1707 90807 12312 116 4 0 0 puppet [41482.728758] [ 1714] 0 1714 3575 34 12 3 0 0 agetty [41482.737588] [ 1716] 0 1716 3631 33 12 3 0 0 agetty [41482.746401] [ 1743] 106 1743 24474 131 23 4 0 0 ntpd [41482.755092] [ 1746] 0 1746 344820 41660 575 4 0 0 ruby [41482.763732] [ 2124] 0 2124 17077 3608 39 4 0 0 google_network_ [41482.773324] [ 2195] 0 2195 17175 3717 40 3 0 -999 google_accounts [41482.782918] [ 2354] 0 2354 17072 3579 39 3 0 0 google_clock_sk [41482.792604] [ 2355] 0 2355 17489 187 37 4 0 -1000 sshd [41482.801260] [14088] 0 14088 105835 47959 154 5 0 0 syz-manager [41482.810512] [28925] 0 28925 114556 52365 168 5 0 0 syz-manager [41482.819759] [43361] 0 43361 126322 81958 233 5 0 0 syz-manager [41482.829007] [29507] 0 29507 102023 60040 193 5 0 0 syz-manager [41482.838270] [39690] 0 39690 62528 550 30 3 0 0 rsyslogd [41482.847292] [20160] 0 20160 80813 57798 162 5 0 0 syz-manager [41482.856535] [35794] 0 35794 315315 4027 61 10 0 0 image [41482.865260] [35833] 0 35833 430074 7223 166 11 0 0 exe [41482.873808] [35840] 0 35840 147777232 29884845 71873 179 0 0 exe [41482.882446] [35913] 0 35913 1 0 1 1 0 0 exe [41482.890999] [35971] 0 35971 23370 4868 52 6 0 0 exe [41482.899683] [38807] 0 38807 315379 4070 64 10 0 0 image [41482.908416] [38816] 0 38816 370258 4784 80 10 0 0 exe [41482.916973] [38817] 0 38817 1267400 23303 535 19 0 0 exe [41482.925576] [38837] 0 38837 1 1 1 1 0 0 exe [41482.934125] [38857] 0 38857 23734 5354 52 6 0 0 exe [41482.942688] [ 1383] 0 1383 179943 694 21 4 0 0 image [41482.951411] [ 1390] 0 1390 181451 703 26 5 0 0 exe [41482.959961] [ 1396] 65534 1396 17181738953 65195 238 6 0 0 exe [41482.968781] [ 2359] 0 2359 179879 594 21 4 0 0 image [41482.977528] [ 2402] 0 2402 180231 692 22 5 0 0 image [41482.986255] [ 2510] 0 2510 181387 761 25 5 0 0 exe [41482.994807] [ 2512] 65534 2512 17181747360 68349 261 6 0 0 exe [41483.003667] [ 3145] 0 3145 296946 3697 57 10 0 0 image [41483.012524] [ 3221] 0 3221 28810 3596 30 7 0 0 exe [41483.021080] [ 3433] 0 3433 1 1 1 1 0 0 exe [41483.029636] [ 3495] 0 3495 1 1 1 1 0 0 exe [41483.038188] [ 3502] 0 3502 1 1 1 1 0 0 exe [41483.046742] [ 3529] 0 3529 1 1 1 1 0 0 exe [41483.055293] [ 3540] 0 3540 1 1 1 1 0 0 exe [41483.063844] [ 3565] 0 3565 4644 1 5 4 0 0 exe [41483.072443] [ 3566] 0 3566 802 1 5 4 0 0 exe [41483.081004] [ 3592] 0 3592 4898 1 5 4 0 0 exe [41483.089562] [ 3697] 0 3697 6417 1 9 5 0 0 exe [41483.098133] [ 3714] 0 3714 6664 1 9 5 0 0 exe [41483.106693] [ 3744] 0 3744 6520 1 9 5 0 0 exe [41483.115289] [ 3762] 0 3762 6774 1 9 5 0 0 exe [41483.123841] [ 5512] 0 5512 179879 607 21 4 0 0 image [41483.132574] [11402] 0 11402 180231 707 22 5 0 0 image [41483.141299] [11409] 0 11409 181323 764 25 5 0 0 exe [41483.149854] [12229] 0 12229 180231 698 22 5 0 0 image [41483.158621] [12235] 0 12235 181035 732 23 5 0 0 exe [41483.167177] [12236] 0 12236 210477 5887 58 6 0 0 exe [41483.175731] [12266] 0 12266 1 1 1 1 0 0 exe [41483.184281] [12300] 0 12300 23701 3566 32 6 0 0 exe [41483.192836] [13311] 0 13311 180231 685 22 5 0 0 image [41483.201562] [13318] 0 13318 181323 710 24 5 0 0 exe [41483.210248] [13319] 0 13319 210541 5700 60 6 0 0 exe [41483.218823] [13357] 0 13357 1 1 1 1 0 0 exe [41483.227387] [13378] 0 13378 23471 3325 32 6 0 0 exe [41483.236663] [14365] 0 14365 180231 668 21 4 0 0 image [41483.245571] [14372] 0 14372 181035 729 23 4 0 0 exe [41483.254132] [14373] 0 14373 210573 5492 57 6 0 0 exe [41483.262686] [14394] 0 14394 1 1 1 1 0 0 exe [41483.271241] [14411] 0 14411 23554 3613 32 6 0 0 exe [41483.279878] [14414] 0 14414 179879 666 21 5 0 0 image [41483.288608] [14459] 0 14459 181035 738 23 5 0 0 exe [41483.297160] [14464] 65534 14464 17181652539 5321 57 6 0 0 exe [41483.305981] [14501] 0 14501 179879 683 20 4 0 0 image [41483.314706] [14506] 0 14506 313042 3903 62 10 0 0 image [41483.323435] [14508] 0 14508 181387 711 23 4 0 0 exe [41483.335990] [14514] 65534 14514 17181647951 2529 46 5 0 0 exe [41483.347334] [14524] 0 14524 296594 3874 56 10 0 0 image [41483.356169] [14525] 0 14525 386770 4721 84 10 0 0 exe [41483.364822] [14526] 0 14526 543732 8978 193 12 0 0 exe [41483.373474] [14548] 0 14548 354098 4921 82 10 0 0 exe [41483.382041] [14550] 0 14550 527412 8942 197 13 0 0 exe [41483.390699] [14578] 0 14578 1 1 1 1 0 0 exe [41483.399266] [14600] 0 14600 1 1 1 1 0 0 exe [41483.407833] [14603] 0 14603 179879 651 19 5 0 0 image [41483.416572] [14631] 0 14631 296594 3673 56 9 0 0 image [41483.425319] [14641] 0 14641 179879 642 20 5 0 0 image [41483.434237] [14642] 0 14642 179879 643 20 4 0 0 image [41483.442989] [14653] 0 14653 313254 4193 64 9 0 0 exe [41483.451563] [14691] 0 14691 1 1 1 1 0 0 exe [41483.460131] [14692] 0 14692 1 1 1 1 0 0 exe [41483.469746] Out of memory: Kill process 35840 (exe) score 939 or sacrifice child [41483.477909] Killed process 35840 (exe) total-vm:591108928kB, anon-rss:119495328kB, file-rss:0kB, shmem-rss:44164kB [41491.405808] oom_reaper: reaped process 35840 (exe), now anon-rss:0kB, file-rss:0kB, shmem-rss:44164kB [41683.337234] kvm [30858]: vcpu0, guest rIP: 0xa74064 unhandled rdmsr: 0xce [41683.583722] kvm [30887]: vcpu0, guest rIP: 0xa74064 unhandled rdmsr: 0xce [41695.349985] exe invoked oom-killer: gfp_mask=0x24201ca(GFP_HIGHUSER_MOVABLE|__GFP_COLD), nodemask=0, order=0, oom_score_adj=0 [41695.361470] exe cpuset=/ mems_allowed=0 [41695.365711] CPU: 11 PID: 14739 Comm: exe Not tainted 4.9.0-11-amd64 #1 Debian 4.9.189-3+deb9u2 [41695.374452] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 [41695.384228] 0000000000000000 ffffffffa1136404 ffffba195a42bc10 ffff9fc0f079b000 [41695.392279] ffffffffa1009690 0000000000000000 0000000000000000 0000000c00000000 [41695.400324] ffff9fbbd181f100 ffffffffa0f8d207 0000004200000000 ffffffffa10acee0 [41695.408324] Call Trace: [41695.410888] [] ? dump_stack+0x5c/0x78 [41695.416343] [] ? dump_header+0x78/0x1fd [41695.421946] [] ? get_page_from_freelist+0x3f7/0xb20 [41695.428588] [] ? cap_inode_killpriv+0x20/0x20 [41695.434721] [] ? oom_kill_process+0x22a/0x3f0 [41695.440850] [] ? out_of_memory+0x111/0x470 [41695.446707] [] ? __alloc_pages_slowpath+0xa1f/0xb30 [41695.453347] [] ? __alloc_pages_nodemask+0x201/0x260 [41695.459993] [] ? alloc_pages_current+0x91/0x140 [41695.466371] [] ? filemap_fault+0x326/0x5d0 [41695.472270] [] ? ext4_filemap_fault+0x31/0x50 [ext4] [41695.478997] [] ? __do_fault+0xa9/0x1c0 [41695.484508] [] ? handle_mm_fault+0xda4/0x1350 [41695.491065] [] ? __do_page_fault+0x255/0x4f0 [41695.497188] [] ? page_fault+0x28/0x30 [41695.502650] Mem-Info: [41695.505058] active_anon:30290405 inactive_anon:252643 isolated_anon:0 active_file:757 inactive_file:334 isolated_file:71 unevictable:0 dirty:0 writeback:13 unstable:0 slab_reclaimable:7488 slab_unreclaimable:31068 mapped:248043 shmem:277992 pagetables:72040 bounce:0 free:138974 free_pcp:247 free_cma:0 [41695.539235] Node 0 active_anon:121161620kB inactive_anon:1010572kB active_file:3028kB inactive_file:2232kB unevictable:0kB isolated(anon):0kB isolated(file):284kB mapped:992412kB dirty:0kB writeback:52kB shmem:1111968kB shmem_thp: 0kB shmem_pmdmapped: 0kB anon_thp: 153600kB writeback_tmp:0kB unstable:0kB pages_scanned:734 all_unreclaimable? no [41695.569769] 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 [41695.599481] lowmem_reserve[]: 0 2974 120837 120837 120837 [41695.605759] Node 0 DMA32 free:473096kB min:1660kB low:4704kB high:7748kB active_anon:2575648kB inactive_anon:5452kB active_file:192kB inactive_file:12kB unevictable:0kB writepending:0kB present:3129320kB managed:3063752kB mlocked:0kB slab_reclaimable:116kB slab_unreclaimable:1272kB kernel_stack:208kB pagetables:5040kB bounce:0kB free_pcp:148kB local_pcp:0kB free_cma:0kB [41695.638753] lowmem_reserve[]: 0 0 117862 117862 117862 [41695.644792] Node 0 Normal free:66248kB min:65908kB low:186596kB high:307284kB active_anon:118585972kB inactive_anon:1005120kB active_file:2480kB inactive_file:684kB unevictable:0kB writepending:108kB present:122683392kB managed:120696220kB mlocked:0kB slab_reclaimable:29836kB slab_unreclaimable:123000kB kernel_stack:59424kB pagetables:283120kB bounce:0kB free_pcp:1528kB local_pcp:0kB free_cma:0kB [41695.680146] lowmem_reserve[]: 0 0 0 0 0 [41695.684882] 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 [41695.701049] Node 0 DMA32: 584*4kB (UME) 458*8kB (UE) 266*16kB (UME) 156*32kB (UMEH) 110*64kB (UMEH) 66*128kB (UEH) 23*256kB (UMEH) 5*512kB (UM) 4*1024kB (UME) 2*2048kB (UM) 104*4096kB (M) = 473360kB [41695.721811] Node 0 Normal: 16746*4kB (UME) 92*8kB (UME) 31*16kB (ME) 4*32kB (M) 0*64kB 0*128kB 0*256kB 0*512kB 0*1024kB 0*2048kB 0*4096kB = 68344kB [41695.737261] Node 0 hugepages_total=0 hugepages_free=0 hugepages_surp=0 hugepages_size=1048576kB [41695.746089] Node 0 hugepages_total=0 hugepages_free=0 hugepages_surp=0 hugepages_size=2048kB [41695.754651] 279125 total pagecache pages [41695.758687] 0 pages in swap cache [41695.762114] Swap cache stats: add 0, delete 0, find 0/0 [41695.767453] Free swap = 0kB [41695.770444] Total swap = 0kB [41695.773438] 31457176 pages RAM [41695.776608] 0 pages HighMem/MovableOnly [41695.780554] 513206 pages reserved [41695.783980] 0 pages hwpoisoned [41695.787145] [ pid ] uid tgid total_vm rss nr_ptes nr_pmds swapents oom_score_adj name [41695.795873] [ 935] 0 935 109884 145 214 4 0 0 systemd-journal [41695.805470] [ 960] 0 960 11548 333 23 3 0 -1000 systemd-udevd [41695.814908] [ 1125] 0 1125 13686 112 26 3 0 -1000 auditd [41695.823828] [ 1137] 0 1137 21093 50 12 3 0 0 audispd [41695.832790] [ 1151] 109 1151 11282 108 28 3 0 -900 dbus-daemon [41695.842066] [ 1155] 0 1155 1051 21 8 3 0 0 acpid [41695.850820] [ 1157] 0 1157 7409 61 19 3 0 0 cron [41695.859476] [ 1158] 0 1158 9495 122 22 3 0 0 systemd-logind [41695.869001] [ 1180] 0 1180 31178 3524 63 5 0 0 syz-ci [41695.877825] [ 1660] 0 1660 5119 274 12 3 0 0 dhclient [41695.886828] [ 1707] 0 1707 90807 12313 116 4 0 0 puppet [41695.895670] [ 1714] 0 1714 3575 34 12 3 0 0 agetty [41695.904509] [ 1716] 0 1716 3631 33 12 3 0 0 agetty [41695.913339] [ 1743] 106 1743 24474 131 23 4 0 0 ntpd [41695.922173] [ 1746] 0 1746 302007 41660 352 4 0 0 ruby [41695.930843] [ 2124] 0 2124 17077 3608 39 4 0 0 google_network_ [41695.940478] [ 2195] 0 2195 17175 3717 40 3 0 -999 google_accounts [41695.950100] [ 2354] 0 2354 17072 3579 39 3 0 0 google_clock_sk [41695.959708] [ 2355] 0 2355 17489 187 37 4 0 -1000 sshd [41695.968378] [14088] 0 14088 105835 49193 159 5 0 0 syz-manager [41695.977920] [28925] 0 28925 114620 53148 168 5 0 0 syz-manager [41695.987189] [43361] 0 43361 126322 83049 235 5 0 0 syz-manager [41695.996453] [29507] 0 29507 102087 60377 193 5 0 0 syz-manager [41696.005743] [39690] 0 39690 62528 462 30 3 0 0 rsyslogd [41696.014782] [20160] 0 20160 80829 58085 162 5 0 0 syz-manager [41696.024059] [38807] 0 38807 315379 4070 64 10 0 0 image [41696.032811] [38816] 0 38816 434284 7418 169 11 0 0 exe [41696.041385] [38817] 0 38817 93119801 29288919 64526 161 0 0 exe [41696.051172] [38837] 0 38837 1 1 1 1 0 0 exe [41696.059744] [38857] 0 38857 23734 5382 52 6 0 0 exe [41696.068326] [ 1383] 0 1383 179943 693 21 4 0 0 image [41696.077077] [ 1390] 0 1390 181451 703 26 5 0 0 exe [41696.085649] [ 1396] 65534 1396 17181738953 65245 238 6 0 0 exe [41696.094483] [ 2359] 0 2359 179879 594 21 4 0 0 image [41696.103320] [ 2402] 0 2402 180231 692 22 5 0 0 image [41696.112068] [ 2510] 0 2510 181387 761 25 5 0 0 exe [41696.120659] [ 2512] 65534 2512 17181747360 68349 261 6 0 0 exe [41696.129481] [ 5512] 0 5512 179879 607 21 4 0 0 image [41696.138220] [14506] 0 14506 313042 3902 62 10 0 0 image [41696.147117] [14525] 0 14525 432233 8374 167 11 0 0 exe [41696.155676] [14526] 0 14526 2222076 494569 1964 24 0 0 exe [41696.164258] [14578] 0 14578 1 1 1 1 0 0 exe [41696.172824] [14691] 0 14691 23702 4238 52 6 0 0 exe [41696.181400] [14864] 0 14864 296594 3700 57 10 0 0 image [41696.190135] [14886] 0 14886 79886 51654 128 6 0 0 exe [41696.198698] [14957] 0 14957 351 1 5 4 0 0 exe [41696.207259] [15286] 0 15286 605 1 5 4 0 0 exe [41696.215919] [15294] 0 15294 4445 1 5 4 0 0 exe [41696.224505] [15300] 0 15300 805 1 5 4 0 0 exe [41696.233081] [15312] 0 15312 4759 1 6 4 0 0 exe [41696.241633] [15324] 0 15324 758 1 5 4 0 0 exe [41696.250190] [15330] 0 15330 4961 1 6 4 0 0 exe [41696.258744] [15354] 0 15354 4914 1 6 4 0 0 exe [41696.267427] [15430] 0 15430 415 1 5 3 0 0 exe [41696.275992] [15439] 0 15439 1 1 1 1 0 0 exe [41696.284577] [15460] 0 15460 1 1 1 1 0 0 exe [41696.293138] [15510] 0 15510 1 1 1 1 0 0 exe [41696.301805] [18546] 0 18546 296946 3953 59 9 0 0 image [41696.310675] [18569] 0 18569 370258 4802 82 10 0 0 exe [41696.319232] [18570] 0 18570 835838 18739 401 16 0 0 exe [41696.327801] [18676] 0 18676 1 1 1 1 0 0 exe [41696.336355] [19051] 0 19051 23411 4572 52 6 0 0 exe [41696.344924] [20419] 0 20419 315315 3734 63 9 0 0 image [41696.353657] [20500] 0 20500 29351 3080 30 6 0 0 exe [41696.362226] [20890] 0 20890 803 1 5 4 0 0 exe [41696.370789] [20981] 0 20981 4897 1 5 4 0 0 exe [41696.379348] [20986] 0 20986 585 1 5 4 0 0 exe [41696.388052] [20998] 0 20998 616 1 5 4 0 0 exe [41696.396616] [21024] 0 21024 4679 1 5 4 0 0 exe [41696.405174] [21035] 0 21035 690 1 5 4 0 0 exe [41696.413741] [21043] 0 21043 4710 1 5 4 0 0 exe [41696.422297] [21063] 0 21063 4786 1 5 4 0 0 exe [41696.430894] [21288] 0 21288 6774 1 9 5 0 0 exe [41696.439465] [21314] 0 21314 6618 1 9 5 0 0 exe [41696.448018] [21334] 0 21334 6555 1 9 5 0 0 exe [41696.456573] [21338] 0 21338 6662 1 9 5 0 0 exe [41696.465126] [21466] 0 21466 415 1 5 3 0 0 exe [41696.473727] [24401] 0 24401 1 1 1 1 0 0 exe [41696.482295] [28149] 0 28149 179879 674 20 4 0 0 image [41696.491032] [28155] 0 28155 181035 776 23 4 0 0 exe [41696.499718] [28156] 0 28156 210605 5775 59 6 0 0 exe [41696.508287] [28188] 0 28188 1 1 1 1 0 0 exe [41696.516841] [28212] 0 28212 23744 3814 32 6 0 0 exe [41696.525396] [29155] 0 29155 35304 13893 74 5 0 0 syz-manager [41696.534783] [29330] 0 29330 179815 653 19 5 0 0 image [41696.543883] [29335] 0 29335 181035 717 24 4 0 0 exe [41696.552566] [29339] 0 29339 210349 5595 58 5 0 0 exe [41696.561136] [29365] 0 29365 1 1 1 1 0 0 exe [41696.570130] [29392] 0 29392 23671 4237 32 6 0 0 exe [41696.578711] [30487] 0 30487 180231 685 22 4 0 0 image [41696.587457] [30493] 0 30493 181323 705 23 4 0 0 exe [41696.596395] [30494] 0 30494 210669 5764 57 5 0 0 exe [41696.604954] [30527] 0 30527 1 1 1 1 0 0 exe [41696.613633] [30549] 0 30549 23584 3574 32 6 0 0 exe [41696.622191] [30847] 0 30847 180295 713 21 5 0 0 image [41696.630924] [30853] 0 30853 181323 704 24 5 0 0 exe [41696.639486] [30858] 65534 30858 17181672458 16349 128 6 0 0 exe [41696.648363] [30876] 0 30876 179879 692 20 5 0 0 image [41696.657286] [30882] 0 30882 181099 671 24 4 0 0 exe [41696.665985] [30887] 65534 30887 17181672518 16217 127 5 0 0 exe [41696.675254] [31017] 0 31017 180231 650 21 5 0 0 image [41696.684106] [31034] 0 31034 180231 648 21 4 0 0 image [41696.692841] [31149] 0 31149 180231 669 22 5 0 0 image [41696.702022] [31155] 0 31155 180167 708 20 4 0 0 image [41696.710936] [31157] 0 31157 180971 673 24 4 0 0 exe [41696.719517] [31166] 65534 31166 206365 3271 42 5 0 0 exe [41696.728079] [31172] 0 31172 181259 740 23 4 0 0 exe [41696.736755] [31200] 65534 31200 1 1 1 1 0 0 exe [41696.745312] [31214] 0 31214 179879 659 20 5 0 0 image [41696.754175] [31233] 0 31233 180167 672 21 4 0 0 image [41696.763295] [31234] 0 31234 181035 733 24 4 0 0 exe [41696.771855] [31243] 65534 31243 206109 2050 36 6 0 0 exe [41696.780810] [31249] 0 31249 181323 708 25 5 0 0 exe [41696.789399] [31254] 65534 31254 206173 1922 35 5 0 0 exe [41696.798053] [31267] 65534 31267 21159 628 18 6 0 0 exe [41696.807266] [31291] 65534 31291 1 1 1 1 0 0 exe [41696.815851] [31306] 65534 31306 1 1 1 1 0 0 exe [41696.824419] [31323] 65534 31323 20665 54 12 4 0 0 exe [41696.832982] [31339] 65534 31339 2170 1 2 2 0 0 exe [41696.841903] [31342] 0 31342 180231 726 22 4 0 0 image [41696.850986] [31349] 0 31349 181323 729 25 5 0 0 exe [41696.859647] [31354] 65534 31354 206109 1933 34 5 0 0 exe [41696.868447] [31371] 65534 31371 1 1 1 1 0 0 exe [41696.877551] [31374] 0 31374 179943 613 20 5 0 0 image [41696.886586] [31390] 65534 31390 1 1 1 1 0 0 exe [41696.895152] Out of memory: Kill process 38817 (exe) score 920 or sacrifice child [41696.902974] Killed process 38837 (exe) total-vm:4kB, anon-rss:4kB, file-rss:0kB, shmem-rss:0kB [41696.916870] oom_reaper: reaped process 38837 (exe), now anon-rss:0kB, file-rss:0kB, shmem-rss:0kB [41699.515899] exe invoked oom-killer: gfp_mask=0x24201ca(GFP_HIGHUSER_MOVABLE|__GFP_COLD), nodemask=0, order=0, oom_score_adj=0 [41699.527499] exe cpuset=/ mems_allowed=0 [41699.531814] CPU: 48 PID: 30586 Comm: exe Not tainted 4.9.0-11-amd64 #1 Debian 4.9.189-3+deb9u2 [41699.540553] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 [41699.549904] 0000000000000000 ffffffffa1136404 ffffba194e02fc10 ffff9fc0f079b000 [41699.558088] ffffffffa1009690 0000000000000000 0000000000000000 0000000c78493000 [41699.566395] ffff9fbc44873080 ffffffffa0f8d207 0000004200000000 ffffffffa10acee0 [41699.574672] Call Trace: [41699.577250] [] ? dump_stack+0x5c/0x78 [41699.582688] [] ? dump_header+0x78/0x1fd [41699.588307] [] ? get_page_from_freelist+0x3f7/0xb20 [41699.595291] [] ? cap_inode_killpriv+0x20/0x20 [41699.601435] [] ? oom_kill_process+0x22a/0x3f0 [41699.607681] [] ? out_of_memory+0x111/0x470 [41699.613641] [] ? __alloc_pages_slowpath+0xa1f/0xb30 [41699.620321] [] ? __alloc_pages_nodemask+0x201/0x260 [41699.626980] [] ? alloc_pages_current+0x91/0x140 [41699.633301] [] ? filemap_fault+0x326/0x5d0 [41699.639211] [] ? ext4_filemap_fault+0x31/0x50 [ext4] [41699.645962] [] ? __do_fault+0xa9/0x1c0 [41699.651699] [] ? handle_mm_fault+0xda4/0x1350 [41699.658286] [] ? __do_page_fault+0x255/0x4f0 [41699.664353] [] ? page_fault+0x28/0x30 [41699.670139] Mem-Info: [41699.672562] active_anon:30290362 inactive_anon:252644 isolated_anon:0 active_file:781 inactive_file:536 isolated_file:0 unevictable:0 dirty:0 writeback:0 unstable:0 slab_reclaimable:7410 slab_unreclaimable:30409 mapped:247975 shmem:277992 pagetables:72070 bounce:0 free:138705 free_pcp:71 free_cma:0 [41699.707134] Node 0 active_anon:121161448kB inactive_anon:1010576kB active_file:1920kB inactive_file:624kB unevictable:0kB isolated(anon):0kB isolated(file):0kB mapped:992144kB dirty:0kB writeback:0kB shmem:1111968kB shmem_thp: 0kB shmem_pmdmapped: 0kB anon_thp: 153600kB writeback_tmp:0kB unstable:0kB pages_scanned:51037 all_unreclaimable? no [41699.737860] 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 [41699.767691] lowmem_reserve[]: 0 2974 120837 120837 120837 [41699.774011] Node 0 DMA32 free:473108kB min:1660kB low:4704kB high:7748kB active_anon:2575660kB inactive_anon:5452kB active_file:0kB inactive_file:48kB unevictable:0kB writepending:0kB present:3129320kB managed:3063752kB mlocked:0kB slab_reclaimable:116kB slab_unreclaimable:1020kB kernel_stack:208kB pagetables:5044kB bounce:0kB free_pcp:0kB local_pcp:0kB free_cma:0kB [41699.807623] lowmem_reserve[]: 0 0 117862 117862 117862 [41699.813710] Node 0 Normal free:65512kB min:65908kB low:186596kB high:307284kB active_anon:118585792kB inactive_anon:1005120kB active_file:2776kB inactive_file:0kB unevictable:0kB writepending:0kB present:122683392kB managed:120696220kB mlocked:0kB slab_reclaimable:29524kB slab_unreclaimable:120616kB kernel_stack:59360kB pagetables:283236kB bounce:0kB free_pcp:8kB local_pcp:0kB free_cma:0kB [41699.849093] lowmem_reserve[]: 0 0 0 0 0 [41699.853921] 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 [41699.870679] Node 0 DMA32: 633*4kB (UME) 486*8kB (UME) 272*16kB (UME) 155*32kB (UMEH) 112*64kB (UMEH) 66*128kB (UEH) 23*256kB (UMEH) 5*512kB (UM) 4*1024kB (UME) 2*2048kB (UM) 104*4096kB (M) = 473972kB [41699.891944] Node 0 Normal: 17506*4kB (UME) 311*8kB (UME) 35*16kB (M) 17*32kB (UME) 2*64kB (E) 0*128kB 0*256kB 0*512kB 0*1024kB 0*2048kB 0*4096kB = 73744kB [41699.908422] Node 0 hugepages_total=0 hugepages_free=0 hugepages_surp=0 hugepages_size=1048576kB [41699.917261] Node 0 hugepages_total=0 hugepages_free=0 hugepages_surp=0 hugepages_size=2048kB [41699.925843] 279647 total pagecache pages [41699.930012] 0 pages in swap cache [41699.933474] Swap cache stats: add 0, delete 0, find 0/0 [41699.939004] Free swap = 0kB [41699.942007] Total swap = 0kB [41699.945125] 31457176 pages RAM [41699.948303] 0 pages HighMem/MovableOnly [41699.952297] 513206 pages reserved [41699.955779] 0 pages hwpoisoned [41699.959313] [ pid ] uid tgid total_vm rss nr_ptes nr_pmds swapents oom_score_adj name [41699.968458] [ 935] 0 935 109884 145 214 4 0 0 systemd-journal [41699.978089] [ 960] 0 960 11548 333 23 3 0 -1000 systemd-udevd [41699.988827] [ 1125] 0 1125 13686 112 26 3 0 -1000 auditd [41699.998127] [ 1137] 0 1137 21093 50 12 3 0 0 audispd [41700.007074] [ 1151] 109 1151 11282 108 28 3 0 -900 dbus-daemon [41700.016443] [ 1155] 0 1155 1051 21 8 3 0 0 acpid [41700.025194] [ 1157] 0 1157 7409 61 19 3 0 0 cron [41700.034424] [ 1158] 0 1158 9495 122 22 3 0 0 systemd-logind [41700.044221] [ 1180] 0 1180 31178 3518 63 5 0 0 syz-ci [41700.053465] [ 1660] 0 1660 5119 274 12 3 0 0 dhclient [41700.063297] [ 1707] 0 1707 90807 12313 116 4 0 0 puppet [41700.072152] [ 1714] 0 1714 3575 34 12 3 0 0 agetty [41700.080994] [ 1716] 0 1716 3631 33 12 3 0 0 agetty [41700.089947] [ 1743] 106 1743 24474 131 23 4 0 0 ntpd [41700.098626] [ 1746] 0 1746 302007 41660 352 4 0 0 ruby [41700.108080] [ 2124] 0 2124 17077 3608 39 4 0 0 google_network_ [41700.117925] [ 2195] 0 2195 17175 3717 40 3 0 -999 google_accounts [41700.127642] [ 2354] 0 2354 17072 3579 39 3 0 0 google_clock_sk [41700.137268] [ 2355] 0 2355 17489 187 37 4 0 -1000 sshd [41700.145942] [14088] 0 14088 105835 49193 159 5 0 0 syz-manager [41700.155219] [28925] 0 28925 114620 53148 168 5 0 0 syz-manager [41700.164575] [43361] 0 43361 126322 83049 235 5 0 0 syz-manager [41700.174054] [29507] 0 29507 102087 60377 193 5 0 0 syz-manager [41700.183522] [39690] 0 39690 62528 462 30 3 0 0 rsyslogd [41700.192670] [20160] 0 20160 80829 58085 162 5 0 0 syz-manager [41700.201924] [38807] 0 38807 315379 4070 64 10 0 0 image [41700.210677] [38816] 0 38816 434284 7418 169 11 0 0 exe [41700.219255] [38817] 0 38817 93119801 29289725 64527 161 0 0 exe [41700.228003] [38837] 0 38837 1 0 1 1 0 0 exe [41700.237633] [38857] 0 38857 23734 5382 52 6 0 0 exe [41700.246737] [ 1383] 0 1383 179943 693 21 4 0 0 image [41700.255916] [ 1390] 0 1390 181451 703 26 5 0 0 exe [41700.264489] [ 1396] 65534 1396 17181738953 65257 238 6 0 0 exe [41700.273322] [ 2359] 0 2359 179879 594 21 4 0 0 image [41700.282061] [ 2402] 0 2402 180231 692 22 5 0 0 image [41700.291182] [ 2510] 0 2510 181387 761 25 5 0 0 exe [41700.299769] [ 2512] 65534 2512 17181747360 68349 261 6 0 0 exe [41700.308702] [ 5512] 0 5512 179879 607 21 4 0 0 image [41700.317803] [14506] 0 14506 313042 3902 62 10 0 0 image [41700.326561] [14525] 0 14525 432233 8374 167 11 0 0 exe [41700.335135] [14526] 0 14526 2222076 494591 1964 24 0 0 exe [41700.343706] [14578] 0 14578 1 1 1 1 0 0 exe [41700.352286] [14691] 0 14691 23702 4238 52 6 0 0 exe [41700.360866] [14864] 0 14864 296594 3700 57 10 0 0 image [41700.369858] [14886] 0 14886 79886 51654 128 6 0 0 exe [41700.378564] [14957] 0 14957 351 1 5 4 0 0 exe [41700.387243] [15286] 0 15286 605 1 5 4 0 0 exe [41700.395824] [15294] 0 15294 4445 1 5 4 0 0 exe [41700.404483] [15300] 0 15300 805 1 5 4 0 0 exe [41700.413055] [15312] 0 15312 4759 1 6 4 0 0 exe [41700.421626] [15324] 0 15324 758 1 5 4 0 0 exe [41700.430180] [15330] 0 15330 4961 1 6 4 0 0 exe [41700.438741] [15354] 0 15354 4914 1 6 4 0 0 exe [41700.447300] [15430] 0 15430 415 1 5 3 0 0 exe [41700.456103] [15439] 0 15439 1 1 1 1 0 0 exe [41700.464692] [15460] 0 15460 1 1 1 1 0 0 exe [41700.473253] [15510] 0 15510 1 1 1 1 0 0 exe [41700.481825] [18546] 0 18546 296946 3953 59 9 0 0 image [41700.490554] [18569] 0 18569 370258 4802 82 10 0 0 exe [41700.499123] [18570] 0 18570 835838 18870 401 16 0 0 exe [41700.507681] [18676] 0 18676 1 1 1 1 0 0 exe [41700.516254] [19051] 0 19051 23411 4572 52 6 0 0 exe [41700.524833] [20419] 0 20419 315315 3734 63 9 0 0 image [41700.533569] [20500] 0 20500 29351 3080 30 6 0 0 exe [41700.542123] [20890] 0 20890 803 1 5 4 0 0 exe [41700.550740] [20981] 0 20981 4897 1 5 4 0 0 exe [41700.559314] [20986] 0 20986 585 1 5 4 0 0 exe [41700.567978] [20998] 0 20998 616 1 5 4 0 0 exe [41700.576627] [21024] 0 21024 4679 1 5 4 0 0 exe [41700.585183] [21035] 0 21035 690 1 5 4 0 0 exe [41700.593793] [21043] 0 21043 4710 1 5 4 0 0 exe [41700.602348] [21063] 0 21063 4786 1 5 4 0 0 exe [41700.610899] [21288] 0 21288 6774 1 9 5 0 0 exe [41700.619453] [21314] 0 21314 6618 1 9 5 0 0 exe [41700.628013] [21334] 0 21334 6555 1 9 5 0 0 exe [41700.636719] [21338] 0 21338 6662 1 9 5 0 0 exe [41700.645386] [21466] 0 21466 415 1 5 3 0 0 exe [41700.653945] [24401] 0 24401 1 1 1 1 0 0 exe [41700.663004] [28149] 0 28149 179879 674 20 4 0 0 image [41700.671840] [28155] 0 28155 181035 776 23 4 0 0 exe [41700.680399] [28156] 0 28156 210605 5843 59 6 0 0 exe [41700.688954] [28188] 0 28188 1 1 1 1 0 0 exe [41700.697596] [28212] 0 28212 23744 3814 32 6 0 0 exe [41700.706235] [29155] 0 29155 35304 14044 74 5 0 0 syz-manager [41700.715483] [29330] 0 29330 179815 653 19 5 0 0 image [41700.724288] [29335] 0 29335 181035 717 24 4 0 0 exe [41700.732842] [29339] 0 29339 210349 5607 58 5 0 0 exe [41700.741397] [29365] 0 29365 1 1 1 1 0 0 exe [41700.749972] [29392] 0 29392 23671 4237 32 6 0 0 exe [41700.758543] [30487] 0 30487 180231 685 22 4 0 0 image [41700.767270] [30493] 0 30493 181323 705 23 4 0 0 exe [41700.775826] [30494] 0 30494 210669 5773 57 5 0 0 exe [41700.784391] [30527] 0 30527 1 1 1 1 0 0 exe [41700.792952] [30549] 0 30549 23584 3574 32 6 0 0 exe [41700.801516] [30847] 0 30847 180295 713 21 5 0 0 image [41700.810257] [30853] 0 30853 181323 704 24 5 0 0 exe [41700.818824] [30858] 65534 30858 17181672458 16349 128 6 0 0 exe [41700.828386] [30876] 0 30876 179879 692 20 5 0 0 image [41700.837121] [30882] 0 30882 181099 671 24 4 0 0 exe [41700.845683] [30887] 65534 30887 17181672518 16217 127 5 0 0 exe [41700.854677] [31017] 0 31017 180231 650 21 5 0 0 image [41700.863414] [31034] 0 31034 180231 648 21 4 0 0 image [41700.872154] [31149] 0 31149 180231 669 22 5 0 0 image [41700.880899] [31155] 0 31155 180167 708 20 4 0 0 image [41700.889807] [31157] 0 31157 180971 673 24 4 0 0 exe [41700.899065] [31166] 65534 31166 206365 3297 42 5 0 0 exe [41700.907680] [31172] 0 31172 181259 740 23 4 0 0 exe [41700.916250] [31200] 65534 31200 1 1 1 1 0 0 exe [41700.924812] [31214] 0 31214 179879 659 20 5 0 0 image [41700.933545] [31233] 0 31233 180167 672 21 4 0 0 image [41700.942281] [31234] 0 31234 181035 733 24 4 0 0 exe [41700.950845] [31243] 65534 31243 206109 2051 36 6 0 0 exe [41700.959396] [31249] 0 31249 181323 708 25 5 0 0 exe [41700.967949] [31254] 65534 31254 206173 1926 35 5 0 0 exe [41700.976514] [31267] 65534 31267 21159 628 18 6 0 0 exe [41700.985068] [31291] 65534 31291 1 1 1 1 0 0 exe [41700.993975] [31306] 65534 31306 1 1 1 1 0 0 exe [41701.002550] [31323] 65534 31323 20683 86 13 5 0 0 exe [41701.011109] [31339] 65534 31339 2170 1 2 2 0 0 exe [41701.019667] [31342] 0 31342 180231 726 22 4 0 0 image [41701.028404] [31349] 0 31349 181323 729 25 5 0 0 exe [41701.036967] [31354] 65534 31354 206109 1938 34 5 0 0 exe [41701.045529] [31371] 65534 31371 1 1 1 1 0 0 exe [41701.054090] [31374] 0 31374 179943 613 20 5 0 0 image [41701.062845] [31390] 65534 31390 1 1 1 1 0 0 exe [41701.071396] Out of memory: Kill process 38817 (exe) score 920 or sacrifice child [41701.079359] Killed process 38817 (exe) total-vm:372479204kB, anon-rss:117103720kB, file-rss:0kB, shmem-rss:55180kB [41704.806704] kvm [32907]: vcpu0, guest rIP: 0xa74064 unhandled rdmsr: 0xce [41704.820404] kvm [32914]: vcpu0, guest rIP: 0xa74064 unhandled rdmsr: 0xce [41710.393135] oom_reaper: reaped process 38817 (exe), now anon-rss:0kB, file-rss:8kB, shmem-rss:55180kB [42105.340214] kvm [57853]: vcpu0, guest rIP: 0xa74064 unhandled rdmsr: 0xce [42143.294408] kvm [62924]: vcpu0, guest rIP: 0xa74064 unhandled rdmsr: 0xce [42143.304992] kvm [62920]: vcpu0, guest rIP: 0xa74064 unhandled rdmsr: 0xce [42389.419832] exe invoked oom-killer: gfp_mask=0x24201ca(GFP_HIGHUSER_MOVABLE|__GFP_COLD), nodemask=0, order=0, oom_score_adj=0 [42389.431861] exe cpuset=/ mems_allowed=0 [42389.436153] CPU: 61 PID: 59505 Comm: exe Not tainted 4.9.0-11-amd64 #1 Debian 4.9.189-3+deb9u2 [42389.445346] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 [42389.455065] 0000000000000000 ffffffffa1136404 ffffba1957353c10 ffff9fca733d8040 [42389.463122] ffffffffa1009690 0000000000000000 0000000000000000 0000000c00000000 [42389.471256] ffff9fbd2bf46040 ffffffffa0f8d207 0000004200000000 ffffffffa10acee0 [42389.480524] Call Trace: [42389.483096] [] ? dump_stack+0x5c/0x78 [42389.488730] [] ? dump_header+0x78/0x1fd [42389.494673] [] ? get_page_from_freelist+0x3f7/0xb20 [42389.501324] [] ? cap_inode_killpriv+0x20/0x20 [42389.508508] [] ? oom_kill_process+0x22a/0x3f0 [42389.514690] [] ? out_of_memory+0x111/0x470 [42389.520582] [] ? __alloc_pages_slowpath+0xa1f/0xb30 [42389.527316] [] ? __alloc_pages_nodemask+0x201/0x260 [42389.534069] [] ? alloc_pages_current+0x91/0x140 [42389.540384] [] ? filemap_fault+0x326/0x5d0 [42389.546276] [] ? ext4_filemap_fault+0x31/0x50 [ext4] [42389.553789] [] ? __do_fault+0xa9/0x1c0 [42389.559301] [] ? handle_mm_fault+0xda4/0x1350 [42389.565431] [] ? __do_page_fault+0x255/0x4f0 [42389.571466] [] ? page_fault+0x28/0x30 [42389.577027] Mem-Info: [42389.579612] active_anon:30322881 inactive_anon:227293 isolated_anon:0 active_file:381 inactive_file:191 isolated_file:0 unevictable:0 dirty:0 writeback:0 unstable:0 slab_reclaimable:8155 slab_unreclaimable:32286 mapped:226633 shmem:247562 pagetables:73248 bounce:0 free:139256 free_pcp:105 free_cma:0 [42389.613712] Node 0 active_anon:121292020kB inactive_anon:909172kB active_file:3856kB inactive_file:3500kB unevictable:0kB isolated(anon):0kB isolated(file):0kB mapped:910656kB dirty:0kB writeback:0kB shmem:990248kB shmem_thp: 0kB shmem_pmdmapped: 0kB anon_thp: 174080kB writeback_tmp:0kB unstable:0kB pages_scanned:0 all_unreclaimable? no [42389.643659] 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 [42389.677423] lowmem_reserve[]: 0 2974 120837 120837 120837 [42389.684004] Node 0 DMA32 free:473012kB min:1660kB low:4704kB high:7748kB active_anon:2566252kB inactive_anon:15164kB active_file:0kB inactive_file:288kB unevictable:0kB writepending:0kB present:3129320kB managed:3063752kB mlocked:0kB slab_reclaimable:116kB slab_unreclaimable:848kB kernel_stack:148kB pagetables:4960kB bounce:0kB free_pcp:516kB local_pcp:0kB free_cma:0kB [42389.717639] lowmem_reserve[]: 0 0 117862 117862 117862 [42389.723859] Node 0 Normal free:65772kB min:65908kB low:186596kB high:307284kB active_anon:118725912kB inactive_anon:894008kB active_file:1876kB inactive_file:0kB unevictable:0kB writepending:0kB present:122683392kB managed:120696220kB mlocked:0kB slab_reclaimable:32504kB slab_unreclaimable:128296kB kernel_stack:62524kB pagetables:288032kB bounce:0kB free_pcp:0kB local_pcp:0kB free_cma:0kB [42389.758720] lowmem_reserve[]: 0 0 0 0 0 [42389.763557] 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 [42389.780080] Node 0 DMA32: 894*4kB (UE) 603*8kB (UE) 263*16kB (UME) 83*32kB (UMEH) 54*64kB (UMEH) 78*128kB (UMEH) 31*256kB (UEH) 5*512kB (U) 4*1024kB (UME) 2*2048kB (UM) 104*4096kB (M) = 473376kB [42389.801107] Node 0 Normal: 17066*4kB (U) 0*8kB 0*16kB 0*32kB 0*64kB 0*128kB 0*256kB 0*512kB 0*1024kB 0*2048kB 0*4096kB = 68264kB [42389.814902] Node 0 hugepages_total=0 hugepages_free=0 hugepages_surp=0 hugepages_size=1048576kB [42389.823734] Node 0 hugepages_total=0 hugepages_free=0 hugepages_surp=0 hugepages_size=2048kB [42389.832302] 249304 total pagecache pages [42389.836353] 0 pages in swap cache [42389.839825] Swap cache stats: add 0, delete 0, find 0/0 [42389.845201] Free swap = 0kB [42389.848197] Total swap = 0kB [42389.851214] 31457176 pages RAM [42389.854397] 0 pages HighMem/MovableOnly [42389.858354] 513206 pages reserved [42389.861789] 0 pages hwpoisoned [42389.865152] [ pid ] uid tgid total_vm rss nr_ptes nr_pmds swapents oom_score_adj name [42389.873895] [ 935] 0 935 75837 145 149 4 0 0 systemd-journal [42389.883704] [ 960] 0 960 11548 333 23 3 0 -1000 systemd-udevd [42389.893152] [ 1125] 0 1125 13686 112 26 3 0 -1000 auditd [42389.902334] [ 1137] 0 1137 21093 63 12 3 0 0 audispd [42389.911415] [ 1151] 109 1151 11282 108 28 3 0 -900 dbus-daemon [42389.920787] [ 1155] 0 1155 1051 21 8 3 0 0 acpid [42389.929526] [ 1157] 0 1157 7409 62 19 3 0 0 cron [42389.938194] [ 1158] 0 1158 9495 122 22 3 0 0 systemd-logind [42389.947733] [ 1180] 0 1180 31178 3294 63 5 0 0 syz-ci [42389.956595] [ 1660] 0 1660 5119 274 12 3 0 0 dhclient [42389.965799] [ 1707] 0 1707 90807 12313 116 4 0 0 puppet [42389.975196] [ 1714] 0 1714 3575 34 12 3 0 0 agetty [42389.984168] [ 1716] 0 1716 3631 33 12 3 0 0 agetty [42389.993109] [ 1743] 106 1743 24474 131 23 4 0 0 ntpd [42390.001862] [ 1746] 0 1746 334775 41660 555 4 0 0 ruby [42390.010657] [ 2124] 0 2124 17077 3608 39 4 0 0 google_network_ [42390.020286] [ 2195] 0 2195 17175 3716 40 3 0 -999 google_accounts [42390.030101] [ 2354] 0 2354 17072 3579 39 3 0 0 google_clock_sk [42390.039905] [ 2355] 0 2355 17489 187 37 4 0 -1000 sshd [42390.048591] [14088] 0 14088 105835 55547 170 5 0 0 syz-manager [42390.057878] [28925] 0 28925 114620 57318 174 5 0 0 syz-manager [42390.067390] [43361] 0 43361 126500 80034 229 5 0 0 syz-manager [42390.076689] [29507] 0 29507 102087 59285 189 5 0 0 syz-manager [42390.085998] [39690] 0 39690 62528 539 30 3 0 0 rsyslogd [42390.095047] [20160] 0 20160 83073 60155 166 5 0 0 syz-manager [42390.104338] [29155] 0 29155 45979 23849 94 5 0 0 syz-manager [42390.114041] [33038] 0 33038 180231 680 22 5 0 0 image [42390.122791] [33049] 0 33049 181387 755 25 4 0 0 exe [42390.131359] [33054] 65534 33054 17181727014 53843 221 6 0 0 exe [42390.140198] [36646] 0 36646 296594 3936 57 10 0 0 image [42390.149034] [36717] 0 36717 440671 7147 173 11 0 0 exe [42390.157590] [36728] 0 36728 38451727 29356453 64601 153 0 0 exe [42390.166147] [36875] 0 36875 1 1 1 1 0 0 exe [42390.174718] [37103] 0 37103 23624 5034 52 6 0 0 exe [42390.183523] [37210] 0 37210 179879 660 19 5 0 0 image [42390.192265] [38966] 0 38966 278097 3953 55 9 0 0 image [42390.201209] [38972] 0 38972 386818 4839 87 10 0 0 exe [42390.209785] [38973] 0 38973 1081536 22080 507 17 0 0 exe [42390.219045] [38992] 0 38992 1 1 1 1 0 0 exe [42390.228791] [39043] 0 39043 23704 5242 52 6 0 0 exe [42390.237383] [40235] 0 40235 294833 4031 57 10 0 0 image [42390.246235] [40240] 0 40240 386354 4811 82 10 0 0 exe [42390.254811] [40241] 0 40241 1205498 23039 523 18 0 0 exe [42390.263568] [40260] 0 40260 1 1 1 1 0 0 exe [42390.272160] [40341] 0 40341 23780 5318 52 6 0 0 exe [42390.280766] [57026] 0 57026 315379 3876 64 10 0 0 image [42390.290259] [57053] 0 57053 430282 6603 166 11 0 0 exe [42390.298921] [57055] 0 57055 2165169 407807 1789 25 0 0 exe [42390.307477] [57084] 0 57084 1 1 1 1 0 0 exe [42390.316050] [57149] 0 57149 23949 4657 53 6 0 0 exe [42390.324648] [57332] 0 57332 296594 3692 58 10 0 0 image [42390.335577] [57357] 0 57357 73845 46312 117 6 0 0 exe [42390.344765] [57544] 0 57544 834 1 5 4 0 0 exe [42390.353782] [57943] 0 57943 179879 729 20 4 0 0 image [42390.362544] [57956] 0 57956 181259 675 23 4 0 0 exe [42390.371099] [57958] 65534 57958 17181747376 68400 258 6 0 0 exe [42390.381478] [58056] 0 58056 626 1 5 4 0 0 exe [42390.390131] [58062] 0 58062 4988 1 6 4 0 0 exe [42390.400096] [58066] 0 58066 844 1 5 4 0 0 exe [42390.408747] [58070] 0 58070 4782 1 6 4 0 0 exe [42390.417639] [58074] 0 58074 721 1 5 4 0 0 exe [42390.426208] [58081] 0 58081 4938 1 5 4 0 0 exe [42390.434913] [58086] 0 58086 4875 1 6 4 0 0 exe [42390.443479] [58158] 0 58158 1 1 1 1 0 0 exe [42390.452037] [58169] 0 58169 1 1 1 1 0 0 exe [42390.460736] [58270] 0 58270 1 1 1 1 0 0 exe [42390.471035] [58445] 0 58445 1 1 1 1 0 0 exe [42390.479625] [60879] 0 60879 180167 660 21 4 0 0 image [42390.488869] [61039] 0 61039 6846 1 9 5 0 0 exe [42390.497451] [61516] 0 61516 296594 4045 57 10 0 0 image [42390.506184] [61522] 0 61522 313266 3692 62 10 0 0 image [42390.514932] [61523] 0 61523 388755 4904 87 10 0 0 exe [42390.523510] [61527] 0 61527 886294 20225 433 16 0 0 exe [42390.532351] [61549] 0 61549 29036 3004 29 6 0 0 exe [42390.545235] [61552] 0 61552 1 1 1 1 0 0 exe [42390.554268] [61581] 0 61581 23528 5143 52 6 0 0 exe [42390.562836] [61596] 0 61596 717 1 5 4 0 0 exe [42390.571402] [61617] 0 61617 4811 1 5 4 0 0 exe [42390.581086] [61629] 0 61629 825 1 5 4 0 0 exe [42390.589649] [61633] 0 61633 4919 1 5 4 0 0 exe [42390.598233] [61646] 0 61646 600 1 5 4 0 0 exe [42390.606812] [61654] 0 61654 4696 1 5 4 0 0 exe [42390.615714] [61656] 0 61656 682 1 5 4 0 0 exe [42390.624835] [61669] 0 61669 4776 1 5 4 0 0 exe [42390.633427] [61762] 0 61762 6795 1 9 5 0 0 exe [42390.642178] [61842] 0 61842 6689 1 9 5 0 0 exe [42390.650944] [61857] 0 61857 6572 1 9 5 0 0 exe [42390.659634] [61880] 0 61880 6654 1 9 5 0 0 exe [42390.668397] [ 6666] 0 6666 179879 691 21 4 0 0 image [42390.677962] [ 6672] 0 6672 181387 715 25 5 0 0 exe [42390.686967] [ 6677] 65534 6677 210669 5754 59 5 0 0 exe [42390.695572] [ 6706] 65534 6706 1 1 1 1 0 0 exe [42390.704133] [ 6733] 65534 6733 23908 4434 52 6 0 0 exe [42390.712699] [ 8352] 0 8352 180231 692 22 4 0 0 image [42390.721436] [ 8909] 0 8909 180231 673 22 5 0 0 image [42390.730256] [ 8916] 0 8916 181451 788 25 5 0 0 exe [42390.738819] [ 8920] 65534 8920 210413 5776 59 6 0 0 exe [42390.747381] [ 8946] 65534 8946 1 1 1 1 0 0 exe [42390.755948] [ 8976] 65534 8976 23738 3383 32 6 0 0 exe [42390.764508] [ 9942] 0 9942 179879 675 20 4 0 0 image [42390.773284] [ 9948] 0 9948 181259 722 25 5 0 0 exe [42390.781861] [ 9949] 0 9949 210605 5701 57 6 0 0 exe [42390.791999] [ 9983] 0 9983 1 1 1 1 0 0 exe [42390.801958] [10010] 0 10010 23842 3533 32 6 0 0 exe [42390.810663] [10834] 0 10834 11100 86 26 3 0 0 cron [42390.820138] [10836] 0 10836 1069 16 8 3 0 0 sh [42390.829327] [10837] 0 10837 1043 16 7 3 0 0 run-parts [42390.838623] [10838] 0 10838 2791 54 10 3 0 0 drawfork-puppet [42390.849888] [10839] 0 10839 1457 15 8 3 0 0 sleep [42390.860472] [10897] 0 10897 179879 670 22 5 0 0 image [42390.870595] [10904] 0 10904 181099 735 23 5 0 0 exe [42390.879173] [10905] 0 10905 210541 5676 57 6 0 0 exe [42390.889622] [10937] 0 10937 1 1 1 1 0 0 exe [42390.900048] [10966] 0 10966 23749 3539 32 6 0 0 exe [42390.910112] [11073] 0 11073 179879 697 20 5 0 0 image [42390.920236] [11080] 0 11080 181387 718 24 4 0 0 exe [42390.928797] [11085] 65534 11085 210541 5914 57 4 0 0 exe [42390.939919] [11113] 65534 11113 1 1 1 1 0 0 exe [42390.948815] [11146] 65534 11146 23597 3774 32 6 0 0 exe [42390.957486] [12146] 0 12146 180167 646 21 5 0 0 image [42390.966232] [12152] 0 12152 181387 764 25 5 0 0 exe [42390.974808] [12153] 0 12153 210605 5728 58 6 0 0 exe [42390.984558] [12183] 0 12183 1 1 1 1 0 0 exe [42390.993158] [12239] 0 12239 23427 3761 32 6 0 0 exe [42391.001737] Out of memory: Kill process 36728 (exe) score 922 or sacrifice child [42391.009407] Killed process 36875 (exe) total-vm:4kB, anon-rss:4kB, file-rss:0kB, shmem-rss:0kB [42391.044622] oom_reaper: reaped process 36875 (exe), now anon-rss:0kB, file-rss:0kB, shmem-rss:0kB [42396.219702] exe invoked oom-killer: gfp_mask=0x24201ca(GFP_HIGHUSER_MOVABLE|__GFP_COLD), nodemask=0, order=0, oom_score_adj=0 [42396.231183] exe cpuset=/ mems_allowed=0 [42396.236433] CPU: 8 PID: 57290 Comm: exe Not tainted 4.9.0-11-amd64 #1 Debian 4.9.189-3+deb9u2 [42396.245379] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 [42396.254725] 0000000000000000 ffffffffa1136404 ffffba195101bc10 ffff9fca733d8040 [42396.262758] ffffffffa1009690 0000000000000000 0000000000000000 0000000c00000001 [42396.270792] ffff9fca3bc0f0c0 ffffffffa0f8d207 0000004200000000 ffffffffa10acee0 [42396.278865] Call Trace: [42396.281439] [] ? dump_stack+0x5c/0x78 [42396.286873] [] ? dump_header+0x78/0x1fd [42396.292963] [] ? get_page_from_freelist+0x3f7/0xb20 [42396.299618] [] ? cap_inode_killpriv+0x20/0x20 [42396.305750] [] ? oom_kill_process+0x22a/0x3f0 [42396.311877] [] ? out_of_memory+0x111/0x470 [42396.317745] [] ? __alloc_pages_slowpath+0xa1f/0xb30 [42396.324390] [] ? __alloc_pages_nodemask+0x201/0x260 [42396.331043] [] ? alloc_pages_current+0x91/0x140 [42396.337342] [] ? filemap_fault+0x326/0x5d0 [42396.343597] [] ? ext4_filemap_fault+0x31/0x50 [ext4] [42396.350423] [] ? __do_fault+0xa9/0x1c0 [42396.355949] [] ? handle_mm_fault+0xda4/0x1350 [42396.362085] [] ? __do_page_fault+0x255/0x4f0 [42396.368129] [] ? page_fault+0x28/0x30 [42396.373606] Mem-Info: [42396.376019] active_anon:30325682 inactive_anon:227293 isolated_anon:0 active_file:727 inactive_file:0 isolated_file:0 unevictable:0 dirty:0 writeback:0 unstable:0 slab_reclaimable:7802 slab_unreclaimable:30053 mapped:227032 shmem:247562 pagetables:73253 bounce:0 free:138706 free_pcp:15 free_cma:0 [42396.409787] Node 0 active_anon:121302728kB inactive_anon:909172kB active_file:340kB inactive_file:0kB unevictable:0kB isolated(anon):0kB isolated(file):0kB mapped:908192kB dirty:0kB writeback:0kB shmem:990248kB shmem_thp: 0kB shmem_pmdmapped: 0kB anon_thp: 174080kB writeback_tmp:0kB unstable:0kB pages_scanned:18857 all_unreclaimable? no [42396.440516] 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 [42396.470533] lowmem_reserve[]: 0 2974 120837 120837 120837 [42396.477011] Node 0 DMA32 free:473092kB min:1660kB low:4704kB high:7748kB active_anon:2567424kB inactive_anon:15164kB active_file:112kB inactive_file:0kB unevictable:0kB writepending:0kB present:3129320kB managed:3063752kB mlocked:0kB slab_reclaimable:112kB slab_unreclaimable:568kB kernel_stack:148kB pagetables:4960kB bounce:0kB free_pcp:0kB local_pcp:0kB free_cma:0kB [42396.509941] lowmem_reserve[]: 0 0 117862 117862 117862 [42396.516059] Node 0 Normal free:65240kB min:65908kB low:186596kB high:307284kB active_anon:118735308kB inactive_anon:894008kB active_file:1568kB inactive_file:252kB unevictable:0kB writepending:0kB present:122683392kB managed:120696220kB mlocked:0kB slab_reclaimable:31096kB slab_unreclaimable:119644kB kernel_stack:62524kB pagetables:288052kB bounce:0kB free_pcp:0kB local_pcp:0kB free_cma:0kB [42396.551932] lowmem_reserve[]: 0 0 0 0 0 [42396.556738] 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 [42396.573184] Node 0 DMA32: 873*4kB (UME) 596*8kB (UME) 275*16kB (UME) 91*32kB (UMEH) 55*64kB (UMEH) 77*128kB (UEH) 32*256kB (UMEH) 6*512kB (UM) 3*1024kB (UE) 2*2048kB (UM) 104*4096kB (M) = 473364kB [42396.594414] Node 0 Normal: 17813*4kB (UME) 204*8kB (UM) 23*16kB (M) 4*32kB (M) 0*64kB 0*128kB 0*256kB 0*512kB 0*1024kB 0*2048kB 0*4096kB = 73380kB [42396.610239] Node 0 hugepages_total=0 hugepages_free=0 hugepages_surp=0 hugepages_size=1048576kB [42396.620720] Node 0 hugepages_total=0 hugepages_free=0 hugepages_surp=0 hugepages_size=2048kB [42396.629298] 249856 total pagecache pages [42396.633348] 0 pages in swap cache [42396.636803] Swap cache stats: add 0, delete 0, find 0/0 [42396.642156] Free swap = 0kB [42396.645163] Total swap = 0kB [42396.648182] 31457176 pages RAM [42396.651360] 0 pages HighMem/MovableOnly [42396.655336] 513206 pages reserved [42396.658773] 0 pages hwpoisoned [42396.661940] [ pid ] uid tgid total_vm rss nr_ptes nr_pmds swapents oom_score_adj name [42396.670684] [ 935] 0 935 75837 145 149 4 0 0 systemd-journal [42396.680316] [ 960] 0 960 11548 333 23 3 0 -1000 systemd-udevd [42396.690368] [ 1125] 0 1125 13686 112 26 3 0 -1000 auditd [42396.699209] [ 1137] 0 1137 21093 75 12 3 0 0 audispd [42396.708870] [ 1151] 109 1151 11282 108 28 3 0 -900 dbus-daemon [42396.718680] [ 1155] 0 1155 1051 21 8 3 0 0 acpid [42396.727426] [ 1157] 0 1157 7409 62 19 3 0 0 cron [42396.736081] [ 1158] 0 1158 9495 122 22 3 0 0 systemd-logind [42396.745743] [ 1180] 0 1180 31178 3294 63 5 0 0 syz-ci [42396.754611] [ 1660] 0 1660 5119 274 12 3 0 0 dhclient [42396.763617] [ 1707] 0 1707 90807 12313 116 4 0 0 puppet [42396.772450] [ 1714] 0 1714 3575 34 12 3 0 0 agetty [42396.781279] [ 1716] 0 1716 3631 33 12 3 0 0 agetty [42396.790112] [ 1743] 106 1743 24474 131 23 4 0 0 ntpd [42396.798760] [ 1746] 0 1746 334775 41660 555 4 0 0 ruby [42396.807543] [ 2124] 0 2124 17077 3608 39 4 0 0 google_network_ [42396.817152] [ 2195] 0 2195 17175 3716 40 3 0 -999 google_accounts [42396.826758] [ 2354] 0 2354 17072 3579 39 3 0 0 google_clock_sk [42396.836406] [ 2355] 0 2355 17489 187 37 4 0 -1000 sshd [42396.845118] [14088] 0 14088 105835 55632 170 5 0 0 syz-manager [42396.854390] [28925] 0 28925 114620 57321 174 5 0 0 syz-manager [42396.864020] [43361] 0 43361 126500 80046 229 5 0 0 syz-manager [42396.873291] [29507] 0 29507 102087 59303 189 5 0 0 syz-manager [42396.882612] [39690] 0 39690 62528 539 30 3 0 0 rsyslogd [42396.891646] [20160] 0 20160 83073 60156 166 5 0 0 syz-manager [42396.900931] [29155] 0 29155 45979 23877 94 5 0 0 syz-manager [42396.910214] [33038] 0 33038 180231 680 22 5 0 0 image [42396.918957] [33049] 0 33049 181387 755 25 4 0 0 exe [42396.927542] [33054] 65534 33054 17181727014 53843 221 6 0 0 exe [42396.936369] [36646] 0 36646 296594 3936 57 10 0 0 image [42396.945113] [36717] 0 36717 440671 7147 173 11 0 0 exe [42396.953686] [36728] 0 36728 38451727 29359699 64607 153 0 0 exe [42396.962248] [36875] 0 36875 1 0 1 1 0 0 exe [42396.970809] [37103] 0 37103 23624 5034 52 6 0 0 exe [42396.979368] [37210] 0 37210 179879 660 19 5 0 0 image [42396.988101] [38966] 0 38966 278097 3952 55 9 0 0 image [42396.996835] [38972] 0 38972 386818 4838 87 10 0 0 exe [42397.005410] [38973] 0 38973 1081536 22080 507 17 0 0 exe [42397.013982] [38992] 0 38992 1 1 1 1 0 0 exe [42397.022552] [39043] 0 39043 23704 5242 52 6 0 0 exe [42397.031121] [40235] 0 40235 294833 4031 57 10 0 0 image [42397.039868] [40240] 0 40240 386354 4811 82 10 0 0 exe [42397.048465] [40241] 0 40241 1205498 23060 523 18 0 0 exe [42397.057062] [40260] 0 40260 1 1 1 1 0 0 exe [42397.065625] [40341] 0 40341 23780 5318 52 6 0 0 exe [42397.074202] [57026] 0 57026 315379 3876 64 10 0 0 image [42397.082965] [57053] 0 57053 430282 6603 166 11 0 0 exe [42397.091541] [57055] 0 57055 2165169 407805 1789 25 0 0 exe [42397.100111] [57084] 0 57084 1 1 1 1 0 0 exe [42397.108677] [57149] 0 57149 23949 4657 53 6 0 0 exe [42397.117257] [57332] 0 57332 296594 3692 58 10 0 0 image [42397.126098] [57357] 0 57357 73845 46312 117 6 0 0 exe [42397.134677] [57544] 0 57544 834 1 5 4 0 0 exe [42397.143265] [57943] 0 57943 179879 729 20 4 0 0 image [42397.152022] [57956] 0 57956 181259 675 23 4 0 0 exe [42397.160604] [57958] 65534 57958 17181747376 68416 258 6 0 0 exe [42397.169711] [58056] 0 58056 626 1 5 4 0 0 exe [42397.181210] [58062] 0 58062 4988 1 6 4 0 0 exe [42397.189787] [58066] 0 58066 844 1 5 4 0 0 exe [42397.198375] [58070] 0 58070 4782 1 6 4 0 0 exe [42397.206962] [58074] 0 58074 721 1 5 4 0 0 exe [42397.215545] [58081] 0 58081 4938 1 5 4 0 0 exe [42397.224225] [58086] 0 58086 4875 1 6 4 0 0 exe [42397.232814] [58158] 0 58158 1 1 1 1 0 0 exe [42397.241825] [58169] 0 58169 1 1 1 1 0 0 exe [42397.250399] [58270] 0 58270 1 1 1 1 0 0 exe [42397.259148] [58445] 0 58445 1 1 1 1 0 0 exe [42397.267911] [60879] 0 60879 180167 660 21 4 0 0 image [42397.277137] [61039] 0 61039 6846 1 9 5 0 0 exe [42397.285715] [61516] 0 61516 296594 4045 57 10 0 0 image [42397.295970] [61522] 0 61522 313266 3692 62 10 0 0 image [42397.304856] [61523] 0 61523 388755 4904 87 10 0 0 exe [42397.313419] [61527] 0 61527 886294 20252 433 16 0 0 exe [42397.321978] [61549] 0 61549 29036 3004 29 6 0 0 exe [42397.330563] [61552] 0 61552 1 1 1 1 0 0 exe [42397.339131] [61581] 0 61581 23528 5143 52 6 0 0 exe [42397.347690] [61596] 0 61596 717 1 5 4 0 0 exe [42397.356408] [61617] 0 61617 4811 1 5 4 0 0 exe [42397.365722] [61629] 0 61629 825 1 5 4 0 0 exe [42397.374573] [61633] 0 61633 4919 1 5 4 0 0 exe [42397.383144] [61646] 0 61646 600 1 5 4 0 0 exe [42397.391711] [61654] 0 61654 4696 1 5 4 0 0 exe [42397.400272] [61656] 0 61656 682 1 5 4 0 0 exe [42397.408839] [61669] 0 61669 4776 1 5 4 0 0 exe [42397.417412] [61762] 0 61762 6795 1 9 5 0 0 exe [42397.425976] [61842] 0 61842 6689 1 9 5 0 0 exe [42397.434538] [61857] 0 61857 6572 1 9 5 0 0 exe [42397.443111] [61880] 0 61880 6654 1 9 5 0 0 exe [42397.451703] [ 6666] 0 6666 179879 691 21 4 0 0 image [42397.460748] [ 6672] 0 6672 181387 715 25 5 0 0 exe [42397.469317] [ 6677] 65534 6677 210669 5754 59 5 0 0 exe [42397.477877] [ 6706] 65534 6706 1 1 1 1 0 0 exe [42397.486439] [ 6733] 65534 6733 23908 4434 52 6 0 0 exe [42397.495029] [ 8352] 0 8352 180231 692 22 4 0 0 image [42397.503786] [ 8909] 0 8909 180231 673 22 5 0 0 image [42397.512730] [ 8916] 0 8916 181451 788 25 5 0 0 exe [42397.521676] [ 8920] 65534 8920 210413 5776 59 6 0 0 exe [42397.530246] [ 8946] 65534 8946 1 1 1 1 0 0 exe [42397.538805] [ 8976] 65534 8976 23738 3383 32 6 0 0 exe [42397.547369] [ 9942] 0 9942 179879 675 20 4 0 0 image [42397.556202] [ 9948] 0 9948 181259 722 25 5 0 0 exe [42397.564764] [ 9949] 0 9949 210605 5701 57 6 0 0 exe [42397.573375] [ 9983] 0 9983 1 1 1 1 0 0 exe [42397.581962] [10010] 0 10010 23842 3533 32 6 0 0 exe [42397.590543] [10834] 0 10834 11100 86 26 3 0 0 cron [42397.599420] [10836] 0 10836 1069 16 8 3 0 0 sh [42397.607901] [10837] 0 10837 1043 16 7 3 0 0 run-parts [42397.617000] [10838] 0 10838 2791 54 10 3 0 0 drawfork-puppet [42397.626622] [10839] 0 10839 1457 15 8 3 0 0 sleep [42397.635374] [10897] 0 10897 179879 670 22 5 0 0 image [42397.644110] [10904] 0 10904 181099 735 23 5 0 0 exe [42397.652767] [10905] 0 10905 210541 5676 57 6 0 0 exe [42397.661372] [10937] 0 10937 1 1 1 1 0 0 exe [42397.670390] [10966] 0 10966 23749 3539 32 6 0 0 exe [42397.679074] [11073] 0 11073 179879 697 20 5 0 0 image [42397.687819] [11080] 0 11080 181387 718 24 4 0 0 exe [42397.696385] [11085] 65534 11085 210541 5914 57 4 0 0 exe [42397.705561] [11113] 65534 11113 1 1 1 1 0 0 exe [42397.714135] [11146] 65534 11146 23597 3774 32 6 0 0 exe [42397.726799] [12146] 0 12146 180167 646 21 5 0 0 image [42397.735545] [12152] 0 12152 181387 764 25 5 0 0 exe [42397.744131] [12153] 0 12153 210605 5739 58 6 0 0 exe [42397.752714] [12183] 0 12183 1 1 1 1 0 0 exe [42397.761358] [12239] 0 12239 23427 3761 32 6 0 0 exe [42397.770024] Out of memory: Kill process 36728 (exe) score 922 or sacrifice child [42397.778504] Killed process 36728 (exe) total-vm:153806908kB, anon-rss:117382468kB, file-rss:0kB, shmem-rss:56328kB [42405.170525] kvm [14478]: vcpu0, guest rIP: 0xa74064 unhandled rdmsr: 0xce [42405.559910] kvm [14540]: vcpu0, guest rIP: 0xa74064 unhandled rdmsr: 0xce [42405.719427] kvm [14564]: vcpu0, guest rIP: 0xa74064 unhandled rdmsr: 0xce [42406.013585] kvm [14706]: vcpu0, guest rIP: 0xa74064 unhandled rdmsr: 0xce [42695.807633] syz-ci invoked oom-killer: gfp_mask=0x24201ca(GFP_HIGHUSER_MOVABLE|__GFP_COLD), nodemask=0, order=0, oom_score_adj=0 [42695.819466] syz-ci cpuset=/ mems_allowed=0 [42695.823977] CPU: 21 PID: 13789 Comm: syz-ci Not tainted 4.9.0-11-amd64 #1 Debian 4.9.189-3+deb9u2 [42695.832992] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 [42695.842334] 0000000000000000 ffffffffa1136404 ffffba19583ebc10 ffff9fc4572de0c0 [42695.850568] ffffffffa1009690 0000000000000000 0000000000000000 0000000c00000000 [42695.859130] ffff9fc840a02040 ffffffffa0f8d207 0000004200000000 ffffffffa10acee0 [42695.867175] Call Trace: [42695.869752] [] ? dump_stack+0x5c/0x78 [42695.875196] [] ? dump_header+0x78/0x1fd [42695.880810] [] ? get_page_from_freelist+0x3f7/0xb20 [42695.887454] [] ? cap_inode_killpriv+0x20/0x20 [42695.894103] [] ? oom_kill_process+0x22a/0x3f0 [42695.900432] [] ? out_of_memory+0x111/0x470 [42695.906307] [] ? __alloc_pages_slowpath+0xa1f/0xb30 [42695.912952] [] ? __alloc_pages_nodemask+0x201/0x260 [42695.919600] [] ? alloc_pages_current+0x91/0x140 [42695.925907] [] ? filemap_fault+0x326/0x5d0 [42695.931828] [] ? ext4_filemap_fault+0x31/0x50 [ext4] [42695.938558] [] ? __do_fault+0xa9/0x1c0 [42695.944504] [] ? handle_mm_fault+0xda4/0x1350 [42695.950625] [] ? pick_next_task_fair+0x2fb/0x490 [42695.957006] [] ? __do_page_fault+0x255/0x4f0 [42695.963039] [] ? page_fault+0x28/0x30 [42695.968539] Mem-Info: [42695.970976] active_anon:30204935 inactive_anon:330510 isolated_anon:0 active_file:148 inactive_file:739 isolated_file:0 unevictable:0 dirty:0 writeback:0 unstable:0 slab_reclaimable:8000 slab_unreclaimable:34784 mapped:334205 shmem:358465 pagetables:72353 bounce:0 free:138839 free_pcp:927 free_cma:0 [42696.006259] Node 0 active_anon:120819776kB inactive_anon:1322040kB active_file:2140kB inactive_file:3412kB unevictable:0kB isolated(anon):0kB isolated(file):80kB mapped:1338520kB dirty:0kB writeback:0kB shmem:1433860kB shmem_thp: 0kB shmem_pmdmapped: 0kB anon_thp: 147456kB writeback_tmp:0kB unstable:0kB pages_scanned:96 all_unreclaimable? no [42696.036757] 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 [42696.067274] lowmem_reserve[]: 0 2974 120837 120837 120837 [42696.073997] Node 0 DMA32 free:473388kB min:1660kB low:4704kB high:7748kB active_anon:2570360kB inactive_anon:10580kB active_file:0kB inactive_file:84kB unevictable:0kB writepending:0kB present:3129320kB managed:3063752kB mlocked:0kB slab_reclaimable:100kB slab_unreclaimable:820kB kernel_stack:208kB pagetables:4936kB bounce:0kB free_pcp:128kB local_pcp:0kB free_cma:0kB [42696.107532] lowmem_reserve[]: 0 0 117862 117862 117862 [42696.113735] Node 0 Normal free:66068kB min:65908kB low:186596kB high:307284kB active_anon:118249420kB inactive_anon:1311460kB active_file:1440kB inactive_file:1604kB unevictable:0kB writepending:0kB present:122683392kB managed:120696220kB mlocked:0kB slab_reclaimable:31896kB slab_unreclaimable:137776kB kernel_stack:87360kB pagetables:284476kB bounce:0kB free_pcp:692kB local_pcp:0kB free_cma:0kB [42696.150770] lowmem_reserve[]: 0 0 0 0 0 [42696.156101] 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 [42696.173174] Node 0 DMA32: 573*4kB (UME) 316*8kB (UME) 130*16kB (UME) 68*32kB (UMEH) 103*64kB (UMEH) 70*128kB (UH) 33*256kB (UEH) 8*512kB (U) 4*1024kB (UE) 1*2048kB (U) 105*4096kB (M) = 473396kB [42696.195430] Node 0 Normal: 17075*4kB (UME) 104*8kB (UM) 0*16kB 0*32kB 0*64kB 0*128kB 0*256kB 0*512kB 0*1024kB 0*2048kB 0*4096kB = 69132kB [42696.212731] Node 0 hugepages_total=0 hugepages_free=0 hugepages_surp=0 hugepages_size=1048576kB [42696.222396] Node 0 hugepages_total=0 hugepages_free=0 hugepages_surp=0 hugepages_size=2048kB [42696.231501] 360400 total pagecache pages [42696.235838] 0 pages in swap cache [42696.240167] Swap cache stats: add 0, delete 0, find 0/0 [42696.246235] Free swap = 0kB [42696.249267] Total swap = 0kB [42696.252456] 31457176 pages RAM [42696.255758] 0 pages HighMem/MovableOnly [42696.259730] 513206 pages reserved [42696.263181] 0 pages hwpoisoned [42696.266525] [ pid ] uid tgid total_vm rss nr_ptes nr_pmds swapents oom_score_adj name [42696.275408] [ 935] 0 935 102515 145 202 4 0 0 systemd-journal [42696.285031] [ 960] 0 960 11548 333 23 3 0 -1000 systemd-udevd [42696.294478] [ 1125] 0 1125 13686 112 26 3 0 -1000 auditd [42696.303482] [ 1137] 0 1137 21093 40 12 3 0 0 audispd [42696.312405] [ 1151] 109 1151 11282 108 28 3 0 -900 dbus-daemon [42696.321678] [ 1155] 0 1155 1051 21 8 3 0 0 acpid [42696.330427] [ 1157] 0 1157 7409 61 19 3 0 0 cron [42696.339205] [ 1158] 0 1158 9495 122 22 3 0 0 systemd-logind [42696.350755] [ 1180] 0 1180 31178 3278 63 5 0 0 syz-ci [42696.359802] [ 1660] 0 1660 5119 274 12 3 0 0 dhclient [42696.368928] [ 1707] 0 1707 90807 12314 116 4 0 0 puppet [42696.377786] [ 1714] 0 1714 3575 34 12 3 0 0 agetty [42696.386644] [ 1716] 0 1716 3631 33 12 3 0 0 agetty [42696.395589] [ 1743] 106 1743 24474 131 23 4 0 0 ntpd [42696.404472] [ 1746] 0 1746 338871 41660 557 4 0 0 ruby [42696.413459] [ 2124] 0 2124 17077 3608 39 4 0 0 google_network_ [42696.423696] [ 2195] 0 2195 17175 3718 40 3 0 -999 google_accounts [42696.433514] [ 2354] 0 2354 17072 3579 39 3 0 0 google_clock_sk [42696.443440] [ 2355] 0 2355 17489 187 37 4 0 -1000 sshd [42696.452122] [14088] 0 14088 105899 57442 171 5 0 0 syz-manager [42696.461396] [28925] 0 28925 114620 58252 172 5 0 0 syz-manager [42696.470664] [43361] 0 43361 126564 70313 211 5 0 0 syz-manager [42696.479930] [29507] 0 29507 102087 52701 189 5 0 0 syz-manager [42696.489204] [39690] 0 39690 62528 527 30 3 0 0 rsyslogd [42696.498385] [20160] 0 20160 83073 58869 166 5 0 0 syz-manager [42696.507695] [29155] 0 29155 46043 24002 94 5 0 0 syz-manager [42696.517019] [40235] 0 40235 294833 4030 57 10 0 0 image [42696.528401] [40240] 0 40240 433996 7231 168 11 0 0 exe [42696.536988] [40241] 0 40241 38280930 29596123 65046 152 0 0 exe [42696.545594] [40260] 0 40260 1 1 1 1 0 0 exe [42696.554273] [40341] 0 40341 23780 5318 52 6 0 0 exe [42696.562882] [61516] 0 61516 296594 4044 57 10 0 0 image [42696.572012] [61523] 0 61523 388755 4904 87 10 0 0 exe [42696.580731] [61527] 0 61527 1081277 22135 487 18 0 0 exe [42696.589442] [61552] 0 61552 1 1 1 1 0 0 exe [42696.598922] [61581] 0 61581 23528 5229 52 6 0 0 exe [42696.607958] [14522] 0 14522 179879 679 20 4 0 0 image [42696.616710] [14534] 0 14534 181387 709 24 5 0 0 exe [42696.625826] [14540] 65534 14540 17181722747 50048 207 6 0 0 exe [42696.636851] [14609] 0 14609 180167 683 21 5 0 0 image [42696.645613] [14616] 0 14616 181387 770 23 4 0 0 exe [42696.655005] [14621] 65534 14621 17181722432 48119 207 6 0 0 exe [42696.663954] [15811] 0 15811 180167 674 21 5 0 0 image [42696.672813] [15821] 0 15821 180971 715 23 5 0 0 exe [42696.682265] [15826] 65534 15826 17181747306 64741 259 6 0 0 exe [42696.691099] [16749] 0 16749 179879 694 20 5 0 0 image [42696.699846] [16770] 0 16770 181099 743 24