[Date Prev][Date Next] [Thread Prev][Thread Next] [Date Index] [Thread Index]

Bug#550739: Wrong SIOCSIWENCODEEXT return code when crypto module not loaded



Hello,

On Wed, Jul 14, 2010 at 10:30:02PM +0200, Moritz Muehlenhoff wrote:
> tags 550739 moreinfo
> thanks
> 
> On Mon, Oct 12, 2009 at 05:52:39PM +0100, Marcin Owsiany wrote:
> > Package: linux-2.6
> > Version: 2.6.30-7
> > 
> > Here is a relevant fragment from http://bugs.debian.org/506223 about the
> > problem.
> > 
> > > > WPA: Sending EAPOL-Key 4/4
> > > > WPA: TX EAPOL-Key - hexdump(len=99): 01 03 00 5f 02 03 0a 00 00 00 00 00 00 00 00 00 02 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 de 32 41 70 ef ea 9e 2f ce 1c ef b5 48 52 32 f8 00 00
> > > > WPA: Installing PTK to the driver.
> > > > wpa_driver_wext_set_key: alg=3 key_idx=0 set_tx=1 seq_len=6 key_len=16
> > > > ioctl[SIOCSIWENCODEEXT]: Cannot allocate memory
> > > [...]
> > >
> > > This indicates that the mac80211 code was unable to set up the AES
> > > encryption/decryption state for WPA.  Excluding the unlikely case that
> > > the system is really out of memory, this means that the 'aes' module is
> > > not available.  Please try adding that to your installation image.
> > 
> > This made it difficult to set up WPA-based networking, as the error message
> > does not point in the right direction.
> 
> Hi,
> The next release of Debian (6.0, code name Squeeze) will be based
> on 2.6.32. Please test the current 2.6.32 from unstable/testing and tell
> us whether the problem persists. If so, we should report it upstream
> to the kernel.org developers.

I have tested this again with the "Debian squeeze 20100717-21:32" live
DVD, using kernel 2.6.32-5-486 and I can confirm that the problem
persists. If the module is not available, the same "Cannot allocate
memory" error code is reported.

Attaching the syslog and wpa_supplicant output.

Please let me know if you need further information.

-- 
Marcin Owsiany <porridge@debian.org>             http://marcin.owsiany.pl/
GnuPG: 1024D/60F41216  FE67 DA2D 0ACA FC5E 3F75  D6F6 3A0D 8AA0 60F4 1216
Aug  1 09:56:18 syslogd started: BusyBox v1.15.3
Aug  1 09:56:18 kernel: klogd started: BusyBox v1.15.3 (Debian 1:1.15.3-1)
Aug  1 09:56:18 kernel: [    0.000000] Initializing cgroup subsys cpuset
Aug  1 09:56:18 kernel: [    0.000000] Initializing cgroup subsys cpu
Aug  1 09:56:18 kernel: [    0.000000] Linux version 2.6.32-5-486 (Debian 2.6.32-15) (ben@decadent.org.uk) (gcc version 4.3.5 (Debian 4.3.5-1) ) #1 Tue Jun 1 04:27:25 UTC 2010
Aug  1 09:56:18 kernel: [    0.000000] KERNEL supported cpus:
Aug  1 09:56:18 kernel: [    0.000000]   Intel GenuineIntel
Aug  1 09:56:18 kernel: [    0.000000]   AMD AuthenticAMD
Aug  1 09:56:18 kernel: [    0.000000]   NSC Geode by NSC
Aug  1 09:56:18 kernel: [    0.000000]   Cyrix CyrixInstead
Aug  1 09:56:18 kernel: [    0.000000]   Centaur CentaurHauls
Aug  1 09:56:18 kernel: [    0.000000]   Transmeta GenuineTMx86
Aug  1 09:56:18 kernel: [    0.000000]   Transmeta TransmetaCPU
Aug  1 09:56:18 kernel: [    0.000000]   UMC UMC UMC UMC
Aug  1 09:56:18 kernel: [    0.000000] BIOS-provided physical RAM map:
Aug  1 09:56:18 kernel: [    0.000000]  BIOS-e820: 0000000000000000 - 000000000009fc00 (usable)
Aug  1 09:56:18 kernel: [    0.000000]  BIOS-e820: 000000000009fc00 - 00000000000a0000 (reserved)
Aug  1 09:56:18 kernel: [    0.000000]  BIOS-e820: 00000000000e0000 - 0000000000100000 (reserved)
Aug  1 09:56:18 kernel: [    0.000000]  BIOS-e820: 0000000000100000 - 000000003f7d0000 (usable)
Aug  1 09:56:18 kernel: [    0.000000]  BIOS-e820: 000000003f7d0000 - 000000003f7efc00 (reserved)
Aug  1 09:56:18 kernel: [    0.000000]  BIOS-e820: 000000003f7efc00 - 000000003f7fb000 (ACPI NVS)
Aug  1 09:56:18 kernel: [    0.000000]  BIOS-e820: 000000003f7fb000 - 000000003f800000 (reserved)
Aug  1 09:56:18 kernel: [    0.000000]  BIOS-e820: 00000000e0000000 - 00000000f0000000 (reserved)
Aug  1 09:56:18 kernel: [    0.000000]  BIOS-e820: 00000000fec00000 - 00000000fec02000 (reserved)
Aug  1 09:56:18 kernel: [    0.000000]  BIOS-e820: 00000000fed20000 - 00000000fed9b000 (reserved)
Aug  1 09:56:18 kernel: [    0.000000]  BIOS-e820: 00000000feda0000 - 00000000fedc0000 (reserved)
Aug  1 09:56:18 kernel: [    0.000000]  BIOS-e820: 00000000ffb00000 - 00000000ffc00000 (reserved)
Aug  1 09:56:18 kernel: [    0.000000]  BIOS-e820: 00000000fff00000 - 0000000100000000 (reserved)
Aug  1 09:56:18 kernel: [    0.000000] DMI 2.3 present.
Aug  1 09:56:18 kernel: [    0.000000] last_pfn = 0x3f7d0 max_arch_pfn = 0x100000
Aug  1 09:56:18 kernel: [    0.000000] MTRR default type: uncachable
Aug  1 09:56:18 kernel: [    0.000000] MTRR fixed ranges enabled:
Aug  1 09:56:18 kernel: [    0.000000]   00000-9FFFF write-back
Aug  1 09:56:18 kernel: [    0.000000]   A0000-BFFFF uncachable
Aug  1 09:56:18 kernel: [    0.000000]   C0000-CFFFF write-protect
Aug  1 09:56:18 kernel: [    0.000000]   D0000-EFFFF uncachable
Aug  1 09:56:18 kernel: [    0.000000]   F0000-FFFFF write-protect
Aug  1 09:56:18 kernel: [    0.000000] MTRR variable ranges enabled:
Aug  1 09:56:18 kernel: [    0.000000]   0 base 000000000 mask FC0000000 write-back
Aug  1 09:56:18 kernel: [    0.000000]   1 base 03F800000 mask FFF800000 uncachable
Aug  1 09:56:18 kernel: [    0.000000]   2 base 0FEDA0000 mask FFFFE0000 uncachable
Aug  1 09:56:18 kernel: [    0.000000]   3 disabled
Aug  1 09:56:18 kernel: [    0.000000]   4 disabled
Aug  1 09:56:18 kernel: [    0.000000]   5 disabled
Aug  1 09:56:18 kernel: [    0.000000]   6 disabled
Aug  1 09:56:18 kernel: [    0.000000]   7 disabled
Aug  1 09:56:18 kernel: [    0.000000] PAT not supported by CPU.
Aug  1 09:56:18 kernel: [    0.000000] initial memory mapped : 0 - 01800000
Aug  1 09:56:18 kernel: [    0.000000] init_memory_mapping: 0000000000000000-00000000377fe000
Aug  1 09:56:18 kernel: [    0.000000]  0000000000 - 0000400000 page 4k
Aug  1 09:56:18 kernel: [    0.000000]  0000400000 - 0037400000 page 2M
Aug  1 09:56:18 kernel: [    0.000000]  0037400000 - 00377fe000 page 4k
Aug  1 09:56:18 kernel: [    0.000000] kernel direct mapping tables up to 377fe000 @ 7000-c000
Aug  1 09:56:18 kernel: [    0.000000] RAMDISK: 3f3de000 - 3f7ae1fd
Aug  1 09:56:18 kernel: [    0.000000] Allocated new RAMDISK: 00100000 - 004d01fd
Aug  1 09:56:18 kernel: [    0.000000] Move RAMDISK from 000000003f3de000 - 000000003f7ae1fc to 00100000 - 004d01fc
Aug  1 09:56:18 kernel: [    0.000000] ACPI: RSDP 000fe270 00014 (v00 HP    )
Aug  1 09:56:18 kernel: [    0.000000] ACPI: RSDT 3f7efc84 00030 (v01 HP     099C     21120420 HP   00000001)
Aug  1 09:56:18 kernel: [    0.000000] ACPI: FACP 3f7efc00 00084 (v02 HP     099C     00000002 HP   00000001)
Aug  1 09:56:18 kernel: [    0.000000] ACPI: DSDT 3f7efd4c 0775C (v01 HP       DAU00  00010000 MSFT 0100000E)
Aug  1 09:56:18 kernel: [    0.000000] ACPI: FACS 3f7fae80 00040
Aug  1 09:56:18 kernel: [    0.000000] ACPI: APIC 3f7efcb4 0005A (v01 HP     099C     00000001 HP   00000001)
Aug  1 09:56:18 kernel: [    0.000000] ACPI: MCFG 3f7efd10 0003C (v01 HP     099C     00000001 HP   00000001)
Aug  1 09:56:18 kernel: [    0.000000] ACPI: Local APIC address 0xfec01000
Aug  1 09:56:18 kernel: [    0.000000] 127MB HIGHMEM available.
Aug  1 09:56:18 kernel: [    0.000000] 887MB LOWMEM available.
Aug  1 09:56:18 kernel: [    0.000000]   mapped low ram: 0 - 377fe000
Aug  1 09:56:18 kernel: [    0.000000]   low ram: 0 - 377fe000
Aug  1 09:56:18 kernel: [    0.000000]   node 0 low ram: 00000000 - 377fe000
Aug  1 09:56:18 kernel: [    0.000000]   node 0 bootmap 00008000 - 0000ef00
Aug  1 09:56:18 kernel: [    0.000000] (7 early reservations) ==> bootmem [0000000000 - 00377fe000]
Aug  1 09:56:18 kernel: [    0.000000]   #0 [0000000000 - 0000001000]   BIOS data page ==> [0000000000 - 0000001000]
Aug  1 09:56:18 kernel: [    0.000000]   #1 [0001000000 - 000144d7f0]    TEXT DATA BSS ==> [0001000000 - 000144d7f0]
Aug  1 09:56:18 kernel: [    0.000000]   #2 [000009fc00 - 0000100000]    BIOS reserved ==> [000009fc00 - 0000100000]
Aug  1 09:56:18 kernel: [    0.000000]   #3 [000144e000 - 00014540d4]              BRK ==> [000144e000 - 00014540d4]
Aug  1 09:56:18 kernel: [    0.000000]   #4 [0000007000 - 0000008000]          PGTABLE ==> [0000007000 - 0000008000]
Aug  1 09:56:18 kernel: [    0.000000]   #5 [0000100000 - 00004d01fd]      NEW RAMDISK ==> [0000100000 - 00004d01fd]
Aug  1 09:56:18 kernel: [    0.000000]   #6 [0000008000 - 000000f000]          BOOTMAP ==> [0000008000 - 000000f000]
Aug  1 09:56:18 kernel: [    0.000000] Zone PFN ranges:
Aug  1 09:56:18 kernel: [    0.000000]   DMA      0x00000000 -> 0x00001000
Aug  1 09:56:18 kernel: [    0.000000]   Normal   0x00001000 -> 0x000377fe
Aug  1 09:56:18 kernel: [    0.000000]   HighMem  0x000377fe -> 0x0003f7d0
Aug  1 09:56:18 kernel: [    0.000000] Movable zone start PFN for each node
Aug  1 09:56:18 kernel: [    0.000000] early_node_map[2] active PFN ranges
Aug  1 09:56:18 kernel: [    0.000000]     0: 0x00000000 -> 0x0000009f
Aug  1 09:56:18 kernel: [    0.000000]     0: 0x00000100 -> 0x0003f7d0
Aug  1 09:56:18 kernel: [    0.000000] On node 0 totalpages: 259951
Aug  1 09:56:18 kernel: [    0.000000] free_area_init_node: node 0, pgdat c135c944, node_mem_map c1456000
Aug  1 09:56:18 kernel: [    0.000000]   DMA zone: 32 pages used for memmap
Aug  1 09:56:18 kernel: [    0.000000]   DMA zone: 0 pages reserved
Aug  1 09:56:18 kernel: [    0.000000]   DMA zone: 3967 pages, LIFO batch:0
Aug  1 09:56:18 kernel: [    0.000000]   Normal zone: 1744 pages used for memmap
Aug  1 09:56:18 kernel: [    0.000000]   Normal zone: 221486 pages, LIFO batch:31
Aug  1 09:56:18 kernel: [    0.000000]   HighMem zone: 256 pages used for memmap
Aug  1 09:56:18 kernel: [    0.000000]   HighMem zone: 32466 pages, LIFO batch:7
Aug  1 09:56:18 kernel: [    0.000000] Using APIC driver default
Aug  1 09:56:18 kernel: [    0.000000] ACPI: PM-Timer IO Port: 0x1008
Aug  1 09:56:18 kernel: [    0.000000] ACPI: Local APIC address 0xfec01000
Aug  1 09:56:18 kernel: [    0.000000] ACPI: LAPIC (acpi_id[0x01] lapic_id[0x00] enabled)
Aug  1 09:56:18 kernel: [    0.000000] ACPI: LAPIC_NMI (acpi_id[0x01] high edge lint[0x1])
Aug  1 09:56:18 kernel: [    0.000000] ACPI: IOAPIC (id[0x01] address[0xfec00000] gsi_base[0])
Aug  1 09:56:18 kernel: [    0.000000] IOAPIC[0]: apic_id 1, version 32, address 0xfec00000, GSI 0-23
Aug  1 09:56:18 kernel: [    0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl)
Aug  1 09:56:18 kernel: [    0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level)
Aug  1 09:56:18 kernel: [    0.000000] ACPI: IRQ0 used by override.
Aug  1 09:56:18 kernel: [    0.000000] ACPI: IRQ2 used by override.
Aug  1 09:56:18 kernel: [    0.000000] ACPI: IRQ9 used by override.
Aug  1 09:56:18 kernel: [    0.000000] Using ACPI (MADT) for SMP configuration information
Aug  1 09:56:18 kernel: [    0.000000] nr_irqs_gsi: 24
Aug  1 09:56:18 kernel: [    0.000000] PM: Registered nosave memory: 000000000009f000 - 00000000000a0000
Aug  1 09:56:18 kernel: [    0.000000] PM: Registered nosave memory: 00000000000a0000 - 00000000000e0000
Aug  1 09:56:18 kernel: [    0.000000] PM: Registered nosave memory: 00000000000e0000 - 0000000000100000
Aug  1 09:56:18 kernel: [    0.000000] Allocating PCI resources starting at 3f800000 (gap: 3f800000:a0800000)
Aug  1 09:56:18 kernel: [    0.000000] Booting paravirtualized kernel on bare hardware
Aug  1 09:56:18 kernel: [    0.000000] Built 1 zonelists in Zone order, mobility grouping on.  Total pages: 257919
Aug  1 09:56:18 kernel: [    0.000000] Kernel command line: initrd=/install/initrd.gz priority=low vga=normal cdrom-detect/try-usb=true BOOT_IMAGE=/install/vmlinuz 
Aug  1 09:56:18 kernel: [    0.000000] PID hash table entries: 4096 (order: 2, 16384 bytes)
Aug  1 09:56:18 kernel: [    0.000000] Dentry cache hash table entries: 131072 (order: 7, 524288 bytes)
Aug  1 09:56:18 kernel: [    0.000000] Inode-cache hash table entries: 65536 (order: 6, 262144 bytes)
Aug  1 09:56:18 kernel: [    0.000000] Enabling fast FPU save and restore... done.
Aug  1 09:56:18 kernel: [    0.000000] Enabling unmasked SIMD FPU exception support... done.
Aug  1 09:56:18 kernel: [    0.000000] Initializing CPU#0
Aug  1 09:56:18 kernel: [    0.000000] Initializing HighMem for node 0 (000377fe:0003f7d0)
Aug  1 09:56:18 kernel: [    0.000000] Memory: 1022156k/1040192k available (2327k kernel code, 17292k reserved, 1142k data, 440k init, 130888k highmem)
Aug  1 09:56:18 kernel: [    0.000000] virtual kernel memory layout:
Aug  1 09:56:18 kernel: [    0.000000]     fixmap  : 0xfffa2000 - 0xfffff000   ( 372 kB)
Aug  1 09:56:18 kernel: [    0.000000]     pkmap   : 0xff800000 - 0xffc00000   (4096 kB)
Aug  1 09:56:18 kernel: [    0.000000]     vmalloc : 0xf7ffe000 - 0xff7fe000   ( 120 MB)
Aug  1 09:56:18 kernel: [    0.000000]     lowmem  : 0xc0000000 - 0xf77fe000   ( 887 MB)
Aug  1 09:56:18 kernel: [    0.000000]       .init : 0xc1364000 - 0xc13d2000   ( 440 kB)
Aug  1 09:56:18 kernel: [    0.000000]       .data : 0xc1245e6b - 0xc1363980   (1142 kB)
Aug  1 09:56:18 kernel: [    0.000000]       .text : 0xc1000000 - 0xc1245e6b   (2327 kB)
Aug  1 09:56:18 kernel: [    0.000000] Checking if this processor honours the WP bit even in supervisor mode...Ok.
Aug  1 09:56:18 kernel: [    0.000000] SLUB: Genslabs=13, HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1
Aug  1 09:56:18 kernel: [    0.000000] Hierarchical RCU implementation.
Aug  1 09:56:18 kernel: [    0.000000] NR_IRQS:288
Aug  1 09:56:18 kernel: [    0.000000] Console: colour VGA+ 80x25
Aug  1 09:56:18 kernel: [    0.000000] console [tty0] enabled
Aug  1 09:56:18 kernel: [    0.000000] Fast TSC calibration using PIT
Aug  1 09:56:18 kernel: [    0.000000] Detected 1296.602 MHz processor.
Aug  1 09:56:18 kernel: [    0.004009] Calibrating delay loop (skipped), value calculated using timer frequency.. 2593.20 BogoMIPS (lpj=5186408)
Aug  1 09:56:18 kernel: [    0.004162] Security Framework initialized
Aug  1 09:56:18 kernel: [    0.004238] SELinux:  Disabled at boot.
Aug  1 09:56:18 kernel: [    0.004313] Mount-cache hash table entries: 512
Aug  1 09:56:18 kernel: [    0.004566] Initializing cgroup subsys ns
Aug  1 09:56:18 kernel: [    0.004637] Initializing cgroup subsys cpuacct
Aug  1 09:56:18 kernel: [    0.004705] Initializing cgroup subsys devices
Aug  1 09:56:18 kernel: [    0.004773] Initializing cgroup subsys freezer
Aug  1 09:56:18 kernel: [    0.004839] Initializing cgroup subsys net_cls
Aug  1 09:56:18 kernel: [    0.004939] CPU: L1 I cache: 32K, L1 D cache: 32K
Aug  1 09:56:18 kernel: [    0.005040] CPU: L2 cache: 1024K
Aug  1 09:56:18 kernel: [    0.005114] CPU: Intel(R) Celeron(R) M processor         1.30GHz stepping 06
Aug  1 09:56:18 kernel: [    0.005264] Checking 'hlt' instruction... OK.
Aug  1 09:56:18 kernel: [    0.022598] ACPI: Core revision 20090903
Aug  1 09:56:18 kernel: [    0.040229] Enabling APIC mode:  Flat.  Using 1 I/O APICs
Aug  1 09:56:18 kernel: [    0.040700] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1
Aug  1 09:56:18 kernel: [    0.084001] devtmpfs: initialized
Aug  1 09:56:18 kernel: [    0.084001] regulator: core version 0.5
Aug  1 09:56:18 kernel: [    0.084001] NET: Registered protocol family 16
Aug  1 09:56:18 kernel: [    0.084001] EISA bus registered
Aug  1 09:56:18 kernel: [    0.084001] ACPI: bus type pci registered
Aug  1 09:56:18 kernel: [    0.084001] PCI: MCFG configuration 0: base e0000000 segment 0 buses 0 - 255
Aug  1 09:56:18 kernel: [    0.084001] PCI: MCFG area at e0000000 reserved in E820
Aug  1 09:56:18 kernel: [    0.084001] PCI: Using MMCONFIG for extended config space
Aug  1 09:56:18 kernel: [    0.084001] PCI: Using configuration type 1 for base access
Aug  1 09:56:18 kernel: [    0.084001] bio: create slab <bio-0> at 0
Aug  1 09:56:18 kernel: [    0.084001] ACPI: EC: Look up EC in DSDT
Aug  1 09:56:18 kernel: [    0.112229] ACPI: Interpreter enabled
Aug  1 09:56:18 kernel: [    0.112314] ACPI: (supports S0 S3 S4 S5)
Aug  1 09:56:18 kernel: [    0.112533] ACPI: Using IOAPIC for interrupt routing
Aug  1 09:56:18 kernel: [    0.126582] ACPI: EC: GPE = 0x10, I/O: command/status = 0x66, data = 0x62
Aug  1 09:56:18 kernel: [    0.126736] ACPI: Power Resource [C1BD] (on)
Aug  1 09:56:18 kernel: [    0.126907] ACPI: Power Resource [C24F] (off)
Aug  1 09:56:18 kernel: [    0.127069] ACPI: Power Resource [C250] (off)
Aug  1 09:56:18 kernel: [    0.127230] ACPI: Power Resource [C251] (off)
Aug  1 09:56:18 kernel: [    0.127392] ACPI: Power Resource [C252] (off)
Aug  1 09:56:18 kernel: [    0.127710] ACPI: No dock devices found.
Aug  1 09:56:18 kernel: [    0.134179] ACPI: PCI Root Bridge [C002] (0000:00)
Aug  1 09:56:18 kernel: [    0.134350] pci 0000:00:02.0: reg 10 32bit mmio: [0xd0400000-0xd047ffff]
Aug  1 09:56:18 kernel: [    0.134358] pci 0000:00:02.0: reg 14 io port: [0x7000-0x7007]
Aug  1 09:56:18 kernel: [    0.134364] pci 0000:00:02.0: reg 18 32bit mmio pref: [0xc0000000-0xcfffffff]
Aug  1 09:56:18 kernel: [    0.134372] pci 0000:00:02.0: reg 1c 32bit mmio: [0xd0480000-0xd04bffff]
Aug  1 09:56:18 kernel: [    0.134411] pci 0000:00:02.1: reg 10 32bit mmio: [0xd0500000-0xd057ffff]
Aug  1 09:56:18 kernel: [    0.134529] pci 0000:00:1d.0: reg 20 io port: [0x2000-0x201f]
Aug  1 09:56:18 kernel: [    0.134598] pci 0000:00:1d.1: reg 20 io port: [0x2020-0x203f]
Aug  1 09:56:18 kernel: [    0.134666] pci 0000:00:1d.2: reg 20 io port: [0x2040-0x205f]
Aug  1 09:56:18 kernel: [    0.134734] pci 0000:00:1d.3: reg 20 io port: [0x2060-0x207f]
Aug  1 09:56:18 kernel: [    0.134807] pci 0000:00:1d.7: reg 10 32bit mmio: [0xd0580000-0xd05803ff]
Aug  1 09:56:18 kernel: [    0.134873] pci 0000:00:1d.7: PME# supported from D0 D3hot D3cold
Aug  1 09:56:18 kernel: [    0.134950] pci 0000:00:1d.7: PME# disabled
Aug  1 09:56:18 kernel: [    0.135131] pci 0000:00:1e.2: reg 10 io port: [0x2100-0x21ff]
Aug  1 09:56:18 kernel: [    0.135141] pci 0000:00:1e.2: reg 14 io port: [0x2200-0x223f]
Aug  1 09:56:18 kernel: [    0.135151] pci 0000:00:1e.2: reg 18 32bit mmio: [0xd0581000-0xd05811ff]
Aug  1 09:56:18 kernel: [    0.135161] pci 0000:00:1e.2: reg 1c 32bit mmio: [0xd0582000-0xd05820ff]
Aug  1 09:56:18 kernel: [    0.135203] pci 0000:00:1e.2: PME# supported from D0 D3hot D3cold
Aug  1 09:56:18 kernel: [    0.135277] pci 0000:00:1e.2: PME# disabled
Aug  1 09:56:18 kernel: [    0.135382] pci 0000:00:1e.3: reg 10 io port: [0x2400-0x24ff]
Aug  1 09:56:18 kernel: [    0.135392] pci 0000:00:1e.3: reg 14 io port: [0x2500-0x257f]
Aug  1 09:56:18 kernel: [    0.135445] pci 0000:00:1e.3: PME# supported from D0 D3hot D3cold
Aug  1 09:56:18 kernel: [    0.135519] pci 0000:00:1e.3: PME# disabled
Aug  1 09:56:18 kernel: [    0.135679] pci 0000:00:1f.0: Force enabled HPET at 0xfed00000
Aug  1 09:56:18 kernel: [    0.135688] pci 0000:00:1f.0: quirk: region 1000-107f claimed by ICH6 ACPI/GPIO/TCO
Aug  1 09:56:18 kernel: [    0.135787] pci 0000:00:1f.0: quirk: region 1100-113f claimed by ICH6 GPIO
Aug  1 09:56:18 kernel: [    0.135863] pci 0000:00:1f.0: LPC Generic IO decode 1 PIO at 0500-057f
Aug  1 09:56:18 kernel: [    0.135970] pci 0000:00:1f.1: reg 10 io port: [0x00-0x07]
Aug  1 09:56:18 kernel: [    0.135979] pci 0000:00:1f.1: reg 14 io port: [0x00-0x03]
Aug  1 09:56:18 kernel: [    0.135989] pci 0000:00:1f.1: reg 18 io port: [0x00-0x07]
Aug  1 09:56:18 kernel: [    0.136004] pci 0000:00:1f.1: reg 1c io port: [0x00-0x03]
Aug  1 09:56:18 kernel: [    0.136014] pci 0000:00:1f.1: reg 20 io port: [0x2580-0x258f]
Aug  1 09:56:18 kernel: [    0.136110] pci 0000:02:06.0: reg 10 32bit mmio: [0xd0000000-0xd0000fff]
Aug  1 09:56:18 kernel: [    0.136140] pci 0000:02:06.0: supports D1 D2
Aug  1 09:56:18 kernel: [    0.136143] pci 0000:02:06.0: PME# supported from D0 D1 D2 D3hot D3cold
Aug  1 09:56:18 kernel: [    0.136220] pci 0000:02:06.0: PME# disabled
Aug  1 09:56:18 kernel: [    0.136337] pci 0000:02:06.2: reg 10 32bit mmio: [0xd0001000-0xd00017ff]
Aug  1 09:56:18 kernel: [    0.136349] pci 0000:02:06.2: reg 14 32bit mmio: [0xd0004000-0xd0007fff]
Aug  1 09:56:18 kernel: [    0.136418] pci 0000:02:06.2: supports D1 D2
Aug  1 09:56:18 kernel: [    0.136421] pci 0000:02:06.2: PME# supported from D0 D1 D2 D3hot
Aug  1 09:56:18 kernel: [    0.136497] pci 0000:02:06.2: PME# disabled
Aug  1 09:56:18 kernel: [    0.136613] pci 0000:02:06.3: reg 10 32bit mmio: [0xd0008000-0xd0009fff]
Aug  1 09:56:18 kernel: [    0.136684] pci 0000:02:06.3: supports D1 D2
Aug  1 09:56:18 kernel: [    0.136688] pci 0000:02:06.3: PME# supported from D0 D1 D2 D3hot
Aug  1 09:56:18 kernel: [    0.136763] pci 0000:02:06.3: PME# disabled
Aug  1 09:56:18 kernel: [    0.136878] pci 0000:02:06.4: reg 10 32bit mmio: [0xd000a000-0xd000a0ff]
Aug  1 09:56:18 kernel: [    0.136890] pci 0000:02:06.4: reg 14 32bit mmio: [0xd000b000-0xd000b0ff]
Aug  1 09:56:18 kernel: [    0.136901] pci 0000:02:06.4: reg 18 32bit mmio: [0xd000c000-0xd000c0ff]
Aug  1 09:56:18 kernel: [    0.136959] pci 0000:02:06.4: supports D1 D2
Aug  1 09:56:18 kernel: [    0.136963] pci 0000:02:06.4: PME# supported from D0 D1 D2 D3hot
Aug  1 09:56:18 kernel: [    0.137036] pci 0000:02:06.4: PME# disabled
Aug  1 09:56:18 kernel: [    0.137171] pci 0000:02:0e.0: reg 10 32bit mmio: [0xd000e000-0xd000ffff]
Aug  1 09:56:18 kernel: [    0.137231] pci 0000:02:0e.0: supports D1 D2
Aug  1 09:56:18 kernel: [    0.137234] pci 0000:02:0e.0: PME# supported from D0 D1 D2 D3hot D3cold
Aug  1 09:56:18 kernel: [    0.137310] pci 0000:02:0e.0: PME# disabled
Aug  1 09:56:18 kernel: [    0.137421] pci 0000:00:1e.0: transparent bridge
Aug  1 09:56:18 kernel: [    0.137494] pci 0000:00:1e.0: bridge 32bit mmio: [0xd0000000-0xd03fffff]
Aug  1 09:56:18 kernel: [    0.137535] pci_bus 0000:00: on NUMA node 0
Aug  1 09:56:18 kernel: [    0.137544] ACPI: PCI Interrupt Routing Table [\_SB_.C002._PRT]
Aug  1 09:56:18 kernel: [    0.137816] ACPI: PCI Interrupt Routing Table [\_SB_.C002.C067._PRT]
Aug  1 09:56:18 kernel: [    0.172585] ACPI: PCI Interrupt Link [C0D7] (IRQs 10 *11)
Aug  1 09:56:18 kernel: [    0.173057] ACPI: PCI Interrupt Link [C0D8] (IRQs *10 11)
Aug  1 09:56:18 kernel: [    0.173525] ACPI: PCI Interrupt Link [C0D9] (IRQs *10 11)
Aug  1 09:56:18 kernel: [    0.173986] ACPI: PCI Interrupt Link [C0DA] (IRQs *10 11)
Aug  1 09:56:18 kernel: [    0.174450] ACPI: PCI Interrupt Link [C0ED] (IRQs *10 11)
Aug  1 09:56:18 kernel: [    0.174904] ACPI: PCI Interrupt Link [C0EE] (IRQs 10 11) *0, disabled.
Aug  1 09:56:18 kernel: [    0.175438] ACPI: PCI Interrupt Link [C0EF] (IRQs *10 11)
Aug  1 09:56:18 kernel: [    0.175881] ACPI: PCI Interrupt Link [C0F0] (IRQs 10 11) *0, disabled.
Aug  1 09:56:18 kernel: [    0.176352] vgaarb: device added: PCI:0000:00:02.0,decodes=io+mem,owns=io+mem,locks=none
Aug  1 09:56:18 kernel: [    0.176459] vgaarb: loaded
Aug  1 09:56:18 kernel: [    0.176601] PCI: Using ACPI for IRQ routing
Aug  1 09:56:18 kernel: [    0.177033] hpet clockevent registered
Aug  1 09:56:18 kernel: [    0.177038] HPET: 3 timers in total, 0 timers will be used for per-cpu timer
Aug  1 09:56:18 kernel: [    0.177117] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0
Aug  1 09:56:18 kernel: [    0.177321] hpet0: 3 comparators, 64-bit 14.318180 MHz counter
Aug  1 09:56:18 kernel: [    0.180020] Switching to clocksource tsc
Aug  1 09:56:18 kernel: [    0.181756] pnp: PnP ACPI init
Aug  1 09:56:18 kernel: [    0.181836] ACPI: bus type pnp registered
Aug  1 09:56:18 kernel: [    0.193028] pnp: PnP ACPI: found 11 devices
Aug  1 09:56:18 kernel: [    0.193095] ACPI: ACPI bus type pnp unregistered
Aug  1 09:56:18 kernel: [    0.194367] PnPBIOS: Disabled by ACPI PNP
Aug  1 09:56:18 kernel: [    0.194446] system 00:00: iomem range 0x0-0x9ffff could not be reserved
Aug  1 09:56:18 kernel: [    0.194521] system 00:00: iomem range 0xe0000-0xfffff could not be reserved
Aug  1 09:56:18 kernel: [    0.194597] system 00:00: iomem range 0x100000-0x3f7fffff could not be reserved
Aug  1 09:56:18 kernel: [    0.194700] system 00:08: ioport range 0x500-0x57f has been reserved
Aug  1 09:56:18 kernel: [    0.194775] system 00:08: iomem range 0xffb00000-0xffbfffff has been reserved
Aug  1 09:56:18 kernel: [    0.194851] system 00:08: iomem range 0xfff00000-0xffffffff has been reserved
Aug  1 09:56:18 kernel: [    0.194930] system 00:09: ioport range 0x4d0-0x4d1 has been reserved
Aug  1 09:56:18 kernel: [    0.195004] system 00:09: ioport range 0x1000-0x107f has been reserved
Aug  1 09:56:18 kernel: [    0.195079] system 00:09: ioport range 0x1100-0x113f has been reserved
Aug  1 09:56:18 kernel: [    0.195153] system 00:09: ioport range 0x1200-0x121f has been reserved
Aug  1 09:56:18 kernel: [    0.195227] system 00:09: iomem range 0xe0000000-0xefffffff has been reserved
Aug  1 09:56:18 kernel: [    0.195304] system 00:09: iomem range 0xfec00000-0xfec000ff could not be reserved
Aug  1 09:56:18 kernel: [    0.195401] system 00:09: iomem range 0xfed20000-0xff41ffff could not be reserved
Aug  1 09:56:18 kernel: [    0.195498] system 00:09: iomem range 0xfed90000-0xfed9afff has been reserved
Aug  1 09:56:18 kernel: [    0.195577] system 00:0a: iomem range 0xfeda0000-0xfedbffff has been reserved
Aug  1 09:56:18 kernel: [    0.195654] system 00:0a: iomem range 0xfec01000-0xfec01fff has been reserved
Aug  1 09:56:18 kernel: [    0.230546] pci 0000:02:06.0: CardBus bridge, secondary bus 0000:03
Aug  1 09:56:18 kernel: [    0.230620] pci 0000:02:06.0:   IO window: 0x003000-0x0030ff
Aug  1 09:56:18 kernel: [    0.230695] pci 0000:02:06.0:   IO window: 0x003400-0x0034ff
Aug  1 09:56:18 kernel: [    0.230769] pci 0000:02:06.0:   PREFETCH window: 0x40000000-0x43ffffff
Aug  1 09:56:18 kernel: [    0.230846] pci 0000:02:06.0:   MEM window: 0x44000000-0x47ffffff
Aug  1 09:56:18 kernel: [    0.230922] pci 0000:00:1e.0: PCI bridge, secondary bus 0000:02
Aug  1 09:56:18 kernel: [    0.230995] pci 0000:00:1e.0:   IO window: 0x3000-0x3fff
Aug  1 09:56:18 kernel: [    0.231069] pci 0000:00:1e.0:   MEM window: 0xd0000000-0xd03fffff
Aug  1 09:56:18 kernel: [    0.231144] pci 0000:00:1e.0:   PREFETCH window: 0x40000000-0x43ffffff
Aug  1 09:56:18 kernel: [    0.231233] pci 0000:00:1e.0: setting latency timer to 64
Aug  1 09:56:18 kernel: [    0.231253] pci 0000:02:06.0: PCI INT A -> GSI 18 (level, low) -> IRQ 18
Aug  1 09:56:18 kernel: [    0.231332] pci_bus 0000:00: resource 0 io:  [0x00-0xffff]
Aug  1 09:56:18 kernel: [    0.231336] pci_bus 0000:00: resource 1 mem: [0x000000-0xffffffff]
Aug  1 09:56:18 kernel: [    0.231341] pci_bus 0000:02: resource 0 io:  [0x3000-0x3fff]
Aug  1 09:56:18 kernel: [    0.231345] pci_bus 0000:02: resource 1 mem: [0xd0000000-0xd03fffff]
Aug  1 09:56:18 kernel: [    0.231349] pci_bus 0000:02: resource 2 pref mem [0x40000000-0x43ffffff]
Aug  1 09:56:18 kernel: [    0.231354] pci_bus 0000:02: resource 3 io:  [0x00-0xffff]
Aug  1 09:56:18 kernel: [    0.231358] pci_bus 0000:02: resource 4 mem: [0x000000-0xffffffff]
Aug  1 09:56:18 kernel: [    0.231362] pci_bus 0000:03: resource 0 io:  [0x3000-0x30ff]
Aug  1 09:56:18 kernel: [    0.231366] pci_bus 0000:03: resource 1 io:  [0x3400-0x34ff]
Aug  1 09:56:18 kernel: [    0.231370] pci_bus 0000:03: resource 2 pref mem [0x40000000-0x43ffffff]
Aug  1 09:56:18 kernel: [    0.231375] pci_bus 0000:03: resource 3 mem: [0x44000000-0x47ffffff]
Aug  1 09:56:18 kernel: [    0.231415] NET: Registered protocol family 2
Aug  1 09:56:18 kernel: [    0.231586] IP route cache hash table entries: 32768 (order: 5, 131072 bytes)
Aug  1 09:56:18 kernel: [    0.232043] TCP established hash table entries: 131072 (order: 8, 1048576 bytes)
Aug  1 09:56:18 kernel: [    0.233441] TCP bind hash table entries: 65536 (order: 6, 262144 bytes)
Aug  1 09:56:18 kernel: [    0.233954] TCP: Hash tables configured (established 131072 bind 65536)
Aug  1 09:56:18 kernel: [    0.234030] TCP reno registered
Aug  1 09:56:18 kernel: [    0.234243] NET: Registered protocol family 1
Aug  1 09:56:18 kernel: [    0.234341] pci 0000:00:02.0: Boot video device
Aug  1 09:56:18 kernel: [    0.234543] Unpacking initramfs...
Aug  1 09:56:18 kernel: [    0.422110] Freeing initrd memory: 3904k freed
Aug  1 09:56:18 kernel: [    0.426812] audit: initializing netlink socket (disabled)
Aug  1 09:56:18 kernel: [    0.426906] type=2000 audit(1280656560.423:1): initialized
Aug  1 09:56:18 kernel: [    0.427233] highmem bounce pool size: 64 pages
Aug  1 09:56:18 kernel: [    0.427309] HugeTLB registered 4 MB page size, pre-allocated 0 pages
Aug  1 09:56:18 kernel: [    0.429511] VFS: Disk quotas dquot_6.5.2
Aug  1 09:56:18 kernel: [    0.429658] Dquot-cache hash table entries: 1024 (order 0, 4096 bytes)
Aug  1 09:56:18 kernel: [    0.429834] msgmni has been set to 1749
Aug  1 09:56:18 kernel: [    0.430159] alg: No test for stdrng (krng)
Aug  1 09:56:18 kernel: [    0.430307] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 253)
Aug  1 09:56:18 kernel: [    0.430403] io scheduler noop registered
Aug  1 09:56:18 kernel: [    0.430468] io scheduler anticipatory registered
Aug  1 09:56:18 kernel: [    0.430535] io scheduler deadline registered
Aug  1 09:56:18 kernel: [    0.430651] io scheduler cfq registered (default)
Aug  1 09:56:18 kernel: [    0.430953] isapnp: Scanning for PnP cards...
Aug  1 09:56:18 kernel: [    0.784946] isapnp: No Plug & Play device found
Aug  1 09:56:18 kernel: [    0.786830] Linux agpgart interface v0.103
Aug  1 09:56:18 kernel: [    0.786990] agpgart-intel 0000:00:00.0: Intel 915GM Chipset
Aug  1 09:56:18 kernel: [    0.787692] agpgart-intel 0000:00:00.0: detected 7932K stolen memory
Aug  1 09:56:18 kernel: [    0.791204] agpgart-intel 0000:00:00.0: AGP aperture is 256M @ 0xc0000000
Aug  1 09:56:18 kernel: [    0.791382] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled
Aug  1 09:56:18 kernel: [    0.791910] serial 0000:00:1e.3: PCI INT B -> GSI 22 (level, low) -> IRQ 22
Aug  1 09:56:18 kernel: [    0.791990] serial 0000:00:1e.3: PCI INT B disabled
Aug  1 09:56:18 kernel: [    0.792190] PNP: PS/2 Controller [PNP0303:C1BA,PNP0f13:C1BB] at 0x60,0x64 irq 1,12
Aug  1 09:56:18 kernel: [    0.793922] i8042.c: Detected active multiplexing controller, rev 1.1.
Aug  1 09:56:18 kernel: [    0.794696] serio: i8042 KBD port at 0x60,0x64 irq 1
Aug  1 09:56:18 kernel: [    0.794770] serio: i8042 AUX0 port at 0x60,0x64 irq 12
Aug  1 09:56:18 kernel: [    0.794873] serio: i8042 AUX1 port at 0x60,0x64 irq 12
Aug  1 09:56:18 kernel: [    0.794969] serio: i8042 AUX2 port at 0x60,0x64 irq 12
Aug  1 09:56:18 kernel: [    0.795063] serio: i8042 AUX3 port at 0x60,0x64 irq 12
Aug  1 09:56:18 kernel: [    0.795257] mice: PS/2 mouse device common for all mice
Aug  1 09:56:18 kernel: [    0.795396] rtc_cmos 00:05: RTC can wake from S4
Aug  1 09:56:18 kernel: [    0.795513] rtc_cmos 00:05: rtc core: registered rtc_cmos as rtc0
Aug  1 09:56:18 kernel: [    0.795612] rtc0: alarms up to one month, y3k, 114 bytes nvram, hpet irqs
Aug  1 09:56:18 kernel: [    0.795730] EISA: Probing bus 0 at eisa.0
Aug  1 09:56:18 kernel: [    0.795801] Cannot allocate resource for EISA slot 1
Aug  1 09:56:18 kernel: [    0.795870] Cannot allocate resource for EISA slot 2
Aug  1 09:56:18 kernel: [    0.795939] Cannot allocate resource for EISA slot 3
Aug  1 09:56:18 kernel: [    0.796029] Cannot allocate resource for EISA slot 7
Aug  1 09:56:18 kernel: [    0.796114] EISA: Detected 0 cards.
Aug  1 09:56:18 kernel: [    0.796178] cpuidle: using governor ladder
Aug  1 09:56:18 kernel: [    0.796244] cpuidle: using governor menu
Aug  1 09:56:18 kernel: [    0.796315] No iBFT detected.
Aug  1 09:56:18 kernel: [    0.796888] TCP cubic registered
Aug  1 09:56:18 kernel: [    0.797150] NET: Registered protocol family 10
Aug  1 09:56:18 kernel: [    0.797754] lo: Disabled Privacy Extensions
Aug  1 09:56:18 kernel: [    0.798204] Mobile IPv6
Aug  1 09:56:18 kernel: [    0.798267] NET: Registered protocol family 17
Aug  1 09:56:18 kernel: [    0.798340] Using IPI Shortcut mode
Aug  1 09:56:18 kernel: [    0.798497] PM: Resume from disk failed.
Aug  1 09:56:18 kernel: [    0.798507] registered taskstats version 1
Aug  1 09:56:18 kernel: [    0.798922] rtc_cmos 00:05: setting system clock to 2010-08-01 09:56:01 UTC (1280656561)
Aug  1 09:56:18 kernel: [    0.799076] Initalizing network drop monitor service
Aug  1 09:56:18 kernel: [    0.799187] Freeing unused kernel memory: 440k freed
Aug  1 09:56:18 kernel: [    0.799522] Write protecting the kernel text: 2328k
Aug  1 09:56:18 kernel: [    0.799613] Write protecting the kernel read-only data: 896k
Aug  1 09:56:18 kernel: [    0.818319] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0
Aug  1 09:56:18 kernel: [    1.608915] usbcore: registered new interface driver usbfs
Aug  1 09:56:18 kernel: [    1.609185] usbcore: registered new interface driver hub
Aug  1 09:56:18 kernel: [    1.609457] usbcore: registered new device driver usb
Aug  1 09:56:18 kernel: [    1.622692] SCSI subsystem initialized
Aug  1 09:56:18 kernel: [    1.626676] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
Aug  1 09:56:18 kernel: [    1.626807] ehci_hcd 0000:00:1d.7: PCI INT A -> GSI 23 (level, low) -> IRQ 23
Aug  1 09:56:18 kernel: [    1.626903] ehci_hcd 0000:00:1d.7: setting latency timer to 64
Aug  1 09:56:18 kernel: [    1.626909] ehci_hcd 0000:00:1d.7: EHCI Host Controller
Aug  1 09:56:18 kernel: [    1.627010] ehci_hcd 0000:00:1d.7: new USB bus registered, assigned bus number 1
Aug  1 09:56:18 kernel: [    1.627140] ehci_hcd 0000:00:1d.7: debug port 1
Aug  1 09:56:18 kernel: [    1.631087] ehci_hcd 0000:00:1d.7: cache line size of 32 is not supported
Aug  1 09:56:18 kernel: [    1.631109] ehci_hcd 0000:00:1d.7: irq 23, io mem 0xd0580000
Aug  1 09:56:18 kernel: [    1.684614] ehci_hcd 0000:00:1d.7: USB 2.0 started, EHCI 1.00
Aug  1 09:56:18 kernel: [    1.684753] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002
Aug  1 09:56:18 kernel: [    1.684828] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
Aug  1 09:56:18 kernel: [    1.684923] usb usb1: Product: EHCI Host Controller
Aug  1 09:56:18 kernel: [    1.684991] usb usb1: Manufacturer: Linux 2.6.32-5-486 ehci_hcd
Aug  1 09:56:18 kernel: [    1.685063] usb usb1: SerialNumber: 0000:00:1d.7
Aug  1 09:56:18 kernel: [    1.685383] usb usb1: configuration #1 chosen from 1 choice
Aug  1 09:56:18 kernel: [    1.685548] hub 1-0:1.0: USB hub found
Aug  1 09:56:18 kernel: [    1.685625] hub 1-0:1.0: 8 ports detected
Aug  1 09:56:18 kernel: [    1.700062] firewire_ohci 0000:02:06.2: PCI INT C -> GSI 22 (level, low) -> IRQ 22
Aug  1 09:56:18 kernel: [    1.703603] uhci_hcd: USB Universal Host Controller Interface driver
Aug  1 09:56:18 kernel: [    1.703747] uhci_hcd 0000:00:1d.0: PCI INT A -> GSI 23 (level, low) -> IRQ 23
Aug  1 09:56:18 kernel: [    1.703830] uhci_hcd 0000:00:1d.0: setting latency timer to 64
Aug  1 09:56:18 kernel: [    1.703835] uhci_hcd 0000:00:1d.0: UHCI Host Controller
Aug  1 09:56:18 kernel: [    1.703919] uhci_hcd 0000:00:1d.0: new USB bus registered, assigned bus number 2
Aug  1 09:56:18 kernel: [    1.704062] uhci_hcd 0000:00:1d.0: irq 23, io base 0x00002000
Aug  1 09:56:18 kernel: [    1.704188] usb usb2: New USB device found, idVendor=1d6b, idProduct=0001
Aug  1 09:56:18 kernel: [    1.704262] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1
Aug  1 09:56:18 kernel: [    1.704358] usb usb2: Product: UHCI Host Controller
Aug  1 09:56:18 kernel: [    1.704426] usb usb2: Manufacturer: Linux 2.6.32-5-486 uhci_hcd
Aug  1 09:56:18 kernel: [    1.704497] usb usb2: SerialNumber: 0000:00:1d.0
Aug  1 09:56:18 kernel: [    1.704734] usb usb2: configuration #1 chosen from 1 choice
Aug  1 09:56:18 kernel: [    1.704896] hub 2-0:1.0: USB hub found
Aug  1 09:56:18 kernel: [    1.704971] hub 2-0:1.0: 2 ports detected
Aug  1 09:56:18 kernel: [    1.705106] uhci_hcd 0000:00:1d.1: PCI INT B -> GSI 17 (level, low) -> IRQ 17
Aug  1 09:56:18 kernel: [    1.705187] uhci_hcd 0000:00:1d.1: setting latency timer to 64
Aug  1 09:56:18 kernel: [    1.705192] uhci_hcd 0000:00:1d.1: UHCI Host Controller
Aug  1 09:56:18 kernel: [    1.705270] uhci_hcd 0000:00:1d.1: new USB bus registered, assigned bus number 3
Aug  1 09:56:18 kernel: [    1.705398] uhci_hcd 0000:00:1d.1: irq 17, io base 0x00002020
Aug  1 09:56:18 kernel: [    1.705508] usb usb3: New USB device found, idVendor=1d6b, idProduct=0001
Aug  1 09:56:18 kernel: [    1.705583] usb usb3: New USB device strings: Mfr=3, Product=2, SerialNumber=1
Aug  1 09:56:18 kernel: [    1.705678] usb usb3: Product: UHCI Host Controller
Aug  1 09:56:18 kernel: [    1.705746] usb usb3: Manufacturer: Linux 2.6.32-5-486 uhci_hcd
Aug  1 09:56:18 kernel: [    1.705817] usb usb3: SerialNumber: 0000:00:1d.1
Aug  1 09:56:18 kernel: [    1.706063] libata version 3.00 loaded.
Aug  1 09:56:18 kernel: [    1.713102] usb usb3: configuration #1 chosen from 1 choice
Aug  1 09:56:18 kernel: [    1.713305] hub 3-0:1.0: USB hub found
Aug  1 09:56:18 kernel: [    1.713381] hub 3-0:1.0: 2 ports detected
Aug  1 09:56:18 kernel: [    1.713521] uhci_hcd 0000:00:1d.2: PCI INT C -> GSI 18 (level, low) -> IRQ 18
Aug  1 09:56:18 kernel: [    1.713604] uhci_hcd 0000:00:1d.2: setting latency timer to 64
Aug  1 09:56:18 kernel: [    1.713609] uhci_hcd 0000:00:1d.2: UHCI Host Controller
Aug  1 09:56:18 kernel: [    1.713688] uhci_hcd 0000:00:1d.2: new USB bus registered, assigned bus number 4
Aug  1 09:56:18 kernel: [    1.713826] uhci_hcd 0000:00:1d.2: irq 18, io base 0x00002040
Aug  1 09:56:18 kernel: [    1.713939] usb usb4: New USB device found, idVendor=1d6b, idProduct=0001
Aug  1 09:56:18 kernel: [    1.714014] usb usb4: New USB device strings: Mfr=3, Product=2, SerialNumber=1
Aug  1 09:56:18 kernel: [    1.714109] usb usb4: Product: UHCI Host Controller
Aug  1 09:56:18 kernel: [    1.714176] usb usb4: Manufacturer: Linux 2.6.32-5-486 uhci_hcd
Aug  1 09:56:18 kernel: [    1.714248] usb usb4: SerialNumber: 0000:00:1d.2
Aug  1 09:56:18 kernel: [    1.719780] usb usb4: configuration #1 chosen from 1 choice
Aug  1 09:56:18 kernel: [    1.719945] hub 4-0:1.0: USB hub found
Aug  1 09:56:18 kernel: [    1.720033] hub 4-0:1.0: 2 ports detected
Aug  1 09:56:18 kernel: [    1.720169] uhci_hcd 0000:00:1d.3: PCI INT D -> GSI 19 (level, low) -> IRQ 19
Aug  1 09:56:18 kernel: [    1.720251] uhci_hcd 0000:00:1d.3: setting latency timer to 64
Aug  1 09:56:18 kernel: [    1.720256] uhci_hcd 0000:00:1d.3: UHCI Host Controller
Aug  1 09:56:18 kernel: [    1.720333] uhci_hcd 0000:00:1d.3: new USB bus registered, assigned bus number 5
Aug  1 09:56:18 kernel: [    1.720461] uhci_hcd 0000:00:1d.3: irq 19, io base 0x00002060
Aug  1 09:56:18 kernel: [    1.720577] usb usb5: New USB device found, idVendor=1d6b, idProduct=0001
Aug  1 09:56:18 kernel: [    1.720651] usb usb5: New USB device strings: Mfr=3, Product=2, SerialNumber=1
Aug  1 09:56:18 kernel: [    1.720746] usb usb5: Product: UHCI Host Controller
Aug  1 09:56:18 kernel: [    1.720814] usb usb5: Manufacturer: Linux 2.6.32-5-486 uhci_hcd
Aug  1 09:56:18 kernel: [    1.720885] usb usb5: SerialNumber: 0000:00:1d.3
Aug  1 09:56:18 kernel: [    1.721318] usb usb5: configuration #1 chosen from 1 choice
Aug  1 09:56:18 kernel: [    1.721478] hub 5-0:1.0: USB hub found
Aug  1 09:56:18 kernel: [    1.721552] hub 5-0:1.0: 2 ports detected
Aug  1 09:56:18 kernel: [    1.721852] ata_piix 0000:00:1f.1: version 2.13
Aug  1 09:56:18 kernel: [    1.721871] ata_piix 0000:00:1f.1: PCI INT A -> GSI 16 (level, low) -> IRQ 16
Aug  1 09:56:18 kernel: [    1.723218] ata_piix 0000:00:1f.1: setting latency timer to 64
Aug  1 09:56:18 kernel: [    1.732046] scsi0 : ata_piix
Aug  1 09:56:18 kernel: [    1.732418] scsi1 : ata_piix
Aug  1 09:56:18 kernel: [    1.733398] ata1: PATA max UDMA/100 cmd 0x1f0 ctl 0x3f6 bmdma 0x2580 irq 14
Aug  1 09:56:18 kernel: [    1.733475] ata2: PATA max UDMA/100 cmd 0x170 ctl 0x376 bmdma 0x2588 irq 15
Aug  1 09:56:18 kernel: [    1.736065] ata2: port disabled. ignoring.
Aug  1 09:56:18 kernel: [    1.742193] thermal LNXTHERM:01: registered as thermal_zone0
Aug  1 09:56:18 kernel: [    1.742279] ACPI: Thermal Zone [TZ1] (71 C)
Aug  1 09:56:18 kernel: [    1.760390] thermal LNXTHERM:02: registered as thermal_zone1
Aug  1 09:56:18 kernel: [    1.760474] ACPI: Thermal Zone [TZ2] (49 C)
Aug  1 09:56:18 kernel: [    1.769344] thermal LNXTHERM:03: registered as thermal_zone2
Aug  1 09:56:18 kernel: [    1.769428] ACPI: Thermal Zone [TZ3] (27 C)
Aug  1 09:56:18 kernel: [    1.772013] firewire_ohci: Added fw-ohci device 0000:02:06.2, OHCI version 1.10
Aug  1 09:56:18 kernel: [    1.772219] yenta_cardbus 0000:02:06.0: CardBus bridge found [103c:099c]
Aug  1 09:56:18 kernel: [    1.772317] yenta_cardbus 0000:02:06.0: Enabling burst memory read transactions
Aug  1 09:56:18 kernel: [    1.772416] yenta_cardbus 0000:02:06.0: Using INTVAL to route CSC interrupts to PCI
Aug  1 09:56:18 kernel: [    1.772512] yenta_cardbus 0000:02:06.0: Routing CardBus interrupts to PCI
Aug  1 09:56:18 kernel: [    1.772590] yenta_cardbus 0000:02:06.0: TI: mfunc 0x01aa1b22, devctl 0x64
Aug  1 09:56:18 kernel: [    1.996020] usb 1-2: new high speed USB device using ehci_hcd and address 2
Aug  1 09:56:18 kernel: [    2.016471] ata1.00: ATA-6: TOSHIBA MK4026GAX, PA103G, max UDMA/100
Aug  1 09:56:18 kernel: [    2.016545] ata1.00: 78140160 sectors, multi 16: LBA 
Aug  1 09:56:18 kernel: [    2.016669] ata1.01: ATAPI: SONY CD-RW/DVD-ROM CRX835E, KPK4, max MWDMA2
Aug  1 09:56:18 kernel: [    2.024382] ata1.00: configured for UDMA/100
Aug  1 09:56:18 kernel: [    2.040286] ata1.01: configured for MWDMA2
Aug  1 09:56:18 kernel: [    2.040791] scsi 0:0:0:0: Direct-Access     ATA      TOSHIBA MK4026GA PA10 PQ: 0 ANSI: 5
Aug  1 09:56:18 kernel: [    2.041834] scsi 0:0:1:0: CD-ROM            SONY     CD-RW  CRX835E   KPK4 PQ: 0 ANSI: 5
Aug  1 09:56:18 kernel: [    2.110436] fan PNP0C0B:00: registered as cooling_device0
Aug  1 09:56:18 kernel: [    2.110519] ACPI: Fan [C253] (off)
Aug  1 09:56:18 kernel: [    2.111011] fan PNP0C0B:01: registered as cooling_device1
Aug  1 09:56:18 kernel: [    2.111089] ACPI: Fan [C254] (off)
Aug  1 09:56:18 kernel: [    2.111564] fan PNP0C0B:02: registered as cooling_device2
Aug  1 09:56:18 kernel: [    2.111643] ACPI: Fan [C255] (off)
Aug  1 09:56:18 kernel: [    2.112141] fan PNP0C0B:03: registered as cooling_device3
Aug  1 09:56:18 kernel: [    2.112217] ACPI: Fan [C256] (off)
Aug  1 09:56:18 kernel: [    2.121045] sd 0:0:0:0: [sda] 78140160 512-byte logical blocks: (40.0 GB/37.2 GiB)
Aug  1 09:56:18 kernel: [    2.121215] sd 0:0:0:0: [sda] Write Protect is off
Aug  1 09:56:18 kernel: [    2.121285] sd 0:0:0:0: [sda] Mode Sense: 00 3a 00 00
Aug  1 09:56:18 kernel: [    2.121316] sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
Aug  1 09:56:18 kernel: [    2.121601]  sda:
Aug  1 09:56:18 kernel: [    2.128662] usb 1-2: New USB device found, idVendor=1a40, idProduct=0101
Aug  1 09:56:18 kernel: [    2.128794] usb 1-2: New USB device strings: Mfr=0, Product=1, SerialNumber=0
Aug  1 09:56:18 kernel: [    2.128870] usb 1-2: Product: USB 2.0 Hub
Aug  1 09:56:18 kernel: [    2.129045] usb 1-2: configuration #1 chosen from 1 choice
Aug  1 09:56:18 kernel: [    2.129722] hub 1-2:1.0: USB hub found
Aug  1 09:56:18 kernel: [    2.129914] hub 1-2:1.0: 4 ports detected
Aug  1 09:56:18 kernel: [    2.168807] yenta_cardbus 0000:02:06.0: ISA IRQ mask 0x0cf8, PCI irq 18
Aug  1 09:56:18 kernel: [    2.168886] yenta_cardbus 0000:02:06.0: Socket status: 30000820
Aug  1 09:56:18 kernel: [    2.168960] pci_bus 0000:02: Raising subordinate bus# of parent bus (#02) from #03 to #06
Aug  1 09:56:18 kernel: [    2.169066] yenta_cardbus 0000:02:06.0: pcmcia: parent PCI bridge I/O window: 0x3000 - 0x3fff
Aug  1 09:56:18 kernel: [    2.169165] pcmcia_socket pcmcia_socket0: cs: IO port probe 0x3000-0x3fff: clean.
Aug  1 09:56:18 kernel: [    2.169641] yenta_cardbus 0000:02:06.0: pcmcia: parent PCI bridge Memory window: 0xd0000000 - 0xd03fffff
Aug  1 09:56:18 kernel: [    2.169743] yenta_cardbus 0000:02:06.0: pcmcia: parent PCI bridge Memory window: 0x40000000 - 0x43ffffff
Aug  1 09:56:18 kernel: [    2.179428]  sda1 sda2
Aug  1 09:56:18 kernel: [    2.181294] sr0: scsi3-mmc drive: 15x/47x writer cd/rw xa/form2 cdda tray
Aug  1 09:56:18 kernel: [    2.181371] Uniform CD-ROM driver Revision: 3.20
Aug  1 09:56:18 kernel: [    2.181545] sr 0:0:1:0: Attached scsi CD-ROM sr0
Aug  1 09:56:18 kernel: [    2.182051] sd 0:0:0:0: [sda] Attached SCSI disk
Aug  1 09:56:18 kernel: [    2.206508] sd 0:0:0:0: Attached scsi generic sg0 type 0
Aug  1 09:56:18 kernel: [    2.207773] sr 0:0:1:0: Attached scsi generic sg1 type 5
Aug  1 09:56:18 kernel: [    2.272125] firewire_core: created device fw0: GUID 718b5000ffffffa6, S400
Aug  1 09:56:18 kernel: [    2.400091] usb 1-2.1: new low speed USB device using ehci_hcd and address 3
Aug  1 09:56:18 kernel: [    2.498091] usb 1-2.1: New USB device found, idVendor=413c, idProduct=2003
Aug  1 09:56:18 kernel: [    2.498167] usb 1-2.1: New USB device strings: Mfr=1, Product=2, SerialNumber=0
Aug  1 09:56:18 kernel: [    2.498263] usb 1-2.1: Product: Dell USB Keyboard
Aug  1 09:56:18 kernel: [    2.498331] usb 1-2.1: Manufacturer: Dell
Aug  1 09:56:18 kernel: [    2.498903] usb 1-2.1: configuration #1 chosen from 1 choice
Aug  1 09:56:18 kernel: [    2.521519] usbcore: registered new interface driver hiddev
Aug  1 09:56:18 kernel: [    2.525522] input: Dell Dell USB Keyboard as /devices/pci0000:00/0000:00:1d.7/usb1/1-2/1-2.1/1-2.1:1.0/input/input1
Aug  1 09:56:18 kernel: [    2.525782] generic-usb 0003:413C:2003.0001: input,hidraw0: USB HID v1.10 Keyboard [Dell Dell USB Keyboard] on usb-0000:00:1d.7-2.1/input0
Aug  1 09:56:18 kernel: [    2.525933] usbcore: registered new interface driver usbhid
Aug  1 09:56:18 kernel: [    2.526181] usbhid: v2.6:USB HID core driver
Aug  1 09:56:18 kernel: [    2.576116] usb 1-2.2: new low speed USB device using ehci_hcd and address 4
Aug  1 09:56:18 kernel: [    2.672992] usb 1-2.2: New USB device found, idVendor=045e, idProduct=0039
Aug  1 09:56:18 kernel: [    2.673068] usb 1-2.2: New USB device strings: Mfr=1, Product=3, SerialNumber=0
Aug  1 09:56:18 kernel: [    2.673163] usb 1-2.2: Product: Microsoft 5-Button Mouse with IntelliEye(TM)
Aug  1 09:56:18 kernel: [    2.673236] usb 1-2.2: Manufacturer: Microsoft
Aug  1 09:56:18 kernel: [    2.673717] usb 1-2.2: configuration #1 chosen from 1 choice
Aug  1 09:56:18 kernel: [    2.677018] input: Microsoft Microsoft 5-Button Mouse with IntelliEye(TM) as /devices/pci0000:00/0000:00:1d.7/usb1/1-2/1-2.2/1-2.2:1.0/input/input2
Aug  1 09:56:18 kernel: [    2.677322] generic-usb 0003:045E:0039.0002: input,hidraw1: USB HID v1.10 Mouse [Microsoft Microsoft 5-Button Mouse with IntelliEye(TM)] on usb-0000:00:1d.7-2.2/input0
Aug  1 09:56:18 kernel: [    2.748143] usb 1-2.3: new high speed USB device using ehci_hcd and address 5
Aug  1 09:56:18 kernel: [    2.804022] pcmcia_socket pcmcia_socket0: pccard: CardBus card inserted into slot 0
Aug  1 09:56:18 kernel: [    2.804179] pci 0000:03:00.0: reg 10 32bit mmio: [0xffffe000-0xffffffff]
Aug  1 09:56:18 kernel: [    2.805746] pcmcia_socket pcmcia_socket0: cs: IO port probe 0x100-0x3af: clean.
Aug  1 09:56:18 kernel: [    2.807622] pcmcia_socket pcmcia_socket0: cs: IO port probe 0x3e0-0x4ff: clean.
Aug  1 09:56:18 kernel: [    2.812578] pcmcia_socket pcmcia_socket0: cs: IO port probe 0x820-0x8ff: clean.
Aug  1 09:56:18 kernel: [    2.813324] pcmcia_socket pcmcia_socket0: cs: IO port probe 0xc00-0xcf7: clean.
Aug  1 09:56:18 kernel: [    2.814259] pcmcia_socket pcmcia_socket0: cs: IO port probe 0xa00-0xaff: clean.
Aug  1 09:56:18 kernel: [    2.845519] usb 1-2.3: New USB device found, idVendor=0471, idProduct=0334
Aug  1 09:56:18 kernel: [    2.845598] usb 1-2.3: New USB device strings: Mfr=0, Product=1, SerialNumber=0
Aug  1 09:56:18 kernel: [    2.845691] usb 1-2.3: Product: USB Video Camera
Aug  1 09:56:18 kernel: [    2.846196] usb 1-2.3: configuration #1 chosen from 1 choice
Aug  1 09:56:18 frontend: Setting debconf/priority to low
Aug  1 09:56:19 kernel: [   18.357821] vga16fb: initializing
Aug  1 09:56:19 kernel: [   18.357827] vga16fb: mapped to 0xc00a0000
Aug  1 09:56:19 kernel: [   18.492764] Console: switching to colour frame buffer device 80x30
Aug  1 09:56:19 kernel: [   18.502491] fb0: VGA16 VGA frame buffer device
Aug  1 09:56:19 init: starting pid 370, tty '': '/sbin/reopen-console /sbin/debian-installer'
Aug  1 09:56:19 init: starting pid 373, tty '/dev/tty4': '/usr/bin/tail -f /var/log/syslog'
Aug  1 09:56:19 debconf: Setting debconf/language to en
Aug  1 09:56:19 main-menu[385]: DEBUG: resolver (libc6-udeb): package doesn't exist (ignored)
Aug  1 09:56:24 main-menu[385]: INFO: Menu item 'localechooser' selected
Aug  1 09:56:24 debconf: Setting debconf/language to en
Aug  1 09:56:25 localechooser: info: Language = 'en'
Aug  1 09:56:25 localechooser: info: line=en;0;US;en_US.UTF-8;;console-setup
Aug  1 09:56:25 localechooser: info: Set debian-installer/language = 'en'
Aug  1 09:56:25 localechooser: info: Default country = 'US'
Aug  1 09:56:25 localechooser: info: Default locale = 'en_US.UTF-8'
Aug  1 09:56:25 localechooser: info: Set debian-installer/consoledisplay = 'console-setup'
Aug  1 09:56:25 debconf: Setting debconf/language to en
Aug  1 09:56:29 localechooser: info: Set debian-installer/country = 'IE'
Aug  1 09:56:30 localechooser: info: Set debian-installer/locale = 'en_IE.UTF-8'
Aug  1 09:56:33 localechooser: info: System locale (debian-installer/locale) = 'en_IE.UTF-8'
Aug  1 09:56:33 localechooser: info: Set debian-installer/language = 'en_IE:en'
Aug  1 09:56:33 debconf: Setting debconf/language to en_IE:en
Aug  1 09:56:33 main-menu[385]: DEBUG: resolver (libc6-udeb): package doesn't exist (ignored)
Aug  1 09:56:34 main-menu[385]: INFO: Menu item 'kbd-chooser' selected
Aug  1 09:56:34 main-menu[385]: INFO: Falling back to the package description for console-keymaps-at
Aug  1 09:56:34 main-menu[385]: INFO: Falling back to the package description for console-keymaps-at
Aug  1 09:56:34 kbd-chooser[1218]: DEBUG: Mounting usbfs to look for kbd
Aug  1 09:56:34 kbd-chooser[1218]: DEBUG: Parsing usb/devices file
Aug  1 09:56:34 kbd-chooser[1218]: DEBUG: Found usb keyboard: 0x4163:0x2003
Aug  1 09:56:34 kbd-chooser[1218]: DEBUG: non-Apple USB keyboard detected
Aug  1 09:56:34 kbd-chooser[1218]: DEBUG: Forcing keymap list to AT (2.6 kernel)
Aug  1 09:56:34 kbd-chooser[1218]: DEBUG: Adding generic entry for AT keymaps
Aug  1 09:56:34 kbd-chooser[1218]: INFO: keyboard type at: present: true:  
Aug  1 09:56:34 kbd-chooser[1218]: INFO: keyboard type at: present: unknown  
Aug  1 09:56:34 kbd-chooser[1218]: INFO: keyboard type at: present: true:  
Aug  1 09:56:34 kbd-chooser[1218]: INFO: Setting debian-installer/serial-console to false
Aug  1 09:56:34 kbd-chooser[1218]: INFO: Setting debian-installer/uml-console to false
Aug  1 09:56:35 kbd-chooser[1218]: INFO: kbd-chooser: arch at selected
Aug  1 09:56:37 kbd-chooser[1218]: INFO: choose_keymap: keymap = us
Aug  1 09:56:37 kbd-chooser[1218]: INFO: kbd_chooser: setting keymap us
Aug  1 09:56:37 main-menu[385]: DEBUG: resolver (libc6-udeb): package doesn't exist (ignored)
Aug  1 09:56:38 main-menu[385]: INFO: Menu item 'cdrom-detect' selected
Aug  1 09:57:02 hw-detect: Detected module 'usb-storage' for 'USB storage'
Aug  1 09:57:02 hw-detect: insmod /lib/modules/2.6.32-5-486/kernel/drivers/usb/storage/usb-storage.ko 
Aug  1 09:57:02 kernel: [   62.289249] Initializing USB Mass Storage driver...
Aug  1 09:57:02 kernel: [   62.289348] usbcore: registered new interface driver usb-storage
Aug  1 09:57:02 kernel: [   62.289505] USB Mass Storage support registered.
Aug  1 09:57:03 hw-detect: ISA bus detected; loading module 'ide-generic'
Aug  1 09:57:03 hw-detect: insmod /lib/modules/2.6.32-5-486/kernel/drivers/ide/ide-core.ko 
Aug  1 09:57:03 hw-detect: insmod /lib/modules/2.6.32-5-486/kernel/drivers/ide/ide-generic.ko 
Aug  1 09:57:03 kernel: [   62.331279] Uniform Multi-Platform E-IDE driver
Aug  1 09:57:03 kernel: [   62.332426] ide_generic: please use "probe_mask=0x3f" module parameter for probing all legacy ISA IDE ports
Aug  1 09:57:03 hw-detect: insmod /lib/modules/2.6.32-5-486/kernel/drivers/firewire/firewire-sbp2.ko 
Aug  1 09:57:03 hw-detect: Detected PCMCIA, installing pcmciautils.
Aug  1 09:57:03 apt-install: Queueing package pcmciautils for later installation
Aug  1 09:57:03 apt-install: Queueing package udev for later installation
Aug  1 09:57:03 apt-install: Queueing package pciutils for later installation
Aug  1 09:57:03 apt-install: Queueing package usbutils for later installation
Aug  1 09:57:03 apt-install: Queueing package acpi for later installation
Aug  1 09:57:03 apt-install: Queueing package acpid for later installation
Aug  1 09:57:03 apt-install: Queueing package acpi-support-base for later installation
Aug  1 09:57:03 apt-install: Queueing package eject for later installation
Aug  1 09:57:03 apt-install: Queueing package libc6-i686 for later installation
Aug  1 09:57:03 hw-detect: Missing modules 'eth1394 (FireWire ethernet)
Aug  1 09:57:04 check-missing-firmware: no missing firmware in /dev/.udev/firmware-missing
Aug  1 09:57:04 cdrom-detect: Searching for Debian installation media...
Aug  1 09:57:04 kernel: [   63.677369] ISO 9660 Extensions: Microsoft Joliet Level 3
Aug  1 09:57:08 cdrom-detect: CD-ROM mount succeeded: device=/dev/sr0 fstype=iso9660
Aug  1 09:57:08 kernel: [   68.114511] ISO 9660 Extensions: RRIP_1991A
Aug  1 09:57:09 cdrom-detect: Detected CD 'Debian GNU/Linux 6 "Squeeze" - Official Snapshot i386 LIVE/INSTALL Binary 20100717-21:32'
Aug  1 09:57:50 cdrom-detect: Detected CD with 'sid' (sid) distribution
Aug  1 09:57:50 anna-install: Queueing udeb eject-udeb for later installation
Aug  1 09:57:57 anna-install: Queueing udeb apt-mirror-setup for later installation
Aug  1 09:57:57 anna-install: Queueing udeb apt-cdrom-setup for later installation
Aug  1 09:57:57 anna-install: Queueing udeb sid-support for later installation
Aug  1 09:57:57 main-menu[385]: DEBUG: resolver (libc6-udeb): package doesn't exist (ignored)
Aug  1 09:57:58 main-menu[385]: INFO: Menu item 'load-cdrom' selected
Aug  1 09:57:59 anna[1955]: DEBUG: resolver (fat-modules): package doesn't exist (ignored)
Aug  1 09:57:59 anna[1955]: DEBUG: resolver (btrfs-modules): package doesn't exist (ignored)
Aug  1 09:57:59 anna[1955]: DEBUG: resolver (ufsutils-udeb): package doesn't exist (ignored)
Aug  1 09:59:06 anna[1955]: DEBUG: resolver (fat-modules): package doesn't exist (ignored)
Aug  1 09:59:06 anna[1955]: DEBUG: resolver (btrfs-modules): package doesn't exist (ignored)
Aug  1 09:59:06 anna[1955]: DEBUG: resolver (ufsutils-udeb): package doesn't exist (ignored)
Aug  1 09:59:06 anna[1955]: DEBUG: retrieving libparted0-udeb 2.3-1
Aug  1 09:59:09 anna[1955]: DEBUG: retrieving partman-auto-raid 16
Aug  1 09:59:09 anna[1955]: DEBUG: retrieving network-preseed 1.46
Aug  1 09:59:09 anna[1955]: DEBUG: retrieving pkgsel 0.30
Aug  1 09:59:09 anna[1955]: DEBUG: retrieving partman-base 142
Aug  1 09:59:09 anna[1955]: DEBUG: retrieving partman-utils 142
Aug  1 09:59:10 anna[1955]: DEBUG: retrieving partman-auto 92
Aug  1 09:59:10 anna[1955]: DEBUG: retrieving partman-basicmethods 45
Aug  1 09:59:10 anna[1955]: DEBUG: retrieving ppp-udeb 2.4.4rel-10.1
Aug  1 09:59:10 anna[1955]: DEBUG: retrieving partman-md 52
Aug  1 09:59:11 anna[1955]: DEBUG: retrieving partman-btrfs 2
Aug  1 09:59:11 anna[1955]: DEBUG: retrieving partman-xfs 45
Aug  1 09:59:11 anna[1955]: DEBUG: retrieving partman-basicfilesystems 64
Aug  1 09:59:11 anna[1955]: DEBUG: retrieving partman-target 68
Aug  1 09:59:11 anna[1955]: DEBUG: retrieving partconf-find-partitions 1.34
Aug  1 09:59:12 anna[1955]: DEBUG: retrieving partman-partitioning 76
Aug  1 09:59:12 anna[1955]: DEBUG: retrieving partman-jfs 31
Aug  1 09:59:12 anna[1955]: DEBUG: retrieving partman-efi 21
Aug  1 09:59:12 anna[1955]: DEBUG: retrieving partman-ufs 2
Aug  1 09:59:12 anna[1955]: DEBUG: retrieving partman-ext3 60
Aug  1 09:59:12 anna[1955]: DEBUG: retrieving xfsprogs-udeb 3.1.2-1
Aug  1 09:59:13 anna[1955]: DEBUG: retrieving rdate-udeb 1:1.2-4
Aug  1 09:59:13 anna[1955]: DEBUG: retrieving libc6-udeb 2.11.2-2
Aug  1 09:59:14 anna[1955]: DEBUG: retrieving elilo-installer 1.16
Aug  1 09:59:14 anna[1955]: DEBUG: retrieving e2fsprogs-udeb 1.41.12-2
Aug  1 09:59:14 anna[1955]: DEBUG: retrieving eject-udeb 2.1.5+deb1+cvs20081104-7
Aug  1 09:59:15 anna[1955]: DEBUG: retrieving di-utils-mapdevfs 1.77
Aug  1 09:59:15 anna[1955]: DEBUG: retrieving jfsutils-udeb 1.1.12-2.1
Aug  1 09:59:16 anna[1955]: DEBUG: retrieving os-prober-udeb 1.39
Aug  1 09:59:16 anna[1955]: DEBUG: retrieving clock-setup 0.103
Aug  1 09:59:16 anna[1955]: DEBUG: retrieving libbsd0-udeb 0.2.0-1
Aug  1 09:59:17 anna[1955]: DEBUG: retrieving apt-setup-udeb 1:0.46
Aug  1 09:59:17 anna[1955]: DEBUG: retrieving apt-cdrom-setup 1:0.46
Aug  1 09:59:18 anna[1955]: DEBUG: retrieving base-installer 1.108
Aug  1 09:59:18 anna[1955]: DEBUG: retrieving btrfs-tools-udeb 0.19+20100601-3
Aug  1 09:59:18 anna[1955]: DEBUG: retrieving libdevmapper1.02.1-udeb 2:1.02.48-2
Aug  1 09:59:19 anna[1955]: DEBUG: retrieving live-installer 20
Aug  1 09:59:19 anna[1955]: DEBUG: retrieving lilo-installer 1.32
Aug  1 09:59:19 anna[1955]: DEBUG: retrieving ntfsprogs-udeb 2.0.0-1+b1
Aug  1 09:59:19 anna[1955]: DEBUG: retrieving ufs-modules-2.6.32-5-486-di 1.89
Aug  1 09:59:20 anna[1955]: DEBUG: retrieving md-modules-2.6.32-5-486-di 1.89
Aug  1 09:59:20 anna[1955]: DEBUG: retrieving xfs-modules-2.6.32-5-486-di 1.89
Aug  1 09:59:21 anna[1955]: DEBUG: retrieving efi-modules-2.6.32-5-486-di 1.89
Aug  1 09:59:22 anna[1955]: DEBUG: retrieving loop-modules-2.6.32-5-486-di 1.89
Aug  1 09:59:22 anna[1955]: DEBUG: retrieving nic-usb-modules-2.6.32-5-486-di 1.89
Aug  1 09:59:23 anna[1955]: DEBUG: retrieving scsi-extra-modules-2.6.32-5-486-di 1.89
Aug  1 09:59:24 anna[1955]: DEBUG: retrieving ext2-modules-2.6.32-5-486-di 1.89
Aug  1 09:59:24 anna[1955]: DEBUG: retrieving nic-extra-modules-2.6.32-5-486-di 1.89
Aug  1 09:59:25 anna[1955]: DEBUG: retrieving ext3-modules-2.6.32-5-486-di 1.89
Aug  1 09:59:26 anna[1955]: DEBUG: retrieving ext4-modules-2.6.32-5-486-di 1.89
Aug  1 09:59:26 anna[1955]: DEBUG: retrieving nic-modules-2.6.32-5-486-di 1.89
Aug  1 09:59:26 anna[1955]: DEBUG: retrieving ppp-modules-2.6.32-5-486-di 1.89
Aug  1 09:59:27 anna[1955]: DEBUG: retrieving nic-wireless-modules-2.6.32-5-486-di 1.89
Aug  1 09:59:28 anna[1955]: DEBUG: retrieving crypto-modules-2.6.32-5-486-di 1.89
Aug  1 09:59:29 anna[1955]: DEBUG: retrieving nic-pcmcia-modules-2.6.32-5-486-di 1.89
Aug  1 09:59:29 anna[1955]: DEBUG: retrieving squashfs-modules-2.6.32-5-486-di 1.89
Aug  1 09:59:30 anna[1955]: DEBUG: retrieving jfs-modules-2.6.32-5-486-di 1.89
Aug  1 09:59:30 anna[1955]: DEBUG: retrieving nic-shared-modules-2.6.32-5-486-di 1.89
Aug  1 09:59:31 anna[1955]: DEBUG: retrieving finish-install 2.25
Aug  1 09:59:31 anna[1955]: DEBUG: retrieving fdisk-udeb 2.17.2-3.1
Aug  1 09:59:32 anna[1955]: DEBUG: retrieving user-setup-udeb 1.31
Aug  1 09:59:32 anna[1955]: DEBUG: retrieving nobootloader 1.27
Aug  1 09:59:32 anna[1955]: DEBUG: retrieving netcfg 1.54
Aug  1 09:59:32 anna[1955]: DEBUG: retrieving disk-detect 1.79
Aug  1 09:59:32 anna[1955]: DEBUG: retrieving ethdetect 1.79
Aug  1 09:59:33 anna[1955]: DEBUG: retrieving kickseed-common 0.54
Aug  1 09:59:33 anna[1955]: DEBUG: retrieving grub-installer 1.53
Aug  1 09:59:33 anna[1955]: DEBUG: retrieving tzsetup-udeb 1:0.29
Aug  1 09:59:34 anna[1955]: DEBUG: retrieving libiw30-udeb 30~pre9-5
Aug  1 09:59:35 anna[1955]: DEBUG: retrieving mdadm-udeb 3.1.2-2
Aug  1 09:59:35 anna[1955]: DEBUG: retrieving load-media 1.21
Aug  1 09:59:54 main-menu[385]: INFO: Menu item 'ethdetect' selected
Aug  1 09:59:54 kernel: [  234.036449] cfg80211: Using static regulatory domain info
Aug  1 09:59:54 kernel: [  234.036454] cfg80211: Regulatory domain: US
Aug  1 09:59:54 kernel: [  234.036457] 	(start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp)
Aug  1 09:59:54 kernel: [  234.036462] 	(2402000 KHz - 2472000 KHz @ 40000 KHz), (600 mBi, 2700 mBm)
Aug  1 09:59:54 kernel: [  234.036466] 	(5170000 KHz - 5190000 KHz @ 40000 KHz), (600 mBi, 2300 mBm)
Aug  1 09:59:54 kernel: [  234.036471] 	(5190000 KHz - 5210000 KHz @ 40000 KHz), (600 mBi, 2300 mBm)
Aug  1 09:59:54 kernel: [  234.036475] 	(5210000 KHz - 5230000 KHz @ 40000 KHz), (600 mBi, 2300 mBm)
Aug  1 09:59:54 kernel: [  234.036479] 	(5230000 KHz - 5330000 KHz @ 40000 KHz), (600 mBi, 2300 mBm)
Aug  1 09:59:54 kernel: [  234.036484] 	(5735000 KHz - 5835000 KHz @ 40000 KHz), (600 mBi, 3000 mBm)
Aug  1 09:59:54 kernel: [  234.036756] cfg80211: Calling CRDA for country: US
Aug  1 09:59:54 kernel: [  234.057897] b44 0000:02:0e.0: PCI INT A -> GSI 16 (level, low) -> IRQ 16
Aug  1 09:59:54 kernel: [  234.078518] rt2500pci 0000:03:00.0: enabling device (0000 -> 0002)
Aug  1 09:59:54 kernel: [  234.078533] rt2500pci 0000:03:00.0: PCI INT A -> GSI 18 (level, low) -> IRQ 18
Aug  1 09:59:54 kernel: [  234.078544] rt2500pci 0000:03:00.0: setting latency timer to 64
Aug  1 09:59:54 kernel: [  234.106389] phy0: Selected rate control algorithm 'minstrel'
Aug  1 09:59:54 kernel: [  234.107190] Registered led device: rt2500pci-phy0::radio
Aug  1 09:59:54 kernel: [  234.107214] Registered led device: rt2500pci-phy0::quality
Aug  1 09:59:54 net/hw-detect.hotplug: Detected hotpluggable network interface wlan0
Aug  1 09:59:54 kernel: [  234.144089] ssb: Sonics Silicon Backplane found on PCI device 0000:02:0e.0
Aug  1 09:59:54 kernel: [  234.144122] b44.c:v2.0
Aug  1 09:59:54 kernel: [  234.164937] eth0: Broadcom 44xx/47xx 10/100BaseT Ethernet 00:12:79:bf:03:97
Aug  1 09:59:54 net/hw-detect.hotplug: Detected hotpluggable network interface eth0
Aug  1 09:59:56 hw-detect: Missing modules 'eth1394 (FireWire ethernet)
Aug  1 09:59:57 check-missing-firmware: no missing firmware in /dev/.udev/firmware-missing
Aug  1 09:59:58 kernel: [  237.408852] ADDRCONF(NETDEV_UP): wlan0: link is not ready
Aug  1 09:59:58 kernel: [  237.415095] ADDRCONF(NETDEV_UP): eth0: link is not ready
Aug  1 09:59:58 kernel: [  237.417948] b44: eth0: powering down PHY
Aug  1 09:59:59 check-missing-firmware: no missing firmware in /dev/.udev/firmware-missing
Aug  1 10:00:04 main-menu[385]: INFO: Menu item 'netcfg' selected
Aug  1 10:00:04 kernel: [  243.906436] ADDRCONF(NETDEV_UP): eth0: link is not ready
Aug  1 10:00:04 netcfg[4132]: INFO: eth0 is disconnected. (MII)
Aug  1 10:00:04 netcfg[4132]: INFO: eth0 is not a wireless interface. Continuing.
Aug  1 10:00:04 kernel: [  243.913387] b44: eth0: powering down PHY
Aug  1 10:00:04 netcfg[4132]: INFO: ethtool ioctl on wlan0 failed
Aug  1 10:00:04 netcfg[4132]: WARNING **: couldn't determine MII ioctl to use for wlan0
Aug  1 10:00:04 kernel: [  243.932407] ADDRCONF(NETDEV_UP): wlan0: link is not ready
Aug  1 10:00:05 netcfg[4132]: INFO: wlan0 is not associated. Relegating to defwireless
Aug  1 10:00:07 apt-install: Queueing package wireless-tools for later installation
Aug  1 10:00:45 kernel: [  284.720419] ADDRCONF(NETDEV_UP): wlan0: link is not ready
Aug  1 10:01:23 netcfg[4132]: INFO: eth0 is disconnected. (MII)
Aug  1 10:01:23 netcfg[4132]: INFO: eth0 is not a wireless interface. Continuing.
Aug  1 10:01:23 kernel: [  323.168246] ADDRCONF(NETDEV_UP): eth0: link is not ready
Aug  1 10:01:23 kernel: [  323.170766] b44: eth0: powering down PHY
Aug  1 10:01:23 netcfg[4132]: INFO: ethtool ioctl on wlan0 failed
Aug  1 10:01:23 netcfg[4132]: WARNING **: couldn't determine MII ioctl to use for wlan0
Aug  1 10:01:24 netcfg[4132]: INFO: wlan0 is not associated. Relegating to defwireless
Aug  1 10:01:28 main-menu[385]: (process:4131): udhcpc (v1.15.3) started
Aug  1 10:01:28 main-menu[385]: (process:4131): Sending discover...
Aug  1 10:01:28 main-menu[385]: (process:4131): Sending discover...
Aug  1 10:01:28 main-menu[385]: (process:4131): Sending discover...
Aug  1 10:01:28 main-menu[385]: (process:4131): udhcpc: has been called with an unknown param: leasefail
Aug  1 10:01:28 main-menu[385]: (process:4131): Sending discover...
Aug  1 10:01:28 main-menu[385]: (process:4131): Sending discover...
Aug  1 10:01:28 main-menu[385]: (process:4131): Sending discover...
Aug  1 10:01:28 main-menu[385]: (process:4131): Received SIGTERM
Aug  1 10:01:28 main-menu[385]: INFO: Menu item 'netcfg' succeeded but requested to be left unconfigured.
Aug  1 10:01:51 main-menu[385]: INFO: Menu item 'di-utils-shell' selected
Aug  1 10:09:35 main-menu[385]: INFO: Menu item 'di-utils-shell' selected
Aug  1 10:18:31 kernel: [ 1351.152155] usb 1-2.4: new full speed USB device using ehci_hcd and address 6
Aug  1 10:18:31 kernel: [ 1351.246409] usb 1-2.4: New USB device found, idVendor=05dc, idProduct=0080
Aug  1 10:18:31 kernel: [ 1351.246414] usb 1-2.4: New USB device strings: Mfr=1, Product=1, SerialNumber=2
Aug  1 10:18:31 kernel: [ 1351.246418] usb 1-2.4: Product: LEXR PLUG DRIVE
Aug  1 10:18:31 kernel: [ 1351.246421] usb 1-2.4: Manufacturer: LEXR PLUG DRIVE
Aug  1 10:18:31 kernel: [ 1351.246425] usb 1-2.4: SerialNumber:               030923030200002E11E100000000000
Aug  1 10:18:31 kernel: [ 1351.246571] usb 1-2.4: configuration #1 chosen from 1 choice
Aug  1 10:18:31 kernel: [ 1351.249682] scsi2 : SCSI emulation for USB Mass Storage devices
Aug  1 10:18:31 kernel: [ 1351.250251] usb-storage: device found at 6
Aug  1 10:18:31 kernel: [ 1351.250255] usb-storage: waiting for device to settle before scanning
Aug  1 10:18:36 kernel: [ 1356.248334] usb-storage: device scan complete
Aug  1 10:18:36 kernel: [ 1356.249058] scsi 2:0:0:0: Direct-Access     LEXAR    DIGITAL FILM     /W1. PQ: 0 ANSI: 2
Aug  1 10:18:36 kernel: [ 1356.250003] sd 2:0:0:0: Attached scsi generic sg2 type 0
Aug  1 10:18:36 kernel: [ 1356.250783] sd 2:0:0:0: [sdb] 251904 512-byte logical blocks: (128 MB/123 MiB)
Aug  1 10:18:36 kernel: [ 1356.251528] sd 2:0:0:0: [sdb] Write Protect is off
Aug  1 10:18:36 kernel: [ 1356.251534] sd 2:0:0:0: [sdb] Mode Sense: 0d 00 00 00
Aug  1 10:18:36 kernel: [ 1356.251538] sd 2:0:0:0: [sdb] Assuming drive cache: write through
Aug  1 10:18:36 kernel: [ 1356.254783] sd 2:0:0:0: [sdb] Assuming drive cache: write through
Aug  1 10:18:36 kernel: [ 1356.254791]  sdb: sdb1
Aug  1 10:18:36 kernel: [ 1356.262281] sd 2:0:0:0: [sdb] Assuming drive cache: write through
Aug  1 10:18:36 kernel: [ 1356.262287] sd 2:0:0:0: [sdb] Attached SCSI removable disk
Aug  1 10:19:37 kernel: [ 1416.448476] FAT: utf8 is not a recommended IO charset for FAT filesystems, filesystem will be case sensitive!
Aug  1 10:23:58 kernel: [ 1678.036438] ADDRCONF(NETDEV_UP): wlan0: link is not ready
Aug  1 10:24:00 kernel: [ 1679.980142] wlan0: direct probe to AP 00:02:cf:6a:32:c8 (try 1)
Aug  1 10:24:00 kernel: [ 1679.981618] wlan0: direct probe responded
Aug  1 10:24:00 kernel: [ 1679.981622] wlan0: authenticate with AP 00:02:cf:6a:32:c8 (try 1)
Aug  1 10:24:00 kernel: [ 1679.983166] wlan0: authenticated
Aug  1 10:24:00 kernel: [ 1679.983181] wlan0: associate with AP 00:02:cf:6a:32:c8 (try 1)
Aug  1 10:24:00 kernel: [ 1679.985760] wlan0: RX AssocResp from 00:02:cf:6a:32:c8 (capab=0x471 status=0 aid=1)
Aug  1 10:24:00 kernel: [ 1679.985764] wlan0: associated
Aug  1 10:24:00 kernel: [ 1679.986829] ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready
Aug  1 10:24:00 kernel: [ 1680.164009] phy0 -> rt2500pci_set_device_state: Error - Device failed to enter state 1 (-16).
Aug  1 10:24:02 kernel: [ 1681.488009] phy0 -> rt2500pci_set_device_state: Error - Device failed to enter state 1 (-16).
Aug  1 10:24:04 kernel: [ 1683.432011] phy0 -> rt2500pci_set_device_state: Error - Device failed to enter state 1 (-16).
Aug  1 10:24:06 kernel: [ 1685.480009] phy0 -> rt2500pci_set_device_state: Error - Device failed to enter state 1 (-16).
Aug  1 10:24:36 kernel: [ 1716.180009] phy0 -> rt2500pci_set_device_state: Error - Device failed to enter state 1 (-16).
Aug  1 10:25:07 kernel: [ 1747.180009] phy0 -> rt2500pci_set_device_state: Error - Device failed to enter state 1 (-16).
Aug  1 10:25:38 kernel: [ 1778.180008] phy0 -> rt2500pci_set_device_state: Error - Device failed to enter state 1 (-16).
Aug  1 10:25:43 init: starting pid 371, tty '/dev/tty2': '-/bin/sh'
Aug  1 10:26:09 kernel: [ 1809.180010] phy0 -> rt2500pci_set_device_state: Error - Device failed to enter state 1 (-16).
Aug  1 10:26:40 kernel: [ 1840.180010] phy0 -> rt2500pci_set_device_state: Error - Device failed to enter state 1 (-16).
Aug  1 10:27:11 kernel: [ 1871.180010] phy0 -> rt2500pci_set_device_state: Error - Device failed to enter state 1 (-16).
Aug  1 10:27:19 kernel: [ 1878.324009] phy0 -> rt2500pci_set_device_state: Error - Device failed to enter state 1 (-16).
Aug  1 10:27:19 kernel: [ 1879.244007] phy0 -> rt2500pci_set_device_state: Error - Device failed to enter state 1 (-16).
Aug  1 10:27:20 kernel: [ 1880.268008] phy0 -> rt2500pci_set_device_state: Error - Device failed to enter state 1 (-16).
Aug  1 10:27:24 kernel: [ 1883.324009] phy0 -> rt2500pci_set_device_state: Error - Device failed to enter state 1 (-16).
Aug  1 10:27:51 kernel: [ 1911.184147] wlan0: deauthenticating from 00:02:cf:6a:32:c8 by local choice (reason=3)
Aug  1 10:28:33 kernel: [ 1952.385910] usb 1-2.1: USB disconnect, address 3
Aug  1 10:28:33 kernel: [ 1952.512428] usb 1-2.2: USB disconnect, address 4
Aug  1 10:28:33 kernel: [ 1952.712089] usb 1-2.2: new low speed USB device using ehci_hcd and address 7
Aug  1 10:28:33 kernel: [ 1952.808843] usb 1-2.2: New USB device found, idVendor=045e, idProduct=0039
Aug  1 10:28:33 kernel: [ 1952.808848] usb 1-2.2: New USB device strings: Mfr=1, Product=3, SerialNumber=0
Aug  1 10:28:33 kernel: [ 1952.808852] usb 1-2.2: Product: Microsoft 5-Button Mouse with IntelliEye(TM)
Aug  1 10:28:33 kernel: [ 1952.808856] usb 1-2.2: Manufacturer: Microsoft
Aug  1 10:28:33 kernel: [ 1952.809231] usb 1-2.2: configuration #1 chosen from 1 choice
Aug  1 10:28:33 kernel: [ 1952.812546] input: Microsoft Microsoft 5-Button Mouse with IntelliEye(TM) as /devices/pci0000:00/0000:00:1d.7/usb1/1-2/1-2.2/1-2.2:1.0/input/input3
Aug  1 10:28:33 kernel: [ 1952.812742] generic-usb 0003:045E:0039.0003: input,hidraw0: USB HID v1.10 Mouse [Microsoft Microsoft 5-Button Mouse with IntelliEye(TM)] on usb-0000:00:1d.7-2.2/input0
Aug  1 10:28:33 kernel: [ 1953.012133] usb 1-2.1: new low speed USB device using ehci_hcd and address 8
Aug  1 10:28:33 kernel: [ 1953.110012] usb 1-2.1: New USB device found, idVendor=413c, idProduct=2003
Aug  1 10:28:33 kernel: [ 1953.110017] usb 1-2.1: New USB device strings: Mfr=1, Product=2, SerialNumber=0
Aug  1 10:28:33 kernel: [ 1953.110022] usb 1-2.1: Product: Dell USB Keyboard
Aug  1 10:28:33 kernel: [ 1953.110025] usb 1-2.1: Manufacturer: Dell
Aug  1 10:28:33 kernel: [ 1953.110156] usb 1-2.1: configuration #1 chosen from 1 choice
Aug  1 10:28:33 kernel: [ 1953.114472] input: Dell Dell USB Keyboard as /devices/pci0000:00/0000:00:1d.7/usb1/1-2/1-2.1/1-2.1:1.0/input/input4
Aug  1 10:28:33 kernel: [ 1953.114642] generic-usb 0003:413C:2003.0004: input,hidraw1: USB HID v1.10 Keyboard [Dell Dell USB Keyboard] on usb-0000:00:1d.7-2.1/input0
Aug  1 10:28:35 kernel: [ 1954.434210] usb 1-2.1: USB disconnect, address 8
Aug  1 10:28:35 kernel: [ 1954.560352] usb 1-2.2: USB disconnect, address 7
Aug  1 10:28:35 kernel: [ 1954.760138] usb 1-2.2: new low speed USB device using ehci_hcd and address 9
Aug  1 10:28:35 kernel: [ 1954.856893] usb 1-2.2: New USB device found, idVendor=045e, idProduct=0039
Aug  1 10:28:35 kernel: [ 1954.856898] usb 1-2.2: New USB device strings: Mfr=1, Product=3, SerialNumber=0
Aug  1 10:28:35 kernel: [ 1954.856903] usb 1-2.2: Product: Microsoft 5-Button Mouse with IntelliEye(TM)
Aug  1 10:28:35 kernel: [ 1954.856906] usb 1-2.2: Manufacturer: Microsoft
Aug  1 10:28:35 kernel: [ 1954.857291] usb 1-2.2: configuration #1 chosen from 1 choice
Aug  1 10:28:35 kernel: [ 1954.861235] input: Microsoft Microsoft 5-Button Mouse with IntelliEye(TM) as /devices/pci0000:00/0000:00:1d.7/usb1/1-2/1-2.2/1-2.2:1.0/input/input5
Aug  1 10:28:35 kernel: [ 1954.861444] generic-usb 0003:045E:0039.0005: input,hidraw0: USB HID v1.10 Mouse [Microsoft Microsoft 5-Button Mouse with IntelliEye(TM)] on usb-0000:00:1d.7-2.2/input0
Aug  1 10:28:35 kernel: [ 1954.861771] usb 1-2.4: USB disconnect, address 6
Aug  1 10:28:35 kernel: [ 1954.992043] hub 1-2:1.0: hub_port_status failed (err = -71)
Aug  1 10:28:35 kernel: [ 1954.992049] hub 1-2:1.0: connect-debounce failed, port 4 disabled
Aug  1 10:28:35 kernel: [ 1954.996166] hub 1-2:1.0: hub_port_status failed (err = -71)
Aug  1 10:28:35 kernel: [ 1954.996185] usb 1-2: USB disconnect, address 2
Aug  1 10:28:35 kernel: [ 1954.996188] usb 1-2.2: USB disconnect, address 9
Aug  1 10:28:35 kernel: [ 1954.996583] usb 1-2.3: USB disconnect, address 5
Aug  1 10:28:35 kernel: [ 1955.236014] usb 1-2: new high speed USB device using ehci_hcd and address 10
Aug  1 10:28:36 kernel: [ 1955.368593] usb 1-2: New USB device found, idVendor=1a40, idProduct=0101
Aug  1 10:28:36 kernel: [ 1955.368598] usb 1-2: New USB device strings: Mfr=0, Product=1, SerialNumber=0
Aug  1 10:28:36 kernel: [ 1955.368602] usb 1-2: Product: USB 2.0 Hub
Aug  1 10:28:36 kernel: [ 1955.368993] usb 1-2: configuration #1 chosen from 1 choice
Aug  1 10:28:36 kernel: [ 1955.369381] hub 1-2:1.0: USB hub found
Aug  1 10:28:36 kernel: [ 1955.369468] hub 1-2:1.0: 4 ports detected
Aug  1 10:28:36 kernel: [ 1955.640143] usb 1-2.1: new low speed USB device using ehci_hcd and address 11
Aug  1 10:28:36 kernel: [ 1955.738022] usb 1-2.1: New USB device found, idVendor=413c, idProduct=2003
Aug  1 10:28:36 kernel: [ 1955.738026] usb 1-2.1: New USB device strings: Mfr=1, Product=2, SerialNumber=0
Aug  1 10:28:36 kernel: [ 1955.738031] usb 1-2.1: Product: Dell USB Keyboard
Aug  1 10:28:36 kernel: [ 1955.738034] usb 1-2.1: Manufacturer: Dell
Aug  1 10:28:36 kernel: [ 1955.738151] usb 1-2.1: configuration #1 chosen from 1 choice
Aug  1 10:28:36 kernel: [ 1955.742627] input: Dell Dell USB Keyboard as /devices/pci0000:00/0000:00:1d.7/usb1/1-2/1-2.1/1-2.1:1.0/input/input6
Aug  1 10:28:36 kernel: [ 1955.742815] generic-usb 0003:413C:2003.0006: input,hidraw0: USB HID v1.10 Keyboard [Dell Dell USB Keyboard] on usb-0000:00:1d.7-2.1/input0
Aug  1 10:28:36 kernel: [ 1955.812170] usb 1-2.2: new low speed USB device using ehci_hcd and address 12
Aug  1 10:28:36 kernel: [ 1955.908922] usb 1-2.2: New USB device found, idVendor=045e, idProduct=0039
Aug  1 10:28:36 kernel: [ 1955.908927] usb 1-2.2: New USB device strings: Mfr=1, Product=3, SerialNumber=0
Aug  1 10:28:36 kernel: [ 1955.908931] usb 1-2.2: Product: Microsoft 5-Button Mouse with IntelliEye(TM)
Aug  1 10:28:36 kernel: [ 1955.908935] usb 1-2.2: Manufacturer: Microsoft
Aug  1 10:28:36 kernel: [ 1955.909059] usb 1-2.2: configuration #1 chosen from 1 choice
Aug  1 10:28:36 kernel: [ 1955.912372] input: Microsoft Microsoft 5-Button Mouse with IntelliEye(TM) as /devices/pci0000:00/0000:00:1d.7/usb1/1-2/1-2.2/1-2.2:1.0/input/input7
Aug  1 10:28:36 kernel: [ 1955.912561] generic-usb 0003:045E:0039.0007: input,hidraw1: USB HID v1.10 Mouse [Microsoft Microsoft 5-Button Mouse with IntelliEye(TM)] on usb-0000:00:1d.7-2.2/input0
Aug  1 10:28:36 kernel: [ 1955.984065] usb 1-2.3: new high speed USB device using ehci_hcd and address 13
Aug  1 10:28:36 kernel: [ 1956.081574] usb 1-2.3: New USB device found, idVendor=0471, idProduct=0334
Aug  1 10:28:36 kernel: [ 1956.081579] usb 1-2.3: New USB device strings: Mfr=0, Product=1, SerialNumber=0
Aug  1 10:28:36 kernel: [ 1956.081583] usb 1-2.3: Product: USB Video Camera
Aug  1 10:28:36 kernel: [ 1956.081710] usb 1-2.3: configuration #1 chosen from 1 choice
Aug  1 10:29:46 kernel: [ 2025.581784] usb 1-2: USB disconnect, address 10
Aug  1 10:29:46 kernel: [ 2025.581789] usb 1-2.1: USB disconnect, address 11
Aug  1 10:29:46 kernel: [ 2025.582189] usb 1-2.2: USB disconnect, address 12
Aug  1 10:29:46 kernel: [ 2025.582507] usb 1-2.3: USB disconnect, address 13
Aug  1 10:29:46 kernel: [ 2025.852016] usb 1-2: new high speed USB device using ehci_hcd and address 14
Aug  1 10:29:46 kernel: [ 2025.984554] usb 1-2: New USB device found, idVendor=1a40, idProduct=0101
Aug  1 10:29:46 kernel: [ 2025.984559] usb 1-2: New USB device strings: Mfr=0, Product=1, SerialNumber=0
Aug  1 10:29:46 kernel: [ 2025.984564] usb 1-2: Product: USB 2.0 Hub
Aug  1 10:29:46 kernel: [ 2025.985018] usb 1-2: configuration #1 chosen from 1 choice
Aug  1 10:29:46 kernel: [ 2025.985482] hub 1-2:1.0: USB hub found
Aug  1 10:29:46 kernel: [ 2025.985556] hub 1-2:1.0: 4 ports detected
Aug  1 10:29:46 kernel: [ 2026.256106] usb 1-2.1: new low speed USB device using ehci_hcd and address 15
Aug  1 10:29:47 kernel: [ 2026.353985] usb 1-2.1: New USB device found, idVendor=413c, idProduct=2003
Aug  1 10:29:47 kernel: [ 2026.353989] usb 1-2.1: New USB device strings: Mfr=1, Product=2, SerialNumber=0
Aug  1 10:29:47 kernel: [ 2026.353994] usb 1-2.1: Product: Dell USB Keyboard
Aug  1 10:29:47 kernel: [ 2026.353997] usb 1-2.1: Manufacturer: Dell
Aug  1 10:29:47 kernel: [ 2026.354117] usb 1-2.1: configuration #1 chosen from 1 choice
Aug  1 10:29:47 kernel: [ 2026.358589] input: Dell Dell USB Keyboard as /devices/pci0000:00/0000:00:1d.7/usb1/1-2/1-2.1/1-2.1:1.0/input/input8
Aug  1 10:29:47 kernel: [ 2026.358772] generic-usb 0003:413C:2003.0008: input,hidraw0: USB HID v1.10 Keyboard [Dell Dell USB Keyboard] on usb-0000:00:1d.7-2.1/input0
Aug  1 10:29:47 kernel: [ 2026.428134] usb 1-2.2: new low speed USB device using ehci_hcd and address 16
Aug  1 10:29:47 kernel: [ 2026.524884] usb 1-2.2: New USB device found, idVendor=045e, idProduct=0039
Aug  1 10:29:47 kernel: [ 2026.524889] usb 1-2.2: New USB device strings: Mfr=1, Product=3, SerialNumber=0
Aug  1 10:29:47 kernel: [ 2026.524894] usb 1-2.2: Product: Microsoft 5-Button Mouse with IntelliEye(TM)
Aug  1 10:29:47 kernel: [ 2026.524898] usb 1-2.2: Manufacturer: Microsoft
Aug  1 10:29:47 kernel: [ 2026.525021] usb 1-2.2: configuration #1 chosen from 1 choice
Aug  1 10:29:47 kernel: [ 2026.528200] input: Microsoft Microsoft 5-Button Mouse with IntelliEye(TM) as /devices/pci0000:00/0000:00:1d.7/usb1/1-2/1-2.2/1-2.2:1.0/input/input9
Aug  1 10:29:47 kernel: [ 2026.528393] generic-usb 0003:045E:0039.0009: input,hidraw1: USB HID v1.10 Mouse [Microsoft Microsoft 5-Button Mouse with IntelliEye(TM)] on usb-0000:00:1d.7-2.2/input0
Aug  1 10:29:47 kernel: [ 2026.600159] usb 1-2.3: new high speed USB device using ehci_hcd and address 17
Aug  1 10:29:47 kernel: [ 2026.697661] usb 1-2.3: New USB device found, idVendor=0471, idProduct=0334
Aug  1 10:29:47 kernel: [ 2026.697666] usb 1-2.3: New USB device strings: Mfr=0, Product=1, SerialNumber=0
Aug  1 10:29:47 kernel: [ 2026.697670] usb 1-2.3: Product: USB Video Camera
Aug  1 10:29:47 kernel: [ 2026.697797] usb 1-2.3: configuration #1 chosen from 1 choice
Aug  1 10:29:47 kernel: [ 2027.096105] usb 1-2.4: new full speed USB device using ehci_hcd and address 18
Aug  1 10:29:47 kernel: [ 2027.190484] usb 1-2.4: New USB device found, idVendor=05dc, idProduct=0080
Aug  1 10:29:47 kernel: [ 2027.190488] usb 1-2.4: New USB device strings: Mfr=1, Product=1, SerialNumber=2
Aug  1 10:29:47 kernel: [ 2027.190493] usb 1-2.4: Product: LEXR PLUG DRIVE
Aug  1 10:29:47 kernel: [ 2027.190496] usb 1-2.4: Manufacturer: LEXR PLUG DRIVE
Aug  1 10:29:47 kernel: [ 2027.190500] usb 1-2.4: SerialNumber:               030923030200002E11E100000000000
Aug  1 10:29:47 kernel: [ 2027.190639] usb 1-2.4: configuration #1 chosen from 1 choice
Aug  1 10:29:47 kernel: [ 2027.193680] scsi3 : SCSI emulation for USB Mass Storage devices
Aug  1 10:29:47 kernel: [ 2027.194244] usb-storage: device found at 18
Aug  1 10:29:47 kernel: [ 2027.194248] usb-storage: waiting for device to settle before scanning
Aug  1 10:29:52 kernel: [ 2032.192128] usb-storage: device scan complete
Aug  1 10:29:52 kernel: [ 2032.192882] scsi 3:0:0:0: Direct-Access     LEXAR    DIGITAL FILM     /W1. PQ: 0 ANSI: 2
Aug  1 10:29:52 kernel: [ 2032.193967] sd 3:0:0:0: Attached scsi generic sg2 type 0
Aug  1 10:29:52 kernel: [ 2032.194732] sd 3:0:0:0: [sdb] 251904 512-byte logical blocks: (128 MB/123 MiB)
Aug  1 10:29:52 kernel: [ 2032.195475] sd 3:0:0:0: [sdb] Write Protect is off
Aug  1 10:29:52 kernel: [ 2032.195481] sd 3:0:0:0: [sdb] Mode Sense: 0d 00 00 00
Aug  1 10:29:52 kernel: [ 2032.195485] sd 3:0:0:0: [sdb] Assuming drive cache: write through
Aug  1 10:29:52 kernel: [ 2032.198862] sd 3:0:0:0: [sdb] Assuming drive cache: write through
Aug  1 10:29:52 kernel: [ 2032.198871]  sdb: sdb1
Aug  1 10:29:52 kernel: [ 2032.206233] sd 3:0:0:0: [sdb] Assuming drive cache: write through
Aug  1 10:29:52 kernel: [ 2032.206240] sd 3:0:0:0: [sdb] Attached SCSI removable disk
Aug  1 10:30:16 kernel: [ 2055.969718] FAT: utf8 is not a recommended IO charset for FAT filesystems, filesystem will be case sensitive!
Aug  1 10:32:01 kernel: [ 2160.460426] ADDRCONF(NETDEV_UP): wlan0: link is not ready
Aug  1 10:32:03 kernel: [ 2162.404170] wlan0: direct probe to AP 00:02:cf:6a:32:c8 (try 1)
Aug  1 10:32:03 kernel: [ 2162.405633] wlan0: direct probe responded
Aug  1 10:32:03 kernel: [ 2162.405637] wlan0: authenticate with AP 00:02:cf:6a:32:c8 (try 1)
Aug  1 10:32:03 kernel: [ 2162.411017] wlan0: authenticated
Aug  1 10:32:03 kernel: [ 2162.411034] wlan0: associate with AP 00:02:cf:6a:32:c8 (try 1)
Aug  1 10:32:03 kernel: [ 2162.414530] wlan0: RX AssocResp from 00:02:cf:6a:32:c8 (capab=0x471 status=0 aid=1)
Aug  1 10:32:03 kernel: [ 2162.414534] wlan0: associated
Aug  1 10:32:03 kernel: [ 2162.415331] ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready
Aug  1 10:32:03 kernel: [ 2162.592009] phy0 -> rt2500pci_set_device_state: Error - Device failed to enter state 1 (-16).
Aug  1 10:32:05 kernel: [ 2164.464009] phy0 -> rt2500pci_set_device_state: Error - Device failed to enter state 1 (-16).
Aug  1 10:32:07 modprobe: FATAL: Could not read '/lib/modules/2.6.32-5-486/kernel/crypto/aes_generic.ko': No such file or directory
Aug  1 10:32:07 kernel: [ 2166.335889] wlan0: deauthenticating from 00:02:cf:6a:32:c8 by local choice (reason=1)
Aug  1 10:32:12 kernel: [ 2172.184168] wlan0: direct probe to AP 00:02:cf:6a:32:c8 (try 1)
Aug  1 10:32:12 kernel: [ 2172.185710] wlan0: direct probe responded
Aug  1 10:32:12 kernel: [ 2172.185714] wlan0: authenticate with AP 00:02:cf:6a:32:c8 (try 1)
Aug  1 10:32:12 kernel: [ 2172.189670] wlan0: authenticated
Aug  1 10:32:12 kernel: [ 2172.189685] wlan0: associate with AP 00:02:cf:6a:32:c8 (try 1)
Aug  1 10:32:12 kernel: [ 2172.193406] wlan0: RX AssocResp from 00:02:cf:6a:32:c8 (capab=0x471 status=0 aid=1)
Aug  1 10:32:12 kernel: [ 2172.193410] wlan0: associated
Aug  1 10:32:13 kernel: [ 2172.372009] phy0 -> rt2500pci_set_device_state: Error - Device failed to enter state 1 (-16).
Aug  1 10:32:14 kernel: [ 2173.476008] phy0 -> rt2500pci_set_device_state: Error - Device failed to enter state 1 (-16).
Aug  1 10:32:16 modprobe: FATAL: Could not read '/lib/modules/2.6.32-5-486/kernel/crypto/aes_generic.ko': No such file or directory
Aug  1 10:32:16 kernel: [ 2175.348043] wlan0: deauthenticating from 00:02:cf:6a:32:c8 by local choice (reason=1)
Aug  1 10:32:21 kernel: [ 2181.192167] wlan0: direct probe to AP 00:02:cf:6a:32:c8 (try 1)
Aug  1 10:32:21 kernel: [ 2181.193698] wlan0: direct probe responded
Aug  1 10:32:21 kernel: [ 2181.193702] wlan0: authenticate with AP 00:02:cf:6a:32:c8 (try 1)
Aug  1 10:32:21 kernel: [ 2181.197217] wlan0: authenticated
Aug  1 10:32:21 kernel: [ 2181.197233] wlan0: associate with AP 00:02:cf:6a:32:c8 (try 1)
Aug  1 10:32:21 kernel: [ 2181.200866] wlan0: RX AssocResp from 00:02:cf:6a:32:c8 (capab=0x471 status=0 aid=1)
Aug  1 10:32:21 kernel: [ 2181.200870] wlan0: associated
Aug  1 10:32:22 kernel: [ 2181.380009] phy0 -> rt2500pci_set_device_state: Error - Device failed to enter state 1 (-16).
Aug  1 10:32:23 kernel: [ 2182.488009] phy0 -> rt2500pci_set_device_state: Error - Device failed to enter state 1 (-16).
Aug  1 10:32:25 modprobe: FATAL: Could not read '/lib/modules/2.6.32-5-486/kernel/crypto/aes_generic.ko': No such file or directory
Aug  1 10:32:25 kernel: [ 2184.364515] wlan0: deauthenticating from 00:02:cf:6a:32:c8 by local choice (reason=1)
Aug  1 10:33:09 kernel: [ 2228.680424] ADDRCONF(NETDEV_UP): wlan0: link is not ready
Aug  1 10:33:11 kernel: [ 2230.624141] wlan0: direct probe to AP 00:02:cf:6a:32:c8 (try 1)
Aug  1 10:33:11 kernel: [ 2230.625693] wlan0: direct probe responded
Aug  1 10:33:11 kernel: [ 2230.625698] wlan0: authenticate with AP 00:02:cf:6a:32:c8 (try 1)
Aug  1 10:33:11 kernel: [ 2230.629150] wlan0: authenticated
Aug  1 10:33:11 kernel: [ 2230.629167] wlan0: associate with AP 00:02:cf:6a:32:c8 (try 1)
Aug  1 10:33:11 kernel: [ 2230.633105] wlan0: RX AssocResp from 00:02:cf:6a:32:c8 (capab=0x471 status=0 aid=1)
Aug  1 10:33:11 kernel: [ 2230.633110] wlan0: associated
Aug  1 10:33:11 kernel: [ 2230.633904] ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready
Aug  1 10:33:11 kernel: [ 2230.812010] phy0 -> rt2500pci_set_device_state: Error - Device failed to enter state 1 (-16).
Aug  1 10:33:13 kernel: [ 2232.568009] phy0 -> rt2500pci_set_device_state: Error - Device failed to enter state 1 (-16).
Aug  1 10:33:15 modprobe: FATAL: Could not read '/lib/modules/2.6.32-5-486/kernel/crypto/aes_generic.ko': No such file or directory
Aug  1 10:33:15 kernel: [ 2234.337429] wlan0: deauthenticating from 00:02:cf:6a:32:c8 by local choice (reason=1)
Aug  1 10:33:20 kernel: [ 2240.180138] wlan0: direct probe to AP 00:02:cf:6a:32:c8 (try 1)
Aug  1 10:33:20 kernel: [ 2240.181605] wlan0: direct probe responded
Aug  1 10:33:20 kernel: [ 2240.181609] wlan0: authenticate with AP 00:02:cf:6a:32:c8 (try 1)
Aug  1 10:33:20 kernel: [ 2240.185431] wlan0: authenticated
Aug  1 10:33:20 kernel: [ 2240.185447] wlan0: associate with AP 00:02:cf:6a:32:c8 (try 1)
Aug  1 10:33:20 kernel: [ 2240.189275] wlan0: RX AssocResp from 00:02:cf:6a:32:c8 (capab=0x471 status=0 aid=1)
Aug  1 10:33:20 kernel: [ 2240.189280] wlan0: associated
Aug  1 10:33:21 kernel: [ 2240.368009] phy0 -> rt2500pci_set_device_state: Error - Device failed to enter state 1 (-16).
Aug  1 10:33:22 kernel: [ 2241.480009] phy0 -> rt2500pci_set_device_state: Error - Device failed to enter state 1 (-16).
Aug  1 10:33:24 modprobe: FATAL: Could not read '/lib/modules/2.6.32-5-486/kernel/crypto/aes_generic.ko': No such file or directory
Aug  1 10:33:24 kernel: [ 2243.349665] wlan0: deauthenticating from 00:02:cf:6a:32:c8 by local choice (reason=1)
Aug  1 10:33:24 kernel: [ 2243.528010] phy0 -> rt2500pci_set_device_state: Error - Device failed to enter state 1 (-16).
Aug  1 10:33:29 kernel: [ 2249.192139] wlan0: direct probe to AP 00:02:cf:6a:32:c8 (try 1)
Aug  1 10:33:29 kernel: [ 2249.193626] wlan0: direct probe responded
Aug  1 10:33:29 kernel: [ 2249.193630] wlan0: authenticate with AP 00:02:cf:6a:32:c8 (try 1)
Aug  1 10:33:29 kernel: [ 2249.197136] wlan0: authenticated
Aug  1 10:33:29 kernel: [ 2249.197152] wlan0: associate with AP 00:02:cf:6a:32:c8 (try 1)
Aug  1 10:33:29 kernel: [ 2249.200992] wlan0: RX AssocResp from 00:02:cf:6a:32:c8 (capab=0x471 status=0 aid=1)
Aug  1 10:33:29 kernel: [ 2249.200996] wlan0: associated
Aug  1 10:33:30 kernel: [ 2249.380007] phy0 -> rt2500pci_set_device_state: Error - Device failed to enter state 1 (-16).
Aug  1 10:33:31 kernel: [ 2250.324114] wlan0: deauthenticating from 00:02:cf:6a:32:c8 by local choice (reason=3)
Aug  1 10:34:06 kernel: [ 2285.526574] FAT: utf8 is not a recommended IO charset for FAT filesystems, filesystem will be case sensitive!
Aug  1 10:34:21 kernel: [ 2301.216428] ADDRCONF(NETDEV_UP): wlan0: link is not ready
Aug  1 10:34:23 kernel: [ 2303.280306] wlan0: direct probe to AP 00:02:cf:6a:32:c8 (try 1)
Aug  1 10:34:23 kernel: [ 2303.281868] wlan0: direct probe responded
Aug  1 10:34:23 kernel: [ 2303.281873] wlan0: authenticate with AP 00:02:cf:6a:32:c8 (try 1)
Aug  1 10:34:23 kernel: [ 2303.285747] wlan0: authenticated
Aug  1 10:34:23 kernel: [ 2303.285763] wlan0: associate with AP 00:02:cf:6a:32:c8 (try 1)
Aug  1 10:34:23 kernel: [ 2303.289541] wlan0: RX AssocResp from 00:02:cf:6a:32:c8 (capab=0x471 status=0 aid=1)
Aug  1 10:34:23 kernel: [ 2303.289545] wlan0: associated
Aug  1 10:34:23 kernel: [ 2303.290351] ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready
Aug  1 10:34:24 kernel: [ 2303.468009] phy0 -> rt2500pci_set_device_state: Error - Device failed to enter state 1 (-16).
Aug  1 10:34:25 kernel: [ 2304.564007] phy0 -> rt2500pci_set_device_state: Error - Device failed to enter state 1 (-16).
Aug  1 10:34:27 modprobe: FATAL: Could not read '/lib/modules/2.6.32-5-486/kernel/crypto/aes_generic.ko': No such file or directory
Aug  1 10:34:27 kernel: [ 2306.333773] wlan0: deauthenticating from 00:02:cf:6a:32:c8 by local choice (reason=1)
Aug  1 10:34:27 kernel: [ 2306.512009] phy0 -> rt2500pci_set_device_state: Error - Device failed to enter state 1 (-16).
Aug  1 10:34:32 kernel: [ 2312.172439] wlan0: direct probe to AP 00:02:cf:6a:32:c8 (try 1)
Aug  1 10:34:32 kernel: [ 2312.173936] wlan0: direct probe responded
Aug  1 10:34:32 kernel: [ 2312.173940] wlan0: authenticate with AP 00:02:cf:6a:32:c8 (try 1)
Aug  1 10:34:32 kernel: [ 2312.177545] wlan0: authenticated
Aug  1 10:34:32 kernel: [ 2312.177560] wlan0: associate with AP 00:02:cf:6a:32:c8 (try 1)
Aug  1 10:34:32 kernel: [ 2312.180913] wlan0: RX AssocResp from 00:02:cf:6a:32:c8 (capab=0x471 status=0 aid=1)
Aug  1 10:34:32 kernel: [ 2312.180917] wlan0: associated
Aug  1 10:34:33 kernel: [ 2312.360009] phy0 -> rt2500pci_set_device_state: Error - Device failed to enter state 1 (-16).
Aug  1 10:34:34 kernel: [ 2313.576007] phy0 -> rt2500pci_set_device_state: Error - Device failed to enter state 1 (-16).
Aug  1 10:34:36 modprobe: FATAL: Could not read '/lib/modules/2.6.32-5-486/kernel/crypto/aes_generic.ko': No such file or directory
Aug  1 10:34:36 kernel: [ 2315.346155] wlan0: deauthenticating from 00:02:cf:6a:32:c8 by local choice (reason=1)
Aug  1 10:34:36 kernel: [ 2315.524010] phy0 -> rt2500pci_set_device_state: Error - Device failed to enter state 1 (-16).
Aug  1 10:34:41 kernel: [ 2321.184399] wlan0: direct probe to AP 00:02:cf:6a:32:c8 (try 1)
Aug  1 10:34:41 kernel: [ 2321.185912] wlan0: direct probe responded
Aug  1 10:34:41 kernel: [ 2321.185917] wlan0: authenticate with AP 00:02:cf:6a:32:c8 (try 1)
Aug  1 10:34:41 kernel: [ 2321.189307] wlan0: authenticated
Aug  1 10:34:41 kernel: [ 2321.189321] wlan0: associate with AP 00:02:cf:6a:32:c8 (try 1)
Aug  1 10:34:41 kernel: [ 2321.192856] wlan0: RX AssocResp from 00:02:cf:6a:32:c8 (capab=0x471 status=0 aid=1)
Aug  1 10:34:41 kernel: [ 2321.192860] wlan0: associated
Aug  1 10:34:42 kernel: [ 2321.372052] phy0 -> rt2500pci_set_device_state: Error - Device failed to enter state 1 (-16).
Aug  1 10:34:43 kernel: [ 2322.488009] phy0 -> rt2500pci_set_device_state: Error - Device failed to enter state 1 (-16).
Aug  1 10:34:44 kernel: [ 2324.016117] wlan0: deauthenticating from 00:02:cf:6a:32:c8 by local choice (reason=3)
Initializing interface 'wlan0' conf '/etc/wpa.conf' driver 'wext' ctrl_interface 'N/A' bridge 'N/A'
Configuration file '/etc/wpa.conf' -> '/etc/wpa.conf'
Reading configuration file '/etc/wpa.conf'
ap_scan=1
fast_reauth=1
Line: 3 - start of a new network block
priority=100 (0x64)
ssid - hexdump_ascii(len=9):
     5a 79 58 45 4c 2d 4d 6e 4d                        ZyXEL-MnM       
scan_ssid=1 (0x1)
key_mgmt: 0x2
PSK (ASCII passphrase) - hexdump_ascii(len=41): [REMOVED]
PSK (from passphrase) - hexdump(len=32): [REMOVED]
Priority group 100
   id=0 ssid='ZyXEL-MnM'
Initializing interface (2) 'wlan0'
WEXT: cfg80211-based driver detected
Interface wlan0 set UP - waiting a second for the driver to complete initialization
SIOCGIWRANGE: WE(compiled)=22 WE(source)=21 enc_capa=0xf
  capabilities: key_mgmt 0xf enc 0xf flags 0x0
WEXT: Operstate: linkmode=1, operstate=5
Own MAC address: 00:11:50:90:41:9b
wpa_driver_wext_set_wpa
wpa_driver_wext_set_key: alg=0 key_idx=0 set_tx=0 seq_len=0 key_len=0
wpa_driver_wext_set_key: alg=0 key_idx=1 set_tx=0 seq_len=0 key_len=0
wpa_driver_wext_set_key: alg=0 key_idx=2 set_tx=0 seq_len=0 key_len=0
wpa_driver_wext_set_key: alg=0 key_idx=3 set_tx=0 seq_len=0 key_len=0
wpa_driver_wext_set_countermeasures
wpa_driver_wext_set_drop_unencrypted
RSN: flushing PMKID list in the driver
Setting scan request: 0 sec 100000 usec
WPS: UUID based on MAC address - hexdump(len=16): 88 ef 2a a6 5d a1 5b a7 a8 99 88 1e 34 b0 23 12
WPS: Build Beacon and Probe Response IEs
WPS:  * Version
WPS:  * Wi-Fi Protected Setup State (0)
WPS:  * Version
WPS:  * Wi-Fi Protected Setup State (0)
WPS:  * Response Type (2)
WPS:  * UUID-E
WPS:  * Manufacturer
WPS:  * Model Name
WPS:  * Model Number
WPS:  * Serial Number
WPS:  * Primary Device Type
WPS:  * Device Name
WPS:  * Config Methods (0)
WPS:  * RF Bands (3)
EAPOL: SUPP_PAE entering state DISCONNECTED
EAPOL: KEY_RX entering state NO_KEY_RECEIVE
EAPOL: SUPP_BE entering state INITIALIZE
EAP: EAP entering state DISABLED
Added interface wlan0
RTM_NEWLINK: operstate=0 ifi_flags=0x1002 ()
RTM_NEWLINK, IFLA_IFNAME: Interface 'wlan0' added
RTM_NEWLINK: operstate=0 ifi_flags=0x1003 ([UP])
RTM_NEWLINK, IFLA_IFNAME: Interface 'wlan0' added
RTM_NEWLINK: operstate=0 ifi_flags=0x1003 ([UP])
RTM_NEWLINK, IFLA_IFNAME: Interface 'wlan0' added
Wireless event: cmd=0x8b06 len=8
State: DISCONNECTED -> SCANNING
Starting AP scan (specific SSID)
Scan SSID - hexdump_ascii(len=9):
     5a 79 58 45 4c 2d 4d 6e 4d                        ZyXEL-MnM       
Trying to get current scan results first without requesting a new scan to speed up initial association
Received 0 bytes of scan results (0 BSSes)
Cached scan results are empty - not posting
Selecting BSS from priority group 100
Try to find WPA-enabled AP
Try to find non-WPA AP
No suitable AP found.
Setting scan request: 0 sec 0 usec
Starting AP scan (broadcast SSID)
Scan requested (ret=0) - scan timeout 5 seconds
EAPOL: disable timer tick
RTM_NEWLINK: operstate=0 ifi_flags=0x1003 ([UP])
RTM_NEWLINK, IFLA_IFNAME: Interface 'wlan0' added
Wireless event: cmd=0x8b19 len=8
Received 683 bytes of scan results (2 BSSes)
New scan results available
Selecting BSS from priority group 100
Try to find WPA-enabled AP
0: 00:02:cf:6a:32:c8 ssid='ZyXEL-MnM' wpa_ie_len=22 rsn_ie_len=20 caps=0x11
   selected based on RSN IE
   selected WPA AP 00:02:cf:6a:32:c8 ssid='ZyXEL-MnM'
Trying to associate with 00:02:cf:6a:32:c8 (SSID='ZyXEL-MnM' freq=2437 MHz)
Cancelling scan request
WPA: clearing own WPA/RSN IE
Automatic auth_alg selection: 0x1
RSN: using IEEE 802.11i/D9.0
WPA: Selected cipher suites: group 8 pairwise 16 key_mgmt 2 proto 2
WPA: set AP WPA IE - hexdump(len=24): dd 16 00 50 f2 01 01 00 00 50 f2 02 01 00 00 50 f2 02 01 00 00 50 f2 02
WPA: set AP RSN IE - hexdump(len=22): 30 14 01 00 00 0f ac 02 01 00 00 0f ac 04 01 00 00 0f ac 02 01 00
WPA: using GTK TKIP
WPA: using PTK CCMP
WPA: using KEY_MGMT WPA-PSK
WPA: Set own WPA IE default - hexdump(len=22): 30 14 01 00 00 0f ac 02 01 00 00 0f ac 04 01 00 00 0f ac 02 00 00
No keys have been configured - skip key clearing
wpa_driver_wext_set_drop_unencrypted
State: SCANNING -> ASSOCIATING
wpa_driver_wext_set_operstate: operstate 0->0 (DORMANT)
WEXT: Operstate: linkmode=-1, operstate=5
wpa_driver_wext_associate
wpa_driver_wext_set_psk
Setting authentication timeout: 10 sec 0 usec
EAPOL: External notification - EAP success=0
EAPOL: External notification - EAP fail=0
EAPOL: External notification - portControl=Auto
RSN: added PMKSA cache candidate 00:02:cf:6a:32:c8 prio 1000
RSN: processing PMKSA candidate list
RSN: not in suitable state for new pre-authentication
RTM_NEWLINK: operstate=0 ifi_flags=0x1003 ([UP])
RTM_NEWLINK, IFLA_IFNAME: Interface 'wlan0' added
Wireless event: cmd=0x8b06 len=8
RTM_NEWLINK: operstate=0 ifi_flags=0x1003 ([UP])
RTM_NEWLINK, IFLA_IFNAME: Interface 'wlan0' added
Wireless event: cmd=0x8b04 len=12
RTM_NEWLINK: operstate=0 ifi_flags=0x11003 ([UP][LOWER_UP])
RTM_NEWLINK, IFLA_IFNAME: Interface 'wlan0' added
RTM_NEWLINK: operstate=0 ifi_flags=0x11003 ([UP][LOWER_UP])
RTM_NEWLINK, IFLA_IFNAME: Interface 'wlan0' added
Wireless event: cmd=0x8c08 len=24
AssocResp IE wireless event - hexdump(len=16): 01 08 82 84 8b 96 0c 12 18 24 32 04 30 48 60 6c
RTM_NEWLINK: operstate=0 ifi_flags=0x11003 ([UP][LOWER_UP])
RTM_NEWLINK, IFLA_IFNAME: Interface 'wlan0' added
Wireless event: cmd=0x8b15 len=20
Wireless event: new AP: 00:02:cf:6a:32:c8
Association info event
resp_ies - hexdump(len=16): 01 08 82 84 8b 96 0c 12 18 24 32 04 30 48 60 6c
State: ASSOCIATING -> ASSOCIATED
wpa_driver_wext_set_operstate: operstate 0->0 (DORMANT)
WEXT: Operstate: linkmode=-1, operstate=5
Associated to a new BSS: BSSID=00:02:cf:6a:32:c8
No keys have been configured - skip key clearing
Associated with 00:02:cf:6a:32:c8
WPA: Association event - clear replay counter
WPA: Clear old PTK
EAPOL: External notification - portEnabled=0
EAPOL: External notification - portValid=0
EAPOL: External notification - EAP success=0
EAPOL: External notification - portEnabled=1
EAPOL: SUPP_PAE entering state CONNECTING
EAPOL: enable timer tick
EAPOL: SUPP_BE entering state IDLE
Setting authentication timeout: 10 sec 0 usec
Cancelling scan request
EAPOL: startWhen --> 0
EAPOL: disable timer tick
EAPOL: SUPP_PAE entering state CONNECTING
EAPOL: enable timer tick
EAPOL: txStart
WPA: drop TX EAPOL in non-IEEE 802.1X mode (type=1 len=0)
RX EAPOL from 00:02:cf:6a:32:c8
RX EAPOL - hexdump(len=99): 01 03 00 5f 02 00 8a 00 10 00 00 00 00 00 00 00 01 cc f3 25 36 99 db 84 1b 80 9f 66 93 36 53 4f e8 ad 36 76 16 10 61 e8 04 3f 2e 01 96 ba a8 1c 64 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
Setting authentication timeout: 10 sec 0 usec
IEEE 802.1X RX: version=1 type=3 length=95
  EAPOL-Key type=2
  key_info 0x8a (ver=2 keyidx=0 rsvd=0 Pairwise Ack)
  key_length=16 key_data_length=0
  replay_counter - hexdump(len=8): 00 00 00 00 00 00 00 01
  key_nonce - hexdump(len=32): cc f3 25 36 99 db 84 1b 80 9f 66 93 36 53 4f e8 ad 36 76 16 10 61 e8 04 3f 2e 01 96 ba a8 1c 64
  key_iv - hexdump(len=16): 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
  key_rsc - hexdump(len=8): 00 00 00 00 00 00 00 00
  key_id (reserved) - hexdump(len=8): 00 00 00 00 00 00 00 00
  key_mic - hexdump(len=16): 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
WPA: RX EAPOL-Key - hexdump(len=99): 01 03 00 5f 02 00 8a 00 10 00 00 00 00 00 00 00 01 cc f3 25 36 99 db 84 1b 80 9f 66 93 36 53 4f e8 ad 36 76 16 10 61 e8 04 3f 2e 01 96 ba a8 1c 64 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
State: ASSOCIATED -> 4WAY_HANDSHAKE
WPA: RX message 1 of 4-Way Handshake from 00:02:cf:6a:32:c8 (ver=2)
RSN: msg 1/4 key data - hexdump(len=0):
WPA: Renewed SNonce - hexdump(len=32): da 7e 6f 07 78 4f fb ef 0a a2 09 13 32 bc 45 4e f3 84 a9 74 a8 f9 86 c0 29 34 af 2e 26 9b b3 6b
WPA: PTK derivation - A1=00:11:50:90:41:9b A2=00:02:cf:6a:32:c8
WPA: PMK - hexdump(len=32): [REMOVED]
WPA: PTK - hexdump(len=48): [REMOVED]
WPA: WPA IE for msg 2/4 - hexdump(len=22): 30 14 01 00 00 0f ac 02 01 00 00 0f ac 04 01 00 00 0f ac 02 00 00
WPA: Sending EAPOL-Key 2/4
WPA: TX EAPOL-Key - hexdump(len=121): 01 03 00 75 02 01 0a 00 00 00 00 00 00 00 00 00 01 da 7e 6f 07 78 4f fb ef 0a a2 09 13 32 bc 45 4e f3 84 a9 74 a8 f9 86 c0 29 34 af 2e 26 9b b3 6b 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 21 ee 85 15 81 c8 e4 f5 b5 d4 db 66 01 e2 d6 cc 00 16 30 14 01 00 00 0f ac 02 01 00 00 0f ac 04 01 00 00 0f ac 02 00 00
RX EAPOL from 00:02:cf:6a:32:c8
RX EAPOL - hexdump(len=171): 01 03 00 a7 02 13 ca 00 10 00 00 00 00 00 00 00 02 cc f3 25 36 99 db 84 1b 80 9f 66 93 36 53 4f e8 ad 36 76 16 10 61 e8 04 3f 2e 01 96 ba a8 1c 64 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 87 e9 65 aa 76 d6 cd 3c 52 9f f4 78 72 54 c0 d3 00 48 4c 1c 26 ae 5c d1 66 e8 cd a9 40 e2 66 eb 1d e5 98 3d a3 ec 95 53 78 01 a5 b0 35 a9 56 d0 00 8f cc f7 2f 55 bb 70 13 1c 40 ae 92 dc a3 58 42 fe 45 6b c3 29 ea 65 15 d6 1d a9 77 da 94 76 37 c0 3c 83 be 77 71 a9 69 d2
IEEE 802.1X RX: version=1 type=3 length=167
  EAPOL-Key type=2
  key_info 0x13ca (ver=2 keyidx=0 rsvd=0 Pairwise Install Ack MIC Secure Encr)
  key_length=16 key_data_length=72
  replay_counter - hexdump(len=8): 00 00 00 00 00 00 00 02
  key_nonce - hexdump(len=32): cc f3 25 36 99 db 84 1b 80 9f 66 93 36 53 4f e8 ad 36 76 16 10 61 e8 04 3f 2e 01 96 ba a8 1c 64
  key_iv - hexdump(len=16): 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
  key_rsc - hexdump(len=8): 00 00 00 00 00 00 00 00
  key_id (reserved) - hexdump(len=8): 00 00 00 00 00 00 00 00
  key_mic - hexdump(len=16): 87 e9 65 aa 76 d6 cd 3c 52 9f f4 78 72 54 c0 d3
WPA: RX EAPOL-Key - hexdump(len=171): 01 03 00 a7 02 13 ca 00 10 00 00 00 00 00 00 00 02 cc f3 25 36 99 db 84 1b 80 9f 66 93 36 53 4f e8 ad 36 76 16 10 61 e8 04 3f 2e 01 96 ba a8 1c 64 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 87 e9 65 aa 76 d6 cd 3c 52 9f f4 78 72 54 c0 d3 00 48 4c 1c 2ioctl[SIOCSIWENCODEEXT]: Cannot allocate memory
6 ae 5c d1 66 e8 cd a9 40 e2 66 eb 1d e5 98 3d a3 ec 95 53 78 01 a5 b0 35 a9 56 d0 00 8f cc f7 2f 55 bb 70 13 1c 40 ae 92 dc a3 58 42 fe 45 6b c3 29 ea 65 15 d6 1d a9 77 da 94 76 37 c0 3c 83 be 77 71 a9 69 d2
RSN: encrypted key data - hexdump(len=72): 4c 1c 26 ae 5c d1 66 e8 cd a9 40 e2 66 eb 1d e5 98 3d a3 ec 95 53 78 01 a5 b0 35 a9 56 d0 00 8f cc f7 2f 55 bb 70 13 1c 40 ae 92 dc a3 58 42 fe 45 6b c3 29 ea 65 15 d6 1d a9 77 da 94 76 37 c0 3c 83 be 77 71 a9 69 d2
WPA: decrypted EAPOL-Key key data - hexdump(len=64): [REMOVED]
State: 4WAY_HANDSHAKE -> 4WAY_HANDSHAKE
WPA: RX message 3 of 4-Way Handshake from 00:02:cf:6a:32:c8 (ver=2)
WPA: IE KeyData - hexdump(len=64): 30 14 01 00 00 0f ac 02 01 00 00 0f ac 04 01 00 00 0f ac 02 01 00 dd 26 00 0f ac 01 01 00 ca bb 2d 2b 8b 8d 0e f1 14 89 76 9c 66 0a fd 77 bc c9 37 73 c1 86 a9 10 b5 a9 47 14 25 eb b0 b1 dd 00
WPA: Sending EAPOL-Key 4/4
WPA: TX EAPOL-Key - hexdump(len=99): 01 03 00 5f 02 03 0a 00 00 00 00 00 00 00 00 00 02 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 ef a6 2a ba ab eb a1 f2 cc 0f 88 93 4b 2b 44 a2 00 00
WPA: Installing PTK to the driver.
wpa_driver_wext_set_key: alg=3 key_idx=0 set_tx=1 seq_len=6 key_len=16
Driver did not support SIOCSIWENCODEEXT
WPA: Failed to set PTK to the driver (alg=3 keylen=16 bssid=00:02:cf:6a:32:c8)
wpa_driver_wext_deauthenticate
wpa_driver_wext_set_key: alg=0 key_idx=0 set_tx=0 seq_len=0 key_len=0
wpa_driver_wext_set_key: alg=0 key_idx=1 set_tx=0 seq_len=0 key_len=0
wpa_driver_wext_set_key: alg=0 key_idx=2 set_tx=0 seq_len=0 key_len=0
wpa_driver_wext_set_key: alg=0 key_idx=3 set_tx=0 seq_len=0 key_len=0
wpa_driver_wext_set_key: alg=0 key_idx=0 set_tx=0 seq_len=0 key_len=0
State: 4WAY_HANDSHAKE -> DISCONNECTED
wpa_driver_wext_set_operstate: operstate 0->0 (DORMANT)
WEXT: Operstate: linkmode=-1, operstate=5
EAPOL: External notification - portEnabled=0
EAPOL: SUPP_PAE entering state DISCONNECTED
EAPOL: SUPP_BE entering state INITIALIZE
EAPOL: External notification - portValid=0
EAPOL: External notification - EAP success=0
Setting scan request: 5 sec 0 usec
RTM_NEWLINK: operstate=0 ifi_flags=0x1003 ([UP])
RTM_NEWLINK, IFLA_IFNAME: Interface 'wlan0' added
RTM_NEWLINK: operstate=0 ifi_flags=0x1003 ([UP])
RTM_NEWLINK, IFLA_IFNAME: Interface 'wlan0' added
Wireless event: cmd=0x8b15 len=20
Wireless event: new AP: 00:00:00:00:00:00
Added BSSID 00:00:00:00:00:00 into blacklist
CTRL-EVENT-DISCONNECTED - Disconnect event - remove keys
wpa_driver_wext_set_key: alg=0 key_idx=0 set_tx=0 seq_len=0 key_len=0
wpa_driver_wext_set_key: alg=0 key_idx=1 set_tx=0 seq_len=0 key_len=0
wpa_driver_wext_set_key: alg=0 key_idx=2 set_tx=0 seq_len=0 key_len=0
wpa_driver_wext_set_key: alg=0 key_idx=3 set_tx=0 seq_len=0 key_len=0
wpa_driver_wext_set_key: alg=0 key_idx=0 set_tx=0 seq_len=0 key_len=0
State: DISCONNECTED -> DISCONNECTED
wpa_driver_wext_set_operstate: operstate 0->0 (DORMANT)
WEXT: Operstate: linkmode=-1, operstate=5
EAPOL: External notification - portEnabled=0
EAPOL: External notification - portValid=0
EAPOL: External notification - EAP success=0
State: DISCONNECTED -> SCANNING
Starting AP scan (specific SSID)
Scan SSID - hexdump_ascii(len=9):
     5a 79 58 45 4c 2d 4d 6e 4d                        ZyXEL-MnM       
Scan requested (ret=0) - scan timeout 30 seconds
RTM_NEWLINK: operstate=0 ifi_flags=0x1003 ([UP])
RTM_NEWLINK, IFLA_IFNAME: Interface 'wlan0' added
Wireless event: cmd=0x8b19 len=8
Received 683 bytes of scan results (2 BSSes)
New scan results available
Selecting BSS from priority group 100
Try to find WPA-enabled AP
0: 00:02:cf:6a:32:c8 ssid='ZyXEL-MnM' wpa_ie_len=22 rsn_ie_len=20 caps=0x11
   selected based on RSN IE
   selected WPA AP 00:02:cf:6a:32:c8 ssid='ZyXEL-MnM'
Trying to associate with 00:02:cf:6a:32:c8 (SSID='ZyXEL-MnM' freq=2437 MHz)
Cancelling scan request
WPA: clearing own WPA/RSN IE
Automatic auth_alg selection: 0x1
RSN: using IEEE 802.11i/D9.0
WPA: Selected cipher suites: group 8 pairwise 16 key_mgmt 2 proto 2
WPA: set AP WPA IE - hexdump(len=24): dd 16 00 50 f2 01 01 00 00 50 f2 02 01 00 00 50 f2 02 01 00 00 50 f2 02
WPA: set AP RSN IE - hexdump(len=22): 30 14 01 00 00 0f ac 02 01 00 00 0f ac 04 01 00 00 0f ac 02 01 00
WPA: using GTK TKIP
WPA: using PTK CCMP
WPA: using KEY_MGMT WPA-PSK
WPA: Set own WPA IE default - hexdump(len=22): 30 14 01 00 00 0f ac 02 01 00 00 0f ac 04 01 00 00 0f ac 02 00 00
No keys have been configured - skip key clearing
wpa_driver_wext_set_drop_unencrypted
State: SCANNING -> ASSOCIATING
wpa_driver_wext_set_operstate: operstate 0->0 (DORMANT)
WEXT: Operstate: linkmode=-1, operstate=5
wpa_driver_wext_associate
wpa_driver_wext_set_psk
Setting authentication timeout: 10 sec 0 usec
EAPOL: External notification - EAP success=0
EAPOL: External notification - EAP fail=0
EAPOL: External notification - portControl=Auto
RTM_NEWLINK: operstate=0 ifi_flags=0x1003 ([UP])
RTM_NEWLINK, IFLA_IFNAME: Interface 'wlan0' added
Wireless event: cmd=0x8b06 len=8
RTM_NEWLINK: operstate=0 ifi_flags=0x1003 ([UP])
RTM_NEWLINK, IFLA_IFNAME: Interface 'wlan0' added
Wireless event: cmd=0x8b04 len=12
RTM_NEWLINK: operstate=0 ifi_flags=0x11003 ([UP][LOWER_UP])
RTM_NEWLINK, IFLA_IFNAME: Interface 'wlan0' added
RTM_NEWLINK: operstate=0 ifi_flags=0x11003 ([UP][LOWER_UP])
RTM_NEWLINK, IFLA_IFNAME: Interface 'wlan0' added
Wireless event: cmd=0x8c08 len=24
AssocResp IE wireless event - hexdump(len=16): 01 08 82 84 8b 96 0c 12 18 24 32 04 30 48 60 6c
RTM_NEWLINK: operstate=0 ifi_flags=0x11003 ([UP][LOWER_UP])
RTM_NEWLINK, IFLA_IFNAME: Interface 'wlan0' added
Wireless event: cmd=0x8b15 len=20
Wireless event: new AP: 00:02:cf:6a:32:c8
Association info event
resp_ies - hexdump(len=16): 01 08 82 84 8b 96 0c 12 18 24 32 04 30 48 60 6c
State: ASSOCIATING -> ASSOCIATED
wpa_driver_wext_set_operstate: operstate 0->0 (DORMANT)
WEXT: Operstate: linkmode=-1, operstate=5
Associated to a new BSS: BSSID=00:02:cf:6a:32:c8
No keys have been configured - skip key clearing
Associated with 00:02:cf:6a:32:c8
WPA: Association event - clear replay counter
WPA: Clear old PTK
EAPOL: External notification - portEnabled=0
EAPOL: External notification - portValid=0
EAPOL: External notification - EAP success=0
EAPOL: External notification - portEnabled=1
EAPOL: SUPP_PAE entering state CONNECTING
EAPOL: SUPP_BE entering state IDLE
Setting authentication timeout: 10 sec 0 usec
Cancelling scan request
EAPOL: startWhen --> 0
EAPOL: disable timer tick
EAPOL: SUPP_PAE entering state CONNECTING
EAPOL: enable timer tick
EAPOL: txStart
WPA: drop TX EAPOL in non-IEEE 802.1X mode (type=1 len=0)
RX EAPOL from 00:02:cf:6a:32:c8
RX EAPOL - hexdump(len=99): 01 03 00 5f 02 00 8a 00 10 00 00 00 00 00 00 00 01 fb fc 98 07 b2 1e 4d bf 18 a7 09 8e c8 9c 79 af ce 64 87 24 6f 2b 35 24 f3 2b 5f 1b 47 fe 0b bb 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
Setting authentication timeout: 10 sec 0 usec
IEEE 802.1X RX: version=1 type=3 length=95
  EAPOL-Key type=2
  key_info 0x8a (ver=2 keyidx=0 rsvd=0 Pairwise Ack)
  key_length=16 key_data_length=0
  replay_counter - hexdump(len=8): 00 00 00 00 00 00 00 01
  key_nonce - hexdump(len=32): fb fc 98 07 b2 1e 4d bf 18 a7 09 8e c8 9c 79 af ce 64 87 24 6f 2b 35 24 f3 2b 5f 1b 47 fe 0b bb
  key_iv - hexdump(len=16): 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
  key_rsc - hexdump(len=8): 00 00 00 00 00 00 00 00
  key_id (reserved) - hexdump(len=8): 00 00 00 00 00 00 00 00
  key_mic - hexdump(len=16): 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
WPA: RX EAPOL-Key - hexdump(len=99): 01 03 00 5f 02 00 8a 00 10 00 00 00 00 00 00 00 01 fb fc 98 07 b2 1e 4d bf 18 a7 09 8e c8 9c 79 af ce 64 87 24 6f 2b 35 24 f3 2b 5f 1b 47 fe 0b bb 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
State: ASSOCIATED -> 4WAY_HANDSHAKE
WPA: RX message 1 of 4-Way Handshake from 00:02:cf:6a:32:c8 (ver=2)
RSN: msg 1/4 key data - hexdump(len=0):
WPA: Renewed SNonce - hexdump(len=32): 25 50 7e 46 df 1f be 66 7d 18 a6 f3 79 24 b3 35 a2 ff ba e5 ae 81 70 a7 48 02 06 6d a1 b9 4e 78
WPA: PTK derivation - A1=00:11:50:90:41:9b A2=00:02:cf:6a:32:c8
WPA: PMK - hexdump(len=32): [REMOVED]
WPA: PTK - hexdump(len=48): [REMOVED]
WPA: WPA IE for msg 2/4 - hexdump(len=22): 30 14 01 00 00 0f ac 02 01 00 00 0f ac 04 01 00 00 0f ac 02 00 00
WPA: Sending EAPOL-Key 2/4
WPA: TX EAPOL-Key - hexdump(len=121): 01 03 00 75 02 01 0a 00 00 00 00 00 00 00 00 00 01 25 50 7e 46 df 1f be 66 7d 18 a6 f3 79 24 b3 35 a2 ff ba e5 ae 81 70 a7 48 02 06 6d a1 b9 4e 78 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 25 7f d1 64 2f e6 9a 77 1b 91 ab e6 df 6c 0d 40 00 16 30 14 01 00 00 0f ac 02 01 00 00 0f ac 04 01 00 00 0f ac 02 00 00
RX EAPOL from 00:02:cf:6a:32:c8
RX EAPOL - hexdump(len=171): 01 03 00 a7 02 13 ca 00 10 00 00 00 00 00 00 00 02 fb fc 98 07 b2 1e 4d bf 18 a7 09 8e c8 9c 79 af ce 64 87 24 6f 2b 35 24 f3 2b 5f 1b 47 fe 0b bb 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 ed a0 1e a1 46 6b 66 a4 61 1e 0c 96 76 65 5a 9b 00 48 1b 69 ee 4e 51 b2 3b 7d 0c f8 5e 6b 4d 7c 6b e9 05 a5 55 9d 14 88 6f 18 cb 79 d6 f5 c3 8c 3f c8 50 64 7a fd 84 bc c2 e9 2f 68 27 ff 39 17 91 ea cd 99 84 64 5c 39 8c 95 db 5d 85 62 0e a3 fd 7b 66 c1 71 62 c6 df a0 db
IEEE 802.1X RX: version=1 type=3 length=167
  EAPOL-Key type=2
  key_info 0x13ca (ver=2 keyidx=0 rsvd=0 Pairwise Install Ack MIC Secure Encr)
  key_length=16 key_data_length=72
  replay_counter - hexdump(len=8): 00 00 00 00 00 00 00 02
  key_nonce - hexdump(len=32): fb fc 98 07 b2 1e 4d bf 18 a7 09 8e c8 9c 79 af ce 64 87 24 6f 2b 35 24 f3 2b 5f 1b 47 fe 0b bb
  key_iv - hexdump(len=16): 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
  key_rsc - hexdump(len=8): 00 00 00 00 00 00 00 00
  key_id (reserved) - hexdump(len=8): 00 00 00 00 00 00 00 00
  key_mic - hexdump(len=16): ed a0 1e a1 46 6b 66 a4 61 1e 0c 96 76 65 5a 9b
WPA: RX EAPOL-Key - hexdump(len=171):ioctl[SIOCSIWENCODEEXT]: Cannot allocate memory
 01 03 00 a7 02 13 ca 00 10 00 00 00 00 00 00 00 02 fb fc 98 07 b2 1e 4d bf 18 a7 09 8e c8 9c 79 af ce 64 87 24 6f 2b 35 24 f3 2b 5f 1b 47 fe 0b bb 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 ed a0 1e a1 46 6b 66 a4 61 1e 0c 96 76 65 5a 9b 00 48 1b 69 ee 4e 51 b2 3b 7d 0c f8 5e 6b 4d 7c 6b e9 05 a5 55 9d 14 88 6f 18 cb 79 d6 f5 c3 8c 3f c8 50 64 7a fd 84 bc c2 e9 2f 68 27 ff 39 17 91 ea cd 99 84 64 5c 39 8c 95 db 5d 85 62 0e a3 fd 7b 66 c1 71 62 c6 df a0 db
RSN: encrypted key data - hexdump(len=72): 1b 69 ee 4e 51 b2 3b 7d 0c f8 5e 6b 4d 7c 6b e9 05 a5 55 9d 14 88 6f 18 cb 79 d6 f5 c3 8c 3f c8 50 64 7a fd 84 bc c2 e9 2f 68 27 ff 39 17 91 ea cd 99 84 64 5c 39 8c 95 db 5d 85 62 0e a3 fd 7b 66 c1 71 62 c6 df a0 db
WPA: decrypted EAPOL-Key key data - hexdump(len=64): [REMOVED]
State: 4WAY_HANDSHAKE -> 4WAY_HANDSHAKE
WPA: RX message 3 of 4-Way Handshake from 00:02:cf:6a:32:c8 (ver=2)
WPA: IE KeyData - hexdump(len=64): 30 14 01 00 00 0f ac 02 01 00 00 0f ac 04 01 00 00 0f ac 02 01 00 dd 26 00 0f ac 01 01 00 d1 ad 23 d8 23 c4 b5 2e fa 96 00 b9 e1 cc 86 79 76 75 98 40 a7 34 0c 4f 43 d9 de 9d e9 21 cb a7 dd 00
WPA: Sending EAPOL-Key 4/4
WPA: TX EAPOL-Key - hexdump(len=99): 01 03 00 5f 02 03 0a 00 00 00 00 00 00 00 00 00 02 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 2f 44 d5 9a 57 ed d2 f1 92 71 5c f2 f5 13 5f b3 00 00
WPA: Installing PTK to the driver.
wpa_driver_wext_set_key: alg=3 key_idx=0 set_tx=1 seq_len=6 key_len=16
Driver did not support SIOCSIWENCODEEXT
WPA: Failed to set PTK to the driver (alg=3 keylen=16 bssid=00:02:cf:6a:32:c8)
wpa_driver_wext_deauthenticate
wpa_driver_wext_set_key: alg=0 key_idx=0 set_tx=0 seq_len=0 key_len=0
wpa_driver_wext_set_key: alg=0 key_idx=1 set_tx=0 seq_len=0 key_len=0
wpa_driver_wext_set_key: alg=0 key_idx=2 set_tx=0 seq_len=0 key_len=0
wpa_driver_wext_set_key: alg=0 key_idx=3 set_tx=0 seq_len=0 key_len=0
wpa_driver_wext_set_key: alg=0 key_idx=0 set_tx=0 seq_len=0 key_len=0
State: 4WAY_HANDSHAKE -> DISCONNECTED
wpa_driver_wext_set_operstate: operstate 0->0 (DORMANT)
WEXT: Operstate: linkmode=-1, operstate=5
EAPOL: External notification - portEnabled=0
EAPOL: SUPP_PAE entering state DISCONNECTED
EAPOL: SUPP_BE entering state INITIALIZE
EAPOL: External notification - portValid=0
EAPOL: External notification - EAP success=0
Setting scan request: 5 sec 0 usec
RTM_NEWLINK: operstate=0 ifi_flags=0x1003 ([UP])
RTM_NEWLINK, IFLA_IFNAME: Interface 'wlan0' added
RTM_NEWLINK: operstate=0 ifi_flags=0x1003 ([UP])
RTM_NEWLINK, IFLA_IFNAME: Interface 'wlan0' added
Wireless event: cmd=0x8b15 len=20
Wireless event: new AP: 00:00:00:00:00:00
BSSID 00:00:00:00:00:00 blacklist count incremented to 2
CTRL-EVENT-DISCONNECTED - Disconnect event - remove keys
wpa_driver_wext_set_key: alg=0 key_idx=0 set_tx=0 seq_len=0 key_len=0
wpa_driver_wext_set_key: alg=0 key_idx=1 set_tx=0 seq_len=0 key_len=0
wpa_driver_wext_set_key: alg=0 key_idx=2 set_tx=0 seq_len=0 key_len=0
wpa_driver_wext_set_key: alg=0 key_idx=3 set_tx=0 seq_len=0 key_len=0
wpa_driver_wext_set_key: alg=0 key_idx=0 set_tx=0 seq_len=0 key_len=0
State: DISCONNECTED -> DISCONNECTED
wpa_driver_wext_set_operstate: operstate 0->0 (DORMANT)
WEXT: Operstate: linkmode=-1, operstate=5
EAPOL: External notification - portEnabled=0
EAPOL: External notification - portValid=0
EAPOL: External notification - EAP success=0
State: DISCONNECTED -> SCANNING
Starting AP scan (broadcast SSID)
Scan requested (ret=0) - scan timeout 30 seconds
RTM_NEWLINK: operstate=0 ifi_flags=0x1003 ([UP])
RTM_NEWLINK, IFLA_IFNAME: Interface 'wlan0' added
Wireless event: cmd=0x8b19 len=8
Received 683 bytes of scan results (2 BSSes)
New scan results available
Selecting BSS from priority group 100
Try to find WPA-enabled AP
0: 00:02:cf:6a:32:c8 ssid='ZyXEL-MnM' wpa_ie_len=22 rsn_ie_len=20 caps=0x11
   selected based on RSN IE
   selected WPA AP 00:02:cf:6a:32:c8 ssid='ZyXEL-MnM'
Trying to associate with 00:02:cf:6a:32:c8 (SSID='ZyXEL-MnM' freq=2437 MHz)
Cancelling scan request
WPA: clearing own WPA/RSN IE
Automatic auth_alg selection: 0x1
RSN: using IEEE 802.11i/D9.0
WPA: Selected cipher suites: group 8 pairwise 16 key_mgmt 2 proto 2
WPA: set AP WPA IE - hexdump(len=24): dd 16 00 50 f2 01 01 00 00 50 f2 02 01 00 00 50 f2 02 01 00 00 50 f2 02
WPA: set AP RSN IE - hexdump(len=22): 30 14 01 00 00 0f ac 02 01 00 00 0f ac 04 01 00 00 0f ac 02 01 00
WPA: using GTK TKIP
WPA: using PTK CCMP
WPA: using KEY_MGMT WPA-PSK
WPA: Set own WPA IE default - hexdump(len=22): 30 14 01 00 00 0f ac 02 01 00 00 0f ac 04 01 00 00 0f ac 02 00 00
No keys have been configured - skip key clearing
wpa_driver_wext_set_drop_unencrypted
State: SCANNING -> ASSOCIATING
wpa_driver_wext_set_operstate: operstate 0->0 (DORMANT)
WEXT: Operstate: linkmode=-1, operstate=5
wpa_driver_wext_associate
wpa_driver_wext_set_psk
Setting authentication timeout: 10 sec 0 usec
EAPOL: External notification - EAP success=0
EAPOL: External notification - EAP fail=0
EAPOL: External notification - portControl=Auto
RTM_NEWLINK: operstate=0 ifi_flags=0x1003 ([UP])
RTM_NEWLINK, IFLA_IFNAME: Interface 'wlan0' added
Wireless event: cmd=0x8b06 len=8
RTM_NEWLINK: operstate=0 ifi_flags=0x1003 ([UP])
RTM_NEWLINK, IFLA_IFNAME: Interface 'wlan0' added
Wireless event: cmd=0x8b04 len=12
RTM_NEWLINK: operstate=0 ifi_flags=0x11003 ([UP][LOWER_UP])
RTM_NEWLINK, IFLA_IFNAME: Interface 'wlan0' added
RTM_NEWLINK: operstate=0 ifi_flags=0x11003 ([UP][LOWER_UP])
RTM_NEWLINK, IFLA_IFNAME: Interface 'wlan0' added
Wireless event: cmd=0x8c08 len=24
AssocResp IE wireless event - hexdump(len=16): 01 08 82 84 8b 96 0c 12 18 24 32 04 30 48 60 6c
RTM_NEWLINK: operstate=0 ifi_flags=0x11003 ([UP][LOWER_UP])
RTM_NEWLINK, IFLA_IFNAME: Interface 'wlan0' added
Wireless event: cmd=0x8b15 len=20
Wireless event: new AP: 00:02:cf:6a:32:c8
Association info event
resp_ies - hexdump(len=16): 01 08 82 84 8b 96 0c 12 18 24 32 04 30 48 60 6c
State: ASSOCIATING -> ASSOCIATED
wpa_driver_wext_set_operstate: operstate 0->0 (DORMANT)
WEXT: Operstate: linkmode=-1, operstate=5
Associated to a new BSS: BSSID=00:02:cf:6a:32:c8
No keys have been configured - skip key clearing
Associated with 00:02:cf:6a:32:c8
WPA: Association event - clear replay counter
WPA: Clear old PTK
EAPOL: External notification - portEnabled=0
EAPOL: External notification - portValid=0
EAPOL: External notification - EAP success=0
EAPOL: External notification - portEnabled=1
EAPOL: SUPP_PAE entering state CONNECTING
EAPOL: SUPP_BE entering state IDLE
Setting authentication timeout: 10 sec 0 usec
Cancelling scan request
EAPOL: startWhen --> 0
EAPOL: disable timer tick
EAPOL: SUPP_PAE entering state CONNECTING
EAPOL: enable timer tick
EAPOL: txStart
WPA: drop TX EAPOL in non-IEEE 802.1X mode (type=1 len=0)
RX EAPOL from 00:02:cf:6a:32:c8
RX EAPOL - hexdump(len=99): 01 03 00 5f 02 00 8a 00 10 00 00 00 00 00 00 00 01 2c 88 c4 00 d2 f5 48 67 c7 6d 9b 2f 46 76 57 cc 80 eb 3c f3 b4 25 9b 6e 19 07 17 1d 44 0d 7d 09 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
Setting authentication timeout: 10 sec 0 usec
IEEE 802.1X RX: version=1 type=3 length=95
  EAPOL-Key type=2
  key_info 0x8a (ver=2 keyidx=0 rsvd=0 Pairwise Ack)
  key_length=16 key_data_length=0
  replay_counter - hexdump(len=8): 00 00 00 00 00 00 00 01
  key_nonce - hexdump(len=32): 2c 88 c4 00 d2 f5 48 67 c7 6d 9b 2f 46 76 57 cc 80 eb 3c f3 b4 25 9b 6e 19 07 17 1d 44 0d 7d 09
  key_iv - hexdump(len=16): 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
  key_rsc - hexdump(len=8): 00 00 00 00 00 00 00 00
  key_id (reserved) - hexdump(len=8): 00 00 00 00 00 00 00 00
  key_mic - hexdump(len=16): 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
WPA: RX EAPOL-Key - hexdump(len=99): 01 03 00 5f 02 00 8a 00 10 00 00 00 00 00 00 00 01 2c 88 c4 00 d2 f5 48 67 c7 6d 9b 2f 46 76 57 cc 80 eb 3c f3 b4 25 9b 6e 19 07 17 1d 44 0d 7d 09 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
State: ASSOCIATED -> 4WAY_HANDSHAKE
WPA: RX message 1 of 4-Way Handshake from 00:02:cf:6a:32:c8 (ver=2)
RSN: msg 1/4 key data - hexdump(len=0):
WPA: Renewed SNonce - hexdump(len=32): a0 66 d5 11 ca 4d 7f 21 d5 a6 d4 4c 01 fc 00 1e a2 08 80 9f d8 a2 8c c9 22 5d 78 01 a2 b4 2d cd
WPA: PTK derivation - A1=00:11:50:90:41:9b A2=00:02:cf:6a:32:c8
WPA: PMK - hexdump(len=32): [REMOVED]
WPA: PTK - hexdump(len=48): [REMOVED]
WPA: WPA IE for msg 2/4 - hexdump(len=22): 30 14 01 00 00 0f ac 02 01 00 00 0f ac 04 01 00 00 0f ac 02 00 00
WPA: Sending EAPOL-Key 2/4
WPA: TX EAPOL-Key - hexdump(len=121): 01 03 00 75 02 01 0a 00 00 00 00 00 00 00 00 00 01 a0 66 d5 11 ca 4d 7f 21 d5 a6 d4 4c 01 fc 00 1e a2 08 80 9f d8 a2 8c c9 22 5d 78 01 a2 b4 2d cd 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 27 40 d3 c8 dd c8 df 70 c6 bb ec 03 c2 61 90 f2 00 16 30 14 01 00 00 0f ac 02 01 00 00 0f ac 04 01 00 00 0f ac 02 00 00
CTRL-EVENT-TERMINATING - signal 2 received
Removing interface wlan0
wpa_driver_wext_deauthenticate
No keys have been configured - skip key clearing
State: 4WAY_HANDSHAKE -> DISCONNECTED
wpa_driver_wext_set_operstate: operstate 0->0 (DORMANT)
WEXT: Operstate: linkmode=-1, operstate=5
EAPOL: External notification - portEnabled=0
EAPOL: SUPP_PAE entering state DISCONNECTED
EAPOL: SUPP_BE entering state INITIALIZE
EAPOL: External notification - portValid=0
EAPOL: External notification - EAP success=0
wpa_driver_wext_set_wpa
wpa_driver_wext_set_drop_unencrypted
wpa_driver_wext_set_countermeasures
No keys have been configured - skip key clearing
Removed BSSID 00:00:00:00:00:00 from blacklist (clear)
Cancelling scan request
Cancelling authentication timeout
WEXT: Operstate: linkmode=0, operstate=6

Reply to: