tx2 and custom UYUV camera

Hello!
I’m developing driver for custom mipi-csi2 2-lane camera for tx2. Camera streams in YCbCr 4:2:2 768x576@50. It seems that hardware can’t handle stream when I try to capture frame.

  1. Is Tx2 supports this format?
  2. Is mclk mandatory for tx2? (camera doesn’t need input clock)
  3. Can it be caused by too big pix_clk_hz? (I set it to 100MHz, like on other our device)
  4. Is tegra video drivers support csi_pixel_depth=8?
  5. Camera stream is always on. Can tx2 sync with running stream?

dmesg:

[    0.000000] Booting Linux on physical CPU 0x100
[    0.000000] Linux version 4.9.140-tegra-tegra (kuklin_m@infratest) (gcc version 7.3.1 20180425 [linaro-7.3-2018.05 revision d29120a424ecfbc167ef90065c0eeb7f91977701] (Linaro GCC 7.3-2018.05) ) #12 SMP PREEMPT Mon Nov 25 17:40:30 +05 2019
[    0.000000] Boot CPU: AArch64 Processor [411fd073]
[    0.000000] OF: fdt:memory scan node memory@80000000, reg size 16416,
[    0.000000] OF: fdt: - 80000000 ,  70000000
[    0.000000] OF: fdt: - f0200000 ,  145600000
[    0.000000] OF: fdt: - 235e00000 ,  200000
[    0.000000] OF: fdt: - 236600000 ,  200000
[    0.000000] OF: fdt: - 237000000 ,  200000
[    0.000000] earlycon: uart8250 at MMIO32 0x0000000003100000 (options '')
[    0.000000] bootconsole [uart8250] enabled
[    0.000000] OF: fdt:Reserved memory: failed to reserve memory for node 'fb0_carveout': base 0x0000000000000000, size 0 MiB
[    0.000000] OF: fdt:Reserved memory: failed to reserve memory for node 'fb0_carveout': base 0x0000000000000000, size 0 MiB
[    0.000000] OF: fdt:Reserved memory: failed to reserve memory for node 'fb1_carveout': base 0x0000000000000000, size 0 MiB
[    0.000000] OF: fdt:Reserved memory: failed to reserve memory for node 'fb1_carveout': base 0x0000000000000000, size 0 MiB
[    0.000000] OF: fdt:Reserved memory: failed to reserve memory for node 'fb2_carveout': base 0x0000000000000000, size 0 MiB
[    0.000000] OF: fdt:Reserved memory: failed to reserve memory for node 'fb2_carveout': base 0x0000000000000000, size 0 MiB
[    0.000000] OF: reserved mem: initialized node ramoops_carveout, compatible id nvidia,ramoops
[    0.000000] cma: Reserved 64 MiB at 0x00000000fc000000
[    0.000000] On node 0 totalpages: 1793024
[    0.000000]   DMA zone: 8192 pages used for memmap
[    0.000000]   DMA zone: 0 pages reserved
[    0.000000]   DMA zone: 523776 pages, LIFO batch:31
[    0.000000]   Normal zone: 19912 pages used for memmap
[    0.000000]   Normal zone: 1269248 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 25 pages/cpu @ffffffc1b7048000 s61592 r8192 d32616 u102400
[    0.000000] pcpu-alloc: s61592 r8192 d32616 u102400 alloc=25*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: 1764920
[    0.000000] Kernel command line: root=/dev/mmcblk0p1 rw rootwait rootfstype=ext4 console=ttyS0,115200n8 console=tty0 OS=l4t fbcon=map:0 net.ifnames=0 video=tegrafb no_console_suspend=1 earlycon=uart8250,mmio32,0x3100000 nvdumper_reserved=0x2372e0000 gpt usbcore.old_scheme_first=1 tegraid=18.1.2.0.0 maxcpus=6 boot.slot_suffix= boot.ratchetvalues=0.2031647.1 bl_prof_dataptr=0x10000@0x235840000 sdhci_tegra.en_boot_part_access=1 quiet
[    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: 29416(89%)
[    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: 6937844K/7172096K available (15230K kernel code, 2918K rwdata, 6628K rodata, 8576K init, 609K bss, 168716K reserved, 65536K 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 - 0xffffff8008f60000   ( 15232 KB)
[    0.000000]     .rodata : 0xffffff8008f60000 - 0xffffff80095e0000   (  6656 KB)
[    0.000000]       .init : 0xffffff80095e0000 - 0xffffff8009e40000   (  8576 KB)
[    0.000000]       .data : 0xffffff8009e40000 - 0xffffff800a119808   (  2919 KB)
[    0.000000]        .bss : 0xffffff800a119808 - 0xffffff800a1b1dbc   (   610 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 - 0xffffffbf06dc8000   (   109 MB actual)
[    0.000000]     memory  : 0xffffffc000000000 - 0xffffffc1b7200000   (  7026 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.000003] sched_clock: 56 bits at 31MHz, resolution 32ns, wraps every 4398046511088ns
[    0.000964] Console: colour dummy device 80x25
[    0.000969] console [tty0] enabled
[    0.000972] bootconsole [uart8250] disabled
[    0.000979] kmemleak: Kernel memory leak detector disabled
[    0.000990] Calibrating delay loop (skipped), value calculated using timer frequency.. 62.50 BogoMIPS (lpj=125000)
[    0.000997] pid_max: default: 32768 minimum: 301
[    0.001309] Security Framework initialized
[    0.001467] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes)
[    0.001472] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes)
[    0.002080] ftrace: allocating 46972 entries in 184 pages
[    0.127992] sched-energy: Sched-energy-costs installed from DT
[    0.128003] ASID allocator initialised with 65536 entries
[    0.161059] tegra-id: chipid=21817.
[    0.161065] tegra-id: opt_subrevision=1.
[    0.161081] Tegra Revision: A02p SKU: 0x90 CPU Process: 0 SoC Process: 0
[    0.161089] DTS File Name: tegra186-tx2i-cti-ASG002-revF+.dts
[    0.161094] DTB Build time: Nov 25 2019 13:46:21
[    0.209630] CPU1: Booted secondary processor [4e0f0030]
[    0.241257] CPU2: Booted secondary processor [4e0f0030]
[    0.273032] CPU3: Booted secondary processor [411fd073]
[    0.305000] CPU4: Booted secondary processor [411fd073]
[    0.336991] CPU5: Booted secondary processor [411fd073]
[    0.337046] Brought up 6 CPUs
[    0.337050] SMP: Total of 6 processors activated.
[    0.337055] CPU features: detected feature: 32-bit EL0 Support
[    0.337867] CPU: All CPU(s) started at EL2
[    0.337918] alternatives: patching kernel code
[    0.347429] devtmpfs: initialized
[    0.371029] Initilizing CustomIPI irq domain
[    0.371228] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns
[    0.371239] futex hash table entries: 2048 (order: 5, 131072 bytes)
[    0.372122] pinctrl core: initialized pinctrl subsystem
[    0.372320] OS set in device tree is not L4T.
[    0.372482] regulator-dummy: no parameters
[    0.372666] Initializing plugin-manager
[    0.372725] Plugin module not found
[    0.372760] Plugin-manager status disabled
[    0.373866] NET: Registered protocol family 16
[    0.374555] pstore: using zlib compression
[    0.374569] console [pstore-1] enabled
[    0.374572] pstore: Registered ramoops as persistent store backend
[    0.374576] ramoops: attached 0x200000@0x235880000, ecc: 0/0
[    0.392575] cpuidle: using governor menu
[    0.393557] bpmp: waiting for handshake
[    0.393560] bpmp: handshake completed
[    0.393571] bpmp: synchronizing channels
[    0.393591] bpmp: channels synchronized
[    0.393594] bpmp: mail init ok
[    0.395221] arm-smmu 12000000.iommu: probing hardware configuration...
[    0.395226] arm-smmu 12000000.iommu: SMMUv2 with:
[    0.395230] arm-smmu 12000000.iommu: 	stage 1 translation
[    0.395233] arm-smmu 12000000.iommu: 	stage 2 translation
[    0.395236] arm-smmu 12000000.iommu: 	nested translation
[    0.395242] arm-smmu 12000000.iommu: 	stream matching with 128 register groups, mask 0x7f80
[    0.395247] arm-smmu 12000000.iommu: SMMU address space size (0x800000) differs from mapped region size (0x1000000)!
[    0.395251] arm-smmu 12000000.iommu: 	64 context banks (0 stage-2 only)
[    0.395256] arm-smmu 12000000.iommu: 	Stage-1: 39-bit VA -> 48-bit IPA
[    0.395259] arm-smmu 12000000.iommu: 	Stage-2: 39-bit IPA -> 48-bit PA
[    0.396910] vdso: 2 pages (1 code @ ffffff8008f67000, 1 data @ ffffff8009e44000)
[    0.396923] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers.
[    0.399974] atomic_pool_init():622: DMA: preallocated 1024 KiB pool for atomic allocations
[    0.400883] Registering BPMP clocks...
[    0.400975] tegra_bpmp_clk_init: clock init ok (638 clks)
[    0.402696] tegra_powergate_init: DONE
[    0.402703] DTS File Name: tegra186-tx2i-cti-ASG002-revF+.dts
[    0.402709] DTB Build time: Nov 25 2019 13:46:21
[    0.403992] Tegra reboot handler registered.
[    0.405196] tegra-pmc c360000.pmc: scratch reg offset dts data not present
[    0.405259] tegra-pmc: get_secure_pmc_setting: done secure_pmc=0
[    0.405271] tegra-pmc: ### PMC reset source: TEGRA_SOFTWARE_RESET
[    0.405274] tegra-pmc: ### PMC reset level: TEGRA_RESET_LEVEL_L1
[    0.405276] tegra-pmc: ### PMC reset status reg: 0x2d
[    0.405428] padctrl padctrl.0: Pad control driver tegra-pmc-padctrl registered
[    0.405438] tegra-pmc c360000.pmc: IO padctrl driver initialized
[    0.405719] iommu: Adding device 3460000.sdhci to group 0
[    0.406132] iommu: Adding device 3440000.sdhci to group 1
[    0.406536] iommu: Adding device 3400000.sdhci to group 2
[    0.407604] iommu: Adding device 3507000.ahci-sata to group 3
[    0.407618] arm-smmu: forcing sodev map for 3507000.ahci-sata
[    0.408015] iommu: Adding device 3160000.i2c to group 4
[    0.408413] iommu: Adding device c240000.i2c to group 5
[    0.408759] iommu: Adding device 3180000.i2c to group 6
[    0.409072] iommu: Adding device 3190000.i2c to group 7
[    0.409463] iommu: Adding device 31b0000.i2c to group 8
[    0.409755] iommu: Adding device 31c0000.i2c to group 9
[    0.410044] iommu: Adding device c250000.i2c to group 10
[    0.410330] iommu: Adding device 31e0000.i2c to group 11
[    0.412254] iommu: Adding device 3210000.spi to group 12
[    0.412636] iommu: Adding device c260000.spi to group 13
[    0.412975] iommu: Adding device 3240000.spi to group 14
[    0.413675] iommu: Adding device 3100000.serial to group 15
[    0.413979] iommu: Adding device 3110000.serial to group 16
[    0.414322] iommu: Adding device c280000.serial to group 17
[    0.414614] iommu: Adding device 3130000.serial to group 18
[    0.415853] iommu: Adding device 2490000.ether_qos to group 19
[    0.415865] arm-smmu: forcing sodev map for 2490000.ether_qos
[    0.416632] iommu: Adding device b000000.rtcpu to group 20
[    0.418467] iommu: Adding device 13e10000.host1x to group 21
[    0.418777] iommu: Adding device 13e10000.host1x:ctx0 to group 22
[    0.419081] iommu: Adding device 13e10000.host1x:ctx1 to group 23
[    0.419371] iommu: Adding device 13e10000.host1x:ctx2 to group 24
[    0.419662] iommu: Adding device 13e10000.host1x:ctx3 to group 25
[    0.419959] iommu: Adding device 13e10000.host1x:ctx4 to group 26
[    0.420258] iommu: Adding device 13e10000.host1x:ctx5 to group 27
[    0.420588] iommu: Adding device 13e10000.host1x:ctx6 to group 28
[    0.420888] iommu: Adding device 13e10000.host1x:ctx7 to group 29
[    0.421348] iommu: Adding device 150c0000.nvcsi to group 30
[    0.421925] iommu: Adding device 15700000.vi to group 31
[    0.422249] iommu: Adding device 15600000.isp to group 32
[    0.422676] iommu: Adding device 15210000.nvdisplay to group 33
[    0.423182] iommu: Adding device 15340000.vic to group 34
[    0.423380] iommu: Adding device 154c0000.nvenc to group 35
[    0.423585] iommu: Adding device 15480000.nvdec to group 36
[    0.423893] iommu: Adding device 15380000.nvjpg to group 37
[    0.424087] iommu: Adding device 15500000.tsec to group 38
[    0.424283] iommu: Adding device 15100000.tsecb to group 39
[    0.424933] iommu: Adding device 15810000.se to group 40
[    0.425135] iommu: Adding device 15820000.se to group 41
[    0.425329] iommu: Adding device 15830000.se to group 42
[    0.425534] iommu: Adding device 15840000.se to group 43
[    0.426304] iommu: Adding device smmu_test to group 44
[    0.426879] mc: mapped MMIO address: 0xffffff800a690000 -> 0x2c10000
[    0.426890] mc: mapped MMIO address: 0xffffff800a6b0000 -> 0x2c20000
[    0.426903] mc: mapped MMIO address: 0xffffff800a6d0000 -> 0x2c30000
[    0.426915] mc: mapped MMIO address: 0xffffff800a7b0000 -> 0x2c40000
[    0.426924] mc: mapped MMIO address: 0xffffff800a7d0000 -> 0x2c50000
[    0.426932] nv-tegra-mc 2c10000.mc: No mssnvlink node
[    0.426942] mc-err: mcerr ops are set to t18x
[    0.427076] dram-ecc: DRAM ECC enabled-MC_ECC_CONTROL:0x0000000d
[    0.427097] dram-ecc: SCE-R5 is booted with Camera FW
[    0.427100] dram-ecc: DRAM ECC interrupts handled in Camera FW
[    0.428087] iommu: Adding device 3530000.xhci to group 45
[    0.428099] arm-smmu: forcing sodev map for 3530000.xhci
[    0.428506] iommu: Adding device 3550000.xudc to group 46
[    0.428518] arm-smmu: forcing sodev map for 3550000.xudc
[    0.429241] tegra186-aowake c370000.pmc: WAKE_AOWAKE_CTRL_0 = 3
[    0.429246] tegra186-aowake c370000.pmc: WAKE_AOWAKE_CNTRL_24(PMU_INT) = 256
[    0.429765] iommu: Adding device 17000000.gp10b to group 47
[    0.430699] tegra-reset 5000000.clock: registered 193 resets.
[    0.432648] iommu: Adding device d000000.bpmp to group 48
[    0.432860] bpmp: ping status is 0
[    0.432964] bpmp d000000.bpmp: firmware tag is 4208eb5c85fe47a2235fbc0b8eb87dca
[    0.434939] bpmp d000000.bpmp: probe ok
[    0.437633] iommu: Adding device 2600000.dma to group 49
[    0.438718] GPIO line 459 (pcie0_lane2_mux) hogged as output/low
[    0.438739] GPIO line 445 (sdmmc-wake-support-input) hogged as input
[    0.438758] GPIO line 446 (sdmmc-wake-support-output) hogged as output/low
[    0.438951] gpiochip_setup_dev: registered GPIOs 320 to 511 on device: gpiochip0 (tegra-gpio)
[    0.442700] gpiochip_setup_dev: registered GPIOs 256 to 319 on device: gpiochip1 (tegra-gpio-aon)
[    0.444209] iommu: Adding device 10003000.pcie-controller to group 50
[    0.444225] arm-smmu: forcing sodev map for 10003000.pcie-controller
[    0.444530] iommu: Adding device sound to group 51
[    0.444848] iommu: Adding device 3510000.hda to group 52
[    0.451780] iommu: Adding device c1a0000.aon to group 53
[    0.452231] vdd-ac-bat: 5000 mV 
[    0.453958] vdd-1v8-ap: 1800 mV 
[    0.486661] HugeTLB registered 2 MB page size, pre-allocated 0 pages
[    0.492527] eventlib_kernel: keventlib is initialized, test id: 0
[    0.493042] SCSI subsystem initialized
[    0.493312] libata version 3.00 loaded.
[    0.493584] usbcore: registered new interface driver usbfs
[    0.493722] usbcore: registered new interface driver hub
[    0.493854] usbcore: registered new device driver usb
[    0.497288] random: fast init done
[    0.510209] max77620 4-003c: PMIC Version OTP:0x4D and ES:0x1
[    0.517158] vddio-ddr: at 1125 mV 
[    0.521659] avdd_dsi_csi: 1200 mV 
[    0.525014] vdd-1v8: 1800 mV 
[    0.529014] vdd-3v3-sys: 3300 mV 
[    0.529570] max77620-sd4: at 600 mV 
[    0.530390] spmic-ldo0: at 1800 mV 
[    0.531074] spmic-ldo1: at 800 mV 
[    0.533013] vddio-3v3: 3300 mV 
[    0.533599] vddio-sdmmc1: 1800 <--> 3300 mV at 3300 mV 
[    0.537015] vdd-rtc: at 800 mV 
[    0.537615] avdd-ts-hv: 1800 <--> 3300 mV at 1800 mV 
[    0.541015] spmic-ldo6: at 1500 mV 
[    0.545013] vdd-pex-1v00: 1000 mV 
[    0.545638] dvdd-pex: 1000 mV 
[    0.546129] GPIO line 253 (spmic_gpio_input) hogged as input
[    0.546273] GPIO line 254 (spmic_gpio_input) hogged as input
[    0.546461] gpiochip_setup_dev: registered GPIOs 248 to 255 on device: gpiochip2 (max77620-gpio)
[    0.548400] max77620 4-003c: max77620 probe successful
[    0.548870] media: Linux media interface: v0.10
[    0.548901] Linux video capture interface: v2.00
[    0.552447] pps_core: LinuxPPS API ver. 1 registered
[    0.552450] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>
[    0.552465] PTP clock support registered
[    0.552841] tegra_wdt_t18x 30c0000.watchdog: Tegra WDT init timeout = 120 sec
[    0.552870] tegra_wdt_t18x 30c0000.watchdog: Registered successfully
[    0.554589] tegra_fiq_debugger_init: found FIQ source (IRQ 386)
[    0.558751] trusty trusty: trusty version: Built: 16:57:40 Jul 16 2019 
[    0.558764] trusty trusty: selected api version: 3 (requested 3)
[    0.560041] Advanced Linux Sound Architecture Driver Initialized.
[    0.560553] Bluetooth: Core ver 2.22
[    0.560599] NET: Registered protocol family 31
[    0.560604] Bluetooth: HCI device and connection manager initialized
[    0.560616] Bluetooth: HCI socket layer initialized
[    0.560625] Bluetooth: L2CAP socket layer initialized
[    0.560650] Bluetooth: SCO socket layer initialized
[    0.561909] en-vdd-sd: 3300 mV 
[    0.562316] en-vdd-cam: 1800 mV 
[    0.562585] vdd-hdmi: 5000 mV 
[    0.562948] vdd-usb0-5v: 5000 mV 
[    0.563311] vdd-usb1-5v: 5000 mV 
[    0.563592] en-vdd-ts-1v8: 1800 mV 
[    0.563895] en-vdd-ts-hv-3v3: 3300 mV 
[    0.564194] en-vdd-disp-3v3: 3300 mV 
[    0.564499] en-mdm-pwr-3v7: 3700 mV 
[    0.564816] en-vdd-disp-1v8: 1800 mV 
[    0.565127] en-vdd-cam-hv-2v8: 2800 mV 
[    0.565437] en-vdd-cam-1v2: 1200 mV 
[    0.565855] vdd-fan: 5000 mV 
[    0.566160] vdd-3v3: 3300 mV 
[    0.566601] dis-vdd-1v2: 1200 mV 
[    0.566932] en-vdd-vcm-2v8: 2800 mV 
[    0.567242] vdd-usb2-5v: 5000 mV 
[    0.567635] vdd-sys-bl: 3300 mV 
[    0.568003] en-vdd-sys: 1200 mV 
[    0.570744] camchar: rtcpu character device driver loaded
[    0.571314] gpio tegra-gpio wake53 for gpio=159(X:7)
[    0.573332] extcon-gpio-states external-connection:extcon@1: Cable state:1, cable id:2
[    0.574815] clocksource: Switched to clocksource arch_sys_counter
[    0.619298] VFS: Disk quotas dquot_6.6.0
[    0.619528] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)
[    0.620669] tegra-pcie 10003000.pcie-controller: 2x1, 1x1, 1x1 configuration
[    0.621663] nvmap_heap_init: nvmap_heap_init: created heap block cache
[    0.622048] nvmap: nvmap_select_cache_ops() nvmap cache ops set to roc
[    0.622053] nvmap_page_pool_init: Total RAM pages: 1750845
[    0.622057] nvmap_page_pool_init: nvmap page pool size: 218855 pages (854 MB)
[    0.622111] nvmap_background_zero_thread: PP zeroing thread starting.
[    0.622625] tegra-pcie 10003000.pcie-controller: PCIE: Enable power rails
[    0.623169] tegra-pcie 10003000.pcie-controller: probing port 0, using 2 lanes
[    0.625348] tegra-pcie 10003000.pcie-controller: probing port 1, using 1 lanes
[    0.626982] tegra-pcie 10003000.pcie-controller: probing port 2, using 1 lanes
[    0.627882] tegra-gpcdma 2600000.dma: GPC DMA driver register 32 channels
[    0.639000] thermal thermal_zone0: Registering thermal zone thermal_zone0 for type BCPU-therm
[    0.639209] thermal thermal_zone1: Registering thermal zone thermal_zone1 for type MCPU-therm
[    0.639587] thermal thermal_zone2: Registering thermal zone thermal_zone2 for type GPU-therm
[    0.639689] thermal thermal_zone3: Registering thermal zone thermal_zone3 for type PLL-therm
[    0.639840] thermal thermal_zone4: Registering thermal zone thermal_zone4 for type Tboard_tegra
[    0.639993] thermal thermal_zone5: Registering thermal zone thermal_zone5 for type Tdiode_tegra
[    0.640164] thermal thermal_zone6: Registering thermal zone thermal_zone6 for type PMIC-Die
[    0.640375] la/ptsa driver initialized.
[    0.640388] pre_t19x_iso_plat_init(): iso emc max clk=1600000KHz
[    0.640394] pre_t19x_iso_plat_init(): max_iso_bw=7680000KB
[    0.641615] NET: Registered protocol family 2
[    0.643759] TCP established hash table entries: 65536 (order: 7, 524288 bytes)
[    0.644342] TCP bind hash table entries: 65536 (order: 8, 1048576 bytes)
[    0.645152] TCP: Hash tables configured (established 65536 bind 65536)
[    0.645212] UDP hash table entries: 4096 (order: 5, 131072 bytes)
[    0.645340] UDP-Lite hash table entries: 4096 (order: 5, 131072 bytes)
[    0.646292] NET: Registered protocol family 1
[    0.647321] RPC: Registered named UNIX socket transport module.
[    0.647328] RPC: Registered udp transport module.
[    0.647333] RPC: Registered tcp transport module.
[    0.647338] RPC: Registered tcp NFSv4.1 backchannel transport module.
[    0.647504] PCI: CLS 0 bytes, default 64
[    0.647785] Trying to unpack rootfs image as initramfs...
[    0.841961] Freeing initrd memory: 5432K
[    0.860688] host1x 13e10000.host1x: initialized
[    0.862018] hw perfevents: enabled with armv8_pmuv3 PMU driver, 7 counters available
[    0.866758] audit: initializing netlink subsys (disabled)
[    0.866798] audit: type=2000 audit(0.752:1): initialized
[    0.867622] workingset: timestamp_bits=46 max_order=21 bucket_order=0
[    0.879754] squashfs: version 4.0 (2009/01/31) Phillip Lougher
[    0.882424] ntfs: driver 2.1.32 [Flags: R/W].
[    0.884015] 9p: Installing v9fs 9p2000 file system support
[    0.889967] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 240)
[    0.890205] io scheduler noop registered
[    0.890905] io scheduler cfq registered (default)
[    0.895757] gic 2a41000.agic-controller: GIC IRQ controller registered
[    0.954906] iommu: Adding device aconnect@2a41000:adsp_audio to group 54
[    0.992541] iommu: Adding device 2993000.adsp to group 55
[    0.993725] tegra-aconnect aconnect@2a41000: Tegra ACONNECT bus registered
[    0.997378] tegra-xusb-padctl 3520000.xusb_padctl: VBUS over-current detection enabled
[    1.001038] tegra-pwm 3280000.pwm: PWM clk can sleep in ops
[    1.002479] tegra-pwm 3290000.pwm: PWM clk can sleep in ops
[    1.003865] tegra-pwm 32a0000.pwm: PWM clk can sleep in ops
[    1.005130] tegra-pwm c340000.pwm: PWM clk can sleep in ops
[    1.008373] tegra_camera_platform tegra-camera-platform: tegra_camera_probe:camera_platform_driver probe
[    1.008553] misc tegra_camera_ctrl: tegra_camera_isomgr_register: some fields not in DT.
[    1.008564] misc tegra_camera_ctrl: tegra_camera_isomgr_register isp_iso_bw=0, vi_iso_bw=750000, max_bw=750000
[    1.011719] tsec 15500000.tsec: initialized
[    1.012932] tsec 15100000.tsecb: initialized
[    1.016126] nvdec 15480000.nvdec: initialized
[    1.021409] falcon 15340000.vic: initialized
[    1.023002] falcon 154c0000.nvenc: initialized
[    1.024397] falcon 15380000.nvjpg: initialized
[    1.028919] iommu_context_dev 13e10000.host1x:ctx0: initialized (streamid=56)
[    1.030865] iommu_context_dev 13e10000.host1x:ctx1: initialized (streamid=57)
[    1.032444] iommu_context_dev 13e10000.host1x:ctx2: initialized (streamid=58)
[    1.034002] iommu_context_dev 13e10000.host1x:ctx3: initialized (streamid=59)
[    1.035629] iommu_context_dev 13e10000.host1x:ctx4: initialized (streamid=60)
[    1.037190] iommu_context_dev 13e10000.host1x:ctx5: initialized (streamid=61)
[    1.038701] iommu_context_dev 13e10000.host1x:ctx6: initialized (streamid=62)
[    1.040279] iommu_context_dev 13e10000.host1x:ctx7: initialized (streamid=63)
[    1.043259] tegradccommon 15200000.dc_common: host1x channel mapped
[    1.043274] tegradccommon 15200000.dc_common: dc_common syncpt # 1 allocated
[    1.043315] tegradccommon 15200000.dc_common: dma mapping done
[    1.044326] tegradc 15210000.nvdisplay: disp0 connected to head1->/host1x/sor1
[    1.044409] generic_infoframe_type: 0x87
[    1.044543] tegradc 15210000.nvdisplay: DT parsed successfully
[    1.044591] tegradc 15210000.nvdisplay: Display dc.ffffff800b690000 registered with id=0
[    1.044912] of_fixed_clk: probe of can_clock failed with error -17
[    1.050848] tegra-pcie 10003000.pcie-controller: link 0 down, retrying
[    1.053288] tegra_nvdisp_bandwidth_register_max_config: max config iso bw = 5924200 KB/s
[    1.053296] tegra_nvdisp_bandwidth_register_max_config: max config EMC floor = 204000000 Hz
[    1.053301] tegra_nvdisp_bandwidth_register_max_config: max config hubclk = 207380000 Hz
[    1.053611] tegradc 15210000.nvdisplay: vblank syncpt # 8 for dc 1
[    1.053622] tegradc 15210000.nvdisplay: vpulse3 syncpt # 9 for dc 1
[    1.055023] tegra-adma 2930000.adma: Tegra210 ADMA driver registered 10 channels
[    1.056481] tegra-fuse-burn 3820000.efuse:efuse-burn: Fuse burn driver initialized
[    1.057200] kfuse 3830000.kfuse: initialized
[    1.058037] tegradc 15210000.nvdisplay: hdmi: invalid prod list prod_list_hdmi_board
[    1.058045] tegradc 15210000.nvdisplay: hdmi: tegra_hdmi_tmds_range_read(bd) failed
[    1.059175] tegra-pmc-iopower pmc-iopower: NO_IOPOWER setting 0x0
[    1.063929] tegradc 15210000.nvdisplay: probed
[    1.067644] Serial: 8250/16550 driver, 4 ports, IRQ sharing disabled
[    1.070944] console [ttyS0] disabled
[    1.071014] 3100000.serial: ttyS0 at MMIO 0x3100000 (irq = 36, base_baud = 25500000) is a Tegra
[    1.071115] console [ttyS0] enabled
[    1.072835] 3110000.serial: ttyTHS1 at MMIO 0x3110000 (irq = 37, base_baud = 0) is a TEGRA_UART
[    1.073938] c280000.serial: ttyTHS2 at MMIO 0xc280000 (irq = 38, base_baud = 0) is a TEGRA_UART
[    1.074163] serial-tegra 3130000.serial: RX in PIO mode
[    1.074866] Console: switching to colour frame buffer device 80x30
[    1.074889] tegradc 15210000.nvdisplay: fb registered
[    1.075077] 3130000.serial: ttyTHS3 at MMIO 0x3130000 (irq = 39, base_baud = 0) is a TEGRA_UART
[    1.075088] gpio tegra-gpio wake21 for gpio=121(P:1)
[    1.076432] [drm] Initialized
[    1.086950] brd: module loaded
[    1.092023] loop: module loaded
[    1.092121] tegra_profiler: version: 1.134, samples/io: 47/26
[    1.092252] tegra_profiler: auth: init
[    1.092821] nct1008_nct72 7-004c: starting parse dt
[    1.092964] nct1008_nct72 7-004c: !!!Found deprecated property!!!
[    1.092976] nct1008_nct72 7-004c: success parsing dt
[    1.093144] nct1008_nct72 7-004c: success in enabling tmp451 VDD rail
[    1.093177] THERMAL EST: found 3 subdevs
[    1.093185] THERMAL EST num_resources: 0
[    1.093191] [THERMAL EST subdev 0]
[    1.093198] [THERMAL EST subdev 1]
[    1.093204] [THERMAL EST subdev 2]
[    1.093502] thermal thermal_zone7: Registering thermal zone thermal_zone7 for type thermal-fan-est
[    1.093507] THERMAL EST: thz register success.
[    1.093646] THERMAL EST: end of probe, return err: 0
[    1.094712] hisi_sas: driver version v1.6
[    1.098713] tegra-ahci 3507000.ahci-sata: AHCI 0001.0301 32 slots 2 ports 3 Gbps 0x1 impl platform mode
[    1.098728] tegra-ahci 3507000.ahci-sata: flags: 64bit ncq sntf pm led pmp pio slum part deso sadm apst 
[    1.099703] scsi host0: tegra_ahci
[    1.100689] scsi host1: tegra_ahci
[    1.100881] ata1: SATA max UDMA/133 mmio [mem 0x03507000-0x03508fff] port 0x100 irq 24
[    1.100923] ata2: DUMMY
[    1.106956] libphy: Fixed MDIO Bus: probed
[    1.107433] tun: Universal TUN/TAP device driver, 1.6
[    1.107439] tun: (C) 1999-2004 Max Krasnyansky <maxk@qualcomm.com>
[    1.108742] e1000e: Intel(R) PRO/1000 Network Driver - 3.2.6-k
[    1.108748] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.
[    1.108801] igb: Intel(R) Gigabit Ethernet Network Driver - version 5.4.0-k
[    1.108806] igb: Copyright (c) 2007-2014 Intel Corporation.
[    1.108839] igbvf: Intel(R) Gigabit Virtual Function Network Driver - version 2.4.0-k
[    1.108843] igbvf: Copyright (c) 2009 - 2012 Intel Corporation.
[    1.108882] Intel(R) 10GbE PCI Express Linux Network Driver - version 4.6.4
[    1.108887] Copyright(c) 1999 - 2017 Intel Corporation.
[    1.110262] eqos 2490000.ether_qos: can't get pllrefe_vcoout clk (-2)
[    1.113972] eqos 2490000.ether_qos: Setting local MAC: 0 4 4b a9 2c bb
[    1.114102] libphy: dwc_phy: probed
[    1.114534] bcm54xx_low_power_mode(): put phy in iddq-lp mode
[    1.116905] PPP generic driver version 2.4.2
[    1.117105] PPP BSD Compression module registered
[    1.117111] PPP Deflate Compression module registered
[    1.117159] PPP MPPE Compression module registered
[    1.117165] NET: Registered protocol family 24
[    1.117224] usbcore: registered new interface driver r8152
[    1.117265] usbcore: registered new interface driver asix
[    1.117293] usbcore: registered new interface driver ax88179_178a
[    1.117318] usbcore: registered new interface driver cdc_ether
[    1.117346] usbcore: registered new interface driver net1080
[    1.117371] usbcore: registered new interface driver cdc_subset
[    1.117395] usbcore: registered new interface driver zaurus
[    1.117429] usbcore: registered new interface driver cdc_ncm
[    1.117626] VFIO - User Level meta-driver version: 0.3
[    1.119325] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
[    1.119344] ehci-pci: EHCI PCI platform driver
[    1.119389] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver
[    1.119399] ohci-pci: OHCI PCI platform driver
[    1.119431] ohci-platform: OHCI generic platform driver
[    1.123878] tegra-xusb 3530000.xhci: USB2 port 0 has OTG_CAP
[    1.124558] nct1008_nct72 7-004c: LOC shutdown limit 110
[    1.125108] nct1008_nct72 7-004c: EXT shutdown limit 110
[    1.125697] tegra-xusb 3530000.xhci: extcon 0: ffffffc1ac77bc00 id
[    1.126491] usbcore: registered new interface driver uas
[    1.126535] usbcore: registered new interface driver usb-storage
[    1.126608] usbcore: registered new interface driver usbserial
[    1.128164] tegra-xusb 3530000.xhci: Firmware timestamp: 2018-12-26 10:30:04 UTC, Version: 55.12 release
[    1.130211] tegra-xudc-new 3550000.xudc: device count: 1
[    1.131384] tegra-xudc-new 3550000.xudc: vbus state: 0
[    1.131707] tegra-xudc-new 3550000.xudc: entering ELPG
[    1.132022] mousedev: PS/2 mouse device common for all mice
[    1.132123] usbcore: registered new interface driver xpad
[    1.132858] tegra-xudc-new 3550000.xudc: entering ELPG done
[    1.154911] tegra-xusb 3530000.xhci: xHCI Host Controller
[    1.154934] tegra-xusb 3530000.xhci: new USB bus registered, assigned bus number 1
[    1.155776] tegra-xusb 3530000.xhci: hcc params 0x0184fd25 hci version 0x100 quirks 0x00050810
[    1.155820] tegra-xusb 3530000.xhci: irq 70, io mem 0x03530000
[    1.156065] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002
[    1.156073] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    1.156080] usb usb1: Product: xHCI Host Controller
[    1.156085] usb usb1: Manufacturer: Linux 4.9.140-tegra-tegra xhci-hcd
[    1.156091] usb usb1: SerialNumber: 3530000.xhci
[    1.156574] hub 1-0:1.0: USB hub found
[    1.156619] hub 1-0:1.0: 4 ports detected
[    1.157016] tegra-xusb 3530000.xhci: xHCI Host Controller
[    1.157028] tegra-xusb 3530000.xhci: new USB bus registered, assigned bus number 2
[    1.157047] nct1008_nct72 7-004c: initial LOC temp: 41000 
[    1.157256] usb usb2: New USB device found, idVendor=1d6b, idProduct=0003
[    1.157264] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    1.157269] usb usb2: Product: xHCI Host Controller
[    1.157274] usb usb2: Manufacturer: Linux 4.9.140-tegra-tegra xhci-hcd
[    1.157279] usb usb2: SerialNumber: 3530000.xhci
[    1.157381] nct1008_nct72 7-004c: initial EXT temp: 43250 
[    1.157632] hub 2-0:1.0: USB hub found
[    1.157674] hub 2-0:1.0: 3 ports detected
[    1.158039] nct1008_nct72 7-004c: hi_b:-11, lo_b:0
[    1.158447] gpio tegra-gpio-aon wake35 for gpio=16(V:0)
[    1.158647] nct1008_nct72 7-004c: nct1008_probe: initialized
[    1.251908] max77686-rtc max77620-rtc: rtc core: registered max77620-rtc as rtc0
[    1.253212] Wake73 for irq=42
[    1.254533] rtc rtc1: alarm rtc device
[    1.254551] tegra_rtc c2a0000.rtc: rtc core: registered c2a0000.rtc as rtc1
[    1.254577] tegra_rtc c2a0000.rtc: Tegra internal Real Time Clock
[    1.254791] i2c /dev entries driver
[    1.257989] thermoframe 2-0010: probing v4l2 sensor
[    1.258140] thermoframe 2-0010: tegracam sensor driver:thermoframe_v2.0.6
[    1.258176] thermoframe 2-0010: Detected Thermoframe sensor
[    1.258623] max77620-power max20024-power: Event recorder REG_NVERC : 0x0
[    1.259071] usb usb1: usb_suspend_both: status 0
[    1.264316] parse_throttle_dt_data: Num cap clks = 4
[    1.264325] parse_throttle_dt_data: clk=mcpu type=1
[    1.264330] parse_throttle_dt_data: clk=bcpu type=2
[    1.264340] parse_throttle_dt_data: clk=gpu type=4
[    1.264345] parse_throttle_dt_data: clk=emc type=3
[    1.265419] tegra_throttle_probe: probe successful. #cdevs=4
[    1.266005] FAN dev name: pwm-fan
[    1.266060] FAN:gpio request success.
[    1.266071] FAN: can't find tach_gpio
[    1.266138] pwm_fan_driver pwm-fan: cap state:7, cap pwm:255
[    1.266433] pwm_fan_driver pwm-fan: got pwm for fan. polarity is inversed
[    1.266440] pwm_fan_driver pwm-fan: tach period: 1000
[    1.266791] pwm_fan_driver pwm-fan: index 0: pwm=0, rpm=0, rru=40, rrd=40, state:2
[    1.266870] pwm_fan_driver pwm-fan: index 1: pwm=80, rpm=1000, rru=2, rrd=2, state:2
[    1.266877] pwm_fan_driver pwm-fan: index 2: pwm=120, rpm=2000, rru=1, rrd=1, state:2
[    1.266883] pwm_fan_driver pwm-fan: index 3: pwm=160, rpm=3000, rru=1, rrd=1, state:2
[    1.266888] pwm_fan_driver pwm-fan: index 4: pwm=255, rpm=4000, rru=1, rrd=1, state:3
[    1.266894] pwm_fan_driver pwm-fan: index 5: pwm=255, rpm=5000, rru=1, rrd=1, state:3
[    1.266899] pwm_fan_driver pwm-fan: index 6: pwm=255, rpm=6000, rru=1, rrd=1, state:3
[    1.266905] pwm_fan_driver pwm-fan: index 7: pwm=255, rpm=7000, rru=1, rrd=1, state:4
[    1.266912] pwm_fan_driver pwm-fan: index 8: pwm=255, rpm=10000, rru=1, rrd=1, state:4
[    1.266918] pwm_fan_driver pwm-fan: index 9: pwm=255, rpm=11000, rru=1, rrd=1, state:4
[    1.268861] device-mapper: uevent: version 1.0.3
[    1.269785] device-mapper: ioctl: 4.35.0-ioctl (2016-06-23) initialised: dm-devel@redhat.com
[    1.272616] cpufreq: platform driver Initialization: pass
[    1.273269] cpuidle: Initializing cpuidle driver init for Denver cluster
[    1.273383] cpuidle: Initializing cpuidle driver init for A57 cluster
[    1.274275] sdhci: Secure Digital Host Controller Interface driver
[    1.274279] sdhci: Copyright(c) Pierre Ossman
[    1.274282] sdhci-pltfm: SDHCI platform and OF driver helper
[    1.275729] sdhci-tegra 3460000.sdhci: Client registration for eMC Successful
[    1.277688] CMDQ: cmdq_platfm_init successful
[    1.278775] CQE: CMD_TIMING bit set for R1B DCMD
[    1.279079] sdhci-tegra 3440000.sdhci: Client registration for eMC Successful
[    1.284305] sdhci-tegra 3400000.sdhci: Got CD GPIO
[    1.284352] sdhci-tegra 3400000.sdhci: Got WP GPIO
[    1.285646] sdhci-tegra 3400000.sdhci: Client registration for eMC Successful
[    1.288474] sdhci-tegra 3400000.sdhci: wakeup init done, cdirq 257
[    1.292377] tegra-se-elp 3ad0000.se_elp: tegra_se_elp_probe: complete
[    1.293557] tegra-se-nvhost 15810000.se: initialized
[    1.294697] tegra-se-nvhost 15810000.se: tegra_se_probe: complete
[    1.295756] tegra-se-nvhost 15820000.se: initialized
[    1.296773] tegra-se-nvhost 15820000.se: tegra_se_probe: complete
[    1.297568] tegra-se-nvhost 15830000.se: initialized
[    1.298133] tegra-se-nvhost 15830000.se: tegra_se_probe: complete
[    1.299003] tegra-se-nvhost 15840000.se: initialized
[    1.299804] tegra-se-nvhost 15840000.se: tegra_se_probe: complete
[    1.300178] hidraw: raw HID events driver (C) Jiri Kosina
[    1.303604] usbcore: registered new interface driver usbhid
[    1.303609] usbhid: USB HID core driver
[    1.307763] tegra-i2c 3160000.i2c: no acknowledge from address 0x42
[    1.307793] ina3221x 0-0042: ina3221 reset failure status: 0xffffff87
[    1.314468] ina3221x: probe of 0-0042 failed with error -121
[    1.314824] tegra-i2c 3160000.i2c: no acknowledge from address 0x43
[    1.314872] ina3221x 0-0043: ina3221 reset failure status: 0xffffff87
[    1.321564] ina3221x: probe of 0-0043 failed with error -121
[    1.322872] mmc0: SDHCI controller on 3460000.sdhci [3460000.sdhci] using ADMA 64-bit with 64 bit addr
[    1.324214] tegra186-cam-rtcpu b000000.rtcpu: Trace buffer configured at IOVA=0xbff00000
[    1.327016] tegra-ivc ivc-b000000.rtcpu: region 0: iova=0xbfef0000-0xbfefdb7f size=56192
[    1.327186] mmc1: SDHCI controller on 3440000.sdhci [3440000.sdhci] using ADMA 64-bit with 64 bit addr
[    1.327335] tegra-ivc ivc-b000000.rtcpu:echo@0: echo: ver=0 grp=1 RX[16x64]=0x1000-0x1480 TX[16x64]=0x1480-0x1900
[    1.327544] tegra-ivc ivc-b000000.rtcpu:i2c@480: i2c: ver=0 grp=1 RX[8x128]=0x1900-0x1d80 TX[8x128]=0x1d80-0x2200
[    1.327932] tegra-ivc ivc-b000000.rtcpu:vinotify@12c0: vinotify: ver=0 grp=1 RX[64x128]=0x2200-0x4280 TX[64x128]=0x4280-0x6300
[    1.332569] tegra-ivc ivc-b000000.rtcpu:mods@32c0: mods: ver=0 grp=1 RX[1x64]=0x6300-0x63c0 TX[1x64]=0x63c0-0x6480
[    1.332680] tegra-ivc ivc-b000000.rtcpu:ivccontrol@52c0: ivccontrol: ver=0 grp=1 RX[16x320]=0x6480-0x7900 TX[16x320]=0x7900-0x8d80
[    1.332744] tegra-ivc ivc-b000000.rtcpu:ivccapture@72c0: ivccapture: ver=0 grp=1 RX[16x64]=0x8d80-0x9200 TX[16x64]=0x9200-0x9680
[    1.332799] tegra-ivc ivc-b000000.rtcpu:dbg@7c00: dbg: ver=0 grp=1 RX[1x384]=0x9680-0x9880 TX[1x384]=0x9880-0x9a80
[    1.332972] tegra-ivc ivc-b000000.rtcpu:dbg@7e00: dbg: ver=0 grp=1 RX[1x8192]=0x9a80-0xbb00 TX[1x8192]=0xbb00-0xdb80
[    1.333591] tegra186-cam-rtcpu b000000.rtcpu: using cam RTCPU IRQ (51)
[    1.333597] tegra186-cam-rtcpu b000000.rtcpu: tegra_camrtc_mon_create is successful
[    1.334577] tegra186-cam-rtcpu b000000.rtcpu: firmware version cpu=sce cmd=5 sha1=3ecb1dca25fa98771f988f238c025334776c9a96
[    1.334870] gpio tegra-gpio wake71 for gpio=125(P:5)
[    1.334916] mmc2: SDHCI controller on 3400000.sdhci [3400000.sdhci] using ADMA 64-bit with 64 bit addr
[    1.336363] tegra_aon c1a0000.aon: tegra aon driver probe OK
[    1.336815] tegra186-aondbg aondbg: aondbg driver probe() OK
[    1.336978] register_ari_mca_banks: Registered MCA ROC:IOB
[    1.337054] register_ari_mca_banks: Registered MCA ROC:CCE
[    1.337378] tegra18-bridge 2390000.axi2apb: axi_cbb clk rate = 172 MHZ, timeout = 97000 useconds
[    1.337382] tegra18-bridge 2390000.axi2apb: enabled timeout = 16684000
[    1.337384] tegra18-bridge 2390000.axi2apb: bridge probed OK
[    1.337437] tegra18-bridge 23a0000.axi2apb: axi_cbb clk rate = 172 MHZ, timeout = 97000 useconds
[    1.337440] tegra18-bridge 23a0000.axi2apb: enabled timeout = 16684000
[    1.337442] tegra18-bridge 23a0000.axi2apb: bridge probed OK
[    1.337492] tegra18-bridge 23b0000.axi2apb: axi_cbb clk rate = 172 MHZ, timeout = 97000 useconds
[    1.337494] tegra18-bridge 23b0000.axi2apb: enabled timeout = 16684000
[    1.337496] tegra18-bridge 23b0000.axi2apb: bridge probed OK
[    1.337542] tegra18-bridge 23c0000.axi2apb: axi_cbb clk rate = 172 MHZ, timeout = 97000 useconds
[    1.337545] tegra18-bridge 23c0000.axi2apb: enabled timeout = 16684000
[    1.337547] tegra18-bridge 23c0000.axi2apb: bridge probed OK
[    1.337592] tegra18-bridge 23d0000.axi2apb: axi_cbb clk rate = 172 MHZ, timeout = 97000 useconds
[    1.337594] tegra18-bridge 23d0000.axi2apb: enabled timeout = 16684000
[    1.337596] tegra18-bridge 23d0000.axi2apb: bridge probed OK
[    1.337655] tegra18-bridge 2100000.axip2p: axi_cbb clk rate = 172 MHZ, timeout = 97000 useconds
[    1.337659] tegra18-bridge 2100000.axip2p: enabled timeout = 16684000
[    1.337661] tegra18-bridge 2100000.axip2p: bridge probed OK
[    1.337708] tegra18-bridge 2110000.axip2p: axi_cbb clk rate = 172 MHZ, timeout = 97000 useconds
[    1.337717] tegra18-bridge 2110000.axip2p: enabled timeout = 16684000
[    1.337719] tegra18-bridge 2110000.axip2p: bridge probed OK
[    1.337785] tegra18-bridge 2120000.axip2p: axi_cbb clk rate = 172 MHZ, timeout = 97000 useconds
[    1.337790] tegra18-bridge 2120000.axip2p: enabled timeout = 16684000
[    1.337792] tegra18-bridge 2120000.axip2p: bridge probed OK
[    1.337836] tegra18-bridge 2130000.axip2p: axi_cbb clk rate = 172 MHZ, timeout = 97000 useconds
[    1.337840] tegra18-bridge 2130000.axip2p: enabled timeout = 16684000
[    1.337842] tegra18-bridge 2130000.axip2p: bridge probed OK
[    1.337885] tegra18-bridge 2140000.axip2p: axi_cbb clk rate = 172 MHZ, timeout = 97000 useconds
[    1.337887] tegra18-bridge 2140000.axip2p: enabled timeout = 16684000
[    1.337889] tegra18-bridge 2140000.axip2p: bridge probed OK
[    1.337935] tegra18-bridge 2150000.axip2p: axi_cbb clk rate = 172 MHZ, timeout = 97000 useconds
[    1.337939] tegra18-bridge 2150000.axip2p: enabled timeout = 16684000
[    1.337941] tegra18-bridge 2150000.axip2p: bridge probed OK
[    1.337987] tegra18-bridge 2160000.axip2p: axi_cbb clk rate = 172 MHZ, timeout = 97000 useconds
[    1.337992] tegra18-bridge 2160000.axip2p: enabled timeout = 16684000
[    1.337993] tegra18-bridge 2160000.axip2p: bridge probed OK
[    1.338036] tegra18-bridge 2170000.axip2p: axi_cbb clk rate = 172 MHZ, timeout = 97000 useconds
[    1.338041] tegra18-bridge 2170000.axip2p: enabled timeout = 16684000
[    1.338043] tegra18-bridge 2170000.axip2p: bridge probed OK
[    1.338088] tegra18-bridge 2180000.axip2p: axi_cbb clk rate = 172 MHZ, timeout = 97000 useconds
[    1.338093] tegra18-bridge 2180000.axip2p: enabled timeout = 16684000
[    1.338095] tegra18-bridge 2180000.axip2p: bridge probed OK
[    1.338153] tegra18-bridge 2190000.axip2p: axi_cbb clk rate = 172 MHZ, timeout = 97000 useconds
[    1.338156] tegra18-bridge 2190000.axip2p: enabled timeout = 16684000
[    1.338158] tegra18-bridge 2190000.axip2p: bridge probed OK
[    1.338213] **** A57 ECC: Enabled
[    1.338215] tegra18_a57_serr_init: on CPU 3 a A57 Core
[    1.338278] tegra18x_actmon d230000.actmon: in actmon_register()...
[    1.338744] tegra18x_actmon d230000.actmon: initialization Completed for the device mc_all
[    1.339659] hw perfevents: enabled with denver15_uncore_pmu PMU driver, 3 counters available
[    1.339770] denver_knobs_init:MTS_VERSION:43068234
[    1.341497] nvpmodel: initialized successfully
[    1.343781] trusty-virtio trusty:virtio: initializing
[    1.343974] trusty_ipc virtio0: vring0: va(pa)  ffffffc1a7a92000(0) qsz 32 notifyid 1
[    1.343986] trusty_ipc virtio0: vring1: va(pa)  ffffffc1a7ab4000(0) qsz 32 notifyid 2
[    1.344144] trusty-virtio trusty:virtio: initializing done
[    1.344302] trusty_ipc virtio0: is online
[    1.346398] usbcore: registered new interface driver snd-usb-audio
[    1.354131] input: tegra-hda HDMI/DP,pcm=3 as /devices/3510000.hda/sound/card0/input0
[    1.354513] input: tegra-hda HDMI/DP,pcm=7 as /devices/3510000.hda/sound/card0/input1
[    1.388419] mmc0: mmc_decode_ext_csd: CMDQ supported: depth: 31, cmdq_support: 1
[    1.406566] mmc0: periodic cache flush enabled
[    1.406582] mmc0: new HS400 Enhanced strobe MMC card at address 0001
[    1.407186] mmcblk0: mmc0:0001 032G34 29.1 GiB 
[    1.407433] mmcblk0boot0: mmc0:0001 032G34 partition 1 8.00 MiB
[    1.411692] mmcblk0boot1: mmc0:0001 032G34 partition 2 8.00 MiB
[    1.411922] mmcblk0rpmb: mmc0:0001 032G34 partition 3 4.00 MiB
[    1.415511]  mmcblk0: p1 p2 p3 p4 p5 p6 p7 p8 p9 p10 p11 p12 p13 p14 p15 p16 p17 p18 p19 p20 p21 p22 p23 p24 p25 p26 p27 p28 p29 p30 p31
[    1.415692] ata1: SATA link down (SStatus 0 SControl 300)
[    1.421465] OPE platform probe
[    1.421568] OPE platform probe successful
[    1.424089] tegra-asoc: sound: This is a dummy codec
[    1.462003] tegra-pcie 10003000.pcie-controller: link 0 down, retrying
[    1.477212] tegra-asoc: sound: ADMAIF1 <-> ADMAIF1 mapping ok
[    1.477308] tegra-asoc: sound: ADMAIF2 <-> ADMAIF2 mapping ok
[    1.477400] tegra-asoc: sound: ADMAIF3 <-> ADMAIF3 mapping ok
[    1.477515] tegra-asoc: sound: ADMAIF4 <-> ADMAIF4 mapping ok
[    1.477600] tegra-asoc: sound: ADMAIF5 <-> ADMAIF5 mapping ok
[    1.477689] tegra-asoc: sound: ADMAIF6 <-> ADMAIF6 mapping ok
[    1.477774] tegra-asoc: sound: ADMAIF7 <-> ADMAIF7 mapping ok
[    1.477868] tegra-asoc: sound: ADMAIF8 <-> ADMAIF8 mapping ok
[    1.477951] tegra-asoc: sound: ADMAIF9 <-> ADMAIF9 mapping ok
[    1.478034] tegra-asoc: sound: ADMAIF10 <-> ADMAIF10 mapping ok
[    1.478127] tegra-asoc: sound: ADMAIF11 <-> ADMAIF11 mapping ok
[    1.478210] tegra-asoc: sound: ADMAIF12 <-> ADMAIF12 mapping ok
[    1.478294] tegra-asoc: sound: ADMAIF13 <-> ADMAIF13 mapping ok
[    1.478382] tegra-asoc: sound: ADMAIF14 <-> ADMAIF14 mapping ok
[    1.478465] tegra-asoc: sound: ADMAIF15 <-> ADMAIF15 mapping ok
[    1.478579] tegra-asoc: sound: ADMAIF16 <-> ADMAIF16 mapping ok
[    1.478667] tegra-asoc: sound: ADMAIF17 <-> ADMAIF17 mapping ok
[    1.478752] tegra-asoc: sound: ADMAIF18 <-> ADMAIF18 mapping ok
[    1.478877] tegra-asoc: sound: ADMAIF19 <-> ADMAIF19 mapping ok
[    1.478983] tegra-asoc: sound: ADMAIF20 <-> ADMAIF20 mapping ok
[    1.511754] u32 classifier
[    1.511760]     Actions configured
[    1.511851] Initializing XFRM netlink socket
[    1.513507] NET: Registered protocol family 10
[    1.514997] NET: Registered protocol family 17
[    1.515015] NET: Registered protocol family 15
[    1.515238] bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this.
[    1.515338] Bluetooth: RFCOMM socket layer initialized
[    1.515355] Bluetooth: RFCOMM ver 1.11
[    1.515363] Bluetooth: HIDP (Human Interface Emulation) ver 1.2
[    1.515370] Bluetooth: HIDP socket layer initialized
[    1.515407] 9pnet: Installing 9P2000 support
[    1.515454] Key type dns_resolver registered
[    1.515707] Registered cp15_barrier emulation handler
[    1.515725] Registered setend emulation handler
[    1.518474] registered taskstats version 1
[    1.521950] isp 15600000.isp: initialized
[    1.526234] nvcsi 150c0000.nvcsi: initialized
[    1.531244] gpio tegra-gpio-aon wake29 for gpio=56(FF:0)
[    1.531341] gpio tegra-gpio-aon wake67 for gpio=57(FF:1)
[    1.531403] gpio tegra-gpio-aon wake68 for gpio=58(FF:2)
[    1.531574] input: gpio-keys as /devices/gpio-keys/input/input2
[    1.533149] tegra-vi4 15700000.vi: initialized
[    1.534279] tegra-vi4 15700000.vi: subdev 150c0000.nvcsi--1 bound
[    1.534294] tegra-vi4 15700000.vi: subdev thermoframe 2-0010 bound
[    1.563595] tegra_rtc c2a0000.rtc: setting system clock to 2019-11-25 12:59:30 UTC (1574686770)
[    1.564664] mmcblk mmc0:0001: Card claimed for testing.
[    1.591298] bpmp: mounted debugfs mirror
[    1.592775] bwmgr: missing cdev-type property
[    1.598790] spmic-ldo0: disabling
[    1.598999] spmic-ldo1: disabling
[    1.599196] en-vdd-sd: disabling
[    1.599201] vdd-usb0-5v: disabling
[    1.599204] vdd-usb1-5v: disabling
[    1.599210] vdd-3v3: disabling
[    1.599213] en-vdd-vcm-2v8: disabling
[    1.599217] vdd-sys-bl: disabling
[    1.599221] ALSA device list:
[    1.599225]   #0: tegra-hda at 0x3518000 irq 383
[    1.599228]   #1: tegra-snd-t186ref-mobile-rt565x
[    1.601260] Freeing unused kernel memory: 8576K
[    1.636842] L4T-INITRD Build DATE: Mon Nov 25 12:59:30 UTC 2019
[    1.646072] Root device found: mmcblk0p1
[    1.648481] Found dev node: /dev/mmcblk0p1
[    1.672675] EXT4-fs (mmcblk0p1): mounted filesystem with ordered data mode. Opts: (null)
[    1.673925] Rootfs mounted over mmcblk0p1
[    1.713382] Switching from initrd to actual rootfs
[    1.865203] tegra-pcie 10003000.pcie-controller: link 0 down, retrying
[    1.867240] tegra-pcie 10003000.pcie-controller: link 0 down, ignoring
[    1.910631] ip_tables: (C) 2000-2006 Netfilter Core Team
[    1.957815] cgroup: cgroup2: unknown option "nsdelegate"
[    1.992202] 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)
[    1.995612] systemd[1]: Detected architecture arm64.
[    2.088739] systemd[1]: Set hostname to <nvidia-desktop>.
[    2.184784] 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.
[    2.184812] systemd[1]: Proceeding WITHOUT firewalling in effect! (This warning is only shown for the first loaded unit using IP firewalling.)
[    2.266843] tegra-pcie 10003000.pcie-controller: link 1 down, retrying
[    2.397539] random: systemd: uninitialized urandom read (16 bytes read)
[    2.405494] systemd[1]: Set up automount Arbitrary Executable File Formats File System Automount Point.
[    2.405738] random: systemd: uninitialized urandom read (16 bytes read)
[    2.409329] systemd[1]: Created slice User and Session Slice.
[    2.409512] random: systemd: uninitialized urandom read (16 bytes read)
[    2.409863] systemd[1]: Started Forward Password Requests to Wall Directory Watch.
[    2.409958] systemd[1]: Reached target User and Group Name Lookups.
[    2.410034] systemd[1]: Reached target Swap.
[    2.461710] EXT4-fs (mmcblk0p1): re-mounted. Opts: (null)
[    2.685512] tegra-pcie 10003000.pcie-controller: link 1 down, retrying
[    2.783536] systemd-journald[2237]: Received request to flush runtime journal from PID 1
[    3.102851] tegra-pcie 10003000.pcie-controller: link 1 down, retrying
[    3.104883] tegra-pcie 10003000.pcie-controller: link 1 down, ignoring
[    3.506861] tegra-pcie 10003000.pcie-controller: link 2 down, retrying
[    3.521515] thermoframe 2-0010: tfm_open:
[    3.554895] thermoframe 2-0010: tfm_power_on: power on
[    3.557571] thermoframe 2-0010: tfm_power_off: power off
[    3.569500] using random self ethernet address
[    3.574042] using random host ethernet address
[    3.579197] random: crng init done
[    3.582604] random: 7 urandom warning(s) missed due to ratelimiting
[    3.942861] tegra-pcie 10003000.pcie-controller: link 2 down, retrying
[    3.951650] nvgpu: 17000000.gp10b             railgate_enable_store:283  [INFO]  railgate is disabled.
[    3.984330] gpio tegra-gpio wake18 for gpio=101(M:5)
[    3.987320] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready
[    4.278901] Wake76 for irq=199
[    4.278907] Wake77 for irq=199
[    4.278910] Wake78 for irq=199
[    4.278912] Wake79 for irq=199
[    4.278915] Wake80 for irq=199
[    4.278917] Wake81 for irq=199
[    4.278920] Wake82 for irq=199
[    4.278972] tegra-xusb 3530000.xhci: Upgrade port 0 to USB3.0
[    4.278978] tegra-xusb 3530000.xhci: Upgrade port 1 to USB3.0
[    4.341454] tegra-pcie 10003000.pcie-controller: link 2 down, retrying
[    4.342905] tegra-pcie 10003000.pcie-controller: link 2 down, ignoring
[    4.379190] usb usb2: usb_suspend_both: status 0
[    4.571253] zram: Added device: zram0
[    4.575124] zram: Added device: zram1
[    4.577269] zram: Added device: zram2
[    4.579434] zram: Added device: zram3
[    4.581870] zram: Added device: zram4
[    4.583477] zram: Added device: zram5
[    4.596103] eqos 2490000.ether_qos eth0: Link is Up - 100Mbps/Full - flow control off
[    4.596672] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
[    4.603437] CPU1: shutdown
[    4.606218] psci: CPU1 killed.
[    4.647342] tegra-pcie 10003000.pcie-controller: PCIE: no end points detected
[    4.683808] CPU2: shutdown
[    4.686547] psci: CPU2 killed.
[    4.688675] zram0: detected capacity change from 0 to 598818816
[    4.707918] Adding 584780k swap on /dev/zram0.  Priority:5 extents:1 across:584780k SS
[    4.710701] zram1: detected capacity change from 0 to 598818816
[    4.727517] Adding 584780k swap on /dev/zram1.  Priority:5 extents:1 across:584780k SS
[    4.730347] zram2: detected capacity change from 0 to 598818816
[    4.756506] Adding 584780k swap on /dev/zram2.  Priority:5 extents:1 across:584780k SS
[    4.759637] zram3: detected capacity change from 0 to 598818816
[    4.773189] Adding 584780k swap on /dev/zram3.  Priority:5 extents:1 across:584780k SS
[    4.776314] zram4: detected capacity change from 0 to 598818816
[    4.800102] Adding 584780k swap on /dev/zram4.  Priority:5 extents:1 across:584780k SS
[    4.803320] zram5: detected capacity change from 0 to 598818816
[    4.820492] Adding 584780k swap on /dev/zram5.  Priority:5 extents:1 across:584780k SS
[    6.895104] tegradc 15210000.nvdisplay: blank - powerdown
[    6.990868] tegra-xusb 3530000.xhci: entering ELPG
[    6.994038] tegra-xusb 3530000.xhci: entering ELPG done
[    9.390256] Bluetooth: BNEP (Ethernet Emulation) ver 1.3
[    9.390268] Bluetooth: BNEP socket layer initialized
[    9.849501] fuse init (API version 7.26)
[   66.313222] thermoframe 2-0010: tfm_open:
[   66.314341] thermoframe 2-0010: tfm_power_on: power on
[   66.315724] thermoframe 2-0010: tfm_power_off: power off
[   66.506672] thermoframe 2-0010: tfm_open:
[   66.506910] thermoframe 2-0010: tfm_power_on: power on
[   66.506955] thermoframe 2-0010: tfm_power_off: power off
[   66.513419] thermoframe 2-0010: tfm_power_on: power on
[   66.518107] thermoframe 2-0010: tfm_set_mode:
[   66.518116] thermoframe 2-0010: tfm_start_streaming:
[   71.759021] tegra-vi4 15700000.vi: PXL_SOF syncpt timeout! err = -11
[   71.765659] tegra-vi4 15700000.vi: tegra_channel_error_recovery: attempting to reset the capture channel
[   76.878887] tegra-vi4 15700000.vi: PXL_SOF syncpt timeout! err = -11
[   76.885293] tegra-vi4 15700000.vi: tegra_channel_error_recovery: attempting to reset the capture channel
[   81.999310] tegra-vi4 15700000.vi: PXL_SOF syncpt timeout! err = -11
[   82.005675] tegra-vi4 15700000.vi: tegra_channel_error_recovery: attempting to reset the capture channel
[   87.119660] tegra-vi4 15700000.vi: PXL_SOF syncpt timeout! err = -11
[   87.126046] tegra-vi4 15700000.vi: tegra_channel_error_recovery: attempting to reset the capture channel
[   92.240109] tegra-vi4 15700000.vi: PXL_SOF syncpt timeout! err = -11
[   92.246657] tegra-vi4 15700000.vi: tegra_channel_error_recovery: attempting to reset the capture channel
[   92.260047] thermoframe 2-0010: tfm_power_off: power off

trace:

[code]

tracer: nop

entries-in-buffer/entries-written: 1028/1028 #P:4

_-----=> irqs-off

/ _----=> need-resched

| / _—=> hardirq/softirq

|| / _–=> preempt-depth

||| / delay

TASK-PID CPU# |||| TIMESTAMP FUNCTION

| | | |||| | |

 kworker/3:3-4089  [003] ....    34.774966: rtos_queue_peek_from_isr_failed: tstamp:1459225229 queue:0x0b4b5f58
 kworker/3:3-4089  [003] ....    34.942911: rtos_queue_peek_from_isr_failed: tstamp:1464225223 queue:0x0b4b5f58
 kworker/3:3-4089  [003] ....    35.110929: rtos_queue_peek_from_isr_failed: tstamp:1469225214 queue:0x0b4b5f58
 kworker/3:3-4089  [003] ....    35.278898: rtos_queue_peek_from_isr_failed: tstamp:1474225234 queue:0x0b4b5f58
 kworker/3:3-4089  [003] ....    35.446943: rtos_queue_peek_from_isr_failed: tstamp:1479225202 queue:0x0b4b5f58
 kworker/3:3-4089  [003] ....    35.614986: rtos_queue_peek_from_isr_failed: tstamp:1484225098 queue:0x0b4b5f58
 kworker/3:3-4089  [003] ....    35.726908: rtos_queue_peek_from_isr_failed: tstamp:1489225215 queue:0x0b4b5f58
 kworker/3:3-4089  [003] ....    35.894932: rtos_queue_peek_from_isr_failed: tstamp:1494225169 queue:0x0b4b5f58
 kworker/3:3-4089  [003] ....    36.062917: rtos_queue_peek_from_isr_failed: tstamp:1499225175 queue:0x0b4b5f58
 kworker/3:3-4089  [003] ....    36.230957: rtos_queue_peek_from_isr_failed: tstamp:1504225169 queue:0x0b4b5f58
 kworker/3:3-4089  [003] ....    36.399038: rtos_queue_peek_from_isr_failed: tstamp:1509225074 queue:0x0b4b5f58
 kworker/3:3-4089  [003] ....    36.570894: rtos_queue_peek_from_isr_failed: tstamp:1514225168 queue:0x0b4b5f58
 kworker/3:3-4089  [003] ....    36.738910: rtos_queue_peek_from_isr_failed: tstamp:1519225148 queue:0x0b4b5f58
 kworker/3:3-4089  [003] ....    36.850948: rtos_queue_peek_from_isr_failed: tstamp:1524225152 queue:0x0b4b5f58
 kworker/3:3-4089  [003] ....    37.018907: rtos_queue_peek_from_isr_failed: tstamp:1529225135 queue:0x0b4b5f58
 kworker/3:3-4089  [003] ....    37.186922: rtos_queue_peek_from_isr_failed: tstamp:1534225129 queue:0x0b4b5f58
 kworker/3:3-4089  [003] ....    37.354920: rtos_queue_peek_from_isr_failed: tstamp:1539225121 queue:0x0b4b5f58
 kworker/3:3-4089  [003] ....    37.522937: rtos_queue_peek_from_isr_failed: tstamp:1544225114 queue:0x0b4b5f58
 kworker/3:3-4089  [003] ....    37.690981: rtos_queue_peek_from_isr_failed: tstamp:1549225120 queue:0x0b4b5f58
 kworker/3:3-4089  [003] ....    37.858917: rtos_queue_peek_from_isr_failed: tstamp:1554225098 queue:0x0b4b5f58
 kworker/3:3-4089  [003] ....    37.970853: rtos_queue_peek_from_isr_failed: tstamp:1559224994 queue:0x0b4b5f58
 kworker/3:3-4089  [003] ....    38.138963: rtos_queue_peek_from_isr_failed: tstamp:1564225101 queue:0x0b4b5f58
 kworker/3:3-4089  [003] ....    38.306959: rtos_queue_peek_from_isr_failed: tstamp:1569225079 queue:0x0b4b5f58
 kworker/3:3-4089  [003] ....    38.474901: rtos_queue_peek_from_isr_failed: tstamp:1574225072 queue:0x0b4b5f58
 kworker/3:3-4089  [003] ....    38.642938: rtos_queue_peek_from_isr_failed: tstamp:1579225066 queue:0x0b4b5f58
 kworker/3:3-4089  [003] ....    38.811067: rtos_queue_peek_from_isr_failed: tstamp:1584224957 queue:0x0b4b5f58
 kworker/3:3-4089  [003] ....    38.978921: rtos_queue_peek_from_isr_failed: tstamp:1589225079 queue:0x0b4b5f58
 kworker/3:3-4089  [003] ....    39.090961: rtos_queue_peek_from_isr_failed: tstamp:1594225044 queue:0x0b4b5f58
 kworker/3:3-4089  [003] ....    39.258894: rtos_queue_peek_from_isr_failed: tstamp:1599225065 queue:0x0b4b5f58
 kworker/3:3-4089  [003] ....    39.426916: rtos_queue_peek_from_isr_failed: tstamp:1604225028 queue:0x0b4b5f58
 kworker/3:3-4089  [003] ....    39.594921: rtos_queue_peek_from_isr_failed: tstamp:1609225025 queue:0x0b4b5f58
 kworker/3:3-4089  [003] ....    39.762997: rtos_queue_peek_from_isr_failed: tstamp:1614225016 queue:0x0b4b5f58
 kworker/3:3-4089  [003] ....    39.930932: rtos_queue_peek_from_isr_failed: tstamp:1619225010 queue:0x0b4b5f58
 kworker/3:3-4089  [003] ....    40.098953: rtos_queue_peek_from_isr_failed: tstamp:1624225030 queue:0x0b4b5f58
 kworker/3:3-4089  [003] ....    40.210918: rtos_queue_peek_from_isr_failed: tstamp:1629224997 queue:0x0b4b5f58
 kworker/3:3-4089  [003] ....    40.379008: rtos_queue_peek_from_isr_failed: tstamp:1634224974 queue:0x0b4b5f58
 kworker/3:3-4089  [003] ....    40.546967: rtos_queue_peek_from_isr_failed: tstamp:1639225008 queue:0x0b4b5f58
 kworker/3:3-4089  [003] ....    40.714920: rtos_queue_peek_from_isr_failed: tstamp:1644224976 queue:0x0b4b5f58
 kworker/3:3-4089  [003] ....    40.882918: rtos_queue_peek_from_isr_failed: tstamp:1649224969 queue:0x0b4b5f58
 kworker/3:3-4089  [003] ....    41.050939: rtos_queue_peek_from_isr_failed: tstamp:1654224963 queue:0x0b4b5f58
 kworker/3:3-4089  [003] ....    41.219013: rtos_queue_peek_from_isr_failed: tstamp:1659224949 queue:0x0b4b5f58
 kworker/3:3-4089  [003] ....    41.330936: rtos_queue_peek_from_isr_failed: tstamp:1664224973 queue:0x0b4b5f58
 kworker/3:3-4089  [003] ....    41.498919: rtos_queue_peek_from_isr_failed: tstamp:1669225019 queue:0x0b4b5f58
 kworker/3:3-4089  [003] ....    41.666945: rtos_queue_peek_from_isr_failed: tstamp:1674224961 queue:0x0b4b5f58
 kworker/3:3-4089  [003] ....    41.834914: rtos_queue_peek_from_isr_failed: tstamp:1679224929 queue:0x0b4b5f58
 kworker/3:3-4089  [003] ....    42.002966: rtos_queue_peek_from_isr_failed: tstamp:1684224922 queue:0x0b4b5f58
 kworker/3:3-4089  [003] ....    42.170925: rtos_queue_peek_from_isr_failed: tstamp:1689224915 queue:0x0b4b5f58
 kworker/3:3-4089  [003] ....    42.338912: rtos_queue_peek_from_isr_failed: tstamp:1694224909 queue:0x0b4b5f58
 kworker/3:3-4089  [003] ....    42.450985: rtos_queue_peek_from_isr_failed: tstamp:1699224928 queue:0x0b4b5f58
 kworker/3:3-4089  [003] ....    42.618960: rtos_queue_peek_from_isr_failed: tstamp:1704224894 queue:0x0b4b5f58
 kworker/3:3-4089  [003] ....    42.786987: rtos_queue_peek_from_isr_failed: tstamp:1709224873 queue:0x0b4b5f58
 kworker/3:3-4089  [003] ....    42.954945: rtos_queue_peek_from_isr_failed: tstamp:1714224908 queue:0x0b4b5f58
 kworker/3:3-4089  [003] ....    43.122941: rtos_queue_peek_from_isr_failed: tstamp:1719224872 queue:0x0b4b5f58
 kworker/3:3-4089  [003] ....    43.290863: rtos_queue_peek_from_isr_failed: tstamp:1724224767 queue:0x0b4b5f58
 kworker/3:3-4089  [003] ....    43.458942: rtos_queue_peek_from_isr_failed: tstamp:1729224859 queue:0x0b4b5f58
 kworker/3:3-4089  [003] ....    43.571111: rtos_queue_peek_from_isr_failed: tstamp:1734224853 queue:0x0b4b5f58
 kworker/3:3-4089  [003] ....    43.738974: rtos_queue_peek_from_isr_failed: tstamp:1739224873 queue:0x0b4b5f58
 kworker/3:3-4089  [003] ....    43.906911: rtos_queue_peek_from_isr_failed: tstamp:1744224840 queue:0x0b4b5f58
 kworker/3:3-4089  [003] ....    44.074977: rtos_queue_peek_from_isr_failed: tstamp:1749224860 queue:0x0b4b5f58
 kworker/3:3-4089  [003] ....    44.242957: rtos_queue_peek_from_isr_failed: tstamp:1754224825 queue:0x0b4b5f58
 kworker/3:3-4089  [003] ....    44.410974: rtos_queue_peek_from_isr_failed: tstamp:1759224819 queue:0x0b4b5f58
 kworker/3:3-4089  [003] ....    44.578918: rtos_queue_peek_from_isr_failed: tstamp:1764224815 queue:0x0b4b5f58
 kworker/3:3-4089  [003] ....    44.694918: rtos_queue_peek_from_isr_failed: tstamp:1769224808 queue:0x0b4b5f58
 kworker/3:3-4089  [003] ....    44.862895: rtos_queue_peek_from_isr_failed: tstamp:1774224703 queue:0x0b4b5f58
 kworker/3:3-4089  [003] ....    45.031005: rtos_queue_peek_from_isr_failed: tstamp:1779224793 queue:0x0b4b5f58
 kworker/3:3-4089  [003] ....    45.198913: rtos_queue_peek_from_isr_failed: tstamp:1784224784 queue:0x0b4b5f58
 kworker/3:3-4089  [003] ....    45.366929: rtos_queue_peek_from_isr_failed: tstamp:1789224807 queue:0x0b4b5f58
 kworker/3:3-4089  [003] ....    45.534911: rtos_queue_peek_from_isr_failed: tstamp:1794224772 queue:0x0b4b5f58
 kworker/3:3-4089  [003] ....    45.646934: rtos_queue_peek_from_isr_failed: tstamp:1799224765 queue:0x0b4b5f58
 kworker/3:3-4089  [003] ....    45.814980: rtos_queue_peek_from_isr_failed: tstamp:1804224758 queue:0x0b4b5f58
 kworker/3:3-4089  [003] ....    45.983154: rtos_queue_peek_from_isr_failed: tstamp:1809224752 queue:0x0b4b5f58
 kworker/3:3-4089  [003] ....    46.150958: rtos_queue_peek_from_isr_failed: tstamp:1814224772 queue:0x0b4b5f58
 kworker/3:3-4089  [003] ....    46.318918: rtos_queue_peek_from_isr_failed: tstamp:1819224737 queue:0x0b4b5f58
 kworker/3:3-4089  [003] ....    46.486920: rtos_queue_peek_from_isr_failed: tstamp:1824224760 queue:0x0b4b5f58
 kworker/3:3-4089  [003] ....    46.654973: rtos_queue_peek_from_isr_failed: tstamp:1829224738 queue:0x0b4b5f58
 kworker/3:3-4089  [003] ....    46.766948: rtos_queue_peek_from_isr_failed: tstamp:1834224717 queue:0x0b4b5f58
 kworker/3:3-4089  [003] ....    46.934914: rtos_queue_peek_from_isr_failed: tstamp:1839224711 queue:0x0b4b5f58
 kworker/3:3-4089  [003] ....    47.102897: rtos_queue_peek_from_isr_failed: tstamp:1844224702 queue:0x0b4b5f58
 kworker/3:3-4089  [003] ....    47.270960: rtos_queue_peek_from_isr_failed: tstamp:1849224725 queue:0x0b4b5f58
 kworker/3:3-4089  [003] ....    47.438952: rtos_queue_peek_from_isr_failed: tstamp:1854224690 queue:0x0b4b5f58
 kworker/3:3-4089  [003] ....    47.607081: rtos_queue_peek_from_isr_failed: tstamp:1859224683 queue:0x0b4b5f58
 kworker/3:3-4089  [003] ....    47.774957: rtos_queue_peek_from_isr_failed: tstamp:1864224700 queue:0x0b4b5f58
 kworker/3:3-4089  [003] ....    47.886911: rtos_queue_peek_from_isr_failed: tstamp:1869224667 queue:0x0b4b5f58
 kwor
  1. From the log NVCSI/VI didn’t receive any validate data from MIPI bus. You may need to probe the signal to confirm it.
  2. You can set the pix_clk_hz to a much bigger value if your device didn’t need input clock. And have no problem to support always on streaming but it better to configure it as discontinuous mode.

Yes, it was hardware problem.

Now in dmesg I have this messages:

[  180.733941] tegra-vi4 15700000.vi: PXL_SOF syncpt timeout! err = -11
[  180.740467] tegra-vi4 15700000.vi: tegra_channel_error_recovery: attempting to reset the capture channel
[  180.750272] nvcsi 150c0000.nvcsi: csi4_stream_check_status (0) INTR_STATUS 0x00000080
[  180.758226] nvcsi 150c0000.nvcsi: csi4_stream_check_status (0) ERR_INTR_STATUS 0x00000080
[  180.969886] tegra-vi4 15700000.vi: PXL_SOF syncpt timeout! err = -11
[  180.976411] tegra-vi4 15700000.vi: tegra_channel_error_recovery: attempting to reset the capture channel
[  181.189930] tegra-vi4 15700000.vi: PXL_SOF syncpt timeout! err = -11
[  181.196442] tegra-vi4 15700000.vi: tegra_channel_error_recovery: attempting to reset the capture channel
[  181.206479] nvcsi 150c0000.nvcsi: csi4_stream_check_status (0) INTR_STATUS 0x00000040
[  181.214398] nvcsi 150c0000.nvcsi: csi4_stream_check_status (0) ERR_INTR_STATUS 0x00000040
[  181.425916] tegra-vi4 15700000.vi: PXL_SOF syncpt timeout! err = -11
[  181.432393] tegra-vi4 15700000.vi: tegra_channel_error_recovery: attempting to reset the capture channel
[  181.645984] tegra-vi4 15700000.vi: PXL_SOF syncpt timeout! err = -11
[  181.652534] tegra-vi4 15700000.vi: tegra_channel_error_recovery: attempting to reset the capture channel
[  181.663043] nvcsi 150c0000.nvcsi: csi4_stream_check_status (0) INTR_STATUS 0x00000040
[  181.670975] nvcsi 150c0000.nvcsi: csi4_stream_check_status (0) ERR_INTR_STATUS 0x00000040
[  181.881980] tegra-vi4 15700000.vi: PXL_SOF syncpt timeout! err = -11
[  181.888601] tegra-vi4 15700000.vi: tegra_channel_error_recovery: attempting to reset the capture channel
[  181.899349] nvcsi 150c0000.nvcsi: csi4_stream_check_status (0) INTR_STATUS 0x00000080
[  181.907288] nvcsi 150c0000.nvcsi: csi4_stream_check_status (0) ERR_INTR_STATUS 0x00000080
[  181.915955] nvcsi 150c0000.nvcsi: csi4_stream_check_status (0) ERROR_STATUS2VI_VC1 = 0x00000004
[  181.924808] nvcsi 150c0000.nvcsi: csi4_stream_check_status (0) INTR_STATUS 0x00000040
[  181.933708] nvcsi 150c0000.nvcsi: csi4_stream_check_status (0) ERR_INTR_STATUS 0x00000040

ERROR_STATUS2VI_VC1 = 0x00000004 means “Packet Payload is less than WC in PH”
and INTR_STATUS means CRC error or WC short error in VC1

what is “WC”? Word count? May it be caused by 8bpp data format?

Have a check below link to disable the ECC check for the CRC error.
I think it doesn’t matter with the format.

https://elinux.org/Jetson_TX2_Camera_BringUp

what link?

Sorry forgot to post the link.
Check the previous comment again.

And in trace I have messages

tstamp:11977028935 tag:CSIMUX_STREAM channel:0xff frame:1 vi_tstamp:11977028606 data:0x00000001

It is notification “SPURIOUS_DATA_STREAM_0” - what does it mean?

Spurious data means VI sees some other packets before FS packet, as VI alwasy expect the 1st packet to be frame start

Try to boost the nvcsi/vi clock for this.

Not working. Seems, no valid data from CSI. But there are all signals on oscilloscope.

trace:

kworker/3:2-2943  [003] ....   778.298200: rtos_queue_peek_from_isr_failed: tstamp:24693458282 queue:0x0b4b5f58
     kworker/3:2-2943  [003] ....   778.466088: rtos_queue_peek_from_isr_failed: tstamp:24698458306 queue:0x0b4b5f58
     kworker/3:2-2943  [003] ....   778.578091: rtos_queue_peek_from_isr_failed: tstamp:24703458282 queue:0x0b4b5f58
        v4l2-ctl-7780  [005] ....   778.637101: tegra_channel_open: vi-output, thermoframe 2-0010
        v4l2-ctl-7780  [000] ....   778.638143: tegra_channel_set_power: thermoframe 2-0010 : 0x1
        v4l2-ctl-7780  [000] ....   778.638155: camera_common_s_power: status : 0x1
        v4l2-ctl-7780  [000] ....   778.638205: tegra_channel_set_power: 150c0000.nvcsi--1 : 0x1
        v4l2-ctl-7780  [000] ....   778.638208: csi_s_power: enable : 0x1
        v4l2-ctl-7780  [004] ....   778.647846: tegra_channel_capture_setup: vnc_id 0 W 768 H 576 fmt e5
 vi-output, ther-7781  [003] ....   778.648027: tegra_channel_set_stream: enable : 0x1
 vi-output, ther-7781  [005] ....   778.649850: tegra_channel_set_stream: 150c0000.nvcsi--1 : 0x1
 vi-output, ther-7781  [005] ....   778.649852: csi_s_stream: enable : 0x1
 vi-output, ther-7781  [005] ....   778.649919: tegra_channel_set_stream: thermoframe 2-0010 : 0x1
     kworker/3:2-2943  [003] ....   778.690123: rtos_queue_send_from_isr_failed: tstamp:24705738228 queue:0x0b4a7258
     kworker/3:2-2943  [003] ....   778.690137: rtos_queue_send_from_isr_failed: tstamp:24705738336 queue:0x0b4aad68
     kworker/3:2-2943  [003] ....   778.690141: rtos_queue_send_from_isr_failed: tstamp:24705738451 queue:0x0b4ac998
     kworker/3:2-2943  [003] ....   778.690145: rtos_queue_send_from_isr_failed: tstamp:24705738558 queue:0x0b4ae518
     kworker/3:2-2943  [003] ....   778.690154: rtos_queue_send_from_isr_failed: tstamp:24705738663 queue:0x0b4af2d8
     kworker/3:2-2943  [003] ....   778.690159: rtos_queue_send_from_isr_failed: tstamp:24705738768 queue:0x0b4b0098
     kworker/3:2-2943  [003] ....   778.690163: rtos_queue_send_from_isr_failed: tstamp:24705738873 queue:0x0b4b0e58
     kworker/3:2-2943  [003] ....   778.690167: rtos_queue_send_from_isr_failed: tstamp:24705738978 queue:0x0b4b1c18
     kworker/3:2-2943  [003] ....   778.690176: rtos_queue_send_failed: tstamp:24705739468 queue:0x0b4a7258
     kworker/3:2-2943  [003] ....   778.690181: rtos_queue_send_from_isr_failed: tstamp:24705743178 queue:0x0b4a7258
     kworker/3:2-2943  [003] ....   778.690184: rtos_queue_send_from_isr_failed: tstamp:24705743284 queue:0x0b4aad68
     kworker/3:2-2943  [003] ....   778.690187: rtos_queue_send_from_isr_failed: tstamp:24705743391 queue:0x0b4ac998
     kworker/3:2-2943  [003] ....   778.690191: rtos_queue_send_from_isr_failed: tstamp:24705743497 queue:0x0b4ae518
     kworker/3:2-2943  [003] ....   778.690194: rtos_queue_send_from_isr_failed: tstamp:24705743602 queue:0x0b4af2d8
     kworker/3:2-2943  [003] ....   778.690198: rtos_queue_send_from_isr_failed: tstamp:24705743707 queue:0x0b4b0098
     kworker/3:2-2943  [003] ....   778.690202: rtos_queue_send_from_isr_failed: tstamp:24705743813 queue:0x0b4b0e58
     kworker/3:2-2943  [003] ....   778.690205: rtos_queue_send_from_isr_failed: tstamp:24705743918 queue:0x0b4b1c18
     kworker/3:2-2943  [003] ....   778.690209: rtos_queue_send_failed: tstamp:24705744809 queue:0x0b4a7258
     kworker/3:2-2943  [003] ....   778.690219: rtcpu_vinotify_event: tstamp:24705812973 tag:CSIMUX_STREAM channel:0xff frame:1 vi_tstamp:24705812634 data:0x00000001
     kworker/3:2-2943  [003] ....   778.746018: rtos_queue_peek_from_isr_failed: tstamp:24708458264 queue:0x0b4b5f58
     kworker/3:2-2943  [003] ....   778.914087: rtos_queue_peek_from_isr_failed: tstamp:24713458256 queue:0x0b4b5f58
     kworker/3:2-2943  [003] ....   779.084304: rtos_queue_peek_from_isr_failed: tstamp:24718458249 queue:0x0b4b5f58
     kworker/3:2-2943  [003] ....   779.250140: rtos_queue_peek_from_isr_failed: tstamp:24723458268 queue:0x0b4b5f58
     kworker/3:2-2943  [003] ....   779.418142: rtos_queue_peek_from_isr_failed: tstamp:24728458235 queue:0x0b4b5f58
     kworker/3:2-2943  [003] ....   779.586138: rtos_queue_peek_from_isr_failed: tstamp:24733458255 queue:0x0b4b5f58

dmesg:

[  799.066691] tegra-vi4 15700000.vi: PXL_SOF syncpt timeout! err = -11
[  799.073237] tegra-vi4 15700000.vi: tegra_channel_error_recovery: attempting to reset the capture channel
[  799.083157] nvcsi 150c0000.nvcsi: csi4_stop_streaming port_idx=0, lanes=2
[  799.083165] nvcsi 150c0000.nvcsi: csi4_phy_config
[  799.083172] nvcsi 150c0000.nvcsi: NVCSI_CIL_CONFIG = 00000002
[  799.083179] nvcsi 150c0000.nvcsi: csi4_stream_check_status
[  799.083190] nvcsi 150c0000.nvcsi: csi4_cil_check_status 410
[  799.083197] nvcsi 150c0000.nvcsi: csi4_start_streaming port_idx=0, lanes=2
[  799.083201] nvcsi 150c0000.nvcsi: csi4_stream_init
[  799.083210] nvcsi 150c0000.nvcsi: csi4_stream_config
[  799.083218] nvcsi 150c0000.nvcsi: csi4_stream_config (0) read VC0_DPCM_CTRL = 00000000
[  799.083221] nvcsi 150c0000.nvcsi: csi4_phy_config
[  799.083226] nvcsi 150c0000.nvcsi: NVCSI_CIL_CONFIG = 00000000
[  799.083233] nvcsi 150c0000.nvcsi: cil core clock: 204, csi clock: 100
[  799.083237] nvcsi 150c0000.nvcsi: cil_settingtime was autocalculated
[  799.083241] nvcsi 150c0000.nvcsi: csi settle time: 33, cil settle time: 26
[  799.083496] tegra-vi4 15700000.vi: Create Surface with imgW=768, imgH=576, memFmt=229
[  804.186850] tegra-vi4 15700000.vi: PXL_SOF syncpt timeout! err = -11
[  804.193396] tegra-vi4 15700000.vi: tegra_channel_error_recovery: attempting to reset the capture channel
[  804.204577] nvcsi 150c0000.nvcsi: csi4_stop_streaming port_idx=0, lanes=2
[  804.204596] nvcsi 150c0000.nvcsi: csi4_phy_config
[  804.204614] nvcsi 150c0000.nvcsi: NVCSI_CIL_CONFIG = 00000002
[  804.204627] nvcsi 150c0000.nvcsi: csi4_stream_check_status
[  804.204648] nvcsi 150c0000.nvcsi: csi4_cil_check_status 410
[  804.204660] nvcsi 150c0000.nvcsi: csi4_start_streaming port_idx=0, lanes=2
[  804.204669] nvcsi 150c0000.nvcsi: csi4_stream_init
[  804.204685] nvcsi 150c0000.nvcsi: csi4_stream_config
[  804.204700] nvcsi 150c0000.nvcsi: csi4_stream_config (0) read VC0_DPCM_CTRL = 00000000
[  804.204730] nvcsi 150c0000.nvcsi: csi4_phy_config
[  804.204741] nvcsi 150c0000.nvcsi: NVCSI_CIL_CONFIG = 00000000
[  804.204754] nvcsi 150c0000.nvcsi: cil core clock: 204, csi clock: 100
[  804.204764] nvcsi 150c0000.nvcsi: cil_settingtime was autocalculated
[  804.204772] nvcsi 150c0000.nvcsi: csi settle time: 33, cil settle time: 26
[  804.205398] tegra-vi4 15700000.vi: Create Surface with imgW=768, imgH=576, memFmt=229
[  809.306922] tegra-vi4 15700000.vi: PXL_SOF syncpt timeout! err = -11
[  809.313526] tegra-vi4 15700000.vi: tegra_channel_error_recovery: attempting to reset the capture channel
[  809.324006] nvcsi 150c0000.nvcsi: csi4_stop_streaming port_idx=0, lanes=2
[  809.324026] nvcsi 150c0000.nvcsi: csi4_phy_config
[  809.324041] nvcsi 150c0000.nvcsi: NVCSI_CIL_CONFIG = 00000002
[  809.324054] nvcsi 150c0000.nvcsi: csi4_stream_check_status
[  809.324072] nvcsi 150c0000.nvcsi: csi4_stream_check_status (0) INTR_STATUS 0x00000080
[  809.332012] nvcsi 150c0000.nvcsi: csi4_stream_check_status (0) ERR_INTR_STATUS 0x00000080
[  809.340254] nvcsi 150c0000.nvcsi: csi4_cil_check_status 410
[  809.340265] nvcsi 150c0000.nvcsi: csi4_start_streaming port_idx=0, lanes=2
[  809.340273] nvcsi 150c0000.nvcsi: csi4_stream_init
[  809.340286] nvcsi 150c0000.nvcsi: csi4_stream_config
[  809.340298] nvcsi 150c0000.nvcsi: csi4_stream_config (0) read VC0_DPCM_CTRL = 00000000
[  809.340304] nvcsi 150c0000.nvcsi: csi4_phy_config
[  809.340311] nvcsi 150c0000.nvcsi: NVCSI_CIL_CONFIG = 00000000
[  809.340319] nvcsi 150c0000.nvcsi: cil core clock: 204, csi clock: 100
[  809.340323] nvcsi 150c0000.nvcsi: cil_settingtime was autocalculated
[  809.340327] nvcsi 150c0000.nvcsi: csi settle time: 33, cil settle time: 26
[  809.340590] tegra-vi4 15700000.vi: Create Surface with imgW=768, imgH=576, memFmt=229
[  814.427156] tegra-vi4 15700000.vi: PXL_SOF syncpt timeout! err = -11
[  814.433693] tegra-vi4 15700000.vi: tegra_channel_error_recovery: attempting to reset the capture channel
[  814.443732] nvcsi 150c0000.nvcsi: csi4_stop_streaming port_idx=0, lanes=2
[  814.443742] nvcsi 150c0000.nvcsi: csi4_phy_config
[  814.443750] nvcsi 150c0000.nvcsi: NVCSI_CIL_CONFIG = 00000002
[  814.443757] nvcsi 150c0000.nvcsi: csi4_stream_check_status
[  814.443771] nvcsi 150c0000.nvcsi: csi4_cil_check_status 410
[  814.443779] nvcsi 150c0000.nvcsi: csi4_start_streaming port_idx=0, lanes=2
[  814.443783] nvcsi 150c0000.nvcsi: csi4_stream_init
[  814.443794] nvcsi 150c0000.nvcsi: csi4_stream_config
[  814.443803] nvcsi 150c0000.nvcsi: csi4_stream_config (0) read VC0_DPCM_CTRL = 00000000
[  814.443807] nvcsi 150c0000.nvcsi: csi4_phy_config
[  814.443814] nvcsi 150c0000.nvcsi: NVCSI_CIL_CONFIG = 00000000
[  814.443822] nvcsi 150c0000.nvcsi: cil core clock: 204, csi clock: 100
[  814.443827] nvcsi 150c0000.nvcsi: cil_settingtime was autocalculated
[  814.443832] nvcsi 150c0000.nvcsi: csi settle time: 33, cil settle time: 26
[  814.444161] tegra-vi4 15700000.vi: Create Surface with imgW=768, imgH=576, memFmt=229
[  814.444672] nvcsi 150c0000.nvcsi: csi4_stop_streaming port_idx=0, lanes=2
[  814.444680] nvcsi 150c0000.nvcsi: csi4_phy_config
[  814.444687] nvcsi 150c0000.nvcsi: NVCSI_CIL_CONFIG = 00000002
[  814.444693] nvcsi 150c0000.nvcsi: csi4_stream_check_status
[  814.444704] nvcsi 150c0000.nvcsi: csi4_cil_check_status 410
[  814.447855] thermoframe 2-0010: tfm_power_off: power off

I cant find in docs, how to configure virtual channels. May it be the problem?

I try to read from NVCSI_STREAM_0_PH_WC_0 and NVCSI_STREAM_0_PH_DI_0 and it seems that NVCSI receives data packets, parses headers but not emit SOF event.

From this registers I see that WC is 1536 (same as frame width * 2), VC is 1 and DT is 0x1e.

It may be that line_length set incorrect, but I can not find, where is frame width of receiver configured. line_length from device tree not used in sci configuration code.

err_intr_stat_pd_wc_short_err_vc1 - what does it mean? payload data less then word count in header?
What if I mask this error?

Try to run camera on another tx2 board
Now get message in trace:

kworker/0:3-1921  [000] ....   896.246547: rtcpu_vinotify_event: tstamp:28380389431 tag:CSIMUX_STREAM channel:0xff frame:4 vi_tstamp:28380389092 data:
0x00000010

and dmesg

[  922.002309] nvcsi 150c0000.nvcsi: csi4_start_streaming port_idx=0, lanes=2
[  922.002312] nvcsi 150c0000.nvcsi: csi4_stream_init
[  922.002320] nvcsi 150c0000.nvcsi: csi4_stream_config
[  922.002327] nvcsi 150c0000.nvcsi: csi4_stream_config (1) read VC0_DPCM_CTRL = 00000000
[  922.002332] nvcsi 150c0000.nvcsi: csi4_phy_config
[  922.002337] nvcsi 150c0000.nvcsi: NVCSI_CIL_CONFIG = 00000000
[  922.002344] nvcsi 150c0000.nvcsi: cil core clock: 204, csi clock: 100
[  922.002347] nvcsi 150c0000.nvcsi: cil_settingtime was autocalculated
[  922.002349] nvcsi 150c0000.nvcsi: csi settle time: 33, cil settle time: 26
[  922.002571] tegra-vi4 15700000.vi: Create Surface with imgW=768, imgH=576, memFmt=229
[  927.003326] tegra-vi4 15700000.vi: PXL_SOF syncpt timeout! err = -11
[  927.009865] tegra-vi4 15700000.vi: tegra_channel_error_recovery: attempting to reset the capture channel
[  927.019742] nvcsi 150c0000.nvcsi: csi4_stop_streaming port_idx=0, lanes=2
[  927.019750] nvcsi 150c0000.nvcsi: csi4_phy_config
[  927.019758] nvcsi 150c0000.nvcsi: NVCSI_CIL_CONFIG = 00000200
[  927.019765] nvcsi 150c0000.nvcsi: csi4_stream_check_status
[  927.019772] nvcsi 150c0000.nvcsi: WC: 0x06000600
[  927.024445] nvcsi 150c0000.nvcsi: VC and DATA_TYPE: 0x0000005E
[  927.030307] nvcsi 150c0000.nvcsi: csi4_cil_check_status 416
[  927.030312] nvcsi 150c0000.nvcsi: csi4_cil_check_status (1) CILA_INTR_STATUS 0x02000044
[  927.038392] nvcsi 150c0000.nvcsi: csi4_cil_check_status (1) CILA_ERR_INTR_STATUS 0x02000044
[  927.046865] nvcsi 150c0000.nvcsi: csi4_start_streaming port_idx=0, lanes=2
[  927.046879] nvcsi 150c0000.nvcsi: csi4_stream_init
[  927.046893] nvcsi 150c0000.nvcsi: csi4_stream_config
[  927.046906] nvcsi 150c0000.nvcsi: csi4_stream_config (1) read VC0_DPCM_CTRL = 00000000
[  927.046914] nvcsi 150c0000.nvcsi: csi4_phy_config
[  927.046920] nvcsi 150c0000.nvcsi: NVCSI_CIL_CONFIG = 00000000
[  927.046932] nvcsi 150c0000.nvcsi: cil core clock: 204, csi clock: 100
[  927.046940] nvcsi 150c0000.nvcsi: cil_settingtime was autocalculated
[  927.046946] nvcsi 150c0000.nvcsi: csi settle time: 33, cil settle time: 26
[  927.047601] tegra-vi4 15700000.vi: Create Surface with imgW=768, imgH=576, memFmt=229
[  927.048137] nvcsi 150c0000.nvcsi: csi4_stop_streaming port_idx=0, lanes=2
[  927.048146] nvcsi 150c0000.nvcsi: csi4_phy_config
[  927.048152] nvcsi 150c0000.nvcsi: NVCSI_CIL_CONFIG = 00000200
[  927.048159] nvcsi 150c0000.nvcsi: csi4_stream_check_status
[  927.048165] nvcsi 150c0000.nvcsi: WC: 0x06000600
[  927.052843] nvcsi 150c0000.nvcsi: VC and DATA_TYPE: 0x0000005E
[  927.058737] nvcsi 150c0000.nvcsi: csi4_stream_check_status (1) ERROR_STATUS2VI_VC1 = 0x00000008
[  927.067503] nvcsi 150c0000.nvcsi: csi4_cil_check_status 416
[  927.067532] nvcsi 150c0000.nvcsi: csi4_cil_check_status (1) CILA_INTR_STATUS 0x02000044
[  927.075799] nvcsi 150c0000.nvcsi: csi4_cil_check_status (1) CILA_ERR_INTR_STATUS 0x02000044

Where can i find detailed description of NVCSI status registers?
What exactly intr_cil_data_lane_sot_mb_err0_a and intr_dphy_cil_deskew_calib_err_lane0_a mean?

And what about virtual channel? Can tx2 recive VC1?

What if I disable crc checks in NVCSI_STREAM_0_PH_CHK_CTRL_0 register? Will nvcsi ignore short packet errors?

Yes, TX2 support VC too.
For the status error you can check the TRM for the detail.
And I can’t tell if disable the crc working for your case but you can disable it during bring up stage.

May it be caused by incorrect configuration of deskew algorithm or cil settle time in NVCSI_CIL_A_CONTROL?

Now after some hardware reworks there’re no crc or sil errors (they not masked). However, we still get SOF timeout and registers NVCSI_STREAM_0_PH_WC_0 and NVCSI_STREAM_0_PH_DI_0 are empty (I read it in csi4_stream_check_status()).

Have I messed something in software configuration?

in DT I set in vi node:

port-index = <0>;
bus-width = <2>;
vc-id = <1>;

nvcsi:

port-index = <0>;
bus-width = <2>;

camera node:

mode0 {
                mclk_khz = "37125";
				num_lanes = "2";
				tegra_sinterface = "serial_a";
				phy_mode = "DPHY";
				discontinuous_clk = "no";
				dpcm_enable = "false";
				cil_settletime = "0";
				dynamic_pixel_bit_depth = "16";
				csi_pixel_bit_depth = "16";
				mode_type = "yuv";
				pixel_phase = "uyvy";

				active_w = "768";
				active_h = "576";
				readout_orientation = "0";
				line_length = "768";
				inherent_gain = "1";
				mclk_multiplier = "20";
				pix_clk_hz = "120000000";
		    };
		    
            ports {
				#address-cells = <1>;
				#size-cells = <0>;
				port@0 {
					reg = <0>;
					tfm_tfm_out0: endpoint {
						port-index = <0>;
						bus-width = <2>;
						remote-endpoint = <&tfm_csi_in0>;
					};
				};
		    };

In camera driver I don’t enable mclk - camera doesn’t use it.
Are there other clock configurations?

Signals are fine on oscilloscope.

How VI driver configures stream ID, data ID, virtual channel ID, CSI data type. In TRM I see that mismatch of any of this items causes SOF timeout. How can I debug it? In trace there are no useful information.

You can check the vi4_fops.c to know how the driver set the data type ect.
Did you check the trace log?

Trace log dont changed. Lots of rtos_queue_peek_from_isr_failed and VI width and height configurations.

It was hardware issue.