(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>
