ubuntu@ubuntu:~/apps$ ./chip-tool interactive start [1766734922.488] [188801:188801] [DL] ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_tool_kvs [1766734922.489] [188801:188801] [DL] ChipLinuxStorage::Init: Attempt to re-initialize with KVS config file: /tmp/chip_kvs, IGNORING. [1766734922.495] [188801:188801] [DL] ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_factory.ini [1766734922.495] [188801:188801] [DL] ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_config.ini [1766734922.495] [188801:188801] [DL] ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_counters.ini [1766734922.502] [188801:188801] [DL] Wrote settings to /tmp/chip_counters.ini [1766734922.502] [188801:188801] [DL] NVS set: chip-counters/reboot-count = 10 (0xA) [1766734922.503] [188801:188801] [DL] Got Ethernet interface: eth0 [1766734922.504] [188801:188801] [DL] Found the primary Ethernet interface:eth0 [1766734922.505] [188801:188801] [DL] Got WiFi interface: wlan0 [1766734922.505] [188801:188801] [DL] Failed to reset WiFi statistic counts [1766734922.505] [188801:188801] [PAF] WiFiPAF: WiFiPAFLayer::Init() [1766734922.505] [188801:188801] [IN] UDP::Init bind&listen port=0 [1766734922.505] [188801:188801] [IN] UDP::Init bound to port=34406 [1766734922.505] [188801:188801] [IN] BLEBase::Init - setting/overriding transport [1766734922.505] [188801:188801] [IN] WiFiPAFBase::Init - setting/overriding transport [1766734922.505] [188801:188801] [CTL] NFCBase::Init [1766734922.505] [188801:188801] [IN] TransportMgr initialized [1766734922.505] [188801:188801] [FP] Initializing FabricTable from persistent storage [1766734922.506] [188801:188801] [TS] Last Known Good Time: 2023-10-14T01:16:48 [1766734922.507] [188801:188801] [FP] Fabric index 0x1 was retrieved from storage. Compressed FabricId 0x5B9949652E0F0B9C, FabricId 0x0000000000000001, NodeId 0x000000000001B669, VendorId 0xFFF1 [1766734922.508] [188801:188801] [FP] Fabric index 0x2 was retrieved from storage. Compressed FabricId 0x5B9949652E0F0B9C, FabricId 0x0000000000000001, NodeId 0x00000000000004D2, VendorId 0xFFF1 [1766734922.511] [188801:188801] [DMG] Ember attribute persistence requires setting up [1766734922.511] [188801:188801] [ZCL] Using ZAP configuration... [1766734922.517] [188801:188801] [DL] Avahi client registered [1766734922.518] [188801:188801] [IN] CASE Server enabling CASE session setups [1766734922.518] [188801:188801] [IN] SecureSession[0xaaaaccb559b0]: Allocated Type:2 LSID:55566 [1766734922.518] [188801:188801] [SC] Allocated SecureSession (0xaaaaccb559b0) - waiting for Sigma1 msg [1766734922.518] [188801:188801] [CTL] System State Initialized... [1766734922.518] [188801:188801] [CTL] Setting attestation nonce to random value [1766734922.518] [188801:188801] [CTL] Setting CSR nonce to random value [1766734922.519] [188801:188801] [IN] UDP::Init bind&listen port=5550 [1766734922.519] [188801:188801] [IN] UDP::Init bound to port=5550 [1766734922.519] [188801:188801] [IN] TransportMgr initialized [1766734922.519] [188801:188803] [DL] CHIP task running >>> accesscontrol subscribe-event access-control-entry-changed 20 30 1 0 [1766734935.011] [188801:188801] [TOO] Command: accesscontrol subscribe-event access-control-entry-changed 20 30 1 0 [1766734935.013] [188801:188803] [CTL] Setting attestation nonce to random value [1766734935.013] [188801:188803] [CTL] Setting CSR nonce to random value [1766734935.016] [188801:188803] [CTL] Generating NOC [1766734935.019] [188801:188803] [FP] Validating NOC chain [1766734935.023] [188801:188803] [FP] NOC chain validation successful [1766734935.024] [188801:188803] [FP] Updated fabric at index: 0x1, Node ID: 0x000000000001B669 [1766734935.024] [188801:188803] [TS] Last Known Good Time: 2023-10-14T01:16:48 [1766734935.024] [188801:188803] [TS] New proposed Last Known Good Time: 2021-01-01T00:00:00 [1766734935.024] [188801:188803] [TS] Retaining current Last Known Good Time [1766734935.033] [188801:188803] [FP] Metadata for Fabric 0x1 persisted to storage. [1766734935.039] [188801:188803] [TS] Committing Last Known Good Time to storage: 2023-10-14T01:16:48 [1766734935.041] [188801:188803] [CTL] Joined the fabric at index 1. Fabric ID is 0x0000000000000001 (Compressed Fabric ID: 5B9949652E0F0B9C) [1766734935.041] [188801:188803] [IN] UDP::Init bind&listen port=5551 [1766734935.041] [188801:188803] [IN] UDP::Init bound to port=5551 [1766734935.041] [188801:188803] [IN] TransportMgr initialized [1766734935.041] [188801:188803] [DIS] Updating services using commissioning mode 0 [1766734935.042] [188801:188803] [DL] Using WiFi MAC for hostname [1766734935.042] [188801:188803] [DIS] Advertise operational node 5B9949652E0F0B9C-000000000001B669 [1766734935.042] [188801:188803] [DL] PublishService 5B9949652E0F0B9C-000000000001B669._matter._tcp34406.-1 [1766734935.045] [188801:188803] [DL] Using addresses from interface id=0 name= [1766734935.059] [188801:188803] [DIS] mDNS service published: _matter._tcp; instance name: 5B9949652E0F0B9C-000000000001B669 [1766734935.060] [188801:188803] [DL] Using WiFi MAC for hostname [1766734935.060] [188801:188803] [DIS] Advertise operational node 5B9949652E0F0B9C-00000000000004D2 [1766734935.060] [188801:188803] [DL] PublishService 5B9949652E0F0B9C-00000000000004D2._matter._tcp34406.-1 [1766734935.062] [188801:188803] [DL] Using addresses from interface id=0 name= [1766734935.078] [188801:188803] [DIS] mDNS service published: _matter._tcp; instance name: 5B9949652E0F0B9C-00000000000004D2 [1766734935.079] [188801:188803] [DL] Using WiFi MAC for hostname [1766734935.079] [188801:188803] [DIS] Advertise commission parameter vendorID=65521 productID=32769 discriminator=0000/00 cm=0 cp=0 jf=0 [1766734935.079] [188801:188803] [DL] PublishService AD5C1596C805BF10._matterd._udp5550.-1 [1766734935.081] [188801:188803] [DL] Using addresses from interface id=0 name= [1766734935.096] [188801:188803] [DIS] mDNS service published: _matterd._udp; instance name: AD5C1596C805BF10 [1766734935.120] [188801:188803] [TOO] Sending command to node 0x1 [1766734935.121] [188801:188803] [CSM] FindOrEstablishSession: PeerId = [1:0000000000000001] [1766734935.121] [188801:188803] [CSM] FindOrEstablishSession: No existing OperationalSessionSetup instance found [1766734935.121] [188801:188803] [DIS] OperationalSessionSetup[1:0000000000000001]: State change 1 --> 2 [1766734935.121] [188801:188803] [DIS] Resolving 5B9949652E0F0B9C:0000000000000001 ... [1766734935.123] [188801:188803] [DIS] Lookup started for 5B9949652E0F0B9C-0000000000000001 [1766734935.123] [188801:188803] [DL] Long dispatch time: 111 ms, for event type 2 [1766734935.228] [188801:188803] [DL] Avahi resolve found [1766734935.228] [188801:188803] [DIS] Node ID resolved for 5B9949652E0F0B9C-0000000000000001 [1766734935.228] [188801:188803] [DIS] Hostname: 3EB9FB237E2A2F81 [1766734935.229] [188801:188803] [DIS] IP Address #1: fd11:11::6729:d33b:eae2:c0fc [1766734935.229] [188801:188803] [DIS] Port: 5540 [1766734935.229] [188801:188803] [DIS] Mrp Interval idle: 3000 ms [1766734935.229] [188801:188803] [DIS] Mrp Interval active: 2200 ms [1766734935.229] [188801:188803] [DIS] Mrp Active Threshold: not present [1766734935.229] [188801:188803] [DIS] TCP Client Supported: 0 [1766734935.229] [188801:188803] [DIS] TCP Server Supported: 0 [1766734935.229] [188801:188803] [DIS] ICD: not present [1766734935.230] [188801:188803] [DIS] Lookup clearing interface for non LL address [1766734935.230] [188801:188803] [DIS] UDP:[fd11:11::6729:d33b:eae2:c0fc%eth0]:5540: new best score: 3 (for 5B9949652E0F0B9C-0000000000000001) [1766734935.230] [188801:188803] [DIS] Checking node lookup status for 5B9949652E0F0B9C-0000000000000001 after 108 ms [1766734935.230] [188801:188803] [DIS] Keeping DNSSD lookup active [1766734935.322] [188801:188803] [DIS] Checking node lookup status for 5B9949652E0F0B9C-0000000000000001 after 200 ms [1766734935.322] [188801:188803] [DIS] OperationalSessionSetup[1:0000000000000001]: Updating device address to UDP:[fd11:11::6729:d33b:eae2:c0fc]:5540 while in state 2 [1766734935.322] [188801:188803] [DIS] OperationalSessionSetup[1:0000000000000001]: State change 2 --> 3 [1766734935.322] [188801:188803] [IN] SecureSession[0xffff8400a970]: Allocated Type:2 LSID:55567 [1766734935.322] [188801:188803] [SC] Initiating session on local FabricIndex 1 from 0x000000000001B669 -> 0x0000000000000001 [1766734935.325] [188801:188803] [EM] <<< [E:22372i S:0 M:144957131] (U) Msg TX from DA2356A083B7410C to 0:0000000000000000 [0000] [UDP:[fd11:11::6729:d33b:eae2:c0fc]:5540] --- Type 0000:30 (SecureChannel:CASE_Sigma1) (B:196) [1766734935.325] [188801:188803] [EM] ??1 [E:22372i S:0 M:144957131] (U) Msg Retransmission to 0:0000000000000000 scheduled for 3713ms from now [State:Idle II:3000 AI:2200 AT:4000] [1766734935.325] [188801:188803] [SC] Sent Sigma1 msg to <0000000000000001, 1> [II:500ms AI:300ms AT:4000ms] [1766734935.325] [188801:188803] [DIS] OperationalSessionSetup[1:0000000000000001]: State change 3 --> 4 [1766734935.800] [188801:188803] [EM] >>> [E:22372i S:0 M:256578614 (Ack:144957131)] (U) Msg RX from 0:0000000000000000 [0000] to DA2356A083B7410C --- Type 0000:10 (SecureChannel:StandaloneAck) (B:26) [1766734935.800] [188801:188803] [EM] Found matching exchange: 22372i, Delegate: 0xffff84011418 [1766734935.801] [188801:188803] [EM] Rxd Ack; Removing MessageCounter:144957131 from Retrans Table on exchange 22372i [1766734935.863] [188801:188803] [EM] >>> [E:22372i S:0 M:256578615 (Ack:144957131)] (U) Msg RX from 0:0000000000000000 [0000] to DA2356A083B7410C --- Type 0000:31 (SecureChannel:CASE_Sigma2) (B:750) [1766734935.864] [188801:188803] [EM] Found matching exchange: 22372i, Delegate: 0xffff84011418 [1766734935.864] [188801:188803] [EM] CHIP MessageCounter:144957131 not in RetransTable on exchange 22372i [1766734935.864] [188801:188803] [SC] Received Sigma2 msg [1766734935.864] [188801:188803] [SC] Found MRP parameters in the message [1766734935.872] [188801:188803] [SC] Peer <0000000000000001, 1> assigned session ID 29892 [1766734935.872] [188801:188803] [SC] Sending Sigma3 [1766734935.873] [188801:188803] [EM] <<< [E:22372i S:0 M:144957132 (Ack:256578615)] (U) Msg TX from DA2356A083B7410C to 0:0000000000000000 [0000] [UDP:[fd11:11::6729:d33b:eae2:c0fc]:5540] --- Type 0000:32 (SecureChannel:CASE_Sigma3) (B:598) [1766734935.873] [188801:188803] [EM] ??1 [E:22372i S:0 M:144957132] (U) Msg Retransmission to 0:0000000000000000 scheduled for 2981ms from now [State:Idle II:3000 AI:2200 AT:0] [1766734935.873] [188801:188803] [SC] Sent Sigma3 msg [1766734935.960] [188801:188803] [DL] Avahi group established [1766734935.977] [188801:188803] [DL] Avahi group established [1766734935.994] [188801:188803] [DL] Avahi group established [1766734936.061] [188801:188803] [EM] >>> [E:22372i S:0 M:256578616 (Ack:144957132)] (U) Msg RX from 0:0000000000000000 [0000] to DA2356A083B7410C --- Type 0000:10 (SecureChannel:StandaloneAck) (B:26) [1766734936.061] [188801:188803] [EM] Found matching exchange: 22372i, Delegate: 0xffff84011418 [1766734936.061] [188801:188803] [EM] Rxd Ack; Removing MessageCounter:144957132 from Retrans Table on exchange 22372i [1766734936.088] [188801:188803] [EM] >>> [E:22372i S:0 M:256578617 (Ack:144957132)] (U) Msg RX from 0:0000000000000000 [0000] to DA2356A083B7410C --- Type 0000:40 (SecureChannel:StatusReport) (B:34) [1766734936.088] [188801:188803] [EM] Found matching exchange: 22372i, Delegate: 0xffff84011418 [1766734936.088] [188801:188803] [EM] CHIP MessageCounter:144957132 not in RetransTable on exchange 22372i [1766734936.088] [188801:188803] [SC] Success status report received. Session was established [1766734936.095] [188801:188803] [SC] SecureSession[0xffff8400a970, LSID:55567]: State change 'kEstablishing' --> 'kActive' [1766734936.095] [188801:188803] [IN] SecureSession[0xffff8400a970]: Activated - Type:2 LSID:55567 [1766734936.095] [188801:188803] [IN] New secure session activated for device <0000000000000001, 1>, LSID:55567 PSID:29892! [1766734936.095] [188801:188803] [DIS] OperationalSessionSetup[1:0000000000000001]: State change 4 --> 5 [1766734936.096] [188801:188803] [TOO] Sending SubscribeEvent to: [1766734936.096] [188801:188803] [TOO] cluster 0x0000_001F, event: 0x0000_0000, endpoint 0 [1766734936.096] [188801:188803] [EM] <<< [E:22373i S:55567 M:27425736] (S) Msg TX from 000000000001B669 to 1:0000000000000001 [0B9C] [UDP:[fd11:11::6729:d33b:eae2:c0fc]:5540] --- Type 0001:03 (IM:SubscribeRequest) (B:59) [1766734936.097] [188801:188803] [EM] ??1 [E:22373i S:55567 M:27425736] (S) Msg Retransmission to 1:0000000000000001 scheduled for 4010ms from now [State:Idle II:3000 AI:2200 AT:0] [1766734936.097] [188801:188803] [DMG] MoveToState ReadClient[0xffff84014100]: Moving to [AwaitingIn] [1766734936.097] [188801:188803] [EM] <<< [E:22372i S:0 M:144957133 (Ack:256578617)] (U) Msg TX from DA2356A083B7410C to 0:0000000000000000 [0000] [UDP:[fd11:11::6729:d33b:eae2:c0fc]:5540] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:26) [1766734936.097] [188801:188803] [EM] Flushed pending ack for MessageCounter:256578617 on exchange 22372i [1766734936.369] [188801:188803] [EM] >>> [E:22373i S:55567 M:87725939 (Ack:27425736)] (S) Msg RX from 1:0000000000000001 [0B9C] to 000000000001B669 --- Type 0001:05 (IM:ReportData) (B:562) [1766734936.369] [188801:188803] [EM] Found matching exchange: 22373i, Delegate: 0xffff84014110 [1766734936.369] [188801:188803] [EM] Rxd Ack; Removing MessageCounter:27425736 from Retrans Table on exchange 22373i [1766734936.369] [188801:188803] [DMG] ReportDataMessage = [1766734936.369] [188801:188803] [DMG] { [1766734936.369] [188801:188803] [DMG] SubscriptionId = 0xf191f3db, [1766734936.369] [188801:188803] [DMG] EventReportIBs = [1766734936.370] [188801:188803] [DMG] [ [1766734936.370] [188801:188803] [DMG] EventReportIB = [1766734936.370] [188801:188803] [DMG] { [1766734936.370] [188801:188803] [DMG] EventDataIB = [1766734936.370] [188801:188803] [DMG] { [1766734936.370] [188801:188803] [DMG] EventPath = [1766734936.370] [188801:188803] [DMG] { [1766734936.370] [188801:188803] [DMG] Endpoint = 0x0, [1766734936.370] [188801:188803] [DMG] Cluster = 0x1f, [1766734936.370] [188801:188803] [DMG] Event = 0x0, [1766734936.370] [188801:188803] [DMG] }, [1766734936.371] [188801:188803] [DMG] [1766734936.371] [188801:188803] [DMG] EventNumber = 0x0000000000000003, [1766734936.371] [188801:188803] [DMG] PriorityLevel = 0x0000000000000001, [1766734936.371] [188801:188803] [DMG] SystemTimestamp = 0x0000000000001AE4, [1766734936.371] [188801:188803] [DMG] EventData = [1766734936.371] [188801:188803] [DMG] { [1766734936.371] [188801:188803] [DMG] 0x1 = NULL [1766734936.371] [188801:188803] [DMG] 0x2 = 0 (unsigned), [1766734936.371] [188801:188803] [DMG] 0x3 = 1 (unsigned), [1766734936.371] [188801:188803] [DMG] 0x4 = [1766734936.372] [188801:188803] [DMG] { [1766734936.372] [188801:188803] [DMG] 0x1 = 5 (unsigned), [1766734936.372] [188801:188803] [DMG] 0x2 = 2 (unsigned), [1766734936.372] [188801:188803] [DMG] 0x3 = [ [1766734936.372] [188801:188803] [DMG] 112233 (unsigned), [1766734936.372] [188801:188803] [DMG] ], [1766734936.372] [188801:188803] [DMG] 0x4 = NULL [1766734936.372] [188801:188803] [DMG] 0xfe = 1 (unsigned), [1766734936.372] [188801:188803] [DMG] }, [1766734936.373] [188801:188803] [DMG] 0xfe = 1 (unsigned), [1766734936.373] [188801:188803] [DMG] }, [1766734936.373] [188801:188803] [DMG] }, [1766734936.373] [188801:188803] [DMG] [1766734936.373] [188801:188803] [DMG] }, [1766734936.373] [188801:188803] [DMG] [1766734936.373] [188801:188803] [DMG] EventReportIB = [1766734936.373] [188801:188803] [DMG] { [1766734936.373] [188801:188803] [DMG] EventDataIB = [1766734936.373] [188801:188803] [DMG] { [1766734936.374] [188801:188803] [DMG] EventPath = [1766734936.374] [188801:188803] [DMG] { [1766734936.374] [188801:188803] [DMG] Endpoint = 0x0, [1766734936.374] [188801:188803] [DMG] Cluster = 0x1f, [1766734936.374] [188801:188803] [DMG] Event = 0x0, [1766734936.374] [188801:188803] [DMG] }, [1766734936.374] [188801:188803] [DMG] [1766734936.374] [188801:188803] [DMG] EventNumber = 0x0000000000000005, [1766734936.374] [188801:188803] [DMG] PriorityLevel = 0x0000000000000001, [1766734936.374] [188801:188803] [DMG] DeltaSystemTimestamp = 0x000000000003CDE8, [1766734936.374] [188801:188803] [DMG] EventData = [1766734936.375] [188801:188803] [DMG] { [1766734936.375] [188801:188803] [DMG] 0x1 = 112233 (unsigned), [1766734936.375] [188801:188803] [DMG] 0x2 = NULL [1766734936.375] [188801:188803] [DMG] 0x3 = 0 (unsigned), [1766734936.375] [188801:188803] [DMG] 0x4 = [1766734936.375] [188801:188803] [DMG] { [1766734936.375] [188801:188803] [DMG] 0x1 = 5 (unsigned), [1766734936.375] [188801:188803] [DMG] 0x2 = 2 (unsigned), [1766734936.375] [188801:188803] [DMG] 0x3 = [ [1766734936.375] [188801:188803] [DMG] 1 (unsigned), 112233 (unsigned), [1766734936.376] [188801:188803] [DMG] ], [1766734936.376] [188801:188803] [DMG] 0x4 = NULL [1766734936.376] [188801:188803] [DMG] 0xfe = 1 (unsigned), [1766734936.376] [188801:188803] [DMG] }, [1766734936.376] [188801:188803] [DMG] 0xfe = 1 (unsigned), [1766734936.376] [188801:188803] [DMG] }, [1766734936.376] [188801:188803] [DMG] }, [1766734936.376] [188801:188803] [DMG] [1766734936.376] [188801:188803] [DMG] }, [1766734936.376] [188801:188803] [DMG] [1766734936.376] [188801:188803] [DMG] EventReportIB = [1766734936.376] [188801:188803] [DMG] { [1766734936.376] [188801:188803] [DMG] EventDataIB = [1766734936.376] [188801:188803] [DMG] { [1766734936.376] [188801:188803] [DMG] EventPath = [1766734936.376] [188801:188803] [DMG] { [1766734936.376] [188801:188803] [DMG] Endpoint = 0x0, [1766734936.376] [188801:188803] [DMG] Cluster = 0x1f, [1766734936.376] [188801:188803] [DMG] Event = 0x0, [1766734936.377] [188801:188803] [DMG] }, [1766734936.377] [188801:188803] [DMG] [1766734936.377] [188801:188803] [DMG] EventNumber = 0x0000000000000006, [1766734936.377] [188801:188803] [DMG] PriorityLevel = 0x0000000000000001, [1766734936.377] [188801:188803] [DMG] DeltaSystemTimestamp = 0x0000000000133097, [1766734936.377] [188801:188803] [DMG] EventData = [1766734936.377] [188801:188803] [DMG] { [1766734936.377] [188801:188803] [DMG] 0x1 = 112233 (unsigned), [1766734936.377] [188801:188803] [DMG] 0x2 = NULL [1766734936.377] [188801:188803] [DMG] 0x3 = 0 (unsigned), [1766734936.377] [188801:188803] [DMG] 0x4 = [1766734936.377] [188801:188803] [DMG] { [1766734936.377] [188801:188803] [DMG] 0x1 = 5 (unsigned), [1766734936.377] [188801:188803] [DMG] 0x2 = 2 (unsigned), [1766734936.377] [188801:188803] [DMG] 0x3 = [ [1766734936.377] [188801:188803] [DMG] 1 (unsigned), 112233 (unsigned), [1766734936.377] [188801:188803] [DMG] ], [1766734936.377] [188801:188803] [DMG] 0x4 = [ [1766734936.377] [188801:188803] [DMG] [1766734936.377] [188801:188803] [DMG] { [1766734936.378] [188801:188803] [DMG] 0x0 = 31 (unsigned), [1766734936.378] [188801:188803] [DMG] 0x1 = 0 (unsigned), [1766734936.378] [188801:188803] [DMG] 0x2 = NULL [1766734936.378] [188801:188803] [DMG] }, [1766734936.378] [188801:188803] [DMG] ], [1766734936.378] [188801:188803] [DMG] 0xfe = 1 (unsigned), [1766734936.378] [188801:188803] [DMG] }, [1766734936.378] [188801:188803] [DMG] 0xfe = 1 (unsigned), [1766734936.378] [188801:188803] [DMG] }, [1766734936.378] [188801:188803] [DMG] }, [1766734936.378] [188801:188803] [DMG] [1766734936.378] [188801:188803] [DMG] }, [1766734936.378] [188801:188803] [DMG] [1766734936.378] [188801:188803] [DMG] EventReportIB = [1766734936.378] [188801:188803] [DMG] { [1766734936.378] [188801:188803] [DMG] EventDataIB = [1766734936.378] [188801:188803] [DMG] { [1766734936.378] [188801:188803] [DMG] EventPath = [1766734936.378] [188801:188803] [DMG] { [1766734936.379] [188801:188803] [DMG] Endpoint = 0x0, [1766734936.379] [188801:188803] [DMG] Cluster = 0x1f, [1766734936.379] [188801:188803] [DMG] Event = 0x0, [1766734936.379] [188801:188803] [DMG] }, [1766734936.379] [188801:188803] [DMG] [1766734936.379] [188801:188803] [DMG] EventNumber = 0x0000000000000007, [1766734936.379] [188801:188803] [DMG] PriorityLevel = 0x0000000000000001, [1766734936.379] [188801:188803] [DMG] DeltaSystemTimestamp = 0x00000000000206D8, [1766734936.379] [188801:188803] [DMG] EventData = [1766734936.379] [188801:188803] [DMG] { [1766734936.379] [188801:188803] [DMG] 0x1 = 112233 (unsigned), [1766734936.379] [188801:188803] [DMG] 0x2 = NULL [1766734936.379] [188801:188803] [DMG] 0x3 = 0 (unsigned), [1766734936.379] [188801:188803] [DMG] 0x4 = [1766734936.379] [188801:188803] [DMG] { [1766734936.379] [188801:188803] [DMG] 0x1 = 5 (unsigned), [1766734936.379] [188801:188803] [DMG] 0x2 = 2 (unsigned), [1766734936.379] [188801:188803] [DMG] 0x3 = [ [1766734936.379] [188801:188803] [DMG] 112233 (unsigned), [1766734936.379] [188801:188803] [DMG] ], [1766734936.379] [188801:188803] [DMG] 0x4 = NULL [1766734936.380] [188801:188803] [DMG] 0xfe = 1 (unsigned), [1766734936.380] [188801:188803] [DMG] }, [1766734936.380] [188801:188803] [DMG] 0xfe = 1 (unsigned), [1766734936.380] [188801:188803] [DMG] }, [1766734936.380] [188801:188803] [DMG] }, [1766734936.380] [188801:188803] [DMG] [1766734936.380] [188801:188803] [DMG] }, [1766734936.380] [188801:188803] [DMG] [1766734936.380] [188801:188803] [DMG] EventReportIB = [1766734936.380] [188801:188803] [DMG] { [1766734936.380] [188801:188803] [DMG] EventDataIB = [1766734936.380] [188801:188803] [DMG] { [1766734936.380] [188801:188803] [DMG] EventPath = [1766734936.380] [188801:188803] [DMG] { [1766734936.380] [188801:188803] [DMG] Endpoint = 0x0, [1766734936.380] [188801:188803] [DMG] Cluster = 0x1f, [1766734936.380] [188801:188803] [DMG] Event = 0x0, [1766734936.380] [188801:188803] [DMG] }, [1766734936.380] [188801:188803] [DMG] [1766734936.380] [188801:188803] [DMG] EventNumber = 0x0000000000000008, [1766734936.381] [188801:188803] [DMG] PriorityLevel = 0x0000000000000001, [1766734936.381] [188801:188803] [DMG] DeltaSystemTimestamp = 0x000000000007C579, [1766734936.381] [188801:188803] [DMG] EventData = [1766734936.381] [188801:188803] [DMG] { [1766734936.381] [188801:188803] [DMG] 0x1 = 112233 (unsigned), [1766734936.381] [188801:188803] [DMG] 0x2 = NULL [1766734936.381] [188801:188803] [DMG] 0x3 = 0 (unsigned), [1766734936.381] [188801:188803] [DMG] 0x4 = [1766734936.381] [188801:188803] [DMG] { [1766734936.381] [188801:188803] [DMG] 0x1 = 5 (unsigned), [1766734936.381] [188801:188803] [DMG] 0x2 = 2 (unsigned), [1766734936.381] [188801:188803] [DMG] 0x3 = [ [1766734936.381] [188801:188803] [DMG] 112233 (unsigned), [1766734936.381] [188801:188803] [DMG] ], [1766734936.381] [188801:188803] [DMG] 0x4 = NULL [1766734936.381] [188801:188803] [DMG] 0xfe = 1 (unsigned), [1766734936.381] [188801:188803] [DMG] }, [1766734936.381] [188801:188803] [DMG] 0xfe = 1 (unsigned), [1766734936.381] [188801:188803] [DMG] }, [1766734936.381] [188801:188803] [DMG] }, [1766734936.382] [188801:188803] [DMG] [1766734936.382] [188801:188803] [DMG] }, [1766734936.382] [188801:188803] [DMG] [1766734936.382] [188801:188803] [DMG] EventReportIB = [1766734936.382] [188801:188803] [DMG] { [1766734936.382] [188801:188803] [DMG] EventDataIB = [1766734936.382] [188801:188803] [DMG] { [1766734936.382] [188801:188803] [DMG] EventPath = [1766734936.382] [188801:188803] [DMG] { [1766734936.382] [188801:188803] [DMG] Endpoint = 0x0, [1766734936.382] [188801:188803] [DMG] Cluster = 0x1f, [1766734936.382] [188801:188803] [DMG] Event = 0x0, [1766734936.382] [188801:188803] [DMG] }, [1766734936.382] [188801:188803] [DMG] [1766734936.382] [188801:188803] [DMG] EventNumber = 0x0000000000000009, [1766734936.382] [188801:188803] [DMG] PriorityLevel = 0x0000000000000001, [1766734936.382] [188801:188803] [DMG] DeltaSystemTimestamp = 0x0000000000000009, [1766734936.382] [188801:188803] [DMG] EventData = [1766734936.382] [188801:188803] [DMG] { [1766734936.382] [188801:188803] [DMG] 0x1 = 112233 (unsigned), [1766734936.382] [188801:188803] [DMG] 0x2 = NULL [1766734936.382] [188801:188803] [DMG] 0x3 = 1 (unsigned), [1766734936.383] [188801:188803] [DMG] 0x4 = [1766734936.383] [188801:188803] [DMG] { [1766734936.383] [188801:188803] [DMG] 0x1 = 1 (unsigned), [1766734936.383] [188801:188803] [DMG] 0x2 = 3 (unsigned), [1766734936.383] [188801:188803] [DMG] 0x3 = [ [1766734936.383] [188801:188803] [DMG] 111 (unsigned), 222 (unsigned), 333 (unsigned), 444 (unsigned), [1766734936.383] [188801:188803] [DMG] ], [1766734936.383] [188801:188803] [DMG] 0x4 = [ [1766734936.383] [188801:188803] [DMG] [1766734936.383] [188801:188803] [DMG] { [1766734936.383] [188801:188803] [DMG] 0x0 = 11 (unsigned), [1766734936.383] [188801:188803] [DMG] 0x1 = 22 (unsigned), [1766734936.383] [188801:188803] [DMG] 0x2 = NULL [1766734936.383] [188801:188803] [DMG] }, [1766734936.383] [188801:188803] [DMG] ], [1766734936.383] [188801:188803] [DMG] 0xfe = 1 (unsigned), [1766734936.383] [188801:188803] [DMG] }, [1766734936.383] [188801:188803] [DMG] 0xfe = 1 (unsigned), [1766734936.384] [188801:188803] [DMG] }, [1766734936.384] [188801:188803] [DMG] }, [1766734936.384] [188801:188803] [DMG] [1766734936.384] [188801:188803] [DMG] }, [1766734936.384] [188801:188803] [DMG] [1766734936.384] [188801:188803] [DMG] EventReportIB = [1766734936.384] [188801:188803] [DMG] { [1766734936.384] [188801:188803] [DMG] EventDataIB = [1766734936.384] [188801:188803] [DMG] { [1766734936.384] [188801:188803] [DMG] EventPath = [1766734936.384] [188801:188803] [DMG] { [1766734936.384] [188801:188803] [DMG] Endpoint = 0x0, [1766734936.384] [188801:188803] [DMG] Cluster = 0x1f, [1766734936.384] [188801:188803] [DMG] Event = 0x0, [1766734936.384] [188801:188803] [DMG] }, [1766734936.384] [188801:188803] [DMG] [1766734936.384] [188801:188803] [DMG] EventNumber = 0x000000000000000A, [1766734936.384] [188801:188803] [DMG] PriorityLevel = 0x0000000000000001, [1766734936.384] [188801:188803] [DMG] DeltaSystemTimestamp = 0x0000000000000008, [1766734936.384] [188801:188803] [DMG] EventData = [1766734936.384] [188801:188803] [DMG] { [1766734936.385] [188801:188803] [DMG] 0x1 = 112233 (unsigned), [1766734936.385] [188801:188803] [DMG] 0x2 = NULL [1766734936.385] [188801:188803] [DMG] 0x3 = 1 (unsigned), [1766734936.385] [188801:188803] [DMG] 0x4 = [1766734936.385] [188801:188803] [DMG] { [1766734936.385] [188801:188803] [DMG] 0x1 = 3 (unsigned), [1766734936.385] [188801:188803] [DMG] 0x2 = 3 (unsigned), [1766734936.385] [188801:188803] [DMG] 0x3 = [ [1766734936.385] [188801:188803] [DMG] 555 (unsigned), 666 (unsigned), 777 (unsigned), 888 (unsigned), [1766734936.385] [188801:188803] [DMG] ], [1766734936.385] [188801:188803] [DMG] 0x4 = [ [1766734936.385] [188801:188803] [DMG] [1766734936.385] [188801:188803] [DMG] { [1766734936.385] [188801:188803] [DMG] 0x0 = 55 (unsigned), [1766734936.385] [188801:188803] [DMG] 0x1 = 66 (unsigned), [1766734936.385] [188801:188803] [DMG] 0x2 = NULL [1766734936.385] [188801:188803] [DMG] }, [1766734936.385] [188801:188803] [DMG] ], [1766734936.385] [188801:188803] [DMG] 0xfe = 1 (unsigned), [1766734936.386] [188801:188803] [DMG] }, [1766734936.386] [188801:188803] [DMG] 0xfe = 1 (unsigned), [1766734936.386] [188801:188803] [DMG] }, [1766734936.386] [188801:188803] [DMG] }, [1766734936.386] [188801:188803] [DMG] [1766734936.386] [188801:188803] [DMG] }, [1766734936.386] [188801:188803] [DMG] [1766734936.386] [188801:188803] [DMG] ], [1766734936.386] [188801:188803] [DMG] [1766734936.386] [188801:188803] [DMG] InteractionModelRevision = 12 [1766734936.386] [188801:188803] [DMG] } [1766734936.387] [188801:188803] [TOO] Endpoint: 0 Cluster: 0x0000_001F Event 0x0000_0000 [1766734936.387] [188801:188803] [TOO] Event number: 3 [1766734936.387] [188801:188803] [TOO] Priority: Info [1766734936.387] [188801:188803] [TOO] Timestamp: 6884 [1766734936.387] [188801:188803] [TOO] AccessControlEntryChanged: { [1766734936.387] [188801:188803] [TOO] AdminNodeID: null [1766734936.387] [188801:188803] [TOO] AdminPasscodeID: 0 [1766734936.387] [188801:188803] [TOO] ChangeType: 1 [1766734936.387] [188801:188803] [TOO] LatestValue: { [1766734936.387] [188801:188803] [TOO] Privilege: 5 [1766734936.387] [188801:188803] [TOO] AuthMode: 2 [1766734936.387] [188801:188803] [TOO] Subjects: 1 entries [1766734936.387] [188801:188803] [TOO] [1]: 112233 [1766734936.387] [188801:188803] [TOO] Targets: null [1766734936.387] [188801:188803] [TOO] FabricIndex: 1 [1766734936.388] [188801:188803] [TOO] } [1766734936.388] [188801:188803] [TOO] FabricIndex: 1 [1766734936.388] [188801:188803] [TOO] } [1766734936.388] [188801:188803] [TOO] Endpoint: 0 Cluster: 0x0000_001F Event 0x0000_0000 [1766734936.388] [188801:188803] [TOO] Event number: 5 [1766734936.388] [188801:188803] [TOO] Priority: Info [1766734936.388] [188801:188803] [TOO] Timestamp: 256204 [1766734936.388] [188801:188803] [TOO] AccessControlEntryChanged: { [1766734936.388] [188801:188803] [TOO] AdminNodeID: 112233 [1766734936.388] [188801:188803] [TOO] AdminPasscodeID: null [1766734936.388] [188801:188803] [TOO] ChangeType: 0 [1766734936.388] [188801:188803] [TOO] LatestValue: { [1766734936.388] [188801:188803] [TOO] Privilege: 5 [1766734936.388] [188801:188803] [TOO] AuthMode: 2 [1766734936.388] [188801:188803] [TOO] Subjects: 2 entries [1766734936.388] [188801:188803] [TOO] [1]: 1 [1766734936.388] [188801:188803] [TOO] [2]: 112233 [1766734936.388] [188801:188803] [TOO] Targets: null [1766734936.388] [188801:188803] [TOO] FabricIndex: 1 [1766734936.388] [188801:188803] [TOO] } [1766734936.388] [188801:188803] [TOO] FabricIndex: 1 [1766734936.388] [188801:188803] [TOO] } [1766734936.388] [188801:188803] [TOO] Endpoint: 0 Cluster: 0x0000_001F Event 0x0000_0000 [1766734936.388] [188801:188803] [TOO] Event number: 6 [1766734936.388] [188801:188803] [TOO] Priority: Info [1766734936.388] [188801:188803] [TOO] Timestamp: 1513827 [1766734936.388] [188801:188803] [TOO] AccessControlEntryChanged: { [1766734936.388] [188801:188803] [TOO] AdminNodeID: 112233 [1766734936.388] [188801:188803] [TOO] AdminPasscodeID: null [1766734936.388] [188801:188803] [TOO] ChangeType: 0 [1766734936.388] [188801:188803] [TOO] LatestValue: { [1766734936.388] [188801:188803] [TOO] Privilege: 5 [1766734936.388] [188801:188803] [TOO] AuthMode: 2 [1766734936.389] [188801:188803] [TOO] Subjects: 2 entries [1766734936.389] [188801:188803] [TOO] [1]: 1 [1766734936.389] [188801:188803] [TOO] [2]: 112233 [1766734936.389] [188801:188803] [TOO] Targets: 1 entries [1766734936.389] [188801:188803] [TOO] [1]: { [1766734936.389] [188801:188803] [TOO] Cluster: 31 [1766734936.389] [188801:188803] [TOO] Endpoint: 0 [1766734936.389] [188801:188803] [TOO] DeviceType: null [1766734936.389] [188801:188803] [TOO] } [1766734936.389] [188801:188803] [TOO] FabricIndex: 1 [1766734936.389] [188801:188803] [TOO] } [1766734936.389] [188801:188803] [TOO] FabricIndex: 1 [1766734936.389] [188801:188803] [TOO] } [1766734936.389] [188801:188803] [TOO] Endpoint: 0 Cluster: 0x0000_001F Event 0x0000_0000 [1766734936.389] [188801:188803] [TOO] Event number: 7 [1766734936.389] [188801:188803] [TOO] Priority: Info [1766734936.389] [188801:188803] [TOO] Timestamp: 1646651 [1766734936.389] [188801:188803] [TOO] AccessControlEntryChanged: { [1766734936.389] [188801:188803] [TOO] AdminNodeID: 112233 [1766734936.389] [188801:188803] [TOO] AdminPasscodeID: null [1766734936.389] [188801:188803] [TOO] ChangeType: 0 [1766734936.389] [188801:188803] [TOO] LatestValue: { [1766734936.389] [188801:188803] [TOO] Privilege: 5 [1766734936.389] [188801:188803] [TOO] AuthMode: 2 [1766734936.389] [188801:188803] [TOO] Subjects: 1 entries [1766734936.389] [188801:188803] [TOO] [1]: 112233 [1766734936.389] [188801:188803] [TOO] Targets: null [1766734936.389] [188801:188803] [TOO] FabricIndex: 1 [1766734936.389] [188801:188803] [TOO] } [1766734936.389] [188801:188803] [TOO] FabricIndex: 1 [1766734936.389] [188801:188803] [TOO] } [1766734936.389] [188801:188803] [TOO] Endpoint: 0 Cluster: 0x0000_001F Event 0x0000_0000 [1766734936.389] [188801:188803] [TOO] Event number: 8 [1766734936.389] [188801:188803] [TOO] Priority: Info [1766734936.389] [188801:188803] [TOO] Timestamp: 2155956 [1766734936.390] [188801:188803] [TOO] AccessControlEntryChanged: { [1766734936.390] [188801:188803] [TOO] AdminNodeID: 112233 [1766734936.390] [188801:188803] [TOO] AdminPasscodeID: null [1766734936.390] [188801:188803] [TOO] ChangeType: 0 [1766734936.390] [188801:188803] [TOO] LatestValue: { [1766734936.390] [188801:188803] [TOO] Privilege: 5 [1766734936.390] [188801:188803] [TOO] AuthMode: 2 [1766734936.390] [188801:188803] [TOO] Subjects: 1 entries [1766734936.390] [188801:188803] [TOO] [1]: 112233 [1766734936.390] [188801:188803] [TOO] Targets: null [1766734936.390] [188801:188803] [TOO] FabricIndex: 1 [1766734936.390] [188801:188803] [TOO] } [1766734936.390] [188801:188803] [TOO] FabricIndex: 1 [1766734936.390] [188801:188803] [TOO] } [1766734936.390] [188801:188803] [TOO] Endpoint: 0 Cluster: 0x0000_001F Event 0x0000_0000 [1766734936.390] [188801:188803] [TOO] Event number: 9 [1766734936.390] [188801:188803] [TOO] Priority: Info [1766734936.390] [188801:188803] [TOO] Timestamp: 2155965 [1766734936.390] [188801:188803] [TOO] AccessControlEntryChanged: { [1766734936.390] [188801:188803] [TOO] AdminNodeID: 112233 [1766734936.390] [188801:188803] [TOO] AdminPasscodeID: null [1766734936.390] [188801:188803] [TOO] ChangeType: 1 [1766734936.390] [188801:188803] [TOO] LatestValue: { [1766734936.390] [188801:188803] [TOO] Privilege: 1 [1766734936.390] [188801:188803] [TOO] AuthMode: 3 [1766734936.390] [188801:188803] [TOO] Subjects: 4 entries [1766734936.390] [188801:188803] [TOO] [1]: 111 [1766734936.390] [188801:188803] [TOO] [2]: 222 [1766734936.390] [188801:188803] [TOO] [3]: 333 [1766734936.390] [188801:188803] [TOO] [4]: 444 [1766734936.390] [188801:188803] [TOO] Targets: 1 entries [1766734936.390] [188801:188803] [TOO] [1]: { [1766734936.390] [188801:188803] [TOO] Cluster: 11 [1766734936.390] [188801:188803] [TOO] Endpoint: 22 [1766734936.390] [188801:188803] [TOO] DeviceType: null [1766734936.390] [188801:188803] [TOO] } [1766734936.390] [188801:188803] [TOO] FabricIndex: 1 [1766734936.390] [188801:188803] [TOO] } [1766734936.391] [188801:188803] [TOO] FabricIndex: 1 [1766734936.391] [188801:188803] [TOO] } [1766734936.391] [188801:188803] [TOO] Endpoint: 0 Cluster: 0x0000_001F Event 0x0000_0000 [1766734936.391] [188801:188803] [TOO] Event number: 10 [1766734936.391] [188801:188803] [TOO] Priority: Info [1766734936.391] [188801:188803] [TOO] Timestamp: 2155973 [1766734936.391] [188801:188803] [TOO] AccessControlEntryChanged: { [1766734936.391] [188801:188803] [TOO] AdminNodeID: 112233 [1766734936.391] [188801:188803] [TOO] AdminPasscodeID: null [1766734936.391] [188801:188803] [TOO] ChangeType: 1 [1766734936.391] [188801:188803] [TOO] LatestValue: { [1766734936.391] [188801:188803] [TOO] Privilege: 3 [1766734936.391] [188801:188803] [TOO] AuthMode: 3 [1766734936.391] [188801:188803] [TOO] Subjects: 4 entries [1766734936.391] [188801:188803] [TOO] [1]: 555 [1766734936.391] [188801:188803] [TOO] [2]: 666 [1766734936.391] [188801:188803] [TOO] [3]: 777 [1766734936.391] [188801:188803] [TOO] [4]: 888 [1766734936.391] [188801:188803] [TOO] Targets: 1 entries [1766734936.391] [188801:188803] [TOO] [1]: { [1766734936.391] [188801:188803] [TOO] Cluster: 55 [1766734936.391] [188801:188803] [TOO] Endpoint: 66 [1766734936.391] [188801:188803] [TOO] DeviceType: null [1766734936.391] [188801:188803] [TOO] } [1766734936.391] [188801:188803] [TOO] FabricIndex: 1 [1766734936.391] [188801:188803] [TOO] } [1766734936.391] [188801:188803] [TOO] FabricIndex: 1 [1766734936.391] [188801:188803] [TOO] } [1766734936.391] [188801:188803] [DMG] MoveToState ReadClient[0xffff84014100]: Moving to [AwaitingSu] [1766734936.392] [188801:188803] [EM] <<< [E:22373i S:55567 M:27425737 (Ack:87725939)] (S) Msg TX from 000000000001B669 to 1:0000000000000001 [0B9C] [UDP:[fd11:11::6729:d33b:eae2:c0fc]:5540] --- Type 0001:01 (IM:StatusResponse) (B:42) [1766734936.392] [188801:188803] [EM] ??1 [E:22373i S:55567 M:27425737] (S) Msg Retransmission to 1:0000000000000001 scheduled for 2919ms from now [State:Idle II:3000 AI:2200 AT:0] [1766734936.491] [188801:188803] [EM] >>> [E:22373i S:55567 M:87725940 (Ack:27425737)] (S) Msg RX from 1:0000000000000001 [0B9C] to 000000000001B669 --- Type 0001:04 (IM:SubscribeResponse) (B:48) [1766734936.491] [188801:188803] [EM] Found matching exchange: 22373i, Delegate: 0xffff84014110 [1766734936.491] [188801:188803] [EM] Rxd Ack; Removing MessageCounter:27425737 from Retrans Table on exchange 22373i [1766734936.491] [188801:188803] [DMG] SubscribeResponse is received [1766734936.491] [188801:188803] [DMG] SubscribeResponseMessage = [1766734936.491] [188801:188803] [DMG] { [1766734936.491] [188801:188803] [DMG] SubscriptionId = 0xf191f3db, [1766734936.491] [188801:188803] [DMG] MaxInterval = 0x78, [1766734936.491] [188801:188803] [DMG] InteractionModelRevision = 12 [1766734936.491] [188801:188803] [DMG] } [1766734936.491] [188801:188803] [DMG] Subscription established in 395ms with SubscriptionID = 0xf191f3db MinInterval = 20s MaxInterval = 120s Peer = 01:0000000000000001 [1766734936.491] [188801:188803] [DMG] MoveToState ReadClient[0xffff84014100]: Moving to [Subscripti] [1766734936.491] [188801:188803] [DMG] Refresh LivenessCheckTime for 160049 milliseconds with SubscriptionId = 0xf191f3db Peer = 01:0000000000000001 [1766734936.492] [188801:188803] [EM] <<< [E:22373i S:55567 M:27425738 (Ack:87725940)] (S) Msg TX from 000000000001B669 to 1:0000000000000001 [0B9C] [UDP:[fd11:11::6729:d33b:eae2:c0fc]:5540] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34) [1766734936.492] [188801:188803] [EM] Flushed pending ack for MessageCounter:87725940 on exchange 22373i >>> accesscontrol write acl '[{"fabricIndex": 1, "privilege": 5, "authMode": 2, "subjects": [112233], "targets": null}, {"fabricIndex": 1, "privilege": 1, "authMode": 3, "subjects": [111,222,333,444], "targets": [{"cluster":11 , "endpoint":22, "deviceType": null}]}, {"fabricIndex": 1, "privilege": 3, "authMode": 3, "subjects": [555,666,777,888], "targets": [{"cluster": 55, "endpoint": 66, "deviceType":null }]}]' 1 0 [1766734938.092] [188801:188801] [TOO] Command: accesscontrol write acl [{"fabricIndex": 1, "privilege": 5, "authMode": 2, "subjects": [112233], "targets": null}, {"fabricIndex": 1, "privilege": 1, "authMode": 3, "subjects": [111,222,333,444], "targets": [{"cluster":11 , "endpoint":22, "deviceType": null}]}, {"fabricIndex": 1, "privilege": 3, "authMode": 3, "subjects": [555,666,777,888], "targets": [{"cluster": 55, "endpoint": 66, "deviceType":null }]}] 1 0 [1766734938.095] [188801:188803] [TOO] Sending command to node 0x1 [1766734938.097] [188801:188803] [CSM] FindOrEstablishSession: PeerId = [1:0000000000000001] [1766734938.097] [188801:188803] [CSM] FindOrEstablishSession: No existing OperationalSessionSetup instance found [1766734938.097] [188801:188803] [DIS] Found an existing secure session to [1:0000000000000001]! [1766734938.097] [188801:188803] [DIS] OperationalSessionSetup[1:0000000000000001]: State change 1 --> 5 [1766734938.098] [188801:188803] [TOO] cluster 0x0000_001F, attribute: 0x0000_0000, endpoint 0 [1766734938.098] [188801:188803] [DMG] WriteClient moving to [AddAttribu] [1766734938.098] [188801:188803] [EM] <<< [E:22374i S:55567 M:27425739] (S) Msg TX from 000000000001B669 to 1:0000000000000001 [0B9C] [UDP:[fd11:11::6729:d33b:eae2:c0fc]:5540] --- Type 0001:06 (IM:WriteRequest) (B:149) [1766734938.099] [188801:188803] [EM] ??1 [E:22374i S:55567 M:27425739] (S) Msg Retransmission to 1:0000000000000001 scheduled for 4039ms from now [State:Idle II:3000 AI:2200 AT:0] [1766734938.099] [188801:188803] [DMG] WriteClient moving to [AwaitingRe] [1766734938.728] [188801:188803] [EM] >>> [E:22374i S:55567 M:87725941 (Ack:27425739)] (S) Msg RX from 1:0000000000000001 [0B9C] to 000000000001B669 --- Type 0001:07 (IM:WriteResponse) (B:62) [1766734938.728] [188801:188803] [EM] Found matching exchange: 22374i, Delegate: 0xffff84012c50 [1766734938.728] [188801:188803] [EM] Rxd Ack; Removing MessageCounter:27425739 from Retrans Table on exchange 22374i [1766734938.728] [188801:188803] [DMG] WriteClient moving to [ResponseRe] [1766734938.729] [188801:188803] [DMG] WriteResponseMessage = [1766734938.729] [188801:188803] [DMG] { [1766734938.729] [188801:188803] [DMG] AttributeStatusIBs = [1766734938.729] [188801:188803] [DMG] [ [1766734938.729] [188801:188803] [DMG] AttributeStatusIB = [1766734938.729] [188801:188803] [DMG] { [1766734938.729] [188801:188803] [DMG] AttributePathIB = [1766734938.729] [188801:188803] [DMG] { [1766734938.729] [188801:188803] [DMG] Endpoint = 0x0, [1766734938.729] [188801:188803] [DMG] Cluster = 0x1f, [1766734938.729] [188801:188803] [DMG] Attribute = 0x0000_0000, [1766734938.729] [188801:188803] [DMG] } [1766734938.729] [188801:188803] [DMG] [1766734938.729] [188801:188803] [DMG] StatusIB = [1766734938.730] [188801:188803] [DMG] { [1766734938.730] [188801:188803] [DMG] status = 0x00 (SUCCESS), [1766734938.730] [188801:188803] [DMG] }, [1766734938.730] [188801:188803] [DMG] [1766734938.730] [188801:188803] [DMG] }, [1766734938.730] [188801:188803] [DMG] [1766734938.730] [188801:188803] [DMG] ], [1766734938.730] [188801:188803] [DMG] [1766734938.730] [188801:188803] [DMG] InteractionModelRevision = 12 [1766734938.730] [188801:188803] [DMG] } [1766734938.730] [188801:188803] [DMG] WriteClient moving to [AwaitingDe] [1766734938.731] [188801:188803] [EM] <<< [E:22374i S:55567 M:27425740 (Ack:87725941)] (S) Msg TX from 000000000001B669 to 1:0000000000000001 [0B9C] [UDP:[fd11:11::6729:d33b:eae2:c0fc]:5540] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34) [1766734938.731] [188801:188803] [EM] Flushed pending ack for MessageCounter:87725941 on exchange 22374i [1766735056.520] [188801:188803] [EM] >>> [E:12235r S:55567 M:87725942] (S) Msg RX from 1:0000000000000001 [0B9C] to 000000000001B669 --- Type 0001:05 (IM:ReportData) (B:276) [1766735056.520] [188801:188803] [EM] Handling via exchange: 12235r, Delegate: 0xaaaab4536cc0 [1766735056.520] [188801:188803] [DMG] ReportDataMessage = [1766735056.520] [188801:188803] [DMG] { [1766735056.520] [188801:188803] [DMG] SubscriptionId = 0xf191f3db, [1766735056.520] [188801:188803] [DMG] EventReportIBs = [1766735056.520] [188801:188803] [DMG] [ [1766735056.520] [188801:188803] [DMG] EventReportIB = [1766735056.521] [188801:188803] [DMG] { [1766735056.521] [188801:188803] [DMG] EventDataIB = [1766735056.521] [188801:188803] [DMG] { [1766735056.521] [188801:188803] [DMG] EventPath = [1766735056.521] [188801:188803] [DMG] { [1766735056.521] [188801:188803] [DMG] Endpoint = 0x0, [1766735056.521] [188801:188803] [DMG] Cluster = 0x1f, [1766735056.521] [188801:188803] [DMG] Event = 0x0, [1766735056.521] [188801:188803] [DMG] }, [1766735056.521] [188801:188803] [DMG] [1766735056.521] [188801:188803] [DMG] EventNumber = 0x000000000000000B, [1766735056.521] [188801:188803] [DMG] PriorityLevel = 0x0000000000000001, [1766735056.521] [188801:188803] [DMG] SystemTimestamp = 0x0000000000231048, [1766735056.522] [188801:188803] [DMG] EventData = [1766735056.522] [188801:188803] [DMG] { [1766735056.522] [188801:188803] [DMG] 0x1 = 112233 (unsigned), [1766735056.522] [188801:188803] [DMG] 0x2 = NULL [1766735056.522] [188801:188803] [DMG] 0x3 = 0 (unsigned), [1766735056.522] [188801:188803] [DMG] 0x4 = [1766735056.522] [188801:188803] [DMG] { [1766735056.522] [188801:188803] [DMG] 0x1 = 5 (unsigned), [1766735056.522] [188801:188803] [DMG] 0x2 = 2 (unsigned), [1766735056.522] [188801:188803] [DMG] 0x3 = [ [1766735056.523] [188801:188803] [DMG] 112233 (unsigned), [1766735056.523] [188801:188803] [DMG] ], [1766735056.523] [188801:188803] [DMG] 0x4 = NULL [1766735056.523] [188801:188803] [DMG] 0xfe = 1 (unsigned), [1766735056.523] [188801:188803] [DMG] }, [1766735056.523] [188801:188803] [DMG] 0xfe = 1 (unsigned), [1766735056.523] [188801:188803] [DMG] }, [1766735056.523] [188801:188803] [DMG] }, [1766735056.523] [188801:188803] [DMG] [1766735056.523] [188801:188803] [DMG] }, [1766735056.523] [188801:188803] [DMG] [1766735056.524] [188801:188803] [DMG] EventReportIB = [1766735056.524] [188801:188803] [DMG] { [1766735056.524] [188801:188803] [DMG] EventDataIB = [1766735056.524] [188801:188803] [DMG] { [1766735056.524] [188801:188803] [DMG] EventPath = [1766735056.524] [188801:188803] [DMG] { [1766735056.524] [188801:188803] [DMG] Endpoint = 0x0, [1766735056.524] [188801:188803] [DMG] Cluster = 0x1f, [1766735056.524] [188801:188803] [DMG] Event = 0x0, [1766735056.524] [188801:188803] [DMG] }, [1766735056.524] [188801:188803] [DMG] [1766735056.524] [188801:188803] [DMG] EventNumber = 0x000000000000000C, [1766735056.525] [188801:188803] [DMG] PriorityLevel = 0x0000000000000001, [1766735056.525] [188801:188803] [DMG] DeltaSystemTimestamp = 0x0000000000000005, [1766735056.525] [188801:188803] [DMG] EventData = [1766735056.525] [188801:188803] [DMG] { [1766735056.525] [188801:188803] [DMG] 0x1 = 112233 (unsigned), [1766735056.525] [188801:188803] [DMG] 0x2 = NULL [1766735056.525] [188801:188803] [DMG] 0x3 = 0 (unsigned), [1766735056.525] [188801:188803] [DMG] 0x4 = [1766735056.525] [188801:188803] [DMG] { [1766735056.525] [188801:188803] [DMG] 0x1 = 1 (unsigned), [1766735056.525] [188801:188803] [DMG] 0x2 = 3 (unsigned), [1766735056.525] [188801:188803] [DMG] 0x3 = [ [1766735056.525] [188801:188803] [DMG] 111 (unsigned), 222 (unsigned), 333 (unsigned), 444 (unsigned), [1766735056.525] [188801:188803] [DMG] ], [1766735056.525] [188801:188803] [DMG] 0x4 = [ [1766735056.525] [188801:188803] [DMG] [1766735056.526] [188801:188803] [DMG] { [1766735056.526] [188801:188803] [DMG] 0x0 = 11 (unsigned), [1766735056.526] [188801:188803] [DMG] 0x1 = 22 (unsigned), [1766735056.526] [188801:188803] [DMG] 0x2 = NULL [1766735056.526] [188801:188803] [DMG] }, [1766735056.526] [188801:188803] [DMG] ], [1766735056.526] [188801:188803] [DMG] 0xfe = 1 (unsigned), [1766735056.526] [188801:188803] [DMG] }, [1766735056.526] [188801:188803] [DMG] 0xfe = 1 (unsigned), [1766735056.526] [188801:188803] [DMG] }, [1766735056.526] [188801:188803] [DMG] }, [1766735056.526] [188801:188803] [DMG] [1766735056.526] [188801:188803] [DMG] }, [1766735056.527] [188801:188803] [DMG] [1766735056.527] [188801:188803] [DMG] EventReportIB = [1766735056.527] [188801:188803] [DMG] { [1766735056.527] [188801:188803] [DMG] EventDataIB = [1766735056.527] [188801:188803] [DMG] { [1766735056.527] [188801:188803] [DMG] EventPath = [1766735056.527] [188801:188803] [DMG] { [1766735056.527] [188801:188803] [DMG] Endpoint = 0x0, [1766735056.527] [188801:188803] [DMG] Cluster = 0x1f, [1766735056.527] [188801:188803] [DMG] Event = 0x0, [1766735056.527] [188801:188803] [DMG] }, [1766735056.527] [188801:188803] [DMG] [1766735056.527] [188801:188803] [DMG] EventNumber = 0x000000000000000D, [1766735056.527] [188801:188803] [DMG] PriorityLevel = 0x0000000000000001, [1766735056.527] [188801:188803] [DMG] DeltaSystemTimestamp = 0x0000000000000004, [1766735056.527] [188801:188803] [DMG] EventData = [1766735056.527] [188801:188803] [DMG] { [1766735056.528] [188801:188803] [DMG] 0x1 = 112233 (unsigned), [1766735056.528] [188801:188803] [DMG] 0x2 = NULL [1766735056.528] [188801:188803] [DMG] 0x3 = 0 (unsigned), [1766735056.528] [188801:188803] [DMG] 0x4 = [1766735056.528] [188801:188803] [DMG] { [1766735056.528] [188801:188803] [DMG] 0x1 = 3 (unsigned), [1766735056.528] [188801:188803] [DMG] 0x2 = 3 (unsigned), [1766735056.528] [188801:188803] [DMG] 0x3 = [ [1766735056.528] [188801:188803] [DMG] 555 (unsigned), 666 (unsigned), 777 (unsigned), 888 (unsigned), [1766735056.528] [188801:188803] [DMG] ], [1766735056.528] [188801:188803] [DMG] 0x4 = [ [1766735056.528] [188801:188803] [DMG] [1766735056.528] [188801:188803] [DMG] { [1766735056.528] [188801:188803] [DMG] 0x0 = 55 (unsigned), [1766735056.529] [188801:188803] [DMG] 0x1 = 66 (unsigned), [1766735056.529] [188801:188803] [DMG] 0x2 = NULL [1766735056.529] [188801:188803] [DMG] }, [1766735056.529] [188801:188803] [DMG] ], [1766735056.529] [188801:188803] [DMG] 0xfe = 1 (unsigned), [1766735056.529] [188801:188803] [DMG] }, [1766735056.529] [188801:188803] [DMG] 0xfe = 1 (unsigned), [1766735056.529] [188801:188803] [DMG] }, [1766735056.529] [188801:188803] [DMG] }, [1766735056.529] [188801:188803] [DMG] [1766735056.529] [188801:188803] [DMG] }, [1766735056.529] [188801:188803] [DMG] [1766735056.529] [188801:188803] [DMG] ], [1766735056.530] [188801:188803] [DMG] [1766735056.530] [188801:188803] [DMG] InteractionModelRevision = 12 [1766735056.530] [188801:188803] [DMG] } [1766735056.530] [188801:188803] [TOO] Endpoint: 0 Cluster: 0x0000_001F Event 0x0000_0000 [1766735056.530] [188801:188803] [TOO] Event number: 11 [1766735056.530] [188801:188803] [TOO] Priority: Info [1766735056.530] [188801:188803] [TOO] Timestamp: 2297928 [1766735056.530] [188801:188803] [TOO] AccessControlEntryChanged: { [1766735056.531] [188801:188803] [TOO] AdminNodeID: 112233 [1766735056.531] [188801:188803] [TOO] AdminPasscodeID: null [1766735056.531] [188801:188803] [TOO] ChangeType: 0 [1766735056.531] [188801:188803] [TOO] LatestValue: { [1766735056.531] [188801:188803] [TOO] Privilege: 5 [1766735056.531] [188801:188803] [TOO] AuthMode: 2 [1766735056.531] [188801:188803] [TOO] Subjects: 1 entries [1766735056.531] [188801:188803] [TOO] [1]: 112233 [1766735056.531] [188801:188803] [TOO] Targets: null [1766735056.531] [188801:188803] [TOO] FabricIndex: 1 [1766735056.531] [188801:188803] [TOO] } [1766735056.531] [188801:188803] [TOO] FabricIndex: 1 [1766735056.531] [188801:188803] [TOO] } [1766735056.531] [188801:188803] [TOO] Endpoint: 0 Cluster: 0x0000_001F Event 0x0000_0000 [1766735056.531] [188801:188803] [TOO] Event number: 12 [1766735056.531] [188801:188803] [TOO] Priority: Info [1766735056.532] [188801:188803] [TOO] Timestamp: 2297933 [1766735056.532] [188801:188803] [TOO] AccessControlEntryChanged: { [1766735056.532] [188801:188803] [TOO] AdminNodeID: 112233 [1766735056.532] [188801:188803] [TOO] AdminPasscodeID: null [1766735056.532] [188801:188803] [TOO] ChangeType: 0 [1766735056.532] [188801:188803] [TOO] LatestValue: { [1766735056.532] [188801:188803] [TOO] Privilege: 1 [1766735056.532] [188801:188803] [TOO] AuthMode: 3 [1766735056.532] [188801:188803] [TOO] Subjects: 4 entries [1766735056.532] [188801:188803] [TOO] [1]: 111 [1766735056.532] [188801:188803] [TOO] [2]: 222 [1766735056.532] [188801:188803] [TOO] [3]: 333 [1766735056.532] [188801:188803] [TOO] [4]: 444 [1766735056.532] [188801:188803] [TOO] Targets: 1 entries [1766735056.532] [188801:188803] [TOO] [1]: { [1766735056.532] [188801:188803] [TOO] Cluster: 11 [1766735056.532] [188801:188803] [TOO] Endpoint: 22 [1766735056.532] [188801:188803] [TOO] DeviceType: null [1766735056.532] [188801:188803] [TOO] } [1766735056.532] [188801:188803] [TOO] FabricIndex: 1 [1766735056.532] [188801:188803] [TOO] } [1766735056.532] [188801:188803] [TOO] FabricIndex: 1 [1766735056.532] [188801:188803] [TOO] } [1766735056.532] [188801:188803] [TOO] Endpoint: 0 Cluster: 0x0000_001F Event 0x0000_0000 [1766735056.532] [188801:188803] [TOO] Event number: 13 [1766735056.532] [188801:188803] [TOO] Priority: Info [1766735056.533] [188801:188803] [TOO] Timestamp: 2297937 [1766735056.533] [188801:188803] [TOO] AccessControlEntryChanged: { [1766735056.533] [188801:188803] [TOO] AdminNodeID: 112233 [1766735056.533] [188801:188803] [TOO] AdminPasscodeID: null [1766735056.533] [188801:188803] [TOO] ChangeType: 0 [1766735056.533] [188801:188803] [TOO] LatestValue: { [1766735056.533] [188801:188803] [TOO] Privilege: 3 [1766735056.533] [188801:188803] [TOO] AuthMode: 3 [1766735056.533] [188801:188803] [TOO] Subjects: 4 entries [1766735056.533] [188801:188803] [TOO] [1]: 555 [1766735056.533] [188801:188803] [TOO] [2]: 666 [1766735056.533] [188801:188803] [TOO] [3]: 777 [1766735056.533] [188801:188803] [TOO] [4]: 888 [1766735056.533] [188801:188803] [TOO] Targets: 1 entries [1766735056.533] [188801:188803] [TOO] [1]: { [1766735056.533] [188801:188803] [TOO] Cluster: 55 [1766735056.533] [188801:188803] [TOO] Endpoint: 66 [1766735056.533] [188801:188803] [TOO] DeviceType: null [1766735056.533] [188801:188803] [TOO] } [1766735056.533] [188801:188803] [TOO] FabricIndex: 1 [1766735056.533] [188801:188803] [TOO] } [1766735056.533] [188801:188803] [TOO] FabricIndex: 1 [1766735056.533] [188801:188803] [TOO] } [1766735056.533] [188801:188803] [DMG] Refresh LivenessCheckTime for 160049 milliseconds with SubscriptionId = 0xf191f3db Peer = 01:0000000000000001 [1766735056.533] [188801:188803] [EM] <<< [E:12235r S:55567 M:27425741 (Ack:87725942)] (S) Msg TX from 000000000001B669 to 1:0000000000000001 [0B9C] [UDP:[fd11:11::6729:d33b:eae2:c0fc]:5540] --- Type 0001:01 (IM:StatusResponse) (B:42) [1766735056.534] [188801:188803] [EM] ??1 [E:12235r S:55567 M:27425741] (S) Msg Retransmission to 1:0000000000000001 scheduled for 2976ms from now [State:Idle II:3000 AI:2200 AT:0] [1766735056.721] [188801:188803] [EM] >>> [E:12235r S:55567 M:87725943 (Ack:27425741)] (S) Msg RX from 1:0000000000000001 [0B9C] to 000000000001B669 --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34) [1766735056.721] [188801:188803] [EM] Found matching exchange: 12235r, Delegate: (nil) [1766735056.721] [188801:188803] [EM] Rxd Ack; Removing MessageCounter:27425741 from Retrans Table on exchange 12235r ubuntu@ubuntu:~/apps$