Bug 1563421 - [CRTC:68:crtc-2] vblank wait timed out
Summary: [CRTC:68:crtc-2] vblank wait timed out
Keywords:
Status: CLOSED INSUFFICIENT_DATA
Alias: None
Product: Fedora
Classification: Fedora
Component: kernel
Version: 28
Hardware: aarch64
OS: Linux
unspecified
unspecified
Target Milestone: ---
Assignee: Kernel Maintainer List
QA Contact: Fedora Extras Quality Assurance
URL:
Whiteboard:
Depends On:
Blocks:
TreeView+ depends on / blocked
 
Reported: 2018-04-03 22:22 UTC by John Reiser
Modified: 2018-11-11 20:46 UTC (History)
18 users (show)

Fixed In Version:
Doc Type: If docs needed, set a value
Doc Text:
Clone Of:
Environment:
Last Closed: 2018-08-29 15:05:53 UTC
Type: Bug
Embargoed:


Attachments (Terms of Use)

Description John Reiser 2018-04-03 22:22:03 UTC
Description of problem: First boot of Fedora-Minimal-28_Beta-1.3.aarch64.raw.xz hangs with no VGA video after about 100 seconds.


Version-Release number of selected component (if applicable): Fedora-Minimal-28_Beta-1.3.aarch64.raw.xz


How reproducible: every time


Steps to Reproduce:
1. xzcat Fedora-Minimal-28_Beta-1.3.aarch64.raw.xz >/dev/sdX
2. boot in RaspberryPi model 3B
3.

Actual results: Boot prompts appear on VGA console immediately.  Kernel VGA console log (scrolling VGA text) appears after about 80 seconds elapsed.  VGA console loses video (no analog or digital sync) after about 100 seconds elapsed, and still is missing after about 250 seconds elapsed.


Expected results: Entry to final anaconda configuration (hostname, timezone, root password, etc.)


Additional info: from syslog journal (power down, mount uSDHC card on another system, run "journactl -D/mnt/var/log/journal"):
Mar 05 19:17:01 localhost.localdomain kernel: ------------[ cut here ]------------
Mar 05 19:17:01 localhost.localdomain kernel: [CRTC:68:crtc-2] vblank wait timed out
Mar 05 19:17:01 localhost.localdomain kernel: WARNING: CPU: 2 PID: 679 at drivers/gpu/drm/drm_atomic_helper.c:1349 drm_atomic_helper_wait_for_vblanks.part.8+0x240/0x258 [drm_kms_helper]
Mar 05 19:17:01 localhost.localdomain kernel: Modules linked in: smsc95xx usbnet mii vc4 snd_soc_core ac97_bus snd_pcm_dmaengine cec rc_core snd_pcm snd_timer snd soundcore drm_kms_helper mmc_block syscopyarea sysfillrect sysimgblt fb_sys_fops drm dwc2 sdhci_iproc udc_core sdhci_pltfm sdhci i2c_bcm2835 pwm_bcm2835 bcm2835 bcm2835_dma phy_generic dm_multipath scsi_dh_rdac scsi_dh_emc scsi_dh_alua
Mar 05 19:17:01 localhost.localdomain kernel: CPU: 2 PID: 679 Comm: setfont Not tainted 4.16.0-0.rc4.git0.1.fc28.aarch64 #1
Mar 05 19:17:01 localhost.localdomain kernel: Hardware name: raspberrypi rpi/rpi, BIOS 2018.03 03/20/2018
Mar 05 19:17:01 localhost.localdomain kernel: pstate: 40400005 (nZcv daif +PAN -UAO)
Mar 05 19:17:01 localhost.localdomain kernel: pc : drm_atomic_helper_wait_for_vblanks.part.8+0x240/0x258 [drm_kms_helper]
Mar 05 19:17:01 localhost.localdomain kernel: lr : drm_atomic_helper_wait_for_vblanks.part.8+0x240/0x258 [drm_kms_helper]
Mar 05 19:17:01 localhost.localdomain kernel: sp : ffff00000cfeb7a0
Mar 05 19:17:01 localhost.localdomain kernel: x29: ffff00000cfeb7a0 x28: 0000000000000441
Mar 05 19:17:01 localhost.localdomain kernel: x27: 0000000000000060 x26: 0000000000000000
Mar 05 19:17:01 localhost.localdomain kernel: x25: 0000000000000001 x24: ffff80002b9cc000
Mar 05 19:17:01 localhost.localdomain kernel: x23: 0000000000000004 x22: 0000000000000030
Mar 05 19:17:01 localhost.localdomain kernel: x21: ffff80002b285828 x20: ffff80002b120e00
Mar 05 19:17:01 localhost.localdomain kernel: x19: 0000000000000002 x18: 00000000fffffffd
Mar 05 19:17:01 localhost.localdomain kernel: x17: 0000000000000001 x16: 0000000000000000
Mar 05 19:17:01 localhost.localdomain kernel: x15: 0000000000000001 x14: ffffffffffffffff
Mar 05 19:17:01 localhost.localdomain kernel: x13: 0000000000000000 x12: ffff00000955b000
Mar 05 19:17:01 localhost.localdomain kernel: x11: ffff0000093a5000 x10: ffff00000955baf8
Mar 05 19:17:01 localhost.localdomain kernel: x9 : 0000000000000000 x8 : ffff0000095664c8
Mar 05 19:17:01 localhost.localdomain kernel: x7 : 0000000000000000 x6 : 00000000609fe17f
Mar 05 19:17:01 localhost.localdomain kernel: x5 : 00ffffffffffffff x4 : 0000000000000000
Mar 05 19:17:01 localhost.localdomain kernel: x3 : 0000000000000000 x2 : ffffffffffffffff
Mar 05 19:17:01 localhost.localdomain kernel: x1 : 00008000355d2000 x0 : 0000000000000026
Mar 05 19:17:01 localhost.localdomain kernel: Call trace:
Mar 05 19:17:01 localhost.localdomain kernel:  drm_atomic_helper_wait_for_vblanks.part.8+0x240/0x258 [drm_kms_helper]
Mar 05 19:17:01 localhost.localdomain kernel:  drm_atomic_helper_wait_for_vblanks+0x30/0x40 [drm_kms_helper]
Mar 05 19:17:01 localhost.localdomain kernel:  vc4_atomic_complete_commit+0x84/0xc8 [vc4]
Mar 05 19:17:01 localhost.localdomain kernel:  vc4_atomic_commit+0xd4/0x138 [vc4]
Mar 05 19:17:01 localhost.localdomain kernel:  drm_atomic_commit+0x54/0x70 [drm]
Mar 05 19:17:01 localhost.localdomain kernel:  restore_fbdev_mode_atomic+0x204/0x228 [drm_kms_helper]
Mar 05 19:17:01 localhost.localdomain kernel:  drm_fb_helper_pan_display+0xa8/0x1d0 [drm_kms_helper]
Mar 05 19:17:01 localhost.localdomain kernel:  fb_pan_display+0x9c/0x110
Mar 05 19:17:01 localhost.localdomain kernel:  bit_update_start+0x28/0x50
Mar 05 19:17:01 localhost.localdomain kernel:  fbcon_switch+0x40c/0x518
Mar 05 19:17:01 localhost.localdomain kernel:  redraw_screen+0x134/0x228
Mar 05 19:17:01 localhost.localdomain kernel:  fbcon_do_set_font+0x1d0/0x260
Mar 05 19:17:01 localhost.localdomain kernel:  fbcon_set_font+0x204/0x258
Mar 05 19:17:01 localhost.localdomain kernel:  con_font_op+0x384/0x460
Mar 05 19:17:01 localhost.localdomain kernel:  vt_ioctl+0xd0c/0x1790
Mar 05 19:17:01 localhost.localdomain kernel:  tty_ioctl+0x23c/0xfe0
Mar 05 19:17:01 localhost.localdomain kernel:  do_vfs_ioctl+0xb0/0x970
Mar 05 19:17:01 localhost.localdomain kernel:  SyS_ioctl+0x90/0xa8
Mar 05 19:17:01 localhost.localdomain kernel:  __sys_trace_return+0x0/0x4
Mar 05 19:17:01 localhost.localdomain kernel: ---[ end trace e2a088407528c092 ]---

Comment 1 John Reiser 2018-04-03 23:12:02 UTC
Console is 17" Samsung 730B terminal, 1280x1024, using DVI input via HDMI-to-DVI cable plugged in to RaspberryPi-3B.

Mar 05 14:16:38 localhost kernel: Kernel command line: BOOT_IMAGE=/vmlinuz-4.16.0-0.rc4.git0.1.fc28.aarch64 root=UUID=d8e0eddd-e5e3-4f22-bdea-4a68250f11d1 ro resume=UUID=ca15e69f-8969-4a9f-b69d-1ad9b29f9343 LANG=en_US.UTF-8

Early drm messages [prefix "Mar 05 14:16:42 " removed]
localhost kernel: vc4_hdmi 3f902000.hdmi: vc4-hdmi-hifi <-> 3f902000.hdmi mapping ok
localhost kernel: vc4-drm soc:gpu: bound 3f902000.hdmi (ops vc4_hdmi_ops [vc4])
localhost kernel: vc4-drm soc:gpu: bound 3f806000.vec (ops vc4_vec_ops [vc4])
localhost kernel: vc4-drm soc:gpu: bound 3f400000.hvs (ops vc4_hvs_ops [vc4])
localhost kernel: usb 1-1: New USB device found, idVendor=0424, idProduct=9514
localhost kernel: vc4-drm soc:gpu: bound 3f206000.pixelvalve (ops vc4_crtc_ops [vc4])
localhost kernel: usb 1-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0
localhost kernel: vc4-drm soc:gpu: bound 3f207000.pixelvalve (ops vc4_crtc_ops [vc4])
localhost kernel: hub 1-1:1.0: USB hub found
localhost kernel: vc4-drm soc:gpu: bound 3f807000.pixelvalve (ops vc4_crtc_ops [vc4])
localhost kernel: hub 1-1:1.0: 5 ports detected
localhost systemd[1]: Found device /dev/disk/by-uuid/d8e0eddd-e5e3-4f22-bdea-4a68250f11d1.
localhost kernel: vc4-drm soc:gpu: bound 3fc00000.v3d (ops vc4_v3d_ops [vc4])
localhost kernel: checking generic (3eafb000 500000) vs hw (0 ffffffffffffffff)
localhost kernel: fb: switching to vc4drmfb from EFI VGA
localhost systemd[1]: Found device /dev/disk/by-uuid/ca15e69f-8969-4a9f-b69d-1ad9b29f9343.
localhost kernel: Console: switching to colour dummy device 80x25
localhost systemd[1]: Starting Resume from hibernation using device /dev/disk/by-uuid/ca15e69f-8969-4a9f-b69d-1ad9b29f9343...
localhost systemd[1]: Reached target Initrd Root Device.
localhost kernel: [drm] Initialized vc4 0.0.0 20140616 for soc:gpu on minor 0
localhost kernel: [drm] Supports vblank timestamp caching Rev 2 (21.10.2013).
localhost kernel: [drm] Driver supports precise vblank timestamp query.
localhost systemd-hibernate-resume[512]: Could not resume from '/dev/disk/by-uuid/ca15e69f-8969-4a9f-b69d-1ad9b29f9343' (179:3).
localhost systemd[1]: Started Resume from hibernation using device /dev/disk/by-uuid/ca15e69f-8969-4a9f-b69d-1ad9b29f9343.
localhost kernel: PM: Starting manual resume from disk
localhost kernel: PM: Image not found (code -22)
localhost kernel: Console: switching to colour frame buffer device 160x64
localhost kernel: vc4-drm soc:gpu: fb0:  frame buffer device

Comment 2 John Reiser 2018-04-04 16:55:41 UTC
The same problem happens with Fedora-Minimal-armhfp-28_Beta-1.3-sda.raw.xz, except that VGA text scrolling of syslog begins at about 10 seconds elapsed, and loss of video sync occurs at about 20 seconds elapsed.

Mar 05 14:16:37 localhost kernel: Linux version 4.16.0-0.rc4.git0.1.fc28.armv7hl (mockbuild.fedoraproject.org) (gcc version 8.0.1 20180222 (Red Hat 8.0.1-0.16) (GCC)) #1 SMP Mon Mar 5 05:58:07 UTC 2018

Mar 05 14:16:41 localhost:
kernel: rc rc0: RC for vc4 as /devices/platform/soc/3f902000.hdmi/rc/rc0
kernel: input: RC for vc4 as /devices/platform/soc/3f902000.hdmi/rc/rc0/input0
kernel: vc4_hdmi 3f902000.hdmi: vc4-hdmi-hifi <-> 3f902000.hdmi mapping ok
kernel: vc4_hdmi 3f902000.hdmi: ASoC: no DMI vendor name!
kernel: vc4-drm soc:gpu: bound 3f902000.hdmi (ops vc4_hdmi_ops [vc4])
kernel: vc4-drm soc:gpu: bound 3f806000.vec (ops vc4_vec_ops [vc4])
kernel: vc4-drm soc:gpu: bound 3f400000.hvs (ops vc4_hvs_ops [vc4])
kernel: vc4-drm soc:gpu: bound 3f206000.pixelvalve (ops vc4_crtc_ops [vc4])
kernel: vc4-drm soc:gpu: bound 3f207000.pixelvalve (ops vc4_crtc_ops [vc4])
kernel: vc4-drm soc:gpu: bound 3f807000.pixelvalve (ops vc4_crtc_ops [vc4])
kernel: usb 1-1: new high-speed USB device number 2 using dwc2
kernel: vc4-drm soc:gpu: bound 3fc00000.v3d (ops vc4_v3d_ops [vc4])
kernel: checking generic (3eafb000 500000) vs hw (0 ffffffff)
kernel: fb: switching to vc4drmfb from simple
kernel: Console: switching to colour dummy device 80x30
kernel: [drm] Initialized vc4 0.0.0 20140616 for soc:gpu on minor 0
kernel: [drm] Supports vblank timestamp caching Rev 2 (21.10.2013).
kernel: [drm] Driver supports precise vblank timestamp query.

Mar 05 14:17:15 localhost:
kernel: ------------[ cut here ]------------
kernel: WARNING: CPU: 3 PID: 432 at drivers/gpu/drm/drm_atomic_helper.c:1349 drm_atomic_helper_wait_for_vblanks+0x1d0/0x1e8 [drm_kms_helper]
kernel: [CRTC:68:crtc-2] vblank wait timed out
kernel: Modules linked in: vc4(+) snd_soc_core ac97_bus snd_pcm_dmaengine cec rc_core snd_pcm snd_timer snd mmc_block soundcore drm_kms_helper syscopyarea sysfillrect sysimgblt fb_sys_fops drm dwc2 sdhci_iproc udc_core crc32_arm_ce sdhci_pltfm pwm_bcm2835 sdhci i2c_bcm2835 bcm2835 bcm2835_dma phy_generic
kernel: CPU: 3 PID: 432 Comm: systemd-udevd Not tainted 4.16.0-0.rc4.git0.1.fc28.armv7hl #1
kernel: Hardware name: BCM2835
kernel: [<c0311dc0>] (unwind_backtrace) from [<c030c598>] (show_stack+0x18/0x1c)
kernel: [<c030c598>] (show_stack) from [<c0ab14c4>] (dump_stack+0x80/0xa0)
kernel: [<c0ab14c4>] (dump_stack) from [<c0351aa8>] (__warn+0xdc/0xf8)
kernel: [<c0351aa8>] (__warn) from [<c0351b00>] (warn_slowpath_fmt+0x3c/0x4c)
kernel: [<c0351b00>] (warn_slowpath_fmt) from [<bf17d7d8>] (drm_atomic_helper_wait_for_vblanks+0x1d0/0x1e8 [drm_kms_helper])
kernel: [<bf17d7d8>] (drm_atomic_helper_wait_for_vblanks [drm_kms_helper]) from [<bf287034>] (vc4_atomic_complete_commit+0x78/0xb0 [vc4])
kernel: [<bf287034>] (vc4_atomic_complete_commit [vc4]) from [<bf287174>] (vc4_atomic_commit+0x108/0x114 [vc4])
kernel: [<bf287174>] (vc4_atomic_commit [vc4]) from [<bf181004>] (restore_fbdev_mode_atomic+0x80/0x1b8 [drm_kms_helper])
kernel: [<bf181004>] (restore_fbdev_mode_atomic [drm_kms_helper]) from [<bf183ac8>] (drm_fb_helper_restore_fbdev_mode_unlocked+0x50/0x84 [drm_kms_helper])
kernel: [<bf183ac8>] (drm_fb_helper_restore_fbdev_mode_unlocked [drm_kms_helper]) from [<bf183b50>] (drm_fb_helper_set_par+0x54/0x64 [drm_kms_helper])
kernel: [<bf183b50>] (drm_fb_helper_set_par [drm_kms_helper]) from [<c070b594>] (fbcon_init+0x2b8/0x474)
kernel: [<c070b594>] (fbcon_init) from [<c077f538>] (visual_init+0xc0/0x110)
kernel: [<c077f538>] (visual_init) from [<c078106c>] (do_bind_con_driver+0x260/0x2cc)
kernel: [<c078106c>] (do_bind_con_driver) from [<c0781438>] (do_take_over_console+0x174/0x1a8)
kernel: [<c0781438>] (do_take_over_console) from [<c070b7a8>] (do_fbcon_takeover+0x58/0xc0)
kernel: [<c070b7a8>] (do_fbcon_takeover) from [<c0371440>] (notifier_call_chain+0x48/0x6c)
kernel: [<c0371440>] (notifier_call_chain) from [<c0371894>] (__blocking_notifier_call_chain+0x48/0x60)
kernel: [<c0371894>] (__blocking_notifier_call_chain) from [<c03718c8>] (blocking_notifier_call_chain+0x1c/0x24)
kernel: [<c03718c8>] (blocking_notifier_call_chain) from [<c0703140>] (register_framebuffer+0x234/0x278)
kernel: [<c0703140>] (register_framebuffer) from [<bf183764>] (__drm_fb_helper_initial_config_and_unlock+0x2a8/0x358 [drm_kms_helper])
kernel: [<bf183764>] (__drm_fb_helper_initial_config_and_unlock [drm_kms_helper]) from [<bf183e60>] (drm_fb_cma_fbdev_init_with_funcs+0xd8/0x138 [drm_kms_helper])
kernel: [<bf183e60>] (drm_fb_cma_fbdev_init_with_funcs [drm_kms_helper]) from [<bf2872f4>] (vc4_kms_load+0x9c/0xb4 [vc4])
kernel: [<bf2872f4>] (vc4_kms_load [vc4]) from [<bf282f5c>] (vc4_drm_bind+0x118/0x130 [vc4])
kernel: [<bf282f5c>] (vc4_drm_bind [vc4]) from [<c07c6574>] (try_to_bring_up_master+0x7c/0x24c)
kernel: [<c07c6574>] (try_to_bring_up_master) from [<c07c69dc>] (component_master_add_with_match+0xc8/0x100)
kernel: [<c07c69dc>] (component_master_add_with_match) from [<bf282fe8>] (vc4_platform_drm_probe+0x74/0xb0 [vc4])
kernel: [<bf282fe8>] (vc4_platform_drm_probe [vc4]) from [<c07ce544>] (platform_drv_probe+0x58/0xa4)
kernel: [<c07ce544>] (platform_drv_probe) from [<c07cc5f4>] (driver_probe_device+0x2c8/0x45c)
kernel: [<c07cc5f4>] (driver_probe_device) from [<c07cc810>] (__driver_attach+0x88/0xf8)
kernel: [<c07cc810>] (__driver_attach) from [<c07ca64c>] (bus_for_each_dev+0x5c/0x80)
kernel: [<c07ca64c>] (bus_for_each_dev) from [<c07cb8b8>] (bus_add_driver+0x1bc/0x234)
kernel: [<c07cb8b8>] (bus_add_driver) from [<c07cd414>] (driver_register+0xa8/0xe8)
kernel: [<c07cd414>] (driver_register) from [<c03027b0>] (do_one_initcall+0x128/0x150)
kernel: [<c03027b0>] (do_one_initcall) from [<c03dd4a8>] (do_init_module+0x60/0x1ec)
kernel: [<c03dd4a8>] (do_init_module) from [<c03df834>] (load_module+0x2168/0x21f8)
kernel: [<c03df834>] (load_module) from [<c03dfa3c>] (SyS_init_module+0x178/0x190)
kernel: [<c03dfa3c>] (SyS_init_module) from [<c03011bc>] (__sys_trace_return+0x0/0x10)
kernel: Exception stack(0xc4e5bfa8 to 0xc4e5bff0)
kernel: bfa0:                   00e5c780 b5a07008 b5a07008 000280b7 b6d9b5e8 0e77ea00
kernel: bfc0: 00e5c780 b5a07008 00020000 00000080 00e5cfd0 be868d48 0051a9f8 00e54678
kernel: bfe0: b6dacfa8 be868c70 b6d9248c b6c518c0
kernel: ---[ end trace 05d2222bd6b955d2 ]---
kernel: Console: switching to colour frame buffer device 160x64
kernel: usb 1-1: New USB device found, idVendor=0424, idProduct=9514
kernel: usb 1-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0
kernel: hub 1-1:1.0: USB hub found
kernel: hub 1-1:1.0: 5 ports detected
kernel: usb 1-1.1: new high-speed USB device number 3 using dwc2
kernel: usb 1-1.1: New USB device found, idVendor=0424, idProduct=ec00
kernel: usb 1-1.1: New USB device strings: Mfr=0, Product=0, SerialNumber=0
kernel: smsc95xx v1.0.6
kernel: usb 1-1.2: new low-speed USB device number 4 using dwc2
kernel: smsc95xx 1-1.1:1.0 eth0: register 'smsc95xx' at usb-3f980000.usb-1.1, smsc95xx USB 2.0 Ethernet, b8:27:eb:8e:10:ed
kernel: usbcore: registered new interface driver smsc95xx
kernel: usb 1-1.2: New USB device found, idVendor=1e54, idProduct=2030
kernel: usb 1-1.2: New USB device strings: Mfr=1, Product=2, SerialNumber=0
kernel: usb 1-1.2: Product: USB Keyboard
kernel: usb 1-1.2: Manufacturer: TypeMatrix.com
kernel: input: TypeMatrix.com USB Keyboard as /devices/platform/soc/3f980000.usb/usb1/1-1/1-1.2/1-1.2:1.0/0003:1E54:2030.0001/input/input1
kernel: hid-generic 0003:1E54:2030.0001: input,hidraw0: USB HID v1.10 Keyboard [TypeMatrix.com USB Keyboard] on usb-3f980000.usb-1.2/input0
kernel: input: TypeMatrix.com USB Keyboard as /devices/platform/soc/3f980000.usb/usb1/1-1/1-1.2/1-1.2:1.1/0003:1E54:2030.0002/input/input2
kernel: hid-generic 0003:1E54:2030.0002: input,hiddev96,hidraw1: USB HID v1.10 Device [TypeMatrix.com USB Keyboard] on usb-3f980000.usb-1.2/input1
kernel: usb 1-1.4: new low-speed USB device number 5 using dwc2
kernel: usb 1-1.4: New USB device found, idVendor=046d, idProduct=c019
kernel: usb 1-1.4: New USB device strings: Mfr=1, Product=2, SerialNumber=0
kernel: usb 1-1.4: Product: Logitech USB Optical Mouse
kernel: usb 1-1.4: Manufacturer: Logitech
kernel: input: Logitech Logitech USB Optical Mouse as /devices/platform/soc/3f980000.usb/usb1/1-1/1-1.4/1-1.4:1.0/0003:046D:C019.0003/input/input3
kernel: hid-generic 0003:046D:C019.0003: input,hidraw2: USB HID v1.11 Mouse [Logitech Logitech USB Optical Mouse] on usb-3f980000.usb-1.4/input0
kernel: random: crng init done
kernel: [drm:drm_atomic_helper_wait_for_dependencies [drm_kms_helper]] *ERROR* [CRTC:68:crtc-2] flip_done timed out
kernel: [drm:drm_atomic_helper_wait_for_dependencies [drm_kms_helper]] *ERROR* [CONNECTOR:29:HDMI-A-1] flip_done timed out
kernel: [drm:drm_atomic_helper_wait_for_dependencies [drm_kms_helper]] *ERROR* [PLANE:67:plane-20] flip_done timed out
kernel: ------------[ cut here ]------------
kernel: WARNING: CPU: 3 PID: 432 at drivers/gpu/drm/drm_atomic_helper.c:1349 drm_atomic_helper_wait_for_vblanks+0x1d0/0x1e8 [drm_kms_helper]
kernel: [CRTC:68:crtc-2] vblank wait timed out
kernel: Modules linked in: smsc95xx usbnet mii vc4(+) snd_soc_core ac97_bus snd_pcm_dmaengine cec rc_core snd_pcm snd_timer snd mmc_block soundcore drm_kms_helper syscopyarea sysfillrect sysimgblt fb_sys_fops drm dwc2 sdhci_iproc udc_core crc32_arm_ce sdhci_pltfm pwm_bcm2835 sdhci i2c_bcm2835 bcm2835 bcm2835_dma phy_generic
kernel: CPU: 3 PID: 432 Comm: systemd-udevd Tainted: G        W        4.16.0-0.rc4.git0.1.fc28.armv7hl #1
kernel: Hardware name: BCM2835
kernel: [<c0311dc0>] (unwind_backtrace) from [<c030c598>] (show_stack+0x18/0x1c)
kernel: [<c030c598>] (show_stack) from [<c0ab14c4>] (dump_stack+0x80/0xa0)
kernel: [<c0ab14c4>] (dump_stack) from [<c0351aa8>] (__warn+0xdc/0xf8)
kernel: [<c0351aa8>] (__warn) from [<c0351b00>] (warn_slowpath_fmt+0x3c/0x4c)
kernel: [<c0351b00>] (warn_slowpath_fmt) from [<bf17d7d8>] (drm_atomic_helper_wait_for_vblanks+0x1d0/0x1e8 [drm_kms_helper])
kernel: [<bf17d7d8>] (drm_atomic_helper_wait_for_vblanks [drm_kms_helper]) from [<bf287034>] (vc4_atomic_complete_commit+0x78/0xb0 [vc4])
kernel: [<bf287034>] (vc4_atomic_complete_commit [vc4]) from [<bf287174>] (vc4_atomic_commit+0x108/0x114 [vc4])
kernel: [<bf287174>] (vc4_atomic_commit [vc4]) from [<bf181004>] (restore_fbdev_mode_atomic+0x80/0x1b8 [drm_kms_helper])
kernel: [<bf181004>] (restore_fbdev_mode_atomic [drm_kms_helper]) from [<bf183c14>] (drm_fb_helper_pan_display+0x94/0x174 [drm_kms_helper])
kernel: [<bf183c14>] (drm_fb_helper_pan_display [drm_kms_helper]) from [<c0701a78>] (fb_pan_display+0xf0/0x12c)
kernel: [<c0701a78>] (fb_pan_display) from [<c070ec4c>] (bit_update_start+0x1c/0x38)
kernel: [<c070ec4c>] (bit_update_start) from [<c070de20>] (fbcon_switch+0x3b0/0x460)
kernel: [<c070de20>] (fbcon_switch) from [<c07806f0>] (redraw_screen+0x124/0x1c0)
kernel: [<c07806f0>] (redraw_screen) from [<c0780f9c>] (do_bind_con_driver+0x190/0x2cc)
kernel: [<c0780f9c>] (do_bind_con_driver) from [<c0781438>] (do_take_over_console+0x174/0x1a8)
kernel: [<c0781438>] (do_take_over_console) from [<c070b7a8>] (do_fbcon_takeover+0x58/0xc0)
kernel: [<c070b7a8>] (do_fbcon_takeover) from [<c0371440>] (notifier_call_chain+0x48/0x6c)
kernel: [<c0371440>] (notifier_call_chain) from [<c0371894>] (__blocking_notifier_call_chain+0x48/0x60)
kernel: [<c0371894>] (__blocking_notifier_call_chain) from [<c03718c8>] (blocking_notifier_call_chain+0x1c/0x24)
kernel: [<c03718c8>] (blocking_notifier_call_chain) from [<c0703140>] (register_framebuffer+0x234/0x278)
kernel: [<c0703140>] (register_framebuffer) from [<bf183764>] (__drm_fb_helper_initial_config_and_unlock+0x2a8/0x358 [drm_kms_helper])
kernel: [<bf183764>] (__drm_fb_helper_initial_config_and_unlock [drm_kms_helper]) from [<bf183e60>] (drm_fb_cma_fbdev_init_with_funcs+0xd8/0x138 [drm_kms_helper])
kernel: [<bf183e60>] (drm_fb_cma_fbdev_init_with_funcs [drm_kms_helper]) from [<bf2872f4>] (vc4_kms_load+0x9c/0xb4 [vc4])
kernel: [<bf2872f4>] (vc4_kms_load [vc4]) from [<bf282f5c>] (vc4_drm_bind+0x118/0x130 [vc4])
kernel: [<bf282f5c>] (vc4_drm_bind [vc4]) from [<c07c6574>] (try_to_bring_up_master+0x7c/0x24c)
kernel: [<c07c6574>] (try_to_bring_up_master) from [<c07c69dc>] (component_master_add_with_match+0xc8/0x100)
kernel: [<c07c69dc>] (component_master_add_with_match) from [<bf282fe8>] (vc4_platform_drm_probe+0x74/0xb0 [vc4])
kernel: [<bf282fe8>] (vc4_platform_drm_probe [vc4]) from [<c07ce544>] (platform_drv_probe+0x58/0xa4)
kernel: [<c07ce544>] (platform_drv_probe) from [<c07cc5f4>] (driver_probe_device+0x2c8/0x45c)
kernel: [<c07cc5f4>] (driver_probe_device) from [<c07cc810>] (__driver_attach+0x88/0xf8)
kernel: [<c07cc810>] (__driver_attach) from [<c07ca64c>] (bus_for_each_dev+0x5c/0x80)
kernel: [<c07ca64c>] (bus_for_each_dev) from [<c07cb8b8>] (bus_add_driver+0x1bc/0x234)
kernel: [<c07cb8b8>] (bus_add_driver) from [<c07cd414>] (driver_register+0xa8/0xe8)
kernel: [<c07cd414>] (driver_register) from [<c03027b0>] (do_one_initcall+0x128/0x150)
kernel: [<c03027b0>] (do_one_initcall) from [<c03dd4a8>] (do_init_module+0x60/0x1ec)
kernel: [<c03dd4a8>] (do_init_module) from [<c03df834>] (load_module+0x2168/0x21f8)
kernel: [<c03df834>] (load_module) from [<c03dfa3c>] (SyS_init_module+0x178/0x190)
kernel: [<c03dfa3c>] (SyS_init_module) from [<c03011bc>] (__sys_trace_return+0x0/0x10)
kernel: Exception stack(0xc4e5bfa8 to 0xc4e5bff0)
kernel: bfa0:                   00e5c780 b5a07008 b5a07008 000280b7 b6d9b5e8 0e77ea00
kernel: bfc0: 00e5c780 b5a07008 00020000 00000080 00e5cfd0 be868d48 0051a9f8 00e54678
kernel: bfe0: b6dacfa8 be868c70 b6d9248c b6c518c0
kernel: ---[ end trace 05d2222bd6b955d3 ]---
kernel: vc4-drm soc:gpu: fb0:  frame buffer device

Comment 3 John Reiser 2018-04-04 17:23:41 UTC
Fedora-Xfce-armhfp-28_Beta-1.3-sda.raw.xz succeeds.  It performs Anaconda final initialization in graphics mode, then switches to Xfce and graphical login.

Mar 05 14:16:37 localhost kernel: Linux version 4.16.0-0.rc4.git0.1.fc28.armv7hl (mockbuild.fedoraproject.org) (gcc version 8.0.1 20180222 (Red Hat 8.0.1-0.16) (GCC)) #1 SMP Mon Mar 5 05:58:07 UTC 2018

Mar 05 14:17:06 localhost:
kernel: vc4_hdmi 3f902000.hdmi: vc4-hdmi-hifi <-> 3f902000.hdmi mapping ok
kernel: vc4_hdmi 3f902000.hdmi: ASoC: no DMI vendor name! 
kernel: Bluetooth: hci0: BCM: chip id 94
kernel: vc4-drm soc:gpu: bound 3f902000.hdmi (ops vc4_hdmi_ops [vc4])
kernel: Bluetooth: hci0: BCM: features 0x2e
kernel: vc4-drm soc:gpu: bound 3f806000.vec (ops vc4_vec_ops [vc4])
kernel: Bluetooth: hci0: BCM43430A1
kernel: vc4-drm soc:gpu: bound 3f400000.hvs (ops vc4_hvs_ops [vc4])
kernel: Bluetooth: hci0: BCM43430A1 (001.002.009) build 0000

kernel: bluetooth hci0: Direct firmware load for brcm/BCM43430A1.hcd failed with error -2
kernel: Bluetooth: hci0: BCM: Patch brcm/BCM43430A1.hcd not found
kernel: vc4-drm soc:gpu: bound 3f206000.pixelvalve (ops vc4_crtc_ops [vc4])
kernel: vc4-drm soc:gpu: bound 3f207000.pixelvalve (ops vc4_crtc_ops [vc4])
kernel: vc4-drm soc:gpu: bound 3f807000.pixelvalve (ops vc4_crtc_ops [vc4])
kernel: audit: type=1130 audit(1520288227.121:75): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:init_t:s0 msg='unit=systemd-rfkill comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
kill comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' 

kernel: vc4-drm soc:gpu: bound 3fc00000.v3d (ops vc4_v3d_ops [vc4])
kernel: checking generic (3eafb000 500000) vs hw (0 ffffffff)
kernel: fb: switching to vc4drmfb from simple
kernel: Console: switching to colour dummy device 80x30
kernel: [drm] Initialized vc4 0.0.0 20140616 for soc:gpu on minor 0
kernel: [drm] Supports vblank timestamp caching Rev 2 (21.10.2013).
kernel: [drm] Driver supports precise vblank timestamp query.
kernel: Console: switching to colour frame buffer device 160x64 
kernel: vc4-drm soc:gpu: fb0:  frame buffer device

Comment 4 Justin M. Forbes 2018-07-23 14:57:23 UTC
*********** MASS BUG UPDATE **************

We apologize for the inconvenience.  There are a large number of bugs to go through and several of them have gone stale.  Due to this, we are doing a mass bug update across all of the Fedora 28 kernel bugs.

Fedora 28 has now been rebased to 4.17.7-200.fc28.  Please test this kernel update (or newer) and let us know if you issue has been resolved or if it is still present with the newer kernel.

If you experience different issues, please open a new bug report for those.

Comment 5 Justin M. Forbes 2018-08-29 15:05:53 UTC
*********** MASS BUG UPDATE **************
This bug is being closed with INSUFFICIENT_DATA as there has not been a response in 5 weeks. If you are still experiencing this issue, please reopen and attach the relevant data from the latest kernel you are running and any data that might have been requested previously.

Comment 6 Sven Anders 2018-10-05 13:18:45 UTC
I installed the "Fedora-Server-29_Beta-1.5.aarch64.raw.xz" image.
I'm using a Raspberry Pi 3B.
It's connected to an Samsung XL2370HD via HDMI cable.

I'm experience similar problems. The "Fedora-Server-28-1.1.aarch64.raw.xz" and "Fedora-Minimal-28-1.1.aarch64.raw.xz" images have the same problems. Only the 
"Fedora-Workstation-28-1.1.aarch64.raw.xz" image works on the CRT.

I'm willing to debug this further or provide more date on request.


My boot sequence is as follows:

~20 seconds boot sequence (EFI, Grub, Kernel init)
~60 seconds blank on CRT and serial console
~20 seconds output on CRT and serial console (Loading initramfs, starting)
    (after it reached "target System Initialization"/"target Basic System"
     or somewhere near this, the CRT goes blank.)
~150-200 seconds only on serial console:
.
.
.
[  OK  ] Started Cleanup udevd DB.
[  OK  ] Reached target Switch Root.
         Starting Switch Root...
[   16.480206] systemd-journald[196]: Received SIGTERM from PID 1 (systemd).
[   16.761729] systemd: 16 output lines suppressed due to ratelimiting
[   19.827189] SELinux:  Class xdp_socket not defined in policy.
[   19.845071] SELinux: the above unknown classes and permissions will be allowed
[   19.864515] SELinux:  policy capability network_peer_controls=1
[   19.882083] SELinux:  policy capability open_perms=1
[   19.898093] SELinux:  policy capability extended_socket_class=1
[   19.914851] SELinux:  policy capability always_check_network=0
[   19.931425] SELinux:  policy capability cgroup_seclabel=1
[   19.947441] SELinux:  policy capability nnp_nosuid_transition=1
[   20.151818] kauditd_printk_skb: 35 callbacks suppressed
[   20.151826] audit: type=1403 audit(1529665922.659:60): auid=4294967295 ses=4294967295 lsm=selinux res=1
[   20.168338] systemd[1]: Successfully loaded SELinux policy in 2.723288s.
[   20.265789] systemd[1]: RTC configured in localtime, applying delta of -240 minutes to system time.
[   20.654418] systemd[1]: Relabelled /dev, /run and /sys/fs/cgroup in 339.772ms.
[   20.695882] systemd[1]: systemd 239 running in system mode. (+PAM +AUDIT +SELINUX +IMA -APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPT)
[   20.739926] systemd[1]: Detected architecture arm64.

Welcome to Fedora 29 (Server Edition)!

[   20.784794] systemd[1]: Set hostname to <localhost.localdomain>.
[   22.077231] audit: type=1130 audit(1529680324.584:61): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:init_t:s'
[   22.082993] systemd[1]: Stopped Switch Root.
[   22.119501] audit: type=1131 audit(1529680324.584:62): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:init_t:s'
[[   22.178940] audit: type=1130 audit(1529680324.684:63): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:init_t:'
[   22.179046] audit: type=1131 audit(1529680324.684:64): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:init_t:s'
  OK  ] Stopped Switch Root.
[   22.179847] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart.
[   22.180395] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.
[   22.180582] systemd[1]: Stopped Journal Service.
[   22.343786] audit: type=1130 audit(1529680324.854:65): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:init_t:s'
[   22.343902] audit: type=1131 audit(1529680324.854:66): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:init_t:s'
[   22.354033] systemd[1]: Starting Journal Service...
[   22.446445] audit: type=1305 audit(1529680324.954:67): audit_enabled=1 old=1 auid=4294967295 ses=4294967295 subj=system_u:system_1
[  OK  ] Stopped Journal Service.
[   22.526843] systemd[1]: Listening on udev Kernel Socket.
         Starting Journal Service...
[   22.566255] systemd[1]: Set up automount Arbitrary Executable File Formats File System Automount Point.
[  OK  ] Listening on udev Kernel Socket.
[[   22.625979] audit: type=1130 audit(1529680325.134:68): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:init_t:'
  OK  ] Set up automount Arbitrary Executab…rmats File System Automount Point.
[   22.626069] audit: type=1131 audit(1529680325.134:69): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:init_t:s'
[  OK  ] Stopped File System Check on Root Device.
[  OK  ] Stopped target Switch Root.
[  OK  ] Created slice system-sshd\x2dkeygen.slice.
[  OK  ] Stopped target Initrd Root File System.
[  OK  ] Listening on initctl Compatibility Named Pipe.
         Starting Create list of required st…ce nodes for the current kernel...
[  OK  ] Listening on udev Control Socket.
         Starting udev Coldplug all Devices...
[  OK  ] Created slice system-serial\x2dgetty.slice.
[  OK  ] Created slice system-getty.slice.
         Starting Remount Root and Kernel File Systems...
[  OK  ] Created slice User and Session Slice.
[  OK  ] Reached target Slices.
[  OK  ] Listening on Process Core Dump Socket.
         Mounting Huge Pages File System...
[  OK  ] Started Forward Password Requests to Wall Directory Watch.
[  OK  ] Reached target Local Encrypted Volumes.
[  OK  ] Listening on multipathd control socket.
         Mounting POSIX Message Queue File System...
[  OK  ] Stopped target Initrd File Systems.
         Starting Apply Kernel Variables...
[  OK  ] Listening on LVM2 poll daemon socket.
         Starting Setup Virtual Console...
         Mounting Kernel Debug File System...
         Starting Enable compressed swap in memory using zram...
[  OK  ] Created slice system-systemd\x2dfsck.slice.
[  OK  ] Reached target Paths.
[  OK  ] Listening on LVM2 metadata daemon socket.
[  OK  ] Listening on Device-mapper event daemon FIFOs.
         Starting Monitoring of LVM2 mirrors…ng dmeventd or progress polling...
[  OK  ] Started Journal Service.
[  OK  ] Started Create list of required sta…vice nodes for the current kernel.
[  OK  ] Started Remount Root and Kernel File Systems.
[  OK  ] Mounted Huge Pages File System.
[  OK  ] Mounted POSIX Message Queue File System.
[  OK  ] Started Apply Kernel Variables.
[  OK  ] Mounted Kernel Debug File System.
         Starting Load/Save Random Seed...
         Starting Create Static Device Nodes in /dev...
         Starting Flush Journal to Persistent Storage...
[  OK  ] Started Load/Save Random Seed.
[   23.710585] zram: Added device: zram0
[  OK  ] Started LVM2 metadata daemon.
[   23.786322] systemd-journald[589]: Received request to flush runtime journal from PID 1
[  OK  ] Started udev Coldplug all Devices.
         Starting udev Wait for Complete Device Initialization...
[   23.867503] systemd-journald[589]: File /var/log/journal/8b7ad4cad1bc4af483a12d1a2a46fea0/system.journal corrupted or uncleanly s.
[   24.012707] zram0: detected capacity change from 0 to 480002048
[  OK  ] Started Create Static Device Nodes in /dev.
         Starting udev Kernel Device Manager...
[  OK  ] Started Flush Journal to Persistent Storage.
[  OK  ] Started Setup Virtual Console.
[   24.246031] Adding 468748k swap on /dev/zram0.  Priority:-2 extents:1 across:468748k SSFS
[  OK  ] Started Enable compressed swap in memory using zram.
[  OK  ] Reached target Swap.
         Mounting Temporary Directory (/tmp)...
[  OK  ] Mounted Temporary Directory (/tmp).
[  OK  ] Started udev Kernel Device Manager.
[   24.903843] vc4-drm soc:gpu: failed to bind 3f902000.hdmi (ops vc4_hdmi_ops [vc4]): -517
[   24.924251] vc4-drm soc:gpu: master bind failed: -517
[   24.981132] bcm2835-rng 3f104000.rng: hwrng registered
[   24.985131] bcm2835-wdt 3f100000.watchdog: Broadcom BCM2835 watchdog timer
[   24.996213] vc4-drm soc:gpu: failed to bind 3f902000.hdmi (ops vc4_hdmi_ops [vc4]): -517
[   25.025526] vc4-drm soc:gpu: master bind failed: -517
[   25.038973] vc4-drm soc:gpu: failed to bind 3f902000.hdmi (ops vc4_hdmi_ops [vc4]): -517
[   25.060129] vc4-drm soc:gpu: master bind failed: -517
[   25.073148] vc4-drm soc:gpu: failed to bind 3f902000.hdmi (ops vc4_hdmi_ops [vc4]): -517
[   25.087025] vc4-drm soc:gpu: master bind failed: -517
[  OK  ] Created slice system-lvm2\x2dpvscan.slice.
         Starting LVM2 PV scan on device 179:3...
[   25.257967] Bluetooth: Core ver 2.22
[   25.268077] NET: Registered protocol family 31
[   25.279539] Bluetooth: HCI device and connection manager initialized
[   25.291954] Bluetooth: HCI socket layer initialized
[   25.303033] Bluetooth: L2CAP socket layer initialized
[   25.314132] Bluetooth: SCO socket layer initialized
[   25.469840] Bluetooth: HCI UART driver ver 2.3
[   25.480300] Bluetooth: HCI UART protocol H4 registered
[   25.481330] Registered IR keymap rc-cec
[   25.491136] Bluetooth: HCI UART protocol BCSP registered
[   25.500783] rc rc0: RC for vc4 as /devices/platform/soc/3f902000.hdmi/rc/rc0
[   25.512178] Bluetooth: HCI UART protocol LL registered
[   25.531803] input: RC for vc4 as /devices/platform/soc/3f902000.hdmi/rc/rc0/input4
[   25.535374] Bluetooth: HCI UART protocol ATH3K registered
[   25.560404] Bluetooth: HCI UART protocol Three-wire (H5) registered
[   25.563241] vc4_hdmi 3f902000.hdmi: ASoC: Failed to create component debugfs directory
[   25.572936] Bluetooth: HCI UART protocol Intel registered
[   25.593967] smsc95xx v1.0.6
[   25.598827] Bluetooth: HCI UART protocol Broadcom registered
[   25.617136] vc4_hdmi 3f902000.hdmi: vc4-hdmi-hifi <-> 3f902000.hdmi mapping ok
[   25.618564] uart-pl011 3f201000.serial: no DMA platform data
[   25.654374] Bluetooth: HCI UART protocol QCA registered
[   25.665861] Bluetooth: HCI UART protocol AG6XX registered
[   25.669272] vc4-drm soc:gpu: bound 3f902000.hdmi (ops vc4_hdmi_ops [vc4])
[   25.677466] Bluetooth: HCI UART protocol Marvell registered
[   25.693658] cfg80211: Loading compiled-in X.509 certificates for regulatory database
[   25.694702] vc4-drm soc:gpu: bound 3f806000.vec (ops vc4_vec_ops [vc4])
[   25.694828] vc4-drm soc:gpu: bound 3f400000.hvs (ops vc4_hvs_ops [vc4])
[   25.695049] vc4-drm soc:gpu: bound 3f206000.pixelvalve (ops vc4_crtc_ops [vc4])
[   25.695206] vc4-drm soc:gpu: bound 3f207000.pixelvalve (ops vc4_crtc_ops [vc4])
[   25.695344] vc4-drm soc:gpu: bound 3f807000.pixelvalve (ops vc4_crtc_ops [vc4])
[   25.790413] cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'
[   25.813978] vc4-drm soc:gpu: bound 3fc00000.v3d (ops vc4_v3d_ops [vc4])
[   25.831642] fb: switching to vc4drmfb from EFI VGA
[   25.836642] smsc95xx 1-1.1:1.0 eth0: register 'smsc95xx' at usb-3f980000.usb-1.1, smsc95xx USB 2.0 Ethernet, b8:27:eb:57:38:fb
[   25.860781] Bluetooth: hci0: BCM: chip id 94
[[   25.860966] Console: switching to colour dummy device 80x25
[   25.861114] usbcore: registered new interface driver smsc95xx
  OK     25.865594] Bluetooth: hci0: BCM: features 0x2e
0m] Listening on Load/Sa[   25.884515] kauditd_printk_skb: 13 callbacks suppressed
ve RF Ki[   25.884523] audit: type=1130 audit(1529680328.394:83): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:'
ll Switc[   25.885434] [drm] Initialized vc4 0.0.0 20140616 for soc:gpu on minor 0
h Status[   25.891397] Bluetooth: hci0: BCM43430A1
 /dev/rf[   25.924964] Bluetooth: hci0: BCM43430A1 (001.002.009) build 0000
kill Watch.
[  OK  ] Started Monitoring of LVM2 mirrors,…sing dmeventd or progress polling.
[   25.942207] bluetooth hci0: Direct firmware load for brcm/BCM43430A1.hcd failed with error -2
        [   25.950967] Bluetooth: hci0: BCM: Patch brcm/BCM43430A1.hcd not found
 Starting Load/Save RF Kill Switch Status...
[   25.973513] [drm] Supports vblank timestamp caching Rev 2 (21.10.2013).
[   25.980275] [drm] Driver supports precise vblank timestamp query.
[   26.039719] brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43430-sdio for chip BCM43430/1
[   26.049017] usbcore: registered new interface driver brcmfmac
[   26.056118] [drm:vc4_hdmi_encoder_enable [vc4]] *ERROR* Failed to retain power domain: -13
[   26.091895] brcmfmac mmc1:0001:1: Direct firmware load for brcm/brcmfmac43430-sdio.txt failed with error -2
[   26.121317] ------------[ cut here ]------------
[   26.121325] [CRTC:89:crtc-2] vblank wait timed out
[   26.121507] WARNING: CPU: 2 PID: 39 at drivers/gpu/drm/drm_atomic_helper.c:1362 drm_atomic_helper_wait_for_vblanks.part.8+0x254/0]
[   26.121510] Modules linked in: brcmfmac brcmutil cfg80211 smsc95xx usbnet hci_uart rc_cec mii joydev btqca btbcm btintel bluetoota
[   26.121592] CPU: 2 PID: 39 Comm: kworker/2:1 Not tainted 4.18.5-300.fc29.aarch64 #1
[   26.121594] Hardware name: raspberrypi rpi/rpi, BIOS 2018.09-rc3 09/05/2018
[   26.121608] Workqueue: events deferred_probe_work_func
[   26.121613] pstate: 40400005 (nZcv daif +PAN -UAO)
[   26.121652] pc : drm_atomic_helper_wait_for_vblanks.part.8+0x254/0x268 [drm_kms_helper]
[   26.121688] lr : drm_atomic_helper_wait_for_vblanks.part.8+0x254/0x268 [drm_kms_helper]
[   26.121689] sp : ffff000009bbb360
[   26.121691] x29: ffff000009bbb360 x28: 0000000000000001 
[   26.121695] x27: 0000000000000060 x26: 0000000000000000 
[   26.121699] x25: 0000000000000001 x24: ffff80002bcae800 
[   26.121703] x23: 0000000000000004 x22: 0000000000000030 
[   26.121707] x21: ffff80002e1a7028 x20: ffff800030e84080 
[   26.121712] x19: 0000000000000002 x18: 00000000fffffffd 
[   26.121715] x17: 0000000000000001 x16: 0000000000000007 
[   26.121719] x15: 0000000000000001 x14: ffffffffffffffff 
[   26.121723] x13: 0000000000000000 x12: ffff00000985a000 
[   26.121727] x11: ffff000009693000 x10: ffff00000985a9e8 
[   26.121731] x9 : 0000000000000000 x8 : ffff0000098669ec 
[   26.121735] x7 : 0000000000000000 x6 : 0000000041a496fc 
[   26.121739] x5 : 0000000000000001 x4 : ffff80003e51b218 
[   26.121743] x3 : ffff80003e51b218 x2 : 0000000000000007 
[   26.121747] x1 : 0000000000000006 x0 : 0000000000000026 
[   26.121751] Call trace:
[   26.121788]  drm_atomic_helper_wait_for_vblanks.part.8+0x254/0x268 [drm_kms_helper]
[   26.121824]  drm_atomic_helper_wait_for_vblanks+0x30/0x40 [drm_kms_helper]
[   26.121850]  vc4_atomic_complete_commit+0x218/0x268 [vc4]
[   26.121868]  vc4_atomic_commit+0x140/0x178 [vc4]
[   26.121975]  drm_atomic_commit+0x54/0x60 [drm]
[   26.122011]  restore_fbdev_mode_atomic+0x204/0x228 [drm_kms_helper]
[   26.122047]  restore_fbdev_mode+0x38/0x180 [drm_kms_helper]
[   26.122083]  drm_fb_helper_restore_fbdev_mode_unlocked+0x78/0xd8 [drm_kms_helper]
[   26.122119]  drm_fb_helper_set_par+0x34/0x60 [drm_kms_helper]
[   26.122126]  fbcon_init+0x3ac/0x4f0
[   26.122130]  visual_init+0xb8/0x110
[   26.122134]  do_bind_con_driver+0x1fc/0x3a0
[   26.122137]  do_take_over_console+0x158/0x1f8
[   26.122141]  do_fbcon_takeover+0x70/0xd8
[   26.122145]  fbcon_fb_registered.isra.18+0x110/0x138
[   26.122149]  fbcon_event_notify+0x3fc/0x7c0
[   26.122156]  notifier_call_chain+0x5c/0x98
[   26.122160]  blocking_notifier_call_chain+0x64/0x88
[   26.122163]  fb_notifier_call_chain+0x30/0x40
[   26.122167]  register_framebuffer+0x1e8/0x300
[   26.122202]  __drm_fb_helper_initial_config_and_unlock+0x200/0x400 [drm_kms_helper]
[   26.122237]  drm_fb_helper_initial_config+0x4c/0x58 [drm_kms_helper]
[   26.122273]  drm_fb_cma_fbdev_init_with_funcs+0xb4/0x158 [drm_kms_helper]
[   26.122309]  drm_fb_cma_fbdev_init+0x38/0x48 [drm_kms_helper]
[   26.122327]  vc4_kms_load+0xe8/0x118 [vc4]
[   26.122345]  vc4_drm_bind+0x140/0x160 [vc4]
[   26.122351]  try_to_bring_up_master+0x1ec/0x2c8
[   26.122355]  component_master_add_with_match+0xe0/0x120
[   26.122372]  vc4_platform_drm_probe+0xc8/0xe8 [vc4]
[   26.122376]  platform_drv_probe+0x58/0xa8
[   26.122380]  really_probe+0x1fc/0x3b8
[   26.122383]  driver_probe_device+0x54/0xd8
[   26.122386]  __device_attach_driver+0xb4/0x148
[   26.122389]  bus_for_each_drv+0x78/0xb8
[   26.122392]  __device_attach+0xc8/0x158
[   26.122395]  device_initial_probe+0x24/0x30
[   26.122398]  bus_probe_device+0x9c/0xa8
[   26.122401]  deferred_probe_work_func+0xbc/0x170
[   26.122406]  process_one_work+0x1b0/0x3b8
[   26.122409]  worker_thread+0x48/0x400
[   26.122412]  kthread+0x130/0x138
[   26.122417]  ret_from_fork+0x10/0x18
[   26.122420] ---[ end trace 4360842cd2f17f83 ]---
[   26.122508] Console: switching to colour frame buffer device 240x67
[   27.101593] brcmfmac: brcmf_sdio_htclk: HT Avail timeout (1000000): clkctl 0x50
[   36.311348] [drm:drm_atomic_helper_wait_for_dependencies [drm_kms_helper]] *ERROR* [CRTC:89:crtc-2] flip_done timed out
[   46.551353] [drm:drm_atomic_helper_wait_for_dependencies [drm_kms_helper]] *ERROR* [CONNECTOR:29:HDMI-A-1] flip_done timed out
[   56.791352] [drm:drm_atomic_helper_wait_for_dependencies [drm_kms_helper]] *ERROR* [PLANE:87:plane-20] flip_done timed out
[   56.851246] ------------[ cut here ]------------
[   56.851252] [CRTC:89:crtc-2] vblank wait timed out
[   56.851472] WARNING: CPU: 2 PID: 39 at drivers/gpu/drm/drm_atomic_helper.c:1362 drm_atomic_helper_wait_for_vblanks.part.8+0x254/0]
[   56.851476] Modules linked in: brcmfmac brcmutil cfg80211 smsc95xx usbnet hci_uart rc_cec mii joydev btqca btbcm btintel bluetoota
[   56.851626] CPU: 2 PID: 39 Comm: kworker/2:1 Tainted: G        W         4.18.5-300.fc29.aarch64 #1
[   56.851630] Hardware name: raspberrypi rpi/rpi, BIOS 2018.09-rc3 09/05/2018
[   56.851649] Workqueue: events deferred_probe_work_func
[   56.851659] pstate: 40400005 (nZcv daif +PAN -UAO)
[   56.851732] pc : drm_atomic_helper_wait_for_vblanks.part.8+0x254/0x268 [drm_kms_helper]
[   56.851804] lr : drm_atomic_helper_wait_for_vblanks.part.8+0x254/0x268 [drm_kms_helper]
[   56.851808] sp : ffff000009bbb2c0
[   56.851811] x29: ffff000009bbb2c0 x28: 0000000000000001 
[   56.851819] x27: 0000000000000060 x26: 0000000000000000 
[   56.851827] x25: 0000000000000001 x24: ffff80002bcae800 
[   56.851835] x23: 0000000000000004 x22: 0000000000000030 
[   56.851843] x21: ffff80002e1a7028 x20: ffff800030e84080 
[   56.851850] x19: 0000000000000002 x18: 00000000fffffffd 
[   56.851858] x17: 0000000000000001 x16: 0000000000000007 
[   56.851865] x15: 0000000000000001 x14: ffffffffffffffff 
[   56.851873] x13: 0000000000000000 x12: ffff00000985a000 
[   56.851881] x11: ffff000009693000 x10: ffff00000985a9e8 
[   56.851888] x9 : 0000000000000000 x8 : ffff000009867e60 
[   56.851896] x7 : 0000000000000000 x6 : 0000000041a496fc 
[   56.851903] x5 : 0000000000000001 x4 : ffff80003e51b218 
[   56.851911] x3 : ffff80003e51b218 x2 : 0000000000000007 
[   56.851919] x1 : 0000000000000006 x0 : 0000000000000026 
[   56.851927] Call trace:
[   56.852001]  drm_atomic_helper_wait_for_vblanks.part.8+0x254/0x268 [drm_kms_helper]
[   56.852074]  drm_atomic_helper_wait_for_vblanks+0x30/0x40 [drm_kms_helper]
[   56.852117]  vc4_atomic_complete_commit+0x218/0x268 [vc4]
[   56.852152]  vc4_atomic_commit+0x140/0x178 [vc4]
[   56.852330]  drm_atomic_commit+0x54/0x60 [drm]
[   56.852400]  restore_fbdev_mode_atomic+0x204/0x228 [drm_kms_helper]
[   56.852472]  drm_fb_helper_pan_display+0xa8/0x1c8 [drm_kms_helper]
[   56.852483]  fb_pan_display+0x9c/0x110
[   56.852492]  bit_update_start+0x28/0x50
[   56.852500]  fbcon_switch+0x40c/0x518
[   56.852507]  redraw_screen+0x134/0x228
[   56.852513]  do_bind_con_driver+0x340/0x3a0
[   56.852519]  do_take_over_console+0x158/0x1f8
[   56.852527]  do_fbcon_takeover+0x70/0xd8
[   56.852535]  fbcon_fb_registered.isra.18+0x110/0x138
[   56.852542]  fbcon_event_notify+0x3fc/0x7c0
[   56.852552]  notifier_call_chain+0x5c/0x98
[   56.852559]  blocking_notifier_call_chain+0x64/0x88
[   56.852565]  fb_notifier_call_chain+0x30/0x40
[   56.852571]  register_framebuffer+0x1e8/0x300
[   56.852642]  __drm_fb_helper_initial_config_and_unlock+0x200/0x400 [drm_kms_helper]
[   56.852714]  drm_fb_helper_initial_config+0x4c/0x58 [drm_kms_helper]
[   56.852786]  drm_fb_cma_fbdev_init_with_funcs+0xb4/0x158 [drm_kms_helper]
[   56.852857]  drm_fb_cma_fbdev_init+0x38/0x48 [drm_kms_helper]
[   56.852893]  vc4_kms_load+0xe8/0x118 [vc4]
[   56.852928]  vc4_drm_bind+0x140/0x160 [vc4]
[   56.852938]  try_to_bring_up_master+0x1ec/0x2c8
[   56.852945]  component_master_add_with_match+0xe0/0x120
[   56.852980]  vc4_platform_drm_probe+0xc8/0xe8 [vc4]
[   56.852988]  platform_drv_probe+0x58/0xa8
[   56.852994]  really_probe+0x1fc/0x3b8
[   56.853000]  driver_probe_device+0x54/0xd8
[   56.853006]  __device_attach_driver+0xb4/0x148
[   56.853011]  bus_for_each_drv+0x78/0xb8
[   56.853017]  __device_attach+0xc8/0x158
[   56.853023]  device_initial_probe+0x24/0x30
[   56.853029]  bus_probe_device+0x9c/0xa8
[   56.853035]  deferred_probe_work_func+0xbc/0x170
[   56.853043]  process_one_work+0x1b0/0x3b8
[   56.853050]  worker_thread+0x48/0x400
[   56.853056]  kthread+0x130/0x138
[   56.853064]  ret_from_fork+0x10/0x18
[   56.853069] ---[ end trace 4360842cd2f17f84 ]---
[   59.019459] vc4-drm soc:gpu: fb0:  frame buffer device
[[   59.029399] audit: type=1130 audit(1529680361.534:84): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:init_t:'
  OK  ] Started Load/Save RF Kill Switch Status.
[   59.061946] audit: type=1130 audit(1529680361.574:85): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:init_t:s'
[   59.066282] audit: type=1131 audit(1529680361.574:86): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:init_t:s'
[  OK  ] Started LVM2 PV scan on device 179:3.
[  OK  ] Reached target Sound Card.
[   69.591373] [drm:drm_atomic_helper_wait_for_dependencies [drm_kms_helper]] *ERROR* [CRTC:89:crtc-2] flip_done timed out
[   79.831353] [drm:drm_atomic_helper_wait_for_dependencies [drm_kms_helper]] *ERROR* [CONNECTOR:29:HDMI-A-1] flip_done timed out
[   90.071352] [drm:drm_atomic_helper_wait_for_dependencies [drm_kms_helper]] *ERROR* [PLANE:87:plane-20] flip_done timed out
[   90.131245] ------------[ cut here ]------------
[   90.131252] [CRTC:89:crtc-2] vblank wait timed out
[   90.131472] WARNING: CPU: 2 PID: 666 at drivers/gpu/drm/drm_atomic_helper.c:1362 drm_atomic_helper_wait_for_vblanks.part.8+0x254/]
[   90.131476] Modules linked in: brcmfmac brcmutil cfg80211 smsc95xx usbnet hci_uart rc_cec mii joydev btqca btbcm btintel bluetoota
[   90.131627] CPU: 2 PID: 666 Comm: setfont Tainted: G        W         4.18.5-300.fc29.aarch64 #1
[   90.131630] Hardware name: raspberrypi rpi/rpi, BIOS 2018.09-rc3 09/05/2018
[   90.131638] pstate: 40400005 (nZcv daif +PAN -UAO)
[   90.131710] pc : drm_atomic_helper_wait_for_vblanks.part.8+0x254/0x268 [drm_kms_helper]
[   90.131783] lr : drm_atomic_helper_wait_for_vblanks.part.8+0x254/0x268 [drm_kms_helper]
[   90.131786] sp : ffff00000faab760
[   90.131789] x29: ffff00000faab760 x28: 0000000000000001 
[   90.131797] x27: 0000000000000060 x26: 0000000000000000 
[   90.131805] x25: 0000000000000001 x24: ffff80002bcae800 
[   90.131813] x23: 0000000000000004 x22: 0000000000000030 
[   90.131821] x21: ffff80002e1a7028 x20: ffff800030e87a00 
[   90.131828] x19: 0000000000000002 x18: 00000000fffffffd 
[   90.131836] x17: 0000000000000001 x16: 0000000000000007 
[   90.131843] x15: 0000000000000001 x14: ffffffffffffffff 
[   90.131851] x13: 0000000000000000 x12: ffff00000985a000 
[   90.131859] x11: ffff000009693000 x10: ffff00000985a9e8 
[   90.131866] x9 : 0000000000000000 x8 : ffff000009869568 
[   90.131874] x7 : 0000000000000000 x6 : 0000000041a496fc 
[   90.131881] x5 : 0000000000000001 x4 : ffff80003e51b218 
[   90.131889] x3 : ffff80003e51b218 x2 : 0000000000000007 
[   90.131896] x1 : 0000000000000006 x0 : 0000000000000026 
[   90.131904] Call trace:
[   90.131978]  drm_atomic_helper_wait_for_vblanks.part.8+0x254/0x268 [drm_kms_helper]
[   90.132051]  drm_atomic_helper_wait_for_vblanks+0x30/0x40 [drm_kms_helper]
[   90.132094]  vc4_atomic_complete_commit+0x218/0x268 [vc4]
[   90.132129]  vc4_atomic_commit+0x140/0x178 [vc4]
[   90.132307]  drm_atomic_commit+0x54/0x60 [drm]
[   90.132379]  restore_fbdev_mode_atomic+0x204/0x228 [drm_kms_helper]
[   90.132450]  drm_fb_helper_pan_display+0xa8/0x1c8 [drm_kms_helper]
[   90.132463]  fb_pan_display+0x9c/0x110
[   90.132473]  bit_update_start+0x28/0x50
[   90.132480]  fbcon_switch+0x40c/0x518
[   90.132488]  redraw_screen+0x134/0x228
[   90.132496]  fbcon_do_set_font+0x1d0/0x260
[   90.132503]  fbcon_set_font+0x204/0x258
[   90.132509]  con_font_op+0x384/0x460
[   90.132517]  vt_ioctl+0xd0c/0x1790
[   90.132525]  tty_ioctl+0x23c/0xfd8
[   90.132535]  do_vfs_ioctl+0xb0/0x970
[   90.132542]  ksys_ioctl+0x84/0xb8
[   90.132550]  sys_ioctl+0x34/0x48
[   90.132558]  __sys_trace_return+0x0/0x4
[   90.132563] ---[ end trace 4360842cd2f17f85 ]---
[  OK[   91.223501] audit: type=1130 audit(1529680393.734:87): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:ini'
  ] Started udev Wait for Complete Device Initialization.
[  OK  ] Reached target Local File Systems (Pre).
         Starting File System Check on /dev/…99e-3d95-448b-93c7-b0f8c2dea43f...
         Starting File System Check on /dev/disk/by-uuid/D96B-99CC...
[[   91.542770] audit: type=1130 audit(1529680394.054:88): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:init_t:'
  OK  ] Started File System Check on /dev/disk/by-uuid/D96B-99CC.
[   91.681582] audit: type=1130 audit(1529680394.194:89): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:init_t:s'
2m  OK  ] Started File System Check on /dev/d…1b99e-3d95-448b-93c7-b0f8c2dea43f.
         Mounting /boot...
[   91.796946] EXT4-fs (mmcblk0p2): mounted filesystem with ordered data mode. Opts: (null)
[  OK  ] Mounted /boot.
         Mounting /boot/efi...
[  OK  ] Mounted /boot/efi.
[  OK  ] Reached target Local File Systems.
         Starting Preprocess NFS configuration...
         Starting Restore /run/initramfs on shutdown...
         Starting Tell Plymouth To Write Out Runtime Data...
         Starting Create Volatile Files and Directories...
[   91.955335] audit: type=1130 audit(1529680394.464:90): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:init_t:s'
[  OK  ] Started Restore /run/initramfs on shutdown.
[  OK  ] Started Preprocess NFS configuration.
[   92.011400] audit: type=1130 audit(1529680394.514:91): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:init_t:s'
[   92.011933] audit: type=1131 audit(1529680394.514:92): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:init_t:s'
[  OK  ] Started Tell Plymouth To Write Out Runtime Data.
[   92.099026] audit: type=1130 audit(1529680394.604:93): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:init_t:s'
[   92.099542] audit: type=1131 audit(1529680394.604:94): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:init_t:s'
[  OK  ] Started Create Volatile Files and Directories.
[   92.246622] audit: type=1130 audit(1529680394.754:95): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:init_t:s'
         Mounting RPC Pipe File System...
         Starting Security Auditing Service...
[   92.371756] RPC: Registered named UNIX socket transport module.
[   92.388076] RPC: Registered udp transport module.
[   92.402852] RPC: Registered tcp transport module.
[   92.417531] RPC: Registered tcp NFSv4.1 backchannel transport module.
[   92.435729] audit: type=1305 audit(1529680394.944:96): audit_enabled=1 old=1 auid=4294967295 ses=4294967295 subj=system_u:system_1
[  OK  ] Mounted RPC Pipe File System.
[  OK  ] Reached target rpc_pipefs.target.
[  OK  ] Started Security Auditing Service.
         Starting Update UTMP about System Boot/Shutdown...
[  OK  ] Started Update UTMP about System Boot/Shutdown.
[  OK  ] Reached target System Initialization.
[  OK  ] Started dnf makecache --timer.
[  OK  ] Listening on SSSD Kerberos Cache Manager responder socket.
[  OK  ] Started Daily Cleanup of Temporary Directories.
[  OK  ] Started Updates mlocate database every day.
[  OK  ] Reached target Timers.
[  OK  ] Listening on D-Bus System Message Bus Socket.
[  OK  ] Listening on PC/SC Smart Card Daemon Activation Socket.
         Starting Cockpit Web Service Socket.
[  OK  ] Listening on Cockpit Web Service Socket.
[  OK  ] Reached target Sockets.
[  OK  ] Reached target Basic System.
[  OK  ] Started D-Bus System Message Bus.
[  OK  ] Started Secure Boot DBX (blacklist) updater.
         Starting Login Service...
         Starting Initial Setup configuration program...
         Starting NTP client/server...
[  OK  ] Reached target sshd-keygen.target.
         Starting Bluetooth service...
         Starting Modem Manager...
         Starting ABRT Automated Bug Reporting Tool...
[  OK  ] Started Hardware RNG Entropy Gatherer Daemon.
[  OK  ] Started Self Monitoring and Reporting Technology (SMART) Daemon.
[  OK  ] Started irqbalance daemon.
         Starting GSSAPI Proxy Daemon...
         Starting firewalld - dynamic firewall daemon...
         Starting System Logging Service...
[   94.569545] Bluetooth: BNEP (Ethernet Emulation) ver 1.3
[   94.569551] Bluetooth: BNEP filters: protocol multicast
[   94.569574] Bluetooth: BNEP socket layer initialized
[   98.825253] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready
[   98.915051] smsc95xx 1-1.1:1.0 eth0: hardware isn't capable of remote wakeup
[   98.915373] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready
[  100.740128] bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if.
[  100.951546] [drm:drm_atomic_helper_wait_for_dependencies [drm_kms_helper]] *ERROR* [CRTC:89:crtc-2] flip_done timed out
[  111.191345] [drm:drm_atomic_helper_wait_for_dependencies [drm_kms_helper]] *ERROR* [CONNECTOR:29:HDMI-A-1] flip_done timed out
[  121.431353] [drm:drm_atomic_helper_wait_for_dependencies [drm_kms_helper]] *ERROR* [PLANE:87:plane-20] flip_done timed out
[  121.491260] ------------[ cut here ]------------
[  121.491266] [CRTC:89:crtc-2] vblank wait timed out
[  121.491509] WARNING: CPU: 2 PID: 39 at drivers/gpu/drm/drm_atomic_helper.c:1362 drm_atomic_helper_wait_for_vblanks.part.8+0x254/0]
[  121.491513] Modules linked in: ip6t_rpfilter ip6t_REJECT nf_reject_ipv6 xt_conntrack ebtable_nat ebtable_broute bridge stp llc ip2
[  121.491682]  drm_kms_helper syscopyarea sysfillrect sysimgblt fb_sys_fops drm sdhci_iproc sdhci_pltfm udc_core sdhci i2c_bcm2835 a
[  121.491741] CPU: 2 PID: 39 Comm: kworker/2:1 Tainted: G        W         4.18.5-300.fc29.aarch64 #1
[  121.491745] Hardware name: raspberrypi rpi/rpi, BIOS 2018.09-rc3 09/05/2018
[  121.491820] Workqueue: events output_poll_execute [drm_kms_helper]
[  121.491830] pstate: 40400005 (nZcv daif +PAN -UAO)
[  121.491902] pc : drm_atomic_helper_wait_for_vblanks.part.8+0x254/0x268 [drm_kms_helper]
[  121.491973] lr : drm_atomic_helper_wait_for_vblanks.part.8+0x254/0x268 [drm_kms_helper]
[  121.491977] sp : ffff000009bbba60
[  121.491980] x29: ffff000009bbba60 x28: 0000000000000001 
[  121.491988] x27: 0000000000000060 x26: 0000000000000000 
[  121.491996] x25: 0000000000000001 x24: ffff80002bcae800 
[  121.492004] x23: 0000000000000004 x22: 0000000000000030 
[  121.492011] x21: ffff80002e1a7028 x20: ffff800030e87980 
[  121.492019] x19: 0000000000000002 x18: 00000000fffffffd 
[  121.492027] x17: 0000000000000001 x16: 0000000000000007 
[  121.492034] x15: 0000000000000001 x14: ffffffffffffffff 
[  121.492042] x13: 0000000000000000 x12: ffff00000985a000 
[  121.492050] x11: ffff000009693000 x10: ffff00000985a9e8 
[  121.492058] x9 : 0000000000000000 x8 : ffff00000986b158 
[  121.492065] x7 : 0000000000000000 x6 : 0000000041a496fc 
[  121.492073] x5 : 0000000000000001 x4 : ffff80003e51b218 
[  121.492080] x3 : ffff80003e51b218 x2 : 0000000000000007 
[  121.492088] x1 : 0000000000000006 x0 : 0000000000000026 
[  121.492096] Call trace:
[  121.492167]  drm_atomic_helper_wait_for_vblanks.part.8+0x254/0x268 [drm_kms_helper]
[  121.492239]  drm_atomic_helper_wait_for_vblanks+0x30/0x40 [drm_kms_helper]
[  121.492282]  vc4_atomic_complete_commit+0x218/0x268 [vc4]
[  121.492317]  vc4_atomic_commit+0x140/0x178 [vc4]
[  121.492495]  drm_atomic_commit+0x54/0x60 [drm]
[  121.492566]  restore_fbdev_mode_atomic+0x204/0x228 [drm_kms_helper]
[  121.492639]  restore_fbdev_mode+0x38/0x180 [drm_kms_helper]
[  121.492711]  drm_fb_helper_restore_fbdev_mode_unlocked+0x78/0xd8 [drm_kms_helper]
[  121.492783]  drm_fb_helper_set_par+0x34/0x60 [drm_kms_helper]
[  121.492855]  drm_fb_helper_hotplug_event.part.27+0xa0/0xc8 [drm_kms_helper]
[  121.492926]  drm_fb_helper_output_poll_changed+0x40/0x50 [drm_kms_helper]
[  121.492995]  drm_kms_helper_hotplug_event+0x34/0x40 [drm_kms_helper]
[  121.493064]  output_poll_execute+0x1ac/0x1b8 [drm_kms_helper]
[  121.493078]  process_one_work+0x1b0/0x3b8
[  121.493086]  worker_thread+0x48/0x400
[  121.493092]  kthread+0x130/0x138
[  121.493101]  ret_from_fork+0x10/0x18
[  121.493105] ---[ end trace 4360842cd2f17f86 ]---
[  151.511368] [drm:drm_atomic_helper_wait_for_dependencies [drm_kms_helper]] *ERROR* [CRTC:89:crtc-2] flip_done timed out
[  161.751367] [drm:drm_atomic_helper_wait_for_dependencies [drm_kms_helper]] *ERROR* [CONNECTOR:29:HDMI-A-1] flip_done timed out
[  171.991368] [drm:drm_atomic_helper_wait_for_dependencies [drm_kms_helper]] *ERROR* [PLANE:87:plane-20] flip_done timed out
[  172.051260] ------------[ cut here ]------------
[  172.051266] [CRTC:89:crtc-2] vblank wait timed out
[  172.051513] WARNING: CPU: 1 PID: 1019 at drivers/gpu/drm/drm_atomic_helper.c:1362 drm_atomic_helper_wait_for_vblanks.part.8+0x254]
[  172.051516] Modules linked in: ip6t_rpfilter ip6t_REJECT nf_reject_ipv6 xt_conntrack ebtable_nat ebtable_broute bridge stp llc ip2
[  172.051687]  drm_kms_helper syscopyarea sysfillrect sysimgblt fb_sys_fops drm sdhci_iproc sdhci_pltfm udc_core sdhci i2c_bcm2835 a
[  172.051746] CPU: 1 PID: 1019 Comm: setfont Tainted: G        W         4.18.5-300.fc29.aarch64 #1
[  172.051750] Hardware name: raspberrypi rpi/rpi, BIOS 2018.09-rc3 09/05/2018
[  172.051758] pstate: 40400005 (nZcv daif +PAN -UAO)
[  172.051830] pc : drm_atomic_helper_wait_for_vblanks.part.8+0x254/0x268 [drm_kms_helper]
[  172.051901] lr : drm_atomic_helper_wait_for_vblanks.part.8+0x254/0x268 [drm_kms_helper]
[  172.051905] sp : ffff000010293760
[  172.051908] x29: ffff000010293760 x28: 0000000000000001 
[  172.051916] x27: 0000000000000060 x26: 0000000000000000 
[  172.051924] x25: 0000000000000001 x24: ffff80002bcae800 
[  172.051932] x23: 0000000000000004 x22: 0000000000000030 
[  172.051940] x21: ffff80002e1a7028 x20: ffff8000328ff380 
[  172.051948] x19: 0000000000000002 x18: 00000000fffffffd 
[  172.051955] x17: 0000000000000001 x16: 0000000000000007 
[  172.051963] x15: 0000000000000001 x14: ffffffffffffffff 
[  172.051971] x13: 0000000000000000 x12: ffff00000985a000 
[  172.051978] x11: ffff000009693000 x10: ffff00000985a9e8 
[  172.051986] x9 : 0000000000000000 x8 : ffff00000986c228 
[  172.051994] x7 : 0000000000000000 x6 : 0000000041a496fc 
[  172.052001] x5 : 0000000000000001 x4 : ffff80003e504218 
[  172.052009] x3 : ffff80003e504218 x2 : 0000000000000007 
[  172.052016] x1 : 0000000000000006 x0 : 0000000000000026 
[  172.052024] Call trace:
[  172.052097]  drm_atomic_helper_wait_for_vblanks.part.8+0x254/0x268 [drm_kms_helper]
[  172.052168]  drm_atomic_helper_wait_for_vblanks+0x30/0x40 [drm_kms_helper]
[  172.052211]  vc4_atomic_complete_commit+0x218/0x268 [vc4]
[  172.052247]  vc4_atomic_commit+0x140/0x178 [vc4]
[  172.052424]  drm_atomic_commit+0x54/0x60 [drm]
[  172.052494]  restore_fbdev_mode_atomic+0x204/0x228 [drm_kms_helper]
[  172.052566]  drm_fb_helper_pan_display+0xa8/0x1c8 [drm_kms_helper]
[  172.052579]  fb_pan_display+0x9c/0x110
[  172.052589]  bit_update_start+0x28/0x50
[  172.052597]  fbcon_switch+0x40c/0x518
[  172.052604]  redraw_screen+0x134/0x228
[  172.052612]  fbcon_do_set_font+0x1d0/0x260
[  172.052619]  fbcon_set_font+0x204/0x258
[  172.052625]  con_font_op+0x384/0x460
[  172.052633]  vt_ioctl+0xd0c/0x1790
[  172.052640]  tty_ioctl+0x23c/0xfd8
[  172.052650]  do_vfs_ioctl+0xb0/0x970
[  172.052658]  ksys_ioctl+0x84/0xb8
[  172.052665]  sys_ioctl+0x34/0x48
[  172.052673]  el0_svc_naked+0x30/0x34
[  172.052678] ---[ end trace 4360842cd2f17f87 ]---

After this the initial setup is started, but only shown on the serial console.
The USB keyboard works too.

Comment 7 Sven Anders 2018-10-17 07:23:13 UTC
The problem was a bad HDMI cable. Changed the cable and now it works.
The only mystery is: Why did it work with the "Workstation" edition?

Comment 8 Sven Anders 2018-11-11 20:46:04 UTC
Hello!

Unfortunately I still have the problem. First I though it was gone, but after some more boots the problem reoccured. 

After that I tried, if setting the following kernel options, changes anything:
 vblankoffdelay=-1
 nomodeset

As an additional info: 
I did not enabled any codecs (via licence), if this would make some difference.

Any further ideas what to try?


Note You need to log in before you can comment on or make changes to this bug.