[85.859719] Initializing watchdogs
[85.860055]   /dev/watchdog0
[85.882895] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:0126 Intel Sandybridge (Gen6) 2nd Generation Core Processor Family Integrated Graphics Controller
[85.958451] [001/161] (960s left) i915_module_load (load)
[88.368168] Starting subtest: load
[92.534386] Subtest load: SUCCESS (4.174s)
[92.774632] [FACT i915_module_load (load)] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[92.778395] [FACT i915_module_load (load)] new: kernel.kmod_is_loaded.i915: true
[92.795628] [002/161] (954s left) core_auth (basic-auth)
[93.422248] Starting subtest: basic-auth
[93.464088] Subtest basic-auth: SUCCESS (0.001s)
[93.615990] [003/161] (953s left) debugfs_test (read_all_entries)
[94.049358] Starting subtest: read_all_entries
[94.083269] Subtest read_all_entries: SUCCESS (0.065s)
[94.620875] [004/161] (952s left) debugfs_test (basic-hwmon)
[95.081212] Starting subtest: basic-hwmon
[95.148030] Subtest basic-hwmon: SKIP (0.000s)
[95.618702] [005/161] (951s left) debugfs_test (sysfs)
[96.069258] Starting subtest: sysfs
[96.111086] Subtest sysfs: SUCCESS (0.089s)
[96.675051] [006/161] (950s left) fbdev (eof)
[97.101092] Starting subtest: eof
[97.142868] Subtest eof: SUCCESS (0.001s)
[97.302772] [007/161] (950s left) fbdev (info)
[97.636296] Starting subtest: info
[97.678099] Subtest info: SUCCESS (0.000s)
[97.837602] [008/161] (949s left) fbdev (nullptr)
[98.129634] Starting subtest: nullptr
[98.163075] Subtest nullptr: SUCCESS (0.000s)
[98.314258] [009/161] (949s left) fbdev (read)
[98.681212] Starting subtest: read
[99.165196] Subtest read: SUCCESS (0.503s)
[99.318296] [010/161] (948s left) fbdev (write)
[99.693313] Starting subtest: write
[99.777504] Subtest write: SUCCESS (0.114s)
[99.920125] [011/161] (948s left) gem_basic (bad-close)
[100.345844] Starting subtest: bad-close
[100.412741] Subtest bad-close: SUCCESS (0.000s)
[100.564359] [012/161] (947s left) gem_basic (create-close)
[100.973166] Starting subtest: create-close
[101.040150] Subtest create-close: SUCCESS (0.000s)
[101.183133] [013/161] (947s left) gem_basic (create-fd-close)
[101.541934] Starting subtest: create-fd-close
[101.608749] Subtest create-fd-close: SUCCESS (0.000s)
[101.795636] [014/161] (946s left) gem_busy (busy@all-engines)
[102.204167] Starting subtest: busy
[102.238239] Starting dynamic subtest: all-engines
[102.271300] Dynamic subtest all-engines: SUCCESS (0.045s)
[102.304668] Subtest busy: SUCCESS (0.045s)
[102.556470] [015/161] (946s left) gem_close_race (basic-process)
[102.957330] Starting subtest: basic-process
[102.990599] Subtest basic-process: SUCCESS (0.050s)
[103.150846] [016/161] (945s left) gem_close_race (basic-threads)
[103.560309] Starting subtest: basic-threads
[104.732932] Subtest basic-threads: SUCCESS (1.314s)
[104.976402] [017/161] (943s left) gem_ctx_create (basic)
[105.460587] Starting subtest: basic
[105.502418] Subtest basic: SUCCESS (0.000s)
[105.654380] [018/161] (943s left) gem_ctx_create (basic-files)
[106.195624] Starting subtest: basic-files
[108.026352] Subtest basic-files: SUCCESS (2.022s)
[108.262157] [019/161] (940s left) gem_ctx_exec (basic)
[108.670893] Starting subtest: basic
[108.712705] Subtest basic: SUCCESS (0.001s)
[108.872380] [020/161] (940s left) gem_exec_basic (basic)
[109.289502] Starting subtest: basic
[109.331691] Starting dynamic subtest: rcs0-smem
[109.373552] Dynamic subtest rcs0-smem: SUCCESS (0.001s)
[109.448695] Starting dynamic subtest: bcs0-smem
[109.490512] Dynamic subtest bcs0-smem: SUCCESS (0.000s)
[109.557301] Starting dynamic subtest: vcs0-smem
[109.598970] Dynamic subtest vcs0-smem: SUCCESS (0.000s)
[109.640946] Subtest basic: SUCCESS (0.002s)
[109.787284] [021/161] (939s left) gem_exec_create (basic)
[110.270748] Starting subtest: basic
[110.304251] Starting dynamic subtest: smem
[112.213750] Dynamic subtest smem: SUCCESS (2.049s)
[112.247188] Subtest basic: SUCCESS (2.049s)
[112.440219] [022/161] (937s left) gem_exec_fence (basic-busy)
[112.966603] Subtest basic-busy: SKIP (0.000s)
[113.478850] [FACT gem_exec_fence (basic-busy)] new: kernel.kmod_is_loaded.vgem: true
[113.494194] [023/161] (936s left) gem_exec_fence (basic-wait)
[114.013883] Subtest basic-wait: SKIP (0.000s)
[114.307315] [024/161] (935s left) gem_exec_fence (basic-await)
[114.749987] Subtest basic-await: SKIP (0.000s)
[115.169057] [025/161] (934s left) gem_exec_fence (nb-await)
[115.678311] Subtest nb-await: SKIP (0.000s)
[116.024359] [026/161] (934s left) gem_exec_gttfill (basic)
[116.415990] Starting subtest: basic
[122.001894] Subtest basic: SUCCESS (5.687s)
[122.270088] [027/161] (927s left) gem_exec_parallel (engines)
[124.304496] Starting subtest: engines
[124.346328] Starting dynamic subtest: basic
[126.747098] Dynamic subtest basic: SUCCESS (2.517s)
[126.797213] Starting dynamic subtest: contexts
[129.481318] Dynamic subtest contexts: SUCCESS (2.799s)
[129.531541] Starting dynamic subtest: fds
[132.291344] Dynamic subtest fds: SUCCESS (2.816s)
[132.333244] Starting dynamic subtest: userptr
[134.109219] Dynamic subtest userptr: SUCCESS (1.761s)
[134.142539] Subtest engines: SUCCESS (9.896s)
[134.354442] [028/161] (916s left) gem_exec_store (basic)
[135.014134] Starting subtest: basic
[135.056047] Subtest basic: SUCCESS (0.036s)
[135.207399] [029/161] (915s left) gem_flink_basic (bad-flink)
[135.632954] Starting subtest: bad-flink
[135.666407] Subtest bad-flink: SUCCESS (0.000s)
[135.817378] [030/161] (914s left) gem_flink_basic (bad-open)
[136.260319] Starting subtest: bad-open
[136.293847] Subtest bad-open: SUCCESS (0.000s)
[136.444765] [031/161] (914s left) gem_flink_basic (basic)
[136.838805] Starting subtest: basic
[136.880540] Subtest basic: SUCCESS (0.000s)
[137.065602] [032/161] (913s left) gem_flink_basic (double-flink)
[137.532799] Starting subtest: double-flink
[137.574617] Subtest double-flink: SUCCESS (0.000s)
[137.734580] [033/161] (913s left) gem_flink_basic (flink-lifetime)
[138.126365] Starting subtest: flink-lifetime
[138.159921] Subtest flink-lifetime: SUCCESS (0.001s)
[138.294247] [034/161] (912s left) gem_huc_copy (huc-copy)
[138.761998] Subtest huc-copy: SKIP (0.000s)
[138.929592] [035/161] (912s left) gem_linear_blits (basic)
[139.566143] Starting subtest: basic
[139.674806] Subtest basic: SUCCESS (0.011s)
[140.293086] [036/161] (911s left) gem_mmap (basic)
[142.051616] Starting subtest: basic
[142.252308] Subtest basic: SUCCESS (0.001s)
[142.411464] [037/161] (909s left) gem_mmap_gtt (basic)
[142.904284] Starting subtest: basic
[142.946257] Subtest basic: SUCCESS (0.001s)
[143.105812] [038/161] (908s left) gem_render_linear_blits (basic)
[143.539705] Starting subtest: basic
[143.750390] Subtest basic: SUCCESS (0.026s)
[143.909837] [039/161] (908s left) gem_render_tiled_blits (basic)
[144.318998] Starting subtest: basic
[144.519758] Subtest basic: SUCCESS (0.038s)
[144.679776] [040/161] (907s left) gem_ringfill (basic-all)
[145.163620] Starting subtest: basic-all
[146.214214] Subtest basic-all: SUCCESS (1.107s)
[146.390656] [041/161] (906s left) gem_softpin (allocator-basic)
[146.908478] Subtest allocator-basic: SKIP (0.000s)
[147.111184] [042/161] (905s left) gem_softpin (allocator-basic-reserve)
[147.495440] Subtest allocator-basic-reserve: SKIP (0.000s)
[147.663297] [043/161] (904s left) gem_softpin (safe-alignment)
[148.130955] Starting subtest: safe-alignment
[148.172879] Subtest safe-alignment: SUCCESS (0.004s)
[148.340719] [044/161] (904s left) gem_sync (basic-all)
[148.883385] Starting subtest: basic-all
[150.792692] Subtest basic-all: SUCCESS (2.087s)
[151.019458] [045/161] (901s left) gem_sync (basic-each)
[151.513084] Starting subtest: basic-each
[153.489400] Subtest basic-each: SUCCESS (2.109s)
[153.699069] [046/161] (899s left) gem_tiled_blits (basic)
[154.350254] Starting subtest: basic
[154.475421] Subtest basic: SUCCESS (0.043s)
[154.993171] [047/161] (898s left) gem_tiled_fence_blits (basic)
[157.052598] Starting subtest: basic
[157.169301] Subtest basic: SUCCESS (0.011s)
[157.695704] [048/161] (895s left) gem_tiled_pread_basic
[159.807975] [049/161] (893s left) gem_wait (busy@all-engines)
[160.350209] Starting subtest: busy
[160.392078] Starting dynamic subtest: all-engines
[160.793106] Dynamic subtest all-engines: SUCCESS (0.517s)
[160.834690] Subtest busy: SUCCESS (0.518s)
[161.086987] [050/161] (892s left) gem_wait (wait@all-engines)
[161.520268] Starting subtest: wait
[161.553749] Starting dynamic subtest: all-engines
[162.446428] Dynamic subtest all-engines: SUCCESS (1.022s)
[162.479902] Subtest wait: SUCCESS (1.022s)
[162.740321] [051/161] (891s left) i915_getparams_basic (basic-eu-total)
[163.159120] Starting subtest: basic-eu-total
[163.242672] Subtest basic-eu-total: SUCCESS (0.000s)
[163.410834] [052/161] (890s left) i915_getparams_basic (basic-subslice-total)
[163.836420] Starting subtest: basic-subslice-total
[163.903329] Subtest basic-subslice-total: SUCCESS (0.000s)
[164.062987] [053/161] (890s left) i915_hangman (error-state-basic)
[164.705169] Starting subtest: error-state-basic
[164.797022] Subtest error-state-basic: SUCCESS (0.007s)
[165.065357] [054/161] (889s left) i915_pciid
[165.642183] [055/161] (888s left) kms_addfb_basic (addfb25-4-tiled)
[166.488456] Starting subtest: addfb25-4-tiled
[166.530295] Subtest addfb25-4-tiled: SUCCESS (0.000s)
[166.858488] [056/161] (887s left) kms_addfb_basic (addfb25-bad-modifier)
[167.468213] Starting subtest: addfb25-bad-modifier
[167.503407] Subtest addfb25-bad-modifier: SUCCESS (0.001s)
[167.864996] [057/161] (886s left) kms_addfb_basic (addfb25-framebuffer-vs-set-tiling)
[168.391725] Starting subtest: addfb25-framebuffer-vs-set-tiling
[168.433239] Subtest addfb25-framebuffer-vs-set-tiling: SUCCESS (0.000s)
[168.811668] [058/161] (886s left) kms_addfb_basic (addfb25-modifier-no-flag)
[169.348111] Starting subtest: addfb25-modifier-no-flag
[169.381225] Subtest addfb25-modifier-no-flag: SUCCESS (0.000s)
[169.751413] [059/161] (885s left) kms_addfb_basic (addfb25-x-tiled-legacy)
[170.327479] Starting subtest: addfb25-x-tiled-legacy
[170.360769] Subtest addfb25-x-tiled-legacy: SUCCESS (0.000s)
[170.738617] [060/161] (884s left) kms_addfb_basic (addfb25-x-tiled-mismatch-legacy)
[171.300651] Starting subtest: addfb25-x-tiled-mismatch-legacy
[171.342299] Subtest addfb25-x-tiled-mismatch-legacy: SUCCESS (0.000s)
[171.738557] [061/161] (883s left) kms_addfb_basic (addfb25-yf-tiled-legacy)
[172.330906] Starting subtest: addfb25-yf-tiled-legacy
[172.364067] Subtest addfb25-yf-tiled-legacy: SUCCESS (0.000s)
[172.710139] [062/161] (882s left) kms_addfb_basic (addfb25-y-tiled-legacy)
[173.328922] Starting subtest: addfb25-y-tiled-legacy
[173.370692] Subtest addfb25-y-tiled-legacy: SUCCESS (0.000s)
[173.731928] [063/161] (881s left) kms_addfb_basic (addfb25-y-tiled-small-legacy)
[174.277353] Starting subtest: addfb25-y-tiled-small-legacy
[174.361598] Subtest addfb25-y-tiled-small-legacy: SKIP (0.000s)
[174.621749] [064/161] (881s left) kms_addfb_basic (bad-pitch-0)
[175.114478] Starting subtest: bad-pitch-0
[175.156509] Subtest bad-pitch-0: SUCCESS (0.000s)
[175.601840] [065/161] (880s left) kms_addfb_basic (bad-pitch-1024)
[176.069352] Starting subtest: bad-pitch-1024
[176.107304] Subtest bad-pitch-1024: SUCCESS (0.000s)
[176.625111] [066/161] (879s left) kms_addfb_basic (bad-pitch-128)
[177.083906] Starting subtest: bad-pitch-128
[177.125701] Subtest bad-pitch-128: SUCCESS (0.000s)
[177.562405] [067/161] (878s left) kms_addfb_basic (bad-pitch-256)
[177.965382] Starting subtest: bad-pitch-256
[178.006871] Subtest bad-pitch-256: SUCCESS (0.000s)
[178.510445] [068/161] (877s left) kms_addfb_basic (bad-pitch-32)
[178.978473] Starting subtest: bad-pitch-32
[179.016288] Subtest bad-pitch-32: SUCCESS (0.000s)
[179.484203] [069/161] (877s left) kms_addfb_basic (bad-pitch-63)
[179.960052] Starting subtest: bad-pitch-63
[180.001871] Subtest bad-pitch-63: SUCCESS (0.000s)
[180.447217] [070/161] (876s left) kms_addfb_basic (bad-pitch-65536)
[180.906231] Starting subtest: bad-pitch-65536
[180.939658] Subtest bad-pitch-65536: SUCCESS (0.000s)
[181.454951] [071/161] (875s left) kms_addfb_basic (bad-pitch-999)
[181.938066] Starting subtest: bad-pitch-999
[181.979421] Subtest bad-pitch-999: SUCCESS (0.000s)
[182.441637] [072/161] (874s left) kms_addfb_basic (basic)
[182.925346] Starting subtest: basic
[182.958956] Subtest basic: SUCCESS (0.000s)
[183.656197] [073/161] (873s left) kms_addfb_basic (basic-x-tiled-legacy)
[184.115289] Starting subtest: basic-x-tiled-legacy
[184.156835] Subtest basic-x-tiled-legacy: SUCCESS (0.000s)
[184.668928] [074/161] (872s left) kms_addfb_basic (basic-y-tiled-legacy)
[185.171856] Starting subtest: basic-y-tiled-legacy
[185.213372] Subtest basic-y-tiled-legacy: SUCCESS (0.001s)
[185.709427] [075/161] (871s left) kms_addfb_basic (bo-too-small)
[186.293999] Starting subtest: bo-too-small
[186.335910] Subtest bo-too-small: SUCCESS (0.001s)
[186.697855] [076/161] (870s left) kms_addfb_basic (bo-too-small-due-to-tiling)
[187.300534] Starting subtest: bo-too-small-due-to-tiling
[187.334069] Subtest bo-too-small-due-to-tiling: SUCCESS (0.001s)
[187.661810] [077/161] (870s left) kms_addfb_basic (clobberred-modifier)
[188.112549] Starting subtest: clobberred-modifier
[188.154181] Subtest clobberred-modifier: SUCCESS (0.000s)
[188.668223] [078/161] (869s left) kms_addfb_basic (framebuffer-vs-set-tiling)
[189.118884] Starting subtest: framebuffer-vs-set-tiling
[189.152241] Subtest framebuffer-vs-set-tiling: SUCCESS (0.000s)
[189.673301] [079/161] (868s left) kms_addfb_basic (invalid-get-prop)
[190.173821] Starting subtest: invalid-get-prop
[190.206973] Subtest invalid-get-prop: SUCCESS (0.000s)
[190.729917] [080/161] (867s left) kms_addfb_basic (invalid-get-prop-any)
[191.180169] Starting subtest: invalid-get-prop-any
[191.222089] Subtest invalid-get-prop-any: SUCCESS (0.000s)
[191.676435] [081/161] (866s left) kms_addfb_basic (invalid-set-prop)
[192.143105] Starting subtest: invalid-set-prop
[192.176597] Subtest invalid-set-prop: SUCCESS (0.000s)
[192.757611] [082/161] (865s left) kms_addfb_basic (invalid-set-prop-any)
[193.200471] Starting subtest: invalid-set-prop-any
[193.233535] Subtest invalid-set-prop-any: SUCCESS (0.000s)
[193.770821] [083/161] (865s left) kms_addfb_basic (no-handle)
[194.315127] Starting subtest: no-handle
[194.357174] Subtest no-handle: SUCCESS (0.000s)
[194.794231] [084/161] (864s left) kms_addfb_basic (size-max)
[195.353540] Starting subtest: size-max
[195.394921] Subtest size-max: SUCCESS (0.000s)
[195.758383] [085/161] (863s left) kms_addfb_basic (small-bo)
[196.351880] Starting subtest: small-bo
[196.385345] Subtest small-bo: SUCCESS (0.000s)
[196.746989] [086/161] (862s left) kms_addfb_basic (tile-pitch-mismatch)
[197.205779] Starting subtest: tile-pitch-mismatch
[197.247899] Subtest tile-pitch-mismatch: SUCCESS (0.001s)
[197.720479] [087/161] (861s left) kms_addfb_basic (too-high)
[198.287603] Starting subtest: too-high
[198.329265] Subtest too-high: SUCCESS (0.001s)
[198.724939] [088/161] (860s left) kms_addfb_basic (too-wide)
[199.316962] Starting subtest: too-wide
[199.350425] Subtest too-wide: SUCCESS (0.000s)
[199.680717] [089/161] (859s left) kms_addfb_basic (unused-handle)
[200.147487] Starting subtest: unused-handle
[200.189724] Subtest unused-handle: SUCCESS (0.000s)
[200.593950] [090/161] (859s left) kms_addfb_basic (unused-modifier)
[200.959741] Starting subtest: unused-modifier
[200.993718] Subtest unused-modifier: SUCCESS (0.000s)
[201.515985] [091/161] (858s left) kms_addfb_basic (unused-offsets)
[201.966542] Starting subtest: unused-offsets
[202.000309] Subtest unused-offsets: SUCCESS (0.000s)
[202.520991] [092/161] (857s left) kms_addfb_basic (unused-pitches)
[203.015028] Starting subtest: unused-pitches
[203.056925] Subtest unused-pitches: SUCCESS (0.000s)
[203.528192] [093/161] (856s left) kms_busy (basic)
[204.162543] Starting subtest: basic
[204.271069] Starting dynamic subtest: flip
[205.597719] Dynamic subtest flip: SUCCESS (1.445s)
[205.647998] Starting dynamic subtest: modeset
[207.980207] Dynamic subtest modeset: SUCCESS (2.366s)
[208.021674] Subtest basic: SUCCESS (3.815s)
[209.051232] [094/161] (851s left) kms_prop_blob (basic)
[209.636249] Starting subtest: basic
[209.669706] Subtest basic: SUCCESS (0.000s)
[209.829795] [095/161] (850s left) kms_cursor_legacy (basic-busy-flip-before-cursor-atomic)
[210.380644] Starting subtest: basic-busy-flip-before-cursor-atomic
[211.564754] Subtest basic-busy-flip-before-cursor-atomic: SUCCESS (1.190s)
[212.687183] [096/161] (847s left) kms_cursor_legacy (basic-busy-flip-before-cursor-legacy)
[213.255819] Starting subtest: basic-busy-flip-before-cursor-legacy
[214.439977] Subtest basic-busy-flip-before-cursor-legacy: SUCCESS (1.163s)
[215.487609] [097/161] (845s left) kms_cursor_legacy (basic-flip-after-cursor-atomic)
[216.139248] Starting subtest: basic-flip-after-cursor-atomic
[217.273214] Subtest basic-flip-after-cursor-atomic: SUCCESS (1.154s)
[218.403220] [098/161] (842s left) kms_cursor_legacy (basic-flip-after-cursor-legacy)
[218.954122] Starting subtest: basic-flip-after-cursor-legacy
[220.137748] Subtest basic-flip-after-cursor-legacy: SUCCESS (1.159s)
[221.169563] [099/161] (840s left) kms_cursor_legacy (basic-flip-after-cursor-varying-size)
[221.736847] Starting subtest: basic-flip-after-cursor-varying-size
[222.938446] Subtest basic-flip-after-cursor-varying-size: SUCCESS (1.167s)
[223.992155] [100/161] (837s left) kms_cursor_legacy (basic-flip-before-cursor-atomic)
[224.628155] Starting subtest: basic-flip-before-cursor-atomic
[225.753962] Subtest basic-flip-before-cursor-atomic: SUCCESS (1.136s)
[226.760433] [101/161] (834s left) kms_cursor_legacy (basic-flip-before-cursor-legacy)
[227.367829] Starting subtest: basic-flip-before-cursor-legacy
[228.534667] Subtest basic-flip-before-cursor-legacy: SUCCESS (1.163s)
[229.549535] [102/161] (832s left) kms_cursor_legacy (basic-flip-before-cursor-varying-size)
[230.175218] Starting subtest: basic-flip-before-cursor-varying-size
[231.334358] Subtest basic-flip-before-cursor-varying-size: SUCCESS (1.140s)
[232.365041] [103/161] (829s left) kms_dsc (dsc-basic)
[232.958596] Subtest dsc-basic: SKIP (0.000s)
[233.187569] [104/161] (828s left) kms_flip (basic-flip-vs-dpms)
[233.904721] Starting subtest: basic-flip-vs-dpms
[233.946768] Starting dynamic subtest: A-LVDS1
[235.668752] Dynamic subtest A-LVDS1: SUCCESS (1.808s)
[235.718956] Starting dynamic subtest: B-LVDS1
[237.188662] Dynamic subtest B-LVDS1: SUCCESS (1.488s)
[237.239188] Starting dynamic subtest: A-VGA1
[237.895106] Dynamic subtest A-VGA1: SUCCESS (0.737s)
[237.945266] Starting dynamic subtest: B-VGA1
[238.675363] Dynamic subtest B-VGA1: SUCCESS (0.763s)
[238.708865] Subtest basic-flip-vs-dpms: SUCCESS (4.807s)
[239.206786] [105/161] (822s left) kms_flip (basic-flip-vs-modeset)
[239.858069] Starting subtest: basic-flip-vs-modeset
[239.900023] Starting dynamic subtest: A-LVDS1
[241.555759] Dynamic subtest A-LVDS1: SUCCESS (1.770s)
[241.614072] Starting dynamic subtest: B-LVDS1
[243.175467] Dynamic subtest B-LVDS1: SUCCESS (1.601s)
[243.225695] Starting dynamic subtest: A-VGA1
[243.990771] Dynamic subtest A-VGA1: SUCCESS (0.800s)
[244.041438] Starting dynamic subtest: B-VGA1
[244.746140] Dynamic subtest B-VGA1: SUCCESS (0.771s)
[244.779929] Subtest basic-flip-vs-modeset: SUCCESS (4.954s)
[245.243660] [106/161] (816s left) kms_flip (basic-flip-vs-wf_vblank)
[245.853208] Starting subtest: basic-flip-vs-wf_vblank
[245.895288] Starting dynamic subtest: A-LVDS1
[247.780570] Dynamic subtest A-LVDS1: SUCCESS (1.949s)
[247.831110] Starting dynamic subtest: B-LVDS1
[249.718854] Dynamic subtest B-LVDS1: SUCCESS (1.886s)
[249.785662] Starting dynamic subtest: A-VGA1
[250.896023] Dynamic subtest A-VGA1: SUCCESS (1.222s)
[250.954604] Starting dynamic subtest: B-VGA1
[251.914459] Dynamic subtest B-VGA1: SUCCESS (0.990s)
[251.956238] Subtest basic-flip-vs-wf_vblank: SUCCESS (6.063s)
[252.436156] [107/161] (809s left) kms_flip (basic-plain-flip)
[253.079901] Starting subtest: basic-plain-flip
[253.113684] Starting dynamic subtest: A-LVDS1
[254.657219] Dynamic subtest A-LVDS1: SUCCESS (1.579s)
[254.716236] Starting dynamic subtest: B-LVDS1
[256.151678] Dynamic subtest B-LVDS1: SUCCESS (1.488s)
[256.202085] Starting dynamic subtest: A-VGA1
[257.115554] Dynamic subtest A-VGA1: SUCCESS (0.949s)
[257.164214] Starting dynamic subtest: B-VGA1
[257.823950] Dynamic subtest B-VGA1: SUCCESS (0.706s)
[257.865629] Subtest basic-plain-flip: SUCCESS (4.738s)
[258.380461] [108/161] (804s left) kms_force_connector_basic (force-connector-state)
[258.879522] Starting subtest: force-connector-state
[259.333129] Subtest force-connector-state: SUCCESS (0.607s)
[260.582958] [109/161] (802s left) kms_force_connector_basic (force-edid)
[261.130472] Starting subtest: force-edid
[261.172466] Subtest force-edid: SUCCESS (0.130s)
[261.671726] [110/161] (801s left) kms_force_connector_basic (force-load-detect)
[262.103830] Starting subtest: force-load-detect
[262.380417] Subtest force-load-detect: SUCCESS (0.409s)
[263.410413] [111/161] (799s left) kms_force_connector_basic (prune-stale-modes)
[263.903631] Starting subtest: prune-stale-modes
[263.937528] Subtest prune-stale-modes: SUCCESS (0.024s)
[264.409936] [112/161] (798s left) kms_frontbuffer_tracking (basic)
[265.051081] Starting subtest: basic
[267.251865] Subtest basic: SUCCESS (2.285s)
[267.690588] [113/161] (795s left) kms_hdmi_inject (inject-audio)
[268.400264] Starting subtest: inject-audio
[269.062172] Subtest inject-audio: SUCCESS (0.659s)
[269.507828] [114/161] (793s left) kms_pipe_crc_basic (compare-crc-sanitycheck-xr24)
[270.185611] Starting subtest: compare-crc-sanitycheck-xr24
[270.227308] Starting dynamic subtest: pipe-A-LVDS-1
[270.728825] Dynamic subtest pipe-A-LVDS-1: SUCCESS (0.567s)
[270.771090] Starting dynamic subtest: pipe-B-LVDS-1
[271.673298] Dynamic subtest pipe-B-LVDS-1: SUCCESS (0.937s)
[271.706630] Subtest compare-crc-sanitycheck-xr24: SUCCESS (1.510s)
[272.646551] [115/161] (790s left) kms_pipe_crc_basic (compare-crc-sanitycheck-nv12)
[273.188717] Starting subtest: compare-crc-sanitycheck-nv12
[273.230151] Starting dynamic subtest: pipe-A-LVDS-1
[273.314034] Dynamic subtest pipe-A-LVDS-1: SKIP (0.000s)
[273.364201] Starting dynamic subtest: pipe-B-LVDS-1
[273.448316] Dynamic subtest pipe-B-LVDS-1: SKIP (0.000s)
[273.523561] Subtest compare-crc-sanitycheck-nv12: SKIP (0.006s)
[273.701156] [116/161] (789s left) kms_pipe_crc_basic (hang-read-crc)
[274.209803] Starting subtest: hang-read-crc
[274.251680] Starting dynamic subtest: pipe-A-LVDS-1
[276.074878] Dynamic subtest pipe-A-LVDS-1: SUCCESS (1.848s)
[276.133797] Starting dynamic subtest: pipe-B-LVDS-1
[278.507166] Dynamic subtest pipe-B-LVDS-1: SUCCESS (2.426s)
[278.544416] Subtest hang-read-crc: SUCCESS (4.281s)
[279.463286] [117/161] (784s left) kms_pipe_crc_basic (nonblocking-crc)
[280.070479] Starting subtest: nonblocking-crc
[280.112635] Starting dynamic subtest: pipe-A-LVDS-1
[280.749689] Dynamic subtest pipe-A-LVDS-1: SUCCESS (0.697s)
[280.824888] Starting dynamic subtest: pipe-B-LVDS-1
[282.044345] Dynamic subtest pipe-B-LVDS-1: SUCCESS (1.295s)
[282.085707] Subtest nonblocking-crc: SUCCESS (1.998s)
[283.073255] [118/161] (780s left) kms_pipe_crc_basic (nonblocking-crc-frame-sequence)
[283.692959] Starting subtest: nonblocking-crc-frame-sequence
[283.726392] Starting dynamic subtest: pipe-A-LVDS-1
[284.436901] Dynamic subtest pipe-A-LVDS-1: SUCCESS (0.720s)
[284.504141] Starting dynamic subtest: pipe-B-LVDS-1
[285.765050] Dynamic subtest pipe-B-LVDS-1: SUCCESS (1.313s)
[285.806778] Subtest nonblocking-crc-frame-sequence: SUCCESS (2.039s)
[286.764201] [119/161] (777s left) kms_pipe_crc_basic (read-crc)
[287.363641] Starting subtest: read-crc
[287.397086] Starting dynamic subtest: pipe-A-LVDS-1
[288.032476] Dynamic subtest pipe-A-LVDS-1: SUCCESS (0.675s)
[288.082957] Starting dynamic subtest: pipe-B-LVDS-1
[289.294035] Dynamic subtest pipe-B-LVDS-1: SUCCESS (1.235s)
[289.335754] Subtest read-crc: SUCCESS (1.916s)
[290.292395] [120/161] (773s left) kms_pipe_crc_basic (read-crc-frame-sequence)
[290.926233] Starting subtest: read-crc-frame-sequence
[290.959905] Starting dynamic subtest: pipe-A-LVDS-1
[291.578368] Dynamic subtest pipe-A-LVDS-1: SUCCESS (0.654s)
[291.654098] Starting dynamic subtest: pipe-B-LVDS-1
[292.791523] Dynamic subtest pipe-B-LVDS-1: SUCCESS (1.217s)
[292.824867] Subtest read-crc-frame-sequence: SUCCESS (1.877s)
[293.839279] [121/161] (770s left) kms_pm_backlight (basic-brightness)
[294.462751] Starting subtest: basic-brightness
[294.504922] Starting dynamic subtest: LVDS-1
[294.797545] Dynamic subtest LVDS-1: SUCCESS (0.289s)
[294.838909] Subtest basic-brightness: SUCCESS (0.332s)
[295.872795] [122/161] (768s left) kms_pm_rpm (basic-pci-d3-state)
[297.739373] Subtest basic-pci-d3-state: SKIP (0.000s)
[297.902235] [123/161] (766s left) kms_pm_rpm (basic-rte)
[298.283274] Starting subtest: basic-rte
[299.654120] Subtest basic-rte: SKIP (1.386s)
[299.899265] [124/161] (764s left) kms_psr (psr-primary-page-flip)
[300.532577] Subtest psr-primary-page-flip: SKIP (0.000s)
[300.762224] [125/161] (764s left) kms_psr (psr-cursor-plane-move)
[301.296171] Subtest psr-cursor-plane-move: SKIP (0.000s)
[301.557060] [126/161] (763s left) kms_psr (psr-sprite-plane-onoff)
[302.142177] Subtest psr-sprite-plane-onoff: SKIP (0.000s)
[302.402996] [127/161] (762s left) kms_psr (psr-primary-mmap-gtt)
[303.220494] Subtest psr-primary-mmap-gtt: SKIP (0.000s)
[303.642719] [128/161] (761s left) kms_setmode (basic-clone-single-crtc)
[304.283150] Starting subtest: basic-clone-single-crtc
[304.383592] Subtest basic-clone-single-crtc: SKIP (0.001s)
[304.535112] [129/161] (761s left) i915_pm_rps (basic-api)
[305.068806] Starting subtest: basic-api
[305.110635] Subtest basic-api: SUCCESS (0.004s)
[305.296314] [130/161] (760s left) prime_self_import (basic-llseek-bad)
[305.729289] Starting subtest: basic-llseek-bad
[305.804470] Subtest basic-llseek-bad: SUCCESS (0.002s)
[305.965338] [131/161] (760s left) prime_self_import (basic-llseek-size)
[306.449708] Starting subtest: basic-llseek-size
[306.575059] Subtest basic-llseek-size: SUCCESS (0.003s)
[306.718320] [132/161] (759s left) prime_self_import (basic-with_fd_dup)
[307.110302] Starting subtest: basic-with_fd_dup
[307.193910] Subtest basic-with_fd_dup: SUCCESS (0.003s)
[307.345571] [133/161] (759s left) prime_self_import (basic-with_one_bo)
[307.695608] Starting subtest: basic-with_one_bo
[307.762574] Subtest basic-with_one_bo: SUCCESS (0.003s)
[307.906982] [134/161] (758s left) prime_self_import (basic-with_one_bo_two_files)
[308.339562] Starting subtest: basic-with_one_bo_two_files
[308.423146] Subtest basic-with_one_bo_two_files: SUCCESS (0.003s)
[308.576517] [135/161] (758s left) prime_self_import (basic-with_two_bos)
[308.943083] Starting subtest: basic-with_two_bos
[309.009943] Subtest basic-with_two_bos: SUCCESS (0.003s)
[309.154912] [136/161] (757s left) prime_vgem (basic-fence-flip)
[309.741304] Starting subtest: basic-fence-flip
[309.912572] Subtest basic-fence-flip: SKIP (0.245s)
[310.290728] [137/161] (756s left) prime_vgem (basic-fence-mmap)
[310.940373] Starting subtest: basic-fence-mmap
[311.015455] Subtest basic-fence-mmap: SUCCESS (0.041s)
[311.335195] [138/161] (756s left) prime_vgem (basic-fence-read)
[311.984397] Starting subtest: basic-fence-read
[312.051297] Subtest basic-fence-read: SUCCESS (0.048s)
[312.373311] [139/161] (755s left) prime_vgem (basic-gtt)
[312.996533] Starting subtest: basic-gtt
[313.054990] Subtest basic-gtt: SUCCESS (0.015s)
[313.324037] [140/161] (754s left) prime_vgem (basic-read)
[313.865771] Starting subtest: basic-read
[313.907545] Subtest basic-read: SUCCESS (0.019s)
[314.237249] [141/161] (753s left) prime_vgem (basic-write)
[314.934723] Starting subtest: basic-write
[315.018279] Subtest basic-write: SUCCESS (0.021s)
[315.404386] [142/161] (752s left) vgem_basic (setversion)
[316.005783] Starting subtest: setversion
[316.089507] Subtest setversion: SUCCESS (0.000s)
[316.250084] [143/161] (752s left) vgem_basic (create)
[316.674724] Starting subtest: create
[316.766561] Subtest create: SUCCESS (0.000s)
[316.918207] [144/161] (751s left) vgem_basic (debugfs)
[317.376762] Starting subtest: debugfs
[317.410236] Subtest debugfs: SUCCESS (0.001s)
[317.636976] [145/161] (751s left) vgem_basic (dmabuf-export)
[318.112212] Starting subtest: dmabuf-export
[318.187512] Subtest dmabuf-export: SUCCESS (0.003s)
[318.381415] [146/161] (750s left) vgem_basic (dmabuf-fence)
[318.841184] Starting subtest: dmabuf-fence
[318.874702] Subtest dmabuf-fence: SUCCESS (0.001s)
[319.059604] [147/161] (750s left) vgem_basic (dmabuf-fence-before)
[319.518346] Starting subtest: dmabuf-fence-before
[319.551817] Subtest dmabuf-fence-before: SUCCESS (0.001s)
[319.689234] [148/161] (749s left) vgem_basic (dmabuf-mmap)
[320.120339] Starting subtest: dmabuf-mmap
[320.162120] Subtest dmabuf-mmap: SUCCESS (0.017s)
[320.307886] [149/161] (749s left) vgem_basic (mmap)
[320.847404] Starting subtest: mmap
[320.881155] Subtest mmap: SUCCESS (0.011s)
[321.058220] [150/161] (748s left) vgem_basic (second-client)
[321.484640] Starting subtest: second-client
[321.551305] Subtest second-client: SUCCESS (0.033s)
[321.686395] [151/161] (748s left) vgem_basic (sysfs)
[322.153557] Starting subtest: sysfs
[322.187080] Subtest sysfs: SUCCESS (0.001s)
[322.330375] [152/161] (747s left) core_hotunplug (unbind-rebind)
[323.097741] Starting subtest: unbind-rebind
[327.224287] Subtest unbind-rebind: SUCCESS (4.212s)
[327.385524] [153/161] (742s left) vgem_basic (unload)
[327.993312] Starting subtest: unload
[357.015304] Subtest unload: SUCCESS (29.120s)
[357.226339] [154/161] (713s left) i915_module_load (reload)
[357.784464] Starting subtest: reload
[361.869832] Subtest reload: SUCCESS (4.202s)
[362.063724] [155/161] (708s left) gem_lmem_swapping (basic)
[362.698307] Subtest basic: SKIP (0.000s)
[362.868398] [156/161] (707s left) gem_lmem_swapping (parallel-random-engines)
[363.333629] Subtest parallel-random-engines: SKIP (0.000s)
[363.490655] [157/161] (707s left) gem_lmem_swapping (random-engines)
[363.979115] Subtest random-engines: SKIP (0.000s)
[364.140663] [158/161] (706s left) gem_lmem_swapping (verify-random)
[364.572771] Subtest verify-random: SKIP (0.000s)
[364.718293] [159/161] (706s left) i915_pm_rpm (module-reload)
[366.529083] Subtest module-reload: SKIP (0.000s)
[366.672607] [160/161] (704s left) i915_selftest (live)
[368.457794] Starting subtest: live
[368.491341] Starting dynamic subtest: sanitycheck
[370.452933] Dynamic subtest sanitycheck: SUCCESS (1.971s)
[370.503047] Starting dynamic subtest: uncore
[372.332984] Dynamic subtest uncore: SUCCESS (1.881s)
[372.374786] Starting dynamic subtest: workarounds
[374.277573] Dynamic subtest workarounds: SUCCESS (1.943s)
[375.186167] Starting dynamic subtest: gt_engines
[376.222653] Dynamic subtest gt_engines: SUCCESS (1.946s)
[376.266158] Starting dynamic subtest: gt_timelines
[381.054060] Dynamic subtest gt_timelines: SUCCESS (4.838s)
[381.104183] Starting dynamic subtest: gt_contexts
[382.982311] Dynamic subtest gt_contexts: SUCCESS (1.895s)
[383.890899] Starting dynamic subtest: gt_lrc
[384.920588] Dynamic subtest gt_lrc: SUCCESS (1.960s)
[384.970729] Starting dynamic subtest: gt_mocs
[386.882117] Dynamic subtest gt_mocs: SUCCESS (1.963s)
[386.924020] Starting dynamic subtest: gt_pm
[396.473157] Dynamic subtest gt_pm: SUCCESS (9.569s)
[397.381614] Starting dynamic subtest: gt_heartbeat
[398.394043] Dynamic subtest gt_heartbeat: SUCCESS (1.933s)
[398.443771] Starting dynamic subtest: gt_tlb
[400.456802] Dynamic subtest gt_tlb: SUCCESS (2.040s)
[400.498667] Starting dynamic subtest: requests
[407.255081] Dynamic subtest requests: SUCCESS (6.792s)
[407.305260] Starting dynamic subtest: migrate
[409.176452] Dynamic subtest migrate: SUCCESS (1.934s)
[410.076622] Starting dynamic subtest: active
[411.271447] Dynamic subtest active: SUCCESS (2.072s)
[411.321555] Starting dynamic subtest: objects
[413.666964] Dynamic subtest objects: SUCCESS (2.407s)
[413.717118] Starting dynamic subtest: mman
[420.791587] Dynamic subtest mman: SUCCESS (7.116s)
[421.691842] Starting dynamic subtest: dmabuf
[422.829842] Dynamic subtest dmabuf: SUCCESS (2.049s)
[423.788672] Starting dynamic subtest: vma
[424.833077] Dynamic subtest vma: SUCCESS (1.998s)
[425.749922] Starting dynamic subtest: coherency
[427.653159] Dynamic subtest coherency: SUCCESS (2.808s)
[427.705047] Starting dynamic subtest: gtt
[432.529310] Dynamic subtest gtt: SUCCESS (4.871s)
[432.586282] Starting dynamic subtest: gem
[434.725795] Dynamic subtest gem: SUCCESS (2.192s)
[435.642436] Starting dynamic subtest: evict
[436.636873] Dynamic subtest evict: SUCCESS (1.922s)
[437.537052] Starting dynamic subtest: hugepages
[439.148615] Dynamic subtest hugepages: SUCCESS (2.495s)
[440.057174] Starting dynamic subtest: gem_contexts
[444.747571] Dynamic subtest gem_contexts: SUCCESS (5.596s)
[445.664843] Starting dynamic subtest: client
[446.759193] Dynamic subtest client: SUCCESS (2.007s)
[447.675935] Starting dynamic subtest: gem_migrate
[448.714077] Dynamic subtest gem_migrate: SUCCESS (1.955s)
[448.755849] Starting dynamic subtest: reset
[450.900816] Dynamic subtest reset: SUCCESS (2.193s)
[450.950998] Starting dynamic subtest: memory_region
[452.853889] Dynamic subtest memory_region: SUCCESS (1.936s)
[452.895752] Starting dynamic subtest: hangcheck
[456.543988] Dynamic subtest hangcheck: SUCCESS (3.701s)
[456.585816] Starting dynamic subtest: execlists
[458.488637] Dynamic subtest execlists: SUCCESS (1.943s)
[458.530480] Starting dynamic subtest: ring_submission
[460.510703] Dynamic subtest ring_submission: SUCCESS (2.000s)
[460.560802] Starting dynamic subtest: perf
[462.439119] Dynamic subtest perf: SUCCESS (1.917s)
[462.480847] Starting dynamic subtest: slpc
[464.277245] Dynamic subtest slpc: SUCCESS (1.845s)
[465.210861] Starting dynamic subtest: guc
[466.188919] Dynamic subtest guc: SUCCESS (1.928s)
[466.230758] Starting dynamic subtest: guc_multi_lrc
[468.150769] Dynamic subtest guc_multi_lrc: SUCCESS (1.935s)
[468.200755] Starting dynamic subtest: guc_hang
[470.005244] Dynamic subtest guc_hang: SUCCESS (1.860s)
[470.047106] Starting dynamic subtest: late_gt_pm
[471.866992] Dynamic subtest late_gt_pm: SUCCESS (1.861s)
[471.908788] Subtest live: SUCCESS (103.400s)
[472.026797] [FACT i915_selftest (live)] deleted: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[472.028560] [FACT i915_selftest (live)] deleted: kernel.kmod_is_loaded.i915: true
[472.045256] [161/161] (599s left) dmabuf (all-tests)
[472.644270] Starting subtest: all-tests
[472.686490] Starting dynamic subtest: sanitycheck
[472.769890] Dynamic subtest sanitycheck: SUCCESS (0.114s)
[472.820116] Starting dynamic subtest: dma_fence
[472.987353] Dynamic subtest dma_fence: SUCCESS (0.229s)
[473.032614] Starting dynamic subtest: dma_fence_chain
[498.392676] Dynamic subtest dma_fence_chain: SUCCESS (25.383s)
[498.459314] Starting dynamic subtest: dma_fence_unwrap
[498.492994] Dynamic subtest dma_fence_unwrap: SUCCESS (0.085s)
[498.534879] Starting dynamic subtest: dma_resv
[498.568474] Dynamic subtest dma_resv: SUCCESS (0.086s)
[498.610213] Subtest all-tests: SUCCESS (25.918s)
[498.734078] Closing watchdogs
Done.