EmonCMS update randomly stops, requiring manual stop/start to resync

1

I’ve been uploading to emonCMS since 2018 with no problems.
However and since a few weeks ago, sometimes (not daily, but a few times per week), the load to emonCMS stops, and looking into the logs I see a system restart (not by me) / software watchdog, and error -11 while posting to EmonService.

To resume, I just go to the web interface, set stop/start for Emoncms, and then uploads just a few minutes, I think uploads just one bulk send because is not continuing ahead as expected, and stops again, so to restart again, I’ve to manually go and press the stop/start button again manually.

After doing this stoprt/start sequence as many times is needed to load all history, which is very annoying, once we are at the current time, then the update keeps synched ontime… for some days, until it happens again.

My emonCMS is a local instance, and I was unable to see any problem on it at all.

No problems on updates to pvoutput and influxdb.

2

Can you post your emoncms setup display and the message log where this has happened?

3

image
image596×1086 61.3 KB

About the emoncms.log, I see that I don’t have it for the last occurrence, just a few hours, neither at the emoncms.log.1. I’ll take it as soon it repeats.

4

Sorry, I meant the IoTaWatt message log.

5

I think is this:

Blockquote
** Restart **

SD initialized.
11/23/20 09:09:42z Real Time Clock is running. Unix time 1606122582
11/23/20 09:09:42z Reset reason: Software/System restart
11/23/20 09:09:42z Trace: 9:1, 8:4, 8:6, 8:8, 8:9, 9:3, 9:5, 9:9, 1:2, 1:3, 1:4, 1:5[19], 1:6, 1:1[13], 1:2, 9:0, 9:0, 8:4, 8:6, 8:8, 8:9, 1:2, 1:3, 1:4, 1:5[5], 5:0, 1:6, 1:3, 1:4, 1:5[21], 21:0, 21:2
11/23/20 09:09:42z ESP8266 ChipID: 2528519
11/23/20 09:09:42z IoTaWatt 4.x, Firmware version 02_05_11
11/23/20 09:09:42z SPIFFS mounted.
11/23/20 10:09:43 Local time zone: +1:00
11/23/20 10:09:43 Using Daylight Saving Time (BST) when in effect.
11/23/20 10:09:43 device name: IoTWGrge
11/23/20 10:09:43 HTTP server started
11/23/20 10:09:43 timeSync: service started.
11/23/20 10:09:43 statService: started.
11/23/20 10:09:44 dataLog: service started.
11/23/20 10:09:49 dataLog: Last log entry 11/23/20 10:09:40
11/23/20 10:09:50 EmonService: started. url=HTTP://emoncms.elserrat.cat/emoncms, node=IoTWGrge, interval=10, encrypted
11/23/20 10:09:50 influxDB: started, url=HTTP://192.168.19.147:8086, db=iotawatt, interval=10
11/23/20 10:09:53 Updater: service started. Auto-update class is MINOR
11/23/20 10:09:53 WiFi connected. SSID=guifi.net-ElSerratFW, IP=192.168.19.188, channel=1, RSSI -65db
11/23/20 10:09:53 MDNS responder started for hostname IoTWGrge
11/23/20 10:09:53 LLMNR responder started for hostname IoTWGrge
11/23/20 10:09:55 EmonService: Start posting at 11/23/20 09:09:40
11/23/20 10:09:55 Updater: Auto-update is current for class MINOR.
11/23/20 10:09:58 influxDB: Start posting at 11/23/20 09:09:40
11/23/20 10:10:45 historyLog: service started.
11/23/20 10:10:46 historyLog: Last log entry 11/23/20 10:09:00
11/23/20 10:10:53 PVoutput: started
11/23/20 10:10:53 PVoutput: System El Serrat del Serí, interval 5, donator mode
11/23/20 10:10:54 PVoutput: Reload status beginning 11/07/20 00:05:00
11/23/20 10:14:03 EmonService: HTTP response -11, retrying.

6

…and I captured the screen for the last stop:

image
image625×886 83.5 KB

7

Emoncms stopped at 9:13am. The log starts after a restart at 10:09. Can you post the portion of the log that covers 9:13?

8

sorry, I went to sleep and I didn’t kept that and now is gone.
i’ll take the complete log at the next occurence, for now keeps synched.

anyway to be sure about what you’re looking for… the log says that started at 10:09, but wasn’t (-11), and I took the screenshot a few hours later and still at 9:13, and shows a reset at 9:09 (a few minutes before the stop). You want to look to the log before that reset at 9:09?

What I still kept from that occurrence is the log just after the portion I pasted before (> 10:14), there is another reboot there:

Blockquote
** Restart **

SD initialized.
11/23/20 10:11:24z Real Time Clock is running. Unix time 1606126284
11/23/20 10:11:24z Reset reason: Software Watchdog
11/23/20 10:11:24z Trace: 9:5, 9:9, 1:2, 1:3, 1:4, 1:5[7], 7:0, 7:7, 1:6, 1:3, 1:4, 1:3, 1:4, 1:3, 1:4, 1:3, 1:4, 1:3, 1:4, 1:1[12], 1:2[13], 9:0[13], 9:0, 9:1, 8:4, 8:6, 8:8, 8:9, 9:3, 9:5, 9:9, 1:2
11/23/20 10:11:24z ESP8266 ChipID: 2528519
11/23/20 10:11:24z IoTaWatt 4.x, Firmware version 02_05_11
11/23/20 10:11:24z SPIFFS mounted.
11/23/20 11:11:25 Local time zone: +1:00
11/23/20 11:11:25 Using Daylight Saving Time (BST) when in effect.
11/23/20 11:11:25 device name: IoTWGrge
11/23/20 11:11:25 HTTP server started
11/23/20 11:11:25 timeSync: service started.
11/23/20 11:11:25 statService: started.
11/23/20 11:11:26 dataLog: service started.
11/23/20 11:11:33 dataLog: Last log entry 11/23/20 11:11:20
11/23/20 11:11:33 Updater: service started. Auto-update class is MINOR
11/23/20 11:11:33 WiFi connected. SSID=guifi.net-ElSerratFW, IP=192.168.19.188, channel=1, RSSI -65db
11/23/20 11:11:33 MDNS responder started for hostname IoTWGrge
11/23/20 11:11:34 LLMNR responder started for hostname IoTWGrge
11/23/20 11:11:34 EmonService: started. url=HTTP://emoncms.elserrat.cat/emoncms, node=IoTWGrge, interval=10, encrypted
11/23/20 11:11:34 influxDB: started, url=HTTP://192.168.19.147:8086, db=iotawatt, interval=10
11/23/20 11:11:35 EmonService: Start posting at 11/23/20 09:13:40
11/23/20 11:11:35 Updater: Auto-update is current for class MINOR.
11/23/20 11:11:39 influxDB: Start posting at 11/23/20 11:11:20
11/23/20 11:12:27 historyLog: service started.
11/23/20 11:12:28 historyLog: Last log entry 11/23/20 11:11:00
11/23/20 11:12:35 PVoutput: started
11/23/20 11:12:35 PVoutput: System El Serrat del Serí, interval 5, donator mode
11/23/20 11:12:36 PVoutput: Reload status beginning 11/07/20 00:05:00
11/23/20 11:14:27 EmonService: HTTP response -11, retrying.
11/23/20 11:40:22 WiFi disconnected.
11/23/20 11:40:25 WiFi connected. SSID=guifi.net-ElSerratFW, IP=192.168.19.188, channel=1, RSSI -64db42

9

Maybe related, you are uploading history to PVoutput after every restart. You need to uncheck the history upload box in PVoutput setup.

Emoncms is timing out. It appears to be a problem with you Emoncms server.

10

ok, yes, pvoutput was set to upload history. I’ve unchecked it now.
I haven’t seen any problem at pvoutput & influxdb…

For now everything is fine.

1 Like
11

Now occured again, here’s the log:
** Restart **

SD initialized.
11/25/20 08:47:29z Real Time Clock is running. Unix time 1606294049 
11/25/20 08:47:29z Reset reason: Software Watchdog
11/25/20 08:47:29z Trace:  1:4, 1:3, 1:4, 1:3, 1:4, 1:3, 1:4, 1:3, 1:4, 1:3, 1:4, 1:3, 1:4, 1:3, 1:4, 1:3, 1:4, 1:3, 1:4, 1:1[1], 1:2[2], 9:0[2], 9:0, 9:1, 8:4, 8:6, 8:8, 8:9, 9:3, 9:5, 9:9, 1:2
11/25/20 08:47:29z ESP8266 ChipID: 2528519
11/25/20 08:47:29z IoTaWatt 4.x, Firmware version 02_05_11
11/25/20 08:47:29z SPIFFS mounted.
11/25/20 09:47:30 Local time zone: +1:00
11/25/20 09:47:30 Using Daylight Saving Time (BST) when in effect.
11/25/20 09:47:30 device name: IoTWGrge
11/25/20 09:47:30 HTTP server started
11/25/20 09:47:30 timeSync: service started.
11/25/20 09:47:30 statService: started.
11/25/20 09:47:31 dataLog: service started.
11/25/20 09:47:35 dataLog: Last log entry 11/25/20 09:47:25
11/25/20 09:47:35 Updater: service started. Auto-update class is MINOR
11/25/20 09:47:35 WiFi connected. SSID=guifi.net-ElSerratFW, IP=192.168.19.188, channel=1, RSSI -67db
11/25/20 09:47:36 MDNS responder started for hostname IoTWGrge
11/25/20 09:47:36 LLMNR responder started for hostname IoTWGrge
11/25/20 09:47:36 EmonService: started. url=HTTP://emoncms.elserrat.cat/emoncms, node=IoTWGrge, interval=10, encrypted
11/25/20 09:47:36 influxDB: started, url=HTTP://192.168.19.147:8086, db=iotawatt, interval=10
11/25/20 09:47:38 EmonService: Start posting at 11/25/20 09:47:30
11/25/20 09:47:38 Updater: Auto-update is current for class MINOR.
11/25/20 09:47:41 influxDB: Start posting at 11/25/20 09:47:30
11/25/20 09:48:31 historyLog: service started.
11/25/20 09:48:32 historyLog: Last log entry 11/25/20 09:47:00
11/25/20 09:48:40 PVoutput: started
11/25/20 09:48:40 PVoutput: System El Serrat del Serí, interval 5, donator mode  
11/25/20 09:48:41 PVoutput: Start status beginning 11/25/20 09:45:00
11/25/20 10:30:47 WiFi disconnected.
11/25/20 10:30:51 WiFi connected. SSID=guifi.net-ElSerratFW, IP=192.168.19.188, channel=1, RSSI -67db
11/25/20 11:46:04 WiFi disconnected.
11/25/20 12:46:04 WiFi disconnected more than 60 minutes, restarting.

** Restart **

SD initialized.
11/25/20 11:46:05z Real Time Clock is running. Unix time 1606304765 
11/25/20 11:46:05z Reset reason: Software/System restart
11/25/20 11:46:05z Trace:  1:4, 1:1[2], 1:2[3], 9:0[3], 9:0, 9:1, 8:4, 8:6, 8:8, 8:9, 9:3, 9:5, 9:9, 1:2, 1:3, 1:4, 1:5[20], 20:0, 20:1, 20:2, 20:3, 1:6, 1:3, 1:4, 1:5[5], 5:0, 1:6, 1:3, 1:4, 1:5[21], 21:0, 21:2
11/25/20 11:46:05z ESP8266 ChipID: 2528519
11/25/20 11:46:05z IoTaWatt 4.x, Firmware version 02_05_11
11/25/20 11:46:05z SPIFFS mounted.
11/25/20 12:46:06 Local time zone: +1:00
11/25/20 12:46:06 Using Daylight Saving Time (BST) when in effect.
11/25/20 12:46:06 device name: IoTWGrge
11/25/20 12:46:06 HTTP server started
11/25/20 12:46:06 timeSync: service started.
11/25/20 12:46:06 statService: started.
11/25/20 12:46:07 dataLog: service started.
11/25/20 12:46:12 dataLog: Last log entry 11/25/20 12:46:00
11/25/20 12:46:12 EmonService: started. url=HTTP://emoncms.elserrat.cat/emoncms, node=IoTWGrge, interval=10, encrypted
11/25/20 12:46:12 influxDB: started, url=HTTP://192.168.19.147:8086, db=iotawatt, interval=10
11/25/20 12:46:16 Updater: service started. Auto-update class is MINOR
11/25/20 12:46:16 WiFi connected. SSID=guifi.net-ElSerratFW, IP=192.168.19.188, channel=1, RSSI -68db
11/25/20 12:46:16 MDNS responder started for hostname IoTWGrge
11/25/20 12:46:16 LLMNR responder started for hostname IoTWGrge
11/25/20 12:46:17 EmonService: Start posting at 11/25/20 11:46:10
11/25/20 12:46:17 Updater: Auto-update is current for class MINOR.
11/25/20 12:46:21 influxDB: Start posting at 11/25/20 11:46:10
11/25/20 12:47:08 historyLog: service started.
11/25/20 12:47:09 historyLog: Last log entry 11/25/20 12:46:00
11/25/20 12:47:16 PVoutput: started
11/25/20 12:47:17 PVoutput: System El Serrat del Serí, interval 5, donator mode  
11/25/20 12:47:18 PVoutput: Start status beginning 11/25/20 11:45:00
11/25/20 12:49:40 EmonService: HTTP response -11, retrying.

…and the status screen:
image

… so looks like there was a restart at 11:46, pvoutput & influxdb resumed loading, but emonCMS don’t.
I can’t see any problem at emonCMS, seems fine.

by manually doing several stop/start at the status for the emoncms upload, I was able to resync

12

The message log apparently stops before that happened. There should be entries showing the various stop/start. I’d like to see those.

13

Still like to see that message log, but studying these events have noticed a few things. Prior to this failure, the IoTaWatt was disconnected then immediately reconnected at 10:30:47. That apparently worked fine and Emoncms continued to update. 76 minutes later, at 11:46:04, Wifi was again disconnected but did not reconnect. After an hour without WiFi, IoTaWatt restarted to attempt to recover.

After the restart, WiFi connected normally. (It happens). PVoutput and Influx recovered the hour of history and were fine. IoTaWatt was able connect to Emoncms and query the time of last update, but then subsequent posts of data timed out. The timeout message (-11 HTTP code) is logged after 30 failed attempts, What happens after that I don’t know and would like to see the rest of the log after 11/25/20 12:49:40.

PVoutput is external so addressed on the WAS using DNS. Your influx is located by LAN IP address. The Emoncms is addressed using a domain name, so I’m wondering if it’s on your LAN, and if not, the nature of it’s hosting.

14

Yes, just before the restart, I see mention to “WiFi” disconnectted, which doesn’t makes sense to me, wifi was ok at that time.

Where I’ve to find those other entries? The pasted iotawatt log is complete for before/after the failure.

In the meantime, let me paste all the entries that I’ve available at the log, before the already pasted. I see otther reboots (Watchdog)…

11/25/20 07:31:50 device name: IoTWGrge
11/25/20 07:31:50 HTTP server started
11/25/20 07:31:50 timeSync: service started.
11/25/20 07:31:50 statService: started.
11/25/20 07:31:51 dataLog: service started.
11/25/20 07:31:56 dataLog: Last log entry 11/25/20 07:31:45
11/25/20 07:31:56 Updater: service started. Auto-update class is MINOR
11/25/20 07:31:56 WiFi connected. SSID=guifi.net-ElSerratFW, IP=192.168.19.188, channel=1, RSSI -65db
11/25/20 07:31:57 MDNS responder started for hostname IoTWGrge
11/25/20 07:31:57 LLMNR responder started for hostname IoTWGrge
11/25/20 07:31:57 EmonService: started. url=HTTP://emoncms.elserrat.cat/emoncms, node=IoTWGrge, interval=10, encrypted
11/25/20 07:31:57 influxDB: started, url=HTTP://192.168.19.147:8086, db=iotawatt, interval=10
11/25/20 07:31:58 Updater: Auto-update is current for class MINOR.
11/25/20 07:31:59 EmonService: Start posting at 11/25/20 07:31:50
11/25/20 07:32:02 influxDB: Start posting at 11/25/20 07:31:50
11/25/20 07:32:51 historyLog: service started.
11/25/20 07:32:52 historyLog: Last log entry 11/25/20 07:31:00
11/25/20 07:33:00 PVoutput: started
11/25/20 07:33:00 PVoutput: System El Serrat del Serí, interval 5, donator mode  
11/25/20 07:33:01 PVoutput: Start status beginning 11/25/20 07:30:00

** Restart **

SD initialized.
11/25/20 08:28:52z Real Time Clock is running. Unix time 1606292932 
11/25/20 08:28:52z Reset reason: Software Watchdog
11/25/20 08:28:52z Trace:  9:5, 9:9, 1:2, 1:3, 1:4, 1:3, 1:4, 1:3, 1:4, 1:3, 1:4, 1:3, 1:4, 1:3, 1:4, 1:3, 1:4, 1:3, 1:4, 1:1[2], 1:2[3], 9:0[3], 9:0, 9:1, 8:4, 8:6, 8:8, 8:9, 9:3, 9:5, 9:9, 1:2
11/25/20 08:28:52z ESP8266 ChipID: 2528519
11/25/20 08:28:52z IoTaWatt 4.x, Firmware version 02_05_11
11/25/20 08:28:52z SPIFFS mounted.
11/25/20 09:28:53 Local time zone: +1:00
11/25/20 09:28:53 Using Daylight Saving Time (BST) when in effect.
11/25/20 09:28:53 device name: IoTWGrge
11/25/20 09:28:53 HTTP server started
11/25/20 09:28:53 timeSync: service started.
11/25/20 09:28:53 statService: started.
11/25/20 09:28:54 dataLog: service started.
11/25/20 09:28:59 dataLog: Last log entry 11/25/20 09:28:45
11/25/20 09:28:59 Updater: service started. Auto-update class is MINOR
11/25/20 09:28:59 WiFi connected. SSID=guifi.net-ElSerratFW, IP=192.168.19.188, channel=1, RSSI -65db
11/25/20 09:29:00 MDNS responder started for hostname IoTWGrge
11/25/20 09:29:00 LLMNR responder started for hostname IoTWGrge
11/25/20 09:29:00 EmonService: started. url=HTTP://emoncms.elserrat.cat/emoncms, node=IoTWGrge, interval=10, encrypted
11/25/20 09:29:00 influxDB: started, url=HTTP://192.168.19.147:8086, db=iotawatt, interval=10
11/25/20 09:29:02 Updater: Auto-update is current for class MINOR.
11/25/20 09:29:02 EmonService: Start posting at 11/25/20 09:28:50
11/25/20 09:29:05 influxDB: Start posting at 11/25/20 09:28:50
11/25/20 09:29:54 historyLog: service started.
11/25/20 09:29:55 historyLog: Last log entry 11/25/20 09:28:00
11/25/20 09:30:03 PVoutput: started
11/25/20 09:30:03 PVoutput: System El Serrat del Serí, interval 5, donator mode  
11/25/20 09:30:04 PVoutput: Start status beginning 11/25/20 09:25:00

** Restart **

SD initialized.
11/25/20 08:30:29z Real Time Clock is running. Unix time 1606293029 
11/25/20 08:30:29z Reset reason: Software Watchdog
11/25/20 08:30:29z Trace:  1:4, 1:3, 1:4, 1:3, 1:4, 1:3, 1:4, 1:3, 1:4, 1:3, 1:4, 1:3, 1:4, 1:3, 1:4, 1:3, 1:4, 1:3, 1:4, 1:1[8], 1:2[9], 9:0[9], 9:0, 9:1, 8:4, 8:6, 8:8, 8:9, 9:3, 9:5, 9:9, 1:2
11/25/20 08:30:29z ESP8266 ChipID: 2528519
11/25/20 08:30:29z IoTaWatt 4.x, Firmware version 02_05_11
11/25/20 08:30:29z SPIFFS mounted.
11/25/20 09:30:30 Local time zone: +1:00
11/25/20 09:30:30 Using Daylight Saving Time (BST) when in effect.
11/25/20 09:30:30 device name: IoTWGrge
11/25/20 09:30:30 HTTP server started
11/25/20 09:30:30 timeSync: service started.
11/25/20 09:30:30 statService: started.
11/25/20 09:30:31 dataLog: service started.
11/25/20 09:30:36 dataLog: Last log entry 11/25/20 09:30:25
11/25/20 09:30:36 Updater: service started. Auto-update class is MINOR
11/25/20 09:30:36 WiFi connected. SSID=guifi.net-ElSerratFW, IP=192.168.19.188, channel=1, RSSI -65db
11/25/20 09:30:36 MDNS responder started for hostname IoTWGrge
11/25/20 09:30:36 LLMNR responder started for hostname IoTWGrge
11/25/20 09:30:36 EmonService: started. url=HTTP://emoncms.elserrat.cat/emoncms, node=IoTWGrge, interval=10, encrypted
11/25/20 09:30:37 influxDB: started, url=HTTP://192.168.19.147:8086, db=iotawatt, interval=10
11/25/20 09:30:38 EmonService: Start posting at 11/25/20 09:30:30
11/25/20 09:30:38 Updater: Auto-update is current for class MINOR.
11/25/20 09:30:41 influxDB: Start posting at 11/25/20 09:30:30
11/25/20 09:31:31 historyLog: service started.
11/25/20 09:31:32 historyLog: Last log entry 11/25/20 09:30:00
11/25/20 09:31:40 PVoutput: started
11/25/20 09:31:40 PVoutput: System El Serrat del Serí, interval 5, donator mode  
11/25/20 09:31:41 PVoutput: Start status beginning 11/25/20 09:30:00
15

That’s strange, because when I stop/start my unit, it gets logged.

11/25/20 07:05:01 PVoutput: Start status beginning 11/25/20 06:00:00
11/25/20 10:11:42 EmonService: Stopped. Last post 11/25/20 10:11:40
11/25/20 10:11:48 EmonService: started. url=HTTP://emoncms.org, node=0, interval=10, encrypted
11/25/20 10:11:49 EmonService: Start posting at 11/25/20 10:11:50

So although you report that stop/start seems to remedy the situation, it does not appear that is actually doing anything if there are no message log entries.

16

Ummm, you mean that start/stop should be also on the logs?

Note that I had to click on start/stop many times, as was just uploading one bunch of records each time until reached the current time.

Now is still woking and keeping updated…

Looks isn’t the expected behaviour. Would make sense to “reset” the device and reconfigure? There is any way to export the current config? Maybe just delete emoncms web service and reconfigure?

17

by deleting the emoncms web server, and re-creating it, I was able to reproduce.

after recreation, the log started, but abouyt 4 hours ago, and not going forward.
then I went to sthe manual stop/startt, and going forward just one batch load, until I reached the current time, and now is loading normally.

Below, the log for this. I see the -11 and the stop/start:

SD initialized.
11/25/20 17:16:02z Real Time Clock is running. Unix time 1606324562 
11/25/20 17:16:02z Reset reason: Software Watchdog
11/25/20 17:16:02z Trace:  1:4, 1:3, 1:4, 1:3, 1:4, 1:3, 1:4, 1:3, 1:4, 1:3, 1:4, 1:3, 1:4, 1:3, 1:4, 1:3, 1:4, 1:3, 1:4, 1:1[3], 1:2[4], 9:0[4], 9:0, 9:1, 8:4, 8:6, 8:8, 8:9, 9:3, 9:5, 9:9, 1:2
11/25/20 17:16:02z ESP8266 ChipID: 2528519
11/25/20 17:16:02z IoTaWatt 4.x, Firmware version 02_05_11
11/25/20 17:16:02z SPIFFS mounted.
11/25/20 18:16:03 Local time zone: +1:00
11/25/20 18:16:03 Using Daylight Saving Time (BST) when in effect.
11/25/20 18:16:03 device name: IoTWGrge
11/25/20 18:16:03 HTTP server started
11/25/20 18:16:03 timeSync: service started.
11/25/20 18:16:03 statService: started.
11/25/20 18:16:04 dataLog: service started.
11/25/20 18:16:09 dataLog: Last log entry 11/25/20 18:15:55
11/25/20 18:16:10 Updater: service started. Auto-update class is MINOR
11/25/20 18:16:10 WiFi connected. SSID=guifi.net-ElSerratFW, IP=192.168.19.188, channel=1, RSSI -76db
11/25/20 18:16:10 MDNS responder started for hostname IoTWGrge
11/25/20 18:16:10 LLMNR responder started for hostname IoTWGrge
11/25/20 18:16:10 EmonService: started. url=HTTP://emoncms.elserrat.cat/emoncms, node=IoTWGrge, interval=10, encrypted
11/25/20 18:16:11 influxDB: started, url=HTTP://192.168.19.147:8086, db=iotawatt, interval=10
11/25/20 18:16:12 EmonService: Start posting at 11/25/20 14:49:30
11/25/20 18:16:12 Updater: Auto-update is current for class MINOR.
11/25/20 18:16:16 influxDB: Start posting at 11/25/20 18:16:00
11/25/20 18:17:05 historyLog: service started.
11/25/20 18:17:06 historyLog: Last log entry 11/25/20 18:15:00
11/25/20 18:17:13 PVoutput: started
11/25/20 18:17:14 PVoutput: System El Serrat del Serí, interval 5, donator mode  
11/25/20 18:17:14 PVoutput: Start status beginning 11/25/20 18:15:00
11/25/20 18:18:45 EmonService: HTTP response -11, retrying.
11/25/20 18:37:02 EmonService: Stopped.  Last post 11/25/20 14:49:20
11/25/20 18:41:42 EmonService: started. url=HTTP://emoncms.elserrat.cat/emoncms, node=IoTWGrge, interval=10, encrypted
11/25/20 18:41:43 EmonService: Start posting at 11/25/20 14:53:40
11/25/20 18:43:11 EmonService: Stopped.  Last post 11/25/20 14:53:30
11/25/20 18:43:20 EmonService: started. url=HTTP://emoncms.elserrat.cat/emoncms, node=IoTWGrge, interval=10, encrypted
11/25/20 18:43:22 EmonService: Start posting at 11/25/20 14:57:40
11/25/20 18:45:39 EmonService: Stopped.  Last post 11/25/20 14:57:30
11/25/20 18:45:49 EmonService: started. url=HTTP://emoncms.elserrat.cat/emoncms, node=IoTWGrge, interval=120, encrypted
11/25/20 18:45:51 EmonService: Start posting at 11/25/20 15:46:00
11/25/20 18:47:31 EmonService: Stopped.  Last post 11/25/20 15:44:00
11/25/20 18:47:37 EmonService: started. url=HTTP://emoncms.elserrat.cat/emoncms, node=IoTWGrge, interval=120, encrypted
11/25/20 18:47:39 EmonService: Start posting at 11/25/20 16:34:00
11/25/20 18:48:00 EmonService: Stopped.  Last post 11/25/20 16:32:00
11/25/20 18:48:08 EmonService: started. url=HTTP://emoncms.elserrat.cat/emoncms, node=IoTWGrge, interval=120, encrypted
11/25/20 18:48:09 EmonService: Start posting at 11/25/20 17:20:00
11/25/20 18:48:15 EmonService: Stopped.  Last post 11/25/20 17:18:00
11/25/20 18:48:18 EmonService: started. url=HTTP://emoncms.elserrat.cat/emoncms, node=IoTWGrge, interval=120, encrypted
11/25/20 18:48:20 EmonService: Start posting at 11/25/20 18:06:00
11/25/20 18:48:25 EmonService: Stopped.  Last post 11/25/20 18:04:00
11/25/20 18:48:27 EmonService: started. url=HTTP://emoncms.elserrat.cat/emoncms, node=IoTWGrge, interval=120, encrypted
11/25/20 18:48:28 EmonService: Start posting at 11/25/20 18:50:00
11/25/20 18:48:30 EmonService: Stopped.  Last post 11/25/20 18:48:00
11/25/20 18:48:37 EmonService: started. url=HTTP://emoncms.elserrat.cat/emoncms, node=IoTWGrge, interval=120, encrypted
11/25/20 18:48:39 EmonService: Start posting at 11/25/20 18:50:00
11/25/20 18:49:12 EmonService: Stopped.  Last post 11/25/20 18:06:00
11/25/20 18:49:16 EmonService: started. url=HTTP://emoncms.elserrat.cat/emoncms, node=IoTWGrge, interval=10, encrypted
11/25/20 18:49:17 EmonService: Start posting at 11/25/20 18:10:10
11/25/20 18:49:32 EmonService: Stopped.  Last post 11/25/20 18:10:00
11/25/20 18:49:36 EmonService: started. url=HTTP://emoncms.elserrat.cat/emoncms, node=IoTWGrge, interval=10, encrypted
11/25/20 18:49:37 EmonService: Start posting at 11/25/20 18:14:10
11/25/20 18:49:39 EmonService: Stopped.  Last post 11/25/20 18:14:00
11/25/20 18:49:40 EmonService: started. url=HTTP://emoncms.elserrat.cat/emoncms, node=IoTWGrge, interval=10, encrypted
11/25/20 18:49:42 EmonService: Start posting at 11/25/20 18:14:10
11/25/20 18:49:48 EmonService: Stopped.  Last post 11/25/20 18:14:00
11/25/20 18:49:57 EmonService: started. url=HTTP://emoncms.elserrat.cat/emoncms, node=IoTWGrge, interval=10, encrypted
11/25/20 18:49:59 EmonService: Start posting at 11/25/20 18:18:20
11/25/20 18:50:12 EmonService: Stopped.  Last post 11/25/20 18:18:10
11/25/20 18:50:24 EmonService: started. url=HTTP://emoncms.elserrat.cat/emoncms, node=IoTWGrge, interval=10, encrypted
11/25/20 18:50:26 EmonService: Start posting at 11/25/20 18:22:20
11/25/20 18:50:26 EmonService: Stopped.  Last post 11/25/20 18:22:10
11/25/20 18:50:31 EmonService: started. url=HTTP://emoncms.elserrat.cat/emoncms, node=IoTWGrge, interval=10, encrypted
11/25/20 18:50:32 EmonService: Start posting at 11/25/20 18:22:20
11/25/20 18:50:53 EmonService: Stopped.  Last post 11/25/20 18:22:10
11/25/20 18:50:56 EmonService: started. url=HTTP://emoncms.elserrat.cat/emoncms, node=IoTWGrge, interval=10, encrypted
11/25/20 18:50:58 EmonService: Start posting at 11/25/20 18:26:20
11/25/20 18:50:59 EmonService: Stopped.  Last post 11/25/20 18:26:10
11/25/20 18:51:02 EmonService: started. url=HTTP://emoncms.elserrat.cat/emoncms, node=IoTWGrge, interval=10, encrypted
11/25/20 18:51:04 EmonService: Start posting at 11/25/20 18:26:20
11/25/20 18:51:10 EmonService: Stopped.  Last post 11/25/20 18:26:10
11/25/20 18:51:13 EmonService: started. url=HTTP://emoncms.elserrat.cat/emoncms, node=IoTWGrge, interval=10, encrypted
11/25/20 18:51:15 EmonService: Start posting at 11/25/20 18:30:20
11/25/20 18:51:20 EmonService: Stopped.  Last post 11/25/20 18:30:10
11/25/20 18:51:23 EmonService: started. url=HTTP://emoncms.elserrat.cat/emoncms, node=IoTWGrge, interval=10, encrypted
11/25/20 18:51:25 EmonService: Start posting at 11/25/20 18:34:20
11/25/20 18:51:47 EmonService: Stopped.  Last post 11/25/20 18:34:10
11/25/20 18:51:51 EmonService: started. url=HTTP://emoncms.elserrat.cat/emoncms, node=IoTWGrge, interval=10, encrypted
11/25/20 18:51:52 EmonService: Start posting at 11/25/20 18:38:20
11/25/20 18:51:59 EmonService: Stopped.  Last post 11/25/20 18:38:10
11/25/20 18:52:02 EmonService: started. url=HTTP://emoncms.elserrat.cat/emoncms, node=IoTWGrge, interval=10, encrypted
11/25/20 18:52:04 EmonService: Start posting at 11/25/20 18:42:20
11/25/20 18:52:10 EmonService: Stopped.  Last post 11/25/20 18:42:10
11/25/20 18:52:15 EmonService: started. url=HTTP://emoncms.elserrat.cat/emoncms, node=IoTWGrge, interval=10, encrypted
11/25/20 18:52:16 EmonService: Start posting at 11/25/20 18:46:20
11/25/20 18:52:21 EmonService: Stopped.  Last post 11/25/20 18:46:10
11/25/20 18:52:25 EmonService: started. url=HTTP://emoncms.elserrat.cat/emoncms, node=IoTWGrge, interval=10, encrypted
11/25/20 18:52:26 EmonService: Start posting at 11/25/20 18:50:20
11/25/20 18:52:31 EmonService: Stopped.  Last post 11/25/20 18:52:30
11/25/20 18:52:36 EmonService: started. url=HTTP://emoncms.elserrat.cat/emoncms, node=IoTWGrge, interval=10, encrypted
11/25/20 18:52:38 EmonService: Start posting at 11/25/20 18:52:40
18

Next question, is all the data from 18:42 to 18:53 present in Emoncms or are there any holes. You can use their graphing application with 10 second intervals and check the box to show missing data. If there is missing data, can you post the CSV?

19

Yes, the graph is there with data.
Note that you’ll see some nulls, but aren’t because there is no data: Since I had to stop/start for each batch load, I changed the interval to 120 secs, and went back to 10s when I was close to current time. With 120secs I was able to load around 40 mins, while with 10s, only 4 mins.

Now the interval is 10s.

Below the csv for the graph:
“Unix timestamp”, “IoTWGrge:VoltsIoTGaratge”, “IoTWGrge:import”
1606326180, null, null
1606326190, null, null
1606326200, null, null
1606326210, null, null
1606326220, null, null
1606326230, null, null
1606326240, 221.8, 1246.1
1606326250, null, null
1606326260, null, null
1606326270, null, null
1606326280, null, null
1606326290, null, null
1606326300, null, null
1606326310, null, null
1606326320, null, null
1606326330, null, null
1606326340, null, null
1606326350, null, null
1606326360, 223.1, 1188.3
1606326370, null, null
1606326380, null, null
1606326390, null, null
1606326400, null, null
1606326410, null, null
1606326420, null, null
1606326430, null, null
1606326440, null, null
1606326450, null, null
1606326460, null, null
1606326470, null, null
1606326480, 222.1, 1171.0
1606326490, 222.0, 1180.5
1606326500, 220.6, 1164.3
1606326510, 220.6, 1168.0
1606326520, 220.9, 1159.1
1606326530, 222.1, 1156.0
1606326540, 221.9, 1159.2
1606326550, 221.9, 1199.3
1606326560, 222.3, 1192.1
1606326570, 221.0, 1224.4
1606326580, 220.8, 1264.2
1606326590, 220.7, 1269.0
1606326600, 221.4, 1257.8
1606326610, 222.6, 1290.9
1606326620, 222.4, 1277.4
1606326630, 222.4, 1261.2
1606326640, 221.7, 1258.0
1606326650, 221.4, 1262.0
1606326660, 222.3, 1256.0
1606326670, 221.6, 1266.4
1606326680, 221.4, 1259.7
1606326690, 221.8, 1244.3
1606326700, 221.9, 1245.9
1606326710, 221.8, 1229.8
1606326720, 221.8, 1236.0
1606326730, 221.2, 1272.6
1606326740, 220.9, 1258.7
1606326750, 221.1, 1221.7
1606326760, 220.9, 1229.8
1606326770, 221.4, 1229.2
1606326780, 221.7, 1233.2
1606326790, 221.5, 1252.6
1606326800, 221.8, 1281.6
1606326810, 222.4, 1232.1

20

Maybe with date time string will be more easy to read… Just found that option for the graph csv export at emoncms.

"Date-time string", "IoTWGrge:VoltsIoTGaratge", "IoTWGrge:import"
2020-11-25 18:43:00, null, null
2020-11-25 18:43:10, null, null
2020-11-25 18:43:20, null, null
2020-11-25 18:43:30, null, null
2020-11-25 18:43:40, null, null
2020-11-25 18:43:50, null, null
2020-11-25 18:44:00, 221.8, 1246.1
2020-11-25 18:44:10, null, null
2020-11-25 18:44:20, null, null
2020-11-25 18:44:30, null, null
2020-11-25 18:44:40, null, null
2020-11-25 18:44:50, null, null
2020-11-25 18:45:00, null, null
2020-11-25 18:45:10, null, null
2020-11-25 18:45:20, null, null
2020-11-25 18:45:30, null, null
2020-11-25 18:45:40, null, null
2020-11-25 18:45:50, null, null
2020-11-25 18:46:00, 223.1, 1188.3
2020-11-25 18:46:10, null, null
2020-11-25 18:46:20, null, null
2020-11-25 18:46:30, null, null
2020-11-25 18:46:40, null, null
2020-11-25 18:46:50, null, null
2020-11-25 18:47:00, null, null
2020-11-25 18:47:10, null, null
2020-11-25 18:47:20, null, null
2020-11-25 18:47:30, null, null
2020-11-25 18:47:40, null, null
2020-11-25 18:47:50, null, null
2020-11-25 18:48:00, 222.1, 1171.0
2020-11-25 18:48:10, 222.0, 1180.5
2020-11-25 18:48:20, 220.6, 1164.3
2020-11-25 18:48:30, 220.6, 1168.0
2020-11-25 18:48:40, 220.9, 1159.1
2020-11-25 18:48:50, 222.1, 1156.0
2020-11-25 18:49:00, 221.9, 1159.2
2020-11-25 18:49:10, 221.9, 1199.3
2020-11-25 18:49:20, 222.3, 1192.1
2020-11-25 18:49:30, 221.0, 1224.4
2020-11-25 18:49:40, 220.8, 1264.2
2020-11-25 18:49:50, 220.7, 1269.0
2020-11-25 18:50:00, 221.4, 1257.8
2020-11-25 18:50:10, 222.6, 1290.9
2020-11-25 18:50:20, 222.4, 1277.4
2020-11-25 18:50:30, 222.4, 1261.2
2020-11-25 18:50:40, 221.7, 1258.0
2020-11-25 18:50:50, 221.4, 1262.0
2020-11-25 18:51:00, 222.3, 1256.0
2020-11-25 18:51:10, 221.6, 1266.4
2020-11-25 18:51:20, 221.4, 1259.7
2020-11-25 18:51:30, 221.8, 1244.3
2020-11-25 18:51:40, 221.9, 1245.9
2020-11-25 18:51:50, 221.8, 1229.8
2020-11-25 18:52:00, 221.8, 1236.0
2020-11-25 18:52:10, 221.2, 1272.6
2020-11-25 18:52:20, 220.9, 1258.7
2020-11-25 18:52:30, 221.1, 1221.7
2020-11-25 18:52:40, 220.9, 1229.8
2020-11-25 18:52:50, 221.4, 1229.2
2020-11-25 18:53:00, 221.7, 1233.2
2020-11-25 18:53:10, 221.5, 1252.6
2020-11-25 18:53:20, 221.8, 1281.6
2020-11-25 18:53:30, 222.4, 1232.1
1 Like