Bug 742437

Summary: tg3 : kernel: unregister_netdevice: waiting for eth0 to become free. Usage count = 4
Product: Red Hat Enterprise Linux 6 Reporter: Weibing Zhang <atzhang>
Component: kernelAssignee: John Feeney <jfeeney>
Status: CLOSED DUPLICATE QA Contact: Red Hat Kernel QE team <kernel-qe>
Severity: high Docs Contact:
Priority: unspecified    
Version: 6.2CC: agospoda, aokuliar, emcnabb, jarod, kzhang
Target Milestone: rc   
Target Release: ---   
Hardware: All   
OS: Linux   
Whiteboard:
Fixed In Version: Doc Type: Bug Fix
Doc Text:
Story Points: ---
Clone Of: Environment:
Last Closed: 2011-10-17 09:13:40 UTC Type: ---
Regression: --- Mount Type: ---
Documentation: --- CRM:
Verified Versions: Category: ---
oVirt Team: --- RHEL 7.3 requirements from Atomic Host:
Cloudforms Team: --- Target Upstream Version:

Description Weibing Zhang 2011-09-30 06:23:07 UTC
Description of problem:
while run module_load test for tg3

unregister_netdevice: waiting for eth1 to become free. Usage count = 4 
Clocksource tsc unstable (delta = 14060839050 ns) 
Switching to clocksource acpi_pm 
File is still growing..  
File is still growing..  
SysRq : Show Blocked State 
  task                        PC stack   pid father 
modprobe      D 0000000000000006     0  2402   2269 0x00000080 
 ffff880834b0fc48 0000000000000086 0000000000000000 0000000000000001 
 ffffffff81b15804 0000000000000286 000000000001125d 0000000000000001 
 ffff8808338db038 ffff880834b0ffd8 000000000000f508 ffff8808338db038 
Call Trace: 
 [<ffffffff814ec962>] schedule_timeout+0x192/0x2e0 
 [<ffffffff8107c180>] ? process_timeout+0x0/0x10 
 [<ffffffff814ecace>] schedule_timeout_uninterruptible+0x1e/0x20 
 [<ffffffff8107d200>] msleep+0x20/0x30 
 [<ffffffff8142bfba>] netdev_run_todo+0xba/0x220 
 [<ffffffff81438ebe>] rtnl_unlock+0xe/0x10 
 [<ffffffff8142cac3>] unregister_netdev+0x23/0x30 
 [<ffffffffa031bada>] tg3_remove_one+0x93/0xea [tg3] 
 [<ffffffff8128a3d7>] pci_device_remove+0x37/0x70 
 [<ffffffff8134581f>] __device_release_driver+0x6f/0xe0 
 [<ffffffff81345958>] driver_detach+0xc8/0xd0 
 [<ffffffff813446be>] bus_remove_driver+0x8e/0x110 
 [<ffffffff81346122>] driver_unregister+0x62/0xa0 
 [<ffffffff8128a6e4>] pci_unregister_driver+0x44/0xb0 
 [<ffffffffa031b9fd>] tg3_cleanup+0x15/0x5f [tg3] 
 [<ffffffff810ac604>] sys_delete_module+0x194/0x260 
 [<ffffffff810d4602>] ? audit_syscall_entry+0x272/0x2a0 
 [<ffffffff814f15ae>] ? do_page_fault+0x3e/0xa0 
 [<ffffffff8100b0b2>] system_call_fastpath+0x16/0x1b 
Sched Debug Version: v0.09, 2.6.32-203.el6.x86_64 #1 
now at 3702662.316767 msecs 
  .jiffies                                 : 4298369965 
  .sysctl_sched_latency                    : 20.000000 
  .sysctl_sched_min_granularity            : 4.000000 
  .sysctl_sched_wakeup_granularity         : 4.000000 
  .sysctl_sched_child_runs_first           : 0.000000 
  .sysctl_sched_features                   : 3183 
  .sysctl_sched_tunable_scaling            : 1 (logaritmic) 
 
cpu#0, 2394.010 MHz 
  .nr_running                    : 1 
  .load                          : 1024 
  .nr_switches                   : 456520 
  .nr_load_updates               : 151070 
  .nr_uninterruptible            : 0 
  .next_balance                  : 4298.369004 
  .curr->pid                     : 31023 
  .clock                         : 3702543.546509 
  .cpu_load[0]                   : 0 
  .cpu_load[1]                   : 0 
  .cpu_load[2]                   : 0 
  .cpu_load[3]                   : 0 
  .cpu_load[4]                   : 0 
  .yld_count                     : 5 
  .sched_switch                  : 0 
  .sched_count                   : 462251 
  .sched_goidle                  : 210778 
  .avg_idle                      : 1000000 
  .ttwu_count                    : 245605 
  .ttwu_local                    : 17333 
  .bkl_count                     : 4131 
 
cfs_rq[0]:/ 
  .exec_clock                    : 29729.810252 
  .MIN_vruntime                  : 0.000001 
  .min_vruntime                  : 24736.207964 
  .max_vruntime                  : 0.000001 
  .spread                        : 0.000000 
  .spread0                       : 0.000000 
  .nr_spread_over                : 2 
  .nr_running                    : 1 
  .load                          : 1024 
  .load_avg                      : 0.000000 
  .load_period                   : 0.000000 
  .load_contrib                  : 0 
  .load_tg                       : 0 
 
runnable tasks: 
            task   PID         tree-key  switches  prio     exec-runtime         sum-exec        sum-sleep 
---------------------------------------------------------------------------------------------------------- 
Rrhts-system-inf 31023     24729.384009        40   120     24729.384009     16758.675718     13081.249131 / 
 
cpu#1, 2394.010 MHz 
  .nr_running                    : 0 
  .load                          : 0 
  .nr_switches                   : 326512 
  .nr_load_updates               : 185611 
  .nr_uninterruptible            : 0 
  .next_balance                  : 4298.370180 
  .curr->pid                     : 0 
  .clock                         : 3702889.077489 
  .cpu_load[0]                   : 0 
  .cpu_load[1]                   : 0 
  .cpu_load[2]                   : 0 
  .cpu_load[3]                   : 0 
  .cpu_load[4]                   : 0 
  .yld_count                     : 0 
  .sched_switch                  : 0 
  .sched_count                   : 327915 
  .sched_goidle                  : 134071 
  .avg_idle                      : 1000000 
  .ttwu_count                    : 164838 
  .ttwu_local                    : 63538 
  .bkl_count                     : 91 
 
cfs_rq[1]:/ 
  .exec_clock                    : 38983.548598 
  .MIN_vruntime                  : 0.000001 
  .min_vruntime                  : 132675.412678 
  .max_vruntime                  : 0.000001 
  .spread                        : 0.000000 
  .spread0                       : 107939.204714 
  .nr_spread_over                : 15 
  .nr_running                    : 0 
  .load                          : 0 
  .load_avg                      : 0.000000 
  .load_period                   : 0.000000 
  .load_contrib                  : 0 
  .load_tg                       : 0 
 
runnable tasks: 
            task   PID         tree-key  switches  prio     exec-runtime         sum-exec        sum-sleep 
---------------------------------------------------------------------------------------------------------- 
 
cpu#2, 2394.010 MHz 
  .nr_running                    : 0 
  .load                          : 0 
  .nr_switches                   : 196831 
  .nr_load_updates               : 107556 
  .nr_uninterruptible            : 0 
  .next_balance                  : 4298.368998 
  .curr->pid                     : 0 
  .clock                         : 3701698.113858 
  .cpu_load[0]                   : 0 
  .cpu_load[1]                   : 0 
  .cpu_load[2]                   : 0 
  .cpu_load[3]                   : 0 
  .cpu_load[4]                   : 0 
  .yld_count                     : 0 
  .sched_switch                  : 0 
  .sched_count                   : 198150 
  .sched_goidle                  : 80333 
  .avg_idle                      : 1000000 
  .ttwu_count                    : 110590 
  .ttwu_local                    : 7843 
  .bkl_count                     : 5 
 
cfs_rq[2]:/ 
  .exec_clock                    : 8441.305311 
  .MIN_vruntime                  : 0.000001 
  .min_vruntime                  : 35383.652686 
  .max_vruntime                  : 0.000001 
  .spread                        : 0.000000 
  .spread0                       : 10647.444722 
  .nr_spread_over                : 1 
  .nr_running                    : 0 
  .load                          : 0 
  .load_avg                      : 0.000000 
  .load_period                   : 0.000000 
  .load_contrib                  : 0 
  .load_tg                       : 0 
 
runnable tasks: 
            task   PID         tree-key  switches  prio     exec-runtime         sum-exec        sum-sleep 
---------------------------------------------------------------------------------------------------------- 
 
cpu#3, 2394.010 MHz 
  .nr_running                    : 0 
  .load                          : 0 
  .nr_switches                   : 78499 
  .nr_load_updates               : 81633 
  .nr_uninterruptible            : 0 
  .next_balance                  : 4298.368995 
  .curr->pid                     : 0 
  .clock                         : 3701695.118922 
  .cpu_load[0]                   : 0 
  .cpu_load[1]                   : 0 
  .cpu_load[2]                   : 0 
  .cpu_load[3]                   : 0 
  .cpu_load[4]                   : 0 
  .yld_count                     : 0 
  .sched_switch                  : 0 
  .sched_count                   : 79936 
  .sched_goidle                  : 22655 
  .avg_idle                      : 1000000 
  .ttwu_count                    : 51725 
  .ttwu_local                    : 4358 
  .bkl_count                     : 3 
 
cfs_rq[3]:/ 
  .exec_clock                    : 4755.223540 
  .MIN_vruntime                  : 0.000001 
  .min_vruntime                  : 4315.381415 
  .max_vruntime                  : 0.000001 
  .spread                        : 0.000000 
  .spread0                       : -20420.826549 
  .nr_spread_over                : 2 
  .nr_running                    : 0 
  .load                          : 0 
  .load_avg                      : 0.000000 
  .load_period                   : 0.000000 
  .load_contrib                  : 0 
  .load_tg                       : 0 
 
runnable tasks: 
            task   PID         tree-key  switches  prio     exec-runtime         sum-exec        sum-sleep 
---------------------------------------------------------------------------------------------------------- 
 
cpu#4, 2394.010 MHz 
  .nr_running                    : 0 
  .load                          : 0 
  .nr_switches                   : 154002 
  .nr_load_updates               : 173516 
  .nr_uninterruptible            : 0 
  .next_balance                  : 4298.368992 
  .curr->pid                     : 0 
  .clock                         : 3701692.104024 
  .cpu_load[0]                   : 0 
  .cpu_load[1]                   : 0 
  .cpu_load[2]                   : 0 
  .cpu_load[3]                   : 0 
  .cpu_load[4]                   : 0 
  .yld_count                     : 0 
  .sched_switch                  : 0 
  .sched_count                   : 155518 
  .sched_goidle                  : 67836 
  .avg_idle                      : 1000000 
  .ttwu_count                    : 86214 
  .ttwu_local                    : 24247 
  .bkl_count                     : 62 
 
cfs_rq[4]:/ 
  .exec_clock                    : 24383.610157 
  .MIN_vruntime                  : 0.000001 
  .min_vruntime                  : 28466.732689 
  .max_vruntime                  : 0.000001 
  .spread                        : 0.000000 
  .spread0                       : 3730.524725 
  .nr_spread_over                : 3 
  .nr_running                    : 0 
  .load                          : 0 
  .load_avg                      : 0.000000 
  .load_period                   : 0.000000 
  .load_contrib                  : 0 
  .load_tg                       : 0 
 
runnable tasks: 
            task   PID         tree-key  switches  prio     exec-runtime         sum-exec        sum-sleep 
---------------------------------------------------------------------------------------------------------- 
 
cpu#5, 2394.010 MHz 
  .nr_running                    : 0 
  .load                          : 0 
  .nr_switches                   : 153341 
  .nr_load_updates               : 178381 
  .nr_uninterruptible            : 0 
  .next_balance                  : 4298.369989 
  .curr->pid                     : 0 
  .clock                         : 3702689.054729 
  .cpu_load[0]                   : 0 
  .cpu_load[1]                   : 0 
  .cpu_load[2]                   : 0 
  .cpu_load[3]                   : 0 
  .cpu_load[4]                   : 0 
  .yld_count                     : 0 
  .sched_switch                  : 0 
  .sched_count                   : 154791 
  .sched_goidle                  : 60178 
  .avg_idle                      : 1000000 
  .ttwu_count                    : 67742 
  .ttwu_local                    : 29069 
  .bkl_count                     : 17 
 
cfs_rq[5]:/ 
  .exec_clock                    : 52186.946841 
  .MIN_vruntime                  : 0.000001 
  .min_vruntime                  : 214082.867150 
  .max_vruntime                  : 0.000001 
  .spread                        : 0.000000 
  .spread0                       : 189346.659186 
  .nr_spread_over                : 14 
  .nr_running                    : 0 
  .load                          : 0 
  .load_avg                      : 0.000000 
  .load_period                   : 0.000000 
  .load_contrib                  : 0 
  .load_tg                       : 0 
 
runnable tasks: 
            task   PID         tree-key  switches  prio     exec-runtime         sum-exec        sum-sleep 
---------------------------------------------------------------------------------------------------------- 
 
cpu#6, 2394.010 MHz 
  .nr_running                    : 0 
  .load                          : 0 
  .nr_switches                   : 87715 
  .nr_load_updates               : 127761 
  .nr_uninterruptible            : 0 
  .next_balance                  : 4298.370870 
  .curr->pid                     : 0 
  .clock                         : 3703571.080223 
  .cpu_load[0]                   : 0 
  .cpu_load[1]                   : 0 
  .cpu_load[2]                   : 0 
  .cpu_load[3]                   : 0 
  .cpu_load[4]                   : 0 
  .yld_count                     : 0 
  .sched_switch                  : 0 
  .sched_count                   : 89276 
  .sched_goidle                  : 35468 
  .avg_idle                      : 875819 
  .ttwu_count                    : 46799 
  .ttwu_local                    : 8440 
  .bkl_count                     : 20 
 
cfs_rq[6]:/ 
  .exec_clock                    : 18433.879426 
  .MIN_vruntime                  : 0.000001 
  .min_vruntime                  : 25504.771326 
  .max_vruntime                  : 0.000001 
  .spread                        : 0.000000 
  .spread0                       : 768.563362 
  .nr_spread_over                : 9 
  .nr_running                    : 0 
  .load                          : 0 
  .load_avg                      : 0.000000 
  .load_period                   : 0.000000 
  .load_contrib                  : 0 
  .load_tg                       : 0 
 
runnable tasks: 
            task   PID         tree-key  switches  prio     exec-runtime         sum-exec        sum-sleep 
---------------------------------------------------------------------------------------------------------- 
 
cpu#7, 2394.010 MHz 
  .nr_running                    : 0 
  .load                          : 0 
  .nr_switches                   : 44533 
  .nr_load_updates               : 45449 
  .nr_uninterruptible            : 0 
  .next_balance                  : 4298.370986 
  .curr->pid                     : 0 
  .clock                         : 3703683.132395 
  .cpu_load[0]                   : 0 
  .cpu_load[1]                   : 0 
  .cpu_load[2]                   : 0 
  .cpu_load[3]                   : 0 
  .cpu_load[4]                   : 0 
  .yld_count                     : 0 
  .sched_switch                  : 0 
  .sched_count                   : 45601 
  .sched_goidle                  : 14654 
  .avg_idle                      : 875609 
  .ttwu_count                    : 28657 
  .ttwu_local                    : 6286 
  .bkl_count                     : 34 
 
cfs_rq[7]:/ 
  .exec_clock                    : 10440.575293 
  .MIN_vruntime                  : 0.000001 
  .min_vruntime                  : 9876.526663 
  .max_vruntime                  : 0.000001 
  .spread                        : 0.000000 
  .spread0                       : -14859.681301 
  .nr_spread_over                : 1 
  .nr_running                    : 0 
  .load                          : 0 
  .load_avg                      : 0.000000 
  .load_period                   : 0.000000 
  .load_contrib                  : 0 
  .load_tg                       : 0 
 
runnable tasks: 
            task   PID         tree-key  switches  prio     exec-runtime         sum-exec        sum-sleep 
---------------------------------------------------------------------------------------------------------- 
 
File is still growing..  
unregister_netdevice: waiting for eth1 to become free. Usage count = 4 
File is still growing..  


Version-Release number of selected component (if applicable):


How reproducible:
frequently, but some time the test passed

Steps to Reproduce:
1. install rhel6.2-20110929.n.0
2. booting with kernel-2.6.32-203.el6
3. do "modprobe tg3; modeprobe -r tg3" for 100 times. 
  
Actual results:
get "unregister_netdevice: waiting for eth0 to become free. Usage count = 4" in console.

Expected results:
no error message

Additional info:

Comment 3 RHEL Program Management 2011-10-07 15:52:09 UTC
Since RHEL 6.2 External Beta has begun, and this bug remains
unresolved, it has been rejected as it is not proposed as
exception or blocker.

Red Hat invites you to ask your support representative to
propose this request, if appropriate and relevant, in the
next release of Red Hat Enterprise Linux.

Comment 4 Adam Okuliar 2011-10-10 09:25:23 UTC
*** Bug 744188 has been marked as a duplicate of this bug. ***

Comment 5 John Feeney 2011-10-14 21:11:51 UTC
Same as bz740477 which has just gone to POST state for 6.2.

Comment 6 Adam Okuliar 2011-10-17 09:13:40 UTC

*** This bug has been marked as a duplicate of bug 740477 ***