Problem or issues reports

rohitjthomas · January 31, 2018, 12:58pm

Hi All,

I think my initial post was to the wrong thread so I am reposting to this thread.

I have several Harmony hubs configured on my VeraLite ZWave controller with UI7 using the Harmony Hub Control plugin. Recently after adding hub #5 to my controller and configuring the Settings, Activities tabs, my fifth hub does not display any of the buttons on the Device page. I see the following message - “Configure the harmony Activities on the Activities tab to define control buttons.” I also have a child device configured for this hub and no buttons display on the Device page after configuring the Settings tab. All my other hubs are working fine and displaying buttons on the Device page as expected. Only the last hub that I added has an issue. I have tried several troubleshooting steps, including deleting and readding the hub multiple times, rebooting my controller and\or manually reloading the engine. I have included some screen-prints. Any thoughts on why my fifth hub is having this particular issue.

Any assistance would be appreciated as I love the features of the plugin. I have my other Harmony hubs and their associated activities\devices integrated with several Vera scenes and Echo\Alexa via the HABridge. I am looking to do the same with my fifth hub.

Thanks!!

reneboer · February 1, 2018, 10:33am

Hi rohitjthomas,

For the fifth Hub plugin can you have a look at the Advanced settings? The device_file should read D_Harmony256.xml and device_json D_Harmony256.xml.

Then make sure you refresh your browser. Vera reboots are triggered by the plugin and doing those manual should not be needed.

There is no limitation in the number of Hubs in the plugin. Also make sure there is sufficient space left to create the additional files required.

Cheers Rene

rohitjthomas · February 17, 2018, 4:31am

Thanks Rene for responding and apologies for the long wait in replying back. I have been traveling for work and recently my Veralite died and is no longer functional. Before it kicked the bucket, I did see some alerts about space issues. As you indicated, its very likely that was the reason the fifth hub did not load properly. I have a VeraPlus now and will be working with support to get it up and running using my Veralite backup.

Thanks for the response and thanks a lot for this wonderful plugin. It’s awesome!!

reneboer · February 17, 2018, 10:04am

Thanks for the update. Glad it is all up and running again.

The Lites are down graded to test boxes for me too. They are now stretched too far on space to be useful I think. The agony wasn’t worth the 120 Euro an Edge costs.

Cheers Rene

thief · March 26, 2018, 5:50am

Hello, and Thanks for a great plugin.
I have a small problem with it though.

I teached my Air Conditioner remote to the Harmony hub and all went just great.
I have the AC now as child device in Vera and configured the On/Off buttons.
It works like a charm when I click the buttons in Vera.

However, If I tell Alexa(or use the buttons in Alexa app smarthome section) it doesn’t work.
I see the AC in alexa app as a “plug”, tried to change that to “generic light” without success
Alexa responds with an “Ok” but nothing is done,

Strange thing is that if I create a scene - it works!
I have named the AC device as “Air Conditioner” and it have only 2 buttons “On” and “Off”
and i tell Alexa “turn on Air Conditioner”

What am I missing here?

And yes I have done everything correctly, rebooted stuff, and enabled the devices on Login

Any Ideas?

reneboer · March 26, 2018, 8:59am

Hi thief,

No sorry. I have zero knowledge on the Alexa integration.

Anyone else?

Cheers Rene

thief · March 26, 2018, 9:59am

Ok, thanks for the reply.

The AC is recognized as an on/off switch, but the switch icon is different from those other switches I have (see image).
I guess the plugin makes “different” kind of (on/off) buttons and they don’t work like the switch in the image for “cameras”
It’s like the switch it self isn’t recognized somehow.
Kinda hard to explain what I mean.

If I make scene to turn it on/off with Alexa it works as it should but the commands are then weird because you can’t
tell Alexa to “turn off” a scene.

You have a better solution maybe?
There is an option to create “child devices”. What can one do with them?

reneboer · March 26, 2018, 10:29am

Hi,

The only think I can think of is two scenes. One for on, one for off.

Cheers Rene

thief · March 26, 2018, 8:17pm

Ok, so apparently there is no way of controlling harmony devices created in Vera directly with Alexa.

So the virtual switch comes very handy here.
I created a scene for ON command and a scene for Off command.
The scenes are connected to a virtual switch that Alexa can control perfectly.
To get this to work I had to add: <Category_Num>3</Category_Num><SubCategory_Num>0</SubCategory_Num> to the “D_VSwitch.xml” file to change the device category. Otherwise it wont show up in: Login

It works perfectly.

sm2117 · September 11, 2018, 10:39pm

Not sure if this is a new problem or not but yesterday I tried to set some Harmony activities via Vera’s House modes (I must admit, I was surprised to see the plugin supported here at all!). Anyway, it seemed to work at first - correctly changed Harmony activities when going from HOME <-> AWAY - but after a few more attempts, I just couldn’t get it to work again, even after numerous Vera reloads etc.

Still works fine when activities are set in scenes so I can only assume Vera’s House Modes functionality is not interacting with the plugin too well.

Anyone else seen this?

reneboer · September 12, 2018, 8:44am

Hi,

Yes, plugins can use the house modes by setting the CustomModeConfiguration. See [url=http://wiki.mios.com/index.php/Luup_UPnP_Variables_and_Actions#HaDevice1]Luup UPnP Variables and Actions - MiOS.

I use it at the moment for all off when leaving the house.

Cheers Rene

sm2117 · September 13, 2018, 1:45am

Thanks for the reply. I guess what I was saying is that I found the plugin was only working intermittently for me for some reason, but only when used in conjunction with house modes. In scenes, it works every time…

reneboer · September 17, 2018, 11:47am

Ok, I will run some tests if I can reproduce.

Cheers Rene

RHCPNG · January 2, 2019, 10:31am

Hi Rene,

Does the setTarget action still work in your setup? Because when I run it through a scene or directly from the device menu nothing happens. I’m trying to set it to 0.

I see this in the log:

2019-01-02 11:22:54.879 luup_log:3: ALTUI: debug: >>>evaluated trueSince(((tonumber((luup.variable_get("urn:upnp-org:serviceId:Dimming1", "LoadLevelTarget", 10024)))) < 50 and (tonumber(new)) > 7), 600), index:1 LastEval:false 2019-01-02 11:22:54.879 luup_log:3: ALTUI: debug: -----> DataProviders() null 2019-01-02 11:22:54.881 luup_log:3: ALTUI: debug: registeredWatches: {"0-10003":{"urn:upnp-org:serviceId:SwitchPower1":{"Status":{"Expressions":{"workflow":[{"WorkflowAltuiID":"0-1"}]}}},"urn:micasaverde-com:serviceId:SceneController1":{"LastSceneTime":{"Expressions":{"((luup.variable_get(\"urn:micasaverde-com:serviceId:SceneController1\", \"LastSceneID\", 10003)) == '26')":[{"SceneID":10}],"workflow":[{"WorkflowAltuiID":"0-4"}]}}}},"0-10591":{"urn:micasaverde-com:serviceId:EnergyMetering1":{"Watts":{"LastUpdate":1546424519,"LastOld":"65.35","Expressions":{"tonumber(new)<1":[{"LastEval":false,"SceneID":23}],"tonumber(new)>10":[{"LastEval":true,"SceneID":21}]},"LastNew":"61.83"}}},"0-10322":{"urn:upnp-org:serviceId:SwitchPower1":{"Status":{"Expressions":{"new=='1'":[{"SceneID":13}]}}}},"0-43":{"urn:upnp-org:serviceId:VContainer1":{"Variable1":{"LastUpdate":1546424519,"LastOld":"On","Expressions":{"new=='Off'":[{"LastEval":false,"SceneID":22}],"new=='On'":[{"LastEval":true,"SceneID":20}]},"LastNew":"On"}}},"0-2":{"openLuup":{"HouseMode":{"Expressions":{"new=='4'":[{"SceneID":37},{"SceneID":36},{"SceneID":3}],"trueSince((new == '3' and (luup.variable_get(\"urn:upnp-org:serviceId:SwitchPower1\", \"Status\", 10342)) == '1'), 240)":[{"SceneID":19}],"workflow":[{"WorkflowAltuiID":"0-3"}],"new=='1'":[{"SceneID":11}],"trueSince((new == '4' and (luup.variable_get(\"urn:upnp-org:serviceId:SwitchPower1\", \"Status\", 10342)) == '1'), 240)":[{"SceneID":19}],"trueSince((new == '2' and (luup.variable_get(\"urn:upnp-org:serviceId:SwitchPower1\", \"Status\", 10342)) == '1'), 240)":[{"SceneID":19}],"new=='3'":[{"SceneID":37},{"SceneID":36},{"SceneID":7}],"new=='2'":[{"SceneID":37},{"SceneID":36},{"SceneID":8}]}}}},"0-10583":{"urn:micasaverde-com:serviceId:LightSensor1":{"CurrentLevel":{"LastUpdate":1546424471,"LastOld":"32","Expressions":{"workflow":[{"WorkflowAltuiID":"0-3"}]},"LastNew":"30"}}},"0-10337":{"urn:dcineco-com:serviceId:MSwitch1":{"Status3":{"Expressions":{"workflow":[{"WorkflowAltuiID":"0-4"}]}},"Status2":{"Expressions":{"workflow":[{"WorkflowAltuiID":"0-4"}],"new=='1'":[{"SceneID":26}]}},"Status6":{"Expressions":{"workflow":[{"WorkflowAltuiID":"0-4"}],"new=='1'":[{"SceneID":30},{"SceneID":27}]}},"Status5":{"Expressions":{"new=='1'":[{"SceneID":29}]}},"Status1":{"Expressions":{"((luup.variable_get(\"urn:dcineco-com:serviceId:MSwitch1\", \"Status4\", 10337)) == '1' and new == '1')":[{"SceneID":25}],"workflow":[{"WorkflowAltuiID":"0-4"}]}}}},"0-10359":{"urn:micasaverde-com:serviceId:SceneController1":{"LastSceneTime":{"Expressions":{"((luup.variable_get(\"urn:micasaverde-com:serviceId:SceneController1\", \"LastSceneID\", 10359)) == '12')":[{"SceneID":33}],"((luup.variable_get(\"urn:micasaverde-com:serviceId:SceneController1\", \"LastSceneID\", 10359)) == '13')":[{"SceneID":31}]}}}},"0-50":{"urn:upnp-micasaverde-com:serviceId:Weather1":{"PrecipProbability":{"Expressions":{"((tonumber((luup.variable_get(\"urn:upnp-org:serviceId:Dimming1\", \"LoadLevelTarget\", 10024)))) < 100 and (tonumber(new)) >= 50)":[{"SceneID":32}]}},"icon":{"Expressions":{"((tonumber((luup.variable_get(\"urn:upnp-org:serviceId:Dimming1\", \"LoadLevelTarget\", 10024)))) < 100 and new == 'rain')":[{"SceneID":32}]}},"WindSpeed":{"LastUpdate":1546424574,"LastOld":"5.5","Expressions":{"trueSince(((tonumber((luup.variable_get(\"urn:upnp-org:serviceId:Dimming1\", \"LoadLevelTarget\", 10024)))) < 50 and (tonumber(new)) > 7), 600)":[{"LastEval":false,"SceneID":32}]},"LastNew":"5.55"}}},"0-47":{"urn:micasaverde-com:serviceId:EnergyMetering1":{"Watts":{"LastUpdate":1546424467,"LastOld":"135","Expressions":{"trueSince(((tonumber(new)) >= 1200 and ((timeOf(lastupdate)) >= (12*3600+00*60+00) and ((timeOf(lastupdate)) < (20*3600+00*60+00) and ((tonumber((luup.variable_get(\"urn:upnp-micasaverde-com:serviceId:Weather1\", \"WindSpeed\", 50)))) <= 7 and ((tonumber((luup.variable_get(\"urn:upnp-org:serviceId:Dimming1\", \"LoadLevelTarget\", 10024)))) == 100 and (tonumber((luup.variable_get(\"urn:upnp-micasaverde-com:serviceId:Weather1\", \"PrecipProbability\", 50)))) <= 0.6))))), 900)":[{"LastEval":false,"SceneID":28}]},"LastNew":"180"}}}} 2019-01-02 11:22:55.389 openLuup.http:: GET /data_request?id=lu_status2&output_format=json&DataVersion=421580808&Timeout=60&MinimumDelay=1500&_=1546424074991 HTTP/1.1 tcp{client}: 0x1de9f28 2019-01-02 11:22:56.438 luup.variable_set:: 10352.urn:micasaverde-com:serviceId:EnergyMetering1.KWHReading was: 1546424443 now: 1546424574 #hooks:0 2019-01-02 11:22:56.438 luup.variable_set:: 10352.urn:micasaverde-com:serviceId:EnergyMetering1.Watts was: 46.39 now: 45.13 #hooks:0 2019-01-02 11:22:56.547 openLuup.http:: GET /data_request?id=action&output_format=json&DeviceNum=69&serviceId=urn:upnp-org:serviceId:SwitchPower1&action=GetStatus HTTP/1.1 tcp{client}: 0x1f87da8 2019-01-02 11:22:56.548 luup.call_action:: 69.urn:upnp-org:serviceId:SwitchPower1.GetStatus 2019-01-02 11:22:56.548 openLuup.context_switch:: ERROR: [string "L_Harmony.lua"]:2745: attempt to call global 'varGet' (a nil value) 2019-01-02 11:22:56.548 openLuup.http:: request completed (44 bytes, 1 chunks, 0 ms) tcp{client}: 0x1f87da8 2019-01-02 11:22:57.054 openLuup.http:: request completed (15903 bytes, 1 chunks, 1665 ms) tcp{client}: 0x1de9f28 2019-01-02 11:22:57.711 openLuup.http:: GET /data_request?id=user_data&output_format=json&DataVersion=421568685&_=1546424074992 HTTP/1.1 tcp{client}: 0x1de9f28 2019-01-02 11:22:57.822 openLuup.http:: request completed (997922 bytes, 63 chunks, 111 ms) tcp{client}: 0x1de9f28 2019-01-02 11:22:59.904 openLuup.http:: GET /data_request?id=lu_status2&output_format=json&DataVersion=421580812&Timeout=60&MinimumDelay=1500&_=1546424074993 HTTP/1.1 tcp{client}: 0x1de9f28 2019-01-02 11:23:01.942 luup.variable_set:: 10352.urn:micasaverde-com:serviceId:EnergyMetering1.Log was: 46,44,59,1546424444,3045 now: 45,44,59,1546424576,3177 #hooks:0 2019-01-02 11:23:01.942 luup.variable_set:: 10352.urn:micasaverde-com:serviceId:ZWaveNetwork1.LastPollSuccess was: 1546424384 now: 1546424576 #hooks:0 2019-01-02 11:23:02.451 openLuup.http:: request completed (15903 bytes, 1 chunks, 2545 ms) tcp{client}: 0x1de9f28 2019-01-02 11:23:02.457 openLuup.http:: GET /data_request?id=user_data&output_format=json&DataVersion=421568686&_=1546424074994 HTTP/1.1 tcp{client}: 0x1de9f28 2019-01-02 11:23:02.582 openLuup.http:: request completed (997922 bytes, 63 chunks, 124 ms) tcp{client}: 0x1de9f28 2019-01-02 11:23:03.833 openLuup.http:: GET /data_request?id=action&output_format=json&DeviceNum=69&serviceId=urn:upnp-org:serviceId:SwitchPower1&action=SetTarget&newTargetValue=0 HTTP/1.1 tcp{client}: 0x1de9f28 2019-01-02 11:23:03.833 luup.call_action:: 69.urn:upnp-org:serviceId:SwitchPower1.SetTarget 2019-01-02 11:23:03.834 openLuup.http:: request completed (62 bytes, 1 chunks, 0 ms) tcp{client}: 0x1de9f28 2019-01-02 11:23:03.834 openLuup.context_switch:: ERROR: [string "L_Harmony.lua"]:2729: attempt to call global 'varGet' (a nil value) 2019-01-02 11:23:03.834 openLuup.scheduler:: job aborted : [string "L_Harmony.lua"]:2729: attempt to call global 'varGet' (a nil value) 2019-01-02 11:23:06.930 openLuup.http:: GET /data_request?id=lu_status2&output_format=json&DataVersion=421580816&Timeout=60&MinimumDelay=1500&_=1546424074995 HTTP/1.1 tcp{client}: 0x1de9f28 2019-01-02 11:23:07.456 luup.variable_set:: 10076.urn:micasaverde-com:serviceId:ZWaveNetwork1.LastPollSuccess was: 1546424391 now: 1546424585 #hooks:0

EDIT
Same error when trying actions GetTarget and GetStatus.
“openLuup.context_switch:: ERROR: [string “L_Harmony.lua”]:2729: attempt to call global ‘varGet’ (a nil value)”

reneboer · January 2, 2019, 11:26am

Hi,

Yes, I found this but too. This new I_Harmony.xml should fix this.

Cheers Rene

RHCPNG · January 2, 2019, 6:09pm

[quote=“reneboer, post:115, topic:191192”]Hi,

Yes, I found this but too. This new I_Harmony.xml should fix this.

Cheers Rene[/quote]

I don’t think it’s flawless yet. It does turn off my devices, but I get an error at Link Status and in the log:

2019-01-02 19:04:10.100 openLuup.http:: request completed (24459 bytes, 2 chunks, 2777 ms) tcp{client}: 0x2269778 2019-01-02 19:04:10.105 openLuup.http:: GET /data_request?id=user_data&output_format=json&DataVersion=451934773&_=1546451723387 HTTP/1.1 tcp{client}: 0x2269778 2019-01-02 19:04:10.223 openLuup.http:: request completed (997815 bytes, 63 chunks, 117 ms) tcp{client}: 0x2269778 2019-01-02 19:04:12.294 openLuup.http:: GET /data_request?id=lu_status2&output_format=json&DataVersion=451944648&Timeout=60&MinimumDelay=1500&_=1546451723388 HTTP/1.1 tcp{client}: 0x2269778 2019-01-02 19:04:12.617 openLuup.http:: GET /data_request?id=action&output_format=json&DeviceNum=69&serviceId=urn:upnp-org:serviceId:SwitchPower1&action=SetTarget&newTargetValue=0 HTTP/1.1 tcp{client}: 0x172fcd8 2019-01-02 19:04:12.621 luup.call_action:: 69.urn:upnp-org:serviceId:SwitchPower1.SetTarget 2019-01-02 19:04:12.622 openLuup.http:: request completed (62 bytes, 1 chunks, 1 ms) tcp{client}: 0x172fcd8 2019-01-02 19:04:12.622 luup.variable_set:: 69.urn:rboer-com:serviceId:Harmony1.IconSet was: 0 now: 2 #hooks:0 2019-01-02 19:04:13.931 luup.variable_set:: 69.urn:rboer-com:serviceId:Harmony1.LinkStatus was: Ok now: Error #hooks:0 2019-01-02 19:04:13.931 openLuup.context_switch:: ERROR: [string "L_Harmony.lua"]:1301: attempt to concatenate local 'msg' (a nil value) 2019-01-02 19:04:13.931 openLuup.scheduler:: job aborted : [string "L_Harmony.lua"]:1301: attempt to concatenate local 'msg' (a nil value) 2019-01-02 19:04:13.932 openLuup.io.server:: HTTP connection from 192.168.1.100 tcp{client}: 0x1980a18 2019-01-02 19:04:13.935 openLuup.http:: request completed (10900 bytes, 1 chunks, 1639 ms) tcp{client}: 0x2269778 2019-01-02 19:04:13.936 openLuup.http:: GET /data_request?id=action&output_format=json&serviceId=urn:upnp-org:serviceId:SwitchPower1&action=SetTarget&newTargetValue=0&DeviceNum=34 HTTP/1.1 tcp{client}: 0x1980a18 2019-01-02 19:04:13.936 luup.call_action:: 34.urn:upnp-org:serviceId:SwitchPower1.SetTarget 2019-01-02 19:04:13.936 luup.call_action:: action will be handled by parent: 31 2019-01-02 19:04:13.937 openLuup.http:: request completed (62 bytes, 1 chunks, 1 ms) tcp{client}: 0x1980a18 2019-01-02 19:04:13.938 luup.io.write:: message length: 37, bytes sent: 37, status: OK tcp{client}: 0x204cb18 2019-01-02 19:04:13.942 luup.io.incoming:: bytes received: 37, status: OK tcp{client}: 0x204cb18 2019-01-02 19:04:13.943 luup.variable_set:: 34.urn:upnp-org:serviceId:SwitchPower1.Status was: 1 now: 0 #hooks:0 2019-01-02 19:04:13.943 luup.io.incoming:: bytes received: 88, status: OK tcp{client}: 0x204cb18 2019-01-02 19:04:13.944 luup.io.incoming:: bytes received: 88, status: OK tcp{client}: 0x204cb18 2019-01-02 19:04:13.944 luup.io.incoming:: bytes received: 39, status: OK tcp{client}: 0x204cb18 2019-01-02 19:04:13.944 luup.variable_set:: 34.urn:upnp-org:serviceId:AVTransport.TransportState was: PLAYING now: PAUSED_PLAYBACK #hooks:0 2019-01-02 19:04:13.945 luup.io.incoming:: bytes received: 45, status: OK tcp{client}: 0x204cb18 2019-01-02 19:04:13.946 luup.io.incoming:: bytes received: 88, status: OK tcp{client}: 0x204cb18 2019-01-02 19:04:13.946 luup.io.incoming:: bytes received: 51, status: OK tcp{client}: 0x204cb18 2019-01-02 19:04:13.947 luup.io.incoming:: bytes received: 88, status: OK tcp{client}: 0x204cb18 2019-01-02 19:04:14.043 openLuup.http:: GET /data_request?id=lu_status2&output_format=json&DataVersion=451944650&Timeout=60&MinimumDelay=1500&_=1546451723389 HTTP/1.1 tcp{client}: 0x2269778 2019-01-02 19:04:15.365 openLuup.io.server:: HTTP connection from 192.168.1.100 tcp{client}: 0x1f76178

reneboer · January 3, 2019, 10:04am

Hi,

For some reason the communication to your Hub is failing and that in its turn is creating the LUA error. In this version the LUA error should no longer occur but I do not know why it fails as I cannot reproduce that.

Please let me know. If the problem still exists, please turn the log level to Debug so I can see some more details.

Cheers Rene

RHCPNG · January 4, 2019, 10:37am

[quote=“reneboer, post:117, topic:191192”]Hi,

For some reason the communication to your Hub is failing and that in its turn is creating the LUA error. In this version the LUA error should no longer occur but I do not know why it fails as I cannot reproduce that.

Please let me know. If the problem still exists, please turn the log level to Debug so I can see some more details.

Cheers Rene[/quote]

I have not replaced the file yet, but I have some more logging for you to maybe find the cause of the error.

2019-01-04 11:21:43.768 openLuup.http:: GET /data_request?id=lu_status2&output_format=json&DataVersion=597305303&Timeout=60&MinimumDelay=1500&_=1546597272397 HTTP/1.1 tcp{client}: 0x131f5b8 2019-01-04 11:21:44.793 luup.variable_set:: 10586.urn:micasaverde-com:serviceId:EnergyMetering1.KWHReading was: 1546596993 now: 1546597303 #hooks:0 2019-01-04 11:21:44.826 openLuup.http:: GET /luvd/S_Harmony.xml HTTP/1.1 tcp{client}: 0x115e988 2019-01-04 11:21:44.827 openLuup.http:: request completed (2640 bytes, 1 chunks, 0 ms) tcp{client}: 0x115e988 2019-01-04 11:21:44.828 openLuup.http:: GET /luvd/S_SwitchPower1.xml HTTP/1.1 tcp{client}: 0x1480cf8 2019-01-04 11:21:44.829 openLuup.http:: request completed (1445 bytes, 1 chunks, 0 ms) tcp{client}: 0x1480cf8 2019-01-04 11:21:45.350 openLuup.http:: request completed (15648 bytes, 1 chunks, 1582 ms) tcp{client}: 0x131f5b8 2019-01-04 11:21:45.458 openLuup.http:: GET /data_request?id=lu_status2&output_format=json&DataVersion=597305305&Timeout=60&MinimumDelay=1500&_=1546597272398 HTTP/1.1 tcp{client}: 0x131f5b8 2019-01-04 11:21:46.464 luup.task:: status=4 Squeezebox: : Clearing... 2019-01-04 11:21:50.017 luup.variable_set:: 10586.urn:micasaverde-com:serviceId:ZWaveNetwork1.LastPollSuccess was: 1546596996 now: 1546597306 #hooks:0 2019-01-04 11:21:50.523 openLuup.http:: request completed (15648 bytes, 1 chunks, 5064 ms) tcp{client}: 0x131f5b8 2019-01-04 11:21:50.630 openLuup.http:: GET /data_request?id=lu_status2&output_format=json&DataVersion=597305307&Timeout=60&MinimumDelay=1500&_=1546597272399 HTTP/1.1 tcp{client}: 0x131f5b8 2019-01-04 11:21:58.688 openLuup.historian:: tidying cache... 2019-01-04 11:21:58.735 openLuup.historian:: ...done 2019-01-04 11:21:59.748 luup.variable_set:: 47.urn:rboer-com:serviceId:SolarMeter1.AppMemoryUsed was: 15375 now: 11894 #hooks:0 2019-01-04 11:22:00.720 openLuup.http:: request completed (10872 bytes, 1 chunks, 10090 ms) tcp{client}: 0x131f5b8 2019-01-04 11:22:00.720 luup_log:2: synchronising to on-the-minute 2019-01-04 11:22:00.720 luup.call_timer:: interval: time=2m, days={} 2019-01-04 11:22:00.720 luup_log:2: 2 minute timer launched 2019-01-04 11:22:00.720 luup_log:2: 9 Mb, cpu 0.8%, 0 days 2019-01-04 11:22:00.740 luup.variable_set:: 10425.urn:micasaverde-com:serviceId:ZWaveNetwork1.LastPollSuccess was: 1546597162 now: 1546597317 #hooks:0 2019-01-04 11:22:00.828 openLuup.http:: GET /data_request?id=lu_status2&output_format=json&DataVersion=597305308&Timeout=60&MinimumDelay=1500&_=1546597272400 HTTP/1.1 tcp{client}: 0x131f5b8 2019-01-04 11:22:02.086 openLuup.http:: GET /data_request?id=action&output_format=json&DeviceNum=69&serviceId=urn:upnp-org:serviceId:SwitchPower1&action=SetTarget&newTargetValue=0 HTTP/1.1 tcp{client}: 0x1480cf8 2019-01-04 11:22:02.086 luup.call_action:: 69.urn:upnp-org:serviceId:SwitchPower1.SetTarget 2019-01-04 11:22:02.087 openLuup.http:: request completed (61 bytes, 1 chunks, 0 ms) tcp{client}: 0x1480cf8 2019-01-04 11:22:02.087 luup.variable_set:: 69.urn:rboer-com:serviceId:Harmony1.IconSet was: 0 now: 2 #hooks:0 2019-01-04 11:22:02.087 luup_log:69: Harmony Control_debug: StartActivity, newActivityID : -1 2019-01-04 11:22:02.087 luup_log:69: Harmony Control_debug: StartActivity, ActivityID : -1 2019-01-04 11:22:02.087 luup_log:69: Harmony Control_debug: Sending command cmd=start_activity 2019-01-04 11:22:02.530 luup_log:69: Harmony Control: response is for other message id : 2019-01-04 11:22:04.506 luup.variable_set:: 69.urn:rboer-com:serviceId:Harmony1.LinkStatus was: Ok now: Error #hooks:0 2019-01-04 11:22:04.506 openLuup.context_switch:: ERROR: [string "L_Harmony.lua"]:1301: attempt to concatenate local 'msg' (a nil value) 2019-01-04 11:22:04.506 openLuup.scheduler:: job aborted : [string "L_Harmony.lua"]:1301: attempt to concatenate local 'msg' (a nil value) 2019-01-04 11:22:04.507 openLuup.io.server:: HTTP connection from 192.168.1.100 tcp{client}: 0xd0cf58 2019-01-04 11:22:04.514 openLuup.http:: request completed (19896 bytes, 2 chunks, 3685 ms) tcp{client}: 0x131f5b8 2019-01-04 11:22:04.514 openLuup.http:: GET /data_request?id=action&output_format=json&serviceId=urn:upnp-org:serviceId:SwitchPower1&action=SetTarget&newTargetValue=0&DeviceNum=34 HTTP/1.1 tcp{client}: 0xd0cf58 2019-01-04 11:22:04.515 luup.call_action:: 34.urn:upnp-org:serviceId:SwitchPower1.SetTarget 2019-01-04 11:22:04.515 luup.call_action:: action will be handled by parent: 31 2019-01-04 11:22:04.516 openLuup.http:: request completed (61 bytes, 1 chunks, 1 ms) tcp{client}: 0xd0cf58 2019-01-04 11:22:04.516 luup.io.write:: message length: 37, bytes sent: 37, status: OK tcp{client}: 0x144f568 2019-01-04 11:22:04.519 luup.io.incoming:: bytes received: 37, status: OK tcp{client}: 0x144f568 2019-01-04 11:22:04.520 luup.variable_set:: 34.urn:upnp-org:serviceId:SwitchPower1.Status was: 1 now: 0 #hooks:0 2019-01-04 11:22:04.520 luup.io.incoming:: bytes received: 88, status: OK tcp{client}: 0x144f568 2019-01-04 11:22:04.521 luup.io.incoming:: bytes received: 88, status: OK tcp{client}: 0x144f568 2019-01-04 11:22:04.521 luup.io.incoming:: bytes received: 63, status: OK tcp{client}: 0x144f568 2019-01-04 11:22:04.521 luup.io.incoming:: bytes received: 51, status: OK tcp{client}: 0x144f568 2019-01-04 11:22:04.521 luup.io.incoming:: bytes received: 88, status: OK tcp{client}: 0x144f568 2019-01-04 11:22:04.535 openLuup.http:: GET /data_request?id=user_data&output_format=json&DataVersion=597295699&_=1546597272401 HTTP/1.1 tcp{client}: 0x131f5b8 2019-01-04 11:22:04.656 openLuup.http:: request completed (997754 bytes, 63 chunks, 121 ms) tcp{client}: 0x131f5b8 2019-01-04 11:22:04.776 openLuup.http:: GET /data_request?id=action&output_format=json&serviceId=urn:upnp-org:serviceId:SwitchPower1&action=SetTarget&newTargetValue=0&DeviceNum=34 HTTP/1.1 tcp{client}: 0xd0cf58 2019-01-04 11:22:04.777 luup.call_action:: 34.urn:upnp-org:serviceId:SwitchPower1.SetTarget 2019-01-04 11:22:04.777 luup.call_action:: action will be handled by parent: 31 2019-01-04 11:22:04.778 openLuup.http:: request completed (61 bytes, 1 chunks, 1 ms) tcp{client}: 0xd0cf58 2019-01-04 11:22:04.779 luup.io.write:: message length: 37, bytes sent: 37, status: OK tcp{client}: 0x144f568 2019-01-04 11:22:04.780 luup.io.incoming:: bytes received: 37, status: OK tcp{client}: 0x144f568

RHCPNG · January 4, 2019, 10:45am

With the new file I also get an error.

2019-01-04 11:42:18.058 luup.variable_set:: 10271.urn:micasaverde-com:serviceId:ZWaveNetwork1.LastPollSuccess was: 1546595365 now: 1546598535 #hooks:0 2019-01-04 11:42:18.058 openLuup.io.server:: HTTP connection from 192.168.1.185 tcp{client}: 0x1488e98 2019-01-04 11:42:18.058 openLuup.http:: GET /data_request?id=action&output_format=json&DeviceNum=69&serviceId=urn:upnp-org:serviceId:SwitchPower1&action=SetTarget&newTargetValue=0 HTTP/1.1 tcp{client}: 0x1488e98 2019-01-04 11:42:18.059 luup.call_action:: 69.urn:upnp-org:serviceId:SwitchPower1.SetTarget 2019-01-04 11:42:18.059 openLuup.http:: request completed (61 bytes, 1 chunks, 0 ms) tcp{client}: 0x1488e98 2019-01-04 11:42:18.059 luup.variable_set:: 69.urn:rboer-com:serviceId:Harmony1.IconSet was: 0 now: 2 #hooks:0 2019-01-04 11:42:18.060 luup_log:69: Harmony Control_debug: StartActivity, newActivityID : -1 2019-01-04 11:42:18.060 luup_log:69: Harmony Control_debug: StartActivity, ActivityID : -1 2019-01-04 11:42:18.060 luup_log:69: Harmony Control_debug: Sending command cmd=start_activity 2019-01-04 11:42:18.501 luup_log:69: Harmony Control: response is for other message id : 2019-01-04 11:42:19.347 luup.variable_set:: 69.urn:rboer-com:serviceId:Harmony1.LinkStatus was: Ok now: Error #hooks:0 2019-01-04 11:42:19.347 luup_log:69: Harmony Control: CMD: errcode=50073837, errmsg=Unknown Harmony response 2019-01-04 11:42:19.347 luup_log:69: Harmony Control: task: CMD: Failed sending command start_activity to Harmony Hub - errorcode=5007 2019-01-04 11:42:19.347 luup.task:: status=2 Harmony Control : CMD: Failed sending command start_activity to Harmony Hub - errorcode=50073837, errormessage=Unknown Harmony response 2019-01-04 11:42:19.348 luup_log:69: Harmony Control: StartActivity, ERROR failed to start Activity... errorcode=50073837, errormessag 2019-01-04 11:42:19.348 luup.variable_set:: 69.urn:rboer-com:serviceId:Harmony1.IconSet was: 2 now: 1 #hooks:0 2019-01-04 11:42:19.354 openLuup.http:: request completed (19013 bytes, 2 chunks, 10910 ms) tcp{client}: 0xfc5b88 2019-01-04 11:42:19.354 openLuup.io.server:: HTTP connection from 192.168.1.100 tcp{client}: 0x1635608 2019-01-04 11:42:19.354 openLuup.http:: GET /data_request?id=action&output_format=json&serviceId=urn:upnp-org:serviceId:SwitchPower1&action=SetTarget&newTargetValue=0&DeviceNum=34 HTTP/1.1 tcp{client}: 0x1635608 2019-01-04 11:42:19.354 luup.call_action:: 34.urn:upnp-org:serviceId:SwitchPower1.SetTarget 2019-01-04 11:42:19.354 luup.call_action:: action will be handled by parent: 31 2019-01-04 11:42:19.355 openLuup.http:: request completed (61 bytes, 1 chunks, 1 ms) tcp{client}: 0x1635608 2019-01-04 11:42:19.356 luup.io.write:: message length: 37, bytes sent: 37, status: OK tcp{client}: 0x169a2d8 2019-01-04 11:42:19.358 luup.io.incoming:: bytes received: 37, status: OK tcp{client}: 0x169a2d8 2019-01-04 11:42:19.359 luup.variable_set:: 34.urn:upnp-org:serviceId:SwitchPower1.Status was: 1 now: 0 #hooks:0 2019-01-04 11:42:19.359 luup.io.incoming:: bytes received: 88, status: OK tcp{client}: 0x169a2d8 2019-01-04 11:42:19.359 luup.io.incoming:: bytes received: 88, status: OK tcp{client}: 0x169a2d8 2019-01-04 11:42:19.360 luup.io.incoming:: bytes received: 51, status: OK tcp{client}: 0x169a2d8 2019-01-04 11:42:19.360 luup.io.incoming:: bytes received: 88, status: OK tcp{client}: 0x169a2d8 2019-01-04 11:42:19.532 openLuup.http:: GET /data_request?id=lu_status2&output_format=json&DataVersion=598533315&Timeout=60&MinimumDelay=1500&_=1546597272823 HTTP/1.1 tcp{client}: 0xfc5b88 2019-01-04 11:42:21.040 openLuup.http:: request completed (8562 bytes, 1 chunks, 1507 ms) tcp{client}: 0xfc5b88 2019-01-04 11:42:21.145 openLuup.http:: GET /data_request?id=lu_status2&output_format=json&DataVersion=598533316&Timeout=60&MinimumDelay=1500&_=1546597272824 HTTP/1.1 tcp{client}: 0xfc5b88 2019-01-04 11:42:22.651 luup.variable_set:: 69.urn:rboer-com:serviceId:Harmony1.IconSet was: 1 now: 0 #hooks:0 2019-01-04 11:42:23.155 openLuup.http:: request completed (10900 bytes, 1 chunks, 2010 ms) tcp{client}: 0xfc5b88

RHCPNG · January 14, 2019, 9:03am

Hi Rene,

Can you identify additional info from the log files?