Skip to content
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

Stale Streams detected but not fixed #94

Open
JoelBecker1998 opened this issue Aug 13, 2023 · 22 comments
Open

Stale Streams detected but not fixed #94

JoelBecker1998 opened this issue Aug 13, 2023 · 22 comments

Comments

@JoelBecker1998
Copy link

Sometimes, after a while streams just die. WebRTC shows nothing, mjpg streams throws a server error.
This happens if the camera is left running for a while, sometimes an hour, sometimes a day.

Anyway, I think the software detects a stale stream and tries to restart it but it still doesn't work. Restarting the entire service works just fine. Any idea what could cause this?

Here is the log:

ug 13 15:41:10 armcam0 bash[601]: device/buffer_list.c: CAMERA:capture: Streaming started... Was 0 of 4 enqueud
Aug 13 15:41:10 armcam0 bash[601]: device/buffer_list.c: SNAPSHOT:output:mplane: Streaming started... Was 0 of 4 enqueud
Aug 13 15:41:10 armcam0 bash[601]: device/buffer_list.c: VIDEO:output:mplane: Streaming started... Was 0 of 4 enqueud
Aug 13 15:41:10 armcam0 bash[601]: device/buffer_list.c: SNAPSHOT:capture:mplane: Streaming started... Was 0 of 4 enqueud
Aug 13 15:41:10 armcam0 bash[601]: device/buffer_list.c: VIDEO:capture:mplane: Streaming started... Was 0 of 4 enqueud
Aug 13 19:12:11 armcam0 bash[601]: util/http/http.c: HTTP8081/1: Client connected 172.16.10.4 (fd=7).
Aug 13 19:12:11 armcam0 bash[601]: util/http/http.c: HTTP8081/1: Request 'GET' '/webrtc' ''
Aug 13 19:12:11 armcam0 bash[601]: util/http/http.c: HTTP8081/1: Client disconnected 172.16.10.4.
Aug 13 19:12:11 armcam0 bash[601]: util/http/http.c: HTTP8081/2: Client connected 172.16.10.4 (fd=8).
Aug 13 19:12:11 armcam0 bash[601]: util/http/http.c: HTTP8081/2: Request 'POST' '/webrtc' ''
Aug 13 19:12:11 armcam0 bash[601]: output/webrtc/webrtc.cc: rtc-mborhwnxgtxllrbydrka: Stream requested.
Aug 13 19:12:15 armcam0 bash[601]: util/http/http.c: HTTP8081/2: Client disconnected 172.16.10.4.
Aug 13 19:12:15 armcam0 bash[601]: util/http/http.c: HTTP8081/0: Client connected 172.16.10.4 (fd=9).
Aug 13 19:12:15 armcam0 bash[601]: util/http/http.c: HTTP8081/0: Request 'POST' '/webrtc' ''
Aug 13 19:12:15 armcam0 bash[601]: output/webrtc/webrtc.cc: rtc-mborhwnxgtxllrbydrka: Answer received.
Aug 13 19:12:15 armcam0 bash[601]: util/http/http.c: HTTP8081/0: Client disconnected 172.16.10.4.
Aug 13 19:12:15 armcam0 bash[601]: device/links.c: CAMERA:capture: Stale detected. Restarting streaming...
Aug 13 19:12:15 armcam0 bash[601]: device/buffer_list.c: CAMERA:capture: Streaming stopped... Was 0 of 4 enqueud
Aug 13 19:12:15 armcam0 bash[601]: device/buffer_list.c: CAMERA:capture: Streaming started... Was 0 of 4 enqueud
@bibitocarlos
Copy link

Samedi here.

@foosel
Copy link
Contributor

foosel commented Oct 2, 2023

We are seeing a lot of those as well on the camera-streamer enabled OctoPi image. This is currently a blocker for general roll-out.

@ayufan
Copy link
Owner

ayufan commented Oct 2, 2023 via email

@foosel
Copy link
Contributor

foosel commented Oct 2, 2023

Hm... I'm not sure how this is going to help here though, for mjpg? The problem we are seeing is that the service simply stops working correctly when a log like the above is encountered. It's still running (so an automatic restart by the unit doesn't trigger), but all the streams (not just webrtc) just error one way or another. If the service would fully crash, the unit restart should trigger, though obviously a graceful handling of this situation would be preferable over all. A refresh in the browser or something like that doesn't work, it just errors out.

@JoelBecker1998
Copy link
Author

Exactly. I temporarily fixed it by just adding an "exit(0)" where the log message "detected stale streams" is logged and just auto restart the service. However, i believe the issue also occures at different places, as this does not fix all issues.
With MJPG or H264 you at least get a "server error message" when you reload the screen. With webRTC there is no error, no image nothing.

@ayufan
Copy link
Owner

ayufan commented Oct 2, 2023

Let me take a look at this again. Previously when I checked this:

  • Stale detected. Restarting streaming is an outcome of jpeg decoder that received a broken jpeg frame
  • The broken JPEG frame can be received from USB bus, it appears that this occasionally happens on RPI when the USB bus is underrun?

@JoelBecker1998
Copy link
Author

Bit confused about that.
Idk where or why I would receive a broken JPEG frame over USB.
My setup is just a CSI camera and i use WebRTC.
If I remember correctly, Stale detected occurs if the timestamp of the frame is too old no?

@foosel
Copy link
Contributor

foosel commented Oct 2, 2023

Too add some more data points to this, we've also gotten reports about this from CSI cameras (primarily RPi Cam 3, but also others). So I don't think it's just USB stuff.

In any case, it would be important that this gets handled gracefully some way or another, either by exiting the service altogether since it is unusable when it happens right now (in which case it can be restarted automatically by systemd), or handling it internally by restarting whatever needs to get restarted in the camera pipeline. As things are at the moment, a manual restart is required to fix things, and for a headless system that's an big UX issue sadly.

@ayufan
Copy link
Owner

ayufan commented Oct 2, 2023

@foosel So, restarting service fixes the issue? Interesting. Do you have maybe dmesg from the time it happens?

@foosel
Copy link
Contributor

foosel commented Oct 2, 2023

Currently not, but I can see that I get one. It's a bit tricky since I haven't found yet a way to trigger it when I want, I just saw it happen myself, and I've gotten (journalctl) logs from users for whom it happened. I'll keep an eye out and will also see if I can trigger it here.

@ayufan
Copy link
Owner

ayufan commented Oct 2, 2023

You can also send my way journalctl as it should contain dmesg output as well.

@foosel
Copy link
Contributor

foosel commented Oct 2, 2023

One example would be https://paste.octoprint.org/OYXd3AsGW5, as also posted by the user in question (with additional info on the hardware) here on this repo as #90.

edit Also attached:
OYXd3AsGW5.txt

edit2 FWIW, I've just flashed a Pi3 with attached Cam3 with a build and am not accessing the stream, as described in #90, to see if I can get a reproduction and associated dmesg output for you.

@foosel
Copy link
Contributor

foosel commented Oct 2, 2023

Successful repro. Left the streamer alone for an hour as suggested in #90, tried to access the mjpg stream, "stale" message in log and just a server error in the browser.

dmesg
[    0.000000] Booting Linux on physical CPU 0x0
[    0.000000] Linux version 6.1.21-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) #1642 SMP Mon Apr  3 17:20:52 BST 2023
[    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] random: crng init done
[    0.000000] Memory policy: Data cache writealloc
[    0.000000] Reserved memory: created CMA memory pool at 0x1ec00000, size 256 MiB
[    0.000000] OF: reserved mem: initialized node linux,cma, compatible id shared-dma-pool
[    0.000000] Zone ranges:
[    0.000000]   DMA      [mem 0x0000000000000000-0x0000000037ffffff]
[    0.000000]   Normal   empty
[    0.000000] Movable zone start for each node
[    0.000000] Early memory node ranges
[    0.000000]   node   0: [mem 0x0000000000000000-0x0000000037ffffff]
[    0.000000] Initmem setup node 0 [mem 0x0000000000000000-0x0000000037ffffff]
[    0.000000] percpu: Embedded 17 pages/cpu s36884 r8192 d24556 u69632
[    0.000000] pcpu-alloc: s36884 r8192 d24556 u69632 alloc=17*4096
[    0.000000] pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 
[    0.000000] Built 1 zonelists, mobility grouping on.  Total pages: 227360
[    0.000000] Kernel command line: coherent_pool=1M 8250.nr_uarts=0 snd_bcm2835.enable_headphones=0 snd_bcm2835.enable_headphones=1 snd_bcm2835.enable_hdmi=1 snd_bcm2835.enable_hdmi=0 video=Composite-1:720x480@60i vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000  console=ttyS0,115200 console=tty1 root=PARTUUID=69498b49-02 rootfstype=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, linear)
[    0.000000] mem auto-init: stack:off, heap alloc:off, heap free:off
[    0.000000] Memory: 628144K/917504K available (10240K kernel code, 1452K rwdata, 2900K rodata, 1024K init, 613K bss, 27216K reserved, 262144K cma-reserved)
[    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1
[    0.000000] ftrace: allocating 34800 entries in 103 pages
[    0.000000] ftrace: allocated 102 pages with 4 groups
[    0.000000] trace event string verifier disabled
[    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 jiffies.
[    0.000000] NR_IRQS: 16, nr_irqs: 16, preallocated irqs: 16
[    0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention.
[    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.000001] sched_clock: 56 bits at 19MHz, resolution 52ns, wraps every 4398046511078ns
[    0.000018] Switching to timer-based delay loop, resolution 52ns
[    0.000389] Console: colour dummy device 80x30
[    0.001010] printk: console [tty1] enabled
[    0.001074] Calibrating delay loop (skipped), value calculated using timer frequency.. 38.40 BogoMIPS (lpj=192000)
[    0.001120] pid_max: default: 32768 minimum: 301
[    0.001283] LSM: Security Framework initializing
[    0.001507] Mount-cache hash table entries: 2048 (order: 1, 8192 bytes, linear)
[    0.001551] Mountpoint-cache hash table entries: 2048 (order: 1, 8192 bytes, linear)
[    0.002735] cgroup: Disabling memory control group subsystem
[    0.002988] CPU: Testing write buffer coherency: ok
[    0.003426] CPU0: thread -1, cpu 0, socket 0, mpidr 80000000
[    0.004288] cblist_init_generic: Setting adjustable number of callback queues.
[    0.004327] cblist_init_generic: Setting shift to 2 and lim to 1.
[    0.004455] cblist_init_generic: Setting shift to 2 and lim to 1.
[    0.004615] Setting up static identity map for 0x100000 - 0x10003c
[    0.004772] rcu: Hierarchical SRCU implementation.
[    0.004796] rcu: 	Max phase no-delay instances is 1000.
[    0.005526] smp: Bringing up secondary CPUs ...
[    0.006456] CPU1: thread -1, cpu 1, socket 0, mpidr 80000001
[    0.007415] CPU2: thread -1, cpu 2, socket 0, mpidr 80000002
[    0.008291] CPU3: thread -1, cpu 3, socket 0, mpidr 80000003
[    0.008392] smp: Brought up 1 node, 4 CPUs
[    0.008473] SMP: Total of 4 processors activated (153.60 BogoMIPS).
[    0.008500] CPU: All CPU(s) started in HYP mode.
[    0.008520] CPU: Virtualization extensions available.
[    0.009278] devtmpfs: initialized
[    0.024447] VFP support v0.3: implementor 41 architecture 3 part 40 variant 3 rev 4
[    0.024679] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns
[    0.024731] futex hash table entries: 1024 (order: 4, 65536 bytes, linear)
[    0.034916] pinctrl core: initialized pinctrl subsystem
[    0.036161] NET: Registered PF_NETLINK/PF_ROUTE protocol family
[    0.039299] DMA: preallocated 1024 KiB pool for atomic coherent allocations
[    0.044384] audit: initializing netlink subsys (disabled)
[    0.044648] audit: type=2000 audit(0.040:1): state=initialized audit_enabled=0 res=1
[    0.045263] thermal_sys: Registered thermal governor 'step_wise'
[    0.046188] hw-breakpoint: found 5 (+1 reserved) breakpoint and 4 watchpoint registers.
[    0.046242] hw-breakpoint: maximum watchpoint size is 8 bytes.
[    0.046510] Serial: AMBA PL011 UART driver
[    0.052928] bcm2835-mbox 3f00b880.mailbox: mailbox enabled
[    0.057245] platform 3f801000.csi: Fixed dependency cycle(s) with /soc/i2c0mux/i2c@1/imx708@1a
[    0.070069] raspberrypi-firmware soc:firmware: Attached to firmware from 2023-03-17T10:53:39, variant start_x
[    0.080083] raspberrypi-firmware soc:firmware: Firmware hash is 82f3750a65fadae9a38077e3c2e217ad158c8d54
[    0.122937] kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible.
[    0.128451] bcm2835-dma 3f007000.dma: DMA legacy API manager, dmachans=0x1
[    0.130167] SCSI subsystem initialized
[    0.130422] usbcore: registered new interface driver usbfs
[    0.130503] usbcore: registered new interface driver hub
[    0.130572] usbcore: registered new device driver usb
[    0.130814] usb_phy_generic phy: supply vcc not found, using dummy regulator
[    0.131000] usb_phy_generic phy: dummy supplies not allowed for exclusive requests
[    0.131285] pps_core: LinuxPPS API ver. 1 registered
[    0.131311] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>
[    0.131360] PTP clock support registered
[    2.009929] clocksource: Switched to clocksource arch_sys_counter
[    2.107832] VFS: Disk quotas dquot_6.6.0
[    2.107962] VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes)
[    2.108148] FS-Cache: Loaded
[    2.108423] CacheFiles: Loaded
[    2.116859] NET: Registered PF_INET protocol family
[    2.117168] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)
[    2.119648] tcp_listen_portaddr_hash hash table entries: 512 (order: 0, 4096 bytes, linear)
[    2.119707] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)
[    2.119820] TCP established hash table entries: 8192 (order: 3, 32768 bytes, linear)
[    2.120040] TCP bind hash table entries: 8192 (order: 5, 131072 bytes, linear)
[    2.120395] TCP: Hash tables configured (established 8192 bind 8192)
[    2.120583] UDP hash table entries: 512 (order: 2, 16384 bytes, linear)
[    2.120656] UDP-Lite hash table entries: 512 (order: 2, 16384 bytes, linear)
[    2.120909] NET: Registered PF_UNIX/PF_LOCAL protocol family
[    2.121470] RPC: Registered named UNIX socket transport module.
[    2.121498] RPC: Registered udp transport module.
[    2.121520] RPC: Registered tcp transport module.
[    2.121541] RPC: Registered tcp NFSv4.1 backchannel transport module.
[    2.123484] hw perfevents: enabled with armv7_cortex_a7 PMU driver, 7 counters available
[    2.126633] Initialise system trusted keyrings
[    2.126884] workingset: timestamp_bits=14 max_order=18 bucket_order=4
[    2.136126] zbud: loaded
[    2.138521] NFS: Registering the id_resolver key type
[    2.138583] Key type id_resolver registered
[    2.138606] Key type id_legacy registered
[    2.138736] nfs4filelayout_init: NFSv4 File Layout Driver Registering...
[    2.138765] nfs4flexfilelayout_init: NFSv4 Flexfile Layout Driver Registering...
[    2.140104] Key type asymmetric registered
[    2.140132] Asymmetric key parser 'x509' registered
[    2.140339] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247)
[    2.140374] io scheduler mq-deadline registered
[    2.140398] io scheduler kyber registered
[    2.145420] simple-framebuffer 3eaa9000.framebuffer: framebuffer at 0x3eaa9000, 0x151800 bytes
[    2.145482] simple-framebuffer 3eaa9000.framebuffer: format=a8r8g8b8, mode=720x480x32, linelength=2880
[    2.150266] Console: switching to colour frame buffer device 90x30
[    2.156844] simple-framebuffer 3eaa9000.framebuffer: fb0: simplefb registered!
[    2.164154] bcm2835-rng 3f104000.rng: hwrng registered
[    2.167084] vc-mem: phys_addr:0x00000000 mem_base=0x3ec00000 mem_size:0x40000000(1024 MiB)
[    2.173016] gpiomem-bcm2835 3f200000.gpiomem: Initialised: Registers at 0x3f200000
[    2.186878] brd: module loaded
[    2.197018] loop: module loaded
[    2.200267] Loading iSCSI transport class v2.0-870.
[    2.204254] usbcore: registered new interface driver lan78xx
[    2.206911] usbcore: registered new interface driver smsc95xx
[    2.209513] dwc_otg: version 3.00a 10-AUG-2012 (platform bus)
[    2.940182] Core Release: 2.80a
[    2.942703] Setting default values for core params
[    2.945204] Finished setting default values for core params
[    3.147963] Using Buffer DMA mode
[    3.150480] Periodic Transfer Interrupt Enhancement - disabled
[    3.153000] Multiprocessor Interrupt Enhancement - disabled
[    3.155474] OTG VER PARAM: 0, OTG VER FLAG: 0
[    3.157968] Dedicated Tx FIFOs mode

[    3.161045] WARN::dwc_otg_hcd_init:1074: FIQ DMA bounce buffers: virt = 9ed04000 dma = 0xded04000 len=9024
[    3.168262] FIQ FSM acceleration enabled for :
               Non-periodic Split Transactions
               Periodic Split Transactions
               High-Speed Isochronous Endpoints
               Interrupt/Control Split Transaction hack enabled
[    3.179520] dwc_otg: Microframe scheduler enabled

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

[    3.183723] WARN::hcd_init_fiq:458: FIQ ASM at 807c4360 length 36

[    3.187715] WARN::hcd_init_fiq:497: MPHI regs_base at b8810000
[    3.191802] dwc_otg 3f980000.usb: DWC OTG Controller
[    3.193956] dwc_otg 3f980000.usb: new USB bus registered, assigned bus number 1
[    3.196151] dwc_otg 3f980000.usb: irq 89, io mem 0x00000000
[    3.198317] Init: Port Power? op_state=1
[    3.200438] Init: Power Port (0)
[    3.202707] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.01
[    3.206966] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    3.209225] usb usb1: Product: DWC OTG Controller
[    3.211463] usb usb1: Manufacturer: Linux 6.1.21-v7+ dwc_otg_hcd
[    3.213732] usb usb1: SerialNumber: 3f980000.usb
[    3.216629] hub 1-0:1.0: USB hub found
[    3.218865] hub 1-0:1.0: 1 port detected
[    3.221640] dwc_otg: FIQ enabled
[    3.221650] dwc_otg: NAK holdoff enabled
[    3.221658] dwc_otg: FIQ split-transaction FSM enabled
[    3.221673] Module dwc_common_port init
[    3.221984] usbcore: registered new interface driver usb-storage
[    3.224333] mousedev: PS/2 mouse device common for all mice
[    3.229044] sdhci: Secure Digital Host Controller Interface driver
[    3.231311] sdhci: Copyright(c) Pierre Ossman
[    3.233804] sdhci-pltfm: SDHCI platform and OF driver helper
[    3.237971] ledtrig-cpu: registered to indicate activity on CPUs
[    3.240691] hid: raw HID events driver (C) Jiri Kosina
[    3.243122] usbcore: registered new interface driver usbhid
[    3.245414] usbhid: USB HID core driver
[    3.251798] Initializing XFRM netlink socket
[    3.254115] NET: Registered PF_PACKET protocol family
[    3.256462] Key type dns_resolver registered
[    3.259419] Registering SWP/SWPB emulation handler
[    3.262311] registered taskstats version 1
[    3.264523] Loading compiled-in X.509 certificates
[    3.267488] Key type .fscrypt registered
[    3.269668] Key type fscrypt-provisioning registered
[    3.283137] uart-pl011 3f201000.serial: cts_event_workaround enabled
[    3.285413] 3f201000.serial: ttyAMA0 at MMIO 0x3f201000 (irq = 114, base_baud = 0) is a PL011 rev2
[    3.291674] bcm2835-wdt bcm2835-wdt: Broadcom BCM2835 watchdog timer
[    3.294161] bcm2835-power bcm2835-power: Broadcom BCM2835 power domains driver
[    3.297661] mmc-bcm2835 3f300000.mmcnr: mmc_debug:0 mmc_debug2:0
[    3.299975] mmc-bcm2835 3f300000.mmcnr: DMA channel allocated
[    3.329069] sdhost: log_buf @ 1eb8c32d (ded07000)
[    3.360036] Indeed it is in host mode hprt0 = 00021501
[    3.383839] mmc0: sdhost-bcm2835 loaded - DMA enabled (>1)
[    3.388361] of_cfs_init
[    3.390708] of_cfs_init: OK
[    3.393939] Waiting for root device PARTUUID=69498b49-02...
[    3.420538] mmc0: host does not support reading read-only switch, assuming write-enable
[    3.425936] mmc0: new high speed SDHC card at address 59b4
[    3.429018] mmcblk0: mmc0:59b4 USDU1 7.28 GiB 
[    3.434176]  mmcblk0: p1 p2
[    3.437046] mmcblk0: mmc0:59b4 USDU1 7.28 GiB
[    3.461712] EXT4-fs (mmcblk0p2): mounted filesystem with ordered data mode. Quota mode: none.
[    3.466384] VFS: Mounted root (ext4 filesystem) readonly on device 179:2.
[    3.476515] devtmpfs: mounted
[    3.485194] Freeing unused kernel image (initmem) memory: 1024K
[    3.490457] Run /sbin/init as init process
[    3.492948]   with arguments:
[    3.492956]     /sbin/init
[    3.492964]   with environment:
[    3.492971]     HOME=/
[    3.492978]     TERM=linux
[    3.542437] mmc1: new high speed SDIO card at address 0001
[    3.579981] usb 1-1: new high-speed USB device number 2 using dwc_otg
[    3.582682] Indeed it is in host mode hprt0 = 00001101
[    3.820251] usb 1-1: New USB device found, idVendor=0424, idProduct=9514, bcdDevice= 2.00
[    3.825272] usb 1-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0
[    3.828589] hub 1-1:1.0: USB hub found
[    3.831295] hub 1-1:1.0: 5 ports detected
[    3.990901] systemd[1]: System time before build time, advancing clock.
[    4.150036] usb 1-1.1: new high-speed USB device number 3 using dwc_otg
[    4.233564] NET: Registered PF_INET6 protocol family
[    4.237612] Segment Routing with IPv6
[    4.240289] In-situ OAM (IOAM) with IPv6
[    4.280248] usb 1-1.1: New USB device found, idVendor=0424, idProduct=ec00, bcdDevice= 2.00
[    4.285464] usb 1-1.1: New USB device strings: Mfr=0, Product=0, SerialNumber=0
[    4.300771] smsc95xx v2.0.0
[    4.329859] systemd[1]: systemd 247.3-7+rpi1+deb11u1 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)
[    4.339398] systemd[1]: Detected architecture arm.
[    4.363535] systemd[1]: Set hostname to <octopib>.
[    4.441643] SMSC LAN8700 usb-001:003:01: attached PHY driver (mii_bus:phy_addr=usb-001:003:01, irq=199)
[    4.448213] smsc95xx 1-1.1:1.0 eth0: register 'smsc95xx' at usb-3f980000.usb-1.1, smsc95xx USB 2.0 Ethernet, b8:27:eb:f1:63:95
[    5.542457] systemd[1]: Queued start job for default target Multi-User System.
[    5.603807] systemd[1]: Created slice system-getty.slice.
[    5.611829] systemd[1]: Created slice system-modprobe.slice.
[    5.619302] systemd[1]: Created slice system-systemd\x2dfsck.slice.
[    5.626491] systemd[1]: Created slice User and Session Slice.
[    5.633317] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.
[    5.640140] systemd[1]: Started Forward Password Requests to Wall Directory Watch.
[    5.647913] systemd[1]: Set up automount Arbitrary Executable File Formats File System Automount Point.
[    5.657765] systemd[1]: Reached target Local Encrypted Volumes.
[    5.665037] systemd[1]: Reached target Paths.
[    5.671749] systemd[1]: Reached target Slices.
[    5.678335] systemd[1]: Reached target Swap.
[    5.685781] systemd[1]: Listening on Syslog Socket.
[    5.692526] systemd[1]: Listening on fsck to fsckd communication Socket.
[    5.699004] systemd[1]: Listening on initctl Compatibility Named Pipe.
[    5.706339] systemd[1]: Listening on Journal Audit Socket.
[    5.713047] systemd[1]: Listening on Journal Socket (/dev/log).
[    5.719859] systemd[1]: Listening on Journal Socket.
[    5.727736] systemd[1]: Listening on udev Control Socket.
[    5.734321] systemd[1]: Listening on udev Kernel Socket.
[    5.741197] systemd[1]: Condition check resulted in Huge Pages File System being skipped.
[    5.800362] systemd[1]: Mounting POSIX Message Queue File System...
[    5.811219] systemd[1]: Mounting RPC Pipe File System...
[    5.823192] systemd[1]: Mounting Kernel Debug File System...
[    5.834984] systemd[1]: Mounting Kernel Trace File System...
[    5.842166] systemd[1]: Condition check resulted in Kernel Module supporting RPCSEC_GSS being skipped.
[    5.855754] systemd[1]: Starting Restore / save the current clock...
[    5.867748] systemd[1]: Starting Set the console keyboard layout...
[    5.880316] systemd[1]: Starting Create list of static device nodes for the current kernel...
[    5.896892] systemd[1]: Starting Load Kernel Module configfs...
[    5.910023] systemd[1]: Starting Load Kernel Module drm...
[    5.922933] systemd[1]: Starting Load Kernel Module fuse...
[    5.942311] systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped.
[    5.953819] systemd[1]: Starting File System Check on Root Device...
[    5.973810] systemd[1]: Starting Journal Service...
[    5.993627] systemd[1]: Starting Load Kernel Modules...
[    6.028722] systemd[1]: Starting Coldplug All udev Devices...
[    6.059676] fuse: init (API version 7.37)
[    6.082101] systemd[1]: Mounted POSIX Message Queue File System.
[    6.096330] systemd[1]: Mounted RPC Pipe File System.
[    6.103544] systemd[1]: Mounted Kernel Debug File System.
[    6.111220] systemd[1]: Mounted Kernel Trace File System.
[    6.129809] systemd[1]: Finished Restore / save the current clock.
[    6.138602] systemd[1]: Finished Create list of static device nodes for the current kernel.
[    6.151196] systemd[1]: modprobe@configfs.service: Succeeded.
[    6.155974] systemd[1]: Finished Load Kernel Module configfs.
[    6.165367] systemd[1]: modprobe@drm.service: Succeeded.
[    6.169512] systemd[1]: Finished Load Kernel Module drm.
[    6.180019] systemd[1]: modprobe@fuse.service: Succeeded.
[    6.203615] systemd[1]: Finished Load Kernel Module fuse.
[    6.222313] systemd[1]: Finished Load Kernel Modules.
[    6.300915] systemd[1]: Mounting FUSE Control File System...
[    6.311933] systemd[1]: Mounting Kernel Configuration File System...
[    6.323543] systemd[1]: Started File System Check Daemon to report status.
[    6.338999] systemd[1]: Starting Apply Kernel Variables...
[    6.351049] systemd[1]: Started Journal Service.
[    6.573992] EXT4-fs (mmcblk0p2): re-mounted. Quota mode: none.
[    6.697102] systemd-journald[141]: Received client request to flush runtime journal.
[    6.728066] systemd-journald[141]: File /var/log/journal/8b3c6b2a9c764364a8ad5d800596a99e/system.journal corrupted or uncleanly shut down, renaming and replacing.
[    8.255443] mc: Linux media interface: v0.10
[    8.318067] vc_sm_cma: module is from the staging directory, the quality is unknown, you have been warned.
[    8.351573] bcm2835_vc_sm_cma_probe: Videocore shared memory driver
[    8.351606] [vc_sm_connected_init]: start
[    8.370481] [vc_sm_connected_init]: installed successfully
[    8.402629] videodev: Linux video capture interface: v2.00
[    8.488584] snd_bcm2835: module is from the staging directory, the quality is unknown, you have been warned.
[    8.512831] bcm2835_audio bcm2835_audio: card created with 8 channels
[    8.519084] bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned.
[    8.522154] bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned.
[    8.602097] bcm2835_isp: module is from the staging directory, the quality is unknown, you have been warned.
[    8.641960] bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video13
[    8.642426] bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video14
[    8.655718] bcm2835_v4l2: module is from the staging directory, the quality is unknown, you have been warned.
[    8.662399] bcm2835_codec: module is from the staging directory, the quality is unknown, you have been warned.
[    8.670589] bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video15
[    8.704155] bcm2835-codec bcm2835-codec: Device registered as /dev/video10
[    8.704213] bcm2835-codec bcm2835-codec: Loaded V4L2 decode
[    8.711314] bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video16
[    8.711368] bcm2835-isp bcm2835-isp: Register output node 0 with media controller
[    8.711391] bcm2835-isp bcm2835-isp: Register capture node 1 with media controller
[    8.711408] bcm2835-isp bcm2835-isp: Register capture node 2 with media controller
[    8.711424] bcm2835-isp bcm2835-isp: Register capture node 3 with media controller
[    8.716354] bcm2835-codec bcm2835-codec: Device registered as /dev/video11
[    8.716413] bcm2835-codec bcm2835-codec: Loaded V4L2 encode
[    8.718652] bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video20
[    8.720508] bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video21
[    8.720923] bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video22
[    8.721246] bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video23
[    8.721275] bcm2835-isp bcm2835-isp: Register output node 0 with media controller
[    8.721297] bcm2835-isp bcm2835-isp: Register capture node 1 with media controller
[    8.721314] bcm2835-isp bcm2835-isp: Register capture node 2 with media controller
[    8.721330] bcm2835-isp bcm2835-isp: Register capture node 3 with media controller
[    8.721544] bcm2835-isp bcm2835-isp: Loaded V4L2 bcm2835-isp
[    8.727382] bcm2835-codec bcm2835-codec: Device registered as /dev/video12
[    8.727435] bcm2835-codec bcm2835-codec: Loaded V4L2 isp
[    8.734836] bcm2835-codec bcm2835-codec: Device registered as /dev/video18
[    8.734895] bcm2835-codec bcm2835-codec: Loaded V4L2 image_fx
[    8.746585] bcm2835-codec bcm2835-codec: Device registered as /dev/video31
[    8.746639] bcm2835-codec bcm2835-codec: Loaded V4L2 encode_image
[    8.942278] i2c i2c-11: Added multiplexed i2c bus 0
[    8.943622] i2c i2c-11: Added multiplexed i2c bus 10
[    9.347606] cfg80211: Loading compiled-in X.509 certificates for regulatory database
[    9.509358] cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'
[    9.715264] cfg80211: loaded regulatory.db is malformed or signature is missing/invalid
[    9.796659] Console: switching to colour dummy device 80x30
[    9.800968] vc4-drm soc:gpu: bound 3f400000.hvs (ops vc4_hvs_ops [vc4])
[    9.804462] Registered IR keymap rc-cec
[    9.804701] rc rc0: vc4-hdmi as /devices/platform/soc/3f902000.hdmi/rc/rc0
[    9.804958] input: vc4-hdmi as /devices/platform/soc/3f902000.hdmi/rc/rc0/input0
[    9.809430] vc4-drm soc:gpu: bound 3f902000.hdmi (ops vc4_hdmi_ops [vc4])
[    9.810085] vc4-drm soc:gpu: bound 3f004000.txp (ops vc4_txp_ops [vc4])
[    9.810578] vc4-drm soc:gpu: bound 3f206000.pixelvalve (ops vc4_crtc_ops [vc4])
[    9.811027] vc4-drm soc:gpu: bound 3f207000.pixelvalve (ops vc4_crtc_ops [vc4])
[    9.811469] vc4-drm soc:gpu: bound 3f807000.pixelvalve (ops vc4_crtc_ops [vc4])
[    9.811856] vc4-drm soc:gpu: bound 3fc00000.v3d (ops vc4_v3d_ops [vc4])
[    9.815501] [drm] Initialized vc4 0.0.0 20140616 for soc:gpu on minor 0
[    9.818039] vc4-drm soc:gpu: [drm] Cannot find any crtc or sizes
[    9.979000] brcmfmac: F1 signature read @0x18000000=0x1541a9a6
[    9.992354] brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43430-sdio for chip BCM43430/1
[    9.993192] usbcore: registered new interface driver brcmfmac
[    9.993719] brcmfmac mmc1:0001:1: Direct firmware load for brcm/brcmfmac43430-sdio.raspberrypi,3-model-b.bin failed with error -2
[   10.298108] brcmfmac: brcmf_c_preinit_dcmds: Firmware: BCM43430/1 wl0: Jul 19 2021 03:24:18 version 7.45.98 (TOB) (56df937 CY) FWID 01-8e14b897
[   10.980218] imx708 10-001a: camera module ID 0x0301
[   11.015431] imx708 10-001a: Consider updating driver imx708 to match on endpoints
[   11.260375] uart-pl011 3f201000.serial: no DMA platform data
[   11.765981] Adding 102396k swap on /var/swap.  Priority:-2 extents:1 across:102396k SSFS
[   16.174606] Bluetooth: Core ver 2.22
[   16.174720] NET: Registered PF_BLUETOOTH protocol family
[   16.174726] Bluetooth: HCI device and connection manager initialized
[   16.174745] Bluetooth: HCI socket layer initialized
[   16.174755] Bluetooth: L2CAP socket layer initialized
[   16.174771] Bluetooth: SCO socket layer initialized
[   16.191949] Bluetooth: HCI UART driver ver 2.3
[   16.191976] Bluetooth: HCI UART protocol H4 registered
[   16.192055] Bluetooth: HCI UART protocol Three-wire (H5) registered
[   16.192211] Bluetooth: HCI UART protocol Broadcom registered
[   16.827121] 8021q: 802.1Q VLAN Support v1.8
[   17.126108] brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled
[   17.358438] Bluetooth: BNEP (Ethernet Emulation) ver 1.3
[   17.358466] Bluetooth: BNEP filters: protocol multicast
[   17.358490] Bluetooth: BNEP socket layer initialized
[   17.369570] Bluetooth: MGMT ver 1.22
[   17.394017] NET: Registered PF_ALG protocol family
[   17.440351] cryptd: max_cpu_qlen set to 1000
[   17.472471] smsc95xx 1-1.1:1.0 eth0: hardware isn't capable of remote wakeup
[   17.474507] smsc95xx 1-1.1:1.0 eth0: Link is Down
[   18.672417] IPv6: ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready
journalctl -u camera-streamer*

(ignore the Sep 22, the date hadn't synced yet, that's all from an hour ago)

-- Journal begins at Thu 2022-09-22 01:23:40 BST, ends at Mon 2023-10-02 16:36:29 BST. --
Sep 22 01:24:04 octopib systemd[1]: Starting camera-streamer...
Sep 22 01:24:04 octopib camera-streamer-control[696]: Running start for camera-streamer-libcamera.service...
Sep 22 01:24:04 octopib camera-streamer-control[696]: ... done.
Sep 22 01:24:04 octopib camera-streamer-control[696]: Adding path unit for autolaunch of camera-streamer-usb@default
Sep 22 01:24:04 octopib systemd[1]: Starting camera-streamer libcamera...
Sep 22 01:24:05 octopib camera-streamer-control[696]: Running start for camera-streamer-usb-default.path...
Sep 22 01:24:05 octopib camera-streamer-control[696]: ... done.
Sep 22 01:24:06 octopib systemd[1]: Started camera-streamer default autolaunch.
Sep 22 01:24:06 octopib systemd[1]: Finished camera-streamer.
Sep 22 01:24:06 octopib sh[713]: /base/soc/i2c0mux/i2c@1/imx708@1a
Sep 22 01:24:06 octopib systemd[1]: Started camera-streamer libcamera.
Sep 22 01:24:09 octopib sh[775]: /usr/bin/camera-streamer Version: v0.2.1 (91d19da)
Sep 22 01:24:09 octopib sh[775]: [0:00:22.718920094] [775]  INFO Camera camera_manager.cpp:299 libcamera v0.0.4+22-923f5d70
Sep 22 01:24:09 octopib sh[775]: [0:00:22.902773115] [891]  INFO RPI raspberrypi.cpp:1476 Registered camera /base/soc/i2c0mux/i2c@1/imx708@1a to Unicam device /dev/media3 and ISP device /dev/media0
Sep 22 01:24:09 octopib sh[775]: device/libcamera/device.cc: CAMERA: Device path=/base/soc/i2c0mux/i2c@1/imx708@1a opened
Sep 22 01:24:09 octopib sh[775]: [0:00:22.909141657] [775]  INFO Camera camera.cpp:1028 configuring streams: (0) 1920x1080-YUYV
Sep 22 01:24:09 octopib sh[775]: [0:00:22.909731500] [891]  INFO RPI raspberrypi.cpp:851 Sensor: /base/soc/i2c0mux/i2c@1/imx708@1a - Selected sensor format: 2304x1296-SBGGR10_1X10 - Selected unicam format: 2304x1296-pBAA
Sep 22 01:24:09 octopib sh[775]: [0:00:22.913644105] [775]  INFO Camera camera.cpp:1028 configuring streams: (0) 1920x1080-YUYV (1) 2304x1296-SBGGR10_CSI2P
Sep 22 01:24:09 octopib sh[775]: [0:00:22.914366344] [891]  INFO RPI raspberrypi.cpp:851 Sensor: /base/soc/i2c0mux/i2c@1/imx708@1a - Selected sensor format: 2304x1296-SBGGR10_1X10 - Selected unicam format: 2304x1296-pBAA
Sep 22 01:24:09 octopib sh[775]: device/buffer_list.c: CAMERA:capture: Using: 1920x1080/YUYV, buffers=2, bytesperline=3840, sizeimage=0.0MiB
Sep 22 01:24:10 octopib sh[775]: device/buffer_list.c: CAMERA:capture: Opened 2 buffers. Memory used: 7.9 MiB
Sep 22 01:24:10 octopib sh[775]: device/buffer_list.c: CAMERA:capture:1: Using: 2304x1296/BG10, buffers=2, bytesperline=2880, sizeimage=0.0MiB
Sep 22 01:24:10 octopib sh[775]: device/buffer_list.c: CAMERA:capture:1: Opened 2 buffers. Memory used: 7.1 MiB
Sep 22 01:24:10 octopib sh[775]: device/v4l2/device.c: SNAPSHOT: Device path=/dev/video31 fd=39 opened
Sep 22 01:24:10 octopib sh[775]: device/v4l2/buffer_list.c: SNAPSHOT:output:mplane: Requested resolution=1920x1080 is unavailable. Got 1920x1088.
Sep 22 01:24:10 octopib sh[775]: device/buffer_list.c: SNAPSHOT:output:mplane: Using: 1920x1056/YUYV, buffers=2, bytesperline=3840, sizeimage=3.9MiB
Sep 22 01:24:10 octopib sh[775]: device/buffer_list.c: SNAPSHOT:output:mplane: Opened 2 buffers. Memory used: 0.0 MiB
Sep 22 01:24:10 octopib sh[775]: device/buffer_list.c: SNAPSHOT:capture:mplane: Using: 1920x1056/JPEG, buffers=2, bytesperline=0, sizeimage=4.0MiB
Sep 22 01:24:10 octopib sh[775]: device/buffer_list.c: SNAPSHOT:capture:mplane: Opened 2 buffers. Memory used: 8.0 MiB
Sep 22 01:24:10 octopib sh[775]: device/v4l2/device.c: RESCALLER:STREAM: Device path=/dev/video12 fd=42 opened
Sep 22 01:24:10 octopib sh[775]: device/buffer_list.c: RESCALLER:STREAM:output:mplane: Using: 1920x1080/YUYV, buffers=2, bytesperline=3840, sizeimage=4.0MiB
Sep 22 01:24:10 octopib sh[775]: device/buffer_list.c: RESCALLER:STREAM:output:mplane: Opened 2 buffers. Memory used: 0.0 MiB
Sep 22 01:24:10 octopib sh[775]: device/buffer_list.c: RESCALLER:STREAM:capture:mplane: Using: 1312x736/YUYV, buffers=2, bytesperline=2624, sizeimage=1.8MiB
Sep 22 01:24:10 octopib sh[775]: device/buffer_list.c: RESCALLER:STREAM:capture:mplane: Opened 2 buffers. Memory used: 3.7 MiB
Sep 22 01:24:10 octopib sh[775]: device/v4l2/device.c: STREAM: Device path=/dev/video31 fd=45 opened
Sep 22 01:24:10 octopib sh[775]: device/buffer_list.c: STREAM:output:mplane: Using: 1312x736/YUYV, buffers=2, bytesperline=2624, sizeimage=1.8MiB
Sep 22 01:24:10 octopib sh[775]: device/buffer_list.c: STREAM:output:mplane: Opened 2 buffers. Memory used: 0.0 MiB
Sep 22 01:24:10 octopib sh[775]: device/buffer_list.c: STREAM:capture:mplane: Using: 1312x736/JPEG, buffers=2, bytesperline=0, sizeimage=4.0MiB
Sep 22 01:24:10 octopib sh[775]: device/buffer_list.c: STREAM:capture:mplane: Opened 2 buffers. Memory used: 8.0 MiB
Sep 22 01:24:10 octopib sh[775]: device/v4l2/device.c: VIDEO: Device path=/dev/video11 fd=48 opened
Sep 22 01:24:10 octopib sh[775]: device/buffer_list.c: VIDEO:output:mplane: Using: 1312x736/YUYV, buffers=2, bytesperline=2624, sizeimage=1.8MiB
Sep 22 01:24:10 octopib sh[775]: device/buffer_list.c: VIDEO:output:mplane: Opened 2 buffers. Memory used: 0.0 MiB
Sep 22 01:24:10 octopib sh[775]: device/buffer_list.c: VIDEO:capture:mplane: Using: 1312x736/H264, buffers=2, bytesperline=0, sizeimage=0.8MiB
Sep 22 01:24:10 octopib sh[775]: device/buffer_list.c: VIDEO:capture:mplane: Opened 2 buffers. Memory used: 1.5 MiB
Sep 22 01:24:10 octopib sh[775]: device/device.c: CAMERA: Setting frame interval_us=0 for FPS=15
Sep 22 01:24:10 octopib sh[775]: device/libcamera/device.cc: CAMERA: Configuring option afmode (0000001c, type=3) = 2
Sep 22 01:24:10 octopib sh[775]: device/libcamera/device.cc: CAMERA: Configuring option afrange (0000001d, type=3) = 2
Sep 22 01:24:10 octopib sh[775]: device/libcamera/device.cc: CAMERA: Configuring option aftrigger (00000021, type=3) = 1
Sep 22 01:24:10 octopib sh[775]: device/v4l2/device_options.c: SNAPSHOT: Configuring option compressionquality (009d0903) = 80
Sep 22 01:24:10 octopib sh[775]: device/v4l2/device_options.c: STREAM: Configuring option compressionquality (009d0903) = 80
Sep 22 01:24:10 octopib sh[775]: device/v4l2/device_options.c: VIDEO: Configuring option repeatsequenceheader (009909e2) = 1
Sep 22 01:24:10 octopib sh[775]: device/v4l2/device_options.c: VIDEO: Configuring option videobitratemode (009909ce) = 0
Sep 22 01:24:10 octopib sh[775]: device/v4l2/device_options.c: VIDEO: Configuring option videobitrate (009909cf) = 2000000
Sep 22 01:24:10 octopib sh[775]: device/v4l2/device_options.c: VIDEO: Configuring option repeatsequenceheader (009909e2) = 5000000
Sep 22 01:24:10 octopib sh[775]: device/v4l2/device_options.c: VIDEO: Configuring option h264iframeperiod (00990a66) = 30
Sep 22 01:24:10 octopib sh[775]: device/v4l2/device_options.c: VIDEO: Configuring option h264level (00990a67) = 11
Sep 22 01:24:10 octopib sh[775]: device/v4l2/device_options.c: VIDEO: Configuring option h264profile (00990a6b) = 4
Sep 22 01:24:10 octopib sh[775]: device/v4l2/device_options.c: VIDEO: Configuring option h264minimumqpvalue (00990a61) = 16
Sep 22 01:24:10 octopib sh[775]: device/v4l2/device_options.c: VIDEO: Configuring option h264maximumqpvalue (00990a62) = 32
Sep 22 01:24:10 octopib sh[775]: device/links.c: ?: Link 0: CAMERA:capture[1920x1080/YUYV/2] => [SNAPSHOT:output:mplane[1920x1056/YUYV/2], RESCALLER:STREAM:output:mplane[1920x1080/YUYV/2]]
Sep 22 01:24:10 octopib sh[775]: device/links.c: ?: Link 1: SNAPSHOT:capture:mplane[1920x1056/JPEG/2] => [SNAPSHOT-CAPTURE]
Sep 22 01:24:10 octopib sh[775]: device/links.c: ?: Link 2: RESCALLER:STREAM:capture:mplane[1312x736/YUYV/2] => [STREAM:output:mplane[1312x736/YUYV/2], VIDEO:output:mplane[1312x736/YUYV/2]]
Sep 22 01:24:10 octopib sh[775]: device/links.c: ?: Link 3: STREAM:capture:mplane[1312x736/JPEG/2] => [STREAM-CAPTURE]
Sep 22 01:24:10 octopib sh[775]: device/links.c: ?: Link 4: VIDEO:capture:mplane[1312x736/H264/2] => [VIDEO-CAPTURE]
Sep 22 01:24:10 octopib sh[775]: [0:00:22.974276865] [894]  WARN IPARPI raspberrypi.cpp:1183 Could not set AF_TRIGGER - no AF algorithm or not Auto
Sep 22 01:24:10 octopib sh[775]: device/buffer_list.c: CAMERA:capture: Streaming started... Was 0 of 2 enqueud
Sep 22 01:24:10 octopib sh[775]: device/buffer_list.c: SNAPSHOT:output:mplane: Streaming started... Was 0 of 2 enqueud
Sep 22 01:24:10 octopib sh[775]: device/buffer_list.c: RESCALLER:STREAM:output:mplane: Streaming started... Was 0 of 2 enqueud
Sep 22 01:24:10 octopib sh[775]: device/buffer_list.c: SNAPSHOT:capture:mplane: Streaming started... Was 0 of 2 enqueud
Sep 22 01:24:10 octopib sh[775]: device/buffer_list.c: RESCALLER:STREAM:capture:mplane: Streaming started... Was 0 of 2 enqueud
Sep 22 01:24:10 octopib sh[775]: device/buffer_list.c: STREAM:output:mplane: Streaming started... Was 0 of 2 enqueud
Sep 22 01:24:10 octopib sh[775]: device/buffer_list.c: VIDEO:output:mplane: Streaming started... Was 0 of 2 enqueud
Sep 22 01:24:10 octopib sh[775]: device/buffer_list.c: STREAM:capture:mplane: Streaming started... Was 0 of 2 enqueud
Sep 22 01:24:10 octopib sh[775]: device/buffer_list.c: VIDEO:capture:mplane: Streaming started... Was 0 of 2 enqueud
Oct 02 16:36:22 octopib sh[775]: util/http/http.c: HTTP8080/0: Client connected 127.0.0.1 (fd=4).
Oct 02 16:36:22 octopib sh[775]: util/http/http.c: HTTP8080/0: Request 'GET' '/' ''
Oct 02 16:36:22 octopib sh[775]: util/http/http.c: HTTP8080/0: Client disconnected 127.0.0.1.
Oct 02 16:36:27 octopib sh[775]: util/http/http.c: HTTP8080/1: Client connected 127.0.0.1 (fd=5).
Oct 02 16:36:27 octopib sh[775]: util/http/http.c: HTTP8080/1: Request 'GET' '/stream' ''
Oct 02 16:36:27 octopib sh[775]: device/links.c: CAMERA:capture: Stale detected. Restarting streaming...
Oct 02 16:36:27 octopib sh[775]: device/buffer_list.c: CAMERA:capture: Streaming stopped... Was 0 of 2 enqueud
Oct 02 16:36:27 octopib sh[775]: device/buffer_list.c: CAMERA:capture: Streaming started... Was 0 of 2 enqueud
Oct 02 16:36:29 octopib sh[775]: util/http/http.c: HTTP8080/1: Client disconnected 127.0.0.1.

Note how the stale message in the journalctl output only gets logged once I tried to access the mjpg stream in the browser. The server is now dysfunctional, neither of the streams nor the snapshot work.

After a server restart however, everything works again.

@warshoot3r
Copy link

warshoot3r commented Oct 26, 2023

I hope this can be of use. I have multiple raspberry pi's( pi4, pizero)

  • pi4 is wired into ethernet and never gets this problem

Now the wifi ones.

  • one of my pizero (stopped getting this problem after I adjusted wifi power to prevent roaming.)

But what I think might be causing the issue is that :

  1. camera streaming server is running.
  2. client is connected
  3. the camera server roams to another wifi network/ disconnects
  4. server is not aware and this could cause the server error(?) as stream socket might not be handled?
  5. You must restart the server or the camera streamer service.

@YaphetS1
Copy link

@foosel Hi!
Did you find to solve issue?
I'd the same problem on my Pi4 with camera OV5647

@recrudesce
Copy link

I have this same issue. Streams just stop working after a while. Wondering if there's a fix ?

@jeloneal
Copy link

You could just restart the service via cron on a regular interval like every few hours. That could at least serve as a workaround until it is fixed.

@DanielJoyce
Copy link

After digging around, apparently H264 encoding 1280p can cause voltage dips? On the kodi forums saw a reccomendation to add over_voltage=2 to the boot/config.txt file, and maybe just drop the res of the pi-cam to 720p, or the framerate to 15fps.

I have made these changes on my mainsail system and will update if things stay stable

@DanielJoyce
Copy link

I have noticed that once my Rpi 4 with klipper starts printing, the camera invariably stops working.

@DanielJoyce
Copy link

After digging around, apparently H264 encoding 1280p can cause voltage dips? On the kodi forums saw a reccomendation to add over_voltage=2 to the boot/config.txt file, and maybe just drop the res of the pi-cam to 720p, or the framerate to 15fps.

I have made these changes on my mainsail system and will update if things stay stable

No crashes of the webcam after these changes so far.

@DanielJoyce
Copy link

@foosel Please check out my comments. The tweaks here have fixed the camera issue for me.

@foosel
Copy link
Contributor

foosel commented Sep 6, 2024

Saw the comments, that isn't a solution for the issue however that will work for the majority I fear.

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

9 participants