Aragon
Posts: 2
Joined: Fri Jul 07, 2017 9:07 am
Location: Cape Town, ZA

Kernel errors in rpi-4.9.y branch

Fri Jul 07, 2017 9:49 am

Hello,

I am experimenting with custom kernel builds for my RPi 3 B+, doing all my builds with the bcm2709_defconfig for the time being. I've noticed boot time kernel errors with kernels built from the rpi-4.9.y branch, that do not appear in the rpi-4.4.y branch. Here's a snippet of the dmesg log:

Code: Select all

[    3.571336] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251)
[    3.571444] io scheduler noop registered
[    3.571450] io scheduler deadline registered (default)
[    3.571759] io scheduler cfq registered
[    3.575805] raspberrypi-firmware soc:firmware: Request 0x00030043 returned status 0x80000001
[    3.575845] brcmexp-gpio soc:expgpio: Failed to get GPIO 0 config (-22)
[    3.575908] raspberrypi-firmware soc:firmware: Request 0x00030043 returned status 0x80000001
[    3.575936] brcmexp-gpio soc:expgpio: Failed to get GPIO 1 config (-22)
[    3.575993] raspberrypi-firmware soc:firmware: Request 0x00030043 returned status 0x80000001
[    3.576021] brcmexp-gpio soc:expgpio: Failed to get GPIO 2 config (-22)
[    3.576077] raspberrypi-firmware soc:firmware: Request 0x00030043 returned status 0x80000001
[    3.576105] brcmexp-gpio soc:expgpio: Failed to get GPIO 3 config (-22)
[    3.576160] raspberrypi-firmware soc:firmware: Request 0x00030043 returned status 0x80000001
[    3.576188] brcmexp-gpio soc:expgpio: Failed to get GPIO 4 config (-22)
[    3.576243] raspberrypi-firmware soc:firmware: Request 0x00030043 returned status 0x80000001
[    3.576270] brcmexp-gpio soc:expgpio: Failed to get GPIO 5 config (-22)
[    3.576325] raspberrypi-firmware soc:firmware: Request 0x00030043 returned status 0x80000001
[    3.576353] brcmexp-gpio soc:expgpio: Failed to get GPIO 6 config (-22)
[    3.576409] raspberrypi-firmware soc:firmware: Request 0x00030043 returned status 0x80000001
[    3.576436] brcmexp-gpio soc:expgpio: Failed to get GPIO 7 config (-22)
[    3.577919] BCM2708FB: allocated DMA memory f6910000
[    3.577941] BCM2708FB: allocated DMA channel 0 @ b780f000
[    3.592245] Console: switching to colour frame buffer device 100x37
Full boot logs from both branches are below.

Does anyone know what's up with these GPIO config errors? Please, any help appreciated. :)

Full rpi-4.9.y boot log:

Code: Select all

[    0.000000] Booting Linux on physical CPU 0x0
[    0.000000] Linux version 4.9.36-v7 ([email protected]) (gcc version 5.4.0 (Buildroot 2017.02.3-00017-g6f95166d0-dirty) ) #5 SMP Fri Jul 7 11:01:12 SAST 2017
[    0.000000] CPU: ARMv7 Processor [410fd034] revision 4 (ARMv7), cr=10c5383d
[    0.000000] CPU: div instructions available: patching division code
[    0.000000] CPU: PIPT / VIPT nonaliasing data cache, VIPT aliasing instruction cache
[    0.000000] OF: fdt:Machine model: Raspberry Pi 3 Model B Rev 1.2
[    0.000000] cma: Reserved 8 MiB at 0x36800000
[    0.000000] Memory policy: Data cache writealloc
[    0.000000] On node 0 totalpages: 225280
[    0.000000] free_area_init_node: node 0, pgdat 82e6ed40, node_mem_map b603a000
[    0.000000]   Normal zone: 1980 pages used for memmap
[    0.000000]   Normal zone: 0 pages reserved
[    0.000000]   Normal zone: 225280 pages, LIFO batch:31
[    0.000000] percpu: Embedded 14 pages/cpu @b5ff4000 s25548 r8192 d23604 u57344
[    0.000000] pcpu-alloc: s25548 r8192 d23604 u57344 alloc=14*4096
[    0.000000] pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 
[    0.000000] Built 1 zonelists in Zone order, mobility grouping on.  Total pages: 223300
[    0.000000] Kernel command line: 8250.nr_uarts=0 dma.dmachans=0x7f35 bcm2708_fb.fbwidth=800 bcm2708_fb.fbheight=600 bcm2709.boardrev=0xa02082 bcm2709.serial=0xe30bb15e smsc95xx.macaddr=B8:27:EB:0B:B1:5E bcm2708_fb.fbswap=1 bcm2709.uart_clock=48000000 vc_mem.mem_base=0x3dc00000 vc_mem.mem_size=0x3f000000  dwc_otg.lpm_enable=0 console=tty1 elevator=deadline fsck.repair=yes quiet
[    0.000000] PID hash table entries: 4096 (order: 2, 16384 bytes)
[    0.000000] Dentry cache hash table entries: 131072 (order: 7, 524288 bytes)
[    0.000000] Inode-cache hash table entries: 65536 (order: 6, 262144 bytes)
[    0.000000] Memory: 836520K/901120K available (7168K kernel code, 483K rwdata, 2012K rodata, 35840K init, 778K bss, 56408K reserved, 8192K cma-reserved)
[    0.000000] Virtual kernel memory layout:
[    0.000000]     vector  : 0xffff0000 - 0xffff1000   (   4 kB)
[    0.000000]     fixmap  : 0xffc00000 - 0xfff00000   (3072 kB)
[    0.000000]     vmalloc : 0xb7800000 - 0xff800000   (1152 MB)
[    0.000000]     lowmem  : 0x80000000 - 0xb7000000   ( 880 MB)
[    0.000000]     modules : 0x7f000000 - 0x80000000   (  16 MB)
[    0.000000]       .text : 0x80008000 - 0x80800000   (8160 kB)
[    0.000000]       .init : 0x80b00000 - 0x82e00000   (35840 kB)
[    0.000000]       .data : 0x82e00000 - 0x82e78f64   ( 484 kB)
[    0.000000]        .bss : 0x82e7a000 - 0x82f3c8d4   ( 779 kB)
[    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1
[    0.000000] Hierarchical RCU implementation.
[    0.000000] 	Build-time adjustment of leaf fanout to 32.
[    0.000000] NR_IRQS:16 nr_irqs:16 16
[    0.000000] arm_arch_timer: Architected cp15 timer(s) running at 19.20MHz (phys).
[    0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x46d987e47, max_idle_ns: 440795202767 ns
[    0.000008] sched_clock: 56 bits at 19MHz, resolution 52ns, wraps every 4398046511078ns
[    0.000020] Switching to timer-based delay loop, resolution 52ns
[    0.000325] Console: colour dummy device 80x30
[    0.000345] console [tty1] enabled
[    0.000370] Calibrating delay loop (skipped), value calculated using timer frequency.. 38.40 BogoMIPS (lpj=192000)
[    0.000388] pid_max: default: 32768 minimum: 301
[    0.000728] Mount-cache hash table entries: 2048 (order: 1, 8192 bytes)
[    0.000737] Mountpoint-cache hash table entries: 2048 (order: 1, 8192 bytes)
[    0.001796] Disabling cpuset control group subsystem
[    0.001950] CPU: Testing write buffer coherency: ok
[    0.001993] ftrace: allocating 22281 entries in 66 pages
[    0.055618] CPU0: update cpu_capacity 1024
[    0.055636] CPU0: thread -1, cpu 0, socket 0, mpidr 80000000
[    0.055709] Setting up static identity map for 0x100000 - 0x100034
[    0.057841] CPU1: update cpu_capacity 1024
[    0.057848] CPU1: thread -1, cpu 1, socket 0, mpidr 80000001
[    0.058514] CPU2: update cpu_capacity 1024
[    0.058520] CPU2: thread -1, cpu 2, socket 0, mpidr 80000002
[    0.059147] CPU3: update cpu_capacity 1024
[    0.059153] CPU3: thread -1, cpu 3, socket 0, mpidr 80000003
[    0.059234] Brought up 4 CPUs
[    0.059244] SMP: Total of 4 processors activated (153.60 BogoMIPS).
[    0.059248] CPU: All CPU(s) started in HYP mode.
[    0.059252] CPU: Virtualization extensions available.
[    0.059965] devtmpfs: initialized
[    0.071498] VFP support v0.3: implementor 41 architecture 3 part 40 variant 3 rev 4
[    0.071769] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns
[    0.071785] futex hash table entries: 1024 (order: 4, 65536 bytes)
[    0.072320] pinctrl core: initialized pinctrl subsystem
[    0.073234] NET: Registered protocol family 16
[    0.075548] DMA: preallocated 1024 KiB pool for atomic coherent allocations
[    0.084605] hw-breakpoint: found 5 (+1 reserved) breakpoint and 4 watchpoint registers.
[    0.084612] hw-breakpoint: maximum watchpoint size is 8 bytes.
[    0.084766] Serial: AMBA PL011 UART driver
[    0.086662] bcm2835-mbox 3f00b880.mailbox: mailbox enabled
[    0.087190] uart-pl011 3f201000.serial: could not find pctldev for node /soc/[email protected]/uart0_pins, deferring probe
[    0.156213] bcm2835-dma 3f007000.dma: DMA legacy API manager at b780f000, dmachans=0x1
[    0.158038] SCSI subsystem initialized
[    0.158196] usbcore: registered new interface driver usbfs
[    0.158272] usbcore: registered new interface driver hub
[    0.158360] usbcore: registered new device driver usb
[    0.165163] raspberrypi-firmware soc:firmware: Attached to firmware from 2017-01-18 20:50
[    0.166638] clocksource: Switched to clocksource arch_sys_counter
[    0.214388] VFS: Disk quotas dquot_6.6.0
[    0.214465] VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes)
[    0.214660] FS-Cache: Loaded
[    0.214911] CacheFiles: Loaded
[    0.227020] NET: Registered protocol family 2
[    0.227890] TCP established hash table entries: 8192 (order: 3, 32768 bytes)
[    0.227998] TCP bind hash table entries: 8192 (order: 4, 65536 bytes)
[    0.228182] TCP: Hash tables configured (established 8192 bind 8192)
[    0.228273] UDP hash table entries: 512 (order: 2, 16384 bytes)
[    0.228315] UDP-Lite hash table entries: 512 (order: 2, 16384 bytes)
[    0.228522] NET: Registered protocol family 1
[    0.228933] RPC: Registered named UNIX socket transport module.
[    0.228938] RPC: Registered udp transport module.
[    0.228942] RPC: Registered tcp transport module.
[    0.228947] RPC: Registered tcp NFSv4.1 backchannel transport module.
[    3.489631] Trying to unpack rootfs image as initramfs...
[    3.493436] Freeing initrd memory: 4K (aefff000 - af000000)
[    3.549515] hw perfevents: enabled with armv7_cortex_a7 PMU driver, 7 counters available
[    3.551843] workingset: timestamp_bits=14 max_order=18 bucket_order=4
[    3.567931] FS-Cache: Netfs 'nfs' registered for caching
[    3.568919] NFS: Registering the id_resolver key type
[    3.568944] Key type id_resolver registered
[    3.568948] Key type id_legacy registered
[    3.571336] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251)
[    3.571444] io scheduler noop registered
[    3.571450] io scheduler deadline registered (default)
[    3.571759] io scheduler cfq registered
[    3.575805] raspberrypi-firmware soc:firmware: Request 0x00030043 returned status 0x80000001
[    3.575845] brcmexp-gpio soc:expgpio: Failed to get GPIO 0 config (-22)
[    3.575908] raspberrypi-firmware soc:firmware: Request 0x00030043 returned status 0x80000001
[    3.575936] brcmexp-gpio soc:expgpio: Failed to get GPIO 1 config (-22)
[    3.575993] raspberrypi-firmware soc:firmware: Request 0x00030043 returned status 0x80000001
[    3.576021] brcmexp-gpio soc:expgpio: Failed to get GPIO 2 config (-22)
[    3.576077] raspberrypi-firmware soc:firmware: Request 0x00030043 returned status 0x80000001
[    3.576105] brcmexp-gpio soc:expgpio: Failed to get GPIO 3 config (-22)
[    3.576160] raspberrypi-firmware soc:firmware: Request 0x00030043 returned status 0x80000001
[    3.576188] brcmexp-gpio soc:expgpio: Failed to get GPIO 4 config (-22)
[    3.576243] raspberrypi-firmware soc:firmware: Request 0x00030043 returned status 0x80000001
[    3.576270] brcmexp-gpio soc:expgpio: Failed to get GPIO 5 config (-22)
[    3.576325] raspberrypi-firmware soc:firmware: Request 0x00030043 returned status 0x80000001
[    3.576353] brcmexp-gpio soc:expgpio: Failed to get GPIO 6 config (-22)
[    3.576409] raspberrypi-firmware soc:firmware: Request 0x00030043 returned status 0x80000001
[    3.576436] brcmexp-gpio soc:expgpio: Failed to get GPIO 7 config (-22)
[    3.577919] BCM2708FB: allocated DMA memory f6910000
[    3.577941] BCM2708FB: allocated DMA channel 0 @ b780f000
[    3.592245] Console: switching to colour frame buffer device 100x37
[    3.601535] bcm2835-rng 3f104000.rng: hwrng registered
[    3.601640] vc-cma: Videocore CMA driver
[    3.601646] vc-cma: vc_cma_base      = 0x00000000
[    3.601651] vc-cma: vc_cma_size      = 0x00000000 (0 MiB)
[    3.601656] vc-cma: vc_cma_initial   = 0x00000000 (0 MiB)
[    3.601848] vc-mem: phys_addr:0x00000000 mem_base=0x3dc00000 mem_size:0x3f000000(1008 MiB)
[    3.617011] brd: module loaded
[    3.625840] loop: module loaded
[    3.625852] Loading iSCSI transport class v2.0-870.
[    3.626388] usbcore: registered new interface driver smsc95xx
[    3.626403] dwc_otg: version 3.00a 10-AUG-2012 (platform bus)
[    3.852441] Core Release: 2.80a
[    3.852449] Setting default values for core params
[    3.852474] Finished setting default values for core params
[    4.052862] Using Buffer DMA mode
[    4.052868] Periodic Transfer Interrupt Enhancement - disabled
[    4.052872] Multiprocessor Interrupt Enhancement - disabled
[    4.052878] OTG VER PARAM: 0, OTG VER FLAG: 0
[    4.052887] Dedicated Tx FIFOs mode
[    4.053215] WARN::dwc_otg_hcd_init:1032: FIQ DMA bounce buffers: virt = 0xb6904000 dma = 0xf6904000 len=9024
[    4.053238] FIQ FSM acceleration enabled for :
[    4.053238] Non-periodic Split Transactions
[    4.053238] Periodic Split Transactions
[    4.053238] High-Speed Isochronous Endpoints
[    4.053238] Interrupt/Control Split Transaction hack enabled
[    4.053245] dwc_otg: Microframe scheduler enabled
[    4.053285] WARN::hcd_init_fiq:459: FIQ on core 1 at 0x8058fd30
[    4.053295] WARN::hcd_init_fiq:460: FIQ ASM at 0x80590094 length 36
[    4.053305] WARN::hcd_init_fiq:486: MPHI regs_base at 0xb807e000
[    4.053359] dwc_otg 3f980000.usb: DWC OTG Controller
[    4.053392] dwc_otg 3f980000.usb: new USB bus registered, assigned bus number 1
[    4.053423] dwc_otg 3f980000.usb: irq 62, io mem 0x00000000
[    4.053470] Init: Port Power? op_state=1
[    4.053474] Init: Power Port (0)
[    4.053676] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002
[    4.053686] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    4.053693] usb usb1: Product: DWC OTG Controller
[    4.053701] usb usb1: Manufacturer: Linux 4.9.36-v7 dwc_otg_hcd
[    4.053709] usb usb1: SerialNumber: 3f980000.usb
[    4.054520] hub 1-0:1.0: USB hub found
[    4.054563] hub 1-0:1.0: 1 port detected
[    4.055266] dwc_otg: FIQ enabled
[    4.055270] dwc_otg: NAK holdoff enabled
[    4.055275] dwc_otg: FIQ split-transaction FSM enabled
[    4.055289] Module dwc_common_port init
[    4.055541] usbcore: registered new interface driver usb-storage
[    4.055759] mousedev: PS/2 mouse device common for all mice
[    4.056763] bcm2835-wdt 3f100000.watchdog: Broadcom BCM2835 watchdog timer
[    4.057038] bcm2835-cpufreq: min=600000 max=1200000
[    4.057418] sdhci: Secure Digital Host Controller Interface driver
[    4.057422] sdhci: Copyright(c) Pierre Ossman
[    4.057692] sdhost-bcm2835 3f202000.sdhost: could not get clk, deferring probe
[    4.059860] mmc-bcm2835 3f300000.mmc: could not get clk, deferring probe
[    4.059956] sdhci-pltfm: SDHCI platform and OF driver helper
[    4.060268] raspberrypi-firmware soc:firmware: Request 0x00030043 returned status 0x80000001
[    4.060524] brcmexp-gpio soc:expgpio: Failed to get GPIO 7 config (-22)
[    4.060756] raspberrypi-firmware soc:firmware: Request 0x00030043 returned status 0x80000001
[    4.060990] brcmexp-gpio soc:expgpio: Failed to get GPIO 7 config (-22)
[    4.061210] raspberrypi-firmware soc:firmware: Request 0x00038043 returned status 0x80000001
[    4.061444] brcmexp-gpio soc:expgpio: Failed to set GPIO 7 to output (-22)
[    4.061756] leds-gpio: probe of leds failed with error -22
[    4.061927] ledtrig-cpu: registered to indicate activity on CPUs
[    4.062020] hidraw: raw HID events driver (C) Jiri Kosina
[    4.062195] usbcore: registered new interface driver usbhid
[    4.062199] usbhid: USB HID core driver
[    4.062930] vchiq: vchiq_init_state: slot_zero = 0xb6980000, is_master = 0
[    4.064637] Initializing XFRM netlink socket
[    4.064659] NET: Registered protocol family 17
[    4.064774] Key type dns_resolver registered
[    4.065267] Registering SWP/SWPB emulation handler
[    4.065948] registered taskstats version 1
[    4.066286] vc-sm: Videocore shared memory driver
[    4.066294] [vc_sm_connected_init]: start
[    4.072804] [vc_sm_connected_init]: end - returning 0
[    4.078738] 3f201000.serial: ttyAMA0 at MMIO 0x3f201000 (irq = 87, base_baud = 0) is a PL011 rev2
[    4.080236] sdhost: log_buf @ b6907000 (f6907000)
[    4.156670] mmc0: sdhost-bcm2835 loaded - DMA enabled (>1)
[    4.158873] mmc-bcm2835 3f300000.mmc: mmc_debug:0 mmc_debug2:0
[    4.158881] mmc-bcm2835 3f300000.mmc: DMA channel allocated
[    4.216770] of_cfs_init
[    4.216872] of_cfs_init: OK
[    4.229206] mmc0: host does not support reading read-only switch, assuming write-enable
[    4.231276] mmc0: new high speed SDHC card at address 1234
[    4.232225] mmcblk0: mmc0:1234 SA04G 3.64 GiB
[    4.234562]  mmcblk0: p1 p2
[    4.237246] mmc1: queuing unknown CIS tuple 0x80 (2 bytes)
[    4.238828] mmc1: queuing unknown CIS tuple 0x80 (3 bytes)
[    4.240409] mmc1: queuing unknown CIS tuple 0x80 (3 bytes)
[    4.243239] mmc1: queuing unknown CIS tuple 0x80 (7 bytes)
[    4.262631] Freeing unused kernel memory: 35840K (80b00000 - 82e00000)
[    4.266802] Indeed it is in host mode hprt0 = 00021501
[    4.275137] [U] rc-start
[    4.309919] [U] random
[    4.318262] random: dd: uninitialized urandom read (512 bytes read)
[    4.319268] [U] network
[    4.319708] [U] udev
[    4.328547] [U] dbus
[    4.331546] random: dbus-uuidgen: uninitialized urandom read (12 bytes read)
[    4.331607] random: dbus-uuidgen: uninitialized urandom read (8 bytes read)
[    4.336372] random: dbus-daemon: uninitialized urandom read (12 bytes read)
[    4.336492] udevd[108]: starting version 3.2.1
[    4.337761] random: udevd: uninitialized urandom read (16 bytes read)
[    4.337978] random: udevd: uninitialized urandom read (16 bytes read)
[    4.338103] random: udevd: uninitialized urandom read (16 bytes read)
[    4.339329] random: dbus-daemon: uninitialized urandom read (12 bytes read)
[    4.343579] [U] dropbear
[    4.346595] random: dropbear: uninitialized urandom read (32 bytes read)
[    4.349840] udevd[116]: starting eudev-3.2.1
[    4.368981] random: fast init done
[    4.397715] mmc1: new high speed SDIO card at address 0001
[    4.446271] NET: Registered protocol family 10
[    4.459871] [U] avahi
[    4.461958] usbcore: registered new interface driver brcmfmac
[    4.466909] usb 1-1: new high-speed USB device number 2 using dwc_otg
[    4.468302] Indeed it is in host mode hprt0 = 00001101
[    4.494881] [U] nvram
[    4.502428] gpiomem-bcm2835 3f200000.gpiomem: Initialised: Registers at 0x3f200000
[    4.613420] brcmfmac: Firmware version = wl0: May 27 2016 00:13:38 version 7.45.41.26 (r640327) FWID 01-df77e4a7
[    4.697047] usb 1-1: New USB device found, idVendor=0424, idProduct=9514
[    4.697062] usb 1-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0
[    4.697926] hub 1-1:1.0: USB hub found
[    4.698031] hub 1-1:1.0: 5 ports detected
[    4.732428] [U] rc-end
[    5.026681] usb 1-1.1: new[code]
high-speed USB device number 3 using dwc_otg
[ 5.156918] usb 1-1.1: New USB device found, idVendor=0424, idProduct=ec00
[ 5.156929] usb 1-1.1: New USB device strings: Mfr=0, Product=0, SerialNumber=0
[ 5.159807] smsc95xx v1.0.5
[ 5.250622] smsc95xx 1-1.1:1.0 eth0: register 'smsc95xx' at usb-3f980000.usb-1.1, smsc95xx USB 2.0 Ethernet, b8:27:eb:0b:b1:5e
[ 5.388910] smsc95xx 1-1.1:1.0 eth0: hardware isn't capable of remote wakeup
[ 5.389092] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready
[ 6.928111] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
[ 6.928905] smsc95xx 1-1.1:1.0 eth0: link up, 100Mbps, full-duplex, lpa 0x45E1
[/code]

Full rpi-4.4.y boot log:

Code: Select all

[    0.000000] Booting Linux on physical CPU 0x0
[    0.000000] Initializing cgroup subsys cpuset
[    0.000000] Initializing cgroup subsys cpu
[    0.000000] Initializing cgroup subsys cpuacct
[    0.000000] Linux version 4.4.50-v7 ([email protected]) (gcc version 5.4.0 (Buildroot 2017.02.3-00017-g6f95166d0-dirty) ) #5 SMP Fri Jul 7 11:37:22 SAST 2017
[    0.000000] CPU: ARMv7 Processor [410fd034] revision 4 (ARMv7), cr=10c5383d
[    0.000000] CPU: PIPT / VIPT nonaliasing data cache, VIPT aliasing instruction cache
[    0.000000] Machine model: Raspberry Pi 3 Model B Rev 1.2
[    0.000000] cma: Reserved 8 MiB at 0x36800000
[    0.000000] Memory policy: Data cache writealloc
[    0.000000] On node 0 totalpages: 225280
[    0.000000] free_area_init_node: node 0, pgdat 82a9cf40, node_mem_map b6036000
[    0.000000]   Normal zone: 1980 pages used for memmap
[    0.000000]   Normal zone: 0 pages reserved
[    0.000000]   Normal zone: 225280 pages, LIFO batch:31
[    0.000000] [bcm2709_smp_init_cpus] enter (9520->f3003010)
[    0.000000] [bcm2709_smp_init_cpus] ncores=4
[    0.000000] PERCPU: Embedded 13 pages/cpu @b5ff2000 s22540 r8192 d22516 u53248
[    0.000000] pcpu-alloc: s22540 r8192 d22516 u53248 alloc=13*4096
[    0.000000] pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 
[    0.000000] Built 1 zonelists in Zone order, mobility grouping on.  Total pages: 223300
[    0.000000] Kernel command line: 8250.nr_uarts=0 dma.dmachans=0x7f35 bcm2708_fb.fbwidth=800 bcm2708_fb.fbheight=600 bcm2709.boardrev=0xa02082 bcm2709.serial=0xe30bb15e smsc95xx.macaddr=B8:27:EB:0B:B1:5E bcm2708_fb.fbswap=1 bcm2709.uart_clock=48000000 vc_mem.mem_base=0x3dc00000 vc_mem.mem_size=0x3f000000  dwc_otg.lpm_enable=0 console=tty1 elevator=deadline fsck.repair=yes quiet
[    0.000000] PID hash table entries: 4096 (order: 2, 16384 bytes)
[    0.000000] Dentry cache hash table entries: 131072 (order: 7, 524288 bytes)
[    0.000000] Inode-cache hash table entries: 65536 (order: 6, 262144 bytes)
[    0.000000] Memory: 839444K/901120K available (6359K kernel code, 432K rwdata, 1716K rodata, 35132K init, 764K bss, 53484K reserved, 8192K cma-reserved)
[    0.000000] Virtual kernel memory layout:
[    0.000000]     vector  : 0xffff0000 - 0xffff1000   (   4 kB)
[    0.000000]     fixmap  : 0xffc00000 - 0xfff00000   (3072 kB)
[    0.000000]     vmalloc : 0xb7800000 - 0xff800000   (1152 MB)
[    0.000000]     lowmem  : 0x80000000 - 0xb7000000   ( 880 MB)
[    0.000000]     modules : 0x7f000000 - 0x80000000   (  16 MB)
[    0.000000]       .text : 0x80008000 - 0x807eae54   (8076 kB)
[    0.000000]       .init : 0x807eb000 - 0x82a3a000   (35132 kB)
[    0.000000]       .data : 0x82a3a000 - 0x82aa62c0   ( 433 kB)
[    0.000000]        .bss : 0x82aa9000 - 0x82b68114   ( 765 kB)
[    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1
[    0.000000] Hierarchical RCU implementation.
[    0.000000] 	Build-time adjustment of leaf fanout to 32.
[    0.000000] NR_IRQS:16 nr_irqs:16 16
[    0.000000] Architected cp15 timer(s) running at 19.20MHz (phys).
[    0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x46d987e47, max_idle_ns: 440795202767 ns
[    0.000009] sched_clock: 56 bits at 19MHz, resolution 52ns, wraps every 4398046511078ns
[    0.000026] Switching to timer-based delay loop, resolution 52ns
[    0.000283] Console: colour dummy device 80x30
[    0.000481] console [tty1] enabled
[    0.000504] Calibrating delay loop (skipped), value calculated using timer frequency.. 38.40 BogoMIPS (lpj=192000)
[    0.000524] pid_max: default: 32768 minimum: 301
[    0.000824] Mount-cache hash table entries: 2048 (order: 1, 8192 bytes)
[    0.000839] Mountpoint-cache hash table entries: 2048 (order: 1, 8192 bytes)
[    0.001830] Disabling cpuset control group subsystem
[    0.001861] Initializing cgroup subsys io
[    0.001889] Initializing cgroup subsys memory
[    0.001928] Initializing cgroup subsys devices
[    0.001948] Initializing cgroup subsys freezer
[    0.001966] Initializing cgroup subsys net_cls
[    0.002013] CPU: Testing write buffer coherency: ok
[    0.002071] ftrace: allocating 21145 entries in 63 pages
[    0.052970] CPU0: update cpu_capacity 1024
[    0.052999] CPU0: thread -1, cpu 0, socket 0, mpidr 80000000
[    0.053009] [bcm2709_smp_prepare_cpus] enter
[    0.053142] Setting up static identity map for 0x8240 - 0x8274
[    0.054768] [bcm2709_boot_secondary] cpu:1 started (0) 18
[    0.054991] [bcm2709_secondary_init] enter cpu:1
[    0.055035] CPU1: update cpu_capacity 1024
[    0.055040] CPU1: thread -1, cpu 1, socket 0, mpidr 80000001
[    0.055409] [bcm2709_boot_secondary] cpu:2 started (0) 18
[    0.055591] [bcm2709_secondary_init] enter cpu:2
[    0.055613] CPU2: update cpu_capacity 1024
[    0.055619] CPU2: thread -1, cpu 2, socket 0, mpidr 80000002
[    0.055973] [bcm2709_boot_secondary] cpu:3 started (0) 17
[    0.056111] [bcm2709_secondary_init] enter cpu:3
[    0.056131] CPU3: update cpu_capacity 1024
[    0.056137] CPU3: thread -1, cpu 3, socket 0, mpidr 80000003
[    0.056196] Brought up 4 CPUs
[    0.056219] SMP: Total of 4 processors activated (153.60 BogoMIPS).
[    0.056227] CPU: All CPU(s) started in HYP mode.
[    0.056234] CPU: Virtualization extensions available.
[    0.056790] devtmpfs: initialized
[    0.067482] VFP support v0.3: implementor 41 architecture 3 part 40 variant 3 rev 4
[    0.067815] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns
[    0.068529] pinctrl core: initialized pinctrl subsystem
[    0.069058] NET: Registered protocol family 16
[    0.074098] DMA: preallocated 4096 KiB pool for atomic coherent allocations
[    0.080691] hw-breakpoint: found 5 (+1 reserved) breakpoint and 4 watchpoint registers.
[    0.080702] hw-breakpoint: maximum watchpoint size is 8 bytes.
[    0.080852] Serial: AMBA PL011 UART driver
[    0.080981] uart-pl011 3f201000.uart: could not find pctldev for node /soc/[email protected]/uart0_pins, deferring probe
[    0.081145] bcm2835-mbox 3f00b880.mailbox: mailbox enabled
[    0.145153] bcm2835-dma 3f007000.dma: DMA legacy API manager at f3007000, dmachans=0x1
[    0.145709] SCSI subsystem initialized
[    0.145889] usbcore: registered new interface driver usbfs
[    0.145990] usbcore: registered new interface driver hub
[    0.146089] usbcore: registered new device driver usb
[    0.152520] raspberrypi-firmware soc:firmware: Attached to firmware from 2017-01-18 20:50
[    0.179554] clocksource: Switched to clocksource arch_sys_counter
[    0.225229] FS-Cache: Loaded
[    0.225499] CacheFiles: Loaded
[    0.237697] NET: Registered protocol family 2
[    0.238533] TCP established hash table entries: 8192 (order: 3, 32768 bytes)
[    0.238647] TCP bind hash table entries: 8192 (order: 4, 65536 bytes)
[    0.238835] TCP: Hash tables configured (established 8192 bind 8192)
[    0.238926] UDP hash table entries: 512 (order: 2, 16384 bytes)
[    0.238972] UDP-Lite hash table entries: 512 (order: 2, 16384 bytes)
[    0.239196] NET: Registered protocol family 1
[    0.239508] RPC: Registered named UNIX socket transport module.
[    0.239519] RPC: Registered udp transport module.
[    0.239527] RPC: Registered tcp transport module.
[    0.239535] RPC: Registered tcp NFSv4.1 backchannel transport module.
[    3.471437] Trying to unpack rootfs image as initramfs...
[    3.475878] Freeing initrd memory: 4K (aefff000 - af000000)
[    3.529442] hw perfevents: enabled with armv7_cortex_a7 PMU driver, 7 counters available
[    3.530822] futex hash table entries: 1024 (order: 4, 65536 bytes)
[    3.543987] VFS: Disk quotas dquot_6.6.0
[    3.544292] VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes)
[    3.546503] FS-Cache: Netfs 'nfs' registered for caching
[    3.547396] NFS: Registering the id_resolver key type
[    3.547437] Key type id_resolver registered
[    3.547447] Key type id_legacy registered
[    3.549793] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 252)
[    3.549934] io scheduler noop registered
[    3.549954] io scheduler deadline registered (default)
[    3.550006] io scheduler cfq registered
[    3.552626] BCM2708FB: allocated DMA memory f6c10000
[    3.552652] BCM2708FB: allocated DMA channel 0 @ f3007000
[    3.566953] Console: switching to colour frame buffer device 100x37
[    3.575930] bcm2835-rng 3f104000.rng: hwrng registered
[    3.576027] vc-cma: Videocore CMA driver
[    3.576036] vc-cma: vc_cma_base      = 0x00000000
[    3.576045] vc-cma: vc_cma_size      = 0x00000000 (0 MiB)
[    3.576054] vc-cma: vc_cma_initial   = 0x00000000 (0 MiB)
[    3.576254] vc-mem: phys_addr:0x00000000 mem_base=0x3dc00000 mem_size:0x3f000000(1008 MiB)
[    3.591044] brd: module loaded
[    3.599640] loop: module loaded
[    3.600508] vchiq: vchiq_init_state: slot_zero = 0xb6c80000, is_master = 0
[    3.601957] Loading iSCSI transport class v2.0-870.
[    3.602582] usbcore: registered new interface driver smsc95xx
[    3.602633] dwc_otg: version 3.00a 10-AUG-2012 (platform bus)
[    3.802939] Core Release: 2.80a
[    3.802952] Setting default values for core params
[    3.802983] Finished setting default values for core params
[    4.003347] Using Buffer DMA mode
[    4.003358] Periodic Transfer Interrupt Enhancement - disabled
[    4.003365] Multiprocessor Interrupt Enhancement - disabled
[    4.003375] OTG VER PARAM: 0, OTG VER FLAG: 0
[    4.003388] Dedicated Tx FIFOs mode
[    4.003685] WARN::dwc_otg_hcd_init:1047: FIQ DMA bounce buffers: virt = 0xb6c04000 dma = 0xf6c04000 len=9024
[    4.003715] FIQ FSM acceleration enabled for :
[    4.003715] Non-periodic Split Transactions
[    4.003715] Periodic Split Transactions
[    4.003715] High-Speed Isochronous Endpoints
[    4.003715] Interrupt/Control Split Transaction hack enabled
[    4.003738] dwc_otg: Microframe scheduler enabled
[    4.003779] WARN::hcd_init_fiq:415: FIQ on core 1 at 0x8044a080
[    4.003793] WARN::hcd_init_fiq:416: FIQ ASM at 0x8044a3e4 length 36
[    4.003807] WARN::hcd_init_fiq:441: MPHI regs_base at 0xb8078000
[    4.003867] dwc_otg 3f980000.usb: DWC OTG Controller
[    4.003903] dwc_otg 3f980000.usb: new USB bus registered, assigned bus number 1
[    4.003939] dwc_otg 3f980000.usb: irq 62, io mem 0x00000000
[    4.003989] Init: Port Power? op_state=1
[    4.003997] Init: Power Port (0)
[    4.004192] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002
[    4.004207] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    4.004219] usb usb1: Product: DWC OTG Controller
[    4.004230] usb usb1: Manufacturer: Linux 4.4.50-v7 dwc_otg_hcd
[    4.004242] usb usb1: SerialNumber: 3f980000.usb
[    4.005022] hub 1-0:1.0: USB hub found
[    4.005065] hub 1-0:1.0: 1 port detected
[    4.005657] dwc_otg: FIQ enabled
[    4.005665] dwc_otg: NAK holdoff enabled
[    4.005673] dwc_otg: FIQ split-transaction FSM enabled
[    4.005702] Module dwc_common_port init
[    4.005967] usbcore: registered new interface driver usb-storage
[    4.006199] mousedev: PS/2 mouse device common for all mice
[    4.006909] bcm2835-cpufreq: min=600000 max=1200000
[    4.007196] sdhci: Secure Digital Host Controller Interface driver
[    4.007205] sdhci: Copyright(c) Pierre Ossman
[    4.007497] sdhost: log_buf @ b6c07000 (f6c07000)
[    4.059589] mmc0: sdhost-bcm2835 loaded - DMA enabled (>1)
[    4.081947] mmc-bcm2835 3f300000.mmc: mmc_debug:0 mmc_debug2:0
[    4.081960] mmc-bcm2835 3f300000.mmc: DMA channel allocated
[    4.116980] mmc0: host does not support reading read-only switch, assuming write-enable
[    4.118935] mmc0: new high speed SDHC card at address 1234
[    4.119474] mmcblk0: mmc0:1234 SA04G 3.64 GiB
[    4.119667] sdhci-pltfm: SDHCI platform and OF driver helper
[    4.120061] ledtrig-cpu: registered to indicate activity on CPUs
[    4.120177] hidraw: raw HID events driver (C) Jiri Kosina
[    4.120352] usbcore: registered new interface driver usbhid
[    4.120360] usbhid: USB HID core driver
[    4.120884] Initializing XFRM netlink socket
[    4.120925] NET: Registered protocol family 17
[    4.121063] Key type dns_resolver registered
[    4.121484] Registering SWP/SWPB emulation handler
[    4.122272]  mmcblk0: p1 p2
[    4.122341] registered taskstats version 1
[    4.122525] vc-sm: Videocore shared memory driver
[    4.122541] [vc_sm_connected_init]: start
[    4.123061] [vc_sm_connected_init]: end - returning 0
[    4.125381] 3f201000.uart: ttyAMA0 at MMIO 0x3f201000 (irq = 87, base_baud = 0) is a PL011 rev2
[    4.125763] of_cfs_init
[    4.125840] of_cfs_init: OK
[    4.138435] mmc1: queuing unknown CIS tuple 0x80 (2 bytes)
[    4.140016] mmc1: queuing unknown CIS tuple 0x80 (3 bytes)
[    4.141594] mmc1: queuing unknown CIS tuple 0x80 (3 bytes)
[    4.144416] mmc1: queuing unknown CIS tuple 0x80 (7 bytes)
[    4.180804] Freeing unused kernel memory: 35132K (807eb000 - 82a3a000)
[    4.193082] [U] rc-start
[    4.199708] Indeed it is in host mode hprt0 = 00021501
[    4.212762] [U] random
[    4.221372] random: dd: uninitialized urandom read (512 bytes read, 4 bits of entropy available)
[    4.222654] [U] network
[    4.223109] [U] udev
[    4.233137] udevd[101]: starting version 3.2.1
[    4.234234] random: udevd: uninitialized urandom read (16 bytes read, 5 bits of entropy available)
[    4.234497] random: udevd: uninitialized urandom read (16 bytes read, 5 bits of entropy available)
[    4.234640] random: udevd: uninitialized urandom read (16 bytes read, 5 bits of entropy available)
[    4.236024] [U] dbus
[    4.239819] random: dbus-uuidgen: uninitialized urandom read (12 bytes read, 5 bits of entropy available)
[    4.240099] random: dbus-uuidgen: uninitialized urandom read (8 bytes read, 5 bits of entropy available)
[    4.247941] mmc1: new high speed SDIO card at address 0001
[    4.250447] udevd[105]: starting eudev-3.2.1
[    4.256308] random: dbus-daemon: uninitialized urandom read (12 bytes read, 7 bits of entropy available)
[    4.266206] random: dbus-daemon: uninitialized urandom read (12 bytes read, 8 bits of entropy available)
[    4.271108] [U] dropbear
[    4.274759] random: dropbear: uninitialized urandom read (32 bytes read, 9 bits of entropy available)
[    4.277819] random: udevd: uninitialized urandom read (16 bytes read, 9 bits of entropy available)
[    4.311302] usbcore: registered new interface driver brcmfmac
[    4.379718] usb 1-1: new high-speed USB device number 2 using dwc_otg
[    4.379898] Indeed it is in host mode hprt0 = 00001101
[    4.389942] NET: Registered protocol family 10
[    4.416202] bcm2835-wdt 3f100000.watchdog: Broadcom BCM2835 watchdog timer
[    4.418394] gpiomem-bcm2835 3f200000.gpiomem: Initialised: Registers at 0x3f200000
[    4.421627] [U] avahi
[    4.459503] brcmfmac: brcmf_c_preinit_dcmds: Firmware version = wl0: May 27 2016 00:13:38 version 7.45.41.26 (r640327) FWID 01-df77e4a7
[    4.467297] [U] nvram
[    4.486668] brcmfmac: brcmf_cfg80211_reg_notifier: not a ISO3166 code
[    4.579879] usb 1-1: New USB device found, idVendor=0424, idProduct=9514
[    4.579901] usb 1-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0
[    4.580721] hub 1-1:1.0: USB hub found
[    4.580829] hub 1-1:1.0: 5 ports detected
[    4.760653] [U] rc-end
[    4.859596] usb 1-1.1: new high-speed USB device number 3 using dwc_otg
[    4.959848] usb 1-1.1: New USB device found, idVendor=0424, idProduct=ec00
[    4.959864] usb 1-1.1: New USB device strings: Mfr=0, Product=0, SerialNumber=0
[    4.962712] smsc95xx v1.0.4
[    5.023123] smsc95xx 1-1.1:1.0 eth0: register 'smsc95xx' at usb-3f980000.usb-1.1, smsc95xx USB 2.0 Ethernet, b8:27:eb:0b:b1:5e
[    5.131727] smsc95xx 1-1.1:1.0 eth0: hardware isn't capable of remote wakeup
[    5.131924] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready
[    6.599065] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
[    6.600107] smsc95xx 1-1.1:1.0 eth0: link up, 100Mbps, full-duplex, lpa 0x45E1

Aragon
Posts: 2
Joined: Fri Jul 07, 2017 9:07 am
Location: Cape Town, ZA

Re: Kernel errors in rpi-4.9.y branch

Sat Jul 08, 2017 2:52 am

To answer my own question, it turns out the errors were caused by me having old rpi-firmware binaries (eg. start.elf) in my /boot file system. Updated to the latest and no more error messages!

Return to “Linux Kernel”