APRS Data String not being created

[alexlc13]

Hello,

I have been using Cumulus for a few years now without issues, but now I'm setting up a new weather station for a friend. I got him running on Cumulus, but data is not being sent to CWOP (the Findu website http://www.findu.com/cgi-bin/raw.cgi?call=EW9102). It is working well sending data to Weather Underground (https://www.wunderground.com/personal-w ... =KSDSTURG5). Everything is entered correctly into the CWOP settings (it's enabled, correct ID/password/server/Port/Interval 5 min).

Trying to resolve this some more, I ran the debug log with FTP logging and compared it to mine. My weather station is creating the APRS data string, but my friend's debug log doesn't show that data string being created. I've pasted below part of the debug log below at the time it should have sent out the APRS observation (at 15:30).

Thanks!

• 7443.2882 : 15:29:46.221 Load current data
  7443.3724 : 3:29:47 PM Updating Wunderground
  7443.3724 : http://rtupdate.wunderground.com/weathe ... 1&rtfreq=5
  7443.3787 : 15:29:47.127 Data loaded
  7443.3787 : 15:29:47.128 Load current data
  7443.3833 : 3:29:47 PM WU Response Code: 200 Response = success
  
  7443.4691 : 15:29:48.035 Data loaded
  7443.4707 : 15:29:48.036 Load current data
  7443.5612 : 15:29:48.943 Data loaded
  7443.5612 : 15:29:48.943 Load current data
  7443.6517 : 15:29:49.851 Data loaded
  7443.6517 : 15:29:49.852 Load current data
  7443.7421 : 15:29:50.759 Data loaded
  7443.7421 : 15:29:50.760 Load current data
  7443.8326 : 15:29:51.667 Data loaded
  7443.8326 : 15:29:51.667 Load current data
  7443.8841 : 3:29:52 PM Updating Wunderground
  7443.8841 : http://rtupdate.wunderground.com/weathe ... 1&rtfreq=5
  7443.8966 : 3:29:52 PM WU Response Code: 200 Response = success
  
  7443.9231 : 15:29:52.574 Data loaded
  7443.9231 : 15:29:52.575 Load current data
  7444.0151 : 15:29:53.482 Data loaded
  7444.0151 : 15:29:53.482 Load current data
  7444.1056 : 15:29:54.389 Data loaded
  7444.1056 : 15:29:54.389 Load current data
  7444.2226 : 15:29:55.564 Data loaded
  7444.2226 : 15:29:55.565 Load current data
  7444.3131 : 15:29:56.472 Data loaded
  7444.3131 : 15:29:56.472 Load current data
  7444.3974 : 3:29:57 PM Updating Wunderground
  7444.3974 : http://rtupdate.wunderground.com/weathe ... 1&rtfreq=5
  7444.4036 : 15:29:57.379 Data loaded
  7444.4036 : 15:29:57.379 Load current data
  7444.4098 : 3:29:57 PM WU Response Code: 200 Response = success
  
  7444.4956 : 15:29:58.286 Data loaded
  7444.4956 : 15:29:58.287 Load current data
  7444.5861 : 15:29:59.195 Data loaded
  7444.5861 : 15:29:59.195 Load current data
  7444.6766 : 15:30:00.101 Data loaded
  7444.6766 : 15:30:00.102 Sending wakeup, attempt 1
  7444.7016 : 15:30:00.352 Received 0A
  7444.7016 : 15:30:00.352 Received 0D
  7444.7016 : 15:30:00.352 Station woken
  7444.7016 : 15:30:00.352 Sending BARDATA
  7444.7577 : 4/20/2016 3:30:00 PM Minute timer 30
  7444.7577 : Adding LH entry, ts = 3:30:00 PM raintot = 0
  7444.7577 : 3:30:00 PM Creating WXNow File
  7444.7733 : 3:30:01 PM Updating APRS
  7444.9106 : 3:30:02 PM Updating Wunderground
  7444.9106 : http://rtupdate.wunderground.com/weathe ... 1&rtfreq=5
  7444.9122 : 15:30:02.466 Bardata = 0A 0D 4F 4B 0A 0D 42 41 52 20 32 39 39 38 32 0A 0D 45 4C 45 56 41 54 49 4F 4E 20 33 35 36 33 0A 0D 44 45 57 20 50 4F 49 4E 54 20 34 33 0A 0D 56 49 52 54 55 41 4C 20 54 45 4D 50 20 34 35 0A 0D 43 20 32 31 0A 0D 52 20 31 31 33 39 0A 0D 42 41 52 43 41 4C 20 30 0A 0D 47 41 49 4E 20 30 0A 0D 4F 46 46 53 45 54 20 2D 34 30 0A 0D FFFFFFFF
  7444.9122 : MSLP=1015.31042480469 SP=26.3169002532959 AP=1014.88323974609 pIn=29.9820003509521 eFt=3563 mtF=45 C=2.09999990463257
  7444.9122 : 15:30:02.466 getting reception stats
  7444.9231 : 3:30:02 PM WU Response Code: 200 Response = success
  
  7445.2039 : rec=15937 miss=215 resynch=0 row=539 crc=118
  7445.2039 : 15:30:05.375 Loading high/low data
  7445.3115 : 15:30:06.458 Reading high gust value
  7445.3115 : 15:30:06.458 High gust value = 21.0
  7445.3115 : 15:30:06.458 Reading high gust time
  7445.3115 : 15:30:06.458 result=0
  7445.3115 : y=2016 m=4 d=20
  7445.3115 : 15:30:06.458 High gust time: 4/20/2016 3:12:00 PM
  7445.3115 : 15:30:06.458 Load current data
  7445.4020 : 15:30:07.364 Data loaded
  7445.4036 : 15:30:07.365 Load current data
  7445.4238 : 3:30:07 PM Updating Wunderground
  7445.4238 : http://rtupdate.wunderground.com/weathe ... 1&rtfreq=5
  7445.4348 : 3:30:07 PM WU Response Code: 200 Response = success
  
  7445.4940 : 15:30:08.272 Data loaded
  7445.4940 : 15:30:08.273 Load current data
  7445.5845 : 15:30:09.181 Data loaded
  7445.5845 : 15:30:09.181 Load current data
  7445.6844 : 15:30:10.184 Data loaded
  7445.6844 : 15:30:10.184 Load current data
  7445.7748 : 15:30:11.091 Data loaded
  7445.7748 : 15:30:11.091 Load current data
  7445.8653 : 15:30:11.998 Data loaded
  7445.8669 : 15:30:11.999 Load current data
  7445.9355 : 3:30:12 PM Updating Wunderground
  7445.9355 : http://rtupdate.wunderground.com/weathe ... 1&rtfreq=5
  7445.9574 : 15:30:12.906 Data loaded
  7445.9574 : 15:30:12.906 Load current data
  7445.9605 : 3:30:12 PM WU Response Code: 200 Response = success
  
  7446.0478 : 15:30:13.814 Data loaded
  7446.0478 : 15:30:13.815 Load current data
  7446.1383 : 15:30:14.722 Data loaded
  7446.1383 : 15:30:14.722 Load current data
  7446.2288 : 15:30:15.628 Data loaded
  7446.2288 : 15:30:15.629 Load current data
  7446.3193 : 15:30:16.536 Data loaded
  7446.3193 : 15:30:16.537 Load current data
  7446.4113 : 15:30:17.444 Data loaded
  7446.4113 : 15:30:17.444 Load current data
  7446.4612 : 3:30:17 PM Updating Wunderground
  7446.4612 : http://rtupdate.wunderground.com/weathe ... 1&rtfreq=5
  7446.4722 : 3:30:18 PM WU Response Code: 200 Response = success
  
  7446.5221 : 15:30:18.565 Data loaded
  7446.5236 : 15:30:18.566 Load current data
  7446.6141 : 15:30:19.474 Data loaded
  7446.6141 : 15:30:19.474 Load current data
  7446.7093 : 15:30:20.433 Data loaded
  7446.7093 : 15:30:20.434 Load current data
  7446.7795 : APRS connect timeout
  7446.7998 : 15:30:21.341 Data loaded
  7446.7998 : 15:30:21.341 Load current data
  7446.8902 : 15:30:22.248 Data loaded
  7446.8918 : 15:30:22.249 Load current data
  7446.9729 : 3:30:23 PM Updating Wunderground
  7446.9729 : http://rtupdate.wunderground.com/weathe ... 1&rtfreq=5
  7446.9823 : 15:30:23.156 Data loaded
  7446.9823 : 15:30:23.157 Load current data
  7446.9838 : 3:30:23 PM WU Response Code: 200 Response = success
  
  7447.0728 : 15:30:24.064 Data loaded
  7447.0728 : 15:30:24.064 Load current data
  7447.1632 : 15:30:24.972 Data loaded
  7447.1632 : 15:30:24.972 Load current data
  7447.2537 : 15:30:25.878 Data loaded
  7447.2537 : 15:30:25.879 Load current data
  7447.3442 : 15:30:26.786 Data loaded
  7447.3442 : 15:30:26.787 Load current data
  7447.4362 : 15:30:27.694 Data loaded
  7447.4362 : 15:30:27.694 Load current data
  7447.4846 : 3:30:28 PM Updating Wunderground
  7447.4846 : http://rtupdate.wunderground.com/weathe ... 1&rtfreq=5
  7447.4971 : 3:30:28 PM WU Response Code: 200 Response = success
  
  7447.5486 : 15:30:28.816 Data loaded
  7447.5486 : 15:30:28.816 Load current data
  7447.6391 : 15:30:29.723 Data loaded
  7447.6391 : 15:30:29.724 Load current data
  7447.7342 : 15:30:30.680 Data loaded
  7447.7342 : 15:30:30.680 Load current data
  7447.8247 : 15:30:31.587 Data loaded
  7447.8247 : 15:30:31.587 Load current data
  7447.9152 : 15:30:32.494 Data loaded
  7447.9152 : 15:30:32.494 Load current data
  7447.9979 : 3:30:33 PM Updating Wunderground
  7447.9979 : http://rtupdate.wunderground.com/weathe ... 1&rtfreq=5
  7448.0057 : 15:30:33.400 Data loaded
  7448.0057 : 15:30:33.401 Load current data
  7448.0103 : 3:30:33 PM WU Response Code: 200 Response = success
  
  7448.0977 : 15:30:34.308 Data loaded
  7448.0977 : 15:30:34.309 Load current data
  7448.1882 : 15:30:35.216 Data loaded
  7448.1882 : 15:30:35.216 Load current data
  7448.2787 : 15:30:36.123 Data loaded
  7448.2787 : 15:30:36.123 Load current data
  7448.3691 : 15:30:37.030 Data loaded
  7448.3691 : 15:30:37.030 Load current data
  7448.4596 : 15:30:37.938 Data loaded
  7448.4596 : 15:30:37.938 Load current data
  7448.5111 : 3:30:38 PM Updating Wunderground
  7448.5111 : http://rtupdate.wunderground.com/weathe ... 1&rtfreq=5
  7448.5236 : 3:30:38 PM WU Response Code: 200 Response = success
  
  7448.5735 : 15:30:39.069 Data loaded
  7448.5735 : 15:30:39.070 Load current data
  7448.6640 : 15:30:39.977 Data loaded
  7448.6640 : 15:30:39.977 Load current data
  7448.7591 : 15:30:40.930 Data loaded
  7448.7591 : 15:30:40.931 Load current data
  7448.8496 : 15:30:41.838 Data loaded
  7448.8496 : 15:30:41.838 Load current data
  7448.9401 : 15:30:42.744 Data loaded
  7448.9401 : 15:30:42.745 Load current data
  7449.0243 : 3:30:43 PM Updating Wunderground
  7449.0243 : http://rtupdate.wunderground.com/weathe ... 1&rtfreq=5
  7449.0306 : 15:30:43.652 Data loaded
  7449.0321 : 15:30:43.653 Load current data
  7449.0368 : 3:30:43 PM WU Response Code: 200 Response = success
  
  7449.1226 : 15:30:44.560 Data loaded
  7449.1226 : 15:30:44.560 Load current data
  7449.2131 : 15:30:45.468 Data loaded
  7449.2131 : 15:30:45.468 Load current data
  7449.3036 : 15:30:46.374 Data loaded
  7449.3036 : 15:30:46.375 Load current data

[steve]

Have you tried restarting Cumulus? If that doesn't help, please zip up the diags folder and attach it, and I'll take a look. Please also paste the contents of the [APRS] section from cumulus.ini.

[alexlc13]

I will work on getting those files for you. It's not my computer, so I can't get them very quickly.

Something I noticed after pasting the debug log was the "APRS connect timeout" 20 seconds later. I found this old post:

https://cumulus.hosiene.co.uk/viewtopic.php?f=4&t=8853

Right off the bat do you think it could be a firewall issue? This station is located at his work, so I'm sure his company has a firewall installed.

[steve]

A firewall issue, or some other kind of problem with the connection to the server. The files I asked for won't be of any help with this, you'll just have to work out what the problem is.

[alexlc13]

Thanks Steve. This is going beyond my understanding, I have one more question: since Cumulus is still successfully sending data to Weather Underground, does that give you any other specific clues/ideas/etc about the problem? Could a firewall clamp down on that CWOP server specifically or would it clamp down on the software itself?

I am not even sure if what I'm asking makes sense.

[steve]

It would be unusual for a firewall to block one site and not another. But note that WU and CWOP use different protocols - WU is an HTTP 'get' but CWOP just uses a raw TCP socket. It may be the firewall is allowing HTTP but blocking raw TCP.

[alexlc13]

Ok, I got it working. I'll let you know in case someone else runs into this issue.

In Cumulus I simply changed the port from the default 14580 to 23.

I was also given the suggestion of trying port 14501 so that might work though I didn't try it myself.

[steve]

23 is the standard 'telnet' port, so it probably was a firewall blocking TCP port 14580.