D0316 08:07:16.838901 46010 sampler.go:168] Time: Adjusting syscall overhead up to 8000 I0316 08:07:16.838978 46010 calibrated_clock.go:79] CalibratedClock(Monotonic): ready D0316 08:07:16.839459 46010 sampler.go:168] Time: Adjusting syscall overhead up to 8000 I0316 08:07:16.839483 46010 calibrated_clock.go:79] CalibratedClock(Realtime): ready D0316 08:07:16.839499 46010 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:683817145893419 monotonicBaseRef:1000183364 monotonicFrequency:2299773557 realtimeReady:1 realtimeBaseCycles:683817147063095 realtimeBaseRef:1584346036839481335 realtimeFrequency:2299774285} D0316 08:07:17.838973 46010 parameters.go:238] Clock(Monotonic): error: 795 ns, adjusted frequency from 2299773557 Hz to 2299775679 Hz D0316 08:07:17.839083 46010 parameters.go:238] Clock(Realtime): error: 97 ns, adjusted frequency from 2299774285 Hz to 2299774509 Hz D0316 08:07:17.839096 46010 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:683819445642992 monotonicBaseRef:2000172935 monotonicFrequency:2299775679 realtimeReady:1 realtimeBaseCycles:683819445918125 realtimeBaseRef:1584346037839081619 realtimeFrequency:2299774509} D0316 08:07:18.838961 46010 parameters.go:238] Clock(Monotonic): error: 321 ns, adjusted frequency from 2299775679 Hz to 2299774992 Hz D0316 08:07:18.839032 46010 parameters.go:238] Clock(Realtime): error: 633 ns, adjusted frequency from 2299774509 Hz to 2299775956 Hz D0316 08:07:18.839065 46010 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:683821745402182 monotonicBaseRef:3000165765 monotonicFrequency:2299774992 realtimeReady:1 realtimeBaseCycles:683821745574743 realtimeBaseRef:1584346038839030357 realtimeFrequency:2299775956} D0316 08:07:19.838953 46010 parameters.go:238] Clock(Monotonic): error: -39 ns, adjusted frequency from 2299774992 Hz to 2299774464 Hz D0316 08:07:19.839046 46010 parameters.go:238] Clock(Realtime): error: -218 ns, adjusted frequency from 2299775956 Hz to 2299774162 Hz D0316 08:07:19.839064 46010 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:683824045142163 monotonicBaseRef:4000150541 monotonicFrequency:2299774464 realtimeReady:1 realtimeBaseCycles:683824045381622 realtimeBaseRef:1584346039839043803 realtimeFrequency:2299774162} D0316 08:07:20.838903 46010 parameters.go:238] Clock(Monotonic): error: 439 ns, adjusted frequency from 2299774464 Hz to 2299775473 Hz D0316 08:07:20.838990 46010 parameters.go:238] Clock(Realtime): error: 96 ns, adjusted frequency from 2299774162 Hz to 2299774853 Hz D0316 08:07:20.839006 46010 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:683826344813978 monotonicBaseRef:5000105906 monotonicFrequency:2299775473 realtimeReady:1 realtimeBaseCycles:683826345029021 realtimeBaseRef:1584346040838988683 realtimeFrequency:2299774853} D0316 08:07:21.839007 46010 parameters.go:238] Clock(Monotonic): error: 28 ns, adjusted frequency from 2299775473 Hz to 2299774524 Hz D0316 08:07:21.839096 46010 parameters.go:238] Clock(Realtime): error: 23 ns, adjusted frequency from 2299774853 Hz to 2299774719 Hz D0316 08:07:21.839112 46010 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:683828644826868 monotonicBaseRef:6000209140 monotonicFrequency:2299774524 realtimeReady:1 realtimeBaseCycles:683828645048088 realtimeBaseRef:1584346041839094873 realtimeFrequency:2299774719} D0316 08:07:22.862970 46010 parameters.go:238] Clock(Monotonic): error: 270 ns, adjusted frequency from 2299774524 Hz to 2299775179 Hz D0316 08:07:22.863052 46010 parameters.go:238] Clock(Realtime): error: 124 ns, adjusted frequency from 2299774719 Hz to 2299774950 Hz D0316 08:07:22.863083 46010 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:683830999712496 monotonicBaseRef:7024172838 monotonicFrequency:2299775179 realtimeReady:1 realtimeBaseCycles:683830999913243 realtimeBaseRef:1584346042863049582 realtimeFrequency:2299774950} D0316 08:07:23.839121 46010 parameters.go:238] Clock(Monotonic): error: 45 ns, adjusted frequency from 2299775179 Hz to 2299774610 Hz D0316 08:07:23.839230 46010 parameters.go:238] Clock(Realtime): error: 168 ns, adjusted frequency from 2299774950 Hz to 2299775005 Hz D0316 08:07:23.839249 46010 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:683833244630109 monotonicBaseRef:8000319390 monotonicFrequency:2299774610 realtimeReady:1 realtimeBaseCycles:683833244903819 realtimeBaseRef:1584346043839227958 realtimeFrequency:2299775005} D0316 08:07:24.838999 46010 parameters.go:238] Clock(Monotonic): error: 211 ns, adjusted frequency from 2299774610 Hz to 2299775203 Hz D0316 08:07:24.839104 46010 parameters.go:238] Clock(Realtime): error: 272 ns, adjusted frequency from 2299775005 Hz to 2299775297 Hz D0316 08:07:24.839122 46010 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:683835544128583 monotonicBaseRef:9000199319 monotonicFrequency:2299775203 realtimeReady:1 realtimeBaseCycles:683835544387388 realtimeBaseRef:1584346044839101234 realtimeFrequency:2299775297} D0316 08:07:25.839027 46010 parameters.go:238] Clock(Monotonic): error: -204 ns, adjusted frequency from 2299775203 Hz to 2299774351 Hz D0316 08:07:25.839126 46010 parameters.go:238] Clock(Realtime): error: -227 ns, adjusted frequency from 2299775297 Hz to 2299774211 Hz D0316 08:07:25.839141 46010 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:683837843965513 monotonicBaseRef:10000226159 monotonicFrequency:2299774351 realtimeReady:1 realtimeBaseCycles:683837844214229 realtimeBaseRef:1584346045839123646 realtimeFrequency:2299774211} D0316 08:07:26.839029 46010 parameters.go:238] Clock(Monotonic): error: 225 ns, adjusted frequency from 2299774351 Hz to 2299775316 Hz D0316 08:07:26.839148 46010 parameters.go:238] Clock(Realtime): error: 315 ns, adjusted frequency from 2299774211 Hz to 2299775504 Hz D0316 08:07:26.839168 46010 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:683840143658486 monotonicBaseRef:11000190773 monotonicFrequency:2299775316 realtimeReady:1 realtimeBaseCycles:683840144037682 realtimeBaseRef:1584346046839145057 realtimeFrequency:2299775504} D0316 08:07:27.838909 46010 parameters.go:238] Clock(Monotonic): error: -155 ns, adjusted frequency from 2299775316 Hz to 2299774339 Hz D0316 08:07:27.839035 46010 parameters.go:238] Clock(Realtime): error: -81 ns, adjusted frequency from 2299775504 Hz to 2299774595 Hz D0316 08:07:27.839061 46010 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:683842443249690 monotonicBaseRef:12000110716 monotonicFrequency:2299774339 realtimeReady:1 realtimeBaseCycles:683842443552745 realtimeBaseRef:1584346047839031810 realtimeFrequency:2299774595} D0316 08:07:28.839013 46010 parameters.go:238] Clock(Monotonic): error: 182 ns, adjusted frequency from 2299774339 Hz to 2299775149 Hz D0316 08:07:28.839112 46010 parameters.go:238] Clock(Realtime): error: -38 ns, adjusted frequency from 2299774595 Hz to 2299774718 Hz D0316 08:07:28.839142 46010 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:683844743209227 monotonicBaseRef:13000191244 monotonicFrequency:2299775149 realtimeReady:1 realtimeBaseCycles:683844743508566 realtimeBaseRef:1584346048839110611 realtimeFrequency:2299774718} D0316 08:07:29.839013 46010 parameters.go:238] Clock(Monotonic): error: 238 ns, adjusted frequency from 2299775149 Hz to 2299775328 Hz D0316 08:07:29.839130 46010 parameters.go:238] Clock(Realtime): error: 30 ns, adjusted frequency from 2299774718 Hz to 2299774875 Hz D0316 08:07:29.839148 46010 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:683847042993817 monotonicBaseRef:14000195349 monotonicFrequency:2299775328 realtimeReady:1 realtimeBaseCycles:683847043324237 realtimeBaseRef:1584346049839128418 realtimeFrequency:2299774875} D0316 08:07:30.838973 46010 parameters.go:238] Clock(Monotonic): error: -350 ns, adjusted frequency from 2299775328 Hz to 2299773943 Hz D0316 08:07:30.839069 46010 parameters.go:238] Clock(Realtime): error: -262 ns, adjusted frequency from 2299774875 Hz to 2299774192 Hz D0316 08:07:30.839083 46010 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:683849342727234 monotonicBaseRef:15000177125 monotonicFrequency:2299773943 realtimeReady:1 realtimeBaseCycles:683849342957993 realtimeBaseRef:1584346050839067055 realtimeFrequency:2299774192} D0316 08:07:31.839307 46010 parameters.go:238] Clock(Monotonic): error: 247 ns, adjusted frequency from 2299773943 Hz to 2299775487 Hz D0316 08:07:31.839416 46010 parameters.go:238] Clock(Realtime): error: 267 ns, adjusted frequency from 2299774192 Hz to 2299775448 Hz D0316 08:07:31.839447 46010 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:683851643214117 monotonicBaseRef:16000487129 monotonicFrequency:2299775487 realtimeReady:1 realtimeBaseCycles:683851643530506 realtimeBaseRef:1584346051839414185 realtimeFrequency:2299775448} D0316 08:07:32.838978 46010 parameters.go:238] Clock(Monotonic): error: -289 ns, adjusted frequency from 2299775487 Hz to 2299774179 Hz D0316 08:07:32.839074 46010 parameters.go:238] Clock(Realtime): error: -312 ns, adjusted frequency from 2299775448 Hz to 2299774132 Hz D0316 08:07:32.839087 46010 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:683853942285222 monotonicBaseRef:17000180846 monotonicFrequency:2299774179 realtimeReady:1 realtimeBaseCycles:683853942520489 realtimeBaseRef:1584346052839072645 realtimeFrequency:2299774132} D0316 08:07:33.840275 46010 parameters.go:238] Clock(Monotonic): error: -269 ns, adjusted frequency from 2299774179 Hz to 2299774147 Hz D0316 08:07:33.840358 46010 parameters.go:238] Clock(Realtime): error: 54 ns, adjusted frequency from 2299774132 Hz to 2299774916 Hz D0316 08:07:33.840379 46010 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:683856245019727 monotonicBaseRef:18001468070 monotonicFrequency:2299774147 realtimeReady:1 realtimeBaseCycles:683856245245710 realtimeBaseRef:1584346053840355853 realtimeFrequency:2299774916} D0316 08:07:34.839028 46010 parameters.go:238] Clock(Monotonic): error: 172 ns, adjusted frequency from 2299774147 Hz to 2299775202 Hz D0316 08:07:34.839110 46010 parameters.go:238] Clock(Realtime): error: -27 ns, adjusted frequency from 2299774916 Hz to 2299774758 Hz D0316 08:07:34.839125 46010 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:683858541948627 monotonicBaseRef:19000230884 monotonicFrequency:2299775202 realtimeReady:1 realtimeBaseCycles:683858542151122 realtimeBaseRef:1584346054839108120 realtimeFrequency:2299774758} D0316 08:07:35.838963 46010 parameters.go:238] Clock(Monotonic): error: 207 ns, adjusted frequency from 2299775202 Hz to 2299775270 Hz D0316 08:07:35.839049 46010 parameters.go:238] Clock(Realtime): error: 28 ns, adjusted frequency from 2299774758 Hz to 2299774892 Hz D0316 08:07:35.839066 46010 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:683860841573984 monotonicBaseRef:20000165727 monotonicFrequency:2299775270 realtimeReady:1 realtimeBaseCycles:683860841785607 realtimeBaseRef:1584346055839047125 realtimeFrequency:2299774892} D0316 08:07:36.838968 46010 parameters.go:238] Clock(Monotonic): error: 217 ns, adjusted frequency from 2299775270 Hz to 2299775381 Hz D0316 08:07:36.839071 46010 parameters.go:238] Clock(Realtime): error: 223 ns, adjusted frequency from 2299774892 Hz to 2299775346 Hz D0316 08:07:36.839088 46010 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:683863141356267 monotonicBaseRef:21000168776 monotonicFrequency:2299775381 realtimeReady:1 realtimeBaseCycles:683863141611185 realtimeBaseRef:1584346056839069164 realtimeFrequency:2299775346} D0316 08:07:37.841597 46010 parameters.go:238] Clock(Monotonic): error: -408 ns, adjusted frequency from 2299775381 Hz to 2299773873 Hz D0316 08:07:37.841696 46010 parameters.go:238] Clock(Realtime): error: -212 ns, adjusted frequency from 2299775346 Hz to 2299774269 Hz D0316 08:07:37.841711 46010 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:683865447173494 monotonicBaseRef:22002795922 monotonicFrequency:2299773873 realtimeReady:1 realtimeBaseCycles:683865447421886 realtimeBaseRef:1584346057841693487 realtimeFrequency:2299774269} D0316 08:07:38.841564 46010 parameters.go:238] Clock(Monotonic): error: -80 ns, adjusted frequency from 2299773873 Hz to 2299774715 Hz D0316 08:07:38.841654 46010 parameters.go:238] Clock(Realtime): error: -3 ns, adjusted frequency from 2299774269 Hz to 2299774768 Hz D0316 08:07:38.841671 46010 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:683867746880105 monotonicBaseRef:23002766674 monotonicFrequency:2299774715 realtimeReady:1 realtimeBaseCycles:683867747101334 realtimeBaseRef:1584346058841652256 realtimeFrequency:2299774768} D0316 08:07:39.839591 46010 parameters.go:238] Clock(Monotonic): error: -84 ns, adjusted frequency from 2299774715 Hz to 2299774772 Hz D0316 08:07:39.839709 46010 parameters.go:238] Clock(Realtime): error: 150 ns, adjusted frequency from 2299774768 Hz to 2299775099 Hz D0316 08:07:39.839727 46010 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:683870042106295 monotonicBaseRef:24000788860 monotonicFrequency:2299774772 realtimeReady:1 realtimeBaseCycles:683870042402632 realtimeBaseRef:1584346059839707078 realtimeFrequency:2299775099} D0316 08:07:40.838951 46010 parameters.go:238] Clock(Monotonic): error: 168 ns, adjusted frequency from 2299774772 Hz to 2299775023 Hz D0316 08:07:40.839042 46010 parameters.go:238] Clock(Realtime): error: -147 ns, adjusted frequency from 2299775099 Hz to 2299774436 Hz D0316 08:07:40.839067 46010 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:683872340417285 monotonicBaseRef:25000152370 monotonicFrequency:2299775023 realtimeReady:1 realtimeBaseCycles:683872340644597 realtimeBaseRef:1584346060839040432 realtimeFrequency:2299774436} D0316 08:07:41.839063 46010 parameters.go:238] Clock(Monotonic): error: 129 ns, adjusted frequency from 2299775023 Hz to 2299775241 Hz D0316 08:07:41.839159 46010 parameters.go:238] Clock(Realtime): error: 282 ns, adjusted frequency from 2299774436 Hz to 2299775455 Hz D0316 08:07:41.839176 46010 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:683874640338083 monotonicBaseRef:26000215756 monotonicFrequency:2299775241 realtimeReady:1 realtimeBaseCycles:683874640686777 realtimeBaseRef:1584346061839156853 realtimeFrequency:2299775455} D0316 08:07:42.839320 46010 parameters.go:238] Clock(Monotonic): error: 317 ns, adjusted frequency from 2299775241 Hz to 2299775493 Hz D0316 08:07:42.839441 46010 parameters.go:238] Clock(Realtime): error: -102 ns, adjusted frequency from 2299775455 Hz to 2299774639 Hz D0316 08:07:42.839459 46010 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:683876940816910 monotonicBaseRef:27000521692 monotonicFrequency:2299775493 realtimeReady:1 realtimeBaseCycles:683876941067079 realtimeBaseRef:1584346062839419855 realtimeFrequency:2299774639} D0316 08:07:43.839797 46010 parameters.go:238] Clock(Monotonic): error: -573 ns, adjusted frequency from 2299775493 Hz to 2299773304 Hz D0316 08:07:43.839915 46010 parameters.go:238] Clock(Realtime): error: -198 ns, adjusted frequency from 2299774639 Hz to 2299774369 Hz D0316 08:07:43.839928 46010 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:683879241689219 monotonicBaseRef:28000998615 monotonicFrequency:2299773304 realtimeReady:1 realtimeBaseCycles:683879241975019 realtimeBaseRef:1584346063839912642 realtimeFrequency:2299774369} D0316 08:07:44.838941 46010 parameters.go:238] Clock(Monotonic): error: 207 ns, adjusted frequency from 2299773304 Hz to 2299775233 Hz D0316 08:07:44.839845 46010 parameters.go:238] Clock(Realtime): error: 158 ns, adjusted frequency from 2299774369 Hz to 2299775169 Hz D0316 08:07:44.839876 46010 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:683881539491446 monotonicBaseRef:29000141540 monotonicFrequency:2299775233 realtimeReady:1 realtimeBaseCycles:683881541583420 realtimeBaseRef:1584346064839840474 realtimeFrequency:2299775169} D0316 08:07:45.839309 46010 parameters.go:238] Clock(Monotonic): error: -68 ns, adjusted frequency from 2299775233 Hz to 2299774745 Hz D0316 08:07:45.839409 46010 parameters.go:238] Clock(Realtime): error: -248 ns, adjusted frequency from 2299775169 Hz to 2299774217 Hz D0316 08:07:45.839434 46010 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:683883840111774 monotonicBaseRef:30000509008 monotonicFrequency:2299774745 realtimeReady:1 realtimeBaseCycles:683883840362396 realtimeBaseRef:1584346065839407304 realtimeFrequency:2299774217} D0316 08:07:46.838994 46010 parameters.go:238] Clock(Monotonic): error: 191 ns, adjusted frequency from 2299774745 Hz to 2299775177 Hz D0316 08:07:46.839091 46010 parameters.go:238] Clock(Realtime): error: 273 ns, adjusted frequency from 2299774217 Hz to 2299775372 Hz D0316 08:07:46.839114 46010 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:683886139135906 monotonicBaseRef:31000182622 monotonicFrequency:2299775177 realtimeReady:1 realtimeBaseCycles:683886139403222 realtimeBaseRef:1584346066839088407 realtimeFrequency:2299775372} D0316 08:07:47.840289 46010 parameters.go:238] Clock(Monotonic): error: -312 ns, adjusted frequency from 2299775177 Hz to 2299774087 Hz D0316 08:07:47.840405 46010 parameters.go:238] Clock(Realtime): error: -12 ns, adjusted frequency from 2299775372 Hz to 2299774747 Hz D0316 08:07:47.840423 46010 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:683888441917426 monotonicBaseRef:32001489855 monotonicFrequency:2299774087 realtimeReady:1 realtimeBaseCycles:683888442200797 realtimeBaseRef:1584346067840402536 realtimeFrequency:2299774747} D0316 08:07:48.840235 46010 parameters.go:238] Clock(Monotonic): error: -12 ns, adjusted frequency from 2299774087 Hz to 2299774779 Hz D0316 08:07:48.840341 46010 parameters.go:238] Clock(Realtime): error: -101 ns, adjusted frequency from 2299774747 Hz to 2299774587 Hz D0316 08:07:48.840362 46010 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:683890741562150 monotonicBaseRef:33001433604 monotonicFrequency:2299774779 realtimeReady:1 realtimeBaseCycles:683890741830395 realtimeBaseRef:1584346068840339421 realtimeFrequency:2299774587} D0316 08:07:49.839006 46010 parameters.go:238] Clock(Monotonic): error: 243 ns, adjusted frequency from 2299774779 Hz to 2299775504 Hz D0316 08:07:49.839109 46010 parameters.go:238] Clock(Realtime): error: 113 ns, adjusted frequency from 2299774587 Hz to 2299775073 Hz D0316 08:07:49.839129 46010 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:683893038517188 monotonicBaseRef:34000207509 monotonicFrequency:2299775504 realtimeReady:1 realtimeBaseCycles:683893038772127 realtimeBaseRef:1584346069839107624 realtimeFrequency:2299775073} D0316 08:07:49.957202 46010 urpc.go:577] urpc: unmarshal success. D0316 08:07:49.957267 46010 controller.go:504] containerManager.Signal &{CID:ci-gvisor-ptrace-direct-overlay-host-0 Signo:0 PID:0 Mode:Process} D0316 08:07:49.957344 46010 urpc.go:534] urpc: successfully marshalled 37 bytes. D0316 08:07:50.839983 46010 parameters.go:238] Clock(Monotonic): error: 90 ns, adjusted frequency from 2299775504 Hz to 2299774870 Hz D0316 08:07:50.840063 46010 parameters.go:238] Clock(Realtime): error: -69 ns, adjusted frequency from 2299775073 Hz to 2299774609 Hz D0316 08:07:50.840079 46010 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:683895338312317 monotonicBaseRef:35000216042 monotonicFrequency:2299774870 realtimeReady:1 realtimeBaseCycles:683895340735509 realtimeBaseRef:1584346070840059155 realtimeFrequency:2299774609} D0316 08:07:51.838926 46010 parameters.go:238] Clock(Monotonic): error: 185 ns, adjusted frequency from 2299774870 Hz to 2299775190 Hz D0316 08:07:51.839006 46010 parameters.go:238] Clock(Realtime): error: 56 ns, adjusted frequency from 2299774609 Hz to 2299774919 Hz D0316 08:07:51.839025 46010 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:683897637798863 monotonicBaseRef:36000090671 monotonicFrequency:2299775190 realtimeReady:1 realtimeBaseCycles:683897638085028 realtimeBaseRef:1584346071839004664 realtimeFrequency:2299774919} D0316 08:07:52.840056 46010 parameters.go:238] Clock(Monotonic): error: 91 ns, adjusted frequency from 2299775190 Hz to 2299775126 Hz D0316 08:07:52.840144 46010 parameters.go:238] Clock(Realtime): error: 95 ns, adjusted frequency from 2299774919 Hz to 2299775018 Hz D0316 08:07:52.840162 46010 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:683899940256876 monotonicBaseRef:37001257229 monotonicFrequency:2299775126 realtimeReady:1 realtimeBaseCycles:683899940476161 realtimeBaseRef:1584346072840142259 realtimeFrequency:2299775018} D0316 08:07:53.838921 46010 parameters.go:238] Clock(Monotonic): error: -657 ns, adjusted frequency from 2299775126 Hz to 2299773444 Hz D0316 08:07:53.839020 46010 parameters.go:238] Clock(Realtime): error: -205 ns, adjusted frequency from 2299775018 Hz to 2299774318 Hz D0316 08:07:53.839035 46010 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:683902237403414 monotonicBaseRef:38000114252 monotonicFrequency:2299773444 realtimeReady:1 realtimeBaseCycles:683902237665273 realtimeBaseRef:1584346073839017842 realtimeFrequency:2299774318} D0316 08:07:54.838889 46010 parameters.go:238] Clock(Monotonic): error: -24 ns, adjusted frequency from 2299773444 Hz to 2299774749 Hz D0316 08:07:54.839002 46010 parameters.go:238] Clock(Realtime): error: -8 ns, adjusted frequency from 2299774318 Hz to 2299774774 Hz D0316 08:07:54.839018 46010 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:683904537124783 monotonicBaseRef:39000091608 monotonicFrequency:2299774749 realtimeReady:1 realtimeBaseCycles:683904537400705 realtimeBaseRef:1584346074839000933 realtimeFrequency:2299774774} D0316 08:07:55.838952 46010 parameters.go:238] Clock(Monotonic): error: 454 ns, adjusted frequency from 2299774749 Hz to 2299775918 Hz D0316 08:07:55.839082 46010 parameters.go:238] Clock(Realtime): error: 119 ns, adjusted frequency from 2299774774 Hz to 2299775146 Hz D0316 08:07:55.839101 46010 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:683906837040898 monotonicBaseRef:40000153077 monotonicFrequency:2299775918 realtimeReady:1 realtimeBaseCycles:683906837358978 realtimeBaseRef:1584346075839080722 realtimeFrequency:2299775146} D0316 08:07:56.838970 46010 parameters.go:238] Clock(Monotonic): error: -18 ns, adjusted frequency from 2299775918 Hz to 2299774677 Hz D0316 08:07:56.839102 46010 parameters.go:238] Clock(Realtime): error: 309 ns, adjusted frequency from 2299775146 Hz to 2299775589 Hz D0316 08:07:56.839122 46010 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:683909136854939 monotonicBaseRef:41000169653 monotonicFrequency:2299774677 realtimeReady:1 realtimeBaseCycles:683909137177480 realtimeBaseRef:1584346076839099574 realtimeFrequency:2299775589} D0316 08:07:57.838989 46010 parameters.go:238] Clock(Monotonic): error: -432 ns, adjusted frequency from 2299774677 Hz to 2299773817 Hz D0316 08:07:57.839087 46010 parameters.go:238] Clock(Realtime): error: -499 ns, adjusted frequency from 2299775589 Hz to 2299773674 Hz D0316 08:07:57.839104 46010 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:683911436676159 monotonicBaseRef:42000189891 monotonicFrequency:2299773817 realtimeReady:1 realtimeBaseCycles:683911436920073 realtimeBaseRef:1584346077839085226 realtimeFrequency:2299773674} D0316 08:07:58.838998 46010 parameters.go:238] Clock(Monotonic): error: 99 ns, adjusted frequency from 2299773817 Hz to 2299774839 Hz D0316 08:07:58.839094 46010 parameters.go:238] Clock(Realtime): error: 232 ns, adjusted frequency from 2299773674 Hz to 2299775344 Hz D0316 08:07:58.839114 46010 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:683913736478898 monotonicBaseRef:43000202467 monotonicFrequency:2299774839 realtimeReady:1 realtimeBaseCycles:683913736710586 realtimeBaseRef:1584346078839092548 realtimeFrequency:2299775344} D0316 08:07:59.838954 46010 parameters.go:238] Clock(Monotonic): error: 188 ns, adjusted frequency from 2299774839 Hz to 2299775215 Hz D0316 08:07:59.839050 46010 parameters.go:238] Clock(Realtime): error: -212 ns, adjusted frequency from 2299775344 Hz to 2299774328 Hz D0316 08:07:59.839065 46010 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:683916036147096 monotonicBaseRef:44000156096 monotonicFrequency:2299775215 realtimeReady:1 realtimeBaseCycles:683916036384557 realtimeBaseRef:1584346079839048468 realtimeFrequency:2299774328} D0316 08:08:00.838947 46010 parameters.go:238] Clock(Monotonic): error: 86 ns, adjusted frequency from 2299775215 Hz to 2299774972 Hz D0316 08:08:00.839030 46010 parameters.go:238] Clock(Realtime): error: 24 ns, adjusted frequency from 2299774328 Hz to 2299774854 Hz D0316 08:08:00.839046 46010 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:683918335906762 monotonicBaseRef:45000149334 monotonicFrequency:2299774972 realtimeReady:1 realtimeBaseCycles:683918336112358 realtimeBaseRef:1584346080839028236 realtimeFrequency:2299774854} I0316 08:08:00.892653 46010 watchdog.go:277] Watchdog starting loop, tasks: 15, discount: 0s D0316 08:08:01.839088 46010 parameters.go:238] Clock(Monotonic): error: 63 ns, adjusted frequency from 2299774972 Hz to 2299774897 Hz D0316 08:08:01.839240 46010 parameters.go:238] Clock(Realtime): error: 336 ns, adjusted frequency from 2299774854 Hz to 2299775644 Hz D0316 08:08:01.839275 46010 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:683920635995692 monotonicBaseRef:46000285850 monotonicFrequency:2299774897 realtimeReady:1 realtimeBaseCycles:683920636285411 realtimeBaseRef:1584346081839201382 realtimeFrequency:2299775644} D0316 08:08:02.838959 46010 parameters.go:238] Clock(Monotonic): error: 290 ns, adjusted frequency from 2299774897 Hz to 2299775415 Hz D0316 08:08:02.839042 46010 parameters.go:238] Clock(Realtime): error: -141 ns, adjusted frequency from 2299775644 Hz to 2299774510 Hz D0316 08:08:02.839059 46010 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:683922935480934 monotonicBaseRef:47000159900 monotonicFrequency:2299775415 realtimeReady:1 realtimeBaseCycles:683922935690206 realtimeBaseRef:1584346082839040127 realtimeFrequency:2299774510} D0316 08:08:03.839895 46010 parameters.go:238] Clock(Monotonic): error: -517 ns, adjusted frequency from 2299775415 Hz to 2299773610 Hz D0316 08:08:03.840016 46010 parameters.go:238] Clock(Realtime): error: -51 ns, adjusted frequency from 2299774510 Hz to 2299774656 Hz D0316 08:08:03.840035 46010 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:683925237388622 monotonicBaseRef:48001087065 monotonicFrequency:2299773610 realtimeReady:1 realtimeBaseCycles:683925237703369 realtimeBaseRef:1584346083840013549 realtimeFrequency:2299774656} D0316 08:08:04.592921 46010 urpc.go:577] urpc: unmarshal success. D0316 08:08:04.593010 46010 controller.go:504] containerManager.Signal &{CID:ci-gvisor-ptrace-direct-overlay-host-0 Signo:0 PID:0 Mode:Process} D0316 08:08:04.593087 46010 urpc.go:534] urpc: successfully marshalled 37 bytes. D0316 08:08:04.838975 46010 parameters.go:238] Clock(Monotonic): error: 71 ns, adjusted frequency from 2299773610 Hz to 2299774833 Hz D0316 08:08:04.839080 46010 parameters.go:238] Clock(Realtime): error: -254 ns, adjusted frequency from 2299774656 Hz to 2299774175 Hz D0316 08:08:04.839104 46010 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:683927535069435 monotonicBaseRef:49000177063 monotonicFrequency:2299774833 realtimeReady:1 realtimeBaseCycles:683927535325779 realtimeBaseRef:1584346084839077698 realtimeFrequency:2299774175} D0316 08:08:05.838949 46010 parameters.go:238] Clock(Monotonic): error: -87 ns, adjusted frequency from 2299774833 Hz to 2299774683 Hz D0316 08:08:05.839047 46010 parameters.go:238] Clock(Realtime): error: 76 ns, adjusted frequency from 2299774175 Hz to 2299774927 Hz D0316 08:08:05.839062 46010 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:683929834777320 monotonicBaseRef:50000147952 monotonicFrequency:2299774683 realtimeReady:1 realtimeBaseCycles:683929835024976 realtimeBaseRef:1584346085839045095 realtimeFrequency:2299774927} D0316 08:08:06.838996 46010 parameters.go:238] Clock(Monotonic): error: 160 ns, adjusted frequency from 2299774683 Hz to 2299775004 Hz D0316 08:08:06.839099 46010 parameters.go:238] Clock(Realtime): error: 56 ns, adjusted frequency from 2299774927 Hz to 2299774925 Hz D0316 08:08:06.839116 46010 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:683932134667641 monotonicBaseRef:51000198234 monotonicFrequency:2299775004 realtimeReady:1 realtimeBaseCycles:683932134918692 realtimeBaseRef:1584346086839096747 realtimeFrequency:2299774925} D0316 08:08:07.838959 46010 parameters.go:238] Clock(Monotonic): error: 171 ns, adjusted frequency from 2299775004 Hz to 2299775219 Hz D0316 08:08:07.839049 46010 parameters.go:238] Clock(Realtime): error: -53 ns, adjusted frequency from 2299774925 Hz to 2299774661 Hz D0316 08:08:07.839064 46010 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:683934434350220 monotonicBaseRef:52000158045 monotonicFrequency:2299775219 realtimeReady:1 realtimeBaseCycles:683934434581126 realtimeBaseRef:1584346087839047833 realtimeFrequency:2299774661} D0316 08:08:08.838994 46010 parameters.go:238] Clock(Monotonic): error: -317 ns, adjusted frequency from 2299775219 Hz to 2299774197 Hz D0316 08:08:08.839116 46010 parameters.go:238] Clock(Realtime): error: 41 ns, adjusted frequency from 2299774661 Hz to 2299774919 Hz D0316 08:08:08.839133 46010 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:683936734209400 monotonicBaseRef:53000194553 monotonicFrequency:2299774197 realtimeReady:1 realtimeBaseCycles:683936734508276 realtimeBaseRef:1584346088839114139 realtimeFrequency:2299774919} D0316 08:08:09.838985 46010 parameters.go:238] Clock(Monotonic): error: 433 ns, adjusted frequency from 2299774197 Hz to 2299775811 Hz D0316 08:08:09.839104 46010 parameters.go:238] Clock(Realtime): error: 302 ns, adjusted frequency from 2299774919 Hz to 2299775525 Hz D0316 08:08:09.839131 46010 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:683939033964127 monotonicBaseRef:54000186086 monotonicFrequency:2299775811 realtimeReady:1 realtimeBaseCycles:683939034254255 realtimeBaseRef:1584346089839101555 realtimeFrequency:2299775525} D0316 08:08:10.838962 46010 parameters.go:238] Clock(Monotonic): error: -181 ns, adjusted frequency from 2299775811 Hz to 2299774382 Hz D0316 08:08:10.839061 46010 parameters.go:238] Clock(Realtime): error: -361 ns, adjusted frequency from 2299775525 Hz to 2299773940 Hz D0316 08:08:10.839077 46010 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:683941333685077 monotonicBaseRef:55000162231 monotonicFrequency:2299774382 realtimeReady:1 realtimeBaseCycles:683941333931594 realtimeBaseRef:1584346090839058861 realtimeFrequency:2299773940} D0316 08:08:11.839067 46010 parameters.go:238] Clock(Monotonic): error: -124 ns, adjusted frequency from 2299774382 Hz to 2299774446 Hz D0316 08:08:11.839156 46010 parameters.go:238] Clock(Realtime): error: 17 ns, adjusted frequency from 2299773940 Hz to 2299774819 Hz D0316 08:08:11.839177 46010 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:683943633674691 monotonicBaseRef:56000255819 monotonicFrequency:2299774446 realtimeReady:1 realtimeBaseCycles:683943633925076 realtimeBaseRef:1584346091839154323 realtimeFrequency:2299774819} D0316 08:08:12.838973 46010 parameters.go:238] Clock(Monotonic): error: 42 ns, adjusted frequency from 2299774446 Hz to 2299774738 Hz D0316 08:08:12.839071 46010 parameters.go:238] Clock(Realtime): error: 15 ns, adjusted frequency from 2299774819 Hz to 2299774879 Hz D0316 08:08:12.839086 46010 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:683945933265296 monotonicBaseRef:57000175880 monotonicFrequency:2299774738 realtimeReady:1 realtimeBaseCycles:683945933505236 realtimeBaseRef:1584346092839069680 realtimeFrequency:2299774879} D0316 08:08:13.838938 46010 parameters.go:238] Clock(Monotonic): error: -11 ns, adjusted frequency from 2299774738 Hz to 2299774823 Hz D0316 08:08:13.839033 46010 parameters.go:238] Clock(Realtime): error: 5 ns, adjusted frequency from 2299774879 Hz to 2299774865 Hz D0316 08:08:13.839049 46010 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:683948232955567 monotonicBaseRef:58000139151 monotonicFrequency:2299774823 realtimeReady:1 realtimeBaseCycles:683948233193346 realtimeBaseRef:1584346093839031950 realtimeFrequency:2299774865} D0316 08:08:14.838991 46010 parameters.go:238] Clock(Monotonic): error: 20 ns, adjusted frequency from 2299774823 Hz to 2299774857 Hz D0316 08:08:14.839105 46010 parameters.go:238] Clock(Realtime): error: 128 ns, adjusted frequency from 2299774865 Hz to 2299775079 Hz D0316 08:08:14.839127 46010 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:683950532852146 monotonicBaseRef:59000192093 monotonicFrequency:2299774857 realtimeReady:1 realtimeBaseCycles:683950533132917 realtimeBaseRef:1584346094839103568 realtimeFrequency:2299775079} D0316 08:08:15.840297 46010 parameters.go:238] Clock(Monotonic): error: -125 ns, adjusted frequency from 2299774857 Hz to 2299774679 Hz D0316 08:08:15.840384 46010 parameters.go:238] Clock(Realtime): error: -75 ns, adjusted frequency from 2299775079 Hz to 2299774604 Hz D0316 08:08:15.840409 46010 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:683952835631755 monotonicBaseRef:60001498634 monotonicFrequency:2299774679 realtimeReady:1 realtimeBaseCycles:683952835848723 realtimeBaseRef:1584346095840382270 realtimeFrequency:2299774604} D0316 08:08:16.838995 46010 parameters.go:238] Clock(Monotonic): error: 169 ns, adjusted frequency from 2299774679 Hz to 2299775220 Hz D0316 08:08:16.839078 46010 parameters.go:238] Clock(Realtime): error: -92 ns, adjusted frequency from 2299774604 Hz to 2299774592 Hz D0316 08:08:16.839094 46010 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:683955132410990 monotonicBaseRef:61000196139 monotonicFrequency:2299775220 realtimeReady:1 realtimeBaseCycles:683955132621656 realtimeBaseRef:1584346096839077067 realtimeFrequency:2299774592} D0316 08:08:17.838953 46010 parameters.go:238] Clock(Monotonic): error: -70 ns, adjusted frequency from 2299775220 Hz to 2299774531 Hz D0316 08:08:17.839052 46010 parameters.go:238] Clock(Realtime): error: -22 ns, adjusted frequency from 2299774592 Hz to 2299774762 Hz D0316 08:08:17.839067 46010 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:683957432089392 monotonicBaseRef:62000154040 monotonicFrequency:2299774531 realtimeReady:1 realtimeBaseCycles:683957432334651 realtimeBaseRef:1584346097839050283 realtimeFrequency:2299774762} D0316 08:08:18.838950 46010 parameters.go:238] Clock(Monotonic): error: -59 ns, adjusted frequency from 2299774531 Hz to 2299774769 Hz D0316 08:08:18.839051 46010 parameters.go:238] Clock(Realtime): error: 160 ns, adjusted frequency from 2299774762 Hz to 2299775105 Hz D0316 08:08:18.839082 46010 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:683959731853991 monotonicBaseRef:63000149721 monotonicFrequency:2299774769 realtimeReady:1 realtimeBaseCycles:683959732105062 realtimeBaseRef:1584346098839048391 realtimeFrequency:2299775105} D0316 08:08:19.838934 46010 parameters.go:238] Clock(Monotonic): error: -183 ns, adjusted frequency from 2299774769 Hz to 2299774501 Hz D0316 08:08:19.839011 46010 parameters.go:238] Clock(Realtime): error: -117 ns, adjusted frequency from 2299775105 Hz to 2299774489 Hz D0316 08:08:19.839026 46010 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:683962031598617 monotonicBaseRef:64000136614 monotonicFrequency:2299774501 realtimeReady:1 realtimeBaseCycles:683962031790274 realtimeBaseRef:1584346099839009303 realtimeFrequency:2299774489} D0316 08:08:20.840167 46010 parameters.go:238] Clock(Monotonic): error: 7 ns, adjusted frequency from 2299774501 Hz to 2299774944 Hz D0316 08:08:20.840266 46010 parameters.go:238] Clock(Realtime): error: -17 ns, adjusted frequency from 2299774489 Hz to 2299774747 Hz D0316 08:08:20.840298 46010 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:683964334205748 monotonicBaseRef:65001368313 monotonicFrequency:2299774944 realtimeReady:1 realtimeBaseCycles:683964334450812 realtimeBaseRef:1584346100840264229 realtimeFrequency:2299774747} D0316 08:08:21.839265 46010 parameters.go:238] Clock(Monotonic): error: 226 ns, adjusted frequency from 2299774944 Hz to 2299775351 Hz D0316 08:08:21.839346 46010 parameters.go:238] Clock(Realtime): error: 162 ns, adjusted frequency from 2299774747 Hz to 2299775147 Hz D0316 08:08:21.839362 46010 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:683966631898471 monotonicBaseRef:66000462910 monotonicFrequency:2299775351 realtimeReady:1 realtimeBaseCycles:683966632109404 realtimeBaseRef:1584346101839344071 realtimeFrequency:2299775147} D0316 08:08:22.840839 46010 parameters.go:238] Clock(Monotonic): error: -196 ns, adjusted frequency from 2299775351 Hz to 2299774329 Hz D0316 08:08:22.840926 46010 parameters.go:238] Clock(Realtime): error: -88 ns, adjusted frequency from 2299775147 Hz to 2299774579 Hz D0316 08:08:22.840942 46010 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:683968935304364 monotonicBaseRef:67002041560 monotonicFrequency:2299774329 realtimeReady:1 realtimeBaseCycles:683968935518898 realtimeBaseRef:1584346102840924376 realtimeFrequency:2299774579} D0316 08:08:23.839127 46010 parameters.go:238] Clock(Monotonic): error: 55 ns, adjusted frequency from 2299774329 Hz to 2299774786 Hz D0316 08:08:23.839201 46010 parameters.go:238] Clock(Realtime): error: -32 ns, adjusted frequency from 2299774579 Hz to 2299774769 Hz D0316 08:08:23.839220 46010 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:683971231118241 monotonicBaseRef:68000319455 monotonicFrequency:2299774786 realtimeReady:1 realtimeBaseCycles:683971231326515 realtimeBaseRef:1584346103839199440 realtimeFrequency:2299774769} D0316 08:08:24.839684 46010 parameters.go:238] Clock(Monotonic): error: 98 ns, adjusted frequency from 2299774786 Hz to 2299774865 Hz D0316 08:08:24.839762 46010 parameters.go:238] Clock(Realtime): error: 5 ns, adjusted frequency from 2299774769 Hz to 2299774855 Hz D0316 08:08:24.839778 46010 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:683973532196425 monotonicBaseRef:69000886205 monotonicFrequency:2299774865 realtimeReady:1 realtimeBaseCycles:683973532391037 realtimeBaseRef:1584346104839760257 realtimeFrequency:2299774855} D0316 08:08:25.840562 46010 parameters.go:238] Clock(Monotonic): error: 180 ns, adjusted frequency from 2299774865 Hz to 2299775185 Hz D0316 08:08:25.840643 46010 parameters.go:238] Clock(Realtime): error: 12 ns, adjusted frequency from 2299774855 Hz to 2299774825 Hz D0316 08:08:25.840658 46010 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:683975833970232 monotonicBaseRef:70001755395 monotonicFrequency:2299775185 realtimeReady:1 realtimeBaseCycles:683975834192607 realtimeBaseRef:1584346105840641523 realtimeFrequency:2299774825} D0316 08:08:26.839042 46010 parameters.go:238] Clock(Monotonic): error: -398 ns, adjusted frequency from 2299775185 Hz to 2299773989 Hz D0316 08:08:26.839119 46010 parameters.go:238] Clock(Realtime): error: -60 ns, adjusted frequency from 2299774825 Hz to 2299774651 Hz D0316 08:08:26.839132 46010 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:683978130270918 monotonicBaseRef:71000244595 monotonicFrequency:2299773989 realtimeReady:1 realtimeBaseCycles:683978130462533 realtimeBaseRef:1584346106839117504 realtimeFrequency:2299774651} D0316 08:08:27.839014 46010 parameters.go:238] Clock(Monotonic): error: 152 ns, adjusted frequency from 2299773989 Hz to 2299775139 Hz D0316 08:08:27.839102 46010 parameters.go:238] Clock(Realtime): error: 262 ns, adjusted frequency from 2299774651 Hz to 2299775440 Hz D0316 08:08:27.839122 46010 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:683980429980682 monotonicBaseRef:72000216668 monotonicFrequency:2299775139 realtimeReady:1 realtimeBaseCycles:683980430197333 realtimeBaseRef:1584346107839100175 realtimeFrequency:2299775440} D0316 08:08:28.838919 46010 parameters.go:238] Clock(Monotonic): error: -83 ns, adjusted frequency from 2299775139 Hz to 2299774618 Hz D0316 08:08:28.839020 46010 parameters.go:238] Clock(Realtime): error: -251 ns, adjusted frequency from 2299775440 Hz to 2299774216 Hz D0316 08:08:28.839040 46010 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:683982729532592 monotonicBaseRef:73000119602 monotonicFrequency:2299774618 realtimeReady:1 realtimeBaseCycles:683982729785385 realtimeBaseRef:1584346108839018694 realtimeFrequency:2299774216} D0316 08:08:29.839102 46010 parameters.go:238] Clock(Monotonic): error: 128 ns, adjusted frequency from 2299774618 Hz to 2299775112 Hz D0316 08:08:29.839203 46010 parameters.go:238] Clock(Realtime): error: 157 ns, adjusted frequency from 2299774216 Hz to 2299775195 Hz D0316 08:08:29.839224 46010 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:683985029729977 monotonicBaseRef:74000303431 monotonicFrequency:2299775112 realtimeReady:1 realtimeBaseCycles:683985029979569 realtimeBaseRef:1584346109839201306 realtimeFrequency:2299775195} D0316 08:08:30.839006 46010 parameters.go:238] Clock(Monotonic): error: -175 ns, adjusted frequency from 2299775112 Hz to 2299774518 Hz D0316 08:08:30.839093 46010 parameters.go:238] Clock(Realtime): error: -189 ns, adjusted frequency from 2299775195 Hz to 2299774391 Hz D0316 08:08:30.839133 46010 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:683987329286695 monotonicBaseRef:75000208467 monotonicFrequency:2299774518 realtimeReady:1 realtimeBaseCycles:683987329501784 realtimeBaseRef:1584346110839091303 realtimeFrequency:2299774391} D0316 08:08:31.839607 46010 parameters.go:238] Clock(Monotonic): error: 238 ns, adjusted frequency from 2299774518 Hz to 2299775383 Hz D0316 08:08:31.839702 46010 parameters.go:238] Clock(Realtime): error: 34 ns, adjusted frequency from 2299774391 Hz to 2299774882 Hz D0316 08:08:31.839718 46010 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:683989630436962 monotonicBaseRef:76000806677 monotonicFrequency:2299775383 realtimeReady:1 realtimeBaseCycles:683989630677482 realtimeBaseRef:1584346111839700626 realtimeFrequency:2299774882} D0316 08:08:32.839142 46010 parameters.go:238] Clock(Monotonic): error: 60 ns, adjusted frequency from 2299775383 Hz to 2299774993 Hz D0316 08:08:32.839237 46010 parameters.go:238] Clock(Realtime): error: 40 ns, adjusted frequency from 2299774882 Hz to 2299774902 Hz D0316 08:08:32.839264 46010 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:683991929143666 monotonicBaseRef:77000341988 monotonicFrequency:2299774993 realtimeReady:1 realtimeBaseCycles:683991929383325 realtimeBaseRef:1584346112839235780 realtimeFrequency:2299774902} D0316 08:08:33.838950 46010 parameters.go:238] Clock(Monotonic): error: -12 ns, adjusted frequency from 2299774993 Hz to 2299774899 Hz D0316 08:08:33.839083 46010 parameters.go:238] Clock(Realtime): error: 890 ns, adjusted frequency from 2299774902 Hz to 2299777010 Hz D0316 08:08:33.839105 46010 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:683994228461766 monotonicBaseRef:78000143319 monotonicFrequency:2299774899 realtimeReady:1 realtimeBaseCycles:683994228801599 realtimeBaseRef:1584346113839080709 realtimeFrequency:2299777010} D0316 08:08:34.838955 46010 parameters.go:238] Clock(Monotonic): error: -1 ns, adjusted frequency from 2299774899 Hz to 2299774855 Hz D0316 08:08:34.839117 46010 parameters.go:238] Clock(Realtime): error: -756 ns, adjusted frequency from 2299777010 Hz to 2299773051 Hz D0316 08:08:34.839137 46010 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:683996528257533 monotonicBaseRef:79000152392 monotonicFrequency:2299774855 realtimeReady:1 realtimeBaseCycles:683996528650987 realtimeBaseRef:1584346114839112180 realtimeFrequency:2299773051} D0316 08:08:35.839028 46010 parameters.go:238] Clock(Monotonic): error: -138 ns, adjusted frequency from 2299774855 Hz to 2299774392 Hz D0316 08:08:35.839125 46010 parameters.go:238] Clock(Realtime): error: -264 ns, adjusted frequency from 2299773051 Hz to 2299774205 Hz D0316 08:08:35.839163 46010 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:683998828208901 monotonicBaseRef:80000229144 monotonicFrequency:2299774392 realtimeReady:1 realtimeBaseCycles:683998828449646 realtimeBaseRef:1584346115839123315 realtimeFrequency:2299774205} D0316 08:08:36.839086 46010 parameters.go:238] Clock(Monotonic): error: -164 ns, adjusted frequency from 2299774392 Hz to 2299774435 Hz D0316 08:08:36.839192 46010 parameters.go:238] Clock(Realtime): error: 282 ns, adjusted frequency from 2299774205 Hz to 2299775456 Hz D0316 08:08:36.839211 46010 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:684001128117368 monotonicBaseRef:81000287443 monotonicFrequency:2299774435 realtimeReady:1 realtimeBaseCycles:684001128377406 realtimeBaseRef:1584346116839190084 realtimeFrequency:2299775456} D0316 08:08:37.838957 46010 parameters.go:238] Clock(Monotonic): error: 130 ns, adjusted frequency from 2299774435 Hz to 2299774983 Hz D0316 08:08:37.839069 46010 parameters.go:238] Clock(Realtime): error: -189 ns, adjusted frequency from 2299775456 Hz to 2299774358 Hz D0316 08:08:37.839092 46010 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:684003427595059 monotonicBaseRef:82000158411 monotonicFrequency:2299774983 realtimeReady:1 realtimeBaseCycles:684003427868850 realtimeBaseRef:1584346117839066588 realtimeFrequency:2299774358} D0316 08:08:38.840001 46010 parameters.go:238] Clock(Monotonic): error: 133 ns, adjusted frequency from 2299774983 Hz to 2299774994 Hz D0316 08:08:38.840086 46010 parameters.go:238] Clock(Realtime): error: -92 ns, adjusted frequency from 2299774358 Hz to 2299774587 Hz D0316 08:08:38.840101 46010 timekeeper.go:209] Updating VDSO parameters: {monotonicReady:1 monotonicBaseCycles:684005729772718 monotonicBaseRef:83001203154 monotonicFrequency:2299774994 realtimeReady:1 realtimeBaseCycles:684005729983467 realtimeBaseRef:1584346118840084191 realtimeFrequency:2299774587} I0316 08:08:39.010292 47731 main.go:305] *************************** I0316 08:08:39.010351 47731 main.go:306] Args: [/syzkaller/managers/ptrace-direct-overlay-host/current/image -root /syzkaller/managers/ptrace-direct-overlay-host/workdir/gvisor_root -watchdog-action=panic -network=none -debug -alsologtostderr -platform=ptrace -file-access=shared -network=host 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-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 /syzkaller897083833] I0316 08:08:39.010431 47731 main.go:307] Version release-20200219.0-178-g97127750289b I0316 08:08:39.010451 47731 main.go:308] PID: 47731 I0316 08:08:39.010473 47731 main.go:309] UID: 0, GID: 0 I0316 08:08:39.010498 47731 main.go:310] Configuration: I0316 08:08:39.010524 47731 main.go:311] RootDir: /syzkaller/managers/ptrace-direct-overlay-host/workdir/gvisor_root I0316 08:08:39.010550 47731 main.go:312] Platform: ptrace I0316 08:08:39.010570 47731 main.go:313] FileAccess: shared, overlay: false I0316 08:08:39.010600 47731 main.go:314] Network: host, logging: false I0316 08:08:39.010630 47731 main.go:315] Strace: false, max size: 1024, syscalls: [] I0316 08:08:39.010651 47731 main.go:316] *************************** D0316 08:08:39.010747 47731 container.go:159] Load container "/syzkaller/managers/ptrace-direct-overlay-host/workdir/gvisor_root" "ci-gvisor-ptrace-direct-overlay-host-0" D0316 08:08:39.011953 47731 container.go:592] Signal container "ci-gvisor-ptrace-direct-overlay-host-0": signal 0 D0316 08:08:39.011992 47731 sandbox.go:814] Signal sandbox "ci-gvisor-ptrace-direct-overlay-host-0" D0316 08:08:39.012005 47731 sandbox.go:318] Connecting to sandbox "ci-gvisor-ptrace-direct-overlay-host-0" D0316 08:08:39.012144 47731 urpc.go:534] urpc: successfully marshalled 118 bytes. D0316 08:08:39.012367 46010 urpc.go:577] urpc: unmarshal success. D0316 08:08:39.012472 46010 controller.go:504] containerManager.Signal &{CID:ci-gvisor-ptrace-direct-overlay-host-0 Signo:0 PID:0 Mode:Process} D0316 08:08:39.012574 46010 urpc.go:534] urpc: successfully marshalled 37 bytes. D0316 08:08:39.012679 47731 urpc.go:577] urpc: unmarshal success. D0316 08:08:39.012760 47731 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 /syzkaller897083833 D0316 08:08:39.012786 47731 exec.go:121] Exec capablities: &{PermittedCaps:274877898751 InheritableCaps:274877898751 EffectiveCaps:274877898751 BoundingCaps:274877898751 AmbientCaps:0} D0316 08:08:39.012819 47731 container.go:533] Execute in container "ci-gvisor-ptrace-direct-overlay-host-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 /syzkaller897083833 D0316 08:08:39.012840 47731 sandbox.go:283] Executing new process in container "ci-gvisor-ptrace-direct-overlay-host-0" in sandbox "ci-gvisor-ptrace-direct-overlay-host-0" D0316 08:08:39.012858 47731 sandbox.go:318] Connecting to sandbox "ci-gvisor-ptrace-direct-overlay-host-0" D0316 08:08:39.013012 47731 urpc.go:534] urpc: successfully marshalled 637 bytes. D0316 08:08:39.013127 46010 urpc.go:577] urpc: unmarshal success. D0316 08:08:39.013471 46010 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 /syzkaller897083833 D0316 08:08:39.013565 46010 transport_flipcall.go:127] send [channel @0xc00040a3c0] Twalkgetattr{FID: 1, NewFID: 5, Names: [etc]} D0316 08:08:39.013833 46010 transport_flipcall.go:234] recv [channel @0xc00040a3c0] Rlerror{Error: 2} D0316 08:08:39.013888 46010 transport_flipcall.go:127] send [channel @0xc00040a3c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.etc, Size: 1} D0316 08:08:39.014052 46010 transport_flipcall.go:234] recv [channel @0xc00040a3c0] Rlerror{Error: 95} I0316 08:08:39.016620 46010 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 /syzkaller897083833] D0316 08:08:39.016774 46010 transport_flipcall.go:127] send [channel @0xc00040a3c0] Twalkgetattr{FID: 1, NewFID: 5, Names: [syz-execprog]} D0316 08:08:39.017096 46010 transport_flipcall.go:234] recv [channel @0xc00040a3c0] 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: 1584346036, NanoSec: 976558207}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}, QIDs: [QID{Type: 0, Version: 0, Path: 7209626}]} D0316 08:08:39.017185 46010 transport_flipcall.go:127] send [channel @0xc00040a3c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syz-execprog, Size: 1} D0316 08:08:39.017345 46010 transport_flipcall.go:234] recv [channel @0xc00040a3c0] Rlerror{Error: 95} D0316 08:08:39.017459 46010 transport_flipcall.go:127] send [channel @0xc00040a3c0] Tgetattr{FID: 5, AttrMask: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime INo Size Blocks BTime Gen DataVersion}} D0316 08:08:39.021761 46010 transport_flipcall.go:234] recv [channel @0xc00040a3c0] Rgetattr{Valid: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime Size Blocks}, QID: QID{Type: 0, Version: 0, Path: 7209626}, 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: 1584346036, NanoSec: 976558207}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}} D0316 08:08:39.021824 46010 transport_flipcall.go:127] send [channel @0xc00040a3c0] Twalk{FID: 5, NewFID: 6, Names: []} D0316 08:08:39.022074 46010 transport_flipcall.go:234] recv [channel @0xc00040a3c0] Rwalk{QIDs: []} D0316 08:08:39.022118 46010 transport_flipcall.go:127] send [channel @0xc00040a3c0] Tlopen{FID: 6, Flags: ReadOnly} D0316 08:08:39.022408 46010 transport_flipcall.go:234] recv [channel @0xc00040a3c0] Rlopen{QID: QID{Type: 0, Version: 0, Path: 7209626}, IoUnit: 0, File: &{{39}}} D0316 08:08:39.026921 46010 syscalls.go:266] Allocating stack with size of 8388608 bytes D0316 08:08:39.027179 46010 loader.go:832] updated processes: map[{ci-gvisor-ptrace-direct-overlay-host-0 0}:0xc00036e2a0 {ci-gvisor-ptrace-direct-overlay-host-0 21}:0xc000774ee0] D0316 08:08:39.027334 47731 urpc.go:577] urpc: unmarshal success. D0316 08:08:39.027384 47731 container.go:580] Wait on PID 21 in container "ci-gvisor-ptrace-direct-overlay-host-0" D0316 08:08:39.027410 47731 sandbox.go:769] Waiting for PID 21 in sandbox "ci-gvisor-ptrace-direct-overlay-host-0" D0316 08:08:39.027264 46010 urpc.go:534] urpc: successfully marshalled 37 bytes. D0316 08:08:39.027423 47731 sandbox.go:318] Connecting to sandbox "ci-gvisor-ptrace-direct-overlay-host-0" D0316 08:08:39.027595 47731 urpc.go:534] urpc: successfully marshalled 101 bytes. D0316 08:08:39.027874 46010 urpc.go:577] urpc: unmarshal success. D0316 08:08:39.029330 46010 controller.go:446] containerManager.Wait D0316 08:08:39.036657 46010 task_block.go:223] [ 21] Interrupt queued D0316 08:08:39.291348 46010 task_block.go:223] [ 22] Interrupt queued D0316 08:08:39.294948 46010 task_block.go:223] [ 23] Interrupt queued D0316 08:08:39.297607 46010 task_block.go:223] [ 24] Interrupt queued D0316 08:08:39.299911 46010 task_block.go:223] [ 25] Interrupt queued D0316 08:08:39.336338 46010 urpc.go:577] urpc: unmarshal success. D0316 08:08:39.336406 46010 controller.go:504] containerManager.Signal &{CID:ci-gvisor-ptrace-direct-overlay-host-0 Signo:0 PID:0 Mode:Process} D0316 08:08:39.336476 46010 urpc.go:534] urpc: successfully marshalled 37 bytes. D0316 08:08:39.336142 46010 task_block.go:223] [ 26] Interrupt queued D0316 08:08:39.343219 46010 task_block.go:223] [ 27] Interrupt queued D0316 08:08:39.364506 46010 task_block.go:223] [ 28] Interrupt queued D0316 08:08:39.373056 46010 transport_flipcall.go:127] send [channel @0xc00040a3c0] Twalkgetattr{FID: 1, NewFID: 7, Names: [etc]} D0316 08:08:39.373421 46010 transport_flipcall.go:234] recv [channel @0xc00040a3c0] Rlerror{Error: 2} D0316 08:08:39.373550 46010 transport_flipcall.go:127] send [channel @0xc00040a3c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.etc, Size: 1} D0316 08:08:39.373731 46010 transport_flipcall.go:234] recv [channel @0xc00040a3c0] Rlerror{Error: 95} D0316 08:08:39.424480 46010 task_block.go:223] [ 29] Interrupt queued D0316 08:08:39.435484 46010 task_block.go:223] [ 30] Interrupt queued D0316 08:08:39.438555 46010 task_block.go:223] [ 31] Interrupt queued D0316 08:08:39.448148 46010 task_block.go:223] [ 32] Interrupt queued D0316 08:08:39.453739 46010 task_block.go:223] [ 33] Interrupt queued D0316 08:08:39.492325 46010 task_block.go:223] [ 34] Interrupt queued D0316 08:08:39.495660 46010 task_block.go:223] [ 35] Interrupt queued D0316 08:08:39.544552 46010 task_block.go:223] [ 36] Interrupt queued D0316 08:08:39.547402 46010 task_block.go:223] [ 37] Interrupt queued D0316 08:08:39.547948 46010 transport_flipcall.go:127] send [channel @0xc00040a3c0] Twalkgetattr{FID: 1, NewFID: 7, Names: [syzkaller897083833]} D0316 08:08:39.548365 46010 transport_flipcall.go:234] recv [channel @0xc00040a3c0] 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: 1584346118, NanoSec: 968586206}, MTime: {Sec: 1584346118, NanoSec: 968586206}, CTime: {Sec: 1584346118, NanoSec: 972586597}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}, QIDs: [QID{Type: 0, Version: 0, Path: 7209227}]} D0316 08:08:39.548443 46010 transport_flipcall.go:127] send [channel @0xc00040a3c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzkaller897083833, Size: 1} D0316 08:08:39.548566 46010 transport_flipcall.go:234] recv [channel @0xc00040a3c0] Rlerror{Error: 95} D0316 08:08:39.549318 46010 transport_flipcall.go:127] send [channel @0xc00040a3c0] Tgetattr{FID: 7, AttrMask: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime INo Size Blocks BTime Gen DataVersion}} D0316 08:08:39.549538 46010 transport_flipcall.go:234] recv [channel @0xc00040a3c0] Rgetattr{Valid: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime Size Blocks}, QID: QID{Type: 0, Version: 0, Path: 7209227}, Attr: Attr{Mode: 0o100600, UID: 0, GID: 0, NLink: 1, RDev: 0, Size: 387, BlockSize: 4096, Blocks: 8, ATime: {Sec: 1584346118, NanoSec: 968586206}, MTime: {Sec: 1584346118, NanoSec: 968586206}, CTime: {Sec: 1584346118, NanoSec: 972586597}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}} D0316 08:08:39.549593 46010 transport_flipcall.go:127] send [channel @0xc00040a3c0] Twalk{FID: 7, NewFID: 8, Names: []} D0316 08:08:39.549832 46010 transport_flipcall.go:234] recv [channel @0xc00040a3c0] Rwalk{QIDs: []} D0316 08:08:39.549862 46010 transport_flipcall.go:127] send [channel @0xc00040a3c0] Tlopen{FID: 8, Flags: ReadOnly} D0316 08:08:39.550182 46010 transport_flipcall.go:234] recv [channel @0xc00040a3c0] Rlopen{QID: QID{Type: 0, Version: 0, Path: 7209227}, IoUnit: 0, File: &{{33}}} D0316 08:08:39.551070 46010 transport_flipcall.go:127] send [channel @0xc00040a3c0] Tgetattr{FID: 8, AttrMask: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime INo Size Blocks BTime Gen DataVersion}} D0316 08:08:39.551355 46010 transport_flipcall.go:234] recv [channel @0xc00040a3c0] Rgetattr{Valid: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime Size Blocks}, QID: QID{Type: 0, Version: 0, Path: 7209227}, Attr: Attr{Mode: 0o100600, UID: 0, GID: 0, NLink: 1, RDev: 0, Size: 387, BlockSize: 4096, Blocks: 8, ATime: {Sec: 1584346118, NanoSec: 968586206}, MTime: {Sec: 1584346118, NanoSec: 968586206}, CTime: {Sec: 1584346118, NanoSec: 972586597}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}} 2020/03/16 08:08:39 parsed 1 programs D0316 08:08:39.557166 46010 task_stop.go:118] [ 32] Entering internal stop (*kernel.vforkStop)(nil) D0316 08:08:39.557293 46010 task_block.go:223] [ 38] Interrupt queued D0316 08:08:39.560368 46010 transport_flipcall.go:127] send [channel @0xc00040a3c0] Twalkgetattr{FID: 1, NewFID: 9, Names: [syz-executor]} D0316 08:08:39.560965 46010 transport_flipcall.go:234] recv [channel @0xc00040a3c0] 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: 1584346036, NanoSec: 980558599}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}, QIDs: [QID{Type: 0, Version: 0, Path: 7209656}]} D0316 08:08:39.561120 46010 transport_flipcall.go:127] send [channel @0xc00040a3c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syz-executor, Size: 1} D0316 08:08:39.561499 46010 transport_flipcall.go:234] recv [channel @0xc00040a3c0] Rlerror{Error: 95} D0316 08:08:39.562079 46010 transport_flipcall.go:127] send [channel @0xc00040a3c0] Tgetattr{FID: 9, AttrMask: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime INo Size Blocks BTime Gen DataVersion}} D0316 08:08:39.562333 46010 transport_flipcall.go:234] recv [channel @0xc00040a3c0] Rgetattr{Valid: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime Size Blocks}, QID: QID{Type: 0, Version: 0, Path: 7209656}, 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: 1584346036, NanoSec: 980558599}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}} D0316 08:08:39.562437 46010 transport_flipcall.go:127] send [channel @0xc00040a3c0] Twalk{FID: 9, NewFID: 10, Names: []} D0316 08:08:39.562694 46010 transport_flipcall.go:234] recv [channel @0xc00040a3c0] Rwalk{QIDs: []} D0316 08:08:39.562729 46010 transport_flipcall.go:127] send [channel @0xc00040a3c0] Tlopen{FID: 10, Flags: ReadOnly} D0316 08:08:39.562971 46010 transport_flipcall.go:234] recv [channel @0xc00040a3c0] Rlopen{QID: QID{Type: 0, Version: 0, Path: 7209656}, IoUnit: 0, File: &{{34}}} D0316 08:08:39.563445 46010 syscalls.go:266] [ 38] Allocating stack with size of 8388608 bytes D0316 08:08:39.563834 46010 task_stop.go:137] [ 32] Leaving internal stop (*kernel.vforkStop)(nil) D0316 08:08:39.616686 46010 transport_flipcall.go:127] send [channel @0xc00040a3c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [etc]} D0316 08:08:39.617075 46010 transport_flipcall.go:234] recv [channel @0xc00040a3c0] Rlerror{Error: 2} D0316 08:08:39.617129 46010 transport_flipcall.go:127] send [channel @0xc00040a3c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.etc, Size: 1} D0316 08:08:39.617329 46010 transport_flipcall.go:234] recv [channel @0xc00040a3c0] Rlerror{Error: 95} D0316 08:08:39.629338 46010 task_exit.go:221] [ 38] Transitioning from exit state TaskExitNone to TaskExitInitiated D0316 08:08:39.629582 46010 task_exit.go:221] [ 38] Transitioning from exit state TaskExitInitiated to TaskExitZombie D0316 08:08:39.629619 46010 task_signals.go:446] [ 21] Notified of signal 17 D0316 08:08:39.629644 46010 task_block.go:223] [ 21] Interrupt queued D0316 08:08:39.630601 46010 task_block.go:223] [ 21] Interrupt queued D0316 08:08:39.630698 46010 task_signals.go:179] [ 21] Restarting syscall 202 after errno 512: interrupted by signal 17 D0316 08:08:39.630726 46010 task_signals.go:220] [ 21] Signal 17: delivering to handler D0316 08:08:39.631325 46010 task_exit.go:221] [ 38] Transitioning from exit state TaskExitZombie to TaskExitDead D0316 08:08:39.632482 46010 task_block.go:223] [ 39] Interrupt queued D0316 08:08:39.636215 46010 transport_flipcall.go:127] send [channel @0xc00040a3c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syz-executor]} D0316 08:08:39.636717 46010 transport_flipcall.go:234] recv [channel @0xc00040a3c0] 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: 1584346036, NanoSec: 980558599}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}, QIDs: [QID{Type: 0, Version: 0, Path: 7209656}]} D0316 08:08:39.636766 46010 transport_flipcall.go:127] send [channel @0xc00040a3c0] Tclunk{FID: 11} D0316 08:08:39.636900 46010 transport_flipcall.go:234] recv [channel @0xc00040a3c0] Rclunk{} D0316 08:08:39.636957 46010 transport_flipcall.go:127] send [channel @0xc00040a3c0] Tgetattr{FID: 9, AttrMask: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime INo Size Blocks BTime Gen DataVersion}} D0316 08:08:39.637226 46010 transport_flipcall.go:234] recv [channel @0xc00040a3c0] Rgetattr{Valid: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime Size Blocks}, QID: QID{Type: 0, Version: 0, Path: 7209656}, 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: 1584346036, NanoSec: 980558599}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}} 2020/03/16 08:08:39 executed programs: 0 D0316 08:08:39.646568 46010 task_stop.go:118] [ 27] Entering internal stop (*kernel.vforkStop)(nil) D0316 08:08:39.646625 46010 task_block.go:223] [ 40] Interrupt queued D0316 08:08:39.649157 46010 transport_flipcall.go:127] send [channel @0xc00040a3c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syz-executor]} D0316 08:08:39.649865 46010 transport_flipcall.go:234] recv [channel @0xc00040a3c0] 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: 1584346036, NanoSec: 980558599}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}, QIDs: [QID{Type: 0, Version: 0, Path: 7209656}]} D0316 08:08:39.650094 46010 transport_flipcall.go:127] send [channel @0xc00040a3c0] Tclunk{FID: 11} D0316 08:08:39.650283 46010 transport_flipcall.go:234] recv [channel @0xc00040a3c0] Rclunk{} D0316 08:08:39.650374 46010 transport_flipcall.go:127] send [channel @0xc00040a3c0] Tgetattr{FID: 9, AttrMask: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime INo Size Blocks BTime Gen DataVersion}} D0316 08:08:39.650720 46010 transport_flipcall.go:234] recv [channel @0xc00040a3c0] Rgetattr{Valid: AttrMask{with: Mode NLink UID GID RDev ATime MTime CTime Size Blocks}, QID: QID{Type: 0, Version: 0, Path: 7209656}, 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: 1584346036, NanoSec: 980558599}, BTime: {Sec: 0, NanoSec: 0}, Gen: 0, DataVersion: 0}} D0316 08:08:39.652360 46010 syscalls.go:266] [ 40] Allocating stack with size of 8388608 bytes D0316 08:08:39.652538 46010 task_stop.go:137] [ 27] Leaving internal stop (*kernel.vforkStop)(nil) D0316 08:08:39.661657 46010 transport_flipcall.go:127] send [channel @0xc00040a3c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [etc]} D0316 08:08:39.662031 46010 transport_flipcall.go:234] recv [channel @0xc00040a3c0] Rlerror{Error: 2} D0316 08:08:39.662141 46010 transport_flipcall.go:127] send [channel @0xc00040a3c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.etc, Size: 1} D0316 08:08:39.662400 46010 transport_flipcall.go:234] recv [channel @0xc00040a3c0] Rlerror{Error: 95} D0316 08:08:39.678991 46010 task_block.go:223] [ 41] Interrupt queued D0316 08:08:39.683466 46010 transport_flipcall.go:127] send [channel @0xc00040a3c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0316 08:08:39.683654 46010 transport_flipcall.go:234] recv [channel @0xc00040a3c0] Rlerror{Error: 2} D0316 08:08:39.683683 46010 transport_flipcall.go:127] send [channel @0xc00040a3c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0316 08:08:39.683863 46010 transport_flipcall.go:234] recv [channel @0xc00040a3c0] Rlerror{Error: 95} D0316 08:08:39.684067 46010 transport_flipcall.go:127] send [channel @0xc00040a3c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0316 08:08:39.684247 46010 transport_flipcall.go:234] recv [channel @0xc00040a3c0] Rlerror{Error: 2} D0316 08:08:39.684296 46010 transport_flipcall.go:127] send [channel @0xc00040a3c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0316 08:08:39.684411 46010 transport_flipcall.go:234] recv [channel @0xc00040a3c0] Rlerror{Error: 95} D0316 08:08:39.685207 46010 transport_flipcall.go:127] send [channel @0xc00040a3c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0316 08:08:39.685438 46010 transport_flipcall.go:234] recv [channel @0xc00040a3c0] Rlerror{Error: 2} D0316 08:08:39.685490 46010 transport_flipcall.go:127] send [channel @0xc00040a3c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0316 08:08:39.685671 46010 transport_flipcall.go:234] recv [channel @0xc00040a3c0] Rlerror{Error: 95} D0316 08:08:39.685906 46010 transport_flipcall.go:127] send [channel @0xc00040a3c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0316 08:08:39.686077 46010 transport_flipcall.go:234] recv [channel @0xc00040a3c0] Rlerror{Error: 2} D0316 08:08:39.686104 46010 transport_flipcall.go:127] send [channel @0xc00040a3c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0316 08:08:39.686214 46010 transport_flipcall.go:234] recv [channel @0xc00040a3c0] Rlerror{Error: 95} D0316 08:08:39.686365 46010 transport_flipcall.go:127] send [channel @0xc00040a3c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0316 08:08:39.686566 46010 transport_flipcall.go:234] recv [channel @0xc00040a3c0] Rlerror{Error: 2} D0316 08:08:39.686609 46010 transport_flipcall.go:127] send [channel @0xc00040a3c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0316 08:08:39.686755 46010 transport_flipcall.go:234] recv [channel @0xc00040a3c0] Rlerror{Error: 95} D0316 08:08:39.686994 46010 transport_flipcall.go:127] send [channel @0xc00040a3c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0316 08:08:39.687240 46010 transport_flipcall.go:234] recv [channel @0xc00040a3c0] Rlerror{Error: 2} D0316 08:08:39.687266 46010 transport_flipcall.go:127] send [channel @0xc00040a3c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0316 08:08:39.687390 46010 transport_flipcall.go:234] recv [channel @0xc00040a3c0] Rlerror{Error: 95} D0316 08:08:39.687596 46010 transport_flipcall.go:127] send [channel @0xc00040a3c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0316 08:08:39.687787 46010 transport_flipcall.go:234] recv [channel @0xc00040a3c0] Rlerror{Error: 2} D0316 08:08:39.687830 46010 transport_flipcall.go:127] send [channel @0xc00040a3c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0316 08:08:39.688036 46010 transport_flipcall.go:234] recv [channel @0xc00040a3c0] Rlerror{Error: 95} D0316 08:08:39.688232 46010 transport_flipcall.go:127] send [channel @0xc00040a3c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0316 08:08:39.688382 46010 transport_flipcall.go:234] recv [channel @0xc00040a3c0] Rlerror{Error: 2} D0316 08:08:39.688408 46010 transport_flipcall.go:127] send [channel @0xc00040a3c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0316 08:08:39.688545 46010 transport_flipcall.go:234] recv [channel @0xc00040a3c0] Rlerror{Error: 95} D0316 08:08:39.688753 46010 transport_flipcall.go:127] send [channel @0xc00040a3c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0316 08:08:39.689095 46010 transport_flipcall.go:234] recv [channel @0xc00040a3c0] Rlerror{Error: 2} D0316 08:08:39.689127 46010 transport_flipcall.go:127] send [channel @0xc00040a3c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0316 08:08:39.689295 46010 transport_flipcall.go:234] recv [channel @0xc00040a3c0] Rlerror{Error: 95} D0316 08:08:39.771657 46010 transport_flipcall.go:127] send [channel @0xc00040a3c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0316 08:08:39.775716 46010 transport_flipcall.go:234] recv [channel @0xc00040a3c0] Rlerror{Error: 2} D0316 08:08:39.775781 46010 transport_flipcall.go:127] send [channel @0xc00040a3c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0316 08:08:39.775958 46010 transport_flipcall.go:234] recv [channel @0xc00040a3c0] Rlerror{Error: 95} D0316 08:08:39.776086 46010 transport_flipcall.go:127] send [channel @0xc00040a3c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0316 08:08:39.776787 46010 transport_flipcall.go:234] recv [channel @0xc00040a3c0] Rlerror{Error: 2} D0316 08:08:39.793504 46010 transport_flipcall.go:127] send [channel @0xc00040a3c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0316 08:08:39.796891 46010 transport_flipcall.go:234] recv [channel @0xc00040a3c0] Rlerror{Error: 95} D0316 08:08:39.797106 46010 transport_flipcall.go:127] send [channel @0xc00040a3c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0316 08:08:39.798096 46010 transport_flipcall.go:234] recv [channel @0xc00040a3c0] Rlerror{Error: 2} D0316 08:08:39.798139 46010 transport_flipcall.go:127] send [channel @0xc00040a3c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0316 08:08:39.801179 46010 transport_flipcall.go:234] recv [channel @0xc00040a3c0] Rlerror{Error: 95} D0316 08:08:39.801434 46010 transport_flipcall.go:127] send [channel @0xc00040a3c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0316 08:08:39.803927 46010 transport_flipcall.go:234] recv [channel @0xc00040a3c0] Rlerror{Error: 2} D0316 08:08:39.803971 46010 transport_flipcall.go:127] send [channel @0xc00040a3c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0316 08:08:39.806363 46010 transport_flipcall.go:234] recv [channel @0xc00040a3c0] Rlerror{Error: 95} D0316 08:08:39.806507 46010 transport_flipcall.go:127] send [channel @0xc00040a3c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0316 08:08:39.808715 46010 transport_flipcall.go:234] recv [channel @0xc00040a3c0] Rlerror{Error: 2} D0316 08:08:39.808760 46010 transport_flipcall.go:127] send [channel @0xc00040a3c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0316 08:08:39.808909 46010 transport_flipcall.go:234] recv [channel @0xc00040a3c0] Rlerror{Error: 95} D0316 08:08:39.809144 46010 transport_flipcall.go:127] send [channel @0xc00040a3c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0316 08:08:39.809347 46010 transport_flipcall.go:234] recv [channel @0xc00040a3c0] Rlerror{Error: 2} D0316 08:08:39.809411 46010 transport_flipcall.go:127] send [channel @0xc00040a3c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0316 08:08:39.809642 46010 transport_flipcall.go:234] recv [channel @0xc00040a3c0] Rlerror{Error: 95} D0316 08:08:39.809833 46010 transport_flipcall.go:127] send [channel @0xc00040a3c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0316 08:08:39.809995 46010 transport_flipcall.go:234] recv [channel @0xc00040a3c0] Rlerror{Error: 2} D0316 08:08:39.810047 46010 transport_flipcall.go:127] send [channel @0xc00040a3c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0316 08:08:39.810206 46010 transport_flipcall.go:234] recv [channel @0xc00040a3c0] Rlerror{Error: 95} D0316 08:08:39.810329 46010 transport_flipcall.go:127] send [channel @0xc00040a3c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0316 08:08:39.810505 46010 transport_flipcall.go:234] recv [channel @0xc00040a3c0] Rlerror{Error: 2} D0316 08:08:39.810562 46010 transport_flipcall.go:127] send [channel @0xc00040a3c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0316 08:08:39.810705 46010 transport_flipcall.go:234] recv [channel @0xc00040a3c0] Rlerror{Error: 95} D0316 08:08:39.810829 46010 transport_flipcall.go:127] send [channel @0xc00040a3c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0316 08:08:39.811007 46010 transport_flipcall.go:234] recv [channel @0xc00040a3c0] Rlerror{Error: 2} D0316 08:08:39.811035 46010 transport_flipcall.go:127] send [channel @0xc00040a3c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0316 08:08:39.811150 46010 transport_flipcall.go:234] recv [channel @0xc00040a3c0] Rlerror{Error: 95} D0316 08:08:39.811273 46010 transport_flipcall.go:127] send [channel @0xc00040a3c0] Twalkgetattr{FID: 1, NewFID: 11, Names: [syzcgroup]} D0316 08:08:39.811475 46010 transport_flipcall.go:234] recv [channel @0xc00040a3c0] Rlerror{Error: 2} D0316 08:08:39.811512 46010 transport_flipcall.go:127] send [channel @0xc00040a3c0] Tgetxattr{FID: 1, Name: trusted.overlay.whiteout.syzcgroup, Size: 1} D0316 08:08:39.811627 46010 transport_flipcall.go:234] recv [channel @0xc00040a3c0] Rlerror{Error: 95} D0316 08:08:39.825518 46010 task_block.go:223] [ 42] Interrupt queued panic: Start 0x561d994dafd6 + offset 0x561d994db000 overflows? goroutine 112 [running]: DIAGNOSIS: I0316 08:08:39.895472 47835 main.go:305] *************************** I0316 08:08:39.895530 47835 main.go:306] Args: [/syzkaller/managers/ptrace-direct-overlay-host/current/image -root /syzkaller/managers/ptrace-direct-overlay-host/workdir/gvisor_root -watchdog-action=panic -network=none -debug -alsologtostderr -platform=ptrace -file-access=shared -network=host debug -stacks --ps ci-gvisor-ptrace-direct-overlay-host-0] I0316 08:08:39.895575 47835 main.go:307] Version release-20200219.0-178-g97127750289b I0316 08:08:39.895596 47835 main.go:308] PID: 47835 I0316 08:08:39.895618 47835 main.go:309] UID: 0, GID: 0 I0316 08:08:39.895642 47835 main.go:310] Configuration: I0316 08:08:39.895667 47835 main.go:311] RootDir: /syzkaller/managers/ptrace-direct-overlay-host/workdir/gvisor_root I0316 08:08:39.895690 47835 main.go:312] Platform: ptrace I0316 08:08:39.895710 47835 main.go:313] FileAccess: shared, overlay: false I0316 08:08:39.895741 47835 main.go:314] Network: host, logging: false I0316 08:08:39.895763 47835 main.go:315] Strace: false, max size: 1024, syscalls: [] I0316 08:08:39.895784 47835 main.go:316] *************************** D0316 08:08:39.895841 47835 container.go:159] Load container "/syzkaller/managers/ptrace-direct-overlay-host/workdir/gvisor_root" "ci-gvisor-ptrace-direct-overlay-host-0" D0316 08:08:39.897302 47835 container.go:592] Signal container "ci-gvisor-ptrace-direct-overlay-host-0": signal 0 D0316 08:08:39.897391 47835 sandbox.go:814] Signal sandbox "ci-gvisor-ptrace-direct-overlay-host-0" D0316 08:08:39.897426 47835 sandbox.go:318] Connecting to sandbox "ci-gvisor-ptrace-direct-overlay-host-0" D0316 08:08:39.897544 47835 urpc.go:534] urpc: successfully marshalled 118 bytes. I0316 08:08:40.053792 47835 debug.go:128] Found sandbox "ci-gvisor-ptrace-direct-overlay-host-0", PID: 46010 I0316 08:08:40.053841 47835 debug.go:137] Retrieving sandbox stacks D0316 08:08:40.053865 47835 sandbox.go:932] Stacks sandbox "ci-gvisor-ptrace-direct-overlay-host-0" D0316 08:08:40.053888 47835 sandbox.go:318] Connecting to sandbox "ci-gvisor-ptrace-direct-overlay-host-0" W0316 08:08:40.053927 47835 error.go:48] FATAL ERROR: retrieving stacks: connecting to control server at PID 46010: connection refused retrieving stacks: connecting to control server at PID 46010: connection refused W0316 08:08:40.054044 47835 main.go:338] Failure to execute command, err: 1 Error collecting stacks: failed to run ["/syzkaller/managers/ptrace-direct-overlay-host/current/image" "-root" "/syzkaller/managers/ptrace-direct-overlay-host/workdir/gvisor_root" "-watchdog-action=panic" "-network=none" "-debug" "-alsologtostderr" "-platform=ptrace" "-file-access=shared" "-network=host" "debug" "-stacks" "--ps" "ci-gvisor-ptrace-direct-overlay-host-0"]: exit status 128 I0316 08:08:39.895472 47835 main.go:305] *************************** I0316 08:08:39.895530 47835 main.go:306] Args: [/syzkaller/managers/ptrace-direct-overlay-host/current/image -root /syzkaller/managers/ptrace-direct-overlay-host/workdir/gvisor_root -watchdog-action=panic -network=none -debug -alsologtostderr -platform=ptrace -file-access=shared -network=host debug -stacks --ps ci-gvisor-ptrace-direct-overlay-host-0] I0316 08:08:39.895575 47835 main.go:307] Version release-20200219.0-178-g97127750289b I0316 08:08:39.895596 47835 main.go:308] PID: 47835 I0316 08:08:39.895618 47835 main.go:309] UID: 0, GID: 0 I0316 08:08:39.895642 47835 main.go:310] Configuration: I0316 08:08:39.895667 47835 main.go:311] RootDir: /syzkaller/managers/ptrace-direct-overlay-host/workdir/gvisor_root I0316 08:08:39.895690 47835 main.go:312] Platform: ptrace I0316 08:08:39.895710 47835 main.go:313] FileAccess: shared, overlay: false I0316 08:08:39.895741 47835 main.go:314] Network: host, logging: false I0316 08:08:39.895763 47835 main.go:315] Strace: false, max size: 1024, syscalls: [] I0316 08:08:39.895784 47835 main.go:316] *************************** D0316 08:08:39.895841 47835 container.go:159] Load container "/syzkaller/managers/ptrace-direct-overlay-host/workdir/gvisor_root" "ci-gvisor-ptrace-direct-overlay-host-0" D0316 08:08:39.897302 47835 container.go:592] Signal container "ci-gvisor-ptrace-direct-overlay-host-0": signal 0 D0316 08:08:39.897391 47835 sandbox.go:814] Signal sandbox "ci-gvisor-ptrace-direct-overlay-host-0" D0316 08:08:39.897426 47835 sandbox.go:318] Connecting to sandbox "ci-gvisor-ptrace-direct-overlay-host-0" D0316 08:08:39.897544 47835 urpc.go:534] urpc: successfully marshalled 118 bytes. I0316 08:08:40.053792 47835 debug.go:128] Found sandbox "ci-gvisor-ptrace-direct-overlay-host-0", PID: 46010 I0316 08:08:40.053841 47835 debug.go:137] Retrieving sandbox stacks D0316 08:08:40.053865 47835 sandbox.go:932] Stacks sandbox "ci-gvisor-ptrace-direct-overlay-host-0" D0316 08:08:40.053888 47835 sandbox.go:318] Connecting to sandbox "ci-gvisor-ptrace-direct-overlay-host-0" W0316 08:08:40.053927 47835 error.go:48] FATAL ERROR: retrieving stacks: connecting to control server at PID 46010: connection refused retrieving stacks: connecting to control server at PID 46010: connection refused W0316 08:08:40.054044 47835 main.go:338] Failure to execute command, err: 1 [81582.955693] lowmem_reserve[]: 0 0 117862 117862 117862 [81582.961821] Node 0 Normal free:65900kB min:65908kB low:186596kB high:307284kB active_anon:118563480kB inactive_anon:971292kB active_file:2288kB inactive_file:0kB unevictable:0kB writepending:0kB present:122683392kB managed:120696220kB mlocked:0kB slab_reclaimable:39016kB slab_unreclaimable:171952kB kernel_stack:136356kB pagetables:260492kB bounce:0kB free_pcp:0kB local_pcp:0kB free_cma:0kB [81582.997481] lowmem_reserve[]: 0 0 0 0 0 [81583.004015] Node 0 DMA: 1*4kB (U) 0*8kB 0*16kB 1*32kB (U) 2*64kB (U) 1*128kB (U) 1*256kB (U) 0*512kB 1*1024kB (U) 1*2048kB (M) 3*4096kB (M) = 15908kB [81583.020860] Node 0 DMA32: 747*4kB (UME) 615*8kB (UME) 1262*16kB (UME) 344*32kB (UMEH) 100*64kB (UMEH) 64*128kB (UMH) 41*256kB (UMEH) 19*512kB (UMEH) 8*1024kB (UME) 9*2048kB (UME) 91*4096kB (M) = 473284kB [81583.042508] Node 0 Normal: 16944*4kB (UME) 8*8kB (UM) 0*16kB 0*32kB 0*64kB 0*128kB 0*256kB 0*512kB 0*1024kB 0*2048kB 0*4096kB = 67840kB [81583.057167] Node 0 hugepages_total=0 hugepages_free=0 hugepages_surp=0 hugepages_size=1048576kB [81583.066082] Node 0 hugepages_total=0 hugepages_free=0 hugepages_surp=0 hugepages_size=2048kB [81583.074824] 297601 total pagecache pages [81583.078875] 0 pages in swap cache [81583.082324] Swap cache stats: add 0, delete 0, find 0/0 [81583.087676] Free swap = 0kB [81583.090675] Total swap = 0kB [81583.093694] 31457176 pages RAM [81583.096889] 0 pages HighMem/MovableOnly [81583.100861] 513206 pages reserved [81583.104506] 0 pages hwpoisoned [81583.107699] [ pid ] uid tgid total_vm rss nr_ptes nr_pmds swapents oom_score_adj name [81583.116536] [ 935] 0 935 91555 147 176 4 0 0 systemd-journal [81583.126170] [ 960] 0 960 11548 333 23 3 0 -1000 systemd-udevd [81583.135606] [ 1125] 0 1125 13686 112 26 3 0 -1000 auditd [81583.144886] [ 1137] 0 1137 21093 40 12 3 0 0 audispd [81583.155457] [ 1151] 109 1151 11282 111 28 3 0 -900 dbus-daemon [81583.164724] [ 1155] 0 1155 1051 21 8 3 0 0 acpid [81583.173467] [ 1157] 0 1157 7409 61 19 3 0 0 cron [81583.182144] [ 1158] 0 1158 9495 122 22 3 0 0 systemd-logind [81583.191675] [ 1180] 0 1180 34588 3145 65 5 0 0 syz-ci [81583.200592] [ 1660] 0 1660 5119 275 12 3 0 0 dhclient [81583.210130] [ 1707] 0 1707 90807 12331 116 4 0 0 puppet [81583.219592] [ 1714] 0 1714 3575 34 12 3 0 0 agetty [81583.230289] [ 1716] 0 1716 3631 33 12 3 0 0 agetty [81583.239127] [ 1743] 106 1743 24474 132 23 4 0 0 ntpd [81583.247784] [ 1746] 0 1746 366819 41661 606 4 0 0 ruby [81583.256444] [ 2124] 0 2124 17077 3619 39 4 0 0 google_network_ [81583.266057] [ 2195] 0 2195 17175 3730 40 3 0 -999 google_accounts [81583.275661] [ 2354] 0 2354 17072 3580 39 3 0 0 google_clock_sk [81583.285259] [ 2355] 0 2355 17489 187 37 4 0 -1000 sshd [81583.293928] [13916] 0 13916 113255 50119 190 5 0 0 syz-manager [81583.303198] [14009] 0 14009 95098 56175 177 5 0 0 syz-manager [81583.313884] [14172] 0 14172 91827 51282 170 5 0 0 syz-manager [81583.324574] [42519] 0 42519 62528 356 28 3 0 0 rsyslogd [81583.334567] [49384] 0 49384 46286 17255 92 5 0 0 syz-manager [81583.343852] [34145] 0 34145 315315 3934 60 10 0 0 image [81583.352735] [34171] 0 34171 440947 11084 178 11 0 0 exe [81583.361327] [34177] 0 34177 42954605 29900237 61462 169 0 0 exe [81583.370454] [34215] 0 34215 1 1 1 1 0 0 exe [81583.385638] [34282] 0 34282 23665 3807 52 6 0 0 exe [81583.409177] [34422] 0 34422 296594 3712 58 9 0 0 image [81583.417923] [34448] 0 34448 82436 54603 133 6 0 0 exe [81583.427201] [34562] 0 34562 656 1 5 4 0 0 exe [81583.435807] [34935] 0 34935 4810 1 6 4 0 0 exe [81583.444476] [34982] 0 34982 1 1 1 1 0 0 exe [81583.453139] [34996] 0 34996 834 1 5 4 0 0 exe [81583.461719] [35017] 0 35017 1 1 1 1 0 0 exe [81583.470310] [35022] 0 35022 439 1 5 4 0 0 exe [81583.478892] [35035] 0 35035 4990 1 6 4 0 0 exe [81583.487463] [35069] 0 35069 4595 1 6 4 0 0 exe [81583.496165] [35255] 0 35255 416 1 5 3 0 0 exe [81583.504736] [35308] 0 35308 1 1 1 1 0 0 exe [81583.513299] [35369] 0 35369 6806 1 9 5 0 0 exe [81583.521912] [35432] 0 35432 1 1 1 1 0 0 exe [81583.530474] [39273] 0 39273 6411 1 9 5 0 0 exe [81583.539042] [40852] 0 40852 180167 660 22 4 0 0 image [81583.547788] [40860] 0 40860 181675 1476 28 4 0 0 exe [81583.556354] [40884] 0 40884 380550 63733 313 7 0 0 exe [81583.564918] [40946] 0 40946 1 1 1 1 0 0 exe [81583.573482] [41015] 0 41015 24120 4046 52 6 0 0 exe [81583.582232] [41132] 0 41132 179815 596 19 5 0 0 image [81583.590973] [41142] 0 41142 73327 45898 116 6 0 0 exe [81583.600005] [41253] 0 41253 530 1 5 4 0 0 exe [81583.608596] [41592] 0 41592 440 1 5 4 0 0 exe [81583.617160] [41593] 0 41593 709 1 5 4 0 0 exe [81583.625725] [41597] 0 41597 400 1 5 4 0 0 exe [81583.634774] [41604] 0 41604 4624 1 5 4 0 0 exe [81583.643347] [41607] 0 41607 4905 1 6 4 0 0 exe [81583.651943] [41610] 0 41610 4494 1 5 4 0 0 exe [81583.660512] [41611] 0 41611 4534 1 5 4 0 0 exe [81583.669093] [41633] 0 41633 6022 1 8 5 0 0 exe [81583.677660] [41648] 0 41648 6410 1 9 5 0 0 exe [81583.686235] [41663] 0 41663 6681 1 9 5 0 0 exe [81583.694841] [41674] 0 41674 5828 1 8 5 0 0 exe [81583.704400] [41790] 0 41790 6681 1 9 5 0 0 exe [81583.712973] [41797] 0 41797 6502 1 9 5 0 0 exe [81583.721616] [41841] 0 41841 6502 1 9 5 0 0 exe [81583.730223] [43725] 0 43725 6713 1 9 5 0 0 exe [81583.738806] [43767] 0 43767 416 1 5 3 0 0 exe [81583.747375] [43802] 0 43802 442 1 5 3 0 0 exe [81583.756028] [43955] 0 43955 6681 1 9 5 0 0 exe [81583.764599] [43971] 0 43971 414 1 5 3 0 0 exe [81583.773518] [43976] 0 43976 414 1 5 3 0 0 exe [81583.782089] [44373] 0 44373 414 1 5 3 0 0 exe [81583.790681] [45846] 0 45846 414 1 5 3 0 0 exe [81583.799424] [45898] 0 45898 442 1 5 3 0 0 exe [81583.808051] [51374] 0 51374 6443 1 9 5 0 0 exe [81583.816808] [53889] 0 53889 179879 688 20 5 0 0 image [81583.825563] [53895] 0 53895 180907 731 21 5 0 0 exe [81583.834146] [53900] 65534 53900 17181731352 36258 229 6 0 0 exe [81583.842977] [54215] 0 54215 180295 645 22 4 0 0 image [81583.855142] [63711] 0 63711 5899 1 8 5 0 0 exe [81583.863893] [63805] 0 63805 1 1 1 1 0 0 exe [81583.872447] [64117] 0 64117 1 1 1 1 0 0 exe [81583.881087] [64324] 0 64324 6411 1 9 5 0 0 exe [81583.889661] [64368] 0 64368 1 1 1 1 0 0 exe [81583.898361] [64397] 0 64397 6411 1 9 5 0 0 exe [81583.906941] [64474] 0 64474 416 1 5 3 0 0 exe [81583.915518] [64503] 0 64503 1 1 1 1 0 0 exe [81583.924079] [64626] 0 64626 6294 1 8 5 0 0 exe [81583.932640] [64729] 0 64729 416 1 5 3 0 0 exe [81583.941203] [64746] 0 64746 414 1 5 3 0 0 exe [81583.949762] [64770] 0 64770 1 1 1 1 0 0 exe [81583.958330] [64863] 0 64863 414 1 5 3 0 0 exe [81583.966936] [64969] 0 64969 414 1 5 3 0 0 exe [81583.975511] [65000] 0 65000 6806 1 9 5 0 0 exe [81583.984077] [65007] 0 65007 416 1 5 3 0 0 exe [81583.992646] [65023] 0 65023 1 1 1 1 0 0 exe [81584.001201] [65141] 0 65141 416 1 5 3 0 0 exe [81584.009757] [65147] 0 65147 414 1 5 3 0 0 exe [81584.018354] [ 456] 0 456 1 1 1 1 0 0 exe [81584.026911] [ 621] 0 621 1 1 1 1 0 0 exe [81584.035463] [ 996] 0 996 416 1 5 3 0 0 exe [81584.044064] [ 1059] 0 1059 6523 1 9 5 0 0 exe [81584.052626] [ 2305] 0 2305 442 1 5 3 0 0 exe [81584.061185] [ 2310] 0 2310 442 1 5 3 0 0 exe [81584.069755] [ 2513] 0 2513 5828 1 8 5 0 0 exe [81584.078316] [ 2519] 0 2519 416 1 5 3 0 0 exe [81584.086878] [ 2561] 0 2561 416 1 5 3 0 0 exe [81584.095444] [ 2641] 0 2641 414 1 5 3 0 0 exe [81584.104013] [ 2697] 0 2697 414 1 5 3 0 0 exe [81584.112683] [ 2859] 0 2859 6404 1 9 5 0 0 exe [81584.121810] [ 2865] 0 2865 6410 1 9 5 0 0 exe [81584.130381] [ 3027] 0 3027 416 1 5 3 0 0 exe [81584.140050] [ 3139] 0 3139 6378 1 9 5 0 0 exe [81584.148717] [ 3146] 0 3146 1 1 1 1 0 0 exe [81584.157281] [ 3230] 0 3230 6410 1 9 5 0 0 exe [81584.165864] [ 3386] 0 3386 416 1 5 3 0 0 exe [81584.174429] [ 3439] 0 3439 416 1 5 3 0 0 exe [81584.182999] [ 3482] 0 3482 5860 1 8 5 0 0 exe [81584.191572] [ 4127] 0 4127 1 1 1 1 0 0 exe [81584.200218] [ 4139] 0 4139 6502 1 9 5 0 0 exe [81584.208848] [ 4587] 0 4587 6372 1 9 5 0 0 exe [81584.217561] [ 4630] 0 4630 1 1 1 1 0 0 exe [81584.226197] [15062] 0 15062 180167 670 21 4 0 0 image [81584.234933] [15744] 0 15744 179879 662 20 5 0 0 image [81584.243715] [15764] 0 15764 181419 1260 26 4 0 0 exe [81584.252312] [15765] 0 15765 297517 17229 152 6 0 0 exe [81584.260872] [15839] 0 15839 1 1 1 1 0 0 exe [81584.269436] [15942] 0 15942 23713 3436 31 6 0 0 exe [81584.278007] [16430] 0 16430 179879 652 20 5 0 0 image [81584.286787] [16440] 0 16440 180167 669 21 4 0 0 image [81584.295522] [16442] 0 16442 72628 45365 115 7 0 0 exe [81584.304084] [16648] 0 16648 658 1 5 4 0 0 exe [81584.312643] [17756] 0 17756 663 1 5 4 0 0 exe [81584.321204] [17777] 0 17777 4814 1 6 4 0 0 exe [81584.329756] [17780] 0 17780 352 1 5 4 0 0 exe [81584.338321] [17792] 0 17792 517 1 5 4 0 0 exe [81584.346874] [17812] 0 17812 4757 1 5 4 0 0 exe [81584.355438] [17837] 0 17837 4671 1 6 4 0 0 exe [81584.364003] [17842] 0 17842 4506 1 6 4 0 0 exe [81584.372554] [17927] 0 17927 1 1 1 1 0 0 exe [81584.381120] [17963] 0 17963 6603 1 9 5 0 0 exe [81584.389685] [17976] 0 17976 1 1 1 1 0 0 exe [81584.398237] [18019] 0 18019 1 1 1 1 0 0 exe [81584.406791] [20490] 0 20490 1 1 1 1 0 0 exe [81584.415347] [26212] 0 26212 296882 3644 57 10 0 0 image [81584.424075] [26385] 0 26385 34478 11246 71 5 0 0 syz-manager [81584.433331] [26386] 0 26386 44991 21388 92 5 0 0 syz-manager [81584.442590] [27214] 0 27214 180231 732 21 5 0 0 image [81584.451361] [27220] 0 27220 180971 713 24 5 0 0 exe [81584.459914] [27223] 65534 27223 17181652865 5780 59 6 0 0 exe [81584.468742] [27256] 0 27256 180231 679 20 5 0 0 image [81584.477475] [27270] 0 27270 180971 745 23 5 0 0 exe [81584.486035] [27276] 65534 27276 211053 4952 55 6 0 0 exe [81584.494631] [27309] 65534 27309 1 1 1 1 0 0 exe [81584.503215] [27361] 65534 27361 23838 3717 30 6 0 0 exe [81584.511769] [27536] 0 27536 179879 670 21 5 0 0 image [81584.520495] [27548] 0 27548 180971 668 23 5 0 0 exe [81584.529047] [27549] 0 27549 206685 1494 33 6 0 0 exe [81584.537602] [27558] 0 27558 315315 3661 59 10 0 0 image [81584.546336] [27577] 0 27577 1 1 1 1 0 0 exe [81584.555322] [27599] 0 27599 2171 1 2 2 0 0 exe [81584.563877] [27602] 0 27602 296882 3629 55 10 0 0 image [81584.572610] Out of memory: Kill process 34177 (exe) score 939 or sacrifice child [81584.580479] Killed process 34215 (exe) total-vm:4kB, anon-rss:4kB, file-rss:0kB, shmem-rss:0kB [81584.599385] oom_reaper: reaped process 34215 (exe), now anon-rss:0kB, file-rss:0kB, shmem-rss:0kB [81585.327454] syz-manager invoked oom-killer: gfp_mask=0x24201ca(GFP_HIGHUSER_MOVABLE|__GFP_COLD), nodemask=0, order=0, oom_score_adj=0 [81585.339673] syz-manager cpuset=/ mems_allowed=0 [81585.344915] CPU: 63 PID: 49385 Comm: syz-manager Not tainted 4.9.0-11-amd64 #1 Debian 4.9.189-3+deb9u2 [81585.354342] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 [81585.363685] 0000000000000000 ffffffffa1136404 ffffba1950ec3c10 ffff9fc5a6796140 [81585.371731] ffffffffa1009690 0000000000000000 0000000000000000 ffff9fca7848a0c0 [81585.379764] ffff9fca787de100 ffff9fca73253690 0000000000000000 ffffffffa10acee0 [81585.387799] Call Trace: [81585.390377] [] ? dump_stack+0x5c/0x78 [81585.395817] [] ? dump_header+0x78/0x1fd [81585.401430] [] ? cap_inode_killpriv+0x20/0x20 [81585.407675] [] ? oom_kill_process+0x22a/0x3f0 [81585.413811] [] ? out_of_memory+0x111/0x470 [81585.419684] [] ? __alloc_pages_slowpath+0xa1f/0xb30 [81585.426333] [] ? __alloc_pages_nodemask+0x201/0x260 [81585.432981] [] ? alloc_pages_current+0x91/0x140 [81585.439377] [] ? filemap_fault+0x326/0x5d0 [81585.445284] [] ? ext4_filemap_fault+0x31/0x50 [ext4] [81585.452018] [] ? __do_fault+0xa9/0x1c0 [81585.457535] [] ? handle_mm_fault+0xda4/0x1350 [81585.463671] [] ? poll_select_copy_remaining+0x11b/0x150 [81585.470663] [] ? __do_page_fault+0x255/0x4f0 [81585.476826] [] ? page_fault+0x28/0x30 [81585.482803] Mem-Info: [81585.485206] active_anon:30285919 inactive_anon:243098 isolated_anon:0 active_file:100 inactive_file:431 isolated_file:0 unevictable:0 dirty:0 writeback:0 unstable:0 slab_reclaimable:9474 slab_unreclaimable:41816 mapped:242882 shmem:297093 pagetables:66348 bounce:0 free:138713 free_pcp:0 free_cma:0 [81585.519047] Node 0 active_anon:121143676kB inactive_anon:972392kB active_file:1040kB inactive_file:0kB unevictable:0kB isolated(anon):0kB isolated(file):0kB mapped:971528kB dirty:0kB writeback:0kB shmem:1188372kB shmem_thp: 0kB shmem_pmdmapped: 0kB anon_thp: 77824kB writeback_tmp:0kB unstable:0kB pages_scanned:22883 all_unreclaimable? no [81585.549064] Node 0 DMA free:15908kB min:8kB low:20kB high:32kB active_anon:0kB inactive_anon:0kB active_file:0kB inactive_file:0kB unevictable:0kB writepending:0kB present:15992kB managed:15908kB mlocked:0kB slab_reclaimable:0kB slab_unreclaimable:0kB kernel_stack:0kB pagetables:0kB bounce:0kB free_pcp:0kB local_pcp:0kB free_cma:0kB [81585.579333] lowmem_reserve[]: 0 2974 120837 120837 120837 [81585.585795] Node 0 DMA32 free:473100kB min:1660kB low:4704kB high:7748kB active_anon:2580192kB inactive_anon:1100kB active_file:28kB inactive_file:0kB unevictable:0kB writepending:0kB present:3129320kB managed:3063752kB mlocked:0kB slab_reclaimable:16kB slab_unreclaimable:596kB kernel_stack:108kB pagetables:4896kB bounce:0kB free_pcp:0kB local_pcp:0kB free_cma:0kB [81585.618297] lowmem_reserve[]: 0 0 117862 117862 117862 [81585.624338] Node 0 Normal free:65904kB min:65908kB low:186596kB high:307284kB active_anon:118563484kB inactive_anon:971292kB active_file:760kB inactive_file:560kB unevictable:0kB writepending:0kB present:122683392kB managed:120696220kB mlocked:0kB slab_reclaimable:37848kB slab_unreclaimable:166652kB kernel_stack:136356kB pagetables:260496kB bounce:0kB free_pcp:0kB local_pcp:0kB free_cma:0kB [81585.659512] lowmem_reserve[]: 0 0 0 0 0 [81585.664254] Node 0 DMA: 1*4kB (U) 0*8kB 0*16kB 1*32kB (U) 2*64kB (U) 1*128kB (U) 1*256kB (U) 0*512kB 1*1024kB (U) 1*2048kB (M) 3*4096kB (M) = 15908kB [81585.680575] Node 0 DMA32: 748*4kB (UME) 615*8kB (UME) 1262*16kB (UME) 345*32kB (UMEH) 100*64kB (UMEH) 64*128kB (UMH) 41*256kB (UMEH) 19*512kB (UMEH) 8*1024kB (UME) 9*2048kB (UME) 91*4096kB (M) = 473320kB [81585.701900] Node 0 Normal: 17664*4kB (UME) 124*8kB (UME) 18*16kB (UM) 66*32kB (UM) 0*64kB 0*128kB 0*256kB 0*512kB 0*1024kB 0*2048kB 0*4096kB = 74048kB [81585.717799] Node 0 hugepages_total=0 hugepages_free=0 hugepages_surp=0 hugepages_size=1048576kB [81585.726790] Node 0 hugepages_total=0 hugepages_free=0 hugepages_surp=0 hugepages_size=2048kB [81585.735550] 298621 total pagecache pages [81585.739596] 0 pages in swap cache [81585.743040] Swap cache stats: add 0, delete 0, find 0/0 [81585.748424] Free swap = 0kB [81585.751448] Total swap = 0kB [81585.754503] 31457176 pages RAM [81585.757712] 0 pages HighMem/MovableOnly [81585.761672] 513206 pages reserved [81585.765115] 0 pages hwpoisoned [81585.768307] [ pid ] uid tgid total_vm rss nr_ptes nr_pmds swapents oom_score_adj name [81585.777225] [ 935] 0 935 91555 147 176 4 0 0 systemd-journal [81585.787062] [ 960] 0 960 11548 333 23 3 0 -1000 systemd-udevd [81585.803711] [ 1125] 0 1125 13686 112 26 3 0 -1000 auditd [81585.812529] [ 1137] 0 1137 21093 75 12 3 0 0 audispd [81585.821438] [ 1151] 109 1151 11282 111 28 3 0 -900 dbus-daemon [81585.830696] [ 1155] 0 1155 1051 21 8 3 0 0 acpid [81585.840119] [ 1157] 0 1157 7409 61 19 3 0 0 cron [81585.848762] [ 1158] 0 1158 9495 122 22 3 0 0 systemd-logind [81585.858276] [ 1180] 0 1180 34588 3145 65 5 0 0 syz-ci [81585.867459] [ 1660] 0 1660 5119 275 12 3 0 0 dhclient [81585.876450] [ 1707] 0 1707 90807 12331 116 4 0 0 puppet [81585.885262] [ 1714] 0 1714 3575 34 12 3 0 0 agetty [81585.894098] [ 1716] 0 1716 3631 33 12 3 0 0 agetty [81585.902928] [ 1743] 106 1743 24474 132 23 4 0 0 ntpd [81585.911664] [ 1746] 0 1746 366819 41661 606 4 0 0 ruby [81585.921010] [ 2124] 0 2124 17077 3619 39 4 0 0 google_network_ [81585.930638] [ 2195] 0 2195 17175 3730 40 3 0 -999 google_accounts [81585.940246] [ 2354] 0 2354 17072 3580 39 3 0 0 google_clock_sk [81585.949859] [ 2355] 0 2355 17489 187 37 4 0 -1000 sshd [81585.958602] [13916] 0 13916 113255 50141 190 5 0 0 syz-manager [81585.967869] [14009] 0 14009 95098 56175 177 5 0 0 syz-manager [81585.977258] [14172] 0 14172 91827 51282 170 5 0 0 syz-manager [81585.986546] [42519] 0 42519 62528 361 28 3 0 0 rsyslogd [81585.995572] [49384] 0 49384 46286 17363 92 5 0 0 syz-manager [81586.004857] [34145] 0 34145 315315 3933 60 10 0 0 image [81586.017424] [34171] 0 34171 440947 11084 178 11 0 0 exe [81586.025979] [34177] 0 34177 42954605 29900237 61462 169 0 0 exe [81586.034533] [34215] 0 34215 1 0 1 1 0 0 exe [81586.043100] [34282] 0 34282 23665 3807 52 6 0 0 exe [81586.051658] [34422] 0 34422 296594 3711 58 9 0 0 image [81586.060384] [34448] 0 34448 82436 54603 133 6 0 0 exe [81586.068950] [34562] 0 34562 656 1 5 4 0 0 exe [81586.077507] [34935] 0 34935 4810 1 6 4 0 0 exe [81586.086077] [34982] 0 34982 1 1 1 1 0 0 exe [81586.094646] [34996] 0 34996 834 1 5 4 0 0 exe [81586.104526] [35017] 0 35017 1 1 1 1 0 0 exe [81586.113085] [35022] 0 35022 439 1 5 4 0 0 exe [81586.121647] [35035] 0 35035 4990 1 6 4 0 0 exe [81586.130232] [35069] 0 35069 4595 1 6 4 0 0 exe [81586.138791] [35255] 0 35255 416 1 5 3 0 0 exe [81586.147790] [35308] 0 35308 1 1 1 1 0 0 exe [81586.156454] [35369] 0 35369 6806 1 9 5 0 0 exe [81586.165015] [35432] 0 35432 1 1 1 1 0 0 exe [81586.173568] [39273] 0 39273 6411 1 9 5 0 0 exe [81586.182210] [40852] 0 40852 180167 660 22 4 0 0 image [81586.190948] [40860] 0 40860 181675 1476 28 4 0 0 exe [81586.199512] [40884] 0 40884 380550 63733 313 7 0 0 exe [81586.208061] [40946] 0 40946 1 1 1 1 0 0 exe [81586.217765] [41015] 0 41015 24120 4046 52 6 0 0 exe [81586.227557] [41132] 0 41132 179815 596 19 5 0 0 image [81586.236399] [41142] 0 41142 73327 45898 116 6 0 0 exe [81586.244983] [41253] 0 41253 530 1 5 4 0 0 exe [81586.253557] [41592] 0 41592 440 1 5 4 0 0 exe [81586.262159] [41593] 0 41593 709 1 5 4 0 0 exe [81586.270725] [41597] 0 41597 400 1 5 4 0 0 exe [81586.279414] [41604] 0 41604 4624 1 5 4 0 0 exe [81586.287974] [41607] 0 41607 4905 1 6 4 0 0 exe [81586.296527] [41610] 0 41610 4494 1 5 4 0 0 exe [81586.305079] [41611] 0 41611 4534 1 5 4 0 0 exe [81586.316321] [41633] 0 41633 6022 1 8 5 0 0 exe [81586.324873] [41648] 0 41648 6410 1 9 5 0 0 exe [81586.333424] [41663] 0 41663 6681 1 9 5 0 0 exe [81586.342016] [41674] 0 41674 5828 1 8 5 0 0 exe [81586.350568] [41790] 0 41790 6681 1 9 5 0 0 exe [81586.363118] [41797] 0 41797 6502 1 9 5 0 0 exe [81586.371666] [41841] 0 41841 6502 1 9 5 0 0 exe [81586.380218] [43725] 0 43725 6713 1 9 5 0 0 exe [81586.388769] [43767] 0 43767 416 1 5 3 0 0 exe [81586.397368] [43802] 0 43802 442 1 5 3 0 0 exe [81586.405920] [43955] 0 43955 6681 1 9 5 0 0 exe [81586.414662] [43971] 0 43971 414 1 5 3 0 0 exe [81586.423217] [43976] 0 43976 414 1 5 3 0 0 exe [81586.431781] [44373] 0 44373 414 1 5 3 0 0 exe [81586.440335] [45846] 0 45846 414 1 5 3 0 0 exe [81586.448886] [45898] 0 45898 442 1 5 3 0 0 exe [81586.457446] [51374] 0 51374 6443 1 9 5 0 0 exe [81586.466001] [53889] 0 53889 179879 688 20 5 0 0 image [81586.474829] [53895] 0 53895 180907 731 21 5 0 0 exe [81586.483388] [53900] 65534 53900 17181731352 36258 229 6 0 0 exe [81586.492200] [54215] 0 54215 180295 645 22 4 0 0 image [81586.500925] [63711] 0 63711 5899 1 8 5 0 0 exe [81586.509482] [63805] 0 63805 1 1 1 1 0 0 exe [81586.518137] [64117] 0 64117 1 1 1 1 0 0 exe [81586.526689] [64324] 0 64324 6411 1 9 5 0 0 exe [81586.535244] [64368] 0 64368 1 1 1 1 0 0 exe [81586.543882] [64397] 0 64397 6411 1 9 5 0 0 exe [81586.552439] [64474] 0 64474 416 1 5 3 0 0 exe [81586.561000] [64503] 0 64503 1 1 1 1 0 0 exe [81586.569553] [64626] 0 64626 6294 1 8 5 0 0 exe [81586.578104] [64729] 0 64729 416 1 5 3 0 0 exe [81586.586656] [64746] 0 64746 414 1 5 3 0 0 exe [81586.595208] [64770] 0 64770 1 1 1 1 0 0 exe [81586.603759] [64863] 0 64863 414 1 5 3 0 0 exe [81586.612313] [64969] 0 64969 414 1 5 3 0 0 exe [81586.620863] [65000] 0 65000 6806 1 9 5 0 0 exe [81586.629419] [65007] 0 65007 416 1 5 3 0 0 exe [81586.637977] [65023] 0 65023 1 1 1 1 0 0 exe [81586.646586] [65141] 0 65141 416 1 5 3 0 0 exe [81586.655148] [65147] 0 65147 414 1 5 3 0 0 exe [81586.663703] [ 456] 0 456 1 1 1 1 0 0 exe [81586.672254] [ 621] 0 621 1 1 1 1 0 0 exe [81586.680806] [ 996] 0 996 416 1 5 3 0 0 exe [81586.692569] [ 1059] 0 1059 6523 1 9 5 0 0 exe [81586.701135] [ 2305] 0 2305 442 1 5 3 0 0 exe [81586.712033] [ 2310] 0 2310 442 1 5 3 0 0 exe [81586.720587] [ 2513] 0 2513 5828 1 8 5 0 0 exe [81586.729182] [ 2519] 0 2519 416 1 5 3 0 0 exe [81586.737734] [ 2561] 0 2561 416 1 5 3 0 0 exe [81586.746288] [ 2641] 0 2641 414 1 5 3 0 0 exe [81586.754838] [ 2697] 0 2697 414 1 5 3 0 0 exe [81586.763391] [ 2859] 0 2859 6404 1 9 5 0 0 exe [81586.774726] [ 2865] 0 2865 6410 1 9 5 0 0 exe [81586.783282] [ 3027] 0 3027 416 1 5 3 0 0 exe [81586.791832] [ 3139] 0 3139 6378 1 9 5 0 0 exe [81586.800425] [ 3146] 0 3146 1 1 1 1 0 0 exe [81586.809113] [ 3230] 0 3230 6410 1 9 5 0 0 exe [81586.817715] [ 3386] 0 3386 416 1 5 3 0 0 exe [81586.826267] [ 3439] 0 3439 416 1 5 3 0 0 exe [81586.834819] [ 3482] 0 3482 5860 1 8 5 0 0 exe [81586.843372] [ 4127] 0 4127 1 1 1 1 0 0 exe [81586.851942] [ 4139] 0 4139 6502 1 9 5 0 0 exe [81586.860491] [ 4587] 0 4587 6372 1 9 5 0 0 exe [81586.869043] [ 4630] 0 4630 1 1 1 1 0 0 exe [81586.877607] [15062] 0 15062 180167 670 21 4 0 0 image [81586.886335] [15744] 0 15744 179879 662 20 5 0 0 image [81586.895061] [15764] 0 15764 181419 1260 26 4 0 0 exe [81586.903613] [15765] 0 15765 297517 17267 152 6 0 0 exe [81586.912205] [15839] 0 15839 1 1 1 1 0 0 exe [81586.920796] [15942] 0 15942 23713 3436 31 6 0 0 exe [81586.929346] [16430] 0 16430 179879 652 20 5 0 0 image [81586.938102] [16440] 0 16440 180167 669 21 4 0 0 image [81586.946832] [16442] 0 16442 72628 45365 115 7 0 0 exe [81586.955422] [16648] 0 16648 658 1 5 4 0 0 exe [81586.963976] [17756] 0 17756 663 1 5 4 0 0 exe [81586.973534] [17777] 0 17777 4814 1 6 4 0 0 exe [81586.982095] [17780] 0 17780 352 1 5 4 0 0 exe [81586.990676] [17792] 0 17792 517 1 5 4 0 0 exe [81586.999230] [17812] 0 17812 4757 1 5 4 0 0 exe [81587.007791] [17837] 0 17837 4671 1 6 4 0 0 exe [81587.016358] [17842] 0 17842 4506 1 6 4 0 0 exe [81587.024916] [17927] 0 17927 1 1 1 1 0 0 exe [81587.033470] [17963] 0 17963 6603 1 9 5 0 0 exe [81587.042035] [17976] 0 17976 1 1 1 1 0 0 exe [81587.050630] [18019] 0 18019 1 1 1 1 0 0 exe [81587.059184] [20490] 0 20490 1 1 1 1 0 0 exe [81587.067787] [26212] 0 26212 296882 3644 57 10 0 0 image [81587.076565] [26385] 0 26385 34478 11246 71 5 0 0 syz-manager [81587.085814] [26386] 0 26386 44991 21388 92 5 0 0 syz-manager [81587.095074] [27214] 0 27214 180231 732 21 5 0 0 image [81587.103801] [27220] 0 27220 180971 713 24 5 0 0 exe [81587.112352] [27223] 65534 27223 17181652865 5780 59 6 0 0 exe [81587.121168] [27256] 0 27256 180231 679 20 5 0 0 image [81587.129908] [27270] 0 27270 180971 745 23 5 0 0 exe [81587.138461] [27276] 65534 27276 211053 4952 55 6 0 0 exe [81587.147169] [27309] 65534 27309 1 1 1 1 0 0 exe [81587.155793] [27361] 65534 27361 23838 3717 30 6 0 0 exe [81587.164373] [27536] 0 27536 179879 670 21 5 0 0 image [81587.173130] [27548] 0 27548 180971 668 23 5 0 0 exe [81587.181721] [27549] 0 27549 206685 1495 33 6 0 0 exe [81587.190596] [27558] 0 27558 315315 3661 59 10 0 0 image [81587.199475] [27577] 0 27577 1 1 1 1 0 0 exe [81587.209418] [27599] 0 27599 2171 1 2 2 0 0 exe [81587.218209] [27602] 0 27602 296882 3629 55 10 0 0 image [81587.227774] Out of memory: Kill process 34177 (exe) score 939 or sacrifice child [81587.236452] Killed process 34177 (exe) total-vm:171818420kB, anon-rss:119445228kB, file-rss:0kB, shmem-rss:156744kB [81591.574780] kvm [28160]: vcpu0, guest rIP: 0xa74064 unhandled rdmsr: 0xce [81592.535632] kvm [28786]: vcpu0, guest rIP: 0xa74064 unhandled rdmsr: 0xce [81596.132172] oom_reaper: reaped process 34177 (exe), now anon-rss:0kB, file-rss:0kB, shmem-rss:157164kB [97099.041207] can: request_module (can-proto-0) failed. [97099.056539] can: request_module (can-proto-0) failed. [97099.072025] can: request_module (can-proto-0) failed. [97120.192202] can: request_module (can-proto-0) failed. [97120.207534] can: request_module (can-proto-0) failed. [97120.234555] can: request_module (can-proto-0) failed. [97124.804225] can: request_module (can-proto-0) failed. [97124.814929] can: request_module (can-proto-0) failed. [97124.828872] can: request_module (can-proto-0) failed. [97307.124852] kvm [41621]: vcpu0, guest rIP: 0xa75294 unhandled rdmsr: 0xce [97307.125552] kvm [41622]: vcpu0, guest rIP: 0xa75294 unhandled rdmsr: 0xce [97307.125896] kvm [41616]: vcpu0, guest rIP: 0xa75294 unhandled rdmsr: 0xce [97482.648808] kvm [42563]: vcpu0, guest rIP: 0xa75294 unhandled rdmsr: 0xce [175346.089220] exe invoked oom-killer: gfp_mask=0x24201ca(GFP_HIGHUSER_MOVABLE|__GFP_COLD), nodemask=0, order=0, oom_score_adj=0 [175346.100888] exe cpuset=/ mems_allowed=0 [175346.105224] CPU: 60 PID: 28325 Comm: exe Not tainted 4.9.0-11-amd64 #1 Debian 4.9.189-3+deb9u2 [175346.114037] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 [175346.123471] 0000000000000000 ffffffffa1136404 ffffba194e00bc10 ffff9fbc7ca2b040 [175346.131570] ffffffffa1009690 0000000000000000 0000000000000000 0000000ca141e4f1 [175346.139670] ffff9fba8277e040 ffffffffa0f8d207 0000004200000000 ffffffffa10acee0 [175346.147838] Call Trace: [175346.150527] [] ? dump_stack+0x5c/0x78 [175346.156061] [] ? dump_header+0x78/0x1fd [175346.161754] [] ? get_page_from_freelist+0x3f7/0xb20 [175346.168482] [] ? cap_inode_killpriv+0x20/0x20 [175346.174695] [] ? oom_kill_process+0x22a/0x3f0 [175346.180905] [] ? out_of_memory+0x111/0x470 [175346.186872] [] ? __alloc_pages_slowpath+0xa1f/0xb30 [175346.193601] [] ? __alloc_pages_nodemask+0x201/0x260 [175346.200471] [] ? alloc_pages_current+0x91/0x140 [175346.206853] [] ? filemap_fault+0x326/0x5d0 [175346.212841] [] ? ext4_filemap_fault+0x31/0x50 [ext4] [175346.219670] [] ? __do_fault+0xa9/0x1c0 [175346.225274] [] ? handle_mm_fault+0xda4/0x1350 [175346.231491] [] ? do_nanosleep+0x8d/0xe0 [175346.237184] [] ? __do_page_fault+0x255/0x4f0 [175346.243410] [] ? page_fault+0x28/0x30 [175346.248954] Mem-Info: [175346.251465] active_anon:30503324 inactive_anon:48047 isolated_anon:0 active_file:648 inactive_file:12 isolated_file:0 unevictable:0 dirty:224 writeback:0 unstable:0 slab_reclaimable:7196 slab_unreclaimable:32177 mapped:52160 shmem:55216 pagetables:72402 bounce:0 free:138700 free_pcp:155 free_cma:0 [175346.286329] Node 0 active_anon:122013296kB inactive_anon:192188kB active_file:3116kB inactive_file:1524kB unevictable:0kB isolated(anon):0kB isolated(file):0kB mapped:210184kB dirty:916kB writeback:0kB shmem:220864kB shmem_thp: 0kB shmem_pmdmapped: 0kB anon_thp: 116736kB writeback_tmp:0kB unstable:0kB pages_scanned:935 all_unreclaimable? no [175346.316693] Node 0 DMA free:15908kB min:8kB low:20kB high:32kB active_anon:0kB inactive_anon:0kB active_file:0kB inactive_file:0kB unevictable:0kB writepending:0kB present:15992kB managed:15908kB mlocked:0kB slab_reclaimable:0kB slab_unreclaimable:0kB kernel_stack:0kB pagetables:0kB bounce:0kB free_pcp:0kB local_pcp:0kB free_cma:0kB [175346.346491] lowmem_reserve[]: 0 2974 120837 120837 120837 [175346.352924] Node 0 DMA32 free:473060kB min:1660kB low:4704kB high:7748kB active_anon:2578804kB inactive_anon:36kB active_file:20kB inactive_file:44kB unevictable:0kB writepending:0kB present:3129320kB managed:3063752kB mlocked:0kB slab_reclaimable:68kB slab_unreclaimable:440kB kernel_stack:80kB pagetables:5300kB bounce:0kB free_pcp:0kB local_pcp:0kB free_cma:0kB [175346.385390] lowmem_reserve[]: 0 0 117862 117862 117862 [175346.391603] Node 0 Normal free:65664kB min:65908kB low:186596kB high:307284kB active_anon:119434492kB inactive_anon:192152kB active_file:3416kB inactive_file:0kB unevictable:0kB writepending:0kB present:122683392kB managed:120696220kB mlocked:0kB slab_reclaimable:28712kB slab_unreclaimable:128264kB kernel_stack:37344kB pagetables:284308kB bounce:0kB free_pcp:1728kB local_pcp:0kB free_cma:0kB [175346.426606] lowmem_reserve[]: 0 0 0 0 0 [175346.431425] Node 0 DMA: 1*4kB (U) 0*8kB 0*16kB 1*32kB (U) 2*64kB (U) 1*128kB (U) 1*256kB (U) 0*512kB 1*1024kB (U) 1*2048kB (M) 3*4096kB (M) = 15908kB [175346.448182] Node 0 DMA32: 580*4kB (UMEH) 287*8kB (UME) 307*16kB (UME) 188*32kB (UME) 82*64kB (UME) 29*128kB (UME) 7*256kB (UMEH) 3*512kB (UM) 7*1024kB (UME) 4*2048kB (UME) 105*4096kB (ME) = 473272kB [175346.469466] Node 0 Normal: 16732*4kB (UMEH) 64*8kB (UMH) 11*16kB (H) 14*32kB (H) 9*64kB (H) 2*128kB (H) 1*256kB (H) 0*512kB 0*1024kB 0*2048kB 0*4096kB = 69152kB [175346.486730] Node 0 hugepages_total=0 hugepages_free=0 hugepages_surp=0 hugepages_size=1048576kB [175346.495642] Node 0 hugepages_total=0 hugepages_free=0 hugepages_surp=0 hugepages_size=2048kB [175346.504297] 56888 total pagecache pages [175346.508341] 0 pages in swap cache [175346.512017] Swap cache stats: add 0, delete 0, find 0/0 [175346.517450] Free swap = 0kB [175346.520537] Total swap = 0kB [175346.523627] 31457176 pages RAM [175346.526889] 0 pages HighMem/MovableOnly [175346.530960] 513206 pages reserved [175346.534473] 0 pages hwpoisoned [175346.537733] [ pid ] uid tgid total_vm rss nr_ptes nr_pmds swapents oom_score_adj name [175346.546554] [ 935] 0 935 113037 147 219 4 0 0 systemd-journal [175346.556310] [ 960] 0 960 11548 333 23 3 0 -1000 systemd-udevd [175346.565866] [ 1125] 0 1125 13686 112 26 3 0 -1000 auditd [175346.574773] [ 1137] 0 1137 21093 16 12 3 0 0 audispd [175346.583766] [ 1151] 109 1151 11282 112 28 3 0 -900 dbus-daemon [175346.593102] [ 1155] 0 1155 1051 21 8 3 0 0 acpid [175346.602216] [ 1157] 0 1157 7409 61 19 3 0 0 cron [175346.610966] [ 1158] 0 1158 9495 122 22 3 0 0 systemd-logind [175346.620698] [ 1180] 0 1180 32028 3099 62 5 0 0 syz-ci [175346.629649] [ 1660] 0 1660 5119 275 12 3 0 0 dhclient [175346.638724] [ 1707] 0 1707 90842 12358 116 4 0 0 puppet [175346.647631] [ 1714] 0 1714 3575 34 12 3 0 0 agetty [175346.656619] [ 1716] 0 1716 3631 33 12 3 0 0 agetty [175346.665538] [ 1743] 106 1743 24474 132 23 4 0 0 ntpd [175346.674432] [ 1746] 0 1746 361781 41111 583 4 0 0 ruby [175346.683290] [ 2124] 0 2124 17077 3622 39 4 0 0 google_network_ [175346.692995] [ 2195] 0 2195 17239 3739 40 3 0 -999 google_accounts [175346.702697] [ 2354] 0 2354 17072 3580 39 3 0 0 google_clock_sk [175346.712581] [ 2355] 0 2355 17489 187 37 4 0 -1000 sshd [175346.721348] [42133] 0 42133 50358 23840 101 5 0 0 syz-manager [175346.730877] [45406] 0 45406 53614 24006 109 5 0 0 syz-manager [175346.740256] [63756] 0 63756 121165 62344 170 5 0 0 syz-manager [175346.749614] [13118] 0 13118 94500 57666 188 5 0 0 syz-manager [175346.758963] [25776] 0 25776 111726 55609 190 5 0 0 syz-manager [175346.768430] [ 9893] 0 9893 62528 353 28 4 0 0 rsyslogd [175346.777739] [62996] 0 62996 76602 52378 155 5 0 0 syz-manager [175346.787110] [24856] 0 24856 180231 726 22 5 0 0 image [175346.795940] [24862] 0 24862 181323 713 23 4 0 0 exe [175346.804704] [24864] 65534 24864 17181731394 40859 231 6 0 0 exe [175346.813623] [25464] 0 25464 180167 648 21 4 0 0 image [175346.822632] [28312] 0 28312 315400 4068 65 9 0 0 image [175346.831678] [28319] 0 28319 442694 7615 175 11 0 0 exe [175346.840331] [28320] 0 28320 50715370 30112456 68984 170 0 0 exe [175346.848989] [28346] 0 28346 1 1 1 1 0 0 exe [175346.857667] [28360] 0 28360 23855 4021 52 6 0 0 exe [175346.866452] [38106] 0 38106 315048 3710 60 10 0 0 image [175346.875292] [38116] 0 38116 29440 4194 31 6 0 0 exe [175346.883951] [38152] 0 38152 4539 1 5 4 0 0 exe [175346.892609] [38163] 0 38163 773 1 5 4 0 0 exe [175346.901360] [38167] 0 38167 4576 1 5 4 0 0 exe [175346.910018] [38170] 0 38170 482 1 5 4 0 0 exe [175346.918685] [38178] 0 38178 475 1 5 4 0 0 exe [175346.927349] [38180] 0 38180 443 1 5 4 0 0 exe [175346.936017] [38197] 0 38197 4569 1 5 4 0 0 exe [175346.944758] [38204] 0 38204 4867 1 5 4 0 0 exe [175346.953467] [38250] 0 38250 6582 1 9 5 0 0 exe [175346.962116] [38257] 0 38257 6936 1 9 5 0 0 exe [175346.970770] [38267] 0 38267 6638 1 9 5 0 0 exe [175346.979442] [38335] 0 38335 6801 1 10 5 0 0 exe [175346.988147] [43215] 0 43215 179815 653 20 5 0 0 image [175346.996960] [43220] 0 43220 181323 762 23 5 0 0 exe [175347.005648] [43226] 65534 43226 17181666072 8587 113 6 0 0 exe [175347.014690] [44668] 0 44668 180167 672 21 4 0 0 image [175347.023510] [44724] 0 44724 179882 685 21 4 0 0 image [175347.032324] [44733] 0 44733 180974 685 23 5 0 0 exe [175347.040964] [44738] 65534 44738 17181653458 6848 89 6 0 0 exe [175347.049871] [45141] 0 45141 180167 668 20 5 0 0 image [175347.058699] [55571] 0 55571 179946 615 20 5 0 0 image [175347.067511] [55577] 0 55577 180234 686 21 5 0 0 image [175347.076328] Out of memory: Kill process 28320 (exe) score 946 or sacrifice child [175347.084083] Killed process 28346 (exe) total-vm:4kB, anon-rss:4kB, file-rss:0kB, shmem-rss:0kB [175347.102603] oom_reaper: reaped process 28346 (exe), now anon-rss:0kB, file-rss:0kB, shmem-rss:0kB [175357.442129] syz-manager invoked oom-killer: gfp_mask=0x24201ca(GFP_HIGHUSER_MOVABLE|__GFP_COLD), nodemask=0, order=0, oom_score_adj=0 [175357.454769] syz-manager cpuset=/ mems_allowed=0 [175357.459771] CPU: 29 PID: 25777 Comm: syz-manager Not tainted 4.9.0-11-amd64 #1 Debian 4.9.189-3+deb9u2 [175357.469274] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 [175357.478692] 0000000000000000 ffffffffa1136404 ffffba195b2efc10 ffff9fbc7ca2b040 [175357.486886] ffffffffa1009690 0000000000000000 0000000000000000 0000000c5b2efb00 [175357.496385] ffff9fbb9f2cd0c0 ffffffffa0f8d207 0000004200000000 ffffffffa10acee0 [175357.504494] Call Trace: [175357.507153] [] ? dump_stack+0x5c/0x78 [175357.514047] [] ? dump_header+0x78/0x1fd [175357.519744] [] ? get_page_from_freelist+0x3f7/0xb20 [175357.526472] [] ? cap_inode_killpriv+0x20/0x20 [175357.534064] [] ? oom_kill_process+0x22a/0x3f0 [175357.540452] [] ? out_of_memory+0x111/0x470 [175357.546399] [] ? __alloc_pages_slowpath+0xa1f/0xb30 [175357.553126] [] ? __alloc_pages_nodemask+0x201/0x260 [175357.559862] [] ? alloc_pages_current+0x91/0x140 [175357.566248] [] ? filemap_fault+0x326/0x5d0 [175357.572242] [] ? ext4_filemap_fault+0x31/0x50 [ext4] [175357.580429] [] ? __do_fault+0xa9/0x1c0 [175357.586037] [] ? handle_mm_fault+0xda4/0x1350 [175357.592245] [] ? poll_select_copy_remaining+0x11b/0x150 [175357.600703] [] ? __do_page_fault+0x255/0x4f0 [175357.606830] [] ? page_fault+0x28/0x30 [175357.613763] Mem-Info: [175357.617611] active_anon:30503330 inactive_anon:48047 isolated_anon:0 active_file:1928 inactive_file:1979 isolated_file:0 unevictable:0 dirty:5 writeback:0 unstable:0 slab_reclaimable:6547 slab_unreclaimable:28753 mapped:55189 shmem:55216 pagetables:72418 bounce:0 free:138860 free_pcp:700 free_cma:0 [175357.652063] Node 0 active_anon:122013824kB inactive_anon:192188kB active_file:6668kB inactive_file:8044kB unevictable:0kB isolated(anon):0kB isolated(file):0kB mapped:220052kB dirty:20kB writeback:0kB shmem:220864kB shmem_thp: 0kB shmem_pmdmapped: 0kB anon_thp: 116736kB writeback_tmp:0kB unstable:0kB pages_scanned:2331 all_unreclaimable? no [175357.682428] Node 0 DMA free:15908kB min:8kB low:20kB high:32kB active_anon:0kB inactive_anon:0kB active_file:0kB inactive_file:0kB unevictable:0kB writepending:0kB present:15992kB managed:15908kB mlocked:0kB slab_reclaimable:0kB slab_unreclaimable:0kB kernel_stack:0kB pagetables:0kB bounce:0kB free_pcp:0kB local_pcp:0kB free_cma:0kB [175357.713621] lowmem_reserve[]: 0 2974 120837 120837 120837 [175357.722812] Node 0 DMA32 free:473164kB min:1660kB low:4704kB high:7748kB active_anon:2578904kB inactive_anon:36kB active_file:16kB inactive_file:20kB unevictable:0kB writepending:0kB present:3129320kB managed:3063752kB mlocked:0kB slab_reclaimable:28kB slab_unreclaimable:412kB kernel_stack:80kB pagetables:5300kB bounce:0kB free_pcp:0kB local_pcp:0kB free_cma:0kB [175357.755235] lowmem_reserve[]: 0 0 117862 117862 117862 [175357.763026] Node 0 Normal free:66104kB min:65908kB low:186596kB high:307284kB active_anon:119444800kB inactive_anon:192152kB active_file:3444kB inactive_file:3332kB unevictable:0kB writepending:52kB present:122683392kB managed:120696220kB mlocked:0kB slab_reclaimable:26076kB slab_unreclaimable:114392kB kernel_stack:37376kB pagetables:284372kB bounce:0kB free_pcp:2332kB local_pcp:0kB free_cma:0kB [175357.799781] lowmem_reserve[]: 0 0 0 0 0 [175357.806090] Node 0 DMA: 1*4kB (U) 0*8kB 0*16kB 1*32kB (U) 2*64kB (U) 1*128kB (U) 1*256kB (U) 0*512kB 1*1024kB (U) 1*2048kB (M) 3*4096kB (M) = 15908kB [175357.825340] Node 0 DMA32: 583*4kB (UMEH) 282*8kB (UME) 308*16kB (UME) 186*32kB (UME) 82*64kB (UME) 29*128kB (UME) 7*256kB (UMEH) 3*512kB (UM) 7*1024kB (UME) 4*2048kB (UME) 105*4096kB (ME) = 473196kB [175357.850478] Node 0 Normal: 17231*4kB (UMEH) 243*8kB (UMEH) 42*16kB (UMEH) 17*32kB (UH) 9*64kB (H) 2*128kB (H) 1*256kB (H) 0*512kB 0*1024kB 0*2048kB 0*4096kB = 73172kB [175357.869731] Node 0 hugepages_total=0 hugepages_free=0 hugepages_surp=0 hugepages_size=1048576kB [175357.878660] Node 0 hugepages_total=0 hugepages_free=0 hugepages_surp=0 hugepages_size=2048kB [175357.887317] 56801 total pagecache pages [175357.891375] 0 pages in swap cache [175357.894926] Swap cache stats: add 0, delete 0, find 0/0 [175357.900368] Free swap = 0kB [175357.903462] Total swap = 0kB [175357.906560] 31457176 pages RAM [175357.909837] 0 pages HighMem/MovableOnly [175357.913885] 513206 pages reserved [175357.917418] 0 pages hwpoisoned [175357.920676] [ pid ] uid tgid total_vm rss nr_ptes nr_pmds swapents oom_score_adj name [175357.929530] [ 935] 0 935 113037 147 219 4 0 0 systemd-journal [175357.939378] [ 960] 0 960 11548 333 23 3 0 -1000 systemd-udevd [175357.948909] [ 1125] 0 1125 13686 112 26 3 0 -1000 auditd [175357.957855] [ 1137] 0 1137 21093 117 12 3 0 0 audispd [175357.966904] [ 1151] 109 1151 11282 112 28 3 0 -900 dbus-daemon [175357.976256] [ 1155] 0 1155 1051 21 8 3 0 0 acpid [175357.985251] [ 1157] 0 1157 7409 61 19 3 0 0 cron [175357.994022] [ 1158] 0 1158 9495 122 22 3 0 0 systemd-logind [175358.003632] [ 1180] 0 1180 32028 3149 62 5 0 0 syz-ci [175358.012549] [ 1660] 0 1660 5119 275 12 3 0 0 dhclient [175358.021646] [ 1707] 0 1707 90842 12359 116 4 0 0 puppet [175358.030567] [ 1714] 0 1714 3575 34 12 3 0 0 agetty [175358.039482] [ 1716] 0 1716 3631 33 12 3 0 0 agetty [175358.048399] [ 1743] 106 1743 24474 132 23 4 0 0 ntpd [175358.058525] [ 1746] 0 1746 361781 41111 583 4 0 0 ruby [175358.067334] [ 2124] 0 2124 17077 3622 39 4 0 0 google_network_ [175358.078433] [ 2195] 0 2195 17239 3739 40 3 0 -999 google_accounts [175358.088324] [ 2354] 0 2354 17072 3580 39 3 0 0 google_clock_sk [175358.099411] [ 2355] 0 2355 17489 187 37 4 0 -1000 sshd [175358.108176] [42133] 0 42133 50358 23840 101 5 0 0 syz-manager [175358.118901] [45406] 0 45406 53614 24087 109 5 0 0 syz-manager [175358.128247] [63756] 0 63756 121165 62399 170 5 0 0 syz-manager [175358.137678] [13118] 0 13118 94500 57666 188 5 0 0 syz-manager [175358.147018] [25776] 0 25776 111726 55645 190 5 0 0 syz-manager [175358.156387] [ 9893] 0 9893 62528 399 28 4 0 0 rsyslogd [175358.165527] [62996] 0 62996 76602 52378 155 5 0 0 syz-manager [175358.176208] [24856] 0 24856 180231 726 22 5 0 0 image [175358.185034] [24862] 0 24862 181323 713 23 4 0 0 exe [175358.195101] [24864] 65534 24864 17181731394 40859 231 6 0 0 exe [175358.204007] [25464] 0 25464 180167 648 21 4 0 0 image [175358.214244] [28312] 0 28312 315400 4068 65 9 0 0 image [175358.224581] [28319] 0 28319 442694 7615 175 11 0 0 exe [175358.234618] [28320] 0 28320 50715370 30115796 68984 170 0 0 exe [175358.243262] [28346] 0 28346 1 0 1 1 0 0 exe [175358.251914] [28360] 0 28360 23855 4021 52 6 0 0 exe [175358.260568] [38106] 0 38106 315048 3710 60 10 0 0 image [175358.269398] [38116] 0 38116 29440 4194 31 6 0 0 exe [175358.278055] [38152] 0 38152 4539 1 5 4 0 0 exe [175358.286703] [38163] 0 38163 773 1 5 4 0 0 exe [175358.296127] [38167] 0 38167 4576 1 5 4 0 0 exe [175358.304838] [38170] 0 38170 482 1 5 4 0 0 exe [175358.313573] [38178] 0 38178 475 1 5 4 0 0 exe [175358.322218] [38180] 0 38180 443 1 5 4 0 0 exe [175358.330862] [38197] 0 38197 4569 1 5 4 0 0 exe [175358.339526] [38204] 0 38204 4867 1 5 4 0 0 exe [175358.348193] [38250] 0 38250 6582 1 9 5 0 0 exe [175358.356840] [38257] 0 38257 6936 1 9 5 0 0 exe [175358.366869] [38267] 0 38267 6638 1 9 5 0 0 exe [175358.375527] [38335] 0 38335 6801 1 10 5 0 0 exe [175358.384220] [43215] 0 43215 179815 653 20 5 0 0 image [175358.394424] [43220] 0 43220 181323 762 23 5 0 0 exe [175358.403071] [43226] 65534 43226 17181666072 8587 113 6 0 0 exe [175358.411980] [44668] 0 44668 180167 672 21 4 0 0 image [175358.420803] [44724] 0 44724 179882 685 21 4 0 0 image [175358.429621] [44733] 0 44733 180974 685 23 5 0 0 exe [175358.438446] [44738] 65534 44738 17181653458 6848 89 6 0 0 exe [175358.447428] [45141] 0 45141 180167 668 20 5 0 0 image [175358.457626] [55571] 0 55571 179946 615 20 5 0 0 image [175358.466445] [55577] 0 55577 180234 686 21 5 0 0 image [175358.476670] [55862] 0 55862 121165 62399 170 5 0 0 syz-manager [175358.486015] [55863] 0 55863 76602 52378 155 5 0 0 syz-manager [175358.495360] [55864] 0 55864 111726 55645 190 5 0 0 syz-manager [175358.504783] Out of memory: Kill process 28320 (exe) score 946 or sacrifice child [175358.512927] Killed process 28320 (exe) total-vm:202861480kB, anon-rss:120420108kB, file-rss:0kB, shmem-rss:45188kB [175366.645578] oom_reaper: reaped process 28320 (exe), now anon-rss:0kB, file-rss:0kB, shmem-rss:45196kB [175366.693167] kvm [57642]: vcpu0, guest rIP: 0xa74064 unhandled rdmsr: 0xce [178598.208219] kvm [26880]: vcpu0, guest rIP: 0xa74064 unhandled rdmsr: 0xce [196368.150210] INFO: rcu_sched self-detected stall on CPU [196368.155762] 8-...: (5248 ticks this GP) idle=333/140000000000001/0 softirq=21787059/21787059 fqs=2382 [196368.158205] INFO: rcu_sched detected stalls on CPUs/tasks: [196368.158208] 8-...: (5248 ticks this GP) idle=333/140000000000001/0 softirq=21787059/21787059 fqs=2382 [196368.158215] (detected by 20, t=5252 jiffies, g=7979813, c=7979812, q=71359) [196368.158215] Task dump for CPU 8: [196368.158218] exe R running task 0 39721 39117 0x00000188 [196368.158222] ffff9fb081a9ac80 ffffba19a5d2be58 000000001046f000 ffffffffa0fbac26 [196368.158224] ffff9fca6fae3200 000000000001044e 000000000001046e 0000000000000000 [196368.158225] 4fd5c60d50c52f3b ffff9fca6fae3088 ffffba19a5d2bec8 ffff9fca6fae3130 [196368.158226] Call Trace: [196368.158234] [] ? unmap_mapping_range+0x116/0x140 [196368.158238] [] ? shmem_fallocate+0x30d/0x4e0 [196368.158241] [] ? vfs_fallocate+0x15a/0x220 [196368.158243] [] ? SyS_fallocate+0x43/0x80 [196368.158246] [] ? do_syscall_64+0x8d/0x100 [196368.158250] [] ? entry_SYSCALL_64_after_swapgs+0x58/0xc6 [196368.262941] (t=5278 jiffies g=7979813 c=7979812 q=71916) [196368.268784] Task dump for CPU 8: [196368.272221] exe R running task 0 39721 39117 0x00000188 [196368.279738] ffffffffa1b19a00 ffffffffa0ea953b 0000000000000008 ffffffffa1b19a00 [196368.287983] ffffffffa0f830ad ffff9fca868196c0 ffffffffa1a4fd80 0000000000000000 [196368.296116] ffffffffa1b19a00 00000000ffffffff ffffffffa0ee51ca 0000000002ac8e6b [196368.304324] Call Trace: [196368.306974] [196368.309103] [] ? sched_show_task+0xcb/0x130 [196368.315161] [] ? rcu_dump_cpu_stacks+0x92/0xb2 [196368.321451] [] ? rcu_check_callbacks+0x75a/0x8b0 [196368.328093] [] ? tick_sched_do_timer+0x30/0x30 [196368.334385] [] ? update_process_times+0x28/0x50 [196368.340767] [] ? tick_sched_handle.isra.12+0x20/0x50 [196368.347585] [] ? tick_sched_timer+0x38/0x70 [196368.362574] [] ? __hrtimer_run_queues+0xde/0x250 [196368.369055] [] ? hrtimer_interrupt+0x9c/0x1a0 [196368.375272] [] ? 0xffffffffc08da000 [196368.380617] [] ? smp_apic_timer_interrupt+0x47/0x60 [196368.387344] [] ? apic_timer_interrupt+0x9e/0xb0 [196368.393726] [196368.395858] [] ? 0xffffffffc08da000 [196368.401219] [] ? smp_call_function_many+0x1f2/0x250 [196368.407997] [] ? kvm_make_all_cpus_request+0xdd/0x110 [kvm] [196368.415435] [] ? kvm_flush_remote_tlbs+0x18/0x40 [kvm] [196368.422450] [] ? kvm_mmu_notifier_invalidate_range_start+0x56/0x80 [kvm] [196368.431005] [] ? __mmu_notifier_invalidate_range_start+0x52/0x80 [196368.438877] [] ? zap_page_range_single+0xf7/0x110 [196368.445432] [] ? unmap_mapping_range+0x116/0x140 [196368.451900] [] ? shmem_fallocate+0x30d/0x4e0 [196368.458019] [] ? vfs_fallocate+0x15a/0x220 [196368.464092] [] ? SyS_fallocate+0x43/0x80 [196368.469879] [] ? do_syscall_64+0x8d/0x100 [196368.475745] [] ? entry_SYSCALL_64_after_swapgs+0x58/0xc6 [196431.164035] INFO: rcu_sched self-detected stall on CPU [196431.169578] 8-...: (20921 ticks this GP) idle=333/140000000000001/0 softirq=21787059/21787059 fqs=9514 [196431.172026] INFO: rcu_sched detected stalls on CPUs/tasks: [196431.172031] 8-...: (20921 ticks this GP) idle=333/140000000000001/0 softirq=21787059/21787059 fqs=9514 [196431.172037] (detected by 20, t=21007 jiffies, g=7979813, c=7979812, q=254885) [196431.172038] Task dump for CPU 8: [196431.172041] exe R running task 0 39721 39117 0x00000188 [196431.172045] ffff9fb081a9ac80 ffffba19a5d2be58 000000001046f000 ffffffffa0fbac26 [196431.172047] ffff9fca6fae3200 000000000001044e 000000000001046e 0000000000000000 [196431.172049] 4fd5c60d50c52f3b ffff9fca6fae3088 ffffba19a5d2bec8 ffff9fca6fae3130 [196431.172050] Call Trace: [196431.172063] [] ? unmap_mapping_range+0x116/0x140 [196431.172067] [] ? shmem_fallocate+0x30d/0x4e0 [196431.172071] [] ? vfs_fallocate+0x15a/0x220 [196431.172073] [] ? SyS_fallocate+0x43/0x80 [196431.172076] [] ? do_syscall_64+0x8d/0x100 [196431.172079] [] ? entry_SYSCALL_64_after_swapgs+0x58/0xc6 [196431.289258] (t=21036 jiffies g=7979813 c=7979812 q=255141) [196431.295268] Task dump for CPU 8: [196431.298703] exe R running task 0 39721 39117 0x00000188 [196431.306333] ffffffffa1b19a00 ffffffffa0ea953b 0000000000000008 ffffffffa1b19a00 [196431.314473] ffffffffa0f830ad ffff9fca868196c0 ffffffffa1a4fd80 0000000000000000 [196431.322690] ffffffffa1b19a00 00000000ffffffff ffffffffa0ee51ca 0000000002bb80ac [196431.330900] Call Trace: [196431.333550] [196431.335685] [] ? sched_show_task+0xcb/0x130 [196431.341756] [] ? rcu_dump_cpu_stacks+0x92/0xb2 [196431.348149] [] ? rcu_check_callbacks+0x75a/0x8b0 [196431.354633] [] ? tick_sched_do_timer+0x30/0x30 [196431.360935] [] ? update_process_times+0x28/0x50 [196431.367322] [] ? tick_sched_handle.isra.12+0x20/0x50 [196431.374145] [] ? tick_sched_timer+0x38/0x70 [196431.380556] [] ? __hrtimer_run_queues+0xde/0x250 [196431.387029] [] ? hrtimer_interrupt+0x9c/0x1a0 [196431.393247] [] ? 0xffffffffc08da000 [196431.398850] [] ? smp_apic_timer_interrupt+0x47/0x60 [196431.405588] [] ? apic_timer_interrupt+0x9e/0xb0 [196431.412059] [196431.414304] [] ? 0xffffffffc08da000 [196431.419671] [] ? smp_call_function_many+0x1f5/0x250 [196431.426448] [] ? kvm_make_all_cpus_request+0xdd/0x110 [kvm] [196431.433897] [] ? kvm_flush_remote_tlbs+0x18/0x40 [kvm] [196431.440934] [] ? kvm_mmu_notifier_invalidate_range_start+0x56/0x80 [kvm] [196431.449700] [] ? __mmu_notifier_invalidate_range_start+0x52/0x80 [196431.460434] [] ? zap_page_range_single+0xf7/0x110 [196431.467112] [] ? unmap_mapping_range+0x116/0x140 [196431.473592] [] ? shmem_fallocate+0x30d/0x4e0 [196431.479809] [] ? vfs_fallocate+0x15a/0x220 [196431.485853] [] ? SyS_fallocate+0x43/0x80 [196431.491638] [] ? do_syscall_64+0x8d/0x100 [196431.497509] [] ? entry_SYSCALL_64_after_swapgs+0x58/0xc6 [196494.181856] INFO: rcu_sched self-detected stall on CPU [196494.185849] INFO: rcu_sched detected stalls on CPUs/tasks: [196494.185857] 8-...: (36593 ticks this GP) idle=333/140000000000001/0 softirq=21787059/21787059 fqs=16616 [196494.185858] [196494.185863] (detected by 20, t=36762 jiffies, g=7979813, c=7979812, q=392287) [196494.185864] Task dump for CPU 8: [196494.185865] exe R [196494.185866] running task [196494.185867] 0 39721 39117 0x00000188 [196494.185870] ffff9fb081a9ac80 [196494.185870] ffffba19a5d2be58 [196494.185871] 000000001046f000 [196494.185871] ffffffffa0fbac26 [196494.185872] ffff9fca6fae3200 [196494.185872] 000000000001044e [196494.185873] 000000000001046e [196494.185874] 0000000000000000 [196494.185874] 4fd5c60d50c52f3b [196494.185875] ffff9fca6fae3088 [196494.185875] ffffba19a5d2bec8 [196494.185876] ffff9fca6fae3130 [196494.185876] Call Trace: [196494.185885] [] ? unmap_mapping_range+0x116/0x140 [196494.185889] [] ? shmem_fallocate+0x30d/0x4e0 [196494.185893] [] ? vfs_fallocate+0x15a/0x220 [196494.185895] [] ? SyS_fallocate+0x43/0x80 [196494.185898] [] ? do_syscall_64+0x8d/0x100 [196494.185901] [] ? entry_SYSCALL_64_after_swapgs+0x58/0xc6 [196494.287979] 8-...: (36593 ticks this GP) idle=333/140000000000001/0 softirq=21787059/21787059 fqs=16629 [196494.297867] (t=36790 jiffies g=7979813 c=7979812 q=392551) [196494.303875] Task dump for CPU 8: [196494.307577] exe R running task 0 39721 39117 0x00000188 [196494.315108] ffffffffa1b19a00 ffffffffa0ea953b 0000000000000008 ffffffffa1b19a00 [196494.323350] ffffffffa0f830ad ffff9fca868196c0 ffffffffa1a4fd80 0000000000000000 [196494.331613] ffffffffa1b19a00 00000000ffffffff ffffffffa0ee51ca 0000000002ac840c [196494.339801] Call Trace: [196494.342550] [196494.344685] [] ? sched_show_task+0xcb/0x130 [196494.350832] [] ? rcu_dump_cpu_stacks+0x92/0xb2 [196494.357135] [] ? rcu_check_callbacks+0x75a/0x8b0 [196494.364020] [] ? tick_sched_do_timer+0x30/0x30 [196494.370416] [] ? update_process_times+0x28/0x50 [196494.376886] [] ? tick_sched_handle.isra.12+0x20/0x50 [196494.383713] [] ? tick_sched_timer+0x38/0x70 [196494.389755] [] ? __hrtimer_run_queues+0xde/0x250 [196494.396249] [] ? hrtimer_interrupt+0x9c/0x1a0 [196494.402468] [] ? 0xffffffffc08da000 [196494.408232] [] ? smp_apic_timer_interrupt+0x47/0x60 [196494.416227] [] ? apic_timer_interrupt+0x9e/0xb0 [196494.423892] [196494.426496] [] ? 0xffffffffc08da000 [196494.434556] [] ? smp_call_function_many+0x1f2/0x250 [196494.444472] [] ? kvm_make_all_cpus_request+0xdd/0x110 [kvm] [196494.452214] [] ? kvm_flush_remote_tlbs+0x18/0x40 [kvm] [196494.459553] [] ? kvm_mmu_notifier_invalidate_range_start+0x56/0x80 [kvm] [196494.469276] [] ? __mmu_notifier_invalidate_range_start+0x52/0x80 [196494.478218] [] ? zap_page_range_single+0xf7/0x110 [196494.484786] [] ? unmap_mapping_range+0x116/0x140 [196494.491262] [] ? shmem_fallocate+0x30d/0x4e0 [196494.497477] [] ? vfs_fallocate+0x15a/0x220 [196494.503432] [] ? SyS_fallocate+0x43/0x80 [196494.509211] [] ? do_syscall_64+0x8d/0x100 [196494.515070] [] ? entry_SYSCALL_64_after_swapgs+0x58/0xc6 [196497.973446] NMI watchdog: BUG: soft lockup - CPU#11 stuck for 134s! [exe:39453] [196497.981117] Modules linked in: rose nfc netrom llc2 ax25 vmw_vsock_vmci_transport vsock vmw_vmci fcrypt pcbc af_rxrpc pptp gre l2tp_ppp l2tp_netlink pppoe pppox pn_pep phonet xfrm_user nfnetlink l2tp_ip6 l2tp_ip l2tp_core ip6_udp_tunnel udp_tunnel af_key xfrm_algo ipx p8023 p8022 psnap llc sctp libcrc32c can_raw can_bcm can rfcomm hidp cmtp kernelcapi bnep af_alg vhost_net vhost macvtap macvlan hci_vhci bluetooth rfkill vfio_iommu_type1 vfio uinput uhid hid tun ppp_generic slhc loop cuse fuse btrfs xor raid6_pq sb_edac edac_core kvm_intel kvm irqbypass crct10dif_pclmul crc32_pclmul ghash_clmulni_intel intel_rapl_perf sg pvpanic evdev serio_raw button ip_tables x_tables autofs4 ext4 crc16 jbd2 crc32c_generic fscrypto ecb mbcache sd_mod virtio_scsi scsi_mod virtio_net crc32c_intel aesni_intel aes_x86_64 [196498.066846] glue_helper lrw gf128mul virtio_pci ablk_helper virtio_ring cryptd psmouse virtio i2c_piix4 [196498.076432] CPU: 11 PID: 39453 Comm: exe Not tainted 4.9.0-11-amd64 #1 Debian 4.9.189-3+deb9u2 [196498.085241] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 [196498.094660] task: ffff9fb7dd77d100 task.stack: ffffba1990ce4000 [196498.100789] RIP: 0010:[] [] native_queued_spin_lock_slowpath+0xf/0x1a0 [196498.110987] RSP: 0018:ffffba1990ce7bf0 EFLAGS: 00000202 [196498.117831] RAX: 0000000000000001 RBX: 0000000000000002 RCX: 00000000003ed5cd [196498.126114] RDX: 0000000000000001 RSI: 0000000000000001 RDI: ffff9fb2d6a48000 [196498.133553] RBP: ffff9faf3e981000 R08: 0000000000000007 R09: 8000000000000000 [196498.141217] R10: 00000000322e631a R11: 0000000000000001 R12: 00003fb12b6da000 [196498.148823] R13: 00000003fb12b6da R14: 0000000000000000 R15: 0000000000000000 [196498.156170] FS: 000000c0002c8090(0000) GS:ffff9fca868c0000(0000) knlGS:0000000000000000 [196498.164468] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 [196498.170551] CR2: 00007faef3ceda68 CR3: 00000016f14bc000 CR4: 0000000000162670 [196498.177915] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000 [196498.185872] DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400 [196498.193501] Stack: [196498.195812] ffffffffa141e2fd ffffffffc0908f1b ffffba1990ce7c27 ffffffffc09cf00c [196498.209893] 0000000000000000 000000000000225f 0100ffffc09cf018 0000000100000000 [196498.218866] 00000000003ed5cd 982c609cbd0a1ac5 ffff9faf3e981000 00003fb12b6da000 [196498.227298] Call Trace: [196498.230179] [] ? _raw_spin_lock+0x1d/0x20 [196498.241555] [] ? tdp_page_fault+0x22b/0x2c0 [kvm] [196498.248149] [] ? vmexit_fill_RSB+0xc/0x30 [kvm_intel] [196498.255093] [] ? kvm_mmu_page_fault+0x5f/0x120 [kvm] [196498.261923] [] ? vmx_handle_exit+0x1d1/0x1680 [kvm_intel] [196498.269188] [] ? vmexit_fill_RSB+0x18/0x30 [kvm_intel] [196498.276194] [] ? vmexit_fill_RSB+0xc/0x30 [kvm_intel] [196498.283104] [] ? vmexit_fill_RSB+0x18/0x30 [kvm_intel] [196498.290116] [] ? vmexit_fill_RSB+0xc/0x30 [kvm_intel] [196498.302521] [] ? vmexit_fill_RSB+0x18/0x30 [kvm_intel] [196498.309631] [] ? vmexit_fill_RSB+0xc/0x30 [kvm_intel] [196498.349360] [] ? vmexit_fill_RSB+0x18/0x30 [kvm_intel] [196498.356471] [] ? vmexit_fill_RSB+0xc/0x30 [kvm_intel] [196498.386596] [] ? vmx_vcpu_run+0x33f/0x4f0 [kvm_intel] [196498.393541] [] ? kvm_arch_vcpu_ioctl_run+0x801/0x16d0 [kvm] [196498.401003] [] ? kvm_arch_vcpu_load+0x61/0x290 [kvm] [196498.407855] [] ? recalc_sigpending+0x17/0x50 [196498.414017] [] ? kvm_vcpu_ioctl+0x315/0x5e0 [kvm] [196498.420587] [] ? signal_setup_done+0x67/0xb0 [196498.426852] [] ? __seccomp_filter+0x74/0x270 [196498.432990] [] ? do_vfs_ioctl+0xa2/0x620 [196498.438773] [] ? __audit_syscall_entry+0xaa/0xf0 [196498.445401] [] ? syscall_trace_enter+0x1ae/0x2c0 [196498.451883] [] ? SyS_ioctl+0x74/0x80 [196498.457318] [] ? do_syscall_64+0x8d/0x100 [196498.463276] [] ? entry_SYSCALL_64_after_swapgs+0x58/0xc6 [196498.470447] Code: 75 ce 48 8b 04 24 c6 03 01 48 c7 00 00 00 00 00 e9 90 fe ff ff 0f 1f 80 00 00 00 00 0f 1f 44 00 00 0f 1f 44 00 00 ba 01 00 00 00 <8b> 07 85 c0 75 0a f0 0f b1 17 85 c0 75 f2 f3 c3 f3 90 eb ec 81 [196498.498810] Sending NMI from CPU 11 to CPUs 0-10,12-63: [196498.504256] NMI backtrace for cpu 1 skipped: idling at pc 0xffffffffa141dc3e [196498.511531] NMI backtrace for cpu 33 [196498.515345] CPU: 33 PID: 39691 Comm: exe Not tainted 4.9.0-11-amd64 #1 Debian 4.9.189-3+deb9u2 [196498.524156] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 [196498.533573] task: ffff9fbfdfa0b140 task.stack: ffffba199f2fc000 [196498.539690] RIP: 0010:[] c [] native_queued_spin_lock_slowpath+0x21/0x1a0 [196498.549907] RSP: 0018:ffffba199f2ffbf0 EFLAGS: 00000202 [196498.555432] RAX: 0000000000000001 RBX: 0000000000000002 RCX: 0000000000b26eb2 [196498.562785] RDX: 0000000000000001 RSI: 0000000000000001 RDI: ffff9fb2d6a48000 [196498.570116] RBP: ffff9fca3da29b80 R08: 0000000000000007 R09: 8000000000000000 [196498.577448] R10: 0000000037aa5555 R11: 0000000000000001 R12: 00003fb12c143020 [196498.584778] R13: 00000003fb12c143 R14: 0000000000000000 R15: 0000000000000000 [196498.592110] FS: 000000c0006b6e90(0000) GS:ffff9fca86e40000(0000) knlGS:0000000000000000 [196498.600404] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 [196498.606355] CR2: 00007f102bf69a68 CR3: 00000016f14bc000 CR4: 0000000000162670 [196498.613820] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000 [196498.621155] DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400 [196498.628493] Stack: [196498.630704] ffffffffa141e2fdc ffffffffc0908f1bc ffffba199f2ffc27c ffffffffc09cf00cc [196498.639001] 0000000000000000c 000000000000225fc 0100ffffc09cf018c 0000000100000000c [196498.647303] 0000000000b26eb2c 47cbf6fcbbd154e0c ffff9fca3da29b80c 00003fb12c143020c [196498.655588] Call Trace: [196498.658233] [] ? _raw_spin_lock+0x1d/0x20 [196498.664100] [] ? tdp_page_fault+0x22b/0x2c0 [kvm] [196498.670656] [] ? vmexit_fill_RSB+0xc/0x30 [kvm_intel] [196498.677572] [] ? kvm_mmu_page_fault+0x5f/0x120 [kvm] [196498.684388] [] ? vmx_handle_exit+0x1d1/0x1680 [kvm_intel] [196498.691634] [] ? vmexit_fill_RSB+0x18/0x30 [kvm_intel] [196498.698721] [] ? vmexit_fill_RSB+0xc/0x30 [kvm_intel] [196498.705625] [] ? vmexit_fill_RSB+0x18/0x30 [kvm_intel] [196498.712608] [] ? vmexit_fill_RSB+0xc/0x30 [kvm_intel] [196498.719506] [] ? vmexit_fill_RSB+0x18/0x30 [kvm_intel] [196498.726505] [] ? vmexit_fill_RSB+0xc/0x30 [kvm_intel] [196498.733409] [] ? vmexit_fill_RSB+0x18/0x30 [kvm_intel] [196498.740407] [] ? vmexit_fill_RSB+0xc/0x30 [kvm_intel] [196498.747304] [] ? vmx_vcpu_run+0x33f/0x4f0 [kvm_intel] [196498.754214] [] ? kvm_arch_vcpu_ioctl_run+0x801/0x16d0 [kvm] [196498.761657] [] ? kvm_arch_vcpu_load+0x61/0x290 [kvm] [196498.768474] [] ? recalc_sigpending+0x17/0x50 [196498.774601] [] ? kvm_vcpu_ioctl+0x315/0x5e0 [kvm] [196498.781154] [] ? signal_setup_done+0x67/0xb0 [196498.787272] [] ? __seccomp_filter+0x74/0x270 [196498.793409] [] ? do_vfs_ioctl+0xa2/0x620 [196498.799183] [] ? __audit_syscall_entry+0xaa/0xf0 [196498.805650] [] ? syscall_trace_enter+0x1ae/0x2c0 [196498.812134] [] ? SyS_ioctl+0x74/0x80 [196498.817567] [] ? do_syscall_64+0x8d/0x100 [196498.823424] [] ? entry_SYSCALL_64_after_swapgs+0x58/0xc6 [196498.830594] Code: cfe cff cff c0f c1f c80 c00 c00 c00 c00 c0f c1f c44 c00 c00 c0f c1f c44 c00 c00 cba c01 c00 c00 c00 c8b c07 c85 cc0 c75 c0a cf0 c0f cb1 c17 c85 cc0 c75 cf2 cf3 cc3 cf3 c90 c cec c81 cfe c00 c01 c00 c00 c0f c84 c1c c01 c00 c00 c40 c30 cf6 c85 cf6 c75 c42 c [196498.860934] NMI backtrace for cpu 5 skipped: idling at pc 0xffffffffa141dc3e [196498.868217] NMI backtrace for cpu 41 skipped: idling at pc 0xffffffffa141dc3e [196498.875596] NMI backtrace for cpu 24 [196498.879402] CPU: 24 PID: 47509 Comm: exe Not tainted 4.9.0-11-amd64 #1 Debian 4.9.189-3+deb9u2 [196498.888221] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 [196498.897663] task: ffff9faf3d775040 task.stack: ffffba194dfa4000 [196498.903785] RIP: 0033:[<0000000001025674>] c [<0000000001025674>] 0x1025674 [196498.911037] RSP: 002b:00007fe8807f7ca0 EFLAGS: 00000206 [196498.916648] RAX: 00007fe8b028aeb0 RBX: 00007fe7cf83cb18 RCX: 0000000000000000 [196498.923985] RDX: 00007fe8b028b000 RSI: 00007fe7cf83cb18 RDI: 0000000000000000 [196498.931323] RBP: 00007fe7700309a8 R08: 00000000023d8100 R09: 0000000000000001 [196498.938661] R10: 0000000000000000 R11: 0000000000000001 R12: 00007fe8807f7cb0 [196498.946009] R13: fffffc0000000000 R14: 00007fe770030c78 R15: 000003ffffffffff [196498.953549] FS: 00007fe8807f8700(0000) GS:ffff9fca86c00000(0000) knlGS:0000000000000000 [196498.961871] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 [196498.967813] CR2: 00007ffce97d8a00 CR3: 0000001b66d92000 CR4: 0000000000162670 [196498.975143] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000 [196498.982478] DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400 [196498.989903] NMI backtrace for cpu 56 [196498.993695] CPU: 56 PID: 39148 Comm: kvm-nx-lpage-re Not tainted 4.9.0-11-amd64 #1 Debian 4.9.189-3+deb9u2 [196499.003660] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 [196499.013195] task: ffff9fc5a591e0c0 task.stack: ffffba197e894000 [196499.019431] RIP: 0010:[] c [] native_queued_spin_lock_slowpath+0x21/0x1a0 [196499.029643] RSP: 0018:ffffba197e897e28 EFLAGS: 00000202 [196499.035151] RAX: 0000000000000001 RBX: ffff9fb2d6a48000 RCX: 00003a4eb8815ba8 [196499.042482] RDX: 0000000000000001 RSI: 0000000000000001 RDI: ffff9fb2d6a48000 [196499.049816] RBP: ffff9fc5a591e0c0 R08: 0000b299f5aebbe3 R09: 0100000001000000 [196499.057145] R10: 0000000000000000 R11: 0000000102ec07ff R12: 0000000102ec0699 [196499.064479] R13: ffffba197e897e80 R14: ffff9fc5a591e0c0 R15: ffffffffc0945984 [196499.071919] FS: 0000000000000000(0000) GS:ffff9fca87400000(0000) knlGS:0000000000000000 [196499.080338] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 [196499.086294] CR2: 00006001b05b9000 CR3: 00000017b5208000 CR4: 0000000000162670 [196499.093771] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000 [196499.101343] DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400 [196499.108718] Stack: [196499.110936] ffffffffa141e2fdc ffffffffc0904264c ffff9fb2d6a4ac10c ffff9fc5a591e0c0c [196499.119438] ffff9fb2d6a48048c 0000000100000000c ffff9fc5a591e0c0c ffffffffa1419af2c [196499.127724] 0000000000000000c 0000000000000000c 0000000000000001c ffffba197e897e80c [196499.136107] Call Trace: [196499.138750] [] ? _raw_spin_lock+0x1d/0x20 [196499.144703] [] ? kvm_nx_lpage_recovery_worker+0x144/0x2c0 [kvm] [196499.152477] [] ? schedule+0x32/0x80 [196499.158096] [] ? kvm_exit+0x80/0x80 [kvm] [196499.163953] [] ? kthread+0xd9/0xf0 [196499.169211] [] ? __switch_to_asm+0x41/0x70 [196499.175151] [] ? kthread_park+0x60/0x60 [196499.180831] [] ? ret_from_fork+0x57/0x70 [196499.186608] Code: cfe cff cff c0f c1f c80 c00 c00 c00 c00 c0f c1f c44 c00 c00 c0f c1f c44 c00 c00 cba c01 c00 c00 c00 c8b c07 c85 cc0 c75 c0a cf0 c0f cb1 c17 c85 cc0 c75 cf2 cf3 cc3 cf3 c90 c cec c81 cfe c00 c01 c00 c00 c0f c84 c1c c01 c00 c00 c40 c30 cf6 c85 cf6 c75 c42 c [196499.220242] NMI backtrace for cpu 3 [196499.223949] CPU: 3 PID: 47098 Comm: exe Not tainted 4.9.0-11-amd64 #1 Debian 4.9.189-3+deb9u2 [196499.232669] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 [196499.242083] task: ffff9fb836707040 task.stack: ffffba1950358000 [196499.248205] RIP: 0010:[] c [] smp_call_function_many+0x1f2/0x250 [196499.257620] RSP: 0018:ffffba195035bca8 EFLAGS: 00000202 [196499.263129] RAX: 0000000000000003 RBX: 0000000000000200 RCX: 0000000000000012 [196499.270476] RDX: ffffda193f282c68 RSI: 0000000000000200 RDI: ffff9fca866d98c8 [196499.277806] RBP: ffff9fca866d98c8 R08: ffffffffffffffff R09: 00400000050c0000 [196499.285148] R10: 0000000000000008 R11: 0000000000000000 R12: ffff9fca866d98c0 [196499.292502] R13: ffffffffa0e6a890 R14: ffffba195035bcf0 R15: 0000000000000001 [196499.299846] FS: 00007fe8c67fc700(0000) GS:ffff9fca866c0000(0000) knlGS:0000000000000000 [196499.308131] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 [196499.314086] CR2: 00006002cb8e0000 CR3: 0000001b66d92000 CR4: 0000000000162670 [196499.321424] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000 [196499.328765] DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400 [196499.336105] Stack: [196499.338319] 0000000000019880c 0000000100000000c ffff9fb04ce04ed8c 000060019a2f1000c [196499.347563] ffff9fb04ce04ed8c ffff9fb04ce04c00c 000060019a2f0000c 0000000000000000c [196499.355855] ffffffffa0e6ad34c ffff9fb04ce04c00c 000060019a2f0000c 000060019a2f1000c [196499.364234] Call Trace: [196499.366882] [] ? native_flush_tlb_others+0x54/0x120 [196499.373612] [] ? flush_tlb_mm_range+0x92/0x140 [196499.379910] [] ? tlb_flush_mmu_tlbonly+0x5e/0xc0 [196499.386373] [] ? tlb_finish_mmu+0xf/0x40 [196499.392148] [] ? zap_page_range+0xe0/0x130 [196499.398089] [] ? __switch_to_asm+0x40/0x70 [196499.404043] [] ? __schedule+0x241/0x6f0 [196499.409810] [] ? hrtimer_start_range_ns+0x194/0x360 [196499.416537] [] ? SyS_madvise+0x3cc/0x900 [196499.422326] [] ? do_nanosleep+0x8d/0xe0 [196499.428021] [] ? do_syscall_64+0x8d/0x100 [196499.433887] [] ? entry_SYSCALL_64_after_swapgs+0x58/0xc6 [196499.441054] Code: cd2 ce8 cf3 cc4 c24 c00 c3b c05 ce1 c98 cc1 c00 c89 cc1 c0f c8d c93 cfe cff cff c48 c98 c49 c8b c14 c24 c48 c03 c14 cc5 c00 c04 c87 ca1 c8b c42 c18 ca8 c01 c74 c09 cf3 c90 c<8b> c42 c18 ca8 c01 c75 cf7 ceb cbf c0f cb6 c4c c24 c0c c48 c83 cc4 c10 c4c c89 cf2 c [196499.474355] NMI backtrace for cpu 30 skipped: idling at pc 0xffffffffa141dc3e [196499.481724] NMI backtrace for cpu 52 [196499.485522] CPU: 52 PID: 39718 Comm: exe Not tainted 4.9.0-11-amd64 #1 Debian 4.9.189-3+deb9u2 [196499.494335] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 [196499.503755] task: ffff9fc2f9bf2000 task.stack: ffffba19847f0000 [196499.509868] RIP: 0010:[] c [] smp_call_function_single+0xd3/0x130 [196499.519371] RSP: 0018:ffffba19847f3c30 EFLAGS: 00000202 [196499.524891] RAX: 0000000000000000 RBX: 000000000000002a RCX: ffffda193f883420 [196499.532221] RDX: 0000000000000001 RSI: ffffba19847f3c40 RDI: ffffba19847f3c40 [196499.539638] RBP: ffffffffc09d4a50 R08: 0000000000000002 R09: ffff9faf6e527400 [196499.546968] R10: 000000000000002b R11: ffff9faf40e21338 R12: 000000000000002a [196499.554297] R13: ffff9fb535d21dc0 R14: ffff9faf40e21300 R15: 0000000000000034 [196499.561628] FS: 000000c000620790(0000) GS:ffff9fca87300000(0000) knlGS:0000000000000000 [196499.570000] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 [196499.575941] CR2: 00007ffc68de2a00 CR3: 00000016f14bc000 CR4: 0000000000162670 [196499.584331] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000 [196499.591662] DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400 [196499.598994] Stack: [196499.601203] ffff9fc2f9bf2000c 0000000000000000c ffffda193f883420c ffffffffc09d4a50c [196499.609489] ffff9fb535d26358c 0000000000000003c 5a830a4d7bad0390c 0000000000000034c [196499.618751] ffffba19847f3d90c ffffffffc09d3e9fc 0000000000000000c 0000000000000000c [196499.627039] Call Trace: [196499.629952] [] ? update_debugctlmsr+0x20/0x20 [kvm_intel] [196499.637290] [] ? vmx_vcpu_load+0x9f/0x360 [kvm_intel] [196499.644287] [] ? futex_wait+0x223/0x250 [196499.649969] [] ? __switch_to_asm+0x41/0x70 [196499.656036] [] ? __switch_to_asm+0x35/0x70 [196499.661993] [] ? __switch_to_asm+0x41/0x70 [196499.667937] [] ? __switch_to_asm+0x35/0x70 [196499.673902] [] ? __switch_to_asm+0x41/0x70 [196499.679845] [] ? __switch_to_asm+0x35/0x70 [196499.685797] [] ? __switch_to_asm+0x41/0x70 [196499.691739] [] ? __switch_to_asm+0x35/0x70 [196499.697682] [] ? __switch_to_asm+0x41/0x70 [196499.703629] [] ? __switch_to_asm+0x35/0x70 [196499.709581] [] ? __switch_to_asm+0x41/0x70 [196499.715526] [] ? __switch_to_asm+0x35/0x70 [196499.721467] [] ? __switch_to_asm+0x41/0x70 [196499.727409] [] ? __switch_to_asm+0x35/0x70 [196499.733353] [] ? __switch_to_asm+0x41/0x70 [196499.739299] [] ? __switch_to_asm+0x35/0x70 [196499.745244] [] ? __switch_to_asm+0x41/0x70 [196499.751186] [] ? kvm_arch_vcpu_load+0x46/0x290 [kvm] [196499.758000] [] ? recalc_sigpending+0x17/0x50 [196499.764126] [] ? vcpu_load+0x3c/0x50 [kvm] [196499.770090] [] ? kvm_vcpu_ioctl+0x77/0x5e0 [kvm] [196499.776552] [] ? signal_setup_done+0x67/0xb0 [196499.782675] [] ? __seccomp_filter+0x74/0x270 [196499.788789] [] ? do_vfs_ioctl+0xa2/0x620 [196499.794556] [] ? __audit_syscall_entry+0xaa/0xf0 [196499.801017] [] ? syscall_trace_enter+0x1ae/0x2c0 [196499.807488] [] ? SyS_ioctl+0x74/0x80 [196499.812912] [] ? do_syscall_64+0x8d/0x100 [196499.818767] [] ? entry_SYSCALL_64_after_swapgs+0x58/0xc6 [196499.825938] Code: c25 c28 c00 c00 c00 c75 c73 c48 c83 cc4 c38 c5b c5d cc3 c48 c8d c74 c24 c10 c48 c89 cd1 c89 cdf c48 c89 cea ce8 c28 cfe cff cff c8b c54 c24 c28 c83 ce2 c01 c74 c0b cf3 c90 c<8b> c54 c24 c28 c83 ce2 c01 c75 cf5 ceb cc1 c8b c05 cc4 c85 cdb c00 c85 cc0 c75 c83 c [196499.856707] NMI backtrace for cpu 37 [196499.860521] CPU: 37 PID: 65501 Comm: exe Not tainted 4.9.0-11-amd64 #1 Debian 4.9.189-3+deb9u2 [196499.869765] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 [196499.879297] task: ffff9fc5a3a950c0 task.stack: ffffba194e990000 [196499.885426] RIP: 0033:[<00000000010177ab>] c [<00000000010177ab>] 0x10177ab [196499.892678] RSP: 002b:00007fed8a41cb28 EFLAGS: 00000297 [196499.898287] RAX: 00007fed7450ccd8 RBX: 00007fed7450cc58 RCX: 00007fed745113c5 [196499.905883] RDX: 000000000000fe00 RSI: 0000000000000000 RDI: 00007fed7450ccd8 [196499.913319] RBP: 0000000000000304 R08: 00007fed7451cad8 R09: 00000000000005b2 [196499.920663] R10: 0000000000000000 R11: 000000000000000b R12: 0000000000034d5b [196499.928003] R13: 00000000000005b2 R14: 0000000000000000 R15: 00007fed8a41cbe0 [196499.935351] FS: 00007fed8a41d700(0000) GS:ffff9fca86f40000(0000) knlGS:0000000000000000 [196499.943645] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 [196499.949684] CR2: 00006002eacc0000 CR3: 000000019bb22000 CR4: 0000000000162670 [196499.957023] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000 [196499.964363] DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400 [196499.971766] NMI backtrace for cpu 40 [196499.975575] CPU: 40 PID: 10785 Comm: exe Not tainted 4.9.0-11-amd64 #1 Debian 4.9.189-3+deb9u2 [196499.984818] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 [196499.994245] task: ffff9fb600394140 task.stack: ffffba197c23c000 [196500.001934] RIP: 0033:[<00000000004827fd>] c [<00000000004827fd>] 0x4827fd [196500.009108] RSP: 002b:000000c001569ca0 EFLAGS: 00000217 [196500.014619] RAX: 0000000000000000 RBX: 0000000000000001 RCX: 00000000004827d5 [196500.022041] RDX: 0000000000000000 RSI: 0000000000002a59 RDI: 000000000000001f [196500.029561] RBP: 000000c001569da0 R08: 0000000000000000 R09: 0000000000000000 [196500.036992] R10: 0000000000000000 R11: 0000000000000246 R12: 0000000000000001 [196500.044419] R13: 0000000000000001 R14: 0000000000ef9cd8 R15: 0000000000000000 [196500.052620] FS: 000000c0011ba090(0000) GS:ffff9fca87000000(0000) knlGS:0000000000000000 [196500.061012] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 [196500.066959] CR2: 00007ffce97d8a00 CR3: 00000006ee902000 CR4: 0000000000162670 [196500.074646] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000 [196500.082512] DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400 [196500.090210] NMI backtrace for cpu 8 [196500.094002] CPU: 8 PID: 39721 Comm: exe Not tainted 4.9.0-11-amd64 #1 Debian 4.9.189-3+deb9u2 [196500.102832] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 [196500.112248] task: ffff9fc397af90c0 task.stack: ffffba19a5d28000 [196500.118375] RIP: 0010:[] c [] smp_call_function_many+0x1f2/0x250 [196500.127797] RSP: 0018:ffffba19a5d2bbe0 EFLAGS: 00000202 [196500.133304] RAX: 0000000000000003 RBX: 0000000000000200 RCX: 000000000000002a [196500.140633] RDX: ffffda193f883420 RSI: 0000000000000200 RDI: ffff9fca868198c8 [196500.147966] RBP: ffff9fca868198c8 R08: ffffffff00000000 R09: 2800040080002800 [196500.155295] R10: 0000000000000008 R11: 0000000000000008 R12: ffff9fca868198c0 [196500.162632] R13: ffffffffc08da000 R14: 0000000000000000 R15: 0000000000000001 [196500.169970] FS: 000000c000620e90(0000) GS:ffff9fca86800000(0000) knlGS:0000000000000000 [196500.178262] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 [196500.184225] CR2: 0000000020006660 CR3: 00000016f14bc000 CR4: 0000000000162670 [196500.191556] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000 [196500.198888] DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400 [196500.206225] Stack: [196500.208438] 0000000000019880c 00000001c0900f40c ffffba19a5d2bc28c 0000000000000001c [196500.217596] ffff9fb2d6a48048c 00003fb02c800000c 00003fb02c821000c 0000000000000001c [196500.225886] ffffffffc08dfe3dc 2800040080002800c 0000000000000000c 0000000000000000c [196500.234183] Call Trace: [196500.236827] [] ? kvm_make_all_cpus_request+0xdd/0x110 [kvm] [196500.244264] [] ? kvm_flush_remote_tlbs+0x18/0x40 [kvm] [196500.251431] [] ? kvm_mmu_notifier_invalidate_range_start+0x56/0x80 [kvm] [196500.260111] [] ? __mmu_notifier_invalidate_range_start+0x52/0x80 [196500.267969] [] ? zap_page_range_single+0xf7/0x110 [196500.274524] [] ? unmap_mapping_range+0x116/0x140 [196500.280990] [] ? shmem_fallocate+0x30d/0x4e0 [196500.287113] [] ? vfs_fallocate+0x15a/0x220 [196500.293059] [] ? SyS_fallocate+0x43/0x80 [196500.298848] [] ? do_syscall_64+0x8d/0x100 [196500.304817] [] ? entry_SYSCALL_64_after_swapgs+0x58/0xc6 [196500.311986] Code: cd2 ce8 cf3 cc4 c24 c00 c3b c05 ce1 c98 cc1 c00 c89 cc1 c0f c8d c93 cfe cff cff c48 c98 c49 c8b c14 c24 c48 c03 c14 cc5 c00 c04 c87 ca1 c8b c42 c18 ca8 c01 c74 c09 cf3 c90 c<8b> c42 c18 ca8 c01 c75 cf7 ceb cbf c0f cb6 c4c c24 c0c c48 c83 cc4 c10 c4c c89 cf2 c [196500.342518] NMI backtrace for cpu 20 [196500.346336] CPU: 20 PID: 40448 Comm: exe Not tainted 4.9.0-11-amd64 #1 Debian 4.9.189-3+deb9u2 [196500.355155] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 [196500.367350] task: ffff9fbc1d77a100 task.stack: ffffba19a9de8000 [196500.378344] RIP: 0010:[] c [] native_queued_spin_lock_slowpath+0x21/0x1a0 [196500.388551] RSP: 0018:ffffba19a9debbf0 EFLAGS: 00000202 [196500.394072] RAX: 0000000000000001 RBX: 0000000000000002 RCX: 00000000003d4482 [196500.401419] RDX: 0000000000000001 RSI: 0000000000000001 RDI: ffff9fb2d6a48000 [196500.408753] RBP: ffff9faf3e1ca000 R08: 0000000000000007 R09: 8000000000000000 [196500.416089] R10: 00000000133c80a0 R11: 0000000000000001 R12: 00003fb13d7f8d60 [196500.423421] R13: 00000003fb13d7f8 R14: 0000000000000000 R15: 0000000000000000 [196500.430776] FS: 000000c0002c9210(0000) GS:ffff9fca86b00000(0000) knlGS:ffff80c0009db200 [196500.439075] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 [196500.445018] CR2: 00007ffc68de2a00 CR3: 00000016f14bc000 CR4: 0000000000162670 [196500.452364] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000 [196500.459784] DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400 [196500.467139] Stack: [196500.469348] ffffffffa141e2fdc ffffffffc0908f1bc ffffba19a9debc27c ffffffffc09cf00cc [196500.477808] 0000000000000000c 000000000000225fc 0100ffffc09cf018c 0000000100000000c [196500.486117] 00000000003d4482c cdb988f5365913ebc ffff9faf3e1ca000c 00003fb13d7f8d60c [196500.494408] Call Trace: [196500.497053] [] ? _raw_spin_lock+0x1d/0x20 [196500.502918] [] ? tdp_page_fault+0x22b/0x2c0 [kvm] [196500.509477] [] ? vmexit_fill_RSB+0xc/0x30 [kvm_intel] [196500.516377] [] ? kvm_mmu_page_fault+0x5f/0x120 [kvm] [196500.523191] [] ? vmx_handle_exit+0x1d1/0x1680 [kvm_intel] [196500.530541] [] ? vmexit_fill_RSB+0x18/0x30 [kvm_intel] [196500.537532] [] ? vmexit_fill_RSB+0xc/0x30 [kvm_intel] [196500.544430] [] ? vmexit_fill_RSB+0x18/0x30 [kvm_intel] [196500.551430] [] ? vmexit_fill_RSB+0xc/0x30 [kvm_intel] [196500.558330] [] ? vmexit_fill_RSB+0x18/0x30 [kvm_intel] [196500.565339] [] ? vmexit_fill_RSB+0xc/0x30 [kvm_intel] [196500.572332] [] ? vmexit_fill_RSB+0x18/0x30 [kvm_intel] [196500.579336] [] ? vmexit_fill_RSB+0xc/0x30 [kvm_intel] [196500.586251] [] ? vmx_vcpu_run+0x33f/0x4f0 [kvm_intel] [196500.593154] [] ? kvm_arch_vcpu_ioctl_run+0x801/0x16d0 [kvm] [196500.600572] [] ? kvm_arch_vcpu_load+0x61/0x290 [kvm] [196500.607382] [] ? recalc_sigpending+0x17/0x50 [196500.613498] [] ? kvm_vcpu_ioctl+0x315/0x5e0 [kvm] [196500.620161] [] ? signal_setup_done+0x67/0xb0 [196500.626280] [] ? __seccomp_filter+0x74/0x270 [196500.632405] [] ? do_vfs_ioctl+0xa2/0x620 [196500.638175] [] ? __audit_syscall_entry+0xaa/0xf0 [196500.644639] [] ? syscall_trace_enter+0x1ae/0x2c0 [196500.651107] [] ? SyS_ioctl+0x74/0x80 [196500.656529] [] ? do_syscall_64+0x8d/0x100 [196500.662390] [] ? entry_SYSCALL_64_after_swapgs+0x58/0xc6 [196500.669553] Code: cfe cff cff c0f c1f c80 c00 c00 c00 c00 c0f c1f c44 c00 c00 c0f c1f c44 c00 c00 cba c01 c00 c00 c00 c8b c07 c85 cc0 c75 c0a cf0 c0f cb1 c17 c85 cc0 c75 cf2 cf3 cc3 cf3 c90 c cec c81 cfe c00 c01 c00 c00 c0f c84 c1c c01 c00 c00 c40 c30 cf6 c85 cf6 c75 c42 c [196500.699893] NMI backtrace for cpu 23 [196500.703708] CPU: 23 PID: 56341 Comm: exe Not tainted 4.9.0-11-amd64 #1 Debian 4.9.189-3+deb9u2 [196500.712521] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 [196500.721947] task: ffff9fca75d4d080 task.stack: ffffba1964b34000 [196500.728062] RIP: 0010:[] c [] do_syscall_64+0xd1/0x100 [196500.736608] RSP: 0018:ffffba1964b37f40 EFLAGS: 00000046 [196500.742119] RAX: 0000000000000180 RBX: ffffba1964b37f58 RCX: 00000000c0000100 [196500.749450] RDX: 00000000000001c2 RSI: ffff9fca75d4dac0 RDI: ffff9fca86bd8980 [196500.756820] RBP: ffff9fca75d4d080 R08: ffff9fbd0536cc40 R09: 0000000000001000 [196500.764173] R10: 0000000000000000 R11: ffff9fbd0536c180 R12: 0000000000000000 [196500.771504] R13: 0000000000000000 R14: 0000000000000000 R15: 0000000000000000 [196500.778833] FS: 000000c001183210(0000) GS:ffff9fca86bc0000(0000) knlGS:0000000000000000 [196500.787133] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 [196500.793074] CR2: 00006001eacf0000 CR3: 000000027feec000 CR4: 0000000000162670 [196500.800403] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000 [196500.807732] DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400 [196500.815062] Stack: [196500.817297] 0000000000000000c 0000000000000000c ffffffffa141e3cec 0000000000000000c [196500.825583] 0000000000ef9cd8c 0000000000000000c 0000000000000018c 000000c001609da0c [196500.833879] 0000000000000001c 0000000000000246c 0000000000000000c 0000000000000000c [196500.842165] Call Trace: [196500.844809] [] ? entry_SYSCALL_64_after_swapgs+0x58/0xc6 [196500.851965] Code: c66 c0f c1f c44 c00 c00 c65 c48 c8b c2c c25 cc0 cfb c00 c00 c48 c8b c75 c00 cf7 cc6 c0e c18 c00 c00 c75 c20 c83 c65 c08 cf9 c0f c1f c44 c00 c00 c0f c00 c2d c5f cd7 c7f c00 c<5b> c5d cc3 ce8 c57 cf6 cff cff c48 c89 cc2 ce9 c4b cff cff cff c48 c89 cdf ce8 cd7 c [196500.882303] NMI backtrace for cpu 0 [196500.886024] CPU: 0 PID: 5932 Comm: exe Not tainted 4.9.0-11-amd64 #1 Debian 4.9.189-3+deb9u2 [196500.894668] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 [196500.904091] task: ffff9fc3061ef100 task.stack: ffffba1961e44000 [196500.910213] RIP: 0033:[<000000000102515f>] c [<000000000102515f>] 0x102515f [196500.917469] RSP: 002b:00007fecafffec80 EFLAGS: 00000246 [196500.922982] RAX: 0000000000002001 RBX: 00007fed3c081aa8 RCX: 00007fec85487000 [196500.930315] RDX: 00007fec85487200 RSI: 0000000000000000 RDI: 00007fecafffec80 [196500.937644] RBP: 00007fecafffec80 R08: 00007fed043545d8 R09: 0000000000000001 [196500.944977] R10: 0000000000000000 R11: 0000000000000001 R12: 00007fed3c081ac8 [196500.952311] R13: 00007fed043545d8 R14: fffffc0000000000 R15: 000003ffffffffff [196500.959649] FS: 00007fecaffff700(0000) GS:ffff9fca86600000(0000) knlGS:0000000000000000 [196500.967962] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 [196500.973907] CR2: 0000000000c72958 CR3: 000000019bb22000 CR4: 0000000000162670 [196500.981248] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000 [196500.988619] DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400 [196500.995960] NMI backtrace for cpu 61 [196500.999779] CPU: 61 PID: 39700 Comm: exe Not tainted 4.9.0-11-amd64 #1 Debian 4.9.189-3+deb9u2 [196501.008608] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 [196501.018034] task: ffff9fbfbb401140 task.stack: ffffba19a02dc000 [196501.024152] RIP: 0010:[] c [] native_queued_spin_lock_slowpath+0x21/0x1a0 [196501.034347] RSP: 0018:ffffba19a02dfbf0 EFLAGS: 00000202 [196501.039855] RAX: 0000000000000001 RBX: 0000000000000002 RCX: 0000000000c7fb3f [196501.047186] RDX: 0000000000000001 RSI: 0000000000000001 RDI: ffff9fb2d6a48000 [196501.055564] RBP: ffff9fc302c82180 R08: 0000000000000007 R09: 8000000000000000 [196501.062900] R10: 00000003ffffffff R11: 0400000000000000 R12: 00003fb12ca79000 [196501.070229] R13: 00000003fb12ca79 R14: 0000000000000000 R15: 0000000000000000 [196501.077568] FS: 000000c000980090(0000) GS:ffff9fca87540000(0000) knlGS:0000000000000000 [196501.085853] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 [196501.091795] CR2: 0000000000c67210 CR3: 00000016f14bc000 CR4: 0000000000162670 [196501.099124] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000 [196501.106458] DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400 [196501.113787] Stack: [196501.116005] ffffffffa141e2fdc ffffffffc0908f1bc ffffba19a02dfc27c ffffffffc09cf00cc [196501.124290] 0000000000000000c 000000000000225fc 0100ffffc09cf018c 0000000100000000c [196501.132599] 0000000000c7fb3fc df381d5320de05c4c ffff9fc302c82180c 00003fb12ca79000c [196501.140885] Call Trace: [196501.143534] [] ? _raw_spin_lock+0x1d/0x20 [196501.149394] [] ? tdp_page_fault+0x22b/0x2c0 [kvm] [196501.155955] [] ? vmexit_fill_RSB+0xc/0x30 [kvm_intel] [196501.162969] [] ? kvm_mmu_page_fault+0x5f/0x120 [kvm] [196501.169784] [] ? vmx_handle_exit+0x1d1/0x1680 [kvm_intel] [196501.177029] [] ? vmexit_fill_RSB+0x18/0x30 [kvm_intel] [196501.184020] [] ? vmexit_fill_RSB+0xc/0x30 [kvm_intel] [196501.190918] [] ? vmexit_fill_RSB+0x18/0x30 [kvm_intel] [196501.197908] [] ? vmexit_fill_RSB+0xc/0x30 [kvm_intel] [196501.204815] [] ? vmexit_fill_RSB+0x18/0x30 [kvm_intel] [196501.211798] [] ? vmexit_fill_RSB+0xc/0x30 [kvm_intel] [196501.218694] [] ? vmexit_fill_RSB+0x18/0x30 [kvm_intel] [196501.225677] [] ? vmexit_fill_RSB+0xc/0x30 [kvm_intel] [196501.232574] [] ? vmx_vcpu_run+0x33f/0x4f0 [kvm_intel] [196501.239483] [] ? kvm_arch_vcpu_ioctl_run+0x801/0x16d0 [kvm] [196501.246993] [] ? kvm_arch_vcpu_load+0x100/0x290 [kvm] [196501.253889] [] ? __switch_to_asm+0x35/0x70 [196501.259834] [] ? __switch_to_asm+0x41/0x70 [196501.265775] [] ? kvm_vcpu_ioctl+0x315/0x5e0 [kvm] [196501.272326] [] ? __switch_to_asm+0x41/0x70 [196501.278799] [] ? __switch_to_asm+0x35/0x70 [196501.284753] [] ? __switch_to_asm+0x41/0x70 [196501.290699] [] ? __switch_to_asm+0x35/0x70 [196501.296648] [] ? __seccomp_filter+0x74/0x270 [196501.302767] [] ? __switch_to+0x1ef/0x670 [196501.308540] [] ? do_vfs_ioctl+0xa2/0x620 [196501.314312] [] ? __audit_syscall_entry+0xaa/0xf0 [196501.320774] [] ? syscall_trace_enter+0x1ae/0x2c0 [196501.327238] [] ? SyS_ioctl+0x74/0x80 [196501.332659] [] ? do_syscall_64+0x8d/0x100 [196501.338602] [] ? entry_SYSCALL_64_after_swapgs+0x58/0xc6 [196501.345764] Code: cfe cff cff c0f c1f c80 c00 c00 c00 c00 c0f c1f c44 c00 c00 c0f c1f c44 c00 c00 cba c01 c00 c00 c00 c8b c07 c85 cc0 c75 c0a cf0 c0f cb1 c17 c85 cc0 c75 cf2 cf3 cc3 cf3 c90 c cec c81 cfe c00 c01 c00 c00 c0f c84 c1c c01 c00 c00 c40 c30 cf6 c85 cf6 c75 c42 c [196501.398500] NMI backtrace for cpu 29 [196501.402290] CPU: 29 PID: 57111 Comm: exe Not tainted 4.9.0-11-amd64 #1 Debian 4.9.189-3+deb9u2 [196501.411111] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 [196501.420524] task: ffff9fc2f19de0c0 task.stack: ffffba1967548000 [196501.426652] RIP: 0033:[<00007ffc7f7cca5d>] c [<00007ffc7f7cca5d>] 0x7ffc7f7cca5d [196501.434352] RSP: 002b:000000c0000f3ee8 EFLAGS: 00000246 [196501.439859] RAX: 0000000000000000 RBX: 00007ffc7f7ca080 RCX: 0000000000000017 [196501.447189] RDX: 0000000000000000 RSI: 000000c0000f3f10 RDI: 000000c0000f3ee4 [196501.454517] RBP: 000000c0000f3f00 R08: 0000000000000528 R09: 0000cd85dd729d58 [196501.461848] R10: 0002bfa06c23c262 R11: 0000000000000001 R12: 0000000005bdaaa4 [196501.469199] R13: 000000c0000f3ee4 R14: 0000000000efa234 R15: 0000000000000000 [196501.476529] FS: 000000c0000e4090(0000) GS:ffff9fca86d40000(0000) knlGS:0000000000000000 [196501.484900] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 [196501.490843] CR2: 00006000bc790000 CR3: 00000006ee902000 CR4: 0000000000162670 [196501.498172] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000 [196501.505501] DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400 [196501.512839] NMI backtrace for cpu 47 skipped: idling at pc 0xffffffffa141dc3e [196501.520216] NMI backtrace for cpu 25 [196501.524018] CPU: 25 PID: 54322 Comm: exe Not tainted 4.9.0-11-amd64 #1 Debian 4.9.189-3+deb9u2 [196501.532827] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 [196501.542243] task: ffff9fb5d6e68000 task.stack: ffffba19527f0000 [196501.548361] RIP: 0010:[] c [] migrate_task_rq_fair+0x27/0x50 [196501.558124] RSP: 0018:ffff9fca86c43eb8 EFLAGS: 00000083 [196501.563637] RAX: 00006626deffec38 RBX: ffff9fb2faa8a0c0 RCX: 0000000000000207 [196501.570970] RDX: ffffffffffffff1d RSI: 000000000000001d RDI: ffff9fb2faa8a0c0 [196501.578302] RBP: 000000000000001d R08: 0000cd85dd727a9e R09: ffffffffffffffff [196501.585723] R10: 0000000000000008 R11: 0000000000000000 R12: 000000000000001d [196501.593160] R13: 0000000000000004 R14: 0000000000000046 R15: ffff9fb2faa8a3f0 [196501.600506] FS: 000000c000726090(0000) GS:ffff9fca86c40000(0000) knlGS:0000000000000000 [196501.608792] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 [196501.614736] CR2: 00006001a4440000 CR3: 000000027feec000 CR4: 0000000000162670 [196501.622066] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000 [196501.629402] DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400 [196501.636822] Stack: [196501.639046] ffff9fb2faa8a0c0c ffffffffa0ea6648c ffff9fb2faa8a0c0c 000000000000001dc [196501.647332] ffff9fb2faa8a7e4c 0000000000000004c ffffffffa0ea6de8c 0000000000000020c [196501.655617] 0000000000018980c 0000000000000282c 75f6cd511d62e616c ffffba199e87bda0c [196501.663904] Call Trace: [196501.666549] d [] ? set_task_cpu+0x68/0x190 [196501.673099] [] ? try_to_wake_up+0x128/0x3c0 [196501.679126] [] ? __hrtimer_init+0xa0/0xa0 [196501.684980] [] ? hrtimer_wakeup+0x1e/0x30 [196501.690834] [] ? __hrtimer_run_queues+0xde/0x250 [196501.697297] [] ? hrtimer_interrupt+0x9c/0x1a0 [196501.703585] [] ? smp_apic_timer_interrupt+0x47/0x60 [196501.710341] [] ? apic_timer_interrupt+0x9e/0xb0 [196501.716720] d [] ? wait_consider_task+0x29/0xb70 [196501.723803] [] ? wait_consider_task+0x104/0xb70 [196501.730195] [] ? audit_comparator+0x1b/0x60 [196501.736227] [] ? do_wait+0x173/0x240 [196501.741649] [] ? SyS_wait4+0x87/0x110 [196501.747422] [] ? task_stopped_code+0x50/0x50 [196501.753538] [] ? do_syscall_64+0x8d/0x100 [196501.759395] [] ? entry_SYSCALL_64_after_swapgs+0x58/0xc6 [196501.766572] Code: cff cff c66 c90 c0f c1f c44 c00 c00 c53 c48 c8b c47 c10 c48 c89 cfb c48 c3d c00 c01 c00 c00 c75 c12 c48 c8b c87 cd0 c01 c00 c00 c48 c8b c40 c20 c48 c29 c87 cd0 c00 c00 c00 c<48> c8d cbb c80 c00 c00 c00 ce8 c1d cfe cff cff c48 cc7 c83 c00 c02 c00 c00 c00 c00 c [196501.796912] NMI backtrace for cpu 60 skipped: idling at pc 0xffffffffa141dc3e [196501.804290] NMI backtrace for cpu 17 [196501.808093] CPU: 17 PID: 8176 Comm: exe Not tainted 4.9.0-11-amd64 #1 Debian 4.9.189-3+deb9u2 [196501.816813] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 [196501.826226] task: ffff9faf4abfd000 task.stack: ffffba1974320000 [196501.832354] RIP: 0010:[] c [] vmx_complete_atomic_exit.constprop.93+0x5e/0x80 [kvm_intel] [196501.843948] RSP: 0018:ffffba1974323ce0 EFLAGS: 00000046 [196501.849477] RAX: 0000000080000200 RBX: ffff9fc6b5dea300 RCX: ffff9fc6b5dea300 [196501.856807] RDX: 0000000080000202 RSI: 0000000000000000 RDI: ffff9fc6b5dea300 [196501.864137] RBP: ffff9fc6b5dea300 R08: 0000000000000000 R09: 0000000000000000 [196501.871477] R10: 0000000000000000 R11: 0000000000000000 R12: 0000000000000000 [196501.878815] R13: 0000000000000000 R14: 0000000000000000 R15: 0000000000000000 [196501.886148] FS: 000000c0015ee090(0000) GS:ffff9fca86a40000(0000) knlGS:ffff80c001e5a480 [196501.894441] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 [196501.900386] CR2: 00000000000002d8 CR3: 000000022c3c8000 CR4: 0000000000162670 [196501.907718] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000 [196501.915047] DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400 [196501.922382] Stack: [196501.924592] ffffffffc09cf018c 0000000000000000c ffffffffc09e0ba7c ffff9fc6b5dea300c [196501.932877] 0000000000000000c ffff9fc6b5dea300c ffffba1974323de8c 0000000000000000c [196501.941170] 0000000000231788c 0000000000000000c 0000000000000000c ffffffffc08fa25fc [196501.949465] Call Trace: [196501.952110] [] ? vmexit_fill_RSB+0x18/0x30 [kvm_intel] [196501.959109] [] ? vmx_vcpu_run+0x337/0x4f0 [kvm_intel] [196501.966003] [] ? kvm_arch_vcpu_ioctl_run+0x6df/0x16d0 [kvm] [196501.973420] [] ? kvm_arch_vcpu_load+0x61/0x290 [kvm] [196501.980229] [] ? recalc_sigpending+0x17/0x50 [196501.986345] [] ? kvm_vcpu_ioctl+0x315/0x5e0 [kvm] [196501.992904] [] ? signal_setup_done+0x67/0xb0 [196501.999120] [] ? __seccomp_filter+0x74/0x270 [196502.005281] [] ? do_vfs_ioctl+0xa2/0x620 [196502.011048] [] ? __audit_syscall_entry+0xaa/0xf0 [196502.017535] [] ? syscall_trace_enter+0x1ae/0x2c0 [196502.024002] [] ? SyS_ioctl+0x74/0x80 [196502.029426] [] ? do_syscall_64+0x8d/0x100 [196502.035287] [] ? entry_SYSCALL_64_after_swapgs+0x58/0xc6 [196502.042445] Code: c00 c81 ce2 cff c07 c00 c80 c81 cfa c12 c03 c00 c80 c74 c29 c25 c00 c07 c00 c80 c3d c00 c02 c00 c80 c74 c06 c48 c83 cc4 c08 c5b cc3 c48 c89 cdf ce8 c14 c21 cf1 cff ccd c02 c<48> c83 cc4 c08 c48 c89 cdf c5b ce9 c15 c21 cf1 cff c48 c89 c04 c24 ce8 c4c cd7 cff c [196502.073047] NMI backtrace for cpu 59 [196502.076850] CPU: 59 PID: 63962 Comm: exe Not tainted 4.9.0-11-amd64 #1 Debian 4.9.189-3+deb9u2 [196502.085858] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 [196502.095290] task: ffff9fc4ffdef100 task.stack: ffffba1964bb4000 [196502.101416] RIP: 0010:[] c [] native_queued_spin_lock_slowpath+0xf/0x1a0 [196502.111525] RSP: 0018:ffffba1964bb7bf0 EFLAGS: 00000202 [196502.117043] RAX: 0000000000000001 RBX: 0000000000000002 RCX: 0000000000ff6052 [196502.124480] RDX: 0000000000000001 RSI: 0000000000000001 RDI: ffff9fb2d6a48000 [196502.131833] RBP: ffff9fb1b6168440 R08: 0000000000000007 R09: 8000000000000000 [196502.139169] R10: 0000000000000000 R11: 0000000000000001 R12: 00003fb12ca0b000 [196502.146503] R13: 00000003fb12ca0b R14: 0000000000000000 R15: 0000000000000000 [196502.153835] FS: 000000c0017b6410(0000) GS:ffff9fca874c0000(0000) knlGS:0000000000000000 [196502.162121] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 [196502.168690] CR2: 0000000000c68448 CR3: 00000016f14bc000 CR4: 0000000000162670 [196502.176028] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000 [196502.183368] DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400 [196502.190698] Stack: [196502.192906] ffffffffa141e2fdc ffffffffc0908f1bc ffffba1964bb7c27c ffffffffc09cf00cc [196502.201203] 0000000000000000c 000000000000225fc 0100ffffc09cf018c 0000000100000000c [196502.209488] 0000000000ff6052c ff880369fcb7f303c ffff9fb1b6168440c 00003fb12ca0b000c [196502.218121] Call Trace: [196502.220764] [] ? _raw_spin_lock+0x1d/0x20 [196502.226618] [] ? tdp_page_fault+0x22b/0x2c0 [kvm] [196502.243330] [] ? vmexit_fill_RSB+0xc/0x30 [kvm_intel] [196502.250237] [] ? kvm_mmu_page_fault+0x5f/0x120 [kvm] [196502.257053] [] ? vmx_handle_exit+0x1d1/0x1680 [kvm_intel] [196502.264303] [] ? vmexit_fill_RSB+0x18/0x30 [kvm_intel] [196502.271290] [] ? vmexit_fill_RSB+0xc/0x30 [kvm_intel] [196502.278187] [] ? vmexit_fill_RSB+0x18/0x30 [kvm_intel] [196502.285180] [] ? vmexit_fill_RSB+0xc/0x30 [kvm_intel] [196502.292097] [] ? vmexit_fill_RSB+0x18/0x30 [kvm_intel] [196502.299082] [] ? vmexit_fill_RSB+0xc/0x30 [kvm_intel] [196502.306074] [] ? vmexit_fill_RSB+0x18/0x30 [kvm_intel] [196502.313056] [] ? vmexit_fill_RSB+0xc/0x30 [kvm_intel] [196502.319960] [] ? vmx_vcpu_run+0x33f/0x4f0 [kvm_intel] [196502.326865] [] ? kvm_arch_vcpu_ioctl_run+0x801/0x16d0 [kvm] [196502.334290] [] ? kvm_arch_vcpu_load+0x61/0x290 [kvm] [196502.341198] [] ? recalc_sigpending+0x17/0x50 [196502.347314] [] ? kvm_vcpu_ioctl+0x315/0x5e0 [kvm] [196502.353873] [] ? signal_setup_done+0x67/0xb0 [196502.359986] [] ? __seccomp_filter+0x74/0x270 [196502.366099] [] ? do_vfs_ioctl+0xa2/0x620 [196502.371867] [] ? __audit_syscall_entry+0xaa/0xf0 [196502.378330] [] ? syscall_trace_enter+0x1ae/0x2c0 [196502.384792] [] ? SyS_ioctl+0x74/0x80 [196502.390226] [] ? do_syscall_64+0x8d/0x100 [196502.396079] [] ? entry_SYSCALL_64_after_swapgs+0x58/0xc6 [196502.403236] Code: c75 cce c48 c8b c04 c24 cc6 c03 c01 c48 cc7 c00 c00 c00 c00 c00 ce9 c90 cfe cff cff c0f c1f c80 c00 c00 c00 c00 c0f c1f c44 c00 c00 c0f c1f c44 c00 c00 cba c01 c00 c00 c00 c<8b> c07 c85 cc0 c75 c0a cf0 c0f cb1 c17 c85 cc0 c75 cf2 cf3 cc3 cf3 c90 ceb cec c81 c [196502.433576] NMI backtrace for cpu 55 [196502.437404] CPU: 55 PID: 39705 Comm: exe Not tainted 4.9.0-11-amd64 #1 Debian 4.9.189-3+deb9u2 [196502.446211] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 [196502.455892] task: ffff9fca771d70c0 task.stack: ffffba19a0998000 [196502.462125] RIP: 0010:[] c [] native_queued_spin_lock_slowpath+0x21/0x1a0 [196502.472324] RSP: 0018:ffffba19a099bbf0 EFLAGS: 00000202 [196502.477831] RAX: 0000000000000001 RBX: 0000000000000000 RCX: 0000000000c21918 [196502.485255] RDX: 0000000000000001 RSI: 0000000000000001 RDI: ffff9fb2d6a48000 [196502.492774] RBP: ffff9fc0b7ab1f00 R08: 0000000000000007 R09: 8000000000000000 [196502.500104] R10: 00000003ffffffff R11: 0400000000000000 R12: 00003fb13d1891d0 [196502.507435] R13: 00000003fb13d189 R14: 0000000000000000 R15: 0000000000000000 [196502.514766] FS: 000000c000a98090(0000) GS:ffff9fca873c0000(0000) knlGS:ffff80c0005c1200 [196502.523065] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 [196502.529006] CR2: 00007ffc68de2a00 CR3: 00000016f14bc000 CR4: 0000000000162670 [196502.536337] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000 [196502.543683] DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400 [196502.551026] Stack: [196502.553248] ffffffffa141e2fdc ffffffffc0908f1bc ffffba19a099bc27c ffffffffc09cf00cc [196502.561542] 0000000100000000c 000000000000225fc 0001ffffc09cf018c 0000000100000000c [196502.569944] 0000000000c21918c 27c937d4df5c0bc9c ffff9fc0b7ab1f00c 00003fb13d1891d0c [196502.578236] Call Trace: [196502.580907] [] ? _raw_spin_lock+0x1d/0x20 [196502.586772] [] ? tdp_page_fault+0x22b/0x2c0 [kvm] [196502.593438] [] ? vmexit_fill_RSB+0xc/0x30 [kvm_intel] [196502.600342] [] ? kvm_mmu_page_fault+0x5f/0x120 [kvm] [196502.607336] [] ? vmx_handle_exit+0x1d1/0x1680 [kvm_intel] [196502.614593] [] ? vmexit_fill_RSB+0x18/0x30 [kvm_intel] [196502.621586] [] ? vmexit_fill_RSB+0xc/0x30 [kvm_intel] [196502.628488] [] ? vmexit_fill_RSB+0x18/0x30 [kvm_intel] [196502.635472] [] ? vmexit_fill_RSB+0xc/0x30 [kvm_intel] [196502.642370] [] ? vmexit_fill_RSB+0x18/0x30 [kvm_intel] [196502.649354] [] ? vmexit_fill_RSB+0xc/0x30 [kvm_intel] [196502.656249] [] ? vmexit_fill_RSB+0x18/0x30 [kvm_intel] [196502.663235] [] ? vmexit_fill_RSB+0xc/0x30 [kvm_intel] [196502.670159] [] ? vmx_vcpu_run+0x33f/0x4f0 [kvm_intel] [196502.677058] [] ? kvm_arch_vcpu_ioctl_run+0x801/0x16d0 [kvm] [196502.684477] [] ? kvm_arch_vcpu_load+0x100/0x290 [kvm] [196502.691392] [] ? recalc_sigpending+0x17/0x50 [196502.697509] [] ? kvm_vcpu_ioctl+0x315/0x5e0 [kvm] [196502.704072] [] ? signal_setup_done+0x67/0xb0 [196502.710192] [] ? __seccomp_filter+0x74/0x270 [196502.716306] [] ? do_vfs_ioctl+0xa2/0x620 [196502.722087] [] ? __audit_syscall_entry+0xaa/0xf0 [196502.728581] [] ? syscall_trace_enter+0x1ae/0x2c0 [196502.735047] [] ? SyS_ioctl+0x74/0x80 [196502.740469] [] ? do_syscall_64+0x8d/0x100 [196502.746325] [] ? entry_SYSCALL_64_after_swapgs+0x58/0xc6 [196502.753505] Code: cfe cff cff c0f c1f c80 c00 c00 c00 c00 c0f c1f c44 c00 c00 c0f c1f c44 c00 c00 cba c01 c00 c00 c00 c8b c07 c85 cc0 c75 c0a cf0 c0f cb1 c17 c85 cc0 c75 cf2 cf3 cc3 cf3 c90 c cec c81 cfe c00 c01 c00 c00 c0f c84 c1c c01 c00 c00 c40 c30 cf6 c85 cf6 c75 c42 c [196502.784127] NMI backtrace for cpu 35 [196502.788096] CPU: 35 PID: 55099 Comm: exe Not tainted 4.9.0-11-amd64 #1 Debian 4.9.189-3+deb9u2 [196502.796914] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 [196502.806361] task: ffff9fc6f08b7100 task.stack: ffffba195fb98000 [196502.812500] RIP: 0033:[<00000000006fd975>] c [<00000000006fd975>] 0x6fd975 [