Timers in my log
-
Can you also look and see if there's a console.log file in your logs directory, and if it has any messages, and especially, rolling error messages at the same pace as the timer messages?
-
OK... in taking a second look at the logs, no two condition IDs are the same. You seem to use date/time conditions a lot?
So is this happening only when the Engine starts, or is it continuous in the logs?
Edit: FYI, these messages are at log level "always" from previous debugging, so I would expect a lot of them at engine startup, but once the engine starts, then really, it should quiet down to only when a timer expires and is rescheduled. And these may be the only messages you have. I guess I assumed they were rolling, but the log snippet you posted doesn't show that, so I need a better picture of when this is happening, and what's actually going on. I don't see email yet with the log file.
-
OK I need that log file, then.
-
OK... in taking a second look at the logs, no two condition IDs are the same. You seem to use date/time conditions a lot?
So is this happening only when the Engine starts, or is it continuous in the logs?
Edit: FYI, these messages are at log level "always" from previous debugging, so I would expect a lot of them at engine startup, but once the engine starts, then really, it should quiet down to only when a timer expires and is rescheduled. And these may be the only messages you have. I guess I assumed they were rolling, but the log snippet you posted doesn't show that, so I need a better picture of when this is happening, and what's actually going on. I don't see email yet with the log file.
@toggledbits said in Timers in my log:
... You seem to use date/time conditions a lot? ...
I change it every room individually. With lights sensors, time and sunrise/sunset conditions.... "or is it continuous in the logs?" ...
Yes, it is continuous... "it should quiet down to only when a timer expires and is rescheduled" ...
I don't see that in the logs -
"You seem to use date/time conditions a lot"
I live in Sweden were light changes dramatically over the year and we have sun from the east and darkness in the west in the morning and the opposite in the evening. After a few seasons I realize I had to change it individually for each room. Works perfectly in MSR, I use a SSD to my Pi so if the log is large it could probably handle it
Due to this perhaps it is normal and I could move on but @kfxo signaled something similar. -
OK. Non-problem. Everything is fine. These are just debug messages from my previous investigation into scheduling of re-evaluations on time-based rules (PR #34 and 44 specifically). For that, I used the "always" log level (hence the "null" reported where the level goes in the logged messages). This is just normal start-up. When you start Reactor and it brings up the Engine, it reads all the rules, does an initial scan for dependencies, then evaluates all the conditions, which sets the timers for the next event based on current (post-startup) conditions. All is well.
The logs do not show they are rolling. If you had a continuous stream of timer messages, that would be noteworthy, but getting a timer messages for every time condition in any rule is expected at start-up. If there were further timer messages at unexpected time, the logs provided don't show that.
-
I'll put a proper level on that debug now that I'm not targeting that area as well. That will keep the log chatter down.
-
Not yet, but it's on its way. Definitely showing promise. Thanks for your help is making it work!
-
T toggledbits locked this topic on
