Opened 5 years ago

Last modified 4 years ago

#15812 new bug

iprowifi2200 fails to establish connection

Reported by: nephele Owned by: nobody
Priority: normal Milestone: Unscheduled
Component: Drivers/Network/iprowifi2200 Version: R1/Development
Keywords: Cc:
Blocked By: Blocking:
Platform: All

Description

this is hrev53967 x86_gcc2 with a 2.4GhZ wifi via WPA2

The dialog to enter the password will just reappear periodically, expected is that the wifi gets connected.

syslog entries:

KERN: wlan_control: 9235, 15
KERN: wlan_control: 9235, 76
KERN: Last message repeated 9 times.
KERN: wlan_control: 9235, 78
KERN: wlan_control: 9235, 76
KERN: register_domain(9, unix)
KERN: wlan_control: 9235, 16
KERN: wlan_control: 9235, 17
KERN: wlan_control: 9235, 26
KERN: wlan_control: 9235, 98
KERN: wlan_close(0xe27dc400)
KERN: [net/iprowifi2200/0] stop running, 1 vaps running
KERN: [net/iprowifi2200/0] ieee80211_new_state_locked: SCAN -> INIT (nrunning 0 nscanning 0)
KERN: [net/iprowifi2200/0] down parent 
KERN: ieee80211_notify_scan_done
KERN: [net/iprowifi2200/0] ieee80211_newstate_cb: SCAN -> INIT arg -1
KERN: [net/iprowifi2200/0] sta_newstate: SCAN -> INIT (-1)
KERN: [net/iprowifi2200/0] node_reclaim: remove 0xe426c000<00:16:6f:a4:bc:d2> from station table, refcnt 1
KERN: [net/iprowifi2200/0] ieee80211_alloc_node 0xf0c26000<00:16:6f:a4:bc:d2> in station table
KERN: [net/iprowifi2200/0] [00:16:6f:a4:bc:d2] ieee80211_alloc_node: inact_reload 2
KERN: wlan_control: 9234, 20
KERN: Last message repeated 3 times.
KERN: wlan_control: 9234, 25
KERN: wlan_control: 9235, 76
KERN: ifmedia_ioctl: switching wlan to   Type: IEEE 802.11 Wireless Ethernet
KERN:   Mode: autoselect
KERN:   SubType: autoselect
KERN: wlan_control: 9234, 16
KERN: wlan_control: 9234, 17
KERN: wlan_control: 9234, 26
KERN: wlan_open(0xe27dc400)
KERN: [net/iprowifi2200/0] ieee80211_init
KERN: [net/iprowifi2200/0] start running, 0 vaps running
KERN: [net/iprowifi2200/0] ieee80211_start_locked: up parent 
KERN: [net/iprowifi2200/0] start running, 1 vaps running
KERN: wlan_control: 9234, 103
KERN: [net/iprowifi2200/0] ieee80211_new_state_locked: INIT -> SCAN (nrunning 0 nscanning 0)
KERN: [net/iprowifi2200/0] start running, 1 vaps running
KERN: [net/iprowifi2200/0] ieee80211_newstate_cb: INIT -> SCAN arg 0
KERN: [net/iprowifi2200/0] sta_newstate: INIT -> SCAN (0)
KERN: [iprowifi2200] (iwi) radio turned off
KERN: ieee80211_notify_radio not implemented, yet.
KERN: wlan_control: 9234, 25
KERN: wlan_control: 9234, 95
KERN: wlan_control: 9234, 17
KERN: wlan_control: 9234, 26
KERN: wlan_close(0xe27dc400)
KERN: [net/iprowifi2200/0] stop running, 1 vaps running
KERN: [net/iprowifi2200/0] ieee80211_new_state_locked: SCAN -> INIT (nrunning 0 nscanning 0)
KERN: [net/iprowifi2200/0] down parent 
KERN: ieee80211_notify_scan_done
KERN: [net/iprowifi2200/0] ieee80211_newstate_cb: SCAN -> INIT arg -1
KERN: [net/iprowifi2200/0] sta_newstate: SCAN -> INIT (-1)
KERN: [net/iprowifi2200/0] node_reclaim: remove 0xf0c26000<00:16:6f:a4:bc:d2> from station table, refcnt 1
KERN: [net/iprowifi2200/0] ieee80211_alloc_node 0xf0c24000<00:16:6f:a4:bc:d2> in station table
KERN: [net/iprowifi2200/0] [00:16:6f:a4:bc:d2] ieee80211_alloc_node: inact_reload 2
KERN: wlan_control: 9234, 95
KERN: wlan_control: 9234, 17
KERN: wlan_control: 9234, 26
KERN: wlan_control: 9234, 16
KERN: wlan_control: 9235, 16
KERN: wlan_control: 9235, 17
KERN: wlan_control: 9235, 26
KERN: wlan_control: 9235, 98
KERN: wlan_close(0xe27dc400)
KERN: wlan_control: 9234, 20
KERN: Last message repeated 3 times.
KERN: wlan_control: 9234, 25
KERN: wlan_control: 9235, 76
KERN: ifmedia_ioctl: switching wlan to   Type: IEEE 802.11 Wireless Ethernet
KERN:   Mode: autoselect
KERN:   SubType: autoselect
KERN: wlan_control: 9234, 16
KERN: wlan_control: 9234, 17
KERN: wlan_control: 9234, 26
KERN: wlan_open(0xe27dc400)
KERN: [net/iprowifi2200/0] ieee80211_init
KERN: [net/iprowifi2200/0] start running, 0 vaps running
KERN: [net/iprowifi2200/0] ieee80211_start_locked: up parent 
KERN: [net/iprowifi2200/0] start running, 1 vaps running
KERN: wlan_control: 9234, 103
KERN: [net/iprowifi2200/0] ieee80211_new_state_locked: INIT -> SCAN (nrunning 0 nscanning 0)
KERN: [net/iprowifi2200/0] start running, 1 vaps running
KERN: [net/iprowifi2200/0] ieee80211_newstate_cb: INIT -> SCAN arg 0
KERN: [net/iprowifi2200/0] sta_newstate: INIT -> SCAN (0)
KERN: [iprowifi2200] (iwi) radio turned off
KERN: ieee80211_notify_radio not implemented, yet.
KERN: wlan_control: 9234, 25
KERN: wlan_control: 9234, 95
KERN: wlan_control: 9234, 17
KERN: wlan_control: 9234, 26
KERN: wlan_close(0xe27dc400)
KERN: [net/iprowifi2200/0] stop running, 1 vaps running
KERN: [net/iprowifi2200/0] ieee80211_new_state_locked: SCAN -> INIT (nrunning 0 nscanning 0)
KERN: [net/iprowifi2200/0] down parent 
KERN: ieee80211_notify_scan_done
KERN: [net/iprowifi2200/0] ieee80211_newstate_cb: SCAN -> INIT arg -1
KERN: [net/iprowifi2200/0] sta_newstate: SCAN -> INIT (-1)
KERN: [net/iprowifi2200/0] node_reclaim: remove 0xf0c24000<00:16:6f:a4:bc:d2> from station table, refcnt 1
KERN: [net/iprowifi2200/0] ieee80211_alloc_node 0xf0c26000<00:16:6f:a4:bc:d2> in station table
KERN: [net/iprowifi2200/0] [00:16:6f:a4:bc:d2] ieee80211_alloc_node: inact_reload 2
KERN: wlan_control: 9234, 95
KERN: wlan_control: 9234, 17
KERN: wlan_control: 9234, 26
KERN: wlan_control: 9234, 16

Attachments (2)

syslog (201.5 KB ) - added by Paradoxon 5 years ago.
Syslog iprowifi 4965/0
Network-541-debug-14-04-2020-05-01-01.report (12.7 KB ) - added by nephele 5 years ago.
preferences/Network crash report

Download all attachments as: .zip

Change History (14)

comment:1 by tojoko, 5 years ago

Same here with an iprowifi 4600 - iirc, it used to work until shortly.

by Paradoxon, 5 years ago

Attachment: syslog added

Syslog iprowifi 4965/0

comment:2 by Paradoxon, 5 years ago

Same Here with iprowifi 4965/0 listdev device Network controller [2|80|0]

vendor 8086: Intel Corporation device 422c: Centrino Advanced-N 6200

comment:3 by waddlesplash, 5 years ago

Nothing has recently changed, and there are no errors in these logs; likely the problem is with wpa_supplicant. I see this rather intermittently on my idualwifi7265 card.

The newer wpa_supplicant will hopefully resolve most of these problems.

comment:4 by miqlas, 5 years ago

Dialog to enter password pops up here too even if i save the credentials. Sometimes the dialog pops up with the field prefilled, sometimes it is empty. After saving the password i can just press cancel in the popup, and after some time it connects automatically. Ofc it is an annoying bug as we are mentally trained to fill all the fileds, while it is sometimes unnecessary. Just try cancel and be patient, hopefully it will work for you too.

Last edited 5 years ago by miqlas (previous) (diff)

comment:5 by nephele, 5 years ago

waddlesplash: neither did i claim that this has recently broken; I am fairly certain the wifi worked on windows XP on this laptop, but i didn't really manage to get it to connect on FreeBSD either (but i always assumed that was because of the wierd need to set some env vars in rc.conf to get it to know i accept the license or something) (I only recently fixed this laptop (new batttery and display) to run haiku on it)

If a newer wpa_supplicant might fix this, is there a review i can test?

comment:6 by waddlesplash, 5 years ago

Please retest under the new wpa_supplicant under the nightlies (version 2.9).

comment:7 by nephele, 5 years ago

I tested the newer wpa_supplicant, I can't see my network (It's technically 4 BSSID's, but 2 are 5GhZ, so i expect to see at least 2 entries for the 2.4GhZ range (one each))

On linux laptop right next to this one i can see both 2.4GhZ BSSID's for my SSID, i do see other networks though, so i am a bit baffled whats wrong (laptop is like 1.5M from the AP, so that shouldnt matter, no walls either)

edit: also the preferences/network applet crashed while trying this, attaching a debug report.

Last edited 5 years ago by nephele (previous) (diff)

by nephele, 5 years ago

preferences/Network crash report

comment:8 by nephele, 5 years ago

In a subsequent test i did get my SSID to show up, the behaviour trying to connect there is the same as with the inital report in this ticket

comment:9 by X512, 5 years ago

Network-541-debug-14-04-2020-05-01-01.report

This is #15752.

comment:10 by tojoko, 5 years ago

Works for me now.

comment:11 by tojoko, 5 years ago

However, it is unstable and pkgman update doesn't work for me anymore.

comment:12 by nephele, 4 years ago

Still the same, here is the output of wpa_supplicant after trying to connect to my home wifi (with the correct password i think)

Initializing interface '/dev/net/iprowifi2200/0' conf 'N/A' driver 'default' ctrl_interface 'N/A' bridge 'N/A'
wpa_driver_bsd_capa: drivercaps=0x05818301,cryptocaps=0x00000000
Add interface /dev/net/iprowifi2200/0 to a new radio N/A
/dev/net/iprowifi2200/0: Failed to attach pkt_type filter
/dev/net/iprowifi2200/0: Own MAC address: 00:16:6f:a4:bc:d2
bsd_set_key: alg=0 addr=(nil) key_idx=0 set_tx=0 seq_len=0 key_len=0
bsd_del_key: key_idx=0
bsd_set_key: alg=0 addr=(nil) key_idx=1 set_tx=0 seq_len=0 key_len=0
bsd_del_key: key_idx=1
bsd_set_key: alg=0 addr=(nil) key_idx=2 set_tx=0 seq_len=0 key_len=0
bsd_del_key: key_idx=2
bsd_set_key: alg=0 addr=(nil) key_idx=3 set_tx=0 seq_len=0 key_len=0
bsd_del_key: key_idx=3
wpa_driver_bsd_set_countermeasures: enabled=0
/dev/net/iprowifi2200/0: RSN: flushing PMKID list in the driver
/dev/net/iprowifi2200/0: State: DISCONNECTED -> INACTIVE
/dev/net/iprowifi2200/0: Added interface /dev/net/iprowifi2200/0
/dev/net/iprowifi2200/0: State: INACTIVE -> DISCONNECTED
ssid - hexdump_ascii(len=11):
     6e 61 63 68 74 6d 65 72 72 69 65                  nachtmerrie     
scan_ssid=1 (0x1)
PSK (ASCII passphrase) - hexdump_ascii(len=12): [REMOVED]
PSK (from passphrase) - hexdump(len=32): [REMOVED]
/dev/net/iprowifi2200/0: Setting scan request: 0.000000 sec
/dev/net/iprowifi2200/0: State: DISCONNECTED -> SCANNING
Scan SSID - hexdump_ascii(len=11):
     6e 61 63 68 74 6d 65 72 72 69 65                  nachtmerrie     
/dev/net/iprowifi2200/0: Starting AP scan for wildcard SSID
/dev/net/iprowifi2200/0: Add radio work 'scan'@0x186b6aa8
/dev/net/iprowifi2200/0: First radio work item in the queue - schedule start immediately
/dev/net/iprowifi2200/0: Event SCAN_RESULTS (3) received
Received 0 bytes of scan results (0 BSSes)
/dev/net/iprowifi2200/0: BSS: Start scan result update 1
BSS: last_scan_res_used=0/0
/dev/net/iprowifi2200/0: New scan results available (own=0 ext=0)
/dev/net/iprowifi2200/0: No suitable network found
/dev/net/iprowifi2200/0: Setting scan request: 5.000000 sec
/dev/net/iprowifi2200/0: Starting radio work 'scan'@0x186b6aa8 after 0.001460 second wait
wpa_driver_bsd_set_wpa: enabled=1
wpa_driver_bsd_set_wpa_internal: wpa=3 privacy=1
/dev/net/iprowifi2200/0: Already scanning - Reschedule the incoming scan req
/dev/net/iprowifi2200/0: Setting scan request: 1.000000 sec
/dev/net/iprowifi2200/0: Already scanning - Reschedule the incoming scan req
/dev/net/iprowifi2200/0: Setting scan request: 1.000000 sec
/dev/net/iprowifi2200/0: Already scanning - Reschedule the incoming scan req
/dev/net/iprowifi2200/0: Setting scan request: 1.000000 sec
/dev/net/iprowifi2200/0: Already scanning - Reschedule the incoming scan req
/dev/net/iprowifi2200/0: Setting scan request: 1.000000 sec
/dev/net/iprowifi2200/0: Already scanning - Reschedule the incoming scan req
/dev/net/iprowifi2200/0: Setting scan request: 1.000000 sec
/dev/net/iprowifi2200/0: Already scanning - Reschedule the incoming scan req
/dev/net/iprowifi2200/0: Setting scan request: 1.000000 sec
/dev/net/iprowifi2200/0: Already scanning - Reschedule the incoming scan req
/dev/net/iprowifi2200/0: Setting scan request: 1.000000 sec
/dev/net/iprowifi2200/0: Already scanning - Reschedule the incoming scan req
/dev/net/iprowifi2200/0: Setting scan request: 1.000000 sec
/dev/net/iprowifi2200/0: Already scanning - Reschedule the incoming scan req
/dev/net/iprowifi2200/0: Setting scan request: 1.000000 sec
/dev/net/iprowifi2200/0: Already scanning - Reschedule the incoming scan req
/dev/net/iprowifi2200/0: Setting scan request: 1.000000 sec
/dev/net/iprowifi2200/0: Removing interface /dev/net/iprowifi2200/0
/dev/net/iprowifi2200/0: Request to deauthenticate - bssid=00:00:00:00:00:00 pending_bssid=00:00:00:00:00:00 reason=3 (DEAUTH_LEAVING) state=SCANNING
/dev/net/iprowifi2200/0: State: SCANNING -> DISCONNECTED
wpa_driver_bsd_set_countermeasures: enabled=0
/dev/net/iprowifi2200/0: WPA: Clear old PMK and PTK
/dev/net/iprowifi2200/0: Cancelling scan request
/dev/net/iprowifi2200/0: Cancelling authentication timeout
Remove interface /dev/net/iprowifi2200/0 from radio 
/dev/net/iprowifi2200/0: Remove radio work 'scan'@0x186b6aa8 (started)
/dev/net/iprowifi2200/0: radio_work_free('scan'@0x186b6aa8): num_active_works --> 0
Remove radio 
wpa_driver_bsd_set_wpa: enabled=0
wpa_driver_bsd_set_wpa_internal: wpa=0 privacy=0
bsd_set_opt_ie: set WPA+RSN ie (len 0)
wpa_driver_bsd_set_wpa_internal: wpa=0 privacy=0
bsd_set_opt_ie: set WPA+RSN ie (len 0)

Note: See TracTickets for help on using tickets.