[82.979022] Initializing watchdogs [82.979225] /dev/watchdog0 [82.989425] [001/171] (960s left) i915_module_load (load) [83.138225] Starting subtest: load [85.689509] Subtest load: SUCCESS (2.552s) [85.714989] [002/171] (957s left) core_auth (basic-auth) [85.861582] Starting subtest: basic-auth [85.862864] Subtest basic-auth: SUCCESS (0.001s) [85.952714] [003/171] (957s left) debugfs_test (read_all_entries) [86.102657] Starting subtest: read_all_entries [86.174690] Subtest read_all_entries: SUCCESS (0.072s) [86.287889] [004/171] (957s left) debugfs_test (basic-hwmon) [86.424421] Starting subtest: basic-hwmon [86.426455] Subtest basic-hwmon: SKIP (0.000s) [86.552710] [005/171] (957s left) fbdev (eof) [86.706995] Starting subtest: eof [86.708701] Subtest eof: SUCCESS (0.000s) [86.771378] [006/171] (956s left) fbdev (info) [86.929444] Starting subtest: info [86.930713] Subtest info: SUCCESS (0.000s) [87.007559] [007/171] (956s left) fbdev (nullptr) [87.158167] Starting subtest: nullptr [87.159895] Subtest nullptr: SUCCESS (0.000s) [87.223862] [008/171] (956s left) fbdev (read) [87.374053] Starting subtest: read [94.487993] Subtest read: SUCCESS (7.080s) [94.523067] [009/171] (949s left) fbdev (write) [94.675296] Starting subtest: write [98.457513] Subtest write: SUCCESS (3.748s) [98.505916] [010/171] (945s left) gem_basic (bad-close) [98.642418] Starting subtest: bad-close [98.644564] Subtest bad-close: SUCCESS (0.000s) [98.721872] [011/171] (945s left) gem_basic (create-close) [98.858752] Starting subtest: create-close [98.861172] Subtest create-close: SUCCESS (0.000s) [98.972308] [012/171] (944s left) gem_basic (create-fd-close) [99.109478] Starting subtest: create-fd-close [99.111467] Subtest create-fd-close: SUCCESS (0.000s) [99.205185] [013/171] (944s left) gem_busy (busy@all-engines) [99.361338] Starting subtest: busy [99.362542] Starting dynamic subtest: all-engines [99.423225] Dynamic subtest all-engines: SUCCESS (0.062s) [99.424487] Subtest busy: SUCCESS (0.062s) [99.809719] [014/171] (944s left) gem_close_race (basic-process) [99.969397] Starting subtest: basic-process [100.040445] Subtest basic-process: SUCCESS (0.070s) [100.240205] [015/171] (943s left) gem_close_race (basic-threads) [100.397724] Starting subtest: basic-threads [101.574024] Subtest basic-threads: SUCCESS (1.176s) [101.790185] [016/171] (942s left) gem_ctx_create (basic) [101.936517] Starting subtest: basic [101.937682] Subtest basic: SUCCESS (0.000s) [102.139788] [017/171] (941s left) gem_ctx_create (basic-files) [102.293377] Starting subtest: basic-files [104.319820] Subtest basic-files: SUCCESS (2.027s) [104.538455] [018/171] (939s left) gem_ctx_exec (basic) [104.679647] Starting subtest: basic [104.683958] Subtest basic: SUCCESS (0.004s) [104.805283] [019/171] (939s left) gem_exec_basic (basic) [104.945315] Starting subtest: basic [104.946450] Starting dynamic subtest: rcs0-smem [104.947460] Dynamic subtest rcs0-smem: SUCCESS (0.002s) [104.958748] Starting dynamic subtest: bcs0-smem [104.959970] Dynamic subtest bcs0-smem: SUCCESS (0.001s) [104.970337] Starting dynamic subtest: vcs0-smem [104.971676] Dynamic subtest vcs0-smem: SUCCESS (0.001s) [104.981851] Starting dynamic subtest: vecs0-smem [104.983115] Dynamic subtest vecs0-smem: SUCCESS (0.001s) [104.984343] Subtest basic: SUCCESS (0.005s) [105.140052] [020/171] (938s left) gem_exec_create (basic) [105.291867] Starting subtest: basic [105.293077] Starting dynamic subtest: smem [107.351592] Dynamic subtest smem: SUCCESS (2.036s) [107.352945] Subtest basic: SUCCESS (2.037s) [107.552165] [021/171] (936s left) gem_exec_fence (basic-busy) [107.854971] Starting subtest: basic-busy [107.856538] Starting dynamic subtest: rcs0 [107.873670] Dynamic subtest rcs0: SUCCESS (0.027s) [107.884521] Starting dynamic subtest: bcs0 [107.902596] Dynamic subtest bcs0: SUCCESS (0.029s) [107.914966] Starting dynamic subtest: vcs0 [107.920275] Dynamic subtest vcs0: SUCCESS (0.018s) [107.931120] Starting dynamic subtest: vecs0 [107.947841] Dynamic subtest vecs0: SUCCESS (0.027s) [107.949274] Subtest basic-busy: SUCCESS (0.101s) [108.540771] [022/171] (935s left) gem_exec_fence (basic-wait) [108.836601] Starting subtest: basic-wait [108.837857] Starting dynamic subtest: rcs0 [108.862651] Dynamic subtest rcs0: SUCCESS (0.031s) [108.876047] Starting dynamic subtest: bcs0 [108.888406] Dynamic subtest bcs0: SUCCESS (0.026s) [108.899774] Starting dynamic subtest: vcs0 [108.923883] Dynamic subtest vcs0: SUCCESS (0.031s) [108.935154] Starting dynamic subtest: vecs0 [108.954287] Dynamic subtest vecs0: SUCCESS (0.034s) [108.955821] Subtest basic-wait: SUCCESS (0.123s) [109.490602] [023/171] (934s left) gem_exec_fence (basic-await) [109.795302] Starting subtest: basic-await [109.796843] Starting dynamic subtest: rcs0 [109.889933] Dynamic subtest rcs0: SUCCESS (0.098s) [109.915937] Starting dynamic subtest: bcs0 [110.008335] Dynamic subtest bcs0: SUCCESS (0.117s) [110.034307] Starting dynamic subtest: vcs0 [110.141882] Dynamic subtest vcs0: SUCCESS (0.128s) [110.168576] Starting dynamic subtest: vecs0 [110.276813] Dynamic subtest vecs0: SUCCESS (0.131s) [110.278902] Subtest basic-await: SUCCESS (0.475s) [110.841785] [024/171] (933s left) gem_exec_fence (nb-await) [111.133494] Starting subtest: nb-await [111.134626] Starting dynamic subtest: rcs0 [111.191253] Dynamic subtest rcs0: SUCCESS (0.065s) [111.209182] Starting dynamic subtest: bcs0 [111.261573] Dynamic subtest bcs0: SUCCESS (0.070s) [111.288531] Starting dynamic subtest: vcs0 [111.335920] Dynamic subtest vcs0: SUCCESS (0.074s) [111.363203] Starting dynamic subtest: vecs0 [111.410468] Dynamic subtest vecs0: SUCCESS (0.074s) [111.412523] Subtest nb-await: SUCCESS (0.284s) [111.975854] [025/171] (932s left) gem_exec_gttfill (basic) [112.122490] Starting subtest: basic [119.776992] Subtest basic: SUCCESS (7.654s) [119.978658] [026/171] (924s left) gem_exec_parallel (engines) [120.203568] Starting subtest: engines [120.204759] Starting dynamic subtest: basic [122.663849] Dynamic subtest basic: SUCCESS (2.442s) [122.688538] Starting dynamic subtest: contexts [126.216136] Dynamic subtest contexts: SUCCESS (3.566s) [126.229459] Starting dynamic subtest: fds [130.202219] Dynamic subtest fds: SUCCESS (3.995s) [130.217139] Starting dynamic subtest: userptr [132.783087] Dynamic subtest userptr: SUCCESS (2.556s) [132.784782] Subtest engines: SUCCESS (12.560s) [132.961277] [027/171] (911s left) gem_exec_store (basic) [133.118605] Starting subtest: basic [133.188693] Subtest basic: SUCCESS (0.070s) [133.394276] [028/171] (910s left) gem_flink_basic (bad-flink) [133.530745] Starting subtest: bad-flink [133.531951] Subtest bad-flink: SUCCESS (0.000s) [133.608755] [029/171] (910s left) gem_flink_basic (bad-open) [133.746628] Starting subtest: bad-open [133.747894] Subtest bad-open: SUCCESS (0.000s) [133.824819] [030/171] (910s left) gem_flink_basic (basic) [133.963597] Starting subtest: basic [133.964800] Subtest basic: SUCCESS (0.000s) [134.058607] [031/171] (910s left) gem_flink_basic (double-flink) [134.196441] Starting subtest: double-flink [134.197505] Subtest double-flink: SUCCESS (0.000s) [134.308465] [032/171] (909s left) gem_flink_basic (flink-lifetime) [134.447291] Starting subtest: flink-lifetime [134.448447] Subtest flink-lifetime: SUCCESS (0.002s) [134.559111] [033/171] (909s left) gem_huc_copy (huc-copy) [134.693539] Starting subtest: huc-copy [134.695804] Subtest huc-copy: SKIP (0.000s) [134.808621] [034/171] (909s left) gem_linear_blits (basic) [135.098097] Starting subtest: basic [135.110507] Subtest basic: SUCCESS (0.013s) [135.242318] [035/171] (909s left) gem_mmap (basic) [135.441489] Starting subtest: basic [135.446405] Subtest basic: SUCCESS (0.001s) [135.525487] [036/171] (908s left) gem_mmap_gtt (basic) [135.654716] Starting subtest: basic [135.656024] Subtest basic: SUCCESS (0.001s) [135.740828] [037/171] (908s left) gem_render_linear_blits (basic) [135.882474] Starting subtest: basic [135.931206] Subtest basic: SUCCESS (0.049s) [135.995049] [038/171] (908s left) gem_render_tiled_blits (basic) [136.128963] Starting subtest: basic [136.188841] Subtest basic: SUCCESS (0.060s) [136.241686] [039/171] (908s left) gem_ringfill (basic-all) [136.516285] Starting subtest: basic-all [137.765434] Subtest basic-all: SUCCESS (1.242s) [137.845505] [040/171] (906s left) gem_softpin (allocator-basic) [137.994053] Starting subtest: allocator-basic [138.153052] Subtest allocator-basic: SUCCESS (0.159s) [138.276236] [041/171] (906s left) gem_softpin (allocator-basic-reserve) [138.426409] Starting subtest: allocator-basic-reserve [138.596878] Subtest allocator-basic-reserve: SUCCESS (0.162s) [138.715639] [042/171] (905s left) gem_softpin (safe-alignment) [138.872869] Starting subtest: safe-alignment [138.891258] Subtest safe-alignment: SUCCESS (0.018s) [139.008849] [043/171] (905s left) gem_sync (basic-all) [139.162865] Starting subtest: basic-all [141.330394] Subtest basic-all: SUCCESS (2.150s) [141.528956] [044/171] (903s left) gem_sync (basic-each) [141.678837] Starting subtest: basic-each [144.030081] Subtest basic-each: SUCCESS (2.351s) [144.242882] [045/171] (900s left) gem_tiled_blits (basic) [144.524596] Starting subtest: basic [144.552833] Subtest basic: SUCCESS (0.028s) [144.675084] [046/171] (899s left) gem_tiled_fence_blits (basic) [145.030304] Starting subtest: basic [145.057240] Subtest basic: SUCCESS (0.027s) [145.192178] [047/171] (899s left) gem_tiled_pread_basic [145.728656] [048/171] (898s left) gem_wait (busy@all-engines) [145.894156] Starting subtest: busy [145.895322] Starting dynamic subtest: all-engines [146.425469] Dynamic subtest all-engines: SUCCESS (0.531s) [146.426657] Subtest busy: SUCCESS (0.532s) [146.659950] [049/171] (898s left) gem_wait (wait@all-engines) [146.819433] Starting subtest: wait [146.820597] Starting dynamic subtest: all-engines [147.860329] Dynamic subtest all-engines: SUCCESS (1.038s) [147.861662] Subtest wait: SUCCESS (1.038s) [148.093670] [050/171] (896s left) i915_getparams_basic (basic-eu-total) [148.227515] Starting subtest: basic-eu-total [148.229620] Subtest basic-eu-total: SUCCESS (0.000s) [148.308518] [051/171] (896s left) i915_getparams_basic (basic-subslice-total) [148.444533] Starting subtest: basic-subslice-total [148.446666] Subtest basic-subslice-total: SUCCESS (0.000s) [148.525476] [052/171] (896s left) i915_hangman (error-state-basic) [148.668501] Starting subtest: error-state-basic [148.680430] Subtest error-state-basic: SUCCESS (0.013s) [148.776124] [053/171] (896s left) i915_pciid [148.993102] [054/171] (895s left) kms_addfb_basic (addfb25-bad-modifier) [149.125445] Starting subtest: addfb25-bad-modifier [149.126492] Subtest addfb25-bad-modifier: SUCCESS (0.000s) [149.258178] [055/171] (895s left) kms_addfb_basic (addfb25-framebuffer-vs-set-tiling) [149.394857] Starting subtest: addfb25-framebuffer-vs-set-tiling [149.396163] Subtest addfb25-framebuffer-vs-set-tiling: SUCCESS (0.000s) [149.508327] [056/171] (895s left) kms_addfb_basic (addfb25-modifier-no-flag) [149.645196] Starting subtest: addfb25-modifier-no-flag [149.646239] Subtest addfb25-modifier-no-flag: SUCCESS (0.000s) [149.776110] [057/171] (895s left) kms_addfb_basic (addfb25-x-tiled-legacy) [149.920486] Starting subtest: addfb25-x-tiled-legacy [149.921658] Subtest addfb25-x-tiled-legacy: SUCCESS (0.000s) [150.041983] [058/171] (894s left) kms_addfb_basic (addfb25-x-tiled-mismatch-legacy) [150.178555] Starting subtest: addfb25-x-tiled-mismatch-legacy [150.179748] Subtest addfb25-x-tiled-mismatch-legacy: SUCCESS (0.000s) [150.309071] [059/171] (894s left) kms_addfb_basic (addfb25-yf-tiled-legacy) [150.475297] Starting subtest: addfb25-yf-tiled-legacy [150.476371] Subtest addfb25-yf-tiled-legacy: SUCCESS (0.000s) [150.558687] [060/171] (894s left) kms_addfb_basic (addfb25-y-tiled-legacy) [150.724274] Starting subtest: addfb25-y-tiled-legacy [150.725365] Subtest addfb25-y-tiled-legacy: SUCCESS (0.000s) [150.824790] [061/171] (894s left) kms_addfb_basic (addfb25-y-tiled-small-legacy) [150.990483] Starting subtest: addfb25-y-tiled-small-legacy [150.991553] Subtest addfb25-y-tiled-small-legacy: SUCCESS (0.000s) [151.092252] [062/171] (894s left) kms_addfb_basic (bad-pitch-0) [151.228445] Starting subtest: bad-pitch-0 [151.229596] Subtest bad-pitch-0: SUCCESS (0.000s) [151.359430] [063/171] (893s left) kms_addfb_basic (bad-pitch-1024) [151.487618] Starting subtest: bad-pitch-1024 [151.488946] Subtest bad-pitch-1024: SUCCESS (0.000s) [151.627736] [064/171] (893s left) kms_addfb_basic (bad-pitch-128) [151.758269] Starting subtest: bad-pitch-128 [151.759455] Subtest bad-pitch-128: SUCCESS (0.000s) [151.875556] [065/171] (893s left) kms_addfb_basic (bad-pitch-256) [152.004662] Starting subtest: bad-pitch-256 [152.005713] Subtest bad-pitch-256: SUCCESS (0.000s) [152.125057] [066/171] (893s left) kms_addfb_basic (bad-pitch-32) [152.262549] Starting subtest: bad-pitch-32 [152.263655] Subtest bad-pitch-32: SUCCESS (0.000s) [152.392092] [067/171] (892s left) kms_addfb_basic (bad-pitch-63) [152.530170] Starting subtest: bad-pitch-63 [152.531226] Subtest bad-pitch-63: SUCCESS (0.000s) [152.658739] [068/171] (892s left) kms_addfb_basic (bad-pitch-65536) [152.787710] Starting subtest: bad-pitch-65536 [152.788830] Subtest bad-pitch-65536: SUCCESS (0.000s) [152.908423] [069/171] (892s left) kms_addfb_basic (bad-pitch-999) [153.044550] Starting subtest: bad-pitch-999 [153.045653] Subtest bad-pitch-999: SUCCESS (0.000s) [153.175673] [070/171] (892s left) kms_addfb_basic (basic) [153.313359] Starting subtest: basic [153.314407] Subtest basic: SUCCESS (0.000s) [153.442201] [071/171] (891s left) kms_addfb_basic (basic-x-tiled-legacy) [153.573480] Starting subtest: basic-x-tiled-legacy [153.574535] Subtest basic-x-tiled-legacy: SUCCESS (0.000s) [153.692699] [072/171] (891s left) kms_addfb_basic (basic-y-tiled-legacy) [153.821813] Starting subtest: basic-y-tiled-legacy [153.822924] Subtest basic-y-tiled-legacy: SUCCESS (0.000s) [153.942270] [073/171] (891s left) kms_addfb_basic (bo-too-small) [154.110463] Starting subtest: bo-too-small [154.111534] Subtest bo-too-small: SUCCESS (0.000s) [154.208309] [074/171] (891s left) kms_addfb_basic (bo-too-small-due-to-tiling) [154.375559] Starting subtest: bo-too-small-due-to-tiling [154.376723] Subtest bo-too-small-due-to-tiling: SUCCESS (0.000s) [154.475073] [075/171] (890s left) kms_addfb_basic (clobberred-modifier) [154.602575] Starting subtest: clobberred-modifier [154.603877] Subtest clobberred-modifier: SUCCESS (0.000s) [154.726345] [076/171] (890s left) kms_addfb_basic (framebuffer-vs-set-tiling) [154.864553] Starting subtest: framebuffer-vs-set-tiling [154.865609] Subtest framebuffer-vs-set-tiling: SUCCESS (0.000s) [154.992146] [077/171] (890s left) kms_addfb_basic (invalid-get-prop) [155.130305] Starting subtest: invalid-get-prop [155.131487] Subtest invalid-get-prop: SUCCESS (0.000s) [155.259328] [078/171] (890s left) kms_addfb_basic (invalid-get-prop-any) [155.394436] Starting subtest: invalid-get-prop-any [155.395486] Subtest invalid-get-prop-any: SUCCESS (0.000s) [155.527433] [079/171] (890s left) kms_addfb_basic (invalid-set-prop) [155.664392] Starting subtest: invalid-set-prop [155.665482] Subtest invalid-set-prop: SUCCESS (0.000s) [155.791927] [080/171] (889s left) kms_addfb_basic (invalid-set-prop-any) [155.930648] Starting subtest: invalid-set-prop-any [155.931737] Subtest invalid-set-prop-any: SUCCESS (0.000s) [156.058649] [081/171] (889s left) kms_addfb_basic (no-handle) [156.194832] Starting subtest: no-handle [156.195966] Subtest no-handle: SUCCESS (0.000s) [156.325936] [082/171] (889s left) kms_addfb_basic (size-max) [156.492189] Starting subtest: size-max [156.493267] Subtest size-max: SUCCESS (0.000s) [156.591853] [083/171] (889s left) kms_addfb_basic (small-bo) [156.750479] Starting subtest: small-bo [156.751497] Subtest small-bo: SUCCESS (0.000s) [156.842120] [084/171] (888s left) kms_addfb_basic (tile-pitch-mismatch) [156.979602] Starting subtest: tile-pitch-mismatch [156.980642] Subtest tile-pitch-mismatch: SUCCESS (0.000s) [157.108870] [085/171] (888s left) kms_addfb_basic (too-high) [157.266875] Starting subtest: too-high [157.268098] Subtest too-high: SUCCESS (0.001s) [157.358463] [086/171] (888s left) kms_addfb_basic (too-wide) [157.524474] Starting subtest: too-wide [157.525685] Subtest too-wide: SUCCESS (0.000s) [157.625034] [087/171] (888s left) kms_addfb_basic (unused-handle) [157.762427] Starting subtest: unused-handle [157.763531] Subtest unused-handle: SUCCESS (0.000s) [157.891991] [088/171] (887s left) kms_addfb_basic (unused-modifier) [158.019354] Starting subtest: unused-modifier [158.020509] Subtest unused-modifier: SUCCESS (0.000s) [158.141729] [089/171] (887s left) kms_addfb_basic (unused-offsets) [158.278492] Starting subtest: unused-offsets [158.279614] Subtest unused-offsets: SUCCESS (0.000s) [158.408963] [090/171] (887s left) kms_addfb_basic (unused-pitches) [158.546500] Starting subtest: unused-pitches [158.547625] Subtest unused-pitches: SUCCESS (0.000s) [158.678997] [091/171] (887s left) kms_busy (basic) [158.866101] Starting subtest: basic [158.868654] Starting dynamic subtest: flip [159.914886] Dynamic subtest flip: SUCCESS (1.044s) [159.931075] Starting dynamic subtest: modeset [163.764150] Dynamic subtest modeset: SUCCESS (3.851s) [163.765209] Subtest basic: SUCCESS (4.897s) [164.736622] [092/171] (881s left) kms_chamelium_hpd (dp-hpd-fast) [165.219658] Subtest dp-hpd-fast: SKIP (0.000s) [166.091113] [093/171] (879s left) kms_chamelium_edid (dp-edid-read) [166.572837] Subtest dp-edid-read: SKIP (0.000s) [167.436835] [094/171] (878s left) kms_chamelium_frames (dp-crc-fast) [167.916443] Subtest dp-crc-fast: SKIP (0.000s) [168.791572] [095/171] (877s left) kms_chamelium_hpd (hdmi-hpd-fast) [169.265828] Subtest hdmi-hpd-fast: SKIP (0.000s) [170.124484] [096/171] (875s left) kms_chamelium_edid (hdmi-edid-read) [170.601130] Subtest hdmi-edid-read: SKIP (0.000s) [171.471572] [097/171] (874s left) kms_chamelium_frames (hdmi-crc-fast) [171.950409] Subtest hdmi-crc-fast: SKIP (0.000s) [172.813349] [098/171] (873s left) kms_chamelium_hpd (vga-hpd-fast) [173.293353] Subtest vga-hpd-fast: SKIP (0.000s) [174.156564] [099/171] (871s left) kms_chamelium_edid (vga-edid-read) [174.639488] Subtest vga-edid-read: SKIP (0.000s) [175.500210] [100/171] (870s left) kms_prop_blob (basic) [175.638546] Starting subtest: basic [175.639749] Subtest basic: SUCCESS (0.000s) [175.717275] [101/171] (870s left) kms_cursor_legacy (basic-busy-flip-before-cursor-atomic) [175.883618] Starting subtest: basic-busy-flip-before-cursor-atomic [175.885768] Subtest basic-busy-flip-before-cursor-atomic: SKIP (0.000s) [175.964838] [102/171] (870s left) kms_cursor_legacy (basic-busy-flip-before-cursor-legacy) [176.130445] Starting subtest: basic-busy-flip-before-cursor-legacy [176.132451] Subtest basic-busy-flip-before-cursor-legacy: SKIP (0.000s) [176.215157] [103/171] (870s left) kms_cursor_legacy (basic-flip-after-cursor-atomic) [176.379420] Starting subtest: basic-flip-after-cursor-atomic [177.661408] Subtest basic-flip-after-cursor-atomic: SUCCESS (1.281s) [178.619805] [104/171] (867s left) kms_cursor_legacy (basic-flip-after-cursor-legacy) [178.785347] Starting subtest: basic-flip-after-cursor-legacy [180.080544] Subtest basic-flip-after-cursor-legacy: SUCCESS (1.295s) [181.055147] [105/171] (865s left) kms_cursor_legacy (basic-flip-after-cursor-varying-size) [181.221242] Starting subtest: basic-flip-after-cursor-varying-size [182.515087] Subtest basic-flip-after-cursor-varying-size: SUCCESS (1.295s) [183.485736] [106/171] (862s left) kms_cursor_legacy (basic-flip-before-cursor-atomic) [183.651106] Starting subtest: basic-flip-before-cursor-atomic [184.946381] Subtest basic-flip-before-cursor-atomic: SUCCESS (1.294s) [185.919214] [107/171] (860s left) kms_cursor_legacy (basic-flip-before-cursor-legacy) [186.077092] Starting subtest: basic-flip-before-cursor-legacy [187.368519] Subtest basic-flip-before-cursor-legacy: SUCCESS (1.285s) [188.285901] [108/171] (858s left) kms_cursor_legacy (basic-flip-before-cursor-varying-size) [188.443479] Starting subtest: basic-flip-before-cursor-varying-size [189.714120] Subtest basic-flip-before-cursor-varying-size: SUCCESS (1.269s) [190.655186] [109/171] (855s left) kms_flip (basic-flip-vs-dpms) [190.800749] Starting subtest: basic-flip-vs-dpms [190.803962] Starting dynamic subtest: A-eDP1 [193.658415] Dynamic subtest A-eDP1: SUCCESS (2.854s) [193.674935] Starting dynamic subtest: B-eDP1 [196.015116] Dynamic subtest B-eDP1: SUCCESS (2.350s) [196.030260] Starting dynamic subtest: C-eDP1 [198.377689] Dynamic subtest C-eDP1: SUCCESS (2.367s) [198.379435] Subtest basic-flip-vs-dpms: SUCCESS (7.580s) [199.292870] [110/171] (847s left) kms_flip (basic-flip-vs-modeset) [199.432334] Starting subtest: basic-flip-vs-modeset [199.436732] Starting dynamic subtest: A-eDP1 [202.300390] Dynamic subtest A-eDP1: SUCCESS (2.858s) [202.315262] Starting dynamic subtest: B-eDP1 [204.662753] Dynamic subtest B-eDP1: SUCCESS (2.367s) [204.683038] Starting dynamic subtest: C-eDP1 [207.023078] Dynamic subtest C-eDP1: SUCCESS (2.350s) [207.024382] Subtest basic-flip-vs-modeset: SUCCESS (7.584s) [207.936096] [111/171] (838s left) kms_flip (basic-flip-vs-wf_vblank) [208.073734] Starting subtest: basic-flip-vs-wf_vblank [208.077570] Starting dynamic subtest: A-eDP1 [210.398354] Dynamic subtest A-eDP1: SUCCESS (2.321s) [210.414581] Starting dynamic subtest: B-eDP1 [212.685355] Dynamic subtest B-eDP1: SUCCESS (2.286s) [212.701778] Starting dynamic subtest: C-eDP1 [214.987300] Dynamic subtest C-eDP1: SUCCESS (2.301s) [214.990156] Subtest basic-flip-vs-wf_vblank: SUCCESS (6.916s) [216.317811] [112/171] (830s left) kms_flip (basic-plain-flip) [216.460480] Starting subtest: basic-plain-flip [216.464148] Starting dynamic subtest: A-eDP1 [218.498720] Dynamic subtest A-eDP1: SUCCESS (2.035s) [218.515199] Starting dynamic subtest: B-eDP1 [220.472674] Dynamic subtest B-eDP1: SUCCESS (1.973s) [220.489206] Starting dynamic subtest: C-eDP1 [222.452133] Dynamic subtest C-eDP1: SUCCESS (1.979s) [222.454685] Subtest basic-plain-flip: SUCCESS (5.995s) [223.742214] [113/171] (822s left) kms_force_connector_basic (force-connector-state) [223.879017] Starting subtest: force-connector-state [225.574612] Subtest force-connector-state: SUCCESS (1.697s) [226.185936] [114/171] (820s left) kms_force_connector_basic (force-edid) [226.339343] Starting subtest: force-edid [226.590680] Subtest force-edid: SUCCESS (0.252s) [226.762549] [115/171] (819s left) kms_force_connector_basic (force-load-detect) [226.894874] Starting subtest: force-load-detect [226.897330] Subtest force-load-detect: SKIP (0.000s) [227.017314] [116/171] (819s left) kms_force_connector_basic (prune-stale-modes) [227.155484] Starting subtest: prune-stale-modes [227.465155] Subtest prune-stale-modes: SUCCESS (0.306s) [227.600490] [117/171] (819s left) kms_frontbuffer_tracking (basic) [227.765904] Starting subtest: basic [231.082304] Subtest basic: SUCCESS (3.316s) [231.202216] [118/171] (815s left) kms_pipe_crc_basic (compare-crc-sanitycheck-xr24) [231.375315] Starting subtest: compare-crc-sanitycheck-xr24 [231.376962] Starting dynamic subtest: pipe-A-eDP-1 [232.068164] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.685s) [232.084363] Starting dynamic subtest: pipe-B-eDP-1 [233.436768] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.369s) [233.455594] Starting dynamic subtest: pipe-C-eDP-1 [234.991082] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.540s) [234.992277] Subtest compare-crc-sanitycheck-xr24: SUCCESS (3.606s) [235.896871] [119/171] (810s left) kms_pipe_crc_basic (compare-crc-sanitycheck-nv12) [236.064125] Starting subtest: compare-crc-sanitycheck-nv12 [236.067386] Starting dynamic subtest: pipe-A-eDP-1 [236.971823] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.904s) [236.990614] Starting dynamic subtest: pipe-B-eDP-1 [238.421829] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.445s) [238.446674] Starting dynamic subtest: pipe-C-eDP-1 [240.181820] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.756s) [240.188484] Subtest compare-crc-sanitycheck-nv12: SUCCESS (4.117s) [241.146591] [120/171] (805s left) kms_pipe_crc_basic (hang-read-crc) [241.314881] Starting subtest: hang-read-crc [241.318365] Starting dynamic subtest: pipe-A-eDP-1 [243.639088] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.321s) [243.658549] Starting dynamic subtest: pipe-B-eDP-1 [246.631314] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.987s) [246.649452] Starting dynamic subtest: pipe-C-eDP-1 [249.913381] Dynamic subtest pipe-C-eDP-1: SUCCESS (3.278s) [249.914484] Subtest hang-read-crc: SUCCESS (8.598s) [250.874428] [121/171] (795s left) kms_pipe_crc_basic (nonblocking-crc) [251.043677] Starting subtest: nonblocking-crc [251.046732] Starting dynamic subtest: pipe-A-eDP-1 [251.899170] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.852s) [251.918389] Starting dynamic subtest: pipe-B-eDP-1 [253.451362] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.547s) [253.470150] Starting dynamic subtest: pipe-C-eDP-1 [255.159761] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.703s) [255.160900] Subtest nonblocking-crc: SUCCESS (4.115s) [256.120744] [122/171] (790s left) kms_pipe_crc_basic (nonblocking-crc-frame-sequence) [256.280596] Starting subtest: nonblocking-crc-frame-sequence [256.284196] Starting dynamic subtest: pipe-A-eDP-1 [257.146518] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.862s) [257.165952] Starting dynamic subtest: pipe-B-eDP-1 [258.699127] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.547s) [258.717320] Starting dynamic subtest: pipe-C-eDP-1 [260.401870] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.699s) [260.403087] Subtest nonblocking-crc-frame-sequence: SUCCESS (4.121s) [261.368777] [123/171] (785s left) kms_pipe_crc_basic (read-crc) [261.537488] Starting subtest: read-crc [261.539040] Starting dynamic subtest: pipe-A-eDP-1 [262.328465] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.789s) [262.347853] Starting dynamic subtest: pipe-B-eDP-1 [263.814852] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.481s) [263.832952] Starting dynamic subtest: pipe-C-eDP-1 [265.454468] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.636s) [265.455612] Subtest read-crc: SUCCESS (3.918s) [266.424835] [124/171] (780s left) kms_pipe_crc_basic (read-crc-frame-sequence) [266.592068] Starting subtest: read-crc-frame-sequence [266.593896] Starting dynamic subtest: pipe-A-eDP-1 [267.383042] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.788s) [267.401672] Starting dynamic subtest: pipe-B-eDP-1 [268.871714] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.485s) [268.890962] Starting dynamic subtest: pipe-C-eDP-1 [270.516656] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.638s) [270.517709] Subtest read-crc-frame-sequence: SUCCESS (3.924s) [271.480978] [125/171] (775s left) kms_psr (primary_page_flip) [271.644831] Starting subtest: primary_page_flip [275.842705] Subtest primary_page_flip: SUCCESS (4.198s) [276.792829] [126/171] (770s left) kms_psr (cursor_plane_move) [276.962419] Starting subtest: cursor_plane_move [281.118427] Subtest cursor_plane_move: SUCCESS (4.155s) [282.042831] [127/171] (764s left) kms_psr (sprite_plane_onoff) [282.213999] Starting subtest: sprite_plane_onoff [286.465174] Subtest sprite_plane_onoff: SUCCESS (4.251s) [287.416816] [128/171] (759s left) kms_psr (primary_mmap_gtt) [287.586431] Starting subtest: primary_mmap_gtt [291.766204] Subtest primary_mmap_gtt: SUCCESS (4.169s) [292.729044] [129/171] (754s left) kms_setmode (basic-clone-single-crtc) [292.868657] Starting subtest: basic-clone-single-crtc [292.871710] Subtest basic-clone-single-crtc: SKIP (0.000s) [292.945332] [130/171] (754s left) i915_pm_backlight (basic-brightness) [293.114238] Starting subtest: basic-brightness [293.235299] Starting dynamic subtest: eDP-1 [293.803867] Dynamic subtest eDP-1: SUCCESS (0.577s) [293.805084] Subtest basic-brightness: SUCCESS (0.691s) [294.777503] [131/171] (752s left) i915_pm_rpm (basic-pci-d3-state) [296.666416] Starting subtest: basic-pci-d3-state [301.079256] Subtest basic-pci-d3-state: SUCCESS (4.407s) [301.277470] [132/171] (745s left) i915_pm_rpm (basic-rte) [301.412920] Starting subtest: basic-rte [307.556333] Subtest basic-rte: SUCCESS (6.125s) [312.846892] [133/171] (734s left) i915_pm_rps (basic-api) [312.993026] Starting subtest: basic-api [312.997482] Subtest basic-api: SUCCESS (0.005s) [313.078437] [134/171] (734s left) prime_self_import (basic-llseek-bad) [313.205235] Starting subtest: basic-llseek-bad [313.209539] Subtest basic-llseek-bad: SUCCESS (0.003s) [313.316999] [135/171] (733s left) prime_self_import (basic-llseek-size) [313.444736] Starting subtest: basic-llseek-size [313.449460] Subtest basic-llseek-size: SUCCESS (0.004s) [313.549997] [136/171] (733s left) prime_self_import (basic-with_fd_dup) [313.684043] Starting subtest: basic-with_fd_dup [313.688518] Subtest basic-with_fd_dup: SUCCESS (0.004s) [313.799855] [137/171] (733s left) prime_self_import (basic-with_one_bo) [313.934023] Starting subtest: basic-with_one_bo [313.939091] Subtest basic-with_one_bo: SUCCESS (0.005s) [314.026992] [138/171] (733s left) prime_self_import (basic-with_one_bo_two_files) [314.162518] Starting subtest: basic-with_one_bo_two_files [314.167166] Subtest basic-with_one_bo_two_files: SUCCESS (0.004s) [314.265620] [139/171] (733s left) prime_self_import (basic-with_two_bos) [314.400909] Starting subtest: basic-with_two_bos [314.405500] Subtest basic-with_two_bos: SUCCESS (0.005s) [314.517416] [140/171] (732s left) prime_vgem (basic-fence-flip) [314.666019] Starting subtest: basic-fence-flip [315.191282] Subtest basic-fence-flip: SUCCESS (0.524s) [315.328507] [141/171] (732s left) prime_vgem (basic-fence-mmap) [315.483250] Starting subtest: basic-fence-mmap [315.530392] Subtest basic-fence-mmap: SUCCESS (0.047s) [315.618364] [142/171] (731s left) prime_vgem (basic-fence-read) [315.778775] Starting subtest: basic-fence-read [315.852204] Subtest basic-fence-read: SUCCESS (0.073s) [315.950229] [143/171] (731s left) prime_vgem (basic-gtt) [316.099204] Starting subtest: basic-gtt [316.122533] Subtest basic-gtt: SUCCESS (0.023s) [316.235681] [144/171] (731s left) prime_vgem (basic-read) [316.382865] Starting subtest: basic-read [316.418872] Subtest basic-read: SUCCESS (0.036s) [316.535980] [145/171] (730s left) prime_vgem (basic-write) [316.682433] Starting subtest: basic-write [316.723953] Subtest basic-write: SUCCESS (0.041s) [316.821043] [146/171] (730s left) vgem_basic (setversion) [316.975148] Starting subtest: setversion [316.977567] Subtest setversion: SUCCESS (0.000s) [317.049592] [147/171] (730s left) vgem_basic (create) [317.192305] Starting subtest: create [317.193455] Subtest create: SUCCESS (0.000s) [317.263799] [148/171] (730s left) vgem_basic (debugfs) [317.408425] Starting subtest: debugfs [317.409556] Subtest debugfs: SUCCESS (0.001s) [317.483470] [149/171] (730s left) vgem_basic (dmabuf-export) [317.625267] Starting subtest: dmabuf-export [317.635172] Subtest dmabuf-export: SUCCESS (0.009s) [317.751317] [150/171] (729s left) vgem_basic (dmabuf-fence) [317.910430] Starting subtest: dmabuf-fence [317.911497] Subtest dmabuf-fence: SUCCESS (0.001s) [317.979192] [151/171] (729s left) vgem_basic (dmabuf-fence-before) [318.127840] Starting subtest: dmabuf-fence-before [318.128983] Subtest dmabuf-fence-before: SUCCESS (0.000s) [318.196817] [152/171] (729s left) vgem_basic (dmabuf-mmap) [318.342409] Starting subtest: dmabuf-mmap [318.368289] Subtest dmabuf-mmap: SUCCESS (0.026s) [318.446053] [153/171] (729s left) vgem_basic (mmap) [318.594159] Starting subtest: mmap [318.608711] Subtest mmap: SUCCESS (0.016s) [318.679903] [154/171] (729s left) vgem_basic (second-client) [318.827108] Starting subtest: second-client [318.860324] Subtest second-client: SUCCESS (0.033s) [318.929953] [155/171] (728s left) vgem_basic (sysfs) [319.077174] Starting subtest: sysfs [319.078246] Subtest sysfs: SUCCESS (0.001s) [319.146102] [156/171] (728s left) core_hotunplug (unbind-rebind) [319.326390] Starting subtest: unbind-rebind [321.724672] Subtest unbind-rebind: SUCCESS (2.399s) [321.760489] [157/171] (726s left) vgem_basic (unload) [321.895856] Starting subtest: unload [350.949620] Subtest unload: SUCCESS (29.019s) [351.047792] [158/171] (696s left) i915_module_load (reload) [351.175589] Starting subtest: reload [354.641087] Subtest reload: SUCCESS (3.465s) [354.671915] [159/171] (693s left) gem_lmem_swapping (basic) [354.829916] Subtest basic: SKIP (0.000s) [354.876266] [160/171] (693s left) gem_lmem_swapping (parallel-random-engines) [355.012334] Subtest parallel-random-engines: SKIP (0.000s) [355.058878] [161/171] (692s left) gem_lmem_swapping (random-engines) [355.205234] Subtest random-engines: SKIP (0.000s) [355.258729] [162/171] (692s left) gem_lmem_swapping (verify-random) [355.403637] Subtest verify-random: SKIP (0.000s) [355.459401] [163/171] (692s left) i915_pm_rpm (module-reload) [367.954861] Starting subtest: module-reload [402.720602] Subtest module-reload: SUCCESS (34.758s) [402.744061] [164/171] (645s left) i915_selftest (live) [402.888578] Starting subtest: live [402.889915] Starting dynamic subtest: sanitycheck [404.765320] Dynamic subtest sanitycheck: SUCCESS (1.867s) [404.779567] Starting dynamic subtest: uncore [406.556285] Dynamic subtest uncore: SUCCESS (1.789s) [406.570601] Starting dynamic subtest: workarounds [408.624221] Dynamic subtest workarounds: SUCCESS (2.066s) [408.638509] Starting dynamic subtest: gt_engines [410.912881] Dynamic subtest gt_engines: SUCCESS (2.286s) [410.927666] Starting dynamic subtest: gt_timelines [423.483870] Dynamic subtest gt_timelines: SUCCESS (12.569s) [423.499786] Starting dynamic subtest: gt_contexts [425.455340] Dynamic subtest gt_contexts: SUCCESS (1.970s) [425.469863] Starting dynamic subtest: gt_lrc [429.794218] Dynamic subtest gt_lrc: SUCCESS (4.337s) [429.808617] Starting dynamic subtest: gt_mocs [431.700460] Dynamic subtest gt_mocs: SUCCESS (1.904s) [431.714864] Starting dynamic subtest: gt_pm [436.749146] Dynamic subtest gt_pm: SUCCESS (5.047s) [436.763691] Starting dynamic subtest: gt_heartbeat [438.620777] Dynamic subtest gt_heartbeat: SUCCESS (1.869s) [438.635317] Starting dynamic subtest: gt_tlb [441.714818] Dynamic subtest gt_tlb: SUCCESS (3.092s) [441.731880] Starting dynamic subtest: requests [449.332553] Dynamic subtest requests: SUCCESS (7.616s) [449.347141] Starting dynamic subtest: migrate [453.959669] Dynamic subtest migrate: SUCCESS (4.625s) [453.974406] Starting dynamic subtest: active [455.835424] Dynamic subtest active: SUCCESS (1.874s) [455.850106] Starting dynamic subtest: objects [459.159949] Dynamic subtest objects: SUCCESS (3.322s) [459.174697] Starting dynamic subtest: mman [466.057070] Dynamic subtest mman: SUCCESS (6.895s) [466.074823] Starting dynamic subtest: dmabuf [467.874039] Dynamic subtest dmabuf: SUCCESS (1.815s) [467.888650] Starting dynamic subtest: vma [469.716387] Dynamic subtest vma: SUCCESS (1.841s) [469.731093] Starting dynamic subtest: coherency [474.229139] Dynamic subtest coherency: SUCCESS (4.511s) [474.243713] Starting dynamic subtest: gtt [483.707472] Dynamic subtest gtt: SUCCESS (9.477s) [483.721946] Starting dynamic subtest: gem [485.678721] Dynamic subtest gem: SUCCESS (1.969s) [485.695024] Starting dynamic subtest: evict [489.522497] Dynamic subtest evict: SUCCESS (3.842s) [489.537085] Starting dynamic subtest: hugepages [496.440291] Dynamic subtest hugepages: SUCCESS (6.916s) [496.455269] Starting dynamic subtest: gem_contexts [511.774312] Dynamic subtest gem_contexts: SUCCESS (15.306s) [511.789331] Starting dynamic subtest: client [513.570491] Dynamic subtest client: SUCCESS (1.821s) [513.585490] Starting dynamic subtest: gem_migrate [515.355104] Dynamic subtest gem_migrate: SUCCESS (1.783s) [515.371060] Starting dynamic subtest: reset [520.704457] Dynamic subtest reset: SUCCESS (5.348s) [520.719516] Starting dynamic subtest: memory_region [522.528361] Dynamic subtest memory_region: SUCCESS (1.822s) [522.543371] Starting dynamic subtest: hangcheck [546.965135] Dynamic subtest hangcheck: SUCCESS (24.435s) [546.980118] Starting dynamic subtest: execlists [557.088393] Dynamic subtest execlists: SUCCESS (10.121s) [557.103383] Starting dynamic subtest: ring_submission [558.950439] Dynamic subtest ring_submission: SUCCESS (1.860s) [558.965531] Starting dynamic subtest: perf [560.870454] Dynamic subtest perf: SUCCESS (1.918s) [560.885487] Starting dynamic subtest: slpc [562.650557] Dynamic subtest slpc: SUCCESS (1.778s) [562.665565] Starting dynamic subtest: guc [564.433390] Dynamic subtest guc: SUCCESS (1.781s) [564.448390] Starting dynamic subtest: guc_multi_lrc [566.284410] Dynamic subtest guc_multi_lrc: SUCCESS (1.849s) [566.299458] Starting dynamic subtest: guc_hang [568.098305] Dynamic subtest guc_hang: SUCCESS (1.812s) [568.113422] Starting dynamic subtest: late_gt_pm [569.959512] Dynamic subtest late_gt_pm: SUCCESS (1.859s) [569.960845] Subtest live: SUCCESS (167.062s) [570.143369] [165/171] (477s left) dmabuf (all-tests) [570.282283] Starting subtest: all-tests [570.283616] Starting dynamic subtest: sanitycheck [570.354840] Dynamic subtest sanitycheck: SUCCESS (0.071s) [570.374324] Starting dynamic subtest: dma_fence [570.543841] Dynamic subtest dma_fence: SUCCESS (0.187s) [570.560424] Starting dynamic subtest: dma_fence_chain [588.434479] Dynamic subtest dma_fence_chain: SUCCESS (17.880s) [588.447143] Starting dynamic subtest: dma_fence_unwrap [588.509592] Dynamic subtest dma_fence_unwrap: SUCCESS (0.082s) [588.526787] Starting dynamic subtest: dma_resv [588.593643] Dynamic subtest dma_resv: SUCCESS (0.082s) [588.595688] Subtest all-tests: SUCCESS (18.311s) [588.624821] [166/171] (459s left) i915_suspend (basic-s2idle-without-i915) [588.753201] Starting subtest: basic-s2idle-without-i915 [612.915309] Subtest basic-s2idle-without-i915: SUCCESS (8.567s) [613.211229] [167/171] (450s left) i915_suspend (basic-s3-without-i915) [613.360570] Starting subtest: basic-s3-without-i915 [636.885796] Subtest basic-s3-without-i915: SUCCESS (7.129s) [637.131087] [168/171] (443s left) gem_exec_suspend (basic-s0) [637.286894] Starting subtest: basic-S0 [637.288008] Starting dynamic subtest: smem [656.876354] Dynamic subtest smem: SUCCESS (3.817s) [656.877400] Subtest basic-S0: SUCCESS (3.818s) [657.037315] [169/171] (438s left) gem_exec_suspend (basic-s3) [657.185637] Starting subtest: basic-S3 [657.186760] Starting dynamic subtest: smem [675.859186] Dynamic subtest smem: SUCCESS (3.613s) [675.860255] Subtest basic-S3: SUCCESS (3.614s) [676.010318] [170/171] (435s left) kms_chamelium_hpd (common-hpd-after-suspend) [676.496310] Subtest common-hpd-after-suspend: SKIP (0.000s) [677.361385] [171/171] (433s left) kms_pipe_crc_basic (suspend-read-crc) [677.524374] Starting subtest: suspend-read-crc [677.527829] Starting dynamic subtest: pipe-A-eDP-1 [694.910586] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.527s) [694.932757] Starting dynamic subtest: pipe-B-eDP-1 [713.815973] Dynamic subtest pipe-B-eDP-1: SUCCESS (3.112s) [713.839079] Starting dynamic subtest: pipe-C-eDP-1 [732.807332] Dynamic subtest pipe-C-eDP-1: SUCCESS (3.247s) [732.808451] Subtest suspend-read-crc: SUCCESS (8.900s) [733.728466] Closing watchdogs Done.