maximkulkin / esp-homekit

Apple HomeKit accessory server library for ESP-OPEN-RTOS
MIT License
1.1k stars 168 forks source link

Updated: Accessories do not update properly in Home app after latest updates #162

Closed peros550 closed 3 years ago

peros550 commented 3 years ago

I'm building a rather overloaded accessory. It includes motion, light , temp & humidity sensor and a heater which uses esp-ir to control a wall AC. Code is here

After latest updates in esp-homekit I'm unable to pair new accessories.

I have captured some logs

SDK version:0.9.9
>>> wifi_config: Initializing WiFi config
!!! wifi_config: No configuration found
>>> wifi_config: Starting AP mode
>>> wifi_config: Starting DHCP server
mode : sta(38:2b:78:03:79:83) + softAP(3a:2b:78:03:79:83)
add if0
add if1
bcn 100
>>> wifi_config: Starting WiFi scan
>>> wifi_config: Starting DNS server
>>> wifi_config: Starting HTTP server
scandone
scandone
add 1
aid 1
station: 8c:86:1e:0e:16:d6 join, AID = 1
scandone
scandone
scandone
scandone
>>> wifi_config: Connecting to test0
>>> wifi_config: WiFi Mode: Normal
>>> wifi_config: Connecting to test0
>>> wifi_config: WiFi Mode: Normal
scandone
scandone
scandone
reconnect
scandone
reconnect
scandone
station: 8c:86:1e:0e:16:d6 join, AID = 1
>>> wifi_config: Connecting to test0
>>> wifi_config: WiFi Mode: Normal
scandone
scandone
reconnect
scandone
add 0
aid 3
cnt

connected with test0, channel 10
dhcp client start...
ip:192.168.177.36,mask:255.255.255.0,gw:192.168.177.1
>>> wifi_config: Connected to WiFi network
station: 8c:86:1e:0e:16:d6 leave, AID = 1
rm match
bcn 0
del if1
mode : sta(38:2b:78:03:79:83)
Function called without core lock
CONNECTED TO >>> WIFI <<<
HOSTNAME set>>>>>:HomekitSensor-037983
>>> HomeKit: Starting server
>>> HomeKit: Formatting HomeKit storage at 0x8c000
>>> HomeKit: Generated new accessory ID: CE:3C:51:31:D2:A6
>>> HomeKit: Generated new accessory key
>>> HomeKit: Configuring mDNS
>>> wifi_config: Stopping HTTP server
Timer Stop Failed
Timer Start Failed
mDNS announcement: Name=HomekitSensor-037983
md=esp8266pv=1.0id=CE:3C:51:31:D2:A6c#=1s#=1ff=0sf=1ci=9 Port=5556 TTL=4500
SERVER JUST INITIALIZED
>>> homekit_run_server: Staring HTTP server
>>> wifi_config: Stopping DNS server
scandone
ip:192.168.177.36,mask:255.255.255.0,gw:192.168.177.1
scandone
scandone
scandone
scandone
scandone
scandone
>>> HomeKit: Got new client connection: 3 from 192.168.177.188
CLIENT JUST CONNECTED
>>> homekit_client_process: [Client 3] Got 138 incomming data
>>> homekit_server_on_pair_setup: Pair Setup
>>> homekit_server_on_pair_setup: Free heap: 22128
>>> tlv_debug: Got following TLV values:
>>> tlv_debug: Type 0 value (1 bytes): \x00
>>> tlv_debug: Type 6 value (1 bytes): \x01
>>> HomeKit: [Client 3] Pair Setup Step 1/3
>>> homekit_server_on_pair_setup: Free heap: 22044
>>> crypto_srp_new: Initializing SRP
>>> homekit_server_on_pair_setup: [Client 3] Initializing crypto
>>> homekit_server_on_pair_setup: Free heap: 21380
>>> homekit_server_on_pair_setup: [Client 3] Using user-specified password: 333-33-333
>>> crypto_srp_init: Generating salt
>>> crypto_srp_init: Setting SRP username
>>> crypto_srp_init: Setting SRP params
>>> crypto_srp_init: Setting SRP password
>>> crypto_srp_init: Getting SRP verifier
>>> crypto_srp_init: Failed to get SRP verifier (code -2)
>>> crypto_srp_get_public_key: Calculating public key
!!! HomeKit: [Client 3] Failed to dump SPR public key (code -2)
>>> send_tlv_response: [Client 3] Sending TLV response
>>> tlv_debug: Got following TLV values:
>>> tlv_debug: Type 6 value (1 bytes): \x02
>>> tlv_debug: Type 7 value (1 bytes): \x01
>>> client_send: [Client 3] Sending payload: HTTP/1.1 200 OK\x0D\x0AContent-Type: application/pairing+tlv8\x0D\x0AContent-Length: 6\x0D\x0AConnection: keep-alive\x0D\x0A\x0D\x0A\x06\x01\x02\x07\x01\x01
>>> homekit_client_process: [Client 3] Finished processing
>>> HomeKit: [Client 3] Closing client connection
CLIENT JUST DISCONNECTED
scandone
scandone
>>> HomeKit: Got new client connection: 3 from 192.168.177.188
CLIENT JUST CONNECTED
>>> homekit_client_process: [Client 3] Got 138 incomming data
>>> homekit_server_on_pair_setup: Pair Setup
>>> homekit_server_on_pair_setup: Free heap: 21948
>>> tlv_debug: Got following TLV values:
>>> tlv_debug: Type 0 value (1 bytes): \x00
>>> tlv_debug: Type 6 value (1 bytes): \x01
>>> HomeKit: [Client 3] Pair Setup Step 1/3
>>> homekit_server_on_pair_setup: Free heap: 21864
>>> crypto_srp_new: Initializing SRP
>>> homekit_server_on_pair_setup: [Client 3] Initializing crypto
>>> homekit_server_on_pair_setup: Free heap: 21200
>>> homekit_server_on_pair_setup: [Client 3] Using user-specified password: 333-33-333
>>> crypto_srp_init: Generating salt
>>> crypto_srp_init: Setting SRP username
>>> crypto_srp_init: Setting SRP params
>>> crypto_srp_init: Setting SRP password
>>> crypto_srp_init: Getting SRP verifier
>>> crypto_srp_init: Failed to get SRP verifier (code -2)
>>> crypto_srp_get_public_key: Calculating public key
scandone
!!! HomeKit: [Client 3] Failed to dump SPR public key (code -2)
>>> send_tlv_response: [Client 3] Sending TLV response
>>> tlv_debug: Got following TLV values:
>>> tlv_debug: Type 6 value (1 bytes): \x02
>>> tlv_debug: Type 7 value (1 bytes): \x01
>>> client_send: [Client 3] Sending payload: HTTP/1.1 200 OK\x0D\x0AContent-Type: application/pairing+tlv8\x0D\x0AContent-Length: 6\x0D\x0AConnection: keep-alive\x0D\x0A\x0D\x0A\x06\x01\x02\x07\x01\x01
>>> homekit_client_process: [Client 3] Finished processing
>>> HomeKit: [Client 3] Closing client connection
CLIENT JUST DISCONNECTED
Traceback (most recent call last):
  File "/home/jpap/projects/esp-open-rtos//utils/filteroutput.py", line 105, in <module>
    main()
  File "/home/jpap/projects/esp-open-rtos//utils/filteroutput.py", line 87, in main
    line = port.readline()
  File "/home/jpap/.local/lib/python2.7/site-packages/serial/serialposix.py", line 501, in read
    'device reports readiness to read but returned no data '
serial.serialutil.SerialException: device reports readiness to read but returned no data (device disconnected or multiple access on port?)
/home/jpap/projects/esp-open-rtos/common.mk:261: recipe for target 'test' failed
make: *** [test] Error 1
make: Leaving directory '/home/jpap/projects/esp-homekit-multiple-sensors/examples/multiple_sensors_ac'
jpap@ubuntu:~/projects/esp-homekit-multiple-sensors$ make -C examples/multiple_sensors_ac monitor
make: Entering directory '/home/jpap/projects/esp-homekit-multiple-sensors/examples/multiple_sensors_ac'
/home/jpap/projects/esp-open-rtos//utils/filteroutput.py --port /dev/ttyUSB0 --baud 115200 --elf ./build/multiple_sensors_ac.out
Opening /dev/ttyUSB0 at 115200bps...
>>> wifi_config: Connected to WiFi network
CONNECTED TO >>> WIFI <<<
HOSTNAME set>>>>>:HomekitSensor-037983
>>> HomeKit: Starting server
>>> HomeKit: Using existing accessory ID: CE:3C:51:31:D2:A6
>>> HomeKit: Configuring mDNS
mDNS announcement: Name=HomekitSensor-037983
md=esp8266pv=1.0id=CE:3C:51:31:D2:A6c#=1s#=1ff=0sf=1ci=9 Port=5556 TTL=4500
SERVER JUST INITIALIZED
>>> homekit_run_server: Staring HTTP server
ip:192.168.177.36,mask:255.255.255.0,gw:192.168.177.1
>>> HomeKit: Got new client connection: 4 from 192.168.177.188
CLIENT JUST CONNECTED
>>> homekit_client_process: [Client 4] Got 138 incomming data
>>> homekit_server_on_pair_setup: Pair Setup
>>> homekit_server_on_pair_setup: Free heap: 25484
>>> tlv_debug: Got following TLV values:
>>> tlv_debug: Type 0 value (1 bytes): \x00
>>> tlv_debug: Type 6 value (1 bytes): \x01
>>> HomeKit: [Client 4] Pair Setup Step 1/3
>>> homekit_server_on_pair_setup: Free heap: 25400
>>> crypto_srp_new: Initializing SRP
>>> homekit_server_on_pair_setup: [Client 4] Initializing crypto
>>> homekit_server_on_pair_setup: Free heap: 24736
>>> homekit_server_on_pair_setup: [Client 4] Using user-specified password: 333-33-333
>>> crypto_srp_init: Generating salt
>>> crypto_srp_init: Setting SRP username
>>> crypto_srp_init: Setting SRP params
>>> crypto_srp_init: Setting SRP password
>>> crypto_srp_init: Getting SRP verifier
>>> crypto_srp_init: Setting SRP verifier
>>> crypto_srp_get_public_key: Calculating public key
>>> send_tlv_response: [Client 4] Sending TLV response
>>> tlv_debug: Got following TLV values:
>>> tlv_debug: Type 3 value (384 bytes): \x09P1\x91\x1B\xB7\xFC\x07JI\x01f\x19\xAEv\\\x82\xFAS\x8[\x96\x1D]\x80\xE5\xC1Q'\xD0\xB7l\x13\xA9\x16\xD2/\x1F\x05\xEE\x9135\xAC\xCF\xA2=\xEB[&\xAFq~-~\xE6\xCC\x11\xA1\x86\xD2\xBA\x09\xA3I\x14:t\xD1O\x09+\xB5f\x99S\xD3<\x8C\xDE=\x0A\xE8\x17\xBF\xFDYxs\x9FP\xBDO\x9F\x18O\x8C\x08\xB7(<\xD2\x92\x1B\xF5>\xCB/\xD0\x12\xC3\xB0\x15\xEETI\x08\xA3\xEF\x8D\xF1&\x06\xC4l\x01\xCD`\x19Pm\xD9\xE3\xC13\xF9\xAE!!\x0Cjm\xF1\xF4%\xA6g\xFA\xC8py\x10\xD2nw\xDA^\xCB\x1EY\xCC\xFF\xCAD\x1C\xE0\x1B\xD8\xC8\x9Eq\xF4*\x97\x85\xF9\x11\x01s\xE1\x0C\x10x\x05~\xB8,nR\x9C\x8C.$\xE3\xF2p\x92q#5\xBF\xCB\x80\x18WL\x92\xA6\x87BLU\xD8*\x99\x00^\xB3\xA9\x88\xE6f\xD8\x115\xA1lJ\x15\xDF\x8B \xE2}\xEF=\x8B\x93xG6\xFBK\x119\xB9\x13\xD2F\xB6\xB0\xEE\xD7\x07\x13\x13\x9B\xDC*\xF3R\xB3\xC3\xB2\xDE\xCA\xB1\x96\xEB\xE5Yf\x06-\x14,\xC3,\xCB\x09\xC1Y\xB2\xA8[\x8EMR\xD6\x1E\x87V<<\x13\x90\x18(#\xC9\x86K\xCD\xD9\xF0\xFD\xB9\xEC\xD6\xE1\xD8K\xF6Fd\xB2\x1C7C^\xDD\xC7\xAB\xDB\xDCtkY\xC8y\xC8l~\xFD\xE9\x91\xBCM\xFC\xF2\xA6m\xF1\xE8'\xC7\xD0Z\xE6\x97D\xF8t:pV\xFF\xF6\xF1p]\x07\xCA\xB1\x0F\xCAI\xBE7}\xAE\xCA.%\x9Bnd\xE2\x0C\x95\xC3
>>> tlv_debug: Type 2 value (16 bytes): W\x93\xC6\xE87\xFE\x02\xFF\x9E\xD2e,\xE1\xA1\xAB\x1F
>>> tlv_debug: Type 6 value (1 bytes): \x02
>>> client_send: [Client 4] Sending payload: HTTP/1.1 200 OK\x0D\x0AContent-Type: application/pairing+tlv8\x0D\x0AContent-Length: 409\x0D\x0AConnection: keep-alive\x0D\x0A\x0D\x0A\x03\xFF\x09P1\x91\x1B\xB7\xFC\x07JI\x01f\x19\xAEv\\\x82\xFAS\x8[\x96\x1D]\x80\xE5\xC1Q'\xD0\xB7l\x13\xA9\x16\xD2/\x1F\x05\xEE\x9135\xAC\xCF\xA2=\xEB[&\xAFq~-~\xE6\xCC\x11\xA1\x86\xD2\xBA\x09\xA3I\x14:t\xD1O\x09+\xB5f\x99S\xD3<\x8C\xDE=\x0A\xE8\x17\xBF\xFDYxs\x9FP\xBDO\x9F\x18O\x8C\x08\xB7(<\xD2\x92\x1B\xF5>\xCB/\xD0\x12\xC3\xB0\x15\xEETI\x08\xA3\xEF\x8D\xF1&\x06\xC4l\x01\xCD`\x19Pm\xD9\xE3\xC13\xF9\xAE!!\x0Cjm\xF1\xF4%\xA6g\xFA\xC8py\x10\xD2nw\xDA^\xCB\x1EY\xCC\xFF\xCAD\x1C\xE0\x1B\xD8\xC8\x9Eq\xF4*\x97\x85\xF9\x11\x01s\xE1\x0C\x10x\x05~\xB8,nR\x9C\x8C.$\xE3\xF2p\x92q#5\xBF\xCB\x80\x18WL\x92\xA6\x87BLU\xD8*\x99\x00^\xB3\xA9\x88\xE6f\xD8\x115\xA1lJ\x15\xDF\x8B \xE2}\xEF=\x8B\x93xG6\xFBK\x119\xB9\x13\xD2F\xB6\xB0\xEE\x03\x81\xD7\x07\x13\x13\x9B\xDC*\xF3R\xB3\xC3\xB2\xDE\xCA\xB1\x96\xEB\xE5Yf\x06-\x14,\xC3,\xCB\x09\xC1Y\xB2\xA8[\x8EMR\xD6\x1E\x87V<<\x13\x90\x18(#\xC9\x86K\xCD\xD9\xF0\xFD\xB9\xEC\xD6\xE1\xD8K\xF6Fd\xB2\x1C7C^\xDD\xC7\xAB\xDB\xDCtkY\xC8y\xC8l~\xFD\xE9\x91\xBCM\xFC\xF2\xA6m\xF1\xE8'\xC7\xD0Z\xE6\x97D\xF8t:pV\xFF\xF6\xF1p]\x07\xCA\xB1\x0F\xCAI\xBE7}\xAE\xCA.%\x9Bnd\xE2\x0C\x95\xC3\x02\x10W\x93\xC6\xE87\xFE\x02\xFF\x9E\xD2e,\xE1\xA1\xAB\x1F\x06\x01\x02
>>> homekit_client_process: [Client 4] Finished processing
>>> homekit_client_process: [Client 4] Got 591 incomming data
>>> homekit_server_on_pair_setup: Pair Setup
>>> homekit_server_on_pair_setup: Free heap: 22544
>>> tlv_debug: Got following TLV values:
>>> tlv_debug: Type 6 value (1 bytes): \x03
>>> tlv_debug: Type 3 value (384 bytes): M\x80;@\xA5\xEE\x8E\xF5\xB8.\xDC\x90\x9E+(\xC0\xDE\xB7\\\xE4\x1C\x14kHQ,\xDF7jN \xE8\xF0\x88\x83\xD9\x19:j\xD6\x09\x846\xDD\xDA\xFAKX\x95T\x06)\xE4a\xAC\xDB3\x8C\x06\x84V\xFBP\x0C\x02i^\xEEeE\xFA\xF6\xBAa\xA1\xD6\x8A$\x99\x18/\x99~\xE5z\x86\xFD\xCA\x14\xB9\xE2oZ\x04\x8F\xEE\xD6\xC5~\x8E\x92\x80\xA4\x93\x1E\xA7R"\x16\x1D,\xF5e\xF1\xD8\xEAw\x90^j!\x9B:p\x14l\xC8\xFE\xF6^O\x9A\xC9\xFAS\x07\xD9U\xAD\xAB\xED\x0Evc\xF3\xF6=\x8D\xAF\xDD\xCA~\xB2\xCC]\x98X\xC1|[\xF8\xE6\x0D\xE6\xE2\xE3\x96\xCD\xA0\xAE\xD9\xD6\xB1+\x89:ojP\xD1\xE91NER\xF4\x87a<d\x9D\x9E\xE2\x88\xBE<\x90,L=\xFE\xABy\x8Dh\x9D\xEC2\xFD\xED(H_\x05\xA5E\xBF\xB2\xD8\xFF\x81*\x9A\x1BN\xBA\xD3\xF1\x8818\xD2\xE7(b{\xB3\xD0\x84\xA1(\x86\xC7xq\xDF\xAF\x0F(nP\x93"\x18(\xC5\xEEvD\xDA\x0E,\x10\xB8\xCBg$8\xB55\x0A\x13hM\xE4+\xB\\\xE7\xB2!\xFAJ\x06\xBFf\x03\x0F\xEA\x96\x8D\xF7\xE7Qf\x93\x0B\xBD^f"\x8B\xBF\x92V\x14\xEBL'\xDC\xEB\xC0\xD2\x1A\xE9W\xB5\xAD\x19\x94]\xE1\xC5R:\xFE\x8Co\xBDI\xF8\xB5YfDu\xF2l\x90R@zA\x8Ar\xC6$\xCA)GZH\x1B\x07e\xFB\xD0\x8BX\x9C\xA3\x1C0j\x06\x05zuSot\xB5\xD3\xB9Uc\xD2o\xBD
>>> tlv_debug: Type 4 value (64 bytes): \xC5F\xAB\xF4:IHnV\xD4\x9F\x92bg4f\xD7a%\xBF\x18\xDAS]"\x15uO\x98H\x0C\x16\xA4$u\xD7A\x92\xDAKxD\x93lZ\xDC\xC8\xFA\x91\x03\xD1\xBA\x10\xA01\x8E\xF2\xFD\xDDX(\xB3\xE2\x8F
>>> HomeKit: [Client 4] Pair Setup Step 2/3
>>> homekit_server_on_pair_setup: Free heap: 21980
>>> homekit_server_on_pair_setup: [Client 4] Computing SRP shared secret
>>> homekit_server_on_pair_setup: Free heap: 21980
>>> crypto_srp_compute_key: Failed to generate SRP shared secret key (code -2)
!!! HomeKit: [Client 4] Failed to compute SRP shared secret (code -2)
>>> send_tlv_response: [Client 4] Sending TLV response
>>> tlv_debug: Got following TLV values:
>>> tlv_debug: Type 6 value (1 bytes): \x04
>>> tlv_debug: Type 7 value (1 bytes): \x02
>>> client_send: [Client 4] Sending payload: HTTP/1.1 200 OK\x0D\x0AContent-Type: application/pairing+tlv8\x0D\x0AContent-Length: 6\x0D\x0AConnection: keep-alive\x0D\x0A\x0D\x0A\x06\x01\x04\x07\x01\x02
>>> homekit_client_process: [Client 4] Finished processing
>>> homekit_client_process: [Client 4] Got 138 incomming data
>>> homekit_server_on_pair_setup: Pair Setup
>>> homekit_server_on_pair_setup: Free heap: 22996
>>> tlv_debug: Got following TLV values:
>>> tlv_debug: Type 0 value (1 bytes): \x00
>>> tlv_debug: Type 6 value (1 bytes): \x01
>>> HomeKit: [Client 4] Pair Setup Step 1/3
>>> homekit_server_on_pair_setup: Free heap: 22912
>>> homekit_server_on_pair_setup: [Client 4] Initializing crypto
>>> homekit_server_on_pair_setup: Free heap: 22912
>>> homekit_server_on_pair_setup: [Client 4] Using user-specified password: 333-33-333
>>> crypto_srp_init: Generating salt
>>> crypto_srp_init: Setting SRP username
>>> crypto_srp_init: Setting SRP params
>>> crypto_srp_init: Setting SRP password
>>> crypto_srp_init: Failed to set SRP password (code -173)
>>> crypto_srp_get_public_key: Calculating public key
>>> send_tlv_response: [Client 4] Sending TLV response
>>> tlv_debug: Got following TLV values:
>>> tlv_debug: Type 3 value (384 bytes): \x09P1\x91\x1B\xB7\xFC\x07JI\x01f\x19\xAEv\\\x82\xFAS\x8[\x96\x1D]\x80\xE5\xC1Q'\xD0\xB7l\x13\xA9\x16\xD2/\x1F\x05\xEE\x9135\xAC\xCF\xA2=\xEB[&\xAFq~-~\xE6\xCC\x11\xA1\x86\xD2\xBA\x09\xA3I\x14:t\xD1O\x09+\xB5f\x99S\xD3<\x8C\xDE=\x0A\xE8\x17\xBF\xFDYxs\x9FP\xBDO\x9F\x18O\x8C\x08\xB7(<\xD2\x92\x1B\xF5>\xCB/\xD0\x12\xC3\xB0\x15\xEETI\x08\xA3\xEF\x8D\xF1&\x06\xC4l\x01\xCD`\x19Pm\xD9\xE3\xC13\xF9\xAE!!\x0Cjm\xF1\xF4%\xA6g\xFA\xC8py\x10\xD2nw\xDA^\xCB\x1EY\xCC\xFF\xCAD\x1C\xE0\x1B\xD8\xC8\x9Eq\xF4*\x97\x85\xF9\x11\x01s\xE1\x0C\x10x\x05~\xB8,nR\x9C\x8C.$\xE3\xF2p\x92q#5\xBF\xCB\x80\x18WL\x92\xA6\x87BLU\xD8*\x99\x00^\xB3\xA9\x88\xE6f\xD8\x115\xA1lJ\x15\xDF\x8B \xE2}\xEF=\x8B\x93xG6\xFBK\x119\xB9\x13\xD2F\xB6\xB0\xEE\xD7\x07\x13\x13\x9B\xDC*\xF3R\xB3\xC3\xB2\xDE\xCA\xB1\x96\xEB\xE5Yf\x06-\x14,\xC3,\xCB\x09\xC1Y\xB2\xA8[\x8EMR\xD6\x1E\x87V<<\x13\x90\x18(#\xC9\x86K\xCD\xD9\xF0\xFD\xB9\xEC\xD6\xE1\xD8K\xF6Fd\xB2\x1C7C^\xDD\xC7\xAB\xDB\xDCtkY\xC8y\xC8l~\xFD\xE9\x91\xBCM\xFC\xF2\xA6m\xF1\xE8'\xC7\xD0Z\xE6\x97D\xF8t:pV\xFF\xF6\xF1p]\x07\xCA\xB1\x0F\xCAI\xBE7}\xAE\xCA.%\x9Bnd\xE2\x0C\x95\xC3
>>> tlv_debug: Type 2 value (16 bytes): \x08C\xB0`\xA\xAB\xFA\x01\x05G\xC8\x09\xA0\xE2-
>>> tlv_debug: Type 6 value (1 bytes): \x02
>>> client_send: [Client 4] Sending payload: HTTP/1.1 200 OK\x0D\x0AContent-Type: application/pairing+tlv8\x0D\x0AContent-Length: 409\x0D\x0AConnection: keep-alive\x0D\x0A\x0D\x0A\x03\xFF\x09P1\x91\x1B\xB7\xFC\x07JI\x01f\x19\xAEv\\\x82\xFAS\x8[\x96\x1D]\x80\xE5\xC1Q'\xD0\xB7l\x13\xA9\x16\xD2/\x1F\x05\xEE\x9135\xAC\xCF\xA2=\xEB[&\xAFq~-~\xE6\xCC\x11\xA1\x86\xD2\xBA\x09\xA3I\x14:t\xD1O\x09+\xB5f\x99S\xD3<\x8C\xDE=\x0A\xE8\x17\xBF\xFDYxs\x9FP\xBDO\x9F\x18O\x8C\x08\xB7(<\xD2\x92\x1B\xF5>\xCB/\xD0\x12\xC3\xB0\x15\xEETI\x08\xA3\xEF\x8D\xF1&\x06\xC4l\x01\xCD`\x19Pm\xD9\xE3\xC13\xF9\xAE!!\x0Cjm\xF1\xF4%\xA6g\xFA\xC8py\x10\xD2nw\xDA^\xCB\x1EY\xCC\xFF\xCAD\x1C\xE0\x1B\xD8\xC8\x9Eq\xF4*\x97\x85\xF9\x11\x01s\xE1\x0C\x10x\x05~\xB8,nR\x9C\x8C.$\xE3\xF2p\x92q#5\xBF\xCB\x80\x18WL\x92\xA6\x87BLU\xD8*\x99\x00^\xB3\xA9\x88\xE6f\xD8\x115\xA1lJ\x15\xDF\x8B \xE2}\xEF=\x8B\x93xG6\xFBK\x119\xB9\x13\xD2F\xB6\xB0\xEE\x03\x81\xD7\x07\x13\x13\x9B\xDC*\xF3R\xB3\xC3\xB2\xDE\xCA\xB1\x96\xEB\xE5Yf\x06-\x14,\xC3,\xCB\x09\xC1Y\xB2\xA8[\x8EMR\xD6\x1E\x87V<<\x13\x90\x18(#\xC9\x86K\xCD\xD9\xF0\xFD\xB9\xEC\xD6\xE1\xD8K\xF6Fd\xB2\x1C7C^\xDD\xC7\xAB\xDB\xDCtkY\xC8y\xC8l~\xFD\xE9\x91\xBCM\xFC\xF2\xA6m\xF1\xE8'\xC7\xD0Z\xE6\x97D\xF8t:pV\xFF\xF6\xF1p]\x07\xCA\xB1\x0F\xCAI\xBE7}\xAE\xCA.%\x9Bnd\xE2\x0C\x95\xC3\x02\x10\x08C\xB0`\xA\xAB\xFA\x01\x05G\xC8\x09\xA0\xE2-\x06\x01\x02
>>> homekit_client_process: [Client 4] Finished processing
>>> homekit_client_process: [Client 4] Got 591 incomming data
>>> homekit_server_on_pair_setup: Pair Setup
>>> homekit_server_on_pair_setup: Free heap: 22524
>>> tlv_debug: Got following TLV values:
>>> tlv_debug: Type 6 value (1 bytes): \x03
>>> tlv_debug: Type 3 value (384 bytes): \x19\xF5\xE0,&\xC2\xB0\x8C\xA0\x18\x1Ar\x13\xFC\xB0o\x0C\x92\x9F<\x93\xA0?&\xC1\xA07:\xFDxXW\x04{\xF3\x88\xDA=\x81\xBC\xC0\xEF\xD3\x17\xA6\xEFY\xECL=\xB6\x80\x99\x86\x85\x0AO&\x80\xBD\xC6\x16\xA8C\xEEJ\x1Cc\x07R\xDD\xBA\xF0\xCDNB\x86\x9D?\xEB\xB5\xC8\xD78\xD22\x12\xEF\x96J\x94I\x03\xF8\x84\x0E\x1A\x95\xDBa\xAC\x94B\xA7\x15n\xDB)\xC9D\x84\x8E^)J\xC6u\xA0\x002_Z\x17\x89\x9E\x17\xA5\xE5\xFD.v\xB2\xEE\xF3'\xA20\x85k\x06\xE2\xFF\xB02h\xB42\xBC\x1A\xF6\x15\xF6fB\x9AC\xEE\x0C\xB4\x83\xE4s\x80{\x01\xE5X\xD6\xC5\xBC\xD5\x8D\x82?\xC9>\xF4\x8C\xE2UW\x1A\xC0\xCDR\xF2O\xFC\xADW\xB4\xD1\xC3\xBB\x1D\x1AK\xC7Fz\x11xi\xB2\xDA\xDAk\xF7\x17f\xDA\xB0G\x06*9\x19FYg\xE0*j\\\x13\x10h\x96y\xF9\x1C\xAB\xC3\xDBJ\xB0Uz\xE0\x06\xC0\x19\xB7\xFA\x1C\xE92'F\xD487\xBA\xE9\xF1\xF0U\xF5\x13\xF6\xB1]\xE2\xBF\xDD\xCE\x03Yf"\xA5D\x0F\x12\xAC\x11T\xE2\xF0(\xF1!\xBC\x93H\xDC&\xAB\x06\x9D\x0C\x998\xACN\xA9\xA9\xEE\x1B\x1A\xF6\xFF\xC9\xC0\x12:\xD03\x85\x96\xFE\x90t\xCC\x92\xF4\xE3y\x05\xBEa\x16\xCB\x1CV\xD0\xDA\xB94\xC2n)UK\x09\xFF\x85ft\xA2#}S\x9C\x8EG\xB65\x97n@\x96\x852\xC6\xFF&\xCD\x1C\xF5\x9B\xF3\xC7J\xBB\xC7\xD5\x0A\xAC\x10\x0D\x02]"\xD3\xA4\x8A\x98\xBDg~W
>>> tlv_debug: Type 4 value (64 bytes): \x029P\xA3j\xDC\xA6\x1FRQ\xCF\xC6\xA2\x81\xCA\xC0\xF1\x1C\x95\xD4& B\xA4\xDB\xAAr{i\x92\x88\xB0\xF7\xB9nfg\xFB\x06\x84f\xC6O_\xE6\x96\x18\x8D{\xA8\xDD\xF8\x80\x14\x82m\xF7\x04\xE\x07g\xD7\xF5
>>> HomeKit: [Client 4] Pair Setup Step 2/3
>>> homekit_server_on_pair_setup: Free heap: 21960
>>> homekit_server_on_pair_setup: [Client 4] Computing SRP shared secret
>>> homekit_server_on_pair_setup: Free heap: 21960
>>> crypto_srp_compute_key: Failed to generate SRP shared secret key (code -2)
!!! HomeKit: [Client 4] Failed to compute SRP shared secret (code -2)
>>> send_tlv_response: [Client 4] Sending TLV response
>>> tlv_debug: Got following TLV values:
>>> tlv_debug: Type 6 value (1 bytes): \x04
>>> tlv_debug: Type 7 value (1 bytes): \x02
>>> client_send: [Client 4] Sending payload: HTTP/1.1 200 OK\x0D\x0AContent-Type: application/pairing+tlv8\x0D\x0AContent-Length: 6\x0D\x0AConnection: keep-alive\x0D\x0A\x0D\x0A\x06\x01\x04\x07\x01\x02
>>> homekit_client_process: [Client 4] Finished processing
>>> homekit_client_process: [Client 4] Got 138 incomming data
>>> homekit_server_on_pair_setup: Pair Setup
>>> homekit_server_on_pair_setup: Free heap: 22836
>>> tlv_debug: Got following TLV values:
>>> tlv_debug: Type 0 value (1 bytes): \x00
>>> tlv_debug: Type 6 value (1 bytes): \x01
>>> HomeKit: [Client 4] Pair Setup Step 1/3
>>> homekit_server_on_pair_setup: Free heap: 22752
>>> homekit_server_on_pair_setup: [Client 4] Initializing crypto
>>> homekit_server_on_pair_setup: Free heap: 22892
>>> homekit_server_on_pair_setup: [Client 4] Using user-specified password: 333-33-333
>>> crypto_srp_init: Generating salt
>>> crypto_srp_init: Setting SRP username
>>> crypto_srp_init: Setting SRP params
>>> crypto_srp_init: Setting SRP password
>>> crypto_srp_init: Failed to set SRP password (code -173)
>>> crypto_srp_get_public_key: Calculating public key
>>> send_tlv_response: [Client 4] Sending TLV response
>>> tlv_debug: Got following TLV values:
>>> tlv_debug: Type 3 value (384 bytes): \x09P1\x91\x1B\xB7\xFC\x07JI\x01f\x19\xAEv\\\x82\xFAS\x8[\x96\x1D]\x80\xE5\xC1Q'\xD0\xB7l\x13\xA9\x16\xD2/\x1F\x05\xEE\x9135\xAC\xCF\xA2=\xEB[&\xAFq~-~\xE6\xCC\x11\xA1\x86\xD2\xBA\x09\xA3I\x14:t\xD1O\x09+\xB5f\x99S\xD3<\x8C\xDE=\x0A\xE8\x17\xBF\xFDYxs\x9FP\xBDO\x9F\x18O\x8C\x08\xB7(<\xD2\x92\x1B\xF5>\xCB/\xD0\x12\xC3\xB0\x15\xEETI\x08\xA3\xEF\x8D\xF1&\x06\xC4l\x01\xCD`\x19Pm\xD9\xE3\xC13\xF9\xAE!!\x0Cjm\xF1\xF4%\xA6g\xFA\xC8py\x10\xD2nw\xDA^\xCB\x1EY\xCC\xFF\xCAD\x1C\xE0\x1B\xD8\xC8\x9Eq\xF4*\x97\x85\xF9\x11\x01s\xE1\x0C\x10x\x05~\xB8,nR\x9C\x8C.$\xE3\xF2p\x92q#5\xBF\xCB\x80\x18WL\x92\xA6\x87BLU\xD8*\x99\x00^\xB3\xA9\x88\xE6f\xD8\x115\xA1lJ\x15\xDF\x8B \xE2}\xEF=\x8B\x93xG6\xFBK\x119\xB9\x13\xD2F\xB6\xB0\xEE\xD7\x07\x13\x13\x9B\xDC*\xF3R\xB3\xC3\xB2\xDE\xCA\xB1\x96\xEB\xE5Yf\x06-\x14,\xC3,\xCB\x09\xC1Y\xB2\xA8[\x8EMR\xD6\x1E\x87V<<\x13\x90\x18(#\xC9\x86K\xCD\xD9\xF0\xFD\xB9\xEC\xD6\xE1\xD8K\xF6Fd\xB2\x1C7C^\xDD\xC7\xAB\xDB\xDCtkY\xC8y\xC8l~\xFD\xE9\x91\xBCM\xFC\xF2\xA6m\xF1\xE8'\xC7\xD0Z\xE6\x97D\xF8t:pV\xFF\xF6\xF1p]\x07\xCA\xB1\x0F\xCAI\xBE7}\xAE\xCA.%\x9Bnd\xE2\x0C\x95\xC3
>>> tlv_debug: Type 2 value (16 bytes): \xAA\x0B{]\xE4\xD2\xB2\x16!\xFDAmR2=\x13
>>> tlv_debug: Type 6 value (1 bytes): \x02
>>> client_send: [Client 4] Sending payload: HTTP/1.1 200 OK\x0D\x0AContent-Type: application/pairing+tlv8\x0D\x0AContent-Length: 409\x0D\x0AConnection: keep-alive\x0D\x0A\x0D\x0A\x03\xFF\x09P1\x91\x1B\xB7\xFC\x07JI\x01f\x19\xAEv\\\x82\xFAS\x8[\x96\x1D]\x80\xE5\xC1Q'\xD0\xB7l\x13\xA9\x16\xD2/\x1F\x05\xEE\x9135\xAC\xCF\xA2=\xEB[&\xAFq~-~\xE6\xCC\x11\xA1\x86\xD2\xBA\x09\xA3I\x14:t\xD1O\x09+\xB5f\x99S\xD3<\x8C\xDE=\x0A\xE8\x17\xBF\xFDYxs\x9FP\xBDO\x9F\x18O\x8C\x08\xB7(<\xD2\x92\x1B\xF5>\xCB/\xD0\x12\xC3\xB0\x15\xEETI\x08\xA3\xEF\x8D\xF1&\x06\xC4l\x01\xCD`\x19Pm\xD9\xE3\xC13\xF9\xAE!!\x0Cjm\xF1\xF4%\xA6g\xFA\xC8py\x10\xD2nw\xDA^\xCB\x1EY\xCC\xFF\xCAD\x1C\xE0\x1B\xD8\xC8\x9Eq\xF4*\x97\x85\xF9\x11\x01s\xE1\x0C\x10x\x05~\xB8,nR\x9C\x8C.$\xE3\xF2p\x92q#5\xBF\xCB\x80\x18WL\x92\xA6\x87BLU\xD8*\x99\x00^\xB3\xA9\x88\xE6f\xD8\x115\xA1lJ\x15\xDF\x8B \xE2}\xEF=\x8B\x93xG6\xFBK\x119\xB9\x13\xD2F\xB6\xB0\xEE\x03\x81\xD7\x07\x13\x13\x9B\xDC*\xF3R\xB3\xC3\xB2\xDE\xCA\xB1\x96\xEB\xE5Yf\x06-\x14,\xC3,\xCB\x09\xC1Y\xB2\xA8[\x8EMR\xD6\x1E\x87V<<\x13\x90\x18(#\xC9\x86K\xCD\xD9\xF0\xFD\xB9\xEC\xD6\xE1\xD8K\xF6Fd\xB2\x1C7C^\xDD\xC7\xAB\xDB\xDCtkY\xC8y\xC8l~\xFD\xE9\x91\xBCM\xFC\xF2\xA6m\xF1\xE8'\xC7\xD0Z\xE6\x97D\xF8t:pV\xFF\xF6\xF1p]\x07\xCA\xB1\x0F\xCAI\xBE7}\xAE\xCA.%\x9Bnd\xE2\x0C\x95\xC3\x02\x10\xAA\x0B{]\xE4\xD2\xB2\x16!\xFDAmR2=\x13\x06\x01\x02
>>> homekit_client_process: [Client 4] Finished processing
>>> HomeKit: [Client 4] Closing client connection
CLIENT JUST DISCONNECTED
peros550 commented 3 years ago

After more attempts, it was only possible to pair again by taking the library back to previous commit (9th of June 2020) Let me know if you would like me to test a different branch.

maximkulkin commented 3 years ago

I've checked your firmware (multiple_sensors_ac) and it pairs fine for me. Right from the start it has 27K to spare which is sufficient for pairing process.

Here is my log for comparison:

Opening /dev/tty.usbserial-14310 at 115200bps...
[b?g???o'???o'???cl
                   ?'o??|???n??clpp_task_hdl : 3fff2ae0, prio:14, stack:512
pm_task_hdl : 3fff2378, prio:1, stack:176
frc2_timer_task_hdl:0x3fff6eb0, prio:12, stack:200

ESP-Open-SDK ver: 0.0.1 compiled @ Aug  6 2020 00:57:45
phy ver: 273, pp ver: 8.3

SDK version:0.9.9
>>> wifi_config: Initializing WiFi config
>>> wifi_config: Connecting to TheNetwork
mode : sta(84:0d:8e:ab:9d:41)
add if0
scandone
add 0
aid 11
cnt

connected with TheNetwork, channel 8
dhcp client start...
ip:10.0.1.40,mask:255.255.255.0,gw:10.0.1.1
>>> wifi_config: Connected to WiFi network
CONNECTED TO >>> WIFI <<<
HOSTNAME set>>>>>:HomekitSensor-AB9D41
>>> HomeKit: Starting server
>>> HomeKit: Using existing accessory ID: AC:C7:7A:12:9F:25
>>> HomeKit: Configuring mDNS
mDNS announcement: Name=HomekitSensor-AB9D41
md=esp8266pv=1.0id=AC:C7:7A:12:9F:25c#=1s#=1ff=0sf=1ci=9 Port=5556 TTL=4500
SERVER JUST INITIALIZED
>>> homekit_run_server: Staring HTTP server
ip:10.0.1.40,mask:255.255.255.0,gw:10.0.1.1
>>> HomeKit: Got new client connection: 4 from 10.0.1.11
CLIENT JUST CONNECTED
>>> homekit_client_process: [Client 4] Got 138 incomming data
>>> homekit_server_on_pair_setup: Pair Setup
>>> homekit_server_on_pair_setup: Free heap: 27692
>>> tlv_debug: Got following TLV values:
>>> tlv_debug: Type 0 value (1 bytes): \x00
>>> tlv_debug: Type 6 value (1 bytes): \x01
>>> HomeKit: [Client 4] Pair Setup Step 1/3
>>> homekit_server_on_pair_setup: Free heap: 27604
>>> crypto_srp_new: Initializing SRP
>>> homekit_server_on_pair_setup: [Client 4] Initializing crypto
>>> homekit_server_on_pair_setup: Free heap: 26940
>>> homekit_server_on_pair_setup: [Client 4] Using user-specified password: 222-22-222
>>> crypto_srp_init: Generating salt
>>> crypto_srp_init: Setting SRP username
>>> crypto_srp_init: Setting SRP params
>>> crypto_srp_init: Setting SRP password
>>> crypto_srp_init: Getting SRP verifier
>>> crypto_srp_init: Setting SRP verifier
>>> crypto_srp_get_public_key: Calculating public key
>>> send_tlv_response: [Client 4] Sending TLV response
>>> tlv_debug: Got following TLV values:
>>> tlv_debug: Type 3 value (384 bytes): \xFD\xD2\x89\xDD>\xB0\xED\x10\xD7;:-2\xFF\x9E"\xC8%k\x0FtEc\x1C\xACeK\xB8\xCC\xEB\xB6\xAANk\x08K#\x8C\xC0\xF3\xBE4\x18\x14\x89\xA1_){X\xF7^w\xA1/\xA4T\xA60a\x85D\x06i$\x14Ge&\xA5\xB7X;\xF3\xBD\x9E\xB1a\xE49mh\x91a\x81Q,#\xC4\x19\x0D0\xE7\x0D\x14}\xEF-\xF8\xC2\xD4\xCFy\x89\xB1\xB7i\xE8\x16`\xABq\xEB\xF2\xF3\xB4}\xB85\xAD\x08\xFF\x0C>!]\x9C\x01Iu\xD3A8\xFB\x8E\xB8\x16B\x0C\xB0!\xF7\x95\xBF/4\xAA\x89\xC8\xCF\x12\x12%G\xBC\xFB\xA9-\x1C\xE9\x1C^\xD5\xCA\x0E\xE7\xC7\xC6o\xD4\xD5\x89\xDA\x88\xC7p\x0EC\xCA\xE3\x18\x93-z\xA3\x01\x82\xCD\xA9\xF8\x0F\x86.1\xEA,\x05q\x0D\x0D-T\x91E\\\xD2r\xBD\x93\xE1!.>\x1C\xD1\xAFt^\xB1j\x99\xCF\xDB\x01\\\xF4\xD6h\x0E]\xA5\x8CR[\xFC5\xB1\xF9\xCA_\x14MmG\xA9W\xFD\x9F\xD2\x1A\x10c@\xD4w\x0Fr\xC3\x19\xF5\x15\xFB^\xDB\x15\xAE\xD3\xBE\xD8\xDB\xFB\xA8!=6\xB1\xE5\x92(\xAD\xA1\xFA\xB6;=\x03f\xD1!\x07S\x8A\xE1>2-\x0F-\xCC0C\x8B\xA6\x18J,$ \xB6\x0At\x13\x85\x15\xA6\xA48\x0A_IT\xD5\x09T\xA2`\xAD(\xAE"}\xFE\xCD\xD1\x9Ed\x08\xE4\x10\x00\xA4\xDF\x1D'\x08\xC2\x0F%>\x08\x9F\xFB\x96GZT\x89\x818&\xC6g5B\x86\x80\x0B\xB9\x98<\xD6\x9D\x9E\xC6(\x13oZ\xA5\xEC\xDC\x0E\xBEq
>>> tlv_debug: Type 2 value (16 bytes): $\xC5\xDB\xAAd\x80N\x9ES\xDDp\xFF\x8E\xE3#_
>>> tlv_debug: Type 6 value (1 bytes): \x02
>>> client_send: [Client 4] Sending payload: HTTP/1.1 200 OK\x0D\x0AContent-Type: application/pairing+tlv8\x0D\x0AContent-Length: 409\x0D\x0AConnection: keep-alive\x0D\x0A\x0D\x0A\x03\xFF\xFD\xD2\x89\xDD>\xB0\xED\x10\xD7;:-2\xFF\x9E"\xC8%k\x0FtEc\x1C\xACeK\xB8\xCC\xEB\xB6\xAANk\x08K#\x8C\xC0\xF3\xBE4\x18\x14\x89\xA1_){X\xF7^w\xA1/\xA4T\xA60a\x85D\x06i$\x14Ge&\xA5\xB7X;\xF3\xBD\x9E\xB1a\xE49mh\x91a\x81Q,#\xC4\x19\x0D0\xE7\x0D\x14}\xEF-\xF8\xC2\xD4\xCFy\x89\xB1\xB7i\xE8\x16`\xABq\xEB\xF2\xF3\xB4}\xB85\xAD\x08\xFF\x0C>!]\x9C\x01Iu\xD3A8\xFB\x8E\xB8\x16B\x0C\xB0!\xF7\x95\xBF/4\xAA\x89\xC8\xCF\x12\x12%G\xBC\xFB\xA9-\x1C\xE9\x1C^\xD5\xCA\x0E\xE7\xC7\xC6o\xD4\xD5\x89\xDA\x88\xC7p\x0EC\xCA\xE3\x18\x93-z\xA3\x01\x82\xCD\xA9\xF8\x0F\x86.1\xEA,\x05q\x0D\x0D-T\x91E\\\xD2r\xBD\x93\xE1!.>\x1C\xD1\xAFt^\xB1j\x99\xCF\xDB\x01\\\xF4\xD6h\x0E]\xA5\x8CR[\xFC5\xB1\xF9\xCA_\x14MmG\xA9W\xFD\x9F\xD2\x1A\x10c@\xD4w\x03\x81\x0Fr\xC3\x19\xF5\x15\xFB^\xDB\x15\xAE\xD3\xBE\xD8\xDB\xFB\xA8!=6\xB1\xE5\x92(\xAD\xA1\xFA\xB6;=\x03f\xD1!\x07S\x8A\xE1>2-\x0F-\xCC0C\x8B\xA6\x18J,$ \xB6\x0At\x13\x85\x15\xA6\xA48\x0A_IT\xD5\x09T\xA2`\xAD(\xAE"}\xFE\xCD\xD1\x9Ed\x08\xE4\x10\x00\xA4\xDF\x1D'\x08\xC2\x0F%>\x08\x9F\xFB\x96GZT\x89\x818&\xC6g5B\x86\x80\x0B\xB9\x98<\xD6\x9D\x9E\xC6(\x13oZ\xA5\xEC\xDC\x0E\xBEq\x02\x10$\xC5\xDB\xAAd\x80N\x9ES\xDDp\xFF\x8E\xE3#_\x06\x01\x02
>>> homekit_client_process: [Client 4] Finished processing
>>> homekit_client_process: [Client 4] Got 591 incomming data
>>> homekit_server_on_pair_setup: Pair Setup
>>> homekit_server_on_pair_setup: Free heap: 24748
>>> tlv_debug: Got following TLV values:
>>> tlv_debug: Type 6 value (1 bytes): \x03
>>> tlv_debug: Type 3 value (384 bytes): \xEF\x9AA\xF7\x10\xB9\xF8\xF0\xE2\x1D\x9B\xD3\xB5\xA9\xBA\xD5\xA7\xFC\xB1\xF9\x86d\xA9H\xAD\xE3D\x97{\x0114u\xFE\xC5\xDB:\xBD6\xB4\x87K\x82\xDD\xCB\xD9\x86F\xC0\xE5\xF9\xC3~j\xCA\xEC\x0E\x1Ep\xB9\xCB\xF4%\xAA\xA4\x96\x83\xF7\xB5\x0Bc\xC7\x9D}8\x02 \x99\xF7M\x08\xCB.\xCC?\x907P\x8B\x15M\x90vN\xF1\xA2\xF8j\xA9\x1D\x09Y\xE9\x86\x13\x9C\x9A\xAF\x13\xE8+\xED\xDC8\xD7\x0F\xEC\xEDB\x87\xBD\xA7\x1F\x877\x9F.\x9A\xEB\x19\xEC\x07&\xB2\x84\xDE1\xB7\xCA\xFE0K\xE4\xFA\x05\x8E\xA4\x13l4v\x1Df,&\xAD!\xDB+\xD1V gO\x1C\x9B\xA5LN\x88\x08=\xD8q\xF84\xF4W\x00\x82\x94M"\x96\x12$2]\x13\xF0a\xA2\xEC\xBF\x1C\xAC|\xE8\xAC\xFF\xD7q\x8Ay\xD2\xA6 \x99\xDBJ7\x19\xB6\xC8f\xD2\xE8\x1D\x95\xB9+\x85\x93q7\xF8o\xF4\x9B\xF5z[\xBD\xBF\x95:]\x08T\xCA\x9B\xC7\xED\xD0OIg\x87\x87\x8A'E\xAA4\x8D\xC3K\xFB|\xE5\xBD\xD6\xE97\xE0\xA4\x99\x1B; \xFE9\xDBdD\xD6W\xFB\x11y\xDF\x15mkA\x93\xC8h\x1E\x02\xCA\x9C\xC8\xFEg:\xA9w\x0A\x1BF\x12\xB0\xD8\xDEd~.\x8C\x13\xBF\xADzf\xFC\x0C\xB4\xE7\x8FX \xBF\xBAL\xCC\xF1\x04\x0D\x8C]P\x09H\x93x\xE6\xFD\xEC+U(oa\x86\xB4\xCB#K\x0D]\x82}\xC1\xB9j\xE0P\xBF\xF8\x92\xE9\xE53\xCD\x97R\x9F\x0C\xFDj\x86\x97|\xA4\xFC\xC81\xF3\x86\xF2\xD3\xAE
>>> tlv_debug: Type 4 value (64 bytes): f\x86\xBD\xA9\xD7\xA7\xBCZc\xC5\xE6a2\x83\x0F5\xBE|\xA4\x97\x87\xCCT\x8312\xFB\xAAO\xF8\xD2\xFB\x1C\xCC\xA43\xBB!\xD9\xEDMs\xA3\x18\x00\xA6\xA16uw\xA1\xD5e\x10\x81P+W\xFC\x80C\x88\xF7
>>> HomeKit: [Client 4] Pair Setup Step 2/3
>>> homekit_server_on_pair_setup: Free heap: 24180
>>> homekit_server_on_pair_setup: [Client 4] Computing SRP shared secret
>>> homekit_server_on_pair_setup: Free heap: 24180
>>> mdns_reply could not alloc 1460
>>> mdns_reply could not alloc 1460
>>> mdns_reply could not alloc 1460
>>> homekit_server_on_pair_setup: [Client 4] Verifying peer's proof
>>> homekit_server_on_pair_setup: Free heap: 24500
>>> homekit_server_on_pair_setup: [Client 4] Generating own proof
>>> send_tlv_response: [Client 4] Sending TLV response
>>> tlv_debug: Got following TLV values:
>>> tlv_debug: Type 6 value (1 bytes): \x04
>>> tlv_debug: Type 4 value (64 bytes): wu\x01\xFC\xB7}Bj\xE8\x92@\xF3\x15E\x8D\x88'\x8FXfi\x11\xF2g\xDA\xCB"\x83Lk\xE0\xFE\x94\xD5.\xFC\x93!\x02_\x86"\xF1\x9D\xB0&:\xB78\xA8a\x9CY\x8A\xB9\xD3\xA3V\xD6\xB1\x96\xA8\xC0\x8E
>>> client_send: [Client 4] Sending payload: HTTP/1.1 200 OK\x0D\x0AContent-Type: application/pairing+tlv8\x0D\x0AContent-Length: 69\x0D\x0AConnection: keep-alive\x0D\x0A\x0D\x0A\x06\x01\x04\x04@wu\x01\xFC\xB7}Bj\xE8\x92@\xF3\x15E\x8D\x88'\x8FXfi\x11\xF2g\xDA\xCB"\x83Lk\xE0\xFE\x94\xD5.\xFC\x93!\x02_\x86"\xF1\x9D\xB0&:\xB78\xA8a\x9CY\x8A\xB9\xD3\xA3V\xD6\xB1\x96\xA8\xC0\x8E
>>> homekit_client_process: [Client 4] Finished processing
>>> homekit_client_process: [Client 4] Got 293 incomming data
>>> homekit_server_on_pair_setup: Pair Setup
>>> homekit_server_on_pair_setup: Free heap: 25368
>>> tlv_debug: Got following TLV values:
>>> tlv_debug: Type 5 value (154 bytes): \x19vF\xD5m)~\xEBH-\x06x\xC21\xB5\x18\xC57: \xDF\x8C\x00\xDD\xBB\xCA\x0F_f\xCC\xA6\x0D\xB9?\x07\x94$\xEAu\xED\xE1\xED\x1F\x91\x85Z \xE4\x116=\xE7\xFB\xB2`\x8Ajdo@y\xFD\x0B\xA6w\x93\x98\xBE\x11z\xCB?\x8Ep\xCB\x1E\x18\xB4nF\x82\xFDY4"\xCF\xAAK\xE2\xCFZT\xE5E\xACk\x1C\xB0b\xAD!\xD3\x9A\xB7\xB4-\xCE0\xB8..\xBC\xB3\x077a\xE9\xE6!\x95P!\xAA\xE4\x87G\x15_\xD5\xE7\x99\x16\xBF\xE3>8\xD5-O\xB0@\xD4\xF5\x93!,VKX\xBF\x82\xAC\xDA
>>> tlv_debug: Type 6 value (1 bytes): \x05
>>> HomeKit: [Client 4] Pair Setup Step 3/3
>>> homekit_server_on_pair_setup: Free heap: 25128
>>> homekit_server_on_pair_setup: [Client 4] Calculating shared secret
>>> homekit_server_on_pair_setup: [Client 4] Decrypting payload
>>> homekit_server_on_pair_setup: [Client 4] Importing device public key
>>> homekit_server_on_pair_setup: [Client 4] Calculating DeviceX
>>> homekit_server_on_pair_setup: [Client 4] Verifying device signature
>>> HomeKit: Added pairing with 3686A133-5FBA-46F9-9008-494FFE5DB43A
CLIENT JUST PAIRED
>>> homekit_server_on_pair_setup: [Client 4] Exporting accessory public key
>>> homekit_server_on_pair_setup: [Client 4] Calculating AccessoryX
>>> homekit_server_on_pair_setup: [Client 4] Generating accessory signature
>>> homekit_server_on_pair_setup: Free heap: 24996
>>> tlv_debug: Got following TLV values:
>>> tlv_debug: Type 1 value (17 bytes): AC:C7:7A:12:9F:25
>>> tlv_debug: Type 3 value (32 bytes): M\xFBK\x9D\xAA\xE3\x16\xDF\x01\xF0u\xF9\xFDN\xD7]\x87\xDD\x96\xAD\x9D\x91\xB6\x0As\xC7yl#\xE4\xBF
>>> tlv_debug: Type 10 value (64 bytes): \xB8H\xAC\x1A,Jc\x8Cd\x91@\xBC\x83\xDFXH\x95\xC2\xF6|\x17\xE6\x07.\xEE\xF4%\xAE\xFB?C\xFCD\x98\x06\xAE\xBA\xDD\x05\xF6\x14k\xB2x\xC0\x93\xAB\xF8Q5\xE3\x9C\xB0\x131X\x12\xC3\xC0\xA5\xD97\x0D
>>> homekit_server_on_pair_setup: [Client 4] Encrypting response
>>> send_tlv_response: [Client 4] Sending TLV response
>>> tlv_debug: Got following TLV values:
>>> tlv_debug: Type 6 value (1 bytes): \x06
>>> tlv_debug: Type 5 value (135 bytes): \xEE~H\xD1\xDA\xF0\x05\xB2)\x1D=\x85\xD0\xD7*\x1F?\xE1\xF05@\xD4_\xB2r\xB1\xEF:\xAD\xF0h].j\x9E;v>\x1DW\xD3l\x1B\xD7\xDA\x9E\xBA3\xDF\xB1\\\xC3\xF2\x84\x97D\x90\xAE\xC4\xDC\xDC\xCB \xB2f\xE5\xF4\xFA\x05B\x9C\xB9\x15_a.\xBC\xDE\xB8\x91\xD2ci#\xF1\xE1S\x12*6\x0C\x815\x0D\xC2Y1\x0D\x06\xA2\xA3`;\xFA=\xBFc\\\x1D\x86\xCE\xA30\x00d\xB8\xCF\xC4\xAC\xC4ez\x93\xBC\xFFZ<Hh/\xE9\xD0N\xA1B
>>> client_send: [Client 4] Sending payload: HTTP/1.1 200 OK\x0D\x0AContent-Type: application/pairing+tlv8\x0D\x0AContent-Length: 140\x0D\x0AConnection: keep-alive\x0D\x0A\x0D\x0A\x06\x01\x06\x05\x87\xEE~H\xD1\xDA\xF0\x05\xB2)\x1D=\x85\xD0\xD7*\x1F?\xE1\xF05@\xD4_\xB2r\xB1\xEF:\xAD\xF0h].j\x9E;v>\x1DW\xD3l\x1B\xD7\xDA\x9E\xBA3\xDF\xB1\\\xC3\xF2\x84\x97D\x90\xAE\xC4\xDC\xDC\xCB \xB2f\xE5\xF4\xFA\x05B\x9C\xB9\x15_a.\xBC\xDE\xB8\x91\xD2ci#\xF1\xE1S\x12*6\x0C\x815\x0D\xC2Y1\x0D\x06\xA2\xA3`;\xFA=\xBFc\\\x1D\x86\xCE\xA30\x00d\xB8\xCF\xC4\xAC\xC4ez\x93\xBC\xFFZ<Hh/\xE9\xD0N\xA1B
>>> HomeKit: Configuring mDNS
mDNS announcement: Name=HomekitSensor-AB9D41
md=esp8266pv=1.0id=AC:C7:7A:12:9F:25c#=1s#=1ff=0sf=0ci=9 Port=5556 TTL=4500
>>> HomeKit: [Client 4] Successfully paired
>>> homekit_client_process: [Client 4] Finished processing
>>> HomeKit: [Client 4] Closing client connection
>>> HomeKit: Got new client connection: 4 from 10.0.1.11
>>> homekit_client_process: [Client 4] Got 171 incomming data
>>> homekit_server_on_pair_verify: HomeKit Pair Verify
>>> homekit_server_on_pair_verify: Free heap: 27652
>>> tlv_debug: Got following TLV values:
>>> tlv_debug: Type 6 value (1 bytes): \x01
>>> tlv_debug: Type 3 value (32 bytes): {\x0F\xEDw4\x01\xB6\x0CBL\x0288\xB8U\xD5\xB7\xEF\xD6\xDEH\xF1\xEDQ'(\xC8\x85\x97>\xD0H
>>> HomeKit: [Client 4] Pair Verify Step 1/2
>>> homekit_server_on_pair_verify: [Client 4] Importing device Curve25519 public key
>>> homekit_server_on_pair_verify: [Client 4] Generating accessory Curve25519 key
>>> homekit_server_on_pair_verify: [Client 4] Exporting accessory Curve25519 public key
>>> homekit_server_on_pair_verify: [Client 4] Generating Curve25519 shared secret
>>> homekit_server_on_pair_verify: [Client 4] Generating signature
>>> homekit_server_on_pair_verify: [Client 4] Generating proof
>>> homekit_server_on_pair_verify: [Client 4] Encrypting response
>>> send_tlv_response: [Client 4] Sending TLV response
>>> tlv_debug: Got following TLV values:
>>> tlv_debug: Type 6 value (1 bytes): \x02
>>> tlv_debug: Type 3 value (32 bytes): G\xDF\xA1t\xB1K\xC8\xA2\x0C\xDE\x04\xBC(3\xC9\xF1pNB\xE8,e\x87_SH\x91U@\xF3\xC1k
>>> tlv_debug: Type 5 value (101 bytes): \xEC\xE9\x92Y\xC0\x0A\xFF\xD71@\x11M\xEC\xD8\xCE\xD5-\x17.\x05,\xC1\xA0\x89\x98F\x1D\xF5\xE6\x15\x96J\xB6M\x10~x$\x0Cx\xDED\x92tO\xF3\x85\xF99\xB2\xAF_G\xF8\xB2\xF9\xD8\x1BR\xDE\xECf\x1F\xC8z\xB6\x84g'\x03\x8C\xB71\xACM\xA4\xD4\xA0\x97-\xCE/\xD1]!W\xF1\xBC\x03\xBBd\xFE8X\x95\xBC\xE5\x00U\xD4\xF1
>>> client_send: [Client 4] Sending payload: HTTP/1.1 200 OK\x0D\x0AContent-Type: application/pairing+tlv8\x0D\x0AContent-Length: 140\x0D\x0AConnection: keep-alive\x0D\x0A\x0D\x0A\x06\x01\x02\x03 G\xDF\xA1t\xB1K\xC8\xA2\x0C\xDE\x04\xBC(3\xC9\xF1pNB\xE8,e\x87_SH\x91U@\xF3\xC1k\x05e\xEC\xE9\x92Y\xC0\x0A\xFF\xD71@\x11M\xEC\xD8\xCE\xD5-\x17.\x05,\xC1\xA0\x89\x98F\x1D\xF5\xE6\x15\x96J\xB6M\x10~x$\x0Cx\xDED\x92tO\xF3\x85\xF99\xB2\xAF_G\xF8\xB2\xF9\xD8\x1BR\xDE\xECf\x1F\xC8z\xB6\x84g'\x03\x8C\xB71\xACM\xA4\xD4\xA0\x97-\xCE/\xD1]!W\xF1\xBC\x03\xBBd\xFE8X\x95\xBC\xE5\x00U\xD4\xF1
>>> homekit_client_process: [Client 4] Finished processing
>>> homekit_client_process: [Client 4] Got 260 incomming data
>>> homekit_server_on_pair_verify: HomeKit Pair Verify
>>> homekit_server_on_pair_verify: Free heap: 27356
>>> tlv_debug: Got following TLV values:
>>> tlv_debug: Type 5 value (120 bytes):  ?\xB7\x85qc\x02\xEF\xDB\xE9\xA7\xC2\x060\x90vW\x87!\xA3\xE1R\x8A37\xABk'uu\x18\x92p&\x05\x99Ere\xB6R\xA4\xEB\x17\x1B\x96*6\x16\x10\xC8%\x94W\xE9\xEBc\xAA\x138f\xD8\xCF?\xBE\\\xE6\xB9}\xE7\xC4S\x92=F\x0F\xA1'\xBB\x88v\xC7\xE1\xDDQ\xEE.bn\x11\xDB\xC4\x8A\x9B\xDE\xE1C\xD7\x8AEr\x09\xAC\x84\x94]1`>\x8C\xAD\xE1\x04V\xF5\xEA\xDCw2
>>> tlv_debug: Type 6 value (1 bytes): \x03
>>> HomeKit: [Client 4] Pair Verify Step 2/2
>>> homekit_server_on_pair_verify: [Client 4] Decrypting payload
>>> homekit_server_on_pair_verify: [Client 4] Searching pairing with 3686A133-5FBA-46F9-9008-494FFE5DB43A
>>> HomeKit: [Client 4] Found pairing with 3686A133-5FBA-46F9-9008-494FFE5DB43A
>>> homekit_server_on_pair_verify: [Client 4] Verifying device signature
>>> send_tlv_response: [Client 4] Sending TLV response
>>> tlv_debug: Got following TLV values:
>>> tlv_debug: Type 6 value (1 bytes): \x04
>>> client_send: [Client 4] Sending payload: HTTP/1.1 200 OK\x0D\x0AContent-Type: application/pairing+tlv8\x0D\x0AContent-Length: 3\x0D\x0AConnection: keep-alive\x0D\x0A\x0D\x0A\x06\x01\x04
CLIENT JUST VERIFIED
Sterted UDP logging
Found pairing, starting timers
Initializing AC parameters
>>> HomeKit: [Client 4] Verification successful, secure session established
>>> homekit_client_process: [Client 4] Finished processing
>>> homekit_client_process: [Client 4] Got 91 incomming data
>>> homekit_client_process: [Client 4] Decrypting data
>>> homekit_client_process: [Client 4] Decrypted 73 bytes, available 0
Decrypted data (73 bytes): "GET /accessories HTTP/1.1\x0D\x0AHost: HomekitSensor-AB9D41._hap._tcp.local\x0D\x0A\x0D\x0A"
>>> HomeKit: [Client 4] Get Accessories
>>> homekit_server_on_get_accessories: Free heap: 24388
>>> client_send: [Client 4] Sending payload: HTTP/1.1 200 OK\x0D\x0AContent-Type: application/hap+json\x0D\x0ATransfer-Encoding: chunked\x0D\x0AConnection: keep-alive\x0D\x0A\x0D\x0A
>>> client_send: [Client 4] Sending payload: 3fb\x0D\x0A{"accessories":[{"aid":1,"services":[{"iid":1,"type":"3E","hidden":false,"primary":false,"characteristics":[{"aid":2,"iid":2,"type":"23","perms":["pr"],"description":"Name","format":"string","value":"HomekitSensor-AB9D41"},{"aid":2,"iid":3,"type":"20","perms":["pr"],"description":"Manufacturer","format":"string","value":"Unknown"},{"aid":2,"iid":4,"type":"30","perms":["pr"],"description":"Serial Number","format":"string","value":"840D8EAB9D41"},{"aid":2,"iid":5,"type":"21","perms":["pr"],"description":"Model","format":"string","value":"esp8266"},{"aid":2,"iid":6,"type":"52","perms":["pr"],"description":"Firmware Revision","format":"string","value":"1.0"},{"aid":1,"iid":7,"type":"14","perms":["pw"],"description":"Identify","format":"bool"}]},{"iid":8,"type":"BC","hidden":false,"primary":true,"characteristics":[{"aid":1,"iid":9,"type":"23","perms":["pr"],"description":"Name","format":"string","value":"AirConditioner"},{"aid":1,"iid":10,"type":"B0","perms":["pr","pw","ev"],"ev":false,"description":"Active",\x0D\x0A
>>> client_send: [Client 4] Sending payload: 3ff\x0D\x0A"format":"uint8","minValue":0,"maxValue":1,"valid-values":[0,1],"value":0},{"aid":1,"iid":11,"type":"11","perms":["pr","ev"],"ev":false,"description":"Current Temperature","format":"float","unit":"celsius","minValue":0,"maxValue":100,"minStep":0.100000001490116,"value":0},{"aid":1,"iid":12,"type":"B1","perms":["pr","ev"],"ev":false,"description":"Current Heater Cooler State","format":"uint8","minValue":0,"maxValue":3,"minStep":1,"valid-values":[0,1,2,3],"value":0},{"aid":1,"iid":13,"type":"B2","perms":["pr","pw","ev"],"ev":false,"description":"Target Heater Cooler State","format":"uint8","minValue":0,"maxValue":2,"minStep":1,"valid-values":[0,1,2],"value":0},{"aid":1,"iid":14,"type":"D","perms":["pr","pw","ev"],"ev":false,"description":"Cooling Threshold Temperature","format":"float","unit":"celsius","minValue":16,"maxValue":30,"minStep":1,"value":18},{"aid":1,"iid":15,"type":"12","perms":["pr","pw","ev"],"ev":false,"description":"Heating Threshold Temperature","format":"float","unit":"celsius","minValue":16\x0D\x0A
>>> client_send: [Client 4] Sending payload: 3fe\x0D\x0A,"maxValue":30,"minStep":1,"value":22}]}]},{"aid":2,"services":[{"iid":1,"type":"3E","hidden":false,"primary":false,"characteristics":[{"aid":2,"iid":2,"type":"23","perms":["pr"],"description":"Name","format":"string","value":"HomekitSensor-AB9D41"},{"aid":2,"iid":3,"type":"20","perms":["pr"],"description":"Manufacturer","format":"string","value":"Unknown"},{"aid":2,"iid":4,"type":"30","perms":["pr"],"description":"Serial Number","format":"string","value":"840D8EAB9D41"},{"aid":2,"iid":5,"type":"21","perms":["pr"],"description":"Model","format":"string","value":"esp8266"},{"aid":2,"iid":6,"type":"52","perms":["pr"],"description":"Firmware Revision","format":"string","value":"1.0"},{"aid":2,"iid":7,"type":"14","perms":["pw"],"description":"Identify","format":"bool"}]},{"iid":8,"type":"49","hidden":false,"primary":true,"characteristics":[{"aid":2,"iid":9,"type":"23","perms":["pr"],"description":"Name","format":"string","value":"Sonoff Switch"},{"aid":2,"iid":10,"type":"25","perms":["pr","pw","ev"],"ev":false,\x0D\x0A
>>> client_send: [Client 4] Sending payload: 3ff\x0D\x0A"description":"On","format":"bool","value":false}]},{"iid":11,"type":"8A","hidden":false,"primary":true,"characteristics":[{"aid":2,"iid":12,"type":"23","perms":["pr"],"description":"Name","format":"string","value":"Temperature Sensor"},{"aid":2,"iid":13,"type":"11","perms":["pr","ev"],"ev":false,"description":"Current Temperature","format":"float","unit":"celsius","minValue":0,"maxValue":100,"minStep":0.100000001490116,"value":0}]},{"iid":14,"type":"84","hidden":false,"primary":true,"characteristics":[{"aid":2,"iid":15,"type":"23","perms":["pr"],"description":"Name","format":"string","value":"Light Sensor"},{"aid":2,"iid":16,"type":"6B","perms":["pr","ev"],"ev":false,"description":"Current Ambient Light Level","format":"float","unit":"lux","minValue":0,"maxValue":100000,"value":0}]},{"iid":17,"type":"85","hidden":false,"primary":true,"characteristics":[{"aid":2,"iid":18,"type":"23","perms":["pr"],"description":"Name","format":"string","value":"Motion Sensor"},{"aid":2,"iid":19,"type":"22","perms":["pr","ev"\x0D\x0A
>>> client_send: [Client 4] Sending payload: 1c9\x0D\x0A],"ev":false,"description":"Motion Detected","format":"bool","value":false}]},{"iid":20,"type":"82","hidden":false,"primary":false,"characteristics":[{"aid":2,"iid":21,"type":"23","perms":["pr"],"description":"Name","format":"string","value":"Humidity Sensor"},{"aid":2,"iid":22,"type":"10","perms":["pr","ev"],"ev":false,"description":"Current Relative Humidity","format":"float","unit":"percentage","minValue":0,"maxValue":100,"minStep":1,"value":0}]}]}]}\x0D\x0A
>>> client_send: [Client 4] Sending payload: 0\x0D\x0A\x0D\x0A
>>> homekit_client_process: [Client 4] Finished processing
>>> homekit_client_process: [Client 4] Got 201 incomming data
>>> homekit_client_process: [Client 4] Decrypting data
>>> homekit_client_process: [Client 4] Decrypted 183 bytes, available 0
Decrypted data (183 bytes): "PUT /characteristics HTTP/1.1\x0D\x0AHost: HomekitSensor-AB9D41._hap._tcp.local\x0D\x0AContent-Length: 50\x0D\x0AContent-Type: application/hap+json\x0D\x0A\x0D\x0A{"characteristics":[{"aid":2,"iid":10,"ev":true}]}"
>>> HomeKit: [Client 4] Update Characteristics
>>> homekit_server_on_update_characteristics: Free heap: 23916
>>> homekit_server_on_update_characteristics: [Client 4] Processing element {
"aid":  2,
"iid":  10,
"ev":   true
}
>>> homekit_server_on_update_characteristics: [Client 4] There were no processing errors, sending No Content response
>>> client_send: [Client 4] Sending payload: HTTP/1.1 204 No Content\x0D\x0A\x0D\x0A
>>> homekit_client_process: [Client 4] Finished processing
>>> homekit_client_process: [Client 4] Got 347 incomming data
>>> homekit_client_process: [Client 4] Decrypting data
>>> homekit_client_process: [Client 4] Decrypted 329 bytes, available 0
Decrypted data (329 bytes): "PUT /characteristics HTTP/1.1\x0D\x0AHost: HomekitSensor-AB9D41._hap._tcp.local\x0D\x0AContent-Length: 195\x0D\x0AContent-Type: application/hap+json\x0D\x0A\x0D\x0A{"characteristics":[{"aid":1,"iid":10,"ev":true},{"aid":1,"iid":11,"ev":true},{"aid":1,"iid":12,"ev":true},{"aid":1,"iid":13,"ev":true},{"aid":1,"iid":14,"ev":true},{"aid":1,"iid":15,"ev":true}]}"
>>> HomeKit: [Client 4] Update Characteristics
>>> homekit_server_on_update_characteristics: Free heap: 23604
>>> homekit_server_on_update_characteristics: [Client 4] Processing element {
"aid":  1,
"iid":  10,
"ev":   true
}
>>> homekit_server_on_update_characteristics: [Client 4] Processing element {
"aid":  1,
"iid":  11,
"ev":   true
}
>>> homekit_server_on_update_characteristics: [Client 4] Processing element {
"aid":  1,
"iid":  12,
"ev":   true
}
>>> homekit_server_on_update_characteristics: [Client 4] Processing element {
"aid":  1,
"iid":  13,
"ev":   true
}
>>> homekit_server_on_update_characteristics: [Client 4] Processing element {
"aid":  1,
"iid":  14,
"ev":   true
}
>>> homekit_server_on_update_characteristics: [Client 4] Processing element {
"aid":  1,
"iid":  15,
"ev":   true
}
>>> homekit_server_on_update_characteristics: [Client 4] There were no processing errors, sending No Content response
>>> client_send: [Client 4] Sending payload: HTTP/1.1 204 No Content\x0D\x0A\x0D\x0A
>>> homekit_client_process: [Client 4] Finished processing
peros550 commented 3 years ago

Sorry for asking, did you manually update esp-homekit after cloning and initializing repo and submodules, right?

I finally made it pair after multiple attempts. I already feel idiot enough, as paring wasn't a real issue, but I would like to show you something happening after pairing.

All accessories are in no response status, but when I touch them they seem to work (except the heater and the switch).

With previous version of esp-homekit, all of them worked well.

Here is a little video of the "new" issue

Thanks for your help & support.

beckmx commented 3 years ago

@maximkulkin I have a similar issue, for some reason today, I tried to flash a brand new ESP8266(actually several I tried) and all of them seem to be failing to register, for some weird reason, if they connect to the "home" app they immediately change to disconnected, maaaaybe, the problem is that apple is no longer allowing new devices to log in, this is my log:

`mDNS announcement: Name=Temperature md=IR senderpv=1.0id=EE:08:AD:B6:BC:B2c#=1s#=1ff=0sf=0ci=5 Port=5556 TTL=4500

HomeKit: [Client 3] Successfully paired HomeKit: [Client 3] Closing client connection HomeKit: Got new client connection: 3 HomeKit: [Client 3] Pair Verify Step 1/2 HomeKit: [Client 3] Pair Verify Step 2/2 HomeKit: [Client 3] Found pairing with 78EBA429-C6E5-4E4D-B43E-606483BEEC27 HomeKit: [Client 3] Verification successful, secure session established HomeKit: [Client 3] Get Accessories HomeKit: [Client 3] Remove Pairing HomeKit: Removed pairing with 78EBA429-C6E5-4E4D-B43E-606483BEEC27 HomeKit: Last admin pairing was removed, enabling pair setup HomeKit: Configuring mDNS mDNS announcement: Name=Temperature md=IR senderpv=1.0id=EE:08:AD:B6:BC:B2c#=1s#=1ff=0sf=1ci=5 Port=5556 TTL=4500 HomeKit: [Client 3] Closing client connection `

Or is it because I haven't changed the pairing number?

maximkulkin commented 3 years ago

@beckmx Can you enable HOMEKIT_DEBUG and post debug logs here. I would like to see what happened on that Get Accessories request.

beckmx commented 3 years ago

@maximkulkin here u go

mode : sta(2c:f4:32:77:44:97)
Function called without core lock
Connected to WiFi
cws774497ADC start  
ADC read [3.000000] Temp [-71.066597] Rth [3400000.000000]
ADC correct [3.000000] Temp [-79.066597] Rth [3400000.000000]
ADC start  
ADC read [3.000000] Temp [-71.066597] Rth [3400000.000000]
ADC correct [3.000000] Temp [-79.066597] Rth [3400000.000000]
>>> HomeKit: Starting server
>>> HomeKit: Formatting flash at 0x100000
>>> HomeKit: Generated new accessory ID: 1A:5D:4F:65:D8:03
>>> HomeKit: Generated new accessory key
>>> HomeKit: Configuring mDNS
mDNS announcement: Name=Temperature with IR-1A5D md=IR senderpv=1.0id=1A:5D:4F:65:D8:03c#=1s#=1ff=0sf=1ci=5 Port=5556 TTL=4500
>>> homekit_run_server: Staring HTTP server
>>> wifi_config: Stopping HTTP server
>>> wifi_config: Stopping DNS server

>>> HomeKit: Got new client connection: 3
>>> homekit_client_process: [Client 3] Got 148 incomming data
>>> homekit_server_on_pair_setup: Pair Setup
>>> homekit_server_on_pair_setup: Free heap: 31020
>>> tlv_debug: Got following TLV values:
>>> tlv_debug: Type 0 value (1 bytes): \x00
>>> tlv_debug: Type 6 value (1 bytes): \x01
>>> HomeKit: [Client 3] Pair Setup Step 1/3
>>> homekit_server_on_pair_setup: Free heap: 30924
>>> crypto_srp_new: Initializing SRP
>>> homekit_server_on_pair_setup: [Client 3] Initializing crypto
>>> homekit_server_on_pair_setup: Free heap: 30260
>>> homekit_server_on_pair_setup: [Client 3] Using user-specified password: 211-11-112
>>> crypto_srp_init: Generating salt
>>> crypto_srp_init: Setting SRP username
>>> crypto_srp_init: Setting SRP params
>>> crypto_srp_init: Setting SRP password
>>> crypto_srp_init: Getting SRP verifier
>>> crypto_srp_init: Setting SRP verifier
>>> crypto_srp_get_public_key: Calculating public key
>>> send_tlv_response: [Client 3] Sending TLV response
>>> tlv_debug: Got following TLV values:
>>> tlv_debug: Type 3 value (384 bytes): \xD2\xF5\xA4\xAE\x1D\xF7o\x81\xC7\x09\xFA.a\x10\xAD\xBE\x9F\x18r\x94R\xE3\xA9\x06(\x88\xD4.\xCC\x9D\x87l\xBC\x8CM\x1E\xB8*\x99~\x15)\x8A\xC7sY.\xD5@/\xCB\xA2\x09\x90\x0E[\xC5d\xACS\xD7\xBC\xCA\xE8\xC4VfD\x9B\xA0!\x91uS\xAC~\x06\xAD\xD3H\x87D\x9C>]L\x00Q\xF9,\x9C\xF5\xB1\x89\xEF\x936\xE4\xD1\xE6e\xB8\xDD={\x86\xE7\xD7\x1A\x19>?\xFA\xB4\\\x8C\x0D\xC4\x93\x94F{\xD8\xCE\xD3ql\xE2foe\xD8\xC35JJ\xB2E\x90[|\xFA\xA9\x9E\xCF|\xF2\x84\xB13G\x1B\xA5\xB5\x11\xE7\xDB\xA1\xDEg\xAA\xA5\x97\x80t\xC3k\xB7oV\x96\xE9;\x81\xDC\x0FB2\x9F\x8F\x88q\xD4\x0D\xD4^\xA0\x177nl|\xB7\x8DO\x9D\xF8\xDE\x05\x11\x16k5\x19\x84”\xEB\xCC\x80\xF9;pR\xB1\xDF\xF9V\xA1Bn!\xB5%D\x14\x08\xA3\x8A\x18\x07Do\xD2u\xF1\xDCw\x14\xFA~W\xDA8\x034\xBA\x0B\xD6/\x91q\x1E\x90\xA2\xE7\xC0\xA7\x97\xC3\xE1=\xAD\x1D\xB5Yo\xCDz\xBC\xB9\x8F\xB3\xD1\xCA\x83\xFB\x18.\x04\xDD\xC9%\xA3*\xDAB*Dow\xF3[\x19\x13\xC7\xE3\xAB>\xA4\xAE”`{\xD0=\x04*W\x00[\xA7\xCD\xE4>F\x9D\xBF\xEA\x03\xD3\x1C\x9E\x99\x17\x0E\xC9\xC2[\xAD\x86U\x9B\x13\x0C\xC6\xBF\x05B\x8F\x84\xB7\x8Fp\xFE\x11\x01K\x19QX\x87=\x13\xA0\x81\xA73\xB4VA6\xFF\x88\xB4\xA5\x1A\xF9,\x9AfM\x84D\x94f\xCD,\xEC\xF5*X@\xFB\xE4
>>> tlv_debug: Type 2 value (16 bytes): \xFD\x83(\x19\xA9\x98’\xE3F\x9BJ\x8B`i+7
>>> tlv_debug: Type 6 value (1 bytes): \x02
>>> client_send: [Client 3] Sending payload: HTTP/1.1 200 OK\x0D\x0AContent-Type: application/pairing+tlv8\x0D\x0AContent-Length: 409\x0D\x0AConnection: keep-alive\x0D\x0A\x0D\x0A\x03\xFF\xD2\xF5\xA4\xAE\x1D\xF7o\x81\xC7\x09\xFA.a\x10\xAD\xBE\x9F\x18r\x94R\xE3\xA9\x06(\x88\xD4.\xCC\x9D\x87l\xBC\x8CM\x1E\xB8*\x99~\x15)\x8A\xC7sY.\xD5@/\xCB\xA2\x09\x90\x0E[\xC5d\xACS\xD7\xBC\xCA\xE8\xC4VfD\x9B\xA0!\x91uS\xAC~\x06\xAD\xD3H\x87D\x9C>]L\x00Q\xF9,\x9C\xF5\xB1\x89\xEF\x936\xE4\xD1\xE6e\xB8\xDD={\x86\xE7\xD7\x1A\x19>?\xFA\xB4\\\x8C\x0D\xC4\x93\x94F{\xD8\xCE\xD3ql\xE2foe\xD8\xC35JJ\xB2E\x90[|\xFA\xA9\x9E\xCF|\xF2\x84\xB13G\x1B\xA5\xB5\x11\xE7\xDB\xA1\xDEg\xAA\xA5\x97\x80t\xC3k\xB7oV\x96\xE9;\x81\xDC\x0FB2\x9F\x8F\x88q\xD4\x0D\xD4^\xA0\x177nl|\xB7\x8DO\x9D\xF8\xDE\x05\x11\x16k5\x19\x84”\xEB\xCC\x80\xF9;pR\xB1\xDF\xF9V\xA1Bn!\xB5%D\x14\x08\xA3\x8A\x18\x07Do\xD2u\xF1\xDCw\x14\xFA~W\xDA8\x034\xBA\x0B\xD6/\x91q\x1E\x90\xA2\xE7\x03\x81\xC0\xA7\x97\xC3\xE1=\xAD\x1D\xB5Yo\xCDz\xBC\xB9\x8F\xB3\xD1\xCA\x83\xFB\x18.\x04\xDD\xC9%\xA3*\xDAB*Dow\xF3[\x19\x13\xC7\xE3\xAB>\xA4\xAE”`{\xD0=\x04*W\x00[\xA7\xCD\xE4>F\x9D\xBF\xEA\x03\xD3\x1C\x9E\x99\x17\x0E\xC9\xC2[\xAD\x86U\x9B\x13\x0C\xC6\xBF\x05B\x8F\x84\xB7\x8Fp\xFE\x11\x01K\x19QX\x87=\x13\xA0\x81\xA73\xB4VA6\xFF\x88\xB4\xA5\x1A\xF9,\x9AfM\x84D\x94f\xCD,\xEC\xF5*X@\xFB\xE4\x02\x10\xFD\x83(\x19\xA9\x98’\xE3F\x9BJ\x8B`i+7\x06\x01\x02
>>> homekit_client_process: [Client 3] Finished processing
>>> homekit_client_process: [Client 3] Got 601 incomming data
>>> homekit_server_on_pair_setup: Pair Setup
>>> homekit_server_on_pair_setup: Free heap: 28076
>>> tlv_debug: Got following TLV values:
>>> tlv_debug: Type 6 value (1 bytes): \x03
>>> tlv_debug: Type 3 value (384 bytes): \x18\xA9T?\xFFC\x85\x1C\x84\xC1\xA2\x14JU,\xA1\x89 \x15\x1FI\xFEJ\xEA\x03\xBA\xA6\xD1\xD7\xA1\xCAc\xF0|79\xD0{\xAFB\x92\xE2[-:\x16FaX\x039\xE8M\xF40\xC6\x8D\xFD\x0CB\x0B\xC1}\xDD\x038\x05\xC04\x04-A/_\x1Ba\xE0’\xEE\xBC\xB9\xB9\xA2_\xBC\xCDII\x877I\x0A)Wol\x8BW\xEF\xE9\xCD-\xE5\x99\x14SsakG\xDAH\xA0\x9Fl\x8C\xAC\xBAeo\x86\xBDEC\xC0\x0C\x0B\x01\x19t5\xDC/G\xF6\x0F\x98\xC5d\x17\xE5\x9BSo\x02\x0D3\xEB\x06\x18\x0A\x16\xAC\xE5\xE7\x87\x8D*\xE5An&(\xBF\xB7\xC3\xE6\xE0w\xD8\xDC\x12e\xB1M\xEE\x89%\xAA\x06v\xAA\x8E\xE7\xDC\xB1\x01)\xC4\x85\x16\x90b\x8C-B\xBC\xDD\xFC(U\xF3\xAA\x12\xD2\x8F{\xBE@g\xAFG7 \xFD\xFEu`\xE3a\xEB\x9C\x09\xFA\xFA\xFEf\xC7+\xE0F\xD0G\xE3\xF9]\x88\x0E\xE3\xAD\xC1C\xE9Y\xF7”\xCF\xE6X\x89+\x05\xCA4\xCFP\xB1!vB!wa\x86\x9E\xC3\xC8en\xC2\xF1!\x07\x9E\xAC\xA9\xC0\xCA\xE9\xFExg\xA4\x9E\xB3\xF9\xD1\xC9\xD0%\xF2\xF76U\x0E\xDCt\xE6\xA0|\xE2\xF8C\x95\xEBg\xD1\xE2.\xBD6\xDFU\xE7T\x93\xB6\xFB\xD3\xEEJ\xCAF\xB1\xE8\xB2\xF8\x09]\x8EAO\xD5h\xEB\xE9\xBD|\x03\xF6\x80w\x0C\xD8\xC0\x81\xD6\x9C’X\xFAu<x\x0A`\x1C\xD1\xA6\xA0\xFF\xAF\x94\xF0\x0DDeK\xB7*\x0B\xDD\x0A\x18\xF9\xAC\xCD\x96\xDD\x0Fj\xFD
>>> tlv_debug: Type 4 value (64 bytes): \xF3@\xD6\xA4\xA8\x9C\x0A\xEB\xE8\xE2\xAD\xD2\xEA\x91\x0Ar/\xC4\xAB\x94\xF6\xCBH\xD0\xE7\xDE\xDD\xE1\x90\xE2\x00\x0A\x9A\xA7\x11\xAA\x1DD\x9Fcjc\xC8*\xA0\xDC\x8E\xA6\xD7\x1C\x81\x83\xF8)\xB0s\x04z\xA1\x04\xC5\xE2\xCA\x95
>>> HomeKit: [Client 3] Pair Setup Step 2/3
>>> homekit_server_on_pair_setup: Free heap: 27500
>>> homekit_server_on_pair_setup: [Client 3] Computing SRP shared secret
>>> homekit_server_on_pair_setup: Free heap: 27500
>>> homekit_server_on_pair_setup: [Client 3] Verifying peer’s proof
>>> homekit_server_on_pair_setup: Free heap: 27820
>>> homekit_server_on_pair_setup: [Client 3] Generating own proof
>>> send_tlv_response: [Client 3] Sending TLV response
>>> tlv_debug: Got following TLV values:
>>> tlv_debug: Type 6 value (1 bytes): \x04
>>> tlv_debug: Type 4 value (64 bytes): ,\xA6\xBA\xA4\x81_ \xAD\x89’\x89\x84>\xB9\xF3e\x01\xE1-T\x08\xE4m9\x08\xB3\x981\xE1F]\xBC\x03\xF2\xD0\xE8K\x84\x1E2\xA8\x8A\xBDL6\x82\xBF\xDB]@1)(H&*\xA5\xA1\xFC\x99\xFA\xD8b\x84
>>> client_send: [Client 3] Sending payload: HTTP/1.1 200 OK\x0D\x0AContent-Type: application/pairing+tlv8\x0D\x0AContent-Length: 69\x0D\x0AConnection: keep-alive\x0D\x0A\x0D\x0A\x06\x01\x04\x04@,\xA6\xBA\xA4\x81_ \xAD\x89’\x89\x84>\xB9\xF3e\x01\xE1-T\x08\xE4m9\x08\xB3\x981\xE1F]\xBC\x03\xF2\xD0\xE8K\x84\x1E2\xA8\x8A\xBDL6\x82\xBF\xDB]@1)(H&*\xA5\xA1\xFC\x99\xFA\xD8b\x84
>>> homekit_client_process: [Client 3] Finished processing
>>> homekit_client_process: [Client 3] Got 303 incomming data
>>> homekit_server_on_pair_setup: Pair Setup
>>> homekit_server_on_pair_setup: Free heap: 28696
>>> tlv_debug: Got following TLV values:
>>> tlv_debug: Type 5 value (154 bytes): \xC0\x93\xC9\x01\xDA\xB5(\xDC\xB7+\x84tA\x0E\xB1\x03\xB1\xB6D\xF7c\xC9\xA4\x9B\x07\x88\x9C\xE4\xADpZ7\xCB\x17\xD7{G>9\x99\xD0f\xB8\x95\x98O\x01\x19Z=\x8AB\xF1\xB4U\xB8B\xE4cV~\xD8\xFA-3\x903\xD8\xD0\xA7\x1C\x93\xFA3M}\xA9\x1E [\xC1\x0E<\xA9s\xC3\xAC\xA1KxO\x8Cc\xC0)@\xC5\x07\x8C\x87H\x06A\x96\x95\xDD\xB9Qu\x1B}\xD7#\xBA\x8C\xE1l\xF3*T\x0B\xEE\x93N\xA6\xFAP\x83\xB9\x093\xC7\xAA\x9B\xE44*b\x9E\xC2\x98\x91\xF3\x86z\xD2\x88\x17\xE2j\xEB\xD8
>>> tlv_debug: Type 6 value (1 bytes): \x05
>>> HomeKit: [Client 3] Pair Setup Step 3/3
>>> homekit_server_on_pair_setup: Free heap: 28444
>>> homekit_server_on_pair_setup: [Client 3] Calculating shared secret
>>> homekit_server_on_pair_setup: [Client 3] Decrypting payload
>>> homekit_server_on_pair_setup: [Client 3] Importing device public key
>>> homekit_server_on_pair_setup: [Client 3] Calculating DeviceX
>>> homekit_server_on_pair_setup: [Client 3] Verifying device signature
>>> HomeKit: Added pairing with 78EBA429-C6E5-4E4D-B43E-606483BEEC27
>>> homekit_server_on_pair_setup: [Client 3] Exporting accessory public key
>>> homekit_server_on_pair_setup: [Client 3] Calculating AccessoryX
>>> homekit_server_on_pair_setup: [Client 3] Generating accessory signature
>>> homekit_server_on_pair_setup: Free heap: 28312
>>> tlv_debug: Got following TLV values:
>>> tlv_debug: Type 1 value (17 bytes): 1A:5D:4F:65:D8:03
>>> tlv_debug: Type 3 value (32 bytes): \xBA\xD8\xF4\xFBB\xF90h\x0Ei\xFE\xF4\x9AI\xD1\xA9Kf”k6\xB06\xAA\x16:\xC0\x19\x12?\xF3U
>>> tlv_debug: Type 10 value (64 bytes): \x82\xB2\x9C\xDA=h\xCDaqe?~\xC7U#u\xC30\xF3\x0F\xA3\xBA\x87\xC0\xF8\xAD\xAA\xAE\xF2\x00\x96h\xD5Wx\x0Al\xCF\xA2\xD4t\x0A~:\x1A\xF6\xEBO\xE4F\xE2_\xF6+\x0AQ\xEE\xEE’\xD0\xD8\x826\x06
>>> homekit_server_on_pair_setup: [Client 3] Encrypting response
>>> send_tlv_response: [Client 3] Sending TLV response
>>> tlv_debug: Got following TLV values:
>>> tlv_debug: Type 6 value (1 bytes): \x06
>>> tlv_debug: Type 5 value (135 bytes): o\x08_\xB9\xFB\x8B\x98\xCEu\xA5\x94#?\xF7\x8E\x00\x19L\xD7\xEB?\xBC\xB4\xD9N\xFA\x87_\xE1m\x9AU?%\xE8W]\x9FE\xEE9\xF8u\xB1#.s\x18\xAB\xE4+1\xDE&\xE4\xC8\xFB\xD7w\xB88\xA9O`\x0A\xAE[\xC1\xD3\x15m\xF0\xD9v\xCB/\x8E\xE4\xD4h\x0B\xC2\x07M\xFD\x13\xDA\xD5\x1A0\xF3\xF8\xC8t7\x9E\xBD\xE7\xE8vy\xCD\xB4;\x88\x88\x98\x1DVf\xE5y\xCE\x1EP\x97\x03\x15\xC4\xE7=\xB4\x0A\xC5\xBB\xEFE\xBC\xF9\xE4K(\x9Bu\xB9
>>> client_send: [Client 3] Sending payload: HTTP/1.1 200 OK\x0D\x0AContent-Type: application/pairing+tlv8\x0D\x0AContent-Length: 140\x0D\x0AConnection: keep-alive\x0D\x0A\x0D\x0A\x06\x01\x06\x05\x87o\x08_\xB9\xFB\x8B\x98\xCEu\xA5\x94#?\xF7\x8E\x00\x19L\xD7\xEB?\xBC\xB4\xD9N\xFA\x87_\xE1m\x9AU?%\xE8W]\x9FE\xEE9\xF8u\xB1#.s\x18\xAB\xE4+1\xDE&\xE4\xC8\xFB\xD7w\xB88\xA9O`\x0A\xAE[\xC1\xD3\x15m\xF0\xD9v\xCB/\x8E\xE4\xD4h\x0B\xC2\x07M\xFD\x13\xDA\xD5\x1A0\xF3\xF8\xC8t7\x9E\xBD\xE7\xE8vy\xCD\xB4;\x88\x88\x98\x1DVf\xE5y\xCE\x1EP\x97\x03\x15\xC4\xE7=\xB4\x0A\xC5\xBB\xEFE\xBC\xF9\xE4K(\x9Bu\xB9
>>> HomeKit: Configuring mDNS
mDNS announcement: Name=Temperature with IR-1A5D md=IR senderpv=1.0id=1A:5D:4F:65:D8:03c#=1s#=1ff=0sf=0ci=5 Port=5556 TTL=4500
>>> HomeKit: [Client 3] Successfully paired
>>> homekit_client_process: [Client 3] Finished processing
>>> HomeKit: [Client 3] Closing client connection
>>> HomeKit: Got new client connection: 3
>>> homekit_client_process: [Client 3] Got 181 incomming data
>>> homekit_server_on_pair_verify: HomeKit Pair Verify
>>> homekit_server_on_pair_verify: Free heap: 30988
>>> tlv_debug: Got following TLV values:
>>> tlv_debug: Type 6 value (1 bytes): \x01
>>> tlv_debug: Type 3 value (32 bytes): {\xAC\xF6\x96\x97_W\xF8\xC9\x1D\x12.\xD2y\xC2\xC2\xF3\x16\x19\x0A\xBC2eL\xA1\xDF\xEA\x80iocU
>>> HomeKit: [Client 3] Pair Verify Step 1/2
>>> homekit_server_on_pair_verify: [Client 3] Importing device Curve25519 public key
>>> homekit_server_on_pair_verify: [Client 3] Generating accessory Curve25519 key
>>> homekit_server_on_pair_verify: [Client 3] Exporting accessory Curve25519 public key
>>> homekit_server_on_pair_verify: [Client 3] Generating Curve25519 shared secret
>>> homekit_server_on_pair_verify: [Client 3] Generating signature
>>> homekit_server_on_pair_verify: [Client 3] Generating proof
>>> homekit_server_on_pair_verify: [Client 3] Encrypting response
>>> send_tlv_response: [Client 3] Sending TLV response
>>> tlv_debug: Got following TLV values:
>>> tlv_debug: Type 6 value (1 bytes): \x02
>>> tlv_debug: Type 3 value (32 bytes): \xAD\xC9Cwk\xAF\x94-\xF0\xC7U(\xE1#\xE6\xC4\x90Xx\x87\x84t\xEA\xDB\x18\x9Dv\xF9\x05\x88JD
>>> tlv_debug: Type 5 value (101 bytes): c\xBF\xA4\xBB\x0CbW\xB6\xA0\x11R4s$\x929\xB95\x80#\x8F\x8D\xF5\xB5\xAA\xA9L\x1E\x19\xC4y\xF0w\xB9\xC2\xB5}\xEF\xD68O[\x9E6h\x08i\x0A\x8Dc\x05\x12iL\x01\xF7\xE84{r\x16\xABM\x0B\xE6o\x16yB%\x0F\xDCt\x00\xA9\xBEp\x16\xAD\xF3\x95\x04\x95G\xBD\x84\x98\xE0\x06\x04x`\xC5\xA1\xEFa]1s
>>> client_send: [Client 3] Sending payload: HTTP/1.1 200 OK\x0D\x0AContent-Type: application/pairing+tlv8\x0D\x0AContent-Length: 140\x0D\x0AConnection: keep-alive\x0D\x0A\x0D\x0A\x06\x01\x02\x03 \xAD\xC9Cwk\xAF\x94-\xF0\xC7U(\xE1#\xE6\xC4\x90Xx\x87\x84t\xEA\xDB\x18\x9Dv\xF9\x05\x88JD\x05ec\xBF\xA4\xBB\x0CbW\xB6\xA0\x11R4s$\x929\xB95\x80#\x8F\x8D\xF5\xB5\xAA\xA9L\x1E\x19\xC4y\xF0w\xB9\xC2\xB5}\xEF\xD68O[\x9E6h\x08i\x0A\x8Dc\x05\x12iL\x01\xF7\xE84{r\x16\xABM\x0B\xE6o\x16yB%\x0F\xDCt\x00\xA9\xBEp\x16\xAD\xF3\x95\x04\x95G\xBD\x84\x98\xE0\x06\x04x`\xC5\xA1\xEFa]1s
>>> homekit_client_process: [Client 3] Finished processing
>>> homekit_client_process: [Client 3] Got 270 incomming data
>>> homekit_server_on_pair_verify: HomeKit Pair Verify
>>> homekit_server_on_pair_verify: Free heap: 30704
>>> tlv_debug: Got following TLV values:
>>> tlv_debug: Type 5 value (120 bytes): \xFDT.!\xAD\xEB\xCA\x18\xFF\xFD\x9A\xDD~\xDB|\xFD\x86\xC0>fo\x93j\xB7\xFF\xB9r*([\x1A(l\xC1’\x15/\xB1p\xB6-\x09\xEEG\xF2\x04\xFF\xE7\x04y|\x04\x06\x1Fc\x81\x0E\x86\xB6.%\xD9\xE6\xB0\x108]\x87\xA1f\x19\x8E@\xB4\xB7h\xEC\x16\xC4\x81\x0A:\x89C\xC3\x84v\xC8\xBE%\x12\x9A\xDE\x1E\x95Y`\xAD\xF90\x1E\xE1g39\x84\xACx\x1C\xAA\x10\xE6A\x876\x8B}z\xF0
>>> tlv_debug: Type 6 value (1 bytes): \x03
>>> HomeKit: [Client 3] Pair Verify Step 2/2
>>> homekit_server_on_pair_verify: [Client 3] Decrypting payload
>>> homekit_server_on_pair_verify: [Client 3] Searching pairing with 78EBA429-C6E5-4E4D-B43E-606483BEEC27
>>> HomeKit: [Client 3] Found pairing with 78EBA429-C6E5-4E4D-B43E-606483BEEC27
>>> homekit_server_on_pair_verify: [Client 3] Verifying device signature
>>> send_tlv_response: [Client 3] Sending TLV response
>>> tlv_debug: Got following TLV values:
>>> tlv_debug: Type 6 value (1 bytes): \x04
>>> client_send: [Client 3] Sending payload: HTTP/1.1 200 OK\x0D\x0AContent-Type: application/pairing+tlv8\x0D\x0AContent-Length: 3\x0D\x0AConnection: keep-alive\x0D\x0A\x0D\x0A\x06\x01\x04
>>> HomeKit: [Client 3] Verification successful, secure session established
>>> homekit_client_process: [Client 3] Finished processing
>>> homekit_client_process: [Client 3] Got 101 incomming data
>>> homekit_client_process: [Client 3] Decrypting data
>>> homekit_client_process: [Client 3] Decrypted 83 bytes, available 0
Decrypted data (83 bytes): “GET /accessories HTTP/1.1\x0D\x0AHost: Temperature\\032with\\032IR-1A5D._hap._tcp.local\x0D\x0A\x0D\x0A”
>>> HomeKit: [Client 3] Get Accessories
>>> homekit_server_on_get_accessories: Free heap: 30868
>>> client_send: [Client 3] Sending payload: HTTP/1.1 200 OK\x0D\x0AContent-Type: application/hap+json\x0D\x0ATransfer-Encoding: chunked\x0D\x0AConnection: keep-alive\x0D\x0A\x0D\x0A
>>> client_send: [Client 3] Sending payload: 3fd\x0D\x0A{“accessories”:[{“aid”:1,”services”:[{“iid”:1,”type”:”3E”,”hidden”:false,”primary”:false,”characteristics”:[{“aid”:1,”iid”:2,”type”:”23”,”perms”:[“pr”],”description”:”Name”,”format”:”string”,”value”:”Temperature with IR”},{“aid”:1,”iid”:3,”type”:”20”,”perms”:[“pr”],”description”:”Manufacturer”,”format”:”string”,”value”:”Consultware”},{“aid”:1,”iid”:4,”type”:”30”,”perms”:[“pr”],”description”:”Serial Number”,”format”:”string”,”value”:”cws774497”},{“aid”:1,”iid”:5,”type”:”21”,”perms”:[“pr”],”description”:”Model”,”format”:”string”,”value”:”IR sender”},{“aid”:1,”iid”:6,”type”:”52”,”perms”:[“pr”],”description”:”Firmware Revision”,”format”:”string”,”value”:”0.1”},{“aid”:1,”iid”:7,”type”:”14”,”perms”:[“pw”],”description”:”Identify”,”format”:”bool”}]},{“iid”:8,”type”:”43”,”hidden”:false,”primary”:true,”characteristics”:[{“aid”:1,”iid”:9,”type”:”23”,”perms”:[“pr”],”description”:”Name”,”format”:”string”,”value”:”IR switch”},{“aid”:1,”iid”:10,”type”:”25”,”perms”:[“pr”,”pw”,”ev”],”ev”:false,”description”:”On”,”format”:\x0D\x0A
>>> client_send: [Client 3] Sending payload: 1ac\x0D\x0A”bool”,”value”:false}]},{“iid”:11,”type”:”8A”,”hidden”:false,”primary”:true,”characteristics”:[{“aid”:1,”iid”:12,”type”:”23”,”perms”:[“pr”],”description”:”Name”,”format”:”string”,”value”:”Temperature Sensor”},{“aid”:1,”iid”:13,”type”:”11”,”perms”:[“pr”,”ev”],”ev”:false,”description”:”Current Temperature”,”format”:”float”,”unit”:”celsius”,”minValue”:0,”maxValue”:100,”minStep”:0.100000001490116,”value”:-79.0665969848633}]}]}]}\x0D\x0A
>>> client_send: [Client 3] Sending payload: 0\x0D\x0A\x0D\x0A
>>> homekit_client_process: [Client 3] Finished processing
>>> homekit_client_process: [Client 3] Got 203 incomming data
>>> homekit_client_process: [Client 3] Decrypting data
>>> homekit_client_process: [Client 3] Decrypted 185 bytes, available 0
Decrypted data (185 bytes): “POST /pairings HTTP/1.1\x0D\x0AHost: Temperature\\032with\\032IR-1A5D._hap._tcp.local\x0D\x0AContent-Length: 44\x0D\x0AContent-Type: application/pairing+tlv8\x0D\x0A\x0D\x0A\x00\x01\x04\x06\x01\x01\x01$78EBA429-C6E5-4E4D-B43E-606483BEEC27”
>>> homekit_server_on_pairings: HomeKit Pairings
>>> homekit_server_on_pairings: Free heap: 30404
>>> tlv_debug: Got following TLV values:
>>> tlv_debug: Type 0 value (1 bytes): \x04
>>> tlv_debug: Type 6 value (1 bytes): \x01
>>> tlv_debug: Type 1 value (36 bytes): 78EBA429-C6E5-4E4D-B43E-606483BEEC27
>>> HomeKit: [Client 3] Remove Pairing
>>> HomeKit: Removed pairing with 78EBA429-C6E5-4E4D-B43E-606483BEEC27
>>> HomeKit: Last admin pairing was removed, enabling pair setup
>>> HomeKit: Configuring mDNS
mDNS announcement: Name=Temperature with IR-1A5D md=IR senderpv=1.0id=1A:5D:4F:65:D8:03c#=1s#=1ff=0sf=1ci=5 Port=5556 TTL=4500
>>> send_tlv_response: [Client 3] Sending TLV response
>>> tlv_debug: Got following TLV values:
>>> tlv_debug: Type 6 value (1 bytes): \x02
>>> client_send: [Client 3] Sending payload: HTTP/1.1 200 OK\x0D\x0AContent-Type: application/pairing+tlv8\x0D\x0AContent-Length: 3\x0D\x0AConnection: keep-alive\x0D\x0A\x0D\x0A\x06\x01\x02
>>> homekit_client_process: [Client 3] Finished processing
>>> HomeKit: [Client 3] Closing client connection
ADC start  
ADC read [2.000000] Temp [-75.851006] Rth [5105000.000000]
ADC correct [2.000000] Temp [-83.851006] Rth [5105000.000000]
maccoylton commented 3 years ago

@maximkulkin I have a similar issue, for some reason today, I tried to flash a brand new ESP8266(actually several I tried) and all of them seem to be failing to register, for some weird reason, if they connect to the "home" app they immediately change to disconnected, maaaaybe, the problem is that apple is no longer allowing new devices to log in, this is my log: ...

@beckmx can you also try the Eve app and let us know if that works ok

Sent with GitHawk

RavenSystem commented 3 years ago

Device pairs, but then it is removed, because temperature value (-78) is under min value (0), then it is an invalid value.

maximkulkin commented 3 years ago

@beckmx ^^ what @RavenSystem said. Now let's figure out why your temperature value is not initialized. Can you send link to the code? Seems like you're using some analog temperature couple and ADC, and your formulas did not expect values like that.

peros550 commented 3 years ago

There must be a generic issue. If you would like to reproduce the issue here is what I did. I downloaded from scratch Maxim's esp-homekit-demo. I initialized the submodules and run the following command to bring sub-modules into their latest state:

git submodule foreach git pull origin master

Then I setup wifi.h and tried the temperature sensor. Results are the same. The home app shows the accessory as "no response" at first, when opening the room's page. Then if you click the accessory, it gets updated.

Here is a video showing the issue

Eve app shows the accessories as available and works properly. Issue found on ios 13.6 ,13.5.1 and iPadOS 14b4

peros550 commented 3 years ago

In order to isolate the issue, I made some tests in the temperature sensor example. I started applying all latest commits one by one. The issue appeared when I applied this commit

peros550 commented 3 years ago

Thanks Maxim! Today's updated fixed the issue! :)