Joel-Mckay commented 6 years ago

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 commented 6 years ago

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 commented 6 years ago

@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: https://github.com/shirishpargaonkar/sspcifso/commit/ebf089248dab2ef569e5e26a607f0977a71182b7 CONFIG_CMA_SIZE_MBYTES=32

//currently running make -j4 zImage modules dtbs

6by9 commented 6 years ago

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 commented 6 years ago

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

6by9 commented 6 years ago

@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 commented 6 years ago

@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 commented 6 years ago

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 commented 6 years ago

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 commented 6 years ago

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. https://github.com/raspberrypi/linux/pull/2233 and https://github.com/raspberrypi/firmware/commit/cab026ba87e1a25b5673ecb658a62e30fb6537c9

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 commented 6 years ago

@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 commented 6 years ago

@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

anholt / linux

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

tried repo Raspbian Stretch kernel

and the RT kernel branch

kernel=4.14.34-rt27-v7+.img

kernel specific flags for the system

Debug information

kern.log information

tried to reduce thrashing here