[46.189491] Initializing watchdogs [46.189668] /dev/watchdog0 [46.199030] [001/171] (960s left) i915_module_load (load) [46.495319] Starting subtest: load [49.018968] Subtest load: SUCCESS (2.524s) [49.045761] [002/171] (957s left) core_auth (basic-auth) [49.187744] Starting subtest: basic-auth [49.188999] Subtest basic-auth: SUCCESS (0.001s) [49.281080] [003/171] (957s left) debugfs_test (read_all_entries) [49.432256] Starting subtest: read_all_entries [49.504748] Subtest read_all_entries: SUCCESS (0.071s) [49.617251] [004/171] (957s left) debugfs_test (basic-hwmon) [49.759751] Starting subtest: basic-hwmon [49.761996] Subtest basic-hwmon: SKIP (0.000s) [49.875859] [005/171] (956s left) fbdev (eof) [50.053504] Starting subtest: eof [50.054665] Subtest eof: SUCCESS (0.000s) [50.120366] [006/171] (956s left) fbdev (info) [50.283452] Starting subtest: info [50.284681] Subtest info: SUCCESS (0.000s) [50.352382] [007/171] (956s left) fbdev (nullptr) [50.504278] Starting subtest: nullptr [50.506073] Subtest nullptr: SUCCESS (0.000s) [50.568872] [008/171] (956s left) fbdev (read) [50.719778] Starting subtest: read [57.808107] Subtest read: SUCCESS (7.053s) [57.852310] [009/171] (949s left) fbdev (write) [58.005122] Starting subtest: write [61.787559] Subtest write: SUCCESS (3.749s) [61.835411] [010/171] (945s left) gem_basic (bad-close) [61.972643] Starting subtest: bad-close [61.974910] Subtest bad-close: SUCCESS (0.000s) [62.051385] [011/171] (944s left) gem_basic (create-close) [62.193575] Starting subtest: create-close [62.195923] Subtest create-close: SUCCESS (0.000s) [62.284878] [012/171] (944s left) gem_basic (create-fd-close) [62.422813] Starting subtest: create-fd-close [62.425169] Subtest create-fd-close: SUCCESS (0.000s) [62.536623] [013/171] (944s left) gem_busy (busy@all-engines) [62.691466] Starting subtest: busy [62.692622] Starting dynamic subtest: all-engines [62.755721] Dynamic subtest all-engines: SUCCESS (0.064s) [62.756841] Subtest busy: SUCCESS (0.064s) [63.136422] [014/171] (943s left) gem_close_race (basic-process) [63.298086] Starting subtest: basic-process [63.374779] Subtest basic-process: SUCCESS (0.076s) [63.569867] [015/171] (943s left) gem_close_race (basic-threads) [63.727807] Starting subtest: basic-threads [64.893961] Subtest basic-threads: SUCCESS (1.166s) [65.122909] [016/171] (941s left) gem_ctx_create (basic) [65.282910] Starting subtest: basic [65.284073] Subtest basic: SUCCESS (0.000s) [65.486877] [017/171] (941s left) gem_ctx_create (basic-files) [65.638688] Starting subtest: basic-files [67.662220] Subtest basic-files: SUCCESS (2.024s) [67.870451] [018/171] (939s left) gem_ctx_exec (basic) [68.027659] Starting subtest: basic [68.032699] Subtest basic: SUCCESS (0.005s) [68.134388] [019/171] (939s left) gem_exec_basic (basic) [68.280434] Starting subtest: basic [68.281561] Starting dynamic subtest: rcs0-smem [68.282585] Dynamic subtest rcs0-smem: SUCCESS (0.002s) [68.293582] Starting dynamic subtest: bcs0-smem [68.294756] Dynamic subtest bcs0-smem: SUCCESS (0.001s) [68.304992] Starting dynamic subtest: vcs0-smem [68.306369] Dynamic subtest vcs0-smem: SUCCESS (0.001s) [68.316673] Starting dynamic subtest: vecs0-smem [68.317960] Dynamic subtest vecs0-smem: SUCCESS (0.001s) [68.319279] Subtest basic: SUCCESS (0.005s) [68.486913] [020/171] (938s left) gem_exec_create (basic) [68.638699] Starting subtest: basic [68.640047] Starting dynamic subtest: smem [70.705664] Dynamic subtest smem: SUCCESS (2.053s) [70.706795] Subtest basic: SUCCESS (2.053s) [70.918829] [021/171] (936s left) gem_exec_fence (basic-busy) [71.213867] Starting subtest: basic-busy [71.220475] Starting dynamic subtest: rcs0 [71.240163] Dynamic subtest rcs0: SUCCESS (0.034s) [71.253092] Starting dynamic subtest: bcs0 [71.264830] Dynamic subtest bcs0: SUCCESS (0.025s) [71.275608] Starting dynamic subtest: vcs0 [71.299880] Dynamic subtest vcs0: SUCCESS (0.035s) [71.313829] Starting dynamic subtest: vecs0 [71.339907] Dynamic subtest vecs0: SUCCESS (0.039s) [71.341624] Subtest basic-busy: SUCCESS (0.134s) [71.919905] [022/171] (935s left) gem_exec_fence (basic-wait) [72.210458] Starting subtest: basic-wait [72.211723] Starting dynamic subtest: rcs0 [72.241833] Dynamic subtest rcs0: SUCCESS (0.031s) [72.253088] Starting dynamic subtest: bcs0 [72.264432] Dynamic subtest bcs0: SUCCESS (0.023s) [72.275787] Starting dynamic subtest: vcs0 [72.289565] Dynamic subtest vcs0: SUCCESS (0.025s) [72.301105] Starting dynamic subtest: vecs0 [72.317889] Dynamic subtest vecs0: SUCCESS (0.028s) [72.319114] Subtest basic-wait: SUCCESS (0.108s) [72.870121] [023/171] (934s left) gem_exec_fence (basic-await) [73.178238] Starting subtest: basic-await [73.179498] Starting dynamic subtest: rcs0 [73.263516] Dynamic subtest rcs0: SUCCESS (0.098s) [73.288925] Starting dynamic subtest: bcs0 [73.377986] Dynamic subtest bcs0: SUCCESS (0.114s) [73.404844] Starting dynamic subtest: vcs0 [73.517532] Dynamic subtest vcs0: SUCCESS (0.128s) [73.543482] Starting dynamic subtest: vecs0 [73.638332] Dynamic subtest vecs0: SUCCESS (0.130s) [73.640260] Subtest basic-await: SUCCESS (0.473s) [74.220652] [024/171] (933s left) gem_exec_fence (nb-await) [74.525635] Starting subtest: nb-await [74.526804] Starting dynamic subtest: rcs0 [74.585296] Dynamic subtest rcs0: SUCCESS (0.066s) [74.605552] Starting dynamic subtest: bcs0 [74.655180] Dynamic subtest bcs0: SUCCESS (0.069s) [74.681755] Starting dynamic subtest: vcs0 [74.729887] Dynamic subtest vcs0: SUCCESS (0.074s) [74.756955] Starting dynamic subtest: vecs0 [74.804854] Dynamic subtest vecs0: SUCCESS (0.074s) [74.806960] Subtest nb-await: SUCCESS (0.287s) [75.386770] [025/171] (931s left) gem_exec_gttfill (basic) [75.532433] Starting subtest: basic [83.775630] Subtest basic: SUCCESS (8.235s) [83.956035] [026/171] (923s left) gem_exec_parallel (engines) [84.178178] Starting subtest: engines [84.179947] Starting dynamic subtest: basic [86.714820] Dynamic subtest basic: SUCCESS (2.519s) [86.735928] Starting dynamic subtest: contexts [90.002720] Dynamic subtest contexts: SUCCESS (3.296s) [90.016537] Starting dynamic subtest: fds [94.165880] Dynamic subtest fds: SUCCESS (4.171s) [94.188166] Starting dynamic subtest: userptr [96.761461] Dynamic subtest userptr: SUCCESS (2.572s) [96.763676] Subtest engines: SUCCESS (12.559s) [96.941352] [027/171] (910s left) gem_exec_store (basic) [97.097429] Starting subtest: basic [97.155605] Subtest basic: SUCCESS (0.058s) [97.355858] [028/171] (910s left) gem_flink_basic (bad-flink) [97.490581] Starting subtest: bad-flink [97.491798] Subtest bad-flink: SUCCESS (0.000s) [97.571030] [029/171] (909s left) gem_flink_basic (bad-open) [97.708618] Starting subtest: bad-open [97.709770] Subtest bad-open: SUCCESS (0.000s) [97.788575] [030/171] (909s left) gem_flink_basic (basic) [97.926269] Starting subtest: basic [97.927383] Subtest basic: SUCCESS (0.000s) [98.038092] [031/171] (909s left) gem_flink_basic (double-flink) [98.174567] Starting subtest: double-flink [98.175847] Subtest double-flink: SUCCESS (0.000s) [98.271282] [032/171] (909s left) gem_flink_basic (flink-lifetime) [98.408166] Starting subtest: flink-lifetime [98.409345] Subtest flink-lifetime: SUCCESS (0.002s) [98.506796] [033/171] (908s left) gem_huc_copy (huc-copy) [98.649225] Starting subtest: huc-copy [98.651506] Subtest huc-copy: SKIP (0.000s) [98.754609] [034/171] (908s left) gem_linear_blits (basic) [99.058427] Starting subtest: basic [99.071812] Subtest basic: SUCCESS (0.014s) [99.206596] [035/171] (908s left) gem_mmap (basic) [99.402658] Starting subtest: basic [99.408794] Subtest basic: SUCCESS (0.001s) [99.504861] [036/171] (908s left) gem_mmap_gtt (basic) [99.645637] Starting subtest: basic [99.646890] Subtest basic: SUCCESS (0.001s) [99.720876] [037/171] (907s left) gem_render_linear_blits (basic) [99.861819] Starting subtest: basic [99.911520] Subtest basic: SUCCESS (0.050s) [99.988086] [038/171] (907s left) gem_render_tiled_blits (basic) [100.129452] Starting subtest: basic [100.187661] Subtest basic: SUCCESS (0.058s) [100.255822] [039/171] (907s left) gem_ringfill (basic-all) [100.525737] Starting subtest: basic-all [101.770887] Subtest basic-all: SUCCESS (1.236s) [101.858783] [040/171] (905s left) gem_softpin (allocator-basic) [102.004087] Starting subtest: allocator-basic [102.172780] Subtest allocator-basic: SUCCESS (0.160s) [102.291212] [041/171] (905s left) gem_softpin (allocator-basic-reserve) [102.442803] Starting subtest: allocator-basic-reserve [102.610486] Subtest allocator-basic-reserve: SUCCESS (0.159s) [102.722610] [042/171] (905s left) gem_softpin (safe-alignment) [102.876830] Starting subtest: safe-alignment [102.894728] Subtest safe-alignment: SUCCESS (0.018s) [103.010760] [043/171] (904s left) gem_sync (basic-all) [103.168055] Starting subtest: basic-all [105.363638] Subtest basic-all: SUCCESS (2.180s) [105.556208] [044/171] (902s left) gem_sync (basic-each) [105.707575] Starting subtest: basic-each [108.114527] Subtest basic-each: SUCCESS (2.407s) [108.322811] [045/171] (899s left) gem_tiled_blits (basic) [108.611831] Starting subtest: basic [108.638853] Subtest basic: SUCCESS (0.028s) [108.754332] [046/171] (899s left) gem_tiled_fence_blits (basic) [109.113343] Starting subtest: basic [109.140292] Subtest basic: SUCCESS (0.027s) [109.269249] [047/171] (898s left) gem_tiled_pread_basic [109.805413] [048/171] (898s left) gem_wait (busy@all-engines) [109.968279] Starting subtest: busy [109.969562] Starting dynamic subtest: all-engines [110.505704] Dynamic subtest all-engines: SUCCESS (0.537s) [110.506973] Subtest busy: SUCCESS (0.538s) [110.739510] [049/171] (897s left) gem_wait (wait@all-engines) [110.899603] Starting subtest: wait [110.900774] Starting dynamic subtest: all-engines [111.951564] Dynamic subtest all-engines: SUCCESS (1.052s) [111.952791] Subtest wait: SUCCESS (1.052s) [112.189047] [050/171] (895s left) i915_getparams_basic (basic-eu-total) [112.322401] Starting subtest: basic-eu-total [112.324592] Subtest basic-eu-total: SUCCESS (0.000s) [112.405324] [051/171] (895s left) i915_getparams_basic (basic-subslice-total) [112.541553] Starting subtest: basic-subslice-total [112.544109] Subtest basic-subslice-total: SUCCESS (0.000s) [112.621929] [052/171] (895s left) i915_hangman (error-state-basic) [112.774279] Starting subtest: error-state-basic [112.787954] Subtest error-state-basic: SUCCESS (0.014s) [112.871886] [053/171] (895s left) i915_pciid [113.088838] [054/171] (894s left) kms_addfb_basic (addfb25-bad-modifier) [113.227099] Starting subtest: addfb25-bad-modifier [113.228261] Subtest addfb25-bad-modifier: SUCCESS (0.000s) [113.354564] [055/171] (894s left) kms_addfb_basic (addfb25-framebuffer-vs-set-tiling) [113.492774] Starting subtest: addfb25-framebuffer-vs-set-tiling [113.493978] Subtest addfb25-framebuffer-vs-set-tiling: SUCCESS (0.000s) [113.621575] [056/171] (894s left) kms_addfb_basic (addfb25-modifier-no-flag) [113.758122] Starting subtest: addfb25-modifier-no-flag [113.759223] Subtest addfb25-modifier-no-flag: SUCCESS (0.000s) [113.888898] [057/171] (894s left) kms_addfb_basic (addfb25-x-tiled-legacy) [114.026148] Starting subtest: addfb25-x-tiled-legacy [114.027313] Subtest addfb25-x-tiled-legacy: SUCCESS (0.000s) [114.155282] [058/171] (894s left) kms_addfb_basic (addfb25-x-tiled-mismatch-legacy) [114.292274] Starting subtest: addfb25-x-tiled-mismatch-legacy [114.293415] Subtest addfb25-x-tiled-mismatch-legacy: SUCCESS (0.000s) [114.421927] [059/171] (893s left) kms_addfb_basic (addfb25-yf-tiled-legacy) [114.589097] Starting subtest: addfb25-yf-tiled-legacy [114.590199] Subtest addfb25-yf-tiled-legacy: SUCCESS (0.000s) [114.688107] [060/171] (893s left) kms_addfb_basic (addfb25-y-tiled-legacy) [114.856684] Starting subtest: addfb25-y-tiled-legacy [114.857882] Subtest addfb25-y-tiled-legacy: SUCCESS (0.000s) [114.955064] [061/171] (893s left) kms_addfb_basic (addfb25-y-tiled-small-legacy) [115.121874] Starting subtest: addfb25-y-tiled-small-legacy [115.123042] Subtest addfb25-y-tiled-small-legacy: SUCCESS (0.000s) [115.222089] [062/171] (893s left) kms_addfb_basic (bad-pitch-0) [115.358349] Starting subtest: bad-pitch-0 [115.359533] Subtest bad-pitch-0: SUCCESS (0.000s) [115.488219] [063/171] (892s left) kms_addfb_basic (bad-pitch-1024) [115.617392] Starting subtest: bad-pitch-1024 [115.618496] Subtest bad-pitch-1024: SUCCESS (0.000s) [115.737991] [064/171] (892s left) kms_addfb_basic (bad-pitch-128) [115.875166] Starting subtest: bad-pitch-128 [115.876493] Subtest bad-pitch-128: SUCCESS (0.000s) [116.005227] [065/171] (892s left) kms_addfb_basic (bad-pitch-256) [116.142410] Starting subtest: bad-pitch-256 [116.143486] Subtest bad-pitch-256: SUCCESS (0.000s) [116.292858] [066/171] (892s left) kms_addfb_basic (bad-pitch-32) [116.428607] Starting subtest: bad-pitch-32 [116.429948] Subtest bad-pitch-32: SUCCESS (0.000s) [116.556412] [067/171] (891s left) kms_addfb_basic (bad-pitch-63) [116.692763] Starting subtest: bad-pitch-63 [116.693983] Subtest bad-pitch-63: SUCCESS (0.000s) [116.821685] [068/171] (891s left) kms_addfb_basic (bad-pitch-65536) [116.951282] Starting subtest: bad-pitch-65536 [116.952542] Subtest bad-pitch-65536: SUCCESS (0.000s) [117.071710] [069/171] (891s left) kms_addfb_basic (bad-pitch-999) [117.210198] Starting subtest: bad-pitch-999 [117.211264] Subtest bad-pitch-999: SUCCESS (0.000s) [117.338600] [070/171] (891s left) kms_addfb_basic (basic) [117.476343] Starting subtest: basic [117.477498] Subtest basic: SUCCESS (0.000s) [117.605202] [071/171] (890s left) kms_addfb_basic (basic-x-tiled-legacy) [117.737476] Starting subtest: basic-x-tiled-legacy [117.738564] Subtest basic-x-tiled-legacy: SUCCESS (0.000s) [117.855098] [072/171] (890s left) kms_addfb_basic (basic-y-tiled-legacy) [117.992881] Starting subtest: basic-y-tiled-legacy [117.994088] Subtest basic-y-tiled-legacy: SUCCESS (0.000s) [118.122157] [073/171] (890s left) kms_addfb_basic (bo-too-small) [118.289350] Starting subtest: bo-too-small [118.290500] Subtest bo-too-small: SUCCESS (0.000s) [118.388116] [074/171] (890s left) kms_addfb_basic (bo-too-small-due-to-tiling) [118.555131] Starting subtest: bo-too-small-due-to-tiling [118.556381] Subtest bo-too-small-due-to-tiling: SUCCESS (0.000s) [118.654915] [075/171] (889s left) kms_addfb_basic (clobberred-modifier) [118.792625] Starting subtest: clobberred-modifier [118.793790] Subtest clobberred-modifier: SUCCESS (0.000s) [118.922335] [076/171] (889s left) kms_addfb_basic (framebuffer-vs-set-tiling) [119.060263] Starting subtest: framebuffer-vs-set-tiling [119.061460] Subtest framebuffer-vs-set-tiling: SUCCESS (0.000s) [119.189191] [077/171] (889s left) kms_addfb_basic (invalid-get-prop) [119.325665] Starting subtest: invalid-get-prop [119.326828] Subtest invalid-get-prop: SUCCESS (0.000s) [119.455276] [078/171] (889s left) kms_addfb_basic (invalid-get-prop-any) [119.592621] Starting subtest: invalid-get-prop-any [119.593799] Subtest invalid-get-prop-any: SUCCESS (0.000s) [119.721839] [079/171] (889s left) kms_addfb_basic (invalid-set-prop) [119.860286] Starting subtest: invalid-set-prop [119.861439] Subtest invalid-set-prop: SUCCESS (0.000s) [119.988743] [080/171] (888s left) kms_addfb_basic (invalid-set-prop-any) [120.126501] Starting subtest: invalid-set-prop-any [120.127626] Subtest invalid-set-prop-any: SUCCESS (0.000s) [120.255215] [081/171] (888s left) kms_addfb_basic (no-handle) [120.392317] Starting subtest: no-handle [120.393454] Subtest no-handle: SUCCESS (0.000s) [120.521524] [082/171] (888s left) kms_addfb_basic (size-max) [120.688397] Starting subtest: size-max [120.689578] Subtest size-max: SUCCESS (0.000s) [120.788165] [083/171] (888s left) kms_addfb_basic (small-bo) [120.955470] Starting subtest: small-bo [120.956621] Subtest small-bo: SUCCESS (0.000s) [121.055347] [084/171] (887s left) kms_addfb_basic (tile-pitch-mismatch) [121.193475] Starting subtest: tile-pitch-mismatch [121.194619] Subtest tile-pitch-mismatch: SUCCESS (0.000s) [121.322236] [085/171] (887s left) kms_addfb_basic (too-high) [121.489519] Starting subtest: too-high [121.490690] Subtest too-high: SUCCESS (0.001s) [121.588200] [086/171] (887s left) kms_addfb_basic (too-wide) [121.752578] Starting subtest: too-wide [121.753736] Subtest too-wide: SUCCESS (0.000s) [121.855402] [087/171] (887s left) kms_addfb_basic (unused-handle) [121.992578] Starting subtest: unused-handle [121.993749] Subtest unused-handle: SUCCESS (0.000s) [122.122446] [088/171] (886s left) kms_addfb_basic (unused-modifier) [122.260985] Starting subtest: unused-modifier [122.262302] Subtest unused-modifier: SUCCESS (0.000s) [122.388846] [089/171] (886s left) kms_addfb_basic (unused-offsets) [122.524842] Starting subtest: unused-offsets [122.526161] Subtest unused-offsets: SUCCESS (0.000s) [122.656106] [090/171] (886s left) kms_addfb_basic (unused-pitches) [122.793749] Starting subtest: unused-pitches [122.795219] Subtest unused-pitches: SUCCESS (0.000s) [122.921827] [091/171] (886s left) kms_busy (basic) [123.094740] Starting subtest: basic [123.097188] Starting dynamic subtest: flip [124.108997] Dynamic subtest flip: SUCCESS (1.013s) [124.125468] Starting dynamic subtest: modeset [127.990925] Dynamic subtest modeset: SUCCESS (3.881s) [127.992228] Subtest basic: SUCCESS (4.897s) [128.961347] [092/171] (880s left) kms_chamelium_hpd (dp-hpd-fast) [129.439404] Subtest dp-hpd-fast: SKIP (0.000s) [130.318303] [093/171] (878s left) kms_chamelium_edid (dp-edid-read) [130.797320] Subtest dp-edid-read: SKIP (0.000s) [131.668988] [094/171] (877s left) kms_chamelium_frames (dp-crc-fast) [132.163129] Subtest dp-crc-fast: SKIP (0.000s) [133.070907] [095/171] (876s left) kms_chamelium_hpd (hdmi-hpd-fast) [133.549362] Subtest hdmi-hpd-fast: SKIP (0.000s) [134.433383] [096/171] (874s left) kms_chamelium_edid (hdmi-edid-read) [134.913256] Subtest hdmi-edid-read: SKIP (0.000s) [135.821061] [097/171] (873s left) kms_chamelium_frames (hdmi-crc-fast) [136.305154] Subtest hdmi-crc-fast: SKIP (0.000s) [137.169320] [098/171] (872s left) kms_chamelium_hpd (vga-hpd-fast) [137.652861] Subtest vga-hpd-fast: SKIP (0.000s) [138.573553] [099/171] (870s left) kms_chamelium_edid (vga-edid-read) [139.054226] Subtest vga-edid-read: SKIP (0.000s) [139.918504] [100/171] (869s left) kms_prop_blob (basic) [140.058851] Starting subtest: basic [140.060090] Subtest basic: SUCCESS (0.000s) [140.141447] [101/171] (869s left) kms_cursor_legacy (basic-busy-flip-before-cursor-atomic) [140.298456] Starting subtest: basic-busy-flip-before-cursor-atomic [140.300630] Subtest basic-busy-flip-before-cursor-atomic: SKIP (0.000s) [140.383991] [102/171] (869s left) kms_cursor_legacy (basic-busy-flip-before-cursor-legacy) [140.541222] Starting subtest: basic-busy-flip-before-cursor-legacy [140.543448] Subtest basic-busy-flip-before-cursor-legacy: SKIP (0.000s) [140.617104] [103/171] (868s left) kms_cursor_legacy (basic-flip-after-cursor-atomic) [140.782511] Starting subtest: basic-flip-after-cursor-atomic [142.064922] Subtest basic-flip-after-cursor-atomic: SUCCESS (1.284s) [143.039676] [104/171] (866s left) kms_cursor_legacy (basic-flip-after-cursor-legacy) [143.207123] Starting subtest: basic-flip-after-cursor-legacy [144.503322] Subtest basic-flip-after-cursor-legacy: SUCCESS (1.291s) [145.473100] [105/171] (864s left) kms_cursor_legacy (basic-flip-after-cursor-varying-size) [145.640150] Starting subtest: basic-flip-after-cursor-varying-size [146.931098] Subtest basic-flip-after-cursor-varying-size: SUCCESS (1.290s) [147.903887] [106/171] (861s left) kms_cursor_legacy (basic-flip-before-cursor-atomic) [148.071113] Starting subtest: basic-flip-before-cursor-atomic [149.364882] Subtest basic-flip-before-cursor-atomic: SUCCESS (1.294s) [150.335090] [107/171] (859s left) kms_cursor_legacy (basic-flip-before-cursor-legacy) [150.501956] Starting subtest: basic-flip-before-cursor-legacy [151.812336] Subtest basic-flip-before-cursor-legacy: SUCCESS (1.310s) [152.767343] [108/171] (856s left) kms_cursor_legacy (basic-flip-before-cursor-varying-size) [152.933167] Starting subtest: basic-flip-before-cursor-varying-size [154.213460] Subtest basic-flip-before-cursor-varying-size: SUCCESS (1.279s) [155.136895] [109/171] (854s left) kms_flip (basic-flip-vs-dpms) [155.282695] Starting subtest: basic-flip-vs-dpms [155.285820] Starting dynamic subtest: A-eDP1 [158.137979] Dynamic subtest A-eDP1: SUCCESS (2.853s) [158.154862] Starting dynamic subtest: B-eDP1 [160.508250] Dynamic subtest B-eDP1: SUCCESS (2.369s) [160.526665] Starting dynamic subtest: C-eDP1 [162.878087] Dynamic subtest C-eDP1: SUCCESS (2.365s) [162.879335] Subtest basic-flip-vs-dpms: SUCCESS (7.596s) [163.837585] [110/171] (845s left) kms_flip (basic-flip-vs-modeset) [163.984746] Starting subtest: basic-flip-vs-modeset [163.987867] Starting dynamic subtest: A-eDP1 [166.840080] Dynamic subtest A-eDP1: SUCCESS (2.849s) [166.855782] Starting dynamic subtest: B-eDP1 [169.200210] Dynamic subtest B-eDP1: SUCCESS (2.361s) [169.218373] Starting dynamic subtest: C-eDP1 [171.574418] Dynamic subtest C-eDP1: SUCCESS (2.373s) [171.577318] Subtest basic-flip-vs-modeset: SUCCESS (7.593s) [172.545292] [111/171] (837s left) kms_flip (basic-flip-vs-wf_vblank) [172.697078] Starting subtest: basic-flip-vs-wf_vblank [172.700216] Starting dynamic subtest: A-eDP1 [175.006254] Dynamic subtest A-eDP1: SUCCESS (2.306s) [175.023076] Starting dynamic subtest: B-eDP1 [177.232847] Dynamic subtest B-eDP1: SUCCESS (2.225s) [177.249455] Starting dynamic subtest: C-eDP1 [179.534716] Dynamic subtest C-eDP1: SUCCESS (2.300s) [179.537715] Subtest basic-flip-vs-wf_vblank: SUCCESS (6.842s) [180.801409] [112/171] (828s left) kms_flip (basic-plain-flip) [180.945573] Starting subtest: basic-plain-flip [180.949293] Starting dynamic subtest: A-eDP1 [182.980612] Dynamic subtest A-eDP1: SUCCESS (2.031s) [182.997404] Starting dynamic subtest: B-eDP1 [184.948809] Dynamic subtest B-eDP1: SUCCESS (1.967s) [184.965569] Starting dynamic subtest: C-eDP1 [186.933349] Dynamic subtest C-eDP1: SUCCESS (1.983s) [186.936432] Subtest basic-plain-flip: SUCCESS (5.991s) [188.225034] [113/171] (821s left) kms_force_connector_basic (force-connector-state) [188.368670] Starting subtest: force-connector-state [190.089276] Subtest force-connector-state: SUCCESS (1.720s) [190.710199] [114/171] (819s left) kms_force_connector_basic (force-edid) [190.854093] Starting subtest: force-edid [191.108569] Subtest force-edid: SUCCESS (0.254s) [191.226414] [115/171] (818s left) kms_force_connector_basic (force-load-detect) [191.369616] Starting subtest: force-load-detect [191.372111] Subtest force-load-detect: SKIP (0.000s) [191.476422] [116/171] (818s left) kms_force_connector_basic (prune-stale-modes) [191.618211] Starting subtest: prune-stale-modes [191.923024] Subtest prune-stale-modes: SUCCESS (0.305s) [192.043396] [117/171] (817s left) kms_frontbuffer_tracking (basic) [192.217266] Starting subtest: basic [195.525386] Subtest basic: SUCCESS (3.308s) [195.644796] [118/171] (814s left) kms_pipe_crc_basic (compare-crc-sanitycheck-xr24) [195.815725] Starting subtest: compare-crc-sanitycheck-xr24 [195.818634] Starting dynamic subtest: pipe-A-eDP-1 [196.512988] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.693s) [196.531989] Starting dynamic subtest: pipe-B-eDP-1 [197.890143] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.362s) [197.905914] Starting dynamic subtest: pipe-C-eDP-1 [199.453193] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.569s) [199.454440] Subtest compare-crc-sanitycheck-xr24: SUCCESS (3.639s) [200.378822] [119/171] (809s left) kms_pipe_crc_basic (compare-crc-sanitycheck-nv12) [200.545642] Starting subtest: compare-crc-sanitycheck-nv12 [200.548868] Starting dynamic subtest: pipe-A-eDP-1 [201.470908] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.921s) [201.496360] Starting dynamic subtest: pipe-B-eDP-1 [202.919317] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.444s) [202.939961] Starting dynamic subtest: pipe-C-eDP-1 [204.685255] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.759s) [204.686455] Subtest compare-crc-sanitycheck-nv12: SUCCESS (4.139s) [205.628894] [120/171] (804s left) kms_pipe_crc_basic (hang-read-crc) [205.797230] Starting subtest: hang-read-crc [205.800350] Starting dynamic subtest: pipe-A-eDP-1 [208.106685] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.305s) [208.125119] Starting dynamic subtest: pipe-B-eDP-1 [211.098389] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.987s) [211.117865] Starting dynamic subtest: pipe-C-eDP-1 [214.420395] Dynamic subtest pipe-C-eDP-1: SUCCESS (3.317s) [214.421665] Subtest hang-read-crc: SUCCESS (8.624s) [215.360778] [121/171] (794s left) kms_pipe_crc_basic (nonblocking-crc) [215.532421] Starting subtest: nonblocking-crc [215.535588] Starting dynamic subtest: pipe-A-eDP-1 [216.398684] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.860s) [216.416983] Starting dynamic subtest: pipe-B-eDP-1 [217.948164] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.547s) [217.967952] Starting dynamic subtest: pipe-C-eDP-1 [219.655388] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.702s) [219.656698] Subtest nonblocking-crc: SUCCESS (4.124s) [220.603134] [122/171] (789s left) kms_pipe_crc_basic (nonblocking-crc-frame-sequence) [220.774377] Starting subtest: nonblocking-crc-frame-sequence [220.778196] Starting dynamic subtest: pipe-A-eDP-1 [221.628393] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.852s) [221.648262] Starting dynamic subtest: pipe-B-eDP-1 [223.182055] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.548s) [223.202241] Starting dynamic subtest: pipe-C-eDP-1 [224.885855] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.698s) [224.887236] Subtest nonblocking-crc-frame-sequence: SUCCESS (4.113s) [225.851043] [123/171] (784s left) kms_pipe_crc_basic (read-crc) [226.016345] Starting subtest: read-crc [226.023111] Starting dynamic subtest: pipe-A-eDP-1 [226.808523] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.790s) [226.828593] Starting dynamic subtest: pipe-B-eDP-1 [228.300220] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.485s) [228.318970] Starting dynamic subtest: pipe-C-eDP-1 [229.948627] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.643s) [229.949977] Subtest read-crc: SUCCESS (3.933s) [230.907069] [124/171] (779s left) kms_pipe_crc_basic (read-crc-frame-sequence) [231.071199] Starting subtest: read-crc-frame-sequence [231.072488] Starting dynamic subtest: pipe-A-eDP-1 [231.867720] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.795s) [231.887766] Starting dynamic subtest: pipe-B-eDP-1 [233.352898] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.479s) [233.374107] Starting dynamic subtest: pipe-C-eDP-1 [234.998499] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.639s) [234.999773] Subtest read-crc-frame-sequence: SUCCESS (3.929s) [235.963043] [125/171] (774s left) kms_psr (primary_page_flip) [236.137413] Starting subtest: primary_page_flip [240.324330] Subtest primary_page_flip: SUCCESS (4.186s) [241.275154] [126/171] (768s left) kms_psr (cursor_plane_move) [241.445353] Starting subtest: cursor_plane_move [245.603705] Subtest cursor_plane_move: SUCCESS (4.156s) [246.523247] [127/171] (763s left) kms_psr (sprite_plane_onoff) [246.693716] Starting subtest: sprite_plane_onoff [250.969981] Subtest sprite_plane_onoff: SUCCESS (4.276s) [251.898974] [128/171] (758s left) kms_psr (primary_mmap_gtt) [252.069017] Starting subtest: primary_mmap_gtt [256.269069] Subtest primary_mmap_gtt: SUCCESS (4.189s) [257.211123] [129/171] (752s left) kms_setmode (basic-clone-single-crtc) [257.352221] Starting subtest: basic-clone-single-crtc [257.355577] Subtest basic-clone-single-crtc: SKIP (0.000s) [257.427465] [130/171] (752s left) i915_pm_backlight (basic-brightness) [257.597061] Starting subtest: basic-brightness [257.717973] Starting dynamic subtest: eDP-1 [258.278483] Dynamic subtest eDP-1: SUCCESS (0.560s) [258.280696] Subtest basic-brightness: SUCCESS (0.673s) [259.196474] [131/171] (751s left) i915_pm_rpm (basic-pci-d3-state) [261.084946] Starting subtest: basic-pci-d3-state [265.494107] Subtest basic-pci-d3-state: SUCCESS (4.408s) [265.696700] [132/171] (744s left) i915_pm_rpm (basic-rte) [265.832938] Starting subtest: basic-rte [271.959365] Subtest basic-rte: SUCCESS (6.123s) [273.426689] [133/171] (736s left) i915_pm_rps (basic-api) [273.573408] Starting subtest: basic-api [273.577849] Subtest basic-api: SUCCESS (0.004s) [273.675194] [134/171] (736s left) prime_self_import (basic-llseek-bad) [273.802532] Starting subtest: basic-llseek-bad [273.807615] Subtest basic-llseek-bad: SUCCESS (0.003s) [273.908358] [135/171] (736s left) prime_self_import (basic-llseek-size) [274.047860] Starting subtest: basic-llseek-size [274.050376] Subtest basic-llseek-size: SUCCESS (0.004s) [274.158636] [136/171] (736s left) prime_self_import (basic-with_fd_dup) [274.296772] Starting subtest: basic-with_fd_dup [274.299199] Subtest basic-with_fd_dup: SUCCESS (0.004s) [274.391598] [137/171] (736s left) prime_self_import (basic-with_one_bo) [274.528201] Starting subtest: basic-with_one_bo [274.533507] Subtest basic-with_one_bo: SUCCESS (0.005s) [274.623162] [138/171] (735s left) prime_self_import (basic-with_one_bo_two_files) [274.758378] Starting subtest: basic-with_one_bo_two_files [274.763176] Subtest basic-with_one_bo_two_files: SUCCESS (0.004s) [274.858619] [139/171] (735s left) prime_self_import (basic-with_two_bos) [274.996059] Starting subtest: basic-with_two_bos [275.001051] Subtest basic-with_two_bos: SUCCESS (0.005s) [275.091925] [140/171] (735s left) prime_vgem (basic-fence-flip) [275.255663] Starting subtest: basic-fence-flip [275.736703] Subtest basic-fence-flip: SUCCESS (0.481s) [275.860979] [141/171] (734s left) prime_vgem (basic-fence-mmap) [276.022979] Starting subtest: basic-fence-mmap [276.071775] Subtest basic-fence-mmap: SUCCESS (0.049s) [276.161249] [142/171] (734s left) prime_vgem (basic-fence-read) [276.324228] Starting subtest: basic-fence-read [276.397308] Subtest basic-fence-read: SUCCESS (0.074s) [276.493039] [143/171] (734s left) prime_vgem (basic-gtt) [276.645391] Starting subtest: basic-gtt [276.668290] Subtest basic-gtt: SUCCESS (0.023s) [276.758785] [144/171] (733s left) prime_vgem (basic-read) [276.912427] Starting subtest: basic-read [276.947789] Subtest basic-read: SUCCESS (0.035s) [277.058847] [145/171] (733s left) prime_vgem (basic-write) [277.212067] Starting subtest: basic-write [277.253494] Subtest basic-write: SUCCESS (0.041s) [277.342045] [146/171] (733s left) vgem_basic (setversion) [277.488104] Starting subtest: setversion [277.490360] Subtest setversion: SUCCESS (0.000s) [277.558491] [147/171] (733s left) vgem_basic (create) [277.704668] Starting subtest: create [277.705975] Subtest create: SUCCESS (0.000s) [277.776679] [148/171] (732s left) vgem_basic (debugfs) [277.921582] Starting subtest: debugfs [277.922867] Subtest debugfs: SUCCESS (0.001s) [277.992107] [149/171] (732s left) vgem_basic (dmabuf-export) [278.138176] Starting subtest: dmabuf-export [278.140637] Subtest dmabuf-export: SUCCESS (0.003s) [278.241819] [150/171] (732s left) vgem_basic (dmabuf-fence) [278.388160] Starting subtest: dmabuf-fence [278.389381] Subtest dmabuf-fence: SUCCESS (0.001s) [278.458717] [151/171] (732s left) vgem_basic (dmabuf-fence-before) [278.607479] Starting subtest: dmabuf-fence-before [278.608642] Subtest dmabuf-fence-before: SUCCESS (0.001s) [278.676103] [152/171] (732s left) vgem_basic (dmabuf-mmap) [278.823831] Starting subtest: dmabuf-mmap [278.848298] Subtest dmabuf-mmap: SUCCESS (0.026s) [278.926055] [153/171] (731s left) vgem_basic (mmap) [279.073628] Starting subtest: mmap [279.089041] Subtest mmap: SUCCESS (0.017s) [279.158832] [154/171] (731s left) vgem_basic (second-client) [279.307599] Starting subtest: second-client [279.340564] Subtest second-client: SUCCESS (0.033s) [279.410139] [155/171] (731s left) vgem_basic (sysfs) [279.558125] Starting subtest: sysfs [279.559341] Subtest sysfs: SUCCESS (0.001s) [279.625642] [156/171] (731s left) core_hotunplug (unbind-rebind) [279.816166] Starting subtest: unbind-rebind [282.274324] Subtest unbind-rebind: SUCCESS (2.469s) [282.313131] [157/171] (728s left) vgem_basic (unload) [282.451436] Starting subtest: unload [311.430506] Subtest unload: SUCCESS (28.969s) [311.565231] [158/171] (699s left) i915_module_load (reload) [311.702594] Starting subtest: reload [315.183456] Subtest reload: SUCCESS (3.481s) [315.214864] [159/171] (695s left) gem_lmem_swapping (basic) [315.371339] Subtest basic: SKIP (0.000s) [315.417124] [160/171] (695s left) gem_lmem_swapping (parallel-random-engines) [315.562658] Subtest parallel-random-engines: SKIP (0.000s) [315.619336] [161/171] (695s left) gem_lmem_swapping (random-engines) [315.763272] Subtest random-engines: SKIP (0.000s) [315.816335] [162/171] (695s left) gem_lmem_swapping (verify-random) [315.961373] Subtest verify-random: SKIP (0.000s) [316.016888] [163/171] (695s left) i915_pm_rpm (module-reload) [328.528852] Starting subtest: module-reload [363.348655] Subtest module-reload: SUCCESS (34.812s) [363.373801] [164/171] (647s left) i915_selftest (live) [363.517678] Starting subtest: live [363.519062] Starting dynamic subtest: sanitycheck [365.338401] Dynamic subtest sanitycheck: SUCCESS (1.811s) [365.352893] Starting dynamic subtest: uncore [367.197353] Dynamic subtest uncore: SUCCESS (1.856s) [367.212156] Starting dynamic subtest: workarounds [369.254278] Dynamic subtest workarounds: SUCCESS (2.054s) [369.268953] Starting dynamic subtest: gt_engines [371.560504] Dynamic subtest gt_engines: SUCCESS (2.303s) [371.574834] Starting dynamic subtest: gt_timelines [384.041088] Dynamic subtest gt_timelines: SUCCESS (12.476s) [384.056124] Starting dynamic subtest: gt_contexts [385.969421] Dynamic subtest gt_contexts: SUCCESS (1.925s) [385.984211] Starting dynamic subtest: gt_lrc [390.392884] Dynamic subtest gt_lrc: SUCCESS (4.420s) [390.407540] Starting dynamic subtest: gt_mocs [392.298462] Dynamic subtest gt_mocs: SUCCESS (1.902s) [392.313068] Starting dynamic subtest: gt_pm [397.371517] Dynamic subtest gt_pm: SUCCESS (5.070s) [397.386241] Starting dynamic subtest: gt_heartbeat [399.326292] Dynamic subtest gt_heartbeat: SUCCESS (1.952s) [399.341133] Starting dynamic subtest: gt_tlb [402.478524] Dynamic subtest gt_tlb: SUCCESS (3.149s) [402.493046] Starting dynamic subtest: requests [410.086216] Dynamic subtest requests: SUCCESS (7.604s) [410.101049] Starting dynamic subtest: migrate [414.679858] Dynamic subtest migrate: SUCCESS (4.590s) [414.694614] Starting dynamic subtest: active [416.509596] Dynamic subtest active: SUCCESS (1.826s) [416.524133] Starting dynamic subtest: objects [419.948502] Dynamic subtest objects: SUCCESS (3.435s) [419.963378] Starting dynamic subtest: mman [426.887640] Dynamic subtest mman: SUCCESS (6.936s) [426.903126] Starting dynamic subtest: dmabuf [428.778747] Dynamic subtest dmabuf: SUCCESS (1.888s) [428.793907] Starting dynamic subtest: vma [430.610934] Dynamic subtest vma: SUCCESS (1.829s) [430.625833] Starting dynamic subtest: coherency [435.101477] Dynamic subtest coherency: SUCCESS (4.487s) [435.116440] Starting dynamic subtest: gtt [444.464040] Dynamic subtest gtt: SUCCESS (9.359s) [444.478941] Starting dynamic subtest: gem [446.435407] Dynamic subtest gem: SUCCESS (1.968s) [446.450537] Starting dynamic subtest: evict [450.226308] Dynamic subtest evict: SUCCESS (3.788s) [450.241419] Starting dynamic subtest: hugepages [457.072783] Dynamic subtest hugepages: SUCCESS (6.843s) [457.087635] Starting dynamic subtest: gem_contexts [472.300460] Dynamic subtest gem_contexts: SUCCESS (15.224s) [472.315768] Starting dynamic subtest: client [474.147607] Dynamic subtest client: SUCCESS (1.844s) [474.163067] Starting dynamic subtest: gem_migrate [476.000411] Dynamic subtest gem_migrate: SUCCESS (1.849s) [476.015722] Starting dynamic subtest: reset [481.371844] Dynamic subtest reset: SUCCESS (5.368s) [481.387257] Starting dynamic subtest: memory_region [483.181329] Dynamic subtest memory_region: SUCCESS (1.806s) [483.196993] Starting dynamic subtest: hangcheck [507.579658] Dynamic subtest hangcheck: SUCCESS (24.395s) [507.595158] Starting dynamic subtest: execlists [517.656165] Dynamic subtest execlists: SUCCESS (10.073s) [517.671733] Starting dynamic subtest: ring_submission [519.547621] Dynamic subtest ring_submission: SUCCESS (1.888s) [519.563019] Starting dynamic subtest: perf [521.447563] Dynamic subtest perf: SUCCESS (1.896s) [521.462827] Starting dynamic subtest: slpc [523.301451] Dynamic subtest slpc: SUCCESS (1.850s) [523.316627] Starting dynamic subtest: guc [525.157376] Dynamic subtest guc: SUCCESS (1.852s) [525.172916] Starting dynamic subtest: guc_multi_lrc [526.942481] Dynamic subtest guc_multi_lrc: SUCCESS (1.782s) [526.957627] Starting dynamic subtest: guc_hang [528.716499] Dynamic subtest guc_hang: SUCCESS (1.770s) [528.732258] Starting dynamic subtest: late_gt_pm [530.521419] Dynamic subtest late_gt_pm: SUCCESS (1.802s) [530.522906] Subtest live: SUCCESS (166.997s) [530.725130] [165/171] (480s left) dmabuf (all-tests) [530.865636] Starting subtest: all-tests [530.867248] Starting dynamic subtest: sanitycheck [530.929081] Dynamic subtest sanitycheck: SUCCESS (0.062s) [530.948116] Starting dynamic subtest: dma_fence [531.123246] Dynamic subtest dma_fence: SUCCESS (0.190s) [531.140889] Starting dynamic subtest: dma_fence_chain [549.047361] Dynamic subtest dma_fence_chain: SUCCESS (17.912s) [549.062962] Starting dynamic subtest: dma_fence_unwrap [549.110664] Dynamic subtest dma_fence_unwrap: SUCCESS (0.068s) [549.129559] Starting dynamic subtest: dma_resv [549.177946] Dynamic subtest dma_resv: SUCCESS (0.063s) [549.180193] Subtest all-tests: SUCCESS (18.313s) [549.211112] [166/171] (462s left) i915_suspend (basic-s2idle-without-i915) [549.347368] Starting subtest: basic-s2idle-without-i915 [572.885109] Subtest basic-s2idle-without-i915: SUCCESS (8.338s) [573.174157] [167/171] (453s left) i915_suspend (basic-s3-without-i915) [573.317811] Starting subtest: basic-s3-without-i915 [596.924088] Subtest basic-s3-without-i915: SUCCESS (7.163s) [597.205937] [168/171] (445s left) gem_exec_suspend (basic-s0) [597.360435] Starting subtest: basic-S0 [597.361724] Starting dynamic subtest: smem [617.036012] Dynamic subtest smem: SUCCESS (4.011s) [617.037187] Subtest basic-S0: SUCCESS (4.013s) [617.248027] [169/171] (441s left) gem_exec_suspend (basic-s3) [617.402637] Starting subtest: basic-S3 [617.403888] Starting dynamic subtest: smem [635.978292] Dynamic subtest smem: SUCCESS (3.821s) [635.979485] Subtest basic-S3: SUCCESS (3.822s) [636.132160] [170/171] (437s left) kms_chamelium_hpd (common-hpd-after-suspend) [636.609192] Subtest common-hpd-after-suspend: SKIP (0.000s) [637.504152] [171/171] (436s left) kms_pipe_crc_basic (suspend-read-crc) [637.664957] Starting subtest: suspend-read-crc [637.669058] Starting dynamic subtest: pipe-A-eDP-1 [655.937050] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.488s) [655.960136] Starting dynamic subtest: pipe-B-eDP-1 [673.890778] Dynamic subtest pipe-B-eDP-1: SUCCESS (3.132s) [673.914084] Starting dynamic subtest: pipe-C-eDP-1 [692.891614] Dynamic subtest pipe-C-eDP-1: SUCCESS (3.283s) [692.892913] Subtest suspend-read-crc: SUCCESS (8.920s) [693.792554] Closing watchdogs Done.