[39.191403] Initializing watchdogs
[39.191639]   /dev/watchdog0
[39.244567] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:5a85 Intel Broxton (Gen9) HD Graphics 500
[39.273656] [001/161] (960s left) i915_module_load (load)
[39.954461] Starting subtest: load
[44.509212] Subtest load: SUCCESS (4.567s)
[44.647940] [FACT i915_module_load (load)] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[44.651998] [FACT i915_module_load (load)] new: kernel.kmod_is_loaded.i915: true
[44.697040] [002/161] (955s left) core_auth (basic-auth)
[45.035561] Starting subtest: basic-auth
[45.056119] Subtest basic-auth: SUCCESS (0.002s)
[45.213459] [003/161] (954s left) debugfs_test (read_all_entries)
[45.478923] Starting subtest: read_all_entries
[45.632241] Subtest read_all_entries: SUCCESS (0.162s)
[45.964677] [004/161] (954s left) debugfs_test (basic-hwmon)
[46.237577] Starting subtest: basic-hwmon
[46.282189] Subtest basic-hwmon: SKIP (0.000s)
[46.500354] [005/161] (953s left) debugfs_test (sysfs)
[46.765245] Starting subtest: sysfs
[46.905081] Subtest sysfs: SUCCESS (0.165s)
[47.191203] [006/161] (953s left) fbdev (eof)
[47.434110] Starting subtest: eof
[47.457859] Subtest eof: SUCCESS (0.002s)
[47.609851] [007/161] (953s left) fbdev (info)
[47.859424] Starting subtest: info
[47.880417] Subtest info: SUCCESS (0.000s)
[48.035714] [008/161] (952s left) fbdev (nullptr)
[48.266782] Starting subtest: nullptr
[48.286865] Subtest nullptr: SUCCESS (0.000s)
[48.446717] [009/161] (952s left) fbdev (read)
[48.668495] Starting subtest: read
[50.152972] Subtest read: SUCCESS (1.491s)
[50.350160] [010/161] (950s left) fbdev (write)
[50.589521] Starting subtest: write
[51.337297] Subtest write: SUCCESS (0.776s)
[51.513777] [011/161] (949s left) gem_basic (bad-close)
[51.770129] Starting subtest: bad-close
[51.819035] Subtest bad-close: SUCCESS (0.000s)
[51.980304] [012/161] (949s left) gem_basic (create-close)
[52.246930] Starting subtest: create-close
[52.305825] Subtest create-close: SUCCESS (0.000s)
[52.475850] [013/161] (949s left) gem_basic (create-fd-close)
[52.779841] Starting subtest: create-fd-close
[52.831704] Subtest create-fd-close: SUCCESS (0.000s)
[52.995309] [014/161] (948s left) gem_busy (busy@all-engines)
[53.303820] Starting subtest: busy
[53.334289] Starting dynamic subtest: all-engines
[53.391631] Dynamic subtest all-engines: SUCCESS (0.118s)
[53.416281] Subtest busy: SUCCESS (0.119s)
[53.899857] [015/161] (948s left) gem_close_race (basic-process)
[54.196936] Starting subtest: basic-process
[54.286953] Subtest basic-process: SUCCESS (0.102s)
[54.630564] [016/161] (947s left) gem_close_race (basic-threads)
[54.921886] Starting subtest: basic-threads
[56.207722] Subtest basic-threads: SUCCESS (1.266s)
[56.506338] [017/161] (946s left) gem_ctx_create (basic)
[56.777904] Starting subtest: basic
[56.799802] Subtest basic: SUCCESS (0.000s)
[57.052695] [018/161] (945s left) gem_ctx_create (basic-files)
[57.318091] Starting subtest: basic-files
[59.352398] Subtest basic-files: SUCCESS (2.015s)
[59.648935] [019/161] (943s left) gem_ctx_exec (basic)
[59.899724] Starting subtest: basic
[59.920498] Subtest basic: SUCCESS (0.009s)
[60.097698] [020/161] (942s left) gem_exec_basic (basic)
[60.361523] Starting subtest: basic
[60.386736] Starting dynamic subtest: rcs0-smem
[60.413934] Dynamic subtest rcs0-smem: SUCCESS (0.003s)
[60.467115] Starting dynamic subtest: bcs0-smem
[60.497127] Dynamic subtest bcs0-smem: SUCCESS (0.002s)
[60.549147] Starting dynamic subtest: vcs0-smem
[60.572330] Dynamic subtest vcs0-smem: SUCCESS (0.002s)
[60.638250] Starting dynamic subtest: vecs0-smem
[60.664087] Dynamic subtest vecs0-smem: SUCCESS (0.002s)
[60.688099] Subtest basic: SUCCESS (0.013s)
[60.855552] [021/161] (942s left) gem_exec_create (basic)
[61.122990] Starting subtest: basic
[61.148590] Starting dynamic subtest: smem
[63.189629] Dynamic subtest smem: SUCCESS (2.063s)
[63.212470] Subtest basic: SUCCESS (2.064s)
[63.483366] [022/161] (939s left) gem_exec_fence (basic-busy)
[63.848118] Starting subtest: basic-busy
[63.868669] Starting dynamic subtest: rcs0
[63.912600] Dynamic subtest rcs0: SUCCESS (0.036s)
[63.953863] Starting dynamic subtest: bcs0
[63.995286] Dynamic subtest bcs0: SUCCESS (0.032s)
[64.056992] Starting dynamic subtest: vcs0
[64.098844] Dynamic subtest vcs0: SUCCESS (0.051s)
[64.143515] Starting dynamic subtest: vecs0
[64.185648] Dynamic subtest vecs0: SUCCESS (0.036s)
[64.205528] Subtest basic-busy: SUCCESS (0.158s)
[64.938465] [FACT gem_exec_fence (basic-busy)] new: kernel.kmod_is_loaded.vgem: true
[64.964077] [023/161] (938s left) gem_exec_fence (basic-wait)
[65.352949] Starting subtest: basic-wait
[65.377165] Starting dynamic subtest: rcs0
[65.398895] Dynamic subtest rcs0: SUCCESS (0.034s)
[65.445464] Starting dynamic subtest: bcs0
[65.466847] Dynamic subtest bcs0: SUCCESS (0.033s)
[65.514850] Starting dynamic subtest: vcs0
[65.537533] Dynamic subtest vcs0: SUCCESS (0.038s)
[65.592444] Starting dynamic subtest: vecs0
[65.613623] Dynamic subtest vecs0: SUCCESS (0.022s)
[65.633639] Subtest basic-wait: SUCCESS (0.131s)
[66.323722] [024/161] (937s left) gem_exec_fence (basic-await)
[66.708118] Starting subtest: basic-await
[66.729950] Starting dynamic subtest: rcs0
[66.882206] Dynamic subtest rcs0: SUCCESS (0.171s)
[66.925490] Starting dynamic subtest: bcs0
[67.049525] Dynamic subtest bcs0: SUCCESS (0.164s)
[67.096107] Starting dynamic subtest: vcs0
[67.223937] Dynamic subtest vcs0: SUCCESS (0.173s)
[67.273196] Starting dynamic subtest: vecs0
[67.392144] Dynamic subtest vecs0: SUCCESS (0.168s)
[67.413453] Subtest basic-await: SUCCESS (0.681s)
[68.493186] [025/161] (935s left) gem_exec_fence (nb-await)
[69.005625] Starting subtest: nb-await
[69.028770] Starting dynamic subtest: rcs0
[69.091182] Dynamic subtest rcs0: SUCCESS (0.085s)
[69.138701] Starting dynamic subtest: bcs0
[69.199853] Dynamic subtest bcs0: SUCCESS (0.103s)
[69.248478] Starting dynamic subtest: vcs0
[69.300919] Dynamic subtest vcs0: SUCCESS (0.103s)
[69.349233] Starting dynamic subtest: vecs0
[69.414890] Dynamic subtest vecs0: SUCCESS (0.109s)
[69.436240] Subtest nb-await: SUCCESS (0.407s)
[70.437116] [026/161] (933s left) gem_exec_gttfill (basic)
[70.701298] Starting subtest: basic
[101.189165] Subtest basic: SUCCESS (30.482s)
[101.510440] [027/161] (902s left) gem_exec_parallel (engines)
[101.903222] Starting subtest: engines
[101.925495] Starting dynamic subtest: basic
[106.306740] Dynamic subtest basic: SUCCESS (4.246s)
[106.352315] Starting dynamic subtest: contexts
[112.163317] Dynamic subtest contexts: SUCCESS (5.854s)
[112.209272] Starting dynamic subtest: fds
[117.865622] Dynamic subtest fds: SUCCESS (5.721s)
[117.911826] Starting dynamic subtest: userptr
[122.077855] Dynamic subtest userptr: SUCCESS (4.195s)
[122.100630] Subtest engines: SUCCESS (20.018s)
[122.279280] [028/161] (882s left) gem_exec_store (basic)
[122.593788] Starting subtest: basic
[122.617540] Subtest basic: SUCCESS (0.082s)
[122.939148] [029/161] (881s left) gem_flink_basic (bad-flink)
[123.230368] Starting subtest: bad-flink
[123.257696] Subtest bad-flink: SUCCESS (0.000s)
[123.440956] [030/161] (881s left) gem_flink_basic (bad-open)
[123.728208] Starting subtest: bad-open
[123.753966] Subtest bad-open: SUCCESS (0.000s)
[123.933188] [031/161] (881s left) gem_flink_basic (basic)
[124.173713] Starting subtest: basic
[124.194185] Subtest basic: SUCCESS (0.000s)
[124.363375] [032/161] (881s left) gem_flink_basic (double-flink)
[124.605136] Starting subtest: double-flink
[124.628153] Subtest double-flink: SUCCESS (0.000s)
[124.794579] [033/161] (880s left) gem_flink_basic (flink-lifetime)
[125.055211] Starting subtest: flink-lifetime
[125.075727] Subtest flink-lifetime: SUCCESS (0.004s)
[125.245217] [034/161] (880s left) gem_huc_copy (huc-copy)
[125.509838] Starting subtest: huc-copy
[125.550466] Subtest huc-copy: SKIP (0.000s)
[125.720716] [035/161] (880s left) gem_linear_blits (basic)
[126.177162] Starting subtest: basic
[126.203115] Subtest basic: SUCCESS (0.028s)
[126.478223] [036/161] (879s left) gem_mmap (basic)
[126.912915] Starting subtest: basic
[127.020720] Subtest basic: SUCCESS (0.001s)
[127.191545] [037/161] (879s left) gem_mmap_gtt (basic)
[127.441750] Starting subtest: basic
[127.464239] Subtest basic: SUCCESS (0.002s)
[127.634460] [038/161] (878s left) gem_render_linear_blits (basic)
[127.894316] Starting subtest: basic
[128.002542] Subtest basic: SUCCESS (0.101s)
[128.168280] [039/161] (878s left) gem_render_tiled_blits (basic)
[128.445964] Starting subtest: basic
[128.574151] Subtest basic: SUCCESS (0.124s)
[128.741413] [040/161] (878s left) gem_ringfill (basic-all)
[129.318112] Starting subtest: basic-all
[130.532320] Subtest basic-all: SUCCESS (1.239s)
[130.763281] [041/161] (876s left) gem_softpin (allocator-basic)
[131.040784] Starting subtest: allocator-basic
[131.276915] Subtest allocator-basic: SUCCESS (0.281s)
[131.517619] [042/161] (875s left) gem_softpin (allocator-basic-reserve)
[131.762903] Starting subtest: allocator-basic-reserve
[131.996314] Subtest allocator-basic-reserve: SUCCESS (0.280s)
[132.227802] [043/161] (875s left) gem_softpin (safe-alignment)
[132.467258] Starting subtest: safe-alignment
[132.487636] Subtest safe-alignment: SUCCESS (0.036s)
[132.713801] [044/161] (874s left) gem_sync (basic-all)
[133.128525] Starting subtest: basic-all
[135.430242] Subtest basic-all: SUCCESS (2.306s)
[135.605289] [045/161] (872s left) gem_sync (basic-each)
[136.045217] Starting subtest: basic-each
[138.392336] Subtest basic-each: SUCCESS (2.488s)
[138.626797] [046/161] (869s left) gem_tiled_blits (basic)
[138.970113] Starting subtest: basic
[139.029253] Subtest basic: SUCCESS (0.062s)
[139.317843] [047/161] (868s left) gem_tiled_fence_blits (basic)
[139.841618] Starting subtest: basic
[139.886530] Subtest basic: SUCCESS (0.045s)
[140.180228] [048/161] (868s left) gem_tiled_pread_basic
[141.037889] [049/161] (867s left) gem_wait (busy@all-engines)
[141.288531] Starting subtest: busy
[141.310294] Starting dynamic subtest: all-engines
[141.826594] Dynamic subtest all-engines: SUCCESS (0.535s)
[141.848284] Subtest busy: SUCCESS (0.536s)
[142.181610] [050/161] (866s left) gem_wait (wait@all-engines)
[142.448644] Starting subtest: wait
[142.470865] Starting dynamic subtest: all-engines
[143.503508] Dynamic subtest all-engines: SUCCESS (1.046s)
[143.525361] Subtest wait: SUCCESS (1.047s)
[143.849083] [051/161] (864s left) i915_getparams_basic (basic-eu-total)
[144.112955] Starting subtest: basic-eu-total
[144.162407] Subtest basic-eu-total: SUCCESS (0.000s)
[144.331481] [052/161] (864s left) i915_getparams_basic (basic-subslice-total)
[144.592320] Starting subtest: basic-subslice-total
[144.636129] Subtest basic-subslice-total: SUCCESS (0.000s)
[144.808864] [053/161] (864s left) i915_hangman (error-state-basic)
[145.074310] Starting subtest: error-state-basic
[145.097069] Subtest error-state-basic: SUCCESS (0.019s)
[145.283738] [054/161] (864s left) i915_pciid
[145.739563] [055/161] (863s left) kms_addfb_basic (addfb25-4-tiled)
[146.158153] Starting subtest: addfb25-4-tiled
[146.177420] Subtest addfb25-4-tiled: SUCCESS (0.001s)
[146.368343] [056/161] (863s left) kms_addfb_basic (addfb25-bad-modifier)
[146.718705] Starting subtest: addfb25-bad-modifier
[146.739221] Subtest addfb25-bad-modifier: SUCCESS (0.001s)
[146.930443] [057/161] (862s left) kms_addfb_basic (addfb25-framebuffer-vs-set-tiling)
[147.259627] Starting subtest: addfb25-framebuffer-vs-set-tiling
[147.281795] Subtest addfb25-framebuffer-vs-set-tiling: SUCCESS (0.000s)
[147.458442] [058/161] (862s left) kms_addfb_basic (addfb25-modifier-no-flag)
[147.811278] Starting subtest: addfb25-modifier-no-flag
[147.833742] Subtest addfb25-modifier-no-flag: SUCCESS (0.000s)
[148.036808] [059/161] (862s left) kms_addfb_basic (addfb25-x-tiled-legacy)
[148.387327] Starting subtest: addfb25-x-tiled-legacy
[148.408818] Subtest addfb25-x-tiled-legacy: SUCCESS (0.000s)
[148.583142] [060/161] (861s left) kms_addfb_basic (addfb25-x-tiled-mismatch-legacy)
[148.934664] Starting subtest: addfb25-x-tiled-mismatch-legacy
[148.954976] Subtest addfb25-x-tiled-mismatch-legacy: SUCCESS (0.001s)
[149.133192] [061/161] (861s left) kms_addfb_basic (addfb25-yf-tiled-legacy)
[149.483989] Starting subtest: addfb25-yf-tiled-legacy
[149.504301] Subtest addfb25-yf-tiled-legacy: SUCCESS (0.000s)
[149.698086] [062/161] (861s left) kms_addfb_basic (addfb25-y-tiled-legacy)
[150.050430] Starting subtest: addfb25-y-tiled-legacy
[150.070847] Subtest addfb25-y-tiled-legacy: SUCCESS (0.000s)
[150.248737] [063/161] (860s left) kms_addfb_basic (addfb25-y-tiled-small-legacy)
[150.618884] Starting subtest: addfb25-y-tiled-small-legacy
[150.645726] Subtest addfb25-y-tiled-small-legacy: SUCCESS (0.001s)
[150.834858] [064/161] (860s left) kms_addfb_basic (bad-pitch-0)
[151.101149] Starting subtest: bad-pitch-0
[151.127442] Subtest bad-pitch-0: SUCCESS (0.000s)
[151.419874] [065/161] (859s left) kms_addfb_basic (bad-pitch-1024)
[151.681025] Starting subtest: bad-pitch-1024
[151.700483] Subtest bad-pitch-1024: SUCCESS (0.000s)
[151.968812] [066/161] (859s left) kms_addfb_basic (bad-pitch-128)
[152.212266] Starting subtest: bad-pitch-128
[152.233844] Subtest bad-pitch-128: SUCCESS (0.000s)
[152.533731] [067/161] (859s left) kms_addfb_basic (bad-pitch-256)
[152.776293] Starting subtest: bad-pitch-256
[152.798249] Subtest bad-pitch-256: SUCCESS (0.000s)
[153.089823] [068/161] (858s left) kms_addfb_basic (bad-pitch-32)
[153.348793] Starting subtest: bad-pitch-32
[153.370593] Subtest bad-pitch-32: SUCCESS (0.000s)
[153.631445] [069/161] (858s left) kms_addfb_basic (bad-pitch-63)
[153.873289] Starting subtest: bad-pitch-63
[153.893107] Subtest bad-pitch-63: SUCCESS (0.000s)
[154.187799] [070/161] (857s left) kms_addfb_basic (bad-pitch-65536)
[154.427762] Starting subtest: bad-pitch-65536
[154.448591] Subtest bad-pitch-65536: SUCCESS (0.001s)
[154.747992] [071/161] (857s left) kms_addfb_basic (bad-pitch-999)
[154.992366] Starting subtest: bad-pitch-999
[155.015298] Subtest bad-pitch-999: SUCCESS (0.000s)
[155.299599] [072/161] (857s left) kms_addfb_basic (basic)
[155.560746] Starting subtest: basic
[155.582412] Subtest basic: SUCCESS (0.000s)
[155.847401] [073/161] (856s left) kms_addfb_basic (basic-x-tiled-legacy)
[156.108783] Starting subtest: basic-x-tiled-legacy
[156.131028] Subtest basic-x-tiled-legacy: SUCCESS (0.000s)
[156.395511] [074/161] (856s left) kms_addfb_basic (basic-y-tiled-legacy)
[156.654784] Starting subtest: basic-y-tiled-legacy
[156.674360] Subtest basic-y-tiled-legacy: SUCCESS (0.001s)
[156.933429] [075/161] (855s left) kms_addfb_basic (bo-too-small)
[157.283781] Starting subtest: bo-too-small
[157.303680] Subtest bo-too-small: SUCCESS (0.001s)
[157.486470] [076/161] (855s left) kms_addfb_basic (bo-too-small-due-to-tiling)
[157.839413] Starting subtest: bo-too-small-due-to-tiling
[157.860971] Subtest bo-too-small-due-to-tiling: SUCCESS (0.001s)
[158.031145] [077/161] (855s left) kms_addfb_basic (clobberred-modifier)
[158.286726] Starting subtest: clobberred-modifier
[158.308945] Subtest clobberred-modifier: SUCCESS (0.000s)
[158.578306] [078/161] (854s left) kms_addfb_basic (framebuffer-vs-set-tiling)
[158.815071] Starting subtest: framebuffer-vs-set-tiling
[158.834815] Subtest framebuffer-vs-set-tiling: SUCCESS (0.000s)
[159.112720] [079/161] (854s left) kms_addfb_basic (invalid-get-prop)
[159.380094] Starting subtest: invalid-get-prop
[159.405702] Subtest invalid-get-prop: SUCCESS (0.000s)
[159.668929] [080/161] (854s left) kms_addfb_basic (invalid-get-prop-any)
[159.952146] Starting subtest: invalid-get-prop-any
[159.979523] Subtest invalid-get-prop-any: SUCCESS (0.000s)
[160.234573] [081/161] (853s left) kms_addfb_basic (invalid-set-prop)
[160.510494] Starting subtest: invalid-set-prop
[160.534930] Subtest invalid-set-prop: SUCCESS (0.000s)
[160.822925] [082/161] (853s left) kms_addfb_basic (invalid-set-prop-any)
[161.099081] Starting subtest: invalid-set-prop-any
[161.126398] Subtest invalid-set-prop-any: SUCCESS (0.000s)
[161.389088] [083/161] (852s left) kms_addfb_basic (no-handle)
[161.649059] Starting subtest: no-handle
[161.671351] Subtest no-handle: SUCCESS (0.000s)
[161.927193] [084/161] (852s left) kms_addfb_basic (size-max)
[162.278320] Starting subtest: size-max
[162.301353] Subtest size-max: SUCCESS (0.000s)
[162.468914] [085/161] (852s left) kms_addfb_basic (small-bo)
[162.821364] Starting subtest: small-bo
[162.842447] Subtest small-bo: SUCCESS (0.000s)
[163.015907] [086/161] (851s left) kms_addfb_basic (tile-pitch-mismatch)
[163.281236] Starting subtest: tile-pitch-mismatch
[163.300960] Subtest tile-pitch-mismatch: SUCCESS (0.001s)
[163.588072] [087/161] (851s left) kms_addfb_basic (too-high)
[163.939184] Starting subtest: too-high
[163.961545] Subtest too-high: SUCCESS (0.003s)
[164.140951] [088/161] (851s left) kms_addfb_basic (too-wide)
[164.492909] Starting subtest: too-wide
[164.516092] Subtest too-wide: SUCCESS (0.000s)
[164.699424] [089/161] (850s left) kms_addfb_basic (unused-handle)
[164.941536] Starting subtest: unused-handle
[164.961405] Subtest unused-handle: SUCCESS (0.000s)
[165.271899] [090/161] (850s left) kms_addfb_basic (unused-modifier)
[165.532347] Starting subtest: unused-modifier
[165.551994] Subtest unused-modifier: SUCCESS (0.000s)
[165.814791] [091/161] (849s left) kms_addfb_basic (unused-offsets)
[166.077193] Starting subtest: unused-offsets
[166.096963] Subtest unused-offsets: SUCCESS (0.000s)
[166.384791] [092/161] (849s left) kms_addfb_basic (unused-pitches)
[166.601979] Starting subtest: unused-pitches
[166.621690] Subtest unused-pitches: SUCCESS (0.000s)
[166.901306] [093/161] (849s left) kms_busy (basic)
[167.242862] Starting subtest: basic
[167.265561] Starting dynamic subtest: flip
[168.073231] Dynamic subtest flip: SUCCESS (0.819s)
[168.153735] Starting dynamic subtest: modeset
[169.534945] Dynamic subtest modeset: SUCCESS (1.470s)
[169.561538] Subtest basic: SUCCESS (2.293s)
[170.026756] [094/161] (846s left) kms_prop_blob (basic)
[170.277653] Starting subtest: basic
[170.299980] Subtest basic: SUCCESS (0.000s)
[170.483141] [095/161] (845s left) kms_cursor_legacy (basic-busy-flip-before-cursor-atomic)
[170.843396] Starting subtest: basic-busy-flip-before-cursor-atomic
[170.890144] Subtest basic-busy-flip-before-cursor-atomic: SKIP (0.000s)
[171.066692] [096/161] (845s left) kms_cursor_legacy (basic-busy-flip-before-cursor-legacy)
[171.424111] Starting subtest: basic-busy-flip-before-cursor-legacy
[171.468675] Subtest basic-busy-flip-before-cursor-legacy: SKIP (0.000s)
[171.643586] [097/161] (845s left) kms_cursor_legacy (basic-flip-after-cursor-atomic)
[171.990501] Starting subtest: basic-flip-after-cursor-atomic
[172.983150] Subtest basic-flip-after-cursor-atomic: SUCCESS (1.001s)
[173.445387] [098/161] (843s left) kms_cursor_legacy (basic-flip-after-cursor-legacy)
[173.797063] Starting subtest: basic-flip-after-cursor-legacy
[174.782537] Subtest basic-flip-after-cursor-legacy: SUCCESS (0.998s)
[175.233542] [099/161] (841s left) kms_cursor_legacy (basic-flip-after-cursor-varying-size)
[175.588847] Starting subtest: basic-flip-after-cursor-varying-size
[176.573390] Subtest basic-flip-after-cursor-varying-size: SUCCESS (1.000s)
[177.029867] [100/161] (840s left) kms_cursor_legacy (basic-flip-before-cursor-atomic)
[177.381228] Starting subtest: basic-flip-before-cursor-atomic
[178.351994] Subtest basic-flip-before-cursor-atomic: SUCCESS (0.987s)
[178.826304] [101/161] (838s left) kms_cursor_legacy (basic-flip-before-cursor-legacy)
[179.182134] Starting subtest: basic-flip-before-cursor-legacy
[180.163833] Subtest basic-flip-before-cursor-legacy: SUCCESS (0.992s)
[180.617262] [102/161] (836s left) kms_cursor_legacy (basic-flip-before-cursor-varying-size)
[180.973744] Starting subtest: basic-flip-before-cursor-varying-size
[181.943206] Subtest basic-flip-before-cursor-varying-size: SUCCESS (0.982s)
[182.394772] [103/161] (835s left) kms_dsc (dsc-basic)
[182.767176] Subtest dsc-basic: SKIP (0.000s)
[182.945388] [104/161] (834s left) kms_flip (basic-flip-vs-dpms)
[183.344354] Starting subtest: basic-flip-vs-dpms
[183.364629] Starting dynamic subtest: A-DP1
[184.591541] Dynamic subtest A-DP1: SUCCESS (1.269s)
[184.639833] Starting dynamic subtest: B-DP1
[185.778598] Dynamic subtest B-DP1: SUCCESS (1.182s)
[185.825234] Starting dynamic subtest: C-DP1
[186.986502] Dynamic subtest C-DP1: SUCCESS (1.201s)
[187.008858] Subtest basic-flip-vs-dpms: SUCCESS (3.662s)
[187.468705] [105/161] (830s left) kms_flip (basic-flip-vs-modeset)
[187.834605] Starting subtest: basic-flip-vs-modeset
[187.855533] Starting dynamic subtest: A-DP1
[189.101118] Dynamic subtest A-DP1: SUCCESS (1.272s)
[189.147524] Starting dynamic subtest: B-DP1
[190.265115] Dynamic subtest B-DP1: SUCCESS (1.171s)
[190.312365] Starting dynamic subtest: C-DP1
[191.449078] Dynamic subtest C-DP1: SUCCESS (1.165s)
[191.472104] Subtest basic-flip-vs-modeset: SUCCESS (3.619s)
[191.910190] [106/161] (826s left) kms_flip (basic-flip-vs-wf_vblank)
[192.293437] Starting subtest: basic-flip-vs-wf_vblank
[192.314903] Starting dynamic subtest: A-DP1
[193.723233] Dynamic subtest A-DP1: SUCCESS (1.451s)
[193.773473] Starting dynamic subtest: B-DP1
[195.148116] Dynamic subtest B-DP1: SUCCESS (1.423s)
[195.197175] Starting dynamic subtest: C-DP1
[196.566878] Dynamic subtest C-DP1: SUCCESS (1.422s)
[196.588488] Subtest basic-flip-vs-wf_vblank: SUCCESS (4.306s)
[198.201509] [107/161] (820s left) kms_flip (basic-plain-flip)
[198.575195] Starting subtest: basic-plain-flip
[198.598292] Starting dynamic subtest: A-DP1
[199.719787] Dynamic subtest A-DP1: SUCCESS (1.171s)
[199.768340] Starting dynamic subtest: B-DP1
[200.869240] Dynamic subtest B-DP1: SUCCESS (1.136s)
[200.922165] Starting dynamic subtest: C-DP1
[202.000529] Dynamic subtest C-DP1: SUCCESS (1.137s)
[202.022495] Subtest basic-plain-flip: SUCCESS (3.456s)
[202.539732] [108/161] (816s left) kms_force_connector_basic (force-connector-state)
[202.834082] Subtest force-connector-state: SKIP (0.000s)
[203.006440] [109/161] (815s left) kms_force_connector_basic (force-edid)
[203.293850] Subtest force-edid: SKIP (0.000s)
[203.466432] [110/161] (815s left) kms_force_connector_basic (force-load-detect)
[203.731107] Subtest force-load-detect: SKIP (0.000s)
[203.904126] [111/161] (815s left) kms_force_connector_basic (prune-stale-modes)
[204.194818] Subtest prune-stale-modes: SKIP (0.000s)
[204.373668] [112/161] (814s left) kms_frontbuffer_tracking (basic)
[204.783605] Starting subtest: basic
[208.533245] Subtest basic: SUCCESS (3.814s)
[208.751979] [113/161] (810s left) kms_hdmi_inject (inject-audio)
[209.042421] Subtest inject-audio: SKIP (0.000s)
[209.216684] [114/161] (810s left) kms_pipe_crc_basic (compare-crc-sanitycheck-xr24)
[209.579772] Starting subtest: compare-crc-sanitycheck-xr24
[209.601727] Starting dynamic subtest: pipe-A-DP-1
[210.037661] Dynamic subtest pipe-A-DP-1: SUCCESS (0.471s)
[210.087896] Starting dynamic subtest: pipe-B-DP-1
[210.676610] Dynamic subtest pipe-B-DP-1: SUCCESS (0.631s)
[210.727696] Starting dynamic subtest: pipe-C-DP-1
[211.318926] Dynamic subtest pipe-C-DP-1: SUCCESS (0.641s)
[211.340460] Subtest compare-crc-sanitycheck-xr24: SUCCESS (1.758s)
[211.780469] [115/161] (808s left) kms_pipe_crc_basic (compare-crc-sanitycheck-nv12)
[212.120614] Starting subtest: compare-crc-sanitycheck-nv12
[212.145619] Starting dynamic subtest: pipe-A-DP-1
[212.196357] Dynamic subtest pipe-A-DP-1: SKIP (0.001s)
[212.278361] Starting dynamic subtest: pipe-B-DP-1
[212.334784] Dynamic subtest pipe-B-DP-1: SKIP (0.001s)
[212.408083] Starting dynamic subtest: pipe-C-DP-1
[212.463414] Dynamic subtest pipe-C-DP-1: SKIP (0.001s)
[212.516074] Subtest compare-crc-sanitycheck-nv12: SKIP (0.017s)
[212.717306] [116/161] (807s left) kms_pipe_crc_basic (hang-read-crc)
[213.092231] Starting subtest: hang-read-crc
[213.112642] Starting dynamic subtest: pipe-A-DP-1
[214.415900] Dynamic subtest pipe-A-DP-1: SUCCESS (1.328s)
[214.465108] Starting dynamic subtest: pipe-B-DP-1
[215.928830] Dynamic subtest pipe-B-DP-1: SUCCESS (1.500s)
[215.977512] Starting dynamic subtest: pipe-C-DP-1
[217.401137] Dynamic subtest pipe-C-DP-1: SUCCESS (1.486s)
[217.426158] Subtest hang-read-crc: SUCCESS (4.329s)
[217.843973] [117/161] (802s left) kms_pipe_crc_basic (nonblocking-crc)
[218.207234] Starting subtest: nonblocking-crc
[218.227181] Starting dynamic subtest: pipe-A-DP-1
[218.819041] Dynamic subtest pipe-A-DP-1: SUCCESS (0.622s)
[218.882280] Starting dynamic subtest: pipe-B-DP-1
[219.638798] Dynamic subtest pipe-B-DP-1: SUCCESS (0.800s)
[219.693729] Starting dynamic subtest: pipe-C-DP-1
[220.450243] Dynamic subtest pipe-C-DP-1: SUCCESS (0.802s)
[220.470362] Subtest nonblocking-crc: SUCCESS (2.240s)
[220.888732] [118/161] (799s left) kms_pipe_crc_basic (nonblocking-crc-frame-sequence)
[221.227659] Starting subtest: nonblocking-crc-frame-sequence
[221.258670] Starting dynamic subtest: pipe-A-DP-1
[221.880691] Dynamic subtest pipe-A-DP-1: SUCCESS (0.650s)
[221.941071] Starting dynamic subtest: pipe-B-DP-1
[222.701954] Dynamic subtest pipe-B-DP-1: SUCCESS (0.804s)
[222.759893] Starting dynamic subtest: pipe-C-DP-1
[223.545811] Dynamic subtest pipe-C-DP-1: SUCCESS (0.816s)
[223.568598] Subtest nonblocking-crc-frame-sequence: SUCCESS (2.285s)
[223.941731] [119/161] (796s left) kms_pipe_crc_basic (read-crc)
[224.300575] Starting subtest: read-crc
[224.322611] Starting dynamic subtest: pipe-A-DP-1
[224.883532] Dynamic subtest pipe-A-DP-1: SUCCESS (0.583s)
[224.932620] Starting dynamic subtest: pipe-B-DP-1
[225.630322] Dynamic subtest pipe-B-DP-1: SUCCESS (0.741s)
[225.679640] Starting dynamic subtest: pipe-C-DP-1
[226.354846] Dynamic subtest pipe-C-DP-1: SUCCESS (0.731s)
[226.379078] Subtest read-crc: SUCCESS (2.071s)
[226.796753] [120/161] (793s left) kms_pipe_crc_basic (read-crc-frame-sequence)
[227.165254] Starting subtest: read-crc-frame-sequence
[227.186370] Starting dynamic subtest: pipe-A-DP-1
[227.743118] Dynamic subtest pipe-A-DP-1: SUCCESS (0.580s)
[227.813093] Starting dynamic subtest: pipe-B-DP-1
[228.491363] Dynamic subtest pipe-B-DP-1: SUCCESS (0.747s)
[228.569881] Starting dynamic subtest: pipe-C-DP-1
[229.248684] Dynamic subtest pipe-C-DP-1: SUCCESS (0.748s)
[229.272995] Subtest read-crc-frame-sequence: SUCCESS (2.092s)
[229.663194] [121/161] (791s left) kms_pm_backlight (basic-brightness)
[230.052671] Subtest basic-brightness: SKIP (0.000s)
[230.247777] [122/161] (790s left) kms_pm_rpm (basic-pci-d3-state)
[232.124439] Starting subtest: basic-pci-d3-state
[232.782082] Subtest basic-pci-d3-state: SUCCESS (0.676s)
[233.103143] [123/161] (788s left) kms_pm_rpm (basic-rte)
[233.350827] Starting subtest: basic-rte
[235.576282] Subtest basic-rte: SUCCESS (2.258s)
[236.556974] [124/161] (784s left) kms_psr (psr-primary-page-flip)
[236.947370] Subtest psr-primary-page-flip: SKIP (0.000s)
[237.124573] [125/161] (784s left) kms_psr (psr-cursor-plane-move)
[237.504862] Subtest psr-cursor-plane-move: SKIP (0.000s)
[237.680976] [126/161] (784s left) kms_psr (psr-sprite-plane-onoff)
[238.066647] Subtest psr-sprite-plane-onoff: SKIP (0.000s)
[238.241702] [127/161] (783s left) kms_psr (psr-primary-mmap-gtt)
[238.602159] Subtest psr-primary-mmap-gtt: SKIP (0.000s)
[238.775531] [128/161] (783s left) kms_setmode (basic-clone-single-crtc)
[239.025356] Starting subtest: basic-clone-single-crtc
[239.090152] Subtest basic-clone-single-crtc: SKIP (0.000s)
[239.266466] [129/161] (783s left) i915_pm_rps (basic-api)
[239.517432] Starting subtest: basic-api
[239.539111] Subtest basic-api: SUCCESS (0.007s)
[239.736281] [130/161] (782s left) prime_self_import (basic-llseek-bad)
[239.967420] Starting subtest: basic-llseek-bad
[240.011833] Subtest basic-llseek-bad: SUCCESS (0.005s)
[240.185469] [131/161] (782s left) prime_self_import (basic-llseek-size)
[240.409752] Starting subtest: basic-llseek-size
[240.452653] Subtest basic-llseek-size: SUCCESS (0.008s)
[240.629178] [132/161] (782s left) prime_self_import (basic-with_fd_dup)
[240.879259] Starting subtest: basic-with_fd_dup
[240.940924] Subtest basic-with_fd_dup: SUCCESS (0.007s)
[241.130693] [133/161] (782s left) prime_self_import (basic-with_one_bo)
[241.394708] Starting subtest: basic-with_one_bo
[241.448110] Subtest basic-with_one_bo: SUCCESS (0.007s)
[241.639481] [134/161] (781s left) prime_self_import (basic-with_one_bo_two_files)
[241.901894] Starting subtest: basic-with_one_bo_two_files
[241.956858] Subtest basic-with_one_bo_two_files: SUCCESS (0.006s)
[242.139848] [135/161] (781s left) prime_self_import (basic-with_two_bos)
[242.380795] Starting subtest: basic-with_two_bos
[242.424954] Subtest basic-with_two_bos: SUCCESS (0.007s)
[242.625259] [136/161] (781s left) prime_vgem (basic-fence-flip)
[242.899680] Starting subtest: basic-fence-flip
[243.537509] Subtest basic-fence-flip: SUCCESS (0.669s)
[243.745687] [137/161] (780s left) prime_vgem (basic-fence-mmap)
[244.035337] Starting subtest: basic-fence-mmap
[244.078980] Subtest basic-fence-mmap: SUCCESS (0.072s)
[244.273344] [138/161] (779s left) prime_vgem (basic-fence-read)
[244.558646] Starting subtest: basic-fence-read
[244.661241] Subtest basic-fence-read: SUCCESS (0.146s)
[244.865698] [139/161] (779s left) prime_vgem (basic-gtt)
[245.150255] Starting subtest: basic-gtt
[245.172059] Subtest basic-gtt: SUCCESS (0.032s)
[245.373789] [140/161] (779s left) prime_vgem (basic-read)
[245.668849] Starting subtest: basic-read
[245.689232] Subtest basic-read: SUCCESS (0.066s)
[245.893452] [141/161] (778s left) prime_vgem (basic-write)
[246.180848] Starting subtest: basic-write
[246.227304] Subtest basic-write: SUCCESS (0.093s)
[246.426645] [142/161] (778s left) vgem_basic (setversion)
[246.683434] Starting subtest: setversion
[246.727858] Subtest setversion: SUCCESS (0.000s)
[246.904653] [143/161] (778s left) vgem_basic (create)
[247.148801] Starting subtest: create
[247.169135] Subtest create: SUCCESS (0.001s)
[247.343953] [144/161] (777s left) vgem_basic (debugfs)
[247.607303] Starting subtest: debugfs
[247.628380] Subtest debugfs: SUCCESS (0.001s)
[247.800435] [145/161] (777s left) vgem_basic (dmabuf-export)
[248.054547] Starting subtest: dmabuf-export
[248.098099] Subtest dmabuf-export: SUCCESS (0.005s)
[248.273342] [146/161] (777s left) vgem_basic (dmabuf-fence)
[248.537627] Starting subtest: dmabuf-fence
[248.559626] Subtest dmabuf-fence: SUCCESS (0.002s)
[248.738573] [147/161] (777s left) vgem_basic (dmabuf-fence-before)
[249.008755] Starting subtest: dmabuf-fence-before
[249.029337] Subtest dmabuf-fence-before: SUCCESS (0.001s)
[249.205989] [148/161] (776s left) vgem_basic (dmabuf-mmap)
[249.477953] Starting subtest: dmabuf-mmap
[249.498385] Subtest dmabuf-mmap: SUCCESS (0.038s)
[249.673422] [149/161] (776s left) vgem_basic (mmap)
[249.937156] Starting subtest: mmap
[249.959543] Subtest mmap: SUCCESS (0.024s)
[250.133295] [150/161] (776s left) vgem_basic (second-client)
[250.391852] Starting subtest: second-client
[250.437637] Subtest second-client: SUCCESS (0.032s)
[250.611625] [151/161] (775s left) vgem_basic (sysfs)
[250.856507] Starting subtest: sysfs
[250.877066] Subtest sysfs: SUCCESS (0.001s)
[251.058440] [152/161] (775s left) core_hotunplug (unbind-rebind)
[251.442093] Starting subtest: unbind-rebind
[256.079154] Subtest unbind-rebind: SUCCESS (4.642s)
[256.259237] [153/161] (770s left) vgem_basic (unload)
[256.512166] Starting subtest: unload
[285.676431] Subtest unload: SUCCESS (29.202s)
[285.946264] [154/161] (741s left) i915_module_load (reload)
[286.198215] Starting subtest: reload
[292.321586] Subtest reload: SUCCESS (6.159s)
[292.606984] [155/161] (734s left) gem_lmem_swapping (basic)
[292.927506] Subtest basic: SKIP (0.000s)
[293.098572] [156/161] (734s left) gem_lmem_swapping (parallel-random-engines)
[293.385156] Subtest parallel-random-engines: SKIP (0.000s)
[293.558768] [157/161] (734s left) gem_lmem_swapping (random-engines)
[293.850689] Subtest random-engines: SKIP (0.000s)
[294.022203] [158/161] (733s left) gem_lmem_swapping (verify-random)
[294.311200] Subtest verify-random: SKIP (0.000s)
[294.489230] [159/161] (733s left) i915_pm_rpm (module-reload)
[296.649548] Starting subtest: module-reload
[311.417361] Subtest module-reload: SUCCESS (14.781s)
[311.554070] [FACT i915_pm_rpm (module-reload)] deleted: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[311.557661] [FACT i915_pm_rpm (module-reload)] deleted: kernel.kmod_is_loaded.i915: true
[311.583171] [160/161] (716s left) i915_selftest (live)
[311.834824] Starting subtest: live
[311.857778] Starting dynamic subtest: sanitycheck
[315.924110] Dynamic subtest sanitycheck: SUCCESS (4.107s)
[315.967543] Starting dynamic subtest: uncore
[319.888630] Dynamic subtest uncore: SUCCESS (3.961s)
[319.944075] Starting dynamic subtest: workarounds
[323.999611] Dynamic subtest workarounds: SUCCESS (4.104s)
[324.041080] Starting dynamic subtest: gt_engines
[328.305409] Dynamic subtest gt_engines: SUCCESS (4.301s)
[328.356404] Starting dynamic subtest: gt_timelines
[351.336110] Dynamic subtest gt_timelines: SUCCESS (23.025s)
[351.379666] Starting dynamic subtest: gt_contexts
[355.523865] Dynamic subtest gt_contexts: SUCCESS (4.185s)
[355.565932] Starting dynamic subtest: gt_lrc
[362.255579] Dynamic subtest gt_lrc: SUCCESS (6.727s)
[362.299916] Starting dynamic subtest: gt_mocs
[366.257925] Dynamic subtest gt_mocs: SUCCESS (3.994s)
[366.300177] Starting dynamic subtest: gt_pm
[379.930590] Dynamic subtest gt_pm: SUCCESS (13.667s)
[379.974177] Starting dynamic subtest: gt_heartbeat
[383.981843] Dynamic subtest gt_heartbeat: SUCCESS (4.047s)
[384.043267] Starting dynamic subtest: gt_tlb
[388.670886] Dynamic subtest gt_tlb: SUCCESS (4.682s)
[388.730348] Starting dynamic subtest: requests
[398.925125] Dynamic subtest requests: SUCCESS (10.245s)
[398.991146] Starting dynamic subtest: migrate
[405.997392] Dynamic subtest migrate: SUCCESS (7.074s)
[406.041580] Starting dynamic subtest: active
[409.850869] Dynamic subtest active: SUCCESS (3.848s)
[409.894428] Starting dynamic subtest: objects
[416.214926] Dynamic subtest objects: SUCCESS (6.361s)
[416.259232] Starting dynamic subtest: mman
[427.018933] Dynamic subtest mman: SUCCESS (10.795s)
[427.062635] Starting dynamic subtest: dmabuf
[430.901564] Dynamic subtest dmabuf: SUCCESS (3.880s)
[430.963098] Starting dynamic subtest: vma
[434.736988] Dynamic subtest vma: SUCCESS (3.831s)
[434.778593] Starting dynamic subtest: coherency
[442.177900] Dynamic subtest coherency: SUCCESS (7.433s)
[442.221282] Starting dynamic subtest: gtt
[453.808991] Dynamic subtest gtt: SUCCESS (11.628s)
[453.850965] Starting dynamic subtest: gem
[459.285874] Dynamic subtest gem: SUCCESS (5.469s)
[459.346612] Starting dynamic subtest: evict
[468.387974] Dynamic subtest evict: SUCCESS (9.098s)
[468.429872] Starting dynamic subtest: hugepages
[479.066901] Dynamic subtest hugepages: SUCCESS (10.677s)
[479.114697] Starting dynamic subtest: gem_contexts
[505.759166] Dynamic subtest gem_contexts: SUCCESS (26.516s)
[505.803312] Starting dynamic subtest: client
[509.507686] Dynamic subtest client: SUCCESS (3.913s)
[509.550181] Starting dynamic subtest: gem_migrate
[513.357868] Dynamic subtest gem_migrate: SUCCESS (3.847s)
[513.402406] Starting dynamic subtest: reset
[527.950498] Dynamic subtest reset: SUCCESS (14.587s)
[527.992314] Starting dynamic subtest: memory_region
[531.926187] Dynamic subtest memory_region: SUCCESS (3.965s)
[531.973731] Starting dynamic subtest: hangcheck
[559.102357] Dynamic subtest hangcheck: SUCCESS (27.180s)
[559.163117] Starting dynamic subtest: execlists
[573.843393] Dynamic subtest execlists: SUCCESS (14.738s)
[573.903436] Starting dynamic subtest: ring_submission
[577.739574] Dynamic subtest ring_submission: SUCCESS (3.889s)
[577.799569] Starting dynamic subtest: perf
[581.757224] Dynamic subtest perf: SUCCESS (4.013s)
[581.815620] Starting dynamic subtest: slpc
[585.539362] Dynamic subtest slpc: SUCCESS (3.780s)
[585.583935] Starting dynamic subtest: guc
[589.370138] Dynamic subtest guc: SUCCESS (3.828s)
[589.456679] Starting dynamic subtest: guc_multi_lrc
[593.183536] Dynamic subtest guc_multi_lrc: SUCCESS (3.802s)
[593.247520] Starting dynamic subtest: guc_hang
[597.056298] Dynamic subtest guc_hang: SUCCESS (3.871s)
[597.106575] Starting dynamic subtest: late_gt_pm
[600.904124] Dynamic subtest late_gt_pm: SUCCESS (3.847s)
[600.925570] Subtest live: SUCCESS (289.086s)
[601.081389] [161/161] (427s left) dmabuf (all-tests)
[601.325838] Starting subtest: all-tests
[601.348332] Starting dynamic subtest: sanitycheck
[601.431859] Dynamic subtest sanitycheck: SUCCESS (0.104s)
[601.479684] Starting dynamic subtest: dma_fence
[601.692620] Dynamic subtest