SIP › SIP (Sustainable Irrigation Platform) › Report a problem v
response time webserver high! and ..

Thread Rating:
  • 0 Vote(s) - 0 Average
  • 1
  • 2
  • 3
  • 4
  • 5
Threaded Mode
response time webserver high! and ..
takezo Offline
Junior Member
**
Posts: 26
Threads: 13
Joined: Aug 2015
Reputation: 1
#1
Sad  2015 Sep 04, 01:15 PM
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!
Find
Reply
dan Offline
Administrator
*******
Posts: 652
Threads: 18
Joined: Aug 2015
Reputation: 22
#2
2015 Sep 04, 07:12 PM
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.
Find
Reply
takezo Offline
Junior Member
**
Posts: 26
Threads: 13
Joined: Aug 2015
Reputation: 1
#3
2015 Sep 05, 09:54 AM
(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!
Find
Reply
dan Offline
Administrator
*******
Posts: 652
Threads: 18
Joined: Aug 2015
Reputation: 22
#4
2015 Sep 05, 06:12 PM
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.
Find
Reply
takezo Offline
Junior Member
**
Posts: 26
Threads: 13
Joined: Aug 2015
Reputation: 1
#5
2015 Sep 06, 11:33 AM
(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!!
Find
Reply
dan Offline
Administrator
*******
Posts: 652
Threads: 18
Joined: Aug 2015
Reputation: 22
#6
2015 Sep 06, 08:00 PM
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.
Find
Reply
takezo Offline
Junior Member
**
Posts: 26
Threads: 13
Joined: Aug 2015
Reputation: 1
#7
2015 Sep 06, 09:49 PM (This post was last modified: 2015 Sep 06, 10:01 PM by takezo.)
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 ---
Find
Reply
dan Offline
Administrator
*******
Posts: 652
Threads: 18
Joined: Aug 2015
Reputation: 22
#8
2015 Sep 09, 10:56 PM
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.
Find
Reply


Forum Jump:


Users browsing this thread: 1 Guest(s)