Dear All,
In our custom AM3517 board, we have a problem, soft lockup when running Linux.
As the following messages, soft lockup happened in omap_i2c_isr when reading RTC with hwclock command.
Kernel version is 2.6.32 and the RTC is inside PMIC TPS65910.
Starting kernel ...
Uncompressing Linux........................................................................................................................................................ done, booting the kernel.
_clkdm
Clocking rate (Crystal/Core/MPU): 26.0/332/600 MHz
GPMC revision 5.0
IRQ: Found an INTC at 0xfa200000 (revision 4.0) with 96 interrupts
Total of 96 interrupts on 1 active controller
gpiochip_add: gpios 0..31 (gpio-0) registered
gpiochip_add: gpios 32..63 (gpio-1) registered
gpiochip_add: gpios 64..95 (gpio-2) registered
gpiochip_add: gpios 96..127 (gpio-3) registered
gpiochip_add: gpios 128..159 (gpio-4) registered
gpiochip_add: gpios 160..191 (gpio-5) registered
OMAP GPIO hardware version 2.5
OMAP clockevent source: GPTIMER1 at 32768 Hz
Console: colour dummy device 80x30
Calibrating delay loop... 620.75 BogoMIPS (lpj=2424832)
Mount-cache hash table entries: 512
CPU: Testing write buffer coherency: ok
regulator: core version 0.5
NET: Registered protocol family 16
mux: Setting signal etk_d10.gpio24 0x0104 -> 0x0004
mux: Setting signal mcspi1_cs3.gpio177 0x0108 -> 0x0004
mux: Setting signal uart1_rts.gpio149 0x0000 -> 0x0004
mux: Setting signal uart1_cts.gpio150 0x0110 -> 0x0004
mux: Setting signal sys_clkout2.gpio186 0x0118 -> 0x0004
mux: Setting signal sys_clkout1.gpio10 0x0100 -> 0x0004
mux: Setting signal gpmc_clk.gpio59 0x0018 -> 0x0004
mux: Setting signal gpmc_ncs3.gpio54 0x001c -> 0x0004
mux: Setting signal gpmc_ncs4.gpio55 0x010c -> 0x0004
mux: Setting signal gpmc_ncs5.gpio56 0x001c -> 0x0004
mux: Setting signal cam_hs.gpio94 0x0118 -> 0x0004
mux: Setting signal cam_vs.gpio95 0x0118 -> 0x0004
mux: Setting signal cam_xclka.gpio96 0x0000 -> 0x0004
mux: Setting signal cam_pclk.gpio97 0x0118 -> 0x0004
mux: Setting signal cam_fld.gpio98 0x0004 -> 0x0004
mux: Setting signal cam_d0.gpio99 0x0100 -> 0x011c
mux: Setting signal cam_d1.gpio100 0x0100 -> 0x011c
mux: Setting signal cam_d3.gpio102 0x0100 -> 0x0004
mux: Setting signal cam_d4.gpio103 0x0100 -> 0x0004
mux: Setting signal cam_d5.gpio104 0x0100 -> 0x0004
mux: Setting signal cam_d6.gpio105 0x0100 -> 0x0004
mux: Setting signal mcspi1_clk.gpio171 0x0100 -> 0x0004
mux: Setting signal mcspi1_simo.gpio172 0x0100 -> 0x011c
mux: Setting signal mcspi1_somi.gpio173 0x0100 -> 0x0004
mux: Setting signal mcspi1_cs0.gpio174 0x0108 -> 0x0004
mux: Setting signal mcspi1_cs1.gpio175 0x010c -> 0x0004
mux: Setting signal mcspi1_cs2.gpio176 0x0114 -> 0x0004
FPGA: base = 0x1000000, end = 0x100ffff
gpio_request: gpio-101 (FPGA IRQ)
OMAP DMA hardware revision 4.0
bio: create slab <bio-0> at 0
SCSI subsystem initialized
usbcore: registered new interface driver usbfs
usbcore: registered new interface driver hub
usbcore: registered new device driver usb
i2c_omap i2c_omap.1: bus 1 rev3.12 at 400 kHz
i2c_omap i2c_omap.2: bus 2 rev3.12 at 400 kHz
i2c_omap i2c_omap.3: bus 3 rev3.12 at 100 kHz
Switching to clocksource 32k_counter
musb_hdrc: version 6.0, cppi4.1-dma, otg (peripheral+host), debug=0
AM3517 OTG revision 4ea41001, PHY f0036a2, control 00
musb_hdrc: USB OTG mode controller at e0810000 using DMA, IRQ 71
NET: Registered protocol family 2
IP route cache hash table entries: 4096 (order: 2, 16384 bytes)
TCP established hash table entries: 16384 (order: 5, 131072 bytes)
TCP bind hash table entries: 16384 (order: 4, 65536 bytes)
TCP: Hash tables configured (established 16384 bind 16384)
TCP reno registered
UDP hash table entries: 256 (order: 0, 4096 bytes)
UDP-Lite hash table entries: 256 (order: 0, 4096 bytes)
NET: Registered protocol family 1
RPC: Registered udp transport module.
RPC: Registered tcp transport module.
RPC: Registered tcp NFSv4.1 backchannel transport module.
NetWinder Floating Point Emulator V0.97 (double precision)
VFS: Disk quotas dquot_6.5.2
Dquot-cache hash table entries: 1024 (order 0, 4096 bytes)
JFFS2 version 2.2. (NAND) ⓒ 2001-2006 Red Hat, Inc.
msgmni has been set to 989
alg: No test for stdrng (krng)
io scheduler noop registered
io scheduler deadline registered
io scheduler cfq registered (default)
OMAP DSS rev 2.0
OMAP DISPC rev 3.0
### MRX-OSC LCD: CH7033 HDMI output
Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled
serial8250.0: ttyS0 at MMIO 0x4806a000 (irq = 72) is a ST16654
serial8250.1: ttyS1 at MMIO 0x4806c000 (irq = 73) is a ST16654
serial8250.2: ttyS2 at MMIO 0x49020000 (irq = 74) is a ST16654
console [ttyS2] enabled
serial8250.3: ttyS3 at MMIO 0x4809e000 (irq = 84) is a ST16654
brd: module loaded
loop: module loaded
omap2-nand driver initializing
NAND device: Manufacturer ID: 0xec, Chip ID: 0xdc (Samsung NAND 512MiB 3,3V 8-bit)
Creating 6 MTD partitions on "omap2-nand.0":
0x000000000000-0x000000080000 : "xloader-nand"
0x000000080000-0x000000240000 : "uboot-nand"
0x000000240000-0x000000280000 : "params-nand"
0x000000280000-0x000000780000 : "linux-nand"
0x000000780000-0x000002580000 : "jffs2-nand"
0x000002580000-0x000020000000 : "yaffs2-nand"
usbcore: registered new interface driver cdc_ether
usbcore: registered new interface driver dm9601
ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
gpio_request: gpio-24 (USB1 PHY reset)
ehci-omap ehci-omap.0: OMAP-EHCI Host Controller
ehci-omap ehci-omap.0: new USB bus registered, assigned bus number 1
ehci-omap ehci-omap.0: irq 77, io mem 0x48064800
ehci-omap ehci-omap.0: USB 2.0 started, EHCI 1.00
usb usb1: New USB device found, idVendor=1d6b, idProduct=0002
usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
usb usb1: Product: OMAP-EHCI Host Controller
usb usb1: Manufacturer: Linux 2.6.32 ehci_hcd
usb usb1: SerialNumber: ehci-omap.0
hub 1-0:1.0: USB hub found
hub 1-0:1.0: 3 ports detected
Initializing USB Mass Storage driver...
usbcore: registered new interface driver usb-storage
USB Mass Storage support registered.
usbcore: registered new interface driver usbtest
tps65910_rtc tps65910_rtc: rtc core: registered tps65910_rtc as rtc0
TPS65910 RTC device successfully registered
i2c /dev entries driver
Linux video capture interface: v2.00
OMAP Watchdog Timer Rev 0x31: initial timeout 60 sec
usbcore: registered new interface driver hiddev
usbcore: registered new interface driver usbhid
usbhid: USB HID core driver
TCP cubic registered
NET: Registered protocol family 17
NET: Registered protocol family 15
Power Management for TI OMAP3.
VFP support v0.3: implementor 41 architecture 3 part 30 variant c rev 1
fb_mode_to_dss_mode: 0x0, 0x20
fb_mode_to_dss_mode(R): 0x0, 0x0, 0x0
fb_mode_to_dss_mode(R): 0x0, 0x0, 0x0
fb_mode_to_dss_mode(R): 0x0, 0x0, 0x0
fb_mode_to_dss_mode(R): 0x0, 0x0, 0x0
fb_mode_to_dss_mode: 0x80
fb_mode_to_dss_mode: 0x0, 0x20
fb_mode_to_dss_mode(R): 0x8, 0x10, 0x0
fb_mode_to_dss_mode(R): 0x8, 0x8, 0x0
fb_mode_to_dss_mode(R): 0x8, 0x0, 0x0
fb_mode_to_dss_mode(R): 0x0, 0x0, 0x0
dpi_set_mode: Requested 74250 kHz, got 72000 kHz
omapdss DPI: Could not find exact pixel clock. Requested 74250 kHz, got 72000 kHz
##### dss_irq_handler_omap3: Entered
##### irqstatus = 0x1
usb 1-1: new high speed USB device using ehci-omap and address 2
emac-mii: probed
tps65910_rtc tps65910_rtc: setting system clock to 2012-10-16 15:30:49 UTC (1350401449)
usb 1-1: New USB device found, idVendor=0424, idProduct=2512
usb 1-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0
hub 1-1:1.0: USB hub found
hub 1-1:1.0: 2 ports detected
usb 1-1.1: new full speed USB device using ehci-omap and address 3
usb 1-1.1: New USB device found, idVendor=1df5, idProduct=3012
usb 1-1.1: New USB device strings: Mfr=1, Product=2, SerialNumber=3
usb 1-1.1: Product: URCHID
usb 1-1.1: Manufacturer: URCSYS
usb 1-1.1: SerialNumber: 000001
HID ESD: logical_minimum,maximum changed. -128,127->0,255
HID ESD: logical_minimum,maximum changed. -128,127->0,255
generic-usb 0003:1DF5:3012.0001: hiddev96: USB HID v1.10 Device [URCSYS URCHID] on usb-ehci-omap.0-1.1/input0
input: URCSYS URCHID as /class/input/input0
generic-usb 0003:1DF5:3012.0002: input: USB HID v1.11 Keyboard [URCSYS URCHID] on usb-ehci-omap.0-1.1/input1
input: URCSYS URCHID as /class/input/input1
generic-usb 0003:1DF5:3012.0003: input: USB HID v1.11 Mouse [URCSYS URCHID] on usb-ehci-omap.0-1.1/input2
Empty flash at 0x01d68a58 ends at 0x01d69000
VFS: Mounted root (jffs2 filesystem) on device 31:4.
Freeing init memory: 744K
Warning: unable to open an initial console.
udev: starting version 141
udev: deprecated sysfs layout; update the kernel or disable CONFIG_SYSFS_DEPRECATED; some udev features will not work correctly
yaffs: dev is 32505861 name is "mtdblock5" rw
yaffs: passed flags ""
eth0: attached PHY driver [Generic PHY] (mii_bus:phy_addr=ffffffff:00, id=7c0f1)
PHY: ffffffff:00 - Link is Up - 100/Full
BUG: soft lockup - CPU#0 stuck for 61s! [hwclock:1183]
Modules linked in:
Pid: 1183, comm: hwclock
CPU: 0 Not tainted (2.6.32 #5)
PC is at omap_i2c_isr+0x28c/0x364
LR is at handle_IRQ_event+0x34/0xf4
pc : [<c02bd324>] lr : [<c010fd08>] psr: 40000113
sp : df309d50 ip : 00000012 fp : df8ffa00
r10: 00000000 r9 : 00000001 r8 : 0000601f
r7 : 00000001 r6 : 00000000 r5 : 00004000 r4 : df8ffa00
r3 : 00004000 r2 : 0000003c r1 : 00000001 r0 : 00000002
Flags: nZcv IRQs on FIQs on Mode SVC_32 ISA ARM Segment user
Control: 10c5387d Table: 9f30c019 DAC: 00000015
BUG: soft lockup - CPU#0 stuck for 61s! [hwclock:1183]
Modules linked in:
Pid: 1183, comm: hwclock
CPU: 0 Not tainted (2.6.32 #5)
PC is at omap_i2c_isr+0x28c/0x364
LR is at handle_IRQ_event+0x34/0xf4
pc : [<c02bd324>] lr : [<c010fd08>] psr: 40000113
sp : df309d50 ip : 00000012 fp : df8ffa00
r10: 00000000 r9 : 00000001 r8 : 0000601f
r7 : 00000001 r6 : 00000000 r5 : 00004000 r4 : df8ffa00
r3 : 00004000 r2 : 0000003c r1 : 00000001 r0 : 00000002
Flags: nZcv IRQs on FIQs on Mode SVC_32 ISA ARM Segment user
Control: 10c5387d Table: 9f30c019 DAC: 00000015
BUG: soft lockup - CPU#0 stuck for 61s! [hwclock:1183]
Modules linked in:
.......
< repeated ..... >
