Welcome to the Cumulus Support forum.

Latest Cumulus MX release v3.0.0 build 3050 - 1 May 2019.
Legacy Cumulus 1 release v1.9.4 (build 1099) - 28 November 2014

See the Wiki for downloading either version.

Debug logging not being enabled

Discussion of version 3 of Cumulus, which runs on Windows, Linux, and OS X. All Cumulus MX queries in here, please.

Moderator: mcrossley

Post Reply
SunDial
Posts: 31
Joined: Tue 03 Jan 2017 8:12 pm
Weather Station: OS WMR928N
Operating System: OPi Armbian 5.75 Ubuntu Bionic

Debug logging not being enabled

Post by SunDial » Mon 03 Jun 2019 11:55 am

Hi,

I'm trying to enable debug logging as my ./MXDiag "20190603-122012.txt" file shows "WU Response: Unauthorized: unauthorized" while trying to update WU. As you can see from the log below, debug logging appears to be disabled but is definitely enabled via the CMX Settings > Station Settings > Options > Debug Logging. Could this be a write access issue? As regards the actual WU login error, I know my station ID and password are correctly saved so it's a mystery to me right now what the problem is.

One other thing is the installation directory contains two forward slashes in front of "CumulusMX/CumulusMX.exe" but the CMX web page appears to be running okay. Are the forward slashes normal and something CMX just interprets?

2019-06-03 12:20:12.592 ========================== Cumulus MX starting ==========================
2019-06-03 12:20:12.607 Command line: /usr/local/bin//CumulusMX/CumulusMX.exe -port 8998
2019-06-03 12:20:12.607 Cumulus MX v.3.0.0 build 3050
2019-06-03 12:20:12.611 Platform: Unix
2019-06-03 12:20:12.612 OS version: Unix 4.19.38.0
2019-06-03 12:20:12.614 Mono version: 4.6.2 (Debian 4.6.2.7+dfsg-1ubuntu1)
2019-06-03 12:20:12.614 Current culture: English (United Kingdom)
2019-06-03 12:20:12.614 Directory separator=[/] Decimal separator=[.] List separator=[,]
2019-06-03 12:20:12.615 Date separator=[/] Time separator=[:]
2019-06-03 12:20:12.615 Standard time zone name: GMT
2019-06-03 12:20:12.615 Daylight saving time name: BST
2019-06-03 12:20:12.616 Daylight saving time? True
2019-06-03 12:20:12.616 03/06/2019 12:20:12
2019-06-03 12:20:12.617 Data path = data/
2019-06-03 12:20:12.737 Creating backup folder backup/20190603122012/
2019-06-03 12:20:12.746 Created backup folder backup/20190603122012/
2019-06-03 12:20:12.801 Reading Cumulus.ini file
2019-06-03 12:20:12.829 ASM=10 AST=00:10:00
2019-06-03 12:20:12.830 Cumulus start date: 03 May 2019
2019-06-03 12:20:12.846 Debug logging is disabled
2019-06-03 12:20:12.847 Data logging is disabled
2019-06-03 12:20:12.847 Logging interval = 5
2019-06-03 12:20:12.847 NoSensorCheck = 0
2019-06-03 12:20:13.264 Calculating sunrise and sunset times
2019-06-03 12:20:13.282 Sunrise: 06:54:26
2019-06-03 12:20:13.282 Sunset : 19:01:49
2019-06-03 12:20:13.283 Tomorrow sunrise: 06:54:36
2019-06-03 12:20:13.283 Tomorrow sunset : 19:01:59
2019-06-03 12:20:13.316 Station type: Oregon Scientific WMR-928
2019-06-03 12:20:13.318 WindUnit=mph RainUnit=mm TempUnit=°C PressureUnit=mb
2019-06-03 12:20:13.318 YTDRain=0.000 Year=0
2019-06-03 12:20:13.318 RainDayThreshold=-1.000
2019-06-03 12:20:13.318 Offsets and Multipliers:
2019-06-03 12:20:13.319 PO=0.000 TO=0.000 HO=0 WDO=0 ITO=0.000 UVO=0.000
2019-06-03 12:20:13.319 WSM=1.000 WGM=1.000 TM=1.000 TM2=0.000 HM=1.000 HM2=0.000 RM=1.000 UVM=1.000
2019-06-03 12:20:13.319 Spike removal:
2019-06-03 12:20:13.319 TD=999.000 GD=999.000 WD=999.000 HD=999.000 PD=999.000
2019-06-03 12:20:13.320 MR=999.000 MH=999.000
2019-06-03 12:20:13.320 Cumulus Starting
2019-06-03 12:20:13.323 Opening station
2019-06-03 12:20:13.367 Last update time from today.ini: 03/06/2019 12:10:00
2019-06-03 12:20:13.368 Read today file: Date = 03/06/2019, LastUpdateTime = 03/06/2019 12:10:00, Month = 6
2019-06-03 12:20:13.371 ReadTodayfile: set notraininit false
2019-06-03 12:20:13.387 /usr/local/bin/CumulusMX/data/alltime.ini
2019-06-03 12:20:13.392 Alltime.ini file read
2019-06-03 12:20:13.440 MonthlyAlltime.ini file read
2019-06-03 12:20:13.451 Month.ini file read
2019-06-03 12:20:13.462 Year.ini file read
2019-06-03 12:20:13.466 Finding raintoday from logfile data/Jun19log.txt
2019-06-03 12:20:13.466 Expecting listsep=, decimal=.
2019-06-03 12:20:13.620 Midnight rain found in the following entry:
2019-06-03 12:20:13.620 03/06/19,00:00,14.9,48,4.0,5.8,13.6,285,0.0,0.0,1009.0,0.0,22.2,50,5.8,13.0,14.9,0.0,0,0.00,0.00,11.8,0,0.0,345,0.0 ,0.0
2019-06-03 12:20:13.652 Setting raintoday from logfile = 0
2019-06-03 12:20:13.652 Calculating rain counter = 0
2019-06-03 12:20:13.653 Midnight rain found = 0
2019-06-03 12:20:13.657 Getting rain totals, rain season start = 1
2019-06-03 12:20:13.657 Today = 03/06/19
2019-06-03 12:20:13.661 Rainthismonth from dayfile.txt: 0
2019-06-03 12:20:13.661 Rainthisyear from dayfile.txt: 0
2019-06-03 12:20:13.668 Station type = WMR928
2019-06-03 12:20:13.671 Opening com port /dev/ttyUSB0
2019-06-03 12:20:13.691 Loading last N hour data from data logs: 03/06/2019 12:10:00
2019-06-03 12:20:13.881 Loaded 11 entries to last hour data list
2019-06-03 12:20:14.071 Loaded 35 entries to last 3 hour data list
2019-06-03 12:20:14.276 Loaded 286 entries to graph data list
2019-06-03 12:20:14.678 Loaded 786 entries to recent data list
2019-06-03 12:20:14.686 Loaded 3 entries to daily data list
2019-06-03 12:20:14.920 629 web tags initialised
2019-06-03 12:20:15.003 HTML root path = /usr/local/bin/CumulusMX/interface
2019-06-03 12:20:15.491 Start Timers
2019-06-03 12:20:15.491 Starting 1-minute timer
2019-06-03 12:20:15.497 Realtime not enabled
2019-06-03 12:20:15.498 Normal running
2019-06-03 12:20:15.502 Start normal reading loop
2019-06-03 12:21:02.261 WU Response: Unauthorized: unauthorized
Last edited by SunDial on Mon 03 Jun 2019 12:50 pm, edited 1 time in total.

User avatar
mcrossley
Posts: 5548
Joined: Thu 07 Jan 2010 9:44 pm
Weather Station: Davis VP2
Operating System: Stretch Lite rPi
Location: Wilmslow, Cheshire, UK
Contact:

Re: Debug logging not being enabled

Post by mcrossley » Mon 03 Jun 2019 12:45 pm

If you set debug logging from the admin interface it is for the *current session* only. If you want it set on startup then you have to set it in the ini file.

SunDial
Posts: 31
Joined: Tue 03 Jan 2017 8:12 pm
Weather Station: OS WMR928N
Operating System: OPi Armbian 5.75 Ubuntu Bionic

Re: Debug logging not being enabled

Post by SunDial » Mon 03 Jun 2019 12:48 pm

Hi,

Thanks MC. So either way there should be a debug.log in my CMX directory? Is this correct? If so, I don't see one there.

User avatar
mcrossley
Posts: 5548
Joined: Thu 07 Jan 2010 9:44 pm
Weather Station: Davis VP2
Operating System: Stretch Lite rPi
Location: Wilmslow, Cheshire, UK
Contact:

Re: Debug logging not being enabled

Post by mcrossley » Mon 03 Jun 2019 3:47 pm

No, it just adds additional messages to the existing log - as does data logging. Only FTP logging creates an additional file.

SunDial
Posts: 31
Joined: Tue 03 Jan 2017 8:12 pm
Weather Station: OS WMR928N
Operating System: OPi Armbian 5.75 Ubuntu Bionic

Re: Debug logging not being enabled

Post by SunDial » Mon 03 Jun 2019 8:26 pm

Thanks MC. I just keep seeing "WU Response: Unauthorized: unauthorized". I completely shut down my OPi Lite, disconnected my WMR928N from the USB port ont he OPi and shut down the WMR928N as well just in case there was something up there but there's been no change following booting up everything again. I successfully changed my WU password and updated the password through the CMX admin console but still no go. By the way, the log I am checking is the latest in the MXdiag sub-folder. Not really sure what else I can do at this point. Any suggestions?

Actually, I've just noticed additionally it's showing:

2019-06-03 21:23:00.236 WU update: Error getting response stream (ReadDone2): ReceiveFailure
2019-06-03 21:24:00.438 WU Response: Unauthorized: unauthorized

SunDial
Posts: 31
Joined: Tue 03 Jan 2017 8:12 pm
Weather Station: OS WMR928N
Operating System: OPi Armbian 5.75 Ubuntu Bionic

Re: Debug logging not being enabled

Post by SunDial » Tue 04 Jun 2019 5:20 pm

I've removed the second version of the error I had in my previous message above as this was a command line output rather than an error actually in the log.

So what I'm seeing repeatedly in thee MXdiag log is below. I'm guessing this may be a Mono issue but since I think others are using Mono v4.6.2, perhaps this is not the case. I read on the https://bugzilla.xamarin.com/show_bug.cgi?id=19673#c4 forum that a forward slash following the "?" character on the URL posting data may be the cause of the error on Xamarin at least and so I wondered if it might be applicable for Linux Mono?

URL for posting data should always end with "/". This makes link posted in this report example not
https://www.paypal.com/de/cgi-bin/webscr?cmd=_login-run
but
https://www.paypal.com/de/cgi-bin/websc ... _login-run
You see that / near webscr? This is the reason why it all failed.


2019-06-04 18:06:00.310 WU Response: Unauthorized: unauthorized
2019-06-04 18:07:00.367 WU update: Error getting response stream (ReadDone2): ReceiveFailure


If anyone would like to see my MXdiag log or ini files please let me know.

Hoping someone can help me out here. :) :) :)

User avatar
mcrossley
Posts: 5548
Joined: Thu 07 Jan 2010 9:44 pm
Weather Station: Davis VP2
Operating System: Stretch Lite rPi
Location: Wilmslow, Cheshire, UK
Contact:

Re: Debug logging not being enabled

Post by mcrossley » Fri 07 Jun 2019 9:30 am

Normally WU unauthorised errors are due to people using the wrong password. WU provides you with two passwords, one for logging in to the web site, and another for uploading data, make sure you are using the latter in Cumulus - this is the Station Key registered with your PWS ID.

SunDial
Posts: 31
Joined: Tue 03 Jan 2017 8:12 pm
Weather Station: OS WMR928N
Operating System: OPi Armbian 5.75 Ubuntu Bionic

Re: Debug logging not being enabled

Post by SunDial » Mon 10 Jun 2019 9:01 pm

Hi Mark,

Oh! I didn't realise that. Doh! :oops: :bash: Is that described somewhere in the installation instructions, do you know? Perhaps CMX should have the Password box under internet settings described as "Key" instead since that's what WU refers to it as? The "Error getting response stream (ReadDone2): ReceiveFailure" was a real red herring but I'm reassured that none of the 130+ views/viewers of my question twigged the error either, except for you. :lol: After all that I'm now online with WU so thank you very much for not overlooking what turned out to be a basic error on my part. :clap: :clap: :clap:

Cheers!

SunDial
Posts: 31
Joined: Tue 03 Jan 2017 8:12 pm
Weather Station: OS WMR928N
Operating System: OPi Armbian 5.75 Ubuntu Bionic

Re: Debug logging not being enabled

Post by SunDial » Mon 10 Jun 2019 9:17 pm

Interestingly , I just check my MXDiags log and I still get the (ReadDone2) failure. My station is online but I'm nt sure if something might be failing to send perhaps? I''ve removed my station ID.

2019-06-10 22:13:00.458 WU update: Error getting response stream (ReadDone2): ReceiveFailure
2019-06-10 22:13:27.824 Updating station settings
2019-06-10 22:13:27.825 Writing Cumulus.ini file
2019-06-10 22:13:28.006 Data received, number of bytes = 9
2019-06-10 22:13:28.006 Packet received: FFFF020288016212FF
2019-06-10 22:13:28.006 Data: FF FF 02 02 88 01 62 12 FF
2019-06-10 22:13:29.206 Data received, number of bytes = 14
2019-06-10 22:13:29.207 Packet received: FFFF02049701561103
2019-06-10 22:13:29.207 Packet received: FFFF0E3945
2019-06-10 22:13:29.207 Data: FF FF 02 04 97 01 56 11 03 FF FF 0E 39 45
2019-06-10 22:13:35.408 Data received, number of bytes = 11
2019-06-10 22:13:35.408 Packet received: FFFF00006702001400118C
2019-06-10 22:13:35.411 Data: FF FF 00 00 67 02 00 14 00 11 8C
2019-06-10 22:13:38.612 Data received, number of bytes = 14
2019-06-10 22:13:38.613 Packet received: FFFF06400002511099390000067F
2019-06-10 22:13:38.613 Data: FF FF 06 40 00 02 51 10 99 39 00 00 06 7F
2019-06-10 22:13:41.814 Data received, number of bytes = 9
2019-06-10 22:13:41.814 Packet received: FFFF030110019811BC
2019-06-10 22:13:41.814 Data: FF FF 03 01 10 01 98 11 BC
2019-06-10 22:13:49.015 Data received, number of bytes = 11
2019-06-10 22:13:49.015 Packet received: FFFF00006702011400118D
2019-06-10 22:13:49.018 Data: FF FF 00 00 67 02 01 14 00 11 8D
2019-06-10 22:13:54.219 Data received, number of bytes = 9
2019-06-10 22:13:54.219 Packet received: FFFF020109019410AF
2019-06-10 22:13:54.220 Data: FF FF 02 01 09 01 94 10 AF
2019-06-10 22:14:00.293 http://weatherstation.wunderground.com/ ... =updateraw
2019-06-10 22:14:00.671 WU Response: OK: success

User avatar
mcrossley
Posts: 5548
Joined: Thu 07 Jan 2010 9:44 pm
Weather Station: Davis VP2
Operating System: Stretch Lite rPi
Location: Wilmslow, Cheshire, UK
Contact:

Re: Debug logging not being enabled

Post by mcrossley » Sun 16 Jun 2019 6:52 pm

I pretty sure it's nothing you have done, unless it is happening for most of the updates I'd ignore it, it's probably an error at the wu end.

Post Reply