[j316] apple-drm soc:display-subsystem: [drm] *ERROR* [CRTC:33:crtc-0] flip_done timed out

[kit-ty-kate]

Using the latest official Asahi Linux Fedora kernel (6.6.3-413.asahi.fc39.aarch64+16k) I booted to a complete black screen in place of GDM. Prior steps of the boot process (including kernel logs) were visible before but when it came to display GDM it failed.

I forced a shutdown by pressing power button and rebooted once again and this time it worked.
Here are the relevant lines of logs for the time it did not work (output of journalctl -b -1 | grep display):

Jan 22 00:00:00 kernel: apple-drm soc:display-subsystem: Adding to iommu group 3
Feb 05 17:56:05 kernel: apple-display-crossbar 130304c000.mux: Switched dpphy to disconnected state
Feb 05 17:56:05 kernel: apple-display-crossbar 130304c000.mux: Switched dpin0 to disconnected state
Feb 05 17:56:05 kernel: apple-display-crossbar 130304c000.mux: Switched dpin1 to disconnected state
Feb 05 17:56:05 kernel: apple-display-crossbar 130304c000.mux: Switched dpphy to dispext0,0
Feb 05 17:56:05 kernel: apple-dcp 38bc00000.dcp: Detected display with notch of 74 pixel
Feb 05 17:56:05 kernel: apple-drm soc:display-subsystem: bound 38bc00000.dcp (ops dcp_comp_ops [apple_dcp])
Feb 05 17:56:05 kernel: apple-drm soc:display-subsystem: bound 289c00000.dcp (ops dcp_comp_ops [apple_dcp])
Feb 05 17:56:05 kernel: apple-dcp 38bc00000.dcp: RTKit: syslog message: ableCompensator.cpp:903: IOMFB setup_table: Could not find table 'display-backlight-compensation-v1' in registry. Using default table.
Feb 05 17:56:05 kernel: apple-dcp 38bc00000.dcp: DCP is_main_display: 1
Feb 05 17:56:05 kernel: apple-dcp 289c00000.dcp: RTKit: syslog message: PPipeDCP_H13P.cpp:11122: IOMFB setup_video_limits called on secondary display with no timing set
Feb 05 17:56:05 kernel: apple-dcp 289c00000.dcp: RTKit: syslog message: PPipeDCP_H13P.cpp:11122: IOMFB setup_video_limits called on secondary display with no timing set
Feb 05 17:56:05 kernel: apple-dcp 289c00000.dcp: DCP is_main_display: 0
Feb 05 17:56:05 kernel: [drm] Initialized apple 1.0.0 20221106 for soc:display-subsystem on minor 0
Feb 05 17:56:15 kernel: apple-drm soc:display-subsystem: [drm] *ERROR* [CRTC:33:crtc-0] flip_done timed out
Feb 05 17:56:15 kernel: apple-drm soc:display-subsystem: [drm] fb0: appledrmfb frame buffer device
Feb 05 17:56:17 gnome-shell[1179]: Running GNOME Shell (using mutter 45.3) as a Wayland display server
Feb 05 17:56:17 gnome-shell[1179]: Using public X11 display :1024, (using :1025 for managed services)
Feb 05 17:56:17 gnome-shell[1179]: Using Wayland display name 'wayland-0'

Comparing with the same command for the current working boot (output of journalctl -k | grep display):

Jan 22 00:00:00 kernel: apple-drm soc:display-subsystem: Adding to iommu group 2
Feb 05 17:59:06 kernel: apple-display-crossbar 130304c000.mux: Switched dpphy to disconnected state
Feb 05 17:59:06 kernel: apple-display-crossbar 130304c000.mux: Switched dpin0 to disconnected state
Feb 05 17:59:06 kernel: apple-display-crossbar 130304c000.mux: Switched dpin1 to disconnected state
Feb 05 17:59:06 kernel: apple-display-crossbar 130304c000.mux: Switched dpphy to dispext0,0
Feb 05 17:59:06 kernel: apple-dcp 38bc00000.dcp: Detected display with notch of 74 pixel
Feb 05 17:59:07 kernel: apple-drm soc:display-subsystem: bound 38bc00000.dcp (ops dcp_comp_ops [apple_dcp])
Feb 05 17:59:07 kernel: apple-drm soc:display-subsystem: bound 289c00000.dcp (ops dcp_comp_ops [apple_dcp])
Feb 05 17:59:07 kernel: apple-dcp 38bc00000.dcp: RTKit: syslog message: ableCompensator.cpp:903: IOMFB setup_table: Could not find table 'display-backlight-compensation-v1' in registry. Using default table.
Feb 05 17:59:07 kernel: apple-dcp 38bc00000.dcp: DCP is_main_display: 1
Feb 05 17:59:07 kernel: apple-dcp 289c00000.dcp: RTKit: syslog message: PPipeDCP_H13P.cpp:11122: IOMFB setup_video_limits called on secondary display with no timing set
Feb 05 17:59:07 kernel: apple-dcp 289c00000.dcp: RTKit: syslog message: PPipeDCP_H13P.cpp:11122: IOMFB setup_video_limits called on secondary display with no timing set
Feb 05 17:59:07 kernel: apple-dcp 289c00000.dcp: DCP is_main_display: 0
Feb 05 17:59:07 kernel: [drm] Initialized apple 1.0.0 20221106 for soc:display-subsystem on minor 0
Feb 05 17:59:07 kernel: apple-drm soc:display-subsystem: [drm] fb0: appledrmfb frame buffer device

It looks to me like the main relevant difference is this one:

apple-drm soc:display-subsystem: [drm] *ERROR* [CRTC:33:crtc-0] flip_done timed out

vs.

[drm] Initialized apple 1.0.0 20221106 for soc:display-subsystem on minor 0

Machine: M1 Pro Macbook Pro 16"

[jannau]

Does this still occur with the 6.8 based kernel? HDMI out initialisation is more reliable in my experience

[kit-ty-kate]

I haven't had this issue since that one time in February so I'm guessing this is fixed. If i ever get it again I'll reopen it. Thanks!