Bug 103640 - [CI] igt@kms_frontbuffer_tracking@fbc-1p-primscrn-spr-indfb-draw-mmap-cpu - Failed assertion: rc == 0
Summary: [CI] igt@kms_frontbuffer_tracking@fbc-1p-primscrn-spr-indfb-draw-mmap-cpu - F...
Status: CLOSED FIXED
Alias: None
Product: DRI
Classification: Unclassified
Component: DRM/Intel (show other bugs)
Version: DRI git
Hardware: Other All
: medium normal
Assignee: Intel GFX Bugs mailing list
QA Contact: Intel GFX Bugs mailing list
URL:
Whiteboard: ReadyForDev
Keywords:
Depends on:
Blocks:
 
Reported: 2017-11-09 08:29 UTC by Marta Löfstedt
Modified: 2018-04-20 11:13 UTC (History)
1 user (show)

See Also:
i915 platform: CFL, KBL
i915 features: display/Other


Attachments
kernel log (10.91 MB, text/plain)
2018-01-16 22:22 UTC, Hector Velazquez
no flags Details

Description Marta Löfstedt 2017-11-09 08:29:27 UTC
CI_DRM_3321 KBL-shards 	igt@kms_frontbuffer_tracking@fbc-1p-primscrn-spr-indfb-draw-mmap-cpu 
fail:

(kms_frontbuffer_tracking:1485) CRITICAL: Test assertion failure function init_blue_crc, file kms_frontbuffer_tracking.c:1263:
(kms_frontbuffer_tracking:1485) CRITICAL: Failed assertion: rc == 0
(kms_frontbuffer_tracking:1485) CRITICAL: Last errno: 22, Invalid argument
(kms_frontbuffer_tracking:1485) CRITICAL: error: -22 != 0
Subtest fbc-1p-primscrn-spr-indfb-draw-mmap-cpu failed.

https://intel-gfx-ci.01.org/tree/drm-tip/CI_DRM_3321/shard-kbl4/igt@kms_frontbuffer_tracking@fbc-1p-primscrn-spr-indfb-draw-mmap-cpu.html

Note, I have never seen kms_frontbuffer_tracking failing like this before.
Comment 1 Marta Löfstedt 2017-12-04 08:17:02 UTC
https://intel-gfx-ci.01.org/tree/drm-tip/CI_DRM_3437/shard-kbl4/igt@kms_frontbuffer_tracking@fbc-1p-primscrn-pri-indfb-draw-mmap-cpu.html

(kms_frontbuffer_tracking:1752) CRITICAL: Test assertion failure function init_crcs, file kms_frontbuffer_tracking.c:1312:
(kms_frontbuffer_tracking:1752) CRITICAL: Failed assertion: rc == 0
(kms_frontbuffer_tracking:1752) CRITICAL: Last errno: 22, Invalid argument
(kms_frontbuffer_tracking:1752) CRITICAL: error: -22 != 0
Subtest fbc-1p-primscrn-pri-indfb-draw-mmap-cpu failed.
Comment 2 Chris Wilson 2017-12-08 19:09:21 UTC
SetCrtc failed:

<7>[  176.742928] [drm:drm_mode_setcrtc] [CRTC:37:pipe A]
<7>[  176.742952] [drm:drm_mode_setcrtc] [CONNECTOR:59:DP-1]
<7>[  176.743015] [drm:intel_atomic_check [i915]] [CONNECTOR:59:DP-1] checking for sink bpp constrains
<7>[  176.743066] [drm:intel_atomic_check [i915]] clamping display bpp (was 36) to default limit of 24
<7>[  176.743095] [drm:intel_dp_compute_config [i915]] DP link computation with max lane count 2 max bw 162000 pixel clock 154000KHz
<7>[  176.743125] [drm:intel_atomic_check [i915]] Encoder config failure
<7>[  176.743153] [drm:intel_dump_pipe_config [i915]] [CRTC:37:pipe A][failed]
<7>[  176.743180] [drm:intel_dump_pipe_config [i915]] output_types: DP (0x80)
<7>[  176.743205] [drm:intel_dump_pipe_config [i915]] cpu_transcoder: A, pipe bpp: 24, dithering: 0
<7>[  176.743229] [drm:intel_dump_pipe_config [i915]] dp m_n: lanes: 0; gmch_m: 0, gmch_n: 0, link_m: 0, link_n: 0, tu: 0
<7>[  176.743253] [drm:intel_dump_pipe_config [i915]] audio: 0, infoframes: 0
<7>[  176.743278] [drm:intel_dump_pipe_config [i915]] requested mode:
<7>[  176.743283] [drm:drm_mode_debug_printmodeline] Modeline 0:"1920x1200" 60 154000 1920 1968 2000 2080 1200 1203 1209 1235 0x48 0x9
<7>[  176.743305] [drm:intel_dump_pipe_config [i915]] adjusted mode:
<7>[  176.743309] [drm:drm_mode_debug_printmodeline] Modeline 0:"1920x1200" 60 154000 1920 1968 2000 2080 1200 1203 1209 1235 0x48 0x9
<7>[  176.743332] [drm:intel_dump_pipe_config [i915]] crtc timings: 154000 1920 1968 2000 2080 1200 1203 1209 1235, type: 0x48 flags: 0x9
<7>[  176.743356] [drm:intel_dump_pipe_config [i915]] port clock: 0, pipe src size: 1920x1200, pixel rate 0
<7>[  176.743380] [drm:intel_dump_pipe_config [i915]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1
<7>[  176.743404] [drm:intel_dump_pipe_config [i915]] pch pfit: pos: 0x00000000, size: 0x00000000, disabled
<7>[  176.743428] [drm:intel_dump_pipe_config [i915]] ips: 0, double wide: 0
<7>[  176.743452] [drm:intel_dump_pipe_config [i915]] dpll_hw_state: ctrl1: 0x3, cfgcr1: 0x0, cfgcr2: 0x0
<7>[  176.743474] [drm:intel_dump_pipe_config [i915]] planes on this crtc
<7>[  176.743498] [drm:intel_dump_pipe_config [i915]] [PLANE:28:plane 1A] disabled, scaler_id = -1
<7>[  176.743521] [drm:intel_dump_pipe_config [i915]] [PLANE:31:plane 2A] disabled, scaler_id = -1
<7>[  176.743543] [drm:intel_dump_pipe_config [i915]] [PLANE:34:cursor A] disabled, scaler_id = -1
<7>[  176.746093] [IGT] kms_frontbuffer_tracking: exiting, ret=99

It looks like it is in the middle of a hotplug storm.
Comment 3 Hector Velazquez 2018-01-16 22:22:08 UTC
This test has failed on CFL QA 

Tests List:

igt@kms_frontbuffer_tracking@fbcpsr-2p-shrfb-fliptrack
igt@kms_frontbuffer_tracking@fbcpsr-2p-indfb-fliptrack
igt@kms_frontbuffer_tracking@fbcpsr-1p-shrfb-fliptrack
igt@kms_frontbuffer_tracking@fbcpsr-1p-indfb-fliptrack

using IGT-Version: 1.20-g84a3080 (x86_64) (Linux: 4.15.0-rc8-drm-tip-ww3-commit-37eb04c+ x86_64)

some tests has this output...
======================================
        output sample
======================================
. . .
**** DEBUG ****
(kms_frontbuffer_tracking:2566) DEBUG: Test requirement passed: fbc.can_test
(kms_frontbuffer_tracking:2566) DEBUG: Test requirement passed: psr.can_test
(kms_frontbuffer_tracking:2566) DEBUG: Test requirement passed: sink_crc.supported
(kms_frontbuffer_tracking:2566) drmtest-DEBUG: Test requirement passed: is_i915_device(fd) && has_known_intel_chipset(fd)
(kms_frontbuffer_tracking:2566) igt-fb-DEBUG: igt_create_fb_with_bo_size(width=1920, height=1080, format=0x34325258, tiling=0x100000000000001, size=8294400)
(kms_frontbuffer_tracking:2566) drmtest-DEBUG: Test requirement passed: is_i915_device(fd) && has_known_intel_chipset(fd)
(kms_frontbuffer_tracking:2566) igt-fb-DEBUG: igt_create_fb_with_bo_size(handle=1, pitch=7680)
(kms_frontbuffer_tracking:2566) igt-fb-DEBUG: igt_create_fb_with_bo_size(width=64, height=64, format=0x34325241, tiling=0x0, size=16384)
(kms_frontbuffer_tracking:2566) igt-fb-DEBUG: igt_create_fb_with_bo_size(handle=2, pitch=256)
(kms_frontbuffer_tracking:2566) drmtest-DEBUG: Test requirement passed: is_i915_device(fd) && has_known_intel_chipset(fd)
(kms_frontbuffer_tracking:2566) igt-fb-DEBUG: igt_create_fb_with_bo_size(width=64, height=64, format=0x34325258, tiling=0x100000000000001, size=32768)
(kms_frontbuffer_tracking:2566) drmtest-DEBUG: Test requirement passed: is_i915_device(fd) && has_known_intel_chipset(fd)
(kms_frontbuffer_tracking:2566) igt-fb-DEBUG: igt_create_fb_with_bo_size(handle=3, pitch=512)
(kms_frontbuffer_tracking:2566) drmtest-DEBUG: Test requirement passed: is_i915_device(fd) && has_known_intel_chipset(fd)
(kms_frontbuffer_tracking:2566) igt-fb-DEBUG: igt_create_fb_with_bo_size(width=1024, height=1024, format=0x34325258, tiling=0x100000000000001, size=4194304)
(kms_frontbuffer_tracking:2566) drmtest-DEBUG: Test requirement passed: is_i915_device(fd) && has_known_intel_chipset(fd)
(kms_frontbuffer_tracking:2566) igt-fb-DEBUG: igt_create_fb_with_bo_size(handle=4, pitch=4096)
(kms_frontbuffer_tracking:2566) drmtest-DEBUG: Test requirement passed: is_i915_device(fd) && has_known_intel_chipset(fd)
(kms_frontbuffer_tracking:2566) igt-fb-DEBUG: igt_create_fb_with_bo_size(width=2420, height=3804, format=0x34325258, tiling=0x100000000000001, size=37044224)
(kms_frontbuffer_tracking:2566) drmtest-DEBUG: Test requirement passed: is_i915_device(fd) && has_known_intel_chipset(fd)
(kms_frontbuffer_tracking:2566) igt-fb-DEBUG: igt_create_fb_with_bo_size(handle=5, pitch=9728)
(kms_frontbuffer_tracking:2566) drmtest-DEBUG: Test requirement passed: is_i915_device(fd) && has_known_intel_chipset(fd)
(kms_frontbuffer_tracking:2566) igt-fb-DEBUG: igt_create_fb_with_bo_size(width=1920, height=1200, format=0x34325258, tiling=0x100000000000001, size=9216000)
(kms_frontbuffer_tracking:2566) drmtest-DEBUG: Test requirement passed: is_i915_device(fd) && has_known_intel_chipset(fd)
(kms_frontbuffer_tracking:2566) igt-fb-DEBUG: igt_create_fb_with_bo_size(handle=6, pitch=7680)
(kms_frontbuffer_tracking:2566) igt-fb-DEBUG: igt_create_fb_with_bo_size(width=64, height=64, format=0x34325241, tiling=0x0, size=16384)
(kms_frontbuffer_tracking:2566) igt-fb-DEBUG: igt_create_fb_with_bo_size(handle=7, pitch=256)
(kms_frontbuffer_tracking:2566) drmtest-DEBUG: Test requirement passed: is_i915_device(fd) && has_known_intel_chipset(fd)
(kms_frontbuffer_tracking:2566) igt-fb-DEBUG: igt_create_fb_with_bo_size(width=64, height=64, format=0x34325258, tiling=0x100000000000001, size=32768)
(kms_frontbuffer_tracking:2566) drmtest-DEBUG: Test requirement passed: is_i915_device(fd) && has_known_intel_chipset(fd)
(kms_frontbuffer_tracking:2566) igt-fb-DEBUG: igt_create_fb_with_bo_size(handle=8, pitch=512)
(kms_frontbuffer_tracking:2566) drmtest-DEBUG: Test requirement passed: is_i915_device(fd) && has_known_intel_chipset(fd)
(kms_frontbuffer_tracking:2566) igt-fb-DEBUG: igt_create_fb_with_bo_size(width=1920, height=1080, format=0x34325258, tiling=0x100000000000001, size=8294400)
(kms_frontbuffer_tracking:2566) drmtest-DEBUG: Test requirement passed: is_i915_device(fd) && has_known_intel_chipset(fd)
(kms_frontbuffer_tracking:2566) igt-fb-DEBUG: igt_create_fb_with_bo_size(handle=11, pitch=7680)
(kms_frontbuffer_tracking:2566) DEBUG: Blue CRC:   pipe:[941b2438 00000000 00000000 00000000 00000000] sink:[00000000c101]
(kms_frontbuffer_tracking:2566) igt-draw-DEBUG: Test requirement passed: gem_get_tiling(fd, buf->handle, &tiling, &swizzle)
(kms_frontbuffer_tracking:2566) DEBUG: checking asserts in line 1780
(kms_frontbuffer_tracking:2566) DEBUG: Calculated CRC: pipe:[941b2438 00000000 00000000 00000000 00000000] sink:[00000000c101]
(kms_frontbuffer_tracking:2566) DEBUG: Test requirement passed: !fbc_not_enough_stolen()
(kms_frontbuffer_tracking:2566) DEBUG: Test requirement passed: !fbc_stride_not_supported()
(kms_frontbuffer_tracking:2566) DEBUG: Calculated CRC: pipe:[941b2438 00000000 00000000 00000000 00000000] sink:[00000000c101]
(kms_frontbuffer_tracking:2566) igt-core-DEBUG: Test requirement passed: !igt_run_in_simulation()
(kms_frontbuffer_tracking:2566) igt-aux-DEBUG: Test requirement passed: (power_dir = open("/sys/power", O_RDONLY)) >= 0
(kms_frontbuffer_tracking:2566) igt-aux-DEBUG: Test requirement passed: get_supported_suspend_states(power_dir) & (1 << state)
(kms_frontbuffer_tracking:2566) igt-aux-DEBUG: Test requirement passed: test == SUSPEND_TEST_NONE || faccessat(power_dir, "pm_test", R_OK | W_OK, 0) == 0
(kms_frontbuffer_tracking:2566) igt-aux-DEBUG: Test requirement passed: ret == 0
(kms_frontbuffer_tracking:2566) igt-core-INFO: [cmd] rtcwake: assuming RTC uses UTC ...
rtcwake: wakeup from "mem" using /dev/rtc0 at Mon Jan  5 18:57:52 1998
(kms_frontbuffer_tracking:2566) igt-core-WARNING: [cmd] rtcwake: [cmd] write error
(kms_frontbuffer_tracking:2566) igt-aux-WARNING: rtcwake failed with 1
Check dmesg for further details.
(kms_frontbuffer_tracking:2566) igt-aux-DEBUG: suspend_stats:
success: 2
fail: 1
failed_freeze: 0
failed_prepare: 0
failed_suspend: 1
failed_suspend_late: 0
failed_suspend_noirq: 0
failed_resume: 0
failed_resume_early: 0
failed_resume_noirq: 0
failures:
  last_failed_dev:	
			
  last_failed_errno:	-16
			0
  last_failed_step:	suspend
			
(kms_frontbuffer_tracking:2566) igt-aux-CRITICAL: Test assertion failure function suspend_via_rtcwake, file igt_aux.c:821:
(kms_frontbuffer_tracking:2566) igt-aux-CRITICAL: Failed assertion: ret == 0
(kms_frontbuffer_tracking:2566) igt-aux-CRITICAL: error: 1 != 0
(kms_frontbuffer_tracking:2566) igt-core-INFO: Stack trace:
(kms_frontbuffer_tracking:2566) igt-core-INFO:   #0 [__igt_fail_assert+0x101]
(kms_frontbuffer_tracking:2566) igt-core-INFO:   #1 [igt_system_suspend_autoresume+0x544]
(kms_frontbuffer_tracking:2566) igt-core-INFO:   #2 [<unknown>+0x544]
(kms_frontbuffer_tracking:2566) igt-core-INFO:   #3 [<unknown>+0x544]
****  END  ****
. . .

The tests has this dmesg fail:

======================================
        dmesg-fail sample
======================================
. . .
[ 9136.359801] [drm:drm_atomic_helper_wait_for_dependencies [drm_kms_helper]] *ERROR* [CRTC:37:pipe A] flip_done timed out
[ 9146.599819] [drm:drm_atomic_helper_wait_for_dependencies [drm_kms_helper]] *ERROR* [CONNECTOR:59:eDP-1] flip_done timed out
[ 9156.839822] [drm:drm_atomic_helper_wait_for_dependencies [drm_kms_helper]] *ERROR* [PLANE:28:plane 1A] flip_done timed out
[ 9167.079863] [drm:drm_atomic_helper_wait_for_flip_done [drm_kms_helper]] *ERROR* [CRTC:37:pipe A] flip_done timed out
[ 9167.282372] Setting dangerous option enable_fbc - tainting kernel
[ 9167.282441] Setting dangerous option enable_psr - tainting kernel
[ 9177.319791] [drm:drm_atomic_helper_wait_for_dependencies [drm_kms_helper]] *ERROR* [CRTC:37:pipe A] flip_done timed out
[ 9187.559827] [drm:drm_atomic_helper_wait_for_dependencies [drm_kms_helper]] *ERROR* [PLANE:28:plane 1A] flip_done timed out
[ 9197.799828] [drm:drm_atomic_helper_wait_for_flip_done [drm_kms_helper]] *ERROR* [CRTC:37:pipe A] flip_done timed out
[ 9208.039811] [drm:drm_atomic_helper_wait_for_dependencies [drm_kms_helper]] *ERROR* [CRTC:37:pipe A] flip_done timed out
[ 9218.279818] [drm:drm_atomic_helper_wait_for_dependencies [drm_kms_helper]] *ERROR* [CONNECTOR:59:eDP-1] flip_done timed out
[ 9228.519829] [drm:drm_atomic_helper_wait_for_dependencies [drm_kms_helper]] *ERROR* [PLANE:28:plane 1A] flip_done timed out
[ 9239.783867] [drm:drm_atomic_helper_wait_for_flip_done [drm_kms_helper]] *ERROR* [CRTC:37:pipe A] flip_done timed out
[ 9250.023816] [drm:drm_atomic_helper_wait_for_dependencies [drm_kms_helper]] *ERROR* [CRTC:37:pipe A] flip_done timed out
[ 9260.263827] [drm:drm_atomic_helper_wait_for_dependencies [drm_kms_helper]] *ERROR* [CONNECTOR:59:eDP-1] flip_done timed out
[ 9270.503832] [drm:drm_atomic_helper_wait_for_dependencies [drm_kms_helper]] *ERROR* [PLANE:28:plane 1A] flip_done timed out
[ 9270.771763] Setting dangerous option enable_fbc - tainting kernel
[ 9270.771894] Setting dangerous option enable_psr - tainting kernel
[ 9281.767828] [drm:drm_atomic_helper_wait_for_flip_done [drm_kms_helper]] *ERROR* [CRTC:37:pipe A] flip_done timed out
[ 9287.311344] Suspending console(s) (use no_console_suspend to debug)
[ 9287.651775] sd 4:0:0:0: [sda] Synchronizing SCSI cache
[ 9287.651944] sd 4:0:0:0: [sda] Stopping disk
[ 9297.895821] [drm:drm_atomic_helper_wait_for_dependencies [drm_kms_helper]] *ERROR* [CRTC:37:pipe A] flip_done timed out
[ 9308.135914] [drm:drm_atomic_helper_wait_for_dependencies [drm_kms_helper]] *ERROR* [CONNECTOR:59:eDP-1] flip_done timed out
[ 9318.375809] [drm:drm_atomic_helper_wait_for_dependencies [drm_kms_helper]] *ERROR* [PLANE:28:plane 1A] flip_done timed out
[ 9318.637293] PM: Some devices failed to suspend, or early wake event detected
[ 9318.645910] sd 4:0:0:0: [sda] Starting disk
[ 9318.879799] atkbd serio0: Failed to deactivate keyboard on isa0060/serio0
[ 9319.255799] atkbd serio0: Failed to enable keyboard on isa0060/serio0
[ 9324.055796] ata5: link is slow to respond, please be patient (ready=0)
[ 9328.675808] ata5: COMRESET failed (errno=-16)
[ 9329.639754] [drm:drm_atomic_helper_wait_for_flip_done [drm_kms_helper]] *ERROR* [CRTC:37:pipe A] flip_done timed out
[ 9339.879847] [drm:drm_atomic_helper_wait_for_dependencies [drm_kms_helper]] *ERROR* [CRTC:37:pipe A] flip_done timed out
[ 9350.119828] [drm:drm_atomic_helper_wait_for_dependencies [drm_kms_helper]] *ERROR* [PLANE:28:plane 1A] flip_done timed out
[ 9360.359815] [drm:drm_atomic_helper_wait_for_flip_done [drm_kms_helper]] *ERROR* [CRTC:37:pipe A] flip_done timed out
[ 9360.360452] Setting dangerous option enable_psr - tainting kernel
[ 9360.360501] Setting dangerous option enable_fbc - tainting kernel
[ 9370.599832] [drm:drm_atomic_helper_wait_for_dependencies [drm_kms_helper]] *ERROR* [CRTC:37:pipe A] flip_done timed out
[ 9380.839823] [drm:drm_atomic_helper_wait_for_dependencies [drm_kms_helper]] *ERROR* [CONNECTOR:59:eDP-1] flip_done timed out
[ 9391.079848] [drm:drm_atomic_helper_wait_for_dependencies [drm_kms_helper]] *ERROR* [PLANE:28:plane 1A] flip_done timed out
[ 9401.319864] [drm:drm_atomic_helper_wait_for_flip_done [drm_kms_helper]] *ERROR* [CRTC:37:pipe A] flip_done timed out
. . .
Comment 4 Hector Velazquez 2018-01-16 22:22:28 UTC
Created attachment 136794 [details]
kernel log
Comment 5 Hector Velazquez 2018-01-16 22:26:39 UTC
I have an error on the content of the comment 3, please ignore...
this is the correct one..., sorry about this mistake...

This test has failed on CFL QA 

Tests List:

igt@kms_frontbuffer_tracking@fbcpsr-2p-shrfb-fliptrack
igt@kms_frontbuffer_tracking@fbcpsr-2p-indfb-fliptrack
igt@kms_frontbuffer_tracking@fbcpsr-1p-shrfb-fliptrack
igt@kms_frontbuffer_tracking@fbcpsr-1p-indfb-fliptrack

using IGT-Version: 1.20-g84a3080 (x86_64) (Linux: 4.15.0-rc8-drm-tip-ww3-commit-37eb04c+ x86_64)

some tests has this output...
======================================
        output sample
======================================
. . .
**** DEBUG ****
(kms_frontbuffer_tracking:2344) DEBUG: Test requirement passed: fbc.can_test
(kms_frontbuffer_tracking:2344) DEBUG: Test requirement passed: psr.can_test
(kms_frontbuffer_tracking:2344) DEBUG: Test requirement passed: sink_crc.supported
(kms_frontbuffer_tracking:2344) drmtest-DEBUG: Test requirement passed: is_i915_device(fd) && has_known_intel_chipset(fd)
(kms_frontbuffer_tracking:2344) igt-fb-DEBUG: igt_create_fb_with_bo_size(width=1920, height=1080, format=0x34325258, tiling=0x100000000000001, size=8294400)
(kms_frontbuffer_tracking:2344) drmtest-DEBUG: Test requirement passed: is_i915_device(fd) && has_known_intel_chipset(fd)
(kms_frontbuffer_tracking:2344) igt-fb-DEBUG: igt_create_fb_with_bo_size(handle=1, pitch=7680)
(kms_frontbuffer_tracking:2344) igt-fb-DEBUG: igt_create_fb_with_bo_size(width=64, height=64, format=0x34325241, tiling=0x0, size=16384)
(kms_frontbuffer_tracking:2344) igt-fb-DEBUG: igt_create_fb_with_bo_size(handle=2, pitch=256)
(kms_frontbuffer_tracking:2344) drmtest-DEBUG: Test requirement passed: is_i915_device(fd) && has_known_intel_chipset(fd)
(kms_frontbuffer_tracking:2344) igt-fb-DEBUG: igt_create_fb_with_bo_size(width=64, height=64, format=0x34325258, tiling=0x100000000000001, size=32768)
(kms_frontbuffer_tracking:2344) drmtest-DEBUG: Test requirement passed: is_i915_device(fd) && has_known_intel_chipset(fd)
(kms_frontbuffer_tracking:2344) igt-fb-DEBUG: igt_create_fb_with_bo_size(handle=3, pitch=512)
(kms_frontbuffer_tracking:2344) drmtest-DEBUG: Test requirement passed: is_i915_device(fd) && has_known_intel_chipset(fd)
(kms_frontbuffer_tracking:2344) igt-fb-DEBUG: igt_create_fb_with_bo_size(width=1024, height=1024, format=0x34325258, tiling=0x100000000000001, size=4194304)
(kms_frontbuffer_tracking:2344) drmtest-DEBUG: Test requirement passed: is_i915_device(fd) && has_known_intel_chipset(fd)
(kms_frontbuffer_tracking:2344) igt-fb-DEBUG: igt_create_fb_with_bo_size(handle=4, pitch=4096)
(kms_frontbuffer_tracking:2344) drmtest-DEBUG: Test requirement passed: is_i915_device(fd) && has_known_intel_chipset(fd)
(kms_frontbuffer_tracking:2344) igt-fb-DEBUG: igt_create_fb_with_bo_size(width=2420, height=3804, format=0x34325258, tiling=0x100000000000001, size=37044224)
(kms_frontbuffer_tracking:2344) drmtest-DEBUG: Test requirement passed: is_i915_device(fd) && has_known_intel_chipset(fd)
(kms_frontbuffer_tracking:2344) igt-fb-DEBUG: igt_create_fb_with_bo_size(handle=5, pitch=9728)
(kms_frontbuffer_tracking:2344) drmtest-DEBUG: Test requirement passed: is_i915_device(fd) && has_known_intel_chipset(fd)
(kms_frontbuffer_tracking:2344) igt-fb-DEBUG: igt_create_fb_with_bo_size(width=1920, height=1200, format=0x34325258, tiling=0x100000000000001, size=9216000)
(kms_frontbuffer_tracking:2344) drmtest-DEBUG: Test requirement passed: is_i915_device(fd) && has_known_intel_chipset(fd)
(kms_frontbuffer_tracking:2344) igt-fb-DEBUG: igt_create_fb_with_bo_size(handle=6, pitch=7680)
(kms_frontbuffer_tracking:2344) igt-fb-DEBUG: igt_create_fb_with_bo_size(width=64, height=64, format=0x34325241, tiling=0x0, size=16384)
(kms_frontbuffer_tracking:2344) igt-fb-DEBUG: igt_create_fb_with_bo_size(handle=7, pitch=256)
(kms_frontbuffer_tracking:2344) drmtest-DEBUG: Test requirement passed: is_i915_device(fd) && has_known_intel_chipset(fd)
(kms_frontbuffer_tracking:2344) igt-fb-DEBUG: igt_create_fb_with_bo_size(width=64, height=64, format=0x34325258, tiling=0x100000000000001, size=32768)
(kms_frontbuffer_tracking:2344) drmtest-DEBUG: Test requirement passed: is_i915_device(fd) && has_known_intel_chipset(fd)
(kms_frontbuffer_tracking:2344) igt-fb-DEBUG: igt_create_fb_with_bo_size(handle=8, pitch=512)
(kms_frontbuffer_tracking:2344) drmtest-DEBUG: Test requirement passed: is_i915_device(fd) && has_known_intel_chipset(fd)
(kms_frontbuffer_tracking:2344) igt-fb-DEBUG: igt_create_fb_with_bo_size(width=1920, height=1080, format=0x34325258, tiling=0x100000000000001, size=8294400)
(kms_frontbuffer_tracking:2344) drmtest-DEBUG: Test requirement passed: is_i915_device(fd) && has_known_intel_chipset(fd)
(kms_frontbuffer_tracking:2344) igt-fb-DEBUG: igt_create_fb_with_bo_size(handle=11, pitch=7680)
(kms_frontbuffer_tracking:2344) DEBUG: Blue CRC:   pipe:[941b2438 00000000 00000000 00000000 00000000] sink:[00000000c101]
(kms_frontbuffer_tracking:2344) drmtest-DEBUG: Test requirement passed: is_i915_device(fd) && has_known_intel_chipset(fd)
(kms_frontbuffer_tracking:2344) igt-fb-DEBUG: igt_create_fb_with_bo_size(width=1920, height=1080, format=0x34325258, tiling=0x100000000000001, size=8294400)
(kms_frontbuffer_tracking:2344) drmtest-DEBUG: Test requirement passed: is_i915_device(fd) && has_known_intel_chipset(fd)
(kms_frontbuffer_tracking:2344) igt-fb-DEBUG: igt_create_fb_with_bo_size(handle=11, pitch=7680)
(kms_frontbuffer_tracking:2344) drmtest-DEBUG: Test requirement passed: is_i915_device(fd) && has_known_intel_chipset(fd)
(kms_frontbuffer_tracking:2344) igt-fb-DEBUG: igt_create_fb_with_bo_size(width=1920, height=1080, format=0x34325258, tiling=0x100000000000001, size=8294400)
(kms_frontbuffer_tracking:2344) drmtest-DEBUG: Test requirement passed: is_i915_device(fd) && has_known_intel_chipset(fd)
(kms_frontbuffer_tracking:2344) igt-fb-DEBUG: igt_create_fb_with_bo_size(handle=12, pitch=7680)
(kms_frontbuffer_tracking:2344) drmtest-DEBUG: Test requirement passed: is_i915_device(fd) && has_known_intel_chipset(fd)
(kms_frontbuffer_tracking:2344) igt-fb-DEBUG: igt_create_fb_with_bo_size(width=1920, height=1080, format=0x34325258, tiling=0x100000000000001, size=8294400)
(kms_frontbuffer_tracking:2344) drmtest-DEBUG: Test requirement passed: is_i915_device(fd) && has_known_intel_chipset(fd)
(kms_frontbuffer_tracking:2344) igt-fb-DEBUG: igt_create_fb_with_bo_size(handle=13, pitch=7680)
(kms_frontbuffer_tracking:2344) drmtest-DEBUG: Test requirement passed: is_i915_device(fd) && has_known_intel_chipset(fd)
(kms_frontbuffer_tracking:2344) igt-fb-DEBUG: igt_create_fb_with_bo_size(width=1920, height=1080, format=0x34325258, tiling=0x100000000000001, size=8294400)
(kms_frontbuffer_tracking:2344) drmtest-DEBUG: Test requirement passed: is_i915_device(fd) && has_known_intel_chipset(fd)
(kms_frontbuffer_tracking:2344) igt-fb-DEBUG: igt_create_fb_with_bo_size(handle=14, pitch=7680)
(kms_frontbuffer_tracking:2344) igt-draw-DEBUG: Test requirement passed: gem_get_tiling(fd, buf->handle, &tiling, &swizzle)
(kms_frontbuffer_tracking:2344) igt-draw-DEBUG: Test requirement passed: intel_gen(intel_get_drm_devid(fd)) >= 5
(kms_frontbuffer_tracking:2344) igt-draw-DEBUG: Test requirement passed: gem_get_tiling(fd, buf->handle, &tiling, &swizzle)
(kms_frontbuffer_tracking:2344) igt-draw-DEBUG: Test requirement passed: intel_gen(intel_get_drm_devid(fd)) >= 5
(kms_frontbuffer_tracking:2344) igt-draw-DEBUG: Test requirement passed: gem_get_tiling(fd, buf->handle, &tiling, &swizzle)
(kms_frontbuffer_tracking:2344) igt-draw-DEBUG: Test requirement passed: intel_gen(intel_get_drm_devid(fd)) >= 5
(kms_frontbuffer_tracking:2344) igt-draw-DEBUG: Test requirement passed: gem_get_tiling(fd, buf->handle, &tiling, &swizzle)
(kms_frontbuffer_tracking:2344) igt-draw-DEBUG: Test requirement passed: intel_gen(intel_get_drm_devid(fd)) >= 5
(kms_frontbuffer_tracking:2344) igt-draw-DEBUG: Test requirement passed: gem_get_tiling(fd, buf->handle, &tiling, &swizzle)
(kms_frontbuffer_tracking:2344) igt-draw-DEBUG: Test requirement passed: intel_gen(intel_get_drm_devid(fd)) >= 5
(kms_frontbuffer_tracking:2344) igt-draw-DEBUG: Test requirement passed: gem_get_tiling(fd, buf->handle, &tiling, &swizzle)
(kms_frontbuffer_tracking:2344) igt-draw-DEBUG: Test requirement passed: intel_gen(intel_get_drm_devid(fd)) >= 5
(kms_frontbuffer_tracking:2344) igt-draw-DEBUG: Test requirement passed: gem_get_tiling(fd, buf->handle, &tiling, &swizzle)
(kms_frontbuffer_tracking:2344) igt-draw-DEBUG: Test requirement passed: intel_gen(intel_get_drm_devid(fd)) >= 5
(kms_frontbuffer_tracking:2344) igt-draw-DEBUG: Test requirement passed: gem_get_tiling(fd, buf->handle, &tiling, &swizzle)
(kms_frontbuffer_tracking:2344) igt-draw-DEBUG: Test requirement passed: intel_gen(intel_get_drm_devid(fd)) >= 5
(kms_frontbuffer_tracking:2344) igt-draw-DEBUG: Test requirement passed: gem_get_tiling(fd, buf->handle, &tiling, &swizzle)
(kms_frontbuffer_tracking:2344) igt-draw-DEBUG: Test requirement passed: intel_gen(intel_get_drm_devid(fd)) >= 5
(kms_frontbuffer_tracking:2344) igt-draw-DEBUG: Test requirement passed: gem_get_tiling(fd, buf->handle, &tiling, &swizzle)
(kms_frontbuffer_tracking:2344) igt-draw-DEBUG: Test requirement passed: intel_gen(intel_get_drm_devid(fd)) >= 5
(kms_frontbuffer_tracking:2344) DEBUG: Rect 0 CRC: pipe:[d15675fd 00000000 00000000 00000000 00000000] sink:[0000982a592b]
(kms_frontbuffer_tracking:2344) DEBUG: Rect 1 CRC: pipe:[c52e6ea4 00000000 00000000 00000000 00000000] sink:[216c982a7847]
(kms_frontbuffer_tracking:2344) DEBUG: Rect 2 CRC: pipe:[9804f15b 00000000 00000000 00000000 00000000] sink:[7a79424da220]
(kms_frontbuffer_tracking:2344) DEBUG: Rect 3 CRC: pipe:[6403325c 00000000 00000000 00000000 00000000] sink:[7a794dcfa220]
(kms_frontbuffer_tracking:2344) igt-draw-DEBUG: Test requirement passed: gem_get_tiling(fd, buf->handle, &tiling, &swizzle)
(kms_frontbuffer_tracking:2344) DEBUG: checking asserts in line 1780
(kms_frontbuffer_tracking:2344) DEBUG: Calculated CRC: pipe:[941b2438 00000000 00000000 00000000 00000000] sink:[00000000c101]
(kms_frontbuffer_tracking:2344) DEBUG: Test requirement passed: !fbc_not_enough_stolen()
(kms_frontbuffer_tracking:2344) DEBUG: Test requirement passed: !fbc_stride_not_supported()
(kms_frontbuffer_tracking:2344) DEBUG: Calculated CRC: pipe:[941b2438 00000000 00000000 00000000 00000000] sink:[00000000c101]
(kms_frontbuffer_tracking:2344) drmtest-DEBUG: Test requirement passed: is_i915_device(fd) && has_known_intel_chipset(fd)
(kms_frontbuffer_tracking:2344) igt-fb-DEBUG: igt_create_fb_with_bo_size(width=2420, height=3804, format=0x34325258, tiling=0x100000000000001, size=37044224)
(kms_frontbuffer_tracking:2344) drmtest-DEBUG: Test requirement passed: is_i915_device(fd) && has_known_intel_chipset(fd)
(kms_frontbuffer_tracking:2344) igt-fb-DEBUG: igt_create_fb_with_bo_size(handle=12, pitch=9728)
(kms_frontbuffer_tracking:2344) CRITICAL: Test assertion failure function page_flip_for_params, file kms_frontbuffer_tracking.c:2346:
(kms_frontbuffer_tracking:2344) CRITICAL: Failed assertion: rc == 0
(kms_frontbuffer_tracking:2344) CRITICAL: Last errno: 16, Device or resource busy
(kms_frontbuffer_tracking:2344) CRITICAL: error: -16 != 0
(kms_frontbuffer_tracking:2344) igt-core-INFO: Stack trace:
(kms_frontbuffer_tracking:2344) igt-core-INFO:   #0 [__igt_fail_assert+0x101]
(kms_frontbuffer_tracking:2344) igt-core-INFO:   #1 [page_flip_for_params+0x1d1]
(kms_frontbuffer_tracking:2344) igt-core-INFO:   #2 [main+0x1176]
(kms_frontbuffer_tracking:2344) igt-core-INFO:   #3 [__libc_start_main+0xf1]
(kms_frontbuffer_tracking:2344) igt-core-INFO:   #4 [_start+0x2a]
(kms_frontbuffer_tracking:2344) igt-core-INFO:   #5 [<unknown>+0x2a]
****  END  ****
. . .

The tests has this dmesg fail:

======================================
        dmesg-fail sample
======================================
. . .
[ 3815.655803] [drm:drm_atomic_helper_wait_for_dependencies [drm_kms_helper]] *ERROR* [CRTC:37:pipe A] flip_done timed out
[ 3825.895802] [drm:drm_atomic_helper_wait_for_dependencies [drm_kms_helper]] *ERROR* [CONNECTOR:59:eDP-1] flip_done timed out
[ 3836.135803] [drm:drm_atomic_helper_wait_for_dependencies [drm_kms_helper]] *ERROR* [PLANE:28:plane 1A] flip_done timed out
[ 3846.375932] [drm:drm_atomic_helper_wait_for_flip_done [drm_kms_helper]] *ERROR* [CRTC:37:pipe A] flip_done timed out
[ 3846.565280] Setting dangerous option enable_fbc - tainting kernel
[ 3846.565352] Setting dangerous option enable_psr - tainting kernel
[ 3856.615738] [drm:drm_atomic_helper_wait_for_dependencies [drm_kms_helper]] *ERROR* [CRTC:37:pipe A] flip_done timed out
[ 3866.855724] [drm:drm_atomic_helper_wait_for_dependencies [drm_kms_helper]] *ERROR* [PLANE:28:plane 1A] flip_done timed out
[ 3877.095792] [drm:drm_atomic_helper_wait_for_flip_done [drm_kms_helper]] *ERROR* [CRTC:37:pipe A] flip_done timed out
[ 3887.335790] [drm:drm_atomic_helper_wait_for_dependencies [drm_kms_helper]] *ERROR* [CRTC:37:pipe A] flip_done timed out
[ 3897.575813] [drm:drm_atomic_helper_wait_for_dependencies [drm_kms_helper]] *ERROR* [CONNECTOR:59:eDP-1] flip_done timed out
[ 3907.815805] [drm:drm_atomic_helper_wait_for_dependencies [drm_kms_helper]] *ERROR* [PLANE:28:plane 1A] flip_done timed out
[ 3919.079802] [drm:drm_atomic_helper_wait_for_flip_done [drm_kms_helper]] *ERROR* [CRTC:37:pipe A] flip_done timed out
[ 3929.319817] [drm:drm_atomic_helper_wait_for_dependencies [drm_kms_helper]] *ERROR* [CRTC:37:pipe A] flip_done timed out
[ 3939.559805] [drm:drm_atomic_helper_wait_for_dependencies [drm_kms_helper]] *ERROR* [CONNECTOR:59:eDP-1] flip_done timed out
[ 3949.799904] [drm:drm_atomic_helper_wait_for_dependencies [drm_kms_helper]] *ERROR* [PLANE:28:plane 1A] flip_done timed out
[ 3961.063802] [drm:drm_atomic_helper_wait_for_flip_done [drm_kms_helper]] *ERROR* [CRTC:37:pipe A] flip_done timed out
[ 3971.559802] [drm:drm_atomic_helper_wait_for_dependencies [drm_kms_helper]] *ERROR* [CRTC:37:pipe A] flip_done timed out
[ 3981.799806] [drm:drm_atomic_helper_wait_for_dependencies [drm_kms_helper]] *ERROR* [CONNECTOR:59:eDP-1] flip_done timed out
[ 3992.039811] [drm:drm_atomic_helper_wait_for_dependencies [drm_kms_helper]] *ERROR* [PLANE:28:plane 1A] flip_done timed out
[ 4002.279891] [drm:drm_atomic_helper_wait_for_flip_done [drm_kms_helper]] *ERROR* [CRTC:37:pipe A] flip_done timed out
[ 4012.775799] [drm:drm_atomic_helper_wait_for_dependencies [drm_kms_helper]] *ERROR* [CRTC:37:pipe A] flip_done timed out
[ 4023.015825] [drm:drm_atomic_helper_wait_for_dependencies [drm_kms_helper]] *ERROR* [CONNECTOR:59:eDP-1] flip_done timed out
[ 4033.255737] [drm:drm_atomic_helper_wait_for_dependencies [drm_kms_helper]] *ERROR* [PLANE:28:plane 1A] flip_done timed out
[ 4043.495790] [drm:drm_atomic_helper_wait_for_flip_done [drm_kms_helper]] *ERROR* [CRTC:37:pipe A] flip_done timed out
[ 4053.991812] [drm:drm_atomic_helper_wait_for_dependencies [drm_kms_helper]] *ERROR* [CRTC:37:pipe A] flip_done timed out
[ 4064.231812] [drm:drm_atomic_helper_wait_for_dependencies [drm_kms_helper]] *ERROR* [CONNECTOR:59:eDP-1] flip_done timed out
[ 4074.471803] [drm:drm_atomic_helper_wait_for_dependencies [drm_kms_helper]] *ERROR* [PLANE:28:plane 1A] flip_done timed out
[ 4084.711810] [drm:drm_atomic_helper_wait_for_flip_done [drm_kms_helper]] *ERROR* [CRTC:37:pipe A] flip_done timed out
[ 4094.951753] [drm:drm_atomic_helper_wait_for_dependencies [drm_kms_helper]] *ERROR* [CRTC:37:pipe A] flip_done timed out
[ 4105.191832] [drm:drm_atomic_helper_wait_for_dependencies [drm_kms_helper]] *ERROR* [CONNECTOR:59:eDP-1] flip_done timed out
[ 4115.431808] [drm:drm_atomic_helper_wait_for_dependencies [drm_kms_helper]] *ERROR* [PLANE:28:plane 1A] flip_done timed out
[ 4115.701289] Setting dangerous option enable_fbc - tainting kernel
[ 4115.701336] Setting dangerous option enable_psr - tainting kernel
[ 4126.695799] [drm:drm_atomic_helper_wait_for_flip_done [drm_kms_helper]] *ERROR* [CRTC:37:pipe A] flip_done timed out
[ 4137.447794] [drm:drm_atomic_helper_wait_for_dependencies [drm_kms_helper]] *ERROR* [CRTC:37:pipe A] flip_done timed out
[ 4147.687781] [drm:drm_atomic_helper_wait_for_dependencies [drm_kms_helper]] *ERROR* [PLANE:28:plane 1A] flip_done timed out
[ 4157.927784] [drm:drm_atomic_helper_wait_for_flip_done [drm_kms_helper]] *ERROR* [CRTC:37:pipe A] flip_done timed out
[ 4157.928311] Setting dangerous option enable_psr - tainting kernel
[ 4157.928352] Setting dangerous option enable_fbc - tainting kernel
[ 4168.167820] [drm:drm_atomic_helper_wait_for_dependencies [drm_kms_helper]] *ERROR* [CRTC:37:pipe A] flip_done timed out
[ 4178.407891] [drm:drm_atomic_helper_wait_for_dependencies [drm_kms_helper]] *ERROR* [CONNECTOR:59:eDP-1] flip_done timed out
[ 4188.647774] [drm:drm_atomic_helper_wait_for_dependencies [drm_kms_helper]] *ERROR* [PLANE:28:plane 1A] flip_done timed out
[ 4198.887803] [drm:drm_atomic_helper_wait_for_flip_done [drm_kms_helper]] *ERROR* [CRTC:37:pipe A] flip_done timed out
. . .

======================================
        Graphic stack
======================================
Component: drm
    tag: libdrm-2.4.89-13-g57d3d4c
    commit: 57d3d4c96887cde016fe1d2766196accf77ca423

Component: cairo
    tag: 1.15.8-76-g6b05938
    commit: b7f313a8d2a3049e77b3497dd6040fcfab3b3c9b

Component: intel-gpu-tools
    tag: intel-gpu-tools-1.20-281-g84a3080
    commit: 84a308022028a55903a1916fcee516aab768ed48

Component: piglit
    tag: piglit-v1
    commit: caf60eece2d899cbfa5e92791326506630363ea4
Comment 6 Marta Löfstedt 2018-01-22 08:35:43 UTC
https://intel-gfx-ci.01.org/tree/drm-tip/IGT_4159/shard-kbl5/igt@kms_frontbuffer_tracking@fbc-1p-primscrn-cur-indfb-onoff.html

(kms_frontbuffer_tracking:1506) CRITICAL: Test assertion failure function init_crcs, file ../tests/kms_frontbuffer_tracking.c:1314:
(kms_frontbuffer_tracking:1506) CRITICAL: Failed assertion: rc == 0
(kms_frontbuffer_tracking:1506) CRITICAL: Last errno: 22, Invalid argument
(kms_frontbuffer_tracking:1506) CRITICAL: error: -22 != 0
Subtest fbc-1p-primscrn-cur-indfb-onoff failed.
Comment 7 Ricardo Perez 2018-01-30 14:30:37 UTC
For CoffeeLake S UDIMM RVP QA system, the following test is failing:

igt@kms_frontbuffer_tracking@fbcpsr-1p-indfb-fliptrack

Running:
IGT-Version: 1.21-g37bd27f (x86_64) (Linux: 4.15.0-rc9-drm-intel-qa-ww4-commit-59275f1+ x86_64)

144 **** DEBUG ****
145 (kms_frontbuffer_tracking:2122) DEBUG: Test requirement passed: fbc.can_test
146 (kms_frontbuffer_tracking:2122) DEBUG: Test requirement passed: psr.can_test
147 (kms_frontbuffer_tracking:2122) DEBUG: Test requirement passed: sink_crc.supported
148 (kms_frontbuffer_tracking:2122) drmtest-DEBUG: Test requirement passed: is_i915_device(fd) && has_known_intel_chipset(fd)
149 (kms_frontbuffer_tracking:2122) igt-fb-DEBUG: igt_create_fb_with_bo_size(width=1920, height=1080, format=0x34325258, tiling=0x100000000000001, size=8294400)
150 (kms_frontbuffer_tracking:2122) drmtest-DEBUG: Test requirement passed: is_i915_device(fd) && has_known_intel_chipset(fd)
151 (kms_frontbuffer_tracking:2122) igt-fb-DEBUG: igt_create_fb_with_bo_size(handle=1, pitch=7680)
152 (kms_frontbuffer_tracking:2122) igt-fb-DEBUG: igt_create_fb_with_bo_size(width=64, height=64, format=0x34325241, tiling=0x0, size=16384)
153 (kms_frontbuffer_tracking:2122) igt-fb-DEBUG: igt_create_fb_with_bo_size(handle=2, pitch=256)
154 (kms_frontbuffer_tracking:2122) drmtest-DEBUG: Test requirement passed: is_i915_device(fd) && has_known_intel_chipset(fd)
155 (kms_frontbuffer_tracking:2122) igt-fb-DEBUG: igt_create_fb_with_bo_size(width=64, height=64, format=0x34325258, tiling=0x100000000000001, size=32768)
156 (kms_frontbuffer_tracking:2122) drmtest-DEBUG: Test requirement passed: is_i915_device(fd) && has_known_intel_chipset(fd)
157 (kms_frontbuffer_tracking:2122) igt-fb-DEBUG: igt_create_fb_with_bo_size(handle=3, pitch=512)
158 (kms_frontbuffer_tracking:2122) drmtest-DEBUG: Test requirement passed: is_i915_device(fd) && has_known_intel_chipset(fd)
159 (kms_frontbuffer_tracking:2122) igt-fb-DEBUG: igt_create_fb_with_bo_size(width=1024, height=1024, format=0x34325258, tiling=0x100000000000001, size=4194304)
160 (kms_frontbuffer_tracking:2122) drmtest-DEBUG: Test requirement passed: is_i915_device(fd) && has_known_intel_chipset(fd)
161 (kms_frontbuffer_tracking:2122) igt-fb-DEBUG: igt_create_fb_with_bo_size(handle=4, pitch=4096)
162 (kms_frontbuffer_tracking:2122) drmtest-DEBUG: Test requirement passed: is_i915_device(fd) && has_known_intel_chipset(fd)
163 (kms_frontbuffer_tracking:2122) igt-fb-DEBUG: igt_create_fb_with_bo_size(width=2420, height=3684, format=0x34325258, tiling=0x100000000000001, size=35876864)
164 (kms_frontbuffer_tracking:2122) drmtest-DEBUG: Test requirement passed: is_i915_device(fd) && has_known_intel_chipset(fd)
165 (kms_frontbuffer_tracking:2122) igt-fb-DEBUG: igt_create_fb_with_bo_size(handle=5, pitch=9728)
166 (kms_frontbuffer_tracking:2122) drmtest-DEBUG: Test requirement passed: is_i915_device(fd) && has_known_intel_chipset(fd)
167 (kms_frontbuffer_tracking:2122) igt-fb-DEBUG: igt_create_fb_with_bo_size(width=1920, height=1080, format=0x34325258, tiling=0x100000000000001, size=8294400)
168 (kms_frontbuffer_tracking:2122) drmtest-DEBUG: Test requirement passed: is_i915_device(fd) && has_known_intel_chipset(fd)
169 (kms_frontbuffer_tracking:2122) igt-fb-DEBUG: igt_create_fb_with_bo_size(handle=6, pitch=7680)
170 (kms_frontbuffer_tracking:2122) igt-fb-DEBUG: igt_create_fb_with_bo_size(width=64, height=64, format=0x34325241, tiling=0x0, size=16384)
171 (kms_frontbuffer_tracking:2122) igt-fb-DEBUG: igt_create_fb_with_bo_size(handle=7, pitch=256)
172 (kms_frontbuffer_tracking:2122) drmtest-DEBUG: Test requirement passed: is_i915_device(fd) && has_known_intel_chipset(fd)
173 (kms_frontbuffer_tracking:2122) igt-fb-DEBUG: igt_create_fb_with_bo_size(width=64, height=64, format=0x34325258, tiling=0x100000000000001, size=32768)
174 (kms_frontbuffer_tracking:2122) drmtest-DEBUG: Test requirement passed: is_i915_device(fd) && has_known_intel_chipset(fd)
175 (kms_frontbuffer_tracking:2122) igt-fb-DEBUG: igt_create_fb_with_bo_size(handle=8, pitch=512)
176 (kms_frontbuffer_tracking:2122) drmtest-DEBUG: Test requirement passed: is_i915_device(fd) && has_known_intel_chipset(fd)
177 (kms_frontbuffer_tracking:2122) igt-fb-DEBUG: igt_create_fb_with_bo_size(width=1920, height=1080, format=0x34325258, tiling=0x100000000000001, size=8294400)
178 (kms_frontbuffer_tracking:2122) drmtest-DEBUG: Test requirement passed: is_i915_device(fd) && has_known_intel_chipset(fd)
179 (kms_frontbuffer_tracking:2122) igt-fb-DEBUG: igt_create_fb_with_bo_size(handle=11, pitch=7680)
180 (kms_frontbuffer_tracking:2122) DEBUG: Blue CRC:   pipe:[941b2438 00000000 00000000 00000000 00000000] sink:[00000000c101]
181 (kms_frontbuffer_tracking:2122) drmtest-DEBUG: Test requirement passed: is_i915_device(fd) && has_known_intel_chipset(fd)
182 (kms_frontbuffer_tracking:2122) igt-fb-DEBUG: igt_create_fb_with_bo_size(width=1920, height=1080, format=0x34325258, tiling=0x100000000000001, size=8294400)
183 (kms_frontbuffer_tracking:2122) drmtest-DEBUG: Test requirement passed: is_i915_device(fd) && has_known_intel_chipset(fd)
184 (kms_frontbuffer_tracking:2122) igt-fb-DEBUG: igt_create_fb_with_bo_size(handle=11, pitch=7680)
185 (kms_frontbuffer_tracking:2122) drmtest-DEBUG: Test requirement passed: is_i915_device(fd) && has_known_intel_chipset(fd)
186 (kms_frontbuffer_tracking:2122) igt-fb-DEBUG: igt_create_fb_with_bo_size(width=1920, height=1080, format=0x34325258, tiling=0x100000000000001, size=8294400)
187 (kms_frontbuffer_tracking:2122) drmtest-DEBUG: Test requirement passed: is_i915_device(fd) && has_known_intel_chipset(fd)
188 (kms_frontbuffer_tracking:2122) igt-fb-DEBUG: igt_create_fb_with_bo_size(handle=12, pitch=7680)
189 (kms_frontbuffer_tracking:2122) drmtest-DEBUG: Test requirement passed: is_i915_device(fd) && has_known_intel_chipset(fd)
190 (kms_frontbuffer_tracking:2122) igt-fb-DEBUG: igt_create_fb_with_bo_size(width=1920, height=1080, format=0x34325258, tiling=0x100000000000001, size=8294400)
191 (kms_frontbuffer_tracking:2122) drmtest-DEBUG: Test requirement passed: is_i915_device(fd) && has_known_intel_chipset(fd)
192 (kms_frontbuffer_tracking:2122) igt-fb-DEBUG: igt_create_fb_with_bo_size(handle=13, pitch=7680)
193 (kms_frontbuffer_tracking:2122) drmtest-DEBUG: Test requirement passed: is_i915_device(fd) && has_known_intel_chipset(fd)
194 (kms_frontbuffer_tracking:2122) igt-fb-DEBUG: igt_create_fb_with_bo_size(width=1920, height=1080, format=0x34325258, tiling=0x100000000000001, size=8294400)
195 (kms_frontbuffer_tracking:2122) drmtest-DEBUG: Test requirement passed: is_i915_device(fd) && has_known_intel_chipset(fd)
196 (kms_frontbuffer_tracking:2122) igt-fb-DEBUG: igt_create_fb_with_bo_size(handle=14, pitch=7680)
197 (kms_frontbuffer_tracking:2122) igt-draw-DEBUG: Test requirement passed: gem_get_tiling(fd, buf->handle, &tiling, &swizzle)
198 (kms_frontbuffer_tracking:2122) igt-draw-DEBUG: Test requirement passed: intel_gen(intel_get_drm_devid(fd)) >= 5
199 (kms_frontbuffer_tracking:2122) igt-draw-DEBUG: Test requirement passed: gem_get_tiling(fd, buf->handle, &tiling, &swizzle)
200 (kms_frontbuffer_tracking:2122) igt-draw-DEBUG: Test requirement passed: intel_gen(intel_get_drm_devid(fd)) >= 5
201 (kms_frontbuffer_tracking:2122) igt-draw-DEBUG: Test requirement passed: gem_get_tiling(fd, buf->handle, &tiling, &swizzle)
202 (kms_frontbuffer_tracking:2122) igt-draw-DEBUG: Test requirement passed: intel_gen(intel_get_drm_devid(fd)) >= 5
203 (kms_frontbuffer_tracking:2122) igt-draw-DEBUG: Test requirement passed: gem_get_tiling(fd, buf->handle, &tiling, &swizzle)
204 (kms_frontbuffer_tracking:2122) igt-draw-DEBUG: Test requirement passed: intel_gen(intel_get_drm_devid(fd)) >= 5
205 (kms_frontbuffer_tracking:2122) igt-draw-DEBUG: Test requirement passed: gem_get_tiling(fd, buf->handle, &tiling, &swizzle)
206 (kms_frontbuffer_tracking:2122) igt-draw-DEBUG: Test requirement passed: intel_gen(intel_get_drm_devid(fd)) >= 5
207 (kms_frontbuffer_tracking:2122) igt-draw-DEBUG: Test requirement passed: gem_get_tiling(fd, buf->handle, &tiling, &swizzle)
208 (kms_frontbuffer_tracking:2122) igt-draw-DEBUG: Test requirement passed: intel_gen(intel_get_drm_devid(fd)) >= 5
209 (kms_frontbuffer_tracking:2122) igt-draw-DEBUG: Test requirement passed: gem_get_tiling(fd, buf->handle, &tiling, &swizzle)
210 (kms_frontbuffer_tracking:2122) igt-draw-DEBUG: Test requirement passed: intel_gen(intel_get_drm_devid(fd)) >= 5
211 (kms_frontbuffer_tracking:2122) igt-draw-DEBUG: Test requirement passed: gem_get_tiling(fd, buf->handle, &tiling, &swizzle)
212 (kms_frontbuffer_tracking:2122) igt-draw-DEBUG: Test requirement passed: intel_gen(intel_get_drm_devid(fd)) >= 5
213 (kms_frontbuffer_tracking:2122) igt-draw-DEBUG: Test requirement passed: gem_get_tiling(fd, buf->handle, &tiling, &swizzle)
214 (kms_frontbuffer_tracking:2122) igt-draw-DEBUG: Test requirement passed: intel_gen(intel_get_drm_devid(fd)) >= 5
215 (kms_frontbuffer_tracking:2122) igt-draw-DEBUG: Test requirement passed: gem_get_tiling(fd, buf->handle, &tiling, &swizzle)
216 (kms_frontbuffer_tracking:2122) igt-draw-DEBUG: Test requirement passed: intel_gen(intel_get_drm_devid(fd)) >= 5
217 (kms_frontbuffer_tracking:2122) DEBUG: Rect 0 CRC: pipe:[d15675fd 00000000 00000000 00000000 00000000] sink:[0000982a592b]
218 (kms_frontbuffer_tracking:2122) DEBUG: Rect 1 CRC: pipe:[c52e6ea4 00000000 00000000 00000000 00000000] sink:[216c982a7847]
219 (kms_frontbuffer_tracking:2122) DEBUG: Rect 2 CRC: pipe:[9804f15b 00000000 00000000 00000000 00000000] sink:[7a79424da220]
220 (kms_frontbuffer_tracking:2122) DEBUG: Rect 3 CRC: pipe:[6403325c 00000000 00000000 00000000 00000000] sink:[7a794dcfa220]
221 (kms_frontbuffer_tracking:2122) igt-draw-DEBUG: Test requirement passed: gem_get_tiling(fd, buf->handle, &tiling, &swizzle)
222 (kms_frontbuffer_tracking:2122) DEBUG: checking asserts in line 1780
223 (kms_frontbuffer_tracking:2122) DEBUG: Calculated CRC: pipe:[941b2438 00000000 00000000 00000000 00000000] sink:[00000000c101]
224 (kms_frontbuffer_tracking:2122) DEBUG: Test requirement passed: !fbc_not_enough_stolen()
225 (kms_frontbuffer_tracking:2122) DEBUG: Test requirement passed: !fbc_stride_not_supported()
226 (kms_frontbuffer_tracking:2122) DEBUG: Calculated CRC: pipe:[941b2438 00000000 00000000 00000000 00000000] sink:[00000000c101]
227 (kms_frontbuffer_tracking:2122) drmtest-DEBUG: Test requirement passed: is_i915_device(fd) && has_known_intel_chipset(fd)
228 (kms_frontbuffer_tracking:2122) igt-fb-DEBUG: igt_create_fb_with_bo_size(width=1920, height=1080, format=0x34325258, tiling=0x100000000000001, size=8294400)
229 (kms_frontbuffer_tracking:2122) drmtest-DEBUG: Test requirement passed: is_i915_device(fd) && has_known_intel_chipset(fd)
230 (kms_frontbuffer_tracking:2122) igt-fb-DEBUG: igt_create_fb_with_bo_size(handle=12, pitch=7680)
231 (kms_frontbuffer_tracking:2122) DEBUG: Flip event received.
232 (kms_frontbuffer_tracking:2122) DEBUG: checking asserts in line 2457
233 (kms_frontbuffer_tracking:2122) DEBUG: Calculated CRC: pipe:[941b2438 00000000 00000000 00000000 00000000] sink:[00000000c101]
234 (kms_frontbuffer_tracking:2122) DEBUG: Test requirement passed: !fbc_not_enough_stolen()
235 (kms_frontbuffer_tracking:2122) DEBUG: Test requirement passed: !fbc_stride_not_supported()
236 (kms_frontbuffer_tracking:2122) DEBUG: Calculated CRC: pipe:[941b2438 00000000 00000000 00000000 00000000] sink:[00000000c101]
237 (kms_frontbuffer_tracking:2122) DEBUG: checking asserts in line 2462
238 (kms_frontbuffer_tracking:2122) DEBUG: Calculated CRC: pipe:[d15675fd 00000000 00000000 00000000 00000000] sink:[0000982a592b]
239 (kms_frontbuffer_tracking:2122) DEBUG: Test requirement passed: !fbc_not_enough_stolen()
240 (kms_frontbuffer_tracking:2122) DEBUG: Test requirement passed: !fbc_stride_not_supported()
241 (kms_frontbuffer_tracking:2122) CRITICAL: Test assertion failure function do_status_assertions, file kms_frontbuffer_tracking.c:1729:
242 (kms_frontbuffer_tracking:2122) CRITICAL: Failed assertion: !psr_wait_until_enabled()
243 (kms_frontbuffer_tracking:2122) igt-core-INFO: Stack trace:
244 (kms_frontbuffer_tracking:2122) igt-core-INFO:   #0 [__igt_fail_assert+0x101]
245 (kms_frontbuffer_tracking:2122) igt-core-INFO:   #1 [__do_assertions+0x3fb]
246 (kms_frontbuffer_tracking:2122) igt-core-INFO:   #2 [main+0x11dc]
247 (kms_frontbuffer_tracking:2122) igt-core-INFO:   #3 [__libc_start_main+0xf1]
248 (kms_frontbuffer_tracking:2122) igt-core-INFO:   #4 [_start+0x2a]
249 (kms_frontbuffer_tracking:2122) igt-core-INFO:   #5 [<unknown>+0x2a]
250 ****  END  ****
Comment 8 Marta Löfstedt 2018-02-08 12:10:03 UTC
The orininal issue hasn't been reproduced for over 200 runs, this bug has been kept alive due to link-training. I will archive from cibuglog prespective but since QA has reproduced it I will not close the bug,
Comment 9 Elizabeth 2018-04-02 21:13:05 UTC
Not seen again on QA, if I understood correctly, I can close it now. Thank you.
Comment 10 Jani Saarinen 2018-04-20 11:13:45 UTC
Closing, please re-open if still occurs.


Use of freedesktop.org services, including Bugzilla, is subject to our Code of Conduct. How we collect and use information is described in our Privacy Policy.