#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