[Nut-upsdev] USB Problem

Greg Grotsky spikeygg at gmail.com
Fri Feb 9 03:20:03 CET 2007 

So my syslog filled up with this:
Feb  8 06:47:59 mythbox upsd[3836]: Data for UPS [CyberPower] is stale -
check driver
Feb  8 06:47:59 mythbox upsd[3836]: UPS [CyberPower] data is no longer stale
Feb  8 06:48:01 mythbox upsd[3836]: Data for UPS [CyberPower] is stale -
check driver
Feb  8 06:48:01 mythbox upsd[3836]: UPS [CyberPower] data is no longer stale
Feb  8 06:48:03 mythbox upsd[3836]: Data for UPS [CyberPower] is stale -
check driver
Feb  8 06:48:03 mythbox upsd[3836]: UPS [CyberPower] data is no longer stale
Feb  8 06:48:05 mythbox upsd[3836]: Data for UPS [CyberPower] is stale -
check driver
Feb  8 06:48:05 mythbox upsd[3836]: UPS [CyberPower] data is no longer stale
...

I noticed that the ups was nolonger listed in lsusb, so I unloaded the
program and drivers and unplugged and replugged the ups.

After that I loaded upsdrvctl, and then upsd and my syslog spit out the
following:
Feb  8 19:09:34 mythbox usbhid-ups[8984]: Startup successful
Feb  8 19:09:34 mythbox upsd[8985]: listening on 0.0.0.0 port 3493
Feb  8 19:09:34 mythbox upsd[8985]: Connected to UPS [CyberPower]:
CyberPower
Feb  8 19:09:34 mythbox upsd[8986]: Startup successful
Feb  8 19:09:39 mythbox kernel: Unable to handle kernel NULL pointer
dereference at 0000000000000078 RIP:
Feb  8 19:09:39 mythbox kernel:  [<ffffffff88086947>]
:ohci_hcd:ohci_urb_dequeue+0x2f/0x79
Feb  8 19:09:39 mythbox kernel: PGD a18d067 PUD 33d0f067 PMD 0
Feb  8 19:09:39 mythbox kernel: Oops: 0000 [1] PREEMPT
Feb  8 19:09:39 mythbox kernel: CPU 0
Feb  8 19:09:39 mythbox kernel: Modules linked in: nvidia(P) imon_vfd
lirc_serial lirc_dev ipv6 dm_snapshot dm_mirror dm_mod w83627hf hwmon_vid
eeprom lm90 i2c_isa
cpufreq_ondemand cpufreq_userspace powernow_k8 freq_table processor sbp2
joydev ide_cd cdrom usbhid ff_memless tuner amd74xx snd_intel8x0
snd_ac97_codec snd_ac97_bu
s snd_pcm_oss snd_mixer_oss snd_pcm snd_timer snd i2c_nforce2 cx8800
cx88_dvb cx8802 cx88xx ir_common cx88_vp3054_i2c i2c_algo_bit mt352 dvb_pll
or51132 video_buf_d
vb dvb_core psmouse generic soundcore nxt200x firmware_class video_buf
tveeprom compat_ioctl32 btcx_risc videodev v4l1_compat v4l2_common rtc
8250_pnp 8250 serial_c
ore parport_pc parport ohci1394 ieee1394 k8temp ohci_hcd ehci_hcd ide_core
isl6421 zl10353 cx24123 lgdt330x cx22702 r8169 i2c_core usbcore forcedeth
snd_page_alloc
evdev unix
Feb  8 19:09:39 mythbox kernel: Pid: 8984, comm: usbhid-ups Tainted: P
2.6.19-Dec_1st #2
Feb  8 19:09:39 mythbox kernel: RIP: 0010:[<ffffffff88086947>]
[<ffffffff88086947>] :ohci_hcd:ohci_urb_dequeue+0x2f/0x79
Feb  8 19:09:39 mythbox kernel: RSP: 0018:ffff8100024c1d78  EFLAGS: 00010086
Feb  8 19:09:39 mythbox kernel: RAX: ffff81000ac09cc0 RBX: 0000000000000287
RCX: ffff810016418220
Feb  8 19:09:39 mythbox kernel: RDX: ffff81003ea97138 RSI: 0000000000000038
RDI: ffff81003ea97000
Feb  8 19:09:39 mythbox kernel: RBP: 0000000000000286 R08: 000000000002ee50
R09: 00000000094b2ab6
Feb  8 19:09:39 mythbox kernel: R10: 0000000045cbd7e3 R11: ffffffff88086918
R12: ffff81003ea97000
Feb  8 19:09:39 mythbox kernel: R13: ffff810016418200 R14: 00000000fffffffe
R15: 00000000ffffffff
Feb  8 19:09:39 mythbox kernel: FS:  00002b57e827dc80(0000)
GS:ffffffff80482000(0000) knlGS:00000000f776c6c0
Feb  8 19:09:39 mythbox kernel: CS:  0010 DS: 0000 ES: 0000 CR0:
000000008005003b
Feb  8 19:09:39 mythbox kernel: CR2: 0000000000000078 CR3: 0000000012ffa000
CR4: 00000000000006e0
Feb  8 19:09:39 mythbox kernel: Process usbhid-ups (pid: 8984, threadinfo
ffff8100024c0000, task ffff81002dfc30c0)
Feb  8 19:09:39 mythbox kernel: Stack:  00000000fffffff0 ffffffff88020f68
ffff810016418200 00007fffc2c873f0
Feb  8 19:09:39 mythbox kernel:  ffff810003c16a40 00007fffc2c873f0
000000000000550b ffffffff88021505
Feb  8 19:09:39 mythbox kernel:  0000000000000000 0000000000000000
0000000000000000 0000000000000000
Feb  8 19:09:39 mythbox kernel: Call Trace:
Feb  8 19:09:39 mythbox kernel:  [<ffffffff88020f68>]
:usbcore:usb_hcd_unlink_urb+0x177/0x1eb
Feb  8 19:09:39 mythbox kernel:  [<ffffffff88021505>]
:usbcore:usb_kill_urb+0x72/0x121
Feb  8 19:09:39 mythbox kernel:  [<ffffffff88028efd>]
:usbcore:usbdev_ioctl+0x1201/0x155c
Feb  8 19:09:39 mythbox kernel:  [<ffffffff80221c91>]
default_wake_function+0x0/0xe
Feb  8 19:09:39 mythbox kernel:  [<ffffffff803c6c0e>]
__down_failed+0x35/0x3a
Feb  8 19:09:39 mythbox kernel:  [<ffffffff80270cc9>] do_ioctl+0x55/0x6b
Feb  8 19:09:39 mythbox kernel:  [<ffffffff80270f31>] vfs_ioctl+0x252/0x26b
Feb  8 19:09:39 mythbox kernel:  [<ffffffff80270f86>] sys_ioctl+0x3c/0x5e
Feb  8 19:09:39 mythbox kernel:  [<ffffffff802096ee>] system_call+0x7e/0x83
Feb  8 19:09:39 mythbox kernel:
Feb  8 19:09:39 mythbox kernel:
Feb  8 19:09:39 mythbox kernel: Code: 80 7e 40 02 75 17 48 89 d7 e8 93 d8 ff
ff eb 0d 48 85 c0 74
Feb  8 19:09:39 mythbox kernel: RIP  [<ffffffff88086947>]
:ohci_hcd:ohci_urb_dequeue+0x2f/0x79
Feb  8 19:09:39 mythbox kernel:  RSP <ffff8100024c1d78>
Feb  8 19:09:39 mythbox kernel: CR2: 0000000000000078
Feb  8 19:09:39 mythbox kernel:  <6>note: usbhid-ups[8984] exited with
preempt_count 1
Feb  8 19:09:39 mythbox kernel: BUG: scheduling while atomic:
usbhid-ups/0x00000001/8984
Feb  8 19:09:39 mythbox kernel:
Feb  8 19:09:39 mythbox kernel: Call Trace:
Feb  8 19:09:39 mythbox kernel:  [<ffffffff803c4afb>]
__sched_text_start+0x5b/0x4c0
Feb  8 19:09:39 mythbox kernel:  [<ffffffff80225745>]
__call_console_drivers+0x41/0x4f
Feb  8 19:09:39 mythbox kernel:  [<ffffffff803c6e2d>] __down+0xb2/0x140
Feb  8 19:09:39 mythbox kernel:  [<ffffffff80221c91>]
default_wake_function+0x0/0xe
Feb  8 19:09:39 mythbox kernel:  [<ffffffff80367193>] sk_free+0xbc/0x122
Feb  8 19:09:39 mythbox kernel:  [<ffffffff803c6c0e>]
__down_failed+0x35/0x3a
Feb  8 19:09:39 mythbox kernel:  [<ffffffff803642ea>]
sock_destroy_inode+0x0/0x10
Feb  8 19:09:39 mythbox kernel:  [<ffffffff88027a78>]
:usbcore:usbdev_release+0x0/0xc3
Feb  8 19:09:39 mythbox kernel:  [<ffffffff88027a9b>]
:usbcore:usbdev_release+0x23/0xc3
Feb  8 19:09:39 mythbox kernel:  [<ffffffff80265d82>] __fput+0x9e/0x1ca
Feb  8 19:09:39 mythbox kernel:  [<ffffffff802636fb>] filp_close+0x5c/0x64
Feb  8 19:09:39 mythbox kernel:  [<ffffffff8022712d>]
put_files_struct+0x6b/0xc2
Feb  8 19:09:39 mythbox kernel:  [<ffffffff80228529>] do_exit+0x267/0x946
Feb  8 19:09:39 mythbox kernel:  [<ffffffff80218e94>]
do_page_fault+0x727/0x7a3
Feb  8 19:09:39 mythbox kernel:  [<ffffffff803c717d>] error_exit+0x0/0x84
Feb  8 19:09:39 mythbox kernel:  [<ffffffff88086918>]
:ohci_hcd:ohci_urb_dequeue+0x0/0x79
Feb  8 19:09:39 mythbox kernel:  [<ffffffff88086947>]
:ohci_hcd:ohci_urb_dequeue+0x2f/0x79
Feb  8 19:09:39 mythbox kernel:  [<ffffffff88020f68>]
:usbcore:usb_hcd_unlink_urb+0x177/0x1eb
Feb  8 19:09:39 mythbox kernel:  [<ffffffff88021505>]
:usbcore:usb_kill_urb+0x72/0x121
Feb  8 19:09:39 mythbox kernel:  [<ffffffff88028efd>]
:usbcore:usbdev_ioctl+0x1201/0x155c
Feb  8 19:09:39 mythbox kernel:  [<ffffffff80221c91>]
default_wake_function+0x0/0xe
Feb  8 19:09:39 mythbox kernel:  [<ffffffff803c6c0e>]
__down_failed+0x35/0x3a
Feb  8 19:09:39 mythbox kernel:  [<ffffffff80270cc9>] do_ioctl+0x55/0x6b
Feb  8 19:09:39 mythbox kernel:  [<ffffffff80270f31>] vfs_ioctl+0x252/0x26b
Feb  8 19:09:39 mythbox kernel:  [<ffffffff80270f86>] sys_ioctl+0x3c/0x5e
Feb  8 19:09:39 mythbox kernel:  [<ffffffff802096ee>] system_call+0x7e/0x83
Feb  8 19:09:39 mythbox kernel:

Then I tried to do another lsusb to see if it's still listed and lsusb
locked up the terminal.  I can't even kill -9 the lsusb process, it's just
hung.  Also, the [usbhid-ups] process is hung as well, and cannot be killed
either.

Any ideas what could cause this?  It looks like at some point the program
lost connection with my ups and the software didn't like that.  I think I'm
going to have to reboot to fix this one.

-Greg
-------------- next part --------------
An HTML attachment was scrubbed...
URL: http://lists.alioth.debian.org/pipermail/nut-upsdev/attachments/20070208/8be7559a/attachment.htm

More information about the Nut-upsdev mailing list