Re: failure to resume from suspend cause by 84ebc102

[Date Prev][Date Next][Thread Prev][Thread Next][Date Index][Thread Index]

 



(Sorry for the delay, the CPU fan _and_ one of disks took offense for so
many frequent hard power-offs.)

On Tue, Aug 27, 2013 at 02:25:36PM -0400, Alan Stern wrote:
> On Tue, 27 Aug 2013, Adam Borowski wrote:
> 
> > > What happens if go back to a kernel without that commit and enable
> > > CONFIG_USB_SUSPEND?  The behavior should be identical -- basically the
> > > commit is supposed to have the effect of always assuming that
> > > CONFIG_USB_SUSPEND has the same value as CONFIG_PM_RUNTIME, except in 
> > > one spot where it is assumed to have the same value as CONFIG_PM.
> > 
> > Going to the parent of that commit but enabling CONFIG_USB_SUSPEND indeed
> > causes the lockup.  So it's not that commit what's the culprit -- it's just
> > that Debian kernels did not have the option enabled but do have
> > CONFIG_PM_RUNTIME which you merged it with.
> > 
> > Surprisingly, though, today's "next" with CONFIG_PM_RUNTIME unset _does_
> > lock up.
> > 
> > I also tried removing all USB devices:
> > * none attached: ok
> > * USB keyboard: ok

Apparently, these two are unreliable, and usually there's a lockup just the
same.

> > * USB mouse (two different manufacturers): lockup
> 
> > So what else should I test?
> 
> Is there any way to get the dmesg information when the system locks up?  
> For example, serial console, or netconsole?  Seeing that, along with
> CONFIG_USB_DEBUG enabled, would help.

Network doesn't work after a failed resume.

I obtained a serial cable, output with CONFIG_USB_DEBUG=y attached.

No WARNING this time.  In this session, the display returned and SysRq
worked. Sometimes, the screen stays blank and SysRq doesn't work.

Another session (also attached) was more interesting: after what seemed to
be the usual lockup, I pulled out the mouse, preparing for more tests.
Suddenly, I see a BUG dump then a prompt.  The system was back up,
apparently normal.  (No CONFIG_USB_DEBUG during that run).

-- 
ᛊᚨᚾᛁᛏᚣ᛫ᛁᛊ᛫ᚠᛟᚱ᛫ᚦᛖ᛫ᚹᛖᚨᚲ
[    0.000000] Initializing cgroup subsys cpuset
[    0.000000] Initializing cgroup subsys cpu
[    0.000000] Initializing cgroup subsys cpuacct
[    0.000000] Linux version 3.11.0-rc6-next-20130822-x32 (kilobyte@umbar) (gcc version 4.8.1 (Debian 4.8.1-9) ) #2 SMP Thu Aug 29 22:45:13 UTC 2013
[    0.000000] Command line: BOOT_IMAGE=/boot/vmlinuz-3.11.0-rc6-next-20130822-x32 root=UUID=a9720ff9-068e-49fc-b1e9-a2d448039149 ro single console=ttyS0,115200 no_console_suspend=1
[    0.000000] e820: BIOS-provided physical RAM map:
[    0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009ebff] usable
[    0.000000] BIOS-e820: [mem 0x000000000009ec00-0x000000000009ffff] reserved
[    0.000000] BIOS-e820: [mem 0x00000000000e4000-0x00000000000fffff] reserved
[    0.000000] BIOS-e820: [mem 0x0000000000100000-0x00000000c7e7ffff] usable
[    0.000000] BIOS-e820: [mem 0x00000000c7e80000-0x00000000c7e97fff] ACPI data
[    0.000000] BIOS-e820: [mem 0x00000000c7e98000-0x00000000c7ecffff] ACPI NVS
[    0.000000] BIOS-e820: [mem 0x00000000c7ed0000-0x00000000c7efffff] reserved
[    0.000000] BIOS-e820: [mem 0x00000000ff700000-0x00000000ffffffff] reserved
[    0.000000] BIOS-e820: [mem 0x0000000100000000-0x0000000237ffffff] usable
[    0.000000] NX (Execute Disable) protection: active
[    0.000000] SMBIOS 2.5 present.
[    0.000000] No AGP bridge found
[    0.000000] e820: last_pfn = 0x238000 max_arch_pfn = 0x400000000
[    0.000000] x86 PAT enabled: cpu 0, old 0x7040600070406, new 0x7010600070106
[    0.000000] e820: last_pfn = 0xc7e80 max_arch_pfn = 0x400000000
[    0.000000] found SMP MP-table at [mem 0x000ff780-0x000ff78f] mapped at [ffff8800000ff780]
[    0.000000] Using GB pages for direct mapping
[    0.000000] init_memory_mapping: [mem 0x00000000-0x000fffff]
[    0.000000] init_memory_mapping: [mem 0x237e00000-0x237ffffff]
[    0.000000] init_memory_mapping: [mem 0x234000000-0x237dfffff]
[    0.000000] init_memory_mapping: [mem 0x200000000-0x233ffffff]
[    0.000000] init_memory_mapping: [mem 0x00100000-0xc7e7ffff]
[    0.000000] init_memory_mapping: [mem 0x100000000-0x1ffffffff]
[    0.000000] RAMDISK: [mem 0x37964000-0x37ca9fff]
[    0.000000] ACPI: RSDP 00000000000fb510 00024 (v02 ACPIAM)
[    0.000000] ACPI: XSDT 00000000c7e80100 0005C (v01 081910 XSDT1732 20100819 MSFT 00000097)
[    0.000000] ACPI: FACP 00000000c7e80290 000F4 (v03 081910 FACP1732 20100819 MSFT 00000097)
[    0.000000] ACPI BIOS Warning (bug): Optional FADT field Pm2ControlBlock has zero address or length: 0x0000000000000000/0x1 (20130725/tbfadt-603)
[    0.000000] ACPI: DSDT 00000000c7e80450 0DB3A (v01  A1540 A1540001 00000001 INTL 20060113)
[    0.000000] ACPI: FACS 00000000c7e98000 00040
[    0.000000] ACPI: APIC 00000000c7e80390 0007C (v01 081910 APIC1732 20100819 MSFT 00000097)
[    0.000000] ACPI: MCFG 00000000c7e80410 0003C (v01 081910 OEMMCFG  20100819 MSFT 00000097)
[    0.000000] ACPI: OEMB 00000000c7e98040 00072 (v01 081910 OEMB1732 20100819 MSFT 00000097)
[    0.000000] ACPI: SRAT 00000000c7e8f650 00108 (v01 AMD    FAM_F_10 00000002 AMD  00000001)
[    0.000000] ACPI: HPET 00000000c7e8f760 00038 (v01 081910 OEMHPET  20100819 MSFT 00000097)
[    0.000000] ACPI: SSDT 00000000c7e8f7a0 00DA4 (v01 A M I  POWERNOW 00000001 AMD  00000001)
[    0.000000] SRAT: PXM 0 -> APIC 0x00 -> Node 0
[    0.000000] SRAT: PXM 0 -> APIC 0x01 -> Node 0
[    0.000000] SRAT: PXM 0 -> APIC 0x02 -> Node 0
[    0.000000] SRAT: PXM 0 -> APIC 0x03 -> Node 0
[    0.000000] SRAT: PXM 0 -> APIC 0x04 -> Node 0
[    0.000000] SRAT: PXM 0 -> APIC 0x05 -> Node 0
[    0.000000] SRAT: Node 0 PXM 0 [mem 0x00000000-0x0009ffff]
[    0.000000] SRAT: Node 0 PXM 0 [mem 0x00100000-0xc7ffffff]
[    0.000000] SRAT: Node 0 PXM 0 [mem 0x100000000-0x237ffffff]
[    0.000000] NUMA: Node 0 [mem 0x00000000-0x0009ffff] + [mem 0x00100000-0xc7ffffff] -> [mem 0x00000000-0xc7ffffff]
[    0.000000] NUMA: Node 0 [mem 0x00000000-0xc7ffffff] + [mem 0x100000000-0x237ffffff] -> [mem 0x00000000-0x237ffffff]
[    0.000000] Initmem setup node 0 [mem 0x000000002lNumber=1
[    2.912760] usb usb2: Product: EHCI Host Controller
[    2.917647] usb usb2: Manufacturer: Linux 3.11.0-rc6-next-20130822-x32 ehci_hcd
[    2.924953] usb usb2: SerialNumber: 0000:00:13.2
[    2.929672] hub 2-0:1.0: USB hub found
[    2.933428] hub 2-0:1.0: 6 ports detected
Begin: Loading essential drivers ... done.
Begin: Running /scripts/init-premount ... done.
Begin: Mounting root file system ... Begin: Running /scripts/local-top ... done.
Begin: Waiting for root file system ... [    2.972969] ata6: SATA link down (SStatus 0 SControl 300)
[    2.978436] ata8: SATA link down (SStatus 0 SControl 300)
[    2.983923] ata4: SATA link down (SStatus 0 SControl 300)
[    3.152886] ata5: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
[    3.159086] ata3: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
[    3.165316] ata7: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
[    3.171698] ata3.00: ATA-8: ST31000528AS, CC44, max UDMA/133
[    3.177377] ata3.00: 1953525168 sectors, multi 16: LBA48 NCQ (depth 31/32)
[    3.184656] ata7.00: ATA-8: Hitachi HUA722010CLA330, JP4OA3KB, max UDMA/133
[    3.191635] ata7.00: 1953525168 sectors, multi 0: LBA48 NCQ (depth 31/32), AA
[    3.198956] ata3.00: configured for UDMA/133
[    3.203404] scsi 2:0:0:0: Direct-Access     ATA      ST31000528AS     CC44 PQ: 0 ANSI: 5
[    3.204572] ata7.00: configured for UDMA/133
[    3.213300] ata5.00: ATA-7: ST3250820NS, 3.AEG, max UDMA/133
[    3.213301] ata5.00: 488397168 sectors, multi 16: LBA48 NCQ (depth 31/32)
[    3.229956] sd 2:0:0:0: [sda] 1953525168 512-byte logical blocks: (1.00 TB/931 GiB)
[    3.236836] tsc: Refined TSC clocksource calibration: 2812.530 MHz
[    3.243830] sd 2:0:0:0: [sda] Write Protect is off
[    3.248649] sd 2:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
[    3.271615] ata5.00: configured for UDMA/133
[    3.276048] scsi 4:0:0:0: Direct-Access     ATA      ST3250820NS      3.AE PQ: 0 ANSI: 5
[    3.284329] sd 4:0:0:0: [sdb] 488397168 512-byte logical blocks: (250 GB/232 GiB)
[    3.284449] scsi 6:0:0:0: Direct-Access     ATA      Hitachi HUA72201 JP4O PQ: 0 ANSI: 5
[    3.284575] sd 6:0:0:0: [sdc] 1953525168 512-byte logical blocks: (1.00 TB/931 GiB)
[    3.284724] sd 6:0:0:0: [sdc] Write Protect is off
[    3.284799] sd 6:0:0:0: [sdc] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
[    3.288148]  sda: sda1 < sda5 sda6 > sda2 sda3
[    3.288560] sd 2:0:0:0: [sda] Attached SCSI disk
[    3.301012]  sdc: sdc1 sdc2
[    3.301512] sd 6:0:0:0: [sdc] Attached SCSI disk
[    3.338060] sd 4:0:0:0: [sdb] Write Protect is off
[    3.342926] sd 4:0:0:0: [sdb] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
[    3.343006] sd 2:0:0:0: Attached scsi generic sg0 type 0
[    3.343067] sd 4:0:0:0: Attached scsi generic sg1 type 0
[    3.343124] sd 6:0:0:0: Attached scsi generic sg2 type 0
[    3.393573]  sdb: sdb1 sdb2 < sdb5 sdb6 >
[    3.397862] sd 4:0:0:0: [sdb] Attached SCSI disk
done.
Begin: Running /scripts/local-premount ... Scanning for Btrfs filesystems
[    3.889034] device label data1 devid 1 transid 732944 /dev/sda6
[    3.953318] device label sigmund devid 1 transid 3976 /dev/sdc1
done.
[    4.066610] EXT4-fs (sdb1): mounted filesystem with ordered data mode. Opts: (null)
Begin: Running /scripts/local-bottom ... done.
done.
Begin: Running /scripts/init-bottom ... done.
[    4.236723] Switched to clocksource tsc
INIT: version 2.88 booting
[info] Using makefile-style concurrent boot in runlevel S.
[....] Starting the hotplug events dispatcher: udevd[    5.730243] udevd[413]: starting version 175
[ ok .
[....] Synthesizing the initial hotplug events...[ ok done.
[....] Waiting for /dev to be fully populated...[    6.155419] input: PC Speaker as /devices/platform/pcspkr/input/input0
[    6.155445] ACPI Warning: 0x0000000000000b00-0x0000000000000b07 SystemIO conflicts with Region \SOR1 1 (20130725/utaddress-251)
[    6.155448] ACPI Warning: 0x0000000000000b00-0x0000000000000b07 SystemIO conflicts with Region \_SB_.PCI0.SBRG.ASOC.SMRG 2 (20130725/utaddress-251)
[    6.155454] ACPI: If an ACPI driver is available for this device, you should use it instead of the native driver
[    6.197040] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver
[    6.203882] wmi: Mapper loaded
[    6.207064] input: Power Button as /devices/LNXSYSTM:00/device:00/PNP0C0C:00/input/input1
[    6.215247] ACPI: Power Button [PWRB]
[    6.218947] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input2
[    6.226353] ACPI: Power Button [PWRF]
[    6.307463] [drm] Initialized drm 1.1.0 20060810
[    6.312237] kvm: Nested Virtualization enabled
[    6.316683] kvm: Nested Paging enabled
[    6.322365] parport_pc 00:04: reported by Plug and Play ACPI
[    6.328141] parport0: PC-style at 0x378, irq 7 [PCSPP,TRISTATE]
[    6.362070] ohci-pci: OHCI PCI platform driver
[    6.366660] ohci-pci 0000:00:12.0: OHCI PCI host controller
[    6.372266] ohci-pci 0000:00:12.0: new USB bus registered, assigned bus number 3
[    6.379671] sp5100_tco: SP5100/SB800 TCO WatchDog Timer Driver v0.05
[    6.379736] ohci-pci 0000:00:12.0: irq 16, io mem 0xf9ffe000
[    6.391741] sp5100_tco: PCI Revision ID: 0x3c
[    6.396110] sp5100_tco: failed to find MMIO address, giving up.
[    6.423344] ACPI: processor limited to max C-state 1
[    6.436162] usb usb3: New USB device found, idVendor=1d6b, idProduct=0001
[    6.442961] usb usb3: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    6.450208] usb usb3: Product: OHCI PCI host controller
[    6.455429] usb usb3: Manufacturer: Linux 3.11.0-rc6-next-20130822-x32 ohci_hcd
[    6.462772] usb usb3: SerialNumber: 0000:00:12.0
[    6.467482] acpi-cpufreq: overriding BIOS provided _PSD data
[    6.467546] hub 3-0:1.0: USB hub found
[    6.467555] hub 3-0:1.0: 3 ports detected
[    6.467789] ohci-pci 0000:00:12.1: OHCI PCI host controller
[    6.467795] ohci-pci 0000:00:12.1: new USB bus registered, assigned bus number 4
[    6.467825] ohci-pci 0000:00:12.1: irq 16, io mem 0xf9ffd000
[    6.500952] nouveau  [  DEVICE][0000:01:00.0] BOOT0  : 0x0a3180a2
[    6.507105] nouveau  [  DEVICE][0000:01:00.0] Chipset: GT215 (NVA3)
[    6.513406] nouveau  [  DEVICE][0000:01:00.0] Family : NV50
[    6.525512] usb usb4: New USB device found, idVendor=1d6b, idProduct=0001
[    6.532357] usb usb4: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    6.539729] usb usb4: Product: OHCI PCI host controller
[    6.545006] usb usb4: Manufacturer: Linux 3.11.0-rc6-next-20130822-x32 ohci_hcd
[    6.552427] usb usb4: SerialNumber: 0000:00:12.1
[    6.557200] nouveau  [   VBIOS][0000:01:00.0] checking PRAMIN for image...
[    6.643859] nouveau  [   VBIOS][0000:01:00.0] ... appears to be valid
[    6.650329] nouveau  [   VBIOS][0000:01:00.0] using image from PRAMIN
[    6.657316] nouveau  [   VBIOS][0000:01:00.0] BIT signature found
[    6.663520] nouveau  [   VBIOS][0000:01:00.0] version 70.15.1e.00.00
[    6.670313] hub 4-0:1.0: USB hub found
[    6.674134] hub 4-0:1.0: 3 ports detected
[    6.699150] nouveau  [     PFB][0000:01:00.0] RAM type: DDR3
[    6.704890] nouveau  [     PFB][0000:01:00.0] RAM size: 1024 MiB
[    6.710972] nouveau  [     PFB][0000:01:00.0]    ZCOMP: 2048 tags
[    6.743468] nouveau  [  PTHERM][0000:01:00.0] FAN control: PWM
[    6.749378] nouveau  [  PTHERM][0000:01:00.0] fan management: disabled
[    6.755997] nouveau  [  PTHERM][0000:01:00.0] internal sensor: yes
[    6.762757] ohci-pci 0000:00:13.0: OHCI PCI host controller
[    6.768351] ohci-pci 0000:00:13.0: new USB bus registered, assigned bus number 5
[    6.775773] ohci-pci 0000:00:13.0: irq 18, io mem 0xf9ffc000
[    6.781447] [TTM] Zone  kernel: Available graphics memory: 4097214 kiB
[    6.787976] [TTM] Zone   dma32: Available graphics memory: 2097152 kiB
[    6.794511] [TTM] Initializing pool allocator
[    6.798872] [TTM] Initializing DMA pool allocator
[    6.803596] nouveau  [     DRM] VRAM: 1024 MiB
[    6.808036] nouveau  [     DRM] GART: 1048576 MiB
[    6.812742] nouveau  [     DRM] TMDS table version 2.0
[    6.817879] nouveau  [     DRM] DCB version 4.0
[    6.822412] nouveau  [     DRM] DCB outp 00: 01000302 00020030
[    6.828254] nouveau  [     DRM] DCB outp 01: 02000300 00000000
[    6.834087] nouveau  [     DRM] DCB outp 02: 01032310 00000000
[    6.839916] nouveau  [     DRM] DCB outp 03: 02021362 00020010
[    6.845745] nouveau  [     DRM] DCB conn 00: 00001030
[    6.850830] nouveau  [     DRM] DCB conn 01: 00002161
[    6.855899] nouveau  [     DRM] DCB conn 02: 00000200
[    6.865019] usb usb5: New USB device found, idVendor=1d6b, idProduct=0001
[    6.871819] usb usb5: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    6.879061] usb usb5: Product: OHCI PCI host controller
[    6.884285] usb usb5: Manufacturer: Linux 3.11.0-rc6-next-20130822-x32 ohci_hcd
[    6.891590] usb usb5: SerialNumber: 0000:00:13.0
[    6.903901] [drm] Supports vblank timestamp caching Rev 1 (10.10.2010).
[    6.910530] [drm] No driver support for vblank timestamp query.
[    6.916567] nouveau  [     DRM] 3 available performance level(s)
[    6.922605] nouveau  [     DRM] 0: core 135MHz shader 270MHz memory 135MHz voltage 1000mV
[    6.930882] nouveau  [     DRM] 1: core 405MHz shader 810MHz memory 324MHz voltage 1000mV
[    6.939061] nouveau  [     DRM] 3: core 600MHz shader 1460MHz memory 800MHz voltage 1000mV
[    6.947365] nouveau  [     DRM] c: core 405MHz shader 810MHz memory 324MHz fanspeed 53%
[    6.955556] hub 5-0:1.0: USB hub found
[    6.959342] hub 5-0:1.0: 3 ports detected
[    6.971170] nouveau  [     DRM] MM: using COPY for buffer copies
[    7.020049] nouveau  [     DRM] allocated 1280x1024 fb: 0x70000, bo ffff88022b4d5400
[    7.028703] fbcon: nouveaufb (fb0) is primary device
[    7.030215] ohci-pci 0000:00:13.1: OHCI PCI host controller
[    7.030221] ohci-pci 0000:00:13.1: new USB bus registered, assigned bus number 6
[    7.030246] ohci-pci 0000:00:13.1: irq 18, io mem 0xf9ffb000
[    7.084003] Console: switching to colour frame buffer device 160x64
[    7.088087] usb usb6: New USB device found, idVendor=1d6b, idProduct=0001
[    7.088088] usb usb6: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    7.088090] usb usb6: Product: OHCI PCI host controller
[    7.088092] usb usb6: Manufacturer: Linux 3.11.0-rc6-next-20130822-x32 ohci_hcd
[    7.088093] usb usb6: SerialNumber: 0000:00:13.1
[    7.150242] nouveau 0000:01:00.0: fb0: nouveaufb frame buffer device
[    7.156606] nouveau 0000:01:00.0: registered panic notifier
[    7.162303] [drm] Initialized nouveau 1.1.1 20120801 for 0000:01:00.0 on minor 0
[    7.163959] usb 3-3: new low-speed USB device number 2 using ohci-pci
[    7.176228] hub 6-0:1.0: USB hub found
[    7.179998] hub 6-0:1.0: 3 ports detected
[    7.184391] ohci-pci 0000:00:14.5: OHCI PCI host controller
[    7.189970] ohci-pci 0000:00:14.5: new USB bus registered, assigned bus number 7
[    7.197405] ohci-pci 0000:00:14.5: irq 18, io mem 0xf9ffa000
[    7.255845] device label sigmund devid 1 transid 3976 /dev/sdc1
[    7.260055] usb usb7: New USB device found, idVendor=1d6b, idProduct=0001
[    7.260059] usb usb7: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    7.260062] usb usb7: Product: OHCI PCI host controller
[    7.260065] usb usb7: Manufacturer: Linux 3.11.0-rc6-next-20130822-x32 ohci_hcd
[    7.260068] usb usb7: SerialNumber: 0000:00:14.5
[    7.260337] hub 7-0:1.0: USB hub found
[    7.260349] hub 7-0:1.0: 2 ports detected
[    7.302658] device label data1 devid 1 transid 732944 /dev/sda6
[    7.318167] input: HDA ATI SB Front Headphone as /devices/pci0000:00/0000:00:14.2/sound/card1/input3
[    7.327520] input: HDA ATI SB Line Out as /devices/pci0000:00/0000:00:14.2/sound/card1/input4
[    7.336143] input: HDA ATI SB Line as /devices/pci0000:00/0000:00:14.2/sound/card1/input5
[    7.341960] usb 3-3: New USB device found, idVendor=093a, idProduct=2500
[    7.341965] usb 3-3: New USB device strings: Mfr=1, Product=2, SerialNumber=0
[    7.341968] usb 3-3: Product: USB OPTICAL MOUSE
[    7.341971] usb 3-3: Manufacturer: PIXART
[    7.366822] input: HDA ATI SB Front Mic as /devices/pci0000:00/0000:00:14.2/sound/card1/input6
[    7.375528] input: HDA ATI SB Rear Mic as /devices/pci0000:00/0000:00:14.2/sound/card1/input7
[    7.384485] hda_intel: Disabling MSI
[    7.388065] hda-intel 0000:01:00.1: Handle VGA-switcheroo audio client
[    7.479941] usb 4-1: new low-speed USB device number 2 using ohci-pci
[    7.555404] hidraw: raw HID events driver (C) Jiri Kosina
[    7.577103] usbcore: registered new interface driver usbhid
[    7.582771] usbhid: USB HID core driver
[    7.599694] input: PIXART USB OPTICAL MOUSE as /devices/pci0000:00/0000:00:12.0/usb3/3-3/3-3:1.0/input/input8
[    7.610038] hid-generic 0003:093A:2500.0001: input,hidraw0: USB HID v1.10 Mouse [PIXART USB OPTICAL MOUSE] on usb-0000:00:12.0-3/input0
[    7.657186] usb 4-1: New USB device found, idVendor=046d, idProduct=c31a
[    7.664012] usb 4-1: New USB device strings: Mfr=1, Product=2, SerialNumber=0
[    7.671176] usb 4-1: Product: USB Multimedia Keyboard
[    7.676296] usb 4-1: Manufacturer: Logitech
[    7.686372] input: Logitech USB Multimedia Keyboard as /devices/pci0000:00/0000:00:12.1/usb4/4-1/4-1:1.0/input/input9
[    7.697133] hid-generic 0003:046D:C31A.0002: input,hidraw1: USB HID v1.10 Keyboard [Logitech USB Multimedia Keyboard] on usb-0000:00:12.1-1/input0
[    7.721178] input: Logitech USB Multimedia Keyboard as /devices/pci0000:00/0000:00:12.1/usb4/4-1/4-1:1.1/input/input10
[    7.732025] hid-generic 0003:046D:C31A.0003: input,hidraw2: USB HID v1.10 Device [Logitech USB Multimedia Keyboard] on usb-0000:00:12.1-1/input1
[    8.207893] input: HDA NVidia HDMI/DP,pcm=9 as /devices/pci0000:00/0000:00:02.0/0000:01:00.1/sound/card2/input11
[    8.218353] input: HDA NVidia HDMI/DP,pcm=8 as /devices/pci0000:00/0000:00:02.0/0000:01:00.1/sound/card2/input12
[    8.228666] input: HDA NVidia HDMI/DP,pcm=7 as /devices/pci0000:00/0000:00:02.0/0000:01:00.1/sound/card2/input13
[    8.238924] input: HDA NVidia HDMI/DP,pcm=3 as /devices/pci0000:00/0000:00:02.0/0000:01:00.1/sound/card2/input14
[ ok done.
[....] Setting parameters of disc: (none)[ ok .
[....] Setting preliminary keymap...[ ok done.
[....] Activating swap...[ ok done.
[    8.993327] EXT4-fs (sdb1): re-mounted. Opts: (null)
[....] Checking root file system...fsck from util-linux 2.20.1
syst: clean, 118889/305216 files, 741708/1220608 blocks
[ ok done.
[    9.158248] EXT4-fs (sdb1): re-mounted. Opts: errors=remount-ro
[    9.298553] loop: module loaded
[info] Loading kernel module loop.
[info] Loading kernel module ipv6.
[info] Loading kernel module vboxdrv.
FATAL: Module vboxdrv not found.
[info] Loading kernel module vboxnetflt.
FATAL: Module vboxnetflt not found.
[info] Loading kernel module nouveau.
[....] Cleaning up temporary files... /tmp /lib/init/rw[ ok .
[....] Activating lvm and md swap...[ ok done.
[....] Checking file systems...fsck from util-linux 2.20.1
[ ok done.
[....] Mounting local filesystems...[ ok done.
[....] Activating swapfile swap...[ ok done.
[....] Cleaning up temporary files...[ ok .
[....] Setting kernel variables ...[ ok done.
[....] Configuring network interfaces...[ ok done.
[....] Cleaning up temporary files...[ ok .
[   10.661280] fuse init (API version 7.22)
[....] Setting up ALSA...[ ok done.
[   10.756400] r8169 0000:02:00.0 eth0: link down
[   10.756431] r8169 0000:02:00.0 eth0: link down
[   10.765557] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready
[info] Setting console screen modes.
setterm: cannot (un)set powersave mode: Inappropriate ioctl for device
[info] Skipping font and keymap setup (handled by console-setup).
[....] Setting up console font and keymap...[ ok done.
[....] Setting up X socket directories... /tmp/.X11-unix /tmp/.ICE-unix[ ok .
Give root password for maintenance
(or type Control-D to continue): [   13.185378] r8169 0000:02:00.0 eth0: link up
[   13.189731] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready

[~]# pm-suspend
[   24.962083] PM: Syncing filesystems ... done.
[   25.027307] Freezing user space processes ... (elapsed 0.001 seconds) done.
[   25.035447] Freezing remaining freezable tasks ... (elapsed 0.001 seconds) done.
[   25.044298] sd 6:0:0:0: [sdc] Synchronizing SCSI cache
[ mFFFsmpboot: CPU1: Stuck ??
[   41.114449] Error taking CPU1 up: -5
[   41.114487] smpboot: Booting Node 0 Processor 2 APIC 0x2
[   41.125433] [Firmware Bug]: cpu 2, try to use APIC500 (LVT offset 0) for vector 0x400, but the register is already in use for vector 0xf9 on another cpu
[   41.125434] perf: IBS APIC setup failed on cpu #2
[   41.127569] process: Switch to broadcast mode on CPU2
[   41.127669] microcode: CPU2: new patch_level=0x010000dc
[   41.127703] CPU2 is up
[   41.127759] smpboot: Booting Node 0 Processor 3 APIC 0x3
[   41.138706] [Firmware Bug]: cpu 3, try to use APIC500 (LVT offset 0) for vector 0x400, but the register is already in use for vector 0xf9 on another cpu
[   41.138707] perf: IBS APIC setup failed on cpu #3
[   41.140840] process: Switch to broadcast mode on CPU3
[   41.140916] microcode: CPU3: new patch_level=0x010000dc
[   41.140939] CPU3 is up
[   41.140994] smpboot: Booting Node 0 Processor 4 APIC 0x4
[   41.151939] [Firmware Bug]: cpu 4, try to use APIC500 (LVT offset 0) for vector 0x400, but the register is already in use for vector 0xf9 on another cpu
[   41.151940] perf: IBS APIC setup failed on cpu #4
[   41.154090] process: Switch to broadcast mode on CPU4
[   41.154168] microcode: CPU4: new patch_level=0x010000dc
[   41.154190] CPU4 is up
[   41.154248] smpboot: Booting Node 0 Processor 5 APIC 0x5
[   41.165194] [Firmware Bug]: cpu 5, try to use APIC500 (LVT offset 0) for vector 0x400, but the register is already in use for vector 0xf9 on another cpu
[   41.165195] perf: IBS APIC setup failed on cpu #5
[   41.167345] process: Switch to broadcast mode on CPU5
[   41.167439] microcode: CPU5: new patch_level=0x010000dc
[   41.167460] CPU5 is up
[   41.172976] ACPI: Waking up from system sleep state S3
[   41.189805] ohci-pci 0000:00:12.0: System wakeup disabled by ACPI
[   41.189838] ohci-pci 0000:00:12.1: System wakeup disabled by ACPI
[   41.205772] ehci-pci 0000:00:12.2: System wakeup disabled by ACPI
[   41.205809] ohci-pci 0000:00:13.0: System wakeup disabled by ACPI
[   41.205843] ohci-pci 0000:00:13.1: System wakeup disabled by ACPI
[   41.221768] ehci-pci 0000:00:13.2: System wakeup disabled by ACPI
[   41.237861] ohci-pci 0000:00:14.5: System wakeup disabled by ACPI
[   41.301857] PM: noirq resume of devices complete after 128.023 msecs
[   41.301960] PM: early resume of devices complete after 0.064 msecs
[   41.302142] nouveau  [     DRM] re-enabling device...
[   41.302155] r8169 0000:02:00.0: System wakeup disabled by ACPI
[   41.302174] nouveau  [     DRM] resuming kernel object tree...
[   41.302181] nouveau  [   VBIOS][0000:01:00.0] running init tables
[   41.304350] parport_pc 00:04: activated
[   41.305248] serial 00:06: activated
[   41.396012] nouveau  [     DRM] resuming client object trees...
[   41.396015] nouveau  [     DRM] resuming display...
[   41.753329] r8169 0000:02:00.0 eth0: link down
[   42.049601] ata6: SATA link down (SStatus 0 SControl 300)
[   42.055045] ata8: SATA link down (SStatus 0 SControl 300)
[   42.060477] ata4: SATA link down (SStatus 0 SControl 300)
[   42.221496] ata7: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
[   42.227696] ata5: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
[   42.233904] ata3: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
[   42.241737] ata7.00: configured for UDMA/133
[   42.246182] ata3.00: configured for UDMA/133
[   42.265487] sd 6:0:0:0: [sdc] Starting disk
[   42.269755] sd 2:0:0:0: [sda] Starting disk
[   42.339914] ata5.00: configured for UDMA/133
[   42.377492] sd 4:0:0:0: [sdb] Starting disk
[   43.777585] r8169 0000:02:00.0 eth0: link up
[  148.245215] SysRq : Emergency Remount R/O
[  150.916592] SysRq : Resetting
[   51.986460] PM: Syncing filesystems ... done.
[   52.923505] Freezing user space processes ... (elapsed 0.001 seconds) done.
[   52.931660] Freezing remaining freezable tasks ... (elapsed 0.001 seconds) done.
[   52.940484] sd 6:0:0:0: [sdc] Synchronizing SCSI cache
[ <line noise>  69.008708] smpboot: CPU1: Stuck ??
[   69.008754] Error taking CPU1 up: -5
[   69.008794] smpboot: Booting Node 0 Processor 2 APIC 0x2
[   69.019740] [Firmware Bug]: cpu 2, try to use APIC500 (LVT offset 0) for vector 0x400, but the register is already in use for vector 0xf9 on another cpu
[   69.019741] perf: IBS APIC setup failed on cpu #2
[   69.021876] process: Switch to broadcast mode on CPU2
[   69.021976] microcode: CPU2: new patch_level=0x010000dc
[   69.022009] CPU2 is up
[   69.022065] smpboot: Booting Node 0 Processor 3 APIC 0x3
[   69.023756] ------------[ cut here ]------------
[   69.023762] WARNING: CPU: 2 PID: 0 at kernel/time/tick-broadcast.c:667 tick_broadcast_oneshot_control+0x177/0x180()
[   69.023790] Modules linked in: ipt_MASQUERADE iptable_nat nf_conntrack_ipv4 nf_defrag_ipv4 nf_nat_ipv4 nf_nat nf_conntrack ip_tables x_tables bridge stp llc nouveau video mxm_wmi ttm drm_kms_helper drm loop fuse hid_generic usbhid snd_hda_codec_hdmi hid snd_hda_codec_via snd_hda_intel snd_hda_codec snd_cmipci gameport snd_mpu401_uart snd_opl3_lib snd_seq_midi snd_pcm snd_seq_midi_event snd_page_alloc snd_hwdep snd_rawmidi snd_seq kvm_amd snd_timer kvm snd_seq_device psmouse parport_pc acpi_cpufreq processor ohci_pci evdev snd serio_raw parport button pcspkr ohci_hcd sp5100_tco soundcore wmi i2c_piix4 asus_atk0110 thermal_sys k10temp sg sd_mod crc_t10dif ata_generic ehci_pci ahci ehci_hcd libahci pata_atiixp microcode libata usbcore r8169 usb_common mii scsi_mod
[   69.023792] CPU: 2 PID: 0 Comm: swapper/2 Not tainted 3.11.0-rc6-next-20130822-x32 #28
[   69.023793] Hardware name: System manufacturer System Product Name/M4A77T, BIOS 2202    08/19/2010
[   69.023796]  0000000000000009 ffffffff8153dda0 0000000000000000 ffffffff81044fb2
[   69.023797]  0000000000000002 0000000000000002 ffff880237c8cd80 0000000000000002
[   69.023798]  ffffffff8181f0c0 ffffffff810964c7 0000000000000004 ffff88022ccfffd8
[   69.023799] Call Trace:
[   69.023803]  [<ffffffff8153dda0>] ? dump_stack+0x41/0x51
[   69.023805]  [<ffffffff81044fb2>] ? warn_slowpath_common+0x72/0x90
[   69.023807]  [<ffffffff810964c7>] ? tick_broadcast_oneshot_control+0x177/0x180
[   69.023809]  [<ffffffff810950a8>] ? clockevents_notify+0x1c8/0x1f0
[   69.023811]  [<ffffffff8100a840>] ? amd_e400_idle+0x40/0xf0
[   69.023813]  [<ffffffff8108d418>] ? cpu_startup_entry+0xc8/0x250
[   69.023814]  [<ffffffff81095af0>] ? tick_check_new_device+0xd0/0xf0
[   69.023816]  [<ffffffff8102b1ae>] ? start_secondary+0x1ce/0x220
[   69.023817] ---[ end trace e47226b83af85cdc ]---
[   69.033010] [Firmware Bug]: cpu 3, try to use APIC500 (LVT offset 0) for vector 0x400, but the register is already in use for vector 0xf9 on another cpu
[   69.033010] perf: IBS APIC setup failed on cpu #3
[   69.035148] process: Switch to broadcast mode on CPU3
[   69.035214] microcode: CPU3: new patch_level=0x010000dc
[   69.035236] CPU3 is up
[   69.035292] smpboot: Booting Node 0 Processor 4 APIC 0x4
[   69.046237] [Firmware Bug]: cpu 4, try to use APIC500 (LVT offset 0) for vector 0x400, but the register is already in use for vector 0xf9 on another cpu
[   69.046237] perf: IBS APIC setup failed on cpu #4
[   69.048391] process: Switch to broadcast mode on CPU4
[   69.048479] microcode: CPU4: new patch_level=0x010000dc
[   69.048502] CPU4 is up
[   69.056153] ACPI: Waking up from system sleep state S3
[   69.071863] ohci-pci 0000:00:12.0: System wakeup disabled by ACPI
[   69.071897] ohci-pci 0000:00:12.1: System wakeup disabled by ACPI
[   69.087835] ehci-pci 0000:00:12.2: System wakeup disabled by ACPI
[   69.087877] ohci-pci 0000:00:13.0: System wakeup disabled by ACPI
[   69.087912] ohci-pci 0000:00:13.1: System wakeup disabled by ACPI
[   69.103838] ehci-pci 0000:00:13.2: System wakeup disabled by ACPI
[   69.119939] ohci-pci 0000:00:14.5: System wakeup disabled by ACPI
[   69.183957] PM: noirq resume of devices complete after 126.909 msecs
[   69.184032] PM: early resume of devices complete after 0.036 msecs
[   69.184860] nouveau  [     DRM] re-enabling device...
[   69.184875] nouveau  [     DRM] resuming kernel object tree...
[   69.184882] nouveau  [   VBIOS][0000:01:00.0] running init tables
[   69.185776] parport_pc 00:04: activated
[   69.186439] serial 00:06: activated
[   69.187830] r8169 0000:02:00.0: System wakeup disabled by ACPI
[   69.281295] nouveau  [     DRM] resuming client object trees...
[   69.281299] nouveau  [     DRM] resuming display...
[   69.507913] ata4: SATA link down (SStatus 0 SControl 300)
[   69.507958] ata8: SATA link down (SStatus 0 SControl 300)
[   69.508058] ata6: SATA link down (SStatus 0 SControl 300)
[   69.641109] r8169 0000:02:00.0 eth0: link down
[   69.679945] ata7: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
[   69.679982] ata3: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
[   69.680016] ata5: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
[   69.682425] ata3.00: configured for UDMA/133
[   69.682798] ata7.00: configured for UDMA/133
[   69.695991] sd 6:0:0:0: [sdc] Starting disk
[   69.696021] sd 2:0:0:0: [sda] Starting disk
[   69.777948] ata5.00: configured for UDMA/133
[   69.816022] sd 4:0:0:0: [sdb] Starting disk
[   70.137872] PM: resume of devices complete after 953.583 msecs
[   70.143766] Restarting tasks ... done.
[   70.147793] ------------[ cut here ]------------
[   70.147798] WARNING: CPU: 0 PID: 7 at arch/x86/kernel/smp.c:124 check_preempt_curr+0x65/0x90()
[   70.147824] Modules linked in: ipt_MASQUERADE iptable_nat nf_conntrack_ipv4 nf_defrag_ipv4 nf_nat_ipv4 nf_nat nf_conntrack ip_tables x_tables bridge stp llc nouveau video mxm_wmi ttm drm_kms_helper drm loop fuse hid_generic usbhid snd_hda_codec_hdmi hid snd_hda_codec_via snd_hda_intel snd_hda_codec snd_cmipci gameport snd_mpu401_uart snd_opl3_lib snd_seq_midi snd_pcm snd_seq_midi_event snd_page_alloc snd_hwdep snd_rawmidi snd_seq kvm_amd snd_timer kvm snd_seq_device psmouse parport_pc acpi_cpufreq processor ohci_pci evdev snd serio_raw parport button pcspkr ohci_hcd sp5100_tco soundcore wmi i2c_piix4 asus_atk0110 thermal_sys k10temp sg sd_mod crc_t10dif ata_generic ehci_pci ahci ehci_hcd libahci pata_atiixp microcode libata usbcore r8169 usb_common mii scsi_mod
[   70.147826] CPU: 0 PID: 7 Comm: migration/0 Tainted: G        W    3.11.0-rc6-next-20130822-x32 #28
[   70.147827] Hardware name: System manufacturer System Product Name/M4A77T, BIOS 2202    08/19/2010
[   70.147830]  0000000000000009 ffffffff8153dda0 0000000000000000 ffffffff81044fb2
[   70.147831]  ffff880237c52f00 ffff880237c52f00 ffff88022b72b7a0 0000000000000001
[   70.147832]  0000000000000000 ffffffff81070b55 ffff880237c12f00 ffffffff81071004
[   70.147833] Call Trace:
[   70.147837]  [<ffffffff8153dda0>] ? dump_stack+0x41/0x51
[   70.147839]  [<ffffffff81044fb2>] ? warn_slowpath_common+0x72/0x90
[   70.147840]  [<ffffffff81070b55>] ? check_preempt_curr+0x65/0x90
[   70.147841]  [<ffffffff81071004>] ? __migrate_task+0x134/0x150
[   70.147843]  [<ffffffff81071020>] ? __migrate_task+0x150/0x150
[   70.147844]  [<ffffffff81071034>] ? migration_cpu_stop+0x14/0x20
[   70.147846]  [<ffffffff810b3569>] ? cpu_stopper_thread+0x79/0x120
[   70.147847]  [<ffffffff815410c7>] ? __schedule+0x297/0x730
[   70.147850]  [<ffffffff8106c284>] ? smpboot_thread_fn+0xf4/0x1a0
[   70.147851]  [<ffffffff8106c190>] ? lg_local_lock+0x40/0x40
[   70.147853]  [<ffffffff81064acf>] ? kthread+0xaf/0xc0
[   70.147854]  [<ffffffff81064a20>] ? kthread_create_on_node+0x110/0x110
[   70.147855]  [<ffffffff8154956c>] ? ret_from_fork+0x7c/0xb0
[   70.147857]  [<ffffffff81064a20>] ? kthread_create_on_node+0x110/0x110
[   70.147858] ---[ end trace e47226b83af85cdd ]---
[   70.592230] SysRq : HELP : loglevel(0-9) reboot(b) crash(c) terminate-all-tasks(e) memory-full-oom-kill(f) kill-all-tasks(i) thaw-filesystems(j) sak(k) show-backtrace-all-active-cpus(l) show-memory-usage(m) nice-all-RT-tasks(n) poweroff(o) show-registers(p) show-all-timers(q) unraw(r) sync(s) show-task-states(t) unmount(u) force-fb(V) show-blocked-tasks(w) dump-ftrace-buffer(z) 
[   71.667932] r8169 0000:02:00.0 eth0: link up
[  108.034962] usb 4-1: USB disconnect, device number 2
[  108.313702] ------------[ cut here ]------------
[  108.318330] kernel BUG at drivers/cpufreq/cpufreq.c:80!
[  108.323547] invalid opcode: 0000 [#1] SMP 
[  108.327670] Modules linked in: ipt_MASQUERADE iptable_nat nf_conntrack_ipv4 nf_defrag_ipv4 nf_nat_ipv4 nf_nat nf_conntrack ip_tables x_tables bridge stp llc nouveau video mxm_wmi ttm drm_kms_helper drm loop fuse hid_generic usbhid snd_hda_codec_hdmi hid snd_hda_codec_via snd_hda_intel snd_hda_codec snd_cmipci gameport snd_mpu401_uart snd_opl3_lib snd_seq_midi snd_pcm snd_seq_midi_event snd_page_alloc snd_hwdep snd_rawmidi snd_seq kvm_amd snd_timer kvm snd_seq_device psmouse parport_pc acpi_cpufreq processor ohci_pci evdev snd serio_raw parport button pcspkr ohci_hcd sp5100_tco soundcore wmi i2c_piix4 asus_atk0110 thermal_sys k10temp sg sd_mod crc_t10dif ata_generic ehci_pci ahci ehci_hcd libahci pata_atiixp microcode libata usbcore r8169 usb_common mii scsi_mod
[  108.396280] CPU: 0 PID: 2570 Comm: cat Tainted: G        W    3.11.0-rc6-next-20130822-x32 #28
[  108.404882] Hardware name: System manufacturer System Product Name/M4A77T, BIOS 2202    08/19/2010
[  108.413824] task: ffff88022cb4c860 ti: ffff88022cb64000 task.ti: ffff88022cb64000
[  108.421293] RIP: 0010:[<ffffffff8142ea68>]  [<ffffffff8142ea68>] lock_policy_rwsem_write+0x38/0x40
[  108.430260] RSP: 0018:ffff88022cb65e98  EFLAGS: 00010246
[  108.435564] RAX: 0000000000000000 RBX: ffffffffffffffea RCX: 0000000000000009
[  108.442685] RDX: ffff880237c40000 RSI: ffffffff81881e80 RDI: 0000000000000001
[  108.449808] RBP: ffff8800c7878b08 R08: 0000000000000001 R09: 00000000ffffffe9
[  108.456931] R10: 0000000000003249 R11: 0000000000000246 R12: ffffffff81881e80
[  108.464052] R13: ffff88022cb20000 R14: 0000000000000009 R15: ffff88022b75e908
[  108.471175] FS:  00007f89bd60b700(0000) GS:ffff880237c00000(0000) knlGS:0000000000000000
[  108.479250] CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[  108.484987] CR2: 0000000001c00000 CR3: 0000000229278000 CR4: 00000000000007f0
[  108.492108] Stack:
[  108.494119]  ffffffff8142eb36 ffff88022c066d40 0000000000000009 ffffffff8167e9a0
[  108.501570]  ffff88022c066d60 ffff88022cb65f58 ffffffff811bb8d9 ffff8800c7878b08
[  108.509022]  0000000000000009 ffff88022b8af3c0 0000000000000009 0000000001c00000
[  108.516473] Call Trace:
[  108.518918]  [<ffffffff8142eb36>] ? store+0x36/0x80
[  108.523790]  [<ffffffff811bb8d9>] ? sysfs_write_file+0xb9/0x140
[  108.529707]  [<ffffffff8114eeea>] ? vfs_write+0xba/0x1f0
[  108.535017]  [<ffffffff8114edb5>] ? vfs_read+0xf5/0x170
[  108.540232]  [<ffffffff8114f943>] ? SyS_write+0x43/0xa0
[  108.545450]  [<ffffffff815437b2>] ? page_fault+0x22/0x30
[  108.550761]  [<ffffffff81549616>] ? system_call_fastpath+0x1a/0x1f
[  108.556937] Code: fd a0 29 8a 81 48 8b 04 10 48 85 c0 74 1d 8b 80 84 00 00 00 48 c7 c7 20 f9 00 00 48 03 3c c5 a0 29 8a 81 e8 2b 20 11 00 31 c0 c3 <0f> 0b 66 0f 1f 44 00 00 48 63 ff 48 c7 c0 58 f9 00 00 48 8b 14 
[  108.576883] RIP  [<ffffffff8142ea68>] lock_policy_rwsem_write+0x38/0x40
[  108.583502]  RSP <ffff88022cb65e98>
[  108.587031] ---[ end trace e47226b83af85cde ]---
[~]# ls
<list of files, etc, everything seems to work>

[Index of Archives]     [Linux Media]     [Linux Input]     [Linux Audio Users]     [Yosemite News]     [Linux Kernel]     [Linux SCSI]     [Old Linux USB Devel Archive]

  Powered by Linux