[3.5-rc6+] Re: ACPI / PM: Leave Bus Master Arbitration enabled for suspend/resume

[Sedat Dilek <sedat.dilek@xxxxxxxxx>]

Hi,

looks like [1] is fixing my suspend/resume problem with v3.5-rc6+
(attached call-trace is with a v3.5-rc6 kernel before the commit got
upstream).
Thanks for the fix!

I am wondering if those new-lines are intended (see attachment)?

- Sedat -

P.S.: Commit details

commit dc332fdf9f373a87b1e2f423b5b004b2a3c37e1a
ACPI / PM: Leave Bus Master Arbitration enabled for suspend/resume

[1] http://git.kernel.org/?p=linux/kernel/git/torvalds/linux.git;a=commitdiff;h=dc332fdf9f373a87b1e2f423b5b004b2a3c37e1a

[17043.897493] wlan0: deauthenticating from 00:04:0e:e4:00:3d by local choice (reason=3)
[17043.908296] cfg80211: All devices are disconnected, going to restore regulatory settings
[17043.908305] cfg80211: Restoring regulatory settings
[17043.908313] cfg80211: Calling CRDA to update world regulatory domain
[17043.915387] cfg80211: Ignoring regulatory request Set by core since the driver uses its own custom regulatory domain
[17043.915395] cfg80211: World regulatory domain updated:
[17043.915398] cfg80211:   (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp)
[17043.915402] cfg80211:   (2402000 KHz - 2472000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
[17043.915407] cfg80211:   (2457000 KHz - 2482000 KHz @ 20000 KHz), (300 mBi, 2000 mBm)
[17043.915410] cfg80211:   (2474000 KHz - 2494000 KHz @ 20000 KHz), (300 mBi, 2000 mBm)
[17043.915413] cfg80211:   (5170000 KHz - 5250000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
[17043.915417] cfg80211:   (5735000 KHz - 5835000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
[17045.745718] PM: Syncing filesystems ... done.
[17045.749964] PM: Preparing system for mem sleep
[17046.303388] Freezing user space processes ... (elapsed 0.01 seconds) done.
[17046.319432] Freezing remaining freezable tasks ... (elapsed 0.01 seconds) done.
[17046.335421] PM: Entering mem sleep
[17046.335491] Suspending console(s) (use no_console_suspend to debug)
[17046.335698] sd 1:0:0:0: [sdb] Synchronizing SCSI cache
[17046.335869] sd 0:0:0:0: [sda] Synchronizing SCSI cache
[17046.336017] sd 0:0:0:0: [sda] Stopping disk
[17046.338588] sd 1:0:0:0: [sdb] Stopping disk
[17046.348401] ------------[ cut here ]------------
[17046.348408] WARNING: at kernel/irq/manage.c:1214 __free_irq+0xa0/0x1d0()
[17046.348409] Hardware name: <HIDDEN>
[17046.348410] Trying to free already-free IRQ 47
[17046.348410] Modules linked in: snd_hda_codec_hdmi snd_hda_codec_realtek rfcomm bnep parport_pc ppdev joydev snd_hda_intel snd_hda_codec snd_hwdep snd_pcm coretemp kvm_intel snd_page_alloc kvm arc4 snd_seq_midi snd_seq_midi_event iwlwifi i915 snd_rawmidi uvcvideo snd_seq ghash_clmulni_intel snd_seq_device videobuf2_vmalloc aesni_intel snd_timer videobuf2_memops aes_x86_64 drm_kms_helper videobuf2_core cryptd mac80211 drm snd videodev psmouse samsung_laptop i2c_algo_bit btusb hid_generic soundcore microcode serio_raw cfg80211 lp mei bluetooth video mac_hid lpc_ich parport usbhid hid r8169
[17046.348441] Pid: 18981, comm: kworker/u:17 Not tainted 3.5.0-rc6-1-iniza-generic #1
[17046.348442] Call Trace:
[17046.348444] 
[17046.348447]  [<ffffffff81052e5f>] warn_slowpath_common+0x7f/0xc0
[17046.348448] 
[17046.348449]  [<ffffffff81052f56>] warn_slowpath_fmt+0x46/0x50
[17046.348450] 
[17046.348452]  [<ffffffff81040a49>] ? default_spin_lock_flags+0x9/0x10
[17046.348453] 
[17046.348455]  [<ffffffff810e0bf0>] __free_irq+0xa0/0x1d0
[17046.348455] 
[17046.348460]  [<ffffffff8107f0e0>] ? async_schedule+0x20/0x20
[17046.348460] 
[17046.348462]  [<ffffffff810e0f15>] free_irq+0x55/0xd0
[17046.348463] 
[17046.348467]  [<ffffffffa00554d8>] mei_pci_suspend+0x78/0xd0 [mei]
[17046.348468] 
[17046.348471]  [<ffffffff8134eb25>] pci_pm_suspend+0x75/0x140
[17046.348472] 
[17046.348475]  [<ffffffff8141a310>] ? device_pm_wait_for_dev+0x30/0x30
[17046.348475] 
[17046.348477]  [<ffffffff8134eab0>] ? pci_pm_freeze+0xb0/0xb0
[17046.348477] 
[17046.348478]  [<ffffffff8141a67b>] dpm_run_callback.isra.5+0x3b/0x80
[17046.348479] 
[17046.348480]  [<ffffffff8141a7ad>] __device_suspend+0xed/0x220
[17046.348481] 
[17046.348482]  [<ffffffff8141b09f>] async_suspend+0x1f/0xa0
[17046.348482] 
[17046.348484]  [<ffffffff8107f15e>] async_run_entry_fn+0x7e/0x170
[17046.348484] 
[17046.348488]  [<ffffffff8107145a>] process_one_work+0x11a/0x480
[17046.348488] 
[17046.348490]  [<ffffffff81072318>] ? manage_workers.isra.28+0x1e8/0x230
[17046.348491] 
[17046.348493]  [<ffffffff810724c5>] worker_thread+0x165/0x370
[17046.348493] 
[17046.348495]  [<ffffffff81072360>] ? manage_workers.isra.28+0x230/0x230
[17046.348496] 
[17046.348498]  [<ffffffff810771c3>] kthread+0x93/0xa0
[17046.348498] 
[17046.348500]  [<ffffffff8167bd24>] kernel_thread_helper+0x4/0x10
[17046.348501] 
[17046.348503]  [<ffffffff81077130>] ? kthread_freezable_should_stop+0x70/0x70
[17046.348503] 
[17046.348504]  [<ffffffff8167bd20>] ? gs_change+0x13/0x13
[17046.348506] ---[ end trace 595e5069309213b1 ]---
[17046.927294] PM: suspend of devices complete after 591.818 msecs
[17046.927296] PM: suspend devices took 0.592 seconds
[17046.927414] PM: late suspend of devices complete after 0.116 msecs
[17046.943325] r8169 0000:02:00.0: wake-up capability enabled by ACPI
[17047.023288] PM: noirq suspend of devices complete after 95.890 msecs
[17047.023520] ACPI: Preparing to enter system sleep state S3
[17047.047343] PM: Saving platform NVS memory
[17047.052086] Disabling non-boot CPUs ...
[17047.155113] CPU 1 is now offline
[17047.259124] CPU 2 is now offline
[17047.363095] CPU 3 is now offline
[17047.363490] Extended CMOS year: 2000
[17047.364735] ACPI: Low-level resume complete
[17047.364782] PM: Restoring platform NVS memory
[17047.366012] Extended CMOS year: 2000
[17047.366056] Enabling non-boot CPUs ...
[17047.366149] Booting Node 0 Processor 1 APIC 0x1
[17047.377158] Disabled fast string operations
[17047.379816] CPU1 is up
[17047.379917] Booting Node 0 Processor 2 APIC 0x2
[17047.390953] Disabled fast string operations
[17047.393575] CPU2 is up
[17047.393815] Booting Node 0 Processor 3 APIC 0x3
[17047.404852] Disabled fast string operations
[17047.407650] CPU3 is up
[17047.412570] ACPI: Waking up from system sleep state S3
[17047.460035] PM: noirq resume of devices complete after 2.331 msecs
[17047.460136] PM: early resume of devices complete after 0.062 msecs
[17047.460211] i915 0000:00:02.0: setting latency timer to 64
[17047.460239] ehci_hcd 0000:00:1a.0: setting latency timer to 64
[17047.460293] ehci_hcd 0000:00:1d.0: setting latency timer to 64
[17047.460315] mei 0000:00:16.0: irq 47 for MSI/MSI-X
[17047.460327] ahci 0000:00:1f.2: setting latency timer to 64
[17047.460344] snd_hda_intel 0000:00:1b.0: irq 50 for MSI/MSI-X
[17047.460485] iwlwifi 0000:01:00.0: RF_KILL bit toggled to enable radio.
[17047.460487] r8169 0000:02:00.0: wake-up capability disabled by ACPI
[17047.460495] genirq: Threaded irq requested with handler=NULL and !ONESHOT for irq 47
[17047.460512] mei 0000:00:16.0: request_threaded_irq failed: irq = 47.
[17047.460518] dpm_run_callback(): pci_pm_resume+0x0/0xe0 returns -22
[17047.460520] PM: Device 0000:00:16.0 failed to resume async: error -22
[17047.460561] usb usb3: root hub lost power or was reset
[17047.460563] usb usb4: root hub lost power or was reset
[17047.465478] xhci_hcd 0000:03:00.0: irq 41 for MSI/MSI-X
[17047.465484] xhci_hcd 0000:03:00.0: irq 42 for MSI/MSI-X
[17047.465489] xhci_hcd 0000:03:00.0: irq 43 for MSI/MSI-X
[17047.465495] xhci_hcd 0000:03:00.0: irq 44 for MSI/MSI-X
[17047.465500] xhci_hcd 0000:03:00.0: irq 45 for MSI/MSI-X
[17047.518924] [drm] Enabling RC6 states: RC6 on, RC6p off, RC6pp off
[17047.789562] ata2: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
[17047.793776] ata2.00: configured for UDMA/133
[17047.797563] ata5: SATA link down (SStatus 0 SControl 300)
[17047.805559] ata4: SATA link down (SStatus 0 SControl 300)
[17047.809656] sd 1:0:0:0: [sdb] Starting disk
[17047.901745] usb 2-1.4: reset low-speed USB device number 3 using ehci_hcd
[17048.253584] usb 1-1.4: reset high-speed USB device number 3 using ehci_hcd
[17049.965077] ata1: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
[17049.967527] ata1.00: configured for UDMA/133
[17049.981210] sd 0:0:0:0: [sda] Starting disk