ath9k on pogoplug v3

[sean77]

Hi all,
I succesfully recompiled compat-wireless on a updated pogoplug v3 and I have ath9k_htc modules working and loadable correctly for the current 2.6.31-6 kernel.

The modules are all correctly loaded, the problem is the firmware.
I placed it in /lib/firmware but still get this error.

[ 72.010000] usb 1-1.3: ath9k_htc: Firmware - htc_9271.fw not found
[ 72.010000] ath9k_htc: probe of 1-1.3:1.0 failed with error -22
[ 72.020000] usbcore: registered new interface driver ath9k_htc


Any idea?

Thanks

PS: If needed I can give the modules packaged so can be usable for everyone.

[pepedog]

/usr/lib/firmware is where it goes nowdays, at least for arm and armv7

[WarheadsSE]

I'll have to check on the 2.6.31.6.. but yes, try putting it in that folder as well.

[sean77]

Already put also there, and also in /usr/local/lib/firmware.
Didn't resolve.

The folder /usr/lib/firmware is full of files .fw (also with htc_9271.fw of course) and I think is part of linux-firmware package.

I found some info about a problem with udev that has some bugs but for an old release of january.
Don't know if udev was fixed.
But I don't have the older package anyway, so I don't know how to downgrade it (I think is systemd package).

The strange think is that there is another firmware for gmac in the same place that is loaded without problem...

Sean

[WarheadsSE]

Time for an strace/gdb session to see whats happening, I think. Any messages in logs other than that? Maybe crank the kernel log levels..

[sean77]

I tried also a newer compat-wireless version (2012-06-29) and the message are still the same.
These are the new dmesg log (same thing written in /var/log/messages):

[ 602.900000] drivers/usb/core/inode.c: creating file '005'
[ 602.910000] usb 1-1.3: uevent
[ 602.910000] Compat-wireless backport release: compat-wireless-2012-06-28-2-4-ga04a56d
[ 602.920000] Backport based on linux-next.git next-20120629
[ 602.920000] compat.git: linux-next.git
[ 603.190000] cfg80211: Calling CRDA to update world regulatory domain
[ 603.500000] ath9k_htc 1-1.3:1.0: usb_probe_interface
[ 603.500000] ath9k_htc 1-1.3:1.0: usb_probe_interface - got id
[ 603.500000] usb 1-1.3: ath9k_htc: Firmware htc_9271.fw requested
[ 603.500000] usb 1-1.3: uevent
[ 603.510000] usbcore: registered new interface driver ath9k_htc
[ 664.000000] usb 1-1.3: ath9k_htc: Failed to get firmware htc_9271.fw
[ 664.000000] usb 1-1.3: usb_disable_device nuking non-ep0 URBs
[ 664.000000] usb 1-1.3: unregistering interface 1-1.3:1.0
[ 664.000000] usb 1-1.3: ath9k_htc: USB layer deinitialized
[ 664.010000] usb 1-1.3:1.0: uevent

lsmod:

Module Size Used by
ath9k_htc 59608 0
compat_firmware_class 8476 1 ath9k_htc
ath9k_common 3476 1 ath9k_htc
ath9k_hw 334224 2 ath9k_common,ath9k_htc
ath 17212 3 ath9k_hw,ath9k_common,ath9k_htc
mac80211 257344 1 ath9k_htc
cfg80211 176520 3 mac80211,ath,ath9k_htc
compat 33564 6 cfg80211,mac80211,ath9k_hw,ath9k_common,compat_firmware_class,ath9k_htc
bootled_module 1472 0
gmac 40012 0
mii 6200 1 gmac

I turn on the udev logs but don't know about making strace/gdb.
Can you tell me how I can do that?

Thanks

Sean

$this->bbcode_second_pass_quote('WarheadsSE', 'T')ime for an strace/gdb session to see whats happening, I think. Any messages in logs other than that? Maybe crank the kernel log levels..

[tofurky]

hi,
make sure you have the following installed:
$this->bbcode_second_pass_code('', '
/lib/udev/rules.d/50-compat_firmware.rules
/lib/udev/compat_firmware.sh
')
they can be found in the udev/ directory in the compat-wireless tarballs.
-matt

[sean77]

$this->bbcode_second_pass_quote('tofurky', 'h')i,
make sure you have the following installed:
$this->bbcode_second_pass_code('', '
/lib/udev/rules.d/50-compat_firmware.rules
/lib/udev/compat_firmware.sh
')
they can be found in the udev/ directory in the compat-wireless tarballs.
-matt

[tofurky]

'udevadm monitor --env' might give you some hints.
i would suggest starting the system w/the dongle unplugged, starting the udevadm monitor, then plugging it in and checking what is output.
-matt

[WarheadsSE]

Here is the output after plugging the dongle.
I don't understand where udev is searching for firmware...


$this->bbcode_second_pass_code('', '
[root@alarm ~]# udevadm monitor --env
monitor will print the received events for:
UDEV - the event which udev sends out after rule processing
KERNEL - the kernel uevent

KERNEL[95.949297] add /devices/platform/oxnas-ehci.0/usb1/1-1/1-1.3 (usb)
ACTION=add
BUSNUM=001
DEVICE=/proc/bus/usb/001/004
DEVNAME=/dev/bus/usb/001/004
DEVNUM=004
DEVPATH=/devices/platform/oxnas-ehci.0/usb1/1-1/1-1.3
DEVTYPE=usb_device
MAJOR=189
MINOR=3
PRODUCT=cf3/9271/108
SEQNUM=516
SUBSYSTEM=usb
TYPE=255/255/255
UDEV_LOG=6

KERNEL[95.958336] add /devices/platform/oxnas-ehci.0/usb1/1-1/1-1.3/1-1.3:1.0 (usb)
ACTION=add
DEVICE=/proc/bus/usb/001/004
DEVPATH=/devices/platform/oxnas-ehci.0/usb1/1-1/1-1.3/1-1.3:1.0
DEVTYPE=usb_interface
INTERFACE=255/0/0
MODALIAS=usb:v0CF3p9271d0108dcFFdscFFdpFFicFFisc00ip00
PRODUCT=cf3/9271/108
SEQNUM=517
SUBSYSTEM=usb
TYPE=255/255/255
UDEV_LOG=6

KERNEL[95.963502] add /devices/platform/oxnas-ehci.0/usb1/1-1/1-1.3/usb_device/usbdev1.4 (usb_device)
ACTION=add
DEVNAME=/dev/usbdev1.4
DEVPATH=/devices/platform/oxnas-ehci.0/usb1/1-1/1-1.3/usb_device/usbdev1.4
MAJOR=189
MINOR=3
SEQNUM=518
SUBSYSTEM=usb_device
UDEV_LOG=6

UDEV [95.973683] add /devices/platform/oxnas-ehci.0/usb1/1-1/1-1.3 (usb)
ACTION=add
BUSNUM=001
DEVICE=/proc/bus/usb/001/004
DEVNAME=/dev/bus/usb/001/004
DEVNUM=004
DEVPATH=/devices/platform/oxnas-ehci.0/usb1/1-1/1-1.3
DEVTYPE=usb_device
ID_BUS=usb
ID_MODEL=USB2.0_WLAN
ID_MODEL_ENC=USB2.0\x20WLAN
ID_MODEL_ID=9271
ID_REVISION=0108
ID_SERIAL=ATHEROS_USB2.0_WLAN_12345
ID_SERIAL_SHORT=12345
ID_USB_INTERFACES=:ff0000:
ID_VENDOR=ATHEROS
ID_VENDOR_ENC=ATHEROS
ID_VENDOR_ID=0cf3
MAJOR=189
MINOR=3
PRODUCT=cf3/9271/108
SEQNUM=516
SUBSYSTEM=usb
TYPE=255/255/255
UDEV_LOG=6
USEC_INITIALIZED=950311

UDEV [95.984847] add /devices/platform/oxnas-ehci.0/usb1/1-1/1-1.3/usb_device/usbdev1.4 (usb_device)
ACTION=add
DEVNAME=/dev/usbdev1.4
DEVPATH=/devices/platform/oxnas-ehci.0/usb1/1-1/1-1.3/usb_device/usbdev1.4
MAJOR=189
MINOR=3
SEQNUM=518
SUBSYSTEM=usb_device
UDEV_LOG=6
USEC_INITIALIZED=950311

KERNEL[96.065904] add /module/compat (module)
ACTION=add
DEVPATH=/module/compat
SEQNUM=519
SUBSYSTEM=module
UDEV_LOG=6

UDEV [96.067805] add /module/compat (module)
ACTION=add
DEVPATH=/module/compat
SEQNUM=519
SUBSYSTEM=module
UDEV_LOG=6
USEC_INITIALIZED=478

KERNEL[96.181241] add /module/cfg80211 (module)
ACTION=add
DEVPATH=/module/cfg80211
SEQNUM=520
SUBSYSTEM=module
UDEV_LOG=6

KERNEL[96.181918] add /class/ieee80211 (class)
ACTION=add
DEVPATH=/class/ieee80211
SEQNUM=521
SUBSYSTEM=class
UDEV_LOG=6

UDEV [96.183237] add /module/cfg80211 (module)
ACTION=add
DEVPATH=/module/cfg80211
SEQNUM=520
SUBSYSTEM=module
UDEV_LOG=6
USEC_INITIALIZED=1628

UDEV [96.184840] add /class/ieee80211 (class)
ACTION=add
DEVPATH=/class/ieee80211
SEQNUM=521
SUBSYSTEM=class
UDEV_LOG=6
USEC_INITIALIZED=3693

KERNEL[96.280494] add /devices/platform/regulatory.0 (platform)
ACTION=add
DEVPATH=/devices/platform/regulatory.0
MODALIAS=platform:regulatory
SEQNUM=522
SUBSYSTEM=platform
UDEV_LOG=6

UDEV [96.284443] add /devices/platform/regulatory.0 (platform)
ACTION=add
DEVPATH=/devices/platform/regulatory.0
MODALIAS=platform:regulatory
SEQNUM=522
SUBSYSTEM=platform
UDEV_LOG=6
USEC_INITIALIZED=937

KERNEL[96.287260] change /devices/platform/regulatory.0 (platform)
ACTION=change
COUNTRY=00
DEVPATH=/devices/platform/regulatory.0
MODALIAS=platform:regulatory
SEQNUM=523
SUBSYSTEM=platform
UDEV_LOG=6

UDEV [96.292109] change /devices/platform/regulatory.0 (platform)
ACTION=change
COUNTRY=00
DEVPATH=/devices/platform/regulatory.0
MODALIAS=platform:regulatory
SEQNUM=523
SUBSYSTEM=platform
UDEV_LOG=6
USEC_INITIALIZED=7778

KERNEL[96.423085] add /module/mac80211 (module)
ACTION=add
DEVPATH=/module/mac80211
SEQNUM=524
SUBSYSTEM=module
UDEV_LOG=6

UDEV [96.424788] add /module/mac80211 (module)
ACTION=add
DEVPATH=/module/mac80211
SEQNUM=524
SUBSYSTEM=module
UDEV_LOG=6
USEC_INITIALIZED=3490

KERNEL[96.438947] add /module/ath (module)
ACTION=add
DEVPATH=/module/ath
SEQNUM=525
SUBSYSTEM=module
UDEV_LOG=6

UDEV [96.441334] add /module/ath (module)
ACTION=add
DEVPATH=/module/ath
SEQNUM=525
SUBSYSTEM=module
UDEV_LOG=6
USEC_INITIALIZED=9380

KERNEL[96.529926] add /module/ath9k_hw (module)
ACTION=add
DEVPATH=/module/ath9k_hw
SEQNUM=526
SUBSYSTEM=module
UDEV_LOG=6

UDEV [96.532196] add /module/ath9k_hw (module)
ACTION=add
DEVPATH=/module/ath9k_hw
SEQNUM=526
SUBSYSTEM=module
UDEV_LOG=6
USEC_INITIALIZED=877

KERNEL[96.540745] add /module/ath9k_common (module)
ACTION=add
DEVPATH=/module/ath9k_common
SEQNUM=527
SUBSYSTEM=module
UDEV_LOG=6

UDEV [96.542592] add /module/ath9k_common (module)
ACTION=add
DEVPATH=/module/ath9k_common
SEQNUM=527
SUBSYSTEM=module
UDEV_LOG=6
USEC_INITIALIZED=1201

KERNEL[96.556195] add /module/compat_firmware_class (module)
ACTION=add
DEVPATH=/module/compat_firmware_class
SEQNUM=528
SUBSYSTEM=module
UDEV_LOG=6

KERNEL[96.556712] add /class/compat_firmware (class)
ACTION=add
DEVPATH=/class/compat_firmware
SEQNUM=529
SUBSYSTEM=class
UDEV_LOG=6

UDEV [96.558428] add /module/compat_firmware_class (module)
ACTION=add
DEVPATH=/module/compat_firmware_class
SEQNUM=528
SUBSYSTEM=module
UDEV_LOG=6
USEC_INITIALIZED=7040

UDEV [96.561077] add /class/compat_firmware (class)
ACTION=add
DEVPATH=/class/compat_firmware
SEQNUM=529
SUBSYSTEM=class
UDEV_LOG=6
USEC_INITIALIZED=8974

KERNEL[96.629511] add /module/ath9k_htc (module)
ACTION=add
DEVPATH=/module/ath9k_htc
SEQNUM=530
SUBSYSTEM=module
UDEV_LOG=6

UDEV [96.632042] add /module/ath9k_htc (module)
ACTION=add
DEVPATH=/module/ath9k_htc
SEQNUM=530
SUBSYSTEM=module
UDEV_LOG=6
USEC_INITIALIZED=9947

KERNEL[96.638189] add /bus/usb/drivers/ath9k_htc (drivers)
ACTION=add
DEVPATH=/bus/usb/drivers/ath9k_htc
SEQNUM=531
SUBSYSTEM=drivers
UDEV_LOG=6

KERNEL[96.638540] add /devices/platform/oxnas-ehci.0/usb1/1-1/1-1.3/compat_firmware/1-1.3 (compat_firmware)
ACTION=add
ASYNC=1
DEVPATH=/devices/platform/oxnas-ehci.0/usb1/1-1/1-1.3/compat_firmware/1-1.3
FIRMWARE=htc_9271.fw
SEQNUM=532
SUBSYSTEM=compat_firmware
TIMEOUT=60
UDEV_LOG=6

UDEV [96.640569] add /bus/usb/drivers/ath9k_htc (drivers)
ACTION=add
DEVPATH=/bus/usb/drivers/ath9k_htc
SEQNUM=531
SUBSYSTEM=drivers
UDEV_LOG=6
USEC_INITIALIZED=9055

UDEV [96.643798] add /devices/platform/oxnas-ehci.0/usb1/1-1/1-1.3/compat_firmware/1-1.3 (compat_firmware)
ACTION=add
ASYNC=1
DEVPATH=/devices/platform/oxnas-ehci.0/usb1/1-1/1-1.3/compat_firmware/1-1.3
FIRMWARE=htc_9271.fw
SEQNUM=532
SUBSYSTEM=compat_firmware
TIMEOUT=60
UDEV_LOG=6
USEC_INITIALIZED=1124

UDEV [96.647271] add /devices/platform/oxnas-ehci.0/usb1/1-1/1-1.3/1-1.3:1.0 (usb)
ACTION=add
DEVICE=/proc/bus/usb/001/004
DEVPATH=/devices/platform/oxnas-ehci.0/usb1/1-1/1-1.3/1-1.3:1.0
DEVTYPE=usb_interface
INTERFACE=255/0/0
MODALIAS=usb:v0CF3p9271d0108dcFFdscFFdpFFicFFisc00ip00
PRODUCT=cf3/9271/108
SEQNUM=517
SUBSYSTEM=usb
TYPE=255/255/255
UDEV_LOG=6
USEC_INITIALIZED=9235

KERNEL[157.040551] remove /devices/platform/oxnas-ehci.0/usb1/1-1/1-1.3/compat_firmware/1-1.3 (compat_firmware)
ACTION=remove
ASYNC=1
DEVPATH=/devices/platform/oxnas-ehci.0/usb1/1-1/1-1.3/compat_firmware/1-1.3
FIRMWARE=htc_9271.fw
SEQNUM=533
SUBSYSTEM=compat_firmware
TIMEOUT=60
UDEV_LOG=6

UDEV [157.044800] remove /devices/platform/oxnas-ehci.0/usb1/1-1/1-1.3/compat_firmware/1-1.3 (compat_firmware)
ACTION=remove
ASYNC=1
DEVPATH=/devices/platform/oxnas-ehci.0/usb1/1-1/1-1.3/compat_firmware/1-1.3
FIRMWARE=htc_9271.fw
SEQNUM=533
SUBSYSTEM=compat_firmware
TIMEOUT=60
UDEV_LOG=6
USEC_INITIALIZED=7041124

KERNEL[157.047450] remove /devices/platform/oxnas-ehci.0/usb1/1-1/1-1.3/usb_device/usbdev1.4 (usb_device)
ACTION=remove
DEVNAME=/dev/usbdev1.4
DEVPATH=/devices/platform/oxnas-ehci.0/usb1/1-1/1-1.3/usb_device/usbdev1.4
MAJOR=189
MINOR=3
SEQNUM=534
SUBSYSTEM=usb_device
UDEV_LOG=6

UDEV [157.049639] remove /devices/platform/oxnas-ehci.0/usb1/1-1/1-1.3/usb_device/usbdev1.4 (usb_device)
ACTION=remove
DEVNAME=/dev/usbdev1.4
DEVPATH=/devices/platform/oxnas-ehci.0/usb1/1-1/1-1.3/usb_device/usbdev1.4
MAJOR=189
MINOR=3
SEQNUM=534
SUBSYSTEM=usb_device
UDEV_LOG=6
USEC_INITIALIZED=48082

KERNEL[157.053881] remove /devices/platform/oxnas-ehci.0/usb1/1-1/1-1.3/1-1.3:1.0 (usb)
ACTION=remove
DEVICE=/proc/bus/usb/001/004
DEVPATH=/devices/platform/oxnas-ehci.0/usb1/1-1/1-1.3/1-1.3:1.0
DEVTYPE=usb_interface
INTERFACE=255/0/0
MODALIAS=usb:v0CF3p9271d0108dcFFdscFFdpFFicFFisc00ip00
PRODUCT=cf3/9271/108
SEQNUM=535
SUBSYSTEM=usb
TYPE=255/255/255
UDEV_LOG=6

UDEV [157.055757] remove /devices/platform/oxnas-ehci.0/usb1/1-1/1-1.3/1-1.3:1.0 (usb)
ACTION=remove
DEVICE=/proc/bus/usb/001/004
DEVPATH=/devices/platform/oxnas-ehci.0/usb1/1-1/1-1.3/1-1.3:1.0
DEVTYPE=usb_interface
INTERFACE=255/0/0
MODALIAS=usb:v0CF3p9271d0108dcFFdscFFdpFFicFFisc00ip00
PRODUCT=cf3/9271/108
SEQNUM=535
SUBSYSTEM=usb
TYPE=255/255/255
UDEV_LOG=6
USEC_INITIALIZED=54608
')