search

victronenergy / venus

Victron Energy Unix/Linux OS
https://github.com/victronenergy/venus/wiki
570 stars 70 forks source link

onboard usb hub not found on Linux ccgx 4.14.67 #353

Closed jhofstee closed 5 years ago

jhofstee commented 5 years ago

While running a reflash test https://github.com/victronenergy/meta-victronenergy/blob/master/meta-venus/recipes-test/test-swupdate_1.3.bb with the swu on an USB pendrive, the test stops because the onboard root hub is not found (and hence the stick doesn't get mounted).

root@ccgx:~# lsusb
Bus 001 Device 001: ID 1d6b:0002 Linux Foundation 2.0 root hub

This is seen on two different ccgx's runing v2.20~66. It is unknown yet if 2.18 has the same issue.

jhofstee commented 5 years ago

2.18 doesn't seem to have this issue, it has been installing / rebooting from usb for at least 12 hours now

jhofstee commented 5 years ago

complete log of a bad boot

Sep 20 11:53:03 ccgx syslog.info syslogd started: BusyBox v1.24.1
Sep 20 11:53:03 ccgx user.notice kernel: klogd started: BusyBox v1.24.1 (2018-07-25 16:03:36 CEST)
Sep 20 11:53:03 ccgx user.info kernel: [    0.000000] Booting Linux on physical CPU 0x0
Sep 20 11:53:03 ccgx user.notice kernel: [    0.000000] Linux version 4.14.67 (oe-user@oe-host) (gcc version 7.3.0 (GCC)) #2 Fri Sep 14 22:12:14 CEST 2018
Sep 20 11:53:03 ccgx user.info kernel: [    0.000000] CPU: ARMv7 Processor [411fc087] revision 7 (ARMv7), cr=10c5387d
Sep 20 11:53:03 ccgx user.info kernel: [    0.000000] CPU: PIPT / VIPT nonaliasing data cache, VIPT nonaliasing instruction cache
Sep 20 11:53:03 ccgx user.info kernel: [    0.000000] OF: fdt: Machine model: Color Control GX
Sep 20 11:53:03 ccgx user.info kernel: [    0.000000] Memory policy: Data cache writeback
Sep 20 11:53:03 ccgx user.info kernel: [    0.000000] cma: Reserved 16 MiB at 0x8e800000
Sep 20 11:53:03 ccgx user.debug kernel: [    0.000000] On node 0 totalpages: 64768
Sep 20 11:53:03 ccgx user.debug kernel: [    0.000000] free_area_init_node: node 0, pgdat c0a5f1bc, node_mem_map cfab1000
Sep 20 11:53:03 ccgx user.debug kernel: [    0.000000]   Normal zone: 570 pages used for memmap
Sep 20 11:53:03 ccgx user.debug kernel: [    0.000000]   Normal zone: 0 pages reserved
Sep 20 11:53:03 ccgx user.debug kernel: [    0.000000]   Normal zone: 64768 pages, LIFO batch:15
Sep 20 11:53:03 ccgx user.info kernel: [    0.000000] CPU: All CPU(s) started in SVC mode.
Sep 20 11:53:03 ccgx user.info kernel: [    0.000000] AM3517 ES1.1 (l2cache sgx neon)
Sep 20 11:53:03 ccgx user.debug kernel: [    0.000000] pcpu-alloc: s0 r0 d32768 u32768 alloc=1*32768
Sep 20 11:53:03 ccgx user.debug kernel: [    0.000000] pcpu-alloc: [0] 0 
Sep 20 11:53:03 ccgx user.info kernel: [    0.000000] Built 1 zonelists, mobility grouping on.  Total pages: 64198
Sep 20 11:53:03 ccgx user.notice kernel: [    0.000000] Kernel command line: mem=254M root=ubi0:rootfs1 rootfstype=ubifs ubi.mtd=12 omapdss.def_disp=lcd omapfb.vram=0:2M@0x8fe00000 fbskip fbcon=vc:64 mtdparts=omap2-nand.0:128k(spl1),128k(spl2),128k(spl3),128k(spl4),1m
Sep 20 11:53:03 ccgx user.info kernel: [    0.000000] PID hash table entries: 1024 (order: 0, 4096 bytes)
Sep 20 11:53:03 ccgx user.info kernel: [    0.000000] Dentry cache hash table entries: 32768 (order: 5, 131072 bytes)
Sep 20 11:53:03 ccgx user.info kernel: [    0.000000] Inode-cache hash table entries: 16384 (order: 4, 65536 bytes)
Sep 20 11:53:03 ccgx user.info kernel: [    0.000000] Memory: 229972K/259072K available (6144K kernel code, 399K rwdata, 1808K rodata, 1024K init, 187K bss, 12716K reserved, 16384K cma-reserved, 0K highmem)
Sep 20 11:53:03 ccgx user.notice kernel: [    0.000000] Virtual kernel memory layout:
Sep 20 11:53:03 ccgx user.notice kernel: [    0.000000]     vector  : 0xffff0000 - 0xffff1000   (   4 kB)
Sep 20 11:53:03 ccgx user.notice kernel: [    0.000000]     fixmap  : 0xffc00000 - 0xfff00000   (3072 kB)
Sep 20 11:53:03 ccgx user.notice kernel: [    0.000000]     vmalloc : 0xd0000000 - 0xff800000   ( 760 MB)
Sep 20 11:53:03 ccgx user.notice kernel: [    0.000000]     lowmem  : 0xc0000000 - 0xcfd00000   ( 253 MB)
Sep 20 11:53:03 ccgx user.notice kernel: [    0.000000]     pkmap   : 0xbfe00000 - 0xc0000000   (   2 MB)
Sep 20 11:53:03 ccgx user.notice kernel: [    0.000000]     modules : 0xbf000000 - 0xbfe00000   (  14 MB)
Sep 20 11:53:03 ccgx user.notice kernel: [    0.000000]       .text : 0xc0008000 - 0xc0700000   (7136 kB)
Sep 20 11:53:03 ccgx user.notice kernel: [    0.000000]       .init : 0xc0900000 - 0xc0a00000   (1024 kB)
Sep 20 11:53:03 ccgx user.notice kernel: [    0.000000]       .data : 0xc0a00000 - 0xc0a63fa8   ( 400 kB)
Sep 20 11:53:03 ccgx user.notice kernel: [    0.000000]        .bss : 0xc0a6a658 - 0xc0a993fc   ( 188 kB)
Sep 20 11:53:03 ccgx user.info kernel: [    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1
Sep 20 11:53:03 ccgx user.info kernel: [    0.000000] NR_IRQS: 16, nr_irqs: 16, preallocated irqs: 16
Sep 20 11:53:03 ccgx user.info kernel: [    0.000000] IRQ: Found an INTC at 0xfa200000 (revision 4.0) with 96 interrupts
Sep 20 11:53:03 ccgx user.info kernel: [    0.000000] Clocking rate (Crystal/Core/MPU): 26.0/332/600 MHz
Sep 20 11:53:03 ccgx user.info kernel: [    0.000000] OMAP clockevent source: timer2 at 13000000 Hz
Sep 20 11:53:03 ccgx user.info kernel: [    0.000025] sched_clock: 32 bits at 13MHz, resolution 76ns, wraps every 165191050201ns
Sep 20 11:53:03 ccgx user.info kernel: [    0.000059] clocksource: timer1: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 147020034397 ns
Sep 20 11:53:03 ccgx user.info kernel: [    0.000075] OMAP clocksource: timer1 at 13000000 Hz
Sep 20 11:53:03 ccgx user.info kernel: [    0.000814] clocksource: 32k_counter: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 58327039986419 ns
Sep 20 11:53:03 ccgx user.info kernel: [    0.000830] OMAP clocksource: 32k_counter at 32768 Hz
Sep 20 11:53:03 ccgx user.info kernel: [    0.001473] Console: colour dummy device 80x30
Sep 20 11:53:03 ccgx user.info kernel: [    0.002053] console [tty0] enabled
Sep 20 11:53:03 ccgx user.info kernel: [    0.002097] Calibrating delay loop... 593.92 BogoMIPS (lpj=296960)
Sep 20 11:53:03 ccgx user.info kernel: [    0.010946] pid_max: default: 32768 minimum: 301
Sep 20 11:53:03 ccgx user.info kernel: [    0.011190] Mount-cache hash table entries: 1024 (order: 0, 4096 bytes)
Sep 20 11:53:03 ccgx user.info kernel: [    0.011230] Mountpoint-cache hash table entries: 1024 (order: 0, 4096 bytes)
Sep 20 11:53:03 ccgx user.info kernel: [    0.012300] CPU: Testing write buffer coherency: ok
Sep 20 11:53:03 ccgx user.info kernel: [    0.013512] Setting up static identity map for 0x80100000 - 0x80100060
Sep 20 11:53:03 ccgx user.info kernel: [    0.016292] devtmpfs: initialized
Sep 20 11:53:03 ccgx user.notice kernel: [    0.035454] random: get_random_u32 called from bucket_table_alloc+0x84/0x1a8 with crng_init=0
Sep 20 11:53:03 ccgx user.info kernel: [    0.035673] VFP support v0.3: implementor 41 architecture 3 part 30 variant c rev 1
Sep 20 11:53:03 ccgx user.info kernel: [    0.036056] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns
Sep 20 11:53:03 ccgx user.info kernel: [    0.036113] futex hash table entries: 256 (order: -1, 3072 bytes)
Sep 20 11:53:03 ccgx user.info kernel: [    0.037868] pinctrl core: initialized pinctrl subsystem
Sep 20 11:53:03 ccgx user.debug kernel: [    0.038472] regulator-dummy: no parameters
Sep 20 11:53:03 ccgx user.info kernel: [    0.039422] NET: Registered protocol family 16
Sep 20 11:53:03 ccgx user.info kernel: [    0.042967] DMA: preallocated 256 KiB pool for atomic coherent allocations
Sep 20 11:53:03 ccgx user.warn kernel: [    0.056866] omap_hwmod: mcbsp2_sidetone using broken dt data from mcbsp
Sep 20 11:53:03 ccgx user.warn kernel: [    0.057377] omap_hwmod: mcbsp3_sidetone using broken dt data from mcbsp
Sep 20 11:53:03 ccgx user.info kernel: [    0.136964] cpuidle: using governor ladder
Sep 20 11:53:03 ccgx user.info kernel: [    0.137657] Reprogramming SDRC clock to 332000000 Hz
Sep 20 11:53:03 ccgx user.err kernel: [    0.140901] platform 480c5000.aes: Cannot lookup hwmod 'aes'
Sep 20 11:53:03 ccgx user.info kernel: [    0.143419] OMAP GPIO hardware version 2.5
Sep 20 11:53:03 ccgx user.err kernel: [    0.153175] platform 480c3000.sham: Cannot lookup hwmod 'sham'
Sep 20 11:53:03 ccgx user.err kernel: [    0.153533] platform 480cb000.smartreflex: Cannot lookup hwmod 'smartreflex_core'
Sep 20 11:53:03 ccgx user.info kernel: [    0.158289] omap-gpmc 6e000000.gpmc: could not find pctldev for node /ocp@68000000/l4@48000000/scm@2000/pinmux@30/gpmc_pins, deferring probe
Sep 20 11:53:03 ccgx user.info kernel: [    0.163568] hw-breakpoint: debug architecture 0x4 unsupported.
Sep 20 11:53:03 ccgx user.info kernel: [    0.164901] OMAP DMA hardware revision 4.0
Sep 20 11:53:03 ccgx user.info kernel: [    0.182534] omap-dma-engine 48056000.dma-controller: OMAP DMA engine driver
Sep 20 11:53:03 ccgx user.debug kernel: [    0.183585] vmmc: 3300 mV 
Sep 20 11:53:03 ccgx user.debug kernel: [    0.183814] reg-fixed-voltage vmmc: vmmc supplying 3300000uV
Sep 20 11:53:03 ccgx user.debug kernel: [    0.184010] hsusb1_vbus: 3300 mV 
Sep 20 11:53:03 ccgx user.debug kernel: [    0.184172] reg-fixed-voltage hsusb1_power_reg: hsusb1_vbus supplying 3300000uV
Sep 20 11:53:03 ccgx user.info kernel: [    0.184266] reg-fixed-voltage vdd_can_regulator: could not find pctldev for node /ocp@68000000/l4@48000000/scm@2000/pinmux@30/vdd_can_pins, deferring probe
Sep 20 11:53:03 ccgx user.info kernel: [    0.184368] reg-fixed-voltage vdd_mk2_regulator: could not find pctldev for node /ocp@68000000/l4@48000000/scm@2000/pinmux@30/vdd_mk2_pins, deferring probe
Sep 20 11:53:03 ccgx user.notice kernel: [    0.186597] SCSI subsystem initialized
Sep 20 11:53:03 ccgx user.info kernel: [    0.187068] usbcore: registered new interface driver usbfs
Sep 20 11:53:03 ccgx user.info kernel: [    0.187168] usbcore: registered new interface driver hub
Sep 20 11:53:03 ccgx user.info kernel: [    0.187298] usbcore: registered new device driver usb
Sep 20 11:53:03 ccgx user.info kernel: [    0.187638] usb_phy_generic hsusb1_phy: could not find pctldev for node /ocp@68000000/pinmux@480025d8/hsusb1_phy_pins, deferring probe
Sep 20 11:53:03 ccgx user.info kernel: [    0.188074] omap_i2c 48070000.i2c: could not find pctldev for node /ocp@68000000/l4@48000000/scm@2000/pinmux@30/i2c1_pins, deferring probe
Sep 20 11:53:03 ccgx user.info kernel: [    0.189234] omap_i2c 48072000.i2c: bus 1 rev3.3 at 100 kHz
Sep 20 11:53:03 ccgx user.info kernel: [    0.189416] omap_i2c 48060000.i2c: could not find pctldev for node /ocp@68000000/l4@48000000/scm@2000/pinmux@30/i2c3_pins, deferring probe
Sep 20 11:53:03 ccgx user.info kernel: [    0.189699] pps_core: LinuxPPS API ver. 1 registered
Sep 20 11:53:03 ccgx user.info kernel: [    0.189728] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>
Sep 20 11:53:03 ccgx user.info kernel: [    0.189787] PTP clock support registered
Sep 20 11:53:03 ccgx user.info kernel: [    0.191174] clocksource: Switched to clocksource timer1
Sep 20 11:53:03 ccgx user.info kernel: [    0.251742] NET: Registered protocol family 2
Sep 20 11:53:03 ccgx user.info kernel: [    0.252654] TCP established hash table entries: 2048 (order: 1, 8192 bytes)
Sep 20 11:53:03 ccgx user.info kernel: [    0.252726] TCP bind hash table entries: 2048 (order: 1, 8192 bytes)
Sep 20 11:53:03 ccgx user.info kernel: [    0.252775] TCP: Hash tables configured (established 2048 bind 2048)
Sep 20 11:53:03 ccgx user.info kernel: [    0.252969] UDP hash table entries: 256 (order: 0, 4096 bytes)
Sep 20 11:53:03 ccgx user.info kernel: [    0.253013] UDP-Lite hash table entries: 256 (order: 0, 4096 bytes)
Sep 20 11:53:03 ccgx user.info kernel: [    0.253305] NET: Registered protocol family 1
Sep 20 11:53:03 ccgx user.notice kernel: [    0.255214] random: fast init done
Sep 20 11:53:03 ccgx user.warn kernel: [    0.256322] hw perfevents: no interrupt-affinity property for /pmu@54000000, guessing.
Sep 20 11:53:03 ccgx user.info kernel: [    0.256516] hw perfevents: enabled with armv7_cortex_a8 PMU driver, 5 counters available
Sep 20 11:53:03 ccgx user.info kernel: [    0.259761] audit: initializing netlink subsys (disabled)
Sep 20 11:53:03 ccgx user.notice kernel: [    0.261051] audit: type=2000 audit(0.260:1): state=initialized audit_enabled=0 res=1
Sep 20 11:53:03 ccgx user.info kernel: [    0.261276] workingset: timestamp_bits=14 max_order=16 bucket_order=2
Sep 20 11:53:03 ccgx user.info kernel: [    0.272028] io scheduler noop registered
Sep 20 11:53:03 ccgx user.info kernel: [    0.272092] io scheduler deadline registered
Sep 20 11:53:03 ccgx user.info kernel: [    0.272522] io scheduler cfq registered (default)
Sep 20 11:53:03 ccgx user.info kernel: [    0.274782] pinctrl-single 48002030.pinmux: 284 pins at pa fa002030 size 568
Sep 20 11:53:03 ccgx user.info kernel: [    0.275503] pinctrl-single 48002a00.pinmux: 46 pins at pa fa002a00 size 92
Sep 20 11:53:03 ccgx user.info kernel: [    0.276083] pinctrl-single 480025d8.pinmux: 18 pins at pa fa0025d8 size 36
Sep 20 11:53:03 ccgx user.debug kernel: [    0.278595] omapdss_dss 48050000.dss: Looking up vdda_video-supply from device tree
Sep 20 11:53:03 ccgx user.debug kernel: [    0.278620] omapdss_dss 48050000.dss: Looking up vdda_video-supply property in node /ocp@68000000/dss@48050000 failed
Sep 20 11:53:03 ccgx user.warn kernel: [    0.278643] omapdss_dss 48050000.dss: 48050000.dss supply vdda_video not found, using dummy regulator
Sep 20 11:53:03 ccgx user.info kernel: [    0.278866] OMAP DSS rev 2.0
Sep 20 11:53:03 ccgx user.info kernel: [    0.279153] omapdss_dss 48050000.dss: bound 48050400.dispc (ops dispc_component_ops)
Sep 20 11:53:03 ccgx user.info kernel: [    0.284252] omapfb omapfb: using display 'lcd' mode 480x272
Sep 20 11:53:03 ccgx user.debug kernel: [    0.285354] pbias_mmc_omap2430: 1800 <--> 3000 mV at 3000 mV 
Sep 20 11:53:03 ccgx user.info kernel: [    0.286890] omap_uart 4806a000.serial: no wakeirq for uart0
Sep 20 11:53:03 ccgx user.info kernel: [    0.287054] 4806a000.serial: ttyO0 at MMIO 0x4806a000 (irq = 88, base_baud = 3000000) is a OMAP UART0
Sep 20 11:53:03 ccgx user.warn kernel: [    0.287772] irq: no irq domain found for /ocp@68000000/l4@48000000/scm@2000/pinmux@30 !
Sep 20 11:53:03 ccgx user.info kernel: [    0.287850] omap_uart 4806c000.serial: no wakeirq for uart1
Sep 20 11:53:03 ccgx user.info kernel: [    0.287944] 4806c000.serial: ttyO1 at MMIO 0x4806c000 (irq = 89, base_baud = 3000000) is a OMAP UART1
Sep 20 11:53:03 ccgx user.warn kernel: [    0.288534] irq: no irq domain found for /ocp@68000000/l4@48000000/scm@2000/pinmux@30 !
Sep 20 11:53:03 ccgx user.info kernel: [    0.288600] omap_uart 49020000.serial: no wakeirq for uart2
Sep 20 11:53:03 ccgx user.info kernel: [    0.288688] 49020000.serial: ttyO2 at MMIO 0x49020000 (irq = 90, base_baud = 3000000) is a OMAP UART2
Sep 20 11:53:03 ccgx user.info kernel: [    0.303225] brd: module loaded
Sep 20 11:53:03 ccgx user.err kernel: [    0.304321] mtdoops: mtd device (mtddev=name/number) must be supplied
Sep 20 11:53:03 ccgx user.info kernel: [    0.306056] libphy: Fixed MDIO Bus: probed
Sep 20 11:53:03 ccgx user.info kernel: [    0.306398] CAN device driver interface
Sep 20 11:53:03 ccgx user.debug kernel: [    0.306725] ti_hecc 5c050000.can: Looking up xceiver-supply from device tree
Sep 20 11:53:03 ccgx user.info kernel: [    0.341210] davinci_mdio davinci_mdio.0: davinci mdio revision 1.5, bus freq 1000000
Sep 20 11:53:03 ccgx user.info kernel: [    0.341277] libphy: davinci_mdio.0: probed
Sep 20 11:53:03 ccgx user.info kernel: [    0.342456] davinci_mdio davinci_mdio.0: phy[0]: device davinci_mdio.0:00, driver SMSC LAN8710/LAN8720
Sep 20 11:53:03 ccgx user.info kernel: [    0.343498] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
Sep 20 11:53:03 ccgx user.info kernel: [    0.343536] ehci-omap: OMAP-EHCI Host Controller driver
Sep 20 11:53:03 ccgx user.err kernel: [    0.344258] ehci-omap 48064800.ehci: Can't get PHY device for port 0: -517
Sep 20 11:53:03 ccgx user.info kernel: [    0.344494] i2c /dev entries driver
Sep 20 11:53:03 ccgx user.info kernel: [    0.345870] omap_wdt: OMAP Watchdog Timer Rev 0x31: initial timeout 60 sec
Sep 20 11:53:03 ccgx user.debug kernel: [    0.346668] cpu cpu0: Looking up cpu0-supply from device tree
Sep 20 11:53:03 ccgx user.info kernel: [    0.347940] omap_hsmmc 4809c000.mmc: Got CD GPIO
Sep 20 11:53:03 ccgx user.debug kernel: [    0.348752] omap_hsmmc 4809c000.mmc: Looking up vmmc-supply from device tree
Sep 20 11:53:03 ccgx user.debug kernel: [    0.348938] omap_hsmmc 4809c000.mmc: Looking up vqmmc-supply from device tree
Sep 20 11:53:03 ccgx user.debug kernel: [    0.348961] omap_hsmmc 4809c000.mmc: Looking up vqmmc-supply property in node /ocp@68000000/mmc@4809c000 failed
Sep 20 11:53:03 ccgx user.debug kernel: [    0.348989] omap_hsmmc 4809c000.mmc: Looking up vmmc_aux-supply from device tree
Sep 20 11:53:03 ccgx user.debug kernel: [    0.349004] omap_hsmmc 4809c000.mmc: Looking up vmmc_aux-supply property in node /ocp@68000000/mmc@4809c000 failed
Sep 20 11:53:03 ccgx user.debug kernel: [    0.349017] omap_hsmmc 4809c000.mmc: Looking up pbias-supply from device tree
Sep 20 11:53:03 ccgx user.info kernel: [    0.375368] NET: Registered protocol family 10
Sep 20 11:53:03 ccgx user.info kernel: [    0.377266] Segment Routing with IPv6
Sep 20 11:53:03 ccgx user.info kernel: [    0.377401] NET: Registered protocol family 17
Sep 20 11:53:03 ccgx user.info kernel: [    0.377439] can: controller area network core (rev 20170425 abi 9)
Sep 20 11:53:03 ccgx user.info kernel: [    0.377584] NET: Registered protocol family 29
Sep 20 11:53:03 ccgx user.info kernel: [    0.377613] can: raw protocol (rev 20170425)
Sep 20 11:53:03 ccgx user.info kernel: [    0.377635] can: broadcast manager protocol (rev 20170425 t)
Sep 20 11:53:03 ccgx user.info kernel: [    0.377665] can: netlink gateway (rev 20170425) max_hops=1
Sep 20 11:53:03 ccgx user.err kernel: [    0.378023] omap2_set_init_voltage: unable to find boot up OPP for vdd_mpu_iva
Sep 20 11:53:03 ccgx user.err kernel: [    0.378060] omap2_set_init_voltage: unable to set vdd_mpu_iva
Sep 20 11:53:03 ccgx user.err kernel: [    0.378102] omap2_set_init_voltage: unable to find boot up OPP for vdd_core
Sep 20 11:53:03 ccgx user.err kernel: [    0.378125] omap2_set_init_voltage: unable to set vdd_core
Sep 20 11:53:03 ccgx user.warn kernel: [    0.378247] PM: no software I/O chain control; some wakeups may be lost
Sep 20 11:53:03 ccgx user.err kernel: [    0.378294] pm: Failed to request pm_wkup irq
Sep 20 11:53:03 ccgx user.info kernel: [    0.378369] ThumbEE CPU extension supported.
Sep 20 11:53:03 ccgx user.notice kernel: [    0.378407] Registering SWP/SWPB emulation handler
Sep 20 11:53:03 ccgx user.info kernel: [    0.378429] SmartReflex Class3 initialized
Sep 20 11:53:03 ccgx user.info kernel: [    0.392519] omap-gpmc 6e000000.gpmc: GPMC revision 5.0
Sep 20 11:53:03 ccgx user.warn kernel: [    0.392600] gpmc_mem_init: disabling cs 0 mapped at 0x0-0x1000000
Sep 20 11:53:03 ccgx user.info kernel: [    0.394334] nand: device found, Manufacturer ID: 0x2c, Chip ID: 0xbc
Sep 20 11:53:03 ccgx user.info kernel: [    0.394385] nand: Micron MT29F4G16ABBDAH4
Sep 20 11:53:03 ccgx user.info kernel: [    0.394410] nand: 512 MiB, SLC, erase size: 128 KiB, page size: 2048, OOB size: 64
Sep 20 11:53:03 ccgx user.warn kernel: [    0.394582] nand: WARNING: omap2-nand.0: the ECC used on your system is too weak compared to the one required by the NAND chip
Sep 20 11:53:03 ccgx user.notice kernel: [    0.394669] 13 cmdlinepart partitions found on MTD device omap2-nand.0
Sep 20 11:53:03 ccgx user.notice kernel: [    0.394695] Creating 13 MTD partitions on "omap2-nand.0":
Sep 20 11:53:03 ccgx user.notice kernel: [    0.394747] 0x000000000000-0x000000020000 : "spl1"
Sep 20 11:53:03 ccgx user.notice kernel: [    0.396320] 0x000000020000-0x000000040000 : "spl2"
Sep 20 11:53:03 ccgx user.notice kernel: [    0.397578] 0x000000040000-0x000000060000 : "spl3"
Sep 20 11:53:03 ccgx user.notice kernel: [    0.398821] 0x000000060000-0x000000080000 : "spl4"
Sep 20 11:53:03 ccgx user.notice kernel: [    0.400273] 0x000000080000-0x000000180000 : "u-boot"
Sep 20 11:53:03 ccgx user.notice kernel: [    0.402361] 0x000000180000-0x0000001c0000 : "env1"
Sep 20 11:53:03 ccgx user.notice kernel: [    0.403726] 0x0000001c0000-0x000000200000 : "env2"
Sep 20 11:53:03 ccgx user.notice kernel: [    0.405148] 0x000000200000-0x000000300000 : "u-boot2"
Sep 20 11:53:03 ccgx user.notice kernel: [    0.407338] 0x000000300000-0x000000340000 : "bootparms"
Sep 20 11:53:03 ccgx user.notice kernel: [    0.408660] 0x000000340000-0x000000400000 : "splash"
Sep 20 11:53:03 ccgx user.notice kernel: [    0.410498] 0x000000400000-0x000000a00000 : "kernel1"
Sep 20 11:53:03 ccgx user.notice kernel: [    0.416982] 0x000000a00000-0x000001000000 : "kernel2"
Sep 20 11:53:03 ccgx user.notice kernel: [    0.423483] 0x000001000000-0x000020000000 : "ubisystem"
Sep 20 11:53:03 ccgx user.debug kernel: [    0.867839] vdd_can: 5000 mV 
Sep 20 11:53:03 ccgx user.debug kernel: [    0.868063] reg-fixed-voltage vdd_can_regulator: vdd_can supplying 5000000uV
Sep 20 11:53:03 ccgx user.debug kernel: [    0.868478] vdd_mk2: 5000 mV 
Sep 20 11:53:03 ccgx user.debug kernel: [    0.868702] reg-fixed-voltage vdd_mk2_regulator: vdd_mk2 supplying 5000000uV
Sep 20 11:53:03 ccgx user.debug kernel: [    0.869336] usb_phy_generic hsusb1_phy: Looking up vcc-supply from device tree
Sep 20 11:53:03 ccgx user.debug kernel: [    0.871681] vdd_mpu: 1200 mV 
Sep 20 11:53:03 ccgx user.debug kernel: [    0.872321] vdd_core: 3300 mV 
Sep 20 11:53:03 ccgx user.debug kernel: [    0.872711] vdd_io: 1800 mV 
Sep 20 11:53:03 ccgx user.debug kernel: [    0.873238] vdd_usb18: 1800 mV 
Sep 20 11:53:03 ccgx user.debug kernel: [    0.873941] vdd_usb33: 3300 mV 
Sep 20 11:53:03 ccgx user.info kernel: [    0.875096] at24 0-0050: 256 byte 24c02 EEPROM, writable, 8 bytes/write
Sep 20 11:53:03 ccgx user.info kernel: [    0.875272] omap_i2c 48070000.i2c: bus 0 rev3.3 at 400 kHz
Sep 20 11:53:03 ccgx user.info kernel: [    0.878392] rtc-ds1307 2-0068: registered as rtc0
Sep 20 11:53:03 ccgx user.info kernel: [    0.878522] omap_i2c 48060000.i2c: bus 2 rev3.3 at 400 kHz
Sep 20 11:53:03 ccgx user.debug kernel: [    0.879002] ti_hecc 5c050000.can: Looking up xceiver-supply from device tree
Sep 20 11:53:03 ccgx user.info kernel: [    0.880331] ti_hecc 5c050000.can: device registered (reg_base=d00fb000, irq=40)
Sep 20 11:53:03 ccgx user.info kernel: [    0.901309] ehci-omap 48064800.ehci: EHCI Host Controller
Sep 20 11:53:03 ccgx user.info kernel: [    0.901412] ehci-omap 48064800.ehci: new USB bus registered, assigned bus number 1
Sep 20 11:53:03 ccgx user.info kernel: [    0.902039] ehci-omap 48064800.ehci: irq 93, io mem 0x48064800
Sep 20 11:53:03 ccgx user.info kernel: [    0.908200] ehci-omap 48064800.ehci: USB 2.0 started, EHCI 1.00
Sep 20 11:53:03 ccgx user.info kernel: [    0.908573] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002
Sep 20 11:53:03 ccgx user.info kernel: [    0.908608] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
Sep 20 11:53:03 ccgx user.info kernel: [    0.908639] usb usb1: Product: EHCI Host Controller
Sep 20 11:53:03 ccgx user.info kernel: [    0.908663] usb usb1: Manufacturer: Linux 4.14.67 ehci_hcd
Sep 20 11:53:03 ccgx user.info kernel: [    0.908687] usb usb1: SerialNumber: 48064800.ehci
Sep 20 11:53:03 ccgx user.info kernel: [    0.909571] hub 1-0:1.0: USB hub found
Sep 20 11:53:03 ccgx user.info kernel: [    0.909685] hub 1-0:1.0: 3 ports detected
Sep 20 11:53:03 ccgx user.debug kernel: [    0.910710] cpu cpu0: Looking up cpu0-supply from device tree
Sep 20 11:53:03 ccgx user.debug kernel: [    0.911508] cpu cpu0: Looking up cpu0-supply from device tree
Sep 20 11:53:03 ccgx user.debug kernel: [    0.913201] vdd_mpu: ramp_delay not set
Sep 20 11:53:03 ccgx user.notice kernel: [    0.913750] ubi0: attaching mtd12
Sep 20 11:53:03 ccgx user.notice kernel: [    1.922220] ubi0: scanning is finished
Sep 20 11:53:03 ccgx user.notice kernel: [    1.935414] ubi0: attached mtd12 (name "ubisystem", size 496 MiB)
Sep 20 11:53:03 ccgx user.notice kernel: [    1.935489] ubi0: PEB size: 131072 bytes (128 KiB), LEB size: 129024 bytes
Sep 20 11:53:03 ccgx user.notice kernel: [    1.935517] ubi0: min./max. I/O unit sizes: 2048/2048, sub-page size 512
Sep 20 11:53:03 ccgx user.notice kernel: [    1.935541] ubi0: VID header offset: 512 (aligned 512), data offset: 2048
Sep 20 11:53:03 ccgx user.notice kernel: [    1.935565] ubi0: good PEBs: 3968, bad PEBs: 0, corrupted PEBs: 0
Sep 20 11:53:03 ccgx user.notice kernel: [    1.935589] ubi0: user volume: 3, internal volumes: 1, max. volumes count: 128
Sep 20 11:53:03 ccgx user.notice kernel: [    1.935621] ubi0: max/mean erase counter: 1620/1105, WL threshold: 4096, image sequence number: 693697514
Sep 20 11:53:03 ccgx user.notice kernel: [    1.935654] ubi0: available PEBs: 371, total reserved PEBs: 3597, PEBs reserved for bad PEB handling: 80
Sep 20 11:53:03 ccgx user.info kernel: [    1.940095] input: gpio-keys as /devices/platform/gpio-keys/input/input0
Sep 20 11:53:03 ccgx user.notice kernel: [    1.940683] ubi0: background thread "ubi_bgt0d" started, PID 41
Sep 20 11:53:03 ccgx user.info kernel: [    1.941412] rtc-ds1307 2-0068: setting system clock to 2018-09-20 11:52:53 UTC (1537444373)
Sep 20 11:53:03 ccgx user.warn kernel: [    1.941464] sr_init: No PMIC hook to init smartreflex
Sep 20 11:53:03 ccgx user.err kernel: [    1.941704] sr_init: platform driver register failed for SR
Sep 20 11:53:03 ccgx user.info kernel: [    1.942729] vdd_can: disabling
Sep 20 11:53:03 ccgx user.notice kernel: [    1.975767] UBIFS (ubi0:0): UBIFS: mounted UBI device 0, volume 0, name "rootfs1", R/O mode
Sep 20 11:53:03 ccgx user.notice kernel: [    1.975850] UBIFS (ubi0:0): LEB size: 129024 bytes (126 KiB), min./max. I/O unit sizes: 2048 bytes/2048 bytes
Sep 20 11:53:03 ccgx user.notice kernel: [    1.975892] UBIFS (ubi0:0): FS size: 208373760 bytes (198 MiB, 1615 LEBs), journal size 9033728 bytes (8 MiB, 71 LEBs)
Sep 20 11:53:03 ccgx user.notice kernel: [    1.975925] UBIFS (ubi0:0): reserved for root: 0 bytes (0 KiB)
Sep 20 11:53:03 ccgx user.notice kernel: [    1.975954] UBIFS (ubi0:0): media format: w4/r0 (latest is w5/r0), UUID F1026514-2BA1-4E94-8DC6-0366B4F72762, small LPT model
Sep 20 11:53:03 ccgx user.info kernel: [    1.976860] VFS: Mounted root (ubifs filesystem) readonly on device 0:14.
Sep 20 11:53:03 ccgx user.info kernel: [    1.977673] devtmpfs: mounted
Sep 20 11:53:03 ccgx user.info kernel: [    1.980368] Freeing unused kernel memory: 1024K
Sep 20 11:53:03 ccgx user.notice kernel: [    2.426637] UBIFS (ubi0:2): background thread "ubifs_bgt0_2" started, PID 64
Sep 20 11:53:03 ccgx user.notice kernel: [    2.462552] UBIFS (ubi0:2): UBIFS: mounted UBI device 0, volume 2, name "data"
Sep 20 11:53:03 ccgx user.notice kernel: [    2.462625] UBIFS (ubi0:2): LEB size: 129024 bytes (126 KiB), min./max. I/O unit sizes: 2048 bytes/2048 bytes
Sep 20 11:53:03 ccgx user.notice kernel: [    2.462663] UBIFS (ubi0:2): FS size: 32385024 bytes (30 MiB, 251 LEBs), journal size 1677312 bytes (1 MiB, 13 LEBs)
Sep 20 11:53:03 ccgx user.notice kernel: [    2.462697] UBIFS (ubi0:2): reserved for root: 1529624 bytes (1493 KiB)
Sep 20 11:53:03 ccgx user.notice kernel: [    2.462727] UBIFS (ubi0:2): media format: w4/r0 (latest is w5/r0), UUID E1D7E377-C081-47C0-8D98-56C87D1A4135, small LPT model
Sep 20 11:53:03 ccgx daemon.info kernel: [    2.617128] udevd[73]: starting version 3.2.2
Sep 20 11:53:03 ccgx user.notice kernel: [    2.630413] random: udevd: uninitialized urandom read (16 bytes read)
Sep 20 11:53:03 ccgx user.notice kernel: [    2.633244] random: udevd: uninitialized urandom read (16 bytes read)
Sep 20 11:53:03 ccgx user.notice kernel: [    2.635887] random: udevd: uninitialized urandom read (16 bytes read)
Sep 20 11:53:03 ccgx daemon.info kernel: [    2.688786] udevd[74]: starting eudev-3.2.2
Sep 20 11:53:03 ccgx user.notice kernel: [    6.861109] UBIFS (ubi0:0): background thread "ubifs_bgt0_0" started, PID 294
Sep 20 11:53:03 ccgx user.warn kernel: [   10.596984] urandom_read: 3 callbacks suppressed
Sep 20 11:53:03 ccgx user.notice kernel: [   10.596998] random: dd: uninitialized urandom read (512 bytes read)
Sep 20 11:53:03 ccgx user.warn kernel: [   11.163569] davinci_mdio davinci_mdio.0: resetting idled controller
Sep 20 11:53:03 ccgx user.info kernel: [   11.220304] SMSC LAN8710/LAN8720 davinci_mdio.0:00: attached PHY driver [SMSC LAN8710/LAN8720] (mii_bus:phy_addr=davinci_mdio.0:00, irq=POLL)
Sep 20 11:53:03 ccgx user.info kernel: [   11.220514] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready
Sep 20 11:53:03 ccgx user.notice kernel: [   11.319487] random: dbus-uuidgen: uninitialized urandom read (12 bytes read)
Sep 20 11:53:03 ccgx user.notice kernel: [   11.319700] random: dbus-uuidgen: uninitialized urandom read (8 bytes read)
Sep 20 11:53:04 ccgx daemon.info connmand[734]: Connection Manager version 1.33
Sep 20 11:53:04 ccgx daemon.info connmand[734]: Checking loopback interface settings
Sep 20 11:53:04 ccgx daemon.info connmand[734]: System hostname is ccgx
Sep 20 11:53:04 ccgx cron.info crond[775]: (CRON) STARTUP (1.5.1)
Sep 20 11:53:04 ccgx cron.info crond[775]: (CRON) INFO (Syslog will be used instead of sendmail.)
Sep 20 11:53:04 ccgx cron.info crond[775]: (CRON) INFO (RANDOM_DELAY will be scaled with factor 4% if used.)
Sep 20 11:53:04 ccgx daemon.notice dbus[708]: [system] Activating service name='fi.w1.wpa_supplicant1' (using servicehelper)
Sep 20 11:53:04 ccgx daemon.err connmand[734]: Failed to open RFKILL control device
Sep 20 11:53:02 ccgx daemon.info dnsmasq[715]: started, version 2.78 cachesize 150
Sep 20 11:53:04 ccgx daemon.warn dnsmasq[715]: overflow: 4 log entries lost
Sep 20 11:53:04 ccgx daemon.err dnsmasq[715]: failed to read /run/resolv.conf: No such file or directory
Sep 20 11:53:04 ccgx daemon.info connmand[734]: lo {newlink} index 1 address 00:00:00:00:00:00 mtu 65536
Sep 20 11:53:04 ccgx daemon.info connmand[734]: lo {newlink} index 1 operstate 0 <UNKNOWN>
Sep 20 11:53:04 ccgx daemon.info connmand[734]: can0 {newlink} index 2 address 00:00:00:00:00:00 mtu 16
Sep 20 11:53:04 ccgx daemon.info connmand[734]: can0 {newlink} index 2 operstate 2 <DOWN>
Sep 20 11:53:04 ccgx daemon.info connmand[734]: eth0 {create} index 3 type 1 <ETHER>
Sep 20 11:53:04 ccgx daemon.info connmand[734]: eth0 {update} flags 4099 <UP>
Sep 20 11:53:04 ccgx daemon.info connmand[734]: eth0 {newlink} index 3 address D0:FF:50:F3:A8:95 mtu 1500
Sep 20 11:53:04 ccgx daemon.info connmand[734]: eth0 {newlink} index 3 operstate 2 <DOWN>
Sep 20 11:53:04 ccgx daemon.info connmand[734]: Adding interface eth0 [ ethernet ]
Sep 20 11:53:04 ccgx user.notice kernel: [   13.255301] random: crng init done
Sep 20 11:53:04 ccgx user.notice kernel: [   13.255381] random: 1 urandom warning(s) missed due to ratelimiting
Sep 20 11:53:05 ccgx user.notice kernel: [   13.642551] UBIFS (ubi0:1): UBIFS: mounted UBI device 0, volume 1, name "rootfs2", R/O mode
Sep 20 11:53:05 ccgx user.notice kernel: [   13.642640] UBIFS (ubi0:1): LEB size: 129024 bytes (126 KiB), min./max. I/O unit sizes: 2048 bytes/2048 bytes
Sep 20 11:53:05 ccgx user.notice kernel: [   13.642682] UBIFS (ubi0:1): FS size: 208373760 bytes (198 MiB, 1615 LEBs), journal size 9033728 bytes (8 MiB, 71 LEBs)
Sep 20 11:53:05 ccgx user.notice kernel: [   13.642716] UBIFS (ubi0:1): reserved for root: 0 bytes (0 KiB)
Sep 20 11:53:05 ccgx user.notice kernel: [   13.642745] UBIFS (ubi0:1): media format: w4/r0 (latest is w5/r0), UUID F1026514-2BA1-4E94-8DC6-0366B4F72762, small LPT model
Sep 20 11:53:05 ccgx daemon.notice dbus[708]: [system] Successfully activated service 'fi.w1.wpa_supplicant1'
Sep 20 11:53:05 ccgx daemon.notice wpa_supplicant[780]: Successfully initialized wpa_supplicant
Sep 20 11:53:05 ccgx user.notice kernel: [   13.748528] UBIFS (ubi0:1): un-mount UBI device 0
Sep 20 11:53:05 ccgx user.info kernel: [   14.334331] davinci_emac davinci_emac.0 eth0: Link is Up - 100Mbps/Full - flow control rx/tx
Sep 20 11:53:05 ccgx user.info kernel: [   14.334456] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
Sep 20 11:53:05 ccgx daemon.info connmand[734]: eth0 {add} route ff00:: gw :: scope 0 <UNIVERSE>
Sep 20 11:53:05 ccgx daemon.info connmand[734]: eth0 {add} route fe80:: gw :: scope 0 <UNIVERSE>
Sep 20 11:53:05 ccgx daemon.info connmand[734]: eth0 {update} flags 69699 <UP,RUNNING,LOWER_UP>
Sep 20 11:53:05 ccgx daemon.info connmand[734]: eth0 {newlink} index 3 address D0:FF:50:F3:A8:95 mtu 1500
Sep 20 11:53:05 ccgx daemon.info connmand[734]: eth0 {newlink} index 3 operstate 6 <UP>
Sep 20 11:53:05 ccgx daemon.warn connmand[734]: Skipping disconnect of carrier, network is connecting.
Sep 20 11:53:05 ccgx daemon.info connmand[734]: Setting domainname to almere.victronenergy.local
Sep 20 11:53:05 ccgx daemon.info connmand[734]: eth0 {add} address 192.168.4.53/22 label eth0 family 2
Sep 20 11:53:06 ccgx daemon.info connmand[734]: eth0 {add} route 192.168.4.0 gw 0.0.0.0 scope 253 <LINK>
Sep 20 11:53:06 ccgx daemon.info connmand[734]: eth0 {add} route 192.168.4.1 gw 0.0.0.0 scope 253 <LINK>
Sep 20 11:53:06 ccgx daemon.info connmand[734]: eth0 {add} route 0.0.0.0 gw 192.168.4.1 scope 0 <UNIVERSE>
Sep 20 11:53:06 ccgx daemon.info connmand[734]: eth0 {add} route 212.227.81.55 gw 192.168.4.1 scope 0 <UNIVERSE>
Sep 20 11:53:06 ccgx daemon.info dnsmasq[715]: read /etc/hosts - 6 addresses
Sep 20 11:53:06 ccgx daemon.info dnsmasq[715]: using nameserver 192.168.4.11#53
Sep 20 11:53:07 ccgx daemon.info connmand[734]: eth0 {del} route 212.227.81.55 gw 192.168.4.1 scope 0 <UNIVERSE>
Sep 20 11:53:08 ccgx daemon.info init: no more processes left in this runlevel
Sep 20 11:53:17 ccgx daemon.info dnsmasq[715]: read /etc/hosts - 6 addresses
Sep 20 11:53:17 ccgx daemon.info dnsmasq[715]: using nameserver 192.168.4.11#53
Sep 20 11:53:23 ccgx user.info kernel: [   32.014795] ti_hecc 5c050000.can can0: setting CANBTC=0x30049
Sep 20 11:53:23 ccgx user.info kernel: [   32.015087] IPv6: ADDRCONF(NETDEV_CHANGE): can0: link becomes ready
Sep 20 11:53:23 ccgx daemon.info connmand[734]: can0 {newlink} index 2 address 00:00:00:00:00:00 mtu 16
Sep 20 11:53:23 ccgx daemon.info connmand[734]: can0 {newlink} index 2 operstate 0 <UNKNOWN>
Sep 20 11:53:23 ccgx daemon.info connmand[734]: can0 {newlink} index 2 address 00:00:00:00:00:00 mtu 16
Sep 20 11:53:23 ccgx daemon.info connmand[734]: can0 {newlink} index 2 operstate 0 <UNKNOWN>
Sep 20 11:53:30 ccgx daemon.info dnsmasq[715]: read /etc/hosts - 6 addresses
Sep 20 11:53:30 ccgx daemon.info dnsmasq[715]: using nameserver 192.168.4.11#53
root@ccgx:/sys/bus/usb/devices/1-0:1.0# lsusb -v

Bus 001 Device 001: ID 1d6b:0002 Linux Foundation 2.0 root hub
Device Descriptor:
  bLength                18
  bDescriptorType         1
  bcdUSB               2.00
  bDeviceClass            9 Hub
  bDeviceSubClass         0 
  bDeviceProtocol         0 Full speed (or root) hub
  bMaxPacketSize0        64
  idVendor           0x1d6b Linux Foundation
  idProduct          0x0002 2.0 root hub
  bcdDevice            4.14
  iManufacturer           3 Linux 4.14.67 ehci_hcd
  iProduct                2 EHCI Host Controller
  iSerial                 1 48064800.ehci
  bNumConfigurations      1
  Configuration Descriptor:
    bLength                 9
    bDescriptorType         2
    wTotalLength           25
    bNumInterfaces          1
    bConfigurationValue     1
    iConfiguration          0 
    bmAttributes         0xe0
      Self Powered
      Remote Wakeup
    MaxPower                0mA
    Interface Descriptor:
      bLength                 9
      bDescriptorType         4
      bInterfaceNumber        0
      bAlternateSetting       0
      bNumEndpoints           1
      bInterfaceClass         9 Hub
      bInterfaceSubClass      0 
      bInterfaceProtocol      0 Full speed (or root) hub
      iInterface              0 
      Endpoint Descriptor:
        bLength                 7
        bDescriptorType         5
        bEndpointAddress     0x81  EP 1 IN
        bmAttributes            3
          Transfer Type            Interrupt
          Synch Type               None
          Usage Type               Data
        wMaxPacketSize     0x0004  1x 4 bytes
        bInterval              12
Hub Descriptor:
  bLength               9
  bDescriptorType      41
  nNbrPorts             3
  wHubCharacteristic 0x0009
    Per-port power switching
    Per-port overcurrent protection
  bPwrOn2PwrGood       10 * 2 milli seconds
  bHubContrCurrent      0 milli Ampere
  DeviceRemovable    0x00
  PortPwrCtrlMask    0xff
 Hub Port Status:
   Port 1: 0000.0100 power
   Port 2: 0000.0100 power
   Port 3: 0000.0100 power
can't get device qualifier: Resource temporarily unavailable
can't get debug descriptor: Resource temporarily unavailable
Device Status:     0x0001
  Self Powered

on a working system port 1 status is 'Port 1: 0000.0503 highspeed power enable connect'

jhofstee commented 5 years ago

good boot on the same device:

Sep 20 12:42:58 ccgx syslog.info syslogd started: BusyBox v1.24.1
Sep 20 12:42:58 ccgx user.notice kernel: klogd started: BusyBox v1.24.1 (2018-07-25 16:03:36 CEST)
Sep 20 12:42:58 ccgx user.info kernel: [    0.000000] Booting Linux on physical CPU 0x0
Sep 20 12:42:58 ccgx user.notice kernel: [    0.000000] Linux version 4.14.67 (oe-user@oe-host) (gcc version 7.3.0 (GCC)) #2 Fri Sep 14 22:12:14 CEST 2018
Sep 20 12:42:58 ccgx user.info kernel: [    0.000000] CPU: ARMv7 Processor [411fc087] revision 7 (ARMv7), cr=10c5387d
Sep 20 12:42:58 ccgx user.info kernel: [    0.000000] CPU: PIPT / VIPT nonaliasing data cache, VIPT nonaliasing instruction cache
Sep 20 12:42:58 ccgx user.info kernel: [    0.000000] OF: fdt: Machine model: Color Control GX
Sep 20 12:42:58 ccgx user.info kernel: [    0.000000] Memory policy: Data cache writeback
Sep 20 12:42:58 ccgx user.info kernel: [    0.000000] cma: Reserved 16 MiB at 0x8e800000
Sep 20 12:42:58 ccgx user.debug kernel: [    0.000000] On node 0 totalpages: 64768
Sep 20 12:42:58 ccgx user.debug kernel: [    0.000000] free_area_init_node: node 0, pgdat c0a5f1bc, node_mem_map cfab1000
Sep 20 12:42:58 ccgx user.debug kernel: [    0.000000]   Normal zone: 570 pages used for memmap
Sep 20 12:42:58 ccgx user.debug kernel: [    0.000000]   Normal zone: 0 pages reserved
Sep 20 12:42:58 ccgx user.debug kernel: [    0.000000]   Normal zone: 64768 pages, LIFO batch:15
Sep 20 12:42:58 ccgx user.info kernel: [    0.000000] CPU: All CPU(s) started in SVC mode.
Sep 20 12:42:58 ccgx user.info kernel: [    0.000000] AM3517 ES1.1 (l2cache sgx neon)
Sep 20 12:42:58 ccgx user.debug kernel: [    0.000000] pcpu-alloc: s0 r0 d32768 u32768 alloc=1*32768
Sep 20 12:42:58 ccgx user.debug kernel: [    0.000000] pcpu-alloc: [0] 0 
Sep 20 12:42:58 ccgx user.info kernel: [    0.000000] Built 1 zonelists, mobility grouping on.  Total pages: 64198
Sep 20 12:42:58 ccgx user.notice kernel: [    0.000000] Kernel command line: mem=254M root=ubi0:rootfs1 rootfstype=ubifs ubi.mtd=12 omapdss.def_disp=lcd omapfb.vram=0:2M@0x8fe00000 fbskip fbcon=vc:64 mtdparts=omap2-nand.0:128k(spl1),128k(spl2),128k(spl3),128k(spl4),1m
Sep 20 12:42:58 ccgx user.info kernel: [    0.000000] PID hash table entries: 1024 (order: 0, 4096 bytes)
Sep 20 12:42:58 ccgx user.info kernel: [    0.000000] Dentry cache hash table entries: 32768 (order: 5, 131072 bytes)
Sep 20 12:42:58 ccgx user.info kernel: [    0.000000] Inode-cache hash table entries: 16384 (order: 4, 65536 bytes)
Sep 20 12:42:58 ccgx user.info kernel: [    0.000000] Memory: 229972K/259072K available (6144K kernel code, 399K rwdata, 1808K rodata, 1024K init, 187K bss, 12716K reserved, 16384K cma-reserved, 0K highmem)
Sep 20 12:42:58 ccgx user.notice kernel: [    0.000000] Virtual kernel memory layout:
Sep 20 12:42:58 ccgx user.notice kernel: [    0.000000]     vector  : 0xffff0000 - 0xffff1000   (   4 kB)
Sep 20 12:42:58 ccgx user.notice kernel: [    0.000000]     fixmap  : 0xffc00000 - 0xfff00000   (3072 kB)
Sep 20 12:42:58 ccgx user.notice kernel: [    0.000000]     vmalloc : 0xd0000000 - 0xff800000   ( 760 MB)
Sep 20 12:42:58 ccgx user.notice kernel: [    0.000000]     lowmem  : 0xc0000000 - 0xcfd00000   ( 253 MB)
Sep 20 12:42:58 ccgx user.notice kernel: [    0.000000]     pkmap   : 0xbfe00000 - 0xc0000000   (   2 MB)
Sep 20 12:42:58 ccgx user.notice kernel: [    0.000000]     modules : 0xbf000000 - 0xbfe00000   (  14 MB)
Sep 20 12:42:58 ccgx user.notice kernel: [    0.000000]       .text : 0xc0008000 - 0xc0700000   (7136 kB)
Sep 20 12:42:58 ccgx user.notice kernel: [    0.000000]       .init : 0xc0900000 - 0xc0a00000   (1024 kB)
Sep 20 12:42:58 ccgx user.notice kernel: [    0.000000]       .data : 0xc0a00000 - 0xc0a63fa8   ( 400 kB)
Sep 20 12:42:58 ccgx user.notice kernel: [    0.000000]        .bss : 0xc0a6a658 - 0xc0a993fc   ( 188 kB)
Sep 20 12:42:58 ccgx user.info kernel: [    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1
Sep 20 12:42:58 ccgx user.info kernel: [    0.000000] NR_IRQS: 16, nr_irqs: 16, preallocated irqs: 16
Sep 20 12:42:58 ccgx user.info kernel: [    0.000000] IRQ: Found an INTC at 0xfa200000 (revision 4.0) with 96 interrupts
Sep 20 12:42:58 ccgx user.info kernel: [    0.000000] Clocking rate (Crystal/Core/MPU): 26.0/332/600 MHz
Sep 20 12:42:58 ccgx user.info kernel: [    0.000000] OMAP clockevent source: timer2 at 13000000 Hz
Sep 20 12:42:58 ccgx user.info kernel: [    0.000025] sched_clock: 32 bits at 13MHz, resolution 76ns, wraps every 165191050201ns
Sep 20 12:42:58 ccgx user.info kernel: [    0.000060] clocksource: timer1: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 147020034397 ns
Sep 20 12:42:58 ccgx user.info kernel: [    0.000075] OMAP clocksource: timer1 at 13000000 Hz
Sep 20 12:42:58 ccgx user.info kernel: [    0.000816] clocksource: 32k_counter: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 58327039986419 ns
Sep 20 12:42:58 ccgx user.info kernel: [    0.000830] OMAP clocksource: 32k_counter at 32768 Hz
Sep 20 12:42:58 ccgx user.info kernel: [    0.001464] Console: colour dummy device 80x30
Sep 20 12:42:58 ccgx user.info kernel: [    0.002040] console [tty0] enabled
Sep 20 12:42:58 ccgx user.info kernel: [    0.002085] Calibrating delay loop... 593.92 BogoMIPS (lpj=296960)
Sep 20 12:42:58 ccgx user.info kernel: [    0.010989] pid_max: default: 32768 minimum: 301
Sep 20 12:42:58 ccgx user.info kernel: [    0.011232] Mount-cache hash table entries: 1024 (order: 0, 4096 bytes)
Sep 20 12:42:58 ccgx user.info kernel: [    0.011273] Mountpoint-cache hash table entries: 1024 (order: 0, 4096 bytes)
Sep 20 12:42:58 ccgx user.info kernel: [    0.012319] CPU: Testing write buffer coherency: ok
Sep 20 12:42:58 ccgx user.info kernel: [    0.013532] Setting up static identity map for 0x80100000 - 0x80100060
Sep 20 12:42:58 ccgx user.info kernel: [    0.016299] devtmpfs: initialized
Sep 20 12:42:58 ccgx user.notice kernel: [    0.035438] random: get_random_u32 called from bucket_table_alloc+0x84/0x1a8 with crng_init=0
Sep 20 12:42:58 ccgx user.info kernel: [    0.035657] VFP support v0.3: implementor 41 architecture 3 part 30 variant c rev 1
Sep 20 12:42:58 ccgx user.info kernel: [    0.036044] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns
Sep 20 12:42:58 ccgx user.info kernel: [    0.036102] futex hash table entries: 256 (order: -1, 3072 bytes)
Sep 20 12:42:58 ccgx user.info kernel: [    0.037832] pinctrl core: initialized pinctrl subsystem
Sep 20 12:42:58 ccgx user.debug kernel: [    0.038429] regulator-dummy: no parameters
Sep 20 12:42:58 ccgx user.info kernel: [    0.039367] NET: Registered protocol family 16
Sep 20 12:42:58 ccgx user.info kernel: [    0.042884] DMA: preallocated 256 KiB pool for atomic coherent allocations
Sep 20 12:42:58 ccgx user.warn kernel: [    0.056723] omap_hwmod: mcbsp2_sidetone using broken dt data from mcbsp
Sep 20 12:42:58 ccgx user.warn kernel: [    0.057218] omap_hwmod: mcbsp3_sidetone using broken dt data from mcbsp
Sep 20 12:42:58 ccgx user.info kernel: [    0.140802] cpuidle: using governor ladder
Sep 20 12:42:58 ccgx user.info kernel: [    0.141548] Reprogramming SDRC clock to 332000000 Hz
Sep 20 12:42:58 ccgx user.err kernel: [    0.144789] platform 480c5000.aes: Cannot lookup hwmod 'aes'
Sep 20 12:42:58 ccgx user.info kernel: [    0.147294] OMAP GPIO hardware version 2.5
Sep 20 12:42:58 ccgx user.err kernel: [    0.157028] platform 480c3000.sham: Cannot lookup hwmod 'sham'
Sep 20 12:42:58 ccgx user.err kernel: [    0.157389] platform 480cb000.smartreflex: Cannot lookup hwmod 'smartreflex_core'
Sep 20 12:42:58 ccgx user.info kernel: [    0.162118] omap-gpmc 6e000000.gpmc: could not find pctldev for node /ocp@68000000/l4@48000000/scm@2000/pinmux@30/gpmc_pins, deferring probe
Sep 20 12:42:58 ccgx user.info kernel: [    0.167370] hw-breakpoint: debug architecture 0x4 unsupported.
Sep 20 12:42:58 ccgx user.info kernel: [    0.168684] OMAP DMA hardware revision 4.0
Sep 20 12:42:58 ccgx user.info kernel: [    0.186235] omap-dma-engine 48056000.dma-controller: OMAP DMA engine driver
Sep 20 12:42:58 ccgx user.debug kernel: [    0.187282] vmmc: 3300 mV 
Sep 20 12:42:58 ccgx user.debug kernel: [    0.187504] reg-fixed-voltage vmmc: vmmc supplying 3300000uV
Sep 20 12:42:58 ccgx user.debug kernel: [    0.187644] hsusb1_vbus: 3300 mV 
Sep 20 12:42:58 ccgx user.debug kernel: [    0.187802] reg-fixed-voltage hsusb1_power_reg: hsusb1_vbus supplying 3300000uV
Sep 20 12:42:58 ccgx user.info kernel: [    0.187891] reg-fixed-voltage vdd_can_regulator: could not find pctldev for node /ocp@68000000/l4@48000000/scm@2000/pinmux@30/vdd_can_pins, deferring probe
Sep 20 12:42:58 ccgx user.info kernel: [    0.188037] reg-fixed-voltage vdd_mk2_regulator: could not find pctldev for node /ocp@68000000/l4@48000000/scm@2000/pinmux@30/vdd_mk2_pins, deferring probe
Sep 20 12:42:58 ccgx user.notice kernel: [    0.190269] SCSI subsystem initialized
Sep 20 12:42:58 ccgx user.info kernel: [    0.190675] usbcore: registered new interface driver usbfs
Sep 20 12:42:58 ccgx user.info kernel: [    0.190771] usbcore: registered new interface driver hub
Sep 20 12:42:58 ccgx user.info kernel: [    0.190896] usbcore: registered new device driver usb
Sep 20 12:42:58 ccgx user.info kernel: [    0.191279] usb_phy_generic hsusb1_phy: could not find pctldev for node /ocp@68000000/pinmux@480025d8/hsusb1_phy_pins, deferring probe
Sep 20 12:42:58 ccgx user.info kernel: [    0.191659] omap_i2c 48070000.i2c: could not find pctldev for node /ocp@68000000/l4@48000000/scm@2000/pinmux@30/i2c1_pins, deferring probe
Sep 20 12:42:58 ccgx user.info kernel: [    0.192796] omap_i2c 48072000.i2c: bus 1 rev3.3 at 100 kHz
Sep 20 12:42:58 ccgx user.info kernel: [    0.193015] omap_i2c 48060000.i2c: could not find pctldev for node /ocp@68000000/l4@48000000/scm@2000/pinmux@30/i2c3_pins, deferring probe
Sep 20 12:42:58 ccgx user.info kernel: [    0.193305] pps_core: LinuxPPS API ver. 1 registered
Sep 20 12:42:58 ccgx user.info kernel: [    0.193333] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>
Sep 20 12:42:58 ccgx user.info kernel: [    0.193391] PTP clock support registered
Sep 20 12:42:58 ccgx user.info kernel: [    0.194689] clocksource: Switched to clocksource timer1
Sep 20 12:42:58 ccgx user.info kernel: [    0.255040] NET: Registered protocol family 2
Sep 20 12:42:58 ccgx user.info kernel: [    0.255950] TCP established hash table entries: 2048 (order: 1, 8192 bytes)
Sep 20 12:42:58 ccgx user.info kernel: [    0.256026] TCP bind hash table entries: 2048 (order: 1, 8192 bytes)
Sep 20 12:42:58 ccgx user.info kernel: [    0.256075] TCP: Hash tables configured (established 2048 bind 2048)
Sep 20 12:42:58 ccgx user.info kernel: [    0.256265] UDP hash table entries: 256 (order: 0, 4096 bytes)
Sep 20 12:42:58 ccgx user.info kernel: [    0.256309] UDP-Lite hash table entries: 256 (order: 0, 4096 bytes)
Sep 20 12:42:58 ccgx user.info kernel: [    0.256542] NET: Registered protocol family 1
Sep 20 12:42:58 ccgx user.notice kernel: [    0.256731] random: fast init done
Sep 20 12:42:58 ccgx user.warn kernel: [    0.259486] hw perfevents: no interrupt-affinity property for /pmu@54000000, guessing.
Sep 20 12:42:58 ccgx user.info kernel: [    0.259747] hw perfevents: enabled with armv7_cortex_a8 PMU driver, 5 counters available
Sep 20 12:42:58 ccgx user.info kernel: [    0.262980] audit: initializing netlink subsys (disabled)
Sep 20 12:42:58 ccgx user.notice kernel: [    0.264266] audit: type=2000 audit(0.261:1): state=initialized audit_enabled=0 res=1
Sep 20 12:42:58 ccgx user.info kernel: [    0.264439] workingset: timestamp_bits=14 max_order=16 bucket_order=2
Sep 20 12:42:58 ccgx user.info kernel: [    0.275211] io scheduler noop registered
Sep 20 12:42:58 ccgx user.info kernel: [    0.275272] io scheduler deadline registered
Sep 20 12:42:58 ccgx user.info kernel: [    0.275658] io scheduler cfq registered (default)
Sep 20 12:42:58 ccgx user.info kernel: [    0.277962] pinctrl-single 48002030.pinmux: 284 pins at pa fa002030 size 568
Sep 20 12:42:58 ccgx user.info kernel: [    0.278610] pinctrl-single 48002a00.pinmux: 46 pins at pa fa002a00 size 92
Sep 20 12:42:58 ccgx user.info kernel: [    0.279252] pinctrl-single 480025d8.pinmux: 18 pins at pa fa0025d8 size 36
Sep 20 12:42:58 ccgx user.debug kernel: [    0.281754] omapdss_dss 48050000.dss: Looking up vdda_video-supply from device tree
Sep 20 12:42:58 ccgx user.debug kernel: [    0.281778] omapdss_dss 48050000.dss: Looking up vdda_video-supply property in node /ocp@68000000/dss@48050000 failed
Sep 20 12:42:58 ccgx user.warn kernel: [    0.281802] omapdss_dss 48050000.dss: 48050000.dss supply vdda_video not found, using dummy regulator
Sep 20 12:42:58 ccgx user.info kernel: [    0.282027] OMAP DSS rev 2.0
Sep 20 12:42:58 ccgx user.info kernel: [    0.282316] omapdss_dss 48050000.dss: bound 48050400.dispc (ops dispc_component_ops)
Sep 20 12:42:58 ccgx user.info kernel: [    0.287360] omapfb omapfb: using display 'lcd' mode 480x272
Sep 20 12:42:58 ccgx user.debug kernel: [    0.288454] pbias_mmc_omap2430: 1800 <--> 3000 mV at 3000 mV 
Sep 20 12:42:58 ccgx user.info kernel: [    0.290031] omap_uart 4806a000.serial: no wakeirq for uart0
Sep 20 12:42:58 ccgx user.info kernel: [    0.290194] 4806a000.serial: ttyO0 at MMIO 0x4806a000 (irq = 88, base_baud = 3000000) is a OMAP UART0
Sep 20 12:42:58 ccgx user.warn kernel: [    0.290905] irq: no irq domain found for /ocp@68000000/l4@48000000/scm@2000/pinmux@30 !
Sep 20 12:42:58 ccgx user.info kernel: [    0.290980] omap_uart 4806c000.serial: no wakeirq for uart1
Sep 20 12:42:58 ccgx user.info kernel: [    0.291077] 4806c000.serial: ttyO1 at MMIO 0x4806c000 (irq = 89, base_baud = 3000000) is a OMAP UART1
Sep 20 12:42:58 ccgx user.warn kernel: [    0.291603] irq: no irq domain found for /ocp@68000000/l4@48000000/scm@2000/pinmux@30 !
Sep 20 12:42:58 ccgx user.info kernel: [    0.291668] omap_uart 49020000.serial: no wakeirq for uart2
Sep 20 12:42:58 ccgx user.info kernel: [    0.291820] 49020000.serial: ttyO2 at MMIO 0x49020000 (irq = 90, base_baud = 3000000) is a OMAP UART2
Sep 20 12:42:58 ccgx user.info kernel: [    0.306283] brd: module loaded
Sep 20 12:42:58 ccgx user.err kernel: [    0.307368] mtdoops: mtd device (mtddev=name/number) must be supplied
Sep 20 12:42:58 ccgx user.info kernel: [    0.309161] libphy: Fixed MDIO Bus: probed
Sep 20 12:42:58 ccgx user.info kernel: [    0.309430] CAN device driver interface
Sep 20 12:42:58 ccgx user.debug kernel: [    0.309820] ti_hecc 5c050000.can: Looking up xceiver-supply from device tree
Sep 20 12:42:58 ccgx user.info kernel: [    0.344728] davinci_mdio davinci_mdio.0: davinci mdio revision 1.5, bus freq 1000000
Sep 20 12:42:58 ccgx user.info kernel: [    0.344787] libphy: davinci_mdio.0: probed
Sep 20 12:42:58 ccgx user.info kernel: [    0.345944] davinci_mdio davinci_mdio.0: phy[0]: device davinci_mdio.0:00, driver SMSC LAN8710/LAN8720
Sep 20 12:42:58 ccgx user.info kernel: [    0.346986] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
Sep 20 12:42:58 ccgx user.info kernel: [    0.347027] ehci-omap: OMAP-EHCI Host Controller driver
Sep 20 12:42:58 ccgx user.err kernel: [    0.347755] ehci-omap 48064800.ehci: Can't get PHY device for port 0: -517
Sep 20 12:42:58 ccgx user.info kernel: [    0.347988] i2c /dev entries driver
Sep 20 12:42:58 ccgx user.info kernel: [    0.349297] omap_wdt: OMAP Watchdog Timer Rev 0x31: initial timeout 60 sec
Sep 20 12:42:58 ccgx user.debug kernel: [    0.350083] cpu cpu0: Looking up cpu0-supply from device tree
Sep 20 12:42:58 ccgx user.info kernel: [    0.351335] omap_hsmmc 4809c000.mmc: Got CD GPIO
Sep 20 12:42:58 ccgx user.debug kernel: [    0.352145] omap_hsmmc 4809c000.mmc: Looking up vmmc-supply from device tree
Sep 20 12:42:58 ccgx user.debug kernel: [    0.352335] omap_hsmmc 4809c000.mmc: Looking up vqmmc-supply from device tree
Sep 20 12:42:58 ccgx user.debug kernel: [    0.352358] omap_hsmmc 4809c000.mmc: Looking up vqmmc-supply property in node /ocp@68000000/mmc@4809c000 failed
Sep 20 12:42:58 ccgx user.debug kernel: [    0.352386] omap_hsmmc 4809c000.mmc: Looking up vmmc_aux-supply from device tree
Sep 20 12:42:58 ccgx user.debug kernel: [    0.352400] omap_hsmmc 4809c000.mmc: Looking up vmmc_aux-supply property in node /ocp@68000000/mmc@4809c000 failed
Sep 20 12:42:58 ccgx user.debug kernel: [    0.352412] omap_hsmmc 4809c000.mmc: Looking up pbias-supply from device tree
Sep 20 12:42:58 ccgx user.info kernel: [    0.378861] NET: Registered protocol family 10
Sep 20 12:42:58 ccgx user.info kernel: [    0.380731] Segment Routing with IPv6
Sep 20 12:42:58 ccgx user.info kernel: [    0.380875] NET: Registered protocol family 17
Sep 20 12:42:58 ccgx user.info kernel: [    0.380912] can: controller area network core (rev 20170425 abi 9)
Sep 20 12:42:58 ccgx user.info kernel: [    0.381052] NET: Registered protocol family 29
Sep 20 12:42:58 ccgx user.info kernel: [    0.381078] can: raw protocol (rev 20170425)
Sep 20 12:42:58 ccgx user.info kernel: [    0.381100] can: broadcast manager protocol (rev 20170425 t)
Sep 20 12:42:58 ccgx user.info kernel: [    0.381130] can: netlink gateway (rev 20170425) max_hops=1
Sep 20 12:42:58 ccgx user.err kernel: [    0.381486] omap2_set_init_voltage: unable to find boot up OPP for vdd_mpu_iva
Sep 20 12:42:58 ccgx user.err kernel: [    0.381526] omap2_set_init_voltage: unable to set vdd_mpu_iva
Sep 20 12:42:58 ccgx user.err kernel: [    0.381569] omap2_set_init_voltage: unable to find boot up OPP for vdd_core
Sep 20 12:42:58 ccgx user.err kernel: [    0.381593] omap2_set_init_voltage: unable to set vdd_core
Sep 20 12:42:58 ccgx user.warn kernel: [    0.381658] PM: no software I/O chain control; some wakeups may be lost
Sep 20 12:42:58 ccgx user.err kernel: [    0.381764] pm: Failed to request pm_wkup irq
Sep 20 12:42:58 ccgx user.info kernel: [    0.381842] ThumbEE CPU extension supported.
Sep 20 12:42:58 ccgx user.notice kernel: [    0.381879] Registering SWP/SWPB emulation handler
Sep 20 12:42:58 ccgx user.info kernel: [    0.381902] SmartReflex Class3 initialized
Sep 20 12:42:58 ccgx user.info kernel: [    0.395865] omap-gpmc 6e000000.gpmc: GPMC revision 5.0
Sep 20 12:42:58 ccgx user.warn kernel: [    0.395946] gpmc_mem_init: disabling cs 0 mapped at 0x0-0x1000000
Sep 20 12:42:58 ccgx user.info kernel: [    0.397608] nand: device found, Manufacturer ID: 0x2c, Chip ID: 0xbc
Sep 20 12:42:58 ccgx user.info kernel: [    0.397656] nand: Micron MT29F4G16ABBDAH4
Sep 20 12:42:58 ccgx user.info kernel: [    0.397745] nand: 512 MiB, SLC, erase size: 128 KiB, page size: 2048, OOB size: 64
Sep 20 12:42:58 ccgx user.warn kernel: [    0.397920] nand: WARNING: omap2-nand.0: the ECC used on your system is too weak compared to the one required by the NAND chip
Sep 20 12:42:58 ccgx user.notice kernel: [    0.398005] 13 cmdlinepart partitions found on MTD device omap2-nand.0
Sep 20 12:42:58 ccgx user.notice kernel: [    0.398032] Creating 13 MTD partitions on "omap2-nand.0":
Sep 20 12:42:58 ccgx user.notice kernel: [    0.398085] 0x000000000000-0x000000020000 : "spl1"
Sep 20 12:42:58 ccgx user.notice kernel: [    0.399624] 0x000000020000-0x000000040000 : "spl2"
Sep 20 12:42:58 ccgx user.notice kernel: [    0.400937] 0x000000040000-0x000000060000 : "spl3"
Sep 20 12:42:58 ccgx user.notice kernel: [    0.402184] 0x000000060000-0x000000080000 : "spl4"
Sep 20 12:42:58 ccgx user.notice kernel: [    0.403550] 0x000000080000-0x000000180000 : "u-boot"
Sep 20 12:42:58 ccgx user.notice kernel: [    0.405602] 0x000000180000-0x0000001c0000 : "env1"
Sep 20 12:42:58 ccgx user.notice kernel: [    0.407049] 0x0000001c0000-0x000000200000 : "env2"
Sep 20 12:42:58 ccgx user.notice kernel: [    0.408458] 0x000000200000-0x000000300000 : "u-boot2"
Sep 20 12:42:58 ccgx user.notice kernel: [    0.410584] 0x000000300000-0x000000340000 : "bootparms"
Sep 20 12:42:58 ccgx user.notice kernel: [    0.411930] 0x000000340000-0x000000400000 : "splash"
Sep 20 12:42:58 ccgx user.notice kernel: [    0.413756] 0x000000400000-0x000000a00000 : "kernel1"
Sep 20 12:42:58 ccgx user.notice kernel: [    0.420222] 0x000000a00000-0x000001000000 : "kernel2"
Sep 20 12:42:58 ccgx user.notice kernel: [    0.426662] 0x000001000000-0x000020000000 : "ubisystem"
Sep 20 12:42:58 ccgx user.debug kernel: [    0.871020] vdd_can: 5000 mV 
Sep 20 12:42:58 ccgx user.debug kernel: [    0.871244] reg-fixed-voltage vdd_can_regulator: vdd_can supplying 5000000uV
Sep 20 12:42:58 ccgx user.debug kernel: [    0.871596] vdd_mk2: 5000 mV 
Sep 20 12:42:58 ccgx user.debug kernel: [    0.871877] reg-fixed-voltage vdd_mk2_regulator: vdd_mk2 supplying 5000000uV
Sep 20 12:42:58 ccgx user.debug kernel: [    0.872450] usb_phy_generic hsusb1_phy: Looking up vcc-supply from device tree
Sep 20 12:42:58 ccgx user.debug kernel: [    0.874828] vdd_mpu: 1200 mV 
Sep 20 12:42:58 ccgx user.debug kernel: [    0.875455] vdd_core: 3300 mV 
Sep 20 12:42:58 ccgx user.debug kernel: [    0.875884] vdd_io: 1800 mV 
Sep 20 12:42:58 ccgx user.debug kernel: [    0.876411] vdd_usb18: 1800 mV 
Sep 20 12:42:58 ccgx user.debug kernel: [    0.877151] vdd_usb33: 3300 mV 
Sep 20 12:42:58 ccgx user.info kernel: [    0.878292] at24 0-0050: 256 byte 24c02 EEPROM, writable, 8 bytes/write
Sep 20 12:42:58 ccgx user.info kernel: [    0.878413] omap_i2c 48070000.i2c: bus 0 rev3.3 at 400 kHz
Sep 20 12:42:58 ccgx user.info kernel: [    0.881482] rtc-ds1307 2-0068: registered as rtc0
Sep 20 12:42:58 ccgx user.info kernel: [    0.881608] omap_i2c 48060000.i2c: bus 2 rev3.3 at 400 kHz
Sep 20 12:42:58 ccgx user.debug kernel: [    0.882129] ti_hecc 5c050000.can: Looking up xceiver-supply from device tree
Sep 20 12:42:58 ccgx user.info kernel: [    0.883401] ti_hecc 5c050000.can: device registered (reg_base=d00fb000, irq=40)
Sep 20 12:42:58 ccgx user.info kernel: [    0.903822] ehci-omap 48064800.ehci: EHCI Host Controller
Sep 20 12:42:58 ccgx user.info kernel: [    0.903918] ehci-omap 48064800.ehci: new USB bus registered, assigned bus number 1
Sep 20 12:42:58 ccgx user.info kernel: [    0.904542] ehci-omap 48064800.ehci: irq 93, io mem 0x48064800
Sep 20 12:42:58 ccgx user.info kernel: [    0.910720] ehci-omap 48064800.ehci: USB 2.0 started, EHCI 1.00
Sep 20 12:42:58 ccgx user.info kernel: [    0.911093] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002
Sep 20 12:42:58 ccgx user.info kernel: [    0.911127] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
Sep 20 12:42:58 ccgx user.info kernel: [    0.911159] usb usb1: Product: EHCI Host Controller
Sep 20 12:42:58 ccgx user.info kernel: [    0.911183] usb usb1: Manufacturer: Linux 4.14.67 ehci_hcd
Sep 20 12:42:58 ccgx user.info kernel: [    0.911207] usb usb1: SerialNumber: 48064800.ehci
Sep 20 12:42:58 ccgx user.info kernel: [    0.912075] hub 1-0:1.0: USB hub found
Sep 20 12:42:58 ccgx user.info kernel: [    0.912182] hub 1-0:1.0: 3 ports detected
Sep 20 12:42:58 ccgx user.debug kernel: [    0.913197] cpu cpu0: Looking up cpu0-supply from device tree
Sep 20 12:42:58 ccgx user.debug kernel: [    0.913976] cpu cpu0: Looking up cpu0-supply from device tree
Sep 20 12:42:58 ccgx user.debug kernel: [    0.915618] vdd_mpu: ramp_delay not set
Sep 20 12:42:58 ccgx user.notice kernel: [    0.916202] ubi0: attaching mtd12
Sep 20 12:42:58 ccgx user.info kernel: [    1.235783] usb 1-1: new high-speed USB device number 2 using ehci-omap
Sep 20 12:42:58 ccgx user.info kernel: [    1.364997] usb 1-1: New USB device found, idVendor=0424, idProduct=2412
Sep 20 12:42:58 ccgx user.info kernel: [    1.365049] usb 1-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0
Sep 20 12:42:58 ccgx user.info kernel: [    1.366180] hub 1-1:1.0: USB hub found
Sep 20 12:42:58 ccgx user.info kernel: [    1.366580] hub 1-1:1.0: 2 ports detected
Sep 20 12:42:58 ccgx user.info kernel: [    1.654931] usb 1-1.1: new high-speed USB device number 3 using ehci-omap
Sep 20 12:42:58 ccgx user.info kernel: [    1.751126] usb 1-1.1: New USB device found, idVendor=0718, idProduct=0704
Sep 20 12:42:58 ccgx user.info kernel: [    1.751171] usb 1-1.1: New USB device strings: Mfr=1, Product=2, SerialNumber=3
Sep 20 12:42:58 ccgx user.info kernel: [    1.751202] usb 1-1.1: Product: ImationFlashDriv
Sep 20 12:42:58 ccgx user.info kernel: [    1.751226] usb 1-1.1: Manufacturer: Imation
Sep 20 12:42:58 ccgx user.info kernel: [    1.751248] usb 1-1.1: SerialNumber: 07BB13034FBAA35E
Sep 20 12:42:58 ccgx user.notice kernel: [    1.932633] ubi0: scanning is finished
Sep 20 12:42:58 ccgx user.notice kernel: [    1.945942] ubi0: attached mtd12 (name "ubisystem", size 496 MiB)
Sep 20 12:42:58 ccgx user.notice kernel: [    1.946016] ubi0: PEB size: 131072 bytes (128 KiB), LEB size: 129024 bytes
Sep 20 12:42:58 ccgx user.notice kernel: [    1.946042] ubi0: min./max. I/O unit sizes: 2048/2048, sub-page size 512
Sep 20 12:42:58 ccgx user.notice kernel: [    1.946066] ubi0: VID header offset: 512 (aligned 512), data offset: 2048
Sep 20 12:42:58 ccgx user.notice kernel: [    1.946090] ubi0: good PEBs: 3968, bad PEBs: 0, corrupted PEBs: 0
Sep 20 12:42:58 ccgx user.notice kernel: [    1.946114] ubi0: user volume: 3, internal volumes: 1, max. volumes count: 128
Sep 20 12:42:58 ccgx user.notice kernel: [    1.946145] ubi0: max/mean erase counter: 1620/1105, WL threshold: 4096, image sequence number: 693697514
Sep 20 12:42:58 ccgx user.notice kernel: [    1.946179] ubi0: available PEBs: 371, total reserved PEBs: 3597, PEBs reserved for bad PEB handling: 80
Sep 20 12:42:58 ccgx user.info kernel: [    1.950604] input: gpio-keys as /devices/platform/gpio-keys/input/input0
Sep 20 12:42:58 ccgx user.notice kernel: [    1.951151] ubi0: background thread "ubi_bgt0d" started, PID 41
Sep 20 12:42:58 ccgx user.info kernel: [    1.951870] rtc-ds1307 2-0068: setting system clock to 2018-09-20 12:42:50 UTC (1537447370)
Sep 20 12:42:58 ccgx user.warn kernel: [    1.951921] sr_init: No PMIC hook to init smartreflex
Sep 20 12:42:58 ccgx user.err kernel: [    1.952160] sr_init: platform driver register failed for SR
Sep 20 12:42:58 ccgx user.info kernel: [    1.953164] vdd_can: disabling
Sep 20 12:42:58 ccgx user.notice kernel: [    1.990381] UBIFS (ubi0:0): UBIFS: mounted UBI device 0, volume 0, name "rootfs1", R/O mode
Sep 20 12:42:58 ccgx user.notice kernel: [    1.990463] UBIFS (ubi0:0): LEB size: 129024 bytes (126 KiB), min./max. I/O unit sizes: 2048 bytes/2048 bytes
Sep 20 12:42:58 ccgx user.notice kernel: [    1.990504] UBIFS (ubi0:0): FS size: 208373760 bytes (198 MiB, 1615 LEBs), journal size 9033728 bytes (8 MiB, 71 LEBs)
Sep 20 12:42:58 ccgx user.notice kernel: [    1.990537] UBIFS (ubi0:0): reserved for root: 0 bytes (0 KiB)
Sep 20 12:42:58 ccgx user.notice kernel: [    1.990567] UBIFS (ubi0:0): media format: w4/r0 (latest is w5/r0), UUID F1026514-2BA1-4E94-8DC6-0366B4F72762, small LPT model
Sep 20 12:42:58 ccgx user.info kernel: [    1.991410] VFS: Mounted root (ubifs filesystem) readonly on device 0:14.
Sep 20 12:42:58 ccgx user.info kernel: [    1.992192] devtmpfs: mounted
Sep 20 12:42:58 ccgx user.info kernel: [    1.994879] Freeing unused kernel memory: 1024K
Sep 20 12:42:58 ccgx user.notice kernel: [    2.441193] UBIFS (ubi0:2): background thread "ubifs_bgt0_2" started, PID 64
Sep 20 12:42:58 ccgx user.notice kernel: [    2.479213] UBIFS (ubi0:2): UBIFS: mounted UBI device 0, volume 2, name "data"
Sep 20 12:42:58 ccgx user.notice kernel: [    2.479286] UBIFS (ubi0:2): LEB size: 129024 bytes (126 KiB), min./max. I/O unit sizes: 2048 bytes/2048 bytes
Sep 20 12:42:58 ccgx user.notice kernel: [    2.479325] UBIFS (ubi0:2): FS size: 32385024 bytes (30 MiB, 251 LEBs), journal size 1677312 bytes (1 MiB, 13 LEBs)
Sep 20 12:42:58 ccgx user.notice kernel: [    2.479359] UBIFS (ubi0:2): reserved for root: 1529624 bytes (1493 KiB)
Sep 20 12:42:58 ccgx user.notice kernel: [    2.479390] UBIFS (ubi0:2): media format: w4/r0 (latest is w5/r0), UUID E1D7E377-C081-47C0-8D98-56C87D1A4135, small LPT model
Sep 20 12:42:58 ccgx daemon.info kernel: [    2.632276] udevd[73]: starting version 3.2.2
Sep 20 12:42:58 ccgx user.notice kernel: [    2.645491] random: udevd: uninitialized urandom read (16 bytes read)
Sep 20 12:42:58 ccgx user.notice kernel: [    2.648353] random: udevd: uninitialized urandom read (16 bytes read)
Sep 20 12:42:58 ccgx user.notice kernel: [    2.650997] random: udevd: uninitialized urandom read (16 bytes read)
Sep 20 12:42:58 ccgx daemon.info kernel: [    2.703360] udevd[74]: starting eudev-3.2.2
Sep 20 12:42:58 ccgx user.info kernel: [    3.416483] usb-storage 1-1.1:1.0: USB Mass Storage device detected
Sep 20 12:42:58 ccgx user.info kernel: [    3.442123] scsi host0: usb-storage 1-1.1:1.0
Sep 20 12:42:58 ccgx user.info kernel: [    3.442875] usbcore: registered new interface driver usb-storage
Sep 20 12:42:58 ccgx user.notice kernel: [    4.532022] scsi 0:0:0:0: Direct-Access     Imation  ImationFlashDriv PMAP PQ: 0 ANSI: 4
Sep 20 12:42:58 ccgx user.notice kernel: [    5.096629] sd 0:0:0:0: [sda] 7570752 512-byte logical blocks: (3.88 GB/3.61 GiB)
Sep 20 12:42:58 ccgx user.notice kernel: [    5.097592] sd 0:0:0:0: [sda] Write Protect is off
Sep 20 12:42:58 ccgx user.debug kernel: [    5.097644] sd 0:0:0:0: [sda] Mode Sense: 23 00 00 00
Sep 20 12:42:58 ccgx user.err kernel: [    5.098471] sd 0:0:0:0: [sda] No Caching mode page found
Sep 20 12:42:58 ccgx user.err kernel: [    5.098516] sd 0:0:0:0: [sda] Assuming drive cache: write through
Sep 20 12:42:58 ccgx user.info kernel: [    5.124880]  sda: sda1
Sep 20 12:42:58 ccgx user.notice kernel: [    5.129652] sd 0:0:0:0: [sda] Attached SCSI removable disk
Sep 20 12:42:58 ccgx user.notice kernel: [    7.382165] random: crng init done
Sep 20 12:42:58 ccgx user.notice kernel: [    7.382242] random: 3 urandom warning(s) missed due to ratelimiting
Sep 20 12:42:58 ccgx user.warn kernel: [    7.602789] FAT-fs (sda1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck.
Sep 20 12:42:58 ccgx user.notice kernel: [    7.752133] UBIFS (ubi0:0): background thread "ubifs_bgt0_0" started, PID 346
Sep 20 12:42:58 ccgx user.warn kernel: [    9.550582] davinci_mdio davinci_mdio.0: resetting idled controller
Sep 20 12:42:58 ccgx user.info kernel: [    9.606824] SMSC LAN8710/LAN8720 davinci_mdio.0:00: attached PHY driver [SMSC LAN8710/LAN8720] (mii_bus:phy_addr=davinci_mdio.0:00, irq=POLL)
Sep 20 12:42:58 ccgx user.info kernel: [    9.607036] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready
Sep 20 12:42:59 ccgx daemon.info connmand[534]: Connection Manager version 1.33
Sep 20 12:42:59 ccgx daemon.info connmand[534]: Checking loopback interface settings
Sep 20 12:42:59 ccgx daemon.info connmand[534]: System hostname is ccgx
Sep 20 12:42:59 ccgx daemon.notice dbus[508]: [system] Activating service name='fi.w1.wpa_supplicant1' (using servicehelper)
Sep 20 12:42:59 ccgx cron.info crond[575]: (CRON) STARTUP (1.5.1)
Sep 20 12:42:59 ccgx cron.info crond[575]: (CRON) INFO (Syslog will be used instead of sendmail.)
Sep 20 12:42:59 ccgx cron.info crond[575]: (CRON) INFO (RANDOM_DELAY will be scaled with factor 76% if used.)
Sep 20 12:42:59 ccgx daemon.err connmand[534]: Failed to open RFKILL control device
Sep 20 12:42:59 ccgx daemon.info connmand[534]: lo {newlink} index 1 address 00:00:00:00:00:00 mtu 65536
Sep 20 12:42:59 ccgx daemon.info connmand[534]: lo {newlink} index 1 operstate 0 <UNKNOWN>
Sep 20 12:42:59 ccgx daemon.info connmand[534]: can0 {newlink} index 2 address 00:00:00:00:00:00 mtu 16
Sep 20 12:42:57 ccgx daemon.info dnsmasq[515]: started, version 2.78 cachesize 150
Sep 20 12:42:59 ccgx daemon.warn dnsmasq[515]: overflow: 4 log entries lost
Sep 20 12:42:59 ccgx daemon.err dnsmasq[515]: failed to read /run/resolv.conf: No such file or directory
Sep 20 12:42:59 ccgx daemon.info connmand[534]: can0 {newlink} index 2 operstate 2 <DOWN>
Sep 20 12:42:59 ccgx daemon.info connmand[534]: eth0 {create} index 3 type 1 <ETHER>
Sep 20 12:42:59 ccgx daemon.info connmand[534]: eth0 {update} flags 4099 <UP>
Sep 20 12:42:59 ccgx daemon.info connmand[534]: eth0 {newlink} index 3 address D0:FF:50:F3:A8:95 mtu 1500
Sep 20 12:42:59 ccgx daemon.info connmand[534]: eth0 {newlink} index 3 operstate 2 <DOWN>
Sep 20 12:42:59 ccgx daemon.info connmand[534]: Adding interface eth0 [ ethernet ]
Sep 20 12:42:59 ccgx daemon.info connmand[534]: eth0 {add} route ff00:: gw :: scope 0 <UNIVERSE>
Sep 20 12:42:59 ccgx user.info kernel: [   11.683792] davinci_emac davinci_emac.0 eth0: Link is Up - 100Mbps/Full - flow control rx/tx
Sep 20 12:42:59 ccgx user.info kernel: [   11.683912] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
Sep 20 12:42:59 ccgx daemon.info connmand[534]: eth0 {add} route fe80:: gw :: scope 0 <UNIVERSE>
Sep 20 12:42:59 ccgx daemon.info connmand[534]: eth0 {update} flags 69699 <UP,RUNNING,LOWER_UP>
Sep 20 12:42:59 ccgx daemon.info connmand[534]: eth0 {newlink} index 3 address D0:FF:50:F3:A8:95 mtu 1500
Sep 20 12:42:59 ccgx daemon.info connmand[534]: eth0 {newlink} index 3 operstate 6 <UP>
Sep 20 12:42:59 ccgx daemon.warn connmand[534]: Skipping disconnect of carrier, network is connecting.
Sep 20 12:42:59 ccgx daemon.info connmand[534]: Setting domainname to almere.victronenergy.local
Sep 20 12:43:00 ccgx daemon.info connmand[534]: eth0 {add} address 192.168.4.53/22 label eth0 family 2
Sep 20 12:43:00 ccgx daemon.info connmand[534]: eth0 {add} route 192.168.4.0 gw 0.0.0.0 scope 253 <LINK>
Sep 20 12:43:00 ccgx daemon.info connmand[534]: eth0 {add} route 192.168.4.1 gw 0.0.0.0 scope 253 <LINK>
Sep 20 12:43:00 ccgx daemon.info connmand[534]: eth0 {add} route 0.0.0.0 gw 192.168.4.1 scope 0 <UNIVERSE>
Sep 20 12:43:00 ccgx daemon.info connmand[534]: eth0 {add} route 212.227.81.55 gw 192.168.4.1 scope 0 <UNIVERSE>
Sep 20 12:43:00 ccgx daemon.info connmand[534]: eth0 {del} route 212.227.81.55 gw 192.168.4.1 scope 0 <UNIVERSE>
Sep 20 12:43:00 ccgx daemon.info dnsmasq[515]: read /etc/hosts - 6 addresses
Sep 20 12:43:00 ccgx daemon.info dnsmasq[515]: using nameserver 192.168.4.11#53
Sep 20 12:43:00 ccgx user.notice kernel: [   12.243182] UBIFS (ubi0:1): UBIFS: mounted UBI device 0, volume 1, name "rootfs2", R/O mode
Sep 20 12:43:00 ccgx user.notice kernel: [   12.243266] UBIFS (ubi0:1): LEB size: 129024 bytes (126 KiB), min./max. I/O unit sizes: 2048 bytes/2048 bytes
Sep 20 12:43:00 ccgx user.notice kernel: [   12.243307] UBIFS (ubi0:1): FS size: 208373760 bytes (198 MiB, 1615 LEBs), journal size 9033728 bytes (8 MiB, 71 LEBs)
Sep 20 12:43:00 ccgx user.notice kernel: [   12.243341] UBIFS (ubi0:1): reserved for root: 0 bytes (0 KiB)
Sep 20 12:43:00 ccgx user.notice kernel: [   12.243370] UBIFS (ubi0:1): media format: w4/r0 (latest is w5/r0), UUID F1026514-2BA1-4E94-8DC6-0366B4F72762, small LPT model
Sep 20 12:43:00 ccgx daemon.notice dbus[508]: [system] Successfully activated service 'fi.w1.wpa_supplicant1'
Sep 20 12:43:00 ccgx daemon.notice wpa_supplicant[579]: Successfully initialized wpa_supplicant
Sep 20 12:43:00 ccgx user.notice kernel: [   12.375361] UBIFS (ubi0:1): un-mount UBI device 0
Sep 20 12:43:11 ccgx daemon.info dnsmasq[515]: read /etc/hosts - 6 addresses
Sep 20 12:43:11 ccgx daemon.info dnsmasq[515]: using nameserver 192.168.4.11#53
Sep 20 12:43:16 ccgx daemon.info init: no more processes left in this runlevel
jhofstee commented 5 years ago

a different pendrive caused the same issue after 5-6 hours flashing and rebooting:

Sep 20 18:48:22 ccgx user.info kernel: [    2.879391] usb 1-1.1: new high-speed USB device number 3 using ehci-omap
Sep 20 18:48:22 ccgx user.info kernel: [    2.979319] usb 1-1.1: New USB device found, idVendor=058f, idProduct=6387
Sep 20 18:48:22 ccgx user.info kernel: [    2.979397] usb 1-1.1: New USB device strings: Mfr=1, Product=2, SerialNumber=3
Sep 20 18:48:22 ccgx user.info kernel: [    2.979430] usb 1-1.1: SerialNumber: 13042251045121
Sep 20 18:48:22 ccgx user.info kernel: [    6.828911] usb-storage 1-1.1:1.0: USB Mass Storage device detected
Sep 20 18:48:22 ccgx user.info kernel: [    6.835677] scsi host0: usb-storage 1-1.1:1.0
Sep 20 18:48:22 ccgx user.info kernel: [    6.836398] usbcore: registered new interface driver usb-storage
Sep 20 18:48:22 ccgx user.notice kernel: [    6.926809] UBIFS (ubi0:1): background thread "ubifs_bgt0_1" started, PID 297
Sep 20 18:48:22 ccgx user.notice kernel: [    7.843060] scsi 0:0:0:0: Direct-Access                               8.07 PQ: 0 ANSI: 4
Sep 20 18:48:22 ccgx user.notice kernel: [    7.849718] sd 0:0:0:0: [sda] 7680000 512-byte logical blocks: (3.93 GB/3.66 GiB)
Sep 20 18:48:22 ccgx user.notice kernel: [    7.851084] sd 0:0:0:0: [sda] Write Protect is off
Sep 20 18:48:22 ccgx user.debug kernel: [    7.851145] sd 0:0:0:0: [sda] Mode Sense: 23 00 00 00
Sep 20 18:48:22 ccgx user.notice kernel: [    7.853757] sd 0:0:0:0: [sda] Write cache: disabled, read cache: enabled, doesn't support DPO or FUA
Sep 20 18:48:22 ccgx user.info kernel: [    7.862911]  sda: sda1
jhofstee commented 5 years ago

linux v3.10 seems to have this issue while linux v3.9 does not

jhofstee commented 5 years ago

workaround is at https://github.com/victronenergy/meta-victronenergy/commit/ef84962dc8a5bedf75bb5fca8b79c2a6fbf1828a

mpvader commented 5 years ago

Closing; we are not going to spend time fixing this for real.