LinuxQuestions.org
Share your knowledge at the LQ Wiki.
Home Forums Tutorials Articles Register
Go Back   LinuxQuestions.org > Forums > Linux Forums > Linux - Laptop and Netbook
User Name
Password
Linux - Laptop and Netbook Having a problem installing or configuring Linux on your laptop? Need help running Linux on your netbook? This forum is for you. This forum is for any topics relating to Linux and either traditional laptops or netbooks (such as the Asus EEE PC, Everex CloudBook or MSI Wind).

Notices


Reply
  Search this Thread
Old 01-10-2007, 02:20 AM   #1
win32sux
LQ Guru
 
Registered: Jul 2003
Location: Los Angeles
Distribution: Ubuntu
Posts: 9,870

Rep: Reputation: 380Reputation: 380Reputation: 380Reputation: 380
Unhappy weird temporary GUI freeze/lag/stuttering wireless related


hi... i'm having a minor issue with my new laptop... it's a Dell Inspiron 640m/e1405... it has Intel 3945 wireless... i'm running Ubuntu 6.10 (Edgy Eft) on it... i'm using whatever ipw3945 and wpa supplicant came with the distro and my connection type is WPA2 Personal... my router is a WRT54G v3.1...

when i'm on the wireless connection i sometimes get disconnected and stuff, which is normal cuz of interference, range etc... but the problem is that sometimes while i'm getting disconnected, the GUI will start to "stutter"... i'm not exactly sure how to describe it... think of little split-second freezes, a few in a row... during these split second freezes, the mouse pointer won't move and anything else like windows or even the keyboard will stop moving/working... once the connection is back, everything is back to normal... the problem seems to start when the connection fails, and it continues happening while re-establishing the connection...

i'm using network-manager-gnome, so i can actually see when the problem is about to happen cuz i start losing bars in the applet... then i lose all the bars and the (physical) wireless LED on the front starts to blink really fast and stuff...

anyhow, i'm trying to figure-out where the root of the problem lies... i suspect it's a bug in the crappy intel closed-source driver or firmware, but i'm not sure... i was wondering if anyone else has experienced something similar, or if you could help me further diagnose and and try to find a solution for this... i'm new to the whole laptop and wireless thing so any help is GREATLY appreciated...

Last edited by win32sux; 01-10-2007 at 03:58 AM.
 
Old 01-10-2007, 02:53 PM   #2
rylan76
Senior Member
 
Registered: Apr 2004
Location: Potchefstroom, South Africa
Distribution: Fedora 17 - 3.3.4-5.fc17.x86_64
Posts: 1,552

Rep: Reputation: 103Reputation: 103
Quote:
Originally Posted by win32sux
hi... i'm having a minor issue with my new laptop... it's a Dell Inspiron 640m/e1405... it has Intel 3945 wireless... i'm running Ubuntu 6.10 (Edgy Eft) on it... i'm using whatever ipw3945 and wpa supplicant came with the distro and my connection type is WPA2 Personal... my router is a WRT54G v3.1...

when i'm on the wireless connection i sometimes get disconnected and stuff, which is normal cuz of interference, range etc... but the problem is that sometimes while i'm getting disconnected, the GUI will start to "stutter"... i'm not exactly sure how to describe it... think of little
.
.
.
anyhow, i'm trying to figure-out where the root of the problem lies... i suspect it's a bug in the crappy intel closed-source driver or firmware, but i'm not sure... i was wondering if anyone else has experienced something similar, or if you could help me further diagnose and and try to find a solution for this... i'm new to the whole laptop and wireless thing so any help is GREATLY appreciated...
I've experienced this on a previous Linux setup under RedHat 9. The moment I started copying anything from another PC over the LAN, I'd get "microfreezes" / gui stuttering just like you describe with your wireless connection dropping. In my case I eventually traced the problem to not having DMA support in the kernel under RedHat 9 for my particular motherboard. This meant that all IO on the board was done non-DMA, i. e. the CPU had to drop whatever it was doing every few microseconds and go do IO scutwork, instead of having the DMA controller handle the transfers. Thus, the moment I started to "push" my system with bulk-IO, it would start stuttering.

The first thing you might check is that the kernel you are using has support for your motherboard chipset compiled in... chances are it doesn't, so the kernel if not using DMA on your board. The reason I think you see stutter when your wireless is connecting / disconnecting is that this requires a lot of data to be shifted around (DNS / authentication / negotiation...?) and because you (maybe) do not have DMA active, the CPU gets so involved with shifting data around that your userland processes get shafted as regards processing time as the CPU is busy doing transfers manually to get your connection up...?
 
Old 01-11-2007, 03:06 AM   #3
win32sux
LQ Guru
 
Registered: Jul 2003
Location: Los Angeles
Distribution: Ubuntu
Posts: 9,870

Original Poster
Rep: Reputation: 380Reputation: 380Reputation: 380Reputation: 380
Quote:
Originally Posted by rylan76
I've experienced this on a previous Linux setup under RedHat 9. The moment I started copying anything from another PC over the LAN, I'd get "microfreezes" / gui stuttering just like you describe with your wireless connection dropping. In my case I eventually traced the problem to not having DMA support in the kernel under RedHat 9 for my particular motherboard. This meant that all IO on the board was done non-DMA, i. e. the CPU had to drop whatever it was doing every few microseconds and go do IO scutwork, instead of having the DMA controller handle the transfers. Thus, the moment I started to "push" my system with bulk-IO, it would start stuttering.

The first thing you might check is that the kernel you are using has support for your motherboard chipset compiled in... chances are it doesn't, so the kernel if not using DMA on your board. The reason I think you see stutter when your wireless is connecting / disconnecting is that this requires a lot of data to be shifted around (DNS / authentication / negotiation...?) and because you (maybe) do not have DMA active, the CPU gets so involved with shifting data around that your userland processes get shafted as regards processing time as the CPU is busy doing transfers manually to get your connection up...?
hey there... thanks for the reply!!!

yeah, it does at least have the "feel" of a DMA issue, but i'm not sure...

in any case, here's a DMA grep from my config, let me know what you think:
Code:
win32sux@andromeda:~$ cat /boot/config-2.6.17-10-generic | grep DMA
CONFIG_GENERIC_ISA_DMA=y
CONFIG_ISA_DMA_API=y
CONFIG_BLK_DEV_IDEDMA_PCI=y
# CONFIG_BLK_DEV_IDEDMA_FORCED is not set
CONFIG_IDEDMA_PCI_AUTO=y
# CONFIG_IDEDMA_ONLYDISK is not set
# CONFIG_HPT34X_AUTODMA is not set
CONFIG_BLK_DEV_IDEDMA=y
# CONFIG_IDEDMA_IVB is not set
CONFIG_IDEDMA_AUTO=y
CONFIG_SCSI_PDC_ADMA=m
# CONFIG_SCSI_PATA_OPTIDMA is not set
CONFIG_SCSI_SYM53C8XX_DMA_ADDRESSING_MODE=1
# CONFIG_IEEE1394_SBP2_PHYS_DMA is not set
CONFIG_BCM43XX_DMA=y
CONFIG_BCM43XX_DMA_AND_PIO_MODE=y
# CONFIG_BCM43XX_DMA_MODE is not set
CONFIG_CARDMAN_4000=m
CONFIG_CARDMAN_4040=m
# CONFIG_SOUND_DMAP is not set
i'm also gonna try to post what my syslog looks like during a stutter...

Last edited by win32sux; 01-11-2007 at 03:16 AM.
 
Old 01-12-2007, 12:08 AM   #4
rylan76
Senior Member
 
Registered: Apr 2004
Location: Potchefstroom, South Africa
Distribution: Fedora 17 - 3.3.4-5.fc17.x86_64
Posts: 1,552

Rep: Reputation: 103Reputation: 103
The config seems OK, but there are settings that influence whether DMA works that do not have the letters DMA in the settings name - for example, on my old GA8-TRS350MT motherboard, chipset level support for DMA was only available after I recompiled a 2.6.14.3 kernel with all lines containing "IXP" (for the IXP300 chipset on the GA8-TRS350MT) and "ATI" (for the ATI chips on the board) active... That's the thing. You'll need to find out exactly which chipset your motherboard uses, and see that support for that chipset is present in your kernel.
 
Old 01-12-2007, 09:51 AM   #5
dracolich
Senior Member
 
Registered: Jul 2005
Distribution: Slackware
Posts: 1,274

Rep: Reputation: 63
In addition to having DMA enabled in the kernel, are you using the hdparm utility to turn it on? I have this in my rc.local starup file for each hard drive

Code:
/usr/sbin/hdparm -m16 -c3 -u1 /dev/hd?
-c3 enables 32-bit I/O transfers with sync. Note that his applies the the drive and not each partition, so use it with /dev/hd? and not /dev/hd?1.
 
Old 01-12-2007, 10:42 AM   #6
win32sux
LQ Guru
 
Registered: Jul 2003
Location: Los Angeles
Distribution: Ubuntu
Posts: 9,870

Original Poster
Rep: Reputation: 380Reputation: 380Reputation: 380Reputation: 380
Quote:
Originally Posted by rylan76
The config seems OK, but there are settings that influence whether DMA works that do not have the letters DMA in the settings name - for example, on my old GA8-TRS350MT motherboard, chipset level support for DMA was only available after I recompiled a 2.6.14.3 kernel with all lines containing "IXP" (for the IXP300 chipset on the GA8-TRS350MT) and "ATI" (for the ATI chips on the board) active... That's the thing. You'll need to find out exactly which chipset your motherboard uses, and see that support for that chipset is present in your kernel.
i'll try and check, but i seriously doubt there isn't support in my kernel considering ubuntu tries so hard to cover all bases, plus the centrino chipset is everywhere...

Quote:
Originally Posted by dracolich
In addition to having DMA enabled in the kernel, are you using the hdparm utility to turn it on? I have this in my rc.local starup file for each hard drive

Code:
/usr/sbin/hdparm -m16 -c3 -u1 /dev/hd?
-c3 enables 32-bit I/O transfers with sync. Note that his applies the the drive and not each partition, so use it with /dev/hd? and not /dev/hd?1.
i think as far as DMA is concerned, if that is indeed the cause, i'd expect it would be in relation to the wireless card or the centrino chipset, not the hard drive... but here's what mine looks like just in case:
Code:
win32sux@andromeda:~$ sudo hdparm -I /dev/sda

/dev/sda:

ATA device, with non-removable media
        Model Number:       Hitachi HTS541680J9SA00                 
        Serial Number:      SB2212SYGXJJZB
        Firmware Revision:  SB2OC74P
Standards:
        Used: ATA/ATAPI-7 T13 1532D revision 1 
        Supported: 7 6 5 4 
Configuration:
        Logical         max     current
        cylinders       16383   16383
        heads           16      16
        sectors/track   63      63
        --
        CHS current addressable sectors:   16514064
        LBA    user addressable sectors:  156301488
        LBA48  user addressable sectors:  156301488
        device size with M = 1024*1024:       76319 MBytes
        device size with M = 1000*1000:       80026 MBytes (80 GB)
Capabilities:
        LBA, IORDY(can be disabled)
        Queue depth: 32
        Standby timer values: spec'd by Vendor, no device specific minimum
        R/W multiple sector transfer: Max = 16  Current = 8
        Advanced power management level: 128 (0x80)
        Recommended acoustic management value: 128, current value: 128
        DMA: mdma0 mdma1 mdma2 udma0 udma1 udma2 udma3 udma4 *udma5 
             Cycle time: min=120ns recommended=120ns
        PIO: pio0 pio1 pio2 pio3 pio4 
             Cycle time: no flow control=120ns  IORDY flow control=120ns
Commands/features:
        Enabled Supported:
           *    SMART feature set
                Security Mode feature set
           *    Power Management feature set
           *    Write cache
           *    Look-ahead
           *    Host Protected Area feature set
           *    WRITE_BUFFER command
           *    READ_BUFFER command
           *    NOP cmd
           *    DOWNLOAD_MICROCODE
           *    Advanced Power Management feature set
                Power-Up In Standby feature set
           *    SET_FEATURES required to spinup after power up
                SET_MAX security extension
           *    Automatic Acoustic Management feature set
           *    48-bit Address feature set
           *    Device Configuration Overlay feature set
           *    Mandatory FLUSH_CACHE
           *    FLUSH_CACHE_EXT
           *    SMART error logging
           *    SMART self-test
           *    General Purpose Logging feature set
           *    WRITE_{DMA|MULTIPLE}_FUA_EXT
           *    64-bit World wide name
           *    SATA-I signaling speed (1.5Gb/s)
           *    Native Command Queueing (NCQ)
           *    Host-initiated interface power management
           *    Phy event counters
                Non-Zero buffer offsets in DMA Setup FIS
                DMA Setup Auto-Activate optimization
                Device-initiated interface power management
                In-order data delivery
           *    Software settings preservation
Security: 
        Master password revision code = 65534
                supported
        not     enabled
        not     locked
                frozen
        not     expired: security count
        not     supported: enhanced erase
        42min for SECURITY ERASE UNIT. 
Checksum: correct
i'll be posting from my syslog ASAP...
 
Old 01-13-2007, 05:07 AM   #7
win32sux
LQ Guru
 
Registered: Jul 2003
Location: Los Angeles
Distribution: Ubuntu
Posts: 9,870

Original Poster
Rep: Reputation: 380Reputation: 380Reputation: 380Reputation: 380
Arrow /var/log/syslog content during issue!

hi guys! okay, i was finally able to get a glimpse of what my syslog looks like from the moment a stutter starts to the moment it stops... i've had to split it into two posts due to the size limit... here's the first part, the second part appears on the next post:
Code:
Jan 13 05:49:28 andromeda kernel: [17186445.236000] ipw3945: Microcode SW error detected.  Restarting.
Jan 13 05:49:28 andromeda kernel: [17186445.236000] ipw3945: Error Reply type 0x000000FF cmd TX (0x1C) seq 0x0000 ser 0x00030000
Jan 13 05:49:28 andromeda kernel: [17186445.236000] ipw3945: Can't stop Rx DMA.
Jan 13 05:49:28 andromeda kernel: [17186446.604000] ipw3945: Detected geography ABG (11 802.11bg channels, 13 802.11a channels)
Jan 13 05:49:30 andromeda kernel: [17186446.920000] ipw3945: Microcode SW error detected.  Restarting.
Jan 13 05:49:30 andromeda kernel: [17186446.920000] ipw3945: Error Reply type 0x000000FF cmd TX (0x1C) seq 0x0000 ser 0x00030000
Jan 13 05:49:30 andromeda kernel: [17186446.920000] ipw3945: Can't stop Rx DMA.
Jan 13 05:49:30 andromeda NetworkManager: <information>^Iwpa_supplicant(4699): ME: Interface 'eth1' added 
Jan 13 05:49:30 andromeda NetworkManager: <information>^Iwpa_supplicant(4699): EAPOL: startWhen --> 0 
Jan 13 05:49:30 andromeda NetworkManager: <information>^Iwpa_supplicant(4699): RX EAPOL from 00:14:bf:05:2f:3e 
Jan 13 05:49:30 andromeda NetworkManager: <information>^Iwpa_supplicant(4699): RX EAPOL - hexdump(len=131): 02 03 00 7f 02 13 82 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 a8 12 29 61 b1 85 69 26 69 ce 63 11 fc 0f 0e 86 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 07 a3 cd 30 3d f1 fa 9b f9 39 01 79 80 93 72 28 00 20 06 d3 e1 7f de 01 cb cd 80 40 fb 2d fe f7 7c 0d cb f6 3e 32 c1 87 da a3 dc 6f 22 25 60 e0 12 08 
Jan 13 05:49:30 andromeda NetworkManager: <information>^Iwpa_supplicant(4699): IEEE 802.1X RX: version=2 type=3 length=127 
Jan 13 05:49:30 andromeda NetworkManager: <information>^Iwpa_supplicant(4699):   EAPOL-Key type=2 
Jan 13 05:49:30 andromeda NetworkManager: <information>^Iwpa_supplicant(4699):   key_info 0x1382 (ver=2 keyidx=0 rsvd=0 Group Ack MIC Secure Encr) 
Jan 13 05:49:30 andromeda NetworkManager: <information>^Iwpa_supplicant(4699):   key_length=0 key_data_length=32 
Jan 13 05:49:30 andromeda NetworkManager: <information>^Iwpa_supplicant(4699):   replay_counter - hexdump(len=8): 00 00 00 00 00 00 00 02 
Jan 13 05:49:30 andromeda NetworkManager: <information>^Iwpa_supplicant(4699):   key_nonce - hexdump(len=32): 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 
Jan 13 05:49:30 andromeda NetworkManager: <information>^Iwpa_supplicant(4699):   key_iv - hexdump(len=16): a8 12 29 61 b1 85 69 26 69 ce 63 11 fc 0f 0e 86 
Jan 13 05:49:30 andromeda NetworkManager: <information>^Iwpa_supplicant(4699):   key_rsc - hexdump(len=8): 00 00 00 00 00 00 00 00 
Jan 13 05:49:30 andromeda NetworkManager: <information>^Iwpa_supplicant(4699):  00 00 00 00 00 00 00 
Jan 13 05:49:30 andromeda NetworkManager: <information>^Iwpa_supplicant(4699):   key_mic - hexdump(len=16): 07 a3 cd 30 3d f1 fa 9b f9 39 01 79 80 93 72 28 
Jan 13 05:49:30 andromeda NetworkManager: <information>^Iwpa_supplicant(4699): WPA: RX EAPOL-Key - hexdump(len=131): 02 03 00 7f 02 13 82 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 a8 12 29 61 b1 85 69 26 69 ce 63 11 fc 0f 0e 86 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 07 a3 cd 30 3d f1 fa 9b f9 39 01 79 80 93 72 28 00 20 06 d3 e1 7f de 01 cb cd 80 40 fb 2d fe f7 7c 0d cb f6 3e 32 c1 87 da a3 dc 6f 22 25 60 e0 12 08 
Jan 13 05:49:30 andromeda NetworkManager: <information>^Iwpa_supplicant(4699): RSN: encrypted key data - hexdump(len=32): 06 d3 e1 7f de 01 cb cd 80 40 fb 2d fe f7 7c 0d cb f6 3e 32 c1 87 da a3 dc 6f 22 25 60 e0 12 08 
Jan 13 05:49:30 andromeda NetworkManager: <information>^Iwpa_supplicant(4699): WPA: decrypted EAPOL-Key key data - hexdump(len=24): [REMOVED] 
Jan 13 05:49:30 andromeda NetworkManager: <information>^Iwpa_supplicant(4699): WPA: RX message 1 of Group Key or STAKey Handshake from 00:14:bf:05:2f:3e (ver=2) 
Jan 13 05:49:30 andromeda NetworkManager: <information>^Iwpa_supplicant(4699): RSN: msg 1/2 key data - hexdump(len=24): dd 16 00 0f ac 01 02 00 0d 00 7a d9 af 52 36 4b 77 e0 3a 8d 56 48 d2 5a 
Jan 13 05:49:30 andromeda NetworkManager: <information>^Iwpa_supplicant(4699): 3a 8d 56 48 d2 5a 
Jan 13 05:49:30 andromeda NetworkManager: <information>^Iwpa_supplicant(4699): State: COMPLETED -> GROUP_HANDSHAKE 
Jan 13 05:49:30 andromeda NetworkManager: <information>^Iwpa_supplicant(4699): WPA: Group Key - hexdump(len=16): [REMOVED] 
Jan 13 05:49:30 andromeda NetworkManager: <information>^Iwpa_supplicant(4699): WPA: Installing GTK to the driver (keyidx=2 tx=0). 
Jan 13 05:49:30 andromeda NetworkManager: <information>^Iwpa_supplicant(4699): WPA: RSC - hexdump(len=6): 00 00 00 00 00 00 
Jan 13 05:49:30 andromeda NetworkManager: <information>^Iwpa_supplicant(4699): wpa_driver_wext_set_key: alg=3 key_idx=2 set_tx=0 seq_len=6 key_len=16 
Jan 13 05:49:30 andromeda NetworkManager: <information>^Iwpa_supplicant(4699): WPA: Sending EAPOL-Key 2/2 
Jan 13 05:49:30 andromeda NetworkManager: <information>^Iwpa_supplicant(4699): WPA: TX EAPOL-Key - hexdump(len=99): 01 03 00 5f 02 03 02 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 c9 bc 32 78 69 a1 25 48 bb 63 7b 4a fc fe 87 00 00 
Jan 13 05:49:30 andromeda NetworkManager: <information>^Iwpa_supplicant(4699): WPA: Group rekeying completed with 00:14:bf:05:2f:3e [GTK=CCMP] 
Jan 13 05:49:30 andromeda NetworkManager: <information>^Iwpa_supplicant(4699): CTRL_IFACE monitor send - hexdump(len=40): 2f 76 61 72 2f 72 75 6e 2f 4e 65 74 77 6f 72 6b 4d 61 6e 61 67 65 72 2f 77 70 61 5f 63 74 72 6c 5f 34 32 33 31 2d 31 00 
Jan 13 05:49:30 andromeda NetworkManager: <information>^Iwpa_supplicant(4699): State: GROUP_HANDSHAKE -> COMPLETED 
Jan 13 05:49:30 andromeda NetworkManager: <information>^Iwpa_supplicant(4699): Wireless event: cmd=0x8b15 len=20 
Jan 13 05:49:30 andromeda NetworkManager: <information>^Iwpa_supplicant(4699): Wireless event: new AP: 00:00:00:00:00:00 
Jan 13 05:49:30 andromeda NetworkManager: <information>^Iwpa_supplicant(4699): Setting scan request: 0 sec 100000 usec 
Jan 13 05:49:30 andromeda NetworkManager: <information>^Iwpa_supplicant(4699): bf:05:2f:3e into blacklist 
Jan 13 05:49:30 andromeda NetworkManager: <information>^Iwpa_supplicant(4699): State: COMPLETED -> DISCONNECTED 
Jan 13 05:49:30 andromeda NetworkManager: <information>^Iwpa_supplicant(4699): WEXT: Operstate: linkmode=-1, operstate=5 
Jan 13 05:49:30 andromeda NetworkManager: <information>^Iwpa_supplicant(4699): EAPOL: External notification - portEnabled=0 
Jan 13 05:49:30 andromeda NetworkManager: <information>^Iwpa_supplicant(4699): EAPOL: SUPP_PAE entering state DISCONNECTED 
Jan 13 05:49:30 andromeda NetworkManager: <information>^Iwpa_supplicant(4699): EAPOL: SUPP_BE entering state INITIALIZE 
Jan 13 05:49:30 andromeda NetworkManager: <information>^Iwpa_supplicant(4699): EAPOL: External notification - portValid=0 
Jan 13 05:49:30 andromeda NetworkManager: <information>^Iwpa_supplicant(4699): EAPOL: External notification - EAP success=0 
Jan 13 05:49:30 andromeda NetworkManager: <information>^Iwpa_supplicant(4699): CTRL-EVENT-DISCONNECTED - Disconnect event - remove keys 
Jan 13 05:49:30 andromeda NetworkManager: <information>^Iwpa_supplicant(4699): CTRL_IFACE monitor send - hexdump(len=40): 2f 76 61 72 2f 72 75 6e 2f 4e 65 74 77 6f 72 6b 4d 61 6e 61 67 65 72 2f 77 70 61 5f 63 74 72 6c 5f 34 32 33 31 2d 31 00 
Jan 13 05:49:30 andromeda NetworkManager: <information>^Iwpa_supplicant(4699): wpa_driver_wext_set_key: alg=0 key_idx=0 set_tx=0 seq_len=0 key_len=0 
Jan 13 05:49:30 andromeda NetworkManager: <information>^Iwpa_supplicant(4699): wpa_driver_wext_set_key: alg=0 key_idx=1 set_tx=0 seq_len=0 key_len=0 
Jan 13 05:49:30 andromeda NetworkManager: <information>^Iwpa_supplicant(4699): wpa_driver_wext_set_key: alg=0 key_idx=2 set_tx=0 seq_len=0 key_len=0 
Jan 13 05:49:30 andromeda NetworkManager: <information>^Iwpa_supplicant(4699): wpa_driver_wext_set_key: alg=0 key_idx=3 set_tx=0 seq_len=0 key_len=0 
Jan 13 05:49:30 andromeda NetworkManager: <information>^Iwpa_supplicant(4699): wpa_driver_wext_set_key: alg=0 key_idx=0 set_tx=0 seq_len=0 key_len=0 
Jan 13 05:49:30 andromeda NetworkManager: <information>^Iwpa_supplicant(4699): RTM_NEWLINK, IFLA_IFNAME: Interface 'eth1' added 
Jan 13 05:49:30 andromeda NetworkManager: <information>^Iwpa_supplicant(4699): State: DISCONNECTED -> SCANNING 
Jan 13 05:49:30 andromeda NetworkManager: <information>^Iwpa_supplicant(4699): Starting AP scan (broadcast SSID) 
Jan 13 05:49:30 andromeda kernel: [17186448.288000] ipw3945: Detected geography ABG (11 802.11bg channels, 13 802.11a channels)
Jan 13 05:49:35 andromeda NetworkManager: <information>^Ieth1: link timed out. 
Jan 13 05:49:35 andromeda NetworkManager: <information>^ISWITCH: found better connection 'eth1/aftermath' than current connection 'eth1/aftermath'.  same_ssid=1, have_link=0 
Jan 13 05:49:35 andromeda NetworkManager: <information>^IWill activate connection 'eth1/aftermath'. 
Jan 13 05:49:35 andromeda NetworkManager: <information>^IDevice eth1 activation scheduled... 
Jan 13 05:49:35 andromeda NetworkManager: <information>^IDeactivating device eth1. 
Jan 13 05:49:35 andromeda dhclient: There is already a pid file /var/run/dhclient.eth1.pid with pid 4731
Jan 13 05:49:35 andromeda dhclient: killed old client process, removed PID file
Jan 13 05:49:35 andromeda dhclient: DHCPRELEASE on eth1 to 192.168.1.1 port 67
Jan 13 05:49:36 andromeda NetworkManager: <WARNING>^I nm_device_802_11_wireless_set_essid (): error setting ESSID to '' for device eth1: Resource temporarily unavailable 
Jan 13 05:49:37 andromeda kernel: [17186453.520000] ipw3945: Microcode SW error detected.  Restarting.
Jan 13 05:49:37 andromeda kernel: [17186453.520000] ipw3945: Error Reply type 0x000000FF cmd DAEMON (0x80) seq 0x4413 ser 0x00030000
Jan 13 05:49:37 andromeda kernel: [17186453.520000] ipw3945: Can't stop Rx DMA.
Jan 13 05:49:37 andromeda kernel: [17186454.516000] psmouse.c: TouchPad at isa0060/serio1/input0 lost synchronization, throwing 3 bytes away.
Jan 13 05:49:37 andromeda kernel: [17186454.888000] ipw3945: Detected geography ABG (11 802.11bg channels, 13 802.11a channels)
Jan 13 05:49:38 andromeda NetworkManager: <information>^IActivation (eth1) started... 
Jan 13 05:49:38 andromeda NetworkManager: <information>^IActivation (eth1) Stage 1 of 5 (Device Prepare) scheduled... 
Jan 13 05:49:38 andromeda NetworkManager: <information>^IActivation (eth1) Stage 1 of 5 (Device Prepare) started... 
Jan 13 05:49:38 andromeda NetworkManager: <information>^IActivation (eth1) Stage 2 of 5 (Device Configure) scheduled... 
Jan 13 05:49:38 andromeda NetworkManager: <information>^IActivation (eth1) Stage 1 of 5 (Device Prepare) complete. 
Jan 13 05:49:38 andromeda NetworkManager: <information>^IActivation (eth1) Stage 2 of 5 (Device Configure) starting... 
Jan 13 05:49:38 andromeda NetworkManager: <information>^IDHCP daemon state is now 14 (normal exit) for interface eth1 
Jan 13 05:49:38 andromeda NetworkManager: <information>^IDHCP daemon state is now 11 (unknown) for interface eth1 
Jan 13 05:49:38 andromeda NetworkManager: <information>^IDHCP daemon state is now 14 (normal exit) for interface eth1 
Jan 13 05:49:38 andromeda NetworkManager: <information>^IActivation (eth1/wireless): access point 'aftermath' is encrypted, and a key exists.  No new key needed. 
Jan 13 05:49:39 andromeda NetworkManager: <information>^ISUP: sending command 'INTERFACE_ADD eth1^I^Iwext^I/var/run/wpa_supplicant^I' 
Jan 13 05:49:39 andromeda NetworkManager: <information>^ISUP: response was 'OK' 
Jan 13 05:49:39 andromeda NetworkManager: <information>^ISUP: sending command 'AP_SCAN 1' 
Jan 13 05:49:39 andromeda NetworkManager: <information>^ISUP: response was 'OK' 
Jan 13 05:49:39 andromeda NetworkManager: <information>^ISUP: sending command 'ADD_NETWORK' 
Jan 13 05:49:39 andromeda NetworkManager: <information>^ISUP: response was '0' 
Jan 13 05:49:39 andromeda NetworkManager: <information>^ISUP: sending command 'SET_NETWORK 0 ssid 61667465726d617468' 
Jan 13 05:49:39 andromeda NetworkManager: <information>^ISUP: response was 'OK' 
Jan 13 05:49:39 andromeda NetworkManager: <information>^ISUP: sending command 'SET_NETWORK 0 proto WPA2' 
Jan 13 05:49:39 andromeda NetworkManager: <information>^ISUP: response was 'OK' 
Jan 13 05:49:39 andromeda NetworkManager: <information>^ISUP: sending command 'SET_NETWORK 0 key_mgmt WPA-PSK' 
Jan 13 05:49:39 andromeda NetworkManager: <information>^ISUP: response was 'OK' 
Jan 13 05:49:39 andromeda NetworkManager: <information>^ISUP: sending command 'SET_NETWORK 0 psk <key>' 
Jan 13 05:49:39 andromeda NetworkManager: <information>^ISUP: response was 'OK' 
Jan 13 05:49:39 andromeda NetworkManager: <information>^ISUP: sending command 'SET_NETWORK 0 pairwise CCMP' 
Jan 13 05:49:39 andromeda NetworkManager: <information>^ISUP: response was 'OK' 
Jan 13 05:49:39 andromeda NetworkManager: <information>^ISUP: sending command 'SET_NETWORK 0 group CCMP' 
Jan 13 05:49:39 andromeda NetworkManager: <information>^ISUP: response was 'OK' 
Jan 13 05:49:39 andromeda NetworkManager: <information>^ISUP: sending command 'ENABLE_NETWORK 0' 
Jan 13 05:49:39 andromeda NetworkManager: <information>^ISUP: response was 'OK' 
Jan 13 05:49:39 andromeda NetworkManager: <information>^IActivation (eth1) Stage 2 of 5 (Device Configure) complete. 
Jan 13 05:49:39 andromeda NetworkManager: <information>^Iwpa_supplicant(7896): Global control interface '/var/run/wpa_supplicant-global' 
Jan 13 05:49:39 andromeda NetworkManager: <information>^Iwpa_supplicant(7896): RX global ctrl_iface - hexdump_ascii(len=49): 
Jan 13 05:49:39 andromeda NetworkManager: <information>^Iwpa_supplicant(7896):      49 4e 54 45 52 46 41 43 45 5f 41 44 44 20 65 74   INTERFACE_ADD et 
Jan 13 05:49:39 andromeda NetworkManager: <information>^Iwpa_supplicant(7896):      68 31 09 09 77 65 78 74 09 2f 76 61 72 2f 72 75   h1__wext_/var/ru 
Jan 13 05:49:39 andromeda NetworkManager: <information>^Iwpa_supplicant(7896):      6e 2f 77 70 61 5f 73 75 70 70 6c 69 63 61 6e 74   n/wpa_supplicant 
Jan 13 05:49:39 andromeda NetworkManager: <information>^Iwpa_supplicant(7896):      09                                                _                
Jan 13 05:49:39 andromeda NetworkManager: <information>^Iwpa_supplicant(7896): CTRL_IFACE GLOBAL INTERFACE_ADD 'eth1^I^Iwext^I/var/run/wpa_supplicant^I' 
Jan 13 05:49:39 andromeda NetworkManager: <information>^Iwpa_supplicant(7896): Initializing interface 'eth1' conf 'N/A' driver 'wext' ctrl_interface '/var/run/wpa_supplicant' bridge 'N/A' 
Jan 13 05:49:39 andromeda NetworkManager: <information>^Iwpa_supplicant(7896): Initializing interface (2) 'eth1' 
Jan 13 05:49:39 andromeda NetworkManager: <information>^Iwpa_supplicant(7896): EAPOL: SUPP_PAE entering state DISCONNECTED 
Jan 13 05:49:39 andromeda NetworkManager: <information>^Iwpa_supplicant(7896): EAPOL: KEY_RX entering state NO_KEY_RECEIVE 
Jan 13 05:49:39 andromeda NetworkManager: <information>^Iwpa_supplicant(7896): EAPOL: SUPP_BE entering state INITIALIZE 
Jan 13 05:49:39 andromeda NetworkManager: <information>^Iwpa_supplicant(7896): EAP: EAP entering state DISABLED 
Jan 13 05:49:39 andromeda NetworkManager: <information>^Iwpa_supplicant(7896): EAPOL: External notification - portEnabled=0 
Jan 13 05:49:39 andromeda NetworkManager: <information>^Iwpa_supplicant(7896): EAPOL: External notification - portValid=0 
Jan 13 05:49:39 andromeda NetworkManager: <information>^Iwpa_supplicant(7896): SIOCGIWRANGE: WE(compiled)=20 WE(source)=16 enc_capa=0xf 
Jan 13 05:49:39 andromeda NetworkManager: <information>^Iwpa_supplicant(7896):   capabilities: key_mgmt 0xf enc 0xf 
Jan 13 05:49:39 andromeda NetworkManager: <information>^Iwpa_supplicant(7896): WEXT: Operstate: linkmode=1, operstate=5 
Jan 13 05:49:39 andromeda NetworkManager: <information>^Iwpa_supplicant(7896): f 
Jan 13 05:49:39 andromeda NetworkManager: <information>^Iwpa_supplicant(7896): wpa_driver_wext_set_wpa 
Jan 13 05:49:39 andromeda NetworkManager: <information>^Iwpa_supplicant(7896): wpa_driver_wext_set_key: alg=0 key_idx=0 set_tx=0 seq_len=0 key_len=0 
Jan 13 05:49:39 andromeda NetworkManager: <information>^Iwpa_supplicant(7896): wpa_driver_wext_set_key: alg=0 key_idx=1 set_tx=0 seq_len=0 key_len=0 
Jan 13 05:49:39 andromeda NetworkManager: <information>^Iwpa_supplicant(7896): wpa_driver_wext_set_key: alg=0 key_idx=2 set_tx=0 seq_len=0 key_len=0 
Jan 13 05:49:39 andromeda NetworkManager: <information>^Iwpa_supplicant(7896): wpa_driver_wext_set_key: alg=0 key_idx=3 set_tx=0 seq_len=0 key_len=0 
Jan 13 05:49:39 andromeda NetworkManager: <information>^Iwpa_supplicant(7896): wpa_driver_wext_set_countermeasures 
Jan 13 05:49:39 andromeda NetworkManager: <information>^Iwpa_supplicant(7896): wpa_driver_wext_set_drop_unencrypted 
Jan 13 05:49:39 andromeda NetworkManager: <information>^Iwpa_supplicant(7896): Setting scan request: 0 sec 100000 usec 
Jan 13 05:49:39 andromeda NetworkManager: <information>^Iwpa_supplicant(7896): Added interface eth1 
Jan 13 05:49:39 andromeda NetworkManager: <information>^Iwpa_supplicant(7896): Wireless event: cmd=0x8b06 len=8 
Jan 13 05:49:39 andromeda NetworkManager: <information>^Iwpa_supplicant(7896): RX ctrl_iface - hexdump_ascii(len=9):
 
Old 01-13-2007, 05:09 AM   #8
win32sux
LQ Guru
 
Registered: Jul 2003
Location: Los Angeles
Distribution: Ubuntu
Posts: 9,870

Original Poster
Rep: Reputation: 380Reputation: 380Reputation: 380Reputation: 380
Arrow /var/log/syslog content during issue! (continued)

Code:
Jan 13 05:49:39 andromeda NetworkManager: <information>^Iwpa_supplicant(7896):      41 50 5f 53 43 41 4e 20 31                        AP_SCAN 1        
Jan 13 05:49:39 andromeda NetworkManager: <information>^Iwpa_supplicant(7896): RX ctrl_iface - hexdump_ascii(len=11): 
Jan 13 05:49:39 andromeda NetworkManager: <information>^Iwpa_supplicant(7896):      41 44 44 5f 4e 45 54 57 4f 52 4b                  ADD_NETWORK      
Jan 13 05:49:39 andromeda NetworkManager: <information>^Iwpa_supplicant(7896): CTRL_IFACE: ADD_NETWORK 
Jan 13 05:49:39 andromeda NetworkManager: <information>^Iwpa_supplicant(7896): RX ctrl_iface - hexdump_ascii(len=37): [REMOVED] 
Jan 13 05:49:39 andromeda NetworkManager: <information>^Iwpa_supplicant(7896): CTRL_IFACE: SET_NETWORK id=0 name='ssid' 
Jan 13 05:49:39 andromeda NetworkManager: <information>^Iwpa_supplicant(7896): CTRL_IFACE: value - hexdump_ascii(len=18): [REMOVED] 
Jan 13 05:49:39 andromeda NetworkManager: <information>^Iwpa_supplicant(7896): ssid - hexdump_ascii(len=9): 
Jan 13 05:49:39 andromeda NetworkManager: <information>^Iwpa_supplicant(7896):      61 66 74 65 72 6d 61 74 68                        aftermath        
Jan 13 05:49:39 andromeda NetworkManager: <information>^Iwpa_supplicant(7896): RX ctrl_iface - hexdump_ascii(len=24): [REMOVED] 
Jan 13 05:49:39 andromeda NetworkManager: <information>^Iwpa_supplicant(7896): SET_NETWORK id=0 name='proto' 
Jan 13 05:49:39 andromeda NetworkManager: <information>^Iwpa_supplicant(7896): CTRL_IFACE: value - hexdump_ascii(len=4): [REMOVED] 
Jan 13 05:49:39 andromeda NetworkManager: <information>^Iwpa_supplicant(7896): proto: 0x2 
Jan 13 05:49:39 andromeda NetworkManager: <information>^Iwpa_supplicant(7896): RX ctrl_iface - hexdump_ascii(len=30): [REMOVED] 
Jan 13 05:49:39 andromeda NetworkManager: <information>^Iwpa_supplicant(7896): CTRL_IFACE: SET_NETWORK id=0 name='key_mgmt' 
Jan 13 05:49:39 andromeda NetworkManager: <information>^Iwpa_supplicant(7896): CTRL_IFACE: value - hexdump_ascii(len=7): [REMOVED] 
Jan 13 05:49:39 andromeda NetworkManager: <information>^Iwpa_supplicant(7896): key_mgmt: 0x2 
Jan 13 05:49:39 andromeda NetworkManager: <information>^Iwpa_supplicant(7896): RX ctrl_iface - hexdump_ascii(len=82): [REMOVED] 
Jan 13 05:49:39 andromeda NetworkManager: <information>^Iwpa_supplicant(7896): CTRL_IFACE: SET_NETWORK id=0 name='psk' 
Jan 13 05:49:39 andromeda NetworkManager: <information>^Iwpa_supplicant(7896): CTRL_IFACE: value - hexdump_ascii(len=64): [REMOVED] 
Jan 13 05:49:39 andromeda NetworkManager: <information>^Iwpa_supplicant(7896): PSK - hexdump(len=32): [REMOVED] 
Jan 13 05:49:39 andromeda NetworkManager: <information>^Iwpa_supplicant(7896): RX ctrl_iface - hexdump_ascii(len=27): [REMOVED] 
Jan 13 05:49:39 andromeda NetworkManager: <information>^Iwpa_supplicant(7896): CTRL_IFACE: SET_NETWORK id=0 name='pairwise' 
Jan 13 05:49:39 andromeda NetworkManager: <information>^Iwpa_supplicant(7896): CTRL_IFACE: value - hexdump_ascii(len=4): [REMOVED] 
Jan 13 05:49:39 andromeda NetworkManager: <information>^Iwpa_supplicant(7896): pairwise: 0x10 
Jan 13 05:49:39 andromeda NetworkManager: <information>^Iwpa_supplicant(7896): RX ctrl_iface - hexdump_ascii(len=24): [REMOVED] 
Jan 13 05:49:39 andromeda NetworkManager: <information>^Iwpa_supplicant(7896): CTRL_IFACE: SET_NETWORK id=0 name='group' 
Jan 13 05:49:39 andromeda NetworkManager: <information>^Iwpa_supplicant(7896): CTRL_IFACE: value - hexdump_ascii(len=4): [REMOVED] 
Jan 13 05:49:39 andromeda NetworkManager: <information>^Iwpa_supplicant(7896): group: 0x10 
Jan 13 05:49:39 andromeda NetworkManager: <information>^Iwpa_supplicant(7896): RX ctrl_iface - hexdump_ascii(len=16): 
Jan 13 05:49:39 andromeda NetworkManager: <information>^Iwpa_supplicant(7896):      45 4e 41 42 4c 45 5f 4e 45 54 57 4f 52 4b 20 30   ENABLE_NETWORK 0 
Jan 13 05:49:39 andromeda NetworkManager: <information>^Iwpa_supplicant(7896): CTRL_IFACE: ENABLE_NETWORK id=0 
Jan 13 05:49:39 andromeda NetworkManager: <information>^Iwpa_supplicant(7896): Setting scan request: 0 sec 0 usec 
Jan 13 05:49:39 andromeda NetworkManager: <information>^Iwpa_supplicant(7896): State: DISCONNECTED -> SCANNING 
Jan 13 05:49:39 andromeda NetworkManager: <information>^Iwpa_supplicant(7896): Starting AP scan (broadcast SSID) 
Jan 13 05:49:39 andromeda NetworkManager: <information>^Iwpa_supplicant(7896): first without requesting a new scan to speed up initial association 
Jan 13 05:49:39 andromeda NetworkManager: <information>^Iwpa_supplicant(7896): Received 960 bytes of scan results (4 BSSes) 
Jan 13 05:49:39 andromeda NetworkManager: <information>^Iwpa_supplicant(7896): Scan results: 4 
Jan 13 05:49:39 andromeda NetworkManager: <information>^Iwpa_supplicant(7896): Selecting BSS from priority group 0 
Jan 13 05:49:39 andromeda NetworkManager: <information>^Iwpa_supplicant(7896): 0: 00:14:bf:05:2f:3e ssid='aftermath' wpa_ie_len=0 rsn_ie_len=22 caps=0x11 
Jan 13 05:49:39 andromeda NetworkManager: <information>^Iwpa_supplicant(7896):    selected based on RSN IE 
Jan 13 05:49:39 andromeda NetworkManager: <information>^Iwpa_supplicant(7896): Trying to associate with 00:14:bf:05:2f:3e (SSID='aftermath' freq=0 MHz) 
Jan 13 05:49:39 andromeda NetworkManager: <information>^Iwpa_supplicant(7896): Cancelling scan request 
Jan 13 05:49:39 andromeda NetworkManager: <information>^Iwpa_supplicant(7896): WPA: clearing own WPA/RSN IE 
Jan 13 05:49:39 andromeda NetworkManager: <information>^Iwpa_supplicant(7896): Automatic auth_alg selection: 0x1 
Jan 13 05:49:39 andromeda NetworkManager: <information>^Iwpa_supplicant(7896): RSN: using IEEE 802.11i/D9.0 
Jan 13 05:49:39 andromeda NetworkManager: <information>^Iwpa_supplicant(7896): WPA: Selected cipher suites: group 16 pairwise 16 key_mgmt 2 
Jan 13 05:49:39 andromeda NetworkManager: <information>^Iwpa_supplicant(7896): WPA: clearing AP WPA IE 
Jan 13 05:49:39 andromeda NetworkManager: <information>^Iwpa_supplicant(7896): WPA: set AP RSN IE - hexdump(len=22): 30 14 01 00 00 0f ac 04 01 00 00 0f ac 04 01 00 00 0f ac 02 00 00 
Jan 13 05:49:39 andromeda NetworkManager: <information>^Iwpa_supplicant(7896): WPA: using GTK CCMP 
Jan 13 05:49:39 andromeda NetworkManager: <information>^Iwpa_supplicant(7896): WPA: using PTK CCMP 
Jan 13 05:49:39 andromeda NetworkManager: <information>^Iwpa_supplicant(7896): WPA: using KEY_MGMT WPA-PSK 
Jan 13 05:49:39 andromeda NetworkManager: <information>^Iwpa_supplicant(7896): WPA: Set own WPA IE default - hexdump(len=22): 30 14 01 00 00 0f ac 04 01 00 00 0f ac 04 01 00 00 0f ac 02 00 00 
Jan 13 05:49:39 andromeda NetworkManager: <information>^Iwpa_supplicant(7896): No keys have been configured - skip key clearing 
Jan 13 05:49:39 andromeda NetworkManager: <information>^Iwpa_supplicant(7896): wpa_driver_wext_set_drop_unencrypted 
Jan 13 05:49:39 andromeda NetworkManager: <information>^Iwpa_supplicant(7896): State: SCANNING -> ASSOCIATING 
Jan 13 05:49:39 andromeda NetworkManager: <information>^Iwpa_supplicant(7896): WEXT: Operstate: linkmode=-1, operstate=5 
Jan 13 05:49:39 andromeda NetworkManager: <information>^Iwpa_supplicant(7896): wpa_driver_wext_associate 
Jan 13 05:49:40 andromeda NetworkManager: <information>^Iwpa_supplicant(7896): hentication timeout: 10 sec 0 usec 
Jan 13 05:49:40 andromeda NetworkManager: <information>^Iwpa_supplicant(7896): EAPOL: External notification - EAP success=0 
Jan 13 05:49:40 andromeda NetworkManager: <information>^Iwpa_supplicant(7896): EAPOL: External notification - EAP fail=0 
Jan 13 05:49:40 andromeda NetworkManager: <information>^Iwpa_supplicant(7896): EAPOL: External notification - portControl=Auto 
Jan 13 05:49:40 andromeda NetworkManager: <information>^Iwpa_supplicant(7896): RSN: Ignored PMKID candidate without preauth flag 
Jan 13 05:49:40 andromeda NetworkManager: <information>^Iwpa_supplicant(7896): Wireless event: cmd=0x8b06 len=8 
Jan 13 05:49:40 andromeda NetworkManager: <information>^Iwpa_supplicant(7896): Wireless event: cmd=0x8b1a len=18 
Jan 13 05:49:40 andromeda NetworkManager: <information>^Iwpa_supplicant(7896): CTRL_IFACE monitor attached - hexdump(len=40): 2f 76 61 72 2f 72 75 6e 2f 4e 65 74 77 6f 72 6b 4d 61 6e 61 67 65 72 2f 77 70 61 5f 63 74 72 6c 5f 34 32 33 31 2d 33 00 
Jan 13 05:49:40 andromeda NetworkManager: <information>^Iwpa_supplicant(7896): RX EAPOL from 00:14:bf:05:2f:3e 
Jan 13 05:49:40 andromeda NetworkManager: <information>^Iwpa_supplicant(7896): RX EAPOL - hexdump(len=121): 02 03 00 75 02 00 8a 00 10 00 00 00 00 00 00 00 02 c9 26 76 75 51 7a 91 b3 26 28 31 33 48 ab c8 40 a8 12 29 61 b1 85 69 26 69 ce 63 11 fc 0f 0e 86 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 16 dd 14 00 0f ac 04 45 0c d9 02 eb 7e ed d4 9f 60 db f4 db f6 3d 1c 
Jan 13 05:49:40 andromeda NetworkManager: <information>^Iwpa_supplicant(7896): Setting authentication timeout: 10 sec 0 usec 
Jan 13 05:49:40 andromeda NetworkManager: <information>^Iwpa_supplicant(7896): IEEE 802.1X RX: version=2 type=3 length=117 
Jan 13 05:49:40 andromeda NetworkManager: <information>^Iwpa_supplicant(7896):   EAPOL-Key type=2 
Jan 13 05:49:40 andromeda NetworkManager: <information>^IActivation (eth1/wireless) Stage 2 of 5 (Device Configure) successful.  Connected to access point 'aftermath'. 
Jan 13 05:49:40 andromeda NetworkManager: <information>^IActivation (eth1) Stage 3 of 5 (IP Configure Start) scheduled. 
Jan 13 05:49:40 andromeda NetworkManager: <information>^IActivation (eth1) Stage 3 of 5 (IP Configure Start) started... 
Jan 13 05:49:41 andromeda NetworkManager: <information>^IActivation (eth1) Beginning DHCP transaction. 
Jan 13 05:49:41 andromeda NetworkManager: <information>^IActivation (eth1) Stage 3 of 5 (IP Configure Start) complete. 
Jan 13 05:49:41 andromeda NetworkManager: <information>^Iwpa_supplicant(7896): =0 Pairwise Ack) 
Jan 13 05:49:41 andromeda NetworkManager: <information>^Iwpa_supplicant(7896):   key_length=16 key_data_length=22 
Jan 13 05:49:41 andromeda NetworkManager: <information>^Iwpa_supplicant(7896):   replay_counter - hexdump(len=8): 00 00 00 00 00 00 00 02 
Jan 13 05:49:41 andromeda NetworkManager: <information>^Iwpa_supplicant(7896):   key_nonce - hexdump(len=32): c9 26 76 75 51 7a 91 b3 26 28 31 33 48 ab c8 40 a8 12 29 61 b1 85 69 26 69 ce 63 11 fc 0f 0e 86 
Jan 13 05:49:41 andromeda NetworkManager: <information>^Iwpa_supplicant(7896):   key_iv - hexdump(len=16): 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 
Jan 13 05:49:41 andromeda NetworkManager: <information>^Iwpa_supplicant(7896):   key_rsc - hexdump(len=8): 00 00 00 00 00 00 00 00 
Jan 13 05:49:41 andromeda NetworkManager: <information>^Iwpa_supplicant(7896):   key_id (reserved) - hexdump(len=8): 00 00 00 00 00 00 00 00 
Jan 13 05:49:41 andromeda NetworkManager: <information>^Iwpa_supplicant(7896):   key_mic - hexdump(len=16): 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 
Jan 13 05:49:41 andromeda NetworkManager: <information>^Iwpa_supplicant(7896): WPA: RX EAPOL-Key - hexdump(len=121): 02 03 00 75 02 00 8a 00 10 00 00 00 00 00 00 00 02 c9 26 76 75 51 7a 91 b3 26 28 31 33 48 ab c8 40 a8 12 29 61 b1 85 69 26 69 ce 63 11 fc 0f 0e 86 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 16 dd 14 00 0f ac 04 45 0c d9 02 eb 7e ed d4 9f 60 db f4 db f6 3d 1c 
Jan 13 05:49:41 andromeda NetworkManager: <information>^Iwpa_supplicant(7896): State: ASSOCIATING -> 4WAY_HANDSHAKE 
Jan 13 05:49:41 andromeda NetworkManager: <information>^Iwpa_supplicant(7896): WPA: RX message 1 of 4-Way Handshake from 00:14:bf:05:2f:3e (ver=2) 
Jan 13 05:49:41 andromeda NetworkManager: <information>^Iwpa_supplicant(7896):  key data - hexdump(len=22): dd 14 00 0f ac 04 45 0c d9 02 eb 7e ed d4 9f 60 db f4 db f6 3d 1c 
Jan 13 05:49:41 andromeda NetworkManager: <information>^Iwpa_supplicant(7896): RSN: PMKID from Authenticator - hexdump(len=16): 45 0c d9 02 eb 7e ed d4 9f 60 db f4 db f6 3d 1c 
Jan 13 05:49:41 andromeda dhclient: There is already a pid file /var/run/dhclient.eth1.pid with pid 134993440
Jan 13 05:49:41 andromeda NetworkManager: <information>^Iwpa_supplicant(7896): RSN: no matching PMKID found 
Jan 13 05:49:41 andromeda NetworkManager: <information>^Iwpa_supplicant(7896): WPA: Renewed SNonce - hexdump(len=32): 0e 38 64 07 0e 5b 95 4e 77 83 98 11 21 e5 9e d2 70 b4 34 46 49 ba 10 40 a8 2c 6d 87 0d ac ce 30 
Jan 13 05:49:41 andromeda NetworkManager: <information>^Iwpa_supplicant(7896): WPA: PMK - hexdump(len=32): [REMOVED] 
Jan 13 05:49:41 andromeda NetworkManager: <information>^Iwpa_supplicant(7896): WPA: PTK - hexdump(len=64): [REMOVED] 
Jan 13 05:49:41 andromeda NetworkManager: <information>^Iwpa_supplicant(7896): WPA: WPA IE for msg 2/4 - hexdump(len=22): 30 14 01 00 00 0f ac 04 01 00 00 0f ac 04 01 00 00 0f ac 02 00 00 
Jan 13 05:49:41 andromeda NetworkManager: <information>^Iwpa_supplicant(7896): WPA: Sending EAPOL-Key 2/4 
Jan 13 05:49:41 andromeda NetworkManager: <information>^Iwpa_supplicant(7896): WPA: TX EAPOL-Key - hexdump(len=121): 01 03 00 75 02 01 0a 00 00 00 00 00 00 00 00 00 02 0e 38 64 07 0e 5b 95 4e 77 83 98 11 21 e5 9e d2 70 b4 34 46 49 ba 10 40 a8 2c 6d 87 0d ac ce 30 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 20 d0 e8 b2 e6 1f 65 48 b8 c2 5e a9 32 ec b2 97 00 16 30 14 01 00 00 0f ac 04 01 00 00 0f ac 04 01 00 00 0f ac 02 00 00 
Jan 13 05:49:41 andromeda NetworkManager: <information>^Iwpa_supplicant(7896): RTM_NEWLINK, IFLA_IFNAME: Interface 'eth1' added 
Jan 13 05:49:41 andromeda NetworkManager: <information>^Iwpa_supplicant(7896): ess event: cmd=0x8b15 len=20 
Jan 13 05:49:41 andromeda NetworkManager: <information>^Iwpa_supplicant(7896): Wireless event: new AP: 00:14:bf:05:2f:3e 
Jan 13 05:49:41 andromeda NetworkManager: <information>^Iwpa_supplicant(7896): State: 4WAY_HANDSHAKE -> ASSOCIATED 
Jan 13 05:49:41 andromeda NetworkManager: <information>^Iwpa_supplicant(7896): WEXT: Operstate: linkmode=-1, operstate=5 
Jan 13 05:49:41 andromeda NetworkManager: <information>^Iwpa_supplicant(7896): Associated to a new BSS: BSSID=00:14:bf:05:2f:3e 
Jan 13 05:49:41 andromeda NetworkManager: <information>^Iwpa_supplicant(7896): No keys have been configured - skip key clearing 
Jan 13 05:49:41 andromeda NetworkManager: <information>^Iwpa_supplicant(7896): Associated with 00:14:bf:05:2f:3e 
Jan 13 05:49:41 andromeda NetworkManager: <information>^Iwpa_supplicant(7896): CTRL_IFACE monitor send - hexdump(len=40): 2f 76 61 72 2f 72 75 6e 2f 4e 65 74 77 6f 72 6b 4d 61 6e 61 67 65 72 2f 77 70 61 5f 63 74 72 6c 5f 34 32 33 31 2d 33 00 
Jan 13 05:49:41 andromeda NetworkManager: <information>^Iwpa_supplicant(7896): WPA: Association event - clear replay counter 
Jan 13 05:49:41 andromeda NetworkManager: <information>^Iwpa_supplicant(7896): EAPOL: External notification - portEnabled=0 
Jan 13 05:49:41 andromeda NetworkManager: <information>^Iwpa_supplicant(7896): EAPOL: External notification - portValid=0 
Jan 13 05:49:41 andromeda NetworkManager: <information>^Iwpa_supplicant(7896): EAPOL: External notification - EAP success=0 
Jan 13 05:49:41 andromeda NetworkManager: <information>^Iwpa_supplicant(7896): EAPOL: External notification - portEnabled=1 
Jan 13 05:49:41 andromeda NetworkManager: <information>^Iwpa_supplicant(7896): EAPOL: SUPP_PAE entering state CONNECTING 
Jan 13 05:49:41 andromeda NetworkManager: <information>^Iwpa_supplicant(7896): EAPOL: SUPP_BE entering state IDLE 
Jan 13 05:49:41 andromeda NetworkManager: <information>^Iwpa_supplicant(7896): Setting authentication timeout: 10 sec 0 usec 
Jan 13 05:49:41 andromeda NetworkManager: <information>^Iwpa_supplicant(7896): RX EAPOL from 00:14:bf:05:2f:3e 
Jan 13 05:49:41 andromeda NetworkManager: <information>^IDHCP daemon state is now 12 (successfully started) for interface eth1 
Jan 13 05:49:42 andromeda NetworkManager: <information>^IDHCP daemon state is now 1 (starting) for interface eth1 
Jan 13 05:49:45 andromeda dhclient: DHCPDISCOVER on eth1 to 255.255.255.255 port 67 interval 3
Jan 13 05:49:45 andromeda dhclient: DHCPOFFER from 192.168.1.1
Jan 13 05:49:45 andromeda dhclient: DHCPREQUEST on eth1 to 255.255.255.255 port 67
Jan 13 05:49:45 andromeda dhclient: DHCPACK from 192.168.1.1
Jan 13 05:49:45 andromeda dhclient: bound to 192.168.1.100 -- renewal in 40303 seconds.
Jan 13 05:49:45 andromeda NetworkManager: <information>^IDHCP daemon state is now 2 (bound) for interface eth1 
Jan 13 05:49:45 andromeda NetworkManager: <information>^IActivation (eth1) Stage 4 of 5 (IP Configure Get) scheduled... 
Jan 13 05:49:45 andromeda NetworkManager: <information>^IActivation (eth1) Stage 4 of 5 (IP Configure Get) started... 
Jan 13 05:49:45 andromeda dhcdbd: message_handler: message handler not found under /com/redhat/dhcp/eth1 for sub-path eth1.dbus.get.host_name
Jan 13 05:49:45 andromeda dhcdbd: message_handler: message handler not found under /com/redhat/dhcp/eth1 for sub-path eth1.dbus.get.nis_domain
Jan 13 05:49:45 andromeda dhcdbd: message_handler: message handler not found under /com/redhat/dhcp/eth1 for sub-path eth1.dbus.get.nis_servers
Jan 13 05:49:45 andromeda NetworkManager: <information>^IRetrieved the following IP4 configuration from the DHCP daemon: 
Jan 13 05:49:45 andromeda NetworkManager: <information>^I  address 192.168.1.100 
Jan 13 05:49:45 andromeda NetworkManager: <information>^I  netmask 255.255.255.0 
Jan 13 05:49:45 andromeda NetworkManager: <information>^I  broadcast 192.168.1.255 
Jan 13 05:49:45 andromeda NetworkManager: <information>^I  gateway 192.168.1.1 
Jan 13 05:49:45 andromeda NetworkManager: <information>^I  nameserver 214.76.203.21 
Jan 13 05:49:45 andromeda NetworkManager: <information>^I  nameserver 214.76.203.23 
Jan 13 05:49:45 andromeda NetworkManager: <information>^I  domain name 'cpe.myisp.net' 
Jan 13 05:49:45 andromeda NetworkManager: <information>^IActivation (eth1) Stage 5 of 5 (IP Configure Commit) scheduled... 
Jan 13 05:49:45 andromeda NetworkManager: <information>^IActivation (eth1) Stage 4 of 5 (IP Configure Get) complete. 
Jan 13 05:49:45 andromeda NetworkManager: <information>^IActivation (eth1) Stage 5 of 5 (IP Configure Commit) started... 
Jan 13 05:49:46 andromeda NetworkManager: <information>^IDHCP returned name servers but system has disabled dynamic modification! 
Jan 13 05:49:46 andromeda NetworkManager: <information>^IActivation (eth1) Finish handler scheduled. 
Jan 13 05:49:46 andromeda NetworkManager: <information>^IActivation (eth1) Stage 5 of 5 (IP Configure Commit) complete. 
Jan 13 05:49:46 andromeda NetworkManager: <information>^IActivation (eth1) successful, device activated. 
Jan 13 05:49:47 andromeda modprobe: WARNING: Not loading blacklisted module ipv6 
Jan 13 05:49:47 andromeda modprobe: WARNING: Not loading blacklisted module ipv6 
Jan 13 05:49:47 andromeda ntpdate[7956]: step time server 82.211.81.145 offset -0.372831 sec
 
Old 02-03-2007, 04:23 AM   #9
win32sux
LQ Guru
 
Registered: Jul 2003
Location: Los Angeles
Distribution: Ubuntu
Posts: 9,870

Original Poster
Rep: Reputation: 380Reputation: 380Reputation: 380Reputation: 380
hi guys... just wanted to let you know that a couple days ago i upgraded the ipw3945 firmware to version 1.14.2 and the issue was resolved... as a bonus, wireless performance in general was also improved...

Last edited by win32sux; 02-03-2007 at 10:32 PM.
 
  


Reply



Posting Rules
You may not post new threads
You may not post replies
You may not post attachments
You may not edit your posts

BB code is On
Smilies are On
[IMG] code is Off
HTML code is Off



Similar Threads
Thread Thread Starter Forum Replies Last Post
hardware related freeze dukeinlondon Linux - Hardware 5 05-12-2006 01:30 PM
...'stuttering' in Linux, Shuttle XPC setup, HDD related? Bencrest Linux - Hardware 1 03-12-2006 12:39 AM
Temporary System Freeze mingli Red Hat 2 05-09-2004 09:33 PM
USB Mouse temporary freeze in X-windows jmtuser Linux - Hardware 0 02-10-2004 09:13 AM
weird DNS related (?) issue bobcat Linux - Networking 1 06-10-2002 08:08 PM

LinuxQuestions.org > Forums > Linux Forums > Linux - Laptop and Netbook

All times are GMT -5. The time now is 03:39 AM.

Main Menu
Advertisement
My LQ
Write for LQ
LinuxQuestions.org is looking for people interested in writing Editorials, Articles, Reviews, and more. If you'd like to contribute content, let us know.
Main Menu
Syndicate
RSS1  Latest Threads
RSS1  LQ News
Twitter: @linuxquestions
Open Source Consulting | Domain Registration