Skip to content

sc16is7xx transmit errors (tty buffer?) and spi (tpm-slb9670) issue on all versions with kernel above 5.4.83-v7+ #4711

New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

Closed
paulenuta opened this issue Nov 19, 2021 · 4 comments

Comments

@paulenuta
Copy link

paulenuta commented Nov 19, 2021

Describe the bug
Serial communication using sc16is7xx randomly fail to send data. Receiving doesn’t seems to be affected.
Issue appears with one port or more ports used in parallel but it does not seems related to the loading or amount of data transmitted.
Last working kernel version was 5.4.83-v7+ - Raspberry Pi reference 2021-06-15 -Generated using pi-gen, https://github.com/RPi-Distro/pi-gen, bf8c9f53833016e1b9410d2eb3ed0149390975a7.
Rebuilding kernel 5.10.17-v7+ with sc16is7xx driver from 5.4.83-v7+ commit 5da6b1c079e6804a81e63ab8337224cbd2148c91 didn't solve the problem.
In our program (not the linux-serial-test) we got randomly 4096 bytes in buffer and this was the reason to link the issue to the kernel tty.

Versions above 5.4.83-v7+ also have the #4228 issue.

To reproduce
Raspberry Pi 3 Model B+
SD image: 2021-10-30-raspios-bullseye-armhf-lite.zip
Custom board witn 2 x SC16IS752 and tpm-slb9670 (@pelwell has one)
Set /boot/config.txt:

[all]
dtoverlay=gpio-poweroff,gpiopin=27,active_low
dtparam=i2c_arm=on
dtparam=spi=on
dtoverlay=i2c-rtc,mcp7941x
dtoverlay=sc16is752-i2c-02
dtoverlay=sc16is752-i2c-01
dtoverlay=tpm-slb9670
enable_uart=1
dtoverlay=gpio-fan,gpiopin=12,temp=50000
#gpu_mem=96
dtoverlay=disable-bt
force_turbo=1
#initramfs initrd followkernel
display_auto_detect=1
[EDID=*]
ignore_lcd=1
display_default_lcd=0
hdmi_group=2
hdmi_mode=18
[EDID=ELO-ET1509L-8UWA]
hdmi_group=2
hdmi_mode=16

Set /boot/cmdline.txt:

console=serial0,115200 console=tty1 root=PARTUUID=e94b1ac0-02 rootfstype=ext4 fsck.repair=yes rootwait

Update system, enable I2C, SPI and autologin to console:

sudo apt update && sudo apt install -y git &&\
sudo raspi-config nonint do_i2c 0 &&\
sudo raspi-config nonint do_spi 0 &&\
SUDO_USER="${FIRST_USER_NAME}" sudo raspi-config nonint do_boot_behaviour B2 &&\
sudo poweroff

List serial ports available to check that sc16is752 is loaded: ls /dev/ttyS*
Clone and build linux-serial-test:

git clone --depth 1 https://github.com/cbrake/linux-serial-test &&\
cd linux-serial-test &&\
gcc -o linux-serial-test linux-serial-test.c

Expected behaviour
All data sent (Tx) should be received (Rx) using a loopback cable or just conecting Rx to Tx of same port.

Actual behaviour
Some date is not transmitted so not received.
image
image

Using an ttyUSB0 is OK:
image

System

System Information
------------------

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

Raspberry Pi reference 2021-10-30
Generated using pi-gen, https://github.com/RPi-Distro/pi-gen, 288b21fc27e128ea6b                                                                                                                                                             330777aca68e0061ebf4fe, stage2

Linux raspberrypi 5.10.63-v7+ #1459 SMP Wed Oct 6 16:41:10 BST 2021 armv7l GNU/L                                                                                                                                                             inux
Revision        : a020d3
Serial          : 00000000d4a08d83
Model           : Raspberry Pi 3 Model B Plus Rev 1.3
Throttled flag  : throttled=0x0
Camera          : supported=0 detected=0

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

Oct 29 2021 10:49:08
Copyright (c) 2012 Broadcom
version b8a114e5a9877e91ca8f26d1a5ce904b2ad3cf13 (clean) (release) (start)

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

Filesystem information
----------------------
Filesystem     1K-blocks    Used Available Use% Mounted on
udev              432932       0    432932   0% /dev
tmpfs              94536    2988     91548   4% /run
/dev/mmcblk0p2  15026928 1404032  12980884  10% /
tmpfs             472680       0    472680   0% /dev/shm
tmpfs               5120       0      5120   0% /run/lock
/dev/mmcblk0p1    258095   62171    195925  25% /boot
tmpfs              94536       0     94536   0% /run/user/1000

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

Package version information
---------------------------
raspberrypi-ui-mods:
 Installed: (none)
raspberrypi-sys-mods:
 Installed: 20211005+bullseye
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 2712  bytes 237230 (231.6 KiB)
       RX errors 0  dropped 0  overruns 0  frame 0
       TX packets 369  bytes 85899 (83.8 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 14  bytes 1800 (1.7 KiB)
       RX errors 0  dropped 0  overruns 0  frame 0
       TX packets 14  bytes 1800 (1.7 KiB)
       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 4, If 0, Class=Vendor Specific Class, Driver=lan78xx                                                                                                                                                             , 480M

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

aphy_params_current=819
arm_freq=1400
arm_freq_min=600
audio_pwm_mode=514
config_hdmi_boost=5
core_freq=400
desired_osc_freq=0x331df0
desired_osc_freq_boost=0x3c45b0
disable_commandline_tags=2
disable_l2cache=1
display_auto_detect=1
display_default_lcd=1
display_hdmi_rotate=-1
display_lcd_rotate=-1
dphy_params_current=547
dvfs=3
enable_tvout=1
enable_uart=1
force_eeprom_read=1
force_pwm_open=1
force_turbo=1
framebuffer_ignore_alpha=1
framebuffer_swap=1
gpu_freq=300
init_uart_clock=0x2dc6c00
lcd_framerate=60
max_framebuffers=-1
over_voltage_avs=0x155cc
over_voltage_avs_boost=0x2dc6c
pause_burst_frames=1
program_serial_random=1
ramfsaddr=-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/
ramfsfile=initrd
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=1 snd_bcm2835.enable_compat_alsa=0 snd_bcm2835.en                                                                                                                                                             able_hdmi=1 bcm2708_fb.fbwidth=800 bcm2708_fb.fbheight=480 bcm2708_fb.fbswap=1 v                                                                                                                                                             c_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000  console=ttyAMA0,115200 con                                                                                                                                                             sole=tty1 root=PARTUUID=e94b1ac0-02 rootfstype=ext4 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=1 func=OUTPUT
GPIO 8: level=1 fsel=1 func=OUTPUT
GPIO 9: level=0 fsel=4 alt=0 func=SPI0_MISO
GPIO 10: level=0 fsel=4 alt=0 func=SPI0_MOSI
GPIO 11: level=0 fsel=4 alt=0 func=SPI0_SCLK
GPIO 12: level=0 fsel=1 func=OUTPUT
GPIO 13: level=0 fsel=0 func=INPUT
GPIO 14: level=1 fsel=4 alt=0 func=TXD0
GPIO 15: level=1 fsel=4 alt=0 func=RXD0
GPIO 16: level=1 fsel=0 func=INPUT
GPIO 17: level=1 fsel=0 func=INPUT
GPIO 18: level=0 fsel=0 func=INPUT
GPIO 19: level=0 fsel=0 func=INPUT
GPIO 20: level=1 fsel=0 func=INPUT
GPIO 21: level=0 fsel=0 func=INPUT
GPIO 22: level=1 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=1 fsel=1 func=OUTPUT
BANK1 (GPIO 28 to 45):
GPIO 28: level=1 fsel=0 func=INPUT
GPIO 29: level=0 fsel=1 func=OUTPUT
GPIO 30: level=1 fsel=0 func=INPUT
GPIO 31: level=1 fsel=0 func=INPUT
GPIO 32: level=1 fsel=0 func=INPUT
GPIO 33: level=1 fsel=0 func=INPUT
GPIO 34: level=0 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=0 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
------------------

001315.658: brfs: File read: /mfs/sd/config.txt
001316.428: brfs: File read: 464 bytes
001370.191: HDMI0:EDID error reading EDID block 0 attempt 0
001371.458: HDMI0:EDID error reading EDID block 0 attempt 1
001372.722: HDMI0:EDID error reading EDID block 0 attempt 2
001373.988: HDMI0:EDID error reading EDID block 0 attempt 3
001375.253: HDMI0:EDID error reading EDID block 0 attempt 4
001376.519: HDMI0:EDID error reading EDID block 0 attempt 5
001377.783: HDMI0:EDID error reading EDID block 0 attempt 6
001379.049: HDMI0:EDID error reading EDID block 0 attempt 7
001380.314: HDMI0:EDID error reading EDID block 0 attempt 8
001381.580: HDMI0:EDID error reading EDID block 0 attempt 9
001382.602: HDMI0:EDID giving up on reading EDID block 0
001387.027: brfs: File read: /mfs/sd/config.txt
002234.709: gpioman: gpioman_get_pin_num: pin DISPLAY_DSI_PORT not defined
002538.019: *** Restart logging
002538.043: brfs: File read: 464 bytes
002539.287: hdmi: HDMI0:EDID error reading EDID block 0 attempt 0
002540.556: hdmi: HDMI0:EDID error reading EDID block 0 attempt 1
002541.825: hdmi: HDMI0:EDID error reading EDID block 0 attempt 2
002543.095: hdmi: HDMI0:EDID error reading EDID block 0 attempt 3
002544.363: hdmi: HDMI0:EDID error reading EDID block 0 attempt 4
002545.633: hdmi: HDMI0:EDID error reading EDID block 0 attempt 5
002546.902: hdmi: HDMI0:EDID error reading EDID block 0 attempt 6
002548.172: hdmi: HDMI0:EDID error reading EDID block 0 attempt 7
002549.440: hdmi: HDMI0:EDID error reading EDID block 0 attempt 8
002550.710: hdmi: HDMI0:EDID error reading EDID block 0 attempt 9
002551.736: hdmi: HDMI0:EDID giving up on reading EDID block 0
002552.064: hdmi: HDMI0:EDID error reading EDID block 0 attempt 0
002553.333: hdmi: HDMI0:EDID error reading EDID block 0 attempt 1
002554.603: hdmi: HDMI0:EDID error reading EDID block 0 attempt 2
002555.873: hdmi: HDMI0:EDID error reading EDID block 0 attempt 3
002557.140: hdmi: HDMI0:EDID error reading EDID block 0 attempt 4
002558.411: hdmi: HDMI0:EDID error reading EDID block 0 attempt 5
002559.680: hdmi: HDMI0:EDID error reading EDID block 0 attempt 6
002560.950: hdmi: HDMI0:EDID error reading EDID block 0 attempt 7
002562.218: hdmi: HDMI0:EDID error reading EDID block 0 attempt 8
002563.488: hdmi: HDMI0:EDID error reading EDID block 0 attempt 9
002564.514: hdmi: HDMI0:EDID giving up on reading EDID block 0
002564.558: hdmi: HDMI:hdmi_get_state is deprecated, use hdmi_get_display_state                                                                                                                                                              instead
002564.581: HDMI0: hdmi_pixel_encoding: 162000000
002564.827: vec: vec_middleware_power_on: vec_base: 0x7e806000 rev-id 0x00002708                                                                                                                                                              @ vec: 0x7e806100 @ 0x00000420 enc: 0x7e806060 @ 0x00000220 cgmsae: 0x7e80605c                                                                                                                                                              @ 0x00000000
003459.851: brfs: File read: /mfs/sd/initrd
003459.875: Loading 'initrd' to 0x0 size 0xca0087
003476.004: initramfs loaded to 0x2e35f000 (size 0xca0087)
003480.266: dtb_file 'bcm2710-rpi-3-b-plus.dtb'
003480.296: brfs: File read: 13238407 bytes
003487.881: brfs: File read: /mfs/sd/bcm2710-rpi-3-b-plus.dtb
003487.905: Loading 'bcm2710-rpi-3-b-plus.dtb' to 0x100 size 0x7376
003501.845: brfs: File read: 29558 bytes
003516.054: brfs: File read: /mfs/sd/overlays/overlay_map.dtb
003588.690: brfs: File read: 1667 bytes
003596.046: brfs: File read: /mfs/sd/config.txt
003596.792: brfs: File read: 464 bytes
003601.758: brfs: File read: /mfs/sd/overlays/gpio-poweroff.dtbo
003607.465: Loaded overlay 'gpio-poweroff'
003607.482: dtparam: gpiopin=27
003608.096: dtparam: active_low=true
003608.438: dtparam: i2c_arm=on
003617.219: dtparam: spi=on
003645.956: brfs: File read: 1053 bytes
003654.555: brfs: File read: /mfs/sd/overlays/i2c-rtc.dtbo
003700.926: Loaded overlay 'i2c-rtc'
003700.943: dtparam: mcp7941x=true
003730.106: brfs: File read: 7238 bytes
003746.872: brfs: File read: /mfs/sd/overlays/sc16is752-i2c-02.dtbo
003755.373: Loaded overlay 'sc16is752-i2c-02'
003782.406: brfs: File read: 1345 bytes
003798.798: brfs: File read: /mfs/sd/overlays/sc16is752-i2c-01.dtbo
003807.377: Loaded overlay 'sc16is752-i2c-01'
003834.790: brfs: File read: 1345 bytes
003842.584: brfs: File read: /mfs/sd/overlays/gpio-fan.dtbo
003849.637: Loaded overlay 'gpio-fan'
003849.653: dtparam: gpiopin=12
003850.326: dtparam: temp=50000
003878.037: brfs: File read: 1259 bytes
003884.960: brfs: File read: /mfs/sd/overlays/disable-bt.dtbo
003901.700: Loaded overlay 'disable-bt'
003946.929: brfs: File read: 1073 bytes
003957.109: brfs: File read: /mfs/sd/overlays/rpi-ft5406.dtbo
003958.660: Loaded overlay 'rpi-ft5406'
003965.798: brfs: File read: 842 bytes
003977.715: brfs: File read: /mfs/sd/overlays/rpi-backlight.dtbo
003985.639: Loaded overlay 'rpi-backlight'
004001.995: brfs: File read: 489 bytes
004006.794: brfs: File read: /mfs/sd/cmdline.txt
004006.839: Read command line from file 'cmdline.txt':
004006.858: 'console=serial0,115200 console=tty1 root=PARTUUID=e94b1ac0-02 rootf                                                                                                                                                             stype=ext4 fsck.repair=yes rootwait'
004111.404: gpioman: gpioman_get_pin_num: pin EMMC_ENABLE not defined
004176.424: brfs: File read: 103 bytes
004625.744: brfs: File read: /mfs/sd/kernel7.img
004625.766: Loading 'kernel7.img' to 0x8000 size 0x60f860
004625.796: Device tree loaded to 0x2e357200 (size 0x7dee)
004629.831: gpioman: gpioman_get_pin_num: pin SDCARD_CONTROL_POWER not defined
007343.214: vchiq_core: vchiq_init_state: slot_zero = 0xf7580000, is_master = 1

dmesg log
---------

[    0.000000] Booting Linux on physical CPU 0x0
[    0.000000] Linux version 5.10.63-v7+ (dom@buildbot) (arm-linux-gnueabihf-gcc                                                                                                                                                             -8 (Ubuntu/Linaro 8.4.0-3ubuntu1) 8.4.0, GNU ld (GNU Binutils for Ubuntu) 2.34)                                                                                                                                                              #1459 SMP Wed Oct 6 16:41:10 BST 2021
[    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 instructio                                                                                                                                                             n cache
[    0.000000] OF: fdt: Machine model: Raspberry Pi 3 Model B Plus Rev 1.3
[    0.000000] random: fast init done
[    0.000000] Memory policy: Data cache writealloc
[    0.000000] Reserved memory: created CMA memory pool at 0x37400000, size 64 M                                                                                                                                                             iB
[    0.000000] OF: reserved mem: initialized node linux,cma, compatible id share                                                                                                                                                             d-dma-pool
[    0.000000] Zone ranges:
[    0.000000]   DMA      [mem 0x0000000000000000-0x000000003b3fffff]
[    0.000000]   Normal   empty
[    0.000000] Movable zone start for each node
[    0.000000] Early memory node ranges
[    0.000000]   node   0: [mem 0x0000000000000000-0x000000003b3fffff]
[    0.000000] Initmem setup node 0 [mem 0x0000000000000000-0x000000003b3fffff]
[    0.000000] On node 0 totalpages: 242688
[    0.000000]   DMA zone: 2133 pages used for memmap
[    0.000000]   DMA zone: 0 pages reserved
[    0.000000]   DMA zone: 242688 pages, LIFO batch:63
[    0.000000] percpu: Embedded 20 pages/cpu s50828 r8192 d22900 u81920
[    0.000000] pcpu-alloc: s50828 r8192 d22900 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: 240555
[    0.000000] Kernel command line: coherent_pool=1M 8250.nr_uarts=1 snd_bcm2835                                                                                                                                                             .enable_compat_alsa=0 snd_bcm2835.enable_hdmi=1 bcm2708_fb.fbwidth=800 bcm2708_f                                                                                                                                                             b.fbheight=480 bcm2708_fb.fbswap=1 vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x                                                                                                                                                             40000000  console=ttyAMA0,115200 console=tty1 root=PARTUUID=e94b1ac0-02 rootfsty                                                                                                                                                             pe=ext4 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, li                                                                                                                                                             near)
[    0.000000] mem auto-init: stack:off, heap alloc:off, heap free:off
[    0.000000] Memory: 865868K/970752K available (10240K kernel code, 1312K rwda                                                                                                                                                             ta, 2948K rodata, 1024K init, 863K bss, 39348K reserved, 65536K cma-reserved)
[    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1
[    0.000000] ftrace: allocating 32054 entries in 63 pages
[    0.000000] ftrace: allocated 63 pages with 6 groups
[    0.000000] rcu: Hierarchical RCU implementation.
[    0.000000]  Rude variant of Tasks RCU enabled.
[    0.000000]  Tracing variant of Tasks RCU enabled.
[    0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 10 jif                                                                                                                                                             fies.
[    0.000000] NR_IRQS: 16, nr_irqs: 16, preallocated irqs: 16
[    0.000000] random: get_random_bytes called from start_kernel+0x3ac/0x580 wit                                                                                                                                                             h crng_init=1
[    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.000003] sched_clock: 56 bits at 19MHz, resolution 52ns, wraps every 43980                                                                                                                                                             46511078ns
[    0.000011] Switching to timer-based delay loop, resolution 52ns
[    0.000154] Console: colour dummy device 80x30
[    0.000514] printk: console [tty1] enabled
[    0.000552] Calibrating delay loop (skipped), value calculated using timer fr                                                                                                                                                             equency.. 38.40 BogoMIPS (lpj=192000)
[    0.000578] pid_max: default: 32768 minimum: 301
[    0.000685] LSM: Security Framework initializing
[    0.000810] Mount-cache hash table entries: 2048 (order: 1, 8192 bytes, linea                                                                                                                                                             r)
[    0.000833] Mountpoint-cache hash table entries: 2048 (order: 1, 8192 bytes,                                                                                                                                                              linear)
[    0.001558] cgroup: Disabling memory control group subsystem
[    0.001708] CPU: Testing write buffer coherency: ok
[    0.001997] CPU0: thread -1, cpu 0, socket 0, mpidr 80000000
[    0.002572] Setting up static identity map for 0x100000 - 0x10003c
[    0.002666] rcu: Hierarchical SRCU implementation.
[    0.003110] smp: Bringing up secondary CPUs ...
[    0.003714] CPU1: thread -1, cpu 1, socket 0, mpidr 80000001
[    0.004348] CPU2: thread -1, cpu 2, socket 0, mpidr 80000002
[    0.004911] CPU3: thread -1, cpu 3, socket 0, mpidr 80000003
[    0.004979] smp: Brought up 1 node, 4 CPUs
[    0.005023] SMP: Total of 4 processors activated (153.60 BogoMIPS).
[    0.005037] CPU: All CPU(s) started in HYP mode.
[    0.005048] CPU: Virtualization extensions available.
[    0.005580] devtmpfs: initialized
[    0.013716] VFP support v0.3: implementor 41 architecture 3 part 40 variant 3                                                                                                                                                              rev 4
[    0.013853] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, ma                                                                                                                                                             x_idle_ns: 19112604462750000 ns
[    0.013881] futex hash table entries: 1024 (order: 4, 65536 bytes, linear)
[    0.015358] pinctrl core: initialized pinctrl subsystem
[    0.015995] NET: Registered protocol family 16
[    0.018107] DMA: preallocated 1024 KiB pool for atomic coherent allocations
[    0.020681] audit: initializing netlink subsys (disabled)
[    0.021137] thermal_sys: Registered thermal governor 'step_wise'
[    0.021549] audit: type=2000 audit(0.020:1): state=initialized audit_enabled=                                                                                                                                                             0 res=1
[    0.021654] hw-breakpoint: found 5 (+1 reserved) breakpoint and 4 watchpoint                                                                                                                                                              registers.
[    0.021671] hw-breakpoint: maximum watchpoint size is 8 bytes.
[    0.021845] Serial: AMBA PL011 UART driver
[    0.031098] bcm2835-mbox 3f00b880.mailbox: mailbox enabled
[    0.050046] raspberrypi-firmware soc:firmware: Attached to firmware from 2021                                                                                                                                                             -10-29T10:49:08, variant start
[    0.060052] raspberrypi-firmware soc:firmware: Firmware hash is b8a114e5a9877                                                                                                                                                             e91ca8f26d1a5ce904b2ad3cf13
[    0.084774] Kprobes globally optimized
[    0.087115] bcm2835-dma 3f007000.dma: DMA legacy API manager, dmachans=0x1
[    0.088125] SCSI subsystem initialized
[    0.088261] usbcore: registered new interface driver usbfs
[    0.088299] usbcore: registered new interface driver hub
[    0.088340] usbcore: registered new device driver usb
[    0.089280] clocksource: Switched to clocksource arch_sys_counter
[    0.851011] VFS: Disk quotas dquot_6.6.0
[    0.851081] VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes)
[    0.851182] FS-Cache: Loaded
[    0.851353] CacheFiles: Loaded
[    0.856285] NET: Registered protocol family 2
[    0.856451] IP idents hash table entries: 16384 (order: 5, 131072 bytes, line                                                                                                                                                             ar)
[    0.857226] tcp_listen_portaddr_hash hash table entries: 512 (order: 0, 6144                                                                                                                                                              bytes, linear)
[    0.857266] TCP established hash table entries: 8192 (order: 3, 32768 bytes,                                                                                                                                                              linear)
[    0.857329] TCP bind hash table entries: 8192 (order: 4, 65536 bytes, linear)
[    0.857420] TCP: Hash tables configured (established 8192 bind 8192)
[    0.857527] UDP hash table entries: 512 (order: 2, 16384 bytes, linear)
[    0.857562] UDP-Lite hash table entries: 512 (order: 2, 16384 bytes, linear)
[    0.857882] NET: Registered protocol family 1
[    0.858303] RPC: Registered named UNIX socket transport module.
[    0.858319] RPC: Registered udp transport module.
[    0.858331] RPC: Registered tcp transport module.
[    0.858344] RPC: Registered tcp NFSv4.1 backchannel transport module.
[    0.858502] Trying to unpack rootfs image as initramfs...
[    1.351165] Freeing initrd memory: 12932K
[    1.351973] hw perfevents: enabled with armv7_cortex_a7 PMU driver, 7 counter                                                                                                                                                             s available
[    1.353796] Initialise system trusted keyrings
[    1.353993] workingset: timestamp_bits=14 max_order=18 bucket_order=4
[    1.358176] zbud: loaded
[    1.359146] FS-Cache: Netfs 'nfs' registered for caching
[    1.360352] NFS: Registering the id_resolver key type
[    1.360386] Key type id_resolver registered
[    1.360399] Key type id_legacy registered
[    1.360478] nfs4filelayout_init: NFSv4 File Layout Driver Registering...
[    1.360494] nfs4flexfilelayout_init: NFSv4 Flexfile Layout Driver Registering                                                                                                                                                             ...
[    1.361067] Key type asymmetric registered
[    1.361082] Asymmetric key parser 'x509' registered
[    1.361116] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 2                                                                                                                                                             49)
[    1.361134] io scheduler mq-deadline registered
[    1.361147] io scheduler kyber registered
[    1.362838] bcm2708_fb soc:fb: FB found 1 display(s)
[    1.371886] Console: switching to colour frame buffer device 100x30
[    1.375950] bcm2708_fb soc:fb: Registered framebuffer for display 0, size 800                                                                                                                                                             x480
[    1.379214] Serial: 8250/16550 driver, 1 ports, IRQ sharing enabled
[    1.382010] bcm2835-rng 3f104000.rng: hwrng registered
[    1.383565] vc-mem: phys_addr:0x00000000 mem_base=0x3ec00000 mem_size:0x40000                                                                                                                                                             000(1024 MiB)
[    1.385362] gpiomem-bcm2835 3f200000.gpiomem: Initialised: Registers at 0x3f2                                                                                                                                                             00000
[    1.392199] brd: module loaded
[    1.399422] loop: module loaded
[    1.401611] Loading iSCSI transport class v2.0-870.
[    1.403737] libphy: Fixed MDIO Bus: probed
[    1.405197] usbcore: registered new interface driver lan78xx
[    1.406557] usbcore: registered new interface driver smsc95xx
[    1.407870] dwc_otg: version 3.00a 10-AUG-2012 (platform bus)
[    2.137108] Core Release: 2.80a
[    2.138432] Setting default values for core params
[    2.139767] Finished setting default values for core params
[    2.341255] Using Buffer DMA mode
[    2.342522] Periodic Transfer Interrupt Enhancement - disabled
[    2.343822] Multiprocessor Interrupt Enhancement - disabled
[    2.345129] OTG VER PARAM: 0, OTG VER FLAG: 0
[    2.346418] Dedicated Tx FIFOs mode

[    2.347940] WARN::dwc_otg_hcd_init:1074: FIQ DMA bounce buffers: virt = b7514                                                                                                                                                             000 dma = 0xf7514000 len=9024
[    2.351768] FIQ FSM acceleration enabled for :
              Non-periodic Split Transactions
              Periodic Split Transactions
              High-Speed Isochronous Endpoints
              Interrupt/Control Split Transaction hack enabled
[    2.358002] dwc_otg: Microframe scheduler enabled

[    2.358039] WARN::hcd_init_fiq:457: FIQ on core 1

[    2.360332] WARN::hcd_init_fiq:458: FIQ ASM at 807c9984 length 36

[    2.362642] WARN::hcd_init_fiq:497: MPHI regs_base at bb810000
[    2.364983] dwc_otg 3f980000.usb: DWC OTG Controller
[    2.366224] dwc_otg 3f980000.usb: new USB bus registered, assigned bus number                                                                                                                                                              1
[    2.367489] dwc_otg 3f980000.usb: irq 89, io mem 0x00000000
[    2.368742] Init: Port Power? op_state=1
[    2.369961] Init: Power Port (0)
[    2.371313] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bc                                                                                                                                                             dDevice= 5.10
[    2.372579] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=                                                                                                                                                             1
[    2.373833] usb usb1: Product: DWC OTG Controller
[    2.375061] usb usb1: Manufacturer: Linux 5.10.63-v7+ dwc_otg_hcd
[    2.376306] usb usb1: SerialNumber: 3f980000.usb
[    2.377929] hub 1-0:1.0: USB hub found
[    2.379193] hub 1-0:1.0: 1 port detected
[    2.380963] dwc_otg: FIQ enabled
[    2.380969] dwc_otg: NAK holdoff enabled
[    2.380974] dwc_otg: FIQ split-transaction FSM enabled
[    2.380983] Module dwc_common_port init
[    2.381133] usbcore: registered new interface driver usb-storage
[    2.382432] mousedev: PS/2 mouse device common for all mice
[    2.384344] bcm2835-wdt bcm2835-wdt: Broadcom BCM2835 watchdog timer
[    2.386418] sdhci: Secure Digital Host Controller Interface driver
[    2.387649] sdhci: Copyright(c) Pierre Ossman
[    2.389179] mmc-bcm2835 3f300000.mmcnr: could not get clk, deferring probe
[    2.390757] sdhost-bcm2835 3f202000.mmc: could not get clk, deferring probe
[    2.392132] sdhci-pltfm: SDHCI platform and OF driver helper
[    2.394678] ledtrig-cpu: registered to indicate activity on CPUs
[    2.396157] hid: raw HID events driver (C) Jiri Kosina
[    2.397548] usbcore: registered new interface driver usbhid
[    2.398845] usbhid: USB HID core driver
[    2.402819] Initializing XFRM netlink socket
[    2.404135] NET: Registered protocol family 17
[    2.405474] Key type dns_resolver registered
[    2.407004] Registering SWP/SWPB emulation handler
[    2.408335] registered taskstats version 1
[    2.409618] Loading compiled-in X.509 certificates
[    2.411255] Key type ._fscrypt registered
[    2.412458] Key type .fscrypt registered
[    2.413624] Key type fscrypt-provisioning registered
[    2.420335] uart-pl011 3f201000.serial: there is not valid maps for state def                                                                                                                                                             ault
[    2.421682] uart-pl011 3f201000.serial: cts_event_workaround enabled
[    2.422948] 3f201000.serial: ttyAMA0 at MMIO 0x3f201000 (irq = 114, base_baud                                                                                                                                                              = 0) is a PL011 rev2
[    3.495992] printk: console [ttyAMA0] enabled
[    3.501901] Indeed it is in host mode hprt0 = 00021501
[    3.569234] bcm2835-power bcm2835-power: Broadcom BCM2835 power domains drive                                                                                                                                                             r
[    3.578576] mmc-bcm2835 3f300000.mmcnr: mmc_debug:0 mmc_debug2:0
[    3.585889] mmc-bcm2835 3f300000.mmcnr: DMA channel allocated
[    3.618847] sdhost: log_buf @ (ptrval) (f7513000)
[    3.659621] mmc1: queuing unknown CIS tuple 0x80 (2 bytes)
[    3.667884] mmc1: queuing unknown CIS tuple 0x80 (3 bytes)
[    3.676115] mmc1: queuing unknown CIS tuple 0x80 (3 bytes)
[    3.682789] mmc0: sdhost-bcm2835 loaded - DMA enabled (>1)
[    3.690810] of_cfs_init
[    3.694471] of_cfs_init: OK
[    3.710643] Freeing unused kernel memory: 1024K
[    3.712010] mmc1: queuing unknown CIS tuple 0x80 (7 bytes)
[    3.723129] Run /init as init process
[    3.727908]   with arguments:
[    3.727913]     /init
[    3.727918]   with environment:
[    3.727923]     HOME=/
[    3.727927]     TERM=linux
[    3.739347] usb 1-1: new high-speed USB device number 2 using dwc_otg
[    3.747198] Indeed it is in host mode hprt0 = 00001101
[    3.850915] mmc0: host does not support reading read-only switch, assuming wr                                                                                                                                                             ite-enable
[    3.867771] mmc0: new high speed SDHC card at address aaaa
[    3.877232] mmcblk0: mmc0:aaaa SL16G 14.8 GiB
[    3.884303] mmc1: new high speed SDIO card at address 0001
[    3.896935]  mmcblk0: p1 p2
[    3.999741] usb 1-1: New USB device found, idVendor=0424, idProduct=2514, bcd                                                                                                                                                             Device= b.b3
[    4.009380] usb 1-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0
[    4.018471] hub 1-1:1.0: USB hub found
[    4.026568] hub 1-1:1.0: 4 ports detected
[    4.125096] rtc-ds1307 1-006f: registered as rtc0
[    4.138116] rtc-ds1307 1-006f: setting system clock to 2021-11-19T06:22:53 UT                                                                                                                                                             C (1637302973)
[    4.315011] i2c /dev entries driver
[    4.369316] usb 1-1.1: new high-speed USB device number 3 using dwc_otg
[    4.499558] usb 1-1.1: New USB device found, idVendor=0424, idProduct=2514, b                                                                                                                                                             cdDevice= b.b3
[    4.509458] usb 1-1.1: New USB device strings: Mfr=0, Product=0, SerialNumber                                                                                                                                                             =0
[    4.518854] hub 1-1.1:1.0: USB hub found
[    4.524977] hub 1-1.1:1.0: 3 ports detected
[    4.661057] EXT4-fs (mmcblk0p2): mounted filesystem with ordered data mode. O                                                                                                                                                             pts: (null)
[    4.884871] dwc_otg_handle_wakeup_detected_intr lxstate = 2
[    5.399000] NET: Registered protocol family 10
[    5.399373] usb 1-1.1.1: new high-speed USB device number 4 using dwc_otg
[    5.405658] Segment Routing with IPv6
[    5.471610] systemd[1]: systemd 247.3-6+rpi1 running in system mode. (+PAM +A                                                                                                                                                             UDIT +SELINUX +IMA +APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUT                                                                                                                                                             LS +ACL +XZ +LZ4 +ZSTD +SECCOMP +BLKID +ELFUTILS +KMOD +IDN2 -IDN +PCRE2 default                                                                                                                                                             -hierarchy=unified)
[    5.505345] systemd[1]: Detected architecture arm.
[    5.528245] systemd[1]: Set hostname to <raspberrypi>.
[    5.539599] usb 1-1.1.1: New USB device found, idVendor=0424, idProduct=7800,                                                                                                                                                              bcdDevice= 3.00
[    5.549704] usb 1-1.1.1: New USB device strings: Mfr=0, Product=0, SerialNumb                                                                                                                                                             er=0
[    5.704095] uart-pl011 3f201000.serial: no DMA platform data
[    5.821899] lan78xx 1-1.1.1:1.0 (unnamed net_device) (uninitialized): No Exte                                                                                                                                                             rnal EEPROM. Setting MAC Speed
[    5.835816] libphy: lan78xx-mdiobus: probed
[    5.852588] lan78xx 1-1.1.1:1.0 (unnamed net_device) (uninitialized): int urb                                                                                                                                                              period 64
[    5.998990] random: lvmconfig: uninitialized urandom read (4 bytes read)
[    6.442900] systemd[1]: Queued start job for default target Multi-User System                                                                                                                                                             .
[    6.451912] systemd[1]: Unnecessary job for /dev/rtc was removed.
[    6.459610] systemd[1]: Unnecessary job for /sys/devices/platform/soc/3f80400                                                                                                                                                             0.i2c/i2c-1/1-006f/rtc/rtc0 was removed.
[    6.474672] random: systemd: uninitialized urandom read (16 bytes read)
[    6.484516] systemd[1]: Created slice system-getty.slice.
[    6.493900] random: systemd: uninitialized urandom read (16 bytes read)
[    6.503113] systemd[1]: Created slice system-modprobe.slice.
[    6.513070] systemd[1]: Created slice system-serial\x2dgetty.slice.
[    6.523560] systemd[1]: Created slice system-systemd\x2dfsck.slice.
[    6.533933] systemd[1]: Created slice User and Session Slice.
[    6.543586] systemd[1]: Started Dispatch Password Requests to Console Directo                                                                                                                                                             ry Watch.
[    6.555422] systemd[1]: Started Forward Password Requests to Wall Directory W                                                                                                                                                             atch.
[    6.567377] systemd[1]: Set up automount Arbitrary Executable File Formats Fi                                                                                                                                                             le System Automount Point.
[    6.582596] systemd[1]: Reached target Local Encrypted Volumes.
[    6.592562] systemd[1]: Reached target Paths.
[    6.600792] systemd[1]: Reached target Slices.
[    6.608920] systemd[1]: Reached target Swap.
[    6.617195] systemd[1]: Listening on Device-mapper event daemon FIFOs.
[    6.627752] systemd[1]: Listening on LVM2 poll daemon socket.
[    6.637524] systemd[1]: Listening on Syslog Socket.
[    6.646373] systemd[1]: Listening on fsck to fsckd communication Socket.
[    6.656964] systemd[1]: Listening on initctl Compatibility Named Pipe.
[    6.667764] systemd[1]: Listening on Journal Audit Socket.
[    6.677223] systemd[1]: Listening on Journal Socket (/dev/log).
[    6.687033] systemd[1]: Listening on Journal Socket.
[    6.699594] systemd[1]: Listening on udev Control Socket.
[    6.708684] systemd[1]: Listening on udev Kernel Socket.
[    6.717866] systemd[1]: Condition check resulted in Huge Pages File System be                                                                                                                                                             ing skipped.
[    6.730058] systemd[1]: Mounting POSIX Message Queue File System...
[    6.742970] systemd[1]: Mounting RPC Pipe File System...
[    6.754533] systemd[1]: Mounting Kernel Debug File System...
[    6.766328] systemd[1]: Mounting Kernel Trace File System...
[    6.775864] systemd[1]: Condition check resulted in Kernel Module supporting                                                                                                                                                              RPCSEC_GSS being skipped.
[    6.788823] systemd[1]: Finished Availability of block devices.
[    6.805458] systemd[1]: Starting Set the console keyboard layout...
[    6.818679] systemd[1]: Starting Create list of static device nodes for the c                                                                                                                                                             urrent kernel...
[    6.834166] systemd[1]: Starting Monitoring of LVM2 mirrors, snapshots etc. u                                                                                                                                                             sing dmeventd or progress polling...
[    6.854824] systemd[1]: Starting Load Kernel Module configfs...
[    6.869464] systemd[1]: Starting Load Kernel Module drm...
[    6.882958] systemd[1]: Starting Load Kernel Module fuse...
[    6.935754] systemd[1]: Condition check resulted in Set Up Additional Binary                                                                                                                                                              Formats being skipped.
[    6.948735] systemd[1]: Condition check resulted in File System Check on Root                                                                                                                                                              Device being skipped.
[    6.969186] fuse: init (API version 7.32)
[    6.970147] systemd[1]: Starting Load Kernel Modules...
[    6.988160] systemd[1]: Starting Remount Root and Kernel File Systems...
[    7.002593] systemd[1]: Starting Coldplug All udev Devices...
[    7.019743] systemd[1]: Mounted POSIX Message Queue File System.
[    7.030938] EXT4-fs (mmcblk0p2): re-mounted. Opts: (null)
[    7.045566] systemd[1]: Mounted RPC Pipe File System.
[    7.054834] systemd[1]: Mounted Kernel Debug File System.
[    7.064359] systemd[1]: Mounted Kernel Trace File System.
[    7.076894] systemd[1]: Finished Create list of static device nodes for the c                                                                                                                                                             urrent kernel.
[    7.090200] systemd[1]: Finished Monitoring of LVM2 mirrors, snapshots etc. u                                                                                                                                                             sing dmeventd or progress polling.
[    7.106931] systemd[1]: [email protected]: Succeeded.
[    7.115052] systemd[1]: Finished Load Kernel Module configfs.
[    7.125356] systemd[1]: [email protected]: Succeeded.
[    7.133036] systemd[1]: Finished Load Kernel Module drm.
[    7.142865] systemd[1]: [email protected]: Succeeded.
[    7.150877] systemd[1]: Finished Load Kernel Module fuse.
[    7.160839] systemd[1]: Finished Load Kernel Modules.
[    7.170858] systemd[1]: Finished Remount Root and Kernel File Systems.
[    7.186978] systemd[1]: Mounting FUSE Control File System...
[    7.199202] systemd[1]: Mounting Kernel Configuration File System...
[    7.214432] systemd[1]: Condition check resulted in Rebuild Hardware Database                                                                                                                                                              being skipped.
[    7.225211] systemd[1]: Condition check resulted in Platform Persistent Stora                                                                                                                                                             ge Archival being skipped.
[    7.240665] systemd[1]: Starting Load/Save Random Seed...
[    7.252987] systemd[1]: Starting Apply Kernel Variables...
[    7.266178] systemd[1]: Starting Create System Users...
[    7.278753] systemd[1]: Finished Set the console keyboard layout.
[    7.288970] systemd[1]: Mounted FUSE Control File System.
[    7.298732] systemd[1]: Mounted Kernel Configuration File System.
[    7.302089] systemd[1]: Finished Apply Kernel Variables.
[    7.316138] systemd[1]: Finished Create System Users.
[    7.319071] systemd[1]: Finished Coldplug All udev Devices.
[    7.327502] systemd[1]: Starting Helper to synchronize boot up for ifupdown..                                                                                                                                                             .
[    7.332696] systemd[1]: Starting Create Static Device Nodes in /dev...
[    7.345047] systemd[1]: Finished Helper to synchronize boot up for ifupdown.
[    7.382281] systemd[1]: Finished Create Static Device Nodes in /dev.
[    7.384455] systemd[1]: Reached target Local File Systems (Pre).
[    7.393160] systemd[1]: Starting Rule-based Manager for Device Events and Fil                                                                                                                                                             es...
[    7.565651] systemd[1]: Started Rule-based Manager for Device Events and File                                                                                                                                                             s.
[    7.721982] gpio-fan gpio-fan@0: GPIO fan initialized
[    7.760753] vc_sm_cma: module is from the staging directory, the quality is u                                                                                                                                                             nknown, you have been warned.
[    7.763693] bcm2835_vc_sm_cma_probe: Videocore shared memory driver
[    7.763709] [vc_sm_connected_init]: start
[    7.769586] [vc_sm_connected_init]: installed successfully
[    7.785978] mc: Linux media interface: v0.10
[    7.814890] videodev: Linux video capture interface: v2.00
[    7.817656] systemd[1]: Found device /dev/ttyAMA0.
[    7.846219] bcm2835_mmal_vchiq: module is from the staging directory, the qua                                                                                                                                                             lity is unknown, you have been warned.
[    7.846550] bcm2835_mmal_vchiq: module is from the staging directory, the qua                                                                                                                                                             lity is unknown, you have been warned.
[    7.885814] bcm2835_isp: module is from the staging directory, the quality is                                                                                                                                                              unknown, you have been warned.
[    7.889962] bcm2835_v4l2: module is from the staging directory, the quality i                                                                                                                                                             s unknown, you have been warned.
[    7.890476] bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev                                                                                                                                                             /video13
[    7.890828] bcm2835-isp bcm2835-isp: Device node capture[0] registered as /de                                                                                                                                                             v/video14
[    7.891063] bcm2835-isp bcm2835-isp: Device node capture[1] registered as /de                                                                                                                                                             v/video15
[    7.891268] bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/                                                                                                                                                             video16
[    7.891290] bcm2835-isp bcm2835-isp: Register output node 0 with media contro                                                                                                                                                             ller
[    7.891304] bcm2835-isp bcm2835-isp: Register capture node 1 with media contr                                                                                                                                                             oller
[    7.891314] bcm2835-isp bcm2835-isp: Register capture node 2 with media contr                                                                                                                                                             oller
[    7.891323] bcm2835-isp bcm2835-isp: Register capture node 3 with media contr                                                                                                                                                             oller
[    7.891442] bcm2835-isp bcm2835-isp: Loaded V4L2 bcm2835-isp
[    7.917411] bcm2835_codec: module is from the staging directory, the quality                                                                                                                                                              is unknown, you have been warned.
[    7.922185] bcm2835-codec bcm2835-codec: Device registered as /dev/video10
[    7.922235] bcm2835-codec bcm2835-codec: Loaded V4L2 decode
[    7.924398] bcm2835-codec bcm2835-codec: Device registered as /dev/video11
[    7.924430] bcm2835-codec bcm2835-codec: Loaded V4L2 encode
[    7.926950] bcm2835-codec bcm2835-codec: Device registered as /dev/video12
[    7.926980] bcm2835-codec bcm2835-codec: Loaded V4L2 isp
[    7.928614] bcm2835-codec bcm2835-codec: Device registered as /dev/video18
[    7.928645] bcm2835-codec bcm2835-codec: Loaded V4L2 image_fx
[    8.125852] systemd[1]: Found device /dev/disk/by-partuuid/e94b1ac0-01.
[    8.138914] systemd[1]: Found device /dev/rtc0.
[    8.144930] systemd[1]: Starting Synchronise System clock to hardware RTC...
[    8.152751] systemd[1]: Starting File System Check on /dev/disk/by-partuuid/e                                                                                                                                                             94b1ac0-01...
[    8.198838] input: raspberrypi-ts as /devices/platform/soc/soc:firmware/soc:f                                                                                                                                                             irmware:touchscreen/input/input0
[    8.200875] cfg80211: Loading compiled-in X.509 certificates for regulatory d                                                                                                                                                             atabase
[    8.203277] 1-0048: ttySC1 at I/O 0x1 (irq = 200, base_baud = 1152000) is a S                                                                                                                                                             C16IS752
[    8.214704] systemd[1]: Started File System Check Daemon to report status.
[    8.230211] 1-004d: ttySC3 at I/O 0x1 (irq = 201, base_baud = 1152000) is a S                                                                                                                                                             C16IS752
[    8.301263] cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'
[    8.330949] cfg80211: loaded regulatory.db is malformed or signature is missi                                                                                                                                                             ng/invalid
[    8.375537] brcmfmac: F1 signature read @0x18000000=0x15264345
[    8.389468] brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio f                                                                                                                                                             or chip BCM4345/6
[    8.389967] usbcore: registered new interface driver brcmfmac
[    8.509120] systemd[1]: Finished Synchronise System clock to hardware RTC.
[    8.516873] systemd[1]: Starting Journal Service...
[    8.625613] systemd[1]: Created slice system-systemd\x2dbacklight.slice.
[    8.639058] random: crng init done
[    8.639074] random: 7 urandom warning(s) missed due to ratelimiting
[    8.653155] systemd[1]: Starting Load/Save Screen Backlight Brightness of bac                                                                                                                                                             klight:rpi_backlight...
[    8.661581] systemd[1]: Finished Load/Save Random Seed.
[    8.664083] systemd[1]: Condition check resulted in First Boot Complete being                                                                                                                                                              skipped.
[    8.673740] systemd[1]: Finished Load/Save Screen Backlight Brightness of bac                                                                                                                                                             klight:rpi_backlight.
[    8.732161] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfk                                                                                                                                                             ill Watch.
[    8.753095] systemd[1]: Started Journal Service.
[    8.789194] brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio f                                                                                                                                                             or chip BCM4345/6
[    8.789406] brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio f                                                                                                                                                             or chip BCM4345/6
[    8.800659] systemd-journald[377]: Received client request to flush runtime j                                                                                                                                                             ournal.
[    8.804687] brcmfmac: brcmf_c_preinit_dcmds: Firmware: BCM4345/6 wl0: Jan  4                                                                                                                                                              2021 19:56:29 version 7.45.229 (617f1f5 CY) FWID 01-2dbd9d2e
[    9.581349] 8021q: 802.1Q VLAN Support v1.8
[    9.809464] Adding 102396k swap on /var/swap.  Priority:-2 extents:1 across:1                                                                                                                                                             02396k SSFS
[   10.174672] 8021q: adding VLAN 0 to HW filter on device eth0
[   10.178170] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
  • Which model of Raspberry Pi? >> Pi3B+
  • Which OS and version (cat /etc/rpi-issue)?
Raspberry Pi reference 2021-10-30
Generated using pi-gen, https://github.com/RPi-Distro/pi-gen, 288b21fc27e128ea6b330777aca68e0061ebf4fe, stage2
  • Which firmware version (vcgencmd version)?
Oct 29 2021 10:49:08
Copyright (c) 2012 Broadcom
version b8a114e5a9877e91ca8f26d1a5ce904b2ad3cf13 (clean) (release) (start)
  • Which kernel version (uname -a)?
    Linux raspberrypi 5.10.63-v7+ #1459 SMP Wed Oct 6 16:41:10 BST 2021 armv7l GNU/Linux
    image

Additional context
Add any other relevant context for the problem.
sc16is752-i2c-01-02.zip

@paulenuta
Copy link
Author

ping

@danergo
Copy link

danergo commented Jan 18, 2022

I'm also having this. But I have another detail to add:

My trial is perfectly working on RPi1 (CM):

Linux rpi1 5.10.63+ #1457 Tue Sep 28 11:24:51 BST 2021 armv6l GNU/Linux

And it never works on RPi3 (CM):

Linux rpi3 5.10.63-v8+ #1488 SMP PREEMPT Thu Nov 18 16:16:16 GMT 2021 aarch64 GNU/Linux

As you can see, I do have a 5.10.y kernel on RPi1 which works perfectly. So it might be kernel related, and it might be also related to architecture, or might be a 32/64-bit issue.

@danergo
Copy link

danergo commented Jan 29, 2022

ping

paulenuta referenced this issue Feb 17, 2022
UART drivers are meant to use the port spinlock within certain
methods, to protect against reentrancy. The sc16is7xx driver does
very little locking, presumably because when added it triggers
"scheduling while atomic" errors. This is due to the use of mutexes
within the regmap abstraction layer, and the mutex implementation's
habit of sleeping the current thread while waiting for access.
Unfortunately this lack of interlocking can lead to corruption of
outbound data, which occurs when the buffer used for I2C transmission
is used simultaneously by two threads - a work queue thread running
sc16is7xx_tx_proc, and an IRQ thread in sc16is7xx_port_irq, both
of which can call sc16is7xx_handle_tx.

An earlier patch added efr_lock, a mutex that controls access to the
EFR register. This mutex is already claimed in the IRQ handler, and
all that is required is to claim the same mutex in sc16is7xx_tx_proc.

See: #4885

Signed-off-by: Phil Elwell <[email protected]>
@paulenuta
Copy link
Author

paulenuta commented Feb 17, 2022

After sudo rpi_update I got uname -a: Linux raspberrypi 5.15.23-v7+ #1525 SMP Wed Feb 16 14:34:22 GMT 2022 armv7l GNU/Linux.

Running the test linux-serial-test/linux-serial-test -s -e -p /dev/ttySC0 -b 9600 -A -T -o 120 -i 125 give me no error:

/dev/ttySC0: count for this session: rx=115683, tx=115683, rx err=0
/dev/ttySC0: TIOCGICOUNT: ret=0, rx=20401, tx=219630, frame = 0, overrun = 0, parity = 0, brk = 0, buf_overrun = 0

4711.log
Full log attached.

Thank you @pelwell !

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Labels
None yet
Projects
None yet
Development

No branches or pull requests

2 participants