vc4-drm soc:gpu: failed to allocate buffer with size 16777216 #135

Joel-Mckay · 2018-07-05T08:57:23Z

I suspect this is not a manifestation of bug #126, but have been unable to identify exactly what causes the vc4-drm to fault and cause the HDMI screen buffer issue.

Any insights would be helpful, as it is great for WebGL when in works....
=)

============================================
Symptoms:
Last two screen buffer states oscillate on mouse move on X. The programs and system continue to run even though the display has locked up.

============================================
Excluding other sources of possible issues:
1.) I disabled the wifi and bluetooth, as the the corruption initially seemed to occur if the wifi card was not associated with a base-station.
This was not related to the issue, but did seem to increase stability to 14 hours. The kernel sometimes warns of various tainted programs, but the cma settings do not seem to change the bug manifestation delay.

2.) I left the display on the LightDM interface as I thought it may be related to the compton GLX mode in MATE/Marco. I also tried the packagers version of the regular compton.conf.
This did not fix the issue.

3.) Switched back from git 4.14.34-rt27-v7+ to the repo kernel 4.14.37-v7+.img
This did not fix the issue.

4.) Switched back from repo firmware images to rpi-update Hexxeh rpi-4.14.y images.
This did not fix the issue.

5.) Just noticed /boot/config.txt did not match cat /proc/cmdline "cma=256M".
I am now testing with gpu_mem=256 to see if the 128 setting was overridden someplace.
This did not fix the issue, so reverted to use "dtoverlay=vc4-kms-v3d,cma-128" to fix setting.

============================================
Affected platforms (the odd part is these should all behave the same):
//hangs the display within 8 hours
Hardware : BCM2835
Revision : a020d3
a020d3 Q1 2018 3 Model B+ 1.3 1 GB (Mfg by Sony)

//hangs the display within 24 hours
Hardware : BCM2835
Revision : a22082
a22082 Q1 2016 3 Model B 1.2 1 GB (Mfg by Embest)

//Seems stable after 6 days with the identical sdcard/settings (very odd)
Revision : a21041
a21041 Q1 2015 2 Model B 1.1 1 GB (Mfg by Embest)

============================================
/boot/cmdline.txt Did not match
cat /proc/cmdline
8250.nr_uarts=1 cma=256M bcm2708_fb.fbwidth=1004 bcm2708_fb.fbheight=748 bcm2708_fb.fbswap=1 vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000 dwc_otg.lpm_enable=0 logo.nologo console=tty3 kgdboc=tty3 loglevel=3 vt.global_cursor_default=0 root=/dev/mmcblk0p2 rootfstype=ext4 elevator=deadline fsck.repair=yes rootwait usbcore.old_scheme_first=N coherent_pool=8M smsc95xx.turbo_mode=N quiet plymouth.ignore-serial-consoles video=1024x768

============================================
/boot/config.txt
#tried repo Raspbian Stretch kernel
kernel=4.14.37-v7+.img
#and the RT kernel branch
#kernel=4.14.34-rt27-v7+.img
hdmi_safe=1
hdmi_force_hotplug=1
hdmi_drive=2
hdmi_force_edid_audio=1
hdmi_group=2
hdmi_mode=16
config_hdmi_boost=4
framebuffer_width=1024
framebuffer_height=768
gpu_mem=128
cma_lwm=64
cma_hwm=96
avoid_safe_mode=1
disable_splash=1
temp_limit=76
max_usb_current=1
dtparam=audio=on
dtparam=i2c_arm=on
dtparam=i2c_arm_baudrate=100000
dtparam=spi=on
dtparam=random=on
dtparam=watchdog=on
enable_uart=1
dtoverlay=vc4-kms-v3d

============================================
/etc/sysctl.conf
#kernel specific flags for the system
vm.dirty_background_bytes=134217728
vm.dirty_bytes=268435456
vm.min_free_kbytes = 16384

============================================
#Debug information
sudo vcdbg log msg
...
debug_sym: vc_mem_copy: Failed to get memory size via ioctl: Device or resource busy(16)
debug_sym: vc_mem_copy: Failed to get memory size via ioctl: Device or resource busy(16)
Failed to allocate -505734863 bytes for message buffer

================================= #kern.log information
Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:42 neptune kernel: [ Jul 4 17:59:43 neptune kernel: [ Jul 4 17:59:44 neptune kernel: [ Jul 4 17:59:44 neptune kernel: [ Jul 4 17:59:44 neptune kernel: [ Jul 4 17:59:44 neptune kernel: [ Jul 4 17:59:44 neptune kernel: [ Jul 4 17:59:44 neptune kernel: [ Jul 4 17:59:44 neptune kernel: [ Jul 4 17:59:44 neptune kernel: [ Jul 4 17:59:45 neptune kernel: [ Jul 4 17:59:50 neptune kernel: [ Jul 4 17:59:50 neptune kernel: [ Jul 4 17:59:50 neptune kernel: [ Jul 4 17:59:50 neptune kernel: [ Jul 4 17:59:50 neptune kernel: [ Jul 4 17:59:50 neptune kernel: [ Jul 4 17:59:50 neptune kernel: [ Jul 4 17:59:50 neptune kernel: [ Jul 4 17:59:50 neptune kernel: [ Jul 4 17:59:50 neptune kernel: [ Jul 4 17:59:50 neptune kernel: [ Jul 4 17:59:50 neptune kernel: [ Jul 4 17:59:50 neptune kernel: [ Jul 4 18:00:08 neptune kernel: [ Jul 4 18:00:08 neptune kernel: [ Jul 4 18:00:08 neptune kernel: [ Jul 4 18:00:23 neptune kernel: [ Jul 4 18:02:27 neptune kernel: [ Jul 4 18:02:27 neptune kernel: [ Jul 4 18:02:27 neptune kernel: [ Jul 4 18:02:27 neptune kernel: [ Jul 4 18:02:27 neptune kernel: [ Jul 4 18:02:27 neptune kernel: [ Jul 4 18:02:27 neptune kernel: [ Jul 4 18:02:27 neptune kernel: [ Jul 4 18:02:27 neptune kernel: [ Jul 4 18:02:27 neptune kernel: [ Jul 4 18:02:27 neptune kernel: [ Jul 4 18:02:27 neptune kernel: [ Jul 4 18:02:27 neptune kernel: [ Jul 4 18:02:27 neptune kernel: [ Jul 4 18:02:27 neptune kernel: [ Jul 4 18:02:27 neptune kernel: [ Jul 4 18:02:27 neptune kernel: [ Jul 4 18:02:27 neptune kernel: [ Jul 4 18:02:27 neptune kernel: [ Jul 4 18:02:27 neptune kernel: [ Jul 4 18:02:27 neptune kernel: [ Jul 4 18:05:40 neptune kernel: [ Jul 4 18:05:40 neptune kernel: [ ===========
0.001519] Disabling memory control group subsystem
0.001607] CPU: Testing write buffer coherency: ok
0.002120] CPU0: thread -1, cpu 0, socket 0, mpidr 80000000
0.010126] Setting up static identity map for 0x100000 - 0x10003c
0.018098] Hierarchical SRCU implementation.
0.034264] smp: Bringing up secondary CPUs ...
0.054831] CPU1: thread -1, cpu 1, socket 0, mpidr 80000001
0.074964] CPU2: thread -1, cpu 2, socket 0, mpidr 80000002
0.095151] CPU3: thread -1, cpu 3, socket 0, mpidr 80000003
0.095314] smp: Brought up 1 node, 4 CPUs
0.095329] SMP: Total of 4 processors activated (153.60 BogoMIPS).
0.095335] CPU: All CPU(s) started in HYP mode.
0.095340] CPU: Virtualization extensions available.
0.096598] devtmpfs: initialized
0.122299] VFP support v0.3: implementor 41 architecture 3 part 40 variant 3 rev 4
0.122673] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 3822520892550000 ns
0.122695] futex hash table entries: 1024 (order: 4, 65536 bytes)
0.134664] pinctrl core: initialized pinctrl subsystem
0.136312] NET: Registered protocol family 16
0.146589] DMA: preallocated 8192 KiB pool for atomic coherent allocations
0.155760] hw-breakpoint: found 5 (+1 reserved) breakpoint and 4 watchpoint registers.
0.155769] hw-breakpoint: maximum watchpoint size is 8 bytes.
0.155993] Serial: AMBA PL011 UART driver
0.158889] bcm2835-mbox 3f00b880.mailbox: mailbox enabled
0.159640] uart-pl011 3f201000.serial: could not find pctldev for node /soc/gpio@7e200000/uart0_pins, deferring probe
0.221352] bcm2835-dma 3f007000.dma: DMA legacy API manager at b8813000, dmachans=0x1
0.223835] SCSI subsystem initialized
0.224295] usbcore: registered new interface driver usbfs
0.224407] usbcore: registered new interface driver hub
0.224562] usbcore: registered new device driver usb
0.226239] raspberrypi-firmware soc:firmware: Attached to firmware from 2018-06-29 13:12
0.228589] clocksource: Switched to clocksource arch_sys_counter
0.376247] VFS: Disk quotas dquot_6.6.0
0.376365] VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes)
0.376850] FS-Cache: Loaded
0.377162] CacheFiles: Loaded
0.393185] NET: Registered protocol family 2
0.394270] TCP established hash table entries: 8192 (order: 3, 32768 bytes)
0.394416] TCP bind hash table entries: 8192 (order: 5, 229376 bytes)
0.395074] TCP: Hash tables configured (established 8192 bind 8192)
0.395307] UDP hash table entries: 512 (order: 3, 32768 bytes)
0.395420] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes)
0.395995] NET: Registered protocol family 1
0.396776] RPC: Registered named UNIX socket transport module.
0.396785] RPC: Registered udp transport module.
0.396794] RPC: Registered tcp transport module.
0.396802] RPC: Registered tcp NFSv4.1 backchannel transport module.
0.399336] hw perfevents: enabled with armv7_cortex_a7 PMU driver, 7 counters available
0.406077] workingset: timestamp_bits=14 max_order=18 bucket_order=4
0.421900] FS-Cache: Netfs 'nfs' registered for caching
0.423253] NFS: Registering the id_resolver key type
0.423301] Key type id_resolver registered
0.423309] Key type id_legacy registered
0.423336] nfs4filelayout_init: NFSv4 File Layout Driver Registering...
0.429915] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251)
0.430114] io scheduler noop registered
0.430124] io scheduler deadline registered (default)
0.430702] io scheduler cfq registered
0.430713] io scheduler mq-deadline registered
0.430722] io scheduler kyber registered
0.435999] Serial: 8250/16550 driver, 1 ports, IRQ sharing enabled
0.438724] bcm2835-rng 3f104000.rng: hwrng registered
0.439070] vc-mem: phys_addr:0x00000000 mem_base=0x3ec00000 mem_size:0x40000000(1024 MiB)
0.439924] vc-sm: Videocore shared memory driver
0.440464] gpiomem-bcm2835 3f200000.gpiomem: Initialised: Registers at 0x3f200000
0.460241] brd: module loaded
0.479870] loop: module loaded
0.479885] Loading iSCSI transport class v2.0-870.
0.480941] libphy: Fixed MDIO Bus: probed
0.481077] usbcore: registered new interface driver lan78xx
0.481173] usbcore: registered new interface driver smsc95xx
0.481194] dwc_otg: version 3.00a 10-AUG-2012 (platform bus)
0.709452] Core Release: 2.80a
0.709463] Setting default values for core params
0.709490] Finished setting default values for core params
0.909976] Using Buffer DMA mode
0.909984] Periodic Transfer Interrupt Enhancement - disabled
0.909990] Multiprocessor Interrupt Enhancement - disabled
0.909998] OTG VER PARAM: 0, OTG VER FLAG: 0
0.910009] Dedicated Tx FIFOs mode
0.914344] WARN::dwc_otg_hcd_init:1046: FIQ DMA bounce buffers: virt = 0x9f404000 dma = 0xdf404000 len=9024
0.914369] FIQ FSM acceleration enabled for :
0.914369] Non-periodic Split Transactions
0.914369] Periodic Split Transactions
0.914369] High-Speed Isochronous Endpoints
0.914369] Interrupt/Control Split Transaction hack enabled
0.914377] dwc_otg: Microframe scheduler enabled
0.914443] WARN::hcd_init_fiq:459: FIQ on core 1 at 0x8061247c
0.914451] WARN::hcd_init_fiq:460: FIQ ASM at 0x806127d4 length 36
0.914459] WARN::hcd_init_fiq:486: MPHI regs_base at 0xb88ab000
0.914521] dwc_otg 3f980000.usb: DWC OTG Controller
0.914560] dwc_otg 3f980000.usb: new USB bus registered, assigned bus number 1
0.914791] dwc_otg 3f980000.usb: irq 62, io mem 0x00000000
0.914839] Init: Port Power? op_state=1
0.914845] Init: Power Port (0)
0.915179] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002
0.915191] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
0.915201] usb usb1: Product: DWC OTG Controller
0.915210] usb usb1: Manufacturer: Linux 4.14.34-rt27-v7+ dwc_otg_hcd
0.915219] usb usb1: SerialNumber: 3f980000.usb
0.916228] hub 1-0:1.0: USB hub found
0.916282] hub 1-0:1.0: 1 port detected
0.917192] dwc_otg: FIQ enabled
0.917199] dwc_otg: NAK holdoff enabled
0.917205] dwc_otg: FIQ split-transaction FSM enabled
0.917220] Module dwc_common_port init
0.917530] usbcore: registered new interface driver usb-storage
0.917848] mousedev: PS/2 mouse device common for all mice
0.918141] IR NEC protocol handler initialized
0.918148] IR RC5(x/sz) protocol handler initialized
0.918154] IR RC6 protocol handler initialized
0.918160] IR JVC protocol handler initialized
0.918166] IR Sony protocol handler initialized
0.918172] IR SANYO protocol handler initialized
0.918178] IR Sharp protocol handler initialized
0.918185] IR MCE Keyboard/mouse protocol handler initialized
0.918191] IR XMP protocol handler initialized
0.919618] bcm2835-wdt 3f100000.watchdog: Broadcom BCM2835 watchdog timer
0.920086] bcm2835-cpufreq: min=600000 max=1400000
0.922285] sdhci: Secure Digital Host Controller Interface driver
0.922288] sdhci: Copyright(c) Pierre Ossman
0.922677] mmc-bcm2835 3f300000.mmc: could not get clk, deferring probe
0.922961] sdhost-bcm2835 3f202000.mmc: could not get clk, deferring probe
0.923061] sdhci-pltfm: SDHCI platform and OF driver helper
0.924386] hidraw: raw HID events driver (C) Jiri Kosina
0.924491] usbcore: registered new interface driver usbhid
0.924494] usbhid: USB HID core driver
0.925066] vchiq: vchiq_init_state: slot_zero = 9f480000, is_master = 0
0.926328] [vc_sm_connected_init]: start
1.118661] Indeed it is in host mode hprt0 = 00021501
1.296609] usb 1-1: new high-speed USB device number 2 using dwc_otg
1.296717] Indeed it is in host mode hprt0 = 00001101
1.488836] usb 1-1: New USB device found, idVendor=0424, idProduct=2514
1.488842] usb 1-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0
1.489351] hub 1-1:1.0: USB hub found
1.489427] hub 1-1:1.0: 4 ports detected
1.744216] [vc_sm_connected_init]: end - returning 0
1.744691] Initializing XFRM netlink socket
1.744713] NET: Registered protocol family 17
1.744844] Key type dns_resolver registered
1.745192] Registering SWP/SWPB emulation handler
1.745711] registered taskstats version 1
1.752687] uart-pl011 3f201000.serial: cts_event_workaround enabled
1.752762] 3f201000.serial: ttyAMA0 at MMIO 0x3f201000 (irq = 87, base_baud = 0) is a PL011 rev2
1.754201] 3f215040.serial: ttyS0 at MMIO 0x0 (irq = 166, base_baud = 31250000) is a 16550
1.754958] mmc-bcm2835 3f300000.mmc: mmc_debug:0 mmc_debug2:0
1.754964] mmc-bcm2835 3f300000.mmc: DMA channel allocated
1.774611] usb 1-1.1: new high-speed USB device number 3 using dwc_otg
1.783018] sdhost: log_buf @ 9f403000 (df403000)
1.800139] mmc1: queuing unknown CIS tuple 0x80 (2 bytes)
1.801680] mmc1: queuing unknown CIS tuple 0x80 (3 bytes)
1.803225] mmc1: queuing unknown CIS tuple 0x80 (3 bytes)
1.806000] mmc1: queuing unknown CIS tuple 0x80 (7 bytes)
1.834639] mmc0: sdhost-bcm2835 loaded - DMA enabled (>1)
1.836234] of_cfs_init
1.836323] of_cfs_init: OK
1.840911] Waiting for root device /dev/mmcblk0p2...
1.870946] usb 1-1.1: New USB device found, idVendor=0424, idProduct=2514
1.870952] usb 1-1.1: New USB device strings: Mfr=0, Product=0, SerialNumber=0
1.871401] hub 1-1.1:1.0: USB hub found
1.871478] hub 1-1.1:1.0: 3 ports detected
1.883340] mmc1: new high speed SDIO card at address 0001
1.925631] mmc0: host does not support reading read-only switch, assuming write-enable
1.928886] mmc0: new high speed SDHC card at address aaaa
1.929190] mmcblk0: mmc0:aaaa SS32G 29.7 GiB
1.930362] mmcblk0: p1 p2
1.955958] EXT4-fs (mmcblk0p2): mounted filesystem with ordered data mode. Opts: (null)
1.956009] VFS: Mounted root (ext4 filesystem) readonly on device 179:2.
1.964180] devtmpfs: mounted
1.966470] Freeing unused kernel memory: 1024K
2.156634] usb 1-1.1.2: new low-speed USB device number 4 using dwc_otg
2.296753] usb 1-1.1.2: New USB device found, idVendor=045e, idProduct=0730
2.296763] usb 1-1.1.2: New USB device strings: Mfr=1, Product=2, SerialNumber=0
2.296768] usb 1-1.1.2: Product: Microsoft® Digital Media Keyboard 3000
2.296772] usb 1-1.1.2: Manufacturer: Microsoft
2.416621] usb 1-1.1.3: new full-speed USB device number 5 using dwc_otg
2.517499] usb 1-1.1.3: New USB device found, idVendor=046d, idProduct=c52e
2.517509] usb 1-1.1.3: New USB device strings: Mfr=1, Product=2, SerialNumber=0
2.517514] usb 1-1.1.3: Product: USB Receiver
2.517518] usb 1-1.1.3: Manufacturer: Logitech
2.521781] input: Logitech USB Receiver as /devices/platform/soc/3f980000.usb/usb1/1-1/1-1.1/1-1.1.3/1-1.1.3:1.0/0003:046D:C52E.0003/input/input0
2.575265] hid-generic 0003:046D:C52E.0003: input,hidraw0: USB HID v1.11 Keyboard [Logitech USB Receiver] on usb-3f980000.usb-1.1.3/input0
2.581436] input: Logitech USB Receiver as /devices/platform/soc/3f980000.usb/usb1/1-1/1-1.1/1-1.1.3/1-1.1.3:1.1/0003:046D:C52E.0004/input/input1
2.581821] NET: Registered protocol family 10
2.582893] Segment Routing with IPv6
2.601348] ip_tables: (C) 2000-2006 Netfilter Core Team
2.635067] hid-generic 0003:046D:C52E.0004: input,hiddev96,hidraw1: USB HID v1.11 Mouse [Logitech USB Receiver] on usb-3f980000.usb-1.1.3/input1
2.836618] usb 1-1.1.1: new high-speed USB device number 6 using dwc_otg
2.932986] usb 1-1.1.1: New USB device found, idVendor=0424, idProduct=7800
2.932996] usb 1-1.1.1: New USB device strings: Mfr=0, Product=0, SerialNumber=0
3.216205] libphy: lan78xx-mdiobus: probed
3.389618] i2c /dev entries driver
3.562064] ip_local_port_range: prefer different parity for start/end values.
3.752866] EXT4-fs (mmcblk0p2): re-mounted. Opts: (null)
4.920391] snd_bcm2835: module is from the staging directory, the quality is unknown, you have been warned.
5.007739] bcm2835_alsa bcm2835_alsa: card created with 8 channels
5.328526] vc4_hdmi 3f902000.hdmi: vc4-hdmi-hifi <-> 3f902000.hdmi mapping ok
5.347706] vc4-drm soc:gpu: bound 3f902000.hdmi (ops vc4_hdmi_ops [vc4])
5.347888] vc4-drm soc:gpu: bound 3f806000.vec (ops vc4_vec_ops [vc4])
5.347957] vc4-drm soc:gpu: bound 3f400000.hvs (ops vc4_hvs_ops [vc4])
5.348253] vc4-drm soc:gpu: bound 3f206000.pixelvalve (ops vc4_crtc_ops [vc4])
5.348462] vc4-drm soc:gpu: bound 3f207000.pixelvalve (ops vc4_crtc_ops [vc4])
5.348771] vc4-drm soc:gpu: bound 3f807000.pixelvalve (ops vc4_crtc_ops [vc4])
5.393935] vc4-drm soc:gpu: bound 3fc00000.v3d (ops vc4_v3d_ops [vc4])
5.436326] [drm] Initialized vc4 0.0.0 20140616 for soc:gpu on minor 0
5.436341] [drm] Supports vblank timestamp caching Rev 2 (21.10.2013).
5.436344] [drm] Driver supports precise vblank timestamp query.
5.443761] brcmfmac: F1 signature read @0x18000000=0x15264345
5.449460] brcmfmac: brcmf_fw_map_chip_to_name: using brcm/brcmfmac43455-sdio.bin for chip 0x004345(17221) rev 0x000006
5.468416] usbcore: registered new interface driver brcmfmac
5.509423] Console: switching to colour frame buffer device 128x48
5.542989] vc4-drm soc:gpu: fb0: frame buffer device
5.734192] brcmfmac: brcmf_c_preinit_dcmds: Firmware version = wl0: Feb 27 2018 03:15:32 version 7.45.154 (r684107 CY) FWID 01-4fbe0b04
5.734819] brcmfmac: brcmf_c_preinit_dcmds: CLM version = API: 12.2 Data: 9.10.105 Compiler: 1.29.4 ClmImport: 1.36.3 Creation: 2018-03-09 18:56:28
6.372137] input: Microsoft Microsoft® Digital Media Keyboard 3000 as /devices/platform/soc/3f980000.usb/usb1/1-1/1-1.1/1-1.1.2/1-1.1.2:1.0/0003:045E:0730.0001/input/input2
6.452475] microsoft 0003:045E:0730.0001: input,hidraw2: USB HID v1.11 Keyboard [Microsoft Microsoft® Digital Media Keyboard 3000] on usb-3f980000.usb-1.1.2/input0
6.467494] input: Microsoft Microsoft® Digital Media Keyboard 3000 as /devices/platform/soc/3f980000.usb/usb1/1-1/1-1.1/1-1.1.2/1-1.1.2:1.1/0003:045E:0730.0002/input/input3
6.524926] microsoft 0003:045E:0730.0002: input,hidraw3: USB HID v1.11 Device [Microsoft Microsoft® Digital Media Keyboard 3000] on usb-3f980000.usb-1.1.2/input1
7.676786] uart-pl011 3f201000.serial: no DMA platform data
8.529763] ------------[ cut here ]------------
8.529789] WARNING: CPU: 1 PID: 96 at kernel/irq/handle.c:151 __handle_irq_event_percpu+0x2cc/0x2d0
8.529797] irq 166 handler irq_default_primary_handler+0x0/0x1c enabled interrupts
8.529801] Modules linked in: binfmt_misc joydev evdev hid_microsoft spidev brcmfmac brcmutil cfg80211 vc4 rfkill drm_kms_helper cec drm snd_soc_core snd_compress snd_bcm2835(C) snd_pcm_dmaengine syscopyarea snd_pcm sysfillrect snd_timer sysimgblt fb_sys_fops snd spi_bcm2835 i2c_bcm2835 uio_pdrv_genirq uio fixed i2c_dev ip_tables x_tables ipv6
8.529873] CPU: 1 PID: 96 Comm: irq/59-bcm2835- Tainted: G C 4.14.34-rt27-v7+ #1
8.529875] Hardware name: BCM2835
8.529890] [<8010fe10>] (unwind_backtrace) from [<8010bf6c>] (show_stack+0x20/0x24)
8.529902] [<8010bf6c>] (show_stack) from [<807b42ac>] (dump_stack+0xbc/0x100)
8.529910] [<807b42ac>] (dump_stack) from [<8011dc1c>] (__warn+0xf8/0x110)
8.529916] [<8011dc1c>] (__warn) from [<8011dc7c>] (warn_slowpath_fmt+0x48/0x50)
8.529922] [<8011dc7c>] (warn_slowpath_fmt) from [<8017b020>] (__handle_irq_event_percpu+0x2cc/0x2d0)
8.529930] [<8017b020>] (__handle_irq_event_percpu) from [<8017b07c>] (handle_irq_event_percpu+0x58/0x94)
8.529937] [<8017b07c>] (handle_irq_event_percpu) from [<8017b130>] (handle_irq_event+0x78/0xbc)
8.529945] [<8017b130>] (handle_irq_event) from [<8017ec54>] (handle_level_irq+0xcc/0x1b4)
8.529955] [<8017ec54>] (handle_level_irq) from [<80179eb8>] (generic_handle_irq+0x34/0x44)
8.529963] [<80179eb8>] (generic_handle_irq) from [<8051f28c>] (bcm2835_auxirq_handler+0x48/0x94)
8.529971] [<8051f28c>] (bcm2835_auxirq_handler) from [<8017c27c>] (irq_forced_thread_fn+0x30/0x84)
8.529978] [<8017c27c>] (irq_forced_thread_fn) from [<8017c5f0>] (irq_thread+0x12c/0x200)
8.529987] [<8017c5f0>] (irq_thread) from [<8013ebc8>] (kthread+0x144/0x174)
8.530004] [<8013ebc8>] (kthread) from [<80107d74>] (ret_from_fork+0x14/0x20)
8.530008] ---[ end trace 0000000000000002 ]---
9.478223] Adding 2047996k swap on /var/swap. Priority:-2 extents:83 across:31054336k SSFS
9.812004] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready
9.812955] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready
9.826492] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
9.858794] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
9.887303] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
9.887317] brcmfmac: power management disabled
10.421057] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
10.421274] brcmfmac: power management disabled
10.830854] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
15.579826] Bluetooth: Core ver 2.22
15.579950] NET: Registered protocol family 31
15.579960] Bluetooth: HCI device and connection manager initialized
15.580042] Bluetooth: HCI socket layer initialized
15.580073] Bluetooth: L2CAP socket layer initialized
15.580207] Bluetooth: SCO socket layer initialized
15.602796] Bluetooth: HCI UART driver ver 2.3
15.602817] Bluetooth: HCI UART protocol H4 registered
15.602826] Bluetooth: HCI UART protocol Three-wire (H5) registered
15.603415] Bluetooth: HCI UART protocol Broadcom registered
15.855950] Bluetooth: BNEP (Ethernet Emulation) ver 1.3
15.855987] Bluetooth: BNEP filters: protocol multicast
15.856016] Bluetooth: BNEP socket layer initialized
33.635433] Bluetooth: RFCOMM TTY layer initialized
33.635477] Bluetooth: RFCOMM socket layer initialized
33.635506] Bluetooth: RFCOMM ver 1.11
38.595172] bcm2835-dma 3f007000.dma: DMA transfer could not be terminated
162.973857] ------------[ cut here ]------------
162.973926] WARNING: CPU: 0 PID: 626 at drivers/gpu/drm/vc4/vc4_plane.c:771 vc4_plane_async_set_fb+0xa4/0xa8 [vc4]
162.973930] Modules linked in: cmac rfcomm bnep hci_uart btbcm serdev bluetooth ecdh_generic binfmt_misc joydev evdev hid_microsoft spidev brcmfmac brcmutil cfg80211 vc4 rfkill drm_kms_helper cec drm snd_soc_core snd_compress snd_bcm2835(C) snd_pcm_dmaengine syscopyarea snd_pcm sysfillrect snd_timer sysimgblt fb_sys_fops snd spi_bcm2835 i2c_bcm2835 uio_pdrv_genirq uio fixed i2c_dev ip_tables x_tables ipv6
162.974013] CPU: 0 PID: 626 Comm: Xorg Tainted: G WC 4.14.34-rt27-v7+ #1
162.974015] Hardware name: BCM2835
162.974038] [<8010fe10>] (unwind_backtrace) from [<8010bf6c>] (show_stack+0x20/0x24)
162.974050] [<8010bf6c>] (show_stack) from [<807b42ac>] (dump_stack+0xbc/0x100)
162.974058] [<807b42ac>] (dump_stack) from [<8011dc1c>] (__warn+0xf8/0x110)
162.974064] [<8011dc1c>] (__warn) from [<8011dd04>] (warn_slowpath_null+0x30/0x38)
162.974093] [<8011dd04>] (warn_slowpath_null) from [<7f346cc4>] (vc4_plane_async_set_fb+0xa4/0xa8 [vc4])
162.974163] [<7f346cc4>] (vc4_plane_async_set_fb [vc4]) from [<7f346da8>] (vc4_update_plane+0xe0/0x168 [vc4])
162.974333] [<7f346da8>] (vc4_update_plane [vc4]) from [<7f260cfc>] (__setplane_internal+0x188/0x224 [drm])
162.974524] [<7f260cfc>] (__setplane_internal [drm]) from [<7f260ec0>] (drm_mode_cursor_universal+0x128/0x1b8 [drm])
162.974711] [<7f260ec0>] (drm_mode_cursor_universal [drm]) from [<7f2610b8>] (drm_mode_cursor_common+0x168/0x1f0 [drm])
162.974898] [<7f2610b8>] (drm_mode_cursor_common [drm]) from [<7f2617d4>] (drm_mode_cursor2_ioctl+0x18/0x1c [drm])
162.975085] [<7f2617d4>] (drm_mode_cursor2_ioctl [drm]) from [<7f246894>] (drm_ioctl_kernel+0x78/0xb8 [drm])
162.975270] [<7f246894>] (drm_ioctl_kernel [drm]) from [<7f246c34>] (drm_ioctl+0x1ec/0x364 [drm])
162.975368] [<7f246c34>] (drm_ioctl [drm]) from [<802afae4>] (do_vfs_ioctl+0xb0/0x7c4)
162.975374] [<802afae4>] (do_vfs_ioctl) from [<802b023c>] (SyS_ioctl+0x44/0x6c)
162.975381] [<802b023c>] (SyS_ioctl) from [<80107cc0>] (ret_fast_syscall+0x0/0x28)
162.975576] ---[ end trace 0000000000000003 ]---
355.546097] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
355.546117] brcmfmac: power management disabled

Jul 5 01:49:14 neptune kernel: [28169.883615] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
Jul 5 01:49:14 neptune kernel: [28169.883642] brcmfmac: power management disabled
Jul 5 01:53:00 neptune kernel: [28395.588624] vc4-drm soc:gpu: failed to allocate buffer with size 16777216
Jul 5 01:53:00 neptune kernel: [28395.672536] vc4-drm soc:gpu: failed to allocate buffer with size 16777216
Jul 5 01:53:00 neptune kernel: [28395.756426] vc4-drm soc:gpu: failed to allocate buffer with size 16777216
Jul 5 01:53:00 neptune kernel: [28395.756496] [drm:vc4_bo_create [vc4]] ERROR Failed to allocate from CMA:
Jul 5 01:53:00 neptune kernel: [28395.756501] [drm] kernel: 3072kb BOs (1)
Jul 5 01:53:00 neptune kernel: [28395.756505] [drm] V3D: 27036kb BOs (45)
Jul 5 01:53:00 neptune kernel: [28395.756508] [drm] V3D shader: 320kb BOs (74)
Jul 5 01:53:00 neptune kernel: [28395.756511] [drm] dumb: 48kb BOs (3)
Jul 5 01:53:00 neptune kernel: [28395.756519] vc4_v3d 3fc00000.v3d: Failed to allocate memory for tile binning: -12. You may need to enable CMA or give it more memory.
Jul 5 01:54:30 neptune kernel: [28485.885612] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
Jul 5 01:54:30 neptune kernel: [28485.885634] brcmfmac: power management disabled
Jul 5 01:59:46 neptune kernel: [28801.845512] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
Jul 5 01:59:46 neptune kernel: [28801.845533] brcmfmac: power management disabled
Jul 5 02:05:02 neptune kernel: [29117.701794] ------------[ cut here ]------------
Jul 5 02:05:02 neptune kernel: [29117.701927] WARNING: CPU: 1 PID: 2550 at drivers/gpu/drm/vc4/vc4_crtc.c:668 vc4_crtc_atomic_flush+0x124/0x128 [vc4]
Jul 5 02:05:02 neptune kernel: [29117.701957] Modules linked in: cmac rfcomm bnep hci_uart btbcm serdev bluetooth ecdh_generic binfmt_misc joydev evdev hid_microsoft spidev brcmfmac brcmutil cfg80211 vc4 rfkill drm_kms_helper cec drm snd_soc_core snd_compress snd_bcm2835(C) snd_pcm_dmaengine syscopyarea snd_pcm sysfillrect snd_timer sysimgblt fb_sys_fops snd spi_bcm2835 i2c_bcm2835 uio_pdrv_genirq uio fixed i2c_dev ip_tables x_tables ipv6
Jul 5 02:05:02 neptune kernel: [29117.702084] CPU: 1 PID: 2550 Comm: kworker/u8:2 Tainted: G WC 4.14.34-rt27-v7+ #1
Jul 5 02:05:02 neptune kernel: [29117.702087] Hardware name: BCM2835
Jul 5 02:05:02 neptune kernel: [29117.702154] Workqueue: events_unbound commit_work [vc4]
Jul 5 02:05:02 neptune kernel: [29117.702187] [<8010fe10>] (unwind_backtrace) from [<8010bf6c>] (show_stack+0x20/0x24)
Jul 5 02:05:02 neptune kernel: [29117.702204] [<8010bf6c>] (show_stack) from [<807b42ac>] (dump_stack+0xbc/0x100)
Jul 5 02:05:02 neptune kernel: [29117.702220] [<807b42ac>] (dump_stack) from [<8011dc1c>] (__warn+0xf8/0x110)
Jul 5 02:05:02 neptune kernel: [29117.702232] [<8011dc1c>] (__warn) from [<8011dd04>] (warn_slowpath_null+0x30/0x38)
Jul 5 02:05:02 neptune kernel: [29117.702301] [<8011dd04>] (warn_slowpath_null) from [<7f338ef0>] (vc4_crtc_atomic_flush+0x124/0x128 [vc4])
Jul 5 02:05:02 neptune kernel: [29117.702498] [<7f338ef0>] (vc4_crtc_atomic_flush [vc4]) from [<7f2dbab0>] (drm_atomic_helper_commit_planes+0x1c4/0x2b0 [drm_kms_helper])
Jul 5 02:05:02 neptune kernel: [29117.702691] [<7f2dbab0>] (drm_atomic_helper_commit_planes [drm_kms_helper]) from [<7f340304>] (vc4_atomic_complete_commit+0x54/0xd8 [vc4])
Jul 5 02:05:02 neptune kernel: [29117.702815] [<7f340304>] (vc4_atomic_complete_commit [vc4]) from [<7f3404d4>] (commit_work+0x1c/0x20 [vc4])
Jul 5 02:05:02 neptune kernel: [29117.702889] [<7f3404d4>] (commit_work [vc4]) from [<80138408>] (process_one_work+0x1fc/0x588)
Jul 5 02:05:02 neptune kernel: [29117.702905] [<80138408>] (process_one_work) from [<8013942c>] (worker_thread+0x60/0x5e4)
Jul 5 02:05:02 neptune kernel: [29117.702923] [<8013942c>] (worker_thread) from [<8013ebc8>] (kthread+0x144/0x174)
Jul 5 02:05:02 neptune kernel: [29117.702941] [<8013ebc8>] (kthread) from [<80107d74>] (ret_from_fork+0x14/0x20)
Jul 5 02:05:02 neptune kernel: [29117.702997] ---[ end trace 0000000000000004 ]---
Jul 5 02:05:02 neptune kernel: [29117.837425] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
Jul 5 02:05:02 neptune kernel: [29117.837443] brcmfmac: power management disabled

stschake · 2018-07-05T10:26:48Z

I assume you are running with 256 MiB of CMA. Since the CMA allocation fails even though you are only using ~30 MiB, you are very likely running into heavy fragmentation in the CMA area. Since CMA is terrible software, this is a near certain event even on moderately used systems.

Personally, I've changed the code to never free the tile binning memory. This means there is always a 16 MiB allocation even if the GPU isn't being used, but it is a good workaround for the fragmentation issue. 16 MiB is a moderately large allocation so it will hit the failure case sooner with fragmentation. Additionally, since the kernel depends on the tile binning memory, the failure is fatal for rendering. When userland request for memory fail the worst that happens is probably your window manager restarting.

Joel-Mckay · 2018-07-05T13:34:49Z

@stschake
Thanks, I was wondering why someone hasn't jettisoned this feature into space yet ;-)

//I have started to re-build the head from 3 days ago:
git clone https://github.com/raspberrypi/linux.git -b "rpi-4.14.y-rt" --depth 1
cd linux

//which branch/patch did you use to fix the cma thrashing bug on?
//todo: git merge https://github.com/anholt/linux.git -b "rpi-4.14.y"

//I also checked the kernel settings made by the default setup template:
sudo modprobe configs
cp /proc/config.gz ./
zcat config.gz > .config

nano .config
CONFIG_DMA_CMA=y
///changed sctipt default: CONFIG_CMA_SIZE_MBYTES=5
//as per: shirishpargaonkar/sspcifso@ebf0892
CONFIG_CMA_SIZE_MBYTES=32

//currently running
make -j4 zImage modules dtbs

6by9 · 2018-07-05T13:46:25Z

Why the change to the config?

The size of the CMA heap is specified on the command line, or via device tree.
cma=256M is present in your cmdline.txt, therefore you should be running with 256MB.
The vc4-kms-v3d overlay also defaults to 256M. https://github.com/raspberrypi/linux/blob/rpi-4.14.y/arch/arm/boot/dts/overlays/vc4-kms-v3d-overlay.dts#L16

Confirm by looking in the kernel log straight after boot. You should get a line similar to

[    0.000000] cma: Reserved 256 MiB at 0x1ec00000

lategoodbye · 2018-07-05T13:53:35Z

@6by9 FWIW, the reason for this defconfig change was the inflexibility of kernelci.org. They don't want platform specific kernel parameter.

6by9 · 2018-07-05T14:06:22Z

@lategoodbye Fair enough, but it's going to make no difference with a build of the downstream 4.14 kernel, dt, and overlays, which is what @Joel-Mckay 's last post was implying he's doing.

@Joel-Mckay NB gpu_mem is not the same as cma. They can (and probably should) be different. gpu_mem is memory assigned to the VideoCore VPU for its use (eg internal buffers for the camera or video codecs). Everything else is then given to the kernel. CMA is the Linux kernel reserving a section of that memory for contiguous allocations.

Joel-Mckay · 2018-07-05T14:59:53Z

@6by9
Indeed, it was my futile attempt to setup various contiguous blocks of memory to figure out why the fault was being generated (did not change the event times). stschake's patch does sound interesting given it may solve 2 different failure modes with long running systems. The part I can't figure out is why a pi2 doesn't fault the same way running the identical software/kernel....

Thus, I am preparing a new kernel to see if:

Done: Verified the issue(s) are still present in 4.14.52-rt31-rc1-v7+ kernel from 3 days ago (our conversation will be about current builds)
Done: The cited cma 32M module minimum wont fix the "make bcm2709_defconfig" builds
todo: When stschake's specific cma patch is listed it can be merged and tested (on 2B / 3B / 3B+)

I will update the log as tests are completed, and I am certainly open to suggestions.

stschake · 2018-07-05T19:34:58Z

You can try this patch, written from memory:

0001-drm-vc4-Keep-the-binner-BO-through-suspend.patch.txt

Can you confirm you are running with 256 MiB CMA? Check the kernel log, the default kernel configuration setting might or might not apply.

There should be no difference to Pi2, but then how much fragmentation you have and how often you are suspend/resuming the GPU depends heavily on usage patterns.

Joel-Mckay · 2018-07-05T19:40:03Z

I am currently testing:
kernel=4.14.52-rt31-rc1-v7+.img
dtoverlay=vc4-kms-v3d,cma-128
avoid_warnings=2
cma_offline_start=128
hdmi_force_hotplug=1
hdmi_drive=2
hdmi_force_edid_audio=1
hdmi_group=2
hdmi_mode=16
config_hdmi_boost=4
overscan_left=10
overscan_right=10
overscan_top=10
overscan_bottom=10
disable_overscan=0
console-width=1024
console-height=700
gpu_mem=256
gpu_mem_256=256
gpu_mem_512=256
gpu_mem_1024=256
#tried to reduce thrashing here
cma_lwm=128
cma_hwm=128
avoid_safe_mode=1
disable_splash=1
temp_limit=76
max_usb_current=1
dtparam=audio=on
dtparam=i2c_arm=on
dtparam=i2c_arm_baudrate=100000
dtparam=spi=on
dtparam=random=on
dtparam=watchdog=on
enable_uart=1

6by9 · 2018-07-05T20:30:46Z

That's a fair number of random entries in config.txt

cma_lwm=64
cma_hwm=96
cma_offline_start=128

should all do nothing - the driver was removed a while back as it was never reliable. raspberrypi#2233 and raspberrypi/firmware@cab026b

gpu_mem_256=256 is going to cause issues on a 256MB Pi as you've just assigned all the memory to the VPU so the ARM gets none. 256MiB is excessive anyway for almost all use cases (the camera is normally the heaviest weight use case and is more than happy on 128).

All of the overscan and hdmi settings are irrelevant as you're using the vc4-kms-v3d driver.
I can't recall if you need avoid_warnings=2 to disable the over-temp and under voltage warnings. I vaguely recall that the firmware disables those automatically if the vc4 driver is loaded.

The rest looks vaguely sane, although give vc4-kms-v3d the normal 256MiB of CMA via dtoverlay=vc4-kms-v3d unless you have a very good reason to do otherwise.

Joel-Mckay · 2018-07-05T20:33:51Z

@stschake
The error no longer seems to hang X with your patch to drivers/gpu/drm/vc4/vc4_v3d.c
Currently testing on pi 3b+, and will let you know how things manifest in the next day.
Thanks again,
=)

Jul 5 20:13:52 neptune kernel: [ 0.000000] Booting Linux on physical CPU 0x0
Jul 5 20:13:52 neptune kernel: [ 0.000000] Linux version 4.14.52-rt31-rc1-v7+ (pi@neptune) (gcc version 6.3.0 20170516 (Raspbian 6.3.0-18+rpi1+deb9u1)) #1 SMP PREEMPT RT Thu Jul 5 15:00:38 UTC 2018
Jul 5 20:13:52 neptune kernel: [ 0.000000] CPU: ARMv7 Processor [410fd034] revision 4 (ARMv7), cr=10c5383d
Jul 5 20:13:52 neptune kernel: [ 0.000000] CPU: div instructions available: patching division code
Jul 5 20:13:52 neptune kernel: [ 0.000000] CPU: PIPT / VIPT nonaliasing data cache, VIPT aliasing instruction cache
Jul 5 20:13:52 neptune kernel: [ 0.000000] OF: fdt: Machine model: Raspberry Pi 3 Model B Plus Rev 1.3
Jul 5 20:13:52 neptune kernel: [ 0.000000] Memory policy: Data cache writealloc
Jul 5 20:13:52 neptune kernel: [ 0.000000] cma: Reserved 128 MiB at 0x26c00000
Jul 5 20:13:52 neptune kernel: [ 0.000000] On node 0 totalpages: 196608
Jul 5 20:13:52 neptune kernel: [ 0.000000] free_area_init_node: node 0, pgdat 80c8a980, node_mem_map af8fa000
Jul 5 20:13:52 neptune kernel: [ 0.000000] Normal zone: 1728 pages used for memmap
Jul 5 20:13:52 neptune kernel: [ 0.000000] Normal zone: 0 pages reserved
Jul 5 20:13:52 neptune kernel: [ 0.000000] Normal zone: 196608 pages, LIFO batch:31
Jul 5 20:13:52 neptune kernel: [ 0.000000] percpu: Embedded 18 pages/cpu @af89f000 s41440 r8192 d24096 u73728
Jul 5 20:13:52 neptune kernel: [ 0.000000] pcpu-alloc: s41440 r8192 d24096 u73728 alloc=18*4096
Jul 5 20:13:52 neptune kernel: [ 0.000000] pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3
Jul 5 20:13:52 neptune kernel: [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 194880
Jul 5 20:13:52 neptune kernel: [ 0.000000] Kernel command line: 8250.nr_uarts=1 cma=128M bcm2708_fb.fbwidth=1004 bcm2708_fb.fbheight=748 bcm2708_fb.fbswap=1 vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000 dwc_otg.lpm_enable=0 logo.nologo console=tty3 kgdboc=tty3 loglevel=3 vt.global_cursor_default=0 root=/dev/mmcblk0p2 rootfstype=ext4 elevator=deadline fsck.repair=yes rootwait usbcore.old_scheme_first=N coherent_pool=8M smsc95xx.turbo_mode=N quiet plymouth.ignore-serial-consoles video=1024x768
Jul 5 20:13:52 neptune kernel: [ 0.000000] PID hash table entries: 4096 (order: 2, 16384 bytes)
Jul 5 20:13:52 neptune kernel: [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes)
Jul 5 20:13:52 neptune kernel: [ 0.000000] Inode-cache hash table entries: 65536 (order: 6, 262144 bytes)
Jul 5 20:13:52 neptune kernel: [ 0.000000] Memory: 633836K/786432K available (7168K kernel code, 581K rwdata, 2108K rodata, 1024K init, 750K bss, 21524K reserved, 131072K cma-reserved)
Jul 5 20:13:52 neptune kernel: [ 0.000000] Virtual kernel memory layout:
Jul 5 20:13:52 neptune kernel: [ 0.000000] vector : 0xffff0000 - 0xffff1000 ( 4 kB)
Jul 5 20:13:52 neptune kernel: [ 0.000000] fixmap : 0xffc00000 - 0xfff00000 (3072 kB)
Jul 5 20:13:52 neptune kernel: [ 0.000000] vmalloc : 0xb0800000 - 0xff800000 (1264 MB)
Jul 5 20:13:52 neptune kernel: [ 0.000000] lowmem : 0x80000000 - 0xb0000000 ( 768 MB)
Jul 5 20:13:52 neptune kernel: [ 0.000000] modules : 0x7f000000 - 0x80000000 ( 16 MB)
Jul 5 20:13:52 neptune kernel: [ 0.000000] .text : 0x80008000 - 0x80800000 (8160 kB)
Jul 5 20:13:52 neptune kernel: [ 0.000000] .init : 0x80b00000 - 0x80c00000 (1024 kB)
Jul 5 20:13:52 neptune kernel: [ 0.000000] .data : 0x80c00000 - 0x80c915c0 ( 582 kB)
Jul 5 20:13:52 neptune kernel: [ 0.000000] .bss : 0x80c992d0 - 0x80d54e9c ( 751 kB)
Jul 5 20:13:52 neptune kernel: [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1
Jul 5 20:13:52 neptune kernel: [ 0.000000] ftrace: allocating 25367 entries in 75 pages
Jul 5 20:13:52 neptune kernel: [ 0.000000] Preemptible hierarchical RCU implementation.
Jul 5 20:13:52 neptune kernel: [ 0.000000] No expedited grace period (rcu_normal_after_boot).
Jul 5 20:13:52 neptune kernel: [ 0.000000] Tasks RCU enabled.
Jul 5 20:13:52 neptune kernel: [ 0.000000] NR_IRQS: 16, nr_irqs: 16, preallocated irqs: 16
Jul 5 20:13:52 neptune kernel: [ 0.000000] arch_timer: cp15 timer(s) running at 19.20MHz (phys).
Jul 5 20:13:52 neptune kernel: [ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x46d987e47, max_idle_ns: 440795202767 ns
Jul 5 20:13:52 neptune kernel: [ 0.000007] sched_clock: 56 bits at 19MHz, resolution 52ns, wraps every 4398046511078ns
Jul 5 20:13:52 neptune kernel: [ 0.000016] Switching to timer-based delay loop, resolution 52ns
Jul 5 20:13:52 neptune kernel: [ 0.000260] Console: colour dummy device 80x30
Jul 5 20:13:52 neptune kernel: [ 0.000282] console [tty3] enabled
Jul 5 20:13:52 neptune kernel: [ 0.000306] Calibrating delay loop (skipped), value calculated using timer frequency.. 38.40 BogoMIPS (lpj=38400)
Jul 5 20:13:52 neptune kernel: [ 0.000318] pid_max: default: 32768 minimum: 301
Jul 5 20:13:52 neptune kernel: [ 0.000631] Mount-cache hash table entries: 2048 (order: 1, 8192 bytes)
Jul 5 20:13:52 neptune kernel: [ 0.000643] Mountpoint-cache hash table entries: 2048 (order: 1, 8192 bytes)
Jul 5 20:13:52 neptune kernel: [ 0.001530] Disabling memory control group subsystem
Jul 5 20:13:52 neptune kernel: [ 0.001620] CPU: Testing write buffer coherency: ok
Jul 5 20:13:52 neptune kernel: [ 0.002136] CPU0: thread -1, cpu 0, socket 0, mpidr 80000000
Jul 5 20:13:52 neptune kernel: [ 0.010133] Setting up static identity map for 0x100000 - 0x10003c
Jul 5 20:13:52 neptune kernel: [ 0.020140] Hierarchical SRCU implementation.
Jul 5 20:13:52 neptune kernel: [ 0.036288] smp: Bringing up secondary CPUs ...
Jul 5 20:13:52 neptune kernel: [ 0.056863] CPU1: thread -1, cpu 1, socket 0, mpidr 80000001
Jul 5 20:13:52 neptune kernel: [ 0.076999] CPU2: thread -1, cpu 2, socket 0, mpidr 80000002
Jul 5 20:13:52 neptune kernel: [ 0.097187] CPU3: thread -1, cpu 3, socket 0, mpidr 80000003
Jul 5 20:13:52 neptune kernel: [ 0.097352] smp: Brought up 1 node, 4 CPUs
Jul 5 20:13:52 neptune kernel: [ 0.097367] SMP: Total of 4 processors activated (153.60 BogoMIPS).
Jul 5 20:13:52 neptune kernel: [ 0.097374] CPU: All CPU(s) started in HYP mode.
Jul 5 20:13:52 neptune kernel: [ 0.097379] CPU: Virtualization extensions available.
Jul 5 20:13:52 neptune kernel: [ 0.098626] devtmpfs: initialized
Jul 5 20:13:52 neptune kernel: [ 0.123662] random: get_random_u32 called from bucket_table_alloc+0x140/0x2ac with crng_init=0
Jul 5 20:13:52 neptune kernel: [ 0.124690] VFP support v0.3: implementor 41 architecture 3 part 40 variant 3 rev 4
Jul 5 20:13:52 neptune kernel: [ 0.125102] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 3822520892550000 ns
Jul 5 20:13:52 neptune kernel: [ 0.125125] futex hash table entries: 1024 (order: 4, 65536 bytes)
Jul 5 20:13:52 neptune kernel: [ 0.131330] pinctrl core: initialized pinctrl subsystem
Jul 5 20:13:52 neptune kernel: [ 0.132912] NET: Registered protocol family 16
Jul 5 20:13:52 neptune kernel: [ 0.143214] DMA: preallocated 8192 KiB pool for atomic coherent allocations
Jul 5 20:13:52 neptune kernel: [ 0.152553] hw-breakpoint: found 5 (+1 reserved) breakpoint and 4 watchpoint registers.
Jul 5 20:13:52 neptune kernel: [ 0.152563] hw-breakpoint: maximum watchpoint size is 8 bytes.
Jul 5 20:13:52 neptune kernel: [ 0.152789] Serial: AMBA PL011 UART driver
Jul 5 20:13:52 neptune kernel: [ 0.155797] bcm2835-mbox 3f00b880.mailbox: mailbox enabled
Jul 5 20:13:52 neptune kernel: [ 0.156480] uart-pl011 3f201000.serial: could not find pctldev for node /soc/gpio@7e200000/uart0_pins, deferring probe
Jul 5 20:13:52 neptune kernel: [ 0.218862] bcm2835-dma 3f007000.dma: DMA legacy API manager at b0813000, dmachans=0x1
Jul 5 20:13:52 neptune kernel: [ 0.221383] SCSI subsystem initialized
Jul 5 20:13:52 neptune kernel: [ 0.221764] usbcore: registered new interface driver usbfs
Jul 5 20:13:52 neptune kernel: [ 0.221859] usbcore: registered new interface driver hub
Jul 5 20:13:52 neptune kernel: [ 0.222032] usbcore: registered new device driver usb
Jul 5 20:13:52 neptune kernel: [ 0.224231] raspberrypi-firmware soc:firmware: Attached to firmware from 2018-06-29 13:12
Jul 5 20:13:52 neptune kernel: [ 0.226614] clocksource: Switched to clocksource arch_sys_counter
Jul 5 20:13:52 neptune kernel: [ 0.377201] VFS: Disk quotas dquot_6.6.0
Jul 5 20:13:52 neptune kernel: [ 0.377318] VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes)
Jul 5 20:13:52 neptune kernel: [ 0.377724] FS-Cache: Loaded
Jul 5 20:13:52 neptune kernel: [ 0.378027] CacheFiles: Loaded
Jul 5 20:13:52 neptune kernel: [ 0.394350] NET: Registered protocol family 2
Jul 5 20:13:52 neptune kernel: [ 0.395517] TCP established hash table entries: 8192 (order: 3, 32768 bytes)
Jul 5 20:13:52 neptune kernel: [ 0.395663] TCP bind hash table entries: 8192 (order: 5, 229376 bytes)
Jul 5 20:13:52 neptune kernel: [ 0.396264] TCP: Hash tables configured (established 8192 bind 8192)
Jul 5 20:13:52 neptune kernel: [ 0.396504] UDP hash table entries: 512 (order: 3, 32768 bytes)
Jul 5 20:13:52 neptune kernel: [ 0.396686] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes)
Jul 5 20:13:52 neptune kernel: [ 0.397254] NET: Registered protocol family 1
Jul 5 20:13:52 neptune kernel: [ 0.397991] RPC: Registered named UNIX socket transport module.
Jul 5 20:13:52 neptune kernel: [ 0.398000] RPC: Registered udp transport module.
Jul 5 20:13:52 neptune kernel: [ 0.398009] RPC: Registered tcp transport module.
Jul 5 20:13:52 neptune kernel: [ 0.398018] RPC: Registered tcp NFSv4.1 backchannel transport module.
Jul 5 20:13:52 neptune kernel: [ 0.400686] hw perfevents: enabled with armv7_cortex_a7 PMU driver, 7 counters available
Jul 5 20:13:52 neptune kernel: [ 0.407637] workingset: timestamp_bits=14 max_order=18 bucket_order=4
Jul 5 20:13:52 neptune kernel: [ 0.423813] FS-Cache: Netfs 'nfs' registered for caching
Jul 5 20:13:52 neptune kernel: [ 0.425208] NFS: Registering the id_resolver key type
Jul 5 20:13:52 neptune kernel: [ 0.425249] Key type id_resolver registered
Jul 5 20:13:52 neptune kernel: [ 0.425257] Key type id_legacy registered
Jul 5 20:13:52 neptune kernel: [ 0.425285] nfs4filelayout_init: NFSv4 File Layout Driver Registering...
Jul 5 20:13:52 neptune kernel: [ 0.432328] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251)
Jul 5 20:13:52 neptune kernel: [ 0.432531] io scheduler noop registered
Jul 5 20:13:52 neptune kernel: [ 0.432542] io scheduler deadline registered (default)
Jul 5 20:13:52 neptune kernel: [ 0.433146] io scheduler cfq registered
Jul 5 20:13:52 neptune kernel: [ 0.433157] io scheduler mq-deadline registered
Jul 5 20:13:52 neptune kernel: [ 0.433166] io scheduler kyber registered
Jul 5 20:13:52 neptune kernel: [ 0.438387] Serial: 8250/16550 driver, 1 ports, IRQ sharing enabled
Jul 5 20:13:52 neptune kernel: [ 0.441100] bcm2835-rng 3f104000.rng: hwrng registered
Jul 5 20:13:52 neptune kernel: [ 0.441367] vc-mem: phys_addr:0x00000000 mem_base=0x3ec00000 mem_size:0x40000000(1024 MiB)
Jul 5 20:13:52 neptune kernel: [ 0.442186] vc-sm: Videocore shared memory driver
Jul 5 20:13:52 neptune kernel: [ 0.442796] gpiomem-bcm2835 3f200000.gpiomem: Initialised: Registers at 0x3f200000
Jul 5 20:13:52 neptune kernel: [ 0.462919] brd: module loaded
Jul 5 20:13:52 neptune kernel: [ 0.482880] loop: module loaded
Jul 5 20:13:52 neptune kernel: [ 0.482895] Loading iSCSI transport class v2.0-870.
Jul 5 20:13:52 neptune kernel: [ 0.483866] libphy: Fixed MDIO Bus: probed
Jul 5 20:13:52 neptune kernel: [ 0.484030] usbcore: registered new interface driver lan78xx
Jul 5 20:13:52 neptune kernel: [ 0.484127] usbcore: registered new interface driver smsc95xx
Jul 5 20:13:52 neptune kernel: [ 0.484148] dwc_otg: version 3.00a 10-AUG-2012 (platform bus)
Jul 5 20:13:52 neptune kernel: [ 0.712406] Core Release: 2.80a
Jul 5 20:13:52 neptune kernel: [ 0.712417] Setting default values for core params
Jul 5 20:13:52 neptune kernel: [ 0.712444] Finished setting default values for core params
Jul 5 20:13:52 neptune kernel: [ 0.913028] Using Buffer DMA mode
Jul 5 20:13:52 neptune kernel: [ 0.913036] Periodic Transfer Interrupt Enhancement - disabled
Jul 5 20:13:52 neptune kernel: [ 0.913042] Multiprocessor Interrupt Enhancement - disabled
Jul 5 20:13:52 neptune kernel: [ 0.913050] OTG VER PARAM: 0, OTG VER FLAG: 0
Jul 5 20:13:52 neptune kernel: [ 0.913061] Dedicated Tx FIFOs mode
Jul 5 20:13:52 neptune kernel: [ 0.917567] WARN::dwc_otg_hcd_init:1046: FIQ DMA bounce buffers: virt = 0xa7404000 dma = 0xe7404000 len=9024
Jul 5 20:13:52 neptune kernel: [ 0.917593] FIQ FSM acceleration enabled for :
Jul 5 20:13:52 neptune kernel: [ 0.917593] Non-periodic Split Transactions
Jul 5 20:13:52 neptune kernel: [ 0.917593] Periodic Split Transactions
Jul 5 20:13:52 neptune kernel: [ 0.917593] High-Speed Isochronous Endpoints
Jul 5 20:13:52 neptune kernel: [ 0.917593] Interrupt/Control Split Transaction hack enabled
Jul 5 20:13:52 neptune kernel: [ 0.917602] dwc_otg: Microframe scheduler enabled
Jul 5 20:13:52 neptune kernel: [ 0.917669] WARN::hcd_init_fiq:459: FIQ on core 1 at 0x80613b2c
Jul 5 20:13:52 neptune kernel: [ 0.917676] WARN::hcd_init_fiq:460: FIQ ASM at 0x80613e84 length 36
Jul 5 20:13:52 neptune kernel: [ 0.917685] WARN::hcd_init_fiq:486: MPHI regs_base at 0xb08ab000
Jul 5 20:13:52 neptune kernel: [ 0.917746] dwc_otg 3f980000.usb: DWC OTG Controller
Jul 5 20:13:52 neptune kernel: [ 0.917786] dwc_otg 3f980000.usb: new USB bus registered, assigned bus number 1
Jul 5 20:13:52 neptune kernel: [ 0.917965] dwc_otg 3f980000.usb: irq 62, io mem 0x00000000
Jul 5 20:13:52 neptune kernel: [ 0.918014] Init: Port Power? op_state=1
Jul 5 20:13:52 neptune kernel: [ 0.918020] Init: Power Port (0)
Jul 5 20:13:52 neptune kernel: [ 0.918354] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002
Jul 5 20:13:52 neptune kernel: [ 0.918366] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
Jul 5 20:13:52 neptune kernel: [ 0.918376] usb usb1: Product: DWC OTG Controller
Jul 5 20:13:52 neptune kernel: [ 0.918385] usb usb1: Manufacturer: Linux 4.14.52-rt31-rc1-v7+ dwc_otg_hcd
Jul 5 20:13:52 neptune kernel: [ 0.918394] usb usb1: SerialNumber: 3f980000.usb
Jul 5 20:13:52 neptune kernel: [ 0.919517] hub 1-0:1.0: USB hub found
Jul 5 20:13:52 neptune kernel: [ 0.919582] hub 1-0:1.0: 1 port detected
Jul 5 20:13:52 neptune kernel: [ 0.920510] dwc_otg: FIQ enabled
Jul 5 20:13:52 neptune kernel: [ 0.920517] dwc_otg: NAK holdoff enabled
Jul 5 20:13:52 neptune kernel: [ 0.920523] dwc_otg: FIQ split-transaction FSM enabled
Jul 5 20:13:52 neptune kernel: [ 0.920538] Module dwc_common_port init
Jul 5 20:13:52 neptune kernel: [ 0.920946] usbcore: registered new interface driver usb-storage
Jul 5 20:13:52 neptune kernel: [ 0.921275] mousedev: PS/2 mouse device common for all mice
Jul 5 20:13:52 neptune kernel: [ 0.921575] IR NEC protocol handler initialized
Jul 5 20:13:52 neptune kernel: [ 0.921582] IR RC5(x/sz) protocol handler initialized
Jul 5 20:13:52 neptune kernel: [ 0.921588] IR RC6 protocol handler initialized
Jul 5 20:13:52 neptune kernel: [ 0.921594] IR JVC protocol handler initialized
Jul 5 20:13:52 neptune kernel: [ 0.921601] IR Sony protocol handler initialized
Jul 5 20:13:52 neptune kernel: [ 0.921607] IR SANYO protocol handler initialized
Jul 5 20:13:52 neptune kernel: [ 0.921613] IR Sharp protocol handler initialized
Jul 5 20:13:52 neptune kernel: [ 0.921620] IR MCE Keyboard/mouse protocol handler initialized
Jul 5 20:13:52 neptune kernel: [ 0.921626] IR XMP protocol handler initialized
Jul 5 20:13:52 neptune kernel: [ 0.923066] bcm2835-wdt 3f100000.watchdog: Broadcom BCM2835 watchdog timer
Jul 5 20:13:52 neptune kernel: [ 0.923555] bcm2835-cpufreq: min=600000 max=1400000
Jul 5 20:13:52 neptune kernel: [ 0.925774] sdhci: Secure Digital Host Controller Interface driver
Jul 5 20:13:52 neptune kernel: [ 0.925777] sdhci: Copyright(c) Pierre Ossman
Jul 5 20:13:52 neptune kernel: [ 0.926122] mmc-bcm2835 3f300000.mmc: could not get clk, deferring probe
Jul 5 20:13:52 neptune kernel: [ 0.926403] sdhost-bcm2835 3f202000.mmc: could not get clk, deferring probe
Jul 5 20:13:52 neptune kernel: [ 0.926503] sdhci-pltfm: SDHCI platform and OF driver helper
Jul 5 20:13:52 neptune kernel: [ 0.927896] hidraw: raw HID events driver (C) Jiri Kosina
Jul 5 20:13:52 neptune kernel: [ 0.928006] usbcore: registered new interface driver usbhid
Jul 5 20:13:52 neptune kernel: [ 0.928009] usbhid: USB HID core driver
Jul 5 20:13:52 neptune kernel: [ 0.928548] vchiq: vchiq_init_state: slot_zero = a7480000, is_master = 0
Jul 5 20:13:52 neptune kernel: [ 0.929837] [vc_sm_connected_init]: start
Jul 5 20:13:52 neptune kernel: [ 1.122685] Indeed it is in host mode hprt0 = 00021501
Jul 5 20:13:52 neptune kernel: [ 1.300645] usb 1-1: new high-speed USB device number 2 using dwc_otg
Jul 5 20:13:52 neptune kernel: [ 1.300751] Indeed it is in host mode hprt0 = 00001101
Jul 5 20:13:52 neptune kernel: [ 1.492902] usb 1-1: New USB device found, idVendor=0424, idProduct=2514
Jul 5 20:13:52 neptune kernel: [ 1.492908] usb 1-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0
Jul 5 20:13:52 neptune kernel: [ 1.493462] hub 1-1:1.0: USB hub found
Jul 5 20:13:52 neptune kernel: [ 1.493540] hub 1-1:1.0: 4 ports detected
Jul 5 20:13:52 neptune kernel: [ 1.717194] [vc_sm_connected_init]: end - returning 0
Jul 5 20:13:52 neptune kernel: [ 1.717630] Initializing XFRM netlink socket
Jul 5 20:13:52 neptune kernel: [ 1.717666] NET: Registered protocol family 17
Jul 5 20:13:52 neptune kernel: [ 1.717786] Key type dns_resolver registered
Jul 5 20:13:52 neptune kernel: [ 1.718138] Registering SWP/SWPB emulation handler
Jul 5 20:13:52 neptune kernel: [ 1.718710] registered taskstats version 1
Jul 5 20:13:52 neptune kernel: [ 1.726074] uart-pl011 3f201000.serial: cts_event_workaround enabled
Jul 5 20:13:52 neptune kernel: [ 1.726151] 3f201000.serial: ttyAMA0 at MMIO 0x3f201000 (irq = 87, base_baud = 0) is a PL011 rev2
Jul 5 20:13:52 neptune kernel: [ 1.727611] 3f215040.serial: ttyS0 at MMIO 0x0 (irq = 166, base_baud = 31250000) is a 16550
Jul 5 20:13:52 neptune kernel: [ 1.728312] mmc-bcm2835 3f300000.mmc: mmc_debug:0 mmc_debug2:0
Jul 5 20:13:52 neptune kernel: [ 1.728317] mmc-bcm2835 3f300000.mmc: DMA channel allocated
Jul 5 20:13:52 neptune kernel: [ 1.757035] sdhost: log_buf @ a7403000 (e7403000)
Jul 5 20:13:52 neptune kernel: [ 1.776101] mmc1: queuing unknown CIS tuple 0x80 (2 bytes)
Jul 5 20:13:52 neptune kernel: [ 1.777639] mmc1: queuing unknown CIS tuple 0x80 (3 bytes)
Jul 5 20:13:52 neptune kernel: [ 1.778631] usb 1-1.1: new high-speed USB device number 3 using dwc_otg
Jul 5 20:13:52 neptune kernel: [ 1.779177] mmc1: queuing unknown CIS tuple 0x80 (3 bytes)
Jul 5 20:13:52 neptune kernel: [ 1.781959] mmc1: queuing unknown CIS tuple 0x80 (7 bytes)
Jul 5 20:13:52 neptune kernel: [ 1.808629] mmc0: sdhost-bcm2835 loaded - DMA enabled (>1)
Jul 5 20:13:52 neptune kernel: [ 1.810281] of_cfs_init
Jul 5 20:13:52 neptune kernel: [ 1.810370] of_cfs_init: OK
Jul 5 20:13:52 neptune kernel: [ 1.814954] Waiting for root device /dev/mmcblk0p2...
Jul 5 20:13:52 neptune kernel: [ 1.859550] mmc1: new high speed SDIO card at address 0001
Jul 5 20:13:52 neptune kernel: [ 1.874941] usb 1-1.1: New USB device found, idVendor=0424, idProduct=2514
Jul 5 20:13:52 neptune kernel: [ 1.874947] usb 1-1.1: New USB device strings: Mfr=0, Product=0, SerialNumber=0
Jul 5 20:13:52 neptune kernel: [ 1.875390] hub 1-1.1:1.0: USB hub found
Jul 5 20:13:52 neptune kernel: [ 1.875466] hub 1-1.1:1.0: 3 ports detected
Jul 5 20:13:52 neptune kernel: [ 1.897489] mmc0: host does not support reading read-only switch, assuming write-enable
Jul 5 20:13:52 neptune kernel: [ 1.900565] mmc0: new high speed SDHC card at address aaaa
Jul 5 20:13:52 neptune kernel: [ 1.900948] mmcblk0: mmc0:aaaa SS32G 29.7 GiB
Jul 5 20:13:52 neptune kernel: [ 1.902107] mmcblk0: p1 p2
Jul 5 20:13:52 neptune kernel: [ 1.917196] EXT4-fs (mmcblk0p2): mounted filesystem with ordered data mode. Opts: (null)
Jul 5 20:13:52 neptune kernel: [ 1.917251] VFS: Mounted root (ext4 filesystem) readonly on device 179:2.
Jul 5 20:13:52 neptune kernel: [ 1.925650] devtmpfs: mounted
Jul 5 20:13:52 neptune kernel: [ 1.927910] Freeing unused kernel memory: 1024K
Jul 5 20:13:52 neptune kernel: [ 2.160665] usb 1-1.1.2: new low-speed USB device number 4 using dwc_otg
Jul 5 20:13:52 neptune kernel: [ 2.300778] usb 1-1.1.2: New USB device found, idVendor=045e, idProduct=0730
Jul 5 20:13:52 neptune kernel: [ 2.300787] usb 1-1.1.2: New USB device strings: Mfr=1, Product=2, SerialNumber=0
Jul 5 20:13:52 neptune kernel: [ 2.300792] usb 1-1.1.2: Product: Microsoft® Digital Media Keyboard 3000
Jul 5 20:13:52 neptune kernel: [ 2.300796] usb 1-1.1.2: Manufacturer: Microsoft
Jul 5 20:13:52 neptune kernel: [ 2.420632] usb 1-1.1.3: new full-speed USB device number 5 using dwc_otg
Jul 5 20:13:52 neptune kernel: [ 2.521522] usb 1-1.1.3: New USB device found, idVendor=046d, idProduct=c52e
Jul 5 20:13:52 neptune kernel: [ 2.521531] usb 1-1.1.3: New USB device strings: Mfr=1, Product=2, SerialNumber=0
Jul 5 20:13:52 neptune kernel: [ 2.521536] usb 1-1.1.3: Product: USB Receiver
Jul 5 20:13:52 neptune kernel: [ 2.521540] usb 1-1.1.3: Manufacturer: Logitech
Jul 5 20:13:52 neptune kernel: [ 2.527090] input: Logitech USB Receiver as /devices/platform/soc/3f980000.usb/usb1/1-1/1-1.1/1-1.1.3/1-1.1.3:1.0/0003:046D:C52E.0003/input/input0
Jul 5 20:13:52 neptune kernel: [ 2.541538] NET: Registered protocol family 10
Jul 5 20:13:52 neptune kernel: [ 2.542648] Segment Routing with IPv6
Jul 5 20:13:52 neptune kernel: [ 2.555229] ip_tables: (C) 2000-2006 Netfilter Core Team
Jul 5 20:13:52 neptune kernel: [ 2.573569] random: systemd: uninitialized urandom read (16 bytes read)
Jul 5 20:13:52 neptune kernel: [ 2.581302] hid-generic 0003:046D:C52E.0003: input,hidraw0: USB HID v1.11 Keyboard [Logitech USB Receiver] on usb-3f980000.usb-1.1.3/input0
Jul 5 20:13:52 neptune kernel: [ 2.586993] input: Logitech USB Receiver as /devices/platform/soc/3f980000.usb/usb1/1-1/1-1.1/1-1.1.3/1-1.1.3:1.1/0003:046D:C52E.0004/input/input1
Jul 5 20:13:52 neptune kernel: [ 2.608969] random: systemd: uninitialized urandom read (16 bytes read)
Jul 5 20:13:52 neptune kernel: [ 2.641726] hid-generic 0003:046D:C52E.0004: input,hiddev96,hidraw1: USB HID v1.11 Mouse [Logitech USB Receiver] on usb-3f980000.usb-1.1.3/input1
Jul 5 20:13:52 neptune kernel: [ 2.652775] random: systemd-cryptse: uninitialized urandom read (16 bytes read)
Jul 5 20:13:52 neptune kernel: [ 2.842750] usb 1-1.1.1: new high-speed USB device number 6 using dwc_otg
Jul 5 20:13:52 neptune kernel: [ 2.939057] usb 1-1.1.1: New USB device found, idVendor=0424, idProduct=7800
Jul 5 20:13:52 neptune kernel: [ 2.939067] usb 1-1.1.1: New USB device strings: Mfr=0, Product=0, SerialNumber=0
Jul 5 20:13:52 neptune kernel: [ 3.222471] libphy: lan78xx-mdiobus: probed
Jul 5 20:13:52 neptune kernel: [ 3.336274] i2c /dev entries driver
Jul 5 20:13:52 neptune kernel: [ 3.497482] ip_local_port_range: prefer different parity for start/end values.
Jul 5 20:13:52 neptune kernel: [ 3.702670] EXT4-fs (mmcblk0p2): re-mounted. Opts: (null)
Jul 5 20:13:52 neptune kernel: [ 5.334181] snd_bcm2835: module is from the staging directory, the quality is unknown, you have been warned.
Jul 5 20:13:52 neptune kernel: [ 5.369343] bcm2835_alsa bcm2835_alsa: card created with 8 channels
Jul 5 20:13:52 neptune kernel: [ 5.656960] vc4_hdmi 3f902000.hdmi: vc4-hdmi-hifi <-> 3f902000.hdmi mapping ok
Jul 5 20:13:52 neptune kernel: [ 5.668701] brcmfmac: F1 signature read @0x18000000=0x15264345
Jul 5 20:13:52 neptune kernel: [ 5.674891] brcmfmac: brcmf_fw_map_chip_to_name: using brcm/brcmfmac43455-sdio.bin for chip 0x004345(17221) rev 0x000006
Jul 5 20:13:52 neptune kernel: [ 5.678980] vc4-drm soc:gpu: bound 3f902000.hdmi (ops vc4_hdmi_ops [vc4])
Jul 5 20:13:52 neptune kernel: [ 5.679140] vc4-drm soc:gpu: bound 3f806000.vec (ops vc4_vec_ops [vc4])
Jul 5 20:13:52 neptune kernel: [ 5.679198] vc4-drm soc:gpu: bound 3f400000.hvs (ops vc4_hvs_ops [vc4])
Jul 5 20:13:52 neptune kernel: [ 5.679557] vc4-drm soc:gpu: bound 3f206000.pixelvalve (ops vc4_crtc_ops [vc4])
Jul 5 20:13:52 neptune kernel: [ 5.679845] vc4-drm soc:gpu: bound 3f207000.pixelvalve (ops vc4_crtc_ops [vc4])
Jul 5 20:13:52 neptune kernel: [ 5.680148] vc4-drm soc:gpu: bound 3f807000.pixelvalve (ops vc4_crtc_ops [vc4])
Jul 5 20:13:52 neptune kernel: [ 5.687095] usbcore: registered new interface driver brcmfmac
Jul 5 20:13:52 neptune kernel: [ 5.708473] vc4-drm soc:gpu: bound 3fc00000.v3d (ops vc4_v3d_ops [vc4])
Jul 5 20:13:52 neptune kernel: [ 5.731533] [drm] Initialized vc4 0.0.0 20140616 for soc:gpu on minor 0
Jul 5 20:13:52 neptune kernel: [ 5.731546] [drm] Supports vblank timestamp caching Rev 2 (21.10.2013).
Jul 5 20:13:52 neptune kernel: [ 5.731549] [drm] Driver supports precise vblank timestamp query.
Jul 5 20:13:52 neptune kernel: [ 5.786943] Console: switching to colour frame buffer device 128x48
Jul 5 20:13:52 neptune kernel: [ 5.811762] vc4-drm soc:gpu: fb0: frame buffer device
Jul 5 20:13:52 neptune kernel: [ 5.984205] brcmfmac: brcmf_c_preinit_dcmds: Firmware version = wl0: Feb 27 2018 03:15:32 version 7.45.154 (r684107 CY) FWID 01-4fbe0b04
Jul 5 20:13:52 neptune kernel: [ 5.984877] brcmfmac: brcmf_c_preinit_dcmds: CLM version = API: 12.2 Data: 9.10.105 Compiler: 1.29.4 ClmImport: 1.36.3 Creation: 2018-03-09 18:56:28
Jul 5 20:13:52 neptune kernel: [ 6.136388] input: Microsoft Microsoft® Digital Media Keyboard 3000 as /devices/platform/soc/3f980000.usb/usb1/1-1/1-1.1/1-1.1.2/1-1.1.2:1.0/0003:045E:0730.0001/input/input2
Jul 5 20:13:52 neptune kernel: [ 6.219312] microsoft 0003:045E:0730.0001: input,hidraw2: USB HID v1.11 Keyboard [Microsoft Microsoft® Digital Media Keyboard 3000] on usb-3f980000.usb-1.1.2/input0
Jul 5 20:13:52 neptune kernel: [ 6.230502] input: Microsoft Microsoft® Digital Media Keyboard 3000 as /devices/platform/soc/3f980000.usb/usb1/1-1/1-1.1/1-1.1.2/1-1.1.2:1.1/0003:045E:0730.0002/input/input3
Jul 5 20:13:52 neptune kernel: [ 6.289334] microsoft 0003:045E:0730.0002: input,hidraw3: USB HID v1.11 Device [Microsoft Microsoft® Digital Media Keyboard 3000] on usb-3f980000.usb-1.1.2/input1
Jul 5 20:13:52 neptune kernel: [ 7.920876] uart-pl011 3f201000.serial: no DMA platform data
Jul 5 20:13:52 neptune kernel: [ 8.371852] ------------[ cut here ]------------
Jul 5 20:13:52 neptune kernel: [ 8.371884] WARNING: CPU: 2 PID: 98 at kernel/irq/handle.c:151 __handle_irq_event_percpu+0x2cc/0x2d0
Jul 5 20:13:52 neptune kernel: [ 8.371897] irq 166 handler serial8250_interrupt+0x0/0xd4 enabled interrupts
Jul 5 20:13:52 neptune kernel: [ 8.371900] Modules linked in: binfmt_misc evdev joydev hid_microsoft brcmfmac brcmutil vc4 cfg80211 drm_kms_helper rfkill cec drm spidev snd_soc_core snd_compress snd_pcm_dmaengine snd_bcm2835(C) syscopyarea sysfillrect snd_pcm sysimgblt fb_sys_fops snd_timer snd i2c_bcm2835 spi_bcm2835 uio_pdrv_genirq fixed uio i2c_dev ip_tables x_tables ipv6
Jul 5 20:13:52 neptune kernel: [ 8.371978] CPU: 2 PID: 98 Comm: irq/59-bcm2835- Tainted: G C 4.14.52-rt31-rc1-v7+ #1
Jul 5 20:13:52 neptune kernel: [ 8.371980] Hardware name: BCM2835
Jul 5 20:13:52 neptune kernel: [ 8.372002] [<8010fe10>] (unwind_backtrace) from [<8010bf6c>] (show_stack+0x20/0x24)
Jul 5 20:13:52 neptune kernel: [ 8.372016] [<8010bf6c>] (show_stack) from [<807b616c>] (dump_stack+0xbc/0x100)
Jul 5 20:13:52 neptune kernel: [ 8.372028] [<807b616c>] (dump_stack) from [<8011dc1c>] (__warn+0xf8/0x110)
Jul 5 20:13:52 neptune kernel: [ 8.372035] [<8011dc1c>] (__warn) from [<8011dc7c>] (warn_slowpath_fmt+0x48/0x50)
Jul 5 20:13:52 neptune kernel: [ 8.372042] [<8011dc7c>] (warn_slowpath_fmt) from [<8017b080>] (__handle_irq_event_percpu+0x2cc/0x2d0)
Jul 5 20:13:52 neptune kernel: [ 8.372054] [<8017b080>] (__handle_irq_event_percpu) from [<8017b0dc>] (handle_irq_event_percpu+0x58/0x94)
Jul 5 20:13:52 neptune kernel: [ 8.372067] [<8017b0dc>] (handle_irq_event_percpu) from [<8017b190>] (handle_irq_event+0x78/0xbc)
Jul 5 20:13:52 neptune kernel: [ 8.372075] [<8017b190>] (handle_irq_event) from [<8017ecb4>] (handle_level_irq+0xcc/0x1b4)
Jul 5 20:13:52 neptune kernel: [ 8.372083] [<8017ecb4>] (handle_level_irq) from [<80179f18>] (generic_handle_irq+0x34/0x44)
Jul 5 20:13:52 neptune kernel: [ 8.372094] [<80179f18>] (generic_handle_irq) from [<80520124>] (bcm2835_auxirq_handler+0x48/0x94)
Jul 5 20:13:52 neptune kernel: [ 8.372106] [<80520124>] (bcm2835_auxirq_handler) from [<8017c2dc>] (irq_forced_thread_fn+0x30/0x84)
Jul 5 20:13:52 neptune kernel: [ 8.372115] [<8017c2dc>] (irq_forced_thread_fn) from [<8017c650>] (irq_thread+0x12c/0x200)
Jul 5 20:13:52 neptune kernel: [ 8.372129] [<8017c650>] (irq_thread) from [<8013ecbc>] (kthread+0x144/0x174)
Jul 5 20:13:52 neptune kernel: [ 8.372145] [<8013ecbc>] (kthread) from [<80107d74>] (ret_from_fork+0x14/0x20)
Jul 5 20:13:52 neptune kernel: [ 8.372150] ---[ end trace 0000000000000002 ]---
Jul 5 20:13:53 neptune kernel: [ 9.384263] Adding 2047996k swap on /var/swap. Priority:-2 extents:83 across:31054336k SSFS
Jul 5 20:13:54 neptune kernel: [ 9.766417] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready
Jul 5 20:13:54 neptune kernel: [ 9.776763] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready
Jul 5 20:13:54 neptune kernel: [ 9.777107] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
Jul 5 20:13:54 neptune kernel: [ 9.818094] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
Jul 5 20:13:54 neptune kernel: [ 9.845415] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
Jul 5 20:13:54 neptune kernel: [ 9.845431] brcmfmac: power management disabled
Jul 5 20:13:54 neptune kernel: [ 10.372209] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
Jul 5 20:13:54 neptune kernel: [ 10.372230] brcmfmac: power management disabled
Jul 5 20:13:56 neptune kernel: [ 12.131448] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
Jul 5 20:13:59 neptune kernel: [ 15.172347] Bluetooth: Core ver 2.22
Jul 5 20:13:59 neptune kernel: [ 15.172432] NET: Registered protocol family 31
Jul 5 20:13:59 neptune kernel: [ 15.172435] Bluetooth: HCI device and connection manager initialized
Jul 5 20:13:59 neptune kernel: [ 15.172462] Bluetooth: HCI socket layer initialized
Jul 5 20:13:59 neptune kernel: [ 15.172480] Bluetooth: L2CAP socket layer initialized
Jul 5 20:13:59 neptune kernel: [ 15.172529] Bluetooth: SCO socket layer initialized
Jul 5 20:13:59 neptune kernel: [ 15.189778] Bluetooth: HCI UART driver ver 2.3
Jul 5 20:13:59 neptune kernel: [ 15.189798] Bluetooth: HCI UART protocol H4 registered
Jul 5 20:13:59 neptune kernel: [ 15.189802] Bluetooth: HCI UART protocol Three-wire (H5) registered
Jul 5 20:13:59 neptune kernel: [ 15.190217] Bluetooth: HCI UART protocol Broadcom registered
Jul 5 20:13:59 neptune kernel: [ 15.382064] Bluetooth: BNEP (Ethernet Emulation) ver 1.3
Jul 5 20:13:59 neptune kernel: [ 15.382159] Bluetooth: BNEP filters: protocol multicast
Jul 5 20:13:59 neptune kernel: [ 15.382185] Bluetooth: BNEP socket layer initialized
Jul 5 20:14:37 neptune kernel: [ 41.748169] Bluetooth: RFCOMM TTY layer initialized
Jul 5 20:14:37 neptune kernel: [ 41.748211] Bluetooth: RFCOMM socket layer initialized
Jul 5 20:14:37 neptune kernel: [ 41.748281] Bluetooth: RFCOMM ver 1.11
Jul 5 20:14:42 neptune kernel: [ 46.671563] bcm2835-dma 3f007000.dma: DMA transfer could not be terminated
Jul 5 20:15:56 neptune kernel: [ 121.041108] vc4-drm soc:gpu: failed to allocate buffer with size 819200
Jul 5 20:15:58 neptune kernel: [ 122.808131] vc4-drm soc:gpu: failed to allocate buffer with size 1916928
Jul 5 20:16:00 neptune kernel: [ 124.923549] vc4-drm soc:gpu: failed to allocate buffer with size 2490368
Jul 5 20:16:01 neptune kernel: [ 125.650654] vc4-drm soc:gpu: failed to allocate buffer with size 1118208
Jul 5 20:16:01 neptune kernel: [ 125.777023] vc4-drm soc:gpu: failed to allocate buffer with size 2138112
Jul 5 20:16:01 neptune kernel: [ 125.777183] vc4-drm soc:gpu: failed to allocate buffer with size 2138112
Jul 5 20:16:01 neptune kernel: [ 125.777220] vc4-drm soc:gpu: failed to allocate buffer with size 2138112
Jul 5 20:16:01 neptune kernel: [ 125.777276] [drm:vc4_bo_create [vc4]] ERROR Failed to allocate from CMA:
Jul 5 20:16:01 neptune kernel: [ 125.777281] [drm] kernel: 3072kb BOs (1)
Jul 5 20:16:01 neptune kernel: [ 125.777285] [drm] V3D: 83176kb BOs (229)
Jul 5 20:16:01 neptune kernel: [ 125.777289] [drm] V3D shader: 308kb BOs (71)
Jul 5 20:16:01 neptune kernel: [ 125.777292] [drm] dumb: 48kb BOs (3)
Jul 5 20:16:01 neptune kernel: [ 125.777295] [drm] binner: 16384kb BOs (1)
Jul 5 20:16:01 neptune kernel: [ 125.780771] vc4-drm soc:gpu: failed to allocate buffer with size 2138112
Jul 5 20:19:51 neptune kernel: [ 356.539608] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
Jul 5 20:19:51 neptune kernel: [ 356.539625] brcmfmac: power management disabled
Jul 5 20:23:40 neptune kernel: [ 585.301944] ------------[ cut here ]------------
Jul 5 20:23:40 neptune kernel: [ 585.302088] WARNING: CPU: 0 PID: 637 at drivers/gpu/drm/vc4/vc4_plane.c:771 vc4_plane_async_set_fb+0xa4/0xa8 [vc4]
Jul 5 20:23:40 neptune kernel: [ 585.302116] Modules linked in: cmac rfcomm bnep hci_uart btbcm serdev bluetooth ecdh_generic binfmt_misc evdev joydev hid_microsoft brcmfmac brcmutil vc4 cfg80211 drm_kms_helper rfkill cec drm spidev snd_soc_core snd_compress snd_pcm_dmaengine snd_bcm2835(C) syscopyarea sysfillrect snd_pcm sysimgblt fb_sys_fops snd_timer snd i2c_bcm2835 spi_bcm2835 uio_pdrv_genirq fixed uio i2c_dev ip_tables x_tables ipv6
Jul 5 20:23:40 neptune kernel: [ 585.302355] CPU: 0 PID: 637 Comm: Xorg Tainted: G WC 4.14.52-rt31-rc1-v7+ #1
Jul 5 20:23:40 neptune kernel: [ 585.302357] Hardware name: BCM2835
Jul 5 20:23:40 neptune kernel: [ 585.302402] [<8010fe10>] (unwind_backtrace) from [<8010bf6c>] (show_stack+0x20/0x24)
Jul 5 20:23:40 neptune kernel: [ 585.302422] [<8010bf6c>] (show_stack) from [<807b616c>] (dump_stack+0xbc/0x100)
Jul 5 20:23:40 neptune kernel: [ 585.302434] [<807b616c>] (dump_stack) from [<8011dc1c>] (__warn+0xf8/0x110)
Jul 5 20:23:40 neptune kernel: [ 585.302443] [<8011dc1c>] (__warn) from [<8011dd04>] (warn_slowpath_null+0x30/0x38)
Jul 5 20:23:40 neptune kernel: [ 585.302488] [<8011dd04>] (warn_slowpath_null) from [<7f550cec>] (vc4_plane_async_set_fb+0xa4/0xa8 [vc4])
Jul 5 20:23:40 neptune kernel: [ 585.302577] [<7f550cec>] (vc4_plane_async_set_fb [vc4]) from [<7f550dd0>] (vc4_update_plane+0xe0/0x168 [vc4])
Jul 5 20:23:40 neptune kernel: [ 585.302831] [<7f550dd0>] (vc4_update_plane [vc4]) from [<7f214d6c>] (__setplane_internal+0x188/0x224 [drm])
Jul 5 20:23:40 neptune kernel: [ 585.303052] [<7f214d6c>] (__setplane_internal [drm]) from [<7f214f30>] (drm_mode_cursor_universal+0x128/0x1b8 [drm])
Jul 5 20:23:40 neptune kernel: [ 585.303245] [<7f214f30>] (drm_mode_cursor_universal [drm]) from [<7f215128>] (drm_mode_cursor_common+0x168/0x1f0 [drm])
Jul 5 20:23:40 neptune kernel: [ 585.303436] [<7f215128>] (drm_mode_cursor_common [drm]) from [<7f215844>] (drm_mode_cursor2_ioctl+0x18/0x1c [drm])
Jul 5 20:23:40 neptune kernel: [ 585.303627] [<7f215844>] (drm_mode_cursor2_ioctl [drm]) from [<7f1fa8a0>] (drm_ioctl_kernel+0x78/0xb8 [drm])
Jul 5 20:23:40 neptune kernel: [ 585.303818] [<7f1fa8a0>] (drm_ioctl_kernel [drm]) from [<7f1fac40>] (drm_ioctl+0x1ec/0x364 [drm])
Jul 5 20:23:40 neptune kernel: [ 585.303920] [<7f1fac40>] (drm_ioctl [drm]) from [<802affdc>] (do_vfs_ioctl+0xb0/0x7c4)
Jul 5 20:23:40 neptune kernel: [ 585.303927] [<802affdc>] (do_vfs_ioctl) from [<802b0734>] (SyS_ioctl+0x44/0x6c)
Jul 5 20:23:40 neptune kernel: [ 585.303935] [<802b0734>] (SyS_ioctl) from [<80107cc0>] (ret_fast_syscall+0x0/0x28)
Jul 5 20:23:40 neptune kernel: [ 585.304549] ---[ end trace 0000000000000003 ]---
Jul 5 20:23:44 neptune kernel: [ 588.708481] vc4-drm soc:gpu: failed to allocate buffer with size 1556480
Jul 5 20:23:44 neptune kernel: [ 588.708974] vc4-drm soc:gpu: failed to allocate buffer with size 1556480
Jul 5 20:23:44 neptune kernel: [ 588.709020] vc4-drm soc:gpu: failed to allocate buffer with size 1556480
Jul 5 20:23:44 neptune kernel: [ 588.709071] [drm:vc4_bo_create [vc4]] ERROR Failed to allocate from CMA:
Jul 5 20:23:44 neptune kernel: [ 588.709077] [drm] kernel: 3072kb BOs (1)
Jul 5 20:23:44 neptune kernel: [ 588.709081] [drm] V3D: 86332kb BOs (214)
Jul 5 20:23:44 neptune kernel: [ 588.709084] [drm] V3D shader: 320kb BOs (74)
Jul 5 20:23:44 neptune kernel: [ 588.709087] [drm] dumb: 48kb BOs (3)
Jul 5 20:23:44 neptune kernel: [ 588.709090] [drm] binner: 16384kb BOs (1)
Jul 5 20:23:44 neptune kernel: [ 588.712439] vc4-drm soc:gpu: failed to allocate buffer with size 1556480
Jul 5 20:25:07 neptune kernel: [ 672.554419] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
Jul 5 20:25:07 neptune kernel: [ 672.554434] brcmfmac: power management disabled

Joel-Mckay · 2018-07-07T20:14:51Z

@6by9
Thanks for tips, as I have also been trying to get the mp4 codec support in "browser-plugin-vlc" package by patching and linking on the OpenGL (pi patch) enabled libVLC:
//edited: npapi-vlc/npapi/vlcplugin_base.cpp
ppsz_argv[ppsz_argc++] = "--vout=xcb_glx";
//Note, this didn't seem to alter software CPU loads in midori even though VLC itself does 720p easily with the hardware codec.

The webGL seems to flake out a bit during initial use, but Midori is so unstable normally I can't tell if its related to the cma mmu bypass handling issue.
//The normal mode testing of vc4: /boot/config.txt
kernel=4.14.52-rt31-rc1-v7+.img
dtoverlay=vc4-kms-v3d
avoid_warnings=2
hdmi_force_hotplug=1
hdmi_drive=2
hdmi_force_edid_audio=1
hdmi_group=2
hdmi_mode=16
config_hdmi_boost=4
disable_overscan=0
console-width=1024
console-height=700
gpu_mem=128
gpu_mem_256=128
gpu_mem_512=128
gpu_mem_1024=128
avoid_safe_mode=1
disable_splash=1
temp_limit=76
max_usb_current=1
dtparam=audio=on
dtparam=i2c_arm=on
dtparam=i2c_arm_baudrate=100000
dtparam=spi=on
dtparam=random=on
dtparam=watchdog=on
enable_uart=1

@stschake
Thank you for posting the patch to at least get this opengl browser working.
You were correct in that the patch does seems to reduce error rates the longer it runs.

I still have no idea why the disk image running on Pi2 (Revision: a21041)
That uses the same vc4 blob as the pi3 running stretch: 0.0.0 20140616
Doesn't exhibit the same memory issue yet even with the vanilla kernel on an LG LCD: uptime: 10:22:13 up 10 days, 23:10, 2 users, load average: 0.07, 0.10, 0.14

filip-hejsek mentioned this issue Jun 11, 2020

vc4_bo_create -> Failed to allocate from CMA. raspberrypi/firmware#1247

Open

Provide feedback

Saved searches

Use saved searches to filter your results more quickly

vc4-drm soc:gpu: failed to allocate buffer with size 16777216 #135

vc4-drm soc:gpu: failed to allocate buffer with size 16777216 #135

Joel-Mckay commented Jul 5, 2018 •

edited

Loading

stschake commented Jul 5, 2018

Joel-Mckay commented Jul 5, 2018

6by9 commented Jul 5, 2018

lategoodbye commented Jul 5, 2018

6by9 commented Jul 5, 2018

Joel-Mckay commented Jul 5, 2018 •

edited

Loading

stschake commented Jul 5, 2018 •

edited

Loading

Joel-Mckay commented Jul 5, 2018

6by9 commented Jul 5, 2018

Joel-Mckay commented Jul 5, 2018

Joel-Mckay commented Jul 7, 2018

vc4-drm soc:gpu: failed to allocate buffer with size 16777216 #135

vc4-drm soc:gpu: failed to allocate buffer with size 16777216 #135

Comments

Joel-Mckay commented Jul 5, 2018 • edited Loading

stschake commented Jul 5, 2018

Joel-Mckay commented Jul 5, 2018

6by9 commented Jul 5, 2018

lategoodbye commented Jul 5, 2018

6by9 commented Jul 5, 2018

Joel-Mckay commented Jul 5, 2018 • edited Loading

stschake commented Jul 5, 2018 • edited Loading

Joel-Mckay commented Jul 5, 2018

6by9 commented Jul 5, 2018

Joel-Mckay commented Jul 5, 2018

Joel-Mckay commented Jul 7, 2018

Joel-Mckay commented Jul 5, 2018 •

edited

Loading

Joel-Mckay commented Jul 5, 2018 •

edited

Loading

stschake commented Jul 5, 2018 •

edited

Loading