[vz-users] vzlogger sendet nach einem Stromausfall keineDatenmehr an die Middleware
vor dem Esche
vordemesche at t-online.de
Fr Okt 30 19:37:16 CET 2020
Problem scheint gelöst. Ich habe:
"debounce_delay": 2
Auf 2ms runtergesetzt nachdem ich gemerkt habe, das die Impulse ca. 4ms lang sind.
Danke für die Hilfe!!
Anbei das logfile
[Oct 30 19:26:14][main] vzlogger v0.8.0 based on heads/master-0-gc5bcd3296a from Sat, 25 Jul 2020 08:54:47 +0200 started.
[Oct 30 19:26:14][mtr0] Creating new meter with protocol s0.
[Oct 30 19:26:14][mtr0] Meter configured, enabled.
[Oct 30 19:26:14] New meter initialized (protocol=s0)
[Oct 30 19:26:14] Configure channel.
[Oct 30 19:26:14][chn0] New channel initialized (uuid=...a16326 api=volkszaehler id=Impulse)
[Oct 30 19:26:14][mtr1] Creating new meter with protocol s0.
[Oct 30 19:26:14][mtr1] Meter configured, enabled.
[Oct 30 19:26:14] New meter initialized (protocol=s0)
[Oct 30 19:26:14] Configure channel.
[Oct 30 19:26:14][chn1] New channel initialized (uuid=...fbfa4f api=volkszaehler id=Impulse)
[Oct 30 19:26:14][mtr2] Creating new meter with protocol s0.
[Oct 30 19:26:14][mtr2] Meter configured, enabled.
[Oct 30 19:26:14] New meter initialized (protocol=s0)
[Oct 30 19:26:14] Configure channel.
[Oct 30 19:26:14][chn2] New channel initialized (uuid=...b93dc3 api=volkszaehler id=Impulse)
[Oct 30 19:26:14][mtr3] Creating new meter with protocol s0.
[Oct 30 19:26:14][mtr3] Meter configured, enabled.
[Oct 30 19:26:14] New meter initialized (protocol=s0)
[Oct 30 19:26:14] Configure channel.
[Oct 30 19:26:14][chn3] New channel initialized (uuid=...5c376b api=volkszaehler id=Impulse)
[Oct 30 19:26:14] Have 4 meters.
[Oct 30 19:26:14][main] log level is 15
[Oct 30 19:26:14][main] daemon=1, local=0
[Oct 30 19:26:14] Daemonize process...
[Oct 30 19:26:14] Opened logfile /home/pi/vzlogger.log
[Oct 30 19:26:14][push] No pushDataServer defined.
[Oct 30 19:26:14][] ===> Start meters
[Oct 30 19:26:14][s0] counter_thread created
[Oct 30 19:26:14][s0] Counter thread started with blocking hwif
[Oct 30 19:26:14][mtr0] Meter connection established
[Oct 30 19:26:14][mtr0] Meter thread started
[Oct 30 19:26:14][mtr0] Meter is opened. Starting channels.
[Oct 30 19:26:14][mtr0] Number of readers: 4
[Oct 30 19:26:14][mtr0] Config.daemon: 1
[Oct 30 19:26:14][chn0] Logging thread started
[Oct 30 19:26:14][chn0] Start logging thread for volkszaehler-api. Running as daemon: yes
[Oct 30 19:26:14][mtr0] Config.local: 0
[Oct 30 19:26:14][s0] counter_thread created
[Oct 30 19:26:14][s0] Counter thread started with blocking hwif
[Oct 30 19:26:14][mtr1] Meter connection established
[Oct 30 19:26:14][chn0] Using default volkszaehler api.
[Oct 30 19:26:14][mtr1] Meter thread started
[Oct 30 19:26:14][mtr1] Meter is opened. Starting channels.
[Oct 30 19:26:14][chn1] Logging thread started
[Oct 30 19:26:14][chn1] Start logging thread for volkszaehler-api. Running as daemon: yes
[Oct 30 19:26:14][chn1] Using default volkszaehler api.
[Oct 30 19:26:14][s0] counter_thread created
[Oct 30 19:26:14][s0] Counter thread started with blocking hwif
[Oct 30 19:26:14][mtr2] Meter connection established
[Oct 30 19:26:14][mtr2] Meter thread started
[Oct 30 19:26:14][mtr2] Number of readers: 4
[Oct 30 19:26:14][mtr2] Config.daemon: 1
[Oct 30 19:26:14][mtr2] Meter is opened. Starting channels.
[Oct 30 19:26:14][mtr2] Config.local: 0
[Oct 30 19:26:14][chn2] Logging thread started
[Oct 30 19:26:14][chn2] Start logging thread for volkszaehler-api. Running as daemon: yes
[Oct 30 19:26:14][chn2] Using default volkszaehler api.
[Oct 30 19:26:14][mtr1] Meter thread started
[Oct 30 19:26:14][mtr1] Meter is opened. Starting channels.
[Oct 30 19:26:14][chn1] Logging thread started
[Oct 30 19:26:14][chn1] Start logging thread for volkszaehler-api. Running as daemon: yes
[Oct 30 19:26:14][chn1] Using default volkszaehler api.
[Oct 30 19:26:14][s0] counter_thread created
[Oct 30 19:26:14][s0] Counter thread started with blocking hwif
[Oct 30 19:26:14][mtr2] Meter connection established
[Oct 30 19:26:14][mtr2] Meter thread started
[Oct 30 19:26:14][mtr2] Number of readers: 4
[Oct 30 19:26:14][mtr2] Config.daemon: 1
[Oct 30 19:26:14][mtr2] Meter is opened. Starting channels.
[Oct 30 19:26:14][mtr2] Config.local: 0
[Oct 30 19:26:14][chn2] Logging thread started
[Oct 30 19:26:14][chn2] Start logging thread for volkszaehler-api. Running as daemon: yes
[Oct 30 19:26:14][chn2] Using default volkszaehler api.
[Oct 30 19:26:14][s0] counter_thread created
[Oct 30 19:26:14][mtr3] Meter connection established
[Oct 30 19:26:14][mtr3] Meter thread started
[Oct 30 19:26:14][mtr3] Number of readers: 4
[Oct 30 19:26:14][mtr3] Meter is opened. Starting channels.
[Oct 30 19:26:14][mtr3] Config.daemon: 1
[Oct 30 19:26:14][mtr3] Config.local: 0
[Oct 30 19:26:14][chn3] Logging thread started
[Oct 30 19:26:14][s0] Counter thread started with blocking hwif
[Oct 30 19:26:14][] Startup done.
[Oct 30 19:26:14][chn3] Start logging thread for volkszaehler-api. Running as daemon: yes
[Oct 30 19:26:14][mtr1] Number of readers: 4
[Oct 30 19:26:14][mtr1] Config.daemon: 1
[Oct 30 19:26:14][mtr1] Config.local: 0
[Oct 30 19:26:14][chn3] Using default volkszaehler api.
[Oct 30 19:26:14][S0] MeterS0:HWIF_GPIO:first poll returned 1
[Oct 30 19:26:14][S0] MeterS0:HWIF_GPIO:first poll returned 1
[Oct 30 19:26:14][S0] MeterS0:HWIF_GPIO:first poll returned 1
[Oct 30 19:26:15][S0] MeterS0:HWIF_GPIO:first poll returned 1
[Oct 30 19:26:15][S0] MeterS0:HWIF_GPIO:first poll returned 0
[Oct 30 19:26:15][S0] MeterS0:HWIF_GPIO:first poll returned 0
[Oct 30 19:26:15][s0] Reading S0 - returning 1 readings (n=1 n_neg = 0)
[Oct 30 19:26:15][mtr2] Got 1 new readings from meter:
[Oct 30 19:26:15][mtr2] Reading: id=Impulse/StringIdentifier: value=1.00 ts=1604082374583
[Oct 30 19:26:15][chn2] Adding reading to queue (value=1.00 ts=1604082374583)
[Oct 30 19:26:15][chn2] ==> number of tuples: 1
[Oct 30 19:26:15][s0] Reading S0 - returning 1 readings (n=2 n_neg = 0)
[Oct 30 19:26:15][chn2] compare: 0 1604082374583
[Oct 30 19:26:15][mtr3] Got 1 new readings from meter:
[Oct 30 19:26:15][chn2] copied 1/1 values for middleware transmission
[Oct 30 19:26:15][mtr3] Reading: id=Impulse/StringIdentifier: value=2.00 ts=1604082375511
[Oct 30 19:26:15][chn3] Adding reading to queue (value=2.00 ts=1604082375511)
[Oct 30 19:26:15][chn3] ==> number of tuples: 1
[Oct 30 19:26:15][chn3] compare: 0 1604082375511
[Oct 30 19:26:15][chn2] JSON request body: [ [ 1604082374583, 1 ] ]
[Oct 30 19:26:15][chn3] copied 1/1 values for middleware transmission
[Oct 30 19:26:15][chn2] CURL: Expire in 0 ms for 6 (transfer 0x71a00dd0)
[Oct 30 19:26:15][chn2] CURL: Expire in 30000 ms for 8 (transfer 0x71a00dd0)
[Oct 30 19:26:15][chn2] CURL: Trying 127.0.0.1...
[Oct 30 19:26:15][chn2] CURL: TCP_NODELAY set
[Oct 30 19:26:15][chn2] CURL: Expire in 200 ms for 4 (transfer 0x71a00dd0)
[Oct 30 19:26:15][chn2] CURL: Connected to 127.0.0.1 (127.0.0.1) port 8080 (#0)
[Oct 30 19:26:15][chn2] CURL: Sent 24 bytes..
[Oct 30 19:26:15][chn2] CURL: Sent '[ [ 1604082374583, 1 ] ]' bytes
[Oct 30 19:26:15][chn2] CURL: upload completely sent off: 24 out of 24 bytes
[Oct 30 19:26:15][chn2] CURL: Received 26 bytes
[Oct 30 19:26:15][chn2] CURL: Received '{"version":"0.3","rows":1}' bytes
[Oct 30 19:26:15][chn2] CURL: Closing connection 0
[Oct 30 19:26:15][chn2] CURL Request succeeded with code: 200
[Oct 30 19:26:15][chn3] JSON request body: [ [ 1604082375511, 2 ] ]
[Oct 30 19:26:15][chn2] emptied all (1) values
[Oct 30 19:26:15][chn3] CURL: Expire in 0 ms for 6 (transfer 0x71a00dd0)
[Oct 30 19:26:15][chn3] CURL: Expire in 30000 ms for 8 (transfer 0x71a00dd0)
[Oct 30 19:26:15][chn3] CURL: Hostname 127.0.0.1 was found in DNS cache
[Oct 30 19:26:15][chn3] CURL: Trying 127.0.0.1...
[Oct 30 19:26:15][chn3] CURL: TCP_NODELAY set
[Oct 30 19:26:15][chn3] CURL: Expire in 200 ms for 4 (transfer 0x71a00dd0)
[Oct 30 19:26:15][chn3] CURL: Connected to 127.0.0.1 (127.0.0.1) port 8080 (#1)
[Oct 30 19:26:15][chn3] CURL: Sent 24 bytes..
[Oct 30 19:26:15][chn3] CURL: Sent '[ [ 1604082375511, 2 ] ]' bytes
[Oct 30 19:26:15][chn3] CURL: upload completely sent off: 24 out of 24 bytes
[Oct 30 19:26:15][chn3] CURL: Received 26 bytes
[Oct 30 19:26:15][chn3] CURL: Received '{"version":"0.3","rows":1}' bytes
[Oct 30 19:26:15][chn3] CURL: Closing connection 1
[Oct 30 19:26:15][chn3] CURL Request succeeded with code: 200
[Oct 30 19:26:15][chn3] emptied all (1) values
[Oct 30 19:26:15][S0] MeterS0:HWIF_GPIO:first poll returned 0
[Oct 30 19:26:15][S0] MeterS0:HWIF_GPIO:first poll returned 1
[Oct 30 19:26:16][S0] MeterS0:HWIF_GPIO:first poll returned 1
[Oct 30 19:26:16][S0] MeterS0:HWIF_GPIO:first poll returned 0
[Oct 30 19:26:16][S0] MeterS0:HWIF_GPIO:first poll returned 0
[Oct 30 19:26:16][s0] Reading S0 - returning 2 readings (n=1 n_neg = 0)
[Oct 30 19:26:16][mtr2] Got 2 new readings from meter:
[Oct 30 19:26:16][mtr2] Reading: id=Power/StringIdentifier: value=2647.06 ts=1604082375943
[Oct 30 19:26:16][mtr2] Reading: id=Impulse/StringIdentifier: value=1.00 ts=1604082375943
[Oct 30 19:26:16][chn2] Adding reading to queue (value=1.00 ts=1604082375943)
[Oct 30 19:26:16][chn2] ==> number of tuples: 1
[Oct 30 19:26:16][chn2] compare: 1604082374583 1604082375943
[Oct 30 19:26:16][chn2] copied 1/1 values for middleware transmission
[Oct 30 19:26:16][chn2] JSON request body: [ [ 1604082375943, 1 ] ]
[Oct 30 19:26:16][chn2] CURL: Expire in 0 ms for 6 (transfer 0x71a00dd0)
[Oct 30 19:26:16][chn2] CURL: Expire in 30000 ms for 8 (transfer 0x71a00dd0)
[Oct 30 19:26:16][chn2] CURL: Hostname 127.0.0.1 was found in DNS cache
[Oct 30 19:26:16][chn2] CURL: Trying 127.0.0.1...
[Oct 30 19:26:16][chn2] CURL: TCP_NODELAY set
[Oct 30 19:26:16][s0] Reading S0 - returning 2 readings (n=1 n_neg = 0)
[Oct 30 19:26:16][mtr3] Got 2 new readings from meter:
Gesendet von Mail für Windows 10
Von: Daniel Lauckner
Gesendet: Freitag, 30. Oktober 2020 14:11
An: volkszaehler.org - users
Betreff: Re: [vz-users] vzlogger sendet nach einem Stromausfall keineDatenmehr an die Middleware
Hallo,
am Freitag, 30. Oktober 2020 um 13:56 hat vor dem Esche geschrieben:
> Im Grunde kommen im Loglile nur noch Meldungen der Art:
> [Oct 25 19:14:16][S0] MeterS0:HWIF_GPIO:first poll returned 1
> [Oct 25 19:14:17][S0] MeterS0:HWIF_GPIO:first poll returned 0
> ……
Normal werden Impulse als Interrupt behandelt. Ist eine Sekunde um
wird aber dennoch gepollt.
Fehlende [curl] heißt von vzlogger nix gesendet, offensichtlich weil
halt einfach nix rein kommt.
Also Hardware prüfen. Impulse erzeugen, im Log kontrollieren.
Womöglich hat beim Stromausfall irgendwas die Füße gehoben...
mfg Daniel
-------------- nächster Teil --------------
Ein Dateianhang mit HTML-Daten wurde abgetrennt...
URL: <http://demo.volkszaehler.org/pipermail/volkszaehler-users/attachments/20201030/6c55abe2/attachment-0001.html>
Mehr Informationen über die Mailingliste volkszaehler-users