[SOLVED] HomeKit Integration - Pairing Problems (iOS 10 - iOS 14)

ok, this is the new homekit.items:

/************************************************** Items ********************************************/
//—> Wohnung
//–> Rolladen

Rollershutter legacy_window_covering “Legacy Window Rollershutter” [“WindowCovering”]
Rollershutter window_covering “Window Rollershutter” {homekit = “WindowCovering”}

Rollershutter SZ_BT “Schlafzimmer Balkontür” [“Blinds”] { channel=“rfxcom:rfy:015XXXXX:shutter” }

Rollershutter SZ_FE “Schlafzimmer Fenster” [“Blinds”] { channel=“rfxcom:rfy:c71XXXXX:shutter” }

Rollershutter WZ_BT “Wohnzimmer Balkontür” [“Blinds”] { channel=“rfxcom:rfy:0ddXXXXX:shutter” }

I have, as predicted, 3 new items in my homekit app, all No answer… I am starting to think there is something wrong with my binding, but i have installed the homekit integratiopn, changed the pin and that’s that. do i need to add something in that config too? Did i miss something?

hm…

  • item configuration is good
  • pairing is fine
  • network connection is fine
    im getting out of ideas.

last try, please do following:

  • close home app
  • start logging with “log:set TRACE io.github.hapjava”
  • start home app
  • paste here the first 20-30 lines of the log.

ok, got a LOT when i stated logging and i haven’t even started the homekit app on the phone
Not sure if there is enough info for you.

openhab> log:set TRACE io.github.hapjava
openhab> log:tail io.github.hapjava
15:29:50.515 [INFO ] [erver.impl.http.impl.AccessoryHandler] - New homekit connection from /192.168.178.20:52558
15:29:50.517 [TRACE] [.server.impl.http.impl.LoggingHandler] - READ PooledUnsafeDirectByteBuf(ridx: 0, widx: 158, cap: 1024) [/192.168.178.20:52558]:
504F5354202F706169722D76657269667920485454502F312E310D0A486F73743A206F70656E4841422E5F6861702E5F7463702E6C6F63616C0D0A436F6E74656E742D4C656E6774683A2033370D0A436F6E74656E742D547970653A206170706C69636174696F6E2F70616972696E672B746C76380D0A0D0A0601010320D5DCD891BF09F65D61C068B7C3B30719C0B7FF26B313405FF62151370AD21946

15:29:50.523 [DEBUG] [.impl.pairing.PairVerificationManager] - Starting pair verification for openHAB
15:29:50.560 [INFO ] [ver.impl.http.HomekitClientConnection] - 200 /pair-verify
15:29:50.565 [TRACE] [.server.impl.http.impl.LoggingHandler] - WRITE PooledUnsafeDirectByteBuf(ridx: 0, widx: 244, cap: 256) [/192.168.178.20:52558]:
485454502F312E3120323030204F4B0D0A436F6E74656E742D747970653A206170706C69636174696F6E2F70616972696E672B746C76380D0A436F6E74656E742D4C656E6774683A203134300D0A436F6E6E656374696F6E3A206B6565702D616C6976650D0A0D0A0601020565B6C7A3586844EAD98344C8F6BB7432819F567D161C166BB3CA548229B54FC392154A1AF61FB47D5C98C10379A52E7DAFDDDF32BE0B31464B74B15ADCD60ED107A7EE8C6CAC63B00D85A5E03799FE069D9E709606238A73620564F94B5F64F885CA512E52820320960AE74B14A4EF13ED2DE5A6C5F2B8C6FBE51A2455B4BAB6DA7FBECDE18F4B20

15:29:50.710 [TRACE] [.server.impl.http.impl.LoggingHandler] - READ PooledUnsafeDirectByteBuf(ridx: 0, widx: 247, cap: 1024) [/192.168.178.20:52558]:
504F5354202F706169722D76657269667920485454502F312E310D0A486F73743A206F70656E4841422E5F6861702E5F7463702E6C6F63616C0D0A436F6E74656E742D4C656E6774683A203132350D0A436F6E74656E742D547970653A206170706C69636174696F6E2F70616972696E672B746C76380D0A0D0A0578AAC88F95AC8686B614EB10D7909F013CA25E8BB8ABA9AF57934773C0BD1545D5FDE4D7F0B79C94FFE4303AFE5E6518C7B715ABFF9E46E9FE6A7624D1815DC9ED87F5323A43A756376A5ADE7D833F71C271435E3A634D384BEA0166408B0054F34F9710C8836E723170400D78CE923F7502FEA8FAC8AABFBE060103

15:29:50.716 [ERROR] [a.server.impl.connections.HttpSession] - Excepton encountered while verifying pairing
java.lang.Exception: Unknown user: 0461ED8E-A3EB-4205-9465-DBBA7CF9DD9B
at io.github.hapjava.server.impl.pairing.PairVerificationManager.stage2(PairVerificationManager.java:113) ~[bundleFile:?]
at io.github.hapjava.server.impl.pairing.PairVerificationManager.handle(PairVerificationManager.java:51) ~[bundleFile:?]
at io.github.hapjava.server.impl.connections.HttpSession.handlePairVerify(HttpSession.java:127) [bundleFile:?]
at io.github.hapjava.server.impl.connections.HttpSession.handleRequest(HttpSession.java:56) [bundleFile:?]
at io.github.hapjava.server.impl.connections.ConnectionImpl.doHandleRequest(ConnectionImpl.java:56) [bundleFile:?]
at io.github.hapjava.server.impl.connections.ConnectionImpl.handleRequest(ConnectionImpl.java:49) [bundleFile:?]
at io.github.hapjava.server.impl.http.impl.AccessoryHandler.channelRead0(AccessoryHandler.java:52) [bundleFile:?]
at io.github.hapjava.server.impl.http.impl.AccessoryHandler.channelRead0(AccessoryHandler.java:17) [bundleFile:?]
at io.netty.channel.SimpleChannelInboundHandler.channelRead(SimpleChannelInboundHandler.java:105) [bundleFile:4.1.42.Final]
at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:374) [bundleFile:4.1.42.Final]
at io.netty.channel.AbstractChannelHandlerContext.access$600(AbstractChannelHandlerContext.java:56) [bundleFile:4.1.42.Final]
at io.netty.channel.AbstractChannelHandlerContext$7.run(AbstractChannelHandlerContext.java:365) [bundleFile:4.1.42.Final]
at io.netty.util.concurrent.DefaultEventExecutor.run(DefaultEventExecutor.java:66) [bundleFile:4.1.42.Final]
at io.netty.util.concurrent.SingleThreadEventExecutor$6.run(SingleThreadEventExecutor.java:1044) [bundleFile:4.1.42.Final]
at io.netty.util.internal.ThreadExecutorMap$2.run(ThreadExecutorMap.java:74) [bundleFile:4.1.42.Final]
at io.netty.util.concurrent.FastThreadLocalRunnable.run(FastThreadLocalRunnable.java:30) [bundleFile:4.1.42.Final]
at java.lang.Thread.run(Thread.java:745) [?:1.8.0_65]
15:29:50.727 [INFO ] [ver.impl.http.HomekitClientConnection] - 500 /pair-verify
15:29:50.733 [TRACE] [.server.impl.http.impl.LoggingHandler] - WRITE PooledUnsafeDirectByteBuf(ridx: 0, widx: 101, cap: 232) [/192.168.178.20:52558]:
485454502F312E312035303020496E7465726E616C20536572766572204572726F720D0A436F6E74656E742D4C656E6774683A2031390D0A436F6E6E656374696F6E3A206B6565702D616C6976650D0A0D0A6A6176612E6C616E672E457863657074696F6E

15:29:50.761 [INFO ] [erver.impl.http.impl.AccessoryHandler] - Terminated homekit connection from /192.168.178.20:52558
15:30:31.769 [INFO ] [erver.impl.http.impl.AccessoryHandler] - New homekit connection from /192.168.178.20:52559
15:30:31.770 [TRACE] [.server.impl.http.impl.LoggingHandler] - READ PooledUnsafeDirectByteBuf(ridx: 0, widx: 158, cap: 1024) [/192.168.178.20:52559]:
504F5354202F706169722D76657269667920485454502F312E310D0A486F73743A206F70656E4841422E5F6861702E5F7463702E6C6F63616C0D0A436F6E74656E742D4C656E6774683A2033370D0A436F6E74656E742D547970653A206170706C69636174696F6E2F70616972696E672B746C76380D0A0D0A0601010320BD44B86B7B8CF575C0289D008F131D0302657E4EF66ECFEC97EC7D38E739E537

15:30:31.776 [DEBUG] [.impl.pairing.PairVerificationManager] - Starting pair verification for openHAB
15:30:31.815 [INFO ] [ver.impl.http.HomekitClientConnection] - 200 /pair-verify
15:30:31.819 [TRACE] [.server.impl.http.impl.LoggingHandler] - WRITE PooledUnsafeDirectByteBuf(ridx: 0, widx: 244, cap: 256) [/192.168.178.20:52559]:
485454502F312E3120323030204F4B0D0A436F6E74656E742D747970653A206170706C69636174696F6E2F70616972696E672B746C76380D0A436F6E74656E742D4C656E6774683A203134300D0A436F6E6E656374696F6E3A206B6565702D616C6976650D0A0D0A0601020565E857DB416912388426537280EDFE4BBE6AAD85826ADD6E2CACDC687BB48A889214DEF51D6A43408BD8656E00B7B9D6483B8F56DBBB79BE0C3D1265DF91AC7B51C573F224874DD33F9D319BD611C03265C06924B9525FE59E980B6213A29BFA61EAD13D822D0320172D27D5BF761E79A57F174942061E76640632EA086F6787C37C2BE3EB0C0823

15:30:31.943 [TRACE] [.server.impl.http.impl.LoggingHandler] - READ PooledUnsafeDirectByteBuf(ridx: 0, widx: 247, cap: 1024) [/192.168.178.20:52559]:
504F5354202F706169722D76657269667920485454502F312E310D0A486F73743A206F70656E4841422E5F6861702E5F7463702E6C6F63616C0D0A436F6E74656E742D4C656E6774683A203132350D0A436F6E74656E742D547970653A206170706C69636174696F6E2F70616972696E672B746C76380D0A0D0A05782CED32C00C0468C94228122B3A7BA1A9B703C1F2178157A37117271CE8B8C4970BB7082A804CD9D34D6097F3220E36877E875798EC0E6CADF6B22B0A25CF9085B352D5091B1D170BC917E3F5CF2AE85F34BE73EFBA146C81CE8AA43B82256D2A7F63DE5A317E79DF6263615EB898042FD143338C62B1DA4B060103

15:30:31.950 [ERROR] [a.server.impl.connections.HttpSession] - Excepton encountered while verifying pairing
java.lang.Exception: Unknown user: 0461ED8E-A3EB-4205-9465-DBBA7CF9DD9B
at io.github.hapjava.server.impl.pairing.PairVerificationManager.stage2(PairVerificationManager.java:113) ~[bundleFile:?]
at io.github.hapjava.server.impl.pairing.PairVerificationManager.handle(PairVerificationManager.java:51) ~[bundleFile:?]
at io.github.hapjava.server.impl.connections.HttpSession.handlePairVerify(HttpSession.java:127) [bundleFile:?]
at io.github.hapjava.server.impl.connections.HttpSession.handleRequest(HttpSession.java:56) [bundleFile:?]
at io.github.hapjava.server.impl.connections.ConnectionImpl.doHandleRequest(ConnectionImpl.java:56) [bundleFile:?]
at io.github.hapjava.server.impl.connections.ConnectionImpl.handleRequest(ConnectionImpl.java:49) [bundleFile:?]
at io.github.hapjava.server.impl.http.impl.AccessoryHandler.channelRead0(AccessoryHandler.java:52) [bundleFile:?]
at io.github.hapjava.server.impl.http.impl.AccessoryHandler.channelRead0(AccessoryHandler.java:17) [bundleFile:?]
at io.netty.channel.SimpleChannelInboundHandler.channelRead(SimpleChannelInboundHandler.java:105) [bundleFile:4.1.42.Final]
at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:374) [bundleFile:4.1.42.Final]
at io.netty.channel.AbstractChannelHandlerContext.access$600(AbstractChannelHandlerContext.java:56) [bundleFile:4.1.42.Final]
at io.netty.channel.AbstractChannelHandlerContext$7.run(AbstractChannelHandlerContext.java:365) [bundleFile:4.1.42.Final]
at io.netty.util.concurrent.DefaultEventExecutor.run(DefaultEventExecutor.java:66) [bundleFile:4.1.42.Final]
at io.netty.util.concurrent.SingleThreadEventExecutor$6.run(SingleThreadEventExecutor.java:1044) [bundleFile:4.1.42.Final]
at io.netty.util.internal.ThreadExecutorMap$2.run(ThreadExecutorMap.java:74) [bundleFile:4.1.42.Final]
at io.netty.util.concurrent.FastThreadLocalRunnable.run(FastThreadLocalRunnable.java:30) [bundleFile:4.1.42.Final]
at java.lang.Thread.run(Thread.java:745) [?:1.8.0_65]
15:30:31.958 [INFO ] [ver.impl.http.HomekitClientConnection] - 500 /pair-verify
15:30:31.963 [TRACE] [.server.impl.http.impl.LoggingHandler] - WRITE PooledUnsafeDirectByteBuf(ridx: 0, widx: 101, cap: 232) [/192.168.178.20:52559]:
485454502F312E312035303020496E7465726E616C20536572766572204572726F720D0A436F6E74656E742D4C656E6774683A2031390D0A436F6E6E656374696F6E3A206B6565702D616C6976650D0A0D0A6A6176612E6C616E672E457863657074696F6E

15:30:31.975 [INFO ] [erver.impl.http.impl.AccessoryHandler] - Terminated homekit connection from /192.168.178.20:52559
15:31:17.940 [INFO ] [erver.impl.http.impl.AccessoryHandler] - New homekit connection from /192.168.178.20:52560
15:31:17.942 [TRACE] [.server.impl.http.impl.LoggingHandler] - READ PooledUnsafeDirectByteBuf(ridx: 0, widx: 158, cap: 1024) [/192.168.178.20:52560]:
504F5354202F706169722D76657269667920485454502F312E310D0A486F73743A206F70656E4841422E5F6861702E5F7463702E6C6F63616C0D0A436F6E74656E742D4C656E6774683A2033370D0A436F6E74656E742D547970653A206170706C69636174696F6E2F70616972696E672B746C76380D0A0D0A0601010320261114ECE1DBA920C41A861088373F3F69BA0C4E933A78E6E0D2A1292BA5DD7B

15:31:17.947 [DEBUG] [.impl.pairing.PairVerificationManager] - Starting pair verification for openHAB
15:31:17.985 [INFO ] [ver.impl.http.HomekitClientConnection] - 200 /pair-verify
15:31:17.991 [TRACE] [.server.impl.http.impl.LoggingHandler] - WRITE PooledUnsafeDirectByteBuf(ridx: 0, widx: 244, cap: 256) [/192.168.178.20:52560]:
485454502F312E3120323030204F4B0D0A436F6E74656E742D747970653A206170706C69636174696F6E2F70616972696E672B746C76380D0A436F6E74656E742D4C656E6774683A203134300D0A436F6E6E656374696F6E3A206B6565702D616C6976650D0A0D0A06010205653D878E591CB71B3D7108463AB85741655A58E70FA72A07301F6BAEC376A7D49825510BA43BE43441A97E02FCC53D82A8B950EE177B5EBC0658054884C248E69F90F18A91D9DCBA5F6329502AC3A2FB43F2858DA17260ABCD1F056F90D9DDA22246524F1F2D03205A69437453DE03D69744D4EDD8F6A6056408C384C2CC1BB90924910DA7BE883A

15:31:18.091 [TRACE] [.server.impl.http.impl.LoggingHandler] - READ PooledUnsafeDirectByteBuf(ridx: 0, widx: 247, cap: 1024) [/192.168.178.20:52560]:
504F5354202F706169722D76657269667920485454502F312E310D0A486F73743A206F70656E4841422E5F6861702E5F7463702E6C6F63616C0D0A436F6E74656E742D4C656E6774683A203132350D0A436F6E74656E742D547970653A206170706C69636174696F6E2F70616972696E672B746C76380D0A0D0A05784C1FB10B0398AD83087FC364E526494E7979933F4B788E875BD417123360E73C256841AA06EC52B4991DDD2DEAC05BFED2602B56CC8498946ABE65FAAFF2867FDD2CAB9FA445043A6552BE29CABD9D5637433B33F0CE9F1516BE0A9AE7F03611FA04F0B7DF706A23BA4A39362B23122C01C9A5AEF615C2CB060103

15:31:18.096 [ERROR] [a.server.impl.connections.HttpSession] - Excepton encountered while verifying pairing
java.lang.Exception: Unknown user: 0461ED8E-A3EB-4205-9465-DBBA7CF9DD9B
at io.github.hapjava.server.impl.pairing.PairVerificationManager.stage2(PairVerificationManager.java:113) ~[bundleFile:?]
at io.github.hapjava.server.impl.pairing.PairVerificationManager.handle(PairVerificationManager.java:51) ~[bundleFile:?]
at io.github.hapjava.server.impl.connections.HttpSession.handlePairVerify(HttpSession.java:127) [bundleFile:?]
at io.github.hapjava.server.impl.connections.HttpSession.handleRequest(HttpSession.java:56) [bundleFile:?]
at io.github.hapjava.server.impl.connections.ConnectionImpl.doHandleRequest(ConnectionImpl.java:56) [bundleFile:?]
at io.github.hapjava.server.impl.connections.ConnectionImpl.handleRequest(ConnectionImpl.java:49) [bundleFile:?]
at io.github.hapjava.server.impl.http.impl.AccessoryHandler.channelRead0(AccessoryHandler.java:52) [bundleFile:?]
at io.github.hapjava.server.impl.http.impl.AccessoryHandler.channelRead0(AccessoryHandler.java:17) [bundleFile:?]
at io.netty.channel.SimpleChannelInboundHandler.channelRead(SimpleChannelInboundHandler.java:105) [bundleFile:4.1.42.Final]
at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:374) [bundleFile:4.1.42.Final]
at io.netty.channel.AbstractChannelHandlerContext.access$600(AbstractChannelHandlerContext.java:56) [bundleFile:4.1.42.Final]
at io.netty.channel.AbstractChannelHandlerContext$7.run(AbstractChannelHandlerContext.java:365) [bundleFile:4.1.42.Final]
at io.netty.util.concurrent.DefaultEventExecutor.run(DefaultEventExecutor.java:66) [bundleFile:4.1.42.Final]
at io.netty.util.concurrent.SingleThreadEventExecutor$6.run(SingleThreadEventExecutor.java:1044) [bundleFile:4.1.42.Final]
at io.netty.util.internal.ThreadExecutorMap$2.run(ThreadExecutorMap.java:74) [bundleFile:4.1.42.Final]
at io.netty.util.concurrent.FastThreadLocalRunnable.run(FastThreadLocalRunnable.java:30) [bundleFile:4.1.42.Final]
at java.lang.Thread.run(Thread.java:745) [?:1.8.0_65]
15:31:18.103 [INFO ] [ver.impl.http.HomekitClientConnection] - 500 /pair-verify
15:31:18.108 [TRACE] [.server.impl.http.impl.LoggingHandler] - WRITE PooledUnsafeDirectByteBuf(ridx: 0, widx: 101, cap: 232) [/192.168.178.20:52560]:
485454502F312E312035303020496E7465726E616C20536572766572204572726F720D0A436F6E74656E742D4C656E6774683A2031390D0A436F6E6E656374696F6E3A206B6565702D616C6976650D0A0D0A6A6176612E6C616E672E457863657074696F6E

15:31:18.121 [INFO ] [erver.impl.http.impl.AccessoryHandler] - Terminated homekit connection from /192.168.178.20:52560
15:31:20.628 [INFO ] [erver.impl.http.impl.AccessoryHandler] - New homekit connection from /192.168.178.28:53071
15:31:20.630 [TRACE] [.server.impl.http.impl.LoggingHandler] - READ PooledUnsafeDirectByteBuf(ridx: 0, widx: 158, cap: 1024) [/192.168.178.28:53071]:
504F5354202F706169722D76657269667920485454502F312E310D0A486F73743A206F70656E4841422E5F6861702E5F7463702E6C6F63616C0D0A436F6E74656E742D4C656E6774683A2033370D0A436F6E74656E742D547970653A206170706C69636174696F6E2F70616972696E672B746C76380D0A0D0A0601010320844A6761ED7BEE16D196A8351428CC7A4C8CD54362057D88915F2922A59BA573

15:31:20.640 [DEBUG] [.impl.pairing.PairVerificationManager] - Starting pair verification for openHAB
15:31:20.678 [INFO ] [ver.impl.http.HomekitClientConnection] - 200 /pair-verify
15:31:20.683 [TRACE] [.server.impl.http.impl.LoggingHandler] - WRITE PooledUnsafeDirectByteBuf(ridx: 0, widx: 244, cap: 256) [/192.168.178.28:53071]:
485454502F312E3120323030204F4B0D0A436F6E74656E742D747970653A206170706C69636174696F6E2F70616972696E672B746C76380D0A436F6E74656E742D4C656E6774683A203134300D0A436F6E6E656374696F6E3A206B6565702D616C6976650D0A0D0A0601020565FA135DFAFCF121944732D29506FAB86C369982C8C5B93E240FB3A7B764ADFD5BE2C943AA201C8CB135022E7B6A544118E1AB78E6A6CEC2BE2D5BDDECB9219CB36DA77EE40068E03FCB9E2FD653E6334A0B4C337D7212D7D204E54C62BE460BD5D8569C4F850320DB18BC538D09192CF110DA76EF1BFCD4E617CBF077C09564A4E8B287AEC38978

15:31:20.822 [TRACE] [.server.impl.http.impl.LoggingHandler] - READ PooledUnsafeDirectByteBuf(ridx: 0, widx: 247, cap: 1024) [/192.168.178.28:53071]:
504F5354202F706169722D76657269667920485454502F312E310D0A486F73743A206F70656E4841422E5F6861702E5F7463702E6C6F63616C0D0A436F6E74656E742D4C656E6774683A203132350D0A436F6E74656E742D547970653A206170706C69636174696F6E2F70616972696E672B746C76380D0A0D0A057893B53F0B7455B65E94123661EB38C5126A848EA6E37C75549C1AEB73462A22C49EA6581077A3A3CFF54FC5813DD6F8B2EB08F8169F2E838BEFF1722E7046B0A4258042A6268A8D2D9CC1DBF366BFD4866DDCCA65ED1AC11756DCF4CA86E141B993B224657D2B587C30E403FEE623B854B811F7DAE6411250060103

15:31:20.828 [ERROR] [a.server.impl.connections.HttpSession] - Excepton encountered while verifying pairing
java.lang.Exception: Unknown user: 0461ED8E-A3EB-4205-9465-DBBA7CF9DD9B
at io.github.hapjava.server.impl.pairing.PairVerificationManager.stage2(PairVerificationManager.java:113) ~[bundleFile:?]
at io.github.hapjava.server.impl.pairing.PairVerificationManager.handle(PairVerificationManager.java:51) ~[bundleFile:?]
at io.github.hapjava.server.impl.connections.HttpSession.handlePairVerify(HttpSession.java:127) [bundleFile:?]
at io.github.hapjava.server.impl.connections.HttpSession.handleRequest(HttpSession.java:56) [bundleFile:?]
at io.github.hapjava.server.impl.connections.ConnectionImpl.doHandleRequest(ConnectionImpl.java:56) [bundleFile:?]
at io.github.hapjava.server.impl.connections.ConnectionImpl.handleRequest(ConnectionImpl.java:49) [bundleFile:?]
at io.github.hapjava.server.impl.http.impl.AccessoryHandler.channelRead0(AccessoryHandler.java:52) [bundleFile:?]
at io.github.hapjava.server.impl.http.impl.AccessoryHandler.channelRead0(AccessoryHandler.java:17) [bundleFile:?]
at io.netty.channel.SimpleChannelInboundHandler.channelRead(SimpleChannelInboundHandler.java:105) [bundleFile:4.1.42.Final]
at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:374) [bundleFile:4.1.42.Final]
at io.netty.channel.AbstractChannelHandlerContext.access$600(AbstractChannelHandlerContext.java:56) [bundleFile:4.1.42.Final]
at io.netty.channel.AbstractChannelHandlerContext$7.run(AbstractChannelHandlerContext.java:365) [bundleFile:4.1.42.Final]
at io.netty.util.concurrent.DefaultEventExecutor.run(DefaultEventExecutor.java:66) [bundleFile:4.1.42.Final]
at io.netty.util.concurrent.SingleThreadEventExecutor$6.run(SingleThreadEventExecutor.java:1044) [bundleFile:4.1.42.Final]
at io.netty.util.internal.ThreadExecutorMap$2.run(ThreadExecutorMap.java:74) [bundleFile:4.1.42.Final]
at io.netty.util.concurrent.FastThreadLocalRunnable.run(FastThreadLocalRunnable.java:30) [bundleFile:4.1.42.Final]
at java.lang.Thread.run(Thread.java:745) [?:1.8.0_65]
15:31:20.835 [INFO ] [ver.impl.http.HomekitClientConnection] - 500 /pair-verify
15:31:20.840 [TRACE] [.server.impl.http.impl.LoggingHandler] - WRITE PooledUnsafeDirectByteBuf(ridx: 0, widx: 101, cap: 232) [/192.168.178.28:53071]:
485454502F312E312035303020496E7465726E616C20536572766572204572726F720D0A436F6E74656E742D4C656E6774683A2031390D0A436F6E6E656374696F6E3A206B6565702D616C6976650D0A0D0A6A6176612E6C616E672E457863657074696F6E

15:31:20.853 [INFO ] [erver.impl.http.impl.AccessoryHandler] - Terminated homekit connection from /192.168.178.28:53071
15:32:25.351 [INFO ] [erver.impl.http.impl.AccessoryHandler] - New homekit connection from /192.168.178.20:52561
15:32:25.352 [TRACE] [.server.impl.http.impl.LoggingHandler] - READ PooledUnsafeDirectByteBuf(ridx: 0, widx: 158, cap: 1024) [/192.168.178.20:52561]:
504F5354202F706169722D76657269667920485454502F312E310D0A486F73743A206F70656E4841422E5F6861702E5F7463702E6C6F63616C0D0A436F6E74656E742D4C656E6774683A2033370D0A436F6E74656E742D547970653A206170706C69636174696F6E2F70616972696E672B746C76380D0A0D0A06010103201730B0E936B4E1749992C6CD7983E020D676573F389FB002C4123C3D77AE6F3F

15:32:25.357 [DEBUG] [.impl.pairing.PairVerificationManager] - Starting pair verification for openHAB
15:32:25.396 [INFO ] [ver.impl.http.HomekitClientConnection] - 200 /pair-verify
15:32:25.402 [TRACE] [.server.impl.http.impl.LoggingHandler] - WRITE PooledUnsafeDirectByteBuf(ridx: 0, widx: 244, cap: 256) [/192.168.178.20:52561]:
485454502F312E3120323030204F4B0D0A436F6E74656E742D747970653A206170706C69636174696F6E2F70616972696E672B746C76380D0A436F6E74656E742D4C656E6774683A203134300D0A436F6E6E656374696F6E3A206B6565702D616C6976650D0A0D0A0601020565F785121C9CF732337FAB04EFDEF21DCA3CD855C2C58C8572A3EC9863E4675FE90A4F897B6B8DC7DE14EBA07EB1C69EF8E9161210A0E4A982E8C0D13DA4B6E5AB8F725C122D8ED7249F523A534120B3E5554706033102ED4D6C3D8CC73F24DFE800F95267EE03203C8456B4D9418F07233B974EDD995D26F762C816730DE2575EE2E7903050201E

15:32:25.538 [TRACE] [.server.impl.http.impl.LoggingHandler] - READ PooledUnsafeDirectByteBuf(ridx: 0, widx: 247, cap: 1024) [/192.168.178.20:52561]:
504F5354202F706169722D76657269667920485454502F312E310D0A486F73743A206F70656E4841422E5F6861702E5F7463702E6C6F63616C0D0A436F6E74656E742D4C656E6774683A203132350D0A436F6E74656E742D547970653A206170706C69636174696F6E2F70616972696E672B746C76380D0A0D0A0578C56483DA316804693AD4EED2C8E85677CCDC27DC79D9064AFE7A0BAED574853318EF248EB205FB2625AD6C2DC4F9D6672D1275DE6342F38C7D866D2D5BEE7183E34C7AD924F4925A3CBC4934BC725A60725920022B688749BCECE5225E65268C9AC02FCF109224AF775528C0F665E1F0403322FC6730A3A7060103

15:32:25.546 [ERROR] [a.server.impl.connections.HttpSession] - Excepton encountered while verifying pairing
java.lang.Exception: Unknown user: 0461ED8E-A3EB-4205-9465-DBBA7CF9DD9B
at io.github.hapjava.server.impl.pairing.PairVerificationManager.stage2(PairVerificationManager.java:113) ~[bundleFile:?]
at io.github.hapjava.server.impl.pairing.PairVerificationManager.handle(PairVerificationManager.java:51) ~[bundleFile:?]
at io.github.hapjava.server.impl.connections.HttpSession.handlePairVerify(HttpSession.java:127) [bundleFile:?]
at io.github.hapjava.server.impl.connections.HttpSession.handleRequest(HttpSession.java:56) [bundleFile:?]
at io.github.hapjava.server.impl.connections.ConnectionImpl.doHandleRequest(ConnectionImpl.java:56) [bundleFile:?]
at io.github.hapjava.server.impl.connections.ConnectionImpl.handleRequest(ConnectionImpl.java:49) [bundleFile:?]
at io.github.hapjava.server.impl.http.impl.AccessoryHandler.channelRead0(AccessoryHandler.java:52) [bundleFile:?]
at io.github.hapjava.server.impl.http.impl.AccessoryHandler.channelRead0(AccessoryHandler.java:17) [bundleFile:?]
at io.netty.channel.SimpleChannelInboundHandler.channelRead(SimpleChannelInboundHandler.java:105) [bundleFile:4.1.42.Final]
at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:374) [bundleFile:4.1.42.Final]
at io.netty.channel.AbstractChannelHandlerContext.access$600(AbstractChannelHandlerContext.java:56) [bundleFile:4.1.42.Final]
at io.netty.channel.AbstractChannelHandlerContext$7.run(AbstractChannelHandlerContext.java:365) [bundleFile:4.1.42.Final]
at io.netty.util.concurrent.DefaultEventExecutor.run(DefaultEventExecutor.java:66) [bundleFile:4.1.42.Final]
at io.netty.util.concurrent.SingleThreadEventExecutor$6.run(SingleThreadEventExecutor.java:1044) [bundleFile:4.1.42.Final]
at io.netty.util.internal.ThreadExecutorMap$2.run(ThreadExecutorMap.java:74) [bundleFile:4.1.42.Final]
at io.netty.util.concurrent.FastThreadLocalRunnable.run(FastThreadLocalRunnable.java:30) [bundleFile:4.1.42.Final]
at java.lang.Thread.run(Thread.java:745) [?:1.8.0_65]
15:32:25.553 [INFO ] [ver.impl.http.HomekitClientConnection] - 500 /pair-verify
15:32:25.560 [TRACE] [.server.impl.http.impl.LoggingHandler] - WRITE PooledUnsafeDirectByteBuf(ridx: 0, widx: 101, cap: 232) [/192.168.178.20:52561]:
485454502F312E312035303020496E7465726E616C20536572766572204572726F720D0A436F6E74656E742D4C656E6774683A2031390D0A436F6E6E656374696F6E3A206B6565702D616C6976650D0A0D0A6A6176612E6C616E672E457863657074696F6E

15:32:25.573 [INFO ] [erver.impl.http.impl.AccessoryHandler] - Terminated homekit connection from /192.168.178.20:52561

And when i open homekit, nothing happens… but i get repeated this portion in the log:

15:36:30.810 [INFO ] [erver.impl.http.impl.AccessoryHandler] - New homekit connection from /192.168.178.21:49522
15:36:30.812 [TRACE] [.server.impl.http.impl.LoggingHandler] - READ PooledUnsafeDirectByteBuf(ridx: 0, widx: 158, cap: 1024) [/192.168.178.21:49522]:
504F5354202F706169722D76657269667920485454502F312E310D0A486F73743A206F70656E4841422E5F6861702E5F7463702E6C6F63616C0D0A436F6E74656E742D4C656E6774683A2033370D0A436F6E74656E742D547970653A206170706C69636174696F6E2F70616972696E672B746C76380D0A0D0A06010103201D29C0C3D16A44493F4CCB2CB7B54AC8F4110269751E677E54A57C681B068D68

15:36:30.825 [DEBUG] [.impl.pairing.PairVerificationManager] - Starting pair verification for openHAB
15:36:30.905 [INFO ] [ver.impl.http.HomekitClientConnection] - 200 /pair-verify
15:36:30.914 [TRACE] [.server.impl.http.impl.LoggingHandler] - WRITE PooledUnsafeDirectByteBuf(ridx: 0, widx: 244, cap: 256) [/192.168.178.21:49522]:
485454502F312E3120323030204F4B0D0A436F6E74656E742D747970653A206170706C69636174696F6E2F70616972696E672B746C76380D0A436F6E74656E742D4C656E6774683A203134300D0A436F6E6E656374696F6E3A206B6565702D616C6976650D0A0D0A060102056544CE32FD969602E95A0F7AD1E1BEA7B61388BDE49B9069362F238B05112644FFFC20C61C7B0824E6759FCA4515BCF599B353D130E72FD5FCEED132B22B9638617C8695EE01608DF141E177D264988E428808B77084F65F7F6BC74B1D197E2A09C43795CD3F032048BC759BF51E03020D2FC2F32F49D78018EBB66F992C4D84548FCB797E255344

15:36:30.952 [TRACE] [.server.impl.http.impl.LoggingHandler] - READ PooledUnsafeDirectByteBuf(ridx: 0, widx: 247, cap: 1024) [/192.168.178.21:49522]:
504F5354202F706169722D76657269667920485454502F312E310D0A486F73743A206F70656E4841422E5F6861702E5F7463702E6C6F63616C0D0A436F6E74656E742D4C656E6774683A203132350D0A436F6E74656E742D547970653A206170706C69636174696F6E2F70616972696E672B746C76380D0A0D0A05789673C2FA2397C8FC9395670E5BB6F280113F8ADB12EFC088453C4DEFFA82F513D2F6D20D9AE2BA773DEAD72D214E50B62CFACC0F0C64920E592C473D9B33BF2E849A35D6360F29563C5C873A7E9BDE06C7CDCF1E657307F4D172F87A456EE3BE803264F841AB7C893007D2700593A033B14F332639F93CF6060103

15:36:30.964 [ERROR] [a.server.impl.connections.HttpSession] - Excepton encountered while verifying pairing
java.lang.Exception: Unknown user: 0461ED8E-A3EB-4205-9465-DBBA7CF9DD9B
at io.github.hapjava.server.impl.pairing.PairVerificationManager.stage2(PairVerificationManager.java:113) ~[bundleFile:?]
at io.github.hapjava.server.impl.pairing.PairVerificationManager.handle(PairVerificationManager.java:51) ~[bundleFile:?]
at io.github.hapjava.server.impl.connections.HttpSession.handlePairVerify(HttpSession.java:127) [bundleFile:?]
at io.github.hapjava.server.impl.connections.HttpSession.handleRequest(HttpSession.java:56) [bundleFile:?]
at io.github.hapjava.server.impl.connections.ConnectionImpl.doHandleRequest(ConnectionImpl.java:56) [bundleFile:?]
at io.github.hapjava.server.impl.connections.ConnectionImpl.handleRequest(ConnectionImpl.java:49) [bundleFile:?]
at io.github.hapjava.server.impl.http.impl.AccessoryHandler.channelRead0(AccessoryHandler.java:52) [bundleFile:?]
at io.github.hapjava.server.impl.http.impl.AccessoryHandler.channelRead0(AccessoryHandler.java:17) [bundleFile:?]
at io.netty.channel.SimpleChannelInboundHandler.channelRead(SimpleChannelInboundHandler.java:105) [bundleFile:4.1.42.Final]
at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:374) [bundleFile:4.1.42.Final]
at io.netty.channel.AbstractChannelHandlerContext.access$600(AbstractChannelHandlerContext.java:56) [bundleFile:4.1.42.Final]
at io.netty.channel.AbstractChannelHandlerContext$7.run(AbstractChannelHandlerContext.java:365) [bundleFile:4.1.42.Final]
at io.netty.util.concurrent.DefaultEventExecutor.run(DefaultEventExecutor.java:66) [bundleFile:4.1.42.Final]
at io.netty.util.concurrent.SingleThreadEventExecutor$6.run(SingleThreadEventExecutor.java:1044) [bundleFile:4.1.42.Final]
at io.netty.util.internal.ThreadExecutorMap$2.run(ThreadExecutorMap.java:74) [bundleFile:4.1.42.Final]
at io.netty.util.concurrent.FastThreadLocalRunnable.run(FastThreadLocalRunnable.java:30) [bundleFile:4.1.42.Final]
at java.lang.Thread.run(Thread.java:745) [?:1.8.0_65]
15:36:30.979 [INFO ] [ver.impl.http.HomekitClientConnection] - 500 /pair-verify
15:36:30.987 [TRACE] [.server.impl.http.impl.LoggingHandler] - WRITE PooledUnsafeDirectByteBuf(ridx: 0, widx: 101, cap: 232) [/192.168.178.21:49522]:
485454502F312E312035303020496E7465726E616C20536572766572204572726F720D0A436F6E74656E742D4C656E6774683A2031390D0A436F6E6E656374696F6E3A206B6565702D616C6976650D0A0D0A6A6176612E6C616E672E457863657074696F6E

15:36:31.005 [INFO ] [erver.impl.http.impl.AccessoryHandler] - Terminated homekit connection from /192.168.178.21:49522

hmm.im really puzzled.
what i see:

  • you dont have network issue, ports are not blocked

  • you have 2 clients connected to openHAB successfully (2 users in homekit.json). so, if you have only 1 iphone then it must be different homes in the home app. maybe some old homes.

  • then 3rd client (home in home app) tries to connect. the client believes that was already connected with openHAB but openHAB says “unknown client”. this is usually the case when home app was already connected to openHAB and then one did the “clearPairings”.

what i dont what are the first 2 clients are and what is the 3rd one.
please double check that you have only one version of homekit binding running.
openhab> list | grep Homekit

the only option i have is reset and try again from the beginning.

  • delete all homes from home app
  • restart iphone
  • pair it again (add new home, enter the pin, etc).

Hello Eugen,
i have deleted everything and can see the shutters now. But unfortunately i can’t open or close them now… Log output:
2020-06-19 12:26:41.562 [ERROR] [fxcom.internal.handler.RFXComHandler] - Transmitting error

org.openhab.binding.rfxcom.internal.exceptions.RFXComUnsupportedChannelException: Channel shutter does not accept 100

at org.openhab.binding.rfxcom.internal.messages.RFXComRfyMessage.convertFromState(RFXComRfyMessage.java:177) ~[bundleFile:?]

at org.openhab.binding.rfxcom.internal.handler.RFXComHandler.handleCommand(RFXComHandler.java:82) [bundleFile:?]

at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[?:1.8.0_65]

at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) ~[?:1.8.0_65]

at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[?:1.8.0_65]

at java.lang.reflect.Method.invoke(Method.java:497) ~[?:1.8.0_65]

at org.eclipse.smarthome.core.internal.common.AbstractInvocationHandler.invokeDirect(AbstractInvocationHandler.java:152) [bundleFile:?]

at org.eclipse.smarthome.core.internal.common.InvocationHandlerSync.invoke(InvocationHandlerSync.java:59) [bundleFile:?]

at com.sun.proxy.$Proxy170.handleCommand(Unknown Source) [?:?]

at org.eclipse.smarthome.core.thing.internal.profiles.ProfileCallbackImpl.handleCommand(ProfileCallbackImpl.java:74) [bundleFile:?]

at org.eclipse.smarthome.core.thing.internal.profiles.SystemDefaultProfile.onCommandFromItem(SystemDefaultProfile.java:48) [bundleFile:?]

at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[?:1.8.0_65]

at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) ~[?:1.8.0_65]

at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[?:1.8.0_65]

at java.lang.reflect.Method.invoke(Method.java:497) ~[?:1.8.0_65]

at org.eclipse.smarthome.core.internal.common.AbstractInvocationHandler.invokeDirect(AbstractInvocationHandler.java:152) [bundleFile:?]

at org.eclipse.smarthome.core.internal.common.Invocation.call(Invocation.java:52) [bundleFile:?]

at java.util.concurrent.FutureTask.run(FutureTask.java:266) [?:1.8.0_65]

at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142) [?:1.8.0_65]

at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) [?:1.8.0_65]

at java.lang.Thread.run(Thread.java:745) [?:1.8.0_65]

2020-06-19 12:26:41.571 [TRACE] [server.impl.http.impl.LoggingHandler] - WRITE PooledUnsafeDirectByteBuf(ridx: 0, widx: 174, cap: 256) [/192.168.178.20:55611]:

9C00038C40751425733BFD6F58575171F234AED1A9E002402CE7CF93D740D1B3F334610553A7501015B6B84855859F4C4257710CB2C6CC3873DD08878C520C5242030C7B6FAB95FA1FB74F66904F0AEF9F768C5394391BC9B37F8CAFB8ACB24B11108D7E96484754B10BAE61C5F43555A48B3D262093F7B0F889B183BAC86887504E25022F8B1130A8A5DD510B148D6CB83AACDDD81D7A76B263D139681EF77FB67AF82906230D1489EF56B7F09E

I think i’ll let openHAB be and look into HASS or IOBroker…

Hi Guys,

today i updated to Openhab 3.0 and still was facing problems with Homekit Pairing!
But i’ve done it twice today :smiley:

My Steps:

  1. Delete Homekit in Openhab & Delete OH-Bridge from the IOS HOME App

  2. Stop Openhab
    sudo systemctl stop openhab

  3. Delete all Homekit Files
    sudo find / -name homekit*
    (delete all Homekit related files, had some “lib/openhab2” files too)
    rm -r /var/lib/openhab/config/org/openhab/homekit.config
    rm -r /var/lib/openhab/jsondb/homekit.json
    and so on…

  4. Delete Cache
    sudo openhab-cli clean-cache

  5. Delete all Homekit Backups
    /var/lib/openhab/jsondb/backup
    rm -r 1608xxxxxxxx–homekit.json

  6. Start Openhab
    sudo systemctl start openhab

  7. Install Homekit

  8. Setup Homekit and Restart OH

  9. Get into Console / Karaf
    sudo ssh openhab@localhost -p 8101

  10. Check if Homekit is running (Console)
    list | grep Homekit
    homekit (gives you Usage Info)

  11. Optional: Check for Homekit Items (Console)
    openhab:homekit list

  12. Set Trace (Console)
    log:set TRACE io.github.hapjava

  13. Check the Log (most Important Step) (Console)
    log:tail io.github.hapjava (Keep the Window open)

  14. Home.app add OH Bridge
    you should see some stuff in the Log/Console

  15. Check Error in Log
    i was getting *“Terminated homekit connection from… Bad Client Credentials” all the time

  16. The current Pin didn’t work and i tried all my previous Pins -> SUCCESS
    then i missclicked in the OH homekit settings and pressed save with another Pin and i lost the connection again :smiley: damn

and did it again, but without uninstalling Homekit, maybe that works too.

  1. Delete Bridge on IOS
  2. Stop Openhab
  3. Delete every Homekit File (incl. Backups)
  4. start Openhab
  5. Console -> Clear Pairings
    openhab:homekit clearPairings
  6. setup a new Pin and check if IP Address is correct ( lost it twice )
  7. save and check nano /var/lib/openhab/config/org/openhab/homekit.config
    looks like this
:org.apache.felix.configadmin.revision:=L"3"
networkInterface="192.168.xxx.xxx"
service.pid="org.openhab.homekit"
  1. Pair again
  2. enjoy (the current Pin in the Setting works for me now )

So keep your old PINs in mind :smiley:

openhab is using old backup stuff, even when you reinstall homekit as you can see here, so delete them. ( Step 4 )

4 Likes

thank you for the detailed and well structured report/instruction. almost ready for copy&paste into the documentation. and good point regarding the backup files!

btw, i have just committed the support for QR code based pairing. So maybe soon nobody would need to remember the PIN.

1 Like

Hi guys,
i have some problems with homekit because from log file, all devices have “none” value in the “serial number” field. Is it possible to value serial number field?

I’m trying to import all devices from openhab to homeassistant (follow this link timestamp_custom undefined · Issue #3412 · home-assistant/core · GitHub)

Thanks a lot,
Alessandro

Hey is it possible to use that QR code already?
Yesterday I could organize all my Innogy shutters in openhab with HomeKit but than there was a connection problem so I must remove it ou of HomeKit and I try to add it again but now its not possible to add it to HomeKit.
It always say the device could not be added. The device is not compatible.
(in German: Gerät kann nicht hinzugefügt werden. Gerät ist nicht kompatibel)
I deleted all the files and refreshed the cache and so on but it doesn’t help me.
I already try to purge openhab and install it again.
Doesn’t help me either.
I can do this: openhab> openhab:homekit list
and than I can see my shutters but I can not connect the bridge to my iPhone.
So now my last hope is the QR code :smiley:
Hopefully this is possible.

Thanks a lot,
monasteria

hey,

now, QR code pairing is not available yet.
strange that it was working before and stopped working now.
can you try to create a new “home” in the home app and pair it with openhab?

Hey already did this.
but thanks for the answer.
I simply have no idea anymore maybe I need to delete it again. I will try that again :slight_smile:

Update:
I removed my home.
I deleted again openhab.
I purged that too.
So I can start fresh.
I installed everything and configured all shutters and all stuff that is needed for HomeKit.
But still got the info its not possible to connect.

But anyways thanks for the try :slight_smile:

can you try with only one accessory. the simple one, something like light.

I tried all your suggested steps above, however, HomeKit is keeping adding openHAB as a device not as a bridge. I‘m getting out of ideas. I removed my complete home from HomeKit, made several iterations of the recommendations above, with no success. Would be grateful for any guidance! I‘m pretty new to openHAB and did quite some progress on other stuff, so things start working for me but I got completely stuck with HomeKit.

do you have any items tag with homekit tag? what you see in home app?
if you have access to karaf console, what you get if you execute there

homekit list

Yes, I‘ve one WeMo Outlet tagged with HomeKit metadata. The list command in karaf returns nothing.image

I believe this is the bridge itself not the „device“. You need to delete the bridge in Homekit, Clear the pairings via Karaf (and select a new pin in OH). Then you should be able again to add the homekit-bridge.

if karaf returns nothing then the item is not tagged correctly. the list in the karaf is the list home app receives.
check your tagging

Thank you for your willingness to help!
I removed the tagging from the WeMo and followed the whole remove, clean-up and restart procedure described above again. I found that I left out one part of step 8 which is another restart of OH (setup HomeKit and restart OH). Which of both actions (or both?) did the trick I don’t know, but now I could add OH as Bridge (and not as device). It also displayed for a while the temperature of the Netatmo sensor in HomeKit, however I messed that up again by trying to add another temperature sensor (this time outside temp) to the game.
Is it necessary to do the whole procedure after every change in the metadata tagging? Just changing the metadata appears not to translate to what karaf reports with the list command.

Definetly no need for a restart after every change!

If you have a working „Thing/Item“ in Homekit just add one after the one. Most important is deleting the bridge in HK and waiting a bit. Clearing the pairings and then readding the bridge with one working item and continue.