[vz-users] S0 Daten auslesen über IR-Schnittstelle beim Itron

Oliver Koch reg_okoch at my.mail.de
Thu Apr 23 21:13:00 CEST 2015 

Hi Udo,
Am 22.04.2015 um 22:00 schrieb Udo1:
> Wahrscheinlich deshalb. Irgendein Zeichen gefällt ihm nicht.
>> "verbosity" : 5,                  /* between 0 and 15 */
>>     "log" : "/var/log/vzlogger.log",  /* path to logfile, optional */ 
> Setz mal verbosity auf 15 und poste dann mal den Inhalt von der 
> vzlogger.log.

wie gewünscht jetzt mit verbosity=15. Jetzt tut sich mehr. Irgendetwas 
schmeckt ihm aber noch nicht... ;-(

[Apr 23 20:52:28][]     ===> Start meters
[Apr 23 20:52:28][mtr0] Meter connection established
[Apr 23 20:52:28][mtr0] Meter thread started
[Apr 23 20:52:28][mtr0] Meter is opened. Starting channels.
[Apr 23 20:52:28][chn0] Logging thread started
[Apr 23 20:52:28][chn0] Start logging thread for volkszaehler-api. 
Running as daemon: yes
[Apr 23 20:52:28][mtr0] Number of readers: 2
[Apr 23 20:52:28][mtr0] Config.daemon: 1
[Apr 23 20:52:28][mtr0] Config.local: 0
[Apr 23 20:52:28][mtr1] Meter connection established
[Apr 23 20:52:28][mtr1] Meter thread started
[Apr 23 20:52:28][mtr1] Meter is opened. Starting channels.
[Apr 23 20:52:28][chn1] Logging thread started
[Apr 23 20:52:28][]     Startup done.
[Apr 23 20:52:28][chn0] Using default volkszaehler api.
[Apr 23 20:52:28][chn1] Start logging thread for volkszaehler-api. 
Running as daemon: yes
[Apr 23 20:52:28][chn1] Using default volkszaehler api.
[Apr 23 20:52:28][mtr1] Number of readers: 2
[Apr 23 20:52:28][mtr1] Config.daemon: 1
[Apr 23 20:52:28][mtr1] Config.local: 0
[Apr 23 20:53:00][s0]   Reading S0 - n=2 power=209.928622
[Apr 23 20:53:00][mtr0] Got 2 new readings from meter:
[Apr 23 20:53:00][mtr0] Reading: id=Power/StringItentifier: value=209.93 
ts=1429815180663
[Apr 23 20:53:00][mtr0] Reading: id=Impulse/StringItentifier: value=2.00 
ts=1429815180663
[Apr 23 20:53:00][chn0] Adding reading to queue (value=2.00 
ts=1429815180663)
[Apr 23 20:53:35][s0]   Reading S0 - n=2 power=206.941925
[Apr 23 20:53:35][mtr0] Got 2 new readings from meter:
[Apr 23 20:53:35][mtr0] Reading: id=Power/StringItentifier: value=206.94 
ts=1429815215492
[Apr 23 20:53:35][mtr0] Reading: id=Impulse/StringItentifier: value=2.00 
ts=1429815215492
[Apr 23 20:53:35][chn0] Adding reading to queue (value=2.00 
ts=1429815215492)
[Apr 23 20:54:10][s0]   Reading S0 - n=2 power=205.935682
[Apr 23 20:54:10][mtr0] Got 2 new readings from meter:
[Apr 23 20:54:10][mtr0] Reading: id=Power/StringItentifier: value=205.94 
ts=1429815250273
[Apr 23 20:54:10][mtr0] Reading: id=Impulse/StringItentifier: value=2.00 
ts=1429815250273
[Apr 23 20:54:10][chn0] Adding reading to queue (value=2.00 
ts=1429815250273)
[Apr 23 20:54:45][s0]   Reading S0 - n=2 power=208.364201
[Apr 23 20:54:45][mtr0] Got 2 new readings from meter:
[Apr 23 20:54:45][mtr0] Reading: id=Power/StringItentifier: value=208.36 
ts=1429815285030
[Apr 23 20:54:45][mtr0] Reading: id=Impulse/StringItentifier: value=2.00 
ts=1429815285030
[Apr 23 20:54:45][chn0] Adding reading to queue (value=2.00 
ts=1429815285030)
[Apr 23 20:55:19][s0]   Reading S0 - n=2 power=207.046208
[Apr 23 20:55:19][mtr0] Got 2 new readings from meter:
[Apr 23 20:55:19][mtr0] Reading: id=Power/StringItentifier: value=207.05 
ts=1429815319731
[Apr 23 20:55:19][mtr0] Reading: id=Impulse/StringItentifier: value=2.00 
ts=1429815319731
[Apr 23 20:55:19][chn0] Adding reading to queue (value=2.00 
ts=1429815319731)
[Apr 23 20:55:54][s0]   Reading S0 - n=2 power=209.592329
[Apr 23 20:55:54][mtr0] Got 2 new readings from meter:
[Apr 23 20:55:54][mtr0] Reading: id=Power/StringItentifier: value=209.59 
ts=1429815354065
[Apr 23 20:55:54][mtr0] Reading: id=Impulse/StringItentifier: value=2.00 
ts=1429815354065
[Apr 23 20:55:54][chn0] Adding reading to queue (value=2.00 
ts=1429815354065)
[Apr 23 20:56:28][s0]   Reading S0 - n=2 power=206.319355
[Apr 23 20:56:28][mtr0] Got 2 new readings from meter:
[Apr 23 20:56:28][mtr0] Reading: id=Power/StringItentifier: value=206.32 
ts=1429815388939
[Apr 23 20:56:28][mtr0] Reading: id=Impulse/StringItentifier: value=2.00 
ts=1429815388939
[Apr 23 20:56:28][chn0] Adding reading to queue (value=2.00 
ts=1429815388939)
[Apr 23 20:57:03][s0]   Reading S0 - n=2 power=206.794022
[Apr 23 20:57:03][mtr0] Got 2 new readings from meter:
[Apr 23 20:57:03][mtr0] Reading: id=Power/StringItentifier: value=206.79 
ts=1429815423749
[Apr 23 20:57:03][mtr0] Reading: id=Impulse/StringItentifier: value=2.00 
ts=1429815423749
[Apr 23 20:57:03][chn0] Adding reading to queue (value=2.00 
ts=1429815423749)
[Apr 23 20:57:38][s0]   Reading S0 - n=2 power=211.921479
[Apr 23 20:57:38][mtr0] Got 2 new readings from meter:
[Apr 23 20:57:38][mtr0] Reading: id=Power/StringItentifier: value=211.92 
ts=1429815458028
[Apr 23 20:57:38][mtr0] Reading: id=Impulse/StringItentifier: value=2.00 
ts=1429815458028
[Apr 23 20:57:38][chn0] Adding reading to queue (value=2.00 
ts=1429815458028)
[Apr 23 20:57:38][chn0] ==> number of tuples: 9
[Apr 23 20:57:38][chn0] compare: 0 1429815180663
[Apr 23 20:57:38][chn0] compare: 1429815180663 1429815215492
[Apr 23 20:57:38][chn0] compare: 1429815215492 1429815250273
[Apr 23 20:57:38][chn0] compare: 1429815250273 1429815285030
[Apr 23 20:57:38][chn0] compare: 1429815285030 1429815319731
[Apr 23 20:57:38][chn0] compare: 1429815319731 1429815354065
[Apr 23 20:57:38][chn0] compare: 1429815354065 1429815388939
[Apr 23 20:57:38][chn0] compare: 1429815388939 1429815423749
[Apr 23 20:57:38][chn0] compare: 1429815423749 1429815458028
[Apr 23 20:57:38][chn0] JSON request body: [ [ 1429815180663, 2 ], [ 
1429815215492, 2 ], [ 1429815250273, 2 ], [ 1429815285030, 2 ], [ 
1429815319731, 2 ], [ 1429815354065, 2 ], [ 1429815388939, 2 ], [ 
1429815423749, 2 ], [ 1429815458028, 2 ] ]
[Apr 23 20:57:38][chn0] Buffer dump (size=0): {}
[Apr 23 20:57:38][chn0] CURL: About to connect() to localhost port 80 (#0)
[Apr 23 20:57:38][chn0] CURL:   Trying 127.0.0.1...
[Apr 23 20:57:38][chn0] CURL: connected
[Apr 23 20:57:38][chn0] CURL: Connected to localhost (127.0.0.1) port 80 
(#0)
[Apr 23 20:57:38][chn0] CURL: Sent 200 bytes..
[Apr 23 20:57:38][chn0] CURL: Sent '[ [ 1429815180663, 2 ], [ 
1429815215492, 2 ], [ 1429815250273, 2 ], [ 1429815285030, 2 ], [ 
1429815319731, 2 ], [ 1429815354065, 2 ], [ 1429815388939, 2 ], [ 
1429815423749, 2 ], [ 1429815458028, 2 ] ]' bytes
[Apr 23 20:57:38][chn0] CURL: upload completely sent off: 200 out of 200 
bytes
[Apr 23 20:57:38][chn0] CURL: HTTP 1.1 or later with persistent 
connection, pipelining supported
[Apr 23 20:57:38][chn0] CURL: Received 358 bytes
[Apr 23 20:57:38][chn0] CURL: Received '<!DOCTYPE HTML PUBLIC 
"-//IETF//DTD HTML 2.0//EN">
<html><head>
<title>404 Not Found</title>
</head><body>
<h1>Not Found</h1>
<p>The requested URL 
/volkszaehler.org/htdocs/middleware.php/data/9c960080-e60a-11e4-aef9-d5e3dca72b32.json 
was not found on this server.</p>
<hr>
<address>Apache/2.2.22 (Debian) Server at localhost Port 80</address>
</body></html>
' bytes
[Apr 23 20:57:38][chn0] CURL: Connection #0 to host localhost left intact
[Apr 23 20:57:38][chn0] CURL Error from middleware: unexpected character
[Apr 23 20:57:38][chn0] Waiting 30 secs for next request due to previous 
failure
[Apr 23 20:58:12][s0]   Reading S0 - n=2 power=211.578827
[Apr 23 20:58:12][mtr0] Got 2 new readings from meter:
[Apr 23 20:58:12][mtr0] Reading: id=Power/StringItentifier: value=211.58 
ts=1429815492173
[Apr 23 20:58:12][mtr0] Reading: id=Impulse/StringItentifier: value=2.00 
ts=1429815492173
[Apr 23 20:58:12][chn0] Adding reading to queue (value=2.00 
ts=1429815492173)
[Apr 23 20:58:46][s0]   Reading S0 - n=2 power=208.787542
[Apr 23 20:58:46][mtr0] Got 2 new readings from meter:
[Apr 23 20:58:46][mtr0] Reading: id=Power/StringItentifier: value=208.79 
ts=1429815526531
[Apr 23 20:58:46][mtr0] Reading: id=Impulse/StringItentifier: value=2.00 
ts=1429815526531
[Apr 23 20:58:46][chn0] Adding reading to queue (value=2.00 
ts=1429815526531)
[Apr 23 20:59:21][s0]   Reading S0 - n=2 power=207.538356
[Apr 23 20:59:21][mtr0] Got 2 new readings from meter:
[Apr 23 20:59:21][mtr0] Reading: id=Power/StringItentifier: value=207.54 
ts=1429815561283
[Apr 23 20:59:21][mtr0] Reading: id=Impulse/StringItentifier: value=2.00 
ts=1429815561283
[Apr 23 20:59:21][chn0] Adding reading to queue (value=2.00 
ts=1429815561283)
[Apr 23 20:59:55][s0]   Reading S0 - n=2 power=206.156661
[Apr 23 20:59:55][mtr0] Got 2 new readings from meter:
[Apr 23 20:59:55][mtr0] Reading: id=Power/StringItentifier: value=206.16 
ts=1429815595884
[Apr 23 20:59:55][mtr0] Reading: id=Impulse/StringItentifier: value=2.00 
ts=1429815595884
[Apr 23 20:59:55][chn0] Adding reading to queue (value=2.00 
ts=1429815595884)
[Apr 23 21:00:30][s0]   Reading S0 - n=2 power=207.928044
[Apr 23 21:00:30][mtr0] Got 2 new readings from meter:
[Apr 23 21:00:30][mtr0] Reading: id=Power/StringItentifier: value=207.93 
ts=1429815630576
[Apr 23 21:00:30][mtr0] Reading: id=Impulse/StringItentifier: value=2.00 
ts=1429815630576
[Apr 23 21:00:30][chn0] Adding reading to queue (value=2.00 
ts=1429815630576)
[Apr 23 21:01:05][s0]   Reading S0 - n=2 power=205.921772
[Apr 23 21:01:05][mtr0] Got 2 new readings from meter:
[Apr 23 21:01:05][mtr0] Reading: id=Power/StringItentifier: value=205.92 
ts=1429815665390
[Apr 23 21:01:05][mtr0] Reading: id=Impulse/StringItentifier: value=2.00 
ts=1429815665390
[Apr 23 21:01:05][chn0] Adding reading to queue (value=2.00 
ts=1429815665390)
[Apr 23 21:01:31][s0]   Reading S0 - n=2 power=336.290923
[Apr 23 21:01:31][mtr0] Got 2 new readings from meter:
[Apr 23 21:01:31][mtr0] Reading: id=Power/StringItentifier: value=336.29 
ts=1429815691654
[Apr 23 21:01:31][mtr0] Reading: id=Impulse/StringItentifier: value=2.00 
ts=1429815691654
[Apr 23 21:01:31][chn0] Adding reading to queue (value=2.00 
ts=1429815691654)
[Apr 23 21:01:55][s0]   Reading S0 - n=2 power=297.027228
[Apr 23 21:01:55][mtr0] Got 2 new readings from meter:
[Apr 23 21:01:55][mtr0] Reading: id=Power/StringItentifier: value=297.03 
ts=1429815715779
[Apr 23 21:01:55][mtr0] Reading: id=Impulse/StringItentifier: value=2.00 
ts=1429815715779
[Apr 23 21:01:55][chn0] Adding reading to queue (value=2.00 
ts=1429815715779)
[Apr 23 21:02:20][s0]   Reading S0 - n=2 power=296.479287
[Apr 23 21:02:20][mtr0] Got 2 new readings from meter:
[Apr 23 21:02:20][mtr0] Reading: id=Power/StringItentifier: value=296.48 
ts=1429815740039
[Apr 23 21:02:20][mtr0] Reading: id=Impulse/StringItentifier: value=2.00 
ts=1429815740039
[Apr 23 21:02:20][chn0] Adding reading to queue (value=2.00 
ts=1429815740039)
[Apr 23 21:02:44][s0]   Reading S0 - n=2 power=289.418614
[Apr 23 21:02:44][mtr0] Got 2 new readings from meter:
[Apr 23 21:02:44][mtr0] Reading: id=Power/StringItentifier: value=289.42 
ts=1429815764823
[Apr 23 21:02:44][mtr0] Reading: id=Impulse/StringItentifier: value=2.00 
ts=1429815764823
[Apr 23 21:02:44][chn0] Adding reading to queue (value=2.00 
ts=1429815764823)
[Apr 23 21:02:44][chn0] ==> number of tuples: 10
[Apr 23 21:02:44][chn0] compare: 1429815458028 1429815492173
[Apr 23 21:02:44][chn0] compare: 1429815492173 1429815526531
[Apr 23 21:02:44][chn0] compare: 1429815526531 1429815561283
[Apr 23 21:02:44][chn0] compare: 1429815561283 1429815595884
[Apr 23 21:02:44][chn0] compare: 1429815595884 1429815630576
[Apr 23 21:02:44][chn0] compare: 1429815630576 1429815665390
[Apr 23 21:02:44][chn0] compare: 1429815665390 1429815691654
[Apr 23 21:02:44][chn0] compare: 1429815691654 1429815715779
[Apr 23 21:02:44][chn0] compare: 1429815715779 1429815740039
[Apr 23 21:02:44][chn0] compare: 1429815740039 1429815764823
[Apr 23 21:02:44][chn0] JSON request body: [ [ 1429815180663, 2 ], [ 
1429815215492, 2 ], [ 1429815250273, 2 ], [ 1429815285030, 2 ], [ 
1429815319731, 2 ], [ 1429815354065, 2 ], [ 1429815388939, 2 ], [ 
1429815423749, 2 ], [ 1429815458028, 2 ], [ 1429815492173, 2 ], [ 
1429815526531, 2 ], [ 1429815561283, 2 ], [ 1429815595884, 2 ], [ 
1429815630576, 2 ], [ 1429815665390, 2 ], [ 1429815691654, 2 ], [ 
1429815715779, 2 ], [ 1429815740039, 2 ], [ 1429815764823, 2 ] ]
[Apr 23 21:02:44][chn0] CURL: Connection #0 seems to be dead!
[Apr 23 21:02:44][chn0] CURL: Closing connection #0
[Apr 23 21:02:44][chn0] Buffer dump (size=0): {}
[Apr 23 21:02:44][chn0] CURL: About to connect() to localhost port 80 (#0)
[Apr 23 21:02:44][chn0] CURL:   Trying 127.0.0.1...
[Apr 23 21:02:44][chn0] CURL: connected
[Apr 23 21:02:44][chn0] CURL: Connected to localhost (127.0.0.1) port 80 
(#0)
[Apr 23 21:02:44][chn0] CURL: Sent 420 bytes..
[Apr 23 21:02:44][chn0] CURL: Sent '[ [ 1429815180663, 2 ], [ 
1429815215492, 2 ], [ 1429815250273, 2 ], [ 1429815285030, 2 ], [ 
1429815319731, 2 ], [ 1429815354065, 2 ], [ 1429815388939, 2 ], [ 
1429815423749, 2 ], [ 1429815458028, 2 ], [ 1429815492173, 2 ], [ 
1429815526531, 2 ], [ 1429815561283, 2 ], [ 1429815595884, 2 ], [ 
1429815630576, 2 ], [ 1429815665390, 2 ], [ 1429815691654, 2 ], [ 
1429815715779, 2 ], [ 1429815740039, 2 ], [ 1429815764823, 2 ] ]' bytes
[Apr 23 21:02:44][chn0] CURL: upload completely sent off: 420 out of 420 
bytes
[Apr 23 21:02:44][chn0] CURL: HTTP 1.1 or later with persistent 
connection, pipelining supported
[Apr 23 21:02:44][chn0] CURL: Received 358 bytes
[Apr 23 21:02:44][chn0] CURL: Received '<!DOCTYPE HTML PUBLIC 
"-//IETF//DTD HTML 2.0//EN">
<html><head>
<title>404 Not Found</title>
</head><body>
<h1>Not Found</h1>
<p>The requested URL 
/volkszaehler.org/htdocs/middleware.php/data/9c960080-e60a-11e4-aef9-d5e3dca72b32.json 
was not found on this server.</p>
<hr>
<address>Apache/2.2.22 (Debian) Server at localhost Port 80</address>
</body></html>
' bytes
[Apr 23 21:02:44][chn0] CURL: Connection #0 to host localhost left intact
[Apr 23 21:02:44][chn0] CURL Error from middleware: unexpected character
[Apr 23 21:02:44][chn0] Waiting 30 secs for next request due to previous 
failure
[Apr 23 21:03:09][s0]   Reading S0 - n=2 power=289.476657
[Apr 23 21:03:09][mtr0] Got 2 new readings from meter:
[Apr 23 21:03:09][mtr0] Reading: id=Power/StringItentifier: value=289.48 
ts=1429815789625
[Apr 23 21:03:09][mtr0] Reading: id=Impulse/StringItentifier: value=2.00 
ts=1429815789625
[Apr 23 21:03:09][chn0] Adding reading to queue (value=2.00 
ts=1429815789625)
[Apr 23 21:03:34][s0]   Reading S0 - n=2 power=289.215073
[Apr 23 21:03:34][mtr0] Got 2 new readings from meter:
[Apr 23 21:03:34][mtr0] Reading: id=Power/StringItentifier: value=289.22 
ts=1429815814475
[Apr 23 21:03:34][mtr0] Reading: id=Impulse/StringItentifier: value=2.00 
ts=1429815814475
[Apr 23 21:03:34][chn0] Adding reading to queue (value=2.00 
ts=1429815814475)
[Apr 23 21:03:59][s0]   Reading S0 - n=2 power=289.855332
[Apr 23 21:03:59][mtr0] Got 2 new readings from meter:
[Apr 23 21:03:59][mtr0] Reading: id=Power/StringItentifier: value=289.86 
ts=1429815839259
[Apr 23 21:03:59][mtr0] Reading: id=Impulse/StringItentifier: value=2.00 
ts=1429815839259
[Apr 23 21:03:59][chn0] Adding reading to queue (value=2.00 
ts=1429815839259)
[Apr 23 21:04:24][s0]   Reading S0 - n=2 power=291.971917
[Apr 23 21:04:24][mtr0] Got 2 new readings from meter:
[Apr 23 21:04:24][mtr0] Reading: id=Power/StringItentifier: value=291.97 
ts=1429815864061
[Apr 23 21:04:24][mtr0] Reading: id=Impulse/StringItentifier: value=2.00 
ts=1429815864061
[Apr 23 21:04:24][chn0] Adding reading to queue (value=2.00 
ts=1429815864061)
[Apr 23 21:04:48][s0]   Reading S0 - n=2 power=289.885766
[Apr 23 21:04:48][mtr0] Got 2 new readings from meter:
[Apr 23 21:04:48][mtr0] Reading: id=Power/StringItentifier: value=289.89 
ts=1429815888881
[Apr 23 21:04:48][mtr0] Reading: id=Impulse/StringItentifier: value=2.00 
ts=1429815888881
[Apr 23 21:04:48][chn0] Adding reading to queue (value=2.00 
ts=1429815888881)
[Apr 23 21:05:13][s0]   Reading S0 - n=2 power=287.139613
[Apr 23 21:05:13][mtr0] Got 2 new readings from meter:
[Apr 23 21:05:13][mtr0] Reading: id=Power/StringItentifier: value=287.14 
ts=1429815913829
[Apr 23 21:05:13][mtr0] Reading: id=Impulse/StringItentifier: value=2.00 
ts=1429815913829
[Apr 23 21:05:13][chn0] Adding reading to queue (value=2.00 
ts=1429815913829)
[Apr 23 21:05:38][s0]   Reading S0 - n=2 power=290.352552
[Apr 23 21:05:38][mtr0] Got 2 new readings from meter:
[Apr 23 21:05:38][mtr0] Reading: id=Power/StringItentifier: value=290.35 
ts=1429815938749
[Apr 23 21:05:38][mtr0] Reading: id=Impulse/StringItentifier: value=2.00 
ts=1429815938749
[Apr 23 21:05:38][chn0] Adding reading to queue (value=2.00 
ts=1429815938749)
[Apr 23 21:06:03][s0]   Reading S0 - n=2 power=287.139225
[Apr 23 21:06:03][mtr0] Got 2 new readings from meter:
[Apr 23 21:06:03][mtr0] Reading: id=Power/StringItentifier: value=287.14 
ts=1429815963812
[Apr 23 21:06:03][mtr0] Reading: id=Impulse/StringItentifier: value=2.00 
ts=1429815963812
[Apr 23 21:06:03][chn0] Adding reading to queue (value=2.00 
ts=1429815963812)
[Apr 23 21:06:28][s0]   Reading S0 - n=2 power=291.115170
[Apr 23 21:06:28][mtr0] Got 2 new readings from meter:
[Apr 23 21:06:28][mtr0] Reading: id=Power/StringItentifier: value=291.12 
ts=1429815988695
[Apr 23 21:06:28][mtr0] Reading: id=Impulse/StringItentifier: value=2.00 
ts=1429815988695
[Apr 23 21:06:28][chn0] Adding reading to queue (value=2.00 
ts=1429815988695)
[Apr 23 21:06:53][s0]   Reading S0 - n=2 power=287.110321
[Apr 23 21:06:53][mtr0] Got 2 new readings from meter:
[Apr 23 21:06:53][mtr0] Reading: id=Power/StringItentifier: value=287.11 
ts=1429816013706
[Apr 23 21:06:53][mtr0] Reading: id=Impulse/StringItentifier: value=2.00 
ts=1429816013706
[Apr 23 21:06:53][chn0] Adding reading to queue (value=2.00 
ts=1429816013706)

More information about the volkszaehler-users mailing list