Hardware List

Results for igt@kms_flip@plain-flip-ts-check@a-edp1

Machine description: shard-lnl-6

Result: Fail

i915_display_info17 igt_runner17 results17.json results17-xe-load.json i915_display_info_post_exec17 boot17 dmesg17

Detail	Value
Duration	6.52 seconds
Hostname	shard-lnl-6
Igt-Version	IGT-Version: 2.0-g0498a2e21 (x86_64) (Linux: 6.14.0-rc6-xe+ x86_64)
Out	Using IGT_SRANDOM=1742294363 for randomisation Opened device: /dev/dri/card0 Using monotonic timestamps Starting subtest: plain-flip-ts-check Starting dynamic subtest: A-eDP1 2880x1800: 60 347710 2880 2928 2960 3040 1800 1803 1809 1906 0x48 0xa Expected frametime: 16664us; measured 16280.7us +- 3.877us accuracy 0.07% vblank interval differs from modeline! expected 16664.0us, measured 16281us +- 3.877us, difference 383.3us (98.9 sigma) Stack trace: #0 ../lib/igt_core.c:2055 __igt_fail_assert() #1 ../tests/kms_flip.c:777 run_test_on_crtc_set.constprop.0() #2 ../tests/kms_flip.c:1831 run_test() #3 ../tests/kms_flip.c:2064 __igt_unique____real_main1987() #4 ../tests/kms_flip.c:1987 main() #5 [__libc_init_first+0x8a] #6 [__libc_start_main+0x8b] #7 [_start+0x25] Dynamic subtest A-eDP1: FAIL (6.523s)
Err	Starting subtest: plain-flip-ts-check Starting dynamic subtest: A-eDP1 (kms_flip:3887) CRITICAL: Test assertion failure function __run_test_on_crtc_set, file ../tests/kms_flip.c:1660: (kms_flip:3887) CRITICAL: Failed assertion: !retried && needs_retry_after_link_reset(mon) Dynamic subtest A-eDP1 failed. ** DEBUG (kms_flip:3887) igt_fb-DEBUG: igt_create_fb_with_bo_size(width=2880, height=1800, format=XR24(0x34325258), modifier=0x0, size=0) (kms_flip:3887) igt_fb-DEBUG: igt_create_fb_with_bo_size(handle=1, pitch=11520) (kms_flip:3887) ioctl_wrappers-DEBUG: Test requirement passed: igt_has_fb_modifiers(fd) (kms_flip:3887) igt_fb-DEBUG: igt_create_fb_with_bo_size(width=2880, height=1800, format=XR24(0x34325258), modifier=0x0, size=0) (kms_flip:3887) igt_fb-DEBUG: igt_create_fb_with_bo_size(handle=2, pitch=11520) (kms_flip:3887) ioctl_wrappers-DEBUG: Test requirement passed: igt_has_fb_modifiers(fd) (kms_flip:3887) intel_allocator_simple-DEBUG: Using simple allocator (kms_flip:3887) intel_allocator_simple-DEBUG: <ial: 0x59bae044a350, fd: 5> objects: 3, reserved_areas: 0 (kms_flip:3887) intel_allocator_simple-DEBUG: <ial: 0x59bae044a350, fd: 5> objects: 3, reserved_areas: 0 (kms_flip:3887) intel_allocator_simple-DEBUG: Using simple allocator (kms_flip:3887) intel_allocator_simple-DEBUG: <ial: 0x59bae048f1a0, fd: 5> objects: 3, reserved_areas: 0 (kms_flip:3887) intel_allocator_simple-DEBUG: <ial: 0x59bae048f1a0, fd: 5> objects: 3, reserved_areas: 0 (kms_flip:3887) intel_allocator_simple-DEBUG: Using simple allocator (kms_flip:3887) intel_allocator_simple-DEBUG: <ial: 0x59bae0457850, fd: 5> objects: 3, reserved_areas: 0 (kms_flip:3887) intel_allocator_simple-DEBUG: <ial: 0x59bae0457850, fd: 5> objects: 3, reserved_areas: 0 (kms_flip:3887) intel_allocator_simple-DEBUG: Using simple allocator (kms_flip:3887) intel_allocator_simple-DEBUG: <ial: 0x59bae0457850, fd: 5> objects: 3, reserved_areas: 0 (kms_flip:3887) intel_allocator_simple-DEBUG: <ial: 0x59bae0457850, fd: 5> objects: 3, reserved_areas: 0 (kms_flip:3887) igt_kms-INFO: 2880x1800: 60 347710 2880 2928 2960 3040 1800 1803 1809 1906 0x48 0xa (kms_flip:3887) DEBUG: No stale events found (kms_flip:3887) i915/intel_drrs-DEBUG: Manually disabling DRRS. 0 (kms_flip:3887) i915/intel_drrs-DEBUG: Test requirement passed: dir >= 0 (kms_flip:3887) INFO: Expected frametime: 16664us; measured 16280.7us +- 3.877us accuracy 0.07% (kms_flip:3887) INFO: vblank interval differs from modeline! expected 16664.0us, measured 16281us +- 3.877us, difference 383.3us (98.9 sigma) (kms_flip:3887) DEBUG: name = flip last_ts = 0.000000 last_received_ts = 0.000000 last_seq = 0 current_ts = 119.826591 current_received_ts = 119.825806 current_seq = 774 count = 0 seq_step = 1 (kms_flip:3887) DEBUG: name = flip last_ts = 119.826591 last_received_ts = 119.825806 last_seq = 774 current_ts = 119.842873 current_received_ts = 119.842194 current_seq = 775 count = 1 seq_step = 1 (kms_flip:3887) DEBUG: flip ts/seq: last 119.826591/774, current 119.842873/775: elapsed=16281.0us expected=16280.7us +- 81.4us, error 0.0% (kms_flip:3887) DEBUG: name = flip last_ts = 119.842873 last_received_ts = 119.842194 last_seq = 775 current_ts = 119.859154 current_received_ts = 119.858482 current_seq = 776 count = 2 seq_step = 1 (kms_flip:3887) DEBUG: flip ts/seq: last 119.842873/775, current 119.859154/776: elapsed=16282.0us expected=16280.7us +- 81.4us, error 0.0% (kms_flip:3887) DEBUG: name = flip last_ts = 119.859154 last_received_ts = 119.858482 last_seq = 776 current_ts = 119.875435 current_received_ts = 119.874725 current_seq = 777 count = 3 seq_step = 1 (kms_flip:3887) DEBUG: flip ts/seq: last 119.859154/776, current 119.875435/777: elapsed=16279.0us expected=16280.7us +- 81.4us, error 0.0% (kms_flip:3887) DEBUG: name = flip last_ts = 119.875435 last_received_ts = 119.874725 last_seq = 777 current_ts = 119.891716 current_received_ts = 119.890991 current_seq = 778 count = 4 seq_step = 1 (kms_flip:3887) DEBUG: flip ts/seq: last 119.875435/777, current 119.891716/778: elapsed=16279.0us expected=16280.7us +- 81.4us, error 0.0% (kms_flip:3887) DEBUG: name = flip last_ts = 119.891716 last_received_ts = 119.890991 last_seq = 778 current_ts = 119.907997 current_received_ts = 119.907280 current_seq = 779 count = 5 seq_step = 1 (kms_flip:3887) DEBUG: flip ts/seq: last 119.891716/778, current 119.907997/779: elapsed=16282.0us expected=16280.7us +- 81.4us, error 0.0% (kms_flip:3887) DEBUG: name = flip last_ts = 119.907997 last_received_ts = 119.907280 last_seq = 779 current_ts = 119.924271 current_received_ts = 119.923553 current_seq = 780 count = 6 seq_step = 1 (kms_flip:3887) DEBUG: flip ts/seq: last 119.907997/779, current 119.924271/780: elapsed=16278.0us expected=16280.7us +- 81.4us, error 0.0% (kms_flip:3887) DEBUG: name = flip last_ts = 119.924271 last_received_ts = 119.923553 last_seq = 780 current_ts = 119.940552 current_received_ts = 119.939873 current_seq = 781 count = 7 seq_step = 1 (kms_flip:3887) DEBUG: flip ts/seq: last 119.924271/780, current 119.940552/781: elapsed=16282.0us expected=16280.7us +- 81.4us, error 0.0% (kms_flip:3887) DEBUG: name = flip last_ts = 119.940552 last_received_ts = 119.939873 last_seq = 781 current_ts = 119.956841 current_received_ts = 119.956146 current_seq = 782 count = 8 seq_step = 1 (kms_flip:3887) DEBUG: flip ts/seq: last 119.940552/781, current 119.956841/782: elapsed=16283.0us expected=16280.7us +- 81.4us, error 0.0% (kms_flip:3887) DEBUG: name = flip last_ts = 119.956841 last_received_ts = 119.956146 last_seq = 782 current_ts = 119.973122 current_received_ts = 119.972305 current_seq = 783 count = 9 seq_step = 1 (kms_flip:3887) DEBUG: flip ts/seq: last 119.956841/782, current 119.973122/783: elapsed=16281.0us expected=16280.7us +- 81.4us, error 0.0% (kms_flip:3887) DEBUG: name = flip last_ts = 119.973122 last_received_ts = 119.972305 last_seq = 783 current_ts = 119.989403 current_received_ts = 119.988609 current_seq = 784 count = 10 seq_step = 1 (kms_flip:3887) DEBUG: flip ts/seq: last 119.973122/783, current 119.989403/784: elapsed=16282.0us expected=16280.7us +- 81.4us, error 0.0% (kms_flip:3887) DEBUG: name = flip last_ts = 119.989403 last_received_ts = 119.988609 last_seq = 784 current_ts = 120.005676 current_received_ts = 120.005051 current_seq = 785 count = 11 seq_step = 1 (kms_flip:3887) DEBUG: flip ts/seq: last 119.989403/784, current 120.005676/785: elapsed=16277.0us expected=16280.7us +- 81.4us, error 0.0% (kms_flip:3887) DEBUG: name = flip last_ts = 120.005676 last_received_ts = 120.005051 last_seq = 785 current_ts = 120.021965 current_received_ts = 120.021217 current_seq = 786 count = 12 seq_step = 1 (kms_flip:3887) DEBUG: flip ts/seq: last 120.005676/785, current 120.021965/786: elapsed=16285.0us expected=16280.7us +- 81.4us, error 0.0% (kms_flip:3887) DEBUG: name = flip last_ts = 120.021965 last_received_ts = 120.021217 last_seq = 786 current_ts = 120.038246 current_received_ts = 120.037521 current_seq = 787 count = 13 seq_step = 1 (kms_flip:3887) DEBUG: flip ts/seq: last 120.021965/786, current 120.038246/787: elapsed=16283.0us expected=16280.7us +- 81.4us, error 0.0% (kms_flip:3887) DEBUG: name = flip last_ts = 120.038246 last_received_ts = 120.037521 last_seq = 787 current_ts = 120.054520 current_received_ts = 120.053795 current_seq = 788 count = 14 seq_step = 1 (kms_flip:3887) DEBUG: flip ts/seq: last 120.038246/787, current 120.054520/788: elapsed=16274.0us expected=16280.7us +- 81.4us, error 0.0% (kms_flip:3887) DEBUG: name = flip last_ts = 120.054520 last_received_ts = 120.053795 last_seq = 788 current_ts = 120.070808 current_received_ts = 120.070122 current_seq = 789 count = 15 seq_step = 1 (kms_flip:3887) DEBUG: flip ts/seq: last 120.054520/788, current 120.070808/789: elapsed=16285.0us expected=16280.7us +- 81.4us, error 0.0% (kms_flip:3887) DEBUG: name = flip last_ts = 120.070808 last_received_ts = 120.070122 last_seq = 789 current_ts = 120.087090 current_received_ts = 120.086349 current_seq = 790 count = 16 seq_step = 1 (kms_flip:3887) DEBUG: flip ts/seq: last 120.070808/789, current 120.087090/790: elapsed=16281.0us expected=16280.7us +- 81.4us, error 0.0% (kms_flip:3887) DEBUG: name = flip last_ts = 120.087090 last_received_ts = 120.086349 last_seq = 790 current_ts = 120.103363 current_received_ts = 120.102676 current_seq = 791 count = 17 seq_step = 1 (kms_flip:3887) DEBUG: flip ts/seq: last 120.087090/790, current 120.103363/791: elapsed=16276.0us expected=16280.7us +- 81.4us, error 0.0% (kms_flip:3887) DEBUG: name = flip last_ts = 120.103363 last_received_ts = 120.102676 last_seq = 791 current_ts = 120.119652 current_received_ts = 120.118958 current_seq = 792 count = 18 seq_step = 1 (kms_flip:3887) DEBUG: flip ts/seq: last 120.103363/791, current 120.119652/792: elapsed=16286.0us expected=16280.7us +- 81.4us, error 0.0% (kms_flip:3887) DEBUG: name = flip last_ts = 120.119652 last_received_ts = 120.118958 last_seq = 792 current_ts = 120.135925 current_received_ts = 120.135086 current_seq = 793 count = 19 seq_step = 1 (kms_flip:3887) DEBUG: flip ts/seq: last 120.119652/792, current 120.135925/793: elapsed=16281.0us expected=16280.7us +- 81.4us, error 0.0% (kms_flip:3887) DEBUG: name = flip last_ts = 120.135925 last_received_ts = 120.135086 last_seq = 793 current_ts = 120.152206 current_received_ts = 120.151390 current_seq = 794 count = 20 seq_step = 1 (kms_flip:3887) DEBUG: flip ts/seq: last 120.135925/793, current 120.152206/794: elapsed=16279.0us expected=16280.7us +- 81.4us, error 0.0% (kms_flip:3887) DEBUG: name = flip last_ts = 120.152206 last_received_ts = 120.151390 last_seq = 794 current_ts = 120.168488 current_received_ts = 120.167793 current_seq = 795 count = 21 seq_step = 1 (kms_flip:3887) DEBUG: flip ts/seq: last 120.152206/794, current 120.168488/795: elapsed=16278.0us expected=16280.7us +- 81.4us, error 0.0% (kms_flip:3887) DEBUG: name = flip last_ts = 120.168488 last_received_ts = 120.167793 last_seq = 795 current_ts = 120.184769 current_received_ts = 120.184067 current_seq = 796 count = 22 seq_step = 1 (kms_flip:3887) DEBUG: flip ts/seq: last 120.168488/795, current 120.184769/796: elapsed=16285.0us expected=16280.7us +- 81.4us, error 0.0% (kms_flip:3887) DEBUG: name = flip last_ts = 120.184769 last_received_ts = 120.184067 last_seq = 796 current_ts = 120.201050 current_received_ts = 120.200363 current_seq = 797 count = 23 seq_step = 1 (kms_flip:3887) DEBUG: flip ts/seq: last 120.184769/796, current 120.201050/797: elapsed=16281.0us expected=16280.7us +- 81.4us, error 0.0% (kms_flip:3887) DEBUG: name = flip last_ts = 120.201050 last_received_ts = 120.200363 last_seq = 797 current_ts = 120.217331 current_received_ts = 120.216644 current_seq = 798 count = 24 seq_step = 1 (kms_flip:3887) DEBUG: flip ts/seq: last 120.201050/797, current 120.217331/798: elapsed=16279.0us expected=16280.7us +- 81.4us, error 0.0% (kms_flip:3887) DEBUG: name = flip last_ts = 120.217331 last_received_ts = 120.216644 last_seq = 798 current_ts = 120.233612 current_received_ts = 120.232903 current_seq = 799 count = 25 seq_step = 1 (kms_flip:3887) DEBUG: flip ts/seq: last 120.217331/798, current 120.233612/799: elapsed=16284.0us expected=16280.7us +- 81.4us, error 0.0% (kms_flip:3887) DEBUG: name = flip last_ts = 120.233612 last_received_ts = 120.232903 last_seq = 799 current_ts = 120.249893 current_received_ts = 120.249146 current_seq = 800 count = 26 seq_step = 1 (kms_flip:3887) DEBUG: flip ts/seq: last 120.233612/799, current 120.249893/800: elapsed=16281.0us expected=16280.7us +- 81.4us, error 0.0% (kms_flip:3887) DEBUG: name = flip last_ts = 120.249893 last_received_ts = 120.249146 last_seq = 800 current_ts = 120.266174 current_received_ts = 120.265442 current_seq = 801 count = 27 seq_step = 1 (kms_flip:3887) DEBUG: flip ts/seq: last 120.249893/800, current 120.266174/801: elapsed=16275.0us expected=16280.7us +- 81.4us, error 0.0% (kms_flip:3887) DEBUG: name = flip last_ts = 120.266174 last_received_ts = 120.265442 last_seq = 801 current_ts = 120.282448 current_received_ts = 120.281792 current_seq = 802 count = 28 seq_step = 1 (kms_flip:3887) DEBUG: flip ts/seq: last 120.266174/801, current 120.282448/802: elapsed=16280.0us expected=16280.7us +- 81.4us, error 0.0% (kms_flip:3887) DEBUG: name = flip last_ts = 120.282448 last_received_ts = 120.281792 last_seq = 802 current_ts = 120.298737 current_received_ts = 120.298065 current_seq = 803 count = 29 seq_step = 1 (kms_flip:3887) DEBUG: flip ts/seq: last 120.282448/802, current 120.298737/803: elapsed=16283.0us expected=16280.7us +- 81.4us, error 0.0% (kms_flip:3887) DEBUG: name = flip last_ts = 120.298737 last_received_ts = 120.298065 last_seq = 803 current_ts = 120.315018 current_received_ts = 120.314316 current_seq = 804 count = 30 seq_step = 1 (kms_flip:3887) DEBUG: flip ts/seq: last 120.298737/803, current 120.315018/804: elapsed=16280.0us expected=16280.7us +- 81.4us, error 0.0% (kms_flip:3887) DEBUG: name = flip last_ts = 120.315018 last_received_ts = 120.314316 last_seq = 804 current_ts = 120.331291 current_received_ts = 120.330620 current_seq = 805 count = 31 seq_step = 1 (kms_flip:3887) DEBUG: flip ts/seq: last 120.315018/804, current 120.331291/805: elapsed=16280.0us expected=16280.7us +- 81.4us, error 0.0% (kms_flip:3887) DEBUG: name = flip last_ts = 120.331291 last_received_ts = 120.330620 last_seq = 805 current_ts = 120.347580 current_received_ts = 120.346909 current_seq = 806 count = 32 seq_step = 1 (kms_flip:3887) DEBUG: flip ts/seq: last 120.331291/805, current 120.347580/806: elapsed=16283.0us expected=16280.7us +- 81.4us, error 0.0% (kms_flip:3887) DEBUG: name = flip last_ts = 120.347580 last_received_ts = 120.346909 last_seq = 806 current_ts = 120.363861 current_received_ts = 120.363152 current_seq = 807 count = 33 seq_step = 1 (kms_flip:3887) DEBUG: flip ts/seq: last 120.347580/806, current 120.363861/807: elapsed=16284.0us expected=16280.7us +- 81.4us, error 0.0% (kms_flip:3887) DEBUG: name = flip last_ts = 120.363861 last_received_ts = 120.363152 last_seq = 807 current_ts = 120.380142 current_received_ts = 120.379333 current_seq = 808 count = 34 seq_step = 1 (kms_flip:3887) DEBUG: flip ts/seq: last 120.363861/807, current 120.380142/808: elapsed=16284.0us expected=16280.7us +- 81.4us, error 0.0% (kms_flip:3887) DEBUG: name = flip last_ts = 120.380142 last_received_ts = 120.379333 last_seq = 808 current_ts = 120.396416 current_received_ts = 120.395599 current_seq = 809 count = 35 seq_step = 1 (kms_flip:3887) DEBUG: flip ts/seq: last 120.380142/808, current 120.396416/809: elapsed=16273.0us expected=16280.7us +- 81.4us, error 0.0% (kms_flip:3887) DEBUG: name = flip last_ts = 120.396416 last_received_ts = 120.395599 last_seq = 809 current_ts = 120.412697 current_received_ts = 120.411980 current_seq = 810 count = 36 seq_step = 1 (kms_flip:3887) DEBUG: flip ts/seq: last 120.396416/809, current 120.412697/810: elapsed=16280.0us expected=16280.7us +- 81.4us, error 0.0% (kms_flip:3887) DEBUG: name = flip last_ts = 120.412697 last_received_ts = 120.411980 last_seq = 810 current_ts = 120.428978 current_received_ts = 120.428246 current_seq = 811 count = 37 seq_step = 1 (kms_flip:3887) DEBUG: flip ts/seq: last 120.412697/810, current 120.428978/811: elapsed=16279.0us expected=16280.7us +- 81.4us, error 0.0% (kms_flip:3887) DEBUG: name = flip last_ts = 120.428978 last_received_ts = 120.428246 last_seq = 811 current_ts = 120.445267 current_received_ts = 120.444435 current_seq = 812 count = 38 seq_step = 1 (kms_flip:3887) DEBUG: flip ts/seq: last 120.428978/811, current 120.445267/812: elapsed=16286.0us expected=16280.7us +- 81.4us, error 0.0% (kms_flip:3887) DEBUG: name = flip last_ts = 120.445267 last_received_ts = 120.444435 last_seq = 812 current_ts = 120.461594 current_received_ts = 120.460861 current_seq = 813 count = 39 seq_step = 1 (kms_flip:3887) DEBUG: flip ts/seq: last 120.445267/812, current 120.461594/813: elapsed=16329.0us expected=16280.7us +- 81.4us, error 0.3% (kms_flip:3887) DEBUG: name = flip last_ts = 120.461594 last_received_ts = 120.460861 last_seq = 813 current_ts = 120.477921 current_received_ts = 120.477234 current_seq = 814 count = 40 seq_step = 1 (kms_flip:3887) DEBUG: flip ts/seq: last 120.461594/813, current 120.477921/814: elapsed=16326.0us expected=16280.7us +- 81.4us, error 0.3% (kms_flip:3887) DEBUG: name = flip last_ts = 120.477921 last_received_ts = 120.477234 last_seq = 814 current_ts = 120.494247 current_received_ts = 120.493507 current_seq = 815 count = 41 seq_step = 1 (kms_flip:3887) DEBUG: flip ts/seq: last 120.477921/814, current 120.494247/815: elapsed=16329.0us expected=16280.7us +- 81.4us, error 0.3% (kms_flip:3887) DEBUG: name = flip last_ts = 120.494247 last_received_ts = 120.493507 last_seq = 815 current_ts = 120.510574 current_received_ts = 120.509850 current_seq = 816 count = 42 seq_step = 1 (kms_flip:3887) DEBUG: flip ts/seq: last 120.494247/815, current 120.510574/816: elapsed=16329.0us expected=16280.7us +- 81.4us, error 0.3% (kms_flip:3887) DEBUG: name = flip last_ts = 120.510574 last_received_ts = 120.509850 last_seq = 816 current_ts = 120.526901 current_received_ts = 120.526161 current_seq = 817 count = 43 seq_step = 1 (kms_flip:3887) DEBUG: flip ts/seq: last 120.510574/816, current 120.526901/817: elapsed=16325.0us expected=16280.7us +- 81.4us, error 0.3% (kms_flip:3887) DEBUG: name = flip last_ts = 120.526901 last_received_ts = 120.526161 last_seq = 817 current_ts = 120.543236 current_received_ts = 120.542389 current_seq = 818 count = 44 seq_step = 1 (kms_flip:3887) DEBUG: flip ts/seq: last 120.526901/817, current 120.543236/818: elapsed=16331.0us expected=16280.7us +- 81.4us, error 0.3% (kms_flip:3887) DEBUG: name = flip last_ts = 120.543236 last_received_ts = 120.542389 last_seq = 818 current_ts = 120.559555 current_received_ts = 120.558884 current_seq = 819 count = 45 seq_step = 1 (kms_flip:3887) DEBUG: flip ts/seq: last 120.543236/818, current 120.559555/819: elapsed=16325.0us expected=16280.7us +- 81.4us, error 0.3% (kms_flip:3887) DEBUG: name = flip last_ts = 120.559555 last_received_ts = 120.558884 last_seq = 819 current_ts = 120.575882 current_received_ts = 120.575134 current_seq = 820 count = 46 seq_step = 1 (kms_flip:3887) DEBUG: flip ts/seq: last 120.559555/819, current 120.575882/820: elapsed=16326.0us expected=16280.7us +- 81.4us, error 0.3% (kms_flip:3887) DEBUG: name = flip last_ts = 120.575882 last_received_ts = 120.575134 last_seq = 820 current_ts = 120.592216 current_received_ts = 120.591507 current_seq = 821 count = 47 seq_step = 1 (kms_flip:3887) DEBUG: flip ts/seq: last 120.575882/820, current 120.592216/821: elapsed=16330.0us expected=16280.7us +- 81.4us, error 0.3% (kms_flip:3887) DEBUG: name = flip last_ts = 120.592216 last_received_ts = 120.591507 last_seq = 821 current_ts = 120.608543 current_received_ts = 120.607803 current_seq = 822 count = 48 seq_step = 1 (kms_flip:3887) DEBUG: flip ts/seq: last 120.592216/821, current 120.608543/822: elapsed=16328.0us expected=16280.7us +- 81.4us, error 0.3% (kms_flip:3887) DEBUG: name = flip last_ts = 120.608543 last_received_ts = 120.607803 last_seq = 822 current_ts = 120.624863 current_received_ts = 120.624123 current_seq = 823 count = 49 seq_step = 1 (kms_flip:3887) DEBUG: flip ts/seq: last 120.608543/822, current 120.624863/823: elapsed=16323.0us expected=16280.7us +- 81.4us, error 0.3% (kms_flip:3887) DEBUG: name = flip last_ts = 120.624863 last_received_ts = 120.624123 last_seq = 823 current_ts = 120.641190 current_received_ts = 120.640411 current_seq = 824 count = 50 seq_step = 1 (kms_flip:3887) DEBUG: flip ts/seq: last 120.624863/823, current 120.641190/824: elapsed=16328.0us expected=16280.7us +- 81.4us, error 0.3% (kms_flip:3887) DEBUG: name = flip last_ts = 120.641190 last_received_ts = 120.640411 last_seq = 824 current_ts = 120.657524 current_received_ts = 120.656731 current_seq = 825 count = 51 seq_step = 1 (kms_flip:3887) DEBUG: flip ts/seq: last 120.641190/824, current 120.657524/825: elapsed=16329.0us expected=16280.7us +- 81.4us, error 0.3% (kms_flip:3887) DEBUG: name = flip last_ts = 120.657524 last_received_ts = 120.656731 last_seq = 825 current_ts = 120.673851 current_received_ts = 120.673126 current_seq = 826 count = 52 seq_step = 1 (kms_flip:3887) DEBUG: flip ts/seq: last 120.657524/825, current 120.673851/826: elapsed=16330.0us expected=16280.7us +- 81.4us, error 0.3% (kms_flip:3887) DEBUG: name = flip last_ts = 120.673851 last_received_ts = 120.673126 last_seq = 826 current_ts = 120.690178 current_received_ts = 120.689514 current_seq = 827 count = 53 seq_step = 1 (kms_flip:3887) DEBUG: flip ts/seq: last 120.673851/826, current 120.690178/827: elapsed=16326.0us expected=16280.7us +- 81.4us, error 0.3% (kms_flip:3887) DEBUG: name = flip last_ts = 120.690178 last_received_ts = 120.689514 last_seq = 827 current_ts = 120.706505 current_received_ts = 120.705818 current_seq = 828 count = 54 seq_step = 1 (kms_flip:3887) DEBUG: flip ts/seq: last 120.690178/827, current 120.706505/828: elapsed=16329.0us expected=16280.7us +- 81.4us, error 0.3% (kms_flip:3887) DEBUG: name = flip last_ts = 120.706505 last_received_ts = 120.705818 last_seq = 828 current_ts = 120.722824 current_received_ts = 120.722145 current_seq = 829 count = 55 seq_step = 1 (kms_flip:3887) DEBUG: flip ts/seq: last 120.706505/828, current 120.722824/829: elapsed=16321.0us expected=16280.7us +- 81.4us, error 0.2% (kms_flip:3887) DEBUG: name = flip last_ts = 120.722824 last_received_ts = 120.722145 last_seq = 829 current_ts = 120.739159 current_received_ts = 120.738464 current_seq = 830 count = 56 seq_step = 1 (kms_flip:3887) DEBUG: flip ts/seq: last 120.722824/829, current 120.739159/830: elapsed=16329.0us expected=16280.7us +- 81.4us, error 0.3% (kms_flip:3887) DEBUG: name = flip last_ts = 120.739159 last_received_ts = 120.738464 last_seq = 830 current_ts = 120.755493 current_received_ts = 120.754768 current_seq = 831 count = 57 seq_step = 1 (kms_flip:3887) DEBUG: flip ts/seq: last 120.739159/830, current 120.755493/831: elapsed=16334.0us expected=16280.7us +- 81.4us, error 0.3% (kms_flip:3887) DEBUG: name = flip last_ts = 120.755493 last_received_ts = 120.754768 last_seq = 831 current_ts = 120.771820 current_received_ts = 120.771088 current_seq = 832 count = 58 seq_step = 1 (kms_flip:3887) DEBUG: flip ts/seq: last 120.755493/831, current 120.771820/832: elapsed=16327.0us expected=16280.7us +- 81.4us, error 0.3% (kms_flip:3887) DEBUG: name = flip last_ts = 120.771820 last_received_ts = 120.771088 last_seq = 832 current_ts = 120.788139 current_received_ts = 120.787315 current_seq = 833 count = 59 seq_step = 1 (kms_flip:3887) DEBUG: flip ts/seq: last 120.771820/832, current 120.788139/833: elapsed=16324.0us expected=16280.7us +- 81.4us, error 0.3% (kms_flip:3887) DEBUG: name = flip last_ts = 120.788139 last_received_ts = 120.787315 last_seq = 833 current_ts = 120.804466 current_received_ts = 120.803787 current_seq = 834 count = 60 seq_step = 1 (kms_flip:3887) DEBUG: flip ts/seq: last 120.788139/833, current 120.804466/834: elapsed=16325.0us expected=16280.7us +- 81.4us, error 0.3% (kms_flip:3887) DEBUG: name = flip last_ts = 120.804466 last_received_ts = 120.803787 last_seq = 834 current_ts = 120.820801 current_received_ts = 120.820030 current_seq = 835 count = 61 seq_step = 1 (kms_flip:3887) DEBUG: flip ts/seq: last 120.804466/834, current 120.820801/835: elapsed=16334.0us expected=16280.7us +- 81.4us, error 0.3% (kms_flip:3887) DEBUG: name = flip last_ts = 120.820801 last_received_ts = 120.820030 last_seq = 835 current_ts = 120.837128 current_received_ts = 120.836334 current_seq = 836 count = 62 seq_step = 1 (kms_flip:3887) DEBUG: flip ts/seq: last 120.820801/835, current 120.837128/836: elapsed=16328.0us expected=16280.7us +- 81.4us, error 0.3% (kms_flip:3887) DEBUG: name = flip last_ts = 120.837128 last_received_ts = 120.836334 last_seq = 836 current_ts = 120.853447 current_received_ts = 120.852737 current_seq = 837 count = 63 seq_step = 1 (kms_flip:3887) DEBUG: flip ts/seq: last 120.837128/836, current 120.853447/837: elapsed=16322.0us expected=16280.7us +- 81.4us, error 0.3% (kms_flip:3887) DEBUG: name = flip last_ts = 120.853447 last_received_ts = 120.852737 last_seq = 837 current_ts = 120.869781 current_received_ts = 120.869072 current_seq = 838 count = 64 seq_step = 1 (kms_flip:3887) DEBUG: flip ts/seq: last 120.853447/837, current 120.869781/838: elapsed=16332.0us expected=16280.7us +- 81.4us, error 0.3% (kms_flip:3887) DEBUG: name = flip last_ts = 120.869781 last_received_ts = 120.869072 last_seq = 838 current_ts = 120.886108 current_received_ts = 120.885315 current_seq = 839 count = 65 seq_step = 1 (kms_flip:3887) DEBUG: flip ts/seq: last 120.869781/838, current 120.886108/839: elapsed=16324.0us expected=16280.7us +- 81.4us, error 0.3% (kms_flip:3887) DEBUG: name = flip last_ts = 120.886108 last_received_ts = 120.885315 last_seq = 839 current_ts = 120.902435 current_received_ts = 120.901756 current_seq = 840 count = 66 seq_step = 1 (kms_flip:3887) DEBUG: flip ts/seq: last 120.886108/839, current 120.902435/840: elapsed=16333.0us expected=16280.7us +- 81.4us, error 0.3% (kms_flip:3887) DEBUG: name = flip last_ts = 120.902435 last_received_ts = 120.901756 last_seq = 840 current_ts = 120.918762 current_received_ts = 120.918037 current_seq = 841 count = 67 seq_step = 1 (kms_flip:3887) DEBUG: flip ts/seq: last 120.902435/840, current 120.918762/841: elapsed=16327.0us expected=16280.7us +- 81.4us, error 0.3% (kms_flip:3887) DEBUG: name = flip last_ts = 120.918762 last_received_ts = 120.918037 last_seq = 841 current_ts = 120.935089 current_received_ts = 120.934395 current_seq = 842 count = 68 seq_step = 1 (kms_flip:3887) DEBUG: flip ts/seq: last 120.918762/841, current 120.935089/842: elapsed=16323.0us expected=16280.7us +- 81.4us, error 0.3% (kms_flip:3887) DEBUG: name = flip last_ts = 120.935089 last_received_ts = 120.934395 last_seq = 842 current_ts = 120.951416 current_received_ts = 120.950722 current_seq = 843 count = 69 seq_step = 1 (kms_flip:3887) DEBUG: flip ts/seq: last 120.935089/842, current 120.951416/843: elapsed=16325.0us expected=16280.7us +- 81.4us, error 0.3% (kms_flip:3887) DEBUG: name = flip last_ts = 120.951416 last_received_ts = 120.950722 last_seq = 843 current_ts = 120.967743 current_received_ts = 120.967033 current_seq = 844 count = 70 seq_step = 1 (kms_flip:3887) DEBUG: flip ts/seq: last 120.951416/843, current 120.967743/844: elapsed=16329.0us expected=16280.7us +- 81.4us, error 0.3% (kms_flip:3887) DEBUG: name = flip last_ts = 120.967743 last_received_ts = 120.967033 last_seq = 844 current_ts = 120.984070 current_received_ts = 120.983398 current_seq = 845 count = 71 seq_step = 1 (kms_flip:3887) DEBUG: flip ts/seq: last 120.967743/844, current 120.984070/845: elapsed=16330.0us expected=16280.7us +- 81.4us, error 0.3% (kms_flip:3887) DEBUG: name = flip last_ts = 120.984070 last_received_ts = 120.983398 last_seq = 845 current_ts = 121.000397 current_received_ts = 120.999710 current_seq = 846 count = 72 seq_step = 1 (kms_flip:3887) DEBUG: flip ts/seq: last 120.984070/845, current 121.000397/846: elapsed=16325.0us expected=16280.7us +- 81.4us, error 0.3% (kms_flip:3887) DEBUG: name = flip last_ts = 121.000397 last_received_ts = 120.999710 last_seq = 846 current_ts = 121.016724 current_received_ts = 121.016029 current_seq = 847 count = 73 seq_step = 1 (kms_flip:3887) DEBUG: flip ts/seq: last 121.000397/846, current 121.016724/847: elapsed=16329.0us expected=16280.7us +- 81.4us, error 0.3% (kms_flip:3887) DEBUG: name = flip last_ts = 121.016724 last_received_ts = 121.016029 last_seq = 847 current_ts = 121.033058 current_received_ts = 121.032295 current_seq = 848 count = 74 seq_step = 1 (kms_flip:3887) DEBUG: flip ts/seq: last 121.016724/847, current 121.033058/848: elapsed=16330.0us expected=16280.7us +- 81.4us, error 0.3% (kms_flip:3887) DEBUG: name = flip last_ts = 121.033058 last_received_ts = 121.032295 last_seq = 848 current_ts = 121.049377 current_received_ts = 121.048660 current_seq = 849 count = 75 seq_step = 1 (kms_flip:3887) DEBUG: flip ts/seq: last 121.033058/848, current 121.049377/849: elapsed=16323.0us expected=16280.7us +- 81.4us, error 0.3% (kms_flip:3887) DEBUG: name = flip last_ts = 121.049377 last_received_ts = 121.048660 last_seq = 849 current_ts = 121.065720 current_received_ts = 121.065033 current_seq = 850 count = 76 seq_step = 1 (kms_flip:3887) DEBUG: flip ts/seq: last 121.049377/849, current 121.065720/850: elapsed=16336.0us expected=16280.7us +- 81.4us, error 0.3% (kms_flip:3887) DEBUG: name = flip last_ts = 121.065720 last_received_ts = 121.065033 last_seq = 850 current_ts = 121.082039 current_received_ts = 121.081345 current_seq = 851 count = 77 seq_step = 1 (kms_flip:3887) DEBUG: flip ts/seq: last 121.065720/850, current 121.082039/851: elapsed=16323.0us expected=16280.7us +- 81.4us, error 0.3% (kms_flip:3887) DEBUG: name = flip last_ts = 121.082039 last_received_ts = 121.081345 last_seq = 851 current_ts = 121.098373 current_received_ts = 121.097649 current_seq = 852 count = 78 seq_step = 1 (kms_flip:3887) DEBUG: flip ts/seq: last 121.082039/851, current 121.098373/852: elapsed=16332.0us expected=16280.7us +- 81.4us, error 0.3% (kms_flip:3887) DEBUG: name = flip last_ts = 121.098373 last_received_ts = 121.097649 last_seq = 852 current_ts = 121.114700 current_received_ts = 121.114021 current_seq = 853 count = 79 seq_step = 1 (kms_flip:3887) DEBUG: flip ts/seq: last 121.098373/852, current 121.114700/853: elapsed=16327.0us expected=16280.7us +- 81.4us, error 0.3% (kms_flip:3887) DEBUG: name = flip last_ts = 121.114700 last_received_ts = 121.114021 last_seq = 853 current_ts = 121.131027 current_received_ts = 121.130302 current_seq = 854 count = 80 seq_step = 1 (kms_flip:3887) DEBUG: flip ts/seq: last 121.114700/853, current 121.131027/854: elapsed=16326.0us expected=16280.7us +- 81.4us, error 0.3% (kms_flip:3887) DEBUG: name = flip last_ts = 121.131027 last_received_ts = 121.130302 last_seq = 854 current_ts = 121.147346 current_received_ts = 121.146645 current_seq = 855 count = 81 seq_step = 1 (kms_flip:3887) DEBUG: flip ts/seq: last 121.131027/854, current 121.147346/855: elapsed=16325.0us expected=16280.7us +- 81.4us, error 0.3% (kms_flip:3887) DEBUG: name = flip last_ts = 121.147346 last_received_ts = 121.146645 last_seq = 855 current_ts = 121.163681 current_received_ts = 121.162949 current_seq = 856 count = 82 seq_step = 1 (kms_flip:3887) DEBUG: flip ts/seq: last 121.147346/855, current 121.163681/856: elapsed=16332.0us expected=16280.7us +- 81.4us, error 0.3% (kms_flip:3887) DEBUG: name = flip last_ts = 121.163681 last_received_ts = 121.162949 last_seq = 856 current_ts = 121.180000 current_received_ts = 121.179283 current_seq = 857 count = 83 seq_step = 1 (kms_flip:3887) DEBUG: flip ts/seq: last 121.163681/856, current 121.180000/857: elapsed=16323.0us expected=16280.7us +- 81.4us, error 0.3% (kms_flip:3887) DEBUG: name = flip last_ts = 121.180000 last_received_ts = 121.179283 last_seq = 857 current_ts = 121.196335 current_received_ts = 121.195511 current_seq = 858 count = 84 seq_step = 1 (kms_flip:3887) DEBUG: flip ts/seq: last 121.180000/857, current 121.196335/858: elapsed=16329.0us expected=16280.7us +- 81.4us, error 0.3% (kms_flip:3887) DEBUG: name = flip last_ts = 121.196335 last_received_ts = 121.195511 last_seq = 858 current_ts = 121.212662 current_received_ts = 121.211830 current_seq = 859 count = 85 seq_step = 1 (kms_flip:3887) DEBUG: flip ts/seq: last 121.196335/858, current 121.212662/859: elapsed=16327.0us expected=16280.7us +- 81.4us, error 0.3% (kms_flip:3887) DEBUG: name = flip last_ts = 121.212662 last_received_ts = 121.211830 last_seq = 859 current_ts = 121.228996 current_received_ts = 121.228142 current_seq = 860 count = 86 seq_step = 1 (kms_flip:3887) DEBUG: flip ts/seq: last 121.212662/859, current 121.228996/860: elapsed=16333.0us expected=16280.7us +- 81.4us, error 0.3% (kms_flip:3887) DEBUG: name = flip last_ts = 121.228996 last_received_ts = 121.228142 last_seq = 860 current_ts = 121.245316 current_received_ts = 121.244453 current_seq = 861 count = 87 seq_step = 1 (kms_flip:3887) DEBUG: flip ts/seq: last 121.228996/860, current 121.245316/861: elapsed=16321.0us expected=16280.7us +- 81.4us, error 0.2% (kms_flip:3887) DEBUG: name = flip last_ts = 121.245316 last_received_ts = 121.244453 last_seq = 861 current_ts = 121.261642 current_received_ts = 121.260826 current_seq = 862 count = 88 seq_step = 1 (kms_flip:3887) DEBUG: flip ts/seq: last 121.245316/861, current 121.261642/862: elapsed=16328.0us expected=16280.7us +- 81.4us, error 0.3% (kms_flip:3887) DEBUG: name = flip last_ts = 121.261642 last_received_ts = 121.260826 last_seq = 862 current_ts = 121.277969 current_received_ts = 121.277153 current_seq = 863 count = 89 seq_step = 1 (kms_flip:3887) DEBUG: flip ts/seq: last 121.261642/862, current 121.277969/863: elapsed=16331.0us expected=16280.7us +- 81.4us, error 0.3% (kms_flip:3887) DEBUG: name = flip last_ts = 121.277969 last_received_ts = 121.277153 last_seq = 863 current_ts = 121.294296 current_received_ts = 121.293472 current_seq = 864 count = 90 seq_step = 1 (kms_flip:3887) DEBUG: flip ts/seq: last 121.277969/863, current 121.294296/864: elapsed=16326.0us expected=16280.7us +- 81.4us, error 0.3% (kms_flip:3887) DEBUG: name = flip last_ts = 121.294296 last_received_ts = 121.293472 last_seq = 864 current_ts = 121.310631 current_received_ts = 121.309799 current_seq = 865 count = 91 seq_step = 1 (kms_flip:3887) DEBUG: flip ts/seq: last 121.294296/864, current 121.310631/865: elapsed=16330.0us expected=16280.7us +- 81.4us, error 0.3% (kms_flip:3887) DEBUG: name = flip last_ts = 121.310631 last_received_ts = 121.309799 last_seq = 865 current_ts = 121.326958 current_received_ts = 121.326118 current_seq = 866 count = 92 seq_step = 1 (kms_flip:3887) DEBUG: flip ts/seq: last 121.310631/865, current 121.326958/866: elapsed=16325.0us expected=16280.7us +- 81.4us, error 0.3% (kms_flip:3887) DEBUG: name = flip last_ts = 121.326958 last_received_ts = 121.326118 last_seq = 866 current_ts = 121.343277 current_received_ts = 121.342445 current_seq = 867 count = 93 seq_step = 1 (kms_flip:3887) DEBUG: flip ts/seq: last 121.326958/866, current 121.343277/867: elapsed=16325.0us expected=16280.7us +- 81.4us, error 0.3% (kms_flip:3887) DEBUG: name = flip last_ts = 121.343277 last_received_ts = 121.342445 last_seq = 867 current_ts = 121.359612 current_received_ts = 121.358780 current_seq = 868 count = 94 seq_step = 1 (kms_flip:3887) DEBUG: flip ts/seq: last 121.343277/867, current 121.359612/868: elapsed=16335.0us expected=16280.7us +- 81.4us, error 0.3% (kms_flip:3887) DEBUG: name = flip last_ts = 121.359612 last_received_ts = 121.358780 last_seq = 868 current_ts = 121.375938 current_received_ts = 121.375107 current_seq = 869 count = 95 seq_step = 1 (kms_flip:3887) DEBUG: flip ts/seq: last 121.359612/868, current 121.375938/869: elapsed=16325.0us expected=16280.7us +- 81.4us, error 0.3% (kms_flip:3887) DEBUG: name = flip last_ts = 121.375938 last_received_ts = 121.375107 last_seq = 869 current_ts = 121.392273 current_received_ts = 121.391487 current_seq = 870 count = 96 seq_step = 1 (kms_flip:3887) DEBUG: flip ts/seq: last 121.375938/869, current 121.392273/870: elapsed=16332.0us expected=16280.7us +- 81.4us, error 0.3% (kms_flip:3887) DEBUG: name = flip last_ts = 121.392273 last_received_ts = 121.391487 last_seq = 870 current_ts = 121.408600 current_received_ts = 121.407768 current_seq = 871 count = 97 seq_step = 1 (kms_flip:3887) DEBUG: flip ts/seq: last 121.392273/870, current 121.408600/871: elapsed=16326.0us expected=16280.7us +- 81.4us, error 0.3% (kms_flip:3887) DEBUG: name = flip last_ts = 121.408600 last_received_ts = 121.407768 last_seq = 871 current_ts = 121.424919 current_received_ts = 121.424103 current_seq = 872 count = 98 seq_step = 1 (kms_flip:3887) DEBUG: flip ts/seq: last 121.408600/871, current 121.424919/872: elapsed=16324.0us expected=16280.7us +- 81.4us, error 0.3% (kms_flip:3887) DEBUG: name = flip last_ts = 121.424919 last_received_ts = 121.424103 last_seq = 872 current_ts = 121.441246 current_received_ts = 121.440437 current_seq = 873 count = 99 seq_step = 1 (kms_flip:3887) DEBUG: flip ts/seq: last 121.424919/872, current 121.441246/873: elapsed=16328.0us expected=16280.7us +- 81.4us, error 0.3% (kms_flip:3887) DEBUG: name = flip last_ts = 121.441246 last_received_ts = 121.440437 last_seq = 873 current_ts = 121.457611 current_received_ts = 121.456795 current_seq = 874 count = 100 seq_step = 1 (kms_flip:3887) DEBUG: flip ts/seq: last 121.441246/873, current 121.457611/874: elapsed=16361.0us expected=16280.7us +- 81.4us, error 0.5% (kms_flip:3887) DEBUG: name = flip last_ts = 121.457611 last_received_ts = 121.456795 last_seq = 874 current_ts = 121.473976 current_received_ts = 121.473160 current_seq = 875 count = 101 seq_step = 1 (kms_flip:3887) DEBUG: flip ts/seq: last 121.457611/874, current 121.473976/875: elapsed=16364.0us expected=16280.7us +- 81.4us, error 0.5% (kms_flip:3887) DEBUG: inconsistent flip ts/seq: last 121.457611/874, current 121.473976/875: elapsed=16364.0us expected=16280.7us (kms_flip:3887) DEBUG: expected 51759, counted 101, encoder type 2 (kms_flip:3887) DEBUG: dropped frames, expected 51759, counted 101, encoder type 2 (kms_flip:3887) CRITICAL: Test assertion failure function __run_test_on_crtc_set, file ../tests/kms_flip.c:1660: (kms_flip:3887) CRITICAL: Failed assertion: !retried && needs_retry_after_link_reset(mon) (kms_flip:3887) igt_core-INFO: Stack trace: (kms_flip:3887) igt_core-INFO: #0 ../lib/igt_core.c:2055 __igt_fail_assert() (kms_flip:3887) igt_core-INFO: #1 ../tests/kms_flip.c:777 run_test_on_crtc_set.constprop.0() (kms_flip:3887) igt_core-INFO: #2 ../tests/kms_flip.c:1831 run_test() (kms_flip:3887) igt_core-INFO: #3 ../tests/kms_flip.c:2064 __igt_unique____real_main1987() (kms_flip:3887) igt_core-INFO: #4 ../tests/kms_flip.c:1987 main() (kms_flip:3887) igt_core-INFO: #5 [__libc_init_first+0x8a] (kms_flip:3887) igt_core-INFO: #6 [__libc_start_main+0x8b] (kms_flip:3887) igt_core-INFO: #7 [_start+0x25] END ** Dynamic subtest A-eDP1: FAIL (6.523s)
Dmesg	<6> [146.229021] Console: switching to colour dummy device 80x25 <6> [146.229081] [IGT] kms_flip: executing <7> [146.235387] xe 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:198:DP-1] <7> [146.235405] xe 0000:00:02.0: [drm:intel_dp_detect [xe]] [CONNECTOR:198:DP-1] <7> [146.235558] xe 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:198:DP-1] disconnected <7> [146.235779] xe 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:210:DP-2] <7> [146.235786] xe 0000:00:02.0: [drm:intel_dp_detect [xe]] [CONNECTOR:210:DP-2] <7> [146.235930] xe 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:210:DP-2] disconnected <7> [146.236206] xe 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:218:DP-3] <7> [146.236214] xe 0000:00:02.0: [drm:intel_dp_detect [xe]] [CONNECTOR:218:DP-3] <7> [146.236339] xe 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:218:DP-3] disconnected <7> [146.236656] xe 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:189:eDP-1] <7> [146.236664] xe 0000:00:02.0: [drm:intel_dp_detect [xe]] [CONNECTOR:189:eDP-1] <7> [146.237370] 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> [146.237910] xe 0000:00:02.0: [drm:intel_dp_print_rates [xe]] source rates: 162000, 216000, 243000, 270000, 324000, 432000, 540000, 675000, 810000 <7> [146.238013] xe 0000:00:02.0: [drm:intel_dp_print_rates [xe]] sink rates: 162000, 216000, 243000, 270000, 324000, 432000, 540000, 810000 <7> [146.238111] xe 0000:00:02.0: [drm:intel_dp_print_rates [xe]] common rates: 162000, 216000, 243000, 270000, 324000, 432000, 540000, 810000 <7> [146.238228] xe 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:189:eDP-1] Supported Monitor Refresh rate range is 40 Hz - 120 Hz <5> [146.238234] [drm] DisplayID checksum invalid, remainder is 248 <5> [146.238236] [drm] DisplayID checksum invalid, remainder is 248 <7> [146.238237] xe 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:189:eDP-1] Assigning EDID-1.4 digital sink color depth as 10 bpc. <5> [146.238240] [drm] DisplayID checksum invalid, remainder is 248 <7> [146.238241] xe 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:189:eDP-1] ELD monitor <5> [146.238244] [drm] DisplayID checksum invalid, remainder is 248 <7> [146.238245] xe 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:189:eDP-1] ELD size 20, SAD count 0 <5> [146.238248] [drm] DisplayID checksum invalid, remainder is 248 <7> [146.238259] xe 0000:00:02.0: [drm:intel_dp_set_edid [xe]] [CONNECTOR:189:eDP-1] VRR capable: yes <7> [146.238353] 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> [146.239052] 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> [146.239150] 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 <5> [146.240109] [drm] DisplayID checksum invalid, remainder is 248 <5> [146.240111] [drm] DisplayID checksum invalid, remainder is 248 <5> [146.240112] [drm] DisplayID checksum invalid, remainder is 248 <7> [146.240142] xe 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:189:eDP-1] probed modes: <7> [146.240153] 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> [146.240179] xe 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:198:DP-1] <7> [146.240189] xe 0000:00:02.0: [drm:intel_dp_detect [xe]] [CONNECTOR:198:DP-1] <7> [146.240317] xe 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:198:DP-1] disconnected <7> [146.240334] xe 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:210:DP-2] <7> [146.240342] xe 0000:00:02.0: [drm:intel_dp_detect [xe]] [CONNECTOR:210:DP-2] <7> [146.240450] xe 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:210:DP-2] disconnected <7> [146.240464] xe 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:218:DP-3] <7> [146.240471] xe 0000:00:02.0: [drm:intel_dp_detect [xe]] [CONNECTOR:218:DP-3] <7> [146.240589] xe 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:218:DP-3] disconnected <6> [146.240848] [IGT] kms_flip: starting subtest plain-flip-ts-check <6> [146.241949] [IGT] kms_flip: starting dynamic subtest A-eDP1 <7> [146.242639] xe 0000:00:02.0: [drm:drm_mode_addfb2] [FB:225] <7> [146.242855] xe 0000:00:02.0: [drm:drm_mode_addfb2] [FB:228] <7> [146.322892] xe 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:82:pipe A] <7> [146.323053] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.enable (expected yes, found no) <7> [146.323210] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.active (expected yes, found no) <7> [146.323316] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in cpu_transcoder (expected 0, found -1) <7> [146.323439] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in lane_count (expected 4, found 0) <7> [146.323539] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [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> [146.323643] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in output_types (expected 0x00000100, found 0x00000000) <7> [146.323745] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in framestart_delay (expected 1, found 0) <7> [146.323845] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 2880, found 0) <7> [146.323944] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 3040, found 0) <7> [146.324045] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 2880, found 0) <7> [146.324143] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 3040, found 0) <7> [146.324240] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 2928, found 0) <7> [146.324336] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2960, found 0) <7> [146.324432] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1800, found 0) <7> [146.324625] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1800, found 0) <7> [146.324722] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1803, found 0) <7> [146.324814] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1809, found 0) <7> [146.324906] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1906, found 0) <7> [146.324997] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1906, found 0) <7> [146.325086] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 2880, found 0) <7> [146.325174] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 3040, found 0) <7> [146.325263] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 2880, found 0) <7> [146.325362] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 3040, found 0) <7> [146.325487] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 2928, found 0) <7> [146.325587] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2960, found 0) <7> [146.325678] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1800, found 0) <7> [146.325769] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1800, found 0) <7> [146.325859] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1803, found 0) <7> [146.325948] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1809, found 0) <7> [146.326038] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1906, found 0) <7> [146.326130] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1906, found 0) <7> [146.326222] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in pixel_multiplier (expected 1, found 0) <7> [146.326325] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.flags (2) (expected 2, found 0) <7> [146.326435] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 8, found 0) <7> [146.326565] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in pipe_bpp (expected 30, found 0) <7> [146.326655] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 347710, found 0) <7> [146.326744] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 347710, found 0) <7> [146.326832] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in port_clock (expected 432000, found 0) <7> [146.326921] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CRTC:82:pipe A] fastset requirement not met, forcing full modeset <7> [146.327145] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] Enabled dbuf slices 0xf -> 0x1 (total dbuf slices 0xf), mbus joined? yes->no <7> [146.327235] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [CRTC:82:pipe A] dbuf slices 0xf -> 0x0, ddb (0 - 4096) -> (0 - 0), active pipes 0x1 -> 0x0 <7> [146.327374] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:32:plane 1A] ddb ( 0 - 4054) -> ( 0 - 0), size 4054 -> 0 <7> [146.327458] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:77:cursor A] ddb (4054 - 4096) -> ( 0 - 0), size 42 -> 0 <7> [146.327531] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:32:plane 1A] level wm0,wm1,wm2,wm3,wm4,wm5, wm6, wm7,twm,swm,stwm -> wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm <7> [146.327618] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:32:plane 1A] lines 1, 12, 12, 12, 11, 12, 0, 0, 0, 3, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 <7> [146.327691] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:32:plane 1A] blocks 24, 289, 289, 289, 265, 289, 0, 0, 38, 73, 87 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 <7> [146.327761] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:32:plane 1A] min_ddb 28, 319, 319, 319, 293, 319, 0, 0, 39, 82, 88 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 <7> [146.327830] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] [CRTC:82:pipe A] data rate 0 num active planes 0 <7> [146.327925] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 0: max bw 23530 required 0 qgv_peak_bw: 38400 <7> [146.328018] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 1: max bw 36260 required 0 qgv_peak_bw: 38400 <7> [146.328107] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 2: max bw 38000 required 0 qgv_peak_bw: 38400 <7> [146.328193] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 3: max bw 38000 required 0 qgv_peak_bw: 38400 <7> [146.328278] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] Matching peaks QGV bw: 38400 for required data rate: 0 <7> [146.328366] xe 0000:00:02.0: [drm:intel_modeset_calc_cdclk [xe]] Can change cdclk via squashing <7> [146.328451] xe 0000:00:02.0: [drm:intel_modeset_calc_cdclk [xe]] New cdclk calculated to be logical 153600 kHz, actual 153600 kHz <7> [146.328533] xe 0000:00:02.0: [drm:intel_modeset_calc_cdclk [xe]] New voltage level calculated to be logical 0, actual 0 <7> [146.328676] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [CRTC:82:pipe A] enable: no [modeset] <7> [146.328766] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:32:plane 1A] fb: [NOFB], visible: no <7> [146.328854] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:41:plane 2A] fb: [NOFB], visible: no <7> [146.328943] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:50:plane 3A] fb: [NOFB], visible: no <7> [146.329030] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:59:plane 4A] fb: [NOFB], visible: no <7> [146.329108] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:68:plane 5A] fb: [NOFB], visible: no <7> [146.329184] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:77:cursor A] fb: [NOFB], visible: no <7> [146.329301] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling DC_off <7> [146.330010] xe 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [xe]] Setting DC state from 02 to 00 <7> [146.330130] xe 0000:00:02.0: [drm:intel_psr_disable_locked [xe]] Disabling PSR2 <7> [146.347060] xe 0000:00:02.0: [drm:intel_edp_backlight_off [xe]] <7> [146.558258] xe 0000:00:02.0: [drm:intel_backlight_set_pwm_level [xe]] [CONNECTOR:189:eDP-1] set backlight PWM = 0 <7> [146.558628] xe 0000:00:02.0: [drm:intel_disable_transcoder [xe]] disabling pipe A <7> [146.568745] xe 0000:00:02.0: [drm:intel_pps_off_unlocked [xe]] [ENCODER:188:DDI A/PHY A] PPS 0 turn panel power off <7> [146.569034] xe 0000:00:02.0: [drm:intel_pps_off_unlocked [xe]] [ENCODER:188:DDI A/PHY A] PPS 0 wait for panel power off time <7> [146.569303] xe 0000:00:02.0: [drm:wait_panel_status [xe]] [ENCODER:188:DDI A/PHY A] PPS 0 mask: 0xb0000000 value: 0x00000000 PP_STATUS: 0xa0000002 PP_CONTROL: 0x00000060 <7> [146.621661] xe 0000:00:02.0: [drm:icp_irq_handler [xe]] hotplug event received, stat 0x00010000, dig 0x00000009, pins 0x00000010, long 0x00000000 <7> [146.621919] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] digital hpd on [ENCODER:188:DDI A/PHY A] - short <7> [146.649320] xe 0000:00:02.0: [drm:wait_panel_status [xe]] Wait complete <7> [146.649741] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling AUX_A <7> [146.649715] xe 0000:00:02.0: [drm:intel_dp_hpd_pulse [xe]] ignoring short hpd on eDP [ENCODER:188:DDI A/PHY A] <7> [146.650108] xe 0000:00:02.0: [drm:__intel_fbc_disable [xe]] Disabling FBC on [PLANE:32:plane 1A] <7> [146.650772] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:188:DDI A/PHY A] <7> [146.651050] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:197:DDI TC1/PHY TC1] <7> [146.651287] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:199:DP-MST A] <7> [146.651599] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:200:DP-MST B] <7> [146.651829] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:201:DP-MST C] <7> [146.652064] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:209:DDI TC2/PHY TC2] <7> [146.652280] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:211:DP-MST A] <7> [146.652494] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:212:DP-MST B] <7> [146.652742] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:213:DP-MST C] <7> [146.652956] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:217:DDI TC3/PHY TC3] <7> [146.653166] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:219:DP-MST A] <7> [146.653372] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:220:DP-MST B] <7> [146.653593] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:221:DP-MST C] <7> [146.653803] xe 0000:00:02.0: [drm:verify_connector_state [xe]] [CONNECTOR:189:eDP-1] <7> [146.654043] xe 0000:00:02.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 4 (mbus joined: no) <7> [146.654254] xe 0000:00:02.0: [drm:intel_dbuf_mbus_join_update [xe]] Changing mbus joined: yes -> no (pipe: ) <7> [146.654464] xe 0000:00:02.0: [drm:gen9_dbuf_slices_update [xe]] Updating dbuf slices to 0x1 <7> [146.654941] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling PW_A <7> [146.655342] xe 0000:00:02.0: [drm:intel_modeset_verify_crtc [xe]] [CRTC:82:pipe A] <7> [146.655570] xe 0000:00:02.0: [drm:intel_set_cdclk [xe]] Post changing CDCLK to 153600 kHz, VCO 614400 kHz, ref 38400 kHz, bypass 19200 kHz, voltage level 0 <7> [146.655919] xe 0000:00:02.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 4 (mbus joined: no) <7> [146.656170] xe 0000:00:02.0: [drm:intel_audio_cdclk_change_post.part.0 [xe]] aud_ts_cdclk set to M=60, N=384 <7> [146.656818] xe 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:134:pipe B] <7> [146.657156] xe 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:186:pipe C] <7> [146.657418] xe 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:82:pipe A] <7> [146.657479] xe 0000:00:02.0: [drm:drm_mode_setcrtc] [CONNECTOR:189:eDP-1] <7> [146.657618] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CONNECTOR:189:eDP-1] Limiting display bpp to 30 (EDID bpp 30, max requested bpp 36, max platform bpp 36) <7> [146.657940] xe 0000:00:02.0: [drm:intel_dp_compute_config_limits [xe]] [ENCODER:188:DDI A/PHY A][CRTC:82:pipe A] DP link limits: pixel clock 347710 kHz DSC off max lanes 4 max rate 810000 max pipe_bpp 30 max link_bpp 30.0000 <7> [146.658174] xe 0000:00:02.0: [drm:intel_dp_compute_link_config [xe]] DP lane count 4 clock 432000 bpp input 30 compressed 0.0000 link rate required 1303913 available 1728000 <7> [146.658410] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CRTC:82:pipe A] hw max bpp: 30, pipe bpp: 30, dithering: 0 <7> [146.658459] xe 0000:00:02.0: [drm:icp_irq_handler [xe]] hotplug event received, stat 0x00010000, dig 0x0000000a, pins 0x00000010, long 0x00000010 <7> [146.658645] xe 0000:00:02.0: [drm:intel_ddi_compute_config_late [xe]] [ENCODER:188:DDI A/PHY A] [CRTC:82:pipe A] <7> [146.658731] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] digital hpd on [ENCODER:188:DDI A/PHY A] - long <7> [146.658975] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.enable (expected no, found yes) <7> [146.659003] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] Received HPD interrupt on PIN 4 - cnt: 10 <7> [146.659219] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.active (expected no, found yes) <7> [146.659299] xe 0000:00:02.0: [drm:intel_dp_hpd_pulse [xe]] ignoring long hpd on eDP [ENCODER:188:DDI A/PHY A] <7> [146.659459] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in cpu_transcoder (expected -1, found 0) <7> [146.659769] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in lane_count (expected 0, found 4) <7> [146.660020] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in dp_m_n (expected tu 0 data 0/0 link 0/0, found tu 64, data 6329869/8388608 link 421991/524288) <7> [146.660334] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in output_types (expected 0x00000000, found 0x00000100) <7> [146.660601] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in framestart_delay (expected 0, found 1) <7> [146.660889] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 2880) <7> [146.661129] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 3040) <7> [146.661363] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 2880) <7> [146.661598] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 3040) <7> [146.661929] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 2928) <7> [146.662187] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2960) <7> [146.662496] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1800) <7> [146.662740] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1800) <7> [146.663056] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1803) <7> [146.663291] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1809) <7> [146.663507] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1906) <7> [146.663723] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1906) <7> [146.663953] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 2880) <7> [146.664153] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 3040) <7> [146.664350] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 2880) <7> [146.664537] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 3040) <7> [146.664712] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 2928) <7> [146.664898] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2960) <7> [146.665073] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1800) <7> [146.665264] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1800) <7> [146.665432] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1803) <7> [146.665585] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1809) <7> [146.665738] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1906) <7> [146.665891] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1906) <7> [146.666075] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in pixel_multiplier (expected 0, found 1) <7> [146.666223] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.flags (2) (expected 0, found 2) <7> [146.666364] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 0, found 8) <7> [146.666504] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in pipe_bpp (expected 0, found 30) <7> [146.666640] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 347710) <7> [146.666769] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 347710) <7> [146.666920] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:82:pipe A] fastset requirement not met in port_clock (expected 0, found 432000) <7> [146.667103] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CRTC:82:pipe A] fastset requirement not met, forcing full modeset <7> [146.667280] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] Enabled dbuf slices 0x1 -> 0xf (total dbuf slices 0xf), mbus joined? no->yes <7> [146.667395] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [CRTC:82:pipe A] dbuf slices 0x0 -> 0xf, ddb (0 - 0) -> (0 - 4096), active pipes 0x0 -> 0x1 <7> [146.667554] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:32:plane 1A] ddb ( 0 - 0) -> ( 0 - 4054), size 0 -> 4054 <7> [146.667683] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:77:cursor A] ddb ( 0 - 0) -> (4054 - 4096), size 0 -> 42 <7> [146.667804] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:32:plane 1A] level wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm -> wm0,wm1,wm2,wm3,wm4,wm5, wm6, wm7,twm,swm,stwm <7> [146.667909] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:32:plane 1A] lines 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 1, 12, 12, 12, 11, 12, 0, 0, 0, 3, 0 <7> [146.668025] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:32:plane 1A] blocks 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 24, 289, 289, 289, 265, 289, 0, 0, 38, 73, 87 <7> [146.668124] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:32:plane 1A] min_ddb 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 28, 319, 319, 319, 293, 319, 0, 0, 39, 82, 88 <7> [146.668232] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] [CRTC:82:pipe A] data rate 1390840 num active planes 1 <7> [146.668382] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 0: max bw 23530 required 1461 qgv_peak_bw: 38400 <7> [146.668508] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 1: max bw 36260 required 1461 qgv_peak_bw: 38400 <7> [146.668629] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 2: max bw 38000 required 1461 qgv_peak_bw: 38400 <7> [146.668745] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 3: max bw 38000 required 1461 qgv_peak_bw: 38400 <7> [146.668860] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] Matching peaks QGV bw: 38400 for required data rate: 1461 <7> [146.668999] xe 0000:00:02.0: [drm:intel_plane_calc_min_cdclk [xe]] [PLANE:32:plane 1A] min cdclk (173855 kHz) > [CRTC:82:pipe A] min cdclk (0 kHz) <7> [146.669118] xe 0000:00:02.0: [drm:intel_bw_calc_min_cdclk [xe]] new bandwidth min cdclk (27165 kHz) > old min cdclk (0 kHz) <7> [146.669223] xe 0000:00:02.0: [drm:intel_modeset_calc_cdclk [xe]] Can change cdclk via squashing <7> [146.669325] xe 0000:00:02.0: [drm:intel_modeset_calc_cdclk [xe]] New cdclk calculated to be logical 192000 kHz, actual 192000 kHz <7> [146.669425] xe 0000:00:02.0: [drm:intel_modeset_calc_cdclk [xe]] New voltage level calculated to be logical 0, actual 0 <7> [146.669542] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [CRTC:82:pipe A] enable: yes [modeset] <7> [146.669655] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] active: yes, output_types: EDP (0x100), output format: RGB, sink format: RGB <7> [146.669755] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] cpu_transcoder: A, pipe bpp: 30, dithering: 0 <7> [146.669855] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] MST master transcoder: <invalid> <7> [146.669952] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0 <7> [146.670062] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] joiner: no, pipes: 0x0 <7> [146.670157] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] splitter: disabled, link count 0, overlap 0 <7> [146.670245] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] dp m_n: lanes: 4; data_m: 6329869, data_n: 8388608, link_m: 421991, link_n: 524288, tu: 64 <7> [146.670332] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] dp m2_n2: lanes: 4; data_m: 0, data_n: 0, link_m: 0, link_n: 0, tu: 0 <7> [146.670417] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] fec: disabled, enhanced framing: disabled <7> [146.670500] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] sdp split: disabled <7> [146.670580] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] psr: enabled, selective update: enabled, panel replay: disabled, selective fetch: enabled <7> [146.670660] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] minimum HBlank: 0 <7> [146.670738] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] audio: 0, infoframes: 0, infoframes enabled: 0x4 <7> [146.670812] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] DP SDP: VSC, revision 4, length 14 <7> [146.670887] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pixelformat: RGB <7> [146.670964] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] colorimetry: sRGB <7> [146.671053] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] bpc: 0 <7> [146.671132] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] dynamic range: VESA range <7> [146.671209] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] content type: Not defined <7> [146.671286] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] scanline offset: 1 <7> [146.671365] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] vblank delay: 0, framestart delay: 1, MSA timing delay: 0 <7> [146.671441] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] vrr: no, vmin: 1906, vmax: 1906, flipline: 1906, pipeline full: 0, guardband: 106 vsync start: 103, vsync end: 97 <7> [146.671518] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] vrr: vmin vblank: 1800, vmax vblank: 1800, vmin vtotal: 1906, vmax vtotal: 1906 <7> [146.671589] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] requested mode: "2880x1800": 60 347710 2880 2928 2960 3040 1800 1803 1809 1906 0x48 0xa <7> [146.671656] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] adjusted mode: "2880x1800": 60 347710 2880 2928 2960 3040 1800 1803 1809 1906 0x48 0xa <7> [146.671720] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] crtc timings: clock=347710, hd=2880 hb=2880-3040 hs=2928-2960 ht=3040, vd=1800 vb=1800-1906 vs=1803-1809 vt=1906, flags=0xa <7> [146.671785] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe mode: "2880x1800": 60 347710 2880 2928 2960 3040 1800 1803 1809 1906 0x40 0xa <7> [146.671850] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] crtc timings: clock=347710, hd=2880 hb=2880-3040 hs=2928-2960 ht=3040, vd=1800 vb=1800-1906 vs=1803-1809 vt=1906, flags=0xa <7> [146.671915] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] port clock: 432000, pipe src: 2880x1800+0+0, pixel rate 347710 <7> [146.671979] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] linetime: 70, ips linetime: 0 <7> [146.672043] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0 <7> [146.672147] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pch pfit: 0x0+0+0, disabled, force thru: no <7> [146.672234] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] ips: 0, double wide: 0, drrs: 0 <7> [146.672320] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] dpll_hw_state: dpll: 0x69780, dpll_md: 0x42110, fp0: 0x1a2, fp1: 0x1000000 <7> [146.672400] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0 <7> [146.672467] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pre csc lut: 0 entries, post csc lut: 0 entries <7> [146.672531] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: pre offsets: 0x0000 0x0000 0x0000 <7> [146.672594] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000 <7> [146.672667] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000 <7> [146.672742] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000 <7> [146.672817] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: post offsets: 0x0000 0x0000 0x0000 <7> [146.672890] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: pre offsets: 0x0000 0x0000 0x0000 <7> [146.672957] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000 <7> [146.673018] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000 <7> [146.673075] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000 <7> [146.673160] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: post offsets: 0x0000 0x0000 0x0000 <7> [146.673235] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:32:plane 1A] fb: [FB:225] 2880x1800 format = XR24 little-endian (0x34325258) modifier = 0x0, visible: yes <7> [146.673312] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] rotation: 0x1, scaler: -1, scaling_filter: 0 <7> [146.673385] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] src: 2880.000000x1800.000000+0.000000+0.000000 dst: 2880x1800+0+0 <7> [146.673448] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:41:plane 2A] fb: [NOFB], visible: no <7> [146.673511] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:50:plane 3A] fb: [NOFB], visible: no <7> [146.673571] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:59:plane 4A] fb: [NOFB], visible: no <7> [146.673633] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:68:plane 5A] fb: [NOFB], visible: no <7> [146.673696] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:77:cursor A] fb: [NOFB], visible: no <7> [146.675166] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling DC_off <7> [146.675518] xe 0000:00:02.0: [drm:skl_enable_dc6 [xe]] Enabling DC6 <7> [146.675665] xe 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [xe]] Setting DC state from 00 to 02 <7> [146.676775] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling DC_off <7> [146.677012] xe 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [xe]] Setting DC state from 02 to 00 <7> [146.677139] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling PW_A <7> [146.677294] xe 0000:00:02.0: [drm:intel_set_cdclk [xe]] Pre changing CDCLK to 192000 kHz, VCO 614400 kHz, ref 38400 kHz, bypass 19200 kHz, voltage level 0 <7> [146.677392] xe 0000:00:02.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 4 (mbus joined: no) <7> [146.677490] xe 0000:00:02.0: [drm:intel_audio_cdclk_change_post.part.0 [xe]] aud_ts_cdclk set to M=60, N=480 <7> [146.677577] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:188:DDI A/PHY A] <7> [146.677641] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:197:DDI TC1/PHY TC1] <7> [146.677710] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:199:DP-MST A] <7> [146.677785] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:200:DP-MST B] <7> [146.677857] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:201:DP-MST C] <7> [146.677929] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:209:DDI TC2/PHY TC2] <7> [146.678000] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:211:DP-MST A] <7> [146.678069] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:212:DP-MST B] <7> [146.678128] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:213:DP-MST C] <7> [146.678185] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:217:DDI TC3/PHY TC3] <7> [146.678264] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:219:DP-MST A] <7> [146.678337] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:220:DP-MST B] <7> [146.678408] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:221:DP-MST C] <7> [146.678477] xe 0000:00:02.0: [drm:gen9_dbuf_slices_update [xe]] Updating dbuf slices to 0xf <7> [146.678635] xe 0000:00:02.0: [drm:intel_dbuf_mbus_join_update [xe]] Changing mbus joined: no -> yes (pipe: ) <7> [146.678696] xe 0000:00:02.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 8 (mbus joined: yes) <7> [146.678767] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling AUX_A <7> [146.680119] xe 0000:00:02.0: [drm:intel_pps_on_unlocked [xe]] [ENCODER:188:DDI A/PHY A] PPS 0 turn panel power on <7> [146.680191] xe 0000:00:02.0: [drm:wait_panel_power_cycle [xe]] [ENCODER:188:DDI A/PHY A] PPS 0 wait for panel power cycle (471 ms remaining) <7> [147.166242] xe 0000:00:02.0: [drm:wait_panel_status [xe]] [ENCODER:188:DDI A/PHY A] PPS 0 mask: 0xb800000f value: 0x00000000 PP_STATUS: 0x08000001 PP_CONTROL: 0x00000060 <7> [147.186637] xe 0000:00:02.0: [drm:wait_panel_status [xe]] Wait complete <7> [147.187020] xe 0000:00:02.0: [drm:intel_pps_on_unlocked [xe]] [ENCODER:188:DDI A/PHY A] PPS 0 wait for panel power on <7> [147.187393] xe 0000:00:02.0: [drm:wait_panel_status [xe]] [ENCODER:188:DDI A/PHY A] PPS 0 mask: 0xb000000f value: 0x80000008 PP_STATUS: 0x9000000a PP_CONTROL: 0x00000063 <7> [147.223373] xe 0000:00:02.0: [drm:icp_irq_handler [xe]] hotplug event received, stat 0x00010000, dig 0x0000000a, pins 0x00000010, long 0x00000010 <7> [147.223698] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] digital hpd on [ENCODER:188:DDI A/PHY A] - long <7> [147.224021] xe 0000:00:02.0: [drm:intel_hpd_irq_handler [xe]] Received HPD interrupt on PIN 4 - cnt: 20 <7> [147.224365] xe 0000:00:02.0: [drm:intel_dp_hpd_pulse [xe]] ignoring long hpd on eDP [ENCODER:188:DDI A/PHY A] <7> [147.322197] xe 0000:00:02.0: [drm:intel_tc_port_reset_mode [xe]] Port D/TC#1: TC port mode reset (tbt-alt -> disconnected) <7> [147.322177] xe 0000:00:02.0: [drm:intel_tc_port_reset_mode [xe]] Port F/TC#3: TC port mode reset (tbt-alt -> disconnected) <7> [147.322185] xe 0000:00:02.0: [drm:intel_tc_port_reset_mode [xe]] Port E/TC#2: TC port mode reset (tbt-alt -> disconnected) <7> [147.486316] xe 0000:00:02.0: [drm:wait_panel_status [xe]] Wait complete <7> [147.488421] xe 0000:00:02.0: [drm:intel_pps_vdd_on_unlocked [xe]] [ENCODER:188:DDI A/PHY A] PPS 0 turning VDD on <7> [147.488732] 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: 0x0000006b <7> [147.491771] xe 0000:00:02.0: [drm:drm_dp_read_dpcd_caps [drm_display_helper]] AUX A/DDI A/PHY A: DPCD: 14 1e 44 c1 00 00 01 c0 02 00 02 00 00 0b 80 <7> [147.493046] xe 0000:00:02.0: [drm:intel_dp_start_link_train [xe]] [CONNECTOR:189:eDP-1][ENCODER:188:DDI A/PHY A][DPRX] Reloading eDP link rates <7> [147.494050] xe 0000:00:02.0: [drm:intel_dp_start_link_train [xe]] [CONNECTOR:189:eDP-1][ENCODER:188:DDI A/PHY A][DPRX] Using LINK_RATE_SET value 05 <7> [147.495127] xe 0000:00:02.0: [drm:intel_dp_set_signal_levels [xe]] [CONNECTOR:189:eDP-1][ENCODER:188:DDI A/PHY A][DPRX] 8b/10b, lanes: 4, vswing levels: 0/0/0/0, pre-emphasis levels: 0/0/0/0 <7> [147.496218] xe 0000:00:02.0: [drm:intel_dp_program_link_training_pattern [xe]] [CONNECTOR:189:eDP-1][ENCODER:188:DDI A/PHY A][DPRX] Using DP training pattern TPS1 <7> [147.497429] xe 0000:00:02.0: [drm:intel_dp_link_train_phy [xe]] [CONNECTOR:189:eDP-1][ENCODER:188:DDI A/PHY A][DPRX] Clock recovery OK <7> [147.497720] xe 0000:00:02.0: [drm:intel_dp_program_link_training_pattern [xe]] [CONNECTOR:189:eDP-1][ENCODER:188:DDI A/PHY A][DPRX] Using DP training pattern TPS4 <7> [147.499264] xe 0000:00:02.0: [drm:intel_dp_link_train_phy [xe]] [CONNECTOR:189:eDP-1][ENCODER:188:DDI A/PHY A][DPRX] Channel EQ done. DP Training successful <7> [147.499519] xe 0000:00:02.0: [drm:intel_dp_link_train_phy [xe]] [CONNECTOR:189:eDP-1][ENCODER:188:DDI A/PHY A][DPRX] Link Training passed at link rate = 432000, lane count = 4 <7> [147.500245] xe 0000:00:02.0: [drm:intel_enable_transcoder [xe]] enabling pipe A <7> [147.500671] xe 0000:00:02.0: [drm:intel_edp_backlight_on [xe]] <7> [147.500953] xe 0000:00:02.0: [drm:intel_backlight_enable [xe]] pipe A <7> [147.501234] xe 0000:00:02.0: [drm:intel_backlight_set_pwm_level [xe]] [CONNECTOR:189:eDP-1] set backlight PWM = 4518 <7> [147.502068] xe 0000:00:02.0: [drm:intel_fbc_update [xe]] reserved 10600448 bytes of contiguous stolen space for FBC, limit: 2 <7> [147.502346] xe 0000:00:02.0: [drm:intel_fbc_update [xe]] Enabling FBC on [PLANE:32:plane 1A] <7> [147.517435] xe 0000:00:02.0: [drm:intel_psr_post_plane_update [xe]] Enabling PSR2 <7> [147.519866] xe 0000:00:02.0: [drm:verify_connector_state [xe]] [CONNECTOR:189:eDP-1] <7> [147.520248] xe 0000:00:02.0: [drm:intel_modeset_verify_crtc [xe]] [CRTC:82:pipe A] <7> [147.541728] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling DC_off <7> [147.542166] xe 0000:00:02.0: [drm:skl_enable_dc6 [xe]] Enabling DC6 <7> [147.542525] xe 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [xe]] Setting DC state from 00 to 02 <7> [147.804290] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling DC_off <7> [147.804698] xe 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [xe]] Setting DC state from 02 to 00 <7> [149.535434] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling DC_off <7> [149.535791] xe 0000:00:02.0: [drm:skl_enable_dc6 [xe]] Enabling DC6 <7> [149.536078] xe 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [xe]] Setting DC state from 00 to 02 <7> [150.133011] xe 0000:00:02.0: [drm:intel_pps_vdd_off_sync_unlocked [xe]] [ENCODER:188:DDI A/PHY A] PPS 0 turning VDD off <7> [150.133452] xe 0000:00:02.0: [drm:intel_pps_vdd_off_sync_unlocked [xe]] [ENCODER:188:DDI A/PHY A] PPS 0 PP_STATUS: 0x80000008 PP_CONTROL: 0x00000067 <6> [152.765302] [IGT] kms_flip: finished subtest A-eDP1, FAIL

Created at 2025-03-18 11:05:22