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.22k stars 5.02k forks source link

Kernel panic, bcm2836_arm_irqchip_handle_irq with raspberrypi-kernel dated 2020723 #3782

Open vasyugan opened 4 years ago

vasyugan commented 4 years ago

Since installing the kernel of 20200723, almost each night, when my raspi receives btrfs snapshot backups from a remote server, it is having kernel panics. The problem doesn't occur with the next older kernel (20200717)

See screenshot below.

IMG_20200810_084915

System System Information

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

Raspberry Pi reference 2016-02-26 Generated using Pi-gen, https://github.com/RPi-Distro/Pi-gen, stage4

Linux raspbian 5.4.51-v7+ #1327 SMP Thu Jul 23 10:58:46 BST 2020 armv7l GNU/Linux Revision : a02082 Serial : 00000000cdcc9893 Model : Raspberry Pi 3 Model B Rev 1.2 Throttled flag : throttled=0x0 Camera : supported=0 detected=0

Videocore information

Jul 17 2020 11:04:27 Copyright (c) 2012 Broadcom version 21a15cb094f41c7506ad65d2cb9b29c550693057 (clean) (release) (start)

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

Filesystem information

Dateisystem 1K-Blöcke Benutzt Verfügbar Verw% Eingehängt auf /dev/root 7344832 3159444 3854196 46% / devtmpfs 441236 0 441236 0% /dev tmpfs 474516 92 474424 1% /dev/shm tmpfs 474516 13464 461052 3% /run tmpfs 5120 4 5116 1% /run/lock tmpfs 474516 0 474516 0% /sys/fs/cgroup /dev/mmcblk0p1 258096 54460 203636 22% /boot /dev/sda1 5858423808 3966408376 1886047912 68% /var/backups /dev/sda1 5858423808 3966408376 1886047912 68% /var/log /dev/sda1 5858423808 3966408376 1886047912 68% /var/www /dev/sda1 5858423808 3966408376 1886047912 68% /root /dev/sda1 5858423808 3966408376 1886047912 68% /tmp /dev/sda1 5858423808 3966408376 1886047912 68% /var/lib/mysql /dev/sda1 5858423808 3966408376 1886047912 68% /var/cache /dev/sda1 5858423808 3966408376 1886047912 68% /home /dev/sda1 5858423808 3966408376 1886047912 68% /srv tmpfs 94900 0 94900 0% /run/user/0 tmpfs 94900 0 94900 0% /run/user/1000

Filename Type Size Used Priority /dev/sda2 partition 2097148 776 -2

Package version information

raspberrypi-ui-mods: Installiert: (keine) raspberrypi-sys-mods: Installiert: 20200514 openbox: Installiert: (keine) lxpanel: Installiert: (keine) pcmanfm: Installiert: (keine) rpd-plym-splash: Installiert: (keine)

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 fd09:c9bc:y::y.y.y.y prefixlen 128 scopeid 0x0 inet6 2003:d3:c718:y::y.y.y.y prefixlen 128 scopeid 0x0 inet6 y.y.y.y.y.y.y.y prefixlen 64 scopeid 0x0 inet6 y.y.y.y.y.y.y.y prefixlen 64 scopeid 0x0 inet6 y::y.y.y.y prefixlen 64 scopeid 0x20 ether m.m.m.m txqueuelen 1000 (Ethernet) RX packets 2629 bytes 276952 (270.4 KiB) RX errors 0 dropped 0 overruns 0 frame 0 TX packets 3181 bytes 1398678 (1.3 MiB) 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 loop txqueuelen 1000 (Lokale Schleife) RX packets 788 bytes 852797 (832.8 KiB) RX errors 0 dropped 0 overruns 0 frame 0 TX packets 788 bytes 852797 (832.8 KiB) TX errors 0 dropped 0 overruns 0 carrier 0 collisions 0

tun0: flags=4305<UP,POINTOPOINT,RUNNING,NOARP,MULTICAST> mtu 1500 inet x.x.x.x netmask x.x.x.x destination x.x.x.x inet6 y::y.y.y.y prefixlen 64 scopeid 0x20 unspec 00-00-00-00-00-00-00-00-00-00-00-00-00-00-00-00 txqueuelen 100 (UNSPEC) RX packets 0 bytes 0 (0.0 B) RX errors 0 dropped 0 overruns 0 frame 0 TX packets 502 bytes 67288 (65.7 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.y.y.y prefixlen 64 scopeid 0x0 inet6 fd09:c9bc:y::y.y.y.y prefixlen 128 scopeid 0x0 inet6 2003:d3:c718:y::y.y.y.y prefixlen 128 scopeid 0x0 inet6 y.y.y.y.y.y.y.y prefixlen 64 scopeid 0x0 inet6 y::y.y.y.y prefixlen 64 scopeid 0x20 ether m.m.m.m txqueuelen 1000 (Ethernet) RX packets 1335 bytes 131910 (128.8 KiB) RX errors 0 dropped 0 overruns 0 frame 0 TX packets 124 bytes 21922 (21.4 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/5p, 480M | Port 1: Dev 3, If 0, Class=Vendor Specific Class, Driver=smsc95xx, 480M | Port 3: Dev 5, If 0, Class=Mass Storage, Driver=usb-storage, 480M | Port 5: Dev 4, If 0, Class=Human Interface Device, Driver=usbhid, 1.5M |__ Port 5: Dev 4, If 1, Class=Human Interface Device, Driver=usbhid, 1.5M

config.txt

aphy_params_current=819 arm_freq=1200 audio_pwm_mode=514 config_hdmi_boost=5 core_freq=400 desired_osc_freq=0x387520 disable_commandline_tags=2 disable_l2cache=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=0x2625a 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=1920 bcm2708_fb.fbheight=1200 bcm2708_fb.fbswap=1 vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000 dwc_otg.lpm_enable=0 console=ttyS0,115200 console=tty1 root=PARTUUID=931cd833-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=0 func=INPUT GPIO 3: level=1 fsel=0 func=INPUT 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=0 fsel=0 func=INPUT 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=0 fsel=0 func=INPUT GPIO 29: level=1 fsel=0 func=INPUT GPIO 30: level=0 fsel=0 func=INPUT GPIO 31: level=0 fsel=0 func=INPUT 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=0 fsel=4 alt=0 func=GPCLK2 GPIO 44: level=1 fsel=0 func=INPUT GPIO 45: level=1 fsel=0 func=INPUT 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

001516.329: brfs: File read: /mfs/sd/config.txt 001517.337: brfs: File read: 1735 bytes 001559.733: brfs: File read: /mfs/sd/config.txt 001560.642: gpioman: gpioman_get_pin_num: pin LEDS_PWR_OK not defined 001572.533: brfs: File read: 1735 bytes 001763.592: gpioman: gpioman_get_pin_num: pin DISPLAY_DSI_PORT not defined 001764.834: gpioman: gpioman_get_pin_num: pin LEDS_PWR_OK not defined 001764.890: *** Restart logging 001784.021: HDMI0: hdmi_pixel_encoding: 162000000 001789.274: dtb_file 'bcm2710-rpi-3-b.dtb' 001794.589: brfs: File read: /mfs/sd/bcm2710-rpi-3-b.dtb 001794.613: Loading 'bcm2710-rpi-3-b.dtb' to 0x100 size 0x6d2a 001834.227: brfs: File read: 27946 bytes 001854.150: brfs: File read: /mfs/sd/overlays/overlay_map.dtb 002040.180: brfs: File read: 1371 bytes 002045.648: brfs: File read: /mfs/sd/config.txt 002046.238: dtparam: audio=on 002095.047: brfs: File read: 1735 bytes 002097.401: brfs: File read: /mfs/sd/cmdline.txt 002097.455: Read command line from file 'cmdline.txt': 002097.477: 'dwc_otg.lpm_enable=0 console=serial0,115200 console=tty1 root=PARTUUID=931cd833-02 rootfstype=ext4 elevator=deadline fsck.repair=yes rootwait' 004235.833: gpioman: gpioman_get_pin_num: pin EMMC_ENABLE not defined 004404.177: brfs: File read: 142 bytes 004838.313: brfs: File read: /mfs/sd/kernel7.img 004838.338: Loading 'kernel7.img' to 0x8000 size 0x557138 004838.370: Device tree loaded to 0x2eff8e00 (size 0x7172) 004839.253: gpioman: gpioman_get_pin_num: pin SDCARD_CONTROL_POWER not defined 007943.901: vchiq_core: vchiq_init_state: slot_zero = 0xf7580000, is_master = 1 007948.941: hdmi: HDMI:hdmi_get_state is deprecated, use hdmi_get_display_state instead 007955.378: TV service:host side not connected, dropping notification 0x00000002, 0x00000002, 0x00000044

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)) #1327 SMP Thu Jul 23 10:58:46 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 Rev 1.2 [ 0.000000] Memory policy: Data cache writealloc [ 0.000000] Reserved memory: created CMA memory pool at 0x37400000, size 64 MiB [ 0.000000] OF: reserved mem: initialized node linux,cma, compatible id shared-dma-pool [ 0.000000] On node 0 totalpages: 242688 [ 0.000000] Normal zone: 1896 pages used for memmap [ 0.000000] Normal zone: 0 pages reserved [ 0.000000] Normal zone: 242688 pages, LIFO batch:63 [ 0.000000] percpu: Embedded 20 pages/cpu s49792 r8192 d23936 u81920 [ 0.000000] pcpu-alloc: s49792 r8192 d23936 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: 240792 [ 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=1920 bcm2708_fb.fbheight=1200 bcm2708_fb.fbswap=1 vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000 dwc_otg.lpm_enable=0 console=ttyS0,115200 console=tty1 root=PARTUUID=931cd833-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: 882472K/970752K available (8192K kernel code, 681K rwdata, 2532K rodata, 1024K init, 824K bss, 22744K reserved, 65536K cma-reserved) [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 [ 0.000000] ftrace: allocating 27966 entries in 55 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/0x4e8 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.000292] Console: colour dummy device 80x30 [ 0.000877] printk: console [tty1] enabled [ 0.000943] Calibrating delay loop (skipped), value calculated using timer frequency.. 38.40 BogoMIPS (lpj=192000) [ 0.000992] pid_max: default: 32768 minimum: 301 [ 0.001337] Mount-cache hash table entries: 2048 (order: 1, 8192 bytes, linear) [ 0.001384] Mountpoint-cache hash table entries: 2048 (order: 1, 8192 bytes, linear) [ 0.002575] CPU: Testing write buffer coherency: ok [ 0.003145] CPU0: thread -1, cpu 0, socket 0, mpidr 80000000 [ 0.004106] Setting up static identity map for 0x100000 - 0x10003c [ 0.004328] rcu: Hierarchical SRCU implementation. [ 0.005027] smp: Bringing up secondary CPUs ... [ 0.006163] CPU1: thread -1, cpu 1, socket 0, mpidr 80000001 [ 0.007422] CPU2: thread -1, cpu 2, socket 0, mpidr 80000002 [ 0.008577] CPU3: thread -1, cpu 3, socket 0, mpidr 80000003 [ 0.008729] smp: Brought up 1 node, 4 CPUs [ 0.008817] SMP: Total of 4 processors activated (153.60 BogoMIPS). [ 0.008846] CPU: All CPU(s) started in HYP mode. [ 0.008870] CPU: Virtualization extensions available. [ 0.009831] devtmpfs: initialized [ 0.026212] VFP support v0.3: implementor 41 architecture 3 part 40 variant 3 rev 4 [ 0.026515] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns [ 0.026571] futex hash table entries: 1024 (order: 4, 65536 bytes, linear) [ 0.029595] pinctrl core: initialized pinctrl subsystem [ 0.030834] NET: Registered protocol family 16 [ 0.035045] DMA: preallocated 1024 KiB pool for atomic coherent allocations [ 0.041345] hw-breakpoint: found 5 (+1 reserved) breakpoint and 4 watchpoint registers. [ 0.041386] hw-breakpoint: maximum watchpoint size is 8 bytes. [ 0.041642] Serial: AMBA PL011 UART driver [ 0.043594] bcm2835-mbox 3f00b880.mailbox: mailbox enabled [ 0.060086] raspberrypi-firmware soc:firmware: Attached to firmware from 2020-07-17 11:04, variant start [ 0.070100] raspberrypi-firmware soc:firmware: Firmware hash is 21a15cb094f41c7506ad65d2cb9b29c550693057 [ 0.121101] bcm2835-dma 3f007000.dma: DMA legacy API manager, dmachans=0x1 [ 0.123290] SCSI subsystem initialized [ 0.123568] usbcore: registered new interface driver usbfs [ 0.123650] usbcore: registered new interface driver hub [ 0.123811] usbcore: registered new device driver usb [ 0.125817] clocksource: Switched to clocksource arch_sys_counter [ 1.283807] VFS: Disk quotas dquot_6.6.0 [ 1.283963] VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes) [ 1.284166] FS-Cache: Loaded [ 1.284408] CacheFiles: Loaded [ 1.294024] thermal_sys: Registered thermal governor 'step_wise' [ 1.294412] NET: Registered protocol family 2 [ 1.295365] tcp_listen_portaddr_hash hash table entries: 512 (order: 0, 6144 bytes, linear) [ 1.295434] TCP established hash table entries: 8192 (order: 3, 32768 bytes, linear) [ 1.295574] TCP bind hash table entries: 8192 (order: 4, 65536 bytes, linear) [ 1.295785] TCP: Hash tables configured (established 8192 bind 8192) [ 1.296012] UDP hash table entries: 512 (order: 2, 16384 bytes, linear) [ 1.296086] UDP-Lite hash table entries: 512 (order: 2, 16384 bytes, linear) [ 1.296400] NET: Registered protocol family 1 [ 1.297283] RPC: Registered named UNIX socket transport module. [ 1.297314] RPC: Registered udp transport module. [ 1.297341] RPC: Registered tcp transport module. [ 1.297367] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 1.299122] hw perfevents: enabled with armv7_cortex_a7 PMU driver, 7 counters available [ 1.302395] Initialise system trusted keyrings [ 1.302669] workingset: timestamp_bits=30 max_order=18 bucket_order=0 [ 1.313122] FS-Cache: Netfs 'nfs' registered for caching [ 1.313883] NFS: Registering the id_resolver key type [ 1.313976] Key type id_resolver registered [ 1.314004] Key type id_legacy registered [ 1.314040] nfs4filelayout_init: NFSv4 File Layout Driver Registering... [ 1.315346] Key type asymmetric registered [ 1.315376] Asymmetric key parser 'x509' registered [ 1.315454] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 249) [ 1.315491] io scheduler mq-deadline registered [ 1.315518] io scheduler kyber registered [ 1.320565] bcm2708_fb soc:fb: FB found 1 display(s) [ 1.402254] Console: switching to colour frame buffer device 240x75 [ 1.440280] bcm2708_fb soc:fb: Registered framebuffer for display 0, size 1920x1200 [ 1.445784] bcm2835-rng 3f104000.rng: hwrng registered [ 1.446486] vc-mem: phys_addr:0x00000000 mem_base=0x3ec00000 mem_size:0x40000000(1024 MiB) [ 1.447430] vc-sm: Videocore shared memory driver [ 1.448037] gpiomem-bcm2835 3f200000.gpiomem: Initialised: Registers at 0x3f200000 [ 1.460109] brd: module loaded [ 1.472495] loop: module loaded [ 1.474016] Loading iSCSI transport class v2.0-870. [ 1.475111] libphy: Fixed MDIO Bus: probed [ 1.475358] usbcore: registered new interface driver lan78xx [ 1.475589] usbcore: registered new interface driver smsc95xx [ 1.475781] dwc_otg: version 3.00a 10-AUG-2012 (platform bus) [ 2.204254] Core Release: 2.80a [ 2.204368] Setting default values for core params [ 2.204545] Finished setting default values for core params [ 2.405074] Using Buffer DMA mode [ 2.405190] Periodic Transfer Interrupt Enhancement - disabled [ 2.405367] Multiprocessor Interrupt Enhancement - disabled [ 2.405538] OTG VER PARAM: 0, OTG VER FLAG: 0 [ 2.405682] Dedicated Tx FIFOs mode [ 2.406484] WARN::dwc_otg_hcd_init:1074: FIQ DMA bounce buffers: virt = b7504000 dma = 0xf7504000 len=9024 [ 2.406789] FIQ FSM acceleration enabled for : Non-periodic Split Transactions Periodic Split Transactions High-Speed Isochronous Endpoints Interrupt/Control Split Transaction hack enabled [ 2.407399] dwc_otg: Microframe scheduler enabled [ 2.407471] WARN::hcd_init_fiq:457: FIQ on core 1 [ 2.407621] WARN::hcd_init_fiq:458: FIQ ASM at 806bdcbc length 36 [ 2.407805] WARN::hcd_init_fiq:497: MPHI regs_base at bb810000 [ 2.407993] dwc_otg 3f980000.usb: DWC OTG Controller [ 2.408179] dwc_otg 3f980000.usb: new USB bus registered, assigned bus number 1 [ 2.408432] dwc_otg 3f980000.usb: irq 56, io mem 0x00000000 [ 2.408648] Init: Port Power? op_state=1 [ 2.408773] Init: Power Port (0) [ 2.409238] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 5.04 [ 2.409487] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 [ 2.409705] usb usb1: Product: DWC OTG Controller [ 2.409857] usb usb1: Manufacturer: Linux 5.4.51-v7+ dwc_otg_hcd [ 2.410041] usb usb1: SerialNumber: 3f980000.usb [ 2.410935] hub 1-0:1.0: USB hub found [ 2.420124] hub 1-0:1.0: 1 port detected [ 2.429900] dwc_otg: FIQ enabled [ 2.429910] dwc_otg: NAK holdoff enabled [ 2.429921] dwc_otg: FIQ split-transaction FSM enabled [ 2.429938] Module dwc_common_port init [ 2.430312] usbcore: registered new interface driver usb-storage [ 2.439580] mousedev: PS/2 mouse device common for all mice [ 2.449953] bcm2835-wdt bcm2835-wdt: Broadcom BCM2835 watchdog timer [ 2.461106] sdhci: Secure Digital Host Controller Interface driver [ 2.470042] sdhci: Copyright(c) Pierre Ossman [ 2.479489] mmc-bcm2835 3f300000.mmcnr: could not get clk, deferring probe [ 2.489060] sdhost-bcm2835 3f202000.mmc: could not get clk, deferring probe [ 2.498184] sdhci-pltfm: SDHCI platform and OF driver helper [ 2.508967] ledtrig-cpu: registered to indicate activity on CPUs [ 2.518361] hidraw: raw HID events driver (C) Jiri Kosina [ 2.527646] usbcore: registered new interface driver usbhid [ 2.536744] usbhid: USB HID core driver [ 2.546986] vchiq: vchiq_init_state: slot_zero = (ptrval)

[ 2.568725] [vc_sm_connected_init]: end - returning 0 [ 2.579648] Initializing XFRM netlink socket [ 2.588526] NET: Registered protocol family 17 [ 2.597510] Key type dns_resolver registered [ 2.607034] Registering SWP/SWPB emulation handler [ 2.616232] registered taskstats version 1 [ 2.625036] Loading compiled-in X.509 certificates [ 2.634383] Key type ._fscrypt registered [ 2.643150] Key type .fscrypt registered [ 2.656064] Indeed it is in host mode hprt0 = 00021501 [ 2.662586] uart-pl011 3f201000.serial: cts_event_workaround enabled [ 2.673699] 3f201000.serial: ttyAMA0 at MMIO 0x3f201000 (irq = 81, base_baud = 0) is a PL011 rev2 [ 2.684611] bcm2835-power bcm2835-power: Broadcom BCM2835 power domains driver [ 2.695174] mmc-bcm2835 3f300000.mmcnr: mmc_debug:0 mmc_debug2:0 [ 2.704142] mmc-bcm2835 3f300000.mmcnr: DMA channel allocated [ 2.739629] sdhost: log_buf @ (ptrval) (f7507000) [ 2.750030] random: fast init done [ 2.784677] mmc1: queuing unknown CIS tuple 0x80 (2 bytes) [ 2.795131] mmc1: queuing unknown CIS tuple 0x80 (3 bytes) [ 2.805427] mmc1: queuing unknown CIS tuple 0x80 (3 bytes) [ 2.813970] mmc0: sdhost-bcm2835 loaded - DMA enabled (>1) [ 2.825345] of_cfs_init [ 2.834115] of_cfs_init: OK [ 2.843535] Waiting for root device PARTUUID=931cd833-02... [ 2.853351] mmc1: queuing unknown CIS tuple 0x80 (7 bytes) [ 2.865871] usb 1-1: new high-speed USB device number 2 using dwc_otg [ 2.874576] Indeed it is in host mode hprt0 = 00001101 [ 2.921929] mmc0: host does not support reading read-only switch, assuming write-enable [ 2.934650] mmc0: new high speed SDHC card at address e624 [ 2.944483] mmcblk0: mmc0:e624 SU08G 7.40 GiB [ 2.960095] mmcblk0: p1 p2 [ 2.996986] mmc1: new high speed SDIO card at address 0001 [ 3.004113] EXT4-fs (mmcblk0p2): INFO: recovery required on readonly filesystem [ 3.014494] EXT4-fs (mmcblk0p2): write access will be enabled during recovery [ 3.116216] usb 1-1: New USB device found, idVendor=0424, idProduct=9514, bcdDevice= 2.00 [ 3.125134] usb 1-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0 [ 3.134765] hub 1-1:1.0: USB hub found [ 3.143634] hub 1-1:1.0: 5 ports detected [ 3.465863] usb 1-1.1: new high-speed USB device number 3 using dwc_otg [ 3.596187] usb 1-1.1: New USB device found, idVendor=0424, idProduct=ec00, bcdDevice= 2.00 [ 3.604979] usb 1-1.1: New USB device strings: Mfr=0, Product=0, SerialNumber=0 [ 3.616634] smsc95xx v1.0.6 [ 3.720295] smsc95xx 1-1.1:1.0 eth0: register 'smsc95xx' at usb-3f980000.usb-1.1, smsc95xx USB 2.0 Ethernet, m.m.m.m [ 3.724121] EXT4-fs (mmcblk0p2): recovery complete [ 3.743121] EXT4-fs (mmcblk0p2): mounted filesystem with ordered data mode. Opts: (null) [ 3.752452] VFS: Mounted root (ext4 filesystem) readonly on device 179:2. [ 3.772326] devtmpfs: mounted [ 3.788411] Freeing unused kernel memory: 1024K [ 3.798080] Run /sbin/init as init process [ 3.825874] usb 1-1.5: new low-speed USB device number 4 using dwc_otg [ 3.983965] usb 1-1.5: New USB device found, idVendor=046d, idProduct=c31c, bcdDevice=64.00 [ 3.993533] usb 1-1.5: New USB device strings: Mfr=1, Product=2, SerialNumber=0 [ 4.003038] usb 1-1.5: Product: USB Keyboard [ 4.012452] usb 1-1.5: Manufacturer: Logitech [ 4.039985] input: Logitech USB Keyboard as /devices/platform/soc/3f980000.usb/usb1/1-1/1-1.5/1-1.5:1.0/0003:046D:C31C.0001/input/input0 [ 4.116804] hid-generic 0003:046D:C31C.0001: input,hidraw0: USB HID v1.10 Keyboard [Logitech USB Keyboard] on usb-3f980000.usb-1.5/input0 [ 4.155553] input: Logitech USB Keyboard Consumer Control as /devices/platform/soc/3f980000.usb/usb1/1-1/1-1.5/1-1.5:1.1/0003:046D:C31C.0002/input/input1 [ 4.236173] input: Logitech USB Keyboard System Control as /devices/platform/soc/3f980000.usb/usb1/1-1/1-1.5/1-1.5:1.1/0003:046D:C31C.0002/input/input2 [ 4.247024] hid-generic 0003:046D:C31C.0002: input,hidraw1: USB HID v1.10 Device [Logitech USB Keyboard] on usb-3f980000.usb-1.5/input1 [ 4.541112] systemd[1]: System time before build time, advancing clock. [ 4.555887] usb 1-1.3: new high-speed USB device number 5 using dwc_otg [ 4.727517] usb 1-1.3: New USB device found, idVendor=174c, idProduct=55aa, bcdDevice= 1.00 [ 4.737971] usb 1-1.3: New USB device strings: Mfr=2, Product=3, SerialNumber=1 [ 4.748325] usb 1-1.3: Product: ASM1153E [ 4.758572] usb 1-1.3: Manufacturer: asmedia [ 4.768785] usb 1-1.3: SerialNumber: 0123456789012 [ 4.787496] NET: Registered protocol family 10 [ 4.799427] Segment Routing with IPv6 [ 4.799597] usb 1-1.3: The driver for the USB controller dwc_otg_hcd does not support scatter-gather which is [ 4.820413] usb 1-1.3: required by the UAS driver. Please try an other USB controller if you wish to use UAS. [ 4.831175] usb-storage 1-1.3:1.0: USB Mass Storage device detected [ 4.842391] usb-storage 1-1.3:1.0: Quirks match for vid 174c pid 55aa: 400000 [ 4.853545] scsi host0: usb-storage 1-1.3:1.0 [ 4.864286] 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) [ 4.888345] systemd[1]: Detected architecture arm. [ 5.026900] systemd[1]: Set hostname to . [ 5.412211] systemd-sysv-generator[101]: stat() failed on /etc/init.d/rcS, ignoring: No such file or directory [ 5.510947] systemd-sysv-generator[101]: stat() failed on /etc/init.d/rc, ignoring: No such file or directory [ 5.916946] scsi 0:0:0:0: Direct-Access asmedia ASM1153E 0 PQ: 0 ANSI: 6 [ 5.931218] sd 0:0:0:0: [sda] 11721045168 512-byte logical blocks: (6.00 TB/5.46 TiB) [ 5.938256] systemd[1]: /lib/systemd/system/smbd.service:9: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/smbd.pid → /run/samba/smbd.pid; please update the unit file accordingly. [ 5.942911] sd 0:0:0:0: [sda] 4096-byte physical blocks [ 5.968685] sd 0:0:0:0: [sda] Write Protect is off [ 5.980968] sd 0:0:0:0: [sda] Mode Sense: 43 00 00 00 [ 5.981779] sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA [ 6.024957] systemd[1]: /lib/systemd/system/nmbd.service:9: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/nmbd.pid → /run/samba/nmbd.pid; please update the unit file accordingly. [ 6.090266] sda: sda1 sda2 [ 6.106924] sd 0:0:0:0: [sda] Attached SCSI disk [ 6.153923] systemd[1]: /lib/systemd/system/rpc-statd.service:13: PIDFile= references path below legacy directory /var/run/, updating /var/run/rpc.statd.pid → /run/rpc.statd.pid; please update the unit file accordingly. [ 6.385149] random: systemd: uninitialized urandom read (16 bytes read) [ 6.423123] random: systemd: uninitialized urandom read (16 bytes read) [ 6.437846] systemd[1]: Listening on fsck to fsckd communication Socket. [ 6.468499] random: systemd: uninitialized urandom read (16 bytes read) [ 6.491020] systemd[1]: Listening on Syslog Socket. [ 6.520923] systemd[1]: Listening on initctl Compatibility Named Pipe. [ 6.551281] systemd[1]: Listening on udev Control Socket. [ 6.909891] Installing knfsd (copyright (C) 1996 okir@monad.swb.de). [ 6.947494] i2c /dev entries driver [ 7.660320] EXT4-fs (mmcblk0p2): re-mounted. Opts: (null) [ 8.509771] mc: Linux media interface: v0.10 [ 8.556088] vc_sm_cma: module is from the staging directory, the quality is unknown, you have been warned. [ 8.561898] bcm2835_vc_sm_cma_probe: Videocore shared memory driver

[ 8.576469] videodev: Linux video capture interface: v2.00 [ 8.576690] [vc_sm_connected_init]: installed successfully [ 8.622757] bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. [ 8.622762] bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. [ 8.622766] bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. [ 8.644452] snd_bcm2835: module is from the staging directory, the quality is unknown, you have been warned. [ 8.652580] bcm2835_isp: module is from the staging directory, the quality is unknown, you have been warned. [ 8.664891] bcm2835_v4l2: module is from the staging directory, the quality is unknown, you have been warned. [ 8.669491] bcm2835_audio bcm2835_audio: card created with 4 channels [ 8.676013] bcm2835_audio bcm2835_audio: card created with 4 channels [ 8.681092] bcm2835_codec: module is from the staging directory, the quality is unknown, you have been warned. [ 8.688562] bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video13 [ 8.689106] bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video14 [ 8.689578] bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video15 [ 8.701026] bcm2835-codec bcm2835-codec: Device registered as /dev/video10 [ 8.701084] bcm2835-codec bcm2835-codec: Loaded V4L2 decode [ 8.701868] bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video16 [ 8.701905] bcm2835-isp bcm2835-isp: Register output node 0 with media controller [ 8.701928] bcm2835-isp bcm2835-isp: Register capture node 1 with media controller [ 8.701948] bcm2835-isp bcm2835-isp: Register capture node 2 with media controller [ 8.701966] bcm2835-isp bcm2835-isp: Register capture node 3 with media controller [ 8.702340] bcm2835-isp bcm2835-isp: Loaded V4L2 bcm2835-isp [ 8.715709] bcm2835-codec bcm2835-codec: Device registered as /dev/video11 [ 8.715784] bcm2835-codec bcm2835-codec: Loaded V4L2 encode [ 8.736997] bcm2835-codec bcm2835-codec: Device registered as /dev/video12 [ 8.737052] bcm2835-codec bcm2835-codec: Loaded V4L2 isp [ 9.074179] usbcore: registered new interface driver uas [ 9.129230] cfg80211: Loading compiled-in X.509 certificates for regulatory database [ 9.210955] sd 0:0:0:0: Attached scsi generic sg0 type 0 [ 9.287578] cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' [ 9.413295] brcmfmac: F1 signature read @0x18000000=0x1541a9a6 [ 9.426172] brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43430-sdio for chip BCM43430/1 [ 9.426546] usbcore: registered new interface driver brcmfmac [ 9.451394] brcmfmac mmc1:0001:1: Direct firmware load for brcm/brcmfmac43430-sdio.raspberrypi,3-model-b.txt failed with error -2 [ 9.697523] random: crng init done [ 9.697539] random: 7 urandom warning(s) missed due to ratelimiting [ 9.702076] brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43430-sdio for chip BCM43430/1 [ 9.702249] brcmfmac: brcmf_c_process_clm_blob: no clm_blob available (err=-2), device may have limited channels available [ 9.707162] brcmfmac: brcmf_c_preinit_dcmds: Firmware: BCM43430/1 wl0: Oct 22 2019 01:59:28 version 7.45.98.94 (r723000 CY) FWID 01-3b33decd [ 9.975978] raid6: neonx8 gen() 658 MB/s [ 10.145906] raid6: neonx8 xor() 551 MB/s [ 10.315933] raid6: neonx4 gen() 726 MB/s [ 10.485871] raid6: neonx4 xor() 664 MB/s [ 10.655932] raid6: neonx2 gen() 532 MB/s [ 10.825899] raid6: neonx2 xor() 564 MB/s [ 10.995891] raid6: neonx1 gen() 320 MB/s [ 11.165854] raid6: neonx1 xor() 383 MB/s [ 11.335963] raid6: int32x8 gen() 155 MB/s [ 11.506048] raid6: int32x8 xor() 109 MB/s [ 11.676211] raid6: int32x4 gen() 151 MB/s [ 11.846043] raid6: int32x4 xor() 113 MB/s [ 12.016020] raid6: int32x2 gen() 132 MB/s [ 12.186029] raid6: int32x2 xor() 102 MB/s [ 12.355825] raid6: int32x1 gen() 89 MB/s [ 12.525925] raid6: int32x1 xor() 85 MB/s [ 12.525937] raid6: using algorithm neonx4 gen() 726 MB/s [ 12.525947] raid6: .... xor() 664 MB/s, rmw enabled [ 12.525960] raid6: using neon recovery algorithm [ 12.588875] xor: measuring software checksum speed [ 12.685823] arm4regs : 1018.400 MB/sec [ 12.785811] 8regs : 684.400 MB/sec [ 12.885810] 32regs : 636.400 MB/sec [ 12.985808] neon : 1087.600 MB/sec [ 12.985820] xor: using function: neon (1087.600 MB/sec) [ 12.995663] Adding 2097148k swap on /dev/sda2. Priority:-2 extents:1 across:2097148k FS [ 13.259199] Btrfs loaded, crc32c=crc32c-generic [ 13.262057] BTRFS: device label WD-RED devid 1 transid 1008279 /dev/sda1 [ 13.687927] BTRFS info (device sda1): disk space caching is enabled [ 13.687948] BTRFS info (device sda1): has skinny extents [ 40.432707] systemd-journald[116]: Received request to flush runtime journal from PID 1 [ 41.746019] uart-pl011 3f201000.serial: no DMA platform data [ 41.839232] 8021q: 802.1Q VLAN Support v1.8 [ 43.586544] brcmfmac: brcmf_cfg80211_set_power_mgmt: power save enabled [ 44.338442] smsc95xx 1-1.1:1.0 eth0: hardware isn't capable of remote wakeup [ 45.494387] IPv6: ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready [ 45.800787] smsc95xx 1-1.1:1.0 eth0: link up, 100Mbps, full-duplex, lpa 0xCDE1 [ 46.097430] Bluetooth: Core ver 2.22 [ 46.097543] NET: Registered protocol family 31 [ 46.097553] Bluetooth: HCI device and connection manager initialized [ 46.097586] Bluetooth: HCI socket layer initialized [ 46.097604] Bluetooth: L2CAP socket layer initialized [ 46.097635] Bluetooth: SCO socket layer initialized [ 46.111882] Bluetooth: HCI UART driver ver 2.3 [ 46.111906] Bluetooth: HCI UART protocol H4 registered [ 46.112055] Bluetooth: HCI UART protocol Three-wire (H5) registered [ 46.112373] Bluetooth: HCI UART protocol Broadcom registered [ 46.615484] Bluetooth: BNEP (Ethernet Emulation) ver 1.3 [ 46.615512] Bluetooth: BNEP filters: protocol multicast [ 46.615552] Bluetooth: BNEP socket layer initialized [ 46.962929] ICMPv6: process dhcpcd' is using deprecated sysctl (syscall) net.ipv6.neigh.wlan0.retrans_time - use net.ipv6.neigh.wlan0.retrans_time_ms instead [ 53.054846] NFSD: Using UMH upcall client tracking operations. [ 53.054870] NFSD: starting 90-second grace period (net f00004c1) [ 53.085964] tun: Universal TUN/TAP device driver, 1.6 **Logs** If applicable, add the relevant output fromdmesg` or similar.

vasyugan commented 4 years ago

I have now downgraded the kernel to the package of 20200717, and so far it seems like the kernel panic doesn't occur there. Which would match the chronology, when this problem started occuring

vasyugan commented 4 years ago

hm, what really seems to be dragging the raspi kernel down is pbzip2. When it receives plain btrfs subvolumes, it doesn't crash, but when it receives pbzip2 compressed subvolumens which it has to decompress, it crashes.

alfiyansys commented 1 year ago

Any updates for this ? Had similar panic log, but randomly.

https://askubuntu.com/questions/1490312/random-kernel-panic-on-ubuntu-22-04-3-raspberry-pi-2b

pelwell commented 1 year ago

I don't think there's any useful information in that photo - it just shows the other cores not stopping, not the initial cause of the problem.