igt@kms_flip@flip-vs-expired-vblank-interruptible@c-hdmi-a1 on shard-glk@Patchwork

Detail	Value
Duration	1.52 seconds
Hostname	shard-glk5
Igt-Version	IGT-Version: 2.4-g63a08403d (x86_64) (Linux: 7.0.0-Patchwork_164957v1-gb2dbef367fba+ x86_64)
Out	Starting dynamic subtest: C-HDMI-A1 1920x1080: 60 148500 1920 2008 2052 2200 1080 1083 1088 1125 0x48 0x9 Stack trace: #0 ../lib/igt_core.c:2075 __igt_fail_assert() #1 ../tests/kms_flip.c:926 run_test_on_crtc_set() #2 ../tests/kms_flip.c:1920 run_test() #3 ../tests/kms_flip.c:2220 __igt_unique____real_main2105() #4 ../tests/kms_flip.c:2105 main() #5 [__libc_init_first+0x8a] #6 [__libc_start_main+0x8b] #7 [_start+0x25] Dynamic subtest C-HDMI-A1: FAIL (1.524s)
Err	Starting dynamic subtest: C-HDMI-A1 (kms_flip:2269) CRITICAL: Test assertion failure function run_test_step, file ../tests/kms_flip.c:931: (kms_flip:2269) CRITICAL: Failed assertion: end - start < 500 (kms_flip:2269) CRITICAL: Last errno: 4, Interrupted system call Dynamic subtest C-HDMI-A1 failed. ** DEBUG (kms_flip:2269) igt_fb-DEBUG: igt_create_fb_with_bo_size(width=1920, height=1080, format=XR24(0x34325258), modifier=0x100000000000001, size=0) (kms_flip:2269) drmtest-DEBUG: Test requirement passed: is_intel_device(fd) (kms_flip:2269) drmtest-DEBUG: Test requirement passed: is_intel_device(fd) (kms_flip:2269) igt_fb-DEBUG: igt_create_fb_with_bo_size(handle=1, pitch=7680) (kms_flip:2269) ioctl_wrappers-DEBUG: Test requirement passed: igt_has_fb_modifiers(fd) (kms_flip:2269) igt_fb-DEBUG: igt_create_fb_with_bo_size(width=1920, height=1080, format=XR24(0x34325258), modifier=0x100000000000001, size=0) (kms_flip:2269) drmtest-DEBUG: Test requirement passed: is_intel_device(fd) (kms_flip:2269) drmtest-DEBUG: Test requirement passed: is_intel_device(fd) (kms_flip:2269) igt_fb-DEBUG: igt_create_fb_with_bo_size(handle=2, pitch=7680) (kms_flip:2269) ioctl_wrappers-DEBUG: Test requirement passed: igt_has_fb_modifiers(fd) (kms_flip:2269) intel_blt-DEBUG: Test requirement passed: cmds_info (kms_flip:2269) intel_blt-DEBUG: Test requirement passed: cmds_info (kms_flip:2269) igt_fb-DEBUG: Test requirement passed: cairo_surface_status(fb->cairo_surface) == CAIRO_STATUS_SUCCESS (kms_flip:2269) intel_blt-DEBUG: Test requirement passed: cmds_info (kms_flip:2269) intel_blt-DEBUG: Test requirement passed: cmds_info (kms_flip:2269) igt_fb-DEBUG: Test requirement passed: cairo_surface_status(fb->cairo_surface) == CAIRO_STATUS_SUCCESS (kms_flip:2269) igt_kms-INFO: 1920x1080: 60 148500 1920 2008 2052 2200 1080 1083 1088 1125 0x48 0x9 (kms_flip:2269) DEBUG: No stale events found (kms_flip:2269) i915/intel_drrs-DEBUG: Manually disabling DRRS. 0 (kms_flip:2269) i915/intel_drrs-DEBUG: Test requirement passed: dir >= 0 (kms_flip:2269) DEBUG: name = flip last_ts = 0.000000 last_received_ts = 0.000000 last_seq = 0 current_ts = 107.479172 current_received_ts = 107.478615 current_seq = 36 count = 0 seq_step = 1 (kms_flip:2269) DEBUG: Vblank took 9us (kms_flip:2269) DEBUG: name = flip last_ts = 107.479172 last_received_ts = 107.478615 last_seq = 36 current_ts = 107.495827 current_received_ts = 107.495262 current_seq = 37 count = 1 seq_step = 1 (kms_flip:2269) DEBUG: Vblank took 6us (kms_flip:2269) DEBUG: name = flip last_ts = 107.495827 last_received_ts = 107.495262 last_seq = 37 current_ts = 107.512520 current_received_ts = 107.511932 current_seq = 38 count = 2 seq_step = 1 (kms_flip:2269) DEBUG: Vblank took 7us (kms_flip:2269) DEBUG: name = flip last_ts = 107.512520 last_received_ts = 107.511932 last_seq = 38 current_ts = 107.529190 current_received_ts = 107.528732 current_seq = 39 count = 3 seq_step = 1 (kms_flip:2269) DEBUG: Vblank took 7us (kms_flip:2269) DEBUG: name = flip last_ts = 107.529190 last_received_ts = 107.528732 last_seq = 39 current_ts = 107.545868 current_received_ts = 107.545433 current_seq = 40 count = 4 seq_step = 1 (kms_flip:2269) DEBUG: Vblank took 8us (kms_flip:2269) DEBUG: name = flip last_ts = 107.545868 last_received_ts = 107.545433 last_seq = 40 current_ts = 107.562531 current_received_ts = 107.562447 current_seq = 41 count = 5 seq_step = 1 (kms_flip:2269) DEBUG: Vblank took 15us (kms_flip:2269) DEBUG: name = flip last_ts = 107.562531 last_received_ts = 107.562447 last_seq = 41 current_ts = 107.579216 current_received_ts = 107.578857 current_seq = 42 count = 6 seq_step = 1 (kms_flip:2269) DEBUG: Vblank took 24us (kms_flip:2269) DEBUG: name = flip last_ts = 107.579216 last_received_ts = 107.578857 last_seq = 42 current_ts = 107.595879 current_received_ts = 107.595421 current_seq = 43 count = 7 seq_step = 1 (kms_flip:2269) DEBUG: Vblank took 26us (kms_flip:2269) DEBUG: name = flip last_ts = 107.595879 last_received_ts = 107.595421 last_seq = 43 current_ts = 107.612549 current_received_ts = 107.612328 current_seq = 44 count = 8 seq_step = 1 (kms_flip:2269) DEBUG: Vblank took 25us (kms_flip:2269) DEBUG: name = flip last_ts = 107.612549 last_received_ts = 107.612328 last_seq = 44 current_ts = 107.629234 current_received_ts = 107.629051 current_seq = 45 count = 9 seq_step = 1 (kms_flip:2269) DEBUG: Vblank took 24us (kms_flip:2269) DEBUG: name = flip last_ts = 107.629234 last_received_ts = 107.629051 last_seq = 45 current_ts = 107.645905 current_received_ts = 107.645470 current_seq = 46 count = 10 seq_step = 1 (kms_flip:2269) DEBUG: Vblank took 22us (kms_flip:2269) DEBUG: name = flip last_ts = 107.645905 last_received_ts = 107.645470 last_seq = 46 current_ts = 107.662582 current_received_ts = 107.662392 current_seq = 47 count = 11 seq_step = 1 (kms_flip:2269) DEBUG: Vblank took 23us (kms_flip:2269) DEBUG: name = flip last_ts = 107.662582 last_received_ts = 107.662392 last_seq = 47 current_ts = 107.679253 current_received_ts = 107.679314 current_seq = 48 count = 12 seq_step = 1 (kms_flip:2269) DEBUG: Vblank took 22us (kms_flip:2269) DEBUG: name = flip last_ts = 107.679253 last_received_ts = 107.679314 last_seq = 48 current_ts = 107.695923 current_received_ts = 107.695534 current_seq = 49 count = 13 seq_step = 1 (kms_flip:2269) DEBUG: Vblank took 23us (kms_flip:2269) DEBUG: name = flip last_ts = 107.695923 last_received_ts = 107.695534 last_seq = 49 current_ts = 107.712593 current_received_ts = 107.712372 current_seq = 50 count = 14 seq_step = 1 (kms_flip:2269) DEBUG: Vblank took 23us (kms_flip:2269) DEBUG: name = flip last_ts = 107.712593 last_received_ts = 107.712372 last_seq = 50 current_ts = 107.729279 current_received_ts = 107.729073 current_seq = 51 count = 15 seq_step = 1 (kms_flip:2269) DEBUG: Vblank took 24us (kms_flip:2269) DEBUG: name = flip last_ts = 107.729279 last_received_ts = 107.729073 last_seq = 51 current_ts = 107.745956 current_received_ts = 107.745537 current_seq = 52 count = 16 seq_step = 1 (kms_flip:2269) DEBUG: Vblank took 22us (kms_flip:2269) DEBUG: name = flip last_ts = 107.745956 last_received_ts = 107.745537 last_seq = 52 current_ts = 107.762627 current_received_ts = 107.762749 current_seq = 53 count = 17 seq_step = 1 (kms_flip:2269) DEBUG: Vblank took 22us (kms_flip:2269) DEBUG: name = flip last_ts = 107.762627 last_received_ts = 107.762749 last_seq = 53 current_ts = 107.779289 current_received_ts = 107.779167 current_seq = 54 count = 18 seq_step = 1 (kms_flip:2269) DEBUG: Vblank took 23us (kms_flip:2269) DEBUG: name = flip last_ts = 107.779289 last_received_ts = 107.779167 last_seq = 54 current_ts = 107.795967 current_received_ts = 107.795555 current_seq = 55 count = 19 seq_step = 1 (kms_flip:2269) DEBUG: Vblank took 24us (kms_flip:2269) DEBUG: name = flip last_ts = 107.795967 last_received_ts = 107.795555 last_seq = 55 current_ts = 107.812645 current_received_ts = 107.812698 current_seq = 56 count = 20 seq_step = 1 (kms_flip:2269) DEBUG: Vblank took 22us (kms_flip:2269) DEBUG: name = flip last_ts = 107.812645 last_received_ts = 107.812698 last_seq = 56 current_ts = 107.829315 current_received_ts = 107.829170 current_seq = 57 count = 21 seq_step = 1 (kms_flip:2269) DEBUG: Vblank took 26us (kms_flip:2269) DEBUG: name = flip last_ts = 107.829315 last_received_ts = 107.829170 last_seq = 57 current_ts = 107.845985 current_received_ts = 107.845932 current_seq = 58 count = 22 seq_step = 1 (kms_flip:2269) DEBUG: Vblank took 63us (kms_flip:2269) DEBUG: name = flip last_ts = 107.845985 last_received_ts = 107.845932 last_seq = 58 current_ts = 107.862663 current_received_ts = 107.862480 current_seq = 59 count = 23 seq_step = 1 (kms_flip:2269) DEBUG: Vblank took 22us (kms_flip:2269) DEBUG: name = flip last_ts = 107.862663 last_received_ts = 107.862480 last_seq = 59 current_ts = 107.879349 current_received_ts = 107.879280 current_seq = 60 count = 24 seq_step = 1 (kms_flip:2269) DEBUG: Vblank took 24us (kms_flip:2269) DEBUG: name = flip last_ts = 107.879349 last_received_ts = 107.879280 last_seq = 60 current_ts = 107.896019 current_received_ts = 107.895561 current_seq = 61 count = 25 seq_step = 1 (kms_flip:2269) DEBUG: Vblank took 25us (kms_flip:2269) DEBUG: name = flip last_ts = 107.896019 last_received_ts = 107.895561 last_seq = 61 current_ts = 107.912697 current_received_ts = 107.912468 current_seq = 62 count = 26 seq_step = 1 (kms_flip:2269) DEBUG: Vblank took 22us (kms_flip:2269) DEBUG: name = flip last_ts = 107.912697 last_received_ts = 107.912468 last_seq = 62 current_ts = 107.929367 current_received_ts = 107.929581 current_seq = 63 count = 27 seq_step = 1 (kms_flip:2269) DEBUG: Vblank took 21us (kms_flip:2269) DEBUG: name = flip last_ts = 107.929367 last_received_ts = 107.929581 last_seq = 63 current_ts = 107.946045 current_received_ts = 107.945580 current_seq = 64 count = 28 seq_step = 1 (kms_flip:2269) DEBUG: Vblank took 26us (kms_flip:2269) DEBUG: name = flip last_ts = 107.946045 last_received_ts = 107.945580 last_seq = 64 current_ts = 107.962708 current_received_ts = 107.962479 current_seq = 65 count = 29 seq_step = 1 (kms_flip:2269) DEBUG: Vblank took 25us (kms_flip:2269) DEBUG: name = flip last_ts = 107.962708 last_received_ts = 107.962479 last_seq = 65 current_ts = 107.979393 current_received_ts = 107.978897 current_seq = 66 count = 30 seq_step = 1 (kms_flip:2269) DEBUG: Vblank took 23us (kms_flip:2269) DEBUG: name = flip last_ts = 107.979393 last_received_ts = 107.978897 last_seq = 66 current_ts = 107.996071 current_received_ts = 107.995697 current_seq = 67 count = 31 seq_step = 1 (kms_flip:2269) DEBUG: Vblank took 24us (kms_flip:2269) DEBUG: name = flip last_ts = 107.996071 last_received_ts = 107.995697 last_seq = 67 current_ts = 108.012733 current_received_ts = 108.012482 current_seq = 68 count = 32 seq_step = 1 (kms_flip:2269) DEBUG: Vblank took 23us (kms_flip:2269) DEBUG: name = flip last_ts = 108.012733 last_received_ts = 108.012482 last_seq = 68 current_ts = 108.029411 current_received_ts = 108.029221 current_seq = 69 count = 33 seq_step = 1 (kms_flip:2269) DEBUG: Vblank took 24us (kms_flip:2269) DEBUG: name = flip last_ts = 108.029411 last_received_ts = 108.029221 last_seq = 69 current_ts = 108.046097 current_received_ts = 108.045616 current_seq = 70 count = 34 seq_step = 1 (kms_flip:2269) DEBUG: Vblank took 24us (kms_flip:2269) DEBUG: name = flip last_ts = 108.046097 last_received_ts = 108.045616 last_seq = 70 current_ts = 108.062767 current_received_ts = 108.062683 current_seq = 71 count = 35 seq_step = 1 (kms_flip:2269) DEBUG: Vblank took 21us (kms_flip:2269) DEBUG: name = flip last_ts = 108.062767 last_received_ts = 108.062683 last_seq = 71 current_ts = 108.079437 current_received_ts = 108.079224 current_seq = 72 count = 36 seq_step = 1 (kms_flip:2269) DEBUG: Vblank took 25us (kms_flip:2269) DEBUG: name = flip last_ts = 108.079437 last_received_ts = 108.079224 last_seq = 72 current_ts = 108.096100 current_received_ts = 108.095711 current_seq = 73 count = 37 seq_step = 1 (kms_flip:2269) DEBUG: Vblank took 24us (kms_flip:2269) DEBUG: name = flip last_ts = 108.096100 last_received_ts = 108.095711 last_seq = 73 current_ts = 108.112793 current_received_ts = 108.112892 current_seq = 74 count = 38 seq_step = 1 (kms_flip:2269) DEBUG: Vblank took 22us (kms_flip:2269) DEBUG: name = flip last_ts = 108.112793 last_received_ts = 108.112892 last_seq = 74 current_ts = 108.129463 current_received_ts = 108.129311 current_seq = 75 count = 39 seq_step = 1 (kms_flip:2269) DEBUG: Vblank took 25us (kms_flip:2269) DEBUG: name = flip last_ts = 108.129463 last_received_ts = 108.129311 last_seq = 75 current_ts = 108.146141 current_received_ts = 108.145699 current_seq = 76 count = 40 seq_step = 1 (kms_flip:2269) DEBUG: Vblank took 23us (kms_flip:2269) DEBUG: name = flip last_ts = 108.146141 last_received_ts = 108.145699 last_seq = 76 current_ts = 108.162811 current_received_ts = 108.162521 current_seq = 77 count = 41 seq_step = 1 (kms_flip:2269) DEBUG: Vblank took 23us (kms_flip:2269) DEBUG: name = flip last_ts = 108.162811 last_received_ts = 108.162521 last_seq = 77 current_ts = 108.179482 current_received_ts = 108.179039 current_seq = 78 count = 42 seq_step = 1 (kms_flip:2269) DEBUG: Vblank took 24us (kms_flip:2269) DEBUG: name = flip last_ts = 108.179482 last_received_ts = 108.179039 last_seq = 78 current_ts = 108.196152 current_received_ts = 108.196220 current_seq = 79 count = 43 seq_step = 1 (kms_flip:2269) DEBUG: Vblank took 23us (kms_flip:2269) DEBUG: name = flip last_ts = 108.196152 last_received_ts = 108.196220 last_seq = 79 current_ts = 108.212830 current_received_ts = 108.212524 current_seq = 80 count = 44 seq_step = 1 (kms_flip:2269) DEBUG: Vblank took 26us (kms_flip:2269) DEBUG: name = flip last_ts = 108.212830 last_received_ts = 108.212524 last_seq = 80 current_ts = 108.229507 current_received_ts = 108.229057 current_seq = 81 count = 45 seq_step = 1 (kms_flip:2269) DEBUG: Vblank took 23us (kms_flip:2269) DEBUG: name = flip last_ts = 108.229507 last_received_ts = 108.229057 last_seq = 81 current_ts = 108.246178 current_received_ts = 108.246559 current_seq = 82 count = 46 seq_step = 1 (kms_flip:2269) DEBUG: Vblank took 22us (kms_flip:2269) DEBUG: name = flip last_ts = 108.246178 last_received_ts = 108.246559 last_seq = 82 current_ts = 108.262848 current_received_ts = 108.262482 current_seq = 83 count = 47 seq_step = 1 (kms_flip:2269) DEBUG: Vblank took 25us (kms_flip:2269) DEBUG: name = flip last_ts = 108.262848 last_received_ts = 108.262482 last_seq = 83 current_ts = 108.279533 current_received_ts = 108.279381 current_seq = 84 count = 48 seq_step = 1 (kms_flip:2269) DEBUG: Vblank took 25us (kms_flip:2269) DEBUG: name = flip last_ts = 108.279533 last_received_ts = 108.279381 last_seq = 84 current_ts = 108.296196 current_received_ts = 108.295982 current_seq = 85 count = 49 seq_step = 1 (kms_flip:2269) DEBUG: Vblank took 25us (kms_flip:2269) DEBUG: name = flip last_ts = 108.296196 last_received_ts = 108.295982 last_seq = 85 current_ts = 108.312866 current_received_ts = 108.312744 current_seq = 86 count = 50 seq_step = 1 (kms_flip:2269) DEBUG: Vblank took 25us (kms_flip:2269) DEBUG: name = flip last_ts = 108.312866 last_received_ts = 108.312744 last_seq = 86 current_ts = 108.329544 current_received_ts = 108.329674 current_seq = 87 count = 51 seq_step = 1 (kms_flip:2269) DEBUG: Vblank took 23us (kms_flip:2269) DEBUG: name = flip last_ts = 108.329544 last_received_ts = 108.329674 last_seq = 87 current_ts = 108.346214 current_received_ts = 108.345879 current_seq = 88 count = 52 seq_step = 1 (kms_flip:2269) DEBUG: Vblank took 26us (kms_flip:2269) DEBUG: name = flip last_ts = 108.346214 last_received_ts = 108.345879 last_seq = 88 current_ts = 108.362892 current_received_ts = 108.362503 current_seq = 89 count = 53 seq_step = 1 (kms_flip:2269) DEBUG: Vblank took 24us (kms_flip:2269) DEBUG: name = flip last_ts = 108.362892 last_received_ts = 108.362503 last_seq = 89 current_ts = 108.379570 current_received_ts = 108.379784 current_seq = 90 count = 54 seq_step = 1 (kms_flip:2269) DEBUG: Vblank took 25us (kms_flip:2269) DEBUG: name = flip last_ts = 108.379570 last_received_ts = 108.379784 last_seq = 90 current_ts = 108.396248 current_received_ts = 108.395775 current_seq = 91 count = 55 seq_step = 1 (kms_flip:2269) DEBUG: Vblank took 25us (kms_flip:2269) DEBUG: name = flip last_ts = 108.396248 last_received_ts = 108.395775 last_seq = 91 current_ts = 108.412910 current_received_ts = 108.412956 current_seq = 92 count = 56 seq_step = 1 (kms_flip:2269) DEBUG: Vblank took 22us (kms_flip:2269) DEBUG: name = flip last_ts = 108.412910 last_received_ts = 108.412956 last_seq = 92 current_ts = 108.429596 current_received_ts = 108.429451 current_seq = 93 count = 57 seq_step = 1 (kms_flip:2269) DEBUG: Vblank took 22us (kms_flip:2269) DEBUG: name = flip last_ts = 108.429596 last_received_ts = 108.429451 last_seq = 93 current_ts = 108.446274 current_received_ts = 108.446121 current_seq = 94 count = 58 seq_step = 1 (kms_flip:2269) DEBUG: Vblank took 23us (kms_flip:2269) DEBUG: name = flip last_ts = 108.446274 last_received_ts = 108.446121 last_seq = 94 current_ts = 108.462936 current_received_ts = 108.462784 current_seq = 95 count = 59 seq_step = 1 (kms_flip:2269) DEBUG: Vblank took 20us (kms_flip:2269) DEBUG: name = flip last_ts = 108.462936 last_received_ts = 108.462784 last_seq = 95 current_ts = 108.479614 current_received_ts = 108.479492 current_seq = 96 count = 60 seq_step = 1 (kms_flip:2269) DEBUG: Vblank took 23us (kms_flip:2269) DEBUG: name = flip last_ts = 108.479614 last_received_ts = 108.479492 last_seq = 96 current_ts = 108.496284 current_received_ts = 108.495796 current_seq = 97 count = 61 seq_step = 1 (kms_flip:2269) DEBUG: Vblank took 22us (kms_flip:2269) DEBUG: name = flip last_ts = 108.496284 last_received_ts = 108.495796 last_seq = 97 current_ts = 108.512962 current_received_ts = 108.512482 current_seq = 98 count = 62 seq_step = 1 (kms_flip:2269) DEBUG: Vblank took 22us (kms_flip:2269) DEBUG: name = flip last_ts = 108.512962 last_received_ts = 108.512482 last_seq = 98 current_ts = 108.529640 current_received_ts = 108.529419 current_seq = 99 count = 63 seq_step = 1 (kms_flip:2269) DEBUG: Vblank took 23us (kms_flip:2269) DEBUG: name = flip last_ts = 108.529640 last_received_ts = 108.529419 last_seq = 99 current_ts = 108.546310 current_received_ts = 108.546120 current_seq = 100 count = 64 seq_step = 1 (kms_flip:2269) DEBUG: Vblank took 23us (kms_flip:2269) DEBUG: name = flip last_ts = 108.546310 last_received_ts = 108.546120 last_seq = 100 current_ts = 108.562988 current_received_ts = 108.562515 current_seq = 101 count = 65 seq_step = 1 (kms_flip:2269) DEBUG: Vblank took 1110us (kms_flip:2269) CRITICAL: Test assertion failure function run_test_step, file ../tests/kms_flip.c:931: (kms_flip:2269) CRITICAL: Failed assertion: end - start < 500 (kms_flip:2269) CRITICAL: Last errno: 4, Interrupted system call (kms_flip:2269) igt_core-INFO: Stack trace: (kms_flip:2269) igt_core-INFO: #0 ../lib/igt_core.c:2075 __igt_fail_assert() (kms_flip:2269) igt_core-INFO: #1 ../tests/kms_flip.c:926 run_test_on_crtc_set() (kms_flip:2269) igt_core-INFO: #2 ../tests/kms_flip.c:1920 run_test() (kms_flip:2269) igt_core-INFO: #3 ../tests/kms_flip.c:2220 __igt_unique____real_main2105() (kms_flip:2269) igt_core-INFO: #4 ../tests/kms_flip.c:2105 main() (kms_flip:2269) igt_core-INFO: #5 [__libc_init_first+0x8a] (kms_flip:2269) igt_core-INFO: #6 [__libc_start_main+0x8b] (kms_flip:2269) igt_core-INFO: #7 [_start+0x25] END ** Dynamic subtest C-HDMI-A1: FAIL (1.524s)
Dmesg	<6> [106.757778] [IGT] kms_flip: starting dynamic subtest C-HDMI-A1 <7> [106.759161] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:179] <7> [106.759565] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:183] <7> [106.802839] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:78:pipe A] <7> [106.803404] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:121:pipe B] <7> [106.803594] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.enable (expected yes, found no) <7> [106.804304] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.active (expected yes, found no) <7> [106.804789] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in cpu_transcoder (expected 1, found -1) <7> [106.805363] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in lane_count (expected 4, found 0) <7> [106.805798] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in lane_lat_optim_mask (expected 0x0000000d, found 0x00000000) <7> [106.806255] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in output_types (expected 0x00000040, found 0x00000000) <7> [106.806688] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in framestart_delay (expected 1, found 0) <7> [106.807276] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 1920, found 0) <7> [106.807737] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 2200, found 0) <7> [106.808436] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 1920, found 0) <7> [106.808875] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 2200, found 0) <7> [106.809429] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 2008, found 0) <7> [106.809859] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2052, found 0) <7> [106.810322] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1080, found 0) <7> [106.810769] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1080, found 0) <7> [106.811263] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1083, found 0) <7> [106.811695] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1088, found 0) <7> [106.812156] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1125, found 0) <7> [106.812586] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1125, found 0) <7> [106.813012] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 1920, found 0) <7> [106.813557] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 2200, found 0) <7> [106.813990] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 1920, found 0) <7> [106.814454] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 2200, found 0) <7> [106.814882] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 2008, found 0) <7> [106.815329] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2052, found 0) <7> [106.815766] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1080, found 0) <7> [106.816451] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1080, found 0) <7> [106.816892] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1083, found 0) <7> [106.817430] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1088, found 0) <7> [106.817861] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1125, found 0) <7> [106.818329] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1125, found 0) <7> [106.818769] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in pixel_multiplier (expected 1, found 0) <7> [106.819237] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 1, found 0) <7> [106.819668] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 8, found 0) <7> [106.820159] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in has_hdmi_sink (expected yes, found no) <7> [106.820590] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in has_infoframe (expected yes, found no) <7> [106.821019] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in pipe_bpp (expected 36, found 0) <7> [106.821688] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 148500, found 0) <7> [106.822177] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 148500, found 0) <7> [106.822606] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in port_clock (expected 222750, found 0) <7> [106.823034] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in infoframes.enable (expected 0x00000071, found 0x00000000) <7> [106.823668] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in infoframes.gcp (expected 0x00000006, found 0x00000000) <7> [106.824246] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in avi infoframe <7> [106.824679] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected: <7> [106.825145] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13 <7> [106.825151] i915 0000:00:02.0: colorspace: RGB <7> [106.825155] i915 0000:00:02.0: scan mode: Underscan <7> [106.825158] i915 0000:00:02.0: colorimetry: No Data <7> [106.825162] i915 0000:00:02.0: picture aspect: No Data <7> [106.825165] i915 0000:00:02.0: active aspect: Same as Picture <7> [106.825168] i915 0000:00:02.0: itc: No Data <7> [106.825172] i915 0000:00:02.0: extended colorimetry: xvYCC 601 <7> [106.825175] i915 0000:00:02.0: quantization range: Full <7> [106.825178] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling <7> [106.825182] i915 0000:00:02.0: video code: 0 <7> [106.825185] i915 0000:00:02.0: ycc quantization range: Full <7> [106.825188] i915 0000:00:02.0: hdmi content type: Graphics <7> [106.825191] i915 0000:00:02.0: pixel repeat: 0 <7> [106.825195] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0 <7> [106.825198] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found: <7> [106.825625] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in spd infoframe <7> [106.826051] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected: <7> [106.826546] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25 <7> [106.826551] i915 0000:00:02.0: vendor: Intel <7> [106.826554] i915 0000:00:02.0: product: Integrated gfx <7> [106.826558] i915 0000:00:02.0: source device information: PC General (0x9) <7> [106.826562] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found: <7> [106.826989] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hdmi infoframe <7> [106.827496] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected: <7> [106.827926] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4 <7> [106.827930] i915 0000:00:02.0: empty frame <7> [106.827933] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found: <7> [106.828396] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:121:pipe B] fastset requirement not met, forcing full modeset <7> [106.828840] i915 0000:00:02.0: [drm:intel_dpll_crtc_put [i915]] [CRTC:121:pipe B] releasing PORT PLL B <7> [106.829378] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:121:pipe B] dbuf slices 0x1 -> 0x0, ddb (0 - 1020) -> (0 - 0), active pipes 0x2 -> 0x0 <7> [106.829861] i915 0000:00:02.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:115:cursor B] ddb ( 988 - 1020) -> ( 0 - 0), size 32 -> 0 <7> [106.830452] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:121:pipe B] min cdclk: 192000 kHz -> 0 kHz <7> [106.830953] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] Modeset required for cdclk change <7> [106.831631] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New cdclk calculated to be logical 79200 kHz, actual 79200 kHz <7> [106.832065] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New voltage level calculated to be logical 4, actual 4 <7> [106.832637] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:121:pipe B] enable: no [modeset] <7> [106.833089] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:79:plane 1B] fb: [NOFB], visible: no <7> [106.833520] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:88:plane 2B] fb: [NOFB], visible: no <7> [106.833972] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:97:plane 3B] fb: [NOFB], visible: no <7> [106.834459] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:106:plane 4B] fb: [NOFB], visible: no <7> [106.834892] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:115:cursor B] fb: [NOFB], visible: no <7> [106.835540] i915 0000:00:02.0: [drm:intel_audio_codec_disable [i915]] [CONNECTOR:166:HDMI-A-1][ENCODER:165:DDI B/PHY B] Disable audio codec on [CRTC:121:pipe B] <7> [106.854381] i915 0000:00:02.0: [drm:intel_audio_component_get_eld [i915]] Not valid for port B <7> [106.855974] i915 0000:00:02.0: [drm:intel_disable_transcoder [i915]] disabling pipe B <7> [106.867665] i915 0000:00:02.0: [drm:intel_audio_component_get_power [i915]] restored AUD_FREQ_CNTRL to 0x10 <7> [106.871113] i915 0000:00:02.0: [drm:intel_ddi_disable_transcoder_func [i915]] Quirk Increase DDI disabled time <7> [106.976384] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DDI_IO_B <7> [106.977684] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disable PORT PLL B (active 0x2, on? 1) for [CRTC:121:pipe B] <7> [106.979172] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disabling PORT PLL B <7> [106.979768] i915 0000:00:02.0: [drm:intel_set_cdclk [i915]] Pre changing CDCLK to 79200 kHz, VCO 633600 kHz, ref 19200 kHz, bypass 19200 kHz, voltage level 4 <7> [106.980332] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:165:DDI B/PHY B] <7> [106.980859] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:175:DDI C/PHY C] <7> [106.981492] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:166:HDMI-A-1] <7> [106.982270] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling dpio-common-b <7> [106.982779] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:121:pipe B] <7> [106.984067] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:164:pipe C] <7> [106.984472] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:164:pipe C] <7> [106.984542] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CONNECTOR:166:HDMI-A-1] <7> [106.984730] i915 0000:00:02.0: [drm:intel_hdmi_compute_output_format [i915]] picking 12 bpc for HDMI output (pipe bpp: 36) <7> [106.985310] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:164:pipe C] hw max bpp: 36, pipe bpp: 36, dithering: 0 <7> [106.985745] i915 0000:00:02.0: [drm:intel_ddi_compute_config_late [i915]] [ENCODER:165:DDI B/PHY B] [CRTC:164:pipe C] <7> [106.986183] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.enable (expected no, found yes) <7> [106.986617] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.active (expected no, found yes) <7> [106.987072] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in cpu_transcoder (expected -1, found 2) <7> [106.987507] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in lane_count (expected 0, found 4) <7> [106.987941] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in lane_lat_optim_mask (expected 0x00000000, found 0x0000000d) <7> [106.988430] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in output_types (expected 0x00000000, found 0x00000040) <7> [106.988860] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in framestart_delay (expected 0, found 1) <7> [106.989489] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 1920) <7> [106.989938] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 2200) <7> [106.990476] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 1920) <7> [106.990972] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 2200) <7> [106.991435] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 2008) <7> [106.991864] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2052) <7> [106.992406] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1080) <7> [106.992868] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1080) <7> [106.993342] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1083) <7> [106.993771] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1088) <7> [106.994252] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1125) <7> [106.994722] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1125) <7> [106.995544] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 1920) <7> [106.996019] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 2200) <7> [106.996447] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 1920) <7> [106.996943] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 2200) <7> [106.997497] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 2008) <7> [106.998020] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2052) <7> [106.998453] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1080) <7> [106.998882] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1080) <7> [106.999355] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1083) <7> [106.999785] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1088) <7> [107.000269] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1125) <7> [107.000721] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1125) <7> [107.001194] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in pixel_multiplier (expected 0, found 1) <7> [107.001623] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 0, found 1) <7> [107.002100] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 0, found 8) <7> [107.002555] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in has_hdmi_sink (expected no, found yes) <7> [107.002988] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in has_infoframe (expected no, found yes) <7> [107.003472] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in pipe_bpp (expected 0, found 36) <7> [107.003902] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 148500) <7> [107.004356] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 148500) <7> [107.004785] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in port_clock (expected 0, found 222750) <7> [107.005251] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in infoframes.enable (expected 0x00000000, found 0x00000071) <7> [107.005682] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in infoframes.gcp (expected 0x00000000, found 0x00000006) <7> [107.006190] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in avi infoframe <7> [107.006623] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected: <7> [107.007169] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found: <7> [107.007658] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13 <7> [107.007664] i915 0000:00:02.0: colorspace: RGB <7> [107.007668] i915 0000:00:02.0: scan mode: Underscan <7> [107.007671] i915 0000:00:02.0: colorimetry: No Data <7> [107.007675] i915 0000:00:02.0: picture aspect: No Data <7> [107.007678] i915 0000:00:02.0: active aspect: Same as Picture <7> [107.007682] i915 0000:00:02.0: itc: No Data <7> [107.007685] i915 0000:00:02.0: extended colorimetry: xvYCC 601 <7> [107.007689] i915 0000:00:02.0: quantization range: Full <7> [107.007692] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling <7> [107.007695] i915 0000:00:02.0: video code: 0 <7> [107.007698] i915 0000:00:02.0: ycc quantization range: Full <7> [107.007701] i915 0000:00:02.0: hdmi content type: Graphics <7> [107.007705] i915 0000:00:02.0: pixel repeat: 0 <7> [107.007708] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0 <7> [107.007712] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in spd infoframe <7> [107.008178] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected: <7> [107.008607] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found: <7> [107.009073] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25 <7> [107.009078] i915 0000:00:02.0: vendor: Intel <7> [107.009081] i915 0000:00:02.0: product: Integrated gfx <7> [107.009085] i915 0000:00:02.0: source device information: PC General (0x9) <7> [107.009089] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hdmi infoframe <7> [107.009537] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected: <7> [107.009964] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found: <7> [107.010540] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4 <7> [107.010545] i915 0000:00:02.0: empty frame <7> [107.010550] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:164:pipe C] fastset requirement not met, forcing full modeset <7> [107.011092] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:164:pipe C] dbuf slices 0x0 -> 0x1, ddb (0 - 0) -> (0 - 1020), active pipes 0x0 -> 0x4 <7> [107.011621] i915 0000:00:02.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:122:plane 1C] ddb ( 0 - 0) -> ( 0 - 988), size 0 -> 988 <7> [107.012091] i915 0000:00:02.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:158:cursor C] ddb ( 0 - 0) -> ( 988 - 1020), size 0 -> 32 <7> [107.012512] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:122:plane 1C] level wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm -> wm0,wm1,wm2,wm3,wm4,wm5,wm6,wm7,*twm, swm, stwm <7> [107.012930] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:122:plane 1C] lines 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 1, 1, 1, 2, 2, 2, 2, 2, 0, 0, 0 <7> [107.013393] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:122:plane 1C] blocks 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 16, 16, 16, 33, 33, 33, 33, 33, 30, 0, 0 <7> [107.013812] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:122:plane 1C] min_ddb 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 17, 17, 17, 34, 34, 34, 34, 34, 31, 0, 0 <7> [107.014257] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:122:plane 1C] min_ddb_uv 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 <7> [107.014722] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:164:pipe C] min cdclk: 0 kHz -> 192000 kHz <7> [107.015289] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] Modeset required for cdclk change <7> [107.015721] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New cdclk calculated to be logical 316800 kHz, actual 316800 kHz <7> [107.016229] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New voltage level calculated to be logical 13, actual 13 <7> [107.016666] i915 0000:00:02.0: [drm:bxt_get_dpll [i915]] [CRTC:164:pipe C] using pre-allocated PORT PLL B <7> [107.017247] i915 0000:00:02.0: [drm:intel_dpll_crtc_get [i915]] [CRTC:164:pipe C] reserving PORT PLL B <7> [107.017746] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:164:pipe C] enable: yes [modeset] <7> [107.018207] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: HDMI (0x40), output format: RGB, sink format: RGB <7> [107.018638] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: C, pipe bpp: 36, dithering: 0 <7> [107.019106] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <invalid> <7> [107.019534] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0 <7> [107.019962] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] joiner: no, pipes: 0x0 <7> [107.020452] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0 <7> [107.020902] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] audio: 1, infoframes: 1, infoframes enabled: 0x71 <7> [107.021374] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] GCP: 0x6 <7> [107.021801] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13 <7> [107.021808] i915 0000:00:02.0: colorspace: RGB <7> [107.021812] i915 0000:00:02.0: scan mode: Underscan <7> [107.021815] i915 0000:00:02.0: colorimetry: No Data <7> [107.021819] i915 0000:00:02.0: picture aspect: No Data <7> [107.021822] i915 0000:00:02.0: active aspect: Same as Picture <7> [107.021825] i915 0000:00:02.0: itc: No Data <7> [107.021828] i915 0000:00:02.0: extended colorimetry: xvYCC 601 <7> [107.021831] i915 0000:00:02.0: quantization range: Full <7> [107.021834] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling <7> [107.021838] i915 0000:00:02.0: video code: 0 <7> [107.021841] i915 0000:00:02.0: ycc quantization range: Full <7> [107.021844] i915 0000:00:02.0: hdmi content type: Graphics <7> [107.021847] i915 0000:00:02.0: pixel repeat: 0 <7> [107.021850] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0 <7> [107.021854] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25 <7> [107.021858] i915 0000:00:02.0: vendor: Intel <7> [107.021861] i915 0000:00:02.0: product: Integrated gfx <7> [107.021864] i915 0000:00:02.0: source device information: PC General (0x9) <7> [107.021868] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4 <7> [107.021871] i915 0000:00:02.0: empty frame <7> [107.021874] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ELD: 10 00 07 00 66 10 00 01 00 00 00 00 00 00 00 00 <7> [107.022345] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ELD: 05 e3 cd 0c 32 38 45 38 35 30 09 07 07 00 00 00 <7> [107.022798] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] scanline offset: 2 <7> [107.023261] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] framestart delay: 1, MSA timing delay: 0, set context latency: 0 <7> [107.023692] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: no, fixed rr: no, vmin: 0, vmax: 0, flipline: 0, pipeline full: 0, guardband: 0 vsync start: 0, vsync end: 0 <7> [107.024154] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: vmin vblank: 0, vmax vblank: 0, vmin vtotal: 0, vmax vtotal: 0 <7> [107.024581] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: dc balance: no, vmin: 0 vmax: 0 guardband: 0, slope: 0 max increase: 0 max decrease: 0 vblank target: 0 <7> [107.025030] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] requested mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1083 1088 1125 0x48 0x9 <7> [107.025513] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] adjusted mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1083 1088 1125 0x48 0x9 <7> [107.025945] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] crtc timings: clock=148500, hd=1920 hb=1920-2200 hs=2008-2052 ht=2200, vd=1080 vb=1080-1125 vs=1083-1088 vt=1125, flags=0x9 <7> [107.026502] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1083 1088 1125 0x40 0x9 <7> [107.027025] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] crtc timings: clock=148500, hd=1920 hb=1920-2200 hs=2008-2052 ht=2200, vd=1080 vb=1080-1125 vs=1083-1088 vt=1125, flags=0x9 <7> [107.027455] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port clock: 222750, pixel rate 148500, min cdclk 192000, min voltage level 0 <7> [107.027879] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] linetime: 59, ips linetime: 0 <7> [107.028339] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] num_scalers: 1, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0, sharpness_strength: 0 <7> [107.028787] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe src: 1920x1080+0+0 <7> [107.029273] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pch pfit: 0x0+0+0, disabled, force thru: no <7> [107.029723] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0 <7> [107.030229] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0 <7> [107.030659] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] dpll_hw_state: ebb0: 0x6200, ebb4: 0x2000,pll0: 0x21, pll1: 0x100, pll2: 0x1a6666, pll3: 0x10000, pll6: 0x30904, pll8: 0x8, pll9: 0xa, pll10: 0x8003c00, pcsdw12: 0x4d <7> [107.031111] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0 <7> [107.031567] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 0 entries <7> [107.032020] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: pre offsets: 0x0000 0x0000 0x0000 <7> [107.032447] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000 <7> [107.032876] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000 <7> [107.033330] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000 <7> [107.033808] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: post offsets: 0x0000 0x0000 0x0000 <7> [107.034346] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:122:plane 1C] fb: [FB:179] 1920x1080 format = XR24 little-endian (0x34325258) modifier = 0x100000000000001, visible: yes <7> [107.034785] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0 <7> [107.035239] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] src: 1920.000000x1080.000000+0.000000+0.000000 dst: 1920x1080+0+0 <7> [107.035670] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:131:plane 2C] fb: [NOFB], visible: no <7> [107.036199] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:140:plane 3C] fb: [NOFB], visible: no <7> [107.036629] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:149:plane 4C] fb: [NOFB], visible: no <7> [107.037101] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:158:cursor C] fb: [NOFB], visible: no <7> [107.038340] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling dpio-common-b <7> [107.039117] i915 0000:00:02.0: [drm:intel_set_cdclk [i915]] Pre changing CDCLK to 316800 kHz, VCO 633600 kHz, ref 19200 kHz, bypass 19200 kHz, voltage level 13 <7> [107.039601] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:165:DDI B/PHY B] <7> [107.040104] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:175:DDI C/PHY C] <7> [107.040694] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enable PORT PLL B (active 0x4, on? 0) for [CRTC:164:pipe C] <7> [107.041198] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enabling PORT PLL B <7> [107.041839] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DDI_IO_B <7> [107.042405] i915 0000:00:02.0: [drm:intel_enable_transcoder [i915]] enabling pipe C <7> [107.060566] i915 0000:00:02.0: [drm:intel_audio_codec_enable [i915]] [CONNECTOR:166:HDMI-A-1][ENCODER:165:DDI B/PHY B] Enable audio codec on [CRTC:164:pipe C], 32 bytes ELD <7> [107.077191] i915 0000:00:02.0: [drm:audio_config_hdmi_pixel_clock [i915]] Configuring HDMI audio for pixel clock 148500 (0x00090000) <7> [107.077702] i915 0000:00:02.0: [drm:hsw_audio_config_update [i915]] using automatic N <7> [107.078341] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:166:HDMI-A-1] <7> [107.079600] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:164:pipe C] <7> [107.087651] azx_init_pci: snd_hda_intel 0000:00:0e.0: Clearing TCSEL <7> [107.087672] azx_init_pci: snd_hda_intel 0000:00:0e.0: SCH snoop: Enabled <6> [108.281933] [IGT] kms_flip: finished subtest C-HDMI-A1, FAIL

Detail

Value

Duration

1.52 seconds

Hostname

shard-glk5

Igt-Version

IGT-Version: 2.4-g63a08403d (x86_64) (Linux: 7.0.0-Patchwork_164957v1-gb2dbef367fba+ x86_64)

Out

Starting dynamic subtest: C-HDMI-A1
  1920x1080: 60 148500 1920 2008 2052 2200 1080 1083 1088 1125 0x48 0x9 
Stack trace:
  #0 ../lib/igt_core.c:2075 __igt_fail_assert()
  #1 ../tests/kms_flip.c:926 run_test_on_crtc_set()
  #2 ../tests/kms_flip.c:1920 run_test()
  #3 ../tests/kms_flip.c:2220 __igt_unique____real_main2105()
  #4 ../tests/kms_flip.c:2105 main()
  #5 [__libc_init_first+0x8a]
  #6 [__libc_start_main+0x8b]
  #7 [_start+0x25]
Dynamic subtest C-HDMI-A1: FAIL (1.524s)

Err

Starting dynamic subtest: C-HDMI-A1
(kms_flip:2269) CRITICAL: Test assertion failure function run_test_step, file ../tests/kms_flip.c:931:
(kms_flip:2269) CRITICAL: Failed assertion: end - start < 500
(kms_flip:2269) CRITICAL: Last errno: 4, Interrupted system call
Dynamic subtest C-HDMI-A1 failed.
**** DEBUG ****
(kms_flip:2269) igt_fb-DEBUG: igt_create_fb_with_bo_size(width=1920, height=1080, format=XR24(0x34325258), modifier=0x100000000000001, size=0)
(kms_flip:2269) drmtest-DEBUG: Test requirement passed: is_intel_device(fd)
(kms_flip:2269) drmtest-DEBUG: Test requirement passed: is_intel_device(fd)
(kms_flip:2269) igt_fb-DEBUG: igt_create_fb_with_bo_size(handle=1, pitch=7680)
(kms_flip:2269) ioctl_wrappers-DEBUG: Test requirement passed: igt_has_fb_modifiers(fd)
(kms_flip:2269) igt_fb-DEBUG: igt_create_fb_with_bo_size(width=1920, height=1080, format=XR24(0x34325258), modifier=0x100000000000001, size=0)
(kms_flip:2269) drmtest-DEBUG: Test requirement passed: is_intel_device(fd)
(kms_flip:2269) drmtest-DEBUG: Test requirement passed: is_intel_device(fd)
(kms_flip:2269) igt_fb-DEBUG: igt_create_fb_with_bo_size(handle=2, pitch=7680)
(kms_flip:2269) ioctl_wrappers-DEBUG: Test requirement passed: igt_has_fb_modifiers(fd)
(kms_flip:2269) intel_blt-DEBUG: Test requirement passed: cmds_info
(kms_flip:2269) intel_blt-DEBUG: Test requirement passed: cmds_info
(kms_flip:2269) igt_fb-DEBUG: Test requirement passed: cairo_surface_status(fb->cairo_surface) == CAIRO_STATUS_SUCCESS
(kms_flip:2269) intel_blt-DEBUG: Test requirement passed: cmds_info
(kms_flip:2269) intel_blt-DEBUG: Test requirement passed: cmds_info
(kms_flip:2269) igt_fb-DEBUG: Test requirement passed: cairo_surface_status(fb->cairo_surface) == CAIRO_STATUS_SUCCESS
(kms_flip:2269) igt_kms-INFO:   1920x1080: 60 148500 1920 2008 2052 2200 1080 1083 1088 1125 0x48 0x9 
(kms_flip:2269) DEBUG: No stale events found
(kms_flip:2269) i915/intel_drrs-DEBUG: Manually disabling DRRS. 0
(kms_flip:2269) i915/intel_drrs-DEBUG: Test requirement passed: dir >= 0
(kms_flip:2269) DEBUG: name = flip
last_ts = 0.000000
last_received_ts = 0.000000
last_seq = 0
current_ts = 107.479172
current_received_ts = 107.478615
current_seq = 36
count = 0
seq_step = 1
(kms_flip:2269) DEBUG: Vblank took 9us
(kms_flip:2269) DEBUG: name = flip
last_ts = 107.479172
last_received_ts = 107.478615
last_seq = 36
current_ts = 107.495827
current_received_ts = 107.495262
current_seq = 37
count = 1
seq_step = 1
(kms_flip:2269) DEBUG: Vblank took 6us
(kms_flip:2269) DEBUG: name = flip
last_ts = 107.495827
last_received_ts = 107.495262
last_seq = 37
current_ts = 107.512520
current_received_ts = 107.511932
current_seq = 38
count = 2
seq_step = 1
(kms_flip:2269) DEBUG: Vblank took 7us
(kms_flip:2269) DEBUG: name = flip
last_ts = 107.512520
last_received_ts = 107.511932
last_seq = 38
current_ts = 107.529190
current_received_ts = 107.528732
current_seq = 39
count = 3
seq_step = 1
(kms_flip:2269) DEBUG: Vblank took 7us
(kms_flip:2269) DEBUG: name = flip
last_ts = 107.529190
last_received_ts = 107.528732
last_seq = 39
current_ts = 107.545868
current_received_ts = 107.545433
current_seq = 40
count = 4
seq_step = 1
(kms_flip:2269) DEBUG: Vblank took 8us
(kms_flip:2269) DEBUG: name = flip
last_ts = 107.545868
last_received_ts = 107.545433
last_seq = 40
current_ts = 107.562531
current_received_ts = 107.562447
current_seq = 41
count = 5
seq_step = 1
(kms_flip:2269) DEBUG: Vblank took 15us
(kms_flip:2269) DEBUG: name = flip
last_ts = 107.562531
last_received_ts = 107.562447
last_seq = 41
current_ts = 107.579216
current_received_ts = 107.578857
current_seq = 42
count = 6
seq_step = 1
(kms_flip:2269) DEBUG: Vblank took 24us
(kms_flip:2269) DEBUG: name = flip
last_ts = 107.579216
last_received_ts = 107.578857
last_seq = 42
current_ts = 107.595879
current_received_ts = 107.595421
current_seq = 43
count = 7
seq_step = 1
(kms_flip:2269) DEBUG: Vblank took 26us
(kms_flip:2269) DEBUG: name = flip
last_ts = 107.595879
last_received_ts = 107.595421
last_seq = 43
current_ts = 107.612549
current_received_ts = 107.612328
current_seq = 44
count = 8
seq_step = 1
(kms_flip:2269) DEBUG: Vblank took 25us
(kms_flip:2269) DEBUG: name = flip
last_ts = 107.612549
last_received_ts = 107.612328
last_seq = 44
current_ts = 107.629234
current_received_ts = 107.629051
current_seq = 45
count = 9
seq_step = 1
(kms_flip:2269) DEBUG: Vblank took 24us
(kms_flip:2269) DEBUG: name = flip
last_ts = 107.629234
last_received_ts = 107.629051
last_seq = 45
current_ts = 107.645905
current_received_ts = 107.645470
current_seq = 46
count = 10
seq_step = 1
(kms_flip:2269) DEBUG: Vblank took 22us
(kms_flip:2269) DEBUG: name = flip
last_ts = 107.645905
last_received_ts = 107.645470
last_seq = 46
current_ts = 107.662582
current_received_ts = 107.662392
current_seq = 47
count = 11
seq_step = 1
(kms_flip:2269) DEBUG: Vblank took 23us
(kms_flip:2269) DEBUG: name = flip
last_ts = 107.662582
last_received_ts = 107.662392
last_seq = 47
current_ts = 107.679253
current_received_ts = 107.679314
current_seq = 48
count = 12
seq_step = 1
(kms_flip:2269) DEBUG: Vblank took 22us
(kms_flip:2269) DEBUG: name = flip
last_ts = 107.679253
last_received_ts = 107.679314
last_seq = 48
current_ts = 107.695923
current_received_ts = 107.695534
current_seq = 49
count = 13
seq_step = 1
(kms_flip:2269) DEBUG: Vblank took 23us
(kms_flip:2269) DEBUG: name = flip
last_ts = 107.695923
last_received_ts = 107.695534
last_seq = 49
current_ts = 107.712593
current_received_ts = 107.712372
current_seq = 50
count = 14
seq_step = 1
(kms_flip:2269) DEBUG: Vblank took 23us
(kms_flip:2269) DEBUG: name = flip
last_ts = 107.712593
last_received_ts = 107.712372
last_seq = 50
current_ts = 107.729279
current_received_ts = 107.729073
current_seq = 51
count = 15
seq_step = 1
(kms_flip:2269) DEBUG: Vblank took 24us
(kms_flip:2269) DEBUG: name = flip
last_ts = 107.729279
last_received_ts = 107.729073
last_seq = 51
current_ts = 107.745956
current_received_ts = 107.745537
current_seq = 52
count = 16
seq_step = 1
(kms_flip:2269) DEBUG: Vblank took 22us
(kms_flip:2269) DEBUG: name = flip
last_ts = 107.745956
last_received_ts = 107.745537
last_seq = 52
current_ts = 107.762627
current_received_ts = 107.762749
current_seq = 53
count = 17
seq_step = 1
(kms_flip:2269) DEBUG: Vblank took 22us
(kms_flip:2269) DEBUG: name = flip
last_ts = 107.762627
last_received_ts = 107.762749
last_seq = 53
current_ts = 107.779289
current_received_ts = 107.779167
current_seq = 54
count = 18
seq_step = 1
(kms_flip:2269) DEBUG: Vblank took 23us
(kms_flip:2269) DEBUG: name = flip
last_ts = 107.779289
last_received_ts = 107.779167
last_seq = 54
current_ts = 107.795967
current_received_ts = 107.795555
current_seq = 55
count = 19
seq_step = 1
(kms_flip:2269) DEBUG: Vblank took 24us
(kms_flip:2269) DEBUG: name = flip
last_ts = 107.795967
last_received_ts = 107.795555
last_seq = 55
current_ts = 107.812645
current_received_ts = 107.812698
current_seq = 56
count = 20
seq_step = 1
(kms_flip:2269) DEBUG: Vblank took 22us
(kms_flip:2269) DEBUG: name = flip
last_ts = 107.812645
last_received_ts = 107.812698
last_seq = 56
current_ts = 107.829315
current_received_ts = 107.829170
current_seq = 57
count = 21
seq_step = 1
(kms_flip:2269) DEBUG: Vblank took 26us
(kms_flip:2269) DEBUG: name = flip
last_ts = 107.829315
last_received_ts = 107.829170
last_seq = 57
current_ts = 107.845985
current_received_ts = 107.845932
current_seq = 58
count = 22
seq_step = 1
(kms_flip:2269) DEBUG: Vblank took 63us
(kms_flip:2269) DEBUG: name = flip
last_ts = 107.845985
last_received_ts = 107.845932
last_seq = 58
current_ts = 107.862663
current_received_ts = 107.862480
current_seq = 59
count = 23
seq_step = 1
(kms_flip:2269) DEBUG: Vblank took 22us
(kms_flip:2269) DEBUG: name = flip
last_ts = 107.862663
last_received_ts = 107.862480
last_seq = 59
current_ts = 107.879349
current_received_ts = 107.879280
current_seq = 60
count = 24
seq_step = 1
(kms_flip:2269) DEBUG: Vblank took 24us
(kms_flip:2269) DEBUG: name = flip
last_ts = 107.879349
last_received_ts = 107.879280
last_seq = 60
current_ts = 107.896019
current_received_ts = 107.895561
current_seq = 61
count = 25
seq_step = 1
(kms_flip:2269) DEBUG: Vblank took 25us
(kms_flip:2269) DEBUG: name = flip
last_ts = 107.896019
last_received_ts = 107.895561
last_seq = 61
current_ts = 107.912697
current_received_ts = 107.912468
current_seq = 62
count = 26
seq_step = 1
(kms_flip:2269) DEBUG: Vblank took 22us
(kms_flip:2269) DEBUG: name = flip
last_ts = 107.912697
last_received_ts = 107.912468
last_seq = 62
current_ts = 107.929367
current_received_ts = 107.929581
current_seq = 63
count = 27
seq_step = 1
(kms_flip:2269) DEBUG: Vblank took 21us
(kms_flip:2269) DEBUG: name = flip
last_ts = 107.929367
last_received_ts = 107.929581
last_seq = 63
current_ts = 107.946045
current_received_ts = 107.945580
current_seq = 64
count = 28
seq_step = 1
(kms_flip:2269) DEBUG: Vblank took 26us
(kms_flip:2269) DEBUG: name = flip
last_ts = 107.946045
last_received_ts = 107.945580
last_seq = 64
current_ts = 107.962708
current_received_ts = 107.962479
current_seq = 65
count = 29
seq_step = 1
(kms_flip:2269) DEBUG: Vblank took 25us
(kms_flip:2269) DEBUG: name = flip
last_ts = 107.962708
last_received_ts = 107.962479
last_seq = 65
current_ts = 107.979393
current_received_ts = 107.978897
current_seq = 66
count = 30
seq_step = 1
(kms_flip:2269) DEBUG: Vblank took 23us
(kms_flip:2269) DEBUG: name = flip
last_ts = 107.979393
last_received_ts = 107.978897
last_seq = 66
current_ts = 107.996071
current_received_ts = 107.995697
current_seq = 67
count = 31
seq_step = 1
(kms_flip:2269) DEBUG: Vblank took 24us
(kms_flip:2269) DEBUG: name = flip
last_ts = 107.996071
last_received_ts = 107.995697
last_seq = 67
current_ts = 108.012733
current_received_ts = 108.012482
current_seq = 68
count = 32
seq_step = 1
(kms_flip:2269) DEBUG: Vblank took 23us
(kms_flip:2269) DEBUG: name = flip
last_ts = 108.012733
last_received_ts = 108.012482
last_seq = 68
current_ts = 108.029411
current_received_ts = 108.029221
current_seq = 69
count = 33
seq_step = 1
(kms_flip:2269) DEBUG: Vblank took 24us
(kms_flip:2269) DEBUG: name = flip
last_ts = 108.029411
last_received_ts = 108.029221
last_seq = 69
current_ts = 108.046097
current_received_ts = 108.045616
current_seq = 70
count = 34
seq_step = 1
(kms_flip:2269) DEBUG: Vblank took 24us
(kms_flip:2269) DEBUG: name = flip
last_ts = 108.046097
last_received_ts = 108.045616
last_seq = 70
current_ts = 108.062767
current_received_ts = 108.062683
current_seq = 71
count = 35
seq_step = 1
(kms_flip:2269) DEBUG: Vblank took 21us
(kms_flip:2269) DEBUG: name = flip
last_ts = 108.062767
last_received_ts = 108.062683
last_seq = 71
current_ts = 108.079437
current_received_ts = 108.079224
current_seq = 72
count = 36
seq_step = 1
(kms_flip:2269) DEBUG: Vblank took 25us
(kms_flip:2269) DEBUG: name = flip
last_ts = 108.079437
last_received_ts = 108.079224
last_seq = 72
current_ts = 108.096100
current_received_ts = 108.095711
current_seq = 73
count = 37
seq_step = 1
(kms_flip:2269) DEBUG: Vblank took 24us
(kms_flip:2269) DEBUG: name = flip
last_ts = 108.096100
last_received_ts = 108.095711
last_seq = 73
current_ts = 108.112793
current_received_ts = 108.112892
current_seq = 74
count = 38
seq_step = 1
(kms_flip:2269) DEBUG: Vblank took 22us
(kms_flip:2269) DEBUG: name = flip
last_ts = 108.112793
last_received_ts = 108.112892
last_seq = 74
current_ts = 108.129463
current_received_ts = 108.129311
current_seq = 75
count = 39
seq_step = 1
(kms_flip:2269) DEBUG: Vblank took 25us
(kms_flip:2269) DEBUG: name = flip
last_ts = 108.129463
last_received_ts = 108.129311
last_seq = 75
current_ts = 108.146141
current_received_ts = 108.145699
current_seq = 76
count = 40
seq_step = 1
(kms_flip:2269) DEBUG: Vblank took 23us
(kms_flip:2269) DEBUG: name = flip
last_ts = 108.146141
last_received_ts = 108.145699
last_seq = 76
current_ts = 108.162811
current_received_ts = 108.162521
current_seq = 77
count = 41
seq_step = 1
(kms_flip:2269) DEBUG: Vblank took 23us
(kms_flip:2269) DEBUG: name = flip
last_ts = 108.162811
last_received_ts = 108.162521
last_seq = 77
current_ts = 108.179482
current_received_ts = 108.179039
current_seq = 78
count = 42
seq_step = 1
(kms_flip:2269) DEBUG: Vblank took 24us
(kms_flip:2269) DEBUG: name = flip
last_ts = 108.179482
last_received_ts = 108.179039
last_seq = 78
current_ts = 108.196152
current_received_ts = 108.196220
current_seq = 79
count = 43
seq_step = 1
(kms_flip:2269) DEBUG: Vblank took 23us
(kms_flip:2269) DEBUG: name = flip
last_ts = 108.196152
last_received_ts = 108.196220
last_seq = 79
current_ts = 108.212830
current_received_ts = 108.212524
current_seq = 80
count = 44
seq_step = 1
(kms_flip:2269) DEBUG: Vblank took 26us
(kms_flip:2269) DEBUG: name = flip
last_ts = 108.212830
last_received_ts = 108.212524
last_seq = 80
current_ts = 108.229507
current_received_ts = 108.229057
current_seq = 81
count = 45
seq_step = 1
(kms_flip:2269) DEBUG: Vblank took 23us
(kms_flip:2269) DEBUG: name = flip
last_ts = 108.229507
last_received_ts = 108.229057
last_seq = 81
current_ts = 108.246178
current_received_ts = 108.246559
current_seq = 82
count = 46
seq_step = 1
(kms_flip:2269) DEBUG: Vblank took 22us
(kms_flip:2269) DEBUG: name = flip
last_ts = 108.246178
last_received_ts = 108.246559
last_seq = 82
current_ts = 108.262848
current_received_ts = 108.262482
current_seq = 83
count = 47
seq_step = 1
(kms_flip:2269) DEBUG: Vblank took 25us
(kms_flip:2269) DEBUG: name = flip
last_ts = 108.262848
last_received_ts = 108.262482
last_seq = 83
current_ts = 108.279533
current_received_ts = 108.279381
current_seq = 84
count = 48
seq_step = 1
(kms_flip:2269) DEBUG: Vblank took 25us
(kms_flip:2269) DEBUG: name = flip
last_ts = 108.279533
last_received_ts = 108.279381
last_seq = 84
current_ts = 108.296196
current_received_ts = 108.295982
current_seq = 85
count = 49
seq_step = 1
(kms_flip:2269) DEBUG: Vblank took 25us
(kms_flip:2269) DEBUG: name = flip
last_ts = 108.296196
last_received_ts = 108.295982
last_seq = 85
current_ts = 108.312866
current_received_ts = 108.312744
current_seq = 86
count = 50
seq_step = 1
(kms_flip:2269) DEBUG: Vblank took 25us
(kms_flip:2269) DEBUG: name = flip
last_ts = 108.312866
last_received_ts = 108.312744
last_seq = 86
current_ts = 108.329544
current_received_ts = 108.329674
current_seq = 87
count = 51
seq_step = 1
(kms_flip:2269) DEBUG: Vblank took 23us
(kms_flip:2269) DEBUG: name = flip
last_ts = 108.329544
last_received_ts = 108.329674
last_seq = 87
current_ts = 108.346214
current_received_ts = 108.345879
current_seq = 88
count = 52
seq_step = 1
(kms_flip:2269) DEBUG: Vblank took 26us
(kms_flip:2269) DEBUG: name = flip
last_ts = 108.346214
last_received_ts = 108.345879
last_seq = 88
current_ts = 108.362892
current_received_ts = 108.362503
current_seq = 89
count = 53
seq_step = 1
(kms_flip:2269) DEBUG: Vblank took 24us
(kms_flip:2269) DEBUG: name = flip
last_ts = 108.362892
last_received_ts = 108.362503
last_seq = 89
current_ts = 108.379570
current_received_ts = 108.379784
current_seq = 90
count = 54
seq_step = 1
(kms_flip:2269) DEBUG: Vblank took 25us
(kms_flip:2269) DEBUG: name = flip
last_ts = 108.379570
last_received_ts = 108.379784
last_seq = 90
current_ts = 108.396248
current_received_ts = 108.395775
current_seq = 91
count = 55
seq_step = 1
(kms_flip:2269) DEBUG: Vblank took 25us
(kms_flip:2269) DEBUG: name = flip
last_ts = 108.396248
last_received_ts = 108.395775
last_seq = 91
current_ts = 108.412910
current_received_ts = 108.412956
current_seq = 92
count = 56
seq_step = 1
(kms_flip:2269) DEBUG: Vblank took 22us
(kms_flip:2269) DEBUG: name = flip
last_ts = 108.412910
last_received_ts = 108.412956
last_seq = 92
current_ts = 108.429596
current_received_ts = 108.429451
current_seq = 93
count = 57
seq_step = 1
(kms_flip:2269) DEBUG: Vblank took 22us
(kms_flip:2269) DEBUG: name = flip
last_ts = 108.429596
last_received_ts = 108.429451
last_seq = 93
current_ts = 108.446274
current_received_ts = 108.446121
current_seq = 94
count = 58
seq_step = 1
(kms_flip:2269) DEBUG: Vblank took 23us
(kms_flip:2269) DEBUG: name = flip
last_ts = 108.446274
last_received_ts = 108.446121
last_seq = 94
current_ts = 108.462936
current_received_ts = 108.462784
current_seq = 95
count = 59
seq_step = 1
(kms_flip:2269) DEBUG: Vblank took 20us
(kms_flip:2269) DEBUG: name = flip
last_ts = 108.462936
last_received_ts = 108.462784
last_seq = 95
current_ts = 108.479614
current_received_ts = 108.479492
current_seq = 96
count = 60
seq_step = 1
(kms_flip:2269) DEBUG: Vblank took 23us
(kms_flip:2269) DEBUG: name = flip
last_ts = 108.479614
last_received_ts = 108.479492
last_seq = 96
current_ts = 108.496284
current_received_ts = 108.495796
current_seq = 97
count = 61
seq_step = 1
(kms_flip:2269) DEBUG: Vblank took 22us
(kms_flip:2269) DEBUG: name = flip
last_ts = 108.496284
last_received_ts = 108.495796
last_seq = 97
current_ts = 108.512962
current_received_ts = 108.512482
current_seq = 98
count = 62
seq_step = 1
(kms_flip:2269) DEBUG: Vblank took 22us
(kms_flip:2269) DEBUG: name = flip
last_ts = 108.512962
last_received_ts = 108.512482
last_seq = 98
current_ts = 108.529640
current_received_ts = 108.529419
current_seq = 99
count = 63
seq_step = 1
(kms_flip:2269) DEBUG: Vblank took 23us
(kms_flip:2269) DEBUG: name = flip
last_ts = 108.529640
last_received_ts = 108.529419
last_seq = 99
current_ts = 108.546310
current_received_ts = 108.546120
current_seq = 100
count = 64
seq_step = 1
(kms_flip:2269) DEBUG: Vblank took 23us
(kms_flip:2269) DEBUG: name = flip
last_ts = 108.546310
last_received_ts = 108.546120
last_seq = 100
current_ts = 108.562988
current_received_ts = 108.562515
current_seq = 101
count = 65
seq_step = 1
(kms_flip:2269) DEBUG: Vblank took 1110us
(kms_flip:2269) CRITICAL: Test assertion failure function run_test_step, file ../tests/kms_flip.c:931:
(kms_flip:2269) CRITICAL: Failed assertion: end - start < 500
(kms_flip:2269) CRITICAL: Last errno: 4, Interrupted system call
(kms_flip:2269) igt_core-INFO: Stack trace:
(kms_flip:2269) igt_core-INFO:   #0 ../lib/igt_core.c:2075 __igt_fail_assert()
(kms_flip:2269) igt_core-INFO:   #1 ../tests/kms_flip.c:926 run_test_on_crtc_set()
(kms_flip:2269) igt_core-INFO:   #2 ../tests/kms_flip.c:1920 run_test()
(kms_flip:2269) igt_core-INFO:   #3 ../tests/kms_flip.c:2220 __igt_unique____real_main2105()
(kms_flip:2269) igt_core-INFO:   #4 ../tests/kms_flip.c:2105 main()
(kms_flip:2269) igt_core-INFO:   #5 [__libc_init_first+0x8a]
(kms_flip:2269) igt_core-INFO:   #6 [__libc_start_main+0x8b]
(kms_flip:2269) igt_core-INFO:   #7 [_start+0x25]
****  END  ****
Dynamic subtest C-HDMI-A1: FAIL (1.524s)

Dmesg

<6> [106.757778] [IGT] kms_flip: starting dynamic subtest C-HDMI-A1

<7> [106.759161] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:179]

<7> [106.759565] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:183]

<7> [106.802839] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:78:pipe A]

<7> [106.803404] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:121:pipe B]

<7> [106.803594] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.enable (expected yes, found no)

<7> [106.804304] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.active (expected yes, found no)

<7> [106.804789] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in cpu_transcoder (expected 1, found -1)

<7> [106.805363] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in lane_count (expected 4, found 0)

<7> [106.805798] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in lane_lat_optim_mask (expected 0x0000000d, found 0x00000000)

<7> [106.806255] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in output_types (expected 0x00000040, found 0x00000000)

<7> [106.806688] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in framestart_delay (expected 1, found 0)

<7> [106.807276] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 1920, found 0)

<7> [106.807737] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 2200, found 0)

<7> [106.808436] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 1920, found 0)

<7> [106.808875] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 2200, found 0)

<7> [106.809429] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 2008, found 0)

<7> [106.809859] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2052, found 0)

<7> [106.810322] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1080, found 0)

<7> [106.810769] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1080, found 0)

<7> [106.811263] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1083, found 0)

<7> [106.811695] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1088, found 0)

<7> [106.812156] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1125, found 0)

<7> [106.812586] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1125, found 0)

<7> [106.813012] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 1920, found 0)

<7> [106.813557] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 2200, found 0)

<7> [106.813990] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 1920, found 0)

<7> [106.814454] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 2200, found 0)

<7> [106.814882] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 2008, found 0)

<7> [106.815329] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2052, found 0)

<7> [106.815766] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1080, found 0)

<7> [106.816451] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1080, found 0)

<7> [106.816892] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1083, found 0)

<7> [106.817430] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1088, found 0)

<7> [106.817861] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1125, found 0)

<7> [106.818329] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1125, found 0)

<7> [106.818769] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in pixel_multiplier (expected 1, found 0)

<7> [106.819237] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 1, found 0)

<7> [106.819668] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 8, found 0)

<7> [106.820159] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in has_hdmi_sink (expected yes, found no)

<7> [106.820590] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in has_infoframe (expected yes, found no)

<7> [106.821019] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in pipe_bpp (expected 36, found 0)

<7> [106.821688] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 148500, found 0)

<7> [106.822177] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 148500, found 0)

<7> [106.822606] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in port_clock (expected 222750, found 0)

<7> [106.823034] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in infoframes.enable (expected 0x00000071, found 0x00000000)

<7> [106.823668] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in infoframes.gcp (expected 0x00000006, found 0x00000000)

<7> [106.824246] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in avi infoframe

<7> [106.824679] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:

<7> [106.825145] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13

<7> [106.825151] i915 0000:00:02.0: colorspace: RGB

<7> [106.825155] i915 0000:00:02.0: scan mode: Underscan

<7> [106.825158] i915 0000:00:02.0: colorimetry: No Data

<7> [106.825162] i915 0000:00:02.0: picture aspect: No Data

<7> [106.825165] i915 0000:00:02.0: active aspect: Same as Picture

<7> [106.825168] i915 0000:00:02.0: itc: No Data

<7> [106.825172] i915 0000:00:02.0: extended colorimetry: xvYCC 601

<7> [106.825175] i915 0000:00:02.0: quantization range: Full

<7> [106.825178] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling

<7> [106.825182] i915 0000:00:02.0: video code: 0

<7> [106.825185] i915 0000:00:02.0: ycc quantization range: Full

<7> [106.825188] i915 0000:00:02.0: hdmi content type: Graphics

<7> [106.825191] i915 0000:00:02.0: pixel repeat: 0

<7> [106.825195] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0

<7> [106.825198] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:

<7> [106.825625] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in spd infoframe

<7> [106.826051] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:

<7> [106.826546] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25

<7> [106.826551] i915 0000:00:02.0: vendor: Intel

<7> [106.826554] i915 0000:00:02.0: product: Integrated gfx

<7> [106.826558] i915 0000:00:02.0: source device information: PC General (0x9)

<7> [106.826562] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:

<7> [106.826989] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hdmi infoframe

<7> [106.827496] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:

<7> [106.827926] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4

<7> [106.827930] i915 0000:00:02.0: empty frame

<7> [106.827933] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:

<7> [106.828396] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:121:pipe B] fastset requirement not met, forcing full modeset

<7> [106.828840] i915 0000:00:02.0: [drm:intel_dpll_crtc_put [i915]] [CRTC:121:pipe B] releasing PORT PLL B

<7> [106.829378] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:121:pipe B] dbuf slices 0x1 -> 0x0, ddb (0 - 1020) -> (0 - 0), active pipes 0x2 -> 0x0

<7> [106.829861] i915 0000:00:02.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:115:cursor B] ddb ( 988 - 1020) -> ( 0 - 0), size 32 -> 0

<7> [106.830452] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:121:pipe B] min cdclk: 192000 kHz -> 0 kHz

<7> [106.830953] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] Modeset required for cdclk change

<7> [106.831631] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New cdclk calculated to be logical 79200 kHz, actual 79200 kHz

<7> [106.832065] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New voltage level calculated to be logical 4, actual 4

<7> [106.832637] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:121:pipe B] enable: no [modeset]

<7> [106.833089] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:79:plane 1B] fb: [NOFB], visible: no

<7> [106.833520] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:88:plane 2B] fb: [NOFB], visible: no

<7> [106.833972] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:97:plane 3B] fb: [NOFB], visible: no

<7> [106.834459] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:106:plane 4B] fb: [NOFB], visible: no

<7> [106.834892] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:115:cursor B] fb: [NOFB], visible: no

<7> [106.835540] i915 0000:00:02.0: [drm:intel_audio_codec_disable [i915]] [CONNECTOR:166:HDMI-A-1][ENCODER:165:DDI B/PHY B] Disable audio codec on [CRTC:121:pipe B]

<7> [106.854381] i915 0000:00:02.0: [drm:intel_audio_component_get_eld [i915]] Not valid for port B

<7> [106.855974] i915 0000:00:02.0: [drm:intel_disable_transcoder [i915]] disabling pipe B

<7> [106.867665] i915 0000:00:02.0: [drm:intel_audio_component_get_power [i915]] restored AUD_FREQ_CNTRL to 0x10

<7> [106.871113] i915 0000:00:02.0: [drm:intel_ddi_disable_transcoder_func [i915]] Quirk Increase DDI disabled time

<7> [106.976384] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DDI_IO_B

<7> [106.977684] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disable PORT PLL B (active 0x2, on? 1) for [CRTC:121:pipe B]

<7> [106.979172] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disabling PORT PLL B

<7> [106.979768] i915 0000:00:02.0: [drm:intel_set_cdclk [i915]] Pre changing CDCLK to 79200 kHz, VCO 633600 kHz, ref 19200 kHz, bypass 19200 kHz, voltage level 4

<7> [106.980332] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:165:DDI B/PHY B]

<7> [106.980859] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:175:DDI C/PHY C]

<7> [106.981492] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:166:HDMI-A-1]

<7> [106.982270] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling dpio-common-b

<7> [106.982779] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:121:pipe B]

<7> [106.984067] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:164:pipe C]

<7> [106.984472] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:164:pipe C]

<7> [106.984542] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CONNECTOR:166:HDMI-A-1]

<7> [106.984730] i915 0000:00:02.0: [drm:intel_hdmi_compute_output_format [i915]] picking 12 bpc for HDMI output (pipe bpp: 36)

<7> [106.985310] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:164:pipe C] hw max bpp: 36, pipe bpp: 36, dithering: 0

<7> [106.985745] i915 0000:00:02.0: [drm:intel_ddi_compute_config_late [i915]] [ENCODER:165:DDI B/PHY B] [CRTC:164:pipe C]

<7> [106.986183] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.enable (expected no, found yes)

<7> [106.986617] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.active (expected no, found yes)

<7> [106.987072] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in cpu_transcoder (expected -1, found 2)

<7> [106.987507] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in lane_count (expected 0, found 4)

<7> [106.987941] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in lane_lat_optim_mask (expected 0x00000000, found 0x0000000d)

<7> [106.988430] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in output_types (expected 0x00000000, found 0x00000040)

<7> [106.988860] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in framestart_delay (expected 0, found 1)

<7> [106.989489] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 1920)

<7> [106.989938] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 2200)

<7> [106.990476] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 1920)

<7> [106.990972] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 2200)

<7> [106.991435] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 2008)

<7> [106.991864] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2052)

<7> [106.992406] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1080)

<7> [106.992868] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1080)

<7> [106.993342] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1083)

<7> [106.993771] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1088)

<7> [106.994252] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1125)

<7> [106.994722] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1125)

<7> [106.995544] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 1920)

<7> [106.996019] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 2200)

<7> [106.996447] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 1920)

<7> [106.996943] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 2200)

<7> [106.997497] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 2008)

<7> [106.998020] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2052)

<7> [106.998453] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1080)

<7> [106.998882] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1080)

<7> [106.999355] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1083)

<7> [106.999785] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1088)

<7> [107.000269] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1125)

<7> [107.000721] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1125)

<7> [107.001194] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in pixel_multiplier (expected 0, found 1)

<7> [107.001623] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 0, found 1)

<7> [107.002100] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 0, found 8)

<7> [107.002555] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in has_hdmi_sink (expected no, found yes)

<7> [107.002988] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in has_infoframe (expected no, found yes)

<7> [107.003472] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in pipe_bpp (expected 0, found 36)

<7> [107.003902] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 148500)

<7> [107.004356] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 148500)

<7> [107.004785] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in port_clock (expected 0, found 222750)

<7> [107.005251] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in infoframes.enable (expected 0x00000000, found 0x00000071)

<7> [107.005682] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in infoframes.gcp (expected 0x00000000, found 0x00000006)

<7> [107.006190] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in avi infoframe

<7> [107.006623] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:

<7> [107.007169] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:

<7> [107.007658] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13

<7> [107.007664] i915 0000:00:02.0: colorspace: RGB

<7> [107.007668] i915 0000:00:02.0: scan mode: Underscan

<7> [107.007671] i915 0000:00:02.0: colorimetry: No Data

<7> [107.007675] i915 0000:00:02.0: picture aspect: No Data

<7> [107.007678] i915 0000:00:02.0: active aspect: Same as Picture

<7> [107.007682] i915 0000:00:02.0: itc: No Data

<7> [107.007685] i915 0000:00:02.0: extended colorimetry: xvYCC 601

<7> [107.007689] i915 0000:00:02.0: quantization range: Full

<7> [107.007692] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling

<7> [107.007695] i915 0000:00:02.0: video code: 0

<7> [107.007698] i915 0000:00:02.0: ycc quantization range: Full

<7> [107.007701] i915 0000:00:02.0: hdmi content type: Graphics

<7> [107.007705] i915 0000:00:02.0: pixel repeat: 0

<7> [107.007708] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0

<7> [107.007712] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in spd infoframe

<7> [107.008178] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:

<7> [107.008607] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:

<7> [107.009073] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25

<7> [107.009078] i915 0000:00:02.0: vendor: Intel

<7> [107.009081] i915 0000:00:02.0: product: Integrated gfx

<7> [107.009085] i915 0000:00:02.0: source device information: PC General (0x9)

<7> [107.009089] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hdmi infoframe

<7> [107.009537] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:

<7> [107.009964] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:

<7> [107.010540] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4

<7> [107.010545] i915 0000:00:02.0: empty frame

<7> [107.010550] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:164:pipe C] fastset requirement not met, forcing full modeset

<7> [107.011092] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:164:pipe C] dbuf slices 0x0 -> 0x1, ddb (0 - 0) -> (0 - 1020), active pipes 0x0 -> 0x4

<7> [107.011621] i915 0000:00:02.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:122:plane 1C] ddb ( 0 - 0) -> ( 0 - 988), size 0 -> 988

<7> [107.012091] i915 0000:00:02.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:158:cursor C] ddb ( 0 - 0) -> ( 988 - 1020), size 0 -> 32

<7> [107.012512] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:122:plane 1C] level wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm -> *wm0,*wm1,*wm2,*wm3,*wm4,*wm5,*wm6,*wm7,*twm, swm, stwm

<7> [107.012930] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:122:plane 1C] lines 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 1, 1, 1, 2, 2, 2, 2, 2, 0, 0, 0

<7> [107.013393] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:122:plane 1C] blocks 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 16, 16, 16, 33, 33, 33, 33, 33, 30, 0, 0

<7> [107.013812] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:122:plane 1C] min_ddb 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 17, 17, 17, 34, 34, 34, 34, 34, 31, 0, 0

<7> [107.014257] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:122:plane 1C] min_ddb_uv 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0

<7> [107.014722] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:164:pipe C] min cdclk: 0 kHz -> 192000 kHz

<7> [107.015289] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] Modeset required for cdclk change

<7> [107.015721] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New cdclk calculated to be logical 316800 kHz, actual 316800 kHz

<7> [107.016229] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New voltage level calculated to be logical 13, actual 13

<7> [107.016666] i915 0000:00:02.0: [drm:bxt_get_dpll [i915]] [CRTC:164:pipe C] using pre-allocated PORT PLL B

<7> [107.017247] i915 0000:00:02.0: [drm:intel_dpll_crtc_get [i915]] [CRTC:164:pipe C] reserving PORT PLL B

<7> [107.017746] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:164:pipe C] enable: yes [modeset]

<7> [107.018207] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: HDMI (0x40), output format: RGB, sink format: RGB

<7> [107.018638] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: C, pipe bpp: 36, dithering: 0

<7> [107.019106] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <invalid>

<7> [107.019534] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0

<7> [107.019962] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] joiner: no, pipes: 0x0

<7> [107.020452] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0

<7> [107.020902] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] audio: 1, infoframes: 1, infoframes enabled: 0x71

<7> [107.021374] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] GCP: 0x6

<7> [107.021801] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13

<7> [107.021808] i915 0000:00:02.0: colorspace: RGB

<7> [107.021812] i915 0000:00:02.0: scan mode: Underscan

<7> [107.021815] i915 0000:00:02.0: colorimetry: No Data

<7> [107.021819] i915 0000:00:02.0: picture aspect: No Data

<7> [107.021822] i915 0000:00:02.0: active aspect: Same as Picture

<7> [107.021825] i915 0000:00:02.0: itc: No Data

<7> [107.021828] i915 0000:00:02.0: extended colorimetry: xvYCC 601

<7> [107.021831] i915 0000:00:02.0: quantization range: Full

<7> [107.021834] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling

<7> [107.021838] i915 0000:00:02.0: video code: 0

<7> [107.021841] i915 0000:00:02.0: ycc quantization range: Full

<7> [107.021844] i915 0000:00:02.0: hdmi content type: Graphics

<7> [107.021847] i915 0000:00:02.0: pixel repeat: 0

<7> [107.021850] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0

<7> [107.021854] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25

<7> [107.021858] i915 0000:00:02.0: vendor: Intel

<7> [107.021861] i915 0000:00:02.0: product: Integrated gfx

<7> [107.021864] i915 0000:00:02.0: source device information: PC General (0x9)

<7> [107.021868] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4

<7> [107.021871] i915 0000:00:02.0: empty frame

<7> [107.021874] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ELD: 10 00 07 00 66 10 00 01 00 00 00 00 00 00 00 00

<7> [107.022345] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ELD: 05 e3 cd 0c 32 38 45 38 35 30 09 07 07 00 00 00

<7> [107.022798] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] scanline offset: 2

<7> [107.023261] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] framestart delay: 1, MSA timing delay: 0, set context latency: 0

<7> [107.023692] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: no, fixed rr: no, vmin: 0, vmax: 0, flipline: 0, pipeline full: 0, guardband: 0 vsync start: 0, vsync end: 0

<7> [107.024154] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: vmin vblank: 0, vmax vblank: 0, vmin vtotal: 0, vmax vtotal: 0

<7> [107.024581] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: dc balance: no, vmin: 0 vmax: 0 guardband: 0, slope: 0 max increase: 0 max decrease: 0 vblank target: 0

<7> [107.025030] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] requested mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1083 1088 1125 0x48 0x9

<7> [107.025513] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] adjusted mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1083 1088 1125 0x48 0x9

<7> [107.025945] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] crtc timings: clock=148500, hd=1920 hb=1920-2200 hs=2008-2052 ht=2200, vd=1080 vb=1080-1125 vs=1083-1088 vt=1125, flags=0x9

<7> [107.026502] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1083 1088 1125 0x40 0x9

<7> [107.027025] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] crtc timings: clock=148500, hd=1920 hb=1920-2200 hs=2008-2052 ht=2200, vd=1080 vb=1080-1125 vs=1083-1088 vt=1125, flags=0x9

<7> [107.027455] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port clock: 222750, pixel rate 148500, min cdclk 192000, min voltage level 0

<7> [107.027879] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] linetime: 59, ips linetime: 0

<7> [107.028339] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] num_scalers: 1, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0, sharpness_strength: 0

<7> [107.028787] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe src: 1920x1080+0+0

<7> [107.029273] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pch pfit: 0x0+0+0, disabled, force thru: no

<7> [107.029723] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0

<7> [107.030229] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0

<7> [107.030659] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] dpll_hw_state: ebb0: 0x6200, ebb4: 0x2000,pll0: 0x21, pll1: 0x100, pll2: 0x1a6666, pll3: 0x10000, pll6: 0x30904, pll8: 0x8, pll9: 0xa, pll10: 0x8003c00, pcsdw12: 0x4d

<7> [107.031111] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0

<7> [107.031567] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 0 entries

<7> [107.032020] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: pre offsets: 0x0000 0x0000 0x0000

<7> [107.032447] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000

<7> [107.032876] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000

<7> [107.033330] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000

<7> [107.033808] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: post offsets: 0x0000 0x0000 0x0000

<7> [107.034346] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:122:plane 1C] fb: [FB:179] 1920x1080 format = XR24 little-endian (0x34325258) modifier = 0x100000000000001, visible: yes

<7> [107.034785] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0

<7> [107.035239] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] src: 1920.000000x1080.000000+0.000000+0.000000 dst: 1920x1080+0+0

<7> [107.035670] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:131:plane 2C] fb: [NOFB], visible: no

<7> [107.036199] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:140:plane 3C] fb: [NOFB], visible: no

<7> [107.036629] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:149:plane 4C] fb: [NOFB], visible: no

<7> [107.037101] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:158:cursor C] fb: [NOFB], visible: no

<7> [107.038340] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling dpio-common-b

<7> [107.039117] i915 0000:00:02.0: [drm:intel_set_cdclk [i915]] Pre changing CDCLK to 316800 kHz, VCO 633600 kHz, ref 19200 kHz, bypass 19200 kHz, voltage level 13

<7> [107.039601] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:165:DDI B/PHY B]

<7> [107.040104] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:175:DDI C/PHY C]

<7> [107.040694] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enable PORT PLL B (active 0x4, on? 0) for [CRTC:164:pipe C]

<7> [107.041198] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enabling PORT PLL B

<7> [107.041839] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DDI_IO_B

<7> [107.042405] i915 0000:00:02.0: [drm:intel_enable_transcoder [i915]] enabling pipe C

<7> [107.060566] i915 0000:00:02.0: [drm:intel_audio_codec_enable [i915]] [CONNECTOR:166:HDMI-A-1][ENCODER:165:DDI B/PHY B] Enable audio codec on [CRTC:164:pipe C], 32 bytes ELD

<7> [107.077191] i915 0000:00:02.0: [drm:audio_config_hdmi_pixel_clock [i915]] Configuring HDMI audio for pixel clock 148500 (0x00090000)

<7> [107.077702] i915 0000:00:02.0: [drm:hsw_audio_config_update [i915]] using automatic N

<7> [107.078341] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:166:HDMI-A-1]

<7> [107.079600] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:164:pipe C]

<7> [107.087651] azx_init_pci: snd_hda_intel 0000:00:0e.0: Clearing TCSEL

<7> [107.087672] azx_init_pci: snd_hda_intel 0000:00:0e.0: SCH snoop: Enabled

<6> [108.281933] [IGT] kms_flip: finished subtest C-HDMI-A1, FAIL

Results for igt@kms_flip@flip-vs-expired-vblank-interruptible@c-hdmi-a1