Upgrade to Mint 17.3 Rosa

[Matt VanGiesen]

I upgraded to 17.3 and lost ability to collect pool...was working OK until then, though slow to sync with pool sometimes. This has been cycling for 5+ hours now after rebooting and resetting weather station. Keep it simple, cause I'm stupid...or new to Linux (but loving and learning)!

Log file:

Dec  8 12:56:30 matt-Inspiron-1750 weewx[1937]: engine: retrying...

Dec  8 12:56:30 matt-Inspiron-1750 weewx[1937]: engine: Using configuration file /etc/weewx/weewx.conf

Dec  8 12:56:30 matt-Inspiron-1750 weewx[1937]: engine: Initializing engine

Dec  8 12:56:30 matt-Inspiron-1750 weewx[1937]: engine: Loading station type AcuRite (weewx.drivers.acurite)

Dec  8 12:56:30 matt-Inspiron-1750 weewx[1937]: acurite: driver version is 0.24

Dec  8 12:56:30 matt-Inspiron-1750 weewx[1937]: acurite: R2 will be decoded using sensor constants

Dec  8 12:56:30 matt-Inspiron-1750 weewx[1937]: engine: Loading service weewx.engine.StdTimeSynch

Dec  8 12:56:30 matt-Inspiron-1750 weewx[1937]: engine: Finished loading service weewx.engine.StdTimeSynch

Dec  8 12:56:30 matt-Inspiron-1750 weewx[1937]: engine: Loading service weewx.engine.StdConvert

Dec  8 12:56:30 matt-Inspiron-1750 weewx[1937]: engine: StdConvert target unit is 0x1

Dec  8 12:56:30 matt-Inspiron-1750 weewx[1937]: engine: Finished loading service weewx.engine.StdConvert

Dec  8 12:56:30 matt-Inspiron-1750 weewx[1937]: engine: Loading service weewx.engine.StdCalibrate

Dec  8 12:56:30 matt-Inspiron-1750 weewx[1937]: engine: Finished loading service weewx.engine.StdCalibrate

Dec  8 12:56:30 matt-Inspiron-1750 weewx[1937]: engine: Loading service weewx.engine.StdQC

Dec  8 12:56:30 matt-Inspiron-1750 weewx[1937]: engine: Finished loading service weewx.engine.StdQC

Dec  8 12:56:30 matt-Inspiron-1750 weewx[1937]: engine: Loading service weewx.wxservices.StdWXCalculate

Dec  8 12:56:30 matt-Inspiron-1750 weewx[1937]: wxcalculate: The following values will be calculated: barometer=prefer_hardware, windchill=prefer_hardware, dewpoint=prefer_hardware, appTemp=prefer_hardware, rainRate=prefer_hardware, windrun=prefer_hardware, heatindex=prefer_hardware, maxSolarRad=prefer_hardware, humidex=prefer_hardware, pressure=prefer_hardware, inDewpoint=prefer_hardware, ET=prefer_hardware, altimeter=prefer_hardware, cloudbase=prefer_hardware

Dec  8 12:56:30 matt-Inspiron-1750 weewx[1937]: wxcalculate: The following algorithms will be used for calculations: altimeter=aaNOAA, maxSolarRad=RS

Dec  8 12:56:30 matt-Inspiron-1750 weewx[1937]: engine: Finished loading service weewx.wxservices.StdWXCalculate

Dec  8 12:56:30 matt-Inspiron-1750 weewx[1937]: engine: Loading service weewx.engine.StdArchive

Dec  8 12:56:30 matt-Inspiron-1750 weewx[1937]: engine: Archive will use data binding wx_binding

Dec  8 12:56:30 matt-Inspiron-1750 weewx[1937]: engine: Record generation will be attempted in 'hardware'

Dec  8 12:56:30 matt-Inspiron-1750 weewx[1937]: engine: Using archive interval of 300 seconds (specified in weewx configuration)

Dec  8 12:56:30 matt-Inspiron-1750 weewx[1937]: engine: Use LOOP data in hi/low calculations: 1

Dec  8 12:56:30 matt-Inspiron-1750 weewx[1937]: engine: Using binding 'wx_binding' to database 'weewx.sdb'

Dec  8 12:56:30 matt-Inspiron-1750 weewx[1937]: manager: Starting backfill of daily summaries

Dec  8 12:56:30 matt-Inspiron-1750 weewx[1937]: manager: Daily summaries up to date

Dec  8 12:56:30 matt-Inspiron-1750 weewx[1937]: engine: Finished loading service weewx.engine.StdArchive

Dec  8 12:56:30 matt-Inspiron-1750 weewx[1937]: engine: Loading service weewx.restx.StdStationRegistry

Dec  8 12:56:30 matt-Inspiron-1750 weewx[1937]: restx: StationRegistry: Registration not requested.

Dec  8 12:56:30 matt-Inspiron-1750 weewx[1937]: engine: Finished loading service weewx.restx.StdStationRegistry

Dec  8 12:56:30 matt-Inspiron-1750 weewx[1937]: engine: Loading service weewx.restx.StdWunderground

Dec  8 12:56:30 matt-Inspiron-1750 weewx[1937]: restx: Wunderground-PWS: Data for station KWAOAKHA61 will be posted

Dec  8 12:56:30 matt-Inspiron-1750 weewx[1937]: engine: Finished loading service weewx.restx.StdWunderground

Dec  8 12:56:30 matt-Inspiron-1750 weewx[1937]: engine: Loading service weewx.restx.StdPWSweather

Dec  8 12:56:30 matt-Inspiron-1750 weewx[1937]: restx: PWSweather: Posting not enabled.

Dec  8 12:56:30 matt-Inspiron-1750 weewx[1937]: engine: Finished loading service weewx.restx.StdPWSweather

Dec  8 12:56:30 matt-Inspiron-1750 weewx[1937]: engine: Loading service weewx.restx.StdCWOP

Dec  8 12:56:30 matt-Inspiron-1750 weewx[1937]: restx: CWOP: Posting not enabled.

Dec  8 12:56:30 matt-Inspiron-1750 weewx[1937]: engine: Finished loading service weewx.restx.StdCWOP

Dec  8 12:56:30 matt-Inspiron-1750 weewx[1937]: engine: Loading service weewx.restx.StdWOW

Dec  8 12:56:30 matt-Inspiron-1750 weewx[1937]: restx: WOW: Posting not enabled.

Dec  8 12:56:30 matt-Inspiron-1750 weewx[1937]: engine: Finished loading service weewx.restx.StdWOW

Dec  8 12:56:30 matt-Inspiron-1750 weewx[1937]: engine: Loading service weewx.restx.StdAWEKAS

Dec  8 12:56:30 matt-Inspiron-1750 weewx[1937]: restx: AWEKAS: Posting not enabled.

Dec  8 12:56:30 matt-Inspiron-1750 weewx[1937]: engine: Finished loading service weewx.restx.StdAWEKAS

Dec  8 12:56:30 matt-Inspiron-1750 weewx[1937]: engine: Loading service weewx.engine.StdPrint

Dec  8 12:56:30 matt-Inspiron-1750 weewx[1937]: engine: Finished loading service weewx.engine.StdPrint

Dec  8 12:56:30 matt-Inspiron-1750 weewx[1937]: engine: Loading service weewx.engine.StdReport

Dec  8 12:56:30 matt-Inspiron-1750 weewx[1937]: engine: Finished loading service weewx.engine.StdReport

Dec  8 12:56:30 matt-Inspiron-1750 weewx[1937]: engine: Starting up weewx version 3.6.2

Dec  8 12:56:30 matt-Inspiron-1750 weewx[1937]: engine: Station does not support reading the time

Dec  8 12:56:30 matt-Inspiron-1750 weewx[1937]: engine: Starting main packet loop.

Dec  8 12:56:31 matt-Inspiron-1750 weewx[1937]: acurite: Found station at bus=006 device=011

Dec  8 12:56:31 matt-Inspiron-1750 weewx[1937]: acurite: Failed attempt 1 of 10 to get LOOP data: error sending control message: Protocol error

Dec  8 12:56:31 matt-Inspiron-1750 kernel: [13871.146102] usb 6-2: usbfs: USBDEVFS_CONTROL failed cmd python rqt 161 rq 1 len 10 ret -71

Dec  8 12:57:01 matt-Inspiron-1750 weewx[1937]: acurite: Found station at bus=006 device=011

Dec  8 12:57:01 matt-Inspiron-1750 weewx[1937]: acurite: Failed attempt 2 of 10 to get LOOP data: error sending control message: Protocol error

Dec  8 12:57:01 matt-Inspiron-1750 kernel: [13901.412167] usb 6-2: usbfs: USBDEVFS_CONTROL failed cmd python rqt 161 rq 1 len 10 ret -71

Dec  8 12:57:08 matt-Inspiron-1750 wpa_supplicant[842]: wlan0: CTRL-EVENT-SCAN-STARTED 

Dec  8 12:57:13 matt-Inspiron-1750 wpa_supplicant[842]: nl80211: send_and_recv->nl_recvmsgs failed: -33                         

Dec  8 12:57:31 matt-Inspiron-1750 weewx[1937]: acurite: Found station at bus=006 device=011                                    

Dec  8 12:57:31 matt-Inspiron-1750 weewx[1937]: acurite: Failed attempt 3 of 10 to get LOOP data: error sending control message:Protocol error

Dec  8 12:57:31 matt-Inspiron-1750 kernel: [13931.740325] usb 6-2: usbfs: USBDEVFS_CONTROL failed cmd python rqt 161 rq 1 len 10ret -71

Dec  8 12:58:02 matt-Inspiron-1750 weewx[1937]: acurite: Found station at bus=006 device=011

Dec  8 12:58:02 matt-Inspiron-1750 weewx[1937]: acurite: Failed attempt 4 of 10 to get LOOP data: error sending control message:Protocol error

Dec  8 12:58:02 matt-Inspiron-1750 kernel: [13962.064412] usb 6-2: usbfs: USBDEVFS_CONTROL failed cmd python rqt 161 rq 1 len 10ret -71

Dec  8 12:58:32 matt-Inspiron-1750 weewx[1937]: acurite: Found station at bus=006 device=011

Dec  8 12:58:32 matt-Inspiron-1750 weewx[1937]: acurite: Failed attempt 5 of 10 to get LOOP data: error sending control message:Protocol error

Dec  8 12:58:32 matt-Inspiron-1750 kernel: [13992.324531] usb 6-2: usbfs: USBDEVFS_CONTROL failed cmd python rqt 161 rq 1 len 10ret -71

Dec  8 12:59:02 matt-Inspiron-1750 weewx[1937]: acurite: Found station at bus=006 device=011

Dec  8 12:59:02 matt-Inspiron-1750 weewx[1937]: acurite: Failed attempt 6 of 10 to get LOOP data: error sending control message:Protocol error

Dec  8 12:59:02 matt-Inspiron-1750 kernel: [14022.655655] usb 6-2: usbfs: USBDEVFS_CONTROL failed cmd python rqt 161 rq 1 len 10ret -71

Dec  8 12:59:08 matt-Inspiron-1750 wpa_supplicant[842]: wlan0: CTRL-EVENT-SCAN-STARTED 

Dec  8 12:59:32 matt-Inspiron-1750 weewx[1937]: acurite: Found station at bus=006 device=011

Dec  8 12:59:32 matt-Inspiron-1750 kernel: [14052.871810] usb 6-2: usbfs: USBDEVFS_CONTROL failed cmd python rqt 161 rq 1 len 10ret -71

Dec  8 12:59:32 matt-Inspiron-1750 weewx[1937]: acurite: Failed attempt 7 of 10 to get LOOP data: error sending control message:Protocol error

Dec  8 13:00:03 matt-Inspiron-1750 weewx[1937]: acurite: Found station at bus=006 device=011

Dec  8 13:00:03 matt-Inspiron-1750 weewx[1937]: acurite: Failed attempt 8 of 10 to get LOOP data: error sending control message: Protocol error

Dec  8 13:00:03 matt-Inspiron-1750 kernel: [14083.182919] usb 6-2: usbfs: USBDEVFS_CONTROL failed cmd python rqt 161 rq 1 len 10 ret -71

[mwall]

On Thursday, December 8, 2016 at 4:10:34 PM UTC-5, Matt VanGiesen wrote:

I upgraded to 17.3 and lost ability to collect pool...was working OK until then, though slow to sync with pool sometimes. This has been cycling for 5+ hours now after rebooting and resetting weather station. Keep it simple, cause I'm stupid...or new to Linux (but loving and learning)!

what model of acurite console is it?

is the console in usb mode 4?

m

[Matt VanGiesen]

2032 in mode 4

But, when verifying the weewx.conf, I found typo in the model number...I believe when I re-installed after the upgrade weewx used an archived .conf file that was incorrect. I'm vetting it now for more errors..

Thanks for the great support and awesome program!

[mwall]

On Thursday, December 8, 2016 at 4:32:11 PM UTC-5, Matt VanGiesen wrote:

2032 in mode 4

But, when verifying the weewx.conf, I found typo in the model number...I believe when I re-installed after the upgrade weewx used an archived .conf file that was incorrect. I'm vetting it now for more errors..

matt,

first be sure that the console is in mode 4 (when you power cycle it, it will default to mode 2, which means no usb communication)

if you see log messages about failed attempt x to get LOOP data more than 3 or 4 times, then unplug the usb cable from the computer or console, wait 30 seconds, then plug it back in.  no need to restart the computer, restart weewx, or power cycle the acurite console.  unplug/replug will force a usb reset. (for some reason a software reset of the usb does not always work on the acurite consoles)

let us know how it goes

m

[Chris Swanda]

My RPi will do this sometimes due to conflicts of USB devices plugged into it.  

If you have any other USB devices plugged in, remove them and KEEP the Acurite plugged in.  I also will comment out entries in /etc/fstab so they do not auto mount.  Then reboot.

Once your machine detects the Acurite and things are go, uncomment out your entry in /etc/fstab and manually mount your /dev/sdx device.

This only happens very, very rarely with me and these are the ways I fix my usb 6-2: usbfs: USBDEVFS_CONTROL failed cmd python rqt 161 rq 1 len 10 ret -71 errors.