Author Topic: Problem or issues reports  (Read 15763 times)

Offline reneboer

  • Moderator
  • Hero Member
  • *****
  • Posts: 1523
  • Karma: +101/-31
Re: Problem or issues reports
« Reply #120 on: January 14, 2019, 04:57:35 am »
Hi,

No sorry. I do not understand the error message that comes back. It should be numbers in used for HTTP errors (400 or 500 range). What you can try is in the L_Harmony.lua file uncomment the debug lines 1100 and 1127 in the low level wait_response and send_request functions so it shows what goes over the wire to and from the hub.

Cheers Rene
2xVeraLite, VeraEdge, openLuup, ALTUI, 20 switches, 10 dimmers, 20 sensors, 10 scene controllers, 1 Harmony Hub, many plug-ins. Not enough time.

Offline RHCPNG

  • Full Member
  • ***
  • Posts: 190
  • Karma: +6/-0
Re: Problem or issues reports
« Reply #121 on: January 28, 2019, 03:21:23 am »
Hi,

No sorry. I do not understand the error message that comes back. It should be numbers in used for HTTP errors (400 or 500 range). What you can try is in the L_Harmony.lua file uncomment the debug lines 1100 and 1127 in the low level wait_response and send_request functions so it shows what goes over the wire to and from the hub.

Cheers Rene

Hi Rene,

Sorry for the late reply. I have uncommented the debug lines and this is the result:

Code: [Select]
2019-01-28 09:13:44.875   luup_log:69: Harmony Control_debug: StartActivity, newActivityID : -1
2019-01-28 09:13:44.875   luup_log:69: Harmony Control_debug: StartActivity, ActivityID : -1
2019-01-28 09:13:44.875   luup_log:69: Harmony Control_debug: Sending command cmd=start_activity
2019-01-28 09:13:44.927   luup_log:69: Harmony Control_debug: Sending command : {"hubId":11770187,"timeout":30,"hbus":{"cmd":"vnd.logitech.connect/vnd.logitech.statedigest?get","id":3,"params":{"verb":"get","format":"json"}}}
2019-01-28 09:13:44.971   luup_log:69: Harmony Control_debug: Received response: {"cmd":"vnd.logitech.connect\/vnd.logitech.statedigest?get","code":200,"id":3,"msg":"OK","data":{"sleepTimerId":-1,"runningZoneList":[],"contentVersion":66,"activityId":"30219462","syncStatus":0,"time":1548663209,"stateVersion":224,"tzOffset":"3600","mode":3,"hubSwVersion":"4.15.206","deviceSetupState":[],"tzoffset":"3600","isSetupComplete":true,"discoveryServer":"http:\/\/svcs.myharmony.com\/Discovery\/Discovery.svc","sequence":false,"discoveryServerCF":"https:\/\/cf-svcs.myharmony.com\/Discovery\/Discovery.svc","updates":[],"activityStatus":2,"wifiStatus":1,"tz":"CET-1CEST,M3.5.0,M10.4.0\/3","activitySetupState":false,"runningActivityList":"30219462","hubUpdate":false,"configVersion":129,"accountId":"10543920"}}
2019-01-28 09:13:44.971   luup_log:69: Harmony Control_debug: Sending command : {"hubId":11770187,"timeout":30,"hbus":{"cmd":"harmony.activityengine?runactivity","id":4,"params":{"async": "true","timestamp": 0,"args":{"rule":"start"},"activityId":"-1"}}}
2019-01-28 09:13:45.299   luup_log:69: Harmony Control_debug: Received response: {"cmd":"harmony.activityengine?runactivity","code":200,"id":4,"msg":"OK"}
2019-01-28 09:13:45.318   luup_log:69: Harmony Control_debug: Received response: {"type":"connect.stateDigest?notify","data":{"sleepTimerId":-1,"runningZoneList":[],"contentVersion":66,"activityId":"30219462","syncStatus":0,"time":1548663209,"stateVersion":225,"tzOffset":"3600","mode":3,"hubSwVersion":"4.15.206","deviceSetupState":[],"tzoffset":"3600","isSetupComplete":true,"discoveryServer":"http:\/\/svcs.myharmony.com\/Discovery\/Discovery.svc","sequence":false,"discoveryServerCF":"https:\/\/cf-svcs.myharmony.com\/Discovery\/Discovery.svc","updates":[],"activityStatus":3,"wifiStatus":1,"tz":"CET-1CEST,M3.5.0,M10.4.0\/3","activitySetupState":false,"runningActivityList":"30219462","hubUpdate":false,"configVersion":129,"accountId":"10543920"}}
2019-01-28 09:13:45.318   luup_log:69: Harmony Control: response is for other message id :
2019-01-28 09:13:45.599   luup_log:69: Harmony Control_debug: Received response: {"cmd":"harmony.engine?startActivity","code":100,"id":4,"msg":"Continue","data":{"done":"1","total":"2","deviceId":"50073836"}}
2019-01-28 09:13:47.523   luup_log:69: Harmony Control_debug: Received response: {"cmd":"harmony.engine?startActivity","code":50073837,"id":4,"data":{"done":"2","total":"2","deviceId":"50073837"}}
2019-01-28 09:13:47.547   luup.variable_set:: 69.urn:rboer-com:serviceId:Harmony1.LinkStatus was: Ok now: Error #hooks:0
2019-01-28 09:13:47.547   luup_log:69: Harmony Control: CMD: errcode=50073837, errmsg=Unknown Harmony response
2019-01-28 09:13:47.548   luup_log:69: Harmony Control: task: CMD: Failed sending command start_activity to Harmony Hub - errorcode=5007
2019-01-28 09:13:47.548   luup.task:: status=2 Harmony Control : CMD: Failed sending command start_activity to Harmony Hub - errorcode=50073837, errormessage=Unknown Harmony response
2019-01-28 09:13:47.548   luup_log:69: Harmony Control: StartActivity, ERROR failed to start Activity... errorcode=50073837, errormessag
2019-01-28 09:13:47.548   luup.variable_set:: 69.urn:rboer-com:serviceId:Harmony1.IconSet was: 2 now: 1 #hooks:0
2019-01-28 09:13:47.548   openLuup.io.server:: HTTP connection from 192.168.1.100 tcp{client}: 0x1f4d178
2019-01-28 09:13:47.552   openLuup.http:: request completed (10897 bytes, 1 chunks, 4083 ms) tcp{client}: 0x2a6ff28
2019-01-28 09:13:47.552   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}: 0x1f4d178
2019-01-28 09:13:47.552   luup.call_action:: 34.urn:upnp-org:serviceId:SwitchPower1.SetTarget
2019-01-28 09:13:47.552   luup.call_action:: action will be handled by parent: 31
2019-01-28 09:13:47.554   openLuup.http:: request completed (61 bytes, 1 chunks, 1 ms) tcp{client}: 0x1f4d178
2019-01-28 09:13:47.554   luup.io.write:: message length: 37, bytes sent: 37, status: OK tcp{client}: 0x26ec528
2019-01-28 09:13:47.557   luup.io.incoming:: bytes received: 37, status: OK tcp{client}: 0x26ec528
2019-01-28 09:13:47.557   luup.variable_set:: 34.urn:upnp-org:serviceId:SwitchPower1.Status was: 1 now: 0 #hooks:0
2019-01-28 09:13:47.558   luup.io.incoming:: bytes received: 88, status: OK tcp{client}: 0x26ec528
2019-01-28 09:13:47.558   luup.io.incoming:: bytes received: 88, status: OK tcp{client}: 0x26ec528
2019-01-28 09:13:47.558   luup.io.incoming:: bytes received: 63, status: OK tcp{client}: 0x26ec528
2019-01-28 09:13:47.559   luup.io.incoming:: bytes received: 51, status: OK tcp{client}: 0x26ec528
2019-01-28 09:13:47.559   luup.io.incoming:: bytes received: 88, status: OK tcp{client}: 0x26e

I do not really know how to interpret this, but it seems that Harmony Hub is giving me a strange code?

BTW Your custom xml you uploaded is probably overwritten by the new base version, because now the action setTarget doesn't work again. Can I replace it without problems again with the new base version?
« Last Edit: January 28, 2019, 03:26:12 am by RHCPNG »

Offline reneboer

  • Moderator
  • Hero Member
  • *****
  • Posts: 1523
  • Karma: +101/-31
Re: Problem or issues reports
« Reply #122 on: January 28, 2019, 05:01:58 am »
Hi

This is the offending response.
Code: [Select]
luup_log:69: Harmony Control_debug: Received response: {"cmd":"harmony.engine?startActivity","code":50073837,"id":4,"data":{"done":"2","total":"2","deviceId":"50073837"}}
The code value should be 200 that the activity is started. It is odd your Hub reports this value, as mine has the same firmware version 4.15.206, and I'm not seeing this. You can try the  new plugin 3.1 version that is availble on the Mios App market as of today. There I changed some of this logic and this return code should not cause issues (lucky coincidence).

Keep me posted.

Cheers Rene
2xVeraLite, VeraEdge, openLuup, ALTUI, 20 switches, 10 dimmers, 20 sensors, 10 scene controllers, 1 Harmony Hub, many plug-ins. Not enough time.