vzlogger.log

[Oct 11 10:03:57][main] vzlogger v0.6.0 based on heads/master-0-g1484f9aad1 from Mon, 22 Aug 2016 22:32:51 +0200 started.
[Oct 11 10:03:57][push] Ignoring push entry due to empty array or duplicate section
[Oct 11 10:03:57][mtr0] Creating new meter with protocol d0.
[Oct 11 10:03:57][d0]   pullseq len:5 found
[Oct 11 10:03:57][mtr0] Meter configured, disabled.
[Oct 11 10:03:57]       New meter initialized (protocol=d0)
[Oct 11 10:03:57]       Configure channel.
[Oct 11 10:03:57][chn0] New channel initialized (uuid=...327b1e api=volkszaehler id=1-0:1.7.0)
[Oct 11 10:03:57][mtr1] Creating new meter with protocol d0.
[Oct 11 10:03:57][d0]   pullseq len:5 found
[Oct 11 10:03:57][mtr1] Meter configured, enabled.
[Oct 11 10:03:57]       New meter initialized (protocol=d0)
[Oct 11 10:03:57]       Configure channel.
[Oct 11 10:03:57][chn1] New channel initialized (uuid=...c4d7ab api=volkszaehler id=1-1:1.7.0)
[Oct 11 10:03:57]       Configure channel.
[Oct 11 10:03:57][chn2] New channel initialized (uuid=...5c1936 api=volkszaehler id=1-1:1.8.0)
[Oct 11 10:03:57]       Have 2 meters.
[Oct 11 10:03:57][main] log level is 15
[Oct 11 10:03:57][main] daemon=1, local=0
[Oct 11 10:03:57]       Daemonize process...
[Oct 11 10:03:57]       Opened logfile /tmp/vzlogger.log
[Oct 11 10:03:57][push] No pushDataServer defined.
[Oct 11 10:03:57][]     ===> Start meters
[Oct 11 10:03:57][mtr0] Meter for protocol 'd0'#0)
[Oct 11 10:05:46][chn2] CURL: Sent 41 bytes..
[Oct 11 10:05:46][chn2] CURL: Sent '[ [ 1476173140914, 10998.209999999999 ] ]' bytes
[Oct 11 10:05:46][chn2] CURL: upload completely sent off: 41 out of 41 bytes
[Oct 11 10:05:46][chn1] copied 1/1 values for middleware transmission
[Oct 11 10:05:50][chn2] CURL: HTTP 1.0, assume close after body
[Oct 11 10:05:50][chn2] CURL: Received 951 bytes
[Oct 11 10:05:50][chn2] CURL: Received '{
    "version": "0.3",
    "rows": 1,
    "debug": {
        "level": 1,
        "database": "pdo_mysql",
        "time": 3.44554,
        "uptime": 1130850,
        "load": [
            0.03,
            0.03,
            0.05
        ],
        "commit-hash": "38d91a1b19668a12f9714dcfbe5c74092fe862ee",
        "php-version": "5.6.24-0+deb8u1",
        "messages": [],
        "sql": {
            "totalTime": 2.6990828514099,
            "worstTime": 2.3655378818512,
            "queries": [
                {
                    "sql": "SELECT e0_.id AS id0, e0_.uuid AS uuid1, e0_.type AS type2, p1_.id AS id3, p1_.pkey AS pkey4, p1_.value AS value5, e0_.class AS class6, p1_.entity_id AS entity_id7 FROM entities e0_ LEFT JOIN properties p1_ ON e0_.id = p1_.entity_id WHERE (e0_.uuid = '640543c0-8db0-11e6-991b-b7de4b5c1936') AND e0_.class IN ('channel', 'aggregator') ORDER BY p1_.pkey ASC",
                    "execTime": 2.3655378818512
                },
                {
                    "sql": "INSERT INTO data (channel_id, timestamp, value) VALUES (13,1476173140914,10998.21)",
                    "execTime": 0.33354496955872
                }
            ]
        }
    }
}' bytes
[Oct 11 10:05:50][chn2] CURL: Closing connection 0
[Oct 11 10:05:50][chn1] JSON request body: [ [ 1476173144582, 0.65600000000000003 ] ]
[Oct 11 10:05:50][chn1] CURL: Hostname was found in DNS cache
[Oct 11 10:05:50][chn1] CURL:   Trying 192.168.178.22...
[Oct 11 10:05:50][chn1] CURL: Connected to 192.168.178.22 (192.168.178.22) port 81 (#1)
[Oct 11 10:05:50][chn1] CURL: Sent 42 bytes..
[Oct 11 10:05:50][chn1] CURL: Sent '[ [ 1476173144582, 0.65600000000000003 ] ]' bytes
[Oct 11 10:05:50][chn1] CURL: upload completely sent off: 42 out of 42 bytes
[Oct 11 10:05:50][chn2] CURL Request succeeded with code: 200
[Oct 11 10:05:50][chn2] emptied all (1) values
[Oct 11 10:05:50][chn1] CURL: HTTP 1.0, assume close after body
[Oct 11 10:05:50][chn1] CURL: Received 949 bytes
[Oct 11 10:05:50][chn1] CURL: Received '{
    "version": "0.3",
    "rows": 1,
    "debug": {
        "level": 1,
        "database": "pdo_mysql",
        "time": 0.61454,
        "uptime": 1131510,
        "load": [
            0.03,
            0.03,
            0.05
        ],
        "commit-hash": "38d91a1b19668a12f9714dcfbe5c74092fe862ee",
        "php-version": "5.6.24-0+deb8u1",
        "messages": [],
        "sql": {
            "totalTime": 0.4567699432373,
            "worstTime": 0.4006359577179,
            "queries": [
                {
                    "sql": "SELECT e0_.id AS id0, e0_.uuid AS uuid1, e0_.type AS type2, p1_.id AS id3, p1_.pkey AS pkey4, p1_.value AS value5, e0_.class AS class6, p1_.entity_id AS entity_id7 FROM entities e0_ LEFT JOIN properties p1_ ON e0_.id = p1_.entity_id WHERE (e0_.uuid = '6fe45bb0-841f-11e6-a66a-218a11c4d7ab') AND e0_.class IN ('channel', 'aggregator') ORDER BY p1_.pkey ASC",
                    "execTime": 0.056133985519409
                },
                {
                    "sql": "INSERT INTO data (channel_id, timestamp, value) VALUES (12,1476173144582,0.656)",
                    "execTime": 0.4006359577179
                }
            ]
        }
    }
}' bytes
[Oct 11 10:05:50][chn1] CURL: Closing connection 1
[Oct 11 10:05:50][chn1] CURL Request succeeded with code: 200
[Oct 11 10:05:50][chn1] emptied all (1) values
[Oct 11 10:06:16][d0]   sending pullsequenz send (len:5 is:5).
[Oct 11 10:06:27][d0]   Read package with 0 tuples (vendor=Ldû, baudrate= , identification=t[#)
[Oct 11 10:06:27][mtr1] Got 0 new readings from meter:
[Oct 11 10:06:27][mtr1] Next reading in 30 seconds
[Oct 11 10:06:27][chn1] ==> number of tuples: 0
[Oct 11 10:06:27][chn1] JSON request body is null. Nothing to send now.
[Oct 11 10:06:27][chn2] ==> number of tuples: 0
[Oct 11 10:06:27][chn2] JSON request body is null. Nothing to send now.
[Oct 11 10:06:42][d0]   nothing received for more than 10 seconds
[Oct 11 10:06:42][d0]   read timed out!, context: 7, bytes read: 4, last byte 0x1d