FAQ Search Today's Posts Mark Forums Read
» Video Reviews

» Linux Archive

Linux-archive is a website aiming to archive linux email lists and to make them easily accessible for linux users/developers.


» Sponsor

» Partners

» Sponsor

Go Back   Linux Archive > Debian > Debian Kernel

 
 
LinkBack Thread Tools
 
Old 12-17-2011, 03:17 PM
Luca Capello
 
Default Bug#652475: linux-image-3.1.0-1-amd64: USB autosuspend goes crazy filling the log files

Package: linux-2.6
Version: 3.1.1-1
Severity: normal

Hi there!

There is a problem somewhere with USB autosuspending on my ThinkPad X60.
When enabled through powertop, the kernel splits out messages different
times every seconds, thus filling the log files:
=====
$ ls -lh /var/log/debug* /var/log/kern.log* /var/log/messages* /var/log/syslog*
-rw-r----- 1 root adm 302M Dec 15 21:25 /var/log/debug
-rw-r----- 1 root adm 157K Nov 25 15:05 /var/log/debug.1
-rw-r----- 1 root adm 90 Nov 15 13:35 /var/log/debug.2.gz
-rw-r----- 1 root adm 1.5M Oct 29 22:32 /var/log/debug.3.gz
-rw-r----- 1 root adm 63 Oct 23 11:16 /var/log/debug.4.gz
-rw-r----- 1 root adm 697M Dec 15 21:25 /var/log/kern.log
-rw-r----- 1 root adm 601K Nov 25 18:00 /var/log/kern.log.1
-rw-r----- 1 root adm 96 Nov 11 10:07 /var/log/kern.log.2.gz
-rw-r----- 1 root adm 227 Oct 31 18:14 /var/log/kern.log.3.gz
-rw-r----- 1 root adm 3.4M Oct 30 03:06 /var/log/kern.log.4.gz
-rw-r----- 1 root adm 395M Dec 15 21:25 /var/log/messages
-rw-r----- 1 root adm 844 Nov 27 06:25 /var/log/messages.1
-rw-r----- 1 root adm 68K Nov 26 06:25 /var/log/messages.2.gz
-rw-r----- 1 root adm 512 Nov 13 06:25 /var/log/messages.3.gz
-rw-r----- 1 root adm 843 Nov 6 06:25 /var/log/messages.4.gz
-rw-r----- 1 root adm 698M Dec 15 21:25 /var/log/syslog
-rw-r----- 1 root adm 58K Nov 27 06:25 /var/log/syslog.1
-rw-r----- 1 root adm 137K Nov 26 06:25 /var/log/syslog.2.gz
-rw-r----- 1 root adm 6.2K Nov 19 06:25 /var/log/syslog.3.gz
-rw-r----- 1 root adm 47K Nov 18 06:25 /var/log/syslog.4.gz
-rw-r----- 1 root adm 2.0K Nov 16 06:25 /var/log/syslog.5.gz
-rw-r----- 1 root adm 7.1K Nov 15 06:25 /var/log/syslog.6.gz
-rw-r----- 1 root adm 16K Nov 14 06:25 /var/log/syslog.7.gz

$ cd /var/log
$ for I in debug kern.log messages syslog; do
cat "$I" | grep -v "_hcd" >/home/luca/"$I"_20111215-USB-autosuspend.bug.grep-v_hcd;
done

$ for I in debug kern.log messages syslog; do
ls -lh /var/log/"$I" /home/luca/"$I"_20111215-USB-autosuspend.bug.grep-v_hcd;
done
-rw-r--r-- 1 root root 352K Dec 15 21:49 /home/luca/debug_20111215-USB-autosuspend.bug.grep-v_hcd
-rw-r----- 1 root adm 302M Dec 15 21:25 /var/log/debug
-rw-r--r-- 1 root root 1.2M Dec 15 21:50 /home/luca/kern.log_20111215-USB-autosuspend.bug.grep-v_hcd
-rw-r----- 1 root adm 697M Dec 15 21:25 /var/log/kern.log
-rw-r--r-- 1 root root 948K Dec 15 21:50 /home/luca/messages_20111215-USB-autosuspend.bug.grep-v_hcd
-rw-r----- 1 root adm 395M Dec 15 21:25 /var/log/messages
-rw-r--r-- 1 root root 2.7M Dec 15 21:50 /home/luca/syslog_20111215-USB-autosuspend.bug.grep-v_hcd
-rw-r----- 1 root adm 698M Dec 15 21:25 /var/log/syslog
=====

Obviously, this is not acceptable and there is a problem somewhere.
Feel free to ask for more tests, I will upgrade soon to the latest
version in unstable (3.1.5-1) and report back the results.

Thx, bye,
Gismo / Luca

-- Package-specific info:
** Version:
Linux version 3.1.0-1-amd64 (Debian 3.1.1-1) (ben@decadent.org.uk) (gcc version 4.6.2 (Debian 4.6.2-4) ) #1 SMP Mon Nov 14 08:02:25 UTC 2011

** Command line:
BOOT_IMAGE=/vmlinuz-3.1.0-1-amd64 root=/dev/mapper/gismo-root ro quiet

** Not tainted

** Kernel log:
[335534.196062] ehci_hcd 0000:00:1d.7: power state changed by ACPI to D0
[335534.196079] ehci_hcd 0000:00:1d.7: restoring config space at offset 0xf (was 0x400, writing 0x40b)
[335534.196104] ehci_hcd 0000:00:1d.7: restoring config space at offset 0x1 (was 0x2900000, writing 0x2900106)
[335534.196181] ehci_hcd 0000:00:1d.7: PME# disabled
[335534.196190] ehci_hcd 0000:00:1d.7: setting latency timer to 64
[335534.328130] ehci_hcd 0000:00:1d.7: PME# enabled
[335534.344055] ehci_hcd 0000:00:1d.7: power state changed by ACPI to D3
[335534.940977] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D0
[335534.940986] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D0
[335534.941107] uhci_hcd 0000:00:1d.2: setting latency timer to 64
[335535.020366] uhci_hcd 0000:00:1d.1: setting latency timer to 64
[335535.020521] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D3
[335535.100216] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D0
[335535.100226] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D0
[335535.101872] uhci_hcd 0000:00:1d.0: setting latency timer to 64
[335535.180311] ehci_hcd 0000:00:1d.7: power state changed by ACPI to D0
[335535.181266] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D3
[335535.196094] ehci_hcd 0000:00:1d.7: BAR 0: set to [mem 0xee444000-0xee4443ff] (PCI address [0xee444000-0xee4443ff])
[335535.196118] ehci_hcd 0000:00:1d.7: power state changed by ACPI to D0
[335535.196143] ehci_hcd 0000:00:1d.7: restoring config space at offset 0xf (was 0x400, writing 0x40b)
[335535.196177] ehci_hcd 0000:00:1d.7: restoring config space at offset 0x1 (was 0x2900000, writing 0x2900106)
[335535.196230] ehci_hcd 0000:00:1d.7: PME# disabled
[335535.196244] ehci_hcd 0000:00:1d.7: setting latency timer to 64
[335535.328095] ehci_hcd 0000:00:1d.7: PME# enabled
[335535.344079] ehci_hcd 0000:00:1d.7: power state changed by ACPI to D3
[335535.940152] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D0
[335535.940162] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D0
[335535.940285] uhci_hcd 0000:00:1d.2: setting latency timer to 64
[335535.980254] uhci_hcd 0000:00:1d.1: setting latency timer to 64
[335535.980386] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D3
[335536.020157] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D0
[335536.020162] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D0
[335536.020928] uhci_hcd 0000:00:1d.0: setting latency timer to 64
[335536.060242] ehci_hcd 0000:00:1d.7: power state changed by ACPI to D0
[335536.061163] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D3
[335536.076087] ehci_hcd 0000:00:1d.7: BAR 0: set to [mem 0xee444000-0xee4443ff] (PCI address [0xee444000-0xee4443ff])
[335536.076105] ehci_hcd 0000:00:1d.7: power state changed by ACPI to D0
[335536.076128] ehci_hcd 0000:00:1d.7: restoring config space at offset 0xf (was 0x400, writing 0x40b)
[335536.076162] ehci_hcd 0000:00:1d.7: restoring config space at offset 0x1 (was 0x2900000, writing 0x2900106)
[335536.076205] ehci_hcd 0000:00:1d.7: PME# disabled
[335536.076218] ehci_hcd 0000:00:1d.7: setting latency timer to 64
[335536.144119] ehci_hcd 0000:00:1d.7: PME# enabled
[335536.160069] ehci_hcd 0000:00:1d.7: power state changed by ACPI to D3
[335536.690004] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D0
[335536.690014] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D0
[335536.690123] uhci_hcd 0000:00:1d.2: setting latency timer to 64
[335536.728309] uhci_hcd 0000:00:1d.1: setting latency timer to 64
[335536.728496] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D3
[335536.732479] ehci_hcd 0000:00:1d.7: power state changed by ACPI to D0
[335536.748078] ehci_hcd 0000:00:1d.7: BAR 0: set to [mem 0xee444000-0xee4443ff] (PCI address [0xee444000-0xee4443ff])
[335536.748089] ehci_hcd 0000:00:1d.7: power state changed by ACPI to D0
[335536.748107] ehci_hcd 0000:00:1d.7: restoring config space at offset 0xf (was 0x400, writing 0x40b)
[335536.748138] ehci_hcd 0000:00:1d.7: restoring config space at offset 0x1 (was 0x2900000, writing 0x2900106)
[335536.748172] ehci_hcd 0000:00:1d.7: PME# disabled
[335536.748181] ehci_hcd 0000:00:1d.7: setting latency timer to 64
[335536.768637] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D0
[335536.768642] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D0
[335536.769431] uhci_hcd 0000:00:1d.0: setting latency timer to 64
[335536.809640] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D3
[335537.440041] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D0
[335537.440046] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D0
[335537.440141] uhci_hcd 0000:00:1d.2: setting latency timer to 64
[335537.480317] uhci_hcd 0000:00:1d.1: setting latency timer to 64
[335537.480520] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D3
[335537.520278] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D0
[335537.520287] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D0
[335537.521250] uhci_hcd 0000:00:1d.0: setting latency timer to 64
[335537.561263] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D3
[335538.190103] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D0
[335538.190113] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D0
[335538.190237] uhci_hcd 0000:00:1d.2: setting latency timer to 64
[335538.228341] uhci_hcd 0000:00:1d.1: setting latency timer to 64
[335538.228525] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D3
[335538.268655] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D0
[335538.268659] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D0
[335538.269430] uhci_hcd 0000:00:1d.0: setting latency timer to 64
[335538.310096] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D3
[335538.708388] uhci_hcd 0000:00:1d.1: setting latency timer to 64
[335538.940083] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D0
[335538.940089] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D0
[335538.940187] uhci_hcd 0000:00:1d.2: setting latency timer to 64
[335538.980290] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D0
[335538.980300] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D0
[335538.981197] uhci_hcd 0000:00:1d.0: setting latency timer to 64
[335538.981285] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D3
[335539.021387] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D3
[335539.060099] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D0
[335539.060104] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D0
[335539.060206] uhci_hcd 0000:00:1d.2: setting latency timer to 64
[335539.690105] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D0
[335539.690110] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D0
[335539.690934] uhci_hcd 0000:00:1d.0: setting latency timer to 64
[335539.730023] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D3
[335540.440141] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D0
[335540.440146] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D0
[335540.440860] uhci_hcd 0000:00:1d.0: setting latency timer to 64
[335540.481289] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D3
[335540.516217] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D0
[335540.516227] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D0
[335540.517093] uhci_hcd 0000:00:1d.0: setting latency timer to 64

** Model information
sys_vendor: LENOVO
product_name: 1706GMG
product_version: ThinkPad X60
chassis_vendor: LENOVO
chassis_version: Not Available
bios_vendor: LENOVO
bios_version: 7BETD7WW (2.18 )
board_vendor: LENOVO
board_name: 1706GMG
board_version: Not Available

** Loaded modules:
Module Size Used by
btrfs 478019 0
zlib_deflate 25638 1 btrfs
crc32c 12656 1
libcrc32c 12426 1 btrfs
ufs 58793 0
qnx4 13184 0
hfsplus 71571 0
hfs 45877 0
minix 27580 0
ntfs 159743 0
vfat 17316 0
msdos 17077 0
fat 45642 2 vfat,msdos
jfs 137157 0
xfs 590259 0
reiserfs 192077 0
ext3 161848 0
jbd 56928 1 ext3
usb_storage 43919 0
uas 13296 0
bluetooth 119290 0
msr 12759 0
tun 18337 0
ip6table_filter 12540 0
ip6_tables 22175 1 ip6table_filter
ebtable_nat 12580 0
ebtables 26235 1 ebtable_nat
ipt_MASQUERADE 12594 3
iptable_nat 12928 1
nf_nat 18242 2 ipt_MASQUERADE,iptable_nat
nf_conntrack_ipv4 14078 4 iptable_nat,nf_nat
nf_defrag_ipv4 12483 1 nf_conntrack_ipv4
xt_state 12503 1
nf_conntrack 52670 5 ipt_MASQUERADE,iptable_nat,nf_nat,nf_conntrack_ipv 4,xt_state
ipt_REJECT 12502 2
xt_CHECKSUM 12471 1
iptable_mangle 12536 1
xt_tcpudp 12570 5
bridge 66534 0
stp 12392 1 bridge
parport_pc 22364 0
ppdev 12763 0
lp 17149 0
parport 31858 3 parport_pc,ppdev,lp
xt_multiport 12548 1
iptable_filter 12536 1
ip_tables 22042 3 iptable_nat,iptable_mangle,iptable_filter
x_tables 19028 13 ip6table_filter,ip6_tables,ebtables,ipt_MASQUERADE ,iptable_nat,xt_state,ipt_REJECT,xt_CHECKSUM,iptab le_mangle,xt_tcpudp,xt_multiport,iptable_filter,ip _tables
acpi_cpufreq 12935 1
mperf 12453 1 acpi_cpufreq
cpufreq_conservative 13147 0
cpufreq_stats 12866 0
cpufreq_powersave 12454 0
cpufreq_userspace 12576 0
fuse 61981 5
nfsd 259717 2
nfs 312096 0
lockd 67328 2 nfsd,nfs
fscache 36739 1 nfs
auth_rpcgss 37143 2 nfsd,nfs
nfs_acl 12511 2 nfsd,nfs
sunrpc 173516 6 nfsd,nfs,lockd,auth_rpcgss,nfs_acl
ext2 59191 1
loop 22597 0
firewire_sbp2 18077 0
kvm_intel 121792 0
kvm 278183 1 kvm_intel
arc4 12458 2
pcmcia 32691 0
iwl3945 51641 0
snd_hda_codec_analog 77709 1
iwl_legacy 48145 1 iwl3945
i915 348449 2
mac80211 183093 2 iwl3945,iwl_legacy
psmouse 55543 0
sdhci_pci 13483 0
sdhci 26577 1 sdhci_pci
snd_hda_intel 26182 0
iTCO_wdt 17081 0
ata_generic 12479 0
firewire_ohci 31530 0
yenta_socket 22899 0
drm_kms_helper 27227 1 i915
drm 167371 3 i915,drm_kms_helper
snd_hda_codec 72799 2 snd_hda_codec_analog,snd_hda_intel
thinkpad_acpi 61270 0
cfg80211 132703 3 iwl3945,iwl_legacy,mac80211
mmc_core 63754 1 sdhci
irda 89767 0
uhci_hcd 26865 0
pcmcia_rsrc 17533 1 yenta_socket
firewire_core 48407 2 firewire_sbp2,firewire_ohci
iTCO_vendor_support 12704 1 iTCO_wdt
serio_raw 12850 0
e1000e 125122 0
i2c_algo_bit 12841 1 i915
ac 12624 0
pcmcia_core 18294 3 pcmcia,yenta_socket,pcmcia_rsrc
evdev 17562 6
ata_piix 25443 0
ehci_hcd 40215 0
battery 13109 0
i2c_i801 16870 0
tpm_tis 17454 0
snd_hwdep 13186 1 snd_hda_codec
power_supply 13475 2 ac,battery
rfkill 19012 3 bluetooth,thinkpad_acpi,cfg80211
crc_itu_t 12347 1 firewire_core
crc_ccitt 12347 1 irda
tpm 17862 1 tpm_tis
snd_pcm 63744 2 snd_hda_intel,snd_hda_codec
i2c_core 23876 5 i915,drm_kms_helper,drm,i2c_algo_bit,i2c_i801
rng_core 12652 0
tpm_bios 12948 1 tpm
usbcore 124095 5 usb_storage,uas,uhci_hcd,ehci_hcd
snd_timer 22917 1 snd_pcm
nvram 13049 1 thinkpad_acpi
snd 52798 7 snd_hda_codec_analog,snd_hda_intel,snd_hda_codec,t hinkpad_acpi,snd_hwdep,snd_pcm,snd_timer
processor 27949 3 acpi_cpufreq
button 12937 1 i915
soundcore 13065 1 snd
snd_page_alloc 13003 2 snd_hda_intel,snd_pcm
video 17628 1 i915
ext4 312988 2
mbcache 13065 3 ext3,ext2,ext4
jbd2 62015 1 ext4
crc16 12343 2 bluetooth,ext4
sha256_generic 16797 2
aes_x86_64 16796 4
aes_generic 33026 1 aes_x86_64
cbc 12754 2
dm_crypt 22586 1
dm_mod 63353 12 dm_crypt
sd_mod 36136 3
crc_t10dif 12348 1 sd_mod
ahci 24997 2
libahci 22860 1 ahci
thermal 17383 0
libata 140545 4 ata_generic,ata_piix,ahci,libahci
thermal_sys 18040 3 processor,video,thermal
scsi_mod 162376 5 usb_storage,uas,firewire_sbp2,sd_mod,libata

** Network interface configuration:
# This file describes the network interfaces available on your system
# and how to activate them. For more information, see interfaces(5).

# The loopback network interface
auto lo
iface lo inet loopback

# The primary network interface
allow-hotplug eth0
## <http://bugs.debian.org/606268>
iface eth0 inet dhcp

# The Wi-Fi interface
allow-hotplug wlan0
## <http://bugs.debian.org/606268>
iface wlan0 inet dhcp
wireless-power on
wpa-conf /etc/wpa_supplicant/wpa_supplicant.conf

# ranma: Openmoko GTA02 (AKA FreeRunner)
## <http://wiki.openmoko.org/wiki/USB_Networking>
allow-hotplug usb0
iface usb0 inet static
address 192.168.0.200
netmask 255.255.255.0
post-up /root/bin/openmoko-usb0.sh up
pre-down /root/bin/openmoko-usb0.sh down

** Network status:
*** IP interfaces and addresses:
1: lo: <LOOPBACK,UP,LOWER_UP> mtu 16436 qdisc noqueue state UNKNOWN
link/loopback 00:00:00:00:00:00 brd 00:00:00:00:00:00
inet 127.0.0.1/8 scope host lo
inet6 ::1/128 scope host
valid_lft forever preferred_lft forever
2: eth0: <BROADCAST,MULTICAST,UP,LOWER_UP> mtu 1500 qdisc pfifo_fast state UP qlen 1000
link/ether 00:16:d3:2c:fc:f5 brd ff:ff:ff:ff:ff:ff
inet 129.194.56.110/22 brd 129.194.59.255 scope global eth0
inet6 fe80::216:d3ff:fe2c:fcf5/64 scope link
valid_lft forever preferred_lft forever
3: wlan0: <BROADCAST,MULTICAST> mtu 1500 qdisc mq state DOWN qlen 1000
link/ether 00:19:d2:07:2c:a0 brd ff:ff:ff:ff:ff:ff
4: virbr0: <NO-CARRIER,BROADCAST,MULTICAST,UP> mtu 1500 qdisc noqueue state DOWN
link/ether 00:00:00:00:00:00 brd ff:ff:ff:ff:ff:ff
inet 192.168.122.1/24 brd 192.168.122.255 scope global virbr0

*** Device statistics:
Inter-| Receive | Transmit
face |bytes packets errs drop fifo frame compressed multicast|bytes packets errs drop fifo colls carrier compressed
lo: 13391152 32178 0 0 0 0 0 0 13391152 32178 0 0 0 0 0 0
eth0: 138926237252 107912182 0 0 0 0 0 1165106 7579712020 12852583 0 0 0 0 0 0
wlan0: 86506642 88705 0 0 0 0 0 0 14057122 76879 0 0 0 0 0 0
virbr0: 535675081 158594 0 0 0 0 0 0 463847385 404341 0 0 0 0 0 0

*** Protocol statistics:
Ip:
36266238 total packets received
363898 forwarded
0 incoming packets discarded
35725133 incoming packets delivered
9253197 requests sent out
5666 dropped because of missing route
513 reassemblies required
242 packets reassembled ok
Icmp:
5395 ICMP messages received
685 input ICMP message failed.
ICMP input histogram:
destination unreachable: 4699
echo requests: 689
echo replies: 6
176 ICMP messages sent
0 ICMP messages failed
ICMP output histogram:
destination unreachable: 147
echo request: 8
echo replies: 21
IcmpMsg:
InType0: 6
InType3: 4699
InType8: 689
InType9: 1
OutType0: 21
OutType3: 147
OutType8: 8
Tcp:
46589 active connections openings
46087 passive connection openings
6067 failed connection attempts
1982 connection resets received
8 connections established
31977829 segments received
12708072 segments send out
3317 segments retransmited
28 bad segments received.
4990 resets sent
Udp:
4578453 packets received
53 packets to unknown port received.
2397 packet receive errors
221807 packets sent
UdpLite:
TcpExt:
5 invalid SYN cookies received
5568 resets received for embryonic SYN_RECV sockets
24 packets pruned from receive queue because of socket buffer overrun
38457 TCP sockets finished time wait in fast timer
54002 delayed acks sent
1314 delayed acks further delayed because of locked socket
Quick ack mode was activated 1836 times
80070 packets directly queued to recvmsg prequeue.
145599 bytes directly in process context from backlog
17832361 bytes directly received in process context from prequeue
26914542 packet headers predicted
8654 packets header predicted and directly queued to user
1691442 acknowledgments not containing data payload received
2964556 predicted acknowledgments
23 times recovered from packet loss due to fast retransmit
667 times recovered from packet loss by selective acknowledgements
1 congestion windows fully recovered without slow start
5 congestion windows partially recovered using Hoe heuristic
1 congestion windows recovered without slow start by DSACK
412 congestion windows recovered without slow start after partial ack
1278 TCP data loss events
TCPLostRetransmit: 46
153 timeouts after SACK recovery
1 timeouts in loss state
1154 fast retransmits
15 forward retransmits
150 retransmits in slow start
1385 other TCP timeouts
18 SACK retransmits failed
2 times receiver scheduled too late for direct processing
2171 packets collapsed in receive queue due to low socket buffer
1830 DSACKs sent for old packets
2 DSACKs sent for out of order packets
239 DSACKs received
498 connections reset due to unexpected data
1847 connections reset due to early user close
68 connections aborted due to timeout
9 times unabled to send RST due to no memory
TCPDSACKIgnoredOld: 1
TCPDSACKIgnoredNoUndo: 56
TCPSpuriousRTOs: 1
TCPSackShifted: 1692
TCPSackMerged: 2907
TCPSackShiftFallback: 2046
TCPBacklogDrop: 254
IpExt:
InNoRoutes: 1117
InTruncatedPkts: 3
InMcastPkts: 688809
OutMcastPkts: 5697
InBcastPkts: 2977556
OutBcastPkts: 2520
InOctets: 535815494
OutOctets: -933517644
InMcastOctets: 309204773
OutMcastOctets: 254182
InBcastOctets: 382952867
OutBcastOctets: 522383


** PCI devices:
00:00.0 Host bridge [0600]: Intel Corporation Mobile 945GM/PM/GMS, 943/940GML and 945GT Express Memory Controller Hub [8086:27a0] (rev 03)
Subsystem: Lenovo ThinkPad T60/R60 series [17aa:2017]
Control: I/O- Mem+ BusMaster+ SpecCycle- MemWINV- VGASnoop- ParErr- Stepping- SERR+ FastB2B- DisINTx-
Status: Cap+ 66MHz- UDF- FastB2B+ ParErr- DEVSEL=fast >TAbort- <TAbort- <MAbort+ >SERR- <PERR- INTx-
Latency: 0
Capabilities: <access denied>
Kernel driver in use: agpgart-intel

00:02.0 VGA compatible controller [0300]: Intel Corporation Mobile 945GM/GMS, 943/940GML Express Integrated Graphics Controller [8086:27a2] (rev 03) (prog-if 00 [VGA controller])
Subsystem: Lenovo ThinkPad T60/R60 series [17aa:201a]
Control: I/O+ Mem+ BusMaster+ SpecCycle- MemWINV- VGASnoop- ParErr- Stepping- SERR- FastB2B- DisINTx-
Status: Cap+ 66MHz- UDF- FastB2B+ ParErr- DEVSEL=fast >TAbort- <TAbort- <MAbort- >SERR- <PERR- INTx-
Latency: 0
Interrupt: pin A routed to IRQ 16
Region 0: Memory at ee100000 (32-bit, non-prefetchable) [size=512K]
Region 1: I/O ports at 1800 [size=8]
Region 2: Memory at d0000000 (32-bit, prefetchable) [size=256M]
Region 3: Memory at ee200000 (32-bit, non-prefetchable) [size=256K]
Expansion ROM at <unassigned> [disabled]
Capabilities: <access denied>
Kernel driver in use: i915

00:02.1 Display controller [0380]: Intel Corporation Mobile 945GM/GMS/GME, 943/940GML Express Integrated Graphics Controller [8086:27a6] (rev 03)
Subsystem: Lenovo ThinkPad T60/R60 series [17aa:201a]
Control: I/O+ Mem+ BusMaster- SpecCycle- MemWINV- VGASnoop- ParErr- Stepping- SERR- FastB2B- DisINTx-
Status: Cap+ 66MHz- UDF- FastB2B+ ParErr- DEVSEL=fast >TAbort- <TAbort- <MAbort- >SERR- <PERR- INTx-
Region 0: Memory at ee180000 (32-bit, non-prefetchable) [size=512K]
Capabilities: <access denied>

00:1b.0 Audio device [0403]: Intel Corporation N10/ICH 7 Family High Definition Audio Controller [8086:27d8] (rev 02)
Subsystem: Lenovo ThinkPad T60/R60 series [17aa:2010]
Control: I/O- Mem+ BusMaster+ SpecCycle- MemWINV- VGASnoop- ParErr- Stepping- SERR+ FastB2B- DisINTx+
Status: Cap+ 66MHz- UDF- FastB2B- ParErr- DEVSEL=fast >TAbort- <TAbort- <MAbort- >SERR- <PERR- INTx-
Latency: 0, Cache Line Size: 64 bytes
Interrupt: pin B routed to IRQ 45
Region 0: Memory at ee240000 (64-bit, non-prefetchable) [size=16K]
Capabilities: <access denied>
Kernel driver in use: snd_hda_intel

00:1c.0 PCI bridge [0604]: Intel Corporation N10/ICH 7 Family PCI Express Port 1 [8086:27d0] (rev 02) (prog-if 00 [Normal decode])
Control: I/O+ Mem+ BusMaster+ SpecCycle- MemWINV- VGASnoop- ParErr- Stepping- SERR+ FastB2B- DisINTx+
Status: Cap+ 66MHz- UDF- FastB2B- ParErr- DEVSEL=fast >TAbort- <TAbort- <MAbort- >SERR- <PERR- INTx-
Latency: 0, Cache Line Size: 64 bytes
Bus: primary=00, secondary=02, subordinate=02, sec-latency=0
I/O behind bridge: 00002000-00002fff
Memory behind bridge: ee000000-ee0fffff
Prefetchable memory behind bridge: 0000000080000000-00000000801fffff
Secondary status: 66MHz- FastB2B- ParErr- DEVSEL=fast >TAbort- <TAbort- <MAbort- <SERR- <PERR-
BridgeCtl: Parity- SERR- NoISA+ VGA- MAbort- >Reset- FastB2B-
PriDiscTmr- SecDiscTmr- DiscTmrStat- DiscTmrSERREn-
Capabilities: <access denied>
Kernel driver in use: pcieport

00:1c.1 PCI bridge [0604]: Intel Corporation N10/ICH 7 Family PCI Express Port 2 [8086:27d2] (rev 02) (prog-if 00 [Normal decode])
Control: I/O+ Mem+ BusMaster+ SpecCycle- MemWINV- VGASnoop- ParErr- Stepping- SERR+ FastB2B- DisINTx+
Status: Cap+ 66MHz- UDF- FastB2B- ParErr- DEVSEL=fast >TAbort- <TAbort- <MAbort- >SERR- <PERR- INTx-
Latency: 0, Cache Line Size: 64 bytes
Bus: primary=00, secondary=03, subordinate=03, sec-latency=0
I/O behind bridge: 00003000-00004fff
Memory behind bridge: ec000000-edffffff
Prefetchable memory behind bridge: 00000000e4000000-00000000e40fffff
Secondary status: 66MHz- FastB2B- ParErr- DEVSEL=fast >TAbort- <TAbort- <MAbort- <SERR- <PERR-
BridgeCtl: Parity- SERR- NoISA+ VGA- MAbort- >Reset- FastB2B-
PriDiscTmr- SecDiscTmr- DiscTmrStat- DiscTmrSERREn-
Capabilities: <access denied>
Kernel driver in use: pcieport

00:1c.2 PCI bridge [0604]: Intel Corporation N10/ICH 7 Family PCI Express Port 3 [8086:27d4] (rev 02) (prog-if 00 [Normal decode])
Control: I/O+ Mem+ BusMaster+ SpecCycle- MemWINV- VGASnoop- ParErr- Stepping- SERR+ FastB2B- DisINTx+
Status: Cap+ 66MHz- UDF- FastB2B- ParErr- DEVSEL=fast >TAbort- <TAbort- <MAbort- >SERR- <PERR- INTx-
Latency: 0, Cache Line Size: 64 bytes
Bus: primary=00, secondary=04, subordinate=0b, sec-latency=0
I/O behind bridge: 00005000-00006fff
Memory behind bridge: e8000000-e9ffffff
Prefetchable memory behind bridge: 00000000e4100000-00000000e41fffff
Secondary status: 66MHz- FastB2B- ParErr- DEVSEL=fast >TAbort- <TAbort- <MAbort- <SERR- <PERR-
BridgeCtl: Parity- SERR- NoISA+ VGA- MAbort- >Reset- FastB2B-
PriDiscTmr- SecDiscTmr- DiscTmrStat- DiscTmrSERREn-
Capabilities: <access denied>
Kernel driver in use: pcieport

00:1c.3 PCI bridge [0604]: Intel Corporation N10/ICH 7 Family PCI Express Port 4 [8086:27d6] (rev 02) (prog-if 00 [Normal decode])
Control: I/O+ Mem+ BusMaster+ SpecCycle- MemWINV- VGASnoop- ParErr- Stepping- SERR+ FastB2B- DisINTx+
Status: Cap+ 66MHz- UDF- FastB2B- ParErr- DEVSEL=fast >TAbort- <TAbort- <MAbort- >SERR- <PERR- INTx-
Latency: 0, Cache Line Size: 64 bytes
Bus: primary=00, secondary=0c, subordinate=13, sec-latency=0
I/O behind bridge: 00007000-00008fff
Memory behind bridge: ea000000-ebffffff
Prefetchable memory behind bridge: 00000000e4200000-00000000e42fffff
Secondary status: 66MHz- FastB2B- ParErr- DEVSEL=fast >TAbort- <TAbort- <MAbort- <SERR- <PERR-
BridgeCtl: Parity- SERR- NoISA+ VGA- MAbort- >Reset- FastB2B-
PriDiscTmr- SecDiscTmr- DiscTmrStat- DiscTmrSERREn-
Capabilities: <access denied>
Kernel driver in use: pcieport

00:1d.0 USB Controller [0c03]: Intel Corporation N10/ICH 7 Family USB UHCI Controller #1 [8086:27c8] (rev 02) (prog-if 00 [UHCI])
Subsystem: Lenovo ThinkPad T60/R60 series [17aa:200a]
Control: I/O+ Mem- BusMaster+ SpecCycle- MemWINV- VGASnoop- ParErr- Stepping- SERR- FastB2B- DisINTx-
Status: Cap- 66MHz- UDF- FastB2B+ ParErr- DEVSEL=medium >TAbort- <TAbort- <MAbort- >SERR- <PERR- INTx-
Latency: 0
Interrupt: pin A routed to IRQ 16
Region 4: I/O ports at 1820 [size=32]
Kernel driver in use: uhci_hcd

00:1d.1 USB Controller [0c03]: Intel Corporation N10/ICH 7 Family USB UHCI Controller #2 [8086:27c9] (rev 02) (prog-if 00 [UHCI])
Subsystem: Lenovo ThinkPad T60/R60 series [17aa:200a]
Control: I/O+ Mem- BusMaster+ SpecCycle- MemWINV- VGASnoop- ParErr- Stepping- SERR- FastB2B- DisINTx-
Status: Cap- 66MHz- UDF- FastB2B+ ParErr- DEVSEL=medium >TAbort- <TAbort- <MAbort- >SERR- <PERR- INTx-
Latency: 0
Interrupt: pin B routed to IRQ 17
Region 4: I/O ports at 1840 [size=32]
Kernel driver in use: uhci_hcd

00:1d.2 USB Controller [0c03]: Intel Corporation N10/ICH 7 Family USB UHCI Controller #3 [8086:27ca] (rev 02) (prog-if 00 [UHCI])
Subsystem: Lenovo ThinkPad T60/R60 series [17aa:200a]
Control: I/O+ Mem- BusMaster+ SpecCycle- MemWINV- VGASnoop- ParErr- Stepping- SERR- FastB2B- DisINTx-
Status: Cap- 66MHz- UDF- FastB2B+ ParErr- DEVSEL=medium >TAbort- <TAbort- <MAbort- >SERR- <PERR- INTx-
Latency: 0
Interrupt: pin C routed to IRQ 18
Region 4: I/O ports at 1860 [size=32]
Kernel driver in use: uhci_hcd

00:1d.3 USB Controller [0c03]: Intel Corporation N10/ICH 7 Family USB UHCI Controller #4 [8086:27cb] (rev 02) (prog-if 00 [UHCI])
Subsystem: Lenovo ThinkPad T60/R60 series [17aa:200a]
Control: I/O+ Mem- BusMaster+ SpecCycle- MemWINV- VGASnoop- ParErr- Stepping- SERR- FastB2B- DisINTx-
Status: Cap- 66MHz- UDF- FastB2B+ ParErr- DEVSEL=medium >TAbort- <TAbort- <MAbort- >SERR- <PERR- INTx-
Latency: 0
Interrupt: pin D routed to IRQ 19
Region 4: I/O ports at 1880 [size=32]
Kernel driver in use: uhci_hcd

00:1d.7 USB Controller [0c03]: Intel Corporation N10/ICH 7 Family USB2 EHCI Controller [8086:27cc] (rev 02) (prog-if 20 [EHCI])
Subsystem: Lenovo ThinkPad T60/R60 series [17aa:200b]
Control: I/O- Mem+ BusMaster+ SpecCycle- MemWINV- VGASnoop- ParErr- Stepping- SERR+ FastB2B- DisINTx-
Status: Cap+ 66MHz- UDF- FastB2B+ ParErr- DEVSEL=medium >TAbort- <TAbort- <MAbort- >SERR- <PERR- INTx-
Latency: 0
Interrupt: pin D routed to IRQ 19
Region 0: Memory at ee444000 (32-bit, non-prefetchable) [size=1K]
Capabilities: <access denied>
Kernel driver in use: ehci_hcd

00:1e.0 PCI bridge [0604]: Intel Corporation 82801 Mobile PCI Bridge [8086:2448] (rev e2) (prog-if 01 [Subtractive decode])
Control: I/O+ Mem+ BusMaster+ SpecCycle- MemWINV- VGASnoop- ParErr- Stepping- SERR- FastB2B- DisINTx-
Status: Cap+ 66MHz- UDF- FastB2B- ParErr- DEVSEL=fast >TAbort- <TAbort- <MAbort- >SERR- <PERR- INTx-
Latency: 0
Bus: primary=00, secondary=15, subordinate=18, sec-latency=32
I/O behind bridge: 00009000-0000cfff
Memory behind bridge: e4300000-e7ffffff
Prefetchable memory behind bridge: 00000000e0000000-00000000e3ffffff
Secondary status: 66MHz- FastB2B+ ParErr- DEVSEL=medium >TAbort- <TAbort- <MAbort+ <SERR- <PERR-
BridgeCtl: Parity- SERR- NoISA- VGA- MAbort- >Reset- FastB2B-
PriDiscTmr- SecDiscTmr- DiscTmrStat- DiscTmrSERREn-
Capabilities: <access denied>

00:1f.0 ISA bridge [0601]: Intel Corporation 82801GBM (ICH7-M) LPC Interface Bridge [8086:27b9] (rev 02)
Subsystem: Lenovo ThinkPad T60/R60 series [17aa:2009]
Control: I/O+ Mem+ BusMaster+ SpecCycle- MemWINV- VGASnoop- ParErr- Stepping- SERR- FastB2B- DisINTx-
Status: Cap+ 66MHz- UDF- FastB2B- ParErr- DEVSEL=medium >TAbort- <TAbort- <MAbort- >SERR- <PERR- INTx-
Latency: 0
Capabilities: <access denied>

00:1f.1 IDE interface [0101]: Intel Corporation 82801G (ICH7 Family) IDE Controller [8086:27df] (rev 02) (prog-if 8a [Master SecP PriP])
Subsystem: Lenovo ThinkPad T60/R60 series [17aa:200c]
Control: I/O+ Mem- BusMaster+ SpecCycle- MemWINV- VGASnoop- ParErr- Stepping- SERR- FastB2B- DisINTx-
Status: Cap- 66MHz- UDF- FastB2B+ ParErr- DEVSEL=medium >TAbort- <TAbort- <MAbort- >SERR- <PERR- INTx+
Latency: 0
Interrupt: pin C routed to IRQ 16
Region 0: I/O ports at 01f0 [size=8]
Region 1: I/O ports at 03f4 [size=1]
Region 2: I/O ports at 0170 [size=8]
Region 3: I/O ports at 0374 [size=1]
Region 4: I/O ports at 1810 [size=16]
Kernel driver in use: ata_piix

00:1f.2 SATA controller [0106]: Intel Corporation 82801GBM/GHM (ICH7 Family) SATA AHCI Controller [8086:27c5] (rev 02) (prog-if 01 [AHCI 1.0])
Subsystem: Lenovo ThinkPad T60/R60 series [17aa:200d]
Control: I/O+ Mem+ BusMaster+ SpecCycle- MemWINV- VGASnoop- ParErr- Stepping- SERR- FastB2B- DisINTx+
Status: Cap+ 66MHz+ UDF- FastB2B+ ParErr- DEVSEL=medium >TAbort- <TAbort- <MAbort- >SERR- <PERR- INTx-
Latency: 0
Interrupt: pin B routed to IRQ 44
Region 0: I/O ports at 18d0 [size=8]
Region 1: I/O ports at 18c4 [size=4]
Region 2: I/O ports at 18c8 [size=8]
Region 3: I/O ports at 18c0 [size=4]
Region 4: I/O ports at 18b0 [size=16]
Region 5: Memory at ee444400 (32-bit, non-prefetchable) [size=1K]
Capabilities: <access denied>
Kernel driver in use: ahci

00:1f.3 SMBus [0c05]: Intel Corporation N10/ICH 7 Family SMBus Controller [8086:27da] (rev 02)
Subsystem: Lenovo ThinkPad T60/R60 series [17aa:200f]
Control: I/O+ Mem- BusMaster- SpecCycle- MemWINV- VGASnoop- ParErr- Stepping- SERR+ FastB2B- DisINTx-
Status: Cap- 66MHz- UDF- FastB2B+ ParErr- DEVSEL=medium >TAbort- <TAbort- <MAbort- >SERR- <PERR- INTx-
Interrupt: pin A routed to IRQ 23
Region 4: I/O ports at 18e0 [size=32]
Kernel driver in use: i801_smbus

02:00.0 Ethernet controller [0200]: Intel Corporation 82573L Gigabit Ethernet Controller [8086:109a]
Subsystem: Lenovo ThinkPad X60s [17aa:207e]
Control: I/O+ Mem+ BusMaster+ SpecCycle- MemWINV- VGASnoop- ParErr- Stepping- SERR+ FastB2B- DisINTx+
Status: Cap+ 66MHz- UDF- FastB2B- ParErr- DEVSEL=fast >TAbort- <TAbort- <MAbort- >SERR- <PERR- INTx-
Latency: 0, Cache Line Size: 64 bytes
Interrupt: pin A routed to IRQ 46
Region 0: Memory at ee000000 (32-bit, non-prefetchable) [size=128K]
Region 2: I/O ports at 2000 [size=32]
Capabilities: <access denied>
Kernel driver in use: e1000e

03:00.0 Network controller [0280]: Intel Corporation PRO/Wireless 3945ABG [Golan] Network Connection [8086:4227] (rev 02)
Subsystem: Intel Corporation ThinkPad R60e/X60s [8086:1011]
Control: I/O- Mem+ BusMaster+ SpecCycle- MemWINV- VGASnoop- ParErr- Stepping- SERR+ FastB2B- DisINTx+
Status: Cap+ 66MHz- UDF- FastB2B- ParErr- DEVSEL=fast >TAbort- <TAbort- <MAbort- >SERR- <PERR- INTx-
Latency: 0, Cache Line Size: 64 bytes
Interrupt: pin A routed to IRQ 47
Region 0: Memory at edf00000 (32-bit, non-prefetchable) [size=4K]
Capabilities: <access denied>
Kernel driver in use: iwl3945

15:00.0 CardBus bridge [0607]: Ricoh Co Ltd RL5c476 II [1180:0476] (rev b4)
Subsystem: Lenovo ThinkPad X60s [17aa:201c]
Control: I/O+ Mem+ BusMaster+ SpecCycle- MemWINV- VGASnoop- ParErr- Stepping- SERR- FastB2B- DisINTx-
Status: Cap+ 66MHz- UDF- FastB2B- ParErr- DEVSEL=medium >TAbort- <TAbort- <MAbort- >SERR- <PERR- INTx-
Latency: 168, Cache Line Size: 64 bytes
Interrupt: pin A routed to IRQ 16
Region 0: Memory at e4300000 (32-bit, non-prefetchable) [size=4K]
Bus: primary=15, secondary=16, subordinate=17, sec-latency=176
Memory window 0: e0000000-e3fff000 (prefetchable)
Memory window 1: 84000000-87fff000
I/O window 0: 00009400-000094ff
I/O window 1: 00009000-000090ff
BridgeCtl: Parity- SERR- ISA- VGA- MAbort- >Reset- 16bInt+ PostWrite+
16-bit legacy interface ports at 0001
Kernel driver in use: yenta_cardbus

15:00.1 FireWire (IEEE 1394) [0c00]: Ricoh Co Ltd R5C552 IEEE 1394 Controller [1180:0552] (rev 09) (prog-if 10 [OHCI])
Subsystem: Lenovo ThinkPad X60s [17aa:201e]
Control: I/O- Mem+ BusMaster+ SpecCycle- MemWINV- VGASnoop- ParErr- Stepping- SERR- FastB2B- DisINTx-
Status: Cap+ 66MHz- UDF- FastB2B- ParErr- DEVSEL=medium >TAbort- <TAbort- <MAbort- >SERR- <PERR- INTx-
Latency: 32 (500ns min, 1000ns max)
Interrupt: pin B routed to IRQ 17
Region 0: Memory at e4301000 (32-bit, non-prefetchable) [size=2K]
Capabilities: <access denied>
Kernel driver in use: firewire_ohci

15:00.2 SD Host controller [0805]: Ricoh Co Ltd R5C822 SD/SDIO/MMC/MS/MSPro Host Adapter [1180:0822] (rev 18)
Subsystem: Lenovo ThinkPad X60s [17aa:201d]
Control: I/O- Mem+ BusMaster+ SpecCycle- MemWINV- VGASnoop- ParErr- Stepping- SERR- FastB2B- DisINTx-
Status: Cap+ 66MHz- UDF- FastB2B- ParErr- DEVSEL=medium >TAbort- <TAbort- <MAbort- >SERR- <PERR- INTx-
Latency: 64
Interrupt: pin C routed to IRQ 18
Region 0: Memory at e4301800 (32-bit, non-prefetchable) [size=256]
Capabilities: <access denied>
Kernel driver in use: sdhci-pci


** USB devices:
Bus 005 Device 006: ID 0483:2016 SGS Thomson Microelectronics Fingerprint Reader
Bus 001 Device 001: ID 1d6b:0002 Linux Foundation 2.0 root hub
Bus 002 Device 001: ID 1d6b:0001 Linux Foundation 1.1 root hub
Bus 003 Device 001: ID 1d6b:0001 Linux Foundation 1.1 root hub
Bus 004 Device 001: ID 1d6b:0001 Linux Foundation 1.1 root hub
Bus 005 Device 001: ID 1d6b:0001 Linux Foundation 1.1 root hub


-- System Information:
Debian Release: wheezy/sid
APT prefers unstable
APT policy: (990, 'unstable'), (1, 'experimental')
Architecture: amd64 (x86_64)

Kernel: Linux 3.1.0-1-amd64 (SMP w/2 CPU cores)
Locale: LANG=en_US.UTF-8, LC_CTYPE=en_US.UTF-8 (charmap=UTF-8)
Shell: /bin/sh linked to /bin/dash

Versions of packages linux-image-3.1.0-1-amd64 depends on:
ii debconf [debconf-2.0] 1.5.41
ii initramfs-tools [linux-initramfs-tool] 0.99
ii linux-base 3.4
ii module-init-tools 3.16-1

Versions of packages linux-image-3.1.0-1-amd64 recommends:
pn firmware-linux-free <none>

Versions of packages linux-image-3.1.0-1-amd64 suggests:
pn grub-pc 1.99-14
pn linux-doc-3.1 <none>

Versions of packages linux-image-3.1.0-1-amd64 is related to:
pn firmware-bnx2 <none>
pn firmware-bnx2x <none>
pn firmware-ipw2x00 <none>
pn firmware-ivtv <none>
pn firmware-iwlwifi 0.34
pn firmware-linux <none>
pn firmware-linux-nonfree <none>
pn firmware-qlogic <none>
pn firmware-ralink <none>
pn xen-hypervisor <none>

-- debconf information:
linux-image-3.1.0-1-amd64/postinst/ignoring-ramdisk:
linux-image-3.1.0-1-amd64/postinst/depmod-error-initrd-3.1.0-1-amd64: false
linux-image-3.1.0-1-amd64/prerm/removing-running-kernel-3.1.0-1-amd64: true
linux-image-3.1.0-1-amd64/postinst/missing-firmware-3.1.0-1-amd64:
 
Old 12-30-2011, 11:48 AM
Ben Hutchings
 
Default Bug#652475: linux-image-3.1.0-1-amd64: USB autosuspend goes crazy filling the log files

On Sat, 2011-12-17 at 17:17 +0100, Luca Capello wrote:
> Package: linux-2.6
> Version: 3.1.1-1
> Severity: normal
>
> Hi there!
>
> There is a problem somewhere with USB autosuspending on my ThinkPad X60.
> When enabled through powertop, the kernel splits out messages different
> times every seconds, thus filling the log files:
[...]

I don't know that there is anything wrong with the auto-suspend
behaviour. Are your USB devices working correctly?

Clearly it should not generate so many log messages. Can you test
whether the attached patch fixes that problem for you? See
<http://kernel-handbook.alioth.debian.org/ch-common-tasks.html#s-common-official> for instructions on how to do this.

Ben.

--
Ben Hutchings
Beware of programmers who carry screwdrivers. - Leonard Brandwein
 
Old 02-21-2012, 07:44 AM
Luca Capello
 
Default Bug#652475: linux-image-3.1.0-1-amd64: USB autosuspend goes crazy filling the log files

found 652475 3.2.4-1
thanks

Hi there!

On Fri, 30 Dec 2011 13:48:59 +0100, Ben Hutchings wrote:
> On Sat, 2011-12-17 at 17:17 +0100, Luca Capello wrote:
>> Package: linux-2.6
>> Version: 3.1.1-1

The bug is still present in newer kernels, version updated.

>> There is a problem somewhere with USB autosuspending on my ThinkPad X60.
>> When enabled through powertop, the kernel splits out messages different
>> times every seconds, thus filling the log files:
> [...]
>
> I don't know that there is anything wrong with the auto-suspend
> behaviour. Are your USB devices working correctly?

Yes, there is no problem with them except with an external 100 GB S-ATA
HD, but this happens when copying >5 GB files and also with other
machines/OSs, thus I guess the fault is the double S-ATA/IDE converter
which does not give enough power to the HD (everything is fine with HDs
smaller in size).

However, I should note that sometime there is a general problem I have
already reported in the past, IIRC directly to the linux-acpi mailing
list (sorry, I do not have direct link right now):
=====
Message from syslogd@gismo at Feb 20 14:12:52 ...
kernel:[184232.065089] Uhhuh. NMI received for unknown reason 31 on CPU 0.

Message from syslogd@gismo at Feb 20 14:12:52 ...
kernel:[184232.065141] Do you have a strange power saving mode enabled?

Message from syslogd@gismo at Feb 20 14:12:52 ...
kernel:[184232.065182] Dazed and confused, but trying to continue
=====

> Clearly it should not generate so many log messages. Can you test
> whether the attached patch fixes that problem for you? See
> <http://kernel-handbook.alioth.debian.org/ch-common-tasks.html#s-common-official> for instructions on how to do this.

I am sorry for the delay, but I tried to build a kernel and it stopped
because of disk space (I had something like 2.5 GB available). Then I
freed 8.1 GB and still this was not enough. I would like some of this
information was already available on the kernel-handbook documentation,
at least I would have not tried beforehand.

Never mind, I managed to freed 18 GB and compile 3.2.4-1 with the
suggested patch, the resulting .deb are available upon request (I sort
of hated that the compilation was not done on a clean environment la
pbuilder, but this is another story). With this kernel I still get the
same messages as without the patch :-(

I found something strange with plain Debian kernels: after the first
pm-hibernate cycle, the bug is not reproducible, i.e. activating
autosuspend is OK. It seems that de- and re-activating autosuspend is
enough to trigger the bug. But please take this as a pure comment.

Please also note that the culprit seems to be the activation of both
powertop's "Runtime PM for PCI Device Intel Corporation N10/ICH 7 Family
USB UHCI Controller #[...]" and "Autosuspend for USB device [...]HCI
Host Controller [usb[...]]" voices: activating only one of the two seems
to be OK.

Thx, bye,
Gismo / Luca
 

Thread Tools




All times are GMT. The time now is 01:17 PM.

VBulletin, Copyright ©2000 - 2014, Jelsoft Enterprises Ltd.
Content Relevant URLs by vBSEO ©2007, Crawlability, Inc.
Copyright 2007 - 2008, www.linux-archive.org