#11843 NORM 12.1.0: task wpa_supplicant blocked for more than 120 seconds
Zarro Boogs per Child
bugtracker at laptop.org
Wed May 9 14:54:28 EDT 2012
#11843: task wpa_supplicant blocked for more than 120 seconds
--------------------+-------------------------------------------------------
Reporter: pgf | Owner:
Type: defect | Status: new
Priority: normal | Milestone: 12.1.0
Component: kernel | Version: Development build as of this date
Keywords: | Next_action: diagnose
Verified: 0 | Deployment_affected:
Blockedby: | Blocking:
--------------------+-------------------------------------------------------
i'm seeing this repeatably on a 1.5 running os9. the log is lightly
annotate -- search for ": note". this was a fresh boot, and i used a
magnet to simulate lid close.
{{{
Forthmacs
Type 'i' to interrupt stand-init sequence
USB2 devices:
USB1 devices:
OLPC D5, 1 GiB memory installed, 4 GB internal storage, S/N SHC049001A1
OpenFirmware Q3C05 EC Firmware Ver:2.2.10 2012-05-09 18:34:13 UTC
Type the Esc key to interrupt automatic startup
Boot device: /sd/disk at 3:\boot\olpc.fth Arguments:
Boot device: /pci/sd at c/disk at 3:\boot\vmlinuz Arguments:
Loading ramdisk image from /pci/sd at c/disk at 3:\boot\initrd.img ...
[ 0.000000] Initializing cgroup subsys cpu
[ 0.000000] Linux version 3.3.3_xo1.5-20120424.1316.olpc.5d76364
(kernels at bob.laptop.org) (gcc version 4.7.0 20120322 (Red Hat 4.7.0-1)
(GCC) ) #1 PREEMPT Tue Apr 24 13:20:55 EDT 2012
[ 0.000000] OFW detected in memory, cif @ 0xff83a6c8 (reserving top
8MB)
[ 0.000000] Reserving virtual address space above 0xff800000
[ 0.000000] BIOS-provided physical RAM map:
[ 0.000000] BIOS-e801: 0000000000000000 - 000000000009f000 (usable)
[ 0.000000] BIOS-e801: 0000000000100000 - 000000003b8ed400 (usable)
[ 0.000000] Notice: NX (Execute Disable) protection cannot be enabled:
non-PAE kernel!
[ 0.000000] DMI 2.1 present.
[ 0.000000] last_pfn = 0x3b8ed max_arch_pfn = 0x100000
[ 0.000000] init_memory_mapping: 0000000000000000-000000003b8ed000
[ 0.000000] RAMDISK: 3b8ed600 - 3bdfd000
[ 0.000000] Allocated new RAMDISK: 3b3dd000 - 3b8eca00
[ 0.000000] Move RAMDISK from 000000003b8ed600 - 000000003bdfcfff to
3b3dd000 - 3b8ec9ff
[ 0.000000] ACPI: RSDP 000e0000 00024 (v02 OLPC )
[ 0.000000] ACPI: RSDT 000e0040 00034 (v01 OLPC OLPC_000 30303030
OLPC 30303030)
[ 0.000000] ACPI: FACP 000e0080 00084 (v02 OLPC OLPC_000 30303030
OLPC 30303030)
[ 0.000000] ACPI: DSDT 000fc000 023CE (v01 OLPC XO-1.5 00000001
INTL 20061109)
[ 0.000000] ACPI: FACS 000e0180 00040
[ 0.000000] ACPI: DSDT 000fc000 023CE (v01 OLPC XO-1.5 00000001
INTL 20061109)
[ 0.000000] ACPI: APIC 000e0200 0005A (v01 OLPC OLPC_000 30303030
OLPC 30303030)
[ 0.000000] ACPI: HPET 000e0280 00038 (v01 OLPC OLPC_000 30303030
OLPC 30303030)
[ 0.000000] 952MB LOWMEM available.
[ 0.000000] mapped low ram: 0 - 3b8ed000
[ 0.000000] low ram: 0 - 3b8ed000
[ 0.000000] PROM DT: Built device tree with 45056 bytes of memory.
[ 0.000000] Zone PFN ranges:
[ 0.000000] DMA 0x00000010 -> 0x00001000
[ 0.000000] Normal 0x00001000 -> 0x0003b8ed
[ 0.000000] Movable zone start PFN for each node
[ 0.000000] Early memory PFN ranges
[ 0.000000] 0: 0x00000010 -> 0x0000009f
[ 0.000000] 0: 0x00000100 -> 0x0003b8ed
[ 0.000000] Using APIC driver default
[ 0.000000] ACPI: PM-Timer IO Port: 0x408
[ 0.000000] ACPI: LAPIC (acpi_id[0x00] lapic_id[0x00] enabled)
[ 0.000000] ACPI: LAPIC_NMI (acpi_id[0x00] high edge lint[0x1])
[ 0.000000] ACPI: IOAPIC (id[0x01] address[0xfec00000] gsi_base[0])
[ 0.000000] IOAPIC[0]: apic_id 1, version 3, address 0xfec00000, GSI
0-23
[ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 high edge)
[ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 low level)
[ 0.000000] Using ACPI (MADT) for SMP configuration information
[ 0.000000] ACPI: HPET id: 0x11068201 base: 0xfed00000
[ 0.000000] Allocating PCI resources starting at 3b8ed400 (gap:
3b8ed400:c4712c00)
[ 0.000000] Built 1 zonelists in Zone order, mobility grouping on.
Total pages: 241930
[ 0.000000] Kernel command line: console=ttyS0,115200 console=tty0
fbcon=font:SUN12x22 no_console_suspend selinux=0
[ 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] Initializing CPU#0
[ 0.000000] Memory: 955712k/975796k available (3345k kernel code,
19632k reserved, 1430k data, 424k init, 0k highmem)
[ 0.000000] virtual kernel memory layout:
[ 0.000000] fixmap : 0xff7a3000 - 0xff7ff000 ( 368 kB)
[ 0.000000] vmalloc : 0xec0ed000 - 0xff7a1000 ( 310 MB)
[ 0.000000] lowmem : 0xb0000000 - 0xeb8ed000 ( 952 MB)
[ 0.000000] .init : 0xb08ab000 - 0xb0915000 ( 424 kB)
[ 0.000000] .data : 0xb0744661 - 0xb08aa180 (1430 kB)
[ 0.000000] .text : 0xb0400000 - 0xb0744661 (3345 kB)
[ 0.000000] Checking if this processor honours the WP bit even in
supervisor mode...Ok.
[ 0.000000] NR_IRQS:2304 nr_irqs:256 16
[ 0.000000] Console: colour dummy device 80x25
[ 0.000000] console [tty0] enabled
[ 0.000000] console [ttyS0] enabled
[ 0.000000] ODEBUG: selftest passed
[ 0.000000] Fast TSC calibration using PIT
[ 0.000000] Detected 997.458 MHz processor.
[ 0.010004] Calibrating delay loop (skipped), value calculated using
timer frequency.. 1994.91 BogoMIPS (lpj=9974580)
[ 0.022819] pid_max: default: 4096 minimum: 301
[ 0.030226] Security Framework initialized
[ 0.034425] Mount-cache hash table entries: 512
[ 0.043414] CPU: Centaur VIA C7-M Processor 1000MHz stepping 00
[ 0.050087] ACPI: Core revision 20120111
[ 0.063694] ftrace: allocating 17982 entries in 36 pages
[ 0.100159] Performance Events:
[ 0.110282] Enabling APIC mode: Flat. Using 1 I/O APICs
[ 0.116407] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1
[ 0.230401] devtmpfs: initialized
[ 0.234346] NET: Registered protocol family 16
[ 0.239853] OLPC board revision D5 (EC=60)
[ 0.240398] ACPI: bus type pci registered
[ 0.245903] PCI: Using configuration type 1 for base access
[ 0.254300] bio: create slab <bio-0> at 0
[ 0.260316] ACPI: Added _OSI(Module Device)
[ 0.264519] ACPI: Added _OSI(Processor Device)
[ 0.270020] ACPI: Added _OSI(3.0 _SCP Extensions)
[ 0.274746] ACPI: Added _OSI(Processor Aggregator Device)
[ 0.298493] ACPI: Interpreter enabled
[ 0.300016] ACPI: (supports S0 S3 S5)
[ 0.303823] ACPI: Using IOAPIC for interrupt routing
[ 0.344352] ACPI: No dock devices found.
[ 0.348316] PCI: Using host bridge windows from ACPI; if necessary, use
"pci=nocrs" and report a bug
[ 0.350362] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff])
[ 0.360476] pci_root PNP0A03:00: host bridge window [io 0x0000-0x0cf7]
[ 0.370009] pci_root PNP0A03:00: host bridge window [io 0x0d00-0xffff]
[ 0.376646] pci_root PNP0A03:00: host bridge window [mem
0x000a0000-0x000bffff]
[ 0.380008] pci_root PNP0A03:00: host bridge window [mem
0x000c0000-0x000dffff]
[ 0.390008] pci_root PNP0A03:00: host bridge window [mem
0xfed40000-0xfed44fff]
[ 0.397340] pci_root PNP0A03:00: host bridge window [mem
0x40000000-0xffe80000]
[ 0.400012] pci_root PNP0A03:00: host bridge window expanded to [mem
0x40000000-0xffe80000]; [mem 0x40000000-0xffe80000] ignored
[ 0.410182] PCI host bridge to bus 0000:00
[ 0.420008] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7]
[ 0.426204] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff]
[ 0.430007] pci_bus 0000:00: root bus resource [mem
0x000a0000-0x000bffff]
[ 0.440007] pci_bus 0000:00: root bus resource [mem
0x000c0000-0x000dffff]
[ 0.446907] pci_bus 0000:00: root bus resource [mem
0x40000000-0xffe80000]
[ 0.452833] pci 0000:00:13.0: PCI bridge to [bus 01-01]
[ 0.461130] pci0000:00: Unable to request _OSC control (_OSC support
mask: 0x08)
[ 0.500189] ACPI: PCI Interrupt Link [LNKA] (IRQs 3 4 5 6 7 *10 11 12
14 15)
[ 0.507771] ACPI: PCI Interrupt Link [LNKB] (IRQs 3 4 5 6 7 10 11 12 14
15) *9
[ 0.513234] ACPI: PCI Interrupt Link [LNKC] (IRQs 3 4 5 6 7 10 *11 12
14 15)
[ 0.523031] ACPI: PCI Interrupt Link [LNKD] (IRQs 3 4 5 6 7 *10 11 12
14 15)
[ 0.530513] ACPI: PCI Interrupt Link [LNKE] (IRQs 3 4 5 6 7 10 11 12 14
15) *0, disabled.
[ 0.539258] ACPI: PCI Interrupt Link [LNKF] (IRQs 3 4 5 6 7 10 11 12 14
15) *0, disabled.
[ 0.544210] ACPI: PCI Interrupt Link [LNK0] (IRQs 3 4 5 6 7 10 11 12 14
15) *0, disabled.
[ 0.554204] ACPI: PCI Interrupt Link [LNK1] (IRQs 3 4 5 6 7 10 11 12 14
15) *0, disabled.
[ 0.564555] SCSI subsystem initialized
[ 0.568447] usbcore: registered new interface driver usbfs
[ 0.570061] usbcore: registered new interface driver hub
[ 0.580129] usbcore: registered new device driver usb
[ 0.585507] Advanced Linux Sound Architecture Driver Version 1.0.24.
[ 0.590017] PCI: Using ACPI for IRQ routing
[ 0.595308] Switching to clocksource hpet
[ 0.600037] cfg80211: Calling CRDA to update world regulatory domain
[ 0.610514] pnp: PnP ACPI init
[ 0.613641] ACPI: bus type pnp registered
[ 0.618967] system 00:01: [mem 0xe0000000-0xefffffff] has been reserved
[ 0.625891] system 00:02: [mem 0xfee00000-0xfee00fff] has been reserved
[ 0.632549] system 00:02: [mem 0xfec00000-0xfec00fff] could not be
reserved
[ 0.651826] system 00:0a: [io 0x0380-0x0387] has been reserved
[ 0.657774] system 00:0a: [io 0x03e0-0x03e7] has been reserved
[ 0.663746] system 00:0a: [io 0x03f8-0x03ff] has been reserved
[ 0.669687] system 00:0a: [io 0x04d0-0x04d1] has been reserved
[ 0.675642] system 00:0a: [io 0x0400-0x047f] has been reserved
[ 0.681596] system 00:0a: [io 0x0500-0x050f] has been reserved
[ 0.687539] system 00:0a: [mem 0xfed30000-0xfed300ff] has been reserved
[ 0.694190] system 00:0a: [mem 0xfed30000-0xfed30fff] could not be
reserved
[ 0.702166] system 00:0b: [mem 0x00000000-0x0009ffff] could not be
reserved
[ 0.709152] system 00:0b: [mem 0x000e0000-0x000fffff] could not be
reserved
[ 0.716156] system 00:0b: [mem 0x00100000-0x3bffffff] could not be
reserved
[ 0.723190] pnp: PnP ACPI: found 12 devices
[ 0.727391] ACPI: ACPI bus type pnp unregistered
[ 0.772823] pci 0000:00:01.0: BAR 6: assigned [mem
0x40000000-0x4000ffff pref]
[ 0.780096] pci 0000:00:13.0: PCI bridge to [bus 01-01]
[ 0.785649] NET: Registered protocol family 2
[ 0.790192] IP route cache hash table entries: 32768 (order: 5, 131072
bytes)
[ 0.798017] TCP established hash table entries: 131072 (order: 8,
1048576 bytes)
[ 0.808177] TCP bind hash table entries: 65536 (order: 8, 1310720
bytes)
[ 0.817295] TCP: Hash tables configured (established 131072 bind 65536)
[ 0.823963] TCP reno registered
[ 0.827371] NET: Registered protocol family 1
[ 0.832087] pci 0000:00:13.0: disabling DAC on VIA PCI bridge
[ 0.838040] Trying to unpack rootfs image as initramfs...
[ 0.890891] Freeing initrd memory: 5184k freed
[ 0.905191] olpc-xo15-sci XO15EC:00: Initialized, GPE = 0x1
[ 1.005192] HugeTLB registered 4 MB page size, pre-allocated 0 pages
[ 1.011964] VFS: Disk quotas dquot_6.5.2
[ 1.015948] Dquot-cache hash table entries: 1024 (order 0, 4096 bytes)
[ 1.023239] msgmni has been set to 1876
[ 1.027428] cryptomgr_test used greatest stack depth: 7700 bytes left
[ 1.034014] cryptomgr_test used greatest stack depth: 7684 bytes left
[ 1.040708] io scheduler noop registered
[ 1.044737] io scheduler cfq registered (default)
[ 1.049736] ioapic: probe of 0000:00:00.5 failed with error -22
[ 1.055953] VIA Graphics Integration Chipset framebuffer 2.4
initializing
[ 1.076860] gpiochip_add: registered GPIOs 250 to 255 on device: VIAFB
onboard GPIO
[ 1.110622] Console: switching to colour frame buffer device 100x40
[ 1.139120] input: Lid Switch as
/devices/LNXSYSTM:00/device:00/PNP0A03:00/PNP0C0D:00/input/input0
[ 1.148951] ACPI: Lid Switch [LID]
[ 1.152854] input: Power Button as
/devices/LNXSYSTM:00/LNXPWRBN:00/input/input1
[ 1.160773] ACPI: Power Button [PWRF]
[ 1.164941] Marking TSC unstable due to TSC halts in idle
[ 1.171145] Serial: 8250/16550 driver, 1 ports, IRQ sharing enabled
�[ 1.470194] serial8250: ttyS0 at I/O 0x3f8 (irq = 4) is a 16550A
[ 1.477493] Non-volatile memory driver v1.3
[ 1.482014] VIA RNG detected
[ 1.485188] Linux agpgart interface v0.103
[ 1.505355] brd: module loaded
[ 1.519847] vx855_gpio vx855_gpio: found VX855 GPIO controller
[ 1.537019] gpiochip_add: registered GPIOs 0 to 41 on device: VX855
South Bridge
[ 1.556052] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
[ 1.574041] ehci_hcd 0000:00:10.4: EHCI Host Controller
[ 1.590586] ehci_hcd 0000:00:10.4: new USB bus registered, assigned bus
number 1
[ 1.609367] ehci_hcd 0000:00:10.4: debug port 1
[ 1.625125] ehci_hcd 0000:00:10.4: irq 23, io mem 0x80003000
[ 1.660037] ehci_hcd 0000:00:10.4: USB 2.0 started, EHCI 1.00
[ 1.676876] usb usb1: New USB device found, idVendor=1d6b,
idProduct=0002
[ 1.694874] usb usb1: New USB device strings: Mfr=3, Product=2,
SerialNumber=1
[ 1.713275] usb usb1: Product: EHCI Host Controller
[ 1.729187] usb usb1: Manufacturer: Linux
3.3.3_xo1.5-20120424.1316.olpc.5d76364 ehci_hcd
[ 1.748687] usb usb1: SerialNumber: 0000:00:10.4
[ 1.764869] hub 1-0:1.0: USB hub found
[ 1.779610] hub 1-0:1.0: 6 ports detected
[ 1.794707] uhci_hcd: USB Universal Host Controller Interface driver
[ 1.812400] uhci_hcd 0000:00:10.0: UHCI Host Controller
[ 1.828777] uhci_hcd 0000:00:10.0: new USB bus registered, assigned bus
number 2
[ 1.847568] uhci_hcd 0000:00:10.0: irq 20, io base 0x00008000
[ 1.864654] usb usb2: New USB device found, idVendor=1d6b,
idProduct=0001
[ 1.882764] usb usb2: New USB device strings: Mfr=3, Product=2,
SerialNumber=1
[ 1.901330] usb usb2: Product: UHCI Host Controller
[ 1.917221] usb usb2: Manufacturer: Linux
3.3.3_xo1.5-20120424.1316.olpc.5d76364 uhci_hcd
[ 1.936666] usb usb2: SerialNumber: 0000:00:10.0
[ 1.952625] hub 2-0:1.0: USB hub found
[ 1.966944] hub 2-0:1.0: 2 ports detected
[ 1.981745] uhci_hcd 0000:00:10.1: UHCI Host Controller
[ 1.997482] uhci_hcd 0000:00:10.1: new USB bus registered, assigned bus
number 3
[ 2.015411] uhci_hcd 0000:00:10.1: irq 22, io base 0x00008020
[ 2.031624] usb usb3: New USB device found, idVendor=1d6b,
idProduct=0001
[ 2.049027] usb usb3: New USB device strings: Mfr=3, Product=2,
SerialNumber=1
[ 2.066738] usb usb3: Product: UHCI Host Controller
[ 2.082035] usb usb3: Manufacturer: Linux
3.3.3_xo1.5-20120424.1316.olpc.5d76364 uhci_hcd
[ 2.101023] usb usb3: SerialNumber: 0000:00:10.1
[ 2.116821] hub 3-0:1.0: USB hub found
[ 2.131311] hub 3-0:1.0: 2 ports detected
[ 2.146102] uhci_hcd 0000:00:10.2: UHCI Host Controller
[ 2.161853] uhci_hcd 0000:00:10.2: new USB bus registered, assigned bus
number 4
[ 2.179816] uhci_hcd 0000:00:10.2: irq 21, io base 0x00008040
[ 2.196109] usb usb4: New USB device found, idVendor=1d6b,
idProduct=0001
[ 2.213450] usb usb4: New USB device strings: Mfr=3, Product=2,
SerialNumber=1
[ 2.231238] usb usb4: Product: UHCI Host Controller
[ 2.246746] usb usb4: Manufacturer: Linux
3.3.3_xo1.5-20120424.1316.olpc.5d76364 uhci_hcd
[ 2.265967] usb usb4: SerialNumber: 0000:00:10.2
[ 2.281825] hub 4-0:1.0: USB hub found
[ 2.296311] hub 4-0:1.0: 2 ports detected
[ 2.311103] Initializing USB Mass Storage driver...
[ 2.326534] usbcore: registered new interface driver usb-storage
[ 2.343019] USB Mass Storage support registered.
[ 2.358194] usbcore: registered new interface driver libusual
[ 2.374739] usbcore: registered new interface driver usbserial
[ 2.391027] USB Serial support registered for generic
[ 2.406593] usbcore: registered new interface driver usbserial_generic
[ 2.423877] usbserial: USB Serial Driver core
[ 2.438923] i8042: PNP: PS/2 Controller [PNP0303:PS2K,PNP0f13:PS2M] at
0x60,0x64 irq 1,12
[ 2.482955] serio: i8042 KBD port at 0x60,0x64 irq 1
[ 2.498830] serio: i8042 AUX port at 0x60,0x64 irq 12
[ 2.514932] rtc_cmos 00:06: RTC can wake from S4
[ 2.530285] rtc_cmos 00:06: rtc core: registered rtc_cmos as rtc0
[ 2.546732] rtc0: alarms up to one year, y3k, 114 bytes nvram, hpet
irqs
[ 2.564151] Linux video capture interface: v2.00
[ 2.611267] input: AT Translated Set 2 keyboard as
/devices/platform/i8042/serio0/input/input2
[ 2.831584] hwmon_vid: Using 6-bit VID table for VIA C7-M CPU
[ 2.861659] cpuidle: using governor ladder
[ 2.897934] cpuidle: using governor menu
[ 2.921557] sdhci: Secure Digital Host Controller Interface driver
[ 2.961694] sdhci: Copyright(c) Pierre Ossman
[ 2.999873] sdhci-pci 0000:00:0c.0: SDHCI controller found [1106:95d0]
(rev 10)
[ 3.028454] mmc0: SDHCI controller on PCI [0000:00:0c.0] using DMA
[ 3.071845] mmc1: SDHCI controller on PCI [0000:00:0c.0] using DMA
[ 3.127196] mmc2: SDHCI controller on PCI [0000:00:0c.0] using DMA
[ 3.155193] usbcore: registered new interface driver usbhid
[ 3.200473] usbhid: USB HID core driver
[ 3.235961] i2c-core: driver [olpc_dcon] using legacy suspend method
[ 3.253001] i2c-core: driver [olpc_dcon] using legacy resume method
[ 3.274358] olpc-dcon: Discovered DCON version 2
[ 3.320427] input: EBook Switch as
/devices/LNXSYSTM:00/device:00/PNP0A03:00/XO15EBK:00/input/input3
[ 3.366665] modprobe used greatest stack depth: 7064 bytes left
[ 3.383692] snd_hda_intel 0000:00:14.0: enabling device (0000 -> 0002)
[ 3.401187] snd_hda_intel 0000:00:14.0: PCI: Disallowing DAC for device
[ 3.449481] mmc1: new SDIO card at address 0001
[ 3.485784] ALSA device list:
[ 3.500550] #0: HDA VIA VT82xx at 0x80004000 irq 17
[ 3.516675] TCP bic registered
[ 3.530336] Initializing XFRM netlink socket
[ 3.545670] NET: Registered protocol family 10
[ 3.564436] Mobile IPv6
[ 3.577382] NET: Registered protocol family 17
[ 3.592326] lib80211: common routines for IEEE802.11 drivers
[ 3.608392] Registering the dns_resolver key type
[ 3.623356] Using IPI Shortcut mode
[ 3.641545] Freeing unused kernel memory: 424k freed
[ 3.658092] Write protecting the kernel text: 3348k
[ 3.673569] Write protecting the kernel read-only data: 1172k
[ 3.703915] mmc2: new SDHC card at address e624
[ 3.723665] init used greatest stack depth: 6988 bytes left
[ 3.739689] mmcblk0: mmc2:e624 SU04G 3.69 GiB
[ 3.763722] mmcblk0: p1 p2
[ 3.801363] dracut: dracut-017-62.git20120322.fc17
[ 3.837021] udevd[59]: starting version 182
[ 3.861753] udevadm used greatest stack depth: 6624 bytes left
[ 5.377943] EXT4-fs (mmcblk0p2): INFO: recovery required on readonly
filesystem
[ 5.395325] EXT4-fs (mmcblk0p2): write access will be enabled during
recovery
[ 6.226618] EXT4-fs (mmcblk0p2): recovery complete
[ 6.246774] EXT4-fs (mmcblk0p2): mounted filesystem with ordered data
mode. Opts: (null)
[ 6.265438] mount used greatest stack depth: 6360 bytes left
[ 6.300708] dracut: Remounting /dev/disk/mmc/mmc2p2 with -o ro
[ 6.345542] EXT4-fs (mmcblk0p2): mounted filesystem with ordered data
mode. Opts: (null)
[ 6.376925] dracut: Mounted root filesystem /dev/disk/mmc/mmc2p2
[ 6.405125] EXT4-fs (mmcblk0p2): re-mounted. Opts:
user_xattr,barrier=1,data=ordered
[ 6.436246] EXT4-fs (mmcblk0p1): warning: mounting unchecked fs,
running e2fsck is recommended
[ 6.460044] EXT4-fs (mmcblk0p1): mounted filesystem without journal.
Opts: (null)
[ 7.231539] plymouthd used greatest stack depth: 6320 bytes left
[ 7.716151] dcon_freeze_store: 0
[ 7.719618] dcon_source_switch to CPU
[ 7.761341] olpc-dcon: The CPU has control
[ 7.820899] dracut: Switching root
[ 8.956397] udevd[189]: starting version 182
[ 9.358280] EXT4-fs (mmcblk0p2): re-mounted. Opts: (null)
[ 10.139960] Via camera: serial port is enabled, refusing to load.
[ 10.210785] Specify override_serial=1 to force module loading.
[ 10.216654] viafb-camera: probe of viafb-camera failed with error -16
[ 10.387202] libertas_sdio: Libertas SDIO driver
[ 10.446254] libertas_sdio: Copyright Pierre Ossman
[ 10.657591] psmouse serio1: synaptics: OLPC XO detected, not enabling
Synaptics protocol.
[ 11.810799] input: PS/2 Synaptics TouchPad as
/devices/platform/i8042/serio1/input/input4
[ 11.853309] mousedev: PS/2 mouse device common for all mice
[ 12.025923] usbcore: registered new interface driver sisusb
[ 12.053378] usbcore: deregistering interface driver sisusb
[ 12.431638] libertas_sdio mmc1:0001:1: (unregistered net_device):
20:7c:8f:4a:9e:07, fw 9.70.20p0, cap 0x000003a3
[ 12.459555] libertas_sdio mmc1:0001:1: wlan0: Marvell WLAN 802.11
adapter
[ 12.623791] dcon_freeze_store: 1
[ 12.627461] dcon_source_switch to DCON
[ 12.640444] udevd[229]: renamed network interface wlan0 to eth0
[ 12.664809] olpc-dcon: The DCON has control
[ 13.330912] psmouse serio1: synaptics: Touchpad model: 1, fw: 6.2, id:
0x92a0b1, caps: 0xa04711/0x200000/0x0
[ 13.458905] input: olpc-kbdshim virtual input as
/devices/virtual/input/input5
[ 13.564026] input: SynRelPS/2 Synaptics TouchPad as
/devices/platform/i8042/serio1/input/input6
[ 14.246345] ip_tables: (C) 2000-2006 Netfilter Core Team
[ 15.600217] ADDRCONF(NETDEV_UP): eth0: link is not ready
[ 18.308647] fuse init (API version 7.18)
[ 19.053362] ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
[ 19.744548] dcon_freeze_store: 0
[ 19.748110] dcon_source_switch to CPU
[ 19.791387] olpc-dcon: The CPU has control
bash-4.2#
bash-4.2#
bash-4.2#
bash-4.2#
bash-4.2#
bash-4.2# powerd-config =trace-on
bash-4.2# tail -f /var/log/powerd.trace &
[1] 916
powerd-dbus-Message: powerd_send_event inhibit-suspend
powerd-dbus-Message: 1336588477: Device 0x9876008 state changed: 50
powerd-dbus-Message: 1336588477: device 0x9876008 state 50 inhibits
suspend
powerd-dbus-Message: 1336588479: Device 0x9876008 state changed: 70
powerd-dbus-Message: 1336588479: device 0x9876008 state 70 inhibits
suspend
powerd-dbus-Message: powerd_send_event unfreeze_dcon
powerd-dbus-Message: 1336588481: Device 0x9876008 state changed: 100
powerd-dbus-Message: 1336588481: sending suspend-OK message after settle
delay
powerd-dbus-Message: powerd_send_event allow-suspend
: @ 1336588513 tracing begun
bash-4.2# iwconfig
lo no wireless extensions.
eth0 IEEE 802.11bg ESSID:"olpc"
Mode:Managed Frequency:2.437 GHz Access Point:
00:25:9C:59:E7:38
Bit Rate=5.5 Mb/s
Retry long limit:7 RTS thr:off Fragment thr:off
Encryption key:off
Power Management:on
Link Quality=66/70 Signal level=-44 dBm
Rx invalid nwid:0 Rx invalid crypt:0 Rx invalid frag:0
Tx excessive retries:0 Invalid misc:0 Missed beacon:0
bash-4.2# : @ 1336588532 got event: lidclose, 1336588533,
/dev/input/event0, , .
: @ 1336588532 backlight off
: @ 1336588532 snoozing: until_shutdown 14400 hard
: @ 1336588532 until-sleep_type is until_shutdown-hard
rtcwake: wakeup from "mem" using /dev/rtc0 at Wed May 9 22:35:35 2012
[ 73.994495] PM: Syncing filesystems ... done.
[ 74.002922] Freezing user space processes ... (elapsed 0.01 seconds)
done.
[ 74.022924] Freezing remaining freezable tasks ... (elapsed 0.01
seconds) done.
[ 74.199619] i8042 kbd 00:04: wake-up capability enabled by ACPI
[ 74.205862] i8042 aux 00:03: wake-up capability disabled by ACPI
[ 74.213813] libertas_sdio mmc1:0001:1: mmc1:0001:1: suspend: PM flags =
0x3
[ 74.221013] viafb_suspend!
[ 74.224617] libertas_sdio mmc1:0001:1: Suspend without wake params --
powering down card
[ 74.239420] cfg80211: Calling CRDA for country: EU
[ 74.250711] mmc1: card 0001 removed
[ 74.270151] PM: suspend of devices complete after 226.728 msecs
[ 74.350383] PM: late suspend of devices complete after 74.203 msecs
[ 74.356873] ACPI: Preparing to enter system sleep state S3
[ 74.460173] PM: Saving platform NVS memory
+r[ 74.460173] ACPI: Low-level resume complete
[ 74.460173] PM: Restoring platform NVS memory
[ 74.460173] ACPI: Waking up from system sleep state S3
[ 74.550540] PM: early resume of devices complete after 80.112 msecs
[ 74.557088] viafb_resume!
[ 74.560216] viafb_resume: Init 3d engine!
[ 74.580765] usb usb2: root hub lost power or was reset
[ 74.586037] usb usb3: root hub lost power or was reset
[ 74.591285] usb usb4: root hub lost power or was reset
[ 74.613092] i8042 kbd 00:04: wake-up capability disabled by ACPI
[ 74.840233] PM: resume of devices complete after 283.299 msecs
[ 74.846391] Restarting tasks ... done.
: @ 1336588538 got wakeup: lid @ 1336588538, slept 5
[ 75.177576] mmc1: new SDIO card at address 0001
[ 75.249862] NetworkManager used greatest stack depth: 5976 bytes left
: @ 1336588539 got event: lidopen, 1336588538, /dev/input/event0, , .
: @ 1336588539 backlight restore
: @ 1336588539 got event: fake_useractive, 1336588538, lid, , .
: @ 1336588539 backlight restore
[ 76.830941] libertas_sdio mmc1:0001:1: (unregistered net_device):
20:7c:8f:4a:9e:07, fw 9.70.20p0, cap 0x000003a3
[ 76.851647] libertas_sdio mmc1:0001:1: wlan0: Marvell WLAN 802.11
adapter
[ 76.893869] udevd[267]: renamed network interface wlan0 to eth0
[ 76.902611] ADDRCONF(NETDEV_UP): eth0: link is not ready
[ 78.108463] ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
: @ 1336588548 got event: lidclose, 1336588549, /dev/input/event0, , .
: @ 1336588548 backlight off
: @ 1336588548 snoozing: until_shutdown 14400 hard
: @ 1336588548 until-sleep_type is until_shutdown-hard
rtcwake: wakeup from "mem" using /dev/rtc0 at Wed May 9 22:35:51 2012
[ 86.371805] PM: Syncing filesystems ... done.
[ 86.380364] Freezing user space processes ... (elapsed 0.01 seconds)
done.
[ 86.402905] Freezing remaining freezable tasks ... (elapsed 0.01
seconds) done.
[ 86.579925] i8042 kbd 00:04: wake-up capability enabled by ACPI
[ 86.585992] i8042 aux 00:03: wake-up capability disabled by ACPI
[ 86.593403] libertas_sdio mmc1:0001:1: mmc1:0001:1: suspend: PM flags =
0x3
[ 86.600560] viafb_suspend!
[ 86.604235] libertas_sdio mmc1:0001:1: Suspend without wake params --
powering down card
[ 86.628364] cfg80211: Calling CRDA to update world regulatory domain
[ 86.640576] mmc1: card 0001 removed
[ 86.660173] PM: suspend of devices complete after 237.007 msecs
[ 86.740388] PM: late suspend of devices complete after 74.180 msecs
[ 86.746841] ACPI: Preparing to enter system sleep state S3
[ 86.850177] PM: Saving platform NVS memory
+r[ 86.850177] ACPI: Low-level resume complete
[ 86.850177] PM: Restoring platform NVS memory
[ 86.850177] ACPI: Waking up from system sleep state S3
[ 86.940540] PM: early resume of devices complete after 80.137 msecs
[ 86.947121] viafb_resume!
[ 86.950251] viafb_resume: Init 3d engine!
[ 86.969774] usb usb2: root hub lost power or was reset
[ 86.975028] usb usb3: root hub lost power or was reset
[ 86.980318] usb usb4: root hub lost power or was reset
[ 87.003893] i8042 kbd 00:04: wake-up capability disabled by ACPI
[ 87.217509] hpet1: lost 1 rtc interrupts
[ 87.232825] PM: resume of devices complete after 285.860 msecs
[ 87.238959] Restarting tasks ... done.
: @ 1336588557 got wakeup: lid @ 1336588557, slept 8
[ 87.344112] cfg80211: World regulatory domain updated:
[ 87.349316] cfg80211: (start_freq - end_freq @ bandwidth),
(max_antenna_gain, max_eirp)
[ 87.426753] cfg80211: (2402000 KHz - 2472000 KHz @ 40000 KHz), (300
mBi, 2000 mBm)
[ 87.514681] cfg80211: (2457000 KHz - 2482000 KHz @ 20000 KHz), (300
mBi, 2000 mBm)
[ 87.546721] cfg80211: (2474000 KHz - 2494000 KHz @ 20000 KHz), (300
mBi, 2000 mBm)
[ 87.605734] cfg80211: (5170000 KHz - 5250000 KHz @ 40000 KHz), (300
mBi, 2000 mBm)
[ 87.633864] cfg80211: (5735000 KHz - 5835000 KHz @ 40000 KHz), (300
mBi, 2000 mBm)
[ 87.677475] mmc1: new SDIO card at address 0001
: @ 1336588557 got event: lidopen, 1336588557, /dev/input/event0, , .
: @ 1336588557 backlight restore
: @ 1336588558 got event: fake_useractive, 1336588557, lid, , .
: @ 1336588558 backlight restore
[ 88.950921] libertas_sdio mmc1:0001:1: (unregistered net_device):
20:7c:8f:4a:9e:07, fw 9.70.20p0, cap 0x000003a3
[ 88.971660] libertas_sdio mmc1:0001:1: wlan0: Marvell WLAN 802.11
adapter
[ 88.978676] cfg80211: Calling CRDA to update world regulatory domain
[ 89.071035] udevd[977]: renamed network interface wlan0 to eth0
: @ 1336588587 got event: timer, 1336588587, 60, , .
bash-4.2#
bash-4.2#
bash-4.2# iwconfig
lo no wireless extensions.
eth0 IEEE 802.11bg ESSID:off/any
Mode:Managed Access Point: Not-Associated
Retry long limit:7 RTS thr:off Fragment thr:off
Encryption key:off
Power Management:on
bash-4.2#
bash-4.2# : note -- no NM applet icon in gnome
bash-4.2#
bash-4.2# : @ 1336588647 got event: timer, 1336588647, 60, , .
: @ 1336588649 got event: lidclose, 1336588650, /dev/input/event0, , .
: @ 1336588649 backlight off
: @ 1336588649 snoozing: until_shutdown 14400 hard
: @ 1336588649 until-sleep_type is until_shutdown-hard
rtcwake: wakeup from "mem" using /dev/rtc0 at Wed May 9 22:37:32 2012
[ 180.809515] PM: Syncing filesystems ... done.
[ 180.817927] Freezing user space processes ...
[ 193.580425] Freezing of tasks aborted after 12.75 seconds (2 tasks
refusing to freeze, wq_busy=0):
[ 193.589547]
[ 193.591064] Restarting tasks ... done.
: @ 1336588663 got wakeup: lid @ 1336588663, slept 13
: @ 1336588663 got event: lidopen, 1336588664, /dev/input/event0, , .
: @ 1336588663 backlight restore
: @ 1336588663 got event: fake_useractive, 1336588663, lid, , .
: @ 1336588663 backlight restore
bash-4.2#
bash-4.2#
bash-4.2#
bash-4.2# : note -- during the above s/r attempt, the power LED never went
out.
bash-4.2#
bash-4.2# ifcofnig
bash: ifcofnig: command not found
bash-4.2# ifconfig
lo: flags=73<UP,LOOPBACK,RUNNING> mtu 16436
inet 127.0.0.1 netmask 255.0.0.0
inet6 ::1 prefixlen 128 scopeid 0x10<host>
loop txqueuelen 0 (Local Loopback)
RX packets 12 bytes 720 (720.0 B)
RX errors 0 dropped 0 overruns 0 frame 0
TX packets 12 bytes 720 (720.0 B)
TX errors 0 dropped 0 overruns 0 carrier 0 collisions 0
bash-4.2# ifconfig -a
eth0: flags=4098<BROADCAST,MULTICAST> mtu 1500
ether 20:7c:8f:4a:9e:07 txqueuelen 1000 (Ethernet)
RX packets 0 bytes 0 (0.0 B)
RX errors 0 dropped 0 overruns 0 frame 0
TX packets 0 bytes 0 (0.0 B)
TX errors 0 dropped 0 overruns 0 carrier 0 collisions 0
lo: flags=73<UP,LOOPBACK,RUNNING> mtu 16436
inet 127.0.0.1 netmask 255.0.0.0
inet6 ::1 prefixlen 128 scopeid 0x10<host>
loop txqueuelen 0 (Local Loopback)
RX packets 12 bytes 720 (720.0 B)
RX errors 0 dropped 0 overruns 0 frame 0
TX packets 12 bytes 720 (720.0 B)
TX errors 0 dropped 0 overruns 0 carrier 0 collisions 0
bash-4.2#
bash-4.2#
bash-4.2# iwconfig
lo no wireless extensions.
eth0 IEEE 802.11bg ESSID:off/any
Mode:Managed Access Point: Not-Associated
Retry long limit:7 RTS thr:off Fragment thr:off
Encryption key:off
Power Management:on
bash-4.2# : @ 1336588706 got event: lidclose, 1336588706,
/dev/input/event0, , .
: @ 1336588706 backlight off
: @ 1336588706 snoozing: until_shutdown 14400 hard
: @ 1336588706 until-sleep_type is until_shutdown-hard
rtcwake: wakeup from "mem" using /dev/rtc0 at Wed May 9 22:38:28 2012
[ 237.204317] PM: Syncing filesystems ... done.
[ 237.212754] Freezing user space processes ...
[ 241.000111] INFO: task wpa_supplicant:509 blocked for more than 120
seconds.
[ 241.007429] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables
this message.
[ 241.015435] wpa_supplicant D e9a27be0 6380 509 1 0x00000004
[ 241.021989] e9a27cb0 00200082 00000000 e9a27be0 bd03eb23 00000014
e9a1e940 e9a1e940
[ 241.029964] eb69f240 000000db 00000000 e9a27be0 b04adab0 00100100
00200200 eb62716c
[ 241.038075] b0438d5e 00000001 e9a27c90 e9a27c94 b0438d5e 00000001
e9a27ca0 b07410c8
[ 241.046111] Call Trace:
[ 241.048638] [<b04adab0>] ? __pollwait+0xa8/0xa8
[ 241.053454] [<b0438d5e>] ? get_parent_ip+0xb/0x31
[ 241.058305] [<b0438d5e>] ? get_parent_ip+0xb/0x31
[ 241.063167] [<b07410c8>] ? sub_preempt_count+0x75/0x92
[ 241.068507] [<b073e4cb>] schedule+0x4d/0x4f
[ 241.072927] [<b073d7b8>] __mutex_lock_slowpath+0x6c/0xc4
[ 241.078419] [<b073d478>] mutex_lock+0x11/0x13
[ 241.082992] [<b06aa5c9>] genl_lock+0x12/0x14
[ 241.087422] [<b06aa5dc>] genl_rcv+0x11/0x25
[ 241.091828] [<b06a99d1>] netlink_unicast+0x100/0x167
[ 241.096974] [<b06a9ca9>] netlink_sendmsg+0x1fc/0x257
[ 241.102142] [<b0681f68>] sock_sendmsg+0xb7/0xd2
[ 241.106812] [<b06a86c7>] ? netlink_recvmsg+0x2b9/0x2d2
[ 241.112256] [<b0558f90>] ? _copy_from_user+0x30/0x44
[ 241.117412] [<b068abc9>] ? verify_iovec+0x45/0x7c
[ 241.122271] [<b0682198>] __sys_sendmsg+0x18b/0x21f
[ 241.127208] [<b07410c8>] ? sub_preempt_count+0x75/0x92
[ 241.132614] [<b073edda>] ? _raw_spin_unlock_irqrestore+0x1c/0x32
[ 241.138801] [<b0438d5e>] ? get_parent_ip+0xb/0x31
[ 241.143691] [<b07410c8>] ? sub_preempt_count+0x75/0x92
[ 241.149014] [<b055d2a0>] ? __debug_check_no_obj_freed+0xe6/0x139
[ 241.155260] [<b04afd48>] ? __d_free+0x41/0x45
[ 241.159787] [<b04afd84>] ? d_free+0x38/0x47
[ 241.164129] [<b0683b5e>] sys_sendmsg+0x30/0x4b
[ 241.168711] [<b0683ffe>] sys_socketcall+0x14a/0x1a4
[ 241.173808] [<b0743690>] sysenter_do_call+0x12/0x26
[ 241.178878] INFO: task crda:1111 blocked for more than 120 seconds.
[ 241.185208] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables
this message.
[ 241.193241] crda D e9d82014 6300 1111 1 0x00000004
[ 241.199737] e9f8ba34 00000082 00000282 e9d82014 ba161ea5 00000014
eb589b40 eb589b40
[ 241.207804] e9f8e940 e9f8ba04 b04376d4 00000000 00000000 00000001
00000003 e9f8ba08
[ 241.215926] e9f8ba0c b0438d5e 00000001 e9f8ba18 b07410c8 00000246
e9f8ba24 b073edda
[ 241.223996] Call Trace:
[ 241.226499] [<b04376d4>] ? __wake_up+0x37/0x40
[ 241.231159] [<b0438d5e>] ? get_parent_ip+0xb/0x31
[ 241.236015] [<b07410c8>] ? sub_preempt_count+0x75/0x92
[ 241.241348] [<b073edda>] ? _raw_spin_unlock_irqrestore+0x1c/0x32
[ 241.247489] [<b073e4cb>] schedule+0x4d/0x4f
[ 241.251921] [<f118f5bb>] __lbs_cmd+0xcb/0x166 [libertas]
[ 241.257404] [<b04304c5>] ? __init_waitqueue_head+0x26/0x26
[ 241.263051] [<f118fe69>] lbs_set_11d_domain_info+0x3c4/0x416
[libertas]
[ 241.269893] [<f118e420>] ? lbs_cmd_async_callback+0xc/0xc [libertas]
[ 241.276549] [<f118c58a>] lbs_reg_notifier+0x7d/0xcb [libertas]
[ 241.282541] [<b071a291>] wiphy_update_regulatory+0x363/0x3a2
[ 241.288367] [<b0719d93>] ? reset_regdomains+0x5f/0x95
[ 241.293646] [<b071af42>] set_regdom+0x305/0x510
[ 241.298320] [<b0438d5e>] ? get_parent_ip+0xb/0x31
[ 241.303219] [<b056345c>] ? nla_parse+0x50/0xa0
[ 241.307803] [<b072033e>] nl80211_set_reg+0x191/0x21e
[ 241.313040] [<b072593d>] ?
nl80211_send_scan_msg.constprop.31+0x22e/0x22e
[ 241.320073] [<b06aa753>] genl_rcv_msg+0x163/0x19e
[ 241.324920] [<b06aa5f0>] ? genl_rcv+0x25/0x25
[ 241.329421] [<b06a9e34>] netlink_rcv_skb+0x37/0x7a
[ 241.334490] [<b06aa5e9>] genl_rcv+0x1e/0x25
[ 241.338809] [<b06a99d1>] netlink_unicast+0x100/0x167
[ 241.343930] [<b06a9ca9>] netlink_sendmsg+0x1fc/0x257
[ 241.349073] [<b0681f68>] sock_sendmsg+0xb7/0xd2
[ 241.353836] [<b0681e53>] ? sock_recvmsg+0xbe/0xda
[ 241.358684] [<b07410c8>] ? sub_preempt_count+0x75/0x92
[ 241.364055] [<b0558f90>] ? _copy_from_user+0x30/0x44
[ 241.369185] [<b068abc9>] ? verify_iovec+0x45/0x7c
[ 241.374120] [<b0682198>] __sys_sendmsg+0x18b/0x21f
[ 241.379142] [<b0682b13>] ? __sys_recvmsg+0x145/0x1a1
[ 241.384263] [<b0681d95>] ? sock_sendmsg_nosec+0xd0/0xd0
[ 241.389669] [<b048cc1c>] ? __do_fault+0x347/0x36d
[ 241.394632] [<b048ea6c>] ? handle_pte_fault+0x22c/0x4d8
[ 241.399997] [<b06836ce>] ? sys_getsockname+0x71/0x96
[ 241.405127] [<b048ed94>] ? handle_mm_fault+0x7c/0x9d
[ 241.410384] [<b0683b5e>] sys_sendmsg+0x30/0x4b
[ 241.415018] [<b0683ffe>] sys_socketcall+0x14a/0x1a4
[ 241.420083] [<b0743690>] sysenter_do_call+0x12/0x26
[ 241.810198]
[ 241.811704] Freezing of tasks aborted after 4.59 seconds (2 tasks
refusing to freeze, wq_busy=0):
[ 241.820718]
[ 241.822238] Restarting tasks ... done.
: @ 1336588712 got wakeup: lid @ 1336588711, slept 4
: @ 1336588712 got event: lidopen, 1336588712, /dev/input/event0, , .
: @ 1336588712 backlight restore
: @ 1336588712 got event: fake_useractive, 1336588711, lid, , .
: @ 1336588712 backlight restore
bash-4.2#
bash-4.2#
bash-4.2#
bash-4.2#
bash-4.2# : note: @ 1336588732 got event: timer, 1336588732, 60, , .
bash-4.2#
bash-4.2#
bash-4.2# : note -- system still sort of functional, but reboot will hang.
bash-4.2#
bash-4.2#
bash-4.2# : @ 1336588763 got event: powerbutton, 1336588764, , , .
: @ 1336588763 backlight is_off
[ 293.798668] dcon_freeze_store: 1
: @ 1336588763 backlight restore
[ 293.810479] dcon_source_switch to DCON
[ 293.836723] olpc-dcon: The DCON has control
[ 293.992484] dcon_freeze_store: 1
[ 294.127782] dcon_freeze_store: 0
[ 294.131320] dcon_source_switch to CPU
[ 294.157551] olpc-dcon: The CPU has control
: @ 1336588770 got event: powertimerdone, -, 14, gotosleep, .
: @ 1336588770 got event: gotosleep, 1336588770, , , .
: @ 1336588770 backlight off
: @ 1336588770 snoozing: until_shutdown 14400 hard
: @ 1336588771 until-sleep_type is until_shutdown-hard
rtcwake: wakeup from "mem" using /dev/rtc0 at Wed May 9 22:39:33 2012
[ 302.032082] PM: Syncing filesystems ... done.
[ 302.040614] Freezing user space processes ...
[ 304.820399] Freezing of tasks aborted after 2.77 seconds (2 tasks
refusing to freeze, wq_busy=0):
[ 304.829409]
[ 304.830985] Restarting tasks ... done.
: @ 1336588774 got wakeup: powerbutton @ 1336588774, slept 2
: @ 1336588775 power button during until_shutdown
: @ 1336588775 got event: powerbutton, 1336588775, , , .
: @ 1336588775 backlight is_off
Caught signal 2. Exiting
: @ 1336588775 backlight restore
[ 305.190152] dcon_freeze_store: 0
[ 305.270106] dcon_freeze_store: 0
: @ 1336588775 got event: fake_useractive, 1336588774, powerbutton, , .
: @ 1336588775 backlight restore
: @ 1336588776 got event: powerbutton, 1336588776, , , .
: @ 1336588776 backlight is_off
[ 306.199109] dcon_freeze_store: 1
: @ 1336588776 backlight restore[ 306.212087] dcon_source_switch to DCON
[ 306.253366] olpc-dcon: The DCON has control
[ 306.409009] dcon_freeze_store: 1
[ 306.480323] dcon_freeze_store: 0
[ 306.483763] dcon_source_switch to CPU
[ 306.514094] olpc-dcon: The CPU has control
: @ 1336588777 got event: powerbutton, 1336588777, , , .
: @ 1336588777 b[ 307.258798] dcon_freeze_store: 1
[ 307.263599] dcon_source_switch to DCON
[ 307.306656] olpc-dcon: The DCON has control
[ 307.393940] dcon_freeze_store: 0
[ 307.397234] dcon_source_switch to CPU
[ 307.427458] olpc-dcon: The CPU has control
: @ 2012-05-09 18:39:38 shutting down due to power press
/usr/sbin/powerd: line 1000: 1420 Terminated
$LIBEXECDIR/pnmto565fb -d -f $FRAMEBUFFER -s 9999999 $args
[ 309.247282] dcon_freeze_store: 0
[ 361.420139] INFO: task wpa_supplicant:509 blocked for more than 120
seconds.
[ 361.427189] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables
this message.
[ 361.435050] wpa_supplicant D e9a27be0 6380 509 1 0x00000004
[ 361.441487] e9a27cb0 00200082 00000000 e9a27be0 bd03eb23 00000014
e9a1e940 e9a1e940
[ 361.449434] eb69f240 000000db 00000000 e9a27be0 b04adab0 00100100
00200200 eb62716c
[ 361.457393] b0438d5e 00000001 e9a27c90 e9a27c94 b0438d5e 00000001
e9a27ca0 b07410c8
[ 361.465352] Call Trace:
[ 361.467824] [<b04adab0>] ? __pollwait+0xa8/0xa8
[ 361.472476] [<b0438d5e>] ? get_parent_ip+0xb/0x31
[ 361.477274] [<b0438d5e>] ? get_parent_ip+0xb/0x31
[ 361.482093] [<b07410c8>] ? sub_preempt_count+0x75/0x92
[ 361.487334] [<b073e4cb>] schedule+0x4d/0x4f
[ 361.491631] [<b073d7b8>] __mutex_lock_slowpath+0x6c/0xc4
[ 361.497037] [<b073d478>] mutex_lock+0x11/0x13
[ 361.501507] [<b06aa5c9>] genl_lock+0x12/0x14
[ 361.505874] [<b06aa5dc>] genl_rcv+0x11/0x25
[ 361.510171] [<b06a99d1>] netlink_unicast+0x100/0x167
[ 361.515230] [<b06a9ca9>] netlink_sendmsg+0x1fc/0x257
[ 361.520316] [<b0681f68>] sock_sendmsg+0xb7/0xd2
[ 361.524944] [<b06a86c7>] ? netlink_recvmsg+0x2b9/0x2d2
[ 361.530225] [<b0558f90>] ? _copy_from_user+0x30/0x44
[ 361.535282] [<b068abc9>] ? verify_iovec+0x45/0x7c
[ 361.540097] [<b0682198>] __sys_sendmsg+0x18b/0x21f
[ 361.544991] [<b07410c8>] ? sub_preempt_count+0x75/0x92
[ 361.550246] [<b073edda>] ? _raw_spin_unlock_irqrestore+0x1c/0x32
[ 361.556349] [<b0438d5e>] ? get_parent_ip+0xb/0x31
[ 361.561163] [<b07410c8>] ? sub_preempt_count+0x75/0x92
[ 361.566399] [<b055d2a0>] ? __debug_check_no_obj_freed+0xe6/0x139
[ 361.572525] [<b04afd48>] ? __d_free+0x41/0x45
[ 361.576977] [<b04afd84>] ? d_free+0x38/0x47
[ 361.581273] [<b0683b5e>] sys_sendmsg+0x30/0x4b
[ 361.585811] [<b0683ffe>] sys_socketcall+0x14a/0x1a4
[ 361.590808] [<b0743690>] sysenter_do_call+0x12/0x26
[ 361.595797] INFO: task crda:1111 blocked for more than 120 seconds.
[ 361.602083] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables
this message.
[ 361.609911] crda D e9d82014 6300 1111 1 0x00000004
[ 361.616344] e9f8ba34 00000082 00000282 e9d82014 ba161ea5 00000014
eb589b40 eb589b40
[ 361.624298] e9f8e940 e9f8ba04 b04376d4 00000000 00000000 00000001
00000003 e9f8ba08
[ 361.632255] e9f8ba0c b0438d5e 00000001 e9f8ba18 b07410c8 00000246
e9f8ba24 b073edda
[ 361.640212] Call Trace:
[ 361.642675] [<b04376d4>] ? __wake_up+0x37/0x40
[ 361.647220] [<b0438d5e>] ? get_parent_ip+0xb/0x31
[ 361.652035] [<b07410c8>] ? sub_preempt_count+0x75/0x92
[ 361.657267] [<b073edda>] ? _raw_spin_unlock_irqrestore+0x1c/0x32
[ 361.663387] [<b073e4cb>] schedule+0x4d/0x4f
[ 361.667686] [<f118f5bb>] __lbs_cmd+0xcb/0x166 [libertas]
[ 361.673114] [<b04304c5>] ? __init_waitqueue_head+0x26/0x26
[ 361.678707] [<f118fe69>] lbs_set_11d_domain_info+0x3c4/0x416
[libertas]
[ 361.685435] [<f118e420>] ? lbs_cmd_async_callback+0xc/0xc [libertas]
[ 361.691915] [<f118c58a>] lbs_reg_notifier+0x7d/0xcb [libertas]
[ 361.697849] [<b071a291>] wiphy_update_regulatory+0x363/0x3a2
[ 361.703618] [<b0719d93>] ? reset_regdomains+0x5f/0x95
[ 361.708765] [<b071af42>] set_regdom+0x305/0x510
[ 361.713411] [<b0438d5e>] ? get_parent_ip+0xb/0x31
[ 361.718210] [<b056345c>] ? nla_parse+0x50/0xa0
[ 361.722765] [<b072033e>] nl80211_set_reg+0x191/0x21e
[ 361.727832] [<b072593d>] ?
nl80211_send_scan_msg.constprop.31+0x22e/0x22e
[ 361.734751] [<b06aa753>] genl_rcv_msg+0x163/0x19e
[ 361.739558] [<b06aa5f0>] ? genl_rcv+0x25/0x25
[ 361.744022] [<b06a9e34>] netlink_rcv_skb+0x37/0x7a
[ 361.748907] [<b06aa5e9>] genl_rcv+0x1e/0x25
[ 361.753200] [<b06a99d1>] netlink_unicast+0x100/0x167
[ 361.758259] [<b06a9ca9>] netlink_sendmsg+0x1fc/0x257
[ 361.763335] [<b0681f68>] sock_sendmsg+0xb7/0xd2
[ 361.767965] [<b0681e53>] ? sock_recvmsg+0xbe/0xda
[ 361.772781] [<b07410c8>] ? sub_preempt_count+0x75/0x92
[ 361.778024] [<b0558f90>] ? _copy_from_user+0x30/0x44
[ 361.783101] [<b068abc9>] ? verify_iovec+0x45/0x7c
[ 361.787897] [<b0682198>] __sys_sendmsg+0x18b/0x21f
[ 361.792798] [<b0682b13>] ? __sys_recvmsg+0x145/0x1a1
[ 361.797856] [<b0681d95>] ? sock_sendmsg_nosec+0xd0/0xd0
[ 361.803196] [<b048cc1c>] ? __do_fault+0x347/0x36d
[ 361.808001] [<b048ea6c>] ? handle_pte_fault+0x22c/0x4d8
[ 361.813339] [<b06836ce>] ? sys_getsockname+0x71/0x96
[ 361.818400] [<b048ed94>] ? handle_mm_fault+0x7c/0x9d
[ 361.823482] [<b0683b5e>] sys_sendmsg+0x30/0x4b
[ 361.828026] [<b0683ffe>] sys_socketcall+0x14a/0x1a4
[ 361.833015] [<b0743690>] sysenter_do_call+0x12/0x26
}}}
--
Ticket URL: <http://dev.laptop.org/ticket/11843>
One Laptop Per Child <http://laptop.org/>
OLPC bug tracking system
More information about the Bugs
mailing list