[39.191251] Initializing watchdogs [39.191375] /dev/watchdog0 [39.198229] [001/171] (960s left) i915_module_load (load) [39.305241] Starting subtest: load [40.381243] Subtest load: SUCCESS (1.074s) [40.416826] [002/171] (959s left) core_auth (basic-auth) [40.550735] Starting subtest: basic-auth [40.552056] Subtest basic-auth: SUCCESS (0.001s) [40.636986] [003/171] (959s left) debugfs_test (read_all_entries) [40.797325] Starting subtest: read_all_entries [40.875545] Subtest read_all_entries: SUCCESS (0.078s) [40.985100] [004/171] (958s left) debugfs_test (basic-hwmon) [41.089028] Starting subtest: basic-hwmon [41.090687] Subtest basic-hwmon: SKIP (0.000s) [41.216235] [005/171] (958s left) fbdev (eof) [41.322197] Starting subtest: eof [41.323111] Subtest eof: SUCCESS (0.000s) [41.383502] [006/171] (958s left) fbdev (info) [41.480657] Starting subtest: info [41.481815] Subtest info: SUCCESS (0.000s) [41.544110] [007/171] (958s left) fbdev (nullptr) [41.655103] Starting subtest: nullptr [41.656164] Subtest nullptr: SUCCESS (0.000s) [41.708857] [008/171] (958s left) fbdev (read) [41.822536] Starting subtest: read [42.668939] Subtest read: SUCCESS (0.838s) [42.711453] [009/171] (957s left) fbdev (write) [42.822298] Starting subtest: write [43.247302] Subtest write: SUCCESS (0.416s) [43.327983] [010/171] (956s left) gem_basic (bad-close) [43.430639] Starting subtest: bad-close [43.432574] Subtest bad-close: SUCCESS (0.000s) [43.488020] [011/171] (956s left) gem_basic (create-close) [43.589568] Starting subtest: create-close [43.591482] Subtest create-close: SUCCESS (0.000s) [43.719820] [012/171] (956s left) gem_basic (create-fd-close) [43.825138] Starting subtest: create-fd-close [43.826964] Subtest create-fd-close: SUCCESS (0.000s) [43.954616] [013/171] (955s left) gem_busy (busy@all-engines) [44.079777] Starting subtest: busy [44.080909] Starting dynamic subtest: all-engines [44.154923] Dynamic subtest all-engines: SUCCESS (0.075s) [44.155902] Subtest busy: SUCCESS (0.075s) [44.583240] [014/171] (955s left) gem_close_race (basic-process) [44.704223] Starting subtest: basic-process [44.770442] Subtest basic-process: SUCCESS (0.066s) [44.978831] [015/171] (955s left) gem_close_race (basic-threads) [45.101267] Starting subtest: basic-threads [46.261104] Subtest basic-threads: SUCCESS (1.159s) [46.503694] [016/171] (953s left) gem_ctx_create (basic) [46.622885] Starting subtest: basic [46.624373] Subtest basic: SUCCESS (0.000s) [46.831461] [017/171] (953s left) gem_ctx_create (basic-files) [46.950174] Starting subtest: basic-files [48.973045] Subtest basic-files: SUCCESS (2.023s) [49.280284] [018/171] (950s left) gem_ctx_exec (basic) [49.389779] Starting subtest: basic [49.392730] Subtest basic: SUCCESS (0.004s) [49.517022] [019/171] (950s left) gem_exec_basic (basic) [49.632575] Starting subtest: basic [49.633858] Starting dynamic subtest: rcs0-smem [49.635066] Dynamic subtest rcs0-smem: SUCCESS (0.002s) [49.642321] Starting dynamic subtest: bcs0-smem [49.643368] Dynamic subtest bcs0-smem: SUCCESS (0.001s) [49.650231] Starting dynamic subtest: vcs0-smem [49.651620] Dynamic subtest vcs0-smem: SUCCESS (0.001s) [49.658507] Starting dynamic subtest: vecs0-smem [49.659866] Dynamic subtest vecs0-smem: SUCCESS (0.001s) [49.661253] Subtest basic: SUCCESS (0.005s) [49.865817] [020/171] (950s left) gem_exec_create (basic) [49.982250] Starting subtest: basic [49.983318] Starting dynamic subtest: smem [52.028816] Dynamic subtest smem: SUCCESS (2.046s) [52.030182] Subtest basic: SUCCESS (2.046s) [52.245931] [021/171] (947s left) gem_exec_fence (basic-busy) [52.515151] Starting subtest: basic-busy [52.516708] Starting dynamic subtest: rcs0 [52.545393] Dynamic subtest rcs0: SUCCESS (0.030s) [52.553133] Starting dynamic subtest: bcs0 [52.569331] Dynamic subtest bcs0: SUCCESS (0.024s) [52.577175] Starting dynamic subtest: vcs0 [52.593293] Dynamic subtest vcs0: SUCCESS (0.024s) [52.600779] Starting dynamic subtest: vecs0 [52.621242] Dynamic subtest vecs0: SUCCESS (0.028s) [52.622475] Subtest basic-busy: SUCCESS (0.107s) [53.246783] [022/171] (946s left) gem_exec_fence (basic-wait) [53.523681] Starting subtest: basic-wait [53.524907] Starting dynamic subtest: rcs0 [53.549257] Dynamic subtest rcs0: SUCCESS (0.026s) [53.556966] Starting dynamic subtest: bcs0 [53.579409] Dynamic subtest bcs0: SUCCESS (0.030s) [53.587222] Starting dynamic subtest: vcs0 [53.602371] Dynamic subtest vcs0: SUCCESS (0.023s) [53.609626] Starting dynamic subtest: vecs0 [53.628375] Dynamic subtest vecs0: SUCCESS (0.026s) [53.629408] Subtest basic-wait: SUCCESS (0.105s) [54.298770] [023/171] (945s left) gem_exec_fence (basic-await) [54.569724] Starting subtest: basic-await [54.571035] Starting dynamic subtest: rcs0 [54.668543] Dynamic subtest rcs0: SUCCESS (0.098s) [54.677285] Starting dynamic subtest: bcs0 [54.764497] Dynamic subtest bcs0: SUCCESS (0.096s) [54.775508] Starting dynamic subtest: vcs0 [54.869171] Dynamic subtest vcs0: SUCCESS (0.104s) [54.878047] Starting dynamic subtest: vecs0 [54.963487] Dynamic subtest vecs0: SUCCESS (0.094s) [54.964408] Subtest basic-await: SUCCESS (0.394s) [55.687581] [024/171] (944s left) gem_exec_fence (nb-await) [55.962136] Starting subtest: nb-await [55.964315] Starting dynamic subtest: rcs0 [56.034140] Dynamic subtest rcs0: SUCCESS (0.072s) [56.051492] Starting dynamic subtest: bcs0 [56.129965] Dynamic subtest bcs0: SUCCESS (0.089s) [56.172937] Starting dynamic subtest: vcs0 [56.230227] Dynamic subtest vcs0: SUCCESS (0.092s) [56.271416] Starting dynamic subtest: vecs0 [56.337577] Dynamic subtest vecs0: SUCCESS (0.106s) [56.341264] Subtest nb-await: SUCCESS (0.374s) [57.066717] [025/171] (943s left) gem_exec_gttfill (basic) [57.192859] Starting subtest: basic [66.198804] Subtest basic: SUCCESS (8.997s) [66.389048] [026/171] (933s left) gem_exec_parallel (engines) [66.572476] Starting subtest: engines [66.573605] Starting dynamic subtest: basic [68.935625] Dynamic subtest basic: SUCCESS (2.363s) [68.943688] Starting dynamic subtest: contexts [71.787070] Dynamic subtest contexts: SUCCESS (2.843s) [71.795075] Starting dynamic subtest: fds [75.134659] Dynamic subtest fds: SUCCESS (3.356s) [75.145628] Starting dynamic subtest: userptr [77.520577] Dynamic subtest userptr: SUCCESS (2.385s) [77.521948] Subtest engines: SUCCESS (10.948s) [77.739493] [027/171] (922s left) gem_exec_store (basic) [77.862669] Starting subtest: basic [77.910768] Subtest basic: SUCCESS (0.048s) [78.138021] [028/171] (922s left) gem_flink_basic (bad-flink) [78.245306] Starting subtest: bad-flink [78.246514] Subtest bad-flink: SUCCESS (0.000s) [78.339679] [029/171] (921s left) gem_flink_basic (bad-open) [78.445748] Starting subtest: bad-open [78.446925] Subtest bad-open: SUCCESS (0.000s) [78.547897] [030/171] (921s left) gem_flink_basic (basic) [78.653597] Starting subtest: basic [78.654707] Subtest basic: SUCCESS (0.000s) [78.751263] [031/171] (921s left) gem_flink_basic (double-flink) [78.858938] Starting subtest: double-flink [78.860028] Subtest double-flink: SUCCESS (0.000s) [78.955473] [032/171] (921s left) gem_flink_basic (flink-lifetime) [79.062656] Starting subtest: flink-lifetime [79.063911] Subtest flink-lifetime: SUCCESS (0.001s) [79.169528] [033/171] (921s left) gem_huc_copy (huc-copy) [79.284642] Starting subtest: huc-copy [79.286893] Subtest huc-copy: SKIP (0.000s) [79.418201] [034/171] (920s left) gem_linear_blits (basic) [79.645932] Starting subtest: basic [79.654788] Subtest basic: SUCCESS (0.009s) [79.823214] [035/171] (920s left) gem_mmap (basic) [79.984921] Starting subtest: basic [79.990060] Subtest basic: SUCCESS (0.001s) [80.060396] [036/171] (920s left) gem_mmap_gtt (basic) [80.168851] Starting subtest: basic [80.170080] Subtest basic: SUCCESS (0.001s) [80.270444] [037/171] (920s left) gem_render_linear_blits (basic) [80.385538] Starting subtest: basic [80.426404] Subtest basic: SUCCESS (0.041s) [80.467731] [038/171] (919s left) gem_render_tiled_blits (basic) [80.582221] Starting subtest: basic [80.628900] Subtest basic: SUCCESS (0.046s) [80.667192] [039/171] (919s left) gem_ringfill (basic-all) [80.960445] Starting subtest: basic-all [82.130841] Subtest basic-all: SUCCESS (1.162s) [82.294988] [040/171] (918s left) gem_softpin (allocator-basic) [82.418087] Starting subtest: allocator-basic [82.560098] Subtest allocator-basic: SUCCESS (0.133s) [82.688226] [041/171] (917s left) gem_softpin (allocator-basic-reserve) [82.799615] Starting subtest: allocator-basic-reserve [82.944579] Subtest allocator-basic-reserve: SUCCESS (0.136s) [83.068434] [042/171] (917s left) gem_softpin (safe-alignment) [83.189679] Starting subtest: safe-alignment [83.205491] Subtest safe-alignment: SUCCESS (0.015s) [83.344539] [043/171] (917s left) gem_sync (basic-all) [83.467354] Starting subtest: basic-all [85.649481] Subtest basic-all: SUCCESS (2.182s) [85.874087] [044/171] (914s left) gem_sync (basic-each) [85.996196] Starting subtest: basic-each [88.187545] Subtest basic-each: SUCCESS (2.191s) [88.471410] [045/171] (912s left) gem_tiled_blits (basic) [88.704519] Starting subtest: basic [88.730736] Subtest basic: SUCCESS (0.026s) [88.894953] [046/171] (911s left) gem_tiled_fence_blits (basic) [89.190681] Starting subtest: basic [89.205220] Subtest basic: SUCCESS (0.015s) [89.352975] [047/171] (911s left) gem_tiled_pread_basic [89.729620] [048/171] (910s left) gem_wait (busy@all-engines) [89.857080] Starting subtest: busy [89.858214] Starting dynamic subtest: all-engines [90.398499] Dynamic subtest all-engines: SUCCESS (0.541s) [90.399592] Subtest busy: SUCCESS (0.541s) [90.625629] [049/171] (910s left) gem_wait (wait@all-engines) [90.756386] Starting subtest: wait [90.757594] Starting dynamic subtest: all-engines [91.818848] Dynamic subtest all-engines: SUCCESS (1.058s) [91.821971] Subtest wait: SUCCESS (1.059s) [92.096765] [050/171] (908s left) i915_getparams_basic (basic-eu-total) [92.204098] Starting subtest: basic-eu-total [92.206112] Subtest basic-eu-total: SUCCESS (0.000s) [92.296608] [051/171] (908s left) i915_getparams_basic (basic-subslice-total) [92.404624] Starting subtest: basic-subslice-total [92.406440] Subtest basic-subslice-total: SUCCESS (0.000s) [92.503474] [052/171] (908s left) i915_hangman (error-state-basic) [92.623089] Starting subtest: error-state-basic [92.633370] Subtest error-state-basic: SUCCESS (0.010s) [92.727272] [053/171] (908s left) i915_pciid [92.928330] [054/171] (907s left) kms_addfb_basic (addfb25-bad-modifier) [93.037511] Starting subtest: addfb25-bad-modifier [93.038646] Subtest addfb25-bad-modifier: SUCCESS (0.000s) [93.205497] [055/171] (907s left) kms_addfb_basic (addfb25-framebuffer-vs-set-tiling) [93.317120] Starting subtest: addfb25-framebuffer-vs-set-tiling [93.318205] Subtest addfb25-framebuffer-vs-set-tiling: SUCCESS (0.000s) [93.452132] [056/171] (907s left) kms_addfb_basic (addfb25-modifier-no-flag) [93.560850] Starting subtest: addfb25-modifier-no-flag [93.561970] Subtest addfb25-modifier-no-flag: SUCCESS (0.000s) [93.698901] [057/171] (907s left) kms_addfb_basic (addfb25-x-tiled-legacy) [93.802577] Starting subtest: addfb25-x-tiled-legacy [93.803749] Subtest addfb25-x-tiled-legacy: SUCCESS (0.000s) [93.945943] [058/171] (906s left) kms_addfb_basic (addfb25-x-tiled-mismatch-legacy) [94.051937] Starting subtest: addfb25-x-tiled-mismatch-legacy [94.053367] Subtest addfb25-x-tiled-mismatch-legacy: SUCCESS (0.000s) [94.189157] [059/171] (906s left) kms_addfb_basic (addfb25-yf-tiled-legacy) [94.334180] Starting subtest: addfb25-yf-tiled-legacy [94.335134] Subtest addfb25-yf-tiled-legacy: SUCCESS (0.000s) [94.431177] [060/171] (906s left) kms_addfb_basic (addfb25-y-tiled-legacy) [94.570133] Starting subtest: addfb25-y-tiled-legacy [94.571144] Subtest addfb25-y-tiled-legacy: SUCCESS (0.000s) [94.665831] [061/171] (906s left) kms_addfb_basic (addfb25-y-tiled-small-legacy) [94.805371] Starting subtest: addfb25-y-tiled-small-legacy [94.806462] Subtest addfb25-y-tiled-small-legacy: SUCCESS (0.000s) [94.907570] [062/171] (906s left) kms_addfb_basic (bad-pitch-0) [95.005646] Starting subtest: bad-pitch-0 [95.006770] Subtest bad-pitch-0: SUCCESS (0.000s) [95.145214] [063/171] (905s left) kms_addfb_basic (bad-pitch-1024) [95.246219] Starting subtest: bad-pitch-1024 [95.247283] Subtest bad-pitch-1024: SUCCESS (0.000s) [95.383066] [064/171] (905s left) kms_addfb_basic (bad-pitch-128) [95.484132] Starting subtest: bad-pitch-128 [95.485191] Subtest bad-pitch-128: SUCCESS (0.000s) [95.617097] [065/171] (905s left) kms_addfb_basic (bad-pitch-256) [95.717094] Starting subtest: bad-pitch-256 [95.718139] Subtest bad-pitch-256: SUCCESS (0.000s) [95.860950] [066/171] (905s left) kms_addfb_basic (bad-pitch-32) [95.958409] Starting subtest: bad-pitch-32 [95.959366] Subtest bad-pitch-32: SUCCESS (0.000s) [96.106168] [067/171] (904s left) kms_addfb_basic (bad-pitch-63) [96.205759] Starting subtest: bad-pitch-63 [96.206827] Subtest bad-pitch-63: SUCCESS (0.000s) [96.350059] [068/171] (904s left) kms_addfb_basic (bad-pitch-65536) [96.449805] Starting subtest: bad-pitch-65536 [96.450935] Subtest bad-pitch-65536: SUCCESS (0.000s) [96.586363] [069/171] (904s left) kms_addfb_basic (bad-pitch-999) [96.681471] Starting subtest: bad-pitch-999 [96.682615] Subtest bad-pitch-999: SUCCESS (0.000s) [96.822402] [070/171] (904s left) kms_addfb_basic (basic) [96.925225] Starting subtest: basic [96.926175] Subtest basic: SUCCESS (0.000s) [97.071880] [071/171] (904s left) kms_addfb_basic (basic-x-tiled-legacy) [97.169098] Starting subtest: basic-x-tiled-legacy [97.170135] Subtest basic-x-tiled-legacy: SUCCESS (0.000s) [97.309154] [072/171] (903s left) kms_addfb_basic (basic-y-tiled-legacy) [97.407663] Starting subtest: basic-y-tiled-legacy [97.408731] Subtest basic-y-tiled-legacy: SUCCESS (0.000s) [97.542712] [073/171] (903s left) kms_addfb_basic (bo-too-small) [97.677677] Starting subtest: bo-too-small [97.678749] Subtest bo-too-small: SUCCESS (0.000s) [97.777045] [074/171] (903s left) kms_addfb_basic (bo-too-small-due-to-tiling) [97.906395] Starting subtest: bo-too-small-due-to-tiling [97.907452] Subtest bo-too-small-due-to-tiling: SUCCESS (0.000s) [97.997395] [075/171] (903s left) kms_addfb_basic (clobberred-modifier) [98.093219] Starting subtest: clobberred-modifier [98.094497] Subtest clobberred-modifier: SUCCESS (0.000s) [98.228858] [076/171] (903s left) kms_addfb_basic (framebuffer-vs-set-tiling) [98.326627] Starting subtest: framebuffer-vs-set-tiling [98.327742] Subtest framebuffer-vs-set-tiling: SUCCESS (0.000s) [98.452221] [077/171] (902s left) kms_addfb_basic (invalid-get-prop) [98.548497] Starting subtest: invalid-get-prop [98.549505] Subtest invalid-get-prop: SUCCESS (0.000s) [98.678388] [078/171] (902s left) kms_addfb_basic (invalid-get-prop-any) [98.777380] Starting subtest: invalid-get-prop-any [98.778385] Subtest invalid-get-prop-any: SUCCESS (0.000s) [98.912369] [079/171] (902s left) kms_addfb_basic (invalid-set-prop) [99.005985] Starting subtest: invalid-set-prop [99.007130] Subtest invalid-set-prop: SUCCESS (0.000s) [99.134166] [080/171] (902s left) kms_addfb_basic (invalid-set-prop-any) [99.231528] Starting subtest: invalid-set-prop-any [99.232560] Subtest invalid-set-prop-any: SUCCESS (0.000s) [99.361962] [081/171] (901s left) kms_addfb_basic (no-handle) [99.459364] Starting subtest: no-handle [99.460324] Subtest no-handle: SUCCESS (0.000s) [99.607991] [082/171] (901s left) kms_addfb_basic (size-max) [99.748156] Starting subtest: size-max [99.749185] Subtest size-max: SUCCESS (0.000s) [99.854415] [083/171] (901s left) kms_addfb_basic (small-bo) [99.993103] Starting subtest: small-bo [99.994051] Subtest small-bo: SUCCESS (0.000s) [100.093133] [084/171] (901s left) kms_addfb_basic (tile-pitch-mismatch) [100.188949] Starting subtest: tile-pitch-mismatch [100.189909] Subtest tile-pitch-mismatch: SUCCESS (0.000s) [100.323936] [085/171] (901s left) kms_addfb_basic (too-high) [100.462249] Starting subtest: too-high [100.463177] Subtest too-high: SUCCESS (0.001s) [100.565914] [086/171] (900s left) kms_addfb_basic (too-wide) [100.698388] Starting subtest: too-wide [100.699375] Subtest too-wide: SUCCESS (0.000s) [100.805397] [087/171] (900s left) kms_addfb_basic (unused-handle) [100.902404] Starting subtest: unused-handle [100.903511] Subtest unused-handle: SUCCESS (0.000s) [101.049106] [088/171] (900s left) kms_addfb_basic (unused-modifier) [101.145371] Starting subtest: unused-modifier [101.146300] Subtest unused-modifier: SUCCESS (0.000s) [101.282201] [089/171] (900s left) kms_addfb_basic (unused-offsets) [101.378562] Starting subtest: unused-offsets [101.379752] Subtest unused-offsets: SUCCESS (0.000s) [101.511931] [090/171] (899s left) kms_addfb_basic (unused-pitches) [101.609489] Starting subtest: unused-pitches [101.610523] Subtest unused-pitches: SUCCESS (0.000s) [101.738791] [091/171] (899s left) kms_busy (basic) [101.882946] Starting subtest: basic [101.885168] Starting dynamic subtest: flip [102.818986] Dynamic subtest flip: SUCCESS (0.931s) [102.834513] Starting dynamic subtest: modeset [106.533177] Dynamic subtest modeset: SUCCESS (3.715s) [106.534260] Subtest basic: SUCCESS (4.649s) [107.454166] [092/171] (894s left) kms_chamelium_hpd (dp-hpd-fast) [107.899355] Subtest dp-hpd-fast: SKIP (0.000s) [108.819985] [093/171] (892s left) kms_chamelium_edid (dp-edid-read) [109.269226] Subtest dp-edid-read: SKIP (0.000s) [110.163166] [094/171] (891s left) kms_chamelium_frames (dp-crc-fast) [110.619797] Subtest dp-crc-fast: SKIP (0.000s) [111.502751] [095/171] (890s left) kms_chamelium_hpd (hdmi-hpd-fast) [111.960682] Subtest hdmi-hpd-fast: SKIP (0.000s) [112.847056] [096/171] (888s left) kms_chamelium_edid (hdmi-edid-read) [113.296828] Subtest hdmi-edid-read: SKIP (0.000s) [114.190556] [097/171] (887s left) kms_chamelium_frames (hdmi-crc-fast) [114.645707] Subtest hdmi-crc-fast: SKIP (0.000s) [115.532871] [098/171] (886s left) kms_chamelium_hpd (vga-hpd-fast) [115.982842] Subtest vga-hpd-fast: SKIP (0.000s) [116.877182] [099/171] (884s left) kms_chamelium_edid (vga-edid-read) [117.310457] Subtest vga-edid-read: SKIP (0.000s) [118.225116] [100/171] (883s left) kms_prop_blob (basic) [118.343528] Starting subtest: basic [118.344509] Subtest basic: SUCCESS (0.000s) [118.429225] [101/171] (883s left) kms_cursor_legacy (basic-busy-flip-before-cursor-atomic) [118.591117] Starting subtest: basic-busy-flip-before-cursor-atomic [118.592927] Subtest basic-busy-flip-before-cursor-atomic: SKIP (0.000s) [118.680881] [102/171] (883s left) kms_cursor_legacy (basic-busy-flip-before-cursor-legacy) [118.823523] Starting subtest: basic-busy-flip-before-cursor-legacy [118.825815] Subtest basic-busy-flip-before-cursor-legacy: SKIP (0.000s) [118.908462] [103/171] (882s left) kms_cursor_legacy (basic-flip-after-cursor-atomic) [119.059132] Starting subtest: basic-flip-after-cursor-atomic [120.362766] Subtest basic-flip-after-cursor-atomic: SUCCESS (1.302s) [121.274474] [104/171] (880s left) kms_cursor_legacy (basic-flip-after-cursor-legacy) [121.423109] Starting subtest: basic-flip-after-cursor-legacy [122.713681] Subtest basic-flip-after-cursor-legacy: SUCCESS (1.289s) [123.644506] [105/171] (878s left) kms_cursor_legacy (basic-flip-after-cursor-varying-size) [123.789537] Starting subtest: basic-flip-after-cursor-varying-size [125.060067] Subtest basic-flip-after-cursor-varying-size: SUCCESS (1.270s) [126.011153] [106/171] (875s left) kms_cursor_legacy (basic-flip-before-cursor-atomic) [126.157574] Starting subtest: basic-flip-before-cursor-atomic [127.426317] Subtest basic-flip-before-cursor-atomic: SUCCESS (1.269s) [128.382384] [107/171] (873s left) kms_cursor_legacy (basic-flip-before-cursor-legacy) [128.526481] Starting subtest: basic-flip-before-cursor-legacy [129.863878] Subtest basic-flip-before-cursor-legacy: SUCCESS (1.338s) [130.815021] [108/171] (871s left) kms_cursor_legacy (basic-flip-before-cursor-varying-size) [130.962479] Starting subtest: basic-flip-before-cursor-varying-size [132.225837] Subtest basic-flip-before-cursor-varying-size: SUCCESS (1.263s) [133.182389] [109/171] (868s left) kms_flip (basic-flip-vs-dpms) [133.307500] Starting subtest: basic-flip-vs-dpms [133.313784] Starting dynamic subtest: A-eDP1 [136.179055] Dynamic subtest A-eDP1: SUCCESS (2.865s) [136.191368] Starting dynamic subtest: B-eDP1 [138.541148] Dynamic subtest B-eDP1: SUCCESS (2.359s) [138.551054] Starting dynamic subtest: C-eDP1 [140.930067] Dynamic subtest C-eDP1: SUCCESS (2.387s) [140.933572] Subtest basic-flip-vs-dpms: SUCCESS (7.626s) [141.815457] [110/171] (860s left) kms_flip (basic-flip-vs-modeset) [141.942119] Starting subtest: basic-flip-vs-modeset [141.944978] Starting dynamic subtest: A-eDP1 [144.746765] Dynamic subtest A-eDP1: SUCCESS (2.801s) [144.759216] Starting dynamic subtest: B-eDP1 [147.109717] Dynamic subtest B-eDP1: SUCCESS (2.362s) [147.121529] Starting dynamic subtest: C-eDP1 [149.489991] Dynamic subtest C-eDP1: SUCCESS (2.377s) [149.497401] Subtest basic-flip-vs-modeset: SUCCESS (7.557s) [150.388673] [111/171] (851s left) kms_flip (basic-flip-vs-wf_vblank) [150.515042] Starting subtest: basic-flip-vs-wf_vblank [150.520136] Starting dynamic subtest: A-eDP1 [152.789401] Dynamic subtest A-eDP1: SUCCESS (2.269s) [152.805185] Starting dynamic subtest: B-eDP1 [155.030942] Dynamic subtest B-eDP1: SUCCESS (2.240s) [155.042668] Starting dynamic subtest: C-eDP1 [157.337849] Dynamic subtest C-eDP1: SUCCESS (2.305s) [157.341691] Subtest basic-flip-vs-wf_vblank: SUCCESS (6.828s) [158.655914] [112/171] (843s left) kms_flip (basic-plain-flip) [158.780070] Starting subtest: basic-plain-flip [158.782906] Starting dynamic subtest: A-eDP1 [160.836401] Dynamic subtest A-eDP1: SUCCESS (2.047s) [160.885176] Starting dynamic subtest: B-eDP1 [162.874421] Dynamic subtest B-eDP1: SUCCESS (2.038s) [162.884627] Starting dynamic subtest: C-eDP1 [164.865770] Dynamic subtest C-eDP1: SUCCESS (1.983s) [164.879628] Subtest basic-plain-flip: SUCCESS (6.101s) [166.206624] [113/171] (835s left) kms_force_connector_basic (force-connector-state) [166.350312] Starting subtest: force-connector-state [166.956070] Subtest force-connector-state: SUCCESS (0.595s) [167.967619] [114/171] (834s left) kms_force_connector_basic (force-edid) [168.107501] Starting subtest: force-edid [168.137309] Subtest force-edid: SUCCESS (0.030s) [168.304286] [115/171] (833s left) kms_force_connector_basic (force-load-detect) [168.432330] Starting subtest: force-load-detect [168.434450] Subtest force-load-detect: SKIP (0.000s) [168.580750] [116/171] (833s left) kms_force_connector_basic (prune-stale-modes) [168.706784] Starting subtest: prune-stale-modes [168.740741] Subtest prune-stale-modes: SUCCESS (0.032s) [168.932680] [117/171] (833s left) kms_frontbuffer_tracking (basic) [169.083920] Starting subtest: basic [171.870747] Subtest basic: SUCCESS (2.789s) [171.979413] [118/171] (830s left) kms_pipe_crc_basic (compare-crc-sanitycheck-xr24) [172.127627] Starting subtest: compare-crc-sanitycheck-xr24 [172.130127] Starting dynamic subtest: pipe-A-eDP-1 [172.749515] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.618s) [172.761388] Starting dynamic subtest: pipe-B-eDP-1 [174.065183] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.311s) [174.076885] Starting dynamic subtest: pipe-C-eDP-1 [175.387560] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.320s) [175.388501] Subtest compare-crc-sanitycheck-xr24: SUCCESS (3.260s) [176.313055] [119/171] (825s left) kms_pipe_crc_basic (compare-crc-sanitycheck-nv12) [176.452763] Starting subtest: compare-crc-sanitycheck-nv12 [176.455823] Starting dynamic subtest: pipe-A-eDP-1 [176.457948] Dynamic subtest pipe-A-eDP-1: SKIP (0.001s) [176.467654] Starting dynamic subtest: pipe-B-eDP-1 [176.469624] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s) [176.477892] Starting dynamic subtest: pipe-C-eDP-1 [176.479946] Dynamic subtest pipe-C-eDP-1: SKIP (0.001s) [176.481939] Subtest compare-crc-sanitycheck-nv12: SKIP (0.009s) [176.544416] [120/171] (825s left) kms_pipe_crc_basic (hang-read-crc) [176.682251] Starting subtest: hang-read-crc [176.685358] Starting dynamic subtest: pipe-A-eDP-1 [178.875521] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.192s) [178.893576] Starting dynamic subtest: pipe-B-eDP-1 [181.806328] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.925s) [181.818676] Starting dynamic subtest: pipe-C-eDP-1 [184.741229] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.928s) [184.743483] Subtest hang-read-crc: SUCCESS (8.057s) [185.666452] [121/171] (816s left) kms_pipe_crc_basic (nonblocking-crc) [185.816885] Starting subtest: nonblocking-crc [185.818154] Starting dynamic subtest: pipe-A-eDP-1 [186.619163] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.793s) [186.637734] Starting dynamic subtest: pipe-B-eDP-1 [188.150288] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.525s) [188.166618] Starting dynamic subtest: pipe-C-eDP-1 [189.679209] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.521s) [189.680470] Subtest nonblocking-crc: SUCCESS (3.862s) [190.591600] [122/171] (811s left) kms_pipe_crc_basic (nonblocking-crc-frame-sequence) [190.737415] Starting subtest: nonblocking-crc-frame-sequence [190.738509] Starting dynamic subtest: pipe-A-eDP-1 [191.535166] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.794s) [191.546991] Starting dynamic subtest: pipe-B-eDP-1 [193.083137] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.542s) [193.099226] Starting dynamic subtest: pipe-C-eDP-1 [194.589153] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.498s) [194.590941] Subtest nonblocking-crc-frame-sequence: SUCCESS (3.851s) [195.521677] [123/171] (806s left) kms_pipe_crc_basic (read-crc) [195.679063] Starting subtest: read-crc [195.682400] Starting dynamic subtest: pipe-A-eDP-1 [196.397155] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.713s) [196.416091] Starting dynamic subtest: pipe-B-eDP-1 [197.875291] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.469s) [197.892500] Starting dynamic subtest: pipe-C-eDP-1 [199.341574] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.460s) [199.343546] Subtest read-crc: SUCCESS (3.658s) [200.249926] [124/171] (802s left) kms_pipe_crc_basic (read-crc-frame-sequence) [200.396219] Starting subtest: read-crc-frame-sequence [200.399467] Starting dynamic subtest: pipe-A-eDP-1 [201.152409] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.750s) [201.165635] Starting dynamic subtest: pipe-B-eDP-1 [202.665090] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.505s) [202.681331] Starting dynamic subtest: pipe-C-eDP-1 [204.082110] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.414s) [204.083355] Subtest read-crc-frame-sequence: SUCCESS (3.685s) [204.984867] [125/171] (797s left) kms_psr (primary_page_flip) [205.138908] Starting subtest: primary_page_flip [207.126020] Subtest primary_page_flip: SUCCESS (1.985s) [208.058512] [126/171] (794s left) kms_psr (cursor_plane_move) [208.227753] Starting subtest: cursor_plane_move [210.177045] Subtest cursor_plane_move: SUCCESS (1.944s) [211.067532] [127/171] (791s left) kms_psr (sprite_plane_onoff) [211.219185] Starting subtest: sprite_plane_onoff [213.235817] Subtest sprite_plane_onoff: SUCCESS (2.014s) [214.121544] [128/171] (788s left) kms_psr (primary_mmap_gtt) [214.273374] Starting subtest: primary_mmap_gtt [216.243170] Subtest primary_mmap_gtt: SUCCESS (1.968s) [217.209433] [129/171] (785s left) kms_setmode (basic-clone-single-crtc) [217.318340] Starting subtest: basic-clone-single-crtc [217.321347] Subtest basic-clone-single-crtc: SKIP (0.000s) [217.433284] [130/171] (784s left) i915_pm_backlight (basic-brightness) [217.590206] Starting subtest: basic-brightness [217.695646] Starting dynamic subtest: eDP-1 [218.323871] Dynamic subtest eDP-1: SUCCESS (0.619s) [218.326699] Subtest basic-brightness: SUCCESS (0.725s) [219.244289] [131/171] (783s left) i915_pm_rpm (basic-pci-d3-state) [221.006277] Starting subtest: basic-pci-d3-state [225.508576] Subtest basic-pci-d3-state: SUCCESS (4.504s) [225.677185] [132/171] (776s left) i915_pm_rpm (basic-rte) [225.782253] Starting subtest: basic-rte [231.970342] Subtest basic-rte: SUCCESS (6.184s) [237.284234] [133/171] (765s left) i915_pm_rps (basic-api) [237.400788] Starting subtest: basic-api [237.405556] Subtest basic-api: SUCCESS (0.005s) [237.498203] [134/171] (764s left) prime_self_import (basic-llseek-bad) [237.605322] Starting subtest: basic-llseek-bad [237.607671] Subtest basic-llseek-bad: SUCCESS (0.002s) [237.737426] [135/171] (764s left) prime_self_import (basic-llseek-size) [237.842345] Starting subtest: basic-llseek-size [237.847088] Subtest basic-llseek-size: SUCCESS (0.003s) [237.963917] [136/171] (764s left) prime_self_import (basic-with_fd_dup) [238.078570] Starting subtest: basic-with_fd_dup [238.082578] Subtest basic-with_fd_dup: SUCCESS (0.004s) [238.212797] [137/171] (764s left) prime_self_import (basic-with_one_bo) [238.319162] Starting subtest: basic-with_one_bo [238.323977] Subtest basic-with_one_bo: SUCCESS (0.004s) [238.395410] [138/171] (764s left) prime_self_import (basic-with_one_bo_two_files) [238.503214] Starting subtest: basic-with_one_bo_two_files [238.505458] Subtest basic-with_one_bo_two_files: SUCCESS (0.002s) [238.607869] [139/171] (764s left) prime_self_import (basic-with_two_bos) [238.723857] Starting subtest: basic-with_two_bos [238.728817] Subtest basic-with_two_bos: SUCCESS (0.003s) [238.853396] [140/171] (763s left) prime_vgem (basic-fence-flip) [238.994797] Starting subtest: basic-fence-flip [239.485782] Subtest basic-fence-flip: SUCCESS (0.491s) [239.597519] [141/171] (763s left) prime_vgem (basic-fence-mmap) [239.751631] Starting subtest: basic-fence-mmap [239.789647] Subtest basic-fence-mmap: SUCCESS (0.038s) [239.881254] [142/171] (762s left) prime_vgem (basic-fence-read) [240.030122] Starting subtest: basic-fence-read [240.087947] Subtest basic-fence-read: SUCCESS (0.058s) [240.179239] [143/171] (762s left) prime_vgem (basic-gtt) [240.318024] Starting subtest: basic-gtt [240.333665] Subtest basic-gtt: SUCCESS (0.016s) [240.446820] [144/171] (762s left) prime_vgem (basic-read) [240.590308] Starting subtest: basic-read [240.616178] Subtest basic-read: SUCCESS (0.026s) [240.716385] [145/171] (762s left) prime_vgem (basic-write) [240.862424] Starting subtest: basic-write [240.891961] Subtest basic-write: SUCCESS (0.029s) [240.969178] [146/171] (761s left) vgem_basic (setversion) [241.111344] Starting subtest: setversion [241.113564] Subtest setversion: SUCCESS (0.000s) [241.197759] [147/171] (761s left) vgem_basic (create) [241.335311] Starting subtest: create [241.336240] Subtest create: SUCCESS (0.000s) [241.401185] [148/171] (761s left) vgem_basic (debugfs) [241.540704] Starting subtest: debugfs [241.541891] Subtest debugfs: SUCCESS (0.001s) [241.607180] [149/171] (761s left) vgem_basic (dmabuf-export) [241.746225] Starting subtest: dmabuf-export [241.748353] Subtest dmabuf-export: SUCCESS (0.003s) [241.863422] [150/171] (761s left) vgem_basic (dmabuf-fence) [241.998097] Starting subtest: dmabuf-fence [241.999053] Subtest dmabuf-fence: SUCCESS (0.001s) [242.063916] [151/171] (760s left) vgem_basic (dmabuf-fence-before) [242.204707] Starting subtest: dmabuf-fence-before [242.206285] Subtest dmabuf-fence-before: SUCCESS (0.001s) [242.272350] [152/171] (760s left) vgem_basic (dmabuf-mmap) [242.408895] Starting subtest: dmabuf-mmap [242.427331] Subtest dmabuf-mmap: SUCCESS (0.019s) [242.484761] [153/171] (760s left) vgem_basic (mmap) [242.634450] Starting subtest: mmap [242.646732] Subtest mmap: SUCCESS (0.014s) [242.712493] [154/171] (760s left) vgem_basic (second-client) [242.855041] Starting subtest: second-client [242.887868] Subtest second-client: SUCCESS (0.032s) [242.948549] [155/171] (760s left) vgem_basic (sysfs) [243.093948] Starting subtest: sysfs [243.095077] Subtest sysfs: SUCCESS (0.001s) [243.154344] [156/171] (759s left) core_hotunplug (unbind-rebind) [243.319988] Starting subtest: unbind-rebind [246.647103] Subtest unbind-rebind: SUCCESS (3.326s) [246.697328] [157/171] (756s left) vgem_basic (unload) [246.822493] Starting subtest: unload [275.884374] Subtest unload: SUCCESS (29.025s) [275.984446] [158/171] (727s left) i915_module_load (reload) [276.090071] Starting subtest: reload [279.408060] Subtest reload: SUCCESS (3.312s) [279.434075] [159/171] (723s left) gem_lmem_swapping (basic) [279.650599] Subtest basic: SKIP (0.000s) [279.689966] [160/171] (723s left) gem_lmem_swapping (parallel-random-engines) [279.803322] Subtest parallel-random-engines: SKIP (0.000s) [279.839846] [161/171] (723s left) gem_lmem_swapping (random-engines) [279.950019] Subtest random-engines: SKIP (0.000s) [279.989727] [162/171] (723s left) gem_lmem_swapping (verify-random) [280.100649] Subtest verify-random: SKIP (0.000s) [280.139930] [163/171] (723s left) i915_pm_rpm (module-reload) [282.793340] Starting subtest: module-reload [300.762358] Subtest module-reload: SUCCESS (17.960s) [300.806600] [164/171] (702s left) i915_selftest (live) [300.926586] Starting subtest: live [300.929710] Starting dynamic subtest: sanitycheck [302.626696] Dynamic subtest sanitycheck: SUCCESS (1.690s) [302.633910] Starting dynamic subtest: uncore [304.293803] Dynamic subtest uncore: SUCCESS (1.666s) [304.301966] Starting dynamic subtest: workarounds [306.105848] Dynamic subtest workarounds: SUCCESS (1.811s) [306.114072] Starting dynamic subtest: gt_engines [308.199862] Dynamic subtest gt_engines: SUCCESS (2.093s) [308.207791] Starting dynamic subtest: gt_timelines [319.179790] Dynamic subtest gt_timelines: SUCCESS (10.979s) [319.187272] Starting dynamic subtest: gt_contexts [320.940037] Dynamic subtest gt_contexts: SUCCESS (1.759s) [320.948854] Starting dynamic subtest: gt_lrc [325.212703] Dynamic subtest gt_lrc: SUCCESS (4.272s) [325.223859] Starting dynamic subtest: gt_mocs [326.956923] Dynamic subtest gt_mocs: SUCCESS (1.743s) [326.968486] Starting dynamic subtest: gt_pm [332.871804] Dynamic subtest gt_pm: SUCCESS (5.914s) [332.879333] Starting dynamic subtest: gt_heartbeat [334.644534] Dynamic subtest gt_heartbeat: SUCCESS (1.772s) [334.654710] Starting dynamic subtest: gt_tlb [337.225812] Dynamic subtest gt_tlb: SUCCESS (2.580s) [337.237340] Starting dynamic subtest: requests [344.607843] Dynamic subtest requests: SUCCESS (7.380s) [344.615355] Starting dynamic subtest: migrate [349.007734] Dynamic subtest migrate: SUCCESS (4.399s) [349.015280] Starting dynamic subtest: active [350.690706] Dynamic subtest active: SUCCESS (1.682s) [350.698889] Starting dynamic subtest: objects [353.519701] Dynamic subtest objects: SUCCESS (2.828s) [353.528257] Starting dynamic subtest: mman [358.644946] Dynamic subtest mman: SUCCESS (5.124s) [358.653851] Starting dynamic subtest: dmabuf [360.372511] Dynamic subtest dmabuf: SUCCESS (1.727s) [360.379916] Starting dynamic subtest: vma [362.027797] Dynamic subtest vma: SUCCESS (1.654s) [362.035618] Starting dynamic subtest: coherency [364.908900] Dynamic subtest coherency: SUCCESS (2.880s) [364.921372] Starting dynamic subtest: gtt [373.339778] Dynamic subtest gtt: SUCCESS (8.430s) [373.351079] Starting dynamic subtest: gem [375.274928] Dynamic subtest gem: SUCCESS (1.934s) [375.283725] Starting dynamic subtest: evict [378.535722] Dynamic subtest evict: SUCCESS (3.260s) [378.543433] Starting dynamic subtest: hugepages [384.604769] Dynamic subtest hugepages: SUCCESS (6.068s) [384.612340] Starting dynamic subtest: gem_contexts [399.139699] Dynamic subtest gem_contexts: SUCCESS (14.534s) [399.147348] Starting dynamic subtest: client [400.878957] Dynamic subtest client: SUCCESS (1.738s) [400.891105] Starting dynamic subtest: gem_migrate [402.543724] Dynamic subtest gem_migrate: SUCCESS (1.664s) [402.555101] Starting dynamic subtest: reset [404.903852] Dynamic subtest reset: SUCCESS (2.359s) [404.915500] Starting dynamic subtest: memory_region [406.569586] Dynamic subtest memory_region: SUCCESS (1.665s) [406.577226] Starting dynamic subtest: hangcheck [430.694051] Dynamic subtest hangcheck: SUCCESS (24.123s) [430.703849] Starting dynamic subtest: execlists [440.172694] Dynamic subtest execlists: SUCCESS (9.478s) [440.183095] Starting dynamic subtest: ring_submission [441.847476] Dynamic subtest ring_submission: SUCCESS (1.682s) [441.857210] Starting dynamic subtest: perf [443.831711] Dynamic subtest perf: SUCCESS (1.975s) [443.839359] Starting dynamic subtest: slpc [445.477766] Dynamic subtest slpc: SUCCESS (1.645s) [445.485469] Starting dynamic subtest: guc [447.144881] Dynamic subtest guc: SUCCESS (1.666s) [447.153613] Starting dynamic subtest: guc_multi_lrc [448.805169] Dynamic subtest guc_multi_lrc: SUCCESS (1.659s) [448.816488] Starting dynamic subtest: guc_hang [450.465773] Dynamic subtest guc_hang: SUCCESS (1.660s) [450.473430] Starting dynamic subtest: late_gt_pm [452.148587] Dynamic subtest late_gt_pm: SUCCESS (1.682s) [452.149761] Subtest live: SUCCESS (151.213s) [452.383374] [165/171] (550s left) dmabuf (all-tests) [452.499504] Starting subtest: all-tests [452.501196] Starting dynamic subtest: sanitycheck [452.574697] Dynamic subtest sanitycheck: SUCCESS (0.073s) [452.606974] Starting dynamic subtest: dma_fence [452.771527] Dynamic subtest dma_fence: SUCCESS (0.194s) [452.783220] Starting dynamic subtest: dma_fence_chain [468.604307] Dynamic subtest dma_fence_chain: SUCCESS (15.819s) [468.632452] Starting dynamic subtest: dma_fence_unwrap [468.676813] Dynamic subtest dma_fence_unwrap: SUCCESS (0.079s) [468.690923] Starting dynamic subtest: dma_resv [468.759847] Dynamic subtest dma_resv: SUCCESS (0.081s) [468.761442] Subtest all-tests: SUCCESS (16.260s) [468.802736] [166/171] (534s left) i915_suspend (basic-s2idle-without-i915) [468.922889] Starting subtest: basic-s2idle-without-i915 [488.076631] Subtest basic-s2idle-without-i915: SUCCESS (4.227s) [490.460894] [167/171] (529s left) i915_suspend (basic-s3-without-i915) [490.572886] Starting subtest: basic-s3-without-i915 [511.341282] Subtest basic-s3-without-i915: SUCCESS (4.753s) [513.607253] [168/171] (524s left) gem_exec_suspend (basic-s0) [513.736689] Starting subtest: basic-S0 [513.737804] Starting dynamic subtest: smem [532.224720] Dynamic subtest smem: SUCCESS (3.436s) [532.225732] Subtest basic-S0: SUCCESS (3.437s) [534.497393] [169/171] (521s left) gem_exec_suspend (basic-s3) [534.656540] Starting subtest: basic-S3 [534.657734] Starting dynamic subtest: smem [555.145097] Dynamic subtest smem: SUCCESS (4.396s) [555.146204] Subtest basic-S3: SUCCESS (4.397s) [557.345108] [170/171] (516s left) kms_chamelium_hpd (common-hpd-after-suspend) [557.802781] Subtest common-hpd-after-suspend: SKIP (0.000s) [558.682411] [171/171] (515s left) kms_pipe_crc_basic (suspend-read-crc) [558.830143] Starting subtest: suspend-read-crc [558.833605] Starting dynamic subtest: pipe-A-eDP-1 [579.404082] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.566s) [579.421473] Starting dynamic subtest: pipe-B-eDP-1 [610.239781] Dynamic subtest pipe-B-eDP-1: SUCCESS (14.208s) [610.254548] Starting dynamic subtest: pipe-C-eDP-1 [641.469793] Dynamic subtest pipe-C-eDP-1: SUCCESS (14.367s) [641.470926] Subtest suspend-read-crc: SUCCESS (32.154s) [642.352644] Closing watchdogs Done.