[42.589064] Initializing watchdogs
[42.589173]   /dev/watchdog0
[42.605083] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:0162 Intel Ivybridge (Gen7) IvyBridge GT2 [HD Graphics 4000]
[42.627479] [001/161] (960s left) i915_module_load (load)
[42.792600] Starting subtest: load
[44.410424] Subtest load: SUCCESS (1.616s)
[44.510421] [FACT i915_module_load (load)] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[44.512484] [FACT i915_module_load (load)] new: kernel.kmod_is_loaded.i915: true
[44.558087] [002/161] (958s left) core_auth (basic-auth)
[44.710082] Starting subtest: basic-auth
[44.731789] Subtest basic-auth: SUCCESS (0.001s)
[44.831267] [003/161] (958s left) debugfs_test (read_all_entries)
[44.959607] Starting subtest: read_all_entries
[45.006692] Subtest read_all_entries: SUCCESS (0.052s)
[45.121690] [004/161] (958s left) debugfs_test (basic-hwmon)
[45.269530] Starting subtest: basic-hwmon
[45.308065] Subtest basic-hwmon: SKIP (0.000s)
[45.406690] [005/161] (958s left) debugfs_test (sysfs)
[45.549562] Starting subtest: sysfs
[45.594033] Subtest sysfs: SUCCESS (0.051s)
[45.710768] [006/161] (957s left) fbdev (eof)
[45.846877] Starting subtest: eof
[45.867799] Subtest eof: SUCCESS (0.001s)
[45.961200] [007/161] (957s left) fbdev (info)
[46.072263] Starting subtest: info
[46.093048] Subtest info: SUCCESS (0.000s)
[46.186988] [008/161] (957s left) fbdev (nullptr)
[46.318925] Starting subtest: nullptr
[46.338721] Subtest nullptr: SUCCESS (0.000s)
[46.432343] [009/161] (957s left) fbdev (read)
[46.567715] Starting subtest: read
[46.777033] Subtest read: SUCCESS (0.223s)
[46.886920] [010/161] (957s left) fbdev (write)
[47.021562] Starting subtest: write
[47.119214] Subtest write: SUCCESS (0.110s)
[47.237401] [011/161] (956s left) gem_basic (bad-close)
[47.419226] Starting subtest: bad-close
[47.468452] Subtest bad-close: SUCCESS (0.000s)
[47.574236] [012/161] (956s left) gem_basic (create-close)
[47.744371] Starting subtest: create-close
[47.791284] Subtest create-close: SUCCESS (0.000s)
[47.904365] [013/161] (956s left) gem_basic (create-fd-close)
[48.045312] Starting subtest: create-fd-close
[48.098389] Subtest create-fd-close: SUCCESS (0.000s)
[48.205107] [014/161] (956s left) gem_busy (busy@all-engines)
[48.390910] Starting subtest: busy
[48.410441] Starting dynamic subtest: all-engines
[48.429876] Dynamic subtest all-engines: SUCCESS (0.026s)
[48.449249] Subtest busy: SUCCESS (0.026s)
[48.736372] [015/161] (955s left) gem_close_race (basic-process)
[48.914263] Starting subtest: basic-process
[48.933799] Subtest basic-process: SUCCESS (0.048s)
[49.146182] [016/161] (955s left) gem_close_race (basic-threads)
[49.290713] Starting subtest: basic-threads
[50.454722] Subtest basic-threads: SUCCESS (1.184s)
[50.670614] [017/161] (953s left) gem_ctx_create (basic)
[50.845037] Starting subtest: basic
[50.864438] Subtest basic: SUCCESS (0.000s)
[51.040516] [018/161] (953s left) gem_ctx_create (basic-files)
[51.186741] Starting subtest: basic-files
[53.201146] Subtest basic-files: SUCCESS (2.038s)
[53.421498] [019/161] (951s left) gem_ctx_exec (basic)
[53.576123] Starting subtest: basic
[53.606663] Subtest basic: SUCCESS (0.003s)
[53.700418] [020/161] (951s left) gem_exec_basic (basic)
[53.854636] Starting subtest: basic
[53.874037] Starting dynamic subtest: rcs0-smem
[53.893277] Dynamic subtest rcs0-smem: SUCCESS (0.001s)
[53.926590] Starting dynamic subtest: bcs0-smem
[53.948705] Dynamic subtest bcs0-smem: SUCCESS (0.000s)
[53.984031] Starting dynamic subtest: vcs0-smem
[54.005328] Dynamic subtest vcs0-smem: SUCCESS (0.000s)
[54.026863] Subtest basic: SUCCESS (0.002s)
[54.119788] [021/161] (950s left) gem_exec_create (basic)
[54.276870] Starting subtest: basic
[54.295715] Starting dynamic subtest: smem
[56.319971] Dynamic subtest smem: SUCCESS (2.047s)
[56.341592] Subtest basic: SUCCESS (2.047s)
[56.523940] [022/161] (948s left) gem_exec_fence (basic-busy)
[56.787966] Starting subtest: basic-busy
[56.809673] Starting dynamic subtest: rcs0
[56.848461] Dynamic subtest rcs0: SUCCESS (0.029s)
[56.882023] Starting dynamic subtest: bcs0
[56.923227] Dynamic subtest bcs0: SUCCESS (0.027s)
[56.958746] Starting dynamic subtest: vcs0
[57.001518] Dynamic subtest vcs0: SUCCESS (0.026s)
[57.020070] Subtest basic-busy: SUCCESS (0.082s)
[57.397287] [FACT gem_exec_fence (basic-busy)] new: kernel.kmod_is_loaded.vgem: true
[57.417805] [023/161] (947s left) gem_exec_fence (basic-wait)
[57.665413] Starting subtest: basic-wait
[57.687689] Starting dynamic subtest: rcs0
[57.709574] Dynamic subtest rcs0: SUCCESS (0.030s)
[57.745840] Starting dynamic subtest: bcs0
[57.767845] Dynamic subtest bcs0: SUCCESS (0.029s)
[57.801509] Starting dynamic subtest: vcs0
[57.821054] Dynamic subtest vcs0: SUCCESS (0.031s)
[57.840169] Subtest basic-wait: SUCCESS (0.090s)
[58.253396] [024/161] (946s left) gem_exec_fence (basic-await)
[58.511760] Starting subtest: basic-await
[58.533064] Starting dynamic subtest: rcs0
[58.570446] Dynamic subtest rcs0: SUCCESS (0.071s)
[58.595380] Starting dynamic subtest: bcs0
[58.641148] Dynamic subtest bcs0: SUCCESS (0.071s)
[58.668774] Starting dynamic subtest: vcs0
[58.710480] Dynamic subtest vcs0: SUCCESS (0.069s)
[58.729962] Subtest basic-await: SUCCESS (0.211s)
[59.209062] [025/161] (946s left) gem_exec_fence (nb-await)
[59.460069] Starting subtest: nb-await
[59.481341] Starting dynamic subtest: rcs0
[59.502871] Dynamic subtest rcs0: SUCCESS (0.054s)
[59.532752] Starting dynamic subtest: bcs0
[59.569725] Dynamic subtest bcs0: SUCCESS (0.054s)
[59.593926] Starting dynamic subtest: vcs0
[59.614784] Dynamic subtest vcs0: SUCCESS (0.052s)
[59.635606] Subtest nb-await: SUCCESS (0.161s)
[60.110543] [026/161] (945s left) gem_exec_gttfill (basic)
[60.249424] Starting subtest: basic
[64.095796] Subtest basic: SUCCESS (3.882s)
[64.328947] [027/161] (941s left) gem_exec_parallel (engines)
[64.544678] Starting subtest: engines
[64.563090] Starting dynamic subtest: basic
[66.432384] Dynamic subtest basic: SUCCESS (1.907s)
[66.461143] Starting dynamic subtest: contexts
[68.042839] Dynamic subtest contexts: SUCCESS (1.616s)
[68.071617] Starting dynamic subtest: fds
[69.750253] Dynamic subtest fds: SUCCESS (1.707s)
[69.780311] Starting dynamic subtest: userptr
[71.682929] Dynamic subtest userptr: SUCCESS (1.924s)
[71.704942] Subtest engines: SUCCESS (7.156s)
[71.910787] [028/161] (933s left) gem_exec_store (basic)
[72.165188] Starting subtest: basic
[72.184226] Subtest basic: SUCCESS (0.054s)
[72.340425] [029/161] (933s left) gem_flink_basic (bad-flink)
[72.500249] Starting subtest: bad-flink
[72.523338] Subtest bad-flink: SUCCESS (0.000s)
[72.638279] [030/161] (933s left) gem_flink_basic (bad-open)
[72.805368] Starting subtest: bad-open
[72.828567] Subtest bad-open: SUCCESS (0.000s)
[72.946047] [031/161] (932s left) gem_flink_basic (basic)
[73.115454] Starting subtest: basic
[73.139723] Subtest basic: SUCCESS (0.000s)
[73.256979] [032/161] (932s left) gem_flink_basic (double-flink)
[73.444183] Starting subtest: double-flink
[73.473179] Subtest double-flink: SUCCESS (0.000s)
[73.590219] [033/161] (932s left) gem_flink_basic (flink-lifetime)
[73.759581] Starting subtest: flink-lifetime
[73.783302] Subtest flink-lifetime: SUCCESS (0.001s)
[73.897476] [034/161] (932s left) gem_huc_copy (huc-copy)
[74.072035] Subtest huc-copy: SKIP (0.000s)
[74.190421] [035/161] (932s left) gem_linear_blits (basic)
[74.409324] Starting subtest: basic
[74.431737] Subtest basic: SUCCESS (0.011s)
[74.564383] [036/161] (931s left) gem_mmap (basic)
[74.792703] Starting subtest: basic
[74.891659] Subtest basic: SUCCESS (0.000s)
[75.000793] [037/161] (931s left) gem_mmap_gtt (basic)
[75.173405] Starting subtest: basic
[75.194967] Subtest basic: SUCCESS (0.001s)
[75.296870] [038/161] (931s left) gem_render_linear_blits (basic)
[75.466869] Starting subtest: basic
[75.571153] Subtest basic: SUCCESS (0.021s)
[75.673968] [039/161] (930s left) gem_render_tiled_blits (basic)
[75.830544] Starting subtest: basic
[75.952131] Subtest basic: SUCCESS (0.028s)
[76.058273] [040/161] (930s left) gem_ringfill (basic-all)
[76.337778] Starting subtest: basic-all
[77.419982] Subtest basic-all: SUCCESS (1.109s)
[77.562792] [041/161] (929s left) gem_softpin (allocator-basic)
[77.738842] Subtest allocator-basic: SKIP (0.000s)
[77.866514] [042/161] (929s left) gem_softpin (allocator-basic-reserve)
[78.014690] Subtest allocator-basic-reserve: SKIP (0.000s)
[78.140988] [043/161] (928s left) gem_softpin (safe-alignment)
[78.331074] Starting subtest: safe-alignment
[78.350272] Subtest safe-alignment: SUCCESS (0.010s)
[78.453950] [044/161] (928s left) gem_sync (basic-all)
[78.663772] Starting subtest: basic-all
[80.661045] Subtest basic-all: SUCCESS (2.063s)
[80.884193] [045/161] (926s left) gem_sync (basic-each)
[81.068604] Starting subtest: basic-each
[83.091622] Subtest basic-each: SUCCESS (2.066s)
[83.301953] [046/161] (923s left) gem_tiled_blits (basic)
[83.493241] Starting subtest: basic
[83.514065] Subtest basic: SUCCESS (0.013s)
[83.687684] [047/161] (923s left) gem_tiled_fence_blits (basic)
[83.956936] Starting subtest: basic
[83.978655] Subtest basic: SUCCESS (0.007s)
[84.128273] [048/161] (923s left) gem_tiled_pread_basic
[84.562859] [049/161] (922s left) gem_wait (busy@all-engines)
[84.717559] Starting subtest: busy
[84.738349] Starting dynamic subtest: all-engines
[85.226615] Dynamic subtest all-engines: SUCCESS (0.521s)
[85.247061] Subtest busy: SUCCESS (0.522s)
[85.479771] [050/161] (922s left) gem_wait (wait@all-engines)
[85.623331] Starting subtest: wait
[85.644526] Starting dynamic subtest: all-engines
[86.629415] Dynamic subtest all-engines: SUCCESS (1.029s)
[86.648816] Subtest wait: SUCCESS (1.030s)
[86.887709] [051/161] (920s left) i915_getparams_basic (basic-eu-total)
[87.036243] Starting subtest: basic-eu-total
[87.078031] Subtest basic-eu-total: SUCCESS (0.000s)
[87.178189] [052/161] (920s left) i915_getparams_basic (basic-subslice-total)
[87.308272] Starting subtest: basic-subslice-total
[87.348224] Subtest basic-subslice-total: SUCCESS (0.000s)
[87.451497] [053/161] (920s left) i915_hangman (error-state-basic)
[87.601771] Starting subtest: error-state-basic
[87.623059] Subtest error-state-basic: SUCCESS (0.004s)
[87.731038] [054/161] (920s left) i915_pciid
[88.003225] [055/161] (920s left) kms_addfb_basic (addfb25-4-tiled)
[88.208175] Starting subtest: addfb25-4-tiled
[88.227749] Subtest addfb25-4-tiled: SUCCESS (0.000s)
[88.334923] [056/161] (919s left) kms_addfb_basic (addfb25-bad-modifier)
[88.478672] Starting subtest: addfb25-bad-modifier
[88.500471] Subtest addfb25-bad-modifier: SUCCESS (0.000s)
[88.625399] [057/161] (919s left) kms_addfb_basic (addfb25-framebuffer-vs-set-tiling)
[88.777568] Starting subtest: addfb25-framebuffer-vs-set-tiling
[88.798094] Subtest addfb25-framebuffer-vs-set-tiling: SUCCESS (0.000s)
[88.897893] [058/161] (919s left) kms_addfb_basic (addfb25-modifier-no-flag)
[89.047556] Starting subtest: addfb25-modifier-no-flag
[89.067177] Subtest addfb25-modifier-no-flag: SUCCESS (0.000s)
[89.171189] [059/161] (919s left) kms_addfb_basic (addfb25-x-tiled-legacy)
[89.317328] Starting subtest: addfb25-x-tiled-legacy
[89.337771] Subtest addfb25-x-tiled-legacy: SUCCESS (0.000s)
[89.465549] [060/161] (919s left) kms_addfb_basic (addfb25-x-tiled-mismatch-legacy)
[89.614822] Starting subtest: addfb25-x-tiled-mismatch-legacy
[89.636120] Subtest addfb25-x-tiled-mismatch-legacy: SUCCESS (0.000s)
[89.740199] [061/161] (918s left) kms_addfb_basic (addfb25-yf-tiled-legacy)
[89.887960] Starting subtest: addfb25-yf-tiled-legacy
[89.907467] Subtest addfb25-yf-tiled-legacy: SUCCESS (0.000s)
[90.043933] [062/161] (918s left) kms_addfb_basic (addfb25-y-tiled-legacy)
[90.182300] Starting subtest: addfb25-y-tiled-legacy
[90.203128] Subtest addfb25-y-tiled-legacy: SUCCESS (0.000s)
[90.310604] [063/161] (918s left) kms_addfb_basic (addfb25-y-tiled-small-legacy)
[90.454469] Starting subtest: addfb25-y-tiled-small-legacy
[90.497508] Subtest addfb25-y-tiled-small-legacy: SKIP (0.000s)
[90.599580] [064/161] (918s left) kms_addfb_basic (bad-pitch-0)
[90.768938] Starting subtest: bad-pitch-0
[90.788315] Subtest bad-pitch-0: SUCCESS (0.000s)
[90.892691] [065/161] (918s left) kms_addfb_basic (bad-pitch-1024)
[91.061078] Starting subtest: bad-pitch-1024
[91.083258] Subtest bad-pitch-1024: SUCCESS (0.000s)
[91.189631] [066/161] (917s left) kms_addfb_basic (bad-pitch-128)
[91.334734] Starting subtest: bad-pitch-128
[91.355892] Subtest bad-pitch-128: SUCCESS (0.000s)
[91.456729] [067/161] (917s left) kms_addfb_basic (bad-pitch-256)
[91.606717] Starting subtest: bad-pitch-256
[91.626106] Subtest bad-pitch-256: SUCCESS (0.000s)
[91.729157] [068/161] (917s left) kms_addfb_basic (bad-pitch-32)
[91.870621] Starting subtest: bad-pitch-32
[91.889681] Subtest bad-pitch-32: SUCCESS (0.000s)
[92.000505] [069/161] (917s left) kms_addfb_basic (bad-pitch-63)
[92.142104] Starting subtest: bad-pitch-63
[92.163946] Subtest bad-pitch-63: SUCCESS (0.000s)
[92.288757] [070/161] (917s left) kms_addfb_basic (bad-pitch-65536)
[92.441978] Starting subtest: bad-pitch-65536
[92.462491] Subtest bad-pitch-65536: SUCCESS (0.000s)
[92.563613] [071/161] (917s left) kms_addfb_basic (bad-pitch-999)
[92.720372] Starting subtest: bad-pitch-999
[92.742450] Subtest bad-pitch-999: SUCCESS (0.000s)
[92.855765] [072/161] (916s left) kms_addfb_basic (basic)
[93.023873] Starting subtest: basic
[93.049237] Subtest basic: SUCCESS (0.000s)
[93.170865] [073/161] (916s left) kms_addfb_basic (basic-x-tiled-legacy)
[93.338214] Starting subtest: basic-x-tiled-legacy
[93.363716] Subtest basic-x-tiled-legacy: SUCCESS (0.000s)
[93.492232] [074/161] (916s left) kms_addfb_basic (basic-y-tiled-legacy)
[93.664749] Starting subtest: basic-y-tiled-legacy
[93.687668] Subtest basic-y-tiled-legacy: SUCCESS (0.000s)
[93.803234] [075/161] (916s left) kms_addfb_basic (bo-too-small)
[93.965151] Starting subtest: bo-too-small
[93.988898] Subtest bo-too-small: SUCCESS (0.000s)
[94.114263] [076/161] (915s left) kms_addfb_basic (bo-too-small-due-to-tiling)
[94.280993] Starting subtest: bo-too-small-due-to-tiling
[94.305367] Subtest bo-too-small-due-to-tiling: SUCCESS (0.000s)
[94.423103] [077/161] (915s left) kms_addfb_basic (clobberred-modifier)
[94.589273] Starting subtest: clobberred-modifier
[94.613360] Subtest clobberred-modifier: SUCCESS (0.000s)
[94.733144] [078/161] (915s left) kms_addfb_basic (framebuffer-vs-set-tiling)
[94.882056] Starting subtest: framebuffer-vs-set-tiling
[94.901149] Subtest framebuffer-vs-set-tiling: SUCCESS (0.000s)
[95.009441] [079/161] (915s left) kms_addfb_basic (invalid-get-prop)
[95.155076] Starting subtest: invalid-get-prop
[95.177124] Subtest invalid-get-prop: SUCCESS (0.000s)
[95.282964] [080/161] (915s left) kms_addfb_basic (invalid-get-prop-any)
[95.431193] Starting subtest: invalid-get-prop-any
[95.452466] Subtest invalid-get-prop-any: SUCCESS (0.000s)
[95.553097] [081/161] (914s left) kms_addfb_basic (invalid-set-prop)
[95.703624] Starting subtest: invalid-set-prop
[95.723472] Subtest invalid-set-prop: SUCCESS (0.000s)
[95.841689] [082/161] (914s left) kms_addfb_basic (invalid-set-prop-any)
[95.984026] Starting subtest: invalid-set-prop-any
[96.010671] Subtest invalid-set-prop-any: SUCCESS (0.000s)
[96.119403] [083/161] (914s left) kms_addfb_basic (no-handle)
[96.262745] Starting subtest: no-handle
[96.284713] Subtest no-handle: SUCCESS (0.000s)
[96.388359] [084/161] (914s left) kms_addfb_basic (size-max)
[96.545433] Starting subtest: size-max
[96.565039] Subtest size-max: SUCCESS (0.000s)
[96.667652] [085/161] (914s left) kms_addfb_basic (small-bo)
[96.814154] Starting subtest: small-bo
[96.833553] Subtest small-bo: SUCCESS (0.000s)
[96.939381] [086/161] (914s left) kms_addfb_basic (tile-pitch-mismatch)
[97.081089] Starting subtest: tile-pitch-mismatch
[97.103167] Subtest tile-pitch-mismatch: SUCCESS (0.000s)
[97.211534] [087/161] (913s left) kms_addfb_basic (too-high)
[97.355811] Starting subtest: too-high
[97.377086] Subtest too-high: SUCCESS (0.001s)
[97.484177] [088/161] (913s left) kms_addfb_basic (too-wide)
[97.631142] Starting subtest: too-wide
[97.651260] Subtest too-wide: SUCCESS (0.000s)
[97.750367] [089/161] (913s left) kms_addfb_basic (unused-handle)
[97.917974] Starting subtest: unused-handle
[97.937277] Subtest unused-handle: SUCCESS (0.000s)
[98.046727] [090/161] (913s left) kms_addfb_basic (unused-modifier)
[98.189530] Starting subtest: unused-modifier
[98.211816] Subtest unused-modifier: SUCCESS (0.000s)
[98.313891] [091/161] (913s left) kms_addfb_basic (unused-offsets)
[98.462657] Starting subtest: unused-offsets
[98.482969] Subtest unused-offsets: SUCCESS (0.000s)
[98.583941] [092/161] (912s left) kms_addfb_basic (unused-pitches)
[98.734460] Starting subtest: unused-pitches
[98.753656] Subtest unused-pitches: SUCCESS (0.000s)
[98.858893] [093/161] (912s left) kms_busy (basic)
[99.009097] Starting subtest: basic
[99.032045] Starting dynamic subtest: flip
[99.504966] Dynamic subtest flip: SUCCESS (0.505s)
[99.539636] Starting dynamic subtest: modeset
[100.070811] Dynamic subtest modeset: SUCCESS (0.568s)
[100.091933] Subtest basic: SUCCESS (1.074s)
[100.270636] [094/161] (911s left) kms_prop_blob (basic)
[100.426141] Starting subtest: basic
[100.445720] Subtest basic: SUCCESS (0.000s)
[100.554768] [095/161] (911s left) kms_cursor_legacy (basic-busy-flip-before-cursor-atomic)
[100.709840] Starting subtest: basic-busy-flip-before-cursor-atomic
[101.641112] Subtest basic-busy-flip-before-cursor-atomic: SUCCESS (0.939s)
[101.840510] [096/161] (910s left) kms_cursor_legacy (basic-busy-flip-before-cursor-legacy)
[101.986133] Starting subtest: basic-busy-flip-before-cursor-legacy
[102.889764] Subtest basic-busy-flip-before-cursor-legacy: SUCCESS (0.914s)
[103.091196] [097/161] (908s left) kms_cursor_legacy (basic-flip-after-cursor-atomic)
[103.232034] Starting subtest: basic-flip-after-cursor-atomic
[104.137947] Subtest basic-flip-after-cursor-atomic: SUCCESS (0.912s)
[104.331488] [098/161] (907s left) kms_cursor_legacy (basic-flip-after-cursor-legacy)
[104.478367] Starting subtest: basic-flip-after-cursor-legacy
[105.385096] Subtest basic-flip-after-cursor-legacy: SUCCESS (0.912s)
[105.578055] [099/161] (906s left) kms_cursor_legacy (basic-flip-after-cursor-varying-size)
[105.723636] Starting subtest: basic-flip-after-cursor-varying-size
[106.633060] Subtest basic-flip-after-cursor-varying-size: SUCCESS (0.918s)
[106.839838] [100/161] (905s left) kms_cursor_legacy (basic-flip-before-cursor-atomic)
[106.984926] Starting subtest: basic-flip-before-cursor-atomic
[107.891191] Subtest basic-flip-before-cursor-atomic: SUCCESS (0.914s)
[108.108362] [101/161] (904s left) kms_cursor_legacy (basic-flip-before-cursor-legacy)
[108.250048] Starting subtest: basic-flip-before-cursor-legacy
[109.154578] Subtest basic-flip-before-cursor-legacy: SUCCESS (0.910s)
[109.352100] [102/161] (903s left) kms_cursor_legacy (basic-flip-before-cursor-varying-size)
[109.499662] Starting subtest: basic-flip-before-cursor-varying-size
[110.434693] Subtest basic-flip-before-cursor-varying-size: SUCCESS (0.941s)
[110.633231] [103/161] (901s left) kms_dsc (dsc-basic)
[110.808307] Subtest dsc-basic: SKIP (0.000s)
[110.913802] [104/161] (901s left) kms_flip (basic-flip-vs-dpms)
[111.104474] Starting subtest: basic-flip-vs-dpms
[111.126413] Starting dynamic subtest: A-VGA1
[111.952462] Dynamic subtest A-VGA1: SUCCESS (0.885s)
[111.986755] Starting dynamic subtest: B-VGA1
[112.781983] Dynamic subtest B-VGA1: SUCCESS (0.822s)
[112.808946] Starting dynamic subtest: C-VGA1
[113.603074] Dynamic subtest C-VGA1: SUCCESS (0.816s)
[113.637501] Subtest basic-flip-vs-dpms: SUCCESS (2.527s)
[113.843493] [105/161] (898s left) kms_flip (basic-flip-vs-modeset)
[114.046177] Starting subtest: basic-flip-vs-modeset
[114.069201] Starting dynamic subtest: A-VGA1
[114.894905] Dynamic subtest A-VGA1: SUCCESS (0.897s)
[114.935046] Starting dynamic subtest: B-VGA1
[115.751675] Dynamic subtest B-VGA1: SUCCESS (0.849s)
[115.783077] Starting dynamic subtest: C-VGA1
[116.593406] Dynamic subtest C-VGA1: SUCCESS (0.842s)
[116.615271] Subtest basic-flip-vs-modeset: SUCCESS (2.592s)
[116.797516] [106/161] (896s left) kms_flip (basic-flip-vs-wf_vblank)
[116.993155] Starting subtest: basic-flip-vs-wf_vblank
[117.012280] Starting dynamic subtest: A-VGA1
[118.095462] Dynamic subtest A-VGA1: SUCCESS (1.149s)
[118.134235] Starting dynamic subtest: B-VGA1
[119.249571] Dynamic subtest B-VGA1: SUCCESS (1.148s)
[119.288714] Starting dynamic subtest: C-VGA1
[120.398053] Dynamic subtest C-VGA1: SUCCESS (1.147s)
[120.417747] Subtest basic-flip-vs-wf_vblank: SUCCESS (3.450s)
[120.624510] [107/161] (892s left) kms_flip (basic-plain-flip)
[120.808619] Starting subtest: basic-plain-flip
[120.828053] Starting dynamic subtest: A-VGA1
[121.637041] Dynamic subtest A-VGA1: SUCCESS (0.874s)
[121.673256] Starting dynamic subtest: B-VGA1
[122.489157] Dynamic subtest B-VGA1: SUCCESS (0.848s)
[122.526743] Starting dynamic subtest: C-VGA1
[123.351245] Dynamic subtest C-VGA1: SUCCESS (0.864s)
[123.372251] Subtest basic-plain-flip: SUCCESS (2.592s)
[123.576438] [108/161] (889s left) kms_force_connector_basic (force-connector-state)
[123.727862] Starting subtest: force-connector-state
[123.870579] Subtest force-connector-state: SUCCESS (0.150s)
[124.119073] [109/161] (889s left) kms_force_connector_basic (force-edid)
[124.268523] Starting subtest: force-edid
[124.288251] Subtest force-edid: SUCCESS (0.020s)
[124.414091] [110/161] (888s left) kms_force_connector_basic (force-load-detect)
[124.580138] Starting subtest: force-load-detect
[124.711762] Subtest force-load-detect: SUCCESS (0.168s)
[124.944676] [111/161] (888s left) kms_force_connector_basic (prune-stale-modes)
[125.116101] Starting subtest: prune-stale-modes
[125.134640] Subtest prune-stale-modes: SUCCESS (0.019s)
[125.242115] [112/161] (888s left) kms_frontbuffer_tracking (basic)
[125.478302] Starting subtest: basic
[127.311370] Subtest basic: SUCCESS (1.912s)
[127.454594] [113/161] (886s left) kms_hdmi_inject (inject-audio)
[127.625670] Starting subtest: inject-audio
[127.665352] Subtest inject-audio: SKIP (0.000s)
[127.799292] [114/161] (885s left) kms_pipe_crc_basic (compare-crc-sanitycheck-xr24)
[127.951707] Starting subtest: compare-crc-sanitycheck-xr24
[127.973185] Starting dynamic subtest: pipe-A-VGA-1
[128.234835] Dynamic subtest pipe-A-VGA-1: SUCCESS (0.283s)
[128.262509] Starting dynamic subtest: pipe-B-VGA-1
[128.509467] Dynamic subtest pipe-B-VGA-1: SUCCESS (0.272s)
[128.537216] Starting dynamic subtest: pipe-C-VGA-1
[128.782471] Dynamic subtest pipe-C-VGA-1: SUCCESS (0.277s)
[128.800691] Subtest compare-crc-sanitycheck-xr24: SUCCESS (0.837s)
[128.980486] [115/161] (884s left) kms_pipe_crc_basic (compare-crc-sanitycheck-nv12)
[129.153295] Starting subtest: compare-crc-sanitycheck-nv12
[129.173891] Starting dynamic subtest: pipe-A-VGA-1
[129.212607] Dynamic subtest pipe-A-VGA-1: SKIP (0.000s)
[129.250276] Starting dynamic subtest: pipe-B-VGA-1
[129.293086] Dynamic subtest pipe-B-VGA-1: SKIP (0.000s)
[129.333094] Starting dynamic subtest: pipe-C-VGA-1
[129.375202] Dynamic subtest pipe-C-VGA-1: SKIP (0.000s)
[129.413811] Subtest compare-crc-sanitycheck-nv12: SKIP (0.004s)
[129.519430] [116/161] (884s left) kms_pipe_crc_basic (hang-read-crc)
[129.667332] Starting subtest: hang-read-crc
[129.688481] Starting dynamic subtest: pipe-A-VGA-1
[130.447080] Dynamic subtest pipe-A-VGA-1: SUCCESS (0.796s)
[130.474104] Starting dynamic subtest: pipe-B-VGA-1
[131.238718] Dynamic subtest pipe-B-VGA-1: SUCCESS (0.773s)
[131.265977] Starting dynamic subtest: pipe-C-VGA-1
[132.040244] Dynamic subtest pipe-C-VGA-1: SUCCESS (0.811s)
[132.059218] Subtest hang-read-crc: SUCCESS (2.384s)
[132.218763] [117/161] (881s left) kms_pipe_crc_basic (nonblocking-crc)
[132.373193] Starting subtest: nonblocking-crc
[132.394435] Starting dynamic subtest: pipe-A-VGA-1
[132.834493] Dynamic subtest pipe-A-VGA-1: SUCCESS (0.467s)
[132.862211] Starting dynamic subtest: pipe-B-VGA-1
[133.296247] Dynamic subtest pipe-B-VGA-1: SUCCESS (0.454s)
[133.329186] Starting dynamic subtest: pipe-C-VGA-1
[133.779483] Dynamic subtest pipe-C-VGA-1: SUCCESS (0.453s)
[133.800940] Subtest nonblocking-crc: SUCCESS (1.378s)
[133.939598] [118/161] (880s left) kms_pipe_crc_basic (nonblocking-crc-frame-sequence)
[134.122861] Starting subtest: nonblocking-crc-frame-sequence
[134.147980] Starting dynamic subtest: pipe-A-VGA-1
[134.550707] Dynamic subtest pipe-A-VGA-1: SUCCESS (0.462s)
[134.580693] Starting dynamic subtest: pipe-B-VGA-1
[135.016241] Dynamic subtest pipe-B-VGA-1: SUCCESS (0.453s)
[135.045294] Starting dynamic subtest: pipe-C-VGA-1
[135.473062] Dynamic subtest pipe-C-VGA-1: SUCCESS (0.454s)
[135.495824] Subtest nonblocking-crc-frame-sequence: SUCCESS (1.374s)
[135.657547] [119/161] (878s left) kms_pipe_crc_basic (read-crc)
[135.837949] Starting subtest: read-crc
[135.858771] Starting dynamic subtest: pipe-A-VGA-1
[136.224749] Dynamic subtest pipe-A-VGA-1: SUCCESS (0.402s)
[136.267889] Starting dynamic subtest: pipe-B-VGA-1
[136.615438] Dynamic subtest pipe-B-VGA-1: SUCCESS (0.386s)
[136.658267] Starting dynamic subtest: pipe-C-VGA-1
[136.985232] Dynamic subtest pipe-C-VGA-1: SUCCESS (0.388s)
[137.008247] Subtest read-crc: SUCCESS (1.179s)
[137.175160] [120/161] (877s left) kms_pipe_crc_basic (read-crc-frame-sequence)
[137.324714] Starting subtest: read-crc-frame-sequence
[137.344177] Starting dynamic subtest: pipe-A-VGA-1
[137.727217] Dynamic subtest pipe-A-VGA-1: SUCCESS (0.392s)
[137.757742] Starting dynamic subtest: pipe-B-VGA-1
[138.109431] Dynamic subtest pipe-B-VGA-1: SUCCESS (0.386s)
[138.137172] Starting dynamic subtest: pipe-C-VGA-1
[138.494577] Dynamic subtest pipe-C-VGA-1: SUCCESS (0.385s)
[138.515274] Subtest read-crc-frame-sequence: SUCCESS (1.169s)
[138.673881] [121/161] (875s left) kms_pm_backlight (basic-brightness)
[138.868382] Subtest basic-brightness: SKIP (0.000s)
[138.974883] [122/161] (875s left) kms_pm_rpm (basic-pci-d3-state)
[150.467751] Subtest basic-pci-d3-state: SKIP (0.000s)
[150.572988] [123/161] (863s left) kms_pm_rpm (basic-rte)
[150.723733] Starting subtest: basic-rte
[161.879732] Subtest basic-rte: SKIP (11.131s)
[162.024061] [124/161] (852s left) kms_psr (psr-primary-page-flip)
[162.199948] Subtest psr-primary-page-flip: SKIP (0.000s)
[162.307866] [125/161] (852s left) kms_psr (psr-cursor-plane-move)
[162.472677] Subtest psr-cursor-plane-move: SKIP (0.000s)
[162.584230] [126/161] (852s left) kms_psr (psr-sprite-plane-onoff)
[162.772803] Subtest psr-sprite-plane-onoff: SKIP (0.000s)
[162.877028] [127/161] (852s left) kms_psr (psr-primary-mmap-gtt)
[163.065734] Subtest psr-primary-mmap-gtt: SKIP (0.000s)
[163.177298] [128/161] (851s left) kms_setmode (basic-clone-single-crtc)
[163.330846] Starting subtest: basic-clone-single-crtc
[163.396303] Subtest basic-clone-single-crtc: SKIP (0.000s)
[163.505522] [129/161] (851s left) i915_pm_rps (basic-api)
[163.662358] Starting subtest: basic-api
[163.681775] Subtest basic-api: SUCCESS (0.003s)
[163.806503] [130/161] (851s left) prime_self_import (basic-llseek-bad)
[163.937632] Starting subtest: basic-llseek-bad
[163.975773] Subtest basic-llseek-bad: SUCCESS (0.002s)
[164.084699] [131/161] (851s left) prime_self_import (basic-llseek-size)
[164.208328] Starting subtest: basic-llseek-size
[164.252551] Subtest basic-llseek-size: SUCCESS (0.003s)
[164.361979] [132/161] (851s left) prime_self_import (basic-with_fd_dup)
[164.513371] Starting subtest: basic-with_fd_dup
[164.554240] Subtest basic-with_fd_dup: SUCCESS (0.003s)
[164.661248] [133/161] (850s left) prime_self_import (basic-with_one_bo)
[164.806927] Starting subtest: basic-with_one_bo
[164.846079] Subtest basic-with_one_bo: SUCCESS (0.004s)
[164.955381] [134/161] (850s left) prime_self_import (basic-with_one_bo_two_files)
[165.076899] Starting subtest: basic-with_one_bo_two_files
[165.121072] Subtest basic-with_one_bo_two_files: SUCCESS (0.002s)
[165.229310] [135/161] (850s left) prime_self_import (basic-with_two_bos)
[165.336464] Starting subtest: basic-with_two_bos
[165.379909] Subtest basic-with_two_bos: SUCCESS (0.003s)
[165.488158] [136/161] (850s left) prime_vgem (basic-fence-flip)
[165.681700] Starting subtest: basic-fence-flip
[165.955149] Subtest basic-fence-flip: SUCCESS (0.326s)
[166.109113] [137/161] (849s left) prime_vgem (basic-fence-mmap)
[166.298807] Starting subtest: basic-fence-mmap
[166.318029] Subtest basic-fence-mmap: SUCCESS (0.027s)
[166.446788] [138/161] (849s left) prime_vgem (basic-fence-read)
[166.612590] Starting subtest: basic-fence-read
[166.633939] Subtest basic-fence-read: SUCCESS (0.036s)
[166.760025] [139/161] (849s left) prime_vgem (basic-gtt)
[166.954151] Starting subtest: basic-gtt
[166.973170] Subtest basic-gtt: SUCCESS (0.012s)
[167.102600] [140/161] (849s left) prime_vgem (basic-read)
[167.274716] Starting subtest: basic-read
[167.296043] Subtest basic-read: SUCCESS (0.015s)
[167.418637] [141/161] (848s left) prime_vgem (basic-write)
[167.585651] Starting subtest: basic-write
[167.604763] Subtest basic-write: SUCCESS (0.017s)
[167.734923] [142/161] (848s left) vgem_basic (setversion)
[167.887972] Starting subtest: setversion
[167.930833] Subtest setversion: SUCCESS (0.000s)
[168.034047] [143/161] (848s left) vgem_basic (create)
[168.182190] Starting subtest: create
[168.201802] Subtest create: SUCCESS (0.001s)
[168.306834] [144/161] (848s left) vgem_basic (debugfs)
[168.432074] Starting subtest: debugfs
[168.451403] Subtest debugfs: SUCCESS (0.001s)
[168.559152] [145/161] (848s left) vgem_basic (dmabuf-export)
[168.724599] Starting subtest: dmabuf-export
[168.767122] Subtest dmabuf-export: SUCCESS (0.003s)
[168.870276] [146/161] (847s left) vgem_basic (dmabuf-fence)
[169.042236] Starting subtest: dmabuf-fence
[169.061511] Subtest dmabuf-fence: SUCCESS (0.001s)
[169.170150] [147/161] (847s left) vgem_basic (dmabuf-fence-before)
[169.296326] Starting subtest: dmabuf-fence-before
[169.315078] Subtest dmabuf-fence-before: SUCCESS (0.001s)
[169.428035] [148/161] (847s left) vgem_basic (dmabuf-mmap)
[169.566853] Starting subtest: dmabuf-mmap
[169.588128] Subtest dmabuf-mmap: SUCCESS (0.015s)
[169.698069] [149/161] (847s left) vgem_basic (mmap)
[169.864890] Starting subtest: mmap
[169.886480] Subtest mmap: SUCCESS (0.010s)
[169.989860] [150/161] (847s left) vgem_basic (second-client)
[170.164523] Starting subtest: second-client
[170.203308] Subtest second-client: SUCCESS (0.033s)
[170.315269] [151/161] (847s left) vgem_basic (sysfs)
[170.458311] Starting subtest: sysfs
[170.477817] Subtest sysfs: SUCCESS (0.001s)
[170.599864] [152/161] (846s left) core_hotunplug (unbind-rebind)
[170.870406] Starting subtest: unbind-rebind
[174.201613] Subtest unbind-rebind: SUCCESS (3.331s)
[174.310254] [153/161] (843s left) vgem_basic (unload)
[174.464561] Starting subtest: unload
[203.664130] Subtest unload: SUCCESS (29.064s)
[203.768526] [154/161] (813s left) i915_module_load (reload)
[203.904573] Starting subtest: reload
[206.630156] Subtest reload: SUCCESS (2.726s)
[206.757280] [155/161] (811s left) gem_lmem_swapping (basic)
[206.934064] Subtest basic: SKIP (0.000s)
[207.044642] [156/161] (810s left) gem_lmem_swapping (parallel-random-engines)
[207.191034] Subtest parallel-random-engines: SKIP (0.000s)
[207.298640] [157/161] (810s left) gem_lmem_swapping (random-engines)
[207.486951] Subtest random-engines: SKIP (0.000s)
[207.585579] [158/161] (810s left) gem_lmem_swapping (verify-random)
[207.771816] Subtest verify-random: SKIP (0.000s)
[207.877675] [159/161] (810s left) i915_pm_rpm (module-reload)
[219.189570] Subtest module-reload: SKIP (0.000s)
[219.295078] [160/161] (799s left) i915_selftest (live)
[220.617531] Starting subtest: live
[220.635966] Starting dynamic subtest: sanitycheck
[222.328436] Dynamic subtest sanitycheck: SUCCESS (1.708s)
[222.354583] Starting dynamic subtest: uncore
[224.052093] Dynamic subtest uncore: SUCCESS (1.721s)
[224.080095] Starting dynamic subtest: workarounds
[225.822377] Dynamic subtest workarounds: SUCCESS (1.771s)
[225.852366] Starting dynamic subtest: gt_engines
[227.579265] Dynamic subtest gt_engines: SUCCESS (1.753s)
[227.605306] Starting dynamic subtest: gt_timelines
[233.230872] Dynamic subtest gt_timelines: SUCCESS (5.649s)
[233.260791] Starting dynamic subtest: gt_contexts
[235.021067] Dynamic subtest gt_contexts: SUCCESS (1.788s)
[235.049221] Starting dynamic subtest: gt_lrc
[236.691577] Dynamic subtest gt_lrc: SUCCESS (1.667s)
[236.725140] Starting dynamic subtest: gt_mocs
[238.323485] Dynamic subtest gt_mocs: SUCCESS (1.634s)
[238.351166] Starting dynamic subtest: gt_pm
[250.020880] Dynamic subtest gt_pm: SUCCESS (11.695s)
[250.055199] Starting dynamic subtest: gt_heartbeat
[251.673266] Dynamic subtest gt_heartbeat: SUCCESS (1.650s)
[251.700184] Starting dynamic subtest: gt_tlb
[253.379178] Dynamic subtest gt_tlb: SUCCESS (1.703s)
[253.405992] Starting dynamic subtest: requests
[259.781226] Dynamic subtest requests: SUCCESS (6.399s)
[259.809376] Starting dynamic subtest: migrate
[261.432058] Dynamic subtest migrate: SUCCESS (1.648s)
[261.461280] Starting dynamic subtest: active
[263.184299] Dynamic subtest active: SUCCESS (1.751s)
[263.218602] Starting dynamic subtest: objects
[266.335927] Dynamic subtest objects: SUCCESS (3.149s)
[266.364726] Starting dynamic subtest: mman
[271.766017] Dynamic subtest mman: SUCCESS (5.430s)
[271.795126] Starting dynamic subtest: dmabuf
[274.613046] Dynamic subtest dmabuf: SUCCESS (2.845s)
[274.640814] Starting dynamic subtest: vma
[276.333220] Dynamic subtest vma: SUCCESS (1.718s)
[276.360432] Starting dynamic subtest: coherency
[278.875134] Dynamic subtest coherency: SUCCESS (2.539s)
[278.905993] Starting dynamic subtest: gtt
[283.360484] Dynamic subtest gtt: SUCCESS (4.484s)
[283.392252] Starting dynamic subtest: gem
[285.409143] Dynamic subtest gem: SUCCESS (2.047s)
[285.437361] Starting dynamic subtest: evict
[287.088636] Dynamic subtest evict: SUCCESS (1.676s)
[287.116063] Starting dynamic subtest: hugepages
[289.383064] Dynamic subtest hugepages: SUCCESS (2.294s)
[289.409784] Starting dynamic subtest: gem_contexts
[294.704764] Dynamic subtest gem_contexts: SUCCESS (5.319s)
[294.734041] Starting dynamic subtest: client
[296.386512] Dynamic subtest client: SUCCESS (1.681s)
[296.414548] Starting dynamic subtest: gem_migrate
[298.030486] Dynamic subtest gem_migrate: SUCCESS (1.641s)
[298.057676] Starting dynamic subtest: reset
[300.124495] Dynamic subtest reset: SUCCESS (2.092s)
[300.151362] Starting dynamic subtest: memory_region
[301.877383] Dynamic subtest memory_region: SUCCESS (1.749s)
[301.904636] Starting dynamic subtest: hangcheck
[316.349029] Dynamic subtest hangcheck: SUCCESS (14.468s)
[316.377838] Starting dynamic subtest: execlists
[319.066414] Dynamic subtest execlists: SUCCESS (2.715s)
[319.096135] Starting dynamic subtest: ring_submission
[320.827927] Dynamic subtest ring_submission: SUCCESS (1.758s)
[320.858995] Starting dynamic subtest: perf
[322.546483] Dynamic subtest perf: SUCCESS (1.718s)
[322.576380] Starting dynamic subtest: slpc
[324.243643] Dynamic subtest slpc: SUCCESS (1.695s)
[324.273316] Starting dynamic subtest: guc
[325.975556] Dynamic subtest guc: SUCCESS (1.729s)
[326.002629] Starting dynamic subtest: guc_multi_lrc
[327.701335] Dynamic subtest guc_multi_lrc: SUCCESS (1.721s)
[327.728340] Starting dynamic subtest: guc_hang
[329.448713] Dynamic subtest guc_hang: SUCCESS (1.746s)
[329.475820] Starting dynamic subtest: late_gt_pm
[331.182524] Dynamic subtest late_gt_pm: SUCCESS (1.729s)
[331.203716] Subtest live: SUCCESS (110.565s)
[331.274907] [FACT i915_selftest (live)] deleted: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[331.276183] [FACT i915_selftest (live)] deleted: kernel.kmod_is_loaded.i915: true
[331.297561] [161/161] (687s left) dmabuf (all-tests)
[331.457581] Starting subtest: all-tests
[331.476337] Starting dynamic subtest: sanitycheck
[331.579712] Dynamic subtest sanitycheck: SUCCESS (0.118s)
[331.607579] Starting dynamic subtest: dma_fence
[331.839550] Dynamic subtest dma_fence: SUCCESS (0.259s)
[331.864690] Starting dynamic subtest: dma_fence_chain
[342.101145] Dynamic subtest dma_fence_chain: SUCCESS (10.260s)
[342.137786] Starting dynamic subtest: dma_fence_unwrap
[342.212039] Dynamic subtest dma_fence_unwrap: SUCCESS (0.107s)
[342.244973] Starting dynamic subtest: dma_resv
[342.330577] Dynamic subtest dma_resv: SUCCESS (0.118s)
[342.350831] Subtest all-tests: SUCCESS (10.873s)
[342.428767] Closing watchdogs
Done.