[vz-users] Verzögerung
Richard Nussdorfer
richard.nussdorfer at gmail.com
Di Feb 2 14:50:46 CET 2021
Hallo Stefan,
Würdest Du mir bitte helfen die Einträge einzuordnen:
14:47:00 oms -> dh vom IR-Kopf werden Daten aus dem Amis ausgelesen und an VZLogger übergeben
14:47:00 chnx -> die Daten werden an Empfänger übergeben?
14:47:01 oms … Beginn von neuem, also 1s Intervall
BG
[Feb 02 14:47:00][oms] got valid mbus frame with len=95, type=4 control=73 controlinfo=5b address=f0
[Feb 02 14:47:00][oms] control_word = 0x550
[Feb 02 14:47:00][oms] AES with dyn. init. vector for 5 16-byte blocks plus 3 unencrypted data bytes
[Feb 02 14:47:00][oms] got 10 data records: active mbus_debug for detail infos
[Feb 02 14:47:00][oms] DIF=06, NDIFE=00, DIFE1=00, VIF=6d, NVIFE=00 VIFE1=00 VIFE2=00
[Feb 02 14:47:00][oms] DIF=04, NDIFE=00, DIFE1=00, VIF=03, NVIFE=00 VIFE1=00 VIFE2=00
[Feb 02 14:47:00][oms] Obis 1.8.0 12016046.000000 Energy (Wh)
[Feb 02 14:47:00][oms] DIF=04, NDIFE=00, DIFE1=00, VIF=83, NVIFE=01 VIFE1=3c VIFE2=00
[Feb 02 14:47:00][oms] Obis 2.8.0 18236420.000000 Energy (Wh)
[Feb 02 14:47:00][oms] DIF=84, NDIFE=01, DIFE1=10, VIF=fb, NVIFE=02 VIFE1=82 VIFE2=73
[Feb 02 14:47:00][oms] DIF=84, NDIFE=01, DIFE1=10, VIF=fb, NVIFE=03 VIFE1=82 VIFE2=f3
[Feb 02 14:47:00][oms] DIF=04, NDIFE=00, DIFE1=00, VIF=2b, NVIFE=00 VIFE1=00 VIFE2=00
[Feb 02 14:47:00][oms] Obis 1.7.0 3023.000000 Power (W)
[Feb 02 14:47:00][oms] DIF=04, NDIFE=00, DIFE1=00, VIF=ab, NVIFE=01 VIFE1=3c VIFE2=00
[Feb 02 14:47:00][oms] Obis 2.7.0 0.000000 Power (W)
[Feb 02 14:47:00][oms] DIF=04, NDIFE=00, DIFE1=00, VIF=fb, NVIFE=01 VIFE1=14 VIFE2=00
[Feb 02 14:47:00][oms] DIF=04, NDIFE=00, DIFE1=00, VIF=fb, NVIFE=02 VIFE1=94 VIFE2=3c
[Feb 02 14:47:00][oms] DIF=04, NDIFE=00, DIFE1=00, VIF=83, NVIFE=02 VIFE1=ff VIFE2=04
[Feb 02 14:47:00][chn0] Adding reading to queue (value=12016046.00 ts=1612273654000)
[Feb 02 14:47:00][chn1] Adding reading to queue (value=18236420.00 ts=1612273654000)
[Feb 02 14:47:00][chn2] Adding reading to queue (value=3023.00 ts=1612273654000)
[Feb 02 14:47:00][chn3] Adding reading to queue (value=0.00 ts=1612273654000)
[Feb 02 14:47:00][chn2] ==> number of tuples: 1
[Feb 02 14:47:00][chn3] ==> number of tuples: 1
[Feb 02 14:47:00][chn2] compare: 1612273653000 1612273654000
[Feb 02 14:47:00][chn3] compare: 1612273653000 1612273654000
[Feb 02 14:47:00][chn2] JSON request body: [ [ 1612273654000, 3023 ] ]
[Feb 02 14:47:00][chn0] ==> number of tuples: 1
[Feb 02 14:47:00][chn0] compare: 1612273653000 1612273654000
[Feb 02 14:47:00][chn1] ==> number of tuples: 1
[Feb 02 14:47:00][chn1] compare: 1612273653000 1612273654000
[Feb 02 14:47:00][chn3] JSON request body: [ [ 1612273654000, 0 ] ]
[Feb 02 14:47:00][chn2] CURL Request succeeded with code: 200
[Feb 02 14:47:00][chn3] CURL Request succeeded with code: 200
[Feb 02 14:47:00][chn0] JSON request body: [ [ 1612273654000, 12016046 ] ]
[Feb 02 14:47:00][chn0] CURL Request succeeded with code: 200
[Feb 02 14:47:00][chn1] JSON request body: [ [ 1612273654000, 18236420 ] ]
[Feb 02 14:47:00][chn1] CURL Request succeeded with code: 200
[Feb 02 14:47:00][chn0] ==> number of tuples: 0
[Feb 02 14:47:00][chn1] ==> number of tuples: 0
[Feb 02 14:47:00][chn0] JSON request body is null. Nothing to send now.
[Feb 02 14:47:00][chn1] JSON request body is null. Nothing to send now.
[Feb 02 14:47:00][chn3] ==> number of tuples: 0
[Feb 02 14:47:00][chn3] JSON request body is null. Nothing to send now.
[Feb 02 14:47:00][chn2] ==> number of tuples: 0
[Feb 02 14:47:00][chn2] JSON request body is null. Nothing to send now.
[Feb 02 14:47:01][chn0] ==> number of tuples: 0
[Feb 02 14:47:01][chn1] ==> number of tuples: 0
[Feb 02 14:47:01][chn3] ==> number of tuples: 0
[Feb 02 14:47:01][chn1] JSON request body is null. Nothing to send now.
[Feb 02 14:47:01][chn3] JSON request body is null. Nothing to send now.
[Feb 02 14:47:01][chn0] JSON request body is null. Nothing to send now.
[Feb 02 14:47:01][chn2] ==> number of tuples: 0
[Feb 02 14:47:01][chn2] JSON request body is null. Nothing to send now.
[Feb 02 14:47:01][chn0] ==> number of tuples: 0
[Feb 02 14:47:01][chn1] ==> number of tuples: 0
[Feb 02 14:47:01][chn3] ==> number of tuples: 0
[Feb 02 14:47:01][chn1] JSON request body is null. Nothing to send now.
[Feb 02 14:47:01][chn0] JSON request body is null. Nothing to send now.
[Feb 02 14:47:01][chn3] JSON request body is null. Nothing to send now.
[Feb 02 14:47:01][chn2] ==> number of tuples: 0
[Feb 02 14:47:01][chn2] JSON request body is null. Nothing to send now.
[Feb 02 14:47:01][oms] got valid mbus frame with len=95, type=4 control=53 controlinfo=5b address=f0
[Feb 02 14:47:01][oms] control_word = 0x550
[Feb 02 14:47:01][oms] AES with dyn. init. vector for 5 16-byte blocks plus 3 unencrypted data bytes
[Feb 02 14:47:01][oms] got 10 data records: active mbus_debug for detail infos
[Feb 02 14:47:01][oms] DIF=06, NDIFE=00, DIFE1=00, VIF=6d, NVIFE=00 VIFE1=00 VIFE2=00
[Feb 02 14:47:01][oms] DIF=04, NDIFE=00, DIFE1=00, VIF=03, NVIFE=00 VIFE1=00 VIFE2=00
[Feb 02 14:47:01][oms] Obis 1.8.0 12016047.000000 Energy (Wh)
[Feb 02 14:47:01][oms] DIF=04, NDIFE=00, DIFE1=00, VIF=83, NVIFE=01 VIFE1=3c VIFE2=00
[Feb 02 14:47:01][oms] Obis 2.8.0 18236420.000000 Energy (Wh)
[Feb 02 14:47:01][oms] DIF=84, NDIFE=01, DIFE1=10, VIF=fb, NVIFE=02 VIFE1=82 VIFE2=73
[Feb 02 14:47:01][oms] DIF=84, NDIFE=01, DIFE1=10, VIF=fb, NVIFE=03 VIFE1=82 VIFE2=f3
[Feb 02 14:47:01][oms] DIF=04, NDIFE=00, DIFE1=00, VIF=2b, NVIFE=00 VIFE1=00 VIFE2=00
[Feb 02 14:47:01][oms] Obis 1.7.0 3025.000000 Power (W)
[Feb 02 14:47:01][oms] DIF=04, NDIFE=00, DIFE1=00, VIF=ab, NVIFE=01 VIFE1=3c VIFE2=00
[Feb 02 14:47:01][oms] Obis 2.7.0 0.000000 Power (W)
[Feb 02 14:47:01][oms] DIF=04, NDIFE=00, DIFE1=00, VIF=fb, NVIFE=01 VIFE1=14 VIFE2=00
[Feb 02 14:47:01][oms] DIF=04, NDIFE=00, DIFE1=00, VIF=fb, NVIFE=02 VIFE1=94 VIFE2=3c
[Feb 02 14:47:01][oms] DIF=04, NDIFE=00, DIFE1=00, VIF=83, NVIFE=02 VIFE1=ff VIFE2=04
[Feb 02 14:47:01][chn0] Adding reading to queue (value=12016047.00 ts=1612273655000)
[Feb 02 14:47:01][chn1] Adding reading to queue (value=18236420.00 ts=1612273655000)
[Feb 02 14:47:01][chn2] Adding reading to queue (value=3025.00 ts=1612273655000)
[Feb 02 14:47:01][chn3] Adding reading to queue (value=0.00 ts=1612273655000)
[Feb 02 14:47:01][chn0] ==> number of tuples: 1
[Feb 02 14:47:01][chn0] compare: 1612273654000 1612273655000
[Feb 02 14:47:01][chn1] ==> number of tuples: 1
[Feb 02 14:47:01][chn0] JSON request body: [ [ 1612273655000, 12016047 ] ]
[Feb 02 14:47:01][chn1] compare: 1612273654000 1612273655000
[Feb 02 14:47:01][chn3] ==> number of tuples: 1
[Feb 02 14:47:01][chn3] compare: 1612273654000 1612273655000
[Feb 02 14:47:01][chn2] ==> number of tuples: 1
[Feb 02 14:47:01][chn2] compare: 1612273654000 1612273655000
[Feb 02 14:47:01][chn0] CURL Request succeeded with code: 200
[Feb 02 14:47:01][chn1] JSON request body: [ [ 1612273655000, 18236420 ] ]
[Feb 02 14:47:01][chn1] CURL Request succeeded with code: 200
> Am 02.02.2021 um 13:43 schrieb Stefan Bauer <spam at stefan-bauer.net>:
>
> Und schon auch einen „Fehler“ gefunden.
> Im Log hast Du z.b. einen Eintrag heute von 12:47:39 mit dem timestamp 1612266493000
> Der Timestamp vom Zähler läuft aber auf 12:48:13 Uhr
>
> Welche Zeit jetzt korrekt ist, kann ich natürlich nicht sagen.
>
> Stefan
>
> Von meinem iPad gesendet
>
>> Am 02.02.2021 um 13:40 schrieb Stefan Bauer <spam at stefan-bauer.net>:
>>
>> In der Ansicht siehst Du die letzten Werte. Aber die stehen bei Dir ja auch im ausführlichen Log drin.
>> Da siehst Du, wann was mit welchem Zeitstempel übermittelt wurde....
>>
>> Stefan
>>
>> Von meinem iPad gesendet
>>
>>> Am 02.02.2021 um 12:51 schrieb Richard Nussdorfer <richard.nussdorfer at gmail.com>:
>>>
>>>
>>> Hallo Stefan,
>>>
>>> der httpd sollte aktiv auf 8081 sein, wie kann dort das beobachten?
>>>
>>> VZL läuft auf einem Raspi4
>>>
>>> BG
>>>
>>> Am Di., 2. Feb. 2021 um 12:48 Uhr schrieb Stefan Bauer <spam at stefan-bauer.net <mailto:spam at stefan-bauer.net>>:
>>> Zum sehen der Live-Werte bietet es sich eher an im vzlogger den httpd zu aktivieren und darüber beobachten...
>>> Sind die Daten im Frontend dann von der Uhrzeit auch 14s versetzt? Dann ist es eher ein Zeitproblem vom Zähler oder vom System auf dem der vzlogger läuft...
>>>
>>> Stefan
>>>
>>> Von meinem iPad gesendet
>>>
>>> > Am 02.02.2021 um 12:41 schrieb Richard Nussdorfer <richard.nussdorfer at gmail.com <mailto:richard.nussdorfer at gmail.com>>:
>>> >
>>> >
>>> > Hallo!
>>> >
>>> > Wollte fragen wie genau sich das Verhält.
>>> > Ich sehe am Zähler den Wert für Bezug/Lieferung (Hager), dem Amis nachgeschaltet.
>>> >
>>> > Der Amis wird per IR-Kopf ausgelesen (Code, Netz-OÖ) und jede Sekunde soll ein Wert kommen.
>>> > Wie ist die Verzögerung über die Middleware zum Frontend.
>>> > Im Frontend ist reingezoomt auf 2s (max).
>>> >
>>> > Ich habe gestoppt, vom Erscheinen des Lieferungspfeiles am Zähler bis im Frontend dieses eine Lieferung erkannt hat, vergingen 14s!
>>> >
>>> > OK, was heißt das?
>>> > BG Richard
>>> >
>>>
-------------- nächster Teil --------------
Ein Dateianhang mit HTML-Daten wurde abgetrennt...
URL: <http://demo.volkszaehler.org/pipermail/volkszaehler-users/attachments/20210202/6e2c2bf0/attachment-0001.html>
Mehr Informationen über die Mailingliste volkszaehler-users