This thread has been locked.

If you have a related question, please click the "Ask a related question" button in the top right corner. The newly created question will be automatically linked to this question.

AM3352: AM3352 USB Host Connection Error

Part Number: AM3352

Hi, 

LTE Modem is connected with AM3352 using USB1 Host interface.

Sometimes USB host connection is disconnected.

to restore connection, turn off/on power or reinstall USB driver.

I attach log message.

Starting kernel ...


TMS 3.1 tms ttyS5

tms login: tmsadmin
Password:
% Service is not available yet.
% Service is not available yet.
# sh
~ # tail -f /mnt/nvlog/messages &
~ # echo 0 > /sys/class/leds/pwr:cell_gate_en/brightness
~ # echo 1 > /sys/class/leds/pwr:lte_pwr_onoff/brightness
~ # sleep 2;echo 1 > /sys/class/leds/pwr:cell_gate_en/brightness
Jun 12 11:31:28 tms user.info kernel: [   31.095461] usb 2-1: GSM modem (1-port) converter now attached to ttyUSB3sleep 1;e
Jun 12 11:31:28 tms user.info kernel: [   31.106506] qmi_wwan 2-1:1.4: cdc-wdm0: USB WDM device
Jun 12 11:31:28 tms user.info kernel: [   31.108853] qmi_wwan 2-1:1.4 wwan0: register 'qmi_wwan' at usb-musb-hdrc.1-1, WWAN/QMI device, 36:5f:f3:a4:5d:4a
Jun 12 11:31:28 tms daemon.warn systemd-udevd[263]: Could not generate persistent MAC address for wwan0: No such file or directory
Jun 12 11:31:09 tms user.info kernel: [   11.394524] (stk) :ldisc_install = 1
Jun 12 11:31:09 tms user.info kernel: [   12.406495] (stk) :ldisc installation timeout
Jun 12 11:31:10 tms user.info kernel: [   12.406520] (stk) :ldisc_install = 0
Jun 12 11:31:10 tms user.info kernel: [   13.444792] (stk) : timed out waiting for ldisc to be un-installed
Jun 12 11:31:10 tms user.info kernel: [   13.446850] Bluetooth: st_register failed -22
Jun 12 11:31:11 tms authpriv.notice login[228]: ROOT LOGIN  on '/dev/ttyS5'
cho 0 > /sys/class/leds/pwr:lte_pwr_onoff/brightness
~ # sleep 1;echo 0 > /sys/class/leds/pwr:lte_pwr_onoff/brightness
~ # sleep 1;echo 1 > /sys/class/leds/pwr:lte_pwr_onoff/brightness
~ # sleep 1;echo 0 > /sys/class/leds/pwr:lte_pwr_onoff/brightness

~ # lsusb -d 2c7c: -v | grep -e iManufacturer -e iProduct
~ # Jun 12 11:33:49 tms user.notice kernel: [  172.124768] random: crng init done
~ # lsusb
Bus 002 Device 001: ID 1d6b:0002 Linux Foundation 2.0 root hub
Bus 001 Device 001: ID 1d6b:0002 Linux Foundation 2.0 root hub
~ #
~ #

~ # rmmod musb_dsps
~ # Jun 12 19:36:53 tms user.info kernel: [29156.373794] musb-hdrc musb-hdrc.1: remove, state 4
Jun 12 19:36:53 tms user.info kernel: [29156.373876] usb usb2: USB disconnect, device number 1
Jun 12 19:36:53 tms user.info kernel: [29156.385792] musb-hdrc musb-hdrc.1: USB bus 2 deregistered
Jun 12 19:36:53 tms user.info kernel: [29156.389693] musb-hdrc musb-hdrc.0: remove, state 4
Jun 12 19:36:53 tms user.info kernel: [29156.389763] usb usb1: USB disconnect, device number 1
Jun 12 19:36:53 tms user.info kernel: [29156.404835] musb-hdrc musb-hdrc.0: USB bus 1 deregistered

~ # rmmod musb_hdrc
~ # rmmod musb_am335x
rmmod: ERROR: could not remove module musb_am335x: Device or resource busy
~ # modprobe musb_hdrc
~ # modprobe musb_am335x
~ # modprobe musb_dsps
~ # [29194.494827] usb 2-1: device descriptor read/64, error -71

~ # Jun 12 19:37:31 tms user.info kernel: [29193.775348] musb-hdrc musb-hdrc.0: MUSB HDRC host driver
Jun 12 19:37:31 tms user.info kernel: [29193.775452] musb-hdrc musb-hdrc.0: new USB bus registered, assigned bus number 1
Jun 12 19:37:31 tms user.info kernel: [29193.783825] hub 1-0:1.0: USB hub found
Jun 12 19:37:31 tms user.info kernel: [29193.793213] hub 1-0:1.0: 1 port detected
Jun 12 19:37:31 tms user.info kernel: [29193.889831] musb-hdrc musb-hdrc.1: MUSB HDRC host driver
Jun 12 19:37:31 tms user.info kernel: [29193.889932] musb-hdrc musb-hdrc.1: new USB bus registered, assigned bus number 2
Jun 12 19:37:31 tms user.info kernel: [29193.924916] hub 2-0:1.0: USB hub found
Jun 12 19:37:31 tms user.info kernel: [29193.925096] hub 2-0:1.0: 1 port detected
Jun 12 19:37:31 tms user.info kernel: [29194.354835] usb 2-1: new full-speed USB device number 2 using musb-hdrc
Jun 12 19:37:31 tms user.err kernel: [29194.494827] usb 2-1: device descriptor read/64, error -71
[29194.755081] usb 2-1: device descriptor read/64, error -71

~ # [29195.154830] usb 2-1: device descriptor read/64, error -71
[29195.414829] usb 2-1: device descriptor read/64, error -71
Jun 12 19:37:32 tms user.err kernel: [29194.755081] usb 2-1: device descriptor read/64, error -71
Jun 12 19:37:32 tms user.info kernel: [29195.014837] usb 2-1: new full-speed USB device number 3 using musb-hdrc
Jun 12 19:37:32 tms user.err kernel: [29195.154830] usb 2-1: device descriptor read/64, error -71
Jun 12 19:37:32 tms user.err kernel: [29195.414829] usb 2-1: device descriptor read/64, error -71
Jun 12 19:37:33 tms user.info kernel: [29195.534984] usb usb2-port1: attempt power cycle

~ # Jun 12 19:37:33 tms user.info kernel: [29196.014830] usb 2-1: new full-speed USB device number 4 using musb-hdrc
Jun 12 19:37:34 tms user.info kernel: [29196.594839] usb 2-1: new full-speed USB device number 5 using musb-hdrc

~ #
~ # echo 0 > /sys/class/leds/pwr:cell_gate_en/brightness
pwr:lte_pwr_onoff/brightness;
~ # echo 1 > /sys/class/leds/pwr:lte_pwr_onoff/brightness;
~ # echo 1 > /sys/class/leds/pwr:cell_gate_en/brightness
~ # echo 0 > /sys/class/leds/pwr:lte_pwr_onoff/brightness
~ # sleep 1;echo 1 > /sys/class/leds/pwr:lte_pwr_onoff/brightness;
sleep 1;echo 0 > /sys/class/leds/pwr:lte_pwr_onoff/brightness
~ # sleep 1;echo 0 > /sys/class/leds/pwr:lte_pwr_onoff/brightness
~ #
~ # lsusb
Bus 002 Device 001: ID 1d6b:0002 Linux Foundation 2.0 root hub
Bus 001 Device 001: ID 1d6b:0002 Linux Foundation 2.0 root hub
~ # Jun 12 19:38:07 tms user.info kernel: [29229.664839] usb 2-1: new high-speed USB device number 6 using musb-hdrc
Jun 12 19:38:07 tms user.info kernel: [29229.852704] option 2-1:1.0: GSM modem (1-port) converter detected
Jun 12 19:38:07 tms user.info kernel: [29229.866673] usb 2-1: GSM modem (1-port) converter now attached to ttyUSB0
Jun 12 19:38:07 tms user.info kernel: [29229.868396] option 2-1:1.1: GSM modem (1-port) converter detected
Jun 12 19:38:07 tms user.info kernel: [29229.875369] usb 2-1: GSM modem (1-port) converter now attached to ttyUSB1
Jun 12 19:38:07 tms user.info kernel: [29229.877134] option 2-1:1.2: GSM modem (1-port) converter detected
Jun 12 19:38:07 tms user.info kernel: [29229.889778] usb 2-1: GSM modem (1-port) converter now attached to ttyUSB2
Jun 12 19:38:07 tms user.info kernel: [29229.891537] option 2-1:1.3: GSM modem (1-port) converter detected
Jun 12 19:38:07 tms user.info kernel: [29229.895491] usb 2-1: GSM modem (1-port) converter now attached to ttyUSB3
Jun 12 19:38:07 tms user.info kernel: [29229.914875] qmi_wwan 2-1:1.4: cdc-wdm0: USB WDM device
Jun 12 19:38:07 tms user.info kernel: [29229.917297] qmi_wwan 2-1:1.4 wwan0: register 'qmi_wwan' at usb-musb-hdrc.1-1, WWAN/QMI device, 36:5f:f3:a4:5d:4a
Jun 12 19:38:07 tms daemon.warn systemd-udevd[1429]: Could not generate persistent MAC address for wwan0: No such file or directory

~ #
~ #
~ #
~ # dmesg
[    0.000000] Booting Linux on physical CPU 0x0
[    0.000000] Linux version 4.9.69-g89d085d1a4 (parkjy@auto-dev02) (gcc version 6.4.0 (GCC) ) #1 PREEMPT Fri Jun 12 20:57:27 KST 2020
[    0.000000] CPU: ARMv7 Processor [413fc082] revision 2 (ARMv7), cr=10c5387d
[    0.000000] CPU: PIPT / VIPT nonaliasing data cache, VIPT aliasing instruction cache
[    0.000000] OF: fdt:Machine model: DASAN TMS 3.0
[    0.000000] efi: Getting EFI parameters from FDT:
[    0.000000] efi: UEFI not found.
[    0.000000] cma: Reserved 48 MiB at 0x88800000
[    0.000000] Memory policy: Data cache writeback
[    0.000000] On node 0 totalpages: 65536
[    0.000000] free_area_init_node: node 0, pgdat c0d46b44, node_mem_map cfda1000
[    0.000000]   Normal zone: 576 pages used for memmap
[    0.000000]   Normal zone: 0 pages reserved
[    0.000000]   Normal zone: 65536 pages, LIFO batch:15
[    0.000000] CPU: All CPU(s) started in SVC mode.
[    0.000000] AM335X ES2.1 (neon)
[    0.000000] pcpu-alloc: s0 r0 d32768 u32768 alloc=1*32768
[    0.000000] pcpu-alloc: [0] 0
[    0.000000] Built 1 zonelists in Zone order, mobility grouping on.  Total pages: 64960
[    0.000000] Kernel command line: console=ttyS5,230400n8 quiet root=ubi0:rootfs rw ubi.mtd=NAND.1.file-system,4096 rootfstype=ubifs rootwait=1
[    0.000000] PID hash table entries: 1024 (order: 0, 4096 bytes)
[    0.000000] Dentry cache hash table entries: 32768 (order: 5, 131072 bytes)
[    0.000000] Inode-cache hash table entries: 16384 (order: 4, 65536 bytes)
[    0.000000] Memory: 196992K/262144K available (8192K kernel code, 292K rwdata, 2656K rodata, 1024K init, 302K bss, 16000K reserved, 49152K cma-reserved, 0K highmem)
[    0.000000] Virtual kernel memory layout:
[    0.000000]     vector  : 0xffff0000 - 0xffff1000   (   4 kB)
[    0.000000]     fixmap  : 0xffc00000 - 0xfff00000   (3072 kB)
[    0.000000]     vmalloc : 0xd0800000 - 0xff800000   ( 752 MB)
[    0.000000]     lowmem  : 0xc0000000 - 0xd0000000   ( 256 MB)
[    0.000000]     pkmap   : 0xbfe00000 - 0xc0000000   (   2 MB)
[    0.000000]     modules : 0xbf000000 - 0xbfe00000   (  14 MB)
[    0.000000]       .text : 0xc0008000 - 0xc0900000   (9184 kB)
[    0.000000]       .init : 0xc0c00000 - 0xc0d00000   (1024 kB)
[    0.000000]       .data : 0xc0d00000 - 0xc0d493d8   ( 293 kB)
[    0.000000]        .bss : 0xc0d493d8 - 0xc0d94c98   ( 303 kB)
[    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1
[    0.000000] Preemptible hierarchical RCU implementation.
[    0.000000]  Build-time adjustment of leaf fanout to 32.
[    0.000000] NR_IRQS:16 nr_irqs:16 16
[    0.000000] IRQ: Found an INTC at 0xfa200000 (revision 5.0) with 128 interrupts
[    0.000000] OMAP clockevent source: timer2 at 25000000 Hz
[    0.000014] sched_clock: 32 bits at 25MHz, resolution 40ns, wraps every 85899345900ns
[    0.000028] clocksource: timer1: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 76450417870 ns
[    0.000035] OMAP clocksource: timer1 at 25000000 Hz
[    0.000224] clocksource_probe: no matching clocksources found
[    0.000389] Console: colour dummy device 80x30
[    0.000427] Calibrating delay loop... 996.14 BogoMIPS (lpj=4980736)
[    0.089232] pid_max: default: 32768 minimum: 301
[    0.089354] Mount-cache hash table entries: 1024 (order: 0, 4096 bytes)
[    0.089361] Mountpoint-cache hash table entries: 1024 (order: 0, 4096 bytes)
[    0.090153] CPU: Testing write buffer coherency: ok
[    0.090523] Setting up static identity map for 0x80100000 - 0x80100060
[    0.091302] EFI services will not be available.
[    0.092460] devtmpfs: initialized
[    0.104674] VFP support v0.3: implementor 41 architecture 3 part 30 variant c rev 3
[    0.105005] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns
[    0.105023] futex hash table entries: 256 (order: -1, 3072 bytes)
[    0.108605] pinctrl core: initialized pinctrl subsystem
[    0.110015] NET: Registered protocol family 16
[    0.111880] DMA: preallocated 256 KiB pool for atomic coherent allocations
[    0.125209] omap_hwmod: debugss: _wait_target_disable failed
[    0.199222] cpuidle: using governor ladder
[    0.229210] cpuidle: using governor menu
[    0.233262] gpio gpiochip0: (gpio): added GPIO chardev (254:0)
[    0.233631] gpiochip_setup_dev: registered GPIOs 0 to 31 on device: gpiochip0 (gpio)
[    0.234840] OMAP GPIO hardware version 0.1
[    0.235647] gpio gpiochip1: (gpio): added GPIO chardev (254:1)
[    0.235948] gpiochip_setup_dev: registered GPIOs 32 to 63 on device: gpiochip1 (gpio)
[    0.237691] gpio gpiochip2: (gpio): added GPIO chardev (254:2)
[    0.238080] gpiochip_setup_dev: registered GPIOs 64 to 95 on device: gpiochip2 (gpio)
[    0.240076] gpio gpiochip3: (gpio): added GPIO chardev (254:3)
[    0.240376] gpiochip_setup_dev: registered GPIOs 96 to 127 on device: gpiochip3 (gpio)
[    0.248115] omap-gpmc 50000000.gpmc: could not find pctldev for node /ocp/l4_wkup@44c00000/scm@210000/pinmux@800/nandflash_pins_s0, deferring probe
[    0.250423] No ATAGs?
[    0.250440] hw-breakpoint: debug architecture 0x4 unsupported.
[    0.251378] pstore: using zlib compression
[    0.251398] pstore: Registered ramoops as persistent store backend
[    0.251405] ramoops: attached 0x40000@0x8bfc0000, ecc: 0/0
[    0.286772] edma 49000000.edma: TI EDMA DMA engine driver
[    0.287131] of_get_named_gpiod_flags: can't parse 'gpio' property of node '/fixedregulator@0[0]'
[    0.287483] of_get_named_gpiod_flags: parsed 'gpio' property of node '/fixedregulator@2[0]' - status (0)
[    0.290490] usbcore: registered new interface driver usbfs
[    0.290551] usbcore: registered new interface driver hub
[    0.290660] usbcore: registered new device driver usb
[    0.290922] omap_i2c 44e0b000.i2c: could not find pctldev for node /ocp/l4_wkup@44c00000/scm@210000/pinmux@800/pinmux_i2c0_pins, deferring probe
[    0.290971] omap_i2c 4802a000.i2c: could not find pctldev for node /ocp/l4_wkup@44c00000/scm@210000/pinmux@800/pinmux_i2c1_pins, deferring probe
[    0.291096] media: Linux media interface: v0.10
[    0.291146] Linux video capture interface: v2.00
[    0.291183] pps_core: LinuxPPS API ver. 1 registered
[    0.291187] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>
[    0.291205] PTP clock support registered
[    0.291238] EDAC MC: Ver: 3.0.0
[    0.292258] omap-mailbox 480c8000.mailbox: omap mailbox rev 0x400
[    0.292601] Advanced Linux Sound Architecture Driver Initialized.
[    0.293181] Bluetooth: Core ver 2.22
[    0.293225] NET: Registered protocol family 31
[    0.293229] Bluetooth: HCI device and connection manager initialized
[    0.293240] Bluetooth: HCI socket layer initialized
[    0.293248] Bluetooth: L2CAP socket layer initialized
[    0.293273] Bluetooth: SCO socket layer initialized
[    0.294444] clocksource: Switched to clocksource timer1
[    0.303896] NET: Registered protocol family 2
[    0.304712] TCP established hash table entries: 2048 (order: 1, 8192 bytes)
[    0.304738] TCP bind hash table entries: 2048 (order: 1, 8192 bytes)
[    0.304759] TCP: Hash tables configured (established 2048 bind 2048)
[    0.304835] UDP hash table entries: 256 (order: 0, 4096 bytes)
[    0.304849] UDP-Lite hash table entries: 256 (order: 0, 4096 bytes)
[    0.304972] NET: Registered protocol family 1
[    0.305399] RPC: Registered named UNIX socket transport module.
[    0.305405] RPC: Registered udp transport module.
[    0.305407] RPC: Registered tcp transport module.
[    0.305410] RPC: Registered tcp NFSv4.1 backchannel transport module.
[    0.305423] PCI: CLS 0 bytes, default 64
[    0.306400] hw perfevents: enabled with armv7_cortex_a8 PMU driver, 5 counters available
[    0.308645] workingset: timestamp_bits=14 max_order=16 bucket_order=2
[    0.315686] squashfs: version 4.0 (2009/01/31) Phillip Lougher
[    0.316508] NFS: Registering the id_resolver key type
[    0.316546] Key type id_resolver registered
[    0.316550] Key type id_legacy registered
[    0.316590] ntfs: driver 2.1.32 [Flags: R/O].
[    0.321107] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 246)
[    0.321122] io scheduler noop registered
[    0.321126] io scheduler deadline registered
[    0.321252] io scheduler cfq registered (default)
[    0.322678] pinctrl-single 44e10800.pinmux: 142 pins at pa f9e10800 size 568
[    0.378703] Serial: 8250/16550 driver, 10 ports, IRQ sharing disabled
[    0.382358] 44e09000.serial: ttyS0 at MMIO 0x44e09000 (irq = 158, base_baud = 3000000) is a 8250
[    0.383362] 48024000.serial: ttyS2 at MMIO 0x48024000 (irq = 159, base_baud = 3000000) is a 8250
[    0.384252] 481a6000.serial: ttyS3 at MMIO 0x481a6000 (irq = 160, base_baud = 3000000) is a 8250
[    0.385257] 481aa000.serial: ttyS5 at MMIO 0x481aa000 (irq = 161, base_baud = 3000000) is a 8250
[    0.385400] console [ttyS5] enabled
[    0.387104] omap_rng 48310000.rng: OMAP Random Number Generator ver. 20
[    0.387228] [drm] Initialized
[    0.400544] brd: module loaded
[    0.406786] loop: module loaded
[    0.407353] (stk) :sysfs entries created
[    0.407389] (stk) : debugfs entries created
[    0.410508] libphy: Fixed MDIO Bus: probed
[    0.411565] CAN device driver interface
[    0.412668] c_can_platform 481cc000.can: c_can_platform device registered (regs=fa1cc000, irq=166)
[    0.413456] c_can_platform 481d0000.can: c_can_platform device registered (regs=fa1d0000, irq=167)
[    0.484479] davinci_mdio 4a101000.mdio: davinci mdio revision 1.6
[    0.484490] davinci_mdio 4a101000.mdio: no live phy, scanning all
[    0.484838] davinci_mdio: probe of 4a101000.mdio failed with error -5
[    0.485493] cpsw 4a100000.ethernet: Detected MACID = 98:5d:ad:e2:94:eb
[    0.485617] cpsw 4a100000.ethernet: cpts: overflow check period 500 (jiffies)
[    0.487051] usbcore: registered new interface driver qmi_wwan
[    0.487100] usbcore: registered new interface driver cdc_wdm
[    0.487203] usbcore: registered new interface driver usbserial
[    0.487239] usbcore: registered new interface driver option
[    0.487287] usbserial: USB Serial support registered for GSM modem (1-port)
[    0.487749] mousedev: PS/2 mouse device common for all mice
[    0.488344] ais328dq_spi spi1.0: platform data is NULL. using default one.
[    0.488355] ais328dq_acc: hw init start
[    0.488478] ais328dq_acc: int1: 00, int2: 00
[    0.488578] ais328dq_acc: hw init done
[    0.489018] input: ais328dq_acc as /devices/platform/ocp/48030000.spi/spi_master/spi1/spi1.0/input/input0
[    0.490006] omap_rtc 44e3e000.rtc: already running
[    0.490379] rtc rtc0: 44e3e000.rtc: dev (253:0)
[    0.490404] omap_rtc 44e3e000.rtc: rtc core: registered 44e3e000.rtc as rtc0
[    0.490644] i2c /dev entries driver
[    0.493121] cpuidle: enable-method property 'ti,am3352' found operations
[    0.493951] omap_hsmmc 48060000.mmc: GPIO lookup for consumer cd
[    0.493960] omap_hsmmc 48060000.mmc: using device tree for GPIO lookup
[    0.493970] of_get_named_gpiod_flags: can't parse 'cd-gpios' property of node '/ocp/mmc@48060000[0]'
[    0.493978] of_get_named_gpiod_flags: can't parse 'cd-gpio' property of node '/ocp/mmc@48060000[0]'
[    0.493983] omap_hsmmc 48060000.mmc: using lookup tables for GPIO lookup
[    0.493990] omap_hsmmc 48060000.mmc: lookup for GPIO cd failed
[    0.493999] omap_hsmmc 48060000.mmc: GPIO lookup for consumer wp
[    0.494003] omap_hsmmc 48060000.mmc: using device tree for GPIO lookup
[    0.494009] of_get_named_gpiod_flags: can't parse 'wp-gpios' property of node '/ocp/mmc@48060000[0]'
[    0.494014] of_get_named_gpiod_flags: can't parse 'wp-gpio' property of node '/ocp/mmc@48060000[0]'
[    0.494018] omap_hsmmc 48060000.mmc: using lookup tables for GPIO lookup
[    0.494023] omap_hsmmc 48060000.mmc: lookup for GPIO wp failed
[    0.494817] (stc):  chnl_id list empty :4
[    0.494830] (stk) : st_kim_start
[    0.599632] (stk) :ldisc_install = 1
[    0.745121] of_get_named_gpiod_flags: parsed 'gpios' property of node '/leds/led@0[0]' - status (0)
[    0.748468] of_get_named_gpiod_flags: parsed 'gpios' property of node '/leds/led@1[0]' - status (0)
[    0.748588] of_get_named_gpiod_flags: parsed 'gpios' property of node '/leds/led@2[0]' - status (0)
[    0.748692] of_get_named_gpiod_flags: parsed 'gpios' property of node '/leds/led@3[0]' - status (0)
[    0.748839] of_get_named_gpiod_flags: parsed 'gpios' property of node '/leds/led@4[0]' - status (0)
[    0.748940] of_get_named_gpiod_flags: parsed 'gpios' property of node '/leds/led@5[0]' - status (0)
[    0.749042] of_get_named_gpiod_flags: parsed 'gpios' property of node '/leds/led@6[0]' - status (0)
[    0.749181] of_get_named_gpiod_flags: parsed 'gpios' property of node '/leds/led@7[0]' - status (0)
[    0.749279] of_get_named_gpiod_flags: parsed 'gpios' property of node '/leds/led@8[0]' - status (0)
[    0.749384] of_get_named_gpiod_flags: parsed 'gpios' property of node '/leds/led@9[0]' - status (0)
[    0.749481] of_get_named_gpiod_flags: parsed 'gpios' property of node '/leds/led@10[0]' - status (0)
[    0.749581] of_get_named_gpiod_flags: parsed 'gpios' property of node '/leds/led@11[0]' - status (0)
[    0.749682] of_get_named_gpiod_flags: parsed 'gpios' property of node '/leds/led@12[0]' - status (0)
[    0.749784] of_get_named_gpiod_flags: parsed 'gpios' property of node '/leds/led@13[0]' - status (0)
[    0.749881] of_get_named_gpiod_flags: parsed 'gpios' property of node '/leds/led@14[0]' - status (0)
[    0.749981] of_get_named_gpiod_flags: parsed 'gpios' property of node '/leds/led@15[0]' - status (0)
[    0.750086] of_get_named_gpiod_flags: parsed 'gpios' property of node '/leds/led@16[0]' - status (0)
[    0.750184] of_get_named_gpiod_flags: parsed 'gpios' property of node '/leds/led@17[0]' - status (0)
[    0.750321] of_get_named_gpiod_flags: parsed 'gpios' property of node '/leds/led@18[0]' - status (0)
[    0.750427] of_get_named_gpiod_flags: parsed 'gpios' property of node '/leds/led@19[0]' - status (0)
[    0.750536] of_get_named_gpiod_flags: parsed 'gpios' property of node '/leds/led@20[0]' - status (0)
[    0.750637] of_get_named_gpiod_flags: parsed 'gpios' property of node '/leds/led@21[0]' - status (0)
[    0.750737] of_get_named_gpiod_flags: parsed 'gpios' property of node '/leds/led@22[0]' - status (0)
[    0.750838] of_get_named_gpiod_flags: parsed 'gpios' property of node '/leds/led@23[0]' - status (0)
[    0.750941] of_get_named_gpiod_flags: parsed 'gpios' property of node '/leds/led@24[0]' - status (0)
[    0.751044] of_get_named_gpiod_flags: parsed 'gpios' property of node '/leds/led@25[0]' - status (0)
[    0.751148] of_get_named_gpiod_flags: parsed 'gpios' property of node '/leds/led@26[0]' - status (0)
[    0.751253] of_get_named_gpiod_flags: parsed 'gpios' property of node '/leds/led@27[0]' - status (0)
[    0.751358] of_get_named_gpiod_flags: parsed 'gpios' property of node '/leds/led@28[0]' - status (0)
[    0.751478] of_get_named_gpiod_flags: parsed 'gpios' property of node '/leds/led@29[0]' - status (0)
[    0.751582] of_get_named_gpiod_flags: parsed 'gpios' property of node '/leds/led@30[0]' - status (0)
[    0.751693] of_get_named_gpiod_flags: parsed 'gpios' property of node '/leds/led@31[0]' - status (0)
[    0.751800] of_get_named_gpiod_flags: parsed 'gpios' property of node '/leds/led@32[0]' - status (0)
[    0.752289] ledtrig-cpu: registered to indicate activity on CPUs
[    0.754968] NET: Registered protocol family 10
[    0.757607] sit: IPv6, IPv4 and MPLS over IPv4 tunneling driver
[    0.758387] NET: Registered protocol family 17
[    0.758410] can: controller area network core (rev 20120528 abi 9)
[    0.758497] NET: Registered protocol family 29
[    0.758541] can: raw protocol (rev 20120528)
[    0.758546] can: broadcast manager protocol (rev 20161123 t)
[    0.758555] can: netlink gateway (rev 20130117) max_hops=1
[    0.758711] can: SAE J1939
[    0.759024] Key type dns_resolver registered
[    0.759249] omap_voltage_late_init: Voltage driver support not added
[    0.764895] omap-gpmc 50000000.gpmc: GPMC revision 6.0
[    0.764915] gpmc_mem_init: disabling cs 0 mapped at 0x0-0x1000000
[    0.764942] gpiochip_find_base: found new base at 510
[    0.764989] gpio gpiochip4: (omap-gpmc): added GPIO chardev (254:4)
[    0.765412] gpiochip_setup_dev: registered GPIOs 510 to 511 on device: gpiochip4 (omap-gpmc)
[    0.766489] omap2-nand 8000000.nand: GPIO lookup for consumer rb
[    0.766501] omap2-nand 8000000.nand: using device tree for GPIO lookup
[    0.766535] of_get_named_gpiod_flags: parsed 'rb-gpios' property of node '/ocp/gpmc@50000000/nand@0,0[0]' - status (0)
[    0.766720] nand: device found, Manufacturer ID: 0x2c, Chip ID: 0xa3
[    0.766724] nand: Micron MT29F8G08ABBCAH4
[    0.766731] nand: 1024 MiB, SLC, erase size: 256 KiB, page size: 4096, OOB size: 224
[    0.766773] nand: using OMAP_ECC_BCH8_CODE_HW ECC scheme
[    0.766888] 14 ofpart partitions found on MTD device omap2-nand.0
[    0.766893] Creating 14 MTD partitions on "omap2-nand.0":
[    0.766907] 0x000000000000-0x000000040000 : "NAND.SPL"
[    0.768320] 0x000000040000-0x000000080000 : "NAND.SPL.backup1"
[    0.769417] 0x000000080000-0x000000140000 : "NAND.u-boot"
[    0.770477] 0x000000140000-0x000000180000 : "NAND.u-boot-env"
[    0.771507] 0x000000180000-0x0000001c0000 : "NAND.u-boot-env.backup1"
[    0.772546] 0x0000001c0000-0x000000200000 : "NAND.inventory"
[    0.773565] 0x000000200000-0x000000240000 : "NAND.1.fdt"
[    0.774770] 0x000000240000-0x000000a00000 : "NAND.1.kernel"
[    0.776506] 0x000000a00000-0x000008a00000 : "NAND.1.file-system"
[    0.788758] 0x000008a00000-0x000008a40000 : "NAND.2.fdt"
[    0.789761] 0x000008a40000-0x000009200000 : "NAND.2.kernel"
[    0.791467] 0x000009200000-0x000011200000 : "NAND.2.file-system"
[    0.803748] 0x000011200000-0x00003c000000 : "NAND.file-data"
[    0.864804] 0x00003c000000-0x000040000000 : "NAND.nv-log"
[    0.894704] tps65910 0-002d: No interrupt support, no core IRQ
[    0.894914] tps65910-gpio tps65910-gpio: ti,en-gpio-sleep not specified
[    0.894943] gpiochip_find_base: found new base at 504
[    0.894972] gpio gpiochip5: (tps65910): added GPIO chardev (254:5)
[    0.895273] gpiochip_setup_dev: registered GPIOs 504 to 509 on device: gpiochip5 (tps65910)
[    0.896653] vrtc: supplied by vbat
[    0.900386] vio: supplied by vbat
[    0.901854] vdd1: supplied by vbat
[    0.903630] vdd2: supplied by vbat
[    0.904115] vdd_core: Bringing 1325000uV into 1112500-1112500uV
[    0.906928] vdig1: supplied by vbat
[    0.908276] vdig2: supplied by vbat
[    0.909605] vpll: supplied by vbat
[    0.910463] random: fast init done
[    0.910954] vdac: supplied by vbat
[    0.912282] vaux1: supplied by vbat
[    0.913627] vaux2: supplied by vbat
[    0.914980] vaux33: supplied by vbat
[    0.916329] vmmc: supplied by vbat
[    0.917672] vbb: supplied by vbat
[    0.919580] lm75 0-0048: hwmon0: sensor 'tmp75'
[    0.919635] omap_i2c 44e0b000.i2c: bus 0 rev0.11 at 400 kHz
[    0.920789] omap_i2c 4802a000.i2c: bus 1 rev0.11 at 400 kHz
[    0.922362] ubi0: attaching mtd8
[    1.207872] ubi0: scanning is finished
[    1.212889] ubi0: attached mtd8 (name "NAND.1.file-system", size 128 MiB)
[    1.212900] ubi0: PEB size: 262144 bytes (256 KiB), LEB size: 253952 bytes
[    1.212905] ubi0: min./max. I/O unit sizes: 4096/4096, sub-page size 1024
[    1.212910] ubi0: VID header offset: 4096 (aligned 4096), data offset: 8192
[    1.212914] ubi0: good PEBs: 512, bad PEBs: 0, corrupted PEBs: 0
[    1.212919] ubi0: user volume: 1, internal volumes: 1, max. volumes count: 128
[    1.212925] ubi0: max/mean erase counter: 65/16, WL threshold: 4096, image sequence number: 1511323209
[    1.212930] ubi0: available PEBs: 0, total reserved PEBs: 512, PEBs reserved for bad PEB handling: 80
[    1.213356] of_get_named_gpiod_flags: parsed 'gpios' property of node '/input_keys/switch0[0]' - status (0)
[    1.213385] of_get_named_gpiod_flags: parsed 'gpios' property of node '/input_keys/switch1[0]' - status (0)
[    1.213400] of_get_named_gpiod_flags: parsed 'gpios' property of node '/input_keys/switch2[0]' - status (0)
[    1.213414] of_get_named_gpiod_flags: parsed 'gpios' property of node '/input_keys/switch3[0]' - status (0)
[    1.213428] of_get_named_gpiod_flags: parsed 'gpios' property of node '/input_keys/switch4[0]' - status (0)
[    1.213443] of_get_named_gpiod_flags: parsed 'gpios' property of node '/input_keys/switch5[0]' - status (0)
[    1.213462] of_get_named_gpiod_flags: parsed 'gpios' property of node '/input_keys/switch6[0]' - status (0)
[    1.213739] omap_gpio 44e07000.gpio: Could not set line 19 debounce to 300000 microseconds (-22)
[    1.213770] omap_gpio 44e07000.gpio: Could not set line 27 debounce to 300000 microseconds (-22)
[    1.214209] input: input_keys as /devices/platform/input_keys/input/input1
[    1.214912] omap_rtc 44e3e000.rtc: setting system clock to 2000-01-01 13:20:22 UTC (946732822)
[    1.215448] wifi-regulator: disabling
[    1.215572] ubi0: background thread "ubi_bgt0d" started, PID 99
[    1.215711] ALSA device list:
[    1.215716]   No soundcards found.
[    1.224553] UBIFS (ubi0:0): background thread "ubifs_bgt0_0" started, PID 100
[    1.251307] UBIFS (ubi0:0): recovery needed
[    1.334158] UBIFS (ubi0:0): recovery completed
[    1.334283] UBIFS (ubi0:0): UBIFS: mounted UBI device 0, volume 0, name "rootfs"
[    1.334293] UBIFS (ubi0:0): LEB size: 253952 bytes (248 KiB), min./max. I/O unit sizes: 4096 bytes/4096 bytes
[    1.334302] UBIFS (ubi0:0): FS size: 106151936 bytes (101 MiB, 418 LEBs), journal size 9404416 bytes (8 MiB, 38 LEBs)
[    1.334307] UBIFS (ubi0:0): reserved for root: 0 bytes (0 KiB)
[    1.334317] UBIFS (ubi0:0): media format: w4/r0 (latest is w4/r0), UUID A7202D23-ED8F-417C-AE52-588F81F4D8CE, small LPT model
[    1.335915] VFS: Mounted root (ubifs filesystem) on device 0:14.
[    1.337388] devtmpfs: mounted
[    1.338849] Freeing unused kernel memory: 1024K
[    1.521196] systemd[1]: System time before build time, advancing clock.
[    1.604642] (stk) :ldisc installation timeout
[    1.604656] (stk) :ldisc_install = 0
[    2.644685] (stk) : timed out waiting for ldisc to be un-installed
[    2.751751] (stk) :ldisc_install = 1
[    3.186317] systemd-journald[112]: Received request to flush runtime journal from PID 1
[    3.764588] (stk) :ldisc installation timeout
[    3.764606] (stk) :ldisc_install = 0
[    4.686721] omap_wdt: OMAP Watchdog Timer Rev 0x01: initial timeout 60 sec
[    4.804631] (stk) : timed out waiting for ldisc to be un-installed
[    4.912067] (stk) :ldisc_install = 1
[    5.147413] ubi1: attaching mtd12
[    5.781334] omap-aes 53500000.aes: OMAP AES hw accel rev: 3.2
[    5.800169] wkup_m3_ipc 44e11324.wkup_m3_ipc: could not get rproc handle
[    5.803219] PM: Cannot get wkup_m3_ipc handle
[    5.844723] omap-aes 53500000.aes: will run requests pump with realtime priority
[    5.867504] wkup_m3_ipc 44e11324.wkup_m3_ipc: could not get rproc handle
[    5.867775] PM: Cannot get wkup_m3_ipc handle
[    5.869625] remoteproc remoteproc0: wkup_m3 is available
[    5.875378] PM: Cannot get wkup_m3_ipc handle
[    5.875468] remoteproc remoteproc0: powering up wkup_m3
[    5.880833] omap-sham 53100000.sham: hw accel on OMAP rev 4.3
[    5.924594] (stk) :ldisc installation timeout
[    5.924611] (stk) :ldisc_install = 0
[    5.934006] remoteproc remoteproc0: Booting fw image am335x-pm-firmware.elf, size 224252
[    5.934288] remoteproc remoteproc0: remote processor wkup_m3 is now up
[    5.934321] wkup_m3_ipc 44e11324.wkup_m3_ipc: CM3 Firmware Version = 0x192
[    5.949181] PM: Cannot get wkup_m3_ipc handle
[    6.964585] (stk) : timed out waiting for ldisc to be un-installed
[    7.071628] (stk) :ldisc_install = 1
[    8.085545] (stk) :ldisc installation timeout
[    8.085563] (stk) :ldisc_install = 0
[    8.782835] PM: bootloader does not support rtc-only!
[    8.842272] am335x-phy-driver 47401300.usb-phy: GPIO lookup for consumer reset
[    8.842291] am335x-phy-driver 47401300.usb-phy: using device tree for GPIO lookup
[    8.842304] of_get_named_gpiod_flags: can't parse 'reset-gpios' property of node '/ocp/usb@47400000/usb-phy@47401300[0]'
[    8.842311] of_get_named_gpiod_flags: can't parse 'reset-gpio' property of node '/ocp/usb@47400000/usb-phy@47401300[0]'
[    8.842317] am335x-phy-driver 47401300.usb-phy: using lookup tables for GPIO lookup
[    8.842324] am335x-phy-driver 47401300.usb-phy: lookup for GPIO reset failed
[    8.842332] am335x-phy-driver 47401300.usb-phy: GPIO lookup for consumer vbus-detect
[    8.842336] am335x-phy-driver 47401300.usb-phy: using device tree for GPIO lookup
[    8.842343] of_get_named_gpiod_flags: can't parse 'vbus-detect-gpios' property of node '/ocp/usb@47400000/usb-phy@47401300[0]'
[    8.842348] of_get_named_gpiod_flags: can't parse 'vbus-detect-gpio' property of node '/ocp/usb@47400000/usb-phy@47401300[0]'
[    8.842352] am335x-phy-driver 47401300.usb-phy: using lookup tables for GPIO lookup
[    8.842357] am335x-phy-driver 47401300.usb-phy: lookup for GPIO vbus-detect failed
[    8.842455] 47401300.usb-phy supply vcc not found, using dummy regulator
[    8.885418] am335x-phy-driver 47401b00.usb-phy: GPIO lookup for consumer reset
[    8.885438] am335x-phy-driver 47401b00.usb-phy: using device tree for GPIO lookup
[    8.885451] of_get_named_gpiod_flags: can't parse 'reset-gpios' property of node '/ocp/usb@47400000/usb-phy@47401b00[0]'
[    8.885458] of_get_named_gpiod_flags: can't parse 'reset-gpio' property of node '/ocp/usb@47400000/usb-phy@47401b00[0]'
[    8.885464] am335x-phy-driver 47401b00.usb-phy: using lookup tables for GPIO lookup
[    8.885471] am335x-phy-driver 47401b00.usb-phy: lookup for GPIO reset failed
[    8.885479] am335x-phy-driver 47401b00.usb-phy: GPIO lookup for consumer vbus-detect
[    8.885484] am335x-phy-driver 47401b00.usb-phy: using device tree for GPIO lookup
[    8.885490] of_get_named_gpiod_flags: can't parse 'vbus-detect-gpios' property of node '/ocp/usb@47400000/usb-phy@47401b00[0]'
[    8.885495] of_get_named_gpiod_flags: can't parse 'vbus-detect-gpio' property of node '/ocp/usb@47400000/usb-phy@47401b00[0]'
[    8.885500] am335x-phy-driver 47401b00.usb-phy: using lookup tables for GPIO lookup
[    8.885505] am335x-phy-driver 47401b00.usb-phy: lookup for GPIO vbus-detect failed
[    8.885602] 47401b00.usb-phy supply vcc not found, using dummy regulator
[    8.984294] musb-hdrc musb-hdrc.0: MUSB HDRC host driver
[    8.984343] musb-hdrc musb-hdrc.0: new USB bus registered, assigned bus number 1
[    9.035170] hub 1-0:1.0: USB hub found
[    9.035227] hub 1-0:1.0: 1 port detected
[    9.063775] musb-hdrc musb-hdrc.1: MUSB HDRC host driver
[    9.063822] musb-hdrc musb-hdrc.1: new USB bus registered, assigned bus number 2
[    9.115016] hub 2-0:1.0: USB hub found
[    9.118411] hub 2-0:1.0: 1 port detected
[    9.124863] (stk) : timed out waiting for ldisc to be un-installed
[    9.232090] (stk) :ldisc_install = 1
[    9.959454] ubi1: scanning is finished
[    9.991279] ubi1: attached mtd12 (name "NAND.file-data", size 686 MiB)
[    9.991298] ubi1: PEB size: 262144 bytes (256 KiB), LEB size: 258048 bytes
[    9.991303] ubi1: min./max. I/O unit sizes: 4096/4096, sub-page size 1024
[    9.991308] ubi1: VID header offset: 1024 (aligned 1024), data offset: 4096
[    9.991312] ubi1: good PEBs: 2744, bad PEBs: 0, corrupted PEBs: 0
[    9.991317] ubi1: user volume: 1, internal volumes: 1, max. volumes count: 128
[    9.991324] ubi1: max/mean erase counter: 16/6, WL threshold: 4096, image sequence number: 1898721964
[    9.991330] ubi1: available PEBs: 0, total reserved PEBs: 2744, PEBs reserved for bad PEB handling: 80
[    9.991377] ubi2: attaching mtd13
[   10.001131] ubi1: background thread "ubi_bgt1d" started, PID 222
[   10.254578] (stk) :ldisc installation timeout
[   10.254595] (stk) :ldisc_install = 0
[   10.336369] ubi2: scanning is finished
[   10.354577] UBIFS (ubi1:0): background thread "ubifs_bgt1_0" started, PID 235
[   10.366812] ubi2: attached mtd13 (name "NAND.nv-log", size 64 MiB)
[   10.366830] ubi2: PEB size: 262144 bytes (256 KiB), LEB size: 258048 bytes
[   10.366836] ubi2: min./max. I/O unit sizes: 4096/4096, sub-page size 1024
[   10.366840] ubi2: VID header offset: 1024 (aligned 1024), data offset: 4096
[   10.366845] ubi2: good PEBs: 256, bad PEBs: 0, corrupted PEBs: 0
[   10.366850] ubi2: user volume: 1, internal volumes: 1, max. volumes count: 128
[   10.366857] ubi2: max/mean erase counter: 201/84, WL threshold: 4096, image sequence number: 1033295459
[   10.366862] ubi2: available PEBs: 0, total reserved PEBs: 256, PEBs reserved for bad PEB handling: 80
[   10.369888] ubi2: background thread "ubi_bgt2d" started, PID 237
[   10.520866] UBIFS (ubi1:0): recovery needed
[   10.527375] UBIFS (ubi2:0): background thread "ubifs_bgt2_0" started, PID 242
[   10.623977] UBIFS (ubi2:0): recovery needed
[   10.721289] UBIFS (ubi1:0): recovery completed
[   10.721403] UBIFS (ubi1:0): UBIFS: mounted UBI device 1, volume 0, name "data"
[   10.721413] UBIFS (ubi1:0): LEB size: 258048 bytes (252 KiB), min./max. I/O unit sizes: 4096 bytes/4096 bytes
[   10.721423] UBIFS (ubi1:0): FS size: 683827200 bytes (652 MiB, 2650 LEBs), journal size 9420800 bytes (8 MiB, 37 LEBs)
[   10.721427] UBIFS (ubi1:0): reserved for root: 0 bytes (0 KiB)
[   10.721437] UBIFS (ubi1:0): media format: w4/r0 (latest is w4/r0), UUID 176624D0-5952-4B29-BE84-12F224232B33, small LPT model
[   10.816740] UBIFS (ubi2:0): recovery completed
[   10.816879] UBIFS (ubi2:0): UBIFS: mounted UBI device 2, volume 0, name "nvlog"
[   10.816889] UBIFS (ubi2:0): LEB size: 258048 bytes (252 KiB), min./max. I/O unit sizes: 4096 bytes/4096 bytes
[   10.816897] UBIFS (ubi2:0): FS size: 41803776 bytes (39 MiB, 162 LEBs), journal size 9031680 bytes (8 MiB, 35 LEBs)
[   10.816902] UBIFS (ubi2:0): reserved for root: 0 bytes (0 KiB)
[   10.816911] UBIFS (ubi2:0): media format: w4/r0 (latest is w4/r0), UUID 4F9C58EE-34EC-4655-A070-A91D30B8EECA, small LPT model
[   11.284698] (stk) : timed out waiting for ldisc to be un-installed
[   11.394524] (stk) :ldisc_install = 1
[   12.406495] (stk) :ldisc installation timeout
[   12.406520] (stk) :ldisc_install = 0
[   13.444792] (stk) : timed out waiting for ldisc to be un-installed
[   13.446850] Bluetooth: st_register failed -22
[  172.124768] random: crng init done
[29156.373794] musb-hdrc musb-hdrc.1: remove, state 4
[29156.373876] usb usb2: USB disconnect, device number 1
[29156.385792] musb-hdrc musb-hdrc.1: USB bus 2 deregistered
[29156.389693] musb-hdrc musb-hdrc.0: remove, state 4
[29156.389763] usb usb1: USB disconnect, device number 1
[29156.404835] musb-hdrc musb-hdrc.0: USB bus 1 deregistered
[29193.775348] musb-hdrc musb-hdrc.0: MUSB HDRC host driver
[29193.775452] musb-hdrc musb-hdrc.0: new USB bus registered, assigned bus number 1
[29193.783825] hub 1-0:1.0: USB hub found
[29193.793213] hub 1-0:1.0: 1 port detected
[29193.889831] musb-hdrc musb-hdrc.1: MUSB HDRC host driver
[29193.889932] musb-hdrc musb-hdrc.1: new USB bus registered, assigned bus number 2
[29193.924916] hub 2-0:1.0: USB hub found
[29193.925096] hub 2-0:1.0: 1 port detected
[29194.354835] usb 2-1: new full-speed USB device number 2 using musb-hdrc
[29194.494827] usb 2-1: device descriptor read/64, error -71
[29194.755081] usb 2-1: device descriptor read/64, error -71
[29195.014837] usb 2-1: new full-speed USB device number 3 using musb-hdrc
[29195.154830] usb 2-1: device descriptor read/64, error -71
[29195.414829] usb 2-1: device descriptor read/64, error -71
[29195.534984] usb usb2-port1: attempt power cycle
[29196.014830] usb 2-1: new full-speed USB device number 4 using musb-hdrc
[29196.594839] usb 2-1: new full-speed USB device number 5 using musb-hdrc
[29229.664839] usb 2-1: new high-speed USB device number 6 using musb-hdrc
[29229.852704] option 2-1:1.0: GSM modem (1-port) converter detected
[29229.866673] usb 2-1: GSM modem (1-port) converter now attached to ttyUSB0
[29229.868396] option 2-1:1.1: GSM modem (1-port) converter detected
[29229.875369] usb 2-1: GSM modem (1-port) converter now attached to ttyUSB1
[29229.877134] option 2-1:1.2: GSM modem (1-port) converter detected
[29229.889778] usb 2-1: GSM modem (1-port) converter now attached to ttyUSB2
[29229.891537] option 2-1:1.3: GSM modem (1-port) converter detected
[29229.895491] usb 2-1: GSM modem (1-port) converter now attached to ttyUSB3
[29229.914875] qmi_wwan 2-1:1.4: cdc-wdm0: USB WDM device
[29229.917297] qmi_wwan 2-1:1.4 wwan0: register 'qmi_wwan' at usb-musb-hdrc.1-1, WWAN/QMI device, 36:5f:f3:a4:5d:4a
~ #
~ #
~ #
~ # echo 0 > ctl:usb_vbus_en/brightness
sh: can't create ctl:usb_vbus_en/brightness: nonexistent directory
~ # cd /sys/class/leds/
/sys/class/leds # echo 0 > ctl:usb_vbus_en/brightness
/sys/class/leds # [29505.622588] musb-hdrc musb-hdrc.1: VBUS_ERROR in a_wait_vrise (80, <SessEnd), retry #3, port1 0008050f
Jun 12 19:42:42 tms user.debug kernel: [29505.165155] musb-hdrc musb-hdrc.1: VBUS_ERROR in a_host (89, <AValid), retry #1, port1 00000507
Jun 12 19:42:42 tms user.debug kernel: [29505.317731] musb-hdrc musb-hdrc.1: VBUS_ERROR in a_wait_vrise (81, <SessEnd), retry #2, port1 00000507
Jun 12 19:42:42 tms user.debug kernel: [29505.470159] musb-hdrc musb-hdrc.1: VBUS_ERROR in a_wait_vrise (81, <SessEnd), retry #3, port1 00000507
Jun 12 19:42:43 tms user.err kernel: [29505.622588] musb-hdrc musb-hdrc.1: VBUS_ERROR in a_wait_vrise (80, <SessEnd), retry #3, port1 0008050f

/sys/class/leds #
/sys/class/leds #

  • Hi,

    The log shows the LTE modem is only enumerated at timestemp 29229 (sec), but not in the beginning of kernel booting. When did you connect the modem to the USB port?

    SY Yoon said:
    Sometimes USB host connection is disconnected.

    Please provide kernel dmesg log when the disconnect happens.

    SY Yoon said:
    I attach log message.

    The log shows VBUS_ERR at the end, please ensure you have minimum 120uF capacitor on the VBUS line close to the USB receptacle to avoid this VBUS_ERROR problem.

  • Hi, 

    I attach kernel dmesg log.

    ~ # dmesg
    [    0.000000] Booting Linux on physical CPU 0x0
    [    0.000000] Linux version 4.9.69-g89d085d1a4 (junil.choi@auto-dev02) (gcc version 6.4.0 (GCC) ) #1 PREEMPT Tue Jun 16 17:31:26 KST 2020
    [    0.000000] CPU: ARMv7 Processor [413fc082] revision 2 (ARMv7), cr=10c5387d
    [    0.000000] CPU: PIPT / VIPT nonaliasing data cache, VIPT aliasing instruction cache
    [    0.000000] OF: fdt:Machine model: DASAN TMS 3.0
    [    0.000000] efi: Getting EFI parameters from FDT:
    [    0.000000] efi: UEFI not found.
    [    0.000000] cma: Reserved 48 MiB at 0x88800000
    [    0.000000] Memory policy: Data cache writeback
    [    0.000000] On node 0 totalpages: 65536
    [    0.000000] free_area_init_node: node 0, pgdat c0d46b44, node_mem_map cfda1000
    [    0.000000]   Normal zone: 576 pages used for memmap
    [    0.000000]   Normal zone: 0 pages reserved
    [    0.000000]   Normal zone: 65536 pages, LIFO batch:15
    [    0.000000] CPU: All CPU(s) started in SVC mode.
    [    0.000000] AM335X ES2.1 (neon)
    [    0.000000] pcpu-alloc: s0 r0 d32768 u32768 alloc=1*32768
    [    0.000000] pcpu-alloc: [0] 0 
    [    0.000000] Built 1 zonelists in Zone order, mobility grouping on.  Total pages: 64960
    [    0.000000] Kernel command line: console=ttyS5,230400n8 quiet root=ubi0:rootfs rw ubi.mtd=NAND.1.file-system,4096 rootfstype=ubifs rootwait=1
    [    0.000000] PID hash table entries: 1024 (order: 0, 4096 bytes)
    [    0.000000] Dentry cache hash table entries: 32768 (order: 5, 131072 bytes)
    [    0.000000] Inode-cache hash table entries: 16384 (order: 4, 65536 bytes)
    [    0.000000] Memory: 196992K/262144K available (8192K kernel code, 292K rwdata, 2656K rodata, 1024K init, 302K bss, 16000K reserved, 49152K cma-reserved, 0K highmem)
    [    0.000000] Virtual kernel memory layout:
    [    0.000000]     vector  : 0xffff0000 - 0xffff1000   (   4 kB)
    [    0.000000]     fixmap  : 0xffc00000 - 0xfff00000   (3072 kB)
    [    0.000000]     vmalloc : 0xd0800000 - 0xff800000   ( 752 MB)
    [    0.000000]     lowmem  : 0xc0000000 - 0xd0000000   ( 256 MB)
    [    0.000000]     pkmap   : 0xbfe00000 - 0xc0000000   (   2 MB)
    [    0.000000]     modules : 0xbf000000 - 0xbfe00000   (  14 MB)
    [    0.000000]       .text : 0xc0008000 - 0xc0900000   (9184 kB)
    [    0.000000]       .init : 0xc0c00000 - 0xc0d00000   (1024 kB)
    [    0.000000]       .data : 0xc0d00000 - 0xc0d493d8   ( 293 kB)
    [    0.000000]        .bss : 0xc0d493d8 - 0xc0d94c98   ( 303 kB)
    [    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1
    [    0.000000] Preemptible hierarchical RCU implementation.
    [    0.000000] 	Build-time adjustment of leaf fanout to 32.
    [    0.000000] NR_IRQS:16 nr_irqs:16 16
    [    0.000000] IRQ: Found an INTC at 0xfa200000 (revision 5.0) with 128 interrupts
    [    0.000000] OMAP clockevent source: timer2 at 25000000 Hz
    [    0.000017] sched_clock: 32 bits at 25MHz, resolution 40ns, wraps every 85899345900ns
    [    0.000030] clocksource: timer1: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 76450417870 ns
    [    0.000036] OMAP clocksource: timer1 at 25000000 Hz
    [    0.000223] clocksource_probe: no matching clocksources found
    [    0.000391] Console: colour dummy device 80x30
    [    0.000428] Calibrating delay loop... 996.14 BogoMIPS (lpj=4980736)
    [    0.089231] pid_max: default: 32768 minimum: 301
    [    0.089352] Mount-cache hash table entries: 1024 (order: 0, 4096 bytes)
    [    0.089358] Mountpoint-cache hash table entries: 1024 (order: 0, 4096 bytes)
    [    0.090141] CPU: Testing write buffer coherency: ok
    [    0.090518] Setting up static identity map for 0x80100000 - 0x80100060
    [    0.091296] EFI services will not be available.
    [    0.092460] devtmpfs: initialized
    [    0.104660] VFP support v0.3: implementor 41 architecture 3 part 30 variant c rev 3
    [    0.104992] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns
    [    0.105012] futex hash table entries: 256 (order: -1, 3072 bytes)
    [    0.108582] pinctrl core: initialized pinctrl subsystem
    [    0.109997] NET: Registered protocol family 16
    [    0.111866] DMA: preallocated 256 KiB pool for atomic coherent allocations
    [    0.125226] omap_hwmod: debugss: _wait_target_disable failed
    [    0.199223] cpuidle: using governor ladder
    [    0.229210] cpuidle: using governor menu
    [    0.233263] gpio gpiochip0: (gpio): added GPIO chardev (254:0)
    [    0.233634] gpiochip_setup_dev: registered GPIOs 0 to 31 on device: gpiochip0 (gpio)
    [    0.234841] OMAP GPIO hardware version 0.1
    [    0.235639] gpio gpiochip1: (gpio): added GPIO chardev (254:1)
    [    0.235941] gpiochip_setup_dev: registered GPIOs 32 to 63 on device: gpiochip1 (gpio)
    [    0.237682] gpio gpiochip2: (gpio): added GPIO chardev (254:2)
    [    0.238067] gpiochip_setup_dev: registered GPIOs 64 to 95 on device: gpiochip2 (gpio)
    [    0.240085] gpio gpiochip3: (gpio): added GPIO chardev (254:3)
    [    0.240379] gpiochip_setup_dev: registered GPIOs 96 to 127 on device: gpiochip3 (gpio)
    [    0.248097] omap-gpmc 50000000.gpmc: could not find pctldev for node /ocp/l4_wkup@44c00000/scm@210000/pinmux@800/nandflash_pins_s0, deferring probe
    [    0.250396] No ATAGs?
    [    0.250412] hw-breakpoint: debug architecture 0x4 unsupported.
    [    0.251368] pstore: using zlib compression
    [    0.251386] pstore: Registered ramoops as persistent store backend
    [    0.251393] ramoops: attached 0x40000@0x8bfc0000, ecc: 0/0
    [    0.286785] edma 49000000.edma: TI EDMA DMA engine driver
    [    0.287148] of_get_named_gpiod_flags: can't parse 'gpio' property of node '/fixedregulator@0[0]'
    [    0.287502] of_get_named_gpiod_flags: parsed 'gpio' property of node '/fixedregulator@2[0]' - status (0)
    [    0.290527] usbcore: registered new interface driver usbfs
    [    0.290590] usbcore: registered new interface driver hub
    [    0.290703] usbcore: registered new device driver usb
    [    0.290966] omap_i2c 44e0b000.i2c: could not find pctldev for node /ocp/l4_wkup@44c00000/scm@210000/pinmux@800/pinmux_i2c0_pins, deferring probe
    [    0.291015] omap_i2c 4802a000.i2c: could not find pctldev for node /ocp/l4_wkup@44c00000/scm@210000/pinmux@800/pinmux_i2c1_pins, deferring probe
    [    0.291140] media: Linux media interface: v0.10
    [    0.291191] Linux video capture interface: v2.00
    [    0.291227] pps_core: LinuxPPS API ver. 1 registered
    [    0.291232] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>
    [    0.291251] PTP clock support registered
    [    0.291282] EDAC MC: Ver: 3.0.0
    [    0.292303] omap-mailbox 480c8000.mailbox: omap mailbox rev 0x400
    [    0.292650] Advanced Linux Sound Architecture Driver Initialized.
    [    0.293229] Bluetooth: Core ver 2.22
    [    0.293276] NET: Registered protocol family 31
    [    0.293280] Bluetooth: HCI device and connection manager initialized
    [    0.293294] Bluetooth: HCI socket layer initialized
    [    0.293302] Bluetooth: L2CAP socket layer initialized
    [    0.293328] Bluetooth: SCO socket layer initialized
    [    0.294502] clocksource: Switched to clocksource timer1
    [    0.303914] NET: Registered protocol family 2
    [    0.304727] TCP established hash table entries: 2048 (order: 1, 8192 bytes)
    [    0.304753] TCP bind hash table entries: 2048 (order: 1, 8192 bytes)
    [    0.304773] TCP: Hash tables configured (established 2048 bind 2048)
    [    0.304848] UDP hash table entries: 256 (order: 0, 4096 bytes)
    [    0.304860] UDP-Lite hash table entries: 256 (order: 0, 4096 bytes)
    [    0.304987] NET: Registered protocol family 1
    [    0.305416] RPC: Registered named UNIX socket transport module.
    [    0.305421] RPC: Registered udp transport module.
    [    0.305424] RPC: Registered tcp transport module.
    [    0.305427] RPC: Registered tcp NFSv4.1 backchannel transport module.
    [    0.305440] PCI: CLS 0 bytes, default 64
    [    0.306411] hw perfevents: enabled with armv7_cortex_a8 PMU driver, 5 counters available
    [    0.308665] workingset: timestamp_bits=14 max_order=16 bucket_order=2
    [    0.315688] squashfs: version 4.0 (2009/01/31) Phillip Lougher
    [    0.316501] NFS: Registering the id_resolver key type
    [    0.316540] Key type id_resolver registered
    [    0.316543] Key type id_legacy registered
    [    0.316585] ntfs: driver 2.1.32 [Flags: R/O].
    [    0.321111] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 246)
    [    0.321126] io scheduler noop registered
    [    0.321129] io scheduler deadline registered
    [    0.321253] io scheduler cfq registered (default)
    [    0.322672] pinctrl-single 44e10800.pinmux: 142 pins at pa f9e10800 size 568
    [    0.378557] Serial: 8250/16550 driver, 10 ports, IRQ sharing disabled
    [    0.382203] 44e09000.serial: ttyS0 at MMIO 0x44e09000 (irq = 158, base_baud = 3000000) is a 8250
    [    0.383189] 48024000.serial: ttyS2 at MMIO 0x48024000 (irq = 159, base_baud = 3000000) is a 8250
    [    0.384063] 481a6000.serial: ttyS3 at MMIO 0x481a6000 (irq = 160, base_baud = 3000000) is a 8250
    [    0.385023] 481aa000.serial: ttyS5 at MMIO 0x481aa000 (irq = 161, base_baud = 3000000) is a 8250
    [    0.385166] console [ttyS5] enabled
    [    0.386874] omap_rng 48310000.rng: OMAP Random Number Generator ver. 20
    [    0.387007] [drm] Initialized
    [    0.400318] brd: module loaded
    [    0.406561] loop: module loaded
    [    0.407127] (stk) :sysfs entries created
    [    0.407160] (stk) : debugfs entries created 
    [    0.410299] libphy: Fixed MDIO Bus: probed
    [    0.411359] CAN device driver interface
    [    0.412461] c_can_platform 481cc000.can: c_can_platform device registered (regs=fa1cc000, irq=166)
    [    0.413259] c_can_platform 481d0000.can: c_can_platform device registered (regs=fa1d0000, irq=167)
    [    0.484537] davinci_mdio 4a101000.mdio: davinci mdio revision 1.6
    [    0.484550] davinci_mdio 4a101000.mdio: no live phy, scanning all
    [    0.484832] davinci_mdio: probe of 4a101000.mdio failed with error -5
    [    0.485500] cpsw 4a100000.ethernet: Detected MACID = 98:5d:ad:e2:94:eb
    [    0.485625] cpsw 4a100000.ethernet: cpts: overflow check period 500 (jiffies)
    [    0.487055] usbcore: registered new interface driver qmi_wwan
    [    0.487108] usbcore: registered new interface driver cdc_wdm
    [    0.487214] usbcore: registered new interface driver usbserial
    [    0.487251] usbcore: registered new interface driver option
    [    0.487300] usbserial: USB Serial support registered for GSM modem (1-port)
    [    0.487762] mousedev: PS/2 mouse device common for all mice
    [    0.488362] ais328dq_spi spi1.0: platform data is NULL. using default one.
    [    0.488372] ais328dq_acc: hw init start
    [    0.488493] ais328dq_acc: int1: 00, int2: 00
    [    0.488593] ais328dq_acc: hw init done
    [    0.489026] input: ais328dq_acc as /devices/platform/ocp/48030000.spi/spi_master/spi1/spi1.0/input/input0
    [    0.490038] omap_rtc 44e3e000.rtc: already running
    [    0.490412] rtc rtc0: 44e3e000.rtc: dev (253:0)
    [    0.490436] omap_rtc 44e3e000.rtc: rtc core: registered 44e3e000.rtc as rtc0
    [    0.490676] i2c /dev entries driver
    [    0.493258] cpuidle: enable-method property 'ti,am3352' found operations
    [    0.494087] omap_hsmmc 48060000.mmc: GPIO lookup for consumer cd
    [    0.494097] omap_hsmmc 48060000.mmc: using device tree for GPIO lookup
    [    0.494107] of_get_named_gpiod_flags: can't parse 'cd-gpios' property of node '/ocp/mmc@48060000[0]'
    [    0.494114] of_get_named_gpiod_flags: can't parse 'cd-gpio' property of node '/ocp/mmc@48060000[0]'
    [    0.494120] omap_hsmmc 48060000.mmc: using lookup tables for GPIO lookup
    [    0.494126] omap_hsmmc 48060000.mmc: lookup for GPIO cd failed
    [    0.494135] omap_hsmmc 48060000.mmc: GPIO lookup for consumer wp
    [    0.494139] omap_hsmmc 48060000.mmc: using device tree for GPIO lookup
    [    0.494144] of_get_named_gpiod_flags: can't parse 'wp-gpios' property of node '/ocp/mmc@48060000[0]'
    [    0.494150] of_get_named_gpiod_flags: can't parse 'wp-gpio' property of node '/ocp/mmc@48060000[0]'
    [    0.494154] omap_hsmmc 48060000.mmc: using lookup tables for GPIO lookup
    [    0.494159] omap_hsmmc 48060000.mmc: lookup for GPIO wp failed
    [    0.494746] (stc):  chnl_id list empty :4 
    [    0.494758] (stk) : st_kim_start
    [    0.599551] (stk) :ldisc_install = 1
    [    0.745159] of_get_named_gpiod_flags: parsed 'gpios' property of node '/leds/led@0[0]' - status (0)
    [    0.748498] of_get_named_gpiod_flags: parsed 'gpios' property of node '/leds/led@1[0]' - status (0)
    [    0.748628] of_get_named_gpiod_flags: parsed 'gpios' property of node '/leds/led@2[0]' - status (0)
    [    0.748734] of_get_named_gpiod_flags: parsed 'gpios' property of node '/leds/led@3[0]' - status (0)
    [    0.748878] of_get_named_gpiod_flags: parsed 'gpios' property of node '/leds/led@4[0]' - status (0)
    [    0.748980] of_get_named_gpiod_flags: parsed 'gpios' property of node '/leds/led@5[0]' - status (0)
    [    0.749081] of_get_named_gpiod_flags: parsed 'gpios' property of node '/leds/led@6[0]' - status (0)
    [    0.749216] of_get_named_gpiod_flags: parsed 'gpios' property of node '/leds/led@7[0]' - status (0)
    [    0.749314] of_get_named_gpiod_flags: parsed 'gpios' property of node '/leds/led@8[0]' - status (0)
    [    0.749417] of_get_named_gpiod_flags: parsed 'gpios' property of node '/leds/led@9[0]' - status (0)
    [    0.749514] of_get_named_gpiod_flags: parsed 'gpios' property of node '/leds/led@10[0]' - status (0)
    [    0.749612] of_get_named_gpiod_flags: parsed 'gpios' property of node '/leds/led@11[0]' - status (0)
    [    0.749711] of_get_named_gpiod_flags: parsed 'gpios' property of node '/leds/led@12[0]' - status (0)
    [    0.749811] of_get_named_gpiod_flags: parsed 'gpios' property of node '/leds/led@13[0]' - status (0)
    [    0.749909] of_get_named_gpiod_flags: parsed 'gpios' property of node '/leds/led@14[0]' - status (0)
    [    0.750006] of_get_named_gpiod_flags: parsed 'gpios' property of node '/leds/led@15[0]' - status (0)
    [    0.750111] of_get_named_gpiod_flags: parsed 'gpios' property of node '/leds/led@16[0]' - status (0)
    [    0.750210] of_get_named_gpiod_flags: parsed 'gpios' property of node '/leds/led@17[0]' - status (0)
    [    0.750346] of_get_named_gpiod_flags: parsed 'gpios' property of node '/leds/led@18[0]' - status (0)
    [    0.750448] of_get_named_gpiod_flags: parsed 'gpios' property of node '/leds/led@19[0]' - status (0)
    [    0.750557] of_get_named_gpiod_flags: parsed 'gpios' property of node '/leds/led@20[0]' - status (0)
    [    0.750658] of_get_named_gpiod_flags: parsed 'gpios' property of node '/leds/led@21[0]' - status (0)
    [    0.750759] of_get_named_gpiod_flags: parsed 'gpios' property of node '/leds/led@22[0]' - status (0)
    [    0.750860] of_get_named_gpiod_flags: parsed 'gpios' property of node '/leds/led@23[0]' - status (0)
    [    0.750962] of_get_named_gpiod_flags: parsed 'gpios' property of node '/leds/led@24[0]' - status (0)
    [    0.751063] of_get_named_gpiod_flags: parsed 'gpios' property of node '/leds/led@25[0]' - status (0)
    [    0.751165] of_get_named_gpiod_flags: parsed 'gpios' property of node '/leds/led@26[0]' - status (0)
    [    0.751273] of_get_named_gpiod_flags: parsed 'gpios' property of node '/leds/led@27[0]' - status (0)
    [    0.751377] of_get_named_gpiod_flags: parsed 'gpios' property of node '/leds/led@28[0]' - status (0)
    [    0.751494] of_get_named_gpiod_flags: parsed 'gpios' property of node '/leds/led@29[0]' - status (0)
    [    0.751601] of_get_named_gpiod_flags: parsed 'gpios' property of node '/leds/led@30[0]' - status (0)
    [    0.751710] of_get_named_gpiod_flags: parsed 'gpios' property of node '/leds/led@31[0]' - status (0)
    [    0.751813] of_get_named_gpiod_flags: parsed 'gpios' property of node '/leds/led@32[0]' - status (0)
    [    0.752291] ledtrig-cpu: registered to indicate activity on CPUs
    [    0.754943] NET: Registered protocol family 10
    [    0.757596] sit: IPv6, IPv4 and MPLS over IPv4 tunneling driver
    [    0.758373] NET: Registered protocol family 17
    [    0.758395] can: controller area network core (rev 20120528 abi 9)
    [    0.758480] NET: Registered protocol family 29
    [    0.758524] can: raw protocol (rev 20120528)
    [    0.758528] can: broadcast manager protocol (rev 20161123 t)
    [    0.758538] can: netlink gateway (rev 20130117) max_hops=1
    [    0.758690] can: SAE J1939
    [    0.759008] Key type dns_resolver registered
    [    0.759231] omap_voltage_late_init: Voltage driver support not added
    [    0.764877] omap-gpmc 50000000.gpmc: GPMC revision 6.0
    [    0.764896] gpmc_mem_init: disabling cs 0 mapped at 0x0-0x1000000
    [    0.764926] gpiochip_find_base: found new base at 510
    [    0.764974] gpio gpiochip4: (omap-gpmc): added GPIO chardev (254:4)
    [    0.765394] gpiochip_setup_dev: registered GPIOs 510 to 511 on device: gpiochip4 (omap-gpmc)
    [    0.766488] omap2-nand 8000000.nand: GPIO lookup for consumer rb
    [    0.766500] omap2-nand 8000000.nand: using device tree for GPIO lookup
    [    0.766535] of_get_named_gpiod_flags: parsed 'rb-gpios' property of node '/ocp/gpmc@50000000/nand@0,0[0]' - status (0)
    [    0.766721] nand: device found, Manufacturer ID: 0x2c, Chip ID: 0xa3
    [    0.766724] nand: Micron MT29F8G08ABBCAH4
    [    0.766733] nand: 1024 MiB, SLC, erase size: 256 KiB, page size: 4096, OOB size: 224
    [    0.766772] nand: using OMAP_ECC_BCH8_CODE_HW ECC scheme
    [    0.766887] 14 ofpart partitions found on MTD device omap2-nand.0
    [    0.766893] Creating 14 MTD partitions on "omap2-nand.0":
    [    0.766907] 0x000000000000-0x000000040000 : "NAND.SPL"
    [    0.768336] 0x000000040000-0x000000080000 : "NAND.SPL.backup1"
    [    0.769421] 0x000000080000-0x000000140000 : "NAND.u-boot"
    [    0.770477] 0x000000140000-0x000000180000 : "NAND.u-boot-env"
    [    0.771492] 0x000000180000-0x0000001c0000 : "NAND.u-boot-env.backup1"
    [    0.772529] 0x0000001c0000-0x000000200000 : "NAND.inventory"
    [    0.773537] 0x000000200000-0x000000240000 : "NAND.1.fdt"
    [    0.774758] 0x000000240000-0x000000a00000 : "NAND.1.kernel"
    [    0.776480] 0x000000a00000-0x000008a00000 : "NAND.1.file-system"
    [    0.788732] 0x000008a00000-0x000008a40000 : "NAND.2.fdt"
    [    0.789742] 0x000008a40000-0x000009200000 : "NAND.2.kernel"
    [    0.791427] 0x000009200000-0x000011200000 : "NAND.2.file-system"
    [    0.803711] 0x000011200000-0x00003c000000 : "NAND.file-data"
    [    0.864765] 0x00003c000000-0x000040000000 : "NAND.nv-log"
    [    0.894760] tps65910 0-002d: No interrupt support, no core IRQ
    [    0.894971] tps65910-gpio tps65910-gpio: ti,en-gpio-sleep not specified
    [    0.894999] gpiochip_find_base: found new base at 504
    [    0.895031] gpio gpiochip5: (tps65910): added GPIO chardev (254:5)
    [    0.895326] gpiochip_setup_dev: registered GPIOs 504 to 509 on device: gpiochip5 (tps65910)
    [    0.896713] vrtc: supplied by vbat
    [    0.900444] vio: supplied by vbat
    [    0.901906] vdd1: supplied by vbat
    [    0.903675] vdd2: supplied by vbat
    [    0.904164] vdd_core: Bringing 1325000uV into 1112500-1112500uV
    [    0.906971] vdig1: supplied by vbat
    [    0.908312] vdig2: supplied by vbat
    [    0.909633] vpll: supplied by vbat
    [    0.910494] random: fast init done
    [    0.910988] vdac: supplied by vbat
    [    0.912323] vaux1: supplied by vbat
    [    0.913670] vaux2: supplied by vbat
    [    0.915017] vaux33: supplied by vbat
    [    0.916356] vmmc: supplied by vbat
    [    0.917690] vbb: supplied by vbat
    [    0.919580] lm75 0-0048: hwmon0: sensor 'tmp75'
    [    0.919637] omap_i2c 44e0b000.i2c: bus 0 rev0.11 at 400 kHz
    [    0.920795] omap_i2c 4802a000.i2c: bus 1 rev0.11 at 400 kHz
    [    0.922377] ubi0: attaching mtd8
    [    1.207901] ubi0: scanning is finished
    [    1.212917] ubi0: attached mtd8 (name "NAND.1.file-system", size 128 MiB)
    [    1.212928] ubi0: PEB size: 262144 bytes (256 KiB), LEB size: 253952 bytes
    [    1.212933] ubi0: min./max. I/O unit sizes: 4096/4096, sub-page size 1024
    [    1.212938] ubi0: VID header offset: 4096 (aligned 4096), data offset: 8192
    [    1.212942] ubi0: good PEBs: 512, bad PEBs: 0, corrupted PEBs: 0
    [    1.212947] ubi0: user volume: 1, internal volumes: 1, max. volumes count: 128
    [    1.212952] ubi0: max/mean erase counter: 15/3, WL threshold: 4096, image sequence number: 8162475
    [    1.212958] ubi0: available PEBs: 0, total reserved PEBs: 512, PEBs reserved for bad PEB handling: 80
    [    1.213383] of_get_named_gpiod_flags: parsed 'gpios' property of node '/input_keys/switch0[0]' - status (0)
    [    1.213410] of_get_named_gpiod_flags: parsed 'gpios' property of node '/input_keys/switch1[0]' - status (0)
    [    1.213426] of_get_named_gpiod_flags: parsed 'gpios' property of node '/input_keys/switch2[0]' - status (0)
    [    1.213439] of_get_named_gpiod_flags: parsed 'gpios' property of node '/input_keys/switch3[0]' - status (0)
    [    1.213453] of_get_named_gpiod_flags: parsed 'gpios' property of node '/input_keys/switch4[0]' - status (0)
    [    1.213468] of_get_named_gpiod_flags: parsed 'gpios' property of node '/input_keys/switch5[0]' - status (0)
    [    1.213485] of_get_named_gpiod_flags: parsed 'gpios' property of node '/input_keys/switch6[0]' - status (0)
    [    1.213750] omap_gpio 44e07000.gpio: Could not set line 19 debounce to 300000 microseconds (-22)
    [    1.213782] omap_gpio 44e07000.gpio: Could not set line 27 debounce to 300000 microseconds (-22)
    [    1.214224] input: input_keys as /devices/platform/input_keys/input/input1
    [    1.214932] omap_rtc 44e3e000.rtc: setting system clock to 2000-01-01 02:30:08 UTC (946693808)
    [    1.215469] wifi-regulator: disabling
    [    1.215609] ubi0: background thread "ubi_bgt0d" started, PID 99
    [    1.215731] ALSA device list:
    [    1.215736]   No soundcards found.
    [    1.224611] UBIFS (ubi0:0): background thread "ubifs_bgt0_0" started, PID 100
    [    1.251163] UBIFS (ubi0:0): recovery needed
    [    1.326529] UBIFS (ubi0:0): recovery completed
    [    1.326649] UBIFS (ubi0:0): UBIFS: mounted UBI device 0, volume 0, name "rootfs"
    [    1.326659] UBIFS (ubi0:0): LEB size: 253952 bytes (248 KiB), min./max. I/O unit sizes: 4096 bytes/4096 bytes
    [    1.326669] UBIFS (ubi0:0): FS size: 106151936 bytes (101 MiB, 418 LEBs), journal size 9404416 bytes (8 MiB, 38 LEBs)
    [    1.326673] UBIFS (ubi0:0): reserved for root: 0 bytes (0 KiB)
    [    1.326682] UBIFS (ubi0:0): media format: w4/r0 (latest is w4/r0), UUID 2C2FB564-AF26-4F76-A197-CE97AD62E633, small LPT model
    [    1.328206] VFS: Mounted root (ubifs filesystem) on device 0:14.
    [    1.329416] devtmpfs: mounted
    [    1.330878] Freeing unused kernel memory: 1024K
    [    1.512659] systemd[1]: System time before build time, advancing clock.
    [    1.604656] (stk) :ldisc installation timeout
    [    1.604670] (stk) :ldisc_install = 0
    [    2.644685] (stk) : timed out waiting for ldisc to be un-installed
    [    2.751821] (stk) :ldisc_install = 1
    [    3.194369] systemd-journald[123]: Received request to flush runtime journal from PID 1
    [    3.764670] (stk) :ldisc installation timeout
    [    3.764688] (stk) :ldisc_install = 0
    [    4.694098] omap_wdt: OMAP Watchdog Timer Rev 0x01: initial timeout 60 sec
    [    4.804726] (stk) : timed out waiting for ldisc to be un-installed
    [    4.912075] (stk) :ldisc_install = 1
    [    4.938607] ubi1: attaching mtd12
    [    5.713917] omap-aes 53500000.aes: OMAP AES hw accel rev: 3.2
    [    5.723865] remoteproc remoteproc0: wkup_m3 is available
    [    5.777884] remoteproc remoteproc0: powering up wkup_m3
    [    5.792337] omap-aes 53500000.aes: will run requests pump with realtime priority
    [    5.817001] PM: Cannot get wkup_m3_ipc handle
    [    5.827701] PM: Cannot get wkup_m3_ipc handle
    [    5.828187] omap-sham 53100000.sham: hw accel on OMAP rev 4.3
    [    5.881054] PM: Cannot get wkup_m3_ipc handle
    [    5.892319] remoteproc remoteproc0: Booting fw image am335x-pm-firmware.elf, size 224240
    [    5.892606] remoteproc remoteproc0: remote processor wkup_m3 is now up
    [    5.892640] wkup_m3_ipc 44e11324.wkup_m3_ipc: CM3 Firmware Version = 0x192
    [    5.924653] (stk) :ldisc installation timeout
    [    5.924671] (stk) :ldisc_install = 0
    [    6.964676] (stk) : timed out waiting for ldisc to be un-installed
    [    7.071801] (stk) :ldisc_install = 1
    [    8.084610] (stk) :ldisc installation timeout
    [    8.084626] (stk) :ldisc_install = 0
    [    8.791680] PM: bootloader does not support rtc-only!
    [    8.907960] am335x-phy-driver 47401300.usb-phy: GPIO lookup for consumer reset
    [    8.907980] am335x-phy-driver 47401300.usb-phy: using device tree for GPIO lookup
    [    8.907993] of_get_named_gpiod_flags: can't parse 'reset-gpios' property of node '/ocp/usb@47400000/usb-phy@47401300[0]'
    [    8.908000] of_get_named_gpiod_flags: can't parse 'reset-gpio' property of node '/ocp/usb@47400000/usb-phy@47401300[0]'
    [    8.908006] am335x-phy-driver 47401300.usb-phy: using lookup tables for GPIO lookup
    [    8.908014] am335x-phy-driver 47401300.usb-phy: lookup for GPIO reset failed
    [    8.908021] am335x-phy-driver 47401300.usb-phy: GPIO lookup for consumer vbus-detect
    [    8.908025] am335x-phy-driver 47401300.usb-phy: using device tree for GPIO lookup
    [    8.908031] of_get_named_gpiod_flags: can't parse 'vbus-detect-gpios' property of node '/ocp/usb@47400000/usb-phy@47401300[0]'
    [    8.908036] of_get_named_gpiod_flags: can't parse 'vbus-detect-gpio' property of node '/ocp/usb@47400000/usb-phy@47401300[0]'
    [    8.908041] am335x-phy-driver 47401300.usb-phy: using lookup tables for GPIO lookup
    [    8.908046] am335x-phy-driver 47401300.usb-phy: lookup for GPIO vbus-detect failed
    [    8.908142] 47401300.usb-phy supply vcc not found, using dummy regulator
    [    8.923340] am335x-phy-driver 47401b00.usb-phy: GPIO lookup for consumer reset
    [    8.923360] am335x-phy-driver 47401b00.usb-phy: using device tree for GPIO lookup
    [    8.923372] of_get_named_gpiod_flags: can't parse 'reset-gpios' property of node '/ocp/usb@47400000/usb-phy@47401b00[0]'
    [    8.923379] of_get_named_gpiod_flags: can't parse 'reset-gpio' property of node '/ocp/usb@47400000/usb-phy@47401b00[0]'
    [    8.923385] am335x-phy-driver 47401b00.usb-phy: using lookup tables for GPIO lookup
    [    8.923393] am335x-phy-driver 47401b00.usb-phy: lookup for GPIO reset failed
    [    8.923401] am335x-phy-driver 47401b00.usb-phy: GPIO lookup for consumer vbus-detect
    [    8.923406] am335x-phy-driver 47401b00.usb-phy: using device tree for GPIO lookup
    [    8.923412] of_get_named_gpiod_flags: can't parse 'vbus-detect-gpios' property of node '/ocp/usb@47400000/usb-phy@47401b00[0]'
    [    8.923417] of_get_named_gpiod_flags: can't parse 'vbus-detect-gpio' property of node '/ocp/usb@47400000/usb-phy@47401b00[0]'
    [    8.923421] am335x-phy-driver 47401b00.usb-phy: using lookup tables for GPIO lookup
    [    8.923426] am335x-phy-driver 47401b00.usb-phy: lookup for GPIO vbus-detect failed
    [    8.923523] 47401b00.usb-phy supply vcc not found, using dummy regulator
    [    9.018856] musb-hdrc musb-hdrc.0: MUSB HDRC host driver
    [    9.018905] musb-hdrc musb-hdrc.0: new USB bus registered, assigned bus number 1
    [    9.037857] hub 1-0:1.0: USB hub found
    [    9.038276] hub 1-0:1.0: 1 port detected
    [    9.108119] musb-hdrc musb-hdrc.1: MUSB HDRC host driver
    [    9.108169] musb-hdrc musb-hdrc.1: new USB bus registered, assigned bus number 2
    [    9.115953] hub 2-0:1.0: USB hub found
    [    9.116011] hub 2-0:1.0: 1 port detected
    [    9.124919] (stk) : timed out waiting for ldisc to be un-installed
    [    9.232247] (stk) :ldisc_install = 1
    [   10.082459] ubi1: scanning is finished
    [   10.117218] ubi1: attached mtd12 (name "NAND.file-data", size 686 MiB)
    [   10.117236] ubi1: PEB size: 262144 bytes (256 KiB), LEB size: 258048 bytes
    [   10.117242] ubi1: min./max. I/O unit sizes: 4096/4096, sub-page size 1024
    [   10.117246] ubi1: VID header offset: 1024 (aligned 1024), data offset: 4096
    [   10.117251] ubi1: good PEBs: 2744, bad PEBs: 0, corrupted PEBs: 0
    [   10.117256] ubi1: user volume: 1, internal volumes: 1, max. volumes count: 128
    [   10.117263] ubi1: max/mean erase counter: 19/7, WL threshold: 4096, image sequence number: 1898721964
    [   10.117269] ubi1: available PEBs: 0, total reserved PEBs: 2744, PEBs reserved for bad PEB handling: 80
    [   10.117317] ubi2: attaching mtd13
    [   10.130837] ubi1: background thread "ubi_bgt1d" started, PID 214
    [   10.244624] (stk) :ldisc installation timeout
    [   10.244642] (stk) :ldisc_install = 0
    [   10.328909] ubi2: scanning is finished
    [   10.353962] ubi2: attached mtd13 (name "NAND.nv-log", size 64 MiB)
    [   10.353981] ubi2: PEB size: 262144 bytes (256 KiB), LEB size: 258048 bytes
    [   10.353986] ubi2: min./max. I/O unit sizes: 4096/4096, sub-page size 1024
    [   10.353991] ubi2: VID header offset: 1024 (aligned 1024), data offset: 4096
    [   10.353996] ubi2: good PEBs: 256, bad PEBs: 0, corrupted PEBs: 0
    [   10.354001] ubi2: user volume: 1, internal volumes: 1, max. volumes count: 128
    [   10.354008] ubi2: max/mean erase counter: 254/97, WL threshold: 4096, image sequence number: 1033295459
    [   10.354014] ubi2: available PEBs: 0, total reserved PEBs: 256, PEBs reserved for bad PEB handling: 80
    [   10.361314] ubi2: background thread "ubi_bgt2d" started, PID 217
    [   10.507948] UBIFS (ubi2:0): background thread "ubifs_bgt2_0" started, PID 224
    [   10.508047] UBIFS (ubi1:0): background thread "ubifs_bgt1_0" started, PID 226
    [   10.608591] UBIFS (ubi2:0): recovery needed
    [   10.630068] UBIFS (ubi1:0): recovery needed
    [   10.849913] UBIFS (ubi2:0): recovery completed
    [   10.850050] UBIFS (ubi2:0): UBIFS: mounted UBI device 2, volume 0, name "nvlog"
    [   10.850060] UBIFS (ubi2:0): LEB size: 258048 bytes (252 KiB), min./max. I/O unit sizes: 4096 bytes/4096 bytes
    [   10.850069] UBIFS (ubi2:0): FS size: 41803776 bytes (39 MiB, 162 LEBs), journal size 9031680 bytes (8 MiB, 35 LEBs)
    [   10.850073] UBIFS (ubi2:0): reserved for root: 0 bytes (0 KiB)
    [   10.850082] UBIFS (ubi2:0): media format: w4/r0 (latest is w4/r0), UUID 4F9C58EE-34EC-4655-A070-A91D30B8EECA, small LPT model
    [   10.886282] UBIFS (ubi1:0): recovery completed
    [   10.886404] UBIFS (ubi1:0): UBIFS: mounted UBI device 1, volume 0, name "data"
    [   10.886413] UBIFS (ubi1:0): LEB size: 258048 bytes (252 KiB), min./max. I/O unit sizes: 4096 bytes/4096 bytes
    [   10.886422] UBIFS (ubi1:0): FS size: 683827200 bytes (652 MiB, 2650 LEBs), journal size 9420800 bytes (8 MiB, 37 LEBs)
    [   10.886427] UBIFS (ubi1:0): reserved for root: 0 bytes (0 KiB)
    [   10.886436] UBIFS (ubi1:0): media format: w4/r0 (latest is w4/r0), UUID 176624D0-5952-4B29-BE84-12F224232B33, small LPT model
    [   11.284732] (stk) : timed out waiting for ldisc to be un-installed
    [   11.393980] (stk) :ldisc_install = 1
    [   12.406550] (stk) :ldisc installation timeout
    [   12.406575] (stk) :ldisc_install = 0
    [   13.444849] (stk) : timed out waiting for ldisc to be un-installed
    [   13.446907] Bluetooth: st_register failed -22
    [  175.184824] random: crng init done
    ~ # 

    linux version is  4.9.69-g89d085d1a4 

    Best Regards, 

  • The kernel log shows no any usb device is enumerated. Is the modem attached to the USB port when kernel was booting? I don't see how the device disconnect happened since there is no device is enumerated.