cmd test.battery.start.deep was not tested yet.
cmd test.battery.start.quick works as expected.
cmd test.battery.start 120 works as expected.
cmd test.battery.stop works as expected.
cmd beeper.toggle works as expected.
cmd shutdown.return works as expected.
cmd shutdown.reboot does not work entirely. After a minute, UPS is shut
down but didn't come back. (Issuing shutdown.stop power up it again)
Some details I found:
After shutdown.return and shutdown.reboot, usb port (/dev/ttyUSB0) is still
open and working.
Powering down from ups button disables communication.
Power up from ups button issues power-on self test (pop, crack and clicking
sounds) whereas shutdown.return and shutdown.stop just bring it on without
post. I think this is normal.
Also, from ups button power up, power up from shutdown.return or
shutdown.stop, the smsbrasil sets ups.status as OL BYPASS. Since this ups
is line interactive, what is this bypass bit? I didn't review the driver
yet but it is a bug from the driver or hardware?
Also related, after returning from a test, the driver sets ups.status as OL
_without_ BYPASS.
There is also (I suppose) wrong values on reestabilishing connection.
input.voltage 3892.0
input.frequency 0.2
output.voltage 3892.0
battery.charge 2252.8
ups.temp 3072.0
ups.load 3891.2
battery.runtime 180
ups.status ALARM OL BOOST OVER FSD
Could this be related to pu.BatOnOff ?
There is also another point, after issuing shutdown.reboot or
shutdown.return, the ups.load, output.voltage and battery.runtime values
goes to 0, but ups.status is "OL BYPASS". Is this ok?
driver debug log is attached. (smsbrasil -qqqqqq -DDDDDD -i 5)
Rodrigo
-------------- next part --------------
An HTML attachment was scrubbed...
URL:
<http://lists.alioth.debian.org/pipermail/nut-upsdev/attachments/20130626/7e5c2fb2/attachment-0001.html>
-------------- next part --------------
TURNED OFF via ups main button
- communication is lost.
- upsc sms always returns "Error: Data stale"
3096.067419 smsBR_RecAnswer: Started
3098.069531 smsBR_RecAnswer: Exiting by timeout (2 seconds)
3098.069592 Communications with UPS lost: upsdrv_updateinfo: UPS at port
/dev/ttyUSB0 is returning by timeout...
3098.069617 send_to_all: DATASTALE
3098.069699 send_to_one: PONG
3101.070466 smsBR_RecAnswer: Started
3103.072605 smsBR_RecAnswer: Exiting by timeout (2 seconds)
3103.072686 Communications with UPS lost: upsdrv_updateinfo: UPS at port
/dev/ttyUSB0 is returning by timeout...
3106.073475 smsBR_RecAnswer: Started
3108.075605 smsBR_RecAnswer: Exiting by timeout (2 seconds)
3108.075686 Communications with UPS lost: upsdrv_updateinfo: UPS at port
/dev/ttyUSB0 is returning by timeout...
3111.076076 smsBR_RecAnswer: Started
3113.078197 smsBR_RecAnswer: Exiting by timeout (2 seconds)
3113.078257 Communications with UPS lost: upsdrv_updateinfo: UPS at port
/dev/ttyUSB0 is returning by timeout...
3116.078210 smsBR_RecAnswer: Started
3118.080324 smsBR_RecAnswer: Exiting by timeout (2 seconds)
3118.080383 Communications with UPS lost: upsdrv_updateinfo: UPS at port
/dev/ttyUSB0 is returning by timeout...
-------------- next part --------------
ISSUED cmd shutdown.reboot (ups powers[B off)
POWERED UP via ups main button.
- OK: after a few seconds, it powers up.
- returns as OL,BYPASS.
2991.969202 send_to_one: PONG
2995.983147 smsBR_RecAnswer: Started
2997.529898 smsBR_RecAnswer: Finished
2998.019372 send_to_one: PONG
3000.986221 smsBR_RecAnswer: Started
3001.101895 smsBR_RecAnswer: Finished
3001.101968 send_to_all: SETINFO output.voltage "114.0"
3005.987327 smsBR_RecAnswer: Started
3006.102942 smsBR_RecAnswer: Finished
3006.102987 send_to_all: SETINFO output.voltage "115.0"
3006.103009 send_to_all: SETINFO ups.load "9.0"
3006.103024 send_to_all: SETINFO battery.runtime "4020"
3010.992345 smsBR_RecAnswer: Started
3011.107999 smsBR_RecAnswer: Finished
3011.108071 send_to_all: SETINFO output.voltage "114.0"
3011.108122 send_to_all: SETINFO ups.load "12.0"
3011.108154 send_to_all: SETINFO battery.runtime "3060"
3015.993547 smsBR_RecAnswer: Started
3016.108933 smsBR_RecAnswer: Finished
3016.109008 send_to_all: SETINFO ups.load "13.0"
3016.109052 send_to_all: SETINFO battery.runtime "2820"
3020.997869 smsBR_RecAnswer: Started
3021.117924 smsBR_RecAnswer: Finished
3021.117994 send_to_all: SETINFO input.voltage "228.0"
3021.118047 send_to_all: SETINFO ups.load "15.0"
3021.118080 send_to_all: SETINFO battery.runtime "2400"
-------------- next part --------------
ISSUED cmd shutdown.reboot
- OK: shutdown ups.
- FAILED: after a minute it is still powered off.
- attempting to power up via ups button works, power-on self test is executed
and it returns as OL,BYPASS.
2430.556827 smsBR_RecAnswer: Started
2430.672842 smsBR_RecAnswer: Finished
2432.019188 send_to_one: PONG
2434.428696 instcmd: command [shutdown.reboot] handled
2435.558033 smsBR_RecAnswer: Started
2435.673902 smsBR_RecAnswer: Finished
2435.673999 send_to_all: SETINFO ups.alarm "UPS under SHUTDOWN mode!"
2435.674042 send_to_all: SETINFO ups.status "ALARM OL BYPASS FSD"
2440.563059 smsBR_RecAnswer: Started
2440.678883 smsBR_RecAnswer: Finished
2442.111280 send_to_one: PONG
2445.566594 smsBR_RecAnswer: Started
2445.681823 smsBR_RecAnswer: Finished
2448.174522 send_to_one: PONG
2450.569001 smsBR_RecAnswer: Started
2450.684810 smsBR_RecAnswer: Finished
2450.684884 send_to_all: SETINFO output.voltage "0.0"
2450.684946 send_to_all: DELINFO ups.alarm
2450.684976 send_to_all: SETINFO ups.status "OL BYPASS"
2455.571353 smsBR_RecAnswer: Started
2455.686801 smsBR_RecAnswer: Finished
2455.686885 send_to_all: SETINFO ups.load "0.0"
2455.686931 send_to_all: SETINFO battery.runtime "0"
2460.576291 smsBR_RecAnswer: Started
2460.691822 smsBR_RecAnswer: Finished
2460.691890 send_to_all: SETINFO input.voltage "228.0"
2465.581234 smsBR_RecAnswer: Started
2465.696827 smsBR_RecAnswer: Finished
2465.696895 send_to_all: SETINFO input.voltage "230.0"
2470.586203 smsBR_RecAnswer: Started
2470.701823 smsBR_RecAnswer: Finished
2471.387767 send_to_one: PONG
2475.590461 smsBR_RecAnswer: Started
2475.705773 smsBR_RecAnswer: Finished
2477.440340 send_to_one: PONG
2480.593682 smsBR_RecAnswer: Started
2480.708823 smsBR_RecAnswer: Finished
2480.708891 send_to_all: SETINFO input.voltage "228.0"
deu
- desligando no botao:
perde conexao ttyusb0 timeout
- ligando no botao:
post
801.445746 smsBR_RecAnswer: Exiting by timeout (2 seconds)
801.445806 Communications with UPS lost: upsdrv_updateinfo: UPS at port
/dev/ttyUSB0 is returning by timeout...
804.446713 smsBR_RecAnswer: Started
804.446755 smsBR_RecAnswer: Finished
804.446775 send_to_all: SETINFO output.voltage "115.0"
804.446804 send_to_all: DATAOK
809.451780 smsBR_RecAnswer: Started
809.451858 smsBR_RecAnswer: Finished
809.451895 send_to_all: SETINFO input.voltage "230.0"
814.456905 smsBR_RecAnswer: Started
814.456979 smsBR_RecAnswer: Finished
814.457020 send_to_all: SETINFO output.voltage "114.0"
814.457069 send_to_all: SETINFO ups.temperature "27.0"
814.457097 send_to_all: SETINFO ups.load "4.0"
814.457128 send_to_all: SETINFO battery.runtime "9180"
819.461994 smsBR_RecAnswer: Started
819.462069 smsBR_RecAnswer: Finished
819.462103 send_to_all: SETINFO input.voltage "228.0"
819.462175 send_to_all: SETINFO ups.temperature "35.0"
819.462206 send_to_all: SETINFO ups.load "10.0"
819.462236 send_to_all: SETINFO battery.runtime "3660"
824.467081 smsBR_RecAnswer: Started
824.467182 smsBR_RecAnswer: Finished
824.467219 send_to_all: SETINFO input.voltage "230.0"
824.467271 send_to_all: SETINFO ups.load "16.0"
824.467304 send_to_all: SETINFO battery.runtime "2280"
829.468666 smsBR_RecAnswer: Started
829.468749 smsBR_RecAnswer: Finished
829.923706 send_to_one: PONG
FSD?
-- test quick
- volta como online apenas
456.942856 instcmd: command [test.battery.start.quick] handled
459.166328 smsBR_RecAnswer: Started
459.282400 smsBR_RecAnswer: Finished
459.282482 send_to_all: SETINFO output.voltage "113.0"
459.282549 send_to_all: SETINFO ups.alarm "UPS under Battery Test
Mode!"
459.282580 send_to_all: SETINFO ups.status "ALARM OB DISCHRG"
464.167063 smsBR_RecAnswer: Started
464.282398 smsBR_RecAnswer: Finished
464.282443 send_to_all: SETINFO output.voltage "111.0"
464.282465 send_to_all: SETINFO ups.temperature "35.0"
469.170223 smsBR_RecAnswer: Started
469.285426 smsBR_RecAnswer: Finished
469.285508 send_to_all: SETINFO output.voltage "107.0"
469.285557 send_to_all: SETINFO battery.charge "76.0"
469.285589 send_to_all: SETINFO ups.load "9.0"
469.285620 send_to_all: SETINFO battery.runtime "3060"
469.285652 send_to_all: DELINFO ups.alarm
469.285703 send_to_all: SETINFO ups.status "OL"
474.175170 smsBR_RecAnswer: Started
474.290443 smsBR_RecAnswer: Finished
474.290478 send_to_all: DATASTALE
479.176141 smsBR_RecAnswer: Started
479.291387 smsBR_RecAnswer: Finished
479.291438 send_to_all: SETINFO battery.charge "87.0"
479.291461 send_to_all: SETINFO ups.temperature "34.0"
479.291476 send_to_all: SETINFO ups.load "8.0"
479.291491 send_to_all: SETINFO battery.runtime "3960"
479.291506 send_to_all: DATAOK
-------------- next part --------------
ISSUED cmd shutdown.return
- OK: shutdown ups
- OK: power up on AC voltage presence.
- power up without power-on self test.
- returns as OL, BYPASS.
- bogus/wrong values at 319.101601-319.101923
287.521507 instcmd: command [shutdown.return] handled
289.080847 smsBR_RecAnswer: Started
289.196576 smsBR_RecAnswer: Finished
289.196645 send_to_all: SETINFO output.voltage "110.0"
289.196699 send_to_all: SETINFO ups.load "17.0"
289.196731 send_to_all: SETINFO battery.runtime "2100"
289.196767 send_to_all: SETINFO ups.alarm "UPS under SHUTDOWN mode!"
289.196798 send_to_all: SETINFO ups.status "ALARM OB DISCHRG"
294.085840 smsBR_RecAnswer: Started
296.087984 smsBR_RecAnswer: Exiting by timeout (2 seconds)
296.088043 Communications with UPS lost: upsdrv_updateinfo: UPS at port
/dev/ttyUSB0 is returning by timeout...
296.088066 send_to_all: DATASTALE
296.088127 send_to_one: PONG
299.087368 smsBR_RecAnswer: Started
301.087525 smsBR_RecAnswer: Exiting by timeout (2 seconds)
301.087589 Communications with UPS lost: upsdrv_updateinfo: UPS at port
/dev/ttyUSB0 is returning by timeout...
304.090471 smsBR_RecAnswer: Started
306.092596 smsBR_RecAnswer: Exiting by timeout (2 seconds)
306.092658 Communications with UPS lost: upsdrv_updateinfo: UPS at port
/dev/ttyUSB0 is returning by timeout...
309.093541 smsBR_RecAnswer: Started
311.085731 smsBR_RecAnswer: after 3 retries the number of bytes was
insuficient. Waited >15: received: 1
311.085792 Communications with UPS lost: upsdrv_updateinfo: UPS at port
/dev/ttyUSB0 is not replying requests...
314.096593 smsBR_RecAnswer: Started
314.336963 smsBR_RecAnswer: after 3 retries the number of bytes was
insuficient. Waited >15: received: 1
314.337024 Communications with UPS lost: upsdrv_updateinfo: UPS at port
/dev/ttyUSB0 is not replying requests...
319.101469 smsBR_RecAnswer: Started
319.101543 smsBR_RecAnswer: Finished
319.101564 Communications with UPS re-established
319.101601 send_to_all: SETINFO input.voltage "3892.0"
319.101642 send_to_all: SETINFO input.frequency "0.2"
319.101674 send_to_all: SETINFO output.voltage "3892.0"
319.101703 send_to_all: SETINFO output.frequency "0.2"
319.101733 send_to_all: SETINFO battery.charge "2252.8"
319.101763 send_to_all: SETINFO ups.temperature "3072.0"
319.101793 send_to_all: SETINFO ups.load "3891.2"
319.101848 send_to_all: SETINFO battery.runtime "180"
319.101888 send_to_all: SETINFO ups.alarm "UPS selftest failed! UPS under
SHUTDOWN mode! UPS under Battery Test Mode!"
319.101923 send_to_all: SETINFO ups.status "ALARM OL BOOST OVER FSD"
319.101949 send_to_all: DATAOK
324.106570 smsBR_RecAnswer: Started
324.106641 smsBR_RecAnswer: Finished
324.106665 send_to_all: DATASTALE
329.111650 smsBR_RecAnswer: Started
329.111723 smsBR_RecAnswer: Finished
329.111760 send_to_all: SETINFO input.voltage "228.0"
329.111800 send_to_all: SETINFO input.frequency "60.0"
329.111830 send_to_all: SETINFO output.voltage "114.0"
329.111859 send_to_all: SETINFO output.frequency "60.0"
329.111888 send_to_all: SETINFO battery.charge "92.0"
329.111915 send_to_all: SETINFO ups.temperature "35.0"
329.111942 send_to_all: SETINFO ups.load "10.0"
329.111992 send_to_all: SETINFO battery.runtime "3360"
329.112031 send_to_all: DELINFO ups.alarm
329.112059 send_to_all: SETINFO ups.status "OL BYPASS"
-------------- next part --------------
ISSUED cmd shutdown.reboot (ups powers[B off)
ISSUED cmd shutdown.stop
- OK: after a few seconds, it powers up.
- returns as OL,BYPASS.
2677.032464 instcmd: command [shutdown.stop] handled
2679.239243 send_to_one: PONG
2680.714296 smsBR_RecAnswer: Started
2680.829828 smsBR_RecAnswer: Finished
2680.829897 send_to_all: SETINFO input.voltage "228.0"
2685.719242 smsBR_RecAnswer: Started
2685.834859 smsBR_RecAnswer: Finished
2685.834929 send_to_all: SETINFO input.voltage "230.0"
2690.724324 smsBR_RecAnswer: Started
2690.839941 smsBR_RecAnswer: Finished
2690.840014 send_to_all: SETINFO output.voltage "115.0"
2695.729329 smsBR_RecAnswer: Started
2695.844934 smsBR_RecAnswer: Finished
2695.845018 send_to_all: SETINFO ups.load "6.0"
2695.845058 send_to_all: SETINFO battery.runtime "6120"
2700.731103 smsBR_RecAnswer: Started
2700.846939 smsBR_RecAnswer: Finished
2700.847014 send_to_all: SETINFO output.voltage "114.0"
2700.847066 send_to_all: SETINFO ups.load "10.0"
2700.847098 send_to_all: SETINFO battery.runtime "3660"
2705.736082 smsBR_RecAnswer: Started
2705.850942 smsBR_RecAnswer: Finished
2705.851025 send_to_all: SETINFO ups.load "12.0"
2705.851071 send_to_all: SETINFO battery.runtime "3060"
2710.741048 smsBR_RecAnswer: Started
2710.856951 smsBR_RecAnswer: Finished
2710.857035 send_to_all: SETINFO ups.load "14.0"
2710.857076 send_to_all: SETINFO battery.runtime "2580"
2715.746045 smsBR_RecAnswer: Started
2715.861871 smsBR_RecAnswer: Finished
2715.861941 send_to_all: SETINFO input.voltage "228.0"
2715.861988 send_to_all: SETINFO output.voltage "115.0"
2715.862027 send_to_all: SETINFO ups.load "16.0"
2715.862058 send_to_all: SETINFO battery.runtime "2280"
-------------- next part --------------
WRONG VALUES
287.521507 instcmd: command [shutdown.return] handled
289.080847 smsBR_RecAnswer: Started
289.196576 smsBR_RecAnswer: Finished
289.196645 send_to_all: SETINFO output.voltage "110.0"
289.196699 send_to_all: SETINFO ups.load "17.0"
289.196731 send_to_all: SETINFO battery.runtime "2100"
289.196767 send_to_all: SETINFO ups.alarm "UPS under SHUTDOWN mode!"
289.196798 send_to_all: SETINFO ups.status "ALARM OB DISCHRG"
294.085840 smsBR_RecAnswer: Started
296.087984 smsBR_RecAnswer: Exiting by timeout (2 seconds)
296.088043 Communications with UPS lost: upsdrv_updateinfo: UPS at port
/dev/ttyUSB0 is returning by timeout...
296.088066 send_to_all: DATASTALE
296.088127 send_to_one: PONG
299.087368 smsBR_RecAnswer: Started
301.087525 smsBR_RecAnswer: Exiting by timeout (2 seconds)
301.087589 Communications with UPS lost: upsdrv_updateinfo: UPS at port
/dev/ttyUSB0 is returning by timeout...
304.090471 smsBR_RecAnswer: Started
306.092596 smsBR_RecAnswer: Exiting by timeout (2 seconds)
306.092658 Communications with UPS lost: upsdrv_updateinfo: UPS at port
/dev/ttyUSB0 is returning by timeout...
309.093541 smsBR_RecAnswer: Started
311.085731 smsBR_RecAnswer: after 3 retries the number of bytes was
insuficient. Waited >15: received: 1
311.085792 Communications with UPS lost: upsdrv_updateinfo: UPS at port
/dev/ttyUSB0 is not replying requests...
314.096593 smsBR_RecAnswer: Started
314.336963 smsBR_RecAnswer: after 3 retries the number of bytes was
insuficient. Waited >15: received: 1
314.337024 Communications with UPS lost: upsdrv_updateinfo: UPS at port
/dev/ttyUSB0 is not replying requests...
319.101469 smsBR_RecAnswer: Started
319.101543 smsBR_RecAnswer: Finished
319.101564 Communications with UPS re-established
319.101601 send_to_all: SETINFO input.voltage "3892.0"
319.101642 send_to_all: SETINFO input.frequency "0.2"
319.101674 send_to_all: SETINFO output.voltage "3892.0"
319.101703 send_to_all: SETINFO output.frequency "0.2"
319.101733 send_to_all: SETINFO battery.charge "2252.8"
319.101763 send_to_all: SETINFO ups.temperature "3072.0"
319.101793 send_to_all: SETINFO ups.load "3891.2"
319.101848 send_to_all: SETINFO battery.runtime "180"
319.101888 send_to_all: SETINFO ups.alarm "UPS selftest failed! UPS under
SHUTDOWN mode! UPS under Battery Test Mode!"
319.101923 send_to_all: SETINFO ups.status "ALARM OL BOOST OVER FSD"
319.101949 send_to_all: DATAOK
324.106570 smsBR_RecAnswer: Started
324.106641 smsBR_RecAnswer: Finished
324.106665 send_to_all: DATASTALE
329.111650 smsBR_RecAnswer: Started
329.111723 smsBR_RecAnswer: Finished
329.111760 send_to_all: SETINFO input.voltage "228.0"
329.111800 send_to_all: SETINFO input.frequency "60.0"
329.111830 send_to_all: SETINFO output.voltage "114.0"
329.111859 send_to_all: SETINFO output.frequency "60.0"
329.111888 send_to_all: SETINFO battery.charge "92.0"
329.111915 send_to_all: SETINFO ups.temperature "35.0"
329.111942 send_to_all: SETINFO ups.load "10.0"
329.111992 send_to_all: SETINFO battery.runtime "3360"
329.112031 send_to_all: DELINFO ups.alarm
329.112059 send_to_all: SETINFO ups.status "OL BYPASS"