#12094 NORM Future : complete wedgeup of machine after mwifiex_cmd_timeout_func: Timeout cmd id (...) = 0xe5, act = 0x1

Zarro Boogs per Child bugtracker at laptop.org
Thu Nov 28 01:06:23 EST 2013 

#12094: complete wedgeup of machine after mwifiex_cmd_timeout_func: Timeout cmd id
(...) = 0xe5, act = 0x1
---------------------------------+------------------------------------------
           Reporter:  shep       |       Owner:  shep                              
               Type:  defect     |      Status:  new                               
           Priority:  normal     |   Milestone:  Future Release                    
          Component:  kernel     |     Version:  Development source as of this date
         Resolution:             |    Keywords:                                    
        Next_action:  reproduce  |    Verified:  0                                 
Deployment_affected:             |   Blockedby:                                    
           Blocking:             |  
---------------------------------+------------------------------------------
Changes (by Quozl):

  * next_action:  never set => reproduce
  * version:  not specified => Development source as of this date
  * milestone:  Not Triaged => Future Release


Old description:

> I am testing suspend/resume of the mwifiex driver using a kernel.org
> linux-3.5.2 kernel on an XO-1.5 with an 8787 dev kit plugged in the
> external SD slot.
>
> iwconfig mlan0 essid olpcn
> ip link set dev mlan0 up
>
> i=0 &&
> while sleep 3 ; do
>       i=$(($i + 1)) &&
>       echo ===== $i =====
>       rtcwake -s 6 -mmem
>       time ping6 -q -A -c 1000 -I mlan0 fe80::21c:25ff:fe55:6758
>       date +'%s --- %a %b %d %H:%M:%S %Z %Y'
> done
>
> I have seen several complete wedgeups of the machine, where the only
> thing that recovers is holding the power button in.
>
> Shortly before each wedgeup, I see these sorts of error message from the
> mwifiex driver (taken from 6 different such wedgeup instances):
>
> [ 9077.640035] mwifiex_sdio mmc0:0001:1: mwifiex_cmd_timeout_func:
> Timeout cmd id (1346191575.320337) = 0xe5, act = 0x1
> [ 9077.650988] mwifiex_sdio mmc0:0001:1: num_data_h2c_failure = 0
> [ 9077.657045] mwifiex_sdio mmc0:0001:1: num_cmd_h2c_failure = 0
> [ 9077.663037] mwifiex_sdio mmc0:0001:1: num_cmd_timeout = 1
> [ 9077.668656] mwifiex_sdio mmc0:0001:1: num_tx_timeout = 0
> [ 9077.674226] mwifiex_sdio mmc0:0001:1: last_cmd_index = 1
> [ 9077.679772] mwifiex_sdio mmc0:0001:1: last_cmd_resp_index = 0
> [ 9077.685771] mwifiex_sdio mmc0:0001:1: last_event_index = 4
> [ 9077.691492] mwifiex_sdio mmc0:0001:1: data_sent=0 cmd_sent=1
> [ 9077.697355] mwifiex_sdio mmc0:0001:1: ps_mode=1 ps_state=0
>
> [114388.800040] mwifiex_sdio mmc0:0001:1: mwifiex_cmd_timeout_func:
> Timeout cmd id (1346684984.330266) = 0xe5, act = 0x1
> [114388.810861] mwifiex_sdio mmc0:0001:1: num_data_h2c_failure = 0
> [114388.816902] mwifiex_sdio mmc0:0001:1: num_cmd_h2c_failure = 0
> [114388.822870] mwifiex_sdio mmc0:0001:1: num_cmd_timeout = 1
> [114388.828471] mwifiex_sdio mmc0:0001:1: num_tx_timeout = 0
> [114388.833995] mwifiex_sdio mmc0:0001:1: last_cmd_index = 2
> [114388.839516] mwifiex_sdio mmc0:0001:1: last_cmd_resp_index = 1
> [114388.845479] mwifiex_sdio mmc0:0001:1: last_event_index = 3
> [114388.851181] mwifiex_sdio mmc0:0001:1: data_sent=0 cmd_sent=1
> [114388.857036] mwifiex_sdio mmc0:0001:1: ps_mode=1 ps_state=0
>
> [40199.880033] mwifiex_sdio mmc0:0001:1: mwifiex_cmd_timeout_func:
> Timeout cmd id (1346765638.100309) = 0xe5, act = 0x1
> [40199.890981] mwifiex_sdio mmc0:0001:1: num_data_h2c_failure = 0
> [40199.897062] mwifiex_sdio mmc0:0001:1: num_cmd_h2c_failure = 0
> [40199.903038] mwifiex_sdio mmc0:0001:1: num_cmd_timeout = 1
> [40199.908667] mwifiex_sdio mmc0:0001:1: num_tx_timeout = 0
> [40199.914190] mwifiex_sdio mmc0:0001:1: last_cmd_index = 3
> [40199.919754] mwifiex_sdio mmc0:0001:1: last_cmd_resp_index = 2
> [40199.925728] mwifiex_sdio mmc0:0001:1: last_event_index = 2
> [40199.931454] mwifiex_sdio mmc0:0001:1: data_sent=0 cmd_sent=1
> [40199.937345] mwifiex_sdio mmc0:0001:1: ps_mode=1 ps_state=0
>
> [ 5049.080035] mwifiex_sdio mmc0:0001:1: mwifiex_cmd_timeout_func:
> Timeout cmd id (1346859349.290203) = 0xe5, act = 0x1
> [ 5049.090784] mwifiex_sdio mmc0:0001:1: num_data_h2c_failure = 0
> [ 5049.096735] mwifiex_sdio mmc0:0001:1: num_cmd_h2c_failure = 0
> [ 5049.102624] mwifiex_sdio mmc0:0001:1: num_cmd_timeout = 1
> [ 5049.108132] mwifiex_sdio mmc0:0001:1: num_tx_timeout = 0
> [ 5049.113572] mwifiex_sdio mmc0:0001:1: last_cmd_index = 1
> [ 5049.119005] mwifiex_sdio mmc0:0001:1: last_cmd_resp_index = 0
> [ 5049.124882] mwifiex_sdio mmc0:0001:1: last_event_index = 0
> [ 5049.130500] mwifiex_sdio mmc0:0001:1: data_sent=0 cmd_sent=1
> [ 5049.136276] mwifiex_sdio mmc0:0001:1: ps_mode=1 ps_state=0
>
> [ 5337.220032] mwifiex_sdio mmc0:0001:1: mwifiex_cmd_timeout_func:
> Timeout cmd id (1346870963.370333) = 0xe5, act = 0x1
> [ 5337.230983] mwifiex_sdio mmc0:0001:1: num_data_h2c_failure = 0
> [ 5337.237031] mwifiex_sdio mmc0:0001:1: num_cmd_h2c_failure = 0
> [ 5337.243039] mwifiex_sdio mmc0:0001:1: num_cmd_timeout = 1
> [ 5337.248658] mwifiex_sdio mmc0:0001:1: num_tx_timeout = 0
> [ 5337.254190] mwifiex_sdio mmc0:0001:1: last_cmd_index = 0
> [ 5337.259755] mwifiex_sdio mmc0:0001:1: last_cmd_resp_index = 4
> [ 5337.265778] mwifiex_sdio mmc0:0001:1: last_event_index = 3
> [ 5337.271514] mwifiex_sdio mmc0:0001:1: data_sent=0 cmd_sent=1
> [ 5337.277395] mwifiex_sdio mmc0:0001:1: ps_mode=1 ps_state=0
>
> [21143.800036] mwifiex_sdio mmc0:0001:1: mwifiex_cmd_timeout_func:
> Timeout cmd id (1346910567.410020) = 0xe5, act = 0x1
> [21143.810775] mwifiex_sdio mmc0:0001:1: num_data_h2c_failure = 0
> [21143.816729] mwifiex_sdio mmc0:0001:1: num_cmd_h2c_failure = 0
> [21143.822599] mwifiex_sdio mmc0:0001:1: num_cmd_timeout = 1
> [21143.828113] mwifiex_sdio mmc0:0001:1: num_tx_timeout = 0
> [21143.833543] mwifiex_sdio mmc0:0001:1: last_cmd_index = 2
> [21143.838976] mwifiex_sdio mmc0:0001:1: last_cmd_resp_index = 1
> [21143.844862] mwifiex_sdio mmc0:0001:1: last_event_index = 1
> [21143.850479] mwifiex_sdio mmc0:0001:1: data_sent=0 cmd_sent=1
> [21143.856256] mwifiex_sdio mmc0:0001:1: ps_mode=1 ps_state=0

New description:

 I am testing suspend/resume of the mwifiex driver using a kernel.org
 linux-3.5.2 kernel on an XO-1.5 with an 8787 dev kit plugged in the
 external SD slot.

 {{{
 iwconfig mlan0 essid olpcn
 ip link set dev mlan0 up

 i=0 &&
 while sleep 3 ; do
       i=$(($i + 1)) &&
       echo ===== $i =====
       rtcwake -s 6 -mmem
       time ping6 -q -A -c 1000 -I mlan0 fe80::21c:25ff:fe55:6758
       date +'%s --- %a %b %d %H:%M:%S %Z %Y'
 done
 }}}

 I have seen several complete wedgeups of the machine, where the only thing
 that recovers is holding the power button in.

 Shortly before each wedgeup, I see these sorts of error message from the
 mwifiex driver (taken from 6 different such wedgeup instances):

 {{{
 [ 9077.640035] mwifiex_sdio mmc0:0001:1: mwifiex_cmd_timeout_func: Timeout
 cmd id (1346191575.320337) = 0xe5, act = 0x1
 [ 9077.650988] mwifiex_sdio mmc0:0001:1: num_data_h2c_failure = 0
 [ 9077.657045] mwifiex_sdio mmc0:0001:1: num_cmd_h2c_failure = 0
 [ 9077.663037] mwifiex_sdio mmc0:0001:1: num_cmd_timeout = 1
 [ 9077.668656] mwifiex_sdio mmc0:0001:1: num_tx_timeout = 0
 [ 9077.674226] mwifiex_sdio mmc0:0001:1: last_cmd_index = 1
 [ 9077.679772] mwifiex_sdio mmc0:0001:1: last_cmd_resp_index = 0
 [ 9077.685771] mwifiex_sdio mmc0:0001:1: last_event_index = 4
 [ 9077.691492] mwifiex_sdio mmc0:0001:1: data_sent=0 cmd_sent=1
 [ 9077.697355] mwifiex_sdio mmc0:0001:1: ps_mode=1 ps_state=0

 [114388.800040] mwifiex_sdio mmc0:0001:1: mwifiex_cmd_timeout_func:
 Timeout cmd id (1346684984.330266) = 0xe5, act = 0x1
 [114388.810861] mwifiex_sdio mmc0:0001:1: num_data_h2c_failure = 0
 [114388.816902] mwifiex_sdio mmc0:0001:1: num_cmd_h2c_failure = 0
 [114388.822870] mwifiex_sdio mmc0:0001:1: num_cmd_timeout = 1
 [114388.828471] mwifiex_sdio mmc0:0001:1: num_tx_timeout = 0
 [114388.833995] mwifiex_sdio mmc0:0001:1: last_cmd_index = 2
 [114388.839516] mwifiex_sdio mmc0:0001:1: last_cmd_resp_index = 1
 [114388.845479] mwifiex_sdio mmc0:0001:1: last_event_index = 3
 [114388.851181] mwifiex_sdio mmc0:0001:1: data_sent=0 cmd_sent=1
 [114388.857036] mwifiex_sdio mmc0:0001:1: ps_mode=1 ps_state=0

 [40199.880033] mwifiex_sdio mmc0:0001:1: mwifiex_cmd_timeout_func: Timeout
 cmd id (1346765638.100309) = 0xe5, act = 0x1
 [40199.890981] mwifiex_sdio mmc0:0001:1: num_data_h2c_failure = 0
 [40199.897062] mwifiex_sdio mmc0:0001:1: num_cmd_h2c_failure = 0
 [40199.903038] mwifiex_sdio mmc0:0001:1: num_cmd_timeout = 1
 [40199.908667] mwifiex_sdio mmc0:0001:1: num_tx_timeout = 0
 [40199.914190] mwifiex_sdio mmc0:0001:1: last_cmd_index = 3
 [40199.919754] mwifiex_sdio mmc0:0001:1: last_cmd_resp_index = 2
 [40199.925728] mwifiex_sdio mmc0:0001:1: last_event_index = 2
 [40199.931454] mwifiex_sdio mmc0:0001:1: data_sent=0 cmd_sent=1
 [40199.937345] mwifiex_sdio mmc0:0001:1: ps_mode=1 ps_state=0

 [ 5049.080035] mwifiex_sdio mmc0:0001:1: mwifiex_cmd_timeout_func: Timeout
 cmd id (1346859349.290203) = 0xe5, act = 0x1
 [ 5049.090784] mwifiex_sdio mmc0:0001:1: num_data_h2c_failure = 0
 [ 5049.096735] mwifiex_sdio mmc0:0001:1: num_cmd_h2c_failure = 0
 [ 5049.102624] mwifiex_sdio mmc0:0001:1: num_cmd_timeout = 1
 [ 5049.108132] mwifiex_sdio mmc0:0001:1: num_tx_timeout = 0
 [ 5049.113572] mwifiex_sdio mmc0:0001:1: last_cmd_index = 1
 [ 5049.119005] mwifiex_sdio mmc0:0001:1: last_cmd_resp_index = 0
 [ 5049.124882] mwifiex_sdio mmc0:0001:1: last_event_index = 0
 [ 5049.130500] mwifiex_sdio mmc0:0001:1: data_sent=0 cmd_sent=1
 [ 5049.136276] mwifiex_sdio mmc0:0001:1: ps_mode=1 ps_state=0

 [ 5337.220032] mwifiex_sdio mmc0:0001:1: mwifiex_cmd_timeout_func: Timeout
 cmd id (1346870963.370333) = 0xe5, act = 0x1
 [ 5337.230983] mwifiex_sdio mmc0:0001:1: num_data_h2c_failure = 0
 [ 5337.237031] mwifiex_sdio mmc0:0001:1: num_cmd_h2c_failure = 0
 [ 5337.243039] mwifiex_sdio mmc0:0001:1: num_cmd_timeout = 1
 [ 5337.248658] mwifiex_sdio mmc0:0001:1: num_tx_timeout = 0
 [ 5337.254190] mwifiex_sdio mmc0:0001:1: last_cmd_index = 0
 [ 5337.259755] mwifiex_sdio mmc0:0001:1: last_cmd_resp_index = 4
 [ 5337.265778] mwifiex_sdio mmc0:0001:1: last_event_index = 3
 [ 5337.271514] mwifiex_sdio mmc0:0001:1: data_sent=0 cmd_sent=1
 [ 5337.277395] mwifiex_sdio mmc0:0001:1: ps_mode=1 ps_state=0

 [21143.800036] mwifiex_sdio mmc0:0001:1: mwifiex_cmd_timeout_func: Timeout
 cmd id (1346910567.410020) = 0xe5, act = 0x1
 [21143.810775] mwifiex_sdio mmc0:0001:1: num_data_h2c_failure = 0
 [21143.816729] mwifiex_sdio mmc0:0001:1: num_cmd_h2c_failure = 0
 [21143.822599] mwifiex_sdio mmc0:0001:1: num_cmd_timeout = 1
 [21143.828113] mwifiex_sdio mmc0:0001:1: num_tx_timeout = 0
 [21143.833543] mwifiex_sdio mmc0:0001:1: last_cmd_index = 2
 [21143.838976] mwifiex_sdio mmc0:0001:1: last_cmd_resp_index = 1
 [21143.844862] mwifiex_sdio mmc0:0001:1: last_event_index = 1
 [21143.850479] mwifiex_sdio mmc0:0001:1: data_sent=0 cmd_sent=1
 [21143.856256] mwifiex_sdio mmc0:0001:1: ps_mode=1 ps_state=0

 }}}

--

-- 
Ticket URL: <http://dev.laptop.org/ticket/12094#comment:1>
One Laptop Per Child <http://laptop.org/>
OLPC bug tracking system

More information about the Bugs mailing list