D0316 07:47:27.222706 10804 parameters.go:238] Clock(Monotonic): error: 156 ns, adjusted frequency from 2299774226 Hz to 2299774764 Hz D0316 07:47:27.223044 10804 parameters.go:238] Clock(Realtime): error: 82 ns, adjusted frequency from 2299774338 Hz to 2299774634 Hz D0316 07:47:27.223145 10804 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:681081296363242 monotonicBaseRef:2000239876 monotonicFrequency:2299774764 realtimeReady:1 realtimeBaseCycles:681081297204481 realtimeBaseRef:1584344847223039110 realtimeFrequency:2299774634} D0316 07:47:28.222741 10804 parameters.go:238] Clock(Monotonic): error: 93 ns, adjusted frequency from 2299774764 Hz to 2299774684 Hz D0316 07:47:28.222980 10804 parameters.go:238] Clock(Realtime): error: 96 ns, adjusted frequency from 2299774634 Hz to 2299774724 Hz D0316 07:47:28.223094 10804 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:681083596251905 monotonicBaseRef:3000289402 monotonicFrequency:2299774684 realtimeReady:1 realtimeBaseCycles:681083596832371 realtimeBaseRef:1584344848222975302 realtimeFrequency:2299774724} D0316 07:47:29.223754 10804 sampler.go:168] Time: Adjusting syscall overhead up to 8000 D0316 07:47:29.224061 10804 parameters.go:238] Clock(Monotonic): error: 288 ns, adjusted frequency from 2299774684 Hz to 2299775088 Hz D0316 07:47:29.224227 10804 sampler.go:168] Time: Adjusting syscall overhead up to 8000 D0316 07:47:29.224322 10804 parameters.go:238] Clock(Realtime): error: 212 ns, adjusted frequency from 2299774724 Hz to 2299774994 Hz D0316 07:47:29.224464 10804 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:681085899057076 monotonicBaseRef:4001607134 monotonicFrequency:2299775088 realtimeReady:1 realtimeBaseCycles:681085899691441 realtimeBaseRef:1584344849224316453 realtimeFrequency:2299774994} D0316 07:47:30.222736 10804 parameters.go:238] Clock(Monotonic): error: -159 ns, adjusted frequency from 2299775088 Hz to 2299774150 Hz D0316 07:47:30.222969 10804 parameters.go:238] Clock(Realtime): error: 123 ns, adjusted frequency from 2299774994 Hz to 2299774700 Hz D0316 07:47:30.223084 10804 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:681088195749625 monotonicBaseRef:5000266768 monotonicFrequency:2299774150 realtimeReady:1 realtimeBaseCycles:681088196356869 realtimeBaseRef:1584344850222964335 realtimeFrequency:2299774700} D0316 07:47:31.223497 10804 parameters.go:238] Clock(Monotonic): error: 97 ns, adjusted frequency from 2299774150 Hz to 2299774741 Hz D0316 07:47:31.223799 10804 parameters.go:238] Clock(Realtime): error: 211 ns, adjusted frequency from 2299774700 Hz to 2299774922 Hz D0316 07:47:31.223934 10804 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:681090497316531 monotonicBaseRef:6001046303 monotonicFrequency:2299774741 realtimeReady:1 realtimeBaseCycles:681090498034851 realtimeBaseRef:1584344851223791929 realtimeFrequency:2299774922} D0316 07:47:32.223480 10804 parameters.go:238] Clock(Monotonic): error: 344 ns, adjusted frequency from 2299774741 Hz to 2299775192 Hz D0316 07:47:32.223711 10804 parameters.go:238] Clock(Realtime): error: -97 ns, adjusted frequency from 2299774922 Hz to 2299774270 Hz D0316 07:47:32.223861 10804 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:681092797051415 monotonicBaseRef:7001028972 monotonicFrequency:2299775192 realtimeReady:1 realtimeBaseCycles:681092797611263 realtimeBaseRef:1584344852223705611 realtimeFrequency:2299774270} D0316 07:47:33.224314 10804 parameters.go:238] Clock(Monotonic): error: 58 ns, adjusted frequency from 2299775192 Hz to 2299774512 Hz D0316 07:47:33.224512 10804 parameters.go:238] Clock(Realtime): error: 68 ns, adjusted frequency from 2299774270 Hz to 2299774660 Hz D0316 07:47:33.224715 10804 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:681095098683031 monotonicBaseRef:8001836191 monotonicFrequency:2299774512 realtimeReady:1 realtimeBaseCycles:681095099225990 realtimeBaseRef:1584344853224505888 realtimeFrequency:2299774660} D0316 07:47:34.222738 10804 parameters.go:238] Clock(Monotonic): error: -200 ns, adjusted frequency from 2299774512 Hz to 2299774081 Hz D0316 07:47:34.223019 10804 parameters.go:238] Clock(Realtime): error: -159 ns, adjusted frequency from 2299774660 Hz to 2299774178 Hz D0316 07:47:34.223168 10804 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:681097394889675 monotonicBaseRef:9000284791 monotonicFrequency:2299774081 realtimeReady:1 realtimeBaseCycles:681097395564263 realtimeBaseRef:1584344854223011660 realtimeFrequency:2299774178} D0316 07:47:35.222952 10804 parameters.go:238] Clock(Monotonic): error: -129 ns, adjusted frequency from 2299774081 Hz to 2299774275 Hz D0316 07:47:35.223134 10804 parameters.go:238] Clock(Realtime): error: 152 ns, adjusted frequency from 2299774178 Hz to 2299774880 Hz D0316 07:47:35.223220 10804 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:681099695160738 monotonicBaseRef:10000500891 monotonicFrequency:2299774275 realtimeReady:1 realtimeBaseCycles:681099695608739 realtimeBaseRef:1584344855223129192 realtimeFrequency:2299774880} D0316 07:47:36.222717 10804 parameters.go:238] Clock(Monotonic): error: 488 ns, adjusted frequency from 2299774275 Hz to 2299775643 Hz D0316 07:47:36.222953 10804 parameters.go:238] Clock(Realtime): error: 180 ns, adjusted frequency from 2299774880 Hz to 2299774964 Hz D0316 07:47:36.223094 10804 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:681101994364524 monotonicBaseRef:11000252827 monotonicFrequency:2299775643 realtimeReady:1 realtimeBaseCycles:681101994965066 realtimeBaseRef:1584344856222947194 realtimeFrequency:2299774964} D0316 07:47:37.222707 10804 parameters.go:238] Clock(Monotonic): error: -10 ns, adjusted frequency from 2299775643 Hz to 2299774442 Hz D0316 07:47:37.222913 10804 parameters.go:238] Clock(Realtime): error: -63 ns, adjusted frequency from 2299774964 Hz to 2299774378 Hz D0316 07:47:37.223115 10804 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:681104294144297 monotonicBaseRef:12000254622 monotonicFrequency:2299774442 realtimeReady:1 realtimeBaseCycles:681104294646014 realtimeBaseRef:1584344857222906313 realtimeFrequency:2299774378} D0316 07:47:38.222698 10804 parameters.go:238] Clock(Monotonic): error: 180 ns, adjusted frequency from 2299774442 Hz to 2299775038 Hz D0316 07:47:38.222935 10804 parameters.go:238] Clock(Realtime): error: 180 ns, adjusted frequency from 2299774378 Hz to 2299774992 Hz D0316 07:47:38.223068 10804 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:681106593897065 monotonicBaseRef:13000245197 monotonicFrequency:2299775038 realtimeReady:1 realtimeBaseCycles:681106594473611 realtimeBaseRef:1584344858222929453 realtimeFrequency:2299774992} D0316 07:47:39.222708 10804 parameters.go:238] Clock(Monotonic): error: -571 ns, adjusted frequency from 2299775038 Hz to 2299773248 Hz D0316 07:47:39.222931 10804 parameters.go:238] Clock(Realtime): error: -320 ns, adjusted frequency from 2299774992 Hz to 2299773836 Hz D0316 07:47:39.223047 10804 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:681108893693709 monotonicBaseRef:14000254591 monotonicFrequency:2299773248 realtimeReady:1 realtimeBaseCycles:681108894240637 realtimeBaseRef:1584344859222925989 realtimeFrequency:2299773836} D0316 07:47:40.222846 10804 parameters.go:238] Clock(Monotonic): error: 7 ns, adjusted frequency from 2299773248 Hz to 2299774588 Hz D0316 07:47:40.223082 10804 parameters.go:238] Clock(Realtime): error: 68 ns, adjusted frequency from 2299773836 Hz to 2299774710 Hz D0316 07:47:40.223176 10804 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:681111193787943 monotonicBaseRef:15000394163 monotonicFrequency:2299774588 realtimeReady:1 realtimeBaseCycles:681111194361044 realtimeBaseRef:1584344860223076686 realtimeFrequency:2299774710} D0316 07:47:41.222763 10804 parameters.go:238] Clock(Monotonic): error: -14 ns, adjusted frequency from 2299774588 Hz to 2299774638 Hz D0316 07:47:41.222969 10804 parameters.go:238] Clock(Realtime): error: 3 ns, adjusted frequency from 2299774710 Hz to 2299774564 Hz D0316 07:47:41.223106 10804 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:681113493282905 monotonicBaseRef:16000272574 monotonicFrequency:2299774638 realtimeReady:1 realtimeBaseCycles:681113493873428 realtimeBaseRef:1584344861222962620 realtimeFrequency:2299774564} D0316 07:47:42.223608 10804 sampler.go:197] Time: Adjusting syscall overhead down to 7000 D0316 07:47:42.223819 10804 parameters.go:238] Clock(Monotonic): error: -71 ns, adjusted frequency from 2299774638 Hz to 2299774535 Hz D0316 07:47:42.223936 10804 parameters.go:238] Clock(Realtime): error: -48 ns, adjusted frequency from 2299774564 Hz to 2299774446 Hz D0316 07:47:42.224059 10804 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:681115795099718 monotonicBaseRef:17001160563 monotonicFrequency:2299774535 realtimeReady:1 realtimeBaseCycles:681115795875848 realtimeBaseRef:1584344862223931347 realtimeFrequency:2299774446} D0316 07:47:43.223550 10804 parameters.go:238] Clock(Monotonic): error: 291 ns, adjusted frequency from 2299774535 Hz to 2299775262 Hz D0316 07:47:43.223804 10804 parameters.go:238] Clock(Realtime): error: 272 ns, adjusted frequency from 2299774446 Hz to 2299775159 Hz D0316 07:47:43.223968 10804 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:681118094730418 monotonicBaseRef:18001098019 monotonicFrequency:2299775262 realtimeReady:1 realtimeBaseCycles:681118095338798 realtimeBaseRef:1584344863223795900 realtimeFrequency:2299775159} D0316 07:47:44.223111 10804 parameters.go:238] Clock(Monotonic): error: -254 ns, adjusted frequency from 2299775262 Hz to 2299773986 Hz D0316 07:47:44.223322 10804 parameters.go:238] Clock(Realtime): error: -165 ns, adjusted frequency from 2299775159 Hz to 2299774194 Hz D0316 07:47:44.223476 10804 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:681120393493033 monotonicBaseRef:19000657694 monotonicFrequency:2299773986 realtimeReady:1 realtimeBaseCycles:681120394010864 realtimeBaseRef:1584344864223316247 realtimeFrequency:2299774194} D0316 07:47:45.222753 10804 parameters.go:238] Clock(Monotonic): error: 61 ns, adjusted frequency from 2299773986 Hz to 2299774759 Hz D0316 07:47:45.222986 10804 parameters.go:238] Clock(Realtime): error: -97 ns, adjusted frequency from 2299774194 Hz to 2299774336 Hz D0316 07:47:45.223073 10804 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:681122692446537 monotonicBaseRef:20000300927 monotonicFrequency:2299774759 realtimeReady:1 realtimeBaseCycles:681122693014586 realtimeBaseRef:1584344865222981226 realtimeFrequency:2299774336} D0316 07:47:46.222974 10804 parameters.go:238] Clock(Monotonic): error: 85 ns, adjusted frequency from 2299774759 Hz to 2299774784 Hz D0316 07:47:46.223180 10804 parameters.go:238] Clock(Realtime): error: 305 ns, adjusted frequency from 2299774336 Hz to 2299775327 Hz D0316 07:47:46.223324 10804 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:681124992731081 monotonicBaseRef:21000522594 monotonicFrequency:2299774784 realtimeReady:1 realtimeBaseCycles:681124993231383 realtimeBaseRef:1584344866223173619 realtimeFrequency:2299775327} D0316 07:47:47.222763 10804 parameters.go:238] Clock(Monotonic): error: 37 ns, adjusted frequency from 2299774784 Hz to 2299774655 Hz D0316 07:47:47.222999 10804 parameters.go:238] Clock(Realtime): error: -277 ns, adjusted frequency from 2299775327 Hz to 2299773840 Hz D0316 07:47:47.223156 10804 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:681127292021737 monotonicBaseRef:22000312082 monotonicFrequency:2299774655 realtimeReady:1 realtimeBaseCycles:681127292585391 realtimeBaseRef:1584344867222990418 realtimeFrequency:2299773840} D0316 07:47:48.222749 10804 parameters.go:238] Clock(Monotonic): error: -74 ns, adjusted frequency from 2299774655 Hz to 2299774421 Hz D0316 07:47:48.223024 10804 parameters.go:238] Clock(Realtime): error: 319 ns, adjusted frequency from 2299773840 Hz to 2299775354 Hz D0316 07:47:48.223203 10804 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:681129591722731 monotonicBaseRef:23000280052 monotonicFrequency:2299774421 realtimeReady:1 realtimeBaseCycles:681129592420540 realtimeBaseRef:1584344868223017076 realtimeFrequency:2299775354} D0316 07:47:49.222738 10804 parameters.go:238] Clock(Monotonic): error: -94 ns, adjusted frequency from 2299774421 Hz to 2299774369 Hz D0316 07:47:49.223013 10804 parameters.go:238] Clock(Realtime): error: -230 ns, adjusted frequency from 2299775354 Hz to 2299774094 Hz D0316 07:47:49.223192 10804 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:681131891509310 monotonicBaseRef:24000285338 monotonicFrequency:2299774369 realtimeReady:1 realtimeBaseCycles:681131892173179 realtimeBaseRef:1584344869223007198 realtimeFrequency:2299774094} D0316 07:47:50.222776 10804 parameters.go:238] Clock(Monotonic): error: -114 ns, adjusted frequency from 2299774369 Hz to 2299774312 Hz D0316 07:47:50.223011 10804 parameters.go:238] Clock(Realtime): error: -52 ns, adjusted frequency from 2299774094 Hz to 2299774464 Hz D0316 07:47:50.223103 10804 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:681134191375695 monotonicBaseRef:25000325348 monotonicFrequency:2299774312 realtimeReady:1 realtimeBaseCycles:681134191944425 realtimeBaseRef:1584344870223005959 realtimeFrequency:2299774464} D0316 07:47:51.222912 10804 parameters.go:238] Clock(Monotonic): error: -43 ns, adjusted frequency from 2299774312 Hz to 2299774482 Hz D0316 07:47:51.223119 10804 parameters.go:238] Clock(Realtime): error: -53 ns, adjusted frequency from 2299774464 Hz to 2299774451 Hz D0316 07:47:51.223226 10804 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:681136491400273 monotonicBaseRef:26000434169 monotonicFrequency:2299774482 realtimeReady:1 realtimeBaseCycles:681136491954544 realtimeBaseRef:1584344871223108427 realtimeFrequency:2299774451} D0316 07:47:52.222716 10804 parameters.go:238] Clock(Monotonic): error: 44 ns, adjusted frequency from 2299774482 Hz to 2299774636 Hz D0316 07:47:52.222970 10804 parameters.go:238] Clock(Realtime): error: -56 ns, adjusted frequency from 2299774451 Hz to 2299774427 Hz D0316 07:47:52.223098 10804 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:681138790780859 monotonicBaseRef:27000262893 monotonicFrequency:2299774636 realtimeReady:1 realtimeBaseCycles:681138791399898 realtimeBaseRef:1584344872222965327 realtimeFrequency:2299774427} D0316 07:47:53.223376 10804 parameters.go:238] Clock(Monotonic): error: 58 ns, adjusted frequency from 2299774636 Hz to 2299774666 Hz D0316 07:47:53.223609 10804 parameters.go:238] Clock(Realtime): error: 21 ns, adjusted frequency from 2299774427 Hz to 2299774604 Hz D0316 07:47:53.223765 10804 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:681141092076333 monotonicBaseRef:28000924191 monotonicFrequency:2299774666 realtimeReady:1 realtimeBaseCycles:681141092644458 realtimeBaseRef:1584344873223604577 realtimeFrequency:2299774604} D0316 07:47:54.222598 10804 parameters.go:238] Clock(Monotonic): error: 84 ns, adjusted frequency from 2299774666 Hz to 2299774783 Hz D0316 07:47:54.222879 10804 parameters.go:238] Clock(Realtime): error: 100 ns, adjusted frequency from 2299774604 Hz to 2299774804 Hz D0316 07:47:54.223052 10804 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:681143390062763 monotonicBaseRef:29000146620 monotonicFrequency:2299774783 realtimeReady:1 realtimeBaseCycles:681143390738205 realtimeBaseRef:1584344874222873697 realtimeFrequency:2299774804} D0316 07:47:55.222745 10804 parameters.go:238] Clock(Monotonic): error: 15 ns, adjusted frequency from 2299774783 Hz to 2299774698 Hz D0316 07:47:55.223009 10804 parameters.go:238] Clock(Realtime): error: 274 ns, adjusted frequency from 2299774804 Hz to 2299775166 Hz D0316 07:47:55.223167 10804 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:681145690169700 monotonicBaseRef:30000291048 monotonicFrequency:2299774698 realtimeReady:1 realtimeBaseCycles:681145690806094 realtimeBaseRef:1584344875223001137 realtimeFrequency:2299775166} D0316 07:47:56.222684 10804 parameters.go:238] Clock(Monotonic): error: -141 ns, adjusted frequency from 2299774698 Hz to 2299774223 Hz D0316 07:47:56.222931 10804 parameters.go:238] Clock(Realtime): error: -103 ns, adjusted frequency from 2299775166 Hz to 2299774407 Hz D0316 07:47:56.223057 10804 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:681147989805529 monotonicBaseRef:31000230664 monotonicFrequency:2299774223 realtimeReady:1 realtimeBaseCycles:681147990405968 realtimeBaseRef:1584344876222924915 realtimeFrequency:2299774407} D0316 07:47:57.222623 10804 parameters.go:238] Clock(Monotonic): error: -134 ns, adjusted frequency from 2299774223 Hz to 2299774242 Hz D0316 07:47:57.222829 10804 parameters.go:238] Clock(Realtime): error: -292 ns, adjusted frequency from 2299774407 Hz to 2299773874 Hz D0316 07:47:57.222947 10804 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:681150289445384 monotonicBaseRef:32000172237 monotonicFrequency:2299774242 realtimeReady:1 realtimeBaseCycles:681150289949690 realtimeBaseRef:1584344877222824607 realtimeFrequency:2299773874} D0316 07:47:58.224004 10804 parameters.go:238] Clock(Monotonic): error: 234 ns, adjusted frequency from 2299774242 Hz to 2299775108 Hz D0316 07:47:58.224222 10804 parameters.go:238] Clock(Realtime): error: 222 ns, adjusted frequency from 2299773874 Hz to 2299775104 Hz D0316 07:47:58.224344 10804 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:681152592392223 monotonicBaseRef:33001551762 monotonicFrequency:2299775108 realtimeReady:1 realtimeBaseCycles:681152592925612 realtimeBaseRef:1584344878224216938 realtimeFrequency:2299775104} D0316 07:47:59.222979 10804 parameters.go:238] Clock(Monotonic): error: -92 ns, adjusted frequency from 2299775108 Hz to 2299774359 Hz D0316 07:47:59.223182 10804 parameters.go:238] Clock(Realtime): error: -52 ns, adjusted frequency from 2299775104 Hz to 2299774435 Hz D0316 07:47:59.223300 10804 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:681154889810991 monotonicBaseRef:34000527166 monotonicFrequency:2299774359 realtimeReady:1 realtimeBaseCycles:681154890309386 realtimeBaseRef:1584344879223177127 realtimeFrequency:2299774435} D0316 07:48:00.212928 10804 urpc.go:577] urpc: unmarshal success. D0316 07:48:00.214474 10804 controller.go:504] containerManager.Signal &{CID:ci-gvisor-ptrace-direct-overlay-host-race-0 Signo:0 PID:0 Mode:Process} D0316 07:48:00.214833 10804 urpc.go:534] urpc: successfully marshalled 37 bytes. D0316 07:48:00.223229 10804 parameters.go:238] Clock(Monotonic): error: 611 ns, adjusted frequency from 2299774359 Hz to 2299776060 Hz D0316 07:48:00.223424 10804 parameters.go:238] Clock(Realtime): error: -58 ns, adjusted frequency from 2299774435 Hz to 2299774470 Hz D0316 07:48:00.223512 10804 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:681157190165446 monotonicBaseRef:35000779406 monotonicFrequency:2299776060 realtimeReady:1 realtimeBaseCycles:681157190639781 realtimeBaseRef:1584344880223418872 realtimeFrequency:2299774470} D0316 07:48:01.223695 10804 parameters.go:238] Clock(Monotonic): error: -685 ns, adjusted frequency from 2299776060 Hz to 2299773011 Hz D0316 07:48:01.223916 10804 parameters.go:238] Clock(Realtime): error: -156 ns, adjusted frequency from 2299774470 Hz to 2299774263 Hz D0316 07:48:01.224023 10804 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:681159488730012 monotonicBaseRef:36000252618 monotonicFrequency:2299773011 realtimeReady:1 realtimeBaseCycles:681159491538501 realtimeBaseRef:1584344881223907724 realtimeFrequency:2299774263} D0316 07:48:02.222704 10804 parameters.go:238] Clock(Monotonic): error: -17 ns, adjusted frequency from 2299773011 Hz to 2299774577 Hz D0316 07:48:02.222975 10804 parameters.go:238] Clock(Realtime): error: 210 ns, adjusted frequency from 2299774263 Hz to 2299775085 Hz D0316 07:48:02.223146 10804 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:681161788503045 monotonicBaseRef:37000252627 monotonicFrequency:2299774577 realtimeReady:1 realtimeBaseCycles:681161789153584 realtimeBaseRef:1584344882222968857 realtimeFrequency:2299775085} D0316 07:48:03.224070 10804 parameters.go:238] Clock(Monotonic): error: -5 ns, adjusted frequency from 2299774577 Hz to 2299774545 Hz D0316 07:48:03.224264 10804 parameters.go:238] Clock(Realtime): error: -134 ns, adjusted frequency from 2299775085 Hz to 2299774263 Hz D0316 07:48:03.224384 10804 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:681164091406499 monotonicBaseRef:38001613141 monotonicFrequency:2299774545 realtimeReady:1 realtimeBaseCycles:681164091894812 realtimeBaseRef:1584344883224258610 realtimeFrequency:2299774263} D0316 07:48:04.223187 10804 parameters.go:238] Clock(Monotonic): error: -105 ns, adjusted frequency from 2299774545 Hz to 2299774305 Hz D0316 07:48:04.223388 10804 parameters.go:238] Clock(Realtime): error: -71 ns, adjusted frequency from 2299774263 Hz to 2299774402 Hz D0316 07:48:04.223946 10804 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:681166389140922 monotonicBaseRef:39000726044 monotonicFrequency:2299774305 realtimeReady:1 realtimeBaseCycles:681166389653974 realtimeBaseRef:1584344884223382393 realtimeFrequency:2299774402} D0316 07:48:05.222794 10804 parameters.go:238] Clock(Monotonic): error: 148 ns, adjusted frequency from 2299774305 Hz to 2299774915 Hz D0316 07:48:05.223056 10804 parameters.go:238] Clock(Realtime): error: 63 ns, adjusted frequency from 2299774402 Hz to 2299774698 Hz D0316 07:48:05.223157 10804 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:681168688000806 monotonicBaseRef:40000328430 monotonicFrequency:2299774915 realtimeReady:1 realtimeBaseCycles:681168688664778 realtimeBaseRef:1584344885223050361 realtimeFrequency:2299774698} D0316 07:48:06.223363 10804 parameters.go:238] Clock(Monotonic): error: 155 ns, adjusted frequency from 2299774915 Hz to 2299774951 Hz D0316 07:48:06.223619 10804 parameters.go:238] Clock(Realtime): error: 207 ns, adjusted frequency from 2299774698 Hz to 2299775058 Hz D0316 07:48:06.223806 10804 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:681170989111586 monotonicBaseRef:41000909297 monotonicFrequency:2299774951 realtimeReady:1 realtimeBaseCycles:681170989729349 realtimeBaseRef:1584344886223611230 realtimeFrequency:2299775058} D0316 07:48:07.222733 10804 parameters.go:238] Clock(Monotonic): error: -190 ns, adjusted frequency from 2299774951 Hz to 2299774129 Hz D0316 07:48:07.222960 10804 parameters.go:238] Clock(Realtime): error: -43 ns, adjusted frequency from 2299775058 Hz to 2299774453 Hz D0316 07:48:07.223120 10804 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:681173287438792 monotonicBaseRef:42000279781 monotonicFrequency:2299774129 realtimeReady:1 realtimeBaseCycles:681173287978781 realtimeBaseRef:1584344887222947849 realtimeFrequency:2299774453} D0316 07:48:08.224864 10804 parameters.go:238] Clock(Monotonic): error: 60 ns, adjusted frequency from 2299774129 Hz to 2299774714 Hz D0316 07:48:08.225164 10804 parameters.go:238] Clock(Realtime): error: -51 ns, adjusted frequency from 2299774453 Hz to 2299774444 Hz D0316 07:48:08.225326 10804 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:681175592092404 monotonicBaseRef:43002401503 monotonicFrequency:2299774714 realtimeReady:1 realtimeBaseCycles:681175592832451 realtimeBaseRef:1584344888225156420 realtimeFrequency:2299774444} D0316 07:48:09.223262 10804 parameters.go:238] Clock(Monotonic): error: -52 ns, adjusted frequency from 2299774714 Hz to 2299774377 Hz D0316 07:48:09.223582 10804 parameters.go:238] Clock(Realtime): error: -306 ns, adjusted frequency from 2299774444 Hz to 2299773891 Hz D0316 07:48:09.223706 10804 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:681177888177518 monotonicBaseRef:44000797171 monotonicFrequency:2299774377 realtimeReady:1 realtimeBaseCycles:681177888971918 realtimeBaseRef:1584344889223575840 realtimeFrequency:2299773891} D0316 07:48:10.223165 10804 parameters.go:238] Clock(Monotonic): error: 16 ns, adjusted frequency from 2299774377 Hz to 2299774611 Hz D0316 07:48:10.223389 10804 parameters.go:238] Clock(Realtime): error: 211 ns, adjusted frequency from 2299773891 Hz to 2299775029 Hz D0316 07:48:10.223485 10804 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:681180187754461 monotonicBaseRef:45000711321 monotonicFrequency:2299774611 realtimeReady:1 realtimeBaseCycles:681180188304222 realtimeBaseRef:1584344890223383826 realtimeFrequency:2299775029} I0316 07:48:10.421783 10804 watchdog.go:277] Watchdog starting loop, tasks: 14, discount: 0s D0316 07:48:11.222744 10804 parameters.go:238] Clock(Monotonic): error: 29 ns, adjusted frequency from 2299774611 Hz to 2299774643 Hz D0316 07:48:11.222972 10804 parameters.go:238] Clock(Realtime): error: 65 ns, adjusted frequency from 2299775029 Hz to 2299774700 Hz D0316 07:48:11.223075 10804 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:681182486436260 monotonicBaseRef:46000236138 monotonicFrequency:2299774643 realtimeReady:1 realtimeBaseCycles:681182487114114 realtimeBaseRef:1584344891222964160 realtimeFrequency:2299774700} D0316 07:48:12.222701 10804 parameters.go:238] Clock(Monotonic): error: 290 ns, adjusted frequency from 2299774643 Hz to 2299775274 Hz D0316 07:48:12.223174 10804 parameters.go:238] Clock(Realtime): error: -133 ns, adjusted frequency from 2299774700 Hz to 2299774303 Hz D0316 07:48:12.223325 10804 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:681184786161996 monotonicBaseRef:47000214872 monotonicFrequency:2299775274 realtimeReady:1 realtimeBaseCycles:681184787351048 realtimeBaseRef:1584344892223165150 realtimeFrequency:2299774303} D0316 07:48:13.222735 10804 parameters.go:238] Clock(Monotonic): error: -90 ns, adjusted frequency from 2299775274 Hz to 2299774419 Hz D0316 07:48:13.222981 10804 parameters.go:238] Clock(Realtime): error: 302 ns, adjusted frequency from 2299774303 Hz to 2299775284 Hz D0316 07:48:13.223140 10804 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:681187086095765 monotonicBaseRef:48000283789 monotonicFrequency:2299774419 realtimeReady:1 realtimeBaseCycles:681187086686579 realtimeBaseRef:1584344893222974360 realtimeFrequency:2299775284} D0316 07:48:14.222839 10804 parameters.go:238] Clock(Monotonic): error: -128 ns, adjusted frequency from 2299774419 Hz to 2299774289 Hz D0316 07:48:14.223071 10804 parameters.go:238] Clock(Realtime): error: -85 ns, adjusted frequency from 2299775284 Hz to 2299774392 Hz D0316 07:48:14.223231 10804 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:681189386109283 monotonicBaseRef:49000387755 monotonicFrequency:2299774289 realtimeReady:1 realtimeBaseCycles:681189386666189 realtimeBaseRef:1584344894223063206 realtimeFrequency:2299774392} D0316 07:48:14.858709 10804 urpc.go:577] urpc: unmarshal success. D0316 07:48:14.859008 10804 controller.go:504] containerManager.Signal &{CID:ci-gvisor-ptrace-direct-overlay-host-race-0 Signo:0 PID:0 Mode:Process} D0316 07:48:14.859282 10804 urpc.go:534] urpc: successfully marshalled 37 bytes. D0316 07:48:15.222650 10804 parameters.go:238] Clock(Monotonic): error: 275 ns, adjusted frequency from 2299774289 Hz to 2299775137 Hz D0316 07:48:15.222880 10804 parameters.go:238] Clock(Realtime): error: -31 ns, adjusted frequency from 2299774392 Hz to 2299774498 Hz D0316 07:48:15.222991 10804 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:681191685435727 monotonicBaseRef:50000193020 monotonicFrequency:2299775137 realtimeReady:1 realtimeBaseCycles:681191686005016 realtimeBaseRef:1584344895222873811 realtimeFrequency:2299774498} D0316 07:48:16.222753 10804 parameters.go:238] Clock(Monotonic): error: -119 ns, adjusted frequency from 2299775137 Hz to 2299774195 Hz D0316 07:48:16.222926 10804 parameters.go:238] Clock(Realtime): error: -225 ns, adjusted frequency from 2299774498 Hz to 2299774060 Hz D0316 07:48:16.223060 10804 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:681193985460590 monotonicBaseRef:51000301607 monotonicFrequency:2299774195 realtimeReady:1 realtimeBaseCycles:681193985889737 realtimeBaseRef:1584344896222921738 realtimeFrequency:2299774060} D0316 07:48:17.222773 10804 parameters.go:238] Clock(Monotonic): error: -191 ns, adjusted frequency from 2299774195 Hz to 2299774130 Hz D0316 07:48:17.223011 10804 parameters.go:238] Clock(Realtime): error: 227 ns, adjusted frequency from 2299774060 Hz to 2299775119 Hz D0316 07:48:17.223192 10804 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:681196285277896 monotonicBaseRef:52000320352 monotonicFrequency:2299774130 realtimeReady:1 realtimeBaseCycles:681196285855848 realtimeBaseRef:1584344897223005246 realtimeFrequency:2299775119} D0316 07:48:18.222964 10804 parameters.go:238] Clock(Monotonic): error: -37 ns, adjusted frequency from 2299774130 Hz to 2299774481 Hz D0316 07:48:18.223166 10804 parameters.go:238] Clock(Realtime): error: -203 ns, adjusted frequency from 2299775119 Hz to 2299774060 Hz D0316 07:48:18.223289 10804 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:681198585456620 monotonicBaseRef:53000496279 monotonicFrequency:2299774481 realtimeReady:1 realtimeBaseCycles:681198585988234 realtimeBaseRef:1584344898223160594 realtimeFrequency:2299774060} D0316 07:48:19.223858 10804 parameters.go:238] Clock(Monotonic): error: 97 ns, adjusted frequency from 2299774481 Hz to 2299774796 Hz D0316 07:48:19.224123 10804 parameters.go:238] Clock(Realtime): error: 430 ns, adjusted frequency from 2299774060 Hz to 2299775582 Hz D0316 07:48:19.224287 10804 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:681200887324307 monotonicBaseRef:54001406457 monotonicFrequency:2299774796 realtimeReady:1 realtimeBaseCycles:681200887960684 realtimeBaseRef:1584344899224116509 realtimeFrequency:2299775582} D0316 07:48:20.222714 10804 parameters.go:238] Clock(Monotonic): error: 154 ns, adjusted frequency from 2299774796 Hz to 2299774808 Hz D0316 07:48:20.222941 10804 parameters.go:238] Clock(Realtime): error: -229 ns, adjusted frequency from 2299775582 Hz to 2299774061 Hz D0316 07:48:20.223072 10804 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:681203184458590 monotonicBaseRef:55000258295 monotonicFrequency:2299774808 realtimeReady:1 realtimeBaseCycles:681203185017308 realtimeBaseRef:1584344900222934237 realtimeFrequency:2299774061} D0316 07:48:21.223678 10804 parameters.go:238] Clock(Monotonic): error: 22 ns, adjusted frequency from 2299774808 Hz to 2299774612 Hz D0316 07:48:21.223974 10804 parameters.go:238] Clock(Realtime): error: -129 ns, adjusted frequency from 2299774061 Hz to 2299774244 Hz D0316 07:48:21.224131 10804 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:681205486452428 monotonicBaseRef:56001223185 monotonicFrequency:2299774612 realtimeReady:1 realtimeBaseCycles:681205487167379 realtimeBaseRef:1584344901223967386 realtimeFrequency:2299774244} D0316 07:48:22.223585 10804 parameters.go:238] Clock(Monotonic): error: -82 ns, adjusted frequency from 2299774612 Hz to 2299774370 Hz D0316 07:48:22.225135 10804 parameters.go:238] Clock(Realtime): error: 103 ns, adjusted frequency from 2299774244 Hz to 2299774779 Hz D0316 07:48:22.225225 10804 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:681207785989007 monotonicBaseRef:57001119682 monotonicFrequency:2299774370 realtimeReady:1 realtimeBaseCycles:681207789612217 realtimeBaseRef:1584344902225128627 realtimeFrequency:2299774779} D0316 07:48:23.222884 10804 parameters.go:238] Clock(Monotonic): error: 78 ns, adjusted frequency from 2299774370 Hz to 2299774753 Hz D0316 07:48:23.223145 10804 parameters.go:238] Clock(Realtime): error: -39 ns, adjusted frequency from 2299774779 Hz to 2299774474 Hz D0316 07:48:23.223307 10804 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:681210084183192 monotonicBaseRef:58000432577 monotonicFrequency:2299774753 realtimeReady:1 realtimeBaseCycles:681210084811881 realtimeBaseRef:1584344903223139251 realtimeFrequency:2299774474} D0316 07:48:24.222711 10804 parameters.go:238] Clock(Monotonic): error: -80 ns, adjusted frequency from 2299774753 Hz to 2299774455 Hz D0316 07:48:24.222924 10804 parameters.go:238] Clock(Realtime): error: 204 ns, adjusted frequency from 2299774474 Hz to 2299775072 Hz D0316 07:48:24.223041 10804 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:681212383561728 monotonicBaseRef:59000260291 monotonicFrequency:2299774455 realtimeReady:1 realtimeBaseCycles:681212384079104 realtimeBaseRef:1584344904222918685 realtimeFrequency:2299775072} D0316 07:48:25.222729 10804 parameters.go:238] Clock(Monotonic): error: 50 ns, adjusted frequency from 2299774455 Hz to 2299774805 Hz D0316 07:48:25.222997 10804 parameters.go:238] Clock(Realtime): error: -50 ns, adjusted frequency from 2299775072 Hz to 2299774442 Hz D0316 07:48:25.223114 10804 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:681214683374809 monotonicBaseRef:60000277086 monotonicFrequency:2299774805 realtimeReady:1 realtimeBaseCycles:681214684017056 realtimeBaseRef:1584344905222989509 realtimeFrequency:2299774442} D0316 07:48:26.222697 10804 parameters.go:238] Clock(Monotonic): error: 215 ns, adjusted frequency from 2299774805 Hz to 2299775076 Hz D0316 07:48:26.222982 10804 parameters.go:238] Clock(Realtime): error: -94 ns, adjusted frequency from 2299774442 Hz to 2299774328 Hz D0316 07:48:26.223106 10804 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:681216983066504 monotonicBaseRef:61000240947 monotonicFrequency:2299775076 realtimeReady:1 realtimeBaseCycles:681216983759679 realtimeBaseRef:1584344906222975673 realtimeFrequency:2299774328} D0316 07:48:27.223267 10804 parameters.go:238] Clock(Monotonic): error: -97 ns, adjusted frequency from 2299775076 Hz to 2299774391 Hz D0316 07:48:27.223515 10804 parameters.go:238] Clock(Realtime): error: 283 ns, adjusted frequency from 2299774328 Hz to 2299775231 Hz D0316 07:48:27.223604 10804 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:681219284156873 monotonicBaseRef:62000812869 monotonicFrequency:2299774391 realtimeReady:1 realtimeBaseCycles:681219284760154 realtimeBaseRef:1584344907223508832 realtimeFrequency:2299775231} D0316 07:48:28.222838 10804 parameters.go:238] Clock(Monotonic): error: -323 ns, adjusted frequency from 2299774391 Hz to 2299773821 Hz D0316 07:48:28.223067 10804 parameters.go:238] Clock(Realtime): error: -358 ns, adjusted frequency from 2299775231 Hz to 2299773708 Hz D0316 07:48:28.223172 10804 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:681221582905156 monotonicBaseRef:63000366691 monotonicFrequency:2299773821 realtimeReady:1 realtimeBaseCycles:681221583508548 realtimeBaseRef:1584344908223062337 realtimeFrequency:2299773708} D0316 07:48:29.224308 10804 parameters.go:238] Clock(Monotonic): error: -3 ns, adjusted frequency from 2299773821 Hz to 2299774668 Hz D0316 07:48:29.224591 10804 parameters.go:238] Clock(Realtime): error: 37 ns, adjusted frequency from 2299773708 Hz to 2299774640 Hz D0316 07:48:29.224729 10804 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:681223886105872 monotonicBaseRef:64001856791 monotonicFrequency:2299774668 realtimeReady:1 realtimeBaseCycles:681223886784067 realtimeBaseRef:1584344909224585013 realtimeFrequency:2299774640} D0316 07:48:30.223654 10804 parameters.go:238] Clock(Monotonic): error: 34 ns, adjusted frequency from 2299774668 Hz to 2299774637 Hz D0316 07:48:30.223940 10804 parameters.go:238] Clock(Realtime): error: 386 ns, adjusted frequency from 2299774640 Hz to 2299775430 Hz D0316 07:48:30.224078 10804 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:681226184373245 monotonicBaseRef:65001201381 monotonicFrequency:2299774637 realtimeReady:1 realtimeBaseCycles:681226185055811 realtimeBaseRef:1584344910223931515 realtimeFrequency:2299775430} D0316 07:48:31.223551 10804 parameters.go:238] Clock(Monotonic): error: -109 ns, adjusted frequency from 2299774637 Hz to 2299774312 Hz D0316 07:48:31.223807 10804 parameters.go:238] Clock(Realtime): error: -403 ns, adjusted frequency from 2299775430 Hz to 2299773664 Hz D0316 07:48:31.223896 10804 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:681228483905188 monotonicBaseRef:66001095851 monotonicFrequency:2299774312 realtimeReady:1 realtimeBaseCycles:681228484529926 realtimeBaseRef:1584344911223800495 realtimeFrequency:2299773664} D0316 07:48:32.222740 10804 parameters.go:238] Clock(Monotonic): error: 30 ns, adjusted frequency from 2299774312 Hz to 2299774636 Hz D0316 07:48:32.222982 10804 parameters.go:238] Clock(Realtime): error: 62 ns, adjusted frequency from 2299773664 Hz to 2299774658 Hz D0316 07:48:32.223107 10804 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:681230781821496 monotonicBaseRef:67000287943 monotonicFrequency:2299774636 realtimeReady:1 realtimeBaseCycles:681230782397187 realtimeBaseRef:1584344912222971542 realtimeFrequency:2299774658} D0316 07:48:33.222757 10804 parameters.go:238] Clock(Monotonic): error: 60 ns, adjusted frequency from 2299774636 Hz to 2299774677 Hz D0316 07:48:33.223036 10804 parameters.go:238] Clock(Realtime): error: 73 ns, adjusted frequency from 2299774658 Hz to 2299774690 Hz D0316 07:48:33.223214 10804 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:681233081627514 monotonicBaseRef:68000301588 monotonicFrequency:2299774677 realtimeReady:1 realtimeBaseCycles:681233082305651 realtimeBaseRef:1584344913223029724 realtimeFrequency:2299774690} D0316 07:48:34.223383 10804 parameters.go:238] Clock(Monotonic): error: 145 ns, adjusted frequency from 2299774677 Hz to 2299774905 Hz D0316 07:48:34.223656 10804 parameters.go:238] Clock(Realtime): error: 210 ns, adjusted frequency from 2299774690 Hz to 2299775027 Hz D0316 07:48:34.223815 10804 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:681235382846963 monotonicBaseRef:69000929811 monotonicFrequency:2299774905 realtimeReady:1 realtimeBaseCycles:681235383504885 realtimeBaseRef:1584344914223649151 realtimeFrequency:2299775027} D0316 07:48:35.222742 10804 parameters.go:238] Clock(Monotonic): error: -56 ns, adjusted frequency from 2299774905 Hz to 2299774429 Hz D0316 07:48:35.223014 10804 parameters.go:238] Clock(Realtime): error: 0 ns, adjusted frequency from 2299775027 Hz to 2299774612 Hz D0316 07:48:35.223169 10804 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:681237681127865 monotonicBaseRef:70000280180 monotonicFrequency:2299774429 realtimeReady:1 realtimeBaseCycles:681237681800653 realtimeBaseRef:1584344915223005931 realtimeFrequency:2299774612} D0316 07:48:36.222924 10804 parameters.go:238] Clock(Monotonic): error: 90 ns, adjusted frequency from 2299774429 Hz to 2299774652 Hz D0316 07:48:36.223171 10804 parameters.go:238] Clock(Realtime): error: -261 ns, adjusted frequency from 2299774612 Hz to 2299774019 Hz D0316 07:48:36.223320 10804 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:681239981350375 monotonicBaseRef:71000475016 monotonicFrequency:2299774652 realtimeReady:1 realtimeBaseCycles:681239981934865 realtimeBaseRef:1584344916223162294 realtimeFrequency:2299774019} D0316 07:48:37.222700 10804 parameters.go:238] Clock(Monotonic): error: -210 ns, adjusted frequency from 2299774652 Hz to 2299774095 Hz D0316 07:48:37.222938 10804 parameters.go:238] Clock(Realtime): error: 23 ns, adjusted frequency from 2299774019 Hz to 2299774639 Hz D0316 07:48:37.223069 10804 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:681242280605009 monotonicBaseRef:72000248899 monotonicFrequency:2299774095 realtimeReady:1 realtimeBaseCycles:681242281179279 realtimeBaseRef:1584344917222932008 realtimeFrequency:2299774639} D0316 07:48:38.223287 10804 parameters.go:238] Clock(Monotonic): error: 108 ns, adjusted frequency from 2299774095 Hz to 2299774835 Hz D0316 07:48:38.223560 10804 parameters.go:238] Clock(Realtime): error: 206 ns, adjusted frequency from 2299774639 Hz to 2299775050 Hz D0316 07:48:38.223686 10804 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:681244581728957 monotonicBaseRef:73000835849 monotonicFrequency:2299774835 realtimeReady:1 realtimeBaseCycles:681244582380943 realtimeBaseRef:1584344918223552514 realtimeFrequency:2299775050} D0316 07:48:39.223629 10804 parameters.go:238] Clock(Monotonic): error: -21 ns, adjusted frequency from 2299774835 Hz to 2299774522 Hz D0316 07:48:39.223944 10804 parameters.go:238] Clock(Realtime): error: -137 ns, adjusted frequency from 2299775050 Hz to 2299774269 Hz D0316 07:48:39.224090 10804 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:681246882289983 monotonicBaseRef:74001177704 monotonicFrequency:2299774522 realtimeReady:1 realtimeBaseCycles:681246883042409 realtimeBaseRef:1584344919223937949 realtimeFrequency:2299774269} D0316 07:48:40.222763 10804 parameters.go:238] Clock(Monotonic): error: -80 ns, adjusted frequency from 2299774522 Hz to 2299774389 Hz D0316 07:48:40.223067 10804 parameters.go:238] Clock(Realtime): error: -129 ns, adjusted frequency from 2299774269 Hz to 2299774260 Hz D0316 07:48:40.223162 10804 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:681249180061899 monotonicBaseRef:75000306920 monotonicFrequency:2299774389 realtimeReady:1 realtimeBaseCycles:681249180799066 realtimeBaseRef:1584344920223060640 realtimeFrequency:2299774260} D0316 07:48:41.222658 10804 parameters.go:238] Clock(Monotonic): error: 261 ns, adjusted frequency from 2299774389 Hz to 2299775187 Hz D0316 07:48:41.222925 10804 parameters.go:238] Clock(Realtime): error: 441 ns, adjusted frequency from 2299774260 Hz to 2299775684 Hz D0316 07:48:41.223086 10804 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:681251479586986 monotonicBaseRef:76000198517 monotonicFrequency:2299775187 realtimeReady:1 realtimeBaseCycles:681251480241359 realtimeBaseRef:1584344921222916292 realtimeFrequency:2299775684} D0316 07:48:42.223168 10804 parameters.go:238] Clock(Monotonic): error: -227 ns, adjusted frequency from 2299775187 Hz to 2299774065 Hz D0316 07:48:42.223436 10804 parameters.go:238] Clock(Realtime): error: -363 ns, adjusted frequency from 2299775684 Hz to 2299773741 Hz D0316 07:48:42.223581 10804 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:681253780553860 monotonicBaseRef:77000716692 monotonicFrequency:2299774065 realtimeReady:1 realtimeBaseCycles:681253781193774 realtimeBaseRef:1584344922223427964 realtimeFrequency:2299773741} D0316 07:48:43.222711 10804 parameters.go:238] Clock(Monotonic): error: 63 ns, adjusted frequency from 2299774065 Hz to 2299774711 Hz D0316 07:48:43.222928 10804 parameters.go:238] Clock(Realtime): error: -123 ns, adjusted frequency from 2299773741 Hz to 2299774328 Hz D0316 07:48:43.223070 10804 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:681256079242636 monotonicBaseRef:78000244780 monotonicFrequency:2299774711 realtimeReady:1 realtimeBaseCycles:681256079804989 realtimeBaseRef:1584344923222922468 realtimeFrequency:2299774328} D0316 07:48:44.223140 10804 parameters.go:238] Clock(Monotonic): error: -92 ns, adjusted frequency from 2299774711 Hz to 2299774368 Hz D0316 07:48:44.223393 10804 parameters.go:238] Clock(Realtime): error: -46 ns, adjusted frequency from 2299774328 Hz to 2299774467 Hz D0316 07:48:44.223519 10804 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:681258380028833 monotonicBaseRef:79000684599 monotonicFrequency:2299774368 realtimeReady:1 realtimeBaseCycles:681258380649822 realtimeBaseRef:1584344924223387950 realtimeFrequency:2299774467} D0316 07:48:45.223464 10804 parameters.go:238] Clock(Monotonic): error: 1 ns, adjusted frequency from 2299774368 Hz to 2299774667 Hz D0316 07:48:45.223707 10804 parameters.go:238] Clock(Realtime): error: 47 ns, adjusted frequency from 2299774467 Hz to 2299774651 Hz D0316 07:48:45.223843 10804 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:681260680559731 monotonicBaseRef:80001013557 monotonicFrequency:2299774667 realtimeReady:1 realtimeBaseCycles:681260681144948 realtimeBaseRef:1584344925223701310 realtimeFrequency:2299774651} D0316 07:48:46.223341 10804 parameters.go:238] Clock(Monotonic): error: 327 ns, adjusted frequency from 2299774667 Hz to 2299775272 Hz D0316 07:48:46.223521 10804 parameters.go:238] Clock(Realtime): error: 22 ns, adjusted frequency from 2299774651 Hz to 2299774609 Hz D0316 07:48:46.223662 10804 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:681262980027264 monotonicBaseRef:81000880007 monotonicFrequency:2299775272 realtimeReady:1 realtimeBaseCycles:681262980489553 realtimeBaseRef:1584344926223514315 realtimeFrequency:2299774609} D0316 07:48:47.222729 10804 parameters.go:238] Clock(Monotonic): error: -7 ns, adjusted frequency from 2299775272 Hz to 2299774598 Hz D0316 07:48:47.222989 10804 parameters.go:238] Clock(Realtime): error: 33 ns, adjusted frequency from 2299774609 Hz to 2299774628 Hz D0316 07:48:47.223124 10804 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:681265278416928 monotonicBaseRef:82000277509 monotonicFrequency:2299774598 realtimeReady:1 realtimeBaseCycles:681265279043444 realtimeBaseRef:1584344927222983516 realtimeFrequency:2299774628} D0316 07:48:48.223026 10804 parameters.go:238] Clock(Monotonic): error: 209 ns, adjusted frequency from 2299774598 Hz to 2299775119 Hz D0316 07:48:48.223383 10804 parameters.go:238] Clock(Realtime): error: 197 ns, adjusted frequency from 2299774628 Hz to 2299775032 Hz D0316 07:48:48.223545 10804 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:681267578856927 monotonicBaseRef:83000566842 monotonicFrequency:2299775119 realtimeReady:1 realtimeBaseCycles:681267579719688 realtimeBaseRef:1584344928223375561 realtimeFrequency:2299775032} D0316 07:48:49.222725 10804 parameters.go:238] Clock(Monotonic): error: -449 ns, adjusted frequency from 2299775119 Hz to 2299773541 Hz D0316 07:48:49.222974 10804 parameters.go:238] Clock(Realtime): error: 214 ns, adjusted frequency from 2299775032 Hz to 2299775065 Hz D0316 07:48:49.223147 10804 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:681269877943264 monotonicBaseRef:84000267342 monotonicFrequency:2299773541 realtimeReady:1 realtimeBaseCycles:681269878555314 realtimeBaseRef:1584344929222967083 realtimeFrequency:2299775065} I0316 07:48:49.348299 11902 main.go:305] *************************** I0316 07:48:49.348480 11902 main.go:306] Args: [/syzkaller/managers/ptrace-direct-overlay-host-race/current/image -root /syzkaller/managers/ptrace-direct-overlay-host-race/workdir/gvisor_root -watchdog-action=panic -network=none -debug -alsologtostderr -platform=ptrace -file-access=exclusive -overlay -network=host -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-direct-overlay-host-race-0 /syz-execprog -executor=/syz-executor -arch=amd64 -sandbox=none -procs=1 -repeat=1 -threaded=false -collide=false -cover=0 -fault_call=-1 -fault_nth=-1 /syzkaller605481713] I0316 07:48:49.349128 11902 main.go:307] Version release-20200219.0-178-g97127750289b I0316 07:48:49.349246 11902 main.go:308] PID: 11902 I0316 07:48:49.349411 11902 main.go:309] UID: 0, GID: 0 I0316 07:48:49.349534 11902 main.go:310] Configuration: I0316 07:48:49.349633 11902 main.go:311] RootDir: /syzkaller/managers/ptrace-direct-overlay-host-race/workdir/gvisor_root I0316 07:48:49.349840 11902 main.go:312] Platform: ptrace I0316 07:48:49.349946 11902 main.go:313] FileAccess: exclusive, overlay: true I0316 07:48:49.350075 11902 main.go:314] Network: host, logging: false I0316 07:48:49.350212 11902 main.go:315] Strace: false, max size: 1024, syscalls: [] I0316 07:48:49.350361 11902 main.go:316] *************************** W0316 07:48:49.350464 11902 main.go:321] Block the TERM signal. This is only safe in tests! D0316 07:48:49.350897 11902 container.go:159] Load container "/syzkaller/managers/ptrace-direct-overlay-host-race/workdir/gvisor_root" "ci-gvisor-ptrace-direct-overlay-host-race-0" D0316 07:48:49.359678 11902 container.go:592] Signal container "ci-gvisor-ptrace-direct-overlay-host-race-0": signal 0 D0316 07:48:49.359868 11902 sandbox.go:814] Signal sandbox "ci-gvisor-ptrace-direct-overlay-host-race-0" D0316 07:48:49.359973 11902 sandbox.go:318] Connecting to sandbox "ci-gvisor-ptrace-direct-overlay-host-race-0" D0316 07:48:49.360416 11902 urpc.go:534] urpc: successfully marshalled 123 bytes. D0316 07:48:49.360864 10804 urpc.go:577] urpc: unmarshal success. D0316 07:48:49.361192 10804 controller.go:504] containerManager.Signal &{CID:ci-gvisor-ptrace-direct-overlay-host-race-0 Signo:0 PID:0 Mode:Process} D0316 07:48:49.361610 10804 urpc.go:534] urpc: successfully marshalled 37 bytes. D0316 07:48:49.361881 11902 urpc.go:577] urpc: unmarshal success. D0316 07:48:49.362059 11902 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 /syzkaller605481713 D0316 07:48:49.362265 11902 exec.go:121] Exec capablities: &{PermittedCaps:274877898751 InheritableCaps:274877898751 EffectiveCaps:274877898751 BoundingCaps:274877898751 AmbientCaps:0} D0316 07:48:49.363070 11902 container.go:533] Execute in container "ci-gvisor-ptrace-direct-overlay-host-race-0", args: /syz-execprog -executor=/syz-executor -arch=amd64 -sandbox=none -procs=1 -repeat=1 -threaded=false -collide=false -cover=0 -fault_call=-1 -fault_nth=-1 /syzkaller605481713 D0316 07:48:49.363336 11902 sandbox.go:283] Executing new process in container "ci-gvisor-ptrace-direct-overlay-host-race-0" in sandbox "ci-gvisor-ptrace-direct-overlay-host-race-0" D0316 07:48:49.363513 11902 sandbox.go:318] Connecting to sandbox "ci-gvisor-ptrace-direct-overlay-host-race-0" D0316 07:48:49.365844 11902 urpc.go:534] urpc: successfully marshalled 642 bytes. D0316 07:48:49.366280 10804 urpc.go:577] urpc: unmarshal success. D0316 07:48:49.367654 10804 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 /syzkaller605481713 I0316 07:48:49.368241 10804 kernel.go:807] EXEC: [/syz-execprog -executor=/syz-executor -arch=amd64 -sandbox=none -procs=1 -repeat=1 -threaded=false -collide=false -cover=0 -fault_call=-1 -fault_nth=-1 /syzkaller605481713] D0316 07:48:49.370190 10804 transport_flipcall.go:127] send [channel @0xc0002923c0] Twalkgetattr{FID: 1, NewFID: 5, Names: [syz-execprog]} D0316 07:48:49.373797 10804 transport_flipcall.go:234] recv [channel @0xc0002923c0] 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: 1584344846, NanoSec: 755994946}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}, QIDs: [QID{Type: 0, Version: 0, Path: 16384794}]} D0316 07:48:49.374107 10804 transport_flipcall.go:127] send [channel @0xc0002923c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syz-execprog, Size: 1} D0316 07:48:49.376902 10804 transport_flipcall.go:234] recv [channel @0xc0002923c0] Rlerror{Error: 95} D0316 07:48:49.377379 10804 transport_flipcall.go:127] send [channel @0xc0002923c0] Twalk{FID: 5, NewFID: 6, Names: []} D0316 07:48:49.378080 10804 transport_flipcall.go:234] recv [channel @0xc0002923c0] Rwalk{QIDs: []} D0316 07:48:49.378296 10804 transport_flipcall.go:127] send [channel @0xc0002923c0] Tlopen{FID: 6, Flags: ReadOnly} D0316 07:48:49.379226 10804 transport_flipcall.go:234] recv [channel @0xc0002923c0] Rlopen{QID: QID{Type: 0, Version: 0, Path: 16384794}, IoUnit: 0, File: &{{39}}} D0316 07:48:49.384511 10804 syscalls.go:266] Allocating stack with size of 8388608 bytes D0316 07:48:49.386245 10804 loader.go:832] updated processes: map[{ci-gvisor-ptrace-direct-overlay-host-race-0 0}:0xc000322200 {ci-gvisor-ptrace-direct-overlay-host-race-0 20}:0xc000323680] D0316 07:48:49.388697 10804 urpc.go:534] urpc: successfully marshalled 37 bytes. D0316 07:48:49.389733 11902 urpc.go:577] urpc: unmarshal success. D0316 07:48:49.389963 11902 container.go:580] Wait on PID 20 in container "ci-gvisor-ptrace-direct-overlay-host-race-0" D0316 07:48:49.390106 11902 sandbox.go:769] Waiting for PID 20 in sandbox "ci-gvisor-ptrace-direct-overlay-host-race-0" D0316 07:48:49.390237 11902 sandbox.go:318] Connecting to sandbox "ci-gvisor-ptrace-direct-overlay-host-race-0" D0316 07:48:49.390532 11902 urpc.go:534] urpc: successfully marshalled 106 bytes. D0316 07:48:49.390836 10804 urpc.go:577] urpc: unmarshal success. D0316 07:48:49.391465 10804 controller.go:446] containerManager.Wait D0316 07:48:49.408882 10804 task_block.go:223] [ 20] Interrupt queued D0316 07:48:49.678286 10804 task_block.go:223] [ 21] Interrupt queued D0316 07:48:49.680900 10804 task_block.go:223] [ 22] Interrupt queued D0316 07:48:49.682046 10804 task_block.go:223] [ 23] Interrupt queued D0316 07:48:49.697205 10804 task_block.go:223] [ 24] Interrupt queued D0316 07:48:49.740358 10804 urpc.go:577] urpc: unmarshal success. D0316 07:48:49.740665 10804 controller.go:504] containerManager.Signal &{CID:ci-gvisor-ptrace-direct-overlay-host-race-0 Signo:0 PID:0 Mode:Process} D0316 07:48:49.740956 10804 urpc.go:534] urpc: successfully marshalled 37 bytes. D0316 07:48:49.751317 10804 task_block.go:223] [ 25] Interrupt queued D0316 07:48:49.759816 10804 task_block.go:223] [ 26] Interrupt queued D0316 07:48:49.775499 10804 task_block.go:223] [ 27] Interrupt queued D0316 07:48:49.833618 10804 task_block.go:223] [ 28] Interrupt queued D0316 07:48:49.865895 10804 task_block.go:223] [ 29] Interrupt queued D0316 07:48:49.869826 10804 task_block.go:223] [ 30] Interrupt queued D0316 07:48:49.887164 10804 task_block.go:223] [ 31] Interrupt queued D0316 07:48:49.914995 10804 task_block.go:223] [ 32] Interrupt queued D0316 07:48:49.917242 10804 task_block.go:223] [ 33] Interrupt queued D0316 07:48:49.922891 10804 task_block.go:223] [ 34] Interrupt queued D0316 07:48:49.956848 10804 task_block.go:223] [ 35] Interrupt queued D0316 07:48:49.966488 10804 transport_flipcall.go:127] send [channel @0xc0002923c0] Twalkgetattr{FID: 1, NewFID: 7, Names: [syzkaller605481713]} D0316 07:48:49.966953 10804 task_block.go:223] [ 36] Interrupt queued D0316 07:48:49.968669 10804 transport_flipcall.go:234] recv [channel @0xc0002923c0] Rwalkgetattr{Valid: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime Size Blocks}, Attr: Attr{Mode: 0o100600, UID: 0, GID: 0, NLink: 1, RDev: 0, Size: 387, BlockSize: 4096, Blocks: 8, ATime: {Sec: 1584344929, NanoSec: 312086368}, MTime: {Sec: 1584344929, NanoSec: 312086368}, CTime: {Sec: 1584344929, NanoSec: 312086368}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}, QIDs: [QID{Type: 0, Version: 0, Path: 16384243}]} D0316 07:48:49.968931 10804 transport_flipcall.go:127] send [channel @0xc0002923c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzkaller605481713, Size: 1} D0316 07:48:49.969546 10804 transport_flipcall.go:234] recv [channel @0xc0002923c0] Rlerror{Error: 95} D0316 07:48:49.969881 10804 transport_flipcall.go:127] send [channel @0xc0002923c0] Twalk{FID: 7, NewFID: 8, Names: []} D0316 07:48:49.970607 10804 transport_flipcall.go:234] recv [channel @0xc0002923c0] Rwalk{QIDs: []} D0316 07:48:49.970884 10804 transport_flipcall.go:127] send [channel @0xc0002923c0] Tlopen{FID: 8, Flags: ReadOnly} D0316 07:48:49.971742 10804 transport_flipcall.go:234] recv [channel @0xc0002923c0] Rlopen{QID: QID{Type: 0, Version: 0, Path: 16384243}, IoUnit: 0, File: &{{33}}} 2020/03/16 07:48:49 parsed 1 programs D0316 07:48:49.994125 10804 task_stop.go:118] [ 29] Entering internal stop (*kernel.vforkStop)(nil) D0316 07:48:49.994795 10804 task_block.go:223] [ 37] Interrupt queued D0316 07:48:50.003890 10804 transport_flipcall.go:127] send [channel @0xc0002923c0] Twalkgetattr{FID: 1, NewFID: 9, Names: [syz-executor]} D0316 07:48:50.005721 10804 transport_flipcall.go:234] recv [channel @0xc0002923c0] 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: 1584344846, NanoSec: 759995338}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}, QIDs: [QID{Type: 0, Version: 0, Path: 16384795}]} D0316 07:48:50.005964 10804 transport_flipcall.go:127] send [channel @0xc0002923c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syz-executor, Size: 1} D0316 07:48:50.006784 10804 transport_flipcall.go:234] recv [channel @0xc0002923c0] Rlerror{Error: 95} D0316 07:48:50.007071 10804 transport_flipcall.go:127] send [channel @0xc0002923c0] Twalk{FID: 9, NewFID: 10, Names: []} D0316 07:48:50.007729 10804 transport_flipcall.go:234] recv [channel @0xc0002923c0] Rwalk{QIDs: []} D0316 07:48:50.007883 10804 transport_flipcall.go:127] send [channel @0xc0002923c0] Tlopen{FID: 10, Flags: ReadOnly} D0316 07:48:50.009038 10804 transport_flipcall.go:234] recv [channel @0xc0002923c0] Rlopen{QID: QID{Type: 0, Version: 0, Path: 16384795}, IoUnit: 0, File: &{{34}}} D0316 07:48:50.010730 10804 syscalls.go:266] [ 37] Allocating stack with size of 8388608 bytes D0316 07:48:50.012078 10804 task_stop.go:137] [ 29] Leaving internal stop (*kernel.vforkStop)(nil) D0316 07:48:50.059396 10804 task_exit.go:221] [ 37] Transitioning from exit state TaskExitNone to TaskExitInitiated D0316 07:48:50.060628 10804 task_exit.go:221] [ 37] Transitioning from exit state TaskExitInitiated to TaskExitZombie D0316 07:48:50.060812 10804 task_signals.go:446] [ 20] Notified of signal 17 D0316 07:48:50.060961 10804 task_block.go:223] [ 20] Interrupt queued D0316 07:48:50.061088 10804 task_block.go:223] [ 20] Interrupt queued D0316 07:48:50.061211 10804 task_signals.go:179] [ 20] Restarting syscall 202 after errno 512: interrupted by signal 17 D0316 07:48:50.061326 10804 task_signals.go:220] [ 20] Signal 17: delivering to handler D0316 07:48:50.061853 10804 task_exit.go:221] [ 37] Transitioning from exit state TaskExitZombie to TaskExitDead D0316 07:48:50.065501 10804 task_block.go:223] [ 38] Interrupt queued 2020/03/16 07:48:50 executed programs: 0 D0316 07:48:50.079172 10804 task_stop.go:118] [ 30] Entering internal stop (*kernel.vforkStop)(nil) D0316 07:48:50.079858 10804 task_block.go:223] [ 39] Interrupt queued D0316 07:48:50.086004 10804 syscalls.go:266] [ 39] Allocating stack with size of 8388608 bytes D0316 07:48:50.086759 10804 task_stop.go:137] [ 30] Leaving internal stop (*kernel.vforkStop)(nil) D0316 07:48:50.108986 10804 task_block.go:223] [ 40] Interrupt queued D0316 07:48:50.114487 10804 transport_flipcall.go:127] send [channel @0xc0002923c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0316 07:48:50.115702 10804 transport_flipcall.go:234] recv [channel @0xc0002923c0] Rlerror{Error: 2} D0316 07:48:50.115980 10804 transport_flipcall.go:127] send [channel @0xc0002923c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0316 07:48:50.116720 10804 transport_flipcall.go:234] recv [channel @0xc0002923c0] Rlerror{Error: 95} D0316 07:48:50.223403 10804 parameters.go:238] Clock(Monotonic): error: -8 ns, adjusted frequency from 2299773541 Hz to 2299774551 Hz D0316 07:48:50.223656 10804 parameters.go:238] Clock(Realtime): error: -332 ns, adjusted frequency from 2299775065 Hz to 2299773767 Hz D0316 07:48:50.223803 10804 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:681272179297063 monotonicBaseRef:85000954478 monotonicFrequency:2299774551 realtimeReady:1 realtimeBaseCycles:681272179898711 realtimeBaseRef:1584344930223649033 realtimeFrequency:2299773767} D0316 07:48:50.264824 10804 task_block.go:223] [ 41] Interrupt queued panic: Start 0x55e6a4188fd6 + offset 0x55e6a4189000 overflows? goroutine 295 [running]: DIAGNOSIS: I0316 07:48:50.334078 11987 main.go:305] *************************** I0316 07:48:50.334208 11987 main.go:306] Args: [/syzkaller/managers/ptrace-direct-overlay-host-race/current/image -root /syzkaller/managers/ptrace-direct-overlay-host-race/workdir/gvisor_root -watchdog-action=panic -network=none -debug -alsologtostderr -platform=ptrace -file-access=exclusive -overlay -network=host -TESTONLY-unsafe-nonroot debug -stacks --ps ci-gvisor-ptrace-direct-overlay-host-race-0] I0316 07:48:50.334388 11987 main.go:307] Version release-20200219.0-178-g97127750289b I0316 07:48:50.334475 11987 main.go:308] PID: 11987 I0316 07:48:50.334568 11987 main.go:309] UID: 0, GID: 0 I0316 07:48:50.334717 11987 main.go:310] Configuration: I0316 07:48:50.334786 11987 main.go:311] RootDir: /syzkaller/managers/ptrace-direct-overlay-host-race/workdir/gvisor_root I0316 07:48:50.334938 11987 main.go:312] Platform: ptrace I0316 07:48:50.335043 11987 main.go:313] FileAccess: exclusive, overlay: true I0316 07:48:50.335159 11987 main.go:314] Network: host, logging: false I0316 07:48:50.335241 11987 main.go:315] Strace: false, max size: 1024, syscalls: [] I0316 07:48:50.335326 11987 main.go:316] *************************** W0316 07:48:50.335411 11987 main.go:321] Block the TERM signal. This is only safe in tests! D0316 07:48:50.335594 11987 container.go:159] Load container "/syzkaller/managers/ptrace-direct-overlay-host-race/workdir/gvisor_root" "ci-gvisor-ptrace-direct-overlay-host-race-0" D0316 07:48:50.343698 11987 container.go:592] Signal container "ci-gvisor-ptrace-direct-overlay-host-race-0": signal 0 D0316 07:48:50.343885 11987 sandbox.go:814] Signal sandbox "ci-gvisor-ptrace-direct-overlay-host-race-0" D0316 07:48:50.343989 11987 sandbox.go:318] Connecting to sandbox "ci-gvisor-ptrace-direct-overlay-host-race-0" D0316 07:48:50.344425 11987 urpc.go:534] urpc: successfully marshalled 123 bytes. I0316 07:48:50.497874 11987 debug.go:128] Found sandbox "ci-gvisor-ptrace-direct-overlay-host-race-0", PID: 10804 I0316 07:48:50.498101 11987 debug.go:137] Retrieving sandbox stacks D0316 07:48:50.498190 11987 sandbox.go:932] Stacks sandbox "ci-gvisor-ptrace-direct-overlay-host-race-0" D0316 07:48:50.498305 11987 sandbox.go:318] Connecting to sandbox "ci-gvisor-ptrace-direct-overlay-host-race-0" W0316 07:48:50.498499 11987 error.go:48] FATAL ERROR: retrieving stacks: connecting to control server at PID 10804: connection refused retrieving stacks: connecting to control server at PID 10804: connection refused W0316 07:48:50.498865 11987 main.go:338] Failure to execute command, err: 1 Error collecting stacks: failed to run ["/syzkaller/managers/ptrace-direct-overlay-host-race/current/image" "-root" "/syzkaller/managers/ptrace-direct-overlay-host-race/workdir/gvisor_root" "-watchdog-action=panic" "-network=none" "-debug" "-alsologtostderr" "-platform=ptrace" "-file-access=exclusive" "-overlay" "-network=host" "-TESTONLY-unsafe-nonroot" "debug" "-stacks" "--ps" "ci-gvisor-ptrace-direct-overlay-host-race-0"]: exit status 128 I0316 07:48:50.334078 11987 main.go:305] *************************** I0316 07:48:50.334208 11987 main.go:306] Args: [/syzkaller/managers/ptrace-direct-overlay-host-race/current/image -root /syzkaller/managers/ptrace-direct-overlay-host-race/workdir/gvisor_root -watchdog-action=panic -network=none -debug -alsologtostderr -platform=ptrace -file-access=exclusive -overlay -network=host -TESTONLY-unsafe-nonroot debug -stacks --ps ci-gvisor-ptrace-direct-overlay-host-race-0] I0316 07:48:50.334388 11987 main.go:307] Version release-20200219.0-178-g97127750289b I0316 07:48:50.334475 11987 main.go:308] PID: 11987 I0316 07:48:50.334568 11987 main.go:309] UID: 0, GID: 0 I0316 07:48:50.334717 11987 main.go:310] Configuration: I0316 07:48:50.334786 11987 main.go:311] RootDir: /syzkaller/managers/ptrace-direct-overlay-host-race/workdir/gvisor_root I0316 07:48:50.334938 11987 main.go:312] Platform: ptrace I0316 07:48:50.335043 11987 main.go:313] FileAccess: exclusive, overlay: true I0316 07:48:50.335159 11987 main.go:314] Network: host, logging: false I0316 07:48:50.335241 11987 main.go:315] Strace: false, max size: 1024, syscalls: [] I0316 07:48:50.335326 11987 main.go:316] *************************** W0316 07:48:50.335411 11987 main.go:321] Block the TERM signal. This is only safe in tests! D0316 07:48:50.335594 11987 container.go:159] Load container "/syzkaller/managers/ptrace-direct-overlay-host-race/workdir/gvisor_root" "ci-gvisor-ptrace-direct-overlay-host-race-0" D0316 07:48:50.343698 11987 container.go:592] Signal container "ci-gvisor-ptrace-direct-overlay-host-race-0": signal 0 D0316 07:48:50.343885 11987 sandbox.go:814] Signal sandbox "ci-gvisor-ptrace-direct-overlay-host-race-0" D0316 07:48:50.343989 11987 sandbox.go:318] Connecting to sandbox "ci-gvisor-ptrace-direct-overlay-host-race-0" D0316 07:48:50.344425 11987 urpc.go:534] urpc: successfully marshalled 123 bytes. I0316 07:48:50.497874 11987 debug.go:128] Found sandbox "ci-gvisor-ptrace-direct-overlay-host-race-0", PID: 10804 I0316 07:48:50.498101 11987 debug.go:137] Retrieving sandbox stacks D0316 07:48:50.498190 11987 sandbox.go:932] Stacks sandbox "ci-gvisor-ptrace-direct-overlay-host-race-0" D0316 07:48:50.498305 11987 sandbox.go:318] Connecting to sandbox "ci-gvisor-ptrace-direct-overlay-host-race-0" W0316 07:48:50.498499 11987 error.go:48] FATAL ERROR: retrieving stacks: connecting to control server at PID 10804: connection refused retrieving stacks: connecting to control server at PID 10804: connection refused W0316 07:48:50.498865 11987 main.go:338] Failure to execute command, err: 1 [81582.955693] lowmem_reserve[]: 0 0 117862 117862 117862 [81582.961821] Node 0 Normal free:65900kB min:65908kB low:186596kB high:307284kB active_anon:118563480kB inactive_anon:971292kB active_file:2288kB inactive_file:0kB unevictable:0kB writepending:0kB present:122683392kB managed:120696220kB mlocked:0kB slab_reclaimable:39016kB slab_unreclaimable:171952kB kernel_stack:136356kB pagetables:260492kB bounce:0kB free_pcp:0kB local_pcp:0kB free_cma:0kB [81582.997481] lowmem_reserve[]: 0 0 0 0 0 [81583.004015] Node 0 DMA: 1*4kB (U) 0*8kB 0*16kB 1*32kB (U) 2*64kB (U) 1*128kB (U) 1*256kB (U) 0*512kB 1*1024kB (U) 1*2048kB (M) 3*4096kB (M) = 15908kB [81583.020860] Node 0 DMA32: 747*4kB (UME) 615*8kB (UME) 1262*16kB (UME) 344*32kB (UMEH) 100*64kB (UMEH) 64*128kB (UMH) 41*256kB (UMEH) 19*512kB (UMEH) 8*1024kB (UME) 9*2048kB (UME) 91*4096kB (M) = 473284kB [81583.042508] Node 0 Normal: 16944*4kB (UME) 8*8kB (UM) 0*16kB 0*32kB 0*64kB 0*128kB 0*256kB 0*512kB 0*1024kB 0*2048kB 0*4096kB = 67840kB [81583.057167] Node 0 hugepages_total=0 hugepages_free=0 hugepages_surp=0 hugepages_size=1048576kB [81583.066082] Node 0 hugepages_total=0 hugepages_free=0 hugepages_surp=0 hugepages_size=2048kB [81583.074824] 297601 total pagecache pages [81583.078875] 0 pages in swap cache [81583.082324] Swap cache stats: add 0, delete 0, find 0/0 [81583.087676] Free swap = 0kB [81583.090675] Total swap = 0kB [81583.093694] 31457176 pages RAM [81583.096889] 0 pages HighMem/MovableOnly [81583.100861] 513206 pages reserved [81583.104506] 0 pages hwpoisoned [81583.107699] [ pid ] uid tgid total_vm rss nr_ptes nr_pmds swapents oom_score_adj name [81583.116536] [ 935] 0 935 91555 147 176 4 0 0 systemd-journal [81583.126170] [ 960] 0 960 11548 333 23 3 0 -1000 systemd-udevd [81583.135606] [ 1125] 0 1125 13686 112 26 3 0 -1000 auditd [81583.144886] [ 1137] 0 1137 21093 40 12 3 0 0 audispd [81583.155457] [ 1151] 109 1151 11282 111 28 3 0 -900 dbus-daemon [81583.164724] [ 1155] 0 1155 1051 21 8 3 0 0 acpid [81583.173467] [ 1157] 0 1157 7409 61 19 3 0 0 cron [81583.182144] [ 1158] 0 1158 9495 122 22 3 0 0 systemd-logind [81583.191675] [ 1180] 0 1180 34588 3145 65 5 0 0 syz-ci [81583.200592] [ 1660] 0 1660 5119 275 12 3 0 0 dhclient [81583.210130] [ 1707] 0 1707 90807 12331 116 4 0 0 puppet [81583.219592] [ 1714] 0 1714 3575 34 12 3 0 0 agetty [81583.230289] [ 1716] 0 1716 3631 33 12 3 0 0 agetty [81583.239127] [ 1743] 106 1743 24474 132 23 4 0 0 ntpd [81583.247784] [ 1746] 0 1746 366819 41661 606 4 0 0 ruby [81583.256444] [ 2124] 0 2124 17077 3619 39 4 0 0 google_network_ [81583.266057] [ 2195] 0 2195 17175 3730 40 3 0 -999 google_accounts [81583.275661] [ 2354] 0 2354 17072 3580 39 3 0 0 google_clock_sk [81583.285259] [ 2355] 0 2355 17489 187 37 4 0 -1000 sshd [81583.293928] [13916] 0 13916 113255 50119 190 5 0 0 syz-manager [81583.303198] [14009] 0 14009 95098 56175 177 5 0 0 syz-manager [81583.313884] [14172] 0 14172 91827 51282 170 5 0 0 syz-manager [81583.324574] [42519] 0 42519 62528 356 28 3 0 0 rsyslogd [81583.334567] [49384] 0 49384 46286 17255 92 5 0 0 syz-manager [81583.343852] [34145] 0 34145 315315 3934 60 10 0 0 image [81583.352735] [34171] 0 34171 440947 11084 178 11 0 0 exe [81583.361327] [34177] 0 34177 42954605 29900237 61462 169 0 0 exe [81583.370454] [34215] 0 34215 1 1 1 1 0 0 exe [81583.385638] [34282] 0 34282 23665 3807 52 6 0 0 exe [81583.409177] [34422] 0 34422 296594 3712 58 9 0 0 image [81583.417923] [34448] 0 34448 82436 54603 133 6 0 0 exe [81583.427201] [34562] 0 34562 656 1 5 4 0 0 exe [81583.435807] [34935] 0 34935 4810 1 6 4 0 0 exe [81583.444476] [34982] 0 34982 1 1 1 1 0 0 exe [81583.453139] [34996] 0 34996 834 1 5 4 0 0 exe [81583.461719] [35017] 0 35017 1 1 1 1 0 0 exe [81583.470310] [35022] 0 35022 439 1 5 4 0 0 exe [81583.478892] [35035] 0 35035 4990 1 6 4 0 0 exe [81583.487463] [35069] 0 35069 4595 1 6 4 0 0 exe [81583.496165] [35255] 0 35255 416 1 5 3 0 0 exe [81583.504736] [35308] 0 35308 1 1 1 1 0 0 exe [81583.513299] [35369] 0 35369 6806 1 9 5 0 0 exe [81583.521912] [35432] 0 35432 1 1 1 1 0 0 exe [81583.530474] [39273] 0 39273 6411 1 9 5 0 0 exe [81583.539042] [40852] 0 40852 180167 660 22 4 0 0 image [81583.547788] [40860] 0 40860 181675 1476 28 4 0 0 exe [81583.556354] [40884] 0 40884 380550 63733 313 7 0 0 exe [81583.564918] [40946] 0 40946 1 1 1 1 0 0 exe [81583.573482] [41015] 0 41015 24120 4046 52 6 0 0 exe [81583.582232] [41132] 0 41132 179815 596 19 5 0 0 image [81583.590973] [41142] 0 41142 73327 45898 116 6 0 0 exe [81583.600005] [41253] 0 41253 530 1 5 4 0 0 exe [81583.608596] [41592] 0 41592 440 1 5 4 0 0 exe [81583.617160] [41593] 0 41593 709 1 5 4 0 0 exe [81583.625725] [41597] 0 41597 400 1 5 4 0 0 exe [81583.634774] [41604] 0 41604 4624 1 5 4 0 0 exe [81583.643347] [41607] 0 41607 4905 1 6 4 0 0 exe [81583.651943] [41610] 0 41610 4494 1 5 4 0 0 exe [81583.660512] [41611] 0 41611 4534 1 5 4 0 0 exe [81583.669093] [41633] 0 41633 6022 1 8 5 0 0 exe [81583.677660] [41648] 0 41648 6410 1 9 5 0 0 exe [81583.686235] [41663] 0 41663 6681 1 9 5 0 0 exe [81583.694841] [41674] 0 41674 5828 1 8 5 0 0 exe [81583.704400] [41790] 0 41790 6681 1 9 5 0 0 exe [81583.712973] [41797] 0 41797 6502 1 9 5 0 0 exe [81583.721616] [41841] 0 41841 6502 1 9 5 0 0 exe [81583.730223] [43725] 0 43725 6713 1 9 5 0 0 exe [81583.738806] [43767] 0 43767 416 1 5 3 0 0 exe [81583.747375] [43802] 0 43802 442 1 5 3 0 0 exe [81583.756028] [43955] 0 43955 6681 1 9 5 0 0 exe [81583.764599] [43971] 0 43971 414 1 5 3 0 0 exe [81583.773518] [43976] 0 43976 414 1 5 3 0 0 exe [81583.782089] [44373] 0 44373 414 1 5 3 0 0 exe [81583.790681] [45846] 0 45846 414 1 5 3 0 0 exe [81583.799424] [45898] 0 45898 442 1 5 3 0 0 exe [81583.808051] [51374] 0 51374 6443 1 9 5 0 0 exe [81583.816808] [53889] 0 53889 179879 688 20 5 0 0 image [81583.825563] [53895] 0 53895 180907 731 21 5 0 0 exe [81583.834146] [53900] 65534 53900 17181731352 36258 229 6 0 0 exe [81583.842977] [54215] 0 54215 180295 645 22 4 0 0 image [81583.855142] [63711] 0 63711 5899 1 8 5 0 0 exe [81583.863893] [63805] 0 63805 1 1 1 1 0 0 exe [81583.872447] [64117] 0 64117 1 1 1 1 0 0 exe [81583.881087] [64324] 0 64324 6411 1 9 5 0 0 exe [81583.889661] [64368] 0 64368 1 1 1 1 0 0 exe [81583.898361] [64397] 0 64397 6411 1 9 5 0 0 exe [81583.906941] [64474] 0 64474 416 1 5 3 0 0 exe [81583.915518] [64503] 0 64503 1 1 1 1 0 0 exe [81583.924079] [64626] 0 64626 6294 1 8 5 0 0 exe [81583.932640] [64729] 0 64729 416 1 5 3 0 0 exe [81583.941203] [64746] 0 64746 414 1 5 3 0 0 exe [81583.949762] [64770] 0 64770 1 1 1 1 0 0 exe [81583.958330] [64863] 0 64863 414 1 5 3 0 0 exe [81583.966936] [64969] 0 64969 414 1 5 3 0 0 exe [81583.975511] [65000] 0 65000 6806 1 9 5 0 0 exe [81583.984077] [65007] 0 65007 416 1 5 3 0 0 exe [81583.992646] [65023] 0 65023 1 1 1 1 0 0 exe [81584.001201] [65141] 0 65141 416 1 5 3 0 0 exe [81584.009757] [65147] 0 65147 414 1 5 3 0 0 exe [81584.018354] [ 456] 0 456 1 1 1 1 0 0 exe [81584.026911] [ 621] 0 621 1 1 1 1 0 0 exe [81584.035463] [ 996] 0 996 416 1 5 3 0 0 exe [81584.044064] [ 1059] 0 1059 6523 1 9 5 0 0 exe [81584.052626] [ 2305] 0 2305 442 1 5 3 0 0 exe [81584.061185] [ 2310] 0 2310 442 1 5 3 0 0 exe [81584.069755] [ 2513] 0 2513 5828 1 8 5 0 0 exe [81584.078316] [ 2519] 0 2519 416 1 5 3 0 0 exe [81584.086878] [ 2561] 0 2561 416 1 5 3 0 0 exe [81584.095444] [ 2641] 0 2641 414 1 5 3 0 0 exe [81584.104013] [ 2697] 0 2697 414 1 5 3 0 0 exe [81584.112683] [ 2859] 0 2859 6404 1 9 5 0 0 exe [81584.121810] [ 2865] 0 2865 6410 1 9 5 0 0 exe [81584.130381] [ 3027] 0 3027 416 1 5 3 0 0 exe [81584.140050] [ 3139] 0 3139 6378 1 9 5 0 0 exe [81584.148717] [ 3146] 0 3146 1 1 1 1 0 0 exe [81584.157281] [ 3230] 0 3230 6410 1 9 5 0 0 exe [81584.165864] [ 3386] 0 3386 416 1 5 3 0 0 exe [81584.174429] [ 3439] 0 3439 416 1 5 3 0 0 exe [81584.182999] [ 3482] 0 3482 5860 1 8 5 0 0 exe [81584.191572] [ 4127] 0 4127 1 1 1 1 0 0 exe [81584.200218] [ 4139] 0 4139 6502 1 9 5 0 0 exe [81584.208848] [ 4587] 0 4587 6372 1 9 5 0 0 exe [81584.217561] [ 4630] 0 4630 1 1 1 1 0 0 exe [81584.226197] [15062] 0 15062 180167 670 21 4 0 0 image [81584.234933] [15744] 0 15744 179879 662 20 5 0 0 image [81584.243715] [15764] 0 15764 181419 1260 26 4 0 0 exe [81584.252312] [15765] 0 15765 297517 17229 152 6 0 0 exe [81584.260872] [15839] 0 15839 1 1 1 1 0 0 exe [81584.269436] [15942] 0 15942 23713 3436 31 6 0 0 exe [81584.278007] [16430] 0 16430 179879 652 20 5 0 0 image [81584.286787] [16440] 0 16440 180167 669 21 4 0 0 image [81584.295522] [16442] 0 16442 72628 45365 115 7 0 0 exe [81584.304084] [16648] 0 16648 658 1 5 4 0 0 exe [81584.312643] [17756] 0 17756 663 1 5 4 0 0 exe [81584.321204] [17777] 0 17777 4814 1 6 4 0 0 exe [81584.329756] [17780] 0 17780 352 1 5 4 0 0 exe [81584.338321] [17792] 0 17792 517 1 5 4 0 0 exe [81584.346874] [17812] 0 17812 4757 1 5 4 0 0 exe [81584.355438] [17837] 0 17837 4671 1 6 4 0 0 exe [81584.364003] [17842] 0 17842 4506 1 6 4 0 0 exe [81584.372554] [17927] 0 17927 1 1 1 1 0 0 exe [81584.381120] [17963] 0 17963 6603 1 9 5 0 0 exe [81584.389685] [17976] 0 17976 1 1 1 1 0 0 exe [81584.398237] [18019] 0 18019 1 1 1 1 0 0 exe [81584.406791] [20490] 0 20490 1 1 1 1 0 0 exe [81584.415347] [26212] 0 26212 296882 3644 57 10 0 0 image [81584.424075] [26385] 0 26385 34478 11246 71 5 0 0 syz-manager [81584.433331] [26386] 0 26386 44991 21388 92 5 0 0 syz-manager [81584.442590] [27214] 0 27214 180231 732 21 5 0 0 image [81584.451361] [27220] 0 27220 180971 713 24 5 0 0 exe [81584.459914] [27223] 65534 27223 17181652865 5780 59 6 0 0 exe [81584.468742] [27256] 0 27256 180231 679 20 5 0 0 image [81584.477475] [27270] 0 27270 180971 745 23 5 0 0 exe [81584.486035] [27276] 65534 27276 211053 4952 55 6 0 0 exe [81584.494631] [27309] 65534 27309 1 1 1 1 0 0 exe [81584.503215] [27361] 65534 27361 23838 3717 30 6 0 0 exe [81584.511769] [27536] 0 27536 179879 670 21 5 0 0 image [81584.520495] [27548] 0 27548 180971 668 23 5 0 0 exe [81584.529047] [27549] 0 27549 206685 1494 33 6 0 0 exe [81584.537602] [27558] 0 27558 315315 3661 59 10 0 0 image [81584.546336] [27577] 0 27577 1 1 1 1 0 0 exe [81584.555322] [27599] 0 27599 2171 1 2 2 0 0 exe [81584.563877] [27602] 0 27602 296882 3629 55 10 0 0 image [81584.572610] Out of memory: Kill process 34177 (exe) score 939 or sacrifice child [81584.580479] Killed process 34215 (exe) total-vm:4kB, anon-rss:4kB, file-rss:0kB, shmem-rss:0kB [81584.599385] oom_reaper: reaped process 34215 (exe), now anon-rss:0kB, file-rss:0kB, shmem-rss:0kB [81585.327454] syz-manager invoked oom-killer: gfp_mask=0x24201ca(GFP_HIGHUSER_MOVABLE|__GFP_COLD), nodemask=0, order=0, oom_score_adj=0 [81585.339673] syz-manager cpuset=/ mems_allowed=0 [81585.344915] CPU: 63 PID: 49385 Comm: syz-manager Not tainted 4.9.0-11-amd64 #1 Debian 4.9.189-3+deb9u2 [81585.354342] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 [81585.363685] 0000000000000000 ffffffffa1136404 ffffba1950ec3c10 ffff9fc5a6796140 [81585.371731] ffffffffa1009690 0000000000000000 0000000000000000 ffff9fca7848a0c0 [81585.379764] ffff9fca787de100 ffff9fca73253690 0000000000000000 ffffffffa10acee0 [81585.387799] Call Trace: [81585.390377] [] ? dump_stack+0x5c/0x78 [81585.395817] [] ? dump_header+0x78/0x1fd [81585.401430] [] ? cap_inode_killpriv+0x20/0x20 [81585.407675] [] ? oom_kill_process+0x22a/0x3f0 [81585.413811] [] ? out_of_memory+0x111/0x470 [81585.419684] [] ? __alloc_pages_slowpath+0xa1f/0xb30 [81585.426333] [] ? __alloc_pages_nodemask+0x201/0x260 [81585.432981] [] ? alloc_pages_current+0x91/0x140 [81585.439377] [] ? filemap_fault+0x326/0x5d0 [81585.445284] [] ? ext4_filemap_fault+0x31/0x50 [ext4] [81585.452018] [] ? __do_fault+0xa9/0x1c0 [81585.457535] [] ? handle_mm_fault+0xda4/0x1350 [81585.463671] [] ? poll_select_copy_remaining+0x11b/0x150 [81585.470663] [] ? __do_page_fault+0x255/0x4f0 [81585.476826] [] ? page_fault+0x28/0x30 [81585.482803] Mem-Info: [81585.485206] active_anon:30285919 inactive_anon:243098 isolated_anon:0 active_file:100 inactive_file:431 isolated_file:0 unevictable:0 dirty:0 writeback:0 unstable:0 slab_reclaimable:9474 slab_unreclaimable:41816 mapped:242882 shmem:297093 pagetables:66348 bounce:0 free:138713 free_pcp:0 free_cma:0 [81585.519047] Node 0 active_anon:121143676kB inactive_anon:972392kB active_file:1040kB inactive_file:0kB unevictable:0kB isolated(anon):0kB isolated(file):0kB mapped:971528kB dirty:0kB writeback:0kB shmem:1188372kB shmem_thp: 0kB shmem_pmdmapped: 0kB anon_thp: 77824kB writeback_tmp:0kB unstable:0kB pages_scanned:22883 all_unreclaimable? no [81585.549064] Node 0 DMA free:15908kB min:8kB low:20kB high:32kB active_anon:0kB inactive_anon:0kB active_file:0kB inactive_file:0kB unevictable:0kB writepending:0kB present:15992kB managed:15908kB mlocked:0kB slab_reclaimable:0kB slab_unreclaimable:0kB kernel_stack:0kB pagetables:0kB bounce:0kB free_pcp:0kB local_pcp:0kB free_cma:0kB [81585.579333] lowmem_reserve[]: 0 2974 120837 120837 120837 [81585.585795] Node 0 DMA32 free:473100kB min:1660kB low:4704kB high:7748kB active_anon:2580192kB inactive_anon:1100kB active_file:28kB inactive_file:0kB unevictable:0kB writepending:0kB present:3129320kB managed:3063752kB mlocked:0kB slab_reclaimable:16kB slab_unreclaimable:596kB kernel_stack:108kB pagetables:4896kB bounce:0kB free_pcp:0kB local_pcp:0kB free_cma:0kB [81585.618297] lowmem_reserve[]: 0 0 117862 117862 117862 [81585.624338] Node 0 Normal free:65904kB min:65908kB low:186596kB high:307284kB active_anon:118563484kB inactive_anon:971292kB active_file:760kB inactive_file:560kB unevictable:0kB writepending:0kB present:122683392kB managed:120696220kB mlocked:0kB slab_reclaimable:37848kB slab_unreclaimable:166652kB kernel_stack:136356kB pagetables:260496kB bounce:0kB free_pcp:0kB local_pcp:0kB free_cma:0kB [81585.659512] lowmem_reserve[]: 0 0 0 0 0 [81585.664254] Node 0 DMA: 1*4kB (U) 0*8kB 0*16kB 1*32kB (U) 2*64kB (U) 1*128kB (U) 1*256kB (U) 0*512kB 1*1024kB (U) 1*2048kB (M) 3*4096kB (M) = 15908kB [81585.680575] Node 0 DMA32: 748*4kB (UME) 615*8kB (UME) 1262*16kB (UME) 345*32kB (UMEH) 100*64kB (UMEH) 64*128kB (UMH) 41*256kB (UMEH) 19*512kB (UMEH) 8*1024kB (UME) 9*2048kB (UME) 91*4096kB (M) = 473320kB [81585.701900] Node 0 Normal: 17664*4kB (UME) 124*8kB (UME) 18*16kB (UM) 66*32kB (UM) 0*64kB 0*128kB 0*256kB 0*512kB 0*1024kB 0*2048kB 0*4096kB = 74048kB [81585.717799] Node 0 hugepages_total=0 hugepages_free=0 hugepages_surp=0 hugepages_size=1048576kB [81585.726790] Node 0 hugepages_total=0 hugepages_free=0 hugepages_surp=0 hugepages_size=2048kB [81585.735550] 298621 total pagecache pages [81585.739596] 0 pages in swap cache [81585.743040] Swap cache stats: add 0, delete 0, find 0/0 [81585.748424] Free swap = 0kB [81585.751448] Total swap = 0kB [81585.754503] 31457176 pages RAM [81585.757712] 0 pages HighMem/MovableOnly [81585.761672] 513206 pages reserved [81585.765115] 0 pages hwpoisoned [81585.768307] [ pid ] uid tgid total_vm rss nr_ptes nr_pmds swapents oom_score_adj name [81585.777225] [ 935] 0 935 91555 147 176 4 0 0 systemd-journal [81585.787062] [ 960] 0 960 11548 333 23 3 0 -1000 systemd-udevd [81585.803711] [ 1125] 0 1125 13686 112 26 3 0 -1000 auditd [81585.812529] [ 1137] 0 1137 21093 75 12 3 0 0 audispd [81585.821438] [ 1151] 109 1151 11282 111 28 3 0 -900 dbus-daemon [81585.830696] [ 1155] 0 1155 1051 21 8 3 0 0 acpid [81585.840119] [ 1157] 0 1157 7409 61 19 3 0 0 cron [81585.848762] [ 1158] 0 1158 9495 122 22 3 0 0 systemd-logind [81585.858276] [ 1180] 0 1180 34588 3145 65 5 0 0 syz-ci [81585.867459] [ 1660] 0 1660 5119 275 12 3 0 0 dhclient [81585.876450] [ 1707] 0 1707 90807 12331 116 4 0 0 puppet [81585.885262] [ 1714] 0 1714 3575 34 12 3 0 0 agetty [81585.894098] [ 1716] 0 1716 3631 33 12 3 0 0 agetty [81585.902928] [ 1743] 106 1743 24474 132 23 4 0 0 ntpd [81585.911664] [ 1746] 0 1746 366819 41661 606 4 0 0 ruby [81585.921010] [ 2124] 0 2124 17077 3619 39 4 0 0 google_network_ [81585.930638] [ 2195] 0 2195 17175 3730 40 3 0 -999 google_accounts [81585.940246] [ 2354] 0 2354 17072 3580 39 3 0 0 google_clock_sk [81585.949859] [ 2355] 0 2355 17489 187 37 4 0 -1000 sshd [81585.958602] [13916] 0 13916 113255 50141 190 5 0 0 syz-manager [81585.967869] [14009] 0 14009 95098 56175 177 5 0 0 syz-manager [81585.977258] [14172] 0 14172 91827 51282 170 5 0 0 syz-manager [81585.986546] [42519] 0 42519 62528 361 28 3 0 0 rsyslogd [81585.995572] [49384] 0 49384 46286 17363 92 5 0 0 syz-manager [81586.004857] [34145] 0 34145 315315 3933 60 10 0 0 image [81586.017424] [34171] 0 34171 440947 11084 178 11 0 0 exe [81586.025979] [34177] 0 34177 42954605 29900237 61462 169 0 0 exe [81586.034533] [34215] 0 34215 1 0 1 1 0 0 exe [81586.043100] [34282] 0 34282 23665 3807 52 6 0 0 exe [81586.051658] [34422] 0 34422 296594 3711 58 9 0 0 image [81586.060384] [34448] 0 34448 82436 54603 133 6 0 0 exe [81586.068950] [34562] 0 34562 656 1 5 4 0 0 exe [81586.077507] [34935] 0 34935 4810 1 6 4 0 0 exe [81586.086077] [34982] 0 34982 1 1 1 1 0 0 exe [81586.094646] [34996] 0 34996 834 1 5 4 0 0 exe [81586.104526] [35017] 0 35017 1 1 1 1 0 0 exe [81586.113085] [35022] 0 35022 439 1 5 4 0 0 exe [81586.121647] [35035] 0 35035 4990 1 6 4 0 0 exe [81586.130232] [35069] 0 35069 4595 1 6 4 0 0 exe [81586.138791] [35255] 0 35255 416 1 5 3 0 0 exe [81586.147790] [35308] 0 35308 1 1 1 1 0 0 exe [81586.156454] [35369] 0 35369 6806 1 9 5 0 0 exe [81586.165015] [35432] 0 35432 1 1 1 1 0 0 exe [81586.173568] [39273] 0 39273 6411 1 9 5 0 0 exe [81586.182210] [40852] 0 40852 180167 660 22 4 0 0 image [81586.190948] [40860] 0 40860 181675 1476 28 4 0 0 exe [81586.199512] [40884] 0 40884 380550 63733 313 7 0 0 exe [81586.208061] [40946] 0 40946 1 1 1 1 0 0 exe [81586.217765] [41015] 0 41015 24120 4046 52 6 0 0 exe [81586.227557] [41132] 0 41132 179815 596 19 5 0 0 image [81586.236399] [41142] 0 41142 73327 45898 116 6 0 0 exe [81586.244983] [41253] 0 41253 530 1 5 4 0 0 exe [81586.253557] [41592] 0 41592 440 1 5 4 0 0 exe [81586.262159] [41593] 0 41593 709 1 5 4 0 0 exe [81586.270725] [41597] 0 41597 400 1 5 4 0 0 exe [81586.279414] [41604] 0 41604 4624 1 5 4 0 0 exe [81586.287974] [41607] 0 41607 4905 1 6 4 0 0 exe [81586.296527] [41610] 0 41610 4494 1 5 4 0 0 exe [81586.305079] [41611] 0 41611 4534 1 5 4 0 0 exe [81586.316321] [41633] 0 41633 6022 1 8 5 0 0 exe [81586.324873] [41648] 0 41648 6410 1 9 5 0 0 exe [81586.333424] [41663] 0 41663 6681 1 9 5 0 0 exe [81586.342016] [41674] 0 41674 5828 1 8 5 0 0 exe [81586.350568] [41790] 0 41790 6681 1 9 5 0 0 exe [81586.363118] [41797] 0 41797 6502 1 9 5 0 0 exe [81586.371666] [41841] 0 41841 6502 1 9 5 0 0 exe [81586.380218] [43725] 0 43725 6713 1 9 5 0 0 exe [81586.388769] [43767] 0 43767 416 1 5 3 0 0 exe [81586.397368] [43802] 0 43802 442 1 5 3 0 0 exe [81586.405920] [43955] 0 43955 6681 1 9 5 0 0 exe [81586.414662] [43971] 0 43971 414 1 5 3 0 0 exe [81586.423217] [43976] 0 43976 414 1 5 3 0 0 exe [81586.431781] [44373] 0 44373 414 1 5 3 0 0 exe [81586.440335] [45846] 0 45846 414 1 5 3 0 0 exe [81586.448886] [45898] 0 45898 442 1 5 3 0 0 exe [81586.457446] [51374] 0 51374 6443 1 9 5 0 0 exe [81586.466001] [53889] 0 53889 179879 688 20 5 0 0 image [81586.474829] [53895] 0 53895 180907 731 21 5 0 0 exe [81586.483388] [53900] 65534 53900 17181731352 36258 229 6 0 0 exe [81586.492200] [54215] 0 54215 180295 645 22 4 0 0 image [81586.500925] [63711] 0 63711 5899 1 8 5 0 0 exe [81586.509482] [63805] 0 63805 1 1 1 1 0 0 exe [81586.518137] [64117] 0 64117 1 1 1 1 0 0 exe [81586.526689] [64324] 0 64324 6411 1 9 5 0 0 exe [81586.535244] [64368] 0 64368 1 1 1 1 0 0 exe [81586.543882] [64397] 0 64397 6411 1 9 5 0 0 exe [81586.552439] [64474] 0 64474 416 1 5 3 0 0 exe [81586.561000] [64503] 0 64503 1 1 1 1 0 0 exe [81586.569553] [64626] 0 64626 6294 1 8 5 0 0 exe [81586.578104] [64729] 0 64729 416 1 5 3 0 0 exe [81586.586656] [64746] 0 64746 414 1 5 3 0 0 exe [81586.595208] [64770] 0 64770 1 1 1 1 0 0 exe [81586.603759] [64863] 0 64863 414 1 5 3 0 0 exe [81586.612313] [64969] 0 64969 414 1 5 3 0 0 exe [81586.620863] [65000] 0 65000 6806 1 9 5 0 0 exe [81586.629419] [65007] 0 65007 416 1 5 3 0 0 exe [81586.637977] [65023] 0 65023 1 1 1 1 0 0 exe [81586.646586] [65141] 0 65141 416 1 5 3 0 0 exe [81586.655148] [65147] 0 65147 414 1 5 3 0 0 exe [81586.663703] [ 456] 0 456 1 1 1 1 0 0 exe [81586.672254] [ 621] 0 621 1 1 1 1 0 0 exe [81586.680806] [ 996] 0 996 416 1 5 3 0 0 exe [81586.692569] [ 1059] 0 1059 6523 1 9 5 0 0 exe [81586.701135] [ 2305] 0 2305 442 1 5 3 0 0 exe [81586.712033] [ 2310] 0 2310 442 1 5 3 0 0 exe [81586.720587] [ 2513] 0 2513 5828 1 8 5 0 0 exe [81586.729182] [ 2519] 0 2519 416 1 5 3 0 0 exe [81586.737734] [ 2561] 0 2561 416 1 5 3 0 0 exe [81586.746288] [ 2641] 0 2641 414 1 5 3 0 0 exe [81586.754838] [ 2697] 0 2697 414 1 5 3 0 0 exe [81586.763391] [ 2859] 0 2859 6404 1 9 5 0 0 exe [81586.774726] [ 2865] 0 2865 6410 1 9 5 0 0 exe [81586.783282] [ 3027] 0 3027 416 1 5 3 0 0 exe [81586.791832] [ 3139] 0 3139 6378 1 9 5 0 0 exe [81586.800425] [ 3146] 0 3146 1 1 1 1 0 0 exe [81586.809113] [ 3230] 0 3230 6410 1 9 5 0 0 exe [81586.817715] [ 3386] 0 3386 416 1 5 3 0 0 exe [81586.826267] [ 3439] 0 3439 416 1 5 3 0 0 exe [81586.834819] [ 3482] 0 3482 5860 1 8 5 0 0 exe [81586.843372] [ 4127] 0 4127 1 1 1 1 0 0 exe [81586.851942] [ 4139] 0 4139 6502 1 9 5 0 0 exe [81586.860491] [ 4587] 0 4587 6372 1 9 5 0 0 exe [81586.869043] [ 4630] 0 4630 1 1 1 1 0 0 exe [81586.877607] [15062] 0 15062 180167 670 21 4 0 0 image [81586.886335] [15744] 0 15744 179879 662 20 5 0 0 image [81586.895061] [15764] 0 15764 181419 1260 26 4 0 0 exe [81586.903613] [15765] 0 15765 297517 17267 152 6 0 0 exe [81586.912205] [15839] 0 15839 1 1 1 1 0 0 exe [81586.920796] [15942] 0 15942 23713 3436 31 6 0 0 exe [81586.929346] [16430] 0 16430 179879 652 20 5 0 0 image [81586.938102] [16440] 0 16440 180167 669 21 4 0 0 image [81586.946832] [16442] 0 16442 72628 45365 115 7 0 0 exe [81586.955422] [16648] 0 16648 658 1 5 4 0 0 exe [81586.963976] [17756] 0 17756 663 1 5 4 0 0 exe [81586.973534] [17777] 0 17777 4814 1 6 4 0 0 exe [81586.982095] [17780] 0 17780 352 1 5 4 0 0 exe [81586.990676] [17792] 0 17792 517 1 5 4 0 0 exe [81586.999230] [17812] 0 17812 4757 1 5 4 0 0 exe [81587.007791] [17837] 0 17837 4671 1 6 4 0 0 exe [81587.016358] [17842] 0 17842 4506 1 6 4 0 0 exe [81587.024916] [17927] 0 17927 1 1 1 1 0 0 exe [81587.033470] [17963] 0 17963 6603 1 9 5 0 0 exe [81587.042035] [17976] 0 17976 1 1 1 1 0 0 exe [81587.050630] [18019] 0 18019 1 1 1 1 0 0 exe [81587.059184] [20490] 0 20490 1 1 1 1 0 0 exe [81587.067787] [26212] 0 26212 296882 3644 57 10 0 0 image [81587.076565] [26385] 0 26385 34478 11246 71 5 0 0 syz-manager [81587.085814] [26386] 0 26386 44991 21388 92 5 0 0 syz-manager [81587.095074] [27214] 0 27214 180231 732 21 5 0 0 image [81587.103801] [27220] 0 27220 180971 713 24 5 0 0 exe [81587.112352] [27223] 65534 27223 17181652865 5780 59 6 0 0 exe [81587.121168] [27256] 0 27256 180231 679 20 5 0 0 image [81587.129908] [27270] 0 27270 180971 745 23 5 0 0 exe [81587.138461] [27276] 65534 27276 211053 4952 55 6 0 0 exe [81587.147169] [27309] 65534 27309 1 1 1 1 0 0 exe [81587.155793] [27361] 65534 27361 23838 3717 30 6 0 0 exe [81587.164373] [27536] 0 27536 179879 670 21 5 0 0 image [81587.173130] [27548] 0 27548 180971 668 23 5 0 0 exe [81587.181721] [27549] 0 27549 206685 1495 33 6 0 0 exe [81587.190596] [27558] 0 27558 315315 3661 59 10 0 0 image [81587.199475] [27577] 0 27577 1 1 1 1 0 0 exe [81587.209418] [27599] 0 27599 2171 1 2 2 0 0 exe [81587.218209] [27602] 0 27602 296882 3629 55 10 0 0 image [81587.227774] Out of memory: Kill process 34177 (exe) score 939 or sacrifice child [81587.236452] Killed process 34177 (exe) total-vm:171818420kB, anon-rss:119445228kB, file-rss:0kB, shmem-rss:156744kB [81591.574780] kvm [28160]: vcpu0, guest rIP: 0xa74064 unhandled rdmsr: 0xce [81592.535632] kvm [28786]: vcpu0, guest rIP: 0xa74064 unhandled rdmsr: 0xce [81596.132172] oom_reaper: reaped process 34177 (exe), now anon-rss:0kB, file-rss:0kB, shmem-rss:157164kB [97099.041207] can: request_module (can-proto-0) failed. [97099.056539] can: request_module (can-proto-0) failed. [97099.072025] can: request_module (can-proto-0) failed. [97120.192202] can: request_module (can-proto-0) failed. [97120.207534] can: request_module (can-proto-0) failed. [97120.234555] can: request_module (can-proto-0) failed. [97124.804225] can: request_module (can-proto-0) failed. [97124.814929] can: request_module (can-proto-0) failed. [97124.828872] can: request_module (can-proto-0) failed. [97307.124852] kvm [41621]: vcpu0, guest rIP: 0xa75294 unhandled rdmsr: 0xce [97307.125552] kvm [41622]: vcpu0, guest rIP: 0xa75294 unhandled rdmsr: 0xce [97307.125896] kvm [41616]: vcpu0, guest rIP: 0xa75294 unhandled rdmsr: 0xce [97482.648808] kvm [42563]: vcpu0, guest rIP: 0xa75294 unhandled rdmsr: 0xce [175346.089220] exe invoked oom-killer: gfp_mask=0x24201ca(GFP_HIGHUSER_MOVABLE|__GFP_COLD), nodemask=0, order=0, oom_score_adj=0 [175346.100888] exe cpuset=/ mems_allowed=0 [175346.105224] CPU: 60 PID: 28325 Comm: exe Not tainted 4.9.0-11-amd64 #1 Debian 4.9.189-3+deb9u2 [175346.114037] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 [175346.123471] 0000000000000000 ffffffffa1136404 ffffba194e00bc10 ffff9fbc7ca2b040 [175346.131570] ffffffffa1009690 0000000000000000 0000000000000000 0000000ca141e4f1 [175346.139670] ffff9fba8277e040 ffffffffa0f8d207 0000004200000000 ffffffffa10acee0 [175346.147838] Call Trace: [175346.150527] [] ? dump_stack+0x5c/0x78 [175346.156061] [] ? dump_header+0x78/0x1fd [175346.161754] [] ? get_page_from_freelist+0x3f7/0xb20 [175346.168482] [] ? cap_inode_killpriv+0x20/0x20 [175346.174695] [] ? oom_kill_process+0x22a/0x3f0 [175346.180905] [] ? out_of_memory+0x111/0x470 [175346.186872] [] ? __alloc_pages_slowpath+0xa1f/0xb30 [175346.193601] [] ? __alloc_pages_nodemask+0x201/0x260 [175346.200471] [] ? alloc_pages_current+0x91/0x140 [175346.206853] [] ? filemap_fault+0x326/0x5d0 [175346.212841] [] ? ext4_filemap_fault+0x31/0x50 [ext4] [175346.219670] [] ? __do_fault+0xa9/0x1c0 [175346.225274] [] ? handle_mm_fault+0xda4/0x1350 [175346.231491] [] ? do_nanosleep+0x8d/0xe0 [175346.237184] [] ? __do_page_fault+0x255/0x4f0 [175346.243410] [] ? page_fault+0x28/0x30 [175346.248954] Mem-Info: [175346.251465] active_anon:30503324 inactive_anon:48047 isolated_anon:0 active_file:648 inactive_file:12 isolated_file:0 unevictable:0 dirty:224 writeback:0 unstable:0 slab_reclaimable:7196 slab_unreclaimable:32177 mapped:52160 shmem:55216 pagetables:72402 bounce:0 free:138700 free_pcp:155 free_cma:0 [175346.286329] Node 0 active_anon:122013296kB inactive_anon:192188kB active_file:3116kB inactive_file:1524kB unevictable:0kB isolated(anon):0kB isolated(file):0kB mapped:210184kB dirty:916kB writeback:0kB shmem:220864kB shmem_thp: 0kB shmem_pmdmapped: 0kB anon_thp: 116736kB writeback_tmp:0kB unstable:0kB pages_scanned:935 all_unreclaimable? no [175346.316693] Node 0 DMA free:15908kB min:8kB low:20kB high:32kB active_anon:0kB inactive_anon:0kB active_file:0kB inactive_file:0kB unevictable:0kB writepending:0kB present:15992kB managed:15908kB mlocked:0kB slab_reclaimable:0kB slab_unreclaimable:0kB kernel_stack:0kB pagetables:0kB bounce:0kB free_pcp:0kB local_pcp:0kB free_cma:0kB [175346.346491] lowmem_reserve[]: 0 2974 120837 120837 120837 [175346.352924] Node 0 DMA32 free:473060kB min:1660kB low:4704kB high:7748kB active_anon:2578804kB inactive_anon:36kB active_file:20kB inactive_file:44kB unevictable:0kB writepending:0kB present:3129320kB managed:3063752kB mlocked:0kB slab_reclaimable:68kB slab_unreclaimable:440kB kernel_stack:80kB pagetables:5300kB bounce:0kB free_pcp:0kB local_pcp:0kB free_cma:0kB [175346.385390] lowmem_reserve[]: 0 0 117862 117862 117862 [175346.391603] Node 0 Normal free:65664kB min:65908kB low:186596kB high:307284kB active_anon:119434492kB inactive_anon:192152kB active_file:3416kB inactive_file:0kB unevictable:0kB writepending:0kB present:122683392kB managed:120696220kB mlocked:0kB slab_reclaimable:28712kB slab_unreclaimable:128264kB kernel_stack:37344kB pagetables:284308kB bounce:0kB free_pcp:1728kB local_pcp:0kB free_cma:0kB [175346.426606] lowmem_reserve[]: 0 0 0 0 0 [175346.431425] Node 0 DMA: 1*4kB (U) 0*8kB 0*16kB 1*32kB (U) 2*64kB (U) 1*128kB (U) 1*256kB (U) 0*512kB 1*1024kB (U) 1*2048kB (M) 3*4096kB (M) = 15908kB [175346.448182] Node 0 DMA32: 580*4kB (UMEH) 287*8kB (UME) 307*16kB (UME) 188*32kB (UME) 82*64kB (UME) 29*128kB (UME) 7*256kB (UMEH) 3*512kB (UM) 7*1024kB (UME) 4*2048kB (UME) 105*4096kB (ME) = 473272kB [175346.469466] Node 0 Normal: 16732*4kB (UMEH) 64*8kB (UMH) 11*16kB (H) 14*32kB (H) 9*64kB (H) 2*128kB (H) 1*256kB (H) 0*512kB 0*1024kB 0*2048kB 0*4096kB = 69152kB [175346.486730] Node 0 hugepages_total=0 hugepages_free=0 hugepages_surp=0 hugepages_size=1048576kB [175346.495642] Node 0 hugepages_total=0 hugepages_free=0 hugepages_surp=0 hugepages_size=2048kB [175346.504297] 56888 total pagecache pages [175346.508341] 0 pages in swap cache [175346.512017] Swap cache stats: add 0, delete 0, find 0/0 [175346.517450] Free swap = 0kB [175346.520537] Total swap = 0kB [175346.523627] 31457176 pages RAM [175346.526889] 0 pages HighMem/MovableOnly [175346.530960] 513206 pages reserved [175346.534473] 0 pages hwpoisoned [175346.537733] [ pid ] uid tgid total_vm rss nr_ptes nr_pmds swapents oom_score_adj name [175346.546554] [ 935] 0 935 113037 147 219 4 0 0 systemd-journal [175346.556310] [ 960] 0 960 11548 333 23 3 0 -1000 systemd-udevd [175346.565866] [ 1125] 0 1125 13686 112 26 3 0 -1000 auditd [175346.574773] [ 1137] 0 1137 21093 16 12 3 0 0 audispd [175346.583766] [ 1151] 109 1151 11282 112 28 3 0 -900 dbus-daemon [175346.593102] [ 1155] 0 1155 1051 21 8 3 0 0 acpid [175346.602216] [ 1157] 0 1157 7409 61 19 3 0 0 cron [175346.610966] [ 1158] 0 1158 9495 122 22 3 0 0 systemd-logind [175346.620698] [ 1180] 0 1180 32028 3099 62 5 0 0 syz-ci [175346.629649] [ 1660] 0 1660 5119 275 12 3 0 0 dhclient [175346.638724] [ 1707] 0 1707 90842 12358 116 4 0 0 puppet [175346.647631] [ 1714] 0 1714 3575 34 12 3 0 0 agetty [175346.656619] [ 1716] 0 1716 3631 33 12 3 0 0 agetty [175346.665538] [ 1743] 106 1743 24474 132 23 4 0 0 ntpd [175346.674432] [ 1746] 0 1746 361781 41111 583 4 0 0 ruby [175346.683290] [ 2124] 0 2124 17077 3622 39 4 0 0 google_network_ [175346.692995] [ 2195] 0 2195 17239 3739 40 3 0 -999 google_accounts [175346.702697] [ 2354] 0 2354 17072 3580 39 3 0 0 google_clock_sk [175346.712581] [ 2355] 0 2355 17489 187 37 4 0 -1000 sshd [175346.721348] [42133] 0 42133 50358 23840 101 5 0 0 syz-manager [175346.730877] [45406] 0 45406 53614 24006 109 5 0 0 syz-manager [175346.740256] [63756] 0 63756 121165 62344 170 5 0 0 syz-manager [175346.749614] [13118] 0 13118 94500 57666 188 5 0 0 syz-manager [175346.758963] [25776] 0 25776 111726 55609 190 5 0 0 syz-manager [175346.768430] [ 9893] 0 9893 62528 353 28 4 0 0 rsyslogd [175346.777739] [62996] 0 62996 76602 52378 155 5 0 0 syz-manager [175346.787110] [24856] 0 24856 180231 726 22 5 0 0 image [175346.795940] [24862] 0 24862 181323 713 23 4 0 0 exe [175346.804704] [24864] 65534 24864 17181731394 40859 231 6 0 0 exe [175346.813623] [25464] 0 25464 180167 648 21 4 0 0 image [175346.822632] [28312] 0 28312 315400 4068 65 9 0 0 image [175346.831678] [28319] 0 28319 442694 7615 175 11 0 0 exe [175346.840331] [28320] 0 28320 50715370 30112456 68984 170 0 0 exe [175346.848989] [28346] 0 28346 1 1 1 1 0 0 exe [175346.857667] [28360] 0 28360 23855 4021 52 6 0 0 exe [175346.866452] [38106] 0 38106 315048 3710 60 10 0 0 image [175346.875292] [38116] 0 38116 29440 4194 31 6 0 0 exe [175346.883951] [38152] 0 38152 4539 1 5 4 0 0 exe [175346.892609] [38163] 0 38163 773 1 5 4 0 0 exe [175346.901360] [38167] 0 38167 4576 1 5 4 0 0 exe [175346.910018] [38170] 0 38170 482 1 5 4 0 0 exe [175346.918685] [38178] 0 38178 475 1 5 4 0 0 exe [175346.927349] [38180] 0 38180 443 1 5 4 0 0 exe [175346.936017] [38197] 0 38197 4569 1 5 4 0 0 exe [175346.944758] [38204] 0 38204 4867 1 5 4 0 0 exe [175346.953467] [38250] 0 38250 6582 1 9 5 0 0 exe [175346.962116] [38257] 0 38257 6936 1 9 5 0 0 exe [175346.970770] [38267] 0 38267 6638 1 9 5 0 0 exe [175346.979442] [38335] 0 38335 6801 1 10 5 0 0 exe [175346.988147] [43215] 0 43215 179815 653 20 5 0 0 image [175346.996960] [43220] 0 43220 181323 762 23 5 0 0 exe [175347.005648] [43226] 65534 43226 17181666072 8587 113 6 0 0 exe [175347.014690] [44668] 0 44668 180167 672 21 4 0 0 image [175347.023510] [44724] 0 44724 179882 685 21 4 0 0 image [175347.032324] [44733] 0 44733 180974 685 23 5 0 0 exe [175347.040964] [44738] 65534 44738 17181653458 6848 89 6 0 0 exe [175347.049871] [45141] 0 45141 180167 668 20 5 0 0 image [175347.058699] [55571] 0 55571 179946 615 20 5 0 0 image [175347.067511] [55577] 0 55577 180234 686 21 5 0 0 image [175347.076328] Out of memory: Kill process 28320 (exe) score 946 or sacrifice child [175347.084083] Killed process 28346 (exe) total-vm:4kB, anon-rss:4kB, file-rss:0kB, shmem-rss:0kB [175347.102603] oom_reaper: reaped process 28346 (exe), now anon-rss:0kB, file-rss:0kB, shmem-rss:0kB [175357.442129] syz-manager invoked oom-killer: gfp_mask=0x24201ca(GFP_HIGHUSER_MOVABLE|__GFP_COLD), nodemask=0, order=0, oom_score_adj=0 [175357.454769] syz-manager cpuset=/ mems_allowed=0 [175357.459771] CPU: 29 PID: 25777 Comm: syz-manager Not tainted 4.9.0-11-amd64 #1 Debian 4.9.189-3+deb9u2 [175357.469274] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 [175357.478692] 0000000000000000 ffffffffa1136404 ffffba195b2efc10 ffff9fbc7ca2b040 [175357.486886] ffffffffa1009690 0000000000000000 0000000000000000 0000000c5b2efb00 [175357.496385] ffff9fbb9f2cd0c0 ffffffffa0f8d207 0000004200000000 ffffffffa10acee0 [175357.504494] Call Trace: [175357.507153] [] ? dump_stack+0x5c/0x78 [175357.514047] [] ? dump_header+0x78/0x1fd [175357.519744] [] ? get_page_from_freelist+0x3f7/0xb20 [175357.526472] [] ? cap_inode_killpriv+0x20/0x20 [175357.534064] [] ? oom_kill_process+0x22a/0x3f0 [175357.540452] [] ? out_of_memory+0x111/0x470 [175357.546399] [] ? __alloc_pages_slowpath+0xa1f/0xb30 [175357.553126] [] ? __alloc_pages_nodemask+0x201/0x260 [175357.559862] [] ? alloc_pages_current+0x91/0x140 [175357.566248] [] ? filemap_fault+0x326/0x5d0 [175357.572242] [] ? ext4_filemap_fault+0x31/0x50 [ext4] [175357.580429] [] ? __do_fault+0xa9/0x1c0 [175357.586037] [] ? handle_mm_fault+0xda4/0x1350 [175357.592245] [] ? poll_select_copy_remaining+0x11b/0x150 [175357.600703] [] ? __do_page_fault+0x255/0x4f0 [175357.606830] [] ? page_fault+0x28/0x30 [175357.613763] Mem-Info: [175357.617611] active_anon:30503330 inactive_anon:48047 isolated_anon:0 active_file:1928 inactive_file:1979 isolated_file:0 unevictable:0 dirty:5 writeback:0 unstable:0 slab_reclaimable:6547 slab_unreclaimable:28753 mapped:55189 shmem:55216 pagetables:72418 bounce:0 free:138860 free_pcp:700 free_cma:0 [175357.652063] Node 0 active_anon:122013824kB inactive_anon:192188kB active_file:6668kB inactive_file:8044kB unevictable:0kB isolated(anon):0kB isolated(file):0kB mapped:220052kB dirty:20kB writeback:0kB shmem:220864kB shmem_thp: 0kB shmem_pmdmapped: 0kB anon_thp: 116736kB writeback_tmp:0kB unstable:0kB pages_scanned:2331 all_unreclaimable? no [175357.682428] Node 0 DMA free:15908kB min:8kB low:20kB high:32kB active_anon:0kB inactive_anon:0kB active_file:0kB inactive_file:0kB unevictable:0kB writepending:0kB present:15992kB managed:15908kB mlocked:0kB slab_reclaimable:0kB slab_unreclaimable:0kB kernel_stack:0kB pagetables:0kB bounce:0kB free_pcp:0kB local_pcp:0kB free_cma:0kB [175357.713621] lowmem_reserve[]: 0 2974 120837 120837 120837 [175357.722812] Node 0 DMA32 free:473164kB min:1660kB low:4704kB high:7748kB active_anon:2578904kB inactive_anon:36kB active_file:16kB inactive_file:20kB unevictable:0kB writepending:0kB present:3129320kB managed:3063752kB mlocked:0kB slab_reclaimable:28kB slab_unreclaimable:412kB kernel_stack:80kB pagetables:5300kB bounce:0kB free_pcp:0kB local_pcp:0kB free_cma:0kB [175357.755235] lowmem_reserve[]: 0 0 117862 117862 117862 [175357.763026] Node 0 Normal free:66104kB min:65908kB low:186596kB high:307284kB active_anon:119444800kB inactive_anon:192152kB active_file:3444kB inactive_file:3332kB unevictable:0kB writepending:52kB present:122683392kB managed:120696220kB mlocked:0kB slab_reclaimable:26076kB slab_unreclaimable:114392kB kernel_stack:37376kB pagetables:284372kB bounce:0kB free_pcp:2332kB local_pcp:0kB free_cma:0kB [175357.799781] lowmem_reserve[]: 0 0 0 0 0 [175357.806090] Node 0 DMA: 1*4kB (U) 0*8kB 0*16kB 1*32kB (U) 2*64kB (U) 1*128kB (U) 1*256kB (U) 0*512kB 1*1024kB (U) 1*2048kB (M) 3*4096kB (M) = 15908kB [175357.825340] Node 0 DMA32: 583*4kB (UMEH) 282*8kB (UME) 308*16kB (UME) 186*32kB (UME) 82*64kB (UME) 29*128kB (UME) 7*256kB (UMEH) 3*512kB (UM) 7*1024kB (UME) 4*2048kB (UME) 105*4096kB (ME) = 473196kB [175357.850478] Node 0 Normal: 17231*4kB (UMEH) 243*8kB (UMEH) 42*16kB (UMEH) 17*32kB (UH) 9*64kB (H) 2*128kB (H) 1*256kB (H) 0*512kB 0*1024kB 0*2048kB 0*4096kB = 73172kB [175357.869731] Node 0 hugepages_total=0 hugepages_free=0 hugepages_surp=0 hugepages_size=1048576kB [175357.878660] Node 0 hugepages_total=0 hugepages_free=0 hugepages_surp=0 hugepages_size=2048kB [175357.887317] 56801 total pagecache pages [175357.891375] 0 pages in swap cache [175357.894926] Swap cache stats: add 0, delete 0, find 0/0 [175357.900368] Free swap = 0kB [175357.903462] Total swap = 0kB [175357.906560] 31457176 pages RAM [175357.909837] 0 pages HighMem/MovableOnly [175357.913885] 513206 pages reserved [175357.917418] 0 pages hwpoisoned [175357.920676] [ pid ] uid tgid total_vm rss nr_ptes nr_pmds swapents oom_score_adj name [175357.929530] [ 935] 0 935 113037 147 219 4 0 0 systemd-journal [175357.939378] [ 960] 0 960 11548 333 23 3 0 -1000 systemd-udevd [175357.948909] [ 1125] 0 1125 13686 112 26 3 0 -1000 auditd [175357.957855] [ 1137] 0 1137 21093 117 12 3 0 0 audispd [175357.966904] [ 1151] 109 1151 11282 112 28 3 0 -900 dbus-daemon [175357.976256] [ 1155] 0 1155 1051 21 8 3 0 0 acpid [175357.985251] [ 1157] 0 1157 7409 61 19 3 0 0 cron [175357.994022] [ 1158] 0 1158 9495 122 22 3 0 0 systemd-logind [175358.003632] [ 1180] 0 1180 32028 3149 62 5 0 0 syz-ci [175358.012549] [ 1660] 0 1660 5119 275 12 3 0 0 dhclient [175358.021646] [ 1707] 0 1707 90842 12359 116 4 0 0 puppet [175358.030567] [ 1714] 0 1714 3575 34 12 3 0 0 agetty [175358.039482] [ 1716] 0 1716 3631 33 12 3 0 0 agetty [175358.048399] [ 1743] 106 1743 24474 132 23 4 0 0 ntpd [175358.058525] [ 1746] 0 1746 361781 41111 583 4 0 0 ruby [175358.067334] [ 2124] 0 2124 17077 3622 39 4 0 0 google_network_ [175358.078433] [ 2195] 0 2195 17239 3739 40 3 0 -999 google_accounts [175358.088324] [ 2354] 0 2354 17072 3580 39 3 0 0 google_clock_sk [175358.099411] [ 2355] 0 2355 17489 187 37 4 0 -1000 sshd [175358.108176] [42133] 0 42133 50358 23840 101 5 0 0 syz-manager [175358.118901] [45406] 0 45406 53614 24087 109 5 0 0 syz-manager [175358.128247] [63756] 0 63756 121165 62399 170 5 0 0 syz-manager [175358.137678] [13118] 0 13118 94500 57666 188 5 0 0 syz-manager [175358.147018] [25776] 0 25776 111726 55645 190 5 0 0 syz-manager [175358.156387] [ 9893] 0 9893 62528 399 28 4 0 0 rsyslogd [175358.165527] [62996] 0 62996 76602 52378 155 5 0 0 syz-manager [175358.176208] [24856] 0 24856 180231 726 22 5 0 0 image [175358.185034] [24862] 0 24862 181323 713 23 4 0 0 exe [175358.195101] [24864] 65534 24864 17181731394 40859 231 6 0 0 exe [175358.204007] [25464] 0 25464 180167 648 21 4 0 0 image [175358.214244] [28312] 0 28312 315400 4068 65 9 0 0 image [175358.224581] [28319] 0 28319 442694 7615 175 11 0 0 exe [175358.234618] [28320] 0 28320 50715370 30115796 68984 170 0 0 exe [175358.243262] [28346] 0 28346 1 0 1 1 0 0 exe [175358.251914] [28360] 0 28360 23855 4021 52 6 0 0 exe [175358.260568] [38106] 0 38106 315048 3710 60 10 0 0 image [175358.269398] [38116] 0 38116 29440 4194 31 6 0 0 exe [175358.278055] [38152] 0 38152 4539 1 5 4 0 0 exe [175358.286703] [38163] 0 38163 773 1 5 4 0 0 exe [175358.296127] [38167] 0 38167 4576 1 5 4 0 0 exe [175358.304838] [38170] 0 38170 482 1 5 4 0 0 exe [175358.313573] [38178] 0 38178 475 1 5 4 0 0 exe [175358.322218] [38180] 0 38180 443 1 5 4 0 0 exe [175358.330862] [38197] 0 38197 4569 1 5 4 0 0 exe [175358.339526] [38204] 0 38204 4867 1 5 4 0 0 exe [175358.348193] [38250] 0 38250 6582 1 9 5 0 0 exe [175358.356840] [38257] 0 38257 6936 1 9 5 0 0 exe [175358.366869] [38267] 0 38267 6638 1 9 5 0 0 exe [175358.375527] [38335] 0 38335 6801 1 10 5 0 0 exe [175358.384220] [43215] 0 43215 179815 653 20 5 0 0 image [175358.394424] [43220] 0 43220 181323 762 23 5 0 0 exe [175358.403071] [43226] 65534 43226 17181666072 8587 113 6 0 0 exe [175358.411980] [44668] 0 44668 180167 672 21 4 0 0 image [175358.420803] [44724] 0 44724 179882 685 21 4 0 0 image [175358.429621] [44733] 0 44733 180974 685 23 5 0 0 exe [175358.438446] [44738] 65534 44738 17181653458 6848 89 6 0 0 exe [175358.447428] [45141] 0 45141 180167 668 20 5 0 0 image [175358.457626] [55571] 0 55571 179946 615 20 5 0 0 image [175358.466445] [55577] 0 55577 180234 686 21 5 0 0 image [175358.476670] [55862] 0 55862 121165 62399 170 5 0 0 syz-manager [175358.486015] [55863] 0 55863 76602 52378 155 5 0 0 syz-manager [175358.495360] [55864] 0 55864 111726 55645 190 5 0 0 syz-manager [175358.504783] Out of memory: Kill process 28320 (exe) score 946 or sacrifice child [175358.512927] Killed process 28320 (exe) total-vm:202861480kB, anon-rss:120420108kB, file-rss:0kB, shmem-rss:45188kB [175366.645578] oom_reaper: reaped process 28320 (exe), now anon-rss:0kB, file-rss:0kB, shmem-rss:45196kB [175366.693167] kvm [57642]: vcpu0, guest rIP: 0xa74064 unhandled rdmsr: 0xce [178598.208219] kvm [26880]: vcpu0, guest rIP: 0xa74064 unhandled rdmsr: 0xce [196368.150210] INFO: rcu_sched self-detected stall on CPU [196368.155762] 8-...: (5248 ticks this GP) idle=333/140000000000001/0 softirq=21787059/21787059 fqs=2382 [196368.158205] INFO: rcu_sched detected stalls on CPUs/tasks: [196368.158208] 8-...: (5248 ticks this GP) idle=333/140000000000001/0 softirq=21787059/21787059 fqs=2382 [196368.158215] (detected by 20, t=5252 jiffies, g=7979813, c=7979812, q=71359) [196368.158215] Task dump for CPU 8: [196368.158218] exe R running task 0 39721 39117 0x00000188 [196368.158222] ffff9fb081a9ac80 ffffba19a5d2be58 000000001046f000 ffffffffa0fbac26 [196368.158224] ffff9fca6fae3200 000000000001044e 000000000001046e 0000000000000000 [196368.158225] 4fd5c60d50c52f3b ffff9fca6fae3088 ffffba19a5d2bec8 ffff9fca6fae3130 [196368.158226] Call Trace: [196368.158234] [] ? unmap_mapping_range+0x116/0x140 [196368.158238] [] ? shmem_fallocate+0x30d/0x4e0 [196368.158241] [] ? vfs_fallocate+0x15a/0x220 [196368.158243] [] ? SyS_fallocate+0x43/0x80 [196368.158246] [] ? do_syscall_64+0x8d/0x100 [196368.158250] [] ? entry_SYSCALL_64_after_swapgs+0x58/0xc6 [196368.262941] (t=5278 jiffies g=7979813 c=7979812 q=71916) [196368.268784] Task dump for CPU 8: [196368.272221] exe R running task 0 39721 39117 0x00000188 [196368.279738] ffffffffa1b19a00 ffffffffa0ea953b 0000000000000008 ffffffffa1b19a00 [196368.287983] ffffffffa0f830ad ffff9fca868196c0 ffffffffa1a4fd80 0000000000000000 [196368.296116] ffffffffa1b19a00 00000000ffffffff ffffffffa0ee51ca 0000000002ac8e6b [196368.304324] Call Trace: [196368.306974] [196368.309103] [] ? sched_show_task+0xcb/0x130 [196368.315161] [] ? rcu_dump_cpu_stacks+0x92/0xb2 [196368.321451] [] ? rcu_check_callbacks+0x75a/0x8b0 [196368.328093] [] ? tick_sched_do_timer+0x30/0x30 [196368.334385] [] ? update_process_times+0x28/0x50 [196368.340767] [] ? tick_sched_handle.isra.12+0x20/0x50 [196368.347585] [] ? tick_sched_timer+0x38/0x70 [196368.362574] [] ? __hrtimer_run_queues+0xde/0x250 [196368.369055] [] ? hrtimer_interrupt+0x9c/0x1a0 [196368.375272] [] ? 0xffffffffc08da000 [196368.380617] [] ? smp_apic_timer_interrupt+0x47/0x60 [196368.387344] [] ? apic_timer_interrupt+0x9e/0xb0 [196368.393726] [196368.395858] [] ? 0xffffffffc08da000 [196368.401219] [] ? smp_call_function_many+0x1f2/0x250 [196368.407997] [] ? kvm_make_all_cpus_request+0xdd/0x110 [kvm] [196368.415435] [] ? kvm_flush_remote_tlbs+0x18/0x40 [kvm] [196368.422450] [] ? kvm_mmu_notifier_invalidate_range_start+0x56/0x80 [kvm] [196368.431005] [] ? __mmu_notifier_invalidate_range_start+0x52/0x80 [196368.438877] [] ? zap_page_range_single+0xf7/0x110 [196368.445432] [] ? unmap_mapping_range+0x116/0x140 [196368.451900] [] ? shmem_fallocate+0x30d/0x4e0 [196368.458019] [] ? vfs_fallocate+0x15a/0x220 [196368.464092] [] ? SyS_fallocate+0x43/0x80 [196368.469879] [] ? do_syscall_64+0x8d/0x100 [196368.475745] [] ? entry_SYSCALL_64_after_swapgs+0x58/0xc6 [196431.164035] INFO: rcu_sched self-detected stall on CPU [196431.169578] 8-...: (20921 ticks this GP) idle=333/140000000000001/0 softirq=21787059/21787059 fqs=9514 [196431.172026] INFO: rcu_sched detected stalls on CPUs/tasks: [196431.172031] 8-...: (20921 ticks this GP) idle=333/140000000000001/0 softirq=21787059/21787059 fqs=9514 [196431.172037] (detected by 20, t=21007 jiffies, g=7979813, c=7979812, q=254885) [196431.172038] Task dump for CPU 8: [196431.172041] exe R running task 0 39721 39117 0x00000188 [196431.172045] ffff9fb081a9ac80 ffffba19a5d2be58 000000001046f000 ffffffffa0fbac26 [196431.172047] ffff9fca6fae3200 000000000001044e 000000000001046e 0000000000000000 [196431.172049] 4fd5c60d50c52f3b ffff9fca6fae3088 ffffba19a5d2bec8 ffff9fca6fae3130 [196431.172050] Call Trace: [196431.172063] [] ? unmap_mapping_range+0x116/0x140 [196431.172067] [] ? shmem_fallocate+0x30d/0x4e0 [196431.172071] [] ? vfs_fallocate+0x15a/0x220 [196431.172073] [] ? SyS_fallocate+0x43/0x80 [196431.172076] [] ? do_syscall_64+0x8d/0x100 [196431.172079] [] ? entry_SYSCALL_64_after_swapgs+0x58/0xc6 [196431.289258] (t=21036 jiffies g=7979813 c=7979812 q=255141) [196431.295268] Task dump for CPU 8: [196431.298703] exe R running task 0 39721 39117 0x00000188 [196431.306333] ffffffffa1b19a00 ffffffffa0ea953b 0000000000000008 ffffffffa1b19a00 [196431.314473] ffffffffa0f830ad ffff9fca868196c0 ffffffffa1a4fd80 0000000000000000 [196431.322690] ffffffffa1b19a00 00000000ffffffff ffffffffa0ee51ca 0000000002bb80ac [196431.330900] Call Trace: [196431.333550] [196431.335685] [] ? sched_show_task+0xcb/0x130 [196431.341756] [] ? rcu_dump_cpu_stacks+0x92/0xb2 [196431.348149] [] ? rcu_check_callbacks+0x75a/0x8b0 [196431.354633] [] ? tick_sched_do_timer+0x30/0x30 [196431.360935] [] ? update_process_times+0x28/0x50 [196431.367322] [] ? tick_sched_handle.isra.12+0x20/0x50 [196431.374145] [] ? tick_sched_timer+0x38/0x70 [196431.380556] [] ? __hrtimer_run_queues+0xde/0x250 [196431.387029] [] ? hrtimer_interrupt+0x9c/0x1a0 [196431.393247] [] ? 0xffffffffc08da000 [196431.398850] [] ? smp_apic_timer_interrupt+0x47/0x60 [196431.405588] [] ? apic_timer_interrupt+0x9e/0xb0 [196431.412059] [196431.414304] [] ? 0xffffffffc08da000 [196431.419671] [] ? smp_call_function_many+0x1f5/0x250 [196431.426448] [] ? kvm_make_all_cpus_request+0xdd/0x110 [kvm] [196431.433897] [] ? kvm_flush_remote_tlbs+0x18/0x40 [kvm] [196431.440934] [] ? kvm_mmu_notifier_invalidate_range_start+0x56/0x80 [kvm] [196431.449700] [] ? __mmu_notifier_invalidate_range_start+0x52/0x80 [196431.460434] [] ? zap_page_range_single+0xf7/0x110 [196431.467112] [] ? unmap_mapping_range+0x116/0x140 [196431.473592] [] ? shmem_fallocate+0x30d/0x4e0 [196431.479809] [] ? vfs_fallocate+0x15a/0x220 [196431.485853] [] ? SyS_fallocate+0x43/0x80 [196431.491638] [] ? do_syscall_64+0x8d/0x100 [196431.497509] [] ? entry_SYSCALL_64_after_swapgs+0x58/0xc6 [196494.181856] INFO: rcu_sched self-detected stall on CPU [196494.185849] INFO: rcu_sched detected stalls on CPUs/tasks: [196494.185857] 8-...: (36593 ticks this GP) idle=333/140000000000001/0 softirq=21787059/21787059 fqs=16616 [196494.185858] [196494.185863] (detected by 20, t=36762 jiffies, g=7979813, c=7979812, q=392287) [196494.185864] Task dump for CPU 8: [196494.185865] exe R [196494.185866] running task [196494.185867] 0 39721 39117 0x00000188 [196494.185870] ffff9fb081a9ac80 [196494.185870] ffffba19a5d2be58 [196494.185871] 000000001046f000 [196494.185871] ffffffffa0fbac26 [196494.185872] ffff9fca6fae3200 [196494.185872] 000000000001044e [196494.185873] 000000000001046e [196494.185874] 0000000000000000 [196494.185874] 4fd5c60d50c52f3b [196494.185875] ffff9fca6fae3088 [196494.185875] ffffba19a5d2bec8 [196494.185876] ffff9fca6fae3130 [196494.185876] Call Trace: [196494.185885] [] ? unmap_mapping_range+0x116/0x140 [196494.185889] [] ? shmem_fallocate+0x30d/0x4e0 [196494.185893] [] ? vfs_fallocate+0x15a/0x220 [196494.185895] [] ? SyS_fallocate+0x43/0x80 [196494.185898] [] ? do_syscall_64+0x8d/0x100 [196494.185901] [] ? entry_SYSCALL_64_after_swapgs+0x58/0xc6 [196494.287979] 8-...: (36593 ticks this GP) idle=333/140000000000001/0 softirq=21787059/21787059 fqs=16629 [196494.297867] (t=36790 jiffies g=7979813 c=7979812 q=392551) [196494.303875] Task dump for CPU 8: [196494.307577] exe R running task 0 39721 39117 0x00000188 [196494.315108] ffffffffa1b19a00 ffffffffa0ea953b 0000000000000008 ffffffffa1b19a00 [196494.323350] ffffffffa0f830ad ffff9fca868196c0 ffffffffa1a4fd80 0000000000000000 [196494.331613] ffffffffa1b19a00 00000000ffffffff ffffffffa0ee51ca 0000000002ac840c [196494.339801] Call Trace: [196494.342550] [196494.344685] [] ? sched_show_task+0xcb/0x130 [196494.350832] [] ? rcu_dump_cpu_stacks+0x92/0xb2 [196494.357135] [] ? rcu_check_callbacks+0x75a/0x8b0 [196494.364020] [] ? tick_sched_do_timer+0x30/0x30 [196494.370416] [] ? update_process_times+0x28/0x50 [196494.376886] [] ? tick_sched_handle.isra.12+0x20/0x50 [196494.383713] [] ? tick_sched_timer+0x38/0x70 [196494.389755] [] ? __hrtimer_run_queues+0xde/0x250 [196494.396249] [] ? hrtimer_interrupt+0x9c/0x1a0 [196494.402468] [] ? 0xffffffffc08da000 [196494.408232] [] ? smp_apic_timer_interrupt+0x47/0x60 [196494.416227] [] ? apic_timer_interrupt+0x9e/0xb0 [196494.423892] [196494.426496] [] ? 0xffffffffc08da000 [196494.434556] [] ? smp_call_function_many+0x1f2/0x250 [196494.444472] [] ? kvm_make_all_cpus_request+0xdd/0x110 [kvm] [196494.452214] [] ? kvm_flush_remote_tlbs+0x18/0x40 [kvm] [196494.459553] [] ? kvm_mmu_notifier_invalidate_range_start+0x56/0x80 [kvm] [196494.469276] [] ? __mmu_notifier_invalidate_range_start+0x52/0x80 [196494.478218] [] ? zap_page_range_single+0xf7/0x110 [196494.484786] [] ? unmap_mapping_range+0x116/0x140 [196494.491262] [] ? shmem_fallocate+0x30d/0x4e0 [196494.497477] [] ? vfs_fallocate+0x15a/0x220 [196494.503432] [] ? SyS_fallocate+0x43/0x80 [196494.509211] [] ? do_syscall_64+0x8d/0x100 [196494.515070] [] ? entry_SYSCALL_64_after_swapgs+0x58/0xc6 [196497.973446] NMI watchdog: BUG: soft lockup - CPU#11 stuck for 134s! [exe:39453] [196497.981117] Modules linked in: rose nfc netrom llc2 ax25 vmw_vsock_vmci_transport vsock vmw_vmci fcrypt pcbc af_rxrpc pptp gre l2tp_ppp l2tp_netlink pppoe pppox pn_pep phonet xfrm_user nfnetlink l2tp_ip6 l2tp_ip l2tp_core ip6_udp_tunnel udp_tunnel af_key xfrm_algo ipx p8023 p8022 psnap llc sctp libcrc32c can_raw can_bcm can rfcomm hidp cmtp kernelcapi bnep af_alg vhost_net vhost macvtap macvlan hci_vhci bluetooth rfkill vfio_iommu_type1 vfio uinput uhid hid tun ppp_generic slhc loop cuse fuse btrfs xor raid6_pq sb_edac edac_core kvm_intel kvm irqbypass crct10dif_pclmul crc32_pclmul ghash_clmulni_intel intel_rapl_perf sg pvpanic evdev serio_raw button ip_tables x_tables autofs4 ext4 crc16 jbd2 crc32c_generic fscrypto ecb mbcache sd_mod virtio_scsi scsi_mod virtio_net crc32c_intel aesni_intel aes_x86_64 [196498.066846] glue_helper lrw gf128mul virtio_pci ablk_helper virtio_ring cryptd psmouse virtio i2c_piix4 [196498.076432] CPU: 11 PID: 39453 Comm: exe Not tainted 4.9.0-11-amd64 #1 Debian 4.9.189-3+deb9u2 [196498.085241] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 [196498.094660] task: ffff9fb7dd77d100 task.stack: ffffba1990ce4000 [196498.100789] RIP: 0010:[] [] native_queued_spin_lock_slowpath+0xf/0x1a0 [196498.110987] RSP: 0018:ffffba1990ce7bf0 EFLAGS: 00000202 [196498.117831] RAX: 0000000000000001 RBX: 0000000000000002 RCX: 00000000003ed5cd [196498.126114] RDX: 0000000000000001 RSI: 0000000000000001 RDI: ffff9fb2d6a48000 [196498.133553] RBP: ffff9faf3e981000 R08: 0000000000000007 R09: 8000000000000000 [196498.141217] R10: 00000000322e631a R11: 0000000000000001 R12: 00003fb12b6da000 [196498.148823] R13: 00000003fb12b6da R14: 0000000000000000 R15: 0000000000000000 [196498.156170] FS: 000000c0002c8090(0000) GS:ffff9fca868c0000(0000) knlGS:0000000000000000 [196498.164468] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 [196498.170551] CR2: 00007faef3ceda68 CR3: 00000016f14bc000 CR4: 0000000000162670 [196498.177915] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000 [196498.185872] DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400 [196498.193501] Stack: [196498.195812] ffffffffa141e2fd ffffffffc0908f1b ffffba1990ce7c27 ffffffffc09cf00c [196498.209893] 0000000000000000 000000000000225f 0100ffffc09cf018 0000000100000000 [196498.218866] 00000000003ed5cd 982c609cbd0a1ac5 ffff9faf3e981000 00003fb12b6da000 [196498.227298] Call Trace: [196498.230179] [] ? _raw_spin_lock+0x1d/0x20 [196498.241555] [] ? tdp_page_fault+0x22b/0x2c0 [kvm] [196498.248149] [] ? vmexit_fill_RSB+0xc/0x30 [kvm_intel] [196498.255093] [] ? kvm_mmu_page_fault+0x5f/0x120 [kvm] [196498.261923] [] ? vmx_handle_exit+0x1d1/0x1680 [kvm_intel] [196498.269188] [] ? vmexit_fill_RSB+0x18/0x30 [kvm_intel] [196498.276194] [] ? vmexit_fill_RSB+0xc/0x30 [kvm_intel] [196498.283104] [] ? vmexit_fill_RSB+0x18/0x30 [kvm_intel] [196498.290116] [] ? vmexit_fill_RSB+0xc/0x30 [kvm_intel] [196498.302521] [] ? vmexit_fill_RSB+0x18/0x30 [kvm_intel] [196498.309631] [] ? vmexit_fill_RSB+0xc/0x30 [kvm_intel] [196498.349360] [] ? vmexit_fill_RSB+0x18/0x30 [kvm_intel] [196498.356471] [] ? vmexit_fill_RSB+0xc/0x30 [kvm_intel] [196498.386596] [] ? vmx_vcpu_run+0x33f/0x4f0 [kvm_intel] [196498.393541] [] ? kvm_arch_vcpu_ioctl_run+0x801/0x16d0 [kvm] [196498.401003] [] ? kvm_arch_vcpu_load+0x61/0x290 [kvm] [196498.407855] [] ? recalc_sigpending+0x17/0x50 [196498.414017] [] ? kvm_vcpu_ioctl+0x315/0x5e0 [kvm] [196498.420587] [] ? signal_setup_done+0x67/0xb0 [196498.426852] [] ? __seccomp_filter+0x74/0x270 [196498.432990] [] ? do_vfs_ioctl+0xa2/0x620 [196498.438773] [] ? __audit_syscall_entry+0xaa/0xf0 [196498.445401] [] ? syscall_trace_enter+0x1ae/0x2c0 [196498.451883] [] ? SyS_ioctl+0x74/0x80 [196498.457318] [] ? do_syscall_64+0x8d/0x100 [196498.463276] [] ? entry_SYSCALL_64_after_swapgs+0x58/0xc6 [196498.470447] Code: 75 ce 48 8b 04 24 c6 03 01 48 c7 00 00 00 00 00 e9 90 fe ff ff 0f 1f 80 00 00 00 00 0f 1f 44 00 00 0f 1f 44 00 00 ba 01 00 00 00 <8b> 07 85 c0 75 0a f0 0f b1 17 85 c0 75 f2 f3 c3 f3 90 eb ec 81 [196498.498810] Sending NMI from CPU 11 to CPUs 0-10,12-63: [196498.504256] NMI backtrace for cpu 1 skipped: idling at pc 0xffffffffa141dc3e [196498.511531] NMI backtrace for cpu 33 [196498.515345] CPU: 33 PID: 39691 Comm: exe Not tainted 4.9.0-11-amd64 #1 Debian 4.9.189-3+deb9u2 [196498.524156] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 [196498.533573] task: ffff9fbfdfa0b140 task.stack: ffffba199f2fc000 [196498.539690] RIP: 0010:[] c [] native_queued_spin_lock_slowpath+0x21/0x1a0 [196498.549907] RSP: 0018:ffffba199f2ffbf0 EFLAGS: 00000202 [196498.555432] RAX: 0000000000000001 RBX: 0000000000000002 RCX: 0000000000b26eb2 [196498.562785] RDX: 0000000000000001 RSI: 0000000000000001 RDI: ffff9fb2d6a48000 [196498.570116] RBP: ffff9fca3da29b80 R08: 0000000000000007 R09: 8000000000000000 [196498.577448] R10: 0000000037aa5555 R11: 0000000000000001 R12: 00003fb12c143020 [196498.584778] R13: 00000003fb12c143 R14: 0000000000000000 R15: 0000000000000000 [196498.592110] FS: 000000c0006b6e90(0000) GS:ffff9fca86e40000(0000) knlGS:0000000000000000 [196498.600404] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 [196498.606355] CR2: 00007f102bf69a68 CR3: 00000016f14bc000 CR4: 0000000000162670 [196498.613820] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000 [196498.621155] DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400 [196498.628493] Stack: [196498.630704] ffffffffa141e2fdc ffffffffc0908f1bc ffffba199f2ffc27c ffffffffc09cf00cc [196498.639001] 0000000000000000c 000000000000225fc 0100ffffc09cf018c 0000000100000000c [196498.647303] 0000000000b26eb2c 47cbf6fcbbd154e0c ffff9fca3da29b80c 00003fb12c143020c [196498.655588] Call Trace: [196498.658233] [] ? _raw_spin_lock+0x1d/0x20 [196498.664100] [] ? tdp_page_fault+0x22b/0x2c0 [kvm] [196498.670656] [] ? vmexit_fill_RSB+0xc/0x30 [kvm_intel] [196498.677572] [] ? kvm_mmu_page_fault+0x5f/0x120 [kvm] [196498.684388] [] ? vmx_handle_exit+0x1d1/0x1680 [kvm_intel] [196498.691634] [] ? vmexit_fill_RSB+0x18/0x30 [kvm_intel] [196498.698721] [] ? vmexit_fill_RSB+0xc/0x30 [kvm_intel] [196498.705625] [] ? vmexit_fill_RSB+0x18/0x30 [kvm_intel] [196498.712608] [] ? vmexit_fill_RSB+0xc/0x30 [kvm_intel] [196498.719506] [] ? vmexit_fill_RSB+0x18/0x30 [kvm_intel] [196498.726505] [] ? vmexit_fill_RSB+0xc/0x30 [kvm_intel] [196498.733409] [] ? vmexit_fill_RSB+0x18/0x30 [kvm_intel] [196498.740407] [] ? vmexit_fill_RSB+0xc/0x30 [kvm_intel] [196498.747304] [] ? vmx_vcpu_run+0x33f/0x4f0 [kvm_intel] [196498.754214] [] ? kvm_arch_vcpu_ioctl_run+0x801/0x16d0 [kvm] [196498.761657] [] ? kvm_arch_vcpu_load+0x61/0x290 [kvm] [196498.768474] [] ? recalc_sigpending+0x17/0x50 [196498.774601] [] ? kvm_vcpu_ioctl+0x315/0x5e0 [kvm] [196498.781154] [] ? signal_setup_done+0x67/0xb0 [196498.787272] [] ? __seccomp_filter+0x74/0x270 [196498.793409] [] ? do_vfs_ioctl+0xa2/0x620 [196498.799183] [] ? __audit_syscall_entry+0xaa/0xf0 [196498.805650] [] ? syscall_trace_enter+0x1ae/0x2c0 [196498.812134] [] ? SyS_ioctl+0x74/0x80 [196498.817567] [] ? do_syscall_64+0x8d/0x100 [196498.823424] [] ? entry_SYSCALL_64_after_swapgs+0x58/0xc6 [196498.830594] Code: cfe cff cff c0f c1f c80 c00 c00 c00 c00 c0f c1f c44 c00 c00 c0f c1f c44 c00 c00 cba c01 c00 c00 c00 c8b c07 c85 cc0 c75 c0a cf0 c0f cb1 c17 c85 cc0 c75 cf2 cf3 cc3 cf3 c90 c cec c81 cfe c00 c01 c00 c00 c0f c84 c1c c01 c00 c00 c40 c30 cf6 c85 cf6 c75 c42 c [196498.860934] NMI backtrace for cpu 5 skipped: idling at pc 0xffffffffa141dc3e [196498.868217] NMI backtrace for cpu 41 skipped: idling at pc 0xffffffffa141dc3e [196498.875596] NMI backtrace for cpu 24 [196498.879402] CPU: 24 PID: 47509 Comm: exe Not tainted 4.9.0-11-amd64 #1 Debian 4.9.189-3+deb9u2 [196498.888221] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 [196498.897663] task: ffff9faf3d775040 task.stack: ffffba194dfa4000 [196498.903785] RIP: 0033:[<0000000001025674>] c [<0000000001025674>] 0x1025674 [196498.911037] RSP: 002b:00007fe8807f7ca0 EFLAGS: 00000206 [196498.916648] RAX: 00007fe8b028aeb0 RBX: 00007fe7cf83cb18 RCX: 0000000000000000 [196498.923985] RDX: 00007fe8b028b000 RSI: 00007fe7cf83cb18 RDI: 0000000000000000 [196498.931323] RBP: 00007fe7700309a8 R08: 00000000023d8100 R09: 0000000000000001 [196498.938661] R10: 0000000000000000 R11: 0000000000000001 R12: 00007fe8807f7cb0 [196498.946009] R13: fffffc0000000000 R14: 00007fe770030c78 R15: 000003ffffffffff [196498.953549] FS: 00007fe8807f8700(0000) GS:ffff9fca86c00000(0000) knlGS:0000000000000000 [196498.961871] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 [196498.967813] CR2: 00007ffce97d8a00 CR3: 0000001b66d92000 CR4: 0000000000162670 [196498.975143] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000 [196498.982478] DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400 [196498.989903] NMI backtrace for cpu 56 [196498.993695] CPU: 56 PID: 39148 Comm: kvm-nx-lpage-re Not tainted 4.9.0-11-amd64 #1 Debian 4.9.189-3+deb9u2 [196499.003660] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 [196499.013195] task: ffff9fc5a591e0c0 task.stack: ffffba197e894000 [196499.019431] RIP: 0010:[] c [] native_queued_spin_lock_slowpath+0x21/0x1a0 [196499.029643] RSP: 0018:ffffba197e897e28 EFLAGS: 00000202 [196499.035151] RAX: 0000000000000001 RBX: ffff9fb2d6a48000 RCX: 00003a4eb8815ba8 [196499.042482] RDX: 0000000000000001 RSI: 0000000000000001 RDI: ffff9fb2d6a48000 [196499.049816] RBP: ffff9fc5a591e0c0 R08: 0000b299f5aebbe3 R09: 0100000001000000 [196499.057145] R10: 0000000000000000 R11: 0000000102ec07ff R12: 0000000102ec0699 [196499.064479] R13: ffffba197e897e80 R14: ffff9fc5a591e0c0 R15: ffffffffc0945984 [196499.071919] FS: 0000000000000000(0000) GS:ffff9fca87400000(0000) knlGS:0000000000000000 [196499.080338] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 [196499.086294] CR2: 00006001b05b9000 CR3: 00000017b5208000 CR4: 0000000000162670 [196499.093771] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000 [196499.101343] DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400 [196499.108718] Stack: [196499.110936] ffffffffa141e2fdc ffffffffc0904264c ffff9fb2d6a4ac10c ffff9fc5a591e0c0c [196499.119438] ffff9fb2d6a48048c 0000000100000000c ffff9fc5a591e0c0c ffffffffa1419af2c [196499.127724] 0000000000000000c 0000000000000000c 0000000000000001c ffffba197e897e80c [196499.136107] Call Trace: [196499.138750] [] ? _raw_spin_lock+0x1d/0x20 [196499.144703] [] ? kvm_nx_lpage_recovery_worker+0x144/0x2c0 [kvm] [196499.152477] [] ? schedule+0x32/0x80 [196499.158096] [] ? kvm_exit+0x80/0x80 [kvm] [196499.163953] [] ? kthread+0xd9/0xf0 [196499.169211] [] ? __switch_to_asm+0x41/0x70 [196499.175151] [] ? kthread_park+0x60/0x60 [196499.180831] [] ? ret_from_fork+0x57/0x70 [196499.186608] Code: cfe cff cff c0f c1f c80 c00 c00 c00 c00 c0f c1f c44 c00 c00 c0f c1f c44 c00 c00 cba c01 c00 c00 c00 c8b c07 c85 cc0 c75 c0a cf0 c0f cb1 c17 c85 cc0 c75 cf2 cf3 cc3 cf3 c90 c cec c81 cfe c00 c01 c00 c00 c0f c84 c1c c01 c00 c00 c40 c30 cf6 c85 cf6 c75 c42 c [196499.220242] NMI backtrace for cpu 3 [196499.223949] CPU: 3 PID: 47098 Comm: exe Not tainted 4.9.0-11-amd64 #1 Debian 4.9.189-3+deb9u2 [196499.232669] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 [196499.242083] task: ffff9fb836707040 task.stack: ffffba1950358000 [196499.248205] RIP: 0010:[] c [] smp_call_function_many+0x1f2/0x250 [196499.257620] RSP: 0018:ffffba195035bca8 EFLAGS: 00000202 [196499.263129] RAX: 0000000000000003 RBX: 0000000000000200 RCX: 0000000000000012 [196499.270476] RDX: ffffda193f282c68 RSI: 0000000000000200 RDI: ffff9fca866d98c8 [196499.277806] RBP: ffff9fca866d98c8 R08: ffffffffffffffff R09: 00400000050c0000 [196499.285148] R10: 0000000000000008 R11: 0000000000000000 R12: ffff9fca866d98c0 [196499.292502] R13: ffffffffa0e6a890 R14: ffffba195035bcf0 R15: 0000000000000001 [196499.299846] FS: 00007fe8c67fc700(0000) GS:ffff9fca866c0000(0000) knlGS:0000000000000000 [196499.308131] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 [196499.314086] CR2: 00006002cb8e0000 CR3: 0000001b66d92000 CR4: 0000000000162670 [196499.321424] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000 [196499.328765] DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400 [196499.336105] Stack: [196499.338319] 0000000000019880c 0000000100000000c ffff9fb04ce04ed8c 000060019a2f1000c [196499.347563] ffff9fb04ce04ed8c ffff9fb04ce04c00c 000060019a2f0000c 0000000000000000c [196499.355855] ffffffffa0e6ad34c ffff9fb04ce04c00c 000060019a2f0000c 000060019a2f1000c [196499.364234] Call Trace: [196499.366882] [] ? native_flush_tlb_others+0x54/0x120 [196499.373612] [] ? flush_tlb_mm_range+0x92/0x140 [196499.379910] [] ? tlb_flush_mmu_tlbonly+0x5e/0xc0 [196499.386373] [] ? tlb_finish_mmu+0xf/0x40 [196499.392148] [] ? zap_page_range+0xe0/0x130 [196499.398089] [] ? __switch_to_asm+0x40/0x70 [196499.404043] [] ? __schedule+0x241/0x6f0 [196499.409810] [] ? hrtimer_start_range_ns+0x194/0x360 [196499.416537] [] ? SyS_madvise+0x3cc/0x900 [196499.422326] [] ? do_nanosleep+0x8d/0xe0 [196499.428021] [] ? do_syscall_64+0x8d/0x100 [196499.433887] [] ? entry_SYSCALL_64_after_swapgs+0x58/0xc6 [196499.441054] Code: cd2 ce8 cf3 cc4 c24 c00 c3b c05 ce1 c98 cc1 c00 c89 cc1 c0f c8d c93 cfe cff cff c48 c98 c49 c8b c14 c24 c48 c03 c14 cc5 c00 c04 c87 ca1 c8b c42 c18 ca8 c01 c74 c09 cf3 c90 c<8b> c42 c18 ca8 c01 c75 cf7 ceb cbf c0f cb6 c4c c24 c0c c48 c83 cc4 c10 c4c c89 cf2 c [196499.474355] NMI backtrace for cpu 30 skipped: idling at pc 0xffffffffa141dc3e [196499.481724] NMI backtrace for cpu 52 [196499.485522] CPU: 52 PID: 39718 Comm: exe Not tainted 4.9.0-11-amd64 #1 Debian 4.9.189-3+deb9u2 [196499.494335] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 [196499.503755] task: ffff9fc2f9bf2000 task.stack: ffffba19847f0000 [196499.509868] RIP: 0010:[] c [] smp_call_function_single+0xd3/0x130 [196499.519371] RSP: 0018:ffffba19847f3c30 EFLAGS: 00000202 [196499.524891] RAX: 0000000000000000 RBX: 000000000000002a RCX: ffffda193f883420 [196499.532221] RDX: 0000000000000001 RSI: ffffba19847f3c40 RDI: ffffba19847f3c40 [196499.539638] RBP: ffffffffc09d4a50 R08: 0000000000000002 R09: ffff9faf6e527400 [196499.546968] R10: 000000000000002b R11: ffff9faf40e21338 R12: 000000000000002a [196499.554297] R13: ffff9fb535d21dc0 R14: ffff9faf40e21300 R15: 0000000000000034 [196499.561628] FS: 000000c000620790(0000) GS:ffff9fca87300000(0000) knlGS:0000000000000000 [196499.570000] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 [196499.575941] CR2: 00007ffc68de2a00 CR3: 00000016f14bc000 CR4: 0000000000162670 [196499.584331] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000 [196499.591662] DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400 [196499.598994] Stack: [196499.601203] ffff9fc2f9bf2000c 0000000000000000c ffffda193f883420c ffffffffc09d4a50c [196499.609489] ffff9fb535d26358c 0000000000000003c 5a830a4d7bad0390c 0000000000000034c [196499.618751] ffffba19847f3d90c ffffffffc09d3e9fc 0000000000000000c 0000000000000000c [196499.627039] Call Trace: [196499.629952] [] ? update_debugctlmsr+0x20/0x20 [kvm_intel] [196499.637290] [] ? vmx_vcpu_load+0x9f/0x360 [kvm_intel] [196499.644287] [] ? futex_wait+0x223/0x250 [196499.649969] [] ? __switch_to_asm+0x41/0x70 [196499.656036] [] ? __switch_to_asm+0x35/0x70 [196499.661993] [] ? __switch_to_asm+0x41/0x70 [196499.667937] [] ? __switch_to_asm+0x35/0x70 [196499.673902] [] ? __switch_to_asm+0x41/0x70 [196499.679845] [] ? __switch_to_asm+0x35/0x70 [196499.685797] [] ? __switch_to_asm+0x41/0x70 [196499.691739] [] ? __switch_to_asm+0x35/0x70 [196499.697682] [] ? __switch_to_asm+0x41/0x70 [196499.703629] [] ? __switch_to_asm+0x35/0x70 [196499.709581] [] ? __switch_to_asm+0x41/0x70 [196499.715526] [] ? __switch_to_asm+0x35/0x70 [196499.721467] [] ? __switch_to_asm+0x41/0x70 [196499.727409] [] ? __switch_to_asm+0x35/0x70 [196499.733353] [] ? __switch_to_asm+0x41/0x70 [196499.739299] [] ? __switch_to_asm+0x35/0x70 [196499.745244] [] ? __switch_to_asm+0x41/0x70 [196499.751186] [] ? kvm_arch_vcpu_load+0x46/0x290 [kvm] [196499.758000] [] ? recalc_sigpending+0x17/0x50 [196499.764126] [] ? vcpu_load+0x3c/0x50 [kvm] [196499.770090] [] ? kvm_vcpu_ioctl+0x77/0x5e0 [kvm] [196499.776552] [] ? signal_setup_done+0x67/0xb0 [196499.782675] [] ? __seccomp_filter+0x74/0x270 [196499.788789] [] ? do_vfs_ioctl+0xa2/0x620 [196499.794556] [] ? __audit_syscall_entry+0xaa/0xf0 [196499.801017] [] ? syscall_trace_enter+0x1ae/0x2c0 [196499.807488] [] ? SyS_ioctl+0x74/0x80 [196499.812912] [] ? do_syscall_64+0x8d/0x100 [196499.818767] [] ? entry_SYSCALL_64_after_swapgs+0x58/0xc6 [196499.825938] Code: c25 c28 c00 c00 c00 c75 c73 c48 c83 cc4 c38 c5b c5d cc3 c48 c8d c74 c24 c10 c48 c89 cd1 c89 cdf c48 c89 cea ce8 c28 cfe cff cff c8b c54 c24 c28 c83 ce2 c01 c74 c0b cf3 c90 c<8b> c54 c24 c28 c83 ce2 c01 c75 cf5 ceb cc1 c8b c05 cc4 c85 cdb c00 c85 cc0 c75 c83 c [196499.856707] NMI backtrace for cpu 37 [196499.860521] CPU: 37 PID: 65501 Comm: exe Not tainted 4.9.0-11-amd64 #1 Debian 4.9.189-3+deb9u2 [196499.869765] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 [196499.879297] task: ffff9fc5a3a950c0 task.stack: ffffba194e990000 [196499.885426] RIP: 0033:[<00000000010177ab>] c [<00000000010177ab>] 0x10177ab [196499.892678] RSP: 002b:00007fed8a41cb28 EFLAGS: 00000297 [196499.898287] RAX: 00007fed7450ccd8 RBX: 00007fed7450cc58 RCX: 00007fed745113c5 [196499.905883] RDX: 000000000000fe00 RSI: 0000000000000000 RDI: 00007fed7450ccd8 [196499.913319] RBP: 0000000000000304 R08: 00007fed7451cad8 R09: 00000000000005b2 [196499.920663] R10: 0000000000000000 R11: 000000000000000b R12: 0000000000034d5b [196499.928003] R13: 00000000000005b2 R14: 0000000000000000 R15: 00007fed8a41cbe0 [196499.935351] FS: 00007fed8a41d700(0000) GS:ffff9fca86f40000(0000) knlGS:0000000000000000 [196499.943645] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 [196499.949684] CR2: 00006002eacc0000 CR3: 000000019bb22000 CR4: 0000000000162670 [196499.957023] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000 [196499.964363] DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400 [196499.971766] NMI backtrace for cpu 40 [196499.975575] CPU: 40 PID: 10785 Comm: exe Not tainted 4.9.0-11-amd64 #1 Debian 4.9.189-3+deb9u2 [196499.984818] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 [196499.994245] task: ffff9fb600394140 task.stack: ffffba197c23c000 [196500.001934] RIP: 0033:[<00000000004827fd>] c [<00000000004827fd>] 0x4827fd [196500.009108] RSP: 002b:000000c001569ca0 EFLAGS: 00000217 [196500.014619] RAX: 0000000000000000 RBX: 0000000000000001 RCX: 00000000004827d5 [196500.022041] RDX: 0000000000000000 RSI: 0000000000002a59 RDI: 000000000000001f [196500.029561] RBP: 000000c001569da0 R08: 0000000000000000 R09: 0000000000000000 [196500.036992] R10: 0000000000000000 R11: 0000000000000246 R12: 0000000000000001 [196500.044419] R13: 0000000000000001 R14: 0000000000ef9cd8 R15: 0000000000000000 [196500.052620] FS: 000000c0011ba090(0000) GS:ffff9fca87000000(0000) knlGS:0000000000000000 [196500.061012] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 [196500.066959] CR2: 00007ffce97d8a00 CR3: 00000006ee902000 CR4: 0000000000162670 [196500.074646] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000 [196500.082512] DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400 [196500.090210] NMI backtrace for cpu 8 [196500.094002] CPU: 8 PID: 39721 Comm: exe Not tainted 4.9.0-11-amd64 #1 Debian 4.9.189-3+deb9u2 [196500.102832] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 [196500.112248] task: ffff9fc397af90c0 task.stack: ffffba19a5d28000 [196500.118375] RIP: 0010:[] c [] smp_call_function_many+0x1f2/0x250 [196500.127797] RSP: 0018:ffffba19a5d2bbe0 EFLAGS: 00000202 [196500.133304] RAX: 0000000000000003 RBX: 0000000000000200 RCX: 000000000000002a [196500.140633] RDX: ffffda193f883420 RSI: 0000000000000200 RDI: ffff9fca868198c8 [196500.147966] RBP: ffff9fca868198c8 R08: ffffffff00000000 R09: 2800040080002800 [196500.155295] R10: 0000000000000008 R11: 0000000000000008 R12: ffff9fca868198c0 [196500.162632] R13: ffffffffc08da000 R14: 0000000000000000 R15: 0000000000000001 [196500.169970] FS: 000000c000620e90(0000) GS:ffff9fca86800000(0000) knlGS:0000000000000000 [196500.178262] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 [196500.184225] CR2: 0000000020006660 CR3: 00000016f14bc000 CR4: 0000000000162670 [196500.191556] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000 [196500.198888] DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400 [196500.206225] Stack: [196500.208438] 0000000000019880c 00000001c0900f40c ffffba19a5d2bc28c 0000000000000001c [196500.217596] ffff9fb2d6a48048c 00003fb02c800000c 00003fb02c821000c 0000000000000001c [196500.225886] ffffffffc08dfe3dc 2800040080002800c 0000000000000000c 0000000000000000c [196500.234183] Call Trace: [196500.236827] [] ? kvm_make_all_cpus_request+0xdd/0x110 [kvm] [196500.244264] [] ? kvm_flush_remote_tlbs+0x18/0x40 [kvm] [196500.251431] [] ? kvm_mmu_notifier_invalidate_range_start+0x56/0x80 [kvm] [196500.260111] [] ? __mmu_notifier_invalidate_range_start+0x52/0x80 [196500.267969] [] ? zap_page_range_single+0xf7/0x110 [196500.274524] [] ? unmap_mapping_range+0x116/0x140 [196500.280990] [] ? shmem_fallocate+0x30d/0x4e0 [196500.287113] [] ? vfs_fallocate+0x15a/0x220 [196500.293059] [] ? SyS_fallocate+0x43/0x80 [196500.298848] [] ? do_syscall_64+0x8d/0x100 [196500.304817] [] ? entry_SYSCALL_64_after_swapgs+0x58/0xc6 [196500.311986] Code: cd2 ce8 cf3 cc4 c24 c00 c3b c05 ce1 c98 cc1 c00 c89 cc1 c0f c8d c93 cfe cff cff c48 c98 c49 c8b c14 c24 c48 c03 c14 cc5 c00 c04 c87 ca1 c8b c42 c18 ca8 c01 c74 c09 cf3 c90 c<8b> c42 c18 ca8 c01 c75 cf7 ceb cbf c0f cb6 c4c c24 c0c c48 c83 cc4 c10 c4c c89 cf2 c [196500.342518] NMI backtrace for cpu 20 [196500.346336] CPU: 20 PID: 40448 Comm: exe Not tainted 4.9.0-11-amd64 #1 Debian 4.9.189-3+deb9u2 [196500.355155] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 [196500.367350] task: ffff9fbc1d77a100 task.stack: ffffba19a9de8000 [196500.378344] RIP: 0010:[] c [] native_queued_spin_lock_slowpath+0x21/0x1a0 [196500.388551] RSP: 0018:ffffba19a9debbf0 EFLAGS: 00000202 [196500.394072] RAX: 0000000000000001 RBX: 0000000000000002 RCX: 00000000003d4482 [196500.401419] RDX: 0000000000000001 RSI: 0000000000000001 RDI: ffff9fb2d6a48000 [196500.408753] RBP: ffff9faf3e1ca000 R08: 0000000000000007 R09: 8000000000000000 [196500.416089] R10: 00000000133c80a0 R11: 0000000000000001 R12: 00003fb13d7f8d60 [196500.423421] R13: 00000003fb13d7f8 R14: 0000000000000000 R15: 0000000000000000 [196500.430776] FS: 000000c0002c9210(0000) GS:ffff9fca86b00000(0000) knlGS:ffff80c0009db200 [196500.439075] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 [196500.445018] CR2: 00007ffc68de2a00 CR3: 00000016f14bc000 CR4: 0000000000162670 [196500.452364] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000 [196500.459784] DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400 [196500.467139] Stack: [196500.469348] ffffffffa141e2fdc ffffffffc0908f1bc ffffba19a9debc27c ffffffffc09cf00cc [196500.477808] 0000000000000000c 000000000000225fc 0100ffffc09cf018c 0000000100000000c [196500.486117] 00000000003d4482c cdb988f5365913ebc ffff9faf3e1ca000c 00003fb13d7f8d60c [196500.494408] Call Trace: [196500.497053] [] ? _raw_spin_lock+0x1d/0x20 [196500.502918] [] ? tdp_page_fault+0x22b/0x2c0 [kvm] [196500.509477] [] ? vmexit_fill_RSB+0xc/0x30 [kvm_intel] [196500.516377] [] ? kvm_mmu_page_fault+0x5f/0x120 [kvm] [196500.523191] [] ? vmx_handle_exit+0x1d1/0x1680 [kvm_intel] [196500.530541] [] ? vmexit_fill_RSB+0x18/0x30 [kvm_intel] [196500.537532] [] ? vmexit_fill_RSB+0xc/0x30 [kvm_intel] [196500.544430] [] ? vmexit_fill_RSB+0x18/0x30 [kvm_intel] [196500.551430] [] ? vmexit_fill_RSB+0xc/0x30 [kvm_intel] [196500.558330] [] ? vmexit_fill_RSB+0x18/0x30 [kvm_intel] [196500.565339] [] ? vmexit_fill_RSB+0xc/0x30 [kvm_intel] [196500.572332] [] ? vmexit_fill_RSB+0x18/0x30 [kvm_intel] [196500.579336] [] ? vmexit_fill_RSB+0xc/0x30 [kvm_intel] [196500.586251] [] ? vmx_vcpu_run+0x33f/0x4f0 [kvm_intel] [196500.593154] [] ? kvm_arch_vcpu_ioctl_run+0x801/0x16d0 [kvm] [196500.600572] [] ? kvm_arch_vcpu_load+0x61/0x290 [kvm] [196500.607382] [] ? recalc_sigpending+0x17/0x50 [196500.613498] [] ? kvm_vcpu_ioctl+0x315/0x5e0 [kvm] [196500.620161] [] ? signal_setup_done+0x67/0xb0 [196500.626280] [] ? __seccomp_filter+0x74/0x270 [196500.632405] [] ? do_vfs_ioctl+0xa2/0x620 [196500.638175] [] ? __audit_syscall_entry+0xaa/0xf0 [196500.644639] [] ? syscall_trace_enter+0x1ae/0x2c0 [196500.651107] [] ? SyS_ioctl+0x74/0x80 [196500.656529] [] ? do_syscall_64+0x8d/0x100 [196500.662390] [] ? entry_SYSCALL_64_after_swapgs+0x58/0xc6 [196500.669553] Code: cfe cff cff c0f c1f c80 c00 c00 c00 c00 c0f c1f c44 c00 c00 c0f c1f c44 c00 c00 cba c01 c00 c00 c00 c8b c07 c85 cc0 c75 c0a cf0 c0f cb1 c17 c85 cc0 c75 cf2 cf3 cc3 cf3 c90 c cec c81 cfe c00 c01 c00 c00 c0f c84 c1c c01 c00 c00 c40 c30 cf6 c85 cf6 c75 c42 c [196500.699893] NMI backtrace for cpu 23 [196500.703708] CPU: 23 PID: 56341 Comm: exe Not tainted 4.9.0-11-amd64 #1 Debian 4.9.189-3+deb9u2 [196500.712521] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 [196500.721947] task: ffff9fca75d4d080 task.stack: ffffba1964b34000 [196500.728062] RIP: 0010:[] c [] do_syscall_64+0xd1/0x100 [196500.736608] RSP: 0018:ffffba1964b37f40 EFLAGS: 00000046 [196500.742119] RAX: 0000000000000180 RBX: ffffba1964b37f58 RCX: 00000000c0000100 [196500.749450] RDX: 00000000000001c2 RSI: ffff9fca75d4dac0 RDI: ffff9fca86bd8980 [196500.756820] RBP: ffff9fca75d4d080 R08: ffff9fbd0536cc40 R09: 0000000000001000 [196500.764173] R10: 0000000000000000 R11: ffff9fbd0536c180 R12: 0000000000000000 [196500.771504] R13: 0000000000000000 R14: 0000000000000000 R15: 0000000000000000 [196500.778833] FS: 000000c001183210(0000) GS:ffff9fca86bc0000(0000) knlGS:0000000000000000 [196500.787133] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 [196500.793074] CR2: 00006001eacf0000 CR3: 000000027feec000 CR4: 0000000000162670 [196500.800403] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000 [196500.807732] DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400 [196500.815062] Stack: [196500.817297] 0000000000000000c 0000000000000000c ffffffffa141e3cec 0000000000000000c [196500.825583] 0000000000ef9cd8c 0000000000000000c 0000000000000018c 000000c001609da0c [196500.833879] 0000000000000001c 0000000000000246c 0000000000000000c 0000000000000000c [196500.842165] Call Trace: [196500.844809] [] ? entry_SYSCALL_64_after_swapgs+0x58/0xc6 [196500.851965] Code: c66 c0f c1f c44 c00 c00 c65 c48 c8b c2c c25 cc0 cfb c00 c00 c48 c8b c75 c00 cf7 cc6 c0e c18 c00 c00 c75 c20 c83 c65 c08 cf9 c0f c1f c44 c00 c00 c0f c00 c2d c5f cd7 c7f c00 c<5b> c5d cc3 ce8 c57 cf6 cff cff c48 c89 cc2 ce9 c4b cff cff cff c48 c89 cdf ce8 cd7 c [196500.882303] NMI backtrace for cpu 0 [196500.886024] CPU: 0 PID: 5932 Comm: exe Not tainted 4.9.0-11-amd64 #1 Debian 4.9.189-3+deb9u2 [196500.894668] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 [196500.904091] task: ffff9fc3061ef100 task.stack: ffffba1961e44000 [196500.910213] RIP: 0033:[<000000000102515f>] c [<000000000102515f>] 0x102515f [196500.917469] RSP: 002b:00007fecafffec80 EFLAGS: 00000246 [196500.922982] RAX: 0000000000002001 RBX: 00007fed3c081aa8 RCX: 00007fec85487000 [196500.930315] RDX: 00007fec85487200 RSI: 0000000000000000 RDI: 00007fecafffec80 [196500.937644] RBP: 00007fecafffec80 R08: 00007fed043545d8 R09: 0000000000000001 [196500.944977] R10: 0000000000000000 R11: 0000000000000001 R12: 00007fed3c081ac8 [196500.952311] R13: 00007fed043545d8 R14: fffffc0000000000 R15: 000003ffffffffff [196500.959649] FS: 00007fecaffff700(0000) GS:ffff9fca86600000(0000) knlGS:0000000000000000 [196500.967962] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 [196500.973907] CR2: 0000000000c72958 CR3: 000000019bb22000 CR4: 0000000000162670 [196500.981248] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000 [196500.988619] DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400 [196500.995960] NMI backtrace for cpu 61 [196500.999779] CPU: 61 PID: 39700 Comm: exe Not tainted 4.9.0-11-amd64 #1 Debian 4.9.189-3+deb9u2 [196501.008608] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 [196501.018034] task: ffff9fbfbb401140 task.stack: ffffba19a02dc000 [196501.024152] RIP: 0010:[] c [] native_queued_spin_lock_slowpath+0x21/0x1a0 [196501.034347] RSP: 0018:ffffba19a02dfbf0 EFLAGS: 00000202 [196501.039855] RAX: 0000000000000001 RBX: 0000000000000002 RCX: 0000000000c7fb3f [196501.047186] RDX: 0000000000000001 RSI: 0000000000000001 RDI: ffff9fb2d6a48000 [196501.055564] RBP: ffff9fc302c82180 R08: 0000000000000007 R09: 8000000000000000 [196501.062900] R10: 00000003ffffffff R11: 0400000000000000 R12: 00003fb12ca79000 [196501.070229] R13: 00000003fb12ca79 R14: 0000000000000000 R15: 0000000000000000 [196501.077568] FS: 000000c000980090(0000) GS:ffff9fca87540000(0000) knlGS:0000000000000000 [196501.085853] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 [196501.091795] CR2: 0000000000c67210 CR3: 00000016f14bc000 CR4: 0000000000162670 [196501.099124] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000 [196501.106458] DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400 [196501.113787] Stack: [196501.116005] ffffffffa141e2fdc ffffffffc0908f1bc ffffba19a02dfc27c ffffffffc09cf00cc [196501.124290] 0000000000000000c 000000000000225fc 0100ffffc09cf018c 0000000100000000c [196501.132599] 0000000000c7fb3fc df381d5320de05c4c ffff9fc302c82180c 00003fb12ca79000c [196501.140885] Call Trace: [196501.143534] [] ? _raw_spin_lock+0x1d/0x20 [196501.149394] [] ? tdp_page_fault+0x22b/0x2c0 [kvm] [196501.155955] [] ? vmexit_fill_RSB+0xc/0x30 [kvm_intel] [196501.162969] [] ? kvm_mmu_page_fault+0x5f/0x120 [kvm] [196501.169784] [] ? vmx_handle_exit+0x1d1/0x1680 [kvm_intel] [196501.177029] [] ? vmexit_fill_RSB+0x18/0x30 [kvm_intel] [196501.184020] [] ? vmexit_fill_RSB+0xc/0x30 [kvm_intel] [196501.190918] [] ? vmexit_fill_RSB+0x18/0x30 [kvm_intel] [196501.197908] [] ? vmexit_fill_RSB+0xc/0x30 [kvm_intel] [196501.204815] [] ? vmexit_fill_RSB+0x18/0x30 [kvm_intel] [196501.211798] [] ? vmexit_fill_RSB+0xc/0x30 [kvm_intel] [196501.218694] [] ? vmexit_fill_RSB+0x18/0x30 [kvm_intel] [196501.225677] [] ? vmexit_fill_RSB+0xc/0x30 [kvm_intel] [196501.232574] [] ? vmx_vcpu_run+0x33f/0x4f0 [kvm_intel] [196501.239483] [] ? kvm_arch_vcpu_ioctl_run+0x801/0x16d0 [kvm] [196501.246993] [] ? kvm_arch_vcpu_load+0x100/0x290 [kvm] [196501.253889] [] ? __switch_to_asm+0x35/0x70 [196501.259834] [] ? __switch_to_asm+0x41/0x70 [196501.265775] [] ? kvm_vcpu_ioctl+0x315/0x5e0 [kvm] [196501.272326] [] ? __switch_to_asm+0x41/0x70 [196501.278799] [] ? __switch_to_asm+0x35/0x70 [196501.284753] [] ? __switch_to_asm+0x41/0x70 [196501.290699] [] ? __switch_to_asm+0x35/0x70 [196501.296648] [] ? __seccomp_filter+0x74/0x270 [196501.302767] [] ? __switch_to+0x1ef/0x670 [196501.308540] [] ? do_vfs_ioctl+0xa2/0x620 [196501.314312] [] ? __audit_syscall_entry+0xaa/0xf0 [196501.320774] [] ? syscall_trace_enter+0x1ae/0x2c0 [196501.327238] [] ? SyS_ioctl+0x74/0x80 [196501.332659] [] ? do_syscall_64+0x8d/0x100 [196501.338602] [] ? entry_SYSCALL_64_after_swapgs+0x58/0xc6 [196501.345764] Code: cfe cff cff c0f c1f c80 c00 c00 c00 c00 c0f c1f c44 c00 c00 c0f c1f c44 c00 c00 cba c01 c00 c00 c00 c8b c07 c85 cc0 c75 c0a cf0 c0f cb1 c17 c85 cc0 c75 cf2 cf3 cc3 cf3 c90 c cec c81 cfe c00 c01 c00 c00 c0f c84 c1c c01 c00 c00 c40 c30 cf6 c85 cf6 c75 c42 c [196501.398500] NMI backtrace for cpu 29 [196501.402290] CPU: 29 PID: 57111 Comm: exe Not tainted 4.9.0-11-amd64 #1 Debian 4.9.189-3+deb9u2 [196501.411111] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 [196501.420524] task: ffff9fc2f19de0c0 task.stack: ffffba1967548000 [196501.426652] RIP: 0033:[<00007ffc7f7cca5d>] c [<00007ffc7f7cca5d>] 0x7ffc7f7cca5d [196501.434352] RSP: 002b:000000c0000f3ee8 EFLAGS: 00000246 [196501.439859] RAX: 0000000000000000 RBX: 00007ffc7f7ca080 RCX: 0000000000000017 [196501.447189] RDX: 0000000000000000 RSI: 000000c0000f3f10 RDI: 000000c0000f3ee4 [196501.454517] RBP: 000000c0000f3f00 R08: 0000000000000528 R09: 0000cd85dd729d58 [196501.461848] R10: 0002bfa06c23c262 R11: 0000000000000001 R12: 0000000005bdaaa4 [196501.469199] R13: 000000c0000f3ee4 R14: 0000000000efa234 R15: 0000000000000000 [196501.476529] FS: 000000c0000e4090(0000) GS:ffff9fca86d40000(0000) knlGS:0000000000000000 [196501.484900] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 [196501.490843] CR2: 00006000bc790000 CR3: 00000006ee902000 CR4: 0000000000162670 [196501.498172] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000 [196501.505501] DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400 [196501.512839] NMI backtrace for cpu 47 skipped: idling at pc 0xffffffffa141dc3e [196501.520216] NMI backtrace for cpu 25 [196501.524018] CPU: 25 PID: 54322 Comm: exe Not tainted 4.9.0-11-amd64 #1 Debian 4.9.189-3+deb9u2 [196501.532827] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 [196501.542243] task: ffff9fb5d6e68000 task.stack: ffffba19527f0000 [196501.548361] RIP: 0010:[] c [] migrate_task_rq_fair+0x27/0x50 [196501.558124] RSP: 0018:ffff9fca86c43eb8 EFLAGS: 00000083 [196501.563637] RAX: 00006626deffec38 RBX: ffff9fb2faa8a0c0 RCX: 0000000000000207 [196501.570970] RDX: ffffffffffffff1d RSI: 000000000000001d RDI: ffff9fb2faa8a0c0 [196501.578302] RBP: 000000000000001d R08: 0000cd85dd727a9e R09: ffffffffffffffff [196501.585723] R10: 0000000000000008 R11: 0000000000000000 R12: 000000000000001d [196501.593160] R13: 0000000000000004 R14: 0000000000000046 R15: ffff9fb2faa8a3f0 [196501.600506] FS: 000000c000726090(0000) GS:ffff9fca86c40000(0000) knlGS:0000000000000000 [196501.608792] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 [196501.614736] CR2: 00006001a4440000 CR3: 000000027feec000 CR4: 0000000000162670 [196501.622066] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000 [196501.629402] DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400 [196501.636822] Stack: [196501.639046] ffff9fb2faa8a0c0c ffffffffa0ea6648c ffff9fb2faa8a0c0c 000000000000001dc [196501.647332] ffff9fb2faa8a7e4c 0000000000000004c ffffffffa0ea6de8c 0000000000000020c [196501.655617] 0000000000018980c 0000000000000282c 75f6cd511d62e616c ffffba199e87bda0c [196501.663904] Call Trace: [196501.666549] d [] ? set_task_cpu+0x68/0x190 [196501.673099] [] ? try_to_wake_up+0x128/0x3c0 [196501.679126] [] ? __hrtimer_init+0xa0/0xa0 [196501.684980] [] ? hrtimer_wakeup+0x1e/0x30 [196501.690834] [] ? __hrtimer_run_queues+0xde/0x250 [196501.697297] [] ? hrtimer_interrupt+0x9c/0x1a0 [196501.703585] [] ? smp_apic_timer_interrupt+0x47/0x60 [196501.710341] [] ? apic_timer_interrupt+0x9e/0xb0 [196501.716720] d [] ? wait_consider_task+0x29/0xb70 [196501.723803] [] ? wait_consider_task+0x104/0xb70 [196501.730195] [] ? audit_comparator+0x1b/0x60 [196501.736227] [] ? do_wait+0x173/0x240 [196501.741649] [] ? SyS_wait4+0x87/0x110 [196501.747422] [] ? task_stopped_code+0x50/0x50 [196501.753538] [] ? do_syscall_64+0x8d/0x100 [196501.759395] [] ? entry_SYSCALL_64_after_swapgs+0x58/0xc6 [196501.766572] Code: cff cff c66 c90 c0f c1f c44 c00 c00 c53 c48 c8b c47 c10 c48 c89 cfb c48 c3d c00 c01 c00 c00 c75 c12 c48 c8b c87 cd0 c01 c00 c00 c48 c8b c40 c20 c48 c29 c87 cd0 c00 c00 c00 c<48> c8d cbb c80 c00 c00 c00 ce8 c1d cfe cff cff c48 cc7 c83 c00 c02 c00 c00 c00 c00 c [196501.796912] NMI backtrace for cpu 60 skipped: idling at pc 0xffffffffa141dc3e [196501.804290] NMI backtrace for cpu 17 [196501.808093] CPU: 17 PID: 8176 Comm: exe Not tainted 4.9.0-11-amd64 #1 Debian 4.9.189-3+deb9u2 [196501.816813] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 [196501.826226] task: ffff9faf4abfd000 task.stack: ffffba1974320000 [196501.832354] RIP: 0010:[] c [] vmx_complete_atomic_exit.constprop.93+0x5e/0x80 [kvm_intel] [196501.843948] RSP: 0018:ffffba1974323ce0 EFLAGS: 00000046 [196501.849477] RAX: 0000000080000200 RBX: ffff9fc6b5dea300 RCX: ffff9fc6b5dea300 [196501.856807] RDX: 0000000080000202 RSI: 0000000000000000 RDI: ffff9fc6b5dea300 [196501.864137] RBP: ffff9fc6b5dea300 R08: 0000000000000000 R09: 0000000000000000 [196501.871477] R10: 0000000000000000 R11: 0000000000000000 R12: 0000000000000000 [196501.878815] R13: 0000000000000000 R14: 0000000000000000 R15: 0000000000000000 [196501.886148] FS: 000000c0015ee090(0000) GS:ffff9fca86a40000(0000) knlGS:ffff80c001e5a480 [196501.894441] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 [196501.900386] CR2: 00000000000002d8 CR3: 000000022c3c8000 CR4: 0000000000162670 [196501.907718] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000 [196501.915047] DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400 [196501.922382] Stack: [196501.924592] ffffffffc09cf018c 0000000000000000c ffffffffc09e0ba7c ffff9fc6b5dea300c [196501.932877] 0000000000000000c ffff9fc6b5dea300c ffffba1974323de8c 0000000000000000c [196501.941170] 0000000000231788c 0000000000000000c 0000000000000000c ffffffffc08fa25fc [196501.949465] Call Trace: [196501.952110] [] ? vmexit_fill_RSB+0x18/0x30 [kvm_intel] [196501.959109] [] ? vmx_vcpu_run+0x337/0x4f0 [kvm_intel] [196501.966003] [] ? kvm_arch_vcpu_ioctl_run+0x6df/0x16d0 [kvm] [196501.973420] [] ? kvm_arch_vcpu_load+0x61/0x290 [kvm] [196501.980229] [] ? recalc_sigpending+0x17/0x50 [196501.986345] [] ? kvm_vcpu_ioctl+0x315/0x5e0 [kvm] [196501.992904] [] ? signal_setup_done+0x67/0xb0 [196501.999120] [] ? __seccomp_filter+0x74/0x270 [196502.005281] [] ? do_vfs_ioctl+0xa2/0x620 [196502.011048] [] ? __audit_syscall_entry+0xaa/0xf0 [196502.017535] [] ? syscall_trace_enter+0x1ae/0x2c0 [196502.024002] [] ? SyS_ioctl+0x74/0x80 [196502.029426] [] ? do_syscall_64+0x8d/0x100 [196502.035287] [] ? entry_SYSCALL_64_after_swapgs+0x58/0xc6 [196502.042445] Code: c00 c81 ce2 cff c07 c00 c80 c81 cfa c12 c03 c00 c80 c74 c29 c25 c00 c07 c00 c80 c3d c00 c02 c00 c80 c74 c06 c48 c83 cc4 c08 c5b cc3 c48 c89 cdf ce8 c14 c21 cf1 cff ccd c02 c<48> c83 cc4 c08 c48 c89 cdf c5b ce9 c15 c21 cf1 cff c48 c89 c04 c24 ce8 c4c cd7 cff c [196502.073047] NMI backtrace for cpu 59 [196502.076850] CPU: 59 PID: 63962 Comm: exe Not tainted 4.9.0-11-amd64 #1 Debian 4.9.189-3+deb9u2 [196502.085858] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 [196502.095290] task: ffff9fc4ffdef100 task.stack: ffffba1964bb4000 [196502.101416] RIP: 0010:[] c [] native_queued_spin_lock_slowpath+0xf/0x1a0 [196502.111525] RSP: 0018:ffffba1964bb7bf0 EFLAGS: 00000202 [196502.117043] RAX: 0000000000000001 RBX: 0000000000000002 RCX: 0000000000ff6052 [196502.124480] RDX: 0000000000000001 RSI: 0000000000000001 RDI: ffff9fb2d6a48000 [196502.131833] RBP: ffff9fb1b6168440 R08: 0000000000000007 R09: 8000000000000000 [196502.139169] R10: 0000000000000000 R11: 0000000000000001 R12: 00003fb12ca0b000 [196502.146503] R13: 00000003fb12ca0b R14: 0000000000000000 R15: 0000000000000000 [196502.153835] FS: 000000c0017b6410(0000) GS:ffff9fca874c0000(0000) knlGS:0000000000000000 [196502.162121] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 [196502.168690] CR2: 0000000000c68448 CR3: 00000016f14bc000 CR4: 0000000000162670 [196502.176028] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000 [196502.183368] DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400 [196502.190698] Stack: [196502.192906] ffffffffa141e2fdc ffffffffc0908f1bc ffffba1964bb7c27c ffffffffc09cf00cc [196502.201203] 0000000000000000c 000000000000225fc 0100ffffc09cf018c 0000000100000000c [196502.209488] 0000000000ff6052c ff880369fcb7f303c ffff9fb1b6168440c 00003fb12ca0b000c [196502.218121] Call Trace: [196502.220764] [] ? _raw_spin_lock+0x1d/0x20 [196502.226618] [] ? tdp_page_fault+0x22b/0x2c0 [kvm] [196502.243330] [] ? vmexit_fill_RSB+0xc/0x30 [kvm_intel] [196502.250237] [] ? kvm_mmu_page_fault+0x5f/0x120 [kvm] [196502.257053] [] ? vmx_handle_exit+0x1d1/0x1680 [kvm_intel] [196502.264303] [] ? vmexit_fill_RSB+0x18/0x30 [kvm_intel] [196502.271290] [] ? vmexit_fill_RSB+0xc/0x30 [kvm_intel] [196502.278187] [] ? vmexit_fill_RSB+0x18/0x30 [kvm_intel] [196502.285180] [] ? vmexit_fill_RSB+0xc/0x30 [kvm_intel] [196502.292097] [] ? vmexit_fill_RSB+0x18/0x30 [kvm_intel] [196502.299082] [] ? vmexit_fill_RSB+0xc/0x30 [kvm_intel] [196502.306074] [] ? vmexit_fill_RSB+0x18/0x30 [kvm_intel] [196502.313056] [] ? vmexit_fill_RSB+0xc/0x30 [kvm_intel] [196502.319960] [] ? vmx_vcpu_run+0x33f/0x4f0 [kvm_intel] [196502.326865] [] ? kvm_arch_vcpu_ioctl_run+0x801/0x16d0 [kvm] [196502.334290] [] ? kvm_arch_vcpu_load+0x61/0x290 [kvm] [196502.341198] [] ? recalc_sigpending+0x17/0x50 [196502.347314] [] ? kvm_vcpu_ioctl+0x315/0x5e0 [kvm] [196502.353873] [] ? signal_setup_done+0x67/0xb0 [196502.359986] [] ? __seccomp_filter+0x74/0x270 [196502.366099] [] ? do_vfs_ioctl+0xa2/0x620 [196502.371867] [] ? __audit_syscall_entry+0xaa/0xf0 [196502.378330] [] ? syscall_trace_enter+0x1ae/0x2c0 [196502.384792] [] ? SyS_ioctl+0x74/0x80 [196502.390226] [] ? do_syscall_64+0x8d/0x100 [196502.396079] [] ? entry_SYSCALL_64_after_swapgs+0x58/0xc6 [196502.403236] Code: c75 cce c48 c8b c04 c24 cc6 c03 c01 c48 cc7 c00 c00 c00 c00 c00 ce9 c90 cfe cff cff c0f c1f c80 c00 c00 c00 c00 c0f c1f c44 c00 c00 c0f c1f c44 c00 c00 cba c01 c00 c00 c00 c<8b> c07 c85 cc0 c75 c0a cf0 c0f cb1 c17 c85 cc0 c75 cf2 cf3 cc3 cf3 c90 ceb cec c81 c [196502.433576] NMI backtrace for cpu 55 [196502.437404] CPU: 55 PID: 39705 Comm: exe Not tainted 4.9.0-11-amd64 #1 Debian 4.9.189-3+deb9u2 [196502.446211] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 [196502.455892] task: ffff9fca771d70c0 task.stack: ffffba19a0998000 [196502.462125] RIP: 0010:[] c [] native_queued_spin_lock_slowpath+0x21/0x1a0 [196502.472324] RSP: 0018:ffffba19a099bbf0 EFLAGS: 00000202 [196502.477831] RAX: 0000000000000001 RBX: 0000000000000000 RCX: 0000000000c21918 [196502.485255] RDX: 0000000000000001 RSI: 0000000000000001 RDI: ffff9fb2d6a48000 [196502.492774] RBP: ffff9fc0b7ab1f00 R08: 0000000000000007 R09: 8000000000000000 [196502.500104] R10: 00000003ffffffff R11: 0400000000000000 R12: 00003fb13d1891d0 [196502.507435] R13: 00000003fb13d189 R14: 0000000000000000 R15: 0000000000000000 [196502.514766] FS: 000000c000a98090(0000) GS:ffff9fca873c0000(0000) knlGS:ffff80c0005c1200 [196502.523065] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 [196502.529006] CR2: 00007ffc68de2a00 CR3: 00000016f14bc000 CR4: 0000000000162670 [196502.536337] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000 [196502.543683] DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400 [196502.551026] Stack: [196502.553248] ffffffffa141e2fdc ffffffffc0908f1bc ffffba19a099bc27c ffffffffc09cf00cc [196502.561542] 0000000100000000c 000000000000225fc 0001ffffc09cf018c 0000000100000000c [196502.569944] 0000000000c21918c 27c937d4df5c0bc9c ffff9fc0b7ab1f00c 00003fb13d1891d0c [196502.578236] Call Trace: [196502.580907] [] ? _raw_spin_lock+0x1d/0x20 [196502.586772] [] ? tdp_page_fault+0x22b/0x2c0 [kvm] [196502.593438] [] ? vmexit_fill_RSB+0xc/0x30 [kvm_intel] [196502.600342] [] ? kvm_mmu_page_fault+0x5f/0x120 [kvm] [196502.607336] [] ? vmx_handle_exit+0x1d1/0x1680 [kvm_intel] [196502.614593] [] ? vmexit_fill_RSB+0x18/0x30 [kvm_intel] [196502.621586] [] ? vmexit_fill_RSB+0xc/0x30 [kvm_intel] [196502.628488] [] ? vmexit_fill_RSB+0x18/0x30 [kvm_intel] [196502.635472] [] ? vmexit_fill_RSB+0xc/0x30 [kvm_intel] [196502.642370] [] ? vmexit_fill_RSB+0x18/0x30 [kvm_intel] [196502.649354] [] ? vmexit_fill_RSB+0xc/0x30 [kvm_intel] [196502.656249] [] ? vmexit_fill_RSB+0x18/0x30 [kvm_intel] [196502.663235] [] ? vmexit_fill_RSB+0xc/0x30 [kvm_intel] [196502.670159] [] ? vmx_vcpu_run+0x33f/0x4f0 [kvm_intel] [196502.677058] [] ? kvm_arch_vcpu_ioctl_run+0x801/0x16d0 [kvm] [196502.684477] [] ? kvm_arch_vcpu_load+0x100/0x290 [kvm] [196502.691392] [] ? recalc_sigpending+0x17/0x50 [196502.697509] [] ? kvm_vcpu_ioctl+0x315/0x5e0 [kvm] [196502.704072] [] ? signal_setup_done+0x67/0xb0 [196502.710192] [] ? __seccomp_filter+0x74/0x270 [196502.716306] [] ? do_vfs_ioctl+0xa2/0x620 [196502.722087] [] ? __audit_syscall_entry+0xaa/0xf0 [196502.728581] [] ? syscall_trace_enter+0x1ae/0x2c0 [196502.735047] [] ? SyS_ioctl+0x74/0x80 [196502.740469] [] ? do_syscall_64+0x8d/0x100 [196502.746325] [] ? entry_SYSCALL_64_after_swapgs+0x58/0xc6 [196502.753505] Code: cfe cff cff c0f c1f c80 c00 c00 c00 c00 c0f c1f c44 c00 c00 c0f c1f c44 c00 c00 cba c01 c00 c00 c00 c8b c07 c85 cc0 c75 c0a cf0 c0f cb1 c17 c85 cc0 c75 cf2 cf3 cc3 cf3 c90 c cec c81 cfe c00 c01 c00 c00 c0f c84 c1c c01