BUG: blank screen - unless keyboard/synaptic combo
From: Niel Lambrechts
Date: Tue May 19 2009 - 14:38:46 EST
Hi there,
I have a strange problem in that during a resume from hibernate my text
console (or X) remains blank - that is, until I simultaneously press a
keyboard key and touch the synaptic pad.
It is not sufficient to press just one or the other, it needs to be done
simultaneously and it resumes fine after doing this. I might be wrong
but to me actually looks like the first resume script
(/usr/lib/pm-utils/sleep.d/99info) is only invoked after this
"recovery", since I notice a "Resuming" message without a command-line
prompt in the first few seconds after recovery, so it has just started
or is still executing the resume scripts.
After the grub menu, the laptop will actually display vesafb output
(vga=791) until just after 100% loading of the hibernation image, then
the display will go blank. The laptop is a Lenovo W500 with 'hybrid'
graphics, and the Intel Mobile 4 integrated adapter is set to active.
This happens in 2.6.28, 2.6.29 and up to 2.6.30-rc6. I have had a slight
few occurrences where this did not happen (not recently), but I could
never find an explanation for the difference.
I eventually gave up on bisecting this, but it definitely still happened
in bisects just after ext4dev was renamed to ext4 somewhere after 2.6.27.
Any ideas on what I can do next? I have even tried enabling extra ACPI
debugging, but I simply do not see any debugging messages happen during
that key/synaptic combination.
Would the fact that it happens in a text console session perhaps imply
that it is ACPI or vesafb related?
Thanks in advance!
Niel
2009-05-19 17:41:13.992560206 +0200: running 'hibernate'/'hibernate'/'' hooks.
===== 2009-05-19 17:41:14.087960120 running hook: /usr/lib/pm-utils/sleep.d/00clear =====
===== 2009-05-19 17:41:14.154333370 running hook: /usr/lib/pm-utils/sleep.d/01logging =====
hibernate initiated: Tue May 19 17:41:14 SAST 2009
Linux linux-7vph 2.6.30-rc6-pae #47 SMP Mon May 18 00:42:36 SAST 2009 i686 i686 i386 GNU/Linux
kernel command line: 'root=/dev/disk/by-id/ata-HITACHI_HTS722020K9SA00_FDE_080726DP0470DTGMUNPC-part6 resume=/dev/disk/by-id/ata-HITACHI_HTS722020K9SA00_FDE_080726DP0470DTGMUNPC-part5 splash=silent showopts vga=791'
Module Size Used by
usbhid 31700 0
hid 34812 1 usbhid
nls_utf8 1500 0
tun 11476 0
i915 144076 0
drm 125048 1 i915
i2c_algo_bit 4792 1 i915
af_packet 15580 0
ip6t_LOG 4948 8
xt_tcpudp 2536 10
xt_pkttype 1368 3
ipt_LOG 4912 9
xt_limit 2044 17
snd_pcm_oss 38248 0
snd_mixer_oss 13788 1 snd_pcm_oss
snd_seq 45068 0
binfmt_misc 7204 1
ip6t_REJECT 3140 3
nf_conntrack_ipv6 11540 6
ip6table_raw 1744 1
xt_NOTRACK 1372 4
ipt_REJECT 2516 3
xt_state 1784 12
iptable_raw 1752 1
iptable_filter 2268 1
cpufreq_conservative 6580 0
cpufreq_userspace 2692 0
ip6table_mangle 2196 0
cpufreq_powersave 1300 0
acpi_cpufreq 7600 0
nf_conntrack_netbios_ns 2100 0
nf_conntrack_ipv4 7244 6
speedstep_lib 3812 0
nf_conntrack 49016 5 nf_conntrack_ipv6,xt_NOTRACK,xt_state,nf_conntrack_netbios_ns,nf_conntrack_ipv4
nf_defrag_ipv4 1564 1 nf_conntrack_ipv4
ip_tables 10032 2 iptable_raw,iptable_filter
ip6table_filter 2180 1
ip6_tables 11016 4 ip6t_LOG,ip6table_raw,ip6table_mangle,ip6table_filter
x_tables 12092 11 ip6t_LOG,xt_tcpudp,xt_pkttype,ipt_LOG,xt_limit,ip6t_REJECT,xt_NOTRACK,ipt_REJECT,xt_state,ip_tables,ip6_tables
ipv6 216400 27 ip6t_REJECT,nf_conntrack_ipv6,ip6table_mangle
microcode 8296 0
fuse 48456 9
loop 13380 0
dm_mod 47072 0
snd_usb_audio 75372 0
snd_usb_lib 13820 1 snd_usb_audio
snd_rawmidi 18552 1 snd_usb_lib
snd_seq_device 5980 2 snd_seq,snd_rawmidi
arc4 1580 0
ecb 2396 0
iwlcore 80604 0
thinkpad_acpi 56216 0
rfkill 8652 3 iwlcore,thinkpad_acpi
ohci1394 25892 0
led_class 3496 2 iwlcore,thinkpad_acpi
mac80211 148200 1 iwlcore
battery 10332 0
ac 4156 0
video 19160 1 i915
ieee1394 67476 1 ohci1394
nvram 6004 1 thinkpad_acpi
rtc_cmos 9444 0
rtc_core 14588 1 rtc_cmos
rtc_lib 2204 1 rtc_core
output 2416 1 video
wmi 5596 0
snd_hda_codec_conexant 23712 1
snd_hda_intel 23972 0
snd_hda_codec 52592 2 snd_hda_codec_conexant,snd_hda_intel
snd_hwdep 6416 2 snd_usb_audio,snd_hda_codec
snd_pcm 63392 4 snd_pcm_oss,snd_usb_audio,snd_hda_intel,snd_hda_codec
snd_timer 17972 2 snd_seq,snd_pcm
snd 49816 13 snd_pcm_oss,snd_mixer_oss,snd_seq,snd_usb_audio,snd_usb_lib,snd_rawmidi,snd_seq_device,snd_hda_codec_conexant,snd_hda_intel,snd_hda_codec,snd_hwdep,snd_pcm,snd_timer
soundcore 5416 1 snd
button 5380 0
iTCO_wdt 10228 0
intel_agp 23076 1
snd_page_alloc 7472 2 snd_hda_intel,snd_pcm
e1000e 100340 0
agpgart 26992 2 drm,intel_agp
iTCO_vendor_support 2708 1 iTCO_wdt
cfg80211 53828 2 iwlcore,mac80211
i2c_i801 8384 0
i2c_core 17772 4 i915,drm,i2c_algo_bit,i2c_i801
sr_mod 12424 0
joydev 8964 0
cdrom 28916 1 sr_mod
sg 25260 0
sd_mod 24140 7
ehci_hcd 30436 0
uhci_hcd 19364 0
usbcore 119720 6 usbhid,snd_usb_audio,snd_usb_lib,ehci_hcd,uhci_hcd
ext4 189060 1
jbd2 55080 1 ext4
crc16 1608 1 ext4
edd 7180 0
ext3 110744 1
mbcache 6256 2 ext4,ext3
jbd 46980 1 ext3
fan 4080 0
ahci 28240 6
libata 137636 1 ahci
scsi_mod 125084 4 sr_mod,sg,sd_mod,libata
thermal 13944 0
processor 39740 3 acpi_cpufreq
total used free shared buffers cached
Mem: 2018872 863040 1155832 0 110480 716580
-/+ buffers/cache: 35980 1982892
Swap: 2099328 10812 2088516
===== 2009-05-19 17:41:14.203097953 running hook: /usr/lib/pm-utils/sleep.d/02rtcwake =====
rtcwake alarm not enabled in /etc/pm/config.d/rtcwake.config, doing nothing...
===== 2009-05-19 17:41:14.216141442 running hook: /usr/lib/pm-utils/sleep.d/05led =====
===== 2009-05-19 17:41:14.227658409 running hook: /usr/lib/pm-utils/sleep.d/06autofs =====
===== 2009-05-19 17:41:14.352373002 running hook: /usr/lib/pm-utils/sleep.d/10NetworkManager =====
method return sender=:1.6 -> dest=:1.424 reply_serial=2
===== 2009-05-19 17:41:14.467820843 running hook: /usr/lib/pm-utils/sleep.d/30s2disk-check =====
INFO: checking for suspend-to-disk prerequisites...
using userspace suspend method
setting resume device to /dev/sda5
setting image size to 930296217
adding these parameters from /etc/suspend.conf:
===== 2009-05-19 17:41:14.519640212 running hook: /usr/lib/pm-utils/sleep.d/45pcmcia =====
ejecting PCMCIA cards...
===== 2009-05-19 17:41:14.549873943 running hook: /usr/lib/pm-utils/sleep.d/50modules =====
===== 2009-05-19 17:41:14.586688750 running hook: /usr/lib/pm-utils/sleep.d/80acpi-fan =====
===== 2009-05-19 17:41:14.597395280 running hook: /usr/lib/pm-utils/sleep.d/80videobios =====
===== 2009-05-19 17:41:14.608098318 running hook: /usr/lib/pm-utils/sleep.d/94cpufreq =====
===== 2009-05-19 17:41:14.658163206 running hook: /usr/lib/pm-utils/sleep.d/95led =====
===== 2009-05-19 17:41:14.661601422 running hook: /usr/lib/pm-utils/sleep.d/95packagekit =====
===== 2009-05-19 17:41:14.680630798 running hook: /usr/lib/pm-utils/sleep.d/98video =====
===== 2009-05-19 17:41:14.704497938 running hook: /usr/lib/pm-utils/sleep.d/99Zgrub =====
INFO: running prepare-grub
Skipping grub entry #2, because it has the noresume option
Skipping grub entry #4, because it has the noresume option
Skipping grub entry #7, because it has the noresume option
Skipping grub entry #10, because it has the noresume option
stat: cannot stat `UUID=3a6813e1-ea3c-403b-8b0a-cc8577e07d76': No such file or directory
Skipping grub entry #12, because its root= parameter (UUID=3a6813e1-ea3c-403b-8b0a-cc8577e07d76)
does not match the current root device (/dev/sda6).
running kernel is grub menu entry 0 (vmlinuz-2.6.30-rc6-pae)
preparing boot-loader: selecting entry 0, kernel /boot/2.6.30-rc6-pae
grub-once: saving original /boot/grub/default
running '/usr/sbin/grubonce 0'
Using entry #0: openSUSE 11.1 - 2.6.30-rc6
time needed for sync: 0.0 seconds, time needed for grub: 0.0 seconds.
===== 2009-05-19 17:41:14.837061431 running hook: /usr/lib/pm-utils/sleep.d/99info =====
2009-05-19 17:41:14.840612580: done running hibernate/hibernate hooks.
+ /usr/sbin/s2disk --config /var/lib/s2disk.conf
+ RET=0
+ set +x
2009-05-19 18:36:28.616375442 +0200: running 'hibernate'/'thaw'/'reverse' hooks.
===== 2009-05-19 18:36:28.642346413 running hook: /usr/lib/pm-utils/sleep.d/99info =====
===== 2009-05-19 18:36:28.649111310 running hook: /usr/lib/pm-utils/sleep.d/99Zgrub =====
INFO: running grub-once-restore
restoring original /boot/grub/default
===== 2009-05-19 18:36:28.655889826 running hook: /usr/lib/pm-utils/sleep.d/98video =====
===== 2009-05-19 18:36:28.664100717 running hook: /usr/lib/pm-utils/sleep.d/95packagekit =====
method return sender=:1.426 -> dest=:1.425 reply_serial=2
===== 2009-05-19 18:36:29.323587290 running hook: /usr/lib/pm-utils/sleep.d/95led =====
===== 2009-05-19 18:36:29.327582141 running hook: /usr/lib/pm-utils/sleep.d/94cpufreq =====
===== 2009-05-19 18:36:29.341824106 running hook: /usr/lib/pm-utils/sleep.d/80videobios =====
===== 2009-05-19 18:36:29.357218591 running hook: /usr/lib/pm-utils/sleep.d/80acpi-fan =====
===== 2009-05-19 18:36:29.362773837 running hook: /usr/lib/pm-utils/sleep.d/50modules =====
===== 2009-05-19 18:36:29.374189325 running hook: /usr/lib/pm-utils/sleep.d/45pcmcia =====
inserting PCMCIA cards...
===== 2009-05-19 18:36:29.380685891 running hook: /usr/lib/pm-utils/sleep.d/30s2disk-check =====
===== 2009-05-19 18:36:29.386997029 running hook: /usr/lib/pm-utils/sleep.d/10NetworkManager =====
method return sender=:1.6 -> dest=:1.428 reply_serial=2
===== 2009-05-19 18:36:29.490646653 running hook: /usr/lib/pm-utils/sleep.d/06autofs =====
===== 2009-05-19 18:36:29.514802724 running hook: /usr/lib/pm-utils/sleep.d/05led =====
===== 2009-05-19 18:36:29.526302717 running hook: /usr/lib/pm-utils/sleep.d/02rtcwake =====
===== 2009-05-19 18:36:29.538773715 running hook: /usr/lib/pm-utils/sleep.d/01logging =====
===== 2009-05-19 18:36:29.549998534 running hook: /usr/lib/pm-utils/sleep.d/00clear =====
2009-05-19 18:36:29.572226566: done running hibernate/thaw hooks.
May 19 17:41:13 linux-7vph pm-hibernate[19986]: Entering hibernate. In case of problems, please check /var/log/pm-suspend.log
May 19 17:41:14 linux-7vph avahi-daemon[2470]: Withdrawing address record for fe80::21c:25ff:fe95:93ec on eth0.
May 19 18:35:53 linux-7vph -- MARK --
May 19 18:35:53 linux-7vph kernel: Syncing filesystems ... done.
May 19 18:35:53 linux-7vph syslog-ng[2838]: Log statistics; dropped='pipe(/dev/xconsole)=0', dropped='pipe(/dev/tty10)=0', processed='center(queued)=3560', processed='center(received)=2835', processed='destination(newsnotice)=0', processed='destination(acpid)=10', processed='destination(firewall)=497', processed='destination(null)=239', processed='destination(mail)=6', processed='destination(mailinfo)=6', processed='destination(console)=235', processed='destination(newserr)=0', processed='destination(newscrit)=0', processed='destination(messages)=1391', processed='destination(mailwarn)=0', processed='destination(localmessages)=0', processed='destination(netmgm)=692', processed='destination(mailerr)=0', processed='destination(xconsole)=235', processed='destination(warn)=249', processed='source(src)=2835'
May 19 18:35:53 linux-7vph kernel: Freezing user space processes ... (elapsed 0.00 seconds) done.
May 19 18:35:53 linux-7vph kernel: Freezing remaining freezable tasks ... (elapsed 0.00 seconds) done.
May 19 18:35:53 linux-7vph kernel: PM: Shrinking memory... done (4220 pages freed)
May 19 18:35:53 linux-7vph kernel: PM: Freed 16880 kbytes in 0.24 seconds (70.33 MB/s)
May 19 18:35:53 linux-7vph kernel: Suspending console(s) (use no_console_suspend to debug)
May 19 18:35:53 linux-7vph kernel: sd 0:0:0:0: [sda] Synchronizing SCSI cache
May 19 18:35:53 linux-7vph kernel: ACPI handle has no context!
May 19 18:35:53 linux-7vph kernel: ehci_hcd 0000:00:1d.7: PCI INT D disabled
May 19 18:35:53 linux-7vph kernel: ehci_hcd 0000:00:1d.7: PME# disabled
May 19 18:35:53 linux-7vph kernel: uhci_hcd 0000:00:1d.2: PCI INT C disabled
May 19 18:35:53 linux-7vph kernel: uhci_hcd 0000:00:1d.1: PCI INT B disabled
May 19 18:35:53 linux-7vph kernel: uhci_hcd 0000:00:1d.0: PCI INT A disabled
May 19 18:35:53 linux-7vph kernel: HDA Intel 0000:00:1b.0: PCI INT B disabled
May 19 18:35:53 linux-7vph kernel: ehci_hcd 0000:00:1a.7: PCI INT D disabled
May 19 18:35:53 linux-7vph kernel: ehci_hcd 0000:00:1a.7: PME# disabled
May 19 18:35:53 linux-7vph kernel: uhci_hcd 0000:00:1a.2: PCI INT C disabled
May 19 18:35:53 linux-7vph kernel: uhci_hcd 0000:00:1a.1: PCI INT B disabled
May 19 18:35:53 linux-7vph kernel: uhci_hcd 0000:00:1a.0: PCI INT A disabled
May 19 18:35:53 linux-7vph kernel: e1000e 0000:00:19.0: PCI INT A disabled
May 19 18:35:53 linux-7vph kernel: e1000e 0000:00:19.0: PME# enabled
May 19 18:35:53 linux-7vph kernel: e1000e 0000:00:19.0: wake-up capability enabled by ACPI
May 19 18:35:53 linux-7vph kernel: ACPI: Preparing to enter system sleep state S4
May 19 18:35:53 linux-7vph kernel: PM: Saving platform NVS memory
May 19 18:35:53 linux-7vph kernel: Disabling non-boot CPUs ...
May 19 18:35:53 linux-7vph kernel: CPU 1 is now offline
May 19 18:35:53 linux-7vph kernel: SMP alternatives: switching to UP code
May 19 18:35:53 linux-7vph kernel: CPU0 attaching NULL sched-domain.
May 19 18:35:53 linux-7vph kernel: CPU1 attaching NULL sched-domain.
May 19 18:35:53 linux-7vph kernel: CPU0 attaching NULL sched-domain.
May 19 18:35:53 linux-7vph kernel: CPU1 is down
May 19 18:35:53 linux-7vph kernel: Extended CMOS year: 2000
May 19 18:35:53 linux-7vph kernel: PM: Creating hibernation image:
May 19 18:35:53 linux-7vph kernel: PM: Need to copy 225213 pages
May 19 18:35:53 linux-7vph kernel: x86 PAT enabled: cpu 0, old 0x7040600070406, new 0x7010600070106
May 19 18:35:53 linux-7vph kernel: Intel machine check architecture supported.
May 19 18:35:53 linux-7vph kernel: Intel machine check reporting enabled on CPU#0.
May 19 18:35:53 linux-7vph kernel: PM: Restoring platform NVS memory
May 19 18:35:53 linux-7vph kernel: Extended CMOS year: 2000
May 19 18:35:53 linux-7vph kernel: Enabling non-boot CPUs ...
May 19 18:35:53 linux-7vph kernel: SMP alternatives: switching to SMP code
May 19 18:35:53 linux-7vph kernel: Booting processor 1 APIC 0x1 ip 0x6000
May 19 18:35:53 linux-7vph kernel: Initializing CPU#1
May 19 18:35:53 linux-7vph kernel: Calibrating delay using timer specific routine.. 13236.70 BogoMIPS (lpj=26473411)
May 19 18:35:53 linux-7vph kernel: CPU: L1 I cache: 32K, L1 D cache: 32K
May 19 18:35:53 linux-7vph kernel: CPU: L2 cache: 6144K
May 19 18:35:53 linux-7vph kernel: CPU: Physical Processor ID: 0
May 19 18:35:53 linux-7vph kernel: CPU: Processor Core ID: 1
May 19 18:35:53 linux-7vph kernel: Intel machine check architecture supported.
May 19 18:35:53 linux-7vph kernel: Intel machine check reporting enabled on CPU#1.
May 19 18:35:53 linux-7vph kernel: x86 PAT enabled: cpu 1, old 0x7040600070406, new 0x7010600070106
May 19 18:35:53 linux-7vph kernel: CPU1: Intel(R) Core(TM)2 Duo CPU T9400 @ 2.53GHz stepping 06
May 19 18:35:53 linux-7vph kernel: CPU0 attaching NULL sched-domain.
May 19 18:35:53 linux-7vph kernel: Switched to high resolution mode on CPU 1
May 19 18:35:53 linux-7vph kernel: CPU0 attaching sched-domain:
May 19 18:35:53 linux-7vph kernel: domain 0: span 0-1 level MC
May 19 18:35:53 linux-7vph kernel: groups: 0 1
May 19 18:35:53 linux-7vph kernel: CPU1 attaching sched-domain:
May 19 18:35:53 linux-7vph kernel: domain 0: span 0-1 level MC
May 19 18:35:53 linux-7vph kernel: groups: 1 0
May 19 18:35:53 linux-7vph kernel: microcode: failed to init CPU1
May 19 18:35:53 linux-7vph kernel: CPU1 is up
May 19 18:35:53 linux-7vph kernel: ACPI: Waking up from system sleep state S4
May 19 18:35:53 linux-7vph kernel: pci 0000:00:02.0: restoring config space at offset 0x1 (was 0x900007, writing 0x900403)
May 19 18:35:53 linux-7vph kernel: uhci_hcd 0000:00:1a.0: power state changed by ACPI to D0
May 19 18:35:53 linux-7vph kernel: uhci_hcd 0000:00:1a.2: power state changed by ACPI to D0
May 19 18:35:53 linux-7vph kernel: HDA Intel 0000:00:1b.0: restoring config space at offset 0x1 (was 0x100106, writing 0x100102)
May 19 18:35:53 linux-7vph kernel: uhci_hcd 0000:00:1d.0: power state changed by ACPI to D0
May 19 18:35:53 linux-7vph kernel: ahci 0000:00:1f.2: restoring config space at offset 0x1 (was 0x2b00403, writing 0x2b00407)
May 19 18:35:53 linux-7vph kernel: pci 0000:03:00.0: restoring config space at offset 0x1 (was 0x100106, writing 0x100102)
May 19 18:35:53 linux-7vph kernel: pci 0000:00:02.0: PME# disabled
May 19 18:35:53 linux-7vph kernel: pci 0000:00:02.0: setting latency timer to 64
May 19 18:35:53 linux-7vph kernel: pci 0000:00:02.1: PME# disabled
May 19 18:35:53 linux-7vph kernel: pci 0000:00:03.0: PME# disabled
May 19 18:35:53 linux-7vph kernel: e1000e 0000:00:19.0: PCI INT A -> GSI 20 (level, low) -> IRQ 20
May 19 18:35:53 linux-7vph kernel: e1000e 0000:00:19.0: pci_enable_pcie_error_reporting failed 0xfffffffb
May 19 18:35:53 linux-7vph kernel: e1000e 0000:00:19.0: setting latency timer to 64
May 19 18:35:53 linux-7vph kernel: e1000e 0000:00:19.0: wake-up capability disabled by ACPI
May 19 18:35:53 linux-7vph kernel: e1000e 0000:00:19.0: PME# disabled
May 19 18:35:53 linux-7vph kernel: e1000e 0000:00:19.0: wake-up capability disabled by ACPI
May 19 18:35:53 linux-7vph kernel: e1000e 0000:00:19.0: PME# disabled
May 19 18:35:53 linux-7vph kernel: e1000e 0000:00:19.0: irq 29 for MSI/MSI-X
May 19 18:35:53 linux-7vph kernel: uhci_hcd 0000:00:1a.0: PCI INT A -> GSI 20 (level, low) -> IRQ 20
May 19 18:35:53 linux-7vph kernel: uhci_hcd 0000:00:1a.0: setting latency timer to 64
May 19 18:35:53 linux-7vph kernel: uhci_hcd 0000:00:1a.1: PCI INT B -> GSI 21 (level, low) -> IRQ 21
May 19 18:35:53 linux-7vph kernel: uhci_hcd 0000:00:1a.1: setting latency timer to 64
May 19 18:35:53 linux-7vph kernel: uhci_hcd 0000:00:1a.2: PCI INT C -> GSI 22 (level, low) -> IRQ 22
May 19 18:35:53 linux-7vph kernel: uhci_hcd 0000:00:1a.2: setting latency timer to 64
May 19 18:35:53 linux-7vph kernel: ehci_hcd 0000:00:1a.7: PME# disabled
May 19 18:35:53 linux-7vph kernel: ehci_hcd 0000:00:1a.7: PCI INT D -> GSI 23 (level, low) -> IRQ 23
May 19 18:35:53 linux-7vph kernel: ehci_hcd 0000:00:1a.7: setting latency timer to 64
May 19 18:35:53 linux-7vph kernel: ehci_hcd 0000:00:1a.7: PME# disabled
May 19 18:35:53 linux-7vph kernel: HDA Intel 0000:00:1b.0: PCI INT B -> GSI 17 (level, low) -> IRQ 17
May 19 18:35:53 linux-7vph kernel: HDA Intel 0000:00:1b.0: setting latency timer to 64
May 19 18:35:53 linux-7vph kernel: uhci_hcd 0000:00:1d.0: PCI INT A -> GSI 16 (level, low) -> IRQ 16
May 19 18:35:53 linux-7vph kernel: uhci_hcd 0000:00:1d.0: setting latency timer to 64
May 19 18:35:53 linux-7vph kernel: uhci_hcd 0000:00:1d.1: PCI INT B -> GSI 17 (level, low) -> IRQ 17
May 19 18:35:53 linux-7vph kernel: uhci_hcd 0000:00:1d.1: setting latency timer to 64
May 19 18:35:53 linux-7vph kernel: uhci_hcd 0000:00:1d.2: PCI INT C -> GSI 18 (level, low) -> IRQ 18
May 19 18:35:53 linux-7vph kernel: uhci_hcd 0000:00:1d.2: setting latency timer to 64
May 19 18:35:53 linux-7vph kernel: ehci_hcd 0000:00:1d.7: PME# disabled
May 19 18:35:53 linux-7vph kernel: ehci_hcd 0000:00:1d.7: PCI INT D -> GSI 19 (level, low) -> IRQ 19
May 19 18:35:53 linux-7vph kernel: ehci_hcd 0000:00:1d.7: setting latency timer to 64
May 19 18:35:53 linux-7vph kernel: ehci_hcd 0000:00:1d.7: PME# disabled
May 19 18:35:53 linux-7vph kernel: pci 0000:00:1e.0: setting latency timer to 64
May 19 18:35:53 linux-7vph kernel: ahci 0000:00:1f.2: setting latency timer to 64
May 19 18:35:53 linux-7vph kernel: pci 0000:03:00.0: PME# disabled
May 19 18:35:53 linux-7vph kernel: pci 0000:15:00.0: PCI INT A -> GSI 16 (level, low) -> IRQ 16
May 19 18:35:53 linux-7vph kernel: ohci1394: fw-host0: OHCI-1394 1.1 (PCI): IRQ=[17] MMIO=[f4801000-f48017ff] Max Packet=[2048] IR/IT contexts=[4/4]
May 19 18:35:53 linux-7vph kernel: pci 0000:15:00.2: PME# disabled
May 19 18:35:53 linux-7vph kernel: pci 0000:15:00.3: PME# disabled
May 19 18:35:53 linux-7vph kernel: pci 0000:15:00.4: PME# disabled
May 19 18:35:53 linux-7vph kernel: pci 0000:15:00.5: PME# disabled
May 19 18:35:53 linux-7vph kernel: sd 0:0:0:0: [sda] Starting disk
May 19 18:35:53 linux-7vph kernel: ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
May 19 18:35:53 linux-7vph kernel: ata2: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
May 19 18:35:53 linux-7vph kernel: ata1.00: ACPI cmd ef/02:00:00:00:00:a0 succeeded
May 19 18:35:53 linux-7vph kernel: ata1.00: ACPI cmd f5/00:00:00:00:00:a0 filtered out
May 19 18:35:53 linux-7vph kernel: ata1.00: ACPI cmd ef/5f:00:00:00:00:a0 succeeded
May 19 18:35:53 linux-7vph kernel: ata1.00: ACPI cmd ef/10:03:00:00:00:a0 filtered out
May 19 18:35:53 linux-7vph kernel: ata1.00: ACPI cmd ef/02:00:00:00:00:a0 succeeded
May 19 18:35:53 linux-7vph kernel: ata1.00: ACPI cmd f5/00:00:00:00:00:a0 filtered out
May 19 18:35:53 linux-7vph kernel: ata1.00: ACPI cmd ef/5f:00:00:00:00:a0 succeeded
May 19 18:35:53 linux-7vph kernel: ata1.00: ACPI cmd ef/10:03:00:00:00:a0 filtered out
May 19 18:35:53 linux-7vph kernel: ata1.00: configured for UDMA/133
May 19 18:35:53 linux-7vph kernel: ata2.00: ACPI cmd e3/00:1f:00:00:00:a0 succeeded
May 19 18:35:53 linux-7vph kernel: ata2.00: ACPI cmd e3/00:02:00:00:00:a0 succeeded
May 19 18:35:53 linux-7vph kernel: ata2.00: ACPI cmd e3/00:1f:00:00:00:a0 succeeded
May 19 18:35:53 linux-7vph kernel: ata2.00: ACPI cmd e3/00:02:00:00:00:a0 succeeded
May 19 18:35:53 linux-7vph kernel: ata1: exception Emask 0x10 SAct 0x0 SErr 0x0 action 0x9 t4
May 19 18:35:53 linux-7vph kernel: ata1: irq_stat 0x00400040, connection status changed
May 19 18:35:53 linux-7vph kernel: ata1.00: configured for UDMA/133
May 19 18:35:53 linux-7vph kernel: ata1: EH complete
May 19 18:35:53 linux-7vph kernel: ata2.00: configured for UDMA/133
May 19 18:35:53 linux-7vph kernel: ata2: exception Emask 0x10 SAct 0x0 SErr 0x0 action 0x9 t4
May 19 18:35:53 linux-7vph kernel: ata2: irq_stat 0x40000001
May 19 18:35:53 linux-7vph kernel: ata2.00: configured for UDMA/133
May 19 18:35:53 linux-7vph kernel: ata2: EH complete
May 19 18:35:53 linux-7vph kernel: pci 0000:00:02.0: restoring config space at offset 0x1 (was 0x900407, writing 0x900403)
May 19 18:35:53 linux-7vph kernel: pci 0000:00:02.0: setting latency timer to 64
May 19 18:35:53 linux-7vph kernel: Restarting tasks ... done.
May 19 18:36:29 linux-7vph kernel: e1000e 0000:00:19.0: irq 29 for MSI/MSI-X
May 19 18:36:29 linux-7vph kernel: e1000e 0000:00:19.0: irq 29 for MSI/MSI-X
May 19 18:36:29 linux-7vph kernel: ADDRCONF(NETDEV_UP): eth0: link is not ready
May 19 18:36:40 linux-7vph init: Switching to runlevel: 5