Re: [zd1211-devs] essid strangeness
On 12/19/06, Daniel Drake [EMAIL PROTECTED] wrote: Jon Smirl wrote: ifup eth1 [ 1457.671472] SoftMAC: Associate: Scanning for networks first. [ 1457.671487] SoftMAC: Associate: failed to initiate scan. Is device up? You have some configuration agent running which is screwing things up. The driver does *not* do this when you bring the interface up, it just sits on the default channel. I'm using Ubuntu, it may have some agent going that I don't know about. From these traces it looks like there is a missing mutex or something that is needed to keep thing synchronized given the way Ubuntu brings the network up. When softmac called back into the driver and the driver wasn't fully initialized, it should have held softmac up instead of returning an error. I disabled auto start in /etc/network/interfaces auto lo iface lo inet loopback iface eth0 inet dhcp auto eth0 iface eth1 inet dhcp wireless-essid Smirl wireless-key F When I boot the zd1211rw module was loaded correctly. Now I type ifup eth1 and get this trace. [ 3487.283681] ieee80211_crypt: registered algorithm 'WEP' [ 3487.326061] zd1211rw 5-3.6:1.0: set_security() [ 3487.326069] zd1211rw 5-3.6:1.0: set_security().active_key = 0 [ 3487.326074] zd1211rw 5-3.6:1.0: set_security().level = 1 [ 3487.326078] zd1211rw 5-3.6:1.0: set_security().enabled = 1 [ 3487.326082] zd1211rw 5-3.6:1.0: set_security().encrypt = 1 [ 3487.330038] SoftMAC: Associate: Scanning for networks first. [ 3487.330047] SoftMAC: Associate: failed to initiate scan. Is device up? [ 3487.386892] zd1211rw 5-3.6:1.0: zd_usb_enable_int() [ 3487.386902] zd1211rw 5-3.6:1.0: zd_usb_enable_int() submit urb cf5696c0 [ 3487.386971] zd1211rw 5-3.6:1.0: zd_chip_set_basic_rates_locked() ff0f [ 3487.388904] zd1211rw 5-3.6:1.0: zd_chip_lock_phy_regs() CR_REG1: 0xa0 - 0x20 [ 3487.390900] zd1211rw 5-3.6:1.0: zd_chip_unlock_phy_regs() CR_REG1: 0x20 - 0xa0 [ 3487.392914] zd1211rw 5-3.6:1.0: zd_chip_lock_phy_regs() CR_REG1: 0xa0 - 0x20 [ 3487.393571] zd1211rw 5-3.6:1.0: zd_rf_set_channel() channel: 1 [ 3487.398539] zd1211rw 5-3.6:1.0: update_pwr_int() channel 1 pwr_int 0x54 [ 3487.399420] zd1211rw 5-3.6:1.0: update_ofdm_cal() channel 1 ofdm_cal 36M 0xba 48M 0xb2 54M 0xa5 [ 3487.400291] zd1211rw 5-3.6:1.0: update_pwr_cal() channel 1 pwr_cal 0xd7 [ 3487.403893] zd1211rw 5-3.6:1.0: patch_cck_gain() patching value 12 [ 3487.406897] zd1211rw 5-3.6:1.0: zd_chip_unlock_phy_regs() CR_REG1: 0x20 - 0xa0 [ 3487.408028] zd1211rw 5-3.6:1.0: zd_usb_enable_rx() [ 3487.409146] zd1211rw 5-3.6:1.0: housekeeping_enable() [ 3487.409160] zd1211rw 5-3.6:1.0: bssinfo_change() changes: 7 [ 3487.410401] zd1211rw 5-3.6:1.0: zd_chip_set_rts_cts_rate_locked() rts_rate=0 preamble=0 [ 3487.411017] zd1211rw 5-3.6:1.0: zd_chip_set_basic_rates_locked() ff0f [ 3487.411647] zd1211rw 5-3.6:1.0: zd_chip_set_multicast_hash() hash l 0x h 0x8000 [ 3487.412543] zd1211rw 5-3.6:1.0: zd_mac_set_multicast_list() mc addr 01:00:5e:00:00:01 [ 3487.415497] zd1211rw 5-3.6:1.0: zd_mac_set_multicast_list() mc addr 01:00:5e:00:00:01 [ 3487.415510] zd1211rw 5-3.6:1.0: zd_chip_set_multicast_hash() hash l 0x0001 h 0x8000 [ 3487.416969] zd1211rw 5-3.6:1.0: zd_mac_set_multicast_list() mc addr 01:00:5e:00:00:01 [ 3487.416984] zd1211rw 5-3.6:1.0: zd_chip_set_multicast_hash() hash l 0x0001 h 0x8000 I wait for DHCP to time out, and type iwconfig eth1 essid Smirl [ 3688.373698] SoftMAC: Canceling existing associate request! [ 3688.373725] SoftMAC: Associate: Scanning for networks first. [ 3688.373737] zd1211rw 5-3.6:1.0: set_channel() channel 1 [ 3688.374837] zd1211rw 5-3.6:1.0: zd_chip_lock_phy_regs() CR_REG1: 0xa0 - 0x20 [ 3688.375479] zd1211rw 5-3.6:1.0: zd_rf_set_channel() channel: 1 [ 3688.379454] zd1211rw 5-3.6:1.0: update_pwr_int() channel 1 pwr_int 0x54 [ 3688.380079] zd1211rw 5-3.6:1.0: update_ofdm_cal() channel 1 ofdm_cal 36M 0xba 48M 0xb2 54M 0xa5 [ 3688.380711] zd1211rw 5-3.6:1.0: update_pwr_cal() channel 1 pwr_cal 0xd7 [ 3688.383818] zd1211rw 5-3.6:1.0: patch_cck_gain() patching value 12 [ 3688.385805] zd1211rw 5-3.6:1.0: zd_chip_unlock_phy_regs() CR_REG1: 0x20 - 0xa0 [ 3688.406540] zd1211rw 5-3.6:1.0: set_channel() channel 2 [ 3688.409770] zd1211rw 5-3.6:1.0: zd_chip_lock_phy_regs() CR_REG1: 0xa0 - 0x20 [ 3688.410275] zd1211rw 5-3.6:1.0: zd_rf_set_channel() channel: 2 [ 3688.414158] zd1211rw 5-3.6:1.0: update_pwr_int() channel 2 pwr_int 0x5c ... [ 3688.668910] zd1211rw 5-3.6:1.0: update_pwr_cal() channel 10 pwr_cal 0xd7 [ 3688.671264] zd1211rw 5-3.6:1.0: patch_cck_gain() patching value 12 [ 3688.673261] zd1211rw 5-3.6:1.0: zd_chip_unlock_phy_regs() CR_REG1: 0x20 - 0xa0 [ 3688.694038] zd1211rw 5-3.6:1.0: set_channel() channel 11 [ 3688.695222] zd1211rw 5-3.6:1.0: zd_chip_lock_phy_regs() CR_REG1: 0xa0 - 0x20 [ 3688.695858] zd1211rw 5-3.6:1.0: zd_rf_set_channel() channel: 11 [ 3688.699599] zd1211rw 5-3.6:1.0: update_pwr_int() channel 11 pwr_int 0x5c [ 3688.700224] zd1211rw 5-3.6:1.0:
Re: [zd1211-devs] essid strangeness
Jon Smirl wrote: It looks to me like Ubuntu told the interface to initialize and then told it to associate before the initialization was complete. No - your trace clearly shows that it is setting security keys and trying to associate *before* the interface is brought up. Daniel - Take Surveys. Earn Cash. Influence the Future of IT Join SourceForge.net's Techsay panel and you'll get the chance to share your opinions on IT business topics through brief surveys - and earn cash http://www.techsay.com/default.php?page=join.phpp=sourceforgeCID=DEVDEV ___ Zd1211-devs mailing list - http://zd1211.ath.cx/ Unsubscribe: https://lists.sourceforge.net/lists/listinfo/zd1211-devs
Re: [zd1211-devs] essid strangeness
On 12/19/06, Daniel Drake [EMAIL PROTECTED] wrote: Jon Smirl wrote: I disabled auto start in /etc/network/interfaces /etc/network/interface is an Ubuntu thing, you want to completely disable whatever is using it. Now I type ifup eth1 and get this trace. Your trace shows that something is trying to associate before the interface has been brought up. This is a distro bug. I'll download the source of ifup and see what it is doing, but I'm not having trouble with other network cards. It looks to me like Ubuntu told the interface to initialize and then told it to associate before the initialization was complete. Who's responsibility is it to coordinate the timing so that the associate request is not made before initialization finishes? Daniel -- Jon Smirl [EMAIL PROTECTED] - Take Surveys. Earn Cash. Influence the Future of IT Join SourceForge.net's Techsay panel and you'll get the chance to share your opinions on IT business topics through brief surveys - and earn cash http://www.techsay.com/default.php?page=join.phpp=sourceforgeCID=DEVDEV ___ Zd1211-devs mailing list - http://zd1211.ath.cx/ Unsubscribe: https://lists.sourceforge.net/lists/listinfo/zd1211-devs
Re: [zd1211-devs] essid strangeness
On 12/19/06, Jon Smirl [EMAIL PROTECTED] wrote: On 12/19/06, Daniel Drake [EMAIL PROTECTED] wrote: Jon Smirl wrote: It looks to me like Ubuntu told the interface to initialize and then told it to associate before the initialization was complete. No - your trace clearly shows that it is setting security keys and trying to associate *before* the interface is brought up. Could other wireless drivers allow you to set the key/essid while they are down, and then process them only after the link is brought up? That appears to be the way the Ubuntu scripts are working, they configure in the key/essid first then bring the link up. This problem is being discussed in this bug on Ubuntu. https://launchpad.net/distros/ubuntu/+source/linux-source-2.6.17/+bug/63664 Daniel -- Jon Smirl [EMAIL PROTECTED] -- Jon Smirl [EMAIL PROTECTED] - Take Surveys. Earn Cash. Influence the Future of IT Join SourceForge.net's Techsay panel and you'll get the chance to share your opinions on IT business topics through brief surveys - and earn cash http://www.techsay.com/default.php?page=join.phpp=sourceforgeCID=DEVDEV ___ Zd1211-devs mailing list - http://zd1211.ath.cx/ Unsubscribe: https://lists.sourceforge.net/lists/listinfo/zd1211-devs
Re: [zd1211-devs] essid strangeness
On Sun, 2006-12-17 at 18:31 -0500, Jon Smirl wrote: Linus git kernel, 2.6.20-rc1+ patched softmac for work queue changes Ubuntu Edgy WEP encryption ifup eth1 Won't start the network, if I look in dmesg ADDRCONF(NETDEV_UP): eth1: link is not ready ifup will timeout after a while because it can't get a DCHP addres If I set the essid manually everything works iwconfig eth1 essid Smirl SoftMAC: Open Authentication completed with 00:0f:b5:5e:98:26 ADDRCONF(NETDEV_CHANGE): eth1: link becomes ready Essid is set correctly in /etc/network/interfaces I have the same problem, on ubuntu feisty, described in this recent thread: [zd1211-devs] zd1211rw problems, bit rate, icwonfig Date: Fri, 15 Dec 2006 21:43:07 +0100 /Henrik Hjelte - Take Surveys. Earn Cash. Influence the Future of IT Join SourceForge.net's Techsay panel and you'll get the chance to share your opinions on IT business topics through brief surveys - and earn cash http://www.techsay.com/default.php?page=join.phpp=sourceforgeCID=DEVDEV ___ Zd1211-devs mailing list - http://zd1211.ath.cx/ Unsubscribe: https://lists.sourceforge.net/lists/listinfo/zd1211-devs
Re: [zd1211-devs] essid strangeness
Is this a problem with Ubuntu not setting the essid when the interface first comes up? Or is it a problem with the driver not performing the first setting of the essid correctly? Other than this issue everything seems to be working for me. On 12/18/06, Henrik Hjelte [EMAIL PROTECTED] wrote: On Sun, 2006-12-17 at 18:31 -0500, Jon Smirl wrote: Linus git kernel, 2.6.20-rc1+ patched softmac for work queue changes Ubuntu Edgy WEP encryption ifup eth1 Won't start the network, if I look in dmesg ADDRCONF(NETDEV_UP): eth1: link is not ready ifup will timeout after a while because it can't get a DCHP addres If I set the essid manually everything works iwconfig eth1 essid Smirl SoftMAC: Open Authentication completed with 00:0f:b5:5e:98:26 ADDRCONF(NETDEV_CHANGE): eth1: link becomes ready Essid is set correctly in /etc/network/interfaces I have the same problem, on ubuntu feisty, described in this recent thread: [zd1211-devs] zd1211rw problems, bit rate, icwonfig Date: Fri, 15 Dec 2006 21:43:07 +0100 /Henrik Hjelte -- Jon Smirl [EMAIL PROTECTED] - Take Surveys. Earn Cash. Influence the Future of IT Join SourceForge.net's Techsay panel and you'll get the chance to share your opinions on IT business topics through brief surveys - and earn cash http://www.techsay.com/default.php?page=join.phpp=sourceforgeCID=DEVDEV ___ Zd1211-devs mailing list - http://zd1211.ath.cx/ Unsubscribe: https://lists.sourceforge.net/lists/listinfo/zd1211-devs
Re: [zd1211-devs] essid strangeness
I am using different hardware but that doesn't seem to be the problem. zd1211rw 5-3:1.0: firmware version 4721 zd1211rw 5-3:1.0: zd1211b chip 0ace:1215 v4810 high 00-02-72 AL2230_RF pa0 g--N -- Jon Smirl [EMAIL PROTECTED] - Take Surveys. Earn Cash. Influence the Future of IT Join SourceForge.net's Techsay panel and you'll get the chance to share your opinions on IT business topics through brief surveys - and earn cash http://www.techsay.com/default.php?page=join.phpp=sourceforgeCID=DEVDEV ___ Zd1211-devs mailing list - http://zd1211.ath.cx/ Unsubscribe: https://lists.sourceforge.net/lists/listinfo/zd1211-devs
Re: [zd1211-devs] essid strangeness
I turned up debug in the zd1211rw driver and can see what the problem is. Tomorrow I'll see if I can learn enough about the driver to fix it. The problem has nothing to do with setting an essid, that just happens to be a way to recover from it. modprobe the driver in. [ 1850.103620] zd1211rw 5-3.6:1.0: zd1211b chip 0ace:1215 v4810 high 00-02-72 AL2230_RF pa0 g--N [ 1850.103628] zd1211rw 5-3.6:1.0: zd_write_mac_addr() mac addr 00:02:72:53:e3:c7 [ 1850.107608] zd1211rw 5-3.6:1.0: zd_read_regdomain() regdomain: 0x10 [ 1850.108122] zd1211rw 5-3.6:1.0: regdomain 0x10 [ 1850.108210] zd1211rw 5-3.6:1.0: zd_usb_disable_int() urb f5a6e8c0 killed [ 1850.108319] zd1211rw 5-3.6:1.0: probe() successful [ 1850.108324] zd1211rw 5-3.6:1.0: eth1 [ 1850.108343] usbcore: registered new interface driver zd1211rw [ 1850.108349] zd1211rw initialized [ 1889.326326] zd1211rw 5-3.6:1.0: set_security() [ 1889.326334] zd1211rw 5-3.6:1.0: set_security().active_key = 0 [ 1889.326339] zd1211rw 5-3.6:1.0: set_security().level = 1 [ 1889.326344] zd1211rw 5-3.6:1.0: set_security().enabled = 1 [ 1889.326347] zd1211rw 5-3.6:1.0: set_security().encrypt = 1 [ 1889.328839] SoftMAC: Associate: Scanning for networks first. [ 1889.328847] SoftMAC: Associate: failed to initiate scan. Is device up? Looks like we tried to initiate a scan before the device was completely initialized [ 1889.357563] zd1211rw 5-3.6:1.0: zd_usb_enable_int() [ 1889.357755] zd1211rw 5-3.6:1.0: zd_usb_enable_int() submit urb f5a6e2c0 [ 1889.358061] zd1211rw 5-3.6:1.0: zd_chip_set_basic_rates_locked() ff0f [ 1889.360399] zd1211rw 5-3.6:1.0: zd_chip_lock_phy_regs() CR_REG1: 0xa0 - 0x20 [ 1889.365376] zd1211rw 5-3.6:1.0: zd_chip_unlock_phy_regs() CR_REG1: 0x20 - 0xa0 [ 1889.367367] zd1211rw 5-3.6:1.0: zd_chip_lock_phy_regs() CR_REG1: 0xa0 - 0x20 [ 1889.368364] zd1211rw 5-3.6:1.0: zd_rf_set_channel() channel: 1 [ 1889.373485] zd1211rw 5-3.6:1.0: update_pwr_int() channel 1 pwr_int 0x54 [ 1889.374470] zd1211rw 5-3.6:1.0: update_ofdm_cal() channel 1 ofdm_cal 36M 0xba 48M 0xb2 54M 0xa5 [ 1889.375481] zd1211rw 5-3.6:1.0: update_pwr_cal() channel 1 pwr_cal 0xd7 [ 1889.379353] zd1211rw 5-3.6:1.0: patch_cck_gain() patching value 12 [ 1889.382359] zd1211rw 5-3.6:1.0: zd_chip_unlock_phy_regs() CR_REG1: 0x20 - 0xa0 [ 1889.383600] zd1211rw 5-3.6:1.0: zd_usb_enable_rx() [ 1889.389389] zd1211rw 5-3.6:1.0: housekeeping_enable() [ 1889.389417] zd1211rw 5-3.6:1.0: bssinfo_change() changes: 7 [ 1889.389751] zd1211rw 5-3.6:1.0: zd_mac_set_multicast_list() mc addr 01:00:5e:00:00:01 [ 1889.393980] zd1211rw 5-3.6:1.0: zd_mac_set_multicast_list() mc addr 01:00:5e:00:00:01 [ 1889.394469] zd1211rw 5-3.6:1.0: zd_chip_set_rts_cts_rate_locked() rts_rate=0 preamble=0 [ 1889.394971] zd1211rw 5-3.6:1.0: zd_chip_set_basic_rates_locked() ff0f [ 1889.395199] zd1211rw 5-3.6:1.0: zd_mac_set_multicast_list() mc addr 01:00:5e:00:00:01 [ 1889.395593] zd1211rw 5-3.6:1.0: zd_chip_set_multicast_hash() hash l 0x0001 h 0x8000 Now chip is fully initialized When I type, iwconfig eth1 essid Smirl, it cancels the first Associate request and starts a new one. This one will work because the device is now initialized. [ 1955.592897] SoftMAC: Canceling existing associate request! [ 1955.593000] SoftMAC: Associate: Scanning for networks first. [ 1955.593010] zd1211rw 5-3.6:1.0: set_channel() channel 1 [ 1955.594136] zd1211rw 5-3.6:1.0: zd_chip_lock_phy_regs() CR_REG1: 0xa0 - 0x20 [ 1955.594768] zd1211rw 5-3.6:1.0: zd_rf_set_channel() channel: 1 [ 1955.598507] zd1211rw 5-3.6:1.0: update_pwr_int() channel 1 pwr_int 0x54 [ 1955.599130] zd1211rw 5-3.6:1.0: update_ofdm_cal() channel 1 ofdm_cal 36M 0xba 48M 0xb2 54M 0xa5 [ 1955.599754] zd1211rw 5-3.6:1.0: update_pwr_cal() channel 1 pwr_cal 0xd7 [ 1955.602111] zd1211rw 5-3.6:1.0: patch_cck_gain() patching value 12 [ 1955.604107] zd1211rw 5-3.6:1.0: zd_chip_unlock_phy_regs() CR_REG1: 0x20 - 0xa0 [ 1955.624511] zd1211rw 5-3.6:1.0: set_channel() channel 2 [ 1955.626068] zd1211rw 5-3.6:1.0: zd_chip_lock_phy_regs() CR_REG1: 0xa0 - 0x20 [ 1955.626703] zd1211rw 5-3.6:1.0: zd_rf_set_channel() channel: 2 [ 1955.630446] zd1211rw 5-3.6:1.0: update_pwr_int() channel 2 pwr_int 0x5c [ 1955.631071] zd1211rw 5-3.6:1.0: update_ofdm_cal() channel 2 ofdm_cal 36M 0xba 48M 0xb2 54M 0xa5 [ 1955.631694] zd1211rw 5-3.6:1.0: update_pwr_cal() channel 2 pwr_cal 0xd7 [ 1955.634050] zd1211rw 5-3.6:1.0: patch_cck_gain() patching value 12 [ 1955.636047] zd1211rw 5-3.6:1.0: zd_chip_unlock_phy_regs() CR_REG1: 0x20 - 0xa0 [ 1955.656329] zd1211rw 5-3.6:1.0: set_channel() channel 3 [ 1955.969417] zd1211rw 5-3.6:1.0: zd_chip_unlock_phy_regs() CR_REG1: 0x20 - 0xa0 [ 1955.970060] SoftMAC: sent association request! [ 1955.971791] SoftMAC: associated! -- Jon Smirl [EMAIL PROTECTED] - Take Surveys. Earn Cash. Influence the Future of IT Join SourceForge.net's Techsay panel and you'll get the chance to share your