wviewweather.com

Entr04y · Joined: 11 Nov 2005 Posts: 8

Hi all,

I seem to be getting some crashes in wview, it runs for about 15 - 20 minutes and the dies. I'm using version 1.8.4, cwop, wunderground, and a vantage pro2 plus. I can't seem to find a core file.

[root@magrathea wview]# uname -a
Linux magrathea.froody.org 2.6.10-1.771_FC2 #1 Mon Mar 28 00:50:14 EST 2005 i686 i686 i386 GNU/Linux

Nov 11 20:13:31 magrathea htmlgend[9247]: <2192412254> : radlib: htmlgend started as a daemon ...
Nov 11 20:13:31 magrathea htmlgend[9247]: <2192412255> : generating to /var/www/html/weather2/
Nov 11 20:13:31 magrathea htmlgend[9247]: <2192412255> : running...
Nov 11 20:13:31 magrathea wvalarmd[9250]: <2192412255> : radlib: wvalarmd started as a daemon ...
Nov 11 20:13:31 magrathea wvalarmd[9250]: <2192412256> : alarms: added 2 alarm definitions from wvalarm.conf
Nov 11 20:13:31 magrathea wvcwopd[9253]: <2192412257> : radlib: wvcwopd started as a daemon ...
Nov 11 20:13:31 magrathea wvcwopd[9253]: <2192412258> : CWOP: configured to submit station CW3512 data to:
Nov 11 20:13:31 magrathea wvcwopd[9253]: <2192412258> : CWOP: Primary: socal.aprs2.net:23
Nov 11 20:13:31 magrathea wvcwopd[9253]: <2192412258> : CWOP: Secondary: arizona.aprs2.net:23
Nov 11 20:13:31 magrathea wvcwopd[9253]: <2192412258> : CWOP: Tertiary: indiana.aprs2.net:23
Nov 11 20:13:31 magrathea wviewftpd[9257]: <2192412262> : /etc/wview/wviewftp.conf does not exist - exiting...
Nov 11 20:13:31 magrathea wviewd[9243]: <2192412279> : Vantage Pro on /dev/ttyS0 opened ...
Nov 11 20:13:31 magrathea wviewd[9243]: <2192412279> : running...
Nov 11 20:13:31 magrathea wviewsshd[9258]: <2192412286> : /etc/wview/wviewssh.conf does not exist - exiting...
Nov 11 20:13:31 magrathea wvwunderd[9256]: <2192412286> : radlib: wvwunderd started as a daemon ...
Nov 11 20:13:31 magrathea wvwunderd[9256]: <2192412299> : WUNDERGROUND: configured to submit station KCASUNNY3 data to wunderground.com
Nov 11 20:13:31 magrathea wviewd[9243]: <2192412337> : station location: elevation: 47 feet
Nov 11 20:13:31 magrathea wviewd[9243]: <2192412337> : station location: latitude: 37.2 N longitude: 122.1 E
Nov 11 20:13:31 magrathea wviewd[9243]: <2192412338> : newest archive record is: 11/11/2005 20:10
Nov 11 20:13:32 magrathea wviewd[9243]: <2192413201> : station time synchronized to: 11-11-2005 20:13:32
Nov 11 20:13:32 magrathea wviewd[9243]: <2192413201> : station GMT offset synchronized to: -8 hours, 0 minutes
Nov 11 20:13:33 magrathea wviewd[9243]: <2192414802> : initializing computed data values...
Nov 11 20:13:33 magrathea wviewd[9243]: <2192414803> : computedDataInit: computeDataWeek failed
Nov 11 20:13:33 magrathea htmlgend[9247]: <2192414803> : received archive path /var/wview/archive from wviewd: 20051111 20:13:33
Nov 11 20:13:33 magrathea htmlgend[9247]: <2192414803> : Vantage Pro station using 5 minute archive interval...
Nov 11 20:13:33 magrathea htmlgend[9247]: <2192414804> : htmlmgrInit: 64 built-in image definitions added
Nov 11 20:13:33 magrathea htmlgend[9247]: <2192414804> : htmlmgrInit: 0 user image definitions added
Nov 11 20:13:33 magrathea htmlgend[9247]: <2192414804> : htmlmgrInit: 7 html templates added
Nov 11 20:13:33 magrathea htmlgend[9247]: <2192414804> : htmlmgrInit: 31 icon definitions, 197 forecast rules found ...
Nov 11 20:13:33 magrathea wvalarmd[9250]: <2192414808> : running...
Nov 11 20:13:33 magrathea wvcwopd[9253]: <2192414808> : running...
Nov 11 20:13:33 magrathea wvwunderd[9256]: <2192414808> : running...
Nov 11 20:13:33 magrathea htmlgend[9247]: <2192414838> : NOAA Generate: creating monthly NOAA reports starting 1-2005
Nov 11 20:13:33 magrathea htmlgend[9247]: <2192414840> : NOAA Generate: creating yearly NOAA reports starting 2005
Nov 11 20:13:33 magrathea htmlgend[9247]: <2192414851> : ARCREC: using header file /etc/wview/arcrec-header.conf ...
Nov 11 20:13:33 magrathea htmlgend[9247]: <2192414852> : ARCREC: saving 30 daily archive reports ...
Nov 11 20:13:33 magrathea htmlgend[9247]: <2192414876> : ARCREC: 30 daily archive reports available
Nov 11 20:13:34 magrathea htmlgend[9247]: <2192415152> : starting html generation in 2 mins 36 secs
Nov 11 20:13:34 magrathea htmlgend[9247]: <2192415152> : doing initial html generation now...
Nov 11 20:13:34 magrathea htmlgend[9247]: <2192415379> : Generated: 227 ms: 64 images, 7 htmls
Nov 11 20:15:04 magrathea wviewd[9243]: <2192505553> : storing record for 11/11/2005 20:15
Nov 11 20:15:04 magrathea wviewd[9243]: <2192505562> : computedDataUpdate: computeDataWeek failed
Nov 11 20:15:05 magrathea htmlgend[9247]: <2192506198> : Adding 5 minute sample...
Nov 11 20:15:09 magrathea wvcwopd[9253]: <2192510258> : CWOP-sending: CW3512>APRS,TCPXX*,qAX,CW3512:@120415z3723.28N/1221.18W_300/001g003t058P001h75b10228.wview184
Nov 11 20:16:10 magrathea htmlgend[9247]: <2192571546> : Generated: 137 ms: 36 images, 7 htmls
Nov 11 20:20:05 magrathea wviewd[9243]: <2192806297> : storing record for 11/11/2005 20:20
Nov 11 20:20:05 magrathea wviewd[9243]: <2192806301> : wviewd: recv sig 11: shutting down!
Nov 11 20:20:05 magrathea wviewd[9243]: <2192806301> : wviewd: recv sig 6: exiting!
Nov 11 20:21:10 magrathea htmlgend[9247]: <2192871418> : htmlgend: recv sig 13: exiting!
Nov 11 20:21:10 magrathea htmlgend[9247]: <2192871418> : radQueueSend: write failed on fd 8: Broken pipe
Nov 11 20:21:10 magrathea htmlgend[9247]: <2192871418> : requestDataPackets: radProcessQueueSend failed!
Nov 11 20:21:10 magrathea htmlgend[9247]: <2192871418> : htmlRunState: requestDataPackets failed!
Nov 11 20:21:10 magrathea htmlgend[9247]: <2192871418> : exiting normally...

Entr04y · Joined: 11 Nov 2005 Posts: 8

I downgraded from 1.8.4p1 to 1.8.3 and i just got the same error.
another thing I noticed is this error message:

Nov 11 21:40:05 magrathea wviewd[13014]: <2197606335> : computedDataUpdate: computeDataWeek failed

I ran strace running against the wviewd process and you can access the output at http://www.froody.org/wview.txt from the last crash. It seems to work fine for about 15 - 20 minutes, then crash.

thanks

mteel · Joined: 30 Jun 2005 Posts: 435 Location: Collinsville, TX

Not going to be an issue with 1.8.4p1...

Did you change your archive interval and are trying to use old archive data collected at a different interval?

There's something goofy going on here...

Entr04y · Joined: 11 Nov 2005 Posts: 8

I did change the archive interval, but I blew away all of the .wlk files and truncated the archive table in the mysql database prior to restarting wview.

Interstingly enough, I set up a cronjob to restart it every 20 minutes before I went to bed last night, and it appears that it stopped crashing at exactly midnight last night.

I've stopped the cron job and re-upgraded to 1.8.4p1 and I'll let you know if it crashes again.

mteel · Joined: 30 Jun 2005 Posts: 435 Location: Collinsville, TX

Please do...

bhnb · Joined: 28 Nov 2005 Posts: 127

Hi

I've also had this issue, and it also went away at midnight (UK time). I've done some experimenting with clearing the archive and getting rid of wlk files, and it only seems to happen if the total data available (files+archive) is all from one day. As far as I can figure out the place it's bombing out at is line 59 of common/windAverage.c in wview 1.8.5.

Hope this helps. I'm happy to be a guinea pig for any new code as my system isn't quite in production yet.

Cheers

Jon

mteel · Joined: 30 Jun 2005 Posts: 435 Location: Collinsville, TX

Thanks for the feedback...

The other guys strace output seems to imply his was happening while reading the WLK file...

That line in windAverage is pretty innocuous...

If you can generate an strace of it or even better run it under gdb so we can get a backtrace on the crash, that data would be nice to see.

At least it seems to only be a problem the very first day Wink

Thanks again.

bhnb · Joined: 28 Nov 2005 Posts: 127

OK, I'm getting the hang of ddd/gdb now. Can you let me know exactly what you need?
Here's a backtrace to start off with...

Program received signal SIGSEGV, Segmentation fault.
infrun: stop_stepping
windAverageAddValue (id=0x0, value=0) at ../common/windAverage.c:59

(gdb) backtrace
#0 windAverageAddValue (id=0x0, value=0) at ../common/windAverage.c:59
#1 0x0805375b in computedDataUpdate (work=0x8058440, record=0xbff6dcbd) at ../daemon/computedData.c:1289
#2 0x08054a6a in vpifReadMessage (work=0x8058440, expectACK=1) at ../daemon/vpinterface.c:275
#3 0x0804e8d7 in daemonReceiveArchiveState (state=6, stimulus=0xbff6e040, data=0x8058440) at ../daemon/daemonStates.c:1003
#4 0x00bcdd8e in radStatesProcess (id=0x88eb320, stimulus=0xbff6e040) at ./src/radstates.c:158
#5 0x0804c7ae in stationDataCallback (fd=0, userData=0x0) at ../daemon/daemon.c:340
#6 0x00bc67b2 in radProcessWait (timeout=0) at ./src/radprocess.c:517
#7 0x0804d598 in main (argc=1, argv=0xbff6e414) at ../daemon/daemon.c:1106
(gdb)
#5 0x0804c7ae in stationDataCallback (fd=0, userData=0x0) at ../daemon/daemon.c:340
#6 0x00bc67b2 in radProcessWait (timeout=0) at ./src/radprocess.c:517
#7 0x0804d598 in main (argc=1, argv=0xbff6e414) at ../daemon/daemon.c:1106
(gdb)

This is in 1.8.6 with no .wlk files and the archive cleared just before wviewd starts.

Here are the corresponding entries from the syslog. The SEGV doesn't show up as it gets trapped by gdb:
ec 4 15:24:09 mythtv wviewd[14746]: <4133450678> : radlib: wviewd started as a daemon ...
Dec 4 15:24:09 mythtv wviewd[14746]: <4133450679> : wview 1.8.6 starting ...
Dec 4 15:24:09 mythtv wviewd[14746]: <4133450679> : sleeping started!
Dec 4 15:27:56 mythtv wviewd[14746]: <4133593448> : sleeping ended!
Dec 4 15:27:56 mythtv wviewd[14746]: <4133677846> : station interface: serial ...
Dec 4 15:27:56 mythtv wviewd[14746]: <4133677846> : console polling interval set to 15 seconds
Dec 4 15:27:58 mythtv wviewd[14746]: <4133679181> : Vantage Pro on /dev/ttyUSB0 opened ...
Dec 4 15:27:58 mythtv wviewd[14746]: <4133679182> : running...
Dec 4 15:27:58 mythtv wviewd[14746]: <4133679182> : starting too close to the top-of-minute, waiting 7 secs before continuing
Dec 4 15:28:05 mythtv wviewd[14746]: <4133686313> : station location: elevation: 236 feet
Dec 4 15:28:05 mythtv wviewd[14746]: <4133686313> : station location: latitude: 50.2 N longitude: 5.1 W
Dec 4 15:28:05 mythtv wviewd[14746]: <4133686314> : newest archive record is: 12/04/2005 15:15
Dec 4 15:28:05 mythtv wviewd[14746]: <4133686553> : storing record for 12/04/2005 15:20
Dec 4 15:28:05 mythtv wviewd[14746]: <4133686554> : storing record for 12/04/2005 15:25
Dec 4 15:28:07 mythtv wviewd[14746]: <4133688241> : station time synchronized to: 12-04-2005 15:28:07
Dec 4 15:28:07 mythtv wviewd[14746]: <4133688241> : station GMT offset synchronized to: 0 hours, 0 minutes
Dec 4 15:28:08 mythtv wviewd[14746]: <4133689825> : initializing computed data values...
Dec 4 15:32:23 mythtv wviewd[14746]: <4133944974> : computedDataInit: computeDataWeek failed
Dec 4 15:32:24 mythtv wviewd[14746]: <4133945488> : storing record for 12/04/2005 15:30
Dec 4 15:32:33 mythtv wviewd[14746]: <4133955133> : computedDataUpdate: computeDataWeek failed
Dec 4 15:35:04 mythtv wviewd[14746]: <4134105987> : storing record for 12/04/2005 15:35
Dec 4 15:40:05 mythtv wviewd[14746]: <4134406518> : storing record for 12/04/2005 15:40

mteel · Joined: 30 Jun 2005 Posts: 435 Location: Collinsville, TX

That's all I need...

I'll let you know if I find something.

Thanks!

bhnb · Joined: 28 Nov 2005 Posts: 127

I agree that the wind average thing looks innocuous. The first error showing in the syslog is from computedDataInit. Is it possible that because there's no data available, some other variables aren't getting initialised?

mteel · Joined: 30 Jun 2005 Posts: 435 Location: Collinsville, TX

Yes, in fact the variable not getting initialized is the wind average ID (as the backtrace indicates):

mteel · Joined: 30 Jun 2005 Posts: 435 Location: Collinsville, TX

This should be fixed as of version 1.9.0.

Thanks to both of you for the help...