Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

Sign up for GitHub

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

Jump to bottom

14.3.0.2 on ESP32 breaks matter support with Google Home #22378

Closed
9 of 14 tasks
maltic42 opened this issue Oct 28, 2024 · 10 comments · Fixed by #22384
Closed
9 of 14 tasks

14.3.0.2 on ESP32 breaks matter support with Google Home #22378

maltic42 opened this issue Oct 28, 2024 · 10 comments · Fixed by #22384

Comments

@maltic42
Copy link

PROBLEM DESCRIPTION

With Tasmota 14.3.0.2 on ESP32 (virtual) matter devices can be commissioned but stay as offline in Google Home. The device works in Homekit. In 14.3.0 the same configuration makes no problems at all, devices are commissioned and are then working.

REQUESTED INFORMATION

Make sure your have performed every step and checked the applicable boxes before submitting your issue. Thank you!

  • Read the Contributing Guide and Policy and the Code of Conduct
  • Searched the problem in issues
  • Searched the problem in discussions
  • Searched the problem in the docs
  • Searched the problem in the chat
  • Device used (e.g., Sonoff Basic): nothing else
  • Tasmota binary firmware version number used: 14.3.0.2
    • Pre-compiled
    • Self-compiled
  • Flashing tools used: web installer w/ Chrome
  • Provide the output of command: Backlog Template; Module; GPIO 255:
  Configuration output here:
19:33:22.110 RSL: RESULT = {"NAME":"ESP32-DevKit","GPIO":[1,1,1,1,1,1,1,1,1,1,1,1,1,1,1,1,0,1,1,1,0,1,1,1,0,0,0,0,1,1,1,1,1,0,0,1],"FLAG":0,"BASE":1}
19:33:22.334 RSL: RESULT = {"Module":{"1":"ESP32-DevKit"}}
19:33:22.541 RSL: RESULT = {"GPIO0":{"0":"None"},"GPIO1":{"0":"None"},"GPIO2":{"0":"None"},"GPIO3":{"0":"None"},"GPIO4":{"0":"None"},"GPIO5":{"0":"None"},"GPIO6":{"0":"None"},"GPIO7":{"0":"None"},"GPIO8":{"0":"None"},"GPIO9":{"0":"None"},"GPIO10":{"0":"None"},"GPIO11":{"0":"None"},"GPIO12":{"0":"None"},"GPIO13":{"0":"None"},"GPIO14":{"0":"None"},"GPIO15":{"0":"None"},"GPIO16":{"0":"None"},"GPIO17":{"0":"None"},"GPIO18":{"0":"None"},"GPIO19":{"0":"None"},"GPIO20":{"0":"None"},"GPIO21":{"0":"None"},"GPIO22":{"0":"None"},"GPIO23":{"0":"None"},"GPIO24":{"0":"None"},"GPIO25":{"0":"None"},"GPIO26":{"0":"None"},"GPIO27":{"0":"None"},"GPIO32":{"0":"None"},"GPIO33":{"0":"None"},"GPIO34":{"0":"None"},"GPIO35":{"0":"None"},"GPIO36":{"0":"None"},"GPIO37":{"0":"None"},"GPIO38":{"0":"None"},"GPIO39":{"0":"None"}}
  • If using rules, provide the output of this command: Backlog Rule1; Rule2; Rule3:
  Rules output here:
  • Provide the output of this command: Status 0:
  STATUS 0 output here:
19:33:57.806 RSL: STATUS = {"Status":{"Module":1,"DeviceName":"Tasmota","FriendlyName":["Tasmota"],"Topic":"tasmota_9A4B90","ButtonTopic":"0","Power":"0","PowerLock":"0","PowerOnState":3,"LedState":1,"LedMask":"FFFF","SaveData":1,"SaveState":1,"SwitchTopic":"0","SwitchMode":[0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0],"ButtonRetain":0,"SwitchRetain":0,"SensorRetain":0,"PowerRetain":0,"InfoRetain":0,"StateRetain":0,"StatusRetain":0}}
19:33:57.862 RSL: STATUS1 = {"StatusPRM":{"Baudrate":115200,"SerialConfig":"8N1","GroupTopic":"tasmotas","OtaUrl":"http://ota.tasmota.com/tasmota32/tasmota32.bin","RestartReason":"Software reset CPU","Uptime":"0T00:19:26","StartupUTC":"2024-10-28T18:14:31","Sleep":50,"CfgHolder":4617,"BootCount":7,"BCResetTime":"2024-10-28T19:10:33","SaveCount":19}}
19:33:57.900 RSL: STATUS2 = {"StatusFWR":{"Version":"14.3.0.2(27db263-tasmota32)","BuildDateTime":"2024-10-28T13:08:59","Core":"3_1_0","SDK":"5.3.1.241016","CpuFrequency":160,"Hardware":"ESP32-D0WDQ6 v1.1","CR":"401/699"}}
19:33:57.917 RSL: STATUS3 = {"StatusLOG":{"SerialLog":4,"WebLog":2,"MqttLog":0,"SysLog":0,"LogHost":"","LogPort":514,"SSId":["TOAD",""],"TelePeriod":300,"Resolution":"558180C0","SetOption":["00008009","2805C80001000600003C5A0A192800000000","00000080","00006000","00004000","00000020"]}}
19:33:57.955 RSL: STATUS4 = {"StatusMEM":{"ProgramSize":2002,"Free":877,"Heap":99,"StackLowMark":2,"PsrMax":0,"PsrFree":0,"ProgramFlashSize":4096,"FlashSize":4096,"FlashChipId":"16405E","FlashFrequency":40,"FlashMode":"DIO","Features":["0809","9F9AD7DF","0015A001","B7F7BFCF","05DA9BC4","E0360DC7","480840D2","20200000","D4BC482D","810A80F1","00000014"],"Drivers":"1,2,!3,!4,!5,7,!8,9,10,11,12,!14,!16,!17,!20,!21,!24,26,!27,29,!34,!35,38,50,52,!59,!60,62,!63,!66,!67,!68,!73,!75,82,!86,!87,!88,!121","Sensors":"1,2,3,5,6,7,8,9,10,11,12,13,14,15,17,18,19,20,21,22,26,31,34,37,39,40,42,43,45,51,52,55,56,58,59,64,66,67,74,85,92,95,98,103,105,109,127","I2CDriver":"7,8,9,10,11,12,13,14,15,17,18,20,24,29,31,36,41,42,44,46,48,58,62,65,69,76,77,82,89"}}
19:33:58.029 RSL: STATUS5 = {"StatusNET":{"Hostname":"tasmota-9A4B90-2960","IPAddress":"192.168.188.37","Gateway":"192.168.188.1","Subnetmask":"255.255.255.0","DNSServer1":"192.168.188.1","DNSServer2":"2003:f2:9f3f:b400:6b4:feff:fe57:302c","Mac":"08:3A:8D:9A:4B:90","IP6Global":"2003:f2:9f3f:b400:a3a:8dff:fe9a:4b90","IP6Local":"fe80::a3a:8dff:fe9a:4b90","Ethernet":{"Hostname":"","IPAddress":"0.0.0.0","Gateway":"0.0.0.0","Subnetmask":"0.0.0.0","DNSServer1":"192.168.188.1","DNSServer2":"2003:f2:9f3f:b400:6b4:feff:fe57:302c","Mac":"00:00:00:00:00:00","IP6Global":"","IP6Local":""},"Webserver":2,"HTTP_API":1,"WifiConfig":4,"WifiPower":16.0}}
19:33:58.090 RSL: STATUS6 = {"StatusMQT":{"MqttHost":"","MqttPort":1883,"MqttClientMask":"DVES_%06X","MqttClient":"DVES_9A4B90","MqttUser":"DVES_USER","MqttCount":0,"MAX_PACKET_SIZE":1200,"KEEPALIVE":30,"SOCKET_TIMEOUT":4}}
19:33:58.107 RSL: STATUS7 = {"StatusTIM":{"UTC":"2024-10-28T18:33:58Z","Local":"2024-10-28T19:33:58","StartDST":"2024-03-31T02:00:00","EndDST":"2024-10-27T03:00:00","Timezone":"+01:00","Sunrise":"07:31","Sunset":"17:35"}}
19:33:58.135 RSL: STATUS10 = {"StatusSNS":{"Time":"2024-10-28T19:33:58"}}
19:33:58.157 RSL: STATUS11 = {"StatusSTS":{"Time":"2024-10-28T19:33:58","Uptime":"0T00:19:27","UptimeSec":1167,"Heap":104,"SleepMode":"Dynamic","Sleep":50,"LoadAvg":20,"MqttCount":0,"Berry":{"HeapUsed":27,"Objects":313},"Wifi":{"AP":1,"SSId":"TOAD","BSSId":"04:B4:FE:57:30:2E","Channel":1,"Mode":"HT40","RSSI":76,"Signal":-62,"LinkCount":1,"Downtime":"0T00:00:05"}}}
  • Set weblog to 4 and then, when you experience your issue, provide the output of the Console log:
  Console output here:
19:35:59.539 HTP: Tools
19:36:00.578 HTP: Console
19:36:03.650 CMD: WebLog 4
19:36:03.651 SRC: WebConsole from 192.168.188.90
19:36:03.653 CMD: Grp 0, Cmd 'WEBLOG', Idx 1, Len 1, Pld 4, Data '4'
19:36:03.656 RSL: RESULT = {"WebLog":4}
19:36:07.304 HTP: Tools
19:36:08.435 HTP: Main Menu
19:36:08.617 BRY: GC from 32412 to 22088 bytes, objects freed 160/265 (in 2 ms) - slots from 495/562 to 242/562
19:36:08.643 BRY: GC from 32372 to 22104 bytes, objects freed 87/262 (in 3 ms) - slots from 262/562 to 241/562
19:36:08.666 BRY: GC from 32396 to 22121 bytes, objects freed 90/261 (in 2 ms) - slots from 253/562 to 237/562
19:36:08.691 BRY: GC from 32422 to 22144 bytes, objects freed 89/262 (in 2 ms) - slots from 253/562 to 241/562
19:36:11.182 BRY: GC from 32422 to 22166 bytes, objects freed 90/265 (in 2 ms) - slots from 345/562 to 241/562
19:36:11.206 BRY: GC from 32457 to 22136 bytes, objects freed 94/262 (in 2 ms) - slots from 261/562 to 241/562
19:36:11.233 BRY: GC from 32391 to 22080 bytes, objects freed 86/262 (in 2 ms) - slots from 257/562 to 241/562
19:36:11.259 BRY: GC from 32388 to 22056 bytes, objects freed 93/262 (in 2 ms) - slots from 257/562 to 241/562
19:36:13.839 BRY: GC from 32363 to 22204 bytes, objects freed 95/265 (in 3 ms) - slots from 356/562 to 241/562
19:36:13.864 BRY: GC from 32532 to 22158 bytes, objects freed 95/262 (in 2 ms) - slots from 261/562 to 241/562
19:36:13.889 BRY: GC from 32474 to 22104 bytes, objects freed 88/262 (in 2 ms) - slots from 257/562 to 241/562
19:36:13.914 BRY: GC from 32378 to 22096 bytes, objects freed 90/262 (in 2 ms) - slots from 257/562 to 241/562
19:36:16.511 BRY: GC from 32432 to 22040 bytes, objects freed 104/262 (in 2 ms) - slots from 365/562 to 241/562
19:36:16.531 BRY: GC from 32317 to 22182 bytes, objects freed 88/262 (in 2 ms) - slots from 253/562 to 241/562
19:36:16.556 BRY: GC from 32488 to 22130 bytes, objects freed 89/262 (in 2 ms) - slots from 257/562 to 241/562
19:36:16.580 BRY: GC from 32390 to 22122 bytes, objects freed 90/262 (in 2 ms) - slots from 257/562 to 241/562
19:36:17.069 HTP: Tools
19:36:17.852 WIF: Checking connection...
19:36:19.408 HTP: Console
19:36:28.488 MTR: UDP received from [fe80::9:50d3:3f9b:ea82]:58935
19:36:28.499 BRY: GC from 32389 to 21388 bytes, objects freed 176/238 (in 2 ms) - slots from 466/562 to 240/562
19:36:28.502 MTR: >Received  (     0) PBKDFParamRequest from [fe80::9:50d3:3f9b:ea82]:58935
19:36:28.527 MTR: +Session   ( 54407) from '[fe80::9:50d3:3f9b:ea82]:58935'
19:36:28.542 MTR: <Replied   (     0) PBKDFParamResponse
19:36:28.549 MTR: error sending packet to '[fe80::9:50d3:3f9b:ea82]:58935'
19:36:28.852 MTR: .          Resending packet id=52598177
19:36:28.855 MTR: error sending packet to '[fe80::9:50d3:3f9b:ea82]:58935'
19:36:28.908 MTR: UDP received from [fe80::9:50d3:3f9b:ea82]:58935
19:36:28.914 MTR: .          Duplicate unencrypted message = 72918730 ref = 72918730
19:36:29.170 MTR: .          Resending packet id=52598177
19:36:29.173 MTR: error sending packet to '[fe80::9:50d3:3f9b:ea82]:58935'
19:36:29.204 MTR: UDP received from [fe80::9:50d3:3f9b:ea82]:58935
19:36:29.210 MTR: .          Duplicate unencrypted message = 72918730 ref = 72918730
19:36:29.527 MTR: .          Resending packet id=52598177
19:36:29.530 MTR: error sending packet to '[fe80::9:50d3:3f9b:ea82]:58935'
19:36:29.837 MTR: UDP received from [fe80::9:50d3:3f9b:ea82]:58935
19:36:29.843 MTR: .          Duplicate unencrypted message = 72918730 ref = 72918730
19:36:30.128 MTR: .          Resending packet id=52598177
19:36:30.131 MTR: error sending packet to '[fe80::9:50d3:3f9b:ea82]:58935'
19:36:30.792 MTR: UDP received from [fe80::9:50d3:3f9b:ea82]:58935
19:36:30.798 MTR: .          Duplicate unencrypted message = 72918730 ref = 72918730
19:36:30.911 MTR: .          Resending packet id=52598177
19:36:30.914 MTR: error sending packet to '[fe80::9:50d3:3f9b:ea82]:58935'
19:36:31.135 BRY: GC from 31666 to 21932 bytes, objects freed 124/248 (in 3 ms) - slots from 387/562 to 250/562
19:36:32.191 MTR: .          Resending packet id=52598177
19:36:32.194 MTR: error sending packet to '[fe80::9:50d3:3f9b:ea82]:58935'
19:36:34.292 MTR: .          (     0) Unacked packet '[fe80::9:50d3:3f9b:ea82]:58935' msg_id=52598177
19:36:37.845 WIF: Checking connection...
19:36:39.034 MTR: UDP received from [192.168.188.45]:51279
19:36:39.043 MTR: >Received  (     0) PBKDFParamRequest from [192.168.188.45]:51279
19:36:39.059 MTR: +Session   ( 36807) from '[192.168.188.45]:51279'
19:36:39.073 BRY: GC from 32175 to 23811 bytes, objects freed 146/270 (in 2 ms) - slots from 475/562 to 264/562
19:36:39.079 MTR: <Replied   (     0) PBKDFParamResponse
19:36:39.087 MTR: sending packet to '[192.168.188.45]:51279'
19:36:39.237 MTR: UDP received from [192.168.188.45]:51279
19:36:39.243 MTR: .          Removed packet from sending list id=3400374
19:36:39.247 MTR: >Received  (     0) PASE_Pake1 from [192.168.188.45]:51279
19:36:39.872 MTR: <Replied   (     0) PASE_Pake2
19:36:39.878 MTR: New Commissioning (PASE id=36807) from [192.168.188.45]:51279
19:36:39.883 MTR: sending packet to '[192.168.188.45]:51279'
19:36:39.888 MTR: UDP received from [192.168.188.45]:51279
19:36:39.894 MTR: .          Duplicate unencrypted message = 72918732 ref = 72918732
19:36:39.902 BRY: GC from 34096 to 23210 bytes, objects freed 124/259 (in 5 ms) - slots from 351/562 to 251/562
19:36:39.905 MTR: UDP received from [192.168.188.45]:51279
19:36:39.922 MTR: .          Removed packet from sending list id=3400375
19:36:39.925 MTR: >Received  (     0) PASE_Pake3 from [192.168.188.45]:51279
19:36:39.934 MTR: <Replied   (     0) StatusReport
19:36:39.941 MTR: sending packet to '[192.168.188.45]:51279'
19:36:39.943 MTR: add_session local_session_id=36807 initiator_session_id=46633
19:36:41.731 MTR: UDP received from [192.168.188.45]:51279
19:36:41.735 MTR: decode header: local_session_id=36807 message_counter=237612547
19:36:41.741 MTR: >          Decrypted message: protocol_id:1 opcode=1 exchange_id=31255
19:36:41.744 MTR: .          Removed packet from sending list id=42095832
19:36:41.769 MTR: sending packet to '[192.168.188.45]:51279'
19:36:41.773 MTR: UDP received from [192.168.188.45]:51279
19:36:41.777 MTR: decode header: local_session_id=36807 message_counter=237612548
19:36:41.784 MTR: >          Decrypted message: protocol_id:1 opcode=8 exchange_id=31256
19:36:41.807 BRY: GC from 35474 to 26559 bytes, objects freed 74/316 (in 3 ms) - slots from 389/562 to 301/547
19:36:41.811 MTR: >Command   ( 36807) [00]0030/0000 ArmFailSafe 
19:36:41.829 MTR: <Replied   ( 36807) [00]0030/0001 ArmFailSafeResponse
19:36:41.841 MTR: sending packet to '[192.168.188.45]:51279'
19:36:41.854 MTR: <snd       ( 36807) id=42095834 exch=31256 rack=237612548
19:36:41.859 MTR: sending packet to '[192.168.188.45]:51279'
19:36:41.864 MTR: UDP received from [192.168.188.45]:51279
19:36:41.868 MTR: decode header: local_session_id=36807 message_counter=237612549
19:36:41.875 MTR: >          Decrypted message: protocol_id:0 opcode=16 exchange_id=31255
19:36:41.888 MTR: .          Removed packet from sending list id=42095833
19:36:41.890 MTR: .          Removed packet from sending list id=42095833
19:36:41.895 MTR: UDP received from [192.168.188.45]:51279
19:36:41.899 MTR: decode header: local_session_id=36807 message_counter=237612550
19:36:41.915 MTR: >          Decrypted message: protocol_id:0 opcode=16 exchange_id=31255
19:36:41.936 MTR: UDP received from [192.168.188.45]:51279
19:36:41.940 MTR: decode header: local_session_id=36807 message_counter=237612551
19:36:41.947 MTR: >          Decrypted message: protocol_id:1 opcode=8 exchange_id=31257
19:36:41.962 BRY: GC from 36824 to 25802 bytes, objects freed 114/301 (in 3 ms) - slots from 422/547 to 290/547
19:36:41.977 MTR: >Command   ( 36807) [00]0030/0002 SetRegulatoryConfig 
19:36:41.985 MTR: <Replied   ( 36807) [00]0030/0003 SetRegulatoryConfigResponse
19:36:41.000 MTR: <snd       ( 36807) id=42095834 exch=31256 rack=237612548
19:36:42.007 MTR: sending packet to '[192.168.188.45]:51279'
19:36:42.020 MTR: <snd       ( 36807) id=42095835 exch=31257 rack=237612551
19:36:42.025 MTR: sending packet to '[192.168.188.45]:51279'
19:36:42.030 MTR: UDP received from [192.168.188.45]:51279
19:36:42.034 MTR: decode header: local_session_id=36807 message_counter=237612552
19:36:42.041 MTR: >          Decrypted message: protocol_id:0 opcode=16 exchange_id=31256
19:36:42.043 MTR: .          Removed packet from sending list id=42095834
19:36:42.055 MTR: .          Removed packet from sending list id=42095834
19:36:42.061 MTR: UDP received from [192.168.188.45]:51279
19:36:42.066 BRY: GC from 36259 to 26070 bytes, objects freed 138/295 (in 3 ms) - slots from 476/547 to 280/547
19:36:42.080 MTR: decode header: local_session_id=36807 message_counter=237612553
19:36:42.086 MTR: >          Decrypted message: protocol_id:0 opcode=16 exchange_id=31256
19:36:42.239 MTR: UDP received from [192.168.188.45]:51279
19:36:42.244 MTR: decode header: local_session_id=36807 message_counter=237612554
19:36:42.250 MTR: >          Decrypted message: protocol_id:1 opcode=8 exchange_id=31258
19:36:42.271 MTR: >Command   ( 36807) [00]0038/0000 SetUTCTime 
19:36:42.275 MTR: <Replied   ( 36807) Status=0x81 exch=31258
19:36:42.290 MTR: <snd       ( 36807) id=42095835 exch=31257 rack=237612551
19:36:42.296 MTR: sending packet to '[192.168.188.45]:51279'
19:36:42.304 BRY: GC from 36347 to 25795 bytes, objects freed 145/294 (in 3 ms) - slots from 478/547 to 280/547
19:36:42.314 MTR: <snd       ( 36807) id=42095836 exch=31258 rack=237612554
19:36:42.319 MTR: sending packet to '[192.168.188.45]:51279'
19:36:42.324 MTR: UDP received from [192.168.188.45]:51279
19:36:42.328 MTR: decode header: local_session_id=36807 message_counter=237612555
19:36:42.334 MTR: >          Decrypted message: protocol_id:0 opcode=16 exchange_id=31257
19:36:42.347 MTR: .          Removed packet from sending list id=42095835
19:36:42.349 MTR: .          Removed packet from sending list id=42095835
19:36:42.369 MTR: UDP received from [192.168.188.45]:51279
19:36:42.373 MTR: decode header: local_session_id=36807 message_counter=237612556
19:36:42.379 MTR: >          Decrypted message: protocol_id:0 opcode=16 exchange_id=31257
19:36:42.385 MTR: UDP received from [192.168.188.45]:51279
19:36:42.388 MTR: decode header: local_session_id=36807 message_counter=237612557
19:36:42.405 MTR: >          Decrypted message: protocol_id:1 opcode=8 exchange_id=31259
19:36:42.427 MTR: >Command   ( 36807) [00]003E/0002 CertificateChainRequest 
19:36:42.435 MTR: <Replied   ( 36807) [00]003E/0003 CertificateChainResponse
19:36:42.442 BRY: GC from 36051 to 27077 bytes, objects freed 98/314 (in 3 ms) - slots from 442/547 to 297/547
19:36:42.454 MTR: <snd       ( 36807) id=42095836 exch=31258 rack=237612554
19:36:42.460 MTR: sending packet to '[192.168.188.45]:51279'
19:36:42.474 MTR: <snd       ( 36807) id=42095837 exch=31259 rack=237612557
19:36:42.480 MTR: sending packet to '[192.168.188.45]:51279'
19:36:42.485 MTR: UDP received from [192.168.188.45]:51279
19:36:42.489 MTR: decode header: local_session_id=36807 message_counter=237612558
19:36:42.496 MTR: >          Decrypted message: protocol_id:0 opcode=16 exchange_id=31258
19:36:42.498 MTR: .          Removed packet from sending list id=42095836
19:36:42.511 MTR: .          Removed packet from sending list id=42095836
19:36:42.516 MTR: UDP received from [192.168.188.45]:51279
19:36:42.519 MTR: decode header: local_session_id=36807 message_counter=237612559
19:36:42.536 MTR: >          Decrypted message: protocol_id:0 opcode=16 exchange_id=31258
19:36:42.806 MTR: sending packet to '[192.168.188.45]:51279'
19:36:42.811 MTR: UDP received from [192.168.188.45]:51279
19:36:42.819 BRY: GC from 37684 to 28299 bytes, objects freed 118/296 (in 3 ms) - slots from 460/547 to 280/547
19:36:42.821 MTR: decode header: local_session_id=36807 message_counter=237612561
19:36:42.837 MTR: >          Decrypted message: protocol_id:0 opcode=16 exchange_id=31259
19:36:42.840 MTR: .          Removed packet from sending list id=42095837
19:36:42.852 MTR: .          Removed packet from sending list id=42095837
19:36:42.858 MTR: UDP received from [192.168.188.45]:51279
19:36:42.861 MTR: decode header: local_session_id=36807 message_counter=237612562
19:36:42.867 MTR: >          Decrypted message: protocol_id:0 opcode=16 exchange_id=31259
19:36:42.883 MTR: UDP received from [192.168.188.45]:51279
19:36:42.887 MTR: decode header: local_session_id=36807 message_counter=237612563
19:36:42.894 MTR: >          Decrypted message: protocol_id:1 opcode=8 exchange_id=31261
19:36:43.040 MTR: >Command   ( 36807) [00]003E/0000 AttestationRequest 
19:36:43.050 BRY: GC from 39012 to 29552 bytes, objects freed 95/330 (in 3 ms) - slots from 432/547 to 310/547
19:36:43.053 MTR: <Replied   ( 36807) [00]003E/0001 AttestationResponse
19:36:43.079 MTR: <snd       ( 36807) id=42095838 exch=31260 rack=237612560
19:36:43.086 MTR: sending packet to '[192.168.188.45]:51279'
19:36:43.102 MTR: <snd       ( 36807) id=42095839 exch=31261 rack=237612563
19:36:43.107 MTR: sending packet to '[192.168.188.45]:51279'
19:36:43.110 MTR: .          Resending packet id=42095838
19:36:43.114 MTR: sending packet to '[192.168.188.45]:51279'
19:36:43.135 MTR: UDP received from [192.168.188.45]:51279
19:36:43.140 MTR: decode header: local_session_id=36807 message_counter=237612564
19:36:43.146 MTR: >          Decrypted message: protocol_id:0 opcode=16 exchange_id=31260
19:36:43.149 MTR: .          Removed packet from sending list id=42095838
19:36:43.161 MTR: .          Removed packet from sending list id=42095838
19:36:43.166 MTR: UDP received from [192.168.188.45]:51279
19:36:43.170 MTR: decode header: local_session_id=36807 message_counter=237612565
19:36:43.176 MTR: >          Decrypted message: protocol_id:0 opcode=16 exchange_id=31260
19:36:43.192 MTR: UDP received from [192.168.188.45]:51279
19:36:43.196 MTR: decode header: local_session_id=36807 message_counter=237612566
19:36:43.202 MTR: >          Decrypted message: protocol_id:1 opcode=8 exchange_id=31262
19:36:43.210 BRY: GC from 39826 to 26004 bytes, objects freed 129/280 (in 4 ms) - slots from 420/547 to 274/547
19:36:43.240 MTR: >Command   ( 36807) [00]0030/0000 ArmFailSafe 
19:36:43.248 MTR: <Replied   ( 36807) [00]0030/0001 ArmFailSafeResponse
19:36:43.265 MTR: <snd       ( 36807) id=42095839 exch=31261 rack=237612563
19:36:43.271 MTR: sending packet to '[192.168.188.45]:51279'
19:36:43.283 BRY: GC from 36299 to 27780 bytes, objects freed 127/294 (in 3 ms) - slots from 472/547 to 279/547
19:36:43.289 MTR: <snd       ( 36807) id=42095840 exch=31262 rack=237612566
19:36:43.294 MTR: sending packet to '[192.168.188.45]:51279'
19:36:43.299 MTR: UDP received from [192.168.188.45]:51279
19:36:43.303 MTR: decode header: local_session_id=36807 message_counter=237612567
19:36:43.320 MTR: >          Decrypted message: protocol_id:0 opcode=16 exchange_id=31261
19:36:43.322 MTR: .          Removed packet from sending list id=42095839
19:36:43.334 MTR: .          Removed packet from sending list id=42095839
19:36:43.346 MTR: UDP received from [192.168.188.45]:51279
19:36:43.350 MTR: decode header: local_session_id=36807 message_counter=237612568
19:36:43.357 MTR: >          Decrypted message: protocol_id:0 opcode=16 exchange_id=31260
19:36:43.362 MTR: UDP received from [192.168.188.45]:51279
19:36:43.366 MTR: decode header: local_session_id=36807 message_counter=237612569
19:36:43.382 MTR: >          Decrypted message: protocol_id:0 opcode=16 exchange_id=31261
19:36:43.387 MTR: UDP received from [192.168.188.45]:51279
19:36:43.391 MTR: decode header: local_session_id=36807 message_counter=237612570
19:36:43.397 MTR: >          Decrypted message: protocol_id:0 opcode=16 exchange_id=31262
19:36:43.410 MTR: .          Removed packet from sending list id=42095840
19:36:44.559 MTR: UDP received from [192.168.188.45]:51279
19:36:44.562 MTR: decode header: local_session_id=36807 message_counter=237612571
19:36:44.569 MTR: >          Decrypted message: protocol_id:1 opcode=8 exchange_id=31263
19:36:44.598 BRY: GC from 38057 to 26257 bytes, objects freed 106/312 (in 3 ms) - slots from 423/547 to 297/547
19:36:44.600 MTR: <Ack*      ( 36807) ack=237612571 id=42095841 
19:36:44.616 MTR: sending packet to '[192.168.188.45]:51279'
19:36:44.946 MTR: >Command   ( 36807) [00]003E/0004 CSRRequest 
19:36:44.955 MTR: <Replied   ( 36807) [00]003E/0005 CSRResponse
19:36:44.972 MTR: <snd       ( 36807) id=42095842 exch=31263 rack=237612571
19:36:44.978 MTR: sending packet to '[192.168.188.45]:51279'
19:36:44.988 MTR: UDP received from [192.168.188.45]:51279
19:36:44.992 MTR: decode header: local_session_id=36807 message_counter=237612572
19:36:44.999 MTR: >          Decrypted message: protocol_id:0 opcode=16 exchange_id=31263
19:36:45.001 MTR: .          Removed packet from sending list id=42095842
19:36:45.017 MTR: UDP received from [192.168.188.45]:51279
19:36:45.021 MTR: decode header: local_session_id=36807 message_counter=237612573
19:36:45.032 BRY: GC from 36525 to 24873 bytes, objects freed 121/275 (in 4 ms) - slots from 410/547 to 268/547
19:36:45.034 MTR: >          Decrypted message: protocol_id:1 opcode=8 exchange_id=31264
19:36:45.065 MTR: >Command   ( 36807) [00]003E/000B AddTrustedRootCertificate 
19:36:45.069 MTR: <Replied   ( 36807) OK exch=31264
19:36:45.083 MTR: <snd       ( 36807) id=42095843 exch=31264 rack=237612573
19:36:45.090 MTR: sending packet to '[192.168.188.45]:51279'
19:36:45.239 MTR: UDP received from [192.168.188.45]:51279
19:36:45.243 MTR: decode header: local_session_id=36807 message_counter=237612574
19:36:45.251 MTR: >          Decrypted message: protocol_id:1 opcode=8 exchange_id=31265
19:36:45.263 BRY: GC from 35136 to 26178 bytes, objects freed 120/288 (in 3 ms) - slots from 431/547 to 274/547
19:36:45.291 BRY: GC from 36852 to 30145 bytes, objects freed 46/340 (in 2 ms) - slots from 381/547 to 311/547
19:36:45.296 MTR: AddNoc Args=1 = {0 = 153001010124020137032714D3D4FDFB088C74ED2415021826043D96B22E2405003706241502261124D5E58718240701240801300941047C885B44C6EF669BD853B7F5EF1A01E3C704545F5CC9A0C79DD49234DF675FD2AF2F0FA00BD34F494F8AF474AF8C8CC644E4286F1BC36B993E2BDF94336AEC91370A3501280118240201360304020401183004140BC31B8076F3080ABD087A74A7B2901EF89D8E5330051463F23D618C6F704DA5B04D3F971891DE29E54BD218300B408C114D30DF293D1D3230D32D98FF38E2698C89D86137179927835A42F91BE30DC6083E8855D51298648BBA45E4C91AFCF059317A967B228D3D6BFCFB40A2574218, 1 = , 2 = 112196F4974A50539189E8ABB44125AE, 3 = 5362999403222020643U, 4 = 4996U}
19:36:45.388 BRY: GC from 40467 to 34293 bytes, objects freed 34/418 (in 2 ms) - slots from 416/547 to 362/547
19:36:45.409 MTR: +Fabric    fab='0000000000000002' vendorid=Apple Keychain
19:36:45.413 MTR: >Command   ( 36807) [00]003E/0006 AddNOC 
19:36:45.421 MTR: <Replied   ( 36807) [00]003E/0008 NOCResponse
19:36:45.435 MTR: <snd       ( 36807) id=42095843 exch=31264 rack=237612573
19:36:45.442 MTR: sending packet to '[192.168.188.45]:51279'
19:36:45.455 MTR: <snd       ( 36807) id=42095844 exch=31265 rack=237612574
19:36:45.460 MTR: sending packet to '[192.168.188.45]:51279'
19:36:45.465 MTR: UDP received from [192.168.188.45]:51279
19:36:45.469 MTR: decode header: local_session_id=36807 message_counter=237612575
19:36:45.476 MTR: >          Decrypted message: protocol_id:0 opcode=16 exchange_id=31264
19:36:45.478 MTR: .          Removed packet from sending list id=42095843
19:36:45.491 MTR: .          Removed packet from sending list id=42095843
19:36:45.502 BRY: GC from 44544 to 27060 bytes, objects freed 230/308 (in 5 ms) - slots from 519/577 to 297/577
19:36:45.508 RSL: RESULT = {"Matter":{"Commissioning":0}}
19:36:45.513 MTR: calling mdns.remove_service(_matterc, _udp, C0FE41241073477A, 083A8D9A4B90)
19:36:45.525 MTR: remove mDNS on wifi 'C0FE41241073477A'
19:36:45.532 MTR: Operational Discovery node = 839977E7D913F18A-0000000087E5D524
19:36:45.537 MTR: adding mDNS on wifi '839977E7D913F18A-0000000087E5D524' ptr to `083A8D9A4B90.local`
19:36:45.540 MTR: adding subtype: _I839977E7D913F18A
19:36:45.563 MTR: UDP received from [192.168.188.45]:51279
19:36:45.568 MTR: decode header: local_session_id=36807 message_counter=237612576
19:36:45.574 MTR: >          Decrypted message: protocol_id:0 opcode=16 exchange_id=31264
19:36:45.580 MTR: UDP received from [192.168.188.45]:51279
19:36:45.584 MTR: decode header: local_session_id=36807 message_counter=237612574
19:36:45.597 MTR: .          Duplicate encrypted message = 237612574 counter=237612576
19:36:45.602 MTR: UDP received from [192.168.188.45]:51279
19:36:45.605 MTR: decode header: local_session_id=36807 message_counter=237612577
19:36:45.622 MTR: >          Decrypted message: protocol_id:0 opcode=16 exchange_id=31265
19:36:45.624 MTR: .          Removed packet from sending list id=42095844
19:36:46.859 MTR: UDP received from [fe80::9:50d3:3f9b:ea82]:58935
19:36:46.869 MTR: >Received  (     0) CASE_Sigma1 from [fe80::9:50d3:3f9b:ea82]:58935
19:36:46.885 BRY: GC from 37388 to 27787 bytes, objects freed 102/309 (in 3 ms) - slots from 421/577 to 294/547
19:36:46.892 MTR: SEARCHING: destinationId=F22DA257D48484B1699573438241E84BAF1A455EA358AAAB92A1315F8FD73B1F
19:36:46.926 MTR: SIGMA1: candidateDestinationId=F22DA257D48484B1699573438241E84BAF1A455EA358AAAB92A1315F8FD73B1F
19:36:46.931 MTR: +Session   (  8928) from '[fe80::9:50d3:3f9b:ea82]:58935'
19:36:47.298 BRY: GC from 38214 to 29119 bytes, objects freed 129/317 (in 4 ms) - slots from 416/547 to 293/547
19:36:47.312 MTR: <Replied   (     0) CASE_Sigma2
19:36:47.317 MTR: New Connection (CASE id=8928) from [fe80::9:50d3:3f9b:ea82]:58935
19:36:47.323 MTR: error sending packet to '[fe80::9:50d3:3f9b:ea82]:58935'
19:36:47.356 MTR: UDP received from [fe80::9:50d3:3f9b:ea82]:58935
19:36:47.363 MTR: .          Duplicate unencrypted message = 72918734 ref = 72918734
19:36:47.656 MTR: .          Resending packet id=228114031
19:36:47.659 MTR: error sending packet to '[fe80::9:50d3:3f9b:ea82]:58935'
19:36:48.000 MTR: .          Resending packet id=228114031
19:36:48.004 MTR: error sending packet to '[fe80::9:50d3:3f9b:ea82]:58935'
19:36:48.150 MTR: UDP received from [fe80::9:50d3:3f9b:ea82]:58935
19:36:48.157 MTR: .          Duplicate unencrypted message = 72918734 ref = 72918734
19:36:48.341 MTR: .          Resending packet id=228114031
19:36:48.345 MTR: error sending packet to '[fe80::9:50d3:3f9b:ea82]:58935'
19:36:48.867 MTR: .          Resending packet id=228114031
19:36:48.870 MTR: error sending packet to '[fe80::9:50d3:3f9b:ea82]:58935'
19:36:49.069 MTR: UDP received from [fe80::9:50d3:3f9b:ea82]:58935
19:36:49.075 MTR: .          Duplicate unencrypted message = 72918734 ref = 72918734
19:36:49.755 MTR: .          Resending packet id=228114031
19:36:49.758 MTR: error sending packet to '[fe80::9:50d3:3f9b:ea82]:58935'
19:36:50.706 MTR: UDP received from [fe80::9:50d3:3f9b:ea82]:58935
19:36:50.712 MTR: .          Duplicate unencrypted message = 72918734 ref = 72918734
19:36:50.909 MTR: -Session   (     0) (removed)
19:36:51.034 MTR: .          Resending packet id=228114031
19:36:51.037 MTR: error sending packet to '[fe80::9:50d3:3f9b:ea82]:58935'
19:36:53.387 BRY: GC from 39405 to 27226 bytes, objects freed 157/289 (in 4 ms) - slots from 449/547 to 277/547
19:36:53.389 MTR: .          (     0) Unacked packet '[fe80::9:50d3:3f9b:ea82]:58935' msg_id=228114031
19:37:00.025 MTR: UDP received from [192.168.188.45]:51279
19:37:00.034 MTR: >Received  (     0) CASE_Sigma1 from [192.168.188.45]:51279
19:37:00.051 MTR: SEARCHING: destinationId=FAE47B281EA0D811BC8FBFE863A51EC82DC528B102F7C581E0F9BCE27336F746
19:37:00.068 BRY: GC from 37511 to 31580 bytes, objects freed 111/354 (in 2 ms) - slots from 489/547 to 317/547
19:37:00.079 MTR: SIGMA1: candidateDestinationId=FAE47B281EA0D811BC8FBFE863A51EC82DC528B102F7C581E0F9BCE27336F746
19:37:00.084 MTR: +Session   ( 30907) from '[192.168.188.45]:51279'
19:37:00.460 MTR: <Replied   (     0) CASE_Sigma2
19:37:00.467 BRY: GC from 41877 to 32710 bytes, objects freed 121/340 (in 4 ms) - slots from 408/547 to 301/547
19:37:00.469 MTR: New Connection (CASE id=30907) from [192.168.188.45]:51279
19:37:00.485 MTR: sending packet to '[192.168.188.45]:51279'
19:37:00.489 MTR: UDP received from [192.168.188.45]:51279
19:37:00.493 MTR: decode header: local_session_id=36807 message_counter=237612578
19:37:00.499 MTR: >          Decrypted message: protocol_id:1 opcode=8 exchange_id=31268
19:37:00.532 MTR: >Command   ( 36807) [00]0030/0000 ArmFailSafe 
19:37:00.540 MTR: <Replied   ( 36807) [00]0030/0001 ArmFailSafeResponse
19:37:00.554 MTR: <snd       ( 36807) id=42095845 exch=31268 rack=237612578
19:37:00.560 MTR: sending packet to '[192.168.188.45]:51279'
19:37:00.565 MTR: UDP received from [192.168.188.45]:51279
19:37:00.571 BRY: GC from 43154 to 29902 bytes, objects freed 156/315 (in 4 ms) - slots from 480/547 to 297/547
19:37:00.579 MTR: .          Removed packet from sending list id=58130754
19:37:00.582 MTR: >Received  (     0) CASE_Sigma3 from [192.168.188.45]:51279
19:37:00.640 BRY: GC from 40372 to 36106 bytes, objects freed 64/388 (in 3 ms) - slots from 414/547 to 337/547
19:37:00.879 MTR: Sigma3 verified, computing new keys
19:37:00.887 MTR: <Replied   (     0) StatusReport
19:37:00.895 MTR: sending packet to '[192.168.188.45]:51279'
19:37:00.986 MTR: =Saved     0 fabric(s) and 0 session(s)
19:37:00.994 MTR: UDP received from [192.168.188.45]:51279
19:37:00.998 MTR: decode header: local_session_id=36807 message_counter=237612579
19:37:01.004 MTR: >          Decrypted message: protocol_id:0 opcode=16 exchange_id=31268
19:37:01.007 MTR: .          Removed packet from sending list id=42095845
19:37:02.435 MTR: <snd       ( 30907) id=256160365 exch=31274 rack=250486742
19:37:02.440 MTR: sending packet to '[192.168.188.45]:51279'
19:37:02.446 MTR: >Read_Attr1( 30907) [04]001D/0000! (DeviceTypeList) - [{0 = 256U, 1 = 2U}]
19:37:02.451 MTR: UDP received from [192.168.188.45]:51279
19:37:02.455 MTR: decode header: local_session_id=30907 message_counter=250486743
19:37:02.472 MTR: >          Decrypted message: protocol_id:0 opcode=16 exchange_id=31273
19:37:02.475 MTR: .          Removed packet from sending list id=256160364
19:37:02.490 MTR: UDP received from [192.168.188.45]:51279
19:37:02.494 MTR: decode header: local_session_id=30907 message_counter=250486744
19:37:02.501 MTR: >          Decrypted message: protocol_id:1 opcode=1 exchange_id=31274
19:37:02.503 MTR: .          Removed packet from sending list id=256160365
19:37:02.519 MTR: >OK        ( 30907) exch=31274 not found
19:37:02.526 MTR: <Ack*      ( 30907) ack=250486744 id=256160366 {reliable}
19:37:02.532 MTR: sending packet to '[192.168.188.45]:51279'
19:37:02.547 MTR: UDP received from [192.168.188.45]:51279
19:37:02.551 MTR: decode header: local_session_id=30907 message_counter=250486745
19:37:02.558 MTR: >          Decrypted message: protocol_id:0 opcode=16 exchange_id=31274
19:37:02.560 MTR: .          Removed packet from sending list id=256160366
19:37:02.684 MTR: UDP received from [192.168.188.45]:51279
19:37:02.687 MTR: decode header: local_session_id=30907 message_counter=250486746
19:37:02.694 MTR: >          Decrypted message: protocol_id:1 opcode=10 exchange_id=31275
19:37:02.699 MTR: >Command   ( 30907) TimedRequest=9999
19:37:02.712 MTR: <snd       ( 30907) id=256160367 exch=31275 rack=250486746
19:37:02.718 MTR: sending packet to '[192.168.188.45]:51279'
19:37:02.726 MTR: UDP received from [192.168.188.45]:51279
19:37:02.732 BRY: GC from 38461 to 28287 bytes, objects freed 82/308 (in 3 ms) - slots from 427/547 to 295/547
19:37:02.736 MTR: decode header: local_session_id=30907 message_counter=250486747
19:37:02.752 MTR: >          Decrypted message: protocol_id:1 opcode=8 exchange_id=31275
19:37:02.755 MTR: .          Removed packet from sending list id=256160367
19:37:02.783 MTR: >Command   ( 30907) [00]003C/0002 RevokeCommissioning 
19:37:02.787 MTR: <Replied   ( 30907) OK exch=31275
19:37:02.799 MTR: <snd       ( 30907) id=256160367 exch=31275 rack=250486746
19:37:02.806 MTR: sending packet to '[192.168.188.45]:51279'
19:37:02.819 MTR: <snd       ( 30907) id=256160368 exch=31275 rack=250486747
19:37:02.824 MTR: sending packet to '[192.168.188.45]:51279'
19:37:02.830 MTR: UDP received from [192.168.188.45]:51279
19:37:02.834 MTR: decode header: local_session_id=30907 message_counter=250486748
19:37:02.840 MTR: >          Decrypted message: protocol_id:0 opcode=16 exchange_id=31275
19:37:02.843 MTR: .          Removed packet from sending list id=256160367
19:37:02.858 MTR: UDP received from [192.168.188.45]:51279
19:37:02.866 BRY: GC from 38583 to 28314 bytes, objects freed 131/311 (in 4 ms) - slots from 470/547 to 297/547
19:37:02.868 MTR: decode header: local_session_id=30907 message_counter=250486749
19:37:02.885 MTR: >          Decrypted message: protocol_id:0 opcode=16 exchange_id=31275
19:37:02.888 MTR: .          Removed packet from sending list id=256160368
19:37:02.902 MTR: UDP received from [192.168.188.45]:51279
19:37:02.906 MTR: decode header: local_session_id=30907 message_counter=250486750
19:37:02.913 MTR: >          Decrypted message: protocol_id:1 opcode=10 exchange_id=31276
19:37:02.919 MTR: >Command   ( 30907) TimedRequest=10000
19:37:02.931 MTR: <snd       ( 30907) id=256160369 exch=31276 rack=250486750
19:37:02.938 MTR: sending packet to '[192.168.188.45]:51279'
19:37:02.959 MTR: UDP received from [192.168.188.45]:51279
19:37:02.963 MTR: decode header: local_session_id=30907 message_counter=250486751
19:37:02.971 MTR: >          Decrypted message: protocol_id:1 opcode=8 exchange_id=31276
19:37:02.973 MTR: .          Removed packet from sending list id=256160369
19:37:03.011 BRY: GC from 38563 to 31824 bytes, objects freed 77/363 (in 2 ms) - slots from 426/547 to 328/547
19:37:03.018 MTR: OpenCommissioningWindow(timeout=300, passcode=E19960F12AC06D8B0CBA5385A76D71036B74D7F974A03A67ADC3FB5AA99C0DCD04DD7024288CB62A6A9F84E37671CD76AF8F773BE54D7A2D388A56246118203EED1B58318E3FEA76F6546A8608681F87833CD6A3172BE23007B4C457CBB7C03F0A, discriminator=3207, iterations=1000, salt=D8DD7FE75D129B6A9D9BB9FE88C56EF55300A862831AF91AE9E1EAF3C76C4D0C)
19:37:03.061 MTR: starting mDNS on wifi 'DEA6AE4262D43849' ptr to `083A8D9A4B90.local`
19:37:03.063 MTR: adding subtype: _L3207
19:37:03.065 MTR: adding subtype: _S12
19:37:03.066 MTR: adding subtype: _V65521
19:37:03.078 MTR: adding subtype: _CM1
19:37:03.081 MTR: >Command   ( 30907) [00]003C/0000 OpenCommissioningWindow 
19:37:03.085 MTR: <Replied   ( 30907) OK exch=31276
19:37:03.098 MTR: <snd       ( 30907) id=256160369 exch=31276 rack=250486750
19:37:03.105 MTR: sending packet to '[192.168.188.45]:51279'
19:37:03.121 BRY: GC from 42132 to 30703 bytes, objects freed 115/339 (in 3 ms) - slots from 482/547 to 308/547
19:37:03.123 MTR: <snd       ( 30907) id=256160370 exch=31276 rack=250486751
19:37:03.139 MTR: sending packet to '[192.168.188.45]:51279'
19:37:03.144 MTR: UDP received from [192.168.188.45]:51279
19:37:03.148 MTR: decode header: local_session_id=30907 message_counter=250486752
19:37:03.154 MTR: >          Decrypted message: protocol_id:0 opcode=16 exchange_id=31276
19:37:03.157 MTR: .          Removed packet from sending list id=256160369
19:37:03.172 MTR: UDP received from [192.168.188.45]:51279
19:37:03.176 MTR: decode header: local_session_id=30907 message_counter=250486753
19:37:03.182 MTR: >          Decrypted message: protocol_id:0 opcode=16 exchange_id=31276
19:37:03.185 MTR: .          Removed packet from sending list id=256160370
19:37:04.945 MTR: -Session   (     0) (removed)
19:37:05.369 MTR: UDP received from [fe80::9:50d3:3f9b:ea82]:62774
19:37:05.379 MTR: >Received  (     0) PBKDFParamRequest from [fe80::9:50d3:3f9b:ea82]:62774
19:37:05.396 MTR: +Session   ( 22425) from '[fe80::9:50d3:3f9b:ea82]:62774'
19:37:05.414 BRY: GC from 41057 to 30000 bytes, objects freed 130/325 (in 4 ms) - slots from 450/547 to 296/532
19:37:05.417 MTR: <Replied   (     0) PBKDFParamResponse
19:37:05.433 MTR: error sending packet to '[fe80::9:50d3:3f9b:ea82]:62774'
19:37:05.768 MTR: UDP received from [fe80::9:50d3:3f9b:ea82]:62774
19:37:05.774 MTR: .          Duplicate unencrypted message = 205240365 ref = 205240365
19:37:05.796 MTR: .          Resending packet id=115600889
19:37:05.799 MTR: error sending packet to '[fe80::9:50d3:3f9b:ea82]:62774'
19:37:06.171 MTR: .          Resending packet id=115600889
19:37:06.174 MTR: error sending packet to '[fe80::9:50d3:3f9b:ea82]:62774'
19:37:06.227 MTR: UDP received from [fe80::9:50d3:3f9b:ea82]:62774
19:37:06.234 MTR: .          Duplicate unencrypted message = 205240365 ref = 205240365
19:37:06.511 MTR: .          Resending packet id=115600889
19:37:06.514 MTR: error sending packet to '[fe80::9:50d3:3f9b:ea82]:62774'
19:37:06.782 MTR: UDP received from [fe80::9:50d3:3f9b:ea82]:62774
19:37:06.788 MTR: .          Duplicate unencrypted message = 205240365 ref = 205240365
19:37:07.070 MTR: .          Resending packet id=115600889
19:37:07.073 MTR: error sending packet to '[fe80::9:50d3:3f9b:ea82]:62774'
19:37:07.702 MTR: UDP received from [fe80::9:50d3:3f9b:ea82]:62774
19:37:07.708 MTR: .          Duplicate unencrypted message = 205240365 ref = 205240365
19:37:07.856 MTR: .          Resending packet id=115600889
19:37:07.859 MTR: error sending packet to '[fe80::9:50d3:3f9b:ea82]:62774'
19:37:09.298 MTR: .          Resending packet id=115600889
19:37:09.301 MTR: error sending packet to '[fe80::9:50d3:3f9b:ea82]:62774'
19:37:11.583 MTR: .          (     0) Unacked packet '[fe80::9:50d3:3f9b:ea82]:62774' msg_id=115600889
19:37:16.009 MTR: UDP received from [192.168.188.45]:49653
19:37:16.023 BRY: GC from 40289 to 29376 bytes, objects freed 178/316 (in 3 ms) - slots from 525/562 to 298/562
19:37:16.025 MTR: >Received  (     0) PBKDFParamRequest from [192.168.188.45]:49653
19:37:16.052 MTR: +Session   ( 35508) from '[192.168.188.45]:49653'
19:37:16.066 MTR: <Replied   (     0) PBKDFParamResponse
19:37:16.073 MTR: sending packet to '[192.168.188.45]:49653'
19:37:17.164 MTR: UDP received from [192.168.188.45]:49653
19:37:17.168 MTR: decode header: local_session_id=35508 message_counter=220904851
19:37:17.175 MTR: >          Decrypted message: protocol_id:1 opcode=8 exchange_id=18145
19:37:17.195 BRY: GC from 41235 to 32108 bytes, objects freed 85/363 (in 3 ms) - slots from 470/547 to 342/547
19:37:17.202 MTR: >Command   ( 35508) [00]0030/0000 ArmFailSafe 
19:37:17.209 MTR: <Replied   ( 35508) [00]0030/0001 ArmFailSafeResponse
19:37:17.224 MTR: <snd       ( 35508) id=100397605 exch=18145 rack=220904851
19:37:17.231 MTR: sending packet to '[192.168.188.45]:49653'
19:37:17.236 MTR: UDP received from [192.168.188.45]:49653
19:37:17.240 MTR: decode header: local_session_id=35508 message_counter=220904852
19:37:17.247 MTR: >          Decrypted message: protocol_id:0 opcode=16 exchange_id=18144
19:37:17.250 MTR: .          Removed packet from sending list id=100397604
19:37:17.280 MTR: UDP received from [192.168.188.45]:49653
19:37:17.284 MTR: decode header: local_session_id=35508 message_counter=220904853
19:37:17.290 MTR: >          Decrypted message: protocol_id:0 opcode=16 exchange_id=18145
19:37:17.293 MTR: .          Removed packet from sending list id=100397605
19:37:17.838 MTR: UDP received from [192.168.188.45]:49653
19:37:17.842 MTR: decode header: local_session_id=35508 message_counter=220904854
19:37:17.849 MTR: >          Decrypted message: protocol_id:1 opcode=8 exchange_id=18146
19:37:17.865 BRY: GC from 42405 to 31098 bytes, objects freed 145/345 (in 3 ms) - slots from 518/562 to 329/562
19:37:17.000 MTR: >Command   ( 35508) [00]003E/0000 AttestationRequest 
19:37:18.009 MTR: <Replied   ( 35508) [00]003E/0001 AttestationResponse
19:37:18.026 MTR: <snd       ( 35508) id=100397606 exch=18146 rack=220904854
19:37:18.037 BRY: GC from 41382 to 33357 bytes, objects freed 105/346 (in 3 ms) - slots from 481/562 to 324/547
19:37:18.039 MTR: sending packet to '[192.168.188.45]:49653'
19:37:18.053 MTR: UDP received from [192.168.188.45]:49653
19:37:18.057 MTR: decode header: local_session_id=35508 message_counter=220904855
19:37:18.063 MTR: >          Decrypted message: protocol_id:0 opcode=16 exchange_id=18146
19:37:18.066 MTR: .          Removed packet from sending list id=100397606
19:37:18.082 MTR: UDP received from [192.168.188.45]:49653
19:37:18.085 MTR: decode header: local_session_id=35508 message_counter=220904856
19:37:18.092 MTR: >          Decrypted message: protocol_id:1 opcode=8 exchange_id=18147
19:37:18.113 MTR: >Command   ( 35508) [00]003E/0002 CertificateChainRequest 
19:37:18.121 MTR: <Replied   ( 35508) [00]003E/0003 CertificateChainResponse
19:37:18.140 BRY: GC from 44064 to 32583 bytes, objects freed 130/342 (in 3 ms) - slots from 494/547 to 324/547
19:37:18.143 MTR: <snd       ( 35508) id=100397607 exch=18147 rack=220904856
19:37:18.160 MTR: sending packet to '[192.168.188.45]:49653'
19:37:18.168 MTR: UDP received from [192.168.188.45]:49653
19:37:18.172 MTR: decode header: local_session_id=35508 message_counter=220904857
19:37:18.179 MTR: >          Decrypted message: protocol_id:0 opcode=16 exchange_id=18147
19:37:18.181 MTR: .          Removed packet from sending list id=100397607
19:37:18.196 MTR: UDP received from [192.168.188.45]:49653
19:37:18.200 MTR: decode header: local_session_id=35508 message_counter=220904858
19:37:18.207 MTR: >          Decrypted message: protocol_id:1 opcode=8 exchange_id=18148
19:37:18.228 MTR: >Command   ( 35508) [00]003E/0002 CertificateChainRequest 
19:37:18.236 MTR: <Replied   ( 35508) [00]003E/0003 CertificateChainResponse
19:37:18.250 BRY: GC from 43295 to 31936 bytes, objects freed 129/341 (in 4 ms) - slots from 500/547 to 323/547
19:37:18.258 MTR: <snd       ( 35508) id=100397608 exch=18148 rack=220904858
19:37:18.265 MTR: sending packet to '[192.168.188.45]:49653'
19:37:18.286 MTR: UDP received from [192.168.188.45]:49653
19:37:18.290 MTR: decode header: local_session_id=35508 message_counter=220904859
19:37:18.297 MTR: >          Decrypted message: protocol_id:0 opcode=16 exchange_id=18148
19:37:18.299 MTR: .          Removed packet from sending list id=100397608
19:37:18.314 MTR: UDP received from [192.168.188.45]:49653
19:37:18.318 MTR: decode header: local_session_id=35508 message_counter=220904860
19:37:18.325 MTR: >          Decrypted message: protocol_id:1 opcode=2 exchange_id=18149
19:37:18.335 MTR: <Ack*      ( 35508) ack=220904860 id=100397609 
19:37:18.342 MTR: sending packet to '[192.168.188.45]:49653'
19:37:18.352 MTR: <snd       ( 35508) id=100397610 exch=18149 rack=220904860
19:37:18.358 MTR: sending packet to '[192.168.188.45]:49653'
19:37:18.362 MTR: >Read_Attr1( 35508) [00]0028/0002! (VendorID) - 65521U
19:37:18.367 MTR: UDP received from [192.168.188.45]:49653
19:37:18.371 MTR: decode header: local_session_id=35508 message_counter=220904861
19:37:18.387 MTR: >          Decrypted message: protocol_id:1 opcode=1 exchange_id=18149
19:37:18.390 MTR: .          Removed packet from sending list id=100397610
19:37:18.406 MTR: >OK        ( 35508) exch=18149 not found
19:37:18.413 MTR: <Ack*      ( 35508) ack=220904861 id=100397611 {reliable}
19:37:18.418 MTR: sending packet to '[192.168.188.45]:49653'
19:37:18.422 MTR: UDP received from [192.168.188.45]:49653
19:37:18.427 MTR: decode header: local_session_id=35508 message_counter=220904862
19:37:18.433 MTR: >          Decrypted message: protocol_id:1 opcode=2 exchange_id=18150
19:37:18.453 MTR: <Ack*      ( 35508) ack=220904862 id=100397612 
19:37:18.459 MTR: sending packet to '[192.168.188.45]:49653'
19:37:18.471 BRY: GC from 42198 to 31035 bytes, objects freed 62/335 (in 3 ms) - slots from 395/547 to 317/547
19:37:18.473 MTR: <snd       ( 35508) id=100397613 exch=18150 rack=220904862
19:37:18.488 MTR: sending packet to '[192.168.188.45]:49653'
19:37:18.493 MTR: >Read_Attr1( 35508) [00]0028/0004! (ProductID) - 32768U
19:37:18.637 MTR: UDP received from [192.168.188.45]:49653
19:37:18.642 MTR: decode header: local_session_id=35508 message_counter=220904863
19:37:18.649 MTR: >          Decrypted message: protocol_id:0 opcode=16 exchange_id=18149
19:37:18.651 MTR: .          Removed packet from sending list id=100397611
19:37:18.666 MTR: UDP received from [192.168.188.45]:49653
19:37:18.670 MTR: decode header: local_session_id=35508 message_counter=220904864
19:37:18.677 MTR: >          Decrypted message: protocol_id:1 opcode=1 exchange_id=18150
19:37:18.679 MTR: .          Removed packet from sending list id=100397613
19:37:18.696 MTR: >OK        ( 35508) exch=18150 not found
19:37:18.703 MTR: <Ack*      ( 35508) ack=220904864 id=100397614 {reliable}
19:37:18.709 MTR: sending packet to '[192.168.188.45]:49653'
19:37:18.713 MTR: UDP received from [192.168.188.45]:49653
19:37:18.717 MTR: decode header: local_session_id=35508 message_counter=220904865
19:37:18.723 MTR: >          Decrypted message: protocol_id:1 opcode=2 exchange_id=18151
19:37:18.744 MTR: <Ack*      ( 35508) ack=220904865 id=100397615 
19:37:18.749 MTR: sending packet to '[192.168.188.45]:49653'
19:37:18.766 MTR: <snd       ( 35508) id=100397616 exch=18151 rack=220904865
19:37:18.771 MTR: sending packet to '[192.168.188.45]:49653'
19:37:18.776 MTR: >Read_Attr1( 35508) [00]0028/0003! (ProductName) - Tasmota
19:37:18.780 MTR: UDP received from [192.168.188.45]:49653
19:37:18.784 MTR: decode header: local_session_id=35508 message_counter=220904866
19:37:18.801 MTR: >          Decrypted message: protocol_id:0 opcode=16 exchange_id=18150
19:37:18.803 MTR: .          Removed packet from sending list id=100397614
19:37:18.828 MTR: UDP received from [192.168.188.45]:49653
19:37:18.832 MTR: decode header: local_session_id=35508 message_counter=220904867
19:37:18.838 MTR: >          Decrypted message: protocol_id:1 opcode=1 exchange_id=18151
19:37:18.841 MTR: .          Removed packet from sending list id=100397616
19:37:18.859 BRY: GC from 41363 to 29596 bytes, objects freed 80/323 (in 4 ms) - slots from 417/547 to 312/547
19:37:18.863 MTR: >OK        ( 35508) exch=18151 not found
19:37:18.869 MTR: <Ack*      ( 35508) ack=220904867 id=100397617 {reliable}
19:37:18.886 MTR: sending packet to '[192.168.188.45]:49653'
19:37:18.890 MTR: UDP received from [192.168.188.45]:49653
19:37:18.894 MTR: decode header: local_session_id=35508 message_counter=220904868
19:37:18.901 MTR: >          Decrypted message: protocol_id:1 opcode=8 exchange_id=18152
19:37:18.926 MTR: <Ack*      ( 35508) ack=220904868 id=100397618 
19:37:18.932 MTR: sending packet to '[192.168.188.45]:49653'
19:37:19.264 BRY: GC from 39847 to 33399 bytes, objects freed 65/381 (in 3 ms) - slots from 461/547 to 353/547
19:37:19.268 MTR: >Command   ( 35508) [00]003E/0004 CSRRequest 
19:37:19.287 MTR: <Replied   ( 35508) [00]003E/0005 CSRResponse
19:37:19.302 MTR: <snd       ( 35508) id=100397619 exch=18152 rack=220904868
19:37:19.309 MTR: sending packet to '[192.168.188.45]:49653'
19:37:19.314 MTR: UDP received from [192.168.188.45]:49653
19:37:19.318 MTR: decode header: local_session_id=35508 message_counter=220904869
19:37:19.325 MTR: >          Decrypted message: protocol_id:0 opcode=16 exchange_id=18151
19:37:19.327 MTR: .          Removed packet from sending list id=100397617
19:37:19.360 MTR: UDP received from [192.168.188.45]:49653
19:37:19.364 MTR: decode header: local_session_id=35508 message_counter=220904870
19:37:19.371 MTR: >          Decrypted message: protocol_id:0 opcode=16 exchange_id=18152
19:37:19.374 MTR: .          Removed packet from sending list id=100397619
19:37:21.469 MTR: <Replied   ( 35508) OK exch=18153
19:37:21.483 MTR: <snd       ( 35508) id=100397620 exch=18153 rack=220904871
19:37:21.490 MTR: sending packet to '[192.168.188.45]:49653'
19:37:21.499 MTR: UDP received from [192.168.188.45]:49653
19:37:21.502 MTR: decode header: local_session_id=35508 message_counter=220904872
19:37:21.509 MTR: >          Decrypted message: protocol_id:0 opcode=16 exchange_id=18153
19:37:21.512 MTR: .          Removed packet from sending list id=100397620
19:37:21.528 MTR: UDP received from [192.168.188.45]:49653
19:37:21.531 MTR: decode header: local_session_id=35508 message_counter=220904873
19:37:21.541 MTR: >          Decrypted message: protocol_id:1 opcode=8 exchange_id=18154
19:37:21.551 BRY: GC from 40543 to 31459 bytes, objects freed 110/332 (in 3 ms) - slots from 466/547 to 316/547
19:37:21.581 BRY: GC from 42214 to 36430 bytes, objects freed 44/387 (in 2 ms) - slots from 420/547 to 354/547
19:37:21.587 BRY: GC from 46923 to 37783 bytes, objects freed 11/387 (in 1 ms) - slots from 364/547 to 354/547
19:37:21.601 MTR: AddNoc Args=1 = {0 = 1530011059C3D2A30ACA971DA11B082FC020CBEF24020137032C840255532C0706476F6F676C652C010F4D61747465722075732D6561737431271302000000FEFFFFFF1826044A81B22E2605629C4A5437062715AB621C6145E878992611CA769B8B18240701240801300941043390799B65749B34A840A277FC19C49731036AD8C66410D4A2A257FB58B758D226060D8DEC6183E33D749439590590E643635EA4C5EE34403C9D8174C031CD60370A350128011824020136030402040118300414EAB4CE9C1614C78B10D09A4DF70F7693A31128B230051481420662831A8A83E7247BFF47DE6CDB19883A2918300B40B97B794EE1D45758C6489CA37E105B28F2B9DE164C059291E75806222EF0E20A7893AFE1E54234CF14A7D385039CBE14DF3A68F34A07F394CF753562740B5E8918, 1 = 153001010224020137032C840255532C0706476F6F676C652C010B4D617474657220526F6F74271401000000FEFFFFFF18260440D34329260540955BE537062C840255532C0706476F6F676C652C010F4D61747465722075732D6561737431271302000000FEFFFFFF1824070124080130094104EDB9DCE05AAA200D89535964B9EE05E874A3F9CD4665DD7905879864EFB091B8B485AB54CFC31DE20B1C2D91F78786468AC00C24A13423E5A31B1EAE6B0A93C6370A350129012402001824026030041481420662831A8A83E7247BFF47DE6CDB19883A2930051472C201F7571913B348CA00CA7B45F4774668C97E18300B4008767394A59C29729A7464F3BF42E2E4D343FF13F90D7AA94EB5AA9CFB67C202356FB5AA0AF4FC478117893421F0C5779EA882ABD650FA18C168246BFC0E13A218, 2 = B813AEF3EEE63A32A5230323C3E04E1A, 3 = -12884836351U, 4 = 24582U}
19:37:21.750 BRY: GC from 48119 to 38975 bytes, objects freed 29/444 (in 2 ms) - slots from 429/547 to 393/547
19:37:21.790 MTR: +Fabric    fab='9978E845611C62AB' vendorid=Google LLC
19:37:21.795 MTR: >Command   ( 35508) [00]003E/0006 AddNOC 
19:37:21.802 MTR: <Replied   ( 35508) [00]003E/0008 NOCResponse
19:37:21.817 MTR: <snd       ( 35508) id=100397621 exch=18154 rack=220904873
19:37:21.824 MTR: sending packet to '[192.168.188.45]:49653'
19:37:21.835 BRY: GC from 49252 to 34370 bytes, objects freed 213/366 (in 6 ms) - slots from 544/592 to 342/592
19:37:21.838 MTR: calling mdns.remove_service(_matterc, _udp, DEA6AE4262D43849, 083A8D9A4B90)
19:37:21.850 MTR: remove mDNS on wifi 'DEA6AE4262D43849'
19:37:21.856 MTR: Operational Discovery node = C144BD97C0B9796B-000000008B9B76CA
19:37:21.862 MTR: adding mDNS on wifi 'C144BD97C0B9796B-000000008B9B76CA' ptr to `083A8D9A4B90.local`
19:37:21.875 MTR: adding subtype: _IC144BD97C0B9796B
19:37:21.891 MTR: UDP received from [192.168.188.45]:49653
19:37:21.895 MTR: decode header: local_session_id=35508 message_counter=220904873
19:37:21.899 MTR: .          Duplicate encrypted message = 220904873 counter=220904873
19:37:21.913 MTR: UDP received from [192.168.188.45]:49653
19:37:21.917 MTR: decode header: local_session_id=35508 message_counter=220904874
19:37:21.924 MTR: >          Decrypted message: protocol_id:0 opcode=16 exchange_id=18154
19:37:21.926 MTR: .          Removed packet from sending list id=100397621
19:37:21.942 MTR: UDP received from [192.168.188.45]:49653
19:37:21.946 MTR: decode header: local_session_id=35508 message_counter=220904875
19:37:21.953 MTR: >          Decrypted message: protocol_id:1 opcode=8 exchange_id=18155

TO REPRODUCE,

Start with a fresh setup, enable matter, force static endpoints, add a virtual device type Light 0, start commissioning, start Google Home on iOS, add matter device, go through the wizard

EXPECTED BEHAVIOUR

The device is created in Google Home and works

SCREENSHOTS

If applicable, add screenshots to help explain your problem.

ADDITIONAL CONTEXT

Add any other context about the problem here.

(Please, remember to close the issue when the problem has been addressed)
@maltic42
Copy link
Author

maltic42 commented Oct 28, 2024 •
edited
Loading 

19:37:21.975 MTR: >Command   ( 35508) [00]0030/0000 ArmFailSafe 
19:37:21.984 BRY: GC from 44669 to 33933 bytes, objects freed 99/390 (in 3 ms) - slots from 508/592 to 371/562
19:37:21.989 MTR: <Replied   ( 35508) [00]0030/0001 ArmFailSafeResponse
19:37:22.013 MTR: <snd       ( 35508) id=100397622 exch=18155 rack=220904875
19:37:22.019 MTR: sending packet to '[192.168.188.45]:49653'
19:37:22.041 MTR: UDP received from [192.168.188.45]:49653
19:37:22.045 MTR: decode header: local_session_id=35508 message_counter=220904876
19:37:22.052 MTR: >          Decrypted message: protocol_id:0 opcode=16 exchange_id=18155
19:37:22.055 MTR: .          Removed packet from sending list id=100397622
19:37:23.086 MTR: UDP received from [fe80::9:50d3:3f9b:ea82]:62774
19:37:23.097 MTR: >Received  (     0) CASE_Sigma1 from [fe80::9:50d3:3f9b:ea82]:62774
19:37:23.114 MTR: SEARCHING: destinationId=9E32BC177E9C88DD9F159FDA215A005D3216EAB3CF0138CA80252D6CAFB83767
19:37:23.122 BRY: GC from 44221 to 34052 bytes, objects freed 135/369 (in 3 ms) - slots from 520/562 to 343/562
19:37:23.154 MTR: SIGMA1: candidateDestinationId=B3073013CCB712921BB031316A5D4CBC8539FFC8060B3D4B73D589F7F5A26465
19:37:23.187 MTR: SIGMA1: candidateDestinationId=9E32BC177E9C88DD9F159FDA215A005D3216EAB3CF0138CA80252D6CAFB83767
19:37:23.196 BRY: GC from 44360 to 33803 bytes, objects freed 149/364 (in 5 ms) - slots from 432/562 to 336/562
19:37:23.198 MTR: +Session   ( 61133) from '[fe80::9:50d3:3f9b:ea82]:62774'
19:37:23.578 MTR: <Replied   (     0) CASE_Sigma2
19:37:23.585 BRY: GC from 44046 to 38820 bytes, objects freed 57/389 (in 3 ms) - slots from 410/562 to 349/562
19:37:23.588 MTR: New Connection (CASE id=61133) from [fe80::9:50d3:3f9b:ea82]:62774
19:37:23.603 MTR: error sending packet to '[fe80::9:50d3:3f9b:ea82]:62774'
19:37:23.756 MTR: UDP received from [fe80::9:50d3:3f9b:ea82]:62774
19:37:23.763 MTR: .          Duplicate unencrypted message = 205240369 ref = 205240369
19:37:23.925 MTR: .          Resending packet id=1296624
19:37:23.929 MTR: error sending packet to '[fe80::9:50d3:3f9b:ea82]:62774'
19:37:24.271 MTR: .          Resending packet id=1296624
19:37:24.274 MTR: error sending packet to '[fe80::9:50d3:3f9b:ea82]:62774'
19:37:24.288 WIF: Checking connection...
19:37:24.494 MTR: UDP received from [fe80::9:50d3:3f9b:ea82]:62774
19:37:24.501 MTR: .          Duplicate unencrypted message = 205240369 ref = 205240369
19:37:24.619 MTR: .          Resending packet id=1296624
19:37:24.622 MTR: error sending packet to '[fe80::9:50d3:3f9b:ea82]:62774'
19:37:25.173 MTR: .          Resending packet id=1296624
19:37:25.176 MTR: error sending packet to '[fe80::9:50d3:3f9b:ea82]:62774'
19:37:25.416 MTR: UDP received from [fe80::9:50d3:3f9b:ea82]:62774
19:37:25.422 MTR: .          Duplicate unencrypted message = 205240369 ref = 205240369
19:37:26.131 MTR: .          Resending packet id=1296624
19:37:26.134 MTR: error sending packet to '[fe80::9:50d3:3f9b:ea82]:62774'
19:37:37.403 MTR: UDP received from [192.168.188.45]:49653
19:37:37.410 MTR: .          Duplicate unencrypted message = 205240371 ref = 205240371
19:37:37.414 MTR: UDP received from [192.168.188.45]:49653
19:37:37.418 MTR: decode header: local_session_id=22993 message_counter=23758076
19:37:37.436 MTR: >          Decrypted message: protocol_id:1 opcode=3 exchange_id=18158
19:37:37.452 MTR: <Ack*      ( 22993) ack=23758076 id=55621824 
19:37:37.459 MTR: sending packet to '[192.168.188.45]:49653'
19:37:37.475 BRY: GC from 58525 to 37386 bytes, objects freed 154/413 (in 5 ms) - slots from 602/638 to 385/638
19:37:37.477 MTR: >Subscribe ( 22993) [**]****/**** (min=0, max=60, keep=1) sub=34781 fabric_filtered=false
19:37:37.491 MTR: >Read_Attr ( 22993) [**]****/****
19:37:37.495 MTR: >Read_Event( 22993) [**]****/** 
19:37:37.576 BRY: GC from 47708 to 37592 bytes, objects freed 158/397 (in 4 ms) - slots from 563/638 to 360/623
19:37:37.817 BRY: GC from 47773 to 41227 bytes, objects freed 81/427 (in 3 ms) - slots from 511/623 to 361/623
19:37:37.885 MTR: sending packet to '[192.168.188.45]:49653'
19:37:37.890 MTR: UDP received from [192.168.188.45]:49653
19:37:37.897 MTR: .          Removed packet from sending list id=185545512
19:37:37.902 MTR: >rcv Ack   ( 22993) rid=205240372 exch=0 ack=18157 185545512from [192.168.188.45]:49653
19:37:38.012 MTR: UDP received from [192.168.188.45]:49653
19:37:38.016 MTR: decode header: local_session_id=22993 message_counter=23758077
19:37:38.023 MTR: >          Decrypted message: protocol_id:1 opcode=1 exchange_id=18158
19:37:38.025 MTR: .          Removed packet from sending list id=55621825
19:37:38.042 MTR: >Sub_OK    ( 22993) sub=34781
19:37:38.049 MTR: <Ack*      ( 22993) ack=23758077 id=55621827 
19:37:38.055 MTR: sending packet to '[192.168.188.45]:49653'
19:37:38.076 BRY: GC from 51488 to 37492 bytes, objects freed 109/385 (in 4 ms) - slots from 452/623 to 360/623
19:37:38.208 BRY: GC from 47741 to 38384 bytes, objects freed 124/390 (in 4 ms) - slots from 497/623 to 363/623
19:37:38.334 BRY: GC from 48660 to 38552 bytes, objects freed 150/386 (in 4 ms) - slots from 508/623 to 362/623
19:37:38.405 MTR: sending packet to '[192.168.188.45]:49653'
19:37:38.411 MTR: UDP received from [192.168.188.45]:49653
19:37:38.415 MTR: decode header: local_session_id=22993 message_counter=23758078
19:37:38.422 MTR: >          Decrypted message: protocol_id:1 opcode=8 exchange_id=18159
19:37:38.431 BRY: GC from 48804 to 39271 bytes, objects freed 68/414 (in 3 ms) - slots from 472/623 to 379/623
19:37:38.450 MTR: <Ack*      ( 22993) ack=23758078 id=55621828 
19:37:38.456 MTR: sending packet to '[192.168.188.45]:49653'
19:37:38.500 BRY: GC from 49628 to 40847 bytes, objects freed 87/449 (in 4 ms) - slots from 619/683 to 423/623
19:37:38.530 BRY: GC from 51135 to 41464 bytes, objects freed 46/481 (in 2 ms) - slots from 584/653 to 450/653
19:37:38.560 BRY: GC from 52008 to 40570 bytes, objects freed 56/448 (in 2 ms) - slots from 584/668 to 423/638
19:37:38.584 BRY: GC from 50823 to 41113 bytes, objects freed 34/475 (in 2 ms) - slots from 563/638 to 451/638
19:37:38.642 MTR: =Saved     2 fabric(s) and 2 session(s)
19:37:38.650 MTR: >Command   ( 22993) [00]0030/0004 CommissioningComplete 
19:37:38.659 MTR: <Replied   ( 22993) [00]0030/0005 CommissioningCompleteResponse
19:37:38.674 MTR: <snd       ( 22993) id=55621829 exch=18159 rack=23758078
19:37:38.681 MTR: sending packet to '[192.168.188.45]:49653'
19:37:38.686 MTR: UDP received from [192.168.188.45]:49653
19:37:38.690 MTR: decode header: local_session_id=22993 message_counter=23758078
19:37:38.694 MTR: .          Duplicate encrypted message = 23758078 counter=23758078
19:37:38.709 MTR: UDP received from [192.168.188.45]:49653
19:37:38.712 MTR: decode header: local_session_id=22993 message_counter=23758079
19:37:38.720 MTR: >          Decrypted message: protocol_id:1 opcode=1 exchange_id=18158
19:37:38.723 MTR: .          Removed packet from sending list id=55621826
19:37:38.740 BRY: GC from 51367 to 38900 bytes, objects freed 111/408 (in 3 ms) - slots from 568/638 to 381/623
19:37:38.744 MTR: >Sub_OK    ( 22993) sub=34781
19:37:38.752 MTR: <Ack*      ( 22993) ack=23758079 id=55621831 
19:37:38.758 MTR: sending packet to '[192.168.188.45]:49653'
19:37:38.897 BRY: GC from 49085 to 39932 bytes, objects freed 116/408 (in 3 ms) - slots from 551/623 to 367/623
19:37:39.023 BRY: GC from 50212 to 41897 bytes, objects freed 119/433 (in 4 ms) - slots from 522/623 to 387/623
19:37:39.052 MTR: sending packet to '[192.168.188.45]:49653'
19:37:39.065 MTR: <snd       ( 22993) id=55621829 exch=18159 rack=23758078
19:37:39.071 MTR: sending packet to '[192.168.188.45]:49653'
19:37:39.073 MTR: .          Resending packet id=55621829
19:37:39.077 MTR: sending packet to '[192.168.188.45]:49653'
19:37:39.085 MTR: UDP received from [192.168.188.45]:49653
19:37:39.090 MTR: decode header: local_session_id=22993 message_counter=23758080
19:37:39.097 MTR: >          Decrypted message: protocol_id:0 opcode=16 exchange_id=18159
19:37:39.099 MTR: .          Removed packet from sending list id=55621829
19:37:39.112 MTR: .          Removed packet from sending list id=55621829
19:37:39.119 MTR: --- Commissioning complete for Fabric '9978E845611C62AB' (Vendor Google LLC) ---
19:37:39.389 MTR: .          Resending packet id=55621830
19:37:39.395 MTR: sending packet to '[192.168.188.45]:49653'
19:37:39.770 MTR: .          Resending packet id=55621830
19:37:39.776 MTR: sending packet to '[192.168.188.45]:49653'
19:37:40.110 MTR: .          Resending packet id=55621830
19:37:40.116 MTR: sending packet to '[192.168.188.45]:49653'
19:37:40.631 MTR: .          Resending packet id=55621830
19:37:40.636 MTR: sending packet to '[192.168.188.45]:49653'
19:37:41.544 MTR: .          Resending packet id=55621830
19:37:41.549 MTR: sending packet to '[192.168.188.45]:49653'
19:37:42.965 MTR: .          Resending packet id=55621830
19:37:42.971 MTR: sending packet to '[192.168.188.45]:49653'
19:37:43.695 BRY: GC from 52149 to 37827 bytes, objects freed 161/383 (in 4 ms) - slots from 551/623 to 364/623
19:37:44.996 MTR: .          ( 53009) Unacked packet '[192.168.188.45]:49653' msg_id=55621830
19:37:46.398 WIF: Checking connection...
19:37:53.885 MTR: UDP received from [fe80::9:50d3:3f9b:ea82]:51068
19:37:53.895 MTR: >Received  (     0) CASE_Sigma1 from [fe80::9:50d3:3f9b:ea82]:51068
19:37:53.913 MTR: SEARCHING: destinationId=B71A126585581BDB09578C339EFF4D042CC13E8F9F7D1EDC4DABD3BEEDC8394E
19:37:53.919 BRY: GC from 48068 to 37967 bytes, objects freed 164/406 (in 3 ms) - slots from 622/638 to 376/638
19:37:53.952 MTR: SIGMA1: candidateDestinationId=F2F436DBF55BC540319B6526E4BBF558C2B679347C163CF553AA1B81825D3011
19:37:53.985 MTR: SIGMA1: candidateDestinationId=B71A126585581BDB09578C339EFF4D042CC13E8F9F7D1EDC4DABD3BEEDC8394E
19:37:53.994 BRY: GC from 48275 to 37718 bytes, objects freed 149/401 (in 5 ms) - slots from 465/638 to 369/638
19:37:53.996 MTR: +Session   ( 25874) from '[fe80::9:50d3:3f9b:ea82]:51068'
19:37:54.376 MTR: <Replied   (     0) CASE_Sigma2
19:37:54.383 BRY: GC from 47961 to 42735 bytes, objects freed 57/426 (in 2 ms) - slots from 443/638 to 382/638
19:37:54.385 MTR: New Connection (CASE id=25874) from [fe80::9:50d3:3f9b:ea82]:51068
19:37:54.401 MTR: error sending packet to '[fe80::9:50d3:3f9b:ea82]:51068'
19:37:54.501 MTR: UDP received from [fe80::9:50d3:3f9b:ea82]:51068
19:37:54.508 MTR: .          Duplicate unencrypted message = 226964858 ref = 226964858
19:37:54.741 MTR: .          Resending packet id=41334992
19:37:54.744 MTR: error sending packet to '[fe80::9:50d3:3f9b:ea82]:51068'
19:37:55.023 MTR: UDP received from [fe80::9:50d3:3f9b:ea82]:51068
19:37:55.030 MTR: .          Duplicate unencrypted message = 226964858 ref = 226964858
19:37:55.051 MTR: .          Resending packet id=41334992
19:37:55.054 MTR: error sending packet to '[fe80::9:50d3:3f9b:ea82]:51068'
19:37:55.421 MTR: .          Resending packet id=41334992
19:37:55.427 MTR: error sending packet to '[fe80::9:50d3:3f9b:ea82]:51068'
19:37:56.012 MTR: .          Resending packet id=41334992
19:37:56.015 MTR: error sending packet to '[fe80::9:50d3:3f9b:ea82]:51068'
19:37:56.035 MTR: UDP received from [fe80::9:50d3:3f9b:ea82]:51068
19:37:56.041 MTR: .          Duplicate unencrypted message = 226964858 ref = 226964858
19:37:56.848 MTR: .          Resending packet id=41334992
19:37:56.852 MTR: error sending packet to '[fe80::9:50d3:3f9b:ea82]:51068'
19:37:57.773 MTR: UDP received from [fe80::9:50d3:3f9b:ea82]:51068
19:37:57.780 MTR: .          Duplicate unencrypted message = 226964858 ref = 226964858
19:37:58.158 MTR: .          Resending packet id=41334992
19:37:58.161 MTR: error sending packet to '[fe80::9:50d3:3f9b:ea82]:51068'
19:37:58.929 MTR: -Session   (     0) (removed)
19:38:00.324 MTR: .          (     0) Unacked packet '[fe80::9:50d3:3f9b:ea82]:51068' msg_id=41334992
19:38:06.932 WIF: Checking connection...
19:38:07.199 MTR: UDP received from [192.168.188.45]:53639
19:38:07.209 BRY: GC from 53159 to 36751 bytes, objects freed 233/375 (in 5 ms) - slots from 635/653 to 358/653
19:38:07.216 MTR: >Received  (     0) CASE_Sigma1 from [192.168.188.45]:53639
19:38:07.233 MTR: SEARCHING: destinationId=6CCEC5DAB5CC967350E722A028ADFD557DB2BECAFED0F98345AC4A32AA75A5A1
19:38:07.258 MTR: SIGMA1: candidateDestinationId=F3D9E71DA6219C21C0A1B046870EC750DD4DB19AD83ABA6B53CCA0181567F845
19:38:07.273 BRY: GC from 47011 to 40244 bytes, objects freed 98/426 (in 3 ms) - slots from 479/653 to 386/653
19:38:07.296 MTR: SIGMA1: candidateDestinationId=6CCEC5DAB5CC967350E722A028ADFD557DB2BECAFED0F98345AC4A32AA75A5A1
19:38:07.301 MTR: +Session   ( 32860) from '[192.168.188.45]:53639'
19:38:07.676 BRY: GC from 50736 to 41301 bytes, objects freed 124/417 (in 4 ms) - slots from 471/653 to 377/653
19:38:07.688 MTR: <Replied   (     0) CASE_Sigma2
19:38:07.693 MTR: New Connection (CASE id=32860) from [192.168.188.45]:53639
19:38:07.699 MTR: sending packet to '[192.168.188.45]:53639'
19:38:08.875 MTR: <Ack*      ( 32860) ack=54649827 id=91241729 
19:38:08.881 MTR: sending packet to '[192.168.188.45]:53639'
19:38:08.948 BRY: GC from 57648 to 42210 bytes, objects freed 139/428 (in 4 ms) - slots from 522/653 to 382/653
19:38:09.057 BRY: GC from 52460 to 42761 bytes, objects freed 135/430 (in 4 ms) - slots from 525/653 to 384/653
19:38:09.194 BRY: GC from 53016 to 44157 bytes, objects freed 122/456 (in 4 ms) - slots from 528/653 to 409/653
19:38:09.233 MTR: sending packet to '[192.168.188.45]:53639'
19:38:09.239 MTR: UDP received from [192.168.188.45]:53639
19:38:09.244 MTR: decode header: local_session_id=32860 message_counter=54649828
19:38:09.251 MTR: >          Decrypted message: protocol_id:1 opcode=2 exchange_id=44190
19:38:09.269 MTR: <snd       ( 32860) id=91241730 exch=44190 rack=54649828
19:38:09.275 MTR: sending packet to '[192.168.188.45]:53639'
19:38:09.282 MTR: >Read_Attr1( 32860) [00]001D/0000! (DeviceTypeList) - [{0 = 22U, 1 = 1U}]
19:38:09.287 MTR: UDP received from [192.168.188.45]:53639
19:38:09.295 BRY: GC from 54426 to 40988 bytes, objects freed 117/411 (in 4 ms) - slots from 546/653 to 378/653
19:38:09.297 MTR: decode header: local_session_id=32860 message_counter=54649828
19:38:09.311 MTR: .          Duplicate encrypted message = 54649828 counter=54649828
19:38:09.315 MTR: UDP received from [192.168.188.45]:53639
19:38:09.319 MTR: decode header: local_session_id=32860 message_counter=54649829
19:38:09.337 MTR: >          Decrypted message: protocol_id:1 opcode=1 exchange_id=44189
19:38:09.340 MTR: .          Removed packet from sending list id=91241728
19:38:09.356 MTR: >Sub_OK    ( 32860) sub=65170
19:38:09.364 MTR: <Ack*      ( 32860) ack=54649829 id=91241732 
19:38:09.371 MTR: sending packet to '[192.168.188.45]:53639'
19:38:09.510 BRY: GC from 51825 to 42671 bytes, objects freed 116/428 (in 3 ms) - slots from 566/653 to 382/653
19:38:09.628 MTR: -Session   (     0) (removed)
19:38:09.638 BRY: GC from 52959 to 43538 bytes, objects freed 128/442 (in 5 ms) - slots from 539/653 to 393/653
19:38:09.668 MTR: sending packet to '[192.168.188.45]:53639'
19:38:09.671 MTR: .          Resending packet id=91241730
19:38:09.676 MTR: sending packet to '[192.168.188.45]:53639'
19:38:09.700 MTR: UDP received from [192.168.188.45]:53639
19:38:09.704 MTR: decode header: local_session_id=32860 message_counter=54649830
19:38:09.711 MTR: >          Decrypted message: protocol_id:1 opcode=1 exchange_id=44190
19:38:09.713 MTR: .          Removed packet from sending list id=91241730
19:38:09.729 MTR: >OK        ( 32860) exch=44190 not found
19:38:09.737 MTR: <Ack*      ( 32860) ack=54649830 id=91241733 {reliable}
19:38:09.743 MTR: sending packet to '[192.168.188.45]:53639'
19:38:09.748 MTR: UDP received from [192.168.188.45]:53639
19:38:09.752 MTR: decode header: local_session_id=32860 message_counter=54649831
19:38:09.759 MTR: >          Decrypted message: protocol_id:1 opcode=2 exchange_id=44191
19:38:09.775 BRY: GC from 53844 to 41639 bytes, objects freed 97/416 (in 3 ms) - slots from 475/653 to 376/653
19:38:09.796 MTR: <snd       ( 32860) id=91241734 exch=44191 rack=54649831
19:38:09.803 MTR: sending packet to '[192.168.188.45]:53639'
19:38:09.814 MTR: >Read_Attr1( 32860) [00]001D/0001! (ServerList) - [29U, 31U, 40U, 43U, 44U, 48U, 49U, 50U, 51U, 52U, 56U, 60U, 62U, 63U]
19:38:09.830 MTR: UDP received from [192.168.188.45]:53639
19:38:09.834 MTR: decode header: local_session_id=32860 message_counter=54649831
19:38:09.838 MTR: .          Duplicate encrypted message = 54649831 counter=54649831
19:38:09.852 MTR: UDP received from [192.168.188.45]:53639
19:38:09.856 MTR: decode header: local_session_id=32860 message_counter=54649832
19:38:09.863 MTR: >          Decrypted message: protocol_id:1 opcode=1 exchange_id=44189
19:38:09.866 MTR: .          Removed packet from sending list id=91241731
19:38:09.882 MTR: >Sub_OK    ( 32860) sub=65170
19:38:09.890 MTR: <Ack*      ( 32860) ack=54649832 id=91241736 
19:38:09.896 MTR: sending packet to '[192.168.188.45]:53639'
19:38:09.919 BRY: GC from 51936 to 44190 bytes, objects freed 44/454 (in 3 ms) - slots from 508/653 to 398/653
19:38:10.006 BRY: GC from 54458 to 43068 bytes, objects freed 167/435 (in 5 ms) - slots from 517/653 to 389/653
19:38:10.029 MTR: sending packet to '[192.168.188.45]:53639'
19:38:10.051 MTR: UDP received from [192.168.188.45]:53639
19:38:10.055 MTR: decode header: local_session_id=32860 message_counter=54649833
19:38:10.062 MTR: >          Decrypted message: protocol_id:0 opcode=16 exchange_id=44190
19:38:10.067 MTR: UDP received from [192.168.188.45]:53639
19:38:10.071 MTR: decode header: local_session_id=32860 message_counter=54649834
19:38:10.088 MTR: >          Decrypted message: protocol_id:0 opcode=16 exchange_id=44190
19:38:10.091 MTR: .          Removed packet from sending list id=91241733
19:38:10.106 MTR: UDP received from [192.168.188.45]:53639
19:38:10.110 MTR: decode header: local_session_id=32860 message_counter=54649835
19:38:10.117 MTR: >          Decrypted message: protocol_id:1 opcode=1 exchange_id=44191
19:38:10.119 MTR: .          Removed packet from sending list id=91241734
19:38:10.135 MTR: >OK        ( 32860) exch=44191 not found
19:38:10.142 MTR: <Ack*      ( 32860) ack=54649835 id=91241737 {reliable}
19:38:10.149 MTR: sending packet to '[192.168.188.45]:53639'
19:38:10.155 MTR: UDP received from [192.168.188.45]:53639
19:38:10.159 MTR: decode header: local_session_id=32860 message_counter=54649836
19:38:10.166 MTR: >          Decrypted message: protocol_id:1 opcode=2 exchange_id=44192
19:38:10.184 BRY: GC from 53309 to 40229 bytes, objects freed 91/408 (in 4 ms) - slots from 462/653 to 373/653
19:38:10.186 MTR: <snd       ( 32860) id=91241738 exch=44192 rack=54649836
19:38:10.202 MTR: sending packet to '[192.168.188.45]:53639'
19:38:10.207 MTR: >Read_Attr1( 32860) [00]001D/0002! (ClientList) - [31U]
19:38:11.865 MTR: .Sub_Done  (      ) sub=65170
19:38:11.870 MTR: UDP received from [192.168.188.45]:53639
19:38:11.873 MTR: decode header: local_session_id=32860 message_counter=54649851
19:38:11.880 MTR: >          Decrypted message: protocol_id:0 opcode=16 exchange_id=44194
19:38:11.893 MTR: .          Removed packet from sending list id=91241750
19:38:11.899 MTR: UDP received from [192.168.188.45]:53639
19:38:11.905 BRY: GC from 50940 to 39053 bytes, objects freed 109/406 (in 4 ms) - slots from 515/653 to 374/653
19:38:11.909 MTR: decode header: local_session_id=32860 message_counter=54649852
19:38:11.926 MTR: >          Decrypted message: protocol_id:1 opcode=1 exchange_id=44195
19:38:11.928 MTR: .          Removed packet from sending list id=91241751
19:38:11.944 MTR: >OK        ( 32860) exch=44195 not found
19:38:11.951 MTR: <Ack*      ( 32860) ack=54649852 id=91241754 {reliable}
19:38:11.958 MTR: sending packet to '[192.168.188.45]:53639'
19:38:11.964 MTR: UDP received from [192.168.188.45]:53639
19:38:11.968 MTR: decode header: local_session_id=32860 message_counter=54649853
19:38:11.974 MTR: >          Decrypted message: protocol_id:1 opcode=2 exchange_id=44196
19:38:11.996 MTR: <snd       ( 32860) id=91241755 exch=44196 rack=54649853
19:38:12.004 MTR: sending packet to '[192.168.188.45]:53639'
19:38:12.010 MTR: >Read_Attr1( 32860) [04]001D/0001! (ServerList) - [3U, 4U, 5U, 6U, 29U, 57U]
19:38:12.025 MTR: sending packet to '[192.168.188.45]:53639'
19:38:12.048 MTR: UDP received from [192.168.188.45]:53639
19:38:12.052 MTR: decode header: local_session_id=32860 message_counter=54649854
19:38:12.059 MTR: >          Decrypted message: protocol_id:0 opcode=16 exchange_id=44189
19:38:12.062 MTR: .          Removed packet from sending list id=91241752
19:38:12.074 MTR: .          Removed packet from sending list id=91241752
19:38:12.080 MTR: UDP received from [192.168.188.45]:53639
19:38:12.088 BRY: GC from 49315 to 37855 bytes, objects freed 105/395 (in 4 ms) - slots from 522/653 to 371/653
19:38:12.090 MTR: decode header: local_session_id=32860 message_counter=54649855
19:38:12.107 MTR: >          Decrypted message: protocol_id:0 opcode=16 exchange_id=44195
19:38:12.109 MTR: .          Removed packet from sending list id=91241754
19:38:12.125 MTR: UDP received from [192.168.188.45]:53639
19:38:12.129 MTR: decode header: local_session_id=32860 message_counter=54649856
19:38:12.136 MTR: >          Decrypted message: protocol_id:1 opcode=1 exchange_id=44196
19:38:12.138 MTR: .          Removed packet from sending list id=91241755
19:38:12.154 MTR: >OK        ( 32860) exch=44196 not found
19:38:12.161 MTR: <Ack*      ( 32860) ack=54649856 id=91241756 {reliable}
19:38:12.168 MTR: sending packet to '[192.168.188.45]:53639'
19:38:12.173 MTR: UDP received from [192.168.188.45]:53639
19:38:12.177 MTR: decode header: local_session_id=32860 message_counter=54649857
19:38:12.184 MTR: >          Decrypted message: protocol_id:1 opcode=2 exchange_id=44197
19:38:12.198 MTR: <snd       ( 32860) id=91241757 exch=44197 rack=54649857
19:38:12.203 MTR: sending packet to '[192.168.188.45]:53639'
19:38:12.208 MTR: >Read_Attr1( 32860) [04]001D/0002! (ClientList) - []
19:38:12.224 MTR: UDP received from [192.168.188.45]:53639
19:38:12.228 MTR: decode header: local_session_id=32860 message_counter=54649858
19:38:12.235 MTR: >          Decrypted message: protocol_id:0 opcode=16 exchange_id=44189
19:38:12.240 MTR: UDP received from [192.168.188.45]:53639
19:38:12.244 MTR: decode header: local_session_id=32860 message_counter=54649859
19:38:12.261 MTR: >          Decrypted message: protocol_id:0 opcode=16 exchange_id=44196
19:38:12.264 MTR: .          Removed packet from sending list id=91241756
19:38:12.279 MTR: UDP received from [192.168.188.45]:53639
19:38:12.283 MTR: decode header: local_session_id=32860 message_counter=54649860
19:38:12.290 MTR: >          Decrypted message: protocol_id:1 opcode=1 exchange_id=44197
19:38:12.292 MTR: .          Removed packet from sending list id=91241757
19:38:12.309 MTR: >OK        ( 32860) exch=44197 not found
19:38:12.316 MTR: <Ack*      ( 32860) ack=54649860 id=91241758 {reliable}
19:38:12.322 MTR: sending packet to '[192.168.188.45]:53639'
19:38:12.327 MTR: UDP received from [192.168.188.45]:53639
19:38:12.332 BRY: GC from 48369 to 38154 bytes, objects freed 77/393 (in 3 ms) - slots from 466/653 to 369/653
19:38:12.336 MTR: decode header: local_session_id=32860 message_counter=54649861
19:38:12.353 MTR: >          Decrypted message: protocol_id:1 opcode=2 exchange_id=44198
19:38:12.367 MTR: <snd       ( 32860) id=91241759 exch=44198 rack=54649861
19:38:12.373 MTR: sending packet to '[192.168.188.45]:53639'
19:38:12.377 MTR: >Read_Attr1( 32860) [04]001D/0003! (PartsList) - []
19:38:12.389 MTR: UDP received from [192.168.188.45]:53639
19:38:12.393 MTR: decode header: local_session_id=32860 message_counter=54649862
19:38:12.400 MTR: >          Decrypted message: protocol_id:0 opcode=16 exchange_id=44197
19:38:12.403 MTR: .          Removed packet from sending list id=91241758
19:38:12.418 MTR: UDP received from [192.168.188.45]:53639
19:38:12.422 MTR: decode header: local_session_id=32860 message_counter=54649863
19:38:12.429 MTR: >          Decrypted message: protocol_id:1 opcode=1 exchange_id=44198
19:38:12.432 MTR: .          Removed packet from sending list id=91241759
19:38:12.448 MTR: >OK        ( 32860) exch=44198 not found
19:38:12.455 MTR: <Ack*      ( 32860) ack=54649863 id=91241760 {reliable}
19:38:12.461 MTR: sending packet to '[192.168.188.45]:53639'
19:38:12.477 MTR: UDP received from [192.168.188.45]:53639
19:38:12.481 MTR: decode header: local_session_id=32860 message_counter=54649864
19:38:12.488 MTR: >          Decrypted message: protocol_id:0 opcode=16 exchange_id=44198
19:38:12.491 MTR: .          Removed packet from sending list id=91241760
19:38:16.206 MTR: UDP received from [fe80::c0af:3d89:e933:b760]:5540
19:38:16.219 BRY: GC from 48420 to 38301 bytes, objects freed 98/402 (in 3 ms) - slots from 531/653 to 385/653
19:38:16.222 MTR: >Received  (     0) CASE_Sigma1 from [fe80::c0af:3d89:e933:b760]:5540
19:38:16.248 MTR: SEARCHING: destinationId=C1E9CDB4B062124820B7E567370D950E3C8C41E083F87D1395D09B10325BC808
19:38:16.273 MTR: SIGMA1: candidateDestinationId=7421960D00309F3BCC10759FD80D240DA988948282E95C6780B8779A08C6CE87
19:38:16.290 BRY: GC from 48620 to 41781 bytes, objects freed 101/449 (in 3 ms) - slots from 496/653 to 404/653
19:38:16.311 MTR: SIGMA1: candidateDestinationId=C1E9CDB4B062124820B7E567370D950E3C8C41E083F87D1395D09B10325BC808
19:38:16.316 MTR: +Session   ( 11777) from '[fe80::c0af:3d89:e933:b760]:5540'
19:38:16.691 BRY: GC from 52026 to 42550 bytes, objects freed 124/435 (in 4 ms) - slots from 486/653 to 392/653
19:38:16.703 MTR: <Replied   (     0) CASE_Sigma2
19:38:16.708 MTR: New Connection (CASE id=11777) from [fe80::c0af:3d89:e933:b760]:5540
19:38:16.713 MTR: error sending packet to '[fe80::c0af:3d89:e933:b760]:5540'
19:38:16.923 MTR: UDP received from [fe80::c0af:3d89:e933:b760]:5540
19:38:16.930 MTR: .          Duplicate unencrypted message = 40710168 ref = 40710168
19:38:16.941 MTR: -Session   (     0) (removed)
19:38:17.051 MTR: .          Resending packet id=186354437
19:38:17.055 MTR: error sending packet to '[fe80::c0af:3d89:e933:b760]:5540'
19:38:17.408 MTR: .          Resending packet id=186354437
19:38:17.412 MTR: error sending packet to '[fe80::c0af:3d89:e933:b760]:5540'
19:38:17.436 MTR: UDP received from [fe80::c0af:3d89:e933:b760]:5540
19:38:17.442 MTR: .          Duplicate unencrypted message = 40710168 ref = 40710168
19:38:17.735 MTR: .          Resending packet id=186354437
19:38:17.738 MTR: error sending packet to '[fe80::c0af:3d89:e933:b760]:5540'
19:38:18.228 MTR: .          Resending packet id=186354437
19:38:18.232 MTR: error sending packet to '[fe80::c0af:3d89:e933:b760]:5540'
19:38:18.461 MTR: UDP received from [fe80::c0af:3d89:e933:b760]:5540
19:38:18.468 MTR: .          Duplicate unencrypted message = 40710168 ref = 40710168
19:38:19.076 MTR: .          Resending packet id=186354437
19:38:19.079 MTR: error sending packet to '[fe80::c0af:3d89:e933:b760]:5540'
19:38:20.002 MTR: UDP received from [fe80::4ad6:d5ff:fe67:6c4b]:5540
19:38:20.017 BRY: GC from 52821 to 39812 bytes, objects freed 127/405 (in 4 ms) - slots from 503/653 to 383/653
19:38:20.020 MTR: >Received  (     0) CASE_Sigma1 from [fe80::4ad6:d5ff:fe67:6c4b]:5540
19:38:20.046 MTR: SEARCHING: destinationId=2424E9D24546ECD28FA323EBC82C847431E35A6D1A78E83B682DBDA9885EDC00
19:38:20.071 MTR: SIGMA1: candidateDestinationId=E868D5A8AC0DB1DED172A657FA2226EC4DDA1962E94754D7E1CFAF4F24360B68
19:38:20.091 BRY: GC from 50088 to 43358 bytes, objects freed 104/454 (in 4 ms) - slots from 492/653 to 404/653
19:38:20.110 MTR: SIGMA1: candidateDestinationId=2424E9D24546ECD28FA323EBC82C847431E35A6D1A78E83B682DBDA9885EDC00
19:38:20.114 MTR: +Session   ( 58787) from '[fe80::4ad6:d5ff:fe67:6c4b]:5540'
19:38:20.490 BRY: GC from 54090 to 44063 bytes, objects freed 121/439 (in 4 ms) - slots from 485/653 to 393/653
19:38:20.501 MTR: <Replied   (     0) CASE_Sigma2
19:38:20.506 MTR: New Connection (CASE id=58787) from [fe80::4ad6:d5ff:fe67:6c4b]:5540
19:38:20.511 MTR: error sending packet to '[fe80::4ad6:d5ff:fe67:6c4b]:5540'
19:38:20.553 MTR: .          Resending packet id=186354437
19:38:20.556 MTR: error sending packet to '[fe80::c0af:3d89:e933:b760]:5540'
19:38:20.665 MTR: UDP received from [fe80::c0af:3d89:e933:b760]:5540
19:38:20.673 MTR: .          Duplicate unencrypted message = 40710168 ref = 40710168
19:38:20.677 MTR: UDP received from [fe80::4ad6:d5ff:fe67:6c4b]:5540
19:38:20.684 MTR: .          Duplicate unencrypted message = 123575503 ref = 123575503
19:38:20.851 MTR: .          Resending packet id=88464004
19:38:20.854 MTR: error sending packet to '[fe80::4ad6:d5ff:fe67:6c4b]:5540'
19:38:21.143 MTR: UDP received from [fe80::4ad6:d5ff:fe67:6c4b]:5540
19:38:21.150 MTR: .          Duplicate unencrypted message = 123575503 ref = 123575503
19:38:21.231 MTR: .          Resending packet id=88464004
19:38:21.234 MTR: error sending packet to '[fe80::4ad6:d5ff:fe67:6c4b]:5540'
19:38:21.580 MTR: .          Resending packet id=88464004
19:38:21.584 MTR: error sending packet to '[fe80::4ad6:d5ff:fe67:6c4b]:5540'
19:38:21.743 MTR: UDP received from [fe80::5660:9ff:fedf:8a1c]:5540
19:38:21.754 MTR: >Received  (     0) CASE_Sigma1 from [fe80::5660:9ff:fedf:8a1c]:5540
19:38:21.762 BRY: GC from 54397 to 43248 bytes, objects freed 104/425 (in 3 ms) - slots from 490/653 to 396/653
19:38:21.776 MTR: SEARCHING: destinationId=FCB865F840BD97BA41064AE800E23389AEBB8D2E702CA24FCC4E932D54162EDB
19:38:21.802 MTR: SIGMA1: candidateDestinationId=35BA9093D24A074AD915E2042533BCEA5DF3ACBA4877AE75F8FD3F5FBFDF7476
19:38:21.826 BRY: GC from 53511 to 47029 bytes, objects freed 101/476 (in 3 ms) - slots from 500/653 to 416/653
19:38:21.841 MTR: SIGMA1: candidateDestinationId=FCB865F840BD97BA41064AE800E23389AEBB8D2E702CA24FCC4E932D54162EDB
19:38:21.846 MTR: +Session   ( 28320) from '[fe80::5660:9ff:fedf:8a1c]:5540'
19:38:22.221 BRY: GC from 57355 to 47374 bytes, objects freed 120/458 (in 4 ms) - slots from 496/653 to 404/653
19:38:22.232 MTR: <Replied   (     0) CASE_Sigma2
19:38:22.237 MTR: New Connection (CASE id=28320) from [fe80::5660:9ff:fedf:8a1c]:5540
19:38:22.243 MTR: error sending packet to '[fe80::5660:9ff:fedf:8a1c]:5540'
19:38:22.248 MTR: UDP received from [fe80::4ad6:d5ff:fe67:6c4b]:5540
19:38:22.256 MTR: .          Duplicate unencrypted message = 123575503 ref = 123575503
19:38:22.261 MTR: UDP received from [fe80::5660:9ff:fedf:8a1c]:5540
19:38:22.267 MTR: .          Duplicate unencrypted message = 8886663 ref = 8886663
19:38:22.270 MTR: .          Resending packet id=88464004
19:38:22.284 MTR: error sending packet to '[fe80::4ad6:d5ff:fe67:6c4b]:5540'
19:38:22.605 MTR: .          Resending packet id=179675199
19:38:22.609 MTR: error sending packet to '[fe80::5660:9ff:fedf:8a1c]:5540'
19:38:22.923 MTR: .          Resending packet id=179675199
19:38:22.926 MTR: error sending packet to '[fe80::5660:9ff:fedf:8a1c]:5540'
19:38:22.964 MTR: UDP received from [fe80::5660:9ff:fedf:8a1c]:5540
19:38:22.971 MTR: .          Duplicate unencrypted message = 8886663 ref = 8886663
19:38:22.983 MTR: .          (     0) Unacked packet '[fe80::c0af:3d89:e933:b760]:5540' msg_id=186354437
19:38:23.079 MTR: .          Resending packet id=88464004
19:38:23.082 MTR: error sending packet to '[fe80::4ad6:d5ff:fe67:6c4b]:5540'
19:38:23.288 MTR: .          Resending packet id=179675199
19:38:23.292 MTR: error sending packet to '[fe80::5660:9ff:fedf:8a1c]:5540'
19:38:23.688 MTR: UDP received from [fe80::4ad6:d5ff:fe67:6c4b]:5540
19:38:23.695 MTR: .          Duplicate unencrypted message = 123575503 ref = 123575503
19:38:23.779 MTR: .          Resending packet id=179675199
19:38:23.783 MTR: error sending packet to '[fe80::5660:9ff:fedf:8a1c]:5540'
19:38:23.993 MTR: UDP received from [fe80::5660:9ff:fedf:8a1c]:5540
19:38:24.000 MTR: .          Duplicate unencrypted message = 8886663 ref = 8886663
19:38:24.589 MTR: .          Resending packet id=88464004
19:38:24.593 MTR: error sending packet to '[fe80::4ad6:d5ff:fe67:6c4b]:5540'
19:38:24.619 MTR: .          Resending packet id=179675199
19:38:24.623 MTR: error sending packet to '[fe80::5660:9ff:fedf:8a1c]:5540'
19:38:24.820 BRY: GC from 57668 to 44368 bytes, objects freed 120/428 (in 4 ms) - slots from 495/653 to 397/653
19:38:25.419 MTR: UDP received from [fe80::5660:9ff:fedf:8a1c]:5540
19:38:25.426 MTR: .          Duplicate unencrypted message = 8886663 ref = 8886663
19:38:26.000 MTR: .          Resending packet id=179675199
19:38:26.004 MTR: error sending packet to '[fe80::5660:9ff:fedf:8a1c]:5540'
19:38:26.189 MTR: UDP received from [fe80::c0af:3d89:e933:b760]:5540
19:38:26.200 MTR: >Received  (     0) CASE_Sigma1 from [fe80::c0af:3d89:e933:b760]:5540
19:38:26.217 MTR: SEARCHING: destinationId=F2036ADD511E8516939616603323D15F22FFDF49404174D8545F454C092C6F0F
19:38:26.240 BRY: GC from 54627 to 49939 bytes, objects freed 64/500 (in 2 ms) - slots from 528/653 to 434/653
19:38:26.248 MTR: SIGMA1: candidateDestinationId=5DEA79EB2989AB6B1A55D4A10C9A6D8243F30BC0F8FF61073937B808480270BE
19:38:26.290 MTR: SIGMA1: candidateDestinationId=F2036ADD511E8516939616603323D15F22FFDF49404174D8545F454C092C6F0F
19:38:26.294 MTR: +Session   ( 17937) from '[fe80::c0af:3d89:e933:b760]:5540'
19:38:26.654 BRY: GC from 60189 to 47034 bytes, objects freed 176/456 (in 5 ms) - slots from 528/653 to 409/653
19:38:26.682 MTR: <Replied   (     0) CASE_Sigma2
19:38:26.687 MTR: New Connection (CASE id=17937) from [fe80::c0af:3d89:e933:b760]:5540
19:38:26.691 MTR: error sending packet to '[fe80::c0af:3d89:e933:b760]:5540'
19:38:26.695 MTR: .          (     0) Unacked packet '[fe80::4ad6:d5ff:fe67:6c4b]:5540' msg_id=88464004
19:38:26.855 MTR: UDP received from [fe80::c0af:3d89:e933:b760]:5540
19:38:26.862 MTR: .          Duplicate unencrypted message = 40710169 ref = 40710169
19:38:27.029 MTR: .          Resending packet id=134643238
19:38:27.032 MTR: error sending packet to '[fe80::c0af:3d89:e933:b760]:5540'
19:38:27.074 BRY: GC from 57319 to 47859 bytes, objects freed 77/450 (in 4 ms) - slots from 471/653 to 407/653
19:38:27.394 MTR: .          Resending packet id=134643238
19:38:27.398 MTR: error sending packet to '[fe80::c0af:3d89:e933:b760]:5540'
19:38:27.496 MTR: UDP received from [fe80::c0af:3d89:e933:b760]:5540
19:38:27.503 MTR: .          Duplicate unencrypted message = 40710169 ref = 40710169
19:38:27.701 MTR: .          Resending packet id=134643238
19:38:27.705 MTR: error sending packet to '[fe80::c0af:3d89:e933:b760]:5540'
19:38:28.308 MTR: .          Resending packet id=134643238
19:38:28.312 MTR: error sending packet to '[fe80::c0af:3d89:e933:b760]:5540'
19:38:28.329 MTR: .          (     0) Unacked packet '[fe80::5660:9ff:fedf:8a1c]:5540' msg_id=179675199
19:38:28.597 MTR: UDP received from [fe80::c0af:3d89:e933:b760]:5540
19:38:28.604 MTR: .          Duplicate unencrypted message = 40710169 ref = 40710169
19:38:29.102 MTR: .          Resending packet id=134643238
19:38:29.105 MTR: error sending packet to '[fe80::c0af:3d89:e933:b760]:5540'
19:38:30.033 MTR: UDP received from [fe80::c0af:3d89:e933:b760]:5540
19:38:30.040 MTR: .          Duplicate unencrypted message = 40710169 ref = 40710169
19:38:30.511 MTR: .          Resending packet id=134643238
19:38:30.515 MTR: error sending packet to '[fe80::c0af:3d89:e933:b760]:5540'
19:38:30.735 WIF: Checking connection...
19:38:31.360 MTR: UDP received from [fe80::4ad6:d5ff:fe67:6c4b]:5540
19:38:31.371 MTR: >Received  (     0) CASE_Sigma1 from [fe80::4ad6:d5ff:fe67:6c4b]:5540
19:38:31.388 MTR: SEARCHING: destinationId=1E64810AEC4F7C8C36E6CF1916DE5077873D4160033D9E244D134FEA2463B1C0
19:38:31.394 BRY: GC from 58124 to 48391 bytes, objects freed 119/471 (in 3 ms) - slots from 570/653 to 419/653
19:38:31.428 MTR: SIGMA1: candidateDestinationId=273DC526E2792F899B55AFF16CFA8827BBB90EFED5C3E0038F84231F82E93DBF
19:38:31.460 MTR: SIGMA1: candidateDestinationId=1E64810AEC4F7C8C36E6CF1916DE5077873D4160033D9E244D134FEA2463B1C0
19:38:31.470 BRY: GC from 58634 to 48249 bytes, objects freed 151/469 (in 5 ms) - slots from 511/653 to 416/653
19:38:31.472 MTR: +Session   ( 33995) from '[fe80::4ad6:d5ff:fe67:6c4b]:5540'
19:38:31.852 MTR: <Replied   (     0) CASE_Sigma2
19:38:31.859 BRY: GC from 58772 to 53345 bytes, objects freed 57/494 (in 3 ms) - slots from 491/653 to 427/653
19:38:31.862 MTR: New Connection (CASE id=33995) from [fe80::4ad6:d5ff:fe67:6c4b]:5540
19:38:31.877 MTR: error sending packet to '[fe80::4ad6:d5ff:fe67:6c4b]:5540'
19:38:31.882 MTR: UDP received from [fe80::4ad6:d5ff:fe67:6c4b]:5540
19:38:31.889 MTR: .          Duplicate unencrypted message = 123575504 ref = 123575504
19:38:32.216 MTR: .          Resending packet id=212368323
19:38:32.220 MTR: error sending packet to '[fe80::4ad6:d5ff:fe67:6c4b]:5540'
19:38:32.490 MTR: UDP received from [fe80::5660:9ff:fedf:8a1c]:5540
19:38:32.501 MTR: >Received  (     0) CASE_Sigma1 from [fe80::5660:9ff:fedf:8a1c]:5540
19:38:32.518 MTR: SEARCHING: destinationId=466B7230EFC974331188BC26646D5E5EEED8B5BB7301DC0192583E59FE8960FF
19:38:32.539 BRY: GC from 63626 to 53833 bytes, objects freed 99/522 (in 3 ms) - slots from 547/653 to 452/653
19:38:32.550 MTR: SIGMA1: candidateDestinationId=8F1851CCFE5C8DB688614016356194BBBB7A73B8F6C9BA5B9D90429F47E7B7DB
19:38:32.582 MTR: SIGMA1: candidateDestinationId=466B7230EFC974331188BC26646D5E5EEED8B5BB7301DC0192583E59FE8960FF
19:38:32.587 MTR: +Session   ( 38501) from '[fe80::5660:9ff:fedf:8a1c]:5540'
19:38:32.945 BRY: GC from 64253 to 51888 bytes, objects freed 175/479 (in 5 ms) - slots from 540/653 to 424/653
19:38:32.975 MTR: <Replied   (     0) CASE_Sigma2
19:38:32.980 MTR: New Connection (CASE id=38501) from [fe80::5660:9ff:fedf:8a1c]:5540
19:38:32.985 MTR: error sending packet to '[fe80::5660:9ff:fedf:8a1c]:5540'
19:38:32.991 MTR: UDP received from [fe80::4ad6:d5ff:fe67:6c4b]:5540
19:38:32.999 MTR: .          Duplicate unencrypted message = 123575504 ref = 123575504
19:38:33.007 BRY: GC from 62227 to 53240 bytes, objects freed 72/479 (in 4 ms) - slots from 483/653 to 424/653
19:38:33.010 MTR: UDP received from [fe80::5660:9ff:fedf:8a1c]:5540
19:38:33.026 MTR: .          Duplicate unencrypted message = 8886664 ref = 8886664
19:38:33.030 MTR: .          (     0) Unacked packet '[fe80::c0af:3d89:e933:b760]:5540' msg_id=134643238
19:38:33.043 MTR: .          Resending packet id=212368323
19:38:33.047 MTR: error sending packet to '[fe80::4ad6:d5ff:fe67:6c4b]:5540'
19:38:33.343 MTR: .          Resending packet id=173832896
19:38:33.347 MTR: error sending packet to '[fe80::5660:9ff:fedf:8a1c]:5540'
19:38:33.380 MTR: UDP received from [fe80::4ad6:d5ff:fe67:6c4b]:5540
19:38:33.388 MTR: .          Duplicate unencrypted message = 123575504 ref = 123575504
19:38:33.394 MTR: .          Resending packet id=212368323
19:38:33.398 MTR: error sending packet to '[fe80::4ad6:d5ff:fe67:6c4b]:5540'
19:38:33.665 MTR: .          Resending packet id=173832896
19:38:33.669 MTR: error sending packet to '[fe80::5660:9ff:fedf:8a1c]:5540'
19:38:33.717 MTR: UDP received from [fe80::5660:9ff:fedf:8a1c]:5540
19:38:33.724 MTR: .          Duplicate unencrypted message = 8886664 ref = 8886664
19:38:33.933 MTR: .          Resending packet id=212368323
19:38:33.937 MTR: error sending packet to '[fe80::4ad6:d5ff:fe67:6c4b]:5540'
19:38:34.025 MTR: .          Resending packet id=173832896
19:38:34.029 MTR: error sending packet to '[fe80::5660:9ff:fedf:8a1c]:5540'
19:38:34.528 MTR: .          Resending packet id=173832896
19:38:34.531 MTR: error sending packet to '[fe80::5660:9ff:fedf:8a1c]:5540'
19:38:34.744 MTR: UDP received from [fe80::5660:9ff:fedf:8a1c]:5540
19:38:34.751 MTR: .          Duplicate unencrypted message = 8886664 ref = 8886664
19:38:34.785 MTR: .          Resending packet id=212368323
19:38:34.788 MTR: error sending packet to '[fe80::4ad6:d5ff:fe67:6c4b]:5540'
19:38:35.052 MTR: UDP received from [fe80::4ad6:d5ff:fe67:6c4b]:5540
19:38:35.059 MTR: .          Duplicate unencrypted message = 123575504 ref = 123575504
19:38:35.389 MTR: .          Resending packet id=173832896
19:38:35.393 MTR: error sending packet to '[fe80::5660:9ff:fedf:8a1c]:5540'
19:38:35.938 MTR: -Session   (     0) (removed)
19:38:36.049 MTR: .          Resending packet id=212368323
19:38:36.053 MTR: error sending packet to '[fe80::4ad6:d5ff:fe67:6c4b]:5540'
19:38:36.171 MTR: UDP received from [fe80::5660:9ff:fedf:8a1c]:5540
19:38:36.178 MTR: .          Duplicate unencrypted message = 8886664 ref = 8886664
19:38:36.822 MTR: .          Resending packet id=173832896
19:38:36.826 MTR: error sending packet to '[fe80::5660:9ff:fedf:8a1c]:5540'
19:38:38.132 MTR: .          (     0) Unacked packet '[fe80::4ad6:d5ff:fe67:6c4b]:5540' msg_id=212368323
19:38:38.373 MTR: UDP received from [fe80::c0af:3d89:e933:b760]:5540
19:38:38.384 MTR: >Received  (     0) CASE_Sigma1 from [fe80::c0af:3d89:e933:b760]:5540
19:38:38.391 BRY: GC from 63546 to 50013 bytes, objects freed 137/467 (in 4 ms) - slots from 562/653 to 424/653
19:38:38.407 MTR: SEARCHING: destinationId=AE5F30090A386165085C40CBB3C44028D7995C49319E617D4E23186CF2362E88
19:38:38.433 MTR: SIGMA1: candidateDestinationId=6EB1EEF320A22700C506FA769E083DABC6FEEFBECF8B3EC4CBA3E836890B3EF5
19:38:38.455 BRY: GC from 60316 to 53762 bytes, objects freed 103/519 (in 4 ms) - slots from 534/653 to 447/653
19:38:38.471 MTR: SIGMA1: candidateDestinationId=AE5F30090A386165085C40CBB3C44028D7995C49319E617D4E23186CF2362E88
19:38:38.476 MTR: +Session   ( 49881) from '[fe80::c0af:3d89:e933:b760]:5540'
19:38:38.852 BRY: GC from 64148 to 54235 bytes, objects freed 119/501 (in 4 ms) - slots from 525/653 to 434/653
19:38:38.863 MTR: <Replied   (     0) CASE_Sigma2
19:38:38.868 MTR: New Connection (CASE id=49881) from [fe80::c0af:3d89:e933:b760]:5540
19:38:38.873 MTR: error sending packet to '[fe80::c0af:3d89:e933:b760]:5540'
19:38:39.141 MTR: UDP received from [fe80::c0af:3d89:e933:b760]:5540
19:38:39.149 MTR: .          Duplicate unencrypted message = 40710170 ref = 40710170
19:38:39.186 MTR: .          Resending packet id=252467799
19:38:39.189 MTR: error sending packet to '[fe80::c0af:3d89:e933:b760]:5540'
19:38:39.257 MTR: .          (     0) Unacked packet '[fe80::5660:9ff:fedf:8a1c]:5540' msg_id=173832896
19:38:39.526 MTR: .          Resending packet id=252467799
19:38:39.529 MTR: error sending packet to '[fe80::c0af:3d89:e933:b760]:5540'
19:38:39.654 MTR: UDP received from [fe80::c0af:3d89:e933:b760]:5540
19:38:39.662 MTR: .          Duplicate unencrypted message = 40710170 ref = 40710170
19:38:39.861 MTR: .          Resending packet id=252467799
19:38:39.864 MTR: error sending packet to '[fe80::c0af:3d89:e933:b760]:5540'
19:38:40.413 MTR: .          Resending packet id=252467799
19:38:40.416 MTR: error sending packet to '[fe80::c0af:3d89:e933:b760]:5540'
19:38:40.924 MTR: UDP received from [fe80::c0af:3d89:e933:b760]:5540
19:38:40.931 MTR: .          Duplicate unencrypted message = 40710170 ref = 40710170
19:38:41.352 MTR: .          Resending packet id=252467799
19:38:41.356 MTR: error sending packet to '[fe80::c0af:3d89:e933:b760]:5540'
19:38:42.120 MTR: UDP received from [fe80::c0af:3d89:e933:b760]:5540
19:38:42.127 MTR: .          Duplicate unencrypted message = 40710170 ref = 40710170
19:38:42.610 MTR: .          Resending packet id=252467799
19:38:42.614 MTR: error sending packet to '[fe80::c0af:3d89:e933:b760]:5540'
19:38:44.074 MTR: UDP received from [fe80::4ad6:d5ff:fe67:6c4b]:5540
19:38:44.084 BRY: GC from 64781 to 51779 bytes, objects freed 146/472 (in 4 ms) - slots from 569/653 to 424/653
19:38:44.091 MTR: >Received  (     0) CASE_Sigma1 from [fe80::4ad6:d5ff:fe67:6c4b]:5540
19:38:44.108 MTR: SEARCHING: destinationId=D4FFBDA5E63AFB9019879217FFE14E9B84208F4A0BAF163FB7DB9AFD1239CE61
19:38:44.133 MTR: SIGMA1: candidateDestinationId=DC8961E1D0F3D4667C0FB95F2725633BDDEB6E93080C84E31E4AC170D6B5C52C
19:38:44.148 BRY: GC from 62081 to 55184 bytes, objects freed 102/522 (in 4 ms) - slots from 545/653 to 452/653
19:38:44.171 MTR: SIGMA1: candidateDestinationId=D4FFBDA5E63AFB9019879217FFE14E9B84208F4A0BAF163FB7DB9AFD1239CE61
19:38:44.177 MTR: +Session   (  1022) from '[fe80::4ad6:d5ff:fe67:6c4b]:5540'
19:38:44.552 BRY: GC from 65805 to 56329 bytes, objects freed 124/514 (in 4 ms) - slots from 537/653 to 443/653
19:38:44.564 MTR: <Replied   (     0) CASE_Sigma2
19:38:44.569 MTR: New Connection (CASE id=1022) from [fe80::4ad6:d5ff:fe67:6c4b]:5540
19:38:44.574 MTR: error sending packet to '[fe80::4ad6:d5ff:fe67:6c4b]:5540'
19:38:44.579 MTR: UDP received from [fe80::5660:9ff:fedf:8a1c]:5540
19:38:44.590 MTR: >Received  (     0) CASE_Sigma1 from [fe80::5660:9ff:fedf:8a1c]:5540
19:38:44.607 MTR: SEARCHING: destinationId=047E6DE50B9B81973967641FADAD60F16EB6745C29E96D519AC810ED87355D9E
19:38:44.614 BRY: GC from 66598 to 57204 bytes, objects freed 85/518 (in 4 ms) - slots from 532/653 to 449/653
19:38:44.648 MTR: SIGMA1: candidateDestinationId=197A9888E278BC900686107A167CBD5D347D883E1EAE19D3115F26406BDFC5CF
19:38:44.682 MTR: SIGMA1: candidateDestinationId=047E6DE50B9B81973967641FADAD60F16EB6745C29E96D519AC810ED87355D9E
19:38:44.690 BRY: GC from 67458 to 57172 bytes, objects freed 150/517 (in 5 ms) - slots from 537/653 to 445/653
19:38:44.704 MTR: +Session   ( 26168) from '[fe80::5660:9ff:fedf:8a1c]:5540'
19:38:45.075 MTR: <Replied   (     0) CASE_Sigma2
19:38:45.082 BRY: GC from 67792 to 62270 bytes, objects freed 58/543 (in 3 ms) - slots from 525/653 to 458/653
19:38:45.085 MTR: New Connection (CASE id=26168) from [fe80::5660:9ff:fedf:8a1c]:5540
19:38:45.100 MTR: error sending packet to '[fe80::5660:9ff:fedf:8a1c]:5540'
19:38:45.106 MTR: UDP received from [fe80::5660:9ff:fedf:8a1c]:5540
19:38:45.114 MTR: .          Duplicate unencrypted message = 8886665 ref = 8886665
19:38:45.118 MTR: UDP received from [fe80::4ad6:d5ff:fe67:6c4b]:5540
19:38:45.126 MTR: .          Duplicate unencrypted message = 123575505 ref = 123575505
19:38:45.129 MTR: .          (     0) Unacked packet '[fe80::c0af:3d89:e933:b760]:5540' msg_id=252467799
19:38:45.142 MTR: .          Resending packet id=30563759
19:38:45.145 MTR: error sending packet to '[fe80::4ad6:d5ff:fe67:6c4b]:5540'
19:38:45.391 MTR: UDP received from [fe80::5660:9ff:fedf:8a1c]:5540
19:38:45.399 MTR: .          Duplicate unencrypted message = 8886665 ref = 8886665
19:38:45.403 MTR: UDP received from [fe80::4ad6:d5ff:fe67:6c4b]:5540
19:38:45.410 MTR: .          Duplicate unencrypted message = 123575505 ref = 123575505
19:38:45.413 MTR: .          Resending packet id=68531872
19:38:45.427 MTR: error sending packet to '[fe80::5660:9ff:fedf:8a1c]:5540'
19:38:45.497 MTR: .          Resending packet id=30563759
19:38:45.500 MTR: error sending packet to '[fe80::4ad6:d5ff:fe67:6c4b]:5540'
19:38:45.783 MTR: .          Resending packet id=68531872
19:38:45.787 MTR: error sending packet to '[fe80::5660:9ff:fedf:8a1c]:5540'
19:38:45.914 MTR: .          Resending packet id=30563759
19:38:45.917 MTR: error sending packet to '[fe80::4ad6:d5ff:fe67:6c4b]:5540'
19:38:46.139 MTR: .          Resending packet id=68531872
19:38:46.143 MTR: error sending packet to '[fe80::5660:9ff:fedf:8a1c]:5540'
19:38:46.416 MTR: UDP received from [fe80::4ad6:d5ff:fe67:6c4b]:5540
19:38:46.423 MTR: .          Duplicate unencrypted message = 123575505 ref = 123575505
19:38:46.428 MTR: UDP received from [fe80::5660:9ff:fedf:8a1c]:5540
19:38:46.435 MTR: .          Duplicate unencrypted message = 8886665 ref = 8886665
19:38:46.438 MTR: .          Resending packet id=30563759
19:38:46.451 MTR: error sending packet to '[fe80::4ad6:d5ff:fe67:6c4b]:5540'
19:38:46.648 MTR: .          Resending packet id=68531872
19:38:46.652 MTR: error sending packet to '[fe80::5660:9ff:fedf:8a1c]:5540'
19:38:46.921 MTR: -Session   (     0) (removed)
19:38:47.337 MTR: .          Resending packet id=30563759
19:38:47.341 MTR: error sending packet to '[fe80::4ad6:d5ff:fe67:6c4b]:5540'
19:38:47.498 MTR: .          Resending packet id=68531872
19:38:47.502 MTR: error sending packet to '[fe80::5660:9ff:fedf:8a1c]:5540'
19:38:47.851 MTR: UDP received from [fe80::4ad6:d5ff:fe67:6c4b]:5540
19:38:47.858 MTR: .          Duplicate unencrypted message = 123575505 ref = 123575505
19:38:48.156 MTR: UDP received from [fe80::5660:9ff:fedf:8a1c]:5540
19:38:48.163 MTR: .          Duplicate unencrypted message = 8886665 ref = 8886665
19:38:48.636 MTR: .          Resending packet id=30563759
19:38:48.640 MTR: error sending packet to '[fe80::4ad6:d5ff:fe67:6c4b]:5540'
19:38:48.951 MTR: .          Resending packet id=68531872
19:38:48.955 MTR: error sending packet to '[fe80::5660:9ff:fedf:8a1c]:5540'
19:38:49.243 BRY: GC from 72557 to 55583 bytes, objects freed 167/491 (in 5 ms) - slots from 571/653 to 436/653
19:38:50.965 MTR: .          (     0) Unacked packet '[fe80::5660:9ff:fedf:8a1c]:5540' msg_id=68531872
19:38:51.047 MTR: .          (     0) Unacked packet '[fe80::4ad6:d5ff:fe67:6c4b]:5540' msg_id=30563759
19:38:52.912 WIF: Checking connection...
19:38:53.124 MTR: UDP received from [fe80::c0af:3d89:e933:b760]:5540
19:38:53.135 MTR: >Received  (     0) CASE_Sigma1 from [fe80::c0af:3d89:e933:b760]:5540
19:38:53.153 MTR: SEARCHING: destinationId=78CF9A315A7FB9366976DD6556C1D52568B2AB03AF1ECAF4158F308806911F39
19:38:53.172 BRY: GC from 65834 to 58644 bytes, objects freed 92/554 (in 3 ms) - slots from 599/653 to 474/653
19:38:53.184 MTR: SIGMA1: candidateDestinationId=6E8CA6830EF543896CF060ECF57E69F572700D7A75837EBD6839C2E251B2A72C
19:38:53.216 MTR: SIGMA1: candidateDestinationId=78CF9A315A7FB9366976DD6556C1D52568B2AB03AF1ECAF4158F308806911F39
19:38:53.221 MTR: +Session   (  1513) from '[fe80::c0af:3d89:e933:b760]:5540'
19:38:53.579 BRY: GC from 69196 to 56755 bytes, objects freed 177/511 (in 5 ms) - slots from 564/653 to 445/653
19:38:53.608 MTR: <Replied   (     0) CASE_Sigma2
19:38:53.613 MTR: New Connection (CASE id=1513) from [fe80::c0af:3d89:e933:b760]:5540
19:38:53.618 MTR: error sending packet to '[fe80::c0af:3d89:e933:b760]:5540'
19:38:53.786 MTR: UDP received from [fe80::c0af:3d89:e933:b760]:5540
19:38:53.793 MTR: .          Duplicate unencrypted message = 40710171 ref = 40710171
19:38:53.946 BRY: GC from 67012 to 56720 bytes, objects freed 89/502 (in 4 ms) - slots from 524/653 to 445/653
19:38:53.948 MTR: -Session   (     0) (removed)
19:38:53.966 MTR: .          Resending packet id=179325990
19:38:53.969 MTR: error sending packet to '[fe80::c0af:3d89:e933:b760]:5540'
19:38:54.323 MTR: .          Resending packet id=179325990
19:38:54.326 MTR: error sending packet to '[fe80::c0af:3d89:e933:b760]:5540'
19:38:54.398 MTR: UDP received from [fe80::c0af:3d89:e933:b760]:5540
19:38:54.406 MTR: .          Duplicate unencrypted message = 40710171 ref = 40710171
19:38:54.632 MTR: .          Resending packet id=179325990
19:38:54.635 MTR: error sending packet to '[fe80::c0af:3d89:e933:b760]:5540'
19:38:55.162 MTR: .          Resending packet id=179325990
19:38:55.165 MTR: error sending packet to '[fe80::c0af:3d89:e933:b760]:5540'
19:38:55.425 MTR: UDP received from [fe80::c0af:3d89:e933:b760]:5540
19:38:55.432 MTR: .          Duplicate unencrypted message = 40710171 ref = 40710171
19:38:55.993 MTR: .          Resending packet id=179325990
19:38:55.997 MTR: error sending packet to '[fe80::c0af:3d89:e933:b760]:5540'
19:38:56.970 MTR: UDP received from [fe80::c0af:3d89:e933:b760]:5540
19:38:56.978 MTR: .          Duplicate unencrypted message = 40710171 ref = 40710171
19:38:57.166 MTR: UDP received from [fe80::4ad6:d5ff:fe67:6c4b]:5540
19:38:57.177 MTR: >Received  (     0) CASE_Sigma1 from [fe80::4ad6:d5ff:fe67:6c4b]:5540
19:38:57.194 MTR: SEARCHING: destinationId=2006696A809F0D5A51539755FAED64F9DA5B517456DA70DD6823DDC967ED170E
19:38:57.208 BRY: GC from 67039 to 58676 bytes, objects freed 99/540 (in 4 ms) - slots from 589/653 to 462/653
19:38:57.225 MTR: SIGMA1: candidateDestinationId=3191D1B9351ED56DC4CC2B144B1F2ACF58ABDB0EE4DC7DC125708EF43406B06D
19:38:57.257 MTR: SIGMA1: candidateDestinationId=2006696A809F0D5A51539755FAED64F9DA5B517456DA70DD6823DDC967ED170E
19:38:57.262 MTR: +Session   ( 33286) from '[fe80::4ad6:d5ff:fe67:6c4b]:5540'
19:38:57.497 BRY: GC from 68990 to 58201 bytes, objects freed 152/530 (in 5 ms) - slots from 553/653 to 451/653
19:38:57.649 MTR: <Replied   (     0) CASE_Sigma2
19:38:57.654 MTR: New Connection (CASE id=33286) from [fe80::4ad6:d5ff:fe67:6c4b]:5540
19:38:57.659 MTR: error sending packet to '[fe80::4ad6:d5ff:fe67:6c4b]:5540'
19:38:57.662 MTR: .          Resending packet id=179325990
19:38:57.680 BRY: GC from 68508 to 59168 bytes, objects freed 91/514 (in 3 ms) - slots from 519/653 to 446/653
19:38:57.682 MTR: error sending packet to '[fe80::c0af:3d89:e933:b760]:5540'
19:38:57.700 MTR: UDP received from [fe80::4ad6:d5ff:fe67:6c4b]:5540
19:38:57.708 MTR: .          Duplicate unencrypted message = 123575506 ref = 123575506
19:38:58.020 MTR: .          Resending packet id=202013461
19:38:58.024 MTR: error sending packet to '[fe80::4ad6:d5ff:fe67:6c4b]:5540'
19:38:58.235 MTR: UDP received from [fe80::4ad6:d5ff:fe67:6c4b]:5540
19:38:58.242 MTR: .          Duplicate unencrypted message = 123575506 ref = 123575506
19:38:58.381 MTR: .          Resending packet id=202013461
19:38:58.385 MTR: error sending packet to '[fe80::4ad6:d5ff:fe67:6c4b]:5540'
19:38:58.763 MTR: .          Resending packet id=202013461
19:38:58.769 MTR: error sending packet to '[fe80::4ad6:d5ff:fe67:6c4b]:5540'
19:38:59.007 MTR: UDP received from [fe80::5660:9ff:fedf:8a1c]:5540
19:38:59.019 MTR: >Received  (     0) CASE_Sigma1 from [fe80::5660:9ff:fedf:8a1c]:5540
19:38:59.036 MTR: SEARCHING: destinationId=C40BEF8FA6978B3C52B23B797A5D180D58A047F6D4DE6F5E0E7D93B96C0591AE
19:38:59.056 BRY: GC from 69496 to 63082 bytes, objects freed 69/574 (in 3 ms) - slots from 567/653 to 483/653
19:38:59.066 MTR: SIGMA1: candidateDestinationId=28840884F0BBEE79620063922160E534D47F3026CFE683C9F6A0F3EF95DE3742
19:38:59.098 MTR: SIGMA1: candidateDestinationId=C40BEF8FA6978B3C52B23B797A5D180D58A047F6D4DE6F5E0E7D93B96C0591AE
19:38:59.104 MTR: +Session   ( 32349) from '[fe80::5660:9ff:fedf:8a1c]:5540'
19:38:59.452 BRY: GC from 73326 to 60961 bytes, objects freed 175/529 (in 5 ms) - slots from 571/653 to 455/653
19:38:59.481 MTR: <Replied   (     0) CASE_Sigma2
19:38:59.486 MTR: New Connection (CASE id=32349) from [fe80::5660:9ff:fedf:8a1c]:5540
19:38:59.491 MTR: error sending packet to '[fe80::5660:9ff:fedf:8a1c]:5540'
19:38:59.496 MTR: UDP received from [fe80::4ad6:d5ff:fe67:6c4b]:5540
19:38:59.504 MTR: .          Duplicate unencrypted message = 123575506 ref = 123575506
19:38:59.507 MTR: .          Resending packet id=202013461
19:38:59.524 BRY: GC from 71266 to 62374 bytes, objects freed 71/530 (in 4 ms) - slots from 515/653 to 456/653
19:38:59.526 MTR: error sending packet to '[fe80::4ad6:d5ff:fe67:6c4b]:5540'
19:38:59.621 MTR: UDP received from [fe80::5660:9ff:fedf:8a1c]:5540
19:38:59.628 MTR: .          Duplicate unencrypted message = 8886666 ref = 8886666
19:38:59.790 MTR: .          Resending packet id=99311263
19:38:59.794 MTR: error sending packet to '[fe80::5660:9ff:fedf:8a1c]:5540'
19:39:00.017 MTR: .          (     0) Unacked packet '[fe80::c0af:3d89:e933:b760]:5540' msg_id=179325990
19:39:00.108 MTR: .          Resending packet id=99311263
19:39:00.111 MTR: error sending packet to '[fe80::5660:9ff:fedf:8a1c]:5540'
19:39:00.328 MTR: .          Resending packet id=202013461
19:39:00.331 MTR: error sending packet to '[fe80::4ad6:d5ff:fe67:6c4b]:5540'
19:39:00.338 MTR: UDP received from [fe80::5660:9ff:fedf:8a1c]:5540
19:39:00.346 MTR: .          Duplicate unencrypted message = 8886666 ref = 8886666
19:39:00.458 MTR: .          Resending packet id=99311263
19:39:00.461 MTR: error sending packet to '[fe80::5660:9ff:fedf:8a1c]:5540'
19:39:01.046 MTR: .          Resending packet id=99311263
19:39:01.051 MTR: error sending packet to '[fe80::5660:9ff:fedf:8a1c]:5540'
19:39:01.059 MTR: UDP received from [fe80::4ad6:d5ff:fe67:6c4b]:5540
19:39:01.066 MTR: .          Duplicate unencrypted message = 123575506 ref = 123575506
19:39:01.192 MTR: UDP received from [fe80::5660:9ff:fedf:8a1c]:5540
19:39:01.200 MTR: .          Duplicate unencrypted message = 8886666 ref = 8886666
19:39:01.839 MTR: .          Resending packet id=202013461
19:39:01.843 MTR: error sending packet to '[fe80::4ad6:d5ff:fe67:6c4b]:5540'
19:39:01.936 MTR: .          Resending packet id=99311263
19:39:01.940 MTR: error sending packet to '[fe80::5660:9ff:fedf:8a1c]:5540'
19:39:02.694 MTR: UDP received from [fe80::5660:9ff:fedf:8a1c]:5540
19:39:02.702 MTR: .          Duplicate unencrypted message = 8886666 ref = 8886666
19:39:03.186 MTR: .          Resending packet id=99311263
19:39:03.190 MTR: error sending packet to '[fe80::5660:9ff:fedf:8a1c]:5540'
19:39:04.133 MTR: .          (     0) Unacked packet '[fe80::4ad6:d5ff:fe67:6c4b]:5540' msg_id=202013461
19:39:05.381 MTR: .          (     0) Unacked packet '[fe80::5660:9ff:fedf:8a1c]:5540' msg_id=99311263
19:39:06.870 MTR: <Sub_Alive ( 32860) sub=65170
19:39:06.877 BRY: GC from 72648 to 58330 bytes, objects freed 164/516 (in 3 ms) - slots from 644/684 to 454/653
19:39:06.890 MTR: sending packet to '[192.168.188.45]:53639'
19:39:07.208 MTR: .          Resending packet id=91241761
19:39:07.214 MTR: sending packet to '[192.168.188.45]:53639'
19:39:07.562 MTR: .          Resending packet id=91241761
19:39:07.567 MTR: sending packet to '[192.168.188.45]:53639'
19:39:07.895 MTR: .          Resending packet id=91241761
19:39:07.900 MTR: sending packet to '[192.168.188.45]:53639'
19:39:08.500 MTR: .          Resending packet id=91241761
19:39:08.505 MTR: sending packet to '[192.168.188.45]:53639'
19:39:08.812 MTR: UDP received from [fe80::c0af:3d89:e933:b760]:5540
19:39:08.824 MTR: >Received  (     0) CASE_Sigma1 from [fe80::c0af:3d89:e933:b760]:5540
19:39:08.842 MTR: SEARCHING: destinationId=EA38CF32E5223B6FF269616419436498D421F15B90081B424AA144F7A31D2865
19:39:08.860 BRY: GC from 68601 to 63691 bytes, objects freed 61/588 (in 2 ms) - slots from 588/653 to 495/653
19:39:08.871 MTR: SIGMA1: candidateDestinationId=4F85DA3AA60876611C4134C6A24792DFE3A1C50094C8DE7FE674078ED4698EE2
19:39:08.903 MTR: SIGMA1: candidateDestinationId=EA38CF32E5223B6FF269616419436498D421F15B90081B424AA144F7A31D2865
19:39:08.908 MTR: +Session   ( 16100) from '[fe80::c0af:3d89:e933:b760]:5540'
19:39:09.267 BRY: GC from 74243 to 61802 bytes, objects freed 177/545 (in 5 ms) - slots from 585/653 to 466/653
19:39:09.296 MTR: <Replied   (     0) CASE_Sigma2
19:39:09.301 MTR: New Connection (CASE id=16100) from [fe80::c0af:3d89:e933:b760]:5540
19:39:09.306 MTR: error sending packet to '[fe80::c0af:3d89:e933:b760]:5540'
19:39:09.344 MTR: .          Resending packet id=91241761
19:39:09.350 MTR: sending packet to '[192.168.188.45]:53639'
19:39:09.553 MTR: UDP received from [fe80::c0af:3d89:e933:b760]:5540
19:39:09.561 MTR: .          Duplicate unencrypted message = 40710172 ref = 40710172
19:39:09.625 MTR: .          Resending packet id=81364264
19:39:09.629 MTR: error sending packet to '[fe80::c0af:3d89:e933:b760]:5540'
19:39:09.790 BRY: GC from 72059 to 63097 bytes, objects freed 73/544 (in 4 ms) - slots from 531/653 to 466/653
19:39:09.950 MTR: .          Resending packet id=81364264
19:39:09.954 MTR: error sending packet to '[fe80::c0af:3d89:e933:b760]:5540'
19:39:10.172 MTR: UDP received from [fe80::c0af:3d89:e933:b760]:5540
19:39:10.180 MTR: .          Duplicate unencrypted message = 40710172 ref = 40710172
19:39:10.296 MTR: .          Resending packet id=81364264
19:39:10.300 MTR: error sending packet to '[fe80::c0af:3d89:e933:b760]:5540'
19:39:10.616 MTR: .          Resending packet id=91241761
19:39:10.622 MTR: sending packet to '[192.168.188.45]:53639'
19:39:10.821 MTR: .          Resending packet id=81364264
19:39:10.824 MTR: error sending packet to '[fe80::c0af:3d89:e933:b760]:5540'
19:39:11.195 MTR: UDP received from [fe80::c0af:3d89:e933:b760]:5540
19:39:11.202 MTR: .          Duplicate unencrypted message = 40710172 ref = 40710172
19:39:11.646 MTR: .          Resending packet id=81364264
19:39:11.650 MTR: error sending packet to '[fe80::c0af:3d89:e933:b760]:5540'
19:39:11.954 MTR: -Sub_Del   (      ) sub=65170
19:39:12.709 MTR: .          ( 22666) Unacked packet '[192.168.188.45]:53639' msg_id=91241761
19:39:13.040 MTR: UDP received from [fe80::c0af:3d89:e933:b760]:5540
19:39:13.048 MTR: .          Duplicate unencrypted message = 40710172 ref = 40710172
19:39:13.068 MTR: UDP received from [fe80::4ad6:d5ff:fe67:6c4b]:5540
19:39:13.080 MTR: >Received  (     0) CASE_Sigma1 from [fe80::4ad6:d5ff:fe67:6c4b]:5540
19:39:13.097 MTR: SEARCHING: destinationId=B8CA03EA0853A229827C5016C255BD40217DCA0F4A9BB3EDDD9FF70B44497719
19:39:13.103 BRY: GC from 73397 to 63562 bytes, objects freed 119/554 (in 4 ms) - slots from 619/653 to 471/653
19:39:13.138 MTR: SIGMA1: candidateDestinationId=D5321C48C241720603E4F3A03C00FB74A320C1B1A1F4CAB8FC78ADD5EEE8FAB3
19:39:13.169 MTR: SIGMA1: candidateDestinationId=B8CA03EA0853A229827C5016C255BD40217DCA0F4A9BB3EDDD9FF70B44497719
19:39:13.180 BRY: GC from 73805 to 63420 bytes, objects freed 151/552 (in 5 ms) - slots from 563/653 to 468/653
19:39:13.182 MTR: +Session   ( 39812) from '[fe80::4ad6:d5ff:fe67:6c4b]:5540'
19:39:13.562 MTR: <Replied   (     0) CASE_Sigma2
19:39:13.570 BRY: GC from 73943 to 68516 bytes, objects freed 57/577 (in 3 ms) - slots from 543/653 to 479/653
19:39:13.572 MTR: New Connection (CASE id=39812) from [fe80::4ad6:d5ff:fe67:6c4b]:5540
19:39:13.588 MTR: error sending packet to '[fe80::4ad6:d5ff:fe67:6c4b]:5540'
19:39:13.591 MTR: .          Resending packet id=81364264
19:39:13.594 MTR: error sending packet to '[fe80::c0af:3d89:e933:b760]:5540'
19:39:13.695 MTR: UDP received from [fe80::4ad6:d5ff:fe67:6c4b]:5540
19:39:13.703 MTR: .          Duplicate unencrypted message = 123575507 ref = 123575507
19:39:13.961 MTR: .          Resending packet id=81825111
19:39:13.965 MTR: error sending packet to '[fe80::4ad6:d5ff:fe67:6c4b]:5540'
19:39:14.268 MTR: .          Resending packet id=81825111
19:39:14.271 MTR: error sending packet to '[fe80::4ad6:d5ff:fe67:6c4b]:5540'
19:39:14.370 MTR: UDP received from [fe80::4ad6:d5ff:fe67:6c4b]:5540
19:39:14.378 MTR: .          Duplicate unencrypted message = 123575507 ref = 123575507
19:39:14.583 MTR: .          Resending packet id=81825111
19:39:14.587 MTR: error sending packet to '[fe80::4ad6:d5ff:fe67:6c4b]:5540'
19:39:14.757 WIF: Checking connection...
19:39:15.146 MTR: .          Resending packet id=81825111
19:39:15.150 MTR: error sending packet to '[fe80::4ad6:d5ff:fe67:6c4b]:5540'
19:39:15.288 MTR: UDP received from [fe80::4ad6:d5ff:fe67:6c4b]:5540
19:39:15.296 MTR: .          Duplicate unencrypted message = 123575507 ref = 123575507
19:39:16.050 MTR: .          (     0) Unacked packet '[fe80::c0af:3d89:e933:b760]:5540' msg_id=81364264
19:39:16.072 MTR: .          Resending packet id=81825111
19:39:16.076 MTR: error sending packet to '[fe80::4ad6:d5ff:fe67:6c4b]:5540'
19:39:16.738 MTR: UDP received from [fe80::4ad6:d5ff:fe67:6c4b]:5540
19:39:16.746 MTR: .          Duplicate unencrypted message = 123575507 ref = 123575507
19:39:17.576 MTR: .          Resending packet id=81825111
19:39:17.580 MTR: error sending packet to '[fe80::4ad6:d5ff:fe67:6c4b]:5540'
19:39:18.054 MTR: UDP received from [fe80::5660:9ff:fedf:8a1c]:5540
19:39:18.066 MTR: >Received  (     0) CASE_Sigma1 from [fe80::5660:9ff:fedf:8a1c]:5540
19:39:18.082 BRY: GC from 78766 to 65083 bytes, objects freed 150/562 (in 5 ms) - slots from 622/653 to 483/653
19:39:18.091 MTR: SEARCHING: destinationId=902B044F7F60B9AD380E7DEAD4BF8C8230F52EC2ACAC4BBC010848CEEDA7795D
19:39:18.116 MTR: SIGMA1: candidateDestinationId=CC801454B07A8259D7EC4331B5B0C27C6442D40BD484B29F3138BB817363F746
19:39:18.146 BRY: GC from 75359 to 68925 bytes, objects freed 100/616 (in 4 ms) - slots from 592/653 to 508/653
19:39:18.155 MTR: SIGMA1: candidateDestinationId=902B044F7F60B9AD380E7DEAD4BF8C8230F52EC2ACAC4BBC010848CEEDA7795D
19:39:18.170 MTR: +Session   ( 17394) from '[fe80::5660:9ff:fedf:8a1c]:5540'
19:39:18.543 BRY: GC from 79925 to 69772 bytes, objects freed 123/589 (in 4 ms) - slots from 591/653 to 489/653
19:39:18.546 MTR: <Replied   (     0) CASE_Sigma2
19:39:18.551 MTR: New Connection (CASE id=17394) from [fe80::5660:9ff:fedf:8a1c]:5540
19:39:18.566 MTR: error sending packet to '[fe80::5660:9ff:fedf:8a1c]:5540'
19:39:18.671 MTR: UDP received from [fe80::5660:9ff:fedf:8a1c]:5540
19:39:18.679 MTR: .          Duplicate unencrypted message = 8886667 ref = 8886667
19:39:18.884 MTR: .          Resending packet id=75334881
19:39:18.887 MTR: error sending packet to '[fe80::5660:9ff:fedf:8a1c]:5540'
19:39:19.241 MTR: .          Resending packet id=75334881
19:39:19.245 MTR: error sending packet to '[fe80::5660:9ff:fedf:8a1c]:5540'
19:39:19.286 MTR: UDP received from [fe80::5660:9ff:fedf:8a1c]:5540
19:39:19.294 MTR: .          Duplicate unencrypted message = 8886667 ref = 8886667
19:39:19.558 MTR: .          Resending packet id=75334881
19:39:19.562 MTR: error sending packet to '[fe80::5660:9ff:fedf:8a1c]:5540'
19:39:20.039 MTR: .          (     0) Unacked packet '[fe80::4ad6:d5ff:fe67:6c4b]:5540' msg_id=81825111
19:39:20.121 MTR: .          Resending packet id=75334881
19:39:20.125 MTR: error sending packet to '[fe80::5660:9ff:fedf:8a1c]:5540'
19:39:20.408 MTR: UDP received from [fe80::5660:9ff:fedf:8a1c]:5540
19:39:20.416 MTR: .          Duplicate unencrypted message = 8886667 ref = 8886667
19:39:21.003 MTR: .          Resending packet id=75334881
19:39:21.006 MTR: error sending packet to '[fe80::5660:9ff:fedf:8a1c]:5540'
19:39:22.152 MTR: UDP received from [fe80::5660:9ff:fedf:8a1c]:5540
19:39:22.160 MTR: .          Duplicate unencrypted message = 8886667 ref = 8886667
19:39:22.526 MTR: .          Resending packet id=75334881
19:39:22.529 MTR: error sending packet to '[fe80::5660:9ff:fedf:8a1c]:5540'
19:39:23.930 MTR: -Session   (     0) (removed)
19:39:24.880 MTR: .          (     0) Unacked packet '[fe80::5660:9ff:fedf:8a1c]:5540' msg_id=75334881
19:39:28.219 MTR: UDP received from [fe80::c0af:3d89:e933:b760]:5540
19:39:28.229 BRY: GC from 80037 to 62111 bytes, objects freed 203/533 (in 6 ms) - slots from 676/699 to 463/699
19:39:28.239 MTR: >Received  (     0) CASE_Sigma1 from [fe80::c0af:3d89:e933:b760]:5540
19:39:28.256 MTR: SEARCHING: destinationId=F736DB14165E8ACB9B3991AFC128533338403AA10012C21E60D1754CB53CD7EB
19:39:28.281 MTR: SIGMA1: candidateDestinationId=6BD6F973A3B7B6A67C61CFBD1FF359E08BEAB64D5DD97211612164ACF06ED5CE
19:39:28.294 BRY: GC from 72374 to 65543 bytes, objects freed 101/579 (in 3 ms) - slots from 581/699 to 489/699
19:39:28.319 MTR: SIGMA1: candidateDestinationId=F736DB14165E8ACB9B3991AFC128533338403AA10012C21E60D1754CB53CD7EB
19:39:28.324 MTR: +Session   ( 57393) from '[fe80::c0af:3d89:e933:b760]:5540'
19:39:28.700 BRY: GC from 76546 to 66956 bytes, objects freed 126/575 (in 5 ms) - slots from 578/699 to 483/699
19:39:28.712 MTR: <Replied   (     0) CASE_Sigma2
19:39:28.716 MTR: New Connection (CASE id=57393) from [fe80::c0af:3d89:e933:b760]:5540
19:39:28.721 MTR: error sending packet to '[fe80::c0af:3d89:e933:b760]:5540'
19:39:28.910 MTR: UDP received from [fe80::c0af:3d89:e933:b760]:5540
19:39:28.919 MTR: .          Duplicate unencrypted message = 40710173 ref = 40710173
19:39:29.067 MTR: .          Resending packet id=143295073
19:39:29.071 MTR: error sending packet to '[fe80::c0af:3d89:e933:b760]:5540'
19:39:29.431 MTR: .          Resending packet id=143295073
19:39:29.434 MTR: error sending packet to '[fe80::c0af:3d89:e933:b760]:5540'
19:39:29.629 MTR: UDP received from [fe80::c0af:3d89:e933:b760]:5540
19:39:29.637 MTR: .          Duplicate unencrypted message = 40710173 ref = 40710173
19:39:29.749 MTR: .          Resending packet id=143295073
19:39:29.753 MTR: error sending packet to '[fe80::c0af:3d89:e933:b760]:5540'
19:39:30.349 MTR: .          Resending packet id=143295073
19:39:30.353 MTR: error sending packet to '[fe80::c0af:3d89:e933:b760]:5540'
19:39:30.751 MTR: UDP received from [fe80::c0af:3d89:e933:b760]:5540
19:39:30.759 MTR: .          Duplicate unencrypted message = 40710173 ref = 40710173
19:39:31.158 MTR: .          Resending packet id=143295073
19:39:31.161 MTR: error sending packet to '[fe80::c0af:3d89:e933:b760]:5540'
19:39:32.183 MTR: UDP received from [fe80::c0af:3d89:e933:b760]:5540
19:39:32.191 MTR: .          Duplicate unencrypted message = 40710173 ref = 40710173
19:39:32.547 MTR: .          Resending packet id=143295073
19:39:32.550 MTR: error sending packet to '[fe80::c0af:3d89:e933:b760]:5540'
19:39:56.037 WIF: Checking connection...
19:39:56.451 MTR: UDP received from [fe80::c0af:3d89:e933:b760]:5540
19:39:56.463 MTR: >Received  (     0) CASE_Sigma1 from [fe80::c0af:3d89:e933:b760]:5540
19:39:56.477 BRY: GC from 82364 to 63748 bytes, objects freed 204/555 (in 6 ms) - slots from 685/714 to 480/714
19:39:56.487 MTR: SEARCHING: destinationId=F41BD047310CD5121719F8EEE23D471F83AF7106E78DFC2A23E6BCD00EF5D682
19:39:56.512 MTR: SIGMA1: candidateDestinationId=6FCC153487C2F4413B457A342E3D7D4B71F4AACE80A2755A0E2620B10C2E52F3
19:39:56.540 BRY: GC from 74037 to 67579 bytes, objects freed 101/609 (in 4 ms) - slots from 593/714 to 506/714
19:39:56.551 MTR: SIGMA1: candidateDestinationId=F41BD047310CD5121719F8EEE23D471F83AF7106E78DFC2A23E6BCD00EF5D682
19:39:56.556 MTR: +Session   ( 60803) from '[fe80::c0af:3d89:e933:b760]:5540'
19:39:56.935 BRY: GC from 77829 to 67732 bytes, objects freed 122/587 (in 5 ms) - slots from 588/714 to 491/714
19:39:56.943 MTR: <Replied   (     0) CASE_Sigma2
19:39:56.948 MTR: New Connection (CASE id=60803) from [fe80::c0af:3d89:e933:b760]:5540
19:39:56.952 MTR: error sending packet to '[fe80::c0af:3d89:e933:b760]:5540'
19:39:56.980 MTR: -Session   (     0) (removed)
19:39:57.167 MTR: UDP received from [fe80::c0af:3d89:e933:b760]:5540
19:39:57.176 MTR: .          Duplicate unencrypted message = 40710174 ref = 40710174
19:39:57.297 MTR: .          Resending packet id=234268072
19:39:57.301 MTR: error sending packet to '[fe80::c0af:3d89:e933:b760]:5540'
19:39:57.633 MTR: .          Resending packet id=234268072
19:39:57.637 MTR: error sending packet to '[fe80::c0af:3d89:e933:b760]:5540'
19:39:57.785 MTR: UDP received from [fe80::c0af:3d89:e933:b760]:5540
19:39:57.793 MTR: .          Duplicate unencrypted message = 40710174 ref = 40710174
19:39:57.949 MTR: .          Resending packet id=234268072
19:39:57.953 MTR: error sending packet to '[fe80::c0af:3d89:e933:b760]:5540'
19:39:58.505 MTR: .          Resending packet id=234268072
19:39:58.508 MTR: error sending packet to '[fe80::c0af:3d89:e933:b760]:5540'
19:39:58.805 MTR: UDP received from [fe80::c0af:3d89:e933:b760]:5540
19:39:58.813 MTR: .          Duplicate unencrypted message = 40710174 ref = 40710174
19:39:59.295 MTR: .          Resending packet id=234268072
19:39:59.298 MTR: error sending packet to '[fe80::c0af:3d89:e933:b760]:5540'
19:40:00.552 MTR: UDP received from [fe80::c0af:3d89:e933:b760]:5540
19:40:00.560 MTR: .          Duplicate unencrypted message = 40710174 ref = 40710174
19:40:00.728 MTR: .          Resending packet id=234268072
19:40:00.732 MTR: error sending packet to '[fe80::c0af:3d89:e933:b760]:5540'
19:40:01.945 MTR: -Session   (     0) (removed)
19:40:02.946 MTR: -Session   (     0) (removed)
19:40:02.955 MTR: .          (     0) Unacked packet '[fe80::c0af:3d89:e933:b760]:5540' msg_id=234268072
19:40:04.599 BRY: GC from 77983 to 57625 bytes, objects freed 214/510 (in 6 ms) - slots from 675/714 to 454/714
19:40:08.951 MTR: -Session   (     0) (removed)
19:40:14.914 MTR: -Session   (     0) (removed)
19:40:14.916 MTR: -Session   (     0) (removed)
19:40:15.906 MTR: UDP received from [fe80::4ad6:d5ff:fe67:6c4b]:5540
19:40:15.916 MTR: >Received  (     0) CASE_Sigma1 from [fe80::4ad6:d5ff:fe67:6c4b]:5540
19:40:15.933 MTR: SEARCHING: destinationId=2DBD59B24E477B14364E023452C80AD3BC09FFC0E273E0DF965854790BB41DF0
19:40:15.944 BRY: GC from 67903 to 54311 bytes, objects freed 203/505 (in 5 ms) - slots from 723/729 to 437/729
19:40:15.965 MTR: SIGMA1: candidateDestinationId=B8A7CCAAFA9196AA50B97704625964F83FD263FF41B194B31B8C19F3983C4AEA
19:40:15.997 MTR: SIGMA1: candidateDestinationId=2DBD59B24E477B14364E023452C80AD3BC09FFC0E273E0DF965854790BB41DF0
19:40:16.002 MTR: +Session   ( 16563) from '[fe80::4ad6:d5ff:fe67:6c4b]:5540'
19:40:16.106 BRY: GC from 64601 to 57523 bytes, objects freed 98/550 (in 4 ms) - slots from 525/729 to 458/714
19:40:16.388 MTR: <Replied   (     0) CASE_Sigma2
19:40:16.393 MTR: New Connection (CASE id=16563) from [fe80::4ad6:d5ff:fe67:6c4b]:5540
19:40:16.399 BRY: GC from 67846 to 58711 bytes, objects freed 111/518 (in 3 ms) - slots from 531/714 to 438/714
19:40:16.404 MTR: error sending packet to '[fe80::4ad6:d5ff:fe67:6c4b]:5540'
19:40:16.575 WIF: Checking connection...
19:40:16.623 MTR: UDP received from [fe80::4ad6:d5ff:fe67:6c4b]:5540
19:40:16.631 MTR: .          Duplicate unencrypted message = 123575509 ref = 123575509
19:40:16.722 MTR: .          Resending packet id=61253051
19:40:16.725 MTR: error sending packet to '[fe80::4ad6:d5ff:fe67:6c4b]:5540'
19:40:17.103 MTR: .          Resending packet id=61253051
19:40:17.106 MTR: error sending packet to '[fe80::4ad6:d5ff:fe67:6c4b]:5540'
19:40:17.202 MTR: UDP received from [fe80::4ad6:d5ff:fe67:6c4b]:5540
19:40:17.209 MTR: .          Duplicate unencrypted message = 123575509 ref = 123575509
19:40:17.461 MTR: .          Resending packet id=61253051
19:40:17.464 MTR: error sending packet to '[fe80::4ad6:d5ff:fe67:6c4b]:5540'
19:40:17.969 MTR: .          Resending packet id=61253051
19:40:17.972 MTR: error sending packet to '[fe80::4ad6:d5ff:fe67:6c4b]:5540'
19:40:18.261 MTR: UDP received from [fe80::4ad6:d5ff:fe67:6c4b]:5540
19:40:18.269 MTR: .          Duplicate unencrypted message = 123575509 ref = 123575509
19:40:18.923 MTR: .          Resending packet id=61253051
19:40:18.927 MTR: error sending packet to '[fe80::4ad6:d5ff:fe67:6c4b]:5540'
19:40:19.805 MTR: UDP received from [fe80::4ad6:d5ff:fe67:6c4b]:5540
19:40:19.813 MTR: .          Duplicate unencrypted message = 123575509 ref = 123575509
19:40:20.383 MTR: .          Resending packet id=61253051
19:40:20.386 MTR: error sending packet to '[fe80::4ad6:d5ff:fe67:6c4b]:5540'
19:40:22.806 MTR: .          (     0) Unacked packet '[fe80::4ad6:d5ff:fe67:6c4b]:5540' msg_id=61253051
19:40:23.958 MTR: -Session   (     0) (removed)
19:40:27.920 MTR: -Session   (     0) (removed)```

@s-hadinger
Copy link
Collaborator

s-hadinger commented Oct 29, 2024 •
edited
Loading

I will have a look in the following days.

@s-hadinger
Copy link
Collaborator

Hmmm
19:39:58.805 MTR: UDP received from [fe80::c0af:3d89:e933:b760]:5540 shows that the zone I'd has disappeared.

@Jason2866 this must be linked with the commit removing the zone id from String. But the problem is that sending packets seems to fail too

@maltic42
Copy link
Author

I will have a look in the following days.

Thanks!!

@Jason2866
Copy link
Collaborator

Hmmm 19:39:58.805 MTR: UDP received from [fe80::c0af:3d89:e933:b760]:5540 shows that the zone I'd has disappeared.

@Jason2866 this must be linked with the commit removing the zone id from String. But the problem is that sending packets seems to fail too

Most probably the commit we talked about.

@s-hadinger
Copy link
Collaborator

s-hadinger commented Oct 29, 2024 •
edited
Loading

The bad news is that this commit causes Matter to fail for all builds... I will try to find a work-around

@maltic42
Copy link
Author

The bad news is that this commit causes Matter to fail for all builds... I will try to find a work-around

Thanks a lot - that would be great!!

@s-hadinger
Copy link
Collaborator

We should have shortly a work around. Thanks for reporting the problem

@Jason2866 Jason2866 mentioned this issue Oct 30, 2024
Merged
6 tasks
@s-hadinger
Copy link
Collaborator

The fix is included in #22384 now in development branch

arendst added a commit that referenced this issue Oct 30, 2024
@arendst
Bump version v14.3.0.3
3f4b233 
- Change ESP32 Platform from 2024.10.30 to 2024.11.30, Framework (Arduino Core) from v3.1.0.241023 to v3.1.0.241030 and IDF to 5.3.1.241024 (#22384)
- Fix ESP32 Arduino Core IPv6 zones used by Matter (#22378)
@maltic42
Copy link
Author

The fix is included in #22384 now in development branch

@s-hadinger Excellent - I just updated and tested it - matter now works again on Google Home. Thanks!!

josef109 pushed a commit to josef109/Tasmota that referenced this issue Nov 7, 2024
@arendst @josef109
Bump version v14.3.0.3
5e3fd41 
- Change ESP32 Platform from 2024.10.30 to 2024.11.30, Framework (Arduino Core) from v3.1.0.241023 to v3.1.0.241030 and IDF to 5.3.1.241024 (arendst#22384)
- Fix ESP32 Arduino Core IPv6 zones used by Matter (arendst#22378)
josef109 pushed a commit to josef109/Tasmota that referenced this issue Nov 10, 2024
@arendst @josef109
Bump version v14.3.0.3
10bea29 
- Change ESP32 Platform from 2024.10.30 to 2024.11.30, Framework (Arduino Core) from v3.1.0.241023 to v3.1.0.241030 and IDF to 5.3.1.241024 (arendst#22384)
- Fix ESP32 Arduino Core IPv6 zones used by Matter (arendst#22378)
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Assignees
No one assigned
Labels
None yet
Projects
None yet
Milestone
No milestone
Development

Successfully merging a pull request may close this issue.

Tasmota Platform 2024.11.30 / Arduino 3.1.0.241030 / IDF 5.3.1.241024 Jason2866/Tasmota
3 participants
@Jason2866 @maltic42 @s-hadinger