Hi,
I'm running version 4.5.1, with the Interceptor set in 'ecowitt-client'.
I've had a long time issue with reports stopping, and have now caught one.
The log file snippet is shown below, but I'm at a loss to explain what's happening, as there's no error message, just a strange behavior.
====================== weewx.log =========================
Apr 14 13:00:50 pete weewx[126266] message repeated 2 times: [ DEBUG user.interceptor: empty queue]
Apr 14 13:00:52 pete weewx[126266] DEBUG user.interceptor: POST: PASSKEY=XXXX&stationtype=EasyWeatherV1.5.6&dateutc=2021-04-14+02:59:56&tempinf=77.2&humidityin=54&baromrelin=29.968&baromabsin=30.024&tempf=78.8&humidity=53&winddir=94&windspeedmph=2.0&windgustmph=3.4&rainratein=0.000&eventrainin=0.000&dailyrainin=0.000&weeklyrainin=0.000&monthlyrainin=5.780&totalrainin=141.402&solarradiation=721.41&uv=8&model=WS2900
Apr 14 13:00:52 pete weewx[126266] DEBUG user.interceptor: raw data: PASSKEY=XXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXX&stationtype=EasyWeatherV1.5.6&dateutc=2021-04-14+02:59:56&tempinf=77.2&humidityin=54&baromrelin=29.968&baromabsin=30.024&tempf=78.8&humidity=53&winddir=94&windspeedmph=2.0&windgustmph=3.4&rainratein=0.000&eventrainin=0.000&dailyrainin=0.000&weeklyrainin=0.000&monthlyrainin=5.780&totalrainin=141.402&solarradiation=721.41&uv=8&model=WS2900
Apr 14 13:00:52 pete weewx[126266] DEBUG user.interceptor: ignored parameter PASSKEY=XXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXX
Apr 14 13:00:52 pete weewx[126266] DEBUG user.interceptor: ignored parameter stationtype=EasyWeatherV1.5.6
Apr 14 13:00:52 pete weewx[126266] DEBUG user.interceptor: ignored parameter baromrelin=29.968
Apr 14 13:00:52 pete weewx[126266] DEBUG user.interceptor: ignored parameter eventrainin=0.000
Apr 14 13:00:52 pete weewx[126266] DEBUG user.interceptor: ignored parameter dailyrainin=0.000
Apr 14 13:00:52 pete weewx[126266] DEBUG user.interceptor: ignored parameter weeklyrainin=0.000
Apr 14 13:00:52 pete weewx[126266] DEBUG user.interceptor: ignored parameter monthlyrainin=5.780
Apr 14 13:00:52 pete weewx[126266] DEBUG user.interceptor: ignored parameter model=WS2900
Apr 14 13:00:52 pete weewx[126266] DEBUG user.interceptor: raw packet: {'dateTime': 1618369196, 'usUnits': 1, 'temperature_in': 77.2, 'humidity_in': 54.0, 'pressure': 30.024, 'temperature_out': 78.8, 'humidity_out': 53.0, 'wind_dir': 94.0, 'wind_speed': 2.0, 'wind_gust': 3.4, 'rain_rate': 0.0, 'rain_total': 141.402, 'solar_radiation': 721.41, 'uv': 8.0, 'rain': 0.0}
Apr 14 13:00:52 pete weewx[126266] DEBUG user.interceptor: mapped packet: {'dateTime': 1618369196, 'usUnits': 1, 'pressure': 30.024, 'outHumidity': 53.0, 'inHumidity': 54.0, 'outTemp': 78.8, 'inTemp': 77.2, 'windSpeed': 2.0, 'windGust': 3.4, 'windDir': 94.0, 'radiation': 721.41, 'rain': 0.0, 'rainRate': 0.0, 'UV': 8.0}
Apr 14 13:01:02 pete weewx[126266] DEBUG user.interceptor: empty queue
Apr 14 13:01:22 pete weewx[126266] message repeated 2 times: [ DEBUG user.interceptor: empty queue]
Apr 14 13:01:24 pete weewx[126266] DEBUG user.interceptor: POST: PASSKEY=XXXX&stationtype=EasyWeatherV1.5.6&dateutc=2021-04-14+03:00:56&tempinf=77.2&humidityin=54&baromrelin=29.968&baromabsin=30.024&tempf=78.8&humidity=53&winddir=139&windspeedmph=1.6&windgustmph=2.2&rainratein=0.000&eventrainin=0.000&dailyrainin=0.000&weeklyrainin=0.000&monthlyrainin=5.780&totalrainin=141.402&solarradiation=715.79&uv=8&model=WS2900
Apr 14 13:01:24 pete weewx[126266] DEBUG user.interceptor: raw data: PASSKEY=XXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXX&stationtype=EasyWeatherV1.5.6&dateutc=2021-04-14+03:00:56&tempinf=77.2&humidityin=54&baromrelin=29.968&baromabsin=30.024&tempf=78.8&humidity=53&winddir=139&windspeedmph=1.6&windgustmph=2.2&rainratein=0.000&eventrainin=0.000&dailyrainin=0.000&weeklyrainin=0.000&monthlyrainin=5.780&totalrainin=141.402&solarradiation=715.79&uv=8&model=WS2900
Apr 14 13:01:24 pete weewx[126266] DEBUG user.interceptor: ignored parameter PASSKEY=XXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXX
Apr 14 13:01:24 pete weewx[126266] DEBUG user.interceptor: ignored parameter stationtype=EasyWeatherV1.5.6
Apr 14 13:01:24 pete weewx[126266] DEBUG user.interceptor: ignored parameter baromrelin=29.968
Apr 14 13:01:24 pete weewx[126266] DEBUG user.interceptor: ignored parameter eventrainin=0.000
Apr 14 13:01:24 pete weewx[126266] DEBUG user.interceptor: ignored parameter dailyrainin=0.000
Apr 14 13:01:24 pete weewx[126266] DEBUG user.interceptor: ignored parameter weeklyrainin=0.000
Apr 14 13:01:24 pete weewx[126266] DEBUG user.interceptor: ignored parameter monthlyrainin=5.780
Apr 14 13:01:24 pete weewx[126266] DEBUG user.interceptor: ignored parameter model=WS2900
Apr 14 13:01:24 pete weewx[126266] DEBUG user.interceptor: raw packet: {'dateTime': 1618369256, 'usUnits': 1, 'temperature_in': 77.2, 'humidity_in': 54.0, 'pressure': 30.024, 'temperature_out': 78.8, 'humidity_out': 53.0, 'wind_dir': 139.0, 'wind_speed': 1.6, 'wind_gust': 2.2, 'rain_rate': 0.0, 'rain_total': 141.402, 'solar_radiation': 715.79, 'uv': 8.0, 'rain': 0.0}
Apr 14 13:01:24 pete weewx[126266] DEBUG user.interceptor: mapped packet: {'dateTime': 1618369256, 'usUnits': 1, 'pressure': 30.024, 'outHumidity': 53.0, 'inHumidity': 54.0, 'outTemp': 78.8, 'inTemp': 77.2, 'windSpeed': 1.6, 'windGust': 2.2, 'windDir': 139.0, 'radiation': 715.79, 'rain': 0.0, 'rainRate': 0.0, 'UV': 8.0}
Apr 14 13:01:24 pete weewx[126266] INFO weewx.manager: Added record 2021-04-14 13:00:00 AEST (1618369200) to database 'weewx'
Apr 14 13:01:24 pete weewx[126266] INFO weewx.manager: Added record 2021-04-14 13:00:00 AEST (1618369200) to daily summary in 'weewx'
Apr 14 13:01:24 pete weewx[126266] DEBUG weewx.reportengine: Running reports for latest time in the database.
Apr 14 13:01:24 pete weewx[126266] DEBUG weewx.reportengine: Running report 'SeasonsReport'
Apr 14 13:01:24 pete weewx[126266] DEBUG weewx.reportengine: Found configuration file /etc/weewx/skins/Seasons/skin.conf for report 'SeasonsReport'
Apr 14 13:01:24 pete weewx[126266] DEBUG weewx.cheetahgenerator: Using search list ['weewx.cheetahgenerator.Almanac', 'weewx.cheetahgenerator.Station', 'weewx.cheetahgenerator.Current', 'weewx.cheetahgenerator.Stats', 'weewx.cheetahgenerator.UnitInfo', 'weewx.cheetahgenerator.Extras', 'weewx.cheetahgenerator.JSONHelpers']
Apr 14 13:01:24 pete weewx[126266] INFO weewx.restx: MQTT: Published record 2021-04-14 13:00:00 AEST (1618369200)
Apr 14 13:01:24 pete weewx[126266] DEBUG weewx.manager: Daily summary version is 4.0
Apr 14 13:01:25 pete weewx[126266] INFO weewx.restx: OWM: Published record 2021-04-14 13:00:00 AEST (1618369200)
Apr 14 13:01:25 pete weewx[126266] INFO weewx.cheetahgenerator: Generated 8 files for report SeasonsReport in 0.89 seconds
Apr 14 13:01:25 pete weewx[126266] DEBUG weewx.manager: Daily summary version is 4.0
Apr 14 13:01:27 pete weewx[126266] INFO weewx.imagegenerator: Generated 30 images for report SeasonsReport in 2.27 seconds
Apr 14 13:01:27 pete weewx[126266] INFO weewx.reportengine: Copied 0 files to /var/www/html/weewx
Apr 14 13:01:27 pete weewx[126266] DEBUG weewx.reportengine: Running report 'SmartphoneReport'
Apr 14 13:01:27 pete weewx[126266] DEBUG weewx.reportengine: Found configuration file /etc/weewx/skins/Smartphone/skin.conf for report 'SmartphoneReport'
Apr 14 13:01:27 pete weewx[126266] DEBUG weewx.cheetahgenerator: Using search list ['weewx.cheetahgenerator.Almanac', 'weewx.cheetahgenerator.Station', 'weewx.cheetahgenerator.Current', 'weewx.cheetahgenerator.Stats', 'weewx.cheetahgenerator.UnitInfo', 'weewx.cheetahgenerator.Extras', 'weewx.cheetahgenerator.JSONHelpers']
Apr 14 13:01:27 pete weewx[126266] DEBUG weewx.manager: Daily summary version is 4.0
Apr 14 13:01:27 pete weewx[126266] INFO weewx.cheetahgenerator: Generated 6 files for report SmartphoneReport in 0.04 seconds
Apr 14 13:01:27 pete weewx[126266] DEBUG weewx.manager: Daily summary version is 4.0
Apr 14 13:01:28 pete weewx[126266] INFO weewx.imagegenerator: Generated 12 images for report SmartphoneReport in 0.33 seconds
Apr 14 13:01:28 pete weewx[126266] INFO weewx.reportengine: Copied 0 files to /var/www/html/weewx/smartphone
Apr 14 13:01:28 pete weewx[126266] DEBUG weewx.reportengine: Report 'MobileReport' not enabled. Skipping.
Apr 14 13:01:28 pete weewx[126266] DEBUG weewx.reportengine: Report 'StandardReport' not enabled. Skipping.
Apr 14 13:01:28 pete weewx[126266] DEBUG weewx.reportengine: Report 'FTP' not enabled. Skipping.
Apr 14 13:01:28 pete weewx[126266] DEBUG weewx.reportengine: Report 'RSYNC' not enabled. Skipping.
Apr 14 13:01:34 pete weewx[126266] DEBUG user.interceptor: empty queue
Apr 14 13:01:54 pete weewx[126266] message repeated 2 times: [ DEBUG user.interceptor: empty queue]
Apr 14 13:01:57 pete weewx[126266] DEBUG user.interceptor: POST: PASSKEY=XXXX&stationtype=EasyWeatherV1.5.6&dateutc=2021-04-14+12:00:00&tempinf=77.2&humidityin=54&baromrelin=29.962&baromabsin=30.018&tempf=78.8&humidity=55&winddir=81&windspeedmph=2.5&windgustmph=3.4&rainratein=0.000&eventrainin=0.000&dailyrainin=0.000&weeklyrainin=0.000&monthlyrainin=5.780&totalrainin=141.402&solarradiation=711.38&uv=8&model=WS2900
Apr 14 13:01:57 pete weewx[126266] DEBUG user.interceptor: raw data: PASSKEY=XXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXX&stationtype=EasyWeatherV1.5.6&dateutc=2021-04-14+12:00:00&tempinf=77.2&humidityin=54&baromrelin=29.962&baromabsin=30.018&tempf=78.8&humidity=55&winddir=81&windspeedmph=2.5&windgustmph=3.4&rainratein=0.000&eventrainin=0.000&dailyrainin=0.000&weeklyrainin=0.000&monthlyrainin=5.780&totalrainin=141.402&solarradiation=711.38&uv=8&model=WS2900
Apr 14 13:01:57 pete weewx[126266] DEBUG user.interceptor: ignored parameter PASSKEY=XXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXX
Apr 14 13:01:57 pete weewx[126266] DEBUG user.interceptor: ignored parameter stationtype=EasyWeatherV1.5.6
Apr 14 13:01:57 pete weewx[126266] DEBUG user.interceptor: ignored parameter baromrelin=29.962
Apr 14 13:01:57 pete weewx[126266] DEBUG user.interceptor: ignored parameter eventrainin=0.000
Apr 14 13:01:57 pete weewx[126266] DEBUG user.interceptor: ignored parameter dailyrainin=0.000
Apr 14 13:01:57 pete weewx[126266] DEBUG user.interceptor: ignored parameter weeklyrainin=0.000
Apr 14 13:01:57 pete weewx[126266] DEBUG user.interceptor: ignored parameter monthlyrainin=5.780
Apr 14 13:01:57 pete weewx[126266] DEBUG user.interceptor: ignored parameter model=WS2900
Apr 14 13:01:57 pete weewx[126266] DEBUG user.interceptor: raw packet: {'dateTime': 1618401600, 'usUnits': 1, 'temperature_in': 77.2, 'humidity_in': 54.0, 'pressure': 30.018, 'temperature_out': 78.8, 'humidity_out': 55.0, 'wind_dir': 81.0, 'wind_speed': 2.5, 'wind_gust': 3.4, 'rain_rate': 0.0, 'rain_total': 141.402, 'solar_radiation': 711.38, 'uv': 8.0, 'rain': 0.0}
Apr 14 13:01:57 pete weewx[126266] DEBUG user.interceptor: mapped packet: {'dateTime': 1618401600, 'usUnits': 1, 'pressure': 30.018, 'outHumidity': 55.0, 'inHumidity': 54.0, 'outTemp': 78.8, 'inTemp': 77.2, 'windSpeed': 2.5, 'windGust': 3.4, 'windDir': 81.0, 'radiation': 711.38, 'rain': 0.0, 'rainRate': 0.0, 'UV': 8.0}
Apr 14 13:01:57 pete weewx[126266] INFO weewx.manager: Added record 2021-04-14 13:05:00 AEST (1618369500) to database 'weewx'
Apr 14 13:01:57 pete weewx[126266] INFO weewx.manager: Added record 2021-04-14 13:05:00 AEST (1618369500) to daily summary in 'weewx'
Apr 14 13:01:57 pete weewx[126266] DEBUG weewx.reportengine: Running reports for latest time in the database.
Apr 14 13:01:57 pete weewx[126266] DEBUG weewx.reportengine: Running report 'SeasonsReport'
Apr 14 13:01:57 pete weewx[126266] DEBUG weewx.reportengine: Found configuration file /etc/weewx/skins/Seasons/skin.conf for report 'SeasonsReport'
Apr 14 13:01:57 pete weewx[126266] DEBUG weewx.cheetahgenerator: Using search list ['weewx.cheetahgenerator.Almanac', 'weewx.cheetahgenerator.Station', 'weewx.cheetahgenerator.Current', 'weewx.cheetahgenerator.Stats', 'weewx.cheetahgenerator.UnitInfo', 'weewx.cheetahgenerator.Extras', 'weewx.cheetahgenerator.JSONHelpers']
Apr 14 13:01:57 pete weewx[126266] DEBUG weewx.manager: Daily summary version is 4.0
Apr 14 13:01:57 pete weewx[126266] INFO weewx.restx: MQTT: Published record 2021-04-14 13:05:00 AEST (1618369500)
Apr 14 13:01:57 pete weewx[126266] INFO weewx.restx: OWM: Published record 2021-04-14 13:05:00 AEST (1618369500)
Apr 14 13:01:58 pete weewx[126266] INFO weewx.cheetahgenerator: Generated 8 files for report SeasonsReport in 0.87 seconds
Apr 14 13:01:58 pete weewx[126266] DEBUG weewx.manager: Daily summary version is 4.0
Apr 14 13:01:58 pete weewx[126266] INFO weewx.imagegenerator: Generated 15 images for report SeasonsReport in 0.29 seconds
Apr 14 13:01:58 pete weewx[126266] INFO weewx.reportengine: Copied 0 files to /var/www/html/weewx
Apr 14 13:01:58 pete weewx[126266] DEBUG weewx.reportengine: Running report 'SmartphoneReport'
Apr 14 13:01:58 pete weewx[126266] DEBUG weewx.reportengine: Found configuration file /etc/weewx/skins/Smartphone/skin.conf for report 'SmartphoneReport'
Apr 14 13:01:58 pete weewx[126266] DEBUG weewx.cheetahgenerator: Using search list ['weewx.cheetahgenerator.Almanac', 'weewx.cheetahgenerator.Station', 'weewx.cheetahgenerator.Current', 'weewx.cheetahgenerator.Stats', 'weewx.cheetahgenerator.UnitInfo', 'weewx.cheetahgenerator.Extras', 'weewx.cheetahgenerator.JSONHelpers']
Apr 14 13:01:58 pete weewx[126266] DEBUG weewx.manager: Daily summary version is 4.0
Apr 14 13:01:58 pete weewx[126266] INFO weewx.cheetahgenerator: Generated 6 files for report SmartphoneReport in 0.04 seconds
Apr 14 13:01:58 pete weewx[126266] DEBUG weewx.manager: Daily summary version is 4.0
Apr 14 13:01:58 pete weewx[126266] INFO weewx.imagegenerator: Generated 6 images for report SmartphoneReport in 0.06 seconds
Apr 14 13:01:58 pete weewx[126266] INFO weewx.reportengine: Copied 0 files to /var/www/html/weewx/smartphone
Apr 14 13:01:58 pete weewx[126266] DEBUG weewx.reportengine: Report 'MobileReport' not enabled. Skipping.
Apr 14 13:01:58 pete weewx[126266] DEBUG weewx.reportengine: Report 'StandardReport' not enabled. Skipping.
Apr 14 13:01:58 pete weewx[126266] DEBUG weewx.reportengine: Report 'FTP' not enabled. Skipping.
Apr 14 13:01:58 pete weewx[126266] DEBUG weewx.reportengine: Report 'RSYNC' not enabled. Skipping.
Apr 14 13:02:07 pete weewx[126266] DEBUG user.interceptor: empty queue
Apr 14 13:02:27 pete weewx[126266] message repeated 2 times: [ DEBUG user.interceptor: empty queue]
Apr 14 13:02:28 pete weewx[126266] DEBUG user.interceptor: POST: PASSKEY=XXXX&stationtype=EasyWeatherV1.5.6&dateutc=2021-04-14+03:01:56&tempinf=77.2&humidityin=54&baromrelin=29.962&baromabsin=30.018&tempf=78.6&humidity=55&winddir=68&windspeedmph=2.2&windgustmph=3.4&rainratein=0.000&eventrainin=0.000&dailyrainin=0.000&weeklyrainin=0.000&monthlyrainin=5.780&totalrainin=141.402&solarradiation=711.38&uv=8&model=WS2900
Apr 14 13:02:28 pete weewx[126266] DEBUG user.interceptor: raw data: PASSKEY=XXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXX&stationtype=EasyWeatherV1.5.6&dateutc=2021-04-14+03:01:56&tempinf=77.2&humidityin=54&baromrelin=29.962&baromabsin=30.018&tempf=78.6&humidity=55&winddir=68&windspeedmph=2.2&windgustmph=3.4&rainratein=0.000&eventrainin=0.000&dailyrainin=0.000&weeklyrainin=0.000&monthlyrainin=5.780&totalrainin=141.402&solarradiation=711.38&uv=8&model=WS2900
Apr 14 13:02:28 pete weewx[126266] DEBUG user.interceptor: ignored parameter PASSKEY=XXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXX
Apr 14 13:02:28 pete weewx[126266] DEBUG user.interceptor: ignored parameter stationtype=EasyWeatherV1.5.6
Apr 14 13:02:28 pete weewx[126266] DEBUG user.interceptor: ignored parameter baromrelin=29.962
Apr 14 13:02:28 pete weewx[126266] DEBUG user.interceptor: ignored parameter eventrainin=0.000
Apr 14 13:02:28 pete weewx[126266] DEBUG user.interceptor: ignored parameter dailyrainin=0.000
Apr 14 13:02:28 pete weewx[126266] DEBUG user.interceptor: ignored parameter weeklyrainin=0.000
Apr 14 13:02:28 pete weewx[126266] DEBUG user.interceptor: ignored parameter monthlyrainin=5.780
Apr 14 13:02:28 pete weewx[126266] DEBUG user.interceptor: ignored parameter model=WS2900
Apr 14 13:02:28 pete weewx[126266] DEBUG user.interceptor: raw packet: {'dateTime': 1618369316, 'usUnits': 1, 'temperature_in': 77.2, 'humidity_in': 54.0, 'pressure': 30.018, 'temperature_out': 78.6, 'humidity_out': 55.0, 'wind_dir': 68.0, 'wind_speed': 2.2, 'wind_gust': 3.4, 'rain_rate': 0.0, 'rain_total': 141.402, 'solar_radiation': 711.38, 'uv': 8.0, 'rain': 0.0}
Apr 14 13:02:28 pete weewx[126266] DEBUG user.interceptor: mapped packet: {'dateTime': 1618369316, 'usUnits': 1, 'pressure': 30.018, 'outHumidity': 55.0, 'inHumidity': 54.0, 'outTemp': 78.6, 'inTemp': 77.2, 'windSpeed': 2.2, 'windGust': 3.4, 'windDir': 68.0, 'radiation': 711.38, 'rain': 0.0, 'rainRate': 0.0, 'UV': 8.0}
Apr 14 13:02:38 pete weewx[126266] DEBUG user.interceptor: empty queue
Apr 14 13:02:58 pete weewx[126266] message repeated 2 times: [ DEBUG user.interceptor: empty queue]
=========================== end ========================
The behavior is that weewx seems to generate two reports is a short interval, generally just after the hour, and then stops talking until restarted. Once restarted, weewx will continue reporting for an hour, then stop again until the next (now automated) restart.
Once this cycle begins, the only medium term fix is to restart the VM that's running weewx.
I've noticed now that the interceptor sends a packet with a shonky timestamp just before the second report. Maybe that's the cause. The interceptor seems to collect it's wits and continues with good timestamps after the problem packet. The reporter however, never recovers.
Hopefully this is enough to let someone give me pointers to where to look next.
regards,
Russell C