Collector Of Bones
2011-Dec-13 11:14 UTC
[Nut-upsuser] nut and eaton 3s over usb, lot of logs
* OS name and version, Gentoo, updated * exact NUT version, 2.6.2 (also tried 2.6.0-r1) * NUT installation method: from source tarball, package or Subversion, portage * exact device name and related information (manufacturing date, web pointers, ?), Eaton 3S 550/700 * complete problem description, with any relevant traces, like system log excerpts, and driver debug output. You can obtain the latter using the following command, as root and after having stopped NUT: Problem is that user.log gets too much logs, whereas everything seems to work fine (data can be obtained from ups, if ups is disconnected from electrical system server gets correctly shutdown after a while and so on) but in user.log is lot of lines like these at below (two lines are generated in every second or two) it starts with "klogd: [? ?34.519386] drivers/usb/core/file.c: removing 96 minor" ant then Dec 12 13:48:22 my-server klogd: [11313.651271] uhci_hcd 0000:00:1d.1: reserve dev 2 ep81-INT, period 16, phase 8, 118 us Dec 12 13:48:23 my-server klogd: [11313.903430] uhci_hcd 0000:00:1d.1: release dev 2 ep81-INT, period 16, phase 8, 118 us Dec 12 13:48:23 my-server klogd: [11313.903684] uhci_hcd 0000:00:1d.1: reserve dev 2 ep81-INT, period 16, phase 8, 118 us Dec 12 13:48:23 my-server klogd: [11314.155431] uhci_hcd 0000:00:1d.1: release dev 2 ep81-INT, period 16, phase 8, 118 us Dec 12 13:48:25 my-server klogd: [11315.905433] uhci_hcd 0000:00:1d.1: reserve dev 2 ep81-INT, period 16, phase 8, 118 us Dec 12 13:48:25 my-server klogd: [11316.157439] uhci_hcd 0000:00:1d.1: release dev 2 ep81-INT, period 16, phase 8, 118 us Dec 12 13:48:27 my-server klogd: [11317.907039] uhci_hcd 0000:00:1d.1: reserve dev 2 ep81-INT, period 16, phase 8, 118 us Dec 12 13:48:27 my-server klogd: [11318.159447] uhci_hcd 0000:00:1d.1: release dev 2 ep81-INT, period 16, phase 8, 118 us Dec 12 13:49:29 my-server klogd: [11380.388267] uhci_hcd 0000:00:1d.1: reserve dev 2 ep81-INT, period 16, phase 8, 118 us Dec 12 13:49:29 my-server klogd: [11380.640697] uhci_hcd 0000:00:1d.1: release dev 2 ep81-INT, period 16, phase 8, 118 us Dec 12 13:49:29 my-server klogd: [11380.640800] uhci_hcd 0000:00:1d.1: reserve dev 2 ep81-INT, period 16, phase 8, 118 us Dec 12 13:49:29 my-server klogd: [11380.892697] uhci_hcd 0000:00:1d.1: release dev 2 ep81-INT, period 16, phase 8, 118 us Dec 12 13:49:31 my-server klogd: [11382.642584] uhci_hcd 0000:00:1d.1: reserve dev 2 ep81-INT, period 16, phase 8, 118 us Dec 12 13:49:31 my-server klogd: [11382.894706] uhci_hcd 0000:00:1d.1: release dev 2 ep81-INT, period 16, phase 8, 118 us ... -- in ups.conf --- [Eaton3s] ?? driver = usbhid-ups ?? port = auto ?? desc = "Serveri UPS" (had to add 'pollfreq=30' at some moment to reduce log messages..) -- get status (everything seems ok) --- $ upsc Eaton3s ups.status OL CHRG -- running usbhid-ups in debug mode (note 'timed out' messages, every time user.log get's those two reserve/release lines) --- # /lib/nut/usbhid-ups -DD -a Eaton3s see at http://pastebin.com/VyeSqC3R -- after lot googling, best idea is that protocol with UPs is broken? Can I do smth to help to fix it? ? Urmas ps. this is second try to send a letter to mailing list, first was, as told by mailserver too big, debug part moved now to pastebin. -------------- next part -------------- An HTML attachment was scrubbed... URL: <http://lists.alioth.debian.org/pipermail/nut-upsuser/attachments/20111213/1f8c3424/attachment.html>
Hi Urmas, 2011/12/13 Collector Of Bones <bonecollector at hot.ee>> * OS name and version, > Gentoo, updated > > * exact NUT version, > 2.6.2 (also tried 2.6.0-r1) > > * NUT installation method: from source tarball, package or Subversion, > portage > > * exact device name and related information (manufacturing date, web > pointers, ?), > Eaton 3S > 550/700 > > * complete problem description, with any relevant traces, like system log > excerpts, and driver debug output. You can obtain the latter using the > following command, as root and after having stopped NUT: > > Problem is that user.log gets too much logs, whereas everything seems to > work fine (data can be obtained from ups, if ups is disconnected from > electrical system server gets correctly shutdown after a while and so on) > but in user.log is lot of lines like these at below (two lines are > generated in every second or two) > > it starts with "klogd: [ 34.519386] drivers/usb/core/file.c: removing 96 > minor" ant then >this kernel message informs that the hiddev driver (minor 96) has been detached from the kernel for this device. usbhid-ups needs exclusive access, through libusb, which explains why we have to eject the kernel driver there.> Dec 12 13:48:22 my-server klogd: [11313.651271] uhci_hcd 0000:00:1d.1: > reserve dev 2 ep81-INT, period 16, phase 8, 118 us > Dec 12 13:48:23 my-server klogd: [11313.903430] uhci_hcd 0000:00:1d.1: > release dev 2 ep81-INT, period 16, phase 8, 118 us > Dec 12 13:48:23 my-server klogd: [11313.903684] uhci_hcd 0000:00:1d.1: > reserve dev 2 ep81-INT, period 16, phase 8, 118 us > Dec 12 13:48:23 my-server klogd: [11314.155431] uhci_hcd 0000:00:1d.1: > release dev 2 ep81-INT, period 16, phase 8, 118 us > Dec 12 13:48:25 my-server klogd: [11315.905433] uhci_hcd 0000:00:1d.1: > reserve dev 2 ep81-INT, period 16, phase 8, 118 us > Dec 12 13:48:25 my-server klogd: [11316.157439] uhci_hcd 0000:00:1d.1: > release dev 2 ep81-INT, period 16, phase 8, 118 us > Dec 12 13:48:27 my-server klogd: [11317.907039] uhci_hcd 0000:00:1d.1: > reserve dev 2 ep81-INT, period 16, phase 8, 118 us > Dec 12 13:48:27 my-server klogd: [11318.159447] uhci_hcd 0000:00:1d.1: > release dev 2 ep81-INT, period 16, phase 8, 118 us > Dec 12 13:49:29 my-server klogd: [11380.388267] uhci_hcd 0000:00:1d.1: > reserve dev 2 ep81-INT, period 16, phase 8, 118 us > Dec 12 13:49:29 my-server klogd: [11380.640697] uhci_hcd 0000:00:1d.1: > release dev 2 ep81-INT, period 16, phase 8, 118 us > Dec 12 13:49:29 my-server klogd: [11380.640800] uhci_hcd 0000:00:1d.1: > reserve dev 2 ep81-INT, period 16, phase 8, 118 us > Dec 12 13:49:29 my-server klogd: [11380.892697] uhci_hcd 0000:00:1d.1: > release dev 2 ep81-INT, period 16, phase 8, 118 us > Dec 12 13:49:31 my-server klogd: [11382.642584] uhci_hcd 0000:00:1d.1: > reserve dev 2 ep81-INT, period 16, phase 8, 118 us > Dec 12 13:49:31 my-server klogd: [11382.894706] uhci_hcd 0000:00:1d.1: > release dev 2 ep81-INT, period 16, phase 8, 118 us > ... >these messages from the kernel, related to USB interrupt (possibly timeout): we get data from the devices through explicit requests (polling) and interrupt. Interrupts are notifications generated by the device, upon data change, without requiring any explicit request. there is nothing we can do in NUT. The only thing for you would be to disable interrupt, by adding "pollonly" to your ups section in ups.conf: [Eaton3s] driver = usbhid-ups port = auto pollonly desc = "Serveri UPS" the should remove the above log message (if it's really related to timeout), but will decrease reactivity WRT status changes. Another option may be to look at the kernel logging level. -- in ups.conf ---> [Eaton3s] > driver = usbhid-ups > port = auto > desc = "Serveri UPS" > > (had to add 'pollfreq=30' at some moment to reduce log messages..) > > > -- get status (everything seems ok) --- > $ upsc Eaton3s ups.status > OL CHRG > > > > -- running usbhid-ups in debug mode (note 'timed out' messages, every time > user.log get's those two reserve/release lines) --- > # /lib/nut/usbhid-ups -DD -a Eaton3s > > see at http://pastebin.com/VyeSqC3R > > -- after lot googling, best idea is that protocol with UPs is broken? Can > I do smth to help to fix it? > >not sure where you found this, but it's unlikely. do you have any other messages around the above ones, related to USB suspend / resume?> ps. this is second try to send a letter to mailing list, first was, as > told by mailserver too big, debug part moved now to pastebin. >right, mails are limited to 40Kb, in order to not overcharge people with low network bandwidth. cheers, Arnaud -- Linux / Unix Expert R&D - Eaton - http://powerquality.eaton.com Network UPS Tools (NUT) Project Leader - http://www.networkupstools.org/ Debian Developer - http://www.debian.org Free Software Developer - http://arnaud.quette.free.fr/ -------------- next part -------------- An HTML attachment was scrubbed... URL: <http://lists.alioth.debian.org/pipermail/nut-upsuser/attachments/20111213/2f8e11f0/attachment.html>