It seems the logging on emoncms is not giving me what I would expect.
Setting the level to INFO (1) on a emonpi on Stable all I am getting is…
LAST ENTRIES ON THE LOG FILE
2019-07-18 18:34:21.587|INFO|index.php|admin/getlog
2019-07-18 18:34:22.588|INFO|index.php|admin/getlog
2019-07-18 18:34:23.586|INFO|index.php|admin/getlog
2019-07-18 18:34:24.587|INFO|index.php|admin/getlog
2019-07-18 18:34:25.588|INFO|index.php|admin/getlog
2019-07-18 18:34:26.585|INFO|index.php|admin/getlog
2019-07-18 18:34:27.584|INFO|index.php|admin/getlog
2019-07-18 18:34:28.588|INFO|index.php|admin/getlog
2019-07-18 18:34:29.589|INFO|index.php|admin/getlog
2019-07-18 18:34:30.583|INFO|index.php|admin/getlog
2019-07-18 18:34:31.587|INFO|index.php|admin/getlog
2019-07-18 18:34:32.582|INFO|index.php|admin/getlog
2019-07-18 18:34:33.585|INFO|index.php|admin/getlog
2019-07-18 18:34:34.584|INFO|index.php|admin/getlog
2019-07-18 18:34:35.586|INFO|index.php|admin/getlog
2019-07-18 18:34:36.583|INFO|index.php|admin/getlog
2019-07-18 18:34:37.588|INFO|index.php|admin/getlog
2019-07-18 18:34:38.587|INFO|index.php|admin/getlog
2019-07-18 18:34:39.010|INFO|index.php|admin/getlog
2019-07-18 18:34:39.508|INFO|index.php|admin/getlog
2019-07-18 18:34:40.012|INFO|index.php|admin/getlog
2019-07-18 18:34:40.510|INFO|index.php|admin/getlog
2019-07-18 18:34:41.011|INFO|index.php|admin/getlog
2019-07-18 18:34:41.510|INFO|index.php|admin/getlog
2019-07-18 18:34:42.013|INFO|index.php|admin/getlog
I have checked on a system that is on master but manually updated via git (so paths etc are as they were) and the logging produces
LAST ENTRIES ON THE LOG FILE
2019-07-18 18:36:21.068|INFO|index.php|admin/getlog
2019-07-18 18:36:21.572|INFO|index.php|admin/getlog
2019-07-18 18:36:22.070|INFO|index.php|admin/getlog
2019-07-18 18:36:22.571|INFO|index.php|admin/getlog
2019-07-18 18:36:23.069|INFO|index.php|admin/getlog
2019-07-18 18:36:23.571|INFO|index.php|admin/getlog
2019-07-18 18:36:24.071|INFO|index.php|admin/getlog
2019-07-18 18:36:24.571|INFO|index.php|admin/getlog
2019-07-18 18:36:25.075|INFO|index.php|admin/getlog
2019-07-18 18:36:25.569|INFO|index.php|admin/getlog
2019-07-18 18:36:25.893|INFO|index.php|input/bulk.json
2019-07-18 18:36:25.897|INFO|feed_model.php|insert_data() feedid=15 updatetime=1563474985 feedtime=1563474985 value=480 arg=
2019-07-18 18:36:25.897|INFO|feed_model.php|EngineClass() Autoloaded new instance of 'RedisBuffer'.
2019-07-18 18:36:25.898|INFO|process_processlist.php|power_to_kwh() feedid=16 last_kwh=5739.7680903794 kwh_inc=0.0014666666666667 new_kwh=5739.7695570461 last_time=1563474974 time_now=1563474985
2019-07-18 18:36:25.898|INFO|feed_model.php|insert_data() feedid=16 updatetime=1563474985 feedtime=1563474985 value=5739.7695570461 arg=join
2019-07-18 18:36:25.898|INFO|feed_model.php|insert_data() feedid=19 updatetime=1563474985 feedtime=1563474985 value=69.2 arg=
2019-07-18 18:36:25.898|INFO|feed_model.php|insert_data() feedid=20 updatetime=1563474985 feedtime=1563474985 value=58.1 arg=
2019-07-18 18:36:25.899|INFO|feed_model.php|insert_data() feedid=21 updatetime=1563474985 feedtime=1563474985 value=47 arg=
2019-07-18 18:36:25.899|INFO|feed_model.php|insert_data() feedid=22 updatetime=1563474985 feedtime=1563474985 value=61.8 arg=
2019-07-18 18:36:25.900|INFO|feed_model.php|insert_data() feedid=23 updatetime=1563474985 feedtime=1563474985 value=42.2 arg=
2019-07-18 18:36:25.900|INFO|feed_model.php|insert_data() feedid=24 updatetime=1563474985 feedtime=1563474985 value=62 arg=
2019-07-18 18:36:25.903|INFO|feed_model.php|insert_data() feedid=25 updatetime=1563474985 feedtime=1563474985 value=85 arg=
2019-07-18 18:36:25.904|INFO|feed_model.php|insert_data() feedid=26 updatetime=1563474985 feedtime=1563474985 value=28.1 arg=
2019-07-18 18:36:26.070|INFO|index.php|admin/getlog
2019-07-18 18:36:26.571|INFO|index.php|admin/getlog
I would expect to see more than this including when data is received as an input or am I remembering this wrong?