search

home-assistant / core

:house_with_garden: Open source home automation that puts local control and privacy first.
https://www.home-assistant.io
Apache License 2.0
72.78k stars 30.49k forks source link

Insteon thermostat #71123

Closed darikweitzel closed 2 years ago

darikweitzel commented 2 years ago

The problem

insteon thermostat intergration wont respond to my insteon thermostat now. I change the temp up and the actual thermostat does nothing. When I physically change the temp on the thermostat, the HA intergration doesnt reflect it. I looked in my logs to see nothing there. I use to beable to see the changes the thermostat was adjusted to.

What version of Home Assistant Core has the issue?

2022.5.0b3

What was the last working version of Home Assistant Core?

No response

What type of installation are you running?

Home Assistant OS

Integration causing the issue

insteon

Link to integration documentation on our website

https://www.home-assistant.io/integrations/insteon/

Diagnostics information

na, dont see where I can

Example YAML snippet

na

Anything in the logs that might be useful for us?

none show up

Additional information

No response

probot-home-assistant[bot] commented 2 years ago

insteon documentation insteon source (message by IssueLinks)

probot-home-assistant[bot] commented 2 years ago

Hey there @teharris1, mind taking a look at this issue as it has been labeled with an integration (insteon) you are listed as a code owner for? Thanks! (message by CodeOwnersMention)

teharris1 commented 2 years ago

Was it working in 2022.4 or earlier?

darikweitzel commented 2 years ago

Sorry I tried to fill out best I can. Yes it worked great with latest version of HA. Soon as I updated to beta, noticed it stoped responding.

Sent from my iPhone

On Apr 30, 2022, at 12:53 PM, Tom Harris @.***> wrote:

 Was it working in 2022.4 or earlier?

— Reply to this email directly, view it on GitHub, or unsubscribe. You are receiving this because you authored the thread.

teharris1 commented 2 years ago

Can you put Insteon in debug mode, restart HA and let it settle in for 10 min or so. Then change the temp a few times, once per min. Let me know the ID of the device and upload the last few min of the logs.

darikweitzel commented 2 years ago

Will do when I get home tonight. Thank you

Sent from my iPhone

On Apr 30, 2022, at 6:08 PM, Tom Harris @.***> wrote:

 Can you put Insteon in debug mode, restart HA and let it settle in for 10 min or so. Then change the temp a few times, once per min. Let me know the ID of the device and upload the last few min of the logs.

— Reply to this email directly, view it on GitHub, or unsubscribe. You are receiving this because you authored the thread.

darikweitzel commented 2 years ago

I could not upload to much data as it errored out during email sending. I attached another at half log data but should still have it in there. added ID in log file at top.

http://www.avg.com/email-signature?utm_medium=email&utm_source=link&utm_campaign=sig-email&utm_content=webmail Virus-free. www.avg.com http://www.avg.com/email-signature?utm_medium=email&utm_source=link&utm_campaign=sig-email&utm_content=webmail <#DAB4FAD8-2DD7-40BB-A1B8-4E2AA1F9FDF2>

On Sat, Apr 30, 2022 at 10:26 PM Darik Weitzel @.***> wrote:

attached the log ,txt file. In there is the ID of the device.

http://www.avg.com/email-signature?utm_medium=email&utm_source=link&utm_campaign=sig-email&utm_content=webmail Virus-free. www.avg.com http://www.avg.com/email-signature?utm_medium=email&utm_source=link&utm_campaign=sig-email&utm_content=webmail <#m_-8451518614946458342_DAB4FAD8-2DD7-40BB-A1B8-4E2AA1F9FDF2>

On Sat, Apr 30, 2022 at 6:08 PM Tom Harris @.***> wrote:

Can you put Insteon in debug mode, restart HA and let it settle in for 10 min or so. Then change the temp a few times, once per min. Let me know the ID of the device and upload the last few min of the logs.

— Reply to this email directly, view it on GitHub https://github.com/home-assistant/core/issues/71123#issuecomment-1114069272, or unsubscribe https://github.com/notifications/unsubscribe-auth/AY2GN6T5FQHG2DNZXVVBZF3VHW4P5ANCNFSM5UYSS5HQ . You are receiving this because you authored the thread.Message ID: @.***>

thermostat","entity":"climate.thermostat_adapter_4f_67_c7_group_1

2022-04-30 22:16:00 DEBUG (MainThread) [rokuecp] Requesting http://192.168.86.22:8060/query/device-info returned OrderedDict([('device-info', OrderedDict([('udn', '280011c0-0000-1000-8000-bcd7d44cd8d3'), ('serial-number', 'X01700ENLA59'), ('device-id', 'S090418NLA59'), ('advertising-id', '94ff2ed5-af1a-5979-92a2-53a0719962f9'), ('vendor-name', 'Roku'), ('model-name', 'Streaming Stick 4K'), ('model-number', '3820RW'), ('model-region', 'US'), ('is-tv', 'false'), ('is-stick', 'true'), ('ui-resolution', '1080p'), ('supports-ethernet', 'false'), ('wifi-mac', 'bc:d7:d4:4c:d8:d3'), ('wifi-driver', 'realtek'), ('has-wifi-extender', 'false'), ('has-wifi-5G-support', 'true'), ('can-use-wifi-extender', 'true'), ('network-type', 'wifi'), ('network-name', 'Woody’s Google'), ('friendly-device-name', 'Streaming Stick 4K'), ('friendly-model-name', 'Streaming Stick 4K'), ('default-device-name', 'Streaming Stick 4K - X01700ENLA59'), ('user-device-name', 'Streaming Stick 4K'), ('user-device-location', 'Warren'), ('build-number', 'CUC.00E05003A'), ('software-version', '11.0.0'), ('software-build', '5003'), ('secure-device', 'true'), ('language', 'en'), ('country', 'US'), ('locale', 'en_US'), ('time-zone-auto', 'true'), ('time-zone', 'US/Central'), ('time-zone-name', 'United States/Central'), ('time-zone-tz', 'America/Chicago'), ('time-zone-offset', '-300'), ('clock-format', '12-hour'), ('uptime', '641487'), ('power-mode', 'Suspend'), ('supports-suspend', 'true'), ('supports-find-remote', 'true'), ('find-remote-is-possible', 'true'), ('supports-audio-guide', 'true'), ('supports-rva', 'true'), ('developer-enabled', 'false'), ('keyed-developer-id', None), ('search-enabled', 'true'), ('search-channels-enabled', 'true'), ('voice-search-enabled', 'true'), ('notifications-enabled', 'true'), ('notifications-first-use', 'true'), ('supports-private-listening', 'true'), ('headphones-connected', 'false'), ('supports-audio-settings', 'false'), ('supports-ecs-textedit', 'true'), ('supports-ecs-microphone', 'true'), ('supports-wake-on-wlan', 'false'), ('supports-airplay', 'true'), ('has-play-on-roku', 'true'), ('has-mobile-screensaver', 'true'), ('support-url', 'roku.com/support'), ('grandcentral-version', '7.1.50'), ('trc-version', '3.0'), ('trc-channel-version', '6.0.15'), ('davinci-version', '2.8.20'), ('av-sync-calibration-enabled', '2.0')]))]) 2022-04-30 22:16:00 DEBUG (MainThread) [homeassistant.components.roku.coordinator] Finished fetching roku data in 0.071 seconds (success: True) 2022-04-30 22:16:00 DEBUG (MainThread) [zeroconf] IPv6 scope_id 0 associated to the receiving interface 2022-04-30 22:16:00 DEBUG (MainThread) [zeroconf] Received from '::ffff:192.168.86.25':5353 [socket 12 (('::', 5353, 0, 0))]: <DNSIncoming:{id=0, flags=0, truncated=False, n_q=1, n_ans=0, n_auth=0, n_add=0, questions=[ptr[question,QM,in,_services._dns-sd._udp.local.]], answers=[]}> (46 bytes) as [b'\x00\x00\x00\x00\x00\x01\x00\x00\x00\x00\x00\x00\t_services\x07_dns-sd\x04_udp\x05local\x00\x00\x0c\x00\x01'] 2022-04-30 22:16:00 DEBUG (MainThread) [zeroconf] IPv6 scope_id 0 associated to the receiving interface 2022-04-30 22:16:00 DEBUG (MainThread) [zeroconf] Received from '::ffff:192.168.86.25':5353 [socket 12 (('::', 5353, 0, 0))]: <DNSIncoming:{id=0, flags=33792, truncated=False, n_q=0, n_ans=1, n_auth=0, n_add=0, questions=[], answers=[record[ptr,in,_services._dns-sd._udp.local.]=4500/4499,_nvstream_dbd._tcp.local.]}> (73 bytes) as [b'\x00\x00\x84\x00\x00\x00\x00\x01\x00\x00\x00\x00\t_services\x07_dns-sd\x04_udp\x05local\x00\x00\x0c\x00\x01\x00\x00\x11\x94\x00\x15\r_nvstream_dbd\x04_tcp\xc0#'] 2022-04-30 22:16:00 DEBUG (MainThread) [zeroconf] IPv6 scope_id 0 associated to the receiving interface 2022-04-30 22:16:00 DEBUG (MainThread) [zeroconf] Received from '::ffff:192.168.86.25':5353 [socket 12 (('::', 5353, 0, 0))]: <DNSIncoming:{id=0, flags=33792, truncated=False, n_q=0, n_ans=1, n_auth=0, n_add=0, questions=[], answers=[record[ptr,in,_services._dns-sd._udp.local.]=4500/4499,_agentdvr._tcp.local.]}> (69 bytes) as [b'\x00\x00\x84\x00\x00\x00\x00\x01\x00\x00\x00\x00\t_services\x07_dns-sd\x04_udp\x05local\x00\x00\x0c\x00\x01\x00\x00\x11\x94\x00\x11\t_agentdvr\x04_tcp\xc0#'] 2022-04-30 22:16:00 DEBUG (MainThread) [zeroconf] IPv6 scope_id 0 associated to the receiving interface 2022-04-30 22:16:00 DEBUG (MainThread) [zeroconf] Received from '::ffff:192.168.86.235':5353 [socket 12 (('::', 5353, 0, 0))]: <DNSIncoming:{id=0, flags=33792, truncated=False, n_q=0, n_ans=1, n_auth=0, n_add=0, questions=[], answers=[record[ptr,in,_services._dns-sd._udp.local.]=120/119,_workstation._tcp.local.]}> (72 bytes) as [b'\x00\x00\x84\x00\x00\x00\x00\x01\x00\x00\x00\x00\t_services\x07_dns-sd\x04_udp\x05local\x00\x00\x0c\x00\x01\x00\x00\x00x\x00\x14\x0c_workstation\x04_tcp\xc0#'] 2022-04-30 22:16:00 DEBUG (MainThread) [zeroconf] Increasing effective ttl of record[ptr,in,_services._dns-sd._udp.local.]=120/119,_workstation._tcp.local. to minimum of 1125.0 to protect against excessive refreshes. 2022-04-30 22:16:00 DEBUG (MainThread) [zeroconf] IPv6 scope_id 0 associated to the receiving interface 2022-04-30 22:16:00 DEBUG (MainThread) [zeroconf] Received from '::ffff:192.168.86.25':5353 [socket 12 (('::', 5353, 0, 0))]: <DNSIncoming:{id=0, flags=0, truncated=False, n_q=1, n_ans=0, n_auth=0, n_add=0, questions=[ptr[question,QM,in,_agentdvr._tcp.local.]], answers=[]}> (38 bytes) as [b'\x00\x00\x00\x00\x00\x01\x00\x00\x00\x00\x00\x00\t_agentdvr\x04_tcp\x05local\x00\x00\x0c\x00\x01'] 2022-04-30 22:16:00 DEBUG (MainThread) [zeroconf] IPv6 scope_id 0 associated to the receiving interface 2022-04-30 22:16:00 DEBUG (MainThread) [zeroconf] Received from '::ffff:192.168.86.25':5353 [socket 12 (('::', 5353, 0, 0))]: <DNSIncoming:{id=0, flags=33792, truncated=False, n_q=0, n_ans=1, n_auth=0, n_add=4, questions=[], answers=[record[ptr,in,_agentdvr._tcp.local.]=4500/4499,53edb993-4233-4a9f-a4d9-f1426bcad27e._agentdvr._tcp.local., record[txt,in,53edb993-4233-4a9f-a4d9-f1426bcad27e._agentdvr._tcp.local.]=4500/4499,b'\ttxtver'..., record[srv,in,53edb993-4233-4a9f-a4d9-f1426bcad27e._agentdvr._tcp.local.]=4500/4499,53edb993-4233-4a9f-a4d9-f1426bcad27e.agentdvr.local.:8090, record[a,in,53edb993-4233-4a9f-a4d9-f1426bcad27e.agentdvr.local.]=120/119,192.168.86.25, record[quada,in,53edb993-4233-4a9f-a4d9-f1426bcad27e.agentdvr.local.]=120/119,fe80::54f3:1f72:cba2:c89e]}> (227 bytes) as [b"\x00\x00\x84\x00\x00\x00\x00\x01\x00\x00\x00\x04\t_agentdvr\x04_tcp\x05local\x00\x00\x0c\x00\x01\x00\x00\x11\x94\x00'$53edb993-4233-4a9f-a4d9-f1426bcad27e\xc0\x0c\xc0,\x00\x10\x00\x01\x00\x00\x11\x94\x00\x16\ttxtvers=1\x0bHTTPS=false\xc0,\x00!\x00\x01\x00\x00\x11\x94\x006\x00\x00\x00\x00\x1f\x9a$53edb993-4233-4a9f-a4d9-f1426bcad27e\x08agentdvr\xc0\x1b\xc0\x87\x00\x01\x00\x01\x00\x00\x00x\x00\x04\xc0\xa8V\x19\xc0\x87\x00\x1c\x00\x01\x00\x00\x00x\x00\x10\xfe\x80\x00\x00\x00\x00\x00\x00T\xf3\x1fr\xcb\xa2\xc8\x9e"] 2022-04-30 22:16:00 DEBUG (MainThread) [zeroconf] IPv6 scope_id 0 associated to the receiving interface 2022-04-30 22:16:00 DEBUG (MainThread) [zeroconf] Received from '::ffff:192.168.86.25':5353 [socket 12 (('::', 5353, 0, 0))]: <DNSIncoming:{id=0, flags=0, truncated=False, n_q=1, n_ans=0, n_auth=0, n_add=0, questions=[srv[question,QM,in,53edb993-4233-4a9f-a4d9-f1426bcad27e._agentdvr._tcp.local.]], answers=[]}> (75 bytes) as [b'\x00\x00\x00\x00\x00\x01\x00\x00\x00\x00\x00\x00$53edb993-4233-4a9f-a4d9-f1426bcad27e\t_agentdvr\x04_tcp\x05local\x00\x00!\x00\x01'] 2022-04-30 22:16:00 DEBUG (MainThread) [zeroconf] IPv6 scope_id 0 associated to the receiving interface 2022-04-30 22:16:00 DEBUG (MainThread) [zeroconf] Received from '::ffff:192.168.86.25':5353 [socket 12 (('::', 5353, 0, 0))]: <DNSIncoming:{id=0, flags=33792, truncated=False, n_q=0, n_ans=1, n_auth=0, n_add=3, questions=[], answers=[record[srv,in,53edb993-4233-4a9f-a4d9-f1426bcad27e._agentdvr._tcp.local.]=4500/4499,53edb993-4233-4a9f-a4d9-f1426bcad27e.agentdvr.local.:8090, record[txt,in,53edb993-4233-4a9f-a4d9-f1426bcad27e._agentdvr._tcp.local.]=4500/4499,b'\ttxtver'..., record[a,in,53edb993-4233-4a9f-a4d9-f1426bcad27e.agentdvr.local.]=120/119,192.168.86.25, record[quada,in,53edb993-4233-4a9f-a4d9-f1426bcad27e.agentdvr.local.]=120/119,fe80::54f3:1f72:cba2:c89e]}> (213 bytes) as [b'\x00\x00\x84\x00\x00\x00\x00\x01\x00\x00\x00\x03$53edb993-4233-4a9f-a4d9-f1426bcad27e\t_agentdvr\x04_tcp\x05local\x00\x00!\x00\x01\x00\x00\x11\x94\x006\x00\x00\x00\x00\x1f\x9a$53edb993-4233-4a9f-a4d9-f1426bcad27e\x08agentdvr\xc0@\xc0\x0c\x00\x10\x00\x01\x00\x00\x11\x94\x00\x16\ttxtvers=1\x0bHTTPS=false\xc0W\x00\x01\x00\x01\x00\x00\x00x\x00\x04\xc0\xa8V\x19\xc0W\x00\x1c\x00\x01\x00\x00\x00x\x00\x10\xfe\x80\x00\x00\x00\x00\x00\x00T\xf3\x1fr\xcb\xa2\xc8\x9e'] 2022-04-30 22:16:00 DEBUG (MainThread) [zeroconf] IPv6 scope_id 0 associated to the receiving interface 2022-04-30 22:16:00 DEBUG (MainThread) [zeroconf] Received from '::ffff:192.168.86.25':5353 [socket 12 (('::', 5353, 0, 0))]: <DNSIncoming:{id=0, flags=0, truncated=False, n_q=1, n_ans=0, n_auth=0, n_add=0, questions=[a[question,QM,in,53edb993-4233-4a9f-a4d9-f1426bcad27e.agentdvr.local.]], answers=[]}> (69 bytes) as [b'\x00\x00\x00\x00\x00\x01\x00\x00\x00\x00\x00\x00$53edb993-4233-4a9f-a4d9-f1426bcad27e\x08agentdvr\x05local\x00\x00\x01\x00\x01'] 2022-04-30 22:16:00 DEBUG (MainThread) [zeroconf] IPv6 scope_id 0 associated to the receiving interface 2022-04-30 22:16:00 DEBUG (MainThread) [zeroconf] Received from '::ffff:192.168.86.25':5353 [socket 12 (('::', 5353, 0, 0))]: <DNSIncoming:{id=0, flags=33792, truncated=False, n_q=0, n_ans=1, n_auth=0, n_add=1, questions=[], answers=[record[a,in,53edb993-4233-4a9f-a4d9-f1426bcad27e.agentdvr.local.]=120/119,192.168.86.25, record[quada,in,53edb993-4233-4a9f-a4d9-f1426bcad27e.agentdvr.local.]=120/119,fe80::54f3:1f72:cba2:c89e]}> (107 bytes) as [b'\x00\x00\x84\x00\x00\x00\x00\x01\x00\x00\x00\x01$53edb993-4233-4a9f-a4d9-f1426bcad27e\x08agentdvr\x05local\x00\x00\x01\x00\x01\x00\x00\x00x\x00\x04\xc0\xa8V\x19\xc0\x0c\x00\x1c\x00\x01\x00\x00\x00x\x00\x10\xfe\x80\x00\x00\x00\x00\x00\x00T\xf3\x1fr\xcb\xa2\xc8\x9e'] 2022-04-30 22:16:00 DEBUG (MainThread) [zeroconf] offsets = questions=0, answers=0, authorities=0, additionals=0 2022-04-30 22:16:00 DEBUG (MainThread) [zeroconf] lengths = questions=0, answers=1, authorities=0, additionals=0 2022-04-30 22:16:00 DEBUG (MainThread) [zeroconf] now offsets = questions=0, answers=1, authorities=0, additionals=0 2022-04-30 22:16:00 DEBUG (MainThread) [zeroconf] Sending to (224.0.0.251, 5353) via [socket 13 (('192.168.86.235', 5353))] (75 bytes #1) <DNSOutgoing:{multicast=True, flags=33792, questions=[], answers=[(record[ptr,in,_services._dns-sd._udp.local.]=4500/4499,_home-assistant._tcp.local., 0)], authorities=[], additionals=[]}> as b'\x00\x00\x84\x00\x00\x00\x00\x01\x00\x00\x00\x00\t_services\x07_dns-sd\x04_udp\x05local\x00\x00\x0c\x00\x01\x00\x00\x11\x94\x00\x17\x0f_home-assistant\x04_tcp\xc0#'... 2022-04-30 22:16:00 DEBUG (MainThread) [zeroconf] Sending to (ff02::fb, 5353) via [socket 14 (('fe80::ea20:b291:51a8:c4cf', 5353, 0, 3))] (75 bytes #1) <DNSOutgoing:{multicast=True, flags=33792, questions=[], answers=[(record[ptr,in,_services._dns-sd._udp.local.]=4500/4499,_home-assistant._tcp.local., 0)], authorities=[], additionals=[]}> as b'\x00\x00\x84\x00\x00\x00\x00\x01\x00\x00\x00\x00\t_services\x07_dns-sd\x04_udp\x05local\x00\x00\x0c\x00\x01\x00\x00\x11\x94\x00\x17\x0f_home-assistant\x04_tcp\xc0#'... 2022-04-30 22:16:00 DEBUG (MainThread) [zeroconf] IPv6 scope_id 0 associated to the receiving interface 2022-04-30 22:16:00 DEBUG (MainThread) [zeroconf] Received from '::ffff:192.168.86.235':5353 [socket 12 (('::', 5353, 0, 0))]: <DNSIncoming:{id=0, flags=33792, truncated=False, n_q=0, n_ans=1, n_auth=0, n_add=0, questions=[], answers=[record[ptr,in,_services._dns-sd._udp.local.]=4500/4499,_home-assistant._tcp.local.]}> (75 bytes) as [b'\x00\x00\x84\x00\x00\x00\x00\x01\x00\x00\x00\x00\t_services\x07_dns-sd\x04_udp\x05local\x00\x00\x0c\x00\x01\x00\x00\x11\x94\x00\x17\x0f_home-assistant\x04_tcp\xc0#'] 2022-04-30 22:16:00 DEBUG (MainThread) [zeroconf] IPv6 scope_id 3 associated to the receiving interface 2022-04-30 22:16:00 DEBUG (MainThread) [zeroconf] Ignoring duplicate message received from 'fe80::ea20:b291:51a8:c4cf':5353 [socket 12 (('::', 5353, 0, 0))] (75 bytes) as [b'\x00\x00\x84\x00\x00\x00\x00\x01\x00\x00\x00\x00\t_services\x07_dns-sd\x04_udp\x05local\x00\x00\x0c\x00\x01\x00\x00\x11\x94\x00\x17\x0f_home-assistant\x04_tcp\xc0#'] 2022-04-30 22:16:00 DEBUG (MainThread) [zeroconf] IPv6 scope_id 0 associated to the receiving interface 2022-04-30 22:16:00 DEBUG (MainThread) [zeroconf] Received from '::ffff:192.168.86.98':5353 [socket 12 (('::', 5353, 0, 0))]: <DNSIncoming:{id=0, flags=33792, truncated=False, n_q=0, n_ans=1, n_auth=0, n_add=0, questions=[], answers=[record[ptr,in,_services._dns-sd._udp.local.]=4500/4499,_teamviewer._tcp.local.]}> (71 bytes) as [b'\x00\x00\x84\x00\x00\x00\x00\x01\x00\x00\x00\x00\t_services\x07_dns-sd\x04_udp\x05local\x00\x00\x0c\x00\x01\x00\x00\x11\x94\x00\x13\x0b_teamviewer\x04_tcp\xc0#'] 2022-04-30 22:16:00 DEBUG (MainThread) [zeroconf] IPv6 scope_id 0 associated to the receiving interface 2022-04-30 22:16:00 DEBUG (MainThread) [zeroconf] Received from '::ffff:192.168.86.98':5353 [socket 12 (('::', 5353, 0, 0))]: <DNSIncoming:{id=0, flags=33792, truncated=False, n_q=0, n_ans=1, n_auth=0, n_add=0, questions=[], answers=[record[ptr,in,_services._dns-sd._udp.local.]=4500/4499,_agentdvr._tcp.local.]}> (69 bytes) as [b'\x00\x00\x84\x00\x00\x00\x00\x01\x00\x00\x00\x00\t_services\x07_dns-sd\x04_udp\x05local\x00\x00\x0c\x00\x01\x00\x00\x11\x94\x00\x11\t_agentdvr\x04_tcp\xc0#'] 2022-04-30 22:16:00 DEBUG (MainThread) [zeroconf] IPv6 scope_id 0 associated to the receiving interface 2022-04-30 22:16:00 DEBUG (MainThread) [zeroconf] Received from '::ffff:192.168.86.98':5353 [socket 12 (('::', 5353, 0, 0))]: <DNSIncoming:{id=0, flags=33792, truncated=False, n_q=0, n_ans=1, n_auth=0, n_add=6, questions=[], answers=[record[ptr,in,_agentdvr._tcp.local.]=4500/4499,agent._agentdvr._tcp.local., record[txt,in,agent._agentdvr._tcp.local.]=86400/86399,b'\ttxtver'..., record[srv,in,agent._agentdvr._tcp.local.]=86400/86399,agent.agentdvr.local.:8090, record[a,in,agent.agentdvr.local.]=120/119,192.168.56.1, record[a,in,agent.agentdvr.local.]=120/119,192.168.86.98, record[quada,in,agent.agentdvr.local.]=120/119,fe80::4dc7:9b31:4f17:27e5, record[quada,in,agent.agentdvr.local.]=120/119,fe80::b923:9f89:363e:6842]}> (241 bytes) as [b"\x00\x00\x84\x00\x00\x00\x00\x01\x00\x00\x00\x06\t_agentdvr\x04_tcp\x05local\x00\x00\x0c\x00\x01\x00\x00\x11\x94\x00\x08\x05agent\xc0\x0c\xc0,\x00\x10\x00\x01\x00\x01Q\x80\x006\ttxtvers=1+serial=931b14eb-7c1c-492a-b796-bd31cfd720a7\xc0,\x00!\x00\x01\x00\x01Q\x80\x00\x17\x00\x00\x00\x00\x1f\x9a\x05agent\x08agentdvr\xc0\x1b\xc0\x88\x00\x01\x00\x01\x00\x00\x00x\x00\x04\xc0\xa88\x01\xc0\x88\x00\x01\x00\x01\x00\x00\x00x\x00\x04\xc0\xa8Vb\xc0\x88\x00\x1c\x00\x01\x00\x00\x00x\x00\x10\xfe\x80\x00\x00\x00\x00\x00\x00M\xc7\x9b1O\x17'\xe5\xc0\x88\x00\x1c\x00\x01\x00\x00\x00x\x00\x10\xfe\x80\x00\x00\x00\x00\x00\x00\xb9#\x9f\x896>hB"] 2022-04-30 22:16:00 DEBUG (MainThread) [zeroconf] IPv6 scope_id 3 associated to the receiving interface 2022-04-30 22:16:00 DEBUG (MainThread) [zeroconf] Received from 'fe80::b923:9f89:363e:6842':5353 [socket 12 (('::', 5353, 0, 0))]: <DNSIncoming:{id=0, flags=33792, truncated=False, n_q=0, n_ans=1, n_auth=0, n_add=0, questions=[], answers=[record[ptr,in,_services._dns-sd._udp.local.]=4500/4499,_agentdvr._tcp.local.]}> (69 bytes) as [b'\x00\x00\x84\x00\x00\x00\x00\x01\x00\x00\x00\x00\t_services\x07_dns-sd\x04_udp\x05local\x00\x00\x0c\x00\x01\x00\x00\x11\x94\x00\x11\t_agentdvr\x04_tcp\xc0#'] 2022-04-30 22:16:00 DEBUG (MainThread) [zeroconf] IPv6 scope_id 3 associated to the receiving interface 2022-04-30 22:16:00 DEBUG (MainThread) [zeroconf] Received from 'fe80::b923:9f89:363e:6842':5353 [socket 12 (('::', 5353, 0, 0))]: <DNSIncoming:{id=0, flags=33792, truncated=False, n_q=0, n_ans=1, n_auth=0, n_add=6, questions=[], answers=[record[ptr,in,_agentdvr._tcp.local.]=4500/4499,agent._agentdvr._tcp.local., record[txt,in,agent._agentdvr._tcp.local.]=86400/86399,b'\ttxtver'..., record[srv,in,agent._agentdvr._tcp.local.]=86400/86399,agent.agentdvr.local.:8090, record[a,in,agent.agentdvr.local.]=120/119,192.168.56.1, record[a,in,agent.agentdvr.local.]=120/119,192.168.86.98, record[quada,in,agent.agentdvr.local.]=120/119,fe80::4dc7:9b31:4f17:27e5, record[quada,in,agent.agentdvr.local.]=120/119,fe80::b923:9f89:363e:6842]}> (241 bytes) as [b"\x00\x00\x84\x00\x00\x00\x00\x01\x00\x00\x00\x06\t_agentdvr\x04_tcp\x05local\x00\x00\x0c\x00\x01\x00\x00\x11\x94\x00\x08\x05agent\xc0\x0c\xc0,\x00\x10\x00\x01\x00\x01Q\x80\x006\ttxtvers=1+serial=931b14eb-7c1c-492a-b796-bd31cfd720a7\xc0,\x00!\x00\x01\x00\x01Q\x80\x00\x17\x00\x00\x00\x00\x1f\x9a\x05agent\x08agentdvr\xc0\x1b\xc0\x88\x00\x01\x00\x01\x00\x00\x00x\x00\x04\xc0\xa88\x01\xc0\x88\x00\x01\x00\x01\x00\x00\x00x\x00\x04\xc0\xa8Vb\xc0\x88\x00\x1c\x00\x01\x00\x00\x00x\x00\x10\xfe\x80\x00\x00\x00\x00\x00\x00M\xc7\x9b1O\x17'\xe5\xc0\x88\x00\x1c\x00\x01\x00\x00\x00x\x00\x10\xfe\x80\x00\x00\x00\x00\x00\x00\xb9#\x9f\x896>hB"] 2022-04-30 22:16:00 DEBUG (MainThread) [zeroconf] IPv6 scope_id 0 associated to the receiving interface 2022-04-30 22:16:00 DEBUG (MainThread) [zeroconf] Received from '::ffff:192.168.86.25':5353 [socket 12 (('::', 5353, 0, 0))]: <DNSIncoming:{id=0, flags=0, truncated=False, n_q=1, n_ans=0, n_auth=0, n_add=0, questions=[srv[question,QM,in,agent._agentdvr._tcp.local.]], answers=[]}> (44 bytes) as [b'\x00\x00\x00\x00\x00\x01\x00\x00\x00\x00\x00\x00\x05agent\t_agentdvr\x04_tcp\x05local\x00\x00!\x00\x01'] 2022-04-30 22:16:00 DEBUG (MainThread) [zeroconf] IPv6 scope_id 0 associated to the receiving interface 2022-04-30 22:16:00 DEBUG (MainThread) [zeroconf] Received from '::ffff:192.168.86.32':5353 [socket 12 (('::', 5353, 0, 0))]: <DNSIncoming:{id=0, flags=33792, truncated=False, n_q=0, n_ans=2, n_auth=0, n_add=0, questions=[], answers=[record[ptr,in,_services._dns-sd._udp.local.]=4500/4499,_spotify-connect._tcp.local., record[ptr,in,_services._dns-sd._udp.local.]=4500/4499,_airplay._tcp.local.]}> (99 bytes) as [b'\x00\x00\x84\x00\x00\x00\x00\x02\x00\x00\x00\x00\t_services\x07_dns-sd\x04_udp\x05local\x00\x00\x0c\x00\x01\x00\x00\x11\x94\x00\x18\x10_spotify-connect\x04_tcp\xc0#\xc0\x0c\x00\x0c\x00\x01\x00\x00\x11\x94\x00\x0b\x08_airplay\xc0E'] 2022-04-30 22:16:00 DEBUG (MainThread) [zeroconf] IPv6 scope_id 0 associated to the receiving interface 2022-04-30 22:16:00 DEBUG (MainThread) [zeroconf] Ignoring duplicate message received from '::ffff:192.168.86.22':5353 [socket 12 (('::', 5353, 0, 0))] (99 bytes) as [b'\x00\x00\x84\x00\x00\x00\x00\x02\x00\x00\x00\x00\t_services\x07_dns-sd\x04_udp\x05local\x00\x00\x0c\x00\x01\x00\x00\x11\x94\x00\x18\x10_spotify-connect\x04_tcp\xc0#\xc0\x0c\x00\x0c\x00\x01\x00\x00\x11\x94\x00\x0b\x08_airplay\xc0E'] 2022-04-30 22:16:00 DEBUG (MainThread) [zeroconf] IPv6 scope_id 0 associated to the receiving interface 2022-04-30 22:16:00 DEBUG (MainThread) [zeroconf] Received from '::ffff:192.168.86.29':5353 [socket 12 (('::', 5353, 0, 0))]: <DNSIncoming:{id=0, flags=33792, truncated=False, n_q=0, n_ans=1, n_auth=0, n_add=0, questions=[], answers=[record[ptr,in,_services._dns-sd._udp.local.]=4500/4499,_airplay._tcp.local.]}> (68 bytes) as [b'\x00\x00\x84\x00\x00\x00\x00\x01\x00\x00\x00\x00\t_services\x07_dns-sd\x04_udp\x05local\x00\x00\x0c\x00\x01\x00\x00\x11\x94\x00\x10\x08_airplay\x04_tcp\xc0#'] 2022-04-30 22:16:00 DEBUG (MainThread) [zeroconf] IPv6 scope_id 0 associated to the receiving interface 2022-04-30 22:16:00 DEBUG (MainThread) [zeroconf] Received from '::ffff:192.168.86.98':5353 [socket 12 (('::', 5353, 0, 0))]: <DNSIncoming:{id=0, flags=33792, truncated=False, n_q=0, n_ans=1, n_auth=0, n_add=5, questions=[], answers=[record[srv,in,agent._agentdvr._tcp.local.]=86400/86399,agent.agentdvr.local.:8090, record[txt,in,agent._agentdvr._tcp.local.]=86400/86399,b'\ttxtver'..., record[a,in,agent.agentdvr.local.]=120/119,192.168.56.1, record[a,in,agent.agentdvr.local.]=120/119,192.168.86.98, record[quada,in,agent.agentdvr.local.]=120/119,fe80::4dc7:9b31:4f17:27e5, record[quada,in,agent.agentdvr.local.]=120/119,fe80::b923:9f89:363e:6842]}> (227 bytes) as [b"\x00\x00\x84\x00\x00\x00\x00\x01\x00\x00\x00\x05\x05agent\t_agentdvr\x04_tcp\x05local\x00\x00!\x00\x01\x00\x01Q\x80\x00\x17\x00\x00\x00\x00\x1f\x9a\x05agent\x08agentdvr\xc0!\xc0\x0c\x00\x10\x00\x01\x00\x01Q\x80\x006\ttxtvers=1+serial=931b14eb-7c1c-492a-b796-bd31cfd720a7\xc08\x00\x01\x00\x01\x00\x00\x00x\x00\x04\xc0\xa88\x01\xc08\x00\x01\x00\x01\x00\x00\x00x\x00\x04\xc0\xa8Vb\xc08\x00\x1c\x00\x01\x00\x00\x00x\x00\x10\xfe\x80\x00\x00\x00\x00\x00\x00M\xc7\x9b1O\x17'\xe5\xc08\x00\x1c\x00\x01\x00\x00\x00x\x00\x10\xfe\x80\x00\x00\x00\x00\x00\x00\xb9#\x9f\x896>hB"] 2022-04-30 22:16:00 DEBUG (MainThread) [zeroconf] IPv6 scope_id 0 associated to the receiving interface 2022-04-30 22:16:00 DEBUG (MainThread) [zeroconf] Received from '::ffff:192.168.86.25':5353 [socket 12 (('::', 5353, 0, 0))]: <DNSIncoming:{id=0, flags=0, truncated=False, n_q=1, n_ans=0, n_auth=0, n_add=0, questions=[a[question,QM,in,agent.agentdvr.local.]], answers=[]}> (38 bytes) as [b'\x00\x00\x00\x00\x00\x01\x00\x00\x00\x00\x00\x00\x05agent\x08agentdvr\x05local\x00\x00\x01\x00\x01'] 2022-04-30 22:16:00 DEBUG (MainThread) [zeroconf] IPv6 scope_id 0 associated to the receiving interface 2022-04-30 22:16:00 DEBUG (MainThread) [zeroconf] Received from '::ffff:192.168.86.230':5353 [socket 12 (('::', 5353, 0, 0))]: <DNSIncoming:{id=0, flags=33792, truncated=False, n_q=0, n_ans=1, n_auth=0, n_add=1, questions=[], answers=[record[ptr,in,_services._dns-sd._udp.local.]=4500/4499,_rdlink._tcp.local.]}> (96 bytes) as [b'\x00\x00\x84\x00\x00\x00\x00\x01\x00\x00\x00\x01\t_services\x07_dns-sd\x04_udp\x05local\x00\x00\x0c\x00\x01\x00\x00\x11\x94\x00\x0f\x07_rdlink\x04_tcp\xc0#\x00\x00)\x05\xa0\x00\x00\x11\x94\x00\x12\x00\x04\x00\x0e\x00d\x1a\x1df&\xa6\xf56\xb2/\xf3\xf94'] 2022-04-30 22:16:00 DEBUG (MainThread) [zeroconf] IPv6 scope_id 3 associated to the receiving interface 2022-04-30 22:16:00 DEBUG (MainThread) [zeroconf] Ignoring duplicate message received from 'fe80::1831:e82d:54df:716c':5353 [socket 12 (('::', 5353, 0, 0))] (96 bytes) as [b'\x00\x00\x84\x00\x00\x00\x00\x01\x00\x00\x00\x01\t_services\x07_dns-sd\x04_udp\x05local\x00\x00\x0c\x00\x01\x00\x00\x11\x94\x00\x0f\x07_rdlink\x04_tcp\xc0#\x00\x00)\x05\xa0\x00\x00\x11\x94\x00\x12\x00\x04\x00\x0e\x00d\x1a\x1df&\xa6\xf56\xb2/\xf3\xf94'] 2022-04-30 22:16:00 DEBUG (MainThread) [zeroconf] IPv6 scope_id 3 associated to the receiving interface 2022-04-30 22:16:00 DEBUG (MainThread) [zeroconf] Received from 'fe80::b923:9f89:363e:6842':5353 [socket 12 (('::', 5353, 0, 0))]: <DNSIncoming:{id=0, flags=33792, truncated=False, n_q=0, n_ans=2, n_auth=0, n_add=2, questions=[], answers=[record[a,in,agent.agentdvr.local.]=120/119,192.168.56.1, record[a,in,agent.agentdvr.local.]=120/119,192.168.86.98, record[quada,in,agent.agentdvr.local.]=120/119,fe80::4dc7:9b31:4f17:27e5, record[quada,in,agent.agentdvr.local.]=120/119,fe80::b923:9f89:363e:6842]}> (120 bytes) as [b"\x00\x00\x84\x00\x00\x00\x00\x02\x00\x00\x00\x02\x05agent\x08agentdvr\x05local\x00\x00\x01\x00\x01\x00\x00\x00x\x00\x04\xc0\xa88\x01\xc0\x0c\x00\x01\x00\x01\x00\x00\x00x\x00\x04\xc0\xa8Vb\xc0\x0c\x00\x1c\x00\x01\x00\x00\x00x\x00\x10\xfe\x80\x00\x00\x00\x00\x00\x00M\xc7\x9b1O\x17'\xe5\xc0\x0c\x00\x1c\x00\x01\x00\x00\x00x\x00\x10\xfe\x80\x00\x00\x00\x00\x00\x00\xb9#\x9f\x896>hB"] 2022-04-30 22:16:00 DEBUG (MainThread) [zeroconf] IPv6 scope_id 0 associated to the receiving interface 2022-04-30 22:16:00 DEBUG (MainThread) [zeroconf] Ignoring duplicate message received from '::ffff:192.168.86.98':5353 [socket 12 (('::', 5353, 0, 0))] (120 bytes) as [b"\x00\x00\x84\x00\x00\x00\x00\x02\x00\x00\x00\x02\x05agent\x08agentdvr\x05local\x00\x00\x01\x00\x01\x00\x00\x00x\x00\x04\xc0\xa88\x01\xc0\x0c\x00\x01\x00\x01\x00\x00\x00x\x00\x04\xc0\xa8Vb\xc0\x0c\x00\x1c\x00\x01\x00\x00\x00x\x00\x10\xfe\x80\x00\x00\x00\x00\x00\x00M\xc7\x9b1O\x17'\xe5\xc0\x0c\x00\x1c\x00\x01\x00\x00\x00x\x00\x10\xfe\x80\x00\x00\x00\x00\x00\x00\xb9#\x9f\x896>hB"] 2022-04-30 22:16:02 DEBUG (MainThread) [homeassistant.components.meater] Finished fetching meater_api data in 0.553 seconds (success: True) 2022-04-30 22:16:03 DEBUG (MainThread) [async_upnp_client.traffic.ssdp] Received packet from ('192.168.86.1', 57113): b'NOTIFY HTTP/1.1\r\nHOST: 239.255.255.250:1900\r\nCACHE-CONTROL: max-age=120\r\nLOCATION: http://192.168.86.1:5000/rootDesc.xml\r\nSERVER: Linux/5.3.15-050315-generic UPnP/1.1 MiniUPnPd/1.9\r\nNT: uuid:0514dbb3-b9f4-4c94-878b-14891032ed5c\r\nUSN: uuid:0514dbb3-b9f4-4c94-878b-14891032ed5c\r\nNTS: ssdp:alive\r\nOPT: "http://schemas.upnp.org/upnp/1/0/"; ns=01\r\n01-NLS: 1\r\nBOOTID.UPNP.ORG: 1\r\nCONFIGID.UPNP.ORG: 1337\r\n\r\n' 2022-04-30 22:16:03 DEBUG (MainThread) [async_upnp_client.advertisement] Received advertisement, USN: uuid:0514dbb3-b9f4-4c94-878b-14891032ed5c, location: http://192.168.86.1:5000/rootDesc.xml 2022-04-30 22:16:03 DEBUG (MainThread) [async_upnp_client.ssdp_listener] Purging device, USN: uuid:07e5eac6-151d-4e34-b35e-b1646e488d9c 2022-04-30 22:16:03 DEBUG (MainThread) [async_upnp_client.ssdp_listener] Purging device, USN: uuid:07e5eac6-151d-4e34-b35e-b1646e488d9e 2022-04-30 22:16:03 DEBUG (MainThread) [async_upnp_client.ssdp_listener] Purging device, USN: uuid:07e5eac6-151d-4e34-b35e-b1646e488d9d 2022-04-30 22:16:03 DEBUG (MainThread) [async_upnp_client.ssdp_listener] Purging device, USN: uuid:b351d206-a948-455d-a96a-64ba80a83d65 2022-04-30 22:16:03 DEBUG (MainThread) [async_upnp_client.ssdp_listener] Purging device, USN: uuid:b351d206-a948-455d-a96a-64ba80a83d67 2022-04-30 22:16:03 DEBUG (MainThread) [async_upnp_client.ssdp_listener] Purging device, USN: uuid:b351d206-a948-455d-a96a-64ba80a83d66 2022-04-30 22:16:03 DEBUG (MainThread) [async_upnp_client.ssdp_listener] See new device: <SsdpDevice(uuid:0514dbb3-b9f4-4c94-878b-14891032ed5c)> 2022-04-30 22:16:03 DEBUG (MainThread) [async_upnp_client.ssdp_listener] See new service: <SsdpDevice(uuid:0514dbb3-b9f4-4c94-878b-14891032ed5c)>, type: uuid:0514dbb3-b9f4-4c94-878b-14891032ed5c 2022-04-30 22:16:03 DEBUG (MainThread) [homeassistant.components.ssdp] SSDP: ssdp_device: <SsdpDevice(uuid:0514dbb3-b9f4-4c94-878b-14891032ed5c)>, dst: uuid:0514dbb3-b9f4-4c94-878b-14891032ed5c, source: SsdpSource.ADVERTISEMENT_ALIVE 2022-04-30 22:16:03 DEBUG (MainThread) [async_upnp_client.traffic.ssdp] Received packet from ('192.168.86.1', 57113): b'NOTIFY HTTP/1.1\r\nHOST: 239.255.255.250:1900\r\nCACHE-CONTROL: max-age=120\r\nLOCATION: http://192.168.86.1:5000/rootDesc.xml\r\nSERVER: Linux/5.3.15-050315-generic UPnP/1.1 MiniUPnPd/1.9\r\nNT: urn:schemas-upnp-org:device:WANConnectionDevice:2\r\nUSN: uuid:0514dbb3-b9f4-4c94-878b-14891032ed5e::urn:schemas-upnp-org:device:WANConnectionDevice:2\r\nNTS: ssdp:alive\r\nOPT: "http://schemas.upnp.org/upnp/1/0/"; ns=01\r\n01-NLS: 1\r\nBOOTID.UPNP.ORG: 1\r\nCONFIGID.UPNP.ORG: 1337\r\n\r\n' 2022-04-30 22:16:03 DEBUG (MainThread) [async_upnp_client.advertisement] Received advertisement, USN: uuid:0514dbb3-b9f4-4c94-878b-14891032ed5e::urn:schemas-upnp-org:device:WANConnectionDevice:2, location: http://192.168.86.1:5000/rootDesc.xml 2022-04-30 22:16:03 DEBUG (MainThread) [async_upnp_client.ssdp_listener] See new device: <SsdpDevice(uuid:0514dbb3-b9f4-4c94-878b-14891032ed5e)> 2022-04-30 22:16:03 DEBUG (MainThread) [async_upnp_client.ssdp_listener] See new service: <SsdpDevice(uuid:0514dbb3-b9f4-4c94-878b-14891032ed5e)>, type: urn:schemas-upnp-org:device:WANConnectionDevice:2 2022-04-30 22:16:03 DEBUG (MainThread) [homeassistant.components.ssdp] SSDP: ssdp_device: <SsdpDevice(uuid:0514dbb3-b9f4-4c94-878b-14891032ed5e)>, dst: urn:schemas-upnp-org:device:WANConnectionDevice:2, source: SsdpSource.ADVERTISEMENT_ALIVE 2022-04-30 22:16:03 DEBUG (MainThread) [async_upnp_client.traffic.ssdp] Received packet from ('192.168.86.1', 57113): b'NOTIFY HTTP/1.1\r\nHOST: 239.255.255.250:1900\r\nCACHE-CONTROL: max-age=120\r\nLOCATION: http://192.168.86.1:5000/rootDesc.xml\r\nSERVER: Linux/5.3.15-050315-generic UPnP/1.1 MiniUPnPd/1.9\r\nNT: uuid:0514dbb3-b9f4-4c94-878b-14891032ed5e\r\nUSN: uuid:0514dbb3-b9f4-4c94-878b-14891032ed5e\r\nNTS: ssdp:alive\r\nOPT: "http://schemas.upnp.org/upnp/1/0/"; ns=01\r\n01-NLS: 1\r\nBOOTID.UPNP.ORG: 1\r\nCONFIGID.UPNP.ORG: 1337\r\n\r\n' 2022-04-30 22:16:03 DEBUG (MainThread) [async_upnp_client.advertisement] Received advertisement, USN: uuid:0514dbb3-b9f4-4c94-878b-14891032ed5e, location: http://192.168.86.1:5000/rootDesc.xml 2022-04-30 22:16:03 DEBUG (MainThread) [async_upnp_client.ssdp_listener] See new service: <SsdpDevice(uuid:0514dbb3-b9f4-4c94-878b-14891032ed5e)>, type: uuid:0514dbb3-b9f4-4c94-878b-14891032ed5e 2022-04-30 22:16:03 DEBUG (MainThread) [homeassistant.components.ssdp] SSDP: ssdp_device: <SsdpDevice(uuid:0514dbb3-b9f4-4c94-878b-14891032ed5e)>, dst: uuid:0514dbb3-b9f4-4c94-878b-14891032ed5e, source: SsdpSource.ADVERTISEMENT_ALIVE 2022-04-30 22:16:03 DEBUG (MainThread) [async_upnp_client.traffic.ssdp] Received packet from ('192.168.86.1', 57113): b'NOTIFY HTTP/1.1\r\nHOST: 239.255.255.250:1900\r\nCACHE-CONTROL: max-age=120\r\nLOCATION: http://192.168.86.1:5000/rootDesc.xml\r\nSERVER: Linux/5.3.15-050315-generic UPnP/1.1 MiniUPnPd/1.9\r\nNT: urn:schemas-upnp-org:device:WANDevice:2\r\nUSN: uuid:0514dbb3-b9f4-4c94-878b-14891032ed5d::urn:schemas-upnp-org:device:WANDevice:2\r\nNTS: ssdp:alive\r\nOPT: "http://schemas.upnp.org/upnp/1/0/"; ns=01\r\n01-NLS: 1\r\nBOOTID.UPNP.ORG: 1\r\nCONFIGID.UPNP.ORG: 1337\r\n\r\n' 2022-04-30 22:16:03 DEBUG (MainThread) [async_upnp_client.advertisement] Received advertisement, USN: uuid:0514dbb3-b9f4-4c94-878b-14891032ed5d::urn:schemas-upnp-org:device:WANDevice:2, location: http://192.168.86.1:5000/rootDesc.xml 2022-04-30 22:16:03 DEBUG (MainThread) [async_upnp_client.ssdp_listener] See new device: <SsdpDevice(uuid:0514dbb3-b9f4-4c94-878b-14891032ed5d)> 2022-04-30 22:16:03 DEBUG (MainThread) [async_upnp_client.ssdp_listener] See new service: <SsdpDevice(uuid:0514dbb3-b9f4-4c94-878b-14891032ed5d)>, type: urn:schemas-upnp-org:device:WANDevice:2 2022-04-30 22:16:03 DEBUG (MainThread) [homeassistant.components.ssdp] SSDP: ssdp_device: <SsdpDevice(uuid:0514dbb3-b9f4-4c94-878b-14891032ed5d)>, dst: urn:schemas-upnp-org:device:WANDevice:2, source: SsdpSource.ADVERTISEMENT_ALIVE 2022-04-30 22:16:03 DEBUG (MainThread) [async_upnp_client.traffic.ssdp] Received packet from ('192.168.86.1', 57113): b'NOTIFY HTTP/1.1\r\nHOST: 239.255.255.250:1900\r\nCACHE-CONTROL: max-age=120\r\nLOCATION: http://192.168.86.1:5000/rootDesc.xml\r\nSERVER: Linux/5.3.15-050315-generic UPnP/1.1 MiniUPnPd/1.9\r\nNT: urn:schemas-upnp-org:service:WANIPConnection:2\r\nUSN: uuid:0514dbb3-b9f4-4c94-878b-14891032ed5e::urn:schemas-upnp-org:service:WANIPConnection:2\r\nNTS: ssdp:alive\r\nOPT: "http://schemas.upnp.org/upnp/1/0/"; ns=01\r\n01-NLS: 1\r\nBOOTID.UPNP.ORG: 1\r\nCONFIGID.UPNP.ORG: 1337\r\n\r\n' 2022-04-30 22:16:03 DEBUG (MainThread) [async_upnp_client.advertisement] Received advertisement, USN: uuid:0514dbb3-b9f4-4c94-878b-14891032ed5e::urn:schemas-upnp-org:service:WANIPConnection:2, location: http://192.168.86.1:5000/rootDesc.xml 2022-04-30 22:16:03 DEBUG (MainThread) [async_upnp_client.ssdp_listener] See new service: <SsdpDevice(uuid:0514dbb3-b9f4-4c94-878b-14891032ed5e)>, type: urn:schemas-upnp-org:service:WANIPConnection:2 2022-04-30 22:16:03 DEBUG (MainThread) [homeassistant.components.ssdp] SSDP: ssdp_device: <SsdpDevice(uuid:0514dbb3-b9f4-4c94-878b-14891032ed5e)>, dst: urn:schemas-upnp-org:service:WANIPConnection:2, source: SsdpSource.ADVERTISEMENT_ALIVE 2022-04-30 22:16:03 DEBUG (MainThread) [async_upnp_client.traffic.ssdp] Received packet from ('192.168.86.1', 57113): b'NOTIFY HTTP/1.1\r\nHOST: 239.255.255.250:1900\r\nCACHE-CONTROL: max-age=120\r\nLOCATION: http://192.168.86.1:5000/rootDesc.xml\r\nSERVER: Linux/5.3.15-050315-generic UPnP/1.1 MiniUPnPd/1.9\r\nNT: urn:schemas-upnp-org:service:DeviceProtection:1\r\nUSN: uuid:0514dbb3-b9f4-4c94-878b-14891032ed5c::urn:schemas-upnp-org:service:DeviceProtection:1\r\nNTS: ssdp:alive\r\nOPT: "http://schemas.upnp.org/upnp/1/0/"; ns=01\r\n01-NLS: 1\r\nBOOTID.UPNP.ORG: 1\r\nCONFIGID.UPNP.ORG: 1337\r\n\r\n' 2022-04-30 22:16:03 DEBUG (MainThread) [async_upnp_client.advertisement] Received advertisement, USN: uuid:0514dbb3-b9f4-4c94-878b-14891032ed5c::urn:schemas-upnp-org:service:DeviceProtection:1, location: http://192.168.86.1:5000/rootDesc.xml 2022-04-30 22:16:03 DEBUG (MainThread) [async_upnp_client.ssdp_listener] See new service: <SsdpDevice(uuid:0514dbb3-b9f4-4c94-878b-14891032ed5c)>, type: urn:schemas-upnp-org:service:DeviceProtection:1 2022-04-30 22:16:03 DEBUG (MainThread) [homeassistant.components.ssdp] SSDP: ssdp_device: <SsdpDevice(uuid:0514dbb3-b9f4-4c94-878b-14891032ed5c)>, dst: urn:schemas-upnp-org:service:DeviceProtection:1, source: SsdpSource.ADVERTISEMENT_ALIVE 2022-04-30 22:16:03 DEBUG (MainThread) [async_upnp_client.traffic.ssdp] Received packet from ('192.168.86.1', 57113): b'NOTIFY HTTP/1.1\r\nHOST: 239.255.255.250:1900\r\nCACHE-CONTROL: max-age=120\r\nLOCATION: http://192.168.86.1:5000/rootDesc.xml\r\nSERVER: Linux/5.3.15-050315-generic UPnP/1.1 MiniUPnPd/1.9\r\nNT: urn:schemas-upnp-org:service:WANCommonInterfaceConfig:1\r\nUSN: uuid:0514dbb3-b9f4-4c94-878b-14891032ed5d::urn:schemas-upnp-org:service:WANCommonInterfaceConfig:1\r\nNTS: ssdp:alive\r\nOPT: "http://schemas.upnp.org/upnp/1/0/"; ns=01\r\n01-NLS: 1\r\nBOOTID.UPNP.ORG: 1\r\nCONFIGID.UPNP.ORG: 1337\r\n\r\n' 2022-04-30 22:16:03 DEBUG (MainThread) [async_upnp_client.advertisement] Received advertisement, USN: uuid:0514dbb3-b9f4-4c94-878b-14891032ed5d::urn:schemas-upnp-org:service:WANCommonInterfaceConfig:1, location: http://192.168.86.1:5000/rootDesc.xml 2022-04-30 22:16:03 DEBUG (MainThread) [async_upnp_client.ssdp_listener] See new service: <SsdpDevice(uuid:0514dbb3-b9f4-4c94-878b-14891032ed5d)>, type: urn:schemas-upnp-org:service:WANCommonInterfaceConfig:1 2022-04-30 22:16:03 DEBUG (MainThread) [homeassistant.components.ssdp] SSDP: ssdp_device: <SsdpDevice(uuid:0514dbb3-b9f4-4c94-878b-14891032ed5d)>, dst: urn:schemas-upnp-org:service:WANCommonInterfaceConfig:1, source: SsdpSource.ADVERTISEMENT_ALIVE 2022-04-30 22:16:03 DEBUG (MainThread) [async_upnp_client.traffic.ssdp] Received packet from ('192.168.86.1', 57113): b'NOTIFY HTTP/1.1\r\nHOST: 239.255.255.250:1900\r\nCACHE-CONTROL: max-age=120\r\nLOCATION: http://192.168.86.1:5000/rootDesc.xml\r\nSERVER: Linux/5.3.15-050315-generic UPnP/1.1 MiniUPnPd/1.9\r\nNT: urn:schemas-upnp-org:service:Layer3Forwarding:1\r\nUSN: uuid:0514dbb3-b9f4-4c94-878b-14891032ed5c::urn:schemas-upnp-org:service:Layer3Forwarding:1\r\nNTS: ssdp:alive\r\nOPT: "http://schemas.upnp.org/upnp/1/0/"; ns=01\r\n01-NLS: 1\r\nBOOTID.UPNP.ORG: 1\r\nCONFIGID.UPNP.ORG: 1337\r\n\r\n' 2022-04-30 22:16:03 DEBUG (MainThread) [async_upnp_client.advertisement] Received advertisement, USN: uuid:0514dbb3-b9f4-4c94-878b-14891032ed5c::urn:schemas-upnp-org:service:Layer3Forwarding:1, location: http://192.168.86.1:5000/rootDesc.xml 2022-04-30 22:16:03 DEBUG (MainThread) [async_upnp_client.ssdp_listener] See new service: <SsdpDevice(uuid:0514dbb3-b9f4-4c94-878b-14891032ed5c)>, type: urn:schemas-upnp-org:service:Layer3Forwarding:1 2022-04-30 22:16:03 DEBUG (MainThread) [homeassistant.components.ssdp] SSDP: ssdp_device: <SsdpDevice(uuid:0514dbb3-b9f4-4c94-878b-14891032ed5c)>, dst: urn:schemas-upnp-org:service:Layer3Forwarding:1, source: SsdpSource.ADVERTISEMENT_ALIVE 2022-04-30 22:16:03 DEBUG (MainThread) [async_upnp_client.traffic.ssdp] Received packet from ('192.168.86.1', 57113): b'NOTIFY HTTP/1.1\r\nHOST: 239.255.255.250:1900\r\nCACHE-CONTROL: max-age=120\r\nLOCATION: http://192.168.86.1:5000/rootDesc.xml\r\nSERVER: Linux/5.3.15-050315-generic UPnP/1.1 MiniUPnPd/1.9\r\nNT: urn:schemas-upnp-org:service:Layer3Forwarding:1\r\nUSN: uuid:0514dbb3-b9f4-4c94-878b-14891032ed5c::urn:schemas-upnp-org:service:Layer3Forwarding:1\r\nNTS: ssdp:alive\r\nOPT: "http://schemas.upnp.org/upnp/1/0/"; ns=01\r\n01-NLS: 1\r\nBOOTID.UPNP.ORG: 1\r\nCONFIGID.UPNP.ORG: 1337\r\n\r\n' 2022-04-30 22:16:03 DEBUG (MainThread) [async_upnp_client.advertisement] Received advertisement, USN: uuid:0514dbb3-b9f4-4c94-878b-14891032ed5c::urn:schemas-upnp-org:service:Layer3Forwarding:1, location: http://192.168.86.1:5000/rootDesc.xml 2022-04-30 22:16:03 DEBUG (MainThread) [async_upnp_client.traffic.ssdp] Received packet from ('192.168.86.1', 57113): b'NOTIFY HTTP/1.1\r\nHOST: 239.255.255.250:1900\r\nCACHE-CONTROL: max-age=120\r\nLOCATION: http://192.168.86.1:5000/rootDesc.xml\r\nSERVER: Linux/5.3.15-050315-generic UPnP/1.1 MiniUPnPd/1.9\r\nNT: urn:schemas-upnp-org:service:WANCommonInterfaceConfig:1\r\nUSN: uuid:0514dbb3-b9f4-4c94-878b-14891032ed5d::urn:schemas-upnp-org:service:WANCommonInterfaceConfig:1\r\nNTS: ssdp:alive\r\nOPT: "http://schemas.upnp.org/upnp/1/0/"; ns=01\r\n01-NLS: 1\r\nBOOTID.UPNP.ORG: 1\r\nCONFIGID.UPNP.ORG: 1337\r\n\r\n' 2022-04-30 22:16:03 DEBUG (MainThread) [async_upnp_client.advertisement] Received advertisement, USN: uuid:0514dbb3-b9f4-4c94-878b-14891032ed5d::urn:schemas-upnp-org:service:WANCommonInterfaceConfig:1, location: http://192.168.86.1:5000/rootDesc.xml 2022-04-30 22:16:03 DEBUG (MainThread) [async_upnp_client.traffic.ssdp] Received packet from ('192.168.86.1', 57113): b'NOTIFY HTTP/1.1\r\nHOST: 239.255.255.250:1900\r\nCACHE-CONTROL: max-age=120\r\nLOCATION: http://192.168.86.1:5000/rootDesc.xml\r\nSERVER: Linux/5.3.15-050315-generic UPnP/1.1 MiniUPnPd/1.9\r\nNT: urn:schemas-upnp-org:service:DeviceProtection:1\r\nUSN: uuid:0514dbb3-b9f4-4c94-878b-14891032ed5c::urn:schemas-upnp-org:service:DeviceProtection:1\r\nNTS: ssdp:alive\r\nOPT: "http://schemas.upnp.org/upnp/1/0/"; ns=01\r\n01-NLS: 1\r\nBOOTID.UPNP.ORG: 1\r\nCONFIGID.UPNP.ORG: 1337\r\n\r\n' 2022-04-30 22:16:03 DEBUG (MainThread) [async_upnp_client.advertisement] Received advertisement, USN: uuid:0514dbb3-b9f4-4c94-878b-14891032ed5c::urn:schemas-upnp-org:service:DeviceProtection:1, location: http://192.168.86.1:5000/rootDesc.xml 2022-04-30 22:16:03 DEBUG (MainThread) [async_upnp_client.traffic.ssdp] Received packet from ('192.168.86.1', 57113): b'NOTIFY HTTP/1.1\r\nHOST: 239.255.255.250:1900\r\nCACHE-CONTROL: max-age=120\r\nLOCATION: http://192.168.86.1:5000/rootDesc.xml\r\nSERVER: Linux/5.3.15-050315-generic UPnP/1.1 MiniUPnPd/1.9\r\nNT: urn:schemas-upnp-org:service:WANIPConnection:2\r\nUSN: uuid:0514dbb3-b9f4-4c94-878b-14891032ed5e::urn:schemas-upnp-org:service:WANIPConnection:2\r\nNTS: ssdp:alive\r\nOPT: "http://schemas.upnp.org/upnp/1/0/"; ns=01\r\n01-NLS: 1\r\nBOOTID.UPNP.ORG: 1\r\nCONFIGID.UPNP.ORG: 1337\r\n\r\n' 2022-04-30 22:16:03 DEBUG (MainThread) [async_upnp_client.advertisement] Received advertisement, USN: uuid:0514dbb3-b9f4-4c94-878b-14891032ed5e::urn:schemas-upnp-org:service:WANIPConnection:2, location: http://192.168.86.1:5000/rootDesc.xml 2022-04-30 22:16:03 DEBUG (MainThread) [async_upnp_client.traffic.ssdp] Received packet from ('192.168.86.1', 57113): b'NOTIFY HTTP/1.1\r\nHOST: 239.255.255.250:1900\r\nCACHE-CONTROL: max-age=120\r\nLOCATION: http://192.168.86.1:5000/rootDesc.xml\r\nSERVER: Linux/5.3.15-050315-generic UPnP/1.1 MiniUPnPd/1.9\r\nNT: uuid:0514dbb3-b9f4-4c94-878b-14891032ed5d\r\nUSN: uuid:0514dbb3-b9f4-4c94-878b-14891032ed5d\r\nNTS: ssdp:alive\r\nOPT: "http://schemas.upnp.org/upnp/1/0/"; ns=01\r\n01-NLS: 1\r\nBOOTID.UPNP.ORG: 1\r\nCONFIGID.UPNP.ORG: 1337\r\n\r\n' 2022-04-30 22:16:03 DEBUG (MainThread) [async_upnp_client.advertisement] Received advertisement, USN: uuid:0514dbb3-b9f4-4c94-878b-14891032ed5d, location: http://192.168.86.1:5000/rootDesc.xml 2022-04-30 22:16:03 DEBUG (MainThread) [async_upnp_client.ssdp_listener] See new service: <SsdpDevice(uuid:0514dbb3-b9f4-4c94-878b-14891032ed5d)>, type: uuid:0514dbb3-b9f4-4c94-878b-14891032ed5d 2022-04-30 22:16:03 DEBUG (MainThread) [homeassistant.components.ssdp] SSDP: ssdp_device: <SsdpDevice(uuid:0514dbb3-b9f4-4c94-878b-14891032ed5d)>, dst: uuid:0514dbb3-b9f4-4c94-878b-14891032ed5d, source: SsdpSource.ADVERTISEMENT_ALIVE 2022-04-30 22:16:03 DEBUG (MainThread) [async_upnp_client.traffic.ssdp] Received packet from ('192.168.86.1', 57113): b'NOTIFY HTTP/1.1\r\nHOST: 239.255.255.250:1900\r\nCACHE-CONTROL: max-age=120\r\nLOCATION: http://192.168.86.1:5000/rootDesc.xml\r\nSERVER: Linux/5.3.15-050315-generic UPnP/1.1 MiniUPnPd/1.9\r\nNT: uuid:0514dbb3-b9f4-4c94-878b-14891032ed5e\r\nUSN: uuid:0514dbb3-b9f4-4c94-878b-14891032ed5e\r\nNTS: ssdp:alive\r\nOPT: "http://schemas.upnp.org/upnp/1/0/"; ns=01\r\n01-NLS: 1\r\nBOOTID.UPNP.ORG: 1\r\nCONFIGID.UPNP.ORG: 1337\r\n\r\n' 2022-04-30 22:16:03 DEBUG (MainThread) [async_upnp_client.advertisement] Received advertisement, USN: uuid:0514dbb3-b9f4-4c94-878b-14891032ed5e, location: http://192.168.86.1:5000/rootDesc.xml 2022-04-30 22:16:03 DEBUG (MainThread) [async_upnp_client.traffic.ssdp] Received packet from ('192.168.86.1', 57113): b'NOTIFY HTTP/1.1\r\nHOST: 239.255.255.250:1900\r\nCACHE-CONTROL: max-age=120\r\nLOCATION: http://192.168.86.1:5000/rootDesc.xml\r\nSERVER: Linux/5.3.15-050315-generic UPnP/1.1 MiniUPnPd/1.9\r\nNT: urn:schemas-upnp-org:device:WANConnectionDevice:2\r\nUSN: uuid:0514dbb3-b9f4-4c94-878b-14891032ed5e::urn:schemas-upnp-org:device:WANConnectionDevice:2\r\nNTS: ssdp:alive\r\nOPT: "http://schemas.upnp.org/upnp/1/0/"; ns=01\r\n01-NLS: 1\r\nBOOTID.UPNP.ORG: 1\r\nCONFIGID.UPNP.ORG: 1337\r\n\r\n' 2022-04-30 22:16:03 DEBUG (MainThread) [async_upnp_client.advertisement] Received advertisement, USN: uuid:0514dbb3-b9f4-4c94-878b-14891032ed5e::urn:schemas-upnp-org:device:WANConnectionDevice:2, location: http://192.168.86.1:5000/rootDesc.xml 2022-04-30 22:16:03 DEBUG (MainThread) [async_upnp_client.traffic.ssdp] Received packet from ('192.168.86.1', 57113): b'NOTIFY HTTP/1.1\r\nHOST: 239.255.255.250:1900\r\nCACHE-CONTROL: max-age=120\r\nLOCATION: http://192.168.86.1:5000/rootDesc.xml\r\nSERVER: Linux/5.3.15-050315-generic UPnP/1.1 MiniUPnPd/1.9\r\nNT: uuid:0514dbb3-b9f4-4c94-878b-14891032ed5c\r\nUSN: uuid:0514dbb3-b9f4-4c94-878b-14891032ed5c\r\nNTS: ssdp:alive\r\nOPT: "http://schemas.upnp.org/upnp/1/0/"; ns=01\r\n01-NLS: 1\r\nBOOTID.UPNP.ORG: 1\r\nCONFIGID.UPNP.ORG: 1337\r\n\r\n' 2022-04-30 22:16:03 DEBUG (MainThread) [async_upnp_client.advertisement] Received advertisement, USN: uuid:0514dbb3-b9f4-4c94-878b-14891032ed5c, location: http://192.168.86.1:5000/rootDesc.xml 2022-04-30 22:16:03 DEBUG (MainThread) [async_upnp_client.traffic.ssdp] Received packet from ('192.168.86.1', 57113): b'NOTIFY HTTP/1.1\r\nHOST: 239.255.255.250:1900\r\nCACHE-CONTROL: max-age=120\r\nLOCATION: http://192.168.86.1:5000/rootDesc.xml\r\nSERVER: Linux/5.3.15-050315-generic UPnP/1.1 MiniUPnPd/1.9\r\nNT: urn:schemas-upnp-org:device:InternetGatewayDevice:2\r\nUSN: uuid:0514dbb3-b9f4-4c94-878b-14891032ed5c::urn:schemas-upnp-org:device:InternetGatewayDevice:2\r\nNTS: ssdp:alive\r\nOPT: "http://schemas.upnp.org/upnp/1/0/"; ns=01\r\n01-NLS: 1\r\nBOOTID.UPNP.ORG: 1\r\nCONFIGID.UPNP.ORG: 1337\r\n\r\n' 2022-04-30 22:16:03 DEBUG (MainThread) [async_upnp_client.advertisement] Received advertisement, USN: uuid:0514dbb3-b9f4-4c94-878b-14891032ed5c::urn:schemas-upnp-org:device:InternetGatewayDevice:2, location: http://192.168.86.1:5000/rootDesc.xml 2022-04-30 22:16:03 DEBUG (MainThread) [async_upnp_client.ssdp_listener] See new service: <SsdpDevice(uuid:0514dbb3-b9f4-4c94-878b-14891032ed5c)>, type: urn:schemas-upnp-org:device:InternetGatewayDevice:2 2022-04-30 22:16:03 DEBUG (MainThread) [homeassistant.components.ssdp] SSDP: ssdp_device: <SsdpDevice(uuid:0514dbb3-b9f4-4c94-878b-14891032ed5c)>, dst: urn:schemas-upnp-org:device:InternetGatewayDevice:2, source: SsdpSource.ADVERTISEMENT_ALIVE 2022-04-30 22:16:03 DEBUG (MainThread) [async_upnp_client.traffic.ssdp] Received packet from ('192.168.86.1', 57113): b'NOTIFY HTTP/1.1\r\nHOST: 239.255.255.250:1900\r\nCACHE-CONTROL: max-age=120\r\nLOCATION: http://192.168.86.1:5000/rootDesc.xml\r\nSERVER: Linux/5.3.15-050315-generic UPnP/1.1 MiniUPnPd/1.9\r\nNT: upnp:rootdevice\r\nUSN: uuid:0514dbb3-b9f4-4c94-878b-14891032ed5c::upnp:rootdevice\r\nNTS: ssdp:alive\r\nOPT: "http://schemas.upnp.org/upnp/1/0/"; ns=01\r\n01-NLS: 1\r\nBOOTID.UPNP.ORG: 1\r\nCONFIGID.UPNP.ORG: 1337\r\n\r\n' 2022-04-30 22:16:03 DEBUG (MainThread) [async_upnp_client.advertisement] Received advertisement, USN: uuid:0514dbb3-b9f4-4c94-878b-14891032ed5c::upnp:rootdevice, location: http://192.168.86.1:5000/rootDesc.xml 2022-04-30 22:16:03 DEBUG (MainThread) [async_upnp_client.ssdp_listener] See new service: <SsdpDevice(uuid:0514dbb3-b9f4-4c94-878b-14891032ed5c)>, type: upnp:rootdevice 2022-04-30 22:16:03 DEBUG (MainThread) [homeassistant.components.ssdp] SSDP: ssdp_device: <SsdpDevice(uuid:0514dbb3-b9f4-4c94-878b-14891032ed5c)>, dst: upnp:rootdevice, source: SsdpSource.ADVERTISEMENT_ALIVE 2022-04-30 22:16:06 DEBUG (MainThread) [rokuecp] Requesting http://192.168.86.32:8060/query/device-info returned OrderedDict([('device-info', OrderedDict([('udn', '29600005-780b-1017-8000-c83a6b55f897'), ('serial-number', 'YH005N726784'), ('device-id', 'C237A5726784'), ('advertising-id', '0d302e80-7b2d-5875-ac5d-bbc73aeb9a8c'), ('vendor-name', 'Roku'), ('model-name', 'Roku Streaming Stick+'), ('model-number', '3810RW'), ('model-region', 'US'), ('is-tv', 'false'), ('is-stick', 'true'), ('ui-resolution', '1080p'), ('supports-ethernet', 'false'), ('wifi-mac', 'c8:3a:6b:55:f8:97'), ('wifi-driver', 'realtek'), ('has-wifi-extender', 'false'), ('has-wifi-5G-support', 'true'), ('can-use-wifi-extender', 'true'), ('network-type', 'wifi'), ('network-name', 'Woody’s Google'), ('friendly-device-name', 'Kids bedroom'), ('friendly-model-name', 'Roku Streaming Stick+'), ('default-device-name', 'Roku Streaming Stick+ - YH005N726784'), ('user-device-name', 'Kids bedroom'), ('user-device-location', 'Kids bedroom'), ('build-number', '50A.50E04208A'), ('software-version', '10.5.0'), ('software-build', '4208'), ('secure-device', 'true'), ('language', 'en'), ('country', 'US'), ('locale', 'en_US'), ('time-zone-auto', 'true'), ('time-zone', 'US/Central'), ('time-zone-name', 'United States/Central'), ('time-zone-tz', 'America/Chicago'), ('time-zone-offset', '-300'), ('clock-format', '12-hour'), ('uptime', '1977'), ('power-mode', 'PowerOn'), ('supports-suspend', 'false'), ('supports-find-remote', 'true'), ('find-remote-is-possible', 'false'), ('supports-audio-guide', 'true'), ('supports-rva', 'true'), ('developer-enabled', 'false'), ('keyed-developer-id', None), ('search-enabled', 'true'), ('search-channels-enabled', 'true'), ('voice-search-enabled', 'true'), ('notifications-enabled', 'true'), ('notifications-first-use', 'true'), ('supports-private-listening', 'true'), ('headphones-connected', 'false'), ('supports-audio-settings', 'false'), ('supports-ecs-textedit', 'true'), ('supports-ecs-microphone', 'true'), ('supports-wake-on-wlan', 'false'), ('supports-airplay', 'true'), ('has-play-on-roku', 'true'), ('has-mobile-screensaver', 'true'), ('support-url', 'roku.com/support'), ('grandcentral-version', '7.1.50'), ('trc-version', '3.0'), ('trc-channel-version', '6.0.15'), ('davinci-version', '2.8.20')]))]) 2022-04-30 22:16:06 DEBUG (MainThread) [rokuecp] Requesting http://192.168.86.32:8060/query/active-app returned OrderedDict([('active-app', OrderedDict([('app', @.', '291097'), @.', 'appl'), @.', '1.21.2022041900'), ('#text', 'Disney Plus')]))]))]) 2022-04-30 22:16:06 DEBUG (MainThread) [rokuecp] Requesting http://192.168.86.32:8060/query/media-player returned OrderedDict([('player', @.', 'false'), @.', 'play'), ('plugin', @.', '74868604 bps'), @.', '291097'), @.', 'Disney Plus')])), ('format', @.', 'aac'), @.', 'webvtt'), @.', 'hls'), @.', 'Widevine'), @.', 'mpeg4_10b')])), ('buffering', @.', '1000'), @.', '1000'), @.', '0')])), ('new_stream', @.', '128000 bps')])), ('position', '158398 ms'), ('duration', '496853 ms'), ('is_live', 'false'), ('stream_segment', @.', '13689505'), @.', '1080'), @._sequence', '18'), @._type', 'video'), @.', '144144'), @.***', '1920')]))]))]) 2022-04-30 22:16:06 DEBUG (MainThread) [homeassistant.components.roku.coordinator] Finished fetching roku data in 0.076 seconds (success: True) 2022-04-30 22:16:06 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=media_player.hattie, old_state=<state media_player.hattie=playing; source_list=['Home', 'ABC', 'Air Hockey Free', 'Apple TV', 'Bubble Shooter Free', 'Candy Bear 2 Free', 'Cups and Ball Free', 'Discovery GO', 'Disney Plus', 'ESPN', 'FOX NOW', 'FOX Sports', 'Fidget Spinner Free', 'Food Network GO', 'Foosball Free', 'Four In A Row Free', 'Freeform - Movies & TV Shows', 'HBO Max', 'HGTV GO', 'HISTORY', 'Hallmark TV', 'HappyKids', 'Hulu', 'Kids & Family on The Roku Channel', 'Live TV on The Roku Channel', 'NBC', 'NOGGIN Preschool kids shows', 'Neon Rider', 'Netflix', 'Plex - Free Movies & TV', 'Prime Video', 'Rainbow Dash', 'Roku Media Player', 'Roku | Getting Started', 'Sling TV', 'Snakes Free', 'Spectrum TV', 'The Roku Channel', 'VUDU', 'Vudu Movie & TV Store', 'YouTube', 'discovery+ | Stream TV Shows'], media_content_type=app, media_duration=496, media_position=148, media_position_updated_at=2022-05-01T02:58:40.520948+00:00, app_id=291097, app_name=Disney Plus, source=Disney Plus, device_class=receiver, entity_picture=/api/media_player_proxy/media_player.hattie?token=57230cdba3ca502a7c640931d56405f4cf0be2224c14c0062cb44d6bf71692ce&cache=b6fa7af45c056517, friendly_name=Hattie, supported_features=151481 @ 2022-05-01T03:13:34.961704+00:00>, new_state=<state media_player.hattie=playing; source_list=['Home', 'ABC', 'Air Hockey Free', 'Apple TV', 'Bubble Shooter Free', 'Candy Bear 2 Free', 'Cups and Ball Free', 'Discovery GO', 'Disney Plus', 'ESPN', 'FOX NOW', 'FOX Sports', 'Fidget Spinner Free', 'Food Network GO', 'Foosball Free', 'Four In A Row Free', 'Freeform - Movies & TV Shows', 'HBO Max', 'HGTV GO', 'HISTORY', 'Hallmark TV', 'HappyKids', 'Hulu', 'Kids & Family on The Roku Channel', 'Live TV on The Roku Channel', 'NBC', 'NOGGIN Preschool kids shows', 'Neon Rider', 'Netflix', 'Plex - Free Movies & TV', 'Prime Video', 'Rainbow Dash', 'Roku Media Player', 'Roku | Getting Started', 'Sling TV', 'Snakes Free', 'Spectrum TV', 'The Roku Channel', 'VUDU', 'Vudu Movie & TV Store', 'YouTube', 'discovery+ | Stream TV Shows'], media_content_type=app, media_duration=496, media_position=158, media_position_updated_at=2022-05-01T02:58:40.520948+00:00, app_id=291097, app_name=Disney Plus, source=Disney Plus, device_class=receiver, entity_picture=/api/media_player_proxy/media_player.hattie?token=57230cdba3ca502a7c640931d56405f4cf0be2224c14c0062cb44d6bf71692ce&cache=b6fa7af45c056517, friendly_name=Hattie, supported_features=151481 @ 2022-05-01T03:13:34.961704+00:00>> 2022-04-30 22:16:06 DEBUG (MainThread) [homeassistant.components.websocket_api.http.connection] [140096801994592] Sending {"id":2,"type":"event","event":{"event_type":"state_changed","data":{"entity_id":"media_player.hattie","old_state":{"entity_id":"media_player.hattie","state":"playing","attributes":{"source_list":["Home","ABC","Air Hockey Free","Apple TV","Bubble Shooter Free","Candy Bear 2 Free","Cups and Ball Free","Discovery GO","Disney Plus","ESPN","FOX NOW","FOX Sports","Fidget Spinner Free","Food Network GO","Foosball Free","Four In A Row Free","Freeform - Movies & TV Shows","HBO Max","HGTV GO","HISTORY","Hallmark TV","HappyKids","Hulu","Kids & Family on The Roku Channel","Live TV on The Roku Channel","NBC","NOGGIN Preschool kids shows","Neon Rider","Netflix","Plex - Free Movies & TV","Prime Video","Rainbow Dash","Roku Media Player","Roku | Getting Started","Sling TV","Snakes Free","Spectrum TV","The Roku Channel","VUDU","Vudu Movie & TV Store","YouTube","discovery+ | Stream TV Shows"],"media_content_type":"app","media_duration":496,"media_position":148,"media_position_updated_at":"2022-05-01T02:58:40.520948","app_id":"291097","app_name":"Disney Plus","source":"Disney Plus","device_class":"receiver","entity_picture":"/api/media_player_proxy/media_player.hattie?token=57230cdba3ca502a7c640931d56405f4cf0be2224c14c0062cb44d6bf71692ce&cache=b6fa7af45c056517","friendly_name":"Hattie","supported_features":151481},"last_changed":"2022-05-01T03:13:34.961704+00:00","last_updated":"2022-05-01T03:15:56.292277+00:00","context":{"id":"01807d9e1f046659a0d3950a396b5331","parent_id":null,"user_id":null}},"new_state":{"entity_id":"media_player.hattie","state":"playing","attributes":{"source_list":["Home","ABC","Air Hockey Free","Apple TV","Bubble Shooter Free","Candy Bear 2 Free","Cups and Ball Free","Discovery GO","Disney Plus","ESPN","FOX NOW","FOX Sports","Fidget Spinner Free","Food Network GO","Foosball Free","Four In A Row Free","Freeform - Movies & TV Shows","HBO Max","HGTV GO","HISTORY","Hallmark TV","HappyKids","Hulu","Kids & Family on The Roku Channel","Live TV on The Roku Channel","NBC","NOGGIN Preschool kids shows","Neon Rider","Netflix","Plex - Free Movies & TV","Prime Video","Rainbow Dash","Roku Media Player","Roku | Getting Started","Sling TV","Snakes Free","Spectrum TV","The Roku Channel","VUDU","Vudu Movie & TV Store","YouTube","discovery+ | Stream TV Shows"],"media_content_type":"app","media_duration":496,"media_position":158,"media_position_updated_at":"2022-05-01T02:58:40.520948","app_id":"291097","app_name":"Disney Plus","source":"Disney Plus","device_class":"receiver","entity_picture":"/api/media_player_proxy/media_player.hattie?token=57230cdba3ca502a7c640931d56405f4cf0be2224c14c0062cb44d6bf71692ce&cache=b6fa7af45c056517","friendly_name":"Hattie","supported_features":151481},"last_changed":"2022-05-01T03:13:34.961704+00:00","last_updated":"2022-05-01T03:16:06.083068+00:00","context":{"id":"01807d9e4543ad8052d63f7e51936bee","parent_id":null,"user_id":null}}},"origin":"LOCAL","time_fired":"2022-05-01T03:16:06.083068+00:00","context":{"id":"01807d9e4543ad8052d63f7e51936bee","parent_id":null,"user_id":null}}} 2022-04-30 22:16:06 DEBUG (Recorder) [homeassistant.components.recorder] Processing task: EventTask(event=<Event state_changed[L]: entity_id=media_player.hattie, old_state=<state media_player.hattie=playing; source_list=['Home', 'ABC', 'Air Hockey Free', 'Apple TV', 'Bubble Shooter Free', 'Candy Bear 2 Free', 'Cups and Ball Free', 'Discovery GO', 'Disney Plus', 'ESPN', 'FOX NOW', 'FOX Sports', 'Fidget Spinner Free', 'Food Network GO', 'Foosball Free', 'Four In A Row Free', 'Freeform - Movies & TV Shows', 'HBO Max', 'HGTV GO', 'HISTORY', 'Hallmark TV', 'HappyKids', 'Hulu', 'Kids & Family on The Roku Channel', 'Live TV on The Roku Channel', 'NBC', 'NOGGIN Preschool kids shows', 'Neon Rider', 'Netflix', 'Plex - Free Movies & TV', 'Prime Video', 'Rainbow Dash', 'Roku Media Player', 'Roku | Getting Started', 'Sling TV', 'Snakes Free', 'Spectrum TV', 'The Roku Channel', 'VUDU', 'Vudu Movie & TV Store', 'YouTube', 'discovery+ | Stream TV Shows'], media_content_type=app, media_duration=496, media_position=148, media_position_updated_at=2022-05-01T02:58:40.520948+00:00, app_id=291097, app_name=Disney Plus, source=Disney Plus, device_class=receiver, entity_picture=/api/media_player_proxy/media_player.hattie?token=57230cdba3ca502a7c640931d56405f4cf0be2224c14c0062cb44d6bf71692ce&cache=b6fa7af45c056517, friendly_name=Hattie, supported_features=151481 @ 2022-05-01T03:13:34.961704+00:00>, new_state=<state media_player.hattie=playing; source_list=['Home', 'ABC', 'Air Hockey Free', 'Apple TV', 'Bubble Shooter Free', 'Candy Bear 2 Free', 'Cups and Ball Free', 'Discovery GO', 'Disney Plus', 'ESPN', 'FOX NOW', 'FOX Sports', 'Fidget Spinner Free', 'Food Network GO', 'Foosball Free', 'Four In A Row Free', 'Freeform - Movies & TV Shows', 'HBO Max', 'HGTV GO', 'HISTORY', 'Hallmark TV', 'HappyKids', 'Hulu', 'Kids & Family on The Roku Channel', 'Live TV on The Roku Channel', 'NBC', 'NOGGIN Preschool kids shows', 'Neon Rider', 'Netflix', 'Plex - Free Movies & TV', 'Prime Video', 'Rainbow Dash', 'Roku Media Player', 'Roku | Getting Started', 'Sling TV', 'Snakes Free', 'Spectrum TV', 'The Roku Channel', 'VUDU', 'Vudu Movie & TV Store', 'YouTube', 'discovery+ | Stream TV Shows'], media_content_type=app, media_duration=496, media_position=158, media_position_updated_at=2022-05-01T02:58:40.520948+00:00, app_id=291097, app_name=Disney Plus, source=Disney Plus, device_class=receiver, entity_picture=/api/media_player_proxy/media_player.hattie?token=57230cdba3ca502a7c640931d56405f4cf0be2224c14c0062cb44d6bf71692ce&cache=b6fa7af45c056517, friendly_name=Hattie, supported_features=151481 @ 2022-05-01T03:13:34.961704+00:00>>) 2022-04-30 22:16:06 DEBUG (MainThread) [homeassistant.components.websocket_api.http.connection] [140096733976992] Sending {"id":2,"type":"event","event":{"c":{"media_player.hattie":{"+":{"lu":1651374966.083068,"c":"01807d9e4543ad8052d63f7e51936bee","a":{"media_position":158}}}}}} 2022-04-30 22:16:06 DEBUG (MainThread) [homeassistant.components.websocket_api.http.connection] [140096733976992] Sending {"id":24,"type":"event","event":{"event_type":"state_changed","data":{"entity_id":"media_player.hattie","old_state":{"entity_id":"media_player.hattie","state":"playing","attributes":{"source_list":["Home","ABC","Air Hockey Free","Apple TV","Bubble Shooter Free","Candy Bear 2 Free","Cups and Ball Free","Discovery GO","Disney Plus","ESPN","FOX NOW","FOX Sports","Fidget Spinner Free","Food Network GO","Foosball Free","Four In A Row Free","Freeform - Movies & TV Shows","HBO Max","HGTV GO","HISTORY","Hallmark TV","HappyKids","Hulu","Kids & Family on The Roku Channel","Live TV on The Roku Channel","NBC","NOGGIN Preschool kids shows","Neon Rider","Netflix","Plex - Free Movies & TV","Prime Video","Rainbow Dash","Roku Media Player","Roku | Getting Started","Sling TV","Snakes Free","Spectrum TV","The Roku Channel","VUDU","Vudu Movie & TV Store","YouTube","discovery+ | Stream TV Shows"],"media_content_type":"app","media_duration":496,"media_position":148,"media_position_updated_at":"2022-05-01T02:58:40.520948","app_id":"291097","app_name":"Disney Plus","source":"Disney Plus","device_class":"receiver","entity_picture":"/api/media_player_proxy/media_player.hattie?token=57230cdba3ca502a7c640931d56405f4cf0be2224c14c0062cb44d6bf71692ce&cache=b6fa7af45c056517","friendly_name":"Hattie","supported_features":151481},"last_changed":"2022-05-01T03:13:34.961704+00:00","last_updated":"2022-05-01T03:15:56.292277+00:00","context":{"id":"01807d9e1f046659a0d3950a396b5331","parent_id":null,"user_id":null}},"new_state":{"entity_id":"media_player.hattie","state":"playing","attributes":{"source_list":["Home","ABC","Air Hockey Free","Apple TV","Bubble Shooter Free","Candy Bear 2 Free","Cups and Ball Free","Discovery GO","Disney Plus","ESPN","FOX NOW","FOX Sports","Fidget Spinner Free","Food Network GO","Foosball Free","Four In A Row Free","Freeform - Movies & TV Shows","HBO Max","HGTV GO","HISTORY","Hallmark TV","HappyKids","Hulu","Kids & Family on The Roku Channel","Live TV on The Roku Channel","NBC","NOGGIN Preschool kids shows","Neon Rider","Netflix","Plex - Free Movies & TV","Prime Video","Rainbow Dash","Roku Media Player","Roku | Getting Started","Sling TV","Snakes Free","Spectrum TV","The Roku Channel","VUDU","Vudu Movie & TV Store","YouTube","discovery+ | Stream TV Shows"],"media_content_type":"app","media_duration":496,"media_position":158,"media_position_updated_at":"2022-05-01T02:58:40.520948","app_id":"291097","app_name":"Disney Plus","source":"Disney Plus","device_class":"receiver","entity_picture":"/api/media_player_proxy/media_player.hattie?token=57230cdba3ca502a7c640931d56405f4cf0be2224c14c0062cb44d6bf71692ce&cache=b6fa7af45c056517","friendly_name":"Hattie","supported_features":151481},"last_changed":"2022-05-01T03:13:34.961704+00:00","last_updated":"2022-05-01T03:16:06.083068+00:00","context":{"id":"01807d9e4543ad8052d63f7e51936bee","parent_id":null,"user_id":null}}},"origin":"LOCAL","time_fired":"2022-05-01T03:16:06.083068+00:00","context":{"id":"01807d9e4543ad8052d63f7e51936bee","parent_id":null,"user_id":null}}} 2022-04-30 22:16:06 DEBUG (MainThread) [zeroconf] IPv6 scope_id 0 associated to the receiving interface 2022-04-30 22:16:06 DEBUG (MainThread) [zeroconf] Received from '::ffff:192.168.86.25':5353 [socket 12 (('::', 5353, 0, 0))]: <DNSIncoming:{id=0, flags=0, truncated=False, n_q=1, n_ans=0, n_auth=0, n_add=0, questions=[ptr[question,QM,in,_services._dns-sd._udp.local.]], answers=[]}> (46 bytes) as [b'\x00\x00\x00\x00\x00\x01\x00\x00\x00\x00\x00\x00\t_services\x07_dns-sd\x04_udp\x05local\x00\x00\x0c\x00\x01'] 2022-04-30 22:16:06 DEBUG (MainThread) [zeroconf] IPv6 scope_id 0 associated to the receiving interface 2022-04-30 22:16:06 DEBUG (MainThread) [zeroconf] Received from '::ffff:192.168.86.25':5353 [socket 12 (('::', 5353, 0, 0))]: <DNSIncoming:{id=0, flags=33792, truncated=False, n_q=0, n_ans=1, n_auth=0, n_add=0, questions=[], answers=[record[ptr,in,_services._dns-sd._udp.local.]=4500/4499,_nvstream_dbd._tcp.local.]}> (73 bytes) as [b'\x00\x00\x84\x00\x00\x00\x00\x01\x00\x00\x00\x00\t_services\x07_dns-sd\x04_udp\x05local\x00\x00\x0c\x00\x01\x00\x00\x11\x94\x00\x15\r_nvstream_dbd\x04_tcp\xc0#'] 2022-04-30 22:16:06 DEBUG (MainThread) [zeroconf] IPv6 scope_id 0 associated to the receiving interface 2022-04-30 22:16:06 DEBUG (MainThread) [zeroconf] Received from '::ffff:192.168.86.25':5353 [socket 12 (('::', 5353, 0, 0))]: <DNSIncoming:{id=0, flags=33792, truncated=False, n_q=0, n_ans=1, n_auth=0, n_add=0, questions=[], answers=[record[ptr,in,_services._dns-sd._udp.local.]=4500/4499,_agentdvr._tcp.local.]}> (69 bytes) as [b'\x00\x00\x84\x00\x00\x00\x00\x01\x00\x00\x00\x00\t_services\x07_dns-sd\x04_udp\x05local\x00\x00\x0c\x00\x01\x00\x00\x11\x94\x00\x11\t_agentdvr\x04_tcp\xc0#'] 2022-04-30 22:16:06 DEBUG (MainThread) [zeroconf] IPv6 scope_id 0 associated to the receiving interface 2022-04-30 22:16:06 DEBUG (MainThread) [zeroconf] Received from '::ffff:192.168.86.25':5353 [socket 12 (('::', 5353, 0, 0))]: <DNSIncoming:{id=0, flags=0, truncated=False, n_q=1, n_ans=0, n_auth=0, n_add=0, questions=[ptr[question,QM,in,_agentdvr._tcp.local.]], answers=[]}> (38 bytes) as [b'\x00\x00\x00\x00\x00\x01\x00\x00\x00\x00\x00\x00\t_agentdvr\x04_tcp\x05local\x00\x00\x0c\x00\x01'] 2022-04-30 22:16:06 DEBUG (MainThread) [zeroconf] IPv6 scope_id 0 associated to the receiving interface 2022-04-30 22:16:06 DEBUG (MainThread) [zeroconf] Received from '::ffff:192.168.86.25':5353 [socket 12 (('::', 5353, 0, 0))]: <DNSIncoming:{id=0, flags=33792, truncated=False, n_q=0, n_ans=1, n_auth=0, n_add=4, questions=[], answers=[record[ptr,in,_agentdvr._tcp.local.]=4500/4499,53edb993-4233-4a9f-a4d9-f1426bcad27e._agentdvr._tcp.local., record[txt,in,53edb993-4233-4a9f-a4d9-f1426bcad27e._agentdvr._tcp.local.]=4500/4499,b'\ttxtver'..., record[srv,in,53edb993-4233-4a9f-a4d9-f1426bcad27e._agentdvr._tcp.local.]=4500/4499,53edb993-4233-4a9f-a4d9-f1426bcad27e.agentdvr.local.:8090, record[a,in,53edb993-4233-4a9f-a4d9-f1426bcad27e.agentdvr.local.]=120/119,192.168.86.25, record[quada,in,53edb993-4233-4a9f-a4d9-f1426bcad27e.agentdvr.local.]=120/119,fe80::54f3:1f72:cba2:c89e]}> (227 bytes) as [b"\x00\x00\

teharris1 commented 2 years ago

Please upgrade to 2022.5.0b4. This has a newer version of the pyinsteon library. I doubt it will fix the issue but it does have a few positive improvements.

teharris1 commented 2 years ago

The logs you uploaded don't have the info needed. It looks like you have set the entire system to debug. This is what you want in the configuration.yaml file:

logger:
  default: warn
  logs:
    pyinsteon: debug

That will get me enough info and will create a smaller log file.

darikweitzel commented 2 years ago

I’ll change it up and send them over. Thanks for being patient.

Sent from my iPhone

On May 1, 2022, at 10:07 AM, Tom Harris @.***> wrote:

 The logs you uploaded don't have the info needed. It looks like you have set the entire system to debug. This is what you want in the configuration.yaml file:

logger: default: warn logs: pyinsteon: debug That will get me enough info and will create a smaller log file.

— Reply to this email directly, view it on GitHub, or unsubscribe. You are receiving this because you authored the thread.

darikweitzel commented 2 years ago

well i am kind of confused in the log because under warnings, there is no warnings for the thermostat but are for a couple other things list in this document. which the log stops 4 min before I started my test from 118am to 1125am. No warnings in that time frame. In the other debug log that doesnt show warnings, it shows me adjusting the thermostat on HA like it should work.

On Sun, May 1, 2022 at 10:40 AM Darik Weitzel @.***> wrote:

I’ll change it up and send them over. Thanks for being patient.

Sent from my iPhone

On May 1, 2022, at 10:07 AM, Tom Harris @.***> wrote:



The logs you uploaded don't have the info needed. It looks like you have set the entire system to debug. This is what you want in the configuration.yaml file:

logger: default: warn logs: pyinsteon: debug

That will get me enough info and will create a smaller log file.

— Reply to this email directly, view it on GitHub https://github.com/home-assistant/core/issues/71123#issuecomment-1114261155, or unsubscribe https://github.com/notifications/unsubscribe-auth/AY2GN6USTGSRX5PZKAEK4LLVH2M47ANCNFSM5UYSS5HQ . You are receiving this because you authored the thread.Message ID: @.***>

http://www.avg.com/email-signature?utm_medium=email&utm_source=link&utm_campaign=sig-email&utm_content=webmail Virus-free. www.avg.com http://www.avg.com/email-signature?utm_medium=email&utm_source=link&utm_campaign=sig-email&utm_content=webmail <#DAB4FAD8-2DD7-40BB-A1B8-4E2AA1F9FDF2>

thermostat","entity":"climate.thermostat_adapter_4f_67_c7_group_1

2022-04-30 22:28:08 WARNING (SyncWorker_0) [homeassistant.loader] We found a custom integration hacs which has not been tested by Home Assistant. This component might cause stability problems, be sure to disable it if you experience issues with Home Assistant 2022-04-30 22:28:08 WARNING (SyncWorker_0) [homeassistant.loader] We found a custom integration garbage_collection which has not been tested by Home Assistant. This component might cause stability problems, be sure to disable it if you experience issues with Home Assistant 2022-04-30 22:28:10 ERROR (MainThread) [homeassistant.components.websocket_api.http.connection] [139806732691440] Received invalid command: mobile_app/push_notification_channel 2022-04-30 22:28:10 ERROR (MainThread) [homeassistant.components.websocket_api.http.connection] [139806732691440] Received invalid command: mobile_app/push_notification_channel 2022-04-30 22:28:10 ERROR (MainThread) [homeassistant.components.websocket_api.http.connection] [139806732691440] Received invalid command: mobile_app/push_notification_channel 2022-04-30 22:28:10 ERROR (MainThread) [homeassistant.components.websocket_api.http.connection] [139806732691440] Received invalid command: mobile_app/push_notification_channel 2022-04-30 22:28:10 ERROR (MainThread) [homeassistant.components.websocket_api.http.connection] [139806732691440] Received invalid command: mobile_app/push_notification_channel 2022-04-30 22:28:10 ERROR (MainThread) [homeassistant.components.websocket_api.http.connection] [139806732691440] Received invalid command: mobile_app/push_notification_channel 2022-04-30 22:28:10 ERROR (MainThread) [homeassistant.components.websocket_api.http.connection] [139806732691440] Received invalid command: mobile_app/push_notification_channel 2022-04-30 22:28:10 ERROR (MainThread) [homeassistant.components.websocket_api.http.connection] [139806732691440] Received invalid command: mobile_app/push_notification_channel 2022-04-30 22:28:10 ERROR (MainThread) [homeassistant.components.websocket_api.http.connection] [139806732691440] Received invalid command: mobile_app/push_notification_channel 2022-04-30 22:28:10 ERROR (MainThread) [homeassistant.components.websocket_api.http.connection] [139806732691440] Received invalid command: mobile_app/push_notification_channel 2022-04-30 22:28:10 ERROR (MainThread) [homeassistant.components.websocket_api.http.connection] [139806732691440] Received invalid command: mobile_app/push_notification_channel 2022-04-30 22:30:20 WARNING (MainThread) [homeassistant.components.zone.trigger] Automation 'Zone Notifications ' is referencing non-existing zone 'zone.neighborhood' in a zone trigger 2022-04-30 22:30:20 WARNING (MainThread) [homeassistant.components.zone.trigger] Automation 'Zone Notifications ' is referencing non-existing zone 'zone.neighborhood' in a zone trigger 2022-04-30 22:38:08 WARNING (MainThread) [homeassistant.components.zone.trigger] Automation 'Zone Notifications ' is referencing non-existing zone 'zone.neighborhood' in a zone trigger 2022-04-30 22:38:08 WARNING (MainThread) [homeassistant.components.zone.trigger] Automation 'Zone Notifications ' is referencing non-existing zone 'zone.neighborhood' in a zone trigger 2022-04-30 22:46:13 WARNING (MainThread) [homeassistant.components.zone.trigger] Automation 'Zone Notifications ' is referencing non-existing zone 'zone.neighborhood' in a zone trigger 2022-04-30 22:46:13 WARNING (MainThread) [homeassistant.components.zone.trigger] Automation 'Zone Notifications ' is referencing non-existing zone 'zone.neighborhood' in a zone trigger 2022-04-30 22:49:11 ERROR (MainThread) [pyinsteon.protocol.http_reader_writer] Client error: (<class 'aiohttp.client_exceptions.ClientOSError'>) [Errno 104] Connection reset by peer 2022-04-30 22:53:50 WARNING (MainThread) [homeassistant.components.zone.trigger] Automation 'Zone Notifications ' is referencing non-existing zone 'zone.neighborhood' in a zone trigger 2022-04-30 22:53:50 WARNING (MainThread) [homeassistant.components.zone.trigger] Automation 'Zone Notifications ' is referencing non-existing zone 'zone.neighborhood' in a zone trigger 2022-04-30 23:01:49 WARNING (MainThread) [homeassistant.components.zone.trigger] Automation 'Zone Notifications ' is referencing non-existing zone 'zone.neighborhood' in a zone trigger 2022-04-30 23:01:49 WARNING (MainThread) [homeassistant.components.zone.trigger] Automation 'Zone Notifications ' is referencing non-existing zone 'zone.neighborhood' in a zone trigger 2022-04-30 23:09:26 WARNING (MainThread) [homeassistant.components.zone.trigger] Automation 'Zone Notifications ' is referencing non-existing zone 'zone.neighborhood' in a zone trigger 2022-04-30 23:09:26 WARNING (MainThread) [homeassistant.components.zone.trigger] Automation 'Zone Notifications ' is referencing non-existing zone 'zone.neighborhood' in a zone trigger 2022-04-30 23:17:27 WARNING (MainThread) [homeassistant.components.zone.trigger] Automation 'Zone Notifications ' is referencing non-existing zone 'zone.neighborhood' in a zone trigger 2022-04-30 23:17:27 WARNING (MainThread) [homeassistant.components.zone.trigger] Automation 'Zone Notifications ' is referencing non-existing zone 'zone.neighborhood' in a zone trigger 2022-04-30 23:24:10 WARNING (MainThread) [homeassistant.components.zone.trigger] Automation 'Zone Notifications ' is referencing non-existing zone 'zone.neighborhood' in a zone trigger 2022-04-30 23:24:10 WARNING (MainThread) [homeassistant.components.zone.trigger] Automation 'Zone Notifications ' is referencing non-existing zone 'zone.neighborhood' in a zone trigger 2022-04-30 23:32:30 WARNING (MainThread) [homeassistant.components.zone.trigger] Automation 'Zone Notifications ' is referencing non-existing zone 'zone.neighborhood' in a zone trigger 2022-04-30 23:32:30 WARNING (MainThread) [homeassistant.components.zone.trigger] Automation 'Zone Notifications ' is referencing non-existing zone 'zone.neighborhood' in a zone trigger 2022-04-30 23:41:25 WARNING (MainThread) [homeassistant.components.zone.trigger] Automation 'Zone Notifications ' is referencing non-existing zone 'zone.neighborhood' in a zone trigger 2022-04-30 23:41:25 WARNING (MainThread) [homeassistant.components.zone.trigger] Automation 'Zone Notifications ' is referencing non-existing zone 'zone.neighborhood' in a zone trigger 2022-04-30 23:49:15 WARNING (MainThread) [homeassistant.components.zone.trigger] Automation 'Zone Notifications ' is referencing non-existing zone 'zone.neighborhood' in a zone trigger 2022-04-30 23:49:15 WARNING (MainThread) [homeassistant.components.zone.trigger] Automation 'Zone Notifications ' is referencing non-existing zone 'zone.neighborhood' in a zone trigger 2022-04-30 23:57:30 WARNING (MainThread) [homeassistant.components.zone.trigger] Automation 'Zone Notifications ' is referencing non-existing zone 'zone.neighborhood' in a zone trigger 2022-04-30 23:57:30 WARNING (MainThread) [homeassistant.components.zone.trigger] Automation 'Zone Notifications ' is referencing non-existing zone 'zone.neighborhood' in a zone trigger 2022-05-01 00:05:24 WARNING (MainThread) [homeassistant.components.zone.trigger] Automation 'Zone Notifications ' is referencing non-existing zone 'zone.neighborhood' in a zone trigger 2022-05-01 00:05:24 WARNING (MainThread) [homeassistant.components.zone.trigger] Automation 'Zone Notifications ' is referencing non-existing zone 'zone.neighborhood' in a zone trigger 2022-05-01 00:13:13 WARNING (MainThread) [homeassistant.components.zone.trigger] Automation 'Zone Notifications ' is referencing non-existing zone 'zone.neighborhood' in a zone trigger 2022-05-01 00:13:13 WARNING (MainThread) [homeassistant.components.zone.trigger] Automation 'Zone Notifications ' is referencing non-existing zone 'zone.neighborhood' in a zone trigger 2022-05-01 00:23:17 WARNING (MainThread) [homeassistant.components.zone.trigger] Automation 'Zone Notifications ' is referencing non-existing zone 'zone.neighborhood' in a zone trigger 2022-05-01 00:23:17 WARNING (MainThread) [homeassistant.components.zone.trigger] Automation 'Zone Notifications ' is referencing non-existing zone 'zone.neighborhood' in a zone trigger 2022-05-01 00:32:32 WARNING (MainThread) [homeassistant.components.zone.trigger] Automation 'Zone Notifications ' is referencing non-existing zone 'zone.neighborhood' in a zone trigger 2022-05-01 00:32:32 WARNING (MainThread) [homeassistant.components.zone.trigger] Automation 'Zone Notifications ' is referencing non-existing zone 'zone.neighborhood' in a zone trigger 2022-05-01 00:43:18 WARNING (MainThread) [homeassistant.components.zone.trigger] Automation 'Zone Notifications ' is referencing non-existing zone 'zone.neighborhood' in a zone trigger 2022-05-01 00:43:18 WARNING (MainThread) [homeassistant.components.zone.trigger] Automation 'Zone Notifications ' is referencing non-existing zone 'zone.neighborhood' in a zone trigger 2022-05-01 11:10:27 ERROR (MainThread) [pyinsteon.protocol.http_reader_writer] Client error: (<class 'asyncio.exceptions.TimeoutError'>) 2022-05-01 11:10:31 ERROR (MainThread) [homeassistant.components.roku.coordinator] Error fetching roku data: Invalid response from API: Timeout occurred while connecting to device 2022-05-01 11:10:36 WARNING (MainThread) [homeassistant.components.camera] Updating agent_dvr camera took longer than the scheduled update interval 0:00:05 2022-05-01 11:10:41 WARNING (MainThread) [homeassistant.components.camera] Updating agent_dvr camera took longer than the scheduled update interval 0:00:05 2022-05-01 11:10:41 WARNING (MainThread) [homeassistant.helpers.entity] Update of camera.camera_11 is taking over 10 seconds 2022-05-01 11:10:41 WARNING (MainThread) [homeassistant.helpers.entity] Update of camera.dinning_room is taking over 10 seconds 2022-05-01 11:10:41 WARNING (MainThread) [homeassistant.helpers.entity] Update of camera.entry_way is taking over 10 seconds 2022-05-01 11:10:41 WARNING (MainThread) [homeassistant.helpers.entity] Update of camera.front_door is taking over 10 seconds 2022-05-01 11:10:41 WARNING (MainThread) [homeassistant.helpers.entity] Update of camera.hattie is taking over 10 seconds 2022-05-01 11:10:41 WARNING (MainThread) [homeassistant.helpers.entity] Update of camera.living_room is taking over 10 seconds 2022-05-01 11:10:41 WARNING (MainThread) [homeassistant.helpers.entity] Update of camera.play_room is taking over 10 seconds 2022-05-01 11:10:41 WARNING (MainThread) [homeassistant.helpers.entity] Update of camera.warren is taking over 10 seconds 2022-05-01 11:14:51 ERROR (MainThread) [homeassistant.components.hassio.handler] Timeout on /homeassistant/restart request

darikweitzel commented 2 years ago

Here is the debug log without warnings, a short version of the thermostat.

http://www.avg.com/email-signature?utm_medium=email&utm_source=link&utm_campaign=sig-email&utm_content=webmail Virus-free. www.avg.com http://www.avg.com/email-signature?utm_medium=email&utm_source=link&utm_campaign=sig-email&utm_content=webmail <#DAB4FAD8-2DD7-40BB-A1B8-4E2AA1F9FDF2>

On Sun, May 1, 2022 at 11:35 AM Darik Weitzel @.***> wrote:

well i am kind of confused in the log because under warnings, there is no warnings for the thermostat but are for a couple other things list in this document. which the log stops 4 min before I started my test from 118am to 1125am. No warnings in that time frame. In the other debug log that doesnt show warnings, it shows me adjusting the thermostat on HA like it should work.

On Sun, May 1, 2022 at 10:40 AM Darik Weitzel @.***> wrote:

I’ll change it up and send them over. Thanks for being patient.

Sent from my iPhone

On May 1, 2022, at 10:07 AM, Tom Harris @.***> wrote:



The logs you uploaded don't have the info needed. It looks like you have set the entire system to debug. This is what you want in the configuration.yaml file:

logger: default: warn logs: pyinsteon: debug

That will get me enough info and will create a smaller log file.

— Reply to this email directly, view it on GitHub https://github.com/home-assistant/core/issues/71123#issuecomment-1114261155, or unsubscribe https://github.com/notifications/unsubscribe-auth/AY2GN6USTGSRX5PZKAEK4LLVH2M47ANCNFSM5UYSS5HQ . You are receiving this because you authored the thread.Message ID: @.***>

http://www.avg.com/email-signature?utm_medium=email&utm_source=link&utm_campaign=sig-email&utm_content=webmail Virus-free. www.avg.com http://www.avg.com/email-signature?utm_medium=email&utm_source=link&utm_campaign=sig-email&utm_content=webmail <#m_-1530713330452934585_DAB4FAD8-2DD7-40BB-A1B8-4E2AA1F9FDF2>

thermostat","entity":"climate.thermostat_adapter_4f_67_c7_group_1

2022-05-01 11:18:16 DEBUG (MainThread) [pyinsteon.topics] Topic: send.ping.direct data: {'address': 4f755c} 2022-05-01 11:18:16 DEBUG (MainThread) [pyinsteon.messages] TX: msg_id: 0x62, address: 4f755c, flags: 0x00, cmd1: 0x0f, cmd2: 0x00 2022-05-01 11:18:16 DEBUG (MainThread) [pyinsteon.protocol.http_reader_writer] Writing message: http://192.168.86.26:25105/3?02624f755c000f00=I=3 2022-05-01 11:18:16 DEBUG (MainThread) [pyinsteon.protocol.http_reader_writer] Post status: 200 2022-05-01 11:18:16 DEBUG (MainThread) [pyinsteon.protocol.http_reader_writer] Raw buffer: 02624F755C000F00060000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000012 2022-05-01 11:18:16 DEBUG (MainThread) [pyinsteon.protocol.http_reader_writer] Buffer from 0 to 18 2022-05-01 11:18:16 DEBUG (MainThread) [pyinsteon.protocol.http_transport] New buffer: 02624F755C000F0006 2022-05-01 11:18:16 DEBUG (MainThread) [pyinsteon.protocol.messages.inbound] IN CREATE: 02624f755c000f0006 2022-05-01 11:18:16 DEBUG (MainThread) [pyinsteon.protocol.messages.inbound] Returning: 2022-05-01 11:18:16 DEBUG (MainThread) [pyinsteon.messages] RX: msg_id: 0x62, address: 4f755c, flags: 0x00, cmd1: 0x0f, cmd2: 0x00, ack: 0x06 2022-05-01 11:18:16 DEBUG (MainThread) [pyinsteon.topics] Topic: ack.4f755c.ping.direct data: {'cmd1': 15, 'cmd2': 0, 'user_data': None} 2022-05-01 11:18:17 DEBUG (MainThread) [pyinsteon.protocol.http_reader_writer] Raw buffer: 02624F755C000F0006025C4F755C4E8DA0230F00000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000028 2022-05-01 11:18:17 DEBUG (MainThread) [pyinsteon.protocol.http_reader_writer] Buffer from 18 to 40 2022-05-01 11:18:17 DEBUG (MainThread) [pyinsteon.protocol.http_transport] New buffer: 025C4F755C4E8DA0230F00 2022-05-01 11:18:17 DEBUG (MainThread) [pyinsteon.protocol.messages.inbound] IN CREATE: 025c4f755c4e8da0230f00 2022-05-01 11:18:17 DEBUG (MainThread) [pyinsteon.protocol.messages.inbound] Error: 92 is not a valid MessageId 2022-05-01 11:18:17 DEBUG (MainThread) [pyinsteon.protocol.messages.inbound] Returning: 2022-05-01 11:18:18 DEBUG (MainThread) [pyinsteon.topics] Topic: send.ping.direct data: {'address': 417fe0} 2022-05-01 11:18:18 DEBUG (MainThread) [pyinsteon.messages] TX: msg_id: 0x62, address: 417fe0, flags: 0x00, cmd1: 0x0f, cmd2: 0x00 2022-05-01 11:18:18 DEBUG (MainThread) [pyinsteon.protocol.http_reader_writer] Writing message: http://192.168.86.26:25105/3?0262417fe0000f00=I=3 2022-05-01 11:18:19 DEBUG (MainThread) [pyinsteon.protocol.http_reader_writer] Post status: 200 2022-05-01 11:18:19 DEBUG (MainThread) [pyinsteon.protocol.http_reader_writer] Raw buffer: 0262417FE0000F00060000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000012 2022-05-01 11:18:19 DEBUG (MainThread) [pyinsteon.protocol.http_reader_writer] Buffer from 0 to 18 2022-05-01 11:18:19 DEBUG (MainThread) [pyinsteon.protocol.http_transport] New buffer: 0262417FE0000F0006 2022-05-01 11:18:19 DEBUG (MainThread) [pyinsteon.protocol.messages.inbound] IN CREATE: 0262417fe0000f0006 2022-05-01 11:18:19 DEBUG (MainThread) [pyinsteon.protocol.messages.inbound] Returning: 2022-05-01 11:18:19 DEBUG (MainThread) [pyinsteon.messages] RX: msg_id: 0x62, address: 417fe0, flags: 0x00, cmd1: 0x0f, cmd2: 0x00, ack: 0x06 2022-05-01 11:18:19 DEBUG (MainThread) [pyinsteon.topics] Topic: ack.417fe0.ping.direct data: {'cmd1': 15, 'cmd2': 0, 'user_data': None} 2022-05-01 11:18:19 DEBUG (MainThread) [pyinsteon.topics] Topic: send.thermostat_set_heat_setpoint.direct data: {'address': 4f67c7, 'degrees': 80.0, 'zone': None, 'deadband': None} 2022-05-01 11:18:19 DEBUG (MainThread) [pyinsteon.messages] TX: msg_id: 0x62, address: 4f67c7, flags: 0x00, cmd1: 0x6d, cmd2: 0xa0 2022-05-01 11:18:19 DEBUG (MainThread) [pyinsteon.protocol.http_reader_writer] Writing message: http://192.168.86.26:25105/3?02624f67c7006da0=I=3 2022-05-01 11:18:19 DEBUG (MainThread) [pyinsteon.protocol.http_reader_writer] Post status: 200 2022-05-01 11:18:20 DEBUG (MainThread) [pyinsteon.topics] Topic: send.ping.direct data: {'address': 4f84e3} 2022-05-01 11:18:20 DEBUG (MainThread) [pyinsteon.protocol.http_reader_writer] Raw buffer: 02624F67C7006DA0060000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000012 2022-05-01 11:18:20 DEBUG (MainThread) [pyinsteon.protocol.http_reader_writer] Buffer from 0 to 18 2022-05-01 11:18:20 DEBUG (MainThread) [pyinsteon.protocol.http_transport] New buffer: 02624F67C7006DA006 2022-05-01 11:18:20 DEBUG (MainThread) [pyinsteon.protocol.messages.inbound] IN CREATE: 02624f67c7006da006 2022-05-01 11:18:20 DEBUG (MainThread) [pyinsteon.protocol.messages.inbound] Returning: 2022-05-01 11:18:20 DEBUG (MainThread) [pyinsteon.messages] RX: msg_id: 0x62, address: 4f67c7, flags: 0x00, cmd1: 0x6d, cmd2: 0xa0, ack: 0x06 2022-05-01 11:18:20 DEBUG (MainThread) [pyinsteon.topics] Topic: ack.4f67c7.thermostat_set_heat_setpoint.direct data: {'cmd1': 109, 'cmd2': 160, 'user_data': None} 2022-05-01 11:18:20 DEBUG (MainThread) [pyinsteon.messages] TX: msg_id: 0x62, address: 4f84e3, flags: 0x00, cmd1: 0x0f, cmd2: 0x00 2022-05-01 11:18:20 DEBUG (MainThread) [pyinsteon.protocol.http_reader_writer] Writing message: http://192.168.86.26:25105/3?02624f84e3000f00=I=3 2022-05-01 11:18:20 DEBUG (MainThread) [pyinsteon.protocol.http_reader_writer] Post status: 200 2022-05-01 11:18:20 DEBUG (MainThread) [pyinsteon.protocol.http_reader_writer] Raw buffer: 15151515025C417FE04E8DA0230F0002504F67C74E8DA0A06DFD000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000034 2022-05-01 11:18:20 DEBUG (MainThread) [pyinsteon.protocol.http_reader_writer] Buffer from 0 to 52 2022-05-01 11:18:20 DEBUG (MainThread) [pyinsteon.protocol.http_transport] New buffer: 15151515025C417FE04E8DA0230F0002504F67C74E8DA0A06DFD 2022-05-01 11:18:20 DEBUG (MainThread) [pyinsteon.protocol.messages.inbound] IN CREATE: 15151515025c417fe04e8da0230f0002504f67c74e8da0a06dfd 2022-05-01 11:18:20 DEBUG (MainThread) [pyinsteon.protocol.messages.inbound] Error: 92 is not a valid MessageId 2022-05-01 11:18:20 DEBUG (MainThread) [pyinsteon.protocol.messages.inbound] Returning: 02504f67c74e8da0a06dfd 2022-05-01 11:18:20 DEBUG (MainThread) [pyinsteon.protocol.messages.inbound] IN CREATE: 02504f67c74e8da0a06dfd 2022-05-01 11:18:20 DEBUG (MainThread) [pyinsteon.protocol.messages.inbound] Returning: 2022-05-01 11:18:20 DEBUG (MainThread) [pyinsteon.messages] RX: msg_id: 0x50, address: 4f67c7, target: 4e8da0, flags: 0xa0, cmd1: 0x6d, cmd2: 0xfd 2022-05-01 11:18:20 DEBUG (MainThread) [pyinsteon.topics] Topic: 4f67c7.thermostat_set_heat_setpoint.direct_nak data: {'cmd1': 109, 'cmd2': 253, 'target': 4e8da0, 'user_data': None, 'hops_left': 0} 2022-05-01 11:18:42 DEBUG (MainThread) [pyinsteon.topics] Topic: send.ping.direct data: {'address': 417fe0} 2022-05-01 11:18:42 DEBUG (MainThread) [pyinsteon.messages] TX: msg_id: 0x62, address: 417fe0, flags: 0x00, cmd1: 0x0f, cmd2: 0x00 2022-05-01 11:18:42 DEBUG (MainThread) [pyinsteon.protocol.http_reader_writer] Writing message: http://192.168.86.26:25105/3?0262417fe0000f00=I=3 2022-05-01 11:18:42 DEBUG (MainThread) [pyinsteon.protocol.http_reader_writer] Post status: 200 2022-05-01 11:18:42 DEBUG (MainThread) [pyinsteon.protocol.http_reader_writer] Raw buffer: 0262417FE0000F00060000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000012 2022-05-01 11:18:42 DEBUG (MainThread) [pyinsteon.protocol.http_reader_writer] Buffer from 0 to 18 2022-05-01 11:18:42 DEBUG (MainThread) [pyinsteon.protocol.http_transport] New buffer: 0262417FE0000F0006 2022-05-01 11:18:42 DEBUG (MainThread) [pyinsteon.protocol.messages.inbound] IN CREATE: 0262417fe0000f0006 2022-05-01 11:18:42 DEBUG (MainThread) [pyinsteon.protocol.messages.inbound] Returning: 2022-05-01 11:18:42 DEBUG (MainThread) [pyinsteon.messages] RX: msg_id: 0x62, address: 417fe0, flags: 0x00, cmd1: 0x0f, cmd2: 0x00, ack: 0x06 2022-05-01 11:18:42 DEBUG (MainThread) [pyinsteon.topics] Topic: ack.417fe0.ping.direct data: {'cmd1': 15, 'cmd2': 0, 'user_data': None} 2022-05-01 11:18:44 DEBUG (MainThread) [pyinsteon.protocol.http_reader_writer] Raw buffer: 0262417FE0000F0006025C417FE04E8DA0230F00000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000028 2022-05-01 11:18:44 DEBUG (MainThread) [pyinsteon.protocol.http_reader_writer] Buffer from 18 to 40 2022-05-01 11:18:44 DEBUG (MainThread) [pyinsteon.protocol.http_transport] New buffer: 025C417FE04E8DA0230F00 2022-05-01 11:18:44 DEBUG (MainThread) [pyinsteon.protocol.messages.inbound] IN CREATE: 025c417fe04e8da0230f00 2022-05-01 11:18:44 DEBUG (MainThread) [pyinsteon.protocol.messages.inbound] Error: 92 is not a valid MessageId 2022-05-01 11:18:44 DEBUG (MainThread) [pyinsteon.protocol.messages.inbound] Returning: 2022-05-01 11:18:48 DEBUG (MainThread) [pyinsteon.topics] Topic: send.ping.direct data: {'address': 4f84e3} 2022-05-01 11:18:48 DEBUG (MainThread) [pyinsteon.messages] TX: msg_id: 0x62, address: 4f84e3, flags: 0x00, cmd1: 0x0f, cmd2: 0x00 2022-05-01 11:18:48 DEBUG (MainThread) [pyinsteon.protocol.http_reader_writer] Writing message: http://192.168.86.26:25105/3?02624f84e3000f00=I=3 2022-05-01 11:18:48 DEBUG (MainThread) [pyinsteon.protocol.http_reader_writer] Post status: 200 2022-05-01 11:18:48 DEBUG (MainThread) [pyinsteon.protocol.http_reader_writer] Raw buffer: 02624F84E3000F00060000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000012 2022-05-01 11:18:48 DEBUG (MainThread) [pyinsteon.protocol.http_reader_writer] Buffer from 0 to 18 2022-05-01 11:18:48 DEBUG (MainThread) [pyinsteon.protocol.http_transport] New buffer: 02624F84E3000F0006 2022-05-01 11:18:48 DEBUG (MainThread) [pyinsteon.protocol.messages.inbound] IN CREATE: 02624f84e3000f0006 2022-05-01 11:18:48 DEBUG (MainThread) [pyinsteon.protocol.messages.inbound] Returning: 2022-05-01 11:18:48 DEBUG (MainThread) [pyinsteon.messages] RX: msg_id: 0x62, address: 4f84e3, flags: 0x00, cmd1: 0x0f, cmd2: 0x00, ack: 0x06 2022-05-01 11:18:48 DEBUG (MainThread) [pyinsteon.topics] Topic: ack.4f84e3.ping.direct data: {'cmd1': 15, 'cmd2': 0, 'user_data': None} 2022-05-01 11:18:49 DEBUG (MainThread) [pyinsteon.protocol.http_reader_writer] Raw buffer: 02624F84E3000F0006025C4F84E34E8DA0230F00000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000028 2022-05-01 11:18:49 DEBUG (MainThread) [pyinsteon.protocol.http_reader_writer] Buffer from 18 to 40 2022-05-01 11:18:49 DEBUG (MainThread) [pyinsteon.protocol.http_transport] New buffer: 025C4F84E34E8DA0230F00 2022-05-01 11:18:49 DEBUG (MainThread) [pyinsteon.protocol.messages.inbound] IN CREATE: 025c4f84e34e8da0230f00 2022-05-01 11:18:49 DEBUG (MainThread) [pyinsteon.protocol.messages.inbound] Error: 92 is not a valid MessageId 2022-05-01 11:18:49 DEBUG (MainThread) [pyinsteon.protocol.messages.inbound] Returning: 2022-05-01 11:19:01 DEBUG (MainThread) [pyinsteon.protocol.http_reader_writer] Raw buffer: 4F84E34E8DA041130102504F84E3130301CB060002504F84E3130301CB06004E89014947026A060257A2012B4E89000000026A060257A300417FE0100200026A1502504F84E3000001CB130102504F84E3000001CB130102504F84E34E8DA041130102503E 2022-05-01 11:19:01 DEBUG (MainThread) [pyinsteon.protocol.http_reader_writer] Buffer from 40 to 62 2022-05-01 11:19:01 DEBUG (MainThread) [pyinsteon.protocol.http_transport] New buffer: 02504F84E3130301CB0600 2022-05-01 11:19:01 DEBUG (MainThread) [pyinsteon.protocol.messages.inbound] IN CREATE: 02504f84e3130301cb0600 2022-05-01 11:19:01 DEBUG (MainThread) [pyinsteon.protocol.messages.inbound] Returning: 2022-05-01 11:19:01 DEBUG (MainThread) [pyinsteon.messages] RX: msg_id: 0x50, address: 4f84e3, target: 130301, flags: 0xcb, cmd1: 0x06, cmd2: 0x00 2022-05-01 11:19:01 DEBUG (MainThread) [pyinsteon.topics] Topic: 4f84e3.all_link_cleanup_status_report.all_link_broadcast data: {'cmd1': 6, 'cmd2': 0, 'target': 130301, 'user_data': None, 'hops_left': 2} 2022-05-01 11:19:01 DEBUG (MainThread) [pyinsteon.device_types.battery_base] We have commands to run so let's get to it 2022-05-01 11:19:01 DEBUG (MainThread) [pyinsteon.topics] Topic: send.extended_get_set.direct data: {'address': 4f84e3, 'priority': 5, 'data1': 0, 'data2': 4, 'data3': 255, 'data4': 0, 'data5': 0, 'data6': 0, 'data7': 0, 'data8': 0, 'data9': 0, 'data10': 0, 'data11': 0, 'data12': 0, 'data13': 0, 'data14': 0} 2022-05-01 11:19:01 DEBUG (MainThread) [pyinsteon.messages] TX: msg_id: 0x62, address: 4f84e3, flags: 0x10, cmd1: 0x2e, cmd2: 0x00, user_data: 00.04.ff.00.00.00.00.00.00.00.00.00.00.cf 2022-05-01 11:19:01 DEBUG (MainThread) [pyinsteon.protocol.http_reader_writer] Writing message: http://192.168.86.26:25105/3?02624f84e3102e000004ff00000000000000000000cf=I=3 2022-05-01 11:19:01 DEBUG (MainThread) [pyinsteon.protocol.http_reader_writer] Post status: 200 2022-05-01 11:19:02 DEBUG (MainThread) [pyinsteon.protocol.http_reader_writer] Raw buffer: 02624F84E3102E000004FF00000000000000000000CF0602504F84E34E000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000038 2022-05-01 11:19:02 DEBUG (MainThread) [pyinsteon.protocol.http_reader_writer] Buffer from 0 to 56 2022-05-01 11:19:02 DEBUG (MainThread) [pyinsteon.protocol.http_transport] New buffer: 02624F84E3102E000004FF00000000000000000000CF0602504F84E3 2022-05-01 11:19:02 DEBUG (MainThread) [pyinsteon.protocol.messages.inbound] IN CREATE: 02624f84e3102e000004ff00000000000000000000cf0602504f84e3 2022-05-01 11:19:02 DEBUG (MainThread) [pyinsteon.protocol.messages.inbound] Returning: 02504f84e3 2022-05-01 11:19:02 DEBUG (MainThread) [pyinsteon.protocol.messages.inbound] IN CREATE: 02504f84e3 2022-05-01 11:19:02 DEBUG (MainThread) [pyinsteon.protocol.messages.inbound] Full message not received 2022-05-01 11:19:02 DEBUG (MainThread) [pyinsteon.protocol.messages.inbound] Returning: 02504f84e3 2022-05-01 11:19:02 DEBUG (MainThread) [pyinsteon.messages] RX: msg_id: 0x62, address: 4f84e3, flags: 0x10, cmd1: 0x2e, cmd2: 0x00, user_data: 00.04.ff.00.00.00.00.00.00.00.00.00.00.cf, ack: 0x06 2022-05-01 11:19:02 DEBUG (MainThread) [pyinsteon.topics] Topic: ack.4f84e3.extended_get_set.direct data: {'cmd1': 46, 'cmd2': 0, 'user_data': 00.04.ff.00.00.00.00.00.00.00.00.00.00.cf} 2022-05-01 11:19:02 DEBUG (MainThread) [pyinsteon.protocol.http_reader_writer] Raw buffer: 02624F84E3102E000004FF00000000000000000000CF0602504F84E34E8DA0212E0000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000044 2022-05-01 11:19:02 DEBUG (MainThread) [pyinsteon.protocol.http_reader_writer] Buffer from 56 to 68 2022-05-01 11:19:02 DEBUG (MainThread) [pyinsteon.protocol.http_transport] New buffer: 4E8DA0212E00 2022-05-01 11:19:02 DEBUG (MainThread) [pyinsteon.protocol.messages.inbound] IN CREATE: 02504f84e34e8da0212e00 2022-05-01 11:19:02 DEBUG (MainThread) [pyinsteon.protocol.messages.inbound] Returning: 2022-05-01 11:19:02 DEBUG (MainThread) [pyinsteon.messages] RX: msg_id: 0x50, address: 4f84e3, target: 4e8da0, flags: 0x21, cmd1: 0x2e, cmd2: 0x00 2022-05-01 11:19:02 DEBUG (MainThread) [pyinsteon.topics] Topic: 4f84e3.on_at_ramp_rate.direct_ack data: {'cmd1': 46, 'cmd2': 0, 'target': 4e8da0, 'user_data': None, 'hops_left': 0} 2022-05-01 11:19:02 DEBUG (MainThread) [pyinsteon.topics] Topic: 4f84e3.extended_get_set.direct_ack data: {'cmd1': 46, 'cmd2': 0, 'target': 4e8da0, 'user_data': None, 'hops_left': 0} 2022-05-01 11:19:02 DEBUG (MainThread) [pyinsteon.device_types.battery_base] Keep awake result: success 2022-05-01 11:19:02 DEBUG (MainThread) [pyinsteon.topics] Topic: send.extended_get_set.direct data: {'address': 4f84e3, 'priority': 5, 'data1': 0, 'data2': 4, 'data3': 255, 'data4': 0, 'data5': 0, 'data6': 0, 'data7': 0, 'data8': 0, 'data9': 0, 'data10': 0, 'data11': 0, 'data12': 0, 'data13': 0, 'data14': 0} 2022-05-01 11:19:02 DEBUG (MainThread) [pyinsteon.messages] TX: msg_id: 0x62, address: 4f84e3, flags: 0x10, cmd1: 0x2e, cmd2: 0x00, user_data: 00.04.ff.00.00.00.00.00.00.00.00.00.00.cf 2022-05-01 11:19:02 DEBUG (MainThread) [pyinsteon.protocol.http_reader_writer] Writing message: http://192.168.86.26:25105/3?02624f84e3102e000004ff00000000000000000000cf=I=3 2022-05-01 11:19:02 DEBUG (MainThread) [pyinsteon.protocol.http_reader_writer] Post status: 200 2022-05-01 11:19:03 DEBUG (MainThread) [pyinsteon.protocol.http_reader_writer] Raw buffer: 02624F84E3102E000004FF00000000000000000000CF0600000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000002E 2022-05-01 11:19:03 DEBUG (MainThread) [pyinsteon.protocol.http_reader_writer] Buffer from 0 to 46 2022-05-01 11:19:03 DEBUG (MainThread) [pyinsteon.protocol.http_transport] New buffer: 02624F84E3102E000004FF00000000000000000000CF06 2022-05-01 11:19:03 DEBUG (MainThread) [pyinsteon.protocol.messages.inbound] IN CREATE: 02624f84e3102e000004ff00000000000000000000cf06 2022-05-01 11:19:03 DEBUG (MainThread) [pyinsteon.protocol.messages.inbound] Returning: 2022-05-01 11:19:03 DEBUG (MainThread) [pyinsteon.messages] RX: msg_id: 0x62, address: 4f84e3, flags: 0x10, cmd1: 0x2e, cmd2: 0x00, user_data: 00.04.ff.00.00.00.00.00.00.00.00.00.00.cf, ack: 0x06 2022-05-01 11:19:03 DEBUG (MainThread) [pyinsteon.topics] Topic: ack.4f84e3.extended_get_set.direct data: {'cmd1': 46, 'cmd2': 0, 'user_data': 00.04.ff.00.00.00.00.00.00.00.00.00.00.cf} 2022-05-01 11:19:03 DEBUG (MainThread) [pyinsteon.protocol.http_reader_writer] Raw buffer: 02624F84E3102E000004FF00000000000000000000CF0602504F84E34E8DA0212E0000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000044 2022-05-01 11:19:03 DEBUG (MainThread) [pyinsteon.protocol.http_reader_writer] Buffer from 46 to 68 2022-05-01 11:19:03 DEBUG (MainThread) [pyinsteon.protocol.http_transport] New buffer: 02504F84E34E8DA0212E00 2022-05-01 11:19:03 DEBUG (MainThread) [pyinsteon.protocol.messages.inbound] IN CREATE: 02504f84e34e8da0212e00 2022-05-01 11:19:03 DEBUG (MainThread) [pyinsteon.protocol.messages.inbound] Returning: 2022-05-01 11:19:03 DEBUG (MainThread) [pyinsteon.messages] RX: msg_id: 0x50, address: 4f84e3, target: 4e8da0, flags: 0x21, cmd1: 0x2e, cmd2: 0x00 2022-05-01 11:19:03 DEBUG (MainThread) [pyinsteon.topics] Topic: 4f84e3.on_at_ramp_rate.direct_ack data: {'cmd1': 46, 'cmd2': 0, 'target': 4e8da0, 'user_data': None, 'hops_left': 0} 2022-05-01 11:19:03 DEBUG (MainThread) [pyinsteon.topics] Topic: 4f84e3.extended_get_set.direct_ack data: {'cmd1': 46, 'cmd2': 0, 'target': 4e8da0, 'user_data': None, 'hops_left': 0} 2022-05-01 11:19:03 DEBUG (MainThread) [pyinsteon.device_types.battery_base] got a command to run YAY 2022-05-01 11:19:03 DEBUG (MainThread) [pyinsteon.device_types.battery_base] functools.partial(<bound method Device.async_read_config of <pyinsteon.device_types.security_health_safety.SecurityHealthSafety_MotionSensor object at 0x7f38994dc760>>, read_aldb=True) 2022-05-01 11:19:03 DEBUG (MainThread) [pyinsteon.topics] Topic: send.extended_get_set.direct data: {'address': 4f84e3, 'priority': 5, 'data1': 0, 'data2': 4, 'data3': 255, 'data4': 0, 'data5': 0, 'data6': 0, 'data7': 0, 'data8': 0, 'data9': 0, 'data10': 0, 'data11': 0, 'data12': 0, 'data13': 0, 'data14': 0} 2022-05-01 11:19:03 DEBUG (MainThread) [pyinsteon.messages] TX: msg_id: 0x62, address: 4f84e3, flags: 0x10, cmd1: 0x2e, cmd2: 0x00, user_data: 00.04.ff.00.00.00.00.00.00.00.00.00.00.cf 2022-05-01 11:19:03 DEBUG (MainThread) [pyinsteon.protocol.http_reader_writer] Writing message: http://192.168.86.26:25105/3?02624f84e3102e000004ff00000000000000000000cf=I=3 2022-05-01 11:19:04 DEBUG (MainThread) [pyinsteon.protocol.http_reader_writer] Post status: 200 2022-05-01 11:19:04 DEBUG (MainThread) [pyinsteon.protocol.http_reader_writer] Raw buffer: 02624F84E3102E000004FF00000000000000000000CF0600000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000002E 2022-05-01 11:19:04 DEBUG (MainThread) [pyinsteon.protocol.http_reader_writer] Buffer from 0 to 46 2022-05-01 11:19:04 DEBUG (MainThread) [pyinsteon.protocol.http_transport] New buffer: 02624F84E3102E000004FF00000000000000000000CF06 2022-05-01 11:19:04 DEBUG (MainThread) [pyinsteon.protocol.messages.inbound] IN CREATE: 02624f84e3102e000004ff00000000000000000000cf06 2022-05-01 11:19:04 DEBUG (MainThread) [pyinsteon.protocol.messages.inbound] Returning: 2022-05-01 11:19:04 DEBUG (MainThread) [pyinsteon.messages] RX: msg_id: 0x62, address: 4f84e3, flags: 0x10, cmd1: 0x2e, cmd2: 0x00, user_data: 00.04.ff.00.00.00.00.00.00.00.00.00.00.cf, ack: 0x06 2022-05-01 11:19:04 DEBUG (MainThread) [pyinsteon.topics] Topic: ack.4f84e3.extended_get_set.direct data: {'cmd1': 46, 'cmd2': 0, 'user_data': 00.04.ff.00.00.00.00.00.00.00.00.00.00.cf} 2022-05-01 11:19:05 DEBUG (MainThread) [pyinsteon.protocol.http_reader_writer] Raw buffer: 02624F84E3102E000004FF00000000000000000000CF0602504F84E34E8DA0212E0000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000044 2022-05-01 11:19:05 DEBUG (MainThread) [pyinsteon.protocol.http_reader_writer] Buffer from 46 to 68 2022-05-01 11:19:05 DEBUG (MainThread) [pyinsteon.protocol.http_transport] New buffer: 02504F84E34E8DA0212E00 2022-05-01 11:19:05 DEBUG (MainThread) [pyinsteon.protocol.messages.inbound] IN CREATE: 02504f84e34e8da0212e00 2022-05-01 11:19:05 DEBUG (MainThread) [pyinsteon.protocol.messages.inbound] Returning: 2022-05-01 11:19:05 DEBUG (MainThread) [pyinsteon.messages] RX: msg_id: 0x50, address: 4f84e3, target: 4e8da0, flags: 0x21, cmd1: 0x2e, cmd2: 0x00 2022-05-01 11:19:05 DEBUG (MainThread) [pyinsteon.topics] Topic: 4f84e3.on_at_ramp_rate.direct_ack data: {'cmd1': 46, 'cmd2': 0, 'target': 4e8da0, 'user_data': None, 'hops_left': 0} 2022-05-01 11:19:05 DEBUG (MainThread) [pyinsteon.topics] Topic: 4f84e3.extended_get_set.direct_ack data: {'cmd1': 46, 'cmd2': 0, 'target': 4e8da0, 'user_data': None, 'hops_left': 0} 2022-05-01 11:19:05 DEBUG (MainThread) [pyinsteon.device_types.battery_base] got a command to run YAY 2022-05-01 11:19:05 DEBUG (MainThread) [pyinsteon.device_types.battery_base] functools.partial(<bound method Device.async_read_op_flags of <pyinsteon.device_types.security_health_safety.SecurityHealthSafety_MotionSensor object at 0x7f38994dc760>>) 2022-05-01 11:19:05 DEBUG (MainThread) [pyinsteon.topics] Topic: send.extended_get_set.direct data: {'address': 4f84e3, 'priority': 5, 'data1': 0, 'data2': 4, 'data3': 255, 'data4': 0, 'data5': 0, 'data6': 0, 'data7': 0, 'data8': 0, 'data9': 0, 'data10': 0, 'data11': 0, 'data12': 0, 'data13': 0, 'data14': 0} 2022-05-01 11:19:05 DEBUG (MainThread) [pyinsteon.messages] TX: msg_id: 0x62, address: 4f84e3, flags: 0x10, cmd1: 0x2e, cmd2: 0x00, user_data: 00.04.ff.00.00.00.00.00.00.00.00.00.00.cf 2022-05-01 11:19:05 DEBUG (MainThread) [pyinsteon.protocol.http_reader_writer] Writing message: http://192.168.86.26:25105/3?02624f84e3102e000004ff00000000000000000000cf=I=3 2022-05-01 11:19:05 DEBUG (MainThread) [pyinsteon.protocol.http_reader_writer] Post status: 200 2022-05-01 11:19:05 DEBUG (MainThread) [pyinsteon.protocol.http_reader_writer] Raw buffer: 02624F84E3102E000004FF00000000000000000000CF0600000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000002E 2022-05-01 11:19:05 DEBUG (MainThread) [pyinsteon.protocol.http_reader_writer] Buffer from 0 to 46 2022-05-01 11:19:05 DEBUG (MainThread) [pyinsteon.protocol.http_transport] New buffer: 02624F84E3102E000004FF00000000000000000000CF06 2022-05-01 11:19:05 DEBUG (MainThread) [pyinsteon.protocol.messages.inbound] IN CREATE: 02624f84e3102e000004ff00000000000000000000cf06 2022-05-01 11:19:05 DEBUG (MainThread) [pyinsteon.protocol.messages.inbound] Returning: 2022-05-01 11:19:05 DEBUG (MainThread) [pyinsteon.messages] RX: msg_id: 0x62, address: 4f84e3, flags: 0x10, cmd1: 0x2e, cmd2: 0x00, user_data: 00.04.ff.00.00.00.00.00.00.00.00.00.00.cf, ack: 0x06 2022-05-01 11:19:05 DEBUG (MainThread) [pyinsteon.topics] Topic: ack.4f84e3.extended_get_set.direct data: {'cmd1': 46, 'cmd2': 0, 'user_data': 00.04.ff.00.00.00.00.00.00.00.00.00.00.cf} 2022-05-01 11:19:05 DEBUG (MainThread) [pyinsteon.topics] Topic: send.ping.direct data: {'address': 417fe0} 2022-05-01 11:19:05 DEBUG (MainThread) [pyinsteon.messages] TX: msg_id: 0x62, address: 417fe0, flags: 0x00, cmd1: 0x0f, cmd2: 0x00 2022-05-01 11:19:05 DEBUG (MainThread) [pyinsteon.protocol.http_reader_writer] Writing message: http://192.168.86.26:25105/3?0262417fe0000f00=I=3 2022-05-01 11:19:06 DEBUG (MainThread) [pyinsteon.protocol.http_reader_writer] Post status: 200 2022-05-01 11:19:06 DEBUG (MainThread) [pyinsteon.protocol.http_reader_writer] Raw buffer: 0262417FE0000F00060000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000012 2022-05-01 11:19:06 DEBUG (MainThread) [pyinsteon.protocol.http_reader_writer] Buffer from 0 to 18 2022-05-01 11:19:06 DEBUG (MainThread) [pyinsteon.protocol.http_transport] New buffer: 0262417FE0000F0006 2022-05-01 11:19:06 DEBUG (MainThread) [pyinsteon.protocol.messages.inbound] IN CREATE: 0262417fe0000f0006 2022-05-01 11:19:06 DEBUG (MainThread) [pyinsteon.protocol.messages.inbound] Returning: 2022-05-01 11:19:06 DEBUG (MainThread) [pyinsteon.messages] RX: msg_id: 0x62, address: 417fe0, flags: 0x00, cmd1: 0x0f, cmd2: 0x00, ack: 0x06 2022-05-01 11:19:06 DEBUG (MainThread) [pyinsteon.topics] Topic: ack.417fe0.ping.direct data: {'cmd1': 15, 'cmd2': 0, 'user_data': None} 2022-05-01 11:19:07 DEBUG (MainThread) [pyinsteon.protocol.http_reader_writer] Raw buffer: 0262417FE0000F0006027F02060000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000001A 2022-05-01 11:19:07 DEBUG (MainThread) [pyinsteon.protocol.http_reader_writer] Buffer from 18 to 26 2022-05-01 11:19:07 DEBUG (MainThread) [pyinsteon.protocol.http_transport] New buffer: 027F0206 2022-05-01 11:19:07 DEBUG (MainThread) [pyinsteon.protocol.messages.inbound] IN CREATE: 027f0206 2022-05-01 11:19:07 DEBUG (MainThread) [pyinsteon.protocol.messages.inbound] Error: 127 is not a valid MessageId 2022-05-01 11:19:07 DEBUG (MainThread) [pyinsteon.protocol.messages.inbound] Returning: 0206 2022-05-01 11:19:07 DEBUG (MainThread) [pyinsteon.protocol.messages.inbound] IN CREATE: 0206 2022-05-01 11:19:07 DEBUG (MainThread) [pyinsteon.protocol.messages.inbound] Error: 6 is not a valid MessageId 2022-05-01 11:19:07 DEBUG (MainThread) [pyinsteon.protocol.messages.inbound] Returning: 2022-05-01 11:19:07 DEBUG (MainThread) [pyinsteon.protocol.http_reader_writer] Raw buffer: 0262417FE0000F0006027F0206025C417FE04E8DA0230F000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000030 2022-05-01 11:19:07 DEBUG (MainThread) [pyinsteon.protocol.http_reader_writer] Buffer from 26 to 48 2022-05-01 11:19:07 DEBUG (MainThread) [pyinsteon.protocol.http_transport] New buffer: 025C417FE04E8DA0230F00 2022-05-01 11:19:07 DEBUG (MainThread) [pyinsteon.protocol.messages.inbound] IN CREATE: 025c417fe04e8da0230f00 2022-05-01 11:19:07 DEBUG (MainThread) [pyinsteon.protocol.messages.inbound] Error: 92 is not a valid MessageId 2022-05-01 11:19:07 DEBUG (MainThread) [pyinsteon.protocol.messages.inbound] Returning: 2022-05-01 11:19:08 DEBUG (MainThread) [pyinsteon.device_types.battery_base] got a command to run YAY 2022-05-01 11:19:08 DEBUG (MainThread) [pyinsteon.device_types.battery_base] functools.partial(<bound method Device.async_read_ext_properties of <pyinsteon.device_types.security_health_safety.SecurityHealthSafety_MotionSensor object at 0x7f38994dc760>>) 2022-05-01 11:19:08 DEBUG (MainThread) [pyinsteon.topics] Topic: send.extended_get_set.direct data: {'address': 4f84e3, 'data1': 1, 'data2': 0} 2022-05-01 11:19:08 DEBUG (MainThread) [pyinsteon.messages] TX: msg_id: 0x62, address: 4f84e3, flags: 0x10, cmd1: 0x2e, cmd2: 0x00, user_data: 01.00.00.00.00.00.00.00.00.00.00.00.00.d1 2022-05-01 11:19:08 DEBUG (MainThread) [pyinsteon.protocol.http_reader_writer] Writing message: http://192.168.86.26:25105/3?02624f84e3102e0001000000000000000000000000d1=I=3 2022-05-01 11:19:09 DEBUG (MainThread) [pyinsteon.protocol.http_reader_writer] Post status: 200 2022-05-01 11:19:09 DEBUG (MainThread) [pyinsteon.protocol.http_reader_writer] Raw buffer: 02624F84E3102E0001000000000000000000000000D10600000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000002E 2022-05-01 11:19:09 DEBUG (MainThread) [pyinsteon.protocol.http_reader_writer] Buffer from 0 to 46 2022-05-01 11:19:09 DEBUG (MainThread) [pyinsteon.protocol.http_transport] New buffer: 02624F84E3102E0001000000000000000000000000D106 2022-05-01 11:19:09 DEBUG (MainThread) [pyinsteon.protocol.messages.inbound] IN CREATE: 02624f84e3102e0001000000000000000000000000d106 2022-05-01 11:19:09 DEBUG (MainThread) [pyinsteon.protocol.messages.inbound] Returning: 2022-05-01 11:19:09 DEBUG (MainThread) [pyinsteon.messages] RX: msg_id: 0x62, address: 4f84e3, flags: 0x10, cmd1: 0x2e, cmd2: 0x00, user_data: 01.00.00.00.00.00.00.00.00.00.00.00.00.d1, ack: 0x06 2022-05-01 11:19:09 DEBUG (MainThread) [pyinsteon.topics] Topic: ack.4f84e3.extended_get_set.direct data: {'cmd1': 46, 'cmd2': 0, 'user_data': 01.00.00.00.00.00.00.00.00.00.00.00.00.d1} 2022-05-01 11:19:11 DEBUG (MainThread) [pyinsteon.protocol.http_reader_writer] Raw buffer: 02624F84E3102E0001000000000000000000000000D106025C4F84E34E8DA0232E0000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000044 2022-05-01 11:19:11 DEBUG (MainThread) [pyinsteon.protocol.http_reader_writer] Buffer from 46 to 68 2022-05-01 11:19:11 DEBUG (MainThread) [pyinsteon.protocol.http_transport] New buffer: 025C4F84E34E8DA0232E00 2022-05-01 11:19:11 DEBUG (MainThread) [pyinsteon.protocol.messages.inbound] IN CREATE: 025c4f84e34e8da0232e00 2022-05-01 11:19:11 DEBUG (MainThread) [pyinsteon.protocol.messages.inbound] Error: 92 is not a valid MessageId 2022-05-01 11:19:11 DEBUG (MainThread) [pyinsteon.protocol.messages.inbound] Returning: 2022-05-01 11:19:14 DEBUG (MainThread) [pyinsteon.topics] Topic: send.extended_get_set.direct data: {'address': 4f84e3, 'data1': 1, 'data2': 0} 2022-05-01 11:19:14 DEBUG (MainThread) [pyinsteon.messages] TX: msg_id: 0x62, address: 4f84e3, flags: 0x10, cmd1: 0x2e, cmd2: 0x00, user_data: 01.00.00.00.00.00.00.00.00.00.00.00.00.d1 2022-05-01 11:19:14 DEBUG (MainThread) [pyinsteon.protocol.http_reader_writer] Writing message: http://192.168.86.26:25105/3?02624f84e3102e0001000000000000000000000000d1=I=3 2022-05-01 11:19:14 DEBUG (MainThread) [pyinsteon.protocol.http_reader_writer] Post status: 200 2022-05-01 11:19:19 DEBUG (MainThread) [pyinsteon.topics] Topic: send.ping.direct data: {'address': 4f755c} 2022-05-01 11:19:19 DEBUG (MainThread) [pyinsteon.messages] TX: msg_id: 0x62, address: 4f755c, flags: 0x00, cmd1: 0x0f, cmd2: 0x00 2022-05-01 11:19:20 DEBUG (MainThread) [pyinsteon.protocol.http_reader_writer] Raw buffer: 19000602504F72054E8DA021020000000000000000D1060262333AF905190006025C4F84E34E8DA0232E000250333AF94E8DA02B0400026238F3A705190006025038F3A74E8DA02000FF026238B12F05190006025038B12F4E8DA020000002624F7205051C 2022-05-01 11:19:20 DEBUG (MainThread) [pyinsteon.protocol.http_reader_writer] Buffer from 0 to 28 2022-05-01 11:19:20 DEBUG (MainThread) [pyinsteon.protocol.http_transport] New buffer: 19000602504F72054E8DA0210200 2022-05-01 11:19:20 DEBUG (MainThread) [pyinsteon.protocol.messages.inbound] IN CREATE: 19000602504f72054e8da0210200 2022-05-01 11:19:20 DEBUG (MainThread) [pyinsteon.protocol.messages.inbound] Returning: 2022-05-01 11:19:20 DEBUG (MainThread) [pyinsteon.messages] RX: msg_id: 0x50, address: 4f7205, target: 4e8da0, flags: 0x21, cmd1: 0x02, cmd2: 0x00 2022-05-01 11:19:20 DEBUG (MainThread) [pyinsteon.topics] Topic: 4f7205.delete_from_all_link_group.direct_ack data: {'cmd1': 2, 'cmd2': 0, 'target': 4e8da0, 'user_data': None, 'hops_left': 0} 2022-05-01 11:19:20 DEBUG (MainThread) [pyinsteon.protocol.http_reader_writer] Writing message: http://192.168.86.26:25105/3?02624f755c000f00=I=3 2022-05-01 11:19:20 DEBUG (MainThread) [pyinsteon.protocol.http_reader_writer] Post status: 200 2022-05-01 11:19:21 DEBUG (MainThread) [pyinsteon.protocol.http_reader_writer] Raw buffer: 02624F755C000F0006025C4F755C4E8DA0230F0002624F755C05190006025C4F755C4E8DA023190000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000050 2022-05-01 11:19:21 DEBUG (MainThread) [pyinsteon.protocol.http_reader_writer] Buffer from 0 to 80 2022-05-01 11:19:21 DEBUG (MainThread) [pyinsteon.protocol.http_transport] New buffer: 02624F755C000F0006025C4F755C4E8DA0230F0002624F755C05190006025C4F755C4E8DA0231900 2022-05-01 11:19:21 DEBUG (MainThread) [pyinsteon.protocol.messages.inbound] IN CREATE: 02624f755c000f0006025c4f755c4e8da0230f0002624f755c05190006025c4f755c4e8da0231900 2022-05-01 11:19:21 DEBUG (MainThread) [pyinsteon.protocol.messages.inbound] Returning: 025c4f755c4e8da0230f0002624f755c05190006025c4f755c4e8da0231900 2022-05-01 11:19:21 DEBUG (MainThread) [pyinsteon.protocol.messages.inbound] IN CREATE: 025c4f755c4e8da0230f0002624f755c05190006025c4f755c4e8da0231900 2022-05-01 11:19:21 DEBUG (MainThread) [pyinsteon.protocol.messages.inbound] Error: 92 is not a valid MessageId 2022-05-01 11:19:21 DEBUG (MainThread) [pyinsteon.protocol.messages.inbound] Returning: 02624f755c05190006025c4f755c4e8da0231900 2022-05-01 11:19:21 DEBUG (MainThread) [pyinsteon.protocol.messages.inbound] IN CREATE: 02624f755c05190006025c4f755c4e8da0231900 2022-05-01 11:19:21 DEBUG (MainThread) [pyinsteon.protocol.messages.inbound] Returning: 025c4f755c4e8da0231900 2022-05-01 11:19:21 DEBUG (MainThread) [pyinsteon.protocol.messages.inbound] IN CREATE: 025c4f755c4e8da0231900 2022-05-01 11:19:21 DEBUG (MainThread) [pyinsteon.protocol.messages.inbound] Error: 92 is not a valid MessageId 2022-05-01 11:19:21 DEBUG (MainThread) [pyinsteon.protocol.messages.inbound] Returning: 2022-05-01 11:19:21 DEBUG (MainThread) [pyinsteon.messages] RX: msg_id: 0x62, address: 4f755c, flags: 0x00, cmd1: 0x0f, cmd2: 0x00, ack: 0x06 2022-05-01 11:19:21 DEBUG (MainThread) [pyinsteon.topics] Topic: ack.4f755c.ping.direct data: {'cmd1': 15, 'cmd2': 0, 'user_data': None} 2022-05-01 11:19:21 DEBUG (MainThread) [pyinsteon.messages] RX: msg_id: 0x62, address: 4f755c, flags: 0x05, cmd1: 0x19, cmd2: 0x00, ack: 0x06 2022-05-01 11:19:21 DEBUG (MainThread) [pyinsteon.topics] Topic: ack.4f755c.status_request.direct data: {'cmd1': 25, 'cmd2': 0, 'user_data': None} 2022-05-01 11:19:21 DEBUG (MainThread) [pyinsteon.protocol.http_reader_writer] Raw buffer: 02624F755C000F0006025C4F755C4E8DA0230F0002624F755C05190006025C4F755C4E8DA023190002625009C40519000602505009C44E8DA02100000000000000000000000000000000000000000000000000000000000000000000000000000000000078 2022-05-01 11:19:21 DEBUG (MainThread) [pyinsteon.protocol.http_reader_writer] Buffer from 80 to 120 2022-05-01 11:19:21 DEBUG (MainThread) [pyinsteon.protocol.http_transport] New buffer: 02625009C40519000602505009C44E8DA0210000 2022-05-01 11:19:21 DEBUG (MainThread) [pyinsteon.protocol.messages.inbound] IN CREATE: 02625009c40519000602505009c44e8da0210000 2022-05-01 11:19:21 DEBUG (MainThread) [pyinsteon.protocol.messages.inbound] Returning: 02505009c44e8da0210000 2022-05-01 11:19:21 DEBUG (MainThread) [pyinsteon.protocol.messages.inbound] IN CREATE: 02505009c44e8da0210000 2022-05-01 11:19:21 DEBUG (MainThread) [pyinsteon.protocol.messages.inbound] Returning: 2022-05-01 11:19:21 DEBUG (MainThread) [pyinsteon.messages] RX: msg_id: 0x62, address: 5009c4, flags: 0x05, cmd1: 0x19, cmd2: 0x00, ack: 0x06 2022-05-01 11:19:21 DEBUG (MainThread) [pyinsteon.topics] Topic: ack.5009c4.status_request.direct data: {'cmd1': 25, 'cmd2': 0, 'user_data': None} 2022-05-01 11:19:21 DEBUG (MainThread) [pyinsteon.messages] RX: msg_id: 0x50, address: 5009c4, target: 4e8da0, flags: 0x21, cmd1: 0x00, cmd2: 0x00 2022-05-01 11:19:21 DEBUG (MainThread) [pyinsteon.topics] Topic: 5009c4.standard_message_received.direct_ack data: {'cmd1': 0, 'cmd2': 0, 'target': 4e8da0, 'user_data': None, 'hops_left': 0} 2022-05-01 11:19:21 DEBUG (MainThread) [pyinsteon.topics] Topic: handler.5009c4.0.status_request.direct data: {'db_version': 0, 'status': 0} 2022-05-01 11:19:21 DEBUG (MainThread) [pyinsteon.topics] Topic: handler.5009c4.0.status_request.direct data: {'db_version': 0, 'status': 0} 2022-05-01 11:19:23 DEBUG (MainThread) [pyinsteon.topics] Topic: send.thermostat_set_heat_setpoint.direct data: {'address': 4f67c7, 'degrees': 75.0, 'zone': None, 'deadband': None} 2022-05-01 11:19:23 DEBUG (MainThread) [pyinsteon.messages] TX: msg_id: 0x62, address: 4f67c7, flags: 0x00, cmd1: 0x6d, cmd2: 0x96 2022-05-01 11:19:23 DEBUG (MainThread) [pyinsteon.protocol.http_reader_writer] Raw buffer: 02624F755C000F0006025C4F755C4E8DA0230F0002624F755C05190006025C4F755C4E8DA023190002625009C40519000602505009C44E8DA021000002624F75BB0519000602504F75BB4E8DA02600FF0000000000000000000000000000000000000000A0 2022-05-01 11:19:23 DEBUG (MainThread) [pyinsteon.protocol.http_reader_writer] Buffer from 120 to 160 2022-05-01 11:19:23 DEBUG (MainThread) [pyinsteon.protocol.http_transport] New buffer: 02624F75BB0519000602504F75BB4E8DA02600FF 2022-05-01 11:19:23 DEBUG (MainThread) [pyinsteon.protocol.messages.inbound] IN CREATE: 02624f75bb0519000602504f75bb4e8da02600ff 2022-05-01 11:19:23 DEBUG (MainThread) [pyinsteon.protocol.messages.inbound] Returning: 02504f75bb4e8da02600ff 2022-05-01 11:19:23 DEBUG (MainThread) [pyinsteon.protocol.messages.inbound] IN CREATE: 02504f75bb4e8da02600ff 2022-05-01 11:19:23 DEBUG (MainThread) [pyinsteon.protocol.messages.inbound] Returning: 2022-05-01 11:19:23 DEBUG (MainThread) [pyinsteon.messages] RX: msg_id: 0x62, address: 4f75bb, flags: 0x05, cmd1: 0x19, cmd2: 0x00, ack: 0x06 2022-05-01 11:19:23 DEBUG (MainThread) [pyinsteon.topics] Topic: ack.4f75bb.status_request.direct data: {'cmd1': 25, 'cmd2': 0, 'user_data': None} 2022-05-01 11:19:23 DEBUG (MainThread) [pyinsteon.messages] RX: msg_id: 0x50, address: 4f75bb, target: 4e8da0, flags: 0x26, cmd1: 0x00, cmd2: 0xff 2022-05-01 11:19:23 DEBUG (MainThread) [pyinsteon.topics] Topic: 4f75bb.standard_message_received.direct_ack data: {'cmd1': 0, 'cmd2': 255, 'target': 4e8da0, 'user_data': None, 'hops_left': 1} 2022-05-01 11:19:23 DEBUG (MainThread) [pyinsteon.protocol.http_reader_writer] Writing message: http://192.168.86.26:25105/3?02624f67c7006d96=I=3 2022-05-01 11:19:23 DEBUG (MainThread) [pyinsteon.protocol.http_reader_writer] Post status: 200 2022-05-01 11:19:23 DEBUG (MainThread) [pyinsteon.topics] Topic: handler.4f75bb.0.status_request.direct data: {'db_version': 0, 'status': 255} 2022-05-01 11:19:23 DEBUG (MainThread) [pyinsteon.topics] Topic: state_4f75bb_on_off_outlet_top_1 data: {'name': 'on_off_outlet_top', 'address': '4f75bb', 'value': 255, 'group': 1} 2022-05-01 11:19:23 DEBUG (MainThread) [pyinsteon.topics] Topic: handler.4f75bb.0.status_request.direct data: {'db_version': 0, 'status': 255} 2022-05-01 11:19:24 DEBUG (MainThread) [pyinsteon.topics] Topic: send.extended_get_set.direct data: {'address': 4f84e3, 'data1': 1, 'data2': 0} 2022-05-01 11:19:24 DEBUG (MainThread) [pyinsteon.messages] TX: msg_id: 0x62, address: 4f84e3, flags: 0x10, cmd1: 0x2e, cmd2: 0x00, user_data: 01.00.00.00.00.00.00.00.00.00.00.00.00.d1 2022-05-01 11:19:24 DEBUG (MainThread) [pyinsteon.protocol.http_reader_writer] Raw buffer: 02624F67C7006D960602504F67C74E8DA0AF6DFD02624F75390519000602504F75394E8DA02500FF0262501F3A051900060250501F3A4E8DA02000000000000000000000000000000000000000000000000000000000000000000000000000000000000078 2022-05-01 11:19:24 DEBUG (MainThread) [pyinsteon.protocol.http_reader_writer] Buffer from 0 to 120 2022-05-01 11:19:24 DEBUG (MainThread) [pyinsteon.protocol.http_transport] New buffer: 02624F67C7006D960602504F67C74E8DA0AF6DFD02624F75390519000602504F75394E8DA02500FF0262501F3A051900060250501F3A4E8DA0200000 2022-05-01 11:19:24 DEBUG (MainThread) [pyinsteon.protocol.messages.inbound] IN CREATE: 02624f67c7006d960602504f67c74e8da0af6dfd02624f75390519000602504f75394e8da02500ff0262501f3a051900060250501f3a4e8da0200000 2022-05-01 11:19:24 DEBUG (MainThread) [pyinsteon.protocol.messages.inbound] Returning: 02504f67c74e8da0af6dfd02624f75390519000602504f75394e8da02500ff0262501f3a051900060250501f3a4e8da0200000 2022-05-01 11:19:24 DEBUG (MainThread) [pyinsteon.protocol.messages.inbound] IN CREATE: 02504f67c74e8da0af6dfd02624f75390519000602504f75394e8da02500ff0262501f3a051900060250501f3a4e8da0200000 2022-05-01 11:19:24 DEBUG (MainThread) [pyinsteon.protocol.messages.inbound] Returning: 02624f75390519000602504f75394e8da02500ff0262501f3a051900060250501f3a4e8da0200000 2022-05-01 11:19:24 DEBUG (MainThread) [pyinsteon.protocol.messages.inbound] IN CREATE: 02624f75390519000602504f75394e8da02500ff0262501f3a051900060250501f3a4e8da0200000 2022-05-01 11:19:24 DEBUG (MainThread) [pyinsteon.protocol.messages.inbound] Returning: 02504f75394e8da02500ff0262501f3a051900060250501f3a4e8da0200000 2022-05-01 11:19:24 DEBUG (MainThread) [pyinsteon.protocol.messages.inbound] IN CREATE: 02504f75394e8da02500ff0262501f3a051900060250501f3a4e8da0200000 2022-05-01 11:19:24 DEBUG (MainThread) [pyinsteon.protocol.messages.inbound] Returning: 0262501f3a051900060250501f3a4e8da0200000 2022-05-01 11:19:24 DEBUG (MainThread) [pyinsteon.protocol.messages.inbound] IN CREATE: 0262501f3a051900060250501f3a4e8da0200000 2022-05-01 11:19:24 DEBUG (MainThread) [pyinsteon.protocol.messages.inbound] Returning: 0250501f3a4e8da0200000 2022-05-01 11:19:24 DEBUG (MainThread) [pyinsteon.protocol.messages.inbound] IN CREATE: 0250501f3a4e8da0200000 2022-05-01 11:19:24 DEBUG (MainThread) [pyinsteon.protocol.messages.inbound] Returning: 2022-05-01 11:19:24 DEBUG (MainThread) [pyinsteon.messages] RX: msg_id: 0x62, address: 4f67c7, flags: 0x00, cmd1: 0x6d, cmd2: 0x96, ack: 0x06 2022-05-01 11:19:24 DEBUG (MainThread) [pyinsteon.topics] Topic: ack.4f67c7.thermostat_set_heat_setpoint.direct data: {'cmd1': 109, 'cmd2': 150, 'user_data': None} 2022-05-01 11:19:24 DEBUG (MainThread) [pyinsteon.messages] RX: msg_id: 0x50, address: 4f67c7, target: 4e8da0, flags: 0xaf, cmd1: 0x6d, cmd2: 0xfd 2022-05-01 11:19:24 DEBUG (MainThread) [pyinsteon.topics] Topic: 4f67c7.thermostat_set_heat_setpoint.direct_nak data: {'cmd1': 109, 'cmd2': 253, 'target': 4e8da0, 'user_data': None, 'hops_left': 3} 2022-05-01 11:19:24 DEBUG (MainThread) [pyinsteon.messages] RX: msg_id: 0x62, address: 4f7539, flags: 0x05, cmd1: 0x19, cmd2: 0x00, ack: 0x06 2022-05-01 11:19:24 DEBUG (MainThread) [pyinsteon.topics] Topic: ack.4f7539.status_request.direct data: {'cmd1': 25, 'cmd2': 0, 'user_data': None} 2022-05-01 11:19:24 DEBUG (MainThread) [pyinsteon.messages] RX: msg_id: 0x50, address: 4f7539, target: 4e8da0, flags: 0x25, cmd1: 0x00, cmd2: 0xff 2022-05-01 11:19:24 DEBUG (MainThread) [pyinsteon.topics] Topic: 4f7539.standard_message_received.direct_ack data: {'cmd1': 0, 'cmd2': 255, 'target': 4e8da0, 'user_data': None, 'hops_left': 1} 2022-05-01 11:19:24 DEBUG (MainThread) [pyinsteon.messages] RX: msg_id: 0x62, address: 501f3a, flags: 0x05, cmd1: 0x19, cmd2: 0x00, ack: 0x06 2022-05-01 11:19:24 DEBUG (MainThread) [pyinsteon.topics] Topic: ack.501f3a.status_request.direct data: {'cmd1': 25, 'cmd2': 0, 'user_data': None} 2022-05-01 11:19:24 DEBUG (MainThread) [pyinsteon.messages] RX: msg_id: 0x50, address: 501f3a, target: 4e8da0, flags: 0x20, cmd1: 0x00, cmd2: 0x00 2022-05-01 11:19:24 DEBUG (MainThread) [pyinsteon.topics] Topic: 501f3a.standard_message_received.direct_ack data: {'cmd1': 0, 'cmd2': 0, 'target': 4e8da0, 'user_data': None, 'hops_left': 0} 2022-05-01 11:19:24 DEBUG (MainThread) [pyinsteon.protocol.http_reader_writer] Writing message: http://192.168.86.26:25105/3?02624f84e3102e0001000000000000000000000000d1=I=3 2022-05-01 11:19:24 DEBUG (MainThread) [pyinsteon.topics] Topic: handler.4f7539.0.status_request.direct data: {'db_version': 0, 'status': 255} 2022-05-01 11:19:24 DEBUG (MainThread) [pyinsteon.topics] Topic: state_4f7539_on_off_outlet_top_1 data: {'name': 'on_off_outlet_top', 'address': '4f7539', 'value': 255, 'group': 1} 2022-05-01 11:19:24 DEBUG (MainThread) [pyinsteon.topics] Topic: handler.4f7539.0.status_request.direct data: {'db_version': 0, 'status': 255} 2022-05-01 11:19:24 DEBUG (MainThread) [pyinsteon.topics] Topic: handler.501f3a.0.status_request.direct data: {'db_version': 0, 'status': 0} 2022-05-01 11:19:24 DEBUG (MainThread) [pyinsteon.topics] Topic: handler.501f3a.0.status_request.direct data: {'db_version': 0, 'status': 0} 2022-05-01 11:19:24 DEBUG (MainThread) [pyinsteon.protocol.http_reader_writer] Post status: 200 2022-05-01 11:19:25 DEBUG (MainThread) [pyinsteon.protocol.http_reader_writer] Raw buffer: 02624F84E3102E0001000000000000000000000000D10600000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000002E 2022-05-01 11:19:25 DEBUG (MainThread) [pyinsteon.protocol.http_reader_writer] Buffer from 0 to 46 2022-05-01 11:19:25 DEBUG (MainThread) [pyinsteon.protocol.http_transport] New buffer: 02624F84E3102E0001000000000000000000000000D106 2022-05-01 11:19:25 DEBUG (MainThread) [pyinsteon.protocol.messages.inbound] IN CREATE: 02624f84e3102e0001000000000000000000000000d106 2022-05-01 11:19:25 DEBUG (MainThread) [pyinsteon.protocol.messages.inbound] Returning: 2022-05-01 11:19:25 DEBUG (MainThread) [pyinsteon.messages] RX: msg_id: 0x62, address: 4f84e3, flags: 0x10, cmd1: 0x2e, cmd2: 0x00, user_data: 01.00.00.00.00.00.00.00.00.00.00.00.00.d1, ack: 0x06 2022-05-01 11:19:25 DEBUG (MainThread) [pyinsteon.topics] Topic: ack.4f84e3.extended_get_set.direct data: {'cmd1': 46, 'cmd2': 0, 'user_data': 01.00.00.00.00.00.00.00.00.00.00.00.00.d1} 2022-05-01 11:19:27 DEBUG (MainThread) [pyinsteon.protocol.http_reader_writer] Raw buffer: 02624F84E3102E0001000000000000000000000000D1060262500B7805190006025C4F84E34E8DA0232E000250500B784E8DA0200000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000006C 2022-05-01 11:19:27 DEBUG (MainThread) [pyinsteon.protocol.http_reader_writer] Buffer from 46 to 108 2022-05-01 11:19:27 DEBUG (MainThread) [pyinsteon.protocol.http_transport] New buffer: 0262500B7805190006025C4F84E34E8DA0232E000250500B784E8DA0200000 2022-05-01 11:19:27 DEBUG (MainThread) [pyinsteon.protocol.messages.inbound] IN CREATE: 0262500b7805190006025c4f84e34e8da0232e000250500b784e8da0200000 2022-05-01 11:19:27 DEBUG (MainThread) [pyinsteon.protocol.messages.inbound] Returning: 025c4f84e34e8da0232e000250500b784e8da0200000 2022-05-01 11:19:27 DEBUG (MainThread) [pyinsteon.protocol.messages.inbound] IN CREATE: 025c4f84e34e8da0232e000250500b784e8da0200000 2022-05-01 11:19:27 DEBUG (MainThread) [pyinsteon.protocol.messages.inbound] Error: 92 is not a valid MessageId 2022-05-01 11:19:27 DEBUG (MainThread) [pyinsteon.protocol.messages.inbound] Returning: 0250500b784e8da0200000 2022-05-01 11:19:27 DEBUG (MainThread) [pyinsteon.protocol.messages.inbound] IN CREATE: 0250500b784e8da0200000 2022-05-01 11:19:27 DEBUG (MainThread) [pyinsteon.protocol.messages.inbound] Returning: 2022-05-01 11:19:27 DEBUG (MainThread) [pyinsteon.messages] RX: msg_id: 0x62, address: 500b78, flags: 0x05, cmd1: 0x19, cmd2: 0x00, ack: 0x06 2022-05-01 11:19:27 DEBUG (MainThread) [pyinsteon.topics] Topic: ack.500b78.status_request.direct data: {'cmd1': 25, 'cmd2': 0, 'user_data': None} 2022-05-01 11:19:27 DEBUG (MainThread) [pyinsteon.messages] RX: msg_id: 0x50, address: 500b78, target: 4e8da0, flags: 0x20, cmd1: 0x00, cmd2: 0x00 2022-05-01 11:19:27 DEBUG (MainThread) [pyinsteon.topics] Topic: 500b78.standard_message_received.direct_ack data: {'cmd1': 0, 'cmd2': 0, 'target': 4e8da0, 'user_data': None, 'hops_left': 0} 2022-05-01 11:19:27 DEBUG (MainThread) [pyinsteon.topics] Topic: handler.500b78.0.status_request.direct data: {'db_version': 0, 'status': 0} 2022-05-01 11:19:27 DEBUG (MainThread) [pyinsteon.topics] Topic: handler.500b78.0.status_request.direct data: {'db_version': 0, 'status': 0} 2022-05-01 11:19:29 DEBUG (MainThread) [pyinsteon.protocol.http_reader_writer] Raw buffer: 02624F84E3102E0001000000000000000000000000D1060262500B7805190006025C4F84E34E8DA0232E000250500B784E8DA020000002623EE3900519000602503EE3904E8DA02B0000000000000000000000000000000000000000000000000000000094 2022-05-01 11:19:29 DEBUG (MainThread) [pyinsteon.protocol.http_reader_writer] Buffer from 108 to 148 2022-05-01 11:19:29 DEBUG (MainThread) [pyinsteon.protocol.http_transport] New buffer: 02623EE3900519000602503EE3904E8DA02B0000 2022-05-01 11:19:29 DEBUG (MainThread) [pyinsteon.protocol.messages.inbound] IN CREATE: 02623ee3900519000602503ee3904e8da02b0000 2022-05-01 11:19:29 DEBUG (MainThread) [pyinsteon.protocol.messages.inbound] Returning: 02503ee3904e8da02b0000 2022-05-01 11:19:29 DEBUG (MainThread) [pyinsteon.protocol.messages.inbound] IN CREATE: 02503ee3904e8da02b0000 2022-05-01 11:19:29 DEBUG (MainThread) [pyinsteon.protocol.messages.inbound] Returning: 2022-05-01 11:19:29 DEBUG (MainThread) [pyinsteon.messages] RX: msg_id: 0x62, address: 3ee390, flags: 0x05, cmd1: 0x19, cmd2: 0x00, ack: 0x06 2022-05-01 11:19:29 DEBUG (MainThread) [pyinsteon.topics] Topic: ack.3ee390.status_request.direct data: {'cmd1': 25, 'cmd2': 0, 'user_data': None} 2022-05-01 11:19:29 DEBUG (MainThread) [pyinsteon.messages] RX: msg_id: 0x50, address: 3ee390, target: 4e8da0, flags: 0x2b, cmd1: 0x00, cmd2: 0x00 2022-05-01 11:19:29 DEBUG (MainThread) [pyinsteon.topics] Topic: 3ee390.standard_message_received.direct_ack data: {'cmd1': 0, 'cmd2': 0, 'target': 4e8da0, 'user_data': None, 'hops_left': 2} 2022-05-01 11:19:29 DEBUG (MainThread) [pyinsteon.topics] Topic: send.ping.direct data: {'address': 417fe0} 2022-05-01 11:19:29 DEBUG (MainThread) [pyinsteon.messages] TX: msg_id: 0x62, address: 417fe0, flags: 0x00, cmd1: 0x0f, cmd2: 0x00 2022-05-01 11:19:29 DEBUG (MainThread) [pyinsteon.protocol.http_reader_writer] Writing message: http://192.168.86.26:25105/3?0262417fe0000f00=I=3 2022-05-01 11:19:29 DEBUG (MainThread) [pyinsteon.protocol.http_reader_writer] Post status: 200 2022-05-01 11:19:30 DEBUG (MainThread) [pyinsteon.topics] Topic: send.extended_get_set.direct data: {'address': 4f84e3, 'data1': 1, 'data2': 0} 2022-05-01 11:19:30 DEBUG (MainThread) [pyinsteon.messages] TX: msg_id: 0x62, address: 4f84e3, flags: 0x10, cmd1: 0x2e, cmd2: 0x00, user_data: 01.00.00.00.00.00.00.00.00.00.00.00.00.d1 2022-05-01 11:19:33 DEBUG (MainThread) [pyinsteon.protocol.http_reader_writer] Raw buffer: 0262417FE0000F00060262507E1005190006025C417FE04E8DA0230F000250507E104E8DA02500000262507D9C051900060250507D9C4E8DA0200000026239612A05190006025039612A4E8DA02601000000000000000000000000000000000000000000A0 2022-05-01 11:19:33 DEBUG (MainThread) [pyinsteon.protocol.http_reader_writer] Buffer from 0 to 160 2022-05-01 11:19:33 DEBUG (MainThread) [pyinsteon.protocol.http_transport] New buffer: 0262417FE0000F00060262507E1005190006025C417FE04E8DA0230F000250507E104E8DA02500000262507D9C051900060250507D9C4E8DA0200000026239612A05190006025039612A4E8DA0260100 2022-05-01 11:19:33 DEBUG (MainThread) [pyinsteon.protocol.messages.inbound] IN CREATE: 0262417fe0000f00060262507e1005190006025c417fe04e8da0230f000250507e104e8da02500000262507d9c051900060250507d9c4e8da0200000026239612a05190006025039612a4e8da0260100 2022-05-01 11:19:33 DEBUG (MainThread) [pyinsteon.protocol.messages.inbound] Returning: 0262507e1005190006025c417fe04e8da0230f000250507e104e8da02500000262507d9c051900060250507d9c4e8da0200000026239612a05190006025039612a4e8da0260100 2022-05-01 11:19:33 DEBUG (MainThread) [pyinsteon.protocol.messages.inbound] IN CREATE: 0262507e1005190006025c417fe04e8da0230f000250507e104e8da02500000262507d9c051900060250507d9c4e8da0200000026239612a05190006025039612a4e8da0260100 2022-05-01 11:19:33 DEBUG (MainThread) [pyinsteon.protocol.messages.inbound] Returning: 025c417fe04e8da0230f000250507e104e8da02500000262507d9c051900060250507d9c4e8da0200000026239612a05190006025039612a4e8da0260100 2022-05-01 11:19:33 DEBUG (MainThread) [pyinsteon.protocol.messages.inbound] IN CREATE: 025c417fe04e8da0230f000250507e104e8da02500000262507d9c051900060250507d9c4e8da0200000026239612a05190006025039612a4e8da0260100 2022-05-01 11:19:33 DEBUG (MainThread) [pyinsteon.protocol.messages.inbound] Error: 92 is not a valid MessageId 2022-05-01 11:19:33 DEBUG (MainThread) [pyinsteon.protocol.messages.inbound] Returning: 0250507e104e8da02500000262507d9c051900060250507d9c4e8da0200000026239612a05190006025039612a4e8da0260100 2022-05-01 11:19:33 DEBUG (MainThread) [pyinsteon.protocol.messages.inbound] IN CREATE: 0250507e104e8da02500000262507d9c051900060250507d9c4e8da0200000026239612a05190006025039612a4e8da0260100 2022-05-01 11:19:33 DEBUG (MainThread) [pyinsteon.protocol.messages.inbound] Returning: 0262507d9c051900060250507d9c4e8da0200000026239612a05190006025039612a4e8da0260100 2022-05-01 11:19:33 DEBUG (MainThread) [pyinsteon.protocol.messages.inbound] IN CREATE: 0262507d9c051900060250507d9c4e8da0200000026239612a05190006025039612a4e8da0260100 2022-05-01 11:19:33 DEBUG (MainThread) [pyinsteon.protocol.messages.inbound] Returning: 0250507d9c4e8da0200000026239612a05190006025039612a4e8da0260100 2022-05-01 11:19:33 DEBUG (MainThread) [pyinsteon.protocol.messages.inbound] IN CREATE: 0250507d9c4e8da0200000026239612a05190006025039612a4e8da0260100 2022-05-01 11:19:33 DEBUG (MainThread) [pyinsteon.protocol.messages.inbound] Returning: 026239612a05190006025039612a4e8da0260100 2022-05-01 11:19:33 DEBUG (MainThread) [pyinsteon.protocol.messages.inbound] IN CREATE: 026239612a05190006025039612a4e8da0260100 2022-05-01 11:19:33 DEBUG (MainThread) [pyinsteon.protocol.messages.inbound] Returning: 025039612a4e8da0260100 2022-05-01 11:19:33 DEBUG (MainThread) [pyinsteon.protocol.messages.inbound] IN CREATE: 025039612a4e8da0260100 2022-05-01 11:19:33 DEBUG (MainThread) [pyinsteon.protocol.messages.inbound] Returning: 2022-05-01 11:19:33 DEBUG (MainThread) [pyinsteon.messages] RX: msg_id: 0x62, address: 417fe0, flags: 0x00, cmd1: 0x0f, cmd2: 0x00, ack: 0x06 2022-05-01 11:19:33 DEBUG (MainThread) [pyinsteon.topics] Topic: ack.417fe0.ping.direct data: {'cmd1': 15, 'cmd2': 0, 'user_data': None} 2022-05-01 11:19:33 DEBUG (MainThread) [pyinsteon.messages] RX: msg_id: 0x62, address: 507e10, flags: 0x05, cmd1: 0x19, cmd2: 0x00, ack: 0x06 2022-05-01 11:19:33 DEBUG (MainThread) [pyinsteon.topics] Topic: ack.507e10.status_request.direct data: {'cmd1': 25, 'cmd2': 0, 'user_data': None} 2022-05-01 11:19:33 DEBUG (MainThread) [pyinsteon.messages] RX: msg_id: 0x50, address: 507e10, target: 4e8da0, flags: 0x25, cmd1: 0x00, cmd2: 0x00 2022-05-01 11:19:33 DEBUG (MainThread) [pyinsteon.topics] Topic: 507e10.standard_message_received.direct_ack data: {'cmd1': 0, 'cmd2': 0, 'target': 4e8da0, 'user_data': None, 'hops_left': 1} 2022-05-01 11:19:33 DEBUG (MainThread) [pyinsteon.messages] RX: msg_id: 0x62, address: 507d9c, flags: 0x05, cmd1: 0x19, cmd2: 0x00, ack: 0x06 2022-05-01 11:19:33 DEBUG (MainThread) [pyinsteon.topics] Topic: ack.507d9c.status_request.direct data: {'cmd1': 25, 'cmd2': 0, 'user_data': None} 2022-05-01 11:19:33 DEBUG (MainThread) [pyinsteon.messages] RX: msg_id: 0x50, address: 507d9c, target: 4e8da0, flags: 0x20, cmd1: 0x00, cmd2: 0x00 2022-05-01 11:19:33 DEBUG (MainThread) [pyinsteon.topics] Topic: 507d9c.standard_message_received.direct_ack data: {'cmd1': 0, 'cmd2': 0, 'target': 4e8da0, 'user_data': None, 'hops_left': 0} 2022-05-01 11:19:33 DEBUG (MainThread) [pyinsteon.messages] RX: msg_id: 0x62, address: 39612a, flags: 0x05, cmd1: 0x19, cmd2: 0x00, ack: 0x06 2022-05-01 11:19:33 DEBUG (MainThread) [pyinsteon.topics] Topic: ack.39612a.status_request.direct data: {'cmd1': 25, 'cmd2': 0, 'user_data': None} 2022-05-01 11:19:33 DEBUG (MainThread) [pyinsteon.messages] RX: msg_id: 0x50, address: 39612a, target: 4e8da0, flags: 0x26, cmd1: 0x01, cmd2: 0x00 2022-05-01 11:19:33 DEBUG (MainThread) [pyinsteon.topics] Topic: 39612a.assign_to_all_link_group.direct_ack data: {'cmd1': 1, 'cmd2': 0, 'target': 4e8da0, 'user_data': None, 'hops_left': 1} 2022-05-01 11:19:33 DEBUG (MainThread) [pyinsteon.protocol.http_reader_writer] Writing message: http://192.168.86.26:25105/3?02624f84e3102e0001000000000000000000000000d1=I=3 2022-05-01 11:19:33 DEBUG (MainThread) [pyinsteon.topics] Topic: handler.507e10.0.status_request.direct data: {'db_version': 0, 'status': 0} 2022-05-01 11:19:33 DEBUG (MainThread) [pyinsteon.topics] Topic: handler.507e10.0.status_request.direct data: {'db_version': 0, 'status': 0} 2022-05-01 11:19:33 DEBUG (MainThread) [pyinsteon.topics] Topic: handler.507d9c.0.status_request.direct data: {'db_version': 0, 'status': 0} 2022-05-01 11:19:33 DEBUG (MainThread) [pyinsteon.topics] Topic: handler.507d9c.0.status_request.direct data: {'db_version': 0, 'status': 0} 2022-05-01 11:19:33 DEBUG (MainThread) [pyinsteon.topics] Topic: handler.39612a.0.status_request.direct data: {'db_version': 1, 'status': 0} 2022-05-01 11:19:33 DEBUG (MainThread) [pyinsteon.protocol.http_reader_writer] Post status: 200 2022-05-01 11:19:36 DEBUG (MainThread) [pyinsteon.protocol.http_reader_writer] Raw buffer: 02624F84E3102E0001000000000000000000000000D10602624F67C71F2E02000000000000000000000000929606025C4F84E34E8DA0232E0002504F67C74E8DA0202E0202514F67C74E8DA0112E02010503162220502600D1E04615BF00000000000000BA 2022-05-01 11:19:36 DEBUG (MainThread) [pyinsteon.protocol.http_reader_writer] Buffer from 0 to 186 2022-05-01 11:19:36 DEBUG (MainThread) [pyinsteon.protocol.http_transport] New buffer: 02624F84E3102E0001000000000000000000000000D10602624F67C71F2E02000000000000000000000000929606025C4F84E34E8DA0232E0002504F67C74E8DA0202E0202514F67C74E8DA0112E02010503162220502600D1E04615BF 2022-05-01 11:19:36 DEBUG (MainThread) [pyinsteon.protocol.messages.inbound] IN CREATE: 02624f84e3102e0001000000000000000000000000d10602624f67c71f2e02000000000000000000000000929606025c4f84e34e8da0232e0002504f67c74e8da0202e0202514f67c74e8da0112e02010503162220502600d1e04615bf 2022-05-01 11:19:36 DEBUG (MainThread) [pyinsteon.protocol.messages.inbound] Returning: 02624f67c71f2e02000000000000000000000000929606025c4f84e34e8da0232e0002504f67c74e8da0202e0202514f67c74e8da0112e02010503162220502600d1e04615bf 2022-05-01 11:19:36 DEBUG (MainThread) [pyinsteon.protocol.messages.inbound] IN CREATE: 02624f67c71f2e02000000000000000000000000929606025c4f84e34e8da0232e0002504f67c74e8da0202e0202514f67c74e8da0112e02010503162220502600d1e04615bf 2022-05-01 11:19:36 DEBUG (MainThread) [pyinsteon.protocol.messages.inbound] Returning: 025c4f84e34e8da0232e0002504f67c74e8da0202e0202514f67c74e8da0112e02010503162220502600d1e04615bf 2022-05-01 11:19:36 DEBUG (MainThread) [pyinsteon.protocol.messages.inbound] IN CREATE: 025c4f84e34e8da0232e0002504f67c74e8da0202e0202514f67c74e8da0112e02010503162220502600d1e04615bf 2022-05-01 11:19:36 DEBUG (MainThread) [pyinsteon.protocol.messages.inbound] Error: 92 is not a valid MessageId 2022-05-01 11:19:36 DEBUG (MainThread) [pyinsteon.protocol.messages.inbound] Returning: 02504f67c74e8da0202e0202514f67c74e8da0112e02010503162220502600d1e04615bf 2022-05-01 11:19:36 DEBUG (MainThread) [pyinsteon.protocol.messages.inbound] IN CREATE: 02504f67c74e8da0202e0202514f67c74e8da0112e02010503162220502600d1e04615bf 2022-05-01 11:19:36 DEBUG (MainThread) [pyinsteon.protocol.messages.inbound] Returning: 02514f67c74e8da0112e02010503162220502600d1e04615bf 2022-05-01 11:19:36 DEBUG (MainThread) [pyinsteon.protocol.messages.inbound] IN CREATE: 02514f67c74e8da0112e02010503162220502600d1e04615bf 2022-05-01 11:19:36 DEBUG (MainThread) [pyinsteon.protocol.messages.inbound] Returning: 2022-05-01 11:19:36 DEBUG (MainThread) [pyinsteon.messages] RX: msg_id: 0x62, address: 4f84e3, flags: 0x10, cmd1: 0x2e, cmd2: 0x00, user_data: 01.00.00.00.00.00.00.00.00.00.00.00.00.d1, ack: 0x06 2022-05-01 11:19:36 DEBUG (MainThread) [pyinsteon.topics] Topic: ack.4f84e3.extended_get_set.direct data: {'cmd1': 46, 'cmd2': 0, 'user_data': 01.00.00.00.00.00.00.00.00.00.00.00.00.d1} 2022-05-01 11:19:36 DEBUG (MainThread) [pyinsteon.messages] RX: msg_id: 0x62, address: 4f67c7, flags: 0x1f, cmd1: 0x2e, cmd2: 0x02, user_data: 00.00.00.00.00.00.00.00.00.00.00.00.92.96, ack: 0x06 2022-05-01 11:19:36 DEBUG (MainThread) [pyinsteon.topics] Topic: ack.4f67c7.extended_get_set_2.direct data: {'cmd1': 46, 'cmd2': 2, 'user_data': 00.00.00.00.00.00.00.00.00.00.00.00.92.96} 2022-05-01 11:19:36 DEBUG (MainThread) [pyinsteon.messages] RX: msg_id: 0x50, address: 4f67c7, target: 4e8da0, flags: 0x20, cmd1: 0x2e, cmd2: 0x02 2022-05-01 11:19:36 DEBUG (MainThread) [pyinsteon.topics] Topic: 4f67c7.on_at_ramp_rate.direct_ack data: {'cmd1': 46, 'cmd2': 2, 'target': 4e8da0, 'user_data': None, 'hops_left': 0} 2022-05-01 11:19:36 DEBUG (MainThread) [pyinsteon.topics] Topic: 4f67c7.extended_get_set_2.direct_ack data: {'cmd1': 46, 'cmd2': 2, 'target': 4e8da0, 'user_data': None, 'hops_left': 0} 2022-05-01 11:19:36 DEBUG (MainThread) [pyinsteon.messages] RX: msg_id: 0x51, address: 4f67c7, target: 4e8da0, flags: 0x11, cmd1: 0x2e, cmd2: 0x02, user_data: 01.05.03.16.22.20.50.26.00.d1.e0.46.15.bf 2022-05-01 11:19:36 DEBUG (MainThread) [pyinsteon.topics] Topic: 4f67c7.extended_get_set_2.direct data: {'cmd1': 46, 'cmd2': 2, 'target': 4e8da0, 'user_data': 01.05.03.16.22.20.50.26.00.d1.e0.46.15.bf, 'hops_left': 0} 2022-05-01 11:19:36 DEBUG (MainThread) [py

teharris1 commented 2 years ago

I see what the issue is and yes, it did break between 2022.4 and 2022.5. This will be fixed in the next day or so.

darikweitzel commented 2 years ago

Awesome thank you!

Sent from my iPhone

On May 1, 2022, at 4:42 PM, Tom Harris @.***> wrote:

 I see what the issue is and yes, it did break between 2022.4 and 2022.5. This will be fixed in the next day or so.

— Reply to this email directly, view it on GitHub, or unsubscribe. You are receiving this because you authored the thread.

teharris1 commented 2 years ago

This code was introduced into core today. It should be in the main product Wednesday.