ArduCounter Support und neue Versionen (war: Stromzähler mit S0 Schnitt...)

Begonnen von StefanStrobel, 26 Januar 2014, 12:08:13

Vorheriges Thema - Nächstes Thema

birdy

Hallo Stefan

Ich habe heute wären rund 6 Stunden das Verhalten mit den DMM aufgezeichnet und die Logs abgeglichen.

Zuerst das erfreuliche, die neue Debugg Funktion zeigt den Status Wechsel zuverlässig. Ein Vergleich zum DMM Log ergab über die gesamte Zeit eine vollständige Übereinstimmung.

Aber nach diesen 6 Stunden hatte der ArduCounter an Pin 4 mehr Impulse gezählt als der mechanische Zähler. ::)
Jetzt zählt er plötzlich mehr als die vorhanden Impulse

Ich habe den ArduCounter (unbewusst) ohne Pulslängen-Kontrolle laufen gelassen. gem. meinem Verständnis müsste er also immer bzw. nur bei 5>0 zählen.
Das macht er gem. Log in der Regel auch korrekt z.B. 18:51:01 / 18:51:20
Dann aber 19:20:58 / 19:21:19 Pin geht Pin 4 von 0>5 und ArduCounter erhöht den Zähler aus einem mir nicht ersichtlichen Grund..... ???


2017.11.26 18:49:20 4: AC: adding 0 to long count 4489 and interpolated count 4489
2017.11.26 18:49:20 4: AC: Pin 4 (pin4) count 17 longCount 4489 interpCount 4489 (diff 0) in 360.000s, Avg Len ms, result 0.000
2017.11.26 18:49:20 4: AC: interval 18:43:20 until 18:49:20, First at , Last at
2017.11.26 18:51:01 3: AC: device: pin 4 changed state to 0 , count 18 (+1)
2017.11.26 18:51:20 4: AC: adding 1 to long count 4489 and interpolated count 4489
2017.11.26 18:51:20 4: AC: Pin 4 (pin4) count 18 longCount 4490 interpCount 4490 (diff 1) in 101.144s, Avg Len ms, result 0.036
2017.11.26 18:51:20 4: AC: interval 18:49:39 until 18:51:20, First at 101144, Last at 101144
2017.11.26 18:52:20 4: AC: adding 0 to long count 13112 and interpolated count 13112
2017.11.26 18:52:20 4: AC: Pin 6 (pin6) count 0 longCount 13112 interpCount 13112 (diff 0) in 360.000s, reject 0, Avg Len ms, result 0.000
2017.11.26 18:52:20 4: AC: interval 18:46:20 until 18:52:20, First at , Last at
2017.11.26 18:52:20 4: AC: Pin 5 (pin5) count 0 (diff 0) in 360.000s, reject 0, Avg Len ms, result 0.000
2017.11.26 18:52:20 4: AC: interval 18:46:20 until 18:52:20, First at , Last at
2017.11.26 18:54:22 3: AC: device: pin 4 changed state to 1 , count 18 (+0)
2017.11.26 18:57:20 4: AC: adding 0 to long count 4490 and interpolated count 4490
2017.11.26 18:57:20 4: AC: Pin 4 (pin4) count 18 longCount 4490 interpCount 4490 (diff 0) in 378.856s, Avg Len ms, result 0.000
2017.11.26 18:57:20 4: AC: interval 18:51:01 until 18:57:20, First at , Last at
2017.11.26 18:58:20 4: AC: adding 0 to long count 13112 and interpolated count 13112
2017.11.26 18:58:20 4: AC: Pin 6 (pin6) count 0 longCount 13112 interpCount 13112 (diff 0) in 360.000s, reject 0, Avg Len ms, result 0.000
2017.11.26 18:58:20 4: AC: interval 18:52:20 until 18:58:20, First at , Last at
2017.11.26 18:58:20 4: AC: Pin 5 (pin5) count 0 (diff 0) in 360.000s, reject 0, Avg Len ms, result 0.000
2017.11.26 18:58:20 4: AC: interval 18:52:20 until 18:58:20, First at , Last at
2017.11.26 19:03:20 4: AC: adding 0 to long count 4490 and interpolated count 4490
2017.11.26 19:03:20 4: AC: Pin 4 (pin4) count 18 longCount 4490 interpCount 4490 (diff 0) in 360.000s, Avg Len ms, result 0.000
2017.11.26 19:03:20 4: AC: interval 18:57:20 until 19:03:20, First at , Last at
2017.11.26 19:04:20 4: AC: adding 0 to long count 13112 and interpolated count 13112
2017.11.26 19:04:20 4: AC: Pin 6 (pin6) count 0 longCount 13112 interpCount 13112 (diff 0) in 360.000s, reject 0, Avg Len ms, result 0.000
2017.11.26 19:04:20 4: AC: interval 18:58:20 until 19:04:20, First at , Last at
2017.11.26 19:04:20 4: AC: Pin 5 (pin5) count 0 (diff 0) in 360.000s, reject 0, Avg Len ms, result 0.000
2017.11.26 19:04:20 4: AC: interval 18:58:20 until 19:04:20, First at , Last at
2017.11.26 19:09:20 4: AC: adding 0 to long count 4490 and interpolated count 4490
2017.11.26 19:09:20 4: AC: Pin 4 (pin4) count 18 longCount 4490 interpCount 4490 (diff 0) in 360.000s, Avg Len ms, result 0.000
2017.11.26 19:09:20 4: AC: interval 19:03:20 until 19:09:20, First at , Last at
2017.11.26 19:10:20 4: AC: adding 0 to long count 13112 and interpolated count 13112
2017.11.26 19:10:20 4: AC: Pin 6 (pin6) count 0 longCount 13112 interpCount 13112 (diff 0) in 360.000s, reject 0, Avg Len ms, result 0.000
2017.11.26 19:10:20 4: AC: interval 19:04:20 until 19:10:20, First at , Last at
2017.11.26 19:10:20 4: AC: Pin 5 (pin5) count 0 (diff 0) in 360.000s, reject 0, Avg Len ms, result 0.000
2017.11.26 19:10:20 4: AC: interval 19:04:20 until 19:10:20, First at , Last at
2017.11.26 19:14:47 3: AC: device: pin 4 changed state to 0 , count 19 (+1)
2017.11.26 19:15:20 4: AC: adding 1 to long count 4490 and interpolated count 4490
2017.11.26 19:15:20 4: AC: Pin 4 (pin4) count 19 longCount 4491 interpCount 4491 (diff 1) in 327.078s, Avg Len ms, result 0.011
2017.11.26 19:15:20 4: AC: interval 19:09:52 until 19:15:20, First at 327078, Last at 327078
2017.11.26 19:16:19 4: AC: adding 0 to long count 13112 and interpolated count 13112
2017.11.26 19:16:19 4: AC: Pin 6 (pin6) count 0 longCount 13112 interpCount 13112 (diff 0) in 360.000s, reject 0, Avg Len ms, result 0.000
2017.11.26 19:16:19 4: AC: interval 19:10:19 until 19:16:19, First at , Last at
2017.11.26 19:16:19 4: AC: Pin 5 (pin5) count 0 (diff 0) in 360.000s, reject 0, Avg Len ms, result 0.000
2017.11.26 19:16:19 4: AC: interval 19:10:19 until 19:16:19, First at , Last at
2017.11.26 19:20:58 3: AC: device: pin 4 changed state to 1 , count 20 (+1)
2017.11.26 19:21:19 4: AC: adding 1 to long count 4491 and interpolated count 4491
2017.11.26 19:21:19 4: AC: Pin 4 (pin4) count 20 longCount 4492 interpCount 4492 (diff 1) in 372.010s, Avg Len ms, result 0.010
2017.11.26 19:21:19 4: AC: interval 19:15:07 until 19:21:19, First at 372010, Last at 372010
2017.11.26 19:22:19 4: AC: adding 0 to long count 13112 and interpolated count 13112
2017.11.26 19:22:19 4: AC: Pin 6 (pin6) count 0 longCount 13112 interpCount 13112 (diff 0) in 360.000s, reject 0, Avg Len ms, result 0.000
2017.11.26 19:22:19 4: AC: interval 19:16:19 until 19:22:19, First at , Last at
2017.11.26 19:22:19 4: AC: Pin 5 (pin5) count 0 (diff 0) in 360.000s, reject 0, Avg Len ms, result 0.000
2017.11.26 19:22:19 4: AC: interval 19:16:19 until 19:22:19, First at , Last at
2017.11.26 19:24:06 3: AC: device: pin 4 changed state to 0 , count 21 (+1)
2017.11.26 19:24:19 4: AC: adding 1 to long count 4492 and interpolated count 4492
2017.11.26 19:24:19 4: AC: Pin 4 (pin4) count 21 longCount 4493 interpCount 4493 (diff 1) in 187.734s, Avg Len ms, result 0.019
2017.11.26 19:24:19 4: AC: interval 19:21:11 until 19:24:19, First at 187734, Last at 187734
2017.11.26 19:26:59 3: AC: device: pin 4 changed state to 1 , count 21 (+0)
2017.11.26 19:28:19 4: AC: adding 0 to long count 13112 and interpolated count 13112
2017.11.26 19:28:19 4: AC: Pin 6 (pin6) count 0 longCount 13112 interpCount 13112 (diff 0) in 360.000s, reject 0, Avg Len ms, result 0.000
2017.11.26 19:28:19 4: AC: interval 19:22:19 until 19:28:19, First at , Last at
2017.11.26 19:28:19 4: AC: Pin 5 (pin5) count 0 (diff 0) in 360.000s, reject 0, Avg Len ms, result 0.000
2017.11.26 19:28:19 4: AC: interval 19:22:19 until 19:28:19, First at , Last at




Internals:
   DEF        /dev/ttyUSB0@38400
   DeviceName /dev/ttyUSB0@38400
   FD         4
   Initialized 1
   NAME       AC
   NOTIFYDEV  global
   NR         21
   NTFY_ORDER 50-AC
   PARTIAL
   STATE      opened
   TYPE       ArduCounter
   VersionFirmware 1.91
   VersionModule 5.3 - 26.11.2017
   buffer
   CounterInterpolated:
   READINGS:
     2017-11-26 23:47:10   OelVorrat       1002.9
     2017-11-26 23:47:10   OelZaehler      451.1
     2017-11-26 23:47:10   OelZaehlerI     451.1
     2017-11-26 23:47:10   countDiff4      0
     2017-11-26 23:46:10   countDiff6      0
     2017-11-26 23:47:10   interpolatedLong4 4511
     2017-11-26 23:46:10   interpolatedLong6 13112
     2017-11-26 23:47:10   lastMsg4        R4 C46 D0 R0 T399642 N32826713

     2017-11-26 23:46:10   lastMsg6        R6 C0 D0 R0 T360000 N32766695 X0

     2017-11-26 23:47:10   long4           4511
     2017-11-26 23:46:10   long6           13112
     2017-11-26 23:47:10   pin4            46
     2017-11-26 23:46:10   pin5            0
     2017-11-26 23:46:10   pin6            0
     2017-11-26 23:47:10   power4          0.000
     2017-11-26 23:46:10   power5          0.000
     2017-11-26 23:46:10   power6          0.000
     2017-11-26 23:47:10   reject4
     2017-11-26 23:46:10   reject6         0
     2017-11-26 14:48:57   state           opened
     2017-11-26 23:47:10   timeDiff4       399642
     2017-11-26 23:46:10   timeDiff6       360000
Attributes:
   flashCommand avrdude -p atmega328P -c arduino -P [PORT] -D -U flash:w:[HEXFILE] 2>[LOGFILE]
   interval   60 360
   pin4       falling pullup
   pin5       falling pullup 2000
   pin6       falling pullup 90
   readingFactor6 10000
   userReadings OelZaehler {ReadingsVal("AC","long4",0)/10},OelZaehlerI {ReadingsVal("AC","interpolatedLong4",0)/10},OelVorrat {1454-ReadingsVal("AC","OelZaehlerI",0)}
   userattr   pin4 pin5 pin6 readingFactor6 verboseReadings4 verboseReadings6
   verbose    4
   verboseReadings4 1
   verboseReadings6 1


Falls Du weitere Angaben benötigst, bitte melden.

Gruss birdy
FHEM  @Debian bullseye @Proxmox VE 8.1.3
@intelNUC's  (i5)
CUL 433(a-culfw), CUL 868(SlowRF), Max-Cube CUN geflash, HM-CFG-USB-2 (HMALND)

StefanStrobel

Hallo birdy,

ich gehe davon aus, dass die Differenz von kurzen Störimpulsen ausgeht. Ohne Pulslängenkontrolle werden die mitgezählt.
Das Zählen erfolgt im Sketch über Interrupts und ist daher schnell. Die Debug-Ausgabe der Zustandsänderungen läuft aber in der Hauptschleife und ist für kurze Impulse zu langsam. Daher kann es gut sein, dass so ein kurzer Störimpuls zwar einen Interrupt auslöst und gezählt wird, aber in der Hauptschleife gar nicht bemerkt wird.

Daher würde ich empfehlen mit Pulslängenkontrolle zu zählen. Ein kurzer Störimpuls wird dann als "rejected" gezählt.

Gruss
   Stefan

birdy

Hallo Stefan

Ja möglicherweise prellt der Reed Kontakt. Vielen Dank für den Hinweis. Werde die nächste Messreihe mit Pulslängenkontrolle laufen lassen.

Gruss birdy
FHEM  @Debian bullseye @Proxmox VE 8.1.3
@intelNUC's  (i5)
CUL 433(a-culfw), CUL 868(SlowRF), Max-Cube CUN geflash, HM-CFG-USB-2 (HMALND)

GlennDandy

Hallo, hab es nach Gebaut und es scheint Zu funktionieren. :D Und somit bin ich schon einmal begeistert von eurer Arbeit. Hatte vorher den IST Verbrauch mit dem S0COUNTER Modul ausgelesen es geht geht auch schnell. Aber es gab unzählige Impulsspitzen.

Aber jetzt zu Frage?

Gibt es eine Möglichkeit das Modul mit dem HourCounter zuverbinden?
Im HourCounter wiki wird ja define <name> HourCounter <regexp_for_ON>

Gibt es eine Befehl für den on Wert? Oder könnnte man das Implementieren? Dann könnte man eine Tages/Monats/... Statistik führen?

StefanStrobel


birdy

Hallo Stefan

Ich habe heute von 17:30 – 21:20 das Verhalten mit den DMM mitgeschnitten.
Der Durchlaufzähler hat 2.24L gezählt. In dieser Zeit ist das Signal gem. DMM Log 23x von 0>5. Der ArduCounter hat in dieser Zeit aber nur 15 Impulse gezählt. Während dieser Zeit sind also 8 Impulse ignoriert worden.
Die Debugg Funktion des ArduCounter hat alle Statusübergänge übereinstimmend zum DMM Log 1:1 erkannt.
Der Zähler reject4 hatte danach den Wert 0  (siehe List )
Die nicht gezählten Impulse kannst Du hier im Log finden.

18:35:17 / 18:37:04
18:49:57 / 18:51:03
18:54:21 / 18:55:03
19:28:12 / 19:29:02
20:20:14 / 20:01:01
20:46:49 / 20:48:49
20:56:59 / 20:58:59
21:02:43 / 21:04:59

Gruss birdy

Log

2017.11.30 17:28:10 3: AC: device: pin 4 changed state to 0 , count 344 (+0)  Rej 1
2017.11.30 17:29:06 4: AC: adding 0 to long count 4793 and interpolated count 4793
2017.11.30 17:29:06 4: AC: Pin 4 (pin4) count 344 longCount 4793 interpCount 4793 (diff 0) in 360.000s, reject 1, Avg Len ms, result 0.000
2017.11.30 17:29:06 4: AC: interval 17:23:06 until 17:29:06, First at , Last at
2017.11.30 17:31:06 4: AC: adding 0 to long count 13112 and interpolated count 13112
2017.11.30 17:31:06 4: AC: Pin 6 (pin6) count 0 longCount 13112 interpCount 13112 (diff 0) in 360.000s, reject 0, Avg Len ms, result 0.000
2017.11.30 17:31:06 4: AC: interval 17:25:06 until 17:31:06, First at , Last at
2017.11.30 17:35:06 4: AC: adding 0 to long count 4893 and interpolated count 4893
2017.11.30 17:35:06 4: AC: Pin 4 (pin4) count 344 longCount 4893 interpCount 4893 (diff 0) in 360.000s, reject 0, Avg Len ms, result 0.000
2017.11.30 17:35:06 4: AC: interval 17:29:06 until 17:35:06, First at , Last at
2017.11.30 17:37:06 4: AC: adding 0 to long count 13112 and interpolated count 13112
2017.11.30 17:37:06 4: AC: Pin 6 (pin6) count 0 longCount 13112 interpCount 13112 (diff 0) in 360.000s, reject 0, Avg Len ms, result 0.000
2017.11.30 17:37:06 4: AC: interval 17:31:06 until 17:37:06, First at , Last at
2017.11.30 17:41:05 4: AC: adding 0 to long count 4893 and interpolated count 4893
2017.11.30 17:41:05 4: AC: Pin 4 (pin4) count 344 longCount 4893 interpCount 4893 (diff 0) in 360.000s, reject 0, Avg Len ms, result 0.000
2017.11.30 17:41:05 4: AC: interval 17:35:05 until 17:41:05, First at , Last at
2017.11.30 17:41:45 3: AC: device: pin 4 changed state to 1 , count 345 (+1)  Rej 0
2017.11.30 17:42:05 4: AC: adding 1 to long count 4893 and interpolated count 4893
2017.11.30 17:42:05 4: AC: Pin 4 (pin4) count 345 longCount 4894 interpCount 4894 (diff 1) in 39.191s, reject 0, Avg Len 815499ms, result 0.092
2017.11.30 17:42:05 4: AC: interval 17:41:26 until 17:42:05, First at 39191, Last at 39191
2017.11.30 17:43:05 4: AC: adding 0 to long count 13112 and interpolated count 13112
2017.11.30 17:43:05 4: AC: Pin 6 (pin6) count 0 longCount 13112 interpCount 13112 (diff 0) in 360.000s, reject 0, Avg Len ms, result 0.000
2017.11.30 17:43:05 4: AC: interval 17:37:05 until 17:43:05, First at , Last at
2017.11.30 17:44:36 3: AC: device: pin 4 changed state to 0 , count 345 (+0)  Rej 0
2017.11.30 17:46:58 3: AC: device: pin 4 changed state to 1 , count 346 (+1)  Rej 0
2017.11.30 17:47:05 4: AC: adding 1 to long count 4894 and interpolated count 4894
2017.11.30 17:47:05 4: AC: Pin 4 (pin4) count 346 longCount 4895 interpCount 4895 (diff 1) in 313.321s, reject 0, Avg Len 141880ms, result 0.011
2017.11.30 17:47:05 4: AC: interval 17:41:52 until 17:47:05, First at 313321, Last at 313321
2017.11.30 17:49:05 4: AC: adding 0 to long count 13112 and interpolated count 13112
2017.11.30 17:49:05 4: AC: Pin 6 (pin6) count 0 longCount 13112 interpCount 13112 (diff 0) in 360.000s, reject 0, Avg Len ms, result 0.000
2017.11.30 17:49:05 4: AC: interval 17:43:05 until 17:49:05, First at , Last at
2017.11.30 17:49:06 3: AC: device: pin 4 changed state to 0 , count 346 (+0)  Rej 0
2017.11.30 17:51:29 3: AC: device: pin 4 changed state to 1 , count 347 (+1)  Rej 0
2017.11.30 17:52:05 4: AC: adding 1 to long count 4895 and interpolated count 4895
2017.11.30 17:52:05 4: AC: Pin 4 (pin4) count 347 longCount 4896 interpCount 4896 (diff 1) in 271.241s, reject 0, Avg Len 143317ms, result 0.013
2017.11.30 17:52:05 4: AC: interval 17:47:34 until 17:52:05, First at 271241, Last at 271241
2017.11.30 17:54:25 3: AC: device: pin 4 changed state to 0 , count 347 (+0)  Rej 0
2017.11.30 17:55:05 4: AC: adding 0 to long count 13112 and interpolated count 13112
2017.11.30 17:55:05 4: AC: Pin 6 (pin6) count 0 longCount 13112 interpCount 13112 (diff 0) in 360.000s, reject 0, Avg Len ms, result 0.000
2017.11.30 17:55:05 4: AC: interval 17:49:05 until 17:55:05, First at , Last at
2017.11.30 17:57:15 3: AC: device: pin 4 changed state to 1 , count 348 (+1)  Rej 0
2017.11.30 17:58:05 4: AC: adding 1 to long count 4896 and interpolated count 4896
2017.11.30 17:58:05 4: AC: Pin 4 (pin4) count 348 longCount 4897 interpCount 4897 (diff 1) in 346.313s, reject 0, Avg Len 169927ms, result 0.010
2017.11.30 17:58:05 4: AC: interval 17:52:19 until 17:58:05, First at 346313, Last at 346313
2017.11.30 18:01:05 4: AC: adding 0 to long count 13112 and interpolated count 13112
2017.11.30 18:01:05 4: AC: Pin 6 (pin6) count 0 longCount 13112 interpCount 13112 (diff 0) in 360.000s, reject 0, Avg Len ms, result 0.000
2017.11.30 18:01:05 4: AC: interval 17:55:05 until 18:01:05, First at , Last at
2017.11.30 18:04:05 4: AC: adding 0 to long count 4897 and interpolated count 4897
2017.11.30 18:04:05 4: AC: Pin 4 (pin4) count 348 longCount 4897 interpCount 4897 (diff 0) in 409.934s, reject 0, Avg Len ms, result 0.000
2017.11.30 18:04:05 4: AC: interval 17:57:15 until 18:04:05, First at , Last at
2017.11.30 18:07:05 4: AC: adding 0 to long count 13112 and interpolated count 13112
2017.11.30 18:07:05 4: AC: Pin 6 (pin6) count 0 longCount 13112 interpCount 13112 (diff 0) in 360.000s, reject 0, Avg Len ms, result 0.000
2017.11.30 18:07:05 4: AC: interval 18:01:05 until 18:07:05, First at , Last at
2017.11.30 18:10:05 4: AC: adding 0 to long count 4897 and interpolated count 4897
2017.11.30 18:10:05 4: AC: Pin 4 (pin4) count 348 longCount 4897 interpCount 4897 (diff 0) in 360.000s, reject 0, Avg Len ms, result 0.000
2017.11.30 18:10:05 4: AC: interval 18:04:05 until 18:10:05, First at , Last at
2017.11.30 18:13:04 4: AC: adding 0 to long count 13112 and interpolated count 13112
2017.11.30 18:13:04 4: AC: Pin 6 (pin6) count 0 longCount 13112 interpCount 13112 (diff 0) in 360.000s, reject 0, Avg Len ms, result 0.000
2017.11.30 18:13:04 4: AC: interval 18:07:04 until 18:13:04, First at , Last at
2017.11.30 18:16:04 4: AC: adding 0 to long count 4897 and interpolated count 4897
2017.11.30 18:16:04 4: AC: Pin 4 (pin4) count 348 longCount 4897 interpCount 4897 (diff 0) in 360.000s, reject 0, Avg Len ms, result 0.000
2017.11.30 18:16:04 4: AC: interval 18:10:04 until 18:16:04, First at , Last at
2017.11.30 18:17:32 3: AC: device: pin 4 changed state to 0 , count 348 (+0)  Rej 0
2017.11.30 18:19:04 4: AC: adding 0 to long count 13112 and interpolated count 13112
2017.11.30 18:19:04 4: AC: Pin 6 (pin6) count 0 longCount 13112 interpCount 13112 (diff 0) in 360.000s, reject 0, Avg Len ms, result 0.000
2017.11.30 18:19:04 4: AC: interval 18:13:04 until 18:19:04, First at , Last at
2017.11.30 18:20:15 3: AC: device: pin 4 changed state to 1 , count 349 (+1)  Rej 0
2017.11.30 18:21:04 4: AC: adding 1 to long count 4897 and interpolated count 4897
2017.11.30 18:21:04 4: AC: Pin 4 (pin4) count 349 longCount 4898 interpCount 4898 (diff 1) in 250.662s, reject 0, Avg Len 163394ms, result 0.014
2017.11.30 18:21:04 4: AC: interval 18:16:54 until 18:21:04, First at 250662, Last at 250662
2017.11.30 18:23:10 3: AC: device: pin 4 changed state to 0 , count 349 (+0)  Rej 1
2017.11.30 18:25:04 4: AC: adding 0 to long count 13112 and interpolated count 13112
2017.11.30 18:25:04 4: AC: Pin 6 (pin6) count 0 longCount 13112 interpCount 13112 (diff 0) in 360.000s, reject 0, Avg Len ms, result 0.000
2017.11.30 18:25:04 4: AC: interval 18:19:04 until 18:25:04, First at , Last at
2017.11.30 18:27:04 4: AC: adding 0 to long count 4898 and interpolated count 4898
2017.11.30 18:27:04 4: AC: Pin 4 (pin4) count 349 longCount 4898 interpCount 4898 (diff 0) in 409.338s, reject 1, Avg Len ms, result 0.000
2017.11.30 18:27:04 4: AC: interval 18:20:15 until 18:27:04, First at , Last at
2017.11.30 18:31:04 4: AC: adding 0 to long count 13112 and interpolated count 13112
2017.11.30 18:31:04 4: AC: Pin 6 (pin6) count 0 longCount 13112 interpCount 13112 (diff 0) in 360.000s, reject 0, Avg Len ms, result 0.000
2017.11.30 18:31:04 4: AC: interval 18:25:04 until 18:31:04, First at , Last at
2017.11.30 18:33:04 4: AC: adding 0 to long count 4898 and interpolated count 4898
2017.11.30 18:33:04 4: AC: Pin 4 (pin4) count 349 longCount 4898 interpCount 4898 (diff 0) in 360.000s, reject 0, Avg Len ms, result 0.000
2017.11.30 18:33:04 4: AC: interval 18:27:04 until 18:33:04, First at , Last at
2017.11.30 18:35:17 3: AC: device: pin 4 changed state to 1 , count 349 (+0)  Rej 1
2017.11.30 18:37:04 4: AC: adding 0 to long count 13112 and interpolated count 13112
2017.11.30 18:37:04 4: AC: Pin 6 (pin6) count 0 longCount 13112 interpCount 13112 (diff 0) in 360.000s, reject 0, Avg Len ms, result 0.000
2017.11.30 18:37:04 4: AC: interval 18:31:04 until 18:37:04, First at , Last at
2017.11.30 18:39:04 4: AC: adding 0 to long count 4898 and interpolated count 4898
2017.11.30 18:39:04 4: AC: Pin 4 (pin4) count 349 longCount 4898 interpCount 4898 (diff 0) in 360.000s, reject 1, Avg Len ms, result 0.000
2017.11.30 18:39:04 4: AC: interval 18:33:04 until 18:39:04, First at , Last at
2017.11.30 18:43:03 4: AC: adding 0 to long count 13112 and interpolated count 13112
2017.11.30 18:43:03 4: AC: Pin 6 (pin6) count 0 longCount 13112 interpCount 13112 (diff 0) in 360.000s, reject 0, Avg Len ms, result 0.000
2017.11.30 18:43:03 4: AC: interval 18:37:03 until 18:43:03, First at , Last at
2017.11.30 18:45:03 4: AC: adding 0 to long count 4898 and interpolated count 4898
2017.11.30 18:45:03 4: AC: Pin 4 (pin4) count 349 longCount 4898 interpCount 4898 (diff 0) in 360.000s, reject 0, Avg Len ms, result 0.000
2017.11.30 18:45:03 4: AC: interval 18:39:03 until 18:45:03, First at , Last at
2017.11.30 18:47:22 3: AC: device: pin 4 changed state to 0 , count 349 (+0)  Rej 1
2017.11.30 18:49:03 4: AC: adding 0 to long count 13112 and interpolated count 13112
2017.11.30 18:49:03 4: AC: Pin 6 (pin6) count 0 longCount 13112 interpCount 13112 (diff 0) in 360.000s, reject 0, Avg Len ms, result 0.000
2017.11.30 18:49:03 4: AC: interval 18:43:03 until 18:49:03, First at , Last at
2017.11.30 18:49:57 3: AC: device: pin 4 changed state to 1 , count 349 (+0)  Rej 2
2017.11.30 18:51:03 4: AC: adding 0 to long count 4898 and interpolated count 4898
2017.11.30 18:51:03 4: AC: Pin 4 (pin4) count 349 longCount 4898 interpCount 4898 (diff 0) in 360.000s, reject 2, Avg Len ms, result 0.000
2017.11.30 18:51:03 4: AC: interval 18:45:03 until 18:51:03, First at , Last at
2017.11.30 18:52:05 3: AC: device: pin 4 changed state to 0 , count 349 (+0)  Rej 1
2017.11.30 18:54:21 3: AC: device: pin 4 changed state to 1 , count 349 (+0)  Rej 2
2017.11.30 18:55:03 4: AC: adding 0 to long count 13112 and interpolated count 13112
2017.11.30 18:55:03 4: AC: Pin 6 (pin6) count 0 longCount 13112 interpCount 13112 (diff 0) in 360.000s, reject 0, Avg Len ms, result 0.000
2017.11.30 18:55:03 4: AC: interval 18:49:03 until 18:55:03, First at , Last at
2017.11.30 18:57:03 4: AC: adding 0 to long count 4898 and interpolated count 4898
2017.11.30 18:57:03 4: AC: Pin 4 (pin4) count 349 longCount 4898 interpCount 4898 (diff 0) in 360.000s, reject 2, Avg Len ms, result 0.000
2017.11.30 18:57:03 4: AC: interval 18:51:03 until 18:57:03, First at , Last at
2017.11.30 18:57:18 3: AC: device: pin 4 changed state to 0 , count 349 (+0)  Rej 0
2017.11.30 19:01:03 4: AC: adding 0 to long count 13112 and interpolated count 13112
2017.11.30 19:01:03 4: AC: Pin 6 (pin6) count 0 longCount 13112 interpCount 13112 (diff 0) in 360.000s, reject 0, Avg Len ms, result 0.000
2017.11.30 19:01:03 4: AC: interval 18:55:03 until 19:01:03, First at , Last at
2017.11.30 19:03:03 4: AC: adding 0 to long count 4898 and interpolated count 4898
2017.11.30 19:03:03 4: AC: Pin 4 (pin4) count 349 longCount 4898 interpCount 4898 (diff 0) in 360.000s, reject 0, Avg Len ms, result 0.000
2017.11.30 19:03:03 4: AC: interval 18:57:03 until 19:03:03, First at , Last at
2017.11.30 19:07:03 4: AC: adding 0 to long count 13112 and interpolated count 13112
2017.11.30 19:07:03 4: AC: Pin 6 (pin6) count 0 longCount 13112 interpCount 13112 (diff 0) in 360.000s, reject 0, Avg Len ms, result 0.000
2017.11.30 19:07:03 4: AC: interval 19:01:03 until 19:07:03, First at , Last at
2017.11.30 19:09:03 4: AC: adding 0 to long count 4898 and interpolated count 4898
2017.11.30 19:09:03 4: AC: Pin 4 (pin4) count 349 longCount 4898 interpCount 4898 (diff 0) in 360.000s, reject 0, Avg Len ms, result 0.000
2017.11.30 19:09:03 4: AC: interval 19:03:03 until 19:09:03, First at , Last at
2017.11.30 19:13:02 4: AC: adding 0 to long count 13112 and interpolated count 13112
2017.11.30 19:13:02 4: AC: Pin 6 (pin6) count 0 longCount 13112 interpCount 13112 (diff 0) in 360.000s, reject 0, Avg Len ms, result 0.000
2017.11.30 19:13:02 4: AC: interval 19:07:02 until 19:13:02, First at , Last at
2017.11.30 19:14:44 3: AC: device: pin 4 changed state to 1 , count 350 (+1)  Rej 0
2017.11.30 19:15:02 4: AC: adding 1 to long count 4898 and interpolated count 4898
2017.11.30 19:15:02 4: AC: Pin 4 (pin4) count 350 longCount 4899 interpCount 4899 (diff 1) in 341.257s, reject 0, Avg Len 1046114ms, result 0.011
2017.11.30 19:15:02 4: AC: interval 19:09:21 until 19:15:02, First at 341257, Last at 341257
2017.11.30 19:17:38 3: AC: device: pin 4 changed state to 0 , count 350 (+0)  Rej 0
2017.11.30 19:19:02 4: AC: adding 0 to long count 13112 and interpolated count 13112
2017.11.30 19:19:02 4: AC: Pin 6 (pin6) count 0 longCount 13112 interpCount 13112 (diff 0) in 360.000s, reject 0, Avg Len ms, result 0.000
2017.11.30 19:19:02 4: AC: interval 19:13:02 until 19:19:02, First at , Last at
2017.11.30 19:19:28 3: AC: device: pin 4 changed state to 1 , count 351 (+1)  Rej 0
2017.11.30 19:20:02 4: AC: adding 1 to long count 4899 and interpolated count 4899
2017.11.30 19:20:02 4: AC: Pin 4 (pin4) count 351 longCount 4900 interpCount 4900 (diff 1) in 284.802s, reject 0, Avg Len 110373ms, result 0.013
2017.11.30 19:20:02 4: AC: interval 19:15:17 until 19:20:02, First at 284802, Last at 284802
2017.11.30 19:21:09 3: AC: device: pin 4 changed state to 0 , count 351 (+0)  Rej 0
2017.11.30 19:22:48 3: AC: device: pin 4 changed state to 1 , count 352 (+1)  Rej 0
2017.11.30 19:23:02 4: AC: adding 1 to long count 4900 and interpolated count 4900
2017.11.30 19:23:02 4: AC: Pin 4 (pin4) count 352 longCount 4901 interpCount 4901 (diff 1) in 199.455s, reject 0, Avg Len 98981ms, result 0.018
2017.11.30 19:23:02 4: AC: interval 19:19:43 until 19:23:02, First at 199455, Last at 199455
2017.11.30 19:25:02 4: AC: adding 0 to long count 13112 and interpolated count 13112
2017.11.30 19:25:02 4: AC: Pin 6 (pin6) count 0 longCount 13112 interpCount 13112 (diff 0) in 360.000s, reject 0, Avg Len ms, result 0.000
2017.11.30 19:25:02 4: AC: interval 19:19:02 until 19:25:02, First at , Last at
2017.11.30 19:25:23 3: AC: device: pin 4 changed state to 0 , count 352 (+0)  Rej 1
2017.11.30 19:28:12 3: AC: device: pin 4 changed state to 1 , count 352 (+0)  Rej 2
2017.11.30 19:29:02 4: AC: adding 0 to long count 4901 and interpolated count 4901
2017.11.30 19:29:02 4: AC: Pin 4 (pin4) count 352 longCount 4901 interpCount 4901 (diff 0) in 374.486s, reject 2, Avg Len ms, result 0.000
2017.11.30 19:29:02 4: AC: interval 19:22:47 until 19:29:02, First at , Last at
2017.11.30 19:31:02 4: AC: adding 0 to long count 13112 and interpolated count 13112
2017.11.30 19:31:02 4: AC: Pin 6 (pin6) count 0 longCount 13112 interpCount 13112 (diff 0) in 360.000s, reject 0, Avg Len ms, result 0.000
2017.11.30 19:31:02 4: AC: interval 19:25:02 until 19:31:02, First at , Last at
2017.11.30 19:35:02 4: AC: adding 0 to long count 4901 and interpolated count 4901
2017.11.30 19:35:02 4: AC: Pin 4 (pin4) count 352 longCount 4901 interpCount 4901 (diff 0) in 360.000s, reject 0, Avg Len ms, result 0.000
2017.11.30 19:35:02 4: AC: interval 19:29:02 until 19:35:02, First at , Last at
2017.11.30 19:37:02 4: AC: adding 0 to long count 13112 and interpolated count 13112
2017.11.30 19:37:02 4: AC: Pin 6 (pin6) count 0 longCount 13112 interpCount 13112 (diff 0) in 360.000s, reject 0, Avg Len ms, result 0.000
2017.11.30 19:37:02 4: AC: interval 19:31:02 until 19:37:02, First at , Last at
2017.11.30 19:40:42 3: AC: device: pin 4 changed state to 0 , count 352 (+0)  Rej 0
2017.11.30 19:41:02 4: AC: adding 0 to long count 4901 and interpolated count 4901
2017.11.30 19:41:02 4: AC: Pin 4 (pin4) count 352 longCount 4901 interpCount 4901 (diff 0) in 360.000s, reject 0, Avg Len ms, result 0.000
2017.11.30 19:41:02 4: AC: interval 19:35:02 until 19:41:02, First at , Last at
2017.11.30 19:43:01 4: AC: adding 0 to long count 13112 and interpolated count 13112
2017.11.30 19:43:01 4: AC: Pin 6 (pin6) count 0 longCount 13112 interpCount 13112 (diff 0) in 360.000s, reject 0, Avg Len ms, result 0.000
2017.11.30 19:43:01 4: AC: interval 19:37:01 until 19:43:01, First at , Last at
2017.11.30 19:47:01 4: AC: adding 0 to long count 4901 and interpolated count 4901
2017.11.30 19:47:01 4: AC: Pin 4 (pin4) count 352 longCount 4901 interpCount 4901 (diff 0) in 360.000s, reject 0, Avg Len ms, result 0.000
2017.11.30 19:47:01 4: AC: interval 19:41:01 until 19:47:01, First at , Last at
2017.11.30 19:48:46 3: AC: device: pin 4 changed state to 1 , count 353 (+1)  Rej 0
2017.11.30 19:49:01 4: AC: adding 1 to long count 4901 and interpolated count 4901
2017.11.30 19:49:01 4: AC: Pin 4 (pin4) count 353 longCount 4902 interpCount 4902 (diff 1) in 104.928s, reject 0, Avg Len 484137ms, result 0.034
2017.11.30 19:49:01 4: AC: interval 19:47:16 until 19:49:01, First at 104928, Last at 104928
2017.11.30 19:49:01 4: AC: adding 0 to long count 13112 and interpolated count 13112
2017.11.30 19:49:01 4: AC: Pin 6 (pin6) count 0 longCount 13112 interpCount 13112 (diff 0) in 360.000s, reject 0, Avg Len ms, result 0.000
2017.11.30 19:49:01 4: AC: interval 19:43:01 until 19:49:01, First at , Last at
2017.11.30 19:51:42 3: AC: device: pin 4 changed state to 0 , count 353 (+0)  Rej 0
2017.11.30 19:54:27 3: AC: device: pin 4 changed state to 1 , count 354 (+1)  Rej 0
2017.11.30 19:55:01 4: AC: adding 1 to long count 4902 and interpolated count 4902
2017.11.30 19:55:01 4: AC: Pin 4 (pin4) count 354 longCount 4903 interpCount 4903 (diff 1) in 341.340s, reject 0, Avg Len 165051ms, result 0.011
2017.11.30 19:55:01 4: AC: interval 19:49:20 until 19:55:01, First at 341340, Last at 341340
2017.11.30 19:55:01 4: AC: adding 0 to long count 13112 and interpolated count 13112
2017.11.30 19:55:01 4: AC: Pin 6 (pin6) count 0 longCount 13112 interpCount 13112 (diff 0) in 360.000s, reject 0, Avg Len ms, result 0.000
2017.11.30 19:55:01 4: AC: interval 19:49:01 until 19:55:01, First at , Last at
2017.11.30 19:57:32 3: AC: device: pin 4 changed state to 0 , count 354 (+0)  Rej 1
2017.11.30 20:00:14 3: AC: device: pin 4 changed state to 1 , count 354 (+0)  Rej 2
2017.11.30 20:01:01 4: AC: adding 0 to long count 4903 and interpolated count 4903
2017.11.30 20:01:01 4: AC: Pin 4 (pin4) count 354 longCount 4903 interpCount 4903 (diff 0) in 393.732s, reject 2, Avg Len ms, result 0.000
2017.11.30 20:01:01 4: AC: interval 19:54:27 until 20:01:01, First at , Last at
2017.11.30 20:01:01 4: AC: adding 0 to long count 13112 and interpolated count 13112
2017.11.30 20:01:01 4: AC: Pin 6 (pin6) count 0 longCount 13112 interpCount 13112 (diff 0) in 360.000s, reject 0, Avg Len ms, result 0.000
2017.11.30 20:01:01 4: AC: interval 19:55:01 until 20:01:01, First at , Last at
2017.11.30 20:03:13 3: AC: device: pin 4 changed state to 0 , count 354 (+0)  Rej 0
2017.11.30 20:07:01 4: AC: adding 0 to long count 4903 and interpolated count 4903
2017.11.30 20:07:01 4: AC: Pin 4 (pin4) count 354 longCount 4903 interpCount 4903 (diff 0) in 360.000s, reject 0, Avg Len ms, result 0.000
2017.11.30 20:07:01 4: AC: interval 20:01:01 until 20:07:01, First at , Last at
2017.11.30 20:07:01 4: AC: adding 0 to long count 13112 and interpolated count 13112
2017.11.30 20:07:01 4: AC: Pin 6 (pin6) count 0 longCount 13112 interpCount 13112 (diff 0) in 360.000s, reject 0, Avg Len ms, result 0.000
2017.11.30 20:07:01 4: AC: interval 20:01:01 until 20:07:01, First at , Last at
2017.11.30 20:13:00 4: AC: adding 0 to long count 4903 and interpolated count 4903
2017.11.30 20:13:00 4: AC: Pin 4 (pin4) count 354 longCount 4903 interpCount 4903 (diff 0) in 360.000s, reject 0, Avg Len ms, result 0.000
2017.11.30 20:13:00 4: AC: interval 20:07:00 until 20:13:00, First at , Last at
2017.11.30 20:13:00 4: AC: adding 0 to long count 13112 and interpolated count 13112
2017.11.30 20:13:00 4: AC: Pin 6 (pin6) count 0 longCount 13112 interpCount 13112 (diff 0) in 360.000s, reject 0, Avg Len ms, result 0.000
2017.11.30 20:13:00 4: AC: interval 20:07:00 until 20:13:00, First at , Last at
2017.11.30 20:19:00 4: AC: adding 0 to long count 4903 and interpolated count 4903
2017.11.30 20:19:00 4: AC: Pin 4 (pin4) count 354 longCount 4903 interpCount 4903 (diff 0) in 360.000s, reject 0, Avg Len ms, result 0.000
2017.11.30 20:19:00 4: AC: interval 20:13:00 until 20:19:00, First at , Last at
2017.11.30 20:19:00 4: AC: adding 0 to long count 13112 and interpolated count 13112
2017.11.30 20:19:00 4: AC: Pin 6 (pin6) count 0 longCount 13112 interpCount 13112 (diff 0) in 360.000s, reject 0, Avg Len ms, result 0.000
2017.11.30 20:19:00 4: AC: interval 20:13:00 until 20:19:00, First at , Last at
2017.11.30 20:20:49 3: AC: device: pin 4 changed state to 1 , count 355 (+1)  Rej 0
2017.11.30 20:21:00 4: AC: adding 1 to long count 4903 and interpolated count 4903
2017.11.30 20:21:00 4: AC: Pin 4 (pin4) count 355 longCount 4904 interpCount 4904 (diff 1) in 109.240s, reject 0, Avg Len 1056614ms, result 0.033
2017.11.30 20:21:00 4: AC: interval 20:19:11 until 20:21:00, First at 109240, Last at 109240
2017.11.30 20:23:42 3: AC: device: pin 4 changed state to 0 , count 355 (+0)  Rej 0
2017.11.30 20:25:00 4: AC: adding 0 to long count 13112 and interpolated count 13112
2017.11.30 20:25:00 4: AC: Pin 6 (pin6) count 0 longCount 13112 interpCount 13112 (diff 0) in 360.000s, reject 0, Avg Len ms, result 0.000
2017.11.30 20:25:00 4: AC: interval 20:19:00 until 20:25:00, First at , Last at
2017.11.30 20:26:29 3: AC: device: pin 4 changed state to 1 , count 356 (+1)  Rej 0
2017.11.30 20:27:00 4: AC: adding 1 to long count 4904 and interpolated count 4904
2017.11.30 20:27:00 4: AC: Pin 4 (pin4) count 356 longCount 4905 interpCount 4905 (diff 1) in 339.949s, reject 0, Avg Len 167296ms, result 0.011
2017.11.30 20:27:00 4: AC: interval 20:21:20 until 20:27:00, First at 339949, Last at 339949
2017.11.30 20:29:29 3: AC: device: pin 4 changed state to 0 , count 356 (+0)  Rej 1
2017.11.30 20:31:00 4: AC: adding 0 to long count 13112 and interpolated count 13112
2017.11.30 20:31:00 4: AC: Pin 6 (pin6) count 0 longCount 13112 interpCount 13112 (diff 0) in 360.000s, reject 0, Avg Len ms, result 0.000
2017.11.30 20:31:00 4: AC: interval 20:25:00 until 20:31:00, First at , Last at
2017.11.30 20:33:00 4: AC: adding 0 to long count 4905 and interpolated count 4905
2017.11.30 20:33:00 4: AC: Pin 4 (pin4) count 356 longCount 4905 interpCount 4905 (diff 0) in 390.811s, reject 1, Avg Len ms, result 0.000
2017.11.30 20:33:00 4: AC: interval 20:26:29 until 20:33:00, First at , Last at
2017.11.30 20:37:00 4: AC: adding 0 to long count 13112 and interpolated count 13112
2017.11.30 20:37:00 4: AC: Pin 6 (pin6) count 0 longCount 13112 interpCount 13112 (diff 0) in 360.000s, reject 0, Avg Len ms, result 0.000
2017.11.30 20:37:00 4: AC: interval 20:31:00 until 20:37:00, First at , Last at
2017.11.30 20:39:00 4: AC: adding 0 to long count 4905 and interpolated count 4905
2017.11.30 20:39:00 4: AC: Pin 4 (pin4) count 356 longCount 4905 interpCount 4905 (diff 0) in 360.000s, reject 0, Avg Len ms, result 0.000
2017.11.30 20:39:00 4: AC: interval 20:33:00 until 20:39:00, First at , Last at
2017.11.30 20:42:59 4: AC: adding 0 to long count 13112 and interpolated count 13112
2017.11.30 20:42:59 4: AC: Pin 6 (pin6) count 0 longCount 13112 interpCount 13112 (diff 0) in 360.000s, reject 0, Avg Len ms, result 0.000
2017.11.30 20:42:59 4: AC: interval 20:36:59 until 20:42:59, First at , Last at
2017.11.30 20:44:59 4: AC: adding 0 to long count 4905 and interpolated count 4905
2017.11.30 20:44:59 4: AC: Pin 4 (pin4) count 356 longCount 4905 interpCount 4905 (diff 0) in 360.000s, reject 0, Avg Len ms, result 0.000
2017.11.30 20:44:59 4: AC: interval 20:38:59 until 20:44:59, First at , Last at
2017.11.30 20:46:49 3: AC: device: pin 4 changed state to 1 , count 356 (+0)  Rej 1
2017.11.30 20:48:59 4: AC: adding 0 to long count 13112 and interpolated count 13112
2017.11.30 20:48:59 4: AC: Pin 6 (pin6) count 0 longCount 13112 interpCount 13112 (diff 0) in 360.000s, reject 0, Avg Len ms, result 0.000
2017.11.30 20:48:59 4: AC: interval 20:42:59 until 20:48:59, First at , Last at
2017.11.30 20:49:45 3: AC: device: pin 4 changed state to 0 , count 356 (+0)  Rej 1
2017.11.30 20:50:59 4: AC: adding 0 to long count 4905 and interpolated count 4905
2017.11.30 20:50:59 4: AC: Pin 4 (pin4) count 356 longCount 4905 interpCount 4905 (diff 0) in 360.000s, reject 1, Avg Len ms, result 0.000
2017.11.30 20:50:59 4: AC: interval 20:44:59 until 20:50:59, First at , Last at
2017.11.30 20:52:14 3: AC: device: pin 4 changed state to 1 , count 357 (+1)  Rej 0
2017.11.30 20:52:59 4: AC: adding 1 to long count 4905 and interpolated count 4905
2017.11.30 20:52:59 4: AC: Pin 4 (pin4) count 357 longCount 4906 interpCount 4906 (diff 1) in 74.544s, reject 0, Avg Len 149033ms, result 0.048
2017.11.30 20:52:59 4: AC: interval 20:51:45 until 20:52:59, First at 74544, Last at 74544
2017.11.30 20:54:27 3: AC: device: pin 4 changed state to 0 , count 357 (+0)  Rej 1
2017.11.30 20:54:59 4: AC: adding 0 to long count 13112 and interpolated count 13112
2017.11.30 20:54:59 4: AC: Pin 6 (pin6) count 0 longCount 13112 interpCount 13112 (diff 0) in 360.000s, reject 0, Avg Len ms, result 0.000
2017.11.30 20:54:59 4: AC: interval 20:48:59 until 20:54:59, First at , Last at
2017.11.30 20:56:59 3: AC: device: pin 4 changed state to 1 , count 357 (+0)  Rej 2
2017.11.30 20:58:59 4: AC: adding 0 to long count 4906 and interpolated count 4906
2017.11.30 20:58:59 4: AC: Pin 4 (pin4) count 357 longCount 4906 interpCount 4906 (diff 0) in 405.456s, reject 2, Avg Len ms, result 0.000
2017.11.30 20:58:59 4: AC: interval 20:52:13 until 20:58:59, First at , Last at
2017.11.30 20:59:54 3: AC: device: pin 4 changed state to 0 , count 357 (+0)  Rej 1
2017.11.30 21:00:59 4: AC: adding 0 to long count 13112 and interpolated count 13112
2017.11.30 21:00:59 4: AC: Pin 6 (pin6) count 0 longCount 13112 interpCount 13112 (diff 0) in 360.000s, reject 0, Avg Len ms, result 0.000
2017.11.30 21:00:59 4: AC: interval 20:54:59 until 21:00:59, First at , Last at
2017.11.30 21:02:43 3: AC: device: pin 4 changed state to 1 , count 357 (+0)  Rej 2
2017.11.30 21:04:59 4: AC: adding 0 to long count 4906 and interpolated count 4906
2017.11.30 21:04:59 4: AC: Pin 4 (pin4) count 357 longCount 4906 interpCount 4906 (diff 0) in 360.000s, reject 2, Avg Len ms, result 0.000
2017.11.30 21:04:59 4: AC: interval 20:58:59 until 21:04:59, First at , Last at
2017.11.30 21:06:59 4: AC: adding 0 to long count 13112 and interpolated count 13112
2017.11.30 21:06:59 4: AC: Pin 6 (pin6) count 0 longCount 13112 interpCount 13112 (diff 0) in 360.000s, reject 0, Avg Len ms, result 0.000
2017.11.30 21:06:59 4: AC: interval 21:00:59 until 21:06:59, First at , Last at
2017.11.30 21:10:59 4: AC: adding 0 to long count 4906 and interpolated count 4906
2017.11.30 21:10:59 4: AC: Pin 4 (pin4) count 357 longCount 4906 interpCount 4906 (diff 0) in 360.000s, reject 0, Avg Len ms, result 0.000
2017.11.30 21:10:59 4: AC: interval 21:04:59 until 21:10:59, First at , Last at
2017.11.30 21:12:58 4: AC: adding 0 to long count 13112 and interpolated count 13112
2017.11.30 21:12:58 4: AC: Pin 6 (pin6) count 0 longCount 13112 interpCount 13112 (diff 0) in 360.000s, reject 0, Avg Len ms, result 0.000
2017.11.30 21:12:58 4: AC: interval 21:06:58 until 21:12:58, First at , Last at
2017.11.30 21:16:58 4: AC: adding 0 to long count 4906 and interpolated count 4906
2017.11.30 21:16:58 4: AC: Pin 4 (pin4) count 357 longCount 4906 interpCount 4906 (diff 0) in 360.000s, reject 0, Avg Len ms, result 0.000
2017.11.30 21:16:58 4: AC: interval 21:10:58 until 21:16:58, First at , Last at
2017.11.30 21:18:58 4: AC: adding 0 to long count 13112 and interpolated count 13112
2017.11.30 21:18:58 4: AC: Pin 6 (pin6) count 0 longCount 13112 interpCount 13112 (diff 0) in 360.000s, reject 0, Avg Len ms, result 0.000
2017.11.30 21:18:58 4: AC: interval 21:12:58 until 21:18:58, First at , Last at
2017.11.30 21:19:52 3: AC: device: pin 4 changed state to 0 , count 357 (+0)  Rej 0
2017.11.30 21:22:38 3: AC: device: pin 4 changed state to 1 , count 358 (+1)  Rej 0
2017.11.30 21:22:58 4: AC: adding 1 to long count 4906 and interpolated count 4906
2017.11.30 21:22:58 4: AC: Pin 4 (pin4) count 358 longCount 4907 interpCount 4907 (diff 1) in 339.616s, reject 0, Avg Len 165550ms, result 0.011
2017.11.30 21:22:58 4: AC: interval 21:17:19 until 21:22:58, First at 339616, Last at 339616
2017.11.30 21:24:58 4: AC: adding 0 to long count 13112 and interpolated count 13112
2017.11.30 21:24:58 4: AC: Pin 6 (pin6) count 0 longCount 13112 interpCount 13112 (diff 0) in 360.000s, reject 0, Avg Len ms, result 0.000
2017.11.30 21:24:58 4: AC: interval 21:18:58 until 21:24:58, First at , Last at
2017.11.30 21:25:34 3: AC: device: pin 4 changed state to 0 , count 358 (+0)  Rej 0
2017.11.30 21:28:24 3: AC: device: pin 4 changed state to 1 , count 359 (+1)  Rej 0
2017.11.30 21:28:58 4: AC: adding 1 to long count 4907 and interpolated count 4907
2017.11.30 21:28:58 4: AC: Pin 4 (pin4) count 359 longCount 4908 interpCount 4908 (diff 1) in 346.147s, reject 0, Avg Len 169740ms, result 0.010
2017.11.30 21:28:58 4: AC: interval 21:23:12 until 21:28:58, First at 346147, Last at 346147
2017.11.30 21:30:58 4: AC: adding 0 to long count 13112 and interpolated count 13112
2017.11.30 21:30:58 4: AC: Pin 6 (pin6) count 0 longCount 13112 interpCount 13112 (diff 0) in 360.000s, reject 0, Avg Len ms, result 0.000
2017.11.30 21:30:58 4: AC: interval 21:24:58 until 21:30:58, First at , Last at
2017.11.30 21:34:58 4: AC: adding 0 to long count 4908 and interpolated count 4908
2017.11.30 21:34:58 4: AC: Pin 4 (pin4) count 359 longCount 4908 interpCount 4908 (diff 0) in 394.237s, reject 0, Avg Len ms, result 0.000
2017.11.30 21:34:58 4: AC: interval 21:28:24 until 21:34:58, First at , Last at
2017.11.30 21:36:58 4: AC: adding 0 to long count 13112 and interpolated count 13112
2017.11.30 21:36:58 4: AC: Pin 6 (pin6) count 0 longCount 13112 interpCount 13112 (diff 0) in 360.000s, reject 0, Avg Len ms, result 0.000
2017.11.30 21:36:58 4: AC: interval 21:30:58 until 21:36:58, First at , Last at
2017.11.30 21:40:58 4: AC: adding 0 to long count 4908 and interpolated count 4908
2017.11.30 21:40:58 4: AC: Pin 4 (pin4) count 359 longCount 4908 interpCount 4908 (diff 0) in 360.000s, reject 0, Avg Len ms, result 0.000
2017.11.30 21:40:58 4: AC: interval 21:34:58 until 21:40:58, First at , Last at
2017.11.30 21:42:57 4: AC: adding 0 to long count 13112 and interpolated count 13112
2017.11.30 21:42:57 4: AC: Pin 6 (pin6) count 0 longCount 13112 interpCount 13112 (diff 0) in 360.000s, reject 0, Avg Len ms, result 0.000
2017.11.30 21:42:57 4: AC: interval 21:36:57 until 21:42:57, First at , Last at


List

Internals:
   DEF        /dev/ttyUSB0@38400
   DeviceName /dev/ttyUSB0@38400
   FD         4
   Initialized 1
   NAME       AC
   NOTIFYDEV  global
   NR         21
   NTFY_ORDER 50-AC
   PARTIAL
   STATE      opened
   TYPE       ArduCounter
   VersionFirmware 1.91
   VersionModule 5.3 - 26.11.2017
   buffer
   CounterInterpolated:
   READINGS:
     2017-11-30 21:34:58   OelVorrat       963.2
     2017-11-30 21:34:58   OelZaehler      490.8
     2017-11-30 21:34:58   OelZaehlerI     490.8
     2017-11-30 21:34:58   countDiff4      0
     2017-11-30 21:30:58   countDiff6      0
     2017-11-30 21:34:58   interpolatedLong4 4908
     2017-11-30 21:30:58   interpolatedLong6 13112
     2017-11-30 21:34:58   lastMsg4        R4 C359 D0 R0 T394237 N370686695 X0

     2017-11-30 21:30:58   lastMsg6        R6 C0 D0 R0 T360000 N370446695 X0

     2017-11-30 21:34:58   long4           4908
     2017-11-30 21:30:58   long6           13112
     2017-11-30 21:34:58   pin4            359
     2017-11-30 21:30:58   pin6            0
     2017-11-30 21:34:58   power4          0.000
     2017-11-30 21:30:58   power6          0.000
     2017-11-30 21:34:58   reject4         0
     2017-11-30 21:30:58   reject6         0
     2017-11-28 08:45:23   state           opened
     2017-11-30 21:34:58   timeDiff4       394237
     2017-11-30 21:30:58   timeDiff6       360000
Attributes:
   flashCommand avrdude -p atmega328P -c arduino -P [PORT] -D -U flash:w:[HEXFILE] 2>[LOGFILE]
   interval   60 360
   pin4       falling pullup 2000
   pin6       falling pullup 90
   readingFactor6 10000
   userReadings OelZaehler {ReadingsVal("AC","long4",0)/10},OelZaehlerI {ReadingsVal("AC","interpolatedLong4",0)/10},OelVorrat {1454-ReadingsVal("AC","OelZaehlerI",0)}
   userattr   pin4 pin5 pin6 readingFactor6 verboseReadings4 verboseReadings6
   verbose    4
   verboseReadings4 1
   verboseReadings6 1
FHEM  @Debian bullseye @Proxmox VE 8.1.3
@intelNUC's  (i5)
CUL 433(a-culfw), CUL 868(SlowRF), Max-Cube CUN geflash, HM-CFG-USB-2 (HMALND)

GlennDandy

#231
Zitat von: StefanStrobel am 30 November 2017, 17:39:10
Hallo,

schau doch mal das an:
https://fhem.de/commandref_DE.html#statistics

Gruss
    Stefan

Genau das was ich gesucht habe. Danke für die Info.
Hab am Impuls Zähler den Wert mit den vom Arducounter Verglichen von 1Tag.  Konnte keine Abweichung feststellen.

Hab ich etwas übersehen oder gibt's die Möglichkeit nen Start Count zusetzten?

edit: Habs gelesen, userReadings ist das Sprichwort mit dem Monotonic Befehl, damits den Zähler nicht zurück setzt.

StefanStrobel

Hallo GlennDandy,

user readings und monotonic brauchst Du nicht wenn Du für das jeweilige Pin verboseReadingsX (X=Pin-Nummer) setzt.
Den Start-Wert des longCounters kannst Du dann mit setreading setzen.

Gruss
   Stefan

StefanStrobel

#233
Hallo birdy,

vielen Dank für das ausführliche Testen. Ich denke wir kommen der Sache näher!


2017.11.30 18:35:17 3: AC: device: pin 4 changed state to 1 , count 349 (+0)  Rej 1

zeigt dass gerade auch ein Impuls rejected wurde.
Das scheint bei Dir oft der Fall zu sein. Die Frage ist nun warum die Impulse als rejected gewertet werden.
Nach Deiner Definition müsste dafür der Impuls oder die Pause vor dem Impuls kleiner als 2 Sekunden sein.
Setz doch mal den Minimalwert auf 100 statt 2000. Das sollte ausreichen um ein Prellen auszufiltern.

Anbei eine neue Version, bei der die zurückgewiesenen Pulse auch summiert werden. Das Modul hat das bisher nicht gemacht (Bug).
Zudem habe ich im Sketch eine zusätzliche Debug-Ausgabe eingebaut, die die letzten 4 Impulslängen und die Pausen dazwischen ausgibt.
Ich hoffe, damit finden wir den Fehler bald.

Ergänzung: der angehängte Sketch hat immer noch das spezielle Debugging aktiviert und ist deshalb für den Normalgebrauch nicht geeignet.

Gruss
   Stefan


birdy

Hallo Stefan

Bin wirklich froh um Deine Unterstützung. Ein hervorragender Service. Vielen vielen Dank.
Ich habe die neuen Versionen installiert. Jetzt zählt der Reject Zähler.

Nachfolgend ein Auszug aus dem Log.
Falls ich mit weiteren Test oder Messungen weiter helfen kann, bitte melden.

Internals:
   DEF        /dev/ttyUSB0@38400
   DeviceName /dev/ttyUSB0@38400
   FD         4
   Initialized 1
   NAME       AC
   NOTIFYDEV  global
   NR         21
   NTFY_ORDER 50-AC
   PARTIAL
   STATE      opened
   TYPE       ArduCounter
   VersionFirmware 1.92
   VersionModule 5.4 - 02.12.2017
   buffer
   CounterInterpolated:
   READINGS:
     2017-12-03 01:08:02   OelVorrat       938.5
     2017-12-03 01:08:02   OelZaehler      515.5
     2017-12-03 01:08:02   OelZaehlerI     515.5
     2017-12-03 01:04:03   countDiff4      1
     2017-12-03 01:08:02   countDiff6      0
     2017-12-03 01:04:03   interpolatedLong4 5155
     2017-12-03 01:08:02   interpolatedLong6 13112
     2017-12-03 01:04:03   lastMsg4        R4 C9 D1 R1 T95078 N10924502 X0 F95078 L95078 A166299

     2017-12-03 01:08:02   lastMsg6        R6 C0 D0 R0 T360000 N11164502 X0

     2017-12-03 01:04:03   long4           5155
     2017-12-03 01:08:02   long6           13112
     2017-12-03 01:04:03   pin4            9
     2017-12-03 01:08:02   pin6            0
     2017-12-03 01:04:03   power4          0.038
     2017-12-03 01:08:02   power6          0.000
     2017-12-03 01:04:03   reject4         2
     2017-12-03 01:08:02   reject6         0
     2017-12-02 22:02:06   state           opened
     2017-12-03 01:04:03   timeDiff4       95078
     2017-12-03 01:08:02   timeDiff6       360000
Attributes:
   flashCommand avrdude -p atmega328P -c arduino -P [PORT] -D -U flash:w:[HEXFILE] 2>[LOGFILE]
   interval   60 360
   pin4       falling pullup 100
   pin6       falling pullup 90
   readingFactor6 10000
   userReadings OelZaehler {ReadingsVal("AC","long4",0)/10},OelZaehlerI {ReadingsVal("AC","interpolatedLong4",0)/10},OelVorrat {1454-ReadingsVal("AC","OelZaehlerI",0)}
   userattr   pin4 pin5 pin6 readingFactor6 verboseReadings4 verboseReadings6
   verbose    4
   verboseReadings4 1
   verboseReadings6 1


2017.12.02 23:20:06 4: AC: Pin 6 debug: adding 0 to long count 13112 and interpolated count 13112
2017.12.02 23:20:06 4: AC: Pin 6 (pin6) count 0 longCount 13112 interpCount 13112 (diff 0) in 360.000s, reject 0, Avg Len ms, result 0.000
2017.12.02 23:20:06 4: AC: interval 23:14:06 until 23:20:06, First at , Last at
2017.12.02 23:22:06 4: AC: Pin 4 debug: adding 0 to long count 5148 and interpolated count 5148
2017.12.02 23:22:06 4: AC: Pin 4 (pin4) count 2 longCount 5148 interpCount 5148 (diff 0) in 360.000s, reject 0, Avg Len ms, result 0.000
2017.12.02 23:22:06 4: AC: interval 23:16:06 until 23:22:06, First at , Last at
2017.12.02 23:22:40 3: AC: device: pin 4 changed state to 0 , count 2 (+0)  Rej 0   pulse history (ms): 0 pulse, 0 gap,  168738 pulse, 171265 gap,  208399 pulse, 2790606 gap,  0 pulse, 0 gap
2017.12.02 23:25:53 3: AC: device: pin 4 changed state to 1 , count 3 (+1)  Rej 1   pulse history (ms): 0 pulse, 0 gap,  192169 pulse, 1499503 gap,  0 pulse, 0 gap,  168738 pulse, 171265 gap
2017.12.02 23:26:06 4: AC: Pin 4 debug: adding 1 to long count 5148 and interpolated count 5148
2017.12.02 23:26:06 4: AC: Pin 4 (pin4) count 3 longCount 5149 interpCount 5149 (diff 1) in 226.662s, reject 1, Avg Len 192169ms, result 0.016
2017.12.02 23:26:06 4: AC: interval 23:22:19 until 23:26:06, First at 226662, Last at 226662
2017.12.02 23:26:06 4: AC: Pin 6 debug: adding 0 to long count 13112 and interpolated count 13112
2017.12.02 23:26:06 4: AC: Pin 6 (pin6) count 0 longCount 13112 interpCount 13112 (diff 0) in 360.000s, reject 0, Avg Len ms, result 0.000
2017.12.02 23:26:06 4: AC: interval 23:20:06 until 23:26:06, First at , Last at
2017.12.02 23:28:50 3: AC: device: pin 4 changed state to 0 , count 3 (+0)  Rej 0   pulse history (ms): 0 pulse, 0 gap,  192169 pulse, 1499503 gap,  0 pulse, 0 gap,  168738 pulse, 171265 gap


Gruss birdy
FHEM  @Debian bullseye @Proxmox VE 8.1.3
@intelNUC's  (i5)
CUL 433(a-culfw), CUL 868(SlowRF), Max-Cube CUN geflash, HM-CFG-USB-2 (HMALND)

StefanStrobel

Hallo birdy,

in dem Log-Ausschnitt scheinen keine Impulse verloren zu gehen.
Dein Signal prellt zwar, aber im angehängten Log-Auszug wird der Puls dennoch gezählt.


2017.12.02 23:22:40 3: AC: device: pin 4 changed state to 0 , count 2 (+0)  Rej 0   pulse history (ms): 0 pulse, 0 gap,  168738 pulse, 171265 gap,  208399 pulse, 2790606 gap,  0 pulse, 0 gap
2017.12.02 23:25:53 3: AC: device: pin 4 changed state to 1 , count 3 (+1)  Rej 1   pulse history (ms): 0 pulse, 0 gap,  192169 pulse, 1499503 gap,  0 pulse, 0 gap,  168738 pulse, 171265 gap
2017.12.02 23:26:06 4: AC: Pin 4 debug: adding 1 to long count 5148 and interpolated count 5148
2017.12.02 23:26:06 4: AC: Pin 4 (pin4) count 3 longCount 5149 interpCount 5149 (diff 1) in 226.662s, reject 1, Avg Len 192169ms, result 0.016


um 23:25 geht das Signal auf 1. Es war nach einer Pause von 1499503 ms (24 Minuten) auf 0 gegangen, ist dann 192169 ms (3 Minuten) auf 0 geblieben und dann wieder auf 1 gegangen. Dabei ist es einmal sehr schnell auf 0 und wieder 1 gesprungen (0 pulse, 0 gap). Das hat Interrupts und einen "reject" erzeugt, war aber zu schnell um in der Hauptschleife einen Log-Eintrag zu erzeugen.
Der Zähler ist folglich um 1 erhöht worden (count 3 (+1)).

Schau mal ob Du im Log Einträge findest, bei denen ein langsamer Impuls sichtbar ist (device: pin 4 changed state to 1) aber ohne dass der Zähler erhöht wird.
Dann hoffe ich, dass die puls history uns erklären kann, was da passiert ist ...

Gruss
   Stefan


birdy

Hallo Stefan

Ich habe soeben (13:00) die Zähler gecheckt. Momentan stimmt der Durchflussmesser und der ArduConter noch überein. Gleichstand keine Abweichung über die Nacht.  :)
Und dies obwohl seit der letzten Synchronisation ca. 50 Impulse gezählt wurden.  Reiner Zufall?

Mach wohl so keinen Sinn das Logs genauer zu untersuchen?

Ich behalte das Ganze im Auge.
Könnte es sein, dass Deine letzte Änderung (Fix Reject Zähler) hier eine Verbesserung bewirkt hat?

Gruss bridy
FHEM  @Debian bullseye @Proxmox VE 8.1.3
@intelNUC's  (i5)
CUL 433(a-culfw), CUL 868(SlowRF), Max-Cube CUN geflash, HM-CFG-USB-2 (HMALND)

StefanStrobel

Hallo birdy,

meine letzten Änderungen können eigentlich keine Auswirkungen haben.
Eventuell hat aber die Einstellung der minimalen Pulsdauer einen Einfluss.
Du könntest die zum Testen wieder auf 2000 setzen.
Wenn es sonst keine Abweichungen gibt, hilft das eventuell bei der Fehlersuche.
Oder Deine Ölpumpe hat zwischendrin "Spurts", bei denen die Impulse tatsächlich kürzer als 2 Sekunden waren ...

Gruss
   Stefan

StefanStrobel

Hallo birdy,

gibt es etwas Neues von Deinem Ölzähler?
Hast Du noch Abweichungen oder stimmen die Zähler inzwischen überein?

Gruss
   Stefan

birdy

Hallo Stefan

Der AdruConter ist nun knapp 2 Wochen mit einer Pulslängen-Kontrolle von 100 gelaufen. Dabei hat alles ausgezeichnet funktioniert. Über die gesamte Zeit keine Abweichung, 100% Übereinstimmung. Perfekt. Die letzten zwei Tage hab ich noch mit 2000 getestet. Auch hier keine Abweichungen.  :)
Auch wenn Du anderer Meinung bist, der letzte Change hat den entscheidenden Unterschied gebracht, zumindest bei mir.
Die von Dir angenommen ,,Spurts" der Ölpumpe sind ausgeschlossen, das würde ja bedeuten, das 0.1 Liter in 2 Sekunden durchlaufen. Unmöglich!

Jetzt könnte die spezielle Debug - Firmwareversion wieder mit einer regulären ersetzt werden.


Internals:
   DEF        /dev/ttyUSB0@38400
   DeviceName /dev/ttyUSB0@38400
   FD         4
   Initialized 1
   NAME       AC
   NOTIFYDEV  global
   NR         21
   NTFY_ORDER 50-AC
   PARTIAL
   STATE      opened
   TYPE       ArduCounter
   VersionFirmware 1.92
   VersionModule 5.4 - 02.12.2017
   buffer
   CounterInterpolated:
   READINGS:
     2017-12-14 23:52:14   OelVorrat       808.2
     2017-12-14 23:52:14   OelZaehler      645.8
     2017-12-14 23:52:14   OelZaehlerI     645.8
     2017-12-14 23:52:14   countDiff4      0
     2017-12-14 23:52:14   countDiff6      0
     2017-12-14 23:52:14   interpolatedLong4 6458
     2017-12-14 23:52:14   interpolatedLong6 13112
     2017-12-14 23:52:14   lastMsg4        R4 C1311 D0 R0 T360000 N1044004502 X0

     2017-12-14 23:52:14   lastMsg6        R6 C0 D0 R0 T360000 N1044004502 X0

     2017-12-14 23:52:14   long4           6458
     2017-12-14 23:52:14   long6           13112
     2017-12-14 23:52:14   pin4            1311
     2017-12-14 23:52:14   pin6            0
     2017-12-14 23:52:14   power4          0.000
     2017-12-14 23:52:14   power6          0.000
     2017-12-14 23:52:14   reject4         47
     2017-12-14 23:52:14   reject6         0
     2017-12-09 12:58:05   state           opened
     2017-12-14 23:52:14   timeDiff4       360000
     2017-12-14 23:52:14   timeDiff6       360000
Attributes:
   flashCommand avrdude -p atmega328P -c arduino -P [PORT] -D -U flash:w:[HEXFILE] 2>[LOGFILE]
   interval   60 360
   pin4       falling pullup 2000
   pin6       falling pullup 90
   readingFactor6 10000
   userReadings OelZaehler {ReadingsVal("AC","long4",0)/10},OelZaehlerI {ReadingsVal("AC","interpolatedLong4",0)/10},OelVorrat {1454-ReadingsVal("AC","OelZaehlerI",0)}
   userattr   pin4 pin5 pin6 readingFactor6 verboseReadings4 verboseReadings6
   verbose    4
   verboseReadings4 1
   verboseReadings6 1


Gruss birdy
FHEM  @Debian bullseye @Proxmox VE 8.1.3
@intelNUC's  (i5)
CUL 433(a-culfw), CUL 868(SlowRF), Max-Cube CUN geflash, HM-CFG-USB-2 (HMALND)