[vz-users] Verzögerung

Di Feb 2 20:09:48 CET 2021

So 100%ig weiß ich das auch nicht, was jede Zeile heißt.
Das „oms“ ist auf alle Fälle das lesen der aktuellen Daten.
Beim „CURL“ werden zum Ende hin Daten an die middleware übergeben...

Stefan

Von meinem iPad gesendet

> Am 02.02.2021 um 14:50 schrieb Richard Nussdorfer <richard.nussdorfer at gmail.com>:
> 
> 
> 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>:
>>>>> 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>:
>>>>> > 
>>>>> > 
>>>>> > 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/8ed02234/attachment.html>