SGX deadlock with panda lt x11 hwpack

Bug #817282 reported by Tom Gall
52
This bug affects 8 people
Affects Status Importance Assigned to Milestone
Linaro Ubuntu
Fix Released
Medium
Ricardo Salveti
linaro-landing-team-ti
Fix Released
Low
Unassigned
ubuntu-omap4-extras-graphics
Fix Released
Undecided
Unassigned

Bug Description

on panda, using the lt-x11 hwpack. LEB 11.07 RC rootfs

boot log:

Starting kernel ...

Uncompressing Linux... done, booting the kernel.
[ 0.000000] Linux version 3.0.0-1402-linaro-lt-omap (buildd@kaylaberry) (gcc version 4.5.2 (Ubuntu/Linaro)
[ 0.000000] CPU: ARMv7 Processor [411fc092] revision 2 (ARMv7), cr=10c5387f
[ 0.000000] CPU: VIPT nonaliasing data cache, VIPT aliasing instruction cache
[ 0.000000] Machine: OMAP4 Panda board
[ 0.000000] Reserving 50331648 bytes SDRAM for VRAM
[ 0.000000] Memory policy: ECC disabled, Data cache writealloc
[ 0.000000] OMAP4430 ES2.1
[ 0.000000] SRAM: Mapped pa 0x40300000 to va 0xfe400000 size: 0xe000
[ 0.000000] powerdomain: waited too long for powerdomain dss_pwrdm to complete transition
[ 0.000000] PERCPU: Embedded 7 pages/cpu @c202f000 s8160 r8192 d12320 u32768
[ 0.000000] Built 1 zonelists in Zone order, mobility grouping on. Total pages: 233472
[ 0.000000] Kernel command line: console=tty0 console=ttyO2,115200n8 root=UUID=55f80ec9-c0a5-481a-a4c9-30
[ 0.000000] PID hash table entries: 4096 (order: 2, 16384 bytes)
[ 0.000000] Dentry cache hash table entries: 131072 (order: 7, 524288 bytes)
[ 0.000000] Inode-cache hash table entries: 65536 (order: 6, 262144 bytes)
[ 0.000000] Memory: 456MB 464MB = 920MB total
[ 0.000000] Memory: 916132k/916132k available, 75100k reserved, 212992K highmem
[ 0.000000] Virtual kernel memory layout:
[ 0.000000] vector : 0xffff0000 - 0xffff1000 ( 4 kB)
[ 0.000000] fixmap : 0xfff00000 - 0xfffe0000 ( 896 kB)
[ 0.000000] DMA : 0xffc00000 - 0xffe00000 ( 2 MB)
[ 0.000000] vmalloc : 0xf0800000 - 0xf8000000 ( 120 MB)
[ 0.000000] lowmem : 0xc0000000 - 0xf0000000 ( 768 MB)
[ 0.000000] pkmap : 0xbfe00000 - 0xc0000000 ( 2 MB)
[ 0.000000] modules : 0xbf000000 - 0xbfe00000 ( 14 MB)
[ 0.000000] .init : 0xc0008000 - 0xc0053000 ( 300 kB)
[ 0.000000] .text : 0xc0053000 - 0xc08c2d08 (8640 kB)
[ 0.000000] .data : 0xc08c4000 - 0xc0958160 ( 593 kB)
[ 0.000000] .bss : 0xc0958184 - 0xc0ec2378 (5545 kB)
[ 0.000000] Preemptible hierarchical RCU implementation.
[ 0.000000] NR_IRQS:410
[ 0.000000] omap_hwmod: dpll_mpu_m2_ck: missing clockdomain for dpll_mpu_m2_ck.
[ 0.000000] OMAP clockevent source: GPTIMER1 at 32768 Hz
[ 0.000000] sched_clock: 32 bits at 32kHz, resolution 30517ns, wraps every 131071999ms
[ 0.000000] Console: colour dummy device 80x30
[ 0.000000] console [tty0] enabled
[ 0.000000] Lock dependency validator: Copyright (c) 2006 Red Hat, Inc., Ingo Molnar
[ 0.000000] ... MAX_LOCKDEP_SUBCLASSES: 8
[ 0.000000] ... MAX_LOCK_DEPTH: 48
[ 0.000000] ... MAX_LOCKDEP_KEYS: 8191
[ 0.000000] ... CLASSHASH_SIZE: 4096
[ 0.000000] ... MAX_LOCKDEP_ENTRIES: 16384
[ 0.000000] ... MAX_LOCKDEP_CHAINS: 32768
[ 0.000000] ... CHAINHASH_SIZE: 16384
[ 0.000000] memory used by lock dependency info: 3695 kB
[ 0.000000] per task-struct memory footprint: 1152 bytes
[ 0.000122] Calibrating delay loop... 2007.19 BogoMIPS (lpj=7839744)
[ 0.062561] pid_max: default: 32768 minimum: 301
[ 0.063354] Security Framework initialized
[ 0.063354] AppArmor: AppArmor initialized
[ 0.063537] Yama: becoming mindful.
[ 0.063537] Mount-cache hash table entries: 512
[ 0.063537] CPU: Testing write buffer coherency: ok
[ 0.068786] twd_timer_setup: no clock found
[ 0.068786] Calibrating local timer... 491.42MHz.
[ 0.109680] L310 cache controller enabled
[ 0.109680] l2x0: 16 ways, CACHE_ID 0x410000c4, AUX_CTRL 0x5e470000, Cache size: 1048576 B
[ 0.170684] CPU1: Booted secondary processor
[ 0.239349] Brought up 2 CPUs
[ 0.239410] SMP: Total of 2 processors activated (3963.96 BogoMIPS).
[ 0.240875] devtmpfs: initialized
[ 0.251464] omap_hwmod: _populate_mpu_rt_base found no _mpu_rt_va for emif_fw
[ 0.251464] omap_hwmod: _populate_mpu_rt_base found no _mpu_rt_va for l3_instr
[ 0.251464] omap_hwmod: _populate_mpu_rt_base found no _mpu_rt_va for l3_main_1
[ 0.251464] omap_hwmod: _populate_mpu_rt_base found no _mpu_rt_va for l3_main_2
[ 0.251464] omap_hwmod: _populate_mpu_rt_base found no _mpu_rt_va for l4_abe
[ 0.251617] omap_hwmod: _populate_mpu_rt_base found no _mpu_rt_va for l4_cfg
[ 0.251617] omap_hwmod: _populate_mpu_rt_base found no _mpu_rt_va for l4_per
[ 0.251617] omap_hwmod: _populate_mpu_rt_base found no _mpu_rt_va for l4_wkup
[ 0.251708] omap_hwmod: _populate_mpu_rt_base found no _mpu_rt_va for mpu_private
[ 0.251708] omap_hwmod: _populate_mpu_rt_base found no _mpu_rt_va for dsp
[ 0.251708] omap_hwmod: _populate_mpu_rt_base found no _mpu_rt_va for ipu
[ 0.251708] omap_hwmod: l3_div_ck: missing clockdomain for l3_div_ck.
[ 0.260009] omap_hwmod: mcpdm: cannot be enabled (3)
[ 0.264648] print_constraints: dummy:
[ 0.264648] NET: Registered protocol family 16
[ 0.264648] Trying to unpack rootfs image as initramfs...
[ 0.406768] Freeing initrd memory: 2168K
[ 0.407623] GPMC revision 6.0
[ 0.413604] omap_device: omap_gpio.0: new worst case activate latency 0: 335693
[ 0.415191] OMAP GPIO hardware version 0.1
[ 0.416107] OMAP GPIO hardware version 0.1
[ 0.416687] OMAP GPIO hardware version 0.1
[ 0.418029] OMAP GPIO hardware version 0.1
[ 0.418548] OMAP GPIO hardware version 0.1
[ 0.419403] OMAP GPIO hardware version 0.1
[ 0.424346] omap_mux_init: Add partition: #1: core, flags: 2
[ 0.424346] omap_mux_init: Add partition: #2: wkup, flags: 2
[ 0.430816] omap_device: omap_uart.0: new worst case activate latency 0: 61035
[ 0.432342] omap_device: omap_uart.2: new worst case activate latency 0: 122070
[ 0.454956] pm_dbg_init: only OMAP3 supported
[ 0.456298] OMAP DMA hardware revision 0.0
[ 0.515014] bio: create slab <bio-0> at 0
[ 0.517761] print_constraints: vwl1271: 1800 mV
[ 0.522216] SCSI subsystem initialized
[ 0.523498] omap_device: omap2_mcspi.1: new worst case deactivate latency 0: 274658
[ 0.524230] omap_device: omap2_mcspi.3: new worst case activate latency 0: 91552
[ 0.527069] usbcore: registered new interface driver usbfs
[ 0.527709] usbcore: registered new interface driver hub
[ 0.528137] usbcore: registered new device driver usb
[ 0.531188] omap_device: omap_i2c.1: new worst case activate latency 0: 122070
[ 0.544311] omap_i2c omap_i2c.1: bus 1 rev4.0 at 400 kHz
[ 0.547576] Skipping twl internal clock init and using bootloader value (unknown osc rate)
[ 0.548919] twl6030: PIH (irq 39) chaining IRQs 368..387
[ 0.551330] print_constraints: VUSB: 3300 mV normal standby
[ 0.751800] twl6030_usb twl6030_usb: Initialized TWL6030 USB module
[ 0.754028] print_constraints: VMMC: 1200 <--> 3000 mV at 3000 mV normal standby
[ 0.755432] print_constraints: VPP: 1800 <--> 2500 mV at 1900 mV normal standby
[ 0.756774] print_constraints: VCXIO: 1800 mV normal standby
[ 0.758056] print_constraints: VDAC: 1800 mV normal standby
[ 0.759399] print_constraints: VAUX2_6030: 1200 <--> 2800 mV at 1800 mV normal standby
[ 0.760833] print_constraints: VAUX3_6030: 1000 <--> 3000 mV at 1200 mV normal standby
[ 0.762176] print_constraints: CLK32KG:
[ 0.763336] print_constraints: VANA: 2100 mV normal standby
[ 0.763732] omap_device: omap_i2c.2: new worst case activate latency 0: 396728
[ 0.765441] omap_i2c omap_i2c.2: bus 2 rev4.0 at 400 kHz
[ 0.787902] omap_i2c omap_i2c.3: bus 3 rev4.0 at 100 kHz
[ 0.802886] omap_i2c omap_i2c.4: bus 4 rev4.0 at 400 kHz
[ 0.805847] Advanced Linux Sound Architecture Driver Version 1.0.24.
[ 0.808502] Bluetooth: Core ver 2.16
[ 0.808807] NET: Registered protocol family 31
[ 0.808807] Bluetooth: HCI device and connection manager initialized
[ 0.808959] Bluetooth: HCI socket layer initialized
[ 0.808959] Bluetooth: L2CAP socket layer initialized
[ 0.809143] Bluetooth: SCO socket layer initialized
[ 0.811340] NetLabel: Initializing
[ 0.811370] NetLabel: domain hash size = 128
[ 0.811370] NetLabel: protocols = UNLABELED CIPSOv4
[ 0.811401] NetLabel: unlabeled traffic allowed by default
[ 0.813354] Switching to clocksource 32k_counter
[ 0.818115] Switched to NOHz mode on CPU #0
[ 0.818786] Switched to NOHz mode on CPU #1
[ 0.823699] cfg80211: Calling CRDA to update world regulatory domain
[ 0.845092] AppArmor: AppArmor Filesystem Enabled
[ 0.890258] musb-hdrc: version 6.0, musb-dma, otg (peripheral+host)
[ 0.890563] omap_device: musb-omap2430.-1: new worst case activate latency 0: 30517
[ 0.891296] musb-hdrc musb-hdrc: USB OTG mode controller at fc0ab000 using DMA, IRQ 124
[ 0.891937] NET: Registered protocol family 2
[ 0.892700] omap_device: musb-omap2430.-1: new worst case deactivate latency 0: 213623
[ 0.892761] IP route cache hash table entries: 32768 (order: 5, 131072 bytes)
[ 0.892761] TCP established hash table entries: 131072 (order: 8, 1048576 bytes)
[ 0.896759] TCP bind hash table entries: 65536 (order: 9, 2621440 bytes)
[ 0.913330] TCP: Hash tables configured (established 131072 bind 65536)
[ 0.913330] TCP reno registered
[ 0.913574] UDP hash table entries: 512 (order: 3, 49152 bytes)
[ 0.913574] UDP-Lite hash table entries: 512 (order: 3, 49152 bytes)
[ 0.914764] NET: Registered protocol family 1
[ 0.915618] RPC: Registered named UNIX socket transport module.
[ 0.915618] RPC: Registered udp transport module.
[ 0.915802] RPC: Registered tcp transport module.
[ 0.915863] RPC: Registered tcp NFSv4.1 backchannel transport module.
[ 0.915893] NetWinder Floating Point Emulator V0.97 (double precision)
[ 0.942077] audit: initializing netlink socket (disabled)
[ 0.942596] type=2000 audit(0.953:1): initialized
[ 1.093811] highmem bounce pool size: 64 pages
[ 1.094970] VFS: Disk quotas dquot_6.5.2
[ 1.094970] Dquot-cache hash table entries: 1024 (order 0, 4096 bytes)
[ 1.097198] Installing knfsd (copyright (C) 1996 <email address hidden>).
[ 1.098297] JFFS2 version 2.2. (NAND) (SUMMARY) ?© 2001-2006 Red Hat, Inc.
[ 1.098297] ROMFS MTD (C) 2007 Red Hat, Inc.
[ 1.100006] Btrfs loaded
[ 1.100067] msgmni has been set to 1377
[ 1.103057] io scheduler noop registered
[ 1.103057] io scheduler deadline registered
[ 1.103057] io scheduler cfq registered (default)
[ 1.179534] OMAP DSS rev 4.0
[ 1.185363] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled
[ 1.329528] omap_uart.0: ttyO0 at MMIO 0x4806a000 (irq = 104) is a OMAP UART0
[ 1.375244] omap_uart.1: ttyO1 at MMIO 0x4806c000 (irq = 105) is a OMAP UART1
[ 1.406738] omap_uart.2: ttyO2 at MMIO 0x48020000 (irq = 106) is a OMAP UART2
[ 2.430053] console [ttyO2] enabled
[ 2.450164] omap_uart.3: ttyO3 at MMIO 0x4806e000 (irq = 102) is a OMAP UART3
[ 2.512268] brd: module loaded
[ 2.526763] loop: module loaded
[ 2.526763] (stk) :sysfs entries created
[ 2.534454] (stk) : debugfs entries created
[ 2.546112] mtdoops: mtd device (mtddev=name/number) must be supplied
[ 2.554046] omap2-nand driver initializing
[ 2.558929] OneNAND driver initializing
[ 2.567535] usbcore: registered new interface driver asix
[ 2.573577] usbcore: registered new interface driver cdc_ether
[ 2.580139] usbcore: registered new interface driver smsc95xx
[ 2.586486] usbcore: registered new interface driver net1080
[ 2.592834] usbcore: registered new interface driver cdc_subset
[ 2.599517] usbcore: registered new interface driver zaurus
[ 2.602355] cdc_ncm: 01-June-2011
[ 2.609924] usbcore: registered new interface driver cdc_ncm
[ 2.616882] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
[ 2.624267] _regulator_get: ehci-omap.0 supply hsusb0 not found, using dummy regulator
[ 2.632904] ehci-omap ehci-omap.0: OMAP-EHCI Host Controller
[ 2.640838] ehci-omap ehci-omap.0: new USB bus registered, assigned bus number 1
[ 2.648681] ehci-omap ehci-omap.0: irq 109, io mem 0x4a064c00
[ 2.660858] ehci-omap ehci-omap.0: USB 2.0 started, EHCI 1.00
[ 2.666931] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002
[ 2.675384] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[ 2.683044] usb usb1: Product: OMAP-EHCI Host Controller
[ 2.688690] usb usb1: Manufacturer: Linux 3.0.0-1402-linaro-lt-omap ehci_hcd
[ 2.696136] usb usb1: SerialNumber: ehci-omap.0
[ 2.704467] hub 1-0:1.0: USB hub found
[ 2.704467] hub 1-0:1.0: 3 ports detected
[ 2.750152] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver
[ 2.756958] ohci-omap3 ohci-omap3.0: OMAP3 OHCI Host Controller
[ 2.764129] ohci-omap3 ohci-omap3.0: new USB bus registered, assigned bus number 2
[ 2.772155] ohci-omap3 ohci-omap3.0: irq 108, io mem 0x4a064800
[ 2.848876] usb usb2: New USB device found, idVendor=1d6b, idProduct=0001
[ 2.848876] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[ 2.863800] usb usb2: Product: OMAP3 OHCI Host Controller
[ 2.869506] usb usb2: Manufacturer: Linux 3.0.0-1402-linaro-lt-omap ohci_hcd
[ 2.876953] usb usb2: SerialNumber: ohci-omap3.0
[ 2.883453] hub 2-0:1.0: USB hub found
[ 2.887481] hub 2-0:1.0: 3 ports detected
[ 2.893585] usbcore: registered new interface driver cdc_wdm
[ 2.899658] Initializing USB Mass Storage driver...
[ 2.905212] usbcore: registered new interface driver usb-storage
[ 2.911773] USB Mass Storage support registered.
[ 2.917480] usbcore: registered new interface driver libusual
[ 3.034149] usb 1-1: new high speed USB device number 2 using ehci-omap
[ 3.722564] usb 1-1: New USB device found, idVendor=0424, idProduct=9514
[ 3.729766] usb 1-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0
[ 3.739227] hub 1-1:1.0: USB hub found
[ 3.743530] hub 1-1:1.0: 5 ports detected
[ 4.157165] usbcore: registered new interface driver usbtest
[ 4.164520] mousedev: PS/2 mouse device common for all mice
[ 4.176696] twl_rtc twl_rtc: rtc core: registered twl_rtc as rtc0
[ 4.183776] twl_rtc twl_rtc: Power up reset detected.
[ 4.190338] twl_rtc twl_rtc: Enabling TWL-RTC.
[ 4.195739] i2c /dev entries driver
[ 4.203399] lirc_dev: IR Remote Control driver registered, major 251
[ 4.210327] IR NEC protocol handler initialized
[ 4.215148] IR RC5(x) protocol handler initialized
[ 4.220245] IR RC6 protocol handler initialized
[ 4.220245] IR JVC protocol handler initialized
[ 4.229827] IR Sony protocol handler initialized
[ 4.234710] IR RC5 (streamzap) protocol handler initialized
[ 4.234710] IR LIRC bridge handler initialized
[ 4.245300] Linux video capture interface: v2.00
[ 4.255157] Driver for 1-wire Dallas network protocol.
[ 4.263061] omap_device: omap_wdt.-1: new worst case activate latency 0: 91552
[ 4.272003] OMAP Watchdog Timer Rev 0x00: initial timeout 60 sec
[ 4.278747] omap_device: omap_wdt.-1: new worst case deactivate latency 0: 30517
[ 4.288574] device-mapper: ioctl: 4.20.0-ioctl (2011-02-02) initialised: <email address hidden>
[ 4.297546] Bluetooth: HCI UART driver ver 2.2
[ 4.302276] Bluetooth: HCI H4 protocol initialized
[ 4.305419] Bluetooth: HCI BCSP protocol initialized
[ 4.308624] Bluetooth: HCILL protocol initialized
[ 4.317718] Bluetooth: Bluetooth Driver for TI WiLink - Version 1.0
[ 4.327209] (stc): st_register(2)
[ 4.328002] cpuidle: using governor ladder
[ 4.328216] cpuidle: using governor menu
[ 4.331176] omap_device: omap_i2c.1: new worst case deactivate latency 0: 30517
[ 4.332916] _regulator_get: omap_hsmmc.0 supply vmmc_aux not found, using dummy regulator
[ 4.335510] _regulator_get: omap_hsmmc.4 supply vmmc_aux not found, using dummy regulator
[ 4.336212] omap_device: omap_i2c.1: new worst case activate latency 0: 671386
[ 4.355834] usbcore: registered new interface driver usbhid
[ 4.355834] usbhid: USB HID core driver
[ 4.367065] SDP4430 SoC init
[ 4.371124] omap_device: aess.-1: new worst case activate latency 0: 2777099
[ 4.392547] (stc): chnl_id list empty :2
[ 4.397430] (stk) : st_kim_start
[ 4.403137] omap_device: omap_i2c.1: new worst case deactivate latency 0: 91552
[ 4.418243] asoc: null-codec-dai <-> MultiMedia1 mapping ok
[ 4.428009] asoc: null-codec-dai <-> MultiMedia2 mapping ok
[ 4.436279] asoc: null-codec-dai <-> Voice mapping ok
[ 4.442596] asoc: null-codec-dai <-> Tones mapping ok
[ 4.449676] asoc: null-codec-dai <-> Vibra mapping ok
[ 4.457366] asoc: null-codec-dai <-> MODEM mapping ok
[ 4.463562] asoc: null-codec-dai <-> MultiMedia1 LP mapping ok
[ 4.470825] asoc: FM Digital <-> omap-mcbsp-dai.1 mapping ok
[ 4.478332] omap_device: omap-mcpdm.-1: new worst case activate latency 0: 30517
[ 4.486450] usb 1-1.1: new high speed USB device number 3 using ehci-omap
[ 4.494567] asoc: twl6040-dl1 <-> mcpdm-dl mapping ok
[ 4.502410] asoc: dmic-hifi <-> omap-dmic-dai-0 mapping ok
[ 4.503784] (stk) :ldisc_install = 1
[ 4.515777] twl6040-codec twl6040-codec: ASoC: Failed to create Aux/FM Stereo In debugfs file
[ 4.526336] asoc: twl6040-dl1 <-> mcpdm-dl1 mapping ok
[ 4.533111] asoc: twl6040-ul <-> mcpdm-ul1 mapping ok
[ 4.539642] asoc: twl6040-dl2 <-> mcpdm-dl2 mapping ok
[ 4.546325] asoc: twl6040-vib <-> mcpdm-vib mapping ok
[ 4.553466] asoc: Bluetooth <-> omap-mcbsp-dai.0 mapping ok
[ 4.560424] asoc: FM Digital <-> omap-mcbsp-dai.1 mapping ok
[ 4.567504] asoc: MODEM <-> omap-mcbsp-dai.1 mapping ok
[ 4.574615] asoc: dmic-hifi <-> omap-dmic-abe-dai-0 mapping ok
[ 4.581634] asoc: dmic-hifi <-> omap-dmic-abe-dai-1 mapping ok
[ 4.589355] asoc: dmic-hifi <-> omap-dmic-abe-dai-2 mapping ok
[ 4.605651] input: Panda Headset Jack as /devices/platform/soc-audio.0/sound/card0/input0
[ 4.613555] mmc0: host does not support reading read-only switch. assuming write-enable.
[ 4.623474] usb 1-1.1: New USB device found, idVendor=0424, idProduct=ec00
[ 4.631134] mmc0: new SDHC card at address b368
[ 4.636840] usb 1-1.1: New USB device strings: Mfr=0, Product=0, SerialNumber=0
[ 4.645812] mmcblk0: mmc0:b368 PNY 7.51 GiB
[ 4.648468] Error: Driver 'hdmi-audio-dai' is already registered, aborting...
[ 4.648468] OMAP4 HDMI audio SoC init
[ 4.649780] asoc: omap4-hdmi-audio-codec <-> hdmi-audio-dai mapping ok
[ 4.652618] ALSA device list:
[ 4.652740] #0: TI OMAP4 Board
[ 4.652740] #1: TI OMAP4 HDMI Board
[ 4.652740] oprofile: hardware counters not available
[ 4.652740] oprofile: using timer interrupt.
[ 4.689453] TCP cubic registered
[ 4.692993] Initializing XFRM netlink socket
[ 4.698028] NET: Registered protocol family 10
[ 4.702148] mmcblk0: p1 p2
[ 4.707000] smsc95xx v1.0.4
[ 4.711822] IPv6 over IPv4 tunneling driver
[ 4.718292] NET: Registered protocol family 17
[ 4.723602] NET: Registered protocol family 15
[ 4.728637] Bluetooth: RFCOMM TTY layer initialized
[ 4.730133] Bluetooth: RFCOMM socket layer initialized
[ 4.739318] Bluetooth: RFCOMM ver 1.11
[ 4.741638] Bluetooth: BNEP (Ethernet Emulation) ver 1.3
[ 4.749267] Bluetooth: BNEP filters: protocol multicast
[ 4.754821] Bluetooth: HIDP (Human Interface Emulation) ver 1.2
[ 4.761413] lib80211: common routines for IEEE802.11 drivers
[ 4.767822] Registering the dns_resolver key type
[ 4.767822] VFP support v0.3: implementor 41 architecture 3 part 30 variant 9 rev 1
[ 4.780456] ThumbEE CPU extension supported.
[ 4.785949] Registering SWP/SWPB emulation handler
[ 4.798095] smsc95xx 1-1.1:1.0: eth0: register 'smsc95xx' at usb-ehci-omap.0-1.1, smsc95xx USB 2.0 Ethern6
[ 4.800292] Power Management for TI OMAP4.
[ 4.800506] sr_init: No PMIC hook to init smartreflex
[ 4.800506] smartreflex smartreflex.0: omap_sr_probe: SmartReflex driver initialized
[ 4.800506] smartreflex smartreflex.1: omap_sr_probe: SmartReflex driver initialized
[ 4.801483] smartreflex smartreflex.2: omap_sr_probe: SmartReflex driver initialized
[ 4.802124] SmartReflex Class3 initialized
[ 4.872680] mmc1: card claims to support voltages below the defined range. These will be ignored.
[ 4.901123] mmc1: queuing unknown CIS tuple 0x91 (3 bytes)
[ 4.908233] mmc1: new SDIO card at address 0001
[ 5.110107] Console: switching to colour frame buffer device 80x30
[ 5.126342] omapdss HDMI: fallback to VGA
[ 5.184478] omapdss HDMI: cannot lock PLL
[ 5.187164] omapdss HDMI: CFG1 0xc00
[ 5.187164] omapdss HDMI: CFG2 0x2004
[ 5.187164] omapdss HDMI: CFG4 0x23955
[ 5.187194] hdmi_pll_init says 65426
[ 5.187194] omapdss HDMI error: failed to power on device
[ 5.187225] omapdss error: failed to power on
[ 5.240142] usb 1-1.2: new high speed USB device number 4 using ehci-omap
[ 5.251373] omap_device: omap_i2c.1: new worst case deactivate latency 0: 152587
[ 5.264739] regulator_init_complete: VANA: incomplete constraints, leaving on
[ 5.275665] regulator_init_complete: CLK32KG: incomplete constraints, leaving on
[ 5.290161] regulator_init_complete: VDAC: incomplete constraints, leaving on
[ 5.301422] regulator_init_complete: VCXIO: incomplete constraints, leaving on
[ 5.315368] regulator_init_complete: VUSB: incomplete constraints, leaving on
[ 5.326965] twl_rtc twl_rtc: setting system clock to 2000-01-01 00:00:01 UTC (946684801)
[ 5.344238] omapdss HDMI: fallback to VGA
[ 5.404724] omapdss HDMI: cannot lock PLL
[ 5.404724] omapdss HDMI: CFG1 0xc00
[ 5.404724] omapdss HDMI: CFG2 0x2004
[ 5.404754] omapdss HDMI: CFG4 0x23955
[ 5.404754] hdmi_pll_init says 65426
[ 5.404785] omapdss HDMI error: failed to power on device
[ 5.404785] omapdss error: failed to power on
[ 5.458984] omapdss HDMI: fallback to VGA
[ 5.468200] usb 1-1.2: New USB device found, idVendor=1234, idProduct=2088
[ 5.468200] usb 1-1.2: New USB device strings: Mfr=1, Product=2, SerialNumber=3
[ 5.468200] usb 1-1.2: Product: V88
[ 5.468292] usb 1-1.2: Manufacturer: V88
[ 5.468292] usb 1-1.2: SerialNumber: 18165700C0D7BC09
[ 5.470764] scsi0 : usb-storage 1-1.2:1.0
[ 5.511505] (stk) :line disc installation timed out
[ 5.511505] (stk) :ldisc_install = 0
[ 5.524505] omapdss HDMI: cannot lock PLL
[ 5.524505] omapdss HDMI: CFG1 0xc00
[ 5.524505] omapdss HDMI: CFG2 0x2004
[ 5.524749] omapdss HDMI: CFG4 0x23955
[ 5.524749] hdmi_pll_init says 65426
[ 5.524780] omapdss HDMI error: failed to power on device
[ 5.524780] omapdss error: failed to power on
[ 5.603240] (stk) :ldisc_install = 1
[ 5.614288] omap_vout omap_vout: Buffer Size = 3686400
[ 5.638275] omap_vout omap_vout: : registered and initialized video device 1
[ 5.662292] Freeing init memory: 300K
Loading, please wait...
[ 5.813751] usb 1-1.3: new high speed USB device number 5 using ehci-omap
[ 5.856658] udev[804]: starting version 167
Begin: Loading essential drivers ... done.
Begin: Running /scripts/init-premount ... done.
Begin: Mounting root file system ... [ 5.949615] usb 1-1.3: New USB device found, idVendor=05ac, idProduc5
Begin: Running /scripts/local-top ... done.
[ 6.037841] usb 1-1.3: New USB device strings: Mfr=1, Product=2, SerialNumber=3
[ 6.160797] usb 1-1.3: Product: Keyboard Hub
[ 6.173187] usb 1-1.3: Manufacturer: Apple Inc.
[ 6.180114] usb 1-1.3: SerialNumber: 000000000000
[ 6.201660] hub 1-1.3:1.0: USB hub found
[ 6.208435] hub 1-1.3:1.0: 3 ports detected
Begin: Running /scripts/local-premount ... done.
[ 6.509582] EXT3-fs: barriers not enabled
[ 6.516540] usb 1-1.3.1: new low speed USB device number 6 using ehci-omap
[ 6.526641] scsi 0:0:0:0: Direct-Access V88 V88 5.00 PQ: 0 ANSI: 2
[ 6.527221] EXT3-fs (mmcblk0p2): mounted filesystem with ordered data mode
[ 6.528503] kjournald starting. Commit interval 5 seconds
[ 6.528686] (stk) :line disc installation timed out
[ 6.528686] (stk) :ldisc_install = 0Begin: Running /scripts/local-bottom ... done.
done.

[ 6.666381] sd 0:0:0:0: [sda] 3901440 512-byte logical blocks: (1.99 GB/1.86 GiB)
Begin: Running /scripts/init-bottom ... [ 6.695434] usb 1-1.3.1: New USB device found, idVendor=046d, idPe
[ 6.722991] sd 0:0:0:0: [sda] Write Protect is off
[ 6.732635] usb 1-1.3.1: New USB device strings: Mfr=1, Product=2, SerialNumber=0
[ 6.760009] sd 0:0:0:0: [sda] No Caching mode page present
[ 6.774444] usb 1-1.3.1: Product: USB-PS/2 Optical Mouse
[ 6.787994] sd 0:0:0:0: [sda] Assuming drive cache: write through
[ 6.809265] usb 1-1.3.1: Manufacturer: Logitech
[ 6.836730] sd 0:0:0:0: [sda] No Caching mode page present
[ 6.882324] input: Logitech USB-PS/2 Optical Mouse as /devices/platform/usbhs-omap.0/ehci-omap.0/usb1/1-11
[ 6.902709] sd 0:0:0:0: [sda] Assuming drive cache: write through
[ 6.925811] (stk) :ldisc_install = 1
[ 6.935821] sda: sda1
[ 6.955413] generic-usb 0003:046D:C00E.0001: input: USB HID v1.10 Mouse [Logitech USB-PS/2 Optical Mouse]0
done.
[ 6.986053] sd 0:0:0:0: [sda] No Caching mode page present
[ 6.999206] sd 0:0:0:0: [sda] Assuming drive cache: write through
[ 7.008850] sd 0:0:0:0: [sda] Attached SCSI removable disk
[ 7.063964] usb 1-1.3.2: new low speed USB device number 7 using ehci-omap
[ 7.205047] usb 1-1.3.2: New USB device found, idVendor=05ac, idProduct=021d
[ 7.215576] usb 1-1.3.2: New USB device strings: Mfr=1, Product=2, SerialNumber=0
[ 7.229461] usb 1-1.3.2: Product: Apple Keyboard
[ 7.238769] usb 1-1.3.2: Manufacturer: Apple Inc.
[ 7.923065] (stk) :line disc installation timed out
[ 7.929046] (stk) :ldisc_install = 0
[ 8.053192] (stk) :ldisc_install = 1[ 9.056304] (stk) :line disc installation timed out
fsck from util-linux-ng 2.17.2
rootfs: Superblock last write time is in the future.
        (by less than a day, probably due to the hardware clock being incorrectly set). FIXED.
rootfs: clean, 158629/489600 files, 509687/1956608 blocks
[ 11.735626] (stk) :line disc installation timed out
[ 12.845397] (stk) :line disc installation timed out
[ 12.853454] (stc): KIM failure complete callback
[ 12.861236] ti_st_open: st_register failed -22
[ 12.861236] fmdrv: ST reg comp CB called with error status 146
[ 12.861236] fmdrv: Unable to prepare FM CORE
 * Starting mDNS/DNS-SD daemon[ OK ]
fsck from util-linux-ng 2.17.2
rootfs: Superblock last write time is in the future.
        (by less than a day, probably due to the hardware clock being incorrectly set). FIXED.
rootfs: clean, 158629/489600 files, 509687/1956608 blocks
 * Starting mDNS/DNS-SD daemon[ OK ]
[ 19.272979]
[ 19.272979] =============================================
[ 19.274322] [ INFO: possible recursive locking detected ]
[ 19.286407] 3.0.0-1402-linaro-lt-omap #3~ppa~natty-Ubuntu
[ 19.289916] ---------------------------------------------
[ 19.297790] Xorg/1341 is trying to acquire lock:
[ 19.297943] (psPVRSRVMutex){+.+.+.}, at: [<bf0a0434>] LinuxMMapPerProcessDisconnect+0x18/0x64 [pvrsrvkm]
[ 19.305572]
[ 19.305572] but task is already holding lock:
[ 19.319030] (psPVRSRVMutex){+.+.+.}, at: [<bf0a0640>] PVRSRVRelease+0x14/0x80 [pvrsrvkm]
[ 19.323120]
[ 19.323120] other info that might help us debug this:
[ 19.330108] Possible unsafe locking scenario:
[ 19.330108]
[ 19.336639] CPU0
[ 19.343170] ----
[ 19.344726] lock(psPVRSRVMutex);
[ 19.349578] lock(psPVRSRVMutex);
[ 19.349578]
[ 19.349578] *** DEADLOCK ***
[ 19.353179]
[ 19.355041] May be due to missing lock nesting notation
[ 19.355041]
[ 19.361938] 1 lock held by Xorg/1341:
[ 19.361938] #0: (psPVRSRVMutex){+.+.+.}, at: [<bf0a0640>] PVRSRVRelease+0x14/0x80 [pvrsrvkm]
[ 19.361938]
[ 19.361938] stack backtrace:
[ 19.379852] [<c0063990>] (unwind_backtrace+0x0/0xe0) from [<c00c5abc>] (print_deadlock_bug+0xb0/0xdc)
[ 19.393920] [<c00c5abc>] (print_deadlock_bug+0xb0/0xdc) from [<c00c7724>] (validate_chain.clone.16+0x4cc/)
[ 19.395477] [<c00c7724>] (validate_chain.clone.16+0x4cc/0x51c) from [<c00c88e4>] (__lock_acquire+0x6e0/0x)
[ 19.408386] [<c00c88e4>] (__lock_acquire+0x6e0/0x7ac) from [<c00c8fd4>] (lock_acquire+0x114/0x138)
[ 19.408386] [<c00c8fd4>] (lock_acquire+0x114/0x138) from [<c0657890>] (mutex_lock_nested+0x60/0x398)
[ 19.434204] [<c0657890>] (mutex_lock_nested+0x60/0x398) from [<bf0a0434>] (LinuxMMapPerProcessDisconnect+)
[ 19.440277] [<bf0a0434>] (LinuxMMapPerProcessDisconnect+0x18/0x64 [pvrsrvkm]) from [<bf0a2088>] (OSPerPro)
[ 19.460357] [<bf0a2088>] (OSPerProcessPrivateDataDeInit+0x10/0x34 [pvrsrvkm]) from [<bf0af558>] (BridgedD)
[ 19.465240] [<bf0af558>] (BridgedDispatchKM+0x190/0x1c0 [pvrsrvkm]) from [<bf0af710>] (PVRSRVPerProcessDa)
[ 19.485748] [<bf0af710>] (PVRSRVPerProcessDataDisconnect+0x40/0x58 [pvrsrvkm]) from [<bf0a0678>] (PVRSRVR)
[ 19.499511] [<bf0a0678>] (PVRSRVRelease+0x4c/0x80 [pvrsrvkm]) from [<bf0b5e44>] (PVRSRVDrmRelease+0x18/0x)
[ 19.510925] [<bf0b5e44>] (PVRSRVDrmRelease+0x18/0x20 [pvrsrvkm]) from [<c012a4f4>] (__fput+0xf8/0x1c0)
[ 19.510925] [<c012a4f4>] (__fput+0xf8/0x1c0) from [<c01272cc>] (filp_close+0x70/0x7c)
[ 19.524505] [<c01272cc>] (filp_close+0x70/0x7c) from [<c0127364>] (sys_close+0x8c/0xcc)
[ 19.532287] [<c0127364>] (sys_close+0x8c/0xcc) from [<c005cf80>] (ret_fast_syscall+0x0/0x3c)
[ 19.735321] type=1400 audit(1311811130.367:2): apparmor="STATUS" operation="profile_load" name="/sbin/dhc"
[ 19.771179] type=1400 audit(1311811130.398:3): apparmor="STATUS" operation="profile_replace" name="/sbin/"
[ 19.793457] type=1400 audit(1311811130.421:4): apparmor="STATUS" operation="profile_replace" name="/sbin/"
[ 19.808441] type=1400 audit(1311811130.437:5): apparmor="STATUS" operation="profile_load" name="/usr/lib/"
[ 19.826934] type=1400 audit(1311811130.460:6): apparmor="STATUS" operation="profile_load" name="/usr/lib/"
[ 19.844482] type=1400 audit(1311811130.476:7): apparmor="STATUS" operation="profile_replace" name="/usr/l"
[ 19.863067] type=1400 audit(1311811130.492:8): apparmor="STATUS" operation="profile_replace" name="/usr/l"
[ 19.884429] type=1400 audit(1311811130.515:9): apparmor="STATUS" operation="profile_replace" name="/usr/l"
[ 19.905212] type=1400 audit(1311811130.531:10): apparmor="STATUS" operation="profile_replace" name="/sbin"
[ 19.928741] type=1400 audit(1311811130.554:11): apparmor="STATUS" operation="profile_replace" name="/usr/"
 * Starting AppArmor profiles Skipping profile in /etc/apparmor.d/disable: usr.bin.firefox
[ 23.385192] wl1271: firmware booted (Rev 6.1.3.50.55)
[ 23.391937] ieee80211 phy0: device now idle
[ 23.418304] ADDRCONF(NETDEV_UP): wlan0: link is not ready
[ 24.158874] ieee80211 phy0: device no longer idle - scanning
[ 25.047790] smsc95xx 1-1.1:1.0: eth0: link up, 100Mbps, full-duplex, lpa 0xCDE1
[ 25.886535] ieee80211 phy0: device now idle
[ 33.835815] eth0: no IPv6 routers present
[ 43.997924] ieee80211 phy0: device no longer idle - scanning
[ 45.722503] ieee80211 phy0: device now idle
[ 52.739562] audit_printk_skb: 48 callbacks suppressed
[ 52.739562] type=1400 audit(1311811163.367:28): apparmor="STATUS" operation="profile_load" name="/usr/bin"
[ 72.001190] type=1400 audit(1311811182.632:29): apparmor="STATUS" operation="profile_load" name="/usr/bin"
[ 72.024566] type=1400 audit(1311811182.656:30): apparmor="STATUS" operation="profile_load" name="/usr/bin"
[ OK ]
[ 73.998229] ieee80211 phy0: device no longer idle - scanning
[ 75.697875] ieee80211 phy0: device now idle

speech-dispatcher disabled; edit /etc/default/speech-dispatcher
[ OK ]rting bluetooth
[ 107.603332] ti_st_open: hci0 efb14000
[ 107.608123] (stc): st_register(2) *
[ 107.611907] (stc): chnl_id list empty :2 PulseAudio confi
[ 107.617492] (stk) : st_kim_startgured for per-user sessions

[ 107.711853] (stk) :ldisc_install = 1
[ 108.913604] (stk) :line disc installation timed out
[ 108.913635] (stk) :ldisc_install = 0
[ 109.022308] (stk) :ldisc_install = 1
[ 110.054687] (stk) :line disc installation timed out
[ 110.059936] (stk) :ldisc_install = 0saned disabled; edit /etc/default/saned

[ 110.180572] (stk) :ldisc_install = 1 * Enabling additional executable binary formats binfmt-support )

Tags: linaro-ti sgx
Revision history for this message
Ricardo Salveti (rsalveti) wrote :

Can confirm the issue but didn't see any side effect of this deadlock, as the desktop keeps working the same way as before, without any new issue when running GLES applications.

You can find the kernel module sources at http://git.linaro.org/gitweb?p=people/rsalveti/pvr-omap4-kernel.git;a=summary.

Changed in linaro-ubuntu:
status: New → Confirmed
importance: Undecided → Medium
milestone: none → 11.07
tags: added: linaro-ti sgx
Revision history for this message
Tom Gall (tom-gall) wrote :

This continues to affect the 0728-0 lt-panda-x11 hwpack. In my case the GUI never comes up and errors continue to spew to the serial cons.

Changed in linaro-ubuntu:
milestone: 11.07 → 11.08
Revision history for this message
Kurt Taylor (krtaylor) wrote :

I am seeing a very similar problem with 11.08 - will capture dmesg. GUI never comes up - serial with messages continues.

I've seen this with images and pre-built binaries (dd). Panda Ubuntu Desktop with X11 hwpack. Also similar results with ALIP.

Revision history for this message
Kurt Taylor (krtaylor) wrote :
Download full text (3.7 KiB)

[ 51.195922] end_request: I/O error, dev mmcblk0, sector 2729088
[ 51.195922] end_request: I/O error, dev mmcblk0, sector 2729096
[ 51.195922] end_request: I/O error, dev mmcblk0, sector 2729104
[ 51.251953] end_request: I/O error, dev mmcblk0, sector 2729112
[ 53.246337] Aborting journal on device mmcblk0p2-8.
[ 53.263671] journal commit I/O error
[ 53.276885] EXT4-fs error (device mmcblk0p2): ext4_journal_start_sb:296: Detected aborted journal

[ 53.277648] EXT4-fs (mmcblk0p2): Remounting filesystem read-only
[ 53.652770] mmcblk0: error -110 transferring data, sector 106496, nr 8, card status 0xc00
[ 53.661865] end_request: I/O error, dev mmcblk0, sector 106496
[ 53.666503] Buffer I/O error on device mmcblk0p2, logical block 0
[ 53.674468] lost page write due to I/O error on mmcblk0p2
[ 53.681030] EXT4-fs (mmcblk0p2): I/O error while writing superblock
[ 53.688415] EXT4-fs error (device mmcblk0p2): ext4_journal_start_sb:296: Detected aborted journal
[ 54.000579] ieee80211 phy0: device no longer idle - scanning
[ 54.049530] mmcblk0: error -110 sending read/write command, response 0x900, card status 0xe00
[ 54.059417] end_request: I/O error, dev mmcblk0, sector 156360
[ 54.064849] Buffer I/O error on device mmcblk0p2, logical block 6233
[ 54.064849] lost page write due to I/O error on mmcblk0p2
[ 55.697387] ieee80211 phy0: device now idle
[ 55.957580] EXT4-fs error (device mmcblk0p2): ext4_remount:4325: Abort forced by user

[ 102.271484] type=1400 audit(1314635671.576:19): apparmor="STATUS" operation="profile_load" name="/usr/bin/evince" pid=1239 comm="apparmor_parser"
/sbin/apparmor_parser: Unable to replace "/usr/bin/evince". Unknown error (30): Read-only file system

                                                                         [fail]
rm: cannot remove `/var/lib/urandom/random-seed': Read-only file system
chown: changing ownership of `/tmp/.X11-unix': Read-only file system

speech-dispatcher disabled; edit /etc/default/speech-dispatcher
 * Starting bluetooth
                                                                         [ OK ]
[ 103.547302] ti_st_open: hci0 eef1b000
[ 103.557037] (stc): st_register(2)
[ 103.558135] (stc): chnl_id list empty :2
[ 103.564788] (stk) : st_kim_start * PulseAudio configured for per-user sessions
saned disabled; edit /etc/default/saned
touch: setting times of `/var/lib/sudo': Read-only file system
 * Enabling additional executable binary formats binfmt-support
[ 104.011260] (stk) :ldisc_install = 1
                                                                         [ OK ]

[ 105.010345] (stk) :line disc installation timed out
[ 105.010345] (stk) :ldisc_install = 0
[ 105.519622] (stk) :ldisc_install = 1root@linaro-desktop:~#
[ 106.533691] (stk) :line disc installation timed out
[ 106.533691] (stk) :ldisc_install = 0
[ 106.939849] (stk) :ldisc_install = 1
[ 107.939941] (stk) :line disc installation timed out
[ 107.945190] (stk) :ldisc_install = 0
[ 108.385162] (stk) :ldisc_install = 1
[ 109.385223] (stk) :line disc installation timed out
[ 109.390...

Read more...

Changed in linaro-ubuntu:
milestone: 11.08 → 11.09
assignee: nobody → Ricardo Salveti (rsalveti)
Revision history for this message
Jeremiah C. Foster (jeremiah-foster) wrote :

I'm seeing this also on the Beagleboard image. Using the Beagle and linaro-alip Debian squeeze/sid with Xfce.

Revision history for this message
Ricardo Salveti (rsalveti) wrote :

Yes, probably because the PVR kernel module needs to be properly ported for the 3.x kernel series.

For omap 4 it would be good to check with the latest SGX packages available at the TI PPA: https://launchpad.net/~tiomap-dev/+archive/release

Changed in linaro-ubuntu:
milestone: 11.09 → 11.10
Revision history for this message
Ricardo Salveti (rsalveti) wrote :
Download full text (4.6 KiB)

This bug is still happening with latest SGX drop from TI:
pvr-omap4 - 1.7.9.0.1.5-1
pvr-omap4-dkms - 1.7.9.0.1.3-1
hwpack: hwpack_linaro-lt-panda-x11-base_20111016-0_armel_supported.tar.gz
image: linaro-o-ubuntu-desktop-tar-20111016-1.tar.gz

[ 36.257171] omap_device: omapdrm_pvr.0: new worst case activate latency 0: 305175
[ 36.274078] omaplfb: Device 31: Framebuffer physical address: 0xbd000000
[ 36.274108] omaplfb: Device 31: Framebuffer virtual address: 0xf1000000
[ 36.274108] omaplfb: Device 31: Framebuffer size: 8294400
[ 36.274139] omaplfb: Device 31: Framebuffer width: 1920
[ 36.274139] omaplfb: Device 31: Framebuffer height: 1080
[ 36.274169] omaplfb: Device 31: Framebuffer stride: 7680
[ 36.274169] omaplfb: Device 31: LCM of stride and page size: 61440
[ 36.274200] omaplfb: Device 31: Maximum number of swap chain buffers: 3
[ 36.274200] omaplfb: Device 31: PVR Device ID: 1
[ 36.277526]
[ 36.277526] =============================================
[ 36.284790] [ INFO: possible recursive locking detected ]
[ 36.289031] 3.0.0-1404-linaro-lt-omap #9~ppa-Ubuntu
[ 36.289031] ---------------------------------------------
[ 36.301269] Xorg/1594 is trying to acquire lock:
[ 36.302642] (psPVRSRVMutex){+.+.+.}, at: [<bf063c64>] LinuxLockMutex+0x1c/0x20 [omapdrm_pvr]
[ 36.308502]
[ 36.308502] but task is already holding lock:
[ 36.321319] (psPVRSRVMutex){+.+.+.}, at: [<bf063c64>] LinuxLockMutex+0x1c/0x20 [omapdrm_pvr]
[ 36.328704]
[ 36.328704] other info that might help us debug this:
[ 36.336517] Possible unsafe locking scenario:
[ 36.336517]
[ 36.342742] CPU0
[ 36.345642] ----
[ 36.347747] lock(psPVRSRVMutex);
[ 36.347747] lock(psPVRSRVMutex);
[ 36.355560]
[ 36.355560] *** DEADLOCK ***
[ 36.355560]
[ 36.359252] May be due to missing lock nesting notation
[ 36.361907]
[ 36.365966] 2 locks held by Xorg/1594:
[ 36.365966] #0: (drm_global_mutex){+.+.+.}, at: [<c03d8e14>] drm_release+0x34/0x388
[ 36.381195] #1: (psPVRSRVMutex){+.+.+.}, at: [<bf063c64>] LinuxLockMutex+0x1c/0x20 [omapdrm_pvr]
[ 36.381988]
[ 36.381988] stack backtrace:
[ 36.395263] [<c001ab2c>] (unwind_backtrace+0x0/0xec) from [<c06e371c>] (dump_stack+0x20/0x24)
[ 36.397644] [<c06e371c>] (dump_stack+0x20/0x24) from [<c008de18>] (print_deadlock_bug+0xb8/0xe4)
[ 36.413452] [<c008de18>] (print_deadlock_bug+0xb8/0xe4) from [<c008dfd0>] (check_deadlock.clone.15+0x18c/0x1b8)
[ 36.413574] [<c008dfd0>] (check_deadlock.clone.15+0x18c/0x1b8) from [<c008fb50>] (validate_chain.clone.16+0x504/0x554)
[ 36.435302] [<c008fb50>] (validate_chain.clone.16+0x504/0x554) from [<c0090d6c>] (__lock_acquire+0x708/0x7cc)
[ 36.443786] [<c0090d6c>] (__lock_acquire+0x708/0x7cc) from [<c0091478>] (lock_acquire+0x124/0x148)
[ 36.454864] [<c0091478>] (lock_acquire+0x124/0x148) from [<c06e5df8>] (mutex_lock_nested+0x70/0x3a8)
[ 36.464752] [<c06e5df8>] (mutex_lock_nested+0x70/0x3a8) from [<bf063c64>] (LinuxLockMutex+0x1c/0x20 [omapdrm_pvr])
[ 36.474548] [<bf063c64>] (LinuxLockMutex+0x1c/0x20 [omapdrm_pvr]) from [<bf061cec>] (LinuxMMapPerProcessDisconnect+0x28/0x78 [omapdrm_pvr])
[ 36.488830...

Read more...

summary: - deadlock 11.07 RC LEB with panda-lt-x11
+ SGX deadlock with panda lt x11 hwpack
Fathi Boudra (fboudra)
Changed in linaro-ubuntu:
milestone: 11.10 → 11.11
Revision history for this message
Ricardo Salveti (rsalveti) wrote :

Maybe something that the TI LT can take a look at?

Anmar Oueja (anmar)
Changed in linaro-landing-team-ti:
importance: Undecided → Low
Changed in linaro-ubuntu:
milestone: 11.11 → none
Revision history for this message
Fang Bao (fang-bao) wrote :
Download full text (3.8 KiB)

Is there any solution for PVR GPU's deadlock issue?

I am troubled by this issue from linaro ubuntu 11.07 to latest lianro ubuntu 11.11 on Pandaborad.

The video output of Pandaboard always worked incorrectly.

[ 37.492187] =============================================
[ 37.499450] [ INFO: possible recursive locking detected ]
[ 37.505157] 3.1.0-1402-linaro-lt-omap #5~oneiric1-Ubuntu
[ 37.510742] ---------------------------------------------
[ 37.516418] Xorg/1686 is trying to acquire lock:
[ 37.521270] (psPVRSRVMutex){+.+.+.}, at: [<bf06ec34>] LinuxLockMutex+0x1c/0x20 [omapdrm_pvr]
[ 37.530334]
[ 37.530334] but task is already holding lock:
[ 37.536468] (psPVRSRVMutex){+.+.+.}, at: [<bf06ec34>] LinuxLockMutex+0x1c/0x20 [omapdrm_pvr]
[ 37.545471]
[ 37.545471] other info that might help us debug this:
[ 37.552337] Possible unsafe locking scenario:
[ 37.552368]
[ 37.558593] CPU0
[ 37.561157] ----
[ 37.563720] lock(psPVRSRVMutex);
[ 37.567321] lock(psPVRSRVMutex);
[ 37.570892]
[ 37.570922] *** DEADLOCK ***
[ 37.570922]
[ 37.577148] May be due to missing lock nesting notation
[ 37.577148]
[ 37.584320] 2 locks held by Xorg/1686:
[ 37.588256] #0: (drm_global_mutex){+.+.+.}, at: [<c03d0b84>] drm_release+0x34/0x38c
[ 37.596557] #1: (psPVRSRVMutex){+.+.+.}, at: [<bf06ec34>] LinuxLockMutex+0x1c/0x20 [omapdrm_pvr]
[ 37.606018]
[ 37.606018] stack backtrace:
[ 37.610626] [<c001a950>] (unwind_backtrace+0x0/0xec) from [<c06e3988>] (dump_stack+0x20/0x24)
[ 37.619628] [<c06e3988>] (dump_stack+0x20/0x24) from [<c008e3f8>] (print_deadlock_bug+0xb8/0xe4)
[ 37.628906] [<c008e3f8>] (print_deadlock_bug+0xb8/0xe4) from [<c008e584>] (check_deadlock.isra.19+0x160/0x18c)
[ 37.639434] [<c008e584>] (check_deadlock.isra.19+0x160/0x18c) from [<c008fc74>] (validate_chain.isra.23+0x4cc/0x51c)
[ 37.650543] [<c008fc74>] (validate_chain.isra.23+0x4cc/0x51c) from [<c00909e0>] (__lock_acquire+0x6a0/0x76c)
[ 37.660888] [<c00909e0>] (__lock_acquire+0x6a0/0x76c) from [<c00910ec>] (lock_acquire+0x138/0x15c)
[ 37.670349] [<c00910ec>] (lock_acquire+0x138/0x15c) from [<c0704f10>] (mutex_lock_nested+0x54/0x38c)
[ 37.679962] [<c0704f10>] (mutex_lock_nested+0x54/0x38c) from [<bf06ec34>] (LinuxLockMutex+0x1c/0x20 [omapdrm_pvr])
[ 37.690917] [<bf06ec34>] (LinuxLockMutex+0x1c/0x20 [omapdrm_pvr]) from [<bf06ccbc>] (LinuxMMapPerProcessDisconnect+0x28/0x78 [omapd)
[ 37.704132] [<bf06ccbc>] (LinuxMMapPerProcessDisconnect+0x28/0x78 [omapdrm_pvr]) from [<bf06f130>] (OSPerProcessPrivateDataDeInit+0)
[ 37.718719] [<bf06f130>] (OSPerProcessPrivateDataDeInit+0x20/0x44 [omapdrm_pvr]) from [<bf07e610>] (BridgedDispatchKM+0x1f8/0x228 [)
[ 37.732391] [<bf07e610>] (BridgedDispatchKM+0x1f8/0x228 [omapdrm_pvr]) from [<bf07e804>] (PVRSRVPerProcessDataDisconnect+0x50/0x68 )
[ 37.746154] [<bf07e804>] (PVRSRVPerProcessDataDisconnect+0x50/0x68 [omapdrm_pvr]) from [<bf076d78>] (PVRSRVProcessDisconnect+0x18/0)
[ 37.760284] [<bf076d78>] (PVRSRVProcessDisconnect+0x18/0x1c [omapdrm_pvr]) from [<bf06d028>] (PVRSRVRelease+0x5c/0x94 [omapdrm_pvr])
[ 37.772827] [<bf06d028>] (PVRSRVRelease+0x5c/0x94 [omapdrm_pvr]) ...

Read more...

Revision history for this message
Ricardo Salveti (rsalveti) wrote :

Andy, as this bug is around for quite a while already, can you take a look at it and see if you can find the cause of the deadlock?

If you need the sources of the SGX kernel module, please check http://git.linaro.org/gitweb?p=people/rsalveti/pvr-omap4-kernel.git;a=summary.

Revision history for this message
warmcat (andy-warmcat) wrote :

FWIW this issue is gone in SGX 1.8 driver used on ICS.

Revision history for this message
Ricardo Salveti (rsalveti) wrote :

Do you know if this issue was also happening with SGX 1.7 for Android? Before actually comparing the code base, would be useful to know if the issue was also happening there, as our use case is quite different from the Android (we're using the DRM driver and X11).

Xavier or Nicolas, I know you're about to update the driver again with a newer version, do you know if it'll still be based on 1.7?

Revision history for this message
Ricardo Salveti (rsalveti) wrote :

Also, this actually depends a bit at how the initialization is done, as after installing all the syslink userspace packages, this issue doesn't happen anymore with Ubuntu (it shows another error, but probably not related with this one: http://paste.ubuntu.com/797747/). Probably related with a racing condition.

Revision history for this message
warmcat (andy-warmcat) wrote :

Yeah it was happening with Android SGX 1.7 stuff as well. In all cases though, it's symptomless.

The new locking warning is entirely to do with syslink stuff... so sort of traded an SGX userland / kernel locking mystery for a syslink one :-O

Revision history for this message
Ricardo Salveti (rsalveti) wrote :

Issue not happening anymore with the 12.06 hwpack. Closing as fix released.

Changed in linaro-ubuntu:
status: Confirmed → Fix Released
Changed in ubuntu-omap4-extras-graphics:
status: New → Fix Released
Changed in linaro-landing-team-ti:
status: New → Fix Released
Changed in linaro-ubuntu:
milestone: none → 12.06
To post a comment you must log in.
This report contains Public information  
Everyone can see this information.

Duplicates of this bug

Other bug subscribers

Remote bug watches

Bug watches keep track of this bug in other bug trackers.