Emoncms Hanging

bungo63 · 21 March 2018 16:46

I have recently i carried out a fresh upgrade to my Emonpi using a preloaded SD card from the shop with the latest software , I did not back up my old data so have nothing to restore.
I purchased my emon pi two years ago and its always worked fine.

Whilst setting up the local Emoncms (editing the inputs, feeds etc) I have experienced a recurring situation whereby the application hangs.
I am not able to SSH onto the emonpi, it does not respond to ping but the unit itself looks healthy and the LCD display looks normal(including wifi strength). My wifi network seems to eb Ok as far as I can tell

The only course of action open tome is to physically reset the emonpi. After which all seems well again for a while.

The hang does not occur on any specific screen, it just seems to happen.

My emon pi is a fairly simple arrangement with 5 temperature sensors monitoring my Solar Thermal hot water system.

Here are a few lines of the emonhub log file which capture the moment it hangs (14:19) , but to my untrained eye there nothing obvious in there that suggests what’s happening.

Would anyone have any thoughts as to where I should start looking? Any advice would be greatly appreciated.

2018-03-21 14:19:31,088 DEBUG    MQTT       Publishing: emon/emonpi/power1 0
2018-03-21 14:19:31,091 DEBUG    MQTT       Publishing: emon/emonpi/power2 0
2018-03-21 14:19:31,094 DEBUG    MQTT       Publishing: emon/emonpi/power1pluspower2 0
2018-03-21 14:19:31,097 DEBUG    MQTT       Publishing: emon/emonpi/vrms 0
2018-03-21 14:19:31,100 DEBUG    MQTT       Publishing: emon/emonpi/t1 19.1
2018-03-21 14:19:31,104 DEBUG    MQTT       Publishing: emon/emonpi/t2 51.8
2018-03-21 14:19:31,108 DEBUG    MQTT       Publishing: emon/emonpi/t3 50.6
2018-03-21 14:19:31,112 DEBUG    MQTT       Publishing: emon/emonpi/t4 46.5
2018-03-21 14:19:31,115 DEBUG    MQTT       Publishing: emon/emonpi/t5 53.1
2018-03-21 14:19:31,121 DEBUG    MQTT       Publishing: emon/emonpi/t6 0
2018-03-21 14:19:31,124 DEBUG    MQTT       Publishing: emon/emonpi/pulsecount 1
2018-03-21 14:19:31,127 INFO     MQTT       Publishing: emonhub/rx/5/values 0,0,0,0,19.1,51.8,50.6,46.5,53.1,0,1
2018-03-21 14:19:31,131 DEBUG    RFM2Pi     Discarding RX frame 'unreliable content'? 14 37 96 33 34 83 53 128 0 0 128 35 16 16 32 16 192 66 9 144 10 (-99)
2018-03-21 14:19:31,844 DEBUG    RFM2Pi     Discarding RX frame 'unreliable content'? 0 12 32 144 25 4 4 184 130 0 32 8 9 44 32 66 164 180 64 49 59 (-101)
2018-03-21 14:19:32,630 DEBUG    emoncmsorg Buffer size: 6
2018-03-21 14:19:33,185 DEBUG    RFM2Pi     Discarding RX frame 'unreliable content'? 0 0 64 56 67 4 0 0 48 224 32 161 187 166 0 32 0 32 40 82 32 (-96)
2018-03-21 15:05:07,465 DEBUG    RFM2Pi     Discarding RX frame 'unreliable content'? 2 72 16 1 32 0 32 1 80 25 224 16 96 0 0 8 25 1 32 19 32 (-98)
2018-03-21 15:05:08,407 DEBUG    RFM2Pi     26 NEW FRAME : OK 5 0 0 0 0 0 0 0 0 191 0 6 2 250 1 209 1 19 2 0 0 1 0 0 0 (-0)
2018-03-21 15:05:08,411 DEBUG    RFM2Pi     26 Timestamp : 1521644708.41
2018-03-21 15:05:08,412 DEBUG    RFM2Pi     26 From Node : 5
2018-03-21 15:05:08,413 DEBUG    RFM2Pi     26    Values : [0, 0, 0, 0, 19.1, 51.800000000000004, 50.6, 46.5, 53.1, 0, 1]
2018-03-21 15:05:08,413 DEBUG    RFM2Pi     26 Sent to channel(start)' : ToEmonCMS
2018-03-21 15:05:08,414 DEBUG    RFM2Pi     26 Sent to channel(end)' : ToEmonCMS
2018-03-21 15:05:08,501 DEBUG    MQTT       Publishing: emon/emonpi/power1 0
2018-03-21 15:05:08,503 DEBUG    MQTT       Publishing: emon/emonpi/power2 0
2018-03-21 15:05:08,505 DEBUG    MQTT       Publishing: emon/emonpi/power1pluspower2 0
2018-03-21 15:05:08,507 DEBUG    MQTT       Publishing: emon/emonpi/vrms 0
2018-03-21 15:05:08,509 DEBUG    MQTT       Publishing: emon/emonpi/t1 19.1
2018-03-21 15:05:08,511 DEBUG    MQTT       Publishing: emon/emonpi/t2 51.8
2018-03-21 15:05:08,513 DEBUG    MQTT       Publishing: emon/emonpi/t3 50.6
2018-03-21 15:05:08,515 DEBUG    MQTT       Publishing: emon/emonpi/t4 46.5
2018-03-21 15:05:08,517 DEBUG    MQTT       Publishing: emon/emonpi/t5 53.1
2018-03-21 15:05:08,519 DEBUG    MQTT       Publishing: emon/emonpi/t6 0

pb66 · 21 March 2018 17:02

Can you provide a bit more log? this snippet is too small to get a feel for whats going on.

Are you saying that at 14:19 you used the rest button on the emonPi?

bungo63 · 21 March 2018 17:27

Hi Paul, thank you for replying so promptly and so late in the day.

Not sure about the time that I started the reboot. I assumed that t 14:19 was the time the that things started to hang as it took me while to go an initiate the reboot.

Here is some more log - prior to 1419

2018-03-21 14:18:20,953 DEBUG    RFM2Pi     11 Timestamp : 1521641900.95
2018-03-21 14:18:20,954 DEBUG    RFM2Pi     11 From Node : 5
2018-03-21 14:18:20,956 DEBUG    RFM2Pi     11    Values : [0, 0, 0, 0, 19.1, 51.800000000000004, 50.7, 46.400000000000006, 53.1, 0, 1]
2018-03-21 14:18:20,956 DEBUG    RFM2Pi     11 Sent to channel(start)' : ToEmonCMS
2018-03-21 14:18:20,957 DEBUG    RFM2Pi     11 Sent to channel(end)' : ToEmonCMS
2018-03-21 14:18:21,056 DEBUG    MQTT       Publishing: emon/emonpi/power1 0
2018-03-21 14:18:21,058 DEBUG    MQTT       Publishing: emon/emonpi/power2 0
2018-03-21 14:18:21,060 DEBUG    MQTT       Publishing: emon/emonpi/power1pluspower2 0
2018-03-21 14:18:21,062 DEBUG    MQTT       Publishing: emon/emonpi/vrms 0
2018-03-21 14:18:21,066 DEBUG    MQTT       Publishing: emon/emonpi/t1 19.1
2018-03-21 14:18:21,071 DEBUG    MQTT       Publishing: emon/emonpi/t2 51.8
2018-03-21 14:18:21,073 DEBUG    MQTT       Publishing: emon/emonpi/t3 50.7
2018-03-21 14:18:21,078 DEBUG    MQTT       Publishing: emon/emonpi/t4 46.4
2018-03-21 14:18:21,080 DEBUG    MQTT       Publishing: emon/emonpi/t5 53.1
2018-03-21 14:18:21,083 DEBUG    MQTT       Publishing: emon/emonpi/t6 0
2018-03-21 14:18:21,086 DEBUG    MQTT       Publishing: emon/emonpi/pulsecount 1
2018-03-21 14:18:21,088 INFO     MQTT       Publishing: emonhub/rx/5/values 0,0,0,0,19.1,51.8,50.7,46.4,53.1,0,1
2018-03-21 14:18:21,478 DEBUG    RFM2Pi     Discarding RX frame 'unreliable content'? 2 144 5 131 92 20 141 81 66 21 85 32 1 2 32 97 12 10 36 192 96 (-93)
2018-03-21 14:18:22,393 DEBUG    RFM2Pi     Discarding RX frame 'unreliable content'? 15 16 96 4 94 120 181 249 163 16 162 136 244 86 30 6 128 134 1 204 98 (-95)
2018-03-21 14:18:25,993 DEBUG    RFM2Pi     12 NEW FRAME : OK 5 0 0 0 0 0 0 0 0 191 0 6 2 250 1 209 1 19 2 0 0 1 0 0 0 (-0)
2018-03-21 14:18:25,996 DEBUG    RFM2Pi     12 Timestamp : 1521641905.99
2018-03-21 14:18:25,997 DEBUG    RFM2Pi     12 From Node : 5
2018-03-21 14:18:25,998 DEBUG    RFM2Pi     12    Values : [0, 0, 0, 0, 19.1, 51.800000000000004, 50.6, 46.5, 53.1, 0, 1]
2018-03-21 14:18:25,999 DEBUG    RFM2Pi     12 Sent to channel(start)' : ToEmonCMS
2018-03-21 14:18:25,999 DEBUG    RFM2Pi     12 Sent to channel(end)' : ToEmonCMS
2018-03-21 14:18:26,088 DEBUG    MQTT       Publishing: emon/emonpi/power1 0
2018-03-21 14:18:26,091 DEBUG    MQTT       Publishing: emon/emonpi/power2 0
2018-03-21 14:18:26,094 DEBUG    MQTT       Publishing: emon/emonpi/power1pluspower2 0
2018-03-21 14:18:26,096 DEBUG    MQTT       Publishing: emon/emonpi/vrms 0
2018-03-21 14:18:26,099 DEBUG    MQTT       Publishing: emon/emonpi/t1 19.1
2018-03-21 14:18:26,101 DEBUG    MQTT       Publishing: emon/emonpi/t2 51.8
2018-03-21 14:18:26,103 DEBUG    MQTT       Publishing: emon/emonpi/t3 50.6
2018-03-21 14:18:26,105 DEBUG    MQTT       Publishing: emon/emonpi/t4 46.5
2018-03-21 14:18:26,106 DEBUG    MQTT       Publishing: emon/emonpi/t5 53.1
2018-03-21 14:18:26,108 DEBUG    MQTT       Publishing: emon/emonpi/t6 0
2018-03-21 14:18:26,110 DEBUG    MQTT       Publishing: emon/emonpi/pulsecount 1
2018-03-21 14:18:26,112 INFO     MQTT       Publishing: emonhub/rx/5/values 0,0,0,0,19.1,51.8,50.6,46.5,53.1,0,1
2018-03-21 14:18:26,716 DEBUG    RFM2Pi     Discarding RX frame 'unreliable content'? 3 188 99 32 20 208 34 15 17 97 183 227 105 148 4 14 49 128 39 196 162 (-93)
2018-03-21 14:18:27,428 DEBUG    RFM2Pi     Discarding RX frame 'unreliable content'? 16 195 18 141 8 67 36 68 184 99 44 48 164 9 0 2 208 152 1 162 144 (-94)
2018-03-21 14:18:27,739 DEBUG    RFM2Pi     Discarding RX frame 'unreliable content'? 7 4 37 68 114 40 72 194 96 32 64 56 20 32 64 0 58 80 9 162 144 (-93)
2018-03-21 14:18:29,058 DEBUG    RFM2Pi     Discarding RX frame 'unreliable content'? 0 2 192 0 12 88 18 224 82 37 64 3 132 62 36 146 9 32 72 178 16 (-96)
2018-03-21 14:18:30,977 DEBUG    RFM2Pi     13 NEW FRAME : OK 5 0 0 0 0 0 0 0 0 191 0 6 2 250 1 209 1 19 2 0 0 1 0 0 0 (-0)
2018-03-21 14:18:30,982 DEBUG    RFM2Pi     13 Timestamp : 1521641910.98
2018-03-21 14:18:30,983 DEBUG    RFM2Pi     13 From Node : 5
2018-03-21 14:18:30,985 DEBUG    RFM2Pi     13    Values : [0, 0, 0, 0, 19.1, 51.800000000000004, 50.6, 46.5, 53.1, 0, 1]
2018-03-21 14:18:30,986 DEBUG    RFM2Pi     13 Sent to channel(start)' : ToEmonCMS
2018-03-21 14:18:30,987 DEBUG    RFM2Pi     13 Sent to channel(end)' : ToEmonCMS
2018-03-21 14:18:31,071 DEBUG    MQTT       Publishing: emon/emonpi/power1 0
2018-03-21 14:18:31,073 DEBUG    MQTT       Publishing: emon/emonpi/power2 0
2018-03-21 14:18:31,076 DEBUG    MQTT       Publishing: emon/emonpi/power1pluspower2 0
2018-03-21 14:18:31,079 DEBUG    MQTT       Publishing: emon/emonpi/vrms 0
2018-03-21 14:18:31,081 DEBUG    MQTT       Publishing: emon/emonpi/t1 19.1
2018-03-21 14:18:31,084 DEBUG    MQTT       Publishing: emon/emonpi/t2 51.8
2018-03-21 14:18:31,087 DEBUG    MQTT       Publishing: emon/emonpi/t3 50.6
2018-03-21 14:18:31,090 DEBUG    MQTT       Publishing: emon/emonpi/t4 46.5
2018-03-21 14:18:31,092 DEBUG    MQTT       Publishing: emon/emonpi/t5 53.1
2018-03-21 14:18:31,095 DEBUG    MQTT       Publishing: emon/emonpi/t6 0
2018-03-21 14:18:31,097 DEBUG    MQTT       Publishing: emon/emonpi/pulsecount 1
2018-03-21 14:18:31,100 INFO     MQTT       Publishing: emonhub/rx/5/values 0,0,0,0,19.1,51.8,50.6,46.5,53.1,0,1
2018-03-21 14:18:32,537 DEBUG    emoncmsorg Buffer size: 6
2018-03-21 14:18:34,868 DEBUG    RFM2Pi     Discarding RX frame 'unreliable content'? 0 36 160 78 2 72 20 150 193 192 27 48 130 5 242 146 9 32 72 178 16 (-93)
2018-03-21 14:18:35,979 DEBUG    RFM2Pi     14 NEW FRAME : OK 5 0 0 0 0 0 0 0 0 191 0 5 2 250 1 209 1 19 2 0 0 1 0 0 0 (-0)
2018-03-21 14:18:35,983 DEBUG    RFM2Pi     14 Timestamp : 1521641915.98
2018-03-21 14:18:35,984 DEBUG    RFM2Pi     14 From Node : 5
2018-03-21 14:18:35,985 DEBUG    RFM2Pi     14    Values : [0, 0, 0, 0, 19.1, 51.7, 50.6, 46.5, 53.1, 0, 1]
2018-03-21 14:18:35,985 DEBUG    RFM2Pi     14 Sent to channel(start)' : ToEmonCMS
2018-03-21 14:18:35,986 DEBUG    RFM2Pi     14 Sent to channel(end)' : ToEmonCMS
2018-03-21 14:18:36,081 DEBUG    MQTT       Publishing: emon/emonpi/power1 0
2018-03-21 14:18:36,083 DEBUG    MQTT       Publishing: emon/emonpi/power2 0
2018-03-21 14:18:36,085 DEBUG    MQTT       Publishing: emon/emonpi/power1pluspower2 0
2018-03-21 14:18:36,087 DEBUG    MQTT       Publishing: emon/emonpi/vrms 0
2018-03-21 14:18:36,089 DEBUG    MQTT       Publishing: emon/emonpi/t1 19.1
2018-03-21 14:18:36,096 DEBUG    RFM2Pi     Discarding RX frame 'unreliable content'? 0 1 25 78 2 72 20 150 193 192 27 48 130 5 242 146 9 32 72 178 16 (-94)
2018-03-21 14:18:36,097 DEBUG    MQTT       Publishing: emon/emonpi/t2 51.7
2018-03-21 14:18:36,099 DEBUG    MQTT       Publishing: emon/emonpi/t3 50.6
2018-03-21 14:18:36,101 DEBUG    MQTT       Publishing: emon/emonpi/t4 46.5
2018-03-21 14:18:36,103 DEBUG    MQTT       Publishing: emon/emonpi/t5 53.1
2018-03-21 14:18:36,105 DEBUG    MQTT       Publishing: emon/emonpi/t6 0
2018-03-21 14:18:36,107 DEBUG    MQTT       Publishing: emon/emonpi/pulsecount 1
2018-03-21 14:18:36,109 INFO     MQTT       Publishing: emonhub/rx/5/values 0,0,0,0,19.1,51.7,50.6,46.5,53.1,0,1
2018-03-21 14:18:40,957 DEBUG    RFM2Pi     15 NEW FRAME : OK 5 0 0 0 0 0 0 0 0 191 0 5 2 250 1 209 1 19 2 0 0 1 0 0 0 (-0)
2018-03-21 14:18:40,961 DEBUG    RFM2Pi     15 Timestamp : 1521641920.96
2018-03-21 14:18:40,962 DEBUG    RFM2Pi     15 From Node : 5
2018-03-21 14:18:40,962 DEBUG    RFM2Pi     15    Values : [0, 0, 0, 0, 19.1, 51.7, 50.6, 46.5, 53.1, 0, 1]
2018-03-21 14:18:40,963 DEBUG    RFM2Pi     15 Sent to channel(start)' : ToEmonCMS
2018-03-21 14:18:40,964 DEBUG    RFM2Pi     15 Sent to channel(end)' : ToEmonCMS
2018-03-21 14:18:41,066 DEBUG    MQTT       Publishing: emon/emonpi/power1 0
2018-03-21 14:18:41,068 DEBUG    MQTT       Publishing: emon/emonpi/power2 0
2018-03-21 14:18:41,070 DEBUG    MQTT       Publishing: emon/emonpi/power1pluspower2 0
2018-03-21 14:18:41,072 DEBUG    MQTT       Publishing: emon/emonpi/vrms 0
2018-03-21 14:18:41,074 DEBUG    MQTT       Publishing: emon/emonpi/t1 19.1
2018-03-21 14:18:41,076 DEBUG    MQTT       Publishing: emon/emonpi/t2 51.7
2018-03-21 14:18:41,078 DEBUG    MQTT       Publishing: emon/emonpi/t3 50.6
2018-03-21 14:18:41,080 DEBUG    MQTT       Publishing: emon/emonpi/t4 46.5
2018-03-21 14:18:41,082 DEBUG    MQTT       Publishing: emon/emonpi/t5 53.1
2018-03-21 14:18:41,083 DEBUG    MQTT       Publishing: emon/emonpi/t6 0
2018-03-21 14:18:41,086 DEBUG    MQTT       Publishing: emon/emonpi/pulsecount 1
2018-03-21 14:18:41,088 INFO     MQTT       Publishing: emonhub/rx/5/values 0,0,0,0,19.1,51.7,50.6,46.5,53.1,0,1
2018-03-21 14:18:42,743 DEBUG    RFM2Pi     Discarding RX frame 'unreliable content'? 1 18 109 192 0 0 35 0 117 171 100 8 134 80 33 32 67 192 3 51 62 (-95)
2018-03-21 14:18:43,051 DEBUG    RFM2Pi     Discarding RX frame 'unreliable content'? 5 1 64 9 0 4 1 51 1 32 64 133 1 80 33 32 67 192 3 51 62 (-93)
2018-03-21 14:18:45,956 DEBUG    RFM2Pi     16 NEW FRAME : OK 5 0 0 0 0 0 0 0 0 191 0 5 2 250 1 209 1 19 2 0 0 1 0 0 0 (-0)
2018-03-21 14:18:45,960 DEBUG    RFM2Pi     16 Timestamp : 1521641925.96
2018-03-21 14:18:45,961 DEBUG    RFM2Pi     16 From Node : 5
2018-03-21 14:18:45,962 DEBUG    RFM2Pi     16    Values : [0, 0, 0, 0, 19.1, 51.7, 50.6, 46.5, 53.1, 0, 1]
2018-03-21 14:18:45,962 DEBUG    RFM2Pi     16 Sent to channel(start)' : ToEmonCMS
2018-03-21 14:18:45,963 DEBUG    RFM2Pi     16 Sent to channel(end)' : ToEmonCMS
2018-03-21 14:18:46,051 DEBUG    MQTT       Publishing: emon/emonpi/power1 0
2018-03-21 14:18:46,053 DEBUG    MQTT       Publishing: emon/emonpi/power2 0
2018-03-21 14:18:46,056 DEBUG    MQTT       Publishing: emon/emonpi/power1pluspower2 0
2018-03-21 14:18:46,058 DEBUG    MQTT       Publishing: emon/emonpi/vrms 0
2018-03-21 14:18:46,060 DEBUG    MQTT       Publishing: emon/emonpi/t1 19.1
2018-03-21 14:18:46,062 DEBUG    MQTT       Publishing: emon/emonpi/t2 51.7
2018-03-21 14:18:46,064 DEBUG    MQTT       Publishing: emon/emonpi/t3 50.6
2018-03-21 14:18:46,066 DEBUG    MQTT       Publishing: emon/emonpi/t4 46.5
2018-03-21 14:18:46,068 DEBUG    MQTT       Publishing: emon/emonpi/t5 53.1
2018-03-21 14:18:46,071 DEBUG    MQTT       Publishing: emon/emonpi/t6 0
2018-03-21 14:18:46,073 DEBUG    MQTT       Publishing: emon/emonpi/pulsecount 1
2018-03-21 14:18:46,075 INFO     MQTT       Publishing: emonhub/rx/5/values 0,0,0,0,19.1,51.7,50.6,46.5,53.1,0,1
2018-03-21 14:18:47,508 DEBUG    RFM2Pi     Discarding RX frame 'unreliable content'? 0 56 44 27 59 32 1 51 1 32 64 133 1 80 33 32 67 192 3 51 62 (-94)
2018-03-21 14:18:51,043 DEBUG    RFM2Pi     17 NEW FRAME : OK 5 0 0 0 0 0 0 0 0 191 0 5 2 250 1 209 1 19 2 0 0 1 0 0 0 (-0)
2018-03-21 14:18:51,047 DEBUG    RFM2Pi     17 Timestamp : 1521641931.04
2018-03-21 14:18:51,048 DEBUG    RFM2Pi     17 From Node : 5
2018-03-21 14:18:51,048 DEBUG    RFM2Pi     17    Values : [0, 0, 0, 0, 19.1, 51.7, 50.6, 46.5, 53.1, 0, 1]
2018-03-21 14:18:51,049 DEBUG    RFM2Pi     17 Sent to channel(start)' : ToEmonCMS
2018-03-21 14:18:51,050 DEBUG    RFM2Pi     17 Sent to channel(end)' : ToEmonCMS
2018-03-21 14:18:51,239 DEBUG    MQTT       Publishing: emon/emonpi/power1 0
2018-03-21 14:18:51,241 DEBUG    MQTT       Publishing: emon/emonpi/power2 0
2018-03-21 14:18:51,243 DEBUG    MQTT       Publishing: emon/emonpi/power1pluspower2 0
2018-03-21 14:18:51,245 DEBUG    MQTT       Publishing: emon/emonpi/vrms 0
2018-03-21 14:18:51,247 DEBUG    MQTT       Publishing: emon/emonpi/t1 19.1
2018-03-21 14:18:51,248 DEBUG    MQTT       Publishing: emon/emonpi/t2 51.7
2018-03-21 14:18:51,250 DEBUG    MQTT       Publishing: emon/emonpi/t3 50.6
2018-03-21 14:18:51,253 DEBUG    MQTT       Publishing: emon/emonpi/t4 46.5
2018-03-21 14:18:51,255 DEBUG    MQTT       Publishing: emon/emonpi/t5 53.1
2018-03-21 14:18:51,257 DEBUG    MQTT       Publishing: emon/emonpi/t6 0
2018-03-21 14:18:51,259 DEBUG    MQTT       Publishing: emon/emonpi/pulsecount 1
2018-03-21 14:18:51,261 INFO     MQTT       Publishing: emonhub/rx/5/values 0,0,0,0,19.1,51.7,50.6,46.5,53.1,0,1
2018-03-21 14:18:51,358 DEBUG    RFM2Pi     Discarding RX frame 'unreliable content'? 0 172 206 44 59 32 1 51 1 32 64 133 1 80 33 32 67 192 3 51 62 (-95)
2018-03-21 14:18:53,117 DEBUG    RFM2Pi     Discarding RX frame 'unreliable content'? 4 33 80 98 129 2 5 64 10 58 136 1 3 80 194 33 0 16 32 72 170 (-98)
2018-03-21 14:18:54,027 DEBUG    RFM2Pi     Discarding RX frame 'unreliable content'? 0 35 81 98 129 2 5 64 10 58 136 1 3 80 194 33 0 16 32 72 170 (-92)
2018-03-21 14:18:54,755 DEBUG    RFM2Pi     Discarding RX frame 'unreliable content'? 8 224 21 98 129 2 5 64 10 58 136 1 3 80 194 33 0 16 32 72 170 (-96)
2018-03-21 14:18:55,991 DEBUG    RFM2Pi     18 NEW FRAME : OK 5 0 0 0 0 0 0 0 0 191 0 5 2 250 1 209 1 19 2 0 0 1 0 0 0 (-0)
2018-03-21 14:18:55,995 DEBUG    RFM2Pi     18 Timestamp : 1521641935.99
2018-03-21 14:18:55,996 DEBUG    RFM2Pi     18 From Node : 5
2018-03-21 14:18:55,997 DEBUG    RFM2Pi     18    Values : [0, 0, 0, 0, 19.1, 51.7, 50.6, 46.5, 53.1, 0, 1]
2018-03-21 14:18:55,998 DEBUG    RFM2Pi     18 Sent to channel(start)' : ToEmonCMS
2018-03-21 14:18:55,999 DEBUG    RFM2Pi     18 Sent to channel(end)' : ToEmonCMS
2018-03-21 14:18:56,218 DEBUG    MQTT       Publishing: emon/emonpi/power1 0
2018-03-21 14:18:56,221 DEBUG    MQTT       Publishing: emon/emonpi/power2 0
2018-03-21 14:18:56,222 DEBUG    MQTT       Publishing: emon/emonpi/power1pluspower2 0
2018-03-21 14:18:56,224 DEBUG    MQTT       Publishing: emon/emonpi/vrms 0
2018-03-21 14:18:56,226 DEBUG    MQTT       Publishing: emon/emonpi/t1 19.1
2018-03-21 14:18:56,228 DEBUG    MQTT       Publishing: emon/emonpi/t2 51.7
2018-03-21 14:18:56,229 DEBUG    MQTT       Publishing: emon/emonpi/t3 50.6
2018-03-21 14:18:56,233 DEBUG    MQTT       Publishing: emon/emonpi/t4 46.5
2018-03-21 14:18:56,235 DEBUG    MQTT       Publishing: emon/emonpi/t5 53.1
2018-03-21 14:18:56,237 DEBUG    MQTT       Publishing: emon/emonpi/t6 0
2018-03-21 14:18:56,239 DEBUG    MQTT       Publishing: emon/emonpi/pulsecount 1
2018-03-21 14:18:56,241 INFO     MQTT       Publishing: emonhub/rx/5/values 0,0,0,0,19.1,51.7,50.6,46.5,53.1,0,1
2018-03-21 14:19:00,995 DEBUG    RFM2Pi     19 NEW FRAME : OK 5 0 0 0 0 0 0 0 0 191 0 5 2 250 1 209 1 19 2 0 0 1 0 0 0 (-0)
2018-03-21 14:19:00,998 DEBUG    RFM2Pi     19 Timestamp : 1521641940.99
2018-03-21 14:19:00,999 DEBUG    RFM2Pi     19 From Node : 5
2018-03-21 14:19:01,000 DEBUG    RFM2Pi     19    Values : [0, 0, 0, 0, 19.1, 51.7, 50.6, 46.5, 53.1, 0, 1]
2018-03-21 14:19:01,000 DEBUG    RFM2Pi     19 Sent to channel(start)' : ToEmonCMS
2018-03-21 14:19:01,001 DEBUG    RFM2Pi     19 Sent to channel(end)' : ToEmonCMS
2018-03-21 14:19:01,289 DEBUG    MQTT       Publishing: emon/emonpi/power1 0
2018-03-21 14:19:01,292 DEBUG    MQTT       Publishing: emon/emonpi/power2 0
2018-03-21 14:19:01,294 DEBUG    MQTT       Publishing: emon/emonpi/power1pluspower2 0
2018-03-21 14:19:01,297 DEBUG    MQTT       Publishing: emon/emonpi/vrms 0
2018-03-21 14:19:01,299 DEBUG    MQTT       Publishing: emon/emonpi/t1 19.1
2018-03-21 14:19:01,302 DEBUG    MQTT       Publishing: emon/emonpi/t2 51.7
2018-03-21 14:19:01,306 DEBUG    MQTT       Publishing: emon/emonpi/t3 50.6
2018-03-21 14:19:01,308 DEBUG    MQTT       Publishing: emon/emonpi/t4 46.5
2018-03-21 14:19:01,312 DEBUG    MQTT       Publishing: emon/emonpi/t5 53.1
2018-03-21 14:19:01,315 DEBUG    MQTT       Publishing: emon/emonpi/t6 0
2018-03-21 14:19:01,318 DEBUG    MQTT       Publishing: emon/emonpi/pulsecount 1
2018-03-21 14:19:01,322 INFO     MQTT       Publishing: emonhub/rx/5/values 0,0,0,0,19.1,51.7,50.6,46.5,53.1,0,1
2018-03-21 14:19:02,033 DEBUG    RFM2Pi     Discarding RX frame 'unreliable content'? 7 73 0 255 184 16 1 65 0 1 0 50 4 136 180 42 47 0 52 72 170 (-96)
2018-03-21 14:19:02,592 DEBUG    emoncmsorg Buffer size: 6
2018-03-21 14:19:02,961 DEBUG    RFM2Pi     Discarding RX frame 'unreliable content'? 0 192 16 128 161 49 176 32 182 140 128 24 97 18 128 128 164 81 4 72 170 (-97)
2018-03-21 14:19:06,009 DEBUG    RFM2Pi     20 NEW FRAME : OK 5 0 0 0 0 0 0 0 0 191 0 5 2 250 1 209 1 19 2 0 0 1 0 0 0 (-0)
2018-03-21 14:19:06,012 DEBUG    RFM2Pi     20 Timestamp : 1521641946.01
2018-03-21 14:19:06,013 DEBUG    RFM2Pi     20 From Node : 5
2018-03-21 14:19:06,013 DEBUG    RFM2Pi     20    Values : [0, 0, 0, 0, 19.1, 51.7, 50.6, 46.5, 53.1, 0, 1]
2018-03-21 14:19:06,014 DEBUG    RFM2Pi     20 Sent to channel(start)' : ToEmonCMS
2018-03-21 14:19:06,014 DEBUG    RFM2Pi     20 Sent to channel(end)' : ToEmonCMS
2018-03-21 14:19:06,206 DEBUG    MQTT       Publishing: emon/emonpi/power1 0
2018-03-21 14:19:06,208 DEBUG    MQTT       Publishing: emon/emonpi/power2 0
2018-03-21 14:19:06,209 DEBUG    MQTT       Publishing: emon/emonpi/power1pluspower2 0
2018-03-21 14:19:06,211 DEBUG    MQTT       Publishing: emon/emonpi/vrms 0
2018-03-21 14:19:06,213 DEBUG    MQTT       Publishing: emon/emonpi/t1 19.1
2018-03-21 14:19:06,214 DEBUG    MQTT       Publishing: emon/emonpi/t2 51.7
2018-03-21 14:19:06,216 DEBUG    MQTT       Publishing: emon/emonpi/t3 50.6
2018-03-21 14:19:06,218 DEBUG    MQTT       Publishing: emon/emonpi/t4 46.5
2018-03-21 14:19:06,219 DEBUG    MQTT       Publishing: emon/emonpi/t5 53.1
2018-03-21 14:19:06,221 DEBUG    MQTT       Publishing: emon/emonpi/t6 0
2018-03-21 14:19:06,223 DEBUG    MQTT       Publishing: emon/emonpi/pulsecount 1
2018-03-21 14:19:06,224 INFO     MQTT       Publishing: emonhub/rx/5/values 0,0,0,0,19.1,51.7,50.6,46.5,53.1,0,1
2018-03-21 14:19:07,127 DEBUG    RFM2Pi     Discarding RX frame 'unreliable content'? 0 108 149 64 160 50 0 160 128 33 64 34 40 32 64 32 32 0 64 0 1 (-98)
2018-03-21 14:19:08,842 DEBUG    RFM2Pi     Discarding RX frame 'unreliable content'? 16 0 50 4 164 146 32 33 131 20 34 97 104 6 216 234 10 2 192 32 36 (-95)
2018-03-21 14:19:10,964 DEBUG    RFM2Pi     21 NEW FRAME : OK 5 0 0 0 0 0 0 0 0 191 0 5 2 250 1 209 1 19 2 0 0 1 0 0 0 (-0)
2018-03-21 14:19:10,967 DEBUG    RFM2Pi     21 Timestamp : 1521641950.96
2018-03-21 14:19:10,968 DEBUG    RFM2Pi     21 From Node : 5
2018-03-21 14:19:10,969 DEBUG    RFM2Pi     21    Values : [0, 0, 0, 0, 19.1, 51.7, 50.6, 46.5, 53.1, 0, 1]
2018-03-21 14:19:10,969 DEBUG    RFM2Pi     21 Sent to channel(start)' : ToEmonCMS
2018-03-21 14:19:10,970 DEBUG    RFM2Pi     21 Sent to channel(end)' : ToEmonCMS
2018-03-21 14:19:11,058 DEBUG    MQTT       Publishing: emon/emonpi/power1 0
2018-03-21 14:19:11,062 DEBUG    MQTT       Publishing: emon/emonpi/power2 0
2018-03-21 14:19:11,064 DEBUG    MQTT       Publishing: emon/emonpi/power1pluspower2 0
2018-03-21 14:19:11,067 DEBUG    MQTT       Publishing: emon/emonpi/vrms 0
2018-03-21 14:19:11,070 DEBUG    MQTT       Publishing: emon/emonpi/t1 19.1
2018-03-21 14:19:11,073 DEBUG    MQTT       Publishing: emon/emonpi/t2 51.7
2018-03-21 14:19:11,076 DEBUG    MQTT       Publishing: emon/emonpi/t3 50.6
2018-03-21 14:19:11,079 DEBUG    MQTT       Publishing: emon/emonpi/t4 46.5
2018-03-21 14:19:11,082 DEBUG    MQTT       Publishing: emon/emonpi/t5 53.1
2018-03-21 14:19:11,085 DEBUG    MQTT       Publishing: emon/emonpi/t6 0
2018-03-21 14:19:11,087 DEBUG    MQTT       Publishing: emon/emonpi/pulsecount 1
2018-03-21 14:19:11,090 INFO     MQTT       Publishing: emonhub/rx/5/values 0,0,0,0,19.1,51.7,50.6,46.5,53.1,0,1
2018-03-21 14:19:12,887 DEBUG    RFM2Pi     Discarding RX frame 'unreliable content'? 0 0 48 4 164 146 32 33 131 20 34 97 104 6 216 234 10 2 192 32 36 (-94)
2018-03-21 14:19:13,297 DEBUG    RFM2Pi     Discarding RX frame 'unreliable content'? 0 210 73 16 0 36 32 129 136 16 120 10 0 64 158 24 2 5 55 129 16 (-96)
2018-03-21 14:19:14,531 DEBUG    RFM2Pi     Discarding RX frame 'unreliable content'? 17 50 65 38 88 128 147 31 136 177 136 192 225 2 104 128 132 128 209 19 161 (-96)
2018-03-21 14:19:15,951 DEBUG    RFM2Pi     22 NEW FRAME : OK 5 0 0 0 0 0 0 0 0 191 0 5 2 250 1 209 1 19 2 0 0 1 0 0 0 (-0)
2018-03-21 14:19:15,954 DEBUG    RFM2Pi     22 Timestamp : 1521641955.95
2018-03-21 14:19:15,955 DEBUG    RFM2Pi     22 From Node : 5
2018-03-21 14:19:15,956 DEBUG    RFM2Pi     22    Values : [0, 0, 0, 0, 19.1, 51.7, 50.6, 46.5, 53.1, 0, 1]
2018-03-21 14:19:15,956 DEBUG    RFM2Pi     22 Sent to channel(start)' : ToEmonCMS
2018-03-21 14:19:15,957 DEBUG    RFM2Pi     22 Sent to channel(end)' : ToEmonCMS
2018-03-21 14:19:16,063 DEBUG    MQTT       Publishing: emon/emonpi/power1 0
2018-03-21 14:19:16,066 DEBUG    MQTT       Publishing: emon/emonpi/power2 0
2018-03-21 14:19:16,068 DEBUG    MQTT       Publishing: emon/emonpi/power1pluspower2 0
2018-03-21 14:19:16,071 DEBUG    RFM2Pi     Discarding RX frame 'unreliable content'? 0 2 8 40 11 41 4 16 160 4 0 34 0 170 0 44 144 0 129 128 145 (-95)
2018-03-21 14:19:16,072 DEBUG    MQTT       Publishing: emon/emonpi/vrms 0
2018-03-21 14:19:16,074 DEBUG    MQTT       Publishing: emon/emonpi/t1 19.1
2018-03-21 14:19:16,077 DEBUG    MQTT       Publishing: emon/emonpi/t2 51.7
2018-03-21 14:19:16,079 DEBUG    MQTT       Publishing: emon/emonpi/t3 50.6
2018-03-21 14:19:16,081 DEBUG    MQTT       Publishing: emon/emonpi/t4 46.5
2018-03-21 14:19:16,084 DEBUG    MQTT       Publishing: emon/emonpi/t5 53.1
2018-03-21 14:19:16,086 DEBUG    MQTT       Publishing: emon/emonpi/t6 0
2018-03-21 14:19:16,088 DEBUG    MQTT       Publishing: emon/emonpi/pulsecount 1
2018-03-21 14:19:16,091 INFO     MQTT       Publishing: emonhub/rx/5/values 0,0,0,0,19.1,51.7,50.6,46.5,53.1,0,1
2018-03-21 14:19:16,379 DEBUG    RFM2Pi     Discarding RX frame 'unreliable content'? 4 66 22 64 42 3 16 182 106 72 32 73 32 60 64 112 113 252 164 176 50 (-95)
2018-03-21 14:19:17,192 DEBUG    RFM2Pi     Discarding RX frame 'unreliable content'? 5 13 0 64 42 3 16 182 106 72 32 73 32 60 64 112 113 252 164 176 50 (-96)
2018-03-21 14:19:21,031 DEBUG    RFM2Pi     23 NEW FRAME : OK 5 0 0 0 0 0 0 0 0 191 0 5 2 250 1 209 1 19 2 0 0 1 0 0 0 (-0)
2018-03-21 14:19:21,036 DEBUG    RFM2Pi     23 Timestamp : 1521641961.03
2018-03-21 14:19:21,037 DEBUG    RFM2Pi     23 From Node : 5
2018-03-21 14:19:21,037 DEBUG    RFM2Pi     23    Values : [0, 0, 0, 0, 19.1, 51.7, 50.6, 46.5, 53.1, 0, 1]
2018-03-21 14:19:21,038 DEBUG    RFM2Pi     23 Sent to channel(start)' : ToEmonCMS
2018-03-21 14:19:21,039 DEBUG    RFM2Pi     23 Sent to channel(end)' : ToEmonCMS
2018-03-21 14:19:21,266 DEBUG    MQTT       Publishing: emon/emonpi/power1 0
2018-03-21 14:19:21,268 DEBUG    MQTT       Publishing: emon/emonpi/power2 0
2018-03-21 14:19:21,270 DEBUG    MQTT       Publishing: emon/emonpi/power1pluspower2 0
2018-03-21 14:19:21,272 DEBUG    MQTT       Publishing: emon/emonpi/vrms 0
2018-03-21 14:19:21,274 DEBUG    MQTT       Publishing: emon/emonpi/t1 19.1
2018-03-21 14:19:21,276 DEBUG    MQTT       Publishing: emon/emonpi/t2 51.7
2018-03-21 14:19:21,278 DEBUG    MQTT       Publishing: emon/emonpi/t3 50.6
2018-03-21 14:19:21,280 DEBUG    MQTT       Publishing: emon/emonpi/t4 46.5
2018-03-21 14:19:21,281 DEBUG    MQTT       Publishing: emon/emonpi/t5 53.1
2018-03-21 14:19:21,283 DEBUG    MQTT       Publishing: emon/emonpi/t6 0
2018-03-21 14:19:21,285 DEBUG    MQTT       Publishing: emon/emonpi/pulsecount 1
2018-03-21 14:19:21,287 INFO     MQTT       Publishing: emonhub/rx/5/values 0,0,0,0,19.1,51.7,50.6,46.5,53.1,0,1
2018-03-21 14:19:25,999 DEBUG    RFM2Pi     24 NEW FRAME : OK 5 0 0 0 0 0 0 0 0 191 0 5 2 250 1 209 1 19 2 0 0 1 0 0 0 (-0)
2018-03-21 14:19:26,003 DEBUG    RFM2Pi     24 Timestamp : 1521641966.0
2018-03-21 14:19:26,004 DEBUG    RFM2Pi     24 From Node : 5
2018-03-21 14:19:26,005 DEBUG    RFM2Pi     24    Values : [0, 0, 0, 0, 19.1, 51.7, 50.6, 46.5, 53.1, 0, 1]
2018-03-21 14:19:26,006 DEBUG    RFM2Pi     24 Sent to channel(start)' : ToEmonCMS
2018-03-21 14:19:26,006 DEBUG    RFM2Pi     24 Sent to channel(end)' : ToEmonCMS
2018-03-21 14:19:26,094 DEBUG    MQTT       Publishing: emon/emonpi/power1 0
2018-03-21 14:19:26,097 DEBUG    MQTT       Publishing: emon/emonpi/power2 0
2018-03-21 14:19:26,099 DEBUG    MQTT       Publishing: emon/emonpi/power1pluspower2 0
2018-03-21 14:19:26,101 DEBUG    MQTT       Publishing: emon/emonpi/vrms 0
2018-03-21 14:19:26,103 DEBUG    MQTT       Publishing: emon/emonpi/t1 19.1
2018-03-21 14:19:26,105 DEBUG    MQTT       Publishing: emon/emonpi/t2 51.7
2018-03-21 14:19:26,106 DEBUG    MQTT       Publishing: emon/emonpi/t3 50.6
2018-03-21 14:19:26,108 DEBUG    MQTT       Publishing: emon/emonpi/t4 46.5
2018-03-21 14:19:26,111 DEBUG    MQTT       Publishing: emon/emonpi/t5 53.1
2018-03-21 14:19:26,112 DEBUG    MQTT       Publishing: emon/emonpi/t6 0
2018-03-21 14:19:26,114 DEBUG    MQTT       Publishing: emon/emonpi/pulsecount 1
2018-03-21 14:19:26,116 INFO     MQTT       Publishing: emonhub/rx/5/values 0,0,0,0,19.1,51.7,50.6,46.5,53.1,0,1
2018-03-21 14:19:26,216 DEBUG    RFM2Pi     Discarding RX frame 'unreliable content'? 0 168 0 65 64 98 6 160 17 96 33 32 24 0 1 104 0 32 224 0 128 (-98)
2018-03-21 14:19:26,324 DEBUG    RFM2Pi     Discarding RX frame 'unreliable content'? 0 0 1 4 2 10 4 49 69 54 160 0 130 136 4 33 67 50 58 34 242 (-99)
2018-03-21 14:19:27,539 DEBUG    RFM2Pi     Discarding RX frame 'unreliable content'? 6 0 194 168 0 128 224 0 32 0 128 35 16 16 32 16 192 66 9 144 10 (-97)
2018-03-21 14:19:30,994 DEBUG    RFM2Pi     25 NEW FRAME : OK 5 0 0 0 0 0 0 0 0 191 0 6 2 250 1 209 1 19 2 0 0 1 0 0 0 (-0)
2018-03-21 14:19:30,999 DEBUG    RFM2Pi     25 Timestamp : 1521641970.99
2018-03-21 14:19:31,000 DEBUG    RFM2Pi     25 From Node : 5
2018-03-21 14:19:31,000 DEBUG    RFM2Pi     25    Values : [0, 0, 0, 0, 19.1, 51.800000000000004, 50.6, 46.5, 53.1, 0, 1]
2018-03-21 14:19:31,001 DEBUG    RFM2Pi     25 Sent to channel(start)' : ToEmonCMS
2018-03-21 14:19:31,002 DEBUG    RFM2Pi     25 Sent to channel(end)' : ToEmonCMS
2018-03-21 14:19:31,088 DEBUG    MQTT       Publishing: emon/emonpi/power1 0
2018-03-21 14:19:31,091 DEBUG    MQTT       Publishing: emon/emonpi/power2 0
2018-03-21 14:19:31,094 DEBUG    MQTT       Publishing: emon/emonpi/power1pluspower2 0
2018-03-21 14:19:31,097 DEBUG    MQTT       Publishing: emon/emonpi/vrms 0
2018-03-21 14:19:31,100 DEBUG    MQTT       Publishing: emon/emonpi/t1 19.1
2018-03-21 14:19:31,104 DEBUG    MQTT       Publishing: emon/emonpi/t2 51.8
2018-03-21 14:19:31,108 DEBUG    MQTT       Publishing: emon/emonpi/t3 50.6
2018-03-21 14:19:31,112 DEBUG    MQTT       Publishing: emon/emonpi/t4 46.5
2018-03-21 14:19:31,115 DEBUG    MQTT       Publishing: emon/emonpi/t5 53.1
2018-03-21 14:19:31,121 DEBUG    MQTT       Publishing: emon/emonpi/t6 0
2018-03-21 14:19:31,124 DEBUG    MQTT       Publishing: emon/emonpi/pulsecount 1
2018-03-21 14:19:31,127 INFO     MQTT       Publishing: emonhub/rx/5/values 0,0,0,0,19.1,51.8,50.6,46.5,53.1,0,1
2018-03-21 14:19:31,131 DEBUG    RFM2Pi     Discarding RX frame 'unreliable content'? 14 37 96 33 34 83 53 128 0 0 128 35 16 16 32 16 192 66 9 144 10 (-99)
2018-03-21 14:19:31,844 DEBUG    RFM2Pi     Discarding RX frame 'unreliable content'? 0 12 32 144 25 4 4 184 130 0 32 8 9 44 32 66 164 180 64 49 59 (-101)
2018-03-21 14:19:32,630 DEBUG    emoncmsorg Buffer size: 6
2018-03-21 14:19:33,185 DEBUG    RFM2Pi     Discarding RX frame 'unreliable content'? 0 0 64 56 67 4 0 0 48 224 32 161 187 166 0 32 0 32 40 82 32 (-96)
2018-03-21 15:05:07,465 DEBUG    RFM2Pi     Discarding RX frame 'unreliable content'? 2 72 16 1 32 0 32 1 80 25 224 16 96 0 0 8 25 1 32 19 32 (-98)
2018-03-21 15:05:08,407 DEBUG    RFM2Pi     26 NEW FRAME : OK 5 0 0 0 0 0 0 0 0 191 0 6 2 250 1 209 1 19 2 0 0 1 0 0 0 (-0)
2018-03-21 15:05:08,411 DEBUG    RFM2Pi     26 Timestamp : 1521644708.41

pb66 · 21 March 2018 17:57

I wasn’t looking to get an exact time, i was more trying to confirm what you did and that it was that action that caused the data to start flowing again at 15:05.

The reason is, the emonPi has not rebooted. All logs are lost on a reboot so you would not be able to show us the before and after in one log file. Also if you rebooted, the emonhub.log would show enonhub going through all it’s configuration whilst starting/setting up.

I therefore wonder if you are only “resetting” the emonpi board (The Pi’s add-on board) however even that doesn’t fully make sense as the emonpi board would print a load of setup stuff to serial, that emonhub wouldn’t like and there would be messages to that effect. The log you post suggests there is no response to you doing what ever it is that you are doing when “rebooting”.

How long do you hold the button down? and what does the screen say when you do?

bungo63 · 21 March 2018 17:58

Paul, as luck would have it, another hang occurred as a I was replying to you earlier. So i took the opportunity to timestamp when I initiated the reboot. This was a t 17:47. Here is the log which appears to indicate that the system went to sleep at 17:18. See bottom of file

2018-03-21 17:17:25,732 INFO     MainThread EmonHub emonHub emon-pi variant v2.1.1
2018-03-21 17:17:25,734 INFO     MainThread Opening hub...
2018-03-21 17:17:25,736 INFO     MainThread Logging level set to DEBUG
2018-03-21 17:17:25,738 INFO     MainThread Creating EmonHubJeeInterfacer 'RFM2Pi' 
2018-03-21 17:17:25,744 DEBUG    MainThread Opening serial port: /dev/ttyAMA0 @ 38400 bits/s
2018-03-21 17:17:27,759 INFO     MainThread RFM2Pi device firmware version: OK
2018-03-21 17:17:27,760 INFO     MainThread RFM2Pi device current settings:  5 0 0 0 0 0 0 0 0 70 2 48 2 208 2 86 1 101 1 0 0 1 0 0 0 (-0)
2018-03-21 17:17:27,761 INFO     MainThread Setting RFM2Pi frequency: 433 (4b)
2018-03-21 17:17:28,763 INFO     MainThread Setting RFM2Pi group: 210 (210g)
2018-03-21 17:17:29,766 INFO     MainThread Setting RFM2Pi quiet: 0 (0q)
2018-03-21 17:17:30,769 INFO     MainThread Setting RFM2Pi baseid: 5 (5i)
2018-03-21 17:17:31,772 INFO     MainThread Setting RFM2Pi calibration: 230V (1p)
2018-03-21 17:17:32,775 DEBUG    MainThread Setting RFM2Pi subchannels: ['ToRFM12']
2018-03-21 17:17:32,777 DEBUG    MainThread Setting RFM2Pi pubchannels: ['ToEmonCMS']
2018-03-21 17:17:32,779 INFO     MainThread Creating EmonHubMqttInterfacer 'MQTT' 
2018-03-21 17:17:32,786 DEBUG    MainThread Setting MQTT subchannels: ['ToEmonCMS']
2018-03-21 17:17:32,787 DEBUG    MainThread Setting MQTT pubchannels: ['ToRFM12']
2018-03-21 17:17:32,789 INFO     MainThread Setting MQTT nodevar_format_enable: 1
2018-03-21 17:17:32,790 INFO     MainThread Setting MQTT node_format_enable: 1
2018-03-21 17:17:32,791 INFO     MainThread Setting MQTT nodevar_format_basetopic: emon/
2018-03-21 17:17:32,797 DEBUG    RFM2Pi     device settings updated: E i5 g210 @ 433 MHz q1 USA 0
2018-03-21 17:17:32,799 INFO     MainThread Creating EmonHubEmoncmsHTTPInterfacer 'emoncmsorg' 
2018-03-21 17:17:32,801 DEBUG    MainThread Setting emoncmsorg subchannels: ['ToEmonCMS']
2018-03-21 17:17:32,802 DEBUG    MainThread Setting emoncmsorg pubchannels: ['ToRFM12']
2018-03-21 17:17:32,803 INFO     MainThread Setting emoncmsorg url: https://emoncms.org
2018-03-21 17:17:32,805 INFO     MainThread Setting emoncmsorg senddata: 1
2018-03-21 17:17:32,806 WARNING  MainThread Setting emoncmsorg apikey: obscured
2018-03-21 17:17:32,807 INFO     MainThread Setting emoncmsorg sendstatus: 1
2018-03-21 17:17:32,905 DEBUG    RFM2Pi     device settings updated: E i5 g210 @ 433 MHz q1 USA 0
2018-03-21 17:17:33,012 DEBUG    RFM2Pi     device settings updated: E i5 g210 @ 433 MHz q0 USA 0
2018-03-21 17:17:33,128 DEBUG    RFM2Pi     Discarding RX frame 'unreliable content'? 16 32 192 0 34 131 3 24 16 128 49 32 62 68 32 40 231 216 146 228 1 (-97)
2018-03-21 17:17:33,234 DEBUG    RFM2Pi     device settings updated: E i5 g210 @ 433 MHz q0 USA 0
2018-03-21 17:17:33,347 DEBUG    RFM2Pi     1 NEW FRAME : OK 5 0 0 0 0 0 0 0 0 70 2 47 2 206 2 86 1 100 1 0 0 1 0 0 0 (-0)
2018-03-21 17:17:33,354 DEBUG    RFM2Pi     1 Timestamp : 1521652653.35
2018-03-21 17:17:33,356 DEBUG    RFM2Pi     1 From Node : 5
2018-03-21 17:17:33,357 DEBUG    RFM2Pi     1    Values : [0, 0, 0, 0, 58.2, 55.900000000000006, 71.8, 34.2, 35.6, 0, 1]
2018-03-21 17:17:33,359 DEBUG    RFM2Pi     1 Sent to channel(start)' : ToEmonCMS
2018-03-21 17:17:33,361 DEBUG    RFM2Pi     1 Sent to channel(end)' : ToEmonCMS
2018-03-21 17:17:33,473 DEBUG    RFM2Pi     Discarding RX frame 'unreliable content'? 23 129 6 225 192 1 0 1 4 192 32 96 0 32 0 0 9 17 145 240 68 (-101)
2018-03-21 17:17:33,528 INFO     MQTT       Connecting to MQTT Server
2018-03-21 17:17:33,534 INFO     MQTT       Could not connect...
2018-03-21 17:17:33,579 DEBUG    RFM2Pi     device settings updated: E i5 g210 @ 433 MHz q0 USA 0
2018-03-21 17:17:33,621 DEBUG    emoncmsorg Buffer size: 1
2018-03-21 17:17:33,689 DEBUG    RFM2Pi     Discarding RX frame 'unreliable content'? 0 4 4 160 3 4 161 1 60 132 192 0 0 36 0 2 64 38 64 28 66 (-98)
2018-03-21 17:17:33,800 DEBUG    RFM2Pi     Discarding RX frame 'unreliable content'? 7 220 2 112 190 12 3 40 192 0 52 188 40 80 60 8 78 160 9 88 100 (-93)
2018-03-21 17:17:34,348 DEBUG    RFM2Pi     Discarding RX frame 'unreliable content'? 1 129 3 0 160 89 168 226 2 119 161 32 4 141 164 10 64 98 0 204 130 (-97)
2018-03-21 17:17:36,133 DEBUG    RFM2Pi     Discarding RX frame 'unreliable content'? 9 108 0 148 227 133 196 226 2 119 161 32 4 141 164 10 64 98 0 204 130 (-98)
2018-03-21 17:17:37,149 DEBUG    RFM2Pi     2 NEW FRAME : OK 5 0 0 0 0 0 0 0 0 70 2 47 2 204 2 86 1 100 1 0 0 1 0 0 0 (-0)
2018-03-21 17:17:37,153 DEBUG    RFM2Pi     2 Timestamp : 1521652657.15
2018-03-21 17:17:37,154 DEBUG    RFM2Pi     2 From Node : 5
2018-03-21 17:17:37,157 DEBUG    RFM2Pi     2    Values : [0, 0, 0, 0, 58.2, 55.900000000000006, 71.60000000000001, 34.2, 35.6, 0, 1]
2018-03-21 17:17:37,159 DEBUG    RFM2Pi     2 Sent to channel(start)' : ToEmonCMS
2018-03-21 17:17:37,160 DEBUG    RFM2Pi     2 Sent to channel(end)' : ToEmonCMS
2018-03-21 17:17:37,267 DEBUG    RFM2Pi     Discarding RX frame 'unreliable content'? 15 144 66 38 89 68 5 39 106 2 50 143 128 42 49 34 176 117 0 155 169 (-94)
2018-03-21 17:17:37,358 INFO     MQTT       Connecting to MQTT Server
2018-03-21 17:17:37,361 INFO     MQTT       Could not connect...
2018-03-21 17:17:39,861 DEBUG    RFM2Pi     Discarding RX frame 'unreliable content'? 0 66 34 38 32 159 189 50 171 227 194 76 232 160 120 183 152 65 38 0 200 (-97)
2018-03-21 17:17:42,178 DEBUG    RFM2Pi     3 NEW FRAME : OK 5 0 0 0 0 0 0 0 0 71 2 48 2 202 2 85 1 100 1 0 0 1 0 0 0 (-0)
2018-03-21 17:17:42,183 DEBUG    RFM2Pi     3 Timestamp : 1521652662.18
2018-03-21 17:17:42,184 DEBUG    RFM2Pi     3 From Node : 5
2018-03-21 17:17:42,185 DEBUG    RFM2Pi     3    Values : [0, 0, 0, 0, 58.300000000000004, 56, 71.4, 34.1, 35.6, 0, 1]
2018-03-21 17:17:42,187 DEBUG    RFM2Pi     3 Sent to channel(start)' : ToEmonCMS
2018-03-21 17:17:42,188 DEBUG    RFM2Pi     3 Sent to channel(end)' : ToEmonCMS
2018-03-21 17:17:42,300 DEBUG    RFM2Pi     Discarding RX frame 'unreliable content'? 2 1 168 70 40 169 248 39 195 17 137 73 148 21 129 0 25 2 120 27 208 (-96)
2018-03-21 17:17:42,408 DEBUG    RFM2Pi     Discarding RX frame 'unreliable content'? 9 33 80 152 64 32 138 65 100 51 212 62 136 5 128 81 36 35 16 35 224 (-94)
2018-03-21 17:17:42,411 INFO     MQTT       Connecting to MQTT Server
2018-03-21 17:17:42,413 INFO     MQTT       Could not connect...
2018-03-21 17:17:43,222 DEBUG    RFM2Pi     Discarding RX frame 'unreliable content'? 19 145 129 35 195 96 162 189 20 17 143 64 160 1 39 56 192 11 1 1 146 (-93)
2018-03-21 17:17:44,338 DEBUG    RFM2Pi     Discarding RX frame 'unreliable content'? 11 112 57 133 242 26 171 80 128 15 178 32 9 225 241 36 23 73 131 115 66 (-95)
2018-03-21 17:17:45,050 DEBUG    RFM2Pi     Discarding RX frame 'unreliable content'? 8 15 200 149 13 64 108 50 18 3 36 162 128 189 185 136 99 4 66 148 113 (-98)
2018-03-21 17:17:47,109 DEBUG    RFM2Pi     4 NEW FRAME : OK 5 0 0 0 0 0 0 0 0 71 2 47 2 200 2 85 1 100 1 0 0 1 0 0 0 (-0)
2018-03-21 17:17:47,113 DEBUG    RFM2Pi     4 Timestamp : 1521652667.11
2018-03-21 17:17:47,114 DEBUG    RFM2Pi     4 From Node : 5
2018-03-21 17:17:47,116 DEBUG    RFM2Pi     4    Values : [0, 0, 0, 0, 58.300000000000004, 55.900000000000006, 71.2, 34.1, 35.6, 0, 1]
2018-03-21 17:17:47,117 DEBUG    RFM2Pi     4 Sent to channel(start)' : ToEmonCMS
2018-03-21 17:17:47,118 DEBUG    RFM2Pi     4 Sent to channel(end)' : ToEmonCMS
2018-03-21 17:17:47,227 DEBUG    RFM2Pi     Discarding RX frame 'unreliable content'? 19 64 8 132 92 226 164 38 9 161 0 34 140 129 36 52 162 0 26 59 229 (-98)
2018-03-21 17:17:47,261 INFO     MQTT       Connecting to MQTT Server
2018-03-21 17:17:47,365 INFO     MQTT       connection status: Connection successful
2018-03-21 17:17:47,367 DEBUG    MQTT       CONACK => Return code: 0
2018-03-21 17:17:47,469 INFO     MQTT       on_subscribe
2018-03-21 17:17:47,676 DEBUG    RFM2Pi     Discarding RX frame 'unreliable content'? 10 224 142 9 64 24 208 24 104 25 122 16 192 0 0 96 13 232 58 3 160 (-96)
2018-03-21 17:17:49,004 DEBUG    RFM2Pi     Discarding RX frame 'unreliable content'? 16 46 60 40 18 176 73 176 2 8 6 37 109 36 29 33 32 40 32 23 1 (-97)
2018-03-21 17:17:52,151 DEBUG    RFM2Pi     5 NEW FRAME : OK 5 0 0 0 0 0 0 0 0 71 2 48 2 198 2 85 1 100 1 0 0 1 0 0 0 (-0)
2018-03-21 17:17:52,156 DEBUG    RFM2Pi     5 Timestamp : 1521652672.15
2018-03-21 17:17:52,157 DEBUG    RFM2Pi     5 From Node : 5
2018-03-21 17:17:52,159 DEBUG    RFM2Pi     5    Values : [0, 0, 0, 0, 58.300000000000004, 56, 71, 34.1, 35.6, 0, 1]
2018-03-21 17:17:52,160 DEBUG    RFM2Pi     5 Sent to channel(start)' : ToEmonCMS
2018-03-21 17:17:52,162 DEBUG    RFM2Pi     5 Sent to channel(end)' : ToEmonCMS
2018-03-21 17:17:52,316 DEBUG    MQTT       Publishing: emon/emonpi/power1 0
2018-03-21 17:17:52,319 DEBUG    MQTT       Publishing: emon/emonpi/power2 0
2018-03-21 17:17:52,322 DEBUG    MQTT       Publishing: emon/emonpi/power1pluspower2 0
2018-03-21 17:17:52,324 DEBUG    MQTT       Publishing: emon/emonpi/vrms 0
2018-03-21 17:17:52,327 DEBUG    MQTT       Publishing: emon/emonpi/t1 58.3
2018-03-21 17:17:52,330 DEBUG    MQTT       Publishing: emon/emonpi/t2 56
2018-03-21 17:17:52,332 DEBUG    MQTT       Publishing: emon/emonpi/t3 71
2018-03-21 17:17:52,335 DEBUG    MQTT       Publishing: emon/emonpi/t4 34.1
2018-03-21 17:17:52,337 DEBUG    MQTT       Publishing: emon/emonpi/t5 35.6
2018-03-21 17:17:52,340 DEBUG    MQTT       Publishing: emon/emonpi/t6 0
2018-03-21 17:17:52,342 DEBUG    MQTT       Publishing: emon/emonpi/pulsecount 1
2018-03-21 17:17:52,345 INFO     MQTT       Publishing: emonhub/rx/5/values 0,0,0,0,58.3,56,71,34.1,35.6,0,1
2018-03-21 17:17:53,180 DEBUG    RFM2Pi     Discarding RX frame 'unreliable content'? 3 4 96 9 39 0 153 33 157 114 21 0 32 0 36 16 161 37 56 34 182 (-95)
2018-03-21 17:17:53,993 DEBUG    RFM2Pi     Discarding RX frame 'unreliable content'? 11 0 128 0 32 1 25 120 18 10 130 53 0 160 0 42 116 160 16 48 129 (-98)
2018-03-21 17:17:55,207 DEBUG    RFM2Pi     Discarding RX frame 'unreliable content'? 22 49 16 12 64 1 25 120 18 10 130 53 0 160 0 42 116 160 16 48 129 (-102)
2018-03-21 17:17:57,123 DEBUG    RFM2Pi     6 NEW FRAME : OK 5 0 0 0 0 0 0 0 0 71 2 48 2 195 2 85 1 100 1 0 0 1 0 0 0 (-0)
2018-03-21 17:17:57,126 DEBUG    RFM2Pi     6 Timestamp : 1521652677.12
2018-03-21 17:17:57,127 DEBUG    RFM2Pi     6 From Node : 5
2018-03-21 17:17:57,129 DEBUG    RFM2Pi     6    Values : [0, 0, 0, 0, 58.300000000000004, 56, 70.7, 34.1, 35.6, 0, 1]
2018-03-21 17:17:57,130 DEBUG    RFM2Pi     6 Sent to channel(start)' : ToEmonCMS
2018-03-21 17:17:57,131 DEBUG    RFM2Pi     6 Sent to channel(end)' : ToEmonCMS
2018-03-21 17:17:57,408 DEBUG    MQTT       Publishing: emon/emonpi/power1 0
2018-03-21 17:17:57,411 DEBUG    MQTT       Publishing: emon/emonpi/power2 0
2018-03-21 17:17:57,413 DEBUG    MQTT       Publishing: emon/emonpi/power1pluspower2 0
2018-03-21 17:17:57,415 DEBUG    MQTT       Publishing: emon/emonpi/vrms 0
2018-03-21 17:17:57,418 DEBUG    MQTT       Publishing: emon/emonpi/t1 58.3
2018-03-21 17:17:57,420 DEBUG    MQTT       Publishing: emon/emonpi/t2 56
2018-03-21 17:17:57,422 DEBUG    MQTT       Publishing: emon/emonpi/t3 70.7
2018-03-21 17:17:57,424 DEBUG    MQTT       Publishing: emon/emonpi/t4 34.1
2018-03-21 17:17:57,426 DEBUG    MQTT       Publishing: emon/emonpi/t5 35.6
2018-03-21 17:17:57,428 DEBUG    MQTT       Publishing: emon/emonpi/t6 0
2018-03-21 17:17:57,430 DEBUG    MQTT       Publishing: emon/emonpi/pulsecount 1
2018-03-21 17:17:57,432 INFO     MQTT       Publishing: emonhub/rx/5/values 0,0,0,0,58.3,56,70.7,34.1,35.6,0,1
2018-03-21 17:17:57,654 DEBUG    RFM2Pi     Discarding RX frame 'unreliable content'? 18 217 132 99 185 135 0 192 27 192 69 160 23 43 32 250 201 112 122 146 1 (-93)
2018-03-21 17:17:58,064 DEBUG    RFM2Pi     Discarding RX frame 'unreliable content'? 21 18 192 8 39 32 121 96 151 69 48 160 23 43 32 250 201 112 122 146 1 (-95)
2018-03-21 17:17:58,172 DEBUG    RFM2Pi     Discarding RX frame 'unreliable content'? 4 100 185 108 153 38 2 60 82 68 58 176 108 96 139 35 38 226 26 126 17 (-93)
2018-03-21 17:17:58,482 DEBUG    RFM2Pi     Discarding RX frame 'unreliable content'? 5 168 101 60 42 97 69 168 29 224 8 172 34 120 0 144 1 31 26 126 17 (-96)
2018-03-21 17:18:02,170 DEBUG    RFM2Pi     7 NEW FRAME : OK 5 0 0 0 0 0 0 0 0 71 2 48 2 193 2 85 1 99 1 0 0 1 0 0 0 (-0)
2018-03-21 17:18:02,173 DEBUG    RFM2Pi     7 Timestamp : 1521652682.17
2018-03-21 17:18:02,175 DEBUG    RFM2Pi     7 From Node : 5
2018-03-21 17:18:02,176 DEBUG    RFM2Pi     7    Values : [0, 0, 0, 0, 58.300000000000004, 56, 70.5, 34.1, 35.5, 0, 1]
2018-03-21 17:18:02,176 DEBUG    RFM2Pi     7 Sent to channel(start)' : ToEmonCMS
2018-03-21 17:18:02,177 DEBUG    RFM2Pi     7 Sent to channel(end)' : ToEmonCMS
2018-03-21 17:18:02,302 DEBUG    MQTT       Publishing: emon/emonpi/power1 0
2018-03-21 17:18:02,306 DEBUG    MQTT       Publishing: emon/emonpi/power2 0
2018-03-21 17:18:02,309 DEBUG    MQTT       Publishing: emon/emonpi/power1pluspower2 0
2018-03-21 17:18:02,312 DEBUG    MQTT       Publishing: emon/emonpi/vrms 0
2018-03-21 17:18:02,316 DEBUG    MQTT       Publishing: emon/emonpi/t1 58.3
2018-03-21 17:18:02,319 DEBUG    MQTT       Publishing: emon/emonpi/t2 56
2018-03-21 17:18:02,322 DEBUG    RFM2Pi     Discarding RX frame 'unreliable content'? 0 171 0 16 2 67 32 0 24 46 16 0 39 32 8 38 184 48 131 225 112 (-99)
2018-03-21 17:18:02,324 DEBUG    MQTT       Publishing: emon/emonpi/t3 70.5
2018-03-21 17:18:02,328 DEBUG    MQTT       Publishing: emon/emonpi/t4 34.1
2018-03-21 17:18:02,330 DEBUG    MQTT       Publishing: emon/emonpi/t5 35.5
2018-03-21 17:18:02,333 DEBUG    MQTT       Publishing: emon/emonpi/t6 0
2018-03-21 17:18:02,336 DEBUG    MQTT       Publishing: emon/emonpi/pulsecount 1
2018-03-21 17:18:02,341 INFO     MQTT       Publishing: emonhub/rx/5/values 0,0,0,0,58.3,56,70.5,34.1,35.5,0,1
2018-03-21 17:18:03,339 DEBUG    RFM2Pi     Discarding RX frame 'unreliable content'? 18 146 192 9 37 192 1 94 60 33 223 163 65 36 64 37 2 174 132 46 40 (-101)
2018-03-21 17:18:03,636 DEBUG    emoncmsorg Buffer size: 6
2018-03-21 17:18:04,049 DEBUG    RFM2Pi     Discarding RX frame 'unreliable content'? 19 118 188 102 0 1 145 195 132 7 74 58 32 100 33 0 55 117 32 10 17 (-98)
2018-03-21 17:18:05,921 DEBUG    RFM2Pi     Discarding RX frame 'unreliable content'? 1 163 168 212 18 41 141 6 0 0 187 128 5 33 2 224 80 40 2 43 55 (-98)
2018-03-21 17:18:07,156 DEBUG    RFM2Pi     8 NEW FRAME : OK 5 0 0 0 0 0 0 0 0 71 2 48 2 191 2 84 1 99 1 0 0 1 0 0 0 (-0)
2018-03-21 17:18:07,159 DEBUG    RFM2Pi     8 Timestamp : 1521652687.16
2018-03-21 17:18:07,160 DEBUG    RFM2Pi     8 From Node : 5
2018-03-21 17:18:07,161 DEBUG    RFM2Pi     8    Values : [0, 0, 0, 0, 58.300000000000004, 56, 70.3, 34, 35.5, 0, 1]
2018-03-21 17:18:07,162 DEBUG    RFM2Pi     8 Sent to channel(start)' : ToEmonCMS
2018-03-21 17:18:07,162 DEBUG    RFM2Pi     8 Sent to channel(end)' : ToEmonCMS
2018-03-21 17:18:07,251 DEBUG    MQTT       Publishing: emon/emonpi/power1 0
2018-03-21 17:18:07,253 DEBUG    MQTT       Publishing: emon/emonpi/power2 0
2018-03-21 17:18:07,254 DEBUG    MQTT       Publishing: emon/emonpi/power1pluspower2 0
2018-03-21 17:18:07,256 DEBUG    MQTT       Publishing: emon/emonpi/vrms 0
2018-03-21 17:18:07,258 DEBUG    MQTT       Publishing: emon/emonpi/t1 58.3
2018-03-21 17:18:07,260 DEBUG    MQTT       Publishing: emon/emonpi/t2 56
2018-03-21 17:18:07,262 DEBUG    MQTT       Publishing: emon/emonpi/t3 70.3
2018-03-21 17:18:07,265 DEBUG    MQTT       Publishing: emon/emonpi/t4 34
2018-03-21 17:18:07,267 DEBUG    MQTT       Publishing: emon/emonpi/t5 35.5
2018-03-21 17:18:07,269 DEBUG    MQTT       Publishing: emon/emonpi/t6 0
2018-03-21 17:18:07,271 DEBUG    MQTT       Publishing: emon/emonpi/pulsecount 1
2018-03-21 17:18:07,273 INFO     MQTT       Publishing: emonhub/rx/5/values 0,0,0,0,58.3,56,70.3,34,35.5,0,1
2018-03-21 17:18:09,794 DEBUG    RFM2Pi     Discarding RX frame 'unreliable content'? 24 208 9 97 145 193 205 162 56 181 33 0 114 214 231 174 87 160 210 40 223 (-97)
2018-03-21 17:18:10,937 DEBUG    RFM2Pi     Discarding RX frame 'unreliable content'? 0 236 86 4 43 107 235 139 50 49 41 28 99 214 231 174 87 160 210 40 223 (-97)
2018-03-21 17:18:12,173 DEBUG    RFM2Pi     9 NEW FRAME : OK 5 0 0 0 0 0 0 0 0 71 2 48 2 189 2 84 1 99 1 0 0 1 0 0 0 (-0)
2018-03-21 17:18:12,177 DEBUG    RFM2Pi     9 Timestamp : 1521652692.17
2018-03-21 17:18:12,178 DEBUG    RFM2Pi     9 From Node : 5
2018-03-21 17:18:12,178 DEBUG    RFM2Pi     9    Values : [0, 0, 0, 0, 58.300000000000004, 56, 70.10000000000001, 34, 35.5, 0, 1]
2018-03-21 17:18:12,179 DEBUG    RFM2Pi     9 Sent to channel(start)' : ToEmonCMS
2018-03-21 17:18:12,180 DEBUG    RFM2Pi     9 Sent to channel(end)' : ToEmonCMS
2018-03-21 17:18:12,269 DEBUG    MQTT       Publishing: emon/emonpi/power1 0
2018-03-21 17:18:12,271 DEBUG    MQTT       Publishing: emon/emonpi/power2 0
2018-03-21 17:18:12,274 DEBUG    MQTT       Publishing: emon/emonpi/power1pluspower2 0
2018-03-21 17:18:12,276 DEBUG    MQTT       Publishing: emon/emonpi/vrms 0
2018-03-21 17:18:12,278 DEBUG    MQTT       Publishing: emon/emonpi/t1 58.3
2018-03-21 17:18:12,279 DEBUG    MQTT       Publishing: emon/emonpi/t2 56
2018-03-21 17:18:12,282 DEBUG    MQTT       Publishing: emon/emonpi/t3 70.1
2018-03-21 17:18:12,285 DEBUG    MQTT       Publishing: emon/emonpi/t4 34
2018-03-21 17:18:12,289 DEBUG    MQTT       Publishing: emon/emonpi/t5 35.5
2018-03-21 17:18:12,292 DEBUG    MQTT       Publishing: emon/emonpi/t6 0
2018-03-21 17:18:12,295 DEBUG    MQTT       Publishing: emon/emonpi/pulsecount 1
2018-03-21 17:18:12,298 INFO     MQTT       Publishing: emonhub/rx/5/values 0,0,0,0,58.3,56,70.1,34,35.5,0,1
2018-03-21 17:18:12,302 DEBUG    RFM2Pi     Discarding RX frame 'unreliable content'? 26 81 131 160 65 32 2 225 1 160 96 52 48 1 71 5 9 220 132 160 162 (-96)
2018-03-21 17:18:13,844 DEBUG    RFM2Pi     Discarding RX frame 'unreliable content'? 0 32 0 160 65 32 2 225 1 160 96 52 48 1 71 5 9 220 132 160 162 (-103)
2018-03-21 17:18:14,861 DEBUG    RFM2Pi     Discarding RX frame 'unreliable content'? 0 0 160 160 65 32 2 225 1 160 96 52 48 1 71 5 9 220 132 160 162 (-104)
2018-03-21 17:18:17,178 DEBUG    RFM2Pi     10 NEW FRAME : OK 5 0 0 0 0 0 0 0 0 71 2 48 2 186 2 84 1 99 1 0 0 1 0 0 0 (-0)
2018-03-21 17:18:17,182 DEBUG    RFM2Pi     10 Timestamp : 1521652697.18
2018-03-21 17:18:17,183 DEBUG    RFM2Pi     10 From Node : 5
2018-03-21 17:18:17,184 DEBUG    RFM2Pi     10    Values : [0, 0, 0, 0, 58.300000000000004, 56, 69.8, 34, 35.5, 0, 1]
2018-03-21 17:18:17,185 DEBUG    RFM2Pi     10 Sent to channel(start)' : ToEmonCMS
2018-03-21 17:18:17,186 DEBUG    RFM2Pi     10 Sent to channel(end)' : ToEmonCMS
2018-03-21 17:18:17,269 DEBUG    MQTT       Publishing: emon/emonpi/power1 0
2018-03-21 17:18:17,271 DEBUG    MQTT       Publishing: emon/emonpi/power2 0
2018-03-21 17:18:17,273 DEBUG    MQTT       Publishing: emon/emonpi/power1pluspower2 0
2018-03-21 17:18:17,275 DEBUG    MQTT       Publishing: emon/emonpi/vrms 0
2018-03-21 17:18:17,277 DEBUG    MQTT       Publishing: emon/emonpi/t1 58.3
2018-03-21 17:18:17,278 DEBUG    MQTT       Publishing: emon/emonpi/t2 56
2018-03-21 17:18:17,280 DEBUG    MQTT       Publishing: emon/emonpi/t3 69.8
2018-03-21 17:18:17,282 DEBUG    MQTT       Publishing: emon/emonpi/t4 34
2018-03-21 17:18:17,284 DEBUG    MQTT       Publishing: emon/emonpi/t5 35.5
2018-03-21 17:18:17,286 DEBUG    MQTT       Publishing: emon/emonpi/t6 0
2018-03-21 17:18:17,289 DEBUG    MQTT       Publishing: emon/emonpi/pulsecount 1
2018-03-21 17:18:17,292 INFO     MQTT       Publishing: emonhub/rx/5/values 0,0,0,0,58.3,56,69.8,34,35.5,0,1
2018-03-21 17:18:17,301 DEBUG    RFM2Pi     Discarding RX frame 'unreliable content'? 0 32 32 33 98 32 6 0 128 0 32 0 34 64 0 26 62 65 0 160 162 (-104)
2018-03-21 17:18:17,518 DEBUG    RFM2Pi     Discarding RX frame 'unreliable content'? 2 229 168 16 35 184 140 64 128 80 8 32 0 50 100 13 28 206 64 16 0 (-99)
2018-03-21 17:18:19,237 DEBUG    RFM2Pi     Discarding RX frame 'unreliable content'? 0 120 162 8 40 32 140 64 128 80 8 32 0 50 100 13 28 206 64 16 0 (-95)
2018-03-21 17:18:20,651 DEBUG    RFM2Pi     Discarding RX frame 'unreliable content'? 17 0 0 8 40 32 140 64 128 80 8 32 0 50 100 13 28 206 64 16 0 (-102)
2018-03-21 17:18:22,166 DEBUG    RFM2Pi     11 NEW FRAME : OK 5 0 0 0 0 0 0 0 0 71 2 48 2 184 2 84 1 98 1 0 0 1 0 0 0 (-0)
2018-03-21 17:18:22,169 DEBUG    RFM2Pi     11 Timestamp : 1521652702.17
2018-03-21 17:18:22,170 DEBUG    RFM2Pi     11 From Node : 5
2018-03-21 17:18:22,171 DEBUG    RFM2Pi     11    Values : [0, 0, 0, 0, 58.300000000000004, 56, 69.60000000000001, 34, 35.4, 0, 1]
2018-03-21 17:18:22,171 DEBUG    RFM2Pi     11 Sent to channel(start)' : ToEmonCMS
2018-03-21 17:18:22,172 DEBUG    RFM2Pi     11 Sent to channel(end)' : ToEmonCMS
2018-03-21 17:18:22,273 DEBUG    MQTT       Publishing: emon/emonpi/power1 0
2018-03-21 17:18:22,275 DEBUG    MQTT       Publishing: emon/emonpi/power2 0
2018-03-21 17:18:22,277 DEBUG    MQTT       Publishing: emon/emonpi/power1pluspower2 0
2018-03-21 17:18:22,279 DEBUG    MQTT       Publishing: emon/emonpi/vrms 0
2018-03-21 17:18:22,281 DEBUG    MQTT       Publishing: emon/emonpi/t1 58.3
2018-03-21 17:18:22,283 DEBUG    MQTT       Publishing: emon/emonpi/t2 56
2018-03-21 17:18:22,285 DEBUG    MQTT       Publishing: emon/emonpi/t3 69.6
2018-03-21 17:18:22,287 DEBUG    MQTT       Publishing: emon/emonpi/t4 34
2018-03-21 17:18:22,289 DEBUG    MQTT       Publishing: emon/emonpi/t5 35.4
2018-03-21 17:18:22,291 DEBUG    MQTT       Publishing: emon/emonpi/t6 0
2018-03-21 17:18:22,293 DEBUG    MQTT       Publishing: emon/emonpi/pulsecount 1
2018-03-21 17:18:22,295 INFO     MQTT       Publishing: emonhub/rx/5/values 0,0,0,0,58.3,56,69.6,34,35.4,0,1
2018-03-21 17:18:23,494 DEBUG    RFM2Pi     Discarding RX frame 'unreliable content'? 0 32 0 0 0 32 140 64 128 80 8 32 0 50 100 13 28 206 64 16 0 (-102)
2018-03-21 17:18:25,210 DEBUG    RFM2Pi     Discarding RX frame 'unreliable content'? 0 32 48 0 132 32 48 0 0 6 172 0 0 8 0 49 160 16 192 2 192 (-101)
2018-03-21 17:18:27,128 DEBUG    RFM2Pi     12 NEW FRAME : OK 5 0 0 0 0 0 0 0 0 71 2 48 2 182 2 84 1 98 1 0 0 1 0 0 0 (-0)
2018-03-21 17:18:27,132 DEBUG    RFM2Pi     12 Timestamp : 1521652707.13
2018-03-21 17:18:27,133 DEBUG    RFM2Pi     12 From Node : 5
2018-03-21 17:18:27,134 DEBUG    RFM2Pi     12    Values : [0, 0, 0, 0, 58.300000000000004, 56, 69.4, 34, 35.4, 0, 1]
2018-03-21 17:18:27,135 DEBUG    RFM2Pi     12 Sent to channel(start)' : ToEmonCMS
2018-03-21 17:18:27,136 DEBUG    RFM2Pi     12 Sent to channel(end)' : ToEmonCMS
2018-03-21 17:18:27,322 DEBUG    MQTT       Publishing: emon/emonpi/power1 0
2018-03-21 17:18:27,325 DEBUG    MQTT       Publishing: emon/emonpi/power2 0
2018-03-21 17:18:27,327 DEBUG    MQTT       Publishing: emon/emonpi/power1pluspower2 0
2018-03-21 17:18:27,329 DEBUG    MQTT       Publishing: emon/emonpi/vrms 0
2018-03-21 17:18:27,331 DEBUG    MQTT       Publishing: emon/emonpi/t1 58.3
2018-03-21 17:18:27,332 DEBUG    MQTT       Publishing: emon/emonpi/t2 56
2018-03-21 17:18:27,334 DEBUG    MQTT       Publishing: emon/emonpi/t3 69.4
2018-03-21 17:18:27,336 DEBUG    MQTT       Publishing: emon/emonpi/t4 34
2018-03-21 17:18:27,338 DEBUG    MQTT       Publishing: emon/emonpi/t5 35.4
2018-03-21 17:18:27,340 DEBUG    MQTT       Publishing: emon/emonpi/t6 0
2018-03-21 17:18:27,341 DEBUG    MQTT       Publishing: emon/emonpi/pulsecount 1
2018-03-21 17:18:27,343 INFO     MQTT       Publishing: emonhub/rx/5/values 0,0,0,0,58.3,56,69.4,34,35.4,0,1
2018-03-21 17:18:28,072 DEBUG    RFM2Pi     Discarding RX frame 'unreliable content'? 0 0 0 72 0 0 40 32 32 32 0 0 0 0 0 0 64 68 164 0 8 (-101)
2018-03-21 17:18:32,164 DEBUG    RFM2Pi     13 NEW FRAME : OK 5 0 0 0 0 0 0 0 0 72 2 48 2 180 2 84 1 98 1 0 0 1 0 0 0 (-0)
2018-03-21 17:18:32,168 DEBUG    RFM2Pi     13 Timestamp : 1521652712.16
2018-03-21 17:18:32,169 DEBUG    RFM2Pi     13 From Node : 5
2018-03-21 17:18:32,170 DEBUG    RFM2Pi     13    Values : [0, 0, 0, 0, 58.400000000000006, 56, 69.2, 34, 35.4, 0, 1]
2018-03-21 17:18:32,171 DEBUG    RFM2Pi     13 Sent to channel(start)' : ToEmonCMS
2018-03-21 17:18:32,171 DEBUG    RFM2Pi     13 Sent to channel(end)' : ToEmonCMS
2018-03-21 17:18:32,258 DEBUG    MQTT       Publishing: emon/emonpi/power1 0
2018-03-21 17:18:32,260 DEBUG    MQTT       Publishing: emon/emonpi/power2 0
2018-03-21 17:18:32,262 DEBUG    MQTT       Publishing: emon/emonpi/power1pluspower2 0
2018-03-21 17:18:32,264 DEBUG    MQTT       Publishing: emon/emonpi/vrms 0
2018-03-21 17:18:32,267 DEBUG    MQTT       Publishing: emon/emonpi/t1 58.4
2018-03-21 17:18:32,269 DEBUG    MQTT       Publishing: emon/emonpi/t2 56
2018-03-21 17:18:32,270 DEBUG    MQTT       Publishing: emon/emonpi/t3 69.2
2018-03-21 17:18:32,272 DEBUG    MQTT       Publishing: emon/emonpi/t4 34
2018-03-21 17:18:32,276 DEBUG    MQTT       Publishing: emon/emonpi/t5 35.4
2018-03-21 17:18:32,280 DEBUG    MQTT       Publishing: emon/emonpi/t6 0
2018-03-21 17:18:32,284 DEBUG    MQTT       Publishing: emon/emonpi/pulsecount 1
2018-03-21 17:18:32,287 DEBUG    RFM2Pi     Discarding RX frame 'unreliable content'? 0 0 32 72 0 0 40 32 32 32 0 0 0 0 0 0 64 68 164 0 8 (-104)
2018-03-21 17:18:32,289 INFO     MQTT       Publishing: emonhub/rx/5/values 0,0,0,0,58.4,56,69.2,34,35.4,0,1
2018-03-21 17:18:32,602 DEBUG    RFM2Pi     Discarding RX frame 'unreliable content'? 0 0 32 72 0 0 40 32 32 32 0 0 0 0 0 0 64 68 164 0 8 (-103)
2018-03-21 17:18:33,214 DEBUG    RFM2Pi     Discarding RX frame 'unreliable content'? 16 8 32 18 33 64 16 32 16 38 32 36 19 128 6 38 192 0 16 0 32 (-104)
2018-03-21 17:18:33,718 DEBUG    emoncmsorg Buffer size: 6
2018-03-21 17:18:37,141 DEBUG    RFM2Pi     14 NEW FRAME : OK 5 0 0 0 0 0 0 0 0 72 2 48 2 178 2 83 1 97 1 0 0 1 0 0 0 (-0)
2018-03-21 17:18:37,145 DEBUG    RFM2Pi     14 Timestamp : 1521652717.14
2018-03-21 17:18:37,146 DEBUG    RFM2Pi     14 From Node : 5
2018-03-21 17:18:37,147 DEBUG    RFM2Pi     14    Values : [0, 0, 0, 0, 58.400000000000006, 56, 69, 33.9, 35.300000000000004, 0, 1]
2018-03-21 17:18:37,147 DEBUG    RFM2Pi     14 Sent to channel(start)' : ToEmonCMS
2018-03-21 17:18:37,148 DEBUG    RFM2Pi     14 Sent to channel(end)' : ToEmonCMS
2018-03-21 17:18:37,341 DEBUG    MQTT       Publishing: emon/emonpi/power1 0
2018-03-21 17:18:37,343 DEBUG    MQTT       Publishing: emon/emonpi/power2 0
2018-03-21 17:18:37,345 DEBUG    MQTT       Publishing: emon/emonpi/power1pluspower2 0
2018-03-21 17:18:37,347 DEBUG    MQTT       Publishing: emon/emonpi/vrms 0
2018-03-21 17:18:37,349 DEBUG    MQTT       Publishing: emon/emonpi/t1 58.4
2018-03-21 17:18:37,351 DEBUG    MQTT       Publishing: emon/emonpi/t2 56
2018-03-21 17:18:37,353 DEBUG    MQTT       Publishing: emon/emonpi/t3 69
2018-03-21 17:18:37,356 DEBUG    MQTT       Publishing: emon/emonpi/t4 33.9
2018-03-21 17:18:37,358 DEBUG    MQTT       Publishing: emon/emonpi/t5 35.3
2018-03-21 17:18:37,360 DEBUG    MQTT       Publishing: emon/emonpi/t6 0
2018-03-21 17:18:37,362 DEBUG    MQTT       Publishing: emon/emonpi/pulsecount 1
2018-03-21 17:18:37,364 INFO     MQTT       Publishing: emonhub/rx/5/values 0,0,0,0,58.4,56,69,33.9,35.3,0,1
2018-03-21 17:18:38,266 DEBUG    RFM2Pi     Discarding RX frame 'unreliable content'? 5 32 0 22 165 0 47 194 25 0 32 0 37 8 1 0 42 192 1 128 0 (-103)
2018-03-21 17:18:42,116 DEBUG    RFM2Pi     15 NEW FRAME : OK 5 0 0 0 0 0 0 0 0 72 2 48 2 176 2 83 1 97 1 0 0 1 0 0 0 (-0)
2018-03-21 17:18:42,120 DEBUG    RFM2Pi     15 Timestamp : 1521652722.12
2018-03-21 17:18:42,121 DEBUG    RFM2Pi     15 From Node : 5
2018-03-21 17:18:42,122 DEBUG    RFM2Pi     15    Values : [0, 0, 0, 0, 58.400000000000006, 56, 68.8, 33.9, 35.300000000000004, 0, 1]
2018-03-21 17:18:42,123 DEBUG    RFM2Pi     15 Sent to channel(start)' : ToEmonCMS
2018-03-21 17:18:42,123 DEBUG    RFM2Pi     15 Sent to channel(end)' : ToEmonCMS
2018-03-21 17:18:42,316 DEBUG    MQTT       Publishing: emon/emonpi/power1 0
2018-03-21 17:18:42,319 DEBUG    MQTT       Publishing: emon/emonpi/power2 0
2018-03-21 17:18:42,321 DEBUG    MQTT       Publishing: emon/emonpi/power1pluspower2 0
2018-03-21 17:18:42,323 DEBUG    MQTT       Publishing: emon/emonpi/vrms 0
2018-03-21 17:18:42,324 DEBUG    MQTT       Publishing: emon/emonpi/t1 58.4
2018-03-21 17:18:42,327 DEBUG    MQTT       Publishing: emon/emonpi/t2 56
2018-03-21 17:18:42,330 DEBUG    MQTT       Publishing: emon/emonpi/t3 68.8
2018-03-21 17:18:42,332 DEBUG    MQTT       Publishing: emon/emonpi/t4 33.9
2018-03-21 17:18:42,334 DEBUG    MQTT       Publishing: emon/emonpi/t5 35.3
2018-03-21 17:18:42,336 DEBUG    MQTT       Publishing: emon/emonpi/t6 0
2018-03-21 17:18:42,338 DEBUG    MQTT       Publishing: emon/emonpi/pulsecount 1
2018-03-21 17:18:42,340 INFO     MQTT       Publishing: emonhub/rx/5/values 0,0,0,0,58.4,56,68.8,33.9,35.3,0,1
2018-03-21 17:18:42,436 DEBUG    RFM2Pi     Discarding RX frame 'unreliable content'? 0 1 0 29 96 3 0 8 64 32 0 32 0 32 32 16 2 13 200 0 32 (-100)
2018-03-21 17:18:43,345 DEBUG    RFM2Pi     Discarding RX frame 'unreliable content'? 0 3 48 32 32 48 32 96 0 0 32 1 32 0 16 10 96 128 48 0 32 (-103)
2018-03-21 17:18:46,166 DEBUG    RFM2Pi     Discarding RX frame 'unreliable content'? 2 32 32 32 32 32 0 32 6 0 0 0 32 49 5 168 0 0 32 32 0 (-101)
2018-03-21 17:18:47,178 DEBUG    RFM2Pi     16 NEW FRAME : OK 5 0 0 0 0 0 0 0 0 72 2 48 2 174 2 83 1 97 1 0 0 1 0 0 0 (-0)
2018-03-21 17:18:47,182 DEBUG    RFM2Pi     16 Timestamp : 1521652727.18
2018-03-21 17:18:47,183 DEBUG    RFM2Pi     16 From Node : 5
2018-03-21 17:18:47,183 DEBUG    RFM2Pi     16    Values : [0, 0, 0, 0, 58.400000000000006, 56, 68.60000000000001, 33.9, 35.300000000000004, 0, 1]
2018-03-21 17:18:47,184 DEBUG    RFM2Pi     16 Sent to channel(start)' : ToEmonCMS
2018-03-21 17:18:47,185 DEBUG    RFM2Pi     16 Sent to channel(end)' : ToEmonCMS
2018-03-21 17:18:47,366 DEBUG    MQTT       Publishing: emon/emonpi/power1 0
2018-03-21 17:18:47,369 DEBUG    MQTT       Publishing: emon/emonpi/power2 0
2018-03-21 17:18:47,371 DEBUG    MQTT       Publishing: emon/emonpi/power1pluspower2 0
2018-03-21 17:18:47,372 DEBUG    MQTT       Publishing: emon/emonpi/vrms 0
2018-03-21 17:18:47,374 DEBUG    MQTT       Publishing: emon/emonpi/t1 58.4
2018-03-21 17:18:47,376 DEBUG    MQTT       Publishing: emon/emonpi/t2 56
2018-03-21 17:18:47,378 DEBUG    MQTT       Publishing: emon/emonpi/t3 68.6
2018-03-21 17:18:47,380 DEBUG    MQTT       Publishing: emon/emonpi/t4 33.9
2018-03-21 17:18:47,382 DEBUG    MQTT       Publishing: emon/emonpi/t5 35.3
2018-03-21 17:18:47,383 DEBUG    MQTT       Publishing: emon/emonpi/t6 0
2018-03-21 17:18:47,386 DEBUG    MQTT       Publishing: emon/emonpi/pulsecount 1
2018-03-21 17:18:47,388 INFO     MQTT       Publishing: emonhub/rx/5/values 0,0,0,0,58.4,56,68.6,33.9,35.3,0,1
2018-03-21 17:18:47,595 DEBUG    RFM2Pi     Discarding RX frame 'unreliable content'? 23 32 5 0 33 128 0 32 4 32 0 0 0 32 64 1 2 46 0 0 36 (-102)
2018-03-21 17:18:48,102 DEBUG    RFM2Pi     Discarding RX frame 'unreliable content'? 0 32 32 32 0 32 32 0 0 32 0 0 0 32 0 149 0 0 32 32 32 (-104)
2018-03-21 17:18:48,512 DEBUG    RFM2Pi     Discarding RX frame 'unreliable content'? 0 40 0 32 0 32 32 0 0 32 0 0 0 32 0 149 0 0 32 32 32 (-102)
2018-03-21 17:49:59,807 DEBUG    emoncmsorg Buffer size: 3

bungo63 · 21 March 2018 18:03

Think my last post validates what you replied too. At 1747 theres no indication that the system has been reset.

When I reboot the emonpi I hold the little button in for 5 seconds until the LED display completes its countdown an says start reboot or something. I am then instructed to wait another 30 seconds and the LED goes black. At his point I power cycle the emon pi.

pb66 · 21 March 2018 22:35

I’m still confused by the log files. Sorry to ask again, but…

Just to be 100% sure. Are you saying that at 17:47 you went through that “reboot” procedure which included taking the power away from the emonPi and powering the emonPi back up?

Where are you getting the log data from? Direct from the log files at /var/log/emonhub/emonhub.log or via the emoncms/config page?

2018-03-21 17:18:48,512 DEBUG    RFM2Pi     Discarding RX frame 'unreliable content'? 0 40 0 32 0 32 32 0 0 32 0 0 0 32 0 149 0 0 32 32 32 (-102)
2018-03-21 17:49:59,807 DEBUG    emoncmsorg Buffer size: 3

These 2 lines just look as if emonhub paused for a while and then conitnued again, if emonhub restarts, it must go through the lines that you see at the beginning of that last log file you shared, starting with the version number etc.

Although even that start up isn’t without some perculiarities

2018-03-21 17:17:25,744 DEBUG    MainThread Opening serial port: /dev/ttyAMA0 @ 38400 bits/s
2018-03-21 17:17:27,759 INFO     MainThread RFM2Pi device firmware version: OK
2018-03-21 17:17:27,760 INFO     MainThread RFM2Pi device current settings:  5 0 0 0 0 0 0 0 0 70 2 48 2 208 2 86 1 101 1 0 0 1 0 0 0 (-0)

These lines are suspicious. The FW version feature wasn’t implemented in the emonpi FW so that should say something like “MainThread RFM2Pi device firmware version: Is not available” or something along those lines, I’ve never seen “OK” and a valid payload in place of the configuration confirmation.

Could you take a look at the cpu and memory usage using the top command? Is the cpu or RAM usage high?

bungo63 · 22 March 2018 10:19

Hi Paul. Thanks for sticking with me! Yes, I used my mobile phone to check
the time and initiated the reboot sequence at 1747.

The log that I have provided comes from the emonhub facility on the local
emoncms

Is it possible that in installing the new software version I have somehow
screwed up the Pi? Would there be a means of starting from scratch again? I
also still have the original SD card with that was replaced. I could
re-install that and see what happens.

Some further observations:

I have noticed is that the Top output says that the system has been up
for around 15 hours. This puts the last restart around the time of our
initial correspondence. A few minutes after being SSH’d onto the Emonmpi
this morning to run the Top command, the SSH session hung. So I’m
concluding that whatever is triggering the hang has something to do with
being connected to the Pi.
Looking at the graphs in Emoncms local, no data appears to have been
collected since 1430 yesterday.
I’ve disconnected my temperature sensors and moved the pi downstairs
and so far its hasn’t hung no matter what I do. So maybe the temp sensors
or data feeds are a contributor?

I run the Top command and the results were as follows.

i@emonpi(ro):~$ top
top - 09:36:44 up 15:48,  1 user,  load average: 0.10, 0.15, 0.14
Tasks: 137 total,   1 running, 136 sleeping,   0 stopped,   0 zombie
%Cpu(s):  2.8 us,  1.2 sy,  0.0 ni, 95.9 id,  0.0 wa,  0.0 hi,  0.1 si,
0.0 st
KiB Mem:    994236 total,   465772 used,   528464 free,     1656 buffers
KiB Swap:        0 total,        0 used,        0 free.   195812 cached Mem

  PID USER      PR  NI    VIRT    RES    SHR S  %CPU %MEM     TIME+ COMMAND
  808 emonhub   20   0   49980  16800   6428 S   6.3  1.7  64:58.54 python
 1968 root      20   0   52568  23052  13912 S   2.0  2.3  11:28.69 php
 1918 root      20   0   36656  13476   6500 S   1.0  1.4  11:10.86 python
 1855 redis     20   0   31048   2796   2160 S   1.3  0.3  10:36.07 redis-server
 1163 openhab   20   0  379984  95328  11828 S   1.0  9.6  10:18.91 java
 1862 mosquit+  20   0    5516   3732   3316 S   0.3  0.4   3:29.41 mosquitto
 2037 pi        20   0    6548   2904   2500 S   0.3  0.3   2:24.86 service-runner
    7 root      20   0       0      0      0 S   0.0  0.0   1:40.70 rcu_sched
 1554 mysql     20   0  324620  38200   8876 S   0.3  3.8   1:40.68 mysqld
    1 root      20   0    5432   3916   2744 S   0.0  0.4   0:25.73 systemd
  503 pi        25   5  109008  60228   7956 S   0.0  6.1   0:22.75 node-red
  504 avahi     20   0    4000   2536   2252 S   0.0  0.3   0:20.25 avahi-daemon
  325 root      20   0       0      0      0 S   0.0  0.0   0:14.92 kworker/1:0
  137 root      20   0   10232   3964   3704 S   0.0  0.4   0:14.52 systemd-journal
 1989 root     -11   0   21180   1868   1640 S   0.0  0.2   0:09.38 lwrfmqtt
 1836 www-data  20   0  115424  14360   9404 S   0.0  1.4   0:05.39 apache2
  987 root      20   0    2560   1660   1376 S   0.0  0.2   0:04.74 dhcpcd
 1832 root      20   0  112764  22940  18640 S   0.0  2.3   0:04.46 apache2
 1840 www-data  20   0  115520  14448   9476 S   0.0  1.5   0:04.07 apache2
 2353 www-data  20   0  115516  13640   8744 S   0.0  1.4   0:03.92 apache2
    3 root      20   0       0      0      0 S   0.0  0.0   0:03.80 ksoftirqd/0
 2349 www-data  20   0  115396  13768   8804 S   0.0  1.4   0:03.79 apache2
 1837 www-data  20   0  115412  15008   9896 S   0.0  1.5   0:03.36 apache2
   13 root      rt   0       0      0      0 S   0.0  0.0   0:03.21 migration/1
27061 pi        20   0    6952   2436   2068 R   0.7  0.2   0:02.98 top
   19 root      20   0       0      0      0 S   0.0  0.0   0:02.92 ksoftirqd/2
   88 root      20   0       0      0      0 S   0.0  0.0   0:02.90 mmcqd/0
  506 root      20   0   32140   2684   2312 S   0.0  0.3   0:02.62 rsyslogd
 2354 www-data  20   0  115396  13752   8808 S   0.0  1.4   0:02.23 apache2
 1838 www-data  20   0  115648  14272   9288 S   0.0  1.4   0:02.11 apache2
 1839 www-data  20   0  115516  14140   9220 S   0.0  1.4   0:02.07 apache2
 2324 www-data  20   0  115516  13676   8784 S   0.0  1.4   0:02.01 apache2
 3377 www-data  20   0  115516  13640   8744 S   0.0  1.4   0:01.99 apache2
18944 root      20   0       0      0      0 S   0.0  0.0   0:01.75 kworker/u8:2
   14 root      20   0       0      0      0 S   0.0  0.0   0:01.73 ksoftirqd/1
   18 root      rt   0       0      0      0 S   0.0  0.0   0:01.67 migration/2
   23 root      rt   0       0      0      0 S   0.0  0.0   0:01.58 migration/3

Regards/le meas

David Moule

pb66 · 22 March 2018 13:45

That fits in with the emonhub.log

2018-03-21 17:17:25,732 INFO     MainThread EmonHub emonHub emon-pi variant v2.1.1
2018-03-21 17:17:25,734 INFO     MainThread Opening hub...
2018-03-21 17:17:25,736 INFO     MainThread Logging level set to DEBUG

Which would suggest the emonpi is not restarting when you think you are restarting it at 17:47.

I’m still not clear on what you are doing

Please confirm if you did or you did not remove the power from the Pi at around 17:47.

Can you also confirm if you are initiating the reboot sequence from the admin page of emoncms or command line via ssh? If emoncms has “hung” the button may not be working.

At this point I might be tempted to say start over again by reflashing the emonSD image to the SD card, place your emonPi somewhere that the network connection is guaranteed (Ethernet preferred at this point) and then power up the emonPi with the newly flashed emonSD and walk away. The image is written to update to the latest version on first boot and interrupting that can cause issues. Leave it plenty of time, perhaps an hour or so just to be sure.

At this point you can log in and start configuring it with wifi details etc. once you have it working and can confirm there are no issues at this point, then move it to where the sensors are, connect those sensors and then power it up at that new location. There should only be one difference at the new location and that is that the input data will have meaningful values rather than just zeros.

If you do take this option, please post the emonpiupdate.log here before moving it.

Before you do though please let us know how you get on with it now you have moved it. it sounds like it might even be possible it’s just a flaky wifi signal. That would cause apparent “hangs” and since you are checking the emonhub.logs in emoncms, I wonder if newly aqqured log entries after a period of no connection would simply append to the cache log entries or not? It’s not something I’ve experimented with.

bungo63 · 26 March 2018 12:26

Paul, apologies for noit getting back sooner but as you’ll see I’ve been busy!

I decided to make another image using a Transcend 16G SD card that I had, and I followed all the instructions to the letter and rebuilt the emonpi (including expanding the storage). The system appeared to be fairly stable after this, although there was an odd experience with setting up the wifi the wifi connection initially whereby the display showed a really strange IP address but I could SSH onto (correct) static IP address assigned to the device on my network. This display issue disappeared overnight!

Whilst doing this I also became aware of some issues my kids were having with wifi connectivity in their bedrooms which are adjacent to the cupboard where the emonpi lives. On investigation it seems that wifi coverage in that part of our house is poor (with some contention from the school next door and my neighbour’s house) and a number of devices were having issues. These issues seem to have co-incided with the introduction of a Cisco Meraki wifi access point that I have been evaluating, so this was turned off and our normal Asus wifi re-stablished. This fixed my kids issues and so may also have contributed to any issues with the emonpi.

Next I set about configuring the local emoncms. This remained stable with the exception of one incident of apparent hanging, but after a few minutes the service came back without any intervention from me. Thinking that editing feeds and inputs may be causing problems I continued to configure the local emoncms but have not been able to recreate any problems.
Also data has been successfully recorded for over 48 hours with no breaks.

I have also configured the web emoncms and data is being uploaded OK.

I then configured my Home Assistant device to connect to to the local emoncms and it is pulling back inputs with no problems.

So having done all that I then returned back to my shop bought 8G SD emonpi update SD card and repeated the above and all is now working OK (or so it seems). I also took the opportunity to check my wifi type – it’s a RFM69CW 433Mhz model so tat should be OK.

So I think the wifi network may be the likeliest suspect, but I may have also screwed up the emonpi somehow when I did the upgrade.

I’ll leave it alone now and see how it goes. Sorry that I can’t be anymore specific.
Thanks for your help.

bungo63 · 4 April 2018 19:19

Hi Paul. One more update. Following my last post I did once again experience a loss of connection with the local emoncms and the emonpi. So in desperation i swapped out the edimax wifi dongle with a cheap an cheerful Chinese antenna adapter that I have on a pi-based weather station. This seems to have cured the connectivity problem. The weather station also seems to run OK with the edimax so I can only assume the edimax was becoming sensitive to interference in the emonpi location.