apcupsd kernel oops in usbhid:hiddev_ioctl+0x67/0xae0
Affects | Status | Importance | Assigned to | Milestone | |
---|---|---|---|---|---|
apcupsd (Ubuntu) |
New
|
Undecided
|
Unassigned |
Bug Description
Binary package hint: apcupsd
ii apcupsd 3.14.2-1build1 APC UPS Power Management (daemon)
Linux lexus 2.6.24-19-generic #1 SMP Wed Aug 20 17:53:40 UTC 2008 x86_64 GNU/Lin
Description: Ubuntu 8.04.2
Release: 8.04
apcupsd should be started during boot, but it does not. I then proceed to issues:
bash: sudo /etc/init.d/apcupsd start
Starting UPS power management: apcupsd.
bash: sudo apcaccess status
APC : 001,039,0900
DATE : Wed Mar 11 23:15:11 PDT 2009
HOSTNAME : lexus
RELEASE : 3.14.2
VERSION : 3.14.2 (15 September 2007) debian
UPSNAME : lexus
CABLE : USB Cable
MODEL : USB UPS Driver
UPSMODE : Stand Alone
STARTTIME: Wed Mar 11 23:15:09 PDT 2009
STATUS : COMMLOST
LINEV : 000.0 Volts
LOADPCT : 0.0 Percent Load Capacity
BCHARGE : 000.0 Percent
TIMELEFT : 0.0 Minutes
MBATTCHG : 5 Percent
MINTIMEL : 3 Minutes
MAXTIME : 15 Seconds
OUTPUTV : 000.0 Volts
SENSE : Unknown
DWAKE : -01 Seconds
DSHUTD : -01 Seconds
LOTRANS : -01.0 Volts
HITRANS : -01.0 Volts
RETPCT : -01.0 Percent
ITEMP : 00.0 C Internal
ALARMDEL : Always
BATTV : 00.0 Volts
LINEFREQ : 0.0 Hz
NUMXFERS : 0
TONBATT : 0 seconds
CUMONBATT: 0 seconds
XOFFBATT : N/A
STATFLAG : 0x07000100 Status Flag
SERIALNO :
BATTDATE :
NOMOUTV : -01
NOMBATTV : 0.0
APCMODEL :
END APC : Wed Mar 11 23:15:12 PDT 2009
Issue the command again:
sudo apcaccess status
FATAL ERROR in apcaccess.c at line 51
ps -A | grep apcupsd
8546 ? 00:00:00 apcupsd <defunct>
dmesg
....
[ 139.451606] hub 1-0:1.0: port 10 disabled by hub (EMI?), re-enabling...
[ 139.451612] usb 1-10: USB disconnect, address 19
[ 139.452321] /build/
[ 139.520161] usb 1-10: new low speed USB device using ohci_hcd and address 20
[ 139.605561] usb 1-10: configuration #1 chosen from 1 choice
[ 140.098330] hiddev98hidraw4: USB HID v1.10 Device [American Power Conversion Smart-UPS 750 FW:651.18.D USB FW:7.3] on usb-0000:00:02.0-10
[ 142.208754] hub 1-0:1.0: port 10 disabled by hub (EMI?), re-enabling...
[ 142.208760] usb 1-10: USB disconnect, address 20
[ 142.209433] /build/
[ 142.217143] Unable to handle kernel paging request at 00000000d4afbd18 RIP:
[ 142.217148] [<ffffffff881f0
[ 142.217154] PGD d0aab067 PUD 0
[ 142.217156] Oops: 0000 [2] SMP
[ 142.217158] CPU 1
[ 142.217159] Modules linked in: vmnet vsock(F) vmci vmmon rfcomm l2cap bluetooth nfsd auth_rpcgss exportfs ppdev autofs4 powernow_k8 cpufreq_ondemand cpufreq_
[ 142.217203] Pid: 8546, comm: apcupsd Tainted: PF D 2.6.24-19-generic #1
[ 142.217205] RIP: 0010:[<
[ 142.217210] RSP: 0018:ffff8100ce
[ 142.217211] RAX: 00000000d4afbc30 RBX: 00000000400c4807 RCX: 00007fff90b62e60
[ 142.217213] RDX: ffff810119972580 RSI: 00000000fffffffb RDI: ffff810108965800
[ 142.217214] RBP: ffff8100d0b8e000 R08: ffff8101174500c8 R09: 0000000000000000
[ 142.217216] R10: 0000000000000008 R11: ffffffff803169a0 R12: 00000000400c4807
[ 142.217218] R13: 00007fff90b62e60 R14: 00000000400c4807 R15: 00007fff90b63140
[ 142.217219] FS: 00007fef88b4a6e
[ 142.217221] CS: 0010 DS: 0000 ES: 0000 CR0: 000000008005003b
[ 142.217223] CR2: 00000000d4afbd18 CR3: 00000000ce8a5000 CR4: 00000000000006e0
[ 142.217224] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
[ 142.217226] DR3: 0000000000000000 DR6: 00000000ffff0ff0 DR7: 0000000000000400
[ 142.217227] Process apcupsd (pid: 8546, threadinfo ffff8100ce846000, task ffff8100d4aa97a0)
[ 142.217229] Stack: ffff8100ce847ea8 ffff8100d4aa97a0 0000000000000282 ffffffff80256ba9
[ 142.217232] ffff8100ce847ea8 00000000ffffffff 0000000000000282 ffffffff80256c69
[ 142.217234] 0000000000000282 ffff8100ce847ea8 0000000000000282 ffff8100ce847ea8
[ 142.217237] Call Trace:
[ 142.217244] [<ffffffff80256
[ 142.217248] [<ffffffff80256
[ 142.217254] [<ffffffff80256
[ 142.217257] [<ffffffff80467
[ 142.217262] [<ffffffff80256
[ 142.217270] [<ffffffff802c0
[ 142.217274] [<ffffffff802c0
[ 142.217281] [<ffffffff802c0
[ 142.217289] [<ffffffff8020c
[ 142.217300]
[ 142.217301]
[ 142.217301] Code: 48 8b 88 e8 00 00 00 0f 84 c0 00 00 00 81 fb 01 48 04 80 4c
[ 142.217307] RIP [<ffffffff881f0
[ 142.217311] RSP <ffff8100ce847e08>
[ 142.217312] CR2: 00000000d4afbd18
[ 142.217315] ---[ end trace f8657ab87c07be98 ]---
[ 142.277138] usb 1-10: new low speed USB device using ohci_hcd and address 21
[ 142.362707] usb 1-10: configuration #1 chosen from 1 choice
[ 142.372301] hub 1-0:1.0: port 10 disabled by hub (EMI?), re-enabling...
[ 142.372306] usb 1-10: USB disconnect, address 21
[ 142.441412] usb 1-10: new low speed USB device using ohci_hcd and address 22
[ 142.527738] usb 1-10: configuration #1 chosen from 1 choice
[ 142.999401] hiddev98hidraw4: USB HID v1.10 Device [American Power Conversion Smart-UPS 750 FW:651.18.D USB FW:7.3] on usb-0000:00:02.0-10
sudo /etc/init.d/apcupsd start
Starting UPS power management:
A copy of the daemon is still running. If you just stopped it,
please wait about 5 seconds for it to shut down.
ps -A | grep apcup
8546 ? 00:00:00 apcupsd <defunct>
sudo killall -9 apcupsd
sudo /etc/init.d/apcupsd start
Starting UPS power management: apcupsd.
ps -A | grep apcup
8648 ? 00:00:00 apcupsd
sudo apcaccess status
APC : 001,042,1020
DATE : Wed Mar 11 23:17:05 PDT 2009
HOSTNAME : lexus
RELEASE : 3.14.2
VERSION : 3.14.2 (15 September 2007) debian
UPSNAME : lexus
CABLE : USB Cable
MODEL : Smart-UPS 750
UPSMODE : Stand Alone
STARTTIME: Wed Mar 11 23:17:02 PDT 2009
STATUS : ONLINE
LINEV : 117.3 Volts
LOADPCT : 33.8 Percent Load Capacity
BCHARGE : 100.0 Percent
TIMELEFT : 29.0 Minutes
MBATTCHG : 5 Percent
MINTIMEL : 3 Minutes
MAXTIME : 15 Seconds
OUTPUTV : 117.3 Volts
SENSE : High
DWAKE : -01 Seconds
DSHUTD : 090 Seconds
LOTRANS : 106.0 Volts
HITRANS : 127.0 Volts
RETPCT : 000.0 Percent
ITEMP : 22.9 C Internal
ALARMDEL : Always
BATTV : 27.7 Volts
LINEFREQ : 60.0 Hz
LASTXFER : Automatic or explicit self test
NUMXFERS : 0
TONBATT : 0 seconds
CUMONBATT: 0 seconds
XOFFBATT : N/A
SELFTEST : NO
STATFLAG : 0x07000008 Status Flag
SERIALNO : AS0824242336
BATTDATE : 2008-06-13
NOMOUTV : 120
NOMBATTV : 24.0
FIRMWARE : 651.18.D USB FW:7.3
APCMODEL : Smart-UPS 750
END APC : Wed Mar 11 23:17:10 PDT 2009
sudo apcaccess status
Broadcast Message from root@lexus
(somewhere) at 23:18 ...
Communications restored with UPS lexus
FATAL ERROR in apcaccess.c at line 51
sudo apcaccess status
FATAL ERROR in apcaccess.c at line 51
ps -A | grep apcu
8648 ? 00:00:00 apcupsd <defunct>
dmesg
....
[ 142.527738] usb 1-10: configuration #1 chosen from 1 choice
[ 142.999401] hiddev98hidraw4: USB HID v1.10 Device [American Power Conversion Smart-UPS 750 FW:651.18.D USB FW:7.3] on usb-0000:00:02.0-10
[ 216.294490] hub 1-0:1.0: port 10 disabled by hub (EMI?), re-enabling...
[ 216.294496] usb 1-10: USB disconnect, address 22
[ 216.295717] /build/
[ 216.363819] usb 1-10: new low speed USB device using ohci_hcd and address 23
[ 216.451512] usb 1-10: configuration #1 chosen from 1 choice
[ 216.935456] hiddev98hidraw4: USB HID v1.10 Device [American Power Conversion Smart-UPS 750 FW:651.18.D USB FW:7.3] on usb-0000:00:02.0-10
[ 219.192870] hub 1-0:1.0: port 10 disabled by hub (EMI?), re-enabling...
[ 219.192876] usb 1-10: USB disconnect, address 23
[ 219.195559] Unable to handle kernel paging request at 00000000ce8d1918 RIP:
[ 219.195565] [<ffffffff881f0
[ 219.195574] PGD ce8a0067 PUD 0
[ 219.195576] Oops: 0000 [3] SMP
[ 219.195578] CPU 0
[ 219.195579] Modules linked in: vmnet vsock(F) vmci vmmon rfcomm l2cap bluetooth nfsd auth_rpcgss exportfs ppdev autofs4 powernow_k8 cpufreq_ondemand cpufreq_
[ 219.195624] Pid: 8648, comm: apcupsd Tainted: PF D 2.6.24-19-generic #1
[ 219.195626] RIP: 0010:[<
[ 219.195630] RSP: 0018:ffff8100d0
[ 219.195632] RAX: 00000000ce8d1830 RBX: 00000000c018480b RCX: 00000000006545f4
[ 219.195633] RDX: ffff8101180243c0 RSI: 00000000fffffffb RDI: ffff81011a7e7000
[ 219.195635] RBP: ffff8100d0b8e000 R08: ffff81011b7850c8 R09: 0000000000000000
[ 219.195637] R10: 0000000000000000 R11: ffffffff803169a0 R12: 00000000c018480b
[ 219.195638] R13: 00000000006545f4 R14: 00000000c018480b R15: 0000000000000000
[ 219.195640] FS: 00007f618c3ad6e
[ 219.195642] CS: 0010 DS: 0000 ES: 0000 CR0: 000000008005003b
[ 219.195643] CR2: 00000000ce8d1918 CR3: 00000000d0acb000 CR4: 00000000000006e0
[ 219.195645] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
[ 219.195646] DR3: 0000000000000000 DR6: 00000000ffff0ff0 DR7: 0000000000000400
[ 219.195648] Process apcupsd (pid: 8648, threadinfo ffff8100d0aa0000, task ffff8100ce8fe000)
[ 219.195649] Stack: ffff8100d0aa1e44 ffff8100ce8fe000 0000000000000282 ffffffff80256ba9
[ 219.195653] ffff8100d0aa1ea8 00000000ffffffff 0000000000000282 ffffffff80256c69
[ 219.195655] 0000000000000282 ffff8100d0aa1ea8 0000000000000282 ffff8100d0aa1ea8
[ 219.195657] Call Trace:
[ 219.195665] [<ffffffff80256
[ 219.195670] [<ffffffff80256
[ 219.195675] [<ffffffff80256
[ 219.195679] [<ffffffff80467
[ 219.195690] [<ffffffff802c0
[ 219.195694] [<ffffffff802c0
[ 219.195701] [<ffffffff802c0
[ 219.195709] [<ffffffff8020c
[ 219.195720]
[ 219.195721]
[ 219.195721] Code: 48 8b 88 e8 00 00 00 0f 84 c0 00 00 00 81 fb 01 48 04 80 4c
[ 219.195727] RIP [<ffffffff881f0
[ 219.195731] RSP <ffff8100d0aa1e08>
[ 219.195732] CR2: 00000000ce8d1918
[ 219.195734] ---[ end trace f8657ab87c07be98 ]---
[ 219.261274] usb 1-10: new low speed USB device using ohci_hcd and address 24
[ 219.347212] usb 1-10: configuration #1 chosen from 1 choice
[ 219.849187] hiddev98hidraw4: USB HID v1.10 Device [American Power Conversion Smart-UPS 750 FW:651.18.D USB FW:7.3] on usb-0000:00:02.0-10
* Note, that I have two kernel oops in a row.
This is definitely related to my boot problem. Looking through kern.log I found:
Mar 11 22:39:59 lexus kernel: [ 81.337862] /dev/vmci[7657]: Module vmci: registered with major=10 minor=62
Mar 11 22:39:59 lexus kernel: [ 81.337864] /dev/vmci[7657]: Module vmci: initialized
Mar 11 22:39:59 lexus kernel: [ 81.349631] vsock: no version for "VMCIDatagram_Send" found: kernel tainted.
Mar 11 22:40:00 lexus kernel: [ 82.139831] hub 1-0:1.0: port 10 disabled by hub (EMI?), re-enabling...
Mar 11 22:40:00 lexus kernel: [ 82.139837] usb 1-10: USB disconnect, address 4
Mar 11 22:40:00 lexus kernel: [ 82.140947] /build/
Mar 11 22:40:00 lexus kernel: [ 82.157881] Unable to handle kernel paging request at 0000000018f44118 RIP:
Mar 11 22:40:00 lexus kernel: [ 82.157886] [usbhid:
Mar 11 22:40:00 lexus kernel: [ 82.157893] PGD 10418a067 PUD 104184067 PMD 0
Mar 11 22:40:00 lexus kernel: [ 82.157896] Oops: 0000 [1] SMP
Mar 11 22:40:00 lexus kernel: [ 82.157898] CPU 1
Mar 11 22:40:00 lexus kernel: [ 82.157899] Modules linked in: vsock(F) vmci vmmon rfcomm l2cap bluetooth nfsd auth_rpcgss exportfs ppdev autofs4 ipv6 powernow_k8 cpufreq_ondemand cpufreq_
Mar 11 22:40:00 lexus kernel: t font bitblit softcursor fuse
Mar 11 22:40:00 lexus kernel: [ 82.157944] Pid: 7576, comm: apcupsd Tainted: PF 2.6.24-19-generic #1
Mar 11 22:40:00 lexus kernel: [ 82.157946] RIP: 0010:[usbhid:
Mar 11 22:40:00 lexus kernel: [ 82.157951] RSP: 0018:ffff810104
Mar 11 22:40:00 lexus kernel: [ 82.157952] RAX: 0000000018f44030 RBX: 00000000400c4807 RCX: 00007fff771d9e10
Mar 11 22:40:00 lexus kernel: [ 82.157954] RDX: ffff810118307040 RSI: 00000000fffffffb RDI: ffff81011a94b800
Mar 11 22:40:00 lexus kernel: [ 82.157956] RBP: ffff810118226000 R08: ffff8101169f76c8 R09: 0000000000000000
Mar 11 22:40:00 lexus kernel: [ 82.157957] R10: 0000000000000000 R11: ffffffff803169a0 R12: 00000000400c4807
Mar 11 22:40:00 lexus kernel: [ 82.157959] R13: 00007fff771d9e10 R14: 00000000400c4807 R15: 0000000000000000
Mar 11 22:40:00 lexus kernel: [ 82.157961] FS: 00007f3b6f1bf6e
Mar 11 22:40:00 lexus kernel: [ 82.157962] CS: 0010 DS: 0000 ES: 0000 CR0: 000000008005003b
Mar 11 22:40:00 lexus kernel: [ 82.157964] CR2: 0000000018f44118 CR3: 00000001041bb000 CR4: 00000000000006e0
Mar 11 22:40:00 lexus kernel: [ 82.157965] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
Mar 11 22:40:00 lexus kernel: [ 82.157967] DR3: 0000000000000000 DR6: 00000000ffff0ff0 DR7: 0000000000000400
Mar 11 22:40:00 lexus kernel: [ 82.157969] Process apcupsd (pid: 7576, threadinfo ffff810104046000, task ffff810104d7c7e0)
Mar 11 22:40:00 lexus kernel: [ 82.157970] Stack: ffff810104047ea8 ffff810104d7c7e0 0000000000000282 ffffffff80256ba9
Mar 11 22:40:00 lexus kernel: [ 82.157973] ffff810104047ea8 00000000ffffffff 0000000000000282 ffffffff80256c69
Mar 11 22:40:00 lexus kernel: [ 82.157976] 0000000000000282 ffff810104047ea8 0000000000000282 ffff810104047ea8
Mar 11 22:40:00 lexus kernel: [ 82.157978] Call Trace:
Mar 11 22:40:00 lexus kernel: [ 82.157985] [lock_hrtimer_
Mar 11 22:40:00 lexus kernel: [ 82.157990] [hrtimer_
Mar 11 22:40:00 lexus kernel: [ 82.157995] [hrtimer_
Mar 11 22:40:00 lexus kernel: [ 82.157999] [do_nanosleep+
Mar 11 22:40:00 lexus kernel: [ 82.158003] [hrtimer_
Mar 11 22:40:00 lexus kernel: [ 82.158010] [do_ioctl+
Mar 11 22:40:00 lexus kernel: [ 82.158014] [vfs_ioctl+
Mar 11 22:40:00 lexus kernel: [ 82.158021] [sys_ioctl+
Mar 11 22:40:00 lexus kernel: [ 82.158028] [system_
Mar 11 22:40:00 lexus kernel: [ 82.158038]
Mar 11 22:40:00 lexus kernel: [ 82.158039]
Mar 11 22:40:00 lexus kernel: [ 82.158039] Code: 48 8b 88 e8 00 00 00 0f 84 c0 00 00 00 81 fb 01 48 04 80 4c
Mar 11 22:40:00 lexus kernel: [ 82.158045] RIP [usbhid:
Mar 11 22:40:00 lexus kernel: [ 82.158049] RSP <ffff810104047e08>
Mar 11 22:40:00 lexus kernel: [ 82.158051] CR2: 0000000018f44118
Mar 11 22:40:00 lexus kernel: [ 82.158053] ---[ end trace 46e144fce5dc0683 ]---
Mar 11 22:40:00 lexus kernel: [ 82.291759] usb 1-10: new low speed USB device using ohci_hcd and address 7
Mar 11 22:40:00 lexus kernel: [ 82.483532] usb 1-10: configuration #1 chosen from 1 choice
Mar 11 22:40:00 lexus kernel: [ 82.666930] /dev/vmnet: open called by PID 7731 (vmnet-bridge)
Mar 11 22:40:00 lexus kernel: [ 82.666939] /dev/vmnet: hub 0 does not exist, allocating memory.
Mar 11 22:40:00 lexus kernel: [ 82.666947] /dev/vmnet: port on hub 0 successfully opened
Mar 11 22:40:00 lexus kernel: [ 82.666958] bridge-eth1: up
Mar 11 22:40:00 lexus kernel: [ 82.666961] bridge-eth1: attached
Mar 11 22:40:01 lexus kernel: [ 82.746891] /build/
Same here for 2 ubuntu installations running 10.04. It did sent me warnings by email when we had a power outage last week, which ended in the FATAL ERROR message as well (the same)... but requesting status dosent work...
root@host2:~# uname -a
Linux host2 2.6.24-27-server #1 SMP Wed Mar 24 11:32:39 UTC 2010 x86_64 GNU/Linux
root@host2:~# /etc/init.d/apcupsd status
FATAL ERROR in apcaccess.c at line 51
root@host2:~# /etc/init.d/apcupsd restart
Stopping UPS power management: apcupsd.
Starting UPS power management: apcupsd.
root@host2:~# /etc/init.d/apcupsd status
FATAL ERROR in apcaccess.c at line 51
root@host2:~# apcaccess status
FATAL ERROR in apcaccess.c at line 51
root@host2:~# dpkg-query -s apcupsd
Package: apcupsd
Status: install ok installed
Priority: extra
Section: admin
Installed-Size: 908
Maintainer: Ubuntu MOTU Developers <email address hidden>
Architecture: amd64
Version: 3.14.2-1build1
Provides: ups-monitor
Depends: libc6 (>= 2.7-1), libncurses5 (>= 5.6+20071006-3), libsnmp15 (>= 5.4.1~dfsg), libssl0.9.8 (>= 0.9.8f-1), libwrap0