View previous topic :: View next topic |
Author |
Message |
gedger
Joined: 18 Oct 2006 Posts: 8
|
Posted: Mon Oct 23, 2006 1:57 pm Post subject: problems starting up cured by delays in startup script |
|
|
I'm running wview on a via epia mini-itx (533Mhz processor with 384Mbyte RAM) system and have had problems with reliable starting, basically the html pages were generated somes times and not others. My system is running suse 9.0.
I cured the problem by inserting a 2 second sleep between each daemon starting in the /etc/init.d/wview script.
For info here's my /var/log/messages output before the delay inserted and after.
Code: |
------------------FAILURE STARTUP---------------------------
Oct 23 20:26:19 gateway radmrouted[2004]: <1990409983> : exiting normally...
Oct 23 20:26:22 gateway radmrouted[2230]: <1990412238> : radlib: radmrouted started as a daemon ...
Oct 23 20:26:22 gateway radmrouted[2230]: <1990412239> : started on radlib system 1, workdir /var/wview
Oct 23 20:26:22 gateway radmrouted[2230]: <1990412240> : running...
Oct 23 20:26:23 gateway wviewd[2234]: <1990413419> : radlib: wviewd started as a daemon ...
Oct 23 20:26:23 gateway wviewd[2234]: <1990413422> : wview 3.2.1 starting ...
Oct 23 20:26:23 gateway wviewd[2234]: <1990413423> : station interface: serial ...
Oct 23 20:26:23 gateway wviewd[2234]: <1990413423> : Rain Season Start Month set to 1
Oct 23 20:26:23 gateway wviewd[2234]: <1990413424> : Rain Storm Start Trigger set to 0.05 in/hr
Oct 23 20:26:23 gateway wviewd[2234]: <1990413424> : Rain Storm Stop Time set to 12 hours
Oct 23 20:26:23 gateway wviewd[2234]: <1990413424> : station polling interval set to 15 seconds
Oct 23 20:26:23 gateway wviewd[2234]: <1990413470> : -- Station Init Start --
Oct 23 20:26:24 gateway htmlgend[2238]: <1990414622> : radlib: htmlgend started as a daemon ...
Oct 23 20:26:24 gateway htmlgend[2238]: <1990414627> : !! configured for metric units/conversion !!
Oct 23 20:26:24 gateway htmlgend[2238]: <1990414627> : !! Rain units will be mm !!
Oct 23 20:26:24 gateway htmlgend[2238]: <1990414627> : generating to /srv/www/wview
Oct 23 20:26:24 gateway wviewd[2234]: <1990414750> : Vantage Pro on /dev/ttyS0 opened ...
Oct 23 20:26:24 gateway wviewd[2234]: <1990414783> : station archive interval: 30 minutes
Oct 23 20:26:24 gateway wviewd[2234]: <1990414799> : running...
Oct 23 20:26:25 gateway htmlgend[2238]: <1990415155> : running...
Oct 23 20:26:25 gateway wvalarmd[2242]: <1990415830> : radlib: wvalarmd started as a daemon ...
Oct 23 20:26:25 gateway wvalarmd[2242]: <1990415855> : alarms: added 0 alarm definitions from wvalarm.conf
Oct 23 20:26:25 gateway wvcwopd[2245]: <1990415969> : /etc/wview/wvcwop.conf does not exist - exiting...
Oct 23 20:26:26 gateway wvwunderd[2248]: <1990416121> : radlib: wvwunderd started as a daemon ...
Oct 23 20:26:26 gateway wvwunderd[2248]: <1990416143> : WUNDERGROUND: configured to submit station IALDERTO1 data to wunderground.com
Oct 23 20:26:26 gateway wviewftpd[2251]: <1990416260> : /etc/wview/wviewftp.conf does not exist - exiting...
Oct 23 20:26:26 gateway wviewsshd[2253]: <1990416409> : /etc/wview/wviewssh.conf does not exist - exiting...
-------------------------GOOD STARTUP------------------------------
Oct 23 20:30:43 gateway radmrouted[2303]: <1990673504> : exiting normally...
Oct 23 20:30:45 gateway radmrouted[2377]: <1990675759> : radlib: radmrouted started as a daemon ...
Oct 23 20:30:45 gateway radmrouted[2377]: <1990675761> : started on radlib system 1, workdir /var/wview
Oct 23 20:30:45 gateway radmrouted[2377]: <1990675762> : running...
Oct 23 20:30:48 gateway wviewd[2382]: <1990678942> : radlib: wviewd started as a daemon ...
Oct 23 20:30:48 gateway wviewd[2382]: <1990678945> : wview 3.2.1 starting ...
Oct 23 20:30:48 gateway wviewd[2382]: <1990678947> : station interface: serial ...
Oct 23 20:30:48 gateway wviewd[2382]: <1990678947> : Rain Season Start Month set to 1
Oct 23 20:30:48 gateway wviewd[2382]: <1990678947> : Rain Storm Start Trigger set to 0.05 in/hr
Oct 23 20:30:48 gateway wviewd[2382]: <1990678948> : Rain Storm Stop Time set to 12 hours
Oct 23 20:30:48 gateway wviewd[2382]: <1990678948> : station polling interval set to 15 seconds
Oct 23 20:30:48 gateway wviewd[2382]: <1990678987> : -- Station Init Start --
Oct 23 20:30:50 gateway wviewd[2382]: <1990680270> : Vantage Pro on /dev/ttyS0 opened ...
Oct 23 20:30:50 gateway wviewd[2382]: <1990680303> : station archive interval: 30 minutes
Oct 23 20:30:50 gateway wviewd[2382]: <1990680319> : running...
Oct 23 20:30:50 gateway wviewd[2382]: <1990680492> : storing record for 2006-10-23 20:30
Oct 23 20:30:52 gateway htmlgend[2387]: <1990682157> : radlib: htmlgend started as a daemon ...
Oct 23 20:30:52 gateway htmlgend[2387]: <1990682168> : !! configured for metric units/conversion !!
Oct 23 20:30:52 gateway htmlgend[2387]: <1990682168> : !! Rain units will be mm !!
Oct 23 20:30:52 gateway htmlgend[2387]: <1990682169> : generating to /srv/www/wview
Oct 23 20:30:52 gateway htmlgend[2387]: <1990682696> : running...
Oct 23 20:30:52 gateway wviewd[2382]: <1990682761> : vproReceiveArchiveState: vpifGetRXCheck failed:
Oct 23 20:30:54 gateway wviewd[2382]: <1990684155> : station location: elevation: 8 feet
Oct 23 20:30:54 gateway wviewd[2382]: <1990684156> : station location: latitude: 52.0 N longitude: 1.4 E
Oct 23 20:30:54 gateway wviewd[2382]: <1990684156> : initializing computed data values...
Oct 23 20:30:54 gateway wviewd[2382]: <1990684432> : -- Station Init Complete --
Oct 23 20:30:54 gateway wviewd[2382]: <1990684435> : newest archive record: 2006-10-23 20:30
Oct 23 20:30:54 gateway htmlgend[2387]: <1990684435> : received archive path /var/wview/archive from wviewd: 20061023 20:30:54
Oct 23 20:30:54 gateway htmlgend[2387]: <1990684484> : htmlmgrInit: 58 built-in image definitions added
Oct 23 20:30:54 gateway htmlgend[2387]: <1990684484> : htmlmgrInit: 0 user image definitions added
Oct 23 20:30:54 gateway htmlgend[2387]: <1990684485> : htmlmgrInit: 8 templates added
Oct 23 20:30:54 gateway htmlgend[2387]: <1990684495> : 31 icon definitions, 197 forecast rules found ...
Oct 23 20:30:54 gateway htmlgend[2387]: <1990684757> : ARCREC: using header file /etc/wview/arcrec-header.conf ...
Oct 23 20:30:54 gateway htmlgend[2387]: <1990684757> : ARCREC: saving ALL daily archive reports ...
Oct 23 20:30:55 gateway htmlgend[2387]: <1990685142> : ARCREC: 18 daily archive reports available
Oct 23 20:30:55 gateway wvalarmd[2392]: <1990685421> : radlib: wvalarmd started as a daemon ...
Oct 23 20:30:55 gateway wvalarmd[2392]: <1990685427> : alarms: added 0 alarm definitions from wvalarm.conf
Oct 23 20:30:55 gateway wvcwopd[2395]: <1990685666> : /etc/wview/wvcwop.conf does not exist - exiting...
Oct 23 20:30:55 gateway wvwunderd[2398]: <1990685944> : radlib: wvwunderd started as a daemon ...
Oct 23 20:30:55 gateway wvwunderd[2398]: <1990685947> : WUNDERGROUND: configured to submit station IALDERTO1 data to wunderground.com
Oct 23 20:30:55 gateway wvalarmd[2392]: <1990686006> : running...
Oct 23 20:30:56 gateway htmlgend[2387]: <1990686104> : starting html generation in 0 mins 14 secs
Oct 23 20:30:56 gateway htmlgend[2387]: <1990686105> : doing initial html generation now...
Oct 23 20:30:56 gateway wviewftpd[2401]: <1990686179> : /etc/wview/wviewftp.conf does not exist - exiting...
Oct 23 20:30:56 gateway wviewsshd[2403]: <1990686380> : /etc/wview/wviewssh.conf does not exist - exiting...
Oct 23 20:30:56 gateway wvwunderd[2398]: <1990686526> : running...
Oct 23 20:30:58 gateway htmlgend[2387]: <1990688411> : Generated: 2306 ms: 58 images, 8 template files
Oct 23 20:31:10 gateway htmlgend[2387]: <1990700857> : Generated: 501 ms: 17 images, 8 template files
Oct 23 20:31:16 gateway wviewd[2382]: <1990706150> : station time synchronized to: 10-23-2006 20:31:16
Oct 23 20:31:16 gateway wviewd[2382]: <1990706151> : station GMT offset synchronized to: 1 hours, 0 minutes
Oct 23 20:32:10 gateway htmlgend[2387]: <1990760827> : Generated: 495 ms: 17 images, 8 template files
Oct 23 20:33:10 gateway htmlgend[2387]: <1990820825> : Generated: 494 ms: 17 images, 8 template files
Oct 23 20:34:05 gateway sshd[2409]: Accepted keyboard-interactive/pam for gedger from ::ffff:192.168.1.2 port 3898 ssh2
Oct 23 20:34:10 gateway htmlgend[2387]: <1990880825> : Generated: 494 ms: 17 images, 8 template files
Oct 23 20:35:10 gateway htmlgend[2387]: <1990940826> : Generated: 495 ms: 17 images, 8 template files
|
|
|
Back to top |
|
|
gedger
Joined: 18 Oct 2006 Posts: 8
|
Posted: Thu Nov 02, 2006 2:35 pm Post subject: Ignore last post still having start up problems |
|
|
The htmlgen daemon starts but doesn't always generate html files. The delay has improved things but it's still not 100%.
Any suggestions for working out what's going wrong?
Thanks
Richard |
|
Back to top |
|
|
gedger
Joined: 18 Oct 2006 Posts: 8
|
Posted: Sat Nov 04, 2006 11:54 am Post subject: Anyone help? |
|
|
The problem seems to be getting worse......
Thanks
Richard |
|
Back to top |
|
|
bhnb
Joined: 28 Nov 2005 Posts: 127
|
Posted: Sun Nov 05, 2006 3:37 am Post subject: |
|
|
Hi Richard
Could there be a problem with your serial port? With wview stopped, does 'vpconfig /dev/ttyS0 show' work consistently?
Jon |
|
Back to top |
|
|
gedger
Joined: 18 Oct 2006 Posts: 8
|
Posted: Sun Nov 05, 2006 11:36 am Post subject: |
|
|
bhnb wrote: | Hi Richard
Could there be a problem with your serial port? With wview stopped, does 'vpconfig /dev/ttyS0 show' work consistently?
Jon |
Have run this many times without failure.
Is there any way of turning on more debug (I've alreeady have the verbose flag set).
Thanks
Richard |
|
Back to top |
|
|
bhnb
Joined: 28 Nov 2005 Posts: 127
|
Posted: Sun Nov 05, 2006 2:08 pm Post subject: |
|
|
So on the failure startup listed above is 'wviewssh.conf does not exist - exiting... ' the last thing you hear from any of the wview daemons? Are any of them (including radmrouted) still running after this? |
|
Back to top |
|
|
gedger
Joined: 18 Oct 2006 Posts: 8
|
Posted: Sun Nov 05, 2006 3:08 pm Post subject: |
|
|
Everything appears normal..
This the is last view lines of a failure startup log
Quote: |
Nov 5 21:02:59 gateway wviewftpd[2523]: <3119409774> : /etc/wview/wviewftp.conf does not exist - exiting...
Nov 5 21:02:59 gateway wviewsshd[2525]: <3119409915> : /etc/wview/wviewssh.conf does not exist - exiting...
Nov 5 21:03:05 gateway wviewd[2506]: <3119415974> : station archive interval: 30 minutes
Nov 5 21:03:05 gateway wviewd[2506]: <3119415989> : running...
|
"ps ax" reveals (partial listing)
Quote: |
2502 ? S 0:00 /usr/local/bin/radmrouted 1 /var/wview
2503 ? S 0:00 /usr/local/bin/radmrouted 1 /var/wview
2506 ? S 0:00 /usr/local/bin/wviewd
2507 ? S 0:00 /usr/local/bin/wviewd
2510 ? S 0:00 /usr/local/bin/htmlgend
2511 ? S 0:00 /usr/local/bin/htmlgend
2514 ? S 0:00 /usr/local/bin/wvalarmd
2515 ? S 0:00 /usr/local/bin/wvalarmd
2520 ? S 0:00 /usr/local/bin/wvwunderd
2521 ? S 0:00 /usr/local/bin/wvwunderd
|
|
|
Back to top |
|
|
gedger
Joined: 18 Oct 2006 Posts: 8
|
Posted: Mon Nov 13, 2006 2:43 pm Post subject: |
|
|
Hi
Does anyone have any more suggestions on solving this problem?
I'm now finding that the system only starts successfully 1 in 5 times or less.
Thanks in advance
Richard |
|
Back to top |
|
|
bhnb
Joined: 28 Nov 2005 Posts: 127
|
Posted: Wed Nov 15, 2006 1:56 pm Post subject: |
|
|
Hi Richard
I'm still a bit puzzled about the exact nature of the problem. Do the other daemons (wvwunderd, wviewd etc) keep on working fine? Are you generating to a local or remote filesystem? When it is running OK are you only stopping it to see whether it starts successfully next time?
Cheers
Jon |
|
Back to top |
|
|
gedger
Joined: 18 Oct 2006 Posts: 8
|
Posted: Thu Nov 16, 2006 3:21 pm Post subject: |
|
|
Hi Jon,
I must admit it is a bit puzzling, the other daemons are running, but I've checked and confirm that the wvalarmd does not accept any connections to the port. I haven't checked whether wvwunderd is still updating. It's almost as if the system is locked in a waiting state. I've tried kill -HUP to the daemons and they respond and toggle their log status as you'd expect.
All files are generated to a local filesystem (same disk as wview is running on).
Once the system is running then everything works perfectly until the box gets rebooted which I'm now avoiding but I've been changing some bios settings which has caused a few reboots.
Thanks in advance.
Richard |
|
Back to top |
|
|
|