Matter (formerly Project CHIP) creates more connections between more objects, simplifying development for manufacturers and increasing compatibility for consumers, guided by the Connectivity Standards Alliance.
Below three test cases in Window Covering Cluster are giving Internal Error when running with the Mac chip tool,
3.3.1. [TC-WNCV-3.1] UpOrOpen Verification with server as DUT
3.3.2. [TC-WNCV-3.2] DownOrClose Verification with server as DUT
3.3.3. [TC-WNCV-3.3] StopMotion Verification with server as DUT
Tried with different endpoint-id but they were still giving the internal error. Attaching the logs for these test cases.
shyam@shyams-MacBook-Pro chip-tool % ./out/debug/chip-tool windowcovering down-or-close 1
[1631906589008] [11666:575338] CHIP: [CTL] Read local id 0x000000000001B669, remote id 0xFC526F7FCD1C3D42
[1631906589010] [11666:575338] CHIP: [CTL] Generating NOC
[1631906589010] [11666:575338] CHIP: [CTL] Generating ICAC
[1631906589010] [11666:575338] CHIP: [DL] _Init
[1631906589010] [11666:575338] CHIP: [IN] UDP::Init bind&listen port=5541
[1631906589011] [11666:575338] CHIP: [IN] UDP::Init bind&listen port=5541
[1631906589011] [11666:575338] CHIP: [IN] TransportMgr initialized
[1631906589011] [11666:575338] CHIP: [DIS] Init fabric pairing table with server storage
[1631906589011] [11666:575338] CHIP: [ZCL] Using ZAP configuration...
[1631906589011] [11666:575338] CHIP: [ZCL] deactivate report event
[1631906589012] [11666:575338] CHIP: [DIS] Verifying the received credentials
[1631906589012] [11666:575338] CHIP: [DIS] Added new fabric at index: 1, Initialized: 1
[1631906589012] [11666:575338] CHIP: [DIS] Assigned fabric ID: 0x0000000000000000, node ID: 0x000000000001B669
[1631906589012] [11666:575338] CHIP: [CTL] Joined the fabric at index 1
[1631906589013] [11666:575343] CHIP: [SC] Sent SigmaR1 msg
[1631906589129] [11666:575344] CHIP: [EM] Received message of type 0x31 with vendorId 0x0000 and protocolId 0x0000 on exchange 44249
[1631906589129] [11666:575344] CHIP: [EM] Rxd Ack; Removing MsgId:00000000 from Retrans Table
[1631906589129] [11666:575344] CHIP: [EM] Removed CHIP MsgId:00000000 from RetransTable
[1631906589129] [11666:575344] CHIP: [SC] Received SigmaR2 msg
[1631906589129] [11666:575344] CHIP: [SC] Peer assigned session key ID 11
[1631906589130] [11666:575344] CHIP: [SC] Sending SigmaR3
[1631906589130] [11666:575344] CHIP: [EM] Piggybacking Ack for MsgId:00000000 with msg
[1631906589130] [11666:575344] CHIP: [SC] Sent SigmaR3 msg
[1631906589130] [11666:575344] CHIP: [IN] New secure session created for device 0xFC526F7FCD1C3D42, key 11!!
[1631906589130] [11666:575344] CHIP: [TOO] Sending cluster (0x0102) command (0x01) on endpoint 1
[1631906589130] [11666:575344] CHIP: [DMG] ICR moving to [Initialize]
[1631906589130] [11666:575344] CHIP: [DMG] ICR moving to [AddCommand]
[1631906589130] [11666:575344] CHIP: [IN] Secure message was encrypted: Msg ID 0
[1631906589130] [11666:575344] CHIP: [IN] Encrypted message 0x7fde38008270 to 0xFC526F7FCD1C3D42 of type 8 and protocolId 5 on exchange 44250.
[1631906589130] [11666:575344] CHIP: [IN] Sending msg 0x7fde38008270 to 0xFC526F7FCD1C3D42 at utc time: 84812817 msec
[1631906589131] [11666:575344] CHIP: [IN] Sending secure msg on generic transport
[1631906589131] [11666:575344] CHIP: [IN] Secure msg send status ../../third_party/connectedhomeip/src/inet/IPEndPointBasis.cpp:915: Success
[1631906589131] [11666:575344] CHIP: [DMG] ICR moving to [ Sending]
[1631906589139] [11666:575344] CHIP: [EM] Received message of type 0x10 with vendorId 0x0000 and protocolId 0x0000 on exchange 44249
[1631906589139] [11666:575344] CHIP: [EM] Rxd Ack; Removing MsgId:00000000 from Retrans Table
[1631906589139] [11666:575344] CHIP: [EM] Removed CHIP MsgId:00000000 from RetransTable
[1631906589143] [11666:575344] CHIP: [EM] Received message of type 0x09 with vendorId 0x0000 and protocolId 0x0005 on exchange 44250
[1631906589143] [11666:575344] CHIP: [EM] Rxd Ack; Removing MsgId:00000000 from Retrans Table
[1631906589143] [11666:575344] CHIP: [EM] Removed CHIP MsgId:00000000 from RetransTable
[1631906589143] [11666:575344] CHIP: [DMG] InvokeCommand =
[1631906589143] [11666:575344] CHIP: [DMG] {
[1631906589143] [11666:575344] CHIP: [DMG] CommandList =
[1631906589143] [11666:575344] CHIP: [DMG] [
[1631906589143] [11666:575344] CHIP: [DMG] CommandDataElement =
[1631906589143] [11666:575344] CHIP: [DMG] {
[1631906589143] [11666:575344] CHIP: [DMG] CommandPath =
[1631906589143] [11666:575344] CHIP: [DMG] {
[1631906589143] [11666:575344] CHIP: [DMG] EndpointId = 0x1,
[1631906589143] [11666:575344] CHIP: [DMG] ClusterId = 0x102,
[1631906589143] [11666:575344] CHIP: [DMG] CommandId = 0x1,
[1631906589143] [11666:575344] CHIP: [DMG] },
[1631906589143] [11666:575344] CHIP: [DMG]
[1631906589143] [11666:575344] CHIP: [DMG] StatusElement =
[1631906589143] [11666:575344] CHIP: [DMG] {
[1631906589143] [11666:575344] CHIP: [DMG] GeneralCode = 0xd,
[1631906589143] [11666:575344] CHIP: [DMG] ProtocolId = 0x0,
[1631906589143] [11666:575344] CHIP: [DMG] protocolCode = 0x85,
[1631906589143] [11666:575344] CHIP: [DMG] },
[1631906589143] [11666:575344] CHIP: [DMG]
[1631906589143] [11666:575344] CHIP: [DMG] },
[1631906589143] [11666:575344] CHIP: [DMG]
[1631906589143] [11666:575344] CHIP: [DMG] ],
[1631906589143] [11666:575344] CHIP: [DMG]
[1631906589143] [11666:575344] CHIP: [DMG] }
[1631906589143] [11666:575344] CHIP: [ZCL] DefaultResponse:
[1631906589144] [11666:575344] CHIP: [ZCL] Transaction: 0x10ffeef28
[1631906589144] [11666:575344] CHIP: [ZCL] status: EMBER_ZCL_STATUS_FAILURE (0x01)
[1631906589144] [11666:575344] CHIP: [TOO] Default Failure Response: 0x01
[1631906589144] [11666:575344] CHIP: [DMG] ICR moving to [Uninitiali]
[1631906589144] [11666:575344] CHIP: [EM] Sending Standalone Ack for MsgId:00000001
[1631906589144] [11666:575344] CHIP: [IN] Secure message was encrypted: Msg ID 1
[1631906589144] [11666:575338] CHIP: [TOO] Run command failure: ../../commands/clusters/Commands.h:43: CHIP Error 0x000000AC: Internal error
[1631906589144] [11666:575344] CHIP: [IN] Encrypted message 0x700008fb1518 to 0xFC526F7FCD1C3D42 of type 16 and protocolId 0 on exchange 44250.
[1631906589144] [11666:575344] CHIP: [IN] Sending msg 0x700008fb1518 to 0xFC526F7FCD1C3D42 at utc time: 84812830 msec
[1631906589144] [11666:575344] CHIP: [IN] Sending secure msg on generic transport
[1631906589144] [11666:575344] CHIP: [IN] Secure msg send status ../../third_party/connectedhomeip/src/inet/IPEndPointBasis.cpp:915: Success
[1631906589144] [11666:575344] CHIP: [EM] Flushed pending ack for MsgId:00000001
[1631906589144] [11666:575338] CHIP: [CTL] Shutting down the commissioner
[1631906589144] [11666:575338] CHIP: [CTL] Shutting down the controller
[1631906589145] [11666:575338] CHIP: [DL] Inet Layer shutdown
[1631906589145] [11666:575338] CHIP: [DL] BLE layer shutdown
[1631906589145] [11666:575338] CHIP: [DL] System Layer shutdown
shyam@shyams-MacBook-Pro chip-tool % ./out/debug/chip-tool windowcovering up-or-open 1
[1631906675197] [11669:576426] CHIP: [CTL] Read local id 0x000000000001B669, remote id 0xFC526F7FCD1C3D42
[1631906675200] [11669:576426] CHIP: [CTL] Generating NOC
[1631906675200] [11669:576426] CHIP: [CTL] Generating ICAC
[1631906675200] [11669:576426] CHIP: [DL] _Init
[1631906675200] [11669:576426] CHIP: [IN] UDP::Init bind&listen port=5541
[1631906675200] [11669:576426] CHIP: [IN] UDP::Init bind&listen port=5541
[1631906675200] [11669:576426] CHIP: [IN] TransportMgr initialized
[1631906675200] [11669:576426] CHIP: [DIS] Init fabric pairing table with server storage
[1631906675201] [11669:576426] CHIP: [ZCL] Using ZAP configuration...
[1631906675201] [11669:576426] CHIP: [ZCL] deactivate report event
[1631906675202] [11669:576426] CHIP: [DIS] Verifying the received credentials
[1631906675202] [11669:576426] CHIP: [DIS] Added new fabric at index: 1, Initialized: 1
[1631906675202] [11669:576426] CHIP: [DIS] Assigned fabric ID: 0x0000000000000000, node ID: 0x000000000001B669
[1631906675202] [11669:576426] CHIP: [CTL] Joined the fabric at index 1
[1631906675203] [11669:576430] CHIP: [SC] Sent SigmaR1 msg
[1631906675249] [11669:576430] CHIP: [EM] Received message of type 0x31 with vendorId 0x0000 and protocolId 0x0000 on exchange 44249
[1631906675249] [11669:576430] CHIP: [EM] Rxd Ack; Removing MsgId:00000000 from Retrans Table
[1631906675249] [11669:576430] CHIP: [EM] Removed CHIP MsgId:00000000 from RetransTable
[1631906675249] [11669:576430] CHIP: [SC] Received SigmaR2 msg
[1631906675249] [11669:576430] CHIP: [SC] Peer assigned session key ID 12
[1631906675250] [11669:576430] CHIP: [SC] Sending SigmaR3
[1631906675250] [11669:576430] CHIP: [EM] Piggybacking Ack for MsgId:00000000 with msg
[1631906675250] [11669:576430] CHIP: [SC] Sent SigmaR3 msg
[1631906675250] [11669:576430] CHIP: [IN] New secure session created for device 0xFC526F7FCD1C3D42, key 12!!
[1631906675250] [11669:576430] CHIP: [TOO] Sending cluster (0x0102) command (0x00) on endpoint 1
[1631906675250] [11669:576430] CHIP: [DMG] ICR moving to [Initialize]
[1631906675250] [11669:576430] CHIP: [DMG] ICR moving to [AddCommand]
[1631906675251] [11669:576430] CHIP: [IN] Secure message was encrypted: Msg ID 0
[1631906675251] [11669:576430] CHIP: [IN] Encrypted message 0x7fc7a700e470 to 0xFC526F7FCD1C3D42 of type 8 and protocolId 5 on exchange 44250.
[1631906675251] [11669:576430] CHIP: [IN] Sending msg 0x7fc7a700e470 to 0xFC526F7FCD1C3D42 at utc time: 84898937 msec
[1631906675251] [11669:576430] CHIP: [IN] Sending secure msg on generic transport
[1631906675251] [11669:576430] CHIP: [IN] Secure msg send status ../../third_party/connectedhomeip/src/inet/IPEndPointBasis.cpp:915: Success
[1631906675251] [11669:576430] CHIP: [DMG] ICR moving to [ Sending]
[1631906675264] [11669:576430] CHIP: [EM] Received message of type 0x10 with vendorId 0x0000 and protocolId 0x0000 on exchange 44249
[1631906675264] [11669:576430] CHIP: [EM] Rxd Ack; Removing MsgId:00000000 from Retrans Table
[1631906675264] [11669:576430] CHIP: [EM] Removed CHIP MsgId:00000000 from RetransTable
[1631906675264] [11669:576430] CHIP: [EM] Received message of type 0x09 with vendorId 0x0000 and protocolId 0x0005 on exchange 44250
[1631906675264] [11669:576430] CHIP: [EM] Rxd Ack; Removing MsgId:00000000 from Retrans Table
[1631906675264] [11669:576430] CHIP: [EM] Removed CHIP MsgId:00000000 from RetransTable
[1631906675264] [11669:576430] CHIP: [DMG] InvokeCommand =
[1631906675264] [11669:576430] CHIP: [DMG] {
[1631906675264] [11669:576430] CHIP: [DMG] CommandList =
[1631906675264] [11669:576430] CHIP: [DMG] [
[1631906675264] [11669:576430] CHIP: [DMG] CommandDataElement =
[1631906675264] [11669:576430] CHIP: [DMG] {
[1631906675264] [11669:576430] CHIP: [DMG] CommandPath =
[1631906675264] [11669:576430] CHIP: [DMG] {
[1631906675264] [11669:576430] CHIP: [DMG] EndpointId = 0x1,
[1631906675264] [11669:576430] CHIP: [DMG] ClusterId = 0x102,
[1631906675264] [11669:576430] CHIP: [DMG] CommandId = 0x0,
[1631906675264] [11669:576430] CHIP: [DMG] },
[1631906675264] [11669:576430] CHIP: [DMG]
[1631906675264] [11669:576430] CHIP: [DMG] StatusElement =
[1631906675264] [11669:576430] CHIP: [DMG] {
[1631906675264] [11669:576430] CHIP: [DMG] GeneralCode = 0xd,
[1631906675264] [11669:576430] CHIP: [DMG] ProtocolId = 0x0,
[1631906675264] [11669:576430] CHIP: [DMG] protocolCode = 0x85,
[1631906675264] [11669:576430] CHIP: [DMG] },
[1631906675264] [11669:576430] CHIP: [DMG]
[1631906675264] [11669:576430] CHIP: [DMG] },
[1631906675264] [11669:576430] CHIP: [DMG]
[1631906675264] [11669:576430] CHIP: [DMG] ],
[1631906675264] [11669:576430] CHIP: [DMG]
[1631906675264] [11669:576430] CHIP: [DMG] }
[1631906675264] [11669:576430] CHIP: [ZCL] DefaultResponse:
[1631906675264] [11669:576430] CHIP: [ZCL] Transaction: 0x10bda4f28
[1631906675264] [11669:576430] CHIP: [ZCL] status: EMBER_ZCL_STATUS_FAILURE (0x01)
[1631906675264] [11669:576430] CHIP: [TOO] Default Failure Response: 0x01
[1631906675264] [11669:576430] CHIP: [DMG] ICR moving to [Uninitiali]
[1631906675264] [11669:576430] CHIP: [EM] Sending Standalone Ack for MsgId:00000001
[1631906675264] [11669:576430] CHIP: [IN] Secure message was encrypted: Msg ID 1
[1631906675264] [11669:576426] CHIP: [TOO] Run command failure: ../../commands/clusters/Commands.h:43: CHIP Error 0x000000AC: Internal error
[1631906675264] [11669:576430] CHIP: [IN] Encrypted message 0x70000e032518 to 0xFC526F7FCD1C3D42 of type 16 and protocolId 0 on exchange 44250.
[1631906675264] [11669:576430] CHIP: [IN] Sending msg 0x70000e032518 to 0xFC526F7FCD1C3D42 at utc time: 84898951 msec
[1631906675264] [11669:576430] CHIP: [IN] Sending secure msg on generic transport
[1631906675264] [11669:576430] CHIP: [IN] Secure msg send status ../../third_party/connectedhomeip/src/inet/IPEndPointBasis.cpp:915: Success
[1631906675264] [11669:576430] CHIP: [EM] Flushed pending ack for MsgId:00000001
[1631906675265] [11669:576426] CHIP: [CTL] Shutting down the commissioner
[1631906675265] [11669:576426] CHIP: [CTL] Shutting down the controller
[1631906675266] [11669:576426] CHIP: [DL] Inet Layer shutdown
[1631906675266] [11669:576426] CHIP: [DL] BLE layer shutdown
[1631906675266] [11669:576426] CHIP: [DL] System Layer shutdown
shyam@shyams-MacBook-Pro chip-tool % ./out/debug/chip-tool windowcovering stop-motion 1
[1631906717689] [11673:577146] CHIP: [CTL] Read local id 0x000000000001B669, remote id 0xFC526F7FCD1C3D42
[1631906717691] [11673:577146] CHIP: [CTL] Generating NOC
[1631906717691] [11673:577146] CHIP: [CTL] Generating ICAC
[1631906717691] [11673:577146] CHIP: [DL] _Init
[1631906717691] [11673:577146] CHIP: [IN] UDP::Init bind&listen port=5541
[1631906717692] [11673:577146] CHIP: [IN] UDP::Init bind&listen port=5541
[1631906717692] [11673:577146] CHIP: [IN] TransportMgr initialized
[1631906717692] [11673:577146] CHIP: [DIS] Init fabric pairing table with server storage
[1631906717692] [11673:577146] CHIP: [ZCL] Using ZAP configuration...
[1631906717692] [11673:577146] CHIP: [ZCL] deactivate report event
[1631906717693] [11673:577146] CHIP: [DIS] Verifying the received credentials
[1631906717693] [11673:577146] CHIP: [DIS] Added new fabric at index: 1, Initialized: 1
[1631906717693] [11673:577146] CHIP: [DIS] Assigned fabric ID: 0x0000000000000000, node ID: 0x000000000001B669
[1631906717693] [11673:577146] CHIP: [CTL] Joined the fabric at index 1
[1631906717694] [11673:577151] CHIP: [SC] Sent SigmaR1 msg
[1631906717747] [11673:577153] CHIP: [EM] Received message of type 0x31 with vendorId 0x0000 and protocolId 0x0000 on exchange 44249
[1631906717747] [11673:577153] CHIP: [EM] Rxd Ack; Removing MsgId:00000000 from Retrans Table
[1631906717747] [11673:577153] CHIP: [EM] Removed CHIP MsgId:00000000 from RetransTable
[1631906717747] [11673:577153] CHIP: [SC] Received SigmaR2 msg
[1631906717747] [11673:577153] CHIP: [SC] Peer assigned session key ID 13
[1631906717748] [11673:577153] CHIP: [SC] Sending SigmaR3
[1631906717748] [11673:577153] CHIP: [EM] Piggybacking Ack for MsgId:00000000 with msg
[1631906717748] [11673:577153] CHIP: [SC] Sent SigmaR3 msg
[1631906717748] [11673:577153] CHIP: [IN] New secure session created for device 0xFC526F7FCD1C3D42, key 13!!
[1631906717748] [11673:577153] CHIP: [TOO] Sending cluster (0x0102) command (0x02) on endpoint 1
[1631906717748] [11673:577153] CHIP: [DMG] ICR moving to [Initialize]
[1631906717748] [11673:577153] CHIP: [DMG] ICR moving to [AddCommand]
[1631906717748] [11673:577153] CHIP: [IN] Secure message was encrypted: Msg ID 0
[1631906717748] [11673:577153] CHIP: [IN] Encrypted message 0x7fa9a180e470 to 0xFC526F7FCD1C3D42 of type 8 and protocolId 5 on exchange 44250.
[1631906717748] [11673:577153] CHIP: [IN] Sending msg 0x7fa9a180e470 to 0xFC526F7FCD1C3D42 at utc time: 84941435 msec
[1631906717748] [11673:577153] CHIP: [IN] Sending secure msg on generic transport
[1631906717748] [11673:577153] CHIP: [IN] Secure msg send status ../../third_party/connectedhomeip/src/inet/IPEndPointBasis.cpp:915: Success
[1631906717748] [11673:577153] CHIP: [DMG] ICR moving to [ Sending]
[1631906717766] [11673:577153] CHIP: [EM] Received message of type 0x10 with vendorId 0x0000 and protocolId 0x0000 on exchange 44249
[1631906717766] [11673:577153] CHIP: [EM] Rxd Ack; Removing MsgId:00000000 from Retrans Table
[1631906717766] [11673:577153] CHIP: [EM] Removed CHIP MsgId:00000000 from RetransTable
[1631906717766] [11673:577153] CHIP: [EM] Received message of type 0x09 with vendorId 0x0000 and protocolId 0x0005 on exchange 44250
[1631906717766] [11673:577153] CHIP: [EM] Rxd Ack; Removing MsgId:00000000 from Retrans Table
[1631906717766] [11673:577153] CHIP: [EM] Removed CHIP MsgId:00000000 from RetransTable
[1631906717766] [11673:577153] CHIP: [DMG] InvokeCommand =
[1631906717766] [11673:577153] CHIP: [DMG] {
[1631906717766] [11673:577153] CHIP: [DMG] CommandList =
[1631906717766] [11673:577153] CHIP: [DMG] [
[1631906717766] [11673:577153] CHIP: [DMG] CommandDataElement =
[1631906717766] [11673:577153] CHIP: [DMG] {
[1631906717766] [11673:577153] CHIP: [DMG] CommandPath =
[1631906717766] [11673:577153] CHIP: [DMG] {
[1631906717766] [11673:577153] CHIP: [DMG] EndpointId = 0x1,
[1631906717766] [11673:577153] CHIP: [DMG] ClusterId = 0x102,
[1631906717766] [11673:577153] CHIP: [DMG] CommandId = 0x2,
[1631906717766] [11673:577153] CHIP: [DMG] },
[1631906717766] [11673:577153] CHIP: [DMG]
[1631906717766] [11673:577153] CHIP: [DMG] StatusElement =
[1631906717766] [11673:577153] CHIP: [DMG] {
[1631906717766] [11673:577153] CHIP: [DMG] GeneralCode = 0xd,
[1631906717766] [11673:577153] CHIP: [DMG] ProtocolId = 0x0,
[1631906717766] [11673:577153] CHIP: [DMG] protocolCode = 0x85,
[1631906717766] [11673:577153] CHIP: [DMG] },
[1631906717766] [11673:577153] CHIP: [DMG]
[1631906717766] [11673:577153] CHIP: [DMG] },
[1631906717766] [11673:577153] CHIP: [DMG]
[1631906717766] [11673:577153] CHIP: [DMG] ],
[1631906717766] [11673:577153] CHIP: [DMG]
[1631906717766] [11673:577153] CHIP: [DMG] }
[1631906717766] [11673:577153] CHIP: [ZCL] DefaultResponse:
[1631906717766] [11673:577153] CHIP: [ZCL] Transaction: 0x103429f28
[1631906717766] [11673:577153] CHIP: [ZCL] status: EMBER_ZCL_STATUS_FAILURE (0x01)
[1631906717766] [11673:577153] CHIP: [TOO] Default Failure Response: 0x01
[1631906717766] [11673:577153] CHIP: [DMG] ICR moving to [Uninitiali]
[1631906717766] [11673:577153] CHIP: [EM] Sending Standalone Ack for MsgId:00000001
[1631906717766] [11673:577153] CHIP: [IN] Secure message was encrypted: Msg ID 1
[1631906717766] [11673:577146] CHIP: [TOO] Run command failure: ../../commands/clusters/Commands.h:43: CHIP Error 0x000000AC: Internal error
[1631906717766] [11673:577153] CHIP: [IN] Encrypted message 0x700002ff5518 to 0xFC526F7FCD1C3D42 of type 16 and protocolId 0 on exchange 44250.
[1631906717766] [11673:577153] CHIP: [IN] Sending msg 0x700002ff5518 to 0xFC526F7FCD1C3D42 at utc time: 84941453 msec
[1631906717766] [11673:577153] CHIP: [IN] Sending secure msg on generic transport
[1631906717766] [11673:577153] CHIP: [IN] Secure msg send status ../../third_party/connectedhomeip/src/inet/IPEndPointBasis.cpp:915: Success
[1631906717766] [11673:577153] CHIP: [EM] Flushed pending ack for MsgId:00000001
[1631906717766] [11673:577146] CHIP: [CTL] Shutting down the commissioner
[1631906717766] [11673:577146] CHIP: [CTL] Shutting down the controller
[1631906717768] [11673:577146] CHIP: [DL] Inet Layer shutdown
[1631906717768] [11673:577146] CHIP: [DL] BLE layer shutdown
[1631906717768] [11673:577146] CHIP: [DL] System Layer shutdown
Below three test cases in Window Covering Cluster are giving Internal Error when running with the Mac chip tool,
3.3.1. [TC-WNCV-3.1] UpOrOpen Verification with server as DUT 3.3.2. [TC-WNCV-3.2] DownOrClose Verification with server as DUT 3.3.3. [TC-WNCV-3.3] StopMotion Verification with server as DUT
Tried with different endpoint-id but they were still giving the internal error. Attaching the logs for these test cases.
shyam@shyams-MacBook-Pro chip-tool % ./out/debug/chip-tool windowcovering down-or-close 1 [1631906589008] [11666:575338] CHIP: [CTL] Read local id 0x000000000001B669, remote id 0xFC526F7FCD1C3D42 [1631906589010] [11666:575338] CHIP: [CTL] Generating NOC [1631906589010] [11666:575338] CHIP: [CTL] Generating ICAC [1631906589010] [11666:575338] CHIP: [DL] _Init [1631906589010] [11666:575338] CHIP: [IN] UDP::Init bind&listen port=5541 [1631906589011] [11666:575338] CHIP: [IN] UDP::Init bind&listen port=5541 [1631906589011] [11666:575338] CHIP: [IN] TransportMgr initialized [1631906589011] [11666:575338] CHIP: [DIS] Init fabric pairing table with server storage [1631906589011] [11666:575338] CHIP: [ZCL] Using ZAP configuration... [1631906589011] [11666:575338] CHIP: [ZCL] deactivate report event [1631906589012] [11666:575338] CHIP: [DIS] Verifying the received credentials [1631906589012] [11666:575338] CHIP: [DIS] Added new fabric at index: 1, Initialized: 1 [1631906589012] [11666:575338] CHIP: [DIS] Assigned fabric ID: 0x0000000000000000, node ID: 0x000000000001B669 [1631906589012] [11666:575338] CHIP: [CTL] Joined the fabric at index 1 [1631906589013] [11666:575343] CHIP: [SC] Sent SigmaR1 msg [1631906589129] [11666:575344] CHIP: [EM] Received message of type 0x31 with vendorId 0x0000 and protocolId 0x0000 on exchange 44249 [1631906589129] [11666:575344] CHIP: [EM] Rxd Ack; Removing MsgId:00000000 from Retrans Table [1631906589129] [11666:575344] CHIP: [EM] Removed CHIP MsgId:00000000 from RetransTable [1631906589129] [11666:575344] CHIP: [SC] Received SigmaR2 msg [1631906589129] [11666:575344] CHIP: [SC] Peer assigned session key ID 11 [1631906589130] [11666:575344] CHIP: [SC] Sending SigmaR3 [1631906589130] [11666:575344] CHIP: [EM] Piggybacking Ack for MsgId:00000000 with msg [1631906589130] [11666:575344] CHIP: [SC] Sent SigmaR3 msg [1631906589130] [11666:575344] CHIP: [IN] New secure session created for device 0xFC526F7FCD1C3D42, key 11!! [1631906589130] [11666:575344] CHIP: [TOO] Sending cluster (0x0102) command (0x01) on endpoint 1 [1631906589130] [11666:575344] CHIP: [DMG] ICR moving to [Initialize] [1631906589130] [11666:575344] CHIP: [DMG] ICR moving to [AddCommand] [1631906589130] [11666:575344] CHIP: [IN] Secure message was encrypted: Msg ID 0 [1631906589130] [11666:575344] CHIP: [IN] Encrypted message 0x7fde38008270 to 0xFC526F7FCD1C3D42 of type 8 and protocolId 5 on exchange 44250. [1631906589130] [11666:575344] CHIP: [IN] Sending msg 0x7fde38008270 to 0xFC526F7FCD1C3D42 at utc time: 84812817 msec [1631906589131] [11666:575344] CHIP: [IN] Sending secure msg on generic transport [1631906589131] [11666:575344] CHIP: [IN] Secure msg send status ../../third_party/connectedhomeip/src/inet/IPEndPointBasis.cpp:915: Success [1631906589131] [11666:575344] CHIP: [DMG] ICR moving to [ Sending] [1631906589139] [11666:575344] CHIP: [EM] Received message of type 0x10 with vendorId 0x0000 and protocolId 0x0000 on exchange 44249 [1631906589139] [11666:575344] CHIP: [EM] Rxd Ack; Removing MsgId:00000000 from Retrans Table [1631906589139] [11666:575344] CHIP: [EM] Removed CHIP MsgId:00000000 from RetransTable [1631906589143] [11666:575344] CHIP: [EM] Received message of type 0x09 with vendorId 0x0000 and protocolId 0x0005 on exchange 44250 [1631906589143] [11666:575344] CHIP: [EM] Rxd Ack; Removing MsgId:00000000 from Retrans Table [1631906589143] [11666:575344] CHIP: [EM] Removed CHIP MsgId:00000000 from RetransTable [1631906589143] [11666:575344] CHIP: [DMG] InvokeCommand = [1631906589143] [11666:575344] CHIP: [DMG] { [1631906589143] [11666:575344] CHIP: [DMG] CommandList = [1631906589143] [11666:575344] CHIP: [DMG] [ [1631906589143] [11666:575344] CHIP: [DMG] CommandDataElement = [1631906589143] [11666:575344] CHIP: [DMG] { [1631906589143] [11666:575344] CHIP: [DMG] CommandPath = [1631906589143] [11666:575344] CHIP: [DMG] { [1631906589143] [11666:575344] CHIP: [DMG] EndpointId = 0x1, [1631906589143] [11666:575344] CHIP: [DMG] ClusterId = 0x102, [1631906589143] [11666:575344] CHIP: [DMG] CommandId = 0x1, [1631906589143] [11666:575344] CHIP: [DMG] }, [1631906589143] [11666:575344] CHIP: [DMG]
[1631906589143] [11666:575344] CHIP: [DMG] StatusElement = [1631906589143] [11666:575344] CHIP: [DMG] { [1631906589143] [11666:575344] CHIP: [DMG] GeneralCode = 0xd, [1631906589143] [11666:575344] CHIP: [DMG] ProtocolId = 0x0, [1631906589143] [11666:575344] CHIP: [DMG] protocolCode = 0x85, [1631906589143] [11666:575344] CHIP: [DMG] }, [1631906589143] [11666:575344] CHIP: [DMG]
[1631906589143] [11666:575344] CHIP: [DMG] }, [1631906589143] [11666:575344] CHIP: [DMG]
[1631906589143] [11666:575344] CHIP: [DMG] ], [1631906589143] [11666:575344] CHIP: [DMG]
[1631906589143] [11666:575344] CHIP: [DMG] } [1631906589143] [11666:575344] CHIP: [ZCL] DefaultResponse: [1631906589144] [11666:575344] CHIP: [ZCL] Transaction: 0x10ffeef28 [1631906589144] [11666:575344] CHIP: [ZCL] status: EMBER_ZCL_STATUS_FAILURE (0x01) [1631906589144] [11666:575344] CHIP: [TOO] Default Failure Response: 0x01 [1631906589144] [11666:575344] CHIP: [DMG] ICR moving to [Uninitiali] [1631906589144] [11666:575344] CHIP: [EM] Sending Standalone Ack for MsgId:00000001 [1631906589144] [11666:575344] CHIP: [IN] Secure message was encrypted: Msg ID 1 [1631906589144] [11666:575338] CHIP: [TOO] Run command failure: ../../commands/clusters/Commands.h:43: CHIP Error 0x000000AC: Internal error [1631906589144] [11666:575344] CHIP: [IN] Encrypted message 0x700008fb1518 to 0xFC526F7FCD1C3D42 of type 16 and protocolId 0 on exchange 44250. [1631906589144] [11666:575344] CHIP: [IN] Sending msg 0x700008fb1518 to 0xFC526F7FCD1C3D42 at utc time: 84812830 msec [1631906589144] [11666:575344] CHIP: [IN] Sending secure msg on generic transport [1631906589144] [11666:575344] CHIP: [IN] Secure msg send status ../../third_party/connectedhomeip/src/inet/IPEndPointBasis.cpp:915: Success [1631906589144] [11666:575344] CHIP: [EM] Flushed pending ack for MsgId:00000001 [1631906589144] [11666:575338] CHIP: [CTL] Shutting down the commissioner [1631906589144] [11666:575338] CHIP: [CTL] Shutting down the controller [1631906589145] [11666:575338] CHIP: [DL] Inet Layer shutdown [1631906589145] [11666:575338] CHIP: [DL] BLE layer shutdown [1631906589145] [11666:575338] CHIP: [DL] System Layer shutdown
shyam@shyams-MacBook-Pro chip-tool % ./out/debug/chip-tool windowcovering up-or-open 1
[1631906675197] [11669:576426] CHIP: [CTL] Read local id 0x000000000001B669, remote id 0xFC526F7FCD1C3D42 [1631906675200] [11669:576426] CHIP: [CTL] Generating NOC [1631906675200] [11669:576426] CHIP: [CTL] Generating ICAC [1631906675200] [11669:576426] CHIP: [DL] _Init [1631906675200] [11669:576426] CHIP: [IN] UDP::Init bind&listen port=5541 [1631906675200] [11669:576426] CHIP: [IN] UDP::Init bind&listen port=5541 [1631906675200] [11669:576426] CHIP: [IN] TransportMgr initialized [1631906675200] [11669:576426] CHIP: [DIS] Init fabric pairing table with server storage [1631906675201] [11669:576426] CHIP: [ZCL] Using ZAP configuration... [1631906675201] [11669:576426] CHIP: [ZCL] deactivate report event [1631906675202] [11669:576426] CHIP: [DIS] Verifying the received credentials [1631906675202] [11669:576426] CHIP: [DIS] Added new fabric at index: 1, Initialized: 1 [1631906675202] [11669:576426] CHIP: [DIS] Assigned fabric ID: 0x0000000000000000, node ID: 0x000000000001B669 [1631906675202] [11669:576426] CHIP: [CTL] Joined the fabric at index 1 [1631906675203] [11669:576430] CHIP: [SC] Sent SigmaR1 msg [1631906675249] [11669:576430] CHIP: [EM] Received message of type 0x31 with vendorId 0x0000 and protocolId 0x0000 on exchange 44249 [1631906675249] [11669:576430] CHIP: [EM] Rxd Ack; Removing MsgId:00000000 from Retrans Table [1631906675249] [11669:576430] CHIP: [EM] Removed CHIP MsgId:00000000 from RetransTable [1631906675249] [11669:576430] CHIP: [SC] Received SigmaR2 msg [1631906675249] [11669:576430] CHIP: [SC] Peer assigned session key ID 12 [1631906675250] [11669:576430] CHIP: [SC] Sending SigmaR3 [1631906675250] [11669:576430] CHIP: [EM] Piggybacking Ack for MsgId:00000000 with msg [1631906675250] [11669:576430] CHIP: [SC] Sent SigmaR3 msg [1631906675250] [11669:576430] CHIP: [IN] New secure session created for device 0xFC526F7FCD1C3D42, key 12!! [1631906675250] [11669:576430] CHIP: [TOO] Sending cluster (0x0102) command (0x00) on endpoint 1 [1631906675250] [11669:576430] CHIP: [DMG] ICR moving to [Initialize] [1631906675250] [11669:576430] CHIP: [DMG] ICR moving to [AddCommand] [1631906675251] [11669:576430] CHIP: [IN] Secure message was encrypted: Msg ID 0 [1631906675251] [11669:576430] CHIP: [IN] Encrypted message 0x7fc7a700e470 to 0xFC526F7FCD1C3D42 of type 8 and protocolId 5 on exchange 44250. [1631906675251] [11669:576430] CHIP: [IN] Sending msg 0x7fc7a700e470 to 0xFC526F7FCD1C3D42 at utc time: 84898937 msec [1631906675251] [11669:576430] CHIP: [IN] Sending secure msg on generic transport [1631906675251] [11669:576430] CHIP: [IN] Secure msg send status ../../third_party/connectedhomeip/src/inet/IPEndPointBasis.cpp:915: Success [1631906675251] [11669:576430] CHIP: [DMG] ICR moving to [ Sending] [1631906675264] [11669:576430] CHIP: [EM] Received message of type 0x10 with vendorId 0x0000 and protocolId 0x0000 on exchange 44249 [1631906675264] [11669:576430] CHIP: [EM] Rxd Ack; Removing MsgId:00000000 from Retrans Table [1631906675264] [11669:576430] CHIP: [EM] Removed CHIP MsgId:00000000 from RetransTable [1631906675264] [11669:576430] CHIP: [EM] Received message of type 0x09 with vendorId 0x0000 and protocolId 0x0005 on exchange 44250 [1631906675264] [11669:576430] CHIP: [EM] Rxd Ack; Removing MsgId:00000000 from Retrans Table [1631906675264] [11669:576430] CHIP: [EM] Removed CHIP MsgId:00000000 from RetransTable [1631906675264] [11669:576430] CHIP: [DMG] InvokeCommand = [1631906675264] [11669:576430] CHIP: [DMG] { [1631906675264] [11669:576430] CHIP: [DMG] CommandList = [1631906675264] [11669:576430] CHIP: [DMG] [ [1631906675264] [11669:576430] CHIP: [DMG] CommandDataElement = [1631906675264] [11669:576430] CHIP: [DMG] { [1631906675264] [11669:576430] CHIP: [DMG] CommandPath = [1631906675264] [11669:576430] CHIP: [DMG] { [1631906675264] [11669:576430] CHIP: [DMG] EndpointId = 0x1, [1631906675264] [11669:576430] CHIP: [DMG] ClusterId = 0x102, [1631906675264] [11669:576430] CHIP: [DMG] CommandId = 0x0, [1631906675264] [11669:576430] CHIP: [DMG] }, [1631906675264] [11669:576430] CHIP: [DMG]
[1631906675264] [11669:576430] CHIP: [DMG] StatusElement = [1631906675264] [11669:576430] CHIP: [DMG] { [1631906675264] [11669:576430] CHIP: [DMG] GeneralCode = 0xd, [1631906675264] [11669:576430] CHIP: [DMG] ProtocolId = 0x0, [1631906675264] [11669:576430] CHIP: [DMG] protocolCode = 0x85, [1631906675264] [11669:576430] CHIP: [DMG] }, [1631906675264] [11669:576430] CHIP: [DMG]
[1631906675264] [11669:576430] CHIP: [DMG] }, [1631906675264] [11669:576430] CHIP: [DMG]
[1631906675264] [11669:576430] CHIP: [DMG] ], [1631906675264] [11669:576430] CHIP: [DMG]
[1631906675264] [11669:576430] CHIP: [DMG] } [1631906675264] [11669:576430] CHIP: [ZCL] DefaultResponse: [1631906675264] [11669:576430] CHIP: [ZCL] Transaction: 0x10bda4f28 [1631906675264] [11669:576430] CHIP: [ZCL] status: EMBER_ZCL_STATUS_FAILURE (0x01) [1631906675264] [11669:576430] CHIP: [TOO] Default Failure Response: 0x01 [1631906675264] [11669:576430] CHIP: [DMG] ICR moving to [Uninitiali] [1631906675264] [11669:576430] CHIP: [EM] Sending Standalone Ack for MsgId:00000001 [1631906675264] [11669:576430] CHIP: [IN] Secure message was encrypted: Msg ID 1 [1631906675264] [11669:576426] CHIP: [TOO] Run command failure: ../../commands/clusters/Commands.h:43: CHIP Error 0x000000AC: Internal error [1631906675264] [11669:576430] CHIP: [IN] Encrypted message 0x70000e032518 to 0xFC526F7FCD1C3D42 of type 16 and protocolId 0 on exchange 44250. [1631906675264] [11669:576430] CHIP: [IN] Sending msg 0x70000e032518 to 0xFC526F7FCD1C3D42 at utc time: 84898951 msec [1631906675264] [11669:576430] CHIP: [IN] Sending secure msg on generic transport [1631906675264] [11669:576430] CHIP: [IN] Secure msg send status ../../third_party/connectedhomeip/src/inet/IPEndPointBasis.cpp:915: Success [1631906675264] [11669:576430] CHIP: [EM] Flushed pending ack for MsgId:00000001 [1631906675265] [11669:576426] CHIP: [CTL] Shutting down the commissioner [1631906675265] [11669:576426] CHIP: [CTL] Shutting down the controller [1631906675266] [11669:576426] CHIP: [DL] Inet Layer shutdown [1631906675266] [11669:576426] CHIP: [DL] BLE layer shutdown [1631906675266] [11669:576426] CHIP: [DL] System Layer shutdown
shyam@shyams-MacBook-Pro chip-tool % ./out/debug/chip-tool windowcovering stop-motion 1
[1631906717689] [11673:577146] CHIP: [CTL] Read local id 0x000000000001B669, remote id 0xFC526F7FCD1C3D42 [1631906717691] [11673:577146] CHIP: [CTL] Generating NOC [1631906717691] [11673:577146] CHIP: [CTL] Generating ICAC [1631906717691] [11673:577146] CHIP: [DL] _Init [1631906717691] [11673:577146] CHIP: [IN] UDP::Init bind&listen port=5541 [1631906717692] [11673:577146] CHIP: [IN] UDP::Init bind&listen port=5541 [1631906717692] [11673:577146] CHIP: [IN] TransportMgr initialized [1631906717692] [11673:577146] CHIP: [DIS] Init fabric pairing table with server storage [1631906717692] [11673:577146] CHIP: [ZCL] Using ZAP configuration... [1631906717692] [11673:577146] CHIP: [ZCL] deactivate report event [1631906717693] [11673:577146] CHIP: [DIS] Verifying the received credentials [1631906717693] [11673:577146] CHIP: [DIS] Added new fabric at index: 1, Initialized: 1 [1631906717693] [11673:577146] CHIP: [DIS] Assigned fabric ID: 0x0000000000000000, node ID: 0x000000000001B669 [1631906717693] [11673:577146] CHIP: [CTL] Joined the fabric at index 1 [1631906717694] [11673:577151] CHIP: [SC] Sent SigmaR1 msg [1631906717747] [11673:577153] CHIP: [EM] Received message of type 0x31 with vendorId 0x0000 and protocolId 0x0000 on exchange 44249 [1631906717747] [11673:577153] CHIP: [EM] Rxd Ack; Removing MsgId:00000000 from Retrans Table [1631906717747] [11673:577153] CHIP: [EM] Removed CHIP MsgId:00000000 from RetransTable [1631906717747] [11673:577153] CHIP: [SC] Received SigmaR2 msg [1631906717747] [11673:577153] CHIP: [SC] Peer assigned session key ID 13 [1631906717748] [11673:577153] CHIP: [SC] Sending SigmaR3 [1631906717748] [11673:577153] CHIP: [EM] Piggybacking Ack for MsgId:00000000 with msg [1631906717748] [11673:577153] CHIP: [SC] Sent SigmaR3 msg [1631906717748] [11673:577153] CHIP: [IN] New secure session created for device 0xFC526F7FCD1C3D42, key 13!! [1631906717748] [11673:577153] CHIP: [TOO] Sending cluster (0x0102) command (0x02) on endpoint 1 [1631906717748] [11673:577153] CHIP: [DMG] ICR moving to [Initialize] [1631906717748] [11673:577153] CHIP: [DMG] ICR moving to [AddCommand] [1631906717748] [11673:577153] CHIP: [IN] Secure message was encrypted: Msg ID 0 [1631906717748] [11673:577153] CHIP: [IN] Encrypted message 0x7fa9a180e470 to 0xFC526F7FCD1C3D42 of type 8 and protocolId 5 on exchange 44250. [1631906717748] [11673:577153] CHIP: [IN] Sending msg 0x7fa9a180e470 to 0xFC526F7FCD1C3D42 at utc time: 84941435 msec [1631906717748] [11673:577153] CHIP: [IN] Sending secure msg on generic transport [1631906717748] [11673:577153] CHIP: [IN] Secure msg send status ../../third_party/connectedhomeip/src/inet/IPEndPointBasis.cpp:915: Success [1631906717748] [11673:577153] CHIP: [DMG] ICR moving to [ Sending] [1631906717766] [11673:577153] CHIP: [EM] Received message of type 0x10 with vendorId 0x0000 and protocolId 0x0000 on exchange 44249 [1631906717766] [11673:577153] CHIP: [EM] Rxd Ack; Removing MsgId:00000000 from Retrans Table [1631906717766] [11673:577153] CHIP: [EM] Removed CHIP MsgId:00000000 from RetransTable [1631906717766] [11673:577153] CHIP: [EM] Received message of type 0x09 with vendorId 0x0000 and protocolId 0x0005 on exchange 44250 [1631906717766] [11673:577153] CHIP: [EM] Rxd Ack; Removing MsgId:00000000 from Retrans Table [1631906717766] [11673:577153] CHIP: [EM] Removed CHIP MsgId:00000000 from RetransTable [1631906717766] [11673:577153] CHIP: [DMG] InvokeCommand = [1631906717766] [11673:577153] CHIP: [DMG] { [1631906717766] [11673:577153] CHIP: [DMG] CommandList = [1631906717766] [11673:577153] CHIP: [DMG] [ [1631906717766] [11673:577153] CHIP: [DMG] CommandDataElement = [1631906717766] [11673:577153] CHIP: [DMG] { [1631906717766] [11673:577153] CHIP: [DMG] CommandPath = [1631906717766] [11673:577153] CHIP: [DMG] { [1631906717766] [11673:577153] CHIP: [DMG] EndpointId = 0x1, [1631906717766] [11673:577153] CHIP: [DMG] ClusterId = 0x102, [1631906717766] [11673:577153] CHIP: [DMG] CommandId = 0x2, [1631906717766] [11673:577153] CHIP: [DMG] }, [1631906717766] [11673:577153] CHIP: [DMG]
[1631906717766] [11673:577153] CHIP: [DMG] StatusElement = [1631906717766] [11673:577153] CHIP: [DMG] { [1631906717766] [11673:577153] CHIP: [DMG] GeneralCode = 0xd, [1631906717766] [11673:577153] CHIP: [DMG] ProtocolId = 0x0, [1631906717766] [11673:577153] CHIP: [DMG] protocolCode = 0x85, [1631906717766] [11673:577153] CHIP: [DMG] }, [1631906717766] [11673:577153] CHIP: [DMG]
[1631906717766] [11673:577153] CHIP: [DMG] }, [1631906717766] [11673:577153] CHIP: [DMG]
[1631906717766] [11673:577153] CHIP: [DMG] ], [1631906717766] [11673:577153] CHIP: [DMG]
[1631906717766] [11673:577153] CHIP: [DMG] } [1631906717766] [11673:577153] CHIP: [ZCL] DefaultResponse: [1631906717766] [11673:577153] CHIP: [ZCL] Transaction: 0x103429f28 [1631906717766] [11673:577153] CHIP: [ZCL] status: EMBER_ZCL_STATUS_FAILURE (0x01) [1631906717766] [11673:577153] CHIP: [TOO] Default Failure Response: 0x01 [1631906717766] [11673:577153] CHIP: [DMG] ICR moving to [Uninitiali] [1631906717766] [11673:577153] CHIP: [EM] Sending Standalone Ack for MsgId:00000001 [1631906717766] [11673:577153] CHIP: [IN] Secure message was encrypted: Msg ID 1 [1631906717766] [11673:577146] CHIP: [TOO] Run command failure: ../../commands/clusters/Commands.h:43: CHIP Error 0x000000AC: Internal error [1631906717766] [11673:577153] CHIP: [IN] Encrypted message 0x700002ff5518 to 0xFC526F7FCD1C3D42 of type 16 and protocolId 0 on exchange 44250. [1631906717766] [11673:577153] CHIP: [IN] Sending msg 0x700002ff5518 to 0xFC526F7FCD1C3D42 at utc time: 84941453 msec [1631906717766] [11673:577153] CHIP: [IN] Sending secure msg on generic transport [1631906717766] [11673:577153] CHIP: [IN] Secure msg send status ../../third_party/connectedhomeip/src/inet/IPEndPointBasis.cpp:915: Success [1631906717766] [11673:577153] CHIP: [EM] Flushed pending ack for MsgId:00000001 [1631906717766] [11673:577146] CHIP: [CTL] Shutting down the commissioner [1631906717766] [11673:577146] CHIP: [CTL] Shutting down the controller [1631906717768] [11673:577146] CHIP: [DL] Inet Layer shutdown [1631906717768] [11673:577146] CHIP: [DL] BLE layer shutdown [1631906717768] [11673:577146] CHIP: [DL] System Layer shutdown