• General
  • Bela Mini : PRU interrupt timeout

Hi Bela,

I have a strange problem. When I try to launch code on one of my Bela Mini's I get the following error:

PRU interrupt timeout, -1 110 Connection timed out
PRU interrupt timeout, -1 110 Connection timed out
PRU interrupt timeout, -1 110 Connection timed out
PRU interrupt timeout, -1 110 Connection timed out
McASP error, abort
PRU interrupt timeout, -1 110 Connection timed out

The weird thing is that if I move the SD card to my other Bela Mini, it works fine.

I see that this has happened in the past also... Any ideas ?

Best,
Hjalte

It would seem like a hardware failure. Is there anything connected to that BelaMini?

No it does not work with nothing connected...
But it seems that I sometimes can get it to work by touching the Bela.... like just connecting a audio connector, removing one or pushing the side of the pin headers slightly, etc.... suggests that it is a hardware issue i guess...

I have attached a dmesg if that is of any use...

[    0.000000] Booting Linux on physical CPU 0x0
[    0.000000] Initializing cgroup subsys cpuset
[    0.000000] Initializing cgroup subsys cpu
[    0.000000] Initializing cgroup subsys cpuacct
[    0.000000] Linux version 4.4.113-ti-xenomai-r149 (root@giulio-VirtualBox) (gcc version 5.5.0 (Linaro GCC 5.5-2017.10) ) #1 PREEMPT Wed Sep 26 15:40:33 BST 2018
[    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 PocketBeagle
[    0.000000] Memory policy: Data cache writeback
[    0.000000] On node 0 totalpages: 131072
[    0.000000] free_area_init_node: node 0, pgdat c100bb04, node_mem_map dfb79000
[    0.000000]   Normal zone: 1152 pages used for memmap
[    0.000000]   Normal zone: 0 pages reserved
[    0.000000]   Normal zone: 131072 pages, LIFO batch:31
[    0.000000] CPU: All CPU(s) started in SVC mode.
[    0.000000] AM335X ES2.1 (sgx neon )
[    0.000000] pcpu-alloc: s0 r0 d32768 u32768 alloc=1*32768
[    0.000000] pcpu-alloc: [0] 0 
[    0.000000] Built 1 zonelists in Zone order, mobility grouping on.  Total pages: 129920
[    0.000000] Kernel command line: console=ttyS0,115200n8 root=/dev/mmcblk0p2 ro rootfstype=ext4 rootwait coherent_pool=1M net.ifnames=0 quiet
[    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: 500720K/524288K available (11518K kernel code, 872K rwdata, 3656K rodata, 456K init, 967K bss, 23568K reserved, 0K cma-reserved, 0K highmem)
[    0.000000] Virtual kernel memory layout:
                   vector  : 0xffff0000 - 0xffff1000   (   4 kB)
                   fixmap  : 0xffc00000 - 0xfff00000   (3072 kB)
                   vmalloc : 0xe0800000 - 0xff800000   ( 496 MB)
                   lowmem  : 0xc0000000 - 0xe0000000   ( 512 MB)
                   pkmap   : 0xbfe00000 - 0xc0000000   (   2 MB)
                   modules : 0xbf000000 - 0xbfe00000   (  14 MB)
                     .text : 0xc0008000 - 0xc0ed9b68   (15175 kB)
                     .init : 0xc0eda000 - 0xc0f4c000   ( 456 kB)
                     .data : 0xc0f4c000 - 0xc10260bc   ( 873 kB)
                      .bss : 0xc1029000 - 0xc111ae04   ( 968 kB)
[    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1
[    0.000000] Preemptible hierarchical RCU implementation.
[    0.000000] 	Build-time adjustment of leaf fanout to 32.
[    0.000000] NR_IRQS:16 nr_irqs:16 16
[    0.000000] IRQ: Found an INTC at 0xfa200000 (revision 5.0) with 128 interrupts
[    0.000000] OMAP clockevent source: timer2 at 24000000 Hz
[    0.000021] sched_clock: 32 bits at 24MHz, resolution 41ns, wraps every 89478484971ns
[    0.000050] I-pipe, 24.000 MHz clocksource, wrap in 178956 ms
[    0.000072] clocksource: ipipe_tsc: mask: 0xffffffffffffffff max_cycles: 0x588fe9dc0, max_idle_ns: 440795202592 ns
[    0.000099] clocksource: timer1: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 79635851949 ns
[    0.000110] OMAP clocksource: timer1 at 24000000 Hz
[    0.002144] clocksource_probe: no matching clocksources found
[    0.002706] Interrupt pipeline (release #9)
[    0.003024] Console: colour dummy device 80x30
[    0.003076] Calibrating delay loop... 995.32 BogoMIPS (lpj=1990656)
[    0.020723] pid_max: default: 32768 minimum: 301
[    0.020874] Security Framework initialized
[    0.020890] Yama: becoming mindful.
[    0.020933] AppArmor: AppArmor disabled by boot time parameter
[    0.020981] Mount-cache hash table entries: 1024 (order: 0, 4096 bytes)
[    0.020995] Mountpoint-cache hash table entries: 1024 (order: 0, 4096 bytes)
[    0.021886] Initializing cgroup subsys io
[    0.021922] Initializing cgroup subsys memory
[    0.021978] Initializing cgroup subsys devices
[    0.021998] Initializing cgroup subsys freezer
[    0.022017] Initializing cgroup subsys net_cls
[    0.022034] Initializing cgroup subsys perf_event
[    0.022052] Initializing cgroup subsys net_prio
[    0.022076] Initializing cgroup subsys pids
[    0.022138] CPU: Testing write buffer coherency: ok
[    0.022661] Setting up static identity map for 0x80008200 - 0x80008260
[    0.028131] devtmpfs: initialized
[    0.081833] VFP support v0.3: implementor 41 architecture 3 part 30 variant c rev 3
[    0.171389] omap_hwmod: debugss: _wait_target_disable failed
[    0.225986] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns
[    0.226023] futex hash table entries: 256 (order: -1, 3072 bytes)
[    0.226669] xor: measuring software checksum speed
[    0.264743]    arm4regs  :  1146.000 MB/sec
[    0.304695]    8regs     :   909.000 MB/sec
[    0.344691]    32regs    :  1028.000 MB/sec
[    0.384694]    neon      :  1377.000 MB/sec
[    0.384705] xor: using function: neon (1377.000 MB/sec)
[    0.384751] pinctrl core: initialized pinctrl subsystem
[    0.387652] NET: Registered protocol family 16
[    0.389444] DMA: preallocated 1024 KiB pool for atomic coherent allocations
[    0.399604] OMAP GPIO hardware version 0.1
[    0.426292] No ATAGs?
[    0.426334] hw-breakpoint: debug architecture 0x4 unsupported.
[    0.520754] raid6: neonx2   gen()  1849 MB/s
[    0.588701] raid6: neonx2   xor()  1137 MB/s
[    0.588714] raid6: using algorithm neonx2 gen() 1849 MB/s
[    0.588724] raid6: .... xor() 1137 MB/s, rmw enabled
[    0.588733] raid6: using intx1 recovery algorithm
[    0.601805] edma 49000000.edma: TI EDMA DMA engine driver
[    0.613077] SCSI subsystem initialized
[    0.613532] libata version 3.00 loaded.
[    0.613976] usbcore: registered new interface driver usbfs
[    0.614069] usbcore: registered new interface driver hub
[    0.614190] usbcore: registered new device driver usb
[    0.615232] omap_i2c 44e0b000.i2c: could not find pctldev for node /ocp/l4_wkup@44c00000/scm@210000/pinmux@800/pinmux_i2c0_pins, deferring probe
[    0.615310] omap_i2c 4802a000.i2c: could not find pctldev for node /ocp/l4_wkup@44c00000/scm@210000/pinmux@800/pinmux_i2c1_pins, deferring probe
[    0.616244] omap_i2c 4819c000.i2c: bus 2 rev0.11 at 400 kHz
[    0.616666] media: Linux media interface: v0.10
[    0.616856] Linux video capture interface: v2.00
[    0.616985] pps_core: LinuxPPS API ver. 1 registered
[    0.616997] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>
[    0.617033] PTP clock support registered
[    0.618189] omap-mailbox 480c8000.mailbox: omap mailbox rev 0x400
[    0.619881] NetLabel: Initializing
[    0.619902] NetLabel:  domain hash size = 128
[    0.619911] NetLabel:  protocols = UNLABELED CIPSOv4
[    0.619999] NetLabel:  unlabeled traffic allowed by default
[    0.620424] clocksource: Switched to clocksource ipipe_tsc
[    0.787485] NET: Registered protocol family 2
[    0.788591] TCP established hash table entries: 4096 (order: 2, 16384 bytes)
[    0.788643] TCP bind hash table entries: 4096 (order: 2, 16384 bytes)
[    0.788685] TCP: Hash tables configured (established 4096 bind 4096)
[    0.788779] UDP hash table entries: 256 (order: 0, 4096 bytes)
[    0.788800] UDP-Lite hash table entries: 256 (order: 0, 4096 bytes)
[    0.789048] NET: Registered protocol family 1
[    0.789738] RPC: Registered named UNIX socket transport module.
[    0.789757] RPC: Registered udp transport module.
[    0.789767] RPC: Registered tcp transport module.
[    0.789776] RPC: Registered tcp NFSv4.1 backchannel transport module.
[    0.790928] hw perfevents: enabled with armv7_cortex_a8 PMU driver, 5 counters available
[    0.794806] audit: initializing netlink subsys (disabled)
[    0.794921] audit: type=2000 audit(0.792:1): initialized
[    0.795859] [Xenomai] scheduling class idle registered.
[    0.795874] [Xenomai] scheduling class rt registered.
[    0.796039] I-pipe: head domain Xenomai registered.
[    0.805400] [Xenomai] Cobalt v3.0.6 (Stellar Parallax) [DEBUG]
[    0.818150] zbud: loaded
[    0.818967] VFS: Disk quotas dquot_6.6.0
[    0.819283] VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes)
[    0.822837] NFS: Registering the id_resolver key type
[    0.822925] Key type id_resolver registered
[    0.822936] Key type id_legacy registered
[    0.822973] nfs4filelayout_init: NFSv4 File Layout Driver Registering...
[    0.823436] fuse init (API version 7.23)
[    0.824254] SGI XFS with ACLs, security attributes, realtime, no debug enabled
[    0.835606] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247)
[    0.835900] io scheduler noop registered
[    0.835923] io scheduler deadline registered
[    0.836021] io scheduler cfq registered (default)
[    0.838493] pinctrl-single 44e10800.pinmux: 142 pins at pa f9e10800 size 568
[    0.839830] gpio-of-helper ocp:cape-universal: Allocated GPIO id=0
[    0.840041] gpio-of-helper ocp:cape-universal: Allocated GPIO id=1
[    0.840271] gpio-of-helper ocp:cape-universal: Allocated GPIO id=2
[    0.840703] gpio-of-helper ocp:cape-universal: Allocated GPIO id=3
[    0.840921] gpio-of-helper ocp:cape-universal: Allocated GPIO id=4
[    0.841105] gpio-of-helper ocp:cape-universal: Allocated GPIO id=5
[    0.841295] gpio-of-helper ocp:cape-universal: Allocated GPIO id=6
[    0.841481] gpio-of-helper ocp:cape-universal: Allocated GPIO id=7
[    0.841663] gpio-of-helper ocp:cape-universal: Allocated GPIO id=8
[    0.841906] gpio-of-helper ocp:cape-universal: Allocated GPIO id=9
[    0.842122] gpio-of-helper ocp:cape-universal: Allocated GPIO id=10
[    0.842317] gpio-of-helper ocp:cape-universal: Allocated GPIO id=11
[    0.842511] gpio-of-helper ocp:cape-universal: Allocated GPIO id=12
[    0.842696] gpio-of-helper ocp:cape-universal: Allocated GPIO id=13
[    0.842884] gpio-of-helper ocp:cape-universal: Allocated GPIO id=14
[    0.843071] gpio-of-helper ocp:cape-universal: Allocated GPIO id=15
[    0.843258] gpio-of-helper ocp:cape-universal: Allocated GPIO id=16
[    0.843454] gpio-of-helper ocp:cape-universal: Allocated GPIO id=17
[    0.843644] gpio-of-helper ocp:cape-universal: Allocated GPIO id=18
[    0.843854] gpio-of-helper ocp:cape-universal: Allocated GPIO id=19
[    0.844045] gpio-of-helper ocp:cape-universal: Allocated GPIO id=20
[    0.844231] gpio-of-helper ocp:cape-universal: Allocated GPIO id=21
[    0.844940] gpio-of-helper ocp:cape-universal: Allocated GPIO id=22
[    0.845168] gpio-of-helper ocp:cape-universal: Allocated GPIO id=23
[    0.845362] gpio-of-helper ocp:cape-universal: Allocated GPIO id=24
[    0.845563] gpio-of-helper ocp:cape-universal: Allocated GPIO id=25
[    0.845757] gpio-of-helper ocp:cape-universal: Allocated GPIO id=26
[    0.845951] gpio-of-helper ocp:cape-universal: Allocated GPIO id=27
[    0.846148] gpio-of-helper ocp:cape-universal: Allocated GPIO id=28
[    0.846335] gpio-of-helper ocp:cape-universal: Allocated GPIO id=29
[    0.846531] gpio-of-helper ocp:cape-universal: Allocated GPIO id=30
[    0.846721] gpio-of-helper ocp:cape-universal: Allocated GPIO id=31
[    0.846909] gpio-of-helper ocp:cape-universal: Allocated GPIO id=32
[    0.847107] gpio-of-helper ocp:cape-universal: Allocated GPIO id=33
[    0.847297] gpio-of-helper ocp:cape-universal: Allocated GPIO id=34
[    0.847493] gpio-of-helper ocp:cape-universal: Allocated GPIO id=35
[    0.847691] gpio-of-helper ocp:cape-universal: Allocated GPIO id=36
[    0.847888] gpio-of-helper ocp:cape-universal: Allocated GPIO id=37
[    0.848090] gpio-of-helper ocp:cape-universal: Allocated GPIO id=38
[    0.848284] gpio-of-helper ocp:cape-universal: Allocated GPIO id=39
[    0.848671] gpio-of-helper ocp:cape-universal: Allocated GPIO id=40
[    0.848896] gpio-of-helper ocp:cape-universal: Allocated GPIO id=41
[    0.849092] gpio-of-helper ocp:cape-universal: Allocated GPIO id=42
[    0.849289] gpio-of-helper ocp:cape-universal: Allocated GPIO id=43
[    0.849303] gpio-of-helper ocp:cape-universal: ready
[    0.853898] wkup_m3_ipc 44e11324.wkup_m3_ipc: could not get rproc handle
[    0.856287] Serial: 8250/16550 driver, 6 ports, IRQ sharing disabled
[    0.860367] console [ttyS0] disabled
[    0.860640] 44e09000.serial: ttyS0 at MMIO 0x44e09000 (irq = 158, base_baud = 3000000) is a 8250
[    0.874029] console [ttyS0] enabled
[    0.875145] 48022000.serial: ttyS1 at MMIO 0x48022000 (irq = 159, base_baud = 3000000) is a 8250
[    0.876152] 48024000.serial: ttyS2 at MMIO 0x48024000 (irq = 160, base_baud = 3000000) is a 8250
[    0.877291] 481a8000.serial: ttyS4 at MMIO 0x481a8000 (irq = 161, base_baud = 3000000) is a 8250
[    0.879762] [drm] Initialized drm 1.1.0 20060810
[    0.909177] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
[    0.909244] ehci-platform: EHCI generic platform driver
[    0.909657] ehci-omap: OMAP-EHCI Host Controller driver
[    0.910491] usbcore: registered new interface driver usb-storage
[    0.914213] 47401300.usb-phy supply vcc not found, using dummy regulator
[    0.917443] musb-hdrc: ConfigData=0xde (UTMI-8, dyn FIFOs, bulk combine, bulk split, HB-ISO Rx, HB-ISO Tx, SoftConn)
[    0.917471] musb-hdrc: MHDRC RTL version 2.0 
[    0.917482] musb-hdrc: setup fifo_mode 4
[    0.917511] musb-hdrc: 28/31 max ep, 16384/16384 memory
[    0.917703] musb-hdrc musb-hdrc.0.auto: MUSB HDRC host driver
[    0.917747] musb-hdrc musb-hdrc.0.auto: new USB bus registered, assigned bus number 1
[    0.918121] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002
[    0.918139] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    0.918151] usb usb1: Product: MUSB HDRC host driver
[    0.918163] usb usb1: Manufacturer: Linux 4.4.113-ti-xenomai-r149 musb-hcd
[    0.918174] usb usb1: SerialNumber: musb-hdrc.0.auto
[    0.919429] hub 1-0:1.0: USB hub found
[    0.919517] hub 1-0:1.0: 1 port detected
[    0.922689] 47401b00.usb-phy supply vcc not found, using dummy regulator
[    0.925853] musb-hdrc: ConfigData=0xde (UTMI-8, dyn FIFOs, bulk combine, bulk split, HB-ISO Rx, HB-ISO Tx, SoftConn)
[    0.925881] musb-hdrc: MHDRC RTL version 2.0 
[    0.925892] musb-hdrc: setup fifo_mode 4
[    0.925917] musb-hdrc: 28/31 max ep, 16384/16384 memory
[    0.926093] musb-hdrc musb-hdrc.1.auto: MUSB HDRC host driver
[    0.926135] musb-hdrc musb-hdrc.1.auto: new USB bus registered, assigned bus number 2
[    0.926488] usb usb2: New USB device found, idVendor=1d6b, idProduct=0002
[    0.926505] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    0.926518] usb usb2: Product: MUSB HDRC host driver
[    0.926529] usb usb2: Manufacturer: Linux 4.4.113-ti-xenomai-r149 musb-hcd
[    0.926542] usb usb2: SerialNumber: musb-hdrc.1.auto
[    0.927759] hub 2-0:1.0: USB hub found
[    0.927838] hub 2-0:1.0: 1 port detected
[    0.940314] mousedev: PS/2 mouse device common for all mice
[    0.944067] omap_rtc 44e3e000.rtc: rtc core: registered 44e3e000.rtc as rtc0
[    0.945746] i2c /dev entries driver
[    0.948313] omap_wdt: OMAP Watchdog Timer Rev 0x01: initial timeout 60 sec
[    0.949961] omap_hsmmc 48060000.mmc: Got CD GPIO
[    0.982212] pinctrl-single 44e10800.pinmux: pin 44e10830.0 already requested by ocp:P2_24_pinmux; cannot claim for bela_digitals
[    0.994110] pinctrl-single 44e10800.pinmux: pin-12 (bela_digitals) status -22
[    1.001416] pinctrl-single 44e10800.pinmux: could not request pin 12 (44e10830.0) from group pinmux_bb_digitals  on device pinctrl-single
[    1.014926] leds-gpio bela_digitals: Error applying setting, reverse things back
[    1.030951] hidraw: raw HID events driver (C) Jiri Kosina
[    1.031907] usbcore: registered new interface driver usbhid
[    1.031924] usbhid: USB HID core driver
[    1.032315] ashmem: initialized
[    1.033703]  remoteproc0: wkup_m3 is available
[    1.033726]  remoteproc0: Note: remoteproc is still under development and considered experimental.
[    1.033737]  remoteproc0: THE BINARY FORMAT IS NOT YET FINALIZED, and backward compatibility isn't yet guaranteed.
[    1.039740] NET: Registered protocol family 10
[    1.041499] mip6: Mobile IPv6
[    1.041535] NET: Registered protocol family 17
[    1.041715] Key type dns_resolver registered
[    1.041736] mpls_gso: MPLS GSO support
[    1.042078] omap_voltage_late_init: Voltage driver support not added
[    1.049776] PM: Cannot get wkup_m3_ipc handle
[    1.054441] ThumbEE CPU extension supported.
[    1.056019] registered taskstats version 1
[    1.056248] zswap: loaded using pool lzo/zbud
[    1.061489] mmc0: host does not support reading read-only switch, assuming write-enable
[    1.061591] Btrfs loaded
[    1.063445] mmc0: new high speed SDHC card at address 0007
[    1.065422] mmcblk0: mmc0:0007 SD32G 29.0 GiB 
[    1.066635] Key type encrypted registered
[    1.068126]  mmcblk0: p1 p2
[    1.073230] input: tps65217_pwr_but as /devices/platform/ocp/44e0b000.i2c/i2c-0/0-0024/input/input0
[    1.100343] tps65217 0-0024: TPS65217 ID 0xe version 1.2
[    1.101697] at24 0-0050: 32768 byte 24c256 EEPROM, writable, 1 bytes/write
[    1.101768] omap_i2c 44e0b000.i2c: bus 0 rev0.11 at 400 kHz
[    1.103717] omap_i2c 4802a000.i2c: bus 1 rev0.11 at 400 kHz
[    1.105427] PM: bootloader does not support rtc-only!
[    1.106690] omap_rtc 44e3e000.rtc: setting system clock to 2000-01-01 00:00:00 UTC (946684800)
[    1.106728] of_cfs_init
[    1.106883] of_cfs_init: OK
[    1.112572]  remoteproc0: powering up wkup_m3
[    1.112618]  remoteproc0: Booting fw image am335x-pm-firmware.elf, size 217148
[    1.112900]  remoteproc0: remote processor wkup_m3 is now up
[    1.112926] wkup_m3_ipc 44e11324.wkup_m3_ipc: CM3 Firmware Version = 0x192
[    1.115924] PM: Hibernation image not present or could not be loaded.
[    1.120904] EXT4-fs (mmcblk0p2): INFO: recovery required on readonly filesystem
[    1.120934] EXT4-fs (mmcblk0p2): write access will be enabled during recovery
[    1.304424] EXT4-fs (mmcblk0p2): recovery complete
[    1.306434] EXT4-fs (mmcblk0p2): mounted filesystem with ordered data mode. Opts: (null)
[    1.306559] VFS: Mounted root (ext4 filesystem) readonly on device 179:2.
[    1.307887] devtmpfs: mounted
[    1.308568] Freeing unused kernel memory: 456K
[    1.316499] usb 2-1: new high-speed USB device number 2 using musb-hdrc
[    1.445199] usb 2-1: New USB device found, idVendor=0bda, idProduct=a811
[    1.445229] usb 2-1: New USB device strings: Mfr=1, Product=2, SerialNumber=3
[    1.445243] usb 2-1: Product: 802.11ac WLAN Adapter 
[    1.445254] usb 2-1: Manufacturer: Realtek 
[    1.445266] usb 2-1: SerialNumber: 00e04c000001
[    1.717181] systemd[1]: System time before build time, advancing clock.
[    1.818504] ip_tables: (C) 2000-2006 Netfilter Core Team
[    1.861749] random: systemd: uninitialized urandom read (16 bytes read, 32 bits of entropy available)
[    1.865515] random: systemd: uninitialized urandom read (16 bytes read, 32 bits of entropy available)
[    1.879147] systemd[1]: systemd 232 running in system mode. (+PAM +AUDIT +SELINUX +IMA +APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD +IDN)
[    1.879947] systemd[1]: Detected architecture arm.
[    1.882616] systemd[1]: Set hostname to <bela>.
[    2.115183] random: systemd-cryptse: uninitialized urandom read (16 bytes read, 34 bits of entropy available)
[    2.152922] random: systemd-sysv-ge: uninitialized urandom read (16 bytes read, 35 bits of entropy available)
[    2.166209] random: systemd-gpt-aut: uninitialized urandom read (16 bytes read, 36 bits of entropy available)
[    2.169120] random: systemd-gpt-aut: uninitialized urandom read (16 bytes read, 36 bits of entropy available)
[    2.272286] random: systemd: uninitialized urandom read (16 bytes read, 40 bits of entropy available)
[    2.272924] random: systemd: uninitialized urandom read (16 bytes read, 40 bits of entropy available)
[    2.273355] random: systemd: uninitialized urandom read (16 bytes read, 40 bits of entropy available)
[    2.274072] random: systemd: uninitialized urandom read (16 bytes read, 40 bits of entropy available)
[    2.570318] systemd[1]: Listening on Journal Socket (/dev/log).
[    2.571370] systemd[1]: Listening on /dev/initctl Compatibility Named Pipe.
[    2.571972] systemd[1]: Started Forward Password Requests to Wall Directory Watch.
[    2.572777] systemd[1]: Listening on udev Kernel Socket.
[    2.573355] systemd[1]: Listening on udev Control Socket.
[    2.575117] systemd[1]: Created slice System Slice.
[    2.575448] systemd[1]: Reached target Swap.
[    2.576077] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.
[    2.576950] systemd[1]: Listening on Journal Socket.
[    2.583365] systemd[1]: Starting Create list of required static device nodes for the current kernel...
[    2.607094] systemd[1]: Starting Load Kernel Modules...
[    2.666569] systemd[1]: Mounting POSIX Message Queue File System...
[    2.667394] systemd[1]: Listening on Syslog Socket.
[    2.667802] systemd[1]: Reached target Paths.
[    2.722138] systemd[1]: Created slice system-serial\x2dgetty.slice.
[    2.723890] systemd[1]: Created slice system-getty.slice.
[    2.754179] systemd[1]: Listening on Journal Audit Socket.
[    2.831306] systemd[1]: Starting Journal Service...
[    2.838824] rtdm_pruss_irq: loading out-of-tree module taints kernel.
[    2.839755] rtdm_pruss_irq version 1 loaded
[    2.885604] systemd[1]: Listening on fsck to fsckd communication Socket.
[    2.885990] systemd[1]: Reached target Remote File Systems.
[    2.961516] systemd[1]: Mounting Debug File System...
[    2.963967] systemd[1]: Set up automount Arbitrary Executable File Formats File System Automount Point.
[    3.054592] systemd[1]: Starting File System Check on Root Device...
[    3.055291] systemd[1]: Reached target Encrypted Volumes.
[    3.093975] systemd[1]: Created slice system-systemd\x2dfsck.slice.
[    3.095620] systemd[1]: Created slice User and Session Slice.
[    3.095923] systemd[1]: Reached target Slices.
[    3.233548] systemd[1]: Mounted Debug File System.
[    3.233979] systemd[1]: Mounted POSIX Message Queue File System.
[    3.245869] systemd[1]: Started Create list of required static device nodes for the current kernel.
[    3.271330] systemd[1]: Started Load Kernel Modules.
[    3.341858] systemd[1]: Started File System Check Daemon to report status.
[    3.425070] systemd[1]: Starting Apply Kernel Variables...
[    3.483529] systemd[1]: Mounting Configuration File System...
[    3.617924] systemd[1]: Mounting FUSE Control File System...
[    3.726234] systemd[1]: Starting Create Static Device Nodes in /dev...
[    3.859724] systemd[1]: Mounted Configuration File System.
[    3.910997] systemd[1]: Started File System Check on Root Device.
[    3.949156] systemd[1]: Started Apply Kernel Variables.
[    3.969460] systemd[1]: Mounted FUSE Control File System.
[    4.019787] systemd[1]: Started Create Static Device Nodes in /dev.
[    4.063604] systemd[1]: Starting udev Kernel Device Manager...
[    4.114504] systemd[1]: Starting Remount Root and Kernel File Systems...
[    4.214774] systemd[1]: Started Journal Service.
[    4.339302] EXT4-fs (mmcblk0p2): re-mounted. Opts: (null)
[    4.782202] systemd-journald[101]: Received request to flush runtime journal from PID 1
[    7.298722] CAN device driver interface
[    7.356675] c_can_platform 481cc000.can: c_can_platform device registered (regs=fa1cc000, irq=167)
[    7.364445] c_can_platform 481d0000.can: c_can_platform device registered (regs=fa1d0000, irq=168)
[    7.527072] omap_rng 48310000.rng: OMAP Random Number Generator ver. 20
[    7.959235] RTL871X: module init start
[    7.959270] RTL871X: rtl8812au v4.3.14_13455.20150212_BTCOEX20150128-51
[    7.959281] RTL871X: rtl8812au BT-Coex version = BTCOEX20150128-51
[    8.337370] omap-aes 53500000.aes: OMAP AES hw accel rev: 3.2
[    8.431757] omap-sham 53100000.sham: hw accel on OMAP rev 4.3
[    9.293678] random: nonblocking pool is initialized
[    9.572607] using random self ethernet address
[    9.572643] using random host ethernet address
[    9.667116] using random self ethernet address
[    9.667151] using random host ethernet address
[    9.798382] Mass Storage Function, version: 2009/09/11
[    9.798420] LUN: removable file: (no medium)
[   10.318949] pinctrl-single 44e10800.pinmux: pin 44e10828.0 already requested by ocp:P1_34_pinmux; cannot claim for spi_gpio
[   10.508817] pinctrl-single 44e10800.pinmux: pin-10 (spi_gpio) status -22
[   10.604824] pinctrl-single 44e10800.pinmux: could not request pin 10 (44e10828.0) from group pinmux_bb_spi_gpio_pins  on device pinctrl-single
[   10.687408] RTL871X: rtw_ndev_init(wlan0)
[   10.720843] usbcore: registered new interface driver rtl8812au
[   10.720875] RTL871X: module init ret=0
[   10.759213] spi_gpio spi_gpio: Error applying setting, reverse things back
[   10.809006] spi_gpio: probe of spi_gpio failed with error -16
[   11.239472] ti_am3359-tscadc 44e0d000.tscadc: chan 0 step_avg truncating to 16
[   11.239507] ti_am3359-tscadc 44e0d000.tscadc: chan 1 step_avg truncating to 16
[   11.239521] ti_am3359-tscadc 44e0d000.tscadc: chan 2 step_avg truncating to 16
[   11.239534] ti_am3359-tscadc 44e0d000.tscadc: chan 3 step_avg truncating to 16
[   11.239547] ti_am3359-tscadc 44e0d000.tscadc: chan 4 step_avg truncating to 16
[   11.239560] ti_am3359-tscadc 44e0d000.tscadc: chan 5 step_avg truncating to 16
[   11.239573] ti_am3359-tscadc 44e0d000.tscadc: chan 6 step_avg truncating to 16
[   11.239585] ti_am3359-tscadc 44e0d000.tscadc: chan 7 step_avg truncating to 16
[   11.959340] usb0: HOST MAC be:1a:05:f7:54:39
[   11.959757] usb0: MAC be:1a:05:f7:54:3a
[   11.965725] usb1: HOST MAC be:1a:05:f7:54:3c
[   11.965969] usb1: MAC be:1a:05:f7:54:3d
[   12.988019] IPv6: ADDRCONF(NETDEV_UP): usb0: link is not ready
[   13.322317] IPv6: ADDRCONF(NETDEV_UP): usb1: link is not ready
[   14.078318] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
[   18.072077] RTL871X: rtw_set_802_11_connect(wlan0)  fw_state=0x00000008
[   18.261765] RTL871X: start auth
[   18.267549] RTL871X: auth success, start assoc
[   18.278970] RTL871X: rtw_cfg80211_indicate_connect(wlan0) BSS not found !!
[   18.279020] RTL871X: assoc success
[   18.280433] IPv6: ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready
[   18.380521] RTL871X: send eapol packet
[   18.387841] RTL871X: send eapol packet
[   18.389950] RTL871X: set pairwise key camid:4, addr:cc:32:e5:86:ca:dc, kid:0, type:AES
[   18.398241] RTL871X: set group key camid:5, addr:cc:32:e5:86:ca:dc, kid:2, type:AES
[  111.435362] rtdm_pruss_irq_open
[  111.435877] Registering PRU interrupt:
                 PRU ICSS system event: 20
                 PRU INTC channel: 4
                 PRU INTC host interrupt: 4
                 ARM IRQ number:  22
[  111.435902] rtdm_pruss_irq linux_irq: 181 for arm_irq_handle_number: 22
[  111.435911] INITING_PRU: system_event: 20, intc_channel: 4, intc_host: 4
[  111.435919] : 0XD00 = 0XFFFFFFFF [u32]
[  111.435927] : 0XD04 = 0XFFFFFFFF [u32]
[  111.435934] : 0XD80 = 0X0 [u32]
[  111.435942] : 0XD84 = 0X0 [u32]
[  111.435950] cmr_reg
[  111.435959] : 0X414 = 0X4 [u8]
[  111.435967] hmr_reg
[  111.435973] : 0X804 = 0X4 [u8]
[  111.435980] secr0
[  111.435986] : 0X280 = 0XFFFFFFFF [u32]
[  111.435994] secr1
[  111.436000] : 0X284 = 0XFFFFFFF [u32]
[  111.436007] eisr_reg
[  111.436013] : 0X28 = 0X14 [u32]
[  111.436020] hieisr_reg
[  111.436026] : 0X34 = 0X4 [u32]
[  111.436033] hier
[  111.436040] hier read: 10
[  111.436047] esr0
[  111.436053] : 0X300 = 0X100000 [u32]
[  111.436060] intc_ger
[  111.436066] : 0X10 = 0X1 [u32]
[  112.357468] rtdm_pruss_irq_close
[  112.357503] rtdm_irq_free
[  112.357515] rtdm_event_pulse
[  112.357526] rtdm_event_destroy
[  149.037289] rtdm_pruss_irq_open
[  149.037803] Registering PRU interrupt:
                 PRU ICSS system event: 20
                 PRU INTC channel: 4
                 PRU INTC host interrupt: 4
                 ARM IRQ number:  22
[  149.037830] rtdm_pruss_irq linux_irq: 181 for arm_irq_handle_number: 22
[  149.037839] INITING_PRU: system_event: 20, intc_channel: 4, intc_host: 4
[  149.037847] : 0XD00 = 0XFFFFFFFF [u32]
[  149.037854] : 0XD04 = 0XFFFFFFFF [u32]
[  149.037862] : 0XD80 = 0X0 [u32]
[  149.037869] : 0XD84 = 0X0 [u32]
[  149.037877] cmr_reg
[  149.037886] : 0X414 = 0X4 [u8]
[  149.037893] hmr_reg
[  149.037899] : 0X804 = 0X4 [u8]
[  149.037906] secr0
[  149.037913] : 0X280 = 0XFFFFFFFF [u32]
[  149.037920] secr1
[  149.037926] : 0X284 = 0XFFFFFFF [u32]
[  149.037933] eisr_reg
[  149.037939] : 0X28 = 0X14 [u32]
[  149.037946] hieisr_reg
[  149.037952] : 0X34 = 0X4 [u32]
[  149.037960] hier
[  149.037966] hier read: 10
[  149.037972] esr0
[  149.037979] : 0X300 = 0X100000 [u32]
[  149.037986] intc_ger
[  149.037992] : 0X10 = 0X1 [u32]
[  149.955830] rtdm_pruss_irq_close
[  149.955864] rtdm_irq_free
[  149.955877] rtdm_event_pulse
[  149.955886] rtdm_event_destroy
[  157.263311] rtdm_pruss_irq_open
[  157.263818] Registering PRU interrupt:
                 PRU ICSS system event: 20
                 PRU INTC channel: 4
                 PRU INTC host interrupt: 4
                 ARM IRQ number:  22
[  157.263843] rtdm_pruss_irq linux_irq: 181 for arm_irq_handle_number: 22
[  157.263853] INITING_PRU: system_event: 20, intc_channel: 4, intc_host: 4
[  157.263861] : 0XD00 = 0XFFFFFFFF [u32]
[  157.263869] : 0XD04 = 0XFFFFFFFF [u32]
[  157.263876] : 0XD80 = 0X0 [u32]
[  157.263884] : 0XD84 = 0X0 [u32]
[  157.263892] cmr_reg
[  157.263901] : 0X414 = 0X4 [u8]
[  157.263908] hmr_reg
[  157.263914] : 0X804 = 0X4 [u8]
[  157.263921] secr0
[  157.263927] : 0X280 = 0XFFFFFFFF [u32]
[  157.263934] secr1
[  157.263941] : 0X284 = 0XFFFFFFF [u32]
[  157.263948] eisr_reg
[  157.263954] : 0X28 = 0X14 [u32]
[  157.263961] hieisr_reg
[  157.263967] : 0X34 = 0X4 [u32]
[  157.263974] hier
[  157.263980] hier read: 10
[  157.263987] esr0
[  157.263993] : 0X300 = 0X100000 [u32]
[  157.264001] intc_ger
[  157.264006] : 0X10 = 0X1 [u32]
[  158.184854] rtdm_pruss_irq_close
[  158.184890] rtdm_irq_free
[  158.184903] rtdm_event_pulse
[  158.184913] rtdm_event_destroy

Best

FWIW I was getting a similar problem with a badly wired P1.36, but when I connected it correctly it went away.

Sure, I'll check the soldering and redo them if they look poor...
I'll get back to you in a couple of days

giuliomoro

I have now tried to redo all the reachable solderings. I could not see any problem by visual inspection.

Problem persists.