RE: [E1000-devel] e1000e 3.9-rc1 suspend failure (was: Re: e1000e:nic does not work properly after cold power on)

[Allan, Bruce W]

> -----Original Message-----
> From: Mihai DonÈu [mailto:mihai.dontu@xxxxxxxxx]
> Sent: Monday, March 04, 2013 2:59 PM
> To: Morten Stevens
> Cc: e1000-devel@xxxxxxxxxxxxxxxxxxxxx; netdev@xxxxxxxxxxxxxxx; linux-
> kernel@xxxxxxxxxxxxxxx; Rafael J. Wysocki; Borislav Petkov; Jiri Slaby
> Subject: Re: [E1000-devel] e1000e 3.9-rc1 suspend failure (was: Re: e1000e:
> nic does not work properly after cold power on)
> 
> On Mon, 4 Mar 2013 22:48:30 +0100 Borislav Petkov wrote:
> > On Mon, Mar 04, 2013 at 07:15:07PM +0100, Morten Stevens wrote:
> > > Can you reproduce this with linux 3.9-rc1? 3.9-rc1 has the latest
> > > upstream driver (e1000e 2.2.14) which contains many bugfixes.
> >
> 
> On my system (ThinkPad T420) I get:
> 
> [   10.694743] e1000e: Intel(R) PRO/1000 Network Driver - 2.2.14-k
> [   10.694746] e1000e: Copyright(c) 1999 - 2013 Intel Corporation.
> [   10.694852] e1000e 0000:00:19.0: setting latency timer to 64
> [   10.694911] e1000e 0000:00:19.0: Interrupt Throttling Rate (ints/sec) set to
> dynamic conservative mode
> [   10.694949] e1000e 0000:00:19.0: irq 47 for MSI/MSI-X
> [   10.975086] e1000e 0000:00:19.0 eth0: registered PHC clock
> [   10.975091] e1000e 0000:00:19.0 eth0: (PCI Express:2.5GT/s:Width x1)
> 00:21:cc:70:17:a0
> [   10.975093] e1000e 0000:00:19.0 eth0: Intel(R) PRO/1000 Network
> Connection
> [   10.975127] e1000e 0000:00:19.0 eth0: MAC: 10, PHY: 11, PBA No: 1000FF-
> 0FF
> [   89.716695] e1000e 0000:00:19.0 eth0: Hardware Error
> [   90.025403] e1000e 0000:00:19.0 eth0: Timesync Tx Control register not set
> as expected
> [   90.349197] e1000e 0000:00:19.0: irq 47 for MSI/MSI-X
> [   90.449760] e1000e 0000:00:19.0: irq 47 for MSI/MSI-X
> 
> The 'hardware error' line caught my attention.
> 
> > This e1000e thing gets more b0rked by the minute. This is what happens
> > when I try to suspend with 3.9-rc1:
> >
> > [   83.502908] PM: Syncing filesystems ... done.
> > [   83.509886] Freezing user space processes ... (elapsed 0.01
> > seconds) done. [   83.523352] PM: Preallocating image memory... done
> > (allocated 95652 pages) [   83.675083] PM: Allocated 382608 kbytes in
> > 0.15 seconds (2550.72 MB/s) [   83.675782] Freezing remaining
> > freezable tasks ... (elapsed 0.01 seconds) done. [   83.688524]
> > Suspending console(s) (use no_console_suspend to debug)
> > [   84.251024] e1000e 0000:00:19.0 eth0: Hardware Error
> > [   84.458866] ------------[ cut here ]------------ [   84.458871]
> > WARNING: at kernel/irq/manage.c:1249 __free_irq+0xa3/0x1e0()
> > [   84.458872] Hardware name: 2320CTO [   84.458872] Trying to free
> > already-free IRQ 20 [   84.458898] Modules linked in:
> > cpufreq_powersave cpufreq_userspace cpufreq_conservative
> > cpufreq_stats uinput loop hid_generic usb hid hid coretemp kvm_intel
> > arc4 kvm crc32_pclmul iwldvm crc32c_intel ghash_clmulni_intel
> > mac80211 aesni_intel xts ipv6 aes_x86_64 lr w gf128mul ablk_helper
> > cryptd iTCO_wdt iTCO_vendor_support iwlwifi sdhci_pci sdhci cfg80211
> > snd_hda_codec_hdmi snd_hda_codec_realtek mmc_core microcode
> e1000e
> > thinkpad_acpi pcspkr lpc_ich i2c_i801 mfd_core nvram snd_hda_intel
> > rfkill snd_hda_codec battery ac snd_hw dep led_class snd_pcm
> > snd_page_alloc snd_timer snd acpi_cpufreq soundcore mperf ptp wmi
> > pps_core xhci_hcd ehci_pci ehci_hcd processo r thermal [   84.458900]
> > Pid: 3353, comm: kworker/u:35 Tainted: G        W    3.9.0-rc1 #1
> > [   84.458901] Call Trace: [   84.458905]  [<ffffffff8103ef7f>]
> > warn_slowpath_common+0x7f/0xc0 [   84.458907]  [<ffffffff8103f076>]
> > warn_slowpath_fmt+0x46/0x50 [   84.458910]  [<ffffffff81537bfe>] ?
> > _raw_spin_lock_irqsave+0x4e/0x60 [   84.458911]
> > [<ffffffff810bc8d5>] ? __free_irq+0x55/0x1e0 [   84.458913]
> > [<ffffffff810bc923>] __free_irq+0xa3/0x1e0 [   84.458914]
> > [<ffffffff810bcab4>] free_irq+0x54/0xc0 [   84.458919]
> > [<ffffffffa017745d>] e1000_free_irq+0x7d/0x90 [e1000e]
> > [   84.458922]  [<ffffffffa01834af>] __e1000_shutdown+0x8f/0x8a0
> > [e1000e] [   84.458924]  [<ffffffff813c92a7>] ?
> > __device_suspend+0xb7/0x200 [   84.458927]  [<ffffffff81073b71>] ?
> > get_parent_ip+0x11/0x50 [   84.458931]  [<ffffffffa0183d33>]
> > e1000_suspend+0x23/0x50 [e1000e] [   84.458932]
> > [<ffffffff813c92a7>] ? __device_suspend+0xb7/0x200 [   84.458933]
> > [<ffffffff8153c049>] ? sub_preempt_count+0x79/0xd0 [   84.458936]
> > [<ffffffff812a2ff5>] pci_pm_freeze+0x55/0xc0 [   84.458937]
> > [<ffffffff812a2fa0>] ? pci_pm_resume_noirq+0xd0/0xd0 [   84.458938]
> > [<ffffffff813c8b45>] dpm_run_callback.isra.5+0x25/0x50
> > [   84.458939]  [<ffffffff813c92d3>] __device_suspend+0xe3/0x200
> > [   84.458941]  [<ffffffff813c940f>] async_suspend+0x1f/0xa0
> > [   84.458942]  [<ffffffff8106bcfb>] async_run_entry_fn+0x3b/0x140
> > [   84.458944]  [<ffffffff8105d00d>] process_one_work+0x1ed/0x510
> > [   84.458946]  [<ffffffff8105cfab>] ? process_one_work+0x18b/0x510
> > [   84.458948]  [<ffffffff8105e7b5>] worker_thread+0x115/0x390
> > [   84.458949]  [<ffffffff8105e6a0>] ? manage_workers+0x300/0x300
> > [   84.458951]  [<ffffffff81064e2a>] kthread+0xea/0xf0
> > [   84.458953]  [<ffffffff81064d40>] ?
> > kthread_create_on_node+0x160/0x160 [   84.458954]
> > [<ffffffff8153ff9c>] ret_from_fork+0x7c/0xb0 [   84.458955]
> > [<ffffffff81064d40>] ? kthread_create_on_node+0x160/0x160
> > [   84.458956] ---[ end trace 3114e23ce50d2357 ]--- [   85.082276]
> > pci_pm_freeze(): e1000_suspend+0x0/0x50 [e1000e] returns -2
> > [   85.082278] dpm_run_callback(): pci_pm_freeze+0x0/0xc0 returns -2
> > [   85.082281] PM: Device 0000:00:19.0 failed to freeze async: error
> > -2
> >
> > Let's add more folks to CC.

This may be related to some runtime power management issues for which there are a
number of patches currently in test.

¢éì®&Þ~º&¶¬–+-±éÝ¥Šw®žË±Êâmébžìdz¹Þ)í…æèw*jg¬±¨¶‰šŽŠÝj/êäz¹ÞŠà2ŠÞ¨è­Ú&¢)ß«a¶Úþø®G«éh®æj:+v‰¨Šwè†Ù>Wš±êÞiÛaxPjØm¶ŸÿÃ-»+ƒùdš_