[39.764901] Initializing watchdogs [39.765096] /dev/watchdog0 [39.774212] [001/171] (960s left) i915_module_load (load) [40.038374] Starting subtest: load [42.449085] Subtest load: SUCCESS (2.407s) [42.478278] [002/171] (957s left) core_auth (basic-auth) [42.624801] Starting subtest: basic-auth [42.628573] Subtest basic-auth: SUCCESS (0.004s) [42.709449] [003/171] (957s left) debugfs_test (read_all_entries) [42.856666] Starting subtest: read_all_entries [42.946988] Subtest read_all_entries: SUCCESS (0.090s) [43.058934] [004/171] (957s left) debugfs_test (basic-hwmon) [43.189572] Starting subtest: basic-hwmon [43.191692] Subtest basic-hwmon: SKIP (0.000s) [43.292884] [005/171] (957s left) fbdev (eof) [43.445870] Starting subtest: eof [43.447083] Subtest eof: SUCCESS (0.001s) [43.504280] [006/171] (956s left) fbdev (info) [43.615240] Starting subtest: info [43.616390] Subtest info: SUCCESS (0.000s) [43.690329] [007/171] (956s left) fbdev (nullptr) [43.811344] Starting subtest: nullptr [43.812522] Subtest nullptr: SUCCESS (0.000s) [43.877132] [008/171] (956s left) fbdev (read) [44.012241] Starting subtest: read [51.114023] Subtest read: SUCCESS (7.067s) [51.140619] [009/171] (949s left) fbdev (write) [51.278670] Starting subtest: write [55.049926] Subtest write: SUCCESS (3.736s) [55.096604] [010/171] (945s left) gem_basic (bad-close) [55.219177] Starting subtest: bad-close [55.221259] Subtest bad-close: SUCCESS (0.000s) [55.290629] [011/171] (945s left) gem_basic (create-close) [55.414991] Starting subtest: create-close [55.417202] Subtest create-close: SUCCESS (0.000s) [55.512141] [012/171] (945s left) gem_basic (create-fd-close) [55.634582] Starting subtest: create-fd-close [55.636654] Subtest create-fd-close: SUCCESS (0.000s) [55.731476] [013/171] (944s left) gem_busy (busy@all-engines) [55.867372] Starting subtest: busy [55.868651] Starting dynamic subtest: all-engines [55.913446] Dynamic subtest all-engines: SUCCESS (0.046s) [55.914778] Subtest busy: SUCCESS (0.046s) [56.297299] [014/171] (944s left) gem_close_race (basic-process) [56.438502] Starting subtest: basic-process [56.500755] Subtest basic-process: SUCCESS (0.062s) [56.697197] [015/171] (943s left) gem_close_race (basic-threads) [56.836197] Starting subtest: basic-threads [58.005790] Subtest basic-threads: SUCCESS (1.169s) [58.222997] [016/171] (942s left) gem_ctx_create (basic) [58.358543] Starting subtest: basic [58.359798] Subtest basic: SUCCESS (0.000s) [58.564808] [017/171] (942s left) gem_ctx_create (basic-files) [58.698267] Starting subtest: basic-files [60.717809] Subtest basic-files: SUCCESS (2.019s) [60.941034] [018/171] (939s left) gem_ctx_exec (basic) [61.075377] Starting subtest: basic [61.079245] Subtest basic: SUCCESS (0.004s) [61.191990] [019/171] (939s left) gem_exec_basic (basic) [61.320328] Starting subtest: basic [61.321429] Starting dynamic subtest: rcs0-smem [61.322424] Dynamic subtest rcs0-smem: SUCCESS (0.002s) [61.334289] Starting dynamic subtest: bcs0-smem [61.335488] Dynamic subtest bcs0-smem: SUCCESS (0.001s) [61.345684] Starting dynamic subtest: vcs0-smem [61.346983] Dynamic subtest vcs0-smem: SUCCESS (0.001s) [61.356913] Starting dynamic subtest: vecs0-smem [61.358179] Dynamic subtest vecs0-smem: SUCCESS (0.001s) [61.359375] Subtest basic: SUCCESS (0.004s) [61.532308] [020/171] (939s left) gem_exec_create (basic) [61.665637] Starting subtest: basic [61.666877] Starting dynamic subtest: smem [63.729077] Dynamic subtest smem: SUCCESS (2.047s) [63.730379] Subtest basic: SUCCESS (2.047s) [63.933207] [021/171] (936s left) gem_exec_fence (basic-busy) [64.207291] Starting subtest: basic-busy [64.208569] Starting dynamic subtest: rcs0 [64.236843] Dynamic subtest rcs0: SUCCESS (0.034s) [64.249480] Starting dynamic subtest: bcs0 [64.260275] Dynamic subtest bcs0: SUCCESS (0.024s) [64.270929] Starting dynamic subtest: vcs0 [64.286336] Dynamic subtest vcs0: SUCCESS (0.026s) [64.296937] Starting dynamic subtest: vecs0 [64.304594] Dynamic subtest vecs0: SUCCESS (0.018s) [64.305938] Subtest basic-busy: SUCCESS (0.103s) [64.880451] [022/171] (935s left) gem_exec_fence (basic-wait) [65.161934] Starting subtest: basic-wait [65.163464] Starting dynamic subtest: rcs0 [65.193300] Dynamic subtest rcs0: SUCCESS (0.035s) [65.211452] Starting dynamic subtest: bcs0 [65.224976] Dynamic subtest bcs0: SUCCESS (0.032s) [65.236132] Starting dynamic subtest: vcs0 [65.253454] Dynamic subtest vcs0: SUCCESS (0.028s) [65.264713] Starting dynamic subtest: vecs0 [65.279632] Dynamic subtest vecs0: SUCCESS (0.026s) [65.280815] Subtest basic-wait: SUCCESS (0.123s) [65.797667] [023/171] (935s left) gem_exec_fence (basic-await) [66.075902] Starting subtest: basic-await [66.077082] Starting dynamic subtest: rcs0 [66.157988] Dynamic subtest rcs0: SUCCESS (0.088s) [66.184463] Starting dynamic subtest: bcs0 [66.275348] Dynamic subtest bcs0: SUCCESS (0.117s) [66.302205] Starting dynamic subtest: vcs0 [66.393217] Dynamic subtest vcs0: SUCCESS (0.117s) [66.419315] Starting dynamic subtest: vecs0 [66.511998] Dynamic subtest vecs0: SUCCESS (0.118s) [66.513832] Subtest basic-await: SUCCESS (0.443s) [67.118144] [024/171] (933s left) gem_exec_fence (nb-await) [67.396932] Starting subtest: nb-await [67.398280] Starting dynamic subtest: rcs0 [67.454930] Dynamic subtest rcs0: SUCCESS (0.065s) [67.474751] Starting dynamic subtest: bcs0 [67.524692] Dynamic subtest bcs0: SUCCESS (0.070s) [67.545973] Starting dynamic subtest: vcs0 [67.596969] Dynamic subtest vcs0: SUCCESS (0.071s) [67.622892] Starting dynamic subtest: vecs0 [67.668995] Dynamic subtest vecs0: SUCCESS (0.072s) [67.670991] Subtest nb-await: SUCCESS (0.280s) [68.196835] [025/171] (932s left) gem_exec_gttfill (basic) [68.333159] Starting subtest: basic [76.169419] Subtest basic: SUCCESS (7.828s) [76.343818] [026/171] (924s left) gem_exec_parallel (engines) [76.540803] Starting subtest: engines [76.542106] Starting dynamic subtest: basic [79.016192] Dynamic subtest basic: SUCCESS (2.477s) [79.029098] Starting dynamic subtest: contexts [82.597196] Dynamic subtest contexts: SUCCESS (3.572s) [82.610016] Starting dynamic subtest: fds [86.583301] Dynamic subtest fds: SUCCESS (3.986s) [86.596356] Starting dynamic subtest: userptr [89.065666] Dynamic subtest userptr: SUCCESS (2.466s) [89.066924] Subtest engines: SUCCESS (12.502s) [89.242867] [027/171] (911s left) gem_exec_store (basic) [89.368438] Starting subtest: basic [89.428913] Subtest basic: SUCCESS (0.060s) [89.633733] [028/171] (911s left) gem_flink_basic (bad-flink) [89.744992] Starting subtest: bad-flink [89.746095] Subtest bad-flink: SUCCESS (0.000s) [89.831630] [029/171] (911s left) gem_flink_basic (bad-open) [89.942744] Starting subtest: bad-open [89.943985] Subtest bad-open: SUCCESS (0.000s) [90.032510] [030/171] (910s left) gem_flink_basic (basic) [90.144729] Starting subtest: basic [90.145920] Subtest basic: SUCCESS (0.000s) [90.248900] [031/171] (910s left) gem_flink_basic (double-flink) [90.358802] Starting subtest: double-flink [90.359935] Subtest double-flink: SUCCESS (0.000s) [90.447051] [032/171] (910s left) gem_flink_basic (flink-lifetime) [90.558360] Starting subtest: flink-lifetime [90.559494] Subtest flink-lifetime: SUCCESS (0.002s) [90.666089] [033/171] (910s left) gem_huc_copy (huc-copy) [90.783462] Starting subtest: huc-copy [90.785493] Subtest huc-copy: SKIP (0.000s) [90.905893] [034/171] (910s left) gem_linear_blits (basic) [91.172352] Starting subtest: basic [91.184208] Subtest basic: SUCCESS (0.012s) [91.316371] [035/171] (909s left) gem_mmap (basic) [91.492846] Starting subtest: basic [91.498185] Subtest basic: SUCCESS (0.001s) [91.580455] [036/171] (909s left) gem_mmap_gtt (basic) [91.692157] Starting subtest: basic [91.693290] Subtest basic: SUCCESS (0.001s) [91.779843] [037/171] (909s left) gem_render_linear_blits (basic) [91.896477] Starting subtest: basic [91.945869] Subtest basic: SUCCESS (0.049s) [91.993937] [038/171] (909s left) gem_render_tiled_blits (basic) [92.111961] Starting subtest: basic [92.170900] Subtest basic: SUCCESS (0.058s) [92.250284] [039/171] (908s left) gem_ringfill (basic-all) [92.501225] Starting subtest: basic-all [93.743147] Subtest basic-all: SUCCESS (1.234s) [93.815355] [040/171] (907s left) gem_softpin (allocator-basic) [93.932738] Starting subtest: allocator-basic [94.094871] Subtest allocator-basic: SUCCESS (0.153s) [94.198760] [041/171] (907s left) gem_softpin (allocator-basic-reserve) [94.315777] Starting subtest: allocator-basic-reserve [94.477796] Subtest allocator-basic-reserve: SUCCESS (0.153s) [94.581779] [042/171] (906s left) gem_softpin (safe-alignment) [94.697871] Starting subtest: safe-alignment [94.717062] Subtest safe-alignment: SUCCESS (0.019s) [94.836525] [043/171] (906s left) gem_sync (basic-all) [94.962612] Starting subtest: basic-all [97.398758] Subtest basic-all: SUCCESS (2.411s) [97.568658] [044/171] (903s left) gem_sync (basic-each) [97.713634] Starting subtest: basic-each [99.872891] Subtest basic-each: SUCCESS (2.159s) [100.099364] [045/171] (901s left) gem_tiled_blits (basic) [100.352794] Starting subtest: basic [100.380844] Subtest basic: SUCCESS (0.028s) [100.499423] [046/171] (900s left) gem_tiled_fence_blits (basic) [100.830696] Starting subtest: basic [100.858093] Subtest basic: SUCCESS (0.028s) [100.982331] [047/171] (900s left) gem_tiled_pread_basic [101.500215] [048/171] (899s left) gem_wait (busy@all-engines) [101.633596] Starting subtest: busy [101.634799] Starting dynamic subtest: all-engines [102.166718] Dynamic subtest all-engines: SUCCESS (0.533s) [102.167923] Subtest busy: SUCCESS (0.533s) [102.400139] [049/171] (899s left) gem_wait (wait@all-engines) [102.530400] Starting subtest: wait [102.531533] Starting dynamic subtest: all-engines [103.559343] Dynamic subtest all-engines: SUCCESS (1.029s) [103.560713] Subtest wait: SUCCESS (1.029s) [103.800518] [050/171] (897s left) i915_getparams_basic (basic-eu-total) [103.913391] Starting subtest: basic-eu-total [103.915356] Subtest basic-eu-total: SUCCESS (0.000s) [103.998308] [051/171] (897s left) i915_getparams_basic (basic-subslice-total) [104.119471] Starting subtest: basic-subslice-total [104.121509] Subtest basic-subslice-total: SUCCESS (0.000s) [104.198615] [052/171] (897s left) i915_hangman (error-state-basic) [104.323594] Starting subtest: error-state-basic [104.333608] Subtest error-state-basic: SUCCESS (0.010s) [104.412501] [053/171] (897s left) i915_pciid [104.596553] [054/171] (896s left) kms_addfb_basic (addfb25-bad-modifier) [104.744162] Starting subtest: addfb25-bad-modifier [104.745168] Subtest addfb25-bad-modifier: SUCCESS (0.000s) [104.894181] [055/171] (896s left) kms_addfb_basic (addfb25-framebuffer-vs-set-tiling) [105.044835] Starting subtest: addfb25-framebuffer-vs-set-tiling [105.045917] Subtest addfb25-framebuffer-vs-set-tiling: SUCCESS (0.000s) [105.194398] [056/171] (896s left) kms_addfb_basic (addfb25-modifier-no-flag) [105.333386] Starting subtest: addfb25-modifier-no-flag [105.334375] Subtest addfb25-modifier-no-flag: SUCCESS (0.000s) [105.494608] [057/171] (896s left) kms_addfb_basic (addfb25-x-tiled-legacy) [105.644121] Starting subtest: addfb25-x-tiled-legacy [105.645145] Subtest addfb25-x-tiled-legacy: SUCCESS (0.000s) [105.794256] [058/171] (895s left) kms_addfb_basic (addfb25-x-tiled-mismatch-legacy) [105.933521] Starting subtest: addfb25-x-tiled-mismatch-legacy [105.934671] Subtest addfb25-x-tiled-mismatch-legacy: SUCCESS (0.000s) [106.077721] [059/171] (895s left) kms_addfb_basic (addfb25-yf-tiled-legacy) [106.240887] Starting subtest: addfb25-yf-tiled-legacy [106.241931] Subtest addfb25-yf-tiled-legacy: SUCCESS (0.000s) [106.344278] [060/171] (895s left) kms_addfb_basic (addfb25-y-tiled-legacy) [106.519484] Starting subtest: addfb25-y-tiled-legacy [106.520639] Subtest addfb25-y-tiled-legacy: SUCCESS (0.000s) [106.644376] [061/171] (895s left) kms_addfb_basic (addfb25-y-tiled-small-legacy) [106.818552] Starting subtest: addfb25-y-tiled-small-legacy [106.819703] Subtest addfb25-y-tiled-small-legacy: SUCCESS (0.000s) [106.928120] [062/171] (894s left) kms_addfb_basic (bad-pitch-0) [107.049179] Starting subtest: bad-pitch-0 [107.050310] Subtest bad-pitch-0: SUCCESS (0.000s) [107.244617] [063/171] (894s left) kms_addfb_basic (bad-pitch-1024) [107.357747] Starting subtest: bad-pitch-1024 [107.358810] Subtest bad-pitch-1024: SUCCESS (0.000s) [107.527633] [064/171] (894s left) kms_addfb_basic (bad-pitch-128) [107.650214] Starting subtest: bad-pitch-128 [107.651247] Subtest bad-pitch-128: SUCCESS (0.000s) [107.811272] [065/171] (894s left) kms_addfb_basic (bad-pitch-256) [107.923655] Starting subtest: bad-pitch-256 [107.924774] Subtest bad-pitch-256: SUCCESS (0.000s) [108.094349] [066/171] (893s left) kms_addfb_basic (bad-pitch-32) [108.205653] Starting subtest: bad-pitch-32 [108.206800] Subtest bad-pitch-32: SUCCESS (0.000s) [108.377663] [067/171] (893s left) kms_addfb_basic (bad-pitch-63) [108.489672] Starting subtest: bad-pitch-63 [108.490732] Subtest bad-pitch-63: SUCCESS (0.000s) [108.661135] [068/171] (893s left) kms_addfb_basic (bad-pitch-65536) [108.785691] Starting subtest: bad-pitch-65536 [108.786727] Subtest bad-pitch-65536: SUCCESS (0.000s) [108.944352] [069/171] (893s left) kms_addfb_basic (bad-pitch-999) [109.060920] Starting subtest: bad-pitch-999 [109.062141] Subtest bad-pitch-999: SUCCESS (0.000s) [109.244292] [070/171] (892s left) kms_addfb_basic (basic) [109.361336] Starting subtest: basic [109.362422] Subtest basic: SUCCESS (0.000s) [109.544271] [071/171] (892s left) kms_addfb_basic (basic-x-tiled-legacy) [109.737432] Starting subtest: basic-x-tiled-legacy [109.738515] Subtest basic-x-tiled-legacy: SUCCESS (0.000s) [109.827805] [072/171] (892s left) kms_addfb_basic (basic-y-tiled-legacy) [110.021137] Starting subtest: basic-y-tiled-legacy [110.022260] Subtest basic-y-tiled-legacy: SUCCESS (0.000s) [110.111308] [073/171] (891s left) kms_addfb_basic (bo-too-small) [110.257208] Starting subtest: bo-too-small [110.258213] Subtest bo-too-small: SUCCESS (0.000s) [110.394242] [074/171] (891s left) kms_addfb_basic (bo-too-small-due-to-tiling) [110.540277] Starting subtest: bo-too-small-due-to-tiling [110.541356] Subtest bo-too-small-due-to-tiling: SUCCESS (0.000s) [110.677717] [075/171] (891s left) kms_addfb_basic (clobberred-modifier) [110.799711] Starting subtest: clobberred-modifier [110.800785] Subtest clobberred-modifier: SUCCESS (0.000s) [110.961050] [076/171] (891s left) kms_addfb_basic (framebuffer-vs-set-tiling) [111.157228] Starting subtest: framebuffer-vs-set-tiling [111.158392] Subtest framebuffer-vs-set-tiling: SUCCESS (0.000s) [111.261104] [077/171] (890s left) kms_addfb_basic (invalid-get-prop) [111.457169] Starting subtest: invalid-get-prop [111.458321] Subtest invalid-get-prop: SUCCESS (0.000s) [111.561311] [078/171] (890s left) kms_addfb_basic (invalid-get-prop-any) [111.758381] Starting subtest: invalid-get-prop-any [111.759381] Subtest invalid-get-prop-any: SUCCESS (0.000s) [111.861099] [079/171] (890s left) kms_addfb_basic (invalid-set-prop) [112.059236] Starting subtest: invalid-set-prop [112.060307] Subtest invalid-set-prop: SUCCESS (0.000s) [112.160939] [080/171] (890s left) kms_addfb_basic (invalid-set-prop-any) [112.357792] Starting subtest: invalid-set-prop-any [112.359064] Subtest invalid-set-prop-any: SUCCESS (0.000s) [112.461370] [081/171] (889s left) kms_addfb_basic (no-handle) [112.583672] Starting subtest: no-handle [112.584761] Subtest no-handle: SUCCESS (0.000s) [112.761178] [082/171] (889s left) kms_addfb_basic (size-max) [112.901135] Starting subtest: size-max [112.902139] Subtest size-max: SUCCESS (0.000s) [113.044901] [083/171] (889s left) kms_addfb_basic (small-bo) [113.194274] Starting subtest: small-bo [113.195312] Subtest small-bo: SUCCESS (0.000s) [113.327760] [084/171] (888s left) kms_addfb_basic (tile-pitch-mismatch) [113.525231] Starting subtest: tile-pitch-mismatch [113.526387] Subtest tile-pitch-mismatch: SUCCESS (0.000s) [113.627402] [085/171] (888s left) kms_addfb_basic (too-high) [113.777172] Starting subtest: too-high [113.778206] Subtest too-high: SUCCESS (0.001s) [113.928061] [086/171] (888s left) kms_addfb_basic (too-wide) [114.078449] Starting subtest: too-wide [114.079456] Subtest too-wide: SUCCESS (0.000s) [114.230291] [087/171] (888s left) kms_addfb_basic (unused-handle) [114.352658] Starting subtest: unused-handle [114.353878] Subtest unused-handle: SUCCESS (0.000s) [114.527829] [088/171] (887s left) kms_addfb_basic (unused-modifier) [114.651639] Starting subtest: unused-modifier [114.652679] Subtest unused-modifier: SUCCESS (0.000s) [114.811206] [089/171] (887s left) kms_addfb_basic (unused-offsets) [114.934607] Starting subtest: unused-offsets [114.935693] Subtest unused-offsets: SUCCESS (0.000s) [115.110957] [090/171] (887s left) kms_addfb_basic (unused-pitches) [115.234698] Starting subtest: unused-pitches [115.235769] Subtest unused-pitches: SUCCESS (0.000s) [115.394569] [091/171] (887s left) kms_busy (basic) [115.544937] Starting subtest: basic [115.546376] Starting dynamic subtest: flip [116.512517] Dynamic subtest flip: SUCCESS (0.968s) [116.528949] Starting dynamic subtest: modeset [120.281304] Dynamic subtest modeset: SUCCESS (3.768s) [120.282350] Subtest basic: SUCCESS (4.736s) [121.253295] [092/171] (881s left) kms_chamelium_hpd (dp-hpd-fast) [121.722866] Subtest dp-hpd-fast: SKIP (0.000s) [122.609174] [093/171] (879s left) kms_chamelium_edid (dp-edid-read) [123.060687] Subtest dp-edid-read: SKIP (0.000s) [123.953937] [094/171] (878s left) kms_chamelium_frames (dp-crc-fast) [124.403130] Subtest dp-crc-fast: SKIP (0.000s) [125.294266] [095/171] (877s left) kms_chamelium_hpd (hdmi-hpd-fast) [125.748257] Subtest hdmi-hpd-fast: SKIP (0.000s) [126.639770] [096/171] (875s left) kms_chamelium_edid (hdmi-edid-read) [127.089867] Subtest hdmi-edid-read: SKIP (0.000s) [127.983831] [097/171] (874s left) kms_chamelium_frames (hdmi-crc-fast) [128.435373] Subtest hdmi-crc-fast: SKIP (0.000s) [129.325657] [098/171] (873s left) kms_chamelium_hpd (vga-hpd-fast) [129.774287] Subtest vga-hpd-fast: SKIP (0.000s) [130.672209] [099/171] (872s left) kms_chamelium_edid (vga-edid-read) [131.136717] Subtest vga-edid-read: SKIP (0.000s) [132.017051] [100/171] (870s left) kms_prop_blob (basic) [132.130675] Starting subtest: basic [132.131772] Subtest basic: SUCCESS (0.000s) [132.218453] [101/171] (870s left) kms_cursor_legacy (basic-busy-flip-before-cursor-atomic) [132.360360] Starting subtest: basic-busy-flip-before-cursor-atomic [132.362359] Subtest basic-busy-flip-before-cursor-atomic: SKIP (0.000s) [132.429208] [102/171] (870s left) kms_cursor_legacy (basic-busy-flip-before-cursor-legacy) [132.567449] Starting subtest: basic-busy-flip-before-cursor-legacy [132.569643] Subtest basic-busy-flip-before-cursor-legacy: SKIP (0.000s) [132.646567] [103/171] (870s left) kms_cursor_legacy (basic-flip-after-cursor-atomic) [132.788027] Starting subtest: basic-flip-after-cursor-atomic [134.082491] Subtest basic-flip-after-cursor-atomic: SUCCESS (1.289s) [135.009549] [104/171] (867s left) kms_cursor_legacy (basic-flip-after-cursor-legacy) [135.149760] Starting subtest: basic-flip-after-cursor-legacy [136.423644] Subtest basic-flip-after-cursor-legacy: SUCCESS (1.273s) [137.376753] [105/171] (865s left) kms_cursor_legacy (basic-flip-after-cursor-varying-size) [137.516503] Starting subtest: basic-flip-after-cursor-varying-size [138.792486] Subtest basic-flip-after-cursor-varying-size: SUCCESS (1.276s) [139.744302] [106/171] (863s left) kms_cursor_legacy (basic-flip-before-cursor-atomic) [139.884884] Starting subtest: basic-flip-before-cursor-atomic [141.172919] Subtest basic-flip-before-cursor-atomic: SUCCESS (1.288s) [142.112255] [107/171] (860s left) kms_cursor_legacy (basic-flip-before-cursor-legacy) [142.251994] Starting subtest: basic-flip-before-cursor-legacy [143.542909] Subtest basic-flip-before-cursor-legacy: SUCCESS (1.290s) [144.481626] [108/171] (858s left) kms_cursor_legacy (basic-flip-before-cursor-varying-size) [144.633410] Starting subtest: basic-flip-before-cursor-varying-size [145.927913] Subtest basic-flip-before-cursor-varying-size: SUCCESS (1.294s) [146.850655] [109/171] (856s left) kms_flip (basic-flip-vs-dpms) [146.972949] Starting subtest: basic-flip-vs-dpms [146.976472] Starting dynamic subtest: A-eDP1 [149.846699] Dynamic subtest A-eDP1: SUCCESS (2.871s) [149.862845] Starting dynamic subtest: B-eDP1 [152.217861] Dynamic subtest B-eDP1: SUCCESS (2.370s) [152.233971] Starting dynamic subtest: C-eDP1 [154.596403] Dynamic subtest C-eDP1: SUCCESS (2.365s) [154.597410] Subtest basic-flip-vs-dpms: SUCCESS (7.615s) [155.552401] [110/171] (847s left) kms_flip (basic-flip-vs-modeset) [155.673794] Starting subtest: basic-flip-vs-modeset [155.676999] Starting dynamic subtest: A-eDP1 [158.576542] Dynamic subtest A-eDP1: SUCCESS (2.898s) [158.592207] Starting dynamic subtest: B-eDP1 [160.926363] Dynamic subtest B-eDP1: SUCCESS (2.349s) [160.946369] Starting dynamic subtest: C-eDP1 [163.275602] Dynamic subtest C-eDP1: SUCCESS (2.349s) [163.277065] Subtest basic-flip-vs-modeset: SUCCESS (7.604s) [164.192938] [111/171] (838s left) kms_flip (basic-flip-vs-wf_vblank) [164.323094] Starting subtest: basic-flip-vs-wf_vblank [164.326170] Starting dynamic subtest: A-eDP1 [166.659247] Dynamic subtest A-eDP1: SUCCESS (2.333s) [166.675362] Starting dynamic subtest: B-eDP1 [168.948277] Dynamic subtest B-eDP1: SUCCESS (2.288s) [168.964506] Starting dynamic subtest: C-eDP1 [171.251766] Dynamic subtest C-eDP1: SUCCESS (2.303s) [171.254233] Subtest basic-flip-vs-wf_vblank: SUCCESS (6.933s) [172.514931] [112/171] (830s left) kms_flip (basic-plain-flip) [172.634738] Starting subtest: basic-plain-flip [172.638511] Starting dynamic subtest: A-eDP1 [174.693408] Dynamic subtest A-eDP1: SUCCESS (2.055s) [174.709241] Starting dynamic subtest: B-eDP1 [176.663110] Dynamic subtest B-eDP1: SUCCESS (1.968s) [176.678875] Starting dynamic subtest: C-eDP1 [178.647274] Dynamic subtest C-eDP1: SUCCESS (1.984s) [178.649757] Subtest basic-plain-flip: SUCCESS (6.015s) [179.937212] [113/171] (823s left) kms_force_connector_basic (force-connector-state) [180.056960] Starting subtest: force-connector-state [180.785495] Subtest force-connector-state: SUCCESS (0.729s) [181.667844] [114/171] (821s left) kms_force_connector_basic (force-edid) [181.793266] Starting subtest: force-edid [182.044618] Subtest force-edid: SUCCESS (0.251s) [182.151106] [115/171] (820s left) kms_force_connector_basic (force-load-detect) [182.266507] Starting subtest: force-load-detect [182.268684] Subtest force-load-detect: SKIP (0.000s) [182.367520] [116/171] (820s left) kms_force_connector_basic (prune-stale-modes) [182.484338] Starting subtest: prune-stale-modes [182.793033] Subtest prune-stale-modes: SUCCESS (0.308s) [182.900720] [117/171] (820s left) kms_frontbuffer_tracking (basic) [183.045446] Starting subtest: basic [186.399885] Subtest basic: SUCCESS (3.355s) [186.501349] [118/171] (816s left) kms_pipe_crc_basic (compare-crc-sanitycheck) [186.644729] Starting subtest: compare-crc-sanitycheck [186.645943] Starting dynamic subtest: pipe-A-eDP-1 [187.340067] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.689s) [187.355204] Starting dynamic subtest: pipe-B-eDP-1 [188.715659] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.381s) [188.732894] Starting dynamic subtest: pipe-C-eDP-1 [190.092731] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.376s) [190.093818] Subtest compare-crc-sanitycheck: SUCCESS (3.447s) [191.022113] [119/171] (812s left) kms_pipe_crc_basic (hang-read-crc) [191.171970] Starting subtest: hang-read-crc [191.173205] Starting dynamic subtest: pipe-A-eDP-1 [193.548607] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.376s) [193.564529] Starting dynamic subtest: pipe-B-eDP-1 [196.566094] Dynamic subtest pipe-B-eDP-1: SUCCESS (3.016s) [196.581896] Starting dynamic subtest: pipe-C-eDP-1 [199.601809] Dynamic subtest pipe-C-eDP-1: SUCCESS (3.036s) [199.603132] Subtest hang-read-crc: SUCCESS (8.430s) [200.542655] [120/171] (802s left) kms_pipe_crc_basic (nonblocking-crc) [200.694298] Starting subtest: nonblocking-crc [200.695492] Starting dynamic subtest: pipe-A-eDP-1 [201.557432] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.863s) [201.573075] Starting dynamic subtest: pipe-B-eDP-1 [203.177619] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.620s) [203.193372] Starting dynamic subtest: pipe-C-eDP-1 [204.778471] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.600s) [204.779539] Subtest nonblocking-crc: SUCCESS (4.085s) [205.731852] [121/171] (797s left) kms_pipe_crc_basic (nonblocking-crc-frame-sequence) [205.884340] Starting subtest: nonblocking-crc-frame-sequence [205.885494] Starting dynamic subtest: pipe-A-eDP-1 [206.748360] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.865s) [206.764124] Starting dynamic subtest: pipe-B-eDP-1 [208.364556] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.614s) [208.380103] Starting dynamic subtest: pipe-C-eDP-1 [209.959251] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.594s) [209.960377] Subtest nonblocking-crc-frame-sequence: SUCCESS (4.076s) [210.911384] [122/171] (792s left) kms_pipe_crc_basic (read-crc) [211.059159] Starting subtest: read-crc [211.060348] Starting dynamic subtest: pipe-A-eDP-1 [211.861321] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.803s) [211.876993] Starting dynamic subtest: pipe-B-eDP-1 [213.412441] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.551s) [213.428138] Starting dynamic subtest: pipe-C-eDP-1 [214.934385] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.519s) [214.935479] Subtest read-crc: SUCCESS (3.875s) [215.841476] [123/171] (787s left) kms_pipe_crc_basic (read-crc-frame-sequence) [215.982479] Starting subtest: read-crc-frame-sequence [215.983537] Starting dynamic subtest: pipe-A-eDP-1 [216.774329] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.791s) [216.790210] Starting dynamic subtest: pipe-B-eDP-1 [218.277117] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.501s) [218.293027] Starting dynamic subtest: pipe-C-eDP-1 [219.814743] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.537s) [219.815828] Subtest read-crc-frame-sequence: SUCCESS (3.832s) [220.773450] [124/171] (782s left) kms_psr (primary_page_flip) [220.916961] Starting subtest: primary_page_flip [223.091770] Subtest primary_page_flip: FAIL (1.902s) [223.117185] [125/171] (780s left) kms_psr (cursor_plane_move) [223.260156] Starting subtest: cursor_plane_move [225.444498] Subtest cursor_plane_move: FAIL (1.915s) [225.469728] [126/171] (777s left) kms_psr (sprite_plane_onoff) [225.622210] Starting subtest: sprite_plane_onoff [227.749388] Subtest sprite_plane_onoff: FAIL (1.848s) [227.773667] [127/171] (775s left) kms_psr (primary_mmap_gtt) [227.926795] Starting subtest: primary_mmap_gtt [230.118702] Subtest primary_mmap_gtt: FAIL (1.918s) [230.147503] [128/171] (773s left) kms_setmode (basic-clone-single-crtc) [230.265391] Starting subtest: basic-clone-single-crtc [230.268351] Subtest basic-clone-single-crtc: SKIP (0.000s) [230.347028] [129/171] (773s left) i915_pm_backlight (basic-brightness) [230.487774] Starting subtest: basic-brightness [230.604626] Starting dynamic subtest: eDP-1 [231.132543] Dynamic subtest eDP-1: SUCCESS (0.533s) [231.133857] Subtest basic-brightness: SUCCESS (0.641s) [232.095667] [130/171] (771s left) i915_pm_rpm (basic-pci-d3-state) [233.951479] Starting subtest: basic-pci-d3-state [238.337911] Subtest basic-pci-d3-state: SUCCESS (4.385s) [238.538809] [131/171] (765s left) i915_pm_rpm (basic-rte) [238.659267] Starting subtest: basic-rte [244.798201] Subtest basic-rte: SUCCESS (6.136s) [250.163908] [132/171] (753s left) i915_pm_rps (basic-api) [250.284334] Starting subtest: basic-api [250.288567] Subtest basic-api: SUCCESS (0.004s) [250.366040] [133/171] (753s left) prime_self_import (basic-llseek-bad) [250.478245] Starting subtest: basic-llseek-bad [250.479387] Subtest basic-llseek-bad: SUCCESS (0.002s) [250.585324] [134/171] (753s left) prime_self_import (basic-llseek-size) [250.706312] Starting subtest: basic-llseek-size [250.709781] Subtest basic-llseek-size: SUCCESS (0.004s) [250.820713] [135/171] (752s left) prime_self_import (basic-with_fd_dup) [250.940546] Starting subtest: basic-with_fd_dup [250.946264] Subtest basic-with_fd_dup: SUCCESS (0.004s) [251.034060] [136/171] (752s left) prime_self_import (basic-with_one_bo) [251.153594] Starting subtest: basic-with_one_bo [251.159259] Subtest basic-with_one_bo: SUCCESS (0.005s) [251.250348] [137/171] (752s left) prime_self_import (basic-with_one_bo_two_files) [251.360061] Starting subtest: basic-with_one_bo_two_files [251.363477] Subtest basic-with_one_bo_two_files: SUCCESS (0.003s) [251.467976] [138/171] (752s left) prime_self_import (basic-with_two_bos) [251.578701] Starting subtest: basic-with_two_bos [251.584345] Subtest basic-with_two_bos: SUCCESS (0.004s) [251.684574] [139/171] (752s left) prime_vgem (basic-fence-flip) [251.820236] Starting subtest: basic-fence-flip [252.344229] Subtest basic-fence-flip: SUCCESS (0.524s) [252.466464] [140/171] (751s left) prime_vgem (basic-fence-mmap) [252.603748] Starting subtest: basic-fence-mmap [252.645267] Subtest basic-fence-mmap: SUCCESS (0.041s) [252.753095] [141/171] (751s left) prime_vgem (basic-fence-read) [252.886495] Starting subtest: basic-fence-read [252.952933] Subtest basic-fence-read: SUCCESS (0.066s) [253.034774] [142/171] (750s left) prime_vgem (basic-gtt) [253.171119] Starting subtest: basic-gtt [253.191579] Subtest basic-gtt: SUCCESS (0.022s) [253.300817] [143/171] (750s left) prime_vgem (basic-read) [253.436157] Starting subtest: basic-read [253.470164] Subtest basic-read: SUCCESS (0.034s) [253.567699] [144/171] (750s left) prime_vgem (basic-write) [253.703392] Starting subtest: basic-write [253.743497] Subtest basic-write: SUCCESS (0.040s) [253.833994] [145/171] (750s left) prime_vgem (basic-userptr) [253.976921] Starting subtest: basic-userptr [253.991332] Subtest basic-userptr: SKIP (0.013s) [254.088714] [146/171] (749s left) vgem_basic (setversion) [254.212188] Starting subtest: setversion [254.214385] Subtest setversion: SUCCESS (0.000s) [254.282901] [147/171] (749s left) vgem_basic (create) [254.412129] Starting subtest: create [254.413322] Subtest create: SUCCESS (0.000s) [254.483874] [148/171] (749s left) vgem_basic (debugfs) [254.612286] Starting subtest: debugfs [254.613411] Subtest debugfs: SUCCESS (0.001s) [254.684794] [149/171] (749s left) vgem_basic (dmabuf-export) [254.812211] Starting subtest: dmabuf-export [254.819325] Subtest dmabuf-export: SUCCESS (0.006s) [254.936809] [150/171] (749s left) vgem_basic (dmabuf-fence) [255.062328] Starting subtest: dmabuf-fence [255.063574] Subtest dmabuf-fence: SUCCESS (0.001s) [255.132411] [151/171] (748s left) vgem_basic (dmabuf-fence-before) [255.261935] Starting subtest: dmabuf-fence-before [255.263158] Subtest dmabuf-fence-before: SUCCESS (0.000s) [255.333953] [152/171] (748s left) vgem_basic (dmabuf-mmap) [255.462458] Starting subtest: dmabuf-mmap [255.488052] Subtest dmabuf-mmap: SUCCESS (0.026s) [255.546313] [153/171] (748s left) vgem_basic (mmap) [255.661921] Starting subtest: mmap [255.678139] Subtest mmap: SUCCESS (0.016s) [255.749822] [154/171] (748s left) vgem_basic (second-client) [255.879347] Starting subtest: second-client [255.929392] Subtest second-client: SUCCESS (0.049s) [256.000926] [155/171] (748s left) vgem_basic (sysfs) [256.129090] Starting subtest: sysfs [256.130352] Subtest sysfs: SUCCESS (0.001s) [256.199610] [156/171] (748s left) core_hotunplug (unbind-rebind) [256.346880] Starting subtest: unbind-rebind [258.670145] Subtest unbind-rebind: SUCCESS (2.321s) [258.700495] [157/171] (745s left) vgem_basic (unload) [258.822743] Starting subtest: unload [287.670410] Subtest unload: SUCCESS (28.813s) [287.777978] [158/171] (716s left) i915_module_load (reload) [287.892099] Starting subtest: reload [291.005948] Subtest reload: SUCCESS (3.115s) [291.033938] [159/171] (713s left) gem_lmem_swapping (basic) [291.167378] Subtest basic: SKIP (0.000s) [291.206609] [160/171] (713s left) gem_lmem_swapping (parallel-random-engines) [291.335309] Subtest parallel-random-engines: SKIP (0.000s) [291.390057] [161/171] (712s left) gem_lmem_swapping (random-engines) [291.520108] Subtest random-engines: SKIP (0.000s) [291.577570] [162/171] (712s left) gem_lmem_swapping (verify-random) [291.707876] Subtest verify-random: SKIP (0.000s) [291.756906] [163/171] (712s left) i915_pm_rpm (module-reload) [304.242961] Starting subtest: module-reload [338.441810] Subtest module-reload: SUCCESS (34.192s) [338.461421] [164/171] (665s left) i915_selftest (live) [338.591041] Starting subtest: live [338.592407] Starting dynamic subtest: sanitycheck [340.370424] Dynamic subtest sanitycheck: SUCCESS (1.770s) [340.385182] Starting dynamic subtest: uncore [342.177670] Dynamic subtest uncore: SUCCESS (1.806s) [342.192395] Starting dynamic subtest: workarounds [344.170511] Dynamic subtest workarounds: SUCCESS (1.992s) [344.185231] Starting dynamic subtest: gt_engines [346.325572] Dynamic subtest gt_engines: SUCCESS (2.154s) [346.340332] Starting dynamic subtest: gt_timelines [358.056447] Dynamic subtest gt_timelines: SUCCESS (11.730s) [358.071231] Starting dynamic subtest: gt_contexts [359.917522] Dynamic subtest gt_contexts: SUCCESS (1.860s) [359.932286] Starting dynamic subtest: gt_lrc [364.198517] Dynamic subtest gt_lrc: SUCCESS (4.280s) [364.213364] Starting dynamic subtest: gt_mocs [366.045739] Dynamic subtest gt_mocs: SUCCESS (1.846s) [366.060528] Starting dynamic subtest: gt_pm [371.068524] Dynamic subtest gt_pm: SUCCESS (5.022s) [371.083468] Starting dynamic subtest: gt_heartbeat [372.955472] Dynamic subtest gt_heartbeat: SUCCESS (1.886s) [372.970322] Starting dynamic subtest: requests [380.479528] Dynamic subtest requests: SUCCESS (7.523s) [380.494416] Starting dynamic subtest: migrate [385.037870] Dynamic subtest migrate: SUCCESS (4.559s) [385.052755] Starting dynamic subtest: active [386.779382] Dynamic subtest active: SUCCESS (1.739s) [386.794292] Starting dynamic subtest: objects [390.110917] Dynamic subtest objects: SUCCESS (3.330s) [390.125955] Starting dynamic subtest: mman [396.951677] Dynamic subtest mman: SUCCESS (6.840s) [396.966764] Starting dynamic subtest: dmabuf [398.768459] Dynamic subtest dmabuf: SUCCESS (1.816s) [398.783432] Starting dynamic subtest: vma [400.541831] Dynamic subtest vma: SUCCESS (1.771s) [400.556935] Starting dynamic subtest: coherency [404.907371] Dynamic subtest coherency: SUCCESS (4.366s) [404.922373] Starting dynamic subtest: gtt [415.961649] Dynamic subtest gtt: SUCCESS (11.053s) [415.976870] Starting dynamic subtest: gem [417.852470] Dynamic subtest gem: SUCCESS (1.890s) [417.867489] Starting dynamic subtest: evict [421.409427] Dynamic subtest evict: SUCCESS (3.556s) [421.424478] Starting dynamic subtest: hugepages [428.257689] Dynamic subtest hugepages: SUCCESS (6.847s) [428.273294] Starting dynamic subtest: gem_contexts [442.758569] Dynamic subtest gem_contexts: SUCCESS (14.500s) [442.774189] Starting dynamic subtest: client [444.580606] Dynamic subtest client: SUCCESS (1.821s) [444.595991] Starting dynamic subtest: gem_migrate [446.364608] Dynamic subtest gem_migrate: SUCCESS (1.783s) [446.380196] Starting dynamic subtest: reset [451.699294] Dynamic subtest reset: SUCCESS (5.334s) [451.714882] Starting dynamic subtest: memory_region [453.455434] Dynamic subtest memory_region: SUCCESS (1.755s) [453.470966] Starting dynamic subtest: hangcheck [477.850525] Dynamic subtest hangcheck: SUCCESS (24.394s) [477.866106] Starting dynamic subtest: execlists [487.796580] Dynamic subtest execlists: SUCCESS (9.945s) [487.812148] Starting dynamic subtest: ring_submission [489.556549] Dynamic subtest ring_submission: SUCCESS (1.759s) [489.572154] Starting dynamic subtest: perf [491.417528] Dynamic subtest perf: SUCCESS (1.860s) [491.433152] Starting dynamic subtest: slpc [493.202572] Dynamic subtest slpc: SUCCESS (1.784s) [493.218096] Starting dynamic subtest: guc [494.999445] Dynamic subtest guc: SUCCESS (1.796s) [495.015113] Starting dynamic subtest: guc_multi_lrc [496.734459] Dynamic subtest guc_multi_lrc: SUCCESS (1.734s) [496.750044] Starting dynamic subtest: guc_hang [498.528451] Dynamic subtest guc_hang: SUCCESS (1.793s) [498.544069] Starting dynamic subtest: late_gt_pm [500.381515] Dynamic subtest late_gt_pm: SUCCESS (1.852s) [500.382647] Subtest live: SUCCESS (161.782s) [500.456810] [165/171] (504s left) dmabuf (all-tests) [500.578551] Starting subtest: all-tests [500.580112] Starting dynamic subtest: sanitycheck [500.602662] Dynamic subtest sanitycheck: SUCCESS (0.024s) [500.615269] Starting dynamic subtest: dma_fence [500.746817] Dynamic subtest dma_fence: SUCCESS (0.143s) [500.758793] Starting dynamic subtest: dma_fence_chain [518.114487] Dynamic subtest dma_fence_chain: SUCCESS (17.358s) [518.128206] Starting dynamic subtest: dma_fence_unwrap [518.134493] Dynamic subtest dma_fence_unwrap: SUCCESS (0.028s) [518.147156] Starting dynamic subtest: dma_resv [518.161514] Dynamic subtest dma_resv: SUCCESS (0.026s) [518.162673] Subtest all-tests: SUCCESS (17.583s) [518.187835] [166/171] (486s left) i915_suspend (basic-s2idle-without-i915) [518.300895] Starting subtest: basic-s2idle-without-i915 [541.282673] Subtest basic-s2idle-without-i915: SUCCESS (8.117s) [541.587362] [167/171] (477s left) i915_suspend (basic-s3-without-i915) [541.719643] Starting subtest: basic-s3-without-i915 [565.358787] Subtest basic-s3-without-i915: SUCCESS (6.891s) [565.651845] [168/171] (470s left) gem_exec_suspend (basic-s0) [565.790556] Starting subtest: basic-S0 [565.791690] Starting dynamic subtest: smem [585.299424] Dynamic subtest smem: SUCCESS (3.679s) [585.300524] Subtest basic-S0: SUCCESS (3.680s) [585.445917] [169/171] (466s left) gem_exec_suspend (basic-s3) [585.575790] Starting subtest: basic-S3 [585.576871] Starting dynamic subtest: smem [604.261505] Dynamic subtest smem: SUCCESS (3.408s) [604.262493] Subtest basic-S3: SUCCESS (3.409s) [604.408160] [170/171] (462s left) kms_chamelium_hpd (common-hpd-after-suspend) [604.861575] Subtest common-hpd-after-suspend: SKIP (0.000s) [605.749700] [171/171] (461s left) kms_pipe_crc_basic (suspend-read-crc) [605.895266] Starting subtest: suspend-read-crc [605.896381] Starting dynamic subtest: pipe-A-eDP-1 [623.317546] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.407s) [623.336486] Starting dynamic subtest: pipe-B-eDP-1 [641.282321] Dynamic subtest pipe-B-eDP-1: SUCCESS (3.119s) [641.301542] Starting dynamic subtest: pipe-C-eDP-1 [659.293674] Dynamic subtest pipe-C-eDP-1: SUCCESS (3.147s) [659.294732] Subtest suspend-read-crc: SUCCESS (8.676s) [660.206473] Closing watchdogs Done.