[43.603578] Initializing watchdogs
[43.603783]   /dev/watchdog0
[43.620641] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:0162 Intel Ivybridge (Gen7) IvyBridge GT2 [HD Graphics 4000]
[43.648093] [001/162] (960s left) i915_module_load (load)
[43.839071] Starting subtest: load
[45.581330] Subtest load: SUCCESS (1.717s)
[45.694290] [FACT i915_module_load (load)] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[45.695822] [FACT i915_module_load (load)] new: kernel.kmod_is_loaded.i915: true
[45.720273] [002/162] (958s left) core_auth (basic-auth)
[45.882391] Starting subtest: basic-auth
[45.904761] Subtest basic-auth: SUCCESS (0.001s)
[46.046058] [003/162] (958s left) core_debugfs (read-all-entries)
[46.194952] Starting subtest: read-all-entries
[46.216666] Subtest read-all-entries: SUCCESS (0.055s)
[46.390657] [004/162] (958s left) core_sysfs (read-all-entries)
[46.560801] Starting subtest: read-all-entries
[46.580472] Subtest read-all-entries: SUCCESS (0.003s)
[46.720528] [005/162] (957s left) fbdev (eof)
[46.852432] Starting subtest: eof
[46.874795] Subtest eof: SUCCESS (0.000s)
[47.017701] [006/162] (957s left) fbdev (info)
[47.167370] Starting subtest: info
[47.189207] Subtest info: SUCCESS (0.000s)
[47.351046] [007/162] (957s left) fbdev (nullptr)
[47.493203] Starting subtest: nullptr
[47.512973] Subtest nullptr: SUCCESS (0.000s)
[47.647207] [008/162] (957s left) fbdev (read)
[47.780264] Starting subtest: read
[47.905720] Subtest read: SUCCESS (0.134s)
[48.045042] [009/162] (957s left) fbdev (write)
[48.190981] Starting subtest: write
[48.234155] Subtest write: SUCCESS (0.068s)
[48.398667] [010/162] (956s left) gem_basic (bad-close)
[48.552071] Starting subtest: bad-close
[48.591718] Subtest bad-close: SUCCESS (0.000s)
[48.734169] [011/162] (956s left) gem_basic (create-close)
[48.880214] Starting subtest: create-close
[48.924611] Subtest create-close: SUCCESS (0.000s)
[49.062976] [012/162] (956s left) gem_basic (create-fd-close)
[49.216406] Starting subtest: create-fd-close
[49.255026] Subtest create-fd-close: SUCCESS (0.000s)
[49.390671] [013/162] (956s left) gem_busy (busy@all-engines)
[49.563624] Starting subtest: busy
[49.584473] Starting dynamic subtest: all-engines
[49.606814] Dynamic subtest all-engines: SUCCESS (0.026s)
[49.628731] Subtest busy: SUCCESS (0.027s)
[49.985467] [014/162] (955s left) gem_close_race (basic-process)
[50.139193] Starting subtest: basic-process
[50.183345] Subtest basic-process: SUCCESS (0.041s)
[50.428561] [015/162] (955s left) gem_close_race (basic-threads)
[50.604779] Starting subtest: basic-threads
[51.766711] Subtest basic-threads: SUCCESS (1.199s)
[52.027302] [016/162] (954s left) gem_ctx_create (basic)
[52.181855] Starting subtest: basic
[52.201446] Subtest basic: SUCCESS (0.000s)
[52.449886] [017/162] (953s left) gem_ctx_create (basic-files)
[52.610748] Starting subtest: basic-files
[54.613722] Subtest basic-files: SUCCESS (2.003s)
[55.016310] [018/162] (951s left) gem_ctx_exec (basic)
[55.179386] Starting subtest: basic
[55.198754] Subtest basic: SUCCESS (0.003s)
[55.333620] [019/162] (951s left) gem_exec_basic (basic)
[55.490805] Starting subtest: basic
[55.510045] Starting dynamic subtest: rcs0-smem
[55.530646] Dynamic subtest rcs0-smem: SUCCESS (0.001s)
[55.562920] Starting dynamic subtest: bcs0-smem
[55.584637] Dynamic subtest bcs0-smem: SUCCESS (0.000s)
[55.615844] Starting dynamic subtest: vcs0-smem
[55.636638] Dynamic subtest vcs0-smem: SUCCESS (0.000s)
[55.656290] Subtest basic: SUCCESS (0.003s)
[55.796875] [020/162] (950s left) gem_exec_create (basic)
[55.951478] Starting subtest: basic
[55.969376] Starting dynamic subtest: smem
[57.975081] Dynamic subtest smem: SUCCESS (2.037s)
[57.996245] Subtest basic: SUCCESS (2.038s)
[58.235569] [021/162] (948s left) gem_exec_fence (basic-busy)
[58.501353] Starting subtest: basic-busy
[58.521628] Starting dynamic subtest: rcs0
[58.560189] Dynamic subtest rcs0: SUCCESS (0.037s)
[58.589727] Starting dynamic subtest: bcs0
[58.633136] Dynamic subtest bcs0: SUCCESS (0.029s)
[58.664335] Starting dynamic subtest: vcs0
[58.712324] Dynamic subtest vcs0: SUCCESS (0.033s)
[58.731452] Subtest basic-busy: SUCCESS (0.101s)
[59.166473] [FACT gem_exec_fence (basic-busy)] new: kernel.kmod_is_loaded.vgem: true
[59.188323] [022/162] (947s left) gem_exec_fence (basic-wait)
[59.443764] Starting subtest: basic-wait
[59.465282] Starting dynamic subtest: rcs0
[59.485318] Dynamic subtest rcs0: SUCCESS (0.029s)
[59.515094] Starting dynamic subtest: bcs0
[59.534394] Dynamic subtest bcs0: SUCCESS (0.026s)
[59.564146] Starting dynamic subtest: vcs0
[59.584713] Dynamic subtest vcs0: SUCCESS (0.031s)
[59.606728] Subtest basic-wait: SUCCESS (0.088s)
[60.057554] [023/162] (946s left) gem_exec_fence (basic-await)
[60.305436] Starting subtest: basic-await
[60.324907] Starting dynamic subtest: rcs0
[60.369489] Dynamic subtest rcs0: SUCCESS (0.073s)
[60.398904] Starting dynamic subtest: bcs0
[60.440084] Dynamic subtest bcs0: SUCCESS (0.070s)
[60.505338] Starting dynamic subtest: vcs0
[60.524226] Dynamic subtest vcs0: SUCCESS (0.079s)
[60.542452] Subtest basic-await: SUCCESS (0.223s)
[61.064811] [024/162] (946s left) gem_exec_fence (nb-await)
[61.310224] Starting subtest: nb-await
[61.335061] Starting dynamic subtest: rcs0
[61.359098] Dynamic subtest rcs0: SUCCESS (0.055s)
[61.388278] Starting dynamic subtest: bcs0
[61.411032] Dynamic subtest bcs0: SUCCESS (0.054s)
[61.443309] Starting dynamic subtest: vcs0
[61.468174] Dynamic subtest vcs0: SUCCESS (0.052s)
[61.493128] Subtest nb-await: SUCCESS (0.162s)
[62.001766] [025/162] (945s left) gem_exec_gttfill (basic)
[62.179932] Starting subtest: basic
[64.276627] Subtest basic: SUCCESS (2.131s)
[64.554461] [026/162] (942s left) gem_exec_parallel (engines)
[64.815483] Starting subtest: engines
[64.834213] Starting dynamic subtest: basic
[66.510234] Dynamic subtest basic: SUCCESS (1.733s)
[66.540913] Starting dynamic subtest: contexts
[68.121253] Dynamic subtest contexts: SUCCESS (1.613s)
[68.153491] Starting dynamic subtest: fds
[69.842513] Dynamic subtest fds: SUCCESS (1.717s)
[69.871956] Starting dynamic subtest: userptr
[75.968827] Dynamic subtest userptr: SUCCESS (6.123s)
[75.990846] Subtest engines: SUCCESS (11.189s)
[76.221660] [027/162] (931s left) gem_exec_store (basic)
[76.443443] Starting subtest: basic
[76.465590] Subtest basic: SUCCESS (0.046s)
[76.672409] [028/162] (931s left) gem_flink_basic (bad-flink)
[76.824660] Starting subtest: bad-flink
[76.843969] Subtest bad-flink: SUCCESS (0.000s)
[76.984753] [029/162] (930s left) gem_flink_basic (bad-open)
[77.134376] Starting subtest: bad-open
[77.153497] Subtest bad-open: SUCCESS (0.000s)
[77.299137] [030/162] (930s left) gem_flink_basic (basic)
[77.443777] Starting subtest: basic
[77.465619] Subtest basic: SUCCESS (0.000s)
[77.608479] [031/162] (930s left) gem_flink_basic (double-flink)
[77.757479] Starting subtest: double-flink
[77.778221] Subtest double-flink: SUCCESS (0.000s)
[77.916744] [032/162] (930s left) gem_flink_basic (flink-lifetime)
[78.071967] Starting subtest: flink-lifetime
[78.091271] Subtest flink-lifetime: SUCCESS (0.001s)
[78.232444] [033/162] (930s left) gem_huc_copy (huc-copy)
[78.394826] Subtest huc-copy: SKIP (0.000s)
[78.546073] [034/162] (929s left) gem_linear_blits (basic)
[78.740608] Starting subtest: basic
[78.761627] Subtest basic: SUCCESS (0.006s)
[78.959416] [035/162] (929s left) gem_mmap (basic)
[79.206845] Starting subtest: basic
[79.312805] Subtest basic: SUCCESS (0.000s)
[79.452398] [036/162] (929s left) gem_mmap_gtt (basic)
[79.608253] Starting subtest: basic
[79.628091] Subtest basic: SUCCESS (0.001s)
[79.793453] [037/162] (929s left) gem_render_linear_blits (basic)
[79.939123] Starting subtest: basic
[80.043555] Subtest basic: SUCCESS (0.026s)
[80.185089] [038/162] (928s left) gem_render_tiled_blits (basic)
[80.341401] Starting subtest: basic
[80.466339] Subtest basic: SUCCESS (0.024s)
[80.606518] [039/162] (928s left) gem_ringfill (basic-all)
[80.887527] Starting subtest: basic-all
[81.961845] Subtest basic-all: SUCCESS (1.109s)
[82.160259] [040/162] (927s left) gem_softpin (allocator-basic)
[82.342647] Subtest allocator-basic: SKIP (0.000s)
[82.502350] [041/162] (926s left) gem_softpin (allocator-basic-reserve)
[82.662931] Subtest allocator-basic-reserve: SKIP (0.000s)
[82.856548] [042/162] (926s left) gem_softpin (safe-alignment)
[83.047301] Starting subtest: safe-alignment
[83.066880] Subtest safe-alignment: SUCCESS (0.006s)
[83.229516] [043/162] (926s left) gem_sync (basic-all)
[83.441811] Starting subtest: basic-all
[85.781638] Subtest basic-all: SUCCESS (2.233s)
[85.975602] [044/162] (923s left) gem_sync (basic-each)
[86.168517] Starting subtest: basic-each
[88.246676] Subtest basic-each: SUCCESS (2.122s)
[88.524335] [045/162] (921s left) gem_tiled_blits (basic)
[88.728678] Starting subtest: basic
[88.751453] Subtest basic: SUCCESS (0.011s)
[88.952269] [046/162] (921s left) gem_tiled_fence_blits (basic)
[89.240550] Starting subtest: basic
[89.260835] Subtest basic: SUCCESS (0.008s)
[89.463948] [047/162] (920s left) gem_tiled_pread_basic (basic)
[89.706118] Starting subtest: basic
[89.810983] Subtest basic: SUCCESS (0.144s)
[89.993037] [048/162] (920s left) gem_wait (busy@all-engines)
[90.161633] Starting subtest: busy
[90.182291] Starting dynamic subtest: all-engines
[90.655054] Dynamic subtest all-engines: SUCCESS (0.518s)
[90.674443] Subtest busy: SUCCESS (0.519s)
[90.946064] [049/162] (919s left) gem_wait (wait@all-engines)
[91.098432] Starting subtest: wait
[91.118623] Starting dynamic subtest: all-engines
[92.104805] Dynamic subtest all-engines: SUCCESS (1.021s)
[92.124294] Subtest wait: SUCCESS (1.022s)
[92.391393] [050/162] (918s left) i915_getparams_basic (basic-eu-total)
[92.541880] Starting subtest: basic-eu-total
[92.582409] Subtest basic-eu-total: SUCCESS (0.000s)
[92.721766] [051/162] (918s left) i915_getparams_basic (basic-subslice-total)
[92.872381] Starting subtest: basic-subslice-total
[92.910877] Subtest basic-subslice-total: SUCCESS (0.000s)
[93.056592] [052/162] (917s left) i915_hangman (error-state-basic)
[93.208883] Starting subtest: error-state-basic
[93.230992] Subtest error-state-basic: SUCCESS (0.006s)
[93.382539] [053/162] (917s left) i915_pciid
[93.690946] [054/162] (917s left) intel_hwmon (hwmon-read)
[93.860868] Subtest hwmon-read: SKIP (0.000s)
[94.001229] [055/162] (917s left) intel_hwmon (hwmon-write)
[94.171913] Subtest hwmon-write: SKIP (0.000s)
[94.317279] [056/162] (917s left) kms_addfb_basic (addfb25-4-tiled)
[94.541825] Starting subtest: addfb25-4-tiled
[94.561225] Subtest addfb25-4-tiled: SUCCESS (0.000s)
[94.698189] [057/162] (916s left) kms_addfb_basic (addfb25-bad-modifier)
[94.845385] Starting subtest: addfb25-bad-modifier
[94.864837] Subtest addfb25-bad-modifier: SUCCESS (0.000s)
[95.030744] [058/162] (916s left) kms_addfb_basic (addfb25-framebuffer-vs-set-tiling)
[95.194780] Starting subtest: addfb25-framebuffer-vs-set-tiling
[95.213504] Subtest addfb25-framebuffer-vs-set-tiling: SUCCESS (0.000s)
[95.394926] [059/162] (916s left) kms_addfb_basic (addfb25-modifier-no-flag)
[95.545559] Starting subtest: addfb25-modifier-no-flag
[95.570247] Subtest addfb25-modifier-no-flag: SUCCESS (0.000s)
[95.753415] [060/162] (916s left) kms_addfb_basic (addfb25-x-tiled-legacy)
[95.932254] Starting subtest: addfb25-x-tiled-legacy
[95.957277] Subtest addfb25-x-tiled-legacy: SUCCESS (0.000s)
[96.131616] [061/162] (916s left) kms_addfb_basic (addfb25-x-tiled-mismatch-legacy)
[96.298336] Starting subtest: addfb25-x-tiled-mismatch-legacy
[96.323985] Subtest addfb25-x-tiled-mismatch-legacy: SUCCESS (0.000s)
[96.497970] [062/162] (915s left) kms_addfb_basic (addfb25-yf-tiled-legacy)
[96.676184] Starting subtest: addfb25-yf-tiled-legacy
[96.703466] Subtest addfb25-yf-tiled-legacy: SUCCESS (0.000s)
[96.892358] [063/162] (915s left) kms_addfb_basic (addfb25-y-tiled-legacy)
[97.063858] Starting subtest: addfb25-y-tiled-legacy
[97.089231] Subtest addfb25-y-tiled-legacy: SUCCESS (0.000s)
[97.259671] [064/162] (915s left) kms_addfb_basic (addfb25-y-tiled-small-legacy)
[97.430264] Starting subtest: addfb25-y-tiled-small-legacy
[97.481823] Subtest addfb25-y-tiled-small-legacy: SKIP (0.000s)
[97.652746] [065/162] (915s left) kms_addfb_basic (bad-pitch-0)
[97.796611] Starting subtest: bad-pitch-0
[97.818663] Subtest bad-pitch-0: SUCCESS (0.000s)
[97.983949] [066/162] (915s left) kms_addfb_basic (bad-pitch-1024)
[98.135127] Starting subtest: bad-pitch-1024
[98.156349] Subtest bad-pitch-1024: SUCCESS (0.000s)
[98.295759] [067/162] (914s left) kms_addfb_basic (bad-pitch-128)
[98.445483] Starting subtest: bad-pitch-128
[98.464796] Subtest bad-pitch-128: SUCCESS (0.000s)
[98.606701] [068/162] (914s left) kms_addfb_basic (bad-pitch-256)
[98.753976] Starting subtest: bad-pitch-256
[98.773402] Subtest bad-pitch-256: SUCCESS (0.000s)
[98.920148] [069/162] (914s left) kms_addfb_basic (bad-pitch-32)
[99.046582] Starting subtest: bad-pitch-32
[99.068744] Subtest bad-pitch-32: SUCCESS (0.000s)
[99.214628] [070/162] (914s left) kms_addfb_basic (bad-pitch-63)
[99.365507] Starting subtest: bad-pitch-63
[99.386800] Subtest bad-pitch-63: SUCCESS (0.000s)
[99.527409] [071/162] (914s left) kms_addfb_basic (bad-pitch-65536)
[99.700187] Starting subtest: bad-pitch-65536
[99.719195] Subtest bad-pitch-65536: SUCCESS (0.000s)
[99.862460] [072/162] (914s left) kms_addfb_basic (bad-pitch-999)
[100.025741] Starting subtest: bad-pitch-999
[100.047591] Subtest bad-pitch-999: SUCCESS (0.000s)
[100.192456] [073/162] (913s left) kms_addfb_basic (basic)
[100.339553] Starting subtest: basic
[100.360714] Subtest basic: SUCCESS (0.000s)
[100.501154] [074/162] (913s left) kms_addfb_basic (basic-x-tiled-legacy)
[100.661539] Starting subtest: basic-x-tiled-legacy
[100.681279] Subtest basic-x-tiled-legacy: SUCCESS (0.000s)
[100.822712] [075/162] (913s left) kms_addfb_basic (basic-y-tiled-legacy)
[100.971084] Starting subtest: basic-y-tiled-legacy
[100.990426] Subtest basic-y-tiled-legacy: SUCCESS (0.000s)
[101.134828] [076/162] (913s left) kms_addfb_basic (bo-too-small)
[101.277248] Starting subtest: bo-too-small
[101.298680] Subtest bo-too-small: SUCCESS (0.000s)
[101.444098] [077/162] (913s left) kms_addfb_basic (bo-too-small-due-to-tiling)
[101.614553] Starting subtest: bo-too-small-due-to-tiling
[101.635814] Subtest bo-too-small-due-to-tiling: SUCCESS (0.000s)
[101.778503] [078/162] (912s left) kms_addfb_basic (clobberred-modifier)
[101.932209] Starting subtest: clobberred-modifier
[101.950961] Subtest clobberred-modifier: SUCCESS (0.000s)
[102.116504] [079/162] (912s left) kms_addfb_basic (framebuffer-vs-set-tiling)
[102.260346] Starting subtest: framebuffer-vs-set-tiling
[102.282156] Subtest framebuffer-vs-set-tiling: SUCCESS (0.000s)
[102.449215] [080/162] (912s left) kms_addfb_basic (invalid-get-prop)
[102.593993] Starting subtest: invalid-get-prop
[102.615587] Subtest invalid-get-prop: SUCCESS (0.000s)
[102.754711] [081/162] (912s left) kms_addfb_basic (invalid-get-prop-any)
[102.904271] Starting subtest: invalid-get-prop-any
[102.923353] Subtest invalid-get-prop-any: SUCCESS (0.000s)
[103.090511] [082/162] (912s left) kms_addfb_basic (invalid-set-prop)
[103.235289] Starting subtest: invalid-set-prop
[103.257125] Subtest invalid-set-prop: SUCCESS (0.000s)
[103.402611] [083/162] (912s left) kms_addfb_basic (invalid-set-prop-any)
[103.548108] Starting subtest: invalid-set-prop-any
[103.569204] Subtest invalid-set-prop-any: SUCCESS (0.000s)
[103.720966] [084/162] (911s left) kms_addfb_basic (no-handle)
[103.872084] Starting subtest: no-handle
[103.891547] Subtest no-handle: SUCCESS (0.000s)
[104.029951] [085/162] (911s left) kms_addfb_basic (size-max)
[104.181065] Starting subtest: size-max
[104.200273] Subtest size-max: SUCCESS (0.000s)
[104.343490] [086/162] (911s left) kms_addfb_basic (small-bo)
[104.486431] Starting subtest: small-bo
[104.508348] Subtest small-bo: SUCCESS (0.000s)
[104.672552] [087/162] (911s left) kms_addfb_basic (tile-pitch-mismatch)
[104.824273] Starting subtest: tile-pitch-mismatch
[104.845700] Subtest tile-pitch-mismatch: SUCCESS (0.000s)
[104.985865] [088/162] (911s left) kms_addfb_basic (too-high)
[105.160901] Starting subtest: too-high
[105.180360] Subtest too-high: SUCCESS (0.001s)
[105.325333] [089/162] (910s left) kms_addfb_basic (too-wide)
[105.490989] Starting subtest: too-wide
[105.513054] Subtest too-wide: SUCCESS (0.000s)
[105.656417] [090/162] (910s left) kms_addfb_basic (unused-handle)
[105.804818] Starting subtest: unused-handle
[105.826181] Subtest unused-handle: SUCCESS (0.000s)
[105.965372] [091/162] (910s left) kms_addfb_basic (unused-modifier)
[106.137504] Starting subtest: unused-modifier
[106.156537] Subtest unused-modifier: SUCCESS (0.000s)
[106.300961] [092/162] (910s left) kms_addfb_basic (unused-offsets)
[106.445218] Starting subtest: unused-offsets
[106.467415] Subtest unused-offsets: SUCCESS (0.000s)
[106.612749] [093/162] (910s left) kms_addfb_basic (unused-pitches)
[106.772652] Starting subtest: unused-pitches
[106.794196] Subtest unused-pitches: SUCCESS (0.000s)
[106.935212] [094/162] (909s left) kms_busy (basic)
[107.110752] Starting subtest: basic
[107.130272] Starting dynamic subtest: flip
[107.616638] Dynamic subtest flip: SUCCESS (0.535s)
[107.647608] Starting dynamic subtest: modeset
[108.191686] Dynamic subtest modeset: SUCCESS (0.576s)
[108.212706] Subtest basic: SUCCESS (1.113s)
[108.417866] [095/162] (908s left) kms_prop_blob (basic)
[108.577652] Starting subtest: basic
[108.597158] Subtest basic: SUCCESS (0.000s)
[108.736512] [096/162] (908s left) kms_cursor_legacy (basic-busy-flip-before-cursor-atomic)
[108.887446] Starting subtest: basic-busy-flip-before-cursor-atomic
[109.812032] Subtest basic-busy-flip-before-cursor-atomic: SUCCESS (0.922s)
[110.034096] [097/162] (907s left) kms_cursor_legacy (basic-busy-flip-before-cursor-legacy)
[110.181358] Starting subtest: basic-busy-flip-before-cursor-legacy
[111.110878] Subtest basic-busy-flip-before-cursor-legacy: SUCCESS (0.947s)
[111.340137] [098/162] (906s left) kms_cursor_legacy (basic-flip-after-cursor-atomic)
[111.503746] Starting subtest: basic-flip-after-cursor-atomic
[112.397602] Subtest basic-flip-after-cursor-atomic: SUCCESS (0.918s)
[112.631940] [099/162] (904s left) kms_cursor_legacy (basic-flip-after-cursor-legacy)
[112.782173] Starting subtest: basic-flip-after-cursor-legacy
[113.697278] Subtest basic-flip-after-cursor-legacy: SUCCESS (0.925s)
[113.924849] [100/162] (903s left) kms_cursor_legacy (basic-flip-after-cursor-varying-size)
[114.073288] Starting subtest: basic-flip-after-cursor-varying-size
[114.984359] Subtest basic-flip-after-cursor-varying-size: SUCCESS (0.916s)
[115.212272] [101/162] (902s left) kms_cursor_legacy (basic-flip-before-cursor-atomic)
[115.387921] Starting subtest: basic-flip-before-cursor-atomic
[116.307220] Subtest basic-flip-before-cursor-atomic: SUCCESS (0.937s)
[116.570764] [102/162] (901s left) kms_cursor_legacy (basic-flip-before-cursor-legacy)
[116.762107] Starting subtest: basic-flip-before-cursor-legacy
[117.651280] Subtest basic-flip-before-cursor-legacy: SUCCESS (0.928s)
[117.906167] [103/162] (900s left) kms_cursor_legacy (basic-flip-before-cursor-varying-size)
[118.081713] Starting subtest: basic-flip-before-cursor-varying-size
[119.006030] Subtest basic-flip-before-cursor-varying-size: SUCCESS (0.916s)
[119.246475] [104/162] (899s left) kms_dsc (dsc-basic)
[119.446910] Subtest dsc-basic: SKIP (0.000s)
[119.628354] [105/162] (898s left) kms_flip (basic-flip-vs-dpms)
[119.819271] Starting subtest: basic-flip-vs-dpms
[119.841387] Starting dynamic subtest: A-VGA1
[120.754958] Dynamic subtest A-VGA1: SUCCESS (0.961s)
[120.787622] Starting dynamic subtest: B-VGA1
[121.681078] Dynamic subtest B-VGA1: SUCCESS (0.917s)
[121.711495] Starting dynamic subtest: C-VGA1
[122.592643] Dynamic subtest C-VGA1: SUCCESS (0.915s)
[122.613602] Subtest basic-flip-vs-dpms: SUCCESS (2.799s)
[122.829608] [106/162] (895s left) kms_flip (basic-flip-vs-modeset)
[123.007111] Starting subtest: basic-flip-vs-modeset
[123.028740] Starting dynamic subtest: A-VGA1
[123.882569] Dynamic subtest A-VGA1: SUCCESS (0.901s)
[123.919230] Starting dynamic subtest: B-VGA1
[124.734033] Dynamic subtest B-VGA1: SUCCESS (0.850s)
[124.766191] Starting dynamic subtest: C-VGA1
[125.578735] Dynamic subtest C-VGA1: SUCCESS (0.849s)
[125.597855] Subtest basic-flip-vs-modeset: SUCCESS (2.605s)
[125.831245] [107/162] (892s left) kms_flip (basic-flip-vs-wf_vblank)
[126.005660] Starting subtest: basic-flip-vs-wf_vblank
[126.024220] Starting dynamic subtest: A-VGA1
[127.140421] Dynamic subtest A-VGA1: SUCCESS (1.140s)
[127.172035] Starting dynamic subtest: B-VGA1
[128.278692] Dynamic subtest B-VGA1: SUCCESS (1.131s)
[128.309542] Starting dynamic subtest: C-VGA1
[129.418576] Dynamic subtest C-VGA1: SUCCESS (1.130s)
[129.436810] Subtest basic-flip-vs-wf_vblank: SUCCESS (3.408s)
[129.656352] [108/162] (889s left) kms_flip (basic-plain-flip)
[129.829358] Starting subtest: basic-plain-flip
[129.851077] Starting dynamic subtest: A-VGA1
[130.647259] Dynamic subtest A-VGA1: SUCCESS (0.863s)
[130.678707] Starting dynamic subtest: B-VGA1
[131.496202] Dynamic subtest B-VGA1: SUCCESS (0.847s)
[131.527414] Starting dynamic subtest: C-VGA1
[132.346158] Dynamic subtest C-VGA1: SUCCESS (0.846s)
[132.364295] Subtest basic-plain-flip: SUCCESS (2.564s)
[132.632166] [109/162] (886s left) kms_force_connector_basic (force-connector-state)
[132.791274] Starting subtest: force-connector-state
[132.929424] Subtest force-connector-state: SUCCESS (0.162s)
[133.196046] [110/162] (885s left) kms_force_connector_basic (force-edid)
[133.340541] Starting subtest: force-edid
[133.359153] Subtest force-edid: SUCCESS (0.025s)
[133.528478] [111/162] (885s left) kms_force_connector_basic (force-load-detect)
[133.694925] Starting subtest: force-load-detect
[133.817827] Subtest force-load-detect: SUCCESS (0.170s)
[134.095353] [112/162] (885s left) kms_force_connector_basic (prune-stale-modes)
[134.258857] Starting subtest: prune-stale-modes
[134.277121] Subtest prune-stale-modes: SUCCESS (0.017s)
[134.424542] [113/162] (885s left) kms_frontbuffer_tracking (basic)
[134.638625] Starting subtest: basic
[136.489430] Subtest basic: SUCCESS (1.913s)
[136.654996] [114/162] (882s left) kms_hdmi_inject (inject-audio)
[136.828503] Starting subtest: inject-audio
[136.865925] Subtest inject-audio: SKIP (0.000s)
[137.087894] [115/162] (882s left) kms_pipe_crc_basic (compare-crc-sanitycheck-xr24)
[137.290224] Starting subtest: compare-crc-sanitycheck-xr24
[137.312485] Starting dynamic subtest: pipe-A-VGA-1
[137.557190] Dynamic subtest pipe-A-VGA-1: SUCCESS (0.284s)
[137.592641] Starting dynamic subtest: pipe-B-VGA-1
[137.821831] Dynamic subtest pipe-B-VGA-1: SUCCESS (0.269s)
[137.855328] Starting dynamic subtest: pipe-C-VGA-1
[138.095341] Dynamic subtest pipe-C-VGA-1: SUCCESS (0.269s)
[138.116685] Subtest compare-crc-sanitycheck-xr24: SUCCESS (0.828s)
[138.366441] [116/162] (881s left) kms_pipe_crc_basic (compare-crc-sanitycheck-nv12)
[138.551632] Starting subtest: compare-crc-sanitycheck-nv12
[138.578244] Starting dynamic subtest: pipe-A-VGA-1
[138.631536] Dynamic subtest pipe-A-VGA-1: SKIP (0.000s)
[138.669410] Starting dynamic subtest: pipe-B-VGA-1
[138.722479] Dynamic subtest pipe-B-VGA-1: SKIP (0.000s)
[138.763249] Starting dynamic subtest: pipe-C-VGA-1
[138.814452] Dynamic subtest pipe-C-VGA-1: SKIP (0.000s)
[138.866339] Subtest compare-crc-sanitycheck-nv12: SKIP (0.006s)
[139.046597] [117/162] (881s left) kms_pipe_crc_basic (hang-read-crc)
[139.243504] Starting subtest: hang-read-crc
[139.265580] Starting dynamic subtest: pipe-A-VGA-1
[140.003202] Dynamic subtest pipe-A-VGA-1: SUCCESS (0.788s)
[140.032615] Starting dynamic subtest: pipe-B-VGA-1
[140.769762] Dynamic subtest pipe-B-VGA-1: SUCCESS (0.770s)
[140.801952] Starting dynamic subtest: pipe-C-VGA-1
[141.549415] Dynamic subtest pipe-C-VGA-1: SUCCESS (0.776s)
[141.568358] Subtest hang-read-crc: SUCCESS (2.339s)
[141.766253] [118/162] (878s left) kms_pipe_crc_basic (nonblocking-crc)
[141.938952] Starting subtest: nonblocking-crc
[141.960440] Starting dynamic subtest: pipe-A-VGA-1
[142.384797] Dynamic subtest pipe-A-VGA-1: SUCCESS (0.468s)
[142.414096] Starting dynamic subtest: pipe-B-VGA-1
[142.828501] Dynamic subtest pipe-B-VGA-1: SUCCESS (0.452s)
[142.860752] Starting dynamic subtest: pipe-C-VGA-1
[143.289310] Dynamic subtest pipe-C-VGA-1: SUCCESS (0.452s)
[143.310361] Subtest nonblocking-crc: SUCCESS (1.376s)
[143.499890] [119/162] (876s left) kms_pipe_crc_basic (nonblocking-crc-frame-sequence)
[143.644726] Starting subtest: nonblocking-crc-frame-sequence
[143.663825] Starting dynamic subtest: pipe-A-VGA-1
[144.127050] Dynamic subtest pipe-A-VGA-1: SUCCESS (0.475s)
[144.158194] Starting dynamic subtest: pipe-B-VGA-1
[144.596524] Dynamic subtest pipe-B-VGA-1: SUCCESS (0.469s)
[144.628275] Starting dynamic subtest: pipe-C-VGA-1
[145.053736] Dynamic subtest pipe-C-VGA-1: SUCCESS (0.452s)
[145.074918] Subtest nonblocking-crc-frame-sequence: SUCCESS (1.401s)
[145.261518] [120/162] (875s left) kms_pipe_crc_basic (read-crc)
[145.408023] Starting subtest: read-crc
[145.427149] Starting dynamic subtest: pipe-A-VGA-1
[145.818512] Dynamic subtest pipe-A-VGA-1: SUCCESS (0.407s)
[145.849774] Starting dynamic subtest: pipe-B-VGA-1
[146.207255] Dynamic subtest pipe-B-VGA-1: SUCCESS (0.384s)
[146.236945] Starting dynamic subtest: pipe-C-VGA-1
[146.589713] Dynamic subtest pipe-C-VGA-1: SUCCESS (0.387s)
[146.608225] Subtest read-crc: SUCCESS (1.184s)
[146.812373] [121/162] (873s left) kms_pipe_crc_basic (read-crc-frame-sequence)
[146.991658] Starting subtest: read-crc-frame-sequence
[147.013193] Starting dynamic subtest: pipe-A-VGA-1
[147.362224] Dynamic subtest pipe-A-VGA-1: SUCCESS (0.400s)
[147.395914] Starting dynamic subtest: pipe-B-VGA-1
[147.743452] Dynamic subtest pipe-B-VGA-1: SUCCESS (0.384s)
[147.772506] Starting dynamic subtest: pipe-C-VGA-1
[148.132678] Dynamic subtest pipe-C-VGA-1: SUCCESS (0.385s)
[148.153058] Subtest read-crc-frame-sequence: SUCCESS (1.174s)
[148.345595] [122/162] (872s left) kms_pm_backlight (basic-brightness)
[148.521926] Subtest basic-brightness: SKIP (0.000s)
[148.670487] [123/162] (872s left) kms_pm_rpm (basic-pci-d3-state)
[160.122213] Subtest basic-pci-d3-state: SKIP (0.000s)
[160.267964] [124/162] (860s left) kms_pm_rpm (basic-rte)
[160.412533] Starting subtest: basic-rte
[171.708284] Subtest basic-rte: SKIP (11.094s)
[171.894359] [125/162] (849s left) kms_psr (psr-primary-page-flip)
[172.069445] Subtest psr-primary-page-flip: SKIP (0.000s)
[172.211997] [126/162] (849s left) kms_psr (psr-cursor-plane-move)
[172.409651] Subtest psr-cursor-plane-move: SKIP (0.000s)
[172.558275] [127/162] (848s left) kms_psr (psr-sprite-plane-onoff)
[172.728481] Subtest psr-sprite-plane-onoff: SKIP (0.000s)
[172.876324] [128/162] (848s left) kms_psr (psr-primary-mmap-gtt)
[173.052949] Subtest psr-primary-mmap-gtt: SKIP (0.000s)
[173.194017] [129/162] (848s left) kms_setmode (basic-clone-single-crtc)
[173.352541] Starting subtest: basic-clone-single-crtc
[173.411364] Subtest basic-clone-single-crtc: SKIP (0.000s)
[173.558292] [130/162] (848s left) i915_pm_rps (basic-api)
[173.711689] Starting subtest: basic-api
[173.733362] Subtest basic-api: SUCCESS (0.003s)
[173.896146] [131/162] (848s left) prime_self_import (basic-llseek-bad)
[174.034483] Starting subtest: basic-llseek-bad
[174.073835] Subtest basic-llseek-bad: SUCCESS (0.002s)
[174.216040] [132/162] (847s left) prime_self_import (basic-llseek-size)
[174.348840] Starting subtest: basic-llseek-size
[174.387392] Subtest basic-llseek-size: SUCCESS (0.003s)
[174.535198] [133/162] (847s left) prime_self_import (basic-with_fd_dup)
[174.662225] Starting subtest: basic-with_fd_dup
[174.702947] Subtest basic-with_fd_dup: SUCCESS (0.004s)
[174.850649] [134/162] (847s left) prime_self_import (basic-with_one_bo)
[174.985654] Starting subtest: basic-with_one_bo
[175.028585] Subtest basic-with_one_bo: SUCCESS (0.003s)
[175.168655] [135/162] (847s left) prime_self_import (basic-with_one_bo_two_files)
[175.299249] Starting subtest: basic-with_one_bo_two_files
[175.338167] Subtest basic-with_one_bo_two_files: SUCCESS (0.002s)
[175.481552] [136/162] (847s left) prime_self_import (basic-with_two_bos)
[175.634052] Starting subtest: basic-with_two_bos
[175.674752] Subtest basic-with_two_bos: SUCCESS (0.004s)
[175.829356] [137/162] (847s left) prime_vgem (basic-fence-flip)
[176.022234] Starting subtest: basic-fence-flip
[176.287999] Subtest basic-fence-flip: SUCCESS (0.327s)
[176.478420] [138/162] (846s left) prime_vgem (basic-fence-mmap)
[176.671049] Starting subtest: basic-fence-mmap
[176.693789] Subtest basic-fence-mmap: SUCCESS (0.032s)
[176.886368] [139/162] (846s left) prime_vgem (basic-fence-read)
[177.087525] Starting subtest: basic-fence-read
[177.113637] Subtest basic-fence-read: SUCCESS (0.043s)
[177.306750] [140/162] (846s left) prime_vgem (basic-gtt)
[177.509620] Starting subtest: basic-gtt
[177.533872] Subtest basic-gtt: SUCCESS (0.015s)
[177.729241] [141/162] (845s left) prime_vgem (basic-read)
[177.928526] Starting subtest: basic-read
[177.953778] Subtest basic-read: SUCCESS (0.018s)
[178.153308] [142/162] (845s left) prime_vgem (basic-write)
[178.345514] Starting subtest: basic-write
[178.369238] Subtest basic-write: SUCCESS (0.017s)
[178.567320] [143/162] (845s left) vgem_basic (setversion)
[178.746366] Starting subtest: setversion
[178.787418] Subtest setversion: SUCCESS (0.000s)
[178.928233] [144/162] (845s left) vgem_basic (create)
[179.099094] Starting subtest: create
[179.118316] Subtest create: SUCCESS (0.000s)
[179.276375] [145/162] (844s left) vgem_basic (debugfs)
[179.427783] Starting subtest: debugfs
[179.448867] Subtest debugfs: SUCCESS (0.001s)
[179.592444] [146/162] (844s left) vgem_basic (dmabuf-export)
[179.744626] Starting subtest: dmabuf-export
[179.783348] Subtest dmabuf-export: SUCCESS (0.003s)
[179.926677] [147/162] (844s left) vgem_basic (dmabuf-fence)
[180.078463] Starting subtest: dmabuf-fence
[180.097939] Subtest dmabuf-fence: SUCCESS (0.001s)
[180.250401] [148/162] (844s left) vgem_basic (dmabuf-fence-before)
[180.396067] Starting subtest: dmabuf-fence-before
[180.418230] Subtest dmabuf-fence-before: SUCCESS (0.001s)
[180.565992] [149/162] (844s left) vgem_basic (dmabuf-mmap)
[180.706976] Starting subtest: dmabuf-mmap
[180.728419] Subtest dmabuf-mmap: SUCCESS (0.015s)
[180.873482] [150/162] (844s left) vgem_basic (mmap)
[181.027884] Starting subtest: mmap
[181.047365] Subtest mmap: SUCCESS (0.011s)
[181.190127] [151/162] (843s left) vgem_basic (second-client)
[181.361210] Starting subtest: second-client
[181.403611] Subtest second-client: SUCCESS (0.033s)
[181.549617] [152/162] (843s left) vgem_basic (sysfs)
[181.719794] Starting subtest: sysfs
[181.740456] Subtest sysfs: SUCCESS (0.001s)
[181.881171] [153/162] (843s left) core_hotunplug (unbind-rebind)
[182.122319] Starting subtest: unbind-rebind
[185.121578] Subtest unbind-rebind: SUCCESS (2.983s)
[185.266475] [154/162] (840s left) vgem_basic (unload)
[185.414566] Starting subtest: unload
[214.350915] Subtest unload: SUCCESS (28.969s)
[214.536092] [155/162] (811s left) i915_module_load (reload)
[214.689501] Starting subtest: reload
[217.013585] Subtest reload: SUCCESS (2.353s)
[217.159546] [156/162] (808s left) gem_lmem_swapping (basic)
[217.361210] Subtest basic: SKIP (0.000s)
[217.501731] [157/162] (808s left) gem_lmem_swapping (parallel-random-engines)
[217.676127] Subtest parallel-random-engines: SKIP (0.000s)
[217.820884] [158/162] (808s left) gem_lmem_swapping (random-engines)
[217.988394] Subtest random-engines: SKIP (0.000s)
[218.135870] [159/162] (808s left) gem_lmem_swapping (verify-random)
[218.328765] Subtest verify-random: SKIP (0.000s)
[218.469954] [160/162] (807s left) i915_pm_rpm (module-reload)
[229.921763] Subtest module-reload: SKIP (0.000s)
[230.067203] [161/162] (796s left) i915_selftest (live)
[230.911415] Starting subtest: live
[230.930361] Starting dynamic subtest: sanitycheck
[241.760087] Killing the test because the kernel is tainted.