192.168.86.45 is the switch that’s “misbehaving” or “lost” this time around. It’s not always this one though. Sometimes it’s the other one (192.168.86.30)
[e71e77ca-df4e-49df-b2d5-0674b809cbfc] 11:20:40 AM: debug Harmony - Current Activities:
[e71e77ca-df4e-49df-b2d5-0674b809cbfc] 11:20:40 AM: debug getChildDevices(false), children=8
[e71e77ca-df4e-49df-b2d5-0674b809cbfc] 11:20:40 AM: debug Harmony - response body: {“hubs”:{“4921471”:{“status”:200,“message”:“OK”,“response”:{“code”:“200”,“msg”:“OK”,“data”:{“accountId”:“4099880”,“configVersion”:593,“sleepTimerId”:-1,“activityStatus”:0,“version”:562,“contentVersion”:67,“currentAvActivity”:“-1”,“syncStatus”:0,“currentActivities”:}}}}}
[6a1e5711-6211-46fb-b952-7c5ad04778ff] 11:20:26 AM: debug Call subscribe on aa18d22a-021a-4620-883f-0a3a3bceb64f
[6a1e5711-6211-46fb-b952-7c5ad04778ff] 11:20:26 AM: debug Call subscribe on 97101755-7680-4865-a52b-e1c7f1b6ac2a
[6a1e5711-6211-46fb-b952-7c5ad04778ff] 11:20:26 AM: debug getChildDevices(true), children=2
[6a1e5711-6211-46fb-b952-7c5ad04778ff] 11:20:26 AM: debug Send discover command
[6a1e5711-6211-46fb-b952-7c5ad04778ff] 11:20:26 AM: debug subscribeToDevices() called
[aa18d22a-021a-4620-883f-0a3a3bceb64f] 11:20:26 AM: debug Executing subscribe(192.168.86.30:49154)
[aa18d22a-021a-4620-883f-0a3a3bceb64f] 11:20:26 AM: debug Using ip: 192.168.86.30 and port: 49154 for device: aa18d22a-021a-4620-883f-0a3a3bceb64f
[aa18d22a-021a-4620-883f-0a3a3bceb64f] 11:20:26 AM: debug calling getHostAddress()
[97101755-7680-4865-a52b-e1c7f1b6ac2a] 11:20:26 AM: debug Executing subscribe(192.168.86.45:49152)
[97101755-7680-4865-a52b-e1c7f1b6ac2a] 11:20:26 AM: debug Using ip: 192.168.86.45 and port: 49152 for device: 97101755-7680-4865-a52b-e1c7f1b6ac2a
[97101755-7680-4865-a52b-e1c7f1b6ac2a] 11:20:26 AM: debug calling getHostAddress()
[e6c9ec97-dd75-4a9f-b0f8-3fb2e09bab9c] 11:19:46 AM: debug Parsing ‘index:15, mac:94103E5034AD, ip:C0A85624, port:C001, requestId:9111ce80-0902-4bd9-96d7-1c01a8f0e127, headers:SFRUUC8xLjEgMjAwIE9LDQpDT05URU5UClgtVXNlci1BZ2VudDogcmVkc29uaWM=, body:PHM6RW52ZWxvcGUgeG1sbnM6cz0iaHR0cDovL3NjaGVtYXMueG1sc29hcC5vcmcvc29hcC9lbnZlbG9wZS8iIHM6ZW5jb2RpbmdTdHlsZT0iaHR0cDovL3NjaGVtYXMueG1sc29hcC5vcmcvc29hcC9lbmNvZGluZy8iPjxzOkJvZHk+Cjx1OlRpbWVTeW5jUmVzcG9uc2UgeG1sbnM6dT0idXJuOkJlbGtpbjpzZXJ2aWNlOnRpbWVzeW5jOjEiPg0KPHN0YXR1cz5zdWNjZXNzPC9zdGF0dXM+DQo8L3U6VGltZVN5bmNSZXNwb25zZT4NCjwvczpCb2R5PiA8L3M6RW52ZWxvcGU+’
[e6c9ec97-dd75-4a9f-b0f8-3fb2e09bab9c] 11:19:46 AM: debug Using ip: C0A85624 and port: C001 for device: e6c9ec97-dd75-4a9f-b0f8-3fb2e09bab9c
[e6c9ec97-dd75-4a9f-b0f8-3fb2e09bab9c] 11:19:46 AM: debug Executing ‘timeSyncResponse()’
[e6c9ec97-dd75-4a9f-b0f8-3fb2e09bab9c] 11:19:46 AM: trace Got TimeSyncRequest
[e6c9ec97-dd75-4a9f-b0f8-3fb2e09bab9c] 11:19:46 AM: debug Parsing ‘index:06, mac:94103E5034AD, headers:Tk9USUZZIC8gSFRUUC8xLjENCkhPU1Q6IDE5Mi4xNjguODYuNTI6Mzk1MDANCkNPTlRFTlQtVFlQRTogdGV4dC94bWw7IGNoYXJzZXQ9InV0Zi04Ig0KQ09OVEVOVC1MRU5HVEg6IDE0MA0KTlQ6IHVwbnA6ZXZlbnQNCk5UUzogdXBucDpwcm9wY2hhbmdlDQpTSUQ6IHV1aWQ6MTA4OTNhNDYtMWRkMi0xMWIyLWEzOGEtZGMxYjUxMWQ0Y2UyDQpTRVE6IDE=, body:PGU6cHJvcGVydHlzZXQgeG1sbnM6ZT0idXJuOnNjaGVtYXMtdXBucC1vcmc6ZXZlbnQtMS0wIj4KPGU6cHJvcGVydHk+CjxUaW1lU3luY1JlcXVlc3Q+MDwvVGltZVN5bmNSZXF1ZXN0Pgo8L2U6cHJvcGVydHk+CjwvZTpwcm9wZXJ0eXNldD4KCg0=’
[e6c9ec97-dd75-4a9f-b0f8-3fb2e09bab9c] 11:19:43 AM: trace Notify: BinaryState = off
[e6c9ec97-dd75-4a9f-b0f8-3fb2e09bab9c] 11:19:43 AM: debug Parsing ‘index:06, mac:94103E5034AD, headers:Tk9USUZZIC8gSFRUUC8xLjENCkhPU1Q6IDE5Mi4xNjguODYuNTI6Mzk1MDANCkNPTlRFTlQtVFlQRTogdGV4dC94bWw7IGNoYXJzZXQ9InV0Zi04Ig0KQ09OVEVOVC1MRU5HVEg6IDEzMg0KTlQ6IHVwbnA6ZXZlbnQNCk5UUzogdXBucDpwcm9wY2hhbmdlDQpTSUQ6IHV1aWQ6MTA4OTNhNDYtMWRkMi0xMWIyLWEzOGEtZGMxYjUxMWQ0Y2UyDQpTRVE6IDA=, body:PGU6cHJvcGVydHlzZXQgeG1sbnM6ZT0idXJuOnNjaGVtYXMtdXBucC1vcmc6ZXZlbnQtMS0wIj4KPGU6cHJvcGVydHk+CjxCaW5hcnlTdGF0ZT4wPC9CaW5hcnlTdGF0ZT4KPC9lOnByb3BlcnR5Pgo8L2U6cHJvcGVydHlzZXQ+CgoN’
[e6c9ec97-dd75-4a9f-b0f8-3fb2e09bab9c] 11:19:43 AM: debug Parsing ‘index:15, mac:94103E5034AD, ip:C0A85624, port:C001, requestId:26cda458-6a53-459f-a859-ac51b8a7e836, headers:SFRUUC8xLjEgMjAwIE9LDQpEQVRFOiBXZWQsIDA2IEZlYiAyMDE5IDE2OjIwOjA2IEdNVA0KU0VSVkVSOiBVbnNwZWNpZmllZCwgVVBuUC8xLjAsIFVuc3BlY2lmaWVkDQpDT05URU5ULUxFTkdUSDogMA0KWC1Vc2VyLUFnZW50OiByZWRzb25pYw0KU0lEOiB1dWlkOjEwODkzYTQ2LTFkZDItMTFiMi1hMzhhLWRjMWI1MTFkNGNlMg0KVElNRU9VVDogU2Vjb25kLTU0MDA=, body:’
[e6c9ec97-dd75-4a9f-b0f8-3fb2e09bab9c] 11:19:43 AM: debug Using ip: C0A85624 and port: C001 for device: e6c9ec97-dd75-4a9f-b0f8-3fb2e09bab9c
[e6c9ec97-dd75-4a9f-b0f8-3fb2e09bab9c] 11:19:43 AM: debug Executing ‘resubscribe()’
[56cea51f-3f81-49a3-a325-da3ecd260cea] 11:19:35 AM: error | NST Manager (v5.5.8) | NST PROTECT DEVICE UPDATE REQUIRED: Protect QrE6AMnZCXsXq6Nu8nKTh1lR1QneXV2T (v5.3.4) | REQUIRED: (v5.4.2) | Update the Device code to the latest software in the IDE
[56cea51f-3f81-49a3-a325-da3ecd260cea] 11:19:35 AM: error | NST Manager (v5.5.8) | NST PROTECT DEVICE UPDATE REQUIRED: Protect QrE6AMnZCXsR7eLAiwZnqVlR1QneXV2T (v5.3.4) | REQUIRED: (v5.4.2) | Update the Device code to the latest software in the IDE
[56cea51f-3f81-49a3-a325-da3ecd260cea] 11:19:35 AM: error | NST Manager (v5.5.8) | NST PROTECT DEVICE UPDATE REQUIRED: Protect QrE6AMnZCXsZzr3aoyoD3VlR1QneXV2T (v5.3.4) | REQUIRED: (v5.4.2) | Update the Device code to the latest software in the IDE
[56cea51f-3f81-49a3-a325-da3ecd260cea] 11:19:35 AM: error | NST Manager (v5.5.8) | NST PROTECT DEVICE UPDATE REQUIRED: Protect QrE6AMnZCXv9nszrxIcmY1lR1QneXV2T (v5.3.4) | REQUIRED: (v5.4.2) | Update the Device code to the latest software in the IDE
[56cea51f-3f81-49a3-a325-da3ecd260cea] 11:19:32 AM: info ||| NST Manager (v5.5.8) | API Device Data HAS Changed (POLL(ASYNC))
[56cea51f-3f81-49a3-a325-da3ecd260cea] 11:19:32 AM: info ||| NST Manager (v5.5.8) | Updating Structure Data (Last Updated: 180 seconds ago) | Updating Device Data (Last Updated: 180 seconds ago) (async)
[4a50d167-634b-4880-ae26-f5a9dc22261c] 11:19:25 AM: trace Notify: BinaryState = off
[4a50d167-634b-4880-ae26-f5a9dc22261c] 11:19:25 AM: debug Parsing ‘index:03, mac:94103E5034BD, headers:Tk9USUZZIC8gSFRUUC8xLjENCkhPU1Q6IDE5Mi4xNjguODYuNTI6Mzk1MDANCkNPTlRFTlQtVFlQRTogdGV4dC94bWw7IGNoYXJzZXQ9InV0Zi04Ig0KQ09OVEVOVC1MRU5HVEg6IDEzMg0KTlQ6IHVwbnA6ZXZlbnQNCk5UUzogdXBucDpwcm9wY2hhbmdlDQpTSUQ6IHV1aWQ6ZjgyMGZjNDYtMWRkMS0xMWIyLTk1N2ItZDk3ZDMwYjdkOTY0DQpTRVE6IDA=, body:PGU6cHJvcGVydHlzZXQgeG1sbnM6ZT0idXJuOnNjaGVtYXMtdXBucC1vcmc6ZXZlbnQtMS0wIj4KPGU6cHJvcGVydHk+CjxCaW5hcnlTdGF0ZT4wPC9CaW5hcnlTdGF0ZT4KPC9lOnByb3BlcnR5Pgo8L2U6cHJvcGVydHlzZXQ+CgoN’
[4a50d167-634b-4880-ae26-f5a9dc22261c] 11:19:25 AM: debug Using ip: C0A8562B and port: C001 for device: 4a50d167-634b-4880-ae26-f5a9dc22261c
[4a50d167-634b-4880-ae26-f5a9dc22261c] 11:19:25 AM: debug Executing ‘resubscribe()’
[4a50d167-634b-4880-ae26-f5a9dc22261c] 11:18:56 AM: debug Parsing ‘index:15, mac:94103E5034BD, ip:C0A8562B, port:C001, requestId:cd00808e-eaed-41ff-86ed-2da835264e73, headers:SFRUUC8xLjEgMjAwIE9LDQpDT05URU5UViIDIwMTkgMTY6MTg6NTYgR01UDQpFWFQ6DQpTRVJWRVI6IFVuc3BlY2lmaWVkLCBVUG5QLzEuMCwgVW5zcGVjaWZpZWQNClgtVXNlci1BZ2VudDogcmVkc29uaWM=, body:PHM6RW52ZWxvcGUgeG1sbnM6cz0iaHR0cDovL3NjaGVtYXMueG1sc29hcC5vcmcvc29hcC9lbnZlbG9wZS8iIHM6ZW5jb2RpbmdTdHlsZT0iaHR0cDovL3NjaGVtYXMueG1sc29hcC5vcmcvc29hcC9lbmNvZGluZy8iPjxzOkJvZHk+Cjx1OlRpbWVTeW5jUmVzcG9uc2UgeG1sbnM6dT0idXJuOkJlbGtpbjpzZXJ2aWNlOnRpbWVzeW5jOjEiPg0KPHN0YXR1cz5zdWNjZXNzPC9zdGF0dXM+DQo8L3U6VGltZVN5bmNSZXNwb25zZT4NCjwvczpCb2R5PiA8L3M6RW52ZWxvcGU+’
[4a50d167-634b-4880-ae26-f5a9dc22261c] 11:18:56 AM: debug Notify: TimeZoneNotification = 13
[4a50d167-634b-4880-ae26-f5a9dc22261c] 11:18:56 AM: debug Parsing ‘index:05, mac:94103E5034BD, headers:Tk9USUZZIC8gSFRUUC8xLjENCkhPU1Q6IDE5Mi4xNjguODYuNTI6Mzk1MDANCkNPTlRFTlQtVFlQRTogdGV4dC94bWw7IGNoYXJzZXQ9InV0Zi04Ig0KQ09OVEVOVC1MRU5HVEg6IDE1MQ0KTlQ6IHVwbnA6ZXZlbnQNCk5UUzogdXBucDpwcm9wY2hhbmdlDQpTSUQ6IHV1aWQ6OTY1YjYyYjAtMWRkMS0xMWIyLTk1N2EtZDk3ZDMwYjdkOTY0DQpTRVE6IDI=, body:PGU6cHJvcGVydHlzZXQgeG1sbnM6ZT0idXJuOnNjaGVtYXMtdXBucC1vcmc6ZXZlbnQtMS0wIj4KPGU6cHJvcGVydHk+CjxUaW1lWm9uZU5vdGlmaWNhdGlvbj4xMzwvVGltZVpvbmVOb3RpZmljYXRpb24+CjwvZTpwcm9wZXJ0eT4KPC9lOnByb3BlcnR5c2V0PgoKDQ==’
[4a50d167-634b-4880-ae26-f5a9dc22261c] 11:18:55 AM: debug Using ip: C0A8562B and port: C001 for device: 4a50d167-634b-4880-ae26-f5a9dc22261c
[4a50d167-634b-4880-ae26-f5a9dc22261c] 11:18:55 AM: debug Executing ‘timeSyncResponse()’
[4a50d167-634b-4880-ae26-f5a9dc22261c] 11:18:55 AM: trace Got TimeSyncRequest
[4a50d167-634b-4880-ae26-f5a9dc22261c] 11:18:55 AM: debug Parsing ‘index:03, mac:94103E5034BD, headers:Tk9USUZZIC8gSFRUUC8xLjENCkhPU1Q6IDE5Mi4xNjguODYuNTI6Mzk1MDANCkNPTlRFTlQtVFlQRTogdGV4dC94bWw7IGNoYXJzZXQ9InV0Zi04Ig0KQ09OVEVOVC1MRU5HVEg6IDE0MA0KTlQ6IHVwbnA6ZXZlbnQNCk5UUzogdXBucDpwcm9wY2hhbmdlDQpTSUQ6IHV1aWQ6OTY1YjYyYjAtMWRkMS0xMWIyLTk1N2EtZDk3ZDMwYjdkOTY0DQpTRVE6IDE=, body:PGU6cHJvcGVydHlzZXQgeG1sbnM6ZT0idXJuOnNjaGVtYXMtdXBucC1vcmc6ZXZlbnQtMS0wIj4KPGU6cHJvcGVydHk+CjxUaW1lU3luY1JlcXVlc3Q+MDwvVGltZVN5bmNSZXF1ZXN0Pgo8L2U6cHJvcGVydHk+CjwvZTpwcm9wZXJ0eXNldD4KCg0=’
[aa18d22a-021a-4620-883f-0a3a3bceb64f] 11:18:40 AM: debug Using ip: 192.168.86.30 and port: 49154 for device: aa18d22a-021a-4620-883f-0a3a3bceb64f
[aa18d22a-021a-4620-883f-0a3a3bceb64f] 11:18:40 AM: debug Executing WeMo Switch ‘subscribe’, then ‘timeSyncResponse’, then ‘poll’
[97101755-7680-4865-a52b-e1c7f1b6ac2a] 11:18:19 AM: debug Using ip: 192.168.86.45 and port: 49152 for device: 97101755-7680-4865-a52b-e1c7f1b6ac2a
[97101755-7680-4865-a52b-e1c7f1b6ac2a] 11:18:19 AM: debug Executing WeMo Switch ‘subscribe’, then ‘timeSyncResponse’, then ‘poll’
[e6c9ec97-dd75-4a9f-b0f8-3fb2e09bab9c] 11:18:13 AM: trace Got SetBinaryStateResponse = Error
[e6c9ec97-dd75-4a9f-b0f8-3fb2e09bab9c] 11:18:13 AM: debug Parsing ‘index:16, mac:94103E5034AD, ip:C0A85624, port:C001, requestId:b8870770-b6df-485c-8572-dcfc9c8f900b, headers:SFRUUC8xLjEgMjAwIE9LDQpDT05URU5ULUxFTkdUSDogMjg5DQpDT05URU5ULVRZUEU6IHRleHQveG1sOyBjaGFyc2V0PSJ1dGYtOCINCkRBVEU6IFdlZCwgMDYgRmViIDIwMTkgMTY6MTg6MzYgR01UDQpFWFQ6DQpTRVJWRVI6IFVuc3BlY2lmaWVkLCBVUG5QLzEuMCwgVW5zcGVjaWZpZWQNClgtVXNlci1BZ2VudDogcmVkc29uaWM=, body:PHM6RW52ZWxvcGUgeG1sbnM6cz0iaHR0cDovL3NjaGVtYXMueG1sc29hcC5vcmcvc29hcC9lbnZlbG9wZS8iIHM6ZW5jb2RpbmdTdHlsZT0iaHR0cDovL3NjaGVtYXMueG1sc29hcC5vcmcvc29hcC9lbmNvZGluZy8iPjxzOkJvZHk+Cjx1OlNldEJpbmFyeVN0YXRlUmVzcG9uc2UgeG1sbnM6dT0idXJuOkJlbGtpbjpzZXJ2aWNlOmJhc2ljZXZlbnQ6MSI+DQo8QmluYXJ5U3RhdGU+RXJyb3I8L0JpbmFyeVN0YXRlPg0KPC91OlNldEJpbmFyeVN0YXRlUmVzcG9uc2U+DQo8L3M6Qm9keT4gPC9zOkVudmVsb3BlPg==’
[4a50d167-634b-4880-ae26-f5a9dc22261c] 11:18:13 AM: trace Got SetBinaryStateResponse = Error
[4a50d167-634b-4880-ae26-f5a9dc22261c] 11:18:13 AM: debug Parsing ‘index:15, mac:94103E5034BD, ip:C0A8562B, port:C001, requestId:4b285dd1-68e8-4063-a4a4-dafa9450cfaa, headers:SFRUUC8xLjEgMjAwIE9LDQpDT05URU5ULUxFTkdUSDogMjg5DQpDT05URU5ULVRZUEU6IHRleHQveG1sOyBjaGFyc2V0PSJ1dGYtOCINCkRBVEU6IFdlZCwgMDYgRmViIDIwMTkgMTY6MTg6MTMgR01UDQpFWFQ6DQpTRVJWRVI6IFVuc3BlY2lmaWVkLCBVUG5QLzEuMCwgVW5zcGVjaWZpZWQNClgtVXNlci1BZ2VudDogcmVkc29uaWM=, body:PHM6RW52ZWxvcGUgeG1sbnM6cz0iaHR0cDovL3NjaGVtYXMueG1sc29hcC5vcmcvc29hcC9lbnZlbG9wZS8iIHM6ZW5jb2RpbmdTdHlsZT0iaHR0cDovL3NjaGVtYXMueG1sc29hcC5vcmcvc29hcC9lbmNvZGluZy8iPjxzOkJvZHk+Cjx1OlNldEJpbmFyeVN0YXRlUmVzcG9uc2UgeG1sbnM6dT0idXJuOkJlbGtpbjpzZXJ2aWNlOmJhc2ljZXZlbnQ6MSI+DQo8QmluYXJ5U3RhdGU+RXJyb3I8L0JpbmFyeVN0YXRlPg0KPC91OlNldEJpbmFyeVN0YXRlUmVzcG9uc2U+DQo8L3M6Qm9keT4gPC9zOkVudmVsb3BlPg==’
[1f976696-fa6f-4b9b-9e26-a24e0268b0dc] 11:18:12 AM: debug off()
[1f976696-fa6f-4b9b-9e26-a24e0268b0dc] 11:18:12 AM: trace stopAction()
[1f976696-fa6f-4b9b-9e26-a24e0268b0dc] 11:18:12 AM: trace switchStateOK = true
[1f976696-fa6f-4b9b-9e26-a24e0268b0dc] 11:18:12 AM: trace ambientLightOK = true
[1f976696-fa6f-4b9b-9e26-a24e0268b0dc] 11:18:12 AM: trace timeOk = true
[1f976696-fa6f-4b9b-9e26-a24e0268b0dc] 11:18:12 AM: trace timeWindowStop = null
[1f976696-fa6f-4b9b-9e26-a24e0268b0dc] 11:18:12 AM: trace timeWindowStart = null
[1f976696-fa6f-4b9b-9e26-a24e0268b0dc] 11:18:12 AM: trace daysOk = true
[1f976696-fa6f-4b9b-9e26-a24e0268b0dc] 11:18:12 AM: trace modeOk = true
[1f976696-fa6f-4b9b-9e26-a24e0268b0dc] 11:18:12 AM: trace stopHandler(switch: off)
[e6c9ec97-dd75-4a9f-b0f8-3fb2e09bab9c] 11:18:13 AM: debug Using ip: C0A85624 and port: C001 for device: e6c9ec97-dd75-4a9f-b0f8-3fb2e09bab9c
[e6c9ec97-dd75-4a9f-b0f8-3fb2e09bab9c] 11:18:12 AM: debug Executing ‘off’
[4a50d167-634b-4880-ae26-f5a9dc22261c] 11:18:12 AM: debug Using ip: C0A8562B and port: C001 for device: 4a50d167-634b-4880-ae26-f5a9dc22261c
[4a50d167-634b-4880-ae26-f5a9dc22261c] 11:18:12 AM: debug Executing ‘off’
11:17:40 AM: info Waiting on events…
11:17:40 AM: info For past logs for individual things go to the My Devices section, find the device and click on the Events link on the device information page.
11:17:40 AM: info This console provides live logging of your SmartThings.