Stdout
Using IGT_SRANDOM=1715024895 for randomisation
Opened device: /dev/dri/card0
Using monotonic timestamps
Starting subtest: flip-vs-expired-vblank-interruptible
Starting dynamic subtest: A-eDP1
2880x1800 60 2880 2928 2960 3040 1800 1803 1809 1906 0xa 0x48 347710
Dynamic subtest A-eDP1: SUCCESS (11.523s)
This test caused an abort condition: Lockdep not active
/proc/lockdep_stats contents:
lock-classes: 1872 [max: 8192]
direct dependencies: 18794 [max: 524288]
indirect dependencies: 103429
all direct dependencies: 339284
dependency chains: 25877 [max: 524288]
dependency chain hlocks used: 102176 [max: 2621440]
dependency chain hlocks lost: 0
in-hardirq chains: 166
in-softirq chains: 526
in-process chains: 25185
stack-trace entries: 214968 [max: 524288]
number of stack traces: 9765
number of stack hash chains: 7340
combined max dependencies: 2216594674
hardirq-safe locks: 76
hardirq-unsafe locks: 1187
softirq-safe locks: 185
softirq-unsafe locks: 1095
irq-safe locks: 194
irq-unsafe locks: 1187
hardirq-read-safe locks: 4
hardirq-read-unsafe locks: 297
softirq-read-safe locks: 8
softirq-read-unsafe locks: 292
irq-read-safe locks: 8
irq-read-unsafe locks: 297
uncategorized locks: 310
unused locks: 2
max locking depth: 16
max bfs queue depth: 311
max lock class index: 1871
chain lookup misses: 26147
chain lookup hits: 145717117
cyclic checks: 25944
redundant checks: 0
redundant links: 0
find-mask forwards checks: 3474
find-mask backwards checks: 2877
hardirq on events: 86810411
hardirq off events: 86810405
redundant hardirq ons: 22
redundant hardirq offs: 6
softirq on events: 165365
softirq off events: 165365
redundant softirq ons: 0
redundant softirq offs: 0
debug_locks: 0
zapped classes: 2
zapped lock chains: 145
large chain blocks: 1
Stderr
Starting subtest: flip-vs-expired-vblank-interruptible
Starting dynamic subtest: A-eDP1
Dynamic subtest A-eDP1: SUCCESS (11.523s)
Dmesg
<6> [96.217177] Console: switching to colour dummy device 80x25
<6> [96.217272] [IGT] kms_flip: executing
<7> [96.237693] xe 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:198:DP-1]
<7> [96.237740] xe 0000:00:02.0: [drm:intel_dp_detect [xe]] [CONNECTOR:198:DP-1]
<7> [96.237930] xe 0000:00:02.0: [drm:intel_tc_port_update_mode [xe]] Port D/TC#1: TC port mode reset (disconnected -> tbt-alt)
<7> [96.238014] xe 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:198:DP-1] disconnected
<7> [96.238381] xe 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:210:DP-2]
<7> [96.238393] xe 0000:00:02.0: [drm:intel_dp_detect [xe]] [CONNECTOR:210:DP-2]
<7> [96.238492] xe 0000:00:02.0: [drm:intel_tc_port_update_mode [xe]] Port E/TC#2: TC port mode reset (disconnected -> tbt-alt)
<7> [96.238563] xe 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:210:DP-2] disconnected
<7> [96.238913] xe 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:218:DP-3]
<7> [96.238924] xe 0000:00:02.0: [drm:intel_dp_detect [xe]] [CONNECTOR:218:DP-3]
<7> [96.239019] xe 0000:00:02.0: [drm:intel_tc_port_update_mode [xe]] Port F/TC#3: TC port mode reset (disconnected -> tbt-alt)
<7> [96.239088] xe 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:218:DP-3] disconnected
<7> [96.239575] xe 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:189:eDP-1]
<7> [96.239587] xe 0000:00:02.0: [drm:intel_dp_detect [xe]] [CONNECTOR:189:eDP-1]
<7> [96.239694] xe 0000:00:02.0: [drm:intel_pps_vdd_on_unlocked [xe]] [ENCODER:188:DDI A/PHY A] PPS 0 turning VDD on
<7> [96.239786] xe 0000:00:02.0: [drm:intel_pps_vdd_on_unlocked [xe]] [ENCODER:188:DDI A/PHY A] PPS 0 PP_STATUS: 0x80000008 PP_CONTROL: 0x0000006f
<7> [96.240604] xe 0000:00:02.0: [drm:intel_dp_read_dsc_dpcd [xe]] DSC DPCD: 00 11 00 76 0b 01 01 80 00 01 06 66 08 00 00 00
<7> [96.240693] xe 0000:00:02.0: [drm:intel_dp_print_rates [xe]] source rates: 162000, 216000, 243000, 270000, 324000, 432000, 540000, 675000, 810000
<7> [96.240757] xe 0000:00:02.0: [drm:intel_dp_print_rates [xe]] sink rates: 162000, 216000, 243000, 270000, 324000, 432000, 540000, 810000
<7> [96.240821] xe 0000:00:02.0: [drm:intel_dp_print_rates [xe]] common rates: 162000, 216000, 243000, 270000, 324000, 432000, 540000, 810000
<7> [96.241075] xe 0000:00:02.0: [drm:update_display_info [drm]] [CONNECTOR:189:eDP-1] Supported Monitor Refresh rate range is 40 Hz - 120 Hz
<7> [96.241122] [drm:__displayid_iter_next [drm]] base revision 0x13, length 121, 0 0
<5> [96.241148] [drm] DisplayID checksum invalid, remainder is 248
<7> [96.241151] [drm:__displayid_iter_next [drm]] base revision 0x13, length 121, 0 0
<5> [96.241173] [drm] DisplayID checksum invalid, remainder is 248
<7> [96.241174] xe 0000:00:02.0: [drm:update_display_info [drm]] [CONNECTOR:189:eDP-1] Assigning EDID-1.4 digital sink color depth as 10 bpc.
<7> [96.241199] [drm:__displayid_iter_next [drm]] base revision 0x13, length 121, 0 0
<5> [96.241220] [drm] DisplayID checksum invalid, remainder is 248
<7> [96.241222] xe 0000:00:02.0: [drm:update_display_info [drm]] [CONNECTOR:189:eDP-1] ELD monitor
<7> [96.241245] [drm:__displayid_iter_next [drm]] base revision 0x13, length 121, 0 0
<5> [96.241265] [drm] DisplayID checksum invalid, remainder is 248
<7> [96.241267] xe 0000:00:02.0: [drm:update_display_info [drm]] [CONNECTOR:189:eDP-1] ELD size 20, SAD count 0
<7> [96.241289] [drm:__displayid_iter_next [drm]] base revision 0x13, length 121, 0 0
<5> [96.241309] [drm] DisplayID checksum invalid, remainder is 248
<7> [96.241343] xe 0000:00:02.0: [drm:intel_dp_set_edid [xe]] [CONNECTOR:189:eDP-1] VRR capable: yes
<7> [96.241413] xe 0000:00:02.0: [drm:intel_dp_set_edid [xe]] [CONNECTOR:189:eDP-1] DFP max bpc 0, max dotclock 0, TMDS clock 0-0, PCON Max FRL BW 0Gbps
<7> [96.242572] xe 0000:00:02.0: [drm:intel_dp_set_edid [xe]] PCON ENCODER DSC DPCD: 00 00 00 00 00 00 00 00 00 00 00 00 00
<7> [96.242642] xe 0000:00:02.0: [drm:intel_dp_set_edid [xe]] [CONNECTOR:189:eDP-1] RGB->YcbCr conversion? no, YCbCr 4:2:0 allowed? yes, YCbCr 4:4:4->4:2:0 conversion? no
<7> [96.243169] [drm:__displayid_iter_next [drm]] base revision 0x13, length 121, 0 0
<5> [96.243199] [drm] DisplayID checksum invalid, remainder is 248
<7> [96.243200] [drm:__displayid_iter_next [drm]] base revision 0x13, length 121, 0 0
<5> [96.243221] [drm] DisplayID checksum invalid, remainder is 248
<7> [96.243223] [drm:__displayid_iter_next [drm]] base revision 0x13, length 121, 0 0
<5> [96.243241] [drm] DisplayID checksum invalid, remainder is 248
<7> [96.243284] xe 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:189:eDP-1] probed modes:
<7> [96.243311] xe 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "2880x1800": 60 347710 2880 2928 2960 3040 1800 1803 1809 1906 0x48 0xa
<7> [96.243345] xe 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:198:DP-1]
<7> [96.243356] xe 0000:00:02.0: [drm:intel_dp_detect [xe]] [CONNECTOR:198:DP-1]
<7> [96.243453] xe 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:198:DP-1] disconnected
<7> [96.243475] xe 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:210:DP-2]
<7> [96.243485] xe 0000:00:02.0: [drm:intel_dp_detect [xe]] [CONNECTOR:210:DP-2]
<7> [96.243574] xe 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:210:DP-2] disconnected
<7> [96.243593] xe 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:218:DP-3]
<7> [96.243602] xe 0000:00:02.0: [drm:intel_dp_detect [xe]] [CONNECTOR:218:DP-3]
<7> [96.243689] xe 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:218:DP-3] disconnected
<6> [96.252982] [IGT] kms_flip: starting subtest flip-vs-expired-vblank-interruptible
<6> [96.254492] [IGT] kms_flip: starting dynamic subtest A-eDP1
<7> [96.261763] xe 0000:00:02.0: [drm:drm_mode_addfb2 [drm]] [FB:190]
<7> [96.267591] xe 0000:00:02.0: [drm:drm_mode_addfb2 [drm]] [FB:228]
<7> [96.430539] xe 0000:00:02.0: [drm:drm_mode_setcrtc [drm]] [CRTC:82:pipe A]
<7> [96.430893] xe 0000:00:02.0: [drm] [CRTC:82:pipe A] fastset requirement not met in hw.enable (expected yes, found no)
<7> [96.430900] xe 0000:00:02.0: [drm] [CRTC:82:pipe A] fastset requirement not met in hw.active (expected yes, found no)
<7> [96.430902] xe 0000:00:02.0: [drm] [CRTC:82:pipe A] fastset requirement not met in cpu_transcoder (expected 0, found -1)
<7> [96.430904] xe 0000:00:02.0: [drm] [CRTC:82:pipe A] fastset requirement not met in lane_count (expected 4, found 0)
<7> [96.430906] xe 0000:00:02.0: [drm] [CRTC:82:pipe A] fastset requirement not met in dp_m_n (expected tu 64 data 6329869/8388608 link 421991/524288, found tu 0, data 0/0 link 0/0)
<7> [96.430909] xe 0000:00:02.0: [drm] [CRTC:82:pipe A] fastset requirement not met in output_types (expected 0x00000100, found 0x00000000)
<7> [96.430912] xe 0000:00:02.0: [drm] [CRTC:82:pipe A] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [96.430913] xe 0000:00:02.0: [drm] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 2880, found 0)
<7> [96.430915] xe 0000:00:02.0: [drm] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 3040, found 0)
<7> [96.430917] xe 0000:00:02.0: [drm] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 2880, found 0)
<7> [96.430918] xe 0000:00:02.0: [drm] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 3040, found 0)
<7> [96.430920] xe 0000:00:02.0: [drm] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 2928, found 0)
<7> [96.430922] xe 0000:00:02.0: [drm] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2960, found 0)
<7> [96.430923] xe 0000:00:02.0: [drm] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1800, found 0)
<7> [96.430925] xe 0000:00:02.0: [drm] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1800, found 0)