Comment 4 for bug 729171

Hi,

Same bug on OO with a 3.0 kernel.

I did a transfer of a very huge amount of data (>50GB) between two computers linked through a Netgear router. The throughput was constant around 11 MB/s. Just after disconnecting and immediately reconnecting the Ethernet cable, the kernel spat a concerning message (see below).

I Googled this and found that other users experimented similar behavior after a huge point to point transfer. I know this is a rather weak hint for a bug hunt, but maybe it will raise a bell to someone...

Best,
Jacques

Dec 17 21:46:16 jacques NetworkManager[726]: <info> (eth0): DHCPv4 state changed nbi -> preinit
Dec 17 21:46:19 jacques dhclient: DHCPREQUEST of 192.168.1.7 on eth0 to 255.255.255.255 port 67
Dec 17 21:46:21 jacques NetworkManager[726]: <info> (eth0): carrier now OFF (device state 70, deferring action for 4 seconds)
Dec 17 21:46:21 jacques kernel: [19494.800075] ------------[ cut here ]------------
Dec 17 21:46:21 jacques kernel: [19494.800102] WARNING: at /build/buildd/linux-3.0.0/net/sched/sch_generic.c:255 dev_watchdog+0x25a/0x270()
Dec 17 21:46:21 jacques kernel: [19494.800112] Hardware name: 1015PX
Dec 17 21:46:21 jacques kernel: [19494.800120] NETDEV WATCHDOG: eth0 (atl1c): transmit queue 0 timed out
Dec 17 21:46:21 jacques kernel: [19494.800127] Modules linked in: pci_stub vboxpci vboxnetadp vboxnetflt vboxdrv parport_pc ppdev wl(P) lib80211 bcma arc4 bnep rfcomm eeepc_wmi asus_wmi sparse_keymap joydev uvcvideo videodev v4l2_compat_ioctl32 snd_hda_codec_realtek snd_hda_intel btusb bluetooth snd_hda_codec snd_hwdep snd_pcm psmouse snd_seq_midi serio_raw snd_rawmidi binfmt_misc brcmsmac(C) snd_seq_midi_event snd_seq brcmutil(C) snd_timer snd_seq_device mac80211 snd cfg80211 wmi crc_ccitt i915 soundcore snd_page_alloc drm_kms_helper drm i2c_algo_bit video lp parport usbhid hid ahci libahci atl1c
Dec 17 21:46:21 jacques kernel: [19494.800292] Pid: 0, comm: kworker/0:1 Tainted: P C 3.0.0-14-generic #23-Ubuntu
Dec 17 21:46:21 jacques kernel: [19494.800301] Call Trace:
Dec 17 21:46:21 jacques kernel: [19494.800309] <IRQ> [<ffffffff8105e7ef>] warn_slowpath_common+0x7f/0xc0
Dec 17 21:46:21 jacques kernel: [19494.800339] [<ffffffff8105e8e6>] warn_slowpath_fmt+0x46/0x50
Dec 17 21:46:21 jacques kernel: [19494.800360] [<ffffffff814f832a>] dev_watchdog+0x25a/0x270
Dec 17 21:46:21 jacques kernel: [19494.800375] [<ffffffff8107a1b0>] ? __queue_work+0x320/0x320
Dec 17 21:46:21 jacques kernel: [19494.800387] [<ffffffff814f80d0>] ? qdisc_reset+0x50/0x50
Dec 17 21:46:21 jacques kernel: [19494.800399] [<ffffffff814f80d0>] ? qdisc_reset+0x50/0x50
Dec 17 21:46:21 jacques kernel: [19494.800414] [<ffffffff8106d546>] call_timer_fn+0x46/0x160
Dec 17 21:46:21 jacques kernel: [19494.800426] [<ffffffff814f80d0>] ? qdisc_reset+0x50/0x50
Dec 17 21:46:21 jacques kernel: [19494.800439] [<ffffffff8106ee72>] run_timer_softirq+0x132/0x2a0
Dec 17 21:46:21 jacques kernel: [19494.800453] [<ffffffff8102706d>] ? lapic_next_event+0x1d/0x30
Dec 17 21:46:21 jacques kernel: [19494.800467] [<ffffffff81065f08>] __do_softirq+0xa8/0x210
Dec 17 21:46:21 jacques kernel: [19494.800480] [<ffffffff8109382f>] ? tick_program_event+0x1f/0x30
Dec 17 21:46:21 jacques kernel: [19494.800495] [<ffffffff815f48dc>] call_softirq+0x1c/0x30
Dec 17 21:46:21 jacques kernel: [19494.800507] [<ffffffff8100c2d5>] do_softirq+0x65/0xa0
Dec 17 21:46:21 jacques kernel: [19494.800519] [<ffffffff810662ee>] irq_exit+0x8e/0xb0
Dec 17 21:46:21 jacques kernel: [19494.800532] [<ffffffff815f521e>] smp_apic_timer_interrupt+0x6e/0x99
Dec 17 21:46:21 jacques kernel: [19494.800545] [<ffffffff815f4093>] apic_timer_interrupt+0x13/0x20
Dec 17 21:46:21 jacques kernel: [19494.800553] <EOI> [<ffffffff814ad8b0>] ? ladder_select_state+0x1a0/0x1a0
Dec 17 21:46:21 jacques kernel: [19494.800577] [<ffffffff814ad8b0>] ? ladder_select_state+0x1a0/0x1a0
Dec 17 21:46:21 jacques kernel: [19494.800590] [<ffffffff814ac6cf>] ? cpuidle_idle_call+0xdf/0x280
Dec 17 21:46:21 jacques kernel: [19494.800606] [<ffffffff8100920b>] cpu_idle+0xab/0x100
Dec 17 21:46:21 jacques kernel: [19494.800620] [<ffffffff815cd7f7>] start_secondary+0xd9/0xdb
Dec 17 21:46:21 jacques kernel: [19494.800630] ---[ end trace 9c2122ccaa8c55f2 ]---
Dec 17 21:46:21 jacques NetworkManager[726]: <info> (eth0): carrier now ON (device state 70)
Dec 17 21:46:21 jacques kernel: [19494.825628] atl1c 0000:01:00.0: irq 46 for MSI/MSI-X
Dec 17 21:46:21 jacques kernel: [19494.825850] atl1c 0000:01:00.0: atl1c: eth0 NIC Link is Up<100 Mbps Full Duplex>
Dec 17 21:46:26 jacques dhclient: DHCPREQUEST of 192.168.1.7 on eth0 to 255.255.255.255 port 67
Dec 17 21:46:26 jacques dhclient: DHCPACK of 192.168.1.7 from 192.168.1.1
Dec 17 21:46:26 jacques dhclient: bound to 192.168.1.7 -- renewal in 33579 seconds.
Dec 17 21:46:26 jacques NetworkManager[726]: <info> (eth0): DHCPv4 state changed preinit -> reboot
Dec 17 21:46:26 jacques NetworkManager[726]: <info> Activation (eth0) Stage 4 of 5 (IP4 Configure Get) scheduled...
Dec 17 21:46:26 jacques NetworkManager[726]: <info> Activation (eth0) Stage 4 of 5 (IP4 Configure Get) started...