[85.982466] Initializing watchdogs
[85.982607]   /dev/watchdog0
[85.993098] [001/165] (960s left) i915_module_load (load)
[86.254041] Starting subtest: load
[88.825791] Subtest load: SUCCESS (2.572s)
[88.851341] [002/165] (957s left) core_auth (basic-auth)
[88.947552] Starting subtest: basic-auth
[88.949910] Subtest basic-auth: SUCCESS (0.001s)
[89.030850] [003/165] (957s left) debugfs_test (read_all_entries)
[89.130283] Starting subtest: read_all_entries
[89.211584] Subtest read_all_entries: SUCCESS (0.082s)
[89.329181] [004/165] (957s left) debugfs_test (basic-hwmon)
[89.424367] Starting subtest: basic-hwmon
[89.426536] Subtest basic-hwmon: SKIP (0.000s)
[89.527896] [005/165] (957s left) fbdev (eof)
[89.630385] Starting subtest: eof
[89.631744] Subtest eof: SUCCESS (0.001s)
[89.692738] [006/165] (956s left) fbdev (info)
[89.807473] Starting subtest: info
[89.810421] Subtest info: SUCCESS (0.000s)
[89.877335] [007/165] (956s left) fbdev (nullptr)
[89.977972] Starting subtest: nullptr
[89.979640] Subtest nullptr: SUCCESS (0.000s)
[90.050392] [008/165] (956s left) fbdev (read)
[90.144445] Starting subtest: read
[97.225236] Subtest read: SUCCESS (7.046s)
[97.268787] [009/165] (949s left) fbdev (write)
[97.368958] Starting subtest: write
[101.138640] Subtest write: SUCCESS (3.735s)
[101.171401] [010/165] (945s left) gem_basic (bad-close)
[101.261111] Starting subtest: bad-close
[101.263203] Subtest bad-close: SUCCESS (0.000s)
[101.339327] [011/165] (945s left) gem_basic (create-close)
[101.429473] Starting subtest: create-close
[101.431517] Subtest create-close: SUCCESS (0.000s)
[101.526645] [012/165] (945s left) gem_basic (create-fd-close)
[101.616413] Starting subtest: create-fd-close
[101.618476] Subtest create-fd-close: SUCCESS (0.000s)
[101.727139] [013/165] (945s left) gem_busy (busy@all-engines)
[101.836543] Starting subtest: busy
[101.837671] Starting dynamic subtest: all-engines
[101.900855] Dynamic subtest all-engines: SUCCESS (0.064s)
[101.901987] Subtest busy: SUCCESS (0.065s)
[102.295289] [014/165] (944s left) gem_close_race (basic-process)
[102.407607] Starting subtest: basic-process
[102.475949] Subtest basic-process: SUCCESS (0.070s)
[102.680175] [015/165] (944s left) gem_close_race (basic-threads)
[102.792240] Starting subtest: basic-threads
[103.960039] Subtest basic-threads: SUCCESS (1.168s)
[104.211720] [016/165] (942s left) gem_ctx_create (basic)
[104.316671] Starting subtest: basic
[104.317838] Subtest basic: SUCCESS (0.000s)
[104.531270] [017/165] (942s left) gem_ctx_create (basic-files)
[104.637785] Starting subtest: basic-files
[106.664446] Subtest basic-files: SUCCESS (2.027s)
[106.908292] [018/165] (940s left) gem_ctx_exec (basic)
[107.003753] Starting subtest: basic
[107.007566] Subtest basic: SUCCESS (0.006s)
[107.114281] [019/165] (939s left) gem_exec_basic (basic)
[107.225134] Starting subtest: basic
[107.226509] Starting dynamic subtest: rcs0-smem
[107.227705] Dynamic subtest rcs0-smem: SUCCESS (0.002s)
[107.240070] Starting dynamic subtest: bcs0-smem
[107.241232] Dynamic subtest bcs0-smem: SUCCESS (0.001s)
[107.252906] Starting dynamic subtest: vcs0-smem
[107.254136] Dynamic subtest vcs0-smem: SUCCESS (0.001s)
[107.266222] Starting dynamic subtest: vecs0-smem
[107.267355] Dynamic subtest vecs0-smem: SUCCESS (0.001s)
[107.268404] Subtest basic: SUCCESS (0.006s)
[107.431386] [020/165] (939s left) gem_exec_create (basic)
[107.538403] Starting subtest: basic
[107.539581] Starting dynamic subtest: smem
[109.599712] Dynamic subtest smem: SUCCESS (2.039s)
[109.601467] Subtest basic: SUCCESS (2.039s)
[109.813316] [021/165] (937s left) gem_exec_fence (basic-busy)
[110.069668] Starting subtest: basic-busy
[110.071122] Starting dynamic subtest: rcs0
[110.095435] Dynamic subtest rcs0: SUCCESS (0.031s)
[110.109499] Starting dynamic subtest: bcs0
[110.118927] Dynamic subtest bcs0: SUCCESS (0.024s)
[110.130258] Starting dynamic subtest: vcs0
[110.153034] Dynamic subtest vcs0: SUCCESS (0.034s)
[110.165236] Starting dynamic subtest: vecs0
[110.188363] Dynamic subtest vecs0: SUCCESS (0.035s)
[110.190038] Subtest basic-busy: SUCCESS (0.125s)
[110.788929] [022/165] (936s left) gem_exec_fence (basic-wait)
[111.045206] Starting subtest: basic-wait
[111.046469] Starting dynamic subtest: rcs0
[111.068598] Dynamic subtest rcs0: SUCCESS (0.031s)
[111.083891] Starting dynamic subtest: bcs0
[111.100815] Dynamic subtest bcs0: SUCCESS (0.032s)
[111.113231] Starting dynamic subtest: vcs0
[111.122966] Dynamic subtest vcs0: SUCCESS (0.022s)
[111.135538] Starting dynamic subtest: vecs0
[111.164570] Dynamic subtest vecs0: SUCCESS (0.041s)
[111.166109] Subtest basic-wait: SUCCESS (0.128s)
[111.705929] [023/165] (935s left) gem_exec_fence (basic-await)
[111.960725] Starting subtest: basic-await
[111.961953] Starting dynamic subtest: rcs0
[112.054270] Dynamic subtest rcs0: SUCCESS (0.100s)
[112.081724] Starting dynamic subtest: bcs0
[112.179571] Dynamic subtest bcs0: SUCCESS (0.124s)
[112.207860] Starting dynamic subtest: vcs0
[112.320522] Dynamic subtest vcs0: SUCCESS (0.132s)
[112.347418] Starting dynamic subtest: vecs0
[112.444892] Dynamic subtest vecs0: SUCCESS (0.131s)
[112.446845] Subtest basic-await: SUCCESS (0.492s)
[113.041085] [024/165] (934s left) gem_exec_fence (nb-await)
[113.295678] Starting subtest: nb-await
[113.296958] Starting dynamic subtest: rcs0
[113.353943] Dynamic subtest rcs0: SUCCESS (0.066s)
[113.372307] Starting dynamic subtest: bcs0
[113.422223] Dynamic subtest bcs0: SUCCESS (0.068s)
[113.443330] Starting dynamic subtest: vcs0
[113.496746] Dynamic subtest vcs0: SUCCESS (0.071s)
[113.528813] Starting dynamic subtest: vecs0
[113.577571] Dynamic subtest vecs0: SUCCESS (0.083s)
[113.579650] Subtest nb-await: SUCCESS (0.290s)
[114.141611] [025/165] (932s left) gem_exec_gttfill (basic)
[114.252862] Starting subtest: basic
[120.308844] Subtest basic: SUCCESS (6.056s)
[120.515784] [026/165] (926s left) gem_exec_parallel (engines)
[120.693228] Starting subtest: engines
[120.694429] Starting dynamic subtest: basic
[123.051558] Dynamic subtest basic: SUCCESS (2.337s)
[123.065910] Starting dynamic subtest: contexts
[127.798841] Dynamic subtest contexts: SUCCESS (4.762s)
[127.812874] Starting dynamic subtest: fds
[136.567101] Dynamic subtest fds: SUCCESS (8.776s)
[136.600700] Starting dynamic subtest: userptr
[139.126060] Dynamic subtest userptr: SUCCESS (2.529s)
[139.127901] Subtest engines: SUCCESS (18.405s)
[139.318524] [027/165] (907s left) gem_exec_store (basic)
[139.430533] Starting subtest: basic
[139.496146] Subtest basic: SUCCESS (0.065s)
[139.704306] [028/165] (907s left) gem_flink_basic (bad-flink)
[139.795406] Starting subtest: bad-flink
[139.796535] Subtest bad-flink: SUCCESS (0.000s)
[139.886943] [029/165] (907s left) gem_flink_basic (bad-open)
[139.976352] Starting subtest: bad-open
[139.977453] Subtest bad-open: SUCCESS (0.000s)
[140.070205] [030/165] (907s left) gem_flink_basic (basic)
[140.160426] Starting subtest: basic
[140.161530] Subtest basic: SUCCESS (0.000s)
[140.271047] [031/165] (907s left) gem_flink_basic (double-flink)
[140.362313] Starting subtest: double-flink
[140.363539] Subtest double-flink: SUCCESS (0.000s)
[140.487818] [032/165] (906s left) gem_flink_basic (flink-lifetime)
[140.580585] Starting subtest: flink-lifetime
[140.581888] Subtest flink-lifetime: SUCCESS (0.002s)
[140.686885] [033/165] (906s left) gem_huc_copy (huc-copy)
[140.785459] Starting subtest: huc-copy
[140.787724] Subtest huc-copy: SKIP (0.000s)
[140.903626] [034/165] (906s left) gem_linear_blits (basic)
[141.154236] Starting subtest: basic
[141.167760] Subtest basic: SUCCESS (0.013s)
[141.307735] [035/165] (906s left) gem_mmap (basic)
[141.468115] Starting subtest: basic
[141.473376] Subtest basic: SUCCESS (0.000s)
[141.552418] [036/165] (905s left) gem_mmap_gtt (basic)
[141.642818] Starting subtest: basic
[141.644182] Subtest basic: SUCCESS (0.001s)
[141.730982] [037/165] (905s left) gem_render_linear_blits (basic)
[141.827099] Starting subtest: basic
[141.879045] Subtest basic: SUCCESS (0.050s)
[141.931573] [038/165] (905s left) gem_render_tiled_blits (basic)
[142.027944] Starting subtest: basic
[142.086685] Subtest basic: SUCCESS (0.059s)
[142.148912] [039/165] (905s left) gem_ringfill (basic-all)
[142.401515] Starting subtest: basic-all
[143.647948] Subtest basic-all: SUCCESS (1.245s)
[143.756899] [040/165] (903s left) gem_softpin (allocator-basic)
[143.854120] Starting subtest: allocator-basic
[144.025032] Subtest allocator-basic: SUCCESS (0.163s)
[144.142583] [041/165] (903s left) gem_softpin (allocator-basic-reserve)
[144.240370] Starting subtest: allocator-basic-reserve
[144.411833] Subtest allocator-basic-reserve: SUCCESS (0.162s)
[144.525074] [042/165] (903s left) gem_softpin (safe-alignment)
[144.624155] Starting subtest: safe-alignment
[144.642275] Subtest safe-alignment: SUCCESS (0.018s)
[144.757353] [043/165] (902s left) gem_sync (basic-all)
[144.866237] Starting subtest: basic-all
[147.132225] Subtest basic-all: SUCCESS (2.243s)
[147.328565] [044/165] (900s left) gem_sync (basic-each)
[147.434371] Starting subtest: basic-each
[149.741623] Subtest basic-each: SUCCESS (2.307s)
[149.965154] [045/165] (897s left) gem_tiled_blits (basic)
[150.196755] Starting subtest: basic
[150.225387] Subtest basic: SUCCESS (0.029s)
[150.370802] [046/165] (897s left) gem_tiled_fence_blits (basic)
[150.677872] Starting subtest: basic
[150.705218] Subtest basic: SUCCESS (0.028s)
[150.848091] [047/165] (896s left) gem_tiled_pread_basic
[151.349143] [048/165] (896s left) gem_wait (busy@all-engines)
[151.467344] Starting subtest: busy
[151.468581] Starting dynamic subtest: all-engines
[151.993973] Dynamic subtest all-engines: SUCCESS (0.526s)
[151.995278] Subtest busy: SUCCESS (0.527s)
[152.232234] [049/165] (895s left) gem_wait (wait@all-engines)
[152.348283] Starting subtest: wait
[152.349439] Starting dynamic subtest: all-engines
[153.396152] Dynamic subtest all-engines: SUCCESS (1.044s)
[153.397218] Subtest wait: SUCCESS (1.045s)
[153.634789] [050/165] (894s left) i915_getparams_basic (basic-eu-total)
[153.726307] Starting subtest: basic-eu-total
[153.728395] Subtest basic-eu-total: SUCCESS (0.000s)
[153.817752] [051/165] (894s left) i915_getparams_basic (basic-subslice-total)
[153.904546] Starting subtest: basic-subslice-total
[153.906775] Subtest basic-subslice-total: SUCCESS (0.000s)
[154.001958] [052/165] (893s left) i915_hangman (error-state-basic)
[154.108544] Starting subtest: error-state-basic
[154.120878] Subtest error-state-basic: SUCCESS (0.013s)
[154.216611] [053/165] (893s left) i915_pciid
[154.400525] [054/165] (893s left) kms_addfb_basic (addfb25-4-tiled)
[154.531975] Starting subtest: addfb25-4-tiled
[154.533218] Subtest addfb25-4-tiled: SUCCESS (0.000s)
[154.636623] [055/165] (893s left) kms_addfb_basic (addfb25-bad-modifier)
[154.728441] Starting subtest: addfb25-bad-modifier
[154.729677] Subtest addfb25-bad-modifier: SUCCESS (0.000s)
[154.870872] [056/165] (893s left) kms_addfb_basic (addfb25-framebuffer-vs-set-tiling)
[154.962361] Starting subtest: addfb25-framebuffer-vs-set-tiling
[154.963380] Subtest addfb25-framebuffer-vs-set-tiling: SUCCESS (0.000s)
[155.088229] [057/165] (892s left) kms_addfb_basic (addfb25-modifier-no-flag)
[155.179388] Starting subtest: addfb25-modifier-no-flag
[155.180530] Subtest addfb25-modifier-no-flag: SUCCESS (0.000s)
[155.304526] [058/165] (892s left) kms_addfb_basic (addfb25-x-tiled-legacy)
[155.396697] Starting subtest: addfb25-x-tiled-legacy
[155.397766] Subtest addfb25-x-tiled-legacy: SUCCESS (0.000s)
[155.537320] [059/165] (892s left) kms_addfb_basic (addfb25-x-tiled-mismatch-legacy)
[155.630223] Starting subtest: addfb25-x-tiled-mismatch-legacy
[155.631343] Subtest addfb25-x-tiled-mismatch-legacy: SUCCESS (0.000s)
[155.778378] [060/165] (892s left) kms_addfb_basic (addfb25-yf-tiled-legacy)
[155.899081] Starting subtest: addfb25-yf-tiled-legacy
[155.900171] Subtest addfb25-yf-tiled-legacy: SUCCESS (0.000s)
[156.004455] [061/165] (892s left) kms_addfb_basic (addfb25-y-tiled-legacy)
[156.126840] Starting subtest: addfb25-y-tiled-legacy
[156.127934] Subtest addfb25-y-tiled-legacy: SUCCESS (0.000s)
[156.220703] [062/165] (891s left) kms_addfb_basic (addfb25-y-tiled-small-legacy)
[156.339153] Starting subtest: addfb25-y-tiled-small-legacy
[156.340478] Subtest addfb25-y-tiled-small-legacy: SUCCESS (0.000s)
[156.457339] [063/165] (891s left) kms_addfb_basic (bad-pitch-0)
[156.550242] Starting subtest: bad-pitch-0
[156.551315] Subtest bad-pitch-0: SUCCESS (0.000s)
[156.675353] [064/165] (891s left) kms_addfb_basic (bad-pitch-1024)
[156.767760] Starting subtest: bad-pitch-1024
[156.768916] Subtest bad-pitch-1024: SUCCESS (0.000s)
[156.882842] [065/165] (891s left) kms_addfb_basic (bad-pitch-128)
[156.970541] Starting subtest: bad-pitch-128
[156.971643] Subtest bad-pitch-128: SUCCESS (0.000s)
[157.106521] [066/165] (891s left) kms_addfb_basic (bad-pitch-256)
[157.198449] Starting subtest: bad-pitch-256
[157.199536] Subtest bad-pitch-256: SUCCESS (0.000s)
[157.321633] [067/165] (891s left) kms_addfb_basic (bad-pitch-32)
[157.413698] Starting subtest: bad-pitch-32
[157.414847] Subtest bad-pitch-32: SUCCESS (0.000s)
[157.555418] [068/165] (890s left) kms_addfb_basic (bad-pitch-63)
[157.647772] Starting subtest: bad-pitch-63
[157.648857] Subtest bad-pitch-63: SUCCESS (0.000s)
[157.789817] [069/165] (890s left) kms_addfb_basic (bad-pitch-65536)
[157.882817] Starting subtest: bad-pitch-65536
[157.884097] Subtest bad-pitch-65536: SUCCESS (0.000s)
[158.003058] [070/165] (890s left) kms_addfb_basic (bad-pitch-999)
[158.095557] Starting subtest: bad-pitch-999
[158.096676] Subtest bad-pitch-999: SUCCESS (0.000s)
[158.223568] [071/165] (890s left) kms_addfb_basic (basic)
[158.314777] Starting subtest: basic
[158.315866] Subtest basic: SUCCESS (0.000s)
[158.458128] [072/165] (890s left) kms_addfb_basic (basic-x-tiled-legacy)
[158.549194] Starting subtest: basic-x-tiled-legacy
[158.550248] Subtest basic-x-tiled-legacy: SUCCESS (0.000s)
[158.672234] [073/165] (889s left) kms_addfb_basic (basic-y-tiled-legacy)
[158.764768] Starting subtest: basic-y-tiled-legacy
[158.765931] Subtest basic-y-tiled-legacy: SUCCESS (0.000s)
[158.890852] [074/165] (889s left) kms_addfb_basic (bo-too-small)
[159.012260] Starting subtest: bo-too-small
[159.013440] Subtest bo-too-small: SUCCESS (0.000s)
[159.101434] [075/165] (889s left) kms_addfb_basic (bo-too-small-due-to-tiling)
[159.223848] Starting subtest: bo-too-small-due-to-tiling
[159.225076] Subtest bo-too-small-due-to-tiling: SUCCESS (0.000s)
[159.323915] [076/165] (889s left) kms_addfb_basic (clobberred-modifier)
[159.415660] Starting subtest: clobberred-modifier
[159.416762] Subtest clobberred-modifier: SUCCESS (0.000s)
[159.544888] [077/165] (889s left) kms_addfb_basic (framebuffer-vs-set-tiling)
[159.639451] Starting subtest: framebuffer-vs-set-tiling
[159.640615] Subtest framebuffer-vs-set-tiling: SUCCESS (0.000s)
[159.772929] [078/165] (888s left) kms_addfb_basic (invalid-get-prop)
[159.864778] Starting subtest: invalid-get-prop
[159.865965] Subtest invalid-get-prop: SUCCESS (0.000s)
[159.985590] [079/165] (888s left) kms_addfb_basic (invalid-get-prop-any)
[160.077880] Starting subtest: invalid-get-prop-any
[160.079095] Subtest invalid-get-prop-any: SUCCESS (0.000s)
[160.205346] [080/165] (888s left) kms_addfb_basic (invalid-set-prop)
[160.299154] Starting subtest: invalid-set-prop
[160.300241] Subtest invalid-set-prop: SUCCESS (0.000s)
[160.442764] [081/165] (888s left) kms_addfb_basic (invalid-set-prop-any)
[160.535650] Starting subtest: invalid-set-prop-any
[160.536799] Subtest invalid-set-prop-any: SUCCESS (0.000s)
[160.684172] [082/165] (888s left) kms_addfb_basic (no-handle)
[160.774756] Starting subtest: no-handle
[160.775866] Subtest no-handle: SUCCESS (0.000s)
[160.909227] [083/165] (887s left) kms_addfb_basic (size-max)
[161.029101] Starting subtest: size-max
[161.030169] Subtest size-max: SUCCESS (0.000s)
[161.143149] [084/165] (887s left) kms_addfb_basic (small-bo)
[161.263258] Starting subtest: small-bo
[161.264351] Subtest small-bo: SUCCESS (0.000s)
[161.355017] [085/165] (887s left) kms_addfb_basic (tile-pitch-mismatch)
[161.446380] Starting subtest: tile-pitch-mismatch
[161.447439] Subtest tile-pitch-mismatch: SUCCESS (0.000s)
[161.576793] [086/165] (887s left) kms_addfb_basic (too-high)
[161.696087] Starting subtest: too-high
[161.697231] Subtest too-high: SUCCESS (0.001s)
[161.796078] [087/165] (887s left) kms_addfb_basic (too-wide)
[161.917153] Starting subtest: too-wide
[161.918235] Subtest too-wide: SUCCESS (0.000s)
[162.015359] [088/165] (886s left) kms_addfb_basic (unused-handle)
[162.107558] Starting subtest: unused-handle
[162.108675] Subtest unused-handle: SUCCESS (0.000s)
[162.221838] [089/165] (886s left) kms_addfb_basic (unused-modifier)
[162.312175] Starting subtest: unused-modifier
[162.313270] Subtest unused-modifier: SUCCESS (0.000s)
[162.445532] [090/165] (886s left) kms_addfb_basic (unused-offsets)
[162.537318] Starting subtest: unused-offsets
[162.538441] Subtest unused-offsets: SUCCESS (0.000s)
[162.677706] [091/165] (886s left) kms_addfb_basic (unused-pitches)
[162.768760] Starting subtest: unused-pitches
[162.769899] Subtest unused-pitches: SUCCESS (0.000s)
[162.900394] [092/165] (886s left) kms_busy (basic)
[163.025868] Starting subtest: basic
[163.028373] Starting dynamic subtest: flip
[164.029261] Dynamic subtest flip: SUCCESS (1.001s)
[164.046588] Starting dynamic subtest: modeset
[167.855855] Dynamic subtest modeset: SUCCESS (3.827s)
[167.857529] Subtest basic: SUCCESS (4.830s)
[168.824654] [093/165] (880s left) kms_prop_blob (basic)
[168.918549] Starting subtest: basic
[168.919691] Subtest basic: SUCCESS (0.000s)
[169.008985] [094/165] (880s left) kms_cursor_legacy (basic-busy-flip-before-cursor-atomic)
[169.129498] Starting subtest: basic-busy-flip-before-cursor-atomic
[169.131888] Subtest basic-busy-flip-before-cursor-atomic: SKIP (0.000s)
[169.223082] [095/165] (879s left) kms_cursor_legacy (basic-busy-flip-before-cursor-legacy)
[169.342062] Starting subtest: basic-busy-flip-before-cursor-legacy
[169.344246] Subtest basic-busy-flip-before-cursor-legacy: SKIP (0.000s)
[169.418692] [096/165] (879s left) kms_cursor_legacy (basic-flip-after-cursor-atomic)
[169.538149] Starting subtest: basic-flip-after-cursor-atomic
[170.834923] Subtest basic-flip-after-cursor-atomic: SUCCESS (1.298s)
[171.770847] [097/165] (877s left) kms_cursor_legacy (basic-flip-after-cursor-legacy)
[171.892200] Starting subtest: basic-flip-after-cursor-legacy
[173.196886] Subtest basic-flip-after-cursor-legacy: SUCCESS (1.305s)
[174.134088] [098/165] (875s left) kms_cursor_legacy (basic-flip-after-cursor-varying-size)
[174.254359] Starting subtest: basic-flip-after-cursor-varying-size
[175.531168] Subtest basic-flip-after-cursor-varying-size: SUCCESS (1.276s)
[176.499904] [099/165] (872s left) kms_cursor_legacy (basic-flip-before-cursor-atomic)
[176.618288] Starting subtest: basic-flip-before-cursor-atomic
[177.932160] Subtest basic-flip-before-cursor-atomic: SUCCESS (1.313s)
[178.871287] [100/165] (870s left) kms_cursor_legacy (basic-flip-before-cursor-legacy)
[178.993210] Starting subtest: basic-flip-before-cursor-legacy
[180.301392] Subtest basic-flip-before-cursor-legacy: SUCCESS (1.309s)
[181.238383] [101/165] (868s left) kms_cursor_legacy (basic-flip-before-cursor-varying-size)
[181.357893] Starting subtest: basic-flip-before-cursor-varying-size
[182.664189] Subtest basic-flip-before-cursor-varying-size: SUCCESS (1.305s)
[183.605227] [102/165] (865s left) kms_dsc (dsc-basic)
[183.738115] Starting subtest: dsc-basic
[183.740230] Subtest dsc-basic: SKIP (0.001s)
[183.819742] [103/165] (865s left) kms_flip (basic-flip-vs-dpms)
[183.918447] Starting subtest: basic-flip-vs-dpms
[183.921134] Starting dynamic subtest: A-eDP1
[186.796264] Dynamic subtest A-eDP1: SUCCESS (2.873s)
[186.812506] Starting dynamic subtest: B-eDP1
[189.165640] Dynamic subtest B-eDP1: SUCCESS (2.370s)
[189.182553] Starting dynamic subtest: C-eDP1
[191.539086] Dynamic subtest C-eDP1: SUCCESS (2.371s)
[191.542764] Subtest basic-flip-vs-dpms: SUCCESS (7.624s)
[192.448176] [104/165] (856s left) kms_flip (basic-flip-vs-modeset)
[192.547621] Starting subtest: basic-flip-vs-modeset
[192.550372] Starting dynamic subtest: A-eDP1
[195.437254] Dynamic subtest A-eDP1: SUCCESS (2.885s)
[195.461183] Starting dynamic subtest: B-eDP1
[197.807848] Dynamic subtest B-eDP1: SUCCESS (2.369s)
[197.824747] Starting dynamic subtest: C-eDP1
[200.170393] Dynamic subtest C-eDP1: SUCCESS (2.362s)
[200.172997] Subtest basic-flip-vs-modeset: SUCCESS (7.626s)
[201.145525] [105/165] (848s left) kms_flip (basic-flip-vs-wf_vblank)
[201.247067] Starting subtest: basic-flip-vs-wf_vblank
[201.250605] Starting dynamic subtest: A-eDP1
[203.604529] Dynamic subtest A-eDP1: SUCCESS (2.352s)
[203.623150] Starting dynamic subtest: B-eDP1
[205.891370] Dynamic subtest B-eDP1: SUCCESS (2.284s)
[205.909696] Starting dynamic subtest: C-eDP1
[208.133284] Dynamic subtest C-eDP1: SUCCESS (2.239s)
[208.138647] Subtest basic-flip-vs-wf_vblank: SUCCESS (6.892s)
[209.463527] [106/165] (840s left) kms_flip (basic-plain-flip)
[209.568047] Starting subtest: basic-plain-flip
[209.570886] Starting dynamic subtest: A-eDP1
[211.639550] Dynamic subtest A-eDP1: SUCCESS (2.067s)
[211.662149] Starting dynamic subtest: B-eDP1
[213.606820] Dynamic subtest B-eDP1: SUCCESS (1.965s)
[213.624961] Starting dynamic subtest: C-eDP1
[215.588857] Dynamic subtest C-eDP1: SUCCESS (1.980s)
[215.593661] Subtest basic-plain-flip: SUCCESS (6.027s)
[216.887610] [107/165] (832s left) kms_force_connector_basic (force-connector-state)
[216.983077] Starting subtest: force-connector-state
[217.615272] Subtest force-connector-state: SUCCESS (0.631s)
[218.674227] [108/165] (830s left) kms_force_connector_basic (force-edid)
[218.770678] Starting subtest: force-edid
[219.032496] Subtest force-edid: SUCCESS (0.262s)
[219.166712] [109/165] (830s left) kms_force_connector_basic (force-load-detect)
[219.261389] Starting subtest: force-load-detect
[219.263486] Subtest force-load-detect: SKIP (0.000s)
[219.363725] [110/165] (830s left) kms_force_connector_basic (prune-stale-modes)
[219.457584] Starting subtest: prune-stale-modes
[219.775368] Subtest prune-stale-modes: SUCCESS (0.318s)
[219.898114] [111/165] (829s left) kms_frontbuffer_tracking (basic)
[220.027819] Starting subtest: basic
[223.399296] Subtest basic: SUCCESS (3.372s)
[223.538681] [112/165] (826s left) kms_hdmi_inject (inject-audio)
[223.957394] Starting subtest: inject-audio
[224.286478] Subtest inject-audio: SUCCESS (0.328s)
[224.888290] [113/165] (824s left) kms_pipe_crc_basic (compare-crc-sanitycheck-xr24)
[225.010662] Starting subtest: compare-crc-sanitycheck-xr24
[225.011770] Starting dynamic subtest: pipe-A-eDP-1
[225.703620] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.687s)
[225.726411] Starting dynamic subtest: pipe-B-eDP-1
[227.093891] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.387s)
[227.120695] Starting dynamic subtest: pipe-C-eDP-1
[228.492436] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.394s)
[228.493677] Subtest compare-crc-sanitycheck-xr24: SUCCESS (3.483s)
[229.429530] [114/165] (820s left) kms_pipe_crc_basic (compare-crc-sanitycheck-nv12)
[229.551060] Starting subtest: compare-crc-sanitycheck-nv12
[229.553660] Starting dynamic subtest: pipe-A-eDP-1
[230.494739] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.939s)
[230.512351] Starting dynamic subtest: pipe-B-eDP-1
[231.883145] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.386s)
[231.901804] Starting dynamic subtest: pipe-C-eDP-1
[233.257446] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.373s)
[233.258638] Subtest compare-crc-sanitycheck-nv12: SUCCESS (3.707s)
[234.232885] [115/165] (815s left) kms_pipe_crc_basic (hang-read-crc)
[234.354045] Starting subtest: hang-read-crc
[234.355146] Starting dynamic subtest: pipe-A-eDP-1
[236.780366] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.423s)
[236.799016] Starting dynamic subtest: pipe-B-eDP-1
[239.865128] Dynamic subtest pipe-B-eDP-1: SUCCESS (3.080s)
[239.892893] Starting dynamic subtest: pipe-C-eDP-1
[242.830233] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.958s)
[242.832430] Subtest hang-read-crc: SUCCESS (8.476s)
[243.764194] [116/165] (806s left) kms_pipe_crc_basic (nonblocking-crc)
[243.889550] Starting subtest: nonblocking-crc
[243.893113] Starting dynamic subtest: pipe-A-eDP-1
[244.797152] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.901s)
[244.826430] Starting dynamic subtest: pipe-B-eDP-1
[246.373210] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.571s)
[246.400988] Starting dynamic subtest: pipe-C-eDP-1
[247.936556] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.555s)
[247.938196] Subtest nonblocking-crc: SUCCESS (4.045s)
[248.888605] [117/165] (800s left) kms_pipe_crc_basic (nonblocking-crc-frame-sequence)
[249.008619] Starting subtest: nonblocking-crc-frame-sequence
[249.011433] Starting dynamic subtest: pipe-A-eDP-1
[249.901693] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.888s)
[249.923026] Starting dynamic subtest: pipe-B-eDP-1
[251.519028] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.612s)
[251.543460] Starting dynamic subtest: pipe-C-eDP-1
[253.080503] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.557s)
[253.081620] Subtest nonblocking-crc-frame-sequence: SUCCESS (4.072s)
[254.014371] [118/165] (795s left) kms_pipe_crc_basic (read-crc)
[254.135956] Starting subtest: read-crc
[254.137257] Starting dynamic subtest: pipe-A-eDP-1
[254.964587] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.824s)
[254.991822] Starting dynamic subtest: pipe-B-eDP-1
[256.478427] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.507s)
[256.516381] Starting dynamic subtest: pipe-C-eDP-1
[257.970900] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.487s)
[257.972157] Subtest read-crc: SUCCESS (3.837s)
[258.935175] [119/165] (790s left) kms_pipe_crc_basic (read-crc-frame-sequence)
[259.056601] Starting subtest: read-crc-frame-sequence
[259.059572] Starting dynamic subtest: pipe-A-eDP-1
[259.883401] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.823s)
[259.916269] Starting dynamic subtest: pipe-B-eDP-1
[261.397841] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.505s)
[261.432777] Starting dynamic subtest: pipe-C-eDP-1
[262.887422] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.483s)
[262.888629] Subtest read-crc-frame-sequence: SUCCESS (3.831s)
[263.860571] [120/165] (786s left) kms_pm_backlight (basic-brightness)
[263.984523] Starting subtest: basic-brightness
[264.097524] Starting dynamic subtest: eDP-1
[264.672219] Dynamic subtest eDP-1: SUCCESS (0.582s)
[264.673651] Subtest basic-brightness: SUCCESS (0.688s)
[265.592797] [121/165] (784s left) kms_psr (primary_page_flip)
[265.717571] Starting subtest: primary_page_flip
[269.528149] Subtest primary_page_flip: SUCCESS (3.807s)
[270.455567] [122/165] (779s left) kms_psr (cursor_plane_move)
[270.579497] Starting subtest: cursor_plane_move
[274.385992] Subtest cursor_plane_move: SUCCESS (3.803s)
[275.319297] [123/165] (774s left) kms_psr (sprite_plane_onoff)
[275.442387] Starting subtest: sprite_plane_onoff
[279.340372] Subtest sprite_plane_onoff: SUCCESS (3.897s)
[280.309807] [124/165] (769s left) kms_psr (primary_mmap_gtt)
[280.434777] Starting subtest: primary_mmap_gtt
[284.262101] Subtest primary_mmap_gtt: SUCCESS (3.826s)
[285.241764] [125/165] (764s left) kms_setmode (basic-clone-single-crtc)
[285.337553] Starting subtest: basic-clone-single-crtc
[285.340809] Subtest basic-clone-single-crtc: SKIP (0.000s)
[285.423135] [126/165] (764s left) i915_pm_rpm (basic-pci-d3-state)
[287.257247] Starting subtest: basic-pci-d3-state
[291.661643] Subtest basic-pci-d3-state: SUCCESS (4.405s)
[291.868978] [127/165] (758s left) i915_pm_rpm (basic-rte)
[291.963367] Starting subtest: basic-rte
[298.127422] Subtest basic-rte: SUCCESS (6.166s)
[299.619370] [128/165] (750s left) i915_pm_rps (basic-api)
[299.718838] Starting subtest: basic-api
[299.722843] Subtest basic-api: SUCCESS (0.004s)
[299.820351] [129/165] (750s left) prime_self_import (basic-llseek-bad)
[299.910321] Starting subtest: basic-llseek-bad
[299.912485] Subtest basic-llseek-bad: SUCCESS (0.003s)
[300.019646] [130/165] (750s left) prime_self_import (basic-llseek-size)
[300.107369] Starting subtest: basic-llseek-size
[300.111768] Subtest basic-llseek-size: SUCCESS (0.004s)
[300.219078] [131/165] (750s left) prime_self_import (basic-with_fd_dup)
[300.307021] Starting subtest: basic-with_fd_dup
[300.311952] Subtest basic-with_fd_dup: SUCCESS (0.005s)
[300.420429] [132/165] (749s left) prime_self_import (basic-with_one_bo)
[300.509100] Starting subtest: basic-with_one_bo
[300.514090] Subtest basic-with_one_bo: SUCCESS (0.005s)
[300.598991] [133/165] (749s left) prime_self_import (basic-with_one_bo_two_files)
[300.688044] Starting subtest: basic-with_one_bo_two_files
[300.692445] Subtest basic-with_one_bo_two_files: SUCCESS (0.004s)
[300.813037] [134/165] (749s left) prime_self_import (basic-with_two_bos)
[300.900583] Starting subtest: basic-with_two_bos
[300.905362] Subtest basic-with_two_bos: SUCCESS (0.005s)
[301.020469] [135/165] (749s left) prime_vgem (basic-fence-flip)
[301.143761] Starting subtest: basic-fence-flip
[301.677368] Subtest basic-fence-flip: SUCCESS (0.534s)
[301.806192] [136/165] (748s left) prime_vgem (basic-fence-mmap)
[301.931268] Starting subtest: basic-fence-mmap
[301.981481] Subtest basic-fence-mmap: SUCCESS (0.050s)
[302.087245] [137/165] (748s left) prime_vgem (basic-fence-read)
[302.204298] Starting subtest: basic-fence-read
[302.281699] Subtest basic-fence-read: SUCCESS (0.077s)
[302.372815] [138/165] (748s left) prime_vgem (basic-gtt)
[302.494376] Starting subtest: basic-gtt
[302.519893] Subtest basic-gtt: SUCCESS (0.025s)
[302.638856] [139/165] (747s left) prime_vgem (basic-read)
[302.754406] Starting subtest: basic-read
[302.792845] Subtest basic-read: SUCCESS (0.038s)
[302.907373] [140/165] (747s left) prime_vgem (basic-write)
[303.025221] Starting subtest: basic-write
[303.069963] Subtest basic-write: SUCCESS (0.044s)
[303.154770] [141/165] (747s left) vgem_basic (setversion)
[303.263756] Starting subtest: setversion
[303.266431] Subtest setversion: SUCCESS (0.000s)
[303.339877] [142/165] (747s left) vgem_basic (create)
[303.447484] Starting subtest: create
[303.448695] Subtest create: SUCCESS (0.000s)
[303.522393] [143/165] (747s left) vgem_basic (debugfs)
[303.631280] Starting subtest: debugfs
[303.632562] Subtest debugfs: SUCCESS (0.001s)
[303.709512] [144/165] (746s left) vgem_basic (dmabuf-export)
[303.814414] Starting subtest: dmabuf-export
[303.819331] Subtest dmabuf-export: SUCCESS (0.003s)
[303.941706] [145/165] (746s left) vgem_basic (dmabuf-fence)
[304.047946] Starting subtest: dmabuf-fence
[304.049406] Subtest dmabuf-fence: SUCCESS (0.001s)
[304.122390] [146/165] (746s left) vgem_basic (dmabuf-fence-before)
[304.214432] Starting subtest: dmabuf-fence-before
[304.215624] Subtest dmabuf-fence-before: SUCCESS (0.001s)
[304.287105] [147/165] (746s left) vgem_basic (dmabuf-mmap)
[304.397768] Starting subtest: dmabuf-mmap
[304.423645] Subtest dmabuf-mmap: SUCCESS (0.026s)
[304.504207] [148/165] (746s left) vgem_basic (mmap)
[304.614742] Starting subtest: mmap
[304.634519] Subtest mmap: SUCCESS (0.018s)
[304.704329] [149/165] (746s left) vgem_basic (second-client)
[304.815376] Starting subtest: second-client
[304.851451] Subtest second-client: SUCCESS (0.033s)
[304.924771] [150/165] (745s left) vgem_basic (sysfs)
[305.032208] Starting subtest: sysfs
[305.033421] Subtest sysfs: SUCCESS (0.001s)
[305.108113] [151/165] (745s left) core_hotunplug (unbind-rebind)
[305.248584] Starting subtest: unbind-rebind
[309.802670] Subtest unbind-rebind: SUCCESS (4.554s)
[309.838960] [152/165] (741s left) vgem_basic (unload)
[309.929538] Starting subtest: unload
[338.982584] Subtest unload: SUCCESS (29.017s)
[339.096803] [153/165] (711s left) i915_module_load (reload)
[339.192596] Starting subtest: reload
[342.642279] Subtest reload: SUCCESS (3.450s)
[342.674679] [154/165] (708s left) gem_lmem_swapping (basic)
[342.784605] Subtest basic: SKIP (0.000s)
[342.827239] [155/165] (708s left) gem_lmem_swapping (parallel-random-engines)
[342.924659] Subtest parallel-random-engines: SKIP (0.000s)
[342.977608] [156/165] (708s left) gem_lmem_swapping (random-engines)
[343.076258] Subtest random-engines: SKIP (0.000s)
[343.127280] [157/165] (707s left) gem_lmem_swapping (verify-random)
[343.224624] Subtest verify-random: SKIP (0.000s)
[343.278719] [158/165] (707s left) i915_pm_rpm (module-reload)
[355.744861] Starting subtest: module-reload
[390.204929] Subtest module-reload: SUCCESS (34.451s)
[390.229057] [159/165] (660s left) i915_selftest (live)
[390.333103] Starting subtest: live
[390.334449] Starting dynamic subtest: sanitycheck
[392.241564] Dynamic subtest sanitycheck: SUCCESS (1.898s)
[392.256444] Starting dynamic subtest: uncore
[394.063600] Dynamic subtest uncore: SUCCESS (1.818s)
[394.078959] Starting dynamic subtest: workarounds
[396.273384] Dynamic subtest workarounds: SUCCESS (2.206s)
[396.288387] Starting dynamic subtest: gt_engines
[398.540659] Dynamic subtest gt_engines: SUCCESS (2.263s)
[398.555388] Starting dynamic subtest: gt_timelines
[411.478612] Dynamic subtest gt_timelines: SUCCESS (12.934s)
[411.493850] Starting dynamic subtest: gt_contexts
[413.468573] Dynamic subtest gt_contexts: SUCCESS (1.986s)
[413.483388] Starting dynamic subtest: gt_lrc
[417.878343] Dynamic subtest gt_lrc: SUCCESS (4.406s)
[417.893129] Starting dynamic subtest: gt_mocs
[419.859342] Dynamic subtest gt_mocs: SUCCESS (1.977s)
[419.874034] Starting dynamic subtest: gt_pm
[424.989573] Dynamic subtest gt_pm: SUCCESS (5.126s)
[425.004713] Starting dynamic subtest: gt_heartbeat
[426.996779] Dynamic subtest gt_heartbeat: SUCCESS (2.003s)
[427.011728] Starting dynamic subtest: gt_tlb
[430.099532] Dynamic subtest gt_tlb: SUCCESS (3.099s)
[430.114305] Starting dynamic subtest: requests
[437.814790] Dynamic subtest requests: SUCCESS (7.711s)
[437.829836] Starting dynamic subtest: migrate
[442.421348] Dynamic subtest migrate: SUCCESS (4.603s)
[442.436840] Starting dynamic subtest: active
[444.306290] Dynamic subtest active: SUCCESS (1.881s)
[444.321443] Starting dynamic subtest: objects
[447.753220] Dynamic subtest objects: SUCCESS (3.443s)
[447.768758] Starting dynamic subtest: mman
[454.730421] Dynamic subtest mman: SUCCESS (6.974s)
[454.745767] Starting dynamic subtest: dmabuf
[456.634640] Dynamic subtest dmabuf: SUCCESS (1.901s)
[456.649691] Starting dynamic subtest: vma
[458.528410] Dynamic subtest vma: SUCCESS (1.890s)
[458.544163] Starting dynamic subtest: coherency
[463.040668] Dynamic subtest coherency: SUCCESS (4.509s)
[463.055711] Starting dynamic subtest: gtt
[472.599594] Dynamic subtest gtt: SUCCESS (9.555s)
[472.614925] Starting dynamic subtest: gem
[474.601553] Dynamic subtest gem: SUCCESS (1.999s)
[474.616744] Starting dynamic subtest: evict
[478.471081] Dynamic subtest evict: SUCCESS (3.866s)
[478.486377] Starting dynamic subtest: hugepages
[486.269882] Dynamic subtest hugepages: SUCCESS (7.795s)
[486.285569] Starting dynamic subtest: gem_contexts
[501.711428] Dynamic subtest gem_contexts: SUCCESS (15.438s)
[501.727189] Starting dynamic subtest: client
[503.580381] Dynamic subtest client: SUCCESS (1.865s)
[503.596338] Starting dynamic subtest: gem_migrate
[505.421458] Dynamic subtest gem_migrate: SUCCESS (1.838s)
[505.437022] Starting dynamic subtest: reset
[510.856587] Dynamic subtest reset: SUCCESS (5.431s)
[510.872101] Starting dynamic subtest: memory_region
[512.728608] Dynamic subtest memory_region: SUCCESS (1.868s)
[512.744067] Starting dynamic subtest: hangcheck
[537.232403] Dynamic subtest hangcheck: SUCCESS (24.500s)
[537.248225] Starting dynamic subtest: execlists
[547.394398] Dynamic subtest execlists: SUCCESS (10.158s)
[547.410303] Starting dynamic subtest: ring_submission
[549.293689] Dynamic subtest ring_submission: SUCCESS (1.896s)
[549.309299] Starting dynamic subtest: perf
[551.265541] Dynamic subtest perf: SUCCESS (1.968s)
[551.281146] Starting dynamic subtest: slpc
[553.120269] Dynamic subtest slpc: SUCCESS (1.851s)
[553.136201] Starting dynamic subtest: guc
[555.028140] Dynamic subtest guc: SUCCESS (1.904s)
[555.045078] Starting dynamic subtest: guc_multi_lrc
[556.888391] Dynamic subtest guc_multi_lrc: SUCCESS (1.857s)
[556.904290] Starting dynamic subtest: guc_hang
[558.732079] Dynamic subtest guc_hang: SUCCESS (1.840s)
[558.747997] Starting dynamic subtest: late_gt_pm
[560.609383] Dynamic subtest late_gt_pm: SUCCESS (1.874s)
[560.610700] Subtest live: SUCCESS (170.270s)
[560.636321] [160/165] (490s left) dmabuf (all-tests)
[560.736069] Starting subtest: all-tests
[560.737386] Starting dynamic subtest: sanitycheck
[560.794773] Dynamic subtest sanitycheck: SUCCESS (0.057s)
[560.814062] Starting dynamic subtest: dma_fence
[560.987682] Dynamic subtest dma_fence: SUCCESS (0.189s)
[561.005676] Starting dynamic subtest: dma_fence_chain
[578.809317] Dynamic subtest dma_fence_chain: SUCCESS (17.809s)
[578.823675] Starting dynamic subtest: dma_fence_unwrap
[578.867572] Dynamic subtest dma_fence_unwrap: SUCCESS (0.063s)
[578.887696] Starting dynamic subtest: dma_resv
[578.956701] Dynamic subtest dma_resv: SUCCESS (0.085s)
[578.958775] Subtest all-tests: SUCCESS (18.222s)
[578.991145] [161/165] (472s left) i915_suspend (basic-s2idle-without-i915)
[579.080803] Starting subtest: basic-s2idle-without-i915
[602.641330] Subtest basic-s2idle-without-i915: SUCCESS (8.005s)
[603.002828] [162/165] (463s left) i915_suspend (basic-s3-without-i915)
[603.114855] Starting subtest: basic-s3-without-i915
[626.764203] Subtest basic-s3-without-i915: SUCCESS (6.975s)
[627.100710] [163/165] (456s left) gem_exec_suspend (basic-s0)
[627.215966] Starting subtest: basic-S0
[627.217188] Starting dynamic subtest: smem
[646.896868] Dynamic subtest smem: SUCCESS (3.755s)
[646.897992] Subtest basic-S0: SUCCESS (3.757s)
[647.115838] [164/165] (452s left) gem_exec_suspend (basic-s3)
[647.228890] Starting subtest: basic-S3
[647.230128] Starting dynamic subtest: smem
[665.965492] Dynamic subtest smem: SUCCESS (3.752s)
[665.966702] Subtest basic-S3: SUCCESS (3.754s)
[666.174835] [165/165] (448s left) kms_pipe_crc_basic (suspend-read-crc)
[666.298380] Starting subtest: suspend-read-crc
[666.301425] Starting dynamic subtest: pipe-A-eDP-1
[683.878954] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.545s)
[683.905935] Starting dynamic subtest: pipe-B-eDP-1
[702.902767] Dynamic subtest pipe-B-eDP-1: SUCCESS (3.241s)
[702.943584] Starting dynamic subtest: pipe-C-eDP-1
[721.853000] Dynamic subtest pipe-C-eDP-1: SUCCESS (3.171s)
[721.854393] Subtest suspend-read-crc: SUCCESS (8.977s)
[722.787823] Closing watchdogs
Done.