4.9.1 issue

[vince]

After upgrading 4.8.0 to 4.9.1 my pi4 setup is seeing continual engine reboots due to "Caught WeeWxIOError: Max tries exceeded while getting archive data". 

System is a VP2 connected to a pi4 via serial2usb connector that has worked for years.  The hardware is good and was last touched over 60 days ago when I moved my setup to the pi4.

Of note is when I flip back to 4.8.0 without touching hardware, there is no issue any more.  It only appeared when I did an upgrade to 4.9.1 here.

Some debug output from the logs....

Oct 26 12:50:16 pi4 weewx[6751] DEBUG weewx.drivers.vantage: Getting archive packets since 2022-10-26 12:45:00 PDT (1666813500)
Oct 26 12:50:17 pi4 weewx[6751] ERROR weewx.drivers.vantage: Unable to wake up Vantage console
Oct 26 12:50:17 pi4 weewx[6751] ERROR weewx.drivers.vantage: DMPAFT try #1; error: Unable to wake up Vantage console
Oct 26 12:50:17 pi4 weewx[6751] DEBUG weewx.drivers.vantage: Getting archive packets since 2022-10-26 12:45:00 PDT (1666813500)
Oct 26 12:50:17 pi4 weewx[6751] ERROR weewx.drivers.vantage: Unable to wake up Vantage console
Oct 26 12:50:17 pi4 weewx[6751] ERROR weewx.drivers.vantage: DMPAFT try #2; error: Unable to wake up Vantage console
Oct 26 12:50:17 pi4 weewx[6751] DEBUG weewx.drivers.vantage: Getting archive packets since 2022-10-26 12:45:00 PDT (1666813500)
Oct 26 12:50:17 pi4 weewx[6751] ERROR weewx.drivers.vantage: Unable to wake up Vantage console
Oct 26 12:50:17 pi4 weewx[6751] ERROR weewx.drivers.vantage: DMPAFT try #3; error: Unable to wake up Vantage console
Oct 26 12:50:17 pi4 weewx[6751] DEBUG weewx.drivers.vantage: Getting archive packets since 2022-10-26 12:45:00 PDT (1666813500)
Oct 26 12:50:17 pi4 weewx[6751] ERROR weewx.drivers.vantage: Unable to wake up Vantage console
Oct 26 12:50:17 pi4 weewx[6751] ERROR weewx.drivers.vantage: DMPAFT try #4; error: Unable to wake up Vantage console
Oct 26 12:50:17 pi4 weewx[6751] ERROR weewx.drivers.vantage: DMPAFT max tries (4) exceeded.
Oct 26 12:50:17 pi4 weewx[6751] INFO weewx.engine: Main loop exiting. Shutting engine down.
Oct 26 12:50:17 pi4 weewx[6751] DEBUG weewx.restx: Shut down MQTT thread.
Oct 26 12:50:18 pi4 weewx[6751] DEBUG weewx.restx: Shut down CWOP thread.
Oct 26 12:50:18 pi4 weewx[6751] DEBUG weewx.restx: Shut down PWSWeather thread.
Oct 26 12:50:18 pi4 weewx[6751] DEBUG weewx.restx: Shut down Wunderground-PWS thread.
Oct 26 12:50:18 pi4 weewx[6751] DEBUG weewx.restx: Shut down StationRegistry thread.
Oct 26 12:50:18 pi4 weewx[6751] INFO user.MQTTSubscribe: (Service) Disconnected with result code 0
Oct 26 12:50:18 pi4 weewx[6751] CRITICAL __main__: Caught WeeWxIOError: Max tries exceeded while getting archive data.
Oct 26 12:50:18 pi4 weewx[6751] CRITICAL __main__:     ****  Waiting 60 seconds then retrying...

I also noted the following:

Oct 26 12:51:19 pi4 weewx[6751] INFO __main__: Starting up weewx version 4.9.1
Oct 26 12:51:19 pi4 weewx[6751] DEBUG weewx.drivers.vantage: Gentle wake up of console successful
Oct 26 12:51:19 pi4 weewx[6751] DEBUG weewx.manager: Daily summary version is 4.0
Oct 26 12:51:19 pi4 weewx[6751] INFO weewx.engine: Clock error is 4.45 seconds (positive is fast)

So what I'm wondering (wild guess mode) is if there's a timing thing going on.  If the clock error is 4.45 seconds is this enough to cause these kinds of errors ?

Again, flipping back to 4.8.0 results in a 100% working system. It's the 4.9.1 update that caused the issue to appear...

Ideas ?

[Tom Keffer]

There are two significant changes to the Vantage driver since v4.8.0.

1. Commit b0145b3. See Issue #772 and this email thread. This tries to make "Expected 99, got 0" errors less traumatic.

However, it would affect genLoopPackets(), not genArchiveRecords(), which is where your error is occurring.

2. Dealing with "ghost values." Recall the discussion last spring about values being archived for sensors the user didn't even own, especially on VantageVue. The fix was to not decode certain values for the Vue. This has nothing to do with pulling values off the station, only what happens to them afterwards.

I don't see how the onboard clock time would cause this. It would only affect archiving on the server, not faults in the logger.

In short, I'm at a loss as to what could cause this. How often does it happen? Every single archive record? Every once in a while?

--
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/4790e51b-091f-4a26-9fc8-ad8aa6688af6n%40googlegroups.com.

[vince]

Almost every single time.  Not 100% but pretty close.

• I put the 4.9.1 driver on my working 4.8.0 system, deleted the __pycache__ file for it, and started weewx up and it 'did' recreate the problem.  Failed immediately and kept failing.

• I also repeated the test with the extra sensors stuff set to { } and it didn't fix it. So I'm guessing it's the issue-772 mods are at fault.

If you can get me a driver with issue-772 backed out or with more diagnostic logging etc added I'd be happy to do more tests to try to figure it out.

Lastly, FWIW, I'm running a VP2 with rather old firmware, if that matters.

Davis Vantage EEPROM settings:

    CONSOLE TYPE:                   Vantage Pro2

    CONSOLE FIRMWARE:
      Date:                         Jul 14 2008
      Version:                      1.80

    CONSOLE SETTINGS:
      Archive interval:             300 (seconds)
      Altitude:                     365 (foot)
      Wind cup type:                large
      Rain bucket type:             0.01 inches
      Rain year start:              10
      Onboard time:                 2022-10-26 15:10:05

    CONSOLE DISPLAY UNITS:
      Barometer:                    inHg
      Temperature:                  degree_F
      Rain:                         inch
      Wind:                         mile_per_hour

    CONSOLE STATION INFO:
      Latitude (onboard):           +47.3
      Longitude (onboard):          -122.3
      Use manual or auto DST?       AUTO
      DST setting:                  N/A
      Use GMT offset or zone code?  GMT_OFFSET
      Time zone code:               N/A
      GMT offset:                   -8.0 hours
      Temperature logging:          LAST
      Retransmit channel:           OFF (0)

    TRANSMITTERS:
      Channel   Receive   Repeater  Type
         1      active      none    iss
         2      inactive    none    (N/A)
         3      inactive    none    (N/A)
         4      inactive    none    (N/A)
         5      inactive    none    (N/A)
         6      inactive    none    (N/A)
         7      inactive    none    (N/A)
         8      inactive    none    (N/A)

    RECEPTION STATS:
      Total packets received:       255
      Total packets missed:         3
      Number of resynchronizations: 0
      Longest good stretch:         122
      Number of CRC errors:         0

    BAROMETER CALIBRATION DATA:
      Current barometer reading:    30.130 inHg
      Altitude:                     365 feet
      Dew point:                    44 F
      Virtual temperature:          46 F
      Humidity correction factor:   1.9
      Correction ratio:             1.014
      Correction constant:          +0.000 inHg
      Gain:                         0.000
      Offset:                       35.000

    OFFSETS:
      Wind direction:               +0 deg
      Inside Temperature:           +0.0 F
      Inside Humidity:              +0 %
      Outside Temperature:          +0.0 F
      Outside Humidity:             +0 %

[Tom Keffer]

How about trying the old V4.8.0 driver in v4.9.1? You can find it here: https://raw.githubusercontent.com/weewx/weewx/v4.8.0/bin/weewx/drivers/vantage.py

--

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/87a9b91d-265d-4c51-9fcf-4d8c7370a3dfn%40googlegroups.com.

[vince]

Testing each later version one-by-one to see when it goes belly up via 'git checkout revnumberhere -- vantage.py'

• 4.8.0 version looks ok when dropped into place over 4.9.1 weewx
• may-06th version 7024f seems ok as well
• may-18th version b0145 fails repeatedly with the 'max tries exceeded' error

debug=1 syslog entries from 'grep vantage /var/log/syslog' are in the attachment.

I used 'logger' to write out which version driver I was starting up....

[Gerard Cerchio]

My Vantage Vue is 4 years later, any thoughts if it would run into the same problem?

  cserve:/etc/weewx# wee_device --info
  Using configuration file /etc/weewx/weewx.conf
  Using Vantage driver version 3.2.3 (weewx.drivers.vantage)
  Querying...
  Davis Vantage EEPROM settings:

  CONSOLE TYPE: Vantage Vue

  CONSOLE FIRMWARE:
  Date: May 1 2012
  Version: 3.0

[Tom Keffer]

Nice sleuthing, Vince.

Very mysterious. I don't think the firmware version has anything to do with it. Mine is even older than yours (6-Jun-2007) and it's working fine.

Try this version. It still uses the v4.9.1 approach, but logs a bit more information about failure modes.

To view this discussion on the web visit https://groups.google.com/d/msgid/weewx-user/a15a58d3-57c9-4e9c-9f55-10dab3dcd549n%40googlegroups.com.

[Gerard Cerchio]

The Vantage Vue console with Firmware 3.0 is working just fine with weewx 4.9.1 and the new vantage driver.

[Phil Green]

Weewx version 4.9.1 working fine with my Vantage Vue.  All log output looks no different to version 4.8.0. Console firmware is up to date, the console is connected to my Raspberry Pi3B+ using the standard Davis USB console logger.

Pleased with Weewx and the flexibility it can offer.

Regards

Phil

[vince]

The instrumented one looks ok.  I can not explain why.

Oct 27 10:05:48 pi4 pi: vantage ==> running 4.9.1 instrumented version of driver
Oct 27 10:05:48 pi4 weewx[19695] INFO weewx.engine: Loading station type Vantage (weewx.drivers.vantage)
Oct 27 10:05:48 pi4 weewx[19695] DEBUG weewx.drivers.vantage: Driver version is 3.5.0
Oct 27 10:05:48 pi4 weewx[19695] DEBUG weewx.drivers.vantage: Option loop_request=1
Oct 27 10:05:48 pi4 weewx[19695] DEBUG weewx.drivers.vantage: Opened up serial port /dev/ttyUSB0; baud 19200; timeout 5.00
Oct 27 10:05:48 pi4 weewx[19695] DEBUG weewx.drivers.vantage: Gentle wake up of console successful
Oct 27 10:05:48 pi4 weewx[19695] DEBUG weewx.drivers.vantage: Hardware type is 16
Oct 27 10:05:48 pi4 weewx[19695] DEBUG weewx.drivers.vantage: ISS ID is 1
Oct 27 10:05:48 pi4 weewx[19695] DEBUG weewx.drivers.vantage: Hardware name: Vantage Pro2
Oct 27 10:05:50 pi4 weewx[19695] DEBUG weewx.drivers.vantage: Gentle wake up of console successful
Oct 27 10:05:50 pi4 weewx[19695] DEBUG weewx.drivers.vantage: Getting archive packets since 2022-10-27 10:05:00 PDT (1666890300)
Oct 27 10:05:50 pi4 weewx[19695] DEBUG weewx.drivers.vantage: Gentle wake up of console successful
Oct 27 10:05:50 pi4 weewx[19695] DEBUG weewx.drivers.vantage: Retrieving 0 page(s); starting index= 0
Oct 27 10:05:50 pi4 weewx[19695] DEBUG weewx.drivers.vantage: Gentle wake up of console successful
Oct 27 10:05:50 pi4 weewx[19695] DEBUG weewx.drivers.vantage: Requesting 200 LOOP packets.
Oct 27 10:05:50 pi4 weewx[19695] DEBUG weewx.drivers.vantage: Gentle wake up of console successful
Oct 27 10:10:14 pi4 weewx[19695] DEBUG weewx.drivers.vantage: Getting archive packets since 2022-10-27 10:05:00 PDT (1666890300)
Oct 27 10:10:16 pi4 weewx[19695] DEBUG weewx.drivers.vantage: Rude wake up retry #1 failed. Response: b'O\xec'
Oct 27 10:10:16 pi4 weewx[19695] DEBUG weewx.drivers.vantage: Retry #1 unable to wake up console... sleeping
Oct 27 10:10:17 pi4 weewx[19695] DEBUG weewx.drivers.vantage: Rude wake up of console successful
Oct 27 10:10:17 pi4 weewx[19695] DEBUG weewx.drivers.vantage: Retrieving 1 page(s); starting index= 0
Oct 27 10:10:18 pi4 weewx[19695] DEBUG weewx.drivers.vantage: DMPAFT complete: page timestamp 2022-10-18 12:55:00 PDT (1666122900) less than final timestamp 2022-10-27 10:10:00 PDT (1666890600)
Oct 27 10:10:18 pi4 weewx[19695] DEBUG weewx.drivers.vantage: Catch up complete.
Oct 27 10:10:18 pi4 weewx[19695] DEBUG weewx.drivers.vantage: Requesting 200 LOOP packets.
Oct 27 10:10:18 pi4 weewx[19695] DEBUG weewx.drivers.vantage: Gentle wake up of console successful
Oct 27 10:15:14 pi4 weewx[19695] DEBUG weewx.drivers.vantage: Getting archive packets since 2022-10-27 10:10:00 PDT (1666890600)
Oct 27 10:15:16 pi4 weewx[19695] DEBUG weewx.drivers.vantage: Rude wake up retry #1 failed. Response: b'O\x00'
Oct 27 10:15:16 pi4 weewx[19695] DEBUG weewx.drivers.vantage: Retry #1 unable to wake up console... sleeping
Oct 27 10:15:17 pi4 weewx[19695] DEBUG weewx.drivers.vantage: Rude wake up of console successful
Oct 27 10:15:17 pi4 weewx[19695] DEBUG weewx.drivers.vantage: Retrieving 1 page(s); starting index= 1
Oct 27 10:15:18 pi4 weewx[19695] DEBUG weewx.drivers.vantage: DMPAFT complete: page timestamp 2022-10-18 13:00:00 PDT (1666123200) less than final timestamp 2022-10-27 10:15:00 PDT (1666890900)
Oct 27 10:15:18 pi4 weewx[19695] DEBUG weewx.drivers.vantage: Catch up complete.
Oct 27 10:15:18 pi4 weewx[19695] DEBUG weewx.drivers.vantage: Requesting 200 LOOP packets.
Oct 27 10:15:18 pi4 weewx[19695] DEBUG weewx.drivers.vantage: Gentle wake up of console successful
Oct 27 10:20:14 pi4 weewx[19695] DEBUG weewx.drivers.vantage: Getting archive packets since 2022-10-27 10:15:00 PDT (1666890900)
Oct 27 10:20:16 pi4 weewx[19695] DEBUG weewx.drivers.vantage: Rude wake up retry #1 failed. Response: b'O\x00'
Oct 27 10:20:16 pi4 weewx[19695] DEBUG weewx.drivers.vantage: Retry #1 unable to wake up console... sleeping
Oct 27 10:20:17 pi4 weewx[19695] DEBUG weewx.drivers.vantage: Rude wake up of console successful
Oct 27 10:20:17 pi4 weewx[19695] DEBUG weewx.drivers.vantage: Retrieving 1 page(s); starting index= 2
Oct 27 10:20:18 pi4 weewx[19695] DEBUG weewx.drivers.vantage: DMPAFT complete: page timestamp 2022-10-18 13:05:00 PDT (1666123500) less than final timestamp 2022-10-27 10:20:00 PDT (1666891200)
Oct 27 10:20:18 pi4 weewx[19695] DEBUG weewx.drivers.vantage: Catch up complete.
Oct 27 10:20:18 pi4 weewx[19695] DEBUG weewx.drivers.vantage: Requesting 200 LOOP packets.
Oct 27 10:20:18 pi4 weewx[19695] DEBUG weewx.drivers.vantage: Gentle wake up of console successful

[vince]

Spoke too fast.  Got a couple failures and restarts just a few minutes ago.

I also notice one restart overnight after 1am PDT so the other one wasn't quite perfect it seems.

[...Tom - If you want to take this to email I can post a followup if we find the cause later on....]

Oct 27 11:35:59 pi4 weewx[19695] ERROR weewx.drivers.vantage: LOOP batch try #1; error: Expected to read 99 chars; got 0 instead
Oct 27 11:35:59 pi4 weewx[19695] DEBUG weewx.drivers.vantage: Rude wake up retry #1 failed. Response: b'O\x00'
Oct 27 11:35:59 pi4 weewx[19695] DEBUG weewx.drivers.vantage: Retry #1 unable to wake up console... sleeping
Oct 27 11:36:00 pi4 weewx[19695] DEBUG weewx.drivers.vantage: Rude wake up of console successful
Oct 27 11:36:06 pi4 weewx[19695] ERROR weewx.drivers.vantage: LOOP batch try #2; error: Expected to read 99 chars; got 0 instead
Oct 27 11:36:06 pi4 weewx[19695] DEBUG weewx.drivers.vantage: Rude wake up retry #1 failed. Response: b'\x00\x00'
Oct 27 11:36:06 pi4 weewx[19695] DEBUG weewx.drivers.vantage: Retry #1 unable to wake up console... sleeping
Oct 27 11:36:07 pi4 weewx[19695] DEBUG weewx.drivers.vantage: Rude wake up of console successful
Oct 27 11:36:19 pi4 weewx[19695] ERROR weewx.drivers.vantage: LOOP batch try #3; error: Expected to read 99 chars; got 0 instead
Oct 27 11:36:19 pi4 weewx[19695] ERROR weewx.drivers.vantage: LOOP max batch errors (3) exceeded.
Oct 27 11:36:19 pi4 weewx[19695] INFO weewx.engine: Main loop exiting. Shutting engine down.
Oct 27 11:36:19 pi4 weewx[19695] INFO weewx.engine: Shutting down StdReport thread
Oct 27 11:36:19 pi4 weewx[19695] DEBUG weewx.engine: StdReport thread has been terminated
Oct 27 11:36:19 pi4 weewx[19695] DEBUG weewx.restx: Shut down MQTT thread.
Oct 27 11:36:19 pi4 weewx[19695] DEBUG weewx.restx: Shut down CWOP thread.
Oct 27 11:36:19 pi4 weewx[19695] DEBUG weewx.restx: Shut down PWSWeather thread.
Oct 27 11:36:19 pi4 weewx[19695] DEBUG weewx.restx: Shut down Wunderground-PWS thread.
Oct 27 11:36:19 pi4 weewx[19695] DEBUG weewx.restx: Shut down StationRegistry thread.
Oct 27 11:36:19 pi4 weewx[19695] INFO user.MQTTSubscribe: (Service) Disconnected with result code 0
Oct 27 11:36:19 pi4 weewx[19695] CRITICAL __main__: Caught WeeWxIOError: LOOP max batch errors (3) exceeded.
Oct 27 11:36:19 pi4 weewx[19695] CRITICAL __main__:     ****  Waiting 60 seconds then retrying...
Oct 27 11:37:19 pi4 weewx[19695] INFO __main__: retrying...
Oct 27 11:37:19 pi4 weewx[19695] DEBUG __main__: Initializing engine
Oct 27 11:37:19 pi4 weewx[19695] INFO weewx.engine: Loading station type Vantage (weewx.drivers.vantage)
Oct 27 11:37:19 pi4 weewx[19695] DEBUG weewx.drivers.vantage: Driver version is 3.5.0
Oct 27 11:37:19 pi4 weewx[19695] DEBUG weewx.drivers.vantage: Option loop_request=1
Oct 27 11:37:19 pi4 weewx[19695] DEBUG weewx.drivers.vantage: Opened up serial port /dev/ttyUSB0; baud 19200; timeout 5.00
Oct 27 11:37:19 pi4 weewx[19695] DEBUG weewx.drivers.vantage: Gentle wake up of console successful
Oct 27 11:37:19 pi4 weewx[19695] DEBUG weewx.drivers.vantage: Hardware type is 16
Oct 27 11:37:19 pi4 weewx[19695] DEBUG weewx.drivers.vantage: ISS ID is 1
Oct 27 11:37:19 pi4 weewx[19695] DEBUG weewx.drivers.vantage: Hardware name: Vantage Pro2
Oct 27 11:37:19 pi4 weewx[19695] DEBUG weewx.engine: Loading service weewx.engine.StdTimeSynch
Oct 27 11:37:19 pi4 weewx[19695] DEBUG weewx.engine: Finished loading service weewx.engine.StdTimeSynch

[wfs...@gmail.com]

I just updated to 4.9.1 and am having similar problems..."unable to wake up Vantage console".  I have a VP1 and I've rebooted the Pi and unplugged and replugged the serial-to-USB interface.

At reboot when Weewx first tries to start, it gets the unable to wakeup error.  If I do a sudo /etc/init.d/weewx stop and then sudo /etc/init.d/weewx start, it starts OK.  But I have yet to see it process an archive interval correctly.  It's getting an unable to wakeup error every time. 

FWIW, I was watching the syslog with tail -f and the try errors all spilled out at once, like it wasn't pausing between retries. 

My setup up until now has been very stable.  I'll go for months without any errors.

At update, I chose to keep my current weewx.conf, and I haven't made any changes.  The only somewhat unexpected difference between my weewx.conf and 

weewx.conf-4.9.1 is that the socket timeout parameter is not in 4.9.1.  But that seems unrelated, and it's still in my weewx.conf anyway.

Walt

At initial startup:

Oct 28 08:20:56 raspberrypi weewx[907] INFO weewx.engine: Loading station type Vantage (weewx.drivers.vantage)
Oct 28 08:20:56 raspberrypi weewx[894]: Starting weewx weather system: weewx.
Oct 28 08:20:56 raspberrypi systemd[1]: Started LSB: weewx weather system.
Oct 28 08:20:56 raspberrypi weewx[907] ERROR weewx.drivers.vantage: Unable to wake up Vantage console
Oct 28 08:20:56 raspberrypi weewx[907] ERROR weewx.engine: Import of driver failed: Unable to wake up Vantage console (<class 'weewx.WakeupError'>)
Oct 28 08:20:56 raspberrypi weewx[907] CRITICAL weewx.engine:     ****  Traceback (most recent call last):
Oct 28 08:20:56 raspberrypi weewx[907] CRITICAL weewx.engine:     ****    File "/usr/share/weewx/weewx/engine.py", line 119, in setupStation
Oct 28 08:20:56 raspberrypi weewx[907] CRITICAL weewx.engine:     ****      self.console = loader_function(config_dict, self)
Oct 28 08:20:56 raspberrypi weewx[907] CRITICAL weewx.engine:     ****    File "/usr/share/weewx/weewx/drivers/vantage.py", line 40, in loader
Oct 28 08:20:56 raspberrypi weewx[907] CRITICAL weewx.engine:     ****      return VantageService(engine, config_dict)
Oct 28 08:20:56 raspberrypi weewx[907] CRITICAL weewx.engine:     ****    File "/usr/share/weewx/weewx/drivers/vantage.py", line 1939, in __init__
Oct 28 08:20:56 raspberrypi weewx[907] CRITICAL weewx.engine:     ****      Vantage.__init__(self, **config_dict[DRIVER_NAME])
Oct 28 08:20:56 raspberrypi weewx[907] CRITICAL weewx.engine:     ****    File "/usr/share/weewx/weewx/drivers/vantage.py", line 521, in __init__
Oct 28 08:20:56 raspberrypi weewx[907] CRITICAL weewx.engine:     ****      self._setup()
Oct 28 08:20:56 raspberrypi weewx[907] CRITICAL weewx.engine:     ****    File "/usr/share/weewx/weewx/drivers/vantage.py", line 1340, in _setup
Oct 28 08:20:56 raspberrypi weewx[907] CRITICAL weewx.engine:     ****      self.port.wakeup_console(max_tries=self.max_tries)
Oct 28 08:20:56 raspberrypi weewx[907] CRITICAL weewx.engine:     ****    File "/usr/share/weewx/weewx/drivers/vantage.py", line 118, in wakeup_console
Oct 28 08:20:56 raspberrypi weewx[907] CRITICAL weewx.engine:     ****      raise weewx.WakeupError("Unable to wake up Vantage console")
Oct 28 08:20:56 raspberrypi weewx[907] CRITICAL weewx.engine:     ****  weewx.WakeupError: Unable to wake up Vantage console
Oct 28 08:20:56 raspberrypi weewx[907] CRITICAL __main__: Unable to load driver: Unable to wake up Vantage console
Oct 28 08:20:56 raspberrypi weewx[907] CRITICAL __main__:     ****  Exiting...
Oct 28 08:20:56 raspberrypi kernel: [  151.179492] pl2303 ttyUSB0: pl2303_read_int_callback - usb_submit_urb failed with result -1


At archive interval:

Oct 28 08:45:16 raspberrypi weewx[1022] ERROR weewx.drivers.vantage: Unable to wake up Vantage console
Oct 28 08:45:16 raspberrypi weewx[1022] ERROR weewx.drivers.vantage: DMPAFT try #1; error: Unable to wake up Vantage console
Oct 28 08:45:16 raspberrypi weewx[1022] ERROR weewx.drivers.vantage: Unable to wake up Vantage console
Oct 28 08:45:16 raspberrypi weewx[1022] ERROR weewx.drivers.vantage: DMPAFT try #2; error: Unable to wake up Vantage console
Oct 28 08:45:16 raspberrypi weewx[1022] ERROR weewx.drivers.vantage: Unable to wake up Vantage console
Oct 28 08:45:16 raspberrypi weewx[1022] ERROR weewx.drivers.vantage: DMPAFT try #3; error: Unable to wake up Vantage console
Oct 28 08:45:16 raspberrypi weewx[1022] ERROR weewx.drivers.vantage: Unable to wake up Vantage console
Oct 28 08:45:16 raspberrypi weewx[1022] ERROR weewx.drivers.vantage: DMPAFT try #4; error: Unable to wake up Vantage console
Oct 28 08:45:16 raspberrypi weewx[1022] ERROR weewx.drivers.vantage: DMPAFT max tries (4) exceeded.
Oct 28 08:45:16 raspberrypi weewx[1022] INFO weewx.engine: Main loop exiting. Shutting engine down.
Oct 28 08:45:16 raspberrypi weewx[1022] CRITICAL __main__: Caught WeeWxIOError: Max tries exceeded while getting archive data.
Oct 28 08:45:16 raspberrypi weewx[1022] CRITICAL __main__:     ****  Waiting 60 seconds then retrying...
Oct 28 08:46:17 raspberrypi weewx[1022] INFO __main__: retrying...

[Tom Keffer]

Walt, could you double check that you do not have more than one instance of weewxd running?

ps aux|grep weewxd

If you can confirm that you only have one instance running, then try this version of the driver. It uses a simpler wake-up sequence.

-tk

To view this discussion on the web visit https://groups.google.com/d/msgid/weewx-user/540448ed-3698-4332-a947-6a0b6b7b50ecn%40googlegroups.com.

[Marius Schamschula]

I ran into the same issue yesterday when upgrading my RaspberryPI.

The device had changed from /dev/ttyUSB0 to /dev/ttyUSB1

I updated the weewx.conf file and all is well.

To view this discussion on the web visit https://groups.google.com/d/msgid/weewx-user/540448ed-3698-4332-a947-6a0b6b7b50ecn%40googlegroups.com.

Marius

--

Marius Schamschula

[Tom Keffer]

Marius, that is a much older problem with several solutions. See the Troubleshooting Davis stations guide in the wiki.

To view this discussion on the web visit https://groups.google.com/d/msgid/weewx-user/73DEDA32-1A68-4FDF-8AC7-71EA073461C2%40gmail.com.

[David Hathaway]

I've had a version of this issue since upgrading to 4.8.0.  In addition to the "Caught WeeWxIOError: Max tries exceeded while getting LOOP data" error, I have "Expected X chars, got Y " errors too.

Oct 28 11:10:15 HP-Iron weewx[3203645] ERROR weewx.drivers.vantage: LOOP try #1; error: Expected to read 99 chars; got 0 instead
Oct 28 11:10:19 HP-Iron weewx[3203645] ERROR weewx.drivers.vantage: LOOP try #2; error: Expected to read 99 chars; got 11 instead
Oct 28 11:10:23 HP-Iron weewx[3203645] ERROR weewx.drivers.vantage: LOOP try #3; error: Expected to read 99 chars; got 20 instead

It will run for a little bit, then eventually fail.  I have cron jobs that kill WeeWX and starts it again 15 minutes later.  It will catch up and then will start having errors and either die, or it is killed because it will fail anyways.

I'm on 4.8.0.  I'll see if 4.9.1 improves things.

Dave

[wfs...@gmail.com]

Hi Tom,

Yeah it looks like I am running just one instance of Weewx and using that replacement vantage.py gets me past the error.

Walt

<tail -f /var/log/syslog with V4.9.1 vantage.py>

Oct 28 10:59:18 raspberrypi weewx[787] DEBUG weewx.drivers.vantage: Gentle wake up of console successful
Oct 28 11:00:14 raspberrypi weewx[787] DEBUG weewx.drivers.vantage: Getting archive packets since 2022-10-28 10:45:00 CDT (1666971900)
Oct 28 11:00:16 raspberrypi weewx[787] ERROR weewx.drivers.vantage: Unable to wake up Vantage console
Oct 28 11:00:16 raspberrypi weewx[787] ERROR weewx.drivers.vantage: DMPAFT try #1; error: Unable to wake up Vantage console
Oct 28 11:00:16 raspberrypi weewx[787] DEBUG weewx.drivers.vantage: Getting archive packets since 2022-10-28 10:45:00 CDT (1666971900)
Oct 28 11:00:16 raspberrypi weewx[787] ERROR weewx.drivers.vantage: Unable to wake up Vantage console
Oct 28 11:00:16 raspberrypi weewx[787] ERROR weewx.drivers.vantage: DMPAFT try #2; error: Unable to wake up Vantage console
Oct 28 11:00:16 raspberrypi weewx[787] DEBUG weewx.drivers.vantage: Getting archive packets since 2022-10-28 10:45:00 CDT (1666971900)
Oct 28 11:00:16 raspberrypi weewx[787] ERROR weewx.drivers.vantage: Unable to wake up Vantage console
Oct 28 11:00:16 raspberrypi weewx[787] ERROR weewx.drivers.vantage: DMPAFT try #3; error: Unable to wake up Vantage console
Oct 28 11:00:16 raspberrypi weewx[787] DEBUG weewx.drivers.vantage: Getting archive packets since 2022-10-28 10:45:00 CDT (1666971900)
Oct 28 11:00:16 raspberrypi weewx[787] ERROR weewx.drivers.vantage: Unable to wake up Vantage console
Oct 28 11:00:16 raspberrypi weewx[787] ERROR weewx.drivers.vantage: DMPAFT try #4; error: Unable to wake up Vantage console
Oct 28 11:00:16 raspberrypi weewx[787] ERROR weewx.drivers.vantage: DMPAFT max tries (4) exceeded.
Oct 28 11:00:16 raspberrypi weewx[787] INFO weewx.engine: Main loop exiting. Shutting engine down.
Oct 28 11:00:16 raspberrypi weewx[787] DEBUG weewx.restx: Shut down StationRegistry thread.
Oct 28 11:00:16 raspberrypi weewx[787] CRITICAL __main__: Caught WeeWxIOError: Max tries exceeded while getting archive data.
Oct 28 11:00:16 raspberrypi weewx[787] CRITICAL __main__:     ****  Waiting 60 seconds then retrying...
^C
pi@raspberrypi:~ $ ps aux|grep weewxd
root       787  0.9  1.6  34084 15424 ?        S    10:52   0:04 python3 /usr/share/weewx/weewxd --daemon --pidfile=/var/run/weewx.pid /etc/weewx/weewx.conf
pi         858  0.0  0.0   7456   572 pts/0    S+   11:00   0:00 grep --color=auto weewxd
pi@raspberrypi:~ $


<tail -f /var/log/syslog with replacement vantage.py>



Oct 28 11:16:46 raspberrypi systemd[1]: Starting LSB: weewx weather system...
Oct 28 11:16:46 raspberrypi weewx[1012] INFO __main__: Initializing weewx version 4.9.1
Oct 28 11:16:46 raspberrypi weewx[1012] INFO __main__: Using Python 3.9.2 (default, Mar 12 2021, 04:06:34) #012[GCC 10.2.1 20210110]
Oct 28 11:16:46 raspberrypi weewx[1012] INFO __main__: Located at /bin/python3
Oct 28 11:16:46 raspberrypi weewx[1012] INFO __main__: Platform Linux-5.15.61-v7+-armv7l-with-glibc2.31
Oct 28 11:16:46 raspberrypi weewx[1012] INFO __main__: Locale is 'en_US.UTF-8'
Oct 28 11:16:46 raspberrypi weewx[1012] INFO __main__: Using configuration file /etc/weewx/weewx.conf
Oct 28 11:16:46 raspberrypi weewx[1012] INFO __main__: Debug is 1
Oct 28 11:16:46 raspberrypi weewx[1012] INFO __main__: PID file is /var/run/weewx.pid
Oct 28 11:16:46 raspberrypi weewx[1015] DEBUG __main__: Initializing engine
Oct 28 11:16:46 raspberrypi weewx[1015] INFO weewx.engine: Loading station type Vantage (weewx.drivers.vantage)
Oct 28 11:16:47 raspberrypi weewx[1002]: Starting weewx weather system: weewx.
Oct 28 11:16:47 raspberrypi systemd[1]: Started LSB: weewx weather system.
Oct 28 11:16:47 raspberrypi weewx[1015] DEBUG weewx.drivers.vantage: Driver version is 3.5.0
Oct 28 11:16:47 raspberrypi weewx[1015] DEBUG weewx.drivers.vantage: Option loop_request=1
Oct 28 11:16:47 raspberrypi weewx[1015] DEBUG weewx.drivers.vantage: Opened up serial port /dev/ttyUSB0; baud 19200; timeout 4.00
Oct 28 11:16:51 raspberrypi weewx[1015] DEBUG weewx.drivers.vantage: Retry #1 unable to wake up console... sleeping
Oct 28 11:16:53 raspberrypi weewx[1015] DEBUG weewx.drivers.vantage: Successfully woke up Vantage console
Oct 28 11:16:53 raspberrypi weewx[1015] DEBUG weewx.drivers.vantage: Hardware type is 16
Oct 28 11:16:53 raspberrypi weewx[1015] DEBUG weewx.drivers.vantage: ISS ID is 1
Oct 28 11:16:53 raspberrypi weewx[1015] DEBUG weewx.drivers.vantage: Hardware name: Vantage Pro
Oct 28 11:16:53 raspberrypi weewx[1015] DEBUG weewx.engine: Loading service weewx.engine.StdTimeSynch
Oct 28 11:16:53 raspberrypi weewx[1015] DEBUG weewx.engine: Finished loading service weewx.engine.StdTimeSynch
Oct 28 11:16:53 raspberrypi weewx[1015] DEBUG weewx.engine: Loading service weewx.engine.StdConvert
Oct 28 11:16:53 raspberrypi weewx[1015] INFO weewx.engine: StdConvert target unit is 0x1
Oct 28 11:16:53 raspberrypi weewx[1015] DEBUG weewx.engine: Finished loading service weewx.engine.StdConvert
Oct 28 11:16:53 raspberrypi weewx[1015] DEBUG weewx.engine: Loading service weewx.engine.StdCalibrate
Oct 28 11:16:53 raspberrypi weewx[1015] DEBUG weewx.engine: Finished loading service weewx.engine.StdCalibrate
Oct 28 11:16:53 raspberrypi weewx[1015] DEBUG weewx.engine: Loading service weewx.engine.StdQC
Oct 28 11:16:53 raspberrypi weewx[1015] DEBUG weewx.engine: Finished loading service weewx.engine.StdQC
Oct 28 11:16:53 raspberrypi weewx[1015] DEBUG weewx.engine: Loading service weewx.wxservices.StdWXCalculate
Oct 28 11:16:53 raspberrypi weewx[1015] INFO weewx.wxservices: StdWXCalculate will use data binding wx_binding
Oct 28 11:16:53 raspberrypi weewx[1015] DEBUG weewx.manager: Daily summary version is 4.0
Oct 28 11:16:53 raspberrypi weewx[1015] DEBUG weewx.engine: Finished loading service weewx.wxservices.StdWXCalculate
Oct 28 11:16:53 raspberrypi weewx[1015] DEBUG weewx.engine: Loading service weewx.wxxtypes.StdWXXTypes
Oct 28 11:16:53 raspberrypi weewx[1015] DEBUG weewx.engine: Finished loading service weewx.wxxtypes.StdWXXTypes
Oct 28 11:16:53 raspberrypi weewx[1015] DEBUG weewx.engine: Loading service weewx.wxxtypes.StdPressureCooker
Oct 28 11:16:53 raspberrypi weewx[1015] DEBUG weewx.engine: Finished loading service weewx.wxxtypes.StdPressureCooker
Oct 28 11:16:53 raspberrypi weewx[1015] DEBUG weewx.engine: Loading service weewx.wxxtypes.StdRainRater
Oct 28 11:16:53 raspberrypi weewx[1015] DEBUG weewx.engine: Finished loading service weewx.wxxtypes.StdRainRater
Oct 28 11:16:53 raspberrypi weewx[1015] DEBUG weewx.engine: Loading service weewx.wxxtypes.StdDelta
Oct 28 11:16:53 raspberrypi weewx[1015] DEBUG weewx.engine: Finished loading service weewx.wxxtypes.StdDelta
Oct 28 11:16:53 raspberrypi weewx[1015] DEBUG weewx.engine: Loading service weewx.engine.StdArchive
Oct 28 11:16:53 raspberrypi weewx[1015] INFO weewx.engine: Archive will use data binding wx_binding
Oct 28 11:16:53 raspberrypi weewx[1015] INFO weewx.engine: Record generation will be attempted in 'hardware'
Oct 28 11:16:53 raspberrypi weewx[1015] INFO weewx.engine: Using archive interval of 900 seconds (specified by hardware)
Oct 28 11:16:53 raspberrypi weewx[1015] DEBUG weewx.engine: Use LOOP data in hi/low calculations: 1
Oct 28 11:16:53 raspberrypi weewx[1015] DEBUG weewx.engine: Finished loading service weewx.engine.StdArchive
Oct 28 11:16:53 raspberrypi weewx[1015] DEBUG weewx.engine: Loading service weewx.restx.StdStationRegistry
Oct 28 11:16:53 raspberrypi weewx[1015] INFO weewx.restx: StationRegistry: Station will be registered.
Oct 28 11:16:53 raspberrypi weewx[1015] DEBUG weewx.engine: Finished loading service weewx.restx.StdStationRegistry
Oct 28 11:16:53 raspberrypi weewx[1015] DEBUG weewx.engine: Loading service weewx.restx.StdWunderground
Oct 28 11:16:53 raspberrypi weewx[1015] INFO weewx.restx: Wunderground: Posting not enabled.
Oct 28 11:16:53 raspberrypi weewx[1015] DEBUG weewx.engine: Finished loading service weewx.restx.StdWunderground
Oct 28 11:16:53 raspberrypi weewx[1015] DEBUG weewx.engine: Loading service weewx.restx.StdPWSweather
Oct 28 11:16:53 raspberrypi weewx[1015] INFO weewx.restx: PWSweather: Posting not enabled.
Oct 28 11:16:53 raspberrypi weewx[1015] DEBUG weewx.engine: Finished loading service weewx.restx.StdPWSweather
Oct 28 11:16:53 raspberrypi weewx[1015] DEBUG weewx.engine: Loading service weewx.restx.StdCWOP
Oct 28 11:16:53 raspberrypi weewx[1015] INFO weewx.restx: CWOP: Posting not enabled.
Oct 28 11:16:53 raspberrypi weewx[1015] DEBUG weewx.engine: Finished loading service weewx.restx.StdCWOP
Oct 28 11:16:53 raspberrypi weewx[1015] DEBUG weewx.engine: Loading service weewx.restx.StdWOW
Oct 28 11:16:53 raspberrypi weewx[1015] INFO weewx.restx: WOW: Posting not enabled.
Oct 28 11:16:53 raspberrypi weewx[1015] DEBUG weewx.engine: Finished loading service weewx.restx.StdWOW
Oct 28 11:16:53 raspberrypi weewx[1015] DEBUG weewx.engine: Loading service weewx.restx.StdAWEKAS
Oct 28 11:16:53 raspberrypi weewx[1015] INFO weewx.restx: AWEKAS: Posting not enabled.
Oct 28 11:16:53 raspberrypi weewx[1015] DEBUG weewx.engine: Finished loading service weewx.restx.StdAWEKAS
Oct 28 11:16:53 raspberrypi weewx[1015] DEBUG weewx.engine: Loading service weewx.engine.StdPrint
Oct 28 11:16:53 raspberrypi weewx[1015] DEBUG weewx.engine: Finished loading service weewx.engine.StdPrint
Oct 28 11:16:53 raspberrypi weewx[1015] DEBUG weewx.engine: Loading service weewx.engine.StdReport
Oct 28 11:16:53 raspberrypi weewx[1015] INFO weewx.engine: 'pyephem' not detected, extended almanac data is not available
Oct 28 11:16:53 raspberrypi weewx[1015] DEBUG weewx.engine: Finished loading service weewx.engine.StdReport
Oct 28 11:16:53 raspberrypi weewx[1015] INFO __main__: Starting up weewx version 4.9.1
Oct 28 11:16:54 raspberrypi weewx[1015] DEBUG weewx.drivers.vantage: Successfully woke up Vantage console
Oct 28 11:16:54 raspberrypi weewx[1015] INFO weewx.engine: Clock error is -0.15 seconds (positive is fast)
Oct 28 11:16:54 raspberrypi weewx[1015] INFO weewx.engine: Using binding 'wx_binding' to database 'weewx.sdb'
Oct 28 11:16:54 raspberrypi weewx[1015] INFO weewx.manager: Starting backfill of daily summaries
Oct 28 11:16:54 raspberrypi weewx[1015] INFO weewx.manager: Daily summaries up to date
Oct 28 11:16:54 raspberrypi weewx[1015] DEBUG weewx.drivers.vantage: Getting archive packets since 2022-10-28 11:00:00 CDT (1666972800)
Oct 28 11:16:54 raspberrypi weewx[1015] DEBUG weewx.drivers.vantage: Successfully woke up Vantage console
Oct 28 11:16:54 raspberrypi weewx[1015] DEBUG weewx.drivers.vantage: Retrieving 1 page(s); starting index= 2
Oct 28 11:16:54 raspberrypi weewx[1015] INFO weewx.manager: Added record 2022-10-28 11:15:00 CDT (1666973700) to database 'weewx.sdb'
Oct 28 11:16:54 raspberrypi weewx[1015] INFO weewx.manager: Added record 2022-10-28 11:15:00 CDT (1666973700) to daily summary in 'weewx.sdb'
Oct 28 11:16:55 raspberrypi weewx[1015] DEBUG weewx.drivers.vantage: DMPAFT complete: page timestamp 2022-10-01 19:30:00 CDT (1664670600) less than final timestamp 2022-10-28 11:15:00 CDT (1666973700)
Oct 28 11:16:55 raspberrypi weewx[1015] DEBUG weewx.drivers.vantage: Catch up complete.
Oct 28 11:16:55 raspberrypi weewx[1015] INFO weewx.engine: Starting main packet loop.
Oct 28 11:16:55 raspberrypi weewx[1015] INFO weewx.restx: StationRegistry: Published record 2022-10-28 11:15:00 CDT (1666973700)
Oct 28 11:16:59 raspberrypi weewx[1015] DEBUG weewx.drivers.vantage: Retry #1 unable to wake up console... sleeping
Oct 28 11:17:01 raspberrypi weewx[1015] DEBUG weewx.drivers.vantage: Successfully woke up Vantage console
Oct 28 11:17:01 raspberrypi weewx[1015] DEBUG weewx.drivers.vantage: Requesting 200 LOOP packets.
Oct 28 11:17:01 raspberrypi CRON[1025]: (root) CMD (   cd / && run-parts --report /etc/cron.hourly)
Oct 28 11:17:01 raspberrypi weewx[1015] DEBUG weewx.drivers.vantage: Successfully woke up Vantage console
Oct 28 11:23:37 raspberrypi weewx[1015] DEBUG weewx.drivers.vantage: Successfully woke up Vantage console
Oct 28 11:28:01 raspberrypi CRON[1043]: (pi) CMD (./nwsInfo1.sh)
Oct 28 11:30:14 raspberrypi weewx[1015] DEBUG weewx.drivers.vantage: Getting archive packets since 2022-10-28 11:15:00 CDT (1666973700)
Oct 28 11:30:15 raspberrypi weewx[1015] DEBUG weewx.drivers.vantage: Successfully woke up Vantage console
Oct 28 11:30:15 raspberrypi weewx[1015] DEBUG weewx.drivers.vantage: Retrieving 1 page(s); starting index= 3
Oct 28 11:30:15 raspberrypi weewx[1015] INFO weewx.manager: Added record 2022-10-28 11:30:00 CDT (1666974600) to database 'weewx.sdb'
Oct 28 11:30:15 raspberrypi weewx[1015] INFO weewx.manager: Added record 2022-10-28 11:30:00 CDT (1666974600) to daily summary in 'weewx.sdb'
Oct 28 11:30:15 raspberrypi weewx[1015] DEBUG weewx.drivers.vantage: DMPAFT complete: page timestamp 2022-10-01 19:45:00 CDT (1664671500) less than final timestamp 2022-10-28 11:30:00 CDT (1666974600)
Oct 28 11:30:15 raspberrypi weewx[1015] DEBUG weewx.restx: StationRegistry: wait interval (900 < 86400) has not passed for record 2022-10-28 11:30:00 CDT (1666974600)
Oct 28 11:30:15 raspberrypi weewx[1015] DEBUG weewx.drivers.vantage: Catch up complete.
Oct 28 11:30:15 raspberrypi weewx[1015] DEBUG weewx.reportengine: Running reports for latest time in the database.
Oct 28 11:30:15 raspberrypi weewx[1015] DEBUG weewx.drivers.vantage: Requesting 200 LOOP packets.
Oct 28 11:30:15 raspberrypi weewx[1015] DEBUG weewx.reportengine: Running report 'CustomReport'
Oct 28 11:30:15 raspberrypi weewx[1015] DEBUG weewx.reportengine: Found configuration file /etc/weewx/skins/Custom/skin.conf for report 'CustomReport'
Oct 28 11:30:15 raspberrypi weewx[1015] DEBUG weewx.reportengine: Cannot read localization file /etc/weewx/skins/Custom/lang/en.conf for report 'CustomReport': Config file not found: "/etc/weewx/skins/Custom/lang/en.conf".
Oct 28 11:30:15 raspberrypi weewx[1015] DEBUG weewx.reportengine: **** Using defaults instead.
Oct 28 11:30:16 raspberrypi weewx[1015] 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']
Oct 28 11:30:16 raspberrypi weewx[1015] DEBUG weewx.manager: Daily summary version is 4.0
Oct 28 11:30:17 raspberrypi weewx[1015] INFO weewx.cheetahgenerator: Generated 1 files for report CustomReport in 1.49 seconds
Oct 28 11:30:17 raspberrypi weewx[1015] DEBUG weewx.manager: Daily summary version is 4.0
Oct 28 11:30:17 raspberrypi weewx[1015] DEBUG PIL.PngImagePlugin: STREAM b'IHDR' 16 13
Oct 28 11:30:17 raspberrypi weewx[1015] DEBUG PIL.PngImagePlugin: STREAM b'IDAT' 41 1216
Oct 28 11:30:17 raspberrypi weewx[1015] DEBUG PIL.PngImagePlugin: STREAM b'IHDR' 16 13
Oct 28 11:30:17 raspberrypi weewx[1015] DEBUG PIL.PngImagePlugin: STREAM b'IDAT' 41 1216
Oct 28 11:30:17 raspberrypi weewx[1015] DEBUG PIL.PngImagePlugin: STREAM b'IHDR' 16 13
Oct 28 11:30:17 raspberrypi weewx[1015] DEBUG PIL.PngImagePlugin: STREAM b'IDAT' 41 1216
Oct 28 11:30:17 raspberrypi weewx[1015] DEBUG PIL.PngImagePlugin: STREAM b'IHDR' 16 13
Oct 28 11:30:17 raspberrypi weewx[1015] DEBUG PIL.PngImagePlugin: STREAM b'IDAT' 41 1216
Oct 28 11:30:18 raspberrypi weewx[1015] INFO weewx.imagegenerator: Generated 2 images for report CustomReport in 0.28 seconds
Oct 28 11:30:18 raspberrypi weewx[1015] INFO weewx.reportengine: Copied 1 files to /var/weewx/reports
Oct 28 11:30:18 raspberrypi weewx[1015] DEBUG weewx.reportengine: Running report 'SeasonsReport'
Oct 28 11:30:18 raspberrypi weewx[1015] DEBUG weewx.reportengine: Found configuration file /etc/weewx/skins/Seasons/skin.conf for report 'SeasonsReport'
Oct 28 11:30:18 raspberrypi weewx[1015] 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']
Oct 28 11:30:18 raspberrypi weewx[1015] DEBUG weewx.manager: Daily summary version is 4.0
Oct 28 11:30:20 raspberrypi weewx[1015] DEBUG weewx.drivers.vantage: Retry #1 unable to wake up console... sleeping
Oct 28 11:30:22 raspberrypi weewx[1015] DEBUG weewx.drivers.vantage: Successfully woke up Vantage console
Oct 28 11:30:59 raspberrypi weewx[1015] INFO weewx.cheetahgenerator: Generated 61 files for report SeasonsReport in 41.76 seconds
Oct 28 11:30:59 raspberrypi weewx[1015] DEBUG weewx.manager: Daily summary version is 4.0
Oct 28 11:31:07 raspberrypi weewx[1015] INFO weewx.imagegenerator: Generated 60 images for report SeasonsReport in 8.02 seconds
Oct 28 11:31:07 raspberrypi weewx[1015] INFO weewx.reportengine: Copied 5 files to /var/weewx/reports
Oct 28 11:31:07 raspberrypi weewx[1015] DEBUG weewx.reportengine: Report 'SmartphoneReport' not enabled. Skipping.
Oct 28 11:31:08 raspberrypi weewx[1015] DEBUG weewx.reportengine: Report 'MobileReport' not enabled. Skipping.
Oct 28 11:31:08 raspberrypi weewx[1015] DEBUG weewx.reportengine: Report 'StandardReport' not enabled. Skipping.
Oct 28 11:31:08 raspberrypi weewx[1015] DEBUG weewx.reportengine: Running report 'StackedWindRose'
Oct 28 11:31:08 raspberrypi weewx[1015] DEBUG weewx.reportengine: Found configuration file /etc/weewx/skins/StackedWindRose/skin.conf for report 'StackedWindRose'
Oct 28 11:31:08 raspberrypi weewx[1015] DEBUG weewx.reportengine: Cannot read localization file /etc/weewx/skins/StackedWindRose/lang/en.conf for report 'StackedWindRose': Config file not found: "/etc/weewx/skins/StackedWindRose/lang/en.conf".
Oct 28 11:31:08 raspberrypi weewx[1015] DEBUG weewx.reportengine: **** Using defaults instead.
Oct 28 11:31:08 raspberrypi weewx[1015] DEBUG weewx.manager: Daily summary version is 4.0
Oct 28 11:31:08 raspberrypi weewx[1015] INFO user.stackedwindrose: Generated 2 images for StackedWindRose in 0.28 seconds
Oct 28 11:31:08 raspberrypi weewx[1015] DEBUG weewx.reportengine: Running report 'NWSReport'
Oct 28 11:31:08 raspberrypi weewx[1015] DEBUG weewx.reportengine: Found configuration file /etc/weewx/skins/nws/skin.conf for report 'NWSReport'
Oct 28 11:31:08 raspberrypi weewx[1015] DEBUG weewx.reportengine: Cannot read localization file /etc/weewx/skins/nws/lang/en.conf for report 'NWSReport': Config file not found: "/etc/weewx/skins/nws/lang/en.conf".
Oct 28 11:31:08 raspberrypi weewx[1015] DEBUG weewx.reportengine: **** Using defaults instead.
Oct 28 11:31:08 raspberrypi weewx[1015] DEBUG weewx.cheetahgenerator: Using search list ['user.nws.NWSForecastVariables', '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']
Oct 28 11:31:08 raspberrypi weewx[1015] DEBUG weewx.manager: Daily summary version is 4.0
Oct 28 11:31:12 raspberrypi weewx[1015] INFO weewx.cheetahgenerator: Generated 1 files for report NWSReport in 4.40 seconds
Oct 28 11:31:12 raspberrypi weewx[1015] DEBUG weewx.reportengine: Report 'FTP' not enabled. Skipping.
Oct 28 11:31:12 raspberrypi weewx[1015] DEBUG weewx.reportengine: Report 'RSYNC' not enabled. Skipping.

[wfs...@gmail.com]

Spoke too soon!  Although the 11:30 am reports worked, the following interval at 11:45 am did not:

Oct 28 11:31:12 raspberrypi weewx[1015] DEBUG weewx.reportengine: Report 'RSYNC' not enabled. Skipping.

Oct 28 11:36:57 raspberrypi weewx[1015] DEBUG weewx.drivers.vantage: Successfully woke up Vantage console
Oct 28 11:43:01 raspberrypi CRON[1070]: (pi) CMD (./nwsInfo1.sh)
Oct 28 11:43:35 raspberrypi weewx[1015] DEBUG weewx.drivers.vantage: Successfully woke up Vantage console
Oct 28 11:45:14 raspberrypi weewx[1015] DEBUG weewx.drivers.vantage: Getting archive packets since 2022-10-28 11:30:00 CDT (1666974600)
Oct 28 11:45:16 raspberrypi weewx[1015] DEBUG weewx.drivers.vantage: Bad wake-up response from Vantage console: b'LO'
Oct 28 11:45:16 raspberrypi weewx[1015] DEBUG weewx.drivers.vantage: Retry #1 unable to wake up console... sleeping
Oct 28 11:45:18 raspberrypi weewx[1015] DEBUG weewx.drivers.vantage: Bad wake-up response from Vantage console: b'O\x00'
Oct 28 11:45:18 raspberrypi weewx[1015] DEBUG weewx.drivers.vantage: Retry #2 unable to wake up console... sleeping
Oct 28 11:45:20 raspberrypi weewx[1015] DEBUG weewx.drivers.vantage: Bad wake-up response from Vantage console: b'\x00\xad'
Oct 28 11:45:20 raspberrypi weewx[1015] DEBUG weewx.drivers.vantage: Retry #3 unable to wake up console... sleeping
Oct 28 11:45:21 raspberrypi weewx[1015] DEBUG weewx.drivers.vantage: Bad wake-up response from Vantage console: b'\x02\x19'
Oct 28 11:45:21 raspberrypi weewx[1015] DEBUG weewx.drivers.vantage: Retry #4 unable to wake up console... sleeping
Oct 28 11:45:23 raspberrypi weewx[1015] ERROR weewx.drivers.vantage: Unable to wake up Vantage console
Oct 28 11:45:23 raspberrypi weewx[1015] ERROR weewx.drivers.vantage: DMPAFT try #1; error: Unable to wake up Vantage console
Oct 28 11:45:23 raspberrypi weewx[1015] DEBUG weewx.drivers.vantage: Getting archive packets since 2022-10-28 11:30:00 CDT (1666974600)
Oct 28 11:45:23 raspberrypi weewx[1015] DEBUG weewx.drivers.vantage: Bad wake-up response from Vantage console: b'v\xc5'
Oct 28 11:45:23 raspberrypi weewx[1015] DEBUG weewx.drivers.vantage: Retry #1 unable to wake up console... sleeping
Oct 28 11:45:25 raspberrypi weewx[1015] DEBUG weewx.drivers.vantage: Bad wake-up response from Vantage console: b'\x02)'
Oct 28 11:45:25 raspberrypi weewx[1015] DEBUG weewx.drivers.vantage: Retry #2 unable to wake up console... sleeping
Oct 28 11:45:26 raspberrypi weewx[1015] DEBUG weewx.drivers.vantage: Bad wake-up response from Vantage console: b'N\x02'
Oct 28 11:45:26 raspberrypi weewx[1015] DEBUG weewx.drivers.vantage: Retry #3 unable to wake up console... sleeping
Oct 28 11:45:28 raspberrypi weewx[1015] DEBUG weewx.drivers.vantage: Bad wake-up response from Vantage console: b'\x00\x01'
Oct 28 11:45:28 raspberrypi weewx[1015] DEBUG weewx.drivers.vantage: Retry #4 unable to wake up console... sleeping
Oct 28 11:45:29 raspberrypi weewx[1015] ERROR weewx.drivers.vantage: Unable to wake up Vantage console
Oct 28 11:45:29 raspberrypi weewx[1015] ERROR weewx.drivers.vantage: DMPAFT try #2; error: Unable to wake up Vantage console
Oct 28 11:45:29 raspberrypi weewx[1015] DEBUG weewx.drivers.vantage: Getting archive packets since 2022-10-28 11:30:00 CDT (1666974600)
Oct 28 11:45:30 raspberrypi weewx[1015] DEBUG weewx.drivers.vantage: Bad wake-up response from Vantage console: b'\x08\x00'
Oct 28 11:45:30 raspberrypi weewx[1015] DEBUG weewx.drivers.vantage: Retry #1 unable to wake up console... sleeping
Oct 28 11:45:32 raspberrypi weewx[1015] DEBUG weewx.drivers.vantage: Bad wake-up response from Vantage console: b'\xff\xff'
Oct 28 11:45:32 raspberrypi weewx[1015] DEBUG weewx.drivers.vantage: Retry #2 unable to wake up console... sleeping
Oct 28 11:45:33 raspberrypi weewx[1015] DEBUG weewx.drivers.vantage: Bad wake-up response from Vantage console: b'\xff\xff'
Oct 28 11:45:33 raspberrypi weewx[1015] DEBUG weewx.drivers.vantage: Retry #3 unable to wake up console... sleeping
Oct 28 11:45:35 raspberrypi weewx[1015] DEBUG weewx.drivers.vantage: Bad wake-up response from Vantage console: b'\xff\xff'
Oct 28 11:45:35 raspberrypi weewx[1015] DEBUG weewx.drivers.vantage: Retry #4 unable to wake up console... sleeping
Oct 28 11:45:36 raspberrypi weewx[1015] ERROR weewx.drivers.vantage: Unable to wake up Vantage console
Oct 28 11:45:36 raspberrypi weewx[1015] ERROR weewx.drivers.vantage: DMPAFT try #3; error: Unable to wake up Vantage console
Oct 28 11:45:36 raspberrypi weewx[1015] DEBUG weewx.drivers.vantage: Getting archive packets since 2022-10-28 11:30:00 CDT (1666974600)
Oct 28 11:45:37 raspberrypi weewx[1015] DEBUG weewx.drivers.vantage: Bad wake-up response from Vantage console: b'\xff\xff'
Oct 28 11:45:37 raspberrypi weewx[1015] DEBUG weewx.drivers.vantage: Retry #1 unable to wake up console... sleeping
Oct 28 11:45:38 raspberrypi weewx[1015] DEBUG weewx.drivers.vantage: Bad wake-up response from Vantage console: b'\xff\xff'
Oct 28 11:45:38 raspberrypi weewx[1015] DEBUG weewx.drivers.vantage: Retry #2 unable to wake up console... sleeping
Oct 28 11:45:40 raspberrypi weewx[1015] DEBUG weewx.drivers.vantage: Bad wake-up response from Vantage console: b'\xff\xff'
Oct 28 11:45:40 raspberrypi weewx[1015] DEBUG weewx.drivers.vantage: Retry #3 unable to wake up console... sleeping
Oct 28 11:45:42 raspberrypi weewx[1015] DEBUG weewx.drivers.vantage: Bad wake-up response from Vantage console: b'\xff\xff'
Oct 28 11:45:42 raspberrypi weewx[1015] DEBUG weewx.drivers.vantage: Retry #4 unable to wake up console... sleeping
Oct 28 11:45:43 raspberrypi weewx[1015] ERROR weewx.drivers.vantage: Unable to wake up Vantage console
Oct 28 11:45:43 raspberrypi weewx[1015] ERROR weewx.drivers.vantage: DMPAFT try #4; error: Unable to wake up Vantage console
Oct 28 11:45:43 raspberrypi weewx[1015] ERROR weewx.drivers.vantage: DMPAFT max tries (4) exceeded.
Oct 28 11:45:43 raspberrypi weewx[1015] INFO weewx.engine: Main loop exiting. Shutting engine down.
Oct 28 11:45:43 raspberrypi weewx[1015] INFO weewx.engine: Shutting down StdReport thread
Oct 28 11:45:43 raspberrypi weewx[1015] DEBUG weewx.engine: StdReport thread has been terminated
Oct 28 11:45:43 raspberrypi weewx[1015] DEBUG weewx.restx: Shut down StationRegistry thread.
Oct 28 11:45:43 raspberrypi weewx[1015] CRITICAL __main__: Caught WeeWxIOError: Max tries exceeded while getting archive data.
Oct 28 11:45:43 raspberrypi weewx[1015] CRITICAL __main__:     ****  Waiting 60 seconds then retrying...

[Tom Keffer]

Thanks, Walt

I would guess that the driver is not waiting long enough after sending the wake-up command. 

Try this version.

Thanks for your patience!

-tk

To view this discussion on the web visit https://groups.google.com/d/msgid/weewx-user/475d7135-3437-4861-b566-a00d04d601a7n%40googlegroups.com.

[vince]

Tom - the instrumented version you provided in your reply to me yesterday stayed up for a day after the two failures I caught briefly yesterday.

The latest master branch one 9e291 was a total fail here (logs attached)

[wfs...@gmail.com]

Still getting the error.  It looks like the console is still sending LOOP packets.

The first wakeup response begins with  'LOO...'

Oct 28 14:45:14 raspberrypi weewx[879] DEBUG weewx.drivers.vantage: Getting archive packets since 2022-10-28 14:30:00 CDT (1666985400)

Oct 28 14:45:16 raspberrypi weewx[879] DEBUG weewx.drivers.vantage: Bad wake-up response from Vantage console: b'LO'
Oct 28 14:45:16 raspberrypi weewx[879] DEBUG weewx.drivers.vantage: Retry #1 unable to wake up console... sleeping
Oct 28 14:45:18 raspberrypi weewx[879] DEBUG weewx.drivers.vantage: Bad wake-up response from Vantage console: b'O\xec'
Oct 28 14:45:18 raspberrypi weewx[879] DEBUG weewx.drivers.vantage: Retry #2 unable to wake up console... sleeping
Oct 28 14:45:20 raspberrypi weewx[879] DEBUG weewx.drivers.vantage: Bad wake-up response from Vantage console: b'\x00\xb9'

[Tom Keffer]

Thanks, Walt. I'm working on a solution with Vince. I think we're getting close.

To view this discussion on the web visit https://groups.google.com/d/msgid/weewx-user/db6497b6-2ebb-46b2-b6cb-81297a484d93n%40googlegroups.com.

[Tom Keffer]

OK, we have a candidate solution. It occasionally gets a single wake-up error, but always recovers on the first try.

V3.5.1 vantage.py 

Please let me know about your experience with it.

[wfs...@gmail.com]

It's working for me.  Thanks Tom & Vince.  Attached is a grep of syslog weewx.drivers.vantage messages.

Walt

[Darryl]

4.9.1 does not work on an old Vantage Pro (1 not 2).  See my email to the group on 11/11 for my specific details, log files, etc.

Will there be a release forthcoming that addresses the situation of not being able to wake the console or should I stay at 4.8.0, which works, and inhibit upgrades?

Thank you very much to the author of the software.  Weewx is great and I appreciate your efforts in making this available to the community.

Regards,

Darryl

[Tom Keffer]

Please give this version of the driver a try. Let me know if it works out. 

https://raw.githubusercontent.com/weewx/weewx/cc170f715a2e1261a632ece67626d0ecc1c00065/bin/weewx/drivers/vantage.py

To view this discussion on the web visit https://groups.google.com/d/msgid/weewx-user/ce5a15fc-e51e-4f9f-b0e6-e793257da1d8n%40googlegroups.com.

[Darryl]

The driver you pointed me to seems to be working.  I see no errors being thrown on the syslog.

Now running Vantage Pro (1, not 2), Weewx 4.9.1, with modified vantage.py driver.

Thank you!

[Greg Troxel]

[issues with 4.9.1 and VP2]

I was scared of this for a while so helf off, and thought a trip report
might be helpful.

I recently took the plunge and udpated from 4.5ish and python 2.7 to
4.9.2a and python 3.10. Specifically to

commit 56337c58cfd5dd07c7510f2618d6de445a190945 (origin/master, origin/HEAD)
Author: Tom Keffer <tke...@gmail.com>
Date: Sun Dec 25 10:32:16 2022 -0800

Which has the fix from upthread, I think.

I think I'm seeing only occasional VP2 errors, nothing unusual. Serial
logger with Prolific USB/serial, RPI3, NetBSD.