Page 1 of 1

APRS Data String not being created

Posted: Thu 21 Apr 2016 4:21 pm
by 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

Re: APRS Data String not being created

Posted: Thu 21 Apr 2016 4:37 pm
by 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.

Re: APRS Data String not being created

Posted: Thu 21 Apr 2016 5:02 pm
by 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.

Re: APRS Data String not being created

Posted: Thu 21 Apr 2016 5:18 pm
by 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.

Re: APRS Data String not being created

Posted: Thu 21 Apr 2016 5:38 pm
by 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. :-)

Re: APRS Data String not being created

Posted: Thu 21 Apr 2016 5:46 pm
by 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.

Re: APRS Data String not being created

Posted: Thu 21 Apr 2016 6:20 pm
by 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.

Re: APRS Data String not being created

Posted: Thu 21 Apr 2016 6:25 pm
by steve
23 is the standard 'telnet' port, so it probably was a firewall blocking TCP port 14580.