[41.713850] Initializing watchdogs
[41.714255]   /dev/watchdog0
[41.754507] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:0042 Intel Ironlake (Gen5) Core Processor Integrated Graphics Controller
[41.781695] [001/161] (960s left) i915_module_load (load)
[41.970621] Starting subtest: load
[43.788390] Subtest load: SUCCESS (1.808s)
[43.924907] [FACT i915_module_load (load)] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[43.928620] [FACT i915_module_load (load)] new: kernel.kmod_is_loaded.i915: true
[43.956269] [002/161] (958s left) core_auth (basic-auth)
[44.186619] Starting subtest: basic-auth
[44.208781] Subtest basic-auth: SUCCESS (0.002s)
[44.320776] [003/161] (958s left) debugfs_test (read_all_entries)
[44.517650] Starting subtest: read_all_entries
[44.556717] Subtest read_all_entries: SUCCESS (0.066s)
[44.717364] [004/161] (957s left) debugfs_test (basic-hwmon)
[44.894048] Starting subtest: basic-hwmon
[44.936994] Subtest basic-hwmon: SKIP (0.000s)
[45.049367] [005/161] (957s left) debugfs_test (sysfs)
[45.209222] Starting subtest: sysfs
[45.229674] Subtest sysfs: SUCCESS (0.039s)
[45.366217] [006/161] (957s left) fbdev (eof)
[45.534146] Starting subtest: eof
[45.553671] Subtest eof: SUCCESS (0.001s)
[45.667932] [007/161] (957s left) fbdev (info)
[45.824366] Starting subtest: info
[45.843777] Subtest info: SUCCESS (0.000s)
[45.958612] [008/161] (957s left) fbdev (nullptr)
[46.115071] Starting subtest: nullptr
[46.134900] Subtest nullptr: SUCCESS (0.000s)
[46.252766] [009/161] (956s left) fbdev (read)
[46.385428] Starting subtest: read
[46.654539] Subtest read: SUCCESS (0.281s)
[46.777269] [010/161] (956s left) fbdev (write)
[46.942084] Starting subtest: write
[47.075073] Subtest write: SUCCESS (0.149s)
[47.191702] [011/161] (956s left) gem_basic (bad-close)
[47.371738] Starting subtest: bad-close
[47.411203] Subtest bad-close: SUCCESS (0.000s)
[47.524841] [012/161] (955s left) gem_basic (create-close)
[47.683170] Starting subtest: create-close
[47.722401] Subtest create-close: SUCCESS (0.000s)
[47.839538] [013/161] (955s left) gem_basic (create-fd-close)
[47.998187] Starting subtest: create-fd-close
[48.041942] Subtest create-fd-close: SUCCESS (0.000s)
[48.155689] [014/161] (955s left) gem_busy (busy@all-engines)
[48.340351] Starting subtest: busy
[48.362103] Starting dynamic subtest: all-engines
[48.381908] Dynamic subtest all-engines: SUCCESS (0.036s)
[48.401783] Subtest busy: SUCCESS (0.036s)
[48.761586] [015/161] (955s left) gem_close_race (basic-process)
[48.944291] Starting subtest: basic-process
[49.005562] Subtest basic-process: SUCCESS (0.062s)
[49.237569] [016/161] (954s left) gem_close_race (basic-threads)
[49.431987] Starting subtest: basic-threads
[50.622846] Subtest basic-threads: SUCCESS (1.230s)
[50.868761] [017/161] (953s left) gem_ctx_create (basic)
[51.054620] Starting subtest: basic
[51.076809] Subtest basic: SUCCESS (0.000s)
[51.278042] [018/161] (952s left) gem_ctx_create (basic-files)
[51.440515] Starting subtest: basic-files
[53.459324] Subtest basic-files: SUCCESS (2.026s)
[53.732452] [019/161] (950s left) gem_ctx_exec (basic)
[53.950268] Starting subtest: basic
[53.970736] Subtest basic: SUCCESS (0.001s)
[54.119322] [020/161] (950s left) gem_exec_basic (basic)
[54.305343] Starting subtest: basic
[54.328246] Starting dynamic subtest: rcs0-smem
[54.352449] Dynamic subtest rcs0-smem: SUCCESS (0.001s)
[54.388134] Starting dynamic subtest: vcs0-smem
[54.412339] Dynamic subtest vcs0-smem: SUCCESS (0.000s)
[54.437069] Subtest basic: SUCCESS (0.001s)
[54.558701] [021/161] (949s left) gem_exec_create (basic)
[54.781637] Starting subtest: basic
[54.802977] Starting dynamic subtest: smem
[56.808513] Dynamic subtest smem: SUCCESS (2.053s)
[56.832085] Subtest basic: SUCCESS (2.053s)
[57.037363] [022/161] (947s left) gem_exec_fence (basic-busy)
[57.322406] Starting subtest: basic-busy
[57.346766] Starting dynamic subtest: rcs0
[57.391790] Dynamic subtest rcs0: SUCCESS (0.027s)
[57.433391] Starting dynamic subtest: vcs0
[57.480848] Dynamic subtest vcs0: SUCCESS (0.021s)
[57.505795] Subtest basic-busy: SUCCESS (0.050s)
[57.949906] [FACT gem_exec_fence (basic-busy)] new: kernel.kmod_is_loaded.vgem: true
[57.972562] [023/161] (946s left) gem_exec_fence (basic-wait)
[58.278544] Starting subtest: basic-wait
[58.301061] Starting dynamic subtest: rcs0
[58.324593] Dynamic subtest rcs0: SUCCESS (0.026s)
[58.368209] Starting dynamic subtest: vcs0
[58.390696] Dynamic subtest vcs0: SUCCESS (0.021s)
[58.412399] Subtest basic-wait: SUCCESS (0.047s)
[58.860789] [024/161] (945s left) gem_exec_fence (basic-await)
[59.156099] Starting subtest: basic-await
[59.177689] Starting dynamic subtest: rcs0
[59.230970] Dynamic subtest rcs0: SUCCESS (0.080s)
[59.260952] Starting dynamic subtest: vcs0
[59.312427] Dynamic subtest vcs0: SUCCESS (0.083s)
[59.332062] Subtest basic-await: SUCCESS (0.164s)
[59.856306] [025/161] (945s left) gem_exec_fence (nb-await)
[60.149621] Starting subtest: nb-await
[60.169677] Starting dynamic subtest: rcs0
[60.211204] Dynamic subtest rcs0: SUCCESS (0.055s)
[60.246176] Starting dynamic subtest: vcs0
[60.267893] Dynamic subtest vcs0: SUCCESS (0.053s)
[60.289667] Subtest nb-await: SUCCESS (0.109s)
[60.789898] [026/161] (944s left) gem_exec_gttfill (basic)
[60.995322] Starting subtest: basic
[70.747654] Subtest basic: SUCCESS (9.794s)
[71.007282] [027/161] (934s left) gem_exec_parallel (engines)
[71.275814] Starting subtest: engines
[71.295985] Starting dynamic subtest: basic
[72.929889] Dynamic subtest basic: SUCCESS (1.660s)
[72.958899] Starting dynamic subtest: contexts
[75.693923] Dynamic subtest contexts: SUCCESS (2.639s)
[75.724187] Starting dynamic subtest: fds
[75.764133] Dynamic subtest fds: SKIP (0.001s)
[75.792302] Starting dynamic subtest: userptr
[78.538520] Dynamic subtest userptr: SUCCESS (2.780s)
[78.559428] Subtest engines: SUCCESS (7.080s)
[78.684842] [028/161] (926s left) gem_exec_store (basic)
[78.929726] Starting subtest: basic
[78.952189] Subtest basic: SUCCESS (0.057s)
[79.157549] [029/161] (926s left) gem_flink_basic (bad-flink)
[79.338417] Starting subtest: bad-flink
[79.357986] Subtest bad-flink: SUCCESS (0.000s)
[79.478360] [030/161] (926s left) gem_flink_basic (bad-open)
[79.661456] Starting subtest: bad-open
[79.683622] Subtest bad-open: SUCCESS (0.000s)
[79.805984] [031/161] (925s left) gem_flink_basic (basic)
[79.992897] Starting subtest: basic
[80.014073] Subtest basic: SUCCESS (0.000s)
[80.131904] [032/161] (925s left) gem_flink_basic (double-flink)
[80.315627] Starting subtest: double-flink
[80.335022] Subtest double-flink: SUCCESS (0.000s)
[80.452936] [033/161] (925s left) gem_flink_basic (flink-lifetime)
[80.637229] Starting subtest: flink-lifetime
[80.656092] Subtest flink-lifetime: SUCCESS (0.001s)
[80.782095] [034/161] (925s left) gem_huc_copy (huc-copy)
[80.981180] Subtest huc-copy: SKIP (0.000s)
[81.102930] [035/161] (925s left) gem_linear_blits (basic)
[81.333942] Starting subtest: basic
[81.353992] Subtest basic: SUCCESS (0.019s)
[81.570620] [036/161] (924s left) gem_mmap (basic)
[81.839179] Starting subtest: basic
[81.944066] Subtest basic: SUCCESS (0.000s)
[82.063064] [037/161] (924s left) gem_mmap_gtt (basic)
[82.241167] Starting subtest: basic
[82.261533] Subtest basic: SUCCESS (0.001s)
[82.409799] [038/161] (924s left) gem_render_linear_blits (basic)
[82.581770] Starting subtest: basic
[82.686990] Subtest basic: SUCCESS (0.034s)
[82.807524] [039/161] (923s left) gem_render_tiled_blits (basic)
[82.993206] Starting subtest: basic
[83.118708] Subtest basic: SUCCESS (0.040s)
[83.235158] [040/161] (923s left) gem_ringfill (basic-all)
[83.505762] Starting subtest: basic-all
[84.590684] Subtest basic-all: SUCCESS (1.115s)
[84.735193] [041/161] (922s left) gem_softpin (allocator-basic)
[84.949267] Subtest allocator-basic: SKIP (0.000s)
[85.092549] [042/161] (921s left) gem_softpin (allocator-basic-reserve)
[85.272198] Subtest allocator-basic-reserve: SKIP (0.000s)
[85.410948] [043/161] (921s left) gem_softpin (safe-alignment)
[85.630221] Starting subtest: safe-alignment
[85.649939] Subtest safe-alignment: SUCCESS (0.007s)
[85.780502] [044/161] (921s left) gem_sync (basic-all)
[85.999587] Starting subtest: basic-all
[88.313539] Subtest basic-all: SUCCESS (2.165s)
[88.457129] [045/161] (918s left) gem_sync (basic-each)
[88.672438] Starting subtest: basic-each
[90.691702] Subtest basic-each: SUCCESS (2.060s)
[90.927498] [046/161] (916s left) gem_tiled_blits (basic)
[91.163418] Starting subtest: basic
[91.207274] Subtest basic: SUCCESS (0.037s)
[91.403113] [047/161] (916s left) gem_tiled_fence_blits (basic)
[91.728947] Starting subtest: basic
[91.750050] Subtest basic: SUCCESS (0.016s)
[91.933288] [048/161] (915s left) gem_tiled_pread_basic
[92.465741] [049/161] (915s left) gem_wait (busy@all-engines)
[92.655406] Starting subtest: busy
[92.674227] Starting dynamic subtest: all-engines
[93.173297] Dynamic subtest all-engines: SUCCESS (0.513s)
[93.200589] Subtest busy: SUCCESS (0.513s)
[93.438570] [050/161] (914s left) gem_wait (wait@all-engines)
[93.634113] Starting subtest: wait
[93.655658] Starting dynamic subtest: all-engines
[94.640721] Dynamic subtest all-engines: SUCCESS (1.024s)
[94.661158] Subtest wait: SUCCESS (1.024s)
[94.926152] [051/161] (913s left) i915_getparams_basic (basic-eu-total)
[95.101901] Starting subtest: basic-eu-total
[95.141060] Subtest basic-eu-total: SUCCESS (0.000s)
[95.276086] [052/161] (912s left) i915_getparams_basic (basic-subslice-total)
[95.445026] Starting subtest: basic-subslice-total
[95.492997] Subtest basic-subslice-total: SUCCESS (0.000s)
[95.628897] [053/161] (912s left) i915_hangman (error-state-basic)
[95.811934] Starting subtest: error-state-basic
[95.836196] Subtest error-state-basic: SUCCESS (0.007s)
[96.008721] [054/161] (912s left) i915_pciid
[96.422618] [055/161] (912s left) kms_addfb_basic (addfb25-4-tiled)
[96.720588] Starting subtest: addfb25-4-tiled
[96.746831] Subtest addfb25-4-tiled: SUCCESS (0.000s)
[96.884580] [056/161] (911s left) kms_addfb_basic (addfb25-bad-modifier)
[97.083913] Starting subtest: addfb25-bad-modifier
[97.108318] Subtest addfb25-bad-modifier: SUCCESS (0.000s)
[97.244278] [057/161] (911s left) kms_addfb_basic (addfb25-framebuffer-vs-set-tiling)
[97.442784] Starting subtest: addfb25-framebuffer-vs-set-tiling
[97.465969] Subtest addfb25-framebuffer-vs-set-tiling: SUCCESS (0.000s)
[97.614503] [058/161] (911s left) kms_addfb_basic (addfb25-modifier-no-flag)
[97.809192] Starting subtest: addfb25-modifier-no-flag
[97.834620] Subtest addfb25-modifier-no-flag: SUCCESS (0.000s)
[97.972530] [059/161] (911s left) kms_addfb_basic (addfb25-x-tiled-legacy)
[98.167122] Starting subtest: addfb25-x-tiled-legacy
[98.187641] Subtest addfb25-x-tiled-legacy: SUCCESS (0.000s)
[98.305528] [060/161] (910s left) kms_addfb_basic (addfb25-x-tiled-mismatch-legacy)
[98.487194] Starting subtest: addfb25-x-tiled-mismatch-legacy
[98.506516] Subtest addfb25-x-tiled-mismatch-legacy: SUCCESS (0.000s)
[98.631909] [061/161] (910s left) kms_addfb_basic (addfb25-yf-tiled-legacy)
[98.828836] Starting subtest: addfb25-yf-tiled-legacy
[98.850288] Subtest addfb25-yf-tiled-legacy: SUCCESS (0.000s)
[98.996623] [062/161] (910s left) kms_addfb_basic (addfb25-y-tiled-legacy)
[99.171920] Starting subtest: addfb25-y-tiled-legacy
[99.193865] Subtest addfb25-y-tiled-legacy: SUCCESS (0.000s)
[99.316218] [063/161] (910s left) kms_addfb_basic (addfb25-y-tiled-small-legacy)
[99.516615] Starting subtest: addfb25-y-tiled-small-legacy
[99.556614] Subtest addfb25-y-tiled-small-legacy: SKIP (0.000s)
[99.681523] [064/161] (909s left) kms_addfb_basic (bad-pitch-0)
[99.854630] Starting subtest: bad-pitch-0
[99.876294] Subtest bad-pitch-0: SUCCESS (0.000s)
[100.006198] [065/161] (909s left) kms_addfb_basic (bad-pitch-1024)
[100.182660] Starting subtest: bad-pitch-1024
[100.202942] Subtest bad-pitch-1024: SUCCESS (0.000s)
[100.330553] [066/161] (909s left) kms_addfb_basic (bad-pitch-128)
[100.528196] Starting subtest: bad-pitch-128
[100.548136] Subtest bad-pitch-128: SUCCESS (0.000s)
[100.673532] [067/161] (909s left) kms_addfb_basic (bad-pitch-256)
[100.847523] Starting subtest: bad-pitch-256
[100.866693] Subtest bad-pitch-256: SUCCESS (0.000s)
[100.999509] [068/161] (909s left) kms_addfb_basic (bad-pitch-32)
[101.177836] Starting subtest: bad-pitch-32
[101.199709] Subtest bad-pitch-32: SUCCESS (0.000s)
[101.321736] [069/161] (908s left) kms_addfb_basic (bad-pitch-63)
[101.502874] Starting subtest: bad-pitch-63
[101.522538] Subtest bad-pitch-63: SUCCESS (0.000s)
[101.644601] [070/161] (908s left) kms_addfb_basic (bad-pitch-65536)
[101.823430] Starting subtest: bad-pitch-65536
[101.842400] Subtest bad-pitch-65536: SUCCESS (0.000s)
[101.987303] [071/161] (908s left) kms_addfb_basic (bad-pitch-999)
[102.187090] Starting subtest: bad-pitch-999
[102.207854] Subtest bad-pitch-999: SUCCESS (0.000s)
[102.330160] [072/161] (908s left) kms_addfb_basic (basic)
[102.540147] Starting subtest: basic
[102.560144] Subtest basic: SUCCESS (0.000s)
[102.681340] [073/161] (908s left) kms_addfb_basic (basic-x-tiled-legacy)
[102.878115] Starting subtest: basic-x-tiled-legacy
[102.899796] Subtest basic-x-tiled-legacy: SUCCESS (0.000s)
[103.025419] [074/161] (907s left) kms_addfb_basic (basic-y-tiled-legacy)
[103.201256] Starting subtest: basic-y-tiled-legacy
[103.222191] Subtest basic-y-tiled-legacy: SUCCESS (0.000s)
[103.342970] [075/161] (907s left) kms_addfb_basic (bo-too-small)
[103.547214] Starting subtest: bo-too-small
[103.566899] Subtest bo-too-small: SUCCESS (0.000s)
[103.692591] [076/161] (907s left) kms_addfb_basic (bo-too-small-due-to-tiling)
[103.868255] Starting subtest: bo-too-small-due-to-tiling
[103.888521] Subtest bo-too-small-due-to-tiling: SUCCESS (0.000s)
[104.031870] [077/161] (907s left) kms_addfb_basic (clobberred-modifier)
[104.207177] Starting subtest: clobberred-modifier
[104.228551] Subtest clobberred-modifier: SUCCESS (0.000s)
[104.353310] [078/161] (907s left) kms_addfb_basic (framebuffer-vs-set-tiling)
[104.533316] Starting subtest: framebuffer-vs-set-tiling
[104.554146] Subtest framebuffer-vs-set-tiling: SUCCESS (0.000s)
[104.672993] [079/161] (906s left) kms_addfb_basic (invalid-get-prop)
[104.855466] Starting subtest: invalid-get-prop
[104.874967] Subtest invalid-get-prop: SUCCESS (0.000s)
[104.997947] [080/161] (906s left) kms_addfb_basic (invalid-get-prop-any)
[105.174993] Starting subtest: invalid-get-prop-any
[105.197434] Subtest invalid-get-prop-any: SUCCESS (0.000s)
[105.320391] [081/161] (906s left) kms_addfb_basic (invalid-set-prop)
[105.499376] Starting subtest: invalid-set-prop
[105.520838] Subtest invalid-set-prop: SUCCESS (0.000s)
[105.644664] [082/161] (906s left) kms_addfb_basic (invalid-set-prop-any)
[105.822133] Starting subtest: invalid-set-prop-any
[105.841941] Subtest invalid-set-prop-any: SUCCESS (0.000s)
[105.957529] [083/161] (905s left) kms_addfb_basic (no-handle)
[106.136933] Starting subtest: no-handle
[106.155828] Subtest no-handle: SUCCESS (0.000s)
[106.280571] [084/161] (905s left) kms_addfb_basic (size-max)
[106.469206] Starting subtest: size-max
[106.492249] Subtest size-max: SUCCESS (0.000s)
[106.616384] [085/161] (905s left) kms_addfb_basic (small-bo)
[106.793577] Starting subtest: small-bo
[106.814548] Subtest small-bo: SUCCESS (0.000s)
[106.935539] [086/161] (905s left) kms_addfb_basic (tile-pitch-mismatch)
[107.113530] Starting subtest: tile-pitch-mismatch
[107.131827] Subtest tile-pitch-mismatch: SUCCESS (0.000s)
[107.249419] [087/161] (905s left) kms_addfb_basic (too-high)
[107.427876] Starting subtest: too-high
[107.446754] Subtest too-high: SUCCESS (0.001s)
[107.574561] [088/161] (904s left) kms_addfb_basic (too-wide)
[107.745779] Starting subtest: too-wide
[107.767536] Subtest too-wide: SUCCESS (0.000s)
[107.894908] [089/161] (904s left) kms_addfb_basic (unused-handle)
[108.075073] Starting subtest: unused-handle
[108.095898] Subtest unused-handle: SUCCESS (0.000s)
[108.224833] [090/161] (904s left) kms_addfb_basic (unused-modifier)
[108.430369] Starting subtest: unused-modifier
[108.450131] Subtest unused-modifier: SUCCESS (0.000s)
[108.574996] [091/161] (904s left) kms_addfb_basic (unused-offsets)
[108.748325] Starting subtest: unused-offsets
[108.768881] Subtest unused-offsets: SUCCESS (0.000s)
[108.899598] [092/161] (904s left) kms_addfb_basic (unused-pitches)
[109.075912] Starting subtest: unused-pitches
[109.097379] Subtest unused-pitches: SUCCESS (0.000s)
[109.216465] [093/161] (903s left) kms_busy (basic)
[109.414439] Starting subtest: basic
[109.436198] Starting dynamic subtest: flip
[109.980413] Dynamic subtest flip: SUCCESS (0.571s)
[110.012105] Starting dynamic subtest: modeset
[110.619122] Dynamic subtest modeset: SUCCESS (0.624s)
[110.637871] Subtest basic: SUCCESS (1.197s)
[110.850116] [094/161] (902s left) kms_prop_blob (basic)
[111.036826] Starting subtest: basic
[111.058672] Subtest basic: SUCCESS (0.000s)
[111.185327] [095/161] (902s left) kms_cursor_legacy (basic-busy-flip-before-cursor-atomic)
[111.385234] Starting subtest: basic-busy-flip-before-cursor-atomic
[112.309404] Subtest basic-busy-flip-before-cursor-atomic: SUCCESS (0.943s)
[112.540307] [096/161] (900s left) kms_cursor_legacy (basic-busy-flip-before-cursor-legacy)
[112.724096] Starting subtest: basic-busy-flip-before-cursor-legacy
[113.655588] Subtest basic-busy-flip-before-cursor-legacy: SUCCESS (0.946s)
[113.894655] [097/161] (899s left) kms_cursor_legacy (basic-flip-after-cursor-atomic)
[114.098717] Starting subtest: basic-flip-after-cursor-atomic
[115.032275] Subtest basic-flip-after-cursor-atomic: SUCCESS (0.953s)
[115.277122] [098/161] (898s left) kms_cursor_legacy (basic-flip-after-cursor-legacy)
[115.472605] Starting subtest: basic-flip-after-cursor-legacy
[116.394999] Subtest basic-flip-after-cursor-legacy: SUCCESS (0.936s)
[116.634208] [099/161] (897s left) kms_cursor_legacy (basic-flip-after-cursor-varying-size)
[116.833400] Starting subtest: basic-flip-after-cursor-varying-size
[117.755752] Subtest basic-flip-after-cursor-varying-size: SUCCESS (0.941s)
[117.988660] [100/161] (895s left) kms_cursor_legacy (basic-flip-before-cursor-atomic)
[118.190418] Starting subtest: basic-flip-before-cursor-atomic
[119.102085] Subtest basic-flip-before-cursor-atomic: SUCCESS (0.934s)
[119.322081] [101/161] (894s left) kms_cursor_legacy (basic-flip-before-cursor-legacy)
[119.502871] Starting subtest: basic-flip-before-cursor-legacy
[120.428736] Subtest basic-flip-before-cursor-legacy: SUCCESS (0.932s)
[120.651151] [102/161] (893s left) kms_cursor_legacy (basic-flip-before-cursor-varying-size)
[120.808722] Starting subtest: basic-flip-before-cursor-varying-size
[121.741221] Subtest basic-flip-before-cursor-varying-size: SUCCESS (0.941s)
[121.984409] [103/161] (892s left) kms_dsc (dsc-basic)
[122.189372] Subtest dsc-basic: SKIP (0.000s)
[122.320288] [104/161] (892s left) kms_flip (basic-flip-vs-dpms)
[122.528977] Starting subtest: basic-flip-vs-dpms
[122.551119] Starting dynamic subtest: A-VGA1
[123.817304] Dynamic subtest A-VGA1: SUCCESS (1.295s)
[123.859371] Starting dynamic subtest: B-VGA1
[125.058749] Dynamic subtest B-VGA1: SUCCESS (1.253s)
[125.078909] Subtest basic-flip-vs-dpms: SUCCESS (2.552s)
[125.293352] [105/161] (889s left) kms_flip (basic-flip-vs-modeset)
[125.515121] Starting subtest: basic-flip-vs-modeset
[125.535277] Starting dynamic subtest: A-VGA1
[126.730798] Dynamic subtest A-VGA1: SUCCESS (1.247s)
[126.774605] Starting dynamic subtest: B-VGA1
[127.934517] Dynamic subtest B-VGA1: SUCCESS (1.188s)
[127.954366] Subtest basic-flip-vs-modeset: SUCCESS (2.439s)
[128.142566] [106/161] (886s left) kms_flip (basic-flip-vs-wf_vblank)
[128.354276] Starting subtest: basic-flip-vs-wf_vblank
[128.375319] Starting dynamic subtest: A-VGA1
[129.854235] Dynamic subtest A-VGA1: SUCCESS (1.523s)
[129.887567] Starting dynamic subtest: B-VGA1
[131.357946] Dynamic subtest B-VGA1: SUCCESS (1.503s)
[131.379892] Subtest basic-flip-vs-wf_vblank: SUCCESS (3.031s)
[131.624522] [107/161] (883s left) kms_flip (basic-plain-flip)
[131.839894] Starting subtest: basic-plain-flip
[131.861169] Starting dynamic subtest: A-VGA1
[133.038392] Dynamic subtest A-VGA1: SUCCESS (1.237s)
[133.075370] Starting dynamic subtest: B-VGA1
[134.256495] Dynamic subtest B-VGA1: SUCCESS (1.217s)
[134.278369] Subtest basic-plain-flip: SUCCESS (2.459s)
[134.538724] [108/161] (880s left) kms_force_connector_basic (force-connector-state)
[134.743243] Starting subtest: force-connector-state
[134.860698] Subtest force-connector-state: SUCCESS (0.154s)
[135.148937] [109/161] (879s left) kms_force_connector_basic (force-edid)
[135.352172] Starting subtest: force-edid
[135.370663] Subtest force-edid: SUCCESS (0.036s)
[135.524721] [110/161] (879s left) kms_force_connector_basic (force-load-detect)
[135.725735] Starting subtest: force-load-detect
[135.864545] Subtest force-load-detect: SUCCESS (0.181s)
[136.120645] [111/161] (879s left) kms_force_connector_basic (prune-stale-modes)
[136.315671] Starting subtest: prune-stale-modes
[136.337351] Subtest prune-stale-modes: SUCCESS (0.019s)
[136.493712] [112/161] (878s left) kms_frontbuffer_tracking (basic)
[136.759509] Starting subtest: basic
[138.975539] Subtest basic: SUCCESS (2.273s)
[139.143540] [113/161] (876s left) kms_hdmi_inject (inject-audio)
[139.409564] Starting subtest: inject-audio
[139.578068] Subtest inject-audio: SUCCESS (0.181s)
[139.847117] [114/161] (875s left) kms_pipe_crc_basic (compare-crc-sanitycheck-xr24)
[140.062691] Starting subtest: compare-crc-sanitycheck-xr24
[140.086509] Starting dynamic subtest: pipe-A-VGA-1
[140.385987] Dynamic subtest pipe-A-VGA-1: SUCCESS (0.331s)
[140.424194] Starting dynamic subtest: pipe-B-VGA-1
[140.724145] Dynamic subtest pipe-B-VGA-1: SUCCESS (0.327s)
[140.750103] Subtest compare-crc-sanitycheck-xr24: SUCCESS (0.663s)
[140.938626] [115/161] (874s left) kms_pipe_crc_basic (compare-crc-sanitycheck-nv12)
[141.121775] Starting subtest: compare-crc-sanitycheck-nv12
[141.140904] Starting dynamic subtest: pipe-A-VGA-1
[141.182847] Dynamic subtest pipe-A-VGA-1: SKIP (0.000s)
[141.221500] Starting dynamic subtest: pipe-B-VGA-1
[141.265472] Dynamic subtest pipe-B-VGA-1: SKIP (0.000s)
[141.306843] Subtest compare-crc-sanitycheck-nv12: SKIP (0.004s)
[141.427637] [116/161] (874s left) kms_pipe_crc_basic (hang-read-crc)
[141.610177] Starting subtest: hang-read-crc
[141.630099] Starting dynamic subtest: pipe-A-VGA-1
[142.485184] Dynamic subtest pipe-A-VGA-1: SUCCESS (0.878s)
[142.514637] Starting dynamic subtest: pipe-B-VGA-1
[143.383107] Dynamic subtest pipe-B-VGA-1: SUCCESS (0.898s)
[143.404614] Subtest hang-read-crc: SUCCESS (1.781s)
[143.584480] [117/161] (872s left) kms_pipe_crc_basic (nonblocking-crc)
[143.786116] Starting subtest: nonblocking-crc
[143.806548] Starting dynamic subtest: pipe-A-VGA-1
[144.288171] Dynamic subtest pipe-A-VGA-1: SUCCESS (0.507s)
[144.319119] Starting dynamic subtest: pipe-B-VGA-1
[144.772764] Dynamic subtest pipe-B-VGA-1: SUCCESS (0.496s)
[144.791922] Subtest nonblocking-crc: SUCCESS (1.007s)
[144.973193] [118/161] (871s left) kms_pipe_crc_basic (nonblocking-crc-frame-sequence)
[145.148784] Starting subtest: nonblocking-crc-frame-sequence
[145.170380] Starting dynamic subtest: pipe-A-VGA-1
[145.691607] Dynamic subtest pipe-A-VGA-1: SUCCESS (0.530s)
[145.723165] Starting dynamic subtest: pipe-B-VGA-1
[146.207065] Dynamic subtest pipe-B-VGA-1: SUCCESS (0.530s)
[146.226499] Subtest nonblocking-crc-frame-sequence: SUCCESS (1.064s)
[146.410446] [119/161] (869s left) kms_pipe_crc_basic (read-crc)
[146.602478] Starting subtest: read-crc
[146.624813] Starting dynamic subtest: pipe-A-VGA-1
[147.025461] Dynamic subtest pipe-A-VGA-1: SUCCESS (0.424s)
[147.057516] Starting dynamic subtest: pipe-B-VGA-1
[147.468631] Dynamic subtest pipe-B-VGA-1: SUCCESS (0.448s)
[147.490406] Subtest read-crc: SUCCESS (0.877s)
[147.663702] [120/161] (868s left) kms_pipe_crc_basic (read-crc-frame-sequence)
[147.867091] Starting subtest: read-crc-frame-sequence
[147.886593] Starting dynamic subtest: pipe-A-VGA-1
[148.297102] Dynamic subtest pipe-A-VGA-1: SUCCESS (0.442s)
[148.331276] Starting dynamic subtest: pipe-B-VGA-1
[148.723118] Dynamic subtest pipe-B-VGA-1: SUCCESS (0.429s)
[148.742479] Subtest read-crc-frame-sequence: SUCCESS (0.874s)
[148.914430] [121/161] (867s left) kms_pm_backlight (basic-brightness)
[149.119735] Subtest basic-brightness: SKIP (0.000s)
[149.246668] [122/161] (867s left) kms_pm_rpm (basic-pci-d3-state)
[150.536090] Subtest basic-pci-d3-state: SKIP (0.000s)
[150.675625] [123/161] (866s left) kms_pm_rpm (basic-rte)
[150.849529] Starting subtest: basic-rte
[151.950110] Subtest basic-rte: SKIP (1.075s)
[152.121492] [124/161] (864s left) kms_psr (psr-primary-page-flip)
[152.331270] Subtest psr-primary-page-flip: SKIP (0.000s)
[152.454724] [125/161] (864s left) kms_psr (psr-cursor-plane-move)
[152.658505] Subtest psr-cursor-plane-move: SKIP (0.000s)
[152.782799] [126/161] (864s left) kms_psr (psr-sprite-plane-onoff)
[153.009335] Subtest psr-sprite-plane-onoff: SKIP (0.000s)
[153.135968] [127/161] (864s left) kms_psr (psr-primary-mmap-gtt)
[153.362371] Subtest psr-primary-mmap-gtt: SKIP (0.000s)
[153.492117] [128/161] (863s left) kms_setmode (basic-clone-single-crtc)
[153.698205] Starting subtest: basic-clone-single-crtc
[153.757531] Subtest basic-clone-single-crtc: SKIP (0.000s)
[153.889172] [129/161] (863s left) i915_pm_rps (basic-api)
[154.113762] Subtest basic-api: SKIP (0.000s)
[154.240263] [130/161] (863s left) prime_self_import (basic-llseek-bad)
[154.430083] Starting subtest: basic-llseek-bad
[154.470567] Subtest basic-llseek-bad: SUCCESS (0.002s)
[154.595520] [131/161] (863s left) prime_self_import (basic-llseek-size)
[154.771990] Starting subtest: basic-llseek-size
[154.813888] Subtest basic-llseek-size: SUCCESS (0.005s)
[154.945333] [132/161] (862s left) prime_self_import (basic-with_fd_dup)
[155.104108] Starting subtest: basic-with_fd_dup
[155.146742] Subtest basic-with_fd_dup: SUCCESS (0.003s)
[155.274119] [133/161] (862s left) prime_self_import (basic-with_one_bo)
[155.433515] Starting subtest: basic-with_one_bo
[155.474838] Subtest basic-with_one_bo: SUCCESS (0.003s)
[155.599703] [134/161] (862s left) prime_self_import (basic-with_one_bo_two_files)
[155.779584] Starting subtest: basic-with_one_bo_two_files
[155.819111] Subtest basic-with_one_bo_two_files: SUCCESS (0.003s)
[155.946263] [135/161] (862s left) prime_self_import (basic-with_two_bos)
[156.080911] Starting subtest: basic-with_two_bos
[156.124423] Subtest basic-with_two_bos: SUCCESS (0.003s)
[156.257258] [136/161] (862s left) prime_vgem (basic-fence-flip)
[156.459688] Starting subtest: basic-fence-flip
[156.783153] Subtest basic-fence-flip: SUCCESS (0.360s)
[156.948864] [137/161] (861s left) prime_vgem (basic-fence-mmap)
[157.149238] Starting subtest: basic-fence-mmap
[157.170279] Subtest basic-fence-mmap: SUCCESS (0.052s)
[157.313671] [138/161] (861s left) prime_vgem (basic-fence-read)
[157.510658] Starting subtest: basic-fence-read
[157.550741] Subtest basic-fence-read: SUCCESS (0.063s)
[157.700063] [139/161] (861s left) prime_vgem (basic-gtt)
[157.922958] Starting subtest: basic-gtt
[157.943380] Subtest basic-gtt: SUCCESS (0.025s)
[158.083738] [140/161] (860s left) prime_vgem (basic-read)
[158.327451] Starting subtest: basic-read
[158.351506] Subtest basic-read: SUCCESS (0.029s)
[158.510936] [141/161] (860s left) prime_vgem (basic-write)
[158.758783] Starting subtest: basic-write
[158.782054] Subtest basic-write: SUCCESS (0.030s)
[158.944881] [142/161] (860s left) vgem_basic (setversion)
[159.180154] Starting subtest: setversion
[159.227644] Subtest setversion: SUCCESS (0.000s)
[159.365821] [143/161] (859s left) vgem_basic (create)
[159.583591] Starting subtest: create
[159.607832] Subtest create: SUCCESS (0.001s)
[159.757965] [144/161] (859s left) vgem_basic (debugfs)
[159.948514] Starting subtest: debugfs
[159.972970] Subtest debugfs: SUCCESS (0.001s)
[160.113406] [145/161] (859s left) vgem_basic (dmabuf-export)
[160.317605] Starting subtest: dmabuf-export
[160.367544] Subtest dmabuf-export: SUCCESS (0.002s)
[160.492248] [146/161] (859s left) vgem_basic (dmabuf-fence)
[160.669313] Starting subtest: dmabuf-fence
[160.690095] Subtest dmabuf-fence: SUCCESS (0.001s)
[160.823157] [147/161] (859s left) vgem_basic (dmabuf-fence-before)
[161.023522] Starting subtest: dmabuf-fence-before
[161.045806] Subtest dmabuf-fence-before: SUCCESS (0.001s)
[161.167232] [148/161] (858s left) vgem_basic (dmabuf-mmap)
[161.347562] Starting subtest: dmabuf-mmap
[161.366977] Subtest dmabuf-mmap: SUCCESS (0.026s)
[161.488621] [149/161] (858s left) vgem_basic (mmap)
[161.685805] Starting subtest: mmap
[161.707324] Subtest mmap: SUCCESS (0.019s)
[161.833524] [150/161] (858s left) vgem_basic (second-client)
[162.017765] Starting subtest: second-client
[162.061708] Subtest second-client: SUCCESS (0.033s)
[162.184225] [151/161] (858s left) vgem_basic (sysfs)
[162.388316] Starting subtest: sysfs
[162.408444] Subtest sysfs: SUCCESS (0.002s)
[162.535350] [152/161] (857s left) core_hotunplug (unbind-rebind)
[162.809944] Starting subtest: unbind-rebind
[166.170646] Subtest unbind-rebind: SUCCESS (3.357s)
[166.301313] [153/161] (854s left) vgem_basic (unload)
[166.501793] Starting subtest: unload
[195.717703] Subtest unload: SUCCESS (29.050s)
[195.844336] [154/161] (824s left) i915_module_load (reload)
[196.006139] Starting subtest: reload
[198.859048] Subtest reload: SUCCESS (2.854s)
[199.044714] [155/161] (821s left) gem_lmem_swapping (basic)
[199.279726] Subtest basic: SKIP (0.000s)
[199.405189] [156/161] (821s left) gem_lmem_swapping (parallel-random-engines)
[199.610345] Subtest parallel-random-engines: SKIP (0.000s)
[199.748457] [157/161] (821s left) gem_lmem_swapping (random-engines)
[199.954237] Subtest random-engines: SKIP (0.000s)
[200.076247] [158/161] (821s left) gem_lmem_swapping (verify-random)
[200.255225] Subtest verify-random: SKIP (0.000s)
[200.378982] [159/161] (821s left) i915_pm_rpm (module-reload)
[201.665185] Subtest module-reload: SKIP (0.000s)
[201.784775] [160/161] (819s left) i915_selftest (live)
[202.954347] Starting subtest: live
[202.973770] Starting dynamic subtest: sanitycheck
[204.737044] Dynamic subtest sanitycheck: SUCCESS (1.781s)
[204.769850] Starting dynamic subtest: uncore
[206.460084] Dynamic subtest uncore: SUCCESS (1.724s)
[206.488775] Starting dynamic subtest: workarounds
[208.275656] Dynamic subtest workarounds: SUCCESS (1.810s)
[208.322208] Starting dynamic subtest: gt_engines
[210.025780] Dynamic subtest gt_engines: SUCCESS (1.746s)
[210.056772] Starting dynamic subtest: gt_timelines
[214.742044] Dynamic subtest gt_timelines: SUCCESS (4.716s)
[214.770694] Starting dynamic subtest: gt_contexts
[216.543421] Dynamic subtest gt_contexts: SUCCESS (1.801s)
[216.573118] Starting dynamic subtest: gt_lrc
[218.290957] Dynamic subtest gt_lrc: SUCCESS (1.743s)
[218.321418] Starting dynamic subtest: gt_mocs
[219.948240] Dynamic subtest gt_mocs: SUCCESS (1.658s)
[219.976626] Starting dynamic subtest: gt_pm
[222.135977] Dynamic subtest gt_pm: SUCCESS (2.186s)
[222.164562] Starting dynamic subtest: gt_heartbeat
[223.923614] Dynamic subtest gt_heartbeat: SUCCESS (1.782s)
[223.954266] Starting dynamic subtest: gt_tlb
[225.724429] Dynamic subtest gt_tlb: SUCCESS (1.800s)
[225.754755] Starting dynamic subtest: requests
[231.082402] Dynamic subtest requests: SUCCESS (5.357s)
[231.114269] Starting dynamic subtest: migrate
[232.806519] Dynamic subtest migrate: SUCCESS (1.716s)
[232.849736] Starting dynamic subtest: active
[234.593174] Dynamic subtest active: SUCCESS (1.783s)
[234.626940] Starting dynamic subtest: objects
[237.672657] Dynamic subtest objects: SUCCESS (3.076s)
[237.706210] Starting dynamic subtest: mman
[244.578589] Dynamic subtest mman: SUCCESS (6.906s)
[244.610165] Starting dynamic subtest: dmabuf
[246.313944] Dynamic subtest dmabuf: SUCCESS (1.734s)
[246.345104] Starting dynamic subtest: vma
[248.108529] Dynamic subtest vma: SUCCESS (1.792s)
[248.156654] Starting dynamic subtest: coherency
[250.956755] Dynamic subtest coherency: SUCCESS (2.842s)
[250.987257] Starting dynamic subtest: gtt
[255.860114] Dynamic subtest gtt: SUCCESS (4.901s)
[255.888487] Starting dynamic subtest: gem
[257.829688] Dynamic subtest gem: SUCCESS (1.967s)
[257.863357] Starting dynamic subtest: evict
[259.503321] Dynamic subtest evict: SUCCESS (1.668s)
[259.536842] Starting dynamic subtest: hugepages
[261.275847] Dynamic subtest hugepages: SUCCESS (1.769s)
[261.306253] Starting dynamic subtest: gem_contexts
[266.004507] Dynamic subtest gem_contexts: SUCCESS (4.726s)
[266.036182] Starting dynamic subtest: client
[267.739317] Dynamic subtest client: SUCCESS (1.730s)
[267.783676] Starting dynamic subtest: gem_migrate
[269.332039] Dynamic subtest gem_migrate: SUCCESS (1.592s)
[269.360549] Starting dynamic subtest: reset
[271.063079] Dynamic subtest reset: SUCCESS (1.726s)
[271.091881] Starting dynamic subtest: memory_region
[272.767783] Dynamic subtest memory_region: SUCCESS (1.698s)
[272.799814] Starting dynamic subtest: hangcheck
[276.113873] Dynamic subtest hangcheck: SUCCESS (3.344s)
[276.158190] Starting dynamic subtest: execlists
[277.859129] Dynamic subtest execlists: SUCCESS (1.745s)
[277.887833] Starting dynamic subtest: ring_submission
[279.587419] Dynamic subtest ring_submission: SUCCESS (1.723s)
[279.616377] Starting dynamic subtest: perf
[281.393355] Dynamic subtest perf: SUCCESS (1.805s)
[281.431831] Starting dynamic subtest: slpc
[283.062695] Dynamic subtest slpc: SUCCESS (1.666s)
[283.092801] Starting dynamic subtest: guc
[284.811035] Dynamic subtest guc: SUCCESS (1.740s)
[284.843386] Starting dynamic subtest: guc_multi_lrc
[286.565348] Dynamic subtest guc_multi_lrc: SUCCESS (1.754s)
[286.594256] Starting dynamic subtest: guc_hang
[288.155344] Dynamic subtest guc_hang: SUCCESS (1.586s)
[288.183942] Starting dynamic subtest: late_gt_pm
[289.974077] Dynamic subtest late_gt_pm: SUCCESS (1.813s)
[289.995771] Subtest live: SUCCESS (87.013s)
[290.084339] [FACT i915_selftest (live)] deleted: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[290.086259] [FACT i915_selftest (live)] deleted: kernel.kmod_is_loaded.i915: true
[290.109130] [161/161] (731s left) dmabuf (all-tests)
[290.282556] Starting subtest: all-tests
[290.303510] Starting dynamic subtest: sanitycheck
[290.388988] Dynamic subtest sanitycheck: SUCCESS (0.106s)
[290.417478] Starting dynamic subtest: dma_fence
[290.613171] Dynamic subtest dma_fence: SUCCESS (0.217s)
[290.644790] Starting dynamic subtest: dma_fence_chain
[303.238368] Dynamic subtest dma_fence_chain: SUCCESS (12.623s)
[303.268277] Starting dynamic subtest: dma_fence_unwrap
[303.331307] Dynamic subtest dma_fence_unwrap: SUCCESS (0.087s)
[303.371762] Starting dynamic subtest: dma_resv
[303.428552] Dynamic subtest dma_resv: SUCCESS (0.094s)
[303.448303] Subtest all-tests: SUCCESS (13.146s)
[303.534299] Closing watchdogs
Done.