executeCommandLine funktioniert nicht

Einrichtung der openHAB Umgebung und allgemeine Konfigurationsthemen.

Moderatoren: seppy, udo1toni

Antworten
chubv
Beiträge: 18
Registriert: 11. Feb 2016 22:04

executeCommandLine funktioniert nicht

Beitrag 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!

Benutzeravatar
seppy
Beiträge: 738
Registriert: 24. Sep 2015 20:25
Answers: 4
Wohnort: Bonn

Re: executeCommandLine funktioniert nicht

Beitrag 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
Homematic und HomematicIP über Raspberrymatic (RaspPi 4 4GB) mit 2x HMLAN. Steuerung und Visualisierung durch OpenHAB2 auf RaspPi in Hutschienengehäuse im Sicherungskasten. Rund 100 Aktoren/Sensoren

- Abgesichert durch APC USV
- Bewässerungssteuerung mit Hunter Magnetventilen (HM-LC-Sw4-DR)
- Beleuchtungssteuerung Innen und Aussen (HM-LC-Sw4-DR + HM-LC-SW1-FM + HMW-IO-12-SW7-DR)
- Rolladensteuerung mit Beschattungsautomatik über Temperaturdifferenzsensor (HM-LC-Bl1PBU-FM)
- Wetter und Unwetterinformationen von wunderground
- Benachrichtigung der Bewohner via Pushover
- Multimediawand und Dreambox Steuerung (HM-LC-SW1-FM)
- Heizungssteuerung mit Komfort und Energiesparfunktionen (HM-CC-RT-DN + HM-Sec-SC-2 + HMIP-eTRV-2)
- Werkstatt Kompressorsteuerung (HMW-IO-12-SW7-DR)
- Weihnachtsbeleuchtung außen
- Präsenzerkennung über Geolocation (iCloud Binding), iBeacon und WLAN (Unifi Binding)
- Philips HUE & Tasmota Devices (Tuya) Einbindung

chubv
Beiträge: 18
Registriert: 11. Feb 2016 22:04

Re: executeCommandLine funktioniert nicht

Beitrag von chubv »

Hab's auch schon mit root probiert.
Aus der command line funktioniert der Befehl auch mit einem normalen User.

Benutzeravatar
seppy
Beiträge: 738
Registriert: 24. Sep 2015 20:25
Answers: 4
Wohnort: Bonn

Re: executeCommandLine funktioniert nicht

Beitrag von seppy »

Setz mal den Debug Mode und poste den Auszug aus dem Logfile


openhabforum.de
Homematic und HomematicIP über Raspberrymatic (RaspPi 4 4GB) mit 2x HMLAN. Steuerung und Visualisierung durch OpenHAB2 auf RaspPi in Hutschienengehäuse im Sicherungskasten. Rund 100 Aktoren/Sensoren

- Abgesichert durch APC USV
- Bewässerungssteuerung mit Hunter Magnetventilen (HM-LC-Sw4-DR)
- Beleuchtungssteuerung Innen und Aussen (HM-LC-Sw4-DR + HM-LC-SW1-FM + HMW-IO-12-SW7-DR)
- Rolladensteuerung mit Beschattungsautomatik über Temperaturdifferenzsensor (HM-LC-Bl1PBU-FM)
- Wetter und Unwetterinformationen von wunderground
- Benachrichtigung der Bewohner via Pushover
- Multimediawand und Dreambox Steuerung (HM-LC-SW1-FM)
- Heizungssteuerung mit Komfort und Energiesparfunktionen (HM-CC-RT-DN + HM-Sec-SC-2 + HMIP-eTRV-2)
- Werkstatt Kompressorsteuerung (HMW-IO-12-SW7-DR)
- Weihnachtsbeleuchtung außen
- Präsenzerkennung über Geolocation (iCloud Binding), iBeacon und WLAN (Unifi Binding)
- Philips HUE & Tasmota Devices (Tuya) Einbindung

chubv
Beiträge: 18
Registriert: 11. Feb 2016 22:04

Re: executeCommandLine funktioniert nicht

Beitrag von chubv »

Mach ich.

chubv
Beiträge: 18
Registriert: 11. Feb 2016 22:04

Re: executeCommandLine funktioniert nicht

Beitrag 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. :(

Benutzeravatar
Cyrelian
Beiträge: 601
Registriert: 24. Sep 2015 17:55
Answers: 4

Re: executeCommandLine funktioniert nicht

Beitrag 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

Benutzeravatar
seppy
Beiträge: 738
Registriert: 24. Sep 2015 20:25
Answers: 4
Wohnort: Bonn

Re: executeCommandLine funktioniert nicht

Beitrag 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
Homematic und HomematicIP über Raspberrymatic (RaspPi 4 4GB) mit 2x HMLAN. Steuerung und Visualisierung durch OpenHAB2 auf RaspPi in Hutschienengehäuse im Sicherungskasten. Rund 100 Aktoren/Sensoren

- Abgesichert durch APC USV
- Bewässerungssteuerung mit Hunter Magnetventilen (HM-LC-Sw4-DR)
- Beleuchtungssteuerung Innen und Aussen (HM-LC-Sw4-DR + HM-LC-SW1-FM + HMW-IO-12-SW7-DR)
- Rolladensteuerung mit Beschattungsautomatik über Temperaturdifferenzsensor (HM-LC-Bl1PBU-FM)
- Wetter und Unwetterinformationen von wunderground
- Benachrichtigung der Bewohner via Pushover
- Multimediawand und Dreambox Steuerung (HM-LC-SW1-FM)
- Heizungssteuerung mit Komfort und Energiesparfunktionen (HM-CC-RT-DN + HM-Sec-SC-2 + HMIP-eTRV-2)
- Werkstatt Kompressorsteuerung (HMW-IO-12-SW7-DR)
- Weihnachtsbeleuchtung außen
- Präsenzerkennung über Geolocation (iCloud Binding), iBeacon und WLAN (Unifi Binding)
- Philips HUE & Tasmota Devices (Tuya) Einbindung

chubv
Beiträge: 18
Registriert: 11. Feb 2016 22:04

Re: executeCommandLine funktioniert nicht

Beitrag 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. :-(

Benutzeravatar
Cyrelian
Beiträge: 601
Registriert: 24. Sep 2015 17:55
Answers: 4

Re: executeCommandLine funktioniert nicht

Beitrag 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.

Antworten