[57.508273] Initializing watchdogs [57.508321] /dev/watchdog0 [57.522137] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:7d55 Intel Meteorlake (Gen12) Meteor Lake-P [Intel Arc Graphics] [57.526116] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0 [57.528229] [FACT before any test] new: kernel.kmod_is_loaded.i915: true [57.531629] [001/131] (960s left) kms_psr (pr-no-drrs) [57.686545] Starting subtest: pr-no-drrs [57.691070] Subtest pr-no-drrs: SKIP (0.000s) [57.748956] [002/131] (960s left) gem_mmap_gtt (basic-read) [57.804140] Subtest basic-read: SKIP (0.000s) [57.849054] [003/131] (960s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-draw-mmap-cpu) [57.950940] Starting subtest: psr-1p-primscrn-spr-indfb-draw-mmap-cpu [62.272951] Subtest psr-1p-primscrn-spr-indfb-draw-mmap-cpu: SUCCESS (4.324s) [62.387375] [004/131] (955s left) kms_frontbuffer_tracking (psr-2p-primscrn-shrfb-msflip-blt) [62.495623] Starting subtest: psr-2p-primscrn-shrfb-msflip-blt [62.500069] Subtest psr-2p-primscrn-shrfb-msflip-blt: SKIP (0.000s) [62.570457] [005/131] (955s left) gem_spin_batch (spin-each) [62.637169] Starting subtest: spin-each [65.915062] Subtest spin-each: SUCCESS (3.270s) [67.952242] [006/131] (950s left) kms_vrr (lobf) [68.051069] Starting subtest: lobf [68.055749] Subtest lobf: SKIP (0.000s) [68.116116] [007/131] (950s left) kms_flip (2x-flip-vs-dpms-off-vs-modeset-interruptible) [68.221090] Starting subtest: 2x-flip-vs-dpms-off-vs-modeset-interruptible [68.234357] Subtest 2x-flip-vs-dpms-off-vs-modeset-interruptible: SKIP (0.012s) [68.303707] [008/131] (949s left) gem_exec_schedule (preempt-contexts) [68.379874] Starting subtest: preempt-contexts [68.382198] Starting dynamic subtest: rcs0 [68.429515] Dynamic subtest rcs0: SUCCESS (0.052s) [68.439189] Starting dynamic subtest: bcs0 [68.473732] Dynamic subtest bcs0: SUCCESS (0.046s) [68.481410] Starting dynamic subtest: vcs0 [68.513495] Dynamic subtest vcs0: SUCCESS (0.039s) [68.521705] Starting dynamic subtest: vcs1 [68.557663] Dynamic subtest vcs1: SUCCESS (0.043s) [68.564939] Starting dynamic subtest: vecs0 [68.598190] Dynamic subtest vecs0: SUCCESS (0.041s) [68.605735] Starting dynamic subtest: ccs0 [68.635438] Dynamic subtest ccs0: SUCCESS (0.038s) [68.637777] Subtest preempt-contexts: SUCCESS (0.259s) [70.039201] [009/131] (948s left) prime_mmap_kms (buffer-sharing) [70.136054] Starting subtest: buffer-sharing [70.597638] Subtest buffer-sharing: SUCCESS (0.458s) [71.384812] [010/131] (946s left) kms_rotation_crc (primary-y-tiled-reflect-x-90) [71.571433] Starting subtest: primary-y-tiled-reflect-x-90 [71.576138] Subtest primary-y-tiled-reflect-x-90: SKIP (0.001s) [71.659404] [011/131] (946s left) kms_ccs (crc-primary-rotation-180-4-tiled-mtl-rc-ccs-cc) [71.805475] Starting subtest: crc-primary-rotation-180-4-tiled-mtl-rc-ccs-cc [71.808021] Starting dynamic subtest: pipe-A-eDP-1 [72.709240] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.894s) [72.718074] Starting dynamic subtest: pipe-B-eDP-1 [74.233244] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.522s) [74.242192] Starting dynamic subtest: pipe-C-eDP-1 [75.691609] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.458s) [75.700243] Starting dynamic subtest: pipe-D-eDP-1 [77.216661] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.525s) [77.218871] Subtest crc-primary-rotation-180-4-tiled-mtl-rc-ccs-cc: SUCCESS (5.402s) [78.378374] [012/131] (940s left) kms_big_fb (x-tiled-max-hw-stride-32bpp-rotate-180-async-flip) [78.490948] Starting subtest: x-tiled-max-hw-stride-32bpp-rotate-180-async-flip [79.369167] Subtest x-tiled-max-hw-stride-32bpp-rotate-180-async-flip: SUCCESS (0.881s) [79.546056] [013/131] (938s left) kms_cursor_edge_walk (256x256-left-edge) [79.637926] Starting subtest: 256x256-left-edge [79.640376] Starting dynamic subtest: pipe-A-eDP-1 [83.035979] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.396s) [83.043076] Starting dynamic subtest: pipe-D-eDP-1 [86.155037] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.116s) [86.157357] Subtest 256x256-left-edge: SUCCESS (6.514s) [86.964283] [014/131] (931s left) kms_universal_plane (universal-plane-pageflip-windowed) [87.071026] Starting subtest: universal-plane-pageflip-windowed [87.073349] Starting dynamic subtest: pipe-A-eDP-1 [87.683278] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.611s) [87.692271] Starting dynamic subtest: pipe-B-eDP-1 [88.903567] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.216s) [88.912224] Starting dynamic subtest: pipe-C-eDP-1 [90.102328] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.200s) [90.110977] Starting dynamic subtest: pipe-D-eDP-1 [91.332655] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.230s) [91.335216] Subtest universal-plane-pageflip-windowed: SUCCESS (4.261s) [92.174784] [015/131] (926s left) syncobj_wait (wait-for-submit-complex) [92.238158] Starting subtest: wait-for-submit-complex [92.301053] Subtest wait-for-submit-complex: SUCCESS (0.062s) [92.368095] [016/131] (926s left) kms_cursor_crc (cursor-offscreen-256x256) [92.581273] Starting subtest: cursor-offscreen-256x256 [92.583605] Starting dynamic subtest: pipe-A-eDP-1 [94.572929] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.990s) [94.601482] Starting dynamic subtest: pipe-D-eDP-1 [97.195137] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.599s) [97.197587] Subtest cursor-offscreen-256x256: SUCCESS (4.615s) [98.036929] [017/131] (920s left) kms_chamelium_edid (hdmi-edid-change-during-suspend) [98.414868] Subtest hdmi-edid-change-during-suspend: SKIP (0.000s) [99.246710] [018/131] (919s left) kms_frontbuffer_tracking (psr-rgb565-draw-pwrite) [99.361191] Starting subtest: psr-rgb565-draw-pwrite [104.322091] Subtest psr-rgb565-draw-pwrite: SUCCESS (4.963s) [104.429183] [019/131] (914s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-0-hflip) [104.542701] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-0-hflip [104.547275] Subtest yf-tiled-max-hw-stride-32bpp-rotate-0-hflip: SKIP (0.000s) [104.651949] [020/131] (914s left) gen9_exec_parse (allowed-all) [104.711370] Subtest allowed-all: SKIP (0.000s) [104.766053] [021/131] (913s left) gem_exec_balancer (parallel-balancer) [105.007859] Starting subtest: parallel-balancer [105.725337] Subtest parallel-balancer: SUCCESS (0.718s) [105.819918] [022/131] (912s left) gem_softpin (overlap) [105.887967] Starting subtest: overlap [106.653429] Subtest overlap: SUCCESS (0.764s) [106.778102] [023/131] (911s left) kms_addfb_basic (basic-y-tiled-legacy) [106.838632] Starting subtest: basic-y-tiled-legacy [106.843327] Subtest basic-y-tiled-legacy: SKIP (0.000s) [106.985304] [024/131] (911s left) gen9_exec_parse (cmd-crossing-page) [107.052375] Subtest cmd-crossing-page: SKIP (0.000s) [107.129559] [025/131] (911s left) drm_fdinfo (basics) [107.169146] Starting subtest: basics [107.171169] Subtest basics: SUCCESS (0.000s) [107.234727] [026/131] (911s left) kms_cursor_crc (cursor-rapid-movement-256x85) [107.459856] Starting subtest: cursor-rapid-movement-256x85 [107.500616] Subtest cursor-rapid-movement-256x85: SKIP (0.035s) [107.682292] [027/131] (911s left) kms_big_fb (4-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip) [107.791584] Starting subtest: 4-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip [108.604609] Subtest 4-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip: SUCCESS (0.814s) [108.788950] [028/131] (910s left) kms_properties (get_properties-sanity-atomic) [108.896076] Starting subtest: get_properties-sanity-atomic [108.900752] Subtest get_properties-sanity-atomic: SUCCESS (0.002s) [108.963352] [029/131] (909s left) kms_ccs (ccs-on-another-bo-4-tiled-mtl-mc-ccs) [109.060141] Starting subtest: ccs-on-another-bo-4-tiled-mtl-mc-ccs [109.062501] Starting dynamic subtest: pipe-A-eDP-1 [109.112625] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.052s) [109.121521] Starting dynamic subtest: pipe-B-eDP-1 [109.162838] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.050s) [109.170970] Starting dynamic subtest: pipe-C-eDP-1 [109.214049] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.050s) [109.221630] Starting dynamic subtest: pipe-D-eDP-1 [109.264595] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.050s) [109.267039] Subtest ccs-on-another-bo-4-tiled-mtl-mc-ccs: SUCCESS (0.205s) [111.470301] [030/131] (907s left) gem_exec_parallel (engines) [111.532737] Starting subtest: engines [111.535170] Starting dynamic subtest: basic [114.921503] Dynamic subtest basic: SUCCESS (3.387s) [114.930932] Starting dynamic subtest: contexts [119.200306] Dynamic subtest contexts: SUCCESS (4.278s) [119.208752] Starting dynamic subtest: fds [123.050213] Dynamic subtest fds: SUCCESS (3.849s) [123.060302] Starting dynamic subtest: userptr [124.904939] Dynamic subtest userptr: SUCCESS (1.853s) [124.907332] Subtest engines: SUCCESS (13.371s) [125.086463] [031/131] (893s left) i915_pm_rps (fence-order) [125.158173] Starting subtest: fence-order [127.653426] Subtest fence-order: SUCCESS (2.493s) [127.727018] [032/131] (891s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-draw-mmap-cpu) [127.835776] Starting subtest: fbc-2p-scndscrn-cur-indfb-draw-mmap-cpu [127.840088] Subtest fbc-2p-scndscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s) [127.929811] [033/131] (891s left) kms_dsc (dsc-fractional-bpp-with-bpc) [128.028218] Starting subtest: dsc-fractional-bpp-with-bpc [128.061604] Subtest dsc-fractional-bpp-with-bpc: SKIP (0.001s) [128.098029] [034/131] (891s left) gem_lmem_swapping (heavy-multi) [128.143254] Subtest heavy-multi: SKIP (0.000s) [128.180240] [035/131] (890s left) kms_frontbuffer_tracking (fbc-tiling-4) [128.280939] Starting subtest: fbc-tiling-4 [131.535940] Subtest fbc-tiling-4: SUCCESS (3.260s) [131.689118] [036/131] (887s left) kms_psr2_sf (fbc-psr2-overlay-plane-update-continuous-sf) [136.746918] Starting subtest: fbc-psr2-overlay-plane-update-continuous-sf [136.749417] Starting dynamic subtest: pipe-A-eDP-1 [137.530885] Dynamic subtest pipe-A-eDP-1: SKIP (0.780s) [137.538825] Starting dynamic subtest: pipe-B-eDP-1 [138.661079] Dynamic subtest pipe-B-eDP-1: SKIP (1.129s) [138.665679] Subtest fbc-psr2-overlay-plane-update-continuous-sf: SKIP (1.911s) [139.832276] [037/131] (879s left) prime_self_import (basic-with_one_bo) [139.868907] Starting subtest: basic-with_one_bo [139.887810] Subtest basic-with_one_bo: SUCCESS (0.020s) [139.980522] [038/131] (879s left) kms_ccs (missing-ccs-buffer-4-tiled-mtl-rc-ccs) [140.090559] Starting subtest: missing-ccs-buffer-4-tiled-mtl-rc-ccs [140.093274] Starting dynamic subtest: pipe-A-eDP-1 [140.115252] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.000s) [140.128228] Starting dynamic subtest: pipe-B-eDP-1 [140.148277] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s) [140.160303] Starting dynamic subtest: pipe-C-eDP-1 [140.180945] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s) [140.190240] Starting dynamic subtest: pipe-D-eDP-1 [140.210083] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s) [140.212385] Subtest missing-ccs-buffer-4-tiled-mtl-rc-ccs: SUCCESS (0.005s) [142.285078] [039/131] (876s left) syncobj_timeline (invalid-signal-illegal-handle) [142.349376] Starting subtest: invalid-signal-illegal-handle [142.351786] Subtest invalid-signal-illegal-handle: SUCCESS (0.000s) [142.455974] [040/131] (876s left) gem_ctx_isolation (dirty-switch) [142.528462] Starting subtest: dirty-switch [142.530717] Starting dynamic subtest: rcs0 [142.968328] Dynamic subtest rcs0: SUCCESS (0.440s) [142.978376] Starting dynamic subtest: bcs0 [143.480842] Dynamic subtest bcs0: SUCCESS (0.512s) [143.490509] Starting dynamic subtest: vcs0 [143.944659] Dynamic subtest vcs0: SUCCESS (0.463s) [143.954423] Starting dynamic subtest: vcs1 [145.443394] Dynamic subtest vcs1: SUCCESS (1.498s) [145.454157] Starting dynamic subtest: vecs0 [147.397655] Dynamic subtest vecs0: SUCCESS (1.954s) [147.408679] Starting dynamic subtest: ccs0 [149.392847] Dynamic subtest ccs0: SUCCESS (1.995s) [149.395633] Subtest dirty-switch: SUCCESS (6.864s) [149.611971] [041/131] (869s left) kms_force_connector_basic (force-connector-state) [149.695089] Starting subtest: force-connector-state [150.288389] Subtest force-connector-state: SUCCESS (0.593s) [151.216887] [042/131] (868s left) gem_sync (basic-all) [151.289305] Starting subtest: basic-all [154.923057] Subtest basic-all: SUCCESS (3.641s) [155.105299] [043/131] (864s left) kms_psr (fbc-psr-basic) [155.201295] Starting subtest: fbc-psr-basic [155.203610] Starting dynamic subtest: eDP-1 [155.208058] Dynamic subtest eDP-1: SKIP (0.000s) [155.212486] Subtest fbc-psr-basic: SKIP (0.001s) [155.265550] [044/131] (864s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-gtt) [155.368433] Starting subtest: fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-gtt [155.372727] Subtest fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s) [155.479187] [045/131] (863s left) kms_chamelium_audio (hdmi-audio-edid) [155.864220] Subtest hdmi-audio-edid: SKIP (0.000s) [156.683687] [046/131] (862s left) gem_ctx_persistence (heartbeat-hang) [156.748588] Starting subtest: heartbeat-hang [156.753515] Subtest heartbeat-hang: SKIP (0.000s) [156.841811] [047/131] (862s left) kms_addfb_basic (unused-modifier) [156.898058] Starting subtest: unused-modifier [156.900308] Subtest unused-modifier: SUCCESS (0.000s) [157.037252] [048/131] (862s left) kms_busy (extended-modeset-hang-oldfb) [157.147166] Starting subtest: extended-modeset-hang-oldfb [157.149485] Starting dynamic subtest: pipe-A [168.889471] Dynamic subtest pipe-A: SUCCESS (11.738s) [168.898109] Starting dynamic subtest: pipe-D [181.238121] Dynamic subtest pipe-D: SUCCESS (12.346s) [181.240603] Subtest extended-modeset-hang-oldfb: SUCCESS (24.088s) [182.013161] [049/131] (837s left) gem_mmap_offset (oob-read) [182.076773] Starting subtest: oob-read [182.078883] Subtest oob-read: SUCCESS (0.000s) [182.183738] [050/131] (837s left) gem_shrink (reclaim) [182.249119] Starting subtest: reclaim [184.764756] Subtest reclaim: SUCCESS (2.516s) [184.881770] [051/131] (834s left) kms_fbc_dirty_rect (fbc-dirty-rectangle-out-visible-area) [185.227366] Subtest fbc-dirty-rectangle-out-visible-area: SKIP (0.000s) [185.273050] [FACT kms_fbc_dirty_rect (fbc-dirty-rectangle-out-visible-area)] new: kernel.kmod_is_loaded.xe: true [185.276406] [052/131] (834s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-cpu) [185.378588] Starting subtest: fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-cpu [185.382924] Subtest fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s) [185.478906] [053/131] (834s left) perf_pmu (busy-double-start) [185.517009] Starting subtest: busy-double-start [185.519397] Starting dynamic subtest: rcs0 [189.153329] Dynamic subtest rcs0: FAIL (3.572s) [189.161848] Starting dynamic subtest: bcs0 [192.663053] Dynamic subtest bcs0: SUCCESS (3.574s) [192.672456] Starting dynamic subtest: vcs0 [196.526107] Dynamic subtest vcs0: FAIL (3.789s) [196.535292] Starting dynamic subtest: vcs1 [200.488785] Dynamic subtest vcs1: FAIL (3.967s) [200.497842] Starting dynamic subtest: vecs0 [204.527208] Dynamic subtest vecs0: FAIL (4.031s) [204.536489] Starting dynamic subtest: ccs0 [208.090170] Dynamic subtest ccs0: FAIL (3.565s) [208.092743] Subtest busy-double-start: FAIL (22.502s) [208.130524] [054/131] (811s left) syncobj_timeline (multi-wait-submitted-signaled) [208.183354] Starting subtest: multi-wait-submitted-signaled [208.185670] Subtest multi-wait-submitted-signaled: SUCCESS (0.001s) [208.284071] [055/131] (811s left) gem_caching (reads) [208.351573] Subtest reads: SKIP (0.000s) [208.418958] [056/131] (811s left) syncobj_timeline (etime-multi-wait-submitted) [208.468702] Starting subtest: etime-multi-wait-submitted [208.573226] Subtest etime-multi-wait-submitted: SUCCESS (0.104s) [208.640264] [057/131] (811s left) kms_frontbuffer_tracking (fbc-1p-offscren-pri-shrfb-draw-mmap-wc) [208.755672] Starting subtest: fbc-1p-offscren-pri-shrfb-draw-mmap-wc [211.761619] Subtest fbc-1p-offscren-pri-shrfb-draw-mmap-wc: SUCCESS (3.010s) [211.881639] [058/131] (808s left) gem_softpin (evict-snoop) [211.953340] Starting subtest: evict-snoop [211.957777] Subtest evict-snoop: SKIP (0.000s) [212.019021] [059/131] (807s left) kms_lease (multimaster-lease) [212.120783] Starting subtest: multimaster-lease [212.125568] Subtest multimaster-lease: SUCCESS (0.004s) [212.218785] [060/131] (807s left) api_intel_bb (crc32) [212.277571] Starting subtest: crc32 [212.279668] Starting dynamic subtest: rcs0-smem0 [212.555884] Dynamic subtest rcs0-smem0: SUCCESS (0.276s) [212.567709] Starting dynamic subtest: bcs0-smem0 [212.868678] Dynamic subtest bcs0-smem0: SUCCESS (0.311s) [212.880063] Starting dynamic subtest: vcs0-smem0 [213.200906] Dynamic subtest vcs0-smem0: SUCCESS (0.331s) [213.212525] Starting dynamic subtest: vcs1-smem0 [213.535831] Dynamic subtest vcs1-smem0: SUCCESS (0.332s) [213.546802] Starting dynamic subtest: vecs0-smem0 [213.856129] Dynamic subtest vecs0-smem0: SUCCESS (0.319s) [213.866906] Starting dynamic subtest: ccs0-smem0 [214.187660] Dynamic subtest ccs0-smem0: SUCCESS (0.330s) [214.190380] Subtest crc32: SUCCESS (1.909s) [214.305153] [061/131] (805s left) gem_userptr_blits (usage-restrictions) [214.366099] Starting subtest: usage-restrictions [214.368466] Subtest usage-restrictions: SUCCESS (0.000s) [214.489350] [062/131] (805s left) gem_exec_flush (basic-uc-pro-default) [214.543897] Starting subtest: basic-uc-pro-default [220.030612] Subtest basic-uc-pro-default: SUCCESS (5.486s) [220.212454] [063/131] (799s left) gem_ccs (block-copy-uncompressed) [220.281571] Starting subtest: block-copy-uncompressed [220.283986] Starting dynamic subtest: linear-uncompressed-compfmt0-smem-smem [220.309765] Dynamic subtest linear-uncompressed-compfmt0-smem-smem: SUCCESS (0.027s) [220.320296] Starting dynamic subtest: xmajor-uncompressed-compfmt0-smem-smem [220.331068] Dynamic subtest xmajor-uncompressed-compfmt0-smem-smem: SUCCESS (0.021s) [220.338722] Starting dynamic subtest: tile4-uncompressed-compfmt0-smem-smem [220.352564] Dynamic subtest tile4-uncompressed-compfmt0-smem-smem: SUCCESS (0.021s) [220.360210] Starting dynamic subtest: tile64-uncompressed-compfmt0-smem-smem [220.375197] Dynamic subtest tile64-uncompressed-compfmt0-smem-smem: SUCCESS (0.022s) [220.377608] Subtest block-copy-uncompressed: SUCCESS (0.094s) [220.505311] [064/131] (799s left) kms_plane_scaling (plane-scaler-unity-scaling-with-pixel-format) [220.607276] Starting subtest: plane-scaler-unity-scaling-with-pixel-format [220.609525] Starting dynamic subtest: pipe-A [222.173496] Dynamic subtest pipe-A: SUCCESS (1.566s) [222.181708] Starting dynamic subtest: pipe-B [224.751709] Dynamic subtest pipe-B: SUCCESS (2.577s) [224.759932] Starting dynamic subtest: pipe-C [225.956932] Dynamic subtest pipe-C: SUCCESS (1.204s) [225.971637] Starting dynamic subtest: pipe-D [227.158055] Dynamic subtest pipe-D: SUCCESS (1.200s) [227.160439] Subtest plane-scaler-unity-scaling-with-pixel-format: SUCCESS (6.551s) [228.334791] [065/131] (791s left) i915_pm_freq_api (freq-basic-api) [228.399959] Starting subtest: freq-basic-api [228.402461] Starting dynamic subtest: gt0 [228.404735] Dynamic subtest gt0: SUCCESS (0.003s) [228.414250] Starting dynamic subtest: gt1 [228.416397] Dynamic subtest gt1: SUCCESS (0.003s) [228.418575] Subtest freq-basic-api: SUCCESS (0.007s) [228.499390] [066/131] (791s left) kms_cursor_crc (cursor-onscreen-128x42) [228.709478] Starting subtest: cursor-onscreen-128x42 [228.758265] Subtest cursor-onscreen-128x42: SKIP (0.042s) [228.940284] [067/131] (791s left) gem_exec_suspend (basic-s0) [229.000974] Starting subtest: basic-S0 [229.003971] Starting dynamic subtest: smem [249.962926] Dynamic subtest smem: SUCCESS (6.880s) [249.965746] Subtest basic-S0: SUCCESS (6.882s) [250.145466] [068/131] (784s left) kms_frontbuffer_tracking (fbcpsr-rgb565-draw-mmap-cpu) [250.257237] Starting subtest: fbcpsr-rgb565-draw-mmap-cpu [254.931292] Subtest fbcpsr-rgb565-draw-mmap-cpu: SUCCESS (4.675s) [255.053216] [069/131] (779s left) kms_flip (2x-modeset-vs-vblank-race) [255.156175] Starting subtest: 2x-modeset-vs-vblank-race [255.167806] Subtest 2x-modeset-vs-vblank-race: SKIP (0.011s) [255.244816] [070/131] (779s left) gem_userptr_blits (map-fixed-invalidate-overlap) [255.315794] Starting subtest: map-fixed-invalidate-overlap [255.319859] Subtest map-fixed-invalidate-overlap: SKIP (0.000s) [255.468107] [071/131] (778s left) sysfs_heartbeat_interval (precise) [255.527183] Starting subtest: precise [255.529603] Starting dynamic subtest: vecs0 [263.054497] Dynamic subtest vecs0: SUCCESS (7.522s) [263.066056] Starting dynamic subtest: ccs0 [264.220990] Dynamic subtest ccs0: SUCCESS (1.167s) [264.229901] Starting dynamic subtest: vcs0 [272.003504] Dynamic subtest vcs0: SUCCESS (7.780s) [272.015813] Starting dynamic subtest: bcs0 [273.216743] Dynamic subtest bcs0: SUCCESS (1.211s) [273.227517] Starting dynamic subtest: rcs0 [274.411582] Dynamic subtest rcs0: SUCCESS (1.193s) [274.423228] Starting dynamic subtest: vcs1 [282.060762] Dynamic subtest vcs1: SUCCESS (7.645s) [282.063376] Subtest precise: SUCCESS (26.529s) [282.130891] [072/131] (752s left) kms_flip (flip-vs-expired-vblank-interruptible) [282.227721] Starting subtest: flip-vs-expired-vblank-interruptible [282.229915] Starting dynamic subtest: A-eDP1 [291.092988] Dynamic subtest A-eDP1: SUCCESS (8.864s) [291.104266] Starting dynamic subtest: B-eDP1 [299.909967] Dynamic subtest B-eDP1: SUCCESS (8.816s) [299.920682] Starting dynamic subtest: C-eDP1 [308.717295] Dynamic subtest C-eDP1: SUCCESS (8.805s) [308.732867] Starting dynamic subtest: D-eDP1 [317.445412] Dynamic subtest D-eDP1: SUCCESS (8.727s) [317.447767] Subtest flip-vs-expired-vblank-interruptible: SUCCESS (35.221s) [318.602182] [073/131] (715s left) gem_render_copy (yf-tiled-to-vebox-x-tiled) [318.660823] Starting subtest: yf-tiled-to-vebox-x-tiled [318.665406] Subtest yf-tiled-to-vebox-x-tiled: SKIP (0.000s) [318.838057] [074/131] (715s left) gem_exec_reloc (basic-cpu-active) [318.901745] Subtest basic-cpu-active: SKIP (0.000s) [318.973400] [075/131] (715s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-cpu) [319.083751] Starting subtest: fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-cpu [319.087793] Subtest fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s) [319.163331] [076/131] (715s left) kms_atomic_transition (plane-primary-toggle-with-vblank-wait) [319.253155] Starting subtest: plane-primary-toggle-with-vblank-wait [319.255027] Starting dynamic subtest: pipe-A-eDP-1 [319.434185] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.181s) [319.733144] Starting dynamic subtest: pipe-B-eDP-1 [320.606183] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.882s) [320.891174] Subtest plane-primary-toggle-with-vblank-wait: SUCCESS (1.640s) [321.743515] [077/131] (712s left) kms_psr (psr-sprite-plane-onoff) [321.844087] Starting subtest: psr-sprite-plane-onoff [321.846457] Starting dynamic subtest: eDP-1 [323.568996] Dynamic subtest eDP-1: SUCCESS (1.726s) [323.571505] Subtest psr-sprite-plane-onoff: SUCCESS (1.727s) [324.393846] [078/131] (710s left) sysfs_heartbeat_interval (idempotent) [324.455532] Starting subtest: idempotent [324.458040] Starting dynamic subtest: vecs0 [324.460470] Dynamic subtest vecs0: SUCCESS (0.000s) [324.470745] Starting dynamic subtest: ccs0 [324.473028] Dynamic subtest ccs0: SUCCESS (0.001s) [324.481633] Starting dynamic subtest: vcs0 [324.483610] Dynamic subtest vcs0: SUCCESS (0.000s) [324.492155] Starting dynamic subtest: bcs0 [324.494435] Dynamic subtest bcs0: SUCCESS (0.001s) [324.502982] Starting dynamic subtest: rcs0 [324.505299] Dynamic subtest rcs0: SUCCESS (0.001s) [324.513542] Starting dynamic subtest: vcs1 [324.515837] Dynamic subtest vcs1: SUCCESS (0.000s) [324.518131] Subtest idempotent: SUCCESS (0.007s) [324.547555] [079/131] (710s left) kms_color (invalid-ctm-matrix-sizes) [324.653885] Starting subtest: invalid-ctm-matrix-sizes [324.656175] Starting dynamic subtest: pipe-A [324.658335] Dynamic subtest pipe-A: SUCCESS (0.002s) [324.668263] Starting dynamic subtest: pipe-B [324.670366] Dynamic subtest pipe-B: SUCCESS (0.003s) [324.678553] Starting dynamic subtest: pipe-C [324.680431] Dynamic subtest pipe-C: SUCCESS (0.005s) [324.688657] Starting dynamic subtest: pipe-D [324.690912] Dynamic subtest pipe-D: SUCCESS (0.006s) [324.693146] Subtest invalid-ctm-matrix-sizes: SUCCESS (0.018s) [324.738098] [080/131] (709s left) syncobj_eventfd (binary-wait) [324.798145] Starting subtest: binary-wait [324.808492] Subtest binary-wait: SUCCESS (0.011s) [324.896195] [081/131] (709s left) kms_addfb_basic (addfb25-4-tiled) [325.002484] Starting subtest: addfb25-4-tiled [325.004680] Subtest addfb25-4-tiled: SUCCESS (0.000s) [325.109575] [082/131] (709s left) gem_exec_balancer (busy) [325.176597] Starting subtest: busy [327.041019] Subtest busy: SUCCESS (1.863s) [327.360751] [083/131] (707s left) kms_flip (flip-vs-rmfb-interruptible) [327.461998] Starting subtest: flip-vs-rmfb-interruptible [327.464417] Starting dynamic subtest: A-eDP1 [336.090533] Dynamic subtest A-eDP1: SUCCESS (8.627s) [336.100562] Starting dynamic subtest: B-eDP1 [344.710963] Dynamic subtest B-eDP1: SUCCESS (8.619s) [344.719777] Starting dynamic subtest: C-eDP1 [353.325911] Dynamic subtest C-eDP1: SUCCESS (8.613s) [353.337905] Starting dynamic subtest: D-eDP1 [361.954576] Dynamic subtest D-eDP1: SUCCESS (8.627s) [361.960466] Subtest flip-vs-rmfb-interruptible: SUCCESS (34.493s) [363.121959] [084/131] (671s left) kms_pm_backlight (fade-with-suspend) [363.224182] Starting subtest: fade-with-suspend [379.070527] Starting dynamic subtest: eDP-1 [381.734896] Dynamic subtest eDP-1: SUCCESS (2.665s) [381.736904] Subtest fade-with-suspend: SUCCESS (3.906s) [382.554392] [085/131] (666s left) core_setmaster (master-drop-set-user) [384.480758] Starting subtest: master-drop-set-user [384.493399] Subtest master-drop-set-user: SUCCESS (0.014s) [384.520389] [FACT core_setmaster (master-drop-set-user)] new: kernel.kmod_is_loaded.amdgpu: true [384.524560] [086/131] (665s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-shrfb-draw-render) [384.636459] Starting subtest: fbc-2p-scndscrn-pri-shrfb-draw-render [384.640494] Subtest fbc-2p-scndscrn-pri-shrfb-draw-render: SKIP (0.000s) [384.745112] [087/131] (664s left) syncobj_timeline (etime-single-wait-all-for-submit-unsubmitted) [384.806124] Starting subtest: etime-single-wait-all-for-submit-unsubmitted [384.913145] Subtest etime-single-wait-all-for-submit-unsubmitted: SUCCESS (0.104s) [384.982442] [088/131] (664s left) perf_pmu (init-sema) [385.023689] Starting subtest: init-sema [385.026054] Starting dynamic subtest: rcs0 [385.028302] Dynamic subtest rcs0: SUCCESS (0.002s) [385.038967] Starting dynamic subtest: bcs0 [385.041283] Dynamic subtest bcs0: SUCCESS (0.001s) [385.050028] Starting dynamic subtest: vcs0 [385.052284] Dynamic subtest vcs0: SUCCESS (0.001s) [385.060972] Starting dynamic subtest: vcs1 [385.063301] Dynamic subtest vcs1: SUCCESS (0.001s) [385.071928] Starting dynamic subtest: vecs0 [385.074377] Dynamic subtest vecs0: SUCCESS (0.000s) [385.084847] Starting dynamic subtest: ccs0 [385.087237] Dynamic subtest ccs0: SUCCESS (0.000s) [385.089525] Subtest init-sema: SUCCESS (0.008s) [385.137047] [089/131] (664s left) kms_pipe_crc_basic (suspend-read-crc) [385.221666] Starting subtest: suspend-read-crc [385.223971] Starting dynamic subtest: pipe-A-eDP-1 [402.954166] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.499s) [402.965364] Starting dynamic subtest: pipe-B-eDP-1 [420.641556] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.751s) [420.651189] Starting dynamic subtest: pipe-C-eDP-1 [438.616026] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.662s) [438.626434] Starting dynamic subtest: pipe-D-eDP-1 [456.616256] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.708s) [456.618593] Subtest suspend-read-crc: SUCCESS (10.625s) [457.445304] [090/131] (652s left) kms_ccs (bad-pixel-format-4-tiled-dg2-rc-ccs-cc) [457.543251] Starting subtest: bad-pixel-format-4-tiled-dg2-rc-ccs-cc [457.545540] Starting dynamic subtest: pipe-A-eDP-1 [457.568752] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s) [457.578021] Starting dynamic subtest: pipe-B-eDP-1 [457.600157] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s) [457.608902] Starting dynamic subtest: pipe-C-eDP-1 [457.631228] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s) [457.641412] Starting dynamic subtest: pipe-D-eDP-1 [457.663737] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s) [457.667871] Subtest bad-pixel-format-4-tiled-dg2-rc-ccs-cc: SKIP (0.004s) [459.687560] [091/131] (650s left) prime_mmap_coherency (ioctl-errors) [459.741242] Starting subtest: ioctl-errors [473.036655] Subtest ioctl-errors: SUCCESS (13.284s) [473.181487] [092/131] (637s left) gem_userptr_blits (stress-mm-invalidate-close-overlap) [473.287113] Starting subtest: stress-mm-invalidate-close-overlap [475.437640] Subtest stress-mm-invalidate-close-overlap: SUCCESS (2.148s) [475.577648] [093/131] (634s left) kms_frontbuffer_tracking (psr-2p-scndscrn-indfb-msflip-blt) [475.694851] Starting subtest: psr-2p-scndscrn-indfb-msflip-blt [475.699005] Subtest psr-2p-scndscrn-indfb-msflip-blt: SKIP (0.000s) [475.789532] [094/131] (634s left) kms_prop_blob (invalid-set-prop-any) [475.853676] Starting subtest: invalid-set-prop-any [475.856033] Subtest invalid-set-prop-any: SUCCESS (0.000s) [475.952956] [095/131] (634s left) kms_selftest (drm_framebuffer) [476.002329] Starting subtest: drm_framebuffer [476.037976] Starting dynamic subtest: drm_test_framebuffer_check_src_coords [476.177847] Dynamic subtest drm_test_framebuffer_check_src_coords: SUCCESS (0.141s) [476.187799] Starting dynamic subtest: drm_test_framebuffer_cleanup [476.326577] Dynamic subtest drm_test_framebuffer_cleanup: SUCCESS (0.146s) [476.335806] Starting dynamic subtest: drm_test_framebuffer_create [476.515167] Dynamic subtest drm_test_framebuffer_create: SUCCESS (0.189s) [476.525526] Starting dynamic subtest: drm_test_framebuffer_free [476.740902] Dynamic subtest drm_test_framebuffer_free: FAIL (0.181s) [476.745404] Subtest drm_framebuffer: FAIL (0.695s) [476.862040] Aborting: Kernel badly tainted (0x40240, 0x200) (check dmesg for details): TAINT_WARN: WARN_ON has happened. [476.876596] [FACT kms_selftest (drm_framebuffer)] new: kernel.is_tainted.taint_warn: true [476.877541] Closing watchdogs results: parsing output: 0/ for test: kms_psr results: parsing output: 1/ for test: gem_mmap_gtt results: parsing output: 2/ for test: kms_frontbuffer_tracking results: parsing output: 3/ for test: kms_frontbuffer_tracking results: parsing output: 4/ for test: gem_spin_batch results: parsing output: 5/ for test: kms_vrr results: parsing output: 6/ for test: kms_flip results: parsing output: 7/ for test: gem_exec_schedule results: parsing output: 8/ for test: prime_mmap_kms results: parsing output: 9/ for test: kms_rotation_crc results: parsing output: 10/ for test: kms_ccs results: parsing output: 11/ for test: kms_big_fb results: parsing output: 12/ for test: kms_cursor_edge_walk results: parsing output: 13/ for test: kms_universal_plane results: parsing output: 14/ for test: syncobj_wait results: parsing output: 15/ for test: kms_cursor_crc results: parsing output: 16/ for test: kms_chamelium_edid results: parsing output: 17/ for test: kms_frontbuffer_tracking results: parsing output: 18/ for test: kms_big_fb results: parsing output: 19/ for test: gen9_exec_parse results: parsing output: 20/ for test: gem_exec_balancer results: parsing output: 21/ for test: gem_softpin results: parsing output: 22/ for test: kms_addfb_basic results: parsing output: 23/ for test: gen9_exec_parse results: parsing output: 24/ for test: drm_fdinfo results: parsing output: 25/ for test: kms_cursor_crc results: parsing output: 26/ for test: kms_big_fb results: parsing output: 27/ for test: kms_properties results: parsing output: 28/ for test: kms_ccs results: parsing output: 29/ for test: gem_exec_parallel results: parsing output: 30/ for test: i915_pm_rps results: parsing output: 31/ for test: kms_frontbuffer_tracking results: parsing output: 32/ for test: kms_dsc results: parsing output: 33/ for test: gem_lmem_swapping results: parsing output: 34/ for test: kms_frontbuffer_tracking results: parsing output: 35/ for test: kms_psr2_sf results: parsing output: 36/ for test: prime_self_import results: parsing output: 37/ for test: kms_ccs results: parsing output: 38/ for test: syncobj_timeline results: parsing output: 39/ for test: gem_ctx_isolation results: parsing output: 40/ for test: kms_force_connector_basic results: parsing output: 41/ for test: gem_sync results: parsing output: 42/ for test: kms_psr results: parsing output: 43/ for test: kms_frontbuffer_tracking results: parsing output: 44/ for test: kms_chamelium_audio results: parsing output: 45/ for test: gem_ctx_persistence results: parsing output: 46/ for test: kms_addfb_basic results: parsing output: 47/ for test: kms_busy results: parsing output: 48/ for test: gem_mmap_offset results: parsing output: 49/ for test: gem_shrink results: parsing output: 50/ for test: kms_fbc_dirty_rect results: parsing output: 51/ for test: kms_frontbuffer_tracking results: parsing output: 52/ for test: perf_pmu results: parsing output: 53/ for test: syncobj_timeline results: parsing output: 54/ for test: gem_caching results: parsing output: 55/ for test: syncobj_timeline results: parsing output: 56/ for test: kms_frontbuffer_tracking results: parsing output: 57/ for test: gem_softpin results: parsing output: 58/ for test: kms_lease results: parsing output: 59/ for test: api_intel_bb results: parsing output: 60/ for test: gem_userptr_blits results: parsing output: 61/ for test: gem_exec_flush results: parsing output: 62/ for test: gem_ccs results: parsing output: 63/ for test: kms_plane_scaling results: parsing output: 64/ for test: i915_pm_freq_api results: parsing output: 65/ for test: kms_cursor_crc results: parsing output: 66/ for test: gem_exec_suspend results: parsing output: 67/ for test: kms_frontbuffer_tracking results: parsing output: 68/ for test: kms_flip results: parsing output: 69/ for test: gem_userptr_blits results: parsing output: 70/ for test: sysfs_heartbeat_interval results: parsing output: 71/ for test: kms_flip results: parsing output: 72/ for test: gem_render_copy results: parsing output: 73/ for test: gem_exec_reloc results: parsing output: 74/ for test: kms_frontbuffer_tracking results: parsing output: 75/ for test: kms_atomic_transition results: parsing output: 76/ for test: kms_psr results: parsing output: 77/ for test: sysfs_heartbeat_interval results: parsing output: 78/ for test: kms_color results: parsing output: 79/ for test: syncobj_eventfd results: parsing output: 80/ for test: kms_addfb_basic results: parsing output: 81/ for test: gem_exec_balancer results: parsing output: 82/ for test: kms_flip results: parsing output: 83/ for test: kms_pm_backlight results: parsing output: 84/ for test: core_setmaster results: parsing output: 85/ for test: kms_frontbuffer_tracking results: parsing output: 86/ for test: syncobj_timeline results: parsing output: 87/ for test: perf_pmu results: parsing output: 88/ for test: kms_pipe_crc_basic results: parsing output: 89/ for test: kms_ccs results: parsing output: 90/ for test: prime_mmap_coherency results: parsing output: 91/ for test: gem_userptr_blits results: parsing output: 92/ for test: kms_frontbuffer_tracking results: parsing output: 93/ for test: kms_prop_blob results: parsing output: 94/ for test: kms_selftest results: parsing output: 95/ for test: kms_frontbuffer_tracking results: no output, setting notrun results: parsing output: 96/ for test: gem_ppgtt results: no output, setting notrun results: parsing output: 97/ for test: gem_exec_fence results: no output, setting notrun results: parsing output: 98/ for test: kms_frontbuffer_tracking results: no output, setting notrun results: parsing output: 99/ for test: kms_psr results: no output, setting notrun results: parsing output: 100/ for test: gem_mmap_gtt results: no output, setting notrun results: parsing output: 101/ for test: gem_exec_params results: no output, setting notrun results: parsing output: 102/ for test: gem_exec_reloc results: no output, setting notrun results: parsing output: 103/ for test: kms_plane_scaling results: no output, setting notrun results: parsing output: 104/ for test: kms_invalid_mode results: no output, setting notrun results: parsing output: 105/ for test: kms_psr2_sf results: no output, setting notrun results: parsing output: 106/ for test: kms_cursor_legacy results: no output, setting notrun results: parsing output: 107/ for test: kms_flip results: no output, setting notrun results: parsing output: 108/ for test: gem_softpin results: no output, setting notrun results: parsing output: 109/ for test: kms_addfb_basic results: no output, setting notrun results: parsing output: 110/ for test: kms_frontbuffer_tracking results: no output, setting notrun results: parsing output: 111/ for test: syncobj_wait results: no output, setting notrun results: parsing output: 112/ for test: kms_flip results: no output, setting notrun results: parsing output: 113/ for test: kms_frontbuffer_tracking results: no output, setting notrun results: parsing output: 114/ for test: drm_fdinfo results: no output, setting notrun results: parsing output: 115/ for test: gem_exec_fence results: no output, setting notrun results: parsing output: 116/ for test: kms_flip_scaled_crc results: no output, setting notrun results: parsing output: 117/ for test: kms_pm_rpm results: no output, setting notrun results: parsing output: 118/ for test: kms_psr results: no output, setting notrun results: parsing output: 119/ for test: kms_big_fb results: no output, setting notrun results: parsing output: 120/ for test: kms_cursor_legacy results: no output, setting notrun results: parsing output: 121/ for test: gem_exec_whisper results: no output, setting notrun results: parsing output: 122/ for test: kms_frontbuffer_tracking results: no output, setting notrun results: parsing output: 123/ for test: gem_ctx_persistence results: no output, setting notrun results: parsing output: 124/ for test: kms_frontbuffer_tracking results: no output, setting notrun results: parsing output: 125/ for test: kms_hdmi_inject results: no output, setting notrun results: parsing output: 126/ for test: kms_chamelium_hpd results: no output, setting notrun results: parsing output: 127/ for test: gem_exec_params results: no output, setting notrun results: parsing output: 128/ for test: gem_eio results: no output, setting notrun results: parsing output: 129/ for test: kms_big_fb results: no output, setting notrun results: parsing output: 130/ for test: kms_big_fb results: no output, setting notrun Done.