WARNING: at net/sched/sch_generic.c:256 dev_watchdog+0x1f2/0x200()

WARNING: at net/sched/sch_generic.c:256 dev_watchdog+0x1f2/0x200()

[Justin P. Mattock]

this showed up as I woke the machine up from suspend. machine is connected
I just see networkmanager constantly spinning trying to connect then telling me 
the wired network is not connected(even though its just wireless that I have).


[ 1584.693284] wlan0: authenticate with 00:1e:2a:62:6b:1e (try 1)
[ 1584.695279] wlan0: authenticated
[ 1584.699289] wlan0: associate with 00:1e:2a:62:6b:1e (try 1)
[ 1584.703037] wlan0: RX AssocResp from 00:1e:2a:62:6b:1e (capab=0x31 status=0 aid=5)
[ 1584.703043] wlan0: associated
[ 1584.703054] wlan0: moving STA 00:1e:2a:62:6b:1e to state 1
[ 1584.703058] wlan0: moving STA 00:1e:2a:62:6b:1e to state 2
[ 1584.722127] wlan0: moving STA 00:1e:2a:62:6b:1e to state 3
[ 2270.546555] ------------[ cut here ]------------
[ 2270.546572] WARNING: at net/sched/sch_generic.c:256 dev_watchdog+0x1f2/0x200()
[ 2270.546576] Hardware name: 0914                            
[ 2270.546580] NETDEV WATCHDOG: eth0 (r8169): transmit queue 0 timed out
[ 2270.546584] Modules linked in: parport_pc ppdev ipt_REJECT ipt_LOG xt_limit xt_tcpudp dm_crypt joydev xt_addrtype arc4 ath9k snd_hda_codec_hdmi snd_hda_codec_conexant mac80211 snd_hda_intel snd_hda_codec snd_hwdep snd_pcm xt_state snd_seq_midi snd_rawmidi nf_conntrack_netbios_ns snd_seq_midi_event nf_conntrack_broadcast snd_seq nf_nat_ftp nf_nat ath9k_common ath9k_hw nf_conntrack_ipv4 nf_defrag_ipv4 uvcvideo ath snd_timer snd_seq_device snd nf_conntrack_ftp videobuf2_core cfg80211 nf_conntrack iptable_filter mei(C) ip_tables intel_ips ideapad_laptop sparse_keymap psmouse lp parport soundcore snd_page_alloc videodev videobuf2_vmalloc x_tables wmi serio_raw videobuf2_memops i915 drm_kms_helper r8169 drm i2c_algo_bit usbhid hid video
[ 2270.546701] Pid: 0, comm: swapper/1 Tainted: G         C   3.3.0-rc2-00172-g23783f8 #1
[ 2270.546705] Call Trace:
[ 2270.546716]  [<c1042d02>] warn_slowpath_common+0x72/0xa0
[ 2270.546724]  [<c14d59d2>] ? dev_watchdog+0x1f2/0x200
[ 2270.546730]  [<c14d59d2>] ? dev_watchdog+0x1f2/0x200
[ 2270.546735]  [<c1042dd3>] warn_slowpath_fmt+0x33/0x40
[ 2270.546742]  [<c14d59d2>] dev_watchdog+0x1f2/0x200
[ 2270.546760]  [<c1053c52>] run_timer_softirq+0x132/0x520
[ 2270.546766]  [<c1053bd8>] ? run_timer_softirq+0xb8/0x520
[ 2270.546774]  [<c10d0f5c>] ? __rcu_process_callbacks+0x4c/0x3c0
[ 2270.546780]  [<c14d57e0>] ? pfifo_fast_init+0x60/0x60
[ 2270.546789]  [<c104ace7>] __do_softirq+0x97/0x340
[ 2270.546796]  [<c104ac50>] ? __tasklet_hi_schedule_first+0x120/0x120
[ 2270.546799]  <IRQ>  [<c104b236>] ? irq_exit+0x86/0xb0
[ 2270.546813]  [<c158fef9>] ? smp_apic_timer_interrupt+0x59/0x88
[ 2270.546822]  [<c12c1028>] ? trace_hardirqs_off_thunk+0xc/0x14
[ 2270.546828]  [<c1588662>] ? apic_timer_interrupt+0x36/0x3c
[ 2270.546836]  [<c10a007b>] ? print_kernel_ident+0x4b/0x50
[ 2270.546844]  [<c1308987>] ? intel_idle+0xd7/0x130
[ 2270.546853]  [<c14773f0>] ? cpuidle_idle_call+0xb0/0x500
[ 2270.546859]  [<c10a48f4>] ? trace_hardirqs_on_caller+0xf4/0x180
[ 2270.546868]  [<c1014a85>] ? cpu_idle+0xa5/0xf0
[ 2270.546874]  [<c1579545>] ? start_secondary+0x21c/0x222
[ 2270.546879] ---[ end trace 22be394e4d8a72e0 ]---
[ 2270.617566] r8169 0000:06:00.0: eth0: link up693284] wlan0: authenticate with 00:1e:2a:62:6b:1e (try 1)
[ 1584.695279] wlan0: authenticated
[ 1584.699289] wlan0: associate with 00:1e:2a:62:6b:1e (try 1)
[ 1584.703037] wlan0: RX AssocResp from 00:1e:2a:62:6b:1e (capab=0x31 status=0 aid=5)
[ 1584.703043] wlan0: associated
[ 1584.703054] wlan0: moving STA 00:1e:2a:62:6b:1e to state 1
[ 1584.703058] wlan0: moving STA 00:1e:2a:62:6b:1e to state 2
[ 1584.722127] wlan0: moving STA 00:1e:2a:62:6b:1e to state 3
[ 2270.546555] ------------[ cut here ]------------
[ 2270.546572] WARNING: at net/sched/sch_generic.c:256 dev_watchdog+0x1f2/0x200()
[ 2270.546576] Hardware name: 0914                            
[ 2270.546580] NETDEV WATCHDOG: eth0 (r8169): transmit queue 0 timed out
[ 2270.546584] Modules linked in: parport_pc ppdev ipt_REJECT ipt_LOG xt_limit xt_tcpudp dm_crypt joydev xt_addrtype arc4 ath9k snd_hda_codec_hdmi snd_hda_codec_conexant mac80211 snd_hda_intel snd_hda_codec snd_hwdep snd_pcm xt_state snd_seq_midi snd_rawmidi nf_conntrack_netbios_ns snd_seq_midi_event nf_conntrack_broadcast snd_seq nf_nat_ftp nf_nat ath9k_common ath9k_hw nf_conntrack_ipv4 nf_defrag_ipv4 uvcvideo ath snd_timer snd_seq_device snd nf_conntrack_ftp videobuf2_core cfg80211 nf_conntrack iptable_filter mei(C) ip_tables intel_ips ideapad_laptop sparse_keymap psmouse lp parport soundcore snd_page_alloc videodev videobuf2_vmalloc x_tables wmi serio_raw videobuf2_memops i915 drm_kms_helper r8169 drm i2c_algo_bit usbhid hid video
[ 2270.546701] Pid: 0, comm: swapper/1 Tainted: G         C   3.3.0-rc2-00172-g23783f8 #1
[ 2270.546705] Call Trace:
[ 2270.546716]  [<c1042d02>] warn_slowpath_common+0x72/0xa0
[ 2270.546724]  [<c14d59d2>] ? dev_watchdog+0x1f2/0x200
[ 2270.546730]  [<c14d59d2>] ? dev_watchdog+0x1f2/0x200
[ 2270.546735]  [<c1042dd3>] warn_slowpath_fmt+0x33/0x40
[ 2270.546742]  [<c14d59d2>] dev_watchdog+0x1f2/0x200
[ 2270.546760]  [<c1053c52>] run_timer_softirq+0x132/0x520
[ 2270.546766]  [<c1053bd8>] ? run_timer_softirq+0xb8/0x520
[ 2270.546774]  [<c10d0f5c>] ? __rcu_process_callbacks+0x4c/0x3c0
[ 2270.546780]  [<c14d57e0>] ? pfifo_fast_init+0x60/0x60
[ 2270.546789]  [<c104ace7>] __do_softirq+0x97/0x340
[ 2270.546796]  [<c104ac50>] ? __tasklet_hi_schedule_first+0x120/0x120
[ 2270.546799]  <IRQ>  [<c104b236>] ? irq_exit+0x86/0xb0
[ 2270.546813]  [<c158fef9>] ? smp_apic_timer_interrupt+0x59/0x88
[ 2270.546822]  [<c12c1028>] ? trace_hardirqs_off_thunk+0xc/0x14
[ 2270.546828]  [<c1588662>] ? apic_timer_interrupt+0x36/0x3c
[ 2270.546836]  [<c10a007b>] ? print_kernel_ident+0x4b/0x50
[ 2270.546844]  [<c1308987>] ? intel_idle+0xd7/0x130
[ 2270.546853]  [<c14773f0>] ? cpuidle_idle_call+0xb0/0x500
[ 2270.546859]  [<c10a48f4>] ? trace_hardirqs_on_caller+0xf4/0x180
[ 2270.546868]  [<c1014a85>] ? cpu_idle+0xa5/0xf0
[ 2270.546874]  [<c1579545>] ? start_secondary+0x21c/0x222
[ 2270.546879] ---[ end trace 22be394e4d8a72e0 ]---
[ 2270.617566] r8169 0000:06:00.0: eth0: link up

full dmesg is here..:
http://fpaste.org/007p/

-- 
Justin P. Mattock <justinmattock@gmail.com>

Re: WARNING: at net/sched/sch_generic.c:256 dev_watchdog+0x1f2/0x200()

[Larry Finger]

On 02/05/2012 03:40 PM, Justin P. Mattock wrote:
>
> this showed up as I woke the machine up from suspend. machine is connected
> I just see networkmanager constantly spinning trying to connect then telling me
> the wired network is not connected(even though its just wireless that I have).
>
>
> [ 1584.693284] wlan0: authenticate with 00:1e:2a:62:6b:1e (try 1)
> [ 1584.695279] wlan0: authenticated
> [ 1584.699289] wlan0: associate with 00:1e:2a:62:6b:1e (try 1)
> [ 1584.703037] wlan0: RX AssocResp from 00:1e:2a:62:6b:1e (capab=0x31 status=0 aid=5)
> [ 1584.703043] wlan0: associated
> [ 1584.703054] wlan0: moving STA 00:1e:2a:62:6b:1e to state 1
> [ 1584.703058] wlan0: moving STA 00:1e:2a:62:6b:1e to state 2
> [ 1584.722127] wlan0: moving STA 00:1e:2a:62:6b:1e to state 3
> [ 2270.546555] ------------[ cut here ]------------
> [ 2270.546572] WARNING: at net/sched/sch_generic.c:256 dev_watchdog+0x1f2/0x200()
> [ 2270.546576] Hardware name: 0914
> [ 2270.546580] NETDEV WATCHDOG: eth0 (r8169): transmit queue 0 timed out
> [ 2270.546584] Modules linked in: parport_pc ppdev ipt_REJECT ipt_LOG xt_limit xt_tcpudp dm_crypt joydev xt_addrtype arc4 ath9k snd_hda_codec_hdmi snd_hda_codec_conexant mac80211 snd_hda_intel snd_hda_codec snd_hwdep snd_pcm xt_state snd_seq_midi snd_rawmidi nf_conntrack_netbios_ns snd_seq_midi_event nf_conntrack_broadcast snd_seq nf_nat_ftp nf_nat ath9k_common ath9k_hw nf_conntrack_ipv4 nf_defrag_ipv4 uvcvideo ath snd_timer snd_seq_device snd nf_conntrack_ftp videobuf2_core cfg80211 nf_conntrack iptable_filter mei(C) ip_tables intel_ips ideapad_laptop sparse_keymap psmouse lp parport soundcore snd_page_alloc videodev videobuf2_vmalloc x_tables wmi serio_raw videobuf2_memops i915 drm_kms_helper r8169 drm i2c_algo_bit usbhid hid video
> [ 2270.546701] Pid: 0, comm: swapper/1 Tainted: G         C   3.3.0-rc2-00172-g23783f8 #1
> [ 2270.546705] Call Trace:
> [ 2270.546716]  [<c1042d02>] warn_slowpath_common+0x72/0xa0
> [ 2270.546724]  [<c14d59d2>] ? dev_watchdog+0x1f2/0x200
> [ 2270.546730]  [<c14d59d2>] ? dev_watchdog+0x1f2/0x200
> [ 2270.546735]  [<c1042dd3>] warn_slowpath_fmt+0x33/0x40
> [ 2270.546742]  [<c14d59d2>] dev_watchdog+0x1f2/0x200
> [ 2270.546760]  [<c1053c52>] run_timer_softirq+0x132/0x520
> [ 2270.546766]  [<c1053bd8>] ? run_timer_softirq+0xb8/0x520
> [ 2270.546774]  [<c10d0f5c>] ? __rcu_process_callbacks+0x4c/0x3c0
> [ 2270.546780]  [<c14d57e0>] ? pfifo_fast_init+0x60/0x60
> [ 2270.546789]  [<c104ace7>] __do_softirq+0x97/0x340
> [ 2270.546796]  [<c104ac50>] ? __tasklet_hi_schedule_first+0x120/0x120
> [ 2270.546799]<IRQ>   [<c104b236>] ? irq_exit+0x86/0xb0
> [ 2270.546813]  [<c158fef9>] ? smp_apic_timer_interrupt+0x59/0x88
> [ 2270.546822]  [<c12c1028>] ? trace_hardirqs_off_thunk+0xc/0x14
> [ 2270.546828]  [<c1588662>] ? apic_timer_interrupt+0x36/0x3c
> [ 2270.546836]  [<c10a007b>] ? print_kernel_ident+0x4b/0x50
> [ 2270.546844]  [<c1308987>] ? intel_idle+0xd7/0x130
> [ 2270.546853]  [<c14773f0>] ? cpuidle_idle_call+0xb0/0x500
> [ 2270.546859]  [<c10a48f4>] ? trace_hardirqs_on_caller+0xf4/0x180
> [ 2270.546868]  [<c1014a85>] ? cpu_idle+0xa5/0xf0
> [ 2270.546874]  [<c1579545>] ? start_secondary+0x21c/0x222
> [ 2270.546879] ---[ end trace 22be394e4d8a72e0 ]---
> [ 2270.617566] r8169 0000:06:00.0: eth0: link up693284] wlan0: authenticate with 00:1e:2a:62:6b:1e (try 1)
> [ 1584.695279] wlan0: authenticated
> [ 1584.699289] wlan0: associate with 00:1e:2a:62:6b:1e (try 1)
> [ 1584.703037] wlan0: RX AssocResp from 00:1e:2a:62:6b:1e (capab=0x31 status=0 aid=5)
> [ 1584.703043] wlan0: associated
> [ 1584.703054] wlan0: moving STA 00:1e:2a:62:6b:1e to state 1
> [ 1584.703058] wlan0: moving STA 00:1e:2a:62:6b:1e to state 2
> [ 1584.722127] wlan0: moving STA 00:1e:2a:62:6b:1e to state 3
> [ 2270.546555] ------------[ cut here ]------------
> [ 2270.546572] WARNING: at net/sched/sch_generic.c:256 dev_watchdog+0x1f2/0x200()
> [ 2270.546576] Hardware name: 0914
> [ 2270.546580] NETDEV WATCHDOG: eth0 (r8169): transmit queue 0 timed out
> [ 2270.546584] Modules linked in: parport_pc ppdev ipt_REJECT ipt_LOG xt_limit xt_tcpudp dm_crypt joydev xt_addrtype arc4 ath9k snd_hda_codec_hdmi snd_hda_codec_conexant mac80211 snd_hda_intel snd_hda_codec snd_hwdep snd_pcm xt_state snd_seq_midi snd_rawmidi nf_conntrack_netbios_ns snd_seq_midi_event nf_conntrack_broadcast snd_seq nf_nat_ftp nf_nat ath9k_common ath9k_hw nf_conntrack_ipv4 nf_defrag_ipv4 uvcvideo ath snd_timer snd_seq_device snd nf_conntrack_ftp videobuf2_core cfg80211 nf_conntrack iptable_filter mei(C) ip_tables intel_ips ideapad_laptop sparse_keymap psmouse lp parport soundcore snd_page_alloc videodev videobuf2_vmalloc x_tables wmi serio_raw videobuf2_memops i915 drm_kms_helper r8169 drm i2c_algo_bit usbhid hid video
> [ 2270.546701] Pid: 0, comm: swapper/1 Tainted: G         C   3.3.0-rc2-00172-g23783f8 #1
> [ 2270.546705] Call Trace:
> [ 2270.546716]  [<c1042d02>] warn_slowpath_common+0x72/0xa0
> [ 2270.546724]  [<c14d59d2>] ? dev_watchdog+0x1f2/0x200
> [ 2270.546730]  [<c14d59d2>] ? dev_watchdog+0x1f2/0x200
> [ 2270.546735]  [<c1042dd3>] warn_slowpath_fmt+0x33/0x40
> [ 2270.546742]  [<c14d59d2>] dev_watchdog+0x1f2/0x200
> [ 2270.546760]  [<c1053c52>] run_timer_softirq+0x132/0x520
> [ 2270.546766]  [<c1053bd8>] ? run_timer_softirq+0xb8/0x520
> [ 2270.546774]  [<c10d0f5c>] ? __rcu_process_callbacks+0x4c/0x3c0
> [ 2270.546780]  [<c14d57e0>] ? pfifo_fast_init+0x60/0x60
> [ 2270.546789]  [<c104ace7>] __do_softirq+0x97/0x340
> [ 2270.546796]  [<c104ac50>] ? __tasklet_hi_schedule_first+0x120/0x120
> [ 2270.546799]<IRQ>   [<c104b236>] ? irq_exit+0x86/0xb0
> [ 2270.546813]  [<c158fef9>] ? smp_apic_timer_interrupt+0x59/0x88
> [ 2270.546822]  [<c12c1028>] ? trace_hardirqs_off_thunk+0xc/0x14
> [ 2270.546828]  [<c1588662>] ? apic_timer_interrupt+0x36/0x3c
> [ 2270.546836]  [<c10a007b>] ? print_kernel_ident+0x4b/0x50
> [ 2270.546844]  [<c1308987>] ? intel_idle+0xd7/0x130
> [ 2270.546853]  [<c14773f0>] ? cpuidle_idle_call+0xb0/0x500
> [ 2270.546859]  [<c10a48f4>] ? trace_hardirqs_on_caller+0xf4/0x180
> [ 2270.546868]  [<c1014a85>] ? cpu_idle+0xa5/0xf0
> [ 2270.546874]  [<c1579545>] ? start_secondary+0x21c/0x222
> [ 2270.546879] ---[ end trace 22be394e4d8a72e0 ]---
> [ 2270.617566] r8169 0000:06:00.0: eth0: link up
>
> full dmesg is here..:
> http://fpaste.org/007p/

The error clearly comes from r8169, not from ath9k. If you unload r8169 before 
suspending, does this still happen? My suspicion is that NM is getting confused.

Larry

P.S. Unless it happens with just the ath9k active, this ML is likely not the 
correct one.

Re: WARNING: at net/sched/sch_generic.c:256 dev_watchdog+0x1f2/0x200()

[Francois Romieu]

Larry Finger <Larry.Finger@lwfinger.net> :
> On 02/05/2012 03:40 PM, Justin P. Mattock wrote:
> >
> >this showed up as I woke the machine up from suspend. machine is connected
> >I just see networkmanager constantly spinning trying to connect then telling me
> >the wired network is not connected(even though its just wireless that I have).

[ 1273.447349] r8169 0000:06:00.0: Refused to change power state, currently in D3
[ 1273.571024] psmouse serio1: elantech: retrying ps2 command 0xf8 (2).
[ 1273.656709] r8169 0000:06:00.0: eth0: link up
                                         ^^^^^^^

Justin, you did not ever connect a cable, did you ?

[...]
> The error clearly comes from r8169, not from ath9k. If you unload
> r8169 before suspending, does this still happen? My suspicion is
> that NM is getting confused.

It could hide the problem but the D3 + link up messages are nonetheless
a bit unexpected.

> P.S. Unless it happens with just the ath9k active, this ML is likely
> not the correct one.

Yes, please Cc: netdev@vger.kernel.org + me instead.

Thanks.

-- 
Ueimor

Re: WARNING: at net/sched/sch_generic.c:256 dev_watchdog+0x1f2/0x200()

[Justin P. Mattock]

On Sun, 05 Feb 2012 16:19:17 -0600
Larry Finger <Larry.Finger@lwfinger.net> wrote:

> On 02/05/2012 03:40 PM, Justin P. Mattock wrote:
> >
> > this showed up as I woke the machine up from suspend. machine is connected
> > I just see networkmanager constantly spinning trying to connect then telling me
> > the wired network is not connected(even though its just wireless that I have).
> >
> >
> > [ 1584.693284] wlan0: authenticate with 00:1e:2a:62:6b:1e (try 1)
> > [ 1584.695279] wlan0: authenticated
> > [ 1584.699289] wlan0: associate with 00:1e:2a:62:6b:1e (try 1)
> > [ 1584.703037] wlan0: RX AssocResp from 00:1e:2a:62:6b:1e (capab=0x31 status=0 aid=5)
> > [ 1584.703043] wlan0: associated
> > [ 1584.703054] wlan0: moving STA 00:1e:2a:62:6b:1e to state 1
> > [ 1584.703058] wlan0: moving STA 00:1e:2a:62:6b:1e to state 2
> > [ 1584.722127] wlan0: moving STA 00:1e:2a:62:6b:1e to state 3
> > [ 2270.546555] ------------[ cut here ]------------
> > [ 2270.546572] WARNING: at net/sched/sch_generic.c:256 dev_watchdog+0x1f2/0x200()
> > [ 2270.546576] Hardware name: 0914
> > [ 2270.546580] NETDEV WATCHDOG: eth0 (r8169): transmit queue 0 timed out
> > [ 2270.546584] Modules linked in: parport_pc ppdev ipt_REJECT ipt_LOG xt_limit xt_tcpudp dm_crypt joydev xt_addrtype arc4 ath9k snd_hda_codec_hdmi snd_hda_codec_conexant mac80211 snd_hda_intel snd_hda_codec snd_hwdep snd_pcm xt_state snd_seq_midi snd_rawmidi nf_conntrack_netbios_ns snd_seq_midi_event nf_conntrack_broadcast snd_seq nf_nat_ftp nf_nat ath9k_common ath9k_hw nf_conntrack_ipv4 nf_defrag_ipv4 uvcvideo ath snd_timer snd_seq_device snd nf_conntrack_ftp videobuf2_core cfg80211 nf_conntrack iptable_filter mei(C) ip_tables intel_ips ideapad_laptop sparse_keymap psmouse lp parport soundcore snd_page_alloc videodev videobuf2_vmalloc x_tables wmi serio_raw videobuf2_memops i915 drm_kms_helper r8169 drm i2c_algo_bit usbhid hid video
> > [ 2270.546701] Pid: 0, comm: swapper/1 Tainted: G         C   3.3.0-rc2-00172-g23783f8 #1
> > [ 2270.546705] Call Trace:
> > [ 2270.546716]  [<c1042d02>] warn_slowpath_common+0x72/0xa0
> > [ 2270.546724]  [<c14d59d2>] ? dev_watchdog+0x1f2/0x200
> > [ 2270.546730]  [<c14d59d2>] ? dev_watchdog+0x1f2/0x200
> > [ 2270.546735]  [<c1042dd3>] warn_slowpath_fmt+0x33/0x40
> > [ 2270.546742]  [<c14d59d2>] dev_watchdog+0x1f2/0x200
> > [ 2270.546760]  [<c1053c52>] run_timer_softirq+0x132/0x520
> > [ 2270.546766]  [<c1053bd8>] ? run_timer_softirq+0xb8/0x520
> > [ 2270.546774]  [<c10d0f5c>] ? __rcu_process_callbacks+0x4c/0x3c0
> > [ 2270.546780]  [<c14d57e0>] ? pfifo_fast_init+0x60/0x60
> > [ 2270.546789]  [<c104ace7>] __do_softirq+0x97/0x340
> > [ 2270.546796]  [<c104ac50>] ? __tasklet_hi_schedule_first+0x120/0x120
> > [ 2270.546799]<IRQ>   [<c104b236>] ? irq_exit+0x86/0xb0
> > [ 2270.546813]  [<c158fef9>] ? smp_apic_timer_interrupt+0x59/0x88
> > [ 2270.546822]  [<c12c1028>] ? trace_hardirqs_off_thunk+0xc/0x14
> > [ 2270.546828]  [<c1588662>] ? apic_timer_interrupt+0x36/0x3c
> > [ 2270.546836]  [<c10a007b>] ? print_kernel_ident+0x4b/0x50
> > [ 2270.546844]  [<c1308987>] ? intel_idle+0xd7/0x130
> > [ 2270.546853]  [<c14773f0>] ? cpuidle_idle_call+0xb0/0x500
> > [ 2270.546859]  [<c10a48f4>] ? trace_hardirqs_on_caller+0xf4/0x180
> > [ 2270.546868]  [<c1014a85>] ? cpu_idle+0xa5/0xf0
> > [ 2270.546874]  [<c1579545>] ? start_secondary+0x21c/0x222
> > [ 2270.546879] ---[ end trace 22be394e4d8a72e0 ]---
> > [ 2270.617566] r8169 0000:06:00.0: eth0: link up693284] wlan0: authenticate with 00:1e:2a:62:6b:1e (try 1)
> > [ 1584.695279] wlan0: authenticated
> > [ 1584.699289] wlan0: associate with 00:1e:2a:62:6b:1e (try 1)
> > [ 1584.703037] wlan0: RX AssocResp from 00:1e:2a:62:6b:1e (capab=0x31 status=0 aid=5)
> > [ 1584.703043] wlan0: associated
> > [ 1584.703054] wlan0: moving STA 00:1e:2a:62:6b:1e to state 1
> > [ 1584.703058] wlan0: moving STA 00:1e:2a:62:6b:1e to state 2
> > [ 1584.722127] wlan0: moving STA 00:1e:2a:62:6b:1e to state 3
> > [ 2270.546555] ------------[ cut here ]------------
> > [ 2270.546572] WARNING: at net/sched/sch_generic.c:256 dev_watchdog+0x1f2/0x200()
> > [ 2270.546576] Hardware name: 0914
> > [ 2270.546580] NETDEV WATCHDOG: eth0 (r8169): transmit queue 0 timed out
> > [ 2270.546584] Modules linked in: parport_pc ppdev ipt_REJECT ipt_LOG xt_limit xt_tcpudp dm_crypt joydev xt_addrtype arc4 ath9k snd_hda_codec_hdmi snd_hda_codec_conexant mac80211 snd_hda_intel snd_hda_codec snd_hwdep snd_pcm xt_state snd_seq_midi snd_rawmidi nf_conntrack_netbios_ns snd_seq_midi_event nf_conntrack_broadcast snd_seq nf_nat_ftp nf_nat ath9k_common ath9k_hw nf_conntrack_ipv4 nf_defrag_ipv4 uvcvideo ath snd_timer snd_seq_device snd nf_conntrack_ftp videobuf2_core cfg80211 nf_conntrack iptable_filter mei(C) ip_tables intel_ips ideapad_laptop sparse_keymap psmouse lp parport soundcore snd_page_alloc videodev videobuf2_vmalloc x_tables wmi serio_raw videobuf2_memops i915 drm_kms_helper r8169 drm i2c_algo_bit usbhid hid video
> > [ 2270.546701] Pid: 0, comm: swapper/1 Tainted: G         C   3.3.0-rc2-00172-g23783f8 #1
> > [ 2270.546705] Call Trace:
> > [ 2270.546716]  [<c1042d02>] warn_slowpath_common+0x72/0xa0
> > [ 2270.546724]  [<c14d59d2>] ? dev_watchdog+0x1f2/0x200
> > [ 2270.546730]  [<c14d59d2>] ? dev_watchdog+0x1f2/0x200
> > [ 2270.546735]  [<c1042dd3>] warn_slowpath_fmt+0x33/0x40
> > [ 2270.546742]  [<c14d59d2>] dev_watchdog+0x1f2/0x200
> > [ 2270.546760]  [<c1053c52>] run_timer_softirq+0x132/0x520
> > [ 2270.546766]  [<c1053bd8>] ? run_timer_softirq+0xb8/0x520
> > [ 2270.546774]  [<c10d0f5c>] ? __rcu_process_callbacks+0x4c/0x3c0
> > [ 2270.546780]  [<c14d57e0>] ? pfifo_fast_init+0x60/0x60
> > [ 2270.546789]  [<c104ace7>] __do_softirq+0x97/0x340
> > [ 2270.546796]  [<c104ac50>] ? __tasklet_hi_schedule_first+0x120/0x120
> > [ 2270.546799]<IRQ>   [<c104b236>] ? irq_exit+0x86/0xb0
> > [ 2270.546813]  [<c158fef9>] ? smp_apic_timer_interrupt+0x59/0x88
> > [ 2270.546822]  [<c12c1028>] ? trace_hardirqs_off_thunk+0xc/0x14
> > [ 2270.546828]  [<c1588662>] ? apic_timer_interrupt+0x36/0x3c
> > [ 2270.546836]  [<c10a007b>] ? print_kernel_ident+0x4b/0x50
> > [ 2270.546844]  [<c1308987>] ? intel_idle+0xd7/0x130
> > [ 2270.546853]  [<c14773f0>] ? cpuidle_idle_call+0xb0/0x500
> > [ 2270.546859]  [<c10a48f4>] ? trace_hardirqs_on_caller+0xf4/0x180
> > [ 2270.546868]  [<c1014a85>] ? cpu_idle+0xa5/0xf0
> > [ 2270.546874]  [<c1579545>] ? start_secondary+0x21c/0x222
> > [ 2270.546879] ---[ end trace 22be394e4d8a72e0 ]---
> > [ 2270.617566] r8169 0000:06:00.0: eth0: link up
> >
> > full dmesg is here..:
> > http://fpaste.org/007p/
> 
> The error clearly comes from r8169, not from ath9k. If you unload r8169 before 
> suspending, does this still happen? My suspicion is that NM is getting confused.
> 
> Larry
> 
> P.S. Unless it happens with just the ath9k active, this ML is likely not the 
> correct one.
as a quick test I unloaded r8169 and did not see this message, but I also added it in and suspended
and no warning message as well. I will keep an eye out for this and see if I can reproduce this.
(if so a bisect should not take to long) as for lists I can go and remove linux-wireless

-- 
Justin P. Mattock <justinmattock@gmail.com>

Re: WARNING: at net/sched/sch_generic.c:256 dev_watchdog+0x1f2/0x200()

[Dave Jones]

On Sun, Feb 05, 2012 at 02:29:46PM -0800, Justin P. Mattock wrote:
 
 > > > [ 2270.546572] WARNING: at net/sched/sch_generic.c:256 dev_watchdog+0x1f2/0x200()
 > > > [ 2270.546576] Hardware name: 0914
 > > > [ 2270.546580] NETDEV WATCHDOG: eth0 (r8169): transmit queue 0 timed out
 > > 
 > > The error clearly comes from r8169, not from ath9k. If you unload r8169 before 
 > > suspending, does this still happen? My suspicion is that NM is getting confused.
 > > 
 > as a quick test I unloaded r8169 and did not see this message, but I also added it in and suspended
 > and no warning message as well.

FWIW, we've been seeing reports of this in Fedora since last summer.
https://bugzilla.redhat.com/show_bug.cgi?id=715137

(We have reports on other chips too, but this bug is just the r8169 reports)

	Dave

Re: WARNING: at net/sched/sch_generic.c:256 dev_watchdog+0x1f2/0x200()

[Justin P. Mattock]

On Sun, 5 Feb 2012 23:21:41 +0100
Francois Romieu <romieu@fr.zoreil.com> wrote:

> Larry Finger <Larry.Finger@lwfinger.net> :
> > On 02/05/2012 03:40 PM, Justin P. Mattock wrote:
> > >
> > >this showed up as I woke the machine up from suspend. machine is connected
> > >I just see networkmanager constantly spinning trying to connect then telling me
> > >the wired network is not connected(even though its just wireless that I have).
> 
> [ 1273.447349] r8169 0000:06:00.0: Refused to change power state, currently in D3
> [ 1273.571024] psmouse serio1: elantech: retrying ps2 command 0xf8 (2).
> [ 1273.656709] r8169 0000:06:00.0: eth0: link up
>                                          ^^^^^^^
> 
> Justin, you did not ever connect a cable, did you ?

nope! was using wireless the whole time. 

> 
> [...]
> > The error clearly comes from r8169, not from ath9k. If you unload
> > r8169 before suspending, does this still happen? My suspicion is
> > that NM is getting confused.
> 
> It could hide the problem but the D3 + link up messages are nonetheless
> a bit unexpected.
> 
> > P.S. Unless it happens with just the ath9k active, this ML is likely
> > not the correct one.
> 
> Yes, please Cc: netdev@vger.kernel.org + me instead.
> 
> Thanks.
> 
> -- 
> Ueimor

removed linux-wireless and added netdev to the cc's
will monitor this and report anything that shows up.

-- 
Justin P. Mattock <justinmattock@gmail.com>

Re: WARNING: at net/sched/sch_generic.c:256 dev_watchdog+0x1f2/0x200()

[Justin Mattock]

On Sun, Feb 5, 2012 at 2:59 PM, Justin P. Mattock
<justinmattock@gmail.com> wrote:
> On Sun, 5 Feb 2012 23:21:41 +0100
> Francois Romieu <romieu@fr.zoreil.com> wrote:
>
>> Larry Finger <Larry.Finger@lwfinger.net> :
>> > On 02/05/2012 03:40 PM, Justin P. Mattock wrote:
>> > >
>> > >this showed up as I woke the machine up from suspend. machine is connected
>> > >I just see networkmanager constantly spinning trying to connect then telling me
>> > >the wired network is not connected(even though its just wireless that I have).
>>
>> [ 1273.447349] r8169 0000:06:00.0: Refused to change power state, currently in D3
>> [ 1273.571024] psmouse serio1: elantech: retrying ps2 command 0xf8 (2).
>> [ 1273.656709] r8169 0000:06:00.0: eth0: link up
>>                                          ^^^^^^^
>>
>> Justin, you did not ever connect a cable, did you ?
>
> nope! was using wireless the whole time.
>
>>
>> [...]
>> > The error clearly comes from r8169, not from ath9k. If you unload
>> > r8169 before suspending, does this still happen? My suspicion is
>> > that NM is getting confused.
>>
>> It could hide the problem but the D3 + link up messages are nonetheless
>> a bit unexpected.
>>
>> > P.S. Unless it happens with just the ath9k active, this ML is likely
>> > not the correct one.
>>
>> Yes, please Cc: netdev@vger.kernel.org + me instead.
>>
>> Thanks.
>>
>> --
>> Ueimor
>
> removed linux-wireless and added netdev to the cc's
> will monitor this and report anything that shows up.
>
> --
> Justin P. Mattock <justinmattock@gmail.com>

seems I see this with the latest linux-next:

[21731.149774] wlan0: associate with 00:1e:2a:62:6b:1e (try 1/3)
[21731.152858] wlan0: RX AssocResp from 00:1e:2a:62:6b:1e (capab=0x31
status=0 aid=7)
[21731.152867] wlan0: associated
[21740.306348] ------------[ cut here ]------------
[21740.306363] WARNING: at net/sched/sch_generic.c:256
dev_watchdog+0x1f2/0x200()
[21740.306367] Hardware name: 0914
[21740.306370] NETDEV WATCHDOG: eth0 (r8169): transmit queue 0 timed out
[21740.306374] Modules linked in: dm_crypt xt_limit xt_tcpudp
xt_addrtype snd_hda_codec_hdmi xt_state joydev arc4
snd_hda_codec_conexant ath9k nf_conntrack_netbios_ns
nf_conntrack_broadcast snd_hda_intel snd_hda_codec mac80211 nf_nat_ftp
nf_nat snd_hwdep nf_conntrack_ipv4 nf_defrag_ipv4 snd_pcm
nf_conntrack_ftp snd_seq_midi ath9k_common ath9k_hw nf_conntrack
iptable_filter snd_rawmidi snd_seq_midi_event uvcvideo ath ip_tables
videobuf2_core videodev psmouse x_tables ideapad_laptop serio_raw
snd_seq cfg80211 lp snd_timer snd_seq_device snd videobuf2_vmalloc
videobuf2_memops soundcore parport sparse_keymap intel_ips
snd_page_alloc usb_storage uas i915 drm_kms_helper drm i2c_algo_bit
r8169 intel_agp intel_gtt agpgart video
[21740.306469] Pid: 0, comm: swapper/2 Not tainted 3.3.0-rc7-next-20120316 #1
[21740.306473] Call Trace:
[21740.306483]  [<c1049072>] warn_slowpath_common+0x72/0xa0
[21740.306489]  [<c14e00a2>] ? dev_watchdog+0x1f2/0x200
[21740.306493]  [<c14e00a2>] ? dev_watchdog+0x1f2/0x200
[21740.306498]  [<c1049143>] warn_slowpath_fmt+0x33/0x40
[21740.306503]  [<c14e00a2>] dev_watchdog+0x1f2/0x200
[21740.306520]  [<c1059c12>] run_timer_softirq+0x132/0x540
[21740.306525]  [<c1059b98>] ? run_timer_softirq+0xb8/0x540
[21740.306529]  [<c14dfeb0>] ? pfifo_fast_init+0x60/0x60
[21740.306534]  [<c1050e67>] __do_softirq+0x97/0x360
[21740.306539]  [<c1050dd0>] ? local_bh_enable+0xd0/0xd0
[21740.306542]  <IRQ>  [<c10513d6>] ? irq_exit+0x86/0xb0
[21740.306552]  [<c159f179>] ? smp_apic_timer_interrupt+0x59/0x88
[21740.306559]  [<c12cf1a8>] ? trace_hardirqs_off_thunk+0xc/0x14
[21740.306565]  [<c1597682>] ? apic_timer_interrupt+0x36/0x3c
[21740.306572]  [<c10a007b>] ? clocks_calc_mult_shift+0xfb/0x100
[21740.306580]  [<c1319fd9>] ? intel_idle+0xc9/0x130
[21740.306588]  [<c1495655>] ? cpuidle_enter+0x15/0x20
[21740.306592]  [<c1495b5a>] ? cpuidle_idle_call+0x8a/0x320
[21740.306600]  [<c10152c5>] ? cpu_idle+0xa5/0xf0
[21740.306607]  [<c1585d2d>] ? start_secondary+0x209/0x20f
[21740.306611] ---[ end trace 9a4d94a7e2dfb4d0 ]---
[21740.318685] r8169 0000:06:00.0: eth0: link up
[21752.292679] r8169 0000:06:00.0: eth0: link up
[21764.268569] r8169 0000:06:00.0: eth0: link up
[21776.254393] r8169 0000:06:00.0: eth0: link up
[21788.235797] r8169 0000:06:00.0: eth0: link up
[21800.196524] r8169 0000:06:00.0: eth0: link up
[21812.172497] r8169 0000:06:00.0: eth0: link up

one thing I have noticed these few weeks is once I wake the machine
up, wireless connects, but Networkmanager keeps trying to connect
ethernet(switching ethernet off in preferences or waiting a while
resolves this issue from the daemon), so I think there must be some
strange thing with NM
causing this(disabling "auto connect" gets rid of this issue)
the system is ubuntu(oneiric)


-- 
Justin P. Mattock

Re: WARNING: at net/sched/sch_generic.c:256 dev_watchdog+0x1f2/0x200()

[Francois Romieu]

(Larry removed)

Justin Mattock <justinmattock@gmail.com> :
[...]
> seems I see this with the latest linux-next:

Thanks for testing.

[...]
> [21740.318685] r8169 0000:06:00.0: eth0: link up
> [21752.292679] r8169 0000:06:00.0: eth0: link up
> [21764.268569] r8169 0000:06:00.0: eth0: link up
> [21776.254393] r8169 0000:06:00.0: eth0: link up
> [21788.235797] r8169 0000:06:00.0: eth0: link up
> [21800.196524] r8169 0000:06:00.0: eth0: link up
> [21812.172497] r8169 0000:06:00.0: eth0: link up

This is completely broken. I could understand a few up/down link changes
until things settles but the driver should not claim periodically that
the link is up when there is no cable, at least not with a supported chipset.

Can you apply the debug helper below and report a complete dmesg from
boot with the same test (please remove l-k, netdev is good enough) ?

diff --git a/drivers/net/ethernet/realtek/r8169.c b/drivers/net/ethernet/realtek/r8169.c
index 61e6ab4..880264a 100644
--- a/drivers/net/ethernet/realtek/r8169.c
+++ b/drivers/net/ethernet/realtek/r8169.c
@@ -61,7 +61,8 @@
 #endif /* RTL8169_DEBUG */
 
 #define R8169_MSG_DEFAULT \
-	(NETIF_MSG_DRV | NETIF_MSG_PROBE | NETIF_MSG_IFUP | NETIF_MSG_IFDOWN)
+	(NETIF_MSG_DRV | NETIF_MSG_PROBE | NETIF_MSG_IFUP | NETIF_MSG_IFDOWN | \
+	 NETIF_MSG_LINK)
 
 #define TX_BUFFS_AVAIL(tp) \
 	(tp->dirty_tx + NUM_TX_DESC - tp->cur_tx - 1)
@@ -731,7 +732,7 @@ struct rtl8169_private {
 	void (*phy_reset_enable)(struct rtl8169_private *tp);
 	void (*hw_start)(struct net_device *);
 	unsigned int (*phy_reset_pending)(struct rtl8169_private *tp);
-	unsigned int (*link_ok)(void __iomem *);
+	unsigned int (*link_ok)(struct rtl8169_private *);
 	int (*do_ioctl)(struct rtl8169_private *tp, struct mii_ioctl_data *data, int cmd);
 
 	struct {
@@ -1260,14 +1261,28 @@ static unsigned int rtl8169_xmii_reset_pending(struct rtl8169_private *tp)
 	return rtl_readphy(tp, MII_BMCR) & BMCR_RESET;
 }
 
-static unsigned int rtl8169_tbi_link_ok(void __iomem *ioaddr)
+static unsigned int rtl8169_tbi_link_ok(struct rtl8169_private *tp)
 {
+	void __iomem *ioaddr = tp->mmio_addr;
+
 	return RTL_R32(TBICSR) & TBILinkOk;
 }
 
-static unsigned int rtl8169_xmii_link_ok(void __iomem *ioaddr)
+static unsigned int rtl8169_xmii_link_ok(struct rtl8169_private *tp)
 {
-	return RTL_R8(PHYstatus) & LinkStatus;
+	void __iomem *ioaddr = tp->mmio_addr;
+	struct net_device *dev = tp->dev;
+	u8 status;
+
+	status = RTL_R8(PHYstatus) & LinkStatus;
+	netif_info(tp, link, dev,
+		   "bmcr: %04x bmsr: %04x gbcr: %04x gbsr: %04x\n",
+		   rtl_readphy(tp, MII_BMCR),
+		   rtl_readphy(tp, MII_BMSR),
+		   rtl_readphy(tp, MII_CTRL1000),
+		   rtl_readphy(tp, MII_STAT1000));
+
+	return status;
 }
 
 static void rtl8169_tbi_reset_enable(struct rtl8169_private *tp)
@@ -1335,7 +1350,7 @@ static void __rtl8169_check_link_status(struct net_device *dev,
 					struct rtl8169_private *tp,
 					void __iomem *ioaddr, bool pm)
 {
-	if (tp->link_ok(ioaddr)) {
+	if (tp->link_ok(tp)) {
 		rtl_link_chg_patch(tp);
 		/* This is to cancel a scheduled suspend if there's one. */
 		if (pm)
@@ -3309,7 +3324,6 @@ static void rtl_hw_phy_config(struct net_device *dev)
 static void rtl_phy_work(struct rtl8169_private *tp)
 {
 	struct timer_list *timer = &tp->timer;
-	void __iomem *ioaddr = tp->mmio_addr;
 	unsigned long timeout = RTL8169_PHY_TIMEOUT;
 
 	assert(tp->mac_version > RTL_GIGA_MAC_VER_01);
@@ -3323,7 +3337,7 @@ static void rtl_phy_work(struct rtl8169_private *tp)
 		goto out_mod_timer;
 	}
 
-	if (tp->link_ok(ioaddr))
+	if (tp->link_ok(tp))
 		return;
 
 	netif_warn(tp, link, tp->dev, "PHY reset until link up\n");
-- 
1.7.7.6