[vz-users] Fehlersuche-HowTo: 1wirevz und s0vz

Heiko Baumann hbcs at gmx.de
Thu Jun 23 11:45:15 CEST 2016 

> 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
[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 
> <mailto: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/b91e9537/attachment-0001.html>

More information about the volkszaehler-users mailing list