Wanting to know a bit of (NetworkManager) workflow upon resume-from-suspend

Discussion:

Ajay Garg

2012-04-29 07:26:17 UTC

Hi all.

To be absolutely clear in communicating my query, I will list the history
of the issue ::

a)
The original issue was that when XO-1s were booted, no wireless/ad-hoc
icons could be seen in the Neighborhood-View. This happened on intermediate
basis.

b)
Upon some debugging, it was found that for the failure cases, "olpc-mesh
device" was being identified in the context of NetworkManager, even though
"echo 0 > "/sys/class/net/eth0/lbs_mesh"" workaround had been integrated
into "/etc/rc.d/rc.local".

c)
Now, this conflict (that the script intended to disable mesh, but
Networkmanager still picked up mesh-device) caused NetworkManager to crash
(randomly). Whenever this happened, no wireless/ad-hoc icons could be seen
in the Neighborhood-View.

d)
So, the solution that was devised, was to place "echo 0 >
"/sys/class/net/eth0/lbs_mesh"" script, right at the beginning of "start()"
method in "/etc/init.d/NetworkManager" script, so that the bootstrap
script, could be executed BEFORE the NetworkManager itself started. This
worked like a charm !!

e)
However, now, what we are seeing is that during "resume-from-suspend", the
mesh-hardware is again being picked up by the NetworkManager, thus again
causing the crash; thus again causing the icons to disappear from the
Neighborhood view. Note that, this is happening in spite of placing the
bottstrap mesh-disable script in the "start()" method of
"/etc/init.d/NetworkManager". Also to be noted, that during reboot, things
work fine (obviously).

So, the question arises, is there a different mechanism of handling of
NetworkManager, during "reboot" and "resume-from-suspend"?

For brevity, I am attaching the contents of "/etc/init.d/NetworkManager"
during the resume-from-suspend failed-case ::

####################################################################################################################
Apr 29 02:43:59 xo-05-2a-1f kernel: imklog 4.6.3, log source = /proc/kmsg
started.
Apr 29 02:43:59 xo-05-2a-1f rsyslogd: [origin software="rsyslogd"
swVersion="4.6.3" x-pid="589" x-info="http://www.rsyslog.com"] (re)start
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 0.000000] Linux version
2.6.35.13_xo1-20111005.1403.olpc.c2bd7b9 (***@sinistra.laptop.org) (gcc
version 4.5.1 20100924 (Red Hat 4.5.1-4) (GCC) ) #1 PREEMPT Wed Oct 5
14:05:52 EDT 2011
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 0.000000] BIOS-provided physical
RAM map:
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 0.000000] BIOS-e801:
0000000000000000 - 000000000009f000 (usable)
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 0.000000] BIOS-e801:
0000000000100000 - 000000000e813c00 (usable)
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 0.000000] Notice: NX (Execute
Disable) protection missing in CPU or disabled in BIOS!
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 0.000000] DMI 2.1 present.
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 0.000000] last_pfn = 0xe813
max_arch_pfn = 0x100000
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 0.000000] init_memory_mapping:
0000000000000000-000000000e813000
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 0.000000] RAMDISK: 0e813e00 -
0ec00000
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 0.000000] Allocated new RAMDISK:
007d9000 - 00bc5200
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 0.000000] Move RAMDISK from
000000000e813e00 - 000000000ebfffff to 007d9000 - 00bc51ff
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 0.000000] 232MB LOWMEM available.
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 0.000000] mapped low ram: 0 -
0e813000
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 0.000000] low ram: 0 - 0e813000
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 0.000000] node 0 low ram:
00000000 - 0e813000
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 0.000000] node 0 bootmap
00001000 - 00002d04
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 0.000000] (6/32 early
reservations) ==> bootmem [0000000000 - 000e813000]
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 0.000000] #0 [0000400000 -
00007d4300] TEXT DATA BSS ==> [0000400000 - 00007d4300]
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 0.000000] #1 [000009fc00 -
0000100000] BIOS reserved ==> [000009fc00 - 0000100000]
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 0.000000] #2 [00007d5000 -
00007d80cc] BRK ==> [00007d5000 - 00007d80cc]
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 0.000000] #3 [0000007000 -
0000008000] PGTABLE ==> [0000007000 - 0000008000]
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 0.000000] #4 [00007d9000 -
0000bc6000] NEW RAMDISK ==> [00007d9000 - 0000bc6000]
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 0.000000] #5 [0000001000 -
0000003000] BOOTMAP ==> [0000001000 - 0000003000]
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 0.000000] Zone PFN ranges:
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 0.000000] DMA 0x00000001 ->
0x00001000
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 0.000000] Normal 0x00001000 ->
0x0000e813
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 0.000000] Movable zone start PFN
for each node
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 0.000000] early_node_map[2] active
PFN ranges
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 0.000000] 0: 0x00000001 ->
0x0000009f
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 0.000000] 0: 0x00000100 ->
0x0000e813
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 0.000000] Allocating PCI resources
starting at e813c00 (gap: e813c00:f17ec400)
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 0.000000] Built 1 zonelists in
Zone order, mobility grouping on. Total pages: 58848
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 0.000000] Kernel command line:
no_console_suspend selinux=0 ro console=ttyS0,115200 console=tty0
fbcon=font:SUN12x22
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 0.000000] PID hash table entries:
1024 (order: 0, 4096 bytes)
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 0.000000] Dentry cache hash table
entries: 32768 (order: 5, 131072 bytes)
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 0.000000] Inode-cache hash table
entries: 16384 (order: 4, 65536 bytes)
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 0.000000] Initializing CPU#0
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 0.000000] Memory: 227200k/237644k
available (2442k kernel code, 10052k reserved, 995k data, 240k init, 0k
highmem)
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 0.000000] virtual kernel memory
layout:
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 0.000000] fixmap : 0xfffe5000
- 0xfffff000 ( 104 kB)
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 0.000000] vmalloc : 0xcf013000
- 0xfffe3000 ( 783 MB)
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 0.000000] lowmem : 0xc0000000
- 0xce813000 ( 232 MB)
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 0.000000] .init : 0xc075c000
- 0xc0798000 ( 240 kB)
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 0.000000] .data : 0xc0662b18
- 0xc075b76c ( 995 kB)
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 0.000000] .text : 0xc0400000
- 0xc0662b18 (2442 kB)
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 0.000000] Checking if this
processor honours the WP bit even in supervisor mode...Ok.
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 0.000000] Hierarchical RCU
implementation.
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 0.000000] RCU-based detection
of stalled CPUs is disabled.
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 0.000000] Verbose stalled-CPUs
detection is disabled.
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 0.000000] NR_IRQS:16
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 0.000000] Console: colour EGA 80x25
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 0.000000] console [tty0] enabled
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 0.000000] console [ttyS0] enabled
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 0.000000] Fast TSC calibration
using PIT
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 0.000000] Detected 431.247 MHz
processor.
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 0.020011] Calibrating delay loop
(skipped), value calculated using timer frequency.. 862.49 BogoMIPS
(lpj=4312470)
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 0.032778] pid_max: default: 4096
minimum: 301
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 0.040211] Security Framework
initialized
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 0.044457] Mount-cache hash table
entries: 512
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 0.050437] Performance Events: no
PMU driver, software events only.
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 0.056934] CPU: Geode(TM)
Integrated Processor by AMD PCS stepping 02
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 0.061782] devtmpfs: initialized
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 0.065903] NET: Registered protocol
family 16
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 0.070053] geode-mfgpt: 8 MFGPT
timers available.
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 0.075008] geode-mfgpt: Registered
timer 0
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 0.080034] mfgpt-timer:
Registering MFGPT timer 0 as a clock event, using IRQ 7
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 0.090530] OLPC board with
OpenFirmware CL1 Q2E45 Q2E
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 0.098100] OLPC board revision B4
(EC=55)
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 0.100996] PCI: Using configuration
type OLPC
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 0.109230] bio: create slab <bio-0>
at 0
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 0.110882] SCSI subsystem
initialized
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 0.115291] Advanced Linux Sound
Architecture Driver Version 1.0.23.
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 0.120075] PCI: Probing PCI hardware
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 0.127804] Switching to clocksource
tsc
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 0.139255] NET: Registered protocol
family 2
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 0.144213] IP route cache hash
table entries: 2048 (order: 1, 8192 bytes)
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 0.151695] TCP established hash
table entries: 8192 (order: 4, 65536 bytes)
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 0.159102] TCP bind hash table
entries: 8192 (order: 3, 32768 bytes)
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 0.165877] TCP: Hash tables
configured (established 8192 bind 8192)
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 0.172310] TCP reno registered
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 0.175769] NET: Registered protocol
family 1
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 0.180587] Trying to unpack rootfs
image as initramfs...
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 0.224642] Freeing initrd memory:
4020k freed
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 0.230325] input: OLPC PM as
/devices/virtual/input/input0
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 0.240838] input: OLPC lid switch
as /devices/virtual/input/input1
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 0.247502] input: OLPC ebook switch
as /devices/virtual/input/input2
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 0.254436] input: OLPC AC power
jack as /devices/virtual/input/input3
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 0.261148] SCI is mapped to IRQ 3
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 0.271048] HugeTLB registered 4 MB
page size, pre-allocated 0 pages
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 0.278254] JFFS2 version 2.2.
(NAND) (SUMMARY) © 2001-2006 Red Hat, Inc.
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 0.385105] PROM: Built device tree
with 36735 bytes of memory.
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 0.391269] msgmni has been set to
451
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 0.398593] io scheduler noop
registered
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 0.402838] io scheduler cfq
registered (default)
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 0.407654] pci 0000:00:0f.0:
allocated PCI BAR #1: base 0x1000
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 0.413821] pci 0000:00:0f.0:
cs5535-gpio: GPIO support successfully loaded.
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 0.427606] lxfb 0000:00:01.1: 16384
KB of video memory at 0xfd000000
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 0.562380] Console: switching to
colour frame buffer device 100x40
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 0.608389] fb0: Geode LX frame
buffer device
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 0.614263] Non-volatile memory
driver v1.3
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 0.619090] AMD Geode RNG detected
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 0.623065] Serial: 8250/16550
driver, 1 ports, IRQ sharing enabled
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 0.916824] serial8250: ttyS0 at I/O
0x3f8 (irq = 4) is a NS16550A
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 0.930412] brd: module loaded
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 0.933990] pci 0000:00:0f.0:
allocated PCI BAR #2: base 0x1800
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 0.940832] pci 0000:00:0f.0:
cs5535-mfgpt: 7 MFGPT timers available
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 0.950671] NAND device:
Manufacturer ID: 0xad, Chip ID: 0xdc (Hynix NAND 512MiB 3,3V 8-bit)
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 0.963360] 2 NAND chips detected
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 0.969418] cmdlinepart partition
parsing not available
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 0.975500] Searching for RedBoot
partition table in NAND 512MiB 3,3V 8-bit at offset 0x0
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 0.991481] No RedBoot partition
table detected in NAND 512MiB 3,3V 8-bit
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 1.017369] serio: i8042 KBD port at
0x60,0x64 irq 1
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 1.023086] serio: i8042 AUX port at
0x60,0x64 irq 12
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 1.039336] rtc_cmos rtc_cmos: rtc
core: registered rtc_cmos as rtc0
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 1.056715] rtc0: alarms up to one
year, y3k, 242 bytes nvram
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 1.075113] olpc-dcon: Discovered
DCON version 2
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 1.094945] Linux video capture
interface: v2.00
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 1.204833] cpuidle: using governor
ladder
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 1.221547] cpuidle: using governor
menu
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 1.238261] sdhci: Secure Digital
Host Controller Interface driver
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 1.258072] sdhci: Copyright(c)
Pierre Ossman
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 1.276374] sdhci-pci 0000:00:0c.1:
SDHCI controller found [11ab:4101] (rev 10)
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 1.298621] sdhci-pci 0000:00:0c.1:
Invalid iomem size. You may experience problems.
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 1.322200] mmc0: SDHCI controller
on PCI [0000:00:0c.1] using DMA
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 1.353854] geode-aes: GEODE AES
engine enabled.
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 1.376062] pci 0000:00:0f.0:
registered timer 1
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 1.397663] cs5535-clockevt: Unable
to set up the interrupt.
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 1.420972] cs5535-clockevt: Unable
to set up the MFGPT clock source
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 1.449707] Failure reading codec
reg 0x7e,Last value=0x7e805368
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 1.474436] Failure reading codec
reg 0x7e,Last value=0x7e805368
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 1.506668] ALSA device list:
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 1.528479] #0: CS5535 Audio
cs5535audio at 0x1480, irq 5
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 1.554231] TCP bic registered
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 1.576507] Initializing XFRM
netlink socket
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 1.600118] NET: Registered protocol
family 10
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 1.624297] lo: Disabled Privacy
Extensions
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 1.647896] Mobile IPv6
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 1.669158] NET: Registered protocol
family 17
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 1.727104] input: AT Translated Set
2 keyboard as /devices/platform/i8042/serio0/input/input4
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 1.831995] Freeing unused kernel
memory: 240k freed
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 1.930413] udev[52]: starting
version 161
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 7.890087] JFFS2 notice: (126)
jffs2_build_xattr_subsystem: complete building xattr subsystem, 0 of xdatum
(0 unchecked, 0 orphan) and 0 of xref (0 dead, 0 orphan) found.
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 7.979968] dracut: Mounted root
filesystem mtd0
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 8.743653] dcon_freeze_store: 0
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 8.746916] dcon_source_switch to CPU
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 8.790427] olpc-dcon: The CPU has
control
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 8.820227] dracut: Switching root
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 9.860198] usbcore: registered new
interface driver usbfs
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 9.867361] usbcore: registered new
interface driver hub
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 9.872845] usbcore: registered new
device driver usb
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 10.606582] udev[169]: starting
version 161
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 11.130752] Marvell M88ALP01 'CAFE'
Camera Controller version 2
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 11.151279] ohci_hcd: USB 1.1 'Open'
Host Controller (OHCI) Driver
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 11.246808] cafe1000-ccic
0000:00:0c.2: enabling device (0000 -> 0002)
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 11.328925] ehci_hcd: USB 2.0
'Enhanced' Host Controller (EHCI) Driver
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 11.658089] Warning! ehci_hcd should
always be loaded before uhci_hcd and ohci_hcd, not after
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 12.275128] psmouse serio1: OLPC
touchpad revision 0x3c
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 12.639344] ov7670 1-0042: chip
found @ 0x84 (cafe_ccic)
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 12.694021] ohci_hcd 0000:00:0f.4:
OHCI Host Controller
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 12.699341] ohci_hcd 0000:00:0f.4:
new USB bus registered, assigned bus number 1
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 12.730181] ohci_hcd 0000:00:0f.4:
irq 10, io mem 0xfe01a000
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 12.822838] input: OLPC HGPK ALPS
HGPK as /devices/platform/i8042/serio1/input/input5
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 12.946693] hub 1-0:1.0: USB hub
found
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 12.950483] hub 1-0:1.0: 4 ports
detected
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 12.957744] mice: PS/2 mouse device
common for all mice
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 13.007874] ehci_hcd 0000:00:0f.5:
EHCI Host Controller
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 13.031931] ehci_hcd 0000:00:0f.5:
new USB bus registered, assigned bus number 2
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 13.096782] ehci_hcd 0000:00:0f.5:
irq 10, io mem 0xfe01b000
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 13.110642] ehci_hcd 0000:00:0f.5:
USB 2.0 started, EHCI 1.00
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 13.158797] hub 2-0:1.0: USB hub
found
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 13.184539] hub 2-0:1.0: 4 ports
detected
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 13.510975] usb 2-1: new high speed
USB device using ehci_hcd and address 2
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 13.794786] lib80211: common
routines for IEEE802.11 drivers
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 14.306633] cfg80211: Calling CRDA
to update world regulatory domain
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 15.235815] cfg80211: World
regulatory domain updated:
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 15.265756] (start_freq -
end_freq @ bandwidth), (max_antenna_gain, max_eirp)
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 15.281348] (2402000 KHz -
2472000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 15.288465] (2457000 KHz -
2482000 KHz @ 20000 KHz), (300 mBi, 2000 mBm)
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 15.320133] (2474000 KHz -
2494000 KHz @ 20000 KHz), (300 mBi, 2000 mBm)
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 15.335105] (5170000 KHz -
5250000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 15.356386] (5735000 KHz -
5835000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 15.830108] usb8xxx: Firmware ready
event received
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 15.854260] libertas:
00:17:c4:05:2a:1f, fw 5.110.22p23, cap 0x000003a3
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 15.885590] libertas: wlan0: Marvell
WLAN 802.11 adapter
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 15.924370] libertas: CMD_RESP:
error 0x0002 in command reply 0x8074
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 15.930862] libertas: PREP_CMD:
command 0x0074 failed: 2
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 15.936185] usb8xxx: Firmware does
not seem to support PS mode
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 15.984129] libertas: CMD_RESP:
error 0x0001 in command reply 0x8043
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 16.007880] libertas: PREP_CMD:
command 0x0043 failed: 1
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 16.013210] libertas: HOST_SLEEP_CFG
failed 1
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 16.034003] usbcore: registered new
interface driver usb8xxx
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 16.531496] udev[173]: renamed
network interface wlan0 to eth0
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 20.489942] init:
ck-log-system-start main process (524) terminated with status 1
Apr 29 02:43:59 xo-05-2a-1f kernel: [ 20.970486] libertas: PREP_CMD:
unknown command 0x004e
Apr 29 02:44:00 xo-05-2a-1f NetworkManager[620]: <info> NetworkManager
(version 0.8.5.92-1.git20110927.fc14) is starting...
Apr 29 02:44:00 xo-05-2a-1f NetworkManager[620]: <info> Read config file
/etc/NetworkManager/NetworkManager.conf
Apr 29 02:44:00 xo-05-2a-1f avahi-daemon[633]: Found user 'avahi' (UID 70)
and group 'avahi' (GID 70).
Apr 29 02:44:00 xo-05-2a-1f avahi-daemon[633]: Successfully dropped root
privileges.
Apr 29 02:44:00 xo-05-2a-1f avahi-daemon[633]: avahi-daemon 0.6.27 starting
up.
Apr 29 02:44:00 xo-05-2a-1f avahi-daemon[633]: WARNING: No NSS support for
mDNS detected, consider installing nss-mdns!
Apr 29 02:44:00 xo-05-2a-1f avahi-daemon[633]: Successfully called chroot().
Apr 29 02:44:00 xo-05-2a-1f avahi-daemon[633]: Successfully dropped
remaining capabilities.
Apr 29 02:44:00 xo-05-2a-1f avahi-daemon[633]: Loading service file
/services/ssh.service.
Apr 29 02:44:00 xo-05-2a-1f avahi-daemon[633]: Loading service file
/services/udisks.service.
Apr 29 02:44:00 xo-05-2a-1f avahi-daemon[633]: Network interface
enumeration completed.
Apr 29 02:44:00 xo-05-2a-1f avahi-daemon[633]: Registering HINFO record
with values 'I586'/'LINUX'.
Apr 29 02:44:00 xo-05-2a-1f avahi-daemon[633]: Server startup complete.
Host name is xo-05-2a-1f.local. Local service cookie is 535333239.
Apr 29 02:44:00 xo-05-2a-1f avahi-daemon[633]: Service "xo-05-2a-1f"
(/services/udisks.service) successfully established.
Apr 29 02:44:00 xo-05-2a-1f avahi-daemon[633]: Service "xo-05-2a-1f"
(/services/ssh.service) successfully established.
Apr 29 02:44:01 xo-05-2a-1f NetworkManager[620]: <info> trying to start the
modem manager...
Apr 29 02:44:01 xo-05-2a-1f modem-manager[644]: <info> ModemManager
(version 0.4.998-1.git20110706.fc14) starting...
Apr 29 02:44:01 xo-05-2a-1f modem-manager[644]: <info> Loaded plugin
Novatel
Apr 29 02:44:01 xo-05-2a-1f polkitd[652]: started daemon version 0.98 using
authority implementation `local' version `0.98'
Apr 29 02:44:01 xo-05-2a-1f modem-manager[644]: <info> Loaded plugin Gobi
Apr 29 02:44:01 xo-05-2a-1f modem-manager[644]: <info> Loaded plugin MotoC
Apr 29 02:44:01 xo-05-2a-1f modem-manager[644]: <info> Loaded plugin
Samsung
Apr 29 02:44:01 xo-05-2a-1f modem-manager[644]: <info> Loaded plugin
Linktop
Apr 29 02:44:01 xo-05-2a-1f modem-manager[644]: <info> Loaded plugin
Longcheer
Apr 29 02:44:01 xo-05-2a-1f NetworkManager[620]: <info> monitoring kernel
firmware directory '/lib/firmware'.
Apr 29 02:44:01 xo-05-2a-1f modem-manager[644]: <info> Loaded plugin
SimTech
Apr 29 02:44:01 xo-05-2a-1f modem-manager[644]: <info> Loaded plugin Nokia
Apr 29 02:44:01 xo-05-2a-1f modem-manager[644]: <info> Loaded plugin Option
Apr 29 02:44:01 xo-05-2a-1f modem-manager[644]: <info> Loaded plugin Sierra
Apr 29 02:44:01 xo-05-2a-1f modem-manager[644]: <info> Loaded plugin
AnyData
Apr 29 02:44:01 xo-05-2a-1f modem-manager[644]: <info> Loaded plugin
Generic
Apr 29 02:44:01 xo-05-2a-1f NetworkManager[620]: ifcfg-rh: Acquired
D-Bus service com.redhat.ifcfgrh1
Apr 29 02:44:01 xo-05-2a-1f NetworkManager[620]: <info> Loaded plugin
ifcfg-rh: (c) 2007 - 2010 Red Hat, Inc. To report bugs please use the
NetworkManager mailing list.
Apr 29 02:44:01 xo-05-2a-1f NetworkManager[620]: ifcfg-rh: parsing
/etc/sysconfig/network-scripts/ifcfg-lo ...
Apr 29 02:44:01 xo-05-2a-1f NetworkManager[620]: <info> found WiFi radio
killswitch rfkill0 (at /sys/devices/platform/olpc-rfkill.0/rfkill/rfkill0)
(driver olpc-rfkill)
Apr 29 02:44:01 xo-05-2a-1f modem-manager[644]: <info> Loaded plugin
Option High-Speed
Apr 29 02:44:01 xo-05-2a-1f NetworkManager[620]: <info> found WiFi radio
killswitch rfkill1 (at /sys/devices/virtual/ieee80211/phy0/rfkill1) (driver
<unknown>)
Apr 29 02:44:02 xo-05-2a-1f modem-manager[644]: <info> Loaded plugin
Ericsson MBM
Apr 29 02:44:02 xo-05-2a-1f modem-manager[644]: <info> Loaded plugin Huawei
Apr 29 02:44:02 xo-05-2a-1f modem-manager[644]: <info> Loaded plugin ZTE
Apr 29 02:44:02 xo-05-2a-1f modem-manager[644]: <info> Loaded plugin X22X
Apr 29 02:44:02 xo-05-2a-1f modem-manager[644]: <info> Loaded plugin
Wavecom
Apr 29 02:44:02 xo-05-2a-1f NetworkManager[620]: <info> WiFi enabled by
radio killswitch; enabled by state file
Apr 29 02:44:02 xo-05-2a-1f NetworkManager[620]: <info> WWAN enabled by
radio killswitch; enabled by state file
Apr 29 02:44:02 xo-05-2a-1f NetworkManager[620]: <info> WiMAX enabled by
radio killswitch; enabled by state file
Apr 29 02:44:02 xo-05-2a-1f NetworkManager[620]: <info> Networking is
enabled by state file
Apr 29 02:44:02 xo-05-2a-1f NetworkManager[620]: <error>
[1335678242.357653] [nm-device-wifi.c:3142]
real_update_permanent_hw_address(): (eth0): unable to read permanent MAC
address (error 0)
Apr 29 02:44:02 xo-05-2a-1f NetworkManager[620]: <info> (eth0): driver
supports SSID scans (scan_capa 0x01).
Apr 29 02:44:02 xo-05-2a-1f NetworkManager[620]: <info> (eth0): new 802.11
WiFi device (driver: 'usb' ifindex: 2)
Apr 29 02:44:02 xo-05-2a-1f NetworkManager[620]: <info> (eth0): exported as
/org/freedesktop/NetworkManager/Devices/0
Apr 29 02:44:02 xo-05-2a-1f NetworkManager[620]: <info> (eth0): now managed
Apr 29 02:44:02 xo-05-2a-1f NetworkManager[620]: <info> (eth0): device
state change: 1 -> 2 (reason 2)
Apr 29 02:44:02 xo-05-2a-1f NetworkManager[620]: <info> (eth0): bringing up
device.
Apr 29 02:44:02 xo-05-2a-1f kernel: [ 26.368472] ADDRCONF(NETDEV_UP):
eth0: link is not ready
Apr 29 02:44:02 xo-05-2a-1f NetworkManager[620]: <info> (eth0): preparing
device.
Apr 29 02:44:02 xo-05-2a-1f NetworkManager[620]: <info> (eth0):
deactivating device (reason: 2).
Apr 29 02:44:02 xo-05-2a-1f NetworkManager[620]:
supplicant_interface_acquire: assertion `mgr_state ==
NM_SUPPLICANT_MANAGER_STATE_IDLE' failed
Apr 29 02:44:02 xo-05-2a-1f NetworkManager[620]: <info> modem-manager is
now available
Apr 29 02:44:02 xo-05-2a-1f NetworkManager[620]: <warn> bluez error getting
default adapter: The name org.bluez was not provided by any .service files
Apr 29 02:44:02 xo-05-2a-1f NetworkManager[620]: <info> Trying to start the
supplicant...
Apr 29 02:44:02 xo-05-2a-1f kernel: [ 26.831857] dcon_freeze_store: 1
Apr 29 02:44:02 xo-05-2a-1f kernel: [ 26.835120] dcon_source_switch to
DCON
Apr 29 02:44:02 xo-05-2a-1f kernel: [ 26.862356] olpc-dcon: The DCON has
control
Apr 29 02:44:03 xo-05-2a-1f olpc-switchd: starting version 47
Apr 29 02:44:03 xo-05-2a-1f olpc-switchd: will poll power sources every 10
seconds
Apr 29 02:44:03 xo-05-2a-1f olpc-switchd: found 4 switches
Apr 29 02:44:03 xo-05-2a-1f olpc-kbdshim-udev[699]: starting
olpc-kbdshim-udev version 25
Apr 29 02:44:04 xo-05-2a-1f olpc-kbdshim-udev[699]: fd 5: found keyboard
(AT Translated Set 2 keyboard) /dev/input/event4 (11:01:01)
Apr 29 02:44:04 xo-05-2a-1f olpc-kbdshim-udev[699]: fd 6: found touchpad
(OLPC HGPK ALPS HGPK) /dev/input/event5 (11:02:0d)
Apr 29 02:44:04 xo-05-2a-1f olpc-kbdshim-udev[699]: fd 7: found ebook switch
Apr 29 02:44:04 xo-05-2a-1f powerd: powerd version 47 startup at Sun Apr 29
02:44:04 UYT 2012, on XO-1
Apr 29 02:44:04 xo-05-2a-1f powerd: Linux version
2.6.35.13_xo1-20111005.1403.olpc.c2bd7b9 (***@sinistra.laptop.org) (gcc
version 4.5.1 20100924 (Red Hat 4.5.1-4) (GCC) ) #1 PREEMPT Wed Oct 5
14:05:52 EDT 2011
Apr 29 02:44:04 xo-05-2a-1f powerd: olpc build: 20 Version a (Dextrose 3 Uy)
Apr 29 02:44:05 xo-05-2a-1f powerd: starting
Apr 29 02:44:05 xo-05-2a-1f powerd: configuring from /etc/powerd/powerd.conf
Apr 29 02:44:05 xo-05-2a-1f kernel: [ 29.616610] input: olpc-kbdshim
virtual input as /devices/virtual/input/input6
Apr 29 02:44:05 xo-05-2a-1f powerd: configured to never shutdown while
plugged in
Apr 29 02:44:05 xo-05-2a-1f NetworkManager[620]: <info> (eth0): supplicant
manager state: down -> idle
Apr 29 02:44:05 xo-05-2a-1f NetworkManager[620]: <info> (eth0): device
state change: 2 -> 3 (reason 0)
Apr 29 02:44:07 xo-05-2a-1f NetworkManager[620]: <info> (eth0): supplicant
interface state: starting -> ready
Apr 29 02:44:08 xo-05-2a-1f kernel: [ 32.020282] ip_tables: (C) 2000-2006
Netfilter Core Team
Apr 29 02:44:11 xo-05-2a-1f olpc-kbdshim-udev[699]: idle timers changed to
10 180 420
Apr 29 02:44:11 xo-05-2a-1f powerd: sleep 15, dim 180, blank 420, shutdown
after 14400
Apr 29 02:44:34 xo-05-2a-1f gnome-keyring-daemon[1030]: GLib-GIO: Using the
'memory' GSettings backend. Your settings will not be saved or shared with
other applications.
Apr 29 02:44:34 xo-05-2a-1f gnome-keyring-daemon[1030]: couldn't set
environment variable in session: The name org.gnome.SessionManager was not
provided by any .service files
Apr 29 02:44:36 xo-05-2a-1f NetworkManager[620]: <info> Activation (eth0)
starting connection 'Auto 001a4d2f872c'
Apr 29 02:44:36 xo-05-2a-1f NetworkManager[620]: <info> (eth0): device
state change: 3 -> 4 (reason 0)
Apr 29 02:44:36 xo-05-2a-1f NetworkManager[620]: <info> Activation (eth0)
Stage 1 of 5 (Device Prepare) scheduled...
Apr 29 02:44:36 xo-05-2a-1f NetworkManager[620]: <info> Activation (eth0)
Stage 1 of 5 (Device Prepare) started...
Apr 29 02:44:36 xo-05-2a-1f NetworkManager[620]: <info> Activation (eth0)
Stage 2 of 5 (Device Configure) scheduled...
Apr 29 02:44:36 xo-05-2a-1f NetworkManager[620]: <info> Activation (eth0)
Stage 1 of 5 (Device Prepare) complete.
Apr 29 02:44:36 xo-05-2a-1f NetworkManager[620]: <info> Activation (eth0)
Stage 2 of 5 (Device Configure) starting...
Apr 29 02:44:36 xo-05-2a-1f NetworkManager[620]: <info> (eth0): device
state change: 4 -> 5 (reason 0)
Apr 29 02:44:36 xo-05-2a-1f NetworkManager[620]: <info> Activation
(eth0/wireless): access point 'Auto 001a4d2f872c' has security, but secrets
are required.
Apr 29 02:44:36 xo-05-2a-1f NetworkManager[620]: <info> (eth0): device
state change: 5 -> 6 (reason 0)
Apr 29 02:44:36 xo-05-2a-1f NetworkManager[620]: <info> Activation (eth0)
Stage 2 of 5 (Device Configure) complete.
Apr 29 02:44:37 xo-05-2a-1f NetworkManager[620]: <info> Activation (eth0)
Stage 1 of 5 (Device Prepare) scheduled...
Apr 29 02:44:37 xo-05-2a-1f NetworkManager[620]: <info> Activation (eth0)
Stage 1 of 5 (Device Prepare) started...
Apr 29 02:44:37 xo-05-2a-1f NetworkManager[620]: <info> (eth0): device
state change: 6 -> 4 (reason 0)
Apr 29 02:44:37 xo-05-2a-1f NetworkManager[620]: <info> Activation (eth0)
Stage 2 of 5 (Device Configure) scheduled...
Apr 29 02:44:37 xo-05-2a-1f NetworkManager[620]: <info> Activation (eth0)
Stage 1 of 5 (Device Prepare) complete.
Apr 29 02:44:37 xo-05-2a-1f NetworkManager[620]: <info> Activation (eth0)
Stage 2 of 5 (Device Configure) starting...
Apr 29 02:44:37 xo-05-2a-1f NetworkManager[620]: <info> (eth0): device
state change: 4 -> 5 (reason 0)
Apr 29 02:44:37 xo-05-2a-1f NetworkManager[620]: <info> Activation
(eth0/wireless): connection 'Auto 001a4d2f872c' has security, and secrets
exist. No new secrets needed.
Apr 29 02:44:37 xo-05-2a-1f NetworkManager[620]: <info> Config: added
'ssid' value '001a4d2f872c'
Apr 29 02:44:37 xo-05-2a-1f NetworkManager[620]: <info> Config: added
'scan_ssid' value '1'
Apr 29 02:44:37 xo-05-2a-1f NetworkManager[620]: <info> Config: added
'key_mgmt' value 'NONE'
Apr 29 02:44:37 xo-05-2a-1f NetworkManager[620]: <info> Config: added
'auth_alg' value 'OPEN'
Apr 29 02:44:37 xo-05-2a-1f NetworkManager[620]: <info> Config: added
'wep_key0' value '<omitted>'
Apr 29 02:44:37 xo-05-2a-1f NetworkManager[620]: <info> Config: added
'wep_tx_keyidx' value '0'
Apr 29 02:44:37 xo-05-2a-1f NetworkManager[620]: <info> Activation (eth0)
Stage 2 of 5 (Device Configure) complete.
Apr 29 02:44:37 xo-05-2a-1f NetworkManager[620]: <info> Config: set
interface ap_scan to 1
Apr 29 02:44:37 xo-05-2a-1f NetworkManager[620]: <info> (eth0): supplicant
connection state: inactive -> associating
Apr 29 02:44:37 xo-05-2a-1f kernel: [ 61.900024] ADDRCONF(NETDEV_CHANGE):
eth0: link becomes ready
Apr 29 02:44:37 xo-05-2a-1f NetworkManager[620]: <info> (eth0): supplicant
connection state: associating -> associated
Apr 29 02:44:37 xo-05-2a-1f NetworkManager[620]: <info> (eth0): supplicant
connection state: associated -> completed
Apr 29 02:44:37 xo-05-2a-1f NetworkManager[620]: <info> Activation
(eth0/wireless) Stage 2 of 5 (Device Configure) successful. Connected to
wireless network '001a4d2f872c'.
Apr 29 02:44:37 xo-05-2a-1f NetworkManager[620]: <info> Activation (eth0)
Stage 3 of 5 (IP Configure Start) scheduled.
Apr 29 02:44:37 xo-05-2a-1f NetworkManager[620]: <info> Activation (eth0)
Stage 3 of 5 (IP Configure Start) started...
Apr 29 02:44:37 xo-05-2a-1f NetworkManager[620]: <info> (eth0): device
state change: 5 -> 7 (reason 0)
Apr 29 02:44:37 xo-05-2a-1f NetworkManager[620]: <info> Activation (eth0)
Beginning DHCPv4 transaction (timeout in 45 seconds)
Apr 29 02:44:37 xo-05-2a-1f NetworkManager[620]: <info> dhclient started
with pid 1043
Apr 29 02:44:37 xo-05-2a-1f NetworkManager[620]: <info> Activation (eth0)
Stage 3 of 5 (IP Configure Start) complete.
Apr 29 02:44:38 xo-05-2a-1f dhclient[1043]: Internet Systems Consortium
DHCP Client 4.2.0-P2
Apr 29 02:44:38 xo-05-2a-1f dhclient[1043]: Copyright 2004-2010 Internet
Systems Consortium.
Apr 29 02:44:38 xo-05-2a-1f dhclient[1043]: All rights reserved.
Apr 29 02:44:38 xo-05-2a-1f dhclient[1043]: For info, please visit
https://www.isc.org/software/dhcp/
Apr 29 02:44:38 xo-05-2a-1f dhclient[1043]:
Apr 29 02:44:38 xo-05-2a-1f dhclient[1043]: Listening on
LPF/eth0/00:17:c4:05:2a:1f
Apr 29 02:44:38 xo-05-2a-1f dhclient[1043]: Sending on
LPF/eth0/00:17:c4:05:2a:1f
Apr 29 02:44:38 xo-05-2a-1f dhclient[1043]: Sending on Socket/fallback
Apr 29 02:44:39 xo-05-2a-1f NetworkManager[620]: <info> (eth0): DHCPv4
state changed nbi -> preinit
Apr 29 02:44:39 xo-05-2a-1f dhclient[1043]: DHCPDISCOVER on eth0 to
255.255.255.255 port 67 interval 3
Apr 29 02:44:39 xo-05-2a-1f dhclient[1043]: DHCPOFFER from 192.168.1.1
Apr 29 02:44:39 xo-05-2a-1f dhclient[1043]: DHCPREQUEST on eth0 to
255.255.255.255 port 67
Apr 29 02:44:39 xo-05-2a-1f dhclient[1043]: DHCPACK from 192.168.1.1
Apr 29 02:44:39 xo-05-2a-1f dhclient[1043]: bound to 192.168.1.3 -- renewal
in 42912 seconds.
Apr 29 02:44:39 xo-05-2a-1f NetworkManager[620]: <info> (eth0): DHCPv4
state changed preinit -> bound
Apr 29 02:44:39 xo-05-2a-1f NetworkManager[620]: <info> Activation (eth0)
Stage 4 of 5 (IP4 Configure Get) scheduled...
Apr 29 02:44:39 xo-05-2a-1f NetworkManager[620]: <info> Activation (eth0)
Stage 4 of 5 (IP4 Configure Get) started...
Apr 29 02:44:39 xo-05-2a-1f NetworkManager[620]: <info> address
192.168.1.3
Apr 29 02:44:39 xo-05-2a-1f NetworkManager[620]: <info> prefix 24
(255.255.255.0)
Apr 29 02:44:39 xo-05-2a-1f NetworkManager[620]: <info> gateway
192.168.1.1
Apr 29 02:44:39 xo-05-2a-1f NetworkManager[620]: <info> hostname 'dhcppc1'
Apr 29 02:44:39 xo-05-2a-1f NetworkManager[620]: <info> nameserver
'203.94.243.70'
Apr 29 02:44:39 xo-05-2a-1f NetworkManager[620]: <info> nameserver
'203.94.227.70'
Apr 29 02:44:39 xo-05-2a-1f NetworkManager[620]: <info> Scheduling stage 5
Apr 29 02:44:39 xo-05-2a-1f NetworkManager[620]: <info> Activation (eth0)
Stage 5 of 5 (IP Configure Commit) scheduled...
Apr 29 02:44:39 xo-05-2a-1f NetworkManager[620]: <info> Done scheduling
stage 5
Apr 29 02:44:39 xo-05-2a-1f NetworkManager[620]: <info> Activation (eth0)
Stage 4 of 5 (IP4 Configure Get) complete.
Apr 29 02:44:39 xo-05-2a-1f NetworkManager[620]: <info> Activation (eth0)
Stage 5 of 5 (IP Configure Commit) started...
Apr 29 02:44:39 xo-05-2a-1f avahi-daemon[633]: Joining mDNS multicast group
on interface eth0.IPv4 with address 192.168.1.3.
Apr 29 02:44:39 xo-05-2a-1f avahi-daemon[633]: New relevant interface
eth0.IPv4 for mDNS.
Apr 29 02:44:39 xo-05-2a-1f avahi-daemon[633]: Registering new address
record for 192.168.1.3 on eth0.IPv4.
Apr 29 02:44:39 xo-05-2a-1f kernel: [ 63.333513] dcon_freeze_store: 0
Apr 29 02:44:39 xo-05-2a-1f kernel: [ 63.336778] dcon_source_switch to CPU
Apr 29 02:44:39 xo-05-2a-1f kernel: [ 63.370802] olpc-dcon: The CPU has
control
Apr 29 02:44:39 xo-05-2a-1f avahi-daemon[633]: Registering new address
record for fe80::217:c4ff:fe05:2a1f on eth0.*.
Apr 29 02:44:40 xo-05-2a-1f NetworkManager[620]: <info> (eth0): device
state change: 7 -> 8 (reason 0)
Apr 29 02:44:40 xo-05-2a-1f NetworkManager[620]: <info> Policy set 'Auto
001a4d2f872c' (eth0) as default for IPv4 routing and DNS.
Apr 29 02:44:40 xo-05-2a-1f NetworkManager[620]: <info> Activation (eth0)
successful, device activated.
Apr 29 02:44:40 xo-05-2a-1f NetworkManager[620]: <info> Activation (eth0)
Stage 5 of 5 (IP Configure Commit) complete.
Apr 29 02:52:45 xo-05-2a-1f kernel: [ 549.409614] dcon_freeze_store: 1
Apr 29 02:52:45 xo-05-2a-1f kernel: [ 549.462391] dcon_source_switch to
DCON
Apr 29 02:52:45 xo-05-2a-1f kernel: [ 549.502797] olpc-dcon: The DCON has
control
Apr 29 02:52:45 xo-05-2a-1f kernel: [ 549.649543] dcon_freeze_store: 1
Apr 29 02:52:46 xo-05-2a-1f kernel: [ 550.060415] dcon_freeze_store: 0
Apr 29 02:52:46 xo-05-2a-1f kernel: [ 550.060415] dcon_source_switch to CPU
Apr 29 02:52:46 xo-05-2a-1f kernel: [ 550.110218] olpc-dcon: The CPU has
control
Apr 29 02:52:52 xo-05-2a-1f NetworkManager[620]: <info> WiFi now disabled
by radio killswitch
Apr 29 02:52:52 xo-05-2a-1f NetworkManager[620]: <info> (eth0): device
state change: 8 -> 2 (reason 0)
Apr 29 02:52:52 xo-05-2a-1f NetworkManager[620]: <info> (eth0):
deactivating device (reason: 0).
Apr 29 02:52:52 xo-05-2a-1f NetworkManager[620]: <info> (eth0): canceled
DHCP transaction, DHCP client pid 1043
Apr 29 02:52:52 xo-05-2a-1f kernel: [ 556.600861] usb 2-1: USB disconnect,
address 2
Apr 29 02:52:52 xo-05-2a-1f avahi-daemon[633]: Withdrawing address record
for 192.168.1.3 on eth0.
Apr 29 02:52:52 xo-05-2a-1f avahi-daemon[633]: Leaving mDNS multicast group
on interface eth0.IPv4 with address 192.168.1.3.
Apr 29 02:52:52 xo-05-2a-1f avahi-daemon[633]: Interface eth0.IPv4 no
longer relevant for mDNS.
Apr 29 02:52:52 xo-05-2a-1f avahi-daemon[633]: Withdrawing address record
for fe80::217:c4ff:fe05:2a1f on eth0.
Apr 29 02:52:52 xo-05-2a-1f avahi-daemon[633]: Withdrawing workstation
service for eth0.
Apr 29 02:52:52 xo-05-2a-1f NetworkManager[620]: <info> (eth0): now
unmanaged
Apr 29 02:52:52 xo-05-2a-1f NetworkManager[620]: <info> (eth0): device
state change: 2 -> 1 (reason 36)
Apr 29 02:52:52 xo-05-2a-1f NetworkManager[620]: <info> (eth0): cleaning
up...
Apr 29 02:52:52 xo-05-2a-1f NetworkManager[620]: <info> radio killswitch
/sys/devices/virtual/ieee80211/phy0/rfkill1 disappeared
Apr 29 02:52:53 xo-05-2a-1f nm-dispatcher.action: Error in get_property:
Method "Get" with signature "ss" on interface
"org.freedesktop.DBus.Properties" doesn't exist#012
Apr 29 02:52:53 xo-05-2a-1f kernel: [ 557.790406] PM: Syncing filesystems
... done.
Apr 29 03:11:25 xo-05-2a-1f kernel: [ 557.798137] Freezing user space
processes ... (elapsed 0.02 seconds) done.
Apr 29 03:11:25 xo-05-2a-1f kernel: [ 557.826215] Freezing remaining
freezable tasks ... (elapsed 0.02 seconds) done.
Apr 29 03:11:25 xo-05-2a-1f kernel: [ 557.859465] dcon_source_switch to
DCON
Apr 29 03:11:25 xo-05-2a-1f kernel: [ 557.892288] olpc-dcon: The DCON has
control
Apr 29 03:11:25 xo-05-2a-1f kernel: [ 557.916879] sdhci_pci_probe: Enable
PME set to 0x1a0c108
Apr 29 03:11:25 xo-05-2a-1f kernel: [ 557.922242] PM: suspend of devices
complete after 62.966 msecs
Apr 29 03:11:25 xo-05-2a-1f kernel: [ 557.946662] ehci_hcd 0000:00:0f.5:
Refused to change power state, currently in D0
Apr 29 03:11:25 xo-05-2a-1f kernel: [ 557.976933] ohci_hcd 0000:00:0f.4:
Refused to change power state, currently in D0
Apr 29 03:11:25 xo-05-2a-1f kernel: [ 557.984608] PM: late suspend of
devices complete after 56.429 msecs
Apr 29 03:11:25 xo-05-2a-1f kernel: [ 557.993192] olpc_do_sleep!
Apr 29 03:11:25 xo-05-2a-1f kernel: [ 557.993192] PM: early resume of
devices complete after 0.521 msecs
Apr 29 03:11:25 xo-05-2a-1f kernel: [ 558.111968] usb usb1: root hub lost
power or was reset
Apr 29 03:11:25 xo-05-2a-1f kernel: [ 558.133970] usb usb2: root hub lost
power or was reset
Apr 29 03:11:25 xo-05-2a-1f kernel: [ 558.145371] dcon_source_switch to CPU
Apr 29 03:11:25 xo-05-2a-1f kernel: [ 558.187020] olpc-dcon: The CPU has
control
Apr 29 03:11:25 xo-05-2a-1f kernel: [ 558.416732] PM: resume of devices
complete after 411.146 msecs
Apr 29 03:11:25 xo-05-2a-1f kernel: [ 558.422745] Restarting tasks ...
done.
Apr 29 03:11:25 xo-05-2a-1f NetworkManager[620]: <info> WiFi now enabled by
radio killswitch
Apr 29 03:11:25 xo-05-2a-1f kernel: [ 558.934219] dcon_freeze_store: 0
Apr 29 03:11:26 xo-05-2a-1f kernel: [ 558.996489] dcon_freeze_store: 0
Apr 29 03:11:26 xo-05-2a-1f kernel: [ 559.176729] usb 2-1: new high speed
USB device using ehci_hcd and address 3
Apr 29 03:11:27 xo-05-2a-1f kernel: [ 560.065633] usb8xxx: Firmware ready
event received
Apr 29 03:11:27 xo-05-2a-1f kernel: [ 560.092068] libertas:
00:17:c4:05:2a:1f, fw 5.110.22p23, cap 0x000003a3
Apr 29 03:11:27 xo-05-2a-1f kernel: [ 560.111778] libertas: wlan0: Marvell
WLAN 802.11 adapter
Apr 29 03:11:27 xo-05-2a-1f kernel: [ 560.138293] libertas: CMD_RESP:
error 0x0002 in command reply 0x8074
Apr 29 03:11:27 xo-05-2a-1f kernel: [ 560.144914] libertas: PREP_CMD:
command 0x0074 failed: 2
Apr 29 03:11:27 xo-05-2a-1f kernel: [ 560.150243] usb8xxx: Firmware does
not seem to support PS mode
Apr 29 03:11:27 xo-05-2a-1f kernel: [ 560.208672] libertas: CMD_RESP:
error 0x0001 in command reply 0x8043
Apr 29 03:11:27 xo-05-2a-1f kernel: [ 560.215439] libertas: PREP_CMD:
command 0x0043 failed: 1
Apr 29 03:11:27 xo-05-2a-1f kernel: [ 560.220769] libertas: HOST_SLEEP_CFG
failed 1
Apr 29 03:11:27 xo-05-2a-1f NetworkManager[620]: <info> found WiFi radio
killswitch rfkill2 (at /sys/devices/virtual/ieee80211/phy1/rfkill2) (driver
<unknown>)
Apr 29 03:11:27 xo-05-2a-1f kernel: [ 560.563949] udev[1400]: renamed
network interface wlan0 to eth0
Apr 29 03:11:27 xo-05-2a-1f NetworkManager[620]: <info> (msh0): new 802.11
OLPC Mesh device (driver: 'usb' ifindex: 5)
Apr 29 03:11:27 xo-05-2a-1f NetworkManager[620]: <info> (msh0): exported as
/org/freedesktop/NetworkManager/Devices/1
Apr 29 03:11:27 xo-05-2a-1f NetworkManager[620]: <info> (msh0): now managed
Apr 29 03:11:27 xo-05-2a-1f NetworkManager[620]: <info> (msh0): device
state change: 1 -> 2 (reason 2)
Apr 29 03:11:27 xo-05-2a-1f NetworkManager[620]: <info> (msh0): bringing up
device.
Apr 29 03:11:27 xo-05-2a-1f NetworkManager[620]: <info> (msh0): preparing
device.
Apr 29 03:11:27 xo-05-2a-1f NetworkManager[620]: <info> (msh0):
deactivating device (reason: 2).
Apr 29 03:11:27 xo-05-2a-1f NetworkManager[620]: <error>
[1335679887.687698] [nm-device-wifi.c:3142]
real_update_permanent_hw_address(): (eth0): unable to read permanent MAC
address (error 0)
Apr 29 03:11:27 xo-05-2a-1f NetworkManager[620]: <info> (eth0): driver
supports SSID scans (scan_capa 0x01).
Apr 29 03:11:27 xo-05-2a-1f NetworkManager[620]: <info> (eth0): new 802.11
WiFi device (driver: 'usb' ifindex: 4)
Apr 29 03:11:27 xo-05-2a-1f NetworkManager[620]: <info> (eth0): exported as
/org/freedesktop/NetworkManager/Devices/2
Apr 29 03:11:27 xo-05-2a-1f NetworkManager[620]: <info> (eth0): now managed
Apr 29 03:11:27 xo-05-2a-1f NetworkManager[620]: <info> (eth0): device
state change: 1 -> 2 (reason 2)
Apr 29 03:11:27 xo-05-2a-1f NetworkManager[620]: <info> (eth0): bringing up
device.
Apr 29 03:11:27 xo-05-2a-1f kernel: [ 560.738668] ADDRCONF(NETDEV_UP):
eth0: link is not ready
Apr 29 03:11:27 xo-05-2a-1f NetworkManager[620]: <info> (eth0): preparing
device.
Apr 29 03:11:27 xo-05-2a-1f NetworkManager[620]: <info> (eth0):
deactivating device (reason: 2).
Apr 29 03:11:27 xo-05-2a-1f NetworkManager[620]: <info> (msh0): device
state change: 2 -> 3 (reason 0)
Apr 29 03:11:27 xo-05-2a-1f NetworkManager[620]: <info> (msh0): found
companion WiFi device eth0
Apr 29 03:11:28 xo-05-2a-1f NetworkManager[620]: <info> (eth0): supplicant
interface state: starting -> ready
Apr 29 03:11:28 xo-05-2a-1f NetworkManager[620]: <info> (eth0): device
state change: 2 -> 3 (reason 42)
Apr 29 03:11:28 xo-05-2a-1f avahi-daemon[633]: Withdrawing workstation
service for msh0.
Apr 29 03:11:28 xo-05-2a-1f NetworkManager[620]: <info> (msh0): now
unmanaged
Apr 29 03:11:28 xo-05-2a-1f NetworkManager[620]: <info> (msh0): device
state change: 3 -> 1 (reason 36)
Apr 29 03:11:28 xo-05-2a-1f NetworkManager[620]: <info> (msh0): cleaning
up...
Apr 29 03:11:29 xo-05-2a-1f NetworkManager[620]: <warn> caught signal 11.
Generating backtrace...
Apr 29 03:11:29 xo-05-2a-1f NetworkManager[620]: ******************* START
**********************************
Apr 29 03:11:29 xo-05-2a-1f NetworkManager[620]: Frame 0: NetworkManager
(nm_logging_backtrace+0x56) [0x80a1536]
Apr 29 03:11:29 xo-05-2a-1f NetworkManager[620]: Frame 1: NetworkManager
(0x8048000+0x8085ee5) [0x8085ee5]
Apr 29 03:11:29 xo-05-2a-1f NetworkManager[620]: Frame 2: (vdso)
(__kernel_sigreturn+0x0) [0xb778b400]
Apr 29 03:11:29 xo-05-2a-1f NetworkManager[620]: Frame 3: NetworkManager
(0x8048000+0x8076404) [0x8076404]
Apr 29 03:11:29 xo-05-2a-1f NetworkManager[620]: Frame 4:
/lib/libgobject-2.0.so.0 (g_cclosure_marshal_VOID__PARAM+0x88) [0xb72c5588]
Apr 29 03:11:29 xo-05-2a-1f NetworkManager[620]: Frame 5:
/lib/libgobject-2.0.so.0 (g_closure_invoke+0x193) [0xb72a8be3]
Apr 29 03:11:29 xo-05-2a-1f NetworkManager[620]: Frame 6:
/lib/libgobject-2.0.so.0 (0xb729d000+0xb72bb0f0) [0xb72bb0f0]
Apr 29 03:11:29 xo-05-2a-1f NetworkManager[620]: Frame 7:
/lib/libgobject-2.0.so.0 (g_signal_emit_valist+0x80e) [0xb72c424e]
Apr 29 03:11:29 xo-05-2a-1f NetworkManager[620]: Frame 8:
/lib/libgobject-2.0.so.0 (g_signal_emit+0x33) [0xb72c4403]
Apr 29 03:11:29 xo-05-2a-1f NetworkManager[620]: Frame 9:
/lib/libgobject-2.0.so.0 (0xb729d000+0xb72aa891) [0xb72aa891]
Apr 29 03:11:29 xo-05-2a-1f NetworkManager[620]: Frame 10:
/lib/libgobject-2.0.so.0 (0xb729d000+0xb72a9c70) [0xb72a9c70]
Apr 29 03:11:29 xo-05-2a-1f NetworkManager[620]: Frame 11:
/lib/libgobject-2.0.so.0 (g_object_notify+0x551) [0xb72acbe1]
Apr 29 03:11:29 xo-05-2a-1f NetworkManager[620]: Frame 12:
/lib/libgobject-2.0.so.0 (g_cclosure_marshal_VOID__PARAM+0x88) [0xb72c5588]
Apr 29 03:11:29 xo-05-2a-1f NetworkManager[620]: Frame 13:
/lib/libgobject-2.0.so.0 (g_closure_invoke+0x193) [0xb72a8be3]
Apr 29 03:11:29 xo-05-2a-1f NetworkManager[620]: Frame 14:
/lib/libgobject-2.0.so.0 (0xb729d000+0xb72bb0f0) [0xb72bb0f0]
Apr 29 03:11:29 xo-05-2a-1f NetworkManager[620]: Frame 15:
/lib/libgobject-2.0.so.0 (g_signal_emit_valist+0x80e) [0xb72c424e]
Apr 29 03:11:29 xo-05-2a-1f NetworkManager[620]: Frame 16:
/lib/libgobject-2.0.so.0 (g_signal_emit+0x33) [0xb72c4403]
Apr 29 03:11:29 xo-05-2a-1f NetworkManager[620]: Frame 17:
/lib/libgobject-2.0.so.0 (0xb729d000+0xb72aa891) [0xb72aa891]
Apr 29 03:11:29 xo-05-2a-1f NetworkManager[620]: Frame 18:
/lib/libgobject-2.0.so.0 (0xb729d000+0xb72a9c70) [0xb72a9c70]
Apr 29 03:11:29 xo-05-2a-1f NetworkManager[620]: Frame 19:
/lib/libgobject-2.0.so.0 (g_object_notify+0x551) [0xb72acbe1]
Apr 29 03:11:29 xo-05-2a-1f NetworkManager[620]: Frame 20: NetworkManager
(0x8048000+0x80bcb6f) [0x80bcb6f]
Apr 29 03:11:29 xo-05-2a-1f NetworkManager[620]: Frame 21:
/lib/libgobject-2.0.so.0 (g_cclosure_marshal_VOID__BOOLEAN+0x88)
[0xb72c4c88]
Apr 29 03:11:29 xo-05-2a-1f NetworkManager[620]: Frame 22:
/usr/lib/libdbus-glib-1.so.2 (0xb74e2000+0xb74f010c) [0xb74f010c]
Apr 29 03:11:29 xo-05-2a-1f NetworkManager[620]: Frame 23:
/lib/libgobject-2.0.so.0 (g_closure_invoke+0x193) [0xb72a8be3]
Apr 29 03:11:29 xo-05-2a-1f NetworkManager[620]: Frame 24:
/lib/libgobject-2.0.so.0 (0xb729d000+0xb72bb0f0) [0xb72bb0f0]
Apr 29 03:11:29 xo-05-2a-1f NetworkManager[620]: Frame 25:
/lib/libgobject-2.0.so.0 (g_signal_emit_valist+0x80e) [0xb72c424e]
Apr 29 03:11:29 xo-05-2a-1f NetworkManager[620]: Frame 26:
/lib/libgobject-2.0.so.0 (g_signal_emit+0x33) [0xb72c4403]
Apr 29 03:11:29 xo-05-2a-1f NetworkManager[620]: Frame 27:
/usr/lib/libdbus-glib-1.so.2 (0xb74e2000+0xb74f0d35) [0xb74f0d35]
Apr 29 03:11:29 xo-05-2a-1f NetworkManager[620]: Frame 28:
/lib/libdbus-1.so.3 (dbus_connection_dispatch+0x393) [0xb74a4f03]
Apr 29 03:11:29 xo-05-2a-1f NetworkManager[620]: Frame 29:
/usr/lib/libdbus-glib-1.so.2 (0xb74e2000+0xb74e920e) [0xb74e920e]
Apr 29 03:11:29 xo-05-2a-1f NetworkManager[620]: Frame 30:
/lib/libglib-2.0.so.0 (g_main_context_dispatch+0x1d2) [0xb71bd192]
Apr 29 03:11:29 xo-05-2a-1f NetworkManager[620]: Frame 31:
/lib/libglib-2.0.so.0 (0xb717d000+0xb71bd978) [0xb71bd978]
Apr 29 03:11:29 xo-05-2a-1f NetworkManager[620]: Frame 32:
/lib/libglib-2.0.so.0 (g_main_loop_run+0x18b) [0xb71be04b]
Apr 29 03:11:29 xo-05-2a-1f NetworkManager[620]: Frame 33: NetworkManager
(main+0x15d6) [0x80876b6]
Apr 29 03:11:29 xo-05-2a-1f NetworkManager[620]: Frame 34: /lib/libc.so.6
(__libc_start_main+0xe6) [0xb6fd9e36]
Apr 29 03:11:29 xo-05-2a-1f NetworkManager[620]: Frame 35: NetworkManager
(0x8048000+0x805df01) [0x805df01]
Apr 29 03:11:29 xo-05-2a-1f NetworkManager[620]: ******************* END
**********************************
Apr 29 04:20:51 xo-05-2a-1f kernel: [ 4724.307009] usb 2-2: new high speed
USB device using ehci_hcd and address 4
Apr 29 04:20:51 xo-05-2a-1f kernel: [ 4724.521615] usbcore: registered new
interface driver libusual
Apr 29 04:20:51 xo-05-2a-1f kernel: [ 4724.610166] Initializing USB Mass
Storage driver...
Apr 29 04:20:51 xo-05-2a-1f kernel: [ 4724.619576] scsi0 : usb-storage
2-2:1.0
Apr 29 04:20:51 xo-05-2a-1f kernel: [ 4724.630555] usbcore: registered new
interface driver usb-storage
Apr 29 04:20:51 xo-05-2a-1f kernel: [ 4724.636943] USB Mass Storage support
registered.
Apr 29 04:20:52 xo-05-2a-1f kernel: [ 4725.663079] scsi 0:0:0:0:
Direct-Access Kingston DataTraveler 108 PMAP PQ: 0 ANSI: 0 CCS
Apr 29 04:20:52 xo-05-2a-1f kernel: [ 4725.726899] sd 0:0:0:0: Attached
scsi generic sg0 type 0
Apr 29 04:20:53 xo-05-2a-1f kernel: [ 4726.416348] sd 0:0:0:0: [sda]
15646720 512-byte logical blocks: (8.01 GB/7.46 GiB)
Apr 29 04:20:53 xo-05-2a-1f kernel: [ 4726.426369] sd 0:0:0:0: [sda] Write
Protect is off
Apr 29 04:20:53 xo-05-2a-1f kernel: [ 4726.431204] sd 0:0:0:0: [sda]
Assuming drive cache: write through
Apr 29 04:20:53 xo-05-2a-1f kernel: [ 4726.444165] sd 0:0:0:0: [sda]
Assuming drive cache: write through
Apr 29 04:20:53 xo-05-2a-1f kernel: [ 4726.450419] sda: sda1
Apr 29 04:20:53 xo-05-2a-1f kernel: [ 4726.473921] sd 0:0:0:0: [sda]
Assuming drive cache: write through
Apr 29 04:20:53 xo-05-2a-1f kernel: [ 4726.480282] sd 0:0:0:0: [sda]
Attached SCSI removable disk
####################################################################################################################

Looking forward to some guiding light.

Regards,
Ajay

Martin Abente

2012-04-29 08:04:41 UTC