Subject: Re: loses communications Package: apcupsd Version: 3.14.8-2 Severity: important
I'm experiencing the same issue with apcupsd 3.14.8-2, while version 3.14.4-1lenny1 works fine. Hardware is Back-UPS CS 500 connected via USB. Attached are debug trace logs made by running 'apcupsd -d 1000 -T' from both working (lenny) and non-working (squeeze) versions. -- System Information: Debian Release: squeeze/sid APT prefers testing APT policy: (990, 'testing'), (500, 'unstable') Architecture: amd64 (x86_64) Kernel: Linux 2.6.32-5-amd64 (SMP w/4 CPU cores) Locale: LANG=ru_RU.UTF-8, LC_CTYPE=ru_RU.UTF-8 (charmap=UTF-8) Shell: /bin/sh linked to /bin/dash Versions of packages apcupsd depends on: ii libc6 2.11.1-3 Embedded GNU C Library: Shared lib ii libgcc1 1:4.4.4-5 GCC support library ii libwrap0 7.6.q-19 Wietse Venema's TCP wrappers libra Versions of packages apcupsd recommends: pn apcupsd-doc <none> (no description available) Versions of packages apcupsd suggests: pn apcupsd-cgi <none> (no description available) pn hal <none> (no description available) ii udev 157-1 /dev/ and hotplug management daemo -- Configuration Files: /etc/apcupsd/apcupsd.conf changed [not included] /etc/default/apcupsd changed [not included] -- no debconf information
0.000 apcupsd: apcupsd.c:219 Options parsed. 0.000 apcupsd: apcconfig.c:803 After config scriptdir: "/etc/apcupsd" 0.000 apcupsd: apcconfig.c:804 After config pwrfailpath: "/etc/apcupsd" 0.001 apcupsd: apcconfig.c:805 After config nologinpath: "/etc" 0.001 apcupsd: apcupsd.c:242 Config file /etc/apcupsd/apcupsd.conf processed. 0.001 apcupsd: newups.c:102 write_lock at drivers.c:181 0.001 apcupsd: drivers.c:183 Looking for driver: usb 0.001 apcupsd: drivers.c:187 Driver dumb is configured. 0.001 apcupsd: drivers.c:187 Driver apcsmart is configured. 0.001 apcupsd: drivers.c:187 Driver net is configured. 0.001 apcupsd: drivers.c:187 Driver usb is configured. 0.001 apcupsd: drivers.c:190 Driver usb found and attached. 0.001 apcupsd: newups.c:108 write_unlock at drivers.c:207 0.001 apcupsd: drivers.c:209 Driver ptr=0x42c4e8 0.001 apcupsd: apcupsd.c:261 Attached to driver: usb 0.092 apcupsd: newups.c:102 write_lock at linux-usb.c:582 0.092 apcupsd: linux-usb.c:111 Attempting to open "/dev/usb/hiddev0" 0.093 apcupsd: linux-usb.c:126 Successfully opened "/dev/usb/hiddev0" 0.093 apcupsd: newups.c:108 write_unlock at linux-usb.c:614 0.720 apcupsd: newups.c:102 write_lock at linux-usb.c:657 0.720 apcupsd: linux-usb.c:725 Got READ ci=47, usage=0x850044, rpt=6 0.720 apcupsd: linux-usb.c:725 Got READ ci=48, usage=0x850045, rpt=6 0.720 apcupsd: linux-usb.c:725 Got READ ci=1, usage=0xff860060, rpt=6 0.720 apcupsd: linux-usb.c:725 Got READ ci=9, usage=0x850066, rpt=12 0.720 apcupsd: linux-usb.c:725 Got READ ci=13, usage=0x850068, rpt=12 0.720 apcupsd: linux-usb.c:725 Got READ ci=55, usage=0x8500d0, rpt=19 0.720 apcupsd: linux-usb.c:725 Got READ ci=45, usage=0x850042, rpt=20 0.720 apcupsd: linux-usb.c:725 Got READ ci=43, usage=0x840069, rpt=20 0.720 apcupsd: linux-usb.c:725 Got READ ci=0, usage=0x8400fe, rpt=1 0.720 apcupsd: linux-usb.c:736 Got WRITE ci=0, usage=0x8400fe, rpt=1 0.720 apcupsd: linux-usb.c:725 Got READ ci=27, usage=0x8400ff, rpt=2 0.720 apcupsd: linux-usb.c:736 Got WRITE ci=27, usage=0x8400ff, rpt=2 0.720 apcupsd: linux-usb.c:736 Got WRITE ci=47, usage=0x850044, rpt=6 0.720 apcupsd: linux-usb.c:736 Got WRITE ci=48, usage=0x850045, rpt=6 0.720 apcupsd: linux-usb.c:736 Got WRITE ci=1, usage=0xff860060, rpt=6 0.720 apcupsd: linux-usb.c:725 Got READ ci=26, usage=0x850085, rpt=7 0.720 apcupsd: linux-usb.c:736 Got WRITE ci=26, usage=0x850085, rpt=7 0.720 apcupsd: linux-usb.c:725 Got READ ci=41, usage=0x8400fd, rpt=10 0.720 apcupsd: linux-usb.c:736 Got WRITE ci=41, usage=0x8400fd, rpt=10 0.720 apcupsd: linux-usb.c:736 Got WRITE ci=9, usage=0x850066, rpt=12 0.720 apcupsd: linux-usb.c:736 Got WRITE ci=13, usage=0x850068, rpt=12 0.720 apcupsd: linux-usb.c:725 Got READ ci=51, usage=0x85008c, rpt=15 0.720 apcupsd: linux-usb.c:736 Got WRITE ci=51, usage=0x85008c, rpt=15 0.720 apcupsd: linux-usb.c:725 Got READ ci=49, usage=0x850029, rpt=17 0.720 apcupsd: linux-usb.c:736 Got WRITE ci=49, usage=0x850029, rpt=17 0.720 apcupsd: linux-usb.c:736 Got WRITE ci=55, usage=0x8500d0, rpt=19 0.720 apcupsd: linux-usb.c:736 Got WRITE ci=45, usage=0x850042, rpt=20 0.720 apcupsd: linux-usb.c:736 Got WRITE ci=43, usage=0x840069, rpt=20 0.720 apcupsd: linux-usb.c:725 Got READ ci=60, usage=0xff860016, rpt=28 0.720 apcupsd: linux-usb.c:736 Got WRITE ci=60, usage=0xff860016, rpt=28 0.720 apcupsd: linux-usb.c:725 Got READ ci=61, usage=0xff86007c, rpt=64 0.721 apcupsd: linux-usb.c:736 Got WRITE ci=61, usage=0xff86007c, rpt=64 0.721 apcupsd: newups.c:108 write_unlock at linux-usb.c:749 0.728 apcupsd: linux-usb.c:304 Def val=1 exp=0 sVal="Back-UPS 500 FW: 6.4.I USB FW: c1 " ci=0 0.728 apcupsd: usb.c:302 Checking for BackUPS Pro quirk "Back-UPS 500 FW: 6.4.I USB FW: c1 " 0.728 apcupsd: newups.c:102 write_lock at usb.c:864 1.089 apcupsd: linux-usb.c:304 Def val=3 exp=5 sVal="American Power Conversion" ci=41 1.110 apcupsd: linux-usb.c:304 Def val=1 exp=0 sVal="Back-UPS 500 FW: 6.4.I USB FW: c1 " ci=0 1.129 apcupsd: linux-usb.c:304 Def val=2 exp=0 sVal="BB0311005920" ci=27 1.147 apcupsd: linux-usb.c:361 Def val=11883 exp=0 dVal=11883.000000 ci=26 1.167 apcupsd: linux-usb.c:361 Def val=10 exp=0 dVal=10.000000 ci=49 1.208 apcupsd: linux-usb.c:361 Def val=0 exp=0 dVal=0.000000 ci=47 1.335 apcupsd: linux-usb.c:361 Def val=50 exp=0 dVal=50.000000 ci=51 1.437 apcupsd: linux-usb.c:361 Def val=0 exp=0 dVal=0.000000 ci=61 1.458 apcupsd: linux-usb.c:361 Def val=200963 exp=0 dVal=200963.000000 ci=60 1.617 apcupsd: newups.c:108 write_unlock at usb.c:872 1.618 apcupsd: apcupsd.c:310 NIS thread started. 1.619 apcupsd: apclog.c:42 NIS server startup succeeded 1.619 apcupsd: apclog.c:42 apcupsd 3.14.4 (18 May 2008) debian startup succeeded 1.619 apcupsd: usb.c:828 Enter usb_ups_read_volatile_data 1.619 apcupsd: newups.c:102 write_lock at usb.c:840 1.841 apcupsd: linux-usb.c:361 Def val=0 exp=0 dVal=0.000000 ci=43 1.841 apcupsd: usb.c:438 ShutdownImminent=0 1.902 apcupsd: linux-usb.c:361 Def val=0 exp=0 dVal=0.000000 ci=45 1.902 apcupsd: usb.c:426 BelowRemCapLimit=0 1.944 apcupsd: linux-usb.c:361 Def val=0 exp=0 dVal=0.000000 ci=48 1.944 apcupsd: usb.c:420 Discharging=0 1.985 apcupsd: linux-usb.c:361 Def val=100 exp=0 dVal=100.000000 ci=9 1.985 apcupsd: usb.c:480 BattCharge = 100 2.005 apcupsd: linux-usb.c:349 Def val=2580 exp=0 dVal=2580.000000 ci=13 2.005 apcupsd: usb.c:504 TimeLeft = 43 2.066 apcupsd: linux-usb.c:361 Def val=8 exp=0 dVal=8.000000 ci=1 2.066 apcupsd: usb.c:409 Status=0x07000008 2.085 apcupsd: newups.c:108 write_unlock at usb.c:852 2.085 apcupsd: device.c:204 Before do_action: 0x7000008 (OB:0). 2.085 apcupsd: newups.c:102 write_lock at action.c:351 2.085 apcupsd: newups.c:108 write_unlock at action.c:644 2.085 apcupsd: device.c:210 Before fillUPS: 0x7000008 (OB:0). 2.085 apcupsd: usb.c:828 Enter usb_ups_read_volatile_data 2.085 apcupsd: newups.c:102 write_lock at usb.c:840 2.308 apcupsd: linux-usb.c:361 Def val=0 exp=0 dVal=0.000000 ci=43 2.308 apcupsd: usb.c:438 ShutdownImminent=0 2.369 apcupsd: linux-usb.c:361 Def val=0 exp=0 dVal=0.000000 ci=45 2.369 apcupsd: usb.c:426 BelowRemCapLimit=0 2.410 apcupsd: linux-usb.c:361 Def val=0 exp=0 dVal=0.000000 ci=48 2.410 apcupsd: usb.c:420 Discharging=0 2.455 apcupsd: linux-usb.c:361 Def val=100 exp=0 dVal=100.000000 ci=9 2.455 apcupsd: usb.c:480 BattCharge = 100 2.472 apcupsd: linux-usb.c:349 Def val=2580 exp=0 dVal=2580.000000 ci=13 2.472 apcupsd: usb.c:504 TimeLeft = 43 2.533 apcupsd: linux-usb.c:361 Def val=8 exp=0 dVal=8.000000 ci=1 2.533 apcupsd: usb.c:409 Status=0x07000008 2.552 apcupsd: newups.c:108 write_unlock at usb.c:852 2.552 apcupsd: device.c:216 Before do_action: 0x7000008 (OB:0). 2.552 apcupsd: newups.c:102 write_lock at action.c:351 2.552 apcupsd: newups.c:108 write_unlock at action.c:644 2.552 apcupsd: device.c:222 Before do_reports: 0x7000008 (OB:0). 2.552 apcupsd: device.c:230 Before device_check_state: 0x7000008 (OB:0). 62.612 apcupsd: device.c:204 Before do_action: 0x7000008 (OB:0). 62.612 apcupsd: newups.c:102 write_lock at action.c:351 62.612 apcupsd: newups.c:108 write_unlock at action.c:644 62.612 apcupsd: device.c:210 Before fillUPS: 0x7000008 (OB:0). 62.612 apcupsd: usb.c:828 Enter usb_ups_read_volatile_data 62.612 apcupsd: newups.c:102 write_lock at usb.c:840 62.837 apcupsd: linux-usb.c:361 Def val=0 exp=0 dVal=0.000000 ci=43 62.837 apcupsd: usb.c:438 ShutdownImminent=0 62.879 apcupsd: linux-usb.c:361 Def val=0 exp=0 dVal=0.000000 ci=45 62.879 apcupsd: usb.c:426 BelowRemCapLimit=0 62.921 apcupsd: linux-usb.c:361 Def val=0 exp=0 dVal=0.000000 ci=48 62.921 apcupsd: usb.c:420 Discharging=0 62.962 apcupsd: linux-usb.c:361 Def val=100 exp=0 dVal=100.000000 ci=9 62.962 apcupsd: usb.c:480 BattCharge = 100 62.983 apcupsd: linux-usb.c:349 Def val=2580 exp=0 dVal=2580.000000 ci=13 62.983 apcupsd: usb.c:504 TimeLeft = 43 63.043 apcupsd: linux-usb.c:361 Def val=8 exp=0 dVal=8.000000 ci=1 63.043 apcupsd: usb.c:409 Status=0x07000008 63.062 apcupsd: newups.c:108 write_unlock at usb.c:852 63.062 apcupsd: device.c:216 Before do_action: 0x7000008 (OB:0). 63.062 apcupsd: newups.c:102 write_lock at action.c:351 63.062 apcupsd: newups.c:108 write_unlock at action.c:644 63.062 apcupsd: device.c:222 Before do_reports: 0x7000008 (OB:0). 63.062 apcupsd: device.c:230 Before device_check_state: 0x7000008 (OB:0). 123.122 apcupsd: device.c:204 Before do_action: 0x7000008 (OB:0). 123.123 apcupsd: newups.c:102 write_lock at action.c:351 123.123 apcupsd: newups.c:108 write_unlock at action.c:644 123.123 apcupsd: device.c:210 Before fillUPS: 0x7000008 (OB:0). 123.123 apcupsd: usb.c:828 Enter usb_ups_read_volatile_data 123.123 apcupsd: newups.c:102 write_lock at usb.c:840 123.325 apcupsd: linux-usb.c:361 Def val=0 exp=0 dVal=0.000000 ci=43 123.326 apcupsd: usb.c:438 ShutdownImminent=0 123.385 apcupsd: linux-usb.c:361 Def val=0 exp=0 dVal=0.000000 ci=45 123.386 apcupsd: usb.c:426 BelowRemCapLimit=0 123.426 apcupsd: linux-usb.c:361 Def val=0 exp=0 dVal=0.000000 ci=48 123.427 apcupsd: usb.c:420 Discharging=0 123.467 apcupsd: linux-usb.c:361 Def val=100 exp=0 dVal=100.000000 ci=9 123.468 apcupsd: usb.c:480 BattCharge = 100 123.488 apcupsd: linux-usb.c:349 Def val=2580 exp=0 dVal=2580.000000 ci=13 123.489 apcupsd: usb.c:504 TimeLeft = 43 123.549 apcupsd: linux-usb.c:361 Def val=8 exp=0 dVal=8.000000 ci=1 123.550 apcupsd: usb.c:409 Status=0x07000008 123.569 apcupsd: newups.c:108 write_unlock at usb.c:852 123.569 apcupsd: device.c:216 Before do_action: 0x7000008 (OB:0). 123.569 apcupsd: newups.c:102 write_lock at action.c:351 123.569 apcupsd: newups.c:108 write_unlock at action.c:644 123.569 apcupsd: device.c:222 Before do_reports: 0x7000008 (OB:0). 123.569 apcupsd: device.c:230 Before device_check_state: 0x7000008 (OB:0).
0.000 apcupsd: apcupsd.c:219 Options parsed. 0.000 apcupsd: apcconfig.c:799 After config scriptdir: "/etc/apcupsd" 0.000 apcupsd: apcconfig.c:800 After config pwrfailpath: "/etc/apcupsd" 0.000 apcupsd: apcconfig.c:801 After config nologinpath: "/etc" 0.000 apcupsd: apcupsd.c:242 Config file /etc/apcupsd/apcupsd.conf processed. 0.000 apcupsd: newups.c:102 write_lock at drivers.c:208 0.000 apcupsd: drivers.c:210 Looking for driver: usb 0.000 apcupsd: drivers.c:214 Driver dumb is configured. 0.000 apcupsd: drivers.c:214 Driver apcsmart is configured. 0.000 apcupsd: drivers.c:214 Driver net is configured. 0.000 apcupsd: drivers.c:214 Driver usb is configured. 0.000 apcupsd: drivers.c:217 Driver usb found and attached. 0.000 apcupsd: newups.c:108 write_unlock at drivers.c:234 0.001 apcupsd: drivers.c:236 Driver ptr=0x4234e0 0.001 apcupsd: apcupsd.c:261 Attached to driver: usb 0.002 apcupsd: newups.c:102 write_lock at linux-usb.c:582 0.002 apcupsd: linux-usb.c:111 Attempting to open "/dev/usb/hiddev0" 0.002 apcupsd: linux-usb.c:126 Successfully opened "/dev/usb/hiddev0" 0.002 apcupsd: newups.c:108 write_unlock at linux-usb.c:614 0.358 apcupsd: newups.c:102 write_lock at linux-usb.c:657 0.358 apcupsd: linux-usb.c:725 Got READ ci=48, usage=0x850044, rpt=6 0.358 apcupsd: linux-usb.c:725 Got READ ci=49, usage=0x850045, rpt=6 0.358 apcupsd: linux-usb.c:725 Got READ ci=1, usage=0xff860060, rpt=6 0.358 apcupsd: linux-usb.c:725 Got READ ci=9, usage=0x850066, rpt=12 0.358 apcupsd: linux-usb.c:725 Got READ ci=13, usage=0x850068, rpt=12 0.358 apcupsd: linux-usb.c:725 Got READ ci=56, usage=0x8500d0, rpt=19 0.358 apcupsd: linux-usb.c:725 Got READ ci=46, usage=0x850042, rpt=20 0.358 apcupsd: linux-usb.c:725 Got READ ci=44, usage=0x840069, rpt=20 0.358 apcupsd: linux-usb.c:725 Got READ ci=0, usage=0x8400fe, rpt=1 0.358 apcupsd: linux-usb.c:736 Got WRITE ci=0, usage=0x8400fe, rpt=1 0.358 apcupsd: linux-usb.c:725 Got READ ci=27, usage=0x8400ff, rpt=2 0.358 apcupsd: linux-usb.c:736 Got WRITE ci=27, usage=0x8400ff, rpt=2 0.358 apcupsd: linux-usb.c:736 Got WRITE ci=48, usage=0x850044, rpt=6 0.358 apcupsd: linux-usb.c:736 Got WRITE ci=49, usage=0x850045, rpt=6 0.358 apcupsd: linux-usb.c:736 Got WRITE ci=1, usage=0xff860060, rpt=6 0.358 apcupsd: linux-usb.c:725 Got READ ci=26, usage=0x850085, rpt=7 0.358 apcupsd: linux-usb.c:736 Got WRITE ci=26, usage=0x850085, rpt=7 0.358 apcupsd: linux-usb.c:725 Got READ ci=29, usage=0x840040, rpt=8 0.358 apcupsd: linux-usb.c:736 Got WRITE ci=29, usage=0x840040, rpt=8 0.358 apcupsd: linux-usb.c:725 Got READ ci=10, usage=0x840030, rpt=9 0.358 apcupsd: linux-usb.c:736 Got WRITE ci=10, usage=0x840030, rpt=9 0.358 apcupsd: linux-usb.c:725 Got READ ci=42, usage=0x8400fd, rpt=10 0.358 apcupsd: linux-usb.c:736 Got WRITE ci=42, usage=0x8400fd, rpt=10 0.358 apcupsd: linux-usb.c:736 Got WRITE ci=9, usage=0x850066, rpt=12 0.358 apcupsd: linux-usb.c:736 Got WRITE ci=13, usage=0x850068, rpt=12 0.358 apcupsd: linux-usb.c:725 Got READ ci=52, usage=0x85008c, rpt=15 0.358 apcupsd: linux-usb.c:736 Got WRITE ci=52, usage=0x85008c, rpt=15 0.359 apcupsd: linux-usb.c:725 Got READ ci=50, usage=0x850029, rpt=17 0.359 apcupsd: linux-usb.c:736 Got WRITE ci=50, usage=0x850029, rpt=17 0.359 apcupsd: linux-usb.c:736 Got WRITE ci=56, usage=0x8500d0, rpt=19 0.359 apcupsd: linux-usb.c:736 Got WRITE ci=46, usage=0x850042, rpt=20 0.359 apcupsd: linux-usb.c:736 Got WRITE ci=44, usage=0x840069, rpt=20 0.359 apcupsd: linux-usb.c:725 Got READ ci=41, usage=0xff860072, rpt=53 0.359 apcupsd: linux-usb.c:736 Got WRITE ci=41, usage=0xff860072, rpt=53 0.359 apcupsd: linux-usb.c:725 Got READ ci=62, usage=0xff86007c, rpt=64 0.359 apcupsd: linux-usb.c:736 Got WRITE ci=62, usage=0xff86007c, rpt=64 0.359 apcupsd: newups.c:108 write_unlock at linux-usb.c:749 0.366 apcupsd: linux-usb.c:304 Def val=1 exp=0 sVal="Back-UPS 500 FW: 6.4.I USB FW: c1 " ci=0 0.366 apcupsd: usb.c:306 Checking for BackUPS Pro quirk "Back-UPS 500 FW: 6.4.I USB FW: c1 " 0.366 apcupsd: newups.c:102 write_lock at usb.c:886 0.382 apcupsd: linux-usb.c:349 Def val=1200 exp=-2 dVal=12.000000 ci=29 0.403 apcupsd: linux-usb.c:349 Def val=1200 exp=-2 dVal=12.000000 ci=29 0.750 apcupsd: linux-usb.c:304 Def val=3 exp=5 sVal="American Power Conversion" ci=42 0.771 apcupsd: linux-usb.c:304 Def val=1 exp=0 sVal="Back-UPS 500 FW: 6.4.I USB FW: c1 " ci=0 0.791 apcupsd: linux-usb.c:304 Def val=2 exp=0 sVal="BB0311005920" ci=27 0.808 apcupsd: linux-usb.c:361 Def val=11883 exp=0 dVal=11883.000000 ci=26 0.829 apcupsd: linux-usb.c:361 Def val=10 exp=0 dVal=10.000000 ci=50 0.870 apcupsd: linux-usb.c:361 Def val=0 exp=0 dVal=0.000000 ci=48 0.971 apcupsd: linux-usb.c:361 Def val=50 exp=0 dVal=50.000000 ci=52 1.111 apcupsd: linux-usb.c:361 Def val=0 exp=0 dVal=0.000000 ci=62 1.132 apcupsd: linux-usb.c:361 Def val=0 exp=0 dVal=0.000000 ci=41 1.292 apcupsd: newups.c:108 write_unlock at usb.c:894 1.293 apcupsd: apcupsd.c:315 NIS thread started. 1.293 apcupsd: apclog.c:62 apcupsd 3.14.8 (16 January 2010) debian startup succeeded 1.293 apcupsd: apclog.c:62 NIS server startup succeeded 1.294 apcupsd: usb.c:850 Enter usb_ups_read_volatile_data 1.294 apcupsd: newups.c:102 write_lock at usb.c:862 1.684 apcupsd: linux-usb.c:349 Def val=1350 exp=-2 dVal=13.500000 ci=10 1.684 apcupsd: usb.c:490 BattVoltage = 13 2.013 apcupsd: linux-usb.c:349 Def val=1350 exp=-2 dVal=13.500000 ci=10 2.013 apcupsd: usb.c:490 BattVoltage = 13 2.276 apcupsd: linux-usb.c:361 Def val=0 exp=0 dVal=0.000000 ci=44 2.276 apcupsd: usb.c:442 ShutdownImminent=0 2.318 apcupsd: linux-usb.c:361 Def val=0 exp=0 dVal=0.000000 ci=46 2.318 apcupsd: usb.c:430 BelowRemCapLimit=0 2.359 apcupsd: linux-usb.c:361 Def val=0 exp=0 dVal=0.000000 ci=49 2.359 apcupsd: usb.c:424 Discharging=0 2.400 apcupsd: linux-usb.c:361 Def val=100 exp=0 dVal=100.000000 ci=9 2.400 apcupsd: usb.c:484 BattCharge = 100 2.421 apcupsd: linux-usb.c:349 Def val=2580 exp=0 dVal=2580.000000 ci=13 2.421 apcupsd: usb.c:508 TimeLeft = 43 2.482 apcupsd: linux-usb.c:361 Def val=8 exp=0 dVal=8.000000 ci=1 2.482 apcupsd: usb.c:413 Status=0x07000008 2.501 apcupsd: newups.c:108 write_unlock at usb.c:874 2.501 apcupsd: device.c:212 Before do_action: 0x7000008 (OB:0). 2.501 apcupsd: newups.c:102 write_lock at action.c:351 2.501 apcupsd: newups.c:108 write_unlock at action.c:649 2.501 apcupsd: device.c:218 Before fillUPS: 0x7000008 (OB:0). 2.501 apcupsd: usb.c:850 Enter usb_ups_read_volatile_data 2.501 apcupsd: newups.c:102 write_lock at usb.c:862 2.905 apcupsd: linux-usb.c:349 Def val=1350 exp=-2 dVal=13.500000 ci=10 2.905 apcupsd: usb.c:490 BattVoltage = 13 3.495 apcupsd: linux-usb.c:349 Def val=1350 exp=-2 dVal=13.500000 ci=10 3.495 apcupsd: usb.c:490 BattVoltage = 13 3.758 apcupsd: linux-usb.c:361 Def val=0 exp=0 dVal=0.000000 ci=44 3.758 apcupsd: usb.c:442 ShutdownImminent=0 3.979 apcupsd: newups.c:108 write_unlock at usb.c:874 3.979 apcupsd: device.c:224 Before do_action: 0x7000000 (OB:0). 3.979 apcupsd: newups.c:102 write_lock at action.c:351 3.979 apcupsd: newups.c:108 write_unlock at action.c:649 3.979 apcupsd: device.c:230 Before do_reports: 0x7000000 (OB:0). 3.979 apcupsd: device.c:238 Before device_check_state: 0x7000000 (OB:0). 3.979 apcupsd: linux-usb.c:490 read error: ERR=Input/output error 3.979 apcupsd: linux-usb.c:241 link_check comm lost 8.979 apcupsd: linux-usb.c:88 Reinitializing private structure. 8.979 apcupsd: linux-usb.c:111 Attempting to open "/dev/usb/hiddev0" 8.979 apcupsd: linux-usb.c:126 Successfully opened "/dev/usb/hiddev0" 9.340 apcupsd: newups.c:102 write_lock at linux-usb.c:657 9.340 apcupsd: linux-usb.c:725 Got READ ci=48, usage=0x850044, rpt=6 9.340 apcupsd: linux-usb.c:725 Got READ ci=49, usage=0x850045, rpt=6 9.340 apcupsd: linux-usb.c:725 Got READ ci=1, usage=0xff860060, rpt=6 9.340 apcupsd: linux-usb.c:725 Got READ ci=9, usage=0x850066, rpt=12 9.340 apcupsd: linux-usb.c:725 Got READ ci=13, usage=0x850068, rpt=12 9.340 apcupsd: linux-usb.c:725 Got READ ci=56, usage=0x8500d0, rpt=19 9.340 apcupsd: linux-usb.c:725 Got READ ci=46, usage=0x850042, rpt=20 9.340 apcupsd: linux-usb.c:725 Got READ ci=44, usage=0x840069, rpt=20 9.340 apcupsd: linux-usb.c:725 Got READ ci=0, usage=0x8400fe, rpt=1 9.340 apcupsd: linux-usb.c:736 Got WRITE ci=0, usage=0x8400fe, rpt=1 9.340 apcupsd: linux-usb.c:725 Got READ ci=27, usage=0x8400ff, rpt=2 9.340 apcupsd: linux-usb.c:736 Got WRITE ci=27, usage=0x8400ff, rpt=2 9.340 apcupsd: linux-usb.c:736 Got WRITE ci=48, usage=0x850044, rpt=6 9.340 apcupsd: linux-usb.c:736 Got WRITE ci=49, usage=0x850045, rpt=6 9.340 apcupsd: linux-usb.c:736 Got WRITE ci=1, usage=0xff860060, rpt=6 9.340 apcupsd: linux-usb.c:725 Got READ ci=26, usage=0x850085, rpt=7 9.340 apcupsd: linux-usb.c:736 Got WRITE ci=26, usage=0x850085, rpt=7 9.340 apcupsd: linux-usb.c:725 Got READ ci=29, usage=0x840040, rpt=8 9.340 apcupsd: linux-usb.c:736 Got WRITE ci=29, usage=0x840040, rpt=8 9.340 apcupsd: linux-usb.c:725 Got READ ci=10, usage=0x840030, rpt=9 9.340 apcupsd: linux-usb.c:736 Got WRITE ci=10, usage=0x840030, rpt=9 9.340 apcupsd: linux-usb.c:725 Got READ ci=42, usage=0x8400fd, rpt=10 9.340 apcupsd: linux-usb.c:736 Got WRITE ci=42, usage=0x8400fd, rpt=10 9.341 apcupsd: linux-usb.c:736 Got WRITE ci=9, usage=0x850066, rpt=12 9.341 apcupsd: linux-usb.c:736 Got WRITE ci=13, usage=0x850068, rpt=12 9.341 apcupsd: linux-usb.c:725 Got READ ci=52, usage=0x85008c, rpt=15 9.341 apcupsd: linux-usb.c:736 Got WRITE ci=52, usage=0x85008c, rpt=15 9.341 apcupsd: linux-usb.c:725 Got READ ci=50, usage=0x850029, rpt=17 9.341 apcupsd: linux-usb.c:736 Got WRITE ci=50, usage=0x850029, rpt=17 9.341 apcupsd: linux-usb.c:736 Got WRITE ci=56, usage=0x8500d0, rpt=19 9.341 apcupsd: linux-usb.c:736 Got WRITE ci=46, usage=0x850042, rpt=20 9.341 apcupsd: linux-usb.c:736 Got WRITE ci=44, usage=0x840069, rpt=20 9.341 apcupsd: linux-usb.c:725 Got READ ci=41, usage=0xff860072, rpt=53 9.341 apcupsd: linux-usb.c:736 Got WRITE ci=41, usage=0xff860072, rpt=53 9.341 apcupsd: linux-usb.c:725 Got READ ci=62, usage=0xff86007c, rpt=64 9.341 apcupsd: linux-usb.c:736 Got WRITE ci=62, usage=0xff86007c, rpt=64 9.341 apcupsd: newups.c:108 write_unlock at linux-usb.c:749 9.348 apcupsd: linux-usb.c:304 Def val=1 exp=0 sVal="Back-UPS 500 FW: 6.4.I USB FW: c1 " ci=0 9.348 apcupsd: usb.c:306 Checking for BackUPS Pro quirk "Back-UPS 500 FW: 6.4.I USB FW: c1 " 9.348 apcupsd: newups.c:102 write_lock at usb.c:886 9.364 apcupsd: linux-usb.c:349 Def val=1200 exp=-2 dVal=12.000000 ci=29 9.385 apcupsd: linux-usb.c:349 Def val=1200 exp=-2 dVal=12.000000 ci=29 9.731 apcupsd: linux-usb.c:304 Def val=3 exp=5 sVal="American Power Conversion" ci=42 9.753 apcupsd: linux-usb.c:304 Def val=1 exp=0 sVal="Back-UPS 500 FW: 6.4.I USB FW: c1 " ci=0 9.773 apcupsd: linux-usb.c:304 Def val=2 exp=0 sVal="BB0311005920" ci=27 9.790 apcupsd: linux-usb.c:361 Def val=11883 exp=0 dVal=11883.000000 ci=26 9.811 apcupsd: linux-usb.c:361 Def val=10 exp=0 dVal=10.000000 ci=50 9.852 apcupsd: linux-usb.c:361 Def val=0 exp=0 dVal=0.000000 ci=48 9.953 apcupsd: linux-usb.c:361 Def val=50 exp=0 dVal=50.000000 ci=52 10.093 apcupsd: linux-usb.c:361 Def val=0 exp=0 dVal=0.000000 ci=62 10.114 apcupsd: linux-usb.c:361 Def val=0 exp=0 dVal=0.000000 ci=41 10.274 apcupsd: newups.c:108 write_unlock at usb.c:894 10.275 apcupsd: apclog.c:62 Communications with UPS restored. 10.275 apcupsd: action.c:89 calling execute_ups_event commok event=0 10.275 apcupsd: linux-usb.c:274 link check comm OK. 10.275 apcupsd: device.c:212 Before do_action: 0x7000000 (OB:0). 10.275 apcupsd: newups.c:102 write_lock at action.c:351 10.275 apcupsd: newups.c:108 write_unlock at action.c:649 10.275 apcupsd: device.c:218 Before fillUPS: 0x7000000 (OB:0). 10.275 apcupsd: usb.c:850 Enter usb_ups_read_volatile_data 10.275 apcupsd: newups.c:102 write_lock at usb.c:862 10.666 apcupsd: linux-usb.c:349 Def val=1350 exp=-2 dVal=13.500000 ci=10 10.666 apcupsd: usb.c:490 BattVoltage = 13 10.996 apcupsd: linux-usb.c:349 Def val=1350 exp=-2 dVal=13.500000 ci=10 10.996 apcupsd: usb.c:490 BattVoltage = 13 11.258 apcupsd: linux-usb.c:361 Def val=0 exp=0 dVal=0.000000 ci=44 11.258 apcupsd: usb.c:442 ShutdownImminent=0 11.300 apcupsd: linux-usb.c:361 Def val=0 exp=0 dVal=0.000000 ci=46 11.300 apcupsd: usb.c:430 BelowRemCapLimit=0 11.341 apcupsd: linux-usb.c:361 Def val=0 exp=0 dVal=0.000000 ci=49 11.341 apcupsd: usb.c:424 Discharging=0 11.382 apcupsd: linux-usb.c:361 Def val=100 exp=0 dVal=100.000000 ci=9 11.382 apcupsd: usb.c:484 BattCharge = 100 11.403 apcupsd: linux-usb.c:349 Def val=2580 exp=0 dVal=2580.000000 ci=13 11.403 apcupsd: usb.c:508 TimeLeft = 43 11.464 apcupsd: linux-usb.c:361 Def val=8 exp=0 dVal=8.000000 ci=1 11.464 apcupsd: usb.c:413 Status=0x07000008 11.483 apcupsd: newups.c:108 write_unlock at usb.c:874 11.483 apcupsd: device.c:224 Before do_action: 0x7000008 (OB:0). 11.483 apcupsd: newups.c:102 write_lock at action.c:351 11.483 apcupsd: newups.c:108 write_unlock at action.c:649 11.483 apcupsd: device.c:230 Before do_reports: 0x7000008 (OB:0). 11.483 apcupsd: newups.c:90 read_lock at apcstatus.c:50 11.483 apcupsd: device.c:238 Before device_check_state: 0x7000008 (OB:0). 11.483 apcupsd: apcstatus.c:202 NO CI_VLINE 11.483 apcupsd: newups.c:96 read_unlock at apcstatus.c:449 71.541 apcupsd: device.c:212 Before do_action: 0x7000008 (OB:0). 71.541 apcupsd: newups.c:102 write_lock at action.c:351 71.541 apcupsd: newups.c:108 write_unlock at action.c:649 71.541 apcupsd: device.c:218 Before fillUPS: 0x7000008 (OB:0). 71.541 apcupsd: usb.c:850 Enter usb_ups_read_volatile_data 71.541 apcupsd: newups.c:102 write_lock at usb.c:862 71.916 apcupsd: linux-usb.c:349 Def val=1350 exp=-2 dVal=13.500000 ci=10 71.916 apcupsd: usb.c:490 BattVoltage = 13 72.506 apcupsd: linux-usb.c:349 Def val=1350 exp=-2 dVal=13.500000 ci=10 72.506 apcupsd: usb.c:490 BattVoltage = 13 72.769 apcupsd: linux-usb.c:361 Def val=0 exp=0 dVal=0.000000 ci=44 72.769 apcupsd: usb.c:442 ShutdownImminent=0 72.990 apcupsd: newups.c:108 write_unlock at usb.c:874 72.991 apcupsd: device.c:224 Before do_action: 0x7000000 (OB:0). 72.991 apcupsd: newups.c:102 write_lock at action.c:351 72.991 apcupsd: newups.c:108 write_unlock at action.c:649 72.991 apcupsd: device.c:230 Before do_reports: 0x7000000 (OB:0). 72.991 apcupsd: device.c:238 Before device_check_state: 0x7000000 (OB:0). 72.991 apcupsd: linux-usb.c:490 read error: ERR=Input/output error 72.991 apcupsd: linux-usb.c:241 link_check comm lost 77.991 apcupsd: linux-usb.c:88 Reinitializing private structure. 77.991 apcupsd: linux-usb.c:111 Attempting to open "/dev/usb/hiddev0" 77.991 apcupsd: linux-usb.c:126 Successfully opened "/dev/usb/hiddev0" 78.350 apcupsd: newups.c:102 write_lock at linux-usb.c:657 78.350 apcupsd: linux-usb.c:725 Got READ ci=48, usage=0x850044, rpt=6 78.350 apcupsd: linux-usb.c:725 Got READ ci=49, usage=0x850045, rpt=6 78.350 apcupsd: linux-usb.c:725 Got READ ci=1, usage=0xff860060, rpt=6 78.350 apcupsd: linux-usb.c:725 Got READ ci=9, usage=0x850066, rpt=12 78.350 apcupsd: linux-usb.c:725 Got READ ci=13, usage=0x850068, rpt=12 78.350 apcupsd: linux-usb.c:725 Got READ ci=56, usage=0x8500d0, rpt=19 78.350 apcupsd: linux-usb.c:725 Got READ ci=46, usage=0x850042, rpt=20 78.350 apcupsd: linux-usb.c:725 Got READ ci=44, usage=0x840069, rpt=20 78.350 apcupsd: linux-usb.c:725 Got READ ci=0, usage=0x8400fe, rpt=1 78.351 apcupsd: linux-usb.c:736 Got WRITE ci=0, usage=0x8400fe, rpt=1 78.351 apcupsd: linux-usb.c:725 Got READ ci=27, usage=0x8400ff, rpt=2 78.351 apcupsd: linux-usb.c:736 Got WRITE ci=27, usage=0x8400ff, rpt=2 78.351 apcupsd: linux-usb.c:736 Got WRITE ci=48, usage=0x850044, rpt=6 78.351 apcupsd: linux-usb.c:736 Got WRITE ci=49, usage=0x850045, rpt=6 78.351 apcupsd: linux-usb.c:736 Got WRITE ci=1, usage=0xff860060, rpt=6 78.351 apcupsd: linux-usb.c:725 Got READ ci=26, usage=0x850085, rpt=7 78.351 apcupsd: linux-usb.c:736 Got WRITE ci=26, usage=0x850085, rpt=7 78.351 apcupsd: linux-usb.c:725 Got READ ci=29, usage=0x840040, rpt=8 78.351 apcupsd: linux-usb.c:736 Got WRITE ci=29, usage=0x840040, rpt=8 78.351 apcupsd: linux-usb.c:725 Got READ ci=10, usage=0x840030, rpt=9 78.351 apcupsd: linux-usb.c:736 Got WRITE ci=10, usage=0x840030, rpt=9 78.351 apcupsd: linux-usb.c:725 Got READ ci=42, usage=0x8400fd, rpt=10 78.351 apcupsd: linux-usb.c:736 Got WRITE ci=42, usage=0x8400fd, rpt=10 78.351 apcupsd: linux-usb.c:736 Got WRITE ci=9, usage=0x850066, rpt=12 78.351 apcupsd: linux-usb.c:736 Got WRITE ci=13, usage=0x850068, rpt=12 78.351 apcupsd: linux-usb.c:725 Got READ ci=52, usage=0x85008c, rpt=15 78.351 apcupsd: linux-usb.c:736 Got WRITE ci=52, usage=0x85008c, rpt=15 78.351 apcupsd: linux-usb.c:725 Got READ ci=50, usage=0x850029, rpt=17 78.351 apcupsd: linux-usb.c:736 Got WRITE ci=50, usage=0x850029, rpt=17 78.351 apcupsd: linux-usb.c:736 Got WRITE ci=56, usage=0x8500d0, rpt=19 78.351 apcupsd: linux-usb.c:736 Got WRITE ci=46, usage=0x850042, rpt=20 78.351 apcupsd: linux-usb.c:736 Got WRITE ci=44, usage=0x840069, rpt=20 78.351 apcupsd: linux-usb.c:725 Got READ ci=41, usage=0xff860072, rpt=53 78.351 apcupsd: linux-usb.c:736 Got WRITE ci=41, usage=0xff860072, rpt=53 78.351 apcupsd: linux-usb.c:725 Got READ ci=62, usage=0xff86007c, rpt=64 78.351 apcupsd: linux-usb.c:736 Got WRITE ci=62, usage=0xff86007c, rpt=64 78.351 apcupsd: newups.c:108 write_unlock at linux-usb.c:749 78.358 apcupsd: linux-usb.c:304 Def val=1 exp=0 sVal="Back-UPS 500 FW: 6.4.I USB FW: c1 " ci=0 78.358 apcupsd: usb.c:306 Checking for BackUPS Pro quirk "Back-UPS 500 FW: 6.4.I USB FW: c1 " 78.358 apcupsd: newups.c:102 write_lock at usb.c:886 78.374 apcupsd: linux-usb.c:349 Def val=1200 exp=-2 dVal=12.000000 ci=29 78.395 apcupsd: linux-usb.c:349 Def val=1200 exp=-2 dVal=12.000000 ci=29 78.741 apcupsd: linux-usb.c:304 Def val=3 exp=5 sVal="American Power Conversion" ci=42 78.763 apcupsd: linux-usb.c:304 Def val=1 exp=0 sVal="Back-UPS 500 FW: 6.4.I USB FW: c1 " ci=0 78.783 apcupsd: linux-usb.c:304 Def val=2 exp=0 sVal="BB0311005920" ci=27 78.800 apcupsd: linux-usb.c:361 Def val=11883 exp=0 dVal=11883.000000 ci=26 78.821 apcupsd: linux-usb.c:361 Def val=10 exp=0 dVal=10.000000 ci=50 78.862 apcupsd: linux-usb.c:361 Def val=0 exp=0 dVal=0.000000 ci=48 78.963 apcupsd: linux-usb.c:361 Def val=50 exp=0 dVal=50.000000 ci=52 79.103 apcupsd: linux-usb.c:361 Def val=0 exp=0 dVal=0.000000 ci=62 79.124 apcupsd: linux-usb.c:361 Def val=0 exp=0 dVal=0.000000 ci=41 79.285 apcupsd: newups.c:108 write_unlock at usb.c:894 79.285 apcupsd: apclog.c:62 Communications with UPS restored. 79.285 apcupsd: action.c:89 calling execute_ups_event commok event=0 79.305 apcupsd: linux-usb.c:274 link check comm OK. 79.306 apcupsd: device.c:212 Before do_action: 0x7000000 (OB:0). 79.306 apcupsd: newups.c:102 write_lock at action.c:351 79.306 apcupsd: newups.c:108 write_unlock at action.c:649 79.306 apcupsd: device.c:218 Before fillUPS: 0x7000000 (OB:0). 79.306 apcupsd: usb.c:850 Enter usb_ups_read_volatile_data 79.306 apcupsd: newups.c:102 write_lock at usb.c:862 79.676 apcupsd: linux-usb.c:349 Def val=1350 exp=-2 dVal=13.500000 ci=10 79.676 apcupsd: usb.c:490 BattVoltage = 13 80.005 apcupsd: linux-usb.c:349 Def val=1350 exp=-2 dVal=13.500000 ci=10 80.005 apcupsd: usb.c:490 BattVoltage = 13 80.268 apcupsd: linux-usb.c:361 Def val=0 exp=0 dVal=0.000000 ci=44 80.268 apcupsd: usb.c:442 ShutdownImminent=0 80.310 apcupsd: linux-usb.c:361 Def val=0 exp=0 dVal=0.000000 ci=46 80.310 apcupsd: usb.c:430 BelowRemCapLimit=0 80.351 apcupsd: linux-usb.c:361 Def val=0 exp=0 dVal=0.000000 ci=49 80.351 apcupsd: usb.c:424 Discharging=0 80.392 apcupsd: linux-usb.c:361 Def val=100 exp=0 dVal=100.000000 ci=9 80.392 apcupsd: usb.c:484 BattCharge = 100 80.413 apcupsd: linux-usb.c:349 Def val=2580 exp=0 dVal=2580.000000 ci=13 80.413 apcupsd: usb.c:508 TimeLeft = 43 80.474 apcupsd: linux-usb.c:361 Def val=8 exp=0 dVal=8.000000 ci=1 80.474 apcupsd: usb.c:413 Status=0x07000008 80.493 apcupsd: newups.c:108 write_unlock at usb.c:874 80.493 apcupsd: device.c:224 Before do_action: 0x7000008 (OB:0). 80.493 apcupsd: newups.c:102 write_lock at action.c:351 80.493 apcupsd: newups.c:108 write_unlock at action.c:649 80.494 apcupsd: device.c:230 Before do_reports: 0x7000008 (OB:0). 80.494 apcupsd: newups.c:90 read_lock at apcstatus.c:50 80.494 apcupsd: device.c:238 Before device_check_state: 0x7000008 (OB:0). 80.494 apcupsd: apcstatus.c:202 NO CI_VLINE 80.494 apcupsd: newups.c:96 read_unlock at apcstatus.c:449 140.554 apcupsd: device.c:212 Before do_action: 0x7000008 (OB:0). 140.554 apcupsd: newups.c:102 write_lock at action.c:351 140.554 apcupsd: newups.c:108 write_unlock at action.c:649 140.554 apcupsd: device.c:218 Before fillUPS: 0x7000008 (OB:0). 140.554 apcupsd: usb.c:850 Enter usb_ups_read_volatile_data 140.554 apcupsd: newups.c:102 write_lock at usb.c:862 140.930 apcupsd: linux-usb.c:349 Def val=1350 exp=-2 dVal=13.500000 ci=10 140.930 apcupsd: usb.c:490 BattVoltage = 13 141.520 apcupsd: linux-usb.c:349 Def val=1350 exp=-2 dVal=13.500000 ci=10 141.520 apcupsd: usb.c:490 BattVoltage = 13 141.784 apcupsd: linux-usb.c:361 Def val=0 exp=0 dVal=0.000000 ci=44 141.784 apcupsd: usb.c:442 ShutdownImminent=0 142.004 apcupsd: newups.c:108 write_unlock at usb.c:874 142.004 apcupsd: device.c:224 Before do_action: 0x7000000 (OB:0). 142.004 apcupsd: newups.c:102 write_lock at action.c:351 142.004 apcupsd: newups.c:108 write_unlock at action.c:649 142.004 apcupsd: device.c:230 Before do_reports: 0x7000000 (OB:0). 142.004 apcupsd: device.c:238 Before device_check_state: 0x7000000 (OB:0). 142.004 apcupsd: linux-usb.c:490 read error: ERR=Input/output error 142.004 apcupsd: linux-usb.c:241 link_check comm lost 147.005 apcupsd: linux-usb.c:88 Reinitializing private structure. 147.005 apcupsd: linux-usb.c:111 Attempting to open "/dev/usb/hiddev0" 147.013 apcupsd: linux-usb.c:126 Successfully opened "/dev/usb/hiddev0" 147.374 apcupsd: newups.c:102 write_lock at linux-usb.c:657 147.374 apcupsd: linux-usb.c:725 Got READ ci=48, usage=0x850044, rpt=6 147.374 apcupsd: linux-usb.c:725 Got READ ci=49, usage=0x850045, rpt=6 147.374 apcupsd: linux-usb.c:725 Got READ ci=1, usage=0xff860060, rpt=6 147.374 apcupsd: linux-usb.c:725 Got READ ci=9, usage=0x850066, rpt=12 147.374 apcupsd: linux-usb.c:725 Got READ ci=13, usage=0x850068, rpt=12 147.374 apcupsd: linux-usb.c:725 Got READ ci=56, usage=0x8500d0, rpt=19 147.374 apcupsd: linux-usb.c:725 Got READ ci=46, usage=0x850042, rpt=20 147.374 apcupsd: linux-usb.c:725 Got READ ci=44, usage=0x840069, rpt=20 147.374 apcupsd: linux-usb.c:725 Got READ ci=0, usage=0x8400fe, rpt=1 147.374 apcupsd: linux-usb.c:736 Got WRITE ci=0, usage=0x8400fe, rpt=1 147.374 apcupsd: linux-usb.c:725 Got READ ci=27, usage=0x8400ff, rpt=2 147.374 apcupsd: linux-usb.c:736 Got WRITE ci=27, usage=0x8400ff, rpt=2 147.374 apcupsd: linux-usb.c:736 Got WRITE ci=48, usage=0x850044, rpt=6 147.374 apcupsd: linux-usb.c:736 Got WRITE ci=49, usage=0x850045, rpt=6 147.374 apcupsd: linux-usb.c:736 Got WRITE ci=1, usage=0xff860060, rpt=6 147.374 apcupsd: linux-usb.c:725 Got READ ci=26, usage=0x850085, rpt=7 147.374 apcupsd: linux-usb.c:736 Got WRITE ci=26, usage=0x850085, rpt=7 147.374 apcupsd: linux-usb.c:725 Got READ ci=29, usage=0x840040, rpt=8 147.374 apcupsd: linux-usb.c:736 Got WRITE ci=29, usage=0x840040, rpt=8 147.374 apcupsd: linux-usb.c:725 Got READ ci=10, usage=0x840030, rpt=9 147.374 apcupsd: linux-usb.c:736 Got WRITE ci=10, usage=0x840030, rpt=9 147.374 apcupsd: linux-usb.c:725 Got READ ci=42, usage=0x8400fd, rpt=10 147.374 apcupsd: linux-usb.c:736 Got WRITE ci=42, usage=0x8400fd, rpt=10 147.374 apcupsd: linux-usb.c:736 Got WRITE ci=9, usage=0x850066, rpt=12 147.375 apcupsd: linux-usb.c:736 Got WRITE ci=13, usage=0x850068, rpt=12 147.375 apcupsd: linux-usb.c:725 Got READ ci=52, usage=0x85008c, rpt=15 147.375 apcupsd: linux-usb.c:736 Got WRITE ci=52, usage=0x85008c, rpt=15 147.375 apcupsd: linux-usb.c:725 Got READ ci=50, usage=0x850029, rpt=17 147.375 apcupsd: linux-usb.c:736 Got WRITE ci=50, usage=0x850029, rpt=17 147.375 apcupsd: linux-usb.c:736 Got WRITE ci=56, usage=0x8500d0, rpt=19 147.375 apcupsd: linux-usb.c:736 Got WRITE ci=46, usage=0x850042, rpt=20 147.375 apcupsd: linux-usb.c:736 Got WRITE ci=44, usage=0x840069, rpt=20 147.375 apcupsd: linux-usb.c:725 Got READ ci=41, usage=0xff860072, rpt=53 147.375 apcupsd: linux-usb.c:736 Got WRITE ci=41, usage=0xff860072, rpt=53 147.375 apcupsd: linux-usb.c:725 Got READ ci=62, usage=0xff86007c, rpt=64 147.375 apcupsd: linux-usb.c:736 Got WRITE ci=62, usage=0xff86007c, rpt=64 147.375 apcupsd: newups.c:108 write_unlock at linux-usb.c:749 147.382 apcupsd: linux-usb.c:304 Def val=1 exp=0 sVal="Back-UPS 500 FW: 6.4.I USB FW: c1 " ci=0 147.382 apcupsd: usb.c:306 Checking for BackUPS Pro quirk "Back-UPS 500 FW: 6.4.I USB FW: c1 " 147.382 apcupsd: newups.c:102 write_lock at usb.c:886 147.398 apcupsd: linux-usb.c:349 Def val=1200 exp=-2 dVal=12.000000 ci=29 147.419 apcupsd: linux-usb.c:349 Def val=1200 exp=-2 dVal=12.000000 ci=29 147.766 apcupsd: linux-usb.c:304 Def val=3 exp=5 sVal="American Power Conversion" ci=42 147.787 apcupsd: linux-usb.c:304 Def val=1 exp=0 sVal="Back-UPS 500 FW: 6.4.I USB FW: c1 " ci=0 147.807 apcupsd: linux-usb.c:304 Def val=2 exp=0 sVal="BB0311005920" ci=27 147.824 apcupsd: linux-usb.c:361 Def val=11883 exp=0 dVal=11883.000000 ci=26 147.845 apcupsd: linux-usb.c:361 Def val=10 exp=0 dVal=10.000000 ci=50 147.886 apcupsd: linux-usb.c:361 Def val=0 exp=0 dVal=0.000000 ci=48 147.987 apcupsd: linux-usb.c:361 Def val=50 exp=0 dVal=50.000000 ci=52 148.127 apcupsd: linux-usb.c:361 Def val=0 exp=0 dVal=0.000000 ci=62 148.148 apcupsd: linux-usb.c:361 Def val=0 exp=0 dVal=0.000000 ci=41 148.309 apcupsd: newups.c:108 write_unlock at usb.c:894 148.309 apcupsd: apclog.c:62 Communications with UPS restored. 148.309 apcupsd: action.c:89 calling execute_ups_event commok event=0 148.309 apcupsd: linux-usb.c:274 link check comm OK. 148.310 apcupsd: device.c:212 Before do_action: 0x7000000 (OB:0). 148.310 apcupsd: newups.c:102 write_lock at action.c:351 148.310 apcupsd: newups.c:108 write_unlock at action.c:649 148.310 apcupsd: device.c:218 Before fillUPS: 0x7000000 (OB:0). 148.310 apcupsd: usb.c:850 Enter usb_ups_read_volatile_data 148.310 apcupsd: newups.c:102 write_lock at usb.c:862 148.701 apcupsd: linux-usb.c:349 Def val=1350 exp=-2 dVal=13.500000 ci=10 148.701 apcupsd: usb.c:490 BattVoltage = 13 149.040 apcupsd: linux-usb.c:349 Def val=1350 exp=-2 dVal=13.500000 ci=10 149.040 apcupsd: usb.c:490 BattVoltage = 13 149.302 apcupsd: linux-usb.c:361 Def val=0 exp=0 dVal=0.000000 ci=44 149.302 apcupsd: usb.c:442 ShutdownImminent=0 149.344 apcupsd: linux-usb.c:361 Def val=0 exp=0 dVal=0.000000 ci=46 149.344 apcupsd: usb.c:430 BelowRemCapLimit=0 149.385 apcupsd: linux-usb.c:361 Def val=0 exp=0 dVal=0.000000 ci=49 149.385 apcupsd: usb.c:424 Discharging=0 149.426 apcupsd: linux-usb.c:361 Def val=100 exp=0 dVal=100.000000 ci=9 149.426 apcupsd: usb.c:484 BattCharge = 100 149.447 apcupsd: linux-usb.c:349 Def val=2580 exp=0 dVal=2580.000000 ci=13 149.447 apcupsd: usb.c:508 TimeLeft = 43 149.508 apcupsd: linux-usb.c:361 Def val=8 exp=0 dVal=8.000000 ci=1 149.508 apcupsd: usb.c:413 Status=0x07000008 149.527 apcupsd: newups.c:108 write_unlock at usb.c:874 149.527 apcupsd: device.c:224 Before do_action: 0x7000008 (OB:0). 149.527 apcupsd: newups.c:102 write_lock at action.c:351 149.527 apcupsd: newups.c:108 write_unlock at action.c:649 149.527 apcupsd: newups.c:90 read_lock at apcstatus.c:50 149.527 apcupsd: device.c:230 Before do_reports: 0x7000008 (OB:0). 149.527 apcupsd: apcstatus.c:202 NO CI_VLINE 149.527 apcupsd: newups.c:96 read_unlock at apcstatus.c:449 149.548 apcupsd: device.c:238 Before device_check_state: 0x7000008 (OB:0).