[45.692592] Initializing watchdogs [45.692722] /dev/watchdog0 [45.700054] [001/171] (960s left) i915_module_load (load) [45.849201] Starting subtest: load [46.967709] Subtest load: SUCCESS (1.119s) [47.011384] [002/171] (959s left) core_auth (basic-auth) [47.170597] Starting subtest: basic-auth [47.178093] Subtest basic-auth: SUCCESS (0.007s) [47.286524] [003/171] (958s left) debugfs_test (read_all_entries) [47.437414] Starting subtest: read_all_entries [47.515251] Subtest read_all_entries: SUCCESS (0.077s) [47.617025] [004/171] (958s left) debugfs_test (basic-hwmon) [47.728175] Starting subtest: basic-hwmon [47.730118] Subtest basic-hwmon: SKIP (0.000s) [47.844073] [005/171] (958s left) fbdev (eof) [47.955391] Starting subtest: eof [47.956439] Subtest eof: SUCCESS (0.001s) [48.009273] [006/171] (958s left) fbdev (info) [48.108360] Starting subtest: info [48.109362] Subtest info: SUCCESS (0.000s) [48.177608] [007/171] (958s left) fbdev (nullptr) [48.288258] Starting subtest: nullptr [48.289267] Subtest nullptr: SUCCESS (0.000s) [48.342328] [008/171] (958s left) fbdev (read) [48.455326] Starting subtest: read [49.296103] Subtest read: SUCCESS (0.837s) [49.343385] [009/171] (957s left) fbdev (write) [49.455803] Starting subtest: write [49.877648] Subtest write: SUCCESS (0.413s) [49.932555] [010/171] (956s left) gem_basic (bad-close) [50.032771] Starting subtest: bad-close [50.034656] Subtest bad-close: SUCCESS (0.000s) [50.100979] [011/171] (956s left) gem_basic (create-close) [50.203000] Starting subtest: create-close [50.204896] Subtest create-close: SUCCESS (0.000s) [50.331232] [012/171] (956s left) gem_basic (create-fd-close) [50.437701] Starting subtest: create-fd-close [50.439494] Subtest create-fd-close: SUCCESS (0.000s) [50.559067] [013/171] (955s left) gem_busy (busy@all-engines) [50.675392] Starting subtest: busy [50.676476] Starting dynamic subtest: all-engines [50.728413] Dynamic subtest all-engines: SUCCESS (0.053s) [50.729392] Subtest busy: SUCCESS (0.053s) [51.126174] [014/171] (955s left) gem_close_race (basic-process) [51.250841] Starting subtest: basic-process [51.314589] Subtest basic-process: SUCCESS (0.064s) [51.522292] [015/171] (954s left) gem_close_race (basic-threads) [51.641679] Starting subtest: basic-threads [52.794709] Subtest basic-threads: SUCCESS (1.153s) [53.017461] [016/171] (953s left) gem_ctx_create (basic) [53.135592] Starting subtest: basic [53.136602] Subtest basic: SUCCESS (0.000s) [53.361287] [017/171] (953s left) gem_ctx_create (basic-files) [53.477831] Starting subtest: basic-files [55.499832] Subtest basic-files: SUCCESS (2.022s) [55.844049] [018/171] (950s left) gem_ctx_exec (basic) [55.952535] Starting subtest: basic [55.956992] Subtest basic: SUCCESS (0.004s) [56.072829] [019/171] (950s left) gem_exec_basic (basic) [56.186192] Starting subtest: basic [56.187348] Starting dynamic subtest: rcs0-smem [56.189633] Dynamic subtest rcs0-smem: SUCCESS (0.003s) [56.197282] Starting dynamic subtest: bcs0-smem [56.198261] Dynamic subtest bcs0-smem: SUCCESS (0.001s) [56.205615] Starting dynamic subtest: vcs0-smem [56.206919] Dynamic subtest vcs0-smem: SUCCESS (0.001s) [56.213984] Starting dynamic subtest: vecs0-smem [56.215355] Dynamic subtest vecs0-smem: SUCCESS (0.001s) [56.216662] Subtest basic: SUCCESS (0.007s) [56.429232] [020/171] (950s left) gem_exec_create (basic) [56.549243] Starting subtest: basic [56.550186] Starting dynamic subtest: smem [58.600564] Dynamic subtest smem: SUCCESS (2.051s) [58.601519] Subtest basic: SUCCESS (2.052s) [58.813968] [021/171] (947s left) gem_exec_fence (basic-busy) [59.075148] Starting subtest: basic-busy [59.077128] Starting dynamic subtest: rcs0 [59.111981] Dynamic subtest rcs0: SUCCESS (0.037s) [59.127260] Starting dynamic subtest: bcs0 [59.141483] Dynamic subtest bcs0: SUCCESS (0.029s) [59.150383] Starting dynamic subtest: vcs0 [59.165284] Dynamic subtest vcs0: SUCCESS (0.024s) [59.172814] Starting dynamic subtest: vecs0 [59.198204] Dynamic subtest vecs0: SUCCESS (0.027s) [59.199612] Subtest basic-busy: SUCCESS (0.119s) [59.875577] [022/171] (946s left) gem_exec_fence (basic-wait) [60.146524] Starting subtest: basic-wait [60.147812] Starting dynamic subtest: rcs0 [60.170300] Dynamic subtest rcs0: SUCCESS (0.024s) [60.177935] Starting dynamic subtest: bcs0 [60.193147] Dynamic subtest bcs0: SUCCESS (0.023s) [60.200702] Starting dynamic subtest: vcs0 [60.221320] Dynamic subtest vcs0: SUCCESS (0.028s) [60.228791] Starting dynamic subtest: vecs0 [60.249282] Dynamic subtest vecs0: SUCCESS (0.028s) [60.250216] Subtest basic-wait: SUCCESS (0.103s) [60.919640] [023/171] (945s left) gem_exec_fence (basic-await) [61.179964] Starting subtest: basic-await [61.182201] Starting dynamic subtest: rcs0 [61.287444] Dynamic subtest rcs0: SUCCESS (0.108s) [61.296668] Starting dynamic subtest: bcs0 [61.386150] Dynamic subtest bcs0: SUCCESS (0.098s) [61.396912] Starting dynamic subtest: vcs0 [61.487218] Dynamic subtest vcs0: SUCCESS (0.101s) [61.497634] Starting dynamic subtest: vecs0 [61.589119] Dynamic subtest vecs0: SUCCESS (0.101s) [61.590172] Subtest basic-await: SUCCESS (0.410s) [62.335553] [024/171] (944s left) gem_exec_fence (nb-await) [62.613770] Starting subtest: nb-await [62.615252] Starting dynamic subtest: rcs0 [62.683203] Dynamic subtest rcs0: SUCCESS (0.069s) [62.708514] Starting dynamic subtest: bcs0 [62.769851] Dynamic subtest bcs0: SUCCESS (0.084s) [62.803931] Starting dynamic subtest: vcs0 [62.858851] Dynamic subtest vcs0: SUCCESS (0.090s) [62.891609] Starting dynamic subtest: vecs0 [62.948532] Dynamic subtest vecs0: SUCCESS (0.088s) [62.950628] Subtest nb-await: SUCCESS (0.334s) [63.671393] [025/171] (943s left) gem_exec_gttfill (basic) [63.792167] Starting subtest: basic [73.321978] Subtest basic: SUCCESS (9.521s) [73.509526] [026/171] (933s left) gem_exec_parallel (engines) [73.685129] Starting subtest: engines [73.686210] Starting dynamic subtest: basic [75.990780] Dynamic subtest basic: SUCCESS (2.305s) [76.000400] Starting dynamic subtest: contexts [78.979926] Dynamic subtest contexts: SUCCESS (2.981s) [78.988450] Starting dynamic subtest: fds [82.511461] Dynamic subtest fds: SUCCESS (3.540s) [82.523304] Starting dynamic subtest: userptr [84.881522] Dynamic subtest userptr: SUCCESS (2.370s) [84.882428] Subtest engines: SUCCESS (11.197s) [85.108723] [027/171] (921s left) gem_exec_store (basic) [85.233373] Starting subtest: basic [85.282328] Subtest basic: SUCCESS (0.049s) [85.505095] [028/171] (921s left) gem_flink_basic (bad-flink) [85.614215] Starting subtest: bad-flink [85.615297] Subtest bad-flink: SUCCESS (0.000s) [85.710396] [029/171] (921s left) gem_flink_basic (bad-open) [85.817927] Starting subtest: bad-open [85.819073] Subtest bad-open: SUCCESS (0.001s) [85.918259] [030/171] (920s left) gem_flink_basic (basic) [86.026435] Starting subtest: basic [86.027475] Subtest basic: SUCCESS (0.000s) [86.131502] [031/171] (920s left) gem_flink_basic (double-flink) [86.240235] Starting subtest: double-flink [86.241323] Subtest double-flink: SUCCESS (0.000s) [86.348155] [032/171] (920s left) gem_flink_basic (flink-lifetime) [86.456564] Starting subtest: flink-lifetime [86.457731] Subtest flink-lifetime: SUCCESS (0.001s) [86.567378] [033/171] (920s left) gem_huc_copy (huc-copy) [86.683561] Starting subtest: huc-copy [86.685654] Subtest huc-copy: SKIP (0.000s) [86.790480] [034/171] (920s left) gem_linear_blits (basic) [87.045386] Starting subtest: basic [87.054267] Subtest basic: SUCCESS (0.010s) [87.208227] [035/171] (919s left) gem_mmap (basic) [87.365956] Starting subtest: basic [87.370687] Subtest basic: SUCCESS (0.001s) [87.444808] [036/171] (919s left) gem_mmap_gtt (basic) [87.552225] Starting subtest: basic [87.553385] Subtest basic: SUCCESS (0.001s) [87.652718] [037/171] (919s left) gem_render_linear_blits (basic) [87.767729] Starting subtest: basic [87.808748] Subtest basic: SUCCESS (0.041s) [87.850526] [038/171] (919s left) gem_render_tiled_blits (basic) [87.967622] Starting subtest: basic [88.013417] Subtest basic: SUCCESS (0.046s) [88.067166] [039/171] (918s left) gem_ringfill (basic-all) [88.364502] Starting subtest: basic-all [89.537365] Subtest basic-all: SUCCESS (1.164s) [89.627909] [040/171] (917s left) gem_softpin (allocator-basic) [89.744652] Starting subtest: allocator-basic [89.883667] Subtest allocator-basic: SUCCESS (0.130s) [90.024252] [041/171] (917s left) gem_softpin (allocator-basic-reserve) [90.136513] Starting subtest: allocator-basic-reserve [90.276821] Subtest allocator-basic-reserve: SUCCESS (0.131s) [90.405945] [042/171] (916s left) gem_softpin (safe-alignment) [90.522409] Starting subtest: safe-alignment [90.537856] Subtest safe-alignment: SUCCESS (0.015s) [90.673133] [043/171] (916s left) gem_sync (basic-all) [90.798721] Starting subtest: basic-all [93.026493] Subtest basic-all: SUCCESS (2.228s) [93.246370] [044/171] (913s left) gem_sync (basic-each) [93.372685] Starting subtest: basic-each [95.521444] Subtest basic-each: SUCCESS (2.149s) [95.721532] [045/171] (911s left) gem_tiled_blits (basic) [95.981055] Starting subtest: basic [96.005420] Subtest basic: SUCCESS (0.024s) [96.154976] [046/171] (911s left) gem_tiled_fence_blits (basic) [96.463356] Starting subtest: basic [96.476381] Subtest basic: SUCCESS (0.013s) [96.630843] [047/171] (910s left) gem_tiled_pread_basic [97.009968] [048/171] (910s left) gem_wait (busy@all-engines) [97.140907] Starting subtest: busy [97.142228] Starting dynamic subtest: all-engines [97.682506] Dynamic subtest all-engines: SUCCESS (0.541s) [97.683791] Subtest busy: SUCCESS (0.542s) [97.923831] [049/171] (909s left) gem_wait (wait@all-engines) [98.051574] Starting subtest: wait [98.052672] Starting dynamic subtest: all-engines [99.104954] Dynamic subtest all-engines: SUCCESS (1.051s) [99.108402] Subtest wait: SUCCESS (1.052s) [99.377934] [050/171] (907s left) i915_getparams_basic (basic-eu-total) [99.486783] Starting subtest: basic-eu-total [99.488957] Subtest basic-eu-total: SUCCESS (0.000s) [99.584859] [051/171] (907s left) i915_getparams_basic (basic-subslice-total) [99.694020] Starting subtest: basic-subslice-total [99.695999] Subtest basic-subslice-total: SUCCESS (0.000s) [99.787483] [052/171] (907s left) i915_hangman (error-state-basic) [99.908387] Starting subtest: error-state-basic [99.917385] Subtest error-state-basic: SUCCESS (0.009s) [100.002938] [053/171] (907s left) i915_pciid [100.210079] [054/171] (907s left) kms_addfb_basic (addfb25-bad-modifier) [100.319463] Starting subtest: addfb25-bad-modifier [100.320519] Subtest addfb25-bad-modifier: SUCCESS (0.000s) [100.478209] [055/171] (906s left) kms_addfb_basic (addfb25-framebuffer-vs-set-tiling) [100.593428] Starting subtest: addfb25-framebuffer-vs-set-tiling [100.594577] Subtest addfb25-framebuffer-vs-set-tiling: SUCCESS (0.000s) [100.732025] [056/171] (906s left) kms_addfb_basic (addfb25-modifier-no-flag) [100.841404] Starting subtest: addfb25-modifier-no-flag [100.842498] Subtest addfb25-modifier-no-flag: SUCCESS (0.000s) [100.979232] [057/171] (906s left) kms_addfb_basic (addfb25-x-tiled-legacy) [101.084839] Starting subtest: addfb25-x-tiled-legacy [101.086089] Subtest addfb25-x-tiled-legacy: SUCCESS (0.001s) [101.219771] [058/171] (906s left) kms_addfb_basic (addfb25-x-tiled-mismatch-legacy) [101.325586] Starting subtest: addfb25-x-tiled-mismatch-legacy [101.326658] Subtest addfb25-x-tiled-mismatch-legacy: SUCCESS (0.000s) [101.462977] [059/171] (905s left) kms_addfb_basic (addfb25-yf-tiled-legacy) [101.607216] Starting subtest: addfb25-yf-tiled-legacy [101.608127] Subtest addfb25-yf-tiled-legacy: SUCCESS (0.000s) [101.709089] [060/171] (905s left) kms_addfb_basic (addfb25-y-tiled-legacy) [101.850116] Starting subtest: addfb25-y-tiled-legacy [101.851054] Subtest addfb25-y-tiled-legacy: SUCCESS (0.000s) [101.944537] [061/171] (905s left) kms_addfb_basic (addfb25-y-tiled-small-legacy) [102.081390] Starting subtest: addfb25-y-tiled-small-legacy [102.082397] Subtest addfb25-y-tiled-small-legacy: SUCCESS (0.000s) [102.174958] [062/171] (905s left) kms_addfb_basic (bad-pitch-0) [102.277138] Starting subtest: bad-pitch-0 [102.278160] Subtest bad-pitch-0: SUCCESS (0.000s) [102.407027] [063/171] (905s left) kms_addfb_basic (bad-pitch-1024) [102.509232] Starting subtest: bad-pitch-1024 [102.510276] Subtest bad-pitch-1024: SUCCESS (0.000s) [102.652868] [064/171] (904s left) kms_addfb_basic (bad-pitch-128) [102.749931] Starting subtest: bad-pitch-128 [102.751050] Subtest bad-pitch-128: SUCCESS (0.000s) [102.894616] [065/171] (904s left) kms_addfb_basic (bad-pitch-256) [102.993531] Starting subtest: bad-pitch-256 [102.994698] Subtest bad-pitch-256: SUCCESS (0.000s) [103.118968] [066/171] (904s left) kms_addfb_basic (bad-pitch-32) [103.219213] Starting subtest: bad-pitch-32 [103.221464] Subtest bad-pitch-32: SUCCESS (0.000s) [103.361479] [067/171] (904s left) kms_addfb_basic (bad-pitch-63) [103.459165] Starting subtest: bad-pitch-63 [103.460102] Subtest bad-pitch-63: SUCCESS (0.000s) [103.602132] [068/171] (904s left) kms_addfb_basic (bad-pitch-65536) [103.699170] Starting subtest: bad-pitch-65536 [103.700142] Subtest bad-pitch-65536: SUCCESS (0.000s) [103.825201] [069/171] (903s left) kms_addfb_basic (bad-pitch-999) [103.923406] Starting subtest: bad-pitch-999 [103.924424] Subtest bad-pitch-999: SUCCESS (0.000s) [104.057494] [070/171] (903s left) kms_addfb_basic (basic) [104.154414] Starting subtest: basic [104.155531] Subtest basic: SUCCESS (0.000s) [104.288857] [071/171] (903s left) kms_addfb_basic (basic-x-tiled-legacy) [104.394279] Starting subtest: basic-x-tiled-legacy [104.395196] Subtest basic-x-tiled-legacy: SUCCESS (0.000s) [104.533305] [072/171] (903s left) kms_addfb_basic (basic-y-tiled-legacy) [104.632589] Starting subtest: basic-y-tiled-legacy [104.633605] Subtest basic-y-tiled-legacy: SUCCESS (0.000s) [104.764359] [073/171] (902s left) kms_addfb_basic (bo-too-small) [104.895902] Starting subtest: bo-too-small [104.896975] Subtest bo-too-small: SUCCESS (0.000s) [104.991115] [074/171] (902s left) kms_addfb_basic (bo-too-small-due-to-tiling) [105.124347] Starting subtest: bo-too-small-due-to-tiling [105.125287] Subtest bo-too-small-due-to-tiling: SUCCESS (0.000s) [105.233494] [075/171] (902s left) kms_addfb_basic (clobberred-modifier) [105.329416] Starting subtest: clobberred-modifier [105.330392] Subtest clobberred-modifier: SUCCESS (0.000s) [105.466224] [076/171] (902s left) kms_addfb_basic (framebuffer-vs-set-tiling) [105.564471] Starting subtest: framebuffer-vs-set-tiling [105.565524] Subtest framebuffer-vs-set-tiling: SUCCESS (0.000s) [105.698983] [077/171] (902s left) kms_addfb_basic (invalid-get-prop) [105.797307] Starting subtest: invalid-get-prop [105.798162] Subtest invalid-get-prop: SUCCESS (0.000s) [105.941176] [078/171] (901s left) kms_addfb_basic (invalid-get-prop-any) [106.035483] Starting subtest: invalid-get-prop-any [106.036456] Subtest invalid-get-prop-any: SUCCESS (0.000s) [106.172554] [079/171] (901s left) kms_addfb_basic (invalid-set-prop) [106.267967] Starting subtest: invalid-set-prop [106.268931] Subtest invalid-set-prop: SUCCESS (0.000s) [106.410116] [080/171] (901s left) kms_addfb_basic (invalid-set-prop-any) [106.507377] Starting subtest: invalid-set-prop-any [106.508370] Subtest invalid-set-prop-any: SUCCESS (0.000s) [106.632345] [081/171] (901s left) kms_addfb_basic (no-handle) [106.730316] Starting subtest: no-handle [106.731463] Subtest no-handle: SUCCESS (0.000s) [106.861838] [082/171] (901s left) kms_addfb_basic (size-max) [107.000287] Starting subtest: size-max [107.001296] Subtest size-max: SUCCESS (0.000s) [107.105882] [083/171] (900s left) kms_addfb_basic (small-bo) [107.235785] Starting subtest: small-bo [107.236823] Subtest small-bo: SUCCESS (0.000s) [107.329178] [084/171] (900s left) kms_addfb_basic (tile-pitch-mismatch) [107.427394] Starting subtest: tile-pitch-mismatch [107.428402] Subtest tile-pitch-mismatch: SUCCESS (0.000s) [107.569338] [085/171] (900s left) kms_addfb_basic (too-high) [107.707049] Starting subtest: too-high [107.707952] Subtest too-high: SUCCESS (0.001s) [107.804702] [086/171] (900s left) kms_addfb_basic (too-wide) [107.938032] Starting subtest: too-wide [107.938952] Subtest too-wide: SUCCESS (0.000s) [108.047142] [087/171] (899s left) kms_addfb_basic (unused-handle) [108.143220] Starting subtest: unused-handle [108.144154] Subtest unused-handle: SUCCESS (0.000s) [108.280028] [088/171] (899s left) kms_addfb_basic (unused-modifier) [108.377493] Starting subtest: unused-modifier [108.378386] Subtest unused-modifier: SUCCESS (0.000s) [108.525053] [089/171] (899s left) kms_addfb_basic (unused-offsets) [108.622629] Starting subtest: unused-offsets [108.623680] Subtest unused-offsets: SUCCESS (0.000s) [108.762757] [090/171] (899s left) kms_addfb_basic (unused-pitches) [108.861196] Starting subtest: unused-pitches [108.862234] Subtest unused-pitches: SUCCESS (0.000s) [108.992922] [091/171] (899s left) kms_busy (basic) [109.129434] Starting subtest: basic [109.131578] Starting dynamic subtest: flip [110.166744] Dynamic subtest flip: SUCCESS (1.035s) [110.185450] Starting dynamic subtest: modeset [113.963495] Dynamic subtest modeset: SUCCESS (3.795s) [113.964986] Subtest basic: SUCCESS (4.833s) [114.883604] [092/171] (893s left) kms_chamelium_hpd (dp-hpd-fast) [115.326601] Subtest dp-hpd-fast: SKIP (0.000s) [116.237675] [093/171] (891s left) kms_chamelium_edid (dp-edid-read) [116.696893] Subtest dp-edid-read: SKIP (0.000s) [117.586896] [094/171] (890s left) kms_chamelium_frames (dp-crc-fast) [118.017446] Subtest dp-crc-fast: SKIP (0.000s) [118.926555] [095/171] (889s left) kms_chamelium_hpd (hdmi-hpd-fast) [119.361378] Subtest hdmi-hpd-fast: SKIP (0.000s) [120.270459] [096/171] (887s left) kms_chamelium_edid (hdmi-edid-read) [120.718120] Subtest hdmi-edid-read: SKIP (0.000s) [121.612808] [097/171] (886s left) kms_chamelium_frames (hdmi-crc-fast) [122.046658] Subtest hdmi-crc-fast: SKIP (0.000s) [122.959173] [098/171] (885s left) kms_chamelium_hpd (vga-hpd-fast) [123.408696] Subtest vga-hpd-fast: SKIP (0.000s) [124.303398] [099/171] (883s left) kms_chamelium_edid (vga-edid-read) [124.761724] Subtest vga-edid-read: SKIP (0.000s) [125.646729] [100/171] (882s left) kms_prop_blob (basic) [125.759422] Starting subtest: basic [125.760422] Subtest basic: SUCCESS (0.000s) [125.824772] [101/171] (882s left) kms_cursor_legacy (basic-busy-flip-before-cursor-atomic) [125.971317] Starting subtest: basic-busy-flip-before-cursor-atomic [125.973413] Subtest basic-busy-flip-before-cursor-atomic: SKIP (0.000s) [126.054642] [102/171] (882s left) kms_cursor_legacy (basic-busy-flip-before-cursor-legacy) [126.199431] Starting subtest: basic-busy-flip-before-cursor-legacy [126.201509] Subtest basic-busy-flip-before-cursor-legacy: SKIP (0.000s) [126.282982] [103/171] (882s left) kms_cursor_legacy (basic-flip-after-cursor-atomic) [126.427850] Starting subtest: basic-flip-after-cursor-atomic [127.713382] Subtest basic-flip-after-cursor-atomic: SUCCESS (1.285s) [128.636985] [104/171] (879s left) kms_cursor_legacy (basic-flip-after-cursor-legacy) [128.784335] Starting subtest: basic-flip-after-cursor-legacy [130.045843] Subtest basic-flip-after-cursor-legacy: SUCCESS (1.259s) [131.002152] [105/171] (877s left) kms_cursor_legacy (basic-flip-after-cursor-varying-size) [131.147406] Starting subtest: basic-flip-after-cursor-varying-size [132.411449] Subtest basic-flip-after-cursor-varying-size: SUCCESS (1.265s) [133.373691] [106/171] (875s left) kms_cursor_legacy (basic-flip-before-cursor-atomic) [133.524157] Starting subtest: basic-flip-before-cursor-atomic [134.791168] Subtest basic-flip-before-cursor-atomic: SUCCESS (1.263s) [135.736511] [107/171] (872s left) kms_cursor_legacy (basic-flip-before-cursor-legacy) [135.881657] Starting subtest: basic-flip-before-cursor-legacy [137.147428] Subtest basic-flip-before-cursor-legacy: SUCCESS (1.267s) [138.108406] [108/171] (870s left) kms_cursor_legacy (basic-flip-before-cursor-varying-size) [138.254483] Starting subtest: basic-flip-before-cursor-varying-size [139.506069] Subtest basic-flip-before-cursor-varying-size: SUCCESS (1.249s) [140.409905] [109/171] (868s left) kms_flip (basic-flip-vs-dpms) [140.535903] Starting subtest: basic-flip-vs-dpms [140.541989] Starting dynamic subtest: A-eDP1 [143.409840] Dynamic subtest A-eDP1: SUCCESS (2.867s) [143.422243] Starting dynamic subtest: B-eDP1 [145.789985] Dynamic subtest B-eDP1: SUCCESS (2.377s) [145.804241] Starting dynamic subtest: C-eDP1 [148.158511] Dynamic subtest C-eDP1: SUCCESS (2.365s) [148.163437] Subtest basic-flip-vs-dpms: SUCCESS (7.627s) [149.058878] [110/171] (859s left) kms_flip (basic-flip-vs-modeset) [149.184777] Starting subtest: basic-flip-vs-modeset [149.187783] Starting dynamic subtest: A-eDP1 [152.045290] Dynamic subtest A-eDP1: SUCCESS (2.854s) [152.061948] Starting dynamic subtest: B-eDP1 [154.411501] Dynamic subtest B-eDP1: SUCCESS (2.365s) [154.432622] Starting dynamic subtest: C-eDP1 [156.786102] Dynamic subtest C-eDP1: SUCCESS (2.370s) [156.792951] Subtest basic-flip-vs-modeset: SUCCESS (7.609s) [157.695161] [111/171] (850s left) kms_flip (basic-flip-vs-wf_vblank) [157.818722] Starting subtest: basic-flip-vs-wf_vblank [157.823183] Starting dynamic subtest: A-eDP1 [160.155886] Dynamic subtest A-eDP1: SUCCESS (2.332s) [160.167136] Starting dynamic subtest: B-eDP1 [162.456909] Dynamic subtest B-eDP1: SUCCESS (2.300s) [162.470062] Starting dynamic subtest: C-eDP1 [164.763554] Dynamic subtest C-eDP1: SUCCESS (2.305s) [164.768853] Subtest basic-flip-vs-wf_vblank: SUCCESS (6.950s) [166.075070] [112/171] (842s left) kms_flip (basic-plain-flip) [166.201077] Starting subtest: basic-plain-flip [166.204291] Starting dynamic subtest: A-eDP1 [168.184644] Dynamic subtest A-eDP1: SUCCESS (1.980s) [168.197330] Starting dynamic subtest: B-eDP1 [170.170766] Dynamic subtest B-eDP1: SUCCESS (1.983s) [170.181901] Starting dynamic subtest: C-eDP1 [172.160535] Dynamic subtest C-eDP1: SUCCESS (1.982s) [172.176610] Subtest basic-plain-flip: SUCCESS (5.977s) [173.504409] [113/171] (835s left) kms_force_connector_basic (force-connector-state) [173.638815] Starting subtest: force-connector-state [174.257481] Subtest force-connector-state: SUCCESS (0.619s) [175.269594] [114/171] (833s left) kms_force_connector_basic (force-edid) [175.408337] Starting subtest: force-edid [175.444840] Subtest force-edid: SUCCESS (0.037s) [175.609939] [115/171] (832s left) kms_force_connector_basic (force-load-detect) [175.744314] Starting subtest: force-load-detect [175.746371] Subtest force-load-detect: SKIP (0.000s) [175.903395] [116/171] (832s left) kms_force_connector_basic (prune-stale-modes) [176.034948] Starting subtest: prune-stale-modes [176.064385] Subtest prune-stale-modes: SUCCESS (0.032s) [176.226913] [117/171] (832s left) kms_frontbuffer_tracking (basic) [176.374262] Starting subtest: basic [179.238520] Subtest basic: SUCCESS (2.864s) [179.346677] [118/171] (829s left) kms_pipe_crc_basic (compare-crc-sanitycheck-xr24) [179.482970] Starting subtest: compare-crc-sanitycheck-xr24 [179.484065] Starting dynamic subtest: pipe-A-eDP-1 [180.115826] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.630s) [180.127256] Starting dynamic subtest: pipe-B-eDP-1 [181.433062] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.315s) [181.444761] Starting dynamic subtest: pipe-C-eDP-1 [182.756223] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.319s) [182.757155] Subtest compare-crc-sanitycheck-xr24: SUCCESS (3.273s) [183.652374] [119/171] (825s left) kms_pipe_crc_basic (compare-crc-sanitycheck-nv12) [183.791044] Starting subtest: compare-crc-sanitycheck-nv12 [183.792090] Starting dynamic subtest: pipe-A-eDP-1 [183.793975] Dynamic subtest pipe-A-eDP-1: SKIP (0.001s) [183.804902] Starting dynamic subtest: pipe-B-eDP-1 [183.806898] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s) [183.817547] Starting dynamic subtest: pipe-C-eDP-1 [183.829075] Dynamic subtest pipe-C-eDP-1: SKIP (0.001s) [183.831051] Subtest compare-crc-sanitycheck-nv12: SKIP (0.009s) [183.884241] [120/171] (824s left) kms_pipe_crc_basic (hang-read-crc) [184.029225] Starting subtest: hang-read-crc [184.030249] Starting dynamic subtest: pipe-A-eDP-1 [186.247974] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.218s) [186.260197] Starting dynamic subtest: pipe-B-eDP-1 [189.189127] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.937s) [189.201293] Starting dynamic subtest: pipe-C-eDP-1 [192.100341] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.905s) [192.101506] Subtest hang-read-crc: SUCCESS (8.070s) [193.019261] [121/171] (815s left) kms_pipe_crc_basic (nonblocking-crc) [193.166511] Starting subtest: nonblocking-crc [193.167543] Starting dynamic subtest: pipe-A-eDP-1 [193.942150] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.775s) [193.962468] Starting dynamic subtest: pipe-B-eDP-1 [195.475543] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.527s) [195.493580] Starting dynamic subtest: pipe-C-eDP-1 [196.997613] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.516s) [197.001558] Subtest nonblocking-crc: SUCCESS (3.834s) [197.946778] [122/171] (810s left) kms_pipe_crc_basic (nonblocking-crc-frame-sequence) [198.096099] Starting subtest: nonblocking-crc-frame-sequence [198.098725] Starting dynamic subtest: pipe-A-eDP-1 [198.920969] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.821s) [198.945335] Starting dynamic subtest: pipe-B-eDP-1 [200.465691] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.538s) [200.478389] Starting dynamic subtest: pipe-C-eDP-1 [202.012656] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.540s) [202.013770] Subtest nonblocking-crc-frame-sequence: SUCCESS (3.915s) [202.939753] [123/171] (805s left) kms_pipe_crc_basic (read-crc) [203.086809] Starting subtest: read-crc [203.088039] Starting dynamic subtest: pipe-A-eDP-1 [203.813108] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.725s) [203.824946] Starting dynamic subtest: pipe-B-eDP-1 [205.271391] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.454s) [205.285528] Starting dynamic subtest: pipe-C-eDP-1 [206.748538] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.470s) [206.750453] Subtest read-crc: SUCCESS (3.663s) [207.675128] [124/171] (801s left) kms_pipe_crc_basic (read-crc-frame-sequence) [207.826564] Starting subtest: read-crc-frame-sequence [207.827660] Starting dynamic subtest: pipe-A-eDP-1 [208.550684] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.723s) [208.565395] Starting dynamic subtest: pipe-B-eDP-1 [210.034761] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.479s) [210.051960] Starting dynamic subtest: pipe-C-eDP-1 [211.478305] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.440s) [211.479202] Subtest read-crc-frame-sequence: SUCCESS (3.654s) [212.406743] [125/171] (796s left) kms_psr (primary_page_flip) [212.554640] Starting subtest: primary_page_flip [214.531512] Subtest primary_page_flip: SUCCESS (1.976s) [215.491919] [126/171] (793s left) kms_psr (cursor_plane_move) [215.639526] Starting subtest: cursor_plane_move [217.547061] Subtest cursor_plane_move: SUCCESS (1.907s) [218.493320] [127/171] (790s left) kms_psr (sprite_plane_onoff) [218.644036] Starting subtest: sprite_plane_onoff [220.638639] Subtest sprite_plane_onoff: SUCCESS (1.991s) [221.568813] [128/171] (787s left) kms_psr (primary_mmap_gtt) [221.717205] Starting subtest: primary_mmap_gtt [223.711583] Subtest primary_mmap_gtt: SUCCESS (1.991s) [224.633510] [129/171] (784s left) kms_setmode (basic-clone-single-crtc) [224.742907] Starting subtest: basic-clone-single-crtc [224.745681] Subtest basic-clone-single-crtc: SKIP (0.001s) [224.889402] [130/171] (784s left) i915_pm_backlight (basic-brightness) [225.046458] Starting subtest: basic-brightness [225.153179] Starting dynamic subtest: eDP-1 [225.780258] Dynamic subtest eDP-1: SUCCESS (0.627s) [225.782573] Subtest basic-brightness: SUCCESS (0.736s) [226.686942] [131/171] (782s left) i915_pm_rpm (basic-pci-d3-state) [228.452805] Starting subtest: basic-pci-d3-state [233.032528] Subtest basic-pci-d3-state: SUCCESS (4.579s) [233.189087] [132/171] (775s left) i915_pm_rpm (basic-rte) [233.308697] Starting subtest: basic-rte [239.559411] Subtest basic-rte: SUCCESS (6.251s) [244.891038] [133/171] (764s left) i915_pm_rps (basic-api) [245.010474] Starting subtest: basic-api [245.016685] Subtest basic-api: SUCCESS (0.006s) [245.110936] [134/171] (763s left) prime_self_import (basic-llseek-bad) [245.216642] Starting subtest: basic-llseek-bad [245.218807] Subtest basic-llseek-bad: SUCCESS (0.002s) [245.356772] [135/171] (763s left) prime_self_import (basic-llseek-size) [245.463249] Starting subtest: basic-llseek-size [245.468207] Subtest basic-llseek-size: SUCCESS (0.003s) [245.587564] [136/171] (763s left) prime_self_import (basic-with_fd_dup) [245.694107] Starting subtest: basic-with_fd_dup [245.697861] Subtest basic-with_fd_dup: SUCCESS (0.004s) [245.812275] [137/171] (763s left) prime_self_import (basic-with_one_bo) [245.925277] Starting subtest: basic-with_one_bo [245.930034] Subtest basic-with_one_bo: SUCCESS (0.004s) [246.004898] [138/171] (763s left) prime_self_import (basic-with_one_bo_two_files) [246.116657] Starting subtest: basic-with_one_bo_two_files [246.120754] Subtest basic-with_one_bo_two_files: SUCCESS (0.003s) [246.206656] [139/171] (762s left) prime_self_import (basic-with_two_bos) [246.316225] Starting subtest: basic-with_two_bos [246.318240] Subtest basic-with_two_bos: SUCCESS (0.004s) [246.452351] [140/171] (762s left) prime_vgem (basic-fence-flip) [246.597585] Starting subtest: basic-fence-flip [247.105375] Subtest basic-fence-flip: SUCCESS (0.507s) [247.235028] [141/171] (762s left) prime_vgem (basic-fence-mmap) [247.385292] Starting subtest: basic-fence-mmap [247.421386] Subtest basic-fence-mmap: SUCCESS (0.036s) [247.528062] [142/171] (761s left) prime_vgem (basic-fence-read) [247.676879] Starting subtest: basic-fence-read [247.736657] Subtest basic-fence-read: SUCCESS (0.060s) [247.824114] [143/171] (761s left) prime_vgem (basic-gtt) [247.968042] Starting subtest: basic-gtt [247.983821] Subtest basic-gtt: SUCCESS (0.016s) [248.076386] [144/171] (761s left) prime_vgem (basic-read) [248.224785] Starting subtest: basic-read [248.250623] Subtest basic-read: SUCCESS (0.026s) [248.360819] [145/171] (761s left) prime_vgem (basic-write) [248.505340] Starting subtest: basic-write [248.534101] Subtest basic-write: SUCCESS (0.029s) [248.607721] [146/171] (760s left) vgem_basic (setversion) [248.753202] Starting subtest: setversion [248.755452] Subtest setversion: SUCCESS (0.000s) [248.839279] [147/171] (760s left) vgem_basic (create) [248.971027] Starting subtest: create [248.971950] Subtest create: SUCCESS (0.000s) [249.036969] [148/171] (760s left) vgem_basic (debugfs) [249.176249] Starting subtest: debugfs [249.177398] Subtest debugfs: SUCCESS (0.001s) [249.240197] [149/171] (760s left) vgem_basic (dmabuf-export) [249.381860] Starting subtest: dmabuf-export [249.384150] Subtest dmabuf-export: SUCCESS (0.002s) [249.503207] [150/171] (759s left) vgem_basic (dmabuf-fence) [249.634112] Starting subtest: dmabuf-fence [249.635155] Subtest dmabuf-fence: SUCCESS (0.001s) [249.694375] [151/171] (759s left) vgem_basic (dmabuf-fence-before) [249.839381] Starting subtest: dmabuf-fence-before [249.840461] Subtest dmabuf-fence-before: SUCCESS (0.001s) [249.906343] [152/171] (759s left) vgem_basic (dmabuf-mmap) [250.046729] Starting subtest: dmabuf-mmap [250.076247] Subtest dmabuf-mmap: SUCCESS (0.030s) [250.137803] [153/171] (759s left) vgem_basic (mmap) [250.286825] Starting subtest: mmap [250.297884] Subtest mmap: SUCCESS (0.013s) [250.361919] [154/171] (759s left) vgem_basic (second-client) [250.509188] Starting subtest: second-client [250.542170] Subtest second-client: SUCCESS (0.032s) [250.603071] [155/171] (759s left) vgem_basic (sysfs) [250.747769] Starting subtest: sysfs [250.748889] Subtest sysfs: SUCCESS (0.001s) [250.805806] [156/171] (758s left) core_hotunplug (unbind-rebind) [250.967335] Starting subtest: unbind-rebind [253.261015] Subtest unbind-rebind: SUCCESS (2.289s) [253.313303] [157/171] (756s left) vgem_basic (unload) [253.516241] Starting subtest: unload [282.575276] Subtest unload: SUCCESS (29.022s) [282.675551] [158/171] (727s left) i915_module_load (reload) [282.779336] Starting subtest: reload [286.144243] Subtest reload: SUCCESS (3.362s) [286.171234] [159/171] (723s left) gem_lmem_swapping (basic) [286.406131] Subtest basic: SKIP (0.000s) [286.451323] [160/171] (723s left) gem_lmem_swapping (parallel-random-engines) [286.565407] Subtest parallel-random-engines: SKIP (0.000s) [286.612075] [161/171] (723s left) gem_lmem_swapping (random-engines) [286.722532] Subtest random-engines: SKIP (0.000s) [286.762262] [162/171] (723s left) gem_lmem_swapping (verify-random) [286.873766] Subtest verify-random: SKIP (0.000s) [286.912355] [163/171] (722s left) i915_pm_rpm (module-reload) [289.531204] Starting subtest: module-reload [308.641393] Subtest module-reload: SUCCESS (19.104s) [308.683075] [164/171] (701s left) i915_selftest (live) [308.802118] Starting subtest: live [308.803203] Starting dynamic subtest: sanitycheck [310.528993] Dynamic subtest sanitycheck: SUCCESS (1.717s) [310.541531] Starting dynamic subtest: uncore [312.235687] Dynamic subtest uncore: SUCCESS (1.704s) [312.246952] Starting dynamic subtest: workarounds [314.101724] Dynamic subtest workarounds: SUCCESS (1.864s) [314.109211] Starting dynamic subtest: gt_engines [316.225886] Dynamic subtest gt_engines: SUCCESS (2.122s) [316.234246] Starting dynamic subtest: gt_timelines [326.809870] Dynamic subtest gt_timelines: SUCCESS (10.582s) [326.819154] Starting dynamic subtest: gt_contexts [328.637598] Dynamic subtest gt_contexts: SUCCESS (1.825s) [328.647809] Starting dynamic subtest: gt_lrc [332.834917] Dynamic subtest gt_lrc: SUCCESS (4.195s) [332.843572] Starting dynamic subtest: gt_mocs [334.660755] Dynamic subtest gt_mocs: SUCCESS (1.824s) [334.669363] Starting dynamic subtest: gt_pm [340.667803] Dynamic subtest gt_pm: SUCCESS (6.005s) [340.676397] Starting dynamic subtest: gt_heartbeat [342.468728] Dynamic subtest gt_heartbeat: SUCCESS (1.799s) [342.476565] Starting dynamic subtest: gt_tlb [344.940545] Dynamic subtest gt_tlb: SUCCESS (2.470s) [344.950880] Starting dynamic subtest: requests [352.416213] Dynamic subtest requests: SUCCESS (7.473s) [352.428592] Starting dynamic subtest: migrate [356.904748] Dynamic subtest migrate: SUCCESS (4.487s) [356.916555] Starting dynamic subtest: active [358.588763] Dynamic subtest active: SUCCESS (1.682s) [358.597285] Starting dynamic subtest: objects [361.458670] Dynamic subtest objects: SUCCESS (2.868s) [361.469378] Starting dynamic subtest: mman [366.625681] Dynamic subtest mman: SUCCESS (5.165s) [366.633339] Starting dynamic subtest: dmabuf [368.315627] Dynamic subtest dmabuf: SUCCESS (1.688s) [368.325763] Starting dynamic subtest: vma [370.042769] Dynamic subtest vma: SUCCESS (1.725s) [370.053531] Starting dynamic subtest: coherency [372.876711] Dynamic subtest coherency: SUCCESS (2.832s) [372.884368] Starting dynamic subtest: gtt [381.375761] Dynamic subtest gtt: SUCCESS (8.497s) [381.387227] Starting dynamic subtest: gem [383.347817] Dynamic subtest gem: SUCCESS (1.970s) [383.355474] Starting dynamic subtest: evict [386.606591] Dynamic subtest evict: SUCCESS (3.257s) [386.614191] Starting dynamic subtest: hugepages [393.535938] Dynamic subtest hugepages: SUCCESS (6.928s) [393.544670] Starting dynamic subtest: gem_contexts [408.054459] Dynamic subtest gem_contexts: SUCCESS (14.516s) [408.066948] Starting dynamic subtest: client [409.785698] Dynamic subtest client: SUCCESS (1.728s) [409.797192] Starting dynamic subtest: gem_migrate [411.489761] Dynamic subtest gem_migrate: SUCCESS (1.702s) [411.498223] Starting dynamic subtest: reset [413.882807] Dynamic subtest reset: SUCCESS (2.391s) [413.891268] Starting dynamic subtest: memory_region [415.551690] Dynamic subtest memory_region: SUCCESS (1.667s) [415.559989] Starting dynamic subtest: hangcheck [439.654662] Dynamic subtest hangcheck: SUCCESS (24.101s) [439.661837] Starting dynamic subtest: execlists [449.167822] Dynamic subtest execlists: SUCCESS (9.511s) [449.179700] Starting dynamic subtest: ring_submission [450.877670] Dynamic subtest ring_submission: SUCCESS (1.708s) [450.888987] Starting dynamic subtest: perf [452.735713] Dynamic subtest perf: SUCCESS (1.856s) [452.743385] Starting dynamic subtest: slpc [454.444621] Dynamic subtest slpc: SUCCESS (1.707s) [454.452228] Starting dynamic subtest: guc [456.123869] Dynamic subtest guc: SUCCESS (1.677s) [456.141889] Starting dynamic subtest: guc_multi_lrc [457.867716] Dynamic subtest guc_multi_lrc: SUCCESS (1.740s) [457.876396] Starting dynamic subtest: guc_hang [459.564849] Dynamic subtest guc_hang: SUCCESS (1.695s) [459.573539] Starting dynamic subtest: late_gt_pm [461.234857] Dynamic subtest late_gt_pm: SUCCESS (1.668s) [461.235943] Subtest live: SUCCESS (152.425s) [461.464128] [165/171] (548s left) dmabuf (all-tests) [461.587549] Starting subtest: all-tests [461.588986] Starting dynamic subtest: sanitycheck [461.645861] Dynamic subtest sanitycheck: SUCCESS (0.057s) [461.669459] Starting dynamic subtest: dma_fence [461.833608] Dynamic subtest dma_fence: SUCCESS (0.184s) [461.849617] Starting dynamic subtest: dma_fence_chain [478.061882] Dynamic subtest dma_fence_chain: SUCCESS (16.216s) [478.084216] Starting dynamic subtest: dma_fence_unwrap [478.138578] Dynamic subtest dma_fence_unwrap: SUCCESS (0.080s) [478.174426] Starting dynamic subtest: dma_resv [478.233678] Dynamic subtest dma_resv: SUCCESS (0.087s) [478.235239] Subtest all-tests: SUCCESS (16.647s) [478.271078] [166/171] (531s left) i915_suspend (basic-s2idle-without-i915) [478.376491] Starting subtest: basic-s2idle-without-i915 [497.399065] Subtest basic-s2idle-without-i915: SUCCESS (4.159s) [499.799087] [167/171] (527s left) i915_suspend (basic-s3-without-i915) [499.913620] Starting subtest: basic-s3-without-i915 [520.418041] Subtest basic-s3-without-i915: SUCCESS (4.407s) [523.882153] [168/171] (522s left) gem_exec_suspend (basic-s0) [524.034533] Starting subtest: basic-S0 [524.035624] Starting dynamic subtest: smem [542.704591] Dynamic subtest smem: SUCCESS (3.510s) [542.707246] Subtest basic-S0: SUCCESS (3.514s) [545.036987] [169/171] (518s left) gem_exec_suspend (basic-s3) [545.173744] Starting subtest: basic-S3 [545.174888] Starting dynamic subtest: smem [565.615179] Dynamic subtest smem: SUCCESS (4.532s) [565.616527] Subtest basic-S3: SUCCESS (4.534s) [568.843870] [170/171] (513s left) kms_chamelium_hpd (common-hpd-after-suspend) [569.329907] Subtest common-hpd-after-suspend: SKIP (0.000s) [570.246692] [171/171] (512s left) kms_pipe_crc_basic (suspend-read-crc) [570.392241] Starting subtest: suspend-read-crc [570.393564] Starting dynamic subtest: pipe-A-eDP-1 [590.301107] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.101s) [590.320300] Starting dynamic subtest: pipe-B-eDP-1 [610.839094] Dynamic subtest pipe-B-eDP-1: SUCCESS (4.344s) [610.855221] Starting dynamic subtest: pipe-C-eDP-1 [641.592473] Dynamic subtest pipe-C-eDP-1: SUCCESS (14.124s) [641.593614] Subtest suspend-read-crc: SUCCESS (21.579s) [643.508287] Closing watchdogs Done.