[vz-users] vzlogger loggt nur im daemon Modus

Thu Nov 2 08:09:01 CET 2017

Hallo,
Am 02.11.2017 um 07:01 schrieb Daniel Lauckner:
> Hallo,

> am Mittwoch, 1. November 2017 um 21:49 hat RaspberryVZ geschrieben:
>>> Config? Logfile?
>> Logfile - siehe erstes Posting
> 
> Hilft leider nix weil die Verbositiy zu gering ist.
die Logs habe ich mit höherer verbosity erzeugt als in der beigefügten 
config, da kommt nicht mehr dabei raus. Eventuell bei der 
Initialisierung, da hatte ich schon gesucht und nicht mehr gefunden, als 
eben daemon true oder false.

> Ist aber egal hatte ne Idee und die gerade geprüft.
> Hängt mit der Aggregation zusammen...
> 
> 
> Zur Erläuterung:
> Ich hab in der Ecke Daemon/nicht Daemon schonmal gearbeitet. Wie sich
> im Gespräch herausstellte war das nicht senden der Daten als Bug in
> den Code eingezogen. Da es aber niemand kümmerte und es für
> Inbetriebnahme vielleicht nicht verkehrt ist nicht gleich jeden Quatsch
> an die MW weiter zu geben hab mich damals nicht weiter damit
> beschäftigt.
Wäre toll, wenn das wieder gehen würde. Man könnte ja einen neuen 
Parameter einbauen - Transfer zur MW oder nicht.

O.k., habe eben nochmal ein paarmal per Kommandozeile gestartet - bei 
drei Aufrufen hat er EINMAL EINEN der beiden Werte geschrieben. Muss 
also auch von irgendwas abhängen, ob er überträgt oder nicht. Passt zum 
Thema Aggregation des vzlogger.

Mal ein paar Zeilen mehr von dem eben entdeckten Effekt (ein Wert 
übertragen):
[Nov 02 07:59:32][main] vzlogger v0.6.1 based on 
heads/master-0-g5342f0b096 from Mon, 9 Oct 2017 18:15:48 +0200 started.
[Nov 02 07:59:32][mtr0] Creating new meter with protocol sml.
[Nov 02 07:59:32][mtr0] Meter configured, enabled.
[Nov 02 07:59:32]       New meter initialized (protocol=sml)
[Nov 02 07:59:32]       Configure channel.
[Nov 02 07:59:32][chn0] New channel initialized (uuid=...b863cf 
api=volkszaehler id=1-0:15.7.0)
[Nov 02 07:59:32][mtr1] Creating new meter with protocol sml.
[Nov 02 07:59:32][mtr1] Meter configured, enabled.
[Nov 02 07:59:32]       New meter initialized (protocol=sml)
[Nov 02 07:59:32]       Configure channel.
[Nov 02 07:59:32][chn1] New channel initialized (uuid=...f31b3c 
api=volkszaehler id=1-0:15.7.0)
[Nov 02 07:59:32]       Have 2 meters.
[Nov 02 07:59:32][main] log level is 15
[Nov 02 07:59:32][main] daemon=0, local=0
[Nov 02 07:59:32]       Process not  daemonized...
[Nov 02 07:59:32]       Opened logfile /var/log/vzlogger.log
[Nov 02 07:59:32][push] No pushDataServer defined.
[Nov 02 07:59:32][]     ===> Start meters
[Nov 02 07:59:32][mtr0] Meter connection established
[Nov 02 07:59:32][mtr0] Meter thread started
[Nov 02 07:59:32][mtr0] Meter is opened. Starting channels.
[Nov 02 07:59:32][chn0] Logging thread started
[Nov 02 07:59:32][chn0] Start logging thread for volkszaehler-api. 
Running as daemon: no
[Nov 02 07:59:32][mtr0] Number of readers: 32
[Nov 02 07:59:32][mtr1] Meter connection established
[Nov 02 07:59:32][mtr1] Meter thread started
[Nov 02 07:59:32][mtr1] Meter is opened. Starting channels.
[Nov 02 07:59:32][chn1] Logging thread started
[Nov 02 07:59:32][]     Startup done.
[Nov 02 07:59:32][mtr0] Config.daemon: 0
[Nov 02 07:59:32][mtr0] Config.local: 0
[Nov 02 07:59:32][chn0] Using default volkszaehler api.
[Nov 02 07:59:32][chn1] Start logging thread for volkszaehler-api. 
Running as daemon: no
[Nov 02 07:59:32][chn1] Using default volkszaehler api.
[Nov 02 07:59:32][mtr1] Number of readers: 32
[Nov 02 07:59:32][mtr1] Config.daemon: 0
[Nov 02 07:59:32][mtr1] Config.local: 0
[Nov 02 07:59:34][mtr1] Got 8 new readings from meter:
[Nov 02 07:59:34][mtr1] Reading: 
id=1-0:1.8.0*255/ObisIdentifier:1-0:1.8.0*255 value=23163052.90 
ts=1509605974004
[Nov 02 07:59:34][mtr1] Reading: 
id=1-0:1.8.1*255/ObisIdentifier:1-0:1.8.1*255 value=23163052.90 
ts=1509605974004
[Nov 02 07:59:34][mtr1] Reading: 
id=1-0:1.8.2*255/ObisIdentifier:1-0:1.8.2*255 value=0.00 ts=1509605974004
[Nov 02 07:59:34][mtr1] Reading: 
id=1-0:15.7.0*255/ObisIdentifier:1-0:15.7.0*255 value=2861.00 
ts=1509605974004
[Nov 02 07:59:34][mtr1] Reading: 
id=1-0:21.7.0*255/ObisIdentifier:1-0:21.7.0*255 value=1089.00 
ts=1509605974004
[Nov 02 07:59:34][mtr1] Reading: 
id=1-0:41.7.0*255/ObisIdentifier:1-0:41.7.0*255 value=878.00 
ts=1509605974004
[Nov 02 07:59:34][mtr1] Reading: 
id=1-0:61.7.0*255/ObisIdentifier:1-0:61.7.0*255 value=892.00 
ts=1509605974004
[Nov 02 07:59:34][mtr1] Reading: 
id=1-0:96.5.5*255/ObisIdentifier:1-0:96.5.5*255 value=3860.00 
ts=1509605974004
[Nov 02 07:59:34][chn1] Adding reading to queue (value=2861.00 
ts=1509605974004)
[Nov 02 07:59:34][chn1] ==> number of tuples: 1
[Nov 02 07:59:34][chn1] compare: 0 1509605974004
[Nov 02 07:59:34][chn1] copied 1/1 values for middleware transmission
[Nov 02 07:59:34][chn1] JSON request body: [ [ 1509605974004, 2861 ] ]
[Nov 02 07:59:34][mtr1] Stopped reading.
[Nov 02 07:59:34][chn1] CURL:   Trying 127.0.0.1...
[Nov 02 07:59:34][chn1] CURL: TCP_NODELAY set
[Nov 02 07:59:34][chn1] CURL: Connected to localhost (127.0.0.1) port 80 
(#0)
[Nov 02 07:59:34][chn1] CURL: Sent 27 bytes..
[Nov 02 07:59:34][chn1] CURL: Sent '[ [ 1509605974004, 2861 ] ]' bytes
[Nov 02 07:59:34][chn1] CURL: upload completely sent off: 27 out of 27 bytes
[Nov 02 07:59:34][chn1] CURL: HTTP 1.0, assume close after body
[Nov 02 07:59:34][chn1] CURL: Received 26 bytes
[Nov 02 07:59:34][chn1] CURL: Received '{"version":"0.3","rows":1}' bytes
[Nov 02 07:59:34][chn1] CURL: Curl_http_done: called premature == 0
[Nov 02 07:59:34][chn1] CURL: Closing connection 0
[Nov 02 07:59:34][chn1] CURL Request succeeded with code: 200
[Nov 02 07:59:34][chn1] emptied all (1) values
[Nov 02 07:59:34][mtr0] Got 8 new readings from meter:
[Nov 02 07:59:34][mtr0] Reading: 
id=1-0:1.8.0*255/ObisIdentifier:1-0:1.8.0*255 value=33164456.70 
ts=1509605974189
[Nov 02 07:59:34][mtr0] Reading: 
id=1-0:1.8.1*255/ObisIdentifier:1-0:1.8.1*255 value=33163434.20 
ts=1509605974189
[Nov 02 07:59:34][mtr0] Reading: 
id=1-0:1.8.2*255/ObisIdentifier:1-0:1.8.2*255 value=1022.50 ts=1509605974189
[Nov 02 07:59:34][mtr0] Reading: 
id=1-0:15.7.0*255/ObisIdentifier:1-0:15.7.0*255 value=826.00 
ts=1509605974189
[Nov 02 07:59:34][mtr0] Reading: 
id=1-0:21.7.0*255/ObisIdentifier:1-0:21.7.0*255 value=330.00 
ts=1509605974189
[Nov 02 07:59:34][mtr0] Reading: 
id=1-0:41.7.0*255/ObisIdentifier:1-0:41.7.0*255 value=345.00 
ts=1509605974189
[Nov 02 07:59:34][mtr0] Reading: 
id=1-0:61.7.0*255/ObisIdentifier:1-0:61.7.0*255 value=151.00 
ts=1509605974189
[Nov 02 07:59:34][mtr0] Reading: 
id=1-0:96.5.5*255/ObisIdentifier:1-0:96.5.5*255 value=3860.00 
ts=1509605974189
[Nov 02 07:59:34][chn0] Adding reading to queue (value=826.00 
ts=1509605974189)
[Nov 02 07:59:34][chn0] ==> number of tuples: 1
[Nov 02 07:59:34][chn0] compare: 0 1509605974189
[Nov 02 07:59:34][chn0] copied 1/1 values for middleware transmission
[Nov 02 07:59:34][chn0] JSON request body: [ [ 1509605974189, 826 ] ]
[Nov 02 07:59:34][chn0] CURL: Hostname localhost was found in DNS cache
[Nov 02 07:59:34][chn0] CURL:   Trying 127.0.0.1...
[Nov 02 07:59:34][chn0] CURL: TCP_NODELAY set
[Nov 02 07:59:34][mtr0] Stopped reading.
[Nov 02 07:59:34][]     Server stopped.
[Nov 02 07:59:34][]     Trying to delete curlSessionProvider...
[Nov 02 07:59:34][chn0] CURL: Curl_http_done: called premature == 1
[Nov 02 07:59:34][chn0] CURL: stopped the pause stream!
[Nov 02 07:59:34][chn0] CURL: Closing connection 1
[Nov 02 07:59:34][]     deleted curlSessionProvider

Danke und Gruß,
Frank