[Nut-upsuser] nut and eaton 3s over usb, lot of logs

Tue Dec 13 11:49:17 UTC 2011

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>