[vz-users] Fehlersuche-HowTo: 1wirevz und s0vz
Andreas Götz
cpuidle at gmail.com
Thu Jun 23 17:20:12 CEST 2016
Ich nochmal...
> Am 23.06.2016 um 11:45 schrieb Heiko Baumann <hbcs at gmx.de>:
>
>> Welches Image nutzt du im Moment?
> Hm, hab ich vor ein paar Tagen runterladen, "offiz. Quelle". Ich glaub (!) ich hab sogar auch noch ein Update gemacht.
> vzlogger ist vom 5.2.2016
>
>> Setz mal bitte verbosity auf 15 und dann starte nochmal neu.
>
> liefert.. :
> pi at BauratPi:/etc $ sudo vzlogger restart
Wieso nicht sudo service vzlogger restart? Das sollte den Service neu starten. Vzlogger startest Du normalerweise nicht händisch. Läuft der Service auch noch? Was sagt ps aux | grep vzlogger?
Und was sagt dann das log auf level 15? Der zweite Channel wundert mich nämlich auch....
Viele Grüße, Andreas
> [Jun 23 11:42:04][main] vzlogger v0.5.1 based on heads/master-0-g7e79f4c700-dirty from Fri, 5 Feb 2016 18:03:28 +0100 started.
> [Jun 23 11:42:04][mtr0] Creating new meter with protocol w1therm.
> [Jun 23 11:42:04][mtr0] Meter configured, enabled.
> [Jun 23 11:42:04] New meter initialized (protocol=w1therm)
> [Jun 23 11:42:04] Configure channel.
> [Jun 23 11:42:04][chn0] New channel initialized (uuid=...77fb28 api=volkszaehler id=28-0000045d2497)
> [Jun 23 11:42:04] Have 1 meters.
> [Jun 23 11:42:04][main] log level is 15
> [Jun 23 11:42:04][main] daemon=1, local=0
> [Jun 23 11:42:04] Daemonize process...
>
> .. und im log:
> pi at BauratPi:~ $ sudo tail -f /var/log/vzlogger.log
> [Jun 23 11:42:04][chn0] compare: 1466674895110 1466674905560
> [Jun 23 11:42:04][chn0] compare: 1466674905560 1466674916430
> [Jun 23 11:42:04][chn0] JSON request body: [ [ 1466674905560, 43.625 ], [ 1466674916430, 43.625 ] ]
> [Jun 23 11:42:04][chn0] CURL: Hostname was found in DNS cache
> [Jun 23 11:42:04][chn0] CURL: Trying 127.0.0.1...
> [Jun 23 11:42:04][chn0] Buffer dump (size=0): {}
> [Jun 23 11:42:04][chn0] CURL: Connected to 127.0.0.1 (127.0.0.1) port 80 (#9)
> [Jun 23 11:42:04][chn0] CURL: Sent 56 bytes..
> [Jun 23 11:42:04][chn0] CURL: Sent '[ [ 1466674905560, 43.625 ], [ 1466674916430, 43.625 ] ]' bytes
> [Jun 23 11:42:04][chn0] CURL: upload completely sent off: 56 out of 56 bytes
> [Jun 23 11:42:04][chn0] Adding reading to queue (value=4.00 ts=1466674924606)
> [Jun 23 11:42:05][chn0] Adding reading to queue (value=43.62 ts=1466674917381)
> [Jun 23 11:42:05][chn1] Adding reading to queue (value=23.56 ts=1466674924364)
> [Jun 23 11:42:05] Opened logfile /var/log/vzlogger.log
> [Jun 23 11:42:05][push] No pushDataServer defined.
> [Jun 23 11:42:05][] ===> Start meters
> [Jun 23 11:42:05][w1t] open found 12 w1 devices
> [Jun 23 11:42:05][mtr0] Meter connection established
> [Jun 23 11:42:05][mtr0] Meter thread started
> [Jun 23 11:42:05][mtr0] Meter is opened. Starting channels.
> [Jun 23 11:42:05][chn0] Logging thread started
> [Jun 23 11:42:05][] Startup done.
> [Jun 23 11:42:05][chn0] Start logging thread for volkszaehler-api. Running as daemon: yes
> [Jun 23 11:42:05][mtr0] Number of readers: 400
> [Jun 23 11:42:05][mtr0] Config.daemon: 1
> [Jun 23 11:42:05][mtr0] Config.local: 0
> [Jun 23 11:42:05][chn0] Using default volkszaehler api.
> [Jun 23 11:42:05][w1t] read 10.000000 from /sys/bus/w1/devices/28-0000045c6924/w1_slave (t=10000
> )
> [Jun 23 11:42:05][w1t] reading w1 device 28-0000045c6924 returned 10.000000
> [Jun 23 11:42:05][chn0] Adding reading to queue (value=4.00 ts=1466674925590)
> [Jun 23 11:42:06][w1t] read 10.000000 from /sys/bus/w1/devices/28-0000045c6924/w1_slave (t=10000
> )
> [Jun 23 11:42:06][w1t] reading w1 device 28-0000045c6924 returned 10.000000
> [Jun 23 11:42:06][w1t] read 24.687000 from /sys/bus/w1/devices/28-0000045c5c7e/w1_slave (t=24687
> )
> [Jun 23 11:42:06][w1t] reading w1 device 28-0000045c5c7e returned 24.687000
> [Jun 23 11:42:06][chn0] Adding reading to queue (value=5.00 ts=1466674926820)
> [Jun 23 11:42:06][chn0] CURL: HTTP 1.0, assume close after body
> [Jun 23 11:42:07][chn0] CURL: Received 26 bytes
> [Jun 23 11:42:07][chn0] CURL: Received '{"version":"0.3","rows":2}' bytes
> [Jun 23 11:42:07][chn0] CURL: Closing connection 9
> [Jun 23 11:42:07][chn0] CURL Request succeeded with code: 200
> [Jun 23 11:42:07][w1t] read 43.625000 from /sys/bus/w1/devices/28-0000045d2497/w1_slave (t=43625
> )
> [Jun 23 11:42:07][w1t] reading w1 device 28-0000045d2497 returned 43.625000
> [Jun 23 11:42:07][w1t] read 24.687000 from /sys/bus/w1/devices/28-0000045c5c7e/w1_slave (t=24687
> )
> [Jun 23 11:42:07][w1t] reading w1 device 28-0000045c5c7e returned 24.687000
> [Jun 23 11:42:07][chn0] Adding reading to queue (value=4.00 ts=1466674927809)
> [Jun 23 11:42:08][w1t] read 16.812000 from /sys/bus/w1/devices/28-0000045d7eb0/w1_slave (t=16812
> )
> [Jun 23 11:42:08][w1t] reading w1 device 28-0000045d7eb0 returned 16.812000
> [Jun 23 11:42:08][w1t] read 43.625000 from /sys/bus/w1/devices/28-0000045d2497/w1_slave (t=43625
> )
> [Jun 23 11:42:08][w1t] reading w1 device 28-0000045d2497 returned 43.625000
> [Jun 23 11:42:08][chn0] Adding reading to queue (value=4.00 ts=1466674928797)
> [Jun 23 11:42:09][chn1] Adding reading to queue (value=43.62 ts=1466674920402)
> [Jun 23 11:42:09][w1t] read 18.437000 from /sys/bus/w1/devices/28-000004a2845a/w1_slave (t=18437
> )
> [Jun 23 11:42:09][w1t] reading w1 device 28-000004a2845a returned 18.437000
> [Jun 23 11:42:09][w1t] read 16.812000 from /sys/bus/w1/devices/28-0000045d7eb0/w1_slave (t=16812
> )
> [Jun 23 11:42:09][w1t] reading w1 device 28-0000045d7eb0 returned 16.812000
> [Jun 23 11:42:09][chn0] Adding reading to queue (value=4.00 ts=1466674929785)
> [Jun 23 11:42:10][w1t] read 18.437000 from /sys/bus/w1/devices/28-000004a2845a/w1_slave (t=18437
> )
> [Jun 23 11:42:10][w1t] reading w1 device 28-000004a2845a returned 18.437000
> [Jun 23 11:42:10][w1t] read 9.937000 from /sys/bus/w1/devices/28-000004c913d5/w1_slave (t=9937
> )
> [Jun 23 11:42:10][w1t] reading w1 device 28-000004c913d5 returned 9.937000
> [Jun 23 11:42:10][chn0] Adding reading to queue (value=4.00 ts=1466674930773)
> [Jun 23 11:42:10][chn0] Adding reading to queue (value=43.62 ts=1466674922387)
> [Jun 23 11:42:10][mtr0] Next reading in 10 seconds
> [Jun 23 11:42:11][w1t] read 10.125000 from /sys/bus/w1/devices/28-000004c9583f/w1_slave (t=10125
> )
> [Jun 23 11:42:11][w1t] reading w1 device 28-000004c9583f returned 10.125000
>
> ...
>
>
>> Am 23.06.2016 um 11:24 schrieb Andreas Götz:
>> Setz mal bitte verbosity auf 15 und dann starte nochmal neu.
>>
>> Viele Grüße, Andreas
>>
>> Am 23.06.2016 um 11:01 schrieb Heiko Baumann <hbcs at gmx.de>:
>>
>>> Hallo Andreas,
>>>
>>> Danke dass du auch mit drüber schaust.
>>>
>>> > Bist Du sicher dass diese conf Datei auch verwendet wird? In welchem Ordner liegt sie und wie startest Du vzlogger?
>>>
>>> Jetzt hast du mich kurz mal in Verlegenheit gebracht, aber ja:
>>>
>>>
>>> Gestartet wird mit
>>>
>>> pi at BauratPi:/etc $ sudo vzlogger restart
>>> [Jun 23 11:00:06][main] vzlogger v0.5.1 based on heads/master-0-g7e79f4c700-dirty from Fri, 5 Feb 2016 18:03:28 +0100 started.
>>> [Jun 23 11:00:06] New meter initialized (protocol=w1therm)
>>> [Jun 23 11:00:06][chn0] New channel initialized (uuid=...77fb28 api=volkszaehler id=28-0000045d2497)
>>> [Jun 23 11:00:06][main] log level is 5
>>> [Jun 23 11:00:06] Daemonize process...
>>>
>>>
>>> pi at BauratPi:~ $ sudo tail -20 /var/log/vzlogger.log
>>> [Jun 23 11:00:18][chn1] Adding reading to queue (value=43.69 ts=1466672410490)
>>> [Jun 23 11:00:18][mtr1] Next reading in 10 seconds
>>> [Jun 23 11:00:19][chn0] Adding reading to queue (value=4.00 ts=1466672419314)
>>> [Jun 23 11:00:20][chn0] Adding reading to queue (value=4.00 ts=1466672420306)
>>> [Jun 23 11:00:20][chn1] Adding reading to queue (value=43.69 ts=1466672412297)
>>> [Jun 23 11:00:20][chn1] Adding reading to queue (value=23.38 ts=1466672419974)
>>> [Jun 23 11:00:20][chn1] Adding reading to queue (value=23.38 ts=1466672420037)
>>> [Jun 23 11:00:20][chn0] Adding reading to queue (value=43.69 ts=1466672412599)
>>> [Jun 23 11:00:21][chn1] Adding reading to queue (value=23.88 ts=1466672411889)
>>> [Jun 23 11:00:21][chn0] Adding reading to queue (value=4.00 ts=1466672421298)
>>> [Jun 23 11:00:22][chn0] Adding reading to queue (value=4.00 ts=1466672422290)
>>> [Jun 23 11:00:23][chn0] Adding reading to queue (value=4.00 ts=1466672423282)
>>> [Jun 23 11:00:24][chn0] Adding reading to queue (value=4.00 ts=1466672424275)
>>> [Jun 23 11:00:25][chn0] Adding reading to queue (value=4.00 ts=1466672425272)
>>> [Jun 23 11:00:26][chn0] Adding reading to queue (value=4.00 ts=1466672426268)
>>> [Jun 23 11:00:27][chn0] Adding reading to queue (value=4.00 ts=1466672427271)
>>> [Jun 23 11:00:28][chn0] Adding reading to queue (value=4.00 ts=1466672428267)
>>> [Jun 23 11:00:29][chn0] Adding reading to queue (value=4.00 ts=1466672429263)
>>> [Jun 23 11:00:29][chn0] Adding reading to queue (value=43.69 ts=1466672421241)
>>> [Jun 23 11:00:30][chn0] Adding reading to queue (value=4.00 ts=1466672430259)
>>>
>>> .. und genau dorthin logge ich ja auch:
>>>
>>> pi at BauratPi:/etc $ cat vzlogger.conf
>>>
>>> {
>>> "retry": 0,
>>> "daemon": true,
>>> "verbosity": 5,
>>> "log": "/var/log/vzlogger.log",
>>> "local": {
>>> "enabled": false,
>>> "port": 8080,
>>> "index": false,
>>> "timeout": 0,
>>> "buffer": 0
>>> },
>>> "meters": [
>>> {
>>> "enabled": true,
>>> "allowskip": false,
>>> "interval": -1,
>>> "aggtime": 20,
>>> "aggfixedinterval": true,
>>> "channels": [
>>> {
>>> "uuid": "46f672b0-f2dc-11e2-8c00-43a2ff77fb28",
>>> "identifier": "28-0000045d2497",
>>> "api": "volkszaehler",
>>> "middleware": "http://127.0.0.1/middleware.php",
>>> "aggmode": "none",
>>> "duplicates": 0
>>> }
>>> ],
>>> "protocol": "w1therm"
>>> }
>>> ]
>>> }
>>>
>>>
>>> Ist mir nicht erklärlich.. :(
>>>
>>> Danke!
>>>
>>> LG Heiko
>>>
>>>>
>>>>
>>>> Am 23.06.2016 um 10:42 schrieb Heiko Baumann <hbcs at gmx.de>:
>>>>
>>>>>> Am 22.06.2016 um 23:55 schrieb Udo1:
>>>>>>> Am 22.06.2016 um 23:42 schrieb Heiko Baumann:
>>>>>>> Muss der "identifier" überall die Sensor-ID sein oder kann ich das als Freitext beliebig wählen?
>>>>>> m.M. müsste das die Sensor-ID sein (bei 1Wire), wie sonst sollte vzlogger die UUID dem Sensor zuordnen (bei mehreren Sensoren).
>>>>> Ok, es werden zwar Werte geliefert, aber kann gut sein, dass sie ohne SensorID einfach dem ersten zugeordnet werden.
>>>>>
>>>>> Beim Rumspielen mit der conf Datei stoße ich auf mir unerklärliche Dinge. Nur zum Test mal ein einziger Temperatursensor:
>>>>>
>>>>>
>>>>> pi at BauratPi:/etc $ cat vzlogger.conf
>>>>> {
>>>>> "retry": 0,
>>>>> "daemon": true,
>>>>> "verbosity": 5,
>>>>> "log": "/var/log/vzlogger.log",
>>>>> "local": {
>>>>> "enabled": false,
>>>>> "port": 8080,
>>>>> "index": false,
>>>>> "timeout": 0,
>>>>> "buffer": 0
>>>>> },
>>>>> "meters": [
>>>>> {
>>>>> "enabled": true,
>>>>> "allowskip": false,
>>>>> "interval": -1,
>>>>> "aggtime": 20,
>>>>> "aggfixedinterval": true,
>>>>> "channels": [
>>>>> {
>>>>> "uuid": "46f672b0-f2dc-11e2-8c00-43a2ff77fb28",
>>>>> "identifier": "28-0000045d2497",
>>>>> "api": "volkszaehler",
>>>>> "middleware": "http://127.0.0.1/middleware.php",
>>>>> "aggmode": "none",
>>>>> "duplicates": 0
>>>>> }
>>>>> ],
>>>>> "protocol": "w1therm"
>>>>> }
>>>>> ]
>>>>> }
>>>>>
>>>>> So. UUID und Sensor-ID passen zusammen, das ist noch aus der alten 1wire Konfig übernommen.
>>>>> Im log stehen dann komische Sachen:
>>>>>
>>>>>
>>>>> [Jun 23 10:38:23][mtr1] Next reading in 10 seconds
>>>>> [Jun 23 10:38:23][chn0] Adding reading to queue (value=43.81 ts=1466671094865)
>>>>> [Jun 23 10:38:23][chn0] Adding reading to queue (value=43.81 ts=1466671095313)
>>>>> [Jun 23 10:38:23][chn0] Adding reading to queue (value=4.00 ts=1466671103085)
>>>>> [Jun 23 10:38:23][mtr0] Next reading in 10 seconds
>>>>> [Jun 23 10:38:24][chn0] Adding reading to queue (value=4.00 ts=1466671104077)
>>>>> [Jun 23 10:38:25][chn0] Adding reading to queue (value=4.00 ts=1466671105069)
>>>>> [Jun 23 10:38:26][chn0] Adding reading to queue (value=4.00 ts=1466671106061)
>>>>> [Jun 23 10:38:26][chn1] Adding reading to queue (value=43.81 ts=1466671098570)
>>>>> [Jun 23 10:38:26][mtr1] Next reading in 10 seconds
>>>>> [Jun 23 10:38:26][chn1] Adding reading to queue (value=23.25 ts=1466671105869)
>>>>> [Jun 23 10:38:27][chn0] Adding reading to queue (value=4.00 ts=1466671107053)
>>>>> [Jun 23 10:38:28][chn0] Adding reading to queue (value=4.00 ts=1466671108045)
>>>>> [Jun 23 10:38:29][chn0] Adding reading to queue (value=4.00 ts=1466671109037)
>>>>> [Jun 23 10:38:30][chn1] Adding reading to queue (value=23.56 ts=1466671101185)
>>>>> [Jun 23 10:38:30][chn0] Adding reading to queue (value=4.00 ts=1466671110043)
>>>>> [Jun 23 10:38:31][chn1] Adding reading to queue (value=43.81 ts=1466671103126)
>>>>> [Jun 23 10:38:31][chn0] Adding reading to queue (value=4.00 ts=1466671111035)
>>>>> [Jun 23 10:38:32][chn0] Adding reading to queue (value=4.00 ts=1466671112027)
>>>>>
>>>>> Richtig sind die Werte um 44° (Warmwasser). Der Rest ist Käse.
>>>>>
>>>>> Wieso werden zwei "Channels" (blau markiert) geloggt, obwohl in der conf doch nur 1 Sensor erfasst ist?
>>>>> (Am Pi hängen insgesamt 12 temperaturfühler und 4 s0).
>>>>>
>>>>> Könnte mir jemand mal bitte seine vzlogger.conf posten, in der s0 (Strom und Betriebsstunden) und 1wire Temperaturfühler angesprochen werden und die Werte sinnvoll aggregiert werden?
>>>>>
>>>>> Danke!
>>>>>
>>>>> LG HEiko
>
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://demo.volkszaehler.org/pipermail/volkszaehler-users/attachments/20160623/8ff08375/attachment-0001.html>
More information about the volkszaehler-users
mailing list