Bob,
Has anything recently changed with network communications? I have started seeing a lot of issues with ISY subscription errors and missed status reports:
Jun-21 00:06:29 ISYInsteon Restart : ->
Jun-21 00:06:29 ISYInsteon Restart : Last message received before restart
Jun-21 00:06:29 ISYInsteon Bottom of subscription thread loop
Jun-21 00:06:29 ISYInsteon Attemp to close TcpClient
Jun-21 00:06:29 ISYInsteon Read exited with False
Jun-21 00:06:29 ISYInsteon read : The subscription connection to ISY has failed.
Jun-21 00:06:49 ISYInsteon ProcessCommand : ISY network communication failure: missed status report for device Basement/KPL NS (Light). (34 DE 4E 1)
Jun-21 00:06:44 ISYInsteon ProcessCommand : ISY network communication failure: missed status report for device Basement/KPL NS (Light). (34 DE 4E 1)
Jun-21 00:06:38 ISYInsteon ProcessCommand : ISY network communication failure: missed status report for device Basement/KPL NS (Light). (34 DE 4E 1)
Jun-21 00:06:33 ISYInsteon ProcessCommand : ISY network communication failure: missed status report for device Basement/KPL NS (Light). (34 DE 4E 1)
Jun-21 00:06:29 ISYInsteon read : The subscription connection to ISY has failed.
I haven't changed anything on the ISY and if I control devices from it everything works fine. I normally have to reboot the ISY and homeseer or the ISY plugin several times before I can get it to start working again. I don't seem to be having any issues with other devices on my network so I'm not sure where to look. I'm going to see if I can find an old version of the plugin to see if that corrects the issue. I'm currently on 3.0.1.6 and have seen the issue off and on for a bit, but it has been worse the last few days. Yesterday I had to cycle everything multiple times before I could get things working.
Here is a snippet with debugging turned on just trying to turn a device on:
Jun-21 00:35:08 ISYInsteon QueueLoop : Command for KPL Scenes/Dryer LED finished and removed from queue. Queue size = 1
Jun-21 00:35:08 ISYInsteon Command Queue is now empty.
Jun-21 00:35:08 ISYInsteon SetIOMulti : SetIOMulti set 86 to value 0
Jun-21 00:35:08 ISYInsteon Add : Command for KPL Scenes/Dryer LED added to queue. Queue size = 1
Jun-21 00:35:08 ISYInsteon Control : Exit control function
Jun-21 00:35:08 ISYInsteon QueueLoop : Processing Command for KPL Scenes/Dryer LED. Queue size = 1
Jun-21 00:35:08 ISYInsteon ProcessCommand : Sending DOF, to KPL Scenes/Dryer LED Attempt 0
Jun-21 00:35:08 ISYInsteon Authorize : Authorize: encoding = "admin:xxx" to "YWRtaW46cWRwenl4"
Jun-21 00:35:08 ISYInsteon SendRequest : Sending command [DOF431174 ]
Jun-21 00:34:31 ISYInsteon read : ISY subscription: No data for 120 seconds, network problem?.
Jun-21 00:34:28 ISYInsteon QueueLoop : Command for Basement/KPL NS (Light) finished and removed from queue. Queue size = 1
Jun-21 00:34:28 ISYInsteon Command Queue is now empty.
Jun-21 00:34:28 ISYInsteon ProcessCommand : ISY network communication failure: missed status report for device Basement/KPL NS (Light). (34 DE 4E 1)
Jun-21 00:34:23 ISYInsteon CheckDeviceStatus : Comparing 255 with 0
Jun-21 00:34:23 ISYInsteon ProcessCommand : Sending DON, 255 to Basement/KPL NS (Light) Attempt 4
Jun-21 00:34:23 ISYInsteon Authorize : Authorize: encoding = "admin:xxx" to "YWRtaW46cWRwenl4"
Jun-21 00:34:23 ISYInsteon SendRequest : Sending command [DON25534 DE 4E 10 ]
Jun-21 00:34:22 ISYInsteon ProcessCommand : ISY network communication failure: missed status report for device Basement/KPL NS (Light). (34 DE 4E 1)
Jun-21 00:34:17 ISYInsteon CheckDeviceStatus : Comparing 255 with 0
Jun-21 00:34:17 ISYInsteon ProcessCommand : Sending DON, 255 to Basement/KPL NS (Light) Attempt 3
Jun-21 00:34:17 ISYInsteon Authorize : Authorize: encoding = "admin:xxx" to "YWRtaW46cWRwenl4"
Jun-21 00:34:17 ISYInsteon SendRequest : Sending command [DON25534 DE 4E 10 ]
Jun-21 00:34:17 ISYInsteon ProcessCommand : ISY network communication failure: missed status report for device Basement/KPL NS (Light). (34 DE 4E 1)
Jun-21 00:34:12 ISYInsteon CheckDeviceStatus : Comparing 255 with 0
Jun-21 00:34:12 ISYInsteon ProcessCommand : Sending DON, 255 to Basement/KPL NS (Light) Attempt 2
Jun-21 00:34:12 ISYInsteon Authorize : Authorize: encoding = "admin:xxx" to "YWRtaW46cWRwenl4"
Jun-21 00:34:12 ISYInsteon SendRequest : Sending command [DON25534 DE 4E 10 ]
Jun-21 00:34:12 ISYInsteon ProcessCommand : ISY network communication failure: missed status report for device Basement/KPL NS (Light). (34 DE 4E 1)
Jun-21 00:34:07 ISYInsteon CheckDeviceStatus : Comparing 255 with 0
Jun-21 00:34:07 ISYInsteon ProcessCommand : Sending DON, 255 to Basement/KPL NS (Light) Attempt 1
Jun-21 00:34:07 ISYInsteon Authorize : Authorize: encoding = "admin:xxx" to "YWRtaW46cWRwenl4"
Jun-21 00:34:07 ISYInsteon SendRequest : Sending command [DON25534 DE 4E 10 ]
Jun-21 00:34:07 ISYInsteon ProcessCommand : ISY network communication failure: missed status report for device Basement/KPL NS (Light). (34 DE 4E 1)
Jun-21 00:34:02 ISYInsteon CheckDeviceStatus : Comparing 255 with 0
Jun-21 00:34:01 ISYInsteon SetIOMulti : SetIOMulti set 1704 to value 100
Jun-21 00:34:01 ISYInsteon Add : Command for Basement/KPL NS (Light) added to queue. Queue size = 1
Jun-21 00:34:01 ISYInsteon Control : Exit control function
Jun-21 00:34:01 ISYInsteon QueueLoop : Processing Command for Basement/KPL NS (Light). Queue size = 1
Jun-21 00:34:01 ISYInsteon ProcessCommand : Sending DON, 255 to Basement/KPL NS (Light) Attempt 0
Jun-21 00:34:01 ISYInsteon Authorize : Authorize: encoding = "admin:xxx" to "YWRtaW46cWRwenl4"
Jun-21 00:34:01 ISYInsteon SendRequest : Sending command [DON25534 DE 4E 10 ]
Jun-21 00:33:34 ISYInsteon Logging to HomeSeer Log Enabled.
Thanks,
Jeff
Has anything recently changed with network communications? I have started seeing a lot of issues with ISY subscription errors and missed status reports:
Jun-21 00:06:29 ISYInsteon Restart : ->
Jun-21 00:06:29 ISYInsteon Restart : Last message received before restart
Jun-21 00:06:29 ISYInsteon Bottom of subscription thread loop
Jun-21 00:06:29 ISYInsteon Attemp to close TcpClient
Jun-21 00:06:29 ISYInsteon Read exited with False
Jun-21 00:06:29 ISYInsteon read : The subscription connection to ISY has failed.
Jun-21 00:06:49 ISYInsteon ProcessCommand : ISY network communication failure: missed status report for device Basement/KPL NS (Light). (34 DE 4E 1)
Jun-21 00:06:44 ISYInsteon ProcessCommand : ISY network communication failure: missed status report for device Basement/KPL NS (Light). (34 DE 4E 1)
Jun-21 00:06:38 ISYInsteon ProcessCommand : ISY network communication failure: missed status report for device Basement/KPL NS (Light). (34 DE 4E 1)
Jun-21 00:06:33 ISYInsteon ProcessCommand : ISY network communication failure: missed status report for device Basement/KPL NS (Light). (34 DE 4E 1)
Jun-21 00:06:29 ISYInsteon read : The subscription connection to ISY has failed.
I haven't changed anything on the ISY and if I control devices from it everything works fine. I normally have to reboot the ISY and homeseer or the ISY plugin several times before I can get it to start working again. I don't seem to be having any issues with other devices on my network so I'm not sure where to look. I'm going to see if I can find an old version of the plugin to see if that corrects the issue. I'm currently on 3.0.1.6 and have seen the issue off and on for a bit, but it has been worse the last few days. Yesterday I had to cycle everything multiple times before I could get things working.
Here is a snippet with debugging turned on just trying to turn a device on:
Jun-21 00:35:08 ISYInsteon QueueLoop : Command for KPL Scenes/Dryer LED finished and removed from queue. Queue size = 1
Jun-21 00:35:08 ISYInsteon Command Queue is now empty.
Jun-21 00:35:08 ISYInsteon SetIOMulti : SetIOMulti set 86 to value 0
Jun-21 00:35:08 ISYInsteon Add : Command for KPL Scenes/Dryer LED added to queue. Queue size = 1
Jun-21 00:35:08 ISYInsteon Control : Exit control function
Jun-21 00:35:08 ISYInsteon QueueLoop : Processing Command for KPL Scenes/Dryer LED. Queue size = 1
Jun-21 00:35:08 ISYInsteon ProcessCommand : Sending DOF, to KPL Scenes/Dryer LED Attempt 0
Jun-21 00:35:08 ISYInsteon Authorize : Authorize: encoding = "admin:xxx" to "YWRtaW46cWRwenl4"
Jun-21 00:35:08 ISYInsteon SendRequest : Sending command [DOF431174 ]
Jun-21 00:34:31 ISYInsteon read : ISY subscription: No data for 120 seconds, network problem?.
Jun-21 00:34:28 ISYInsteon QueueLoop : Command for Basement/KPL NS (Light) finished and removed from queue. Queue size = 1
Jun-21 00:34:28 ISYInsteon Command Queue is now empty.
Jun-21 00:34:28 ISYInsteon ProcessCommand : ISY network communication failure: missed status report for device Basement/KPL NS (Light). (34 DE 4E 1)
Jun-21 00:34:23 ISYInsteon CheckDeviceStatus : Comparing 255 with 0
Jun-21 00:34:23 ISYInsteon ProcessCommand : Sending DON, 255 to Basement/KPL NS (Light) Attempt 4
Jun-21 00:34:23 ISYInsteon Authorize : Authorize: encoding = "admin:xxx" to "YWRtaW46cWRwenl4"
Jun-21 00:34:23 ISYInsteon SendRequest : Sending command [DON25534 DE 4E 10 ]
Jun-21 00:34:22 ISYInsteon ProcessCommand : ISY network communication failure: missed status report for device Basement/KPL NS (Light). (34 DE 4E 1)
Jun-21 00:34:17 ISYInsteon CheckDeviceStatus : Comparing 255 with 0
Jun-21 00:34:17 ISYInsteon ProcessCommand : Sending DON, 255 to Basement/KPL NS (Light) Attempt 3
Jun-21 00:34:17 ISYInsteon Authorize : Authorize: encoding = "admin:xxx" to "YWRtaW46cWRwenl4"
Jun-21 00:34:17 ISYInsteon SendRequest : Sending command [DON25534 DE 4E 10 ]
Jun-21 00:34:17 ISYInsteon ProcessCommand : ISY network communication failure: missed status report for device Basement/KPL NS (Light). (34 DE 4E 1)
Jun-21 00:34:12 ISYInsteon CheckDeviceStatus : Comparing 255 with 0
Jun-21 00:34:12 ISYInsteon ProcessCommand : Sending DON, 255 to Basement/KPL NS (Light) Attempt 2
Jun-21 00:34:12 ISYInsteon Authorize : Authorize: encoding = "admin:xxx" to "YWRtaW46cWRwenl4"
Jun-21 00:34:12 ISYInsteon SendRequest : Sending command [DON25534 DE 4E 10 ]
Jun-21 00:34:12 ISYInsteon ProcessCommand : ISY network communication failure: missed status report for device Basement/KPL NS (Light). (34 DE 4E 1)
Jun-21 00:34:07 ISYInsteon CheckDeviceStatus : Comparing 255 with 0
Jun-21 00:34:07 ISYInsteon ProcessCommand : Sending DON, 255 to Basement/KPL NS (Light) Attempt 1
Jun-21 00:34:07 ISYInsteon Authorize : Authorize: encoding = "admin:xxx" to "YWRtaW46cWRwenl4"
Jun-21 00:34:07 ISYInsteon SendRequest : Sending command [DON25534 DE 4E 10 ]
Jun-21 00:34:07 ISYInsteon ProcessCommand : ISY network communication failure: missed status report for device Basement/KPL NS (Light). (34 DE 4E 1)
Jun-21 00:34:02 ISYInsteon CheckDeviceStatus : Comparing 255 with 0
Jun-21 00:34:01 ISYInsteon SetIOMulti : SetIOMulti set 1704 to value 100
Jun-21 00:34:01 ISYInsteon Add : Command for Basement/KPL NS (Light) added to queue. Queue size = 1
Jun-21 00:34:01 ISYInsteon Control : Exit control function
Jun-21 00:34:01 ISYInsteon QueueLoop : Processing Command for Basement/KPL NS (Light). Queue size = 1
Jun-21 00:34:01 ISYInsteon ProcessCommand : Sending DON, 255 to Basement/KPL NS (Light) Attempt 0
Jun-21 00:34:01 ISYInsteon Authorize : Authorize: encoding = "admin:xxx" to "YWRtaW46cWRwenl4"
Jun-21 00:34:01 ISYInsteon SendRequest : Sending command [DON25534 DE 4E 10 ]
Jun-21 00:33:34 ISYInsteon Logging to HomeSeer Log Enabled.
Thanks,
Jeff
Comment