First, let me repeat that I’m asking if there are clues in the emoncms.log that indicate which feedid is causing an error (if not, then I’m requesting that if possible, future versions allow the emoncms.log to show more detail for errors). Specifically it would be really helpful if the log identified the feed causing an error. My challenge was trying to figure out which of my 170 feeds was causing the json error. Once I identified which feed was causing the error, I quickly fixed my Python code that sends the json string to emoncms. But simply identifying which feed was causing the error took a fair bit of time.
In settings.ini
I’m not seeing a DEBUG mode:
[log]
; Log Level: 1=INFO, 2=WARN, 3=ERROR
level = 1
level = 1 give the most detail. Here’s a portion of the emoncms.log containing the ERROR. Am I missing something that indicates which feedid is causing the error?
2021-01-04 15:34:37.901|INFO|feed_model.php|EngineClass() Autoloaded new instance of 'PHPFina'.
2021-01-04 15:34:38.085|INFO|feed_model.php|get_timevalue() calling VIRTUAL lastvalue 169
2021-01-04 15:34:38.086|INFO|feed_model.php|EngineClass() Autoloaded new instance of 'VirtualFeed'.
2021-01-04 15:34:38.090|INFO|process_processlist.php|source_feed_data_time() feedid=159 start=1609774.478 end=1609774.478 len=0 int=0 cnt=1 value=0 took=0.00073099136352539
2021-01-04 15:34:38.099|INFO|feed_model.php|get_timevalue() calling VIRTUAL lastvalue 181
2021-01-04 15:34:38.103|INFO|process_processlist.php|source_feed_data_time() feedid=160 start=1609774.478 end=1609774.478 len=0 int=0 cnt=1 value=1340 took=0.00072813034057617
2021-01-04 15:34:38.104|INFO|feed_model.php|get_timevalue() calling VIRTUAL lastvalue 182
2021-01-04 15:34:38.107|INFO|process_processlist.php|source_feed_data_time() feedid=173 start=1609774.478 end=1609774.478 len=0 int=0 cnt=1 value=6810 took=0.00073504447937012
2021-01-04 15:34:38.108|INFO|feed_model.php|get_timevalue() calling VIRTUAL lastvalue 183
2021-01-04 15:34:38.112|INFO|process_processlist.php|source_feed_data_time() feedid=173 start=1609774.478 end=1609774.478 len=0 int=0 cnt=1 value=6810 took=0.0007321834564209
2021-01-04 15:34:42.882|INFO|index.php|feed/list.json
2021-01-04 15:34:42.897|INFO|feed_model.php|EngineClass() Autoloaded new instance of 'PHPFina'.
2021-01-04 15:34:43.096|INFO|feed_model.php|get_timevalue() calling VIRTUAL lastvalue 169
2021-01-04 15:34:43.097|INFO|feed_model.php|EngineClass() Autoloaded new instance of 'VirtualFeed'.
2021-01-04 15:34:43.100|INFO|process_processlist.php|source_feed_data_time() feedid=159 start=1609774.483 end=1609774.483 len=0 int=0 cnt=1 value=0 took=0.00073504447937012
2021-01-04 15:34:43.109|INFO|feed_model.php|get_timevalue() calling VIRTUAL lastvalue 181
2021-01-04 15:34:43.113|INFO|process_processlist.php|source_feed_data_time() feedid=160 start=1609774.483 end=1609774.483 len=0 int=0 cnt=1 value=1340 took=0.00069904327392578
2021-01-04 15:34:43.114|INFO|feed_model.php|get_timevalue() calling VIRTUAL lastvalue 182
2021-01-04 15:34:43.117|INFO|process_processlist.php|source_feed_data_time() feedid=173 start=1609774.483 end=1609774.483 len=0 int=0 cnt=1 value=6810 took=0.00071287155151367
2021-01-04 15:34:43.119|INFO|feed_model.php|get_timevalue() calling VIRTUAL lastvalue 183
2021-01-04 15:34:43.122|INFO|process_processlist.php|source_feed_data_time() feedid=173 start=1609774.483 end=1609774.483 len=0 int=0 cnt=1 value=6810 took=0.00072693824768066
2021-01-04 15:34:47.879|INFO|index.php|feed/list.json
2021-01-04 15:34:47.894|INFO|feed_model.php|EngineClass() Autoloaded new instance of 'PHPFina'.
2021-01-04 15:34:48.072|INFO|feed_model.php|get_timevalue() calling VIRTUAL lastvalue 169
2021-01-04 15:34:48.072|INFO|feed_model.php|EngineClass() Autoloaded new instance of 'VirtualFeed'.
2021-01-04 15:34:48.076|INFO|process_processlist.php|source_feed_data_time() feedid=159 start=1609774.488 end=1609774.488 len=0 int=0 cnt=1 value=0 took=0.00076508522033691
2021-01-04 15:34:48.085|INFO|feed_model.php|get_timevalue() calling VIRTUAL lastvalue 181
2021-01-04 15:34:48.089|INFO|process_processlist.php|source_feed_data_time() feedid=160 start=1609774.488 end=1609774.488 len=0 int=0 cnt=1 value=1340 took=0.00094890594482422
2021-01-04 15:34:48.090|INFO|feed_model.php|get_timevalue() calling VIRTUAL lastvalue 182
2021-01-04 15:34:48.094|INFO|process_processlist.php|source_feed_data_time() feedid=173 start=1609774.488 end=1609774.488 len=0 int=0 cnt=1 value=6810 took=0.00073909759521484
2021-01-04 15:34:48.095|INFO|feed_model.php|get_timevalue() calling VIRTUAL lastvalue 183
2021-01-04 15:34:48.098|INFO|process_processlist.php|source_feed_data_time() feedid=173 start=1609774.488 end=1609774.488 len=0 int=0 cnt=1 value=6810 took=0.00078010559082031
2021-01-04 15:34:48.536|INFO|index.php|input/post
2021-01-04 15:34:48.543|INFO|index.php|Valid time in seconds used 1609774488
2021-01-04 15:34:48.544|ERROR|input_controller.php|{"success": false, "message": "Format error, json value is not numeric"} for User: 1
2021-01-04 15:34:49.460|INFO|index.php|input/post.json
2021-01-04 15:34:49.472|INFO|index.php|Valid time in seconds used 1609774489
2021-01-04 15:34:49.500|INFO|feed_model.php|insert_data() feedid=110 updatetime=1609774489 feedtime=1609774489 value=28.23 arg=
2021-01-04 15:34:49.501|INFO|feed_model.php|EngineClass() Autoloaded new instance of 'RedisBuffer'.
2021-01-04 15:34:49.503|INFO|feed_model.php|insert_data() feedid=111 updatetime=1609774489 feedtime=1609774489 value=1.03 arg=
2021-01-04 15:34:49.506|INFO|feed_model.php|insert_data() feedid=115 updatetime=1609774489 feedtime=1609774489 value=27.2 arg=
2021-01-04 15:34:49.508|INFO|feed_model.php|insert_data() feedid=112 updatetime=1609774489 feedtime=1609774489 value=25.49 arg=
2021-01-04 15:34:49.510|INFO|feed_model.php|insert_data() feedid=113 updatetime=1609774489 feedtime=1609774489 value=1.15 arg=
2021-01-04 15:34:49.512|INFO|feed_model.php|insert_data() feedid=114 updatetime=1609774489 feedtime=1609774489 value=0.57 arg=
2021-01-04 15:34:49.513|INFO|feed_model.php|insert_data() feedid=122 updatetime=1609774489 feedtime=1609774489 value=0.66 arg=
2021-01-04 15:34:49.515|INFO|feed_model.php|insert_data() feedid=123 updatetime=1609774489 feedtime=1609774489 value=0.08 arg=
2021-01-04 15:34:49.516|INFO|feed_model.php|insert_data() feedid=124 updatetime=1609774489 feedtime=1609774489 value=0.02 arg=
2021-01-04 15:34:49.518|INFO|feed_model.php|insert_data() feedid=125 updatetime=1609774489 feedtime=1609774489 value=0.26 arg=