4.10 Unable to run daemon from init.d script (Debian bullseye)

195 views
Skip to first unread message

phxroa...@gmail.com

unread,
Feb 9, 2023, 10:26:57 PM2/9/23
to weewx-user
This is a fresh install of weewx 4.10 on a Raspberry Pi running Debian bullseye. I'd had weewx 3.1.running since c.2015 on an older Pi with a much older Debian, and finally upgraded everything to current releases.

If I run the executable (weewxd) from the shell in the foreground, it runs fine and does everything it's supposed to do. If I try to run it as "sudo /etc/init.d/weewx start" it never starts. The pid did not change since the foreground invocation, so weewxd itself never got started.

I'm at a loss for how to investigate this. I've tried running "sudo sh -x /etc/init.d/weewx start" to see what the script is doing, but it's somewhat complicated and not obvious why it's doing what it's doing. I'm hoping someone can help with this as my only other option is to start it manually after every power outage or other interruption. Kinda gross haha.

If it helps I can post whatever log files would be helpful.

vince

unread,
Feb 9, 2023, 11:19:54 PM2/9/23
to weewx-user
On Thursday, February 9, 2023 at 7:26:57 PM UTC-8 phxroa...@gmail.com wrote:
If it helps I can post whatever log files would be helpful.

We'd need to know if you did a dpkg installation or a setup.py installation.

It always helps to post log files.

Try "systemctl status weewx" and see what it says for starters.
 

phxroa...@gmail.com

unread,
Feb 11, 2023, 2:30:36 PM2/11/23
to weewx-user
First, the shell output trying to use /etc/init.d/weewx start:

###############################################
pi@raspberrypi:~ $ sudo sh -x /etc/init.d/weewx start
+ PATH=/sbin:/usr/sbin:/bin:/usr/bin
+ DESC=weewx weather system
+ NAME=weewx
+ WEEWX_BIN=/home/weewx/bin/weewxd
+ WEEWX_CFG=/home/weewx/weewx.conf
+ WEEWX_PID=/var/run/weewx.pid
+ WEEWX_USER=root
+ [ -r /etc/default/weewx ]
+ . /etc/default/weewx
+ WEEWX_PYTHON=python3
+ WEEWX_PYTHON_ARGS=
+ WEEWX_BINDIR=/usr/share/weewx
+ WEEWX_BIN=/usr/bin/weewxd
+ WEEWX_CFG=/etc/weewx/weewx.conf
+ [ -x /usr/bin/weewxd ]
+ DAEMON=/usr/bin/weewxd
+ DAEMON_ARGS=--daemon --pidfile=/var/run/weewx.pid /etc/weewx/weewx.conf
+ . /lib/init/vars.sh
+ TMPTIME=0
+ SULOGIN=no
+ DELAYLOGIN=no
+ UTC=yes
+ VERBOSE=no
+ FSCKFIX=no
+ [ -f /etc/default/rcS ]
+ unset EDITMOTD
+ unset RAMRUN
+ unset RAMLOCK
+ [ -r /proc/cmdline ]
+ cat /proc/cmdline
+ [  ]
+ [  ]
+ . /lib/lsb/init-functions
+ run-parts --lsbsysinit --list /lib/lsb/init-functions.d
+ [ -r /lib/lsb/init-functions.d/00-verbose ]
+ . /lib/lsb/init-functions.d/00-verbose
+ [ -r /lib/lsb/init-functions.d/40-systemd ]
+ . /lib/lsb/init-functions.d/40-systemd
+ _use_systemctl=0
+ [ -d /run/systemd/system ]
+ [ -n  ]
+ [ weewx = init-d-script ]
+ [ weewx = start ]
+ executable=/etc/init.d/weewx
+ argument=start
+ prog=weewx
+ service=weewx.service
+ systemctl -p LoadState --value show weewx.service
+ state=loaded
+ [ loaded = masked ]
+ [ 7437 -ne 1 ]
+ [ -z  ]
+ readlink -f /etc/init.d/weewx
+ [ loaded != not-found ]
+ _use_systemctl=1
+ systemctl -p CanReload --value show weewx.service
+ [ yes = no ]
+ [ 1 = 1 ]
+ set +e
+ set +u
+ systemctl_redirect /etc/init.d/weewx start
+ local s
+ local rc
+ local prog=weewx
+ local command=start
+ s=Starting weewx (via systemctl)
+ service=weewx.service
+ systemctl --quiet is-system-running
+ [ start = status ]
+ log_daemon_msg Starting weewx (via systemctl) weewx.service
+ [ -z Starting weewx (via systemctl) ]
+ log_daemon_msg_pre Starting weewx (via systemctl) weewx.service
+ :
+ [ -z weewx.service ]
+ echo -n Starting weewx (via systemctl): weewx.service
Starting weewx (via systemctl): weewx.service+ log_daemon_msg_post Starting weewx (via systemctl) weewx.service
+ :
+ systemctl --no-pager start weewx.service
+ rc=0
+ [ start = status ]
+ log_end_msg 0
+ [ -z 0 ]
+ local retval
+ retval=0
+ log_end_msg_pre 0
+ :
+ log_use_fancy_output
+ TPUT=/usr/bin/tput
+ EXPR=/usr/bin/expr
+ [ -t 1 ]
+ [ xxterm != x ]
+ [ xxterm != xdumb ]
+ [ -x /usr/bin/tput ]
+ [ -x /usr/bin/expr ]
+ /usr/bin/tput hpa 60
+ /usr/bin/tput setaf 1
+ [ -z ]
+ FANCYTTY=1
+ true
+ /usr/bin/tput setaf 1
+ RED=
+ /usr/bin/tput setaf 3
+ YELLOW=
+ /usr/bin/tput op
+ NORMAL=
+ [ 0 -eq 0 ]
+ echo .
.
+ log_end_msg_post 0
+ :
+ return 0
+ return 0
+ exit 0
pi@raspberrypi:~ $
###############################################
It looks like part way through the script it eventually tries to run systemctl to start the service.

weewx was installed with:
sudo apt-get install weewx

The installation script _does_ run weewxd, but of course the distribution config file (/etc/weewx/weewx.conf) isn't right for my hardware. It's at this point I try to exercise the stop & start methods after editing weewx.conf.

systemctl status gives this. The errors listed at the end seem to be left over from trying to copy the 3.1 weewx.conf lat/lon syntax into the current file (current config file seems to use separate lat & lon lines instead).
###############################################
pi@raspberrypi:~ $ sudo systemctl status weewx
weewx.service - LSB: weewx weather system
     Loaded: loaded (/etc/init.d/weewx; generated)
     Active: active (exited) since Thu 2023-02-09 19:38:04 MST; 1 day 16h ago
       Docs: man:systemd-sysv-generator(8)
    Process: 4056 ExecStart=/etc/init.d/weewx start (code=exited, status=0/SUCCESS)
        CPU: 575ms

Feb 09 19:38:10 raspberrypi python3[4070]: weewx[4070] CRITICAL __main__:     ****  could not convert string to float: '34.945 -1>
Feb 09 19:38:10 raspberrypi python3[4070]: weewx[4070] CRITICAL __main__:     ****  Traceback (most recent call last):
Feb 09 19:38:10 raspberrypi python3[4070]: weewx[4070] CRITICAL __main__:     ****    File "/usr/share/weewx/weewxd", line 148, i>
Feb 09 19:38:10 raspberrypi python3[4070]: weewx[4070] CRITICAL __main__:     ****      engine = weewx.engine.StdEngine(config_di>
Feb 09 19:38:10 raspberrypi python3[4070]: weewx[4070] CRITICAL __main__:     ****    File "/usr/share/weewx/weewx/engine.py", li>
Feb 09 19:38:10 raspberrypi python3[4070]: weewx[4070] CRITICAL __main__:     ****      self.stn_info = weewx.station.StationInfo>
Feb 09 19:38:10 raspberrypi python3[4070]: weewx[4070] CRITICAL __main__:     ****    File "/usr/share/weewx/weewx/station.py", l>
Feb 09 19:38:10 raspberrypi python3[4070]: weewx[4070] CRITICAL __main__:     ****      self.latitude_f      = float(stn_dict['la>
Feb 09 19:38:10 raspberrypi python3[4070]: weewx[4070] CRITICAL __main__:     ****  ValueError: could not convert string to float>
Feb 09 19:38:10 raspberrypi python3[4070]: weewx[4070] CRITICAL __main__:     ****  Exiting.
###############################################
Trying to start it with systemctl gives no messages (also tried w/o --no-pager option):
sudo systemctl --no-pager start weewx.service

Nothing in /var/log/syslog, either.

vince

unread,
Feb 11, 2023, 2:49:22 PM2/11/23
to weewx-user
Running a init.d script via "sh -x" never helps, unfortunately.   Your Feb-09 errors are definitely the lat/lon wrong in weewx.conf at that time.

What exact command did you use to run weewxd in the foreground ?
What is in your /var/log/syslog ?

Be sure to set debug=1 in weewx.conf first.

phxroa...@gmail.com

unread,
Feb 13, 2023, 4:26:52 PM2/13/23
to weewx-user
When run in the foreground it's just "sudo weewxd". The output is, as expected, lots of LOOP msgs, e.g.:

LOOP:   2023-02-13 14:02:25 MST (1676322145) 'altimeter': '29.69994054581585', 'appTemp': '31.6819209717981', 'barometer': '29.744', 'cloudbase': '8389.073423533182', 'consBatteryVoltage': '5.56', 'dateTime': '1676322145', 'dayET': '0.0', 'dayRain': '0.02', 'dewpoint': '28.007276936453998', 'ET': 'None', 'extraAlarm1': '0', 'extraAlarm2': '0', 'extraAlarm3': '0', 'extraAlarm4': '0', 'extraAlarm5': '0', 'extraAlarm6': '0', 'extraAlarm7': '0', 'extraAlarm8': '0', 'forecastIcon': '23', 'forecastRule': '172', 'heatindex': '35.8', 'humidex': '35.8', 'inDewpoint': '31.152498371623157', 'inHumidity': '26.0', 'insideAlarm': '0', 'inTemp': '67.2', 'maxSolarRad': 'None', 'monthET': '0.0', 'monthRain': '0.02', 'outHumidity': '73.0', 'outsideAlarm1': '0', 'outsideAlarm2': '0', 'outTemp': '35.8', 'pressure': '23.24132311458846', 'rain': '0.0', 'rainAlarm': '0', 'rainRate': '0.05', 'soilLeafAlarm1': '0', 'soilLeafAlarm2': '0', 'soilLeafAlarm3': '0', 'soilLeafAlarm4': '0', 'soilMoist3': '0.0', 'stormRain': '0.02', 'stormStart': '1676271600', 'sunrise': '1676297640', 'sunset': '1676336820', 'txBatteryStatus': '0', 'usUnits': '1', 'windchill': '35.8', 'windDir': 'None', 'windGust': '0.0', 'windGustDir': 'None', 'windrun': 'None', 'windSpeed': '0.0', 'windSpeed10': '0.0', 'yearET': '0.0', 'yearRain': '4.08'

This run was with "debug = 1" in weewx.conf.

Tons of stuff in syslog, as usual, with more from the debug flag.

It all looks to be working fine. Just can't get it to start as a background process started from init.d or systemctl. I'm a pretty old Linux nerd, and I've been retired for 17 yrs. I come from the time before systemctl, so I'm not up on how processes are "supposed to" get started at boot time any more. If I have to I can ssh to the thing (unit is at a vacation home) after a power outage or other interruption and start it by hand with "sudo weewxd &". I keep thinking, though, that there's got to be something I'm not seeing to get it to start automatically.

Looking at the timestamps in /var/log there is at least one weewx-related line in auth.log when I try to start it with systemctl. Not much help, though.

Feb 13 14:22:42 raspberrypi sudo:       pi : TTY=pts/1 ; PWD=/home/pi ; USER=root ; COMMAND=/usr/bin/systemctl --no-pager start weewx.service

#######################################################
Feb 13 13:49:32 raspberrypi weewx[11705] INFO __main__: Initializing weewx version 4.10.1
Feb 13 13:49:32 raspberrypi weewx[11705] INFO __main__: Using Python 3.9.2 (default, Feb 28 2021, 17:03:44) #012[GCC 10.2.1 20210110]
Feb 13 13:49:32 raspberrypi weewx[11705] INFO __main__: Located at /usr/bin/python3
Feb 13 13:49:32 raspberrypi weewx[11705] INFO __main__: Platform Linux-5.15.84-v8+-aarch64-with-glibc2.31
Feb 13 13:49:32 raspberrypi weewx[11705] INFO __main__: Locale is 'en_GB.UTF-8'
Feb 13 13:49:32 raspberrypi weewx[11705] INFO __main__: Using configuration file /etc/weewx/weewx.conf
Feb 13 13:49:32 raspberrypi weewx[11705] INFO __main__: Debug is 1
Feb 13 13:49:32 raspberrypi weewx[11705] DEBUG __main__: Initializing engine
Feb 13 13:49:32 raspberrypi weewx[11705] INFO weewx.engine: Loading station type Vantage (weewx.drivers.vantage)
Feb 13 13:49:32 raspberrypi weewx[11705] DEBUG weewx.drivers.vantage: Driver version is 3.5.2
Feb 13 13:49:32 raspberrypi weewx[11705] DEBUG weewx.drivers.vantage: Option loop_request=1
Feb 13 13:49:32 raspberrypi weewx[11705] DEBUG weewx.drivers.vantage: Opened up ethernet host 192.168.0.5 on port 22222. timeout=4.0, tcp_send_delay=1.0
Feb 13 13:49:35 raspberrypi weewx[11705] DEBUG weewx.drivers.vantage: Successfully woke up Vantage console
Feb 13 13:49:36 raspberrypi weewx[11705] DEBUG weewx.drivers.vantage: Hardware type is 17
Feb 13 13:49:41 raspberrypi weewx[11705] DEBUG weewx.drivers.vantage: ISS ID is 1
Feb 13 13:49:41 raspberrypi weewx[11705] DEBUG weewx.drivers.vantage: Hardware name: Vantage Vue
Feb 13 13:49:41 raspberrypi weewx[11705] DEBUG weewx.engine: Loading service weewx.engine.StdTimeSynch
Feb 13 13:49:41 raspberrypi weewx[11705] DEBUG weewx.engine: Finished loading service weewx.engine.StdTimeSynch
Feb 13 13:49:41 raspberrypi weewx[11705] DEBUG weewx.engine: Loading service weewx.engine.StdConvert
Feb 13 13:49:41 raspberrypi weewx[11705] INFO weewx.engine: StdConvert target unit is 0x1
Feb 13 13:49:41 raspberrypi weewx[11705] DEBUG weewx.engine: Finished loading service weewx.engine.StdConvert
Feb 13 13:49:41 raspberrypi weewx[11705] DEBUG weewx.engine: Loading service weewx.engine.StdCalibrate
Feb 13 13:49:41 raspberrypi weewx[11705] DEBUG weewx.engine: Finished loading service weewx.engine.StdCalibrate
Feb 13 13:49:41 raspberrypi weewx[11705] DEBUG weewx.engine: Loading service weewx.engine.StdQC
Feb 13 13:49:41 raspberrypi weewx[11705] DEBUG weewx.engine: Finished loading service weewx.engine.StdQC
Feb 13 13:49:41 raspberrypi weewx[11705] DEBUG weewx.engine: Loading service weewx.wxservices.StdWXCalculate
Feb 13 13:49:41 raspberrypi weewx[11705] INFO weewx.wxservices: StdWXCalculate will use data binding wx_binding
Feb 13 13:49:41 raspberrypi weewx[11705] DEBUG weewx.manager: Daily summary version is 4.0
Feb 13 13:49:41 raspberrypi weewx[11705] DEBUG weewx.engine: Finished loading service weewx.wxservices.StdWXCalculate
Feb 13 13:49:41 raspberrypi weewx[11705] DEBUG weewx.engine: Loading service weewx.wxxtypes.StdWXXTypes
Feb 13 13:49:41 raspberrypi weewx[11705] DEBUG weewx.engine: Finished loading service weewx.wxxtypes.StdWXXTypes
Feb 13 13:49:41 raspberrypi weewx[11705] DEBUG weewx.engine: Loading service weewx.wxxtypes.StdPressureCooker
Feb 13 13:49:41 raspberrypi weewx[11705] DEBUG weewx.engine: Finished loading service weewx.wxxtypes.StdPressureCooker
Feb 13 13:49:41 raspberrypi weewx[11705] DEBUG weewx.engine: Loading service weewx.wxxtypes.StdRainRater
Feb 13 13:49:41 raspberrypi weewx[11705] DEBUG weewx.engine: Finished loading service weewx.wxxtypes.StdRainRater
Feb 13 13:49:41 raspberrypi weewx[11705] DEBUG weewx.engine: Loading service weewx.wxxtypes.StdDelta
Feb 13 13:49:41 raspberrypi weewx[11705] DEBUG weewx.engine: Finished loading service weewx.wxxtypes.StdDelta
Feb 13 13:49:41 raspberrypi weewx[11705] DEBUG weewx.engine: Loading service weewx.engine.StdArchive
Feb 13 13:49:41 raspberrypi weewx[11705] INFO weewx.engine: Archive will use data binding wx_binding
Feb 13 13:49:41 raspberrypi weewx[11705] INFO weewx.engine: Record generation will be attempted in 'hardware'
Feb 13 13:49:41 raspberrypi weewx[11705] INFO weewx.engine: Using archive interval of 300 seconds (specified by hardware)
Feb 13 13:49:41 raspberrypi weewx[11705] DEBUG weewx.engine: Use LOOP data in hi/low calculations: 1
Feb 13 13:49:41 raspberrypi weewx[11705] DEBUG weewx.engine: Finished loading service weewx.engine.StdArchive
Feb 13 13:49:41 raspberrypi weewx[11705] DEBUG weewx.engine: Loading service weewx.restx.StdStationRegistry
Feb 13 13:49:41 raspberrypi weewx[11705] INFO weewx.restx: StationRegistry: Station will be registered.
Feb 13 13:49:41 raspberrypi weewx[11705] DEBUG weewx.engine: Finished loading service weewx.restx.StdStationRegistry
Feb 13 13:49:41 raspberrypi weewx[11705] DEBUG weewx.engine: Loading service weewx.restx.StdWunderground
Feb 13 13:49:41 raspberrypi weewx[11705] DEBUG weewx.restx: WU essentials: {}
Feb 13 13:49:41 raspberrypi weewx[11705] INFO weewx.restx: Wunderground-PWS: Data for station KAZMUNDS3 will be posted
Feb 13 13:49:41 raspberrypi weewx[11705] DEBUG weewx.engine: Finished loading service weewx.restx.StdWunderground
Feb 13 13:49:41 raspberrypi weewx[11705] DEBUG weewx.engine: Loading service weewx.restx.StdPWSweather
Feb 13 13:49:41 raspberrypi weewx[11705] INFO weewx.restx: PWSweather: Posting not enabled.
Feb 13 13:49:41 raspberrypi weewx[11705] DEBUG weewx.engine: Finished loading service weewx.restx.StdPWSweather
Feb 13 13:49:41 raspberrypi weewx[11705] DEBUG weewx.engine: Loading service weewx.restx.StdCWOP
Feb 13 13:49:41 raspberrypi weewx[11705] INFO weewx.restx: CWOP: Data for station DW5827 will be posted
Feb 13 13:49:41 raspberrypi weewx[11705] DEBUG weewx.engine: Finished loading service weewx.restx.StdCWOP
Feb 13 13:49:41 raspberrypi weewx[11705] DEBUG weewx.engine: Loading service weewx.restx.StdWOW
Feb 13 13:49:41 raspberrypi weewx[11705] INFO weewx.restx: WOW: Posting not enabled.
Feb 13 13:49:41 raspberrypi weewx[11705] DEBUG weewx.engine: Finished loading service weewx.restx.StdWOW
Feb 13 13:49:41 raspberrypi weewx[11705] DEBUG weewx.engine: Loading service weewx.restx.StdAWEKAS
Feb 13 13:49:41 raspberrypi weewx[11705] INFO weewx.restx: AWEKAS: Posting not enabled.
Feb 13 13:49:41 raspberrypi weewx[11705] DEBUG weewx.engine: Finished loading service weewx.restx.StdAWEKAS
Feb 13 13:49:41 raspberrypi weewx[11705] DEBUG weewx.engine: Loading service weewx.engine.StdPrint
Feb 13 13:49:41 raspberrypi weewx[11705] DEBUG weewx.engine: Finished loading service weewx.engine.StdPrint
Feb 13 13:49:41 raspberrypi weewx[11705] DEBUG weewx.engine: Loading service weewx.engine.StdReport
Feb 13 13:49:41 raspberrypi weewx[11705] INFO weewx.engine: 'pyephem' not detected, extended almanac data is not available
Feb 13 13:49:41 raspberrypi weewx[11705] DEBUG weewx.engine: Finished loading service weewx.engine.StdReport
Feb 13 13:49:41 raspberrypi weewx[11705] INFO __main__: Starting up weewx version 4.10.1
Feb 13 13:49:41 raspberrypi weewx[11705] DEBUG weewx.manager: Daily summary version is 4.0
Feb 13 13:49:41 raspberrypi weewx[11705] DEBUG weewx.manager: Daily summary version is 4.0
Feb 13 13:49:44 raspberrypi weewx[11705] DEBUG weewx.drivers.vantage: Successfully woke up Vantage console
Feb 13 13:49:45 raspberrypi weewx[11705] INFO weewx.engine: Clock error is -0.06 seconds (positive is fast)
Feb 13 13:49:45 raspberrypi weewx[11705] INFO weewx.engine: Using binding 'wx_binding' to database 'weewx.sdb'
Feb 13 13:49:45 raspberrypi weewx[11705] INFO weewx.manager: Starting backfill of daily summaries
Feb 13 13:49:45 raspberrypi weewx[11705] INFO weewx.manager: Daily summaries up to date
Feb 13 13:49:45 raspberrypi weewx[11705] DEBUG weewx.drivers.vantage: Getting archive packets since 2023-02-09 19:10:00 MST (1675995000)
Feb 13 13:49:47 raspberrypi weewx[11705] DEBUG weewx.drivers.vantage: Successfully woke up Vantage console
Feb 13 13:49:49 raspberrypi weewx[11705] DEBUG weewx.drivers.vantage: Retrieving 219 page(s); starting index= 4
Feb 13 13:49:50 raspberrypi weewx[11705] INFO weewx.manager: Added record 2023-02-09 19:15:00 MST (1675995300) to database 'weewx.sdb'
Feb 13 13:49:50 raspberrypi weewx[11705] INFO weewx.manager: Added record 2023-02-09 19:15:00 MST (1675995300) to daily summary in 'weewx.sdb'
Feb 13 13:49:50 raspberrypi weewx[11705] DEBUG weewx.restx: CWOP: record 2023-02-09 19:15:00 MST (1675995300) is stale (326090 > 600).
Feb 13 13:49:51 raspberrypi weewx[11705] DEBUG weewx.restx: StationRegistry: Failed upload attempt 1: b'FAIL: Server is undergoing maintenance\n'
Feb 13 13:49:51 raspberrypi weewx[11705] INFO weewx.restx: Wunderground-PWS: Published record 2023-02-09 19:15:00 MST (1675995300)
Feb 13 13:49:51 raspberrypi weewx[11705] INFO weewx.manager: Added record 2023-02-09 19:20:00 MST (1675995600) to database 'weewx.sdb'
Feb 13 13:49:51 raspberrypi weewx[11705] INFO weewx.manager: Added record 2023-02-09 19:20:00 MST (1675995600) to daily summary in 'weewx.sdb'
Feb 13 13:49:51 raspberrypi weewx[11705] DEBUG weewx.restx: CWOP: record 2023-02-09 19:20:00 MST (1675995600) is stale (325791 > 600).

[lots of data records as the DB is backfilled from the console - eventually it completes]

Feb 13 13:56:46 raspberrypi weewx[11705] INFO weewx.manager: Added record 2023-02-13 13:45:00 MST (1676321100) to database 'weewx.
sdb'
Feb 13 13:56:46 raspberrypi weewx[11705] INFO weewx.manager: Added record 2023-02-13 13:45:00 MST (1676321100) to daily summary in
 'weewx.sdb'
Feb 13 13:56:46 raspberrypi weewx[11705] INFO weewx.restx: Wunderground-PWS: Published record 2023-02-12 22:55:00 MST (1676267700)
Feb 13 13:56:46 raspberrypi weewx[11705] DEBUG weewx.restx: StationRegistry: wait interval (66600 < 86400) has not passed for reco
rd 2023-02-13 13:45:00 MST (1676321100)
Feb 13 13:56:46 raspberrypi weewx[11705] DEBUG weewx.restx: CWOP: record 2023-02-13 13:45:00 MST (1676321100) is stale (706 > 600)
.
Feb 13 13:56:46 raspberrypi weewx[11705] DEBUG weewx.drivers.vantage: DMPAFT complete: page timestamp 2023-02-04 16:05:00 MST (167
5551900) less than final timestamp 2023-02-13 13:45:00 MST (1676321100)
Feb 13 13:56:46 raspberrypi weewx[11705] DEBUG weewx.drivers.vantage: Catch up complete.
Feb 13 13:56:46 raspberrypi weewx[11705] INFO weewx.engine: Starting main packet loop.

[then this]

Feb 13 13:57:54 raspberrypi weewx[11705] DEBUG weewx.drivers.vantage: Successfully woke up Vantage console
Feb 13 13:57:54 raspberrypi weewx[11705] INFO weewx.restx: Wunderground-PWS: Published record 2023-02-13 12:10:00 MST (1676315400)
Feb 13 13:57:54 raspberrypi weewx[11705] INFO weewx.restx: Wunderground-PWS: Published record 2023-02-13 12:15:00 MST (1676315700)
Feb 13 13:57:54 raspberrypi weewx[11705] INFO weewx.restx: Wunderground-PWS: Published record 2023-02-13 12:20:00 MST (1676316000)
Feb 13 13:57:55 raspberrypi weewx[11705] INFO weewx.restx: Wunderground-PWS: Published record 2023-02-13 12:25:00 MST (1676316300)
Feb 13 13:57:55 raspberrypi weewx[11705] INFO weewx.restx: Wunderground-PWS: Published record 2023-02-13 12:30:00 MST (1676316600)
Feb 13 13:57:55 raspberrypi weewx[11705] INFO weewx.restx: Wunderground-PWS: Published record 2023-02-13 12:35:00 MST (1676316900)
Feb 13 13:57:56 raspberrypi weewx[11705] INFO weewx.restx: Wunderground-PWS: Published record 2023-02-13 12:40:00 MST (1676317200)
Feb 13 13:57:56 raspberrypi weewx[11705] INFO weewx.restx: Wunderground-PWS: Published record 2023-02-13 12:45:00 MST (1676317500)
Feb 13 13:57:57 raspberrypi weewx[11705] INFO weewx.restx: Wunderground-PWS: Published record 2023-02-13 12:50:00 MST (1676317800)
Feb 13 13:57:57 raspberrypi weewx[11705] INFO weewx.restx: Wunderground-PWS: Published record 2023-02-13 12:55:00 MST (1676318100)
Feb 13 13:57:57 raspberrypi weewx[11705] INFO weewx.restx: Wunderground-PWS: Published record 2023-02-13 13:00:00 MST (1676318400)
Feb 13 13:57:58 raspberrypi weewx[11705] INFO weewx.restx: Wunderground-PWS: Published record 2023-02-13 13:05:00 MST (1676318700)
Feb 13 13:57:58 raspberrypi weewx[11705] INFO weewx.restx: Wunderground-PWS: Published record 2023-02-13 13:10:00 MST (1676319000)
Feb 13 13:57:58 raspberrypi weewx[11705] INFO weewx.restx: Wunderground-PWS: Published record 2023-02-13 13:15:00 MST (1676319300)
Feb 13 13:57:59 raspberrypi weewx[11705] ERROR weewx.drivers.vantage: ip-read error: timed out
Feb 13 13:57:59 raspberrypi weewx[11705] ERROR weewx.drivers.vantage: LOOP batch try #3; error: timed out
Feb 13 13:57:59 raspberrypi weewx[11705] ERROR weewx.drivers.vantage: LOOP max batch errors (3) exceeded.
Feb 13 13:57:59 raspberrypi weewx[11705] INFO weewx.engine: Main loop exiting. Shutting engine down.
Feb 13 13:57:59 raspberrypi weewx[11705] DEBUG weewx.restx: Shut down CWOP thread.
Feb 13 13:57:59 raspberrypi weewx[11705] INFO weewx.restx: Wunderground-PWS: Published record 2023-02-13 13:20:00 MST (1676319600)
Feb 13 13:57:59 raspberrypi weewx[11705] INFO weewx.restx: Wunderground-PWS: Published record 2023-02-13 13:25:00 MST (1676319900)
Feb 13 13:57:59 raspberrypi weewx[11705] INFO weewx.restx: Wunderground-PWS: Published record 2023-02-13 13:30:00 MST (1676320200)
Feb 13 13:58:00 raspberrypi weewx[11705] INFO weewx.restx: Wunderground-PWS: Published record 2023-02-13 13:35:00 MST (1676320500)
Feb 13 13:58:00 raspberrypi weewx[11705] INFO weewx.restx: Wunderground-PWS: Published record 2023-02-13 13:40:00 MST (1676320800)
Feb 13 13:58:00 raspberrypi weewx[11705] INFO weewx.restx: Wunderground-PWS: Published record 2023-02-13 13:45:00 MST (1676321100)
Feb 13 13:58:00 raspberrypi weewx[11705] DEBUG weewx.restx: Shut down Wunderground-PWS thread.
Feb 13 13:58:00 raspberrypi weewx[11705] DEBUG weewx.restx: Shut down StationRegistry thread.
Feb 13 13:58:01 raspberrypi weewx[11705] CRITICAL __main__: Caught WeeWxIOError: LOOP max batch errors (3) exceeded.
Feb 13 13:58:01 raspberrypi weewx[11705] CRITICAL __main__:     ****  Waiting 60 seconds then retrying...
Feb 13 13:59:01 raspberrypi weewx[11705] INFO __main__: retrying...
Feb 13 13:59:01 raspberrypi weewx[11705] DEBUG __main__: Initializing engine
Feb 13 13:59:01 raspberrypi weewx[11705] INFO weewx.engine: Loading station type Vantage (weewx.drivers.vantage)
Feb 13 13:59:01 raspberrypi weewx[11705] DEBUG weewx.drivers.vantage: Driver version is 3.5.2
Feb 13 13:59:01 raspberrypi weewx[11705] DEBUG weewx.drivers.vantage: Option loop_request=1
Feb 13 13:59:01 raspberrypi weewx[11705] DEBUG weewx.drivers.vantage: Opened up ethernet host 192.168.0.5 on port 22222. timeout=4
.0, tcp_send_delay=1.0
Feb 13 13:59:04 raspberrypi weewx[11705] DEBUG weewx.drivers.vantage: Successfully woke up Vantage console
Feb 13 13:59:05 raspberrypi weewx[11705] DEBUG weewx.drivers.vantage: Hardware type is 17
Feb 13 13:59:10 raspberrypi weewx[11705] DEBUG weewx.drivers.vantage: ISS ID is 1
Feb 13 13:59:10 raspberrypi weewx[11705] DEBUG weewx.drivers.vantage: Hardware name: Vantage Vue
Feb 13 13:59:10 raspberrypi weewx[11705] DEBUG weewx.engine: Loading service weewx.engine.StdTimeSynch
Feb 13 13:59:10 raspberrypi weewx[11705] DEBUG weewx.engine: Finished loading service weewx.engine.StdTimeSynch
Feb 13 13:59:10 raspberrypi weewx[11705] DEBUG weewx.engine: Loading service weewx.engine.StdConvert
Feb 13 13:59:10 raspberrypi weewx[11705] INFO weewx.engine: StdConvert target unit is 0x1
Feb 13 13:59:10 raspberrypi weewx[11705] DEBUG weewx.engine: Finished loading service weewx.engine.StdConvert
Feb 13 13:59:10 raspberrypi weewx[11705] DEBUG weewx.engine: Loading service weewx.engine.StdCalibrate
Feb 13 13:59:10 raspberrypi weewx[11705] DEBUG weewx.engine: Finished loading service weewx.engine.StdCalibrate
Feb 13 13:59:10 raspberrypi weewx[11705] DEBUG weewx.engine: Loading service weewx.engine.StdQC
Feb 13 13:59:10 raspberrypi weewx[11705] DEBUG weewx.engine: Finished loading service weewx.engine.StdQC
Feb 13 13:59:10 raspberrypi weewx[11705] DEBUG weewx.engine: Loading service weewx.wxservices.StdWXCalculate
Feb 13 13:59:10 raspberrypi weewx[11705] INFO weewx.wxservices: StdWXCalculate will use data binding wx_binding
Feb 13 13:59:10 raspberrypi weewx[11705] DEBUG weewx.manager: Daily summary version is 4.0
Feb 13 13:59:10 raspberrypi weewx[11705] DEBUG weewx.engine: Finished loading service weewx.wxservices.StdWXCalculate
Feb 13 13:59:10 raspberrypi weewx[11705] DEBUG weewx.engine: Loading service weewx.wxxtypes.StdWXXTypes
Feb 13 13:59:10 raspberrypi weewx[11705] DEBUG weewx.engine: Finished loading service weewx.wxxtypes.StdWXXTypes
Feb 13 13:59:10 raspberrypi weewx[11705] DEBUG weewx.engine: Loading service weewx.wxxtypes.StdPressureCooker
Feb 13 13:59:10 raspberrypi weewx[11705] DEBUG weewx.engine: Finished loading service weewx.wxxtypes.StdPressureCooker
Feb 13 13:59:10 raspberrypi weewx[11705] DEBUG weewx.engine: Loading service weewx.wxxtypes.StdRainRater
Feb 13 13:59:10 raspberrypi weewx[11705] DEBUG weewx.engine: Finished loading service weewx.wxxtypes.StdRainRater
Feb 13 13:59:10 raspberrypi weewx[11705] DEBUG weewx.engine: Loading service weewx.wxxtypes.StdDelta
Feb 13 13:59:10 raspberrypi weewx[11705] DEBUG weewx.engine: Finished loading service weewx.wxxtypes.StdDelta
Feb 13 13:59:10 raspberrypi weewx[11705] DEBUG weewx.engine: Loading service weewx.engine.StdArchive
Feb 13 13:59:10 raspberrypi weewx[11705] INFO weewx.engine: Archive will use data binding wx_binding
Feb 13 13:59:10 raspberrypi weewx[11705] INFO weewx.engine: Record generation will be attempted in 'hardware'
Feb 13 13:59:10 raspberrypi weewx[11705] INFO weewx.engine: Using archive interval of 300 seconds (specified by hardware)
Feb 13 13:59:10 raspberrypi weewx[11705] DEBUG weewx.engine: Use LOOP data in hi/low calculations: 1
Feb 13 13:59:10 raspberrypi weewx[11705] DEBUG weewx.engine: Finished loading service weewx.engine.StdArchive
Feb 13 13:59:10 raspberrypi weewx[11705] DEBUG weewx.engine: Loading service weewx.restx.StdStationRegistry
Feb 13 13:59:10 raspberrypi weewx[11705] INFO weewx.restx: StationRegistry: Station will be registered.
Feb 13 13:59:10 raspberrypi weewx[11705] DEBUG weewx.engine: Finished loading service weewx.restx.StdStationRegistry
Feb 13 13:59:10 raspberrypi weewx[11705] DEBUG weewx.engine: Loading service weewx.restx.StdWunderground
Feb 13 13:59:10 raspberrypi weewx[11705] DEBUG weewx.restx: WU essentials: {}
Feb 13 13:59:10 raspberrypi weewx[11705] INFO weewx.restx: Wunderground-PWS: Data for station KAZMUNDS3 will be posted
Feb 13 13:59:10 raspberrypi weewx[11705] DEBUG weewx.engine: Finished loading service weewx.restx.StdWunderground
Feb 13 13:59:10 raspberrypi weewx[11705] DEBUG weewx.engine: Loading service weewx.restx.StdPWSweather
Feb 13 13:59:10 raspberrypi weewx[11705] INFO weewx.restx: PWSweather: Posting not enabled.
Feb 13 13:59:10 raspberrypi weewx[11705] DEBUG weewx.engine: Finished loading service weewx.restx.StdPWSweather
Feb 13 13:59:10 raspberrypi weewx[11705] DEBUG weewx.engine: Loading service weewx.restx.StdCWOP
Feb 13 13:59:10 raspberrypi weewx[11705] INFO weewx.restx: CWOP: Data for station DW5827 will be posted
Feb 13 13:59:10 raspberrypi weewx[11705] DEBUG weewx.engine: Finished loading service weewx.restx.StdCWOP
Feb 13 13:59:10 raspberrypi weewx[11705] DEBUG weewx.engine: Loading service weewx.restx.StdWOW
Feb 13 13:59:10 raspberrypi weewx[11705] INFO weewx.restx: WOW: Posting not enabled.
Feb 13 13:59:10 raspberrypi weewx[11705] DEBUG weewx.engine: Finished loading service weewx.restx.StdWOW
Feb 13 13:59:10 raspberrypi weewx[11705] DEBUG weewx.engine: Loading service weewx.restx.StdAWEKAS
Feb 13 13:59:10 raspberrypi weewx[11705] INFO weewx.restx: AWEKAS: Posting not enabled.
Feb 13 13:59:10 raspberrypi weewx[11705] DEBUG weewx.engine: Finished loading service weewx.restx.StdAWEKAS
Feb 13 13:59:10 raspberrypi weewx[11705] DEBUG weewx.engine: Loading service weewx.engine.StdPrint
Feb 13 13:59:10 raspberrypi weewx[11705] DEBUG weewx.engine: Finished loading service weewx.engine.StdPrint
Feb 13 13:59:10 raspberrypi weewx[11705] DEBUG weewx.engine: Loading service weewx.engine.StdReport
Feb 13 13:59:10 raspberrypi weewx[11705] INFO weewx.engine: 'pyephem' not detected, extended almanac data is not available
Feb 13 13:59:10 raspberrypi weewx[11705] DEBUG weewx.engine: Finished loading service weewx.engine.StdReport
Feb 13 13:59:10 raspberrypi weewx[11705] INFO __main__: Starting up weewx version 4.10.1
Feb 13 13:59:10 raspberrypi weewx[11705] DEBUG weewx.manager: Daily summary version is 4.0
Feb 13 13:59:10 raspberrypi weewx[11705] DEBUG weewx.manager: Daily summary version is 4.0
Feb 13 13:59:13 raspberrypi weewx[11705] DEBUG weewx.drivers.vantage: Successfully woke up Vantage console
Feb 13 13:59:14 raspberrypi weewx[11705] INFO weewx.engine: Clock error is -0.01 seconds (positive is fast)
Feb 13 13:59:14 raspberrypi weewx[11705] INFO weewx.engine: Using binding 'wx_binding' to database 'weewx.sdb'
Feb 13 13:59:14 raspberrypi weewx[11705] INFO weewx.manager: Starting backfill of daily summaries
Feb 13 13:59:14 raspberrypi weewx[11705] INFO weewx.manager: Daily summaries up to date
Feb 13 13:59:14 raspberrypi weewx[11705] DEBUG weewx.drivers.vantage: Getting archive packets since 2023-02-13 13:45:00 MST (1676321100)
Feb 13 13:59:16 raspberrypi weewx[11705] DEBUG weewx.drivers.vantage: Successfully woke up Vantage console
Feb 13 13:59:18 raspberrypi weewx[11705] DEBUG weewx.drivers.vantage: Retrieving 1 page(s); starting index= 1
Feb 13 13:59:19 raspberrypi weewx[11705] INFO weewx.manager: Added record 2023-02-13 13:56:00 MST (1676321760) to database 'weewx.sdb'

[later]

Feb 13 14:00:21 raspberrypi weewx[11705] DEBUG weewx.drivers.vantage: Catch up complete.
Feb 13 14:00:21 raspberrypi weewx[11705] DEBUG weewx.reportengine: Running reports for latest time in the database.
Feb 13 14:00:21 raspberrypi weewx[11705] DEBUG weewx.drivers.vantage: Requesting 200 LOOP packets.
Feb 13 14:00:21 raspberrypi weewx[11705] DEBUG weewx.reportengine: Running report 'SeasonsReport'
Feb 13 14:00:21 raspberrypi weewx[11705] DEBUG weewx.reportengine: Found configuration file /etc/weewx/skins/Seasons/skin.conf for report 'SeasonsReport'
Feb 13 14:00:21 raspberrypi weewx[11705] DEBUG weewx.cheetahgenerator: Using search list ['weewx.cheetahgenerator.Almanac', 'weewx.cheetahgenerator.Current', 'weewx.cheetahgenerator.DisplayOptions', 'weewx.cheetahgenerator.Extras', 'weewx.cheetahgenerator.Gettext', 'weewx.cheetahgenerator.JSONHelpers', 'weewx.cheetahgenerator.PlotInfo', 'weewx.cheetahgenerator.SkinInfo', 'weewx.cheetahgenerator.Station', 'weewx.cheetahgenerator.Stats', 'weewx.cheetahgenerator.UnitInfo']
Feb 13 14:00:21 raspberrypi weewx[11705] DEBUG weewx.manager: Daily summary version is 4.0
Feb 13 14:00:22 raspberrypi weewx[11705] INFO weewx.restx: Wunderground-PWS: Published record 2023-02-13 14:00:00 MST (1676322000)
Feb 13 14:00:23 raspberrypi weewx[11705] INFO weewx.cheetahgenerator: Generated 8 files for report SeasonsReport in 1.76 seconds
Feb 13 14:00:23 raspberrypi weewx[11705] DEBUG weewx.manager: Daily summary version is 4.0
Feb 13 14:00:23 raspberrypi weewx[11705] DEBUG weewx.drivers.vantage: Successfully woke up Vantage console
Feb 13 14:00:26 raspberrypi weewx[11705] INFO weewx.imagegenerator: Generated 56 images for report SeasonsReport in 3.20 seconds
Feb 13 14:00:26 raspberrypi weewx[11705] INFO weewx.reportengine: Copied 5 files to /var/www/html/weewx
Feb 13 14:00:26 raspberrypi weewx[11705] DEBUG weewx.reportengine: Report 'SmartphoneReport' not enabled. Skipping.
Feb 13 14:00:26 raspberrypi weewx[11705] DEBUG weewx.reportengine: Report 'MobileReport' not enabled. Skipping.
Feb 13 14:00:26 raspberrypi weewx[11705] DEBUG weewx.reportengine: Report 'StandardReport' not enabled. Skipping.
Feb 13 14:00:26 raspberrypi weewx[11705] DEBUG weewx.reportengine: Running report 'FTP'
Feb 13 14:00:26 raspberrypi weewx[11705] DEBUG weewx.reportengine: Found configuration file /etc/weewx/skins/Ftp/skin.conf for report 'FTP'
Feb 13 14:00:26 raspberrypi weewx[11705] DEBUG weewx.reportengine: Cannot read localization file /etc/weewx/skins/Ftp/lang/en.conf for report 'FTP': Config file not found: "/etc/weewx/skins/Ftp/lang/en.conf".
Feb 13 14:00:26 raspberrypi weewx[11705] DEBUG weewx.reportengine: **** Using defaults instead.
Feb 13 14:00:26 raspberrypi weewx[11705] DEBUG weeutil.ftpupload: Attempting connection to xxx.xxx.xxx.xxx
Feb 13 14:00:26 raspberrypi weewx[11705] DEBUG weeutil.ftpupload: Connected to xxx.xxx.xxx.xxx
Feb 13 14:00:26 raspberrypi weewx[11705] DEBUG weeutil.ftpupload: 0 ./yearvolt.png 9e967036e70f010123805bd67559643f9ce293c54989e563a039ed682aec4cd0
Feb 13 14:00:27 raspberrypi weewx[11705] DEBUG weeutil.ftpupload: Uploaded file /var/www/html/weewx/yearvolt.png to public_html/weewx/yearvolt.png
Feb 13 14:00:27 raspberrypi weewx[11705] DEBUG weeutil.ftpupload: 1 ./monthrain.png ac92429142ccbb83956f73b869b629be096e46ff3865f1ee49b63fbc7143b0e4
Feb 13 14:00:27 raspberrypi weewx[11705] DEBUG weeutil.ftpupload: Uploaded file /var/www/html/weewx/monthrain.png to public_html/weewx/monthrain.png
Feb 13 14:00:27 raspberrypi weewx[11705] DEBUG weeutil.ftpupload: 2 ./weektempfeel.png 07f7bd64fd8605b6c7806ef5abb35304b8a2c0aa21da5c3a4837fae1f814ebbe
Feb 13 14:00:27 raspberrypi weewx[11705] DEBUG weeutil.ftpupload: Uploaded file /var/www/html/weewx/weektempfeel.png to public_html/weewx/weektempfeel.png

[a total of 63 files uploaded, at which point I killed it from the terminal]

Feb 13 14:00:51 raspberrypi weewx[11705] DEBUG weeutil.ftpupload: 63 ./NOAA/NOAA-2023-02.txt 985e612f796c1a31d4e2ef220cd79a759b6ecea85340cf6fed42bd9f691b54e5
Feb 13 14:00:52 raspberrypi weewx[11705] DEBUG weeutil.ftpupload: Uploaded file /var/www/html/weewx/NOAA/NOAA-2023-02.txt to public_html/weewx/NOAA/NOAA-2023-02.txt
Feb 13 14:00:52 raspberrypi weewx[11705] INFO weewx.reportengine: ftpgenerator: Ftp'd 64 files in 25.59 seconds
Feb 13 14:00:52 raspberrypi weewx[11705] DEBUG weewx.reportengine: Report 'RSYNC' not enabled. Skipping.
Feb 13 14:02:32 raspberrypi weewx[11705] INFO weewx.engine: Main loop exiting. Shutting engine down.
Feb 13 14:02:32 raspberrypi weewx[11705] INFO weewx.engine: Shutting down StdReport thread
Feb 13 14:02:32 raspberrypi weewx[11705] DEBUG weewx.engine: StdReport thread has been terminated
Feb 13 14:02:32 raspberrypi weewx[11705] DEBUG weewx.restx: Shut down CWOP thread.
Feb 13 14:02:32 raspberrypi weewx[11705] DEBUG weewx.restx: Shut down Wunderground-PWS thread.
Feb 13 14:02:32 raspberrypi weewx[11705] DEBUG weewx.restx: Shut down StationRegistry thread.
Feb 13 14:02:33 raspberrypi weewx[11705] CRITICAL __main__: Keyboard interrupt.

vince

unread,
Feb 13, 2023, 5:52:40 PM2/13/23
to weewx-user
Crazy idea, but see if the init.d file has odd permissions.
Do a 'sudo chmod 755 /etc/init.d/weewx' and then try to start it via systemctl.

Tom Keffer

unread,
Feb 13, 2023, 6:11:33 PM2/13/23
to weewx...@googlegroups.com
Why not dispose of the init.d script and use the systemd script? It's much simpler.

On Mon, Feb 13, 2023 at 2:52 PM vince <vince...@gmail.com> wrote:
Crazy idea, but see if the init.d file has odd permissions.
Do a 'sudo chmod 755 /etc/init.d/weewx' and then try to start it via systemctl.

--
You received this message because you are subscribed to the Google Groups "weewx-user" group.
To unsubscribe from this group and stop receiving emails from it, send an email to weewx-user+...@googlegroups.com.
To view this discussion on the web visit https://groups.google.com/d/msgid/weewx-user/aa41165d-2dcc-4132-a8ca-1ce84d576037n%40googlegroups.com.

phxroa...@gmail.com

unread,
Feb 13, 2023, 7:22:16 PM2/13/23
to weewx-user
Ah, good thought. I didn't consider permissions. Unfortunately, it was already 755:

pi@raspberrypi:~ $ ls -l /etc/init.d/weewx
-rwxr-xr-x 1 root root 5111 Oct 25 04:29 /etc/init.d/weewx

The more I think about this the more it feels like whoever developed the script was on a system with some package installed that I don't have (yet), or was on a different version. It's more or less impossible to describe a "standard" Debian installation so I don't know such a situation could be avoided. Such is life in DIY world, but we persevere. I may wind up making a copy of the script and sprinkling echo's throughout just to get more info about what it thinks is going on. Oh for an interactive shell script debugger!

phxroa...@gmail.com

unread,
Feb 13, 2023, 7:25:10 PM2/13/23
to weewx-user
The init.d style of startup is what I was familiar with back in my pre-retirement days, so that's what I've been concentrating on. Probably time I should learn the other style, though!
Reply all
Reply to author
Forward
0 new messages