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,

Was steht denn in der Zeile in Deiner fhem.pl?
Ich habe vermutlich nicht die gleiche Version und kann es daher nicht prüfen.

Gruss
   Stefan

BillyPbg

Hallo Stefan,

Version:
fhem.pl:13700/2017-03-14 (Siehe Anhang)

VG.
BillyPbg

StefanStrobel

In 4147 wird die Addition für monotonic gemacht ...
Die Meldung kommt also von Deinem Userreading.

Gruß
    Stefan

BillyPbg


Hallo Stefan,

wenn kein Fehler seitens des Arduino gemeldet wird, schickt line 580 'Nichts' rüber, was zu meiner Fehlermeldung beim 'Monotonic' führt:
Ich hab's nun für mich wie folgt gelöst:
          'reject3Summe:reject3:.* monotonic {my $a=ReadingsVal ($name, "reject3", 0); $a =~ /([\d])/g?$a:0}'

... eleganter aber wäre wohl, wenn Du in Line 580 die NULL für diesen Fall setzen könntest...  ;)
         'my $reject = ($6 ? substr($6, 2) : 0);'

VG.
BillyPbg

StefanStrobel

Wenn der Arduino keine Rejects hat, sollte er X0 melden.
Ich frage mich woher Meldungen ohne X kommen. Evt. sind das Reste im seriellen Puffer - zumindest bekomme ich nach dem Reset des Arduino gelegentlich erst mal eine Müll-Zeile.
In so einem Fall wäre es aber vermutlich sinnvoller, gar kein Reading zu setzen statt einer 0.
Kannst Du den Fall mal mit verbose 5 protokollieren, so dass ich die Kommunikation zwischen Ardiuno und Fhem sehe wenn Dein Problem auftritt?

Gruss / Thanx
    Stefan

BillyPbg


BillyPbg

#141
Zitat von: StefanStrobel am 20 März 2017, 23:12:01
Wenn der Arduino keine Rejects hat, sollte er X0 melden.
Ich frage mich woher Meldungen ohne X kommen. Evt. sind das Reste im seriellen Puffer - zumindest bekomme ich nach dem Reset des Arduino gelegentlich erst mal eine Müll-Zeile.
In so einem Fall wäre es aber vermutlich sinnvoller, gar kein Reading zu setzen statt einer 0.
Kannst Du den Fall mal mit verbose 5 protokollieren, so dass ich die Kommunikation zwischen Ardiuno und Fhem sehe wenn Dein Problem auftritt?
...

LOG-Auszüge, jeweils in der Restart-Phase:

...
2017.03.21 05:59:23.922 5: TEST: Notify called with events: INITIALIZED DEFINED Melde_Anrufer_A, open device and set timer to send hello to device
2017.03.21 05:59:23.923 3: Opening TEST device /dev/serial/by-path/platform-3f980000.usb-usb-0:1.2.2:1.0-port0
2017.03.21 05:59:23.931 3: Setting TEST serial parameters to 38400,8,N,1
2017.03.21 05:59:23.947 3: TEST device opened
2017.03.21 05:59:24.846 0: Featurelevel: 5.8
2017.03.21 05:59:24.846 0: Server started with 759 defined entities (fhem.pl:13733/2017-03-19 perl:5.020002 os:linux user:fhem pid:9092)
...
2017.03.21 05:59:55.002 3: TEST: sending h(ello) to device to ask for version
2017.03.21 05:59:55.003 5: SW: h
...
2017.03.21 05:59:59.057 4: TEST: Pin 3 (pin3) count 46145 (diff 1) in 58.538s, result 0.061
2017.03.21 05:59:59.057 5: TEST: interval 05:59:00 until 05:59:59
2017.03.21 05:59:59.058 5: TEST: readingStartTime3 specified: setting reading timestamp to 05:59:00
2017.03.21 05:59:59.058 5: TEST: set readings power3 to 0.061, timeDiff3 to 58538 and countDiff3 to 1
2017.03.21 05:59:59.060 1: PERL WARNING: "" isn't numeric in numeric gt (>) at fhem.pl line 4147.
2017.03.21 05:59:59.161 5: TEST: Initialize clock offset to 1489485938.45901
2017.03.21 05:59:59.162 5: TEST: Device Time 586460.598, Drift 0.000s in 0.000s
2017.03.21 05:59:59.162 3: TEST: device is still counting
2017.03.21 05:59:59.163 3: TEST: device replied to hello, V1.8
2017.03.21 05:59:59.163 4: TEST: device reported firmware 1.8
2017.03.21 05:59:59.164 3: TEST: ConfigureDevice calls Attr with interval 15 1200 5 1
2017.03.21 05:59:59.164 5: SW: 15,1200,5,1i

2017.03.21 05:59:59.166 3: TEST: ConfigureDevice calls Attr with pinD3 falling pullup 89
2017.03.21 05:59:59.166 5: SW: 3,2,1,89a

...
2017.03.21 06:00:04.414 3: TEST: device: intervals set to 15 1200 5 1
2017.03.21 06:00:04.414 3: TEST: device: defined pin 3 PCInt pin 19, iMode change, min len 89 ms falling, count 46147 (+1) in 49050 ms Rej 0
2017.03.21 06:00:04.415 3: TEST: device:   first at 37299, last at 37299, avg len 89
2017.03.21 06:00:04.415 4: TEST: Pin 3 (pin3) count 46147 (diff 1) in 49.050s, reject 0, result 0.073
2017.03.21 06:00:04.416 5: TEST: interval 05:59:15 until 06:00:04, First at 49050
2017.03.21 06:00:04.416 5: TEST: readingStartTime3 specified: setting reading timestamp to 05:59:15
2017.03.21 06:00:04.416 5: TEST: set readings power3 to 0.073, timeDiff3 to 49050 and countDiff3 to 1
2017.03.21 06:00:04.519 5: TEST: Device Time 586565.598, Drift -99.642s in 5.358s, -1859.51%
...



...
2017.03.21 09:36:30.216 5: TEST: Notify called with events: INITIALIZED DEFINED Melde_Anrufer_A, open device and set timer to send hello to device
2017.03.21 09:36:30.216 3: Opening TEST device /dev/serial/by-path/platform-3f980000.usb-usb-0:1.2.2:1.0-port0
2017.03.21 09:36:30.224 3: Setting TEST serial parameters to 38400,8,N,1
2017.03.21 09:36:30.241 3: TEST device opened
2017.03.21 09:36:31.144 0: Featurelevel: 5.8
2017.03.21 09:36:31.144 0: Server started with 759 defined entities (fhem.pl:13733/2017-03-19 perl:5.020002 os:linux user:fhem pid:4649)
2017.03.21 09:37:02.669 3: TEST: sending h(ello) to device to ask for version
2017.03.21 09:37:02.669 5: SW: h

2017.03.21 09:37:03.942 4: TEST: Pin 3 (pin3) count 46724 (diff 1) in 15.085s, result 0.239
2017.03.21 09:37:03.942 5: TEST: interval 09:36:48 until 09:37:03
2017.03.21 09:37:03.942 5: TEST: readingStartTime3 specified: setting reading timestamp to 09:36:48
2017.03.21 09:37:03.943 5: TEST: set readings power3 to 0.239, timeDiff3 to 15085 and countDiff3 to 1
2017.03.21 09:37:03.945 1: PERL WARNING: Argument "" isn't numeric in numeric gt (>) at fhem.pl line 4147.
2017.03.21 09:37:04.050 5: TEST: Initialize clock offset to 1489485928.34374
2017.03.21 09:37:04.051 5: TEST: Device Time 599495.598, Drift 0.000s in 0.000s
2017.03.21 09:37:04.051 3: TEST: device is still counting
2017.03.21 09:37:04.053 4: TEST: Pin 3 (pin3) count 46730 (diff 1) in 15.436s, reject 0, result 0.233
2017.03.21 09:37:04.053 5: TEST: interval 09:36:48 until 09:37:04, First at 15436
2017.03.21 09:37:04.053 5: TEST: readingStartTime3 specified: setting reading timestamp to 09:36:48
2017.03.21 09:37:04.053 5: TEST: set readings power3 to 0.233, timeDiff3 to 15436 and countDiff3 to 1
2017.03.21 09:37:04.157 5: TEST: Device Time 599585.598, Drift -89.889s in 0.111s, -80997.06%
2017.03.21 09:37:04.158 3: TEST: device is still counting
2017.03.21 09:37:04.159 3: TEST: device replied to hello, V1.8
2017.03.21 09:37:04.159 4: TEST: device reported firmware 1.8
2017.03.21 09:37:04.160 3: TEST: ConfigureDevice calls Attr with interval 15 1200 5 1
2017.03.21 09:37:04.160 5: SW: 15,1200,5,1i

2017.03.21 09:37:04.162 3: TEST: ConfigureDevice calls Attr with pinD3 falling pullup 89
2017.03.21 09:37:04.162 5: SW: 3,2,1,89a

2017.03.21 09:37:09.438 3: TEST: device: intervals set to 15 1200 5 1
2017.03.21 09:37:09.438 3: TEST: device: defined pin 3 PCInt pin 19, iMode change, min len 89 ms falling, count 46730 (+0) in 7074 ms Rej 0
2017.03.21 09:37:16.449 4: TEST: Pin 3 (pin3) count 46731 (diff 1) in 15.467s, reject 0, result 0.233
2017.03.21 09:37:16.450 5: TEST: interval 09:37:00 until 09:37:16, First at 15467
2017.03.21 09:37:16.450 5: TEST: readingStartTime3 specified: setting reading timestamp to 09:37:00
2017.03.21 09:37:16.450 5: TEST: set readings power3 to 0.233, timeDiff3 to 15467 and countDiff3 to 1
2017.03.21 09:37:16.563 5: TEST: Device Time 599600.598, Drift -92.493s in 12.507s, -739.51%
2017.03.21 09:37:33.155 4: TEST: Pin 3 (pin3) count 46732 (diff 1) in 15.604s, reject 0, result 0.231
2017.03.21 09:37:33.156 5: TEST: interval 09:37:17 until 09:37:33, First at 15604
2017.03.21 09:37:33.156 5: TEST: readingStartTime3 specified: setting reading timestamp to 09:37:17
2017.03.21 09:37:33.156 5: TEST: set readings power3 to 0.231, timeDiff3 to 15604 and countDiff3 to 1
2017.03.21 09:37:33.261 5: TEST: Device Time 599615.598, Drift -90.786s in 29.214s, -310.77%
2017.03.21 09:38:16.869 4: TEST: Pin 3 (pin3) count 46733 (diff 1) in 15.684s, reject 0, result 0.230
2017.03.21 09:38:16.869 5: TEST: interval 09:38:01 until 09:38:16, First at 15684
2017.03.21 09:38:16.870 5: TEST: readingStartTime3 specified: setting reading timestamp to 09:38:01
2017.03.21 09:38:16.870 5: TEST: set readings power3 to 0.230, timeDiff3 to 15684 and countDiff3 to 1
2017.03.21 09:38:16.975 5: TEST: Device Time 599630.598, Drift -62.073s in 72.927s, -85.12%
2017.03.21 09:38:16.976 4: TEST: Pin 3 (pin3) count 46734 (diff 1) in 15.770s, reject 0, result 0.228
2017.03.21 09:38:16.977 5: TEST: interval 09:38:01 until 09:38:16, First at 15770
2017.03.21 09:38:16.977 5: TEST: readingStartTime3 specified: setting reading timestamp to 09:38:01
2017.03.21 09:38:16.977 5: TEST: set readings power3 to 0.228, timeDiff3 to 15770 and countDiff3 to 1
2017.03.21 09:38:17.083 5: TEST: Device Time 599645.598, Drift -76.965s in 73.035s, -105.38%
2017.03.21 09:38:30.269 4: TEST: Pin 3 (pin3) count 46735 (diff 1) in 15.816s, reject 0, result 0.228
2017.03.21 09:38:30.269 5: TEST: interval 09:38:14 until 09:38:30, First at 15816
2017.03.21 09:38:30.270 5: TEST: readingStartTime3 specified: setting reading timestamp to 09:38:14
2017.03.21 09:38:30.270 5: TEST: set readings power3 to 0.228, timeDiff3 to 15816 and countDiff3 to 1
2017.03.21 09:38:30.374 5: TEST: Device Time 599675.598, Drift -93.673s in 86.327s, -108.51%
2017.03.21 09:38:45.090 4: TEST: Pin 3 (pin3) count 46736 (diff 1) in 15.828s, reject 0, result 0.227
2017.03.21 09:38:45.090 5: TEST: interval 09:38:29 until 09:38:45, First at 15828
2017.03.21 09:38:45.091 5: TEST: readingStartTime3 specified: setting reading timestamp to 09:38:29
2017.03.21 09:38:45.091 5: TEST: set readings power3 to 0.227, timeDiff3 to 15828 and countDiff3 to 1
2017.03.21 09:38:45.205 5: TEST: Device Time 599690.598, Drift -93.852s in 101.148s, -92.79%
2017.03.21 09:39:00.145 4: TEST: Pin 3 (pin3) count 46737 (diff 1) in 15.827s, reject 0, result 0.227
2017.03.21 09:39:00.145 5: TEST: interval 09:38:44 until 09:39:00, First at 15827
2017.03.21 09:39:00.146 5: TEST: readingStartTime3 specified: setting reading timestamp to 09:38:44
2017.03.21 09:39:00.146 5: TEST: set readings power3 to 0.227, timeDiff3 to 15827 and countDiff3 to 1
2017.03.21 09:39:00.257 5: TEST: Device Time 599705.598, Drift -93.797s in 116.203s, -80.72%
2017.03.21 09:39:15.085 4: TEST: Pin 3 (pin3) count 46738 (diff 1) in 15.669s, reject 0, result 0.230
2017.03.21 09:39:15.086 5: TEST: interval 09:38:59 until 09:39:15, First at 15669
2017.03.21 09:39:15.086 5: TEST: readingStartTime3 specified: setting reading timestamp to 09:38:59
2017.03.21 09:39:15.087 5: TEST: set readings power3 to 0.230, timeDiff3 to 15669 and countDiff3 to 1
2017.03.21 09:39:15.199 5: TEST: Device Time 599720.598, Drift -93.856s in 131.144s, -71.57%
2017.03.21 09:39:30.082 4: TEST: Pin 3 (pin3) count 46739 (diff 1) in 15.524s, reject 0, result 0.232
2017.03.21 09:39:30.083 5: TEST: interval 09:39:14 until 09:39:30, First at 15524
2017.03.21 09:39:30.083 5: TEST: readingStartTime3 specified: setting reading timestamp to 09:39:14
2017.03.21 09:39:30.084 5: TEST: set readings power3 to 0.232, timeDiff3 to 15524 and countDiff3 to 1
2017.03.21 09:39:30.204 5: TEST: Device Time 599735.598, Drift -93.859s in 146.141s, -64.23%
2017.03.21 09:39:45.082 4: TEST: Pin 3 (pin3) count 46740 (diff 1) in 15.123s, reject 0, result 0.238
2017.03.21 09:39:45.083 5: TEST: interval 09:39:29 until 09:39:45, First at 15123
2017.03.21 09:39:45.083 5: TEST: readingStartTime3 specified: setting reading timestamp to 09:39:29
2017.03.21 09:39:45.083 5: TEST: set readings power3 to 0.238, timeDiff3 to 15123 and countDiff3 to 1
2017.03.21 09:39:45.221 5: TEST: Device Time 599750.598, Drift -93.860s in 161.140s, -58.25%
2017.03.21 09:40:00.078 4: TEST: Pin 3 (pin3) count 46741 (diff 1) in 15.149s, reject 0, result 0.238
2017.03.21 09:40:00.079 5: TEST: interval 09:39:44 until 09:40:00, First at 15149
2017.03.21 09:40:00.079 5: TEST: readingStartTime3 specified: setting reading timestamp to 09:39:44
2017.03.21 09:40:00.080 5: TEST: set readings power3 to 0.238, timeDiff3 to 15149 and countDiff3 to 1
2017.03.21 09:40:00.210 5: TEST: Device Time 599765.598, Drift -93.863s in 176.137s, -53.29%
2017.03.21 09:40:15.077 4: TEST: Pin 3 (pin3) count 46742 (diff 1) in 15.146s, reject 0, result 0.238
2017.03.21 09:40:15.078 5: TEST: interval 09:39:59 until 09:40:15, First at 15146
2017.03.21 09:40:15.079 5: TEST: readingStartTime3 specified: setting reading timestamp to 09:39:59
2017.03.21 09:40:15.079 5: TEST: set readings power3 to 0.238, timeDiff3 to 15146 and countDiff3 to 1
2017.03.21 09:40:15.191 5: TEST: Device Time 599780.598, Drift -93.864s in 191.136s, -49.11%
2017.03.21 09:40:30.074 4: TEST: Pin 3 (pin3) count 46743 (diff 1) in 15.209s, reject 0, result 0.237
2017.03.21 09:40:30.074 5: TEST: interval 09:40:14 until 09:40:30, First at 15209
2017.03.21 09:40:30.075 5: TEST: readingStartTime3 specified: setting reading timestamp to 09:40:14
2017.03.21 09:40:30.075 5: TEST: set readings power3 to 0.237, timeDiff3 to 15209 and countDiff3 to 1
2017.03.21 09:40:30.187 5: TEST: Device Time 599795.598, Drift -93.868s in 206.132s, -45.54%
2017.03.21 09:40:45.072 4: TEST: Pin 3 (pin3) count 46744 (diff 1) in 15.239s, reject 0, result 0.236
2017.03.21 09:40:45.072 5: TEST: interval 09:40:29 until 09:40:45, First at 15239
2017.03.21 09:40:45.073 5: TEST: readingStartTime3 specified: setting reading timestamp to 09:40:29
2017.03.21 09:40:45.073 5: TEST: set readings power3 to 0.236, timeDiff3 to 15239 and countDiff3 to 1
2017.03.21 09:40:45.179 5: TEST: Device Time 599810.598, Drift -93.870s in 221.130s, -42.45%
2017.03.21 09:41:00.069 4: TEST: Pin 3 (pin3) count 46745 (diff 1) in 15.167s, reject 0, result 0.237
2017.03.21 09:41:00.070 5: TEST: interval 09:40:44 until 09:41:00, First at 15167
2017.03.21 09:41:00.070 5: TEST: readingStartTime3 specified: setting reading timestamp to 09:40:44
2017.03.21 09:41:00.071 5: TEST: set readings power3 to 0.237, timeDiff3 to 15167 and countDiff3 to 1
2017.03.21 09:41:00.181 5: TEST: Device Time 599825.598, Drift -93.873s in 236.127s, -39.76%
2017.03.21 09:41:15.066 4: TEST: Pin 3 (pin3) count 46746 (diff 1) in 15.226s, reject 0, result 0.236
2017.03.21 09:41:15.067 5: TEST: interval 09:40:59 until 09:41:15, First at 15226
2017.03.21 09:41:15.067 5: TEST: readingStartTime3 specified: setting reading timestamp to 09:40:59
2017.03.21 09:41:15.068 5: TEST: set readings power3 to 0.236, timeDiff3 to 15226 and countDiff3 to 1
2017.03.21 09:41:15.178 5: TEST: Device Time 599840.598, Drift -93.875s in 251.125s, -37.38%
2017.03.21 09:41:30.068 4: TEST: Pin 3 (pin3) count 46747 (diff 1) in 15.211s, reject 0, result 0.237
2017.03.21 09:41:30.069 5: TEST: interval 09:41:14 until 09:41:30, First at 15211
2017.03.21 09:41:30.069 5: TEST: readingStartTime3 specified: setting reading timestamp to 09:41:14
2017.03.21 09:41:30.069 5: TEST: set readings power3 to 0.237, timeDiff3 to 15211 and countDiff3 to 1
2017.03.21 09:41:30.174 5: TEST: Device Time 599855.598, Drift -93.873s in 266.127s, -35.27%
2017.03.21 09:41:45.061 4: TEST: Pin 3 (pin3) count 46748 (diff 1) in 15.245s, reject 0, result 0.236
2017.03.21 09:41:45.062 5: TEST: interval 09:41:29 until 09:41:45, First at 15245
2017.03.21 09:41:45.062 5: TEST: readingStartTime3 specified: setting reading timestamp to 09:41:29
2017.03.21 09:41:45.062 5: TEST: set readings power3 to 0.236, timeDiff3 to 15245 and countDiff3 to 1
2017.03.21 09:41:45.166 5: TEST: Device Time 599870.598, Drift -93.880s in 281.120s, -33.40%
2017.03.21 09:42:00.060 4: TEST: Pin 3 (pin3) count 46749 (diff 1) in 15.058s, reject 0, result 0.239
2017.03.21 09:42:00.061 5: TEST: interval 09:41:45 until 09:42:00, First at 15058
2017.03.21 09:42:00.061 5: TEST: readingStartTime3 specified: setting reading timestamp to 09:41:45
2017.03.21 09:42:00.062 5: TEST: set readings power3 to 0.239, timeDiff3 to 15058 and countDiff3 to 1
2017.03.21 09:42:00.172 5: TEST: Device Time 599885.598, Drift -93.881s in 296.119s, -31.70%
2017.03.21 09:42:15.095 4: TEST: Pin 3 (pin3) count 46750 (diff 1) in 15.057s, reject 0, result 0.239
2017.03.21 09:42:15.096 5: TEST: interval 09:42:00 until 09:42:15, First at 15057
2017.03.21 09:42:15.096 5: TEST: readingStartTime3 specified: setting reading timestamp to 09:42:00
2017.03.21 09:42:15.097 5: TEST: set readings power3 to 0.239, timeDiff3 to 15057 and countDiff3 to 1
2017.03.21 09:42:15.222 5: TEST: Device Time 599900.598, Drift -93.847s in 311.153s, -30.16%
2017.03.21 09:42:30.056 4: TEST: Pin 3 (pin3) count 46751 (diff 1) in 15.105s, reject 0, result 0.238
2017.03.21 09:42:30.056 5: TEST: interval 09:42:14 until 09:42:30, First at 15105
2017.03.21 09:42:30.056 5: TEST: readingStartTime3 specified: setting reading timestamp to 09:42:14
2017.03.21 09:42:30.057 5: TEST: set readings power3 to 0.238, timeDiff3 to 15105 and countDiff3 to 1
2017.03.21 09:42:30.183 5: TEST: Device Time 599915.598, Drift -93.886s in 326.114s, -28.79%
2017.03.21 09:42:45.053 4: TEST: Pin 3 (pin3) count 46752 (diff 1) in 15.114s, reject 0, result 0.238
2017.03.21 09:42:45.054 5: TEST: interval 09:42:29 until 09:42:45, First at 15114
2017.03.21 09:42:45.054 5: TEST: readingStartTime3 specified: setting reading timestamp to 09:42:29
2017.03.21 09:42:45.054 5: TEST: set readings power3 to 0.238, timeDiff3 to 15114 and countDiff3 to 1
2017.03.21 09:42:45.182 5: TEST: Device Time 599930.598, Drift -93.889s in 341.111s, -27.52%
2017.03.21 09:43:00.051 4: TEST: Pin 3 (pin3) count 46753 (diff 1) in 15.228s, reject 0, result 0.236
2017.03.21 09:43:00.051 5: TEST: interval 09:42:44 until 09:43:00, First at 15228
2017.03.21 09:43:00.052 5: TEST: readingStartTime3 specified: setting reading timestamp to 09:42:44
2017.03.21 09:43:00.052 5: TEST: set readings power3 to 0.236, timeDiff3 to 15228 and countDiff3 to 1
2017.03.21 09:43:00.157 5: TEST: Device Time 599945.598, Drift -93.891s in 356.109s, -26.37%
2017.03.21 09:43:15.048 4: TEST: Pin 3 (pin3) count 46754 (diff 1) in 15.263s, reject 0, result 0.236
2017.03.21 09:43:15.049 5: TEST: interval 09:42:59 until 09:43:15, First at 15263
2017.03.21 09:43:15.049 5: TEST: readingStartTime3 specified: setting reading timestamp to 09:42:59
2017.03.21 09:43:15.050 5: TEST: set readings power3 to 0.236, timeDiff3 to 15263 and countDiff3 to 1
2017.03.21 09:43:15.160 5: TEST: Device Time 599960.598, Drift -93.893s in 371.107s, -25.30%
2017.03.21 09:43:30.046 4: TEST: Pin 3 (pin3) count 46755 (diff 1) in 15.295s, reject 0, result 0.235
2017.03.21 09:43:30.046 5: TEST: interval 09:43:14 until 09:43:30, First at 15295
2017.03.21 09:43:30.046 5: TEST: readingStartTime3 specified: setting reading timestamp to 09:43:14
2017.03.21 09:43:30.047 5: TEST: set readings power3 to 0.235, timeDiff3 to 15295 and countDiff3 to 1
2017.03.21 09:43:30.158 5: TEST: Device Time 599975.598, Drift -93.896s in 386.104s, -24.32%
...



...
2017.03.21 09:52:10.159 5: TEST: Notify called with events: INITIALIZED DEFINED Melde_Anrufer_A, open device and set timer to send hello to device
2017.03.21 09:52:10.184 3: TEST device opened
2017.03.21 09:52:11.210 0: Featurelevel: 5.8
2017.03.21 09:52:11.211 0: Server started with 759 defined entities (fhem.pl:13733/2017-03-19 perl:5.020002 os:linux user:fhem pid:6421)
---
2017.03.21 09:52:16.497 4: TEST: Pin 3 (pin3) count 46780 (diff 1) in 24.998s, result 0.144
2017.03.21 09:52:16.497 5: TEST: interval 09:51:51 until 09:52:16
2017.03.21 09:52:16.498 5: TEST: readingStartTime3 specified: setting reading timestamp to 09:51:51
2017.03.21 09:52:16.499 5: TEST: set readings power3 to 0.144, timeDiff3 to 24998 and countDiff3 to 1
2017.03.21 09:52:16.502 1: PERL WARNING: Argument "" isn't numeric in numeric gt (>) at fhem.pl line 4147.
2017.03.21 09:52:16.637 5: TEST: Initialize clock offset to 1489485895.89887
2017.03.21 09:52:16.637 5: TEST: Device Time 600440.598, Drift 0.000s in 0.000s
2017.03.21 09:52:16.638 3: TEST: device is still counting
2017.03.21 09:52:16.638 3: TEST: sending h(ello) to device to ask for version
2017.03.21 09:52:16.638 5: SW: h

...
2017.03.21 09:52:44.798 3: TEST: device didn't reply to h(ello). Is the right sketch flashed? Is speed set to 38400?
...
2017.03.21 09:52:49.934 3: TEST: device replied to hello, V1.8
2017.03.21 09:52:49.934 4: TEST: device reported firmware 1.8
2017.03.21 09:52:49.934 3: TEST: ConfigureDevice calls Attr with interval 15 1200 5 1
2017.03.21 09:52:49.935 5: SW: 15,1200,5,1i

2017.03.21 09:52:49.937 3: TEST: ConfigureDevice calls Attr with pinD3 falling pullup 89
2017.03.21 09:52:49.937 5: SW: 3,2,1,89a

2017.03.21 09:52:49.943 4: TEST: Pin 3 (pin3) count 46783 (diff 1) in 26.369s, reject 0, result 0.137
2017.03.21 09:52:49.943 5: TEST: interval 09:52:23 until 09:52:49, First at 26369
2017.03.21 09:52:49.943 5: TEST: readingStartTime3 specified: setting reading timestamp to 09:52:23
2017.03.21 09:52:49.943 5: TEST: set readings power3 to 0.137, timeDiff3 to 26369 and countDiff3 to 1
2017.03.21 09:52:50.050 5: TEST: Device Time 600515.598, Drift -41.554s in 33.446s, -124.24%
...
2017.03.21 09:52:50.524 3: TEST: device: intervals set to 15 1200 5 1
2017.03.21 09:52:50.524 3: TEST: device: defined pin 3 PCInt pin 19, iMode change, min len 89 ms falling, count 46783 (+0) in 22827 ms Rej 0
...
2017.03.21 09:53:03.191 4: TEST: Pin 3 (pin3) count 46784 (diff 1) in 26.360s, reject 0, result 0.137
2017.03.21 09:53:03.192 5: TEST: interval 09:52:36 until 09:53:03, First at 26360
2017.03.21 09:53:03.192 5: TEST: readingStartTime3 specified: setting reading timestamp to 09:52:36
2017.03.21 09:53:03.192 5: TEST: set readings power3 to 0.137, timeDiff3 to 26360 and countDiff3 to 1
2017.03.21 09:53:03.304 5: TEST: Device Time 600545.598, Drift -58.306s in 46.694s, -124.87%
...
2017.03.21 09:54:03.108 4: TEST: Pin 3 (pin3) count 46785 (diff 1) in 30.463s, reject 0, result 0.118
2017.03.21 09:54:03.108 5: TEST: interval 09:53:32 until 09:54:03, First at 30463
2017.03.21 09:54:03.109 5: TEST: readingStartTime3 specified: setting reading timestamp to 09:53:32
2017.03.21 09:54:03.109 5: TEST: set readings power3 to 0.118, timeDiff3 to 30463 and countDiff3 to 1
2017.03.21 09:54:03.227 5: TEST: Device Time 600575.598, Drift -28.389s in 106.611s, -26.63%
2017.03.21 09:54:03.228 4: TEST: Pin 3 (pin3) count 46786 (diff 1) in 33.255s, reject 0, result 0.108
2017.03.21 09:54:03.228 5: TEST: interval 09:53:29 until 09:54:03, First at 33255
2017.03.21 09:54:03.229 5: TEST: readingStartTime3 specified: setting reading timestamp to 09:53:29
2017.03.21 09:54:03.229 5: TEST: set readings power3 to 0.108, timeDiff3 to 33255 and countDiff3 to 1
2017.03.21 09:54:03.338 5: TEST: Device Time 600605.598, Drift -58.269s in 106.731s, -54.59%
...
2017.03.21 09:54:44.941 4: TEST: Pin 3 (pin3) count 46787 (diff 1) in 34.299s, reject 0, result 0.105
2017.03.21 09:54:44.942 5: TEST: interval 09:54:10 until 09:54:44, First at 34299
2017.03.21 09:54:44.942 5: TEST: readingStartTime3 specified: setting reading timestamp to 09:54:10
2017.03.21 09:54:44.942 5: TEST: set readings power3 to 0.105, timeDiff3 to 34299 and countDiff3 to 1
2017.03.21 09:54:45.048 5: TEST: Device Time 600650.598, Drift -61.555s in 148.445s, -41.47%
2017.03.21 09:55:14.937 4: TEST: Pin 3 (pin3) count 46788 (diff 1) in 34.470s, reject 0, result 0.104
2017.03.21 09:55:14.937 5: TEST: interval 09:54:40 until 09:55:14, First at 34470
2017.03.21 09:55:14.938 5: TEST: readingStartTime3 specified: setting reading timestamp to 09:54:40
2017.03.21 09:55:14.938 5: TEST: set readings power3 to 0.104, timeDiff3 to 34470 and countDiff3 to 1
2017.03.21 09:55:15.044 5: TEST: Device Time 600680.598, Drift -61.560s in 178.440s, -34.50%
2017.03.21 09:55:44.942 4: TEST: Pin 3 (pin3) count 46789 (diff 1) in 31.259s, reject 0, result 0.115
2017.03.21 09:55:44.943 5: TEST: interval 09:55:13 until 09:55:44, First at 31259
2017.03.21 09:55:44.943 5: TEST: readingStartTime3 specified: setting reading timestamp to 09:55:13
2017.03.21 09:55:44.943 5: TEST: set readings power3 to 0.115, timeDiff3 to 31259 and countDiff3 to 1
2017.03.21 09:55:45.059 5: TEST: Device Time 600710.598, Drift -61.555s in 208.445s, -29.53%
2017.03.21 09:56:14.927 4: TEST: Pin 3 (pin3) count 46790 (diff 1) in 30.826s, reject 0, result 0.117
2017.03.21 09:56:14.927 5: TEST: interval 09:55:44 until 09:56:14, First at 30826
2017.03.21 09:56:14.928 5: TEST: readingStartTime3 specified: setting reading timestamp to 09:55:44
2017.03.21 09:56:14.928 5: TEST: set readings power3 to 0.117, timeDiff3 to 30826 and countDiff3 to 1
2017.03.21 09:56:15.033 5: TEST: Device Time 600740.598, Drift -61.570s in 238.430s, -25.82%
2017.03.21 09:56:44.923 4: TEST: Pin 3 (pin3) count 46791 (diff 1) in 30.922s, reject 0, result 0.116
2017.03.21 09:56:44.924 5: TEST: interval 09:56:14 until 09:56:44, First at 30922
2017.03.21 09:56:44.924 5: TEST: readingStartTime3 specified: setting reading timestamp to 09:56:14
2017.03.21 09:56:44.924 5: TEST: set readings power3 to 0.116, timeDiff3 to 30922 and countDiff3 to 1
2017.03.21 09:56:45.028 5: TEST: Device Time 600770.598, Drift -61.574s in 268.426s, -22.94%
2017.03.21 09:57:15.330 4: TEST: Pin 3 (pin3) count 46792 (diff 1) in 30.977s, reject 0, result 0.116
2017.03.21 09:57:15.330 5: TEST: interval 09:56:44 until 09:57:15, First at 30977
2017.03.21 09:57:15.331 5: TEST: readingStartTime3 specified: setting reading timestamp to 09:56:44
2017.03.21 09:57:15.331 5: TEST: set readings power3 to 0.116, timeDiff3 to 30977 and countDiff3 to 1
2017.03.21 09:57:15.459 5: TEST: Device Time 600800.598, Drift -61.168s in 298.832s, -20.47%
2017.03.21 09:57:45.772 4: TEST: Pin 3 (pin3) count 46793 (diff 1) in 30.706s, reject 0, result 0.117
2017.03.21 09:57:45.773 5: TEST: interval 09:57:15 until 09:57:45, First at 30706
2017.03.21 09:57:45.773 5: TEST: readingStartTime3 specified: setting reading timestamp to 09:57:15
2017.03.21 09:57:45.773 5: TEST: set readings power3 to 0.117, timeDiff3 to 30706 and countDiff3 to 1
2017.03.21 09:57:45.882 5: TEST: Device Time 600830.598, Drift -60.725s in 329.275s, -18.44%
2017.03.21 09:57:59.911 4: TEST: Pin 3 (pin3) count 46794 (diff 1) in 16.151s, reject 0, result 0.223
2017.03.21 09:57:59.912 5: TEST: interval 09:57:43 until 09:57:59, First at 16151
2017.03.21 09:57:59.913 5: TEST: readingStartTime3 specified: setting reading timestamp to 09:57:43
2017.03.21 09:57:59.913 5: TEST: set readings power3 to 0.223, timeDiff3 to 16151 and countDiff3 to 1
2017.03.21 09:58:00.027 5: TEST: Device Time 600845.598, Drift -61.585s in 343.415s, -17.93%
2017.03.21 09:58:14.949 4: TEST: Pin 3 (pin3) count 46795 (diff 1) in 15.812s, reject 0, result 0.228
2017.03.21 09:58:14.950 5: TEST: interval 09:57:59 until 09:58:14, First at 15812
2017.03.21 09:58:14.950 5: TEST: readingStartTime3 specified: setting reading timestamp to 09:57:59
2017.03.21 09:58:14.950 5: TEST: set readings power3 to 0.228, timeDiff3 to 15812 and countDiff3 to 1
2017.03.21 09:58:15.060 5: TEST: Device Time 600860.598, Drift -61.548s in 358.452s, -17.17%
...



...
2017.03.21 10:32:38.290 5: TEST: Notify called with events: INITIALIZED DEFINED Melde_Anrufer_A DEFINED firmata_reconnector ATTR firmata_reconnector room ZÄHLER, open device and set timer to send hello to device
2017.03.21 10:32:38.291 3: Opening TEST device /dev/serial/by-path/platform-3f980000.usb-usb-0:1.2.2:1.0-port0
2017.03.21 10:32:38.298 3: Setting TEST serial parameters to 38400,8,N,1
2017.03.21 10:32:38.315 3: TEST device opened
2017.03.21 10:32:39.808 0: Featurelevel: 5.8
2017.03.21 10:32:39.809 0: Server started with 760 defined entities (fhem.pl:13733/2017-03-19 perl:5.020002 os:linux user:fhem pid:10557)
...
2017.03.21 10:32:45.058 4: TEST: Pin 3 (pin3) count 46876 (diff 1) in 33.000s, result 0.109
2017.03.21 10:32:45.059 5: TEST: interval 10:32:12 until 10:32:45
2017.03.21 10:32:45.059 5: TEST: readingStartTime3 specified: setting reading timestamp to 10:32:12
2017.03.21 10:32:45.060 5: TEST: set readings power3 to 0.109, timeDiff3 to 33000 and countDiff3 to 1
2017.03.21 10:32:45.062 1: PERL WARNING: Argument "" isn't numeric in numeric gt (>) at fhem.pl line 4147.
2017.03.21 10:32:45.164 5: TEST: Initialize clock offset to 1489485894.46045
2017.03.21 10:32:45.164 5: TEST: Device Time 602870.598, Drift 0.000s in 0.000s
2017.03.21 10:32:45.164 3: TEST: device is still counting
2017.03.21 10:32:45.165 3: TEST: sending h(ello) to device to ask for version
2017.03.21 10:32:45.165 5: SW: h

...
2017.03.21 10:33:08.401 3: TEST: device replied to hello, V1.8
2017.03.21 10:33:08.402 4: TEST: device reported firmware 1.8
2017.03.21 10:33:08.402 3: TEST: ConfigureDevice calls Attr with pinD3 falling pullup 89
2017.03.21 10:33:08.403 5: SW: 3,2,1,89a

2017.03.21 10:33:08.406 3: TEST: ConfigureDevice calls Attr with interval 15 1200 5 1
2017.03.21 10:33:08.406 5: SW: 15,1200,5,1i

...
2017.03.21 10:33:19.160 3: TEST: device: defined pin 3 PCInt pin 19, iMode change, min len 89 ms falling, count 46879 (+1) in 29771 ms Rej 0
2017.03.21 10:33:19.161 3: TEST: device:   first at 19695, last at 19695, avg len 90
2017.03.21 10:33:19.161 3: TEST: device: intervals set to 15 1200 5 1
2017.03.21 10:33:19.162 4: TEST: Pin 3 (pin3) count 46879 (diff 1) in 29.771s, reject 0, result 0.121
2017.03.21 10:33:19.162 5: TEST: interval 10:32:49 until 10:33:19, First at 29771
2017.03.21 10:33:19.162 5: TEST: readingStartTime3 specified: setting reading timestamp to 10:32:49
2017.03.21 10:33:19.162 5: TEST: set readings power3 to 0.121, timeDiff3 to 29771 and countDiff3 to 1
2017.03.21 10:33:19.265 5: TEST: Device Time 602960.598, Drift -55.897s in 34.103s, -163.90%
...
2017.03.21 10:33:44.775 4: TEST: Pin 3 (pin3) count 46880 (diff 1) in 33.057s, reject 0, result 0.109
2017.03.21 10:33:44.775 5: TEST: interval 10:33:11 until 10:33:44, First at 33057
2017.03.21 10:33:44.775 5: TEST: readingStartTime3 specified: setting reading timestamp to 10:33:11
2017.03.21 10:33:44.776 5: TEST: set readings power3 to 0.109, timeDiff3 to 33057 and countDiff3 to 1
2017.03.21 10:33:44.881 5: TEST: Device Time 602990.598, Drift -60.284s in 59.716s, -100.95%
...
2017.03.21 10:34:26.214 4: TEST: Pin 3 (pin3) count 46881 (diff 1) in 30.123s, reject 0, result 0.120
2017.03.21 10:34:26.215 5: TEST: interval 10:33:56 until 10:34:26, First at 30123
2017.03.21 10:34:26.215 5: TEST: readingStartTime3 specified: setting reading timestamp to 10:33:56
2017.03.21 10:34:26.215 5: TEST: set readings power3 to 0.120, timeDiff3 to 30123 and countDiff3 to 1
2017.03.21 10:34:26.320 5: TEST: Device Time 603020.598, Drift -48.844s in 101.156s, -48.29%
2017.03.21 10:34:44.814 4: TEST: Pin 3 (pin3) count 46882 (diff 1) in 27.924s, reject 0, result 0.129
2017.03.21 10:34:44.814 5: TEST: interval 10:34:16 until 10:34:44, First at 27924
2017.03.21 10:34:44.814 5: TEST: readingStartTime3 specified: setting reading timestamp to 10:34:16
2017.03.21 10:34:44.815 5: TEST: set readings power3 to 0.129, timeDiff3 to 27924 and countDiff3 to 1
2017.03.21 10:34:44.923 5: TEST: Device Time 603050.598, Drift -60.245s in 119.755s, -50.31%
2017.03.21 10:35:14.578 4: TEST: Pin 3 (pin3) count 46883 (diff 1) in 27.522s, reject 0, result 0.131
2017.03.21 10:35:14.578 5: TEST: interval 10:34:47 until 10:35:14, First at 27522
2017.03.21 10:35:14.579 5: TEST: readingStartTime3 specified: setting reading timestamp to 10:34:47
2017.03.21 10:35:14.579 5: TEST: set readings power3 to 0.131, timeDiff3 to 27522 and countDiff3 to 1
2017.03.21 10:35:14.684 5: TEST: Device Time 603080.598, Drift -60.481s in 149.519s, -40.45%
2017.03.21 10:35:44.792 4: TEST: Pin 3 (pin3) count 46884 (diff 1) in 26.745s, reject 0, result 0.135
2017.03.21 10:35:44.792 5: TEST: interval 10:35:18 until 10:35:44, First at 26745
2017.03.21 10:35:44.792 5: TEST: readingStartTime3 specified: setting reading timestamp to 10:35:18
2017.03.21 10:35:44.793 5: TEST: set readings power3 to 0.135, timeDiff3 to 26745 and countDiff3 to 1
2017.03.21 10:35:44.894 5: TEST: Device Time 603110.598, Drift -60.267s in 179.733s, -33.53%
2017.03.21 10:35:59.572 4: TEST: Pin 3 (pin3) count 46885 (diff 1) in 26.420s, reject 0, result 0.136
2017.03.21 10:35:59.572 5: TEST: interval 10:35:33 until 10:35:59, First at 26420
2017.03.21 10:35:59.573 5: TEST: readingStartTime3 specified: setting reading timestamp to 10:35:33
2017.03.21 10:35:59.573 5: TEST: set readings power3 to 0.136, timeDiff3 to 26420 and countDiff3 to 1
2017.03.21 10:35:59.678 5: TEST: Device Time 603125.598, Drift -60.487s in 194.513s, -31.10%
...



...
2017.03.21 12:52:10.805 5: TEST: Notify called with events: INITIALIZED DEFINED Melde_Anrufer_A, open device and set timer to send hello to device
2017.03.21 12:52:10.806 3: Opening TEST device /dev/serial/by-path/platform-3f980000.usb-usb-0:1.2.2:1.0-port0
2017.03.21 12:52:10.814 3: Setting TEST serial parameters to 38400,8,N,1
2017.03.21 12:52:10.832 3: TEST device opened
2017.03.21 12:52:11.828 0: Featurelevel: 5.8
2017.03.21 12:52:11.828 0: Server started with 759 defined entities (fhem.pl:13733/2017-03-19 perl:5.020002 os:linux user:fhem pid:11749)
...
2017.03.21 12:52:21.533 4: TEST: Pin 3 (pin3) count 49243 (diff 7) in 10.539s, result 2.391
2017.03.21 12:52:21.533 5: TEST: interval 12:52:10 until 12:52:21
2017.03.21 12:52:21.534 5: TEST: readingStartTime3 specified: setting reading timestamp to 12:52:10
2017.03.21 12:52:21.534 5: TEST: set readings power3 to 2.391, timeDiff3 to 10539 and countDiff3 to 7
2017.03.21 12:52:21.536 1: PERL WARNING: Argument "" isn't numeric in numeric gt (>) at fhem.pl line 4147.
2017.03.21 12:52:21.651 5: TEST: Initialize clock offset to 1489485915.93464
2017.03.21 12:52:21.652 5: TEST: Device Time 611225.598, Drift 0.000s in 0.000s
2017.03.21 12:52:21.652 3: TEST: device is still counting
2017.03.21 12:52:21.652 3: TEST: sending h(ello) to device to ask for version
2017.03.21 12:52:21.653 5: SW: h

...
2017.03.21 12:52:58.501 3: TEST: device replied to hello, V1.8
2017.03.21 12:52:58.502 4: TEST: device reported firmware 1.8
2017.03.21 12:52:58.502 3: TEST: ConfigureDevice calls Attr with pinD3 falling pullup 89
2017.03.21 12:52:58.503 5: SW: 3,2,1,89a

2017.03.21 12:52:58.506 3: TEST: ConfigureDevice calls Attr with interval 15 1200 5 1
2017.03.21 12:52:58.507 5: SW: 15,1200,5,1i

2017.03.21 12:52:58.511 4: TEST: Pin 3 (pin3) count 49249 (diff 1) in 14.527s, reject 0, result 0.248
2017.03.21 12:52:58.512 5: TEST: interval 12:52:43 until 12:52:58, First at 14527
2017.03.21 12:52:58.512 5: TEST: readingStartTime3 specified: setting reading timestamp to 12:52:43
2017.03.21 12:52:58.512 5: TEST: set readings power3 to 0.248, timeDiff3 to 14527 and countDiff3 to 1
2017.03.21 12:52:58.790 5: TEST: Device Time 611315.598, Drift -53.021s in 36.979s, -143.38%
2017.03.21 12:52:58.791 4: TEST: Pin 3 (pin3) count 49251 (diff 2) in 29.331s, reject 0, result 0.245
2017.03.21 12:52:58.792 5: TEST: interval 12:52:29 until 12:52:58, First at 14811
2017.03.21 12:52:58.792 5: TEST: readingStartTime3 specified: setting reading timestamp to 12:52:29
2017.03.21 12:52:58.792 5: TEST: set readings power3 to 0.245, timeDiff3 to 29331 and countDiff3 to 2
2017.03.21 12:52:58.906 5: TEST: Device Time 611330.598, Drift -67.741s in 37.259s, -181.81%
2017.03.21 12:52:58.907 4: TEST: Pin 3 (pin3) count 49252 (diff 1) in 14.305s, reject 0, result 0.252
2017.03.21 12:52:58.907 5: TEST: interval 12:52:44 until 12:52:58, First at 14305
2017.03.21 12:52:58.908 5: TEST: readingStartTime3 specified: setting reading timestamp to 12:52:44
2017.03.21 12:52:58.908 5: TEST: set readings power3 to 0.252, timeDiff3 to 14305 and countDiff3 to 1
2017.03.21 12:52:59.022 5: TEST: Device Time 611345.598, Drift -82.625s in 37.375s, -221.07%
2017.03.21 12:52:59.727 3: TEST: device: defined pin 3 PCInt pin 19, iMode change, min len 89 ms falling, count 49252 (+0) in 816 ms Rej 0
2017.03.21 12:52:59.728 3: TEST: device: intervals set to 15 1200 5 1
2017.03.21 12:53:14.417 4: TEST: Pin 3 (pin3) count 49253 (diff 1) in 13.521s, reject 0, result 0.266
2017.03.21 12:53:14.418 5: TEST: interval 12:53:00 until 12:53:14, First at 13521
2017.03.21 12:53:14.419 5: TEST: readingStartTime3 specified: setting reading timestamp to 12:53:00
2017.03.21 12:53:14.419 5: TEST: set readings power3 to 0.266, timeDiff3 to 13521 and countDiff3 to 1
2017.03.21 12:53:14.534 5: TEST: Device Time 611360.598, Drift -82.115s in 52.885s, -155.27%
...
2017.03.21 12:53:43.830 4: TEST: Pin 3 (pin3) count 49254 (diff 1) in 18.334s, reject 0, result 0.196
2017.03.21 12:53:43.830 5: TEST: interval 12:53:25 until 12:53:43, First at 18334
2017.03.21 12:53:43.831 5: TEST: readingStartTime3 specified: setting reading timestamp to 12:53:25
2017.03.21 12:53:43.831 5: TEST: set readings power3 to 0.196, timeDiff3 to 18334 and countDiff3 to 1
2017.03.21 12:53:43.943 5: TEST: Device Time 611390.598, Drift -82.703s in 82.297s, -100.49%
2017.03.21 12:53:58.511 4: TEST: Pin 3 (pin3) count 49255 (diff 1) in 23.069s, reject 0, result 0.156
2017.03.21 12:53:58.512 5: TEST: interval 12:53:35 until 12:53:58, First at 23069
2017.03.21 12:53:58.513 5: TEST: readingStartTime3 specified: setting reading timestamp to 12:53:35
2017.03.21 12:53:58.513 5: TEST: set readings power3 to 0.156, timeDiff3 to 23069 and countDiff3 to 1
2017.03.21 12:53:58.632 5: TEST: Device Time 611405.598, Drift -83.021s in 96.979s, -85.61%
2017.03.21 12:54:13.941 4: TEST: Pin 3 (pin3) count 49264 (diff 9) in 19.362s, reject 0, result 1.673
2017.03.21 12:54:13.941 5: TEST: interval 12:53:54 until 12:54:13, First at 6914
2017.03.21 12:54:13.942 5: TEST: readingStartTime3 specified: setting reading timestamp to 12:53:54
2017.03.21 12:54:13.942 5: TEST: set readings power3 to 1.673, timeDiff3 to 19362 and countDiff3 to 9
2017.03.21 12:54:14.060 5: TEST: Device Time 611420.598, Drift -82.592s in 112.408s, -73.47%
2017.03.21 12:54:28.482 4: TEST: Pin 3 (pin3) count 49274 (diff 10) in 15.628s, reject 0, result 2.304
2017.03.21 12:54:28.482 5: TEST: interval 12:54:12 until 12:54:28, First at 1577
2017.03.21 12:54:28.483 5: TEST: readingStartTime3 specified: setting reading timestamp to 12:54:12
2017.03.21 12:54:28.483 5: TEST: set readings power3 to 2.304, timeDiff3 to 15628 and countDiff3 to 10
2017.03.21 12:54:28.597 5: TEST: Device Time 611435.598, Drift -83.051s in 126.949s, -65.42%
2017.03.21 12:54:44.467 4: TEST: Pin 3 (pin3) count 49284 (diff 10) in 15.636s, reject 0, result 2.302
2017.03.21 12:54:44.468 5: TEST: interval 12:54:28 until 12:54:44, First at 1562
2017.03.21 12:54:44.469 5: TEST: readingStartTime3 specified: setting reading timestamp to 12:54:28
2017.03.21 12:54:44.469 5: TEST: set readings power3 to 2.302, timeDiff3 to 15636 and countDiff3 to 10
2017.03.21 12:54:44.620 5: TEST: Device Time 611450.598, Drift -82.066s in 142.934s, -57.41%
2017.03.21 12:54:58.479 4: TEST: Pin 3 (pin3) count 49293 (diff 9) in 14.189s, reject 0, result 2.283
2017.03.21 12:54:58.479 5: TEST: interval 12:54:44 until 12:54:58, First at 1569
2017.03.21 12:54:58.480 5: TEST: readingStartTime3 specified: setting reading timestamp to 12:54:44
2017.03.21 12:54:58.480 5: TEST: set readings power3 to 2.283, timeDiff3 to 14189 and countDiff3 to 9
2017.03.21 12:54:58.605 5: TEST: Device Time 611465.598, Drift -83.054s in 156.946s, -52.92%
2017.03.21 12:55:13.638 4: TEST: Pin 3 (pin3) count 49303 (diff 10) in 15.793s, reject 0, result 2.279
2017.03.21 12:55:13.638 5: TEST: interval 12:54:57 until 12:55:13, First at 1576
2017.03.21 12:55:13.639 5: TEST: readingStartTime3 specified: setting reading timestamp to 12:54:57
2017.03.21 12:55:13.639 5: TEST: set readings power3 to 2.279, timeDiff3 to 15793 and countDiff3 to 10
2017.03.21 12:55:13.773 5: TEST: Device Time 611480.598, Drift -82.895s in 172.105s, -48.17%
2017.03.21 12:55:29.152 4: TEST: Pin 3 (pin3) count 49312 (diff 9) in 14.208s, reject 0, result 2.280
2017.03.21 12:55:29.153 5: TEST: interval 12:55:14 until 12:55:29, First at 1585
2017.03.21 12:55:29.153 5: TEST: readingStartTime3 specified: setting reading timestamp to 12:55:14
2017.03.21 12:55:29.153 5: TEST: set readings power3 to 2.280, timeDiff3 to 14208 and countDiff3 to 9
2017.03.21 12:55:29.294 5: TEST: Device Time 611495.598, Drift -82.381s in 187.619s, -43.91%
...


Langzeit-Mitschnitt: 13:44 bis 13:55 Uhr

...
2017.03.21 13:44:51.960 5: TEST: Notify called with events: INITIALIZED DEFINED Melde_Anrufer_A, open device and set timer to send hello to device
2017.03.21 13:44:51.960 3: Opening TEST device /dev/serial/by-path/platform-3f980000.usb-usb-0:1.2.2:1.0-port0
2017.03.21 13:44:51.968 3: Setting TEST serial parameters to 38400,8,N,1
2017.03.21 13:44:51.985 3: TEST device opened
2017.03.21 13:44:52.963 0: Featurelevel: 5.8
2017.03.21 13:44:52.963 0: Server started with 759 defined entities (fhem.pl:13733/2017-03-19 perl:5.020002 os:linux user:fhem pid:17442)
...
2017.03.21 13:44:59.478 4: TEST: Pin 3 (pin3) count 50522 (diff 9) in 14.215s, result 2.279
2017.03.21 13:44:59.478 5: TEST: interval 13:44:45 until 13:44:59
2017.03.21 13:44:59.479 5: TEST: readingStartTime3 specified: setting reading timestamp to 13:44:45
2017.03.21 13:44:59.479 5: TEST: set readings power3 to 2.279, timeDiff3 to 14215 and countDiff3 to 9
2017.03.21 13:44:59.641 5: TEST: Initialize clock offset to 1489485908.87963
2017.03.21 13:44:59.641 5: TEST: Device Time 614390.598, Drift 0.000s in 0.000s
2017.03.21 13:44:59.642 3: TEST: device is still counting
2017.03.21 13:44:59.642 3: TEST: sending h(ello) to device to ask for version
2017.03.21 13:44:59.643 5: SW: h

...
2017.03.21 13:45:43.233 3: TEST: device didn't reply to h(ello). Is the right sketch flashed? Is speed set to 38400?
2017.03.21 13:45:43.463 3: TEST: device replied to hello, V1.8
2017.03.21 13:45:43.463 4: TEST: device reported firmware 1.8
2017.03.21 13:45:43.463 3: TEST: ConfigureDevice calls Attr with interval 15 1200 5 1
2017.03.21 13:45:43.464 5: SW: 15,1200,5,1i

2017.03.21 13:45:43.466 3: TEST: ConfigureDevice calls Attr with pinD3 falling pullup 89
2017.03.21 13:45:43.466 5: SW: 3,2,1,89a

2017.03.21 13:45:43.473 4: TEST: Pin 3 (pin3) count 50579 (diff 10) in 15.946s, reject 0, result 2.258
2017.03.21 13:45:43.474 5: TEST: interval 13:45:27 until 13:45:43, First at 1594
2017.03.21 13:45:43.474 5: TEST: readingStartTime3 specified: setting reading timestamp to 13:45:27
2017.03.21 13:45:43.474 5: TEST: set readings power3 to 2.258, timeDiff3 to 15946 and countDiff3 to 10
2017.03.21 13:45:43.764 5: TEST: Device Time 614480.598, Drift -46.004s in 43.996s, -104.57%
2017.03.21 13:45:43.765 4: TEST: Pin 3 (pin3) count 50588 (diff 9) in 14.313s, reject 1, result 2.264
2017.03.21 13:45:43.765 5: TEST: interval 13:45:29 until 13:45:43, First at 1590
2017.03.21 13:45:43.766 5: TEST: readingStartTime3 specified: setting reading timestamp to 13:45:29
2017.03.21 13:45:43.766 5: TEST: set readings power3 to 2.264, timeDiff3 to 14313 and countDiff3 to 9
2017.03.21 13:45:43.884 5: TEST: Device Time 614495.598, Drift -60.712s in 44.288s, -137.09%
2017.03.21 13:45:43.885 4: TEST: Pin 3 (pin3) count 50598 (diff 10) in 15.846s, reject 0, result 2.272
2017.03.21 13:45:43.885 5: TEST: interval 13:45:28 until 13:45:43, First at 1583
2017.03.21 13:45:43.885 5: TEST: readingStartTime3 specified: setting reading timestamp to 13:45:28
2017.03.21 13:45:43.886 5: TEST: set readings power3 to 2.272, timeDiff3 to 15846 and countDiff3 to 10
2017.03.21 13:45:44.004 5: TEST: Device Time 614510.598, Drift -75.593s in 44.407s, -170.23%
2017.03.21 13:45:45.374 3: TEST: device: intervals set to 15 1200 5 1
2017.03.21 13:45:45.374 3: TEST: device: defined pin 3 PCInt pin 19, iMode change, min len 89 ms falling, count 50598 (+0) in 501 ms Rej 0
...
2017.03.21 13:45:59.170 4: TEST: Pin 3 (pin3) count 50607 (diff 9) in 14.236s, reject 0, result 2.276
2017.03.21 13:45:59.170 5: TEST: interval 13:45:44 until 13:45:59, First at 1584
2017.03.21 13:45:59.171 5: TEST: readingStartTime3 specified: setting reading timestamp to 13:45:44
2017.03.21 13:45:59.171 5: TEST: set readings power3 to 2.276, timeDiff3 to 14236 and countDiff3 to 9
2017.03.21 13:45:59.288 5: TEST: Device Time 614525.598, Drift -75.308s in 59.692s, -126.16%
...
2017.03.21 13:46:25.228 4: TEST: Pin 3 (pin3) count 50617 (diff 10) in 15.824s, reject 0, result 2.275
2017.03.21 13:46:25.229 5: TEST: interval 13:46:09 until 13:46:25, First at 1582
2017.03.21 13:46:25.229 5: TEST: readingStartTime3 specified: setting reading timestamp to 13:46:09
2017.03.21 13:46:25.230 5: TEST: set readings power3 to 2.275, timeDiff3 to 15824 and countDiff3 to 10
2017.03.21 13:46:25.346 5: TEST: Device Time 614540.598, Drift -64.249s in 85.751s, -74.93%
...
2017.03.21 13:46:28.438 4: TEST: Pin 3 (pin3) count 50626 (diff 9) in 14.229s, reject 0, result 2.277
2017.03.21 13:46:28.438 5: TEST: interval 13:46:14 until 13:46:28, First at 1581
2017.03.21 13:46:28.439 5: TEST: readingStartTime3 specified: setting reading timestamp to 13:46:14
2017.03.21 13:46:28.439 5: TEST: set readings power3 to 2.277, timeDiff3 to 14229 and countDiff3 to 9
2017.03.21 13:46:28.571 5: TEST: Device Time 614555.598, Drift -76.040s in 88.960s, -85.48%
2017.03.21 13:46:43.157 4: TEST: Pin 3 (pin3) count 50636 (diff 10) in 15.804s, reject 0, result 2.278
2017.03.21 13:46:43.157 5: TEST: interval 13:46:27 until 13:46:43, First at 1582
2017.03.21 13:46:43.158 5: TEST: readingStartTime3 specified: setting reading timestamp to 13:46:27
2017.03.21 13:46:43.158 5: TEST: set readings power3 to 2.278, timeDiff3 to 15804 and countDiff3 to 10
2017.03.21 13:46:43.297 5: TEST: Device Time 614570.598, Drift -76.321s in 103.679s, -73.61%
2017.03.21 13:46:58.619 4: TEST: Pin 3 (pin3) count 50645 (diff 9) in 14.236s, reject 0, result 2.276
2017.03.21 13:46:58.619 5: TEST: interval 13:46:44 until 13:46:58, First at 1581
2017.03.21 13:46:58.620 5: TEST: readingStartTime3 specified: setting reading timestamp to 13:46:44
2017.03.21 13:46:58.621 5: TEST: set readings power3 to 2.276, timeDiff3 to 14236 and countDiff3 to 9
2017.03.21 13:46:58.763 5: TEST: Device Time 614585.598, Drift -75.860s in 119.140s, -63.67%
2017.03.21 13:47:13.161 4: TEST: Pin 3 (pin3) count 50655 (diff 10) in 15.818s, reject 0, result 2.276
2017.03.21 13:47:13.161 5: TEST: interval 13:46:57 until 13:47:13, First at 1583
2017.03.21 13:47:13.162 5: TEST: readingStartTime3 specified: setting reading timestamp to 13:46:57
2017.03.21 13:47:13.162 5: TEST: set readings power3 to 2.276, timeDiff3 to 15818 and countDiff3 to 10
2017.03.21 13:47:13.283 5: TEST: Device Time 614600.598, Drift -76.317s in 133.683s, -57.09%
2017.03.21 13:47:28.409 4: TEST: Pin 3 (pin3) count 50664 (diff 9) in 14.258s, reject 0, result 2.272
2017.03.21 13:47:28.410 5: TEST: interval 13:47:14 until 13:47:28, First at 1585
2017.03.21 13:47:28.411 5: TEST: readingStartTime3 specified: setting reading timestamp to 13:47:14
2017.03.21 13:47:28.412 5: TEST: set readings power3 to 2.272, timeDiff3 to 14258 and countDiff3 to 9
2017.03.21 13:47:28.573 5: TEST: Device Time 614615.598, Drift -76.070s in 148.930s, -51.08%
2017.03.21 13:47:43.192 4: TEST: Pin 3 (pin3) count 50674 (diff 10) in 15.699s, reject 0, result 2.293
2017.03.21 13:47:43.192 5: TEST: interval 13:47:27 until 13:47:43, First at 1582
2017.03.21 13:47:43.193 5: TEST: readingStartTime3 specified: setting reading timestamp to 13:47:27
2017.03.21 13:47:43.193 5: TEST: set readings power3 to 2.293, timeDiff3 to 15699 and countDiff3 to 10
2017.03.21 13:47:43.312 5: TEST: Device Time 614630.598, Drift -76.286s in 163.714s, -46.60%
2017.03.21 13:47:58.227 4: TEST: Pin 3 (pin3) count 50683 (diff 9) in 14.103s, reject 0, result 2.297
2017.03.21 13:47:58.228 5: TEST: interval 13:47:44 until 13:47:58, First at 1567
2017.03.21 13:47:58.228 5: TEST: readingStartTime3 specified: setting reading timestamp to 13:47:44
2017.03.21 13:47:58.228 5: TEST: set readings power3 to 2.297, timeDiff3 to 14103 and countDiff3 to 9
2017.03.21 13:47:58.340 5: TEST: Device Time 614645.598, Drift -76.250s in 178.750s, -42.66%
2017.03.21 13:48:13.148 4: TEST: Pin 3 (pin3) count 50693 (diff 10) in 15.662s, reject 0, result 2.299
2017.03.21 13:48:13.148 5: TEST: interval 13:47:57 until 13:48:13, First at 1566
2017.03.21 13:48:13.149 5: TEST: readingStartTime3 specified: setting reading timestamp to 13:47:57
2017.03.21 13:48:13.149 5: TEST: set readings power3 to 2.299, timeDiff3 to 15662 and countDiff3 to 10
2017.03.21 13:48:13.269 5: TEST: Device Time 614660.598, Drift -76.330s in 193.670s, -39.41%
2017.03.21 13:48:28.145 4: TEST: Pin 3 (pin3) count 50702 (diff 9) in 14.131s, reject 0, result 2.293
2017.03.21 13:48:28.146 5: TEST: interval 13:48:14 until 13:48:28, First at 1569
2017.03.21 13:48:28.146 5: TEST: readingStartTime3 specified: setting reading timestamp to 13:48:14
2017.03.21 13:48:28.147 5: TEST: set readings power3 to 2.293, timeDiff3 to 14131 and countDiff3 to 9
2017.03.21 13:48:28.259 5: TEST: Device Time 614675.598, Drift -76.332s in 208.668s, -36.58%
2017.03.21 13:48:43.474 4: TEST: Pin 3 (pin3) count 50712 (diff 10) in 15.721s, reject 0, result 2.290
2017.03.21 13:48:43.475 5: TEST: interval 13:48:27 until 13:48:43, First at 1569
2017.03.21 13:48:43.475 5: TEST: readingStartTime3 specified: setting reading timestamp to 13:48:27
2017.03.21 13:48:43.475 5: TEST: set readings power3 to 2.290, timeDiff3 to 15721 and countDiff3 to 10
2017.03.21 13:48:43.590 5: TEST: Device Time 614690.598, Drift -76.003s in 223.997s, -33.93%
2017.03.21 13:48:59.331 4: TEST: Pin 3 (pin3) count 50721 (diff 9) in 14.118s, reject 0, result 2.295
2017.03.21 13:48:59.332 5: TEST: interval 13:48:45 until 13:48:59, First at 1568
2017.03.21 13:48:59.332 5: TEST: readingStartTime3 specified: setting reading timestamp to 13:48:45
2017.03.21 13:48:59.332 5: TEST: set readings power3 to 2.295, timeDiff3 to 14118 and countDiff3 to 9
2017.03.21 13:48:59.449 5: TEST: Device Time 614705.598, Drift -75.146s in 239.854s, -31.33%
2017.03.21 13:49:14.209 4: TEST: Pin 3 (pin3) count 50731 (diff 10) in 15.666s, reject 0, result 2.298
2017.03.21 13:49:14.210 5: TEST: interval 13:48:58 until 13:49:14, First at 1569
2017.03.21 13:49:14.211 5: TEST: readingStartTime3 specified: setting reading timestamp to 13:48:58
2017.03.21 13:49:14.211 5: TEST: set readings power3 to 2.298, timeDiff3 to 15666 and countDiff3 to 10
2017.03.21 13:49:14.325 5: TEST: Device Time 614720.598, Drift -75.268s in 254.732s, -29.55%
2017.03.21 13:49:28.140 4: TEST: Pin 3 (pin3) count 50740 (diff 9) in 14.105s, reject 0, result 2.297
2017.03.21 13:49:28.140 5: TEST: interval 13:49:14 until 13:49:28, First at 1567
2017.03.21 13:49:28.141 5: TEST: readingStartTime3 specified: setting reading timestamp to 13:49:14
2017.03.21 13:49:28.141 5: TEST: set readings power3 to 2.297, timeDiff3 to 14105 and countDiff3 to 9
2017.03.21 13:49:28.254 5: TEST: Device Time 614735.598, Drift -76.338s in 268.662s, -28.41%
2017.03.21 13:49:43.198 4: TEST: Pin 3 (pin3) count 50750 (diff 10) in 15.693s, reject 0, result 2.294
2017.03.21 13:49:43.199 5: TEST: interval 13:49:27 until 13:49:43, First at 1566
2017.03.21 13:49:43.200 5: TEST: readingStartTime3 specified: setting reading timestamp to 13:49:27
2017.03.21 13:49:43.200 5: TEST: set readings power3 to 2.294, timeDiff3 to 15693 and countDiff3 to 10
2017.03.21 13:49:43.335 5: TEST: Device Time 614750.598, Drift -76.279s in 283.721s, -26.89%
2017.03.21 13:49:59.577 4: TEST: Pin 3 (pin3) count 50760 (diff 10) in 15.660s, reject 0, result 2.299
2017.03.21 13:49:59.578 5: TEST: interval 13:49:43 until 13:49:59, First at 1565
2017.03.21 13:49:59.578 5: TEST: readingStartTime3 specified: setting reading timestamp to 13:49:43
2017.03.21 13:49:59.579 5: TEST: set readings power3 to 2.299, timeDiff3 to 15660 and countDiff3 to 10
2017.03.21 13:49:59.693 5: TEST: Device Time 614765.598, Drift -74.900s in 300.100s, -24.96%
2017.03.21 13:50:14.239 4: TEST: Pin 3 (pin3) count 50769 (diff 9) in 14.142s, reject 0, result 2.291
2017.03.21 13:50:14.239 5: TEST: interval 13:50:00 until 13:50:14, First at 1570
2017.03.21 13:50:14.240 5: TEST: readingStartTime3 specified: setting reading timestamp to 13:50:00
2017.03.21 13:50:14.240 5: TEST: set readings power3 to 2.291, timeDiff3 to 14142 and countDiff3 to 9
2017.03.21 13:50:14.362 5: TEST: Device Time 614780.598, Drift -75.239s in 314.761s, -23.90%
2017.03.21 13:50:28.135 4: TEST: Pin 3 (pin3) count 50779 (diff 10) in 15.735s, reject 0, result 2.288
2017.03.21 13:50:28.135 5: TEST: interval 13:50:12 until 13:50:28, First at 1568
2017.03.21 13:50:28.136 5: TEST: readingStartTime3 specified: setting reading timestamp to 13:50:12
2017.03.21 13:50:28.136 5: TEST: set readings power3 to 2.288, timeDiff3 to 15735 and countDiff3 to 10
2017.03.21 13:50:28.249 5: TEST: Device Time 614795.598, Drift -76.343s in 328.657s, -23.23%
2017.03.21 13:50:43.437 4: TEST: Pin 3 (pin3) count 50788 (diff 9) in 14.215s, reject 0, result 2.279
2017.03.21 13:50:43.437 5: TEST: interval 13:50:29 until 13:50:43, First at 1580
2017.03.21 13:50:43.438 5: TEST: readingStartTime3 specified: setting reading timestamp to 13:50:29
2017.03.21 13:50:43.438 5: TEST: set readings power3 to 2.279, timeDiff3 to 14215 and countDiff3 to 9
2017.03.21 13:50:43.577 5: TEST: Device Time 614810.598, Drift -76.041s in 343.959s, -22.11%
2017.03.21 13:50:58.561 4: TEST: Pin 3 (pin3) count 50798 (diff 10) in 15.807s, reject 0, result 2.277
2017.03.21 13:50:58.561 5: TEST: interval 13:50:42 until 13:50:58, First at 1577
2017.03.21 13:50:58.562 5: TEST: readingStartTime3 specified: setting reading timestamp to 13:50:42
2017.03.21 13:50:58.562 5: TEST: set readings power3 to 2.277, timeDiff3 to 15807 and countDiff3 to 10
2017.03.21 13:50:58.675 5: TEST: Device Time 614825.598, Drift -75.917s in 359.083s, -21.14%
2017.03.21 13:51:13.355 4: TEST: Pin 3 (pin3) count 50807 (diff 9) in 14.201s, reject 0, result 2.282
2017.03.21 13:51:13.356 5: TEST: interval 13:50:59 until 13:51:13, First at 1580
2017.03.21 13:51:13.356 5: TEST: readingStartTime3 specified: setting reading timestamp to 13:50:59
2017.03.21 13:51:13.356 5: TEST: set readings power3 to 2.282, timeDiff3 to 14201 and countDiff3 to 9
2017.03.21 13:51:13.469 5: TEST: Device Time 614840.598, Drift -76.122s in 373.878s, -20.36%
2017.03.21 13:51:28.127 4: TEST: Pin 3 (pin3) count 50817 (diff 10) in 15.416s, reject 0, result 2.335
2017.03.21 13:51:28.128 5: TEST: interval 13:51:12 until 13:51:28, First at 1567
2017.03.21 13:51:28.128 5: TEST: readingStartTime3 specified: setting reading timestamp to 13:51:12
2017.03.21 13:51:28.128 5: TEST: set readings power3 to 2.335, timeDiff3 to 15416 and countDiff3 to 10
2017.03.21 13:51:28.248 5: TEST: Device Time 614855.598, Drift -76.350s in 388.650s, -19.65%
2017.03.21 13:51:43.127 4: TEST: Pin 3 (pin3) count 50827 (diff 10) in 15.387s, reject 0, result 2.340
2017.03.21 13:51:43.128 5: TEST: interval 13:51:27 until 13:51:43, First at 1514
2017.03.21 13:51:43.128 5: TEST: readingStartTime3 specified: setting reading timestamp to 13:51:27
2017.03.21 13:51:43.128 5: TEST: set readings power3 to 2.340, timeDiff3 to 15387 and countDiff3 to 10
2017.03.21 13:51:43.245 5: TEST: Device Time 614870.598, Drift -76.350s in 403.650s, -18.92%
2017.03.21 13:51:58.125 4: TEST: Pin 3 (pin3) count 50836 (diff 9) in 14.093s, reject 0, result 2.299
2017.03.21 13:51:58.126 5: TEST: interval 13:51:44 until 13:51:58, First at 1559
2017.03.21 13:51:58.126 5: TEST: readingStartTime3 specified: setting reading timestamp to 13:51:44
2017.03.21 13:51:58.127 5: TEST: set readings power3 to 2.299, timeDiff3 to 14093 and countDiff3 to 9
2017.03.21 13:51:58.264 5: TEST: Device Time 614885.598, Drift -76.352s in 418.648s, -18.24%
2017.03.21 13:52:14.118 4: TEST: Pin 3 (pin3) count 50846 (diff 10) in 15.683s, reject 0, result 2.295
2017.03.21 13:52:14.118 5: TEST: interval 13:51:58 until 13:52:14, First at 1564
2017.03.21 13:52:14.119 5: TEST: readingStartTime3 specified: setting reading timestamp to 13:51:58
2017.03.21 13:52:14.119 5: TEST: set readings power3 to 2.295, timeDiff3 to 15683 and countDiff3 to 10
2017.03.21 13:52:14.255 5: TEST: Device Time 614900.598, Drift -75.360s in 434.640s, -17.34%
2017.03.21 13:52:28.122 4: TEST: Pin 3 (pin3) count 50855 (diff 9) in 14.121s, reject 0, result 2.294
2017.03.21 13:52:28.123 5: TEST: interval 13:52:14 until 13:52:28, First at 1568
2017.03.21 13:52:28.123 5: TEST: readingStartTime3 specified: setting reading timestamp to 13:52:14
2017.03.21 13:52:28.123 5: TEST: set readings power3 to 2.294, timeDiff3 to 14121 and countDiff3 to 9
2017.03.21 13:52:28.264 5: TEST: Device Time 614915.598, Drift -76.356s in 448.644s, -17.02%
2017.03.21 13:52:43.122 4: TEST: Pin 3 (pin3) count 50865 (diff 10) in 15.700s, reject 0, result 2.293
2017.03.21 13:52:43.122 5: TEST: interval 13:52:27 until 13:52:43, First at 1568
2017.03.21 13:52:43.123 5: TEST: readingStartTime3 specified: setting reading timestamp to 13:52:27
2017.03.21 13:52:43.123 5: TEST: set readings power3 to 2.293, timeDiff3 to 15700 and countDiff3 to 10
2017.03.21 13:52:43.236 5: TEST: Device Time 614930.598, Drift -76.356s in 463.644s, -16.47%
2017.03.21 13:52:58.133 4: TEST: Pin 3 (pin3) count 50875 (diff 10) in 14.626s, reject 0, result 2.461
2017.03.21 13:52:58.134 5: TEST: interval 13:52:43 until 13:52:58, First at 1567
2017.03.21 13:52:58.134 5: TEST: readingStartTime3 specified: setting reading timestamp to 13:52:43
2017.03.21 13:52:58.134 5: TEST: set readings power3 to 2.461, timeDiff3 to 14626 and countDiff3 to 10
2017.03.21 13:52:58.252 5: TEST: Device Time 614945.598, Drift -76.344s in 478.656s, -15.95%
2017.03.21 13:53:13.364 4: TEST: Pin 3 (pin3) count 50885 (diff 10) in 14.890s, reject 0, result 2.418
2017.03.21 13:53:13.364 5: TEST: interval 13:52:58 until 13:53:13, First at 1487
2017.03.21 13:53:13.365 5: TEST: readingStartTime3 specified: setting reading timestamp to 13:52:58
2017.03.21 13:53:13.365 5: TEST: set readings power3 to 2.418, timeDiff3 to 14890 and countDiff3 to 10
2017.03.21 13:53:13.512 5: TEST: Device Time 614960.598, Drift -76.114s in 493.886s, -15.41%
2017.03.21 13:53:28.219 4: TEST: Pin 3 (pin3) count 50895 (diff 10) in 14.871s, reject 0, result 2.421
2017.03.21 13:53:28.219 5: TEST: interval 13:53:13 until 13:53:28, First at 1489
2017.03.21 13:53:28.220 5: TEST: readingStartTime3 specified: setting reading timestamp to 13:53:13
2017.03.21 13:53:28.220 5: TEST: set readings power3 to 2.421, timeDiff3 to 14871 and countDiff3 to 10
2017.03.21 13:53:28.333 5: TEST: Device Time 614975.598, Drift -76.259s in 508.741s, -14.99%
2017.03.21 13:53:43.115 4: TEST: Pin 3 (pin3) count 50905 (diff 10) in 14.897s, reject 0, result 2.417
2017.03.21 13:53:43.115 5: TEST: interval 13:53:28 until 13:53:43, First at 1489
2017.03.21 13:53:43.116 5: TEST: readingStartTime3 specified: setting reading timestamp to 13:53:28
2017.03.21 13:53:43.116 5: TEST: set readings power3 to 2.417, timeDiff3 to 14897 and countDiff3 to 10
2017.03.21 13:53:43.230 5: TEST: Device Time 614990.598, Drift -76.363s in 523.637s, -14.58%
2017.03.21 13:53:58.114 4: TEST: Pin 3 (pin3) count 50915 (diff 10) in 14.980s, reject 0, result 2.403
2017.03.21 13:53:58.115 5: TEST: interval 13:53:43 until 13:53:58, First at 1494
2017.03.21 13:53:58.115 5: TEST: readingStartTime3 specified: setting reading timestamp to 13:53:43
2017.03.21 13:53:58.116 5: TEST: set readings power3 to 2.403, timeDiff3 to 14980 and countDiff3 to 10
2017.03.21 13:53:58.256 5: TEST: Device Time 615005.598, Drift -76.364s in 538.636s, -14.18%
2017.03.21 13:54:13.386 4: TEST: Pin 3 (pin3) count 50925 (diff 10) in 14.994s, reject 0, result 2.401
2017.03.21 13:54:13.387 5: TEST: interval 13:53:58 until 13:54:13, First at 1497
2017.03.21 13:54:13.387 5: TEST: readingStartTime3 specified: setting reading timestamp to 13:53:58
2017.03.21 13:54:13.387 5: TEST: set readings power3 to 2.401, timeDiff3 to 14994 and countDiff3 to 10
2017.03.21 13:54:13.509 5: TEST: Device Time 615020.598, Drift -76.092s in 553.908s, -13.74%
2017.03.21 13:54:28.635 4: TEST: Pin 3 (pin3) count 50935 (diff 10) in 14.995s, reject 0, result 2.401
2017.03.21 13:54:28.635 5: TEST: interval 13:54:13 until 13:54:28, First at 1497
2017.03.21 13:54:28.636 5: TEST: readingStartTime3 specified: setting reading timestamp to 13:54:13
2017.03.21 13:54:28.636 5: TEST: set readings power3 to 2.401, timeDiff3 to 14995 and countDiff3 to 10
2017.03.21 13:54:28.777 5: TEST: Device Time 615035.598, Drift -75.843s in 569.157s, -13.33%
2017.03.21 13:54:43.395 4: TEST: Pin 3 (pin3) count 50945 (diff 10) in 15.029s, reject 0, result 2.395
2017.03.21 13:54:43.396 5: TEST: interval 13:54:28 until 13:54:43, First at 1499
2017.03.21 13:54:43.396 5: TEST: readingStartTime3 specified: setting reading timestamp to 13:54:28
2017.03.21 13:54:43.396 5: TEST: set readings power3 to 2.395, timeDiff3 to 15029 and countDiff3 to 10
2017.03.21 13:54:43.761 5: TEST: Device Time 615050.598, Drift -76.083s in 583.917s, -13.03%
2017.03.21 13:55:02.258 4: TEST: Pin 3 (pin3) count 50955 (diff 10) in 15.026s, reject 0, result 2.396
2017.03.21 13:55:02.259 5: TEST: interval 13:54:47 until 13:55:02, First at 1508
2017.03.21 13:55:02.260 5: TEST: readingStartTime3 specified: setting reading timestamp to 13:54:47
2017.03.21 13:55:02.260 5: TEST: set readings power3 to 2.396, timeDiff3 to 15026 and countDiff3 to 10
2017.03.21 13:55:02.448 5: TEST: Device Time 615065.598, Drift -72.220s in 602.780s, -11.98%
2017.03.21 13:55:13.870 4: TEST: Pin 3 (pin3) count 50965 (diff 10) in 15.001s, reject 0, result 2.400
2017.03.21 13:55:13.871 5: TEST: interval 13:54:58 until 13:55:13, First at 1497
2017.03.21 13:55:13.876 5: TEST: readingStartTime3 specified: setting reading timestamp to 13:54:58
2017.03.21 13:55:13.876 5: TEST: set readings power3 to 2.400, timeDiff3 to 15001 and countDiff3 to 10
2017.03.21 13:55:14.022 5: TEST: Device Time 615080.598, Drift -75.608s in 614.392s, -12.31%
2017.03.21 13:55:28.105 4: TEST: Pin 3 (pin3) count 50975 (diff 10) in 15.069s, reject 0, result 2.389
2017.03.21 13:55:28.106 5: TEST: interval 13:55:13 until 13:55:28, First at 1504
2017.03.21 13:55:28.107 5: TEST: readingStartTime3 specified: setting reading timestamp to 13:55:13
2017.03.21 13:55:28.107 5: TEST: set readings power3 to 2.389, timeDiff3 to 15069 and countDiff3 to 10
2017.03.21 13:55:28.224 5: TEST: Device Time 615095.598, Drift -76.372s in 628.628s, -12.15%
2017.03.21 13:55:43.148 4: TEST: Pin 3 (pin3) count 50985 (diff 10) in 15.097s, reject 0, result 2.385
2017.03.21 13:55:43.148 5: TEST: interval 13:55:28 until 13:55:43, First at 1515
2017.03.21 13:55:43.149 5: TEST: readingStartTime3 specified: setting reading timestamp to 13:55:28
2017.03.21 13:55:43.149 5: TEST: set readings power3 to 2.385, timeDiff3 to 15097 and countDiff3 to 10
2017.03.21 13:55:43.279 5: TEST: Device Time 615110.598, Drift -76.330s in 643.670s, -11.86%

VG.
BillyPbg

StefanStrobel

Schade, die entscheidenden Zeilen sind auskommentiert ...
Kannst Du im Modul mal die Kommentarzeichen vor Zeile 572 und 752 entfernen damit die Kommunikation zwischen Arduino und Modul auch protokolliert wird?

Gruss / Thanx
    Stefan

BillyPbg


birdy

Evtl. eine dumme Frage, aber wenn man einen ArduCounter bauen möchte, benötigt man dann zwingend ein Arduino Nano oder geht das auch mit einem Arduino Micro (bzw. dessen Klon)?
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,

ich habe es nur mit den 328er Controllern probiert.
Ob die pin change interrupts exakt so auch mit dem 32u4 funktionieren kann ich nicht sagen.

Gruss
   Stefan

Skusi

#146
Hallo zusammen,
nun muß ich doch mal um eure Hilfe bitten:

Ich habe einen WEMOS an meinem S0 Zähler angeschlossen, und lese damit auch erfolgreich meinen  Verbrauch aus. Das Auswerten der Werte funktioniert auch sehr gut.
Nun habe ich mir ein Doif gebaut das am Tagesende den Wert des tagesverbrauchs in ein reading "Gestern" schiebt und den ESP resettet.
Eingebunden ist das ganze über eine ESPBridge und ein entsprechendes ESPEasy Device, also ohne Mosquito.

..sieht dann also so aus:
   
([23:59])
(setreading Strom Vorgestern [Strom:Gestern],
setreading Strom Gestern [Strom:Heute],
setreading Strom Monat [Strom_Statistik:Strom_zaehler_jetzt_EnergyMonth],
setreading Strom zaehler_vortag [Strom:zaehler_jetzt],
set PulsStrom reboot)


Nun ist mein Problem das der reboot nachts nicht ausgeführt wird. Feuer ich das DOIF aber manuell ab, funktioniert es. Alledings habe ich das Gefühl das auch manuell der reboot nicht immer ausgeführt wird.

Kennt das jemand von Euch ?
Warum nimmt der Wemos ESP den reboot nicht immer an. Komischerweise hat er das um 23:59 seit einer guten Woche, die ich nun schon daran rumdoktor, noch nie getan.

Hab ich irgendwo noch eine Atribut falsch gesetzt ?

hier nochmal die defs:

define espBridge ESPEasy bridge 8383
attr espBridge authentication 0
attr espBridge autocreate 1
attr espBridge combineDevices 0
attr espBridge group ESPEasy Bridge
attr espBridge room 9.0_System


define PulsStrom ESPEasy 192.168.12.40 80 espBridge Stromcount_StromImpuls
attr PulsStrom IODev espBridge
attr PulsStrom group Strom
attr PulsStrom presenceCheck 1
attr PulsStrom readingSwitchText 1
attr PulsStrom room 8.2_Energie
attr PulsStrom setState 3


define set_Stromverbrauch DOIF ([23:59])\
(setreading Strom Vorgestern [Strom:Gestern], \
setreading Strom Gestern [Strom:Heute], \
setreading Strom Monat [Strom_Statistik:Strom_zaehler_jetzt_EnergyMonth],\
setreading Strom zaehler_vortag [Strom:zaehler_jetzt])\
\
(set PulsStrom reboot) \
\
DOELSEIF ([00:01] and $mday==1) \
(setreading Strom Vormonat [Strom:Monat], \
setreading Strom Monat 0)
attr set_Stromverbrauch do always
attr set_Stromverbrauch group Strom
attr set_Stromverbrauch room 8.2_Energie
attr set_Stromverbrauch verbose 3


Help...!
RPI3B, SIGNALduino, NanoCul868 (a-culfw), JeeLink Clone (LaCrosse), Firmata  für FB Heizung,Wasser+Gas+Klingel+Lux, Somfy Rolladen, Pollin Steckd.,TX29DTH,ESPEasy an S0 Stromz., MAX Fensterkontakte, IButton, SonOff Tasmota, ESP LED Controler

Skusi

RPI3B, SIGNALduino, NanoCul868 (a-culfw), JeeLink Clone (LaCrosse), Firmata  für FB Heizung,Wasser+Gas+Klingel+Lux, Somfy Rolladen, Pollin Steckd.,TX29DTH,ESPEasy an S0 Stromz., MAX Fensterkontakte, IButton, SonOff Tasmota, ESP LED Controler

Thorsten Pferdekaemper

Hi,
was mir auffällt ist, dass Du zwei verschiedene Definitionen Deines DOIFs zeigst. Im zweiten steht "set PulsStrom reboot" in eigenen Klammern, also  (set PulsStrom reboot), beim ersten steht es mit den setreadings in denselben Klammern.
Keine Ahnung, was das bei DOIF macht, aber bei IF würde die zweite Definition wahrscheinlich gar nicht funktionieren.
Gruß,
   Thorsten
FUIP

Skusi

Du hast Recht, das hat sich beim schreiben des Postings wohl überschnitten. Den ersten Code habe ich aus der Web Oberfläche und den 2. aus der fhem.cfg. Dazwischen habe ich kein save ausgeführt.

Beide Definitionen funktionieren aber bei DOIF. Mit der (set PulsStrom reboot) ist es möglich eine Verzögerung einzubauen. Mittlerweile habe ich es schon so versucht das ich mit folgender Schreibweise:

([23:59])
(setreading Strom Vorgestern [Strom:Gestern],
setreading Strom Gestern [Strom:Heute],
setreading Strom Monat [Strom_Statistik:Strom_zaehler_jetzt_EnergyMonth],
setreading Strom zaehler_vortag [Strom:zaehler_jetzt],
set PulsStrom reboot)
(set PulsStrom reboot)
(set PulsStrom reboot)

DOELSEIF ([00:02] and $mday==1)
(setreading Strom Vormonat [Strom:Monat],
setreading Strom Monat 0)


und einem wait 0,5,5:0

den reboot Befehl 3 mal im 5 sec Takt schicke. Leider ohne Erfolg.
RPI3B, SIGNALduino, NanoCul868 (a-culfw), JeeLink Clone (LaCrosse), Firmata  für FB Heizung,Wasser+Gas+Klingel+Lux, Somfy Rolladen, Pollin Steckd.,TX29DTH,ESPEasy an S0 Stromz., MAX Fensterkontakte, IButton, SonOff Tasmota, ESP LED Controler