BBB gets stuck during bootup @ i2c i2c-2: SCL is stuck low, exit recovery

[Jelle Spijker]

Quite often my BBB gets stuck during bootup. Allways at "i2c i2c-2: SCL is stuck low, exit recovery"

When I reset a couple of times it boots up okay. 

My BBB runs from an SD card with Debian 4.1.17-ti-rt-r46.

U-Boot SPL 2015.07-rc3-00001-g2c9c20a (Jun 30 2015 - 09:02:07)

U-Boot 2015.07-rc3-00001-g2c9c20a (Jun 30 2015 - 09:02:07 -0500), Build: jenkins-github_Bootloader-Builder-173

       Watchdog enabled

I2C:   ready

DRAM:  512 MiB

MMC:   OMAP SD/MMC: 0, OMAP SD/MMC: 1

Using default environment

Net:   <ethaddr> not set. Validating first E-fuse MAC

Phy 0 not found

cpsw

Hit any key to stop autoboot:  0 

gpio: pin 53 (gpio 53) value is 1

switch to partitions #0, OK

mmc0 is current device

gpio: pin 54 (gpio 54) value is 1

Checking for: /uEnv.txt ...

Checking for: /boot.scr ...

Checking for: /boot/boot.scr ...

Checking for: /boot/uEnv.txt ...

gpio: pin 55 (gpio 55) value is 1

281 bytes read in 24 ms (10.7 KiB/s)

Loaded environment from /boot/uEnv.txt

Using: dtb=am335x-boneblack-overlay.dtb ...

Checking if uname_r is set in /boot/uEnv.txt...

gpio: pin 56 (gpio 56) value is 1

Running uname_boot ...

loading /boot/vmlinuz-4.1.17-ti-rt-r46 ...

7777608 bytes read in 461 ms (16.1 MiB/s)

loading /boot/dtbs/4.1.17-ti-rt-r46/am335x-boneblack-overlay.dtb ...

57971 bytes read in 52 ms (1.1 MiB/s)

loading /boot/initrd.img-4.1.17-ti-rt-r46 ...

5125199 bytes read in 313 ms (15.6 MiB/s)

debug: [console=ttyO0,115200n8 bone_capemgr.disable_partno= bone_capemgr.enable_partno=cape-univ-audio,cape-univ-emmc,cape-univ-hdmi root=/dev/mmcblk0p1 ro rootfstype=ext4 rootwait fixrtc] ...

debug: [bootz 0x82000000 0x88080000:4e344f 0x88000000] ...

Kernel image @ 0x82000000 [ 0x000000 - 0x76ad48 ]

## Flattened Device Tree blob at 88000000

   Booting using the fdt blob at 0x88000000

   Loading Ramdisk to 8fb1c000, end 8ffff44f ... OK

   Loading Device Tree to 8fb0a000, end 8fb1b272 ... OK

Starting kernel ...

[    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.1.17-ti-rt-r46 (peer23peer@peerLaptop) (gcc version 4.9.3 20150413 (prerelease) (Linaro GCC 4.9-2015.05) ) #1 SMP PREEMPT RT Sun Feb 7 17:10:04 CET 2016

[    0.000000] CPU: ARMv7 Processor [413fc082] revision 2 (ARMv7), cr=50c5387d

[    0.000000] CPU: PIPT / VIPT nonaliasing data cache, VIPT aliasing instruction cache

[    0.000000] Machine model: TI AM335x BeagleBone Black

[    0.000000] cma: Reserved 24 MiB at 0x9e000000

[    0.000000] Memory policy: Data cache writeback

[    0.000000] CPU: All CPU(s) started in SVC mode.

[    0.000000] AM335X ES2.1 (sgx neon )

[    0.000000] PERCPU: Embedded 13 pages/cpu @df910000 s24128 r8192 d20928 u53248

[    0.000000] Built 1 zonelists in Zone order, mobility grouping on.  Total pages: 129408

[    0.000000] Kernel command line: console=ttyO0,115200n8 bone_capemgr.disable_partno= bone_capemgr.enable_partno=cape-univ-audio,cape-univ-emmc,cape-univ-hdmi root=/dev/mmcblk0p1 ro rootfstype=ext4 rootwait fixrtc

[    0.000000] PID hash table entries: 2048 (order: 1, 8192 bytes)

[    0.000000] Dentry cache hash table entries: 65536 (order: 7, 524288 bytes)

[    0.000000] Inode-cache hash table entries: 32768 (order: 5, 131072 bytes)

[    0.000000] Memory: 473484K/522240K available (7169K kernel code, 935K rwdata, 3764K rodata, 560K init, 954K bss, 24180K reserved, 24576K cma-reserved, 0K highmem)

[    0.000000] Virtual kernel memory layout:

[    0.000000]     vector  : 0xffff0000 - 0xffff1000   (   4 kB)

[    0.000000]     fixmap  : 0xffc00000 - 0xfff00000   (3072 kB)

[    0.000000]     vmalloc : 0xe0800000 - 0xff000000   ( 488 MB)

[    0.000000]     lowmem  : 0xc0000000 - 0xe0000000   ( 512 MB)

[    0.000000]     pkmap   : 0xbfe00000 - 0xc0000000   (   2 MB)

[    0.000000]     modules : 0xbf800000 - 0xbfe00000   (   6 MB)

[    0.000000]       .text : 0xc0008000 - 0xc0ab58c8   (10935 kB)

[    0.000000]       .init : 0xc0ab6000 - 0xc0b42000   ( 560 kB)

[    0.000000]       .data : 0xc0b42000 - 0xc0c2beac   ( 936 kB)

[    0.000000]        .bss : 0xc0c2e000 - 0xc0d1caa0   ( 955 kB)

[    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1

[    0.000000] Preemptible hierarchical RCU implementation.

[    0.000000] RCU restricting CPUs from NR_CPUS=2 to nr_cpu_ids=1.

[    0.000000] RCU: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1

[    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.000012] sched_clock: 32 bits at 24MHz, resolution 41ns, wraps every 89478484971ns

[    0.000025] clocksource timer1: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 79635851949 ns

[    0.000032] OMAP clocksource: timer1 at 24000000 Hz

[    0.000226] Console: colour dummy device 80x30

[    0.000391] WARNING: Your 'console=ttyO0' has been replaced by 'ttyS0'

[    0.000394] This ensures that you still see kernel messages. Please

[    0.000397] update your kernel commandline.

[    0.088890] Calibrating delay loop... 996.14 BogoMIPS (lpj=4980736)

[    0.088897] pid_max: default: 32768 minimum: 301

[    0.089041] Security Framework initialized

[    0.089109] AppArmor: AppArmor disabled by boot time parameter

[    0.089112] Yama: becoming mindful.

[    0.089302] Mount-cache hash table entries: 1024 (order: 0, 4096 bytes)

[    0.089310] Mountpoint-cache hash table entries: 1024 (order: 0, 4096 bytes)

[    0.090223] Initializing cgroup subsys blkio

[    0.090238] Initializing cgroup subsys memory

[    0.090280] Initializing cgroup subsys devices

[    0.090294] Initializing cgroup subsys freezer

[    0.090308] Initializing cgroup subsys net_cls

[    0.090320] Initializing cgroup subsys perf_event

[    0.090331] Initializing cgroup subsys net_prio

[    0.090445] CPU: Testing write buffer coherency: ok

[    0.090453] ftrace: allocating 35031 entries in 69 pages

[    0.174578] CPU0: thread -1, cpu 0, socket -1, mpidr 0

[    0.174751] Setting up static identity map for 0x80008240 - 0x8000828c

[    0.268918] Brought up 1 CPUs

[    0.268944] SMP: Total of 1 processors activated (996.14 BogoMIPS).

[    0.268955] CPU: All CPU(s) started in SVC mode.

[    0.269892] devtmpfs: initialized

[    0.292310] VFP support v0.3: implementor 41 architecture 3 part 30 variant c rev 3

[    0.318329] omap_hwmod: debugss: _wait_target_disable failed

[    0.373438] clocksource jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns

[    0.375671] xor: measuring software checksum speed

[    0.468949]    arm4regs  :  1222.400 MB/sec

[    0.568881]    8regs     :   932.000 MB/sec

[    0.668876]    32regs    :  1093.600 MB/sec

[    0.768871]    neon      :  1682.800 MB/sec

[    0.768881] xor: using function: neon (1682.800 MB/sec)

[    0.769074] pinctrl core: initialized pinctrl subsystem

[    0.771302] NET: Registered protocol family 16

[    0.776046] DMA: preallocated 256 KiB pool for atomic coherent allocations

[    0.808918] cpuidle: using governor ladder

[    0.838909] cpuidle: using governor menu

[    0.845771] OMAP GPIO hardware version 0.1

[    0.846555] GPIO line 52 (EMMC ResetN) hogged as output/high

[    0.861382] No ATAGs?

[    0.861417] hw-breakpoint: debug architecture 0x4 unsupported.

[    0.861997] omap4_sram_init:Unable to allocate sram needed to handle errata I688

[    0.862014] omap4_sram_init:Unable to get sram pool needed to handle errata I688

[    1.078995] raid6: int32x1  gen()   242 MB/s

[    1.248919] raid6: int32x1  xor()   237 MB/s

[    1.418891] raid6: int32x2  gen()   365 MB/s

[    1.588905] raid6: int32x2  xor()   270 MB/s

[    1.758907] raid6: int32x4  gen()   398 MB/s

[    1.928997] raid6: int32x4  xor()   243 MB/s

[    2.098890] raid6: int32x8  gen()   325 MB/s

[    2.268948] raid6: int32x8  xor()   212 MB/s

[    2.438890] raid6: neonx1   gen()  1457 MB/s

[    2.608874] raid6: neonx2   gen()  1869 MB/s

[    2.778883] raid6: neonx4   gen()  2014 MB/s

[    2.948903] raid6: neonx8   gen()   894 MB/s

[    2.948913] raid6: using algorithm neonx4 gen() 2014 MB/s

[    2.948923] raid6: using intx1 recovery algorithm

[    2.962295] edma 49000000.edma: TI EDMA DMA engine driver

[    2.966212] vgaarb: loaded

[    2.967072] SCSI subsystem initialized

[    2.967900] usbcore: registered new interface driver usbfs

[    2.967995] usbcore: registered new interface driver hub

[    2.968162] usbcore: registered new device driver usb

[    2.969039] omap_i2c 44e0b000.i2c: could not find pctldev for node /ocp/l4_wkup@44c00000/scm@210000/pinmux@800/pinmux_i2c0_pins, deferring probe

[    2.969103] omap_i2c 4819c000.i2c: could not find pctldev for node /ocp/l4_wkup@44c00000/scm@210000/pinmux@800/pinmux_i2c2_pins, deferring probe

[    2.969282] pps_core: LinuxPPS API ver. 1 registered

[    2.969293] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giom...@linux.it>

[    2.969330] PTP clock support registered

[    2.970541] omap-mailbox 480c8000.mailbox: omap mailbox rev 0x400

[    2.971222] Advanced Linux Sound Architecture Driver Initialized.

[    2.972191] NetLabel: Initializing

[    2.972205] NetLabel:  domain hash size = 128

[    2.972213] NetLabel:  protocols = UNLABELED CIPSOv4

[    2.972281] NetLabel:  unlabeled traffic allowed by default

[    2.972633] Switched to clocksource timer1

[    3.141730] NET: Registered protocol family 2

[    3.142897] TCP established hash table entries: 4096 (order: 2, 16384 bytes)

[    3.142980] TCP bind hash table entries: 4096 (order: 4, 114688 bytes)

[    3.143125] TCP: Hash tables configured (established 4096 bind 4096)

[    3.143212] UDP hash table entries: 256 (order: 2, 16384 bytes)

[    3.143249] UDP-Lite hash table entries: 256 (order: 2, 16384 bytes)

[    3.143538] NET: Registered protocol family 1

[    3.144411] RPC: Registered named UNIX socket transport module.

[    3.144427] RPC: Registered udp transport module.

[    3.144436] RPC: Registered tcp transport module.

[    3.144445] RPC: Registered tcp NFSv4.1 backchannel transport module.

[    3.145137] Unpacking initramfs...

[    3.506105] Freeing initrd memory: 5008K (cfb1c000 - d0000000)

[    3.506566] CPU PMU: Failed to parse /pmu/interrupt-affinity[0]

[    3.506632] hw perfevents: enabled with armv7_cortex_a8 PMU driver, 5 counters available

[    3.509988] futex hash table entries: 256 (order: 2, 16384 bytes)

[    3.510135] audit: initializing netlink subsys (disabled)

[    3.510220] audit: type=2000 audit(3.450:1): initialized

[    3.524161] zpool: loaded

[    3.524187] zbud: loaded

[    3.524956] VFS: Disk quotas dquot_6.6.0

[    3.525273] VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes)

[    3.528888] NFS: Registering the id_resolver key type

[    3.528954] Key type id_resolver registered

[    3.528963] Key type id_legacy registered

[    3.528997] nfs4filelayout_init: NFSv4 File Layout Driver Registering...

[    3.529390] fuse init (API version 7.23)

[    3.530181] SGI XFS with ACLs, security attributes, realtime, no debug enabled

[    3.537109] NET: Registered protocol family 38

[    3.537273] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250)

[    3.537515] io scheduler noop registered

[    3.537533] io scheduler deadline registered

[    3.537598] io scheduler cfq registered (default)

[    3.539374] pinctrl-single 44e10800.pinmux: 142 pins at pa f9e10800 size 568

[    3.543086] wkup_m3_ipc 44e11324.wkup_m3_ipc: could not get rproc handle

[    3.544821] Serial: 8250/16550 driver, 6 ports, IRQ sharing disabled

[    3.548629] 44e09000.serial: ttyS0 at MMIO 0x44e09000 (irq = 158, base_baud = 3000000) is a 8250

[    4.422455] console [ttyS0] enabled

[    4.427580] [drm] Initialized drm 1.1.0 20060810

[    4.432345] usbcore: registered new interface driver udl

[    4.502668] davinci_mdio 4a101000.mdio: davinci mdio revision 1.6

[    4.508810] davinci_mdio 4a101000.mdio: detected phy mask fffffffb

[    4.515342] davinci_mdio: dt: updated phy_id[2] from phy_mask[fffffffb]

[    4.529813] libphy: 4a101000.mdio: probed

[    4.533990] davinci_mdio 4a101000.mdio: phy[2]: device 4a101000.mdio:02, driver SMSC LAN8710/LAN8720

[    4.544091] cpsw 4a100000.ethernet: Detected MACID = 7c:66:9d:57:29:dd

[    4.552360] pegasus: v0.9.3 (2013/04/25), Pegasus/Pegasus II USB Ethernet driver

[    4.560025] usbcore: registered new interface driver pegasus

[    4.565795] usbcore: registered new interface driver rtl8150

[    4.571543] usbcore: registered new interface driver r8152

[    4.578834] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver

[    4.585490] ehci-pci: EHCI PCI platform driver

[    4.590043] ehci-platform: EHCI generic platform driver

[    4.595538] ehci-omap: OMAP-EHCI Host Controller driver

[    4.602910] 47401300.usb-phy supply vcc not found, using dummy regulator

[    4.646969] 47401b00.usb-phy supply vcc not found, using dummy regulator

[    4.695409] musb-hdrc musb-hdrc.1.auto: MUSB HDRC host driver

[    4.701558] musb-hdrc musb-hdrc.1.auto: new USB bus registered, assigned bus number 1

[    4.709684] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002

[    4.716512] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1

[    4.723770] usb usb1: Product: MUSB HDRC host driver

[    4.728760] usb usb1: Manufacturer: Linux 4.1.17-ti-rt-r46 musb-hcd

[    4.735056] usb usb1: SerialNumber: musb-hdrc.1.auto

[    4.741069] hub 1-0:1.0: USB hub found

[    4.744886] hub 1-0:1.0: 1 port detected

[    4.785445] mousedev: PS/2 mouse device common for all mice

[    4.792158] omap_rtc 44e3e000.rtc: already running

[    4.797592] omap_rtc 44e3e000.rtc: rtc core: registered 44e3e000.rtc as rtc0

[    4.806109] i2c /dev entries driver

[    4.809740] Driver for 1-wire Dallas network protocol.

[    4.816955] omap_wdt: OMAP Watchdog Timer Rev 0x01: initial timeout 60 sec

[    4.825057] omap_hsmmc 48060000.mmc: Got CD GPIO

[    4.864850] omap-aes 53500000.aes: OMAP AES hw accel rev: 3.2

[    4.876609] omap-sham 53100000.sham: hw accel on OMAP rev 4.3

[    4.887131] hidraw: raw HID events driver (C) Jiri Kosina

[    4.894264] usbcore: registered new interface driver usbhid

[    4.899869] usbhid: USB HID core driver

[    4.904177] ashmem: initialized

[    4.908435]  remoteproc0: wkup_m3 is available

[    4.910123] mmc0: host does not support reading read-only switch, assuming write-enable

[    4.912076] mmc0: new high speed SDHC card at address b368

[    4.922748] mmcblk0: mmc0:b368 NCard 7.48 GiB 

[    4.924377]  mmcblk0: p1

[    4.936343]  remoteproc0: Note: remoteproc is still under development and considered experimental.

[    4.945346]  remoteproc0: THE BINARY FORMAT IS NOT YET FINALIZED, and backward compatibility isn't yet guaranteed.

[    4.960638] NET: Registered protocol family 10

[    4.966738] mip6: Mobile IPv6

[    4.969750] NET: Registered protocol family 17

[    4.974432] Key type dns_resolver registered

[    4.978732] mpls_gso: MPLS GSO support

[    4.982910] omap_voltage_late_init: Voltage driver support not added

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

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

[    5.004753] PM: bootloader does not support rtc-only!

[    5.010094] ThumbEE CPU extension supported.

[    5.014415] Registering SWP/SWPB emulation handler

[    5.020432] registered taskstats version 1

[    5.028054] Btrfs loaded

[    5.053814] Key type encrypted registered

[    5.061202] input: tps65217_pwr_but as /devices/platform/ocp/44e0b000.i2c/i2c-0/0-0024/input/input0

[    5.092494] tps65217 0-0024: TPS65217 ID 0xe version 1.2

[    5.098603] at24 0-0050: 32768 byte 24c256 EEPROM, writable, 1 bytes/write

[    5.105712] omap_i2c 44e0b000.i2c: bus 0 rev0.11 at 400 kHz

[    5.113612] at24 2-0054: 32768 byte 24c256 EEPROM, writable, 1 bytes/write

[    5.121016] at24 2-0055: 32768 byte 24c256 EEPROM, writable, 1 bytes/write

[    5.128463] at24 2-0056: 32768 byte 24c256 EEPROM, writable, 1 bytes/write

[    5.135910] at24 2-0057: 32768 byte 24c256 EEPROM, writable, 1 bytes/write

[    5.142880] omap_i2c 4819c000.i2c: bus 2 rev0.11 at 100 kHz

[    5.150142]  remoteproc0: powering up wkup_m3

[    5.154587]  remoteproc0: Booting fw image am335x-pm-firmware.elf, size 219735

[    5.156178] bone_capemgr bone_capemgr: Baseboard: 'A335BNLT,000C,2714BBBK0809'

[    5.156196] bone_capemgr bone_capemgr: compatible-baseboard=ti,beaglebone-black - #slots=4

[    5.172712] omap_i2c 4819c000.i2c: Arbitration lost

[    5.182603]  remoteproc0: remote processor wkup_m3 is now up

[    5.182670] wkup_m3_ipc 44e11324.wkup_m3_ipc: CM3 Firmware Version = 0x191

[    6.241709] random: nonblocking pool is initialized

[    8.002615] sched: RT throttling activated

[    8.002699] i2c i2c-2: SCL is stuck low, exit recovery

 

[ison....@mindteck.com]

Hi,

Did you by any chance find a fix to the issue. I am also facing a similar problem where my i2c-1 gets stuck with "Arbitration Lost and RT throttling activated" message.

Thanks and Regards
Ison Thomas

[Jelle Spijker]

Hi Ison,

Unfortunately no.
I switched to an Arch Linux image, which ran without problems.

Best regards,
Jelle

[Ison Thomas]

Thanks Jelle,

Fortunately, mine was an issue with the i2c slave hardware. :). Its
working now. Changing the Linux Image was not an option for me.. :)

Regards
Ison Thomas