jwatte
Posts: 203
Joined: Sat Aug 13, 2011 7:28 pm

Pi 3 intermittent boot failure; dmesg warning when suceeding

Sat Jan 20, 2018 6:55 pm

I am using a Pi 3, attached to a Waveshare 7" 1024x640 HDMI/USB touchscreen, Ethernet, Logitech keyboard/mouse dongle, and 64 GB USB memory stick mounted on /var/tmp.
I use fake KMS desktop GL, because I also use the camera, but need to run a non-ES (windowed) GL program.
There is also a Teensy 3.2 microcontroller on both USB ttyACM0, and the I2C bus, and ttyS0 UART. There's also a rct-pcf8563 real time clock with battery backup. I have force_turbo=1 and enable_uart=1 in /boot/config.txt (plus necessary incantations to enable the display and RTC)
The whole thing is powered by a 3A/5V regulator that goes into both of the +5V GPIO pins of the pi.

About one time out of three, the Pi will turn on, show the rainbow square on the display, then show about 20 scanlines of noise at the top and dim the square, then the screen goes black as if it's switching into text mode, but it never shows the boot console nor finishes booting.
Two times out of three, it boots fine, boot log on console, GUI comes up (I use auto-login) and I can SSH into it.\
However, there is a warning in dmesg that may or may not be relevant (from drm_irq.c)

What would cause this failure/hang on start-up? How do I debug this?

Code: Select all

[email protected]:~ $ uname -a
Linux raspberrypi 4.9.59-v7+ #1047 SMP Sun Oct 29 12:19:23 GMT 2017 armv7l GNU/Linux
config.txt:

Code: Select all

disable_overscan=1

# Enable audio (loads snd_bcm2835)
dtparam=audio=on
start_x=1
gpu_mem=256

# Waveshare recommendations to make the 7" display go
max_usb_current=1
hdmi_group=2
hdmi_mode=87
hdmi_cvt 1024 600 60 6 0 0 0

config_hdmi_boost=2

dtparam=i2c_arm=on
#dtparam=i2s=on
#dtparam=spi=on

dtoverlay=i2c-rtc,pcf8563
#dtoverlay=i2c-rtc,ds3231

# Uncomment to se the slower UART for Bluetooth
# dtoverlay=pi3-miniuart-bt
enable_uart=1
force_turbo=1

# KMS -- camera doesn't work, EGL doesn't work, desktop GL works
#dtoverlay=vc4-kms-v3d
# fake KMS -- camera works, desktop GL works, EGL doesn't
dtoverlay=vc4-fkms-v3d
dmesg from a successful boot. NOTE THE WARNING in drm_irq.c! (search for "cut here")

Code: Select all

[    0.000000] Booting Linux on physical CPU 0x0
[    0.000000] Linux version 4.9.59-v7+ ([email protected]) (gcc version 4.9.3 (crosstool-NG crosstool-ng-1.22.0-88-g8460611) ) #1047 SMP Sun Oct 29 12:19:23 GMT 2017
[    0.000000] CPU: ARMv7 Processor [410fd034] revision 4 (ARMv7), cr=10c5383d
[    0.000000] CPU: div instructions available: patching division code
[    0.000000] CPU: PIPT / VIPT nonaliasing data cache, VIPT aliasing instruction cache
[    0.000000] OF: fdt:Machine model: Raspberry Pi 3 Model B Rev 1.2
[    0.000000] cma: Reserved 256 MiB at 0x1ec00000
[    0.000000] Memory policy: Data cache writealloc
[    0.000000] On node 0 totalpages: 196608
[    0.000000] free_area_init_node: node 0, pgdat 80c6f380, node_mem_map af8ba000
[    0.000000]   Normal zone: 1728 pages used for memmap
[    0.000000]   Normal zone: 0 pages reserved
[    0.000000]   Normal zone: 196608 pages, LIFO batch:31
[    0.000000] percpu: Embedded 14 pages/cpu @af874000 s25600 r8192 d23552 u57344
[    0.000000] pcpu-alloc: s25600 r8192 d23552 u57344 alloc=14*4096
[    0.000000] pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 
[    0.000000] Built 1 zonelists in Zone order, mobility grouping on.  Total pages: 194880
[    0.000000] Kernel command line: 8250.nr_uarts=1 cma=256M bcm2708_fb.fbwidth=1024 bcm2708_fb.fbheight=600 bcm2708_fb.fbswap=1 vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000  dwc_otg.lpm_enable=0 console=tty1 console=ttyS0,115200 root=PARTUUID=4238718c-02 rootfstype=ext4 elevator=deadline fsck.repair=yes rootwait plymouth.ignore-serial-consoles
[    0.000000] PID hash table entries: 4096 (order: 2, 16384 bytes)
[    0.000000] Dentry cache hash table entries: 131072 (order: 7, 524288 bytes)
[    0.000000] Inode-cache hash table entries: 65536 (order: 6, 262144 bytes)
[    0.000000] Memory: 503216K/786432K available (7168K kernel code, 486K rwdata, 2012K rodata, 1024K init, 770K bss, 21072K reserved, 262144K cma-reserved)
[    0.000000] Virtual kernel memory layout:
                   vector  : 0xffff0000 - 0xffff1000   (   4 kB)
                   fixmap  : 0xffc00000 - 0xfff00000   (3072 kB)
                   vmalloc : 0xb0800000 - 0xff800000   (1264 MB)
                   lowmem  : 0x80000000 - 0xb0000000   ( 768 MB)
                   modules : 0x7f000000 - 0x80000000   (  16 MB)
                     .text : 0x80008000 - 0x80800000   (8160 kB)
                     .init : 0x80b00000 - 0x80c00000   (1024 kB)
                     .data : 0x80c00000 - 0x80c79834   ( 487 kB)
                      .bss : 0x80c7b000 - 0x80d3b964   ( 771 kB)
[    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1
[    0.000000] Hierarchical RCU implementation.
[    0.000000] 	Build-time adjustment of leaf fanout to 32.
[    0.000000] NR_IRQS:16 nr_irqs:16 16
[    0.000000] arm_arch_timer: Architected cp15 timer(s) running at 19.20MHz (phys).
[    0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x46d987e47, max_idle_ns: 440795202767 ns
[    0.000003] sched_clock: 56 bits at 19MHz, resolution 52ns, wraps every 4398046511078ns
[    0.000012] Switching to timer-based delay loop, resolution 52ns
[    0.000167] Console: colour dummy device 80x30
[    0.000627] console [tty1] enabled
[    0.000654] Calibrating delay loop (skipped), value calculated using timer frequency.. 38.40 BogoMIPS (lpj=192000)
[    0.000690] pid_max: default: 32768 minimum: 301
[    0.000866] Mount-cache hash table entries: 2048 (order: 1, 8192 bytes)
[    0.000887] Mountpoint-cache hash table entries: 2048 (order: 1, 8192 bytes)
[    0.001481] Disabling memory control group subsystem
[    0.001538] CPU: Testing write buffer coherency: ok
[    0.001572] ftrace: allocating 22392 entries in 66 pages
[    0.026342] CPU0: update cpu_capacity 1024
[    0.026374] CPU0: thread -1, cpu 0, socket 0, mpidr 80000000
[    0.026413] Setting up static identity map for 0x100000 - 0x100034
[    0.027505] CPU1: update cpu_capacity 1024
[    0.027509] CPU1: thread -1, cpu 1, socket 0, mpidr 80000001
[    0.027911] CPU2: update cpu_capacity 1024
[    0.027915] CPU2: thread -1, cpu 2, socket 0, mpidr 80000002
[    0.028302] CPU3: update cpu_capacity 1024
[    0.028305] CPU3: thread -1, cpu 3, socket 0, mpidr 80000003
[    0.028350] Brought up 4 CPUs
[    0.028438] SMP: Total of 4 processors activated (153.60 BogoMIPS).
[    0.028453] CPU: All CPU(s) started in HYP mode.
[    0.028466] CPU: Virtualization extensions available.
[    0.028936] devtmpfs: initialized
[    0.034843] VFP support v0.3: implementor 41 architecture 3 part 40 variant 3 rev 4
[    0.035016] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns
[    0.035049] futex hash table entries: 1024 (order: 4, 65536 bytes)
[    0.040490] pinctrl core: initialized pinctrl subsystem
[    0.041084] NET: Registered protocol family 16
[    0.042443] DMA: preallocated 1024 KiB pool for atomic coherent allocations
[    0.047047] hw-breakpoint: found 5 (+1 reserved) breakpoint and 4 watchpoint registers.
[    0.047072] hw-breakpoint: maximum watchpoint size is 8 bytes.
[    0.047170] Serial: AMBA PL011 UART driver
[    0.048227] bcm2835-mbox 3f00b880.mailbox: mailbox enabled
[    0.048508] uart-pl011 3f201000.serial: could not find pctldev for node /soc/[email protected]/uart0_pins, deferring probe
[    0.048689] irq: no irq domain found for /soc/[email protected] !
[    0.096761] bcm2835-dma 3f007000.dma: DMA legacy API manager at b080f000, dmachans=0x1
[    0.097727] SCSI subsystem initialized
[    0.097824] usbcore: registered new interface driver usbfs
[    0.097877] usbcore: registered new interface driver hub
[    0.097936] usbcore: registered new device driver usb
[    0.106181] raspberrypi-firmware soc:firmware: Attached to firmware from 2017-10-24 17:13
[    0.106979] clocksource: Switched to clocksource arch_sys_counter
[    0.131610] VFS: Disk quotas dquot_6.6.0
[    0.131675] VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes)
[    0.131807] FS-Cache: Loaded
[    0.131964] CacheFiles: Loaded
[    0.138149] NET: Registered protocol family 2
[    0.138666] TCP established hash table entries: 8192 (order: 3, 32768 bytes)
[    0.138736] TCP bind hash table entries: 8192 (order: 4, 65536 bytes)
[    0.138841] TCP: Hash tables configured (established 8192 bind 8192)
[    0.138909] UDP hash table entries: 512 (order: 2, 16384 bytes)
[    0.138943] UDP-Lite hash table entries: 512 (order: 2, 16384 bytes)
[    0.139067] NET: Registered protocol family 1
[    0.139304] RPC: Registered named UNIX socket transport module.
[    0.139320] RPC: Registered udp transport module.
[    0.139334] RPC: Registered tcp transport module.
[    0.139348] RPC: Registered tcp NFSv4.1 backchannel transport module.
[    0.139946] hw perfevents: enabled with armv7_cortex_a7 PMU driver, 7 counters available
[    0.141176] workingset: timestamp_bits=14 max_order=18 bucket_order=4
[    0.149291] FS-Cache: Netfs 'nfs' registered for caching
[    0.149814] NFS: Registering the id_resolver key type
[    0.149845] Key type id_resolver registered
[    0.149860] Key type id_legacy registered
[    0.151082] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251)
[    0.151160] io scheduler noop registered
[    0.151175] io scheduler deadline registered (default)
[    0.151319] io scheduler cfq registered
[    0.154692] Serial: 8250/16550 driver, 1 ports, IRQ sharing enabled
[    0.155024] bcm2835-aux-uart 3f215040.serial: could not get clk: -517
[    0.155581] bcm2835-rng 3f104000.rng: hwrng registered
[    0.155657] vc-mem: phys_addr:0x00000000 mem_base=0x3ec00000 mem_size:0x40000000(1024 MiB)
[    0.155951] vc-sm: Videocore shared memory driver
[    0.163553] brd: module loaded
[    0.168165] loop: module loaded
[    0.168192] Loading iSCSI transport class v2.0-870.
[    0.168494] usbcore: registered new interface driver smsc95xx
[    0.168517] dwc_otg: version 3.00a 10-AUG-2012 (platform bus)
[    0.396943] Core Release: 2.80a
[    0.396961] Setting default values for core params
[    0.397003] Finished setting default values for core params
[    0.597231] Using Buffer DMA mode
[    0.597246] Periodic Transfer Interrupt Enhancement - disabled
[    0.597260] Multiprocessor Interrupt Enhancement - disabled
[    0.597276] OTG VER PARAM: 0, OTG VER FLAG: 0
[    0.597295] Dedicated Tx FIFOs mode
[    0.597507] WARN::dwc_otg_hcd_init:1032: FIQ DMA bounce buffers: virt = 0x9ed04000 dma = 0xded04000 len=9024
[    0.597543] FIQ FSM acceleration enabled for :
               Non-periodic Split Transactions
               Periodic Split Transactions
               High-Speed Isochronous Endpoints
               Interrupt/Control Split Transaction hack enabled
[    0.597601] dwc_otg: Microframe scheduler enabled
[    0.597626] WARN::hcd_init_fiq:459: FIQ on core 1 at 0x8058f5b0
[    0.597644] WARN::hcd_init_fiq:460: FIQ ASM at 0x8058f920 length 36
[    0.597663] WARN::hcd_init_fiq:486: MPHI regs_base at 0xb08a7000
[    0.597707] dwc_otg 3f980000.usb: DWC OTG Controller
[    0.597738] dwc_otg 3f980000.usb: new USB bus registered, assigned bus number 1
[    0.597775] dwc_otg 3f980000.usb: irq 62, io mem 0x00000000
[    0.597823] Init: Port Power? op_state=1
[    0.597836] Init: Power Port (0)
[    0.597963] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002
[    0.597982] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    0.598006] usb usb1: Product: DWC OTG Controller
[    0.598021] usb usb1: Manufacturer: Linux 4.9.59-v7+ dwc_otg_hcd
[    0.598038] usb usb1: SerialNumber: 3f980000.usb
[    0.598480] hub 1-0:1.0: USB hub found
[    0.598512] hub 1-0:1.0: 1 port detected
[    0.598875] dwc_otg: FIQ enabled
[    0.598877] dwc_otg: NAK holdoff enabled
[    0.598879] dwc_otg: FIQ split-transaction FSM enabled
[    0.598886] Module dwc_common_port init
[    0.599000] usbcore: registered new interface driver usb-storage
[    0.599135] mousedev: PS/2 mouse device common for all mice
[    0.599657] bcm2835-wdt 3f100000.watchdog: Broadcom BCM2835 watchdog timer
[    0.599826] bcm2835-cpufreq: min=1200000 max=1200000
[    0.600054] sdhci: Secure Digital Host Controller Interface driver
[    0.600070] sdhci: Copyright(c) Pierre Ossman
[    0.600234] sdhost-bcm2835 3f202000.sdhost: could not get clk, deferring probe
[    0.602252] mmc-bcm2835 3f300000.mmc: could not get clk, deferring probe
[    0.602317] sdhci-pltfm: SDHCI platform and OF driver helper
[    0.604273] ledtrig-cpu: registered to indicate activity on CPUs
[    0.604387] hidraw: raw HID events driver (C) Jiri Kosina
[    0.604503] usbcore: registered new interface driver usbhid
[    0.604518] usbhid: USB HID core driver
[    0.604970] vchiq: vchiq_init_state: slot_zero = 0x9ed80000, is_master = 0
[    0.606503] [vc_sm_connected_init]: start
[    0.612400] [vc_sm_connected_init]: end - returning 0
[    0.612620] Initializing XFRM netlink socket
[    0.612645] NET: Registered protocol family 17
[    0.612720] Key type dns_resolver registered
[    0.612975] Registering SWP/SWPB emulation handler
[    0.613342] registered taskstats version 1
[    0.617136] uart-pl011 3f201000.serial: cts_event_workaround enabled
[    0.617193] 3f201000.serial: ttyAMA0 at MMIO 0x3f201000 (irq = 87, base_baud = 0) is a PL011 rev2
[    0.618067] console [ttyS0] disabled
[    0.618097] 3f215040.serial: ttyS0 at MMIO 0x0 (irq = 220, base_baud = 50000000) is a 16550
[    1.588496] console [ttyS0] enabled
[    1.592455] sdhost: log_buf @ 9ed07000 (ded07000)
[    1.676991] mmc0: sdhost-bcm2835 loaded - DMA enabled (>1)
[    1.684568] mmc-bcm2835 3f300000.mmc: mmc_debug:0 mmc_debug2:0
[    1.690499] mmc-bcm2835 3f300000.mmc: DMA channel allocated
[    1.716553] Indeed it is in host mode hprt0 = 00021501
[    1.807044] of_cfs_init
[    1.809655] of_cfs_init: OK
[    1.809666] random: fast init done
[    1.816295] Waiting for root device PARTUUID=4238718c-02...
[    1.823741] mmc0: host does not support reading read-only switch, assuming write-enable
[    1.833708] mmc0: new high speed SDXC card at address 0001
[    1.839686] mmcblk0: mmc0:0001 00000 59.6 GiB
[    1.845202]  mmcblk0: p1 p2
[    1.848368] mmc1: queuing unknown CIS tuple 0x80 (2 bytes)
[    1.855435] mmc1: queuing unknown CIS tuple 0x80 (3 bytes)
[    1.862510] mmc1: queuing unknown CIS tuple 0x80 (3 bytes)
[    1.870772] mmc1: queuing unknown CIS tuple 0x80 (7 bytes)
[    1.916996] usb 1-1: new high-speed USB device number 2 using dwc_otg
[    1.923609] Indeed it is in host mode hprt0 = 00001101
[    1.950820] EXT4-fs (mmcblk0p2): mounted filesystem with ordered data mode. Opts: (null)
[    1.959084] VFS: Mounted root (ext4 filesystem) readonly on device 179:2.
[    1.966799] devtmpfs: mounted
[    1.989742] Freeing unused kernel memory: 1024K
[    2.034830] mmc1: new high speed SDIO card at address 0001
[    2.157189] usb 1-1: New USB device found, idVendor=0424, idProduct=9514
[    2.164007] usb 1-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0
[    2.171748] hub 1-1:1.0: USB hub found
[    2.175635] hub 1-1:1.0: 5 ports detected
[    2.399892] systemd[1]: System time before build time, advancing clock.
[    2.497016] usb 1-1.1: new high-speed USB device number 3 using dwc_otg
[    2.505398] NET: Registered protocol family 10
[    2.524173] ip_tables: (C) 2000-2006 Netfilter Core Team
[    2.547463] systemd[1]: systemd 232 running in system mode. (+PAM +AUDIT +SELINUX +IMA +APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD +IDN)
[    2.566097] systemd[1]: Detected architecture arm.
[    2.617661] systemd[1]: Set hostname to <raspberrypi>.
[    2.637210] usb 1-1.1: New USB device found, idVendor=0424, idProduct=ec00
[    2.644205] usb 1-1.1: New USB device strings: Mfr=0, Product=0, SerialNumber=0
[    2.654175] smsc95xx v1.0.5
[    2.749136] smsc95xx 1-1.1:1.0 eth0: register 'smsc95xx' at usb-3f980000.usb-1.1, smsc95xx USB 2.0 Ethernet, b8:27:eb:0c:a4:fb
[    2.981375] systemd[1]: Set up automount Arbitrary Executable File Formats File System Automount Point.
[    3.027235] systemd[1]: Started Forward Password Requests to Wall Directory Watch.
[    3.056996] usb 1-1.2: new high-speed USB device number 4 using dwc_otg
[    3.080770] systemd[1]: Listening on Journal Socket.
[    3.117564] systemd[1]: Created slice System Slice.
[    3.147397] systemd[1]: Created slice system-systemd\x2dfsck.slice.
[    3.187618] usb 1-1.2: New USB device found, idVendor=0781, idProduct=5590
[    3.194605] usb 1-1.2: New USB device strings: Mfr=1, Product=2, SerialNumber=3
[    3.202053] usb 1-1.2: Product: Ultra
[    3.205770] usb 1-1.2: Manufacturer: SanDisk
[    3.209614] systemd[1]: Mounting POSIX Message Queue File System...
[    3.216542] usb 1-1.2: SerialNumber: 4C531001400912109274
[    3.217187] usb-storage 1-1.2:1.0: USB Mass Storage device detected
[    3.228638] scsi host0: usb-storage 1-1.2:1.0
[    3.270489] systemd[1]: Starting Load Kernel Modules...
[    3.285452] i2c /dev entries driver
[    3.327021] usb 1-1.3: new full-speed USB device number 5 using dwc_otg
[    3.459805] usb 1-1.3: New USB device found, idVendor=16c0, idProduct=0483
[    3.466797] usb 1-1.3: New USB device strings: Mfr=1, Product=2, SerialNumber=3
[    3.474254] usb 1-1.3: Product: USB Serial
[    3.478445] usb 1-1.3: Manufacturer: Teensyduino
[    3.478449] usb 1-1.3: SerialNumber: 3764060
[    3.582746] usb 1-1.4: new full-speed USB device number 6 using dwc_otg
[    3.722315] usb 1-1.4: New USB device found, idVendor=046d, idProduct=c52b
[    3.729339] usb 1-1.4: New USB device strings: Mfr=1, Product=2, SerialNumber=0
[    3.736767] usb 1-1.4: Product: USB Receiver
[    3.741115] usb 1-1.4: Manufacturer: Logitech
[    3.847004] usb 1-1.5: new full-speed USB device number 7 using dwc_otg
[    3.980430] usb 1-1.5: New USB device found, idVendor=0eef, idProduct=0005
[    3.987435] usb 1-1.5: New USB device strings: Mfr=1, Product=2, SerialNumber=3
[    3.994860] usb 1-1.5: Product: WaveShare Touchscreen
[    3.999996] usb 1-1.5: Manufacturer: WaveShare
[    4.004511] usb 1-1.5: SerialNumber: 2016-11-06
[    4.241676] scsi 0:0:0:0: Direct-Access     SanDisk  Ultra            1.00 PQ: 0 ANSI: 6
[    4.250927] sd 0:0:0:0: [sda] 121307136 512-byte logical blocks: (62.1 GB/57.8 GiB)
[    4.260252] sd 0:0:0:0: [sda] Write Protect is off
[    4.265365] sd 0:0:0:0: [sda] Mode Sense: 43 00 00 00
[    4.265481] EXT4-fs (mmcblk0p2): re-mounted. Opts: (null)
[    4.271524] sd 0:0:0:0: [sda] Write cache: disabled, read cache: enabled, doesn't support DPO or FUA
[    4.308249]  sda: sda1 sda2
[    4.310550] sd 0:0:0:0: [sda] Attached SCSI removable disk
[    4.590846] systemd-journald[145]: Received request to flush runtime journal from PID 1
[    4.629063] sd 0:0:0:0: Attached scsi generic sg0 type 0
[    4.713914] logitech-djreceiver 0003:046D:C52B.0003: hiddev0,hidraw0: USB HID v1.11 Device [Logitech USB Receiver] on usb-3f980000.usb-1.4/input2
[    4.719738] input: WaveShare WaveShare Touchscreen as /devices/platform/soc/3f980000.usb/usb1/1-1/1-1.5/1-1.5:1.0/0003:0EEF:0005.0004/input/input0
[    4.720104] hid-multitouch 0003:0EEF:0005.0004: input,hidraw1: USB HID v1.11 Device [WaveShare WaveShare Touchscreen] on usb-3f980000.usb-1.5/input0
[    4.878174] gpiomem-bcm2835 3f200000.gpiomem: Initialised: Registers at 0x3f200000
[    5.064002] input: Logitech K400 as /devices/platform/soc/3f980000.usb/usb1/1-1/1-1.4/1-1.4:1.2/0003:046D:C52B.0003/0003:046D:400E.0005/input/input1
[    5.064870] logitech-hidpp-device 0003:046D:400E.0005: input,hidraw2: USB HID v1.11 Keyboard [Logitech K400] on usb-3f980000.usb-1.4:1
[    5.095447] [drm] Initialized
[    5.098204] cdc_acm 1-1.3:1.0: ttyACM0: USB ACM device
[    5.099155] usbcore: registered new interface driver cdc_acm
[    5.099161] cdc_acm: USB Abstract Control Model driver for USB modems and ISDN adapters
[    5.166450] rtc-pcf8563 1-0051: rtc core: registered rtc-pcf8563 as rtc0
[    5.169763] vc4-drm soc:gpu: bound 3f600000.firmwarekms (ops vc4_fkms_ops [vc4])
[    5.174080] brcmfmac: F1 signature read @0x18000000=0x1541a9a6
[    5.175754] ------------[ cut here ]------------
[    5.176038] WARNING: CPU: 0 PID: 164 at drivers/gpu/drm/drm_irq.c:1794 drm_handle_vblank+0x298/0x29c [drm]
[    5.176044] Modules linked in: brcmfmac rtc_pcf8563 vc4(+) drm_kms_helper cdc_acm drm brcmutil hid_logitech_hidpp cfg80211 snd_soc_core snd_compress rfkill snd_pcm_dmaengine syscopyarea snd_bcm2835 sysfillrect sysimgblt fb_sys_fops snd_pcm snd_timer snd i2c_bcm2835 bcm2835_gpiomem hid_multitouch hid_logitech_dj uio_pdrv_genirq uio fixed sg i2c_dev ip_tables x_tables ipv6
[    5.176146] CPU: 0 PID: 164 Comm: systemd-udevd Not tainted 4.9.59-v7+ #1047
[    5.176149] Hardware name: BCM2835
[    5.176187] [<8010fb3c>] (unwind_backtrace) from [<8010c058>] (show_stack+0x20/0x24)
[    5.176201] [<8010c058>] (show_stack) from [<80456764>] (dump_stack+0xd4/0x118)
[    5.176215] [<80456764>] (dump_stack) from [<8011d348>] (__warn+0xf8/0x110)
[    5.176225] [<8011d348>] (__warn) from [<8011d430>] (warn_slowpath_null+0x30/0x38)
[    5.176421] [<8011d430>] (warn_slowpath_null) from [<7f349ca0>] (drm_handle_vblank+0x298/0x29c [drm])
[    5.176745] [<7f349ca0>] (drm_handle_vblank [drm]) from [<7f349cc8>] (drm_crtc_handle_vblank+0x24/0x28 [drm])
[    5.176915] [<7f349cc8>] (drm_crtc_handle_vblank [drm]) from [<7f40d704>] (vc4_crtc_irq_handler+0x4c/0x94 [vc4])
[    5.176972] [<7f40d704>] (vc4_crtc_irq_handler [vc4]) from [<80173fe4>] (__handle_irq_event_percpu+0xc0/0x22c)
[    5.176981] [<80173fe4>] (__handle_irq_event_percpu) from [<8017417c>] (handle_irq_event_percpu+0x2c/0x68)
[    5.176988] [<8017417c>] (handle_irq_event_percpu) from [<8017420c>] (handle_irq_event+0x54/0x78)
[    5.176996] [<8017420c>] (handle_irq_event) from [<8017793c>] (handle_level_irq+0xb0/0x158)
[    5.177004] [<8017793c>] (handle_level_irq) from [<801730ec>] (generic_handle_irq+0x34/0x44)
[    5.177016] [<801730ec>] (generic_handle_irq) from [<8047ef50>] (bcm2836_chained_handle_irq+0x38/0x50)
[    5.177026] [<8047ef50>] (bcm2836_chained_handle_irq) from [<801730ec>] (generic_handle_irq+0x34/0x44)
[    5.177032] [<801730ec>] (generic_handle_irq) from [<80173718>] (__handle_domain_irq+0x6c/0xc4)
[    5.177042] [<80173718>] (__handle_domain_irq) from [<8010150c>] (bcm2836_arm_irqchip_handle_irq+0xa8/0xac)
[    5.177056] [<8010150c>] (bcm2836_arm_irqchip_handle_irq) from [<8071aa0c>] (__irq_usr+0x4c/0x60)
[    5.177060] Exception stack(0x9e1b1fb0 to 0x9e1b1ff8)
[    5.177065] 1fa0:                                     7f0de949 59e3bbc8 d7e0dd51 59e3bbc8
[    5.177071] 1fc0: e64db3ca 8eac0653 8ddf8d84 7f0de949 b8e523a4 7011e060 00000049 7ea64580
[    5.177076] 1fe0: 019cd7c8 7ea644d8 e64db3ca 004b7798 a0000010 ffffffff
[    5.177085] ---[ end trace de4147035cedced7 ]---
[    5.185446] usbcore: registered new interface driver brcmfmac
[    5.194829] vc4-drm soc:gpu: bound 3fc00000.v3d (ops vc4_v3d_ops [vc4])
[    5.195742] [drm] Supports vblank timestamp caching Rev 2 (21.10.2013).
[    5.195750] [drm] Driver supports precise vblank timestamp query.
[    5.209398] Console: switching to colour frame buffer device 128x37
[    5.216327] vc4-drm soc:gpu: fb0:  frame buffer device
[    5.361186] random: crng init done
[    5.374096] brcmfmac: Firmware version = wl0: Aug  7 2017 00:46:29 version 7.45.41.46 (r666254 CY) FWID 01-f8a78378
[    5.374725] brcmfmac: brcmf_c_preinit_dcmds: CLM version = API: 12.2 Data: 7.11.15 Compiler: 1.24.2 ClmImport: 1.24.1 Creation: 2014-05-26 10:53:55 Inc Data: 9.10.41 Inc Compiler: 1.29.4 Inc ClmImport: 1.36.3 Creation: 2017-08-07 00:37:47 
[    6.068658] uart-pl011 3f201000.serial: no DMA platform data
[    6.540523] Adding 102396k swap on /var/swap.  Priority:-1 extents:1 across:102396k SSFS
[    6.830856] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
[    6.830876] brcmfmac: power management disabled
[    7.198367] smsc95xx 1-1.1:1.0 eth0: hardware isn't capable of remote wakeup
[    8.810014] smsc95xx 1-1.1:1.0 eth0: link up, 100Mbps, full-duplex, lpa 0xCDE1
[   10.502771] Bluetooth: Core ver 2.22
[   10.502817] NET: Registered protocol family 31

Return to “Troubleshooting”