custom am335x board - remoteproc0: failed to load am335x-pm-firmware.elf???

Hi Tom,

Is this the official TI distributed Linux SDK?

Hi, Yes it is Sitara SDK 8.00. Tom

Hi Biser, Thanks for the info as it was helpful to gain an understanding of the details.

The question now is that I cannot find the am335x-pm-firmware.bin file. In the targetNFS/lib/firmware dir there are these.

40 Feb 11 06:13 am43x-evm-scale-data.bin
151K Feb 11 06:13 am335x-pm-firmware.elf
16 Feb 11 06:13 am335x-evm-scale-data.bin
72 Feb 11 06:13 am335x-bone-scale-data.bin
50K Feb 11 06:14 TIInit_7.6.15.bts
50K Feb 11 06:14 TIInit_7.2.31.bts
69K Feb 11 06:14 TIInit_12.8.32.bts
15K Feb 11 06:14 TIInit_12.10.28.bts
e 69K Feb 11 06:14 TIInit_11.8.32.bts
53K Feb 11 06:14 TIInit_10.6.15.bts
4.0K Feb 11 07:07 ti-connectivity

Should I look somewhere else? Regs, Tom

Tom,

Sorry for the misunderstanding. The above post is a bit old. For SDK 8.0 the "am335x-pm-firmware.elf" file is the one that should be loaded, but the logic is the same.

No worries I did expect that was the file to use. However I tried building it into the kernel per the post you suggested with the same results. I turned on udev_debug and it complains about not finding /var/run/ so I am suspecting perhaps issues with the remount of the SD Card interface we are using for booting?

To simplify we have removed everything from the DTS except uart0, mmc, tps, memory. Is there a more simplified udev that I can look at? Thanks, Tom





==============================
udevd[850]: seq 820 running
[ 17.352109] platform 44d00000.wkup_m3: Driver wkup_m3 requests probe deferral
udevd[850]: device 0x39440 has devpath '/devices/ocp.3/53100000.sham'
[ 17.366012] remoteproc0: 4a334000.pru0 is available
[ 17.374973] remoteproc0: Note: remoteproc is still under development and considered experimental.
[ 17.388585] remoteproc0: THE BINARY FORMAT IS NOT YET FINALIZED, and backward compatibility isn't yet guaranteed.
udevd[850]: no db file to read /var/run/udev/data/+platform:53100000.sham: No such file or directory
udevd[850]: RUN '/sbin/modprobe $env{MODALIAS}' /etc/udev/rules.d/local.rules:17
udevd[825]: no db file to read /var/run/udev/data/+mmc:mmc1:0001: No such file or directory
[ 17.412326] remoteproc0: Direct firmware load failed with error -2
[ 17.422873] remoteproc0: Falling back to user helper

===============================
Starting kernel ...

[ 0.000000] Booting Linux on physical CPU 0x0
[ 0.000000] Linux version 3.14.26-g03832cc-dirty (triddle@triddle-ThinkPad-T430) (gcc version 4.7.3 20130226 (prerelease) (crosstool-NG linaro-1.13.1-4.5
[ 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] Machine model: TI AM335x EVM
[ 0.000000] cma: CMA: reserved 24 MiB at 82000000
[ 0.000000] Memory policy: Data cache writeback
[ 0.000000] CPU: All CPU(s) started in SVC mode.
[ 0.000000] AM335X ES2.1 (neon )
[ 0.000000] Built 1 zonelists in Zone order, mobility grouping on. Total pages: 15744
[ 0.000000] Kernel command line: console=ttyO0,115200n8 root=/dev/mmcblk0p2 ro rootfstype=ext4 rootwait
[ 0.000000] PID hash table entries: 256 (order: -2, 1024 bytes)
[ 0.000000] Dentry cache hash table entries: 8192 (order: 3, 32768 bytes)
[ 0.000000] Inode-cache hash table entries: 4096 (order: 2, 16384 bytes)
[ 0.000000] Memory: 29320K/63488K available (5763K kernel code, 312K rwdata, 2232K rodata, 267K init, 231K bss, 34168K reserved, 0K highmem)
[ 0.000000] Virtual kernel memory layout:
[ 0.000000] vector : 0xffff0000 - 0xffff1000 ( 4 kB)
[ 0.000000] fixmap : 0xfff00000 - 0xfffe0000 ( 896 kB)
[ 0.000000] vmalloc : 0xc4800000 - 0xff000000 ( 936 MB)
[ 0.000000] lowmem : 0xc0000000 - 0xc4000000 ( 64 MB)
[ 0.000000] pkmap : 0xbfe00000 - 0xc0000000 ( 2 MB)
[ 0.000000] modules : 0xbf000000 - 0xbfe00000 ( 14 MB)
[ 0.000000] .text : 0xc0008000 - 0xc07d7054 (7997 kB)
[ 0.000000] .init : 0xc07d8000 - 0xc081ad84 ( 268 kB)
[ 0.000000] .data : 0xc081c000 - 0xc086a260 ( 313 kB)
[ 0.000000] .bss : 0xc086a260 - 0xc08a41f8 ( 232 kB)
[ 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] Total of 128 interrupts on 1 active controller
[ 0.000000] OMAP clockevent source: timer2 at 24000000 Hz
[ 0.000015] sched_clock: 32 bits at 24MHz, resolution 41ns, wraps every 178956969942ns
[ 0.000041] OMAP clocksource: timer1 at 24000000 Hz
[ 0.000389] Console: colour dummy device 80x30
[ 0.000428] Calibrating delay loop... 597.60 BogoMIPS (lpj=2988032)
[ 0.119249] pid_max: default: 32768 minimum: 301
[ 0.119371] Security Framework initialized
[ 0.119441] Mount-cache hash table entries: 1024 (order: 0, 4096 bytes)
[ 0.119455] Mountpoint-cache hash table entries: 1024 (order: 0, 4096 bytes)
[ 0.127974] CPU: Testing write buffer coherency: ok
[ 0.128479] Setting up static identity map for 0x805a71a0 - 0x805a71f8
[ 0.129937] devtmpfs: initialized
[ 0.132424] VFP support v0.3: implementor 41 architecture 3 part 30 variant c rev 3
[ 0.141595] omap_hwmod: tptc0 using broken dt data from edma
[ 0.141707] omap_hwmod: tptc1 using broken dt data from edma
[ 0.141801] omap_hwmod: tptc2 using broken dt data from edma
[ 0.146336] omap_hwmod: debugss: _wait_target_disable failed
[ 0.203547] pinctrl core: initialized pinctrl subsystem
[ 0.205030] regulator-dummy: no parameters
[ 0.208041] NET: Registered protocol family 16
[ 0.210822] DMA: preallocated 256 KiB pool for atomic coherent allocations
[ 0.214364] cpuidle: using governor ladder
[ 0.214378] cpuidle: using governor menu
[ 0.223521] syscon 44e10000.control_module: regmap [mem 0x44e10000-0x44e107fb] registered
[ 0.225689] platform 49000000.edma: alias fck already exists
[ 0.225713] platform 49000000.edma: alias fck already exists
[ 0.225732] platform 49000000.edma: alias fck already exists
[ 0.227328] OMAP GPIO hardware version 0.1
[ 0.243966] No ATAGs?
[ 0.243988] hw-breakpoint: debug architecture 0x4 unsupported.
[ 0.277936] bio: create slab <bio-0> at 0
[ 0.300270] edma-dma-engine edma-dma-engine.0: TI EDMA DMA engine driver
[ 0.301584] vbat: 5000 mV
[ 0.302163] lis3_reg: no parameters
[ 0.305878] vgaarb: loaded
[ 0.306593] i2c-core: driver [palmas] using legacy suspend method
[ 0.306604] i2c-core: driver [palmas] using legacy resume method
[ 0.307613] SCSI subsystem initialized
[ 0.309709] usbcore: registered new interface driver usbfs
[ 0.309955] usbcore: registered new interface driver hub
[ 0.310221] usbcore: registered new device driver usb
[ 0.311551] omap_i2c 44e0b000.i2c: could not find pctldev for node /pinmux@44e10800/pinmux_i2c0_pins, deferring probe
[ 0.311598] platform 44e0b000.i2c: Driver omap_i2c requests probe deferral
[ 0.312100] pps_core: LinuxPPS API ver. 1 registered
[ 0.312114] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>
[ 0.312292] PTP clock support registered
[ 0.315043] omap-mailbox 480c8000.mailbox: omap mailbox rev 0x400
[ 0.316154] Advanced Linux Sound Architecture Driver Initialized.
[ 0.318293] Switched to clocksource timer1
[ 0.346029] NET: Registered protocol family 2
[ 0.347133] TCP established hash table entries: 1024 (order: 0, 4096 bytes)
[ 0.347167] TCP bind hash table entries: 1024 (order: 0, 4096 bytes)
[ 0.347192] TCP: Hash tables configured (established 1024 bind 1024)
[ 0.347293] TCP: reno registered
[ 0.347309] UDP hash table entries: 256 (order: 0, 4096 bytes)
[ 0.347335] UDP-Lite hash table entries: 256 (order: 0, 4096 bytes)
[ 0.347589] NET: Registered protocol family 1
[ 0.348068] RPC: Registered named UNIX socket transport module.
[ 0.348083] RPC: Registered udp transport module.
[ 0.348091] RPC: Registered tcp transport module.
[ 0.348100] RPC: Registered tcp NFSv4.1 backchannel transport module.
[ 0.349663] hw perfevents: enabled with ARMv7 Cortex-A8 PMU driver, 5 counters available
[ 0.353789] futex hash table entries: 256 (order: -1, 3072 bytes)
[ 0.573647] VFS: Disk quotas dquot_6.5.2
[ 0.573735] Dquot-cache hash table entries: 1024 (order 0, 4096 bytes)
[ 0.574420] NFS: Registering the id_resolver key type
[ 0.574526] Key type id_resolver registered
[ 0.574538] Key type id_legacy registered
[ 0.574590] jffs2: version 2.2. (NAND) (SUMMARY) �© 2001-2006 Red Hat, Inc.
[ 0.574825] msgmni has been set to 105
[ 0.576720] NET: Registered protocol family 38
[ 0.576775] io scheduler noop registered
[ 0.576786] io scheduler deadline registered
[ 0.576821] io scheduler cfq registered (default)
[ 0.579140] pinctrl-single 44e10800.pinmux: 142 pins at pa f9e10800 size 568
[ 0.585491] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled
[ 0.589894] omap_uart 44e09000.serial: no wakeirq for uart0
[ 0.590095] 44e09000.serial: ttyO0 at MMIO 0x44e09000 (irq = 88, base_baud = 3000000) is a OMAP UART0
[ 1.238251] console [ttyO0] enabled
[ 1.244045] omap_rng 48310000.rng: OMAP Random Number Generator ver. 20
[ 1.251652] [drm] Initialized drm 1.1.0 20060810
[ 1.271575] brd: module loaded
[ 1.282655] loop: module loaded
[ 1.286439] (hci_tty): inside hci_tty_init
[ 1.291439] (hci_tty): allocated 249, 0
[ 1.301329] mtdoops: mtd device (mtddev=name/number) must be supplied
[ 1.313220] usbcore: registered new interface driver asix
[ 1.319241] usbcore: registered new interface driver ax88179_178a
[ 1.325914] usbcore: registered new interface driver cdc_ether
[ 1.332307] usbcore: registered new interface driver smsc95xx
[ 1.338610] usbcore: registered new interface driver net1080
[ 1.344815] usbcore: registered new interface driver cdc_subset
[ 1.351258] usbcore: registered new interface driver zaurus
[ 1.357472] usbcore: registered new interface driver cdc_ncm
[ 1.364124] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
[ 1.371036] ehci-pci: EHCI PCI platform driver
[ 1.375969] ehci-omap: OMAP-EHCI Host Controller driver
[ 1.382127] usbcore: registered new interface driver cdc_wdm
[ 1.388422] usbcore: registered new interface driver usb-storage
[ 1.396192] mousedev: PS/2 mouse device common for all mice
[ 1.403777] i2c /dev entries driver
[ 1.407662] Driver for 1-wire Dallas network protocol.
[ 1.416187] omap_wdt: OMAP Watchdog Timer Rev 0x01: initial timeout 60 sec
[ 1.426136] omap_hsmmc 48060000.mmc: unable to get vmmc regulator -517
[ 1.433346] platform 48060000.mmc: Driver omap_hsmmc requests probe deferral
[ 1.442778] ledtrig-cpu: registered to indicate activity on CPUs
[ 1.449622] omap-aes 53500000.aes: OMAP AES hw accel rev: 3.2
[ 1.457275] omap-sham 53100000.sham: hw accel on OMAP rev 4.3
[ 1.465415] usbcore: registered new interface driver usbhid
[ 1.471340] usbhid: USB HID core driver
[ 1.475750] platform 44d00000.wkup_m3: Driver wkup_m3 requests probe deferral
[ 1.487767] oprofile: using arm/armv7
[ 1.492147] TCP: cubic registered
[ 1.495640] Initializing XFRM netlink socket
[ 1.500192] NET: Registered protocol family 17
[ 1.504929] NET: Registered protocol family 15
[ 1.509808] Key type dns_resolver registered
[ 1.515304] cpu cpu0: of_pm_voltdm_notifier_register: Failed to get cpu0 regulator/voltdm: -517
[ 1.524537] cpu cpu0: cpu0 clock notifier not ready, retry
[ 1.530454] platform cpufreq-cpu0.0: Driver cpufreq-cpu0 requests probe deferral
[ 1.539143] PM: could not locate rtc hwmod
[ 1.543476] ThumbEE CPU extension supported.
[ 1.548011] Registering SWP/SWPB emulation handler
[ 1.555374] lis3_reg: disabling
[ 1.558848] vbat: disabling
[ 1.561793] regulator-dummy: disabling
[ 1.570686] tps65910 0-002d: No interrupt support, no core IRQ
[ 1.581841] vrtc: 1800 mV
[ 1.585007] vrtc: supplied by vbat
[ 1.590558] vio: at 1800 mV
[ 1.593691] vio: supplied by vbat
[ 1.599211] vdd_mpu: 912 <--> 1375 mV at 1137 mV
[ 1.604266] vdd_mpu: supplied by vbat
[ 1.610133] vdd_core: 912 <--> 1150 mV at 1137 mV
[ 1.615272] vdd_core: supplied by vbat
[ 1.620778] vdd3: 5000 mV
[ 1.625144] vdig1: at 1800 mV
[ 1.628485] vdig1: supplied by vbat
[ 1.633721] vdig2: at 1800 mV
[ 1.637044] vdig2: supplied by vbat
[ 1.642298] vpll: at 1800 mV
[ 1.645517] vpll: supplied by vbat
[ 1.650699] vdac: at 1800 mV
[ 1.653916] vdac: supplied by vbat
[ 1.659071] vaux1: at 1800 mV
[ 1.662379] vaux1: supplied by vbat
[ 1.667593] vaux2: at 3300 mV
[ 1.670927] vaux2: supplied by vbat
[ 1.676165] vaux33: at 3300 mV
[ 1.679590] vaux33: supplied by vbat
[ 1.684913] vmmc: 1800 <--> 3300 mV at 3300 mV
[ 1.689801] vmmc: supplied by vbat
[ 1.694590] omap_i2c 44e0b000.i2c: bus 0 rev0.11 at 400 kHz
[ 1.738354] mmc0: mmc_rescan_try_freq: trying to init card at 400000 Hz
[ 1.746024] platform 44d00000.wkup_m3: Driver wkup_m3 requests probe deferral
[ 1.758156] platform 44d00000.wkup_m3: Driver wkup_m3 requests probe deferral
[ 1.806706] mmc0: host does not support reading read-only switch. assuming write-enable.
[ 1.817964] mmc0: new high speed SDHC card at address 1234
[ 1.824635] mmcblk0: mmc0:1234 SA08G 7.41 GiB
[ 1.829392] davinci_mdio 4a101000.mdio: davinci mdio revision 1.6
[ 1.835814] davinci_mdio 4a101000.mdio: detected phy mask fffffffe
[ 1.844622] libphy: 4a101000.mdio: probed
[ 1.848951] davinci_mdio 4a101000.mdio: phy[0]: device 4a101000.mdio:00, driver SMSC LAN8710/LAN8720
[ 1.858772] mmcblk0: p1 p2
[ 1.862479] platform 44d00000.wkup_m3: Driver wkup_m3 requests probe deferral
[ 1.872143] cpsw 4a100000.ethernet: Missing slave[0] phy_id property
[ 1.878908] cpsw 4a100000.ethernet: cpsw: platform data missing
[ 1.886521] platform 44d00000.wkup_m3: Driver wkup_m3 requests probe deferral
[ 1.899070] platform 4a100000.ethernet: omap_device_late_idle: enabled but no driver. Idling
[ 1.908209] ALSA device list:
[ 1.911395] No soundcards found.
[ 1.935527] EXT4-fs (mmcblk0p2): mounted filesystem with ordered data mode. Opts: (null)
[ 1.944201] VFS: Mounted root (ext4 filesystem) readonly on device 179:2.
[ 1.960908] devtmpfs: mounted
[ 1.964630] Freeing unused kernel memory: 264K (c07d8000 - c081a000)
INIT: version 2.88 booting
Error opening /dev/fb0: No such file or directory
Starting udev
[ 3.083908] udevd[798]: starting version 182
TR_DBG: doing a dry run
TR_DBG: done with dry run
TR_DBG: udevadm action=add
TR_DBG: udevadm settle
[ 4.076739] pruss-rproc 4a300000.pruss: creating platform devices for PRU cores
[ 4.215290] platform 44d00000.wkup_m3: Driver wkup_m3 requests probe deferral
[ 4.308387] remoteproc0: 4a334000.pru0 is available
[ 4.313625] remoteproc0: Note: remoteproc is still under development and considered experimental.
[ 4.323078] remoteproc0: THE BINARY FORMAT IS NOT YET FINALIZED, and backward compatibility isn't yet guaranteed.
[ 4.336812] platform 44d00000.wkup_m3: Driver wkup_m3 requests probe deferral
[ 4.492241] remoteproc0: Direct firmware load failed with error -2
[ 4.498945] remoteproc0: Falling back to user helper
udevd[798]: worker [821] timeout, kill it

udevd[798]: seq 784 '/devices/ocp.3/4a300000.pruss' killed

[ 34.469454] random: nonblocking pool is initialized
[ 34.554181] remoteproc0: failed to load rproc-pru0-fw
[ 34.559926] pru-rproc 4a334000.pru0: booting the PRU core manually
[ 34.566415] remoteproc0: powering up 4a334000.pru0
[ 34.579273] remoteproc0: Direct firmware load failed with error -2
[ 34.585998] remoteproc0: Falling back to user helper
[ 34.599625] remoteproc0: request_firmware failed: -2
[ 34.604953] pru-rproc 4a334000.pru0: rproc_boot failed
[ 34.611028] remoteproc0: releasing 4a334000.pru0
[ 34.616603] pru-rproc: probe of 4a334000.pru0 failed with error -2
[ 34.627895] remoteproc0: 4a338000.pru1 is available
[ 34.633377] remoteproc0: Note: remoteproc is still under development and considered experimental.
[ 34.642808] remoteproc0: THE BINARY FORMAT IS NOT YET FINALIZED, and backward compatibility isn't yet guaranteed.
[ 34.662081] remoteproc0: Direct firmware load failed with error -2
[ 34.668787] remoteproc0: Falling back to user helper
[ 34.684458] remoteproc0: failed to load rproc-pru1-fw
[ 34.690157] pru-rproc 4a338000.pru1: booting the PRU core manually
[ 34.696647] remoteproc0: powering up 4a338000.pru1
[ 34.707899] remoteproc0: Direct firmware load failed with error -2
[ 34.714756] remoteproc0: Falling back to user helper
[ 34.728185] remoteproc0: request_firmware failed: -2
[ 34.733633] pru-rproc 4a338000.pru1: rproc_boot failed
[ 34.739719] remoteproc0: releasing 4a338000.pru1
[ 34.746689] pru-rproc: probe of 4a338000.pru1 failed with error -2
udevd[798]: worker [821] terminated by signal 9 (Killed)

[ 34.910347] EXT4-fs (mmcblk0p2): re-mounted. Opts: data=ordered
Starting Bootlog daemon: bootlogd: cannot allocate pseudo tty: No such file or directory
bootlogd.
Populating dev cache

[ 240.568557] INFO: task kworker/u2:0:6 blocked for more than 120 seconds.
[ 240.575686] Not tainted 3.14.26-g03832cc-dirty #31
[ 240.581343] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[ 240.589653] kworker/u2:0 D c05a0f94 0 6 2 0x00000000
[ 240.596437] Workqueue: writeback bdi_writeback_workfn (flush-179:0)
[ 240.603104] Backtrace:
[ 240.605728] [<c05a0e18>] (__schedule) from [<c05a12cc>] (schedule+0x38/0x88)
[ 240.613206] r10:c188390c r9:c3dad440 r8:c00f3b30 r7:00000002 r6:c1882000 r5:c0834aa8
[ 240.621491] r4:c0834ec0
[ 240.624172] [<c05a1294>] (schedule) from [<c05a1388>] (io_schedule+0x6c/0x98)
[ 240.631764] [<c05a131c>] (io_schedule) from [<c00f3b40>] (sleep_on_buffer+0x10/0x18)
[ 240.639973] r6:c188386c r5:c3dffef8 r4:c1883864 r3:00000002
[ 240.646010] [<c00f3b30>] (sleep_on_buffer) from [<c05a1644>] (__wait_on_bit+0x84/0xbc)
[ 240.654421] [<c05a15c0>] (__wait_on_bit) from [<c05a16ec>] (out_of_line_wait_on_bit+0x70/0x78)
[ 240.663546] r10:c188390c r8:00000001 r7:c005d92c r6:c187c800 r5:c108c370 r4:00000002
[ 240.671918] [<c05a167c>] (out_of_line_wait_on_bit) from [<c00f3b28>] (__wait_on_buffer+0x28/0x30)
[ 240.681316] r7:c1b16a00 r6:0000000c r5:c3d48180 r4:c108c370
[ 240.687372] [<c00f3b00>] (__wait_on_buffer) from [<c0147e70>] (ext4_wait_block_bitmap+0xd4/0xe0)
[ 240.696713] [<c0147d9c>] (ext4_wait_block_bitmap) from [<c0172c0c>] (ext4_mb_init_cache+0x174/0x484)
[ 240.706388] r7:0000000c r6:c188390c r5:00000000 r4:c1b16a00
[ 240.712453] [<c0172a98>] (ext4_mb_init_cache) from [<c0173020>] (ext4_mb_init_group+0x104/0x1b0)
[ 240.721760] r10:c10751d0 r9:c3dad440 r8:00000018 r7:00000001 r6:c10729a8 r5:00001000
[ 240.730109] r4:c3dacb00
[ 240.732815] [<c0172f1c>] (ext4_mb_init_group) from [<c0173250>] (ext4_mb_good_group+0x184/0x194)
[ 240.742127] r10:c1aa51b0 r9:c1b16a00 r8:00000000 r7:000028bb r6:c13ac720 r5:c10751d0
[ 240.750478] r4:0000000c
[ 240.753186] [<c01730cc>] (ext4_mb_good_group) from [<c0174688>] (ext4_mb_regular_allocator+0x1a4/0x2dc)
[ 240.763135] r10:c1aa51b0 r9:c1b16a00 r8:0000000c r7:0000003b r6:00000001 r5:00000000
[ 240.771485] r4:c13ac720
[ 240.774191] [<c01744e4>] (ext4_mb_regular_allocator) from [<c0176dac>] (ext4_mb_new_blocks+0x3e0/0x4e8)
[ 240.784141] r10:00000000 r9:c1883a7c r8:00000000 r7:c1aa5000 r6:c1883aa0 r5:c1883b50
[ 240.792492] r4:c1b16a00
[ 240.795201] [<c01769cc>] (ext4_mb_new_blocks) from [<c017c608>] (ext4_alloc_branch+0x22c/0x34c)
[ 240.804417] r10:00000000 r9:c1883ad8 r8:00060000 r7:c1883a78 r6:c1883b50 r5:c1883b50
[ 240.812767] r4:00000000
[ 240.815474] [<c017c3dc>] (ext4_alloc_branch) from [<c017c8e4>] (ext4_ind_map_blocks+0x1bc/0x5b8)
[ 240.824782] r10:c1407e38 r9:00000001 r8:00000000 r7:c1883d10 r6:c1883b50 r5:c17473a8
[ 240.833132] r4:c1883b50
[ 240.835839] [<c017c728>] (ext4_ind_map_blocks) from [<c014fb8c>] (ext4_map_blocks+0x160/0x4a8)
[ 240.844963] r10:00000000 r9:c17473a8 r8:00000025 r7:c1407e38 r6:c1883d10 r5:00000004
[ 240.853311] r4:c1407e28
[ 240.856018] [<c014fa2c>] (ext4_map_blocks) from [<c0154094>] (mpage_map_and_submit_extent+0xa0/0x720)
[ 240.865782] r10:00000000 r9:00000001 r8:c1883cf8 r7:c1883db0 r6:c1aa5000 r5:c1407e38
[ 240.874131] r4:00000000
[ 240.876835] [<c0153ff4>] (mpage_map_and_submit_extent) from [<c0154a78>] (ext4_writepages+0x364/0x528)
[ 240.886691] r10:00000000 r9:00000001 r8:c17473a8 r7:c1883db0 r6:c1aa5000 r5:00000000
[ 240.895037] r4:c1407e38
[ 240.897756] [<c0154714>] (ext4_writepages) from [<c009dc54>] (do_writepages+0x2c/0x40)
[ 240.906146] r10:c1b0ccf8 r9:00000044 r8:c1b16a00 r7:c1407efc r6:c1883db0 r5:c1407e38
[ 240.914496] r4:c1407e38
[ 240.917218] [<c009dc28>] (do_writepages) from [<c00ed504>] (__writeback_single_inode+0x30/0xf8)
[ 240.926456] [<c00ed4d4>] (__writeback_single_inode) from [<c00edd98>] (writeback_sb_inodes+0x188/0x324)
[ 240.936404] r7:c1e05f80 r6:c1b0ccb0 r5:c1407e38 r4:c1407ebc
[ 240.942467] [<c00edc10>] (writeback_sb_inodes) from [<c00edfb8>] (__writeback_inodes_wb+0x84/0xc0)
[ 240.951958] r10:c0833cf8 r9:c1087ebc r8:c1b0ccf8 r7:c1e05f80 r6:00000002 r5:c1b16a00
[ 240.960305] r4:c1b0ccb0
[ 240.963007] [<c00edf34>] (__writeback_inodes_wb) from [<c00ee180>] (wb_writeback+0x18c/0x198)
[ 240.972038] r10:c0833cf8 r9:c1b0ccb0 r8:ffff9987 r7:c1b0ccf8 r6:c1b0ccb0 r5:00000000
[ 240.980385] r4:c1e05f80
[ 240.983088] [<c00edff4>] (wb_writeback) from [<c00ee5ac>] (bdi_writeback_workfn+0xe8/0x344)
[ 240.991935] r10:00000000 r9:c1e05f80 r8:c00ee558 r7:c1b0cd08 r6:c1b0ccb0 r5:c1b0cc30
[ 241.000283] r4:c1882000
[ 241.002999] [<c00ee4c4>] (bdi_writeback_workfn) from [<c004ae28>] (process_one_work+0xfc/0x338)
[ 241.012222] r10:00000000 r9:c1882000 r8:00000000 r7:c1881d00 r6:c186da00 r5:c1b0ccbc
[ 241.020574] r4:c185fc00
[ 241.023280] [<c004ad2c>] (process_one_work) from [<c004b1bc>] (worker_thread+0x11c/0x364)
[ 241.031945] r10:c0869e5f r9:c186da00 r8:00000001 r7:c1882000 r6:c185fc18 r5:c186da10
[ 241.040293] r4:c185fc00
[ 241.043003] [<c004b0a0>] (worker_thread) from [<c0050d30>] (kthread+0xc4/0xe0)
[ 241.050660] r10:00000000 r9:00000000 r8:00000000 r7:c004b0a0 r6:c185fc00 r5:c1874c00
[ 241.059005] r4:00000000
[ 241.061720] [<c0050c6c>] (kthread) from [<c000e878>] (ret_from_fork+0x14/0x3c)
[ 241.069377] r7:00000000 r6:00000000 r5:c0050c6c r4:c1874c00
[ 241.075419] INFO: task jbd2/mmcblk0p2-:767 blocked for more than 120 seconds.
[ 241.082989] Not tainted 3.14.26-g03832cc-dirty #31
[ 241.088627] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[ 241.096899] jbd2/mmcblk0p2- D c05a0f94 0 767 2 0x00000000
[ 241.103659] Backtrace:
[ 241.106269] [<c05a0e18>] (__schedule) from [<c05a12cc>] (schedule+0x38/0x88)
[ 241.113744] r10:c1b29ec0 r9:c1b29ec0 r8:c00f3b30 r7:00000002 r6:c1b28000 r5:c0834aa8
[ 241.122094] r4:c0834ec0
[ 241.124794] [<c05a1294>] (schedule) from [<c05a1388>] (io_schedule+0x6c/0x98)
[ 241.132382] [<c05a131c>] (io_schedule) from [<c00f3b40>] (sleep_on_buffer+0x10/0x18)
[ 241.140523] r6:c1b29db4 r5:c3dffe58 r4:c1b29dac r3:00000002
[ 241.146511] [<c00f3b30>] (sleep_on_buffer) from [<c05a1644>] (__wait_on_bit+0x84/0xbc)
[ 241.154920] [<c05a15c0>] (__wait_on_bit) from [<c05a16ec>] (out_of_line_wait_on_bit+0x70/0x78)
[ 241.164044] r10:c1b29ec0 r8:c1b29ec8 r7:c005d92c r6:c1b01800 r5:c102c9f0 r4:00000002
[ 241.172416] [<c05a167c>] (out_of_line_wait_on_bit) from [<c00f3b28>] (__wait_on_buffer+0x28/0x30)
[ 241.181815] r7:c0f92000 r6:00000000 r5:c1b14800 r4:c102c9f0
[ 241.187874] [<c00f3b00>] (__wait_on_buffer) from [<c0192984>] (jbd2_write_superblock+0x110/0x16c)
[ 241.197304] [<c0192874>] (jbd2_write_superblock) from [<c0193134>] (jbd2_journal_update_sb_log_tail+0x38/0x6c)
[ 241.207897] r7:c1b25800 r6:00000000 r5:c0f92000 r4:c1b14800
[ 241.213966] [<c01930fc>] (jbd2_journal_update_sb_log_tail) from [<c018ee04>] (jbd2_journal_commit_transaction+0x125c/0x13a8)
[ 241.225841] r5:c1b14800 r4:c1b14858
[ 241.229686] [<c018dba8>] (jbd2_journal_commit_transaction) from [<c0191910>] (kjournald2+0xa0/0x1fc)
[ 241.239360] r10:c0833cf8 r9:00000000 r8:c087f028 r7:c1b14838 r6:c1b28000 r5:c1b149fc
[ 241.247684] r4:c1b14800
[ 241.250423] [<c0191870>] (kjournald2) from [<c0050d30>] (kthread+0xc4/0xe0)
[ 241.257780] r10:00000000 r9:00000000 r8:00000000 r7:c0191870 r6:c1b14800 r5:c1b18b00
[ 241.266130] r4:00000000
[ 241.268860] [<c0050c6c>] (kthread) from [<c000e878>] (ret_from_fork+0x14/0x3c)
[ 241.276491] r7:00000000 r6:00000000 r5:c0050c6c r4:c1b18b00
[ 241.282543] INFO: task udevd:798 blocked for more than 120 seconds.
[ 241.289193] Not tainted 3.14.26-g03832cc-dirty #31
[ 241.294808] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[ 241.303101] udevd D c05a0f94 0 798 1 0x00000000
[ 241.309856] Backtrace:
[ 241.312469] [<c05a0e18>] (__schedule) from [<c05a12cc>] (schedule+0x38/0x88)
[ 241.319942] r10:c108c330 r9:00000001 r8:c00f3b30 r7:00000002 r6:c1b7a000 r5:c0834aa8
[ 241.328264] r4:c0834ec0
[ 241.330989] [<c05a1294>] (schedule) from [<c05a1388>] (io_schedule+0x6c/0x98)
[ 241.338572] [<c05a131c>] (io_schedule) from [<c00f3b40>] (sleep_on_buffer+0x10/0x18)
[ 241.346755] r6:c1b7bc7c r5:c3dffd88 r4:c1b7bc74 r3:00000002
[ 241.352817] [<c00f3b30>] (sleep_on_buffer) from [<c05a1644>] (__wait_on_bit+0x84/0xbc)
[ 241.361224] [<c05a15c0>] (__wait_on_bit) from [<c05a16ec>] (out_of_line_wait_on_bit+0x70/0x78)
[ 241.370348] r10:c108c330 r8:c10902d0 r7:c005d92c r6:c1b69800 r5:c108c330 r4:00000002
[ 241.378719] [<c05a167c>] (out_of_line_wait_on_bit) from [<c00f3b28>] (__wait_on_buffer+0x28/0x30)
[ 241.388094] r7:00000001 r6:00000001 r5:00000000 r4:00000001
[ 241.394161] [<c00f3b00>] (__wait_on_buffer) from [<c01595a4>] (ext4_find_entry+0x35c/0x5e8)
[ 241.403030] [<c0159248>] (ext4_find_entry) from [<c0159870>] (ext4_lookup+0x40/0x160)
[ 241.411329] r10:c1b7bf40 r9:c1b7a000 r8:c1e8f010 r7:c1b7be60 r6:c108f7e8 r5:c10ad3a8
[ 241.419676] r4:c10902d0
[ 241.422391] [<c0159830>] (ext4_lookup) from [<c00d3c4c>] (lookup_real+0x28/0x58)
[ 241.430229] r6:c108f7e8 r5:c108f7e8 r4:c10ad3a8
[ 241.435159] [<c00d3c24>] (lookup_real) from [<c00d4a34>] (__lookup_hash+0x3c/0x4c)
[ 241.443182] r4:00000001 r3:c1b7bdaf
[ 241.447001] [<c00d49f8>] (__lookup_hash) from [<c00d4a84>] (lookup_slow+0x40/0xa0)
[ 241.455023] r5:c1b7be00 r4:c1b7be60
[ 241.458866] [<c00d4a44>] (lookup_slow) from [<c00d69e0>] (path_lookupat+0x6c0/0x6ec)
[ 241.467047] r7:c1b7be60 r6:c1b7a000 r5:00000000 r4:00000051
[ 241.473112] [<c00d6320>] (path_lookupat) from [<c00d6a34>] (filename_lookup+0x28/0x68)
[ 241.481501] r10:c1b7bf40 r9:c1b7a000 r8:ffffff9c r7:ffffff9c r6:c1b7be60 r5:c1e8f000
[ 241.489847] r4:00000001
[ 241.492557] [<c00d6a0c>] (filename_lookup) from [<c00d91e4>] (user_path_at_empty+0x5c/0x84)
[ 241.501403] r7:c1b7bf00 r6:c1b7be60 r5:00000001 r4:c1e8f000
[ 241.507445] [<c00d9188>] (user_path_at_empty) from [<c00d9228>] (user_path_at+0x1c/0x24)
[ 241.516017] r8:000330b8 r7:ffffff9c r6:00000001 r5:c1b7bf00 r4:bec7f170
[ 241.523191] [<c00d920c>] (user_path_at) from [<c00cfaec>] (vfs_fstatat+0x50/0x9c)
[ 241.531139] [<c00cfa9c>] (vfs_fstatat) from [<c00cfb7c>] (vfs_stat+0x20/0x24)
[ 241.538704] r10:00000000 r8:c000e964 r7:000000c3 r6:00000000 r5:ffffe4a0 r4:bec7f170
[ 241.547047] [<c00cfb5c>] (vfs_stat) from [<c00cfdb0>] (SyS_stat64+0x1c/0x38)
[ 241.554540] [<c00cfd94>] (SyS_stat64) from [<c000e7e0>] (ret_fast_syscall+0x0/0x30)
[ 241.562654] r4:00000000
[ 241.565378] INFO: task sync:1430 blocked for more than 120 seconds.
[ 241.571978] Not tainted 3.14.26-g03832cc-dirty #31
[ 241.577545] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[ 241.585772] sync D c05a0f94 0 1430 1092 0x00000000
[ 241.592476] Backtrace:
[ 241.595066] [<c05a0e18>] (__schedule) from [<c05a12cc>] (schedule+0x38/0x88)
[ 241.602538] r10:c1b37f00 r9:c1db0800 r8:00000000 r7:00000000 r6:7fffffff r5:c1b36000
[ 241.610820] r4:7fffffff
[ 241.613500] [<c05a1294>] (schedule) from [<c05a0908>] (schedule_timeout+0xf8/0x148)
[ 241.621632] [<c05a0810>] (schedule_timeout) from [<c05a1998>] (wait_for_common+0xb4/0x140)
[ 241.630387] r10:c1b37f00 r8:00000000 r7:00000000 r6:00000002 r5:c1b36000 r4:7fffffff
[ 241.638753] [<c05a18e4>] (wait_for_common) from [<c05a1a3c>] (wait_for_completion+0x18/0x1c)
[ 241.647668] r10:00000000 r9:c1b36000 r8:c00f1bec r7:c0837be4 r6:c1b16a00 r5:c1b16a40
[ 241.656015] r4:c1b37f00
[ 241.658751] [<c05a1a24>] (wait_for_completion) from [<c00ed808>] (sync_inodes_sb+0x9c/0x148)
[ 241.667689] [<c00ed76c>] (sync_inodes_sb) from [<c00f1c08>] (sync_inodes_one_sb+0x1c/0x20)
[ 241.676445] r7:c0837be4 r6:c1b16a40 r5:c1a2f000 r4:c1b16a00
[ 241.682508] [<c00f1bec>] (sync_inodes_one_sb) from [<c00ce530>] (iterate_supers+0x9c/0xb8)
[ 241.691281] [<c00ce494>] (iterate_supers) from [<c00f1d4c>] (sys_sync+0x38/0xa0)
[ 241.699119] r10:00000000 r8:c000e964 r7:00000024 r6:00011631 r5:0000008d r4:00000000
[ 241.707463] [<c00f1d14>] (sys_sync) from [<c000e7e0>] (ret_fast_syscall+0x0/0x30)
[ 241.715393] r4:bec10e24
[ 361.708816] INFO: task kworker/u2:0:6 blocked for more than 120 seconds.
[ 361.715869] Not tainted 3.14.26-g03832cc-dirty #31
[ 361.721484] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[ 361.729721] kworker/u2:0 D c05a0f94 0 6 2 0x00000000
[ 361.736435] Workqueue: writeback bdi_writeback_workfn (flush-179:0)
[ 361.743044] Backtrace:
[ 361.745640] [<c05a0e18>] (__schedule) from [<c05a12cc>] (schedule+0x38/0x88)
[ 361.753116] r10:c188390c r9:c3dad440 r8:c00f3b30 r7:00000002 r6:c1882000 r5:c0834aa8
[ 361.761399] r4:c0834ec0
[ 361.764078] [<c05a1294>] (schedule) from [<c05a1388>] (io_schedule+0x6c/0x98)
[ 361.771671] [<c05a131c>] (io_schedule) from [<c00f3b40>] (sleep_on_buffer+0x10/0x18)
[ 361.779879] r6:c188386c r5:c3dffef8 r4:c1883864 r3:00000002
[ 361.785917] [<c00f3b30>] (sleep_on_buffer) from [<c05a1644>] (__wait_on_bit+0x84/0xbc)
[ 361.794327] [<c05a15c0>] (__wait_on_bit) from [<c05a16ec>] (out_of_line_wait_on_bit+0x70/0x78)
[ 361.803453] r10:c188390c r8:00000001 r7:c005d92c r6:c187c800 r5:c108c370 r4:00000002
[ 361.811823] [<c05a167c>] (out_of_line_wait_on_bit) from [<c00f3b28>] (__wait_on_buffer+0x28/0x30)
[ 361.821221] r7:c1b16a00 r6:0000000c r5:c3d48180 r4:c108c370
[ 361.827272] [<c00f3b00>] (__wait_on_buffer) from [<c0147e70>] (ext4_wait_block_bitmap+0xd4/0xe0)
[ 361.836605] [<c0147d9c>] (ext4_wait_block_bitmap) from [<c0172c0c>] (ext4_mb_init_cache+0x174/0x484)
[ 361.846288] r7:0000000c r6:c188390c r5:00000000 r4:c1b16a00
[ 361.852360] [<c0172a98>] (ext4_mb_init_cache) from [<c0173020>] (ext4_mb_init_group+0x104/0x1b0)
[ 361.861667] r10:c10751d0 r9:c3dad440 r8:00000018 r7:00000001 r6:c10729a8 r5:00001000
[ 361.870015] r4:c3dacb00
[ 361.872721] [<c0172f1c>] (ext4_mb_init_group) from [<c0173250>] (ext4_mb_good_group+0x184/0x194)
[ 361.882028] r10:c1aa51b0 r9:c1b16a00 r8:00000000 r7:000028bb r6:c13ac720 r5:c10751d0
[ 361.890377] r4:0000000c
[ 361.893083] [<c01730cc>] (ext4_mb_good_group) from [<c0174688>] (ext4_mb_regular_allocator+0x1a4/0x2dc)
[ 361.903031] r10:c1aa51b0 r9:c1b16a00 r8:0000000c r7:0000003b r6:00000001 r5:00000000
[ 361.911378] r4:c13ac720
[ 361.914085] [<c01744e4>] (ext4_mb_regular_allocator) from [<c0176dac>] (ext4_mb_new_blocks+0x3e0/0x4e8)
[ 361.924033] r10:00000000 r9:c1883a7c r8:00000000 r7:c1aa5000 r6:c1883aa0 r5:c1883b50
[ 361.932381] r4:c1b16a00
[ 361.935089] [<c01769cc>] (ext4_mb_new_blocks) from [<c017c608>] (ext4_alloc_branch+0x22c/0x34c)
[ 361.944302] r10:00000000 r9:c1883ad8 r8:00060000 r7:c1883a78 r6:c1883b50 r5:c1883b50
[ 361.952652] r4:00000000
[ 361.955359] [<c017c3dc>] (ext4_alloc_branch) from [<c017c8e4>] (ext4_ind_map_blocks+0x1bc/0x5b8)
[ 361.964665] r10:c1407e38 r9:00000001 r8:00000000 r7:c1883d10 r6:c1883b50 r5:c17473a8
[ 361.973012] r4:c1883b50
[ 361.975717] [<c017c728>] (ext4_ind_map_blocks) from [<c014fb8c>] (ext4_map_blocks+0x160/0x4a8)
[ 361.984840] r10:00000000 r9:c17473a8 r8:00000025 r7:c1407e38 r6:c1883d10 r5:00000004
[ 361.993121] r4:c1407e28
[ 361.995804] [<c014fa2c>] (ext4_map_blocks) from [<c0154094>] (mpage_map_and_submit_extent+0xa0/0x720)
[ 362.005568] r10:00000000 r9:00000001 r8:c1883cf8 r7:c1883db0 r6:c1aa5000 r5:c1407e38
[ 362.013928] r4:00000000
[ 362.016635] [<c0153ff4>] (mpage_map_and_submit_extent) from [<c0154a78>] (ext4_writepages+0x364/0x528)
[ 362.026495] r10:00000000 r9:00000001 r8:c17473a8 r7:c1883db0 r6:c1aa5000 r5:00000000
[ 362.034843] r4:c1407e38
[ 362.037556] [<c0154714>] (ext4_writepages) from [<c009dc54>] (do_writepages+0x2c/0x40)
[ 362.045947] r10:c1b0ccf8 r9:00000044 r8:c1b16a00 r7:c1407efc r6:c1883db0 r5:c1407e38
[ 362.054294] r4:c1407e38
[ 362.057011] [<c009dc28>] (do_writepages) from [<c00ed504>] (__writeback_single_inode+0x30/0xf8)
[ 362.066249] [<c00ed4d4>] (__writeback_single_inode) from [<c00edd98>] (writeback_sb_inodes+0x188/0x324)
[ 362.076198] r7:c1e05f80 r6:c1b0ccb0 r5:c1407e38 r4:c1407ebc
[ 362.082258] [<c00edc10>] (writeback_sb_inodes) from [<c00edfb8>] (__writeback_inodes_wb+0x84/0xc0)
[ 362.091749] r10:c0833cf8 r9:c1087ebc r8:c1b0ccf8 r7:c1e05f80 r6:00000002 r5:c1b16a00
[ 362.100097] r4:c1b0ccb0
[ 362.102799] [<c00edf34>] (__writeback_inodes_wb) from [<c00ee180>] (wb_writeback+0x18c/0x198)
[ 362.111830] r10:c0833cf8 r9:c1b0ccb0 r8:ffff9987 r7:c1b0ccf8 r6:c1b0ccb0 r5:00000000
[ 362.120178] r4:c1e05f80
[ 362.122881] [<c00edff4>] (wb_writeback) from [<c00ee5ac>] (bdi_writeback_workfn+0xe8/0x344)
[ 362.131727] r10:00000000 r9:c1e05f80 r8:c00ee558 r7:c1b0cd08 r6:c1b0ccb0 r5:c1b0cc30
[ 362.140074] r4:c1882000
[ 362.142781] [<c00ee4c4>] (bdi_writeback_workfn) from [<c004ae28>] (process_one_work+0xfc/0x338)
[ 362.151996] r10:00000000 r9:c1882000 r8:00000000 r7:c1881d00 r6:c186da00 r5:c1b0ccbc
[ 362.160344] r4:c185fc00
[ 362.163049] [<c004ad2c>] (process_one_work) from [<c004b1bc>] (worker_thread+0x11c/0x364)
[ 362.171714] r10:c0869e5f r9:c186da00 r8:00000001 r7:c1882000 r6:c185fc18 r5:c186da10
[ 362.180063] r4:c185fc00
[ 362.182768] [<c004b0a0>] (worker_thread) from [<c0050d30>] (kthread+0xc4/0xe0)
[ 362.190425] r10:00000000 r9:00000000 r8:00000000 r7:c004b0a0 r6:c185fc00 r5:c1874c00
[ 362.198772] r4:00000000
[ 362.201483] [<c0050c6c>] (kthread) from [<c000e878>] (ret_from_fork+0x14/0x3c)
[ 362.209140] r7:00000000 r6:00000000 r5:c0050c6c r4:c1874c00
[ 362.215186] INFO: task jbd2/mmcblk0p2-:767 blocked for more than 120 seconds.
[ 362.222693] Not tainted 3.14.26-g03832cc-dirty #31
[ 362.228260] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[ 362.236487] jbd2/mmcblk0p2- D c05a0f94 0 767 2 0x00000000
[ 362.243190] Backtrace:
[ 362.245779] [<c05a0e18>] (__schedule) from [<c05a12cc>] (schedule+0x38/0x88)
[ 362.253253] r10:c1b29ec0 r9:c1b29ec0 r8:c00f3b30 r7:00000002 r6:c1b28000 r5:c0834aa8
[ 362.261603] r4:c0834ec0
[ 362.264303] [<c05a1294>] (schedule) from [<c05a1388>] (io_schedule+0x6c/0x98)
[ 362.271890] [<c05a131c>] (io_schedule) from [<c00f3b40>] (sleep_on_buffer+0x10/0x18)
[ 362.280096] r6:c1b29db4 r5:c3dffe58 r4:c1b29dac r3:00000002
[ 362.286135] [<c00f3b30>] (sleep_on_buffer) from [<c05a1644>] (__wait_on_bit+0x84/0xbc)
[ 362.294543] [<c05a15c0>] (__wait_on_bit) from [<c05a16ec>] (out_of_line_wait_on_bit+0x70/0x78)
[ 362.303666] r10:c1b29ec0 r8:c1b29ec8 r7:c005d92c r6:c1b01800 r5:c102c9f0 r4:00000002
[ 362.312036] [<c05a167c>] (out_of_line_wait_on_bit) from [<c00f3b28>] (__wait_on_buffer+0x28/0x30)
[ 362.321434] r7:c0f92000 r6:00000000 r5:c1b14800 r4:c102c9f0
[ 362.327484] [<c00f3b00>] (__wait_on_buffer) from [<c0192984>] (jbd2_write_superblock+0x110/0x16c)
[ 362.336915] [<c0192874>] (jbd2_write_superblock) from [<c0193134>] (jbd2_journal_update_sb_log_tail+0x38/0x6c)
[ 362.347507] r7:c1b25800 r6:00000000 r5:c0f92000 r4:c1b14800
[ 362.353577] [<c01930fc>] (jbd2_journal_update_sb_log_tail) from [<c018ee04>] (jbd2_journal_commit_transaction+0x125c/0x13a8)
[ 362.365451] r5:c1b14800 r4:c1b14858
[ 362.369294] [<c018dba8>] (jbd2_journal_commit_transaction) from [<c0191910>] (kjournald2+0xa0/0x1fc)
[ 362.378967] r10:c0833cf8 r9:00000000 r8:c087f028 r7:c1b14838 r6:c1b28000 r5:c1b149fc
[ 362.387292] r4:c1b14800
[ 362.390021] [<c0191870>] (kjournald2) from [<c0050d30>] (kthread+0xc4/0xe0)
[ 362.397378] r10:00000000 r9:00000000 r8:00000000 r7:c0191870 r6:c1b14800 r5:c1b18b00
[ 362.405727] r4:00000000
[ 362.408459] [<c0050c6c>] (kthread) from [<c000e878>] (ret_from_fork+0x14/0x3c)
[ 362.416092] r7:00000000 r6:00000000 r5:c0050c6c r4:c1b18b00
[ 362.422144] INFO: task udevd:798 blocked for more than 120 seconds.
[ 362.428795] Not tainted 3.14.26-g03832cc-dirty #31
[ 362.434409] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[ 362.442704] udevd D c05a0f94 0 798 1 0x00000000
[ 362.449461] Backtrace:
[ 362.452074] [<c05a0e18>] (__schedule) from [<c05a12cc>] (schedule+0x38/0x88)
[ 362.459549] r10:c108c330 r9:00000001 r8:c00f3b30 r7:00000002 r6:c1b7a000 r5:c0834aa8
[ 362.467874] r4:c0834ec0
[ 362.470600] [<c05a1294>] (schedule) from [<c05a1388>] (io_schedule+0x6c/0x98)
[ 362.478160] [<c05a131c>] (io_schedule) from [<c00f3b40>] (sleep_on_buffer+0x10/0x18)
[ 362.486366] r6:c1b7bc7c r5:c3dffd88 r4:c1b7bc74 r3:00000002
[ 362.492427] [<c00f3b30>] (sleep_on_buffer) from [<c05a1644>] (__wait_on_bit+0x84/0xbc)
[ 362.500836] [<c05a15c0>] (__wait_on_bit) from [<c05a16ec>] (out_of_line_wait_on_bit+0x70/0x78)
[ 362.509959] r10:c108c330 r8:c10902d0 r7:c005d92c r6:c1b69800 r5:c108c330 r4:00000002
[ 362.518328] [<c05a167c>] (out_of_line_wait_on_bit) from [<c00f3b28>] (__wait_on_buffer+0x28/0x30)
[ 362.527702] r7:00000001 r6:00000001 r5:00000000 r4:00000001
[ 362.533767] [<c00f3b00>] (__wait_on_buffer) from [<c01595a4>] (ext4_find_entry+0x35c/0x5e8)
[ 362.542635] [<c0159248>] (ext4_find_entry) from [<c0159870>] (ext4_lookup+0x40/0x160)
[ 362.550934] r10:c1b7bf40 r9:c1b7a000 r8:c1e8f010 r7:c1b7be60 r6:c108f7e8 r5:c10ad3a8
[ 362.559280] r4:c10902d0
[ 362.561993] [<c0159830>] (ext4_lookup) from [<c00d3c4c>] (lookup_real+0x28/0x58)
[ 362.569832] r6:c108f7e8 r5:c108f7e8 r4:c10ad3a8
[ 362.574761] [<c00d3c24>] (lookup_real) from [<c00d4a34>] (__lookup_hash+0x3c/0x4c)
[ 362.582784] r4:00000001 r3:c1b7bdaf
[ 362.586602] [<c00d49f8>] (__lookup_hash) from [<c00d4a84>] (lookup_slow+0x40/0xa0)
[ 362.594625] r5:c1b7be00 r4:c1b7be60
[ 362.598468] [<c00d4a44>] (lookup_slow) from [<c00d69e0>] (path_lookupat+0x6c0/0x6ec)
[ 362.606650] r7:c1b7be60 r6:c1b7a000 r5:00000000 r4:00000051
[ 362.612715] [<c00d6320>] (path_lookupat) from [<c00d6a34>] (filename_lookup+0x28/0x68)
[ 362.621104] r10:c1b7bf40 r9:c1b7a000 r8:ffffff9c r7:ffffff9c r6:c1b7be60 r5:c1e8f000
[ 362.629450] r4:00000001
[ 362.632161] [<c00d6a0c>] (filename_lookup) from [<c00d91e4>] (user_path_at_empty+0x5c/0x84)
[ 362.641010] r7:c1b7bf00 r6:c1b7be60 r5:00000001 r4:c1e8f000
[ 362.647051] [<c00d9188>] (user_path_at_empty) from [<c00d9228>] (user_path_at+0x1c/0x24)
[ 362.655623] r8:000330b8 r7:ffffff9c r6:00000001 r5:c1b7bf00 r4:bec7f170
[ 362.662798] [<c00d920c>] (user_path_at) from [<c00cfaec>] (vfs_fstatat+0x50/0x9c)
[ 362.670747] [<c00cfa9c>] (vfs_fstatat) from [<c00cfb7c>] (vfs_stat+0x20/0x24)
[ 362.678312] r10:00000000 r8:c000e964 r7:000000c3 r6:00000000 r5:ffffe4a0 r4:bec7f170
[ 362.686654] [<c00cfb5c>] (vfs_stat) from [<c00cfdb0>] (SyS_stat64+0x1c/0x38)
[ 362.694148] [<c00cfd94>] (SyS_stat64) from [<c000e7e0>] (ret_fast_syscall+0x0/0x30)
[ 362.702262] r4:00000000
[ 362.704986] INFO: task sync:1430 blocked for more than 120 seconds.
[ 362.711583] Not tainted 3.14.26-g03832cc-dirty #31
[ 362.717152] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[ 362.725377] sync D c05a0f94 0 1430 1092 0x00000000
[ 362.732081] Backtrace:
[ 362.734669] [<c05a0e18>] (__schedule) from [<c05a12cc>] (schedule+0x38/0x88)
[ 362.742144] r10:c1b37f00 r9:c1db0800 r8:00000000 r7:00000000 r6:7fffffff r5:c1b36000
[ 362.750493] r4:7fffffff
[ 362.753194] [<c05a1294>] (schedule) from [<c05a0908>] (schedule_timeout+0xf8/0x148)
[ 362.761327] [<c05a0810>] (schedule_timeout) from [<c05a1998>] (wait_for_common+0xb4/0x140)
[ 362.770085] r10:c1b37f00 r8:00000000 r7:00000000 r6:00000002 r5:c1b36000 r4:7fffffff
[ 362.778449] [<c05a18e4>] (wait_for_common) from [<c05a1a3c>] (wait_for_completion+0x18/0x1c)
[ 362.787366] r10:00000000 r9:c1b36000 r8:c00f1bec r7:c0837be4 r6:c1b16a00 r5:c1b16a40
[ 362.795715] r4:c1b37f00
[ 362.798446] [<c05a1a24>] (wait_for_completion) from [<c00ed808>] (sync_inodes_sb+0x9c/0x148)
[ 362.807386] [<c00ed76c>] (sync_inodes_sb) from [<c00f1c08>] (sync_inodes_one_sb+0x1c/0x20)
[ 362.816143] r7:c0837be4 r6:c1b16a40 r5:c1a2f000 r4:c1b16a00
[ 362.822206] [<c00f1bec>] (sync_inodes_one_sb) from [<c00ce530>] (iterate_supers+0x9c/0xb8)
[ 362.830977] [<c00ce494>] (iterate_supers) from [<c00f1d4c>] (sys_sync+0x38/0xa0)
[ 362.838816] r10:00000000 r8:c000e964 r7:00000024 r6:00011631 r5:0000008d r4:00000000
[ 362.847161] [<c00f1d14>] (sys_sync) from [<c000e7e0>] (ret_fast_syscall+0x0/0x30)
[ 362.855092] r4:bec10e24

Hi Tom,

Have you considered the following error messages:

Tom Riddle83 said:

[ 0.311551] omap_i2c 44e0b000.i2c: could not find pctldev for node /pinmux@44e10800/pinmux_i2c0_pins, deferring probe

[ 0.311598] platform 44e0b000.i2c: Driver omap_i2c requests probe deferral

 

Have in mind that i2c0 is used to connect the SoC & external PMIC. So the incorrect i2c0 initialization could lead to the errors bellow: 

Tom Riddle83 said:

[ 1.426136] omap_hsmmc 48060000.mmc: unable to get vmmc regulator -517
[ 1.433346] platform 48060000.mmc: Driver omap_hsmmc requests probe deferral

Tom Riddle83 said:

[ 1.515304] cpu cpu0: of_pm_voltdm_notifier_register: Failed to get cpu0 regulator/voltdm: -517
[ 1.524537] cpu cpu0: cpu0 clock notifier not ready, retry
[ 1.530454] platform cpufreq-cpu0.0: Driver cpufreq-cpu0 requests probe deferral
[ 1.539143] PM: could not locate rtc hwmod

Do you have the correct i2c0 settings in your dts file:   

i2c0_pins: pinmux_i2c0_pins {
                              pinctrl-single,pins = <
                                           0x188 (PIN_INPUT_PULLUP | MUX_MODE0) /* i2c0_sda.i2c0_sda */
                                           0x18c (PIN_INPUT_PULLUP | MUX_MODE0) /* i2c0_scl.i2c0_scl */
                               >;
};

&i2c0 {
            pinctrl-names = "default";
            pinctrl-0 = <&i2c0_pins>;

            status = "okay";
            clock-frequency = <400000>;

            tps: tps@2d {
                            reg = <0x2d>;
            };
};

And corresponding configuration in am335x.dtsi file:   

i2c0: i2c@44e0b000 {
                       compatible = "ti,omap4-i2c";
                       #address-cells = <1>;
                       #size-cells = <0>;
                       ti,hwmods = "i2c1";
                       reg = <0x44e0b000 0x1000>;
                       interrupts = <70>;
                       status = "disabled";
};

Best Regards, 
Yordan

Hi Yordan,

Thanks for the suggestion, any input is much appreciated. Our I2C configuration does indeed match what you have listed. We also

#include "tps65910.dtsi"

BTW, we have a starter kit as a reference and it's kernel boot produces the same message. With our custom board U-boot sees the PMIC and executes the proper init and we can manipulate regs etc. So I am curious as to why it does complain about that on kernel boot. Anything else I can check?

In a follow-on to the PRU firmware load issue, we did configure the kernel to have it builtin, it attempts to load earlier in the boot process with the same loading failure results (see below).

The only reference in the DT is in am33xx.dtsi which we include in our DTB

pruss: pruss@4a300000

I've also tried to create a minimal DT ie: mem, uart, mmc1, tps, wkup_m3, and that makes no difference which leads me to be believe it is something very basic in the configuration.

Any additional ideas are very welcome.

Thanks, Tom

==========================================
Starting kernel ...

[ 0.000000] Booting Linux on physical CPU 0x0
[ 0.000000] Linux version 3.14.26-g03832cc-dirty (triddle@triddle-ThinkPad-T430) (gcc version 4.7.3 20130226 (prerelease) (crosstool-NG linaro-1.13.1-4.5
[ 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] Machine model: TI AM335x EVM
[ 0.000000] cma: CMA: reserved 24 MiB at 82000000
[ 0.000000] Memory policy: Data cache writeback
[ 0.000000] CPU: All CPU(s) started in SVC mode.
[ 0.000000] AM335X ES2.1 (neon )
[ 0.000000] Built 1 zonelists in Zone order, mobility grouping on. Total pages: 15744
[ 0.000000] Kernel command line: console=ttyO0,115200n8 root=/dev/mmcblk0p2 rw rootfstype=ext4 rootwait
[ 0.000000] PID hash table entries: 256 (order: -2, 1024 bytes)
[ 0.000000] Dentry cache hash table entries: 8192 (order: 3, 32768 bytes)
[ 0.000000] Inode-cache hash table entries: 4096 (order: 2, 16384 bytes)
[ 0.000000] Memory: 29288K/63488K available (5779K kernel code, 313K rwdata, 2240K rodata, 267K init, 231K bss, 34200K reserved, 0K highmem)
[ 0.000000] Virtual kernel memory layout:
[ 0.000000] vector : 0xffff0000 - 0xffff1000 ( 4 kB)
[ 0.000000] fixmap : 0xfff00000 - 0xfffe0000 ( 896 kB)
[ 0.000000] vmalloc : 0xc4800000 - 0xff000000 ( 936 MB)
[ 0.000000] lowmem : 0xc0000000 - 0xc4000000 ( 64 MB)
[ 0.000000] pkmap : 0xbfe00000 - 0xc0000000 ( 2 MB)
[ 0.000000] modules : 0xbf000000 - 0xbfe00000 ( 14 MB)
[ 0.000000] .text : 0xc0008000 - 0xc07dd054 (8021 kB)
[ 0.000000] .init : 0xc07de000 - 0xc0820fb4 ( 268 kB)
[ 0.000000] .data : 0xc0822000 - 0xc0870760 ( 314 kB)
[ 0.000000] .bss : 0xc0870760 - 0xc08aa6f8 ( 232 kB)
[ 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] Total of 128 interrupts on 1 active controller
[ 0.000000] OMAP clockevent source: timer2 at 24000000 Hz
[ 0.000018] sched_clock: 32 bits at 24MHz, resolution 41ns, wraps every 178956969942ns
[ 0.000042] OMAP clocksource: timer1 at 24000000 Hz
[ 0.000388] Console: colour dummy device 80x30
[ 0.000428] Calibrating delay loop... 597.60 BogoMIPS (lpj=2988032)
[ 0.119248] pid_max: default: 32768 minimum: 301
[ 0.119366] Security Framework initialized
[ 0.119439] Mount-cache hash table entries: 1024 (order: 0, 4096 bytes)
[ 0.119453] Mountpoint-cache hash table entries: 1024 (order: 0, 4096 bytes)
[ 0.128386] CPU: Testing write buffer coherency: ok
[ 0.128894] Setting up static identity map for 0x805aa760 - 0x805aa7b8
[ 0.130380] devtmpfs: initialized
[ 0.132901] VFP support v0.3: implementor 41 architecture 3 part 30 variant c rev 3
[ 0.142074] omap_hwmod: tptc0 using broken dt data from edma
[ 0.142180] omap_hwmod: tptc1 using broken dt data from edma
[ 0.142273] omap_hwmod: tptc2 using broken dt data from edma
[ 0.146811] omap_hwmod: debugss: _wait_target_disable failed
[ 0.204206] pinctrl core: initialized pinctrl subsystem
[ 0.205691] regulator-dummy: no parameters
[ 0.208697] NET: Registered protocol family 16
[ 0.211499] DMA: preallocated 256 KiB pool for atomic coherent allocations
[ 0.215047] cpuidle: using governor ladder
[ 0.215061] cpuidle: using governor menu
[ 0.224203] syscon 44e10000.control_module: regmap [mem 0x44e10000-0x44e107fb] registered
[ 0.226351] platform 49000000.edma: alias fck already exists
[ 0.226377] platform 49000000.edma: alias fck already exists
[ 0.226396] platform 49000000.edma: alias fck already exists
[ 0.227967] OMAP GPIO hardware version 0.1
[ 0.246149] No ATAGs?
[ 0.246173] hw-breakpoint: debug architecture 0x4 unsupported.
[ 0.280125] bio: create slab <bio-0> at 0
[ 0.302437] edma-dma-engine edma-dma-engine.0: TI EDMA DMA engine driver
[ 0.303797] vbat: 5000 mV
[ 0.304377] lis3_reg: no parameters
[ 0.308267] vgaarb: loaded
[ 0.308999] i2c-core: driver [palmas] using legacy suspend method
[ 0.309012] i2c-core: driver [palmas] using legacy resume method
[ 0.310100] SCSI subsystem initialized
[ 0.312118] usbcore: registered new interface driver usbfs
[ 0.312367] usbcore: registered new interface driver hub
[ 0.312632] usbcore: registered new device driver usb
[ 0.313961] omap_i2c 44e0b000.i2c: could not find pctldev for node /pinmux@44e10800/pinmux_i2c0_pins, deferring probe
[ 0.314007] platform 44e0b000.i2c: Driver omap_i2c requests probe deferral
[ 0.314511] pps_core: LinuxPPS API ver. 1 registered
[ 0.314523] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>
[ 0.314697] PTP clock support registered
[ 0.317481] omap-mailbox 480c8000.mailbox: omap mailbox rev 0x400
[ 0.319050] Advanced Linux Sound Architecture Driver Initialized.
[ 0.321283] Switched to clocksource timer1
[ 0.349102] NET: Registered protocol family 2
[ 0.350227] TCP established hash table entries: 1024 (order: 0, 4096 bytes)
[ 0.350262] TCP bind hash table entries: 1024 (order: 0, 4096 bytes)
[ 0.350287] TCP: Hash tables configured (established 1024 bind 1024)
[ 0.350385] TCP: reno registered
[ 0.350402] UDP hash table entries: 256 (order: 0, 4096 bytes)
[ 0.350428] UDP-Lite hash table entries: 256 (order: 0, 4096 bytes)
[ 0.350676] NET: Registered protocol family 1
[ 0.351167] RPC: Registered named UNIX socket transport module.
[ 0.351183] RPC: Registered udp transport module.
[ 0.351192] RPC: Registered tcp transport module.
[ 0.351201] RPC: Registered tcp NFSv4.1 backchannel transport module.
[ 0.352788] hw perfevents: enabled with ARMv7 Cortex-A8 PMU driver, 5 counters available
[ 0.356909] futex hash table entries: 256 (order: -1, 3072 bytes)
[ 0.577490] VFS: Disk quotas dquot_6.5.2
[ 0.577582] Dquot-cache hash table entries: 1024 (order 0, 4096 bytes)
[ 0.578271] NFS: Registering the id_resolver key type
[ 0.578370] Key type id_resolver registered
[ 0.578382] Key type id_legacy registered
[ 0.578433] jffs2: version 2.2. (NAND) (SUMMARY) �© 2001-2006 Red Hat, Inc.
[ 0.578665] msgmni has been set to 105
[ 0.580566] NET: Registered protocol family 38
[ 0.580618] io scheduler noop registered
[ 0.580628] io scheduler deadline registered
[ 0.580656] io scheduler cfq registered (default)
[ 0.583064] pinctrl-single 44e10800.pinmux: 142 pins at pa f9e10800 size 568
[ 0.589462] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled
[ 0.593970] omap_uart 44e09000.serial: no wakeirq for uart0
[ 0.594173] 44e09000.serial: ttyO0 at MMIO 0x44e09000 (irq = 88, base_baud = 3000000) is a OMAP UART0
[ 1.242285] console [ttyO0] enabled
[ 1.247016] omap_uart 48022000.serial: no wakeirq for uart0
[ 1.253247] 48022000.serial: ttyO1 at MMIO 0x48022000 (irq = 89, base_baud = 3000000) is a OMAP UART1
[ 1.264929] omap_rng 48310000.rng: OMAP Random Number Generator ver. 20
[ 1.272515] [drm] Initialized drm 1.1.0 20060810
[ 1.292477] brd: module loaded
[ 1.303489] loop: module loaded
[ 1.307280] (hci_tty): inside hci_tty_init
[ 1.312295] (hci_tty): allocated 249, 0
[ 1.322162] mtdoops: mtd device (mtddev=name/number) must be supplied
[ 1.334131] usbcore: registered new interface driver asix
[ 1.340101] usbcore: registered new interface driver ax88179_178a
[ 1.346760] usbcore: registered new interface driver cdc_ether
[ 1.353187] usbcore: registered new interface driver smsc95xx
[ 1.359480] usbcore: registered new interface driver net1080
[ 1.365647] usbcore: registered new interface driver cdc_subset
[ 1.372121] usbcore: registered new interface driver zaurus
[ 1.378348] usbcore: registered new interface driver cdc_ncm
[ 1.385001] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
[ 1.391915] ehci-pci: EHCI PCI platform driver
[ 1.396868] ehci-omap: OMAP-EHCI Host Controller driver
[ 1.403041] usbcore: registered new interface driver cdc_wdm
[ 1.409285] usbcore: registered new interface driver usb-storage
[ 1.417074] mousedev: PS/2 mouse device common for all mice
[ 1.424680] i2c /dev entries driver
[ 1.428564] Driver for 1-wire Dallas network protocol.
[ 1.437109] omap_wdt: OMAP Watchdog Timer Rev 0x01: initial timeout 60 sec
[ 1.447084] omap_hsmmc 48060000.mmc: unable to get vmmc regulator -517
[ 1.454297] platform 48060000.mmc: Driver omap_hsmmc requests probe deferral
[ 1.465877] ledtrig-cpu: registered to indicate activity on CPUs
[ 1.472764] omap-aes 53500000.aes: OMAP AES hw accel rev: 3.2
[ 1.480468] omap-sham 53100000.sham: hw accel on OMAP rev 4.3
[ 1.488587] usbcore: registered new interface driver usbhid
[ 1.494508] usbhid: USB HID core driver
[ 1.498920] platform 44d00000.wkup_m3: Driver wkup_m3 requests probe deferral
[ 1.510606] pruss-rproc 4a300000.pruss: creating platform devices for PRU cores
[ 1.521180] remoteproc0: 4a334000.pru0 is available
[ 1.526492] remoteproc0: Note: remoteproc is still under development and considered experimental.
[ 1.535942] remoteproc0: THE BINARY FORMAT IS NOT YET FINALIZED, and backward compatibility isn't yet guaranteed.
[ 1.547102] remoteproc0: Direct firmware load failed with error -2
[ 1.553735] remoteproc0: Falling back to user helper
[ 61.602251] remoteproc0: failed to load rproc-pru0-fw
[ 61.607683] pru-rproc 4a334000.pru0: booting the PRU core manually
[ 61.614325] remoteproc0: powering up 4a334000.pru0
[ 61.619536] remoteproc0: Direct firmware load failed with error -2
[ 61.626161] remoteproc0: Falling back to user helper
[ 121.761636] remoteproc0: request_firmware failed: -2
[ 121.766962] pru-rproc 4a334000.pru0: rproc_boot failed
[ 121.772589] remoteproc0: releasing 4a334000.pru0
[ 121.777667] pru-rproc: probe of 4a334000.pru0 failed with error -2
[ 121.784704] remoteproc0: 4a338000.pru1 is available
[ 121.789930] remoteproc0: Note: remoteproc is still under development and considered experimental.
[ 121.799403] remoteproc0: THE BINARY FORMAT IS NOT YET FINALIZED, and backward compatibility isn't yet guaranteed.
[ 121.810472] remoteproc0: Direct firmware load failed with error -2
[ 121.817112] remoteproc0: Falling back to user helper
[ 181.922057] remoteproc0: failed to load rproc-pru1-fw
[ 181.927487] pru-rproc 4a338000.pru1: booting the PRU core manually
[ 181.934106] remoteproc0: powering up 4a338000.pru1
[ 181.939308] remoteproc0: Direct firmware load failed with error -2
[ 181.945930] remoteproc0: Falling back to user helper
[ 242.081611] remoteproc0: request_firmware failed: -2
[ 242.086934] pru-rproc 4a338000.pru1: rproc_boot failed
[ 242.092542] remoteproc0: releasing 4a338000.pru1
[ 242.097606] pru-rproc: probe of 4a338000.pru1 failed with error -2
[ 242.108750] oprofile: using arm/armv7
[ 242.113133] TCP: cubic registered
[ 242.116629] Initializing XFRM netlink socket
[ 242.121143] NET: Registered protocol family 17
[ 242.125913] NET: Registered protocol family 15
[ 242.130745] Key type dns_resolver registered
[ 242.135274] NET: Registered protocol family 41
[ 242.141254] cpu cpu0: of_pm_voltdm_notifier_register: Failed to get cpu0 regulator/voltdm: -517
[ 242.150488] cpu cpu0: cpu0 clock notifier not ready, retry
[ 242.156383] platform cpufreq-cpu0.0: Driver cpufreq-cpu0 requests probe deferral
[ 242.165047] PM: could not locate rtc hwmod
[ 242.169378] ThumbEE CPU extension supported.
[ 242.174057] Registering SWP/SWPB emulation handler
[ 242.181204] lis3_reg: disabling
[ 242.184668] vbat: disabling
[ 242.187614] regulator-dummy: disabling
[ 242.196546] tps65910 0-002d: No interrupt support, no core IRQ
[ 242.207787] vrtc: 1800 mV
[ 242.210951] vrtc: supplied by vbat
[ 242.216491] vio: at 1800 mV
[ 242.219638] vio: supplied by vbat
[ 242.225168] vdd_mpu: 912 <--> 1375 mV at 1137 mV
[ 242.230211] vdd_mpu: supplied by vbat
[ 242.236079] vdd_core: 912 <--> 1150 mV at 1137 mV
[ 242.241230] vdd_core: supplied by vbat
[ 242.246827] vdd3: 5000 mV
[ 242.251196] vdig1: at 1800 mV
[ 242.254540] vdig1: supplied by vbat
[ 242.259784] vdig2: at 1800 mV
[ 242.263115] vdig2: supplied by vbat
[ 242.268362] vpll: at 1800 mV
[ 242.271598] vpll: supplied by vbat
[ 242.276707] vdac: at 1800 mV
[ 242.279933] vdac: supplied by vbat
[ 242.285098] vaux1: at 1800 mV
[ 242.288409] vaux1: supplied by vbat
[ 242.293658] vaux2: at 3300 mV
[ 242.296964] vaux2: supplied by vbat
[ 242.302214] vaux33: at 3300 mV
[ 242.305611] vaux33: supplied by vbat
[ 242.310920] vmmc: 1800 <--> 3300 mV at 3300 mV
[ 242.315809] vmmc: supplied by vbat
[ 242.320603] omap_i2c 44e0b000.i2c: bus 0 rev0.11 at 400 kHz
[ 242.361340] mmc0: mmc_rescan_try_freq: trying to init card at 400000 Hz
[ 242.368998] platform 44d00000.wkup_m3: Driver wkup_m3 requests probe deferral
[ 242.381136] platform 44d00000.wkup_m3: Driver wkup_m3 requests probe deferral
[ 242.429691] mmc0: host does not support reading read-only switch. assuming write-enable.
[ 242.440953] mmc0: new high speed SDHC card at address 1234
[ 242.447676] mmcblk0: mmc0:1234 SA08G 7.41 GiB
[ 242.452440] davinci_mdio 4a101000.mdio: davinci mdio revision 1.6
[ 242.458863] davinci_mdio 4a101000.mdio: detected phy mask fffffffe
[ 242.467483] libphy: 4a101000.mdio: probed
[ 242.471813] davinci_mdio 4a101000.mdio: phy[0]: device 4a101000.mdio:00, driver unknown
[ 242.480435] mmcblk0: p1 p2
[ 242.484878] platform 44d00000.wkup_m3: Driver wkup_m3 requests probe deferral
[ 242.493913] cpsw 4a100000.ethernet: Detected MACID = 20:cd:39:f6:66:fd
[ 242.502843] platform 44d00000.wkup_m3: Driver wkup_m3 requests probe deferral
[ 242.512251] platform 44d00000.wkup_m3: Driver wkup_m3 requests probe deferral
[ 242.524609] ALSA device list:
[ 242.527766] No soundcards found.

Here it hangs

So I went back to basics, burned an SD card with pre-built binaries from SDK 8.00 and ran the starter kit from it, I noted that the remoteproc failure notice is given early on, but it immediately continues and later it is successfully loaded. I did notice that on the starter kit it has what appears to be a successful probe

[ 1.936072] remoteproc0: wkup_m3 is available

vs a probe deferral on our custom design

[ 1.498920] platform 44d00000.wkup_m3: Driver wkup_m3 requests probe deferral
[ 1.510606] pruss-rproc 4a300000.pruss: creating platform devices for PRU cores
[ 1.521180] remoteproc0: 4a334000.pru0 is available

What should the proper DT setup be for wkup_m3? We leveraged this from the EVM

am335x-evm.dts:&wkup_m3 {
am335x-evm.dts: ti,scale-data-fw = "am335x-evm-scale-data.bin";
am335x-evm.dts:};

On another note is the PRU core powered from an individual source? On our custom design we had disabled the RTC and ended up having to bypass reading the internal RTC regs because the logic core wasn't powered and it would fail on a read. Just wondering if this is something like that. Thanks, Tom



------------- starter kit boot snip -----------------------
[ 1.772879] omap_i2c 44e0b000.i2c: bus 0 rev0.11 at 400 kHz
[ 1.853287] mmc0: host does not support reading read-only switch. assuming write-enable.
[ 1.864287] mmc0: new high speed SDHC card at address 1234
[ 1.870734] mmcblk0: mmc0:1234 SA08G 7.41 GiB
[ 1.877782] mmcblk0: p1 p2
[ 1.936072] remoteproc0: wkup_m3 is available
[ 1.940723] remoteproc0: Note: remoteproc is still under development and considered experimental.
[ 1.950121] remoteproc0: THE BINARY FORMAT IS NOT YET FINALIZED, and backward compatibility isn't yet guaranteed.
[ 1.961152] remoteproc0: Direct firmware load failed with error -2
[ 1.967762] remoteproc0: Falling back to user helper
[ 1.973535] cpu cpu0: of_pm_voltdm_notifier_register: Fail calculating voltage latency[1100000<->1260000]:-22
[ 1.988574] omap_hsmmc 481d8000.mmc: card claims to support voltages below defined range
[ 2.021926] mmc1: queuing unknown CIS tuple 0x91 (3 bytes)
[ 2.028463] mmc1: new SDIO card at address 0001
[ 2.055499] davinci_mdio 4a101000.mdio: davinci mdio revision 1.6
[ 2.061876] davinci_mdio 4a101000.mdio: detected phy mask fffffffc
[ 2.069799] libphy: 4a101000.mdio: probed
[ 2.073999] davinci_mdio 4a101000.mdio: phy[0]: device 4a101000.mdio:00, driver Atheros 8031 ethernet
[ 2.083668] davinci_mdio 4a101000.mdio: phy[1]: device 4a101000.mdio:01, driver Atheros 8031 ethernet
[ 2.094254] cpsw 4a100000.ethernet: Detected MACID = 00:18:31:e2:1c:73
[ 2.102457] cpsw 4a100000.ethernet: cpsw: Detected MACID = 00:18:31:e2:1c:74
[ 2.112361] input: gpio_buttons.9 as /devices/gpio_buttons.9/input/input1
[ 2.120314] omap_rtc 44e3e000.rtc: setting system clock to 2000-01-01 00:00:01 UTC (946684801)
[ 2.132684] ALSA device list:
[ 2.135867] No soundcards found.
[ 2.159620] EXT4-fs (mmcblk0p2): mounted filesystem with ordered data mode. Opts: (null)
[ 2.168205] VFS: Mounted root (ext4 filesystem) readonly on device 179:2.
[ 2.184641] devtmpfs: mounted
[ 2.188262] Freeing unused kernel memory: 268K (c07bb000 - c07fe000)
INIT: version 2.88 booting
Starting udev
[ 3.322823] udevd[855]: starting version 182
[ 4.004787] 47401300.usb-phy supply vcc not found, using dummy regulator
[ 4.159999] 47401b00.usb-phy supply vcc not found, using dummy regulator
[ 4.222948] remoteproc0: powering up wkup_m3
[ 4.262300] remoteproc0: Booting fw image am335x-pm-firmware.elf, size 154412
[ 4.375644] lis3lv02d: 16 bits lis331dlh sensor found
[ 4.414777] PM: CM3 Firmware Version = 0x190
[ 4.489253] remoteproc0: remote processor wkup_m3 is now up
------------ snip ---------------

So I've reverted my kernel config to mirror the default SDK 8.00 more closely. Things have changed and the PRU firmware load is not being attempted now. The Device Drivers --> Remoteproc drivers --> TI PRUSS was not selected in the default config so I disabled it.

It runs up to populating the dev cache and hangs. After a few mins it faults.

===================================

Starting kernel ...

[ 0.000000] Booting Linux on physical CPU 0x0
[ 0.000000] Linux version 3.14.26-g03832cc-dirty (triddle@triddle-ThinkPad-T430) (gcc version 4.7.3 20130226 (prerelease) (crosstool-NG linaro-1.13.1-4.5
[ 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] Machine model: TI AM335x EVM
[ 0.000000] cma: CMA: reserved 24 MiB at 82000000
[ 0.000000] Memory policy: Data cache writeback
[ 0.000000] CPU: All CPU(s) started in SVC mode.
[ 0.000000] AM335X ES2.1 (neon )
[ 0.000000] Built 1 zonelists in Zone order, mobility grouping on. Total pages: 15744
[ 0.000000] Kernel command line: console=ttyO0,115200n8 root=/dev/mmcblk0p2 ro rootfstype=ext4 rootwait
[ 0.000000] PID hash table entries: 256 (order: -2, 1024 bytes)
[ 0.000000] Dentry cache hash table entries: 8192 (order: 3, 32768 bytes)
[ 0.000000] Inode-cache hash table entries: 4096 (order: 2, 16384 bytes)
[ 0.000000] Memory: 29448K/63488K available (5775K kernel code, 313K rwdata, 2084K rodata, 269K init, 231K bss, 34040K reserved, 0K highmem)
[ 0.000000] Virtual kernel memory layout:
[ 0.000000] vector : 0xffff0000 - 0xffff1000 ( 4 kB)
[ 0.000000] fixmap : 0xfff00000 - 0xfffe0000 ( 896 kB)
[ 0.000000] vmalloc : 0xc4800000 - 0xff000000 ( 936 MB)
[ 0.000000] lowmem : 0xc0000000 - 0xc4000000 ( 64 MB)
[ 0.000000] pkmap : 0xbfe00000 - 0xc0000000 ( 2 MB)
[ 0.000000] modules : 0xbf000000 - 0xbfe00000 ( 14 MB)
[ 0.000000] .text : 0xc0008000 - 0xc07b5054 (7861 kB)
[ 0.000000] .init : 0xc07b6000 - 0xc07f947c ( 270 kB)
[ 0.000000] .data : 0xc07fa000 - 0xc08486a0 ( 314 kB)
[ 0.000000] .bss : 0xc08486a0 - 0xc0882678 ( 232 kB)
[ 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] Total of 128 interrupts on 1 active controller
[ 0.000000] OMAP clockevent source: timer2 at 24000000 Hz
[ 0.000017] sched_clock: 32 bits at 24MHz, resolution 41ns, wraps every 178956969942ns
[ 0.000043] OMAP clocksource: timer1 at 24000000 Hz
[ 0.000387] Console: colour dummy device 80x30
[ 0.000426] Calibrating delay loop... 597.60 BogoMIPS (lpj=2988032)
[ 0.119253] pid_max: default: 32768 minimum: 301
[ 0.119375] Security Framework initialized
[ 0.119448] Mount-cache hash table entries: 1024 (order: 0, 4096 bytes)
[ 0.119463] Mountpoint-cache hash table entries: 1024 (order: 0, 4096 bytes)
[ 0.128481] CPU: Testing write buffer coherency: ok
[ 0.128994] Setting up static identity map for 0x805a9ea0 - 0x805a9ef8
[ 0.130460] devtmpfs: initialized
[ 0.132938] VFP support v0.3: implementor 41 architecture 3 part 30 variant c rev 3
[ 0.142186] omap_hwmod: tptc0 using broken dt data from edma
[ 0.142294] omap_hwmod: tptc1 using broken dt data from edma
[ 0.142389] omap_hwmod: tptc2 using broken dt data from edma
[ 0.146937] omap_hwmod: debugss: _wait_target_disable failed
[ 0.204284] pinctrl core: initialized pinctrl subsystem
[ 0.205594] regulator-dummy: no parameters
[ 0.208638] NET: Registered protocol family 16
[ 0.211401] DMA: preallocated 256 KiB pool for atomic coherent allocations
[ 0.214702] cpuidle: using governor ladder
[ 0.214721] cpuidle: using governor menu
[ 0.223556] syscon 44e10000.control_module: regmap [mem 0x44e10000-0x44e107fb] registered
[ 0.225478] platform 49000000.edma: alias fck already exists
[ 0.225508] platform 49000000.edma: alias fck already exists
[ 0.225527] platform 49000000.edma: alias fck already exists
[ 0.227015] OMAP GPIO hardware version 0.1
[ 0.243556] No ATAGs?
[ 0.243586] hw-breakpoint: debug architecture 0x4 unsupported.
[ 0.277221] bio: create slab <bio-0> at 0
[ 0.297735] edma-dma-engine edma-dma-engine.0: TI EDMA DMA engine driver
[ 0.299004] vbat: 5000 mV
[ 0.299578] lis3_reg: no parameters
[ 0.303341] vgaarb: loaded
[ 0.304052] i2c-core: driver [palmas] using legacy suspend method
[ 0.304066] i2c-core: driver [palmas] using legacy resume method
[ 0.305005] SCSI subsystem initialized
[ 0.306925] usbcore: registered new interface driver usbfs
[ 0.307168] usbcore: registered new interface driver hub
[ 0.307420] usbcore: registered new device driver usb
[ 0.308659] omap_i2c 44e0b000.i2c: could not find pctldev for node /pinmux@44e10800/pinmux_i2c0_pins, deferring probe
[ 0.308695] platform 44e0b000.i2c: Driver omap_i2c requests probe deferral
[ 0.309173] pps_core: LinuxPPS API ver. 1 registered
[ 0.309186] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>
[ 0.309434] PTP clock support registered
[ 0.312017] omap-mailbox 480c8000.mailbox: omap mailbox rev 0x400
[ 0.313118] Advanced Linux Sound Architecture Driver Initialized.
[ 0.315219] Switched to clocksource timer1
[ 0.341105] NET: Registered protocol family 2
[ 0.342233] TCP established hash table entries: 1024 (order: 0, 4096 bytes)
[ 0.342265] TCP bind hash table entries: 1024 (order: 0, 4096 bytes)
[ 0.342291] TCP: Hash tables configured (established 1024 bind 1024)
[ 0.342389] TCP: reno registered
[ 0.342407] UDP hash table entries: 256 (order: 0, 4096 bytes)
[ 0.342433] UDP-Lite hash table entries: 256 (order: 0, 4096 bytes)
[ 0.342686] NET: Registered protocol family 1
[ 0.343166] RPC: Registered named UNIX socket transport module.
[ 0.343181] RPC: Registered udp transport module.
[ 0.343189] RPC: Registered tcp transport module.
[ 0.343197] RPC: Registered tcp NFSv4.1 backchannel transport module.
[ 0.344570] hw perfevents: enabled with ARMv7 Cortex-A8 PMU driver, 5 counters available
[ 0.348147] futex hash table entries: 256 (order: -1, 3072 bytes)
[ 0.573148] VFS: Disk quotas dquot_6.5.2
[ 0.573243] Dquot-cache hash table entries: 1024 (order 0, 4096 bytes)
[ 0.573932] NFS: Registering the id_resolver key type
[ 0.574031] Key type id_resolver registered
[ 0.574042] Key type id_legacy registered
[ 0.574095] jffs2: version 2.2. (NAND) (SUMMARY) �© 2001-2006 Red Hat, Inc.
[ 0.574327] msgmni has been set to 105
[ 0.576341] NET: Registered protocol family 38
[ 0.576402] io scheduler noop registered
[ 0.576413] io scheduler deadline registered
[ 0.576439] io scheduler cfq registered (default)
[ 0.578561] pinctrl-single 44e10800.pinmux: 142 pins at pa f9e10800 size 568
[ 0.584711] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled
[ 0.588911] omap_uart 44e09000.serial: no wakeirq for uart0
[ 0.589135] 44e09000.serial: ttyO0 at MMIO 0x44e09000 (irq = 88, base_baud = 3000000) is a OMAP UART0
[ 1.234143] console [ttyO0] enabled
[ 1.238755] omap_uart 48022000.serial: no wakeirq for uart0
[ 1.244872] 48022000.serial: ttyO1 at MMIO 0x48022000 (irq = 89, base_baud = 3000000) is a OMAP UART1
[ 1.256430] omap_rng 48310000.rng: OMAP Random Number Generator ver. 20
[ 1.263902] [drm] Initialized drm 1.1.0 20060810
[ 1.283228] brd: module loaded
[ 1.293914] loop: module loaded
[ 1.297784] (hci_tty): inside hci_tty_init
[ 1.302677] (hci_tty): allocated 249, 0
[ 1.312147] mtdoops: mtd device (mtddev=name/number) must be supplied
[ 1.323868] usbcore: registered new interface driver asix
[ 1.329899] usbcore: registered new interface driver ax88179_178a
[ 1.336581] usbcore: registered new interface driver cdc_ether
[ 1.343001] usbcore: registered new interface driver smsc95xx
[ 1.349265] usbcore: registered new interface driver net1080
[ 1.355457] usbcore: registered new interface driver cdc_subset
[ 1.361921] usbcore: registered new interface driver zaurus
[ 1.368078] usbcore: registered new interface driver cdc_ncm
[ 1.374736] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
[ 1.381652] ehci-pci: EHCI PCI platform driver
[ 1.386608] ehci-omap: OMAP-EHCI Host Controller driver
[ 1.392719] usbcore: registered new interface driver cdc_wdm
[ 1.398995] usbcore: registered new interface driver usb-storage
[ 1.406766] mousedev: PS/2 mouse device common for all mice
[ 1.414410] i2c-core: driver [rtc-ds1307] using legacy suspend method
[ 1.421262] i2c-core: driver [rtc-ds1307] using legacy resume method
[ 1.429056] i2c /dev entries driver
[ 1.432930] Driver for 1-wire Dallas network protocol.
[ 1.441284] omap_wdt: OMAP Watchdog Timer Rev 0x01: initial timeout 60 sec
[ 1.451132] omap_hsmmc 48060000.mmc: unable to get vmmc regulator -517
[ 1.458337] platform 48060000.mmc: Driver omap_hsmmc requests probe deferral
[ 1.469581] ledtrig-cpu: registered to indicate activity on CPUs
[ 1.476422] omap-aes 53500000.aes: OMAP AES hw accel rev: 3.2
[ 1.484027] omap-sham 53100000.sham: hw accel on OMAP rev 4.3
[ 1.492104] usbcore: registered new interface driver usbhid
[ 1.498030] usbhid: USB HID core driver
[ 1.502395] platform 44d00000.wkup_m3: Driver wkup_m3 requests probe deferral
[ 1.513930] oprofile: using arm/armv7
[ 1.518311] TCP: cubic registered
[ 1.521807] Initializing XFRM netlink socket
[ 1.526359] NET: Registered protocol family 17
[ 1.531091] NET: Registered protocol family 15
[ 1.535956] Key type dns_resolver registered
[ 1.541373] cpu cpu0: of_pm_voltdm_notifier_register: Failed to get cpu0 regulator/voltdm: -517
[ 1.550615] cpu cpu0: cpu0 clock notifier not ready, retry
[ 1.556530] platform cpufreq-cpu0.0: Driver cpufreq-cpu0 requests probe deferral
[ 1.565144] PM: could not locate rtc hwmod
[ 1.569532] ThumbEE CPU extension supported.
[ 1.574157] Registering SWP/SWPB emulation handler
[ 1.581227] lis3_reg: disabling
[ 1.584548] vbat: disabling
[ 1.587627] regulator-dummy: disabling
[ 1.596319] tps65910 0-002d: No interrupt support, no core IRQ
[ 1.607246] vrtc: 1800 mV
[ 1.610433] vrtc: supplied by vbat
[ 1.615965] vio: at 1800 mV
[ 1.619107] vio: supplied by vbat
[ 1.624596] vdd_mpu: 912 <--> 1375 mV at 1137 mV
[ 1.629675] vdd_mpu: supplied by vbat
[ 1.635533] vdd_core: 912 <--> 1150 mV at 1137 mV
[ 1.640668] vdd_core: supplied by vbat
[ 1.646234] vdd3: 5000 mV
[ 1.650591] vdig1: at 1800 mV
[ 1.653905] vdig1: supplied by vbat
[ 1.659167] vdig2: at 1800 mV
[ 1.662478] vdig2: supplied by vbat
[ 1.667722] vpll: at 1800 mV
[ 1.670958] vpll: supplied by vbat
[ 1.676103] vdac: at 1800 mV
[ 1.679322] vdac: supplied by vbat
[ 1.684443] vaux1: at 1800 mV
[ 1.687781] vaux1: supplied by vbat
[ 1.692982] vaux2: at 3300 mV
[ 1.696314] vaux2: supplied by vbat
[ 1.701509] vaux33: at 3300 mV
[ 1.704908] vaux33: supplied by vbat
[ 1.710264] vmmc: 1800 <--> 3300 mV at 3300 mV
[ 1.715132] vmmc: supplied by vbat
[ 1.719804] omap_i2c 44e0b000.i2c: bus 0 rev0.11 at 400 kHz
[ 1.765273] mmc0: mmc_rescan_try_freq: trying to init card at 400000 Hz
[ 1.772855] platform 44d00000.wkup_m3: Driver wkup_m3 requests probe deferral
[ 1.784839] platform 44d00000.wkup_m3: Driver wkup_m3 requests probe deferral
[ 1.833611] mmc0: host does not support reading read-only switch. assuming write-enable.
[ 1.844876] mmc0: new high speed SDHC card at address 1234
[ 1.851500] mmcblk0: mmc0:1234 SA08G 7.41 GiB
[ 1.856266] davinci_mdio 4a101000.mdio: davinci mdio revision 1.6
[ 1.862689] davinci_mdio 4a101000.mdio: detected phy mask fffffffe
[ 1.871275] libphy: 4a101000.mdio: probed
[ 1.875605] davinci_mdio 4a101000.mdio: phy[0]: device 4a101000.mdio:00, driver SMSC LAN8710/LAN8720
[ 1.885420] mmcblk0: p1 p2
[ 1.889054] platform 44d00000.wkup_m3: Driver wkup_m3 requests probe deferral
[ 1.898767] cpsw 4a100000.ethernet: Detected MACID = 20:cd:39:f6:66:fd
[ 1.907042] platform 44d00000.wkup_m3: Driver wkup_m3 requests probe deferral
[ 1.916842] platform 44d00000.wkup_m3: Driver wkup_m3 requests probe deferral
[ 1.925165] drivers/rtc/hctosys.c: unable to open rtc device (rtc0)
[ 1.935956] ALSA device list:
[ 1.939105] No soundcards found.
[ 1.964458] EXT4-fs (mmcblk0p2): mounted filesystem with ordered data mode. Opts: (null)
[ 1.973146] VFS: Mounted root (ext4 filesystem) readonly on device 179:2.
[ 1.990029] devtmpfs: mounted
[ 1.993758] Freeing unused kernel memory: 268K (c07b6000 - c07f9000)
INIT: version 2.88 booting
Error opening /dev/fb0: No such file or directory
Starting udev
[ 3.075819] udevd[816]: starting version 182
[ 3.598811] platform 44d00000.wkup_m3: Driver wkup_m3 requests probe deferral
[ 3.607200] 47401300.usb-phy supply vcc not found, using dummy regulator
[ 3.676048] platform 44d00000.wkup_m3: Driver wkup_m3 requests probe deferral
[ 3.727787] 47401b00.usb-phy supply vcc not found, using dummy regulator
[ 3.856406] platform 44d00000.wkup_m3: Driver wkup_m3 requests probe deferral
[ 3.974352] platform 44d00000.wkup_m3: Driver wkup_m3 requests probe deferral
[ 4.096130] platform 44d00000.wkup_m3: Driver wkup_m3 requests probe deferral
[ 4.276730] platform 44d00000.wkup_m3: Driver wkup_m3 requests probe deferral
[ 4.652840] davinci_evm: probe of sound.8 failed with error -22
[ 7.041485] musb-hdrc musb-hdrc.0.auto: MUSB HDRC host driver
[ 7.128615] musb-hdrc musb-hdrc.0.auto: new USB bus registered, assigned bus number 1
[ 7.226512] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002
[ 7.233693] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[ 7.241374] usb usb1: Product: MUSB HDRC host driver
[ 7.246649] usb usb1: Manufacturer: Linux 3.14.26-g03832cc-dirty musb-hcd
[ 7.253799] usb usb1: SerialNumber: musb-hdrc.0.auto
[ 7.553556] hub 1-0:1.0: USB hub found
[ 7.582812] random: nonblocking pool is initialized
[ 7.619206] hub 1-0:1.0: 1 port detected
[ 7.649421] platform 44d00000.wkup_m3: Driver wkup_m3 requests probe deferral
[ 7.783190] platform 44d00000.wkup_m3: Driver wkup_m3 requests probe deferral
[ 7.882676] platform 44d00000.wkup_m3: Driver wkup_m3 requests probe deferral
[ 7.898440] musb-hdrc musb-hdrc.1.auto: MUSB HDRC host driver
[ 7.905355] platform 44d00000.wkup_m3: Driver wkup_m3 requests probe deferral
[ 7.913579] musb-hdrc musb-hdrc.1.auto: new USB bus registered, assigned bus number 2
[ 7.922440] usb usb2: New USB device found, idVendor=1d6b, idProduct=0002
[ 7.929701] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[ 7.937329] usb usb2: Product: MUSB HDRC host driver
[ 7.942559] usb usb2: Manufacturer: Linux 3.14.26-g03832cc-dirty musb-hcd
[ 7.949722] usb usb2: SerialNumber: musb-hdrc.1.auto
[ 7.956088] hub 2-0:1.0: USB hub found
[ 7.960093] hub 2-0:1.0: 1 port detected
[ 7.964898] platform 44d00000.wkup_m3: Driver wkup_m3 requests probe deferral
[ 7.975809] platform 44d00000.wkup_m3: Driver wkup_m3 requests probe deferral
[ 28.964083] EXT4-fs (mmcblk0p2): re-mounted. Opts: data=ordered
Starting Bootlog daemon: bootlogd: cannot allocate pseudo tty: No such file or directory
bootlogd.
Populating dev cache


hangs....

Hi Tom,

A thought on your issue.

Does your board have sysboot switches?

I experienced similar problem with my AM437x GP board. Errors were:

 [ 0.311551] omap_i2c 44e0b000.i2c: could not find pctldev for node /pinmux@44e10800/pinmux_i2c0_pins, deferring probe

 [ 0.311598] platform 44e0b000.i2c: Driver omap_i2c requests probe deferral

 [ 1.515304] cpu cpu0: of_pm_voltdm_notifier_register: Failed to get cpu0 regulator/voltdm: -517

 [ 1.524537] cpu cpu0: cpu0 clock notifier not ready, retry

 [ 1.530454] platform cpufreq-cpu0.0: Driver cpufreq-cpu0 requests probe deferral

And after that the boot would continue a little more and hang either on:

  [ 242.512251] platform 44d00000.wkup_m3: Driver wkup_m3 requests probe deferral

  [ 242.524609] ALSA device list:

  [ 242.527766] No soundcards found.

Or:  

  [   33.565070] lost page write due to I/O error on mmcblk0p2                                                                                                      

  [   33.571049] mmcblk0: error -110 sending status command, retrying                                                                                              

  [   33.587781] mmcblk0: timed out sending r/w cmd command, card status 0x800d00                                                                                  

  ALSA: Restoring mixer settings...                                                                                                                                

  No state is present for card AM437xGPEVM                                                                                                                          

  Found hardware: "AM437x-GP-EVM" "" "" "" ""                                                                                                                      

  Hardware is initialized using a generic method                                                                                                                    

  No state is present for card AM437xGPEVM                                                                                                                          

  Fri Apr 10 19:48:00 UTC 2015

 Or at:

 [   15.279644] (stk) :ldisc_install = 1(stk) :ldisc installation timeout                                                                                          

 [   16.280802] (stk) :ldisc_install = 0(stk) : timed out waiting for ldisc to be un-installed                                                                    

 [   17.284761] Bluetooth: st_register failed -22

The problem was, as you suspect incorrect reading (or remounting) from the sdcard.

What seemed to solve my problem was changing the sysboot pins to another configuration to another configuration where MMC0 is the first boot device, there are several options for this (see SYSBOOT Configuration Pins table in TRM).

Hope this is of some help. 

Best Regards,

Yordan

Hi ,

Is this issue resolved ? I am also getting the same kind of error .. Pls find the logs ...

I am porting the sdk-1.00.00.03 to my custom hardware. Earlier we were using the 3.2 kernel .

Any help on this ?



U-Boot SPL 2014.07-00001-g4a8e5b8-dirty (Aug 12 2015 - 17:28:08)
DEBUG:RAG:inside s_init 1
DEBUG:RAG:inside s_init 2
DEBUG:RAG:inside get_dpll_ddr_params
DEBUG:RAG:inside read_eeprom
DEBUG:RAG:A335BONE
DEBUG:RAG:inside set_mux_conf_regs
DEBUG:RAG:inside read_eeprom
DEBUG:RAG:A335BONE
DEBUG:RAG:sdram_init
DEBUG:RAG:inside read_eeprom
DEBUG:RAG:A335BONE
DEBUG:RAG:inside s_init 3
DEBUG:RAG:inside s_init end
DEBUG:RAG:inside read_eeprom
DEBUG:RAG:A335BONE
DEBUG:RAG:inside am33xx_spl_board_init 1
No AC power, disabling frequency switch
reading args
spl_load_image_fat_os: error reading image args, err - -1
reading u-boot.img
reading u-boot.img


U-Boot 2014.07-00001-g4a8e5b8-dirty (Aug 12 2015 - 17:28:08)

I2C: ready
DRAM: 512 MiB
NAND: 512 MiB
MMC: OMAP SD/MMC: 0, OMAP SD/MMC: 1
reading uboot.env

** Unable to read "uboot.env" from mmc0:1 **
Using default environment

DEBUG:RAG:inside board_late_init
DEBUG:RAG:inside read_eeprom
DEBUG:RAG:A335BONE
Net: <ethaddr> not set. Validating first E-fuse MAC
DEBUG:RAG:inside board_eth_init
DEBUG:RAG:inside read_eeprom
DEBUG:RAG:A335BONE
Phy not found
cpsw, usb_ether
Hit any key to stop autoboot: 0
switch to partitions #0, OK
mmc0 is current device
SD/MMC found on device 0
reading uEnv.txt
** Unable to read file uEnv.txt **
4704608 bytes read in 659 ms (6.8 MiB/s)
30625 bytes read in 42 ms (711.9 KiB/s)
Kernel image @ 0x82000000 [ 0x000000 - 0x47c960 ]
## Flattened Device Tree blob at 88000000
Booting using the fdt blob at 0x88000000
Loading Device Tree to 8fff5000, end 8ffff7a0 ... OK

Starting kernel ...

[ 0.000000] Booting Linux on physical CPU 0x0
[ 0.000000] Linux version 3.14.43-g875c69b (philips@philips-OptiPlex-760) (gcc version 4.7.3 20130226 (prerelease) (crosstool-NG linaro-1.13.1-4.7-2013.03-20130313 - Linaro GCC 2013.03) ) #1 Thu Aug 13 15:39:05 IST 2015
[ 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] Machine model: TI AM335x BeagleBone
[ 0.000000] cma: CMA: reserved 24 MiB at 9e000000
[ 0.000000] Memory policy: Data cache writeback
[ 0.000000] CPU: All CPU(s) started in SVC mode.
[ 0.000000] AM335X ES1.0 (neon )
[ 0.000000] Built 1 zonelists in Zone order, mobility grouping on. Total pages: 129792
[ 0.000000] Kernel command line: console=ttyO0,115200n8 root=/dev/mmcblk0p2 ro rootfstype=ext4 rootwait
[ 0.000000] PID hash table entries: 2048 (order: 1, 8192 bytes)
[ 0.000000] Dentry cache hash table entries: 65536 (order: 6, 262144 bytes)
[ 0.000000] Inode-cache hash table entries: 32768 (order: 5, 131072 bytes)
[ 0.000000] Memory: 484432K/523264K available (6419K kernel code, 308K rwdata, 2316K rodata, 261K init, 233K bss, 38832K reserved, 0K highmem)
[ 0.000000] Virtual kernel memory layout:
[ 0.000000] vector : 0xffff0000 - 0xffff1000 ( 4 kB)
[ 0.000000] fixmap : 0xfff00000 - 0xfffe0000 ( 896 kB)
[ 0.000000] vmalloc : 0xe0800000 - 0xff000000 ( 488 MB)
[ 0.000000] lowmem : 0xc0000000 - 0xe0000000 ( 512 MB)
[ 0.000000] pkmap : 0xbfe00000 - 0xc0000000 ( 2 MB)
[ 0.000000] modules : 0xbf000000 - 0xbfe00000 ( 14 MB)
[ 0.000000] .text : 0xc0008000 - 0xc08900c4 (8737 kB)
[ 0.000000] .init : 0xc0891000 - 0xc08d27bc ( 262 kB)
[ 0.000000] .data : 0xc08d4000 - 0xc09213a0 ( 309 kB)
[ 0.000000] .bss : 0xc09213a0 - 0xc095b900 ( 234 kB)
[ 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] Total of 128 interrupts on 1 active controller
[ 0.000000] OMAP clockevent source: timer2 at 24000000 Hz
[ 0.000028] sched_clock: 32 bits at 24MHz, resolution 41ns, wraps every 178956969942ns
[ 0.000073] OMAP clocksource: timer1 at 24000000 Hz
[ 0.000656] Console: colour dummy device 80x30
[ 0.000711] WARNING: Your 'console=ttyO0' has been replaced by 'ttyS0'
[ 0.000727] This ensures that you still see kernel messages. Please
[ 0.000741] update your kernel commandline.
[ 0.000791] Calibrating delay loop... 298.59 BogoMIPS (lpj=1492992)
[ 0.068524] pid_max: default: 32768 minimum: 301
[ 0.068771] Security Framework initialized
[ 0.068892] Mount-cache hash table entries: 1024 (order: 0, 4096 bytes)
[ 0.068920] Mountpoint-cache hash table entries: 1024 (order: 0, 4096 bytes)
[ 0.084786] CPU: Testing write buffer coherency: ok
[ 0.085519] Setting up static identity map for 0x8064b020 - 0x8064b078
[ 0.087658] devtmpfs: initialized
[ 0.092360] VFP support v0.3: implementor 41 architecture 3 part 30 variant c rev 3
[ 0.110372] omap_hwmod: tptc0 using broken dt data from edma
[ 0.110577] omap_hwmod: tptc1 using broken dt data from edma
[ 0.110763] omap_hwmod: tptc2 using broken dt data from edma
[ 0.117129] omap_hwmod: debugss: _wait_target_disable failed
[ 0.187445] pinctrl core: initialized pinctrl subsystem
[ 0.189904] regulator-dummy: no parameters
[ 0.194912] NET: Registered protocol family 16
[ 0.199989] DMA: preallocated 256 KiB pool for atomic coherent allocations
[ 0.206208] cpuidle: using governor ladder
[ 0.206244] cpuidle: using governor menu
[ 0.222543] syscon 44e10000.control_module: regmap [mem 0x44e10000-0x44e107fb] registered
[ 0.226129] platform 49000000.edma: alias fck already exists
[ 0.226185] platform 49000000.edma: alias fck already exists
[ 0.226222] platform 49000000.edma: alias fck already exists
[ 0.228997] OMAP GPIO hardware version 0.1
[ 0.256572] No ATAGs?
[ 0.256620] hw-breakpoint: debug architecture 0x4 unsupported.
[ 0.333028] bio: create slab <bio-0> at 0
[ 0.373497] edma-dma-engine edma-dma-engine.0: TI EDMA DMA engine driver
[ 0.375752] vmmcsd_fixed: 3300 mV
[ 0.382762] vgaarb: loaded
[ 0.384018] i2c-core: driver [palmas] using legacy suspend method
[ 0.384042] i2c-core: driver [palmas] using legacy resume method
[ 0.385755] SCSI subsystem initialized
[ 0.389518] usbcore: registered new interface driver usbfs
[ 0.389976] usbcore: registered new interface driver hub
[ 0.390473] usbcore: registered new device driver usb
[ 0.392742] omap_i2c 44e0b000.i2c: could not find pctldev for node /pinmux@44e10800/pinmux_i2c0_pins, deferring probe
[ 0.392804] platform 44e0b000.i2c: Driver omap_i2c requests probe deferral
[ 0.393684] pps_core: LinuxPPS API ver. 1 registered
[ 0.393708] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>
[ 0.394037] PTP clock support registered
[ 0.398918] omap-mailbox 480c8000.mailbox: omap mailbox rev 0x400
[ 0.400862] Advanced Linux Sound Architecture Driver Initialized.
[ 0.404488] ------------[ cut here ]------------
[ 0.404568] WARNING: CPU: 0 PID: 1 at net/wireless/reg.c:481 regulatory_init+0x78/0x13c()
[ 0.404588] db.txt is empty, you should update it...
[ 0.404607] Modules linked in:
[ 0.404653] CPU: 0 PID: 1 Comm: swapper Not tainted 3.14.43-g875c69b #1
[ 0.404671] Backtrace:
[ 0.404741] [<c0011240>] (dump_backtrace) from [<c00113dc>] (show_stack+0x18/0x1c)
[ 0.404761] r6:000001e1 r5:00000009 r4:dd87fe40 r3:00000000
[ 0.404825] [<c00113c4>] (show_stack) from [<c06428ac>] (dump_stack+0x20/0x28)
[ 0.404883] [<c064288c>] (dump_stack) from [<c0034ffc>] (warn_slowpath_common+0x6c/0x8c)
[ 0.404923] [<c0034f90>] (warn_slowpath_common) from [<c00350c0>] (warn_slowpath_fmt+0x38/0x40)
[ 0.404940] r8:c08c080c r7:000000eb r6:00000000 r5:c095b3b8 r4:c09210a6
[ 0.405003] [<c003508c>] (warn_slowpath_fmt) from [<c08c0968>] (regulatory_init+0x78/0x13c)
[ 0.405020] r3:00000000 r2:c084eaac
[ 0.405060] [<c08c08f0>] (regulatory_init) from [<c08c086c>] (cfg80211_init+0x60/0xe4)
[ 0.405077] r4:00000000
[ 0.405111] [<c08c080c>] (cfg80211_init) from [<c0008858>] (do_one_initcall+0xf4/0x150)
[ 0.405128] r5:00000004 r4:c08d21b4
[ 0.405182] [<c0008764>] (do_one_initcall) from [<c0891bf0>] (kernel_init_freeable+0xf0/0x1b8)
[ 0.405199] r10:c08c5740 r9:c08c572c r8:c08914d0 r7:000000eb r6:c09213c0 r5:00000004
[ 0.405245] r4:c08d21b4
[ 0.405296] [<c0891b00>] (kernel_init_freeable) from [<c063d8e0>] (kernel_init+0x14/0xf4)
[ 0.405314] r10:00000000 r9:00000000 r8:00000000 r7:00000000 r6:00000000 r5:c063d8cc
[ 0.405357] r4:c09213c0
[ 0.405395] [<c063d8cc>] (kernel_init) from [<c000e818>] (ret_from_fork+0x14/0x3c)
[ 0.405412] r4:00000000 r3:dd87e000
[ 0.405511] ---[ end trace ee58c80f8b402b58 ]---
[ 0.405584] cfg80211: Calling CRDA to update world regulatory domain
[ 0.408116] Switched to clocksource timer1
[ 0.457984] NET: Registered protocol family 2
[ 0.460195] TCP established hash table entries: 4096 (order: 2, 16384 bytes)
[ 0.460329] TCP bind hash table entries: 4096 (order: 2, 16384 bytes)
[ 0.460450] TCP: Hash tables configured (established 4096 bind 4096)
[ 0.460594] TCP: reno registered
[ 0.460624] UDP hash table entries: 256 (order: 0, 4096 bytes)
[ 0.460674] UDP-Lite hash table entries: 256 (order: 0, 4096 bytes)
[ 0.461102] NET: Registered protocol family 1
[ 0.461997] RPC: Registered named UNIX socket transport module.
[ 0.462029] RPC: Registered udp transport module.
[ 0.462045] RPC: Registered tcp transport module.
[ 0.462062] RPC: Registered tcp NFSv4.1 backchannel transport module.
[ 0.464405] hw perfevents: enabled with ARMv7 Cortex-A8 PMU driver, 5 counters available
[ 0.470830] futex hash table entries: 256 (order: -1, 3072 bytes)
[ 0.939659] VFS: Disk quotas dquot_6.5.2
[ 0.939804] Dquot-cache hash table entries: 1024 (order 0, 4096 bytes)
[ 0.940979] NFS: Registering the id_resolver key type
[ 0.941151] Key type id_resolver registered
[ 0.941173] Key type id_legacy registered
[ 0.941262] jffs2: version 2.2. (NAND) (SUMMARY) © 2001-2006 Red Hat, Inc.
[ 0.941680] msgmni has been set to 994
[ 0.945233] NET: Registered protocol family 38
[ 0.945326] io scheduler noop registered
[ 0.945346] io scheduler deadline registered
[ 0.945397] io scheduler cfq registered (default)
[ 0.949318] pinctrl-single 44e10800.pinmux: 142 pins at pa f9e10800 size 568
[ 0.962324] Serial: 8250/16550 driver, 10 ports, IRQ sharing enabled
[ 0.975550] 44e09000.serial: ttyS0 at MMIO 0x44e09000 (irq = 88, base_baud = 3000000) is a 8250
[ 1.769693] console [ttyS0] enabled
[ 1.777312] omap_rng 48310000.rng: OMAP Random Number Generator ver. 20
[ 1.784975] [drm] Initialized drm 1.1.0 20060810
[ 1.817102] brd: module loaded
[ 1.834255] loop: module loaded
[ 1.838326] (hci_tty): inside hci_tty_init
[ 1.843521] (hci_tty): allocated 250, 0
[ 1.854691] mtdoops: mtd device (mtddev=name/number) must be supplied
[ 1.871754] usbcore: registered new interface driver asix
[ 1.877743] usbcore: registered new interface driver ax88179_178a
[ 1.884331] usbcore: registered new interface driver cdc_ether
[ 1.890751] usbcore: registered new interface driver smsc95xx
[ 1.896995] usbcore: registered new interface driver net1080
[ 1.903097] usbcore: registered new interface driver cdc_subset
[ 1.909522] usbcore: registered new interface driver zaurus
[ 1.915782] usbcore: registered new interface driver cdc_ncm
[ 1.922748] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
[ 1.929396] ehci-pci: EHCI PCI platform driver
[ 1.934418] ehci-omap: OMAP-EHCI Host Controller driver
[ 1.940849] usbcore: registered new interface driver cdc_wdm
[ 1.947095] usbcore: registered new interface driver usb-storage
[ 1.955711] mousedev: PS/2 mouse device common for all mice
[ 1.966005] i2c-core: driver [rtc-ds1307] using legacy suspend method
[ 1.972580] i2c-core: driver [rtc-ds1307] using legacy resume method
[ 1.980758] omap_rtc 44e3e000.rtc: rtc core: registered 44e3e000.rtc as rtc0
[ 1.989888] i2c /dev entries driver
[ 1.993793] Driver for 1-wire Dallas network protocol.
[ 2.004297] omap_wdt: OMAP Watchdog Timer Rev 0x01: initial timeout 60 sec
[ 2.015595] omap_hsmmc 48060000.mmc: unable to get vmmc regulator -517
[ 2.022801] platform 48060000.mmc: Driver omap_hsmmc requests probe deferral
[ 2.034932] ledtrig-cpu: registered to indicate activity on CPUs
[ 2.041835] omap-aes 53500000.aes: OMAP AES hw accel rev: 3.2
[ 2.050945] omap-sham 53100000.sham: hw accel on OMAP rev 4.3
[ 2.060373] usbcore: registered new interface driver usbhid
[ 2.065998] usbhid: USB HID core driver
[ 2.070501] platform 44d00000.wkup_m3: Driver wkup_m3 requests probe deferral
[ 2.084868] oprofile: using arm/armv7
[ 2.089437] TCP: cubic registered
[ 2.092788] Initializing XFRM netlink socket
[ 2.097118] NET: Registered protocol family 17
[ 2.101760] NET: Registered protocol family 15
[ 2.106501] Key type dns_resolver registered
[ 2.112417] cpu cpu0: of_pm_voltdm_notifier_register: Failed to get cpu0 regulator/voltdm: -517
[ 2.121285] cpu cpu0: cpu0 clock notifier not ready, retry
[ 2.127007] platform cpufreq-cpu0.0: Driver cpufreq-cpu0 requests probe deferral
[ 2.135842] PM: bootloader does not support rtc-only!
[ 2.141187] ThumbEE CPU extension supported.
[ 2.145556] Registering SWP/SWPB emulation handler
[ 2.153860] vmmcsd_fixed: disabling
[ 2.157400] regulator-dummy: disabling
[ 2.162859] Error: Driver 'tfp410' is already registered, aborting...
[ 2.177909] DCDC1: at 1350 mV
[ 2.183064] vdd_mpu: 925 <--> 1375 mV at 1100 mV
[ 2.189792] vdd_core: 925 <--> 1150 mV at 1100 mV
[ 2.196416] LDO1: at 1800 mV
[ 2.201318] LDO2: at 3300 mV
[ 2.206157] LDO3: 1800 <--> 3300 mV at 1800 mV
[ 2.212600] LDO4: at 3300 mV
[ 2.217623] tps65217 0-0024: TPS65217 ID 0x6 version 1.2
[ 2.223116] omap_i2c 44e0b000.i2c: bus 0 rev0.11 at 400 kHz
[ 2.269542] remoteproc0: wkup_m3 is available
[ 2.274051] remoteproc0: Note: remoteproc is still under development and considered experimental.
[ 2.283156] remoteproc0: THE BINARY FORMAT IS NOT YET FINALIZED, and backward compatibility isn't yet guaranteed.
[ 2.294979] remoteproc0: Direct firmware load failed with error -2
[ 2.301354] remoteproc0: Falling back to user helper
[ 2.307409] cpu cpu0: of_pm_voltdm_notifier_register: Fail calculating voltage latency[1100000<->1260000]:-22
[ 2.388245] davinci_mdio 4a101000.mdio: davinci mdio revision 1.6
[ 2.394398] davinci_mdio 4a101000.mdio: no live phy, scanning all
[ 2.401030] davinci_mdio: probe of 4a101000.mdio failed with error -5
[ 2.408997] cpsw 4a100000.ethernet: Detected MACID = 84:eb:18:9f:b0:8e
[ 2.433528] omap_rtc 44e3e000.rtc: setting system clock to 2000-01-01 00:00:00 UTC (946684800)
[ 2.446128] ALSA device list:
[ 2.449236] No soundcards found.
[ 2.453502] Waiting for root device /dev/mmcblk0p2...
[ 62.409185] remoteproc0: failed to load am335x-pm-firmware.elf
[ 62.415215] remoteproc0: powering up wkup_m3
[ 62.419995] remoteproc0: Direct firmware load failed with error -2
[ 62.426315] remoteproc0: Falling back to user helper
[ 122.568830] remoteproc0: request_firmware failed: -2
[ 122.573952] wkup_m3 44d00000.wkup_m3: rproc_boot failed


then hangs....


Thanks in advance

Raghu Ramaraj

Have you fixed the issue?