JS transformation service starts too late when openHAB startup

Setup, Configuration and Use Runtime
, ,
1

Hi, I have used openHAB2 from February, from beta 2 release. Some of my items are based on HTTP binding with javascript transformation.
In beta 2 I had a problem, that JS service was loaded too late, and some items were not able to transform received HTTP messages corectly.
In beta 4 build #477 everything had working great without any errors.
Yesterday I downloaded build # 595 and the problem returned, exactly the same as before. Always at the openHAB starts I have problem with transform HTTP messages by JS service which is not available at this moment. Sometimes with one item, sometimes with a more ones. Next calling to HTTP (after startup) are transform succesfully.
Therefore, it seems to me that the service responsible for javascript transformation loads too late.
Here’s the output from openHAB:

2016-11-20 13:55:20.062 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'astro.items'
2016-11-20 13:55:20.125 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'rrd4j.persist'
2016-11-20 13:55:20.171 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'comfoair.items'
2016-11-20 13:55:20.281 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'nodes.items'
2016-11-20 13:55:20.453 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'weather.items'
2016-11-20 13:55:20.765 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'weather_METAR.items'
2016-11-20 13:55:20.875 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'weather_TAF.items'
2016-11-20 13:55:22.187 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'weather_TAF.rules'
2016-11-20 13:55:23.187 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'comfoair.rules'
2016-11-20 13:55:23.328 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'nodes_times.rules'
2016-11-20 13:55:23.375 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'nodes.rules'
2016-11-20 13:55:23.390 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'AGM.rules'
2016-11-20 13:55:23.406 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'weather.rules'
2016-11-20 13:55:23.578 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'weather_METAR.rules'
2016-11-20 13:55:24.031 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'prepareErrorMsgToSend.script'
2016-11-20 13:55:24.125 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'prepareErrorMsg.script'
2016-11-20 13:55:24.140 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'weather_TAF_condition.script'
2016-11-20 13:55:24.171 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'weather_METAR_condition.script'
2016-11-20 13:55:25.156 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'AGM.sitemap'
2016-11-20 13:55:25.265 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'pogoda.sitemap'
2016-11-20 13:55:25.500 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'astro.things'
2016-11-20 13:55:25.531 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'ntp.things'
2016-11-20 13:55:27.562 [INFO ] [basic.internal.servlet.WebAppServlet] - Started Basic UI at /basicui/app
2016-11-20 13:55:28.000 [INFO ] [assic.internal.servlet.WebAppServlet] - Started Classic UI at /classicui/app
2016-11-20 13:55:28.078 [INFO ] [arthome.ui.paper.internal.PaperUIApp] - Started Paper UI at /ui
2016-11-20 13:55:28.125 [INFO ] [.dashboard.internal.DashboardService] - Started dashboard at /start
2016-11-20 13:55:28.359 [INFO ] [penhab.io.transport.mqtt.MqttService] - MQTT Service initialization completed.
2016-11-20 13:55:28.359 [INFO ] [t.mqtt.internal.MqttBrokerConnection] - Starting MQTT broker connection 'mysensor'
2016-11-20 13:55:28.453 [INFO ] [b.core.service.AbstractActiveService] - HTTP Refresh Service has been started
2016-11-20 13:55:29.328 [WARN ] [.core.transform.TransformationHelper] - Cannot get service reference for transformation service of type JS
2016-11-20 13:55:29.328 [WARN ] [ab.binding.http.internal.HttpBinding] - couldn't transform response because transformationService of type 'JS' is unavailable
2016-11-20 13:55:29.671 [WARN ] [.core.transform.TransformationHelper] - Cannot get service reference for transformation service of type JS
2016-11-20 13:55:29.671 [WARN ] [ab.binding.http.internal.HttpBinding] - couldn't transform response because transformationService of type 'JS' is unavailable
2016-11-20 13:55:29.843 [INFO ] [b.core.service.AbstractActiveService] - ComfoAir Refresh Service has been started
2016-11-20 13:55:29.937 [WARN ] [.core.transform.TransformationHelper] - Cannot get service reference for transformation service of type JS
2016-11-20 13:55:29.937 [WARN ] [ab.binding.http.internal.HttpBinding] - couldn't transform response because transformationService of type 'JS' is unavailable
2016-11-20 13:55:30.375 [WARN ] [.core.transform.TransformationHelper] - Cannot get service reference for transformation service of type JS
2016-11-20 13:55:30.375 [WARN ] [ab.binding.http.internal.HttpBinding] - couldn't transform response because transformationService of type 'JS' is unavailable
2016-11-20 13:55:31.140 [WARN ] [.core.transform.TransformationHelper] - Cannot get service reference for transformation service of type JS
2016-11-20 13:55:31.140 [WARN ] [ab.binding.http.internal.HttpBinding] - couldn't transform response because transformationService of type 'JS' is unavailable

Does anybody met with this problem ? and is there some method to eliminate it ? I hope “yes” because in build #477 it works fine.
My openHAB is working on Windows XP, but the problem is on Windows 10 too.

2

Can confirm this problem having the same issue with XLST transformation.

3

I can second that this problem happens with XSLT and it even happened once for me with Map when Map was the only Transform I had installed. It quickly corrects itself shortly after the first boot so I’ve not looked into it any deeper.

4

Same here.

5

I think this warrants an issue opened in the openhab/openhab-distro repository, or maybe eclipse/smarthome (not sure which is appropriate, think the former).

6

Done:

1 Like
7

Same with JS & MAP transform to me, and it start after 6-7 mins.

2016-12-05 16:25:43.776 [WARN ] [.core.transform.TransformationHelper] - Cannot get service reference for transformation service of type MAP
2016-12-05 16:25:43.868 [WARN ] [.core.transform.TransformationHelper] - Cannot get service reference for transformation service of type MAP
2016-12-05 16:25:43.870 [WARN ] [.core.transform.TransformationHelper] - Cannot get service reference for transformation service of type MAP
2016-12-05 16:25:43.880 [WARN ] [.core.transform.TransformationHelper] - Cannot get service reference for transformation service of type JS
2016-12-05 16:25:43.890 [WARN ] [.core.transform.TransformationHelper] - Cannot get service reference for transformation service of type JS
2016-12-05 16:25:43.895 [WARN ] [.core.transform.TransformationHelper] - Cannot get service reference for transformation service of type JS