Jetson TX2 USB3.0 Performance

Hello,
I am using the Jetson TX2 Developer Kit with Jetpack 4.4. We are using the USB 3.0 port to receive constant rate data from an external signal processing device. We are able to send the TX 2 data at a constant rate of about 896 Mega bits per second with no drops. When we test with rates higher than this we see dropped data.
When we send our max data rate to the TX2 which is 1.792 Giga bits per second we see that the processors have about an 8% utilization but we see dropped data. It seems like the USB 3.0 can perform data transfers at this rate but it seems as though the USB is interrupted from time to time causing drops. Can the TX2 handle a sustained transfer rate of 1.792 giga samples per second? Is there a set up issue or can the TX2 not handle a sustained rate of 1.792 giga bits per second.
I am running with a desktop, headless, and nothing else is going on in the system, the jetson_clocks script was run as well nvpmodel -m 0. Should be the fastest set up.
Clearly the CPU usage is not the bottle neck, seems like something in the USB driver.
Thanks

Hi,
We have published Jetpack 4.4 production release. Please update and give it a try.

Thanks for the reply DaneLLL.
I did an apt-get update and upgrade. Is this the update that you mean?
If so I did this and re-tested with the same results.
The processor is clearly not being challenged with this test it seems as though an interrupt or something is diverting the USB interface. Could it be that the display or some other back round task could cause this?
Thank you

DaneLL,
We are using Jetpack 4.4 that was installed using the SDK manager from the Nvidia web site. The web site says that it is the newest production release. What do you mean to update to Jetpack 4.4? I said I was using Jetpack 4.4.

Do note that there was a 4.4 developer preview. As of today there is a full 4.4 release. For a JetPack/SDKM release listing, see:
https://developer.nvidia.com/embedded/jetpack-archive
(you probably have to log in and then click the link again)

Hello Linuxdev, Thanks for taking the time to post. I do see that there was a preview but I just flashed the device a couple of days ago and I did check to make sure that I did download the production release and I was able to confirm that I did use the production release. Thanks

Hi,
Please share the USB device for reference. Also please run sudo tegrastats and share the result. Probably performance is not capped by USB3 bandwidth but other hardware blocks.

Hello DaneLLL,
The device we are using is a USB based radio. We use various radios to do signal classification using ML.

Here are the tegrastats results

The radio we are using does not have a great deal of buffering so if the USB 3.0 transfers are interrupted and the processor is diverted it could cause the drops. I do believe that the USB 3.0 port is fast enough to ingest the highest rate of this radio but it can’t seem to sustain the transfer rate. Are there devices on the development board that could also be using the USB or something interrupting the usb transfers? The tegrastats show that the processors seem to have plenty of over head.

Using TX2 USB 3.0

nvidia@nvidia-TX2:~/project/avs4000$ sudo tegrastats
RAM 589/7860MB (lfb 1513x4MB) SWAP 0/3930MB (cached 0MB) CPU [31%@2034,0%@2035,0%@2034,32%@2034,26%@2035,24%@2035] EMC_FREQ 1%@1866 GR3D_FREQ 0%@1300 APE 150 MTS fg 0% bg 0% PLL@34.5C MCPU@34.5C PMIC@100C Tboard@30C GPU@32C BCPU@34.5C thermal@33.5C Tdiode@31.75C VDD_SYS_GPU 230/230 VDD_SYS_SOC 996/996 VDD_4V0_WIFI 0/0 VDD_IN 5100/5100 VDD_SYS_CPU 1302/1302 VDD_SYS_DDR 1262/1262

Hi,
Please share dmesg for reference. Would like to check if there is any error prints. If no error is shown in kernel log, may need your help to get the bus trace through USB analyzer.

Here is the dmesg

[    0.000000] Booting Linux on physical CPU 0x100
[    0.000000] Linux version 4.9.140-tegra (buildbrain@mobile-u64-1291) (gcc version 7.3.1 20180425 [linaro-7.3-2018.05 revision d29120a424ecfbc167ef90065c0eeb7f91977701] (Linaro GCC 7.3-2018.05) ) #1 SMP PREEMPT Tue Apr 28 14:06:23 PDT 2020
[    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 ,  185600000
[    0.000000] OF: fdt: - 275e00000 ,  200000
[    0.000000] OF: fdt: - 276600000 ,  200000
[    0.000000] OF: fdt: - 277000000 ,  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: 2055168
[    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: 24008 pages used for memmap
[    0.000000]   Normal zone: 1531392 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 @ffffffc1f676a000 s61656 r8192 d32552 u102400
[    0.000000] pcpu-alloc: s61656 r8192 d32552 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: 2022968
[    0.000000] Kernel command line: root=/dev/mmcblk0p1 rw rootwait rootfstype=ext4 console=ttyS0,115200n8 console=tty0 fbcon=map:0 net.ifnames=0 isolcpus=1-2 video=tegrafb no_console_suspend=1 earlycon=uart8250,mmio32,0x3100000 nvdumper_reserved=0x2772e0000 gpt usbcore.old_scheme_first=1 tegraid=18.1.2.0.0 maxcpus=6 boot.slot_suffix=_b boot.ratchetvalues=0.2031647.1 bl_prof_dataptr=0x10000@0x275840000 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: 29408(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: 7968944K/8220672K available (15294K kernel code, 2934K rwdata, 6636K rodata, 8576K init, 609K bss, 186192K 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 - 0xffffff8008f70000   ( 15296 KB)
[    0.000000]     .rodata : 0xffffff8008f70000 - 0xffffff80095f0000   (  6656 KB)
[    0.000000]       .init : 0xffffff80095f0000 - 0xffffff8009e50000   (  8576 KB)
[    0.000000]       .data : 0xffffff8009e50000 - 0xffffff800a12d808   (  2935 KB)
[    0.000000]        .bss : 0xffffff800a12d808 - 0xffffff800a1c5f3c   (   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 - 0xffffffbf07dc8000   (   125 MB actual)
[    0.000000]     memory  : 0xffffffc000000000 - 0xffffffc1f7200000   (  8050 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.001643] Console: colour dummy device 80x25
[    0.001648] console [tty0] enabled
[    0.001651] bootconsole [uart8250] disabled
[    0.001659] kmemleak: Kernel memory leak detector disabled
[    0.001668] Calibrating delay loop (skipped), value calculated using timer frequency.. 62.50 BogoMIPS (lpj=125000)
[    0.001675] pid_max: default: 32768 minimum: 301
[    0.001987] Security Framework initialized
[    0.002144] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes)
[    0.002148] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes)
[    0.002762] ftrace: allocating 47094 entries in 184 pages
[    0.127547] sched-energy: Sched-energy-costs installed from DT
[    0.127558] ASID allocator initialised with 65536 entries
[    0.164334] tegra-id: chipid=21817.
[    0.164339] tegra-id: opt_subrevision=1.
[    0.164356] Tegra Revision: A02p SKU: 0xdc CPU Process: 0 SoC Process: 0
[    0.164364] DTS File Name: /dvs/git/dirty/git-master_linux/kernel/kernel-4.9/arch/arm64/boot/dts/../../../../../../hardware/nvidia/platform/t18x/quill/kernel-dts/tegra186-quill-p3310-1000-c03-00-base.dts
[    0.164369] DTB Build time: Apr 29 2020 22:40:10
[    0.213677] CPU1: Booted secondary processor [4e0f0030]
[    0.245319] CPU2: Booted secondary processor [4e0f0030]
[    0.277108] CPU3: Booted secondary processor [411fd073]
[    0.309090] CPU4: Booted secondary processor [411fd073]
[    0.341084] CPU5: Booted secondary processor [411fd073]
[    0.341136] Brought up 6 CPUs
[    0.341140] SMP: Total of 6 processors activated.
[    0.341145] CPU features: detected feature: 32-bit EL0 Support
[    0.341833] CPU: All CPU(s) started at EL2
[    0.341865] alternatives: patching kernel code
[    0.344740] devtmpfs: initialized
[    0.383869] Initilizing CustomIPI irq domain
[    0.384092] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns
[    0.384100] futex hash table entries: 2048 (order: 5, 131072 bytes)
[    0.384943] pinctrl core: initialized pinctrl subsystem
[    0.385163] OS set in device tree is not L4T.
[    0.385315] regulator-dummy: no parameters
[    0.385445] Initializing plugin-manager
[    0.385498] Plugin module not found
[    0.385535] Plugin-manager status disabled
[    0.387314] NET: Registered protocol family 16
[    0.387978] pstore: using zlib compression
[    0.387991] console [pstore-1] enabled
[    0.387994] pstore: Registered ramoops as persistent store backend
[    0.387998] ramoops: attached 0x200000@0x275880000, ecc: 0/0
[    0.401122] cpuidle: using governor menu
[    0.402659] bpmp: waiting for handshake
[    0.402662] bpmp: handshake completed
[    0.402680] bpmp: synchronizing channels
[    0.402694] bpmp: channels synchronized
[    0.402697] bpmp: mail init ok
[    0.404792] arm-smmu 12000000.iommu: probing hardware configuration...
[    0.404796] arm-smmu 12000000.iommu: SMMUv2 with:
[    0.404800] arm-smmu 12000000.iommu: 	stage 1 translation
[    0.404803] arm-smmu 12000000.iommu: 	stage 2 translation
[    0.404806] arm-smmu 12000000.iommu: 	nested translation
[    0.404813] arm-smmu 12000000.iommu: 	stream matching with 128 register groups, mask 0x7f80
[    0.404818] arm-smmu 12000000.iommu: SMMU address space size (0x800000) differs from mapped region size (0x1000000)!
[    0.404822] arm-smmu 12000000.iommu: 	64 context banks (0 stage-2 only)
[    0.404826] arm-smmu 12000000.iommu: 	Stage-1: 39-bit VA -> 48-bit IPA
[    0.404830] arm-smmu 12000000.iommu: 	Stage-2: 39-bit IPA -> 48-bit PA
[    0.406958] vdso: 2 pages (1 code @ ffffff8008f77000, 1 data @ ffffff8009e54000)
[    0.406971] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers.
[    0.409288] atomic_pool_init():622: DMA: preallocated 1024 KiB pool for atomic allocations
[    0.411217] Registering BPMP clocks...
[    0.411304] tegra_bpmp_clk_init: clock init ok (638 clks)
[    0.412256] Serial: AMBA PL011 UART driver
[    0.413423] tegra_powergate_init: DONE
[    0.413431] DTS File Name: /dvs/git/dirty/git-master_linux/kernel/kernel-4.9/arch/arm64/boot/dts/../../../../../../hardware/nvidia/platform/t18x/quill/kernel-dts/tegra186-quill-p3310-1000-c03-00-base.dts
[    0.413436] DTB Build time: Apr 29 2020 22:40:10
[    0.415587] Tegra reboot handler registered.
[    0.417137] tegra-pmc c360000.pmc: scratch reg offset dts data not present
[    0.417202] tegra-pmc: get_secure_pmc_setting: done secure_pmc=0
[    0.417215] tegra-pmc: ### PMC reset source: TEGRA_POWER_ON_RESET
[    0.417218] tegra-pmc: ### PMC reset level: TEGRA_RESET_LEVEL_L0
[    0.417220] tegra-pmc: ### PMC reset status reg: 0x0
[    0.417378] padctrl padctrl.0: Pad control driver tegra-pmc-padctrl registered
[    0.417388] tegra-pmc c360000.pmc: IO padctrl driver initialized
[    0.417718] iommu: Adding device 3460000.sdhci to group 0
[    0.418400] iommu: Adding device 3400000.sdhci to group 1
[    0.419438] iommu: Adding device 3507000.ahci-sata to group 2
[    0.419449] arm-smmu: forcing sodev map for 3507000.ahci-sata
[    0.419888] iommu: Adding device 3160000.i2c to group 3
[    0.420325] iommu: Adding device c240000.i2c to group 4
[    0.420668] iommu: Adding device 3180000.i2c to group 5
[    0.421009] iommu: Adding device 3190000.i2c to group 6
[    0.421458] iommu: Adding device 31b0000.i2c to group 7
[    0.421793] iommu: Adding device 31c0000.i2c to group 8
[    0.422126] iommu: Adding device c250000.i2c to group 9
[    0.422458] iommu: Adding device 31e0000.i2c to group 10
[    0.423930] iommu: Adding device 3210000.spi to group 11
[    0.424265] iommu: Adding device c260000.spi to group 12
[    0.424595] iommu: Adding device 3240000.spi to group 13
[    0.425315] iommu: Adding device 3100000.serial to group 14
[    0.425655] iommu: Adding device 3110000.serial to group 15
[    0.425985] iommu: Adding device c280000.serial to group 16
[    0.426317] iommu: Adding device 3130000.serial to group 17
[    0.427668] iommu: Adding device 2490000.ether_qos to group 18
[    0.427679] arm-smmu: forcing sodev map for 2490000.ether_qos
[    0.428341] iommu: Adding device b000000.rtcpu to group 19
[    0.430566] iommu: Adding device 13e10000.host1x to group 20
[    0.430880] iommu: Adding device 13e10000.host1x:ctx0 to group 21
[    0.431177] iommu: Adding device 13e10000.host1x:ctx1 to group 22
[    0.431483] iommu: Adding device 13e10000.host1x:ctx2 to group 23
[    0.431773] iommu: Adding device 13e10000.host1x:ctx3 to group 24
[    0.432068] iommu: Adding device 13e10000.host1x:ctx4 to group 25
[    0.432362] iommu: Adding device 13e10000.host1x:ctx5 to group 26
[    0.432672] iommu: Adding device 13e10000.host1x:ctx6 to group 27
[    0.432977] iommu: Adding device 13e10000.host1x:ctx7 to group 28
[    0.433463] iommu: Adding device 150c0000.nvcsi to group 29
[    0.434104] iommu: Adding device 15700000.vi to group 30
[    0.434457] iommu: Adding device 15600000.isp to group 31
[    0.434914] iommu: Adding device 15210000.nvdisplay to group 32
[    0.435518] iommu: Adding device 15340000.vic to group 33
[    0.435735] iommu: Adding device 154c0000.nvenc to group 34
[    0.435947] iommu: Adding device 15480000.nvdec to group 35
[    0.436287] iommu: Adding device 15380000.nvjpg to group 36
[    0.436511] iommu: Adding device 15500000.tsec to group 37
[    0.436723] iommu: Adding device 15100000.tsecb to group 38
[    0.437412] iommu: Adding device 15810000.se to group 39
[    0.437629] iommu: Adding device 15820000.se to group 40
[    0.437843] iommu: Adding device 15830000.se to group 41
[    0.438051] iommu: Adding device 15840000.se to group 42
[    0.438920] iommu: Adding device smmu_test to group 43
[    0.439512] mc: mapped MMIO address: 0xffffff800a6c0000 -> 0x2c10000
[    0.439522] mc: mapped MMIO address: 0xffffff800a6e0000 -> 0x2c20000
[    0.439532] mc: mapped MMIO address: 0xffffff800a7b0000 -> 0x2c30000
[    0.439544] mc: mapped MMIO address: 0xffffff800a7d0000 -> 0x2c40000
[    0.439553] mc: mapped MMIO address: 0xffffff800b110000 -> 0x2c50000
[    0.439561] nv-tegra-mc 2c10000.mc: No mssnvlink node
[    0.439571] mc-err: mcerr ops are set to t18x
[    0.439707] dram-ecc: DRAM ECC disabled-MC_ECC_CONTROL:0x0000000c
[    0.440785] iommu: Adding device 3530000.xhci to group 44
[    0.440795] arm-smmu: forcing sodev map for 3530000.xhci
[    0.441268] iommu: Adding device 3550000.xudc to group 45
[    0.441279] arm-smmu: forcing sodev map for 3550000.xudc
[    0.441985] tegra186-aowake c370000.pmc: WAKE_AOWAKE_CTRL_0 = 3
[    0.441991] tegra186-aowake c370000.pmc: WAKE_AOWAKE_CNTRL_24(PMU_INT) = 256
[    0.442561] iommu: Adding device 17000000.gp10b to group 46
[    0.443603] tegra-reset 5000000.clock: registered 193 resets.
[    0.445807] iommu: Adding device d000000.bpmp to group 47
[    0.446001] bpmp: ping status is 0
[    0.446101] bpmp d000000.bpmp: firmware tag is 4208eb5c85fe47a2235fbc0b8eb87dca
[    0.448531] bpmp d000000.bpmp: probe ok
[    0.451666] iommu: Adding device 2600000.dma to group 48
[    0.452933] GPIO line 461 (camera-control-output-low) hogged as output/low
[    0.452962] GPIO line 456 (camera-control-output-low) hogged as output/low
[    0.452990] GPIO line 457 (camera-control-output-low) hogged as output/low
[    0.453017] GPIO line 426 (camera-control-output-low) hogged as output/low
[    0.453046] GPIO line 424 (wifi-enable) hogged as output/high
[    0.453073] GPIO line 445 (sdmmc-wake-support-input) hogged as input
[    0.453120] GPIO line 446 (sdmmc-wake-support-output) hogged as output/low
[    0.453276] gpiochip_setup_dev: registered GPIOs 320 to 511 on device: gpiochip0 (tegra-gpio)
[    0.457004] GPIO line 315 (wifi-wake-ap) hogged as input
[    0.457155] gpiochip_setup_dev: registered GPIOs 256 to 319 on device: gpiochip1 (tegra-gpio-aon)
[    0.458744] iommu: Adding device 10003000.pcie-controller to group 49
[    0.458758] arm-smmu: forcing sodev map for 10003000.pcie-controller
[    0.459077] iommu: Adding device sound to group 50
[    0.459438] iommu: Adding device 3510000.hda to group 51
[    0.466943] iommu: Adding device c1a0000.aon to group 52
[    0.467393] vdd-ac-bat: 5000 mV 
[    0.469123] vdd-1v8-ap: 1800 mV 
[    0.493511] HugeTLB registered 2 MB page size, pre-allocated 0 pages
[    0.496292] eventlib_kernel: keventlib is initialized, test id: 0
[    0.496479] SCSI subsystem initialized
[    0.496602] libata version 3.00 loaded.
[    0.496748] usbcore: registered new interface driver usbfs
[    0.496782] usbcore: registered new interface driver hub
[    0.496841] usbcore: registered new device driver usb
[    0.500056] GPIO line 241 (touch-rails) hogged as output/high
[    0.500259] random: fast init done
[    0.500466] GPIO line 242 (touch-rails) hogged as output/high
[    0.500618] gpiochip_setup_dev: registered GPIOs 240 to 255 on device: gpiochip2 (tca9539)
[    0.502170] GPIO line 228 (lcd-bias-rails) hogged as output/high
[    0.502320] gpiochip_setup_dev: registered GPIOs 224 to 239 on device: gpiochip3 (tca9539)
[    0.512003] max77620 4-003c: PMIC Version OTP:0x45 and ES:0x8
[    0.517335] vddio-ddr: at 1125 mV 
[    0.521104] avdd_dsi_csi: 1200 mV 
[    0.525099] vdd-1v8: 1800 mV 
[    0.528695] vdd-3v3-sys: 3300 mV 
[    0.529805] spmic-ldo0: at 1800 mV 
[    0.530506] spmic-ldo1: at 800 mV 
[    0.532696] vddio-3v3: 3300 mV 
[    0.533451] vddio-sdmmc1: 1800 <--> 3300 mV at 3300 mV 
[    0.536700] vdd-rtc: at 800 mV 
[    0.537459] avdd-ts-hv: 1800 <--> 3300 mV at 1800 mV 
[    0.540694] spmic-ldo6: at 1500 mV 
[    0.544695] vdd-pex-1v00: 1000 mV 
[    0.545333] dvdd-pex: 1000 mV 
[    0.547576] GPIO line 221 (spmic_gpio_input) hogged as input
[    0.547731] GPIO line 222 (spmic_gpio_input) hogged as input
[    0.547876] gpiochip_setup_dev: registered GPIOs 216 to 223 on device: gpiochip4 (max77620-gpio)
[    0.548085] max77620 4-003c: max77620 probe successful
[    0.548470] media: Linux media interface: v0.10
[    0.548500] Linux video capture interface: v2.00
[    0.552185] pps_core: LinuxPPS API ver. 1 registered
[    0.552189] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>
[    0.552204] PTP clock support registered
[    0.552404] tegra_wdt_t18x 30c0000.watchdog: Expiry count is deprecated
[    0.552599] tegra_wdt_t18x 30c0000.watchdog: Tegra WDT init timeout = 120 sec
[    0.552627] tegra_wdt_t18x 30c0000.watchdog: Registered successfully
[    0.554020] trusty trusty: trusty version: Built: 22:26:48 Apr 29 2020 
[    0.554034] trusty trusty: selected api version: 3 (requested 3)
[    0.554826] Advanced Linux Sound Architecture Driver Initialized.
[    0.555135] Bluetooth: Core ver 2.22
[    0.555160] NET: Registered protocol family 31
[    0.555163] Bluetooth: HCI device and connection manager initialized
[    0.555169] Bluetooth: HCI socket layer initialized
[    0.555174] Bluetooth: L2CAP socket layer initialized
[    0.555188] Bluetooth: SCO socket layer initialized
[    0.555918] en-vdd-sd: 3300 mV 
[    0.556611] en-vdd-cam: 1800 mV 
[    0.561113] vdd-hdmi: 5000 mV 
[    0.561405] vdd-usb0-5v: 5000 mV 
[    0.561685] vdd-usb1-5v: 5000 mV 
[    0.564697] en-vdd-ts-1v8: 1800 mV 
[    0.568756] en-vdd-ts-hv-3v3: 3300 mV 
[    0.569477] en-vdd-disp-3v3: 3300 mV 
[    0.570149] en-mdm-pwr-3v7: 3700 mV 
[    0.570818] en-vdd-disp-1v8: 1800 mV 
[    0.571480] en-vdd-cam-hv-2v8: 2800 mV 
[    0.572153] en-vdd-cam-1v2: 1200 mV 
[    0.572822] vdd-fan: 5000 mV 
[    0.573057] vdd-3v3: 3300 mV 
[    0.573952] dis-vdd-1v2: 1200 mV 
[    0.574198] en-vdd-vcm-2v8: 2800 mV 
[    0.574879] vdd-usb2-5v: 5000 mV 
[    0.575142] vdd-sys-bl: 3300 mV 
[    0.575831] en-vdd-sys: 1200 mV 
[    0.577926] camchar: rtcpu character device driver loaded
[    0.578446] gpio tegra-gpio wake53 for gpio=159(X:7)
[    0.580134] extcon-gpio-states external-connection:extcon@1: Cable state:0, cable id:0
[    0.580848] clocksource: Switched to clocksource arch_sys_counter
[    0.602630] VFS: Disk quotas dquot_6.6.0
[    0.602701] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)
[    0.602936] nvmap_heap_init: nvmap_heap_init: created heap block cache
[    0.603436] nvmap: nvmap_select_cache_ops() nvmap cache ops set to roc
[    0.603441] nvmap_page_pool_init: Total RAM pages: 2008620
[    0.603444] nvmap_page_pool_init: nvmap page pool size: 251077 pages (980 MB)
[    0.603507] nvmap_background_zero_thread: PP zeroing thread starting.
[    0.606026] tegra-gpcdma 2600000.dma: GPC DMA driver register 32 channels
[    0.611521] thermal thermal_zone0: Registering thermal zone thermal_zone0 for type BCPU-therm
[    0.611691] thermal thermal_zone1: Registering thermal zone thermal_zone1 for type MCPU-therm
[    0.612075] thermal thermal_zone2: Registering thermal zone thermal_zone2 for type GPU-therm
[    0.612149] thermal thermal_zone3: Registering thermal zone thermal_zone3 for type PLL-therm
[    0.612253] thermal thermal_zone4: Registering thermal zone thermal_zone4 for type Tboard_tegra
[    0.612356] thermal thermal_zone5: Registering thermal zone thermal_zone5 for type Tdiode_tegra
[    0.612509] thermal thermal_zone6: Registering thermal zone thermal_zone6 for type PMIC-Die
[    0.612709] la/ptsa driver initialized.
[    0.612718] pre_t19x_iso_plat_init(): iso emc max clk=1866000KHz
[    0.612721] pre_t19x_iso_plat_init(): max_iso_bw=26870400KB
[    0.613045] NET: Registered protocol family 2
[    0.613642] TCP established hash table entries: 65536 (order: 7, 524288 bytes)
[    0.613945] TCP bind hash table entries: 65536 (order: 8, 1048576 bytes)
[    0.614535] TCP: Hash tables configured (established 65536 bind 65536)
[    0.614591] UDP hash table entries: 4096 (order: 5, 131072 bytes)
[    0.614706] UDP-Lite hash table entries: 4096 (order: 5, 131072 bytes)
[    0.615033] NET: Registered protocol family 1
[    0.615409] RPC: Registered named UNIX socket transport module.
[    0.615412] RPC: Registered udp transport module.
[    0.615415] RPC: Registered tcp transport module.
[    0.615417] RPC: Registered tcp NFSv4.1 backchannel transport module.
[    0.615423] PCI: CLS 0 bytes, default 64
[    0.615530] Trying to unpack rootfs image as initramfs...
[    0.782481] Freeing initrd memory: 5432K
[    0.798421] host1x 13e10000.host1x: initialized
[    0.799570] hw perfevents: enabled with armv8_pmuv3 PMU driver, 7 counters available
[    0.801716] audit: initializing netlink subsys (disabled)
[    0.801752] audit: type=2000 audit(0.696:1): initialized
[    0.802198] workingset: timestamp_bits=46 max_order=21 bucket_order=0
[    0.808707] squashfs: version 4.0 (2009/01/31) Phillip Lougher
[    0.809693] ntfs: driver 2.1.32 [Flags: R/W].
[    0.810200] 9p: Installing v9fs 9p2000 file system support
[    0.812300] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 240)
[    0.812378] io scheduler noop registered
[    0.812577] io scheduler cfq registered (default)
[    0.815757] gic 2a41000.agic-controller: GIC IRQ controller registered
[    0.858372] iommu: Adding device aconnect@2a41000:adsp_audio to group 53
[    0.862168] iommu: Adding device 2993000.adsp to group 54
[    0.862263] tegra-aconnect aconnect@2a41000: Tegra ACONNECT bus registered
[    0.864678] tegra-xusb-padctl 3520000.xusb_padctl: VBUS over-current detection enabled
[    0.868336] tegra-pwm 3280000.pwm: PWM clk can sleep in ops
[    0.869497] tegra-pwm 3290000.pwm: PWM clk can sleep in ops
[    0.870572] tegra-pwm 32a0000.pwm: PWM clk can sleep in ops
[    0.871597] tegra-pwm c340000.pwm: PWM clk can sleep in ops
[    0.873858] tegra-pcie 10003000.pcie-controller: 4x1, 1x1 configuration
[    0.874797] tegra-pcie 10003000.pcie-controller: PCIE: Enable power rails
[    0.875137] tegra-pcie 10003000.pcie-controller: probing port 0, using 4 lanes
[    0.875286] tegra_camera_platform tegra-camera-platform: tegra_camera_probe:camera_platform_driver probe
[    0.875387] misc tegra_camera_ctrl: tegra_camera_isomgr_register tpg_max_iso = 3916800KBs
[    0.875392] misc tegra_camera_ctrl: tegra_camera_isomgr_register isp_iso_bw=4687500, vi_iso_bw=1500000, max_bw=4687500
[    0.877625] tegra-pcie 10003000.pcie-controller: probing port 2, using 1 lanes
[    0.878895] tsec 15500000.tsec: initialized
[    0.879952] tsec 15100000.tsecb: initialized
[    0.883440] nvdec 15480000.nvdec: initialized
[    0.889736] falcon 15340000.vic: initialized
[    0.891061] falcon 154c0000.nvenc: initialized
[    0.892262] falcon 15380000.nvjpg: initialized
[    0.894151] iommu_context_dev 13e10000.host1x:ctx0: initialized (streamid=56)
[    0.895595] iommu_context_dev 13e10000.host1x:ctx1: initialized (streamid=57)
[    0.897073] iommu_context_dev 13e10000.host1x:ctx2: initialized (streamid=58)
[    0.898532] iommu_context_dev 13e10000.host1x:ctx3: initialized (streamid=59)
[    0.899971] iommu_context_dev 13e10000.host1x:ctx4: initialized (streamid=60)
[    0.901481] iommu_context_dev 13e10000.host1x:ctx5: initialized (streamid=61)
[    0.902980] iommu_context_dev 13e10000.host1x:ctx6: initialized (streamid=62)
[    0.904455] iommu_context_dev 13e10000.host1x:ctx7: initialized (streamid=63)
[    0.907793] tegradccommon 15200000.dc_common: host1x channel mapped
[    0.907800] tegradccommon 15200000.dc_common: dc_common syncpt # 1 allocated
[    0.907836] tegradccommon 15200000.dc_common: dma mapping done
[    0.908009] tegra_cec 3960000.tegra_cec: dt=1 start=0x03960000 end=0x03960FFF irq=390
[    0.908521] tegradc 15210000.nvdisplay: disp0 connected to head1->/host1x/sor1
[    0.908563] generic_infoframe_type: 0x87
[    0.908641] tegradc 15210000.nvdisplay: DT parsed successfully
[    0.908654] tegra_cec 3960000.tegra_cec: Unpowergate DISP: 0.
[    0.908662] tegradc 15210000.nvdisplay: Display dc.ffffff800b650000 registered with id=0
[    0.908876] tegra_cec 3960000.tegra_cec: Enable clock result: 0.
[    0.908968] tegra_cec 3960000.tegra_cec: tegra_cec_init started
[    0.909036] tegra_cec 3960000.tegra_cec: cec_add_sysfs ret=0
[    0.909039] tegra_cec 3960000.tegra_cec: probed
[    0.916617] tegra_nvdisp_bandwidth_register_max_config: max config iso bw = 16727000 KB/s
[    0.916622] tegra_nvdisp_bandwidth_register_max_config: max config EMC floor = 665600000 Hz
[    0.916624] tegra_nvdisp_bandwidth_register_max_config: max config hubclk = 357620000 Hz
[    0.916865] tegradc 15210000.nvdisplay: vblank syncpt # 8 for dc 1
[    0.916870] tegradc 15210000.nvdisplay: vpulse3 syncpt # 9 for dc 1
[    0.920538] tegradc 15210000.nvdisplay: hdmi: invalid prod list prod_list_hdmi_board
[    0.920542] tegradc 15210000.nvdisplay: hdmi: tegra_hdmi_tmds_range_read(bd) failed
[    0.923267] tegradc 15210000.nvdisplay: probed
[    0.923603] Console: switching to colour frame buffer device 80x30
[    0.923623] tegradc 15210000.nvdisplay: fb registered
[    0.923865] gpio tegra-gpio wake21 for gpio=121(P:1)
[    0.924385] tegra-adma 2930000.adma: Tegra210 ADMA driver registered 32 channels
[    0.925047] tegra-fuse-burn 3820000.efuse:efuse-burn: shutdown limit check disabled
[    0.925086] tegra-fuse-burn 3820000.efuse:efuse-burn: Fuse burn driver initialized
[    0.925495] kfuse 3830000.kfuse: initialized
[    0.926843] tegra-pmc-iopower pmc-iopower: NO_IOPOWER setting 0x0
[    0.929601] Serial: 8250/16550 driver, 4 ports, IRQ sharing disabled
[    0.931878] console [ttyS0] disabled
[    0.931912] 3100000.serial: ttyS0 at MMIO 0x3100000 (irq = 36, base_baud = 25500000) is a Tegra
[    0.931945] console [ttyS0] enabled
[    0.933291] 3110000.serial: ttyTHS1 at MMIO 0x3110000 (irq = 37, base_baud = 0) is a TEGRA_UART
[    0.934131] c280000.serial: ttyTHS2 at MMIO 0xc280000 (irq = 38, base_baud = 0) is a TEGRA_UART
[    0.934283] serial-tegra 3130000.serial: RX in PIO mode
[    0.935013] 3130000.serial: ttyTHS3 at MMIO 0x3130000 (irq = 39, base_baud = 0) is a TEGRA_UART
[    0.935960] [drm] Initialized
[    0.942708] brd: module loaded
[    0.946280] loop: module loaded
[    0.946371] tegra_profiler: version: 1.141, samples/io: 48/28
[    0.946469] tegra_profiler: auth: init
[    0.946797] nct1008_nct72 7-004c: starting parse dt
[    0.946882] nct1008_nct72 7-004c: !!!Found deprecated property!!!
[    0.946886] nct1008_nct72 7-004c: success parsing dt
[    0.946977] nct1008_nct72 7-004c: success in enabling tmp451 VDD rail
[    0.947592] THERMAL EST: found 3 subdevs
[    0.947595] THERMAL EST num_resources: 0
[    0.947599] [THERMAL EST subdev 0]
[    0.947603] [THERMAL EST subdev 1]
[    0.947606] [THERMAL EST subdev 2]
[    0.947871] thermal thermal_zone7: Registering thermal zone thermal_zone7 for type thermal-fan-est
[    0.947873] THERMAL EST: thz register success.
[    0.947957] THERMAL EST: end of probe, return err: 0
[    0.948577] sd: No Scsi addr parsed to reserve index
[    0.948606] hisi_sas: driver version v1.6
[    0.952099] tegra-ahci 3507000.ahci-sata: AHCI 0001.0301 32 slots 2 ports 3 Gbps 0x1 impl platform mode
[    0.952106] tegra-ahci 3507000.ahci-sata: flags: 64bit ncq sntf pm led pmp pio slum part deso sadm apst 
[    0.952824] scsi host0: tegra_ahci
[    0.953255] scsi host1: tegra_ahci
[    0.953389] ata1: SATA max UDMA/133 mmio [mem 0x03507000-0x03508fff] port 0x100 irq 24
[    0.953392] ata2: DUMMY
[    0.958358] libphy: Fixed MDIO Bus: probed
[    0.958901] tun: Universal TUN/TAP device driver, 1.6
[    0.958904] tun: (C) 1999-2004 Max Krasnyansky <maxk@qualcomm.com>
[    0.959560] e1000e: Intel(R) PRO/1000 Network Driver - 3.2.6-k
[    0.959563] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.
[    0.959594] igb: Intel(R) Gigabit Ethernet Network Driver - version 5.4.0-k
[    0.959596] igb: Copyright (c) 2007-2014 Intel Corporation.
[    0.959627] igbvf: Intel(R) Gigabit Virtual Function Network Driver - version 2.4.0-k
[    0.959629] igbvf: Copyright (c) 2009 - 2012 Intel Corporation.
[    0.959661] Intel(R) 10GbE PCI Express Linux Network Driver - version 4.6.4
[    0.959664] Copyright(c) 1999 - 2017 Intel Corporation.
[    0.968940] eqos 2490000.ether_qos: can't get pllrefe_vcoout clk (-2)
[    0.971662] eqos 2490000.ether_qos: Setting local MAC: 0 4 4b 8c cc d6
[    0.971761] libphy: dwc_phy: probed
[    0.972039] bcm54xx_low_power_mode(): put phy in iddq-lp mode
[    0.973908] PPP generic driver version 2.4.2
[    0.974021] PPP BSD Compression module registered
[    0.974024] PPP Deflate Compression module registered
[    0.974036] PPP MPPE Compression module registered
[    0.974040] NET: Registered protocol family 24
[    0.974081] usbcore: registered new interface driver r8152
[    0.974111] usbcore: registered new interface driver asix
[    0.974140] usbcore: registered new interface driver ax88179_178a
[    0.974165] usbcore: registered new interface driver cdc_ether
[    0.974193] usbcore: registered new interface driver net1080
[    0.974219] usbcore: registered new interface driver cdc_subset
[    0.974247] usbcore: registered new interface driver zaurus
[    0.974283] usbcore: registered new interface driver cdc_ncm
[    0.974385] VFIO - User Level meta-driver version: 0.3
[    0.975179] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
[    0.975188] ehci-pci: EHCI PCI platform driver
[    0.975217] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver
[    0.975223] ohci-pci: OHCI PCI platform driver
[    0.975253] ohci-platform: OHCI generic platform driver
[    0.978795] nct1008_nct72 7-004c: LOC shutdown limit 107
[    0.979284] nct1008_nct72 7-004c: EXT shutdown limit 107
[    0.980021] tegra-xusb 3530000.xhci: USB2 port 0 has OTG_CAP
[    0.981587] tegra-xusb 3530000.xhci: extcon 0: ffffffc1eb43f400 id
[    0.982256] usbcore: registered new interface driver uas
[    0.982293] usbcore: registered new interface driver usb-storage
[    0.982352] usbcore: registered new interface driver usbserial
[    0.983913] tegra-xusb 3530000.xhci: Firmware timestamp: 2019-10-31 05:52:15 UTC, Version: 55.17 release
[    0.986968] tegra-xudc-new 3550000.xudc: device count: 1
[    0.987947] tegra-xudc-new 3550000.xudc: vbus state: 0
[    0.988283] tegra-xudc-new 3550000.xudc: entering ELPG
[    0.988404] mousedev: PS/2 mouse device common for all mice
[    0.988451] usbcore: registered new interface driver xpad
[    0.990458] tegra-xudc-new 3550000.xudc: entering ELPG done
[    1.009196] tegra-xusb 3530000.xhci: xHCI Host Controller
[    1.009215] tegra-xusb 3530000.xhci: new USB bus registered, assigned bus number 1
[    1.009933] tegra-xusb 3530000.xhci: hcc params 0x0184fd25 hci version 0x100 quirks 0x00050810
[    1.009963] tegra-xusb 3530000.xhci: irq 70, io mem 0x03530000
[    1.010093] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002
[    1.010098] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    1.010102] usb usb1: Product: xHCI Host Controller
[    1.010105] usb usb1: Manufacturer: Linux 4.9.140-tegra xhci-hcd
[    1.010108] usb usb1: SerialNumber: 3530000.xhci
[    1.010423] hub 1-0:1.0: USB hub found
[    1.010451] hub 1-0:1.0: 4 ports detected
[    1.010774] tegra-xusb 3530000.xhci: xHCI Host Controller
[    1.010781] tegra-xusb 3530000.xhci: new USB bus registered, assigned bus number 2
[    1.010911] usb usb2: New USB device found, idVendor=1d6b, idProduct=0003
[    1.010916] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    1.010919] usb usb2: Product: xHCI Host Controller
[    1.010922] usb usb2: Manufacturer: Linux 4.9.140-tegra xhci-hcd
[    1.010925] usb usb2: SerialNumber: 3530000.xhci
[    1.011192] hub 2-0:1.0: USB hub found
[    1.011216] hub 2-0:1.0: 3 ports detected
[    1.012017] nct1008_nct72 7-004c: initial LOC temp: 26000 
[    1.012323] nct1008_nct72 7-004c: initial EXT temp: 39000 
[    1.012927] nct1008_nct72 7-004c: hi_b:-11, lo_b:0
[    1.013219] gpio tegra-gpio-aon wake35 for gpio=16(V:0)
[    1.013400] nct1008_nct72 7-004c: nct1008_probe: initialized
[    1.103404] max77686-rtc max77620-rtc: rtc core: registered max77620-rtc as rtc0
[    1.104494] Wake73 for irq=42
[    1.105732] rtc rtc1: alarm rtc device
[    1.105743] tegra_rtc c2a0000.rtc: rtc core: registered c2a0000.rtc as rtc1
[    1.105757] tegra_rtc c2a0000.rtc: Tegra internal Real Time Clock
[    1.105963] i2c /dev entries driver
[    1.107872] max77620-power max77620-power: Event recorder REG_NVERC : 0x0
[    1.112625] parse_throttle_dt_data: Num cap clks = 4
[    1.112630] parse_throttle_dt_data: clk=mcpu type=1
[    1.112633] parse_throttle_dt_data: clk=bcpu type=2
[    1.112639] parse_throttle_dt_data: clk=gpu type=4
[    1.112642] parse_throttle_dt_data: clk=emc type=3
[    1.113594] tegra_throttle_probe: probe successful. #cdevs=4
[    1.114162] FAN dev name: pwm-fan
[    1.114221] FAN:gpio request success.
[    1.114230] FAN: can't find tach_gpio
[    1.114253] pwm_fan_driver pwm-fan: cap state:7, cap pwm:255
[    1.114428] pwm_fan_driver pwm-fan: got pwm for fan. polarity is inversed
[    1.114433] pwm_fan_driver pwm-fan: tach period: 1000
[    1.114990] pwm_fan_driver pwm-fan: index 0: pwm=0, rpm=0, rru=40, rrd=40, state:2
[    1.114994] pwm_fan_driver pwm-fan: index 1: pwm=80, rpm=1000, rru=2, rrd=2, state:2
[    1.114998] pwm_fan_driver pwm-fan: index 2: pwm=120, rpm=2000, rru=1, rrd=1, state:2
[    1.115002] pwm_fan_driver pwm-fan: index 3: pwm=160, rpm=3000, rru=1, rrd=1, state:2
[    1.115006] pwm_fan_driver pwm-fan: index 4: pwm=255, rpm=4000, rru=1, rrd=1, state:3
[    1.115010] pwm_fan_driver pwm-fan: index 5: pwm=255, rpm=5000, rru=1, rrd=1, state:3
[    1.115013] pwm_fan_driver pwm-fan: index 6: pwm=255, rpm=6000, rru=1, rrd=1, state:3
[    1.115016] pwm_fan_driver pwm-fan: index 7: pwm=255, rpm=7000, rru=1, rrd=1, state:4
[    1.115020] pwm_fan_driver pwm-fan: index 8: pwm=255, rpm=10000, rru=1, rrd=1, state:4
[    1.115024] pwm_fan_driver pwm-fan: index 9: pwm=255, rpm=11000, rru=1, rrd=1, state:4
[    1.115923] device-mapper: uevent: version 1.0.3
[    1.116325] device-mapper: ioctl: 4.35.0-ioctl (2016-06-23) initialised: dm-devel@redhat.com
[    1.116590] CPU EMC frequency mapping table: from device tree
[    1.123207] cpufreq: platform driver Initialization: pass
[    1.123823] cpuidle: Initializing cpuidle driver init for Denver cluster
[    1.123937] cpuidle: Initializing cpuidle driver init for A57 cluster
[    1.124721] sdhci: Secure Digital Host Controller Interface driver
[    1.124723] sdhci: Copyright(c) Pierre Ossman
[    1.124725] sdhci-pltfm: SDHCI platform and OF driver helper
[    1.125953] sdhci-tegra 3460000.sdhci: Client registration for eMC Successful
[    1.127709] CMDQ: cmdq_platfm_init successful
[    1.128759] CQE: CMD_TIMING bit set for R1B DCMD
[    1.131015] sdhci-tegra 3440000.sdhci: Client registration for eMC Successful
[    1.136853] sdhci-tegra 3400000.sdhci: Got CD GPIO
[    1.136908] sdhci-tegra 3400000.sdhci: Got WP GPIO
[    1.137536] sdhci-tegra 3400000.sdhci: Client registration for eMC Successful
[    1.139206] sdhci-tegra 3400000.sdhci: wakeup init done, cdirq 257
[    1.141437] tegra-se-elp 3ad0000.se_elp: tegra_se_elp_probe: complete
[    1.142292] tegra-se-nvhost 15810000.se: initialized
[    1.143468] tegra-se-nvhost 15810000.se: tegra_se_probe: complete
[    1.144042] tegra-se-nvhost 15820000.se: initialized
[    1.144968] tegra-se-nvhost 15820000.se: tegra_se_probe: complete
[    1.145516] tegra-se-nvhost 15830000.se: initialized
[    1.146259] tegra-se-nvhost 15830000.se: tegra_se_probe: complete
[    1.146790] tegra-se-nvhost 15840000.se: initialized
[    1.147642] tegra-se-nvhost 15840000.se: tegra_se_probe: complete
[    1.147881] hidraw: raw HID events driver (C) Jiri Kosina
[    1.149124] usbcore: registered new interface driver usbhid
[    1.149127] usbhid: USB HID core driver
[    1.154513] tegra186-cam-rtcpu b000000.rtcpu: Trace buffer configured at IOVA=0xbff00000
[    1.156598] tegra-ivc ivc-b000000.rtcpu: region 0: iova=0xbfef0000-0xbfefdb7f size=56192
[    1.156953] tegra-ivc ivc-b000000.rtcpu:echo@0: echo: ver=0 grp=1 RX[16x64]=0x1000-0x1480 TX[16x64]=0x1480-0x1900
[    1.157081] tegra-ivc ivc-b000000.rtcpu:i2c@480: i2c: ver=0 grp=1 RX[8x128]=0x1900-0x1d80 TX[8x128]=0x1d80-0x2200
[    1.157275] tegra-ivc ivc-b000000.rtcpu:vinotify@12c0: vinotify: ver=0 grp=1 RX[64x128]=0x2200-0x4280 TX[64x128]=0x4280-0x6300
[    1.157947] tegra-ivc ivc-b000000.rtcpu:mods@32c0: mods: ver=0 grp=1 RX[1x64]=0x6300-0x63c0 TX[1x64]=0x63c0-0x6480
[    1.158018] tegra-ivc ivc-b000000.rtcpu:ivccontrol@52c0: ivccontrol: ver=0 grp=1 RX[16x320]=0x6480-0x7900 TX[16x320]=0x7900-0x8d80
[    1.158069] tegra-ivc ivc-b000000.rtcpu:ivccapture@72c0: ivccapture: ver=0 grp=1 RX[16x64]=0x8d80-0x9200 TX[16x64]=0x9200-0x9680
[    1.158115] tegra-ivc ivc-b000000.rtcpu:dbg@7c00: dbg: ver=0 grp=1 RX[1x384]=0x9680-0x9880 TX[1x384]=0x9880-0x9a80
[    1.158208] tegra-ivc ivc-b000000.rtcpu:dbg@7e00: dbg: ver=0 grp=1 RX[1x8192]=0x9a80-0xbb00 TX[1x8192]=0xbb00-0xdb80
[    1.158528] tegra186-cam-rtcpu b000000.rtcpu: using cam RTCPU IRQ (51)
[    1.158530] tegra186-cam-rtcpu b000000.rtcpu: tegra_camrtc_mon_create is successful
[    1.159045] tegra186-cam-rtcpu b000000.rtcpu: firmware version cpu=sce cmd=5 sha1=3ecb1dca25fa98771f988f238c025334776c9a96
[    1.160651] tegra_aon c1a0000.aon: tegra aon driver probe OK
[    1.161120] tegra186-aondbg aondbg: aondbg driver probe() OK
[    1.161275] register_ari_mca_banks: Registered MCA ROC:IOB
[    1.161340] register_ari_mca_banks: Registered MCA ROC:CCE
[    1.161658] tegra18-bridge 2390000.axi2apb: axi_cbb clk rate = 115 MHZ, timeout = 97000 useconds
[    1.161661] tegra18-bridge 2390000.axi2apb: enabled timeout = 11155000
[    1.161663] tegra18-bridge 2390000.axi2apb: bridge probed OK
[    1.161713] tegra18-bridge 23a0000.axi2apb: axi_cbb clk rate = 115 MHZ, timeout = 97000 useconds
[    1.161715] tegra18-bridge 23a0000.axi2apb: enabled timeout = 11155000
[    1.161717] tegra18-bridge 23a0000.axi2apb: bridge probed OK
[    1.161765] tegra18-bridge 23b0000.axi2apb: axi_cbb clk rate = 115 MHZ, timeout = 97000 useconds
[    1.161767] tegra18-bridge 23b0000.axi2apb: enabled timeout = 11155000
[    1.161769] tegra18-bridge 23b0000.axi2apb: bridge probed OK
[    1.161819] tegra18-bridge 23c0000.axi2apb: axi_cbb clk rate = 115 MHZ, timeout = 97000 useconds
[    1.161822] tegra18-bridge 23c0000.axi2apb: enabled timeout = 11155000
[    1.161824] tegra18-bridge 23c0000.axi2apb: bridge probed OK
[    1.161874] tegra18-bridge 23d0000.axi2apb: axi_cbb clk rate = 115 MHZ, timeout = 97000 useconds
[    1.161876] tegra18-bridge 23d0000.axi2apb: enabled timeout = 11155000
[    1.161878] tegra18-bridge 23d0000.axi2apb: bridge probed OK
[    1.161924] tegra18-bridge 2100000.axip2p: axi_cbb clk rate = 115 MHZ, timeout = 97000 useconds
[    1.161929] tegra18-bridge 2100000.axip2p: enabled timeout = 11155000
[    1.161931] tegra18-bridge 2100000.axip2p: bridge probed OK
[    1.161979] tegra18-bridge 2110000.axip2p: axi_cbb clk rate = 115 MHZ, timeout = 97000 useconds
[    1.161984] tegra18-bridge 2110000.axip2p: enabled timeout = 11155000
[    1.161986] tegra18-bridge 2110000.axip2p: bridge probed OK
[    1.162032] tegra18-bridge 2120000.axip2p: axi_cbb clk rate = 115 MHZ, timeout = 97000 useconds
[    1.162036] tegra18-bridge 2120000.axip2p: enabled timeout = 11155000
[    1.162038] tegra18-bridge 2120000.axip2p: bridge probed OK
[    1.162084] tegra18-bridge 2130000.axip2p: axi_cbb clk rate = 115 MHZ, timeout = 97000 useconds
[    1.162089] tegra18-bridge 2130000.axip2p: enabled timeout = 11155000
[    1.162091] tegra18-bridge 2130000.axip2p: bridge probed OK
[    1.162138] tegra18-bridge 2140000.axip2p: axi_cbb clk rate = 115 MHZ, timeout = 97000 useconds
[    1.162140] tegra18-bridge 2140000.axip2p: enabled timeout = 11155000
[    1.162142] tegra18-bridge 2140000.axip2p: bridge probed OK
[    1.162189] tegra18-bridge 2150000.axip2p: axi_cbb clk rate = 115 MHZ, timeout = 97000 useconds
[    1.162194] tegra18-bridge 2150000.axip2p: enabled timeout = 11155000
[    1.162196] tegra18-bridge 2150000.axip2p: bridge probed OK
[    1.162244] tegra18-bridge 2160000.axip2p: axi_cbb clk rate = 115 MHZ, timeout = 97000 useconds
[    1.162249] tegra18-bridge 2160000.axip2p: enabled timeout = 11155000
[    1.162251] tegra18-bridge 2160000.axip2p: bridge probed OK
[    1.162304] tegra18-bridge 2170000.axip2p: axi_cbb clk rate = 115 MHZ, timeout = 97000 useconds
[    1.162309] tegra18-bridge 2170000.axip2p: enabled timeout = 11155000
[    1.162312] tegra18-bridge 2170000.axip2p: bridge probed OK
[    1.162361] tegra18-bridge 2180000.axip2p: axi_cbb clk rate = 115 MHZ, timeout = 97000 useconds
[    1.162366] tegra18-bridge 2180000.axip2p: enabled timeout = 11155000
[    1.162368] tegra18-bridge 2180000.axip2p: bridge probed OK
[    1.162413] tegra18-bridge 2190000.axip2p: axi_cbb clk rate = 115 MHZ, timeout = 97000 useconds
[    1.162417] tegra18-bridge 2190000.axip2p: enabled timeout = 11155000
[    1.162419] tegra18-bridge 2190000.axip2p: bridge probed OK
[    1.162477] **** A57 ECC: Enabled
[    1.162480] tegra18_a57_serr_init: on CPU 3 a A57 Core
[    1.162540] tegra18x_actmon d230000.actmon: in actmon_register()...
[    1.162975] tegra18x_actmon d230000.actmon: initialization Completed for the device mc_all
[    1.163383] hw perfevents: enabled with denver15_uncore_pmu PMU driver, 3 counters available
[    1.163512] denver_knobs_init:MTS_VERSION:47926945
[    1.165324] nvpmodel: initialized successfully
[    1.166198] trusty-virtio trusty:virtio: initializing
[    1.166304] trusty_ipc virtio0: vring0: va(pa)  ffffffc1e92ee000(0) qsz 32 notifyid 1
[    1.166310] trusty_ipc virtio0: vring1: va(pa)  ffffffc1e7e14000(0) qsz 32 notifyid 2
[    1.166376] trusty-virtio trusty:virtio: initializing done
[    1.166459] trusty_ipc virtio0: is online
[    1.168130] usbcore: registered new interface driver snd-usb-audio
[    1.168885] mmc0: SDHCI controller on 3460000.sdhci [3460000.sdhci] using ADMA 64-bit with 64 bit addr
[    1.175649] input: tegra-hda HDMI/DP,pcm=3 as /devices/3510000.hda/sound/card0/input0
[    1.175764] input: tegra-hda HDMI/DP,pcm=7 as /devices/3510000.hda/sound/card0/input1
[    1.176883] mmc1: SDHCI controller on 3440000.sdhci [3440000.sdhci] using ADMA 64-bit with 64 bit addr
[    1.188923] gpio tegra-gpio wake71 for gpio=125(P:5)
[    1.188971] mmc2: SDHCI controller on 3400000.sdhci [3400000.sdhci] using ADMA 64-bit with 64 bit addr
[    1.254096] OPE platform probe
[    1.254171] OPE platform probe successful
[    1.254285] mmc0: mmc_decode_ext_csd: CMDQ supported: depth: 31, cmdq_support: 1
[    1.267226] ata1: SATA link down (SStatus 0 SControl 300)
[    1.267425] mmc0: periodic cache flush enabled
[    1.267432] mmc0: new HS400 Enhanced strobe MMC card at address 0001
[    1.267804] mmcblk0: mmc0:0001 032G34 29.1 GiB 
[    1.272044] mmcblk0boot0: mmc0:0001 032G34 partition 1 4.00 MiB
[    1.276194] mmcblk0boot1: mmc0:0001 032G34 partition 2 4.00 MiB
[    1.280341] mmcblk0rpmb: mmc0:0001 032G34 partition 3 4.00 MiB
[    1.283323]  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 p32 p33
[    1.305679] tegra-pcie 10003000.pcie-controller: link 0 down, retrying
[    1.328710] tegra-asoc: sound: ADMAIF1 <-> ADMAIF1 mapping ok
[    1.328781] tegra-asoc: sound: ADMAIF2 <-> ADMAIF2 mapping ok
[    1.328868] tegra-asoc: sound: ADMAIF3 <-> ADMAIF3 mapping ok
[    1.328938] tegra-asoc: sound: ADMAIF4 <-> ADMAIF4 mapping ok
[    1.329002] tegra-asoc: sound: ADMAIF5 <-> ADMAIF5 mapping ok
[    1.329073] tegra-asoc: sound: ADMAIF6 <-> ADMAIF6 mapping ok
[    1.329137] tegra-asoc: sound: ADMAIF7 <-> ADMAIF7 mapping ok
[    1.329210] tegra-asoc: sound: ADMAIF8 <-> ADMAIF8 mapping ok
[    1.329276] tegra-asoc: sound: ADMAIF9 <-> ADMAIF9 mapping ok
[    1.329342] tegra-asoc: sound: ADMAIF10 <-> ADMAIF10 mapping ok
[    1.329410] tegra-asoc: sound: ADMAIF11 <-> ADMAIF11 mapping ok
[    1.329477] tegra-asoc: sound: ADMAIF12 <-> ADMAIF12 mapping ok
[    1.329543] tegra-asoc: sound: ADMAIF13 <-> ADMAIF13 mapping ok
[    1.329610] tegra-asoc: sound: ADMAIF14 <-> ADMAIF14 mapping ok
[    1.329674] tegra-asoc: sound: ADMAIF15 <-> ADMAIF15 mapping ok
[    1.329738] tegra-asoc: sound: ADMAIF16 <-> ADMAIF16 mapping ok
[    1.329807] tegra-asoc: sound: ADMAIF17 <-> ADMAIF17 mapping ok
[    1.329871] tegra-asoc: sound: ADMAIF18 <-> ADMAIF18 mapping ok
[    1.329936] tegra-asoc: sound: ADMAIF19 <-> ADMAIF19 mapping ok
[    1.330004] tegra-asoc: sound: ADMAIF20 <-> ADMAIF20 mapping ok
[    1.340886] usb 1-2: new high-speed USB device number 2 using tegra-xusb
[    1.349214] u32 classifier
[    1.349217]     Actions configured
[    1.349277] Initializing XFRM netlink socket
[    1.349872] NET: Registered protocol family 10
[    1.350464] NET: Registered protocol family 17
[    1.350473] NET: Registered protocol family 15
[    1.350530] bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this.
[    1.350571] Bluetooth: RFCOMM socket layer initialized
[    1.350580] Bluetooth: RFCOMM ver 1.11
[    1.350584] Bluetooth: HIDP (Human Interface Emulation) ver 1.2
[    1.350588] Bluetooth: HIDP socket layer initialized
[    1.350602] 9pnet: Installing 9P2000 support
[    1.350633] Key type dns_resolver registered
[    1.350902] Registered cp15_barrier emulation handler
[    1.350915] Registered setend emulation handler
[    1.351710] registered taskstats version 1
[    1.364434] isp 15600000.isp: initialized
[    1.374830] usb 1-2: New USB device found, idVendor=2109, idProduct=2820
[    1.374836] usb 1-2: New USB device strings: Mfr=1, Product=2, SerialNumber=0
[    1.374838] usb 1-2: Product: USB2.0 Hub             
[    1.374840] usb 1-2: Manufacturer: VIA Labs, Inc.         
[    1.376994] hub 1-2:1.0: USB hub found
[    1.377969] hub 1-2:1.0: 4 ports detected
[    1.396724] nvcsi 150c0000.nvcsi: initialized
[    1.398639] tegra-vi4 15700000.vi: using default number of vi channels,15
[    1.401030] gpio tegra-gpio-aon wake29 for gpio=56(FF:0)
[    1.401109] gpio tegra-gpio-aon wake67 for gpio=57(FF:1)
[    1.401177] gpio tegra-gpio-aon wake68 for gpio=58(FF:2)
[    1.401302] input: gpio-keys as /devices/gpio-keys/input/input2
[    1.421239] mmc1: hw tuning done ...
[    1.421734] tegra-vi4 15700000.vi: initialized
[    1.422613] tegra-vi4 15700000.vi: subdev 150c0000.nvcsi--1 bound
[    1.425096] mmc1: new ultra high speed SDR104 SDIO card at address 0001
[    1.433534] tegra_rtc c2a0000.rtc: setting system clock to 2020-07-10 13:53:14 UTC (1594389194)
[    1.434832] mmcblk mmc0:0001: Card claimed for testing.
[    1.463067] bpmp: mounted debugfs mirror
[    1.464313] bwmgr: missing cdev-type property
[    1.470896] spmic-ldo0: disabling
[    1.471057] spmic-ldo1: disabling
[    1.471198] en-vdd-sd: disabling
[    1.471201] en-vdd-cam: disabling
[    1.471205] vdd-usb0-5v: disabling
[    1.471208] vdd-usb1-5v: disabling
[    1.471212] en-vdd-disp-3v3: disabling
[    1.471215] en-mdm-pwr-3v7: disabling
[    1.471218] en-vdd-disp-1v8: disabling
[    1.471220] en-vdd-cam-hv-2v8: disabling
[    1.471223] en-vdd-cam-1v2: disabling
[    1.471226] vdd-fan: disabling
[    1.471229] vdd-3v3: disabling
[    1.471232] en-vdd-vcm-2v8: disabling
[    1.471236] vdd-sys-bl: disabling
[    1.471239] en-vdd-sys: disabling
[    1.471242] ALSA device list:
[    1.471246]   #0: tegra-hda at 0x3518000 irq 383
[    1.471249]   #1: tegra-snd-t186ref-mobile-rt565x
[    1.473553] Freeing unused kernel memory: 8576K
[    1.491039] Root device found: mmcblk0p1
[    1.491825] Found dev node: /dev/mmcblk0p1
[    1.512164] EXT4-fs (mmcblk0p1): mounted filesystem with ordered data mode. Opts: (null)
[    1.512777] Rootfs mounted over mmcblk0p1
[    1.540491] Switching from initrd to actual rootfs
[    1.686922] ip_tables: (C) 2000-2006 Netfilter Core Team
[    1.695628] cgroup: cgroup2: unknown option "nsdelegate"
[    1.708035] 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.708758] systemd[1]: Detected architecture arm64.
[    1.710896] tegra-pcie 10003000.pcie-controller: link 0 down, retrying
[    1.727164] systemd[1]: Set hostname to <nvidia-TX2>.
[    1.823169] 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.
[    1.823177] systemd[1]: Proceeding WITHOUT firewalling in effect! (This warning is only shown for the first loaded unit using IP firewalling.)
[    1.924876] tegra_cec 3960000.tegra_cec: Can't find physical addresse.
[    1.924880] tegra_cec 3960000.tegra_cec: tegra_cec_init Done.
[    1.947571] random: systemd: uninitialized urandom read (16 bytes read)
[    1.948833] systemd[1]: Created slice User and Session Slice.
[    1.949092] random: systemd: uninitialized urandom read (16 bytes read)
[    1.950443] systemd[1]: Set up automount Arbitrary Executable File Formats File System Automount Point.
[    1.950501] random: systemd: uninitialized urandom read (16 bytes read)
[    1.951174] systemd[1]: Created slice System Slice.
[    1.951372] systemd[1]: Listening on udev Control Socket.
[    1.951482] systemd[1]: Listening on RPCbind Server Activation Socket.
[    1.983978] gpio tegra-gpio-aon wake30 for gpio=60(FF:4)
[    1.998416] EXT4-fs (mmcblk0p1): re-mounted. Opts: (null)
[    2.092891] usb 1-2.1: new high-speed USB device number 3 using tegra-xusb
[    2.113383] usb 1-2.1: New USB device found, idVendor=04b4, idProduct=00f3
[    2.113389] usb 1-2.1: New USB device strings: Mfr=1, Product=2, SerialNumber=3
[    2.113391] usb 1-2.1: Product: WestBridge 
[    2.113394] usb 1-2.1: Manufacturer: Cypress
[    2.113396] usb 1-2.1: SerialNumber: 0000000004BE
[    2.120888] tegra-pcie 10003000.pcie-controller: link 0 down, retrying
[    2.122929] tegra-pcie 10003000.pcie-controller: link 0 down, ignoring
[    2.284523] systemd-journald[2263]: Received request to flush runtime journal from PID 1
[    2.530916] tegra-pcie 10003000.pcie-controller: link 2 down, retrying
[    2.850681] using random self ethernet address
[    2.855627] using random host ethernet address
[    2.938899] tegra-pcie 10003000.pcie-controller: link 2 down, retrying
[    3.065155] random: crng init done
[    3.068566] random: 7 urandom warning(s) missed due to ratelimiting
[    3.352887] tegra-pcie 10003000.pcie-controller: link 2 down, retrying
[    3.355053] tegra-pcie 10003000.pcie-controller: link 2 down, ignoring
[    3.389880] Mass Storage Function, version: 2009/09/11
[    3.389887] LUN: removable file: (no medium)
[    3.399367] using random self ethernet address
[    3.403860] using random host ethernet address
[    3.415253] ov5693 2-0036: probing v4l2 sensor.
[    3.441475] rndis0: HOST MAC 1e:45:a2:c3:e9:20
[    3.441543] rndis0: MAC 1e:45:a2:c3:e9:21
[    3.441943] ov5693 2-0036: tegracam sensor driver:ov5693_v2.0.6
[    3.443326] usb0: HOST MAC 1e:45:a2:c3:e9:22
[    3.443514] usb0: MAC 1e:45:a2:c3:e9:23
[    3.443541] tegra-xudc-new 3550000.xudc: exiting ELPG
[    3.447970] tegra-xudc-new 3550000.xudc: exiting ELPG done
[    3.447992] tegra-xudc-new 3550000.xudc: ep 0 (type: 0, dir: out) enabled
[    3.448044] tegra-xudc-new 3550000.xudc: entering ELPG
[    3.450135] tegra-xudc-new 3550000.xudc: entering ELPG done
[    3.450874] tegra-xudc-new 3550000.xudc: exiting ELPG
[    3.452480] tegra-xudc-new 3550000.xudc: exiting ELPG done
[    3.452504] tegra-xudc-new 3550000.xudc: entering ELPG
[    3.453814] tegra-xudc-new 3550000.xudc: entering ELPG done
[    3.462141] l4tbr0: port 1(rndis0) entered blocking state
[    3.462150] l4tbr0: port 1(rndis0) entered disabled state
[    3.462872] device rndis0 entered promiscuous mode
[    3.486385] IPv6: ADDRCONF(NETDEV_UP): rndis0: link is not ready
[    3.494392] l4tbr0: port 2(usb0) entered blocking state
[    3.494398] l4tbr0: port 2(usb0) entered disabled state
[    3.494913] device usb0 entered promiscuous mode
[    3.503059] IPv6: ADDRCONF(NETDEV_UP): usb0: link is not ready
[    3.559429] tegra-pcie 10003000.pcie-controller: PCIE: no end points detected
[    3.559718] tegra-pcie 10003000.pcie-controller: PCIE: Disable power rails
[    3.755063] dhd_module_init in
[    3.755147] found wifi platform device bcmdhd_wlan
[    3.769165] wifi_platform_get_country_code_map: could not get country_code_map
[    3.769171] wifi_plat_dev_drv_probe:platform country code map is not available
[    3.769185] Power-up adapter 'DHD generic adapter'
[    3.769195] wifi_platform_set_power = 1
[    3.940816] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready
[    3.976907] wifi_platform_bus_enumerate device present 1
[    3.998489] wifi_platform_bus_enumerate device present 0
[    4.004683] F1 signature read @0x18000000=0x17214354
[    4.021806] gpio tegra-gpio wake18 for gpio=101(M:5)
[    4.027192] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready
[    4.049118] zram: Added device: zram0
[    4.053052] zram: Added device: zram1
[    4.057358] zram: Added device: zram2
[    4.058804] zram: Added device: zram3
[    4.059256] zram: Added device: zram4
[    4.059658] zram: Added device: zram5
[    4.097685] zram0: detected capacity change from 0 to 686804992
[    4.128185] Adding 670704k swap on /dev/zram0.  Priority:5 extents:1 across:670704k SS
[    4.136130] zram1: detected capacity change from 0 to 686804992
[    4.144922] Wake76 for irq=199
[    4.144927] Wake77 for irq=199
[    4.144928] Wake78 for irq=199
[    4.144929] Wake79 for irq=199
[    4.144930] Wake80 for irq=199
[    4.144932] Wake81 for irq=199
[    4.144933] Wake82 for irq=199
[    4.144972] tegra-xusb 3530000.xhci: Upgrade port 0 to USB3.0
[    4.144976] tegra-xusb 3530000.xhci: Upgrade port 1 to USB3.0
[    4.154268] F1 signature OK, socitype:0x1 chip:0x4354 rev:0x1 pkg:0x2
[    4.155107] DHD: dongle ram size is set to 786432(orig 786432) at 0x180000
[    4.155194] wifi_platform_get_mac_addr
[    4.162569] ids dhd_log_netlink_init
[    4.163075] wl_create_event_handler(): thread:wl_event_handler:15ae started
[    4.163221]  wl_event_handler : 
[    4.163225] tsk Enter, tsk = 0xffffffc1cf701a90
[    4.163254] dhd_attach(): thread:dhd_watchdog_thread:15b0 started
[    4.163318] dhd_attach(): thread:dhd_dpc:15b1 started
[    4.163369] dhd_attach(): thread:dhd_rxf:15b2 started
[    4.163375] dhd_deferred_work_init: work queue initialized 
[    4.163694] 
               Dongle Host Driver, version 1.201.82 (r)
               Compiled from 
[    4.174959] Adding 670704k swap on /dev/zram1.  Priority:5 extents:1 across:670704k SS
[    4.187143] zram2: detected capacity change from 0 to 686804992
[    4.188151] Register interface [wlan0]  MAC: 00:04:4b:8c:cc:d4

[    4.188158] dhd_prot_ioctl : bus is down. we have nothing to do
[    4.202234] sdhci-tegra 3440000.sdhci: Tuning done, restoring the best tap value : 69
[    4.203280] wifi_platform_set_power = 0
[    4.220493] Adding 670704k swap on /dev/zram2.  Priority:5 extents:1 across:670704k SS
[    4.224311] zram3: detected capacity change from 0 to 686804992
[    4.245694] Adding 670704k swap on /dev/zram3.  Priority:5 extents:1 across:670704k SS
[    4.249161] zram4: detected capacity change from 0 to 686804992
[    4.277444] Adding 670704k swap on /dev/zram4.  Priority:5 extents:1 across:670704k SS
[    4.282736] zram5: detected capacity change from 0 to 686804992
[    4.315871] Adding 670704k swap on /dev/zram5.  Priority:5 extents:1 across:670704k SS
[    4.465461] usb 2-1: new SuperSpeed USB device number 2 using tegra-xusb
[    4.487325] usb 2-1: New USB device found, idVendor=2109, idProduct=0820
[    4.487332] usb 2-1: New USB device strings: Mfr=1, Product=2, SerialNumber=0
[    4.487335] usb 2-1: Product: USB3.1 Hub             
[    4.487337] usb 2-1: Manufacturer: VIA Labs, Inc.         
[    4.490640] hub 2-1:1.0: USB hub found
[    4.491440] hub 2-1:1.0: 4 ports detected
[    4.660026] tegra-vi4 15700000.vi: subdev ov5693 2-0036 bound
[    4.924894] usb 2-1: usb_suspend_both: status 0
[    4.925066] usb usb2: usb_suspend_both: status 0
[    5.145177] tegradc 15210000.nvdisplay: blank - powerdown
[    5.940547] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
[    5.940673] 
               
               Dongle Host Driver, version 1.201.82 (r)
               Compiled from 
[    5.940682] wl_android_wifi_on in
[    5.940686] wifi_platform_set_power = 1
[    6.148557] vdd-1v8: voltage operation not allowed
[    6.153423] sdhci-tegra 3440000.sdhci: could not set regulator OCR (-1)
[    6.181163] vdd-1v8: voltage operation not allowed
[    6.185986] sdhci-tegra 3440000.sdhci: could not set regulator OCR (-1)
[    6.209874] vdd-1v8: voltage operation not allowed
[    6.214685] sdhci-tegra 3440000.sdhci: could not set regulator OCR (-1)
[    6.223751] vdd-1v8: voltage operation not allowed
[    6.228756] sdhci-tegra 3440000.sdhci: could not set regulator OCR (-1)
[    6.338167] vdd-1v8: voltage operation not allowed
[    6.342998] sdhci-tegra 3440000.sdhci: could not set regulator OCR (-1)
[    6.350560] vdd-1v8: voltage operation not allowed
[    6.355426] sdhci-tegra 3440000.sdhci: could not set regulator OCR (-1)
[    6.363750] vdd-1v8: voltage operation not allowed
[    6.368612] sdhci-tegra 3440000.sdhci: could not set regulator OCR (-1)
[    6.375283] sdhci-tegra 3440000.sdhci: Tuning done, restoring the best tap value : 69
[    6.375855] F1 signature read @0x18000000=0x17214354
[    6.380176] F1 signature OK, socitype:0x1 chip:0x4354 rev:0x1 pkg:0x2
[    6.380787] DHD: dongle ram size is set to 786432(orig 786432) at 0x180000
[    6.450367] dhdsdio_write_vars: Download, Upload and compare of NVRAM succeeded.
[    6.492433] dhd_bus_init: enable 0x06, ready 0x06 (waited 0us)
[    6.492901] gpio tegra-gpio-aon wake69 for gpio=59(FF:3)
[    6.492962] gpio tegra-gpio-aon wake69 for gpio=59(FF:3)
[    6.492965] Enabling wake69
[    6.493794] wifi_platform_get_mac_addr
[    6.495992] Firmware up: op_mode=0x0005, MAC=00:04:4b:8c:cc:d4
[    6.500659] clm path from default:/lib/firmware/brcm/bcmdhd.clm_blob
[    6.500688] Skipping the clm download. len:0 memblk:          (null)
[    6.503043] dhd_preinit_ioctls pspretend_threshold for HostAPD failed  -23
[    6.508413] Firmware version = wl0: May 17 2019 16:59:40 version 7.35.349.79 (r714996 CY) FWID 01-e527e2ad
[    6.510825] dhd_interworking_enable: failed to set WNM info, ret=-23
[    6.620376] CFGP2P-ERROR) wl_cfgp2p_add_p2p_disc_if : 
[    6.620380] P2P interface registered
[    6.628349] WLC_E_IF: NO_IF set, event Ignored

[    6.637501] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
[    7.040422] eqos 2490000.ether_qos eth0: Link is Up - 1Gbps/Full - flow control rx/tx
[    7.041057] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
[  256.219025] usb 1-2.1: reset high-speed USB device number 3 using tegra-xusb
[  257.378217] usb 1-2.1: reset high-speed USB device number 3 using tegra-xusb
[  257.791735] usb 1-2.1: USB disconnect, device number 3
[  257.814125] usb 2-1.1: new SuperSpeed USB device number 3 using tegra-xusb
[  257.834357] usb 2-1.1: LPM exit latency is zeroed, disabling LPM.
[  257.835016] usb 2-1.1: New USB device found, idVendor=04b4, idProduct=00f1
[  257.835021] usb 2-1.1: New USB device strings: Mfr=1, Product=2, SerialNumber=0
[  257.835023] usb 2-1.1: Product: AVS4000
[  257.835026] usb 2-1.1: Manufacturer: Avid
[  257.981787] usb 1-2: usb_suspend_both: status 0
[  257.981859] usb usb1: usb_suspend_both: status 0
[  288.369344] usb 2-1.1: USB disconnect, device number 3
[  288.578155] usb 2-1: usb_suspend_both: status 0
[  288.578699] usb usb2: usb_suspend_both: status 0
[  288.694056] usb 1-2.1: new high-speed USB device number 4 using tegra-xusb
[  288.717339] usb 1-2.1: New USB device found, idVendor=04b4, idProduct=00f3
[  288.717401] usb 1-2.1: New USB device strings: Mfr=1, Product=2, SerialNumber=3
[  288.717428] usb 1-2.1: Product: WestBridge 
[  288.717446] usb 1-2.1: Manufacturer: Cypress
[  288.717462] usb 1-2.1: SerialNumber: 0000000004BE
[  297.494752] usb 1-2.1: reset high-speed USB device number 4 using tegra-xusb
[  297.727678] usb 1-2.1: USB disconnect, device number 4
[  297.925918] usb 1-2: usb_suspend_both: status 0
[  297.926098] usb usb1: usb_suspend_both: status 0
[  297.935362] usb 2-1.1: new SuperSpeed USB device number 4 using tegra-xusb
[  297.954861] usb 2-1.1: LPM exit latency is zeroed, disabling LPM.
[  297.956610] usb 2-1.1: New USB device found, idVendor=04b4, idProduct=00f1
[  297.956636] usb 2-1.1: New USB device strings: Mfr=1, Product=2, SerialNumber=0
[  297.956660] usb 2-1.1: Product: AVS4000
[  297.956677] usb 2-1.1: Manufacturer: Avid
[  304.737514] usb 2-1.1: USB disconnect, device number 4
[  304.946091] usb 2-1: usb_suspend_both: status 0
[  304.946582] usb usb2: usb_suspend_both: status 0
[  305.058101] usb 1-2.1: new high-speed USB device number 5 using tegra-xusb
[  305.081188] usb 1-2.1: New USB device found, idVendor=04b4, idProduct=00f3
[  305.081235] usb 1-2.1: New USB device strings: Mfr=1, Product=2, SerialNumber=3
[  305.081253] usb 1-2.1: Product: WestBridge 
[  305.081267] usb 1-2.1: Manufacturer: Cypress
[  305.081280] usb 1-2.1: SerialNumber: 0000000004BE
[  305.386214] usb 1-2.1: reset high-speed USB device number 5 using tegra-xusb
[  305.658957] usb 2-1.1: new SuperSpeed USB device number 5 using tegra-xusb
[  305.663867] usb 1-2.1: USB disconnect, device number 5
[  305.678519] usb 2-1.1: LPM exit latency is zeroed, disabling LPM.
[  305.679661] usb 2-1.1: New USB device found, idVendor=04b4, idProduct=00f1
[  305.679668] usb 2-1.1: New USB device strings: Mfr=1, Product=2, SerialNumber=0
[  305.679672] usb 2-1.1: Product: AVS4000
[  305.679675] usb 2-1.1: Manufacturer: Avid
[  305.849848] usb 1-2: usb_suspend_both: status 0
[  305.849934] usb usb1: usb_suspend_both: status 0

Hello,
Any thoughts on the dmesg?
Thanks

Do check to be sure the device is actually running at USB3 speeds. See:
lsusb -t
…where “480M” indicates USB2, “5000M” indicates USB3.1 gen. 1, and “10000M” indicates USB3.1 gen. 2.

Also, make sure two USB3 devices are not using the same root HUB…in that case it might just be the bandwidth as a whole is ok, but sharing gets in the way.

Hi,
From the log, there is a USB2 device:

[  305.058101] usb 1-2.1: new high-speed USB device number 5 using tegra-xusb
[  305.081188] usb 1-2.1: New USB device found, idVendor=04b4, idProduct=00f3
[  305.081235] usb 1-2.1: New USB device strings: Mfr=1, Product=2, SerialNumber=3
[  305.081253] usb 1-2.1: Product: WestBridge 
[  305.081267] usb 1-2.1: Manufacturer: Cypress
[  305.081280] usb 1-2.1: SerialNumber: 0000000004BE

And a USB3 device:

[  305.658957] usb 2-1.1: new SuperSpeed USB device number 5 using tegra-xusb
[  305.678519] usb 2-1.1: LPM exit latency is zeroed, disabling LPM.
[  305.679661] usb 2-1.1: New USB device found, idVendor=04b4, idProduct=00f1
[  305.679668] usb 2-1.1: New USB device strings: Mfr=1, Product=2, SerialNumber=0
[  305.679672] usb 2-1.1: Product: AVS4000
[  305.679675] usb 2-1.1: Manufacturer: Avid

Could you share how you connect the two devices to type-A port on TX2 devkit? Probably the USB2 device caps performance.

Hello linuxDev and DaneLL
Thanks for taking the time to respond.
What you are seeing in the dmesg is the radio device switching from USB 2 to the USB3.0 bus. The radio comes up in USB 2.0 mode. There is firmware that is loaded to the radio in USB 2.0 and then once the radio firmware is successfully programmed the radio switches to the USB 3.0 bus. I believe that is what you are seeing in dmesg. This is normal behavior of the device.
It seems as though that the TX2 can handle the high rate USB 3.0 data from the radio for a short amount of time. It seems as though the TX2 may either locks out interrupts for the USB for some short amount of time or the TX2 goes to perform other tasks and stops requesting data from the radio for some amount of time so buffers on the radio overflow and data is dropped.
I thought that perhaps the wifi (which we are not using) may be on the USB bus or something else is taking the TX2 away from servicing the USB for some short amount of time causing intermittent drops of data due to buffer over runs in the radio. These over runs start at surprising low USB transfer rates. So it isn’t that the TX2 USB port can’t handle the high rates, it just can’t sustain USB 3.0 rates. Given that the processor utilization isn’t that high it just seems as though the TX2 system is just ignoring or some how is being diverted away from servicing the USB 3.0 port for a period of time that causes over runs on the bus. It would be nice to know if that is the case so we could turn off what ever the TX2 needs to service so we could get a better sustained rate over the USB 3.0 bus.
Thanks again

One other piece of information. When we install a USB 3.0 PCIe card into the PCIe slot of the TX2, the TX2 can sustain the USB3.0 rates of the radio. This again shows that the TX2 is not processor bound in the transfer of this data. It again points to something interrupting the TX2 from requesting data from the USB 3.0 device to the point that data buffers on the external device overflows and data is dropped.

You might try increasing the amount of buffer the Jetson uses for its USB. This argument will set a large size:
usbcore.usbfs_memory_mb=1024

Unfortunately, I’m not sure how to examine what the current buffer size is. Note that this is not the buffer built in to the UART, but is instead buffer space the driver will use. Depending on release, you might be able to just append this to the “APPEND” key/value pair in “/boot/extlinux/extlinux.conf”, and verify after boot if it shows up under “cat /proc/cmdline”.

Hmmm, that is a very good thought. I will give that a try.

Thanks so much. I will let you know how it goes

Hello linuxdev,
I set the buffer size to 1024 as you suggested but sadly it had no effect. It is interesting that when I do speed tests with things that do not require a sustained rate exchange with the USB 3.0 I can get reasonable good number. It is when sustained data is begin sent to the USB 3.0 that problems start to arise. It is like the TX2’s average data request latency over the USB 3.0 is good but the maximum latency is poor and devices that are sending single sustained data rates to the TX2 are going to drop data in the instances when the TX2 hits one of its max latency events.
I am going to look at disabling the WiFi, since we don’t use it, in hopes that it is perhaps causing the TX2 latency issues. It really seems like the process servicing the USB is being put to sleep for too long of a period to handle sustained USB data rates. It seems as though the TX2 system has been configured with some assumptions about buffer sizes on the USB device.
The big bother is that I know this TX2 can handle all the rates we send to it because I can just put a USB 3.0 card into the PCIe slot and all works perfectly. I just can’t seem to figure out what is holding up the native USB interface. In our custom design we have an FPGA connected to the PCIe lanes so using a USB interface over PCIe is not going to work for us.
Thanks again for participating in the conversation.

Are the processes which use the USB data in user space? Or are they other drivers in kernel space? If in user space, then perhaps it is just a case of increasing priority by a small amount for those processes. A small increase in priority would mean those processes get priority over “normal” software. If in user space, how are your programs started, e.g., on command line?

Hello linuxdev and DaneLLL,
I am a bit hat in hand this morning as I believe this topic can be closed in that we have discovered the speed issue we were experiencing with the TX2. The speed issue was caused by our USB interface. We set up the USB end point in the radio to start sending data before we set up the USB interface in the TX2. This caused data buffers on the radio to begin to fill while the USB was being set up on the TX2. We reversed the set up order of these two things i.e. set up the USB on the TX2 first then started the radio end point. The TX2 is now able to consume and process the maximum sustained rate of the radio which is approximately 1.7 Gbits/sec. I apologize for unnecessarily exercising the participants on this forum but I am greatly appreciative for your participation. So the TX2 is able to process a sustained rate of 1.7 Gbits/sec and in this case has about 60% of CPU utilization. It works well. Sorry

1 Like