Tom,

For more than a year my weewx Modbus Energy Monitor driver/service (mben) 
runned without any problems.
The archive interval was set to 60 seconds and the report interval was set 
to 5 minutes.
Configuration: raspberry PI 3B+, raspbian stretch, weewx 3.9.1, python 2.

After I upgraded to weewx 4.0.0b16 with python 3 mben  can't run on time 
anymore.
In an earlier message I already mentioned the numerous debug log messages 
the modbus section produced with 'logging'..
These messages were suppressed in a way you suggested.
The 4.0.0b16 logging below show that mben can't keep up in time even when 
the archive period is set to 300 seconds.
The last log message says:
Mar 25 07:08:00 pi31 mben[484] INFO weewx.manager: Added record 2020-03-25 
06:45:00 -03 (1585129500) to database 'weewx_mben31'
So in one hour the program is already 23 minutes behind.
The only difference in the mben code is the logging which was set to module 
logging instead of syslog for weewx 4.0.0b16.
I can't tell why mben is so much slower in 4.0.0b16. Maybe the logging, 
maybe something else.

I went back today to weewx 3.9.2, python 2 and 60 s archive interval and 
syslog debug messages (instead of logging) and everything is back to normal.

I know, you are not familiar with my mben driver/service and the pymodbus 
package and also I don't expect a solution. 
This message is for your information only.

Luc

====
Mar 25 06:07:01 pi31 mben[484] INFO __main__: Starting up weewx version 
4.0.0b16
Mar 25 06:07:01 pi31 mben[484] INFO weewx.engine: Clock error is -0.16 
seconds (positive is fast)
Mar 25 06:07:01 pi31 mben[484] INFO weewx.engine: Using binding 
'wx_binding' to database 'weewx_mben31'
Mar 25 06:07:01 pi31 mben[484] INFO weewx.manager: Starting backfill of 
daily summaries
Mar 25 06:07:01 pi31 mben[484] INFO weewx.engine: Starting main packet loop.
Mar 25 06:07:02 pi31 cmon[485] INFO weewx.manager: Processed 142 records to 
backfill 1 day summaries in 0.35 seconds
Mar 25 06:07:02 pi31 cmon[485] INFO weewx.engine: Starting main packet loop.
Mar 25 06:07:02 pi31 cmon[485] DEBUG weewx.wxservices: DatabaseError 
'(1054, "Unknown column 'rain' in 'field list'")'
Mar 25 06:08:01 pi31 CRON[823]: (root) CMD 
(/home/weewx/util/init.d/webcam.debian 1> /var/log/webcam.txt 2> 
/var/log/webcam.err)
Mar 25 06:08:03 pi31 mben[484] INFO user.modbusenergy: PTot: 395.4 W,  
ETot: 4987.3 kWh,  ETotEbsBer=68052.5 kWh
Mar 25 06:09:01 pi31 CRON[959]: (root) CMD 
(/home/weewx/util/init.d/webcam.debian 1> /var/log/webcam.txt 2> 
/var/log/webcam.err)
Mar 25 06:09:02 pi31 mben[484] INFO user.modbusenergy: PTot: 396.0 W,  
ETot: 4987.3 kWh,  ETotEbsBer=68052.5 kWh
Mar 25 06:10:01 pi31 CRON[1103]: (root) CMD 
(/home/weewx/util/init.d/webcam.debian 1> /var/log/webcam.txt 2> 
/var/log/webcam.err)
Mar 25 06:10:02 pi31 mben[484] INFO user.modbusenergy: PTot: 396.1 W,  
ETot: 4987.3 kWh,  ETotEbsBer=68052.5 kWh
Mar 25 06:10:23 pi31 cmon[485] INFO weewx.manager: Added record 2020-03-25 
06:10:00 -03 (1585127400) to database 'cmon31'
Mar 25 06:10:23 pi31 cmon[485] INFO weewx.manager: Added record 2020-03-25 
06:10:00 -03 (1585127400) to daily summary in 'cmon31'
Mar 25 06:10:23 pi31 cmon[485] DEBUG weewx.reportengine: Running reports 
for latest time in the database.
Mar 25 06:10:23 pi31 cmon[485] DEBUG weewx.reportengine: Running report 
'cmon31'
Mar 25 06:10:23 pi31 cmon[485] DEBUG weewx.reportengine: Found 
configuration file /home/weewx/skins/cmon31/skin.conf for report 'cmon31'
Mar 25 06:10:23 pi31 cmon[485] DEBUG weewx.cheetahgenerator: Using search 
list ['weewx.cheetahgenerator.Almanac', 'weewx.cheetahgenerator.Station', 
'weewx.cheetahgenerator.Current', 'weewx.cheetahgenerator.Stats', 
'weewx.cheetahgenerator.UnitInfo', 'weewx.cheetahgenerator.Extras']
Mar 25 06:10:23 pi31 cmon[485] DEBUG weewx.manager: Daily summary version 
is 2.0
Mar 25 06:10:24 pi31 cmon[485] INFO weewx.cheetahgenerator: Generated 1 
files for report cmon31 in 0.24 seconds
Mar 25 06:10:24 pi31 cmon[485] DEBUG weewx.manager: Daily summary version 
is 2.0
Mar 25 06:10:30 pi31 cmon[485] INFO weewx.imagegenerator: Generated 5 
images for report cmon31 in 6.10 seconds
Mar 25 06:10:30 pi31 cmon[485] DEBUG weewx.reportengine: Running report 
'FTP'
Mar 25 06:10:30 pi31 cmon[485] DEBUG weewx.reportengine: Found 
configuration file /home/weewx/skins/Ftp/skin.conf for report 'FTP'
Mar 25 06:10:30 pi31 cmon[485] DEBUG weeutil.ftpupload: Attempting 
connection to ftp.lucdesign.nl
Mar 25 06:10:31 pi31 cmon[485] DEBUG weeutil.ftpupload: Connected to 
ftp.lucdesign.nl
Mar 25 06:10:38 pi31 cmon[485] DEBUG weeutil.ftpupload: Uploaded file 
/_weewx/webcam3_v.jpg
Mar 25 06:10:43 pi31 cmon[485] DEBUG weeutil.ftpupload: Uploaded file 
/_weewx/webcam3_l.jpg
Mar 25 06:10:45 pi31 cmon[485] INFO weewx.reportengine: ftpgenerator: Ftp'd 
2 files in 14.65 seconds
Mar 25 06:10:45 pi31 cmon[485] DEBUG weewx.reportengine: Report 'RSYNC' not 
enabled. Skipping.
Mar 25 06:11:01 pi31 CRON[1235]: (root) CMD 
(/home/weewx/util/init.d/webcam.debian 1> /var/log/webcam.txt 2> 
/var/log/webcam.err)
Mar 25 06:11:01 pi31 mben[484] INFO user.modbusenergy: PTot: 397.9 W,  
ETot: 4987.3 kWh,  ETotEbsBer=68052.6 kWh
Mar 25 06:11:58 pi31 mben[484] INFO weewx.manager: Added record 2020-03-25 
06:10:00 -03 (1585127400) to database 'weewx_mben31'
Mar 25 06:11:58 pi31 systemd[1]: Starting Daily apt upgrade and clean 
activities...
Mar 25 06:11:58 pi31 mben[484] INFO weewx.manager: Added record 2020-03-25 
06:10:00 -03 (1585127400) to daily summary in 'weewx_mben31'
Mar 25 06:11:59 pi31 mben[484] INFO weewx.cheetahgenerator: Generated 1 
files for report mben31 in 0.49 seconds
Mar 25 06:12:00 pi31 systemd[1]: apt-daily-upgrade.service: Succeeded.
Mar 25 06:12:00 pi31 systemd[1]: Started Daily apt upgrade and clean 
activities.
Mar 25 06:12:01 pi31 CRON[1413]: (root) CMD 
(/home/weewx/util/init.d/webcam.debian 1> /var/log/webcam.txt 2> 
/var/log/webcam.err)
Mar 25 06:12:17 pi31 mben[484] INFO weewx.imagegenerator: Generated 88 
images for report mben31 in 17.97 seconds
Mar 25 06:13:00 pi31 mben[484] INFO user.modbusenergy: PTot: 398.0 W,  
ETot: 4987.3 kWh,  ETotEbsBer=68052.6 kWh
Mar 25 06:13:02 pi31 CRON[1533]: (root) CMD 
(/home/weewx/util/init.d/webcam.debian 1> /var/log/webcam.txt 2> 
/var/log/webcam.err)
Mar 25 06:13:59 pi31 mben[484] INFO user.modbusenergy: PTot: 398.0 W,  
ETot: 4987.3 kWh,  ETotEbsBer=68052.6 kWh
Mar 25 06:14:01 pi31 CRON[1655]: (root) CMD 
(/home/weewx/util/init.d/webcam.debian 1> /var/log/webcam.txt 2> 
/var/log/webcam.err)
Mar 25 06:14:59 pi31 mben[484] INFO user.modbusenergy: PTot: 392.9 W,  
ETot: 4987.3 kWh,  ETotEbsBer=68052.6 kWh
Mar 25 06:15:01 pi31 CRON[1791]: (root) CMD 
(/home/weewx/util/init.d/webcam.debian 1> /var/log/webcam.txt 2> 
/var/log/webcam.err)
Mar 25 06:15:11 pi31 mben[484] INFO weewx.manager: Added record 2020-03-25 
06:15:11 -03 (1585127711) to database 'mbendevices31'
Mar 25 06:15:11 pi31 mben[484] INFO weewx.manager: Added record 2020-03-25 
06:15:11 -03 (1585127711) to daily summary in 'mbendevices31'
Mar 25 06:15:24 pi31 cmon[485] INFO weewx.manager: Added record 2020-03-25 
06:15:24 -03 (1585127724) to database 'cmon31'
Mar 25 06:15:24 pi31 cmon[485] INFO weewx.manager: Added record 2020-03-25 
06:15:24 -03 (1585127724) to daily summary in 'cmon31'
Mar 25 06:15:24 pi31 cmon[485] INFO weewx.manager: Added record 2020-03-25 
06:15:00 -03 (1585127700) to database 'cmon31'
Mar 25 06:15:25 pi31 cmon[485] INFO weewx.manager: Added record 2020-03-25 
06:15:00 -03 (1585127700) to daily summary in 'cmon31'
Mar 25 06:15:25 pi31 cmon[485] DEBUG weewx.reportengine: Running reports 
for latest time in the database.
Mar 25 06:15:25 pi31 cmon[485] DEBUG weewx.reportengine: Running report 
'cmon31'
Mar 25 06:15:25 pi31 cmon[485] DEBUG weewx.reportengine: Found 
configuration file /home/weewx/skins/cmon31/skin.conf for report 'cmon31'
Mar 25 06:15:25 pi31 cmon[485] DEBUG weewx.cheetahgenerator: Using search 
list ['weewx.cheetahgenerator.Almanac', 'weewx.cheetahgenerator.Station', 
'weewx.cheetahgenerator.Current', 'weewx.cheetahgenerator.Stats', 
'weewx.cheetahgenerator.UnitInfo', 'weewx.cheetahgenerator.Extras']
Mar 25 06:15:25 pi31 cmon[485] DEBUG weewx.manager: Daily summary version 
is 2.0
Mar 25 06:15:25 pi31 cmon[485] INFO weewx.cheetahgenerator: Generated 1 
files for report cmon31 in 0.22 seconds
Mar 25 06:15:25 pi31 cmon[485] DEBUG weewx.manager: Daily summary version 
is 2.0
Mar 25 06:15:25 pi31 cmon[485] INFO weewx.imagegenerator: Generated 0 
images for report cmon31 in 0.13 seconds
Mar 25 06:15:25 pi31 cmon[485] DEBUG weewx.reportengine: Running report 
'FTP'
Mar 25 06:15:25 pi31 cmon[485] DEBUG weewx.reportengine: Found 
configuration file /home/weewx/skins/Ftp/skin.conf for report 'FTP'
Mar 25 06:15:25 pi31 cmon[485] DEBUG weeutil.ftpupload: Attempting 
connection to ftp.lucdesign.nl
Mar 25 06:15:28 pi31 cmon[485] DEBUG weeutil.ftpupload: Connected to 
ftp.lucdesign.nl
Mar 25 06:15:37 pi31 cmon[485] DEBUG weeutil.ftpupload: Uploaded file 
/_weewx/webcam3_v.jpg
Mar 25 06:15:41 pi31 cmon[485] DEBUG weeutil.ftpupload: Uploaded file 
/_weewx/webcam3_l.jpg
Mar 25 06:15:41 pi31 cmon[485] INFO weewx.reportengine: ftpgenerator: Ftp'd 
2 files in 16.03 seconds
Mar 25 06:15:41 pi31 cmon[485] DEBUG weewx.reportengine: Report 'RSYNC' not 
enabled. Skipping.
Mar 25 06:15:59 pi31 mben[484] INFO user.modbusenergy: PTot: 506.7 W,  
ETot: 4987.3 kWh,  ETotEbsBer=68052.6 kWh
Mar 25 06:16:01 pi31 CRON[1946]: (root) CMD 
(/home/weewx/util/init.d/webcam.debian 1> /var/log/webcam.txt 2> 
/var/log/webcam.err)
Mar 25 06:16:58 pi31 mben[484] INFO user.modbusenergy: PTot: 492.4 W,  
ETot: 4987.3 kWh,  ETotEbsBer=68052.6 kWh
Mar 25 06:17:01 pi31 CRON[2080]: (root) CMD 
(/home/weewx/util/init.d/webcam.debian 1> /var/log/webcam.txt 2> 
/var/log/webcam.err)
Mar 25 06:17:01 pi31 CRON[2079]: (root) CMD (   cd / && run-parts --report 
/etc/cron.hourly)
Mar 25 06:17:57 pi31 mben[484] INFO user.modbusenergy: PTot: 486.8 W,  
ETot: 4987.4 kWh,  ETotEbsBer=68052.6 kWh
Mar 25 06:18:01 pi31 CRON[2218]: (root) CMD 
(/home/weewx/util/init.d/webcam.debian 1> /var/log/webcam.txt 2> 
/var/log/webcam.err)
Mar 25 06:18:56 pi31 mben[484] INFO user.modbusenergy: PTot: 494.0 W,  
ETot: 4987.4 kWh,  ETotEbsBer=68052.6 kWh
Mar 25 06:19:01 pi31 CRON[2348]: (root) CMD 
(/home/weewx/util/init.d/webcam.debian 1> /var/log/webcam.txt 2> 
/var/log/webcam.err)
Mar 25 06:19:56 pi31 mben[484] INFO user.modbusenergy: PTot: 503.8 W,  
ETot: 4987.4 kWh,  ETotEbsBer=68052.6 kWh
Mar 25 06:19:56 pi31 mben[484] INFO weewx.manager: Added record 2020-03-25 
06:15:00 -03 (1585127700) to database 'weewx_mben31'
Mar 25 06:19:57 pi31 mben[484] INFO weewx.manager: Added record 2020-03-25 
06:15:00 -03 (1585127700) to daily summary in 'weewx_mben31'
...
Mar 25 06:27:56 pi31 mben[484] INFO weewx.manager: Added record 2020-03-25 
06:20:00 -03 (1585128000) to database 'weewx_mben31'
Mar 25 06:27:56 pi31 mben[484] INFO weewx.manager: Added record 2020-03-25 
06:20:00 -03 (1585128000) to daily summary in 'weewx_mben31'
...
Mar 25 06:35:56 pi31 mben[484] INFO weewx.manager: Added record 2020-03-25 
06:25:00 -03 (1585128300) to database 'weewx_mben31'
Mar 25 06:35:57 pi31 mben[484] INFO weewx.manager: Added record 2020-03-25 
06:25:00 -03 (1585128300) to daily summary in 'weewx_mben31'
...
Mar 25 06:43:55 pi31 mben[484] INFO weewx.manager: Added record 2020-03-25 
06:30:00 -03 (1585128600) to database 'weewx_mben31'
Mar 25 06:43:56 pi31 mben[484] INFO weewx.manager: Added record 2020-03-25 
06:30:00 -03 (1585128600) to daily summary in 'weewx_mben31'
...
Mar 25 06:51:56 pi31 mben[484] INFO weewx.manager: Added record 2020-03-25 
06:35:00 -03 (1585128900) to database 'weewx_mben31'
Mar 25 06:51:56 pi31 mben[484] INFO weewx.manager: Added record 2020-03-25 
06:35:00 -03 (1585128900) to daily summary in 'weewx_mben31'
..
Mar 25 06:59:56 pi31 mben[484] INFO weewx.manager: Added record 2020-03-25 
06:40:00 -03 (1585129200) to database 'weewx_mben31'
Mar 25 06:59:57 pi31 mben[484] INFO weewx.manager: Added record 2020-03-25 
06:40:00 -03 (1585129200) to daily summary in 'weewx_mben31'
...
Mar 25 07:08:00 pi31 mben[484] INFO weewx.manager: Added record 2020-03-25 
06:45:00 -03 (1585129500) to database 'weewx_mben31'
Mar 25 07:08:01 pi31 mben[484] INFO weewx.manager: Added record 2020-03-25 
06:45:00 -03 (1585129500) to daily summary in 'weewx_mben31'
.====

-- 
You received this message because you are subscribed to the Google Groups 
"weewx-development" group.
To unsubscribe from this group and stop receiving emails from it, send an email 
to weewx-development+unsubscr...@googlegroups.com.
To view this discussion on the web visit 
https://groups.google.com/d/msgid/weewx-development/63268133-e650-4a61-8200-319a3c0e121e%40googlegroups.com.

Reply via email to