[39.400825] Initializing watchdogs [39.400945] /dev/watchdog0 [39.407801] [001/171] (960s left) i915_module_load (load) [39.511736] Starting subtest: load [40.598570] Subtest load: SUCCESS (1.087s) [40.629362] [002/171] (959s left) core_auth (basic-auth) [40.770560] Starting subtest: basic-auth [40.775171] Subtest basic-auth: SUCCESS (0.001s) [40.859782] [003/171] (959s left) debugfs_test (read_all_entries) [41.040719] Starting subtest: read_all_entries [41.114069] Subtest read_all_entries: SUCCESS (0.073s) [41.217650] [004/171] (958s left) debugfs_test (basic-hwmon) [41.321800] Starting subtest: basic-hwmon [41.323801] Subtest basic-hwmon: SKIP (0.000s) [41.437100] [005/171] (958s left) fbdev (eof) [41.549229] Starting subtest: eof [41.550229] Subtest eof: SUCCESS (0.001s) [41.601866] [006/171] (958s left) fbdev (info) [41.699051] Starting subtest: info [41.700089] Subtest info: SUCCESS (0.000s) [41.770332] [007/171] (958s left) fbdev (nullptr) [41.881106] Starting subtest: nullptr [41.882132] Subtest nullptr: SUCCESS (0.000s) [41.934843] [008/171] (958s left) fbdev (read) [42.047893] Starting subtest: read [42.894969] Subtest read: SUCCESS (0.838s) [42.937599] [009/171] (957s left) fbdev (write) [43.048824] Starting subtest: write [43.475661] Subtest write: SUCCESS (0.418s) [43.519592] [010/171] (956s left) gem_basic (bad-close) [43.618453] Starting subtest: bad-close [43.620491] Subtest bad-close: SUCCESS (0.000s) [43.691585] [011/171] (956s left) gem_basic (create-close) [43.796517] Starting subtest: create-close [43.798526] Subtest create-close: SUCCESS (0.000s) [43.913777] [012/171] (956s left) gem_basic (create-fd-close) [44.015656] Starting subtest: create-fd-close [44.017383] Subtest create-fd-close: SUCCESS (0.000s) [44.117211] [013/171] (956s left) gem_busy (busy@all-engines) [44.243463] Starting subtest: busy [44.244579] Starting dynamic subtest: all-engines [44.308330] Dynamic subtest all-engines: SUCCESS (0.065s) [44.309352] Subtest busy: SUCCESS (0.065s) [44.744725] [014/171] (955s left) gem_close_race (basic-process) [44.867314] Starting subtest: basic-process [44.935437] Subtest basic-process: SUCCESS (0.068s) [45.155106] [015/171] (955s left) gem_close_race (basic-threads) [45.278287] Starting subtest: basic-threads [46.452174] Subtest basic-threads: SUCCESS (1.174s) [46.716650] [016/171] (953s left) gem_ctx_create (basic) [46.843176] Starting subtest: basic [46.844257] Subtest basic: SUCCESS (0.000s) [47.048927] [017/171] (953s left) gem_ctx_create (basic-files) [47.165793] Starting subtest: basic-files [49.189894] Subtest basic-files: SUCCESS (2.024s) [49.517086] [018/171] (950s left) gem_ctx_exec (basic) [49.639482] Starting subtest: basic [49.643737] Subtest basic: SUCCESS (0.004s) [49.762889] [019/171] (950s left) gem_exec_basic (basic) [49.877203] Starting subtest: basic [49.878451] Starting dynamic subtest: rcs0-smem [49.880840] Dynamic subtest rcs0-smem: SUCCESS (0.003s) [49.891068] Starting dynamic subtest: bcs0-smem [49.892148] Dynamic subtest bcs0-smem: SUCCESS (0.001s) [49.899660] Starting dynamic subtest: vcs0-smem [49.900685] Dynamic subtest vcs0-smem: SUCCESS (0.000s) [49.907530] Starting dynamic subtest: vecs0-smem [49.908554] Dynamic subtest vecs0-smem: SUCCESS (0.000s) [49.909584] Subtest basic: SUCCESS (0.006s) [50.107397] [020/171] (950s left) gem_exec_create (basic) [50.228527] Starting subtest: basic [50.229491] Starting dynamic subtest: smem [52.269585] Dynamic subtest smem: SUCCESS (2.041s) [52.270666] Subtest basic: SUCCESS (2.041s) [52.493559] [021/171] (947s left) gem_exec_fence (basic-busy) [52.768447] Starting subtest: basic-busy [52.769867] Starting dynamic subtest: rcs0 [52.793356] Dynamic subtest rcs0: SUCCESS (0.026s) [52.801487] Starting dynamic subtest: bcs0 [52.820407] Dynamic subtest bcs0: SUCCESS (0.027s) [52.828588] Starting dynamic subtest: vcs0 [52.846319] Dynamic subtest vcs0: SUCCESS (0.026s) [52.855125] Starting dynamic subtest: vecs0 [52.867413] Dynamic subtest vecs0: SUCCESS (0.021s) [52.868426] Subtest basic-busy: SUCCESS (0.100s) [53.570206] [022/171] (946s left) gem_exec_fence (basic-wait) [53.840220] Starting subtest: basic-wait [53.841468] Starting dynamic subtest: rcs0 [53.882646] Dynamic subtest rcs0: SUCCESS (0.042s) [53.892775] Starting dynamic subtest: bcs0 [53.910532] Dynamic subtest bcs0: SUCCESS (0.028s) [53.920505] Starting dynamic subtest: vcs0 [53.943340] Dynamic subtest vcs0: SUCCESS (0.033s) [53.953321] Starting dynamic subtest: vecs0 [53.971428] Dynamic subtest vecs0: SUCCESS (0.028s) [53.972495] Subtest basic-wait: SUCCESS (0.132s) [54.633078] [023/171] (945s left) gem_exec_fence (basic-await) [54.909824] Starting subtest: basic-await [54.910976] Starting dynamic subtest: rcs0 [55.010309] Dynamic subtest rcs0: SUCCESS (0.100s) [55.021391] Starting dynamic subtest: bcs0 [55.114304] Dynamic subtest bcs0: SUCCESS (0.103s) [55.122973] Starting dynamic subtest: vcs0 [55.213708] Dynamic subtest vcs0: SUCCESS (0.099s) [55.223224] Starting dynamic subtest: vecs0 [55.312945] Dynamic subtest vecs0: SUCCESS (0.099s) [55.314103] Subtest basic-await: SUCCESS (0.403s) [56.073796] [024/171] (944s left) gem_exec_fence (nb-await) [56.341081] Starting subtest: nb-await [56.342391] Starting dynamic subtest: rcs0 [56.407099] Dynamic subtest rcs0: SUCCESS (0.066s) [56.421891] Starting dynamic subtest: bcs0 [56.483475] Dynamic subtest bcs0: SUCCESS (0.071s) [56.517451] Starting dynamic subtest: vcs0 [56.573576] Dynamic subtest vcs0: SUCCESS (0.091s) [56.627385] Starting dynamic subtest: vecs0 [56.692803] Dynamic subtest vecs0: SUCCESS (0.116s) [56.696849] Subtest nb-await: SUCCESS (0.350s) [57.541083] [025/171] (942s left) gem_exec_gttfill (basic) [57.671100] Starting subtest: basic [67.145162] Subtest basic: SUCCESS (9.466s) [67.331835] [026/171] (933s left) gem_exec_parallel (engines) [67.514842] Starting subtest: engines [67.515995] Starting dynamic subtest: basic [69.847519] Dynamic subtest basic: SUCCESS (2.332s) [69.857929] Starting dynamic subtest: contexts [72.968248] Dynamic subtest contexts: SUCCESS (3.112s) [72.976801] Starting dynamic subtest: fds [76.215415] Dynamic subtest fds: SUCCESS (3.256s) [76.227223] Starting dynamic subtest: userptr [78.563532] Dynamic subtest userptr: SUCCESS (2.348s) [78.564670] Subtest engines: SUCCESS (11.049s) [78.793985] [027/171] (921s left) gem_exec_store (basic) [78.919062] Starting subtest: basic [78.971423] Subtest basic: SUCCESS (0.053s) [79.190974] [028/171] (921s left) gem_flink_basic (bad-flink) [79.297320] Starting subtest: bad-flink [79.298457] Subtest bad-flink: SUCCESS (0.000s) [79.397937] [029/171] (921s left) gem_flink_basic (bad-open) [79.504532] Starting subtest: bad-open [79.505678] Subtest bad-open: SUCCESS (0.000s) [79.601559] [030/171] (920s left) gem_flink_basic (basic) [79.708368] Starting subtest: basic [79.709471] Subtest basic: SUCCESS (0.000s) [79.805878] [031/171] (920s left) gem_flink_basic (double-flink) [79.913332] Starting subtest: double-flink [79.914572] Subtest double-flink: SUCCESS (0.000s) [80.018967] [032/171] (920s left) gem_flink_basic (flink-lifetime) [80.125139] Starting subtest: flink-lifetime [80.127341] Subtest flink-lifetime: SUCCESS (0.002s) [80.225165] [033/171] (920s left) gem_huc_copy (huc-copy) [80.339014] Starting subtest: huc-copy [80.341116] Subtest huc-copy: SKIP (0.000s) [80.486098] [034/171] (920s left) gem_linear_blits (basic) [80.714610] Starting subtest: basic [80.726101] Subtest basic: SUCCESS (0.011s) [80.882206] [035/171] (919s left) gem_mmap (basic) [81.051164] Starting subtest: basic [81.056196] Subtest basic: SUCCESS (0.000s) [81.130019] [036/171] (919s left) gem_mmap_gtt (basic) [81.239147] Starting subtest: basic [81.240231] Subtest basic: SUCCESS (0.001s) [81.338890] [037/171] (919s left) gem_render_linear_blits (basic) [81.454032] Starting subtest: basic [81.495527] Subtest basic: SUCCESS (0.041s) [81.535694] [038/171] (919s left) gem_render_tiled_blits (basic) [81.651345] Starting subtest: basic [81.698130] Subtest basic: SUCCESS (0.044s) [81.752719] [039/171] (918s left) gem_ringfill (basic-all) [82.051647] Starting subtest: basic-all [83.221742] Subtest basic-all: SUCCESS (1.162s) [83.320818] [040/171] (917s left) gem_softpin (allocator-basic) [83.446613] Starting subtest: allocator-basic [83.586782] Subtest allocator-basic: SUCCESS (0.132s) [83.693853] [041/171] (917s left) gem_softpin (allocator-basic-reserve) [83.810483] Starting subtest: allocator-basic-reserve [83.957885] Subtest allocator-basic-reserve: SUCCESS (0.139s) [84.079205] [042/171] (916s left) gem_softpin (safe-alignment) [84.189580] Starting subtest: safe-alignment [84.205211] Subtest safe-alignment: SUCCESS (0.015s) [84.347620] [043/171] (916s left) gem_sync (basic-all) [84.474223] Starting subtest: basic-all [86.731378] Subtest basic-all: SUCCESS (2.257s) [86.943629] [044/171] (913s left) gem_sync (basic-each) [87.066344] Starting subtest: basic-each [89.195435] Subtest basic-each: SUCCESS (2.129s) [89.518261] [045/171] (911s left) gem_tiled_blits (basic) [89.775318] Starting subtest: basic [89.801966] Subtest basic: SUCCESS (0.027s) [89.949566] [046/171] (910s left) gem_tiled_fence_blits (basic) [90.238522] Starting subtest: basic [90.251891] Subtest basic: SUCCESS (0.013s) [90.407498] [047/171] (910s left) gem_tiled_pread_basic [90.787812] [048/171] (910s left) gem_wait (busy@all-engines) [90.919563] Starting subtest: busy [90.920727] Starting dynamic subtest: all-engines [91.459299] Dynamic subtest all-engines: SUCCESS (0.540s) [91.460383] Subtest busy: SUCCESS (0.540s) [91.723192] [049/171] (909s left) gem_wait (wait@all-engines) [91.852593] Starting subtest: wait [91.853809] Starting dynamic subtest: all-engines [92.911708] Dynamic subtest all-engines: SUCCESS (1.057s) [92.915212] Subtest wait: SUCCESS (1.058s) [93.196082] [050/171] (907s left) i915_getparams_basic (basic-eu-total) [93.303206] Starting subtest: basic-eu-total [93.305246] Subtest basic-eu-total: SUCCESS (0.000s) [93.395674] [051/171] (907s left) i915_getparams_basic (basic-subslice-total) [93.504340] Starting subtest: basic-subslice-total [93.506177] Subtest basic-subslice-total: SUCCESS (0.000s) [93.600958] [052/171] (907s left) i915_hangman (error-state-basic) [93.726370] Starting subtest: error-state-basic [93.737299] Subtest error-state-basic: SUCCESS (0.011s) [93.832222] [053/171] (907s left) i915_pciid [94.032799] [054/171] (907s left) kms_addfb_basic (addfb25-bad-modifier) [94.143994] Starting subtest: addfb25-bad-modifier [94.145052] Subtest addfb25-bad-modifier: SUCCESS (0.000s) [94.308226] [055/171] (906s left) kms_addfb_basic (addfb25-framebuffer-vs-set-tiling) [94.417509] Starting subtest: addfb25-framebuffer-vs-set-tiling [94.418570] Subtest addfb25-framebuffer-vs-set-tiling: SUCCESS (0.000s) [94.559795] [056/171] (906s left) kms_addfb_basic (addfb25-modifier-no-flag) [94.668609] Starting subtest: addfb25-modifier-no-flag [94.669739] Subtest addfb25-modifier-no-flag: SUCCESS (0.000s) [94.802517] [057/171] (906s left) kms_addfb_basic (addfb25-x-tiled-legacy) [94.908339] Starting subtest: addfb25-x-tiled-legacy [94.909361] Subtest addfb25-x-tiled-legacy: SUCCESS (0.000s) [95.045711] [058/171] (906s left) kms_addfb_basic (addfb25-x-tiled-mismatch-legacy) [95.151623] Starting subtest: addfb25-x-tiled-mismatch-legacy [95.152928] Subtest addfb25-x-tiled-mismatch-legacy: SUCCESS (0.000s) [95.285763] [059/171] (905s left) kms_addfb_basic (addfb25-yf-tiled-legacy) [95.433375] Starting subtest: addfb25-yf-tiled-legacy [95.434340] Subtest addfb25-yf-tiled-legacy: SUCCESS (0.000s) [95.531235] [060/171] (905s left) kms_addfb_basic (addfb25-y-tiled-legacy) [95.675195] Starting subtest: addfb25-y-tiled-legacy [95.676100] Subtest addfb25-y-tiled-legacy: SUCCESS (0.000s) [95.785397] [061/171] (905s left) kms_addfb_basic (addfb25-y-tiled-small-legacy) [95.927079] Starting subtest: addfb25-y-tiled-small-legacy [95.928132] Subtest addfb25-y-tiled-small-legacy: SUCCESS (0.000s) [96.031491] [062/171] (905s left) kms_addfb_basic (bad-pitch-0) [96.131152] Starting subtest: bad-pitch-0 [96.132171] Subtest bad-pitch-0: SUCCESS (0.000s) [96.263976] [063/171] (905s left) kms_addfb_basic (bad-pitch-1024) [96.363960] Starting subtest: bad-pitch-1024 [96.364971] Subtest bad-pitch-1024: SUCCESS (0.000s) [96.508980] [064/171] (904s left) kms_addfb_basic (bad-pitch-128) [96.607662] Starting subtest: bad-pitch-128 [96.608754] Subtest bad-pitch-128: SUCCESS (0.000s) [96.737381] [065/171] (904s left) kms_addfb_basic (bad-pitch-256) [96.837187] Starting subtest: bad-pitch-256 [96.838059] Subtest bad-pitch-256: SUCCESS (0.000s) [96.986737] [066/171] (904s left) kms_addfb_basic (bad-pitch-32) [97.084533] Starting subtest: bad-pitch-32 [97.085597] Subtest bad-pitch-32: SUCCESS (0.000s) [97.217725] [067/171] (904s left) kms_addfb_basic (bad-pitch-63) [97.318247] Starting subtest: bad-pitch-63 [97.319243] Subtest bad-pitch-63: SUCCESS (0.000s) [97.458840] [068/171] (903s left) kms_addfb_basic (bad-pitch-65536) [97.556267] Starting subtest: bad-pitch-65536 [97.557259] Subtest bad-pitch-65536: SUCCESS (0.000s) [97.699746] [069/171] (903s left) kms_addfb_basic (bad-pitch-999) [97.799486] Starting subtest: bad-pitch-999 [97.800402] Subtest bad-pitch-999: SUCCESS (0.000s) [97.943197] [070/171] (903s left) kms_addfb_basic (basic) [98.039191] Starting subtest: basic [98.040196] Subtest basic: SUCCESS (0.000s) [98.166538] [071/171] (903s left) kms_addfb_basic (basic-x-tiled-legacy) [98.265607] Starting subtest: basic-x-tiled-legacy [98.266602] Subtest basic-x-tiled-legacy: SUCCESS (0.000s) [98.410728] [072/171] (903s left) kms_addfb_basic (basic-y-tiled-legacy) [98.510607] Starting subtest: basic-y-tiled-legacy [98.511510] Subtest basic-y-tiled-legacy: SUCCESS (0.000s) [98.649423] [073/171] (902s left) kms_addfb_basic (bo-too-small) [98.782481] Starting subtest: bo-too-small [98.783566] Subtest bo-too-small: SUCCESS (0.000s) [98.878285] [074/171] (902s left) kms_addfb_basic (bo-too-small-due-to-tiling) [99.009310] Starting subtest: bo-too-small-due-to-tiling [99.010299] Subtest bo-too-small-due-to-tiling: SUCCESS (0.000s) [99.106702] [075/171] (902s left) kms_addfb_basic (clobberred-modifier) [99.203875] Starting subtest: clobberred-modifier [99.204971] Subtest clobberred-modifier: SUCCESS (0.000s) [99.342779] [076/171] (902s left) kms_addfb_basic (framebuffer-vs-set-tiling) [99.438560] Starting subtest: framebuffer-vs-set-tiling [99.439557] Subtest framebuffer-vs-set-tiling: SUCCESS (0.000s) [99.567277] [077/171] (901s left) kms_addfb_basic (invalid-get-prop) [99.664305] Starting subtest: invalid-get-prop [99.665306] Subtest invalid-get-prop: SUCCESS (0.000s) [99.805845] [078/171] (901s left) kms_addfb_basic (invalid-get-prop-any) [99.903265] Starting subtest: invalid-get-prop-any [99.904245] Subtest invalid-get-prop-any: SUCCESS (0.000s) [100.045582] [079/171] (901s left) kms_addfb_basic (invalid-set-prop) [100.146123] Starting subtest: invalid-set-prop [100.147122] Subtest invalid-set-prop: SUCCESS (0.000s) [100.285175] [080/171] (901s left) kms_addfb_basic (invalid-set-prop-any) [100.383500] Starting subtest: invalid-set-prop-any [100.384483] Subtest invalid-set-prop-any: SUCCESS (0.000s) [100.513430] [081/171] (901s left) kms_addfb_basic (no-handle) [100.611363] Starting subtest: no-handle [100.612432] Subtest no-handle: SUCCESS (0.000s) [100.739299] [082/171] (900s left) kms_addfb_basic (size-max) [100.878083] Starting subtest: size-max [100.879150] Subtest size-max: SUCCESS (0.000s) [100.984766] [083/171] (900s left) kms_addfb_basic (small-bo) [101.114168] Starting subtest: small-bo [101.115203] Subtest small-bo: SUCCESS (0.000s) [101.207045] [084/171] (900s left) kms_addfb_basic (tile-pitch-mismatch) [101.304780] Starting subtest: tile-pitch-mismatch [101.305837] Subtest tile-pitch-mismatch: SUCCESS (0.000s) [101.444203] [085/171] (900s left) kms_addfb_basic (too-high) [101.574508] Starting subtest: too-high [101.575614] Subtest too-high: SUCCESS (0.001s) [101.666936] [086/171] (900s left) kms_addfb_basic (too-wide) [101.803175] Starting subtest: too-wide [101.804078] Subtest too-wide: SUCCESS (0.000s) [101.902226] [087/171] (899s left) kms_addfb_basic (unused-handle) [101.999324] Starting subtest: unused-handle [102.000328] Subtest unused-handle: SUCCESS (0.000s) [102.134664] [088/171] (899s left) kms_addfb_basic (unused-modifier) [102.233449] Starting subtest: unused-modifier [102.234513] Subtest unused-modifier: SUCCESS (0.000s) [102.370183] [089/171] (899s left) kms_addfb_basic (unused-offsets) [102.465926] Starting subtest: unused-offsets [102.466933] Subtest unused-offsets: SUCCESS (0.000s) [102.608172] [090/171] (899s left) kms_addfb_basic (unused-pitches) [102.706113] Starting subtest: unused-pitches [102.707102] Subtest unused-pitches: SUCCESS (0.000s) [102.834948] [091/171] (898s left) kms_busy (basic) [102.972846] Starting subtest: basic [102.974814] Starting dynamic subtest: flip [103.979116] Dynamic subtest flip: SUCCESS (1.003s) [103.995459] Starting dynamic subtest: modeset [107.833162] Dynamic subtest modeset: SUCCESS (3.851s) [107.834973] Subtest basic: SUCCESS (4.857s) [108.737366] [092/171] (893s left) kms_chamelium_hpd (dp-hpd-fast) [109.183463] Subtest dp-hpd-fast: SKIP (0.000s) [110.098965] [093/171] (891s left) kms_chamelium_edid (dp-edid-read) [110.531842] Subtest dp-edid-read: SKIP (0.000s) [111.447787] [094/171] (890s left) kms_chamelium_frames (dp-crc-fast) [111.896628] Subtest dp-crc-fast: SKIP (0.000s) [112.789136] [095/171] (889s left) kms_chamelium_hpd (hdmi-hpd-fast) [113.220166] Subtest hdmi-hpd-fast: SKIP (0.000s) [114.133148] [096/171] (887s left) kms_chamelium_edid (hdmi-edid-read) [114.580782] Subtest hdmi-edid-read: SKIP (0.000s) [115.476810] [097/171] (886s left) kms_chamelium_frames (hdmi-crc-fast) [115.921883] Subtest hdmi-crc-fast: SKIP (0.000s) [116.824623] [098/171] (885s left) kms_chamelium_hpd (vga-hpd-fast) [117.270383] Subtest vga-hpd-fast: SKIP (0.000s) [118.162574] [099/171] (883s left) kms_chamelium_edid (vga-edid-read) [118.610485] Subtest vga-edid-read: SKIP (0.000s) [119.505786] [100/171] (882s left) kms_prop_blob (basic) [119.616357] Starting subtest: basic [119.617402] Subtest basic: SUCCESS (0.000s) [119.685252] [101/171] (882s left) kms_cursor_legacy (basic-busy-flip-before-cursor-atomic) [119.830793] Starting subtest: basic-busy-flip-before-cursor-atomic [119.832922] Subtest basic-busy-flip-before-cursor-atomic: SKIP (0.000s) [119.917024] [102/171] (882s left) kms_cursor_legacy (basic-busy-flip-before-cursor-legacy) [120.063283] Starting subtest: basic-busy-flip-before-cursor-legacy [120.065339] Subtest basic-busy-flip-before-cursor-legacy: SKIP (0.000s) [120.146176] [103/171] (881s left) kms_cursor_legacy (basic-flip-after-cursor-atomic) [120.289368] Starting subtest: basic-flip-after-cursor-atomic [121.554122] Subtest basic-flip-after-cursor-atomic: SUCCESS (1.259s) [122.496078] [104/171] (879s left) kms_cursor_legacy (basic-flip-after-cursor-legacy) [122.638365] Starting subtest: basic-flip-after-cursor-legacy [123.901517] Subtest basic-flip-after-cursor-legacy: SUCCESS (1.263s) [124.862510] [105/171] (877s left) kms_cursor_legacy (basic-flip-after-cursor-varying-size) [125.007582] Starting subtest: basic-flip-after-cursor-varying-size [126.269469] Subtest basic-flip-after-cursor-varying-size: SUCCESS (1.261s) [127.238375] [106/171] (874s left) kms_cursor_legacy (basic-flip-before-cursor-atomic) [127.382144] Starting subtest: basic-flip-before-cursor-atomic [128.665193] Subtest basic-flip-before-cursor-atomic: SUCCESS (1.280s) [129.610338] [107/171] (872s left) kms_cursor_legacy (basic-flip-before-cursor-legacy) [129.755539] Starting subtest: basic-flip-before-cursor-legacy [131.013744] Subtest basic-flip-before-cursor-legacy: SUCCESS (1.263s) [131.967054] [108/171] (870s left) kms_cursor_legacy (basic-flip-before-cursor-varying-size) [132.113188] Starting subtest: basic-flip-before-cursor-varying-size [133.368603] Subtest basic-flip-before-cursor-varying-size: SUCCESS (1.253s) [134.344869] [109/171] (867s left) kms_flip (basic-flip-vs-dpms) [134.466757] Starting subtest: basic-flip-vs-dpms [134.470841] Starting dynamic subtest: A-eDP1 [137.348074] Dynamic subtest A-eDP1: SUCCESS (2.869s) [137.361424] Starting dynamic subtest: B-eDP1 [139.700262] Dynamic subtest B-eDP1: SUCCESS (2.357s) [139.712585] Starting dynamic subtest: C-eDP1 [142.068335] Dynamic subtest C-eDP1: SUCCESS (2.366s) [142.071696] Subtest basic-flip-vs-dpms: SUCCESS (7.605s) [142.976863] [110/171] (859s left) kms_flip (basic-flip-vs-modeset) [143.099129] Starting subtest: basic-flip-vs-modeset [143.103605] Starting dynamic subtest: A-eDP1 [145.898603] Dynamic subtest A-eDP1: SUCCESS (2.795s) [145.915283] Starting dynamic subtest: B-eDP1 [148.268798] Dynamic subtest B-eDP1: SUCCESS (2.367s) [148.279527] Starting dynamic subtest: C-eDP1 [150.645177] Dynamic subtest C-eDP1: SUCCESS (2.375s) [150.655276] Subtest basic-flip-vs-modeset: SUCCESS (7.556s) [151.546424] [111/171] (850s left) kms_flip (basic-flip-vs-wf_vblank) [151.667631] Starting subtest: basic-flip-vs-wf_vblank [151.671952] Starting dynamic subtest: A-eDP1 [153.947612] Dynamic subtest A-eDP1: SUCCESS (2.276s) [153.961801] Starting dynamic subtest: B-eDP1 [156.253149] Dynamic subtest B-eDP1: SUCCESS (2.300s) [156.264350] Starting dynamic subtest: C-eDP1 [158.560080] Dynamic subtest C-eDP1: SUCCESS (2.308s) [158.568058] Subtest basic-flip-vs-wf_vblank: SUCCESS (6.897s) [159.873296] [112/171] (842s left) kms_flip (basic-plain-flip) [159.996166] Starting subtest: basic-plain-flip [160.001417] Starting dynamic subtest: A-eDP1 [162.062805] Dynamic subtest A-eDP1: SUCCESS (2.051s) [162.087208] Starting dynamic subtest: B-eDP1 [164.037723] Dynamic subtest B-eDP1: SUCCESS (1.967s) [164.062538] Starting dynamic subtest: C-eDP1 [166.015300] Dynamic subtest C-eDP1: SUCCESS (1.972s) [166.019634] Subtest basic-plain-flip: SUCCESS (6.024s) [167.293040] [113/171] (835s left) kms_force_connector_basic (force-connector-state) [167.430292] Starting subtest: force-connector-state [168.066539] Subtest force-connector-state: SUCCESS (0.638s) [169.056676] [114/171] (833s left) kms_force_connector_basic (force-edid) [169.185542] Starting subtest: force-edid [169.219827] Subtest force-edid: SUCCESS (0.035s) [169.397223] [115/171] (832s left) kms_force_connector_basic (force-load-detect) [169.525765] Starting subtest: force-load-detect [169.527561] Subtest force-load-detect: SKIP (0.000s) [169.684176] [116/171] (832s left) kms_force_connector_basic (prune-stale-modes) [169.814557] Starting subtest: prune-stale-modes [169.845151] Subtest prune-stale-modes: SUCCESS (0.032s) [170.042951] [117/171] (832s left) kms_frontbuffer_tracking (basic) [170.186666] Starting subtest: basic [173.032588] Subtest basic: SUCCESS (2.846s) [173.150256] [118/171] (829s left) kms_pipe_crc_basic (compare-crc-sanitycheck-xr24) [173.296961] Starting subtest: compare-crc-sanitycheck-xr24 [173.298065] Starting dynamic subtest: pipe-A-eDP-1 [173.925355] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.627s) [173.943802] Starting dynamic subtest: pipe-B-eDP-1 [175.245168] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.317s) [175.256961] Starting dynamic subtest: pipe-C-eDP-1 [176.564349] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.315s) [176.565301] Subtest compare-crc-sanitycheck-xr24: SUCCESS (3.268s) [177.525828] [119/171] (824s left) kms_pipe_crc_basic (compare-crc-sanitycheck-nv12) [177.664956] Starting subtest: compare-crc-sanitycheck-nv12 [177.666082] Starting dynamic subtest: pipe-A-eDP-1 [177.667970] Dynamic subtest pipe-A-eDP-1: SKIP (0.001s) [177.678530] Starting dynamic subtest: pipe-B-eDP-1 [177.680406] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s) [177.688730] Starting dynamic subtest: pipe-C-eDP-1 [177.690651] Dynamic subtest pipe-C-eDP-1: SKIP (0.001s) [177.692452] Subtest compare-crc-sanitycheck-nv12: SKIP (0.010s) [177.760542] [120/171] (824s left) kms_pipe_crc_basic (hang-read-crc) [177.895553] Starting subtest: hang-read-crc [177.898514] Starting dynamic subtest: pipe-A-eDP-1 [180.146067] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.249s) [180.158378] Starting dynamic subtest: pipe-B-eDP-1 [183.053389] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.902s) [183.067375] Starting dynamic subtest: pipe-C-eDP-1 [185.972966] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.917s) [185.974257] Subtest hang-read-crc: SUCCESS (8.079s) [186.938909] [121/171] (815s left) kms_pipe_crc_basic (nonblocking-crc) [187.082833] Starting subtest: nonblocking-crc [187.085599] Starting dynamic subtest: pipe-A-eDP-1 [187.888218] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.802s) [187.910941] Starting dynamic subtest: pipe-B-eDP-1 [189.438242] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.544s) [189.455966] Starting dynamic subtest: pipe-C-eDP-1 [190.961394] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.515s) [190.962708] Subtest nonblocking-crc: SUCCESS (3.879s) [191.871303] [122/171] (810s left) kms_pipe_crc_basic (nonblocking-crc-frame-sequence) [192.017093] Starting subtest: nonblocking-crc-frame-sequence [192.019967] Starting dynamic subtest: pipe-A-eDP-1 [192.835870] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.816s) [192.849141] Starting dynamic subtest: pipe-B-eDP-1 [194.369265] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.529s) [194.384173] Starting dynamic subtest: pipe-C-eDP-1 [195.901660] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.527s) [195.902759] Subtest nonblocking-crc-frame-sequence: SUCCESS (3.885s) [196.863638] [123/171] (805s left) kms_pipe_crc_basic (read-crc) [197.013045] Starting subtest: read-crc [197.016168] Starting dynamic subtest: pipe-A-eDP-1 [197.766686] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.750s) [197.784077] Starting dynamic subtest: pipe-B-eDP-1 [199.236206] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.465s) [199.248181] Starting dynamic subtest: pipe-C-eDP-1 [200.730091] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.488s) [200.731381] Subtest read-crc: SUCCESS (3.718s) [201.661967] [124/171] (800s left) kms_pipe_crc_basic (read-crc-frame-sequence) [201.809908] Starting subtest: read-crc-frame-sequence [201.812647] Starting dynamic subtest: pipe-A-eDP-1 [202.529586] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.716s) [202.544517] Starting dynamic subtest: pipe-B-eDP-1 [203.986171] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.452s) [204.000804] Starting dynamic subtest: pipe-C-eDP-1 [205.433118] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.442s) [205.434423] Subtest read-crc-frame-sequence: SUCCESS (3.624s) [206.333327] [125/171] (796s left) kms_psr (primary_page_flip) [206.481515] Starting subtest: primary_page_flip [208.444630] Subtest primary_page_flip: SUCCESS (1.962s) [209.408125] [126/171] (793s left) kms_psr (cursor_plane_move) [209.553493] Starting subtest: cursor_plane_move [211.504201] Subtest cursor_plane_move: SUCCESS (1.943s) [212.414967] [127/171] (790s left) kms_psr (sprite_plane_onoff) [212.563406] Starting subtest: sprite_plane_onoff [214.545992] Subtest sprite_plane_onoff: SUCCESS (1.984s) [215.484158] [128/171] (787s left) kms_psr (primary_mmap_gtt) [215.633725] Starting subtest: primary_mmap_gtt [217.584947] Subtest primary_mmap_gtt: SUCCESS (1.951s) [218.498454] [129/171] (784s left) kms_setmode (basic-clone-single-crtc) [218.610215] Starting subtest: basic-clone-single-crtc [218.613418] Subtest basic-clone-single-crtc: SKIP (0.000s) [218.711948] [130/171] (783s left) i915_pm_backlight (basic-brightness) [218.860536] Starting subtest: basic-brightness [218.976486] Starting dynamic subtest: eDP-1 [219.607087] Dynamic subtest eDP-1: SUCCESS (0.629s) [219.608320] Subtest basic-brightness: SUCCESS (0.748s) [220.545132] [131/171] (782s left) i915_pm_rpm (basic-pci-d3-state) [222.314248] Starting subtest: basic-pci-d3-state [226.884826] Subtest basic-pci-d3-state: SUCCESS (4.570s) [227.042775] [132/171] (775s left) i915_pm_rpm (basic-rte) [227.150433] Starting subtest: basic-rte [233.319764] Subtest basic-rte: SUCCESS (6.169s) [238.678311] [133/171] (764s left) i915_pm_rps (basic-api) [238.795444] Starting subtest: basic-api [238.800636] Subtest basic-api: SUCCESS (0.005s) [238.945719] [134/171] (763s left) prime_self_import (basic-llseek-bad) [239.052054] Starting subtest: basic-llseek-bad [239.054270] Subtest basic-llseek-bad: SUCCESS (0.002s) [239.201103] [135/171] (763s left) prime_self_import (basic-llseek-size) [239.307588] Starting subtest: basic-llseek-size [239.312260] Subtest basic-llseek-size: SUCCESS (0.004s) [239.427781] [136/171] (763s left) prime_self_import (basic-with_fd_dup) [239.540645] Starting subtest: basic-with_fd_dup [239.545574] Subtest basic-with_fd_dup: SUCCESS (0.003s) [239.669452] [137/171] (763s left) prime_self_import (basic-with_one_bo) [239.784290] Starting subtest: basic-with_one_bo [239.789059] Subtest basic-with_one_bo: SUCCESS (0.004s) [239.862058] [138/171] (763s left) prime_self_import (basic-with_one_bo_two_files) [239.970576] Starting subtest: basic-with_one_bo_two_files [239.974374] Subtest basic-with_one_bo_two_files: SUCCESS (0.003s) [240.077055] [139/171] (762s left) prime_self_import (basic-with_two_bos) [240.183050] Starting subtest: basic-with_two_bos [240.187719] Subtest basic-with_two_bos: SUCCESS (0.004s) [240.305806] [140/171] (762s left) prime_vgem (basic-fence-flip) [240.445970] Starting subtest: basic-fence-flip [240.936779] Subtest basic-fence-flip: SUCCESS (0.491s) [241.048879] [141/171] (761s left) prime_vgem (basic-fence-mmap) [241.201234] Starting subtest: basic-fence-mmap [241.239369] Subtest basic-fence-mmap: SUCCESS (0.038s) [241.358759] [142/171] (761s left) prime_vgem (basic-fence-read) [241.501739] Starting subtest: basic-fence-read [241.561597] Subtest basic-fence-read: SUCCESS (0.060s) [241.644728] [143/171] (761s left) prime_vgem (basic-gtt) [241.789243] Starting subtest: basic-gtt [241.805296] Subtest basic-gtt: SUCCESS (0.016s) [241.901543] [144/171] (761s left) prime_vgem (basic-read) [242.044742] Starting subtest: basic-read [242.070946] Subtest basic-read: SUCCESS (0.026s) [242.188829] [145/171] (760s left) prime_vgem (basic-write) [242.332115] Starting subtest: basic-write [242.359372] Subtest basic-write: SUCCESS (0.028s) [242.433442] [146/171] (760s left) vgem_basic (setversion) [242.579207] Starting subtest: setversion [242.581282] Subtest setversion: SUCCESS (0.000s) [242.656769] [147/171] (760s left) vgem_basic (create) [242.787162] Starting subtest: create [242.788175] Subtest create: SUCCESS (0.000s) [242.850319] [148/171] (760s left) vgem_basic (debugfs) [242.992509] Starting subtest: debugfs [242.993824] Subtest debugfs: SUCCESS (0.001s) [243.068848] [149/171] (760s left) vgem_basic (dmabuf-export) [243.199114] Starting subtest: dmabuf-export [243.201274] Subtest dmabuf-export: SUCCESS (0.002s) [243.319847] [150/171] (759s left) vgem_basic (dmabuf-fence) [243.454387] Starting subtest: dmabuf-fence [243.455524] Subtest dmabuf-fence: SUCCESS (0.001s) [243.521352] [151/171] (759s left) vgem_basic (dmabuf-fence-before) [243.659652] Starting subtest: dmabuf-fence-before [243.660734] Subtest dmabuf-fence-before: SUCCESS (0.000s) [243.722730] [152/171] (759s left) vgem_basic (dmabuf-mmap) [243.865062] Starting subtest: dmabuf-mmap [243.883564] Subtest dmabuf-mmap: SUCCESS (0.019s) [243.942140] [153/171] (759s left) vgem_basic (mmap) [244.090210] Starting subtest: mmap [244.101653] Subtest mmap: SUCCESS (0.013s) [244.164686] [154/171] (759s left) vgem_basic (second-client) [244.312860] Starting subtest: second-client [244.345282] Subtest second-client: SUCCESS (0.032s) [244.407193] [155/171] (758s left) vgem_basic (sysfs) [244.551046] Starting subtest: sysfs [244.552226] Subtest sysfs: SUCCESS (0.001s) [244.609212] [156/171] (758s left) core_hotunplug (unbind-rebind) [244.775756] Starting subtest: unbind-rebind [247.066126] Subtest unbind-rebind: SUCCESS (2.290s) [247.128921] [157/171] (756s left) vgem_basic (unload) [247.288523] Starting subtest: unload [276.373342] Subtest unload: SUCCESS (29.046s) [276.467825] [158/171] (727s left) i915_module_load (reload) [276.572096] Starting subtest: reload [279.881268] Subtest reload: SUCCESS (3.309s) [279.913131] [159/171] (723s left) gem_lmem_swapping (basic) [280.145154] Subtest basic: SKIP (0.000s) [280.183720] [160/171] (723s left) gem_lmem_swapping (parallel-random-engines) [280.293927] Subtest parallel-random-engines: SKIP (0.000s) [280.333084] [161/171] (723s left) gem_lmem_swapping (random-engines) [280.443637] Subtest random-engines: SKIP (0.000s) [280.482053] [162/171] (723s left) gem_lmem_swapping (verify-random) [280.593260] Subtest verify-random: SKIP (0.000s) [280.632919] [163/171] (722s left) i915_pm_rpm (module-reload) [283.235718] Starting subtest: module-reload [301.194372] Subtest module-reload: SUCCESS (17.950s) [301.238471] [164/171] (702s left) i915_selftest (live) [301.356831] Starting subtest: live [301.358068] Starting dynamic subtest: sanitycheck [303.093861] Dynamic subtest sanitycheck: SUCCESS (1.728s) [303.101782] Starting dynamic subtest: uncore [304.761193] Dynamic subtest uncore: SUCCESS (1.674s) [304.772685] Starting dynamic subtest: workarounds [306.662792] Dynamic subtest workarounds: SUCCESS (1.891s) [306.675525] Starting dynamic subtest: gt_engines [308.804847] Dynamic subtest gt_engines: SUCCESS (2.140s) [308.813908] Starting dynamic subtest: gt_timelines [318.735899] Dynamic subtest gt_timelines: SUCCESS (9.929s) [318.748141] Starting dynamic subtest: gt_contexts [320.516944] Dynamic subtest gt_contexts: SUCCESS (1.779s) [320.526861] Starting dynamic subtest: gt_lrc [324.683836] Dynamic subtest gt_lrc: SUCCESS (4.165s) [324.696596] Starting dynamic subtest: gt_mocs [326.450710] Dynamic subtest gt_mocs: SUCCESS (1.765s) [326.459131] Starting dynamic subtest: gt_pm [332.506743] Dynamic subtest gt_pm: SUCCESS (6.054s) [332.514447] Starting dynamic subtest: gt_heartbeat [334.270767] Dynamic subtest gt_heartbeat: SUCCESS (1.762s) [334.282097] Starting dynamic subtest: gt_tlb [336.840658] Dynamic subtest gt_tlb: SUCCESS (2.572s) [336.849903] Starting dynamic subtest: requests [344.296144] Dynamic subtest requests: SUCCESS (7.449s) [344.305906] Starting dynamic subtest: migrate [348.816775] Dynamic subtest migrate: SUCCESS (4.519s) [348.825071] Starting dynamic subtest: active [350.513781] Dynamic subtest active: SUCCESS (1.695s) [350.521825] Starting dynamic subtest: objects [353.323746] Dynamic subtest objects: SUCCESS (2.808s) [353.334158] Starting dynamic subtest: mman [358.508966] Dynamic subtest mman: SUCCESS (5.183s) [358.519901] Starting dynamic subtest: dmabuf [360.240745] Dynamic subtest dmabuf: SUCCESS (1.730s) [360.251301] Starting dynamic subtest: vma [361.919661] Dynamic subtest vma: SUCCESS (1.677s) [361.927494] Starting dynamic subtest: coherency [364.728645] Dynamic subtest coherency: SUCCESS (2.807s) [364.735778] Starting dynamic subtest: gtt [373.229779] Dynamic subtest gtt: SUCCESS (8.500s) [373.240054] Starting dynamic subtest: gem [375.152716] Dynamic subtest gem: SUCCESS (1.921s) [375.160511] Starting dynamic subtest: evict [378.419540] Dynamic subtest evict: SUCCESS (3.265s) [378.427145] Starting dynamic subtest: hugepages [385.394744] Dynamic subtest hugepages: SUCCESS (6.973s) [385.402595] Starting dynamic subtest: gem_contexts [400.084548] Dynamic subtest gem_contexts: SUCCESS (14.686s) [400.091913] Starting dynamic subtest: client [401.843663] Dynamic subtest client: SUCCESS (1.759s) [401.854346] Starting dynamic subtest: gem_migrate [403.573756] Dynamic subtest gem_migrate: SUCCESS (1.729s) [403.581634] Starting dynamic subtest: reset [406.006863] Dynamic subtest reset: SUCCESS (2.431s) [406.014733] Starting dynamic subtest: memory_region [407.671725] Dynamic subtest memory_region: SUCCESS (1.663s) [407.679610] Starting dynamic subtest: hangcheck [431.910041] Dynamic subtest hangcheck: SUCCESS (24.237s) [431.919994] Starting dynamic subtest: execlists [441.461828] Dynamic subtest execlists: SUCCESS (9.550s) [441.473166] Starting dynamic subtest: ring_submission [443.122795] Dynamic subtest ring_submission: SUCCESS (1.659s) [443.131477] Starting dynamic subtest: perf [445.010828] Dynamic subtest perf: SUCCESS (1.886s) [445.018771] Starting dynamic subtest: slpc [446.629764] Dynamic subtest slpc: SUCCESS (1.617s) [446.638559] Starting dynamic subtest: guc [448.310301] Dynamic subtest guc: SUCCESS (1.678s) [448.323058] Starting dynamic subtest: guc_multi_lrc [449.970100] Dynamic subtest guc_multi_lrc: SUCCESS (1.665s) [449.980111] Starting dynamic subtest: guc_hang [451.648817] Dynamic subtest guc_hang: SUCCESS (1.669s) [451.657832] Starting dynamic subtest: late_gt_pm [453.324045] Dynamic subtest late_gt_pm: SUCCESS (1.673s) [453.325478] Subtest live: SUCCESS (151.960s) [453.539279] [165/171] (550s left) dmabuf (all-tests) [453.660248] Starting subtest: all-tests [453.661647] Starting dynamic subtest: sanitycheck [453.731148] Dynamic subtest sanitycheck: SUCCESS (0.069s) [453.766526] Starting dynamic subtest: dma_fence [453.930574] Dynamic subtest dma_fence: SUCCESS (0.194s) [453.946825] Starting dynamic subtest: dma_fence_chain [469.686271] Dynamic subtest dma_fence_chain: SUCCESS (15.742s) [469.717347] Starting dynamic subtest: dma_fence_unwrap [469.758573] Dynamic subtest dma_fence_unwrap: SUCCESS (0.075s) [469.773420] Starting dynamic subtest: dma_resv [469.836836] Dynamic subtest dma_resv: SUCCESS (0.075s) [469.838837] Subtest all-tests: SUCCESS (16.178s) [469.877836] [166/171] (533s left) i915_suspend (basic-s2idle-without-i915) [469.983125] Starting subtest: basic-s2idle-without-i915 [489.482568] Subtest basic-s2idle-without-i915: SUCCESS (4.165s) [491.822604] [167/171] (529s left) i915_suspend (basic-s3-without-i915) [491.938594] Starting subtest: basic-s3-without-i915 [512.512766] Subtest basic-s3-without-i915: SUCCESS (4.509s) [515.949149] [168/171] (524s left) gem_exec_suspend (basic-s0) [516.167447] Starting subtest: basic-S0 [516.168570] Starting dynamic subtest: smem [534.529062] Dynamic subtest smem: SUCCESS (3.204s) [534.530044] Subtest basic-S0: SUCCESS (3.206s) [536.707808] [169/171] (520s left) gem_exec_suspend (basic-s3) [536.872745] Starting subtest: basic-S3 [536.873669] Starting dynamic subtest: smem [557.542808] Dynamic subtest smem: SUCCESS (4.313s) [557.543924] Subtest basic-S3: SUCCESS (4.315s) [559.737533] [170/171] (516s left) kms_chamelium_hpd (common-hpd-after-suspend) [560.209009] Subtest common-hpd-after-suspend: SKIP (0.000s) [561.091223] [171/171] (514s left) kms_pipe_crc_basic (suspend-read-crc) [561.243198] Starting subtest: suspend-read-crc [561.246665] Starting dynamic subtest: pipe-A-eDP-1 [581.648250] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.339s) [581.683419] Starting dynamic subtest: pipe-B-eDP-1 [612.936688] Dynamic subtest pipe-B-eDP-1: SUCCESS (14.372s) [612.951834] Starting dynamic subtest: pipe-C-eDP-1 [643.918836] Dynamic subtest pipe-C-eDP-1: SUCCESS (14.366s) [643.919836] Subtest suspend-read-crc: SUCCESS (32.088s) [644.809668] Closing watchdogs Done.