+- SIP (https://nosack.com/sipforum)
+-- Forum: SIP (Sustainable Irrigation Platform) (https://nosack.com/sipforum/forumdisplay.php?fid=1)
+--- Forum: Report a problem (https://nosack.com/sipforum/forumdisplay.php?fid=9)
+--- Thread: response time webserver high! and .. (/showthread.php?tid=10)
response time webserver high! and .. - takezo - 2015 Sep 04
Hi!
I have two issues with SIP software. First of one is related with response time of webservice, I have been checking the times of responses and is very high, you can see in the following command returns lookup, connect, pretransfer, starttransfer time in seconds and the total time that the full operation lasted:
~ curl -s -w '\nLookup time:\t%{time_namelookup}\nConnect time:\t%{time_connect}\nPreXfer time:\t%{time_pretransfer}\nStartXfer time:\t%{time_starttransfer}\n\nTotal time:\t%{time_total}\n' -o /dev/null http://raspi-chapitown/
Lookup time: 0,005
Connect time: 0,009
PreXfer time: 0,009
StartXfer time: 25,870
Total time: 25,880
---- end log --
The second issue is rated with the programs execution. All of them works fine least one, and to do it work I must stop the service and re-starting the service again. The software looks that the program-1 works but it not sends the command to pump can start , do you know why to start this program-1 I must to restart the service manually from console raspberry-pi with command "sudo service sip stop" and "sudo service sip start"
Thanks for your comments!
I look your support!
RE: response time webserver high! and .. - dan - 2015 Sep 04
Hi,
First issue has been a known problem problem. I am looking for a solution.
Second issue:
I will need more information abut your setup to find the problem.
I am guessing you have the pump on a station that is set as Master and the station that runs the water valve is set to Activate master on the Stations page.
Are you using a relay board or the OpanSprinkler board?
What version of the software are you using?
Is there any thing else about program-1 that is different from the other programs?
Do other programs/stations use the pump?
When the pump does not start, does the water valve operate or does nothing happen?
Also, please try starting the software manually from the console:
Code:
sudo service sip stop
cd SIP
sudo python sip.pyThen look for any error message in the console.
RE: response time webserver high! and .. - takezo - 2015 Sep 05
(2015 Sep 04, 07:12 PM)dan Wrote: Hi,
First issue has been a known problem problem. I am looking for a solution.
Second issue:
I will need more information abut your setup to find the problem.
I am guessing you have the pump on a station that is set as Master and the station that runs the water valve is set to Activate master on the Stations page.
Are you using a relay board or the OpanSprinkler board?
What version of the software are you using?
Is there any thing else about program-1 that is different from the other programs?
Do other programs/stations use the pump?
When the pump does not start, does the water valve operate or does nothing happen?
Also, please try starting the software manually from the console:
Code:sudo service sip stop
cd SIP
sudo python sip.py
Then look for any error message in the console.
hi Dan!!! thanks for your soon answer. There is any way to redirect output log of service sip that start at run to a file to see what happens?
because the problem only happens if I reboot the system . After stop the service and start again everything works fine!
RE: response time webserver high! and .. - dan - 2015 Sep 05
To give the SD card in the Raspberry Pi a long life the only log file SIP writes is the irrigation log. I don't know of a way to get output from the program when it starts from the init script.
If you can describe how your system is configured I will do the same here and look for the problem.
RE: response time webserver high! and .. - takezo - 2015 Sep 06
(2015 Sep 05, 06:12 PM)dan Wrote: To give the SD card in the Raspberry Pi a long life the only log file SIP writes is the irrigation log. I don't know of a way to get output from the program when it starts from the init script.
If you can describe how your system is configured I will do the same here and look for the problem.
Hi dan!
The setup of system configured is all station in line I mean do not have a master pump.
I'm using relay board connected to my raspberry pi and the version installed of SIP is: 3.1.25 (2015-09-03).
There are not differences between the progam-1 and other programs except the time that is scheduled the execution.
I'm attaching a log output of console when I have the issue with program-1 failing (manual execution porgram-1 for one minute):
--- start log ---
pi@raspberrypi-:~/sip$ sudo sip.sh
sudo: sip.sh: command not found
pi@raspberrypi-:~/sip$ sudo python sip.py
Ignoring exception while loading the relay plug-in.
cannot import name pin_relay
Complementos cargados:
email_adj
mobile_app
monthly_adj
plugin_manager
proto
relay_board
signaling_examples
system_update
weather_adj
weather_level_adj
Comenzando tiempo de blucle
zones changed
[0, 0, 0, 0, 0, 0, 0, 0]
http://0.0.0.0:80/
Monthly Adjust: Setting water level to 90%
Email plugin is started
Checking weather status...
Checking rain status...
Good weather detected: Partly Cloudy. Removing rain delay.
Skipped wundergound data because of a parsing error for today
Using 7 days of information.
Water needed (7 days): 21.0mm
Total rainfall : 4.0mm
_______________________________-
Irrigation needed : 17.0mm
Weather Adjustment : 60.7%
192.168.1.128:57726 - - [06/Sep/2015 13:12:21] "HTTP/1.1 GET /" - 200 OK
192.168.1.128:57728 - - [06/Sep/2015 13:12:21] "HTTP/1.1 GET /" - 200 OK
192.168.1.128:57726 - - [06/Sep/2015 13:12:24] "HTTP/1.1 GET /static/themes/basic/base.css" - 200
192.168.1.128:57900 - - [06/Sep/2015 13:12:24] "HTTP/1.1 GET /static/scripts/jquery-1.8.2.min.js" - 200
192.168.1.128:57901 - - [06/Sep/2015 13:12:24] "HTTP/1.1 GET /static/scripts/behaviors.js" - 200
192.168.1.128:57902 - - [06/Sep/2015 13:12:24] "HTTP/1.1 GET /static/scripts/schedule.js" - 200
192.168.1.128:57903 - - [06/Sep/2015 13:12:24] "HTTP/1.1 GET /static/scripts/intervalSelect.js" - 200
192.168.1.128:57900 - - [06/Sep/2015 13:12:27] "HTTP/1.1 GET /static/themes/basic/logo.png" - 200
192.168.1.128:57726 - - [06/Sep/2015 13:12:27] "HTTP/1.1 GET /static/themes/basic/grass.jpg" - 200
192.168.1.128:57901 - - [06/Sep/2015 13:12:27] "HTTP/1.1 GET /static/themes/basic/on.png" - 200 192.168.1.128:57902 - - [06/Sep/2015 13:12:27] "HTTP/1.1 GET /static/themes/basic/home.png" - 200
Traceback (most recent call last):
File "/home/pi/sip/web/application.py", line 239, in process
return self.handle()
File "/home/pi/sip/web/application.py", line 229, in handle
fn, args = self._match(self.mapping, web.ctx.path)
File "/home/pi/sip/web/application.py", line 438, in _match
what, result = utils.re_subm('^' + pat + '$', what, value)
File "/home/pi/sip/web/utils.py", line 531, in re_subm
compiled_pat = re_compile(pat)
File "/home/pi/sip/web/utils.py", line 499, in __call__
update(block=True)
File "/home/pi/sip/web/utils.py", line 496, in update
self.running[key].release()
error: release unlocked lock
192.168.1.128:57904 - - [06/Sep/2015 13:12:28] "HTTP/1.1 GET /api/status" - 500 Internal Server Error
192.168.1.128:57903 - - [06/Sep/2015 13:12:29] "HTTP/1.1 GET /api/log" - 200 OK
192.168.1.128:57900 - - [06/Sep/2015 13:12:30] "HTTP/1.1 GET /static/themes/basic/whitestripes.png" - 200
192.168.1.128:57919 - - [06/Sep/2015 13:12:51] "HTTP/1.1 GET /vr" - 200 OK
192.168.1.128:57919 - - [06/Sep/2015 13:12:52] "HTTP/1.1 GET /api/log" - 200 OK
192.168.1.128:57919 - - [06/Sep/2015 13:12:59] "HTTP/1.1 GET /cr" - 303 See Other
zones changed
[1, 0, 0, 0, 0, 0, 0, 0]
192.168.1.128:57919 - - [06/Sep/2015 13:13:00] "HTTP/1.1 GET /" - 200 OK
192.168.1.128:57923 - - [06/Sep/2015 13:13:01] "HTTP/1.1 GET /static/themes/basic/off.png" - 200
192.168.1.128:57922 - - [06/Sep/2015 13:13:03] "HTTP/1.1 GET /api/status" - 200 OK
192.168.1.128:57919 - - [06/Sep/2015 13:13:03] "HTTP/1.1 GET /api/log" - 200 OK
192.168.1.128:57919 - - [06/Sep/2015 13:13:04] "HTTP/1.1 GET /api/status" - 200 OK
192.168.1.128:57919 - - [06/Sep/2015 13:13:06] "HTTP/1.1 GET /api/status" - 200 OK
192.168.1.128:57919 - - [06/Sep/2015 13:13:08] "HTTP/1.1 GET /api/status" - 200 OK
192.168.1.128:57919 - - [06/Sep/2015 13:13:10] "HTTP/1.1 GET /api/status" - 200 OK
192.168.1.128:57919 - - [06/Sep/2015 13:13:11] "HTTP/1.1 GET /api/status" - 200 OK
192.168.1.128:57919 - - [06/Sep/2015 13:13:13] "HTTP/1.1 GET /api/status" - 200 OK
192.168.1.128:57919 - - [06/Sep/2015 13:13:15] "HTTP/1.1 GET /api/status" - 200 OK
192.168.1.128:57919 - - [06/Sep/2015 13:13:16] "HTTP/1.1 GET /api/status" - 200 OK
192.168.1.128:57919 - - [06/Sep/2015 13:13:18] "HTTP/1.1 GET /api/status" - 200 OK
192.168.1.128:57919 - - [06/Sep/2015 13:13:20] "HTTP/1.1 GET /api/status" - 200 OK
192.168.1.128:57919 - - [06/Sep/2015 13:13:22] "HTTP/1.1 GET /api/status" - 200 OK
192.168.1.128:57919 - - [06/Sep/2015 13:13:23] "HTTP/1.1 GET /api/status" - 200 OK
192.168.1.128:57919 - - [06/Sep/2015 13:13:25] "HTTP/1.1 GET /api/status" - 200 OK
192.168.1.128:57919 - - [06/Sep/2015 13:13:27] "HTTP/1.1 GET /api/status" - 200 OK
192.168.1.128:57919 - - [06/Sep/2015 13:13:28] "HTTP/1.1 GET /api/status" - 200 OK
192.168.1.128:57919 - - [06/Sep/2015 13:13:30] "HTTP/1.1 GET /api/status" - 200 OK
192.168.1.128:57919 - - [06/Sep/2015 13:13:32] "HTTP/1.1 GET /api/status" - 200 OK
192.168.1.128:57919 - - [06/Sep/2015 13:13:34] "HTTP/1.1 GET /api/status" - 200 OK
192.168.1.128:57919 - - [06/Sep/2015 13:13:35] "HTTP/1.1 GET /api/status" - 200 OK
192.168.1.128:57919 - - [06/Sep/2015 13:13:37] "HTTP/1.1 GET /api/status" - 200 OK
192.168.1.128:57919 - - [06/Sep/2015 13:13:39] "HTTP/1.1 GET /api/status" - 200 OK
192.168.1.128:57919 - - [06/Sep/2015 13:13:40] "HTTP/1.1 GET /api/status" - 200 OK
192.168.1.128:57919 - - [06/Sep/2015 13:13:42] "HTTP/1.1 GET /api/status" - 200 OK
192.168.1.128:57919 - - [06/Sep/2015 13:13:44] "HTTP/1.1 GET /api/status" - 200 OK
192.168.1.128:57919 - - [06/Sep/2015 13:13:45] "HTTP/1.1 GET /api/status" - 200 OK
192.168.1.128:57919 - - [06/Sep/2015 13:13:47] "HTTP/1.1 GET /api/status" - 200 OK
192.168.1.128:57919 - - [06/Sep/2015 13:13:49] "HTTP/1.1 GET /api/status" - 200 OK
192.168.1.128:57919 - - [06/Sep/2015 13:13:51] "HTTP/1.1 GET /api/status" - 200 OK
192.168.1.128:57919 - - [06/Sep/2015 13:13:52] "HTTP/1.1 GET /api/status" - 200 OK
192.168.1.128:57919 - - [06/Sep/2015 13:13:54] "HTTP/1.1 GET /api/status" - 200 OK
192.168.1.128:57919 - - [06/Sep/2015 13:13:56] "HTTP/1.1 GET /api/status" - 200 OK
192.168.1.128:57919 - - [06/Sep/2015 13:13:57] "HTTP/1.1 GET /api/status" - 200 OK
zones changed
[0, 0, 0, 0, 0, 0, 0, 0]
zones changed
[0, 0, 0, 0, 0, 0, 0, 0]
192.168.1.128:57919 - - [06/Sep/2015 13:13:59] "HTTP/1.1 GET /api/status" - 200 OK
192.168.1.128:57919 - - [06/Sep/2015 13:14:00] "HTTP/1.1 GET /api/log" - 200 OK
--- end log ---
And this is the output log of sucessfull manual execution, 30seconds, after force command "sudo service sip stop" :
-- start log --
pi@raspberrypi-:~/sip$ sudo service sip stop
pi@raspberrypi-:~/sip$ sudo python sip.py
Ignoring exception while loading the relay plug-in.
cannot import name pin_relay
Complementos cargados:
email_adj
mobile_app
monthly_adj
plugin_manager
proto
relay_board
signaling_examples
system_update
weather_adj
weather_level_adj
Comenzando tiempo de blucle
zones changed
[0, 0, 0, 0, 0, 0, 0, 0]
http://0.0.0.0:80/
Monthly Adjust: Setting water level to 90%
Checking rain status...
Good weather detected: Partly Cloudy. Removing rain delay.
Checking weather status...
Email plugin is started
Skipped wundergound data because of a parsing error for today
Using 7 days of information.
Water needed (7 days): 21.0mm
Total rainfall : 4.0mm
_______________________________-
Irrigation needed : 17.0mm
Weather Adjustment : 60.7%
192.168.1.128:58081 - - [06/Sep/2015 13:29:07] "HTTP/1.1 GET /" - 200 OK
192.168.1.128:58081 - - [06/Sep/2015 13:29:07] "HTTP/1.1 GET /static/themes/basic/base.css" - 304 Not Modified
192.168.1.128:58086 - - [06/Sep/2015 13:29:07] "HTTP/1.1 GET /static/scripts/jquery-1.8.2.min.js" - 304 Not Modified
192.168.1.128:58087 - - [06/Sep/2015 13:29:08] "HTTP/1.1 GET /static/scripts/behaviors.js" - 304 Not Modified
192.168.1.128:58088 - - [06/Sep/2015 13:29:08] "HTTP/1.1 GET /static/scripts/schedule.js" - 304 Not Modified
192.168.1.128:58089 - - [06/Sep/2015 13:29:08] "HTTP/1.1 GET /static/scripts/intervalSelect.js" - 304 Not Modified
192.168.1.128:58086 - - [06/Sep/2015 13:29:08] "HTTP/1.1 GET /static/themes/basic/grass.jpg" - 304 Not Modified
192.168.1.128:58081 - - [06/Sep/2015 13:29:08] "HTTP/1.1 GET /static/themes/basic/logo.png" - 304 Not Modified
192.168.1.128:58087 - - [06/Sep/2015 13:29:08] "HTTP/1.1 GET /static/themes/basic/home.png" - 304 Not Modified192.168.1.128:58088 - - [06/Sep/2015 13:29:08] "HTTP/1.1 GET /static/themes/basic/on.png" - 304 Not Modified
192.168.1.128:58091 - - [06/Sep/2015 13:29:10] "HTTP/1.1 GET /api/status" - 200 OK
192.168.1.128:58089 - - [06/Sep/2015 13:29:10] "HTTP/1.1 GET /api/log" - 200 OK
192.168.1.128:58089 - - [06/Sep/2015 13:29:10] "HTTP/1.1 GET /static/themes/basic/whitestripes.png" - 304 Not Modified
192.168.1.128:58081 - - [06/Sep/2015 13:29:10] "HTTP/1.1 GET /api/log" - 200 OK
192.168.1.128:58081 - - [06/Sep/2015 13:29:18] "HTTP/1.1 GET /vr" - 200 OK
192.168.1.128:58081 - - [06/Sep/2015 13:29:20] "HTTP/1.1 GET /api/log" - 200 OK
192.168.1.128:58081 - - [06/Sep/2015 13:29:27] "HTTP/1.1 GET /cr" - 303 See Other
zones changed
[1, 0, 0, 0, 0, 0, 0, 0]
192.168.1.128:58081 - - [06/Sep/2015 13:29:28] "HTTP/1.1 GET /" - 200 OK
192.168.1.128:58081 - - [06/Sep/2015 13:29:29] "HTTP/1.1 GET /api/log" - 200 OK
192.168.1.128:58099 - - [06/Sep/2015 13:29:29] "HTTP/1.1 GET /api/status" - 200 OK
192.168.1.128:58081 - - [06/Sep/2015 13:29:30] "HTTP/1.1 GET /api/status" - 200 OK
192.168.1.128:58081 - - [06/Sep/2015 13:29:31] "HTTP/1.1 GET /api/status" - 200 OK
192.168.1.128:58081 - - [06/Sep/2015 13:29:32] "HTTP/1.1 GET /api/status" - 200 OK
192.168.1.128:58081 - - [06/Sep/2015 13:29:33] "HTTP/1.1 GET /api/status" - 200 OK
192.168.1.128:58081 - - [06/Sep/2015 13:29:35] "HTTP/1.1 GET /api/status" - 200 OK
192.168.1.128:58081 - - [06/Sep/2015 13:29:36] "HTTP/1.1 GET /api/status" - 200 OK
192.168.1.128:58081 - - [06/Sep/2015 13:29:37] "HTTP/1.1 GET /api/status" - 200 OK
192.168.1.128:58081 - - [06/Sep/2015 13:29:39] "HTTP/1.1 GET /api/status" - 200 OK
192.168.1.128:58081 - - [06/Sep/2015 13:29:40] "HTTP/1.1 GET /api/status" - 200 OK
192.168.1.128:58081 - - [06/Sep/2015 13:29:41] "HTTP/1.1 GET /api/status" - 200 OK
192.168.1.128:58081 - - [06/Sep/2015 13:29:42] "HTTP/1.1 GET /api/status" - 200 OK
192.168.1.128:58081 - - [06/Sep/2015 13:29:43] "HTTP/1.1 GET /api/status" - 200 OK
192.168.1.128:58081 - - [06/Sep/2015 13:29:45] "HTTP/1.1 GET /api/status" - 200 OK
192.168.1.128:58081 - - [06/Sep/2015 13:29:46] "HTTP/1.1 GET /api/status" - 200 OK
192.168.1.128:58081 - - [06/Sep/2015 13:29:47] "HTTP/1.1 GET /api/status" - 200 OK
192.168.1.128:58081 - - [06/Sep/2015 13:29:48] "HTTP/1.1 GET /api/status" - 200 OK
192.168.1.128:58081 - - [06/Sep/2015 13:29:50] "HTTP/1.1 GET /api/status" - 200 OK
192.168.1.128:58081 - - [06/Sep/2015 13:29:51] "HTTP/1.1 GET /api/status" - 200 OK
192.168.1.128:58081 - - [06/Sep/2015 13:29:52] "HTTP/1.1 GET /api/status" - 200 OK
192.168.1.128:58081 - - [06/Sep/2015 13:29:53] "HTTP/1.1 GET /api/status" - 200 OK
192.168.1.128:58081 - - [06/Sep/2015 13:29:54] "HTTP/1.1 GET /api/status" - 200 OK
zones changed
[0, 0, 0, 0, 0, 0, 0, 0]
zones changed
[0, 0, 0, 0, 0, 0, 0, 0]
192.168.1.128:58081 - - [06/Sep/2015 13:29:56] "HTTP/1.1 GET /api/status" - 200 OK
192.168.1.128:58081 - - [06/Sep/2015 13:29:56] "HTTP/1.1 GET /api/log" - 200 OK
--end log --
is there any way to see the output of script "sudo service sip stop" ?
thanks for your help!!
RE: response time webserver high! and .. - dan - 2015 Sep 06
Thank you, this is helpful.
I see you have the relay plugin enabled and it is not loading. I just pushed a fix for that problem to GitHub.
Also you have both weather plugins enabled. That might be causing a conflict. Try disabling one and see if the problem goes away.
The error message looks like it could be from a problem with a thread lock. This could indicate a plugin conflict.
RE: response time webserver high! and .. - takezo - 2015 Sep 06
thanks Dan for your help!
I have disabled one of the plugins weather and also updated the system with last release, versión: 3.1.26. After reboot the system and run manually program-1, one minute, it doesnt work :-(
attached the log:
-- start log --
pi@raspberrypi-:~/sip$ sudo python sip.py
Complementos cargados:
email_adj
mobile_app
monthly_adj
plugin_manager
proto
relay
relay_board
signaling_examples
system_update
weather_level_adj
Comenzando tiempo de blucle
zones changed
[0, 0, 0, 0, 0, 0, 0, 0]
http://0.0.0.0:80/
Monthly Adjust: Setting water level to 90%
Checking weather status...
Email plugin is started
Skipped wundergound data because of a parsing error for today
Using 7 days of information.
Water needed (7 days): 21.0mm
Total rainfall : 4.0mm
_______________________________-
Irrigation needed : 17.0mm
Weather Adjustment : 60.7%
192.168.1.77:62008 - - [06/Sep/2015 23:42:19] "HTTP/1.1 GET /" - 200 OK
192.168.1.77:62010 - - [06/Sep/2015 23:42:22] "HTTP/1.1 GET /vr" - 200 OK
192.168.1.77:62010 - - [06/Sep/2015 23:42:23] "HTTP/1.1 GET /api/log" - 200 OK
192.168.1.77:62010 - - [06/Sep/2015 23:42:27] "HTTP/1.1 GET /cr" - 303 See Other
zones changed
[1, 0, 0, 0, 0, 0, 0, 0]
192.168.1.77:62010 - - [06/Sep/2015 23:42:28] "HTTP/1.1 GET /" - 200 OK
192.168.1.77:62018 - - [06/Sep/2015 23:42:30] "HTTP/1.1 GET /api/status" - 200 OK
192.168.1.77:62010 - - [06/Sep/2015 23:42:30] "HTTP/1.1 GET /api/log" - 200 OK
192.168.1.77:62010 - - [06/Sep/2015 23:42:32] "HTTP/1.1 GET /api/status" - 200 OK
192.168.1.77:62010 - - [06/Sep/2015 23:42:34] "HTTP/1.1 GET /api/status" - 200 OK
192.168.1.77:62010 - - [06/Sep/2015 23:42:36] "HTTP/1.1 GET /api/status" - 200 OK
192.168.1.77:62010 - - [06/Sep/2015 23:42:37] "HTTP/1.1 GET /api/status" - 200 OK
192.168.1.77:62010 - - [06/Sep/2015 23:42:39] "HTTP/1.1 GET /api/status" - 200 OK
192.168.1.77:62010 - - [06/Sep/2015 23:42:41] "HTTP/1.1 GET /api/status" - 200 OK
192.168.1.77:62010 - - [06/Sep/2015 23:42:43] "HTTP/1.1 GET /api/status" - 200 OK
192.168.1.77:62010 - - [06/Sep/2015 23:42:44] "HTTP/1.1 GET /api/status" - 200 OK
192.168.1.77:62010 - - [06/Sep/2015 23:42:46] "HTTP/1.1 GET /api/status" - 200 OK
192.168.1.77:62010 - - [06/Sep/2015 23:42:48] "HTTP/1.1 GET /api/status" - 200 OK
192.168.1.77:62010 - - [06/Sep/2015 23:42:50] "HTTP/1.1 GET /api/status" - 200 OK
192.168.1.77:62010 - - [06/Sep/2015 23:42:51] "HTTP/1.1 GET /api/status" - 200 OK
192.168.1.77:62010 - - [06/Sep/2015 23:42:54] "HTTP/1.1 GET /api/status" - 200 OK
192.168.1.77:62010 - - [06/Sep/2015 23:42:57] "HTTP/1.1 GET /api/status" - 200 OK
192.168.1.77:62010 - - [06/Sep/2015 23:43:00] "HTTP/1.1 GET /api/status" - 200 OK
192.168.1.77:62010 - - [06/Sep/2015 23:43:04] "HTTP/1.1 GET /api/status" - 200 OK
192.168.1.77:62010 - - [06/Sep/2015 23:43:06] "HTTP/1.1 GET /api/status" - 200 OK
192.168.1.77:62010 - - [06/Sep/2015 23:43:08] "HTTP/1.1 GET /api/status" - 200 OK
192.168.1.77:62010 - - [06/Sep/2015 23:43:12] "HTTP/1.1 GET /api/status" - 200 OK
192.168.1.77:62010 - - [06/Sep/2015 23:43:13] "HTTP/1.1 GET /api/status" - 200 OK
192.168.1.77:62010 - - [06/Sep/2015 23:43:15] "HTTP/1.1 GET /api/status" - 200 OK
192.168.1.77:62010 - - [06/Sep/2015 23:43:17] "HTTP/1.1 GET /api/status" - 200 OK
192.168.1.77:62010 - - [06/Sep/2015 23:43:19] "HTTP/1.1 GET /api/status" - 200 OK
192.168.1.77:62010 - - [06/Sep/2015 23:43:22] "HTTP/1.1 GET /api/status" - 200 OK
192.168.1.77:62010 - - [06/Sep/2015 23:43:24] "HTTP/1.1 GET /api/status" - 200 OK
192.168.1.77:62010 - - [06/Sep/2015 23:43:26] "HTTP/1.1 GET /api/status" - 200 OK
zones changed
[0, 0, 0, 0, 0, 0, 0, 0]
zones changed
[0, 0, 0, 0, 0, 0, 0, 0]
192.168.1.77:62010 - - [06/Sep/2015 23:43:27] "HTTP/1.1 GET /api/status" - 200 OK
192.168.1.77:62010 - - [06/Sep/2015 23:43:28] "HTTP/1.1 GET /api/log" - 200 OK
--- end log --
and if I do "sudo service stop" and "sudo service sip start or sudo python sip.py" program-1 works!, check the log:
--- start log ---
pi@raspberrypi-:~/sip$ sudo service sip stop
pi@raspberrypi-:~/sip$ sudo python sip.py
Complementos cargados:
email_adj
mobile_app
monthly_adj
plugin_manager
proto
relay
relay_board
signaling_examples
system_update
weather_level_adj
Comenzando tiempo de blucle
zones changed
[0, 0, 0, 0, 0, 0, 0, 0]
http://0.0.0.0:80/
Monthly Adjust: Setting water level to 90%
Email plugin is started
Checking weather status...
Skipped wundergound data because of a parsing error for today
Using 7 days of information.
Water needed (7 days): 21.0mm
Total rainfall : 4.0mm
_______________________________-
Irrigation needed : 17.0mm
Weather Adjustment : 60.7%
192.168.1.77:62053 - - [06/Sep/2015 23:47:24] "HTTP/1.1 GET /" - 200 OK
Traceback (most recent call last):
File "/home/pi/sip/web/application.py", line 239, in process
return self.handle()
File "/home/pi/sip/web/application.py", line 229, in handle
fn, args = self._match(self.mapping, web.ctx.path)
File "/home/pi/sip/web/application.py", line 438, in _match
what, result = utils.re_subm('^' + pat + '$', what, value)
File "/home/pi/sip/web/utils.py", line 531, in re_subm
compiled_pat = re_compile(pat)
File "/home/pi/sip/web/utils.py", line 499, in __call__
update(block=True)
File "/home/pi/sip/web/utils.py", line 496, in update
self.running[key].release()
error: release unlocked lock
192.168.1.77:62059 - - [06/Sep/2015 23:47:27] "HTTP/1.1 GET /api/status" - 500 Internal Server Error
192.168.1.77:62053 - - [06/Sep/2015 23:47:27] "HTTP/1.1 GET /api/log" - 200 OK
192.168.1.77:62053 - - [06/Sep/2015 23:47:38] "HTTP/1.1 GET /vr" - 200 OK
192.168.1.77:62053 - - [06/Sep/2015 23:47:39] "HTTP/1.1 GET /api/log" - 200 OK
192.168.1.77:62053 - - [06/Sep/2015 23:47:44] "HTTP/1.1 GET /cr" - 303 See Other
zones changed
[1, 0, 0, 0, 0, 0, 0, 0]
192.168.1.77:62053 - - [06/Sep/2015 23:47:45] "HTTP/1.1 GET /" - 200 OK
192.168.1.77:62053 - - [06/Sep/2015 23:47:46] "HTTP/1.1 GET /api/log" - 200 OK
192.168.1.77:62069 - - [06/Sep/2015 23:47:46] "HTTP/1.1 GET /api/status" - 200 OK
192.168.1.77:62053 - - [06/Sep/2015 23:47:47] "HTTP/1.1 GET /api/status" - 200 OK
192.168.1.77:62053 - - [06/Sep/2015 23:47:48] "HTTP/1.1 GET /api/status" - 200 OK
192.168.1.77:62053 - - [06/Sep/2015 23:47:50] "HTTP/1.1 GET /api/status" - 200 OK
192.168.1.77:62053 - - [06/Sep/2015 23:47:51] "HTTP/1.1 GET /api/status" - 200 OK
192.168.1.77:62053 - - [06/Sep/2015 23:47:52] "HTTP/1.1 GET /api/status" - 200 OK
192.168.1.77:62053 - - [06/Sep/2015 23:47:53] "HTTP/1.1 GET /api/status" - 200 OK
192.168.1.77:62053 - - [06/Sep/2015 23:47:54] "HTTP/1.1 GET /vr" - 200 OK
192.168.1.77:62053 - - [06/Sep/2015 23:47:55] "HTTP/1.1 GET /api/log" - 200 OK
192.168.1.77:62053 - - [06/Sep/2015 23:47:58] "HTTP/1.1 GET /" - 200 OK
192.168.1.77:62053 - - [06/Sep/2015 23:47:58] "HTTP/1.1 GET /api/log" - 200 OK
192.168.1.77:62075 - - [06/Sep/2015 23:47:59] "HTTP/1.1 GET /api/status" - 200 OK
192.168.1.77:62053 - - [06/Sep/2015 23:48:00] "HTTP/1.1 GET /api/status" - 200 OK
192.168.1.77:62053 - - [06/Sep/2015 23:48:02] "HTTP/1.1 GET /api/status" - 200 OK
192.168.1.77:62053 - - [06/Sep/2015 23:48:03] "HTTP/1.1 GET /api/status" - 200 OK
192.168.1.77:62053 - - [06/Sep/2015 23:48:04] "HTTP/1.1 GET /api/status" - 200 OK
192.168.1.77:62053 - - [06/Sep/2015 23:48:05] "HTTP/1.1 GET /api/status" - 200 OK
192.168.1.77:62053 - - [06/Sep/2015 23:48:07] "HTTP/1.1 GET /api/status" - 200 OK
192.168.1.77:62053 - - [06/Sep/2015 23:48:08] "HTTP/1.1 GET /api/status" - 200 OK
192.168.1.77:62053 - - [06/Sep/2015 23:48:09] "HTTP/1.1 GET /api/status" - 200 OK
192.168.1.77:62053 - - [06/Sep/2015 23:48:10] "HTTP/1.1 GET /api/status" - 200 OK
192.168.1.77:62053 - - [06/Sep/2015 23:48:11] "HTTP/1.1 GET /api/status" - 200 OK
192.168.1.77:62053 - - [06/Sep/2015 23:48:13] "HTTP/1.1 GET /api/status" - 200 OK
zones changed
[0, 0, 0, 0, 0, 0, 0, 0]
zones changed
[0, 0, 0, 0, 0, 0, 0, 0]
192.168.1.77:62053 - - [06/Sep/2015 23:48:14] "HTTP/1.1 GET /api/status" - 200 OK
192.168.1.77:62053 - - [06/Sep/2015 23:48:14] "HTTP/1.1 GET /api/log" - 200 OK
-- end log ---
RE: response time webserver high! and .. - dan - 2015 Sep 09
I have my test system set up with the same plugins as you have.
I found one bug that prevented programs from running when a station was set to ignore water level changes.
Try updating your software and see if it fixes your problem.