[63.926037] Initializing watchdogs [63.926218] /dev/watchdog0 [63.936888] [001/171] (960s left) i915_module_load (load) [64.254056] Starting subtest: load [66.791398] Subtest load: SUCCESS (2.538s) [66.817193] [002/171] (957s left) core_auth (basic-auth) [66.963732] Starting subtest: basic-auth [66.964942] Subtest basic-auth: SUCCESS (0.002s) [67.058849] [003/171] (957s left) debugfs_test (read_all_entries) [67.226798] Starting subtest: read_all_entries [67.309979] Subtest read_all_entries: SUCCESS (0.083s) [67.423303] [004/171] (957s left) debugfs_test (basic-hwmon) [67.566784] Starting subtest: basic-hwmon [67.569187] Subtest basic-hwmon: SKIP (0.000s) [67.686130] [005/171] (956s left) fbdev (eof) [67.844504] Starting subtest: eof [67.845904] Subtest eof: SUCCESS (0.000s) [67.907769] [006/171] (956s left) fbdev (info) [68.068306] Starting subtest: info [68.069561] Subtest info: SUCCESS (0.000s) [68.140699] [007/171] (956s left) fbdev (nullptr) [68.293321] Starting subtest: nullptr [68.295044] Subtest nullptr: SUCCESS (0.000s) [68.360410] [008/171] (956s left) fbdev (read) [68.508882] Starting subtest: read [75.600954] Subtest read: SUCCESS (7.057s) [75.641671] [009/171] (949s left) fbdev (write) [75.793732] Starting subtest: write [79.575038] Subtest write: SUCCESS (3.747s) [79.607842] [010/171] (945s left) gem_basic (bad-close) [79.744761] Starting subtest: bad-close [79.747122] Subtest bad-close: SUCCESS (0.000s) [79.823617] [011/171] (944s left) gem_basic (create-close) [79.962217] Starting subtest: create-close [79.969573] Subtest create-close: SUCCESS (0.000s) [80.057094] [012/171] (944s left) gem_basic (create-fd-close) [80.194546] Starting subtest: create-fd-close [80.196645] Subtest create-fd-close: SUCCESS (0.000s) [80.290364] [013/171] (944s left) gem_busy (busy@all-engines) [80.446126] Starting subtest: busy [80.447494] Starting dynamic subtest: all-engines [80.504411] Dynamic subtest all-engines: SUCCESS (0.058s) [80.505465] Subtest busy: SUCCESS (0.059s) [80.892171] [014/171] (943s left) gem_close_race (basic-process) [81.050098] Starting subtest: basic-process [81.123631] Subtest basic-process: SUCCESS (0.073s) [81.324964] [015/171] (943s left) gem_close_race (basic-threads) [81.483457] Starting subtest: basic-threads [82.645025] Subtest basic-threads: SUCCESS (1.162s) [82.870387] [016/171] (941s left) gem_ctx_create (basic) [83.041284] Starting subtest: basic [83.042573] Subtest basic: SUCCESS (0.000s) [83.242511] [017/171] (941s left) gem_ctx_create (basic-files) [83.394493] Starting subtest: basic-files [85.418424] Subtest basic-files: SUCCESS (2.024s) [85.641022] [018/171] (939s left) gem_ctx_exec (basic) [85.787279] Starting subtest: basic [85.791497] Subtest basic: SUCCESS (0.005s) [85.906904] [019/171] (938s left) gem_exec_basic (basic) [86.054218] Starting subtest: basic [86.055392] Starting dynamic subtest: rcs0-smem [86.056467] Dynamic subtest rcs0-smem: SUCCESS (0.002s) [86.067674] Starting dynamic subtest: bcs0-smem [86.068850] Dynamic subtest bcs0-smem: SUCCESS (0.001s) [86.078992] Starting dynamic subtest: vcs0-smem [86.080347] Dynamic subtest vcs0-smem: SUCCESS (0.001s) [86.090482] Starting dynamic subtest: vecs0-smem [86.091670] Dynamic subtest vecs0-smem: SUCCESS (0.001s) [86.092846] Subtest basic: SUCCESS (0.005s) [86.258198] [020/171] (938s left) gem_exec_create (basic) [86.409964] Starting subtest: basic [86.411203] Starting dynamic subtest: smem [88.464696] Dynamic subtest smem: SUCCESS (2.055s) [88.465862] Subtest basic: SUCCESS (2.055s) [88.687744] [021/171] (936s left) gem_exec_fence (basic-busy) [88.992607] Starting subtest: basic-busy [88.993891] Starting dynamic subtest: rcs0 [89.006337] Dynamic subtest rcs0: SUCCESS (0.022s) [89.017088] Starting dynamic subtest: bcs0 [89.031429] Dynamic subtest bcs0: SUCCESS (0.025s) [89.042206] Starting dynamic subtest: vcs0 [89.054893] Dynamic subtest vcs0: SUCCESS (0.023s) [89.065941] Starting dynamic subtest: vecs0 [89.085091] Dynamic subtest vecs0: SUCCESS (0.030s) [89.086366] Subtest basic-busy: SUCCESS (0.101s) [89.698830] [022/171] (935s left) gem_exec_fence (basic-wait) [90.004107] Starting subtest: basic-wait [90.005508] Starting dynamic subtest: rcs0 [90.017070] Dynamic subtest rcs0: SUCCESS (0.021s) [90.028517] Starting dynamic subtest: bcs0 [90.036307] Dynamic subtest bcs0: SUCCESS (0.019s) [90.047663] Starting dynamic subtest: vcs0 [90.063532] Dynamic subtest vcs0: SUCCESS (0.027s) [90.074895] Starting dynamic subtest: vecs0 [90.096829] Dynamic subtest vecs0: SUCCESS (0.033s) [90.098401] Subtest basic-wait: SUCCESS (0.101s) [90.642510] [023/171] (934s left) gem_exec_fence (basic-await) [90.943141] Starting subtest: basic-await [90.944237] Starting dynamic subtest: rcs0 [91.035361] Dynamic subtest rcs0: SUCCESS (0.098s) [91.059187] Starting dynamic subtest: bcs0 [91.147786] Dynamic subtest bcs0: SUCCESS (0.112s) [91.174971] Starting dynamic subtest: vcs0 [91.276840] Dynamic subtest vcs0: SUCCESS (0.129s) [91.303920] Starting dynamic subtest: vecs0 [91.407306] Dynamic subtest vecs0: SUCCESS (0.129s) [91.409237] Subtest basic-await: SUCCESS (0.471s) [91.976104] [024/171] (933s left) gem_exec_fence (nb-await) [92.280883] Starting subtest: nb-await [92.282080] Starting dynamic subtest: rcs0 [92.339082] Dynamic subtest rcs0: SUCCESS (0.066s) [92.363898] Starting dynamic subtest: bcs0 [92.413074] Dynamic subtest bcs0: SUCCESS (0.074s) [92.439200] Starting dynamic subtest: vcs0 [92.488160] Dynamic subtest vcs0: SUCCESS (0.074s) [92.517068] Starting dynamic subtest: vecs0 [92.567740] Dynamic subtest vecs0: SUCCESS (0.078s) [92.569850] Subtest nb-await: SUCCESS (0.294s) [93.159112] [025/171] (931s left) gem_exec_gttfill (basic) [93.312720] Starting subtest: basic [101.197507] Subtest basic: SUCCESS (7.876s) [101.384585] [026/171] (923s left) gem_exec_parallel (engines) [101.606583] Starting subtest: engines [101.607872] Starting dynamic subtest: basic [104.142818] Dynamic subtest basic: SUCCESS (2.515s) [104.167584] Starting dynamic subtest: contexts [107.652764] Dynamic subtest contexts: SUCCESS (3.521s) [107.666129] Starting dynamic subtest: fds [111.621555] Dynamic subtest fds: SUCCESS (3.977s) [111.635360] Starting dynamic subtest: userptr [114.179486] Dynamic subtest userptr: SUCCESS (2.534s) [114.181448] Subtest engines: SUCCESS (12.549s) [114.373669] [027/171] (910s left) gem_exec_store (basic) [114.520360] Starting subtest: basic [114.577164] Subtest basic: SUCCESS (0.056s) [114.778073] [028/171] (910s left) gem_flink_basic (bad-flink) [114.913095] Starting subtest: bad-flink [114.914385] Subtest bad-flink: SUCCESS (0.000s) [114.993901] [029/171] (910s left) gem_flink_basic (bad-open) [115.130728] Starting subtest: bad-open [115.132020] Subtest bad-open: SUCCESS (0.000s) [115.210616] [030/171] (909s left) gem_flink_basic (basic) [115.347822] Starting subtest: basic [115.349089] Subtest basic: SUCCESS (0.000s) [115.444320] [031/171] (909s left) gem_flink_basic (double-flink) [115.581537] Starting subtest: double-flink [115.582772] Subtest double-flink: SUCCESS (0.000s) [115.693701] [032/171] (909s left) gem_flink_basic (flink-lifetime) [115.831530] Starting subtest: flink-lifetime [115.834104] Subtest flink-lifetime: SUCCESS (0.002s) [115.943616] [033/171] (909s left) gem_huc_copy (huc-copy) [116.087738] Starting subtest: huc-copy [116.090051] Subtest huc-copy: SKIP (0.000s) [116.210314] [034/171] (909s left) gem_linear_blits (basic) [116.507974] Starting subtest: basic [116.519902] Subtest basic: SUCCESS (0.012s) [116.662983] [035/171] (908s left) gem_mmap (basic) [116.862655] Starting subtest: basic [116.867926] Subtest basic: SUCCESS (0.001s) [116.960295] [036/171] (908s left) gem_mmap_gtt (basic) [117.090806] Starting subtest: basic [117.092115] Subtest basic: SUCCESS (0.001s) [117.176995] [037/171] (908s left) gem_render_linear_blits (basic) [117.318019] Starting subtest: basic [117.368180] Subtest basic: SUCCESS (0.049s) [117.426104] [038/171] (907s left) gem_render_tiled_blits (basic) [117.570545] Starting subtest: basic [117.629911] Subtest basic: SUCCESS (0.059s) [117.695698] [039/171] (907s left) gem_ringfill (basic-all) [117.981041] Starting subtest: basic-all [119.217644] Subtest basic-all: SUCCESS (1.229s) [119.296049] [040/171] (906s left) gem_softpin (allocator-basic) [119.432846] Starting subtest: allocator-basic [119.606105] Subtest allocator-basic: SUCCESS (0.165s) [119.711578] [041/171] (905s left) gem_softpin (allocator-basic-reserve) [119.865484] Starting subtest: allocator-basic-reserve [120.027652] Subtest allocator-basic-reserve: SUCCESS (0.161s) [120.128631] [042/171] (905s left) gem_softpin (safe-alignment) [120.273093] Starting subtest: safe-alignment [120.293525] Subtest safe-alignment: SUCCESS (0.020s) [120.410631] [043/171] (905s left) gem_sync (basic-all) [120.557715] Starting subtest: basic-all [122.778997] Subtest basic-all: SUCCESS (2.195s) [122.962399] [044/171] (902s left) gem_sync (basic-each) [123.117240] Starting subtest: basic-each [125.310063] Subtest basic-each: SUCCESS (2.193s) [125.553093] [045/171] (900s left) gem_tiled_blits (basic) [125.839971] Starting subtest: basic [125.867869] Subtest basic: SUCCESS (0.028s) [125.995516] [046/171] (899s left) gem_tiled_fence_blits (basic) [126.357338] Starting subtest: basic [126.384695] Subtest basic: SUCCESS (0.028s) [126.509150] [047/171] (899s left) gem_tiled_pread_basic [127.045552] [048/171] (898s left) gem_wait (busy@all-engines) [127.201417] Starting subtest: busy [127.202667] Starting dynamic subtest: all-engines [127.737581] Dynamic subtest all-engines: SUCCESS (0.536s) [127.738694] Subtest busy: SUCCESS (0.537s) [127.998183] [049/171] (897s left) gem_wait (wait@all-engines) [128.158985] Starting subtest: wait [128.160248] Starting dynamic subtest: all-engines [129.199307] Dynamic subtest all-engines: SUCCESS (1.038s) [129.200355] Subtest wait: SUCCESS (1.038s) [129.429509] [050/171] (896s left) i915_getparams_basic (basic-eu-total) [129.565660] Starting subtest: basic-eu-total [129.568139] Subtest basic-eu-total: SUCCESS (0.000s) [129.644063] [051/171] (896s left) i915_getparams_basic (basic-subslice-total) [129.782450] Starting subtest: basic-subslice-total [129.784628] Subtest basic-subslice-total: SUCCESS (0.000s) [129.860756] [052/171] (895s left) i915_hangman (error-state-basic) [130.014971] Starting subtest: error-state-basic [130.025414] Subtest error-state-basic: SUCCESS (0.010s) [130.111307] [053/171] (895s left) i915_pciid [130.327637] [054/171] (895s left) kms_addfb_basic (addfb25-bad-modifier) [130.458797] Starting subtest: addfb25-bad-modifier [130.460153] Subtest addfb25-bad-modifier: SUCCESS (0.000s) [130.593322] [055/171] (895s left) kms_addfb_basic (addfb25-framebuffer-vs-set-tiling) [130.731405] Starting subtest: addfb25-framebuffer-vs-set-tiling [130.732474] Subtest addfb25-framebuffer-vs-set-tiling: SUCCESS (0.000s) [130.860882] [056/171] (895s left) kms_addfb_basic (addfb25-modifier-no-flag) [131.005171] Starting subtest: addfb25-modifier-no-flag [131.006260] Subtest addfb25-modifier-no-flag: SUCCESS (0.000s) [131.127493] [057/171] (894s left) kms_addfb_basic (addfb25-x-tiled-legacy) [131.265929] Starting subtest: addfb25-x-tiled-legacy [131.267117] Subtest addfb25-x-tiled-legacy: SUCCESS (0.000s) [131.413329] [058/171] (894s left) kms_addfb_basic (addfb25-x-tiled-mismatch-legacy) [131.558450] Starting subtest: addfb25-x-tiled-mismatch-legacy [131.559560] Subtest addfb25-x-tiled-mismatch-legacy: SUCCESS (0.000s) [131.677289] [059/171] (894s left) kms_addfb_basic (addfb25-yf-tiled-legacy) [131.843417] Starting subtest: addfb25-yf-tiled-legacy [131.844609] Subtest addfb25-yf-tiled-legacy: SUCCESS (0.000s) [131.943872] [060/171] (894s left) kms_addfb_basic (addfb25-y-tiled-legacy) [132.109955] Starting subtest: addfb25-y-tiled-legacy [132.111255] Subtest addfb25-y-tiled-legacy: SUCCESS (0.000s) [132.210900] [061/171] (893s left) kms_addfb_basic (addfb25-y-tiled-small-legacy) [132.377959] Starting subtest: addfb25-y-tiled-small-legacy [132.379137] Subtest addfb25-y-tiled-small-legacy: SUCCESS (0.000s) [132.477417] [062/171] (893s left) kms_addfb_basic (bad-pitch-0) [132.616349] Starting subtest: bad-pitch-0 [132.617419] Subtest bad-pitch-0: SUCCESS (0.000s) [132.743774] [063/171] (893s left) kms_addfb_basic (bad-pitch-1024) [132.881551] Starting subtest: bad-pitch-1024 [132.882760] Subtest bad-pitch-1024: SUCCESS (0.000s) [133.011130] [064/171] (893s left) kms_addfb_basic (bad-pitch-128) [133.149207] Starting subtest: bad-pitch-128 [133.150333] Subtest bad-pitch-128: SUCCESS (0.000s) [133.278354] [065/171] (892s left) kms_addfb_basic (bad-pitch-256) [133.418475] Starting subtest: bad-pitch-256 [133.419561] Subtest bad-pitch-256: SUCCESS (0.000s) [133.543516] [066/171] (892s left) kms_addfb_basic (bad-pitch-32) [133.679911] Starting subtest: bad-pitch-32 [133.681364] Subtest bad-pitch-32: SUCCESS (0.000s) [133.812225] [067/171] (892s left) kms_addfb_basic (bad-pitch-63) [133.948585] Starting subtest: bad-pitch-63 [133.949769] Subtest bad-pitch-63: SUCCESS (0.000s) [134.077385] [068/171] (892s left) kms_addfb_basic (bad-pitch-65536) [134.215483] Starting subtest: bad-pitch-65536 [134.216587] Subtest bad-pitch-65536: SUCCESS (0.000s) [134.345527] [069/171] (891s left) kms_addfb_basic (bad-pitch-999) [134.481887] Starting subtest: bad-pitch-999 [134.483128] Subtest bad-pitch-999: SUCCESS (0.000s) [134.610307] [070/171] (891s left) kms_addfb_basic (basic) [134.748344] Starting subtest: basic [134.749405] Subtest basic: SUCCESS (0.000s) [134.876893] [071/171] (891s left) kms_addfb_basic (basic-x-tiled-legacy) [135.013537] Starting subtest: basic-x-tiled-legacy [135.014901] Subtest basic-x-tiled-legacy: SUCCESS (0.000s) [135.143305] [072/171] (891s left) kms_addfb_basic (basic-y-tiled-legacy) [135.282948] Starting subtest: basic-y-tiled-legacy [135.284159] Subtest basic-y-tiled-legacy: SUCCESS (0.000s) [135.410385] [073/171] (890s left) kms_addfb_basic (bo-too-small) [135.577228] Starting subtest: bo-too-small [135.578346] Subtest bo-too-small: SUCCESS (0.000s) [135.677323] [074/171] (890s left) kms_addfb_basic (bo-too-small-due-to-tiling) [135.841989] Starting subtest: bo-too-small-due-to-tiling [135.843306] Subtest bo-too-small-due-to-tiling: SUCCESS (0.000s) [135.943348] [075/171] (890s left) kms_addfb_basic (clobberred-modifier) [136.080660] Starting subtest: clobberred-modifier [136.081831] Subtest clobberred-modifier: SUCCESS (0.000s) [136.210432] [076/171] (890s left) kms_addfb_basic (framebuffer-vs-set-tiling) [136.350365] Starting subtest: framebuffer-vs-set-tiling [136.351450] Subtest framebuffer-vs-set-tiling: SUCCESS (0.000s) [136.477735] [077/171] (889s left) kms_addfb_basic (invalid-get-prop) [136.614678] Starting subtest: invalid-get-prop [136.615843] Subtest invalid-get-prop: SUCCESS (0.000s) [136.743904] [078/171] (889s left) kms_addfb_basic (invalid-get-prop-any) [136.881622] Starting subtest: invalid-get-prop-any [136.882795] Subtest invalid-get-prop-any: SUCCESS (0.000s) [137.010413] [079/171] (889s left) kms_addfb_basic (invalid-set-prop) [137.148410] Starting subtest: invalid-set-prop [137.149479] Subtest invalid-set-prop: SUCCESS (0.000s) [137.276655] [080/171] (889s left) kms_addfb_basic (invalid-set-prop-any) [137.415108] Starting subtest: invalid-set-prop-any [137.416193] Subtest invalid-set-prop-any: SUCCESS (0.000s) [137.544144] [081/171] (889s left) kms_addfb_basic (no-handle) [137.683148] Starting subtest: no-handle [137.684241] Subtest no-handle: SUCCESS (0.000s) [137.810765] [082/171] (888s left) kms_addfb_basic (size-max) [137.977890] Starting subtest: size-max [137.979147] Subtest size-max: SUCCESS (0.000s) [138.077032] [083/171] (888s left) kms_addfb_basic (small-bo) [138.243696] Starting subtest: small-bo [138.244836] Subtest small-bo: SUCCESS (0.000s) [138.343560] [084/171] (888s left) kms_addfb_basic (tile-pitch-mismatch) [138.481517] Starting subtest: tile-pitch-mismatch [138.482623] Subtest tile-pitch-mismatch: SUCCESS (0.000s) [138.610627] [085/171] (888s left) kms_addfb_basic (too-high) [138.776060] Starting subtest: too-high [138.777361] Subtest too-high: SUCCESS (0.001s) [138.876784] [086/171] (887s left) kms_addfb_basic (too-wide) [139.043403] Starting subtest: too-wide [139.044606] Subtest too-wide: SUCCESS (0.000s) [139.143649] [087/171] (887s left) kms_addfb_basic (unused-handle) [139.280618] Starting subtest: unused-handle [139.282090] Subtest unused-handle: SUCCESS (0.000s) [139.410373] [088/171] (887s left) kms_addfb_basic (unused-modifier) [139.548650] Starting subtest: unused-modifier [139.549809] Subtest unused-modifier: SUCCESS (0.000s) [139.677079] [089/171] (887s left) kms_addfb_basic (unused-offsets) [139.815835] Starting subtest: unused-offsets [139.816988] Subtest unused-offsets: SUCCESS (0.000s) [139.943769] [090/171] (886s left) kms_addfb_basic (unused-pitches) [140.082401] Starting subtest: unused-pitches [140.083547] Subtest unused-pitches: SUCCESS (0.000s) [140.210604] [091/171] (886s left) kms_busy (basic) [140.375679] Starting subtest: basic [140.378353] Starting dynamic subtest: flip [141.362513] Dynamic subtest flip: SUCCESS (0.985s) [141.378463] Starting dynamic subtest: modeset [145.263785] Dynamic subtest modeset: SUCCESS (3.901s) [145.264967] Subtest basic: SUCCESS (4.889s) [146.236189] [092/171] (880s left) kms_chamelium_hpd (dp-hpd-fast) [146.716376] Subtest dp-hpd-fast: SKIP (0.000s) [147.593929] [093/171] (879s left) kms_chamelium_edid (dp-edid-read) [148.075340] Subtest dp-edid-read: SKIP (0.000s) [148.937456] [094/171] (877s left) kms_chamelium_frames (dp-crc-fast) [149.419129] Subtest dp-crc-fast: SKIP (0.000s) [150.279290] [095/171] (876s left) kms_chamelium_hpd (hdmi-hpd-fast) [150.759020] Subtest hdmi-hpd-fast: SKIP (0.000s) [151.624619] [096/171] (875s left) kms_chamelium_edid (hdmi-edid-read) [152.108076] Subtest hdmi-edid-read: SKIP (0.000s) [152.989405] [097/171] (874s left) kms_chamelium_frames (hdmi-crc-fast) [153.472039] Subtest hdmi-crc-fast: SKIP (0.000s) [154.377224] [098/171] (872s left) kms_chamelium_hpd (vga-hpd-fast) [154.856882] Subtest vga-hpd-fast: SKIP (0.000s) [155.722455] [099/171] (871s left) kms_chamelium_edid (vga-edid-read) [156.204741] Subtest vga-edid-read: SKIP (0.000s) [157.066578] [100/171] (870s left) kms_prop_blob (basic) [157.195823] Starting subtest: basic [157.197257] Subtest basic: SUCCESS (0.000s) [157.287146] [101/171] (869s left) kms_cursor_legacy (basic-busy-flip-before-cursor-atomic) [157.446853] Starting subtest: basic-busy-flip-before-cursor-atomic [157.449068] Subtest basic-busy-flip-before-cursor-atomic: SKIP (0.000s) [157.532159] [102/171] (869s left) kms_cursor_legacy (basic-busy-flip-before-cursor-legacy) [157.697546] Starting subtest: basic-busy-flip-before-cursor-legacy [157.700280] Subtest basic-busy-flip-before-cursor-legacy: SKIP (0.000s) [157.781671] [103/171] (869s left) kms_cursor_legacy (basic-flip-after-cursor-atomic) [157.949440] Starting subtest: basic-flip-after-cursor-atomic [159.233664] Subtest basic-flip-after-cursor-atomic: SUCCESS (1.284s) [160.186716] [104/171] (867s left) kms_cursor_legacy (basic-flip-after-cursor-legacy) [160.352586] Starting subtest: basic-flip-after-cursor-legacy [161.646429] Subtest basic-flip-after-cursor-legacy: SUCCESS (1.294s) [162.620995] [105/171] (864s left) kms_cursor_legacy (basic-flip-after-cursor-varying-size) [162.786651] Starting subtest: basic-flip-after-cursor-varying-size [164.088255] Subtest basic-flip-after-cursor-varying-size: SUCCESS (1.295s) [165.050999] [106/171] (862s left) kms_cursor_legacy (basic-flip-before-cursor-atomic) [165.218070] Starting subtest: basic-flip-before-cursor-atomic [166.510702] Subtest basic-flip-before-cursor-atomic: SUCCESS (1.293s) [167.484251] [107/171] (859s left) kms_cursor_legacy (basic-flip-before-cursor-legacy) [167.650789] Starting subtest: basic-flip-before-cursor-legacy [168.949046] Subtest basic-flip-before-cursor-legacy: SUCCESS (1.291s) [169.915125] [108/171] (857s left) kms_cursor_legacy (basic-flip-before-cursor-varying-size) [170.081245] Starting subtest: basic-flip-before-cursor-varying-size [171.364175] Subtest basic-flip-before-cursor-varying-size: SUCCESS (1.282s) [172.283275] [109/171] (855s left) kms_flip (basic-flip-vs-dpms) [172.422796] Starting subtest: basic-flip-vs-dpms [172.425892] Starting dynamic subtest: A-eDP1 [175.287252] Dynamic subtest A-eDP1: SUCCESS (2.862s) [175.303770] Starting dynamic subtest: B-eDP1 [177.641519] Dynamic subtest B-eDP1: SUCCESS (2.352s) [177.657441] Starting dynamic subtest: C-eDP1 [180.007540] Dynamic subtest C-eDP1: SUCCESS (2.366s) [180.011126] Subtest basic-flip-vs-dpms: SUCCESS (7.588s) [180.925028] [110/171] (846s left) kms_flip (basic-flip-vs-modeset) [181.072934] Starting subtest: basic-flip-vs-modeset [181.076213] Starting dynamic subtest: A-eDP1 [183.920480] Dynamic subtest A-eDP1: SUCCESS (2.843s) [183.936706] Starting dynamic subtest: B-eDP1 [186.272953] Dynamic subtest B-eDP1: SUCCESS (2.351s) [186.289096] Starting dynamic subtest: C-eDP1 [188.654080] Dynamic subtest C-eDP1: SUCCESS (2.371s) [188.655362] Subtest basic-flip-vs-modeset: SUCCESS (7.574s) [189.563635] [111/171] (837s left) kms_flip (basic-flip-vs-wf_vblank) [189.710952] Starting subtest: basic-flip-vs-wf_vblank [189.714236] Starting dynamic subtest: A-eDP1 [192.027307] Dynamic subtest A-eDP1: SUCCESS (2.314s) [192.043500] Starting dynamic subtest: B-eDP1 [194.250432] Dynamic subtest B-eDP1: SUCCESS (2.222s) [194.266851] Starting dynamic subtest: C-eDP1 [196.489984] Dynamic subtest C-eDP1: SUCCESS (2.238s) [196.493116] Subtest basic-flip-vs-wf_vblank: SUCCESS (6.784s) [197.820636] [112/171] (829s left) kms_flip (basic-plain-flip) [197.965400] Starting subtest: basic-plain-flip [197.969284] Starting dynamic subtest: A-eDP1 [200.001078] Dynamic subtest A-eDP1: SUCCESS (2.032s) [200.017199] Starting dynamic subtest: B-eDP1 [201.967900] Dynamic subtest B-eDP1: SUCCESS (1.965s) [201.984295] Starting dynamic subtest: C-eDP1 [203.954165] Dynamic subtest C-eDP1: SUCCESS (1.985s) [203.957155] Subtest basic-plain-flip: SUCCESS (5.992s) [205.247072] [113/171] (822s left) kms_force_connector_basic (force-connector-state) [205.383408] Starting subtest: force-connector-state [207.085802] Subtest force-connector-state: SUCCESS (1.701s) [207.693339] [114/171] (819s left) kms_force_connector_basic (force-edid) [207.836476] Starting subtest: force-edid [208.092203] Subtest force-edid: SUCCESS (0.257s) [208.226266] [115/171] (819s left) kms_force_connector_basic (force-load-detect) [208.369835] Starting subtest: force-load-detect [208.372155] Subtest force-load-detect: SKIP (0.000s) [208.476326] [116/171] (819s left) kms_force_connector_basic (prune-stale-modes) [208.620587] Starting subtest: prune-stale-modes [208.925690] Subtest prune-stale-modes: SUCCESS (0.305s) [209.059839] [117/171] (818s left) kms_frontbuffer_tracking (basic) [209.226273] Starting subtest: basic [212.541643] Subtest basic: SUCCESS (3.315s) [212.661310] [118/171] (814s left) kms_pipe_crc_basic (compare-crc-sanitycheck-xr24) [212.824669] Starting subtest: compare-crc-sanitycheck-xr24 [212.826524] Starting dynamic subtest: pipe-A-eDP-1 [213.508072] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.680s) [213.527017] Starting dynamic subtest: pipe-B-eDP-1 [214.902358] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.378s) [214.917624] Starting dynamic subtest: pipe-C-eDP-1 [216.472334] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.566s) [216.473646] Subtest compare-crc-sanitycheck-xr24: SUCCESS (3.638s) [217.398277] [119/171] (810s left) kms_pipe_crc_basic (compare-crc-sanitycheck-nv12) [217.568443] Starting subtest: compare-crc-sanitycheck-nv12 [217.571659] Starting dynamic subtest: pipe-A-eDP-1 [218.517240] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.937s) [218.532292] Starting dynamic subtest: pipe-B-eDP-1 [220.001242] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.478s) [220.016510] Starting dynamic subtest: pipe-C-eDP-1 [221.753838] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.756s) [221.762074] Subtest compare-crc-sanitycheck-nv12: SUCCESS (4.186s) [222.712477] [120/171] (804s left) kms_pipe_crc_basic (hang-read-crc) [222.881419] Starting subtest: hang-read-crc [222.884544] Starting dynamic subtest: pipe-A-eDP-1 [225.191675] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.307s) [225.210477] Starting dynamic subtest: pipe-B-eDP-1 [228.173552] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.976s) [228.191580] Starting dynamic subtest: pipe-C-eDP-1 [231.553716] Dynamic subtest pipe-C-eDP-1: SUCCESS (3.375s) [231.554990] Subtest hang-read-crc: SUCCESS (8.672s) [232.502958] [121/171] (795s left) kms_pipe_crc_basic (nonblocking-crc) [232.675625] Starting subtest: nonblocking-crc [232.679094] Starting dynamic subtest: pipe-A-eDP-1 [233.545370] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.864s) [233.563239] Starting dynamic subtest: pipe-B-eDP-1 [235.094848] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.546s) [235.115755] Starting dynamic subtest: pipe-C-eDP-1 [236.806370] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.704s) [236.807578] Subtest nonblocking-crc: SUCCESS (4.130s) [237.750610] [122/171] (789s left) kms_pipe_crc_basic (nonblocking-crc-frame-sequence) [237.920236] Starting subtest: nonblocking-crc-frame-sequence [237.928898] Starting dynamic subtest: pipe-A-eDP-1 [238.776685] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.852s) [238.796929] Starting dynamic subtest: pipe-B-eDP-1 [240.329233] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.548s) [240.348326] Starting dynamic subtest: pipe-C-eDP-1 [242.040067] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.705s) [242.041404] Subtest nonblocking-crc-frame-sequence: SUCCESS (4.120s) [242.998591] [123/171] (784s left) kms_pipe_crc_basic (read-crc) [243.168791] Starting subtest: read-crc [243.172264] Starting dynamic subtest: pipe-A-eDP-1 [243.955541] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.783s) [243.975071] Starting dynamic subtest: pipe-B-eDP-1 [245.447863] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.486s) [245.466186] Starting dynamic subtest: pipe-C-eDP-1 [247.086087] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.633s) [247.087350] Subtest read-crc: SUCCESS (3.917s) [248.054618] [124/171] (779s left) kms_pipe_crc_basic (read-crc-frame-sequence) [248.221726] Starting subtest: read-crc-frame-sequence [248.224925] Starting dynamic subtest: pipe-A-eDP-1 [249.021439] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.794s) [249.040391] Starting dynamic subtest: pipe-B-eDP-1 [250.509915] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.485s) [250.529563] Starting dynamic subtest: pipe-C-eDP-1 [252.149069] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.632s) [252.150336] Subtest read-crc-frame-sequence: SUCCESS (3.926s) [253.109705] [125/171] (774s left) kms_psr (primary_page_flip) [253.283511] Starting subtest: primary_page_flip [257.548524] Subtest primary_page_flip: SUCCESS (4.251s) [258.487043] [126/171] (769s left) kms_psr (cursor_plane_move) [258.660183] Starting subtest: cursor_plane_move [262.850813] Subtest cursor_plane_move: SUCCESS (4.190s) [263.799509] [127/171] (764s left) kms_psr (sprite_plane_onoff) [263.969145] Starting subtest: sprite_plane_onoff [268.219679] Subtest sprite_plane_onoff: SUCCESS (4.250s) [269.174464] [128/171] (758s left) kms_psr (primary_mmap_gtt) [269.346660] Starting subtest: primary_mmap_gtt [273.576082] Subtest primary_mmap_gtt: SUCCESS (4.228s) [274.505887] [129/171] (753s left) kms_setmode (basic-clone-single-crtc) [274.648702] Starting subtest: basic-clone-single-crtc [274.652412] Subtest basic-clone-single-crtc: SKIP (0.000s) [274.738855] [130/171] (753s left) i915_pm_backlight (basic-brightness) [274.909213] Starting subtest: basic-brightness [275.030105] Starting dynamic subtest: eDP-1 [275.583803] Dynamic subtest eDP-1: SUCCESS (0.561s) [275.585466] Subtest basic-brightness: SUCCESS (0.675s) [276.535962] [131/171] (751s left) i915_pm_rpm (basic-pci-d3-state) [278.425209] Starting subtest: basic-pci-d3-state [282.834338] Subtest basic-pci-d3-state: SUCCESS (4.408s) [283.036763] [132/171] (744s left) i915_pm_rpm (basic-rte) [283.168925] Starting subtest: basic-rte [289.297463] Subtest basic-rte: SUCCESS (6.129s) [290.765023] [133/171] (737s left) i915_pm_rps (basic-api) [290.903362] Starting subtest: basic-api [290.907646] Subtest basic-api: SUCCESS (0.004s) [290.997361] [134/171] (737s left) prime_self_import (basic-llseek-bad) [291.134112] Starting subtest: basic-llseek-bad [291.139245] Subtest basic-llseek-bad: SUCCESS (0.003s) [291.247061] [135/171] (736s left) prime_self_import (basic-llseek-size) [291.382300] Starting subtest: basic-llseek-size [291.387142] Subtest basic-llseek-size: SUCCESS (0.004s) [291.480570] [136/171] (736s left) prime_self_import (basic-with_fd_dup) [291.617356] Starting subtest: basic-with_fd_dup [291.622313] Subtest basic-with_fd_dup: SUCCESS (0.005s) [291.713632] [137/171] (736s left) prime_self_import (basic-with_one_bo) [291.849294] Starting subtest: basic-with_one_bo [291.854454] Subtest basic-with_one_bo: SUCCESS (0.005s) [291.945695] [138/171] (736s left) prime_self_import (basic-with_one_bo_two_files) [292.081759] Starting subtest: basic-with_one_bo_two_files [292.086279] Subtest basic-with_one_bo_two_files: SUCCESS (0.004s) [292.196936] [139/171] (736s left) prime_self_import (basic-with_two_bos) [292.335334] Starting subtest: basic-with_two_bos [292.339884] Subtest basic-with_two_bos: SUCCESS (0.005s) [292.430227] [140/171] (735s left) prime_vgem (basic-fence-flip) [292.584701] Starting subtest: basic-fence-flip [293.059084] Subtest basic-fence-flip: SUCCESS (0.474s) [293.181623] [141/171] (735s left) prime_vgem (basic-fence-mmap) [293.334771] Starting subtest: basic-fence-mmap [293.382270] Subtest basic-fence-mmap: SUCCESS (0.047s) [293.481564] [142/171] (734s left) prime_vgem (basic-fence-read) [293.643801] Starting subtest: basic-fence-read [293.717245] Subtest basic-fence-read: SUCCESS (0.073s) [293.798068] [143/171] (734s left) prime_vgem (basic-gtt) [293.951132] Starting subtest: basic-gtt [293.974424] Subtest basic-gtt: SUCCESS (0.023s) [294.081950] [144/171] (734s left) prime_vgem (basic-read) [294.245459] Starting subtest: basic-read [294.281054] Subtest basic-read: SUCCESS (0.036s) [294.380587] [145/171] (734s left) prime_vgem (basic-write) [294.544488] Starting subtest: basic-write [294.587591] Subtest basic-write: SUCCESS (0.043s) [294.680602] [146/171] (733s left) vgem_basic (setversion) [294.827050] Starting subtest: setversion [294.829417] Subtest setversion: SUCCESS (0.000s) [294.897122] [147/171] (733s left) vgem_basic (create) [295.044297] Starting subtest: create [295.045593] Subtest create: SUCCESS (0.000s) [295.114255] [148/171] (733s left) vgem_basic (debugfs) [295.260349] Starting subtest: debugfs [295.261557] Subtest debugfs: SUCCESS (0.001s) [295.336795] [149/171] (733s left) vgem_basic (dmabuf-export) [295.476892] Starting subtest: dmabuf-export [295.481612] Subtest dmabuf-export: SUCCESS (0.003s) [295.585141] [150/171] (732s left) vgem_basic (dmabuf-fence) [295.727052] Starting subtest: dmabuf-fence [295.728306] Subtest dmabuf-fence: SUCCESS (0.001s) [295.800481] [151/171] (732s left) vgem_basic (dmabuf-fence-before) [295.945860] Starting subtest: dmabuf-fence-before [295.947120] Subtest dmabuf-fence-before: SUCCESS (0.001s) [296.014813] [152/171] (732s left) vgem_basic (dmabuf-mmap) [296.162590] Starting subtest: dmabuf-mmap [296.187455] Subtest dmabuf-mmap: SUCCESS (0.026s) [296.264666] [153/171] (732s left) vgem_basic (mmap) [296.412628] Starting subtest: mmap [296.428157] Subtest mmap: SUCCESS (0.017s) [296.497932] [154/171] (732s left) vgem_basic (second-client) [296.646442] Starting subtest: second-client [296.679457] Subtest second-client: SUCCESS (0.033s) [296.748690] [155/171] (731s left) vgem_basic (sysfs) [296.896624] Starting subtest: sysfs [296.897744] Subtest sysfs: SUCCESS (0.001s) [296.964467] [156/171] (731s left) core_hotunplug (unbind-rebind) [297.145268] Starting subtest: unbind-rebind [299.589151] Subtest unbind-rebind: SUCCESS (2.443s) [299.625653] [157/171] (729s left) vgem_basic (unload) [299.765355] Starting subtest: unload [328.762033] Subtest unload: SUCCESS (28.986s) [328.894695] [158/171] (699s left) i915_module_load (reload) [329.023311] Starting subtest: reload [332.529903] Subtest reload: SUCCESS (3.506s) [332.561482] [159/171] (696s left) gem_lmem_swapping (basic) [332.719427] Subtest basic: SKIP (0.000s) [332.764059] [160/171] (696s left) gem_lmem_swapping (parallel-random-engines) [332.907479] Subtest parallel-random-engines: SKIP (0.000s) [332.974050] [161/171] (695s left) gem_lmem_swapping (random-engines) [333.117743] Subtest random-engines: SKIP (0.000s) [333.163347] [162/171] (695s left) gem_lmem_swapping (verify-random) [333.307406] Subtest verify-random: SKIP (0.000s) [333.346957] [163/171] (695s left) i915_pm_rpm (module-reload) [345.851422] Starting subtest: module-reload [380.742655] Subtest module-reload: SUCCESS (34.882s) [380.768396] [164/171] (648s left) i915_selftest (live) [380.913557] Starting subtest: live [380.915128] Starting dynamic subtest: sanitycheck [382.732383] Dynamic subtest sanitycheck: SUCCESS (1.811s) [382.746496] Starting dynamic subtest: uncore [384.546507] Dynamic subtest uncore: SUCCESS (1.810s) [384.560711] Starting dynamic subtest: workarounds [386.597254] Dynamic subtest workarounds: SUCCESS (2.047s) [386.611525] Starting dynamic subtest: gt_engines [388.878064] Dynamic subtest gt_engines: SUCCESS (2.277s) [388.892188] Starting dynamic subtest: gt_timelines [401.402602] Dynamic subtest gt_timelines: SUCCESS (12.522s) [401.416673] Starting dynamic subtest: gt_contexts [403.286613] Dynamic subtest gt_contexts: SUCCESS (1.880s) [403.300556] Starting dynamic subtest: gt_lrc [407.672675] Dynamic subtest gt_lrc: SUCCESS (4.382s) [407.686614] Starting dynamic subtest: gt_mocs [409.554767] Dynamic subtest gt_mocs: SUCCESS (1.878s) [409.569186] Starting dynamic subtest: gt_pm [414.633473] Dynamic subtest gt_pm: SUCCESS (5.075s) [414.647471] Starting dynamic subtest: gt_heartbeat [416.549531] Dynamic subtest gt_heartbeat: SUCCESS (1.912s) [416.563844] Starting dynamic subtest: gt_tlb [419.631510] Dynamic subtest gt_tlb: SUCCESS (3.079s) [419.645684] Starting dynamic subtest: requests [427.215730] Dynamic subtest requests: SUCCESS (7.580s) [427.229931] Starting dynamic subtest: migrate [431.871789] Dynamic subtest migrate: SUCCESS (4.652s) [431.885991] Starting dynamic subtest: active [433.768632] Dynamic subtest active: SUCCESS (1.893s) [433.782962] Starting dynamic subtest: objects [437.217312] Dynamic subtest objects: SUCCESS (3.445s) [437.231838] Starting dynamic subtest: mman [444.149680] Dynamic subtest mman: SUCCESS (6.929s) [444.163632] Starting dynamic subtest: dmabuf [446.051492] Dynamic subtest dmabuf: SUCCESS (1.898s) [446.065749] Starting dynamic subtest: vma [447.840913] Dynamic subtest vma: SUCCESS (1.785s) [447.855848] Starting dynamic subtest: coherency [452.269604] Dynamic subtest coherency: SUCCESS (4.425s) [452.283824] Starting dynamic subtest: gtt [461.717475] Dynamic subtest gtt: SUCCESS (9.444s) [461.731844] Starting dynamic subtest: gem [463.655498] Dynamic subtest gem: SUCCESS (1.934s) [463.669966] Starting dynamic subtest: evict [467.492391] Dynamic subtest evict: SUCCESS (3.833s) [467.506725] Starting dynamic subtest: hugepages [474.403151] Dynamic subtest hugepages: SUCCESS (6.907s) [474.418037] Starting dynamic subtest: gem_contexts [489.531046] Dynamic subtest gem_contexts: SUCCESS (15.125s) [489.545735] Starting dynamic subtest: client [491.355222] Dynamic subtest client: SUCCESS (1.821s) [491.370173] Starting dynamic subtest: gem_migrate [493.139598] Dynamic subtest gem_migrate: SUCCESS (1.781s) [493.154174] Starting dynamic subtest: reset [498.494441] Dynamic subtest reset: SUCCESS (5.351s) [498.509247] Starting dynamic subtest: memory_region [500.336571] Dynamic subtest memory_region: SUCCESS (1.839s) [500.351393] Starting dynamic subtest: hangcheck [524.701474] Dynamic subtest hangcheck: SUCCESS (24.361s) [524.716267] Starting dynamic subtest: execlists [534.711503] Dynamic subtest execlists: SUCCESS (10.007s) [534.726376] Starting dynamic subtest: ring_submission [536.550109] Dynamic subtest ring_submission: SUCCESS (1.835s) [536.565160] Starting dynamic subtest: perf [538.467461] Dynamic subtest perf: SUCCESS (1.914s) [538.482242] Starting dynamic subtest: slpc [540.316428] Dynamic subtest slpc: SUCCESS (1.845s) [540.331463] Starting dynamic subtest: guc [542.106384] Dynamic subtest guc: SUCCESS (1.787s) [542.121514] Starting dynamic subtest: guc_multi_lrc [543.902643] Dynamic subtest guc_multi_lrc: SUCCESS (1.793s) [543.917606] Starting dynamic subtest: guc_hang [545.684521] Dynamic subtest guc_hang: SUCCESS (1.779s) [545.699185] Starting dynamic subtest: late_gt_pm [547.558842] Dynamic subtest late_gt_pm: SUCCESS (1.871s) [547.560297] Subtest live: SUCCESS (166.640s) [547.761186] [165/171] (481s left) dmabuf (all-tests) [547.902467] Starting subtest: all-tests [547.903959] Starting dynamic subtest: sanitycheck [547.967878] Dynamic subtest sanitycheck: SUCCESS (0.064s) [547.987440] Starting dynamic subtest: dma_fence [548.153071] Dynamic subtest dma_fence: SUCCESS (0.181s) [548.172536] Starting dynamic subtest: dma_fence_chain [565.953461] Dynamic subtest dma_fence_chain: SUCCESS (17.788s) [565.966832] Starting dynamic subtest: dma_fence_unwrap [566.016797] Dynamic subtest dma_fence_unwrap: SUCCESS (0.068s) [566.036424] Starting dynamic subtest: dma_resv [566.089724] Dynamic subtest dma_resv: SUCCESS (0.069s) [566.092064] Subtest all-tests: SUCCESS (18.188s) [566.122154] [166/171] (462s left) i915_suspend (basic-s2idle-without-i915) [566.248694] Starting subtest: basic-s2idle-without-i915 [589.720641] Subtest basic-s2idle-without-i915: SUCCESS (8.290s) [590.018462] [167/171] (454s left) i915_suspend (basic-s3-without-i915) [590.157952] Starting subtest: basic-s3-without-i915 [613.900179] Subtest basic-s3-without-i915: SUCCESS (7.285s) [614.233617] [168/171] (446s left) gem_exec_suspend (basic-s0) [614.389773] Starting subtest: basic-S0 [614.390977] Starting dynamic subtest: smem [633.929346] Dynamic subtest smem: SUCCESS (3.916s) [633.930560] Subtest basic-S0: SUCCESS (3.917s) [634.100341] [169/171] (442s left) gem_exec_suspend (basic-s3) [634.246387] Starting subtest: basic-S3 [634.247705] Starting dynamic subtest: smem [652.851221] Dynamic subtest smem: SUCCESS (3.610s) [652.852517] Subtest basic-S3: SUCCESS (3.612s) [653.009242] [170/171] (438s left) kms_chamelium_hpd (common-hpd-after-suspend) [653.503035] Subtest common-hpd-after-suspend: SKIP (0.000s) [654.396243] [171/171] (437s left) kms_pipe_crc_basic (suspend-read-crc) [654.555913] Starting subtest: suspend-read-crc [654.559510] Starting dynamic subtest: pipe-A-eDP-1 [671.845817] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.441s) [671.868103] Starting dynamic subtest: pipe-B-eDP-1 [689.810766] Dynamic subtest pipe-B-eDP-1: SUCCESS (3.093s) [689.833780] Starting dynamic subtest: pipe-C-eDP-1 [708.805990] Dynamic subtest pipe-C-eDP-1: SUCCESS (3.255s) [708.807166] Subtest suspend-read-crc: SUCCESS (8.806s) [709.698924] Closing watchdogs Done.