search

raspberrypi / linux

Kernel source tree for Raspberry Pi-provided kernel builds. Issues unrelated to the linux kernel should be posted on the community forum at https://forums.raspberrypi.com/
Other
11.02k stars 4.95k forks source link

Camera randomly disrupts i2c on other busses (RPI 3B+ & 4) #3893

Open N4321D opened 3 years ago

N4321D commented 3 years ago

Recording from the camera (especially when using multiple splitter ports) and sampling the I2C bus (1 or others on RPI4) simultaneously randomly throws:

errno 5 input output error or errno 110 connection timed out.

this happens on the RPI4 every minute or so, and on the RPI3B+ less frequently (sometimes once 1h or something).

See this thread for all the specific test condition etc: https://www.raspberrypi.org/forums/viewtopic.php?f=44&t=287015&p=1738555#p1734925

I tested it on both Buster and Stretch with different firmware versions and kernels.

Changing anything in config.txt (e.g. core_freq, arm_freq, or gpu mem etc) did not solve the issue.

Last setup:

ON RPI3B+:

raspinfo:

EDIT: ran it again on RPI3B+ with better power supply to avoid under voltage, still same issue.

pi@raspberrypi:~ $ raspinfo
System Information
------------------

Raspberry Pi 3 Model B Plus Rev 1.3
PRETTY_NAME="Raspbian GNU/Linux 10 (buster)"
NAME="Raspbian GNU/Linux"
VERSION_ID="10"
VERSION="10 (buster)"

Raspberry Pi reference 2020-08-20
Generated using pi-gen, https://github.com/RPi-Distro/pi-gen, 9a3a10bf1019ebb2d59053564dc6b90068bad27d, stage2

Linux raspberrypi 5.4.51-v7+ #1333 SMP Mon Aug 10 16:45:19 BST 2020 armv7l GNU/Linux
Revision    : a020d3
Serial      : 0000000070348527
Model       : Raspberry Pi 3 Model B Plus Rev 1.3
Throttled flag  : throttled=0x0
Camera          : supported=1 detected=1

Videocore information
---------------------

Sep  2 2020 21:16:35 
Copyright (c) 2012 Broadcom
version 4439d2aaa6c376a2d1ef4402f142e1cf4de37c43 (clean) (release) (start_x)

alloc failures:     0
compactions:        0
legacy block fails: 0

Filesystem information
----------------------
Filesystem     1K-blocks    Used Available Use% Mounted on
/dev/root       30485636 3075892  26139728  11% /
devtmpfs          381884       0    381884   0% /dev
tmpfs             415164      36    415128   1% /dev/shm
tmpfs             415164    5640    409524   2% /run
tmpfs               5120       4      5116   1% /run/lock
tmpfs             415164       0    415164   0% /sys/fs/cgroup
/dev/mmcblk0p1    258095   54604    203492  22% /boot
tmpfs              83032       0     83032   0% /run/user/1000

Filename                Type        Size    Used    Priority
/var/swap                               file        102396  0   -2

Package version information
---------------------------
raspberrypi-ui-mods:
  Installed: (none)
raspberrypi-sys-mods:
  Installed: 20200812
openbox:
  Installed: (none)
lxpanel:
  Installed: (none)
pcmanfm:
  Installed: (none)
rpd-plym-splash:
  Installed: (none)

Networking Information
----------------------

eth0: flags=4099<UP,BROADCAST,MULTICAST>  mtu 1500
        ether m.m.m.m  txqueuelen 1000  (Ethernet)
        RX packets 0  bytes 0 (0.0 B)
        RX errors 0  dropped 0  overruns 0  frame 0
        TX packets 0  bytes 0 (0.0 B)
        TX errors 0  dropped 0 overruns 0  carrier 0  collisions 0

lo: flags=73<UP,LOOPBACK,RUNNING>  mtu 65536
        inet x.x.x.x  netmask x.x.x.x
        inet6 ::1  prefixlen 128  scopeid 0x10<host>
        loop  txqueuelen 1000  (Local Loopback)
        RX packets 304  bytes 26676 (26.0 KiB)
        RX errors 0  dropped 0  overruns 0  frame 0
        TX packets 304  bytes 26676 (26.0 KiB)
        TX errors 0  dropped 0 overruns 0  carrier 0  collisions 0

wlan0: flags=4163<UP,BROADCAST,RUNNING,MULTICAST>  mtu 1500
        inet x.x.x.x  netmask x.x.x.x  broadcast x.x.x.x
        inet6 y::y.y.y.y  prefixlen 64  scopeid 0x20<link>
        ether m.m.m.m  txqueuelen 1000  (Ethernet)
        RX packets 2030  bytes 153790 (150.1 KiB)
        RX errors 0  dropped 0  overruns 0  frame 0
        TX packets 14583  bytes 13607078 (12.9 MiB)
        TX errors 0  dropped 0 overruns 0  carrier 0  collisions 0

USB Information
---------------

/:  Bus 01.Port 1: Dev 1, Class=root_hub, Driver=dwc_otg/1p, 480M
    |__ Port 1: Dev 2, If 0, Class=Hub, Driver=hub/4p, 480M
        |__ Port 1: Dev 3, If 0, Class=Hub, Driver=hub/3p, 480M
            |__ Port 1: Dev 5, If 0, Class=Vendor Specific Class, Driver=lan78xx, 480M
            |__ Port 3: Dev 4, If 0, Class=Human Interface Device, Driver=usbhid, 12M
            |__ Port 3: Dev 4, If 1, Class=Human Interface Device, Driver=usbhid, 12M
            |__ Port 3: Dev 4, If 2, Class=Human Interface Device, Driver=usbhid, 12M

config.txt
----------

aphy_params_current=819
arm_freq=1400
arm_freq_min=600
audio_pwm_mode=514
config_hdmi_boost=5
core_freq=250
desired_osc_freq=0x331df0
desired_osc_freq_boost=0x3c45b0
disable_commandline_tags=2
disable_l2cache=1
display_default_lcd=1
display_hdmi_rotate=-1
display_lcd_rotate=-1
dphy_params_current=547
enable_tvout=1
force_eeprom_read=1
force_pwm_open=1
framebuffer_ignore_alpha=1
framebuffer_swap=1
gpu_freq=300
init_uart_clock=0x2dc6c00
lcd_framerate=60
over_voltage_avs=31250
over_voltage_avs_boost=0x200b2
pause_burst_frames=1
program_serial_random=1
sdram_freq=450
total_mem=1024
hdmi_force_cec_address:0=65535
hdmi_force_cec_address:1=65535
hdmi_pixel_freq_limit:0=0x9a7ec80
device_tree=-
overlay_prefix=overlays/
hdmi_cvt:0=
hdmi_cvt:1=
hdmi_edid_filename:0=
hdmi_edid_filename:1=
hdmi_timings:0=
hdmi_timings:1=

cmdline.txt
-----------
coherent_pool=1M 8250.nr_uarts=0 snd_bcm2835.enable_compat_alsa=0 snd_bcm2835.enable_hdmi=1 snd_bcm2835.enable_headphones=1 bcm2708_fb.fbwidth=800 bcm2708_fb.fbheight=480 bcm2708_fb.fbswap=1 vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000  console=ttyS0,115200 console=tty1 root=PARTUUID=3172c665-02 rootfstype=ext4 elevator=deadline fsck.repair=yes rootwait

raspi-gpio settings
-------------------

BANK0 (GPIO 0 to 27):
GPIO 0: level=1 fsel=0 func=INPUT
GPIO 1: level=1 fsel=0 func=INPUT
GPIO 2: level=1 fsel=4 alt=0 func=SDA1
GPIO 3: level=1 fsel=4 alt=0 func=SCL1
GPIO 4: level=1 fsel=0 func=INPUT
GPIO 5: level=1 fsel=0 func=INPUT
GPIO 6: level=1 fsel=0 func=INPUT
GPIO 7: level=1 fsel=0 func=INPUT
GPIO 8: level=1 fsel=0 func=INPUT
GPIO 9: level=0 fsel=0 func=INPUT
GPIO 10: level=0 fsel=0 func=INPUT
GPIO 11: level=0 fsel=0 func=INPUT
GPIO 12: level=0 fsel=0 func=INPUT
GPIO 13: level=0 fsel=0 func=INPUT
GPIO 14: level=0 fsel=0 func=INPUT
GPIO 15: level=1 fsel=0 func=INPUT
GPIO 16: level=0 fsel=0 func=INPUT
GPIO 17: level=1 fsel=1 func=OUTPUT
GPIO 18: level=0 fsel=0 func=INPUT
GPIO 19: level=0 fsel=0 func=INPUT
GPIO 20: level=0 fsel=0 func=INPUT
GPIO 21: level=0 fsel=0 func=INPUT
GPIO 22: level=0 fsel=0 func=INPUT
GPIO 23: level=0 fsel=0 func=INPUT
GPIO 24: level=0 fsel=0 func=INPUT
GPIO 25: level=0 fsel=0 func=INPUT
GPIO 26: level=0 fsel=0 func=INPUT
GPIO 27: level=0 fsel=0 func=INPUT
BANK1 (GPIO 28 to 45):
GPIO 28: level=1 fsel=0 func=INPUT
GPIO 29: level=0 fsel=1 func=OUTPUT
GPIO 30: level=0 fsel=7 alt=3 func=CTS0
GPIO 31: level=0 fsel=7 alt=3 func=RTS0
GPIO 32: level=1 fsel=7 alt=3 func=TXD0
GPIO 33: level=1 fsel=7 alt=3 func=RXD0
GPIO 34: level=1 fsel=7 alt=3 func=SD1_CLK
GPIO 35: level=1 fsel=7 alt=3 func=SD1_CMD
GPIO 36: level=1 fsel=7 alt=3 func=SD1_DAT0
GPIO 37: level=1 fsel=7 alt=3 func=SD1_DAT1
GPIO 38: level=1 fsel=7 alt=3 func=SD1_DAT2
GPIO 39: level=1 fsel=7 alt=3 func=SD1_DAT3
GPIO 40: level=0 fsel=4 alt=0 func=PWM0
GPIO 41: level=0 fsel=4 alt=0 func=PWM1
GPIO 42: level=1 fsel=4 alt=0 func=GPCLK1
GPIO 43: level=1 fsel=4 alt=0 func=GPCLK2
GPIO 44: level=1 fsel=5 alt=1 func=SDA0
GPIO 45: level=1 fsel=5 alt=1 func=SCL0
BANK2 (GPIO 46 to 53):
GPIO 46: level=1 fsel=0 func=INPUT
GPIO 47: level=1 fsel=1 func=OUTPUT
GPIO 48: level=0 fsel=4 alt=0 func=SD0_CLK
GPIO 49: level=1 fsel=4 alt=0 func=SD0_CMD
GPIO 50: level=1 fsel=4 alt=0 func=SD0_DAT0
GPIO 51: level=1 fsel=4 alt=0 func=SD0_DAT1
GPIO 52: level=1 fsel=4 alt=0 func=SD0_DAT2
GPIO 53: level=1 fsel=4 alt=0 func=SD0_DAT3

vcdbg log messages
------------------

001263.989: brfs: File read: /mfs/sd/config.txt
001265.131: brfs: File read: 1846 bytes
001292.277: HDMI:EDID error reading EDID block 0 attempt 0
001293.540: HDMI:EDID error reading EDID block 0 attempt 1
001294.799: HDMI:EDID error reading EDID block 0 attempt 2
001296.059: HDMI:EDID error reading EDID block 0 attempt 3
001297.318: HDMI:EDID error reading EDID block 0 attempt 4
001298.578: HDMI:EDID error reading EDID block 0 attempt 5
001299.837: HDMI:EDID error reading EDID block 0 attempt 6
001301.097: HDMI:EDID error reading EDID block 0 attempt 7
001302.356: HDMI:EDID error reading EDID block 0 attempt 8
001303.616: HDMI:EDID error reading EDID block 0 attempt 9
001304.634: HDMI:EDID giving up on reading EDID block 0
001307.481: brfs: File read: /mfs/sd/config.txt
002150.747: gpioman: gpioman_get_pin_num: pin DISPLAY_DSI_PORT not defined
002453.721: *** Restart logging
002453.748: brfs: File read: 1846 bytes
002454.961: hdmi: HDMI:EDID error reading EDID block 0 attempt 0
002456.224: hdmi: HDMI:EDID error reading EDID block 0 attempt 1
002457.487: hdmi: HDMI:EDID error reading EDID block 0 attempt 2
002458.750: hdmi: HDMI:EDID error reading EDID block 0 attempt 3
002460.013: hdmi: HDMI:EDID error reading EDID block 0 attempt 4
002461.276: hdmi: HDMI:EDID error reading EDID block 0 attempt 5
002462.539: hdmi: HDMI:EDID error reading EDID block 0 attempt 6
002463.802: hdmi: HDMI:EDID error reading EDID block 0 attempt 7
002465.065: hdmi: HDMI:EDID error reading EDID block 0 attempt 8
002466.328: hdmi: HDMI:EDID error reading EDID block 0 attempt 9
002467.349: hdmi: HDMI:EDID giving up on reading EDID block 0
002467.666: hdmi: HDMI:EDID error reading EDID block 0 attempt 0
002468.932: hdmi: HDMI:EDID error reading EDID block 0 attempt 1
002470.197: hdmi: HDMI:EDID error reading EDID block 0 attempt 2
002471.462: hdmi: HDMI:EDID error reading EDID block 0 attempt 3
002472.727: hdmi: HDMI:EDID error reading EDID block 0 attempt 4
002473.992: hdmi: HDMI:EDID error reading EDID block 0 attempt 5
002475.257: hdmi: HDMI:EDID error reading EDID block 0 attempt 6
002476.522: hdmi: HDMI:EDID error reading EDID block 0 attempt 7
002477.787: hdmi: HDMI:EDID error reading EDID block 0 attempt 8
002479.052: hdmi: HDMI:EDID error reading EDID block 0 attempt 9
002480.075: hdmi: HDMI:EDID giving up on reading EDID block 0
002480.116: hdmi: HDMI:hdmi_get_state is deprecated, use hdmi_get_display_state instead
002480.140: HDMI0: hdmi_pixel_encoding: 162000000
002480.386: vec: vec_middleware_power_on: vec_base: 0x7e806000 rev-id 0x00002708 @ vec: 0x7e806100 @ 0x00000420 enc: 0x7e806060 @ 0x00000220 cgmsae: 0x7e80605c @ 0x00000000
002486.090: dtb_file 'bcm2710-rpi-3-b-plus.dtb'
002489.980: brfs: File read: /mfs/sd/bcm2710-rpi-3-b-plus.dtb
002490.005: Loading 'bcm2710-rpi-3-b-plus.dtb' to 0x100 size 0x6fb7
002503.572: brfs: File read: 28599 bytes
002514.809: brfs: File read: /mfs/sd/overlays/overlay_map.dtb
002578.125: brfs: File read: 1423 bytes
002587.309: brfs: File read: /mfs/sd/overlays/rpi-ft5406.dtbo
002588.873: Loaded overlay 'rpi-ft5406'
002595.510: brfs: File read: 842 bytes
002604.066: brfs: File read: /mfs/sd/overlays/rpi-backlight.dtbo
002611.277: Loaded overlay 'rpi-backlight'
002625.417: brfs: File read: 489 bytes
002628.646: brfs: File read: /mfs/sd/config.txt
002629.190: dtparam: i2c_arm=on
002637.558: dtparam: i2c_arm_baudrate=400000
002645.507: dtparam: audio=on
002662.224: brfs: File read: 1846 bytes
002662.994: brfs: File read: /mfs/sd/cmdline.txt
002663.043: Read command line from file 'cmdline.txt':
002663.064: 'console=serial0,115200 console=tty1 root=PARTUUID=3172c665-02 rootfstype=ext4 elevator=deadline fsck.repair=yes rootwait'
004357.245: gpioman: gpioman_get_pin_num: pin EMMC_ENABLE not defined
004413.024: brfs: File read: 121 bytes
004804.946: brfs: File read: /mfs/sd/kernel7.img
004804.968: Loading 'kernel7.img' to 0x8000 size 0x567700
004804.997: Device tree loaded to 0x2eff8b00 (size 0x74fd)
004806.194: gpioman: gpioman_get_pin_num: pin SDCARD_CONTROL_POWER not defined
007852.264: vchiq_core: vchiq_init_state: slot_zero = 0xf0180000, is_master = 1
012298.837: camsubs: Looking for camera 0: i2c_port = 0, led gpio = 134, power enable gpio = 133
012599.562: camsubs: Camera not found
012599.600: camsubs: Looking for camera 0: i2c_port = 0, led gpio = 134, power enable gpio = 133
012900.325: camsubs: Camera not found
012900.365: camsubs: Looking for camera 0: i2c_port = 0, led gpio = 134, power enable gpio = 133
012901.079: camsubs: Camera not found
012901.115: camsubs: Looking for camera 0: i2c_port = 0, led gpio = 134, power enable gpio = 133
012902.740: camsubs: Camera found OK
047843.593: Using RPI AWB
047971.793: Using RPI AWB
048096.886: Using RPI AWB
063825.286: Using RPI AWB
066378.944: brfs: File read: 5666560 bytes
066379.003: mmal: mmal_vll_load: could not load VLL 'videnc.vll': 
110710.409: *** No KHAN handle found for pid 598
118958.554: Using RPI AWB
119084.552: Using RPI AWB
119209.418: Using RPI AWB
126986.172: mmal: mmal_vll_load: could not load VLL 'videnc.vll': 
138383.421: mmal: mmal_vll_load: could not load VLL 'videnc.vll': 
152927.841: *** No KHAN handle found for pid 660
158985.048: Using RPI AWB
159113.485: Using RPI AWB
159239.593: Using RPI AWB
167413.382: Using RPI AWB
172125.273: Using RPI AWB
172279.372: Using RPI AWB
189132.811: mmal: mmal_vll_load: could not load VLL 'videnc.vll': 
195935.220: mmal: mmal_vll_load: could not load VLL 'videnc.vll': 

dmesg log
---------

[    0.000000] Booting Linux on physical CPU 0x0
[    0.000000] Linux version 5.4.51-v7+ (dom@buildbot) (gcc version 4.9.3 (crosstool-NG crosstool-ng-1.22.0-88-g8460611)) #1333 SMP Mon Aug 10 16:45:19 BST 2020
[    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 Plus Rev 1.3
[    0.000000] Memory policy: Data cache writealloc
[    0.000000] Reserved memory: created CMA memory pool at 0x30000000, size 64 MiB
[    0.000000] OF: reserved mem: initialized node linux,cma, compatible id shared-dma-pool
[    0.000000] On node 0 totalpages: 212992
[    0.000000]   Normal zone: 1872 pages used for memmap
[    0.000000]   Normal zone: 0 pages reserved
[    0.000000]   Normal zone: 212992 pages, LIFO batch:63
[    0.000000] percpu: Embedded 20 pages/cpu s49856 r8192 d23872 u81920
[    0.000000] pcpu-alloc: s49856 r8192 d23872 u81920 alloc=20*4096
[    0.000000] pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 
[    0.000000] Built 1 zonelists, mobility grouping on.  Total pages: 211120
[    0.000000] Kernel command line: coherent_pool=1M 8250.nr_uarts=0 snd_bcm2835.enable_compat_alsa=0 snd_bcm2835.enable_hdmi=1 snd_bcm2835.enable_headphones=1 bcm2708_fb.fbwidth=800 bcm2708_fb.fbheight=480 bcm2708_fb.fbswap=1 vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000  console=ttyS0,115200 console=tty1 root=PARTUUID=3172c665-02 rootfstype=ext4 elevator=deadline fsck.repair=yes rootwait
[    0.000000] Dentry cache hash table entries: 131072 (order: 7, 524288 bytes, linear)
[    0.000000] Inode-cache hash table entries: 65536 (order: 6, 262144 bytes, linear)
[    0.000000] mem auto-init: stack:off, heap alloc:off, heap free:off
[    0.000000] Memory: 763772K/851968K available (8192K kernel code, 689K rwdata, 2552K rodata, 1024K init, 825K bss, 22660K reserved, 65536K cma-reserved)
[    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1
[    0.000000] ftrace: allocating 28275 entries in 56 pages
[    0.000000] rcu: Hierarchical RCU implementation.
[    0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 10 jiffies.
[    0.000000] NR_IRQS: 16, nr_irqs: 16, preallocated irqs: 16
[    0.000000] random: get_random_bytes called from start_kernel+0x328/0x500 with crng_init=0
[    0.000000] arch_timer: 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.000007] sched_clock: 56 bits at 19MHz, resolution 52ns, wraps every 4398046511078ns
[    0.000022] Switching to timer-based delay loop, resolution 52ns
[    0.000290] Console: colour dummy device 80x30
[    0.000868] printk: console [tty1] enabled
[    0.000933] Calibrating delay loop (skipped), value calculated using timer frequency.. 38.40 BogoMIPS (lpj=192000)
[    0.000982] pid_max: default: 32768 minimum: 301
[    0.001380] Mount-cache hash table entries: 2048 (order: 1, 8192 bytes, linear)
[    0.001426] Mountpoint-cache hash table entries: 2048 (order: 1, 8192 bytes, linear)
[    0.002809] Disabling memory control group subsystem
[    0.002932] CPU: Testing write buffer coherency: ok
[    0.003509] CPU0: thread -1, cpu 0, socket 0, mpidr 80000000
[    0.004461] Setting up static identity map for 0x100000 - 0x10003c
[    0.004678] rcu: Hierarchical SRCU implementation.
[    0.005371] smp: Bringing up secondary CPUs ...
[    0.006511] CPU1: thread -1, cpu 1, socket 0, mpidr 80000001
[    0.007783] CPU2: thread -1, cpu 2, socket 0, mpidr 80000002
[    0.008929] CPU3: thread -1, cpu 3, socket 0, mpidr 80000003
[    0.009082] smp: Brought up 1 node, 4 CPUs
[    0.009170] SMP: Total of 4 processors activated (153.60 BogoMIPS).
[    0.009199] CPU: All CPU(s) started in HYP mode.
[    0.009223] CPU: Virtualization extensions available.
[    0.010278] devtmpfs: initialized
[    0.026683] VFP support v0.3: implementor 41 architecture 3 part 40 variant 3 rev 4
[    0.026986] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns
[    0.027044] futex hash table entries: 1024 (order: 4, 65536 bytes, linear)
[    0.030178] pinctrl core: initialized pinctrl subsystem
[    0.031378] NET: Registered protocol family 16
[    0.035594] DMA: preallocated 1024 KiB pool for atomic coherent allocations
[    0.041865] hw-breakpoint: found 5 (+1 reserved) breakpoint and 4 watchpoint registers.
[    0.041906] hw-breakpoint: maximum watchpoint size is 8 bytes.
[    0.042173] Serial: AMBA PL011 UART driver
[    0.044337] bcm2835-mbox 3f00b880.mailbox: mailbox enabled
[    0.070101] raspberrypi-firmware soc:firmware: Attached to firmware from 2020-09-02 21:16, variant start_x
[    0.080114] raspberrypi-firmware soc:firmware: Firmware hash is 4439d2aaa6c376a2d1ef4402f142e1cf4de37c43
[    0.130785] bcm2835-dma 3f007000.dma: DMA legacy API manager, dmachans=0x1
[    0.133038] SCSI subsystem initialized
[    0.133332] usbcore: registered new interface driver usbfs
[    0.133416] usbcore: registered new interface driver hub
[    0.133562] usbcore: registered new device driver usb
[    0.135521] clocksource: Switched to clocksource arch_sys_counter
[    1.295282] VFS: Disk quotas dquot_6.6.0
[    1.295426] VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes)
[    1.295691] FS-Cache: Loaded
[    1.295979] CacheFiles: Loaded
[    1.307330] thermal_sys: Registered thermal governor 'step_wise'
[    1.307730] NET: Registered protocol family 2
[    1.308796] tcp_listen_portaddr_hash hash table entries: 512 (order: 0, 6144 bytes, linear)
[    1.308872] TCP established hash table entries: 8192 (order: 3, 32768 bytes, linear)
[    1.309013] TCP bind hash table entries: 8192 (order: 4, 65536 bytes, linear)
[    1.309222] TCP: Hash tables configured (established 8192 bind 8192)
[    1.309404] UDP hash table entries: 512 (order: 2, 16384 bytes, linear)
[    1.309477] UDP-Lite hash table entries: 512 (order: 2, 16384 bytes, linear)
[    1.309791] NET: Registered protocol family 1
[    1.310514] RPC: Registered named UNIX socket transport module.
[    1.310546] RPC: Registered udp transport module.
[    1.310572] RPC: Registered tcp transport module.
[    1.310598] RPC: Registered tcp NFSv4.1 backchannel transport module.
[    1.312353] hw perfevents: enabled with armv7_cortex_a7 PMU driver, 7 counters available
[    1.315733] Initialise system trusted keyrings
[    1.316022] workingset: timestamp_bits=14 max_order=18 bucket_order=4
[    1.327835] FS-Cache: Netfs 'nfs' registered for caching
[    1.328666] NFS: Registering the id_resolver key type
[    1.328734] Key type id_resolver registered
[    1.328761] Key type id_legacy registered
[    1.328797] nfs4filelayout_init: NFSv4 File Layout Driver Registering...
[    1.330138] Key type asymmetric registered
[    1.330169] Asymmetric key parser 'x509' registered
[    1.330236] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 249)
[    1.330273] io scheduler mq-deadline registered
[    1.330300] io scheduler kyber registered
[    1.333810] bcm2708_fb soc:fb: FB found 1 display(s)
[    1.349767] Console: switching to colour frame buffer device 100x30
[    1.358813] bcm2708_fb soc:fb: Registered framebuffer for display 0, size 800x480
[    1.367148] bcm2835-rng 3f104000.rng: hwrng registered
[    1.370565] vc-mem: phys_addr:0x00000000 mem_base=0x3ec00000 mem_size:0x40000000(1024 MiB)
[    1.374317] vc-sm: Videocore shared memory driver
[    1.377823] gpiomem-bcm2835 3f200000.gpiomem: Initialised: Registers at 0x3f200000
[    1.393982] brd: module loaded
[    1.409750] loop: module loaded
[    1.414282] Loading iSCSI transport class v2.0-870.
[    1.418367] libphy: Fixed MDIO Bus: probed
[    1.421524] usbcore: registered new interface driver lan78xx
[    1.424675] usbcore: registered new interface driver smsc95xx
[    1.427737] dwc_otg: version 3.00a 10-AUG-2012 (platform bus)
[    2.159021] Core Release: 2.80a
[    2.161974] Setting default values for core params
[    2.164969] Finished setting default values for core params
[    2.368437] Using Buffer DMA mode
[    2.371416] Periodic Transfer Interrupt Enhancement - disabled
[    2.374432] Multiprocessor Interrupt Enhancement - disabled
[    2.377491] OTG VER PARAM: 0, OTG VER FLAG: 0
[    2.380481] Dedicated Tx FIFOs mode
[    2.384002] WARN::dwc_otg_hcd_init:1074: FIQ DMA bounce buffers: virt = b0114000 dma = 0xf0114000 len=9024
[    2.390154] FIQ FSM acceleration enabled for :
               Non-periodic Split Transactions
               Periodic Split Transactions
               High-Speed Isochronous Endpoints
               Interrupt/Control Split Transaction hack enabled
[    2.405077] dwc_otg: Microframe scheduler enabled
[    2.405144] WARN::hcd_init_fiq:457: FIQ on core 1
[    2.407954] WARN::hcd_init_fiq:458: FIQ ASM at 806d89f4 length 36
[    2.410705] WARN::hcd_init_fiq:497: MPHI regs_base at b4810000
[    2.413408] dwc_otg 3f980000.usb: DWC OTG Controller
[    2.416167] dwc_otg 3f980000.usb: new USB bus registered, assigned bus number 1
[    2.418978] dwc_otg 3f980000.usb: irq 56, io mem 0x00000000
[    2.421760] Init: Port Power? op_state=1
[    2.424457] Init: Power Port (0)
[    2.427542] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 5.04
[    2.430445] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    2.433316] usb usb1: Product: DWC OTG Controller
[    2.436185] usb usb1: Manufacturer: Linux 5.4.51-v7+ dwc_otg_hcd
[    2.439055] usb usb1: SerialNumber: 3f980000.usb
[    2.442537] hub 1-0:1.0: USB hub found
[    2.445357] hub 1-0:1.0: 1 port detected
[    2.448868] dwc_otg: FIQ enabled
[    2.448878] dwc_otg: NAK holdoff enabled
[    2.448887] dwc_otg: FIQ split-transaction FSM enabled
[    2.448902] Module dwc_common_port init
[    2.449279] usbcore: registered new interface driver usb-storage
[    2.452239] mousedev: PS/2 mouse device common for all mice
[    2.456455] bcm2835-wdt bcm2835-wdt: Broadcom BCM2835 watchdog timer
[    2.460637] cpufreq: cpufreq_online: CPU0: Running at unlisted freq: 600000 KHz
[    2.466025] cpufreq: cpufreq_online: CPU0: Unlisted initial frequency changed to: 700000 KHz
[    2.469030] sdhci: Secure Digital Host Controller Interface driver
[    2.471439] sdhci: Copyright(c) Pierre Ossman
[    2.474345] mmc-bcm2835 3f300000.mmcnr: could not get clk, deferring probe
[    2.477456] sdhost-bcm2835 3f202000.mmc: could not get clk, deferring probe
[    2.480193] sdhci-pltfm: SDHCI platform and OF driver helper
[    2.484463] ledtrig-cpu: registered to indicate activity on CPUs
[    2.487408] hidraw: raw HID events driver (C) Jiri Kosina
[    2.490170] usbcore: registered new interface driver usbhid
[    2.492785] usbhid: USB HID core driver
[    2.496309] vchiq: vchiq_init_state: slot_zero = (ptrval)
[    2.500562] [vc_sm_connected_init]: start
[    2.509410] [vc_sm_connected_init]: end - returning 0
[    2.513746] Initializing XFRM netlink socket
[    2.516337] NET: Registered protocol family 17
[    2.518941] Key type dns_resolver registered
[    2.521959] Registering SWP/SWPB emulation handler
[    2.524686] registered taskstats version 1
[    2.527151] Loading compiled-in X.509 certificates
[    2.530024] Key type ._fscrypt registered
[    2.532500] Key type .fscrypt registered
[    2.544271] uart-pl011 3f201000.serial: cts_event_workaround enabled
[    2.546916] 3f201000.serial: ttyAMA0 at MMIO 0x3f201000 (irq = 81, base_baud = 0) is a PL011 rev2
[    2.551219] bcm2835-power bcm2835-power: Broadcom BCM2835 power domains driver
[    2.555100] mmc-bcm2835 3f300000.mmcnr: mmc_debug:0 mmc_debug2:0
[    2.557728] mmc-bcm2835 3f300000.mmcnr: DMA channel allocated
[    2.586573] sdhost: log_buf @ (ptrval) (f0113000)
[    2.623846] mmc1: queuing unknown CIS tuple 0x80 (2 bytes)
[    2.627961] mmc1: queuing unknown CIS tuple 0x80 (3 bytes)
[    2.631934] mmc1: queuing unknown CIS tuple 0x80 (3 bytes)
[    2.637085] mmc1: queuing unknown CIS tuple 0x80 (7 bytes)
[    2.640433] mmc0: sdhost-bcm2835 loaded - DMA enabled (>1)
[    2.644797] of_cfs_init
[    2.647175] of_cfs_init: OK
[    2.650147] Waiting for root device PARTUUID=3172c665-02...
[    2.675655] Indeed it is in host mode hprt0 = 00021501
[    2.746527] random: fast init done
[    2.763972] mmc0: host does not support reading read-only switch, assuming write-enable
[    2.769696] mmc0: new high speed SDHC card at address 0001
[    2.773027] mmcblk0: mmc0:0001 GB1QT 29.8 GiB
[    2.778079]  mmcblk0: p1 p2
[    2.798615] EXT4-fs (mmcblk0p2): INFO: recovery required on readonly filesystem
[    2.799740] mmc1: new high speed SDIO card at address 0001
[    2.800993] EXT4-fs (mmcblk0p2): write access will be enabled during recovery
[    2.885575] usb 1-1: new high-speed USB device number 2 using dwc_otg
[    2.888288] Indeed it is in host mode hprt0 = 00001101
[    3.008133] EXT4-fs (mmcblk0p2): recovery complete
[    3.024519] EXT4-fs (mmcblk0p2): mounted filesystem with ordered data mode. Opts: (null)
[    3.027053] VFS: Mounted root (ext4 filesystem) readonly on device 179:2.
[    3.037650] devtmpfs: mounted
[    3.046762] Freeing unused kernel memory: 1024K
[    3.049493] Run /sbin/init as init process
[    3.125891] usb 1-1: New USB device found, idVendor=0424, idProduct=2514, bcdDevice= b.b3
[    3.128539] usb 1-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0
[    3.131854] hub 1-1:1.0: USB hub found
[    3.134674] hub 1-1:1.0: 4 ports detected
[    3.455581] usb 1-1.1: new high-speed USB device number 3 using dwc_otg
[    3.585881] usb 1-1.1: New USB device found, idVendor=0424, idProduct=2514, bcdDevice= b.b3
[    3.588803] usb 1-1.1: New USB device strings: Mfr=0, Product=0, SerialNumber=0
[    3.592336] hub 1-1.1:1.0: USB hub found
[    3.595237] hub 1-1.1:1.0: 3 ports detected
[    3.643855] systemd[1]: System time before build time, advancing clock.
[    3.783017] NET: Registered protocol family 10
[    3.787034] Segment Routing with IPv6
[    3.832410] systemd[1]: systemd 241 running in system mode. (+PAM +AUDIT +SELINUX +IMA +APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD -IDN2 +IDN -PCRE2 default-hierarchy=hybrid)
[    3.842166] systemd[1]: Detected architecture arm.
[    3.915579] usb 1-1.1.3: new full-speed USB device number 4 using dwc_otg
[    3.936860] systemd[1]: Set hostname to <raspberrypi>.
[    4.064028] usb 1-1.1.3: New USB device found, idVendor=046d, idProduct=c52b, bcdDevice=24.11
[    4.067282] usb 1-1.1.3: New USB device strings: Mfr=1, Product=2, SerialNumber=0
[    4.070254] usb 1-1.1.3: Product: USB Receiver
[    4.073199] usb 1-1.1.3: Manufacturer: Logitech
[    4.096859] 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:C52B.0001/input/input0
[    4.166379] hid-generic 0003:046D:C52B.0001: input,hidraw0: USB HID v1.11 Keyboard [Logitech USB Receiver] on usb-3f980000.usb-1.1.3/input0
[    4.185010] input: Logitech USB Receiver Mouse as /devices/platform/soc/3f980000.usb/usb1/1-1/1-1.1/1-1.1.3/1-1.1.3:1.1/0003:046D:C52B.0002/input/input1
[    4.192224] input: Logitech USB Receiver Consumer Control as /devices/platform/soc/3f980000.usb/usb1/1-1/1-1.1/1-1.1.3/1-1.1.3:1.1/0003:046D:C52B.0002/input/input2
[    4.265829] input: Logitech USB Receiver System Control as /devices/platform/soc/3f980000.usb/usb1/1-1/1-1.1/1-1.1.3/1-1.1.3:1.1/0003:046D:C52B.0002/input/input3
[    4.273475] hid-generic 0003:046D:C52B.0002: input,hiddev96,hidraw1: USB HID v1.11 Mouse [Logitech USB Receiver] on usb-3f980000.usb-1.1.3/input1
[    4.291158] hid-generic 0003:046D:C52B.0003: hiddev97,hidraw2: USB HID v1.11 Device [Logitech USB Receiver] on usb-3f980000.usb-1.1.3/input2
[    4.595581] usb 1-1.1.1: new high-speed USB device number 5 using dwc_otg
[    4.686034] random: systemd: uninitialized urandom read (16 bytes read)
[    4.705907] random: systemd: uninitialized urandom read (16 bytes read)
[    4.710263] systemd[1]: Set up automount Arbitrary Executable File Formats File System Automount Point.
[    4.722728] random: systemd: uninitialized urandom read (16 bytes read)
[    4.726116] usb 1-1.1.1: New USB device found, idVendor=0424, idProduct=7800, bcdDevice= 3.00
[    4.727165] systemd[1]: Listening on udev Kernel Socket.
[    4.730578] usb 1-1.1.1: New USB device strings: Mfr=0, Product=0, SerialNumber=0
[    4.743896] systemd[1]: Listening on fsck to fsckd communication Socket.
[    4.755601] systemd[1]: Created slice system-systemd\x2dfsck.slice.
[    4.765258] systemd[1]: Created slice system-getty.slice.
[    4.773488] systemd[1]: Reached target Swap.
[    4.807249] systemd[1]: Condition check resulted in Journal Audit Socket being skipped.
[    5.002163] i2c /dev entries driver
[    5.023451] lan78xx 1-1.1.1:1.0 (unnamed net_device) (uninitialized): No External EEPROM. Setting MAC Speed
[    5.041770] libphy: lan78xx-mdiobus: probed
[    5.074414] lan78xx 1-1.1.1:1.0 (unnamed net_device) (uninitialized): int urb period 64
[    5.640051] EXT4-fs (mmcblk0p2): re-mounted. Opts: (null)
[    5.763678] systemd-journald[114]: Received request to flush runtime journal from PID 1
[    6.679131] vc_sm_cma: module is from the staging directory, the quality is unknown, you have been warned.
[    6.689192] mc: Linux media interface: v0.10
[    6.692552] bcm2835_vc_sm_cma_probe: Videocore shared memory driver
[    6.692573] [vc_sm_connected_init]: start
[    6.696079] [vc_sm_connected_init]: installed successfully
[    6.756510] snd_bcm2835: module is from the staging directory, the quality is unknown, you have been warned.
[    6.773977] bcm2835_audio bcm2835_audio: card created with 8 channels
[    6.785837] videodev: Linux video capture interface: v2.00
[    6.902570] bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned.
[    6.902574] bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned.
[    6.904080] bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned.
[    6.913187] bcm2835_isp: module is from the staging directory, the quality is unknown, you have been warned.
[    6.915424] bcm2835_v4l2: module is from the staging directory, the quality is unknown, you have been warned.
[    6.923868] bcm2835_codec: module is from the staging directory, the quality is unknown, you have been warned.
[    7.084320] cfg80211: Loading compiled-in X.509 certificates for regulatory database
[    7.185251] cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'
[    7.273785] brcmfmac: F1 signature read @0x18000000=0x15264345
[    7.305831] brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6
[    7.306701] usbcore: registered new interface driver brcmfmac
[    7.337499] brcmfmac mmc1:0001:1: Direct firmware load for brcm/brcmfmac43455-sdio.raspberrypi,3-model-b-plus.txt failed with error -2
[    7.366773] input: raspberrypi-ts as /devices/platform/soc/soc:firmware/soc:firmware:touchscreen/input/input5
[    7.531016] logitech-djreceiver 0003:046D:C52B.0003: hiddev96,hidraw0: USB HID v1.11 Device [Logitech USB Receiver] on usb-3f980000.usb-1.1.3/input2
[    7.558305] bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video13
[    7.559493] brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6
[    7.560211] bcm2835-codec bcm2835-codec: Device registered as /dev/video10
[    7.560263] bcm2835-codec bcm2835-codec: Loaded V4L2 decode
[    7.560758] bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video14
[    7.561332] bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video15
[    7.561676] bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video16
[    7.561734] bcm2835-isp bcm2835-isp: Register output node 0 with media controller
[    7.561753] bcm2835-isp bcm2835-isp: Register capture node 1 with media controller
[    7.561770] bcm2835-isp bcm2835-isp: Register capture node 2 with media controller
[    7.561785] bcm2835-isp bcm2835-isp: Register capture node 3 with media controller
[    7.562015] bcm2835-isp bcm2835-ispi@raspberrypi:~ $ p: Loaded V4L2 bcm2835-isp
[    7.569620] bcm2835-codec bcm2835-codec: Device registered as /dev/video11
[    7.569666] bcm2835-codec bcm2835-codec: Loaded V4L2 encode
[    7.575095] bcm2835-codec bcm2835-codec: Device registered as /dev/video12
[    7.575152] bcm2835-codec bcm2835-codec: Loaded V4L2 isp
[    7.576278] bcm2835-v4l2: scene mode selected 0, was 0
[    7.576661] bcm2835-v4l2: V4L2 device registered as video0 - stills mode > 1280x720
[    7.581918] bcm2835-v4l2: Broadcom 2835 MMAL video capture ver 0.0.2 loaded.
[    7.583902] random: crng init done
[    7.583916] random: 7 urandom warning(s) missed due to ratelimiting
[    7.593144] brcmfmac: brcmf_c_preinit_dcmds: Firmware: BCM4345/6 wl0: Mar 23 2020 02:19:54 version 7.45.206 (r725000 CY) FWID 01-88ee44ea
[    7.690946] input: Logitech Unifying Device. Wireless PID:404d Keyboard as /devices/platform/soc/3f980000.usb/usb1/1-1/1-1.1/1-1.1.3/1-1.1.3:1.2/0003:046D:C52B.0003/0003:046D:404D.0004/input/input6
[    7.691913] input: Logitech Unifying Device. Wireless PID:404d Mouse as /devices/platform/soc/3f980000.usb/usb1/1-1/1-1.1/1-1.1.3/1-1.1.3:1.2/0003:046D:C52B.0003/0003:046D:404D.0004/input/input7
[    7.693952] input: Logitech Unifying Device. Wireless PID:404d Consumer Control as /devices/platform/soc/3f980000.usb/usb1/1-1/1-1.1/1-1.1.3/1-1.1.3:1.2/0003:046D:C52B.0003/0003:046D:404D.0004/input/input8
[    7.694173] input: Logitech Unifying Device. Wireless PID:404d System Control as /devices/platform/soc/3f980000.usb/usb1/1-1/1-1.1/1-1.1.3/1-1.1.3:1.2/0003:046D:C52B.0003/0003:046D:404D.0004/input/input9
[    7.694433] hid-generic 0003:046D:404D.0004: input,hidraw1: USB HID v1.11 Keyboard [Logitech Unifying Device. Wireless PID:404d] on usb-3f980000.usb-1.1.3/input2:1
[    8.662074] input: Logitech K400 Plus as /devices/platform/soc/3f980000.usb/usb1/1-1/1-1.1/1-1.1.3/1-1.1.3:1.2/0003:046D:C52B.0003/0003:046D:404D.0004/input/input13
[    8.663267] logitech-hidpp-device 0003:046D:404D.0004: input,hidraw1: USB HID v1.11 Keyboard [Logitech K400 Plus] on usb-3f980000.usb-1.1.3/input2:1
[    9.192674] uart-pl011 3f201000.serial: no DMA platform data
[    9.249696] 8021q: 802.1Q VLAN Support v1.8
[    9.495635] Adding 102396k swap on /var/swap.  Priority:-2 extents:2 across:307200k SSFS
[    9.621762] brcmfmac: brcmf_cfg80211_set_power_mgmt: power save enabled
[    9.951392] 8021q: adding VLAN 0 to HW filter on device eth0
[   15.661486] IPv6: ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready
[   15.958136] Bluetooth: Core ver 2.22
[   15.958216] NET: Registered protocol family 31
[   15.958224] Bluetooth: HCI device and connection manager initialized
[   15.958245] Bluetooth: HCI socket layer initialized
[   15.958258] Bluetooth: L2CAP socket layer initialized
[   15.958278] Bluetooth: SCO socket layer initialized
[   15.968210] Bluetooth: HCI UART driver ver 2.3
[   15.968227] Bluetooth: HCI UART protocol H4 registered
[   15.968285] Bluetooth: HCI UART protocol Three-wire (H5) registered
[   15.968488] Bluetooth: HCI UART protocol Broadcom registered
[   16.238244] Bluetooth: BNEP (Ethernet Emulation) ver 1.3
[   16.238258] Bluetooth: BNEP filters: protocol multicast
[   16.238279] Bluetooth: BNEP socket layer initialized
[  165.125288] logitech-hidpp-device 0003:046D:404D.0004: HID++ 4.1 device connected.
[  192.875674] i2c-bcm2835 3f804000.i2c: i2c transfer timed out
[  194.555578] i2c-bcm2835 3f804000.i2c: i2c transfer timed out
[  195.915594] i2c-bcm2835 3f804000.i2c: i2c transfer timed out
[  197.915730] i2c-bcm2835 3f804000.i2c: i2c transfer timed out
[  200.565917] i2c-bcm2835 3f804000.i2c: i2c transfer timed out
[  201.915641] i2c-bcm2835 3f804000.i2c: i2c transfer timed out
[  204.235830] i2c-bcm2835 3f804000.i2c: i2c transfer timed out
[  206.395725] i2c-bcm2835 3f804000.i2c: i2c transfer timed out
[  208.155710] i2c-bcm2835 3f804000.i2c: i2c transfer timed out
[  209.835706] i2c-bcm2835 3f804000.i2c: i2c transfer timed out
[  212.955890] i2c-bcm2835 3f804000.i2c: i2c transfer timed out
[  215.515716] i2c-bcm2835 3f804000.i2c: i2c transfer timed out
[  219.035829] i2c-bcm2835 3f804000.i2c: i2c transfer timed out
[  221.515930] i2c-bcm2835 3f804000.i2c: i2c transfer timed out
[  223.275910] i2c-bcm2835 3f804000.i2c: i2c transfer timed out
[  224.475702] i2c-bcm2835 3f804000.i2c: i2c transfer timed out
[  227.755817] i2c-bcm2835 3f804000.i2c: i2c transfer timed out
[  229.195804] i2c-bcm2835 3f804000.i2c: i2c transfer timed out
[  232.235884] i2c-bcm2835 3f804000.i2c: i2c transfer timed out
[  234.955766] i2c-bcm2835 3f804000.i2c: i2c transfer timed out
[  243.675832] i2c-bcm2835 3f804000.i2c: i2c transfer timed out
[  246.635960] i2c-bcm2835 3f804000.i2c: i2c transfer timed out
[  248.555789] i2c-bcm2835 3f804000.i2c: i2c transfer timed out
[  250.155837] i2c-bcm2835 3f804000.i2c: i2c transfer timed out
[  251.995777] i2c-bcm2835 3f804000.i2c: i2c transfer timed out
[  258.155773] i2c-bcm2835 3f804000.i2c: i2c transfer timed out
[  260.235895] i2c-bcm2835 3f804000.i2c: i2c transfer timed out
[  262.155866] i2c-bcm2835 3f804000.i2c: i2c transfer timed out
[  263.755807] i2c-bcm2835 3f804000.i2c: i2c transfer timed out
[  266.645805] i2c-bcm2835 3f804000.i2c: i2c transfer timed out
[  268.715912] i2c-bcm2835 3f804000.i2c: i2c transfer timed out
[  269.995797] i2c-bcm2835 3f804000.i2c: i2c transfer timed out
[  271.915761] i2c-bcm2835 3f804000.i2c: i2c transfer timed out
[  274.235752] i2c-bcm2835 3f804000.i2c: i2c transfer timed out
[  275.915755] i2c-bcm2835 3f804000.i2c: i2c transfer timed out
[  277.355758] i2c-bcm2835 3f804000.i2c: i2c transfer timed out
[  281.995720] i2c-bcm2835 3f804000.i2c: i2c transfer timed out
[  289.675954] i2c-bcm2835 3f804000.i2c: i2c transfer timed out
[  290.955915] i2c-bcm2835 3f804000.i2c: i2c transfer timed out
[  292.155624] i2c-bcm2835 3f804000.i2c: i2c transfer timed out
[  294.635625] i2c-bcm2835 3f804000.i2c: i2c transfer timed out
[  296.635770] i2c-bcm2835 3f804000.i2c: i2c transfer timed out
[  298.555731] i2c-bcm2835 3f804000.i2c: i2c transfer timed out
[  299.995576] i2c-bcm2835 3f804000.i2c: i2c transfer timed out
[  301.675925] i2c-bcm2835 3f804000.i2c: i2c transfer timed out
[  303.435582] i2c-bcm2835 3f804000.i2c: i2c transfer timed out
[  307.996061] i2c-bcm2835 3f804000.i2c: i2c transfer timed out
[  310.475526] i2c-bcm2835 3f804000.i2c: i2c transfer timed out
[  312.555636] i2c-bcm2835 3f804000.i2c: i2c transfer timed out
[  313.835598] i2c-bcm2835 3f804000.i2c: i2c transfer timed out
[  315.765608] i2c-bcm2835 3f804000.i2c: i2c transfer timed out
[  317.435661] i2c-bcm2835 3f804000.i2c: i2c transfer timed out
[  320.955523] i2c-bcm2835 3f804000.i2c: i2c transfer timed out
[  324.155517] i2c-bcm2835 3f804000.i2c: i2c transfer timed out
[  331.355573] i2c-bcm2835 3f804000.i2c: i2c transfer timed out
[  333.515561] i2c-bcm2835 3f804000.i2c: i2c transfer timed out
[  336.155638] i2c-bcm2835 3f804000.i2c: i2c transfer timed out
[  337.355462] i2c-bcm2835 3f804000.i2c: i2c transfer timed out
[  339.195640] i2c-bcm2835 3f804000.i2c: i2c transfer timed out
[  343.435653] i2c-bcm2835 3f804000.i2c: i2c transfer timed out
[  345.675523] i2c-bcm2835 3f804000.i2c: i2c transfer timed out
[  353.115538] i2c-bcm2835 3f804000.i2c: i2c transfer timed out
[  355.195460] i2c-bcm2835 3f804000.i2c: i2c transfer timed out
[  357.755527] i2c-bcm2835 3f804000.i2c: i2c transfer timed out
[  359.995590] i2c-bcm2835 3f804000.i2c: i2c transfer timed out
[  361.115866] i2c-bcm2835 3f804000.i2c: i2c transfer timed out
[  362.315455] i2c-bcm2835 3f804000.i2c: i2c transfer timed out
[  364.155461] i2c-bcm2835 3f804000.i2c: i2c transfer timed out
[  365.365467] i2c-bcm2835 3f804000.i2c: i2c transfer timed out
[  367.355431] i2c-bcm2835 3f804000.i2c: i2c transfer timed out
[  372.315499] i2c-bcm2835 3f804000.i2c: i2c transfer timed out
[  374.725593] i2c-bcm2835 3f804000.i2c: i2c transfer timed out
[  379.835618] i2c-bcm2835 3f804000.i2c: i2c transfer timed out
[  384.075531] i2c-bcm2835 3f804000.i2c: i2c transfer timed out
[  385.435640] i2c-bcm2835 3f804000.i2c: i2c transfer timed out
[  386.715448] i2c-bcm2835 3f804000.i2c: i2c transfer timed out
[  390.635468] i2c-bcm2835 3f804000.i2c: i2c transfer timed out
[  395.435446] i2c-bcm2835 3f804000.i2c: i2c transfer timed out
[  397.035445] i2c-bcm2835 3f804000.i2c: i2c transfer timed out
[  398.475426] i2c-bcm2835 3f804000.i2c: i2c transfer timed out
[  401.515570] i2c-bcm2835 3f804000.i2c: i2c transfer timed out
[  403.675451] i2c-bcm2835 3f804000.i2c: i2c transfer timed out
[  406.155469] i2c-bcm2835 3f804000.i2c: i2c transfer timed out
[  407.435703] i2c-bcm2835 3f804000.i2c: i2c transfer timed out
[  414.715582] i2c-bcm2835 3f804000.i2c: i2c transfer timed out
[  417.595406] i2c-bcm2835 3f804000.i2c: i2c transfer timed out
[  420.485539] i2c-bcm2835 3f804000.i2c: i2c transfer timed out
[  422.075428] i2c-bcm2835 3f804000.i2c: i2c transfer timed out
[  428.635457] i2c-bcm2835 3f804000.i2c: i2c transfer timed out
[  431.035401] i2c-bcm2835 3f804000.i2c: i2c transfer timed out
[  432.635400] i2c-bcm2835 3f804000.i2c: i2c transfer timed out
[  434.475441] i2c-bcm2835 3f804000.i2c: i2c transfer timed out
[  437.435452] i2c-bcm2835 3f804000.i2c: i2c transfer timed out
[  441.275422] i2c-bcm2835 3f804000.i2c: i2c transfer timed out
[  444.635492] i2c-bcm2835 3f804000.i2c: i2c transfer timed out
[  446.795419] i2c-bcm2835 3f804000.i2c: i2c transfer timed out
[  452.395415] i2c-bcm2835 3f804000.i2c: i2c transfer timed out
[  454.955470] i2c-bcm2835 3f804000.i2c: i2c transfer timed out
[  456.875406] i2c-bcm2835 3f804000.i2c: i2c transfer timed out
[  461.036336] i2c-bcm2835 3f804000.i2c: i2c transfer timed out
[  463.275458] i2c-bcm2835 3f804000.i2c: i2c transfer timed out

config.txt (current version but it tried multiple options see https://www.raspberrypi.org/forums/viewtopic.php?f=44&t=287015&p=1738555#p1734925)


# For more options and information see
# http://rpf.io/configtxt
# Some settings may impact device functionality. See link above for details

# uncomment if you get no picture on HDMI for a default "safe" mode
#hdmi_safe=1

# uncomment this if your display has a black border of unused pixels visible
# and your display can output without overscan
#disable_overscan=1

# uncomment the following to adjust overscan. Use positive numbers if console
# goes off screen, and negative if there is too much border
#overscan_left=16
#overscan_right=16
#overscan_top=16
#overscan_bottom=16

# uncomment to force a console size. By default it will be display's size minus
# overscan.
#framebuffer_width=1280
#framebuffer_height=720

# uncomment if hdmi display is not detected and composite is being output
#hdmi_force_hotplug=1

# uncomment to force a specific HDMI mode (this will force VGA)
#hdmi_group=1
#hdmi_mode=1

# uncomment to force a HDMI mode rather than DVI. This can make audio work in
# DMT (computer monitor) modes
#hdmi_drive=2

# uncomment to increase signal to HDMI, if you have interference, blanking, or
# no display
#config_hdmi_boost=4

# uncomment for composite PAL
#sdtv_mode=2

#uncomment to overclock the arm. 700 MHz is the default.
#arm_freq=800

# Uncomment some or all of these to enable the optional hardware interfaces
dtparam=i2c_arm=on,i2c_arm_baudrate=400000
#dtparam=i2s=on
#dtparam=spi=on

# Uncomment this to enable infrared communication.
#dtoverlay=gpio-ir,gpio_pin=17
#dtoverlay=gpio-ir-tx,gpio_pin=18

# Additional overlays and parameters are documented /boot/overlays/README

# Enable audio (loads snd_bcm2835)
dtparam=audio=on

[pi4]
# Enable DRM VC4 V3D driver on top of the dispmanx display stack
dtoverlay=vc4-fkms-v3d
max_framebuffers=2

[all]
#dtoverlay=vc4-fkms-v3d
start_x=1
gpu_mem=192

core_freq=250

RPI4: raspinfo

pi@raspberrypi:~ $ raspinfo
System Information
------------------

Raspberry Pi 4 Model B Rev 1.1
PRETTY_NAME="Raspbian GNU/Linux 10 (buster)"
NAME="Raspbian GNU/Linux"
VERSION_ID="10"
VERSION="10 (buster)"

Raspberry Pi reference 2020-08-20
Generated using pi-gen, https://github.com/RPi-Distro/pi-gen, 9a3a10bf1019ebb2d59053564dc6b90068bad27d, stage2

Linux raspberrypi 5.4.68-v7l+ #1343 SMP Mon Sep 28 12:44:25 BST 2020 armv7l GNU/Linux
Revision    : b03111
Serial      : 10000000e9a7a285
Model       : Raspberry Pi 4 Model B Rev 1.1
Throttled flag  : throttled=0x0
Camera          : supported=1 detected=1

Videocore information
---------------------

Sep 28 2020 12:53:40 
Copyright (c) 2012 Broadcom
version 2c4b5cf8e4a920c2976ebdb62b27bd742b69ea8b (clean) (release) (start_x)

alloc failures:     0
compactions:        0
legacy block fails: 0

Filesystem information
----------------------
Filesystem     1K-blocks    Used Available Use% Mounted on
/dev/root       61268276 2939944  55807924   6% /
devtmpfs          765200       0    765200   0% /dev
tmpfs             897296      36    897260   1% /dev/shm
tmpfs             897296    8632    888664   1% /run
tmpfs               5120       4      5116   1% /run/lock
tmpfs             897296       0    897296   0% /sys/fs/cgroup
/dev/mmcblk0p1    258095   55185    202911  22% /boot
tmpfs             179456       0    179456   0% /run/user/1000

Filename                Type        Size    Used    Priority
/var/swap                               file        102396  0   -2

Package version information
---------------------------
raspberrypi-ui-mods:
  Installed: (none)
raspberrypi-sys-mods:
  Installed: 20200812
openbox:
  Installed: (none)
lxpanel:
  Installed: (none)
pcmanfm:
  Installed: (none)
rpd-plym-splash:
  Installed: (none)

Networking Information
----------------------

eth0: flags=4163<UP,BROADCAST,RUNNING,MULTICAST>  mtu 1500
        inet x.x.x.x  netmask x.x.x.x  broadcast x.x.x.x
        inet6 y::y.y.y.y  prefixlen 64  scopeid 0x20<link>
        ether m.m.m.m  txqueuelen 1000  (Ethernet)
        RX packets 764  bytes 82315 (80.3 KiB)
        RX errors 0  dropped 0  overruns 0  frame 0
        TX packets 5081  bytes 291475 (284.6 KiB)
        TX errors 0  dropped 0 overruns 0  carrier 0  collisions 0

lo: flags=73<UP,LOOPBACK,RUNNING>  mtu 65536
        inet x.x.x.x  netmask x.x.x.x
        inet6 ::1  prefixlen 128  scopeid 0x10<host>
        loop  txqueuelen 1000  (Local Loopback)
        RX packets 504  bytes 44200 (43.1 KiB)
        RX errors 0  dropped 0  overruns 0  frame 0
        TX packets 504  bytes 44200 (43.1 KiB)
        TX errors 0  dropped 0 overruns 0  carrier 0  collisions 0

USB Information
---------------

/:  Bus 02.Port 1: Dev 1, Class=root_hub, Driver=xhci_hcd/4p, 5000M
/:  Bus 01.Port 1: Dev 1, Class=root_hub, Driver=xhci_hcd/1p, 480M
    |__ Port 1: Dev 2, If 0, Class=Hub, Driver=hub/4p, 480M

config.txt
----------

arm_freq=1500
audio_pwm_mode=514
config_hdmi_boost=5
core_freq=500
core_freq_min=200
disable_commandline_tags=2
disable_l2cache=1
display_default_lcd=1
display_hdmi_rotate=-1
display_lcd_rotate=-1
enable_gic=1
force_eeprom_read=1
force_pwm_open=1
framebuffer_ignore_alpha=1
framebuffer_swap=1
gpu_freq=500
gpu_freq_min=250
init_uart_clock=0x2dc6c00
lcd_framerate=60
mask_gpu_interrupt0=1024
mask_gpu_interrupt1=0x10000
max_framebuffers=2
over_voltage_avs=-30000
pause_burst_frames=1
program_serial_random=1
total_mem=2048
hdmi_force_cec_address:0=65535
hdmi_force_cec_address:1=65535
hdmi_pixel_freq_limit:0=0x11e1a300
hdmi_pixel_freq_limit:1=0x11e1a300
device_tree=-
overlay_prefix=overlays/
hdmi_cvt:0=
hdmi_cvt:1=
hdmi_edid_filename:0=
hdmi_edid_filename:1=
hdmi_timings:0=
hdmi_timings:1=

cmdline.txt
-----------
coherent_pool=1M 8250.nr_uarts=0 snd_bcm2835.enable_compat_alsa=0 snd_bcm2835.enable_hdmi=1 snd_bcm2835.enable_headphones=1  smsc95xx.macaddr=DC:A6:32:32:9D:91 vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000  console=ttyS0,115200 console=tty1 root=PARTUUID=49476cb6-02 rootfstype=ext4 elevator=deadline fsck.repair=yes rootwait

raspi-gpio settings
-------------------

BANK0 (GPIO 0 to 27):
GPIO 0: level=1 fsel=0 func=INPUT pull=UP
GPIO 1: level=1 fsel=0 func=INPUT pull=UP
GPIO 2: level=1 fsel=4 alt=0 func=SDA1 pull=UP
GPIO 3: level=1 fsel=4 alt=0 func=SCL1 pull=UP
GPIO 4: level=1 fsel=0 func=INPUT pull=UP
GPIO 5: level=1 fsel=0 func=INPUT pull=UP
GPIO 6: level=1 fsel=0 func=INPUT pull=UP
GPIO 7: level=1 fsel=0 func=INPUT pull=UP
GPIO 8: level=1 fsel=0 func=INPUT pull=UP
GPIO 9: level=0 fsel=0 func=INPUT pull=DOWN
GPIO 10: level=0 fsel=0 func=INPUT pull=DOWN
GPIO 11: level=0 fsel=0 func=INPUT pull=DOWN
GPIO 12: level=0 fsel=0 func=INPUT pull=DOWN
GPIO 13: level=0 fsel=0 func=INPUT pull=DOWN
GPIO 14: level=1 fsel=0 func=INPUT pull=NONE
GPIO 15: level=1 fsel=0 func=INPUT pull=UP
GPIO 16: level=0 fsel=0 func=INPUT pull=DOWN
GPIO 17: level=1 fsel=1 func=OUTPUT pull=NONE
GPIO 18: level=1 fsel=0 func=INPUT pull=NONE
GPIO 19: level=0 fsel=0 func=INPUT pull=DOWN
GPIO 20: level=0 fsel=0 func=INPUT pull=DOWN
GPIO 21: level=0 fsel=0 func=INPUT pull=DOWN
GPIO 22: level=0 fsel=0 func=INPUT pull=DOWN
GPIO 23: level=0 fsel=0 func=INPUT pull=DOWN
GPIO 24: level=0 fsel=0 func=INPUT pull=DOWN
GPIO 25: level=0 fsel=0 func=INPUT pull=DOWN
GPIO 26: level=0 fsel=0 func=INPUT pull=DOWN
GPIO 27: level=0 fsel=0 func=INPUT pull=DOWN
BANK1 (GPIO 28 to 45):
GPIO 28: level=1 fsel=2 alt=5 func=RGMII_MDIO pull=UP
GPIO 29: level=0 fsel=2 alt=5 func=RGMII_MDC pull=DOWN
GPIO 30: level=0 fsel=7 alt=3 func=CTS0 pull=UP
GPIO 31: level=0 fsel=7 alt=3 func=RTS0 pull=NONE
GPIO 32: level=1 fsel=7 alt=3 func=TXD0 pull=NONE
GPIO 33: level=1 fsel=7 alt=3 func=RXD0 pull=UP
GPIO 34: level=1 fsel=7 alt=3 func=SD1_CLK pull=NONE
GPIO 35: level=1 fsel=7 alt=3 func=SD1_CMD pull=UP
GPIO 36: level=1 fsel=7 alt=3 func=SD1_DAT0 pull=UP
GPIO 37: level=1 fsel=7 alt=3 func=SD1_DAT1 pull=UP
GPIO 38: level=1 fsel=7 alt=3 func=SD1_DAT2 pull=UP
GPIO 39: level=1 fsel=7 alt=3 func=SD1_DAT3 pull=UP
GPIO 40: level=0 fsel=4 alt=0 func=PWM1_0 pull=NONE
GPIO 41: level=0 fsel=4 alt=0 func=PWM1_1 pull=NONE
GPIO 42: level=0 fsel=1 func=OUTPUT pull=UP
GPIO 43: level=1 fsel=0 func=INPUT pull=UP
GPIO 44: level=1 fsel=5 alt=1 func=SDA0 pull=UP
GPIO 45: level=1 fsel=5 alt=1 func=SCL0 pull=UP
BANK2 (GPIO 46 to 53):
GPIO 46: level=0 fsel=0 func=INPUT pull=DOWN
GPIO 47: level=0 fsel=0 func=INPUT pull=DOWN
GPIO 48: level=0 fsel=0 func=INPUT pull=DOWN
GPIO 49: level=0 fsel=0 func=INPUT pull=DOWN
GPIO 50: level=0 fsel=0 func=INPUT pull=DOWN
GPIO 51: level=0 fsel=0 func=INPUT pull=DOWN
GPIO 52: level=0 fsel=0 func=INPUT pull=DOWN
GPIO 53: level=0 fsel=0 func=INPUT pull=DOWN

vcdbg log messages
------------------

005388.096: arasan: arasan_emmc_open
005539.083: brfs: File read: /mfs/sd/config.txt
005539.901: brfs: File read: 1831 bytes
005601.866: brfs: File read: /mfs/sd/config.txt
006708.402: gpioman: gpioman_get_pin_num: pin DISPLAY_DSI_PORT not defined
007011.173: *** Restart logging
007011.194: brfs: File read: 1831 bytes
007015.393: hdmi: HDMI:hdmi_get_state is deprecated, use hdmi_get_display_state instead
007018.765: hdmi: HDMI:hdmi_get_state is deprecated, use hdmi_get_display_state instead
007018.778: HDMI0: hdmi_pixel_encoding: 300000000
007018.792: HDMI1: hdmi_pixel_encoding: 300000000
007023.729: dtb_file 'bcm2711-rpi-4-b.dtb'
007027.693: brfs: File read: /mfs/sd/bcm2711-rpi-4-b.dtb
007027.710: Loading 'bcm2711-rpi-4-b.dtb' to 0x100 size 0xb99c
007039.887: brfs: File read: 47516 bytes
007050.562: brfs: File read: /mfs/sd/overlays/overlay_map.dtb
007103.397: brfs: File read: 1463 bytes
007112.615: brfs: File read: /mfs/sd/overlays/rpi-ft5406.dtbo
007113.429: Loaded overlay 'rpi-ft5406'
007118.590: brfs: File read: 842 bytes
007127.203: brfs: File read: /mfs/sd/overlays/rpi-backlight.dtbo
007133.013: Loaded overlay 'rpi-backlight'
007145.213: brfs: File read: 489 bytes
007147.809: brfs: File read: /mfs/sd/config.txt
007148.246: dtparam: i2c_arm=on
007156.419: dtparam: i2c_arm_baudrate=400000
007164.249: dtparam: audio=on
007173.638: brfs: File read: 1831 bytes
007185.709: brfs: File read: /mfs/sd/overlays/vc4-fkms-v3d.dtbo
007202.734: Loaded overlay 'vc4-fkms-v3d'
007242.198: brfs: File read: 1446 bytes
007244.078: brfs: File read: /mfs/sd/cmdline.txt
007244.114: Read command line from file 'cmdline.txt':
007244.126: 'console=serial0,115200 console=tty1 root=PARTUUID=49476cb6-02 rootfstype=ext4 elevator=deadline fsck.repair=yes rootwait'
008314.520: brfs: File read: 121 bytes
008690.449: brfs: File read: /mfs/sd/kernel7l.img
008690.469: Loading 'kernel7l.img' to 0x8000 size 0x5e0d00
008690.496: Device tree loaded to 0x2eff4000 (size 0xbfc0)
008698.487: gpioman: gpioman_get_pin_num: pin SDCARD_CONTROL_POWER not defined
008698.535: arasan: arasan_emmc_close
011012.514: vchiq_core: vchiq_init_state: slot_zero = 0xded80000, is_master = 1
014064.879: camsubs: Looking for camera 0: i2c_port = 0, led gpio = -1, power enable gpio = 133
014365.617: camsubs: Camera not found
014365.666: camsubs: Looking for camera 0: i2c_port = 0, led gpio = -1, power enable gpio = 133
014666.399: camsubs: Camera not found
014666.446: camsubs: Looking for camera 0: i2c_port = 0, led gpio = -1, power enable gpio = 133
014667.168: camsubs: Camera not found
014667.213: camsubs: Looking for camera 0: i2c_port = 0, led gpio = -1, power enable gpio = 133
014668.847: camsubs: Camera found OK
339754.941: Using RPI AWB
339875.949: Using RPI AWB
339997.816: Using RPI AWB
370077.499: Using RPI AWB
371823.374: brfs: File read: 6163712 bytes
371823.434: mmal: mmal_vll_load: could not load VLL 'videnc.vll': 

dmesg log
---------

[    0.000000] Booting Linux on physical CPU 0x0
[    0.000000] Linux version 5.4.68-v7l+ (dom@buildbot) (gcc version 4.9.3 (crosstool-NG crosstool-ng-1.22.0-88-g8460611)) #1343 SMP Mon Sep 28 12:44:25 BST 2020
[    0.000000] CPU: ARMv7 Processor [410fd083] revision 3 (ARMv7), cr=30c5383d
[    0.000000] CPU: div instructions available: patching division code
[    0.000000] CPU: PIPT / VIPT nonaliasing data cache, PIPT instruction cache
[    0.000000] OF: fdt: Machine model: Raspberry Pi 4 Model B Rev 1.1
[    0.000000] Memory policy: Data cache writealloc
[    0.000000] Reserved memory: created CMA memory pool at 0x000000001ec00000, size 256 MiB
[    0.000000] OF: reserved mem: initialized node linux,cma, compatible id shared-dma-pool
[    0.000000] On node 0 totalpages: 475136
[    0.000000]   DMA zone: 2304 pages used for memmap
[    0.000000]   DMA zone: 0 pages reserved
[    0.000000]   DMA zone: 196608 pages, LIFO batch:63
[    0.000000]   HighMem zone: 278528 pages, LIFO batch:63
[    0.000000] percpu: Embedded 20 pages/cpu s49920 r8192 d23808 u81920
[    0.000000] pcpu-alloc: s49920 r8192 d23808 u81920 alloc=20*4096
[    0.000000] pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 
[    0.000000] Built 1 zonelists, mobility grouping on.  Total pages: 472832
[    0.000000] Kernel command line: coherent_pool=1M 8250.nr_uarts=0 snd_bcm2835.enable_compat_alsa=0 snd_bcm2835.enable_hdmi=1 snd_bcm2835.enable_headphones=1  smsc95xx.macaddr=m.m.m.m vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000  console=ttyS0,115200 console=tty1 root=PARTUUID=49476cb6-02 rootfstype=ext4 elevator=deadline fsck.repair=yes rootwait
[    0.000000] Dentry cache hash table entries: 131072 (order: 7, 524288 bytes, linear)
[    0.000000] Inode-cache hash table entries: 65536 (order: 6, 262144 bytes, linear)
[    0.000000] mem auto-init: stack:off, heap alloc:off, heap free:off
[    0.000000] software IO TLB: mapped [mem 0x19400000-0x1d400000] (64MB)
[    0.000000] Memory: 1530404K/1900544K available (10240K kernel code, 738K rwdata, 2808K rodata, 2048K init, 857K bss, 107996K reserved, 262144K cma-reserved, 1114112K highmem)
[    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1
[    0.000000] ftrace: allocating 31117 entries in 61 pages
[    0.000000] rcu: Hierarchical RCU implementation.
[    0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 10 jiffies.
[    0.000000] NR_IRQS: 16, nr_irqs: 16, preallocated irqs: 16
[    0.000000] GIC: Using split EOI/Deactivate mode
[    0.000000] random: get_random_bytes called from start_kernel+0x344/0x520 with crng_init=0
[    0.000007] sched_clock: 32 bits at 1000kHz, resolution 1000ns, wraps every 2147483647500ns
[    0.000031] clocksource: timer: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275 ns
[    0.000111] bcm2835: system timer (irq = 17)
[    0.000781] arch_timer: cp15 timer(s) running at 54.00MHz (phys).
[    0.000801] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0xc743ce346, max_idle_ns: 440795203123 ns
[    0.000822] sched_clock: 56 bits at 54MHz, resolution 18ns, wraps every 4398046511102ns
[    0.000838] Switching to timer-based delay loop, resolution 18ns
[    0.001080] Console: colour dummy device 80x30
[    0.001570] printk: console [tty1] enabled
[    0.001631] Calibrating delay loop (skipped), value calculated using timer frequency.. 108.00 BogoMIPS (lpj=540000)
[    0.001672] pid_max: default: 32768 minimum: 301
[    0.001829] LSM: Security Framework initializing
[    0.002034] Mount-cache hash table entries: 2048 (order: 1, 8192 bytes, linear)
[    0.002074] Mountpoint-cache hash table entries: 2048 (order: 1, 8192 bytes, linear)
[    0.003269] Disabling memory control group subsystem
[    0.003407] CPU: Testing write buffer coherency: ok
[    0.003903] CPU0: thread -1, cpu 0, socket 0, mpidr 80000000
[    0.004758] Setting up static identity map for 0x200000 - 0x20003c
[    0.004964] rcu: Hierarchical SRCU implementation.
[    0.005604] smp: Bringing up secondary CPUs ...
[    0.006674] CPU1: thread -1, cpu 1, socket 0, mpidr 80000001
[    0.007853] CPU2: thread -1, cpu 2, socket 0, mpidr 80000002
[    0.008979] CPU3: thread -1, cpu 3, socket 0, mpidr 80000003
[    0.009123] smp: Brought up 1 node, 4 CPUs
[    0.009192] SMP: Total of 4 processors activated (432.00 BogoMIPS).
[    0.009218] CPU: All CPU(s) started in HYP mode.
[    0.009241] CPU: Virtualization extensions available.
[    0.010021] devtmpfs: initialized
[    0.023333] VFP support v0.3: implementor 41 architecture 3 part 40 variant 8 rev 0
[    0.023614] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns
[    0.023663] futex hash table entries: 1024 (order: 4, 65536 bytes, linear)
[    0.031527] pinctrl core: initialized pinctrl subsystem
[    0.032504] NET: Registered protocol family 16
[    0.036101] DMA: preallocated 1024 KiB pool for atomic coherent allocations
[    0.036729] audit: initializing netlink subsys (disabled)
[    0.036980] audit: type=2000 audit(0.030:1): state=initialized audit_enabled=0 res=1
[    0.038066] hw-breakpoint: found 5 (+1 reserved) breakpoint and 4 watchpoint registers.
[    0.038100] hw-breakpoint: maximum watchpoint size is 8 bytes.
[    0.038438] Serial: AMBA PL011 UART driver
[    0.042040] bcm2835-mbox fe00b880.mailbox: mailbox enabled
[    0.060850] raspberrypi-firmware soc:firmware: Attached to firmware from 2020-09-28 12:53, variant start_x
[    0.070867] raspberrypi-firmware soc:firmware: Firmware hash is 2c4b5cf8e4a920c2976ebdb62b27bd742b69ea8b
[    0.125838] bcm2835-dma fe007000.dma: DMA legacy API manager, dmachans=0x1
[    0.130359] vgaarb: loaded
[    0.130835] SCSI subsystem initialized
[    0.131128] usbcore: registered new interface driver usbfs
[    0.131202] usbcore: registered new interface driver hub
[    0.131333] usbcore: registered new device driver usb
[    0.131660] usb_phy_generic phy: phy supply vcc not found, using dummy regulator
[    0.133437] clocksource: Switched to clocksource arch_sys_counter
[    0.911374] VFS: Disk quotas dquot_6.6.0
[    0.911486] VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes)
[    0.911660] FS-Cache: Loaded
[    0.911860] CacheFiles: Loaded
[    0.912719] simple-framebuffer 3ea50000.framebuffer: framebuffer at 0x3ea50000, 0x177000 bytes, mapped to 0x(ptrval)
[    0.912759] simple-framebuffer 3ea50000.framebuffer: format=a8r8g8b8, mode=800x480x32, linelength=3200
[    0.915168] Console: switching to colour frame buffer device 100x30
[    0.918159] simple-framebuffer 3ea50000.framebuffer: fb0: simplefb registered!
[    0.929181] thermal_sys: Registered thermal governor 'step_wise'
[    0.929541] NET: Registered protocol family 2
[    0.932459] tcp_listen_portaddr_hash hash table entries: 512 (order: 0, 6144 bytes, linear)
[    0.933654] TCP established hash table entries: 8192 (order: 3, 32768 bytes, linear)
[    0.934843] TCP bind hash table entries: 8192 (order: 4, 65536 bytes, linear)
[    0.936021] TCP: Hash tables configured (established 8192 bind 8192)
[    0.937260] UDP hash table entries: 512 (order: 2, 16384 bytes, linear)
[    0.938387] UDP-Lite hash table entries: 512 (order: 2, 16384 bytes, linear)
[    0.939748] NET: Registered protocol family 1
[    0.941564] RPC: Registered named UNIX socket transport module.
[    0.942723] RPC: Registered udp transport module.
[    0.943909] RPC: Registered tcp transport module.
[    0.945028] RPC: Registered tcp NFSv4.1 backchannel transport module.
[    0.946139] PCI: CLS 0 bytes, default 64
[    0.949014] hw perfevents: enabled with armv7_cortex_a15 PMU driver, 7 counters available
[    0.952302] Initialise system trusted keyrings
[    0.953675] workingset: timestamp_bits=14 max_order=19 bucket_order=5
[    0.965708] FS-Cache: Netfs 'nfs' registered for caching
[    0.967538] NFS: Registering the id_resolver key type
[    0.968687] Key type id_resolver registered
[    0.969788] Key type id_legacy registered
[    0.970862] nfs4filelayout_init: NFSv4 File Layout Driver Registering...
[    0.973006] Key type asymmetric registered
[    0.974106] Asymmetric key parser 'x509' registered
[    0.975306] bounce: pool size: 64 pages
[    0.976366] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 249)
[    0.977629] io scheduler mq-deadline registered
[    0.978659] io scheduler kyber registered
[    0.983577] brcm-pcie fd500000.pcie: host bridge /scb/pcie@7d500000 ranges:
[    0.984607] brcm-pcie fd500000.pcie:   No bus range found for /scb/pcie@7d500000, using [bus 00-ff]
[    0.986630] brcm-pcie fd500000.pcie:      MEM 0x0600000000..0x0603ffffff -> 0x00f8000000
[    0.987731] brcm-pcie fd500000.pcie:   IB MEM 0x0000000000..0x007fffffff -> 0x0000000000
[    1.045546] brcm-pcie fd500000.pcie: link up, 5 GT/s x1 (SSC)
[    1.046836] brcm-pcie fd500000.pcie: PCI host bridge to bus 0000:00
[    1.047854] pci_bus 0000:00: root bus resource [bus 00-ff]
[    1.048897] pci_bus 0000:00: root bus resource [mem 0x600000000-0x603ffffff] (bus address [0xf8000000-0xfbffffff])
[    1.051014] pci 0000:00:00.0: [14e4:2711] type 01 class 0x060400
[    1.052299] pci 0000:00:00.0: PME# supported from D0 D3hot
[    1.056749] PCI: bus0: Fast back to back transfers disabled
[    1.058000] pci 0000:01:00.0: [1106:3483] type 00 class 0x0c0330
[    1.059153] pci 0000:01:00.0: reg 0x10: [mem 0x00000000-0x00000fff 64bit]
[    1.060585] pci 0000:01:00.0: PME# supported from D0 D3cold
[    1.064984] PCI: bus1: Fast back to back transfers disabled
[    1.066065] pci 0000:00:00.0: BAR 8: assigned [mem 0x600000000-0x6000fffff]
[    1.067120] pci 0000:01:00.0: BAR 0: assigned [mem 0x600000000-0x600000fff 64bit]
[    1.068206] pci 0000:00:00.0: PCI bridge to [bus 01]
[    1.069224] pci 0000:00:00.0:   bridge window [mem 0x600000000-0x6000fffff]
[    1.070540] pcieport 0000:00:00.0: enabling device (0140 -> 0142)
[    1.071784] pcieport 0000:00:00.0: PME: Signaling with IRQ 56
[    1.073143] pcieport 0000:00:00.0: AER: enabled with IRQ 56
[    1.074495] pci 0000:01:00.0: enabling device (0140 -> 0142)
[    1.082341] iproc-rng200 fe104000.rng: hwrng registered
[    1.083678] vc-mem: phys_addr:0x00000000 mem_base=0x3ec00000 mem_size:0x40000000(1024 MiB)
[    1.085315] vc-sm: Videocore shared memory driver
[    1.086819] gpiomem-bcm2835 fe200000.gpiomem: Initialised: Registers at 0xfe200000
[    1.099706] brd: module loaded
[    1.112753] loop: module loaded
[    1.115263] Loading iSCSI transport class v2.0-870.
[    1.118250] libphy: Fixed MDIO Bus: probed
[    1.119817] bcmgenet fd580000.ethernet: failed to get enet clock
[    1.120797] bcmgenet fd580000.ethernet: GENET 5.0 EPHY: 0x0000
[    1.121747] bcmgenet fd580000.ethernet: failed to get enet-wol clock
[    1.122696] bcmgenet fd580000.ethernet: failed to get enet-eee clock
[    1.123667] bcmgenet: Skipping UMAC reset
[    1.143512] libphy: bcmgenet MII bus: probed
[    1.233538] unimac-mdio unimac-mdio.-19: Broadcom UniMAC MDIO bus
[    1.235459] usbcore: registered new interface driver r8152
[    1.236431] usbcore: registered new interface driver lan78xx
[    1.237362] usbcore: registered new interface driver smsc95xx
[    1.238632] xhci_hcd 0000:01:00.0: xHCI Host Controller
[    1.239526] xhci_hcd 0000:01:00.0: new USB bus registered, assigned bus number 1
[    1.242803] xhci_hcd 0000:01:00.0: hcc params 0x002841eb hci version 0x100 quirks 0x0000001000000890
[    1.245836] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 5.04
[    1.246819] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    1.247790] usb usb1: Product: xHCI Host Controller
[    1.248747] usb usb1: Manufacturer: Linux 5.4.68-v7l+ xhci-hcd
[    1.249689] usb usb1: SerialNumber: 0000:01:00.0
[    1.251185] hub 1-0:1.0: USB hub found
[    1.252151] hub 1-0:1.0: 1 port detected
[    1.253571] xhci_hcd 0000:01:00.0: xHCI Host Controller
[    1.254458] xhci_hcd 0000:01:00.0: new USB bus registered, assigned bus number 2
[    1.255357] xhci_hcd 0000:01:00.0: Host supports USB 3.0 SuperSpeed
[    1.256715] usb usb2: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 5.04
[    1.257636] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNupi@raspberrypi:~ $ mber=1
[    1.258564] usb usb2: Product: xHCI Host Controller
[    1.259489] usb usb2: Manufacturer: Linux 5.4.68-v7l+ xhci-hcd
[    1.260443] usb usb2: SerialNumber: 0000:01:00.0
[    1.261958] hub 2-0:1.0: USB hub found
[    1.262958] hub 2-0:1.0: 4 ports detected
[    1.265376] dwc_otg: version 3.00a 10-AUG-2012 (platform bus)
[    1.266574] dwc_otg: FIQ enabled
[    1.266585] dwc_otg: NAK holdoff enabled
[    1.266596] dwc_otg: FIQ split-transaction FSM enabled
[    1.266611] Module dwc_common_port init
[    1.267039] usbcore: registered new interface driver uas
[    1.268095] usbcore: registered new interface driver usb-storage
[    1.269224] mousedev: PS/2 mouse device common for all mice
[    1.271955] bcm2835-wdt bcm2835-wdt: Broadcom BCM2835 watchdog timer
[    1.276317] sdhci: Secure Digital Host Controller Interface driver
[    1.277283] sdhci: Copyright(c) Pierre Ossman
[    1.278763] mmc-bcm2835 fe300000.mmcnr: could not get clk, deferring probe
[    1.280205] sdhci-pltfm: SDHCI platform and OF driver helper
[    1.284826] ledtrig-cpu: registered to indicate activity on CPUs
[    1.286041] hidraw: raw HID events driver (C) Jiri Kosina
[    1.287134] usbcore: registered new interface driver usbhid
[    1.288059] usbhid: USB HID core driver
[    1.289978] vchiq: vchiq_init_state: slot_zero = (ptrval)
[    1.292802] [vc_sm_connected_init]: start
[    1.302022] [vc_sm_connected_init]: end - returning 0
[    1.305068] Initializing XFRM netlink socket
[    1.306022] NET: Registered protocol family 17
[    1.307010] Key type dns_resolver registered
[    1.308248] Registering SWP/SWPB emulation handler
[    1.309424] registered taskstats version 1
[    1.310304] Loading compiled-in X.509 certificates
[    1.311668] Key type ._fscrypt registered
[    1.312538] Key type .fscrypt registered
[    1.323607] uart-pl011 fe201000.serial: cts_event_workaround enabled
[    1.324588] fe201000.serial: ttyAMA0 at MMIO 0xfe201000 (irq = 29, base_baud = 0) is a PL011 rev2
[    1.331405] bcm2835-power bcm2835-power: Broadcom BCM2835 power domains driver
[    1.333285] mmc-bcm2835 fe300000.mmcnr: mmc_debug:0 mmc_debug2:0
[    1.334310] mmc-bcm2835 fe300000.mmcnr: DMA channel allocated
[    1.385604] mmc1: queuing unknown CIS tuple 0x80 (2 bytes)
[    1.388188] mmc1: queuing unknown CIS tuple 0x80 (3 bytes)
[    1.390740] mmc1: queuing unknown CIS tuple 0x80 (3 bytes)
[    1.394556] mmc1: queuing unknown CIS tuple 0x80 (7 bytes)
[    1.397037] mmc1: queuing unknown CIS tuple 0x80 (3 bytes)
[    1.401823] mmc0: SDHCI controller on fe340000.emmc2 [fe340000.emmc2] using ADMA
[    1.405570] of_cfs_init
[    1.406627] of_cfs_init: OK
[    1.408487] Waiting for root device PARTUUID=49476cb6-02...
[    1.445479] random: fast init done
[    1.476501] mmc1: new high speed SDIO card at address 0001
[    1.509911] mmc0: new ultra high speed DDR50 SDXC card at address 0001
[    1.511601] mmcblk0: mmc0:0001 GC2QT 59.6 GiB
[    1.514341]  mmcblk0: p1 p2
[    1.535367] EXT4-fs (mmcblk0p2): mounted filesystem with ordered data mode. Opts: (null)
[    1.536329] VFS: Mounted root (ext4 filesystem) readonly on device 179:2.
[    1.542199] devtmpfs: mounted
[    1.551230] Freeing unused kernel memory: 2048K
[    1.573738] Run /sbin/init as init process
[    1.623497] usb 1-1: new high-speed USB device number 2 using xhci_hcd
[    1.806290] usb 1-1: New USB device found, idVendor=2109, idProduct=3431, bcdDevice= 4.21
[    1.807273] usb 1-1: New USB device strings: Mfr=0, Product=1, SerialNumber=0
[    1.808174] usb 1-1: Product: USB2.0 Hub
[    1.811014] hub 1-1:1.0: USB hub found
[    1.812192] hub 1-1:1.0: 4 ports detected
[    1.996728] systemd[1]: System time before build time, advancing clock.
[    2.085192] NET: Registered protocol family 10
[    2.087399] Segment Routing with IPv6
[    2.115017] systemd[1]: systemd 241 running in system mode. (+PAM +AUDIT +SELINUX +IMA +APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD -IDN2 +IDN -PCRE2 default-hierarchy=hybrid)
[    2.119391] systemd[1]: Detected architecture arm.
[    2.180443] systemd[1]: Set hostname to <raspberrypi>.
[    2.812748] random: systemd: uninitialized urandom read (16 bytes read)
[    2.827980] random: systemd: uninitialized urandom read (16 bytes read)
[    2.829559] systemd[1]: Listening on Journal Audit Socket.
[    2.832446] random: systemd: uninitialized urandom read (16 bytes read)
[    2.834175] systemd[1]: Listening on fsck to fsckd communication Socket.
[    2.837599] systemd[1]: Listening on Journal Socket.
[    2.847225] systemd[1]: Mounting RPC Pipe File System...
[    2.851380] systemd[1]: Listening on udev Kernel Socket.
[    2.860068] systemd[1]: Mounting Kernel Debug File System...
[    2.864875] systemd[1]: Created slice User and Session Slice.
[    3.030300] i2c /dev entries driver
[    3.527052] EXT4-fs (mmcblk0p2): re-mounted. Opts: (null)
[    3.642597] systemd-journald[126]: Received request to flush runtime journal from PID 1
[    4.145332] rpivid-mem feb00000.hevc-decoder: rpivid-hevcmem initialised: Registers at 0xfeb00000 length 0x00010000
[    4.145870] rpivid-mem feb10000.rpivid-local-intc: rpivid-intcmem initialised: Registers at 0xfeb10000 length 0x00001000
[    4.147895] rpivid-mem feb20000.h264-decoder: rpivid-h264mem initialised: Registers at 0xfeb20000 length 0x00010000
[    4.148396] rpivid-mem feb30000.vp9-decoder: rpivid-vp9mem initialised: Registers at 0xfeb30000 length 0x00010000
[    4.186087] vc_sm_cma: module is from the staging directory, the quality is unknown, you have been warned.
[    4.188798] bcm2835_vc_sm_cma_probe: Videocore shared memory driver
[    4.188819] [vc_sm_connected_init]: start
[    4.194036] [vc_sm_connected_init]: installed successfully
[    4.214664] snd_bcm2835: module is from the staging directory, the quality is unknown, you have been warned.
[    4.216095] mc: Linux media interface: v0.10
[    4.240849] videodev: Linux video capture interface: v2.00
[    4.241791] bcm2835_audio bcm2835_audio: card created with 8 channels
[    4.293362] bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned.
[    4.297429] bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned.
[    4.306933] bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned.
[    4.308161] bcm2835_v4l2: module is from the staging directory, the quality is unknown, you have been warned.
[    4.322841] bcm2835_codec: module is from the staging directory, the quality is unknown, you have been warned.
[    4.323848] bcm2835_isp: module is from the staging directory, the quality is unknown, you have been warned.
[    4.474494] vc4-drm gpu: bound fe600000.firmwarekms (ops vc4_fkms_ops [vc4])
[    4.474517] checking generic (3ea50000 177000) vs hw (0 ffffffffffffffff)
[    4.474531] fb0: switching to vc4drmfb from simple
[    4.475084] Console: switching to colour dummy device 80x30
[    4.475323] [drm] Supports vblank timestamp caching Rev 2 (21.10.2013).
[    4.475335] [drm] No driver support for vblank timestamp query.
[    4.475346] [drm] Setting vblank_disable_immediate to false because get_vblank_timestamp == NULL
[    4.475915] [drm] Initialized vc4 0.0.0 20140616 for gpu on minor 0
[    4.481998] Console: switching to colour frame buffer device 100x30
[    4.498773] vc4-drm gpu: fb0: vc4drmfb frame buffer device
[    4.522832] cfg80211: Loading compiled-in X.509 certificates for regulatory database
[    4.562848] input: raspberrypi-ts as /devices/platform/soc/soc:firmware/soc:firmware:touchscreen/input/input0
[    4.645338] cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'
[    4.678872] [drm] Initialized v3d 1.0.0 20180419 for fec00000.v3d on minor 1
[    4.799349] brcmfmac: F1 signature read @0x18000000=0x15264345
[    4.809311] brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6
[    4.809726] usbcore: registered new interface driver brcmfmac
[    4.836412] brcmfmac mmc1:0001:1: Direct firmware load for brcm/brcmfmac43455-sdio.raspberrypi,4-model-b.txt failed with error -2
[    4.955532] bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video13
[    4.956029] bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video14
[    4.956423] bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video15
[    4.956726] bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video16
[    4.956752] bcm2835-isp bcm2835-isp: Register output node 0 with media controller
[    4.956786] bcm2835-isp bcm2835-isp: Register capture node 1 with media controller
[    4.956805] bcm2835-isp bcm2835-isp: Register capture node 2 with media controller
[    4.956824] bcm2835-isp bcm2835-isp: Register capture node 3 with media controller
[    4.957047] bcm2835-isp bcm2835-isp: Loaded V4L2 bcm2835-isp
[    4.974435] bcm2835-codec bcm2835-codec: Device registered as /dev/video10
[    4.974501] bcm2835-codec bcm2835-codec: Loaded V4L2 decode
[    4.982188] bcm2835-codec bcm2835-codec: Device registered as /dev/video11
[    4.982229] bcm2835-codec bcm2835-codec: Loaded V4L2 encode
[    4.991686] bcm2835-codec bcm2835-codec: Device registered as /dev/video12
[    4.991729] bcm2835-codec bcm2835-codec: Loaded V4L2 isp
[    5.065044] bcm2835-v4l2: scene mode selected 0, was 0
[    5.066558] bcm2835-v4l2: V4L2 device registered as video0 - stills mode > 1280x720
[    5.070932] brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6
[    5.077077] bcm2835-v4l2: Broadcom 2835 MMAL video capture ver 0.0.2 loaded.
[    5.086476] brcmfmac: brcmf_c_preinit_dcmds: Firmware: BCM4345/6 wl0: Mar 23 2020 02:19:54 version 7.45.206 (r725000 CY) FWID 01-88ee44ea
[    6.280064] uart-pl011 fe201000.serial: no DMA platform data
[    6.302403] random: crng init done
[    6.302421] random: 7 urandom warning(s) missed due to ratelimiting
[    6.528531] 8021q: 802.1Q VLAN Support v1.8
[    6.693561] Adding 102396k swap on /var/swap.  Priority:-2 extents:2 across:307200k SSFS
[    7.027666] bcmgenet: Skipping UMAC reset
[    7.030307] bcmgenet fd580000.ethernet: configuring instance for external RGMII
[    7.030578] bcmgenet fd580000.ethernet eth0: Link is Down
[   11.193599] bcmgenet fd580000.ethernet eth0: Link is Up - 100Mbps/Full - flow control rx/tx
[   11.193647] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
[   12.995068] Bluetooth: Core ver 2.22
[   12.995154] NET: Registered protocol family 31
[   12.995167] Bluetooth: HCI device and connection manager initialized
[   12.995194] Bluetooth: HCI socket layer initialized
[   12.995213] Bluetooth: L2CAP socket layer initialized
[   12.995243] Bluetooth: SCO socket layer initialized
[   13.003244] Bluetooth: HCI UART driver ver 2.3
[   13.003263] Bluetooth: HCI UART protocol H4 registered
[   13.003343] Bluetooth: HCI UART protocol Three-wire (H5) registered
[   13.009301] Bluetooth: HCI UART protocol Broadcom registered
[   13.198532] Bluetooth: BNEP (Ethernet Emulation) ver 1.3
[   13.198539] Bluetooth: BNEP filters: protocol multicast
[   13.198552] Bluetooth: BNEP socket layer initialized
[  368.878277] i2c-bcm2835 fe804000.i2c: i2c transfer timed out
[  370.878330] i2c-bcm2835 fe804000.i2c: i2c transfer timed out
[  373.598472] i2c-bcm2835 fe804000.i2c: i2c transfer timed out
[  377.598336] i2c-bcm2835 fe804000.i2c: i2c transfer timed out
[  380.958519] i2c-bcm2835 fe804000.i2c: i2c transfer timed out
[  384.798562] i2c-bcm2835 fe804000.i2c: i2c transfer timed out
[  386.398483] i2c-bcm2835 fe804000.i2c: i2c transfer timed out
[  387.598532] i2c-bcm2835 fe804000.i2c: i2c transfer timed out
[  389.678572] i2c-bcm2835 fe804000.i2c: i2c transfer timed out
[  391.918602] i2c-bcm2835 fe804000.i2c: i2c transfer timed out
[  394.561111] i2c-bcm2835 fe804000.i2c: i2c transfer timed out
[  397.438667] i2c-bcm2835 fe804000.i2c: i2c transfer timed out
[  399.118743] i2c-bcm2835 fe804000.i2c: i2c transfer timed out
[  401.438760] i2c-bcm2835 fe804000.i2c: i2c transfer timed out
[  404.478757] i2c-bcm2835 fe804000.i2c: i2c transfer timed out

config.txt

# For more options and information see
# http://rpf.io/configtxt
# Some settings may impact device functionality. See link above for details

# uncomment if you get no picture on HDMI for a default "safe" mode
#hdmi_safe=1

# uncomment this if your display has a black border of unused pixels visible
# and your display can output without overscan
#disable_overscan=1

# uncomment the following to adjust overscan. Use positive numbers if console
# goes off screen, and negative if there is too much border
#overscan_left=16
#overscan_right=16
#overscan_top=16
#overscan_bottom=16

# uncomment to force a console size. By default it will be display's size minus
# overscan.
#framebuffer_width=1280
#framebuffer_height=720

# uncomment if hdmi display is not detected and composite is being output
#hdmi_force_hotplug=1

# uncomment to force a specific HDMI mode (this will force VGA)
#hdmi_group=1
#hdmi_mode=1

# uncomment to force a HDMI mode rather than DVI. This can make audio work in
# DMT (computer monitor) modes
#hdmi_drive=2

# uncomment to increase signal to HDMI, if you have interference, blanking, or
# no display
#config_hdmi_boost=4

# uncomment for composite PAL
#sdtv_mode=2

#uncomment to overclock the arm. 700 MHz is the default.
#arm_freq=1500
# Uncomment some or all of these to enable the optional hardware interfaces
dtparam=i2c_arm=on,i2c_arm_baudrate=400000
#dtparam=i2s=on
#dtparam=spi=on

# Uncomment this to enable infrared communication.
#dtoverlay=gpio-ir,gpio_pin=17
#dtoverlay=gpio-ir-tx,gpio_pin=18

# Additional overlays and parameters are documented /boot/overlays/README

# Enable audio (loads snd_bcm2835)
dtparam=audio=on

[pi4]
# Enable DRM VC4 V3D driver on top of the dispmanx display stack
dtoverlay=vc4-fkms-v3d
max_framebuffers=2

[all]
#dtoverlay=vc4-fkms-v3d
start_x=1
gpu_mem=192
pelwell commented 3 years ago

It's going to be hard to get much interest here when you have a log full of undervoltage warnings - there's a good chance that the reason using the camera affects I2C is that using it draws more power.

N4321D commented 3 years ago

Thank you, You are right (although there was no undervoltage on RPI4).

I ran it again on RPI3B+ with a better power supply (no undervolt warnings) and updated the output in the first post. Unfortunately there was still the same issue.

It's going to be hard to get much interest here when you have a log full of undervoltage warnings - there's a good chance that the reason using the camera affects I2C is that using it draws more power.

chukhanhhoang commented 3 years ago

Hello! I have the same issue, but with multiple I2C devices on multiple channels. In addition to the default channel 1, I enabled 3 additional I2C channels to read from and write to 4 devices simultaneously. After just a few seconds 3 channels stop working (while the (randomly) remaining one still runs) and timed-out errors appear in dmesg

[ 8661.510950] i2c-bcm2835 fe205800.i2c: i2c transfer timed out
[ 8661.511088] i2c-bcm2835 fe205c00.i2c: i2c transfer timed out
[ 8661.600935] i2c-bcm2835 fe804000.i2c: i2c transfer timed out

My setup is Raspberry Pi 4 running Raspberry Pi OS Buster (Linux raspberrypi 5.10.17-v7l+ #1403 SMP Mon Feb 22 11:33:35 GMT 2021 armv7l GNU/Linux).

6by9 commented 3 years ago

@chukhanhhoang Are you running the camera at the same time, or just the different I2C buses from the Pi4?

All the I2C controllers share a common interrupt line (GIC 117 for Pi4, interrupt 21 on earlier boards), so there is a bit of cooperation required when they come to service them.

The firmware (which generally controls the camera and /dev/i2c-0) doesn't use the interrupts, so shouldn't cause any effects.

chukhanhhoang commented 3 years ago

Thank you very much for your quick response! No, I am not using the camera now, just I2C devices as you said. Fortunately, my problem is solved in the latest kernel (Linux raspberrypi 5.10.46-v7l+). Now I am can read the channels (1,3,4,6) simultaneously. Have a nice day!

6by9 commented 3 years ago

Curious as I'm not aware of any I2C changes between 5.10.17 and 5.10.46.

N4321D commented 3 years ago

Hello! I have the same issue, but with multiple I2C devices on multiple channels. In addition to the default channel 1, I enabled 3 additional I2C channels to read from and write to 4 devices simultaneously. After just a few seconds 3 channels stop working (while the (randomly) remaining one still runs) and timed-out errors appear in dmesg

[ 8661.510950] i2c-bcm2835 fe205800.i2c: i2c transfer timed out
[ 8661.511088] i2c-bcm2835 fe205c00.i2c: i2c transfer timed out
[ 8661.600935] i2c-bcm2835 fe804000.i2c: i2c transfer timed out

My setup is Raspberry Pi 4 running Raspberry Pi OS Buster (Linux raspberrypi 5.10.17-v7l+ #1403 SMP Mon Feb 22 11:33:35 GMT 2021 armv7l GNU/Linux).

Yes Linux 5.10.17-v7+ also created the same issues for me even without camera (on RPI3 B+). i2c timed out every second or so. rpi-update to 5.10.48-v7+ fixed it