--- Begin Message ---
- To: Debian Bug Tracking System <submit@bugs.debian.org>
- Subject: kernel: time jumps and usb resets
- From: Mario Frasca <mfrasca@zonnet.nl>
- Date: Mon, 28 May 2007 18:21:24 +0200
- Message-id: <20070528162124.30298.27803.reportbug@localhost.localdomain>
Package: linux-image-2.6.21-1-powerpc
Version: 2.6.21-4
Severity: important
File: kernel
I don't know if this is a hardware problem or a kernel bug. the
same hardware had served correctly for years, so I sincerely hope it's a
kernel bug...
I'm not sure when the problem started to display...
a crontab line for root runs ntpdate -q every single minute...
the drift looks more or less constant, until occasional jumps. these
jumps are coincident with some kernel logging...
here are the syslog lines, bracketed by the output of ntpdate:
...
28 May 16:46:04 ntpdate[27236]: step time server 206.57.44.149 offset -23.773322 sec
May 28 16:49:06 localhost kernel: usb 2-1.3: reset full speed USB device using ohci_hcd and address 4
May 28 16:49:07 localhost kernel: usb 1-1.2: reset full speed USB device using ohci_hcd and address 4
28 May 16:49:10 ntpdate[27256]: step time server 206.57.44.149 offset -187.783438 sec
...
28 May 16:55:04 ntpdate[27340]: step time server 206.57.44.149 offset -187.782406 sec
May 28 16:57:43 localhost kernel: usb 2-1.3: reset full speed USB device using ohci_hcd and address 4
May 28 16:57:43 localhost kernel: usb 1-1.2: reset full speed USB device using ohci_hcd and address 4
28 May 16:57:46 ntpdate[27362]: step time server 206.57.44.149 offset -297.535529 sec
...
28 May 17:01:04 ntpdate[27427]: step time server 206.57.44.149 offset -297.534059 sec
May 28 17:03:27 localhost kernel: usb 1-1.2: reset full speed USB device using ohci_hcd and address 4
May 28 17:03:27 localhost kernel: usb 2-1.4.3: reset full speed USB device using ohci_hcd and address 7
28 May 17:03:30 ntpdate[27453]: step time server 206.57.44.149 offset -409.487201 sec
...
are these kernel logs an other effect of the jumps, or are they closely related to the cause?
maybe it's also relevant that I am running wmaker and that more or less
with the same frequency of these problems (maybe in coincidence?) I have
to restart the window manager because the keyboard stops working (but
the keys simulating mouse buttons 2 and 3 keep working).
-- Package-specific info:
** Version: Linux version 2.6.21-1-powerpc (Debian 2.6.21-4) (waldi@debian.org) (gcc version 4.1.3 20070518 (prerelease) (Debian 4.1.2-8)) #1 Sat May 26 14:30:26 CEST 2007
** Not tainted
** Kernel log:
sdf: Mode Sense: 27 00 00 00
sdf: assuming drive cache: write through
sdf: [mac] sdf1 sdf2 sdf3 sdf4 sdf5 sdf6
sd 7:0:0:0: Attached scsi disk sdf
sd 7:0:0:0: Attached scsi generic sg5 type 0
hfs: write access to a jounaled filesystem is not supported, use the force option at your own risk, mounting read-only.
hfs: write access to a jounaled filesystem is not supported, use the force option at your own risk, mounting read-only.
hfs: filesystem is marked journaled, leaving read-only.
hfs: write access to a jounaled filesystem is not supported, use the force option at your own risk, mounting read-only.
hfs: write access to a jounaled filesystem is not supported, use the force option at your own risk, mounting read-only.
hfs: write access to a jounaled filesystem is not supported, use the force option at your own risk, mounting read-only.
hfs: write access to a jounaled filesystem is not supported, use the force option at your own risk, mounting read-only.
usb 2-1.2: USB disconnect, address 10
ieee1394: Node changed: 0-01:1023 -> 0-00:1023
ieee1394: Node suspended: ID:BUS[0-00:1023] GUID[0010100550000b43]
Synchronizing SCSI cache for disk sda:
FAILED
status = 0, message = 00, host = 1, driver = 00
<6>usb 2-1.3: reset full speed USB device using ohci_hcd and address 4
usb 1-1.2: reset full speed USB device using ohci_hcd and address 4
usb 2-1.4.3: reset full speed USB device using ohci_hcd and address 7
usb 2-1.3: reset full speed USB device using ohci_hcd and address 4
usb 1-1.2: reset full speed USB device using ohci_hcd and address 4
usb 1-1.2: reset full speed USB device using ohci_hcd and address 4
usb 2-1.3: reset full speed USB device using ohci_hcd and address 4
usb 1-1.2: reset full speed USB device using ohci_hcd and address 4
usb 2-1.4.3: reset full speed USB device using ohci_hcd and address 7
usb 1-1.2: reset full speed USB device using ohci_hcd and address 4
usb 2-1.3: reset full speed USB device using ohci_hcd and address 4
usb 2-1.3: reset full speed USB device using ohci_hcd and address 4
usb 1-1.2: reset full speed USB device using ohci_hcd and address 4
usb 2-1.4.3: reset full speed USB device using ohci_hcd and address 7
usb 1-1.2: reset full speed USB device using ohci_hcd and address 4
usb 2-1.4.3: reset full speed USB device using ohci_hcd and address 7
usb 1-1.2: reset full speed USB device using ohci_hcd and address 4
usb 1-1.2: reset full speed USB device using ohci_hcd and address 4
usb 2-1.3: reset full speed USB device using ohci_hcd and address 4
usb 1-1.2: reset full speed USB device using ohci_hcd and address 4
usb 2-1.3: reset full speed USB device using ohci_hcd and address 4
usb 1-1.2: reset full speed USB device using ohci_hcd and address 4
usb 2-1.3: reset full speed USB device using ohci_hcd and address 4
usb 1-1.2: reset full speed USB device using ohci_hcd and address 4
usb 2-1.3: reset full speed USB device using ohci_hcd and address 4
usb 1-1.2: reset full speed USB device using ohci_hcd and address 4
usb 2-1.3: reset full speed USB device using ohci_hcd and address 4
usb 1-1.2: reset full speed USB device using ohci_hcd and address 4
usb 2-1.3: reset full speed USB device using ohci_hcd and address 4
usb 1-1.2: reset full speed USB device using ohci_hcd and address 4
usb 1-1.2: reset full speed USB device using ohci_hcd and address 4
usb 2-1.4.3: reset full speed USB device using ohci_hcd and address 7
usb 2-1.4.3: reset full speed USB device using ohci_hcd and address 7
usb 1-1.2: reset full speed USB device using ohci_hcd and address 4
usb 2-1.3: reset full speed USB device using ohci_hcd and address 4
usb 1-1.2: reset full speed USB device using ohci_hcd and address 4
usb 2-1.4.2: USB disconnect, address 6
ir-usb ttyUSB0: IR Dongle converter now disconnected from ttyUSB0
ir-usb 2-1.4.2:1.0: device disconnected
usb 2-1.4: USB disconnect, address 5
usb 2-1.4.3: USB disconnect, address 7
usb 2-1.4.4: USB disconnect, address 8
drivers/usb/class/usblp.c: usblp0: removed
usb 2-1.4: new full speed USB device using ohci_hcd and address 11
usb 2-1.4: configuration #1 chosen from 1 choice
hub 2-1.4:1.0: USB hub found
hub 2-1.4:1.0: 4 ports detected
usb 2-1.4.3: new full speed USB device using ohci_hcd and address 12
usb 2-1.4.3: rejected 1 configuration due to insufficient available bus power
usb 2-1.4.3: no configuration chosen from 1 choice
usb 2-1.4.4: new full speed USB device using ohci_hcd and address 13
usb 2-1.4.4: configuration #1 chosen from 1 choice
drivers/usb/class/usblp.c: usblp0: USB Bidirectional printer dev 13 if 0 alt 0 proto 2 vid 0x04B8 pid 0x0005
usb 2-1.4: USB disconnect, address 11
usb 2-1.4.3: USB disconnect, address 12
usb 2-1.4.4: USB disconnect, address 13
drivers/usb/class/usblp.c: usblp0: removed
usb 2-1.4: new full speed USB device using ohci_hcd and address 14
usb 2-1.4: configuration #1 chosen from 1 choice
hub 2-1.4:1.0: USB hub found
hub 2-1.4:1.0: 4 ports detected
usb 2-1.4.3: new full speed USB device using ohci_hcd and address 15
usb 2-1.4.3: configuration #1 chosen from 1 choice
scsi8 : SCSI emulation for USB Mass Storage devices
usb-storage: device found at 15
usb-storage: waiting for device to settle before scanning
usb 2-1.4.4: new full speed USB device using ohci_hcd and address 16
usb 2-1.4.4: configuration #1 chosen from 1 choice
drivers/usb/class/usblp.c: usblp0: USB Bidirectional printer dev 16 if 0 alt 0 proto 2 vid 0x04B8 pid 0x0005
usb-storage: device scan complete
scsi 8:0:0:0: Direct-Access Y-E DATA USB-FDU 7.03 PQ: 0 ANSI: 0 CCS
sd 8:0:0:0: Attached scsi removable disk sda
sd 8:0:0:0: Attached scsi generic sg0 type 0
usb 2-1.4.2: new full speed USB device using ohci_hcd and address 17
usb 2-1.4.2: configuration #1 chosen from 1 choice
ir-usb 2-1.4.2:1.0: IR Dongle converter detected
usb 2-1.4.2: IR Dongle converter now attached to ttyUSB0
usb 2-1.3: reset full speed USB device using ohci_hcd and address 4
usb 1-1.2: reset full speed USB device using ohci_hcd and address 4
usb 1-1.2: reset full speed USB device using ohci_hcd and address 4
usb 2-1.4.3: reset full speed USB device using ohci_hcd and address 15
usb 2-1.3: reset full speed USB device using ohci_hcd and address 4
** Loaded modules:
Module Size Used by
nls_utf8 2176 0
hfsplus 87972 0
tcp_diag 1760 0
inet_diag 11976 1 tcp_diag
nfs 280008 0
nfsd 244524 17
exportfs 6272 1 nfsd
lockd 75032 3 nfs,nfsd
nfs_acl 4000 2 nfs,nfsd
sunrpc 195616 13 nfs,nfsd,lockd,nfs_acl
lp 13516 0
parport 43152 1 lp
ipv6 287180 30
ext2 77704 0
fuse 51380 1
dm_snapshot 20320 0
dm_mirror 24212 0
dm_mod 66444 2 dm_snapshot,dm_mirror
snd_powermac 48160 2
snd_pcm_oss 52096 1
snd_pcm 92004 2 snd_powermac,snd_pcm_oss
snd_mixer_oss 20384 2 snd_pcm_oss
snd_seq_oss 39604 0
snd_seq_midi 9664 0
snd_rawmidi 28384 1 snd_seq_midi
snd_seq_midi_event 8064 2 snd_seq_oss,snd_seq_midi
snd_seq 60136 5 snd_seq_oss,snd_seq_midi,snd_seq_midi_event
snd_timer 25828 2 snd_pcm,snd_seq
snd_seq_device 9420 4 snd_seq_oss,snd_seq_midi,snd_rawmidi,snd_seq
snd 67476 9 snd_powermac,snd_pcm_oss,snd_pcm,snd_mixer_oss,snd_seq_oss,snd_rawmidi,snd_seq,snd_timer,snd_seq_device
soundcore 8900 3 snd
snd_page_alloc 11208 1 snd_pcm
irda_usb 22980 0
firmware_class 11168 1 irda_usb
irda 232408 1 irda_usb
crc_ccitt 2080 1 irda
ir_usb 12620 0
usbserial 37616 1 ir_usb
usblp 16064 0
uninorth_agp 11724 1
agpgart 40284 1 uninorth_agp
tsdev 9120 0
evdev 12448 4
eth1394 21124 0
sg 40544 0
sr_mod 19972 0
ext3 152904 5
jbd 67880 1 ext3
mbcache 9540 2 ext2,ext3
sd_mod 24688 0
sbp2 24516 0
usb_storage 91244 0
scsi_mod 177708 5 sg,sr_mod,sd_mod,sbp2,usb_storage
usbhid 43364 0
hid 30400 1 usbhid
ff_memless 6152 1 usbhid
ide_cd 47236 0
cdrom 43164 2 sr_mod,ide_cd
ide_disk 18784 7
ohci1394 41488 0
sungem 34916 0
sungem_phy 12896 1 sungem
ieee1394 110672 3 eth1394,sbp2,ohci1394
i2c_powermac 5984 0
** PCI devices:
0000:00:0b.0 Host bridge [0600]: Apple Computer Inc. UniNorth/Pangea AGP [106b:0027]
Control: I/O- Mem+ BusMaster+ SpecCycle- MemWINV+ VGASnoop- ParErr- Stepping- SERR- FastB2B-
Status: Cap+ 66MHz+ UDF- FastB2B- ParErr- DEVSEL=medium >TAbort- <TAbort- <MAbort+ >SERR- <PERR-
Latency: 16, Cache Line Size: 32 bytes
Capabilities: <access denied>
0000:00:10.0 Display controller [0380]: ATI Technologies Inc Rage 128 Pro Ultra TR [1002:5452]
Subsystem: ATI Technologies Inc Rage 128 Pro Ultra TR [1002:5452]
Control: I/O+ Mem+ BusMaster+ SpecCycle- MemWINV- VGASnoop- ParErr- Stepping+ SERR- FastB2B-
Status: Cap+ 66MHz+ UDF- FastB2B+ ParErr- DEVSEL=medium >TAbort- <TAbort- <MAbort- >SERR- <PERR-
Latency: 255 (2000ns min), Cache Line Size: 32 bytes
Interrupt: pin A routed to IRQ 48
Region 0: Memory at 94000000 (32-bit, prefetchable) [size=64M]
Region 1: I/O ports at 0400 [size=256]
Region 2: Memory at 90000000 (32-bit, non-prefetchable) [size=16K]
Expansion ROM at f1000000 [disabled] [size=128K]
Capabilities: <access denied>
0001:10:0b.0 Host bridge [0600]: Apple Computer Inc. UniNorth/Pangea PCI [106b:0028]
Control: I/O- Mem+ BusMaster+ SpecCycle- MemWINV+ VGASnoop- ParErr- Stepping- SERR- FastB2B-
Status: Cap- 66MHz+ UDF- FastB2B- ParErr- DEVSEL=medium >TAbort- <TAbort- <MAbort+ >SERR- <PERR-
Latency: 16, Cache Line Size: 32 bytes
0001:10:17.0 Unknown class [ff00]: Apple Computer Inc. KeyLargo/Pangea Mac I/O [106b:0025]
Control: I/O- Mem+ BusMaster+ SpecCycle- MemWINV+ VGASnoop- ParErr- Stepping- SERR- FastB2B-
Status: Cap- 66MHz- UDF- FastB2B- ParErr- DEVSEL=medium >TAbort- <TAbort- <MAbort- >SERR- <PERR-
Latency: 16, Cache Line Size: 32 bytes
Region 0: Memory at 80000000 (32-bit, non-prefetchable) [size=512K]
0001:10:18.0 USB Controller [0c03]: Apple Computer Inc. KeyLargo/Pangea USB [106b:0026] (prog-if 10 [OHCI])
Control: I/O- Mem+ BusMaster+ SpecCycle- MemWINV- VGASnoop- ParErr- Stepping- SERR- FastB2B-
Status: Cap+ 66MHz- UDF- FastB2B- ParErr- DEVSEL=medium >TAbort- <TAbort- <MAbort- >SERR- <PERR-
Latency: 16 (750ns min, 21500ns max)
Interrupt: pin A routed to IRQ 27
Region 0: Memory at 80081000 (32-bit, non-prefetchable) [size=4K]
Capabilities: <access denied>
0001:10:19.0 USB Controller [0c03]: Apple Computer Inc. KeyLargo/Pangea USB [106b:0026] (prog-if 10 [OHCI])
Control: I/O- Mem+ BusMaster+ SpecCycle- MemWINV- VGASnoop- ParErr- Stepping- SERR- FastB2B-
Status: Cap+ 66MHz- UDF- FastB2B- ParErr- DEVSEL=medium >TAbort- <TAbort- <MAbort- >SERR- <PERR-
Latency: 16 (750ns min, 21500ns max)
Interrupt: pin A routed to IRQ 28
Region 0: Memory at 80080000 (32-bit, non-prefetchable) [size=4K]
Capabilities: <access denied>
0002:20:0b.0 Host bridge [0600]: Apple Computer Inc. UniNorth/Pangea Internal PCI [106b:0029]
Control: I/O- Mem+ BusMaster+ SpecCycle- MemWINV+ VGASnoop- ParErr- Stepping- SERR- FastB2B-
Status: Cap- 66MHz+ UDF- FastB2B- ParErr- DEVSEL=medium >TAbort- <TAbort- <MAbort+ >SERR- <PERR-
Latency: 16, Cache Line Size: 32 bytes
0002:20:0e.0 FireWire (IEEE 1394) [0c00]: Apple Computer Inc. UniNorth/Pangea FireWire [106b:0030] (prog-if 10 [OHCI])
Subsystem: Apple Computer Inc. UniNorth/Pangea FireWire [106b:0030]
Control: I/O- Mem+ BusMaster+ SpecCycle- MemWINV+ VGASnoop- ParErr- Stepping- SERR- FastB2B-
Status: Cap+ 66MHz+ UDF- FastB2B+ ParErr- DEVSEL=medium >TAbort- <TAbort- <MAbort- >SERR- <PERR-
Latency: 16 (3000ns min, 6000ns max), Cache Line Size: 32 bytes
Interrupt: pin A routed to IRQ 40
Region 0: Memory at f5000000 (32-bit, non-prefetchable) [size=4K]
Capabilities: <access denied>
0002:20:0f.0 Ethernet controller [0200]: Apple Computer Inc. UniNorth/Pangea GMAC (Sun GEM) [106b:0024]
Control: I/O- Mem+ BusMaster+ SpecCycle- MemWINV+ VGASnoop- ParErr- Stepping- SERR- FastB2B-
Status: Cap- 66MHz+ UDF- FastB2B+ ParErr- DEVSEL=slow >TAbort- <TAbort- <MAbort- >SERR- <PERR+
Latency: 16 (16000ns min, 16000ns max), Cache Line Size: 32 bytes
Interrupt: pin A routed to IRQ 41
Region 0: Memory at f5200000 (32-bit, non-prefetchable) [size=2M]
Expansion ROM at f5100000 [disabled] [size=1M]
-- System Information:
Debian Release: lenny/sid
APT prefers proposed-updates
APT policy: (500, 'proposed-updates'), (500, 'unstable'), (500, 'testing'), (500, 'stable'), (1, 'experimental')
Architecture: powerpc (ppc)
Kernel: Linux 2.6.21-1-powerpc
Locale: LANG=en_DK.UTF-8, LC_CTYPE=en_DK.UTF-8 (charmap=UTF-8)
Shell: /bin/sh linked to /bin/bash
Versions of packages linux-image-2.6.21-1-powerpc depends on:
ii initramfs-tools [linux-initr 0.87b tools for generating an initramfs
ii mkvmlinuz 33 create a kernel to boot a PowerPC
ii module-init-tools 3.3-pre11-1 tools for managing Linux kernel mo
linux-image-2.6.21-1-powerpc recommends no packages.
-- debconf information:
linux-image-2.6.21-1-powerpc/preinst/lilo-has-ramdisk:
linux-image-2.6.21-1-powerpc/postinst/bootloader-error-2.6.21-1-powerpc:
linux-image-2.6.21-1-powerpc/preinst/overwriting-modules-2.6.21-1-powerpc: true
linux-image-2.6.21-1-powerpc/postinst/bootloader-test-error-2.6.21-1-powerpc:
linux-image-2.6.21-1-powerpc/preinst/initrd-2.6.21-1-powerpc:
linux-image-2.6.21-1-powerpc/preinst/failed-to-move-modules-2.6.21-1-powerpc:
linux-image-2.6.21-1-powerpc/preinst/abort-overwrite-2.6.21-1-powerpc:
linux-image-2.6.21-1-powerpc/preinst/already-running-this-2.6.21-1-powerpc:
linux-image-2.6.21-1-powerpc/postinst/old-initrd-link-2.6.21-1-powerpc: true
shared/kernel-image/really-run-bootloader: true
linux-image-2.6.21-1-powerpc/preinst/bootloader-initrd-2.6.21-1-powerpc: true
linux-image-2.6.21-1-powerpc/prerm/removing-running-kernel-2.6.21-1-powerpc: true
linux-image-2.6.21-1-powerpc/postinst/old-dir-initrd-link-2.6.21-1-powerpc: true
linux-image-2.6.21-1-powerpc/postinst/depmod-error-initrd-2.6.21-1-powerpc: false
linux-image-2.6.21-1-powerpc/postinst/old-system-map-link-2.6.21-1-powerpc: true
linux-image-2.6.21-1-powerpc/preinst/elilo-initrd-2.6.21-1-powerpc: true
linux-image-2.6.21-1-powerpc/preinst/abort-install-2.6.21-1-powerpc:
linux-image-2.6.21-1-powerpc/postinst/create-kimage-link-2.6.21-1-powerpc: true
linux-image-2.6.21-1-powerpc/preinst/lilo-initrd-2.6.21-1-powerpc: true
linux-image-2.6.21-1-powerpc/prerm/would-invalidate-boot-loader-2.6.21-1-powerpc: true
linux-image-2.6.21-1-powerpc/postinst/depmod-error-2.6.21-1-powerpc: false
linux-image-2.6.21-1-powerpc/postinst/kimage-is-a-directory:
--- End Message ---