dani6292
September 20, 2016, 9:25am
1
Hello, I’m using the mochad x10 binding and I don’t know why it doesn’t work. I have changed the configuration adding the next information in my configuration.cfg file:
mochadx10:hostIp=192.168.1.30
mochadx10:hostPort=1099
I have a doubt, I’m working with the CM15a and with an appliance module (UM720E) who ring when I turn on. Could I use the switch sintax to conect my appliance module?
I have this in my item file:
Switch Alarma_casa "Alarma" (GF_Dani) {mochadx10="a1"}
I have added the addon org.openhab.binding.mochadx10-1.8.3.jar in the addon folder. But I don’t know if the problem may be in the Cm15a. I have been testing in windows and it work ok but I can not find any program to check on Linux. In Windows I use ActiveHome pro.
Can anyone help me?
Hey, have you checked your log files? Is the addon correctly installed? it should tell you a bit more about what’s actually going on behind the sheets…
dani6292
September 20, 2016, 11:33am
3
Hello Tom,
First of all, thank you for your answer.
Second, I get this message in my console while the demo was running.
12:53:38.165 [INFO ] [runtime.busevents :22 ] - Alarma_casa received command ON
12:53:42.092 [INFO ] [runtime.busevents :22 ] - Alarma_casa received command OFF
12:53:43.914 [INFO ] [runtime.busevents :22 ] - Alarma_casa received command ON
12:53:47.225 [INFO ] [runtime.busevents :22 ] - Alarma_casa received command OFF
12:53:48.858 [INFO ] [runtime.busevents :22 ] - Alarma_casa received command ON
And in my log file I see this other message:
127.0.0.1 - - [20/sep/2016:10:53:35 +0000] "GET /openhab.app?sitemap=demo&sitemap=demo&w=GF_Dani&poll=true&__async=true&__source=waGF_Dani HTTP/1.1" 200 660
127.0.0.1 - - [20/sep/2016:10:53:34 +0000] "GET /openhab.app?sitemap=demo&sitemap=demo&w=0004&poll=true&__async=true&__source=wa0004 HTTP/1.1" 200 547
127.0.0.1 - - [20/sep/2016:10:53:38 +0000] "GET /images/switch-on.png HTTP/1.1" 304 0
127.0.0.1 - - [20/sep/2016:10:53:34 +0000] "GET /openhab.app?sitemap=demo&poll=true&__async=true&__source=wa0004 HTTP/1.1" 200 2051
127.0.0.1 - - [20/sep/2016:10:53:42 +0000] "GET /CMD?Alarma_casa=TOGGLE&__async=true&__source=waGF_Dani HTTP/1.1" 200 13
127.0.0.1 - - [20/sep/2016:10:53:38 +0000] "GET /openhab.app?sitemap=demo&sitemap=demo&w=GF_Dani&poll=true&__async=true&__source=waGF_Dani HTTP/1.1" 200 649
127.0.0.1 - - [20/sep/2016:10:53:42 +0000] "GET /images/switch-off.png HTTP/1.1" 304 0
127.0.0.1 - - [20/sep/2016:10:53:43 +0000] "GET /CMD?Alarma_casa=TOGGLE&__async=true&__source=waGF_Dani HTTP/1.1" 200 13
127.0.0.1 - - [20/sep/2016:10:53:42 +0000] "GET /openhab.app?sitemap=demo&sitemap=demo&w=GF_Dani&poll=true&__async=true&__source=waGF_Dani HTTP/1.1" 200 660
127.0.0.1 - - [20/sep/2016:10:53:47 +0000] "GET /CMD?Alarma_casa=TOGGLE&__async=true&__source=waGF_Dani HTTP/1.1" 200 13
127.0.0.1 - - [20/sep/2016:10:53:44 +0000] "GET /openhab.app?sitemap=demo&sitemap=demo&w=GF_Dani&poll=true&__async=true&__source=waGF_Dani HTTP/1.1" 200 649
127.0.0.1 - - [20/sep/2016:10:53:48 +0000] "GET /CMD?Alarma_casa=TOGGLE&__async=true&__source=waGF_Dani HTTP/1.1" 200 13
127.0.0.1 - - [20/sep/2016:10:53:47 +0000] "GET /openhab.app?sitemap=demo&sitemap=demo&w=GF_Dani&poll=true&__async=true&__source=waGF_Dani HTTP/1.1" 200 660
127.0.0.1 - - [20/sep/2016:10:53:49 +0000] "GET /openhab.app?sitemap=demo&sitemap=demo&w=GF_Dani&poll=true&__async=true&__source=waGF_Dani HTTP/1.1" 200 92
I have checked and I have the org.openhab.binding.mochadx10-1.8.3.jar in my all_addons folder like i saw in the demo setup. I’m sure that the CM15a is working because I have just checked it in other laptop using ActiveHome and it’s working.
Than you.
I have trouble getting your point. The switches between OFF and ON indicate that the openHAB integration is in fact working. Can you clarify what it is you are looking for? Btw why are you mentioning linux all the time. You are talking about openHAB no? Your problem is not really related to you using Linux…
dani6292
September 21, 2016, 8:24am
5
I’m using openhab is correct but I have a problem with mochad X10 binding. I have set the mochad binding and It doesn’t work.I turn ON/OFF the switch in the UI and it doesn’t work. Then I have to know where is the problem. I’m using SO ubuntu and I suppose that may be the CM15a had a problem. Then I installed ActiveHome in other computer (SO windows) and it worked well. So, the point is: Is there any program to test the CM15a in ubuntu? or Do you think the error may be in other point?
This is the item “Alarma” which is in my .items file:
Switch Alarma_casa “Alarma” (GF_Dani) {mochadx10=“a1”}
Of course I’m sure my module has the address a1 also.
Thanks for your help!!
Did you look into the openhab.log as I suggested? You should see infos by bindings there, especially as there seems to be a problem. You may post the relevant parts here, maaybe we can help from there.
dani6292
September 22, 2016, 11:02am
8
Tom this is the content that my openhab.log which is in openhab/logs/openhab.log :
12:48:54.919 [DEBUG] [.s.internal.SchedulerActivator:36 ] - Scheduler has been started.
12:48:54.956 [INFO ] [uartz.impl.StdSchedulerFactory:1175 ] - Using default implementation for ThreadExecutor
12:48:54.973 [INFO ] [rtz.core.SchedulerSignalerImpl:61 ] - Initialized Scheduler Signaller of type: class org.quartz.core.SchedulerSignalerImpl
12:48:54.974 [INFO ] [rg.quartz.core.QuartzScheduler:243 ] - Quartz Scheduler v.2.1.7 created.
12:48:54.975 [INFO ] [org.quartz.simpl.RAMJobStore :154 ] - RAMJobStore initialized.
12:48:54.976 [INFO ] [rg.quartz.core.QuartzScheduler:268 ] - Scheduler meta-data: Quartz Scheduler (v2.1.7) 'openHAB-job-scheduler' with instanceId 'NON_CLUSTERED'
Scheduler class: 'org.quartz.core.QuartzScheduler' - running locally.
NOT STARTED.
Currently in standby mode.
Number of jobs executed: 0
Using thread pool 'org.quartz.simpl.SimpleThreadPool' - with 2 threads.
Using job-store 'org.quartz.simpl.RAMJobStore' - which does not support persistence. and is not clustered.
12:48:54.976 [INFO ] [uartz.impl.StdSchedulerFactory:1324 ] - Quartz scheduler 'openHAB-job-scheduler' initialized from specified file: './etc/quartz.properties'
12:48:54.976 [INFO ] [uartz.impl.StdSchedulerFactory:1328 ] - Quartz scheduler version: 2.1.7
12:48:54.977 [INFO ] [rg.quartz.core.QuartzScheduler:534 ] - Scheduler openHAB-job-scheduler_$_NON_CLUSTERED started.
12:48:54.980 [DEBUG] [o.config.core.ConfigDispatcher:146 ] - Processing openHAB default configuration file '/home/dani/Escritorio/openhab_demo/configurations/openhab_default.cfg'.
12:48:54.980 [DEBUG] [o.config.core.ConfigDispatcher:168 ] - Processing openHAB main configuration file '/home/dani/Escritorio/openhab_demo/configurations/openhab.cfg'.
12:48:55.356 [DEBUG] [.o.core.internal.CoreActivator:93 ] - UUID file already exists at '/home/dani/Escritorio/openhab_demo/webapps/static/uuid' with content 'd2aea787-9148-47d1-95c1-13167dd61edc'
12:48:55.358 [DEBUG] [.o.core.internal.CoreActivator:115 ] - Created file '/home/dani/Escritorio/openhab_demo/webapps/static/version' with content '1.8.3'
12:48:55.359 [INFO ] [.o.core.internal.CoreActivator:61 ] - openHAB runtime has been started (v1.8.3).
12:48:55.389 [DEBUG] [a.internal.AutoUpdateActivator:31 ] - AutoUpdate binding has been started.
12:48:56.246 [DEBUG] [.p.i.PersistenceModelActivator:23 ] - Registered 'persistence' configuration parser
12:48:56.263 [DEBUG] [.c.t.i.TransformationActivator:38 ] - Transformation Service has been started.
12:48:56.309 [DEBUG] [m.internal.MultimediaActivator:34 ] - Multimedia I/O bundle has been started.
12:48:56.334 [DEBUG] [.s.i.DiscoveryServiceActivator:27 ] - Discovery service has been started.
12:48:56.344 [INFO ] [o.o.i.s.i.DiscoveryServiceImpl:93 ] - mDNS service has been started
12:48:56.366 [DEBUG] [.io.transport.mqtt.MqttService:123 ] - Starting MQTT Service...
12:48:56.499 [DEBUG] [.i.internal.ItemModelActivator:24 ] - Registered 'item' configuration parser
12:48:56.502 [INFO ] [o.o.i.s.i.DiscoveryServiceImpl:53 ] - Service Discovery initialization completed.
12:48:56.595 [DEBUG] [o.o.c.i.items.ItemRegistryImpl:137 ] - Item provider 'GenericItemProvider' has been added.
12:48:57.385 [DEBUG] [.o.m.s.i.SitemapModelActivator:23 ] - Registered 'sitemap' configuration parser
12:48:57.452 [DEBUG] [io.rest.internal.RESTActivator:33 ] - REST API has been started.
12:48:57.522 [INFO ] [org.atmosphere.util.IOUtils :370 ] - META-INF/services/org.atmosphere.cpr.AtmosphereFramework not found in class loader
12:48:57.573 [INFO ] [sphere.cpr.AtmosphereFramework:2601 ] - Atmosphere is using org.atmosphere.cpr.DefaultAnnotationProcessor for processing annotation
12:48:57.575 [INFO ] [cpr.DefaultAnnotationProcessor:138 ] - AnnotationProcessor class org.atmosphere.cpr.DefaultAnnotationProcessor$BytecodeBasedAnnotationProcessor being used
12:48:57.592 [WARN ] [cpr.DefaultAnnotationProcessor:178 ] - Unable to detect annotations. Application may fail to deploy.
12:48:57.619 [INFO ] [sphere.cpr.AtmosphereFramework:1891 ] - Auto detecting atmosphere handlers /WEB-INF/classes/
12:48:57.641 [INFO ] [sphere.cpr.AtmosphereFramework:639 ] - Installed AtmosphereHandler org.atmosphere.handler.ReflectorServletProcessor mapped to context-path /* and Broadcaster Class org.atmosphere.jersey.JerseyBroadcaster
12:48:57.642 [INFO ] [sphere.cpr.AtmosphereFramework:1945 ] - Auto detecting WebSocketHandler in /WEB-INF/classes/
12:48:57.643 [INFO ] [sphere.cpr.AtmosphereFramework:1602 ] - Installed WebSocketProtocol org.atmosphere.websocket.protocol.SimpleHttpProtocol
12:48:57.681 [INFO ] [.a.h.ReflectorServletProcessor:103 ] - Installing Servlet com.sun.jersey.spi.container.servlet.ServletContainer
12:48:57.823 [INFO ] [c.s.j.s.i.a.WebApplicationImpl:815 ] - Initiating Jersey application, version 'Jersey: 1.18.1 02/19/2014 03:28 AM'
12:48:57.826 [INFO ] [c.s.j.s.i.a.WebApplicationImpl:826 ] - Adding the following classes declared in META-INF/services/jersey-server-components to the resource configuration:
class org.atmosphere.jersey.AtmosphereResourceConfigurator
12:48:57.897 [INFO ] [j.s.i.a.DeferredResourceConfig:101 ] - Instantiated the Application class org.openhab.io.rest.RESTApplication
12:48:58.799 [INFO ] [sphere.cpr.AtmosphereFramework:2435 ] - AtmosphereInterceptor CORS Interceptor Support will always be executed first
12:48:58.799 [INFO ] [sphere.cpr.AtmosphereFramework:2404 ] - Installed AtmosphereInterceptor CORS Interceptor Support with priority FIRST_BEFORE_DEFAULT
12:48:58.800 [INFO ] [sphere.cpr.AtmosphereFramework:2404 ] - Installed AtmosphereInterceptor Default Response's Headers Interceptor with priority AFTER_DEFAULT
12:48:58.801 [INFO ] [sphere.cpr.AtmosphereFramework:2404 ] - Installed AtmosphereInterceptor Android Interceptor Support with priority AFTER_DEFAULT
12:48:58.802 [INFO ] [sphere.cpr.AtmosphereFramework:2404 ] - Installed AtmosphereInterceptor SSE Interceptor Support with priority AFTER_DEFAULT
12:48:58.803 [INFO ] [sphere.cpr.AtmosphereFramework:2404 ] - Installed AtmosphereInterceptor JSONP Interceptor Support with priority AFTER_DEFAULT
12:48:58.805 [INFO ] [sphere.cpr.AtmosphereFramework:2404 ] - Installed AtmosphereInterceptor Atmosphere JavaScript Protocol with priority AFTER_DEFAULT
12:48:58.806 [INFO ] [sphere.cpr.AtmosphereFramework:2404 ] - Installed AtmosphereInterceptor Browser disconnection detection with priority AFTER_DEFAULT
12:48:58.810 [INFO ] [sphere.cpr.AtmosphereFramework:935 ] - Using EndpointMapper class org.atmosphere.util.DefaultEndpointMapper
12:48:58.812 [WARN ] [sphere.cpr.AtmosphereFramework:941 ] - No BroadcasterCache configured. Broadcasted message between client reconnection will be LOST. It is recommended to configure the org.atmosphere.cache.UUIDBroadcasterCache
12:48:58.812 [INFO ] [sphere.cpr.AtmosphereFramework:949 ] - Default Broadcaster Class: org.atmosphere.jersey.JerseyBroadcaster
12:48:58.813 [INFO ] [sphere.cpr.AtmosphereFramework:950 ] - Broadcaster Polling Wait Time 100
12:48:58.813 [INFO ] [sphere.cpr.AtmosphereFramework:951 ] - Shared ExecutorService supported: true
12:48:58.813 [INFO ] [sphere.cpr.AtmosphereFramework:958 ] - Messaging Thread Pool Size: 10
12:48:58.814 [INFO ] [sphere.cpr.AtmosphereFramework:968 ] - Async I/O Thread Pool Size: 10
12:48:58.814 [INFO ] [sphere.cpr.AtmosphereFramework:979 ] - Using BroadcasterFactory: org.atmosphere.cpr.DefaultBroadcasterFactory
12:48:58.815 [INFO ] [sphere.cpr.AtmosphereFramework:980 ] - Using WebSocketProcessor: org.atmosphere.websocket.DefaultWebSocketProcessor
12:48:58.815 [INFO ] [sphere.cpr.AtmosphereFramework:990 ] - Invoke AtmosphereInterceptor on WebSocket message true
12:48:58.815 [INFO ] [sphere.cpr.AtmosphereFramework:991 ] - HttpSession supported: false
12:48:58.816 [INFO ] [sphere.cpr.AtmosphereFramework:993 ] - Atmosphere is using DefaultAtmosphereObjectFactory for dependency injection and object creation
12:48:58.816 [INFO ] [sphere.cpr.AtmosphereFramework:994 ] - Atmosphere is using async support: org.atmosphere.container.JettyAsyncSupportWithWebSocket running under container: jetty/8.1.3.v20120522 with WebSocket enabled.
12:48:58.818 [INFO ] [sphere.cpr.AtmosphereFramework:996 ] - Atmosphere Framework 2.2.5 started.
12:48:58.818 [INFO ] [sphere.cpr.AtmosphereFramework:998 ] -
For Atmosphere Framework Commercial Support, visit
http://www.async-io.org/ or send an email to support@async-io.org
12:48:58.819 [INFO ] [penhab.io.rest.RESTApplication:144 ] - Started REST API at /rest
12:48:58.821 [DEBUG] [o.o.i.s.i.DiscoveryServiceImpl:66 ] - Registering new service _openhab-server._tcp.local. at port 8080
12:49:04.075 [DEBUG] [o.o.i.s.i.DiscoveryServiceImpl:66 ] - Registering new service _openhab-server-ssl._tcp.local. at port 8443
12:49:06.054 [INFO ] [c.internal.ModelRepositoryImpl:80 ] - Loading model 'demo.sitemap'
12:49:06.302 [INFO ] [c.internal.ModelRepositoryImpl:80 ] - Loading model 'logging.persist'
12:49:06.331 [INFO ] [c.internal.ModelRepositoryImpl:80 ] - Loading model 'db4o.persist'
12:49:06.340 [INFO ] [c.internal.ModelRepositoryImpl:80 ] - Loading model 'rrd4j.persist'
12:49:06.345 [INFO ] [c.internal.ModelRepositoryImpl:80 ] - Loading model 'exec.persist'
12:49:06.405 [INFO ] [c.internal.ModelRepositoryImpl:80 ] - Loading model 'demo.script'
12:49:06.500 [INFO ] [c.internal.ModelRepositoryImpl:80 ] - Loading model 'demo.items'
12:49:06.547 [DEBUG] [i.internal.GenericItemProvider:154 ] - Processing binding configs for items from model 'demo.items'
12:49:06.557 [DEBUG] [i.internal.GenericItemProvider:133 ] - Read items from model 'demo.items'
12:49:06.633 [INFO ] [.o.u.w.i.servlet.WebAppServlet:80 ] - Started Classic UI at /classicui/openhab.app
12:49:06.858 [DEBUG] [.r.internal.RuleModelActivator:42 ] - Registered 'rules' configuration parser
12:49:06.869 [DEBUG] [m.r.internal.engine.RuleEngine:77 ] - Started rule engine
12:49:09.717 [DEBUG] [.b.http.internal.HttpActivator:34 ] - HTTP binding has been started.
12:49:09.741 [DEBUG] [i.internal.GenericItemProvider:341 ] - Start processing binding configuration of Item 'Weather_Temperature (Type=NumberItem, State=Uninitialized)' with 'HttpGenericBindingProvider' reader.
12:49:09.753 [INFO ] [.service.AbstractActiveService:169 ] - HTTP Refresh Service has been started
12:49:09.755 [DEBUG] [.o.b.http.internal.HttpBinding:163 ] - item 'Weather_Temperature' is about to be refreshed now
12:49:09.773 [DEBUG] [.rrd4j.internal.RRD4jActivator:31 ] - RRD4j persistence bundle has been started.
12:49:09.822 [DEBUG] [.p.rrd4j.internal.RRD4jService:395 ] - Unknown property name : org.openhab.persistence.rrd4j
12:49:09.823 [DEBUG] [.p.rrd4j.internal.RRD4jService:364 ] - config 'objectClass' should have the format 'name.configkey'
12:49:09.824 [INFO ] [.p.rrd4j.internal.RRD4jService:407 ] - Removing invalid defintion component = null heartbeat = 0 min/max = 0.0/0.0 step = 0 0 archives(s) = [] 0 items(s) = []
12:49:09.825 [DEBUG] [.p.rrd4j.internal.RRD4jService:405 ] - Created default_other = GAUGE heartbeat = 3600 min/max = NaN/NaN step = 1 6 archives(s) = [ MAX xff = 0.999 steps = 1 rows = 3600 MAX xff = 0.999 steps = 10 rows = 1440 MAX xff = 0.999 steps = 60 rows = 1440 MAX xff = 0.999 steps = 900 rows = 2880 MAX xff = 0.999 steps = 21600 rows = 1460 MAX xff = 0.999 steps = 86400 rows = 3650] 0 items(s) = []
12:49:09.825 [DEBUG] [.p.rrd4j.internal.RRD4jService:405 ] - Created default_numeric = GAUGE heartbeat = 60 min/max = NaN/NaN step = 60 6 archives(s) = [ AVERAGE xff = 0.5 steps = 1 rows = 480 AVERAGE xff = 0.5 steps = 4 rows = 360 AVERAGE xff = 0.5 steps = 14 rows = 644 AVERAGE xff = 0.5 steps = 60 rows = 720 AVERAGE xff = 0.5 steps = 720 rows = 730 AVERAGE xff = 0.5 steps = 10080 rows = 520] 0 items(s) = []
12:49:09.827 [DEBUG] [.p.internal.PersistenceManager:146 ] - Initializing rrd4j persistence service.
12:49:09.873 [DEBUG] [.p.internal.PersistenceManager:315 ] - Item 'NoOfLights' does not exist.
12:49:09.919 [DEBUG] [.p.internal.PersistenceManager:377 ] - Restored item state from '22-sep-2016 12:43:00' for item 'Heating_FF_Bed' -> 'OFF'
12:49:09.932 [DEBUG] [.p.internal.PersistenceManager:377 ] - Restored item state from '22-sep-2016 12:43:00' for item 'Heating_GF_Corridor' -> 'OFF'
12:49:09.942 [DEBUG] [.p.internal.PersistenceManager:377 ] - Restored item state from '22-sep-2016 12:43:00' for item 'Heating_FF_Child' -> 'OFF'
12:49:09.952 [DEBUG] [.p.internal.PersistenceManager:377 ] - Restored item state from '22-sep-2016 12:43:00' for item 'Heating_FF_Office' -> 'OFF'
12:49:09.955 [DEBUG] [.p.internal.PersistenceManager:377 ] - Restored item state from '22-sep-2016 12:43:00' for item 'DemoSwitch' -> 'OFF'
12:49:09.957 [DEBUG] [.p.internal.PersistenceManager:377 ] - Restored item state from '22-sep-2016 12:43:00' for item 'Heating_GF_Toilet' -> 'OFF'
12:49:09.959 [DEBUG] [.p.internal.PersistenceManager:377 ] - Restored item state from '22-sep-2016 12:43:00' for item 'Heating_GF_Living' -> 'OFF'
12:49:09.962 [DEBUG] [.p.internal.PersistenceManager:377 ] - Restored item state from '22-sep-2016 12:43:00' for item 'Heating_FF_Bath' -> 'OFF'
12:49:09.964 [DEBUG] [.p.internal.PersistenceManager:377 ] - Restored item state from '22-sep-2016 12:43:00' for item 'Heating_GF_Kitchen' -> 'ON'
12:49:09.966 [DEBUG] [.p.internal.PersistenceManager:377 ] - Restored item state from '22-sep-2016 12:43:00' for item 'Window_GF_Toilet' -> 'OPEN'
12:49:09.970 [DEBUG] [.p.internal.PersistenceManager:377 ] - Restored item state from '22-sep-2016 12:43:00' for item 'Temperature_GF_Living' -> '17.89999999999999857891452847979962825775146484375'
12:49:09.972 [DEBUG] [.p.internal.PersistenceManager:377 ] - Restored item state from '22-sep-2016 12:43:00' for item 'Temperature_GF_Toilet' -> '21.39999999999999857891452847979962825775146484375'
12:49:09.974 [DEBUG] [.p.internal.PersistenceManager:377 ] - Restored item state from '22-sep-2016 12:43:00' for item 'Temperature_FF_Bed' -> '20.5'
12:49:09.976 [DEBUG] [.p.internal.PersistenceManager:377 ] - Restored item state from '22-sep-2016 12:43:00' for item 'Weather_Temperature' -> '21'
12:49:09.979 [DEBUG] [.p.internal.PersistenceManager:377 ] - Restored item state from '22-sep-2016 12:43:00' for item 'Temperature_FF_Child' -> '21.800000000000000710542735760100185871124267578125'
12:49:09.987 [DEBUG] [.p.internal.PersistenceManager:377 ] - Restored item state from '22-sep-2016 12:43:00' for item 'Temperature_FF_Office' -> '21.699999999999999289457264239899814128875732421875'
12:49:09.989 [DEBUG] [.p.internal.PersistenceManager:377 ] - Restored item state from '22-sep-2016 12:43:00' for item 'Temperature_GF_Corridor' -> '22.10000000000000142108547152020037174224853515625'
12:49:09.991 [DEBUG] [.p.internal.PersistenceManager:377 ] - Restored item state from '22-sep-2016 12:43:00' for item 'Temperature_GF_Kitchen' -> '18.10000000000000142108547152020037174224853515625'
12:49:09.993 [DEBUG] [.p.internal.PersistenceManager:377 ] - Restored item state from '22-sep-2016 12:43:00' for item 'Temperature_FF_Bath' -> '18.89999999999999857891452847979962825775146484375'
12:49:09.995 [DEBUG] [.p.internal.PersistenceManager:377 ] - Restored item state from '22-sep-2016 12:43:00' for item 'Weather_Temp_Max' -> '22'
12:49:09.997 [DEBUG] [.p.internal.PersistenceManager:377 ] - Restored item state from '22-sep-2016 12:43:00' for item 'Weather_Temp_Min' -> '21'
12:49:10.019 [DEBUG] [.p.internal.PersistenceManager:428 ] - Scheduled strategy rrd4j.everyMinute with cron expression 0 * * * * ?
12:49:10.025 [DEBUG] [p.r.i.charts.RRD4jChartServlet:118 ] - Starting up rrd chart servlet at /rrdchart.png
12:49:10.027 [DEBUG] [xecPersistenceServiceActivator:33 ] - Exec persistence bundle has been started.
12:49:10.039 [DEBUG] [.p.internal.PersistenceManager:146 ] - Initializing exec persistence service.
12:49:10.041 [DEBUG] [.p.internal.PersistenceManager:428 ] - Scheduled strategy exec.everyMinute with cron expression 0 * * * * ?
12:49:10.044 [DEBUG] [.i.LoggingPersistenceActivator:31 ] - Logging persistence bundle has been started.
12:49:10.056 [DEBUG] [.p.internal.PersistenceManager:146 ] - Initializing logging persistence service.
12:49:10.060 [DEBUG] [.o.b.ntp.internal.NtpActivator:31 ] - NTP binding has been started.
12:49:10.068 [DEBUG] [i.internal.GenericItemProvider:341 ] - Start processing binding configuration of Item 'Date (Type=DateTimeItem, State=Uninitialized)' with 'NtpGenericBindingProvider' reader.
12:49:10.078 [INFO ] [.service.AbstractActiveService:169 ] - NTP Refresh Service has been started
12:49:10.263 [DEBUG] [inding.ntp.internal.NtpBinding:84 ] - Got time from ptbtime1.ptb.de: jueves 22 de septiembre de 2016 12H49' CEST
12:49:10.847 [DEBUG] [.i.s.XsltTransformationService:74 ] - about to transform '<?xml version="1.0" encoding="UTF-8"?>
<BR />
<b>Current Conditions:</b>
<BR />Mostly Cloudy
<BR />
<BR />
<b>Forecast:</b>
<BR /> Thu - Partly Cloudy. High: 21Low: 19
<BR /> Fri - Sunny. High: 22Low: 17
<BR /> Sat - Sunny. High: 25Low: 18
<BR /> Sun - Partly Cloudy. High: 24Low: 18
<BR /> Mon - Partly Cloudy. High: 26Low: 19
<BR />
<BR />
<a href="http://us.rd.yahoo.com/dailynews/rss/weather/Country__Country/*https://weather.yahoo.com/country/state/city-772213/">Full Forecast at Yahoo! Weather</a>
<BR />
<BR />
(provided by <a href="http://www.weather.com" >The Weather Channel</a>)
<BR />
]]></description><guid isPermaLink="false"/></item></channel></results></query><!-- total: 8 -->
<!-- prod_bf1_1;paas.yql;queryyahooapiscomproductionbf1;d405fa7d-75e9-11e6-a2c9-d4ae52974741 -->
' by the function 'javax.xml.transform.stream.StreamSource@65f338c'
12:49:11.024 [DEBUG] [.i.s.XsltTransformationService:89 ] - transformation resulted in '21'
12:49:11.025 [DEBUG] [.o.b.http.internal.HttpBinding:197 ] - transformed response is '21'
12:49:16.633 [INFO ] [c.internal.ModelRepositoryImpl:80 ] - Loading model 'demo.rules'
12:49:16.856 [DEBUG] [.r.i.engine.RuleTriggerManager:438 ] - Scheduled rule Set random room temperatures with cron expression 0 0/5 * * * ?
12:49:16.858 [DEBUG] [.r.i.engine.RuleTriggerManager:438 ] - Scheduled rule Update max and min temperatures with cron expression 0 0 0 * * ?
12:49:16.860 [DEBUG] [.r.i.engine.RuleTriggerManager:438 ] - Scheduled rule Persistence Demo with cron expression 0 * * * * ?
12:49:16.864 [DEBUG] [m.r.internal.engine.RuleEngine:264 ] - Executing startup rule 'Initialize light states'
12:49:17.138 [DEBUG] [riptExtensionClassNameProvider:63 ] - Script actions have changed: TransformationActionService, AudioActionService, ExecActionService, HTTPActionService, PingActionService,
12:49:17.695 [ERROR] [m.r.internal.engine.RuleEngine:278 ] - Error during the execution of startup rule 'Initialize light states': Could not invoke method: org.openhab.model.script.actions.BusEvent.postUpdate(org.openhab.core.items.Item,java.lang.Number) on instance: null
12:49:17.696 [DEBUG] [m.r.internal.engine.RuleEngine:264 ] - Executing startup rule 'Initialize heating states'
12:49:17.847 [ERROR] [m.r.internal.engine.RuleEngine:278 ] - Error during the execution of startup rule 'Initialize heating states': Could not invoke method: org.openhab.model.script.actions.BusEvent.postUpdate(org.openhab.core.items.Item,java.lang.Number) on instance: null
12:49:17.848 [DEBUG] [m.r.internal.engine.RuleEngine:264 ] - Executing startup rule 'Initialize contact states'
12:49:17.977 [ERROR] [m.r.internal.engine.RuleEngine:278 ] - Error during the execution of startup rule 'Initialize contact states': Could not invoke method: org.openhab.model.script.actions.BusEvent.postUpdate(org.openhab.core.items.Item,java.lang.Number) on instance: null
12:49:17.977 [DEBUG] [m.r.internal.engine.RuleEngine:264 ] - Executing startup rule 'Initialize Location'
12:49:18.015 [DEBUG] [m.r.internal.engine.RuleEngine:264 ] - Executing startup rule 'Set random room temperatures'
12:49:18.156 [DEBUG] [.l.i.LoggingPersistenceService:100 ] - Logged item 'Temperature_GF_Corridor' to file 'temperatures.log'
12:49:18.167 [DEBUG] [.l.i.LoggingPersistenceService:100 ] - Logged item 'Temperature_GF_Toilet' to file 'temperatures.log'
12:49:18.179 [DEBUG] [m.r.internal.engine.RuleEngine:264 ] - Executing startup rule 'Update max and min temperatures'
12:49:18.179 [DEBUG] [.l.i.LoggingPersistenceService:100 ] - Logged item 'Temperature_GF_Living' to file 'temperatures.log'
12:49:18.194 [DEBUG] [.l.i.LoggingPersistenceService:100 ] - Logged item 'Temperature_GF_Kitchen' to file 'temperatures.log'
12:49:18.203 [DEBUG] [.l.i.LoggingPersistenceService:100 ] - Logged item 'Temperature_FF_Bath' to file 'temperatures.log'
12:49:18.207 [DEBUG] [.l.i.LoggingPersistenceService:100 ] - Logged item 'Temperature_FF_Office' to file 'temperatures.log'
12:49:18.213 [DEBUG] [.l.i.LoggingPersistenceService:100 ] - Logged item 'Temperature_FF_Child' to file 'temperatures.log'
12:49:18.220 [DEBUG] [.l.i.LoggingPersistenceService:100 ] - Logged item 'Temperature_FF_Bed' to file 'temperatures.log'
12:49:18.432 [INFO ] [g.openhab.model.script.Weather:53 ] - Temperature evolved of null degrees.
12:50:00.027 [DEBUG] [.o.m.r.i.engine.ExecuteRuleJob:53 ] - Executing scheduled rule 'Persistence Demo'
12:50:00.035 [DEBUG] [.p.internal.PersistenceManager:315 ] - Item 'NoOfLights' does not exist.
12:50:00.059 [DEBUG] [.p.rrd4j.internal.RRD4jService:113 ] - Stored 'Heating_GF_Corridor' with state 'OFF' in rrd4j database (again)
12:50:00.060 [DEBUG] [.p.rrd4j.internal.RRD4jService:132 ] - Stored 'Heating_GF_Corridor' with state '0' in rrd4j database
12:50:00.278 [TRACE] [o.c.p.internal.PersistItemsJob:56 ] - Storing item 'Heating_GF_Corridor' with persistence service 'rrd4j' took 241ms
12:50:00.281 [DEBUG] [.p.rrd4j.internal.RRD4jService:113 ] - Stored 'Heating_FF_Child' with state 'OFF' in rrd4j database (again)
12:50:00.281 [DEBUG] [.p.rrd4j.internal.RRD4jService:132 ] - Stored 'Heating_FF_Child' with state '0' in rrd4j database
12:50:00.333 [TRACE] [o.c.p.internal.PersistItemsJob:56 ] - Storing item 'Heating_FF_Child' with persistence service 'rrd4j' took 55ms
12:50:00.335 [DEBUG] [.p.rrd4j.internal.RRD4jService:113 ] - Stored 'Heating_FF_Office' with state 'OFF' in rrd4j database (again)
12:50:00.336 [DEBUG] [.p.rrd4j.internal.RRD4jService:132 ] - Stored 'Heating_FF_Office' with state '0' in rrd4j database
12:50:00.341 [DEBUG] [.o.m.r.i.engine.ExecuteRuleJob:53 ] - Executing scheduled rule 'Set random room temperatures'
12:50:00.352 [DEBUG] [.l.i.LoggingPersistenceService:100 ] - Logged item 'Temperature_GF_Corridor' to file 'temperatures.log'
12:50:00.355 [DEBUG] [.l.i.LoggingPersistenceService:100 ] - Logged item 'Temperature_GF_Living' to file 'temperatures.log'
12:50:00.359 [DEBUG] [.l.i.LoggingPersistenceService:100 ] - Logged item 'Temperature_GF_Kitchen' to file 'temperatures.log'
12:50:00.365 [DEBUG] [.l.i.LoggingPersistenceService:100 ] - Logged item 'Temperature_FF_Bath' to file 'temperatures.log'
12:50:00.377 [TRACE] [o.c.p.internal.PersistItemsJob:56 ] - Storing item 'Heating_FF_Office' with persistence service 'rrd4j' took 44ms
12:50:00.378 [DEBUG] [.l.i.LoggingPersistenceService:100 ] - Logged item 'Temperature_FF_Office' to file 'temperatures.log'
12:50:00.380 [DEBUG] [.p.rrd4j.internal.RRD4jService:113 ] - Stored 'DemoSwitch' with state 'OFF' in rrd4j database (again)
12:50:00.380 [DEBUG] [.p.rrd4j.internal.RRD4jService:132 ] - Stored 'DemoSwitch' with state '0' in rrd4j database
12:50:00.389 [DEBUG] [.l.i.LoggingPersistenceService:100 ] - Logged item 'Temperature_FF_Child' to file 'temperatures.log'
12:50:00.393 [DEBUG] [.l.i.LoggingPersistenceService:100 ] - Logged item 'Temperature_FF_Bed' to file 'temperatures.log'
12:50:00.433 [TRACE] [o.c.p.internal.PersistItemsJob:56 ] - Storing item 'DemoSwitch' with persistence service 'rrd4j' took 56ms
12:50:00.439 [DEBUG] [.p.rrd4j.internal.RRD4jService:113 ] - Stored 'Heating_FF_Bath' with state 'OFF' in rrd4j database (again)
12:50:00.441 [DEBUG] [.p.rrd4j.internal.RRD4jService:132 ] - Stored 'Heating_FF_Bath' with state '0' in rrd4j database
12:50:00.477 [TRACE] [o.c.p.internal.PersistItemsJob:56 ] - Storing item 'Heating_FF_Bath' with persistence service 'rrd4j' took 43ms
12:50:00.479 [DEBUG] [.p.rrd4j.internal.RRD4jService:113 ] - Stored 'Heating_GF_Toilet' with state 'OFF' in rrd4j database (again)
12:50:00.480 [DEBUG] [.p.rrd4j.internal.RRD4jService:132 ] - Stored 'Heating_GF_Toilet' with state '0' in rrd4j database
12:50:00.522 [TRACE] [o.c.p.internal.PersistItemsJob:56 ] - Storing item 'Heating_GF_Toilet' with persistence service 'rrd4j' took 44ms
12:50:00.524 [DEBUG] [.p.rrd4j.internal.RRD4jService:113 ] - Stored 'Heating_GF_Living' with state 'OFF' in rrd4j database (again)
12:50:00.527 [DEBUG] [.p.rrd4j.internal.RRD4jService:132 ] - Stored 'Heating_GF_Living' with state '0' in rrd4j database
12:50:00.567 [TRACE] [o.c.p.internal.PersistItemsJob:56 ] - Storing item 'Heating_GF_Living' with persistence service 'rrd4j' took 44ms
12:50:00.568 [DEBUG] [.p.rrd4j.internal.RRD4jService:113 ] - Stored 'Heating_GF_Kitchen' with state 'ON' in rrd4j database (again)
12:50:00.569 [DEBUG] [.p.rrd4j.internal.RRD4jService:132 ] - Stored 'Heating_GF_Kitchen' with state '1' in rrd4j database
12:50:00.611 [TRACE] [o.c.p.internal.PersistItemsJob:56 ] - Storing item 'Heating_GF_Kitchen' with persistence service 'rrd4j' took 44ms
12:50:00.613 [DEBUG] [.p.rrd4j.internal.RRD4jService:113 ] - Stored 'Heating_FF_Bed' with state 'OFF' in rrd4j database (again)
12:50:00.614 [DEBUG] [.p.rrd4j.internal.RRD4jService:132 ] - Stored 'Heating_FF_Bed' with state '0' in rrd4j database
12:50:00.656 [TRACE] [o.c.p.internal.PersistItemsJob:56 ] - Storing item 'Heating_FF_Bed' with persistence service 'rrd4j' took 44ms
12:50:00.657 [DEBUG] [.p.rrd4j.internal.RRD4jService:113 ] - Stored 'Window_GF_Toilet' with state 'OPEN' in rrd4j database (again)
12:50:00.657 [DEBUG] [.p.rrd4j.internal.RRD4jService:132 ] - Stored 'Window_GF_Toilet' with state '1' in rrd4j database
12:50:00.701 [TRACE] [o.c.p.internal.PersistItemsJob:56 ] - Storing item 'Window_GF_Toilet' with persistence service 'rrd4j' took 45ms
12:50:00.702 [DEBUG] [.p.rrd4j.internal.RRD4jService:132 ] - Stored 'Temperature_GF_Living' with state '19.00000000' in rrd4j database
12:50:00.745 [TRACE] [o.c.p.internal.PersistItemsJob:56 ] - Storing item 'Temperature_GF_Living' with persistence service 'rrd4j' took 44ms
12:50:00.746 [DEBUG] [.p.rrd4j.internal.RRD4jService:132 ] - Stored 'Weather_Temp_Min' with state '21' in rrd4j database
12:50:00.790 [TRACE] [o.c.p.internal.PersistItemsJob:56 ] - Storing item 'Weather_Temp_Min' with persistence service 'rrd4j' took 44ms
12:50:00.792 [DEBUG] [.p.rrd4j.internal.RRD4jService:132 ] - Stored 'Temperature_FF_Office' with state '21.90000000' in rrd4j database
12:50:00.835 [TRACE] [o.c.p.internal.PersistItemsJob:56 ] - Storing item 'Temperature_FF_Office' with persistence service 'rrd4j' took 43ms
12:50:00.837 [DEBUG] [.p.rrd4j.internal.RRD4jService:132 ] - Stored 'Temperature_GF_Toilet' with state '19.60000000' in rrd4j database
12:50:00.879 [TRACE] [o.c.p.internal.PersistItemsJob:56 ] - Storing item 'Temperature_GF_Toilet' with persistence service 'rrd4j' took 44ms
12:50:00.881 [DEBUG] [.p.rrd4j.internal.RRD4jService:132 ] - Stored 'Temperature_GF_Kitchen' with state '21.50000000' in rrd4j database
12:50:00.924 [TRACE] [o.c.p.internal.PersistItemsJob:56 ] - Storing item 'Temperature_GF_Kitchen' with persistence service 'rrd4j' took 44ms
12:50:00.925 [DEBUG] [.p.rrd4j.internal.RRD4jService:132 ] - Stored 'Temperature_GF_Corridor' with state '18.10000000' in rrd4j database
12:50:00.957 [TRACE] [o.c.p.internal.PersistItemsJob:56 ] - Storing item 'Temperature_GF_Corridor' with persistence service 'rrd4j' took 33ms
12:50:00.960 [DEBUG] [.p.rrd4j.internal.RRD4jService:132 ] - Stored 'Temperature_FF_Bath' with state '21.60000000' in rrd4j database
12:50:01.002 [TRACE] [o.c.p.internal.PersistItemsJob:56 ] - Storing item 'Temperature_FF_Bath' with persistence service 'rrd4j' took 44ms
12:50:01.003 [DEBUG] [.p.rrd4j.internal.RRD4jService:132 ] - Stored 'Temperature_FF_Child' with state '19.60000000' in rrd4j database
12:50:01.057 [TRACE] [o.c.p.internal.PersistItemsJob:56 ] - Storing item 'Temperature_FF_Child' with persistence service 'rrd4j' took 55ms
12:50:01.059 [DEBUG] [.p.rrd4j.internal.RRD4jService:132 ] - Stored 'Weather_Temp_Max' with state '22' in rrd4j database
12:50:01.102 [TRACE] [o.c.p.internal.PersistItemsJob:56 ] - Storing item 'Weather_Temp_Max' with persistence service 'rrd4j' took 44ms
12:50:01.103 [DEBUG] [.p.rrd4j.internal.RRD4jService:132 ] - Stored 'Temperature_FF_Bed' with state '19.80000000' in rrd4j database
12:50:01.147 [TRACE] [o.c.p.internal.PersistItemsJob:56 ] - Storing item 'Temperature_FF_Bed' with persistence service 'rrd4j' took 44ms
12:50:01.148 [DEBUG] [.p.rrd4j.internal.RRD4jService:132 ] - Stored 'Weather_Temperature' with state '21' in rrd4j database
12:50:01.191 [TRACE] [o.c.p.internal.PersistItemsJob:56 ] - Storing item 'Weather_Temperature' with persistence service 'rrd4j' took 44ms
12:50:04.513 [INFO ] [penhab.io.rest.RESTApplication:160 ] - Stopped REST API
12:50:04.515 [DEBUG] [o.o.i.s.i.DiscoveryServiceImpl:80 ] - Unregistering service _openhab-server._tcp.local. at port 8080
12:50:06.520 [DEBUG] [o.o.i.s.i.DiscoveryServiceImpl:80 ] - Unregistering service _openhab-server-ssl._tcp.local. at port 8443
I can’t see anything about mochad binding or the commands here (turn ON/OFF the module).
The commands should be logged in openhab/logs/events.log.
I’m with openHAB2 for a while now. Maybe @rlkoshak has more ideas how to debug your problem.
rlkoshak
September 22, 2016, 1:17pm
10
Have you actually installed the mochad binding’s jar file? I don’t see any mention of mochad in that log. Usually there is at least something along the lines of “Binding starting” from most bindings when OH starts. Each addon must be separately installed.
dani6292
September 22, 2016, 4:35pm
11
I have checked it again and the jar file are actually installed. I run the demo again and I can see this in my terminal:
18:12:05.972 [DEBUG] [.p.internal.PersistenceManager:428 ] - Scheduled strategy rrd4j.everyMinute with cron expression 0 * * * * ?
But after that I read this:
18:20:51.765 [ERROR] [.b.m.internal.MochadX10Binding:275 ] - IOException: there is no route to «host» while trying to connect to Mochad X10 host: 192.168.1.30:1099
This is my openhab.cfg file:
Mochad X10
This is my demo.item file:
/* Alarma*/
I configure the values like in this website https://github.com/openhab/openhab/wiki/Mochad-X10-Binding . I don’t know what is wrong.
Thanks for your help!
rlkoshak
September 22, 2016, 5:07pm
12
We have reached the end of my expertise. The binding is being loaded and started so the problem appears the be somewhere within the binding itself. Since I’ve no experience with this binding I’ll be of no further help.
I am irritated by this line. “192.168.1.30” is the IP address used in the Binding wiki article as an example. Does your device indeed own this IP?
smar
September 23, 2016, 6:36am
14
Also an obvious question, but worth double checking - have you actually installed the mochad server itself (this is separate to the binding)? If not, this must be installed first. If you have, please check that it is working correctly. You can use the netcat command to see what messages the mochad server is seeing/sending. E.g. on the server that the server is installed, try nc localhost 1099 and make sure that you are seeing the actual X10 commands being sent/received from your CM15a.
dani6292
September 23, 2016, 8:57am
15
Hello, you were right, I haven’t installed the mochad server. I have installed it now and I’m watching the commands in other terminal. I have got this results:
The next message is from the terminal which is running the demo:
10:47:43.538 [INFO ] [runtime.busevents :22 ] - Alarma_casa received command ON
This one is from the terminal which is waiting for the X10 message ( running previusly nc localhost 1099) ;
dani@dani-HP-Pavilion-g6-Notebook-PC:~$ nc localhost 1099
I suppose that everything is right but the module don’t make any noise. I’m sure that the address is A1 because I tried it with the ActiveHome in windows and it sound.
Thank you very much for your help!
