openhabforum.de


https://openhabforum.de/

executeCommandLine funktioniert nicht

https://openhabforum.de/viewtopic.php?t=140

Seite 1 von 1

executeCommandLine funktioniert nicht

Verfasst: 18. Feb 2016 23:57
von chubv
Hallo openHABbers!

Ich beschäftige mich seit ein paar Tagen mit openHAB2.
Dahinter betreibe ich eine Homematic.

Zum Test wollte ich nur mal eine einfache Regel schreiben und mir eine pushover Nachricht schicken lassen.
Allerding funktionier das nicht aus openHAB heraus.

Das ist meine Regel:

Code: Alles auswählen

rule "myrule on"
when
	Item Licht_Ambilight_TV received command ON
	
then

executeCommandLine ("curl -s -k -d token=\"TOKEN\" -d user=\"USER\" -d title=\"LIGHTS ON\" -d message=\"openHAB pushover test\" https://api.pushover.net/1/messages.json")

end
Laut openhab.log wird der Befehl ausgeführt und es kommt zu keiner Fehlermeldung.
Ich habe auch schon versucht statt der Leerzeichen @@ einzusetzen. Das hat aber auch nichts gebracht.

Wenn ich den Befehl in der Linux Commandline starte funktioniert das jedoch.

Hat jemand das gleiche Problem oder noch besser ... eine Lösung?

Thx1!

Re: executeCommandLine funktioniert nicht

Verfasst: 19. Feb 2016 09:57
von seppy
Hi,
ich habe mich mit openhab 2 noch nicht beschäftigt, aber die erste Vermutung: als welcher User läuft openhab?

Gruß,
Seppy


openhabforum.de

Re: executeCommandLine funktioniert nicht

Verfasst: 19. Feb 2016 22:57
von chubv
Hab's auch schon mit root probiert. [emoji6]
Aus der command line funktioniert der Befehl auch mit einem normalen User.

Re: executeCommandLine funktioniert nicht

Verfasst: 19. Feb 2016 23:01
von seppy
Setz mal den Debug Mode und poste den Auszug aus dem Logfile


openhabforum.de

Re: executeCommandLine funktioniert nicht

Verfasst: 21. Feb 2016 01:27
von chubv
Mach ich.

Re: executeCommandLine funktioniert nicht

Verfasst: 21. Feb 2016 21:53
von chubv
So, hier mal das Debuglog

Code: Alles auswählen

2016-02-21 12:40:51.532 [DEBUG] [org.eclipse.jetty.io.SelectorManager] - Selector loop woken up from select, 1/2 selected
2016-02-21 12:40:51.535 [DEBUG] [lipse.jetty.io.SelectChannelEndPoint] - Key interests updated 1 -> 0 on SelectChannelEndPoint@1676c45{/192.168.100.12:61226<->8080,Open,in,out,R,-,9771/30000,HttpConnection}{io=1,kio=0,kro=1}
2016-02-21 12:40:51.536 [DEBUG] [lipse.jetty.io.SelectChannelEndPoint] - Local interests updating 1 -> 0 for SelectChannelEndPoint@1676c45{/192.168.100.12:61226<->8080,Open,in,out,R,-,9771/30000,HttpConnection}{io=0,kio=0,kro=1}
2016-02-21 12:40:51.536 [DEBUG] [org.eclipse.jetty.io.SelectorManager] - Running change org.eclipse.jetty.io.SelectChannelEndPoint$1@188fb0a
2016-02-21 12:40:51.536 [DEBUG] [.eclipse.jetty.io.AbstractConnection] - FILL_INTERESTED-->FILLING HttpConnection@1eda376{FILLING}
2016-02-21 12:40:51.538 [DEBUG] [.eclipse.jetty.server.HttpConnection] - HttpConnection@1eda376{FILLING} onFillable HttpChannelState@209160{s=IDLE i=true a=null}
2016-02-21 12:40:51.539 [DEBUG] [org.eclipse.jetty.io.ChannelEndPoint] - filled 298 SelectChannelEndPoint@1676c45{/192.168.100.12:61226<->8080,Open,in,out,-,-,9775/30000,HttpConnection}{io=0,kio=0,kro=1}
2016-02-21 12:40:51.540 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - parseNext s=START HeapByteBuffer@ce3a27[p=0,l=298,c=8192,r=298]={<<<POST /rest/items/..., deflate\r\n\r\nON>>>ip, deflate\r\n\r\nn:...\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00}
2016-02-21 12:40:51.540 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - START --> SPACE1
2016-02-21 12:40:51.540 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - SPACE1 --> URI
2016-02-21 12:40:51.541 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - URI --> SPACE2
2016-02-21 12:40:51.542 [DEBUG] [org.eclipse.jetty.io.SelectorManager] - Selector loop waiting on select
2016-02-21 12:40:51.544 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - SPACE2 --> REQUEST_VERSION
2016-02-21 12:40:51.545 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - REQUEST_VERSION --> HEADER
2016-02-21 12:40:51.547 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - HEADER --> HEADER_IN_VALUE
2016-02-21 12:40:51.567 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - HEADER_IN_VALUE --> HEADER
2016-02-21 12:40:51.568 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - HEADER --> HEADER_IN_VALUE
2016-02-21 12:40:51.570 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - HEADER_IN_VALUE --> HEADER
2016-02-21 12:40:51.571 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - HEADER --> HEADER_VALUE
2016-02-21 12:40:51.572 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - HEADER_VALUE --> HEADER_IN_VALUE
2016-02-21 12:40:51.574 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - HEADER_IN_VALUE --> HEADER
2016-02-21 12:40:51.580 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - HEADER --> HEADER_IN_VALUE
2016-02-21 12:40:51.581 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - HEADER_IN_VALUE --> HEADER
2016-02-21 12:40:51.582 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - HEADER --> HEADER_IN_VALUE
2016-02-21 12:40:51.589 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - HEADER_IN_VALUE --> HEADER
2016-02-21 12:40:51.593 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - HEADER --> HEADER_IN_VALUE
2016-02-21 12:40:51.595 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - HEADER_IN_VALUE --> HEADER
2016-02-21 12:40:51.596 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - HEADER --> HEADER_IN_VALUE
2016-02-21 12:40:51.607 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - HEADER_IN_VALUE --> HEADER
2016-02-21 12:40:51.608 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - HEADER --> HEADER_IN_VALUE
2016-02-21 12:40:51.614 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - HEADER_IN_VALUE --> HEADER
2016-02-21 12:40:51.616 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - HEADER --> HEADER_IN_VALUE
2016-02-21 12:40:51.617 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - HEADER_IN_VALUE --> HEADER
2016-02-21 12:40:51.622 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - HEADER --> CONTENT
2016-02-21 12:40:51.623 [DEBUG] [org.eclipse.jetty.server.HttpChannel] - HttpChannelOverHttp@1349500{r=2,c=false,a=IDLE,uri=-} handle enter
2016-02-21 12:40:51.626 [DEBUG] [clipse.jetty.server.HttpChannelState] - HttpChannelState@209160{s=IDLE i=true a=null} handling IDLE
2016-02-21 12:40:51.634 [DEBUG] [org.eclipse.jetty.server.HttpChannel] - HttpChannelOverHttp@1349500{r=2,c=false,a=DISPATCHED,uri=/rest/items/Licht_Ambilight_TV} action REQUEST_DISPATCH
2016-02-21 12:40:51.635 [DEBUG] [org.eclipse.jetty.server.Server     ] - REQUEST POST /rest/items/Licht_Ambilight_TV on HttpChannelOverHttp@1349500{r=2,c=false,a=DISPATCHED,uri=/rest/items/Licht_Ambilight_TV}
2016-02-21 12:40:51.636 [DEBUG] [ax.web.service.spi.model.ServerModel] - Matching [/rest/items/Licht_Ambilight_TV]...
2016-02-21 12:40:51.637 [DEBUG] [ax.web.service.spi.model.ServerModel] - Path [/rest/items/Licht_Ambilight_TV] matched to {pattern=/rest/.*,model=ServletModel{id=org.ops4j.pax.web.service.spi.model.ServletModel-2,name=org.ops4j.pax.web.service.spi.model.ServletModel-2,urlPatterns=[/rest/*],alias=/rest,servlet=com.eclipsesource.jaxrs.publisher.internal.ServletContainerBridge@161b036,initParams={},context=ContextModel{id=org.ops4j.pax.web.service.spi.model.ContextModel-1,name=,httpContext=DefaultHttpContext [bundle=com.eclipsesource.jaxrs.publisher_5.3.0.201512270850 [12], contextID=default],contextParams={},virtualHosts={},connectors={}}}}
2016-02-21 12:40:51.639 [DEBUG] [.jetty.server.handler.ContextHandler] - scope null||/rest/items/Licht_Ambilight_TV @ HttpServiceContext{httpContext=DefaultHttpContext [bundle=com.eclipsesource.jaxrs.publisher_5.3.0.201512270850 [12], contextID=default]}
2016-02-21 12:40:51.644 [DEBUG] [.jetty.server.handler.ContextHandler] - context=||/rest/items/Licht_Ambilight_TV @ HttpServiceContext{httpContext=DefaultHttpContext [bundle=com.eclipsesource.jaxrs.publisher_5.3.0.201512270850 [12], contextID=default]}
2016-02-21 12:40:51.645 [DEBUG] [org.eclipse.jetty.server.session    ] - sessionManager=org.ops4j.pax.web.service.jetty.internal.LateInvalidatingHashSessionManager@28468b
2016-02-21 12:40:51.646 [DEBUG] [org.eclipse.jetty.server.session    ] - session=null
2016-02-21 12:40:51.653 [DEBUG] [eclipse.jetty.servlet.ServletHandler] - servlet |/rest|/items/Licht_Ambilight_TV -> org.ops4j.pax.web.service.spi.model.ServletModel-2@378be0fa==com.eclipsesource.jaxrs.publisher.internal.ServletContainerBridge,-1,true
2016-02-21 12:40:51.654 [DEBUG] [ce.jetty.internal.HttpServiceContext] - Handling request for [/rest/items/Licht_Ambilight_TV] using http context [DefaultHttpContext [bundle=com.eclipsesource.jaxrs.publisher_5.3.0.201512270850 [12], contextID=default]]
2016-02-21 12:40:51.655 [DEBUG] [y.internal.HttpServiceServletHandler] - handling request org.ops4j.pax.web.service.jetty.internal.HttpServiceRequestWrapper@17863e2, org.ops4j.pax.web.service.jetty.internal.HttpServiceResponseWrapper@3346ca
2016-02-21 12:40:51.656 [DEBUG] [eclipse.jetty.servlet.ServletHandler] - chain=null
2016-02-21 12:40:51.668 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - parseNext s=CONTENT HeapByteBuffer@ce3a27[p=296,l=298,c=8192,r=2]={POST /rest/items/...ip, deflate\r\n\r\n<<<ON>>>ip, deflate\r\n\r\nn:...\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00}
2016-02-21 12:40:51.674 [DEBUG] [org.eclipse.jetty.server.HttpChannel] - HttpChannelOverHttp@1349500{r=2,c=false,a=DISPATCHED,uri=/rest/items/Licht_Ambilight_TV} content java.nio.HeapByteBufferR[pos=296 lim=298 cap=8192]
2016-02-21 12:40:51.675 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - parseNext s=CONTENT HeapByteBuffer@ce3a27[p=298,l=298,c=8192,r=0]={POST /rest/items/..., deflate\r\n\r\nON<<<>>>ip, deflate\r\n\r\nn:...\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00}
2016-02-21 12:40:51.682 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - CONTENT --> END
2016-02-21 12:40:51.683 [DEBUG] [org.eclipse.jetty.server.HttpChannel] - HttpChannelOverHttp@1349500{r=2,c=false,a=DISPATCHED,uri=/rest/items/Licht_Ambilight_TV} messageComplete
2016-02-21 12:40:51.684 [DEBUG] [org.eclipse.jetty.server.HttpInput  ] - HttpInputOverHTTP@1e5437b EOF
2016-02-21 12:40:51.686 [DEBUG] [org.eclipse.jetty.io.ChannelEndPoint] - filled 0 SelectChannelEndPoint@1676c45{/192.168.100.12:61226<->8080,Open,in,out,-,-,146/30000,HttpConnection}{io=0,kio=0,kro=1}
2016-02-21 12:40:51.690 [DEBUG] [.eclipse.jetty.server.HttpConnection] - HttpConnection@1eda376{FILLING} filled 0
2016-02-21 12:40:51.691 [DEBUG] [org.eclipse.jetty.server.HttpInput  ] - HttpInputOverHTTP@1e5437b eof EOF
2016-02-21 12:40:51.693 [DEBUG] [org.eclipse.jetty.server.HttpInput  ] - HttpInputOverHTTP@1e5437b eof EOF
2016-02-21 12:40:51.709 [DEBUG] [org.eclipse.jetty.server.HttpInput  ] - HttpInputOverHTTP@1e5437b eof EOF
2016-02-21 12:40:51.719 [DEBUG] [.jetty.server.handler.ContextHandler] - scope null||/rest/items/Licht_Ambilight_TV @ o.e.j.s.h.ContextHandler@10960d3{/static,null,AVAILABLE}
2016-02-21 12:40:51.720 [INFO ] [smarthome.event.ItemCommandEvent    ] - Item 'Licht_Ambilight_TV' received command ON
2016-02-21 12:40:51.737 [INFO ] [marthome.event.ItemStateChangedEvent] - Licht_Ambilight_TV changed from OFF to ON
2016-02-21 12:40:51.754 [DEBUG] [.jetty.server.handler.ContextHandler] - scope null||/rest/items/Licht_Ambilight_TV @ HttpServiceContext{httpContext=DefaultHttpContext [bundle=org.eclipse.smarthome.ui_0.8.0.201601301304 [120], contextID=default]}
2016-02-21 12:40:51.771 [DEBUG] [.jetty.server.handler.ContextHandler] - scope null||/rest/items/Licht_Ambilight_TV @ HttpServiceContext{httpContext=DefaultHttpContext [bundle=org.eclipse.smarthome.ui.icon_0.8.0.201601301304 [121], contextID=default]}
2016-02-21 12:40:51.774 [DEBUG] [.jetty.server.handler.ContextHandler] - scope null||/rest/items/Licht_Ambilight_TV @ HttpServiceContext{httpContext=DefaultHttpContext [bundle=org.eclipse.smarthome.ui.basic_0.8.0.201601301304 [150], contextID=default]}
2016-02-21 12:40:51.795 [DEBUG] [.jetty.server.handler.ContextHandler] - scope null||/rest/items/Licht_Ambilight_TV @ HttpServiceContext{httpContext=DefaultHttpContext [bundle=org.eclipse.smarthome.ui.classic_0.8.0.201601301304 [151], contextID=default]}
2016-02-21 12:40:51.796 [INFO ] [lipse.smarthome.io.net.exec.ExecUtil] - executed commandLine 'curl -s -k -d token="TOKEN" -d user="USER" -d title="LIGHTS ON" -d message="openHAB pushover test" https://api.pushover.net/1/messages.json'
2016-02-21 12:40:51.815 [DEBUG] [.jetty.server.handler.ContextHandler] - scope null||/rest/items/Licht_Ambilight_TV @ HttpServiceContext{httpContext=DefaultHttpContext [bundle=org.eclipse.smarthome.ui.paper_0.8.0.201601301304 [152], contextID=default]}
2016-02-21 12:40:51.815 [DEBUG] [.jetty.server.handler.ContextHandler] - scope null||/rest/items/Licht_Ambilight_TV @ HttpServiceContext{httpContext=org.jupnp.transport.impl.osgi.DisableAuthenticationHttpContext@14267fb}
2016-02-21 12:40:51.816 [DEBUG] [.jetty.server.handler.ContextHandler] - scope null||/rest/items/Licht_Ambilight_TV @ HttpServiceContext{httpContext=DefaultHttpContext [bundle=org.openhab.io.rest.docs_2.0.0.201601310204 [158], contextID=default]}
2016-02-21 12:40:51.816 [DEBUG] [.jetty.server.handler.ContextHandler] - scope null||/rest/items/Licht_Ambilight_TV @ HttpServiceContext{httpContext=org.openhab.io.net.http.SecureHttpContext@1543b39}
2016-02-21 12:40:51.816 [DEBUG] [.jetty.server.handler.ContextHandler] - scope null||/rest/items/Licht_Ambilight_TV @ HttpServiceContext{httpContext=DefaultHttpContext [bundle=org.openhab.ui.dashboard_2.0.0.201601310204 [163], contextID=default]}
2016-02-21 12:40:51.816 [DEBUG] [.jetty.server.handler.ContextHandler] - scope null||/rest/items/Licht_Ambilight_TV @ HttpServiceContext{httpContext=DefaultHttpContext [bundle=org.openhab.core_2.0.0.201601310204 [131], contextID=default]}
2016-02-21 12:40:51.817 [DEBUG] [org.eclipse.jetty.server.Server     ] - RESPONSE /rest/items/Licht_Ambilight_TV  201 handled=true
2016-02-21 12:40:51.839 [DEBUG] [clipse.jetty.server.HttpChannelState] - HttpChannelState@209160{s=DISPATCHED i=true a=null} unhandle DISPATCHED
2016-02-21 12:40:51.841 [DEBUG] [.eclipse.jetty.server.HttpConnection] - org.eclipse.jetty.server.HttpConnection$SendCallback@1cd726a[PROCESSING][i=ResponseInfo{HTTP/1.1 201 Created,-1,false},cb=org.eclipse.jetty.server.HttpChannel$CommitCallback@71e887] generate: NEED_HEADER (null,[p=0,l=0,c=0,r=0],true)@START
2016-02-21 12:40:51.850 [DEBUG] [.eclipse.jetty.server.HttpConnection] - org.eclipse.jetty.server.HttpConnection$SendCallback@1cd726a[PROCESSING][i=ResponseInfo{HTTP/1.1 201 Created,-1,false},cb=org.eclipse.jetty.server.HttpChannel$CommitCallback@71e887] generate: FLUSH ([p=0,l=183,c=8192,r=183],[p=0,l=0,c=0,r=0],true)@COMPLETING
2016-02-21 12:40:51.856 [DEBUG] [org.eclipse.jetty.io.ChannelEndPoint] - flushed 183 SelectChannelEndPoint@1676c45{/192.168.100.12:61226<->8080,Open,in,out,-,W,317/30000,HttpConnection}{io=0,kio=0,kro=1}
2016-02-21 12:40:51.862 [DEBUG] [.eclipse.jetty.server.HttpConnection] - org.eclipse.jetty.server.HttpConnection$SendCallback@1cd726a[PROCESSING][i=ResponseInfo{HTTP/1.1 201 Created,-1,false},cb=org.eclipse.jetty.server.HttpChannel$CommitCallback@71e887] generate: DONE ([p=183,l=183,c=8192,r=0],[p=0,l=0,c=0,r=0],true)@END
2016-02-21 12:40:51.871 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - reset HttpParser{s=END,2 of 2}
2016-02-21 12:40:51.873 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - END --> START
2016-02-21 12:40:51.878 [DEBUG] [org.eclipse.jetty.server.HttpChannel] - HttpChannelOverHttp@1349500{r=2,c=false,a=IDLE,uri=-} handle exit, result COMPLETE
2016-02-21 12:40:51.890 [DEBUG] [org.eclipse.jetty.io.ChannelEndPoint] - filled 0 SelectChannelEndPoint@1676c45{/192.168.100.12:61226<->8080,Open,in,out,-,-,28/30000,HttpConnection}{io=0,kio=0,kro=1}
2016-02-21 12:40:51.908 [DEBUG] [org.eclipse.jetty.io.ChannelEndPoint] - filled 0 SelectChannelEndPoint@1676c45{/192.168.100.12:61226<->8080,Open,in,out,-,-,46/30000,HttpConnection}{io=0,kio=0,kro=1}
2016-02-21 12:40:51.923 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - parseNext s=START HeapByteBuffer@189b170[p=0,l=0,c=8192,r=0]={<<<>>>GET /rest/sitemap...\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00}
2016-02-21 12:40:51.931 [DEBUG] [.eclipse.jetty.io.AbstractConnection] - fillInterested HttpConnection@1eda376{FILLING}
2016-02-21 12:40:51.935 [DEBUG] [e.internal.WriterInterceptorExecutor] - Message body writer (class com.eclipsesource.jaxrs.provider.gson.GsonProvider) is trying to close the entity output stream. Not closing.
2016-02-21 12:40:51.952 [DEBUG] [.eclipse.jetty.server.HttpConnection] - org.eclipse.jetty.server.HttpConnection$SendCallback@187a199[PROCESSING][i=ResponseInfo{HTTP/1.1 200 OK,2180,false},cb=org.eclipse.jetty.server.HttpChannel$CommitCallback@e1ad06] generate: NEED_HEADER (null,[p=0,l=2180,c=8192,r=2180],true)@START
2016-02-21 12:40:51.949 [DEBUG] [.eclipse.jetty.io.AbstractConnection] - FILLING-->FILLING_FILL_INTERESTED HttpConnection@1eda376{FILLING_FILL_INTERESTED}
2016-02-21 12:40:51.967 [DEBUG] [.eclipse.jetty.io.AbstractConnection] - FILLING_FILL_INTERESTED-->FILL_INTERESTED HttpConnection@1eda376{FILL_INTERESTED}
2016-02-21 12:40:51.967 [DEBUG] [lipse.jetty.io.SelectChannelEndPoint] - Local interests updating 0 -> 1 for SelectChannelEndPoint@1676c45{/192.168.100.12:61226<->8080,Open,in,out,R,-,0/30000,HttpConnection}{io=1,kio=0,kro=1}
2016-02-21 12:40:51.968 [DEBUG] [org.eclipse.jetty.io.SelectorManager] - Running change org.eclipse.jetty.io.SelectChannelEndPoint$1@188fb0a
2016-02-21 12:40:51.968 [DEBUG] [lipse.jetty.io.SelectChannelEndPoint] - Key interests updated 0 -> 1 on SelectChannelEndPoint@1676c45{/192.168.100.12:61226<->8080,Open,in,out,R,-,1/30000,HttpConnection}{io=1,kio=1,kro=1}
2016-02-21 12:40:51.975 [DEBUG] [org.eclipse.jetty.io.SelectorManager] - Selector loop woken up from select, 0/2 selected
2016-02-21 12:40:51.975 [DEBUG] [org.eclipse.jetty.io.SelectorManager] - Selector loop waiting on select
2016-02-21 12:40:51.977 [DEBUG] [.eclipse.jetty.server.HttpConnection] - org.eclipse.jetty.server.HttpConnection$SendCallback@187a199[PROCESSING][i=ResponseInfo{HTTP/1.1 200 OK,2180,false},cb=org.eclipse.jetty.server.HttpChannel$CommitCallback@e1ad06] generate: FLUSH ([p=0,l=143,c=8192,r=143],[p=0,l=2180,c=8192,r=2180],true)@COMPLETING
2016-02-21 12:40:51.979 [DEBUG] [org.eclipse.jetty.io.ChannelEndPoint] - flushed 2323 SelectChannelEndPoint@a73b31{/192.168.100.12:61206<->8080,Open,in,out,-,W,9828/30000,HttpConnection}{io=0,kio=0,kro=1}
2016-02-21 12:40:51.994 [DEBUG] [.eclipse.jetty.server.HttpConnection] - org.eclipse.jetty.server.HttpConnection$SendCallback@187a199[PROCESSING][i=ResponseInfo{HTTP/1.1 200 OK,2180,false},cb=org.eclipse.jetty.server.HttpChannel$CommitCallback@e1ad06] generate: DONE ([p=143,l=143,c=8192,r=0],[p=2180,l=2180,c=8192,r=0],true)@END
2016-02-21 12:40:52.003 [DEBUG] [.jetty.server.handler.ContextHandler] - scope null||/rest/sitemaps/myhome/EG_Wohnzimmer @ o.e.j.s.h.ContextHandler@10960d3{/static,null,AVAILABLE}
2016-02-21 12:40:52.005 [DEBUG] [.jetty.server.handler.ContextHandler] - scope null||/rest/sitemaps/myhome/EG_Wohnzimmer @ HttpServiceContext{httpContext=DefaultHttpContext [bundle=org.eclipse.smarthome.ui_0.8.0.201601301304 [120], contextID=default]}
2016-02-21 12:40:52.010 [DEBUG] [.jetty.server.handler.ContextHandler] - scope null||/rest/sitemaps/myhome/EG_Wohnzimmer @ HttpServiceContext{httpContext=DefaultHttpContext [bundle=org.eclipse.smarthome.ui.icon_0.8.0.201601301304 [121], contextID=default]}
2016-02-21 12:40:52.012 [DEBUG] [.jetty.server.handler.ContextHandler] - scope null||/rest/sitemaps/myhome/EG_Wohnzimmer @ HttpServiceContext{httpContext=DefaultHttpContext [bundle=org.eclipse.smarthome.ui.basic_0.8.0.201601301304 [150], contextID=default]}
2016-02-21 12:40:52.015 [DEBUG] [.jetty.server.handler.ContextHandler] - scope null||/rest/sitemaps/myhome/EG_Wohnzimmer @ HttpServiceContext{httpContext=DefaultHttpContext [bundle=org.eclipse.smarthome.ui.classic_0.8.0.201601301304 [151], contextID=default]}
2016-02-21 12:40:52.018 [DEBUG] [.jetty.server.handler.ContextHandler] - scope null||/rest/sitemaps/myhome/EG_Wohnzimmer @ HttpServiceContext{httpContext=DefaultHttpContext [bundle=org.eclipse.smarthome.ui.paper_0.8.0.201601301304 [152], contextID=default]}
2016-02-21 12:40:52.023 [DEBUG] [.jetty.server.handler.ContextHandler] - scope null||/rest/sitemaps/myhome/EG_Wohnzimmer @ HttpServiceContext{httpContext=org.jupnp.transport.impl.osgi.DisableAuthenticationHttpContext@14267fb}
2016-02-21 12:40:52.026 [DEBUG] [.jetty.server.handler.ContextHandler] - scope null||/rest/sitemaps/myhome/EG_Wohnzimmer @ HttpServiceContext{httpContext=DefaultHttpContext [bundle=org.openhab.io.rest.docs_2.0.0.201601310204 [158], contextID=default]}
2016-02-21 12:40:52.031 [DEBUG] [.jetty.server.handler.ContextHandler] - scope null||/rest/sitemaps/myhome/EG_Wohnzimmer @ HttpServiceContext{httpContext=org.openhab.io.net.http.SecureHttpContext@1543b39}
2016-02-21 12:40:52.035 [DEBUG] [.jetty.server.handler.ContextHandler] - scope null||/rest/sitemaps/myhome/EG_Wohnzimmer @ HttpServiceContext{httpContext=DefaultHttpContext [bundle=org.openhab.ui.dashboard_2.0.0.201601310204 [163], contextID=default]}
2016-02-21 12:40:52.039 [DEBUG] [.jetty.server.handler.ContextHandler] - scope null||/rest/sitemaps/myhome/EG_Wohnzimmer @ HttpServiceContext{httpContext=DefaultHttpContext [bundle=org.openhab.core_2.0.0.201601310204 [131], contextID=default]}
2016-02-21 12:40:52.040 [DEBUG] [org.eclipse.jetty.server.Server     ] - RESPONSE /rest/sitemaps/myhome/EG_Wohnzimmer  200 handled=true
2016-02-21 12:40:52.044 [DEBUG] [clipse.jetty.server.HttpChannelState] - HttpChannelState@120dc65{s=DISPATCHED i=true a=null} unhandle DISPATCHED
2016-02-21 12:40:52.053 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - reset HttpParser{s=END,0 of -1}
2016-02-21 12:40:52.054 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - END --> START
2016-02-21 12:40:52.056 [DEBUG] [org.eclipse.jetty.server.HttpChannel] - HttpChannelOverHttp@fde055{r=8,c=false,a=IDLE,uri=-} handle exit, result COMPLETE
2016-02-21 12:40:52.062 [DEBUG] [org.eclipse.jetty.io.ChannelEndPoint] - filled 0 SelectChannelEndPoint@a73b31{/192.168.100.12:61206<->8080,Open,in,out,-,-,73/30000,HttpConnection}{io=0,kio=0,kro=1}
2016-02-21 12:40:52.066 [DEBUG] [org.eclipse.jetty.io.ChannelEndPoint] - filled 0 SelectChannelEndPoint@a73b31{/192.168.100.12:61206<->8080,Open,in,out,-,-,78/30000,HttpConnection}{io=0,kio=0,kro=1}
2016-02-21 12:40:52.070 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - parseNext s=START HeapByteBuffer@56bd0[p=0,l=0,c=8192,r=0]={<<<>>>HTTP/1.1 201 Crea...\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00}
2016-02-21 12:40:52.071 [DEBUG] [.eclipse.jetty.io.AbstractConnection] - fillInterested HttpConnection@3be230{FILLING}
2016-02-21 12:40:52.074 [DEBUG] [.eclipse.jetty.io.AbstractConnection] - FILLING-->FILLING_FILL_INTERESTED HttpConnection@3be230{FILLING_FILL_INTERESTED}
2016-02-21 12:40:52.079 [DEBUG] [.eclipse.jetty.io.AbstractConnection] - FILLING_FILL_INTERESTED-->FILL_INTERESTED HttpConnection@3be230{FILL_INTERESTED}
2016-02-21 12:40:52.080 [DEBUG] [lipse.jetty.io.SelectChannelEndPoint] - Local interests updating 0 -> 1 for SelectChannelEndPoint@a73b31{/192.168.100.12:61206<->8080,Open,in,out,R,-,0/30000,HttpConnection}{io=1,kio=0,kro=1}
2016-02-21 12:40:52.086 [DEBUG] [org.eclipse.jetty.io.SelectorManager] - Running change org.eclipse.jetty.io.SelectChannelEndPoint$1@1fa3135
2016-02-21 12:40:52.088 [DEBUG] [lipse.jetty.io.SelectChannelEndPoint] - Key interests updated 0 -> 1 on SelectChannelEndPoint@a73b31{/192.168.100.12:61206<->8080,Open,in,out,R,-,7/30000,HttpConnection}{io=1,kio=1,kro=1}
2016-02-21 12:40:52.088 [DEBUG] [org.eclipse.jetty.io.SelectorManager] - Selector loop woken up from select, 0/2 selected
2016-02-21 12:40:52.095 [DEBUG] [org.eclipse.jetty.io.SelectorManager] - Selector loop waiting on select
2016-02-21 12:40:52.095 [DEBUG] [org.eclipse.jetty.io.SelectorManager] - Selector loop woken up from select, 1/2 selected
2016-02-21 12:40:52.095 [DEBUG] [lipse.jetty.io.SelectChannelEndPoint] - Key interests updated 1 -> 0 on SelectChannelEndPoint@a73b31{/192.168.100.12:61206<->8080,Open,in,out,R,-,15/30000,HttpConnection}{io=1,kio=0,kro=1}
2016-02-21 12:40:52.096 [DEBUG] [lipse.jetty.io.SelectChannelEndPoint] - Local interests updating 1 -> 0 for SelectChannelEndPoint@a73b31{/192.168.100.12:61206<->8080,Open,in,out,R,-,16/30000,HttpConnection}{io=0,kio=0,kro=1}
2016-02-21 12:40:52.098 [DEBUG] [org.eclipse.jetty.io.SelectorManager] - Running change org.eclipse.jetty.io.SelectChannelEndPoint$1@1fa3135
2016-02-21 12:40:52.103 [DEBUG] [.eclipse.jetty.io.AbstractConnection] - FILL_INTERESTED-->FILLING HttpConnection@3be230{FILLING}
2016-02-21 12:40:52.108 [DEBUG] [.eclipse.jetty.server.HttpConnection] - HttpConnection@3be230{FILLING} onFillable HttpChannelState@120dc65{s=IDLE i=true a=null}
2016-02-21 12:40:52.109 [DEBUG] [org.eclipse.jetty.io.ChannelEndPoint] - filled 313 SelectChannelEndPoint@a73b31{/192.168.100.12:61206<->8080,Open,in,out,-,-,28/30000,HttpConnection}{io=0,kio=0,kro=1}
2016-02-21 12:40:52.109 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - parseNext s=START HeapByteBuffer@ce3a27[p=0,l=313,c=8192,r=313]={<<<GET /rest/sitemap...ip, deflate\r\n\r\n>>>n: keep-alive\r\nAc...\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00}
2016-02-21 12:40:52.109 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - START --> SPACE1
2016-02-21 12:40:52.109 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - SPACE1 --> URI
2016-02-21 12:40:52.114 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - URI --> SPACE2
2016-02-21 12:40:52.115 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - SPACE2 --> REQUEST_VERSION
2016-02-21 12:40:52.123 [DEBUG] [org.eclipse.jetty.io.SelectorManager] - Selector loop waiting on select
2016-02-21 12:40:52.124 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - REQUEST_VERSION --> HEADER
2016-02-21 12:40:52.124 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - HEADER --> HEADER_IN_VALUE
2016-02-21 12:40:52.125 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - HEADER_IN_VALUE --> HEADER
2016-02-21 12:40:52.125 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - HEADER --> HEADER_IN_VALUE
2016-02-21 12:40:52.130 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - HEADER_IN_VALUE --> HEADER
2016-02-21 12:40:52.130 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - HEADER --> HEADER_IN_VALUE
2016-02-21 12:40:52.132 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - HEADER_IN_VALUE --> HEADER
2016-02-21 12:40:52.132 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - HEADER --> HEADER_IN_NAME
2016-02-21 12:40:52.138 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - HEADER_IN_NAME --> HEADER_VALUE
2016-02-21 12:40:52.138 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - HEADER_VALUE --> HEADER_IN_VALUE
2016-02-21 12:40:52.139 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - HEADER_IN_VALUE --> HEADER
2016-02-21 12:40:52.139 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - HEADER --> HEADER_IN_VALUE
2016-02-21 12:40:52.140 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - HEADER_IN_VALUE --> HEADER
2016-02-21 12:40:52.140 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - HEADER --> HEADER_IN_VALUE
2016-02-21 12:40:52.146 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - HEADER_IN_VALUE --> HEADER
2016-02-21 12:40:52.146 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - HEADER --> HEADER_IN_NAME
2016-02-21 12:40:52.147 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - HEADER_IN_NAME --> HEADER_VALUE
2016-02-21 12:40:52.147 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - HEADER_VALUE --> HEADER_IN_VALUE
2016-02-21 12:40:52.148 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - HEADER_IN_VALUE --> HEADER
2016-02-21 12:40:52.148 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - HEADER --> HEADER_IN_VALUE
2016-02-21 12:40:52.153 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - HEADER_IN_VALUE --> HEADER
2016-02-21 12:40:52.154 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - HEADER --> HEADER_IN_VALUE
2016-02-21 12:40:52.155 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - HEADER_IN_VALUE --> HEADER
2016-02-21 12:40:52.163 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - HEADER --> END
2016-02-21 12:40:52.164 [DEBUG] [org.eclipse.jetty.server.HttpChannel] - HttpChannelOverHttp@fde055{r=9,c=false,a=IDLE,uri=-} messageComplete
2016-02-21 12:40:52.164 [DEBUG] [org.eclipse.jetty.server.HttpInput  ] - HttpInputOverHTTP@8e3495 EOF
2016-02-21 12:40:52.165 [DEBUG] [org.eclipse.jetty.server.HttpChannel] - HttpChannelOverHttp@fde055{r=9,c=false,a=IDLE,uri=-} handle enter
2016-02-21 12:40:52.166 [DEBUG] [clipse.jetty.server.HttpChannelState] - HttpChannelState@120dc65{s=IDLE i=true a=null} handling IDLE
2016-02-21 12:40:52.166 [DEBUG] [org.eclipse.jetty.server.HttpChannel] - HttpChannelOverHttp@fde055{r=9,c=false,a=DISPATCHED,uri=/rest/sitemaps/myhome/EG_Wohnzimmer} action REQUEST_DISPATCH
2016-02-21 12:40:52.174 [DEBUG] [org.eclipse.jetty.server.Server     ] - REQUEST GET /rest/sitemaps/myhome/EG_Wohnzimmer on HttpChannelOverHttp@fde055{r=9,c=false,a=DISPATCHED,uri=/rest/sitemaps/myhome/EG_Wohnzimmer}
2016-02-21 12:40:52.187 [DEBUG] [ax.web.service.spi.model.ServerModel] - Matching [/rest/sitemaps/myhome/EG_Wohnzimmer]...
2016-02-21 12:40:52.195 [DEBUG] [ax.web.service.spi.model.ServerModel] - Path [/rest/sitemaps/myhome/EG_Wohnzimmer] matched to {pattern=/rest/.*,model=ServletModel{id=org.ops4j.pax.web.service.spi.model.ServletModel-2,name=org.ops4j.pax.web.service.spi.model.ServletModel-2,urlPatterns=[/rest/*],alias=/rest,servlet=com.eclipsesource.jaxrs.publisher.internal.ServletContainerBridge@161b036,initParams={},context=ContextModel{id=org.ops4j.pax.web.service.spi.model.ContextModel-1,name=,httpContext=DefaultHttpContext [bundle=com.eclipsesource.jaxrs.publisher_5.3.0.201512270850 [12], contextID=default],contextParams={},virtualHosts={},connectors={}}}}
2016-02-21 12:40:52.196 [DEBUG] [.jetty.server.handler.ContextHandler] - scope null||/rest/sitemaps/myhome/EG_Wohnzimmer @ HttpServiceContext{httpContext=DefaultHttpContext [bundle=com.eclipsesource.jaxrs.publisher_5.3.0.201512270850 [12], contextID=default]}
2016-02-21 12:40:52.197 [DEBUG] [.jetty.server.handler.ContextHandler] - context=||/rest/sitemaps/myhome/EG_Wohnzimmer @ HttpServiceContext{httpContext=DefaultHttpContext [bundle=com.eclipsesource.jaxrs.publisher_5.3.0.201512270850 [12], contextID=default]}
2016-02-21 12:40:52.198 [DEBUG] [org.eclipse.jetty.server.session    ] - sessionManager=org.ops4j.pax.web.service.jetty.internal.LateInvalidatingHashSessionManager@28468b
2016-02-21 12:40:52.206 [DEBUG] [org.eclipse.jetty.server.session    ] - session=null
2016-02-21 12:40:52.208 [DEBUG] [eclipse.jetty.servlet.ServletHandler] - servlet |/rest|/sitemaps/myhome/EG_Wohnzimmer -> org.ops4j.pax.web.service.spi.model.ServletModel-2@378be0fa==com.eclipsesource.jaxrs.publisher.internal.ServletContainerBridge,-1,true
2016-02-21 12:40:52.208 [DEBUG] [ce.jetty.internal.HttpServiceContext] - Handling request for [/rest/sitemaps/myhome/EG_Wohnzimmer] using http context [DefaultHttpContext [bundle=com.eclipsesource.jaxrs.publisher_5.3.0.201512270850 [12], contextID=default]]
2016-02-21 12:40:52.209 [DEBUG] [y.internal.HttpServiceServletHandler] - handling request org.ops4j.pax.web.service.jetty.internal.HttpServiceRequestWrapper@1cd8ebd, org.ops4j.pax.web.service.jetty.internal.HttpServiceResponseWrapper@1122387
2016-02-21 12:40:52.211 [DEBUG] [eclipse.jetty.servlet.ServletHandler] - chain=null
2016-02-21 12:40:52.244 [DEBUG] [e.internal.WriterInterceptorExecutor] - Message body writer (class com.eclipsesource.jaxrs.provider.gson.GsonProvider) is trying to close the entity output stream. Not closing.
2016-02-21 12:40:52.245 [DEBUG] [.eclipse.jetty.server.HttpConnection] - org.eclipse.jetty.server.HttpConnection$SendCallback@187a199[PROCESSING][i=ResponseInfo{HTTP/1.1 200 OK,2180,false},cb=org.eclipse.jetty.server.HttpChannel$CommitCallback@215320] generate: NEED_HEADER (null,[p=0,l=2180,c=8192,r=2180],true)@START
2016-02-21 12:40:52.270 [DEBUG] [.eclipse.jetty.server.HttpConnection] - org.eclipse.jetty.server.HttpConnection$SendCallback@187a199[PROCESSING][i=ResponseInfo{HTTP/1.1 200 OK,2180,false},cb=org.eclipse.jetty.server.HttpChannel$CommitCallback@215320] generate: FLUSH ([p=0,l=143,c=8192,r=143],[p=0,l=2180,c=8192,r=2180],true)@COMPLETING
2016-02-21 12:40:52.272 [DEBUG] [org.eclipse.jetty.io.ChannelEndPoint] - flushed 2323 SelectChannelEndPoint@a73b31{/192.168.100.12:61206<->8080,Open,in,out,-,W,163/30000,HttpConnection}{io=0,kio=0,kro=1}
2016-02-21 12:40:52.273 [DEBUG] [.eclipse.jetty.server.HttpConnection] - org.eclipse.jetty.server.HttpConnection$SendCallback@187a199[PROCESSING][i=ResponseInfo{HTTP/1.1 200 OK,2180,false},cb=org.eclipse.jetty.server.HttpChannel$CommitCallback@215320] generate: DONE ([p=143,l=143,c=8192,r=0],[p=2180,l=2180,c=8192,r=0],true)@END
2016-02-21 12:40:52.287 [DEBUG] [.jetty.server.handler.ContextHandler] - scope null||/rest/sitemaps/myhome/EG_Wohnzimmer @ o.e.j.s.h.ContextHandler@10960d3{/static,null,AVAILABLE}
2016-02-21 12:40:52.293 [DEBUG] [.jetty.server.handler.ContextHandler] - scope null||/rest/sitemaps/myhome/EG_Wohnzimmer @ HttpServiceContext{httpContext=DefaultHttpContext [bundle=org.eclipse.smarthome.ui_0.8.0.201601301304 [120], contextID=default]}
2016-02-21 12:40:52.296 [DEBUG] [.jetty.server.handler.ContextHandler] - scope null||/rest/sitemaps/myhome/EG_Wohnzimmer @ HttpServiceContext{httpContext=DefaultHttpContext [bundle=org.eclipse.smarthome.ui.icon_0.8.0.201601301304 [121], contextID=default]}
2016-02-21 12:40:52.301 [DEBUG] [.jetty.server.handler.ContextHandler] - scope null||/rest/sitemaps/myhome/EG_Wohnzimmer @ HttpServiceContext{httpContext=DefaultHttpContext [bundle=org.eclipse.smarthome.ui.basic_0.8.0.201601301304 [150], contextID=default]}
2016-02-21 12:40:52.317 [DEBUG] [.jetty.server.handler.ContextHandler] - scope null||/rest/sitemaps/myhome/EG_Wohnzimmer @ HttpServiceContext{httpContext=DefaultHttpContext [bundle=org.eclipse.smarthome.ui.classic_0.8.0.201601301304 [151], contextID=default]}
2016-02-21 12:40:52.317 [DEBUG] [.jetty.server.handler.ContextHandler] - scope null||/rest/sitemaps/myhome/EG_Wohnzimmer @ HttpServiceContext{httpContext=DefaultHttpContext [bundle=org.eclipse.smarthome.ui.paper_0.8.0.201601301304 [152], contextID=default]}
2016-02-21 12:40:52.325 [DEBUG] [.jetty.server.handler.ContextHandler] - scope null||/rest/sitemaps/myhome/EG_Wohnzimmer @ HttpServiceContext{httpContext=org.jupnp.transport.impl.osgi.DisableAuthenticationHttpContext@14267fb}
2016-02-21 12:40:52.330 [DEBUG] [.jetty.server.handler.ContextHandler] - scope null||/rest/sitemaps/myhome/EG_Wohnzimmer @ HttpServiceContext{httpContext=DefaultHttpContext [bundle=org.openhab.io.rest.docs_2.0.0.201601310204 [158], contextID=default]}
2016-02-21 12:40:52.331 [DEBUG] [.jetty.server.handler.ContextHandler] - scope null||/rest/sitemaps/myhome/EG_Wohnzimmer @ HttpServiceContext{httpContext=org.openhab.io.net.http.SecureHttpContext@1543b39}
2016-02-21 12:40:52.331 [DEBUG] [.jetty.server.handler.ContextHandler] - scope null||/rest/sitemaps/myhome/EG_Wohnzimmer @ HttpServiceContext{httpContext=DefaultHttpContext [bundle=org.openhab.ui.dashboard_2.0.0.201601310204 [163], contextID=default]}
2016-02-21 12:40:52.331 [DEBUG] [.jetty.server.handler.ContextHandler] - scope null||/rest/sitemaps/myhome/EG_Wohnzimmer @ HttpServiceContext{httpContext=DefaultHttpContext [bundle=org.openhab.core_2.0.0.201601310204 [131], contextID=default]}
2016-02-21 12:40:52.335 [DEBUG] [org.eclipse.jetty.server.Server     ] - RESPONSE /rest/sitemaps/myhome/EG_Wohnzimmer  200 handled=true
2016-02-21 12:40:52.343 [DEBUG] [clipse.jetty.server.HttpChannelState] - HttpChannelState@120dc65{s=DISPATCHED i=true a=null} unhandle DISPATCHED
2016-02-21 12:40:52.343 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - reset HttpParser{s=END,0 of -1}
2016-02-21 12:40:52.349 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - END --> START
2016-02-21 12:40:52.355 [DEBUG] [org.eclipse.jetty.server.HttpChannel] - HttpChannelOverHttp@fde055{r=9,c=false,a=IDLE,uri=-} handle exit, result COMPLETE
2016-02-21 12:40:52.375 [DEBUG] [org.eclipse.jetty.io.ChannelEndPoint] - filled 351 SelectChannelEndPoint@a73b31{/192.168.100.12:61206<->8080,Open,in,out,-,-,102/30000,HttpConnection}{io=0,kio=0,kro=1}
2016-02-21 12:40:52.377 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - parseNext s=START HeapByteBuffer@d5ca06[p=0,l=351,c=8192,r=351]={<<<GET /rest/sitemap...ip, deflate\r\n\r\n>>>\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00...\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00}
2016-02-21 12:40:52.386 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - START --> SPACE1
2016-02-21 12:40:52.391 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - SPACE1 --> URI
2016-02-21 12:40:52.398 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - URI --> SPACE2
2016-02-21 12:40:52.415 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - SPACE2 --> REQUEST_VERSION
2016-02-21 12:40:52.416 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - REQUEST_VERSION --> HEADER
2016-02-21 12:40:52.418 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - HEADER --> HEADER_IN_VALUE
2016-02-21 12:40:52.418 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - HEADER_IN_VALUE --> HEADER
2016-02-21 12:40:52.419 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - HEADER --> HEADER_IN_VALUE
2016-02-21 12:40:52.422 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - HEADER_IN_VALUE --> HEADER
2016-02-21 12:40:52.430 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - HEADER --> HEADER_IN_VALUE
2016-02-21 12:40:52.431 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - HEADER_IN_VALUE --> HEADER
2016-02-21 12:40:52.431 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - HEADER --> HEADER_IN_NAME
2016-02-21 12:40:52.440 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - HEADER_IN_NAME --> HEADER_VALUE
2016-02-21 12:40:52.440 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - HEADER_VALUE --> HEADER_IN_VALUE
2016-02-21 12:40:52.466 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - HEADER_IN_VALUE --> HEADER
2016-02-21 12:40:52.474 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - HEADER --> HEADER_IN_NAME
2016-02-21 12:40:52.478 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - HEADER_IN_NAME --> HEADER_VALUE
2016-02-21 12:40:52.479 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - HEADER_VALUE --> HEADER_IN_VALUE
2016-02-21 12:40:52.479 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - HEADER_IN_VALUE --> HEADER
2016-02-21 12:40:52.486 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - HEADER --> HEADER_IN_VALUE
2016-02-21 12:40:52.486 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - HEADER_IN_VALUE --> HEADER
2016-02-21 12:40:52.487 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - HEADER --> HEADER_IN_VALUE
2016-02-21 12:40:52.487 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - HEADER_IN_VALUE --> HEADER
2016-02-21 12:40:52.495 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - HEADER --> HEADER_IN_NAME
2016-02-21 12:40:52.495 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - HEADER_IN_NAME --> HEADER_VALUE
2016-02-21 12:40:52.496 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - HEADER_VALUE --> HEADER_IN_VALUE
2016-02-21 12:40:52.496 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - HEADER_IN_VALUE --> HEADER
2016-02-21 12:40:52.500 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - HEADER --> HEADER_IN_VALUE
2016-02-21 12:40:52.515 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - HEADER_IN_VALUE --> HEADER
2016-02-21 12:40:52.515 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - HEADER --> HEADER_IN_VALUE
2016-02-21 12:40:52.516 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - HEADER_IN_VALUE --> HEADER
2016-02-21 12:40:52.518 [DEBUG] [org.eclipse.jetty.http.HttpParser   ] - HEADER --> END
2016-02-21 12:40:52.518 [DEBUG] [org.eclipse.jetty.server.HttpChannel] - HttpChannelOverHttp@fde055{r=10,c=false,a=IDLE,uri=-} messageComplete
2016-02-21 12:40:52.522 [DEBUG] [org.eclipse.jetty.server.HttpInput  ] - HttpInputOverHTTP@8e3495 EOF
2016-02-21 12:40:52.523 [DEBUG] [org.eclipse.jetty.server.HttpChannel] - HttpChannelOverHttp@fde055{r=10,c=false,a=IDLE,uri=-} handle enter
2016-02-21 12:40:52.524 [DEBUG] [clipse.jetty.server.HttpChannelState] - HttpChannelState@120dc65{s=IDLE i=true a=null} handling IDLE
2016-02-21 12:40:52.533 [DEBUG] [org.eclipse.jetty.server.HttpChannel] - HttpChannelOverHttp@fde055{r=10,c=false,a=DISPATCHED,uri=/rest/sitemaps/myhome/EG_Wohnzimmer} action REQUEST_DISPATCH
2016-02-21 12:40:52.542 [DEBUG] [org.eclipse.jetty.server.Server     ] - REQUEST GET /rest/sitemaps/myhome/EG_Wohnzimmer on HttpChannelOverHttp@fde055{r=10,c=false,a=DISPATCHED,uri=/rest/sitemaps/myhome/EG_Wohnzimmer}
2016-02-21 12:40:52.551 [DEBUG] [ax.web.service.spi.model.ServerModel] - Matching [/rest/sitemaps/myhome/EG_Wohnzimmer]...
2016-02-21 12:40:52.552 [DEBUG] [ax.web.service.spi.model.ServerModel] - Path [/rest/sitemaps/myhome/EG_Wohnzimmer] matched to {pattern=/rest/.*,model=ServletModel{id=org.ops4j.pax.web.service.spi.model.ServletModel-2,name=org.ops4j.pax.web.service.spi.model.ServletModel-2,urlPatterns=[/rest/*],alias=/rest,servlet=com.eclipsesource.jaxrs.publisher.internal.ServletContainerBridge@161b036,initParams={},context=ContextModel{id=org.ops4j.pax.web.service.spi.model.ContextModel-1,name=,httpContext=DefaultHttpContext [bundle=com.eclipsesource.jaxrs.publisher_5.3.0.201512270850 [12], contextID=default],contextParams={},virtualHosts={},connectors={}}}}
2016-02-21 12:40:52.565 [DEBUG] [.jetty.server.handler.ContextHandler] - scope null||/rest/sitemaps/myhome/EG_Wohnzimmer @ HttpServiceContext{httpContext=DefaultHttpContext [bundle=com.eclipsesource.jaxrs.publisher_5.3.0.201512270850 [12], contextID=default]}
2016-02-21 12:40:52.566 [DEBUG] [.jetty.server.handler.ContextHandler] - context=||/rest/sitemaps/myhome/EG_Wohnzimmer @ HttpServiceContext{httpContext=DefaultHttpContext [bundle=com.eclipsesource.jaxrs.publisher_5.3.0.201512270850 [12], contextID=default]}
2016-02-21 12:40:52.568 [DEBUG] [org.eclipse.jetty.server.session    ] - sessionManager=org.ops4j.pax.web.service.jetty.internal.LateInvalidatingHashSessionManager@28468b
2016-02-21 12:40:52.568 [DEBUG] [org.eclipse.jetty.server.session    ] - session=null
2016-02-21 12:40:52.575 [DEBUG] [eclipse.jetty.servlet.ServletHandler] - servlet |/rest|/sitemaps/myhome/EG_Wohnzimmer -> org.ops4j.pax.web.service.spi.model.ServletModel-2@378be0fa==com.eclipsesource.jaxrs.publisher.internal.ServletContainerBridge,-1,true
2016-02-21 12:40:52.578 [DEBUG] [ce.jetty.internal.HttpServiceContext] - Handling request for [/rest/sitemaps/myhome/EG_Wohnzimmer] using http context [DefaultHttpContext [bundle=com.eclipsesource.jaxrs.publisher_5.3.0.201512270850 [12], contextID=default]]
2016-02-21 12:40:52.578 [DEBUG] [y.internal.HttpServiceServletHandler] - handling request org.ops4j.pax.web.service.jetty.internal.HttpServiceRequestWrapper@1684b42, org.ops4j.pax.web.service.jetty.internal.HttpServiceResponseWrapper@b79474
2016-02-21 12:40:52.587 [DEBUG] [eclipse.jetty.servlet.ServletHandler] - chain=null

Ich kann darin nichts entdecken was nach einem Fehler aussieht. :(

Re: executeCommandLine funktioniert nicht

Verfasst: 23. Feb 2016 19:47
von Cyrelian
Hi Chubv,

dein Log läuft für das exec Binding noch im INFO Modus.

Code: Alles auswählen

[INFO ] [lipse.smarthome.io.net.exec.ExecUtil] - executed commandLine 'curl -s -k -d token="TOKEN" -d user="USER" -d title="LIGHTS ON" -d message="openHAB pushover test" https://api.pushover.net/1/messages.json'
Bitte mach mal folgenden Eintrag in der "/etc/openhab/logback.xml"

Code: Alles auswählen

<logger name="org.openhab.binding.exec" level="TRACE" />
alternativ erstmal

Code: Alles auswählen

<logger name="org.openhab.binding.exec" level="DEBUG" />
Das sollte dann ungefähr so aussehen:

Code: Alles auswählen

<logger name="runtime.busevents" level="INFO" additivity="false">
		<appender-ref ref="EVENTFILE" />
		<appender-ref ref="STDOUT" />
	</logger>

	<logger name="org.openhab" level="INFO"/>
	<logger name="org.openhab.binding.homematic" level="INFO" />
	<logger name="org.openhab.ui.webapp" level="WARN" />
	<logger name="org.openhab.binding.weather" level="WARN" />
	<logger name="org.openhab.binding.exec" level="DEBUG" />
	<logger name="org.openhab.action.astro" level="WARN" />
	<logger name="org.openhab.binding.astro" level="WARN" />
	<logger name="org.openhab.persistence.rrd4j" level="INFO" />	

	<!-- this class gives an irrelevant warning at startup -->
CU
Cyrelian

Re: executeCommandLine funktioniert nicht

Verfasst: 23. Feb 2016 21:57
von seppy
Hi,
@Cyrelian: stimmt hab ich gar nicht gesehen, manchmal sieht man den Wald vor lauter Bäumen nicht .. :lol:

Also bei mir läuft die Erkennung darüber ganz ordentlich. Ich habe allerdings schon ein paar Fallstricke erkannt:
1) Meine Tochter deaktiviert dauernd ihr WLAN um "ONLINE Zeit" zu sparen :roll:
2) Wenn wir im Garten sind, habe ich keinen WLAN Empfang und bin nicht mehr zu Hause
3) Wenn wir zu lange schlafen ...

Also muss ich wahrscheinlich doch noch andere Erkennungsmerkmale kombinieren. Hat da jemand eine Idee?

Grüße,
Seppy

Re: executeCommandLine funktioniert nicht

Verfasst: 24. Feb 2016 06:36
von chubv
Ich werde das Logging mal ändern.
Mittlerweile geht's auch mit der Pushover Action. Leider aber auch nicht ohne Probleme.
Ich kann der Pushover Action nur eine gewisse Anzahl an Strings (Token, User, Text, Device) übergeben. Ab dem fünften String funktioniert's nicht mehr. :-(

Re: executeCommandLine funktioniert nicht

Verfasst: 24. Feb 2016 17:00
von Cyrelian
HI Seppy,

also für Punkt 3 deiner Liste hätte ich ne Lösung :D . Ne aber mal Spaß bei Seite. Tochter und Garten/WLAN hab ich verstanden..aber lange schlafen? Das würde bedeuten, dass das Phone doch irgendeine Art von Interaktion in einem gewissen Zeitraum braucht.
Ich konnte das bei mir nicht feststellen.
Beispiel:
Das iPhone meiner Frau wird meist nach 22:00 Uhr nicht mehr benutzt. Erst wieder gegen 6:00 Uhr. Das wären definitiv 8h ohne Benutzerinteraktion. In dieser Zeit ist es immer "anwesend".
Jetzt stellt sich natürlich die Frage, ob mir hier der E-Mail(Push) ein die Karten spielt. Die Phones/E-Mail Account sind nämlich alle auf "Push" und nicht auf "laden" eingestellt.
Wäre mal nen Versuch für Punkt 3 wert.

CU
Cyrelian

p.s.

Alle drei Punkte kannst Du mit IFTTT(Geo fenching) abdecken. Hierbei ist es egal wie lange das Handy aus ist. Solange es beim Eintritt und Austritt aus der gewählten Zone an ist.
HIer die beiden Recipes mit denen ich das gemacht hatte:
Turn Presence on when entering your home area
https://ifttt.com/recipes/292244-turn-p ... -home-area
Turn Presence off when leaving your home area
https://ifttt.com/recipes/292243-turn-p ... -home-area
Somit hast Du ne super Kombie aus WLAN und Geo Fenching.
Alle Zeiten sind UTC+02:00
Seite 1 von 1

Powered by phpBB® Forum Software © phpBB Limited

Deutsche Übersetzung durch phpBB.de