b3101 - program not exiting when no station connected

[freddie]

An observation from user mdlawler . The uncaught exception when exiting the program has been addressed in b3101 - however, the program now no longer exits in response to ctrl-c. See the log file:

Code: Select all

2021-01-21 11:37:47.923  ========================== Cumulus MX starting ==========================
2021-01-21 11:37:47.923 Command line: cumulusmx -debug
2021-01-21 11:37:47.924 Cumulus MX v.3.9.6 build 3101
2021-01-21 11:37:47.925 Platform: Win32NT
2021-01-21 11:37:47.925 OS version: Microsoft Windows NT 6.2.9200.0
2021-01-21 11:37:48.150 Current culture: English (United States)
2021-01-21 11:37:48.150 Directory separator=[\] Decimal separator=[.] List separator=[,]
2021-01-21 11:37:48.150 Date separator=[/] Time separator=[:]
2021-01-21 11:37:48.150 Standard time zone name:   Eastern Standard Time
2021-01-21 11:37:48.151 Daylight saving time name: Eastern Daylight Time
2021-01-21 11:37:48.151 Daylight saving time? False
2021-01-21 11:37:48.151 1/21/2021 11:37:48 AM
2021-01-21 11:37:48.163 Reading Cumulus.ini file
2021-01-21 11:37:48.165 ASM=10 AST=00:10:00
2021-01-21 11:37:48.166 Cumulus start date: Monday, January 4, 2021
2021-01-21 11:37:48.168 No start-up PING
2021-01-21 11:37:48.168 No start-up delay - disabled
2021-01-21 11:37:48.180 Data path = data\
2021-01-21 11:37:48.195 Creating backup folder backup\20210121113748\
2021-01-21 11:37:48.197 Created backup folder backup\20210121113748\
2021-01-21 11:37:48.197 Debug logging is enabled
2021-01-21 11:37:48.197 Data logging is enabled
2021-01-21 11:37:48.197 FTP logging is disabled
2021-01-21 11:37:48.197 Spike logging is enabled
2021-01-21 11:37:48.197 Logging interval = 10 mins
2021-01-21 11:37:48.197 Real time interval = 30 secs
2021-01-21 11:37:48.197 NoSensorCheck = 0
2021-01-21 11:37:48.225 Calculating sunrise and sunset times
2021-01-21 11:37:48.227 Sunrise: 01:07:52
2021-01-21 11:37:48.227 Sunset : 13:15:10
2021-01-21 11:37:48.228 Tomorrow sunrise: 01:08:09
2021-01-21 11:37:48.228 Tomorrow sunset : 13:15:26
2021-01-21 11:37:48.228 Tomorrow length diff: -1
2021-01-21 11:37:48.237 Station type: Davis Vantage Pro2
2021-01-21 11:37:48.237 WindUnit=mph RainUnit=in TempUnit=°F PressureUnit=in
2021-01-21 11:37:48.237 YTDRain=0.000 Year=0
2021-01-21 11:37:48.237 RainDayThreshold=-1.000
2021-01-21 11:37:48.238 Offsets and Multipliers:
2021-01-21 11:37:48.238 PO=0.000 TO=0.000 HO=0 WDO=0 ITO=0.000 SO=0.000 UVO=0.000
2021-01-21 11:37:48.238 PM=1.000 WSM=1.000 WGM=1.000 TM=1.000 TM2=0.000 HM=1.000 HM2=0.000 RM=1.000 SM=1.000 UVM=1.000
2021-01-21 11:37:48.238 Spike removal:
2021-01-21 11:37:48.238 TD=999.000 GD=999.000 WD=999.000 HD=999.000 PD=999.000 MR=999.000 MH=999.000
2021-01-21 11:37:48.238 Limits:
2021-01-21 11:37:48.238 TH=60.0 TL=-60.0 DH=40.0 PH=1090.00 PL=870.00 GH=90.000
2021-01-21 11:37:48.238 Cumulus Starting
2021-01-21 11:37:48.238 Lock: Cumulus waiting for the lock
2021-01-21 11:37:48.238 Lock: Cumulus has lock
2021-01-21 11:37:48.238 Opening station
2021-01-21 11:37:48.249 Last update time from today.ini: 1/21/2021 11:37:48 AM
2021-01-21 11:37:48.249 Read today file: Date = 00/00/00, LastUpdateTime = 1/21/2021 11:37:48 AM, Month = 1
2021-01-21 11:37:48.249 Sensor clock  12:00:00 AM
2021-01-21 11:37:48.249 Station clock 12:00:00 AM
2021-01-21 11:37:48.249 ET not initialised
2021-01-21 11:37:48.250 ReadTodayfile: Rain day start = -1
2021-01-21 11:37:48.253 C:\cumulusmx\data\alltime.ini
2021-01-21 11:37:48.254 Alltime.ini file read
2021-01-21 11:37:48.259 MonthlyAlltime.ini file read
2021-01-21 11:37:48.269 Finding raintoday from logfile data\Jan21log.txt
2021-01-21 11:37:48.269 Expecting listsep=, decimal=.
2021-01-21 11:37:48.269 Midnight rain not found, setting midnight count to raindaystart = -1
2021-01-21 11:37:48.269 Checking rain counter = -1
2021-01-21 11:37:48.269 Rain counter negative, setting to zero
2021-01-21 11:37:48.270 Getting rain totals, rain season start = 1
2021-01-21 11:37:48.270 Today = 21/01/21
2021-01-21 11:37:48.271 Station type = Davis
2021-01-21 11:37:48.271 LOOP2 enabled
2021-01-21 11:37:48.271 IP address = 192.168.1.127 Port = 22222
2021-01-21 11:37:48.271 periodic disconnect = 0
2021-01-21 11:37:48.272 InitTCP: Connecting to the station
2021-01-21 11:37:48.272 OpenTcpPort: TCP Logger Connect attempt 1
2021-01-21 11:37:48.598 This Cumulus MX instance is running the latest version
Exiting system due to external: Ctrl-C
Cumulus has shutdown
2021-01-21 11:38:09.275 OpenTcpPort: Error - A connection attempt failed because the connected party did not properly respond after a period of time, or established connection failed because connected host has failed to respond 192.168.1.127:22222
2021-01-21 11:38:09.275 OpenTcpPort: TCP Logger Connect attempt 2
2021-01-21 11:38:30.277 OpenTcpPort: Error - A connection attempt failed because the connected party did not properly respond after a period of time, or established connection failed because connected host has failed to respond 192.168.1.127:22222
2021-01-21 11:38:30.277 OpenTcpPort: TCP Logger Connect attempt 3
Exiting system due to external: Ctrl-C
Cumulus has shutdown
2021-01-21 11:38:51.279 OpenTcpPort: Error - A connection attempt failed because the connected party did not properly respond after a period of time, or established connection failed because connected host has failed to respond 192.168.1.127:22222
2021-01-21 11:38:51.279 OpenTcpPort: TCP Logger Connect attempt 4
2021-01-21 11:39:12.282 OpenTcpPort: Error - A connection attempt failed because the connected party did not properly respond after a period of time, or established connection failed because connected host has failed to respond 192.168.1.127:22222
2021-01-21 11:39:12.282 OpenTcpPort: TCP Logger Connect attempt 5

The attempts to create a TCP connection continue ad infinitum after the exit has been triggered by ctrl-c.

[mcrossley]

So it does, I'll add a check for the stop flag into that loop...

[freddie]

Did this get fixed in the latest release?

[mcrossley]

It did.

[freddie]

Okay, I wasn't sure. Michael has replied saying there is another error - I wasn't sure if it was connected. Here is the diags output:

Code: Select all

2021-01-31 14:09:20.423  ========================== Cumulus MX starting ==========================
2021-01-31 14:09:20.424 Command line: cumulusmx -debug
2021-01-31 14:09:20.424 Cumulus MX v.3.9.7 build 3107
2021-01-31 14:09:20.425 Platform: Win32NT
2021-01-31 14:09:20.425 OS version: Microsoft Windows NT 6.2.9200.0
2021-01-31 14:09:20.670 Current culture: English (United States)
2021-01-31 14:09:20.670 Directory separator=[\] Decimal separator=[.] List separator=[,]
2021-01-31 14:09:20.670 Date separator=[/] Time separator=[:]
2021-01-31 14:09:20.670 Standard time zone name:   Eastern Standard Time
2021-01-31 14:09:20.671 Daylight saving time name: Eastern Daylight Time
2021-01-31 14:09:20.671 Daylight saving time? False
2021-01-31 14:09:20.671 1/31/2021 2:09:20 PM
2021-01-31 14:09:20.684 Reading Cumulus.ini file
2021-01-31 14:09:20.686 ASM=10 AST=00:10:00
2021-01-31 14:09:20.687 Cumulus start date: Monday, January 4, 2021
2021-01-31 14:09:20.688 No start-up PING
2021-01-31 14:09:20.688 No start-up delay - disabled
2021-01-31 14:09:20.700 Data path = data\
2021-01-31 14:09:20.715 Creating backup folder backup\20210131140920\
2021-01-31 14:09:20.717 Created backup folder backup\20210131140920\
2021-01-31 14:09:20.717 Debug logging is enabled
2021-01-31 14:09:20.717 Data logging is enabled
2021-01-31 14:09:20.717 FTP logging is disabled
2021-01-31 14:09:20.717 Spike logging is enabled
2021-01-31 14:09:20.717 Logging interval = 10 mins
2021-01-31 14:09:20.717 Real time interval = 30 secs
2021-01-31 14:09:20.717 NoSensorCheck = 0
2021-01-31 14:09:20.743 Calculating sunrise and sunset times
2021-01-31 14:09:20.745 Sunrise: 01:10:04
2021-01-31 14:09:20.745 Sunset : 13:17:11
2021-01-31 14:09:20.745 Tomorrow sunrise: 01:10:13
2021-01-31 14:09:20.745 Tomorrow sunset : 13:17:19
2021-01-31 14:09:20.745 Tomorrow length diff: -1
2021-01-31 14:09:20.752 Station type: Davis Vantage Pro2
2021-01-31 14:09:20.752 WindUnit=mph RainUnit=in TempUnit=°F PressureUnit=in
2021-01-31 14:09:20.752 YTDRain=0.000 Year=0
2021-01-31 14:09:20.752 RainDayThreshold=-1.000
2021-01-31 14:09:20.752 Roll over hour=0
2021-01-31 14:09:20.753 Offsets and Multipliers:
2021-01-31 14:09:20.753 PO=0.000 TO=0.000 HO=0 WDO=0 ITO=0.000 SO=0.000 UVO=0.000
2021-01-31 14:09:20.753 PM=1.000 WSM=1.000 WGM=1.000 TM=1.000 TM2=0.000 HM=1.000 HM2=0.000 RM=1.000 SM=1.000 UVM=1.000
2021-01-31 14:09:20.753 Spike removal:
2021-01-31 14:09:20.753 TD=999.000 GD=999.000 WD=999.000 HD=999.000 PD=999.000 MR=999.000 MH=999.000
2021-01-31 14:09:20.753 Limits:
2021-01-31 14:09:20.753 TH=60.0 TL=-60.0 DH=40.0 PH=1090.00 PL=870.00 GH=90.000
2021-01-31 14:09:20.753 Primary AQ Sensor = Undefined
2021-01-31 14:09:20.753 Cumulus Starting
2021-01-31 14:09:20.753 Lock: Cumulus waiting for the lock
2021-01-31 14:09:20.753 Lock: Cumulus has lock
2021-01-31 14:09:20.753 Opening station
2021-01-31 14:09:20.761 Last update time from today.ini: 1/31/2021 2:09:20 PM
2021-01-31 14:09:20.761 Read today file: Date = 00/00/00, LastUpdateTime = 1/31/2021 2:09:20 PM, Month = 1
2021-01-31 14:09:20.762 Sensor clock  12:00:00 AM
2021-01-31 14:09:20.762 Station clock 12:00:00 AM
2021-01-31 14:09:20.762 ET not initialised
2021-01-31 14:09:20.762 ReadTodayfile: Rain day start = -1
2021-01-31 14:09:20.765 C:\cumulusmx\data\alltime.ini
2021-01-31 14:09:20.766 Alltime.ini file read
2021-01-31 14:09:20.770 MonthlyAlltime.ini file read
2021-01-31 14:09:20.776 Finding raintoday from logfile data\Jan21log.txt
2021-01-31 14:09:20.777 Expecting listsep=, decimal=.
2021-01-31 14:09:20.777 Midnight rain not found, setting midnight count to raindaystart = -1
2021-01-31 14:09:20.777 Checking rain counter = -1
2021-01-31 14:09:20.777 Rain counter negative, setting to zero
2021-01-31 14:09:20.778 Getting rain totals, rain season start = 1
2021-01-31 14:09:20.778 Today = 31/01/21
2021-01-31 14:09:20.778 Station type = Davis
2021-01-31 14:09:20.778 LOOP2 enabled
2021-01-31 14:09:20.778 IP address = 192.168.1.127 Port = 22222
2021-01-31 14:09:20.778 periodic disconnect = 0
2021-01-31 14:09:20.779 InitTCP: Connecting to the station
2021-01-31 14:09:20.780 OpenTcpPort: TCP Logger Connect attempt 1
2021-01-31 14:09:21.097 This Cumulus MX instance is running the latest version
Exiting system due to external: Ctrl-C
Cumulus has shutdown
2021-01-31 14:09:41.783 OpenTcpPort: Error - A connection attempt failed because the connected party did not properly respond after a period of time, or established connection failed because connected host has failed to respond 192.168.1.127:22222
2021-01-31 14:09:41.783 OpenTcpPort: TCP Logger Connect attempt 2

[mcrossley]

That is expected, the thread connecting to the station will exit after the current connection attempt times out - with an error.

[freddie]

Thanks, I will pass that on.

[freddie]

It looks as if it keeps trying further connections. Here's more of the log from Michael:

Code: Select all

Exiting system due to external: Ctrl-C
Cumulus has shutdown
2021-01-31 15:47:22.001 OpenTcpPort: Error - A connection attempt failed because the connected party did not properly respond after a period of time, or established connection failed because connected host has failed to respond 192.168.1.127:22222
2021-01-31 15:47:22.001 OpenTcpPort: TCP Logger Connect attempt 2
Exiting system due to external: Ctrl-C
Cumulus has shutdown
2021-01-31 15:47:43.005 OpenTcpPort: Error - A connection attempt failed because the connected party did not properly respond after a period of time, or established connection failed because connected host has failed to respond 192.168.1.127:22222
2021-01-31 15:47:43.005 OpenTcpPort: TCP Logger Connect attempt 3

[mcrossley]

OK, it's much more complex than I thought (I won't go into it), but I have a workaround I can implement for the next release.

[freddie]

Thanks Mark, I'll pass on the good news.