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.05k stars 4.96k forks source link

Running libcamera-hello leads to Division by zero in kernel #4714

Open oniongarlic opened 2 years ago

oniongarlic commented 2 years ago

Describe the bug Running libcamera-hello on a Model B with Camera V1 leads to Division by zero in kernel oops.

To reproduce Run libcamera-hello

Expected behaviour No Division by zero in kernel

Actual behaviour [ 95.199368] Division by zero in kernel.

System Copy and paste the results of the raspinfo command in to this section. Alternatively, copy and paste a pastebin link, or add answers to the following questions:

Logs

[   95.199368] Division by zero in kernel.
[   95.199425] CPU: 0 PID: 494 Comm: libcamera-hello Tainted: G         C        5.10.63+ #1459
[   95.199442] Hardware name: BCM2835
[   95.199455] Backtrace: 
[   95.199542] [<c08746c0>] (dump_backtrace) from [<c0874a40>] (show_stack+0x20/0x24)
[   95.199571]  r7:c20b8020 r6:bf3bc280 r5:c20b9178 r4:c0a70ae0
[   95.199606] [<c0874a20>] (show_stack) from [<c0877cb8>] (dump_stack+0x28/0x30)
[   95.199636] [<c0877c90>] (dump_stack) from [<c0874840>] (__div0+0x20/0x28)
[   95.199657]  r5:c20b9178 r4:c28004f8
[   95.199692] [<c0874820>] (__div0) from [<c04f5afc>] (Ldiv0+0x8/0x10)
[   95.199768] [<bf3b7000>] (setup_mmal_port_format [bcm2835_isp]) from [<bf3b82d4>] (bcm2835_isp_node_s_fmt+0x148/0x32c [bcm2835_isp])
[   95.199790]  r5:c2521400 r4:c20b9178
[   95.200347] [<bf3b818c>] (bcm2835_isp_node_s_fmt [bcm2835_isp]) from [<bf1d93e8>] (v4l_s_fmt+0x42c/0x43c [videodev])
[   95.200384]  r8:c27d4b40 r7:bf3ba894 r6:c20b9188 r5:c26b7e40 r4:c2521400
[   95.201016] [<bf1d8fbc>] (v4l_s_fmt [videodev]) from [<bf1d9dd8>] (__video_do_ioctl+0x234/0x464 [videodev])
[   95.201048]  r9:bf3ba894 r8:c20b94d0 r7:00000003 r6:bf1d8fbc r5:c0cc5605 r4:c20b9188
[   95.201635] [<bf1d9ba4>] (__video_do_ioctl [videodev]) from [<bf1da80c>] (video_usercopy+0x118/0x558 [videodev])
[   95.201668]  r10:c2521400 r9:c0bb3028 r8:c2521400 r7:00000000 r6:00000000 r5:c0cc5605
[   95.201684]  r4:c0cc5605
[   95.202266] [<bf1da6f4>] (video_usercopy [videodev]) from [<bf1dac68>] (video_ioctl2+0x1c/0x24 [videodev])
[   95.202415]  r10:00000013 r9:b54c83cc r8:c0bb3028 r7:00000000 r6:c26b7e40 r5:c26b7e41
[   95.202437]  r4:bf1dac4c
[   95.203050] [<bf1dac4c>] (video_ioctl2 [videodev]) from [<bf1d2148>] (v4l2_ioctl+0x4c/0x64 [videodev])
[   95.203636] [<bf1d20fc>] (v4l2_ioctl [videodev]) from [<c020b678>] (sys_ioctl+0x1ac/0x88c)
[   95.203670]  r5:c26b7e41 r4:c0cc5605
[   95.203704] [<c020b4cc>] (sys_ioctl) from [<c0008240>] (ret_fast_syscall+0x0/0x28)
[   95.203722] Exception stack(0xc26dbfa8 to 0xc26dbff0)
[   95.203751] bfa0:                   b54c8690 b4b15068 00000013 c0cc5605 b54c83cc 00000000
[   95.203777] bfc0: b54c8690 b4b15068 b4b15068 00000036 b4b13e10 b54c8690 012425b8 b54c8658
[   95.203795] bfe0: b6f4aff4 b54c83b4 b6eeebf4 b6b9a1ac
[   95.203822]  r10:00000000 r9:c26da000 r8:c00083e4 r7:00000036 r6:b4b15068 r5:b4b15068
[   95.203838]  r4:b54c8690
[   95.349114] Division by zero in kernel.
[   95.349170] CPU: 0 PID: 494 Comm: libcamera-hello Tainted: G         C        5.10.63+ #1459
[   95.349188] Hardware name: BCM2835
[   95.349203] Backtrace: 
[   95.349290] [<c08746c0>] (dump_backtrace) from [<c0874a40>] (show_stack+0x20/0x24)
[   95.349319]  r7:bf3bc280 r6:c0bb3028 r5:c20b9178 r4:c0a70ae0
[   95.349355] [<c0874a20>] (show_stack) from [<c0877cb8>] (dump_stack+0x28/0x30)
[   95.349387] [<c0877c90>] (dump_stack) from [<c0874840>] (__div0+0x20/0x28)
[   95.349408]  r5:c20b9178 r4:c28004f8
[   95.349446] [<c0874820>] (__div0) from [<c04f5afc>] (Ldiv0+0x8/0x10)
[   95.349521] [<bf3b7000>] (setup_mmal_port_format [bcm2835_isp]) from [<bf3b7bb8>] (bcm2835_isp_node_queue_setup+0x90/0x1ec [bcm2835_isp])
[   95.349543]  r5:c20b8020 r4:c20b9178
[   95.349699] [<bf3b7b28>] (bcm2835_isp_node_queue_setup [bcm2835_isp]) from [<bf3027f8>] (vb2_core_reqbufs+0x12c/0x49c [videobuf2_common])
[   95.349736]  r10:c0bb3028 r9:bf3ba894 r8:bf3b7b28 r7:c26dbe3c r6:c0bb3028 r5:c20b9528
[   95.349753]  r4:c20b94e8
[   95.349909] [<bf3026cc>] (vb2_core_reqbufs [videobuf2_common]) from [<bf32bf84>] (vb2_ioctl_reqbufs+0x80/0xa8 [videobuf2_v4l2])
[   95.349942]  r10:c0bb3028 r9:bf3ba894 r8:c20b94dc r7:c26b7e40 r6:c20b9188 r5:00000000
[   95.349958]  r4:c26dbe3c
[   95.350538] [<bf32bf04>] (vb2_ioctl_reqbufs [videobuf2_v4l2]) from [<bf1d960c>] (v4l_reqbufs+0x50/0x54 [videodev])
[   95.350568]  r7:bf3ba894 r6:c27d4b40 r5:c26b7e40 r4:c26dbe3c
[   95.351197] [<bf1d95bc>] (v4l_reqbufs [videodev]) from [<bf1d9dd8>] (__video_do_ioctl+0x234/0x464 [videodev])
[   95.351230]  r7:00000003 r6:bf1d95bc r5:c0145608 r4:c20b9188
[   95.351813] [<bf1d9ba4>] (__video_do_ioctl [videodev]) from [<bf1da80c>] (video_usercopy+0x118/0x558 [videodev])
[   95.351846]  r10:00000000 r9:c0bb3028 r8:c26dbe3c r7:00000000 r6:00000000 r5:c0145608
[   95.351865]  r4:c0145608
[   95.352446] [<bf1da6f4>] (video_usercopy [videodev]) from [<bf1dac68>] (video_ioctl2+0x1c/0x24 [videodev])
[   95.352480]  r10:00000013 r9:b54c83e4 r8:c0bb3028 r7:00000000 r6:c26b7e40 r5:c26b7e41
[   95.352496]  r4:bf1dac4c
[   95.353077] [<bf1dac4c>] (video_ioctl2 [videodev]) from [<bf1d2148>] (v4l2_ioctl+0x4c/0x64 [videodev])
[   95.353651] [<bf1d20fc>] (v4l2_ioctl [videodev]) from [<c020b678>] (sys_ioctl+0x1ac/0x88c)
[   95.353685]  r5:c26b7e41 r4:c0145608
[   95.353720] [<c020b4cc>] (sys_ioctl) from [<c0008240>] (ret_fast_syscall+0x0/0x28)
[   95.353741] Exception stack(0xc26dbfa8 to 0xc26dbff0)
[   95.353766] bfa0:                   b4b14020 b4b15068 00000013 c0145608 b54c83e4 0000000d
[   95.353790] bfc0: b4b14020 b4b15068 00000004 00000036 00000001 00000000 b4b13abc 01242d28
[   95.353810] bfe0: b6f4aff4 b54c83cc b6eeebf4 b6b9a1ac
[   95.353837]  r10:00000000 r9:c26da000 r8:c00083e4 r7:00000036 r6:00000004 r5:b4b15068
[   95.353856]  r4:b4b14020
[   95.375120] Division by zero in kernel.
[   95.375179] CPU: 0 PID: 494 Comm: libcamera-hello Tainted: G         C        5.10.63+ #1459
[   95.375196] Hardware name: BCM2835
[   95.375213] Backtrace: 
[   95.375299] [<c08746c0>] (dump_backtrace) from [<c0874a40>] (show_stack+0x20/0x24)
[   95.375328]  r7:bf3bc280 r6:c0bb3028 r5:c20b9178 r4:c0a70ae0
[   95.375364] [<c0874a20>] (show_stack) from [<c0877cb8>] (dump_stack+0x28/0x30)
[   95.375394] [<c0877c90>] (dump_stack) from [<c0874840>] (__div0+0x20/0x28)
[   95.375414]  r5:c20b9178 r4:c28004f8
[   95.375449] [<c0874820>] (__div0) from [<c04f5afc>] (Ldiv0+0x8/0x10)
[   95.375526] [<bf3b7000>] (setup_mmal_port_format [bcm2835_isp]) from [<bf3b7bb8>] (bcm2835_isp_node_queue_setup+0x90/0x1ec [bcm2835_isp])
[   95.375550]  r5:c20b8020 r4:c20b9178
[   95.375706] [<bf3b7b28>] (bcm2835_isp_node_queue_setup [bcm2835_isp]) from [<bf3027f8>] (vb2_core_reqbufs+0x12c/0x49c [videobuf2_common])
[   95.375742]  r10:c0bb3028 r9:bf3ba894 r8:bf3b7b28 r7:c26dbe3c r6:c0bb3028 r5:c20b9528
[   95.375759]  r4:c20b94e8
[   95.375916] [<bf3026cc>] (vb2_core_reqbufs [videobuf2_common]) from [<bf32bf84>] (vb2_ioctl_reqbufs+0x80/0xa8 [videobuf2_v4l2])
[   95.375946]  r10:c0bb3028 r9:bf3ba894 r8:c20b94dc r7:c26b7e40 r6:c20b9188 r5:00000000
[   95.375962]  r4:c26dbe3c
[   95.376540] [<bf32bf04>] (vb2_ioctl_reqbufs [videobuf2_v4l2]) from [<bf1d960c>] (v4l_reqbufs+0x50/0x54 [videodev])
[   95.376574]  r7:bf3ba894 r6:c27d4b40 r5:c26b7e40 r4:c26dbe3c
[   95.377202] [<bf1d95bc>] (v4l_reqbufs [videodev]) from [<bf1d9dd8>] (__video_do_ioctl+0x234/0x464 [videodev])
[   95.377233]  r7:00000003 r6:bf1d95bc r5:c0145608 r4:c20b9188
[   95.377863] [<bf1d9ba4>] (__video_do_ioctl [videodev]) from [<bf1da80c>] (video_usercopy+0x118/0x558 [videodev])
[   95.377897]  r10:00000000 r9:c0bb3028 r8:c26dbe3c r7:00000000 r6:00000000 r5:c0145608
[   95.377914]  r4:c0145608
[   95.378540] [<bf1da6f4>] (video_usercopy [videodev]) from [<bf1dac68>] (video_ioctl2+0x1c/0x24 [videodev])
[   95.378661]  r10:00000013 r9:b54c8434 r8:c0bb3028 r7:00000000 r6:c26b7e40 r5:c26b7e41
[   95.378682]  r4:bf1dac4c
[   95.379334] [<bf1dac4c>] (video_ioctl2 [videodev]) from [<bf1d2148>] (v4l2_ioctl+0x4c/0x64 [videodev])
[   95.379754] [<bf1d20fc>] (v4l2_ioctl [videodev]) from [<c020b678>] (sys_ioctl+0x1ac/0x88c)
[   95.379784]  r5:c26b7e41 r4:c0145608
[   95.379817] [<c020b4cc>] (sys_ioctl) from [<c0008240>] (ret_fast_syscall+0x0/0x28)
[   95.379836] Exception stack(0xc26dbfa8 to 0xc26dbff0)
[   95.379862] bfa0:                   b4b15068 b4b15068 00000013 c0145608 b54c8434 0000000d
[   95.379887] bfc0: b4b15068 b4b15068 00000004 00000036 00000004 00000000 b4b13abc 01242d28
[   95.379907] bfe0: b6f4aff4 b54c841c b6eeebf4 b6b9a1ac
[   95.379932]  r10:00000000 r9:c26da000 r8:c00083e4 r7:00000036 r6:00000004 r5:b4b15068
[   95.379949]  r4:b4b15068
[   95.502558] vc_sm_cma_import_dmabuf_internal: Expecting an uncached alias for dma_addr 0x8d300000
[   95.503918] vc_sm_cma_import_dmabuf_internal: Expecting an uncached alias for dma_addr 0x8d500000
[   95.504358] vc_sm_cma_import_dmabuf_internal: Expecting an uncached alias for dma_addr 0x8d700000
[   95.504728] vc_sm_cma_import_dmabuf_internal: Expecting an uncached alias for dma_addr 0x8d900000
[   95.508301] vc_sm_cma_import_dmabuf_internal: Expecting an uncached alias for dma_addr 0x8be80000
[   95.508767] vc_sm_cma_import_dmabuf_internal: Expecting an uncached alias for dma_addr 0x8db00000
[   95.509135] vc_sm_cma_import_dmabuf_internal: Expecting an uncached alias for dma_addr 0x8db80000
[   95.509482] vc_sm_cma_import_dmabuf_internal: Expecting an uncached alias for dma_addr 0x8dc00000
[   95.512863] vc_sm_cma_import_dmabuf_internal: Expecting an uncached alias for dma_addr 0x8bd0c000
[   95.514885] vc_sm_cma_import_dmabuf_internal: Expecting an uncached alias for dma_addr 0x8bd18000
[   95.515695] vc_sm_cma_import_dmabuf_internal: Expecting an uncached alias for dma_addr 0x8bd1c000
[   95.516099] vc_sm_cma_import_dmabuf_internal: Expecting an uncached alias for dma_addr 0x8bdc4000
[   95.545419] vc_sm_cma_import_dmabuf_internal: Expecting an uncached alias for dma_addr 0x8bd10000
[   95.549335] vc_sm_cma_import_dmabuf_internal: Expecting an uncached alias for dma_addr 0x8cb00000
[   95.583287] vc_sm_cma_import_dmabuf_internal: Expecting an uncached alias for dma_addr 0x8bd10000
[   95.585430] vc_sm_cma_import_dmabuf_internal: Expecting an uncached alias for dma_addr 0x8cd00000
[   95.611319] vc_sm_cma_import_dmabuf_internal: Expecting an uncached alias for dma_addr 0x8bd10000
[   95.616365] vc_sm_cma_import_dmabuf_internal: Expecting an uncached alias for dma_addr 0x8cf00000
[   95.651980] vc_sm_cma_import_dmabuf_internal: Expecting an uncached alias for dma_addr 0x8bd10000
[   95.657659] vc_sm_cma_import_dmabuf_internal: Expecting an uncached alias for dma_addr 0x8d100000
[   95.669924] vc_sm_cma_import_dmabuf_internal: Expecting an uncached alias for dma_addr 0x8c300000
[   95.670979] vc_sm_cma_import_dmabuf_internal: Expecting an uncached alias for dma_addr 0x8c500000
[   95.671841] vc_sm_cma_import_dmabuf_internal: Expecting an uncached alias for dma_addr 0x8c700000
[   95.677903] vc_sm_cma_import_dmabuf_internal: Expecting an uncached alias for dma_addr 0x8c900000
[   95.705864] vc_sm_cma_import_dmabuf_internal: Expecting an uncached alias for dma_addr 0x8bd10000
[   95.742315] vc_sm_cma_import_dmabuf_internal: Expecting an uncached alias for dma_addr 0x8bd10000
[   95.782113] vc_sm_cma_import_dmabuf_internal: Expecting an uncached alias for dma_addr 0x8bd10000
[   95.821145] vc_sm_cma_import_dmabuf_internal: Expecting an uncached alias for dma_addr 0x8bd10000
[   95.883581] vc_sm_cma_import_dmabuf_internal: Expecting an uncached alias for dma_addr 0x8bd10000
[   95.930017] vc_sm_cma_import_dmabuf_internal: Expecting an uncached alias for dma_addr 0x8bd10000
[   95.981672] vc_sm_cma_import_dmabuf_internal: Expecting an uncached alias for dma_addr 0x8bd10000
[   96.033632] vc_sm_cma_import_dmabuf_internal: Expecting an uncached alias for dma_addr 0x8bd10000
[   96.067788] vc_sm_cma_import_dmabuf_internal: Expecting an uncached alias for dma_addr 0x8bd10000
[   96.107873] vc_sm_cma_import_dmabuf_internal: Expecting an uncached alias for dma_addr 0x8bd10000
[   96.143137] vc_sm_cma_import_dmabuf_internal: Expecting an uncached alias for dma_addr 0x8bd10000
[   96.178712] vc_sm_cma_import_dmabuf_internal: Expecting an uncached alias for dma_addr 0x8bd10000
[   96.212013] vc_sm_cma_import_dmabuf_internal: Expecting an uncached alias for dma_addr 0x8bd10000
[   96.247264] vc_sm_cma_import_dmabuf_internal: Expecting an uncached alias for dma_addr 0x8bd10000
[   96.292490] vc_sm_cma_import_dmabuf_internal: Expecting an uncached alias for dma_addr 0x8bd10000
[   96.339998] vc_sm_cma_import_dmabuf_internal: Expecting an uncached alias for dma_addr 0x8bd10000
[   96.389237] vc_sm_cma_import_dmabuf_internal: Expecting an uncached alias for dma_addr 0x8bd10000

Additional context 1080p forced in config.txt, otherwise defaults

framebuffer_width=1920
framebuffer_height=1080
hdmi_group=1
hdmi_mode=16
hdmi_drive=2
popcornmix commented 2 years ago

@naushir looks like q_data->fmt->depth must be zero to trigger a division by zero exception.

naushir commented 2 years ago

@davidplowman have you seen this before? Not something I've encountered.

I can't really see how q_data->fmt->depth would be zero in this case either.

naushir commented 2 years ago

@oniongarlic is this easily reproducible?

Could you add the following to the end of /boot/cmdline.txt:

bcm2835_isp.debug=5

and provide a kernel log after the failure please?

groman commented 2 years ago

I'm observing something similar on Pi Zero W when trying to use an OV5647 module:

pi@pi-zero-webcam:~ $ dmesg
[    0.000000] Booting Linux on physical CPU 0x0
[    0.000000] Linux version 5.10.63+ (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 Wed Oct 6 16:40:27 BST 2021
[    0.000000] CPU: ARMv6-compatible processor [410fb767] revision 7 (ARMv7), cr=00c5387d
[    0.000000] CPU: PIPT / VIPT nonaliasing data cache, VIPT nonaliasing instruction cache
[    0.000000] OF: fdt: Machine model: Raspberry Pi Zero W Rev 1.1
[    0.000000] random: fast init done
[    0.000000] Memory policy: Data cache writeback
[    0.000000] OF: reserved mem: failed to allocate memory for node 'linux,cma'
[    0.000000] cma: Reserved 8 MiB at 0x0e800000
[    0.000000] Zone ranges:
[    0.000000]   Normal   [mem 0x0000000000000000-0x000000000fffffff]
[    0.000000] Movable zone start for each node
[    0.000000] Early memory node ranges
[    0.000000]   node   0: [mem 0x0000000000000000-0x000000000fffffff]
[    0.000000] Initmem setup node 0 [mem 0x0000000000000000-0x000000000fffffff]
[    0.000000] On node 0 totalpages: 65536
[    0.000000]   Normal zone: 576 pages used for memmap
[    0.000000]   Normal zone: 0 pages reserved
[    0.000000]   Normal zone: 65536 pages, LIFO batch:15
[    0.000000] pcpu-alloc: s0 r0 d32768 u32768 alloc=1*32768
[    0.000000] pcpu-alloc: [0] 0
[    0.000000] Built 1 zonelists, mobility grouping on.  Total pages: 64960
[    0.000000] Kernel command line: coherent_pool=1M 8250.nr_uarts=1 snd_bcm2835.enable_compat_alsa=0 snd_bcm2835.enable_hdmi=1 video=Composite-1:720x480@60i smsc95xx.macaddr=B8:27:EB:20:CE:A9 vc_mem.mem_base=0x1ec00000 vc_mem.mem_size=0x20000000  console=ttyS0,115200 console=tty1 root=PARTUUID=6858b1ed-02 rootfstype=ext4 fsck.repair=yes rootwait modules-load=dwc2,usb_f_rndis,usb_f_acm,usb_f_uvc bcm2835_isp.debug=5
[    0.000000] Dentry cache hash table entries: 32768 (order: 5, 131072 bytes, linear)
[    0.000000] Inode-cache hash table entries: 16384 (order: 4, 65536 bytes, linear)
[    0.000000] mem auto-init: stack:off, heap alloc:off, heap free:off
[    0.000000] Memory: 222392K/262144K available (8683K kernel code, 1324K rwdata, 2824K rodata, 420K init, 837K bss, 31560K reserved, 8192K cma-reserved)
[    0.000000] SLUB: HWalign=32, Order=0-3, MinObjects=0, CPUs=1, Nodes=1
[    0.000000] ftrace: allocating 30717 entries in 60 pages
[    0.000000] ftrace: allocated 60 pages with 4 groups
[    0.000000] NR_IRQS: 16, nr_irqs: 16, preallocated irqs: 16
[    0.000000] random: get_random_bytes called from start_kernel+0x324/0x560 with crng_init=1
[    0.000024] sched_clock: 32 bits at 1000kHz, resolution 1000ns, wraps every 2147483647500ns
[    0.000115] clocksource: timer: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275 ns
[    0.000201] bcm2835: system timer (irq = 27)
[    0.000888] Console: colour dummy device 80x30
[    0.001521] printk: console [tty1] enabled
[    0.001620] Calibrating delay loop... 697.95 BogoMIPS (lpj=3489792)
[    0.060369] pid_max: default: 32768 minimum: 301
[    0.060671] LSM: Security Framework initializing
[    0.060995] Mount-cache hash table entries: 1024 (order: 0, 4096 bytes, linear)
[    0.061063] Mountpoint-cache hash table entries: 1024 (order: 0, 4096 bytes, linear)
[    0.063101] cgroup: Disabling memory control group subsystem
[    0.063542] CPU: Testing write buffer coherency: ok
[    0.065398] Setting up static identity map for 0x8200 - 0x8238
[    0.066634] devtmpfs: initialized
[    0.079554] VFP support v0.3: implementor 41 architecture 1 part 20 variant b rev 5
[    0.079941] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns
[    0.080022] futex hash table entries: 256 (order: -1, 3072 bytes, linear)
[    0.081448] pinctrl core: initialized pinctrl subsystem
[    0.083302] NET: Registered protocol family 16
[    0.088020] DMA: preallocated 1024 KiB pool for atomic coherent allocations
[    0.094030] audit: initializing netlink subsys (disabled)
[    0.095540] thermal_sys: Registered thermal governor 'step_wise'
[    0.096557] hw-breakpoint: found 6 breakpoint and 1 watchpoint registers.
[    0.096647] hw-breakpoint: maximum watchpoint size is 4 bytes.
[    0.096935] Serial: AMBA PL011 UART driver
[    0.100725] audit: type=2000 audit(0.090:1): state=initialized audit_enabled=0 res=1
[    0.130977] bcm2835-mbox 2000b880.mailbox: mailbox enabled
[    0.150836] raspberrypi-firmware soc:firmware: Attached to firmware from 2021-10-29T10:49:08, variant start
[    0.160849] raspberrypi-firmware soc:firmware: Firmware hash is b8a114e5a9877e91ca8f26d1a5ce904b2ad3cf13
[    0.210094] Kprobes globally optimized
[    0.219224] bcm2835-dma 20007000.dma: DMA legacy API manager, dmachans=0x1
[    0.222455] SCSI subsystem initialized
[    0.222917] usbcore: registered new interface driver usbfs
[    0.223057] usbcore: registered new interface driver hub
[    0.223190] usbcore: registered new device driver usb
[    0.225949] clocksource: Switched to clocksource timer
[    2.220522] VFS: Disk quotas dquot_6.6.0
[    2.220699] VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes)
[    2.220990] FS-Cache: Loaded
[    2.221385] CacheFiles: Loaded
[    2.222513] simple-framebuffer 1eaa9000.framebuffer: framebuffer at 0x1eaa9000, 0x151800 bytes, mapped to 0x(ptrval)
[    2.222598] simple-framebuffer 1eaa9000.framebuffer: format=a8r8g8b8, mode=720x480x32, linelength=2880
[    2.229655] Console: switching to colour frame buffer device 90x30
[    2.238795] simple-framebuffer 1eaa9000.framebuffer: fb0: simplefb registered!
[    2.260334] NET: Registered protocol family 2
[    2.264377] IP idents hash table entries: 4096 (order: 3, 32768 bytes, linear)
[    2.269431] tcp_listen_portaddr_hash hash table entries: 512 (order: 0, 4096 bytes, linear)
[    2.277114] TCP established hash table entries: 2048 (order: 1, 8192 bytes, linear)
[    2.281035] TCP bind hash table entries: 2048 (order: 1, 8192 bytes, linear)
[    2.284874] TCP: Hash tables configured (established 2048 bind 2048)
[    2.288854] UDP hash table entries: 256 (order: 0, 4096 bytes, linear)
[    2.292731] UDP-Lite hash table entries: 256 (order: 0, 4096 bytes, linear)
[    2.297056] NET: Registered protocol family 1
[    2.302071] RPC: Registered named UNIX socket transport module.
[    2.305928] RPC: Registered udp transport module.
[    2.309846] RPC: Registered tcp transport module.
[    2.313622] RPC: Registered tcp NFSv4.1 backchannel transport module.
[    2.317999] Trying to unpack rootfs image as initramfs...
[    4.085507] Freeing initrd memory: 14772K
[    4.090966] hw perfevents: no irqs for PMU, sampling events not supported
[    4.094951] hw perfevents: enabled with armv6_1176 PMU driver, 3 counters available
[    4.103412] Initialise system trusted keyrings
[    4.108056] workingset: timestamp_bits=14 max_order=16 bucket_order=2
[    4.123674] zbud: loaded
[    4.130583] FS-Cache: Netfs 'nfs' registered for caching
[    4.135814] NFS: Registering the id_resolver key type
[    4.139771] Key type id_resolver registered
[    4.143440] Key type id_legacy registered
[    4.147275] nfs4filelayout_init: NFSv4 File Layout Driver Registering...
[    4.150913] nfs4flexfilelayout_init: NFSv4 Flexfile Layout Driver Registering...
[    4.156478] Key type asymmetric registered
[    4.160053] Asymmetric key parser 'x509' registered
[    4.163548] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 249)
[    4.167094] io scheduler mq-deadline registered
[    4.170496] io scheduler kyber registered
[    4.182836] Serial: 8250/16550 driver, 1 ports, IRQ sharing enabled
[    4.187691] bcm2835-aux-uart 20215040.serial: there is not valid maps for state default
[    4.194140] bcm2835-rng 20104000.rng: hwrng registered
[    4.198525] vc-mem: phys_addr:0x00000000 mem_base=0x1ec00000 mem_size:0x20000000(512 MiB)
[    4.207332] gpiomem-bcm2835 20200000.gpiomem: Initialised: Registers at 0x20200000
[    4.232052] brd: module loaded
[    4.250731] loop: module loaded
[    4.257095] Loading iSCSI transport class v2.0-870.
[    4.263215] libphy: Fixed MDIO Bus: probed
[    4.267488] usbcore: registered new interface driver smsc95xx
[    4.271168] dwc_otg: version 3.00a 10-AUG-2012 (platform bus)
[    4.275095] dwc_otg: FIQ enabled
[    4.275116] dwc_otg: NAK holdoff enabled
[    4.275129] dwc_otg: FIQ split-transaction FSM enabled
[    4.275152] Module dwc_common_port init
[    4.275609] usbcore: registered new interface driver usb-storage
[    4.279817] mousedev: PS/2 mouse device common for all mice
[    4.285338] bcm2835-wdt bcm2835-wdt: Broadcom BCM2835 watchdog timer
[    4.292331] sdhci: Secure Digital Host Controller Interface driver
[    4.296169] sdhci: Copyright(c) Pierre Ossman
[    4.300799] mmc-bcm2835 20300000.mmcnr: could not get clk, deferring probe
[    4.305611] sdhost-bcm2835 20202000.mmc: could not get clk, deferring probe
[    4.309928] sdhci-pltfm: SDHCI platform and OF driver helper
[    4.314559] ledtrig-cpu: registered to indicate activity on CPUs
[    4.319082] hid: raw HID events driver (C) Jiri Kosina
[    4.323038] usbcore: registered new interface driver usbhid
[    4.326776] usbhid: USB HID core driver
[    4.337714] Initializing XFRM netlink socket
[    4.342031] NET: Registered protocol family 17
[    4.345837] Key type dns_resolver registered
[    4.350344] registered taskstats version 1
[    4.353770] Loading compiled-in X.509 certificates
[    4.373193] uart-pl011 20201000.serial: cts_event_workaround enabled
[    4.376802] 20201000.serial: ttyAMA0 at MMIO 0x20201000 (irq = 81, base_baud = 0) is a PL011 rev2
[    4.386669] bcm2835-aux-uart 20215040.serial: there is not valid maps for state default
[    4.391273] printk: console [ttyS0] disabled
[    4.395074] 20215040.serial: ttyS0 at MMIO 0x20215040 (irq = 53, base_baud = 50000000) is a 16550
[    5.248751] printk: console [ttyS0] enabled
[    5.258308] bcm2835-power bcm2835-power: Broadcom BCM2835 power domains driver
[    5.271991] mmc-bcm2835 20300000.mmcnr: mmc_debug:0 mmc_debug2:0
[    5.282031] mmc-bcm2835 20300000.mmcnr: DMA channel allocated
[    5.320469] sdhost: log_buf @ (ptrval) (8e942000)
[    5.366855] mmc1: queuing unknown CIS tuple 0x80 (2 bytes)
[    5.376317] mmc0: sdhost-bcm2835 loaded - DMA enabled (>1)
[    5.389340] of_cfs_init
[    5.395586] of_cfs_init: OK
[    5.423378] mmc1: queuing unknown CIS tuple 0x80 (3 bytes)
[    5.440268] Freeing unused kernel memory: 420K
[    5.448362] Kernel memory protection not selected by kernel config.
[    5.458560] Run /init as init process
[    5.465825]   with arguments:
[    5.465840]     /init
[    5.465852]   with environment:
[    5.465864]     HOME=/
[    5.465874]     TERM=linux
[    5.465888]     modules-load=dwc2,usb_f_rndis,usb_f_acm,usb_f_uvc
[    5.467273] mmc1: queuing unknown CIS tuple 0x80 (3 bytes)
[    5.491265] mmc1: queuing unknown CIS tuple 0x80 (7 bytes)
[    5.537269] mmc0: host does not support reading read-only switch, assuming write-enable
[    5.563706] mmc0: new high speed SDHC card at address aaaa
[    5.585237] mmcblk0: mmc0:aaaa SP32G 29.7 GiB
[    5.612647]  mmcblk0: p1 p2
[    5.671273] mmc1: new high speed SDIO card at address 0001
[    7.758148] i2c i2c-11: Added multiplexed i2c bus 0
[    7.837221] i2c i2c-11: Added multiplexed i2c bus 10
[    8.335047] dwc2 20980000.usb: supply vusb_d not found, using dummy regulator
[    8.386495] dwc2 20980000.usb: supply vusb_a not found, using dummy regulator
[    8.665554] checking generic (1eaa9000 151800) vs hw (0 ffffffff)
[    8.665585] fb0: switching to vc4drmfb from simple
[    8.676296] dwc2 20980000.usb: EPs: 8, dedicated fifos, 4080 entries in SPRAM
[    8.717187] dwc2 20980000.usb: DWC OTG Controller
[    8.725160] dwc2 20980000.usb: new USB bus registered, assigned bus number 1
[    8.746099] Console: switching to colour dummy device 80x30
[    8.791867] vc4-drm soc:gpu: bound 20400000.hvs (ops vc4_hvs_ops [vc4])
[    8.822145] dwc2 20980000.usb: irq 33, io mem 0x20980000
[    8.832520] Registered IR keymap rc-cec
[    8.845370] rc rc0: vc4 as /devices/platform/soc/20902000.hdmi/rc/rc0
[    8.858344] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 5.10
[    8.866887] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    8.874257] usb usb1: Product: DWC OTG Controller
[    8.879098] usb usb1: Manufacturer: Linux 5.10.63+ dwc2_hsotg
[    8.884963] usb usb1: SerialNumber: 20980000.usb
[    8.906536] input: vc4 as /devices/platform/soc/20902000.hdmi/rc/rc0/input0
[    8.938135] vc4_hdmi 20902000.hdmi: Could not register sound card: -517
[    9.004195] hub 1-0:1.0: USB hub found
[    9.012243] hub 1-0:1.0: 1 port detected
[    9.026420] vc4-drm soc:gpu: bound 20400000.hvs (ops vc4_hvs_ops [vc4])
[    9.046828] Registered IR keymap rc-cec
[    9.054767] rc rc0: vc4 as /devices/platform/soc/20902000.hdmi/rc/rc0
[    9.069154] input: vc4 as /devices/platform/soc/20902000.hdmi/rc/rc0/input1
[    9.087744] vc4_hdmi 20902000.hdmi: Could not register sound card: -517
[    9.120786] vc4-drm soc:gpu: bound 20400000.hvs (ops vc4_hvs_ops [vc4])
[    9.142346] Registered IR keymap rc-cec
[    9.146818] rc rc0: vc4 as /devices/platform/soc/20902000.hdmi/rc/rc0
[    9.162371] input: vc4 as /devices/platform/soc/20902000.hdmi/rc/rc0/input2
[    9.181590] vc4_hdmi 20902000.hdmi: Could not register sound card: -517
[    9.393731] mc: Linux media interface: v0.10
[    9.431842] videodev: Linux video capture interface: v2.00
[   10.708126] EXT4-fs (mmcblk0p2): mounted filesystem with ordered data mode. Opts: (null)
[   11.028630] using random self ethernet address
[   11.033214] using random host ethernet address
[   11.253955] configfs-gadget gadget: uvc: uvc_function_bind()
[   11.261646] usb0: HOST MAC c6:fc:91:88:1c:43
[   11.266877] usb0: MAC 9a:6a:5f:ba:e2:0f
[   11.271344] dwc2 20980000.usb: bound driver configfs-gadget
[   14.133806] systemd[1]: System time before build time, advancing clock.
[   14.312389] NET: Registered protocol family 10
[   14.319314] Segment Routing with IPv6
[   14.445525] systemd[1]: systemd 247.3-6+rpi1 running in system mode. (+PAM +AUDIT +SELINUX +IMA +APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +ZSTD +SECCOMP +BLKID +ELFUTILS +KMOD +IDN2 -IDN +PCRE2 default-hierarchy=unified)
[   14.470201] systemd[1]: Detected architecture arm.
[   14.490783] systemd[1]: Set hostname to <pi-zero-webcam>.
[   16.739890] systemd[1]: /lib/systemd/system/plymouth-start.service:16: Unit configured to use KillMode=none. This is unsafe, as it disables systemd's process lifecycle management for the service. Please update your service to use a safer KillMode=, such as 'mixed' or 'control-group'. Support for KillMode=none is deprecated and will eventually be removed.
[   17.391316] systemd[1]: Queued start job for default target Multi-User System.
[   17.402941] random: systemd: uninitialized urandom read (16 bytes read)
[   17.415292] systemd[1]: Created slice system-getty.slice.
[   17.423275] random: systemd: uninitialized urandom read (16 bytes read)
[   17.433197] systemd[1]: Created slice system-modprobe.slice.
[   17.441302] random: systemd: uninitialized urandom read (16 bytes read)
[   17.451442] systemd[1]: Created slice system-serial\x2dgetty.slice.
[   17.462710] systemd[1]: Created slice system-systemd\x2dfsck.slice.
[   17.472803] systemd[1]: Created slice User and Session Slice.
[   17.482038] systemd[1]: Started Forward Password Requests to Wall Directory Watch.
[   17.493789] systemd[1]: Set up automount Arbitrary Executable File Formats File System Automount Point.
[   17.507581] systemd[1]: Reached target Slices.
[   17.514315] systemd[1]: Reached target Swap.
[   17.523212] systemd[1]: Listening on Syslog Socket.
[   17.532348] systemd[1]: Listening on fsck to fsckd communication Socket.
[   17.541934] systemd[1]: Listening on initctl Compatibility Named Pipe.
[   17.553009] systemd[1]: Listening on Journal Audit Socket.
[   17.562915] systemd[1]: Listening on Journal Socket (/dev/log).
[   17.573356] systemd[1]: Listening on Journal Socket.
[   17.592477] systemd[1]: Listening on udev Control Socket.
[   17.601943] systemd[1]: Listening on udev Kernel Socket.
[   17.611539] systemd[1]: Condition check resulted in Huge Pages File System being skipped.
[   17.629193] systemd[1]: Mounting POSIX Message Queue File System...
[   17.671155] systemd[1]: Mounting RPC Pipe File System...
[   17.708622] systemd[1]: Mounting Kernel Debug File System...
[   17.759984] systemd[1]: Mounting Kernel Trace File System...
[   17.782457] systemd[1]: Condition check resulted in Kernel Module supporting RPCSEC_GSS being skipped.
[   17.819243] systemd[1]: Starting Restore / save the current clock...
[   17.857600] systemd[1]: Starting Set the console keyboard layout...
[   17.930783] systemd[1]: Starting Create list of static device nodes for the current kernel...
[   17.997730] systemd[1]: Starting Load Kernel Module configfs...
[   18.067743] systemd[1]: Starting Load Kernel Module drm...
[   18.137650] systemd[1]: Starting Load Kernel Module fuse...
[   18.184808] systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped.
[   18.230468] systemd[1]: Condition check resulted in File System Check on Root Device being skipped.
[   18.299057] fuse: init (API version 7.32)
[   18.309111] systemd[1]: Starting Journal Service...
[   18.377829] systemd[1]: Starting Load Kernel Modules...
[   18.449057] systemd[1]: Starting Remount Root and Kernel File Systems...
[   18.557733] systemd[1]: Starting Coldplug All udev Devices...
[   18.746996] systemd[1]: Mounted POSIX Message Queue File System.
[   18.802730] systemd[1]: Mounted RPC Pipe File System.
[   18.852270] systemd[1]: Mounted Kernel Debug File System.
[   18.913995] systemd[1]: Mounted Kernel Trace File System.
[   18.967364] systemd[1]: Finished Restore / save the current clock.
[   18.984676] i2c /dev entries driver
[   19.066255] systemd[1]: Finished Create list of static device nodes for the current kernel.
[   19.121591] systemd[1]: modprobe@configfs.service: Succeeded.
[   19.178687] systemd[1]: Finished Load Kernel Module configfs.
[   19.220613] systemd[1]: modprobe@drm.service: Succeeded.
[   19.273961] systemd[1]: Finished Load Kernel Module drm.
[   19.279816] EXT4-fs (mmcblk0p2): re-mounted. Opts: (null)
[   19.350190] systemd[1]: modprobe@fuse.service: Succeeded.
[   19.386367] systemd[1]: Finished Load Kernel Module fuse.
[   19.431579] systemd[1]: Finished Load Kernel Modules.
[   19.486381] systemd[1]: Finished Remount Root and Kernel File Systems.
[   19.596754] systemd[1]: Mounting FUSE Control File System...
[   19.638357] systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped.
[   19.707105] systemd[1]: Condition check resulted in Platform Persistent Storage Archival being skipped.
[   19.777719] systemd[1]: Starting Load/Save Random Seed...
[   19.847728] systemd[1]: Starting Apply Kernel Variables...
[   19.937700] systemd[1]: Starting Create System Users...
[   20.099998] systemd[1]: Mounted FUSE Control File System.
[   20.446627] systemd[1]: Finished Apply Kernel Variables.
[   20.586318] systemd[1]: Finished Create System Users.
[   20.605362] systemd[1]: Starting Create Static Device Nodes in /dev...
[   20.840460] systemd[1]: Started Journal Service.
[   21.337980] systemd-journald[234]: Received client request to flush runtime journal.
[   21.353242] systemd-journald[234]: File /var/log/journal/6fac1eaec7b9452fbc662cf677ddb680/system.journal corrupted or uncleanly shut down, renaming and replacing.
[   25.901983] vc_sm_cma: module is from the staging directory, the quality is unknown, you have been warned.
[   25.904782] bcm2835_vc_sm_cma_probe: Videocore shared memory driver
[   25.904817] [vc_sm_connected_init]: start
[   25.953856] vc_sm_cma: module is from the staging directory, the quality is unknown, you have been warned.
[   25.958048] vc_sm_cma: module is from the staging directory, the quality is unknown, you have been warned.
[   25.976742] [vc_sm_connected_init]: installed successfully
[   25.988066] vc4-drm soc:gpu: bound 20400000.hvs (ops vc4_hvs_ops [vc4])
[   25.990894] bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned.
[   25.994599] bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned.
[   26.002345] Registered IR keymap rc-cec
[   26.002725] rc rc0: vc4 as /devices/platform/soc/20902000.hdmi/rc/rc0
[   26.003096] input: vc4 as /devices/platform/soc/20902000.hdmi/rc/rc0/input3
[   26.004724] vc4_hdmi 20902000.hdmi: Could not register sound card: -517
[   26.033434] bcm2835_v4l2: module is from the staging directory, the quality is unknown, you have been warned.
[   26.077914] bcm2835_isp: module is from the staging directory, the quality is unknown, you have been warned.
[   26.137288] bcm2835_codec: module is from the staging directory, the quality is unknown, you have been warned.
[   26.138521] bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video13
[   26.153824] bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video14
[   26.166231] snd_bcm2835: module is from the staging directory, the quality is unknown, you have been warned.
[   26.169540] bcm2835_audio bcm2835_audio: there is not valid maps for state default
[   26.181850] bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video15
[   26.186808] vc4-drm soc:gpu: bound 20400000.hvs (ops vc4_hvs_ops [vc4])
[   26.192563] bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video16
[   26.192590] bcm2835-isp bcm2835-isp: Registering with media controller
[   26.192607] bcm2835-isp bcm2835-isp: Register entity for nodes
[   26.192644] bcm2835-isp bcm2835-isp: Register output node 0 with media controller
[   26.192679] bcm2835-isp bcm2835-isp: Register capture node 1 with media controller
[   26.192715] bcm2835-isp bcm2835-isp: Register capture node 2 with media controller
[   26.192771] bcm2835-isp bcm2835-isp: Register capture node 3 with media controller
[   26.193637] bcm2835-isp bcm2835-isp: Loaded V4L2 bcm2835-isp
[   26.196298] Registered IR keymap rc-cec
[   26.196650] rc rc0: vc4 as /devices/platform/soc/20902000.hdmi/rc/rc0
[   26.197045] input: vc4 as /devices/platform/soc/20902000.hdmi/rc/rc0/input4
[   26.198389] vc4_hdmi 20902000.hdmi: Could not register sound card: -517
[   26.227850] vc4-drm soc:gpu: bound 20400000.hvs (ops vc4_hvs_ops [vc4])
[   26.233567] Registered IR keymap rc-cec
[   26.233927] rc rc0: vc4 as /devices/platform/soc/20902000.hdmi/rc/rc0
[   26.234378] input: vc4 as /devices/platform/soc/20902000.hdmi/rc/rc0/input5
[   26.235824] vc4_hdmi 20902000.hdmi: Could not register sound card: -517
[   26.282840] bcm2835-codec bcm2835-codec: Device registered as /dev/video10
[   26.282921] bcm2835-codec bcm2835-codec: Loaded V4L2 decode
[   26.291785] bcm2835-codec bcm2835-codec: Device registered as /dev/video11
[   26.291863] bcm2835-codec bcm2835-codec: Loaded V4L2 encode
[   26.316229] bcm2835-codec bcm2835-codec: Device registered as /dev/video12
[   26.316305] bcm2835-codec bcm2835-codec: Loaded V4L2 isp
[   26.338850] bcm2835-codec bcm2835-codec: Device registered as /dev/video18
[   26.338934] bcm2835-codec bcm2835-codec: Loaded V4L2 image_fx
[   26.356623] vc4-drm soc:gpu: bound 20400000.hvs (ops vc4_hvs_ops [vc4])
[   26.370125] Registered IR keymap rc-cec
[   26.370477] rc rc0: vc4 as /devices/platform/soc/20902000.hdmi/rc/rc0
[   26.370879] input: vc4 as /devices/platform/soc/20902000.hdmi/rc/rc0/input6
[   26.372239] vc4_hdmi 20902000.hdmi: Could not register sound card: -517
[   27.286246] vc4-drm soc:gpu: bound 20400000.hvs (ops vc4_hvs_ops [vc4])
[   27.291899] Registered IR keymap rc-cec
[   27.292251] rc rc0: vc4 as /devices/platform/soc/20902000.hdmi/rc/rc0
[   27.292654] input: vc4 as /devices/platform/soc/20902000.hdmi/rc/rc0/input7
[   27.294021] vc4_hdmi 20902000.hdmi: Could not register sound card: -517
[   27.552718] vc4-drm soc:gpu: bound 20400000.hvs (ops vc4_hvs_ops [vc4])
[   27.563738] Registered IR keymap rc-cec
[   27.564304] rc rc0: vc4 as /devices/platform/soc/20902000.hdmi/rc/rc0
[   27.564694] input: vc4 as /devices/platform/soc/20902000.hdmi/rc/rc0/input8
[   27.575198] vc4-drm soc:gpu: bound 20902000.hdmi (ops vc4_hdmi_ops [vc4])
[   27.575753] vc4-drm soc:gpu: bound 20806000.vec (ops vc4_vec_ops [vc4])
[   27.576545] vc4-drm soc:gpu: bound 20004000.txp (ops vc4_txp_ops [vc4])
[   27.577131] vc4-drm soc:gpu: bound 20206000.pixelvalve (ops vc4_crtc_ops [vc4])
[   27.577690] vc4-drm soc:gpu: bound 20207000.pixelvalve (ops vc4_crtc_ops [vc4])
[   27.578280] vc4-drm soc:gpu: bound 20807000.pixelvalve (ops vc4_crtc_ops [vc4])
[   27.578728] vc4-drm soc:gpu: bound 20c00000.v3d (ops vc4_v3d_ops [vc4])
[   27.609439] [drm] Initialized vc4 0.0.0 20140616 for soc:gpu on minor 0
[   27.644275] Console: switching to colour frame buffer device 90x30
[   27.665169] vc4-drm soc:gpu: [drm] fb0: vc4drmfb frame buffer device
[   29.202963] cfg80211: Loading compiled-in X.509 certificates for regulatory database
[   29.616890] cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'
[   29.651648] cfg80211: loaded regulatory.db is malformed or signature is missing/invalid
[   30.444851] brcmfmac: F1 signature read @0x18000000=0x1541a9a6
[   30.766302] brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43430-sdio for chip BCM43430/1
[   30.767423] usbcore: registered new interface driver brcmfmac
[   31.150779] brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43430-sdio for chip BCM43430/1
[   31.151027] brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43430-sdio for chip BCM43430/1
[   31.151176] brcmfmac: brcmf_c_process_clm_blob: no clm_blob available (err=-2), device may have limited channels available
[   31.159220] 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
[   35.511263] 8021q: 802.1Q VLAN Support v1.8
[   36.792055] Adding 102396k swap on /var/swap.  Priority:-2 extents:1 across:102396k SSFS
[   36.825013] uart-pl011 20201000.serial: no DMA platform data
[   38.159648] random: crng init done
[   38.159714] random: 7 urandom warning(s) missed due to ratelimiting
[   41.461698] Bluetooth: Core ver 2.22
[   41.461867] NET: Registered protocol family 31
[   41.461883] Bluetooth: HCI device and connection manager initialized
[   41.461918] Bluetooth: HCI socket layer initialized
[   41.461938] Bluetooth: L2CAP socket layer initialized
[   41.461983] Bluetooth: SCO socket layer initialized
[   41.495934] Bluetooth: HCI UART driver ver 2.3
[   41.496070] Bluetooth: HCI UART protocol H4 registered
[   41.508917] Bluetooth: HCI UART protocol Three-wire (H5) registered
[   41.509269] Bluetooth: HCI UART protocol Broadcom registered
[   45.472319] Bluetooth: BNEP (Ethernet Emulation) ver 1.3
[   45.472343] Bluetooth: BNEP filters: protocol multicast
[   45.472376] Bluetooth: BNEP socket layer initialized
[   45.689728] NET: Registered protocol family 38
[   46.815080] brcmfmac: brcmf_cfg80211_set_power_mgmt: power save enabled
[   49.447942] IPv6: ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready
[   52.314696] ICMPv6: process `dhcpcd' is using deprecated sysctl (syscall) net.ipv6.neigh.wlan0.retrans_time - use net.ipv6.neigh.wlan0.retrans_time_ms instead
[   79.507876] hdmi-audio-codec hdmi-audio-codec.8.auto: ASoC: error at snd_soc_dai_startup on i2s-hifi: -19
[   79.512279] hdmi-audio-codec hdmi-audio-codec.8.auto: ASoC: error at snd_soc_dai_startup on i2s-hifi: -19
[   79.524863] hdmi-audio-codec hdmi-audio-codec.8.auto: ASoC: error at snd_soc_dai_startup on i2s-hifi: -19
[   79.593528] hdmi-audio-codec hdmi-audio-codec.8.auto: ASoC: error at snd_soc_dai_startup on i2s-hifi: -19
[   79.595802] hdmi-audio-codec hdmi-audio-codec.8.auto: ASoC: error at snd_soc_dai_startup on i2s-hifi: -19
[   79.605687] hdmi-audio-codec hdmi-audio-codec.8.auto: ASoC: error at snd_soc_dai_startup on i2s-hifi: -19
[   80.459379] Bluetooth: RFCOMM TTY layer initialized
[   80.459428] Bluetooth: RFCOMM socket layer initialized
[   80.459488] Bluetooth: RFCOMM ver 1.11
[  126.877529] bcm2835-isp bcm2835-isp: bcm2835_isp_node_s_fmt: Set format for node output[0]
[  126.877563] bcm2835-isp bcm2835-isp: populate_qdata_fmt: Setting pix format for type 2, wxh: 1296x972, fmt: 41414270, size 1586304
[  126.877578] bcm2835-isp bcm2835-isp: populate_qdata_fmt: Calculated bpl as 1632, size 1586304
[  126.877986] bcm2835-isp bcm2835-isp: populate_qdata_fmt: Set format for type 2, wxh: 1296x972, fmt: 41414270, size 1586304
[  126.878209] bcm2835-isp bcm2835-isp: bcm2835_isp_node_s_fmt: Set format for node capture[0]
[  126.878232] bcm2835-isp bcm2835-isp: populate_qdata_fmt: Setting pix format for type 1, wxh: 800x600, fmt: 33424752, size 1440000
[  126.878247] bcm2835-isp bcm2835-isp: populate_qdata_fmt: Calculated bpl as 2400, size 1440000
[  126.878566] bcm2835-isp bcm2835-isp: populate_qdata_fmt: Set format for type 1, wxh: 800x600, fmt: 33424752, size 1440000
[  126.878771] bcm2835-isp bcm2835-isp: bcm2835_isp_node_s_fmt: Set format for node capture[1]
[  126.878795] bcm2835-isp bcm2835-isp: populate_qdata_fmt: Setting pix format for type 1, wxh: 400x300, fmt: 56595559, size 249600
[  126.878809] bcm2835-isp bcm2835-isp: populate_qdata_fmt: Calculated bpl as 832, size 249600
[  126.880699] bcm2835-isp bcm2835-isp: populate_qdata_fmt: Set format for type 1, wxh: 400x300, fmt: 56595559, size 249600
[  126.880799] bcm2835-isp bcm2835-isp: bcm2835_isp_node_s_fmt: Set format for node stats[2]
[  126.880817] bcm2835-isp bcm2835-isp: populate_qdata_fmt: Setting meta format for fmt: 41545342, size 10824
[  126.880830] bcm2835-isp bcm2835-isp: populate_qdata_fmt: Calculated bpl as 0, size 10824
[  126.880839] Division by zero in kernel.
[  126.880857] CPU: 0 PID: 867 Comm: qcam Tainted: G         C        5.10.63+ #1459
[  126.880866] Hardware name: BCM2835
[  126.880876] Backtrace:
[  126.880933] [<c08746c0>] (dump_backtrace) from [<c0874a40>] (show_stack+0x20/0x24)
[  126.880952]  r7:c1b8a020 r6:bf363280 r5:c1b8b178 r4:c0a70ae0
[  126.880974] [<c0874a20>] (show_stack) from [<c0877cb8>] (dump_stack+0x28/0x30)
[  126.880993] [<c0877c90>] (dump_stack) from [<c0874840>] (__div0+0x20/0x28)
[  126.881006]  r5:c1b8b178 r4:c1e404f8
[  126.881032] [<c0874820>] (__div0) from [<c04f5afc>] (Ldiv0+0x8/0x10)
[  126.881083] [<bf35e000>] (setup_mmal_port_format [bcm2835_isp]) from [<bf35f2d4>] (bcm2835_isp_node_s_fmt+0x148/0x32c [bcm2835_isp])
[  126.881098]  r5:c1f40a00 r4:c1b8b178
[  126.881488] [<bf35f18c>] (bcm2835_isp_node_s_fmt [bcm2835_isp]) from [<bf31f3e8>] (v4l_s_fmt+0x42c/0x43c [videodev])
[  126.881510]  r8:c1903660 r7:bf361894 r6:c1b8b188 r5:c36c5540 r4:c1f40a00
[  126.881905] [<bf31efbc>] (v4l_s_fmt [videodev]) from [<bf31fdd8>] (__video_do_ioctl+0x234/0x464 [videodev])
[  126.881927]  r9:bf361894 r8:c1b8b4d0 r7:00000003 r6:bf31efbc r5:c0cc5605 r4:c1b8b188
[  126.882321] [<bf31fba4>] (__video_do_ioctl [videodev]) from [<bf32080c>] (video_usercopy+0x118/0x558 [videodev])
[  126.882342]  r10:c1f40a00 r9:c0bb3028 r8:c1f40a00 r7:00000000 r6:00000000 r5:c0cc5605
[  126.882353]  r4:c0cc5605
[  126.882745] [<bf3206f4>] (video_usercopy [videodev]) from [<bf320c68>] (video_ioctl2+0x1c/0x24 [videodev])
[  126.882768]  r10:00000015 r9:b10bc38c r8:c0bb3028 r7:00000000 r6:c36c5540 r5:c36c5541
[  126.882778]  r4:bf320c4c
[  126.883170] [<bf320c4c>] (video_ioctl2 [videodev]) from [<bf318148>] (v4l2_ioctl+0x4c/0x64 [videodev])
[  126.883385] [<bf3180fc>] (v4l2_ioctl [videodev]) from [<c020b678>] (sys_ioctl+0x1ac/0x88c)
[  126.883401]  r5:c36c5541 r4:c0cc5605
[  126.883421] [<c020b4cc>] (sys_ioctl) from [<c0008240>] (ret_fast_syscall+0x0/0x28)
[  126.883433] Exception stack(0xc34bffa8 to 0xc34bfff0)
[  126.883449] ffa0:                   b10bc650 b0711f18 00000015 c0cc5605 b10bc38c 00000000
[  126.883466] ffc0: b10bc650 b0711f18 b0711f18 00000036 b0714f70 b10bc650 00d55f90 b10bc618
[  126.883480] ffe0: b6ef6ff4 b10bc374 b6e9abf4 b5aa71ac
[  126.883498]  r10:00000000 r9:c34be000 r8:c00083e4 r7:00000036 r6:b0711f18 r5:b0711f18
[  126.883508]  r4:b10bc650
[  126.883855] bcm2835-isp bcm2835-isp: populate_qdata_fmt: Set format for type 13, wxh: 0x0, fmt: 41545342, size 10824
[  126.908476] bcm2835-isp bcm2835-isp: setup_mmal_port: setup capture[0]
[  126.908990] bcm2835-isp bcm2835-isp: bcm2835_isp_node_queue_setup: Image size 1440000, nbuffers 4 for node capture[0]
[  126.912443] bcm2835-isp bcm2835-isp: bcm2835_isp_buf_init: vb 17f4df5c
[  126.919406] bcm2835-isp bcm2835-isp: bcm2835_isp_buf_init: vb 133317b6
[  126.919475] cma: cma_alloc: alloc failed, req-size: 352 pages, ret: -12
[  126.919503] bcm2835-isp bcm2835-isp: dma_alloc_coherent of size 1441792 failed
[  126.919521] bcm2835-isp bcm2835-isp: setup_mmal_port: setup capture[0]
[  126.927477] bcm2835-isp bcm2835-isp: bcm2835_isp_node_queue_setup: Image size 1440000, nbuffers 2 for node capture[0]
pi@pi-zero-webcam:~ $

That's the kernel log with bcm2835_isp.debug=5

naushir commented 2 years ago

I think https://github.com/raspberrypi/linux/pull/4732 might fix this div by 0. Fairly obvious bug when you look at it, so I am somewhat surprised we have not seen it happen more.

renekliment commented 2 years ago

I can confirm this has been fixed for me. (Raspberry Pi Zero W) Thank you!

popcornmix commented 2 years ago

@oniongarlic can you close issue if this is fixed for you?