SDIO and DHCP

Simon Twogood stwogood at siconix.com
Tue Dec 16 14:55:15 EST 2008


On 16-Dec-08, at 12:25 PM, Simon Twogood wrote:

> On 16-Dec-08, at 12:02 PM, Dan Williams wrote:
>
>> On Tue, 2008-12-16 at 13:27 -0500, Jeff Sutherland wrote:
>>> -----BEGIN PGP SIGNED MESSAGE-----
>>> Hash: SHA1
>>>
>>> On Tuesday 16 December 2008, Simon Twogood wrote:
>>>> Hell everyone,
>>>>
>>>> I've been having success using the a Libertas SDIO module with  
>>>> Linux
>>>> 2.6.27, but only with static IP addresses. My board is an Atmel
>>>> AT91SAM9263. Has anyone had success obtaining an IP address through
>>>> DHCP on a similar board, or any other board for that matter?
>>>>
>>>> I found a old thread that mentions a similar problem here
>>>> http://lists.infradead.org/pipermail/libertas-dev/2008-May/001526.html
>>>> ,
>>>> but it seems it was unresolved.
>>>>
>>>> My firmware version is 9.70.3p24. Here's a sample of the the
>>>> procedure
>>>> I use to reproduce my error:
>>>>
>>>>
>>>> root at mydevice:~$ modprobe libertas_sdio
>>>> libertas_sdio: Libertas SDIO driver
>>>> libertas_sdio: Copyright Pierre Ossman
>>>> firmware: requesting sd8686_helper.bin
>>>> firmware: requesting sd8686.bin
>>>> libertas: 00:19:88:05:11:56, fw 9.70.3p24, cap 0x00000303
>>>> libertas: PREP_CMD: command 0x00a3 failed: 2
>>>> libertas: PREP_CMD: command 0x00a3 failed: 2
>>>> libertas: eth1: Marvell WLAN 802.11 adapter
>>>>
>>>> root at mydevice:~$ ifconfig eth1 up
>>>>
>>>> root at mydevice:~$ iwlist eth1 scan
>>>> eth1      Scan completed :
>>>>          Cell 01 - Address: 00:21:29:71:19:E8
>>>>                    ESSID:"emptynetwork"
>>>>                    Mode:Managed
>>>>                    Frequency:2.437 GHz (Channel 6)
>>>>                    Quality=92/100  Signal level=-58 dBm  Noise
>>>> level=-96 dBm
>>>>                    Encryption key:off
>>>>                    Bit Rates:1 Mb/s; 2 Mb/s; 5.5 Mb/s; 11 Mb/s; 18
>>>> Mb/s
>>>>                              24 Mb/s; 36 Mb/s; 54 Mb/s; 6 Mb/s; 9
>>>> Mb/s
>>>>                              12 Mb/s; 48 Mb/s
>>>>
>>>> root at mydevice:~$ iwconfig eth1 essid emptynetwork
>>>>
>>>> root at mydevice:~$ udhcpc -H mydevice -i eth1
>>>> udhcpc (v1.2.1) started
>>>> Sending discover...
>>>> Sending discover...
>>>> Sending discover...
>>>> ------------[ cut here ]------------
>>>> WARNING: at net/sched/sch_generic.c:219 dev_watchdog+0x154/0x240()
>>>> NETDEV WATCHDOG: eth1 (libertas_sdio): transmit timed out
>>>> Modules linked in: libertas_sdio libertas at91_mci
>>>> [<c0025fa0>] (dump_stack+0x0/0x14) from [<c003822c>] (warn_slowpath
>>>> +0x64/0x80)
>>>> [<c00381c8>] (warn_slowpath+0x0/0x80) from [<c0180b0c>]
>>>> (dev_watchdog
>>>> +0x154/0x240)
>>>> r3:c394c000 r2:c0238d80
>>>> r6:c01809b8 r5:c394c000 r4:c027ef34
>>>> [<c01809b8>] (dev_watchdog+0x0/0x240) from [<c0041440>]
>>>> (run_timer_softirq+0x150/0x1e8)
>>>> r6:c01809b8 r5:00000100 r4:c026f740
>>>> [<c00412f0>] (run_timer_softirq+0x0/0x1e8) from [<c003cf08>]
>>>> (__do_softirq+0x60/0xc8)
>>>> r7:c0255c80 r6:0000000a r5:c026f544 r4:00000001
>>>> [<c003cea8>] (__do_softirq+0x0/0xc8) from [<c003d258>] (irq_exit
>>>> +0x48/0x50)
>>>> r7:c0255c80 r6:00000000 r5:c0259ea4 r4:00000001
>>>> [<c003d210>] (irq_exit+0x0/0x50) from [<c0021054>]
>>>> (__exception_text_start+0x54/0x6c)
>>>> [<c0021000>] (__exception_text_start+0x0/0x6c) from [<c0021834>]
>>>> (__irq_svc+0x34/0x60)
>>>> Exception stack(0xc0253f50 to 0xc0253f98)
>>>> 3f40:                                     00000000 0005317f  
>>>> 0005217f
>>>> 60000013
>>>> 3f60: c0023270 c0252000 c001eee8 c0255c80 2001d32c 41069265  
>>>> 2001d2f8
>>>> c0253fa4
>>>> 3f80: 600000d3 c0253f98 c00232b8 c00232c4 60000013 ffffffff
>>>> r7:c0255c80 r6:00000001 r5:fefff000 r4:ffffffff
>>>> [<c0023270>] (default_idle+0x0/0x5c) from [<c002314c>] (cpu_idle
>>>> +0x3c/
>>>> 0x58)
>>>> [<c0023110>] (cpu_idle+0x0/0x58) from [<c01d6610>] (rest_init
>>>> +0x58/0x6c)
>>>> r5:c0269e10 r4:c0271f88
>>>> [<c01d65b8>] (rest_init+0x0/0x6c) from [<c0008bc8>] (start_kernel
>>>> +0x284/0x2dc)
>>>> [<c0008944>] (start_kernel+0x0/0x2dc) from [<20008034>]  
>>>> (0x20008034)
>>>> r5:c026a320 r4:00053175
>>>> ---[ end trace 4093a6ca7302871d ]---
>>>> libertas: tx watch dog timeout
>>>> Sending select for 192.168.5.128...
>>>> Sending select for 192.168.5.128...
>>>> Sending select for 192.168.5.128...
>>>> libertas: tx watch dog timeout
>>>> Sending discover...
>>>> Sending discover...
>>>> libertas: tx watch dog timeout
>>>> Sending discover...
>>>> libertas: tx watch dog timeout
>>>> libertas: tx watch dog timeout
>>>> libertas: tx watch dog timeout
>>>> libertas: tx watch dog timeout
>>>> Sending select for 192.168.5.128...
>>>> Sending select for 192.168.5.128...
>>>> Sending select for 192.168.5.128...
>>>> libertas: tx watch dog timeout
>>>> Sending discover...
>>>> Sending discover...
>>>
>>> Please try one thing and let us know how you get on: turn on all
>>> the debugging
>>> for the wifi subsystem and libertas module and try again.  This  
>>> looks
>>> suspiciously like a problem I encountered with this chipset on
>>> another
>>> platform.
>>
>> Yeah, in general this stuff is highly controller-dependent; there are
>> some comments in if_sdio about that sort of thing.  Some controllers
>> don't really support reads of certain byte widths, others require
>> random
>> alignments, etc.  I've usually tested libertas_sdio on an generic
>> laptop
>> Ricoh SD/MMC controller and that normally works well, but we  
>> certainly
>> had to quirk a few things for the OLPC "Cafe" SD controller.  Also
>> make
>> sure you're using a fairly recent kernel (2.6.26 if possible) since
>> there have been fixes to the SDIO stack itself in the kernel since it
>> got merged in 2.6.24.
>>
>> Dan
>>
>>
>>
>> _______________________________________________
>> libertas-dev mailing list
>> libertas-dev at lists.infradead.org
>> http://lists.infradead.org/mailman/listinfo/libertas-dev
>
>
> Perhaps I'm not enabling the wireless subsystem debugging properly as
> nothing from it shows up in my syslog, I've said 'y' to
> CONFIG_MAC80211_VERBOSE_DEBUG, is that correct? Anyway, the libertas
> debugging is enabled and here is the output from it. Interesting to me
> is that after issuing 'ifconfig eth1 up' it enters
> lbs_submit_command(), but I don't see any exit, and there is no more
> log output after running udhcp.
>
> I'm using 2.6.27 which has changes to the AT91 MMC driver. With
> earlier kernels I had no success at all. Could still be a problem with
> the controller. Adding MMC debugging seems to mess up timings, causing
> the libertas driver to fail.
>
>
> ### modprobe libertas libertas_debug=0x4863a7
> Jan  1 00:03:04 mydevice user.debug kernel: libertas enter:
> lbs_init_module()
> Jan  1 00:03:04 mydevice user.debug kernel: libertas leave:
> lbs_init_module()
>
> ### modprobe libertas_sdio
> Jan  1 00:03:42 mydevice user.debug kernel: libertas enter:
> if_sdio_init_module()
> Jan  1 00:03:42 mydevice user.info kernel: libertas_sdio: Libertas
> SDIO driver
> Jan  1 00:03:42 mydevice user.info kernel: libertas_sdio: Copyright
> Pierre Ossman
> Jan  1 00:03:42 mydevice user.debug kernel: libertas enter:
> if_sdio_probe()
> Jan  1 00:03:42 mydevice user.debug kernel: libertas sdio: class =
> 0x7, vendor = 0x2DF, device = 0x9103, model = 0xB, ioport = 0x10000
> Jan  1 00:03:42 mydevice user.debug kernel: libertas enter:
> if_sdio_prog_firmware()
> Jan  1 00:03:42 mydevice user.debug kernel: libertas enter:
> if_sdio_prog_helper()
> Jan  1 00:03:42 mydevice user.info kernel: firmware: requesting
> sd8686_helper.bin
> Jan  1 00:03:42 mydevice user.debug kernel: libertas sdio: waiting for
> helper to boot...
> Jan  1 00:03:42 mydevice user.debug kernel: libertas leave:
> if_sdio_prog_helper(), ret 0
> Jan  1 00:03:42 mydevice user.debug kernel: libertas enter:
> if_sdio_prog_real()
> Jan  1 00:03:42 mydevice user.info kernel: firmware: requesting
> sd8686.bin
> Jan  1 00:03:43 mydevice user.debug kernel: libertas sdio: waiting for
> firmware to boot...
> Jan  1 00:03:43 mydevice user.debug kernel: libertas leave:
> if_sdio_prog_real(), ret 0
> Jan  1 00:03:43 mydevice user.debug kernel: libertas leave:
> if_sdio_prog_firmware(), ret 0
> Jan  1 00:03:43 mydevice user.debug kernel: libertas enter:
> lbs_add_card()
> Jan  1 00:03:43 mydevice user.debug kernel: libertas enter:
> lbs_init_adapter()
> Jan  1 00:03:43 mydevice user.debug kernel: libertas enter:
> lbs_allocate_cmd_buffer()
> Jan  1 00:03:43 mydevice user.debug kernel: libertas enter:
> __lbs_cleanup_and_insert_cmd()
> Jan  1 00:03:43 mydevice user.debug kernel: libertas leave:
> __lbs_cleanup_and_insert_cmd()
> Jan  1 00:03:43 mydevice user.debug kernel: libertas enter:
> __lbs_cleanup_and_insert_cmd()
> Jan  1 00:03:43 mydevice user.debug kernel: libertas leave:
> __lbs_cleanup_and_insert_cmd()
> Jan  1 00:03:43 mydevice user.debug kernel: libertas enter:
> __lbs_cleanup_and_insert_cmd()
> Jan  1 00:03:43 mydevice user.debug kernel: libertas leave:
> __lbs_cleanup_and_insert_cmd()
> Jan  1 00:03:43 mydevice user.debug kernel: libertas enter:
> __lbs_cleanup_and_insert_cmd()
> Jan  1 00:03:43 mydevice user.debug kernel: libertas leave:
> __lbs_cleanup_and_insert_cmd()
> Jan  1 00:03:43 mydevice user.debug kernel: libertas enter:
> __lbs_cleanup_and_insert_cmd()
> Jan  1 00:03:43 mydevice user.debug kernel: libertas leave:
> __lbs_cleanup_and_insert_cmd()
> Jan  1 00:03:44 mydevice user.debug kernel: libertas enter:
> __lbs_cleanup_and_insert_cmd()
> Jan  1 00:03:44 mydevice user.debug kernel: libertas leave:
> __lbs_cleanup_and_insert_cmd()
> Jan  1 00:03:44 mydevice user.debug kernel: libertas enter:
> __lbs_cleanup_and_insert_cmd()
> Jan  1 00:03:44 mydevice user.debug kernel: libertas leave:
> __lbs_cleanup_and_insert_cmd()
> Jan  1 00:03:44 mydevice user.debug kernel: libertas enter:
> __lbs_cleanup_and_insert_cmd()
> Jan  1 00:03:44 mydevice user.debug kernel: libertas leave:
> __lbs_cleanup_and_insert_cmd()
> Jan  1 00:03:44 mydevice user.debug kernel: libertas enter:
> __lbs_cleanup_and_insert_cmd()
> Jan  1 00:03:44 mydevice user.debug kernel: libertas leave:
> __lbs_cleanup_and_insert_cmd()
> Jan  1 00:03:44 mydevice user.debug kernel: libertas enter:
> __lbs_cleanup_and_insert_cmd()
> Jan  1 00:03:44 mydevice user.debug kernel: libertas leave:
> __lbs_cleanup_and_insert_cmd()
> Jan  1 00:03:44 mydevice user.debug kernel: libertas leave:
> lbs_allocate_cmd_buffer(), ret 0
> Jan  1 00:03:44 mydevice user.debug kernel: libertas leave:
> lbs_init_adapter(), ret 0
> Jan  1 00:03:44 mydevice user.debug kernel: libertas leave:
> lbs_add_card(), priv c3a38360
> Jan  1 00:03:44 mydevice user.debug kernel: libertas enter:
> lbs_start_card()
> Jan  1 00:03:44 mydevice user.debug kernel: libertas enter:
> lbs_setup_firmware()
> Jan  1 00:03:44 mydevice user.debug kernel: libertas enter:
> lbs_update_hw_spec()
> Jan  1 00:03:44 mydevice user.debug kernel: libertas enter:  
> __lbs_cmd()
> Jan  1 00:03:44 mydevice user.debug kernel: libertas enter:
> __lbs_cmd_async()
> Jan  1 00:03:44 mydevice user.debug kernel: libertas enter:
> lbs_get_cmd_ctrl_node()
> Jan  1 00:03:44 mydevice user.debug kernel: libertas leave:
> lbs_get_cmd_ctrl_node()
> Jan  1 00:03:44 mydevice user.debug kernel: libertas host: PREP_CMD:
> command 0x0003
> Jan  1 00:03:44 mydevice user.debug kernel: libertas enter:
> lbs_queue_cmd()
> Jan  1 00:03:44 mydevice user.debug kernel: libertas host: QUEUE_CMD:
> inserted command 0x0003 into cmdpendingq
> Jan  1 00:03:44 mydevice user.debug kernel: libertas leave:
> lbs_queue_cmd()
> Jan  1 00:03:44 mydevice user.debug kernel: libertas leave:
> __lbs_cmd_async(), ret c3ab3e00
> Jan  1 00:03:44 mydevice user.debug kernel: libertas host:
> EXEC_NEXT_CMD: sending command 0x0003
> Jan  1 00:03:44 mydevice user.debug kernel: libertas enter:
> lbs_submit_command()
> Jan  1 00:03:44 mydevice user.debug kernel: libertas cmd: DNLD_CMD:
> command 0x0003, seq 1, size 46
> Jan  1 00:03:44 mydevice user.debug kernel: libertas enter:
> if_sdio_host_to_card(type 1, bytes 46)
> Jan  1 00:03:44 mydevice user.debug kernel: libertas leave:
> if_sdio_host_to_card(), ret 0
> Jan  1 00:03:44 mydevice user.debug kernel: libertas leave:
> lbs_submit_command()
> Jan  1 00:03:44 mydevice user.debug kernel: libertas enter:
> if_sdio_host_to_card_worker()
> Jan  1 00:03:44 mydevice user.debug kernel: libertas leave:
> if_sdio_host_to_card_worker()
> Jan  1 00:03:44 mydevice user.debug kernel: libertas enter:
> if_sdio_interrupt()
> Jan  1 00:03:44 mydevice user.debug kernel: libertas sdio: interrupt:
> 0x2
> Jan  1 00:03:44 mydevice user.debug kernel: libertas leave:
> if_sdio_interrupt(), ret 0
> Jan  1 00:03:44 mydevice user.debug kernel: libertas enter:
> if_sdio_interrupt()
> Jan  1 00:03:44 mydevice user.debug kernel: libertas sdio: interrupt:
> 0x1
> Jan  1 00:03:44 mydevice user.debug kernel: libertas enter:
> if_sdio_card_to_host()
> Jan  1 00:03:44 mydevice user.debug kernel: libertas sdio: packet of
> type 1 and size 50 bytes
> Jan  1 00:03:44 mydevice user.debug kernel: libertas enter:
> if_sdio_handle_cmd()
> Jan  1 00:03:44 mydevice user.debug kernel: libertas leave:
> if_sdio_handle_cmd(), ret 0
> Jan  1 00:03:44 mydevice user.debug kernel: libertas leave:
> if_sdio_card_to_host(), ret 0
> Jan  1 00:03:44 mydevice user.debug kernel: libertas leave:
> if_sdio_interrupt(), ret 0
> Jan  1 00:03:44 mydevice user.debug kernel: libertas enter:
> lbs_process_command_response()
> Jan  1 00:03:44 mydevice user.debug kernel: libertas cmd: CMD_RESP:
> response 0x8003, seq 1, size 46
> Jan  1 00:03:44 mydevice user.debug kernel: libertas leave:
> lbs_process_command_response(), ret 0
> Jan  1 00:03:44 mydevice user.debug kernel: libertas enter:
> __lbs_cleanup_and_insert_cmd()
> Jan  1 00:03:44 mydevice user.debug kernel: libertas leave:
> __lbs_cleanup_and_insert_cmd()
> Jan  1 00:03:44 mydevice user.debug kernel: libertas leave:
> __lbs_cmd(), ret 0
> Jan  1 00:03:44 mydevice user.info kernel: libertas:
> 00:19:88:05:11:56, fw 9.70.3p24, cap 0x00000303
> Jan  1 00:03:44 mydevice user.debug kernel: libertas cmd: GET_HW_SPEC:
> hardware interface 0x2, hardware spec 0x0011
> Jan  1 00:03:44 mydevice user.debug kernel: libertas enter:
> lbs_set_regiontable()
> Jan  1 00:03:44 mydevice user.debug kernel: libertas enter:
> lbs_get_region_cfp_table()
> Jan  1 00:03:44 mydevice user.debug kernel: libertas main:
> region_cfp_table[i].region=16
> Jan  1 00:03:44 mydevice user.debug kernel: libertas main:
> region_cfp_table[i].region=32
> Jan  1 00:03:44 mydevice user.debug kernel: libertas main:
> region_cfp_table[i].region=48
> Jan  1 00:03:44 mydevice user.debug kernel: libertas main:
> region_cfp_table[i].region=49
> Jan  1 00:03:44 mydevice user.debug kernel: libertas main:
> region_cfp_table[i].region=50
> Jan  1 00:03:44 mydevice user.debug kernel: libertas main:
> region_cfp_table[i].region=64
> Jan  1 00:03:44 mydevice user.debug kernel: libertas leave:
> lbs_get_region_cfp_table()
> Jan  1 00:03:44 mydevice user.debug kernel: libertas leave:
> lbs_set_regiontable(), ret 0
> Jan  1 00:03:44 mydevice user.debug kernel: libertas leave:
> lbs_update_hw_spec()
> Jan  1 00:03:44 mydevice user.debug kernel: libertas enter:
> lbs_set_mac_control()
> Jan  1 00:03:44 mydevice user.debug kernel: libertas enter:
> lbs_cmd_async()
> Jan  1 00:03:44 mydevice user.debug kernel: libertas enter:
> __lbs_cmd_async()
> Jan  1 00:03:44 mydevice user.debug kernel: libertas enter:
> lbs_get_cmd_ctrl_node()
> Jan  1 00:03:44 mydevice user.debug kernel: libertas leave:
> lbs_get_cmd_ctrl_node()
> Jan  1 00:03:44 mydevice user.debug kernel: libertas host: PREP_CMD:
> command 0x0028
> Jan  1 00:03:44 mydevice user.debug kernel: libertas enter:
> lbs_queue_cmd()
> Jan  1 00:03:44 mydevice user.debug kernel: libertas host: QUEUE_CMD:
> inserted command 0x0028 into cmdpendingq
> Jan  1 00:03:44 mydevice user.debug kernel: libertas leave:
> lbs_queue_cmd()
> Jan  1 00:03:44 mydevice user.debug kernel: libertas leave:
> __lbs_cmd_async(), ret c3ab3e24
> Jan  1 00:03:44 mydevice user.debug kernel: libertas leave:
> lbs_cmd_async()
> Jan  1 00:03:44 mydevice user.debug kernel: libertas leave:
> lbs_set_mac_control()
> Jan  1 00:03:44 mydevice user.debug kernel: libertas leave:
> lbs_setup_firmware(), ret 0
> Jan  1 00:03:44 mydevice user.debug kernel: libertas enter:
> lbs_update_channel()
> Jan  1 00:03:44 mydevice user.debug kernel: libertas enter:
> lbs_get_channel()
> Jan  1 00:03:44 mydevice user.debug kernel: libertas enter:  
> __lbs_cmd()
> Jan  1 00:03:44 mydevice user.debug kernel: libertas enter:
> __lbs_cmd_async()
> Jan  1 00:03:44 mydevice user.debug kernel: libertas enter:
> lbs_get_cmd_ctrl_node()
> Jan  1 00:03:44 mydevice user.debug kernel: libertas leave:
> lbs_get_cmd_ctrl_node()
> Jan  1 00:03:44 mydevice user.debug kernel: libertas host: PREP_CMD:
> command 0x001d
> Jan  1 00:03:44 mydevice user.debug kernel: libertas enter:
> lbs_queue_cmd()
> Jan  1 00:03:44 mydevice user.debug kernel: libertas host: QUEUE_CMD:
> inserted command 0x001d into cmdpendingq
> Jan  1 00:03:44 mydevice user.debug kernel: libertas leave:
> lbs_queue_cmd()
> Jan  1 00:03:44 mydevice user.debug kernel: libertas leave:
> __lbs_cmd_async(), ret c3ab3e48
> Jan  1 00:03:44 mydevice user.debug kernel: libertas host:
> EXEC_NEXT_CMD: sending command 0x0028
> Jan  1 00:03:44 mydevice user.debug kernel: libertas enter:
> lbs_submit_command()
> Jan  1 00:03:44 mydevice user.debug kernel: libertas cmd: DNLD_CMD:
> command 0x0028, seq 2, size 12
> Jan  1 00:03:44 mydevice user.debug kernel: libertas enter:
> if_sdio_host_to_card(type 1, bytes 12)
> Jan  1 00:03:44 mydevice user.debug kernel: libertas leave:
> if_sdio_host_to_card(), ret 0
> Jan  1 00:03:44 mydevice user.debug kernel: libertas leave:
> lbs_submit_command()
> Jan  1 00:03:44 mydevice user.debug kernel: libertas enter:
> if_sdio_host_to_card_worker()
> Jan  1 00:03:44 mydevice user.debug kernel: libertas leave:
> if_sdio_host_to_card_worker()
> Jan  1 00:03:44 mydevice user.debug kernel: libertas enter:
> if_sdio_interrupt()
> Jan  1 00:03:44 mydevice user.debug kernel: libertas sdio: interrupt:
> 0x2
> Jan  1 00:03:44 mydevice user.debug kernel: libertas leave:
> if_sdio_interrupt(), ret 0
> Jan  1 00:03:44 mydevice user.debug kernel: libertas enter:
> if_sdio_interrupt()
> Jan  1 00:03:44 mydevice user.debug kernel: libertas sdio: interrupt:
> 0x1
> Jan  1 00:03:44 mydevice user.debug kernel: libertas enter:
> if_sdio_card_to_host()
> Jan  1 00:03:44 mydevice user.debug kernel: libertas sdio: packet of
> type 1 and size 16 bytes
> Jan  1 00:03:44 mydevice user.debug kernel: libertas enter:
> if_sdio_handle_cmd()
> Jan  1 00:03:44 mydevice user.debug kernel: libertas leave:
> if_sdio_handle_cmd(), ret 0
> Jan  1 00:03:44 mydevice user.debug kernel: libertas leave:
> if_sdio_card_to_host(), ret 0
> Jan  1 00:03:44 mydevice user.debug kernel: libertas leave:
> if_sdio_interrupt(), ret 0
> Jan  1 00:03:44 mydevice user.debug kernel: libertas enter:
> lbs_process_command_response()
> Jan  1 00:03:44 mydevice user.debug kernel: libertas cmd: CMD_RESP:
> response 0x8028, seq 2, size 12
> Jan  1 00:03:44 mydevice user.debug kernel: libertas enter:
> __lbs_cleanup_and_insert_cmd()
> Jan  1 00:03:44 mydevice user.debug kernel: libertas leave:
> __lbs_cleanup_and_insert_cmd()
> Jan  1 00:03:44 mydevice user.debug kernel: libertas leave:
> lbs_process_command_response(), ret 0
> Jan  1 00:03:44 mydevice user.debug kernel: libertas host:
> EXEC_NEXT_CMD: sending command 0x001d
> Jan  1 00:03:44 mydevice user.debug kernel: libertas enter:
> lbs_submit_command()
> Jan  1 00:03:44 mydevice user.debug kernel: libertas cmd: DNLD_CMD:
> command 0x001d, seq 3, size 48
> Jan  1 00:03:44 mydevice user.debug kernel: libertas enter:
> if_sdio_host_to_card(type 1, bytes 48)
> Jan  1 00:03:44 mydevice user.debug kernel: libertas leave:
> if_sdio_host_to_card(), ret 0
> Jan  1 00:03:44 mydevice user.debug kernel: libertas leave:
> lbs_submit_command()
> Jan  1 00:03:44 mydevice user.debug kernel: libertas enter:
> if_sdio_host_to_card_worker()
> Jan  1 00:03:44 mydevice user.debug kernel: libertas leave:
> if_sdio_host_to_card_worker()
> Jan  1 00:03:44 mydevice user.debug kernel: libertas enter:
> if_sdio_interrupt()
> Jan  1 00:03:44 mydevice user.debug kernel: libertas sdio: interrupt:
> 0x2
> Jan  1 00:03:44 mydevice user.debug kernel: rtas leave:
> if_sdio_interrupt(), ret 0
> Jan  1 00:03:44 mydevice user.debug kernel: libertas enter:
> if_sdio_interrupt()
> Jan  1 00:03:44 mydevice user.debug kernel: libertas sdio: interrupt:
> 0x1
> Jan  1 00:03:44 mydevice user.debug kernel: libertas enter:
> if_sdio_card_to_host()
> Jan  1 00:03:44 mydevice user.debug kernel: libertas sdio: packet of
> type 1 and size 52 bytes
> Jan  1 00:03:44 mydevice user.debug kernel: libertas enter:
> if_sdio_handle_cmd()
> Jan  1 00:03:44 mydevice user.debug kernel: libertas leave:
> if_sdio_handle_cmd(), ret 0
> Jan  1 00:03:44 mydevice user.debug kernel: libertas leave:
> if_sdio_card_to_host(), ret 0
> Jan  1 00:03:44 mydevice user.debug kernel: libertas leave:
> if_sdio_interrupt(), ret 0
> Jan  1 00:03:44 mydevice user.debug kernel: libertas enter:
> lbs_process_command_response()
> Jan  1 00:03:44 mydevice user.debug kernel: libertas cmd: CMD_RESP:
> response 0x801d, seq 3, size 48
> Jan  1 00:03:44 mydevice user.debug kernel: libertas leave:
> lbs_process_command_response(), ret 0
> Jan  1 00:03:44 mydevice user.debug kernel: libertas enter:
> __lbs_cleanup_and_insert_cmd()
> Jan  1 00:03:44 mydevice user.debug kernel: libertas leave:
> __lbs_cleanup_and_insert_cmd()
> Jan  1 00:03:44 mydevice user.debug kernel: libertas leave:
> __lbs_cmd(), ret 0
> Jan  1 00:03:44 mydevice user.debug kernel: libertas cmd: current
> radio channel is 1
> Jan  1 00:03:44 mydevice user.debug kernel: libertas leave:
> lbs_get_channel(), ret 1
> Jan  1 00:03:44 mydevice user.debug kernel: libertas leave:
> lbs_update_channel(), ret 0
> Jan  1 00:03:44 mydevice user.debug kernel: libertas cmd: mesh config
> action 1 type 223 channel 1 SSID mesh
> Jan  1 00:03:44 mydevice user.debug kernel: libertas enter:
> lbs_mesh_config_send()
> Jan  1 00:03:44 mydevice user.debug kernel: libertas enter:  
> __lbs_cmd()
> Jan  1 00:03:44 mydevice user.debug kernel: libertas enter:
> __lbs_cmd_async()
> Jan  1 00:03:44 mydevice user.debug kernel: libertas enter:
> lbs_get_cmd_ctrl_node()
> Jan  1 00:03:44 mydevice user.debug kernel: libertas leave:
> lbs_get_cmd_ctrl_node()
> Jan  1 00:03:44 mydevice user.debug kernel: libertas host: PREP_CMD:
> command 0x00a3
> Jan  1 00:03:44 mydevice user.debug kernel: libertas enter:
> lbs_queue_cmd()
> Jan  1 00:03:44 mydevice user.debug kernel: libertas host: QUEUE_CMD:
> inserted command 0x00a3 into cmdpendingq
> Jan  1 00:03:44 mydevice user.debug kernel: libertas leave:
> lbs_queue_cmd()
> Jan  1 00:03:44 mydevice user.debug kernel: libertas leave:
> __lbs_cmd_async(), ret c3ab3e6c
> Jan  1 00:03:44 mydevice user.debug kernel: libertas host:
> EXEC_NEXT_CMD: sending command 0x00a3
> Jan  1 00:03:44 mydevice user.debug kernel: libertas enter:
> lbs_submit_command()
> Jan  1 00:03:44 mydevice user.debug kernel: libertas cmd: DNLD_CMD:
> command 0x00a3, seq 4, size 144
> Jan  1 00:03:44 mydevice user.debug kernel: libertas enter:
> if_sdio_host_to_card(type 1, bytes 144)
> Jan  1 00:03:44 mydevice user.debug kernel: libertas leave:
> if_sdio_host_to_card(), ret 0
> Jan  1 00:03:44 mydevice user.debug kernel: libertas leave:
> lbs_submit_command()
> Jan  1 00:03:44 mydevice user.debug kernel: libertas enter:
> if_sdio_host_to_card_worker()
> Jan  1 00:03:44 mydevice user.debug kernel: libertas leave:
> if_sdio_host_to_card_worker()
> Jan  1 00:03:44 mydevice user.debug kernel: libertas enter:
> if_sdio_interrupt()
> Jan  1 00:03:44 mydevice user.debug kernel: libertas sdio: interrupt:
> 0x2
> Jan  1 00:03:44 mydevice user.debug kernel: libertas leave:
> if_sdio_interrupt(), ret 0
> Jan  1 00:03:44 mydevice user.debug kernel: libertas enter:
> if_sdio_interrupt()
> Jan  1 00:03:44 mydevice user.debug kernel: libertas sdio: interrupt:
> 0x1
> Jan  1 00:03:44 mydevice user.debug kernel: libertas enter:
> if_sdio_card_to_host()
> Jan  1 00:03:44 mydevice user.debug kernel: libertas sdio: packet of
> type 1 and size 148 bytes
> Jan  1 00:03:44 mydevice user.debug kernel: libertas enter:
> if_sdio_handle_cmd()
> Jan  1 00:03:44 mydevice user.debug kernel: libertas leave:
> if_sdio_handle_cmd(), ret 0
> Jan  1 00:03:44 mydevice user.debug kernel: libertas leave:
> if_sdio_card_to_host(), ret 0
> Jan  1 00:03:44 mydevice user.debug kernel: libertas leave:
> if_sdio_interrupt(), ret 0
> Jan  1 00:03:44 mydevice user.debug kernel: libertas enter:
> lbs_process_command_response()
> Jan  1 00:03:44 mydevice user.debug kernel: libertas cmd: CMD_RESP:
> response 0x80a3, seq 4, size 144
> Jan  1 00:03:44 mydevice user.debug kernel: libertas host: CMD_RESP:
> error 0x0002 in command reply 0x80a3
> Jan  1 00:03:44 mydevice user.debug kernel: libertas leave:
> lbs_process_command_response(), ret -1
> Jan  1 00:03:44 mydevice user.info kernel: libertas: PREP_CMD: command
> 0x00a3 failed: 2
> Jan  1 00:03:44 mydevice user.debug kernel: libertas enter:
> __lbs_cleanup_and_insert_cmd()
> Jan  1 00:03:44 mydevice user.debug kernel: libertas leave:
> __lbs_cleanup_and_insert_cmd()
> Jan  1 00:03:44 mydevice user.debug kernel: libertas leave:
> __lbs_cmd(), ret 2
> Jan  1 00:03:44 mydevice user.debug kernel: libertas leave:
> lbs_mesh_config_send()
> Jan  1 00:03:44 mydevice user.debug kernel: libertas cmd: mesh config
> action 1 type 125 channel 1 SSID mesh
> Jan  1 00:03:44 mydevice user.debug kernel: libertas enter:
> lbs_mesh_config_send()
> Jan  1 00:03:44 mydevice user.debug kernel: libertas enter:  
> __lbs_cmd()
> Jan  1 00:03:44 mydevice user.debug kernel: libertas enter:
> __lbs_cmd_async()
> Jan  1 00:03:44 mydevice user.debug kernel: libertas enter:
> lbs_get_cmd_ctrl_node()
> Jan  1 00:03:44 mydevice user.debug kernel: libertas leave:
> lbs_get_cmd_ctrl_node()
> Jan  1 00:03:44 mydevice user.debug kernel: libertas host: PREP_CMD:
> command 0x00a3
> Jan  1 00:03:44 mydevice user.debug kernel: libertas enter:
> lbs_queue_cmd()
> Jan  1 00:03:44 mydevice user.debug kernel: libertas host: QUEUE_CMD:
> inserted command 0x00a3 into cmdpendingq
> Jan  1 00:03:44 mydevice user.debug kernel: libertas leave:
> lbs_queue_cmd()
> Jan  1 00:03:44 mydevice user.debug kernel: libertas leave:
> __lbs_cmd_async(), ret c3ab3e90
> Jan  1 00:03:44 mydevice user.debug kernel: libertas host:
> EXEC_NEXT_CMD: sending command 0x00a3
> Jan  1 00:03:44 mydevice user.debug kernel: libertas enter:
> lbs_submit_command()
> Jan  1 00:03:44 mydevice user.debug kernel: libertas cmd: DNLD_CMD:
> command 0x00a3, seq 5, size 144
> Jan  1 00:03:44 mydevice user.debug kernel: libertas enter:
> if_sdio_host_to_card(type 1, bytes 144)
> Jan  1 00:03:44 mydevice user.debug kernel: libertas leave:
> if_sdio_host_to_card(), ret 0
> Jan  1 00:03:44 mydevice user.debug kernel: libertas leave:
> lbs_submit_command()
> Jan  1 00:03:44 mydevice user.debug kernel: libertas enter:
> if_sdio_host_to_card_worker()
> Jan  1 00:03:44 mydevice user.debug kernel: libertas leave:
> if_sdio_host_to_card_worker()
> Jan  1 00:03:44 mydevice user.debug kernel: libertas enter:
> if_sdio_interrupt()
> Jan  1 00:03:44 mydevice user.debug kernel: libertas sdio: interrupt:
> 0x2
> Jan  1 00:03:44 mydevice user.debug kernel: libertas leave:
> if_sdio_interrupt(), ret 0
> Jan  1 00:03:44 mydevice user.debug kernel: libertas enter:
> if_sdio_interrupt()
> Jan  1 00:03:44 mydevice user.debug kernel: libertas sdio: interrupt:
> 0x1
> Jan  1 00:03:44 mydevice user.debug kernel: libertas enter:
> if_sdio_card_to_host()
> Jan  1 00:03:44 mydevice user.debug kernel: libertas sdio: packet of
> type 1 and size 148 bytes
> Jan  1 00:03:44 mydevice user.debug kernel: libertas enter:
> if_sdio_handle_cmd()
> Jan  1 00:03:44 mydevice user.debug kernel: libertas leave:
> if_sdio_handle_cmd(), ret 0
> Jan  1 00:03:44 mydevice user.debug kernel: libertas leave:
> if_sdio_card_to_host(), ret 0
> Jan  1 00:03:44 mydevice user.debug kernel: libertas leave:
> if_sdio_interrupt(), ret 0
> Jan  1 00:03:44 mydevice user.debug kernel: libertas enter:
> lbs_process_command_response()
> Jan  1 00:03:44 mydevice user.debug kernel:  response 0x80a3, seq 5,
> size 144
> Jan  1 00:03:44 mydevice user.debug kernel: libertas host: CMD_RESP:
> error 0x0002 in command reply 0x80a3
> Jan  1 00:03:44 mydevice user.debug kernel: libertas leave:
> lbs_process_command_response(), ret -1
> Jan  1 00:03:44 mydevice user.info kernel: libertas: PREP_CMD: command
> 0x00a3 failed: 2
> Jan  1 00:03:44 mydevice user.debug kernel: libertas enter:
> __lbs_cleanup_and_insert_cmd()
> Jan  1 00:03:44 mydevice user.debug kernel: libertas leave:
> __lbs_cleanup_and_insert_cmd()
> Jan  1 00:03:44 mydevice user.debug kernel: libertas leave:
> __lbs_cmd(), ret 2
> Jan  1 00:03:44 mydevice user.debug kernel: libertas leave:
> lbs_mesh_config_send()
> Jan  1 00:03:44 mydevice user.info kernel: libertas: eth1: Marvell
> WLAN 802.11 adapter
> Jan  1 00:03:44 mydevice user.debug kernel: libertas leave:
> lbs_start_card(), ret 0
> Jan  1 00:03:44 mydevice user.debug kernel: libertas leave:
> if_sdio_probe(), ret 0
> Jan  1 00:03:44 mydevice user.debug kernel: libertas leave:
> if_sdio_init_module(), ret 0
>
> ### ifconfig eth1 up
> Jan  1 00:05:09 mydevice user.debug kernel: libertas enter:
> lbs_cmd_async()
> Jan  1 00:05:09 mydevice user.debug kernel: libertas enter:
> __lbs_cmd_async()
> Jan  1 00:05:09 mydevice user.debug kernel: libertas enter:
> lbs_get_cmd_ctrl_node()
> Jan  1 00:05:09 mydevice user.debug kernel: libertas leave:
> lbs_get_cmd_ctrl_node()
> Jan  1 00:05:09 mydevice user.debug kernel: libertas host: PREP_CMD:
> command 0x0010
> Jan  1 00:05:09 mydevice user.debug kernel: libertas enter:
> lbs_queue_cmd()
> Jan  1 00:05:09 mydevice user.debug kernel: libertas host: QUEUE_CMD:
> inserted command 0x0010 into cmdpendingq
> Jan  1 00:05:09 mydevice user.debug kernel: libertas leave:
> lbs_queue_cmd()
> Jan  1 00:05:09 mydevice user.debug kernel: libertas leave:
> __lbs_cmd_async(), ret c3ab3eb4
> Jan  1 00:05:09 mydevice user.debug kernel: libertas leave:
> lbs_cmd_async()
> Jan  1 00:05:09 mydevice user.debug kernel: libertas enter:
> lbs_set_mac_control()
> Jan  1 00:05:09 mydevice user.debug kernel: libertas enter:
> lbs_cmd_async()
> Jan  1 00:05:09 mydevice user.debug kernel: libertas enter:
> __lbs_cmd_async()
> Jan  1 00:05:09 mydevice user.debug kernel: libertas enter:
> lbs_get_cmd_ctrl_node()
> Jan  1 00:05:09 mydevice user.debug kernel: libertas leave:
> lbs_get_cmd_ctrl_node()
> Jan  1 00:05:09 mydevice user.debug kernel: libertas host: PREP_CMD:
> command 0x0028
> Jan  1 00:05:09 mydevice user.debug kernel: libertas enter:
> lbs_queue_cmd()
> Jan  1 00:05:09 mydevice user.debug kernel: libertas host: QUEUE_CMD:
> inserted command 0x0028 into cmdpendingq
> Jan  1 00:05:09 mydevice user.debug kernel: libertas leave:
> lbs_queue_cmd()
> Jan  1 00:05:09 mydevice user.debug kernel: libertas leave:
> __lbs_cmd_async(), ret c3ab3ed8
> Jan  1 00:05:09 mydevice user.debug kernel: libertas leave:
> lbs_cmd_async()
> Jan  1 00:05:09 mydevice user.debug kernel: libertas leave:
> lbs_set_mac_control()
> Jan  1 00:05:09 mydevice user.debug kernel: libertas host:
> EXEC_NEXT_CMD: sending command 0x0010
> Jan  1 00:05:09 mydevice user.debug kernel: libertas enter:
> lbs_submit_command()
>
> ### udhcpc -H mydevice -i eth1
>
> _______________________________________________
> libertas-dev mailing list
> libertas-dev at lists.infradead.org
> http://lists.infradead.org/mailman/listinfo/libertas-dev


Update: Sorry, bad syslog config was the reason my logging stopped.  
here's the output after starting udhcpc.

  Jan  1 00:02:40 mydevice local0.info udhcpc[1239]: udhcpc (v1.2.1)  
started
Jan  1 00:02:40 mydevice user.debug kernel: libertas enter:  
lbs_cmd_async()
Jan  1 00:02:40 mydevice user.debug kernel: libertas enter:  
__lbs_cmd_async()
Jan  1 00:02:40 mydevice user.debug kernel: libertas enter:  
lbs_get_cmd_ctrl_node()
Jan  1 00:02:40 mydevice user.debug kernel: libertas leave:  
lbs_get_cmd_ctrl_node()
Jan  1 00:02:40 mydevice user.debug kernel: libertas host: PREP_CMD:  
command 0x0010
Jan  1 00:02:40 mydevice user.debug kernel: libertas enter:  
lbs_queue_cmd()
Jan  1 00:02:40 mydevice user.debug kernel: libertas host: QUEUE_CMD:  
inserted command 0x0010 into cmdpendingq
Jan  1 00:02:40 mydevice user.debug kernel: libertas leave:  
lbs_queue_cmd()
Jan  1 00:02:40 mydevice user.debug kernel: libertas leave:  
__lbs_cmd_async(), ret c3adcab4
Jan  1 00:02:40 mydevice user.debug kernel: libertas leave:  
lbs_cmd_async()
Jan  1 00:02:40 mydevice user.debug kernel: libertas host:  
EXEC_NEXT_CMD: sending command 0x0010
Jan  1 00:02:40 mydevice user.debug kernel: libertas enter:  
lbs_submit_command()
Jan  1 00:02:40 mydevice user.debug kernel: libertas cmd: DNLD_CMD:  
command 0x0010, seq 16, size 18
Jan  1 00:02:40 mydevice user.debug kernel: libertas enter:  
if_sdio_host_to_card(type 1, bytes 18)
Jan  1 00:02:40 mydevice user.debug kernel: libertas leave:  
if_sdio_host_to_card(), ret 0
Jan  1 00:02:40 mydevice user.debug kernel: libertas leave:  
lbs_submit_command()
Jan  1 00:02:40 mydevice user.debug kernel: libertas enter:  
if_sdio_host_to_card_worker()
Jan  1 00:02:40 mydevice user.debug kernel: libertas leave:  
if_sdio_host_to_card_worker()
Jan  1 00:02:40 mydevice user.debug kernel: libertas enter:  
if_sdio_interrupt()
Jan  1 00:02:40 mydevice user.debug kernel: libertas sdio: interrupt:  
0x2
Jan  1 00:02:40 mydevice user.debug kernel: libertas leave:  
if_sdio_interrupt(), ret 0
Jan  1 00:02:40 mydevice user.debug kernel: libertas enter:  
if_sdio_interrupt()
Jan  1 00:02:40 mydevice user.debug kernel: libertas sdio: interrupt:  
0x1
Jan  1 00:02:40 mydevice user.debug kernel: libertas enter:  
if_sdio_card_to_host()
Jan  1 00:02:40 mydevice user.debug kernel: libertas sdio: packet of  
type 1 and size 22 bytes
Jan  1 00:02:40 mydevice user.debug kernel: libertas enter:  
if_sdio_handle_cmd()
Jan  1 00:02:40 mydevice user.debug kernel: libertas leave:  
if_sdio_handle_cmd(), ret 0
Jan  1 00:02:40 mydevice user.debug kernel: libertas leave:  
if_sdio_card_to_host(), ret 0
Jan  1 00:02:40 mydevice user.debug kernel: libertas leave:  
if_sdio_interrupt(), ret 0
Jan  1 00:02:40 mydevice user.debug kernel: libertas enter:  
lbs_process_command_response()
Jan  1 00:02:40 mydevice user.debug kernel: libertas cmd: CMD_RESP:  
response 0x8010, seq 16, size 18
Jan  1 00:02:40 mydevice user.debug kernel: libertas enter:  
__lbs_cleanup_and_insert_cmd()
Jan  1 00:02:40 mydevice user.debug kernel: libertas leave:  
__lbs_cleanup_and_insert_cmd()
Jan  1 00:02:40 mydevice user.debug kernel: libertas leave:  
lbs_process_command_response(), ret 0
Jan  1 00:02:40 mydevice local0.debug udhcpc[1239]: Sending discover...
Jan  1 00:02:40 mydevice user.debug kernel: libertas enter:  
if_sdio_host_to_card(type 0, bytes 614)
Jan  1 00:02:40 mydevice user.debug kernel: libertas leave:  
if_sdio_host_to_card(), ret 0
Jan  1 00:02:40 mydevice user.debug kernel: libertas enter:  
if_sdio_host_to_card_worker()
Jan  1 00:02:40 mydevice user.debug kernel: libertas leave:  
if_sdio_host_to_card_worker()
Jan  1 00:02:43 mydevice local0.debug udhcpc[1239]: Sending discover...
Jan  1 00:02:46 mydevice local0.debug udhcpc[1239]: Sending discover...
Jan  1 00:02:53 mydevice user.warn kernel: ------------[ cut  
here ]------------
Jan  1 00:02:53 mydevice local0.debug udhcpc[1239]: Sending select for  
192.168.5.128...
Jan  1 00:02:53 mydevice user.warn kernel: WARNING: at net/sched/ 
sch_generic.c:219 dev_watchdog+0x154/0x240()
Jan  1 00:02:53 mydevice user.info kernel: NETDEV WATCHDOG: eth1  
(libertas_sdio): transmit timed out
Jan  1 00:02:53 mydevice user.warn kernel: Modules linked in:  
libertas_sdio libertas at91_mci [last unloaded: libertas]
Jan  1 00:02:53 mydevice user.warn kernel: [<c0026f9c>] (dump_stack 
+0x0/0x14) from [<c0039274>] (warn_slowpath+0x64/0x80)
Jan  1 00:02:53 mydevice user.warn kernel: [<c0039210>] (warn_slowpath 
+0x0/0x80) from [<c018f178>] (dev_watchdog+0x154/0x240)
Jan  1 00:02:53 mydevice user.warn kernel:  r3:c3a10000 r2:c0266b29
Jan  1 00:02:53 mydevice user.warn kernel:  r6:c018f024 r5:c3a10000  
r4:c02b3ab4
Jan  1 00:02:53 mydevice user.warn kernel: [<c018f024>] (dev_watchdog 
+0x0/0x240) from [<c004248c>] (run_timer_softirq+0x150/0x1e8)
Jan  1 00:02:53 mydevice user.warn kernel:  r6:c018f024 r5:00000100  
r4:c02a0180
Jan  1 00:02:53 mydevice user.warn kernel: [<c004233c>]  
(run_timer_softirq+0x0/0x1e8) from [<c003df54>] (__do_softirq+0x60/0xc8)
Jan  1 00:02:53 mydevice user.warn kernel:  r7:c0285c90 r6:0000000a  
r5:c029ff84 r4:00000001
Jan  1 00:02:53 mydevice user.warn kernel: [<c003def4>] (__do_softirq 
+0x0/0xc8) from [<c003e2a4>] (irq_exit+0x48/0x50)
Jan  1 00:02:53 mydevice user.warn kernel:  r7:c0285c90 r6:00000000  
r5:c0289ee4 r4:00000001
Jan  1 00:02:53 mydevice user.warn kernel: [<c003e25c>] (irq_exit 
+0x0/0x50) from [<c0022054>] (__exception_text_start+0x54/0x6c)
Jan  1 00:02:53 mydevice user.warn kernel: [<c0022000>]  
(__exception_text_start+0x0/0x6c) from [<c0022834>] (__irq_svc 
+0x34/0x60)
Jan  1 00:02:53 mydevice user.warn kernel: Exception stack(0xc0283f50  
to 0xc0283f98)
Jan  1 00:02:53 mydevice user.warn kernel:  
3f40:                                     00000000 0005317f 0005217f  
60000013
Jan  1 00:02:53 mydevice user.warn kernel: 3f60: c0024270 c0282000  
c001fee8 c0285c90 2001da34 41069265 2001da00 c0283fa4
Jan  1 00:02:53 mydevice user.warn kernel: 3f80: 600000d3 c0283f98  
c00242b8 c00242c4 60000013 ffffffff
Jan  1 00:02:53 mydevice user.warn kernel:  r7:c0285c90 r6:00000001  
r5:fefff000 r4:ffffffff
Jan  1 00:02:53 mydevice user.warn kernel: [<c0024270>] (default_idle 
+0x0/0x5c) from [<c002414c>] (cpu_idle+0x3c/0x58)
Jan  1 00:02:53 mydevice user.warn kernel: [<c0024110>] (cpu_idle 
+0x0/0x58) from [<c01ff3cc>] (rest_init+0x58/0x6c)
Jan  1 00:02:53 mydevice user.warn kernel:  r5:c029a850 r4:c02a29c8
Jan  1 00:02:53 mydevice user.warn kernel: [<c01ff374>] (rest_init 
+0x0/0x6c) from [<c0008bcc>] (start_kernel+0x288/0x2e0)
Jan  1 00:02:53 mydevice user.warn kernel: [<c0008944>] (start_kernel 
+0x0/0x2e0) from [<20008034>] (0x20008034)
Jan  1 00:02:53 mydevice user.warn kernel:  r5:c029ad60 r4:00053175
Jan  1 00:02:53 mydevice user.warn kernel: ---[ end trace  
bc8156f409d24c91 ]---
Jan  1 00:02:53 mydevice user.err kernel: libertas: tx watch dog timeout
Jan  1 00:02:53 mydevice user.debug kernel: libertas enter (INT):  
lbs_prepare_and_send_command()
Jan  1 00:02:53 mydevice user.debug kernel: libertas enter (INT):  
lbs_get_cmd_ctrl_node()
Jan  1 00:02:53 mydevice user.debug kernel: libertas leave (INT):  
lbs_get_cmd_ctrl_node()
Jan  1 00:02:53 mydevice user.debug kernel: libertas host (INT):  
PREP_CMD: command 0x001f
Jan  1 00:02:53 mydevice user.debug kernel: libertas enter (INT):  
lbs_cmd_802_11_rssi()
Jan  1 00:02:53 mydevice user.debug kernel: libertas leave (INT):  
lbs_cmd_802_11_rssi()
Jan  1 00:02:53 mydevice user.debug kernel: libertas enter (INT):  
lbs_queue_cmd()
Jan  1 00:02:53 mydevice user.debug kernel: libertas host (INT):  
QUEUE_CMD: inserted command 0x001f into cmdpendingq
Jan  1 00:02:53 mydevice user.debug kernel: libertas leave (INT):  
lbs_queue_cmd()
Jan  1 00:02:53 mydevice user.debug kernel: libertas leave (INT):  
lbs_prepare_and_send_command(), ret 0
Jan  1 00:02:53 mydevice user.debug kernel: libertas host:  
EXEC_NEXT_CMD: OK to send command 0x001f in psstate 0
Jan  1 00:02:53 mydevice user.debug kernel: libertas host:  
EXEC_NEXT_CMD: sending command 0x001f
Jan  1 00:02:53 mydevice user.debug kernel: libertas enter:  
lbs_submit_command()
Jan  1 00:02:53 mydevice user.debug kernel: libertas cmd: DNLD_CMD:  
command 0x001f, seq 17, size 16
Jan  1 00:02:53 mydevice user.debug kernel: libertas enter:  
if_sdio_host_to_card(type 1, bytes 16)
Jan  1 00:02:53 mydevice user.debug kernel: libertas leave:  
if_sdio_host_to_card(), ret 0
Jan  1 00:02:53 mydevice user.debug kernel: libertas leave:  
lbs_submit_command()
Jan  1 00:02:53 mydevice user.debug kernel: libertas enter:  
if_sdio_host_to_card_worker()
Jan  1 00:02:53 mydevice user.debug kernel: libertas leave:  
if_sdio_host_to_card_worker()
Jan  1 00:02:53 mydevice user.debug kernel: libertas enter:  
if_sdio_interrupt()
Jan  1 00:02:53 mydevice user.debug kernel: libertas sdio: interrupt:  
0x3
Jan  1 00:02:53 mydevice user.debug kernel: libertas enter:  
if_sdio_card_to_host()
Jan  1 00:02:53 mydevice user.debug kernel: libertas enter:  
if_sdio_host_to_card(type 0, bytes 614)
Jan  1 00:02:53 mydevice user.debug kernel: libertas leave:  
if_sdio_host_to_card(), ret 0
Jan  1 00:02:53 mydevice user.debug kernel: libertas sdio: packet of  
type 0 and size 92 bytes
Jan  1 00:02:53 mydevice user.debug kernel: libertas enter:  
if_sdio_handle_data()
Jan  1 00:02:53 mydevice user.debug kernel: libertas leave:  
if_sdio_handle_data(), ret 0
Jan  1 00:02:53 mydevice user.debug kernel: libertas leave:  
if_sdio_card_to_host(), ret 0
Jan  1 00:02:53 mydevice user.debug kernel: libertas leave:  
if_sdio_interrupt(), ret 0
Jan  1 00:02:53 mydevice user.debug kernel: libertas enter:  
if_sdio_interrupt()
Jan  1 00:02:53 mydevice user.debug kernel: libertas sdio: interrupt:  
0x1
Jan  1 00:02:53 mydevice user.debug kernel: libertas enter:  
if_sdio_card_to_host()
Jan  1 00:02:53 mydevice user.debug kernel: libertas sdio: packet of  
type 1 and size 20 bytes
Jan  1 00:02:53 mydevice user.debug kernel: libertas enter:  
if_sdio_handle_cmd()
Jan  1 00:02:53 mydevice user.debug kernel: libertas leave:  
if_sdio_handle_cmd(), ret 0
Jan  1 00:02:53 mydevice user.debug kernel: libertas leave:  
if_sdio_card_to_host(), ret 0
Jan  1 00:02:53 mydevice user.debug kernel: libertas leave:  
if_sdio_interrupt(), ret 0
Jan  1 00:02:53 mydevice user.debug kernel: libertas enter:  
if_sdio_interrupt()
Jan  1 00:02:53 mydevice user.debug kernel: libertas sdio: interrupt:  
0x1
Jan  1 00:02:53 mydevice user.debug kernel: libertas enter:  
if_sdio_card_to_host()
Jan  1 00:02:53 mydevice user.debug kernel: libertas enter:  
lbs_process_command_response()
Jan  1 00:02:53 mydevice user.debug kernel: libertas cmd: CMD_RESP:  
response 0x801f, seq 17, size 16
Jan  1 00:02:53 mydevice user.debug kernel: libertas enter:  
handle_cmd_response()
Jan  1 00:02:53 mydevice user.debug kernel: libertas enter:  
lbs_ret_802_11_rssi()
Jan  1 00:02:53 mydevice user.debug kernel: libertas cmd: RSSI: beacon  
201, avg 0
Jan  1 00:02:53 mydevice user.debug kernel: libertas leave:  
lbs_ret_802_11_rssi()
Jan  1 00:02:53 mydevice user.debug kernel: libertas leave:  
handle_cmd_response()
Jan  1 00:02:53 mydevice user.debug kernel: libertas enter:  
__lbs_cleanup_and_insert_cmd()
Jan  1 00:02:53 mydevice user.debug kernel: libertas leave:  
__lbs_cleanup_and_insert_cmd()
Jan  1 00:02:53 mydevice user.debug kernel: libertas leave:  
lbs_process_command_response(), ret 0
Jan  1 00:02:53 mydevice user.debug kernel: libertas sdio: packet of  
type 0 and size 622 bytes
Jan  1 00:02:53 mydevice user.debug kernel: libertas enter:  
if_sdio_handle_data()
Jan  1 00:02:53 mydevice user.debug kernel: libertas leave:  
if_sdio_handle_data(), ret 0
Jan  1 00:02:53 mydevice user.debug kernel: libertas leave:  
if_sdio_card_to_host(), ret 0
Jan  1 00:02:53 mydevice user.debug kernel: libertas leave:  
if_sdio_interrupt(), ret 0
Jan  1 00:02:53 mydevice user.debug kernel: libertas enter:  
if_sdio_host_to_card_worker()
Jan  1 00:02:53 mydevice user.debug kernel: libertas leave:  
if_sdio_host_to_card_worker()
Jan  1 00:02:55 mydevice local0.debug udhcpc[1239]: Sending select for  
192.168.5.128...
Jan  1 00:02:57 mydevice local0.debug udhcpc[1239]: Sending select for  
192.168.5.128...
Jan  1 00:03:03 mydevice user.err kernel: libertas: tx watch dog timeout








More information about the libertas-dev mailing list