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

StefanStrobel

hallo,

anbei das ganze wieder ohne Debug-Code.
Eigentlich ist es nur ein #define slowDebug, der geändert werden muss.

Gruss
   Stefan

birdy

Hallo Stefan

Immer noch Gleichstand zwischen dem mechanischen Zähler und dem ArduConter. Also alles bestens, auch mit einer Pulslängen-Kontrolle von 2000 ;)
Jetzt habe ich soeben die neue Firmware (1.93) aufgespielt.
Vielen Dank für bereitstellen.

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)

hermann258

Hallo
Ich habe alles auf den neuesten Stand gebracht ,leider steht jetzt nur noch opened in Fhem.
Wie kann ich das beheben
Gruß Hermann

birdy

Hallo Stefan

Es ist wie verhext. Seit Sonntag habe ich die Firmwaren Version von Post #240 (1.93) im Einsatz. Seit diesem Zeitpunkt treten bei mir wieder täglich massive Abweichungen auf. Etwa 25%-35% der Impulse werden nicht gezählt.  :(

Ich denke ich werde versuchen die Firmware nochmals aufzuspielen. Sonst hat ja nichts geändert gegenüber der letzten Woche.
Hast Du evtl. noch eine Idee was das Problem sein könnte?

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)

Nighthawk

Hallo zusammen,

auch ich kann bestätigen, dass seit dem Flashen der 1.93 nur ein Bruchteil gezählt wird.
Ich habe eben die 1.90 wieder geflasht und siehe da, es wird alles gezählt.

StefanStrobel

Hallo,

das ist wirklich seltsam.
Alles was ich seit Sketch Version 1.9(0) eingebaut habe, waren Debug-Features um herauszufinden, warum in der 1.90er bei birdy Impulse nicht gezählt werden.
Das Zwischenergebnis war, dass die fehlenden Impulse, die ich in birdys Log sehen konnte, als rejected gezählt wurden. Das ist ein Feature der Impulslängenkontrolle, die zu kurze Impulse bzw. Störungen ignoriert. Ob die fehlenden Impulse zu recht ignoriert wurden oder nicht, kann ich natürlich nicht sagen.

Bei den langsamen Impulsen von birdys Ölpumpe wäre ein Vergleich mit dem Protokoll eines DMM ideal zur Fehlersuche, nur leider sind seit der erweiterten Debug-Ausgabe keine Abweichungen mehr aufgetreten. Das ist schon verhext.

Der Unterschied von 1.92 zu 1.93 ist der #define slowdebug.
wenn slowdebug definiert ist, werden zusätzliche Werte (Länge der letzten Impulse und der Pausen dazwischen) gespeichert und ausgegeben. Die Code-Größe und der RAM-Bedarf steigen dadurch. Am Zählen per Interrupt sollte sich dadurch nichts ändern.

@birdy: wenn Du in Zeile 73 die Kommentarzeichen (//) entfernst, wird der Debug-Code wieder reincompiliert. Bitte prüf doch mal, ob bei Dir dann alles wieder klappt.
Das beantwortet dann zwar immer noch nicht warum das so ist, aber immerhin sind wir dann sicher, dass der Debug-Code tatsächlich den Unterschied macht und ich kann da weitersuchen.
Unabhängig von den Debug-Ausgaben habe ich seit 1.9(0) nur zusätzliche Kommentare eingefügt, ein paar Variablennamen geändert, so dass ihre Funktion leichter verständlich ist und unbenutzte Variable entfernt. Das alles darf keine Auswirkungen auf die Funktion des compilats haben.

@Nighthawk: verwendest Du Pulslängenkontrolle? werden Impulse rejected? Womit vergleichst Du die gezählten Impulse? Die 1.9(0), die bei Dir und auch bisher allen anderen Anwendern funktioniert, ist genau die, die bei birdys Ölpumpe nicht funktioniert. Oder meintest Du die 1.92, auf die Du zurück gegangen bist?
Hast Du einen Stromzähler mit S0-Ausgang oder auch einen Spezial-Zähler mit Reedkontakt?

Da ich das Problem leider nicht nachstellen kann, bin ich weiter auf Eure Hilfe beim Testen / Eingrenzen angewiesen.

Gruss
   Stefan

birdy

Hallo Stefan

Erst mal eine Frage:
Ich habe wie in früheren  Post, vor einigen Monaten  diskutieret, einen Kondensator verbaut. Macht dies in der aktuellen Firm- /Software Kombination noch einen Sinn?
Ohne diesen Kondensator würde das flashen der Firmware etwas einfacher gehen.

Zitat von: StefanStrobel am 22 Dezember 2017, 15:08:48
@birdy: wenn Du in Zeile 73 die Kommentarzeichen (//) entfernst, wird der Debug-Code wieder reincompiliert. Bitte prüf doch mal, ob bei Dir dann alles wieder klappt.
Klaro, werde ich in den nächsten Tagen machen.


Zitat von: StefanStrobel am 22 Dezember 2017, 15:08:48
Ob die fehlenden Impulse zu recht ignoriert wurden oder nicht, kann ich natürlich nicht sagen. 
In der letzten korrekt funktionierenden Version (mit Debug Option) hatte ich immer wieder Rejects. Offensichtlich prellt der Kontakt bei mir (manchmal). Diese Impulse wurden also zu recht ignoriert. Ich hatte ja während rund 2 Wochen keinerlei Abweichung, nicht einen einzigen Impuls. Wie es in der letzten Version (ohne Debut Option) aussieht kann ich auch nicht sagen. Ich sehe gerade auch keine Möglichkeit die berechtigen Rejects von allfälligen nicht berechtigten zu unterscheiden.


Zitat von: StefanStrobel am 22 Dezember 2017, 15:08:48
Da ich das Problem leider nicht nachstellen kann, bin ich weiter auf Eure Hilfe beim Testen / Eingrenzen angewiesen.
Mache ich sehr gerne, werde ja von Dir auch sehr tatkräftig unterstützt.  :)

Gruss birdy

PS: Ist zwar nicht von Bedeutung aber meine ,,Ölpumpe" ist genau genommen ein Ringkolbendurchfluszähler. 
http://www.weinmann-schanz.de/de/de/Heizung/%C3%96l-Installation/%C3%96lz%C3%A4hler/pid.1143.1160/agid.89208.89536.2378/ecm.ag/Ringkolbenz%C3%A4hler-AQUA-METRO-VZO-4-8-mit-Innengewindeanschluss.html
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 würde den Kondensator drin lassen.
Zum Flashen habe ich auf meinem Fhem-Raspberry, an dem der Arduino angeschlossen ist, avrdude installiert. Dann kann man die neue Firmware (als hex-File) direkt aus Fhem heraus flashen und muss keine Kabel abklemmen. Einfach set flash.

Bin gespannt, was Deine weiteren Tests ergeben.
Ich habe gerade nochmals per diff geprüft, ob ich wirklich nicht versehentlich noch etwas anderes geändert habe, aber zwischen 1.92 und 1.93 ändert sich nur der #define und der Versionsstring. Folglich müsste dann ja mit Debug-Code wieder alles funktionieren.
Ich werde dann schrittweise die Debug-Funktionen wieder entfernen um so einzugrenzen, an welcher Stelle es Seiteneffekte gibt...

Noch eine Frage zum Verhalten mit der 1.93er Version ohne Debug-Ausgabe:
Sind die jetzt fehlenden Pulse als Rejected gezählt worden oder fehlen die einfach spurlos?
Kannst Du mit dem DMM und gleichzeitigem Fhem-Log mit verbose 5 nochmal ein Protokoll posten wo man die fehlenden Pulse sieht?

Gruss
   Stefan

Nighthawk

Hallo Stefan,

ich nutze den Arducounter für einen Gas und einen Wasserzähler mit Reedkontakt.
Pulslängenkontrolle habe ich bisher nicht genutzt.
Rejects habe ich wenige mit der 1.90, mit der 1.93 sind die fehlenden Impulse gar nicht da.
Ich teste gerade die 1,92 und hier werden die rejectete Impulse mitgezählt und es ist nun sehr nahe an dem realen Zähler, ich beobachte es noch ein paar tage.

Hier ein List von meiem aktuellen Device:

Internals:
   DEF        /dev/serial/by-id/usb-1a86_USB2.0-Serial-if00-port0@38400
   DeviceName /dev/serial/by-id/usb-1a86_USB2.0-Serial-if00-port0@38400
   FD         4
   Initialized 1
   NAME       AC
   NOTIFYDEV  global
   NR         1100
   NTFY_ORDER 50-AC
   PARTIAL   
   STATE      opened
   TYPE       ArduCounter
   VersionFirmware 1.92
   VersionModule 5.4 - 02.12.2017
   buffer     
   CounterInterpolated:
 
   READINGS:
     2017-12-23 20:47:12   GasZaehler      96185
     2017-12-18 19:59:54   GasZaehlerStart 91772
     2017-12-23 20:47:12   Gaszaehler_Longcount 4413
     2017-12-23 20:47:12   WasserZaehler   549728
     2017-12-23 17:57:08   WasserZaehlerStart 534835
     2017-12-23 20:45:12   Wasserzaehler_Longcount 14893
     2017-12-23 20:47:12   countDiff4      0
     2017-12-23 20:45:12   countDiff6      0
     2017-12-23 20:47:12   interpolatedLong4 4415
     2017-12-23 20:45:12   interpolatedLong6 1737
     2017-12-23 20:47:12   lastMsg4        R4 C327 D0 R0 T360000 N357120548 X0
     2017-12-23 20:45:12   lastMsg6        R6 C1737 D0 R0 T360000 N357000548 X0
     2017-10-23 10:34:20   long4           2
     2017-12-23 20:47:12   pin4            327
     2017-12-23 20:45:12   pin6            1737
     2017-12-23 20:47:12   power4          0.000
     2017-12-23 20:45:12   power6          0.000
     2017-12-23 20:47:12   reject4         166
     2017-12-23 20:45:12   reject6         21
     2017-12-23 20:47:12   statGasZaehler  Hour: 6 Day: 70 Month: 1993 Year: 4497 (since: 2017-10-23 )
     2017-12-23 20:47:12   statGasZaehlerDay 70
     2017-12-22 23:59:55   statGasZaehlerDayLast 77
     2017-12-23 19:59:55   statGasZaehlerLast Hour: 9 Day: 77 Month: 2127 Year: -
     2017-12-23 20:47:12   statGasZaehlerMonth 1993
     2017-11-30 23:59:55   statGasZaehlerMonthLast 2127
     2017-12-23 20:47:12   statWasserZaehler Hour: 17 Day: 525 Month: 6504 Year: 17674 (since: 2017-10-23 )
     2017-12-23 20:47:12   statWasserZaehlerDay 525
     2017-12-22 23:59:55   statWasserZaehlerDayLast 158
     2017-12-23 19:59:55   statWasserZaehlerLast Hour: 203 Day: 158 Month: 8116 Year: -
     2017-12-23 20:47:12   statWasserZaehlerMonth 6504
     2017-11-30 23:59:55   statWasserZaehlerMonthLast 8116
     2017-12-19 18:56:25   state           opened
     2017-12-23 20:47:12   timeDiff4       360000
     2017-12-19 20:04:35   timeDiff5       360000
     2017-12-23 20:45:12   timeDiff6       360000
   helper:
     _98_statistics myStatDevice
Attributes:
   event-on-change-reading .*
   flashCommand avrdude -p atmega328P -c arduino -b 57600 -P [PORT] -D -U flash:w:[HEXFILE] 2>[LOGFILE]
   group      Energie
   interval   60 360
   pinD4      falling 100
   pinD6      falling 100
   readingNameLongCount4 Gaszaehler_Longcount
   readingNameLongCount6 Wasserzaehler_Longcount
   room       Anschlussraum
   sortby     10
   userReadings GasZaehler {ReadingsVal("AC","Gaszaehler_Longcount",0) + ReadingsNum("AC","GasZaehlerStart",0) }, WasserZaehler {ReadingsVal("AC","Wasserzaehler_Longcount",0) + ReadingsNum("AC","WasserZaehlerStart",0) }
   userattr   pinD4 pinD6 readingNameLongCount4 readingNameLongCount6 verboseReadings4 verboseReadings6
   verbose    5
   verboseReadings4 1
   verboseReadings6 1

birdy

Hallo Stefan

Zitat von: StefanStrobel am 23 Dezember 2017, 18:17:48

ich würde den Kondensator drin lassen.
Zum Flashen habe ich auf meinem Fhem-Raspberry, an dem der Arduino angeschlossen ist, avrdude installiert. Dann kann man die neue Firmware (als hex-File) direkt aus Fhem heraus flashen und muss keine Kabel abklemmen. Einfach set flash.

Hast Du auch einen Kondensator verbaut?
Ich muss mit dem verbauten Kondensator immer genau im richtigen Moment den Reset Taster auf dem Arduino drücken um zu flashen. Ohne geht es nicht, auch nicht mit avrdude. Drücke ich den Taster einen Bruchteil einer Sekunde zu früh oder zu säht scheitert der Flashvorgang.

Zitat von: StefanStrobel am 23 Dezember 2017, 18:17:48
Noch eine Frage zum Verhalten mit der 1.93er Version ohne Debug-Ausgabe:
Sind die jetzt fehlenden Pulse als Rejected gezählt worden oder fehlen die einfach spurlos?
Das habe ich mir auch schon überlegt, aber dazu habe ich noch keinen Ansatz gefunden wie ich das eingrenzen könnte. Mein Reedkontakt prellt offensichtlich. Die ,,Prell" Impulse werden zu recht nur bei den Rejcts gezählt. Ich habe keinen Ahnung wie viele und wann solche zu kurzen Impulse auftreten. Somit habe ich auch keine Ahnung wie ich die zurecht ignorierten von den fälschlicherweise ignorierten unterscheiden könnte.

Leider bin ich bis jetzt noch nicht dazu gekommen irgendwelche Test zu machen.

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,

wo hast Du den Kondensator denn eingebaut? Ich fürchte ich habe Dich hier falsch verstanden und Du hast den Kondensator gar nicht zum Entprellen sondern zum Verhindern des Reset eingebaut oder?
Ich denke auf den kannst Du inzwischen verzichten. Der LongCounter zählt ja im Modul entsprechend weiter. Es bleibt also nur das Risiko, dass während des Restarts ein Impuls übersehen wird.
Ich würde ihn zumindest so lange entfernen bis wir das Problem gelöst / vollständig verstanden haben.

Ich versuche gerade Deine Umgebung zu simulieren und einen zweiten Arduino als Signalgeber an den Eingang zu hängen. Wie hast Du denn den Arducounter genau konfiguriert wenn die Probleme auftreten? In welchen Abständen kommen zu der Zeit ungefähr die Impulse? (dann kann ich versuchen das so nahe wie möglich zu simulieren)

Gruss
   Stefan


birdy

Hallo Stefan

Genau den Kondensator hatte ich zum Verhindern des Reset eingebaut. 
Diesen habe gestern gegen Abend entfernt (Flashen mit set flash hat trotzdem noch nicht funktioniert) .
Ich habe Deine 1.93 genommen den Kommentar bei Zeile 73 entfern und so die Debug Funktion wieder aktiviert. Seit gestern ca. 17:00 bis jetzt keinerlei Abweichung :)

Der Reedkonkakt hängt zwischen Pin 4 und GND.
Nachfolgend ein Extrakt des Log aus Post @230, aus dem ich alle Statusübergänge extrahiert habe. Diese liegen in der Regel ca. 2:30 auseinander. Je nach aktueller Leistung kann dies etwas variieren. Zwischendurch schaltet die Heizung auch mal für 20, 30 Minuten aus.   


2017.11.30 17:28:10 3: AC: device: pin 4 changed state to 0 , count 344 (+0)  Rej 1
2017.11.30 17:41:45 3: AC: device: pin 4 changed state to 1 , count 345 (+1)  Rej 0
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: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:54:25 3: AC: device: pin 4 changed state to 0 , count 347 (+0)  Rej 0
2017.11.30 17:57:15 3: AC: device: pin 4 changed state to 1 , count 348 (+1)  Rej 0
2017.11.30 18:17:32 3: AC: device: pin 4 changed state to 0 , count 348 (+0)  Rej 0
2017.11.30 18:20:15 3: AC: device: pin 4 changed state to 1 , count 349 (+1)  Rej 0
2017.11.30 18:23:10 3: AC: device: pin 4 changed state to 0 , count 349 (+0)  Rej 1
2017.11.30 18:35:17 3: AC: device: pin 4 changed state to 1 , count 349 (+0)  Rej 1
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:57 3: AC: device: pin 4 changed state to 1 , count 349 (+0)  Rej 2
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:57:18 3: AC: device: pin 4 changed state to 0 , count 349 (+0)  Rej 0
2017.11.30 19:14:44 3: AC: device: pin 4 changed state to 1 , count 350 (+1)  Rej 0
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:28 3: AC: device: pin 4 changed state to 1 , count 351 (+1)  Rej 0
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: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:40:42 3: AC: device: pin 4 changed state to 0 , count 352 (+0)  Rej 0
2017.11.30 19:48:46 3: AC: device: pin 4 changed state to 1 , count 353 (+1)  Rej 0
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: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:03:13 3: AC: device: pin 4 changed state to 0 , count 354 (+0)  Rej 0
2017.11.30 20:20:49 3: AC: device: pin 4 changed state to 1 , count 355 (+1)  Rej 0
2017.11.30 20:26:29 3: AC: device: pin 4 changed state to 1 , count 356 (+1)  Rej 0
2017.11.30 20:29:29 3: AC: device: pin 4 changed state to 0 , count 356 (+0)  Rej 1
2017.11.30 20:49:45 3: AC: device: pin 4 changed state to 0 , count 356 (+0)  Rej 1
2017.11.30 20:52:14 3: AC: device: pin 4 changed state to 1 , count 357 (+1)  Rej 0
2017.11.30 20:54:27 3: AC: device: pin 4 changed state to 0 , count 357 (+0)  Rej 1
2017.11.30 20:56:59 3: AC: device: pin 4 changed state to 1 , count 357 (+0)  Rej 2
2017.11.30 20:59:54 3: AC: device: pin 4 changed state to 0 , count 357 (+0)  Rej 1
2017.11.30 21:02:43 3: AC: device: pin 4 changed state to 1 , count 357 (+0)  Rej 2
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: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


Und List des ArduCounters:

Internals:
   CounterResetTime 1514298686.03582
   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.94
   VersionModule 5.4 - 02.12.2017
   buffer
   CounterInterpolated:
     4          1
     6          1
   READINGS:
     2017-12-27 14:00:38   OelVorrat       681.8
     2017-12-27 14:00:38   OelZaehler      772.2
     2017-12-27 14:00:38   OelZaehlerI     772.2
     2017-12-27 14:00:38   countDiff4      1
     2017-12-27 14:00:38   countDiff6      0
     2017-12-27 14:00:38   interpolatedLong4 7722
     2017-12-27 14:00:38   interpolatedLong6 13112
     2017-12-27 14:00:38   lastMsg4        R4 C94 D1 R1 T275143 N81000523 X0 F275143 L275143 A515709

     2017-12-27 14:00:38   lastMsg6        R6 C0 D0 R0 T360000 N81000523 X0

     2017-12-27 14:00:38   long4           7722
     2017-12-27 14:00:38   long6           13112
     2017-12-27 14:00:38   pin4            94
     2017-12-27 14:00:38   pin6            0
     2017-12-27 14:00:38   power4          0.013
     2017-12-27 14:00:38   power6          0.000
     2017-12-27 14:00:38   reject4         27
     2017-12-27 14:00:38   reject6         0
     2017-12-26 15:31:24   state           opened
     2017-12-27 14:00:38   timeDiff4       275143
     2017-12-27 14:00:38   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 pin6 readingFactor6 verboseReadings4 verboseReadings6
   verbose    5
   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)

StefanStrobel

vielen Dank!

dann sind es also tatsächlich die Debug-Funktionen.
Jetzt würde ich gerne weiter eingrenzen, welcher Teil der Debug-Funktionen den Unterschied macht.

Als Basis wäre es hilfreich, wenn Du nochmal Dein DMM zum Vergleich mitlaufen lassen würdest und dabei mit der bisherigen Version ohne Debug-Funktionen aber mit verbose 5 einen Log-Auszug posten könntest, in dem man sieht, wo / unter welchen Bedingungen bzw. wie oft Abweichungen auftreten.

Danach würde ich Dir gerne eine neue Version schicken, bei der zunächst nur der Debug-Teil in der Hauptschleife drin steckt, nicht aber der Teil im Interrupt-Handler, der die zusätzlichen Daten bereitstellt.
Eigentlich sollte das dann keinen Unterschied machen, denn gezählt wird ja per Interrupt. Folglich sollten hier wieder Impulse fehlen. Warum auch immer ...

Gruss / Thanx
   Stefan

Anbei schon mal die neue Version für den zweiten Test

birdy

Hallo Stefan

Ich habe heute Abend währen rund 3 Stunden  einen Test durchgeführt.

Start: 20:19

  • Der mechanische Zähler zeigt 774.82, der ArduCounter steht bei 774.8.
  • Der Reed-Kontakt ist offen, am Pin 4 liegen 4.7 V an.

Ende: 23:24

  • Der mechanische Zähler zeigt 776.45, der ArudConter steht bei 775.3
  • Der Reed-Kontakt ist geschlossen, am Pin 4 liegen 0.0V an.

Erklärungen:
Der Reed-Kontakt schliesst jeweils bei x.x1 und öffnet bei x.x6 Liter. Ergibt ein Zyklus per 0.1L
Das DMM Protokoll habe ich bearbeitet, es sind nur noch die Statusübergänge enthalten. Immer der letzte Messwert vor und nach dem Übergang.
Es ist davon auszugehen, dass die beiden Uhren DDM und FHEM nicht ganz synchron laufen.

List:
Internals:
   CounterResetTime 1514402090.93483
   DEF        /dev/ttyUSB0@38400
   DeviceName /dev/ttyUSB0@38400
   FD         10
   Initialized 1
   NAME       AC
   NOTIFYDEV  global
   NR         21
   NTFY_ORDER 50-AC
   PARTIAL
   STATE      opened
   TYPE       ArduCounter
   VersionFirmware 1.93
   VersionModule 5.4 - 02.12.2017
   buffer
   CounterInterpolated:
     4          1
     6          1
   READINGS:
     2017-12-27 23:26:44   OelVorrat       678.7
     2017-12-27 23:26:44   OelZaehler      775.3
     2017-12-27 23:26:44   OelZaehlerI     775.3
     2017-12-27 23:23:44   countDiff4      0
     2017-12-27 23:26:44   countDiff6      0
     2017-12-27 23:23:44   interpolatedLong4 7753
     2017-12-27 23:26:44   interpolatedLong6 13112
     2017-12-27 23:23:44   lastMsg4        R4 C5 D0 R0 T360000 N11340534 X1

     2017-12-27 23:26:44   lastMsg6        R6 C0 D0 R0 T360000 N11520534 X0

     2017-12-27 23:23:44   long4           7753
     2017-12-27 23:26:44   long6           13112
     2017-12-27 23:23:44   pin4            5
     2017-12-27 23:26:44   pin6            0
     2017-12-27 23:23:44   power4          0.000
     2017-12-27 23:26:44   power6          0.000
     2017-12-27 23:23:44   reject4         23
     2017-12-27 23:26:44   reject6         0
     2017-12-27 20:14:49   state           opened
     2017-12-27 23:23:44   timeDiff4       360000
     2017-12-27 23:26:44   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 pin6 readingFactor6 verboseReadings4 verboseReadings6
   verbose    5
   verboseReadings4 1
   verboseReadings6 1


DMM-Log:
No Time DC/AC Value Unit
1 20:19:49 DC 0.0060 V
328 20:25:20 DC 0.0060 V
329 20:25:21 DC 04.775 V
502 20:28:17 DC 04.776 V
503 20:28:18 DC 0.0040 V
644 20:30:40 DC 0.0060 V
645 20:30:41 DC 04.775 V
771 20:32:49 DC 04.776 V
772 20:32:50 DC 0.0060 V
888 20:34:48 DC 0.0060 V
889 20:34:49 DC 04.774 V
1047 20:37:30 DC 04.777 V
1048 20:37:31 DC 0.0060 V
1216 20:40:21 DC 0.0060 V
1217 20:40:22 DC 04.775 V
2441 21:01:03 DC 04.777 V
2442 21:01:04 DC 0.0060 V
2584 21:03:28 DC 0.0060 V
2585 21:03:29 DC 04.775 V
2755 21:06:21 DC 04.777 V
2756 21:06:22 DC 0.0060 V
2923 21:09:12 DC 0.0060 V
2924 21:09:13 DC 04.777 V
3098 21:12:09 DC 04.778 V
3099 21:12:10 DC 0.0060 V
4252 21:31:39 DC 00.060 V
4253 21:31:40 DC 04.775 V
4389 21:33:58 DC 04.778 V
4390 21:33:59 DC 0.0060 V
4554 21:36:46 DC 0.0060 V
4555 21:36:47 DC 04.775 V
4730 21:39:44 DC 04.777 V
4731 21:39:45 DC 0.0060 V
6142 22:03:36 DC 0.0060 V
6143 22:03:37 DC 04.774 V
6308 22:06:24 DC 04.777 V
6309 22:06:25 DC 0.0060 V
6439 22:08:37 DC 0.0060 V
6440 22:08:38 DC 04.775 V
6544 22:10:23 DC 04.777 V
6545 22:10:24 DC 0.0060 V
6648 22:12:09 DC 0.0059 V
6649 22:12:10 DC 04.776 V
6813 22:14:56 DC 04.777 V
6814 22:14:57 DC 0.0060 V
6980 22:17:45 DC 00.060 V
6981 22:17:46 DC 04.775 V
8548 22:44:15 DC 04.777 V
8549 22:44:16 DC 0.0060 V
8713 22:47:03 DC 0.0060 V
8714 22:47:04 DC 04.777 V
8887 22:49:59 DC 0.4778 V
8888 22:50:00 DC 0.0060 V
9054 22:52:48 DC 0.0060 V
9055 22:52:49 DC 04.775 V
10068 23:09:57 DC 04.777 V
10069 23:09:58 DC 0.0060 V
10234 23:12:45 DC 0.0060 V
10235 23:12:46 DC 04.775 V
10394 23:15:27 DC 04.777 V
10395 23:15:28 DC 0.0060 V
10563 23:18:18 DC 0.0060 V
10564 23:18:20 DC 04.775 V
10737 23:21:15 DC 04.777 V
10738 23:21:16 DC 0.0060 V
10954 23:24:55 DC 0.0059 V


FHEM-Log:
2017.12.27 20:14:51 3: AC: device: Next report in 29861 Milliseconds
2017.12.27 20:14:51 5: AC: ReadAnswer matched Next report in [0-9]+ Milliseconds
2017.12.27 20:20:50 4: AC: arduino was restarted so some impulses might have got lost for 4 (pin4)
2017.12.27 20:20:50 4: AC: interpolation of missed pulses for pin 4 (pin4) not possible - no valid historic data.
2017.12.27 20:20:50 4: AC: Pin 4 debug: adding 0 to long count 7748 and interpolated count 7748
2017.12.27 20:20:50 4: AC: Pin 4 (pin4) count 0 longCount 7748 interpCount 7748 (diff 0) in 360.000s, reject 0, Avg Len ms, result 0.000
2017.12.27 20:20:50 4: AC: interval 20:14:50 until 20:20:50, First at , Last at
2017.12.27 20:20:50 5: AC: set readings power4 to 0.000, timeDiff4 to 360000 and countDiff4 to 0
2017.12.27 20:20:50 4: AC: device clock wrapped or reset (now 360.52, before 101340.523). New offset is 1514402090.22161
2017.12.27 20:20:50 5: AC: Device Time 360.52, Drift -59.366s in 326197.966s, -0.02%
2017.12.27 20:20:50 4: AC: arduino was restarted so some impulses might have got lost for 6 (pin6)
2017.12.27 20:20:50 3: AC: interpolation after counter reset for pin 6 (pin6): offline 2304.93 secs, 0 estimated pulses (before 0 in 360000 ms, now 0 in 360000 ms, avg ratio 0 p/s)
2017.12.27 20:20:50 4: AC: adding interpolated 0 to interpolated count 13112
2017.12.27 20:20:50 4: AC: Pin 6 debug: adding 0 to long count 13112 and interpolated count 13112
2017.12.27 20:20:50 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.27 20:20:50 4: AC: interval 20:14:50 until 20:20:50, First at , Last at
2017.12.27 20:20:50 5: AC: set readings power6 to 0.000, timeDiff6 to 360000 and countDiff6 to 0
2017.12.27 20:20:50 5: AC: Device Time 360.534, Drift 0.002s in 326197.982s, 0.00%
2017.12.27 20:26:50 4: AC: Pin 4 debug: adding 0 to long count 7748 and interpolated count 7748
2017.12.27 20:26:50 4: AC: Pin 4 (pin4) count 0 longCount 7748 interpCount 7748 (diff 0) in 360.000s, reject 2, Avg Len ms, result 0.000
2017.12.27 20:26:50 4: AC: interval 20:20:50 until 20:26:50, First at , Last at
2017.12.27 20:26:50 5: AC: set readings power4 to 0.000, timeDiff4 to 360000 and countDiff4 to 0
2017.12.27 20:26:50 5: AC: Device Time 720.52, Drift -0.178s in 326557.787s, -0.00%
2017.12.27 20:26:50 4: AC: Pin 6 debug: adding 0 to long count 13112 and interpolated count 13112
2017.12.27 20:26:50 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.27 20:26:50 4: AC: interval 20:20:50 until 20:26:50, First at , Last at
2017.12.27 20:26:50 5: AC: set readings power6 to 0.000, timeDiff6 to 360000 and countDiff6 to 0
2017.12.27 20:26:50 5: AC: Device Time 720.534, Drift -0.192s in 326557.787s, -0.00%
2017.12.27 20:32:50 4: AC: Pin 4 debug: adding 1 to long count 7748 and interpolated count 7748
2017.12.27 20:32:50 4: AC: Pin 4 (pin4) count 1 longCount 7749 interpCount 7749 (diff 0) in 129.761s, reject 1, Avg Len ms, result 0.000
2017.12.27 20:32:50 4: AC: interval 20:30:40 until 20:32:50, First at , Last at
2017.12.27 20:32:50 5: AC: set readings power4 to 0.000, timeDiff4 to 129761 and countDiff4 to 0
2017.12.27 20:32:50 5: AC: Device Time 1080.52, Drift -0.390s in 326917.576s, -0.00%
2017.12.27 20:32:50 4: AC: Pin 6 debug: adding 0 to long count 13112 and interpolated count 13112
2017.12.27 20:32:50 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.27 20:32:50 4: AC: interval 20:26:50 until 20:32:50, First at , Last at
2017.12.27 20:32:50 5: AC: set readings power6 to 0.000, timeDiff6 to 360000 and countDiff6 to 0
2017.12.27 20:32:50 5: AC: Device Time 1080.534, Drift -0.404s in 326917.576s, -0.00%
2017.12.27 20:38:50 4: AC: Pin 4 debug: adding 0 to long count 7749 and interpolated count 7749
2017.12.27 20:38:50 4: AC: Pin 4 (pin4) count 1 longCount 7749 interpCount 7749 (diff 0) in 360.000s, reject 1, Avg Len ms, result 0.000
2017.12.27 20:38:50 4: AC: interval 20:32:50 until 20:38:50, First at , Last at
2017.12.27 20:38:50 5: AC: set readings power4 to 0.000, timeDiff4 to 360000 and countDiff4 to 0
2017.12.27 20:38:50 5: AC: Device Time 1440.52, Drift -0.601s in 327277.365s, -0.00%
2017.12.27 20:38:50 4: AC: Pin 6 debug: adding 0 to long count 13112 and interpolated count 13112
2017.12.27 20:38:50 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.27 20:38:50 4: AC: interval 20:32:50 until 20:38:50, First at , Last at
2017.12.27 20:38:50 5: AC: set readings power6 to 0.000, timeDiff6 to 360000 and countDiff6 to 0
2017.12.27 20:38:50 5: AC: Device Time 1440.534, Drift -0.615s in 327277.365s, -0.00%
2017.12.27 20:40:50 4: AC: Pin 4 debug: adding 1 to long count 7749 and interpolated count 7749
2017.12.27 20:40:50 4: AC: Pin 4 (pin4) count 2 longCount 7750 interpCount 7750 (diff 1) in 90.159s, reject 0, Avg Len 314630ms, result 0.040
2017.12.27 20:40:50 4: AC: interval 20:39:19 until 20:40:50, First at 4294477535, Last at 90159
2017.12.27 20:40:50 5: AC: set readings power4 to 0.040, timeDiff4 to 90159 and countDiff4 to 1
2017.12.27 20:40:50 5: AC: Device Time 1560.534, Drift -0.668s in 327397.311s, -0.00%
2017.12.27 20:44:49 4: AC: Pin 6 debug: adding 0 to long count 13112 and interpolated count 13112
2017.12.27 20:44:49 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.27 20:44:49 4: AC: interval 20:38:49 until 20:44:49, First at , Last at
2017.12.27 20:44:49 5: AC: set readings power6 to 0.000, timeDiff6 to 360000 and countDiff6 to 0
2017.12.27 20:44:49 5: AC: Device Time 1800.534, Drift -0.825s in 327637.154s, -0.00%
2017.12.27 20:46:49 4: AC: Pin 4 debug: adding 0 to long count 7750 and interpolated count 7750
2017.12.27 20:46:49 4: AC: Pin 4 (pin4) count 2 longCount 7750 interpCount 7750 (diff 0) in 389.855s, reject 0, Avg Len ms, result 0.000
2017.12.27 20:46:49 4: AC: interval 20:40:20 until 20:46:49, First at , Last at
2017.12.27 20:46:49 5: AC: set readings power4 to 0.000, timeDiff4 to 389855 and countDiff4 to 0
2017.12.27 20:46:49 5: AC: Device Time 1920.534, Drift -0.896s in 327757.084s, -0.00%
2017.12.27 20:50:49 4: AC: Pin 6 debug: adding 0 to long count 13112 and interpolated count 13112
2017.12.27 20:50:49 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.27 20:50:49 4: AC: interval 20:44:49 until 20:50:49, First at , Last at
2017.12.27 20:50:49 5: AC: set readings power6 to 0.000, timeDiff6 to 360000 and countDiff6 to 0
2017.12.27 20:50:49 5: AC: Device Time 2160.534, Drift -1.020s in 327996.959s, -0.00%
2017.12.27 20:52:49 4: AC: Pin 4 debug: adding 0 to long count 7750 and interpolated count 7750
2017.12.27 20:52:49 4: AC: Pin 4 (pin4) count 2 longCount 7750 interpCount 7750 (diff 0) in 360.000s, reject 0, Avg Len ms, result 0.000
2017.12.27 20:52:49 4: AC: interval 20:46:49 until 20:52:49, First at , Last at
2017.12.27 20:52:49 5: AC: set readings power4 to 0.000, timeDiff4 to 360000 and countDiff4 to 0
2017.12.27 20:52:49 5: AC: Device Time 2280.534, Drift -1.091s in 328116.889s, -0.00%
2017.12.27 20:56:49 4: AC: Pin 6 debug: adding 0 to long count 13112 and interpolated count 13112
2017.12.27 20:56:49 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.27 20:56:49 4: AC: interval 20:50:49 until 20:56:49, First at , Last at
2017.12.27 20:56:49 5: AC: set readings power6 to 0.000, timeDiff6 to 360000 and countDiff6 to 0
2017.12.27 20:56:49 5: AC: Device Time 2520.534, Drift -1.231s in 328356.748s, -0.00%
2017.12.27 20:58:49 4: AC: Pin 4 debug: adding 0 to long count 7750 and interpolated count 7750
2017.12.27 20:58:49 4: AC: Pin 4 (pin4) count 2 longCount 7750 interpCount 7750 (diff 0) in 360.000s, reject 0, Avg Len ms, result 0.000
2017.12.27 20:58:49 4: AC: interval 20:52:49 until 20:58:49, First at , Last at
2017.12.27 20:58:49 5: AC: set readings power4 to 0.000, timeDiff4 to 360000 and countDiff4 to 0
2017.12.27 20:58:49 5: AC: Device Time 2640.534, Drift -1.302s in 328476.678s, -0.00%
2017.12.27 21:02:49 4: AC: Pin 6 debug: adding 0 to long count 13112 and interpolated count 13112
2017.12.27 21:02:49 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.27 21:02:49 4: AC: interval 20:56:49 until 21:02:49, First at , Last at
2017.12.27 21:02:49 5: AC: set readings power6 to 0.000, timeDiff6 to 360000 and countDiff6 to 0
2017.12.27 21:02:49 5: AC: Device Time 2880.534, Drift -1.442s in 328716.537s, -0.00%
2017.12.27 21:04:49 4: AC: Pin 4 debug: adding 0 to long count 7750 and interpolated count 7750
2017.12.27 21:04:49 4: AC: Pin 4 (pin4) count 2 longCount 7750 interpCount 7750 (diff 0) in 360.000s, reject 2, Avg Len ms, result 0.000
2017.12.27 21:04:49 4: AC: interval 20:58:49 until 21:04:49, First at , Last at
2017.12.27 21:04:49 5: AC: set readings power4 to 0.000, timeDiff4 to 360000 and countDiff4 to 0
2017.12.27 21:04:49 5: AC: Device Time 3000.534, Drift -1.513s in 328836.467s, -0.00%
2017.12.27 21:08:49 4: AC: Pin 6 debug: adding 0 to long count 13112 and interpolated count 13112
2017.12.27 21:08:49 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.27 21:08:49 4: AC: interval 21:02:49 until 21:08:49, First at , Last at
2017.12.27 21:08:49 5: AC: set readings power6 to 0.000, timeDiff6 to 360000 and countDiff6 to 0
2017.12.27 21:08:49 5: AC: Device Time 3240.534, Drift -1.637s in 329076.342s, -0.00%
2017.12.27 21:10:49 4: AC: Pin 4 debug: adding 0 to long count 7750 and interpolated count 7750
2017.12.27 21:10:49 4: AC: Pin 4 (pin4) count 2 longCount 7750 interpCount 7750 (diff 0) in 360.000s, reject 2, Avg Len ms, result 0.000
2017.12.27 21:10:49 4: AC: interval 21:04:49 until 21:10:49, First at , Last at
2017.12.27 21:10:49 5: AC: set readings power4 to 0.000, timeDiff4 to 360000 and countDiff4 to 0
2017.12.27 21:10:49 5: AC: Device Time 3360.534, Drift -1.708s in 329196.272s, -0.00%
2017.12.27 21:14:48 4: AC: Pin 6 debug: adding 0 to long count 13112 and interpolated count 13112
2017.12.27 21:14:48 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.27 21:14:48 4: AC: interval 21:08:48 until 21:14:48, First at , Last at
2017.12.27 21:14:48 5: AC: set readings power6 to 0.000, timeDiff6 to 360000 and countDiff6 to 0
2017.12.27 21:14:48 5: AC: Device Time 3600.534, Drift -1.848s in 329436.132s, -0.00%
2017.12.27 21:16:48 4: AC: Pin 4 debug: adding 0 to long count 7750 and interpolated count 7750
2017.12.27 21:16:48 4: AC: Pin 4 (pin4) count 2 longCount 7750 interpCount 7750 (diff 0) in 360.000s, reject 0, Avg Len ms, result 0.000
2017.12.27 21:16:48 4: AC: interval 21:10:48 until 21:16:48, First at , Last at
2017.12.27 21:16:48 5: AC: set readings power4 to 0.000, timeDiff4 to 360000 and countDiff4 to 0
2017.12.27 21:16:48 5: AC: Device Time 3720.534, Drift -1.918s in 329556.061s, -0.00%
2017.12.27 21:20:48 4: AC: Pin 6 debug: adding 0 to long count 13112 and interpolated count 13112
2017.12.27 21:20:48 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.27 21:20:48 4: AC: interval 21:14:48 until 21:20:48, First at , Last at
2017.12.27 21:20:48 5: AC: set readings power6 to 0.000, timeDiff6 to 360000 and countDiff6 to 0
2017.12.27 21:20:48 5: AC: Device Time 3960.534, Drift -2.043s in 329795.937s, -0.00%
2017.12.27 21:22:48 4: AC: Pin 4 debug: adding 0 to long count 7750 and interpolated count 7750
2017.12.27 21:22:48 4: AC: Pin 4 (pin4) count 2 longCount 7750 interpCount 7750 (diff 0) in 360.000s, reject 0, Avg Len ms, result 0.000
2017.12.27 21:22:48 4: AC: interval 21:16:48 until 21:22:48, First at , Last at
2017.12.27 21:22:48 5: AC: set readings power4 to 0.000, timeDiff4 to 360000 and countDiff4 to 0
2017.12.27 21:22:48 5: AC: Device Time 4080.534, Drift -2.113s in 329915.866s, -0.00%
2017.12.27 21:26:48 4: AC: Pin 6 debug: adding 0 to long count 13112 and interpolated count 13112
2017.12.27 21:26:48 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.27 21:26:48 4: AC: interval 21:20:48 until 21:26:48, First at , Last at
2017.12.27 21:26:48 5: AC: set readings power6 to 0.000, timeDiff6 to 360000 and countDiff6 to 0
2017.12.27 21:26:48 5: AC: Device Time 4320.534, Drift -2.254s in 330155.726s, -0.00%
2017.12.27 21:28:48 4: AC: Pin 4 debug: adding 0 to long count 7750 and interpolated count 7750
2017.12.27 21:28:48 4: AC: Pin 4 (pin4) count 2 longCount 7750 interpCount 7750 (diff 0) in 360.000s, reject 0, Avg Len ms, result 0.000
2017.12.27 21:28:48 4: AC: interval 21:22:48 until 21:28:48, First at , Last at
2017.12.27 21:28:48 5: AC: set readings power4 to 0.000, timeDiff4 to 360000 and countDiff4 to 0
2017.12.27 21:28:48 5: AC: Device Time 4440.534, Drift -2.324s in 330275.656s, -0.00%
2017.12.27 21:31:48 4: AC: Pin 4 debug: adding 1 to long count 7750 and interpolated count 7750
2017.12.27 21:31:48 4: AC: Pin 4 (pin4) count 3 longCount 7751 interpCount 7751 (diff 1) in 170.321s, reject 0, Avg Len 1169784ms, result 0.021
2017.12.27 21:31:48 4: AC: interval 21:28:58 until 21:31:48, First at 170321, Last at 170321
2017.12.27 21:31:48 5: AC: set readings power4 to 0.021, timeDiff4 to 170321 and countDiff4 to 1
2017.12.27 21:31:48 5: AC: Device Time 4620.534, Drift -2.413s in 330455.566s, -0.00%
2017.12.27 21:32:48 4: AC: Pin 6 debug: adding 0 to long count 13112 and interpolated count 13112
2017.12.27 21:32:48 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.27 21:32:48 4: AC: interval 21:26:48 until 21:32:48, First at , Last at
2017.12.27 21:32:48 5: AC: set readings power6 to 0.000, timeDiff6 to 360000 and countDiff6 to 0
2017.12.27 21:32:48 5: AC: Device Time 4680.534, Drift -2.465s in 330515.515s, -0.00%
2017.12.27 21:36:48 4: AC: Pin 4 debug: adding 1 to long count 7751 and interpolated count 7751
2017.12.27 21:36:48 4: AC: Pin 4 (pin4) count 4 longCount 7752 interpCount 7752 (diff 1) in 307.402s, reject 1, Avg Len 167935ms, result 0.012
2017.12.27 21:36:48 4: AC: interval 21:31:40 until 21:36:48, First at 307402, Last at 307402
2017.12.27 21:36:48 5: AC: set readings power4 to 0.012, timeDiff4 to 307402 and countDiff4 to 1
2017.12.27 21:36:48 5: AC: Device Time 4920.534, Drift -2.589s in 330755.391s, -0.00%
2017.12.27 21:38:48 4: AC: Pin 6 debug: adding 0 to long count 13112 and interpolated count 13112
2017.12.27 21:38:48 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.27 21:38:48 4: AC: interval 21:32:48 until 21:38:48, First at , Last at
2017.12.27 21:38:48 5: AC: set readings power6 to 0.000, timeDiff6 to 360000 and countDiff6 to 0
2017.12.27 21:38:48 5: AC: Device Time 5040.534, Drift -2.659s in 330875.320s, -0.00%
2017.12.27 21:42:47 4: AC: Pin 4 debug: adding 0 to long count 7752 and interpolated count 7752
2017.12.27 21:42:47 4: AC: Pin 4 (pin4) count 4 longCount 7752 interpCount 7752 (diff 0) in 362.277s, reject 1, Avg Len ms, result 0.000
2017.12.27 21:42:47 4: AC: interval 21:36:45 until 21:42:47, First at , Last at
2017.12.27 21:42:47 5: AC: set readings power4 to 0.000, timeDiff4 to 362277 and countDiff4 to 0
2017.12.27 21:42:47 5: AC: Device Time 5280.534, Drift -2.800s in 331115.180s, -0.00%
2017.12.27 21:44:47 4: AC: Pin 6 debug: adding 0 to long count 13112 and interpolated count 13112
2017.12.27 21:44:47 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.27 21:44:47 4: AC: interval 21:38:47 until 21:44:47, First at , Last at
2017.12.27 21:44:47 5: AC: set readings power6 to 0.000, timeDiff6 to 360000 and countDiff6 to 0
2017.12.27 21:44:47 5: AC: Device Time 5400.534, Drift -2.870s in 331235.109s, -0.00%
2017.12.27 21:48:47 4: AC: Pin 4 debug: adding 0 to long count 7752 and interpolated count 7752
2017.12.27 21:48:47 4: AC: Pin 4 (pin4) count 4 longCount 7752 interpCount 7752 (diff 0) in 360.000s, reject 0, Avg Len ms, result 0.000
2017.12.27 21:48:47 4: AC: interval 21:42:47 until 21:48:47, First at , Last at
2017.12.27 21:48:47 5: AC: set readings power4 to 0.000, timeDiff4 to 360000 and countDiff4 to 0
2017.12.27 21:48:47 5: AC: Device Time 5640.534, Drift -3.011s in 331474.969s, -0.00%
2017.12.27 21:50:47 4: AC: Pin 6 debug: adding 0 to long count 13112 and interpolated count 13112
2017.12.27 21:50:47 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.27 21:50:47 4: AC: interval 21:44:47 until 21:50:47, First at , Last at
2017.12.27 21:50:47 5: AC: set readings power6 to 0.000, timeDiff6 to 360000 and countDiff6 to 0
2017.12.27 21:50:47 5: AC: Device Time 5760.534, Drift -3.081s in 331594.899s, -0.00%
2017.12.27 21:54:47 4: AC: Pin 4 debug: adding 0 to long count 7752 and interpolated count 7752
2017.12.27 21:54:47 4: AC: Pin 4 (pin4) count 4 longCount 7752 interpCount 7752 (diff 0) in 360.000s, reject 0, Avg Len ms, result 0.000
2017.12.27 21:54:47 4: AC: interval 21:48:47 until 21:54:47, First at , Last at
2017.12.27 21:54:47 5: AC: set readings power4 to 0.000, timeDiff4 to 360000 and countDiff4 to 0
2017.12.27 21:54:47 5: AC: Device Time 6000.534, Drift -3.205s in 331834.774s, -0.00%
2017.12.27 21:56:47 4: AC: Pin 6 debug: adding 0 to long count 13112 and interpolated count 13112
2017.12.27 21:56:47 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.27 21:56:47 4: AC: interval 21:50:47 until 21:56:47, First at , Last at
2017.12.27 21:56:47 5: AC: set readings power6 to 0.000, timeDiff6 to 360000 and countDiff6 to 0
2017.12.27 21:56:47 5: AC: Device Time 6120.534, Drift -3.276s in 331954.704s, -0.00%
2017.12.27 22:00:47 4: AC: Pin 4 debug: adding 0 to long count 7752 and interpolated count 7752
2017.12.27 22:00:47 4: AC: Pin 4 (pin4) count 4 longCount 7752 interpCount 7752 (diff 0) in 360.000s, reject 0, Avg Len ms, result 0.000
2017.12.27 22:00:47 4: AC: interval 21:54:47 until 22:00:47, First at , Last at
2017.12.27 22:00:47 5: AC: set readings power4 to 0.000, timeDiff4 to 360000 and countDiff4 to 0
2017.12.27 22:00:47 5: AC: Device Time 6360.534, Drift -3.416s in 332194.563s, -0.00%
2017.12.27 22:02:47 4: AC: Pin 6 debug: adding 0 to long count 13112 and interpolated count 13112
2017.12.27 22:02:47 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.27 22:02:47 4: AC: interval 21:56:47 until 22:02:47, First at , Last at
2017.12.27 22:02:47 5: AC: set readings power6 to 0.000, timeDiff6 to 360000 and countDiff6 to 0
2017.12.27 22:02:47 5: AC: Device Time 6480.534, Drift -3.486s in 332314.493s, -0.00%
2017.12.27 22:06:47 4: AC: Pin 4 debug: adding 0 to long count 7752 and interpolated count 7752
2017.12.27 22:06:47 4: AC: Pin 4 (pin4) count 4 longCount 7752 interpCount 7752 (diff 0) in 360.000s, reject 2, Avg Len ms, result 0.000
2017.12.27 22:06:47 4: AC: interval 22:00:47 until 22:06:47, First at , Last at
2017.12.27 22:06:47 5: AC: set readings power4 to 0.000, timeDiff4 to 360000 and countDiff4 to 0
2017.12.27 22:06:47 5: AC: Device Time 6720.534, Drift -3.627s in 332554.353s, -0.00%
2017.12.27 22:08:47 4: AC: Pin 6 debug: adding 0 to long count 13112 and interpolated count 13112
2017.12.27 22:08:47 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.27 22:08:47 4: AC: interval 22:02:47 until 22:08:47, First at , Last at
2017.12.27 22:08:47 5: AC: set readings power6 to 0.000, timeDiff6 to 360000 and countDiff6 to 0
2017.12.27 22:08:47 5: AC: Device Time 6840.534, Drift -3.697s in 332674.282s, -0.00%
2017.12.27 22:12:46 4: AC: Pin 4 debug: adding 0 to long count 7752 and interpolated count 7752
2017.12.27 22:12:46 4: AC: Pin 4 (pin4) count 4 longCount 7752 interpCount 7752 (diff 0) in 360.000s, reject 3, Avg Len ms, result 0.000
2017.12.27 22:12:46 4: AC: interval 22:06:46 until 22:12:46, First at , Last at
2017.12.27 22:12:46 5: AC: set readings power4 to 0.000, timeDiff4 to 360000 and countDiff4 to 0
2017.12.27 22:12:46 5: AC: Device Time 7080.534, Drift -3.822s in 332914.158s, -0.00%
2017.12.27 22:14:46 4: AC: Pin 6 debug: adding 0 to long count 13112 and interpolated count 13112
2017.12.27 22:14:46 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.27 22:14:46 4: AC: interval 22:08:46 until 22:14:46, First at , Last at
2017.12.27 22:14:46 5: AC: set readings power6 to 0.000, timeDiff6 to 360000 and countDiff6 to 0
2017.12.27 22:14:46 5: AC: Device Time 7200.534, Drift -3.892s in 333034.087s, -0.00%
2017.12.27 22:18:46 4: AC: Pin 4 debug: adding 0 to long count 7752 and interpolated count 7752
2017.12.27 22:18:46 4: AC: Pin 4 (pin4) count 4 longCount 7752 interpCount 7752 (diff 0) in 360.000s, reject 2, Avg Len ms, result 0.000
2017.12.27 22:18:46 4: AC: interval 22:12:46 until 22:18:46, First at , Last at
2017.12.27 22:18:46 5: AC: set readings power4 to 0.000, timeDiff4 to 360000 and countDiff4 to 0
2017.12.27 22:18:46 5: AC: Device Time 7440.534, Drift -4.033s in 333273.947s, -0.00%
2017.12.27 22:20:46 4: AC: Pin 6 debug: adding 0 to long count 13112 and interpolated count 13112
2017.12.27 22:20:46 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.27 22:20:46 4: AC: interval 22:14:46 until 22:20:46, First at , Last at
2017.12.27 22:20:46 5: AC: set readings power6 to 0.000, timeDiff6 to 360000 and countDiff6 to 0
2017.12.27 22:20:46 5: AC: Device Time 7560.534, Drift -4.103s in 333393.877s, -0.00%
2017.12.27 22:24:46 4: AC: Pin 4 debug: adding 0 to long count 7752 and interpolated count 7752
2017.12.27 22:24:46 4: AC: Pin 4 (pin4) count 4 longCount 7752 interpCount 7752 (diff 0) in 360.000s, reject 0, Avg Len ms, result 0.000
2017.12.27 22:24:46 4: AC: interval 22:18:46 until 22:24:46, First at , Last at
2017.12.27 22:24:46 5: AC: set readings power4 to 0.000, timeDiff4 to 360000 and countDiff4 to 0
2017.12.27 22:24:46 5: AC: Device Time 7800.534, Drift -4.244s in 333633.736s, -0.00%
2017.12.27 22:26:46 4: AC: Pin 6 debug: adding 0 to long count 13112 and interpolated count 13112
2017.12.27 22:26:46 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.27 22:26:46 4: AC: interval 22:20:46 until 22:26:46, First at , Last at
2017.12.27 22:26:46 5: AC: set readings power6 to 0.000, timeDiff6 to 360000 and countDiff6 to 0
2017.12.27 22:26:46 5: AC: Device Time 7920.534, Drift -4.314s in 333753.666s, -0.00%
2017.12.27 22:30:46 4: AC: Pin 4 debug: adding 0 to long count 7752 and interpolated count 7752
2017.12.27 22:30:46 4: AC: Pin 4 (pin4) count 4 longCount 7752 interpCount 7752 (diff 0) in 360.000s, reject 0, Avg Len ms, result 0.000
2017.12.27 22:30:46 4: AC: interval 22:24:46 until 22:30:46, First at , Last at
2017.12.27 22:30:46 5: AC: set readings power4 to 0.000, timeDiff4 to 360000 and countDiff4 to 0
2017.12.27 22:30:46 5: AC: Device Time 8160.534, Drift -4.438s in 333993.541s, -0.00%
2017.12.27 22:32:46 4: AC: Pin 6 debug: adding 0 to long count 13112 and interpolated count 13112
2017.12.27 22:32:46 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.27 22:32:46 4: AC: interval 22:26:46 until 22:32:46, First at , Last at
2017.12.27 22:32:46 5: AC: set readings power6 to 0.000, timeDiff6 to 360000 and countDiff6 to 0
2017.12.27 22:32:46 5: AC: Device Time 8280.534, Drift -4.509s in 334113.471s, -0.00%
2017.12.27 22:36:46 4: AC: Pin 4 debug: adding 0 to long count 7752 and interpolated count 7752
2017.12.27 22:36:46 4: AC: Pin 4 (pin4) count 4 longCount 7752 interpCount 7752 (diff 0) in 360.000s, reject 0, Avg Len ms, result 0.000
2017.12.27 22:36:46 4: AC: interval 22:30:46 until 22:36:46, First at , Last at
2017.12.27 22:36:46 5: AC: set readings power4 to 0.000, timeDiff4 to 360000 and countDiff4 to 0
2017.12.27 22:36:46 5: AC: Device Time 8520.534, Drift -4.649s in 334353.330s, -0.00%
2017.12.27 22:38:46 4: AC: Pin 6 debug: adding 0 to long count 13112 and interpolated count 13112
2017.12.27 22:38:46 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.27 22:38:46 4: AC: interval 22:32:46 until 22:38:46, First at , Last at
2017.12.27 22:38:46 5: AC: set readings power6 to 0.000, timeDiff6 to 360000 and countDiff6 to 0
2017.12.27 22:38:46 5: AC: Device Time 8640.534, Drift -4.719s in 334473.260s, -0.00%
2017.12.27 22:42:45 4: AC: Pin 4 debug: adding 0 to long count 7752 and interpolated count 7752
2017.12.27 22:42:45 4: AC: Pin 4 (pin4) count 4 longCount 7752 interpCount 7752 (diff 0) in 360.000s, reject 0, Avg Len ms, result 0.000
2017.12.27 22:42:45 4: AC: interval 22:36:45 until 22:42:45, First at , Last at
2017.12.27 22:42:45 5: AC: set readings power4 to 0.000, timeDiff4 to 360000 and countDiff4 to 0
2017.12.27 22:42:45 5: AC: Device Time 8880.534, Drift -4.860s in 334713.119s, -0.00%
2017.12.27 22:44:45 4: AC: Pin 6 debug: adding 0 to long count 13112 and interpolated count 13112
2017.12.27 22:44:45 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.27 22:44:45 4: AC: interval 22:38:45 until 22:44:45, First at , Last at
2017.12.27 22:44:45 5: AC: set readings power6 to 0.000, timeDiff6 to 360000 and countDiff6 to 0
2017.12.27 22:44:45 5: AC: Device Time 9000.534, Drift -4.930s in 334833.049s, -0.00%
2017.12.27 22:48:45 4: AC: Pin 4 debug: adding 0 to long count 7752 and interpolated count 7752
2017.12.27 22:48:45 4: AC: Pin 4 (pin4) count 4 longCount 7752 interpCount 7752 (diff 0) in 360.000s, reject 2, Avg Len ms, result 0.000
2017.12.27 22:48:45 4: AC: interval 22:42:45 until 22:48:45, First at , Last at
2017.12.27 22:48:45 5: AC: set readings power4 to 0.000, timeDiff4 to 360000 and countDiff4 to 0
2017.12.27 22:48:45 5: AC: Device Time 9240.534, Drift -5.055s in 335072.925s, -0.00%
2017.12.27 22:50:45 4: AC: Pin 6 debug: adding 0 to long count 13112 and interpolated count 13112
2017.12.27 22:50:45 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.27 22:50:45 4: AC: interval 22:44:45 until 22:50:45, First at , Last at
2017.12.27 22:50:45 5: AC: set readings power6 to 0.000, timeDiff6 to 360000 and countDiff6 to 0
2017.12.27 22:50:45 5: AC: Device Time 9360.534, Drift -5.125s in 335192.855s, -0.00%
2017.12.27 22:53:45 4: AC: Pin 4 debug: adding 1 to long count 7752 and interpolated count 7752
2017.12.27 22:53:45 4: AC: Pin 4 (pin4) count 5 longCount 7753 interpCount 7753 (diff 1) in 242.745s, reject 0, Avg Len 169465ms, result 0.015
2017.12.27 22:53:45 4: AC: interval 22:49:42 until 22:53:45, First at 242745, Last at 242745
2017.12.27 22:53:45 5: AC: set readings power4 to 0.015, timeDiff4 to 242745 and countDiff4 to 1
2017.12.27 22:53:45 5: AC: Device Time 9540.534, Drift -5.230s in 335372.749s, -0.00%
2017.12.27 22:56:45 4: AC: Pin 6 debug: adding 0 to long count 13112 and interpolated count 13112
2017.12.27 22:56:45 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.27 22:56:45 4: AC: interval 22:50:45 until 22:56:45, First at , Last at
2017.12.27 22:56:45 5: AC: set readings power6 to 0.000, timeDiff6 to 360000 and countDiff6 to 0
2017.12.27 22:56:45 5: AC: Device Time 9720.534, Drift -5.336s in 335552.644s, -0.00%
2017.12.27 22:59:45 4: AC: Pin 4 debug: adding 0 to long count 7753 and interpolated count 7753
2017.12.27 22:59:45 4: AC: Pin 4 (pin4) count 5 longCount 7753 interpCount 7753 (diff 0) in 417.255s, reject 0, Avg Len ms, result 0.000
2017.12.27 22:59:45 4: AC: interval 22:52:48 until 22:59:45, First at , Last at
2017.12.27 22:59:45 5: AC: set readings power4 to 0.000, timeDiff4 to 417255 and countDiff4 to 0
2017.12.27 22:59:45 5: AC: Device Time 9900.534, Drift -5.441s in 335732.539s, -0.00%
2017.12.27 23:02:45 4: AC: Pin 6 debug: adding 0 to long count 13112 and interpolated count 13112
2017.12.27 23:02:45 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.27 23:02:45 4: AC: interval 22:56:45 until 23:02:45, First at , Last at
2017.12.27 23:02:45 5: AC: set readings power6 to 0.000, timeDiff6 to 360000 and countDiff6 to 0
2017.12.27 23:02:45 5: AC: Device Time 10080.534, Drift -5.530s in 335912.449s, -0.00%
2017.12.27 23:05:45 4: AC: Pin 4 debug: adding 0 to long count 7753 and interpolated count 7753
2017.12.27 23:05:45 4: AC: Pin 4 (pin4) count 5 longCount 7753 interpCount 7753 (diff 0) in 360.000s, reject 0, Avg Len ms, result 0.000
2017.12.27 23:05:45 4: AC: interval 22:59:45 until 23:05:45, First at , Last at
2017.12.27 23:05:45 5: AC: set readings power4 to 0.000, timeDiff4 to 360000 and countDiff4 to 0
2017.12.27 23:05:45 5: AC: Device Time 10260.534, Drift -5.636s in 336092.344s, -0.00%
2017.12.27 23:08:45 4: AC: Pin 6 debug: adding 0 to long count 13112 and interpolated count 13112
2017.12.27 23:08:45 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.27 23:08:45 4: AC: interval 23:02:45 until 23:08:45, First at , Last at
2017.12.27 23:08:45 5: AC: set readings power6 to 0.000, timeDiff6 to 360000 and countDiff6 to 0
2017.12.27 23:08:45 5: AC: Device Time 10440.534, Drift -5.741s in 336272.238s, -0.00%
2017.12.27 23:11:44 4: AC: Pin 4 debug: adding 0 to long count 7753 and interpolated count 7753
2017.12.27 23:11:44 4: AC: Pin 4 (pin4) count 5 longCount 7753 interpCount 7753 (diff 0) in 360.000s, reject 1, Avg Len ms, result 0.000
2017.12.27 23:11:44 4: AC: interval 23:05:44 until 23:11:44, First at , Last at
2017.12.27 23:11:44 5: AC: set readings power4 to 0.000, timeDiff4 to 360000 and countDiff4 to 0
2017.12.27 23:11:44 5: AC: Device Time 10620.534, Drift -5.846s in 336452.133s, -0.00%
2017.12.27 23:14:44 4: AC: Pin 6 debug: adding 0 to long count 13112 and interpolated count 13112
2017.12.27 23:14:44 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.27 23:14:44 4: AC: interval 23:08:44 until 23:14:44, First at , Last at
2017.12.27 23:14:44 5: AC: set readings power6 to 0.000, timeDiff6 to 360000 and countDiff6 to 0
2017.12.27 23:14:44 5: AC: Device Time 10800.534, Drift -5.952s in 336632.028s, -0.00%
2017.12.27 23:17:44 4: AC: Pin 4 debug: adding 0 to long count 7753 and interpolated count 7753
2017.12.27 23:17:44 4: AC: Pin 4 (pin4) count 5 longCount 7753 interpCount 7753 (diff 0) in 360.000s, reject 2, Avg Len ms, result 0.000
2017.12.27 23:17:44 4: AC: interval 23:11:44 until 23:17:44, First at , Last at
2017.12.27 23:17:44 5: AC: set readings power4 to 0.000, timeDiff4 to 360000 and countDiff4 to 0
2017.12.27 23:17:44 5: AC: Device Time 10980.534, Drift -6.057s in 336811.922s, -0.00%
2017.12.27 23:20:44 4: AC: Pin 6 debug: adding 0 to long count 13112 and interpolated count 13112
2017.12.27 23:20:44 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.27 23:20:44 4: AC: interval 23:14:44 until 23:20:44, First at , Last at
2017.12.27 23:20:44 5: AC: set readings power6 to 0.000, timeDiff6 to 360000 and countDiff6 to 0
2017.12.27 23:20:44 5: AC: Device Time 11160.534, Drift -6.147s in 336991.833s, -0.00%
2017.12.27 23:23:44 4: AC: Pin 4 debug: adding 0 to long count 7753 and interpolated count 7753
2017.12.27 23:23:44 4: AC: Pin 4 (pin4) count 5 longCount 7753 interpCount 7753 (diff 0) in 360.000s, reject 1, Avg Len ms, result 0.000
2017.12.27 23:23:44 4: AC: interval 23:17:44 until 23:23:44, First at , Last at
2017.12.27 23:23:44 5: AC: set readings power4 to 0.000, timeDiff4 to 360000 and countDiff4 to 0
2017.12.27 23:23:44 5: AC: Device Time 11340.534, Drift -6.252s in 337171.728s, -0.00%
2017.12.27 23:26:44 4: AC: Pin 6 debug: adding 0 to long count 13112 and interpolated count 13112
2017.12.27 23:26:44 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.27 23:26:44 4: AC: interval 23:20:44 until 23:26:44, First at , Last at
2017.12.27 23:26:44 5: AC: set readings power6 to 0.000, timeDiff6 to 360000 and countDiff6 to 0
2017.12.27 23:26:44 5: AC: Device Time 11520.534, Drift -6.357s in 337351.622s, -0.00%
2017.12.27 23:29:44 4: AC: Pin 4 debug: adding 0 to long count 7753 and interpolated count 7753
2017.12.27 23:29:44 4: AC: Pin 4 (pin4) count 5 longCount 7753 interpCount 7753 (diff 0) in 360.000s, reject 0, Avg Len ms, result 0.000
2017.12.27 23:29:44 4: AC: interval 23:23:44 until 23:29:44, First at , Last at
2017.12.27 23:29:44 5: AC: set readings power4 to 0.000, timeDiff4 to 360000 and countDiff4 to 0
2017.12.27 23:29:44 5: AC: Device Time 11700.534, Drift -6.463s in 337531.517s, -0.00%
2017.12.27 23:32:44 4: AC: Pin 6 debug: adding 0 to long count 13112 and interpolated count 13112
2017.12.27 23:32:44 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.27 23:32:44 4: AC: interval 23:26:44 until 23:32:44, First at , Last at
2017.12.27 23:32:44 5: AC: set readings power6 to 0.000, timeDiff6 to 360000 and countDiff6 to 0
2017.12.27 23:32:44 5: AC: Device Time 11880.534, Drift -6.568s in 337711.411s, -0.00%
2017.12.27 23:35:44 4: AC: Pin 4 debug: adding 0 to long count 7753 and interpolated count 7753
2017.12.27 23:35:44 4: AC: Pin 4 (pin4) count 5 longCount 7753 interpCount 7753 (diff 0) in 360.000s, reject 0, Avg Len ms, result 0.000
2017.12.27 23:35:44 4: AC: interval 23:29:44 until 23:35:44, First at , Last at
2017.12.27 23:35:44 5: AC: set readings power4 to 0.000, timeDiff4 to 360000 and countDiff4 to 0
2017.12.27 23:35:44 5: AC: Device Time 12060.534, Drift -6.674s in 337891.306s, -0.00%
2017.12.27 23:38:43 4: AC: Pin 6 debug: adding 0 to long count 13112 and interpolated count 13112
2017.12.27 23:38:43 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.27 23:38:43 4: AC: interval 23:32:43 until 23:38:43, First at , Last at
2017.12.27 23:38:43 5: AC: set readings power6 to 0.000, timeDiff6 to 360000 and countDiff6 to 0
2017.12.27 23:38:43 5: AC: Device Time 12240.534, Drift -6.763s in 338071.217s, -0.00%
2017.12.27 23:41:43 4: AC: Pin 4 debug: adding 1 to long count 7753 and interpolated count 7753
2017.12.27 23:41:43 4: AC: Pin 4 (pin4) count 6 longCount 7754 interpCount 7754 (diff 1) in 326.944s, reject 0, Avg Len 1196436ms, result 0.011
2017.12.27 23:41:43 4: AC: interval 23:36:16 until 23:41:43, First at 326944, Last at 326944
2017.12.27 23:41:43 5: AC: set readings power4 to 0.011, timeDiff4 to 326944 and countDiff4 to 1
2017.12.27 23:41:43 5: AC: Device Time 12420.534, Drift -6.868s in 338251.111s, -0.00%


Ich hoffe Du kannst mit diesen Angaben schon mal etwas anfangen.
Den zweiten Test mit der Version aus #252 werde ich in den nächsten Tagen durchführen.

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)

birdy

Hallo Stefan

Die in #252 enthaltene ArduCounter1.94.ino enthält die folgende Code Zeile

const char versionStr[] PROGMEM = "ArduCounter V1.93";

Ist das korrekt? Bevor ich weiter teste möchte ich sicherstellen, dass ich die richtige V1.94 verwende.

Ich wäre froh um einen Tipp bezüglich avrdude. Ich habe es noch nicht zu Stande gebracht den Arduino direkt zu flashen. Wenn das ginge, würde dies mir das weitere Testen deutlich vereinfachen.

flashing device as ArduCounter for AC
hex file: ./FHEM/firmware/ArduCounter.hex
port: /dev/ttyUSB0
log file: ./log/ArduCounterFlash.log
AC closed
command: avrdude -p atmega328P -c arduino -P /dev/ttyUSB0 -D -U flash:w:./FHEM/firmware/ArduCounter.hex 2>./log/ArduCounterFlash.log

--- AVRDUDE ---------------------------------------------------------------------------------
avrdude: stk500_getsync() attempt 1 of 10: not in sync: resp=0x1c
avrdude: stk500_getsync() attempt 2 of 10: not in sync: resp=0x00
avrdude: stk500_getsync() attempt 3 of 10: not in sync: resp=0xe0
avrdude: stk500_getsync() attempt 4 of 10: not in sync: resp=0x00
avrdude: stk500_getsync() attempt 5 of 10: not in sync: resp=0x00
avrdude: stk500_getsync() attempt 6 of 10: not in sync: resp=0x1c
avrdude: stk500_getsync() attempt 7 of 10: not in sync: resp=0xe0
avrdude: stk500_getsync() attempt 8 of 10: not in sync: resp=0x1c
avrdude: stk500_getsync() attempt 9 of 10: not in sync: resp=0xe0
avrdude: stk500_getsync() attempt 10 of 10: not in sync: resp=0x1c

avrdude done.  Thank you.

--- AVRDUDE ---------------------------------------------------------------------------------

AC opened



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)