"vi-output, ardu" process uses 100% CPU on Jetson when trigger_mode=1

  1. Where did you get the camera module(s)?
    Arducam OV9281 1MP Global Shutter NoIR Camera Module for Jetson Nano

  2. Model number of the product(s)?
    B0223, Arducam OV9281 1MP Global Shutter IR, Camera Module for Jetson Nano

  3. What hardware/platform were you working on?
    Jetson Xavier NX

  4. Instructions you have followed. (link/manual/etc.)
    docs arducam com Nvidia-Jetson-Camera Jetvariety-Camera Quick-Start-Guide/

  5. Problems you were having?
    “vi-output, ardu” process uses 100% CPU when trigger_mode=1

  6. The dmesg log from your hardware?
    Was too long, snipped something out, please let me know if something important is missing

$ dmesg
[ 0.000000] Booting Linux on physical CPU 0x0
[ 0.000000] Linux version 4.9.253-tegra (jenkins@feaf98b9a84d) (gcc version 7.3.1 20180425 [linaro-7.3-2018.05 revision d29120a424ecfbc167ef90065c0eeb7f91977701] (Linaro GCC 7.3-2018.05) ) #1 SMP PREEMPT Mon Mar 14 02:51:19 UTC 2022
[ 0.000000] Boot CPU: AArch64 Processor [4e0f0040]
[ 0.000000] OF: fdt:memory scan node memory, reg size 48,
[ 0.000000] OF: fdt: - 80000000 , 2c000000
[ 0.000000] OF: fdt: - ac200000 , 44800000
[ 0.000000] OF: fdt: - 100000000 , 180000000
[ 0.000000] earlycon: tegra_comb_uart0 at MMIO32 0x000000000c168000 (options ‘’)
[ 0.000000] bootconsole [tegra_comb_uart0] enabled
[ 0.000000] OF: reserved mem: initialized node ramoops_carveout, compatible id nvidia,ramoops
[ 0.000000] OF: reserved mem: initialized node vpr-carveout, compatible id nvidia,vpr-carveout
[ 0.000000] OF: reserved mem: initialized node grid-of-semaphores, compatible id nvidia,gosmem
[ 0.000000] cma: Reserved 64 MiB at 0x00000000c2000000
[ 0.000000] On node 0 totalpages: 2033152
[ 0.000000] DMA zone: 7192 pages used for memmap
[ 0.000000] DMA zone: 0 pages reserved
[ 0.000000] DMA zone: 460288 pages, LIFO batch:31
[ 0.000000] Normal zone: 24576 pages used for memmap
[ 0.000000] Normal zone: 1572864 pages, LIFO batch:31
[ 0.000000] psci: probing for conduit method from DT.
[ 0.000000] psci: PSCIv1.0 detected in firmware.
[ 0.000000] psci: Using standard PSCI v0.2 function IDs
[ 0.000000] psci: MIGRATE_INFO_TYPE not supported.
[ 0.000000] psci: SMC Calling Convention v1.1
[ 0.000000] percpu: Embedded 24 pages/cpu s57624 r8192 d32488 u98304
[ 0.000000] pcpu-alloc: s57624 r8192 d32488 u98304 alloc=24*4096
[ 0.000000] pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 [0] 4 [0] 5
[ 0.000000] Speculative Store Bypass Disable mitigation not required
[ 0.000000] Built 1 zonelists in Zone order, mobility grouping on. Total pages: 2001384
[ 0.000000] Kernel command line: console=ttyTCU0,115200 video=tegrafb earlycon=tegra_comb_uart,mmio32,0x0c168000 gpt rootfs.slot_suffix= usbcore.old_scheme_first=1 tegraid=19.1.2.0.0 maxcpus=6 boot.slot_suffix=_b boot.ratchetvalues=0.4.2 vpr_resize sdhci_tegra.en_boot_part_access=1 quiet root=/dev/mmcblk0p1 rw rootwait rootfstype=ext4 console=ttyTCU0,115200n8 console=tty0 fbcon=map:0 net.ifnames=0
[ 0.000000] log_buf_len individual max cpu contribution: 32768 bytes
[ 0.000000] log_buf_len total cpu_extra contributions: 163840 bytes
[ 0.000000] log_buf_len min size: 32768 bytes
[ 0.000000] log_buf_len: 262144 bytes
[ 0.000000] early log buf free: 30136(91%)
[ 0.000000] PID hash table entries: 4096 (order: 3, 32768 bytes)
[ 0.000000] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes)
[ 0.000000] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes)
[ 0.000000] Memory: 7190528K/8132608K available (15358K kernel code, 2956K rwdata, 6752K rodata, 8640K init, 612K bss, 188416K reserved, 753664K cma-reserved)
[ 0.000000] Virtual kernel memory layout:
[ 0.000000] modules : 0xffffff8000000000 - 0xffffff8008000000 ( 128 MB)
[ 0.000000] vmalloc : 0xffffff8008000000 - 0xffffffbebfff0000 ( 250 GB)
[ 0.000000] .text : 0xffffff8008080000 - 0xffffff8008f80000 ( 15360 KB)
[ 0.000000] .rodata : 0xffffff8008f80000 - 0xffffff8009620000 ( 6784 KB)
[ 0.000000] .init : 0xffffff8009620000 - 0xffffff8009e90000 ( 8640 KB)
[ 0.000000] .data : 0xffffff8009e90000 - 0xffffff800a173008 ( 2957 KB)
[ 0.000000] .bss : 0xffffff800a173008 - 0xffffff800a20c0b4 ( 613 KB)
[ 0.000000] fixed : 0xffffffbefe7fd000 - 0xffffffbefec00000 ( 4108 KB)
[ 0.000000] PCI I/O : 0xffffffbefee00000 - 0xffffffbeffe00000 ( 16 MB)
[ 0.000000] vmemmap : 0xffffffbf00000000 - 0xffffffc000000000 ( 4 GB maximum)
[ 0.000000] 0xffffffbf00000000 - 0xffffffbf08000000 ( 128 MB actual)
[ 0.000000] memory : 0xffffffc000000000 - 0xffffffc200000000 ( 8192 MB)
[ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=6, Nodes=1
[ 0.000000] Preemptible hierarchical RCU implementation.
[ 0.000000] Build-time adjustment of leaf fanout to 64.
[ 0.000000] RCU restricting CPUs from NR_CPUS=64 to nr_cpu_ids=6.
[ 0.000000] RCU: Adjusting geometry for rcu_fanout_leaf=64, nr_cpu_ids=6
[ 0.000000] NR_IRQS:64 nr_irqs:64 0
[ 0.000000] GIC: Using split EOI/Deactivate mode
[ 0.000000] arm_arch_timer: Architected cp15 timer(s) running at 31.25MHz (phys).
[ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0xe6a171046, max_idle_ns: 881590405314 ns
[ 0.000004] sched_clock: 56 bits at 31MHz, resolution 32ns, wraps every 4398046511088ns
[ 0.000796] Console: colour dummy device 80x25
[ 0.000807] console [tty0] enabled
[ 0.000814] bootconsole [tegra_comb_uart0] disabled
[ 0.000828] kmemleak: Kernel memory leak detector disabled
[ 0.000847] Calibrating delay loop (skipped), value calculated using timer frequency… 62.50 BogoMIPS (lpj=125000)
[ 0.000862] pid_max: default: 32768 minimum: 301
[ 0.001920] Security Framework initialized
[ 0.002485] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes)
[ 0.002495] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes)
[ 0.004124] ftrace: allocating 47123 entries in 185 pages
[ 0.400128] sched-energy: Sched-energy-costs installed from DT
[ 0.400148] ASID allocator initialised with 65536 entries
[ 0.435257] tegra-id: chipid=21917.
[ 0.435269] tegra-id: opt_subrevision=1.
[ 0.435296] Tegra Revision: A02p SKU: 0xde CPU Process: 0 SoC Process: 0
[ 0.435310] DTS File Name: /var/jenkins_home/workspace/ier_nx_kernel_l4t-32.7.1-arducam/kernel/kernel-4.9/arch/arm64/boot/dts/…/…/…/…/…/…/hardware/nvidia/platform/t19x/jakku/kernel-dts/tegra194-p3668-all-p3509-0000.dts
[ 0.435320] DTB Build time: Mar 14 2022 02:52:04
[ 0.483350] CPU1: Booted secondary processor [4e0f0040]
[ 0.515501] CPU2: Booted secondary processor [4e0f0040]
[ 0.547616] CPU3: Booted secondary processor [4e0f0040]
[ 0.579921] CPU4: Booted secondary processor [4e0f0040]
[ 0.611987] CPU5: Booted secondary processor [4e0f0040]
[ 0.612247] Brought up 6 CPUs
[ 0.612256] SMP: Total of 6 processors activated.
[ 0.612266] CPU features: detected feature: Privileged Access Never
[ 0.612272] CPU features: detected feature: LSE atomic instructions
[ 0.612278] CPU features: detected feature: User Access Override
[ 0.612284] CPU features: detected feature: 32-bit EL0 Support
[ 0.613700] CPU: All CPU(s) started at EL2
[ 0.613747] alternatives: patching kernel code
[ 0.620258] devtmpfs: initialized
[ 0.660109] Initilizing CustomIPI irq domain
[ 0.660368] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns
[ 0.660385] futex hash table entries: 2048 (order: 5, 131072 bytes)
[ 0.680542] pinctrl core: initialized pinctrl subsystem
[ 0.680805] OS set in device tree is not L4T.
[ 0.681123] regulator-dummy: no parameters
[ 0.681407] Initializing plugin-manager
[ 0.681462] Plugin module not found
[ 0.681497] Plugin-manager status disabled
[ 0.682570] NET: Registered protocol family 16
[ 0.683734] pstore: using zlib compression
[ 0.683754] console [pstore-1] enabled
[ 0.683759] pstore: Registered ramoops as persistent store backend
[ 0.683766] ramoops: attached 0x200000@0xf0800000, ecc: 0/0
[ 0.702691] cpuidle: using governor menu
[ 0.703643] bpmp: waiting for handshake
[ 0.703652] bpmp: handshake completed
[ 0.703669] bpmp: synchronizing channels
[ 0.703677] bpmp: channels synchronized
[ 0.703684] bpmp: mail init ok
[ 0.706061] t19x-arm-smmu 12000000.iommu: found 3 SMMUs and ISO SMMU id is 2
[ 0.706250] t19x-arm-smmu 12000000.iommu: probing hardware configuration…
[ 0.706259] t19x-arm-smmu 12000000.iommu: SMMUv2 with:
[ 0.706287] t19x-arm-smmu 12000000.iommu: stage 1 translation
[ 0.706292] t19x-arm-smmu 12000000.iommu: stage 2 translation
[ 0.706298] t19x-arm-smmu 12000000.iommu: nested translation
[ 0.706308] t19x-arm-smmu 12000000.iommu: stream matching with 128 register groups, mask 0x7f80
[ 0.706334] t19x-arm-smmu 12000000.iommu: SMMU address space size (0x800000) differs from mapped region size (0x1000000)!
[ 0.706341] t19x-arm-smmu 12000000.iommu: 64 context banks (0 stage-2 only)
[ 0.706349] t19x-arm-smmu 12000000.iommu: Stage-1: 39-bit VA → 48-bit IPA
[ 0.706355] t19x-arm-smmu 12000000.iommu: Stage-2: 39-bit IPA → 48-bit PA
[ 0.709635] vdso: 2 pages (1 code @ ffffff8008f87000, 1 data @ ffffff8009e94000)
[ 0.709667] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers.
[ 0.712288] atomic_pool_init():622: DMA: preallocated 1024 KiB pool for atomic allocations
[ 0.713328] tegra_aon_clk_init: ok
[ 0.713339] Registering BPMP clocks…
[ 0.713437] tegra_bpmp_clk_init: clock init ok (400 clks)
[ 0.713972] Serial: AMBA PL011 UART driver
[ 0.714095] tegra_powergate_init: DONE
[ 0.714152] DTS File Name: /var/jenkins_home/workspace/ier_nx_kernel_l4t-32.7.1-arducam/kernel/kernel-4.9/arch/arm64/boot/dts/…/…/…/…/…/…/hardware/nvidia/platform/t19x/jakku/kernel-dts/tegra194-p3668-all-p3509-0000.dts
[ 0.714162] DTB Build time: Mar 14 2022 02:52:04
[ 0.716966] Tegra reboot handler registered.
[ 0.724133] iommu: Adding device 14160000.pcie to group 0
[ 0.724740] iommu: Adding device 141a0000.pcie to group 1
[ 0.726014] iommu: Adding device 2490000.ether_qos to group 2
[ 0.726594] iommu: Adding device 3100000.serial to group 3
[ 0.727112] iommu: Adding device 3110000.serial to group 4
[ 0.727681] iommu: Adding device 3140000.serial to group 5
[ 0.728285] tegra-pmc c360000.pmc: scratch reg offset dts data not present
[ 0.728519] tegra-pmc: get_secure_pmc_setting: done secure_pmc=0
[ 0.728537] tegra-pmc: ### PMC reset source: TEGRA_POWER_ON_RESET
[ 0.728543] tegra-pmc: ### PMC reset level: TEGRA_RESET_LEVEL_L0
[ 0.728548] tegra-pmc: ### PMC reset status reg: 0x0
[ 0.728595] tegra-pmc: PMC Prod config success
[ 0.728843] padctrl padctrl.0: Pad control driver tegra-pmc-padctrl registered
[ 0.728859] tegra-pmc c360000.pmc: IO padctrl driver initialized

[ 1.879316] tegra-xusb 3610000.xhci: Upgrade port 0 to USB3.0
[ 1.879324] tegra-xusb 3610000.xhci: Upgrade port 1 to USB3.0
[ 1.879800] tegra-xudc-new 3550000.xudc: PMQOS CPU boost enabled
[ 1.879870] tegra-xudc-new 3550000.xudc: device count: 1
[ 1.880647] tegra-xudc-new 3550000.xudc: vbus state: 0
[ 1.880657] tegra-xudc-new 3550000.xudc: Initialize boost_cpufreq work
[ 1.880682] tegra-xudc-new 3550000.xudc: entering ELPG
[ 1.880893] tegra-xudc-new 3550000.xudc: entering ELPG done
[ 1.881607] mousedev: PS/2 mouse device common for all mice
[ 1.881753] usbcore: registered new interface driver xpad
[ 1.978798] usb usb2: usb_suspend_both: status 0
[ 2.012329] max77686-rtc max77620-rtc: rtc core: registered max77620-rtc as rtc0
[ 2.014374] Wake73 for irq=42
[ 2.015603] rtc rtc1: alarm rtc device
[ 2.015618] tegra_rtc c2a0000.rtc: rtc core: registered c2a0000.rtc as rtc1
[ 2.015647] tegra_rtc c2a0000.rtc: Tegra internal Real Time Clock
[ 2.015833] i2c /dev entries driver
[ 2.018460] i2c i2c-2: Added multiplexed i2c bus 9
[ 2.018809] i2c i2c-2: Added multiplexed i2c bus 10
[ 2.018843] i2c-mux-gpio cam_i2cmux: 2 port mux on 3180000.i2c adapter
[ 2.043696] arducam-csi2 9-000c: firmware version: 65539
[ 2.043981] arducam-csi2 9-000c: Sensor ID: 0x9281
[ 2.121578] arducam-csi2 9-000c: sensor arducam-csi2 9-000c registered
[ 2.145597] arducam-csi2 10-000c: firmware version: 65539
[ 2.145870] arducam-csi2 10-000c: Sensor ID: 0x9281
[ 2.202205] usb 1-2: new high-speed USB device number 2 using tegra-xusb
[ 2.222322] arducam-csi2 10-000c: sensor arducam-csi2 10-000c registered
[ 2.223170] max77620-power max20024-power: Event recorder REG_NVERC : 0x40
[ 2.230862] tegra-thermal-throttle bthrot_cdev: cpufreq policy is not ready defer
[ 2.231314] FAN dev name: pwm-fan
[ 2.231358] FAN:gpio request success.
[ 2.231369] FAN: can’t find tach_gpio
[ 2.231391] pwm_fan_driver pwm-fan: Found 2 profiles, default profile is quiet
[ 2.231431] pwm_fan_driver pwm-fan: cap state:4, cap pwm:255
[ 2.231638] pwm_fan_driver pwm-fan: got pwm for fan. polarity is normal
[ 2.231646] pwm_fan_driver pwm-fan: tach period: 1000

[ 3.285210] pci_bus 0005:00: root bus resource [bus 00-ff]
[ 3.285219] pci_bus 0005:00: root bus resource [io 0x100000-0x1fffff] (bus address [0x3a100000-0x3a1fffff])
[ 3.285225] pci_bus 0005:00: root bus resource [mem 0x1f40000000-0x1fffffffff] (bus address [0x40000000-0xffffffff])
[ 3.285230] pci_bus 0005:00: root bus resource [mem 0x1c00000000-0x1f3fffffff pref]
[ 3.285255] pci 0005:00:00.0: [10de:1ad0] type 01 class 0x060400
[ 3.285422] pci 0005:00:00.0: PME# supported from D0 D3hot D3cold
[ 3.285680] iommu: Adding device 0005:00:00.0 to group 60
[ 3.285910] pci 0005:00:00.0: PCI bridge to [bus 01-ff]
[ 3.285933] pci 0005:00:00.0: Max Payload Size set to 256/ 256 (was 256), Max Read Rq 512
[ 3.286176] pcieport 0005:00:00.0: Signaling PME through PCIe PME interrupt
[ 3.286183] pcie_pme 0005:00:00.0:pcie001: service driver pcie_pme loaded
[ 3.286252] aer 0005:00:00.0:pcie002: service driver aer loaded
[ 3.286386] pcie_pme 0005:00:00.0:pcie001: unloading service driver pcie_pme
[ 3.286427] aer 0005:00:00.0:pcie002: unloading service driver aer
[ 3.286499] pci_bus 0005:01: busn_res: [bus 01-ff] is released
[ 3.286571] iommu: Removing device 0005:00:00.0 from group 60
[ 3.286594] pci_bus 0005:00: busn_res: [bus 00-ff] is released
[ 3.288356] tegra-pcie-dw 141a0000.pcie: PCIe link is not up…!
[ 3.300084] tegra-cbb 14040000.cv-noc: noc_secure_irq = 90, noc_nonsecure_irq = 89>
[ 3.304486] tegra194-isp5 14800000.isp: initialized
[ 3.311165] tegra194-vi5 15c10000.vi: using default number of vi channels, 36
[ 3.314329] tegra194-vi5 15c10000.vi: initialized
[ 3.318552] tegra194-vi5 15c10000.vi: subdev arducam-csi2 9-000c bound
[ 3.318579] tegra194-vi5 15c10000.vi: subdev 15a00000.nvcsi–2 bound
[ 3.319251] tegra194-vi5 15c10000.vi: subdev arducam-csi2 10-000c bound
[ 3.319258] tegra194-vi5 15c10000.vi: subdev 15a00000.nvcsi–1 bound

[ 3.489788] ALSA device list:
[ 3.489794] #0: tegra-hda-xnx at 0x3518000 irq 64
[ 3.489797] #1: jetson-xaviernx-ape
[ 3.492093] Freeing unused kernel memory: 8640K
[ 3.529933] Root device found: mmcblk0p1
[ 3.531950] Found dev node: /dev/mmcblk0p1
[ 4.796802] EXT4-fs (mmcblk0p1): 2 orphan inodes deleted
[ 4.796808] EXT4-fs (mmcblk0p1): recovery complete
[ 4.806607] EXT4-fs (mmcblk0p1): mounted filesystem with ordered data mode. Opts: (null)
[ 4.809645] Rootfs mounted over mmcblk0p1
[ 4.831699] Switching from initrd to actual rootfs
[ 5.123122] systemd[1]: System time before build time, advancing clock.
[ 5.171071] ip_tables: (C) 2000-2006 Netfilter Core Team
[ 5.175843] cgroup: cgroup2: unknown option “nsdelegate”
[ 5.193024] systemd[1]: systemd 237 running in system mode. (+PAM +AUDIT +SELINUX +IMA +APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD -IDN2 +IDN -PCRE2 default-hierarchy=hybrid)
[ 5.193771] systemd[1]: Detected architecture arm64.
[ 5.200076] systemd[1]: Set hostname to .
[ 5.325156] systemd[1]: File /lib/systemd/system/systemd-journald.service:36 configures an IP firewall (IPAddressDeny=any), but the local system does not support BPF/cgroup based firewalling.
[ 5.325169] systemd[1]: Proceeding WITHOUT firewalling in effect! (This warning is only shown for the first loaded unit using IP firewalling.)
[ 5.464523] random: systemd: uninitialized urandom read (16 bytes read)
[ 5.467796] systemd[1]: Created slice System Slice.
[ 5.468001] random: systemd: uninitialized urandom read (16 bytes read)
[ 5.468303] systemd[1]: Listening on Journal Socket (/dev/log).
[ 5.468346] random: systemd: uninitialized urandom read (16 bytes read)
[ 5.468510] systemd[1]: Listening on RPCbind Server Activation Socket.
[ 5.468849] systemd[1]: Listening on udev Control Socket.
[ 5.470115] systemd[1]: Created slice system-serial\x2dgetty.slice.
[ 5.541403] EXT4-fs (mmcblk0p1): re-mounted. Opts: (null)
[ 5.639225] nvgpu: 17000000.gv11b nvgpu_nvhost_syncpt_init:291 [INFO] syncpt_unit_base 60000000 syncpt_unit_size 400000 size 1000

[ 5.743547] systemd-journald[2286]: Received request to flush runtime journal from PID 1
[ 6.712381] random: crng init done
[ 6.712496] random: 7 urandom warning(s) missed due to ratelimiting
[ 6.898319] cpu-throttle-alert cooling device registered.
[ 6.900404] gpu-throttle-alert cooling device registered.
[ 6.900778] aux-throttle-alert cooling device registered.
[ 7.188717] rtl88x2ce 0004:01:00.0: enabling device (0000 → 0003)
[ 7.333579] using random self ethernet address
[ 7.333719] using random host ethernet address
[ 7.384948] usbcore: registered new interface driver btusb
[ 7.437623] rtk_btusb: Realtek Bluetooth USB driver ver 3.1
[ 7.437631] rtk_btcoex: rtk_btcoex_init: version: 1.2
[ 7.437634] rtk_btcoex: create workqueue
[ 7.437782] rtk_btcoex: alloc buffers 1792, 2432 for ev and l2
[ 7.437858] rtk_btusb: btusb_probe intf->cur_altsetting->desc.bInterfaceNumber 0
[ 7.437862] rtk_btusb: btusb_probe can_wakeup 1, may wakeup 0
[ 7.437866] rtk_btusb: patch_add
[ 7.437869] rtk_btusb: auto suspend is disabled
[ 7.437874] rtk_btusb: pid = 0x3549
[ 7.437878] rtk_btusb: patch_add: Reset gEVersion to 0xff
[ 7.437888] rtk_btusb: set_bit(HCI_QUIRK_RESET_ON_CLOSE, &hdev->quirks);
[ 7.440588] rtk_btusb: btusb_probe: done
[ 7.440593] rtk_btusb: btusb_open start
[ 7.440599] rtk_btusb: btusb_open hdev->promisc ==0
[ 7.440602] rtk_btusb: download_patch start
[ 7.440608] rtk_btusb: chip type value: 0x73
[ 7.440613] rtk_btusb: HCI reset.
[ 7.440667] usbcore: registered new interface driver rtk_btusb
[ 7.452084] rtk_btusb: read_ver_rsp->lmp_subver = 0x8822
[ 7.452091] rtk_btusb: read_ver_rsp->hci_rev = 0xc
[ 7.452095] rtk_btusb: patch_entry->lmp_sub = 0x8822
[ 7.452098] rtk_btusb: load_firmware start
[ 7.452102] rtk_btusb: lmp_version = 0x8822
[ 7.452106] rtk_btusb: config filename rtl8822cu_config
[ 7.562960] rtk_btusb: no bdaddr file /opt/bdaddr
[ 7.562968] rtk_btusb: 55 ab 23 87 00 00
[ 7.562982] rtk_btusb: Config file length 6, new length 6
[ 7.562986] rtk_btusb: fw name is rtl8822cu_fw
[ 7.619086] rtk_btusb: This is not 8723a, use new patch style!
[ 7.619093] rtk_btusb: rtk_get_eversion: gEVersion 255
[ 7.620079] rtk_btusb: eversion->status = 0x0, eversion->version = 0x3
[ 7.620087] rtk_btusb: load_firmware: New gEVersion 3
[ 7.620092] rtk_btusb: rtk_get_fw_project_id: opcode 0, len 1, data 13
[ 7.620098] rtk_btusb: lmp_version is 8822, project_id is 8822, match!
[ 7.620101] rtk_btusb: fw_version = 0x98a42f7
[ 7.620104] rtk_btusb: number_of_total_patch = 3
[ 7.620107] rtk_btusb: chipID 4
[ 7.620110] rtk_btusb: patch_length 0x55f0
[ 7.620114] rtk_btusb: start_offset 0x00005d00
[ 7.620118] rtk_btusb: Svn version: 21761
[ 7.620122] rtk_btusb: Coexistence: BTCOEX_20190509-0d0d
[ 7.620125] rtk_btusb: buf_len = 0x55f6
[ 7.620140] rtk_btusb: fw: exists, config file: exists
[ 7.620143] rtk_btusb: load_firmware done
[ 7.620165] rtk_btusb: download_data start
[ 7.643244] cdc_acm 1-2.4:1.0: ttyACM0: USB ACM device
[ 7.646922] usbcore: registered new interface driver cdc_acm
[ 7.646930] cdc_acm: USB Abstract Control Model driver for USB modems and ISDN adapters
[ 7.733110] rtk_btusb: download_data done
[ 7.733118] rtk_btusb: HCI reset.
[ 7.744079] rtk_btusb: read_ver_rsp->lmp_subver = 0x42f7
[ 7.744087] rtk_btusb: read_ver_rsp->hci_rev = 0x98a
[ 7.744091] rtk_btusb: patch_entry->lmp_sub = 0x8822
[ 7.744112] rtk_btusb: Rtk patch end 0
[ 7.744118] rtk_btusb: btusb_open set HCI_RUNNING
[ 7.744201] rtk_btcoex: Open BTCOEX
[ 7.744206] rtk_btusb: btusb_open end
[ 7.746097] rtk_btcoex: BTCOEX hci_rev 0x098a
[ 7.746104] rtk_btcoex: BTCOEX lmp_subver 0x42f7
[ 7.766095] rtk_btusb: btusb_notify: hci0 evt 3
[ 8.476487] Mass Storage Function, version: 2009/09/11
[ 8.476497] LUN: removable file: (no medium)
[ 8.481944] using random self ethernet address
[ 8.482068] using random host ethernet address
[ 8.646762] rndis0: HOST MAC 2a:01:de:cd:bd:20
[ 8.646847] rndis0: MAC 2a:01:de:cd:bd:21
[ 8.648489] usb0: HOST MAC 2a:01:de:cd:bd:22
[ 8.648524] usb0: MAC 2a:01:de:cd:bd:23
[ 8.648548] tegra-xudc-new 3550000.xudc: exiting ELPG
[ 8.649966] tegra-xudc-new 3550000.xudc: exiting ELPG done
[ 8.650001] tegra-xudc-new 3550000.xudc: ep 0 (type: 0, dir: out) enabled
[ 8.650038] tegra-xudc-new 3550000.xudc: entering ELPG
[ 8.650345] tegra-xudc-new 3550000.xudc: entering ELPG done
[ 8.650360] tegra-xudc-new 3550000.xudc: exiting ELPG
[ 8.650834] tegra-xudc-new 3550000.xudc: exiting ELPG done
[ 8.650848] tegra-xudc-new 3550000.xudc: entering ELPG
[ 8.651047] tegra-xudc-new 3550000.xudc: entering ELPG done
[ 8.659476] l4tbr0: port 1(rndis0) entered blocking state
[ 8.659485] l4tbr0: port 1(rndis0) entered disabled state
[ 8.659766] device rndis0 entered promiscuous mode
[ 8.668002] IPv6: ADDRCONF(NETDEV_UP): rndis0: link is not ready
[ 8.673147] l4tbr0: port 2(usb0) entered blocking state
[ 8.673154] l4tbr0: port 2(usb0) entered disabled state
[ 8.673522] device usb0 entered promiscuous mode
[ 8.679030] IPv6: ADDRCONF(NETDEV_UP): usb0: link is not ready
[ 9.295082] Bluetooth: BNEP (Ethernet Emulation) ver 1.3
[ 9.295102] Bluetooth: BNEP socket layer initialized
[ 10.719097] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready
[ 10.942767] ras_fhi_disable: FHI 473 disabled
[ 10.945415] CPU4: shutdown
[ 10.945531] psci: CPU4 killed (polled 0 ms)
[ 11.002770] ras_fhi_disable: FHI 474 disabled
[ 11.005943] CPU5: shutdown
[ 11.007227] psci: CPU5 killed (polled 4 ms)
[ 11.024835] nvgpu: 17000000.gv11b tpc_pg_mask_store:843 [INFO] no value change, same mask already set
[ 11.031074] gpio tegra-gpio wake20 for gpio=52(G:4)
[ 11.039204] net eth0: get_configure_l3v4_filter →
[ 11.040206] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready
[ 11.066813] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
[ 11.355342] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
[ 11.656670] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
[ 11.836895] zram: Added device: zram0
[ 11.837887] zram: Added device: zram1
[ 11.839774] zram: Added device: zram2
[ 11.840803] zram: Added device: zram3
[ 11.854244] zram0: detected capacity change from 0 to 1018867712
[ 11.916382] Adding 994984k swap on /dev/zram0. Priority:5 extents:1 across:994984k SS
[ 11.920838] zram1: detected capacity change from 0 to 1018867712
[ 11.932968] Adding 994984k swap on /dev/zram1. Priority:5 extents:1 across:994984k SS
[ 11.943705] zram2: detected capacity change from 0 to 1018867712
[ 11.956392] Adding 994984k swap on /dev/zram2. Priority:5 extents:1 across:994984k SS
[ 11.959323] zram3: detected capacity change from 0 to 1018867712
[ 11.973258] Adding 994984k swap on /dev/zram3. Priority:5 extents:1 across:994984k SS
[ 12.635089] Bridge firewalling registered
[ 12.668599] nf_conntrack version 0.5.0 (65536 buckets, 262144 max)
[ 13.037950] Netfilter messages via NETLINK v0.30.
[ 13.044536] ctnetlink v0.93: registering with nfnetlink.
[ 13.242229] IPv6: ADDRCONF(NETDEV_UP): docker0: link is not ready
[ 14.128795] tegradc 15200000.nvdisplay: blank - powerdown
[ 14.128807] tegradc 15210000.nvdisplay: blank - powerdown
[ 15.055613] fuse init (API version 7.26)
[ 16.032282] ------------[ cut here ]------------
[ 16.032482] WARNING: CPU: 3 PID: 6081 at /dvs/git/dirty/git-master_linux/kernel/kernel-4.9/net/wireless/nl80211.c:14153 cfg80211_ch_switch_notify+0x150/0x170 [cfg80211]
[ 16.032727] Modules linked in: fuse xt_conntrack ipt_MASQUERADE nf_nat_masquerade_ipv4 nf_conntrack_netlink nfnetlink xt_addrtype iptable_filter iptable_nat nf_conntrack_ipv4 nf_defrag_ipv4 nf_nat_ipv4 nf_nat nf_conntrack br_netfilter zram overlay bnep cdc_acm rtk_btusb btusb btrtl btbcm rtl8822ce btintel cfg80211 userspace_alert binfmt_misc nvgpu ip_tables x_tables

[ 16.032817] CPU: 3 PID: 6081 Comm: RTW_CMD_THREAD Not tainted 4.9.253-tegra #1
[ 16.032821] Hardware name: NVIDIA Jetson Xavier NX Developer Kit (DT)
[ 16.032825] task: ffffffc1e89a7000 task.stack: ffffffc1dbfe8000
[ 16.032898] PC is at cfg80211_ch_switch_notify+0x150/0x170 [cfg80211]
[ 16.033360] LR is at rtw_cfg80211_ch_switch_notify+0x94/0x98 [rtl8822ce]
[ 16.033366] pc : [] lr : [] pstate: 60c00045
[ 16.033369] sp : ffffffc1dbfebc20
[ 16.033373] x29: ffffffc1dbfebc20 x28: ffffff8001291760
[ 16.033382] x27: ffffff800bee21bc x26: ffffff8001456578
[ 16.033390] x25: ffffff80014da13c x24: ffffffc1eabdb000
[ 16.033398] x23: ffffffc1e2406000 x22: ffffffc1e2417000
[ 16.033406] x21: 0000000000000002 x20: ffffffc1dbfebca8
[ 16.033414] x19: ffffffc1ea97a000 x18: 0000000000000030
[ 16.033422] x17: 0000000000000002 x16: 0000000000000000
[ 16.033430] x15: ffffffffffffffff x14: 0000000000000008
[ 16.033438] x13: 7f1201c7fffa002a x12: 0105c00363fffa03
[ 16.033445] x11: 0000000000000004 x10: 0000000000000002
[ 16.033454] x9 : 0000000000000004 x8 : ffffffc1dbfebd3a
[ 16.033462] x7 : 0000000000000000 x6 : 0000000000000001
[ 16.033470] x5 : 0000000000000040 x4 : 00000000000009b4
[ 16.033478] x3 : 0000145a00000003 x2 : ffffffc1e2458848
[ 16.033486] x1 : 0000145a00000003 x0 : 0000000000000000

[ 16.033496] —[ end trace 24a358c37aa45792 ]—
[ 16.033586] Call trace:
[ 16.033653] [] cfg80211_ch_switch_notify+0x150/0x170 [cfg80211]
[ 16.034098] [] rtw_cfg80211_ch_switch_notify+0x94/0x98 [rtl8822ce]
[ 16.034523] [] rtw_chk_start_clnt_join+0x43c/0x4d8 [rtl8822ce]
[ 16.034947] [] join_cmd_hdl+0x238/0x330 [rtl8822ce]
[ 16.035374] [] rtw_cmd_thread+0x308/0x608 [rtl8822ce]
[ 16.035384] [] kthread+0xec/0xf0
[ 16.035392] [] ret_from_fork+0x10/0x30
[ 16.142415] IPv6: ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready
[ 34.142648] vdd-1v8-sd: disabling
[ 34.142672] vdd-1v8-cvb: disabling
[ 34.142680] vdd-epb-1v0: disabling
[ 34.142687] avdd-cam-2v8: disabling
[ 34.142694] vdd-fan: disabling
[ 34.142703] vdd_sys_en: disabling

[ 3761.008243] [RCE] vi5_hwinit: firmware CL2018101701 protocol version 2.2
[ 3761.027069] misc tegra_camera_ctrl: tegra_camera_update_isobw: Warning, Requested ISO BW 1640624 has been capped to VI’s max BW 1500000
[ 3763.530476] tegra194-vi5 15c10000.vi: corr_err: discarding frame 187, flags: 0, err_data 131072
[ 3778.352012] tegra194-vi5 15c10000.vi: no reply from camera processor
[ 3778.352168] tegra194-vi5 15c10000.vi: uncorr_err: request timed out after 2500 ms
[ 3778.352307] tegra194-vi5 15c10000.vi: err_rec: attempting to reset the capture channel
[ 3778.352487] tegra194-vi5 15c10000.vi: no reply from camera processor
[ 3778.352609] tegra194-vi5 15c10000.vi: uncorr_err: request timed out after 2500 ms
[ 3778.352738] tegra194-vi5 15c10000.vi: err_rec: attempting to reset the capture channel
[ 3778.357622] tegra194-vi5 15c10000.vi: err_rec: successfully reset the captu

  1. Troubleshooting attempts you’ve made?
    I have several Jetson & camera modules, all behave the same

$ v4l2-ctl --set-fmt-video=width=1280,height=800,pixelformat=GRAY --stream-mmap --stream-count=1000 -d /dev/video0
<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<< 80.00 fps
<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<< 80.00 fps
<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<< 80.00 fps
<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<< 80.00 fps
<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<< 80.00 fps
<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<< 80.00 fps
<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<< 80.00 fps
<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<< 80.00 fps
<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<< 80.00 fps
<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<< 75.04 fps <— Run “v4l2-ctl -c trigger_mode=1”, external trigger signal at 30fps. Jetson CPU usage jumps to 100%
<<<<<<<<<<<<<<<<<<<<<<<<<<<<<< 70.96 fps
<<<<<<<<<<<<<<<<<<<<<<<<<<<<<< 67.55 fps
<<<<<<<<<<<<<<<<<<<<<<<<<<<<<< 64.66 fps
<<<<<<<<<<<<<<<<<<<<<<<<<<<<<< 62.19 fps
<<<<<<<<<<<<<<<<<<<<<<<<<<<<<< 60.05 fps

Setting trigger_mode back to 0 works, but CPU usage stays at 100% until I restart video stream

$ top
PID USER PR NI VIRT RES SHR S %CPU %MEM TIME+ COMMAND
14995 root 20 0 0 0 0 R 99,3 0,0 0:03.55 vi-output, ardu
^^^ cpu usage high only after using trigger_mode=1

  1. What help do you need?

How to avoid 100% CPU usage when trigger_mode=1?

@Bercon

The cpu does rise for a while after setting trigger_mode=0, but it doesn’t last.

You can give a trigger signal to try, it should be back to normal.