• State Not Answered
  • Replies 11 replies
  • Subscribers 75 subscribers
  • Views 4630 views
  • Users 0 members are here
Attachments (0)
Nordic Case Info
  • Case ID: 286057
Options

Error when running the Python CHIP controller

Carla31

I'm actually trying to run the Python CHIP controller in my PC but I got some errors.

I've successfully set up the Open Thread Border Router in a Raspberry PI and I'm using the Light Bulb Matter example flashed in a nRF5340 DK which is my Matter accessory. 

I followed the tutorial https://developer.nordicsemi.com/nRF_Connect_SDK/doc/1.9.1/matter/python_chip_controller_building.html#using-python-chip-controller-for-matter-accessory-testing to create my Matter controller using the tool Python CHIP controller.

I used all the required commands here below for installation and building the Python CHIP controller.

Then I tried to run the tool as shown here below :

I successfully activated the Python virtual environment. Then I tried to run the Python CHIP controller using "sudo out/python_env/bin/chip-device-ctrl " but I got the following output :

:~/connectedhomeip$ sudo out/python_env/bin/chip-device-ctrl 
[1647525260.928139][48172:48172] CHIP:CTL: Setting attestation nonce to random value
[1647525260.928241][48172:48172] CHIP:CTL: Setting CSR nonce to random value
[1647525260.930233][48172:48172] CHIP:DL: writing settings to file (/tmp/chip_counters.ini-hoBbXJ)
[1647525260.930450][48172:48172] CHIP:DL: renamed tmp file to file (/tmp/chip_counters.ini)
[1647525260.930478][48172:48172] CHIP:DL: NVS set: chip-counters/reboot-count = 6 (0x6)
[1647525260.930711][48172:48172] CHIP:DL: Got Ethernet interface: enp0s31f6
[1647525260.930868][48172:48172] CHIP:DL: Found the primary Ethernet interface:enp0s31f6
[1647525260.931027][48172:48172] CHIP:DL: Got WiFi interface: wlp1s0
[1647525260.931673][48172:48172] CHIP:DL: Found the primary WiFi interface:wlp1s0
[1647525260.931786][48172:48186] CHIP:DL: Platform main loop started.
2022-03-17 14:54:20 IMP-IT0087lx root[48172] CRITICAL Loading configuration from /tmp/chip-device-ctrl-storage.json...
2022-03-17 14:54:20 IMP-IT0087lx chip.IN[48172] INFO Loading from storage for fabric index 1
2022-03-17 14:54:20 IMP-IT0087lx chip.IN[48172] INFO Loading from storage for fabric index 2
2022-03-17 14:54:20 IMP-IT0087lx chip.IN[48172] INFO Loading from storage for fabric index 3
2022-03-17 14:54:20 IMP-IT0087lx chip.IN[48172] INFO Loading from storage for fabric index 4
2022-03-17 14:54:20 IMP-IT0087lx chip.IN[48172] INFO Loading from storage for fabric index 5
2022-03-17 14:54:20 IMP-IT0087lx chip.IN[48172] INFO Loading from storage for fabric index 6
2022-03-17 14:54:20 IMP-IT0087lx chip.IN[48172] INFO Loading from storage for fabric index 7
2022-03-17 14:54:20 IMP-IT0087lx chip.IN[48172] INFO Loading from storage for fabric index 8
2022-03-17 14:54:20 IMP-IT0087lx chip.IN[48172] INFO Loading from storage for fabric index 9
2022-03-17 14:54:20 IMP-IT0087lx chip.IN[48172] INFO Loading from storage for fabric index 10
2022-03-17 14:54:20 IMP-IT0087lx chip.IN[48172] INFO Loading from storage for fabric index 11
2022-03-17 14:54:20 IMP-IT0087lx chip.IN[48172] INFO Loading from storage for fabric index 12
2022-03-17 14:54:20 IMP-IT0087lx chip.IN[48172] INFO Loading from storage for fabric index 13
2022-03-17 14:54:20 IMP-IT0087lx chip.IN[48172] INFO Loading from storage for fabric index 14
2022-03-17 14:54:20 IMP-IT0087lx chip.IN[48172] INFO Loading from storage for fabric index 15
2022-03-17 14:54:20 IMP-IT0087lx chip.IN[48172] INFO Loading from storage for fabric index 16
2022-03-17 14:54:20 IMP-IT0087lx chip.DL[48172] INFO writing settings to file (/tmp/chip_counters.ini-23Pp4J)
2022-03-17 14:54:20 IMP-IT0087lx chip.DL[48172] ERROR renamed tmp file to file (/tmp/chip_counters.ini)
2022-03-17 14:54:20 IMP-IT0087lx chip.DL[48172] INFO NVS set: chip-counters/GlobalMCTR = 6000 (0x1770)
2022-03-17 14:54:20 IMP-IT0087lx root[48172] INFO SetSdkKey: gcc = b'\xd0\x07\x00\x00'
2022-03-17 14:54:20 IMP-IT0087lx root[48172] INFO SetSdkKey: gdc = b'\xd0\x07\x00\x00'
2022-03-17 14:54:20 IMP-IT0087lx chip.ZCL[48172] INFO Using ZAP configuration...
2022-03-17 14:54:20 IMP-IT0087lx chip.DL[48172] INFO Avahi connecting
2022-03-17 14:54:20 IMP-IT0087lx chip.IN[48172] INFO CASE Server enabling CASE session setups
2022-03-17 14:54:20 IMP-IT0087lx chip.DIS[48172] ERROR Failed to initialize advertiser: ../../src/platform/Linux/DnssdImpl.cpp:333: CHIP Error 0x00000003: Incorrect state
2022-03-17 14:54:20 IMP-IT0087lx chip.DIS[48172] INFO Failed to find a valid admin pairing. Node ID unknown
2022-03-17 14:54:20 IMP-IT0087lx chip.DIS[48172] INFO Start dns-sd server - no current nodeId
2022-03-17 14:54:20 IMP-IT0087lx chip.DIS[48172] INFO Advertise commission parameter vendorID=65521 productID=32769 discriminator=3840/15
2022-03-17 14:54:20 IMP-IT0087lx chip.DIS[48172] ERROR Failed to advertise unprovisioned commissionable node: ../../src/lib/dnssd/Discovery_ImplPlatform.cpp:473: CHIP Error 0x00000003: Incorrect state
2022-03-17 14:54:20 IMP-IT0087lx chip.DIS[48172] INFO Advertise commission parameter vendorID=65521 productID=32769 discriminator=3840/15
2022-03-17 14:54:20 IMP-IT0087lx chip.DIS[48172] ERROR Failed to advertise commissioner: ../../src/lib/dnssd/Discovery_ImplPlatform.cpp:473: CHIP Error 0x00000003: Incorrect state
2022-03-17 14:54:21 IMP-IT0087lx chip.DL[48172] ERROR Avahi client failure
2022-03-17 14:54:21 IMP-IT0087lx chip.DIS[48172] ERROR DNS-SD error: ../../src/platform/Linux/DnssdImpl.cpp:405: CHIP Error 0x000000AC: Internal error
New FabricAdmin: FabricId: 1(1)
2022-03-17 14:54:21 IMP-IT0087lx root[48172] INFO SetReplKey: fabricAdmins = {'1': {'fabricId': 1}}
Allocating new controller with FabricId: 1(1), NodeId: 1
2022-03-17 14:54:21 IMP-IT0087lx chip.CTL[48172] INFO Setting attestation nonce to random value
2022-03-17 14:54:21 IMP-IT0087lx chip.CTL[48172] INFO Setting CSR nonce to random value
2022-03-17 14:54:21 IMP-IT0087lx chip.CTL[48172] INFO Generating NOC
CHIP Error 0x00000003: Incorrect state
Failed to bringup CHIPDeviceController CLI
Exception ignored in: <function ChipDeviceController.__del__ at 0x7f75f07d4040>
Traceback (most recent call last):
  File "/home/czena/connectedhomeip/out/python_env/lib/python3.8/site-packages/chip/ChipDeviceCtrl.py", line 186, in __del__
    self.Shutdown()
  File "/home/czena/connectedhomeip/out/python_env/lib/python3.8/site-packages/chip/ChipDeviceCtrl.py", line 150, in Shutdown
    if (self.isActive):
AttributeError: 'ChipDeviceController' object has no attribute 'isActive'
Segmentation fault

I would like to know why I got a segmentation fault. Could you help me please ?

I'm using OS Ubuntu 20.04.4 LTS and nRF Connect SDK v1.9.1

  • 0 in reply to HeneryH

    Thanks for you answer. Now I can see the python_env folder.

    Now I tried to run the Python CHIP controller but with no success. I got the following output :

    connectedhomeip$ source out/python_env/bin/activate
(python_env) czena@IMP-IT0087lx:~/Documents/Others/Matter/connectedhomeip$ sudo out/python_env/bin/chip-device-ctrl 
[sudo] password for czena: 
[1649165043.900006][98518:98518] CHIP:CTL: Setting attestation nonce to random value
[1649165043.900108][98518:98518] CHIP:CTL: Setting CSR nonce to random value
[1649165043.901938][98518:98518] CHIP:DL: writing settings to file (/tmp/chip_counters.ini-CWgGZG)
[1649165043.902132][98518:98518] CHIP:DL: renamed tmp file to file (/tmp/chip_counters.ini)
[1649165043.902143][98518:98518] CHIP:DL: NVS set: chip-counters/reboot-count = 4 (0x4)
[1649165043.902389][98518:98518] CHIP:DL: Got Ethernet interface: enp0s31f6
[1649165043.902574][98518:98518] CHIP:DL: Found the primary Ethernet interface:enp0s31f6
[1649165043.902761][98518:98518] CHIP:DL: Got WiFi interface: wlp1s0
[1649165043.904488][98518:98518] CHIP:DL: Found the primary WiFi interface:wlp1s0
[1649165043.904616][98518:98526] CHIP:DL: Platform main loop started.
2022-04-05 15:24:03 IMP-IT0087lx root[98518] CRITICAL Loading configuration from /tmp/chip-device-ctrl-storage.json...
2022-04-05 15:24:03 IMP-IT0087lx chip.DL[98518] INFO writing settings to file (/tmp/chip_counters.ini-OzNelH)
2022-04-05 15:24:03 IMP-IT0087lx chip.DL[98518] ERROR renamed tmp file to file (/tmp/chip_counters.ini)
2022-04-05 15:24:03 IMP-IT0087lx chip.DL[98518] INFO NVS set: chip-counters/GlobalMCTR = 4000 (0xFA0)
2022-04-05 15:24:03 IMP-IT0087lx root[98518] INFO SetSdkKey: g/gcc = b'\xd0\x07\x00\x00'
Exception ignored on calling ctypes callback function: <function _OnSyncSetKeyValueCb at 0x7f6993ecf550>
Traceback (most recent call last):
  File "/home/czena/Documents/Others/Matter/connectedhomeip/out/python_env/lib/python3.8/site-packages/chip/storage/__init__.py", line 44, in _OnSyncSetKeyValueCb
    storageObj.SetSdkKey(key.decode("utf-8"), ctypes.string_at(value, size))
  File "/home/czena/Documents/Others/Matter/connectedhomeip/out/python_env/lib/python3.8/site-packages/chip/storage/__init__.py", line 147, in SetSdkKey
    self.Sync()
  File "/home/czena/Documents/Others/Matter/connectedhomeip/out/python_env/lib/python3.8/site-packages/chip/storage/__init__.py", line 111, in Sync
    f"Could not open {path} for writing configuration. Error:")
NameError: name 'path' is not defined
2022-04-05 15:24:03 IMP-IT0087lx root[98518] INFO SetSdkKey: g/gdc = b'\xd0\x07\x00\x00'
Exception ignored on calling ctypes callback function: <function _OnSyncSetKeyValueCb at 0x7f6993ecf550>
Traceback (most recent call last):
  File "/home/czena/Documents/Others/Matter/connectedhomeip/out/python_env/lib/python3.8/site-packages/chip/storage/__init__.py", line 44, in _OnSyncSetKeyValueCb
    storageObj.SetSdkKey(key.decode("utf-8"), ctypes.string_at(value, size))
  File "/home/czena/Documents/Others/Matter/connectedhomeip/out/python_env/lib/python3.8/site-packages/chip/storage/__init__.py", line 147, in SetSdkKey
    self.Sync()
  File "/home/czena/Documents/Others/Matter/connectedhomeip/out/python_env/lib/python3.8/site-packages/chip/storage/__init__.py", line 111, in Sync
    f"Could not open {path} for writing configuration. Error:")
NameError: name 'path' is not defined
2022-04-05 15:24:03 IMP-IT0087lx chip.ZCL[98518] INFO Using ZAP configuration...
Segmentation fault
(python_env) czena@IMP-IT0087lx:~/Documents/Others/Matter/connectedhomeip$ sudo out/python_env/bin/chip-device-ctrl 
[1649165195.463120][99597:99597] CHIP:CTL: Setting attestation nonce to random value
[1649165195.463219][99597:99597] CHIP:CTL: Setting CSR nonce to random value
[1649165195.467032][99597:99597] CHIP:DL: writing settings to file (/tmp/chip_counters.ini-E3TpNe)
[1649165195.467209][99597:99597] CHIP:DL: renamed tmp file to file (/tmp/chip_counters.ini)
[1649165195.467237][99597:99597] CHIP:DL: NVS set: chip-counters/reboot-count = 5 (0x5)
[1649165195.467456][99597:99597] CHIP:DL: Got Ethernet interface: enp0s31f6
[1649165195.467614][99597:99597] CHIP:DL: Found the primary Ethernet interface:enp0s31f6
[1649165195.467783][99597:99597] CHIP:DL: Got WiFi interface: wlp1s0
[1649165195.469345][99597:99597] CHIP:DL: Found the primary WiFi interface:wlp1s0
[1649165195.469478][99597:99611] CHIP:DL: Platform main loop started.
2022-04-05 15:26:35 IMP-IT0087lx root[99597] CRITICAL Loading configuration from /tmp/chip-device-ctrl-storage.json...
2022-04-05 15:26:35 IMP-IT0087lx chip.DL[99597] INFO writing settings to file (/tmp/chip_counters.ini-1CfIUf)
2022-04-05 15:26:35 IMP-IT0087lx chip.DL[99597] ERROR renamed tmp file to file (/tmp/chip_counters.ini)
2022-04-05 15:26:35 IMP-IT0087lx chip.DL[99597] INFO NVS set: chip-counters/GlobalMCTR = 5000 (0x1388)
2022-04-05 15:26:35 IMP-IT0087lx root[99597] INFO SetSdkKey: g/gcc = b'\xd0\x07\x00\x00'
Exception ignored on calling ctypes callback function: <function _OnSyncSetKeyValueCb at 0x7fa28f70e550>
Traceback (most recent call last):
  File "/home/czena/Documents/Others/Matter/connectedhomeip/out/python_env/lib/python3.8/site-packages/chip/storage/__init__.py", line 44, in _OnSyncSetKeyValueCb
    storageObj.SetSdkKey(key.decode("utf-8"), ctypes.string_at(value, size))
  File "/home/czena/Documents/Others/Matter/connectedhomeip/out/python_env/lib/python3.8/site-packages/chip/storage/__init__.py", line 147, in SetSdkKey
    self.Sync()
  File "/home/czena/Documents/Others/Matter/connectedhomeip/out/python_env/lib/python3.8/site-packages/chip/storage/__init__.py", line 111, in Sync
    f"Could not open {path} for writing configuration. Error:")
NameError: name 'path' is not defined
2022-04-05 15:26:35 IMP-IT0087lx root[99597] INFO SetSdkKey: g/gdc = b'\xd0\x07\x00\x00'
Exception ignored on calling ctypes callback function: <function _OnSyncSetKeyValueCb at 0x7fa28f70e550>
Traceback (most recent call last):
  File "/home/czena/Documents/Others/Matter/connectedhomeip/out/python_env/lib/python3.8/site-packages/chip/storage/__init__.py", line 44, in _OnSyncSetKeyValueCb
    storageObj.SetSdkKey(key.decode("utf-8"), ctypes.string_at(value, size))
  File "/home/czena/Documents/Others/Matter/connectedhomeip/out/python_env/lib/python3.8/site-packages/chip/storage/__init__.py", line 147, in SetSdkKey
    self.Sync()
  File "/home/czena/Documents/Others/Matter/connectedhomeip/out/python_env/lib/python3.8/site-packages/chip/storage/__init__.py", line 111, in Sync
    f"Could not open {path} for writing configuration. Error:")
NameError: name 'path' is not defined
2022-04-05 15:26:35 IMP-IT0087lx chip.ZCL[99597] INFO Using ZAP configuration...
2022-04-05 15:26:35 IMP-IT0087lx chip.DL[99597] INFO Avahi connecting
2022-04-05 15:26:35 IMP-IT0087lx chip.IN[99597] INFO CASE Server enabling CASE session setups
2022-04-05 15:26:35 IMP-IT0087lx chip.DIS[99597] INFO Updating services using commissioning mode 0
2022-04-05 15:26:35 IMP-IT0087lx chip.DIS[99597] ERROR Failed to initialize advertiser: ../../src/platform/Linux/DnssdImpl.cpp:333: CHIP Error 0x00000003: Incorrect state
2022-04-05 15:26:35 IMP-IT0087lx chip.DIS[99597] INFO Advertise commission parameter vendorID=65521 productID=32769 discriminator=3840/15
2022-04-05 15:26:35 IMP-IT0087lx chip.DIS[99597] ERROR Failed to advertise commissioner: ../../src/lib/dnssd/Discovery_ImplPlatform.cpp:473: CHIP Error 0x00000003: Incorrect state
2022-04-05 15:26:35 IMP-IT0087lx chip.DIS[99597] INFO Delaying proxy of operational discovery: missing delegate
2022-04-05 15:26:35 IMP-IT0087lx chip.DL[99597] ERROR Avahi client failure
2022-04-05 15:26:35 IMP-IT0087lx chip.DIS[99597] ERROR DNS-SD error: ../../src/platform/Linux/DnssdImpl.cpp:405: CHIP Error 0x000000AC: Internal error
New FabricAdmin: FabricId: 1(1)
2022-04-05 15:26:35 IMP-IT0087lx root[99597] INFO SetReplKey: fabricAdmins = {'1': {'fabricId': 1}}
name 'path' is not defined
Failed to bringup CHIPDeviceController CLI
Exception ignored in: <function FabricAdmin.__del__ at 0x7fa28df59790>
Traceback (most recent call last):
  File "/home/czena/Documents/Others/Matter/connectedhomeip/out/python_env/lib/python3.8/site-packages/chip/FabricAdmin.py", line 209, in __del__
    self.Shutdown(False)
  File "/home/czena/Documents/Others/Matter/connectedhomeip/out/python_env/lib/python3.8/site-packages/chip/FabricAdmin.py", line 188, in Shutdown
    if (self._isActive):
AttributeError: 'FabricAdmin' object has no attribute '_isActive'
Trace/breakpoint trap
(python_env) czena@IMP-IT0087lx:~/Documents/Others/Matter/connectedhomeip$ sudo out/python_env/bin/chip-device-ctrl 
[1649165200.415890][99632:99632] CHIP:CTL: Setting attestation nonce to random value
[1649165200.415975][99632:99632] CHIP:CTL: Setting CSR nonce to random value
[1649165200.417802][99632:99632] CHIP:DL: writing settings to file (/tmp/chip_counters.ini-vACnqU)
[1649165200.418016][99632:99632] CHIP:DL: renamed tmp file to file (/tmp/chip_counters.ini)
[1649165200.418044][99632:99632] CHIP:DL: NVS set: chip-counters/reboot-count = 6 (0x6)
[1649165200.418267][99632:99632] CHIP:DL: Got Ethernet interface: enp0s31f6
[1649165200.418466][99632:99632] CHIP:DL: Found the primary Ethernet interface:enp0s31f6
[1649165200.418676][99632:99632] CHIP:DL: Got WiFi interface: wlp1s0
[1649165200.420319][99632:99632] CHIP:DL: Found the primary WiFi interface:wlp1s0
[1649165200.420422][99632:99638] CHIP:DL: Platform main loop started.
2022-04-05 15:26:40 IMP-IT0087lx root[99632] CRITICAL Loading configuration from /tmp/chip-device-ctrl-storage.json...
2022-04-05 15:26:40 IMP-IT0087lx chip.DL[99632] INFO writing settings to file (/tmp/chip_counters.ini-gYRMIT)
2022-04-05 15:26:40 IMP-IT0087lx chip.DL[99632] ERROR renamed tmp file to file (/tmp/chip_counters.ini)
2022-04-05 15:26:40 IMP-IT0087lx chip.DL[99632] INFO NVS set: chip-counters/GlobalMCTR = 6000 (0x1770)
2022-04-05 15:26:40 IMP-IT0087lx root[99632] INFO SetSdkKey: g/gcc = b'\xd0\x07\x00\x00'
Exception ignored on calling ctypes callback function: <function _OnSyncSetKeyValueCb at 0x7f11e170a550>
Traceback (most recent call last):
  File "/home/czena/Documents/Others/Matter/connectedhomeip/out/python_env/lib/python3.8/site-packages/chip/storage/__init__.py", line 44, in _OnSyncSetKeyValueCb
    storageObj.SetSdkKey(key.decode("utf-8"), ctypes.string_at(value, size))
  File "/home/czena/Documents/Others/Matter/connectedhomeip/out/python_env/lib/python3.8/site-packages/chip/storage/__init__.py", line 147, in SetSdkKey
    self.Sync()
  File "/home/czena/Documents/Others/Matter/connectedhomeip/out/python_env/lib/python3.8/site-packages/chip/storage/__init__.py", line 111, in Sync
    f"Could not open {path} for writing configuration. Error:")
NameError: name 'path' is not defined
2022-04-05 15:26:40 IMP-IT0087lx root[99632] INFO SetSdkKey: g/gdc = b'\xd0\x07\x00\x00'
Exception ignored on calling ctypes callback function: <function _OnSyncSetKeyValueCb at 0x7f11e170a550>
Traceback (most recent call last):
  File "/home/czena/Documents/Others/Matter/connectedhomeip/out/python_env/lib/python3.8/site-packages/chip/storage/__init__.py", line 44, in _OnSyncSetKeyValueCb
    storageObj.SetSdkKey(key.decode("utf-8"), ctypes.string_at(value, size))
  File "/home/czena/Documents/Others/Matter/connectedhomeip/out/python_env/lib/python3.8/site-packages/chip/storage/__init__.py", line 147, in SetSdkKey
    self.Sync()
  File "/home/czena/Documents/Others/Matter/connectedhomeip/out/python_env/lib/python3.8/site-packages/chip/storage/__init__.py", line 111, in Sync
    f"Could not open {path} for writing configuration. Error:")
NameError: name 'path' is not defined
2022-04-05 15:26:40 IMP-IT0087lx chip.ZCL[99632] INFO Using ZAP configuration...
2022-04-05 15:26:40 IMP-IT0087lx chip.DL[99632] INFO Avahi connecting
2022-04-05 15:26:40 IMP-IT0087lx chip.IN[99632] INFO CASE Server enabling CASE session setups
2022-04-05 15:26:40 IMP-IT0087lx chip.DIS[99632] INFO Updating services using commissioning mode 0
2022-04-05 15:26:40 IMP-IT0087lx chip.DIS[99632] ERROR Failed to initialize advertiser: ../../src/platform/Linux/DnssdImpl.cpp:333: CHIP Error 0x00000003: Incorrect state
2022-04-05 15:26:40 IMP-IT0087lx chip.DIS[99632] INFO Advertise commission parameter vendorID=65521 productID=32769 discriminator=3840/15
2022-04-05 15:26:40 IMP-IT0087lx chip.DIS[99632] ERROR Failed to advertise commissioner: ../../src/lib/dnssd/Discovery_ImplPlatform.cpp:473: CHIP Error 0x00000003: Incorrect state
2022-04-05 15:26:40 IMP-IT0087lx chip.DIS[99632] INFO Delaying proxy of operational discovery: missing delegate
2022-04-05 15:26:40 IMP-IT0087lx chip.DL[99632] ERROR Avahi client failure
2022-04-05 15:26:40 IMP-IT0087lx chip.DIS[99632] ERROR DNS-SD error: ../../src/platform/Linux/DnssdImpl.cpp:405: CHIP Error 0x000000AC: Internal error
New FabricAdmin: FabricId: 1(1)
2022-04-05 15:26:40 IMP-IT0087lx root[99632] INFO SetReplKey: fabricAdmins = {'1': {'fabricId': 1}}
name 'path' is not defined
Failed to bringup CHIPDeviceController CLI
Exception ignored in: <function FabricAdmin.__del__ at 0x7f11dff55790>
Traceback (most recent call last):
  File "/home/czena/Documents/Others/Matter/connectedhomeip/out/python_env/lib/python3.8/site-packages/chip/FabricAdmin.py", line 209, in __del__
    self.Shutdown(False)
  File "/home/czena/Documents/Others/Matter/connectedhomeip/out/python_env/lib/python3.8/site-packages/chip/FabricAdmin.py", line 188, in Shutdown
    if (self._isActive):
AttributeError: 'FabricAdmin' object has no attribute '_isActive'
Illegal instruction

    How can I solve this ?

  • 0 in reply to Carla31

    Hello again,

    It seems to me that your Avahi deamon is not running, so the Python CHIP controller has nothing to connect to, and therefore fails.
    Could you restart the deamon using:

    sudo systemctl restart avahi-daemon

    and then checking its status again with:
    sudo systemctl status avahi-daemon

    To see if it is able to get up and running?

    If it does not manage to start, could you do a reinstallation of the Avahi deamon?
    Alternatively we would have to look into the syslog to see why the deamon does not start.

    Best regards,
    Karl

  • 0

    thanks for your answer. Now it seems to work fine, but it displays some errors. I would like to know if it is necessary to solve those errors or not. I removed and reinstall avahi-daemon. Here below, you can find the errors (in red) : 

    $ sudo apt-get purge avahi-daemon
Reading package lists... Done
Building dependency tree       
Reading state information... Done
The following package was automatically installed and is no longer required:
  libavahi-core7
Use 'sudo apt autoremove' to remove it.
The following packages will be REMOVED:
  avahi-daemon* avahi-utils* libnss-mdns*
0 upgraded, 0 newly installed, 3 to remove and 7 not upgraded.
After this operation, 602 kB disk space will be freed.
Do you want to continue? [Y/n] y
(Reading database ... 183234 files and directories currently installed.)
Removing avahi-utils (0.7-4ubuntu7.1) ...
Removing libnss-mdns:amd64 (0.14.1-1ubuntu1) ...
Removing avahi-daemon (0.7-4ubuntu7.1) ...
Created symlink /run/systemd/system/avahi-daemon.service → /dev/null.
Removed /run/systemd/system/avahi-daemon.service.
Processing triggers for man-db (2.9.1-1) ...
Processing triggers for dbus (1.12.16-2ubuntu2.1) ...
Processing triggers for libc-bin (2.31-0ubuntu9.7) ...
(Reading database ... 183167 files and directories currently installed.)
Purging configuration files for avahi-daemon (0.7-4ubuntu7.1) ...
rmdir: failed to remove '/var/run/avahi-daemon': Directory not empty
Purging configuration files for libnss-mdns:amd64 (0.14.1-1ubuntu1) ...
libnss-mdns.postrm: Checking NSS setup...
libnss-mdns.postrm: Removing mdns from NSS setup
Processing triggers for dbus (1.12.16-2ubuntu2.1) ...
Processing triggers for systemd (245.4-4ubuntu3.15) ...
czena@IMP-IT0087lx:~$ sudo systemctl stop avahi-daemon
czena@IMP-IT0087lx:~$ sudo systemctl disable avahi-daemon
Failed to disable unit: Unit file avahi-daemon.service does not exist.
czena@IMP-IT0087lx:~$ sudo apt-get install avahi-daemon
Reading package lists... Done
Building dependency tree       
Reading state information... Done
The following additional packages will be installed:
  libnss-mdns
The following NEW packages will be installed:
  avahi-daemon libnss-mdns
0 upgraded, 2 newly installed, 0 to remove and 7 not upgraded.
Need to get 83,9 kB of archives.
After this operation, 434 kB of additional disk space will be used.
Do you want to continue? [Y/n] y
Get:1 fr.archive.ubuntu.com/ubuntu focal-updates/main amd64 avahi-daemon amd64 0.7-4ubuntu7.1 [61,0 kB]
Get:2 fr.archive.ubuntu.com/ubuntu focal/main amd64 libnss-mdns amd64 0.14.1-1ubuntu1 [22,9 kB]
Fetched 83,9 kB in 0s (366 kB/s)       
Selecting previously unselected package avahi-daemon.
(Reading database ... 183162 files and directories currently installed.)
Preparing to unpack .../avahi-daemon_0.7-4ubuntu7.1_amd64.deb ...
Unpacking avahi-daemon (0.7-4ubuntu7.1) ...
Selecting previously unselected package libnss-mdns:amd64.
Preparing to unpack .../libnss-mdns_0.14.1-1ubuntu1_amd64.deb ...
Unpacking libnss-mdns:amd64 (0.14.1-1ubuntu1) ...
Setting up avahi-daemon (0.7-4ubuntu7.1) ...
Created symlink /etc/systemd/system/dbus-org.freedesktop.Avahi.service → /lib/systemd/system/avahi-daemon.service.
Created symlink /etc/systemd/system/multi-user.target.wants/avahi-daemon.service → /lib/systemd/system/avahi-daemon.service.
Created symlink /etc/systemd/system/sockets.target.wants/avahi-daemon.socket → /lib/systemd/system/avahi-daemon.socket.
Setting up libnss-mdns:amd64 (0.14.1-1ubuntu1) ...
First installation detected...
Checking NSS setup...
Processing triggers for systemd (245.4-4ubuntu3.15) ...
Processing triggers for man-db (2.9.1-1) ...
Processing triggers for dbus (1.12.16-2ubuntu2.1) ...
Processing triggers for libc-bin (2.31-0ubuntu9.7) ...
czena@IMP-IT0087lx:~$ sudo systemctl status avahi-daemon
â—Ź avahi-daemon.service - Avahi mDNS/DNS-SD Stack
     Loaded: loaded (/lib/systemd/system/avahi-daemon.service; enabled; vendor preset: enabled)
     Active: active (running) since Mon 2022-04-11 08:58:42 CEST; 13s ago
TriggeredBy: â—Ź avahi-daemon.socket
   Main PID: 201051 (avahi-daemon)
     Status: "avahi-daemon 0.7 starting up."
      Tasks: 2 (limit: 18978)
     Memory: 832.0K
     CGroup: /system.slice/avahi-daemon.service
             ├─201051 avahi-daemon: running [IMP-IT0087lx.local]
             └─201054 avahi-daemon: chroot helper

avril 11 08:58:42 IMP-IT0087lx avahi-daemon[201051]: Joining mDNS multicast group on interface lo.IPv4 with address 127.0.0.1.
avril 11 08:58:42 IMP-IT0087lx avahi-daemon[201051]: New relevant interface lo.IPv4 for mDNS.
avril 11 08:58:42 IMP-IT0087lx avahi-daemon[201051]: Network interface enumeration completed.
avril 11 08:58:42 IMP-IT0087lx avahi-daemon[201051]: Registering new address record for fe80::9a13:bdba:b4a5:9445 on wlp1s0.*.
avril 11 08:58:42 IMP-IT0087lx avahi-daemon[201051]: Registering new address record for 10.224.242.8 on wlp1s0.IPv4.
avril 11 08:58:42 IMP-IT0087lx avahi-daemon[201051]: Registering new address record for fe80::5e5a:7d2c:7c5:1e34 on enp0s31f6.*.
avril 11 08:58:42 IMP-IT0087lx avahi-daemon[201051]: Registering new address record for 10.224.242.18 on enp0s31f6.IPv4.
avril 11 08:58:42 IMP-IT0087lx avahi-daemon[201051]: Registering new address record for ::1 on lo.*.
avril 11 08:58:42 IMP-IT0087lx avahi-daemon[201051]: Registering new address record for 127.0.0.1 on lo.IPv4.
avril 11 08:58:43 IMP-IT0087lx avahi-daemon[201051]: Server startup complete. Host name is IMP-IT0087lx.local. Local service cookie is 1137175407.
czena@IMP-IT0087lx:~$ chip-device-ctrl 
  File "/home/czena/.local/bin/chip-device-ctrl", line 516
    print(f"Unable to connect on network: {ex}")
                                              ^
SyntaxError: invalid syntax
czena@IMP-IT0087lx:~$ sudo chip-device-ctrl
[1649660362.953181][202335:202335] CHIP:CTL: Setting attestation nonce to random value
[1649660362.953677][202335:202335] CHIP:CTL: Setting CSR nonce to random value
[1649660362.959382][202335:202335] CHIP:DL: writing settings to file (/tmp/chip_kvs-uSp2rQ)
[1649660362.959431][202335:202335] CHIP:DL: renamed tmp file to file (/tmp/chip_kvs)
[1649660362.959514][202335:202335] CHIP:DL: writing settings to file (/tmp/chip_factory.ini-LZjp9O)
[1649660362.959547][202335:202335] CHIP:DL: renamed tmp file to file (/tmp/chip_factory.ini)
[1649660362.959588][202335:202335] CHIP:DL: writing settings to file (/tmp/chip_config.ini-BBl8lN)
[1649660362.959617][202335:202335] CHIP:DL: renamed tmp file to file (/tmp/chip_config.ini)
[1649660362.959668][202335:202335] CHIP:DL: writing settings to file (/tmp/chip_counters.ini-HzB7MP)
[1649660362.959699][202335:202335] CHIP:DL: renamed tmp file to file (/tmp/chip_counters.ini)
[1649660362.959775][202335:202335] CHIP:DL: writing settings to file (/tmp/chip_factory.ini-kh0iLQ)
[1649660362.959875][202335:202335] CHIP:DL: renamed tmp file to file (/tmp/chip_factory.ini)
[1649660362.959888][202335:202335] CHIP:DL: NVS set: chip-factory/unique-id = "5803A6BD21E387DC"
[1649660362.959925][202335:202335] CHIP:DL: writing settings to file (/tmp/chip_counters.ini-fhRp1O)
[1649660362.960271][202335:202335] CHIP:DL: renamed tmp file to file (/tmp/chip_counters.ini)
[1649660362.960290][202335:202335] CHIP:DL: NVS set: chip-counters/reboot-count = 1 (0x1)
[1649660362.960333][202335:202335] CHIP:DL: writing settings to file (/tmp/chip_counters.ini-IIxOPQ)
[1649660362.960427][202335:202335] CHIP:DL: renamed tmp file to file (/tmp/chip_counters.ini)
[1649660362.960438][202335:202335] CHIP:DL: NVS set: chip-counters/total-operational-hours = 0 (0x0)
[1649660362.960834][202335:202335] CHIP:DL: writing settings to file (/tmp/chip_counters.ini-HqVVJP)
[1649660362.960925][202335:202335] CHIP:DL: renamed tmp file to file (/tmp/chip_counters.ini)
[1649660362.960936][202335:202335] CHIP:DL: NVS set: chip-counters/boot-reason = 0 (0x0)
[1649660362.960971][202335:202335] CHIP:DL: writing settings to file (/tmp/chip_config.ini-4Q7DCO)
[1649660362.961041][202335:202335] CHIP:DL: renamed tmp file to file (/tmp/chip_config.ini)
[1649660362.961060][202335:202335] CHIP:DL: NVS set: chip-config/regulatory-location = 0 (0x0)
[1649660362.961096][202335:202335] CHIP:DL: writing settings to file (/tmp/chip_config.ini-uyicHO)
[1649660362.961181][202335:202335] CHIP:DL: renamed tmp file to file (/tmp/chip_config.ini)
[1649660362.961192][202335:202335] CHIP:DL: NVS set: chip-config/location-capability = 0 (0x0)
[1649660362.961371][202335:202335] CHIP:DL: Got Ethernet interface: enp0s31f6
[1649660362.961503][202335:202335] CHIP:DL: Found the primary Ethernet interface:enp0s31f6
[1649660362.961636][202335:202335] CHIP:DL: Got WiFi interface: wlp1s0
[1649660362.963282][202335:202335] CHIP:DL: Found the primary WiFi interface:wlp1s0
[1649660362.963343][202335:202349] CHIP:DL: Platform main loop started.
2022-04-11 08:59:23 IMP-IT0087lx root[202335] ERROR [Errno 2] No such file or directory: '/tmp/chip-device-ctrl-storage.json'
2022-04-11 08:59:23 IMP-IT0087lx root[202335] WARNING Could not load configuration from /tmp/chip-device-ctrl-storage.json - resetting configuration...
2022-04-11 08:59:23 IMP-IT0087lx chip.DL[202335] INFO writing settings to file (/tmp/chip_counters.ini-Sj81eO)
2022-04-11 08:59:23 IMP-IT0087lx chip.DL[202335] ERROR renamed tmp file to file (/tmp/chip_counters.ini)
2022-04-11 08:59:23 IMP-IT0087lx chip.DL[202335] INFO NVS set: chip-counters/GlobalMCTR = 1000 (0x3E8)
2022-04-11 08:59:23 IMP-IT0087lx root[202335] INFO SetSdkKey: g/gcc = b'\xe8\x03\x00\x00'
2022-04-11 08:59:23 IMP-IT0087lx root[202335] INFO SetSdkKey: g/gdc = b'\xe8\x03\x00\x00'
2022-04-11 08:59:23 IMP-IT0087lx chip.ZCL[202335] INFO Using ZAP configuration...
2022-04-11 08:59:23 IMP-IT0087lx chip.DL[202335] INFO Avahi client registered
2022-04-11 08:59:23 IMP-IT0087lx chip.IN[202335] INFO CASE Server enabling CASE session setups
2022-04-11 08:59:23 IMP-IT0087lx chip.DIS[202335] INFO Updating services using commissioning mode 0
2022-04-11 08:59:23 IMP-IT0087lx chip.DIS[202335] INFO Advertise commission parameter vendorID=65521 productID=32769 discriminator=3840/15
2022-04-11 08:59:23 IMP-IT0087lx chip.DL[202335] INFO PublishService 8B05DAF8B4B221F0._matterd._udp5550.-1
2022-04-11 08:59:23 IMP-IT0087lx chip.DL[202335] INFO Avahi re-register required
2022-04-11 08:59:23 IMP-IT0087lx chip.-[202335] ERROR ../../src/system/SystemLayerImplSelect.cpp:220: CHIP Error 0x0000002F: Invalid argument at ../../src/platform/Linux/DnssdImpl.cpp:175
2022-04-11 08:59:23 IMP-IT0087lx chip.-[202335] ERROR ../../src/system/SystemLayerImplSelect.cpp:238: CHIP Error 0x0000002F: Invalid argument at ../../src/platform/Linux/DnssdImpl.cpp:176
2022-04-11 08:59:23 IMP-IT0087lx chip.-[202335] ERROR ../../src/system/SystemLayerImplSelect.cpp:266: CHIP Error 0x0000002F: Invalid argument at ../../src/platform/Linux/DnssdImpl.cpp:195
2022-04-11 08:59:23 IMP-IT0087lx chip.-[202335] ERROR ../../src/system/SystemLayerImplSelect.cpp:257: CHIP Error 0x0000002F: Invalid argument at ../../src/platform/Linux/DnssdImpl.cpp:199
2022-04-11 08:59:23 IMP-IT0087lx chip.-[202335] ERROR ../../src/system/SystemLayerImplSelect.cpp:220: CHIP Error 0x0000002F: Invalid argument at ../../src/platform/Linux/DnssdImpl.cpp:175
2022-04-11 08:59:23 IMP-IT0087lx chip.-[202335] ERROR ../../src/system/SystemLayerImplSelect.cpp:238: CHIP Error 0x0000002F: Invalid argument at ../../src/platform/Linux/DnssdImpl.cpp:176
2022-04-11 08:59:23 IMP-IT0087lx chip.-[202335] ERROR ../../src/system/SystemLayerImplSelect.cpp:266: CHIP Error 0x0000002F: Invalid argument at ../../src/platform/Linux/DnssdImpl.cpp:195
2022-04-11 08:59:23 IMP-IT0087lx chip.-[202335] ERROR ../../src/system/SystemLayerImplSelect.cpp:257: CHIP Error 0x0000002F: Invalid argument at ../../src/platform/Linux/DnssdImpl.cpp:199
2022-04-11 08:59:23 IMP-IT0087lx chip.DIS[202335] ERROR mDNS service published error: ../../src/platform/Linux/DnssdImpl.cpp:505: CHIP Error 0x000000AC: Internal error
2022-04-11 08:59:23 IMP-IT0087lx chip.DIS[202335] ERROR Failed to advertise commissioner: ../../src/platform/Linux/DnssdImpl.cpp:505: CHIP Error 0x000000AC: Internal error
New FabricAdmin: FabricId: 1(1)
2022-04-11 08:59:23 IMP-IT0087lx chip.CTL[202335] INFO Couldn't get ExampleOpCredsCAKey from storage: ../../src/controller/python/ChipDeviceController-StorageDelegate.cpp:97: CHIP Error 0x000000A0: Value not found in the persisted storage
2022-04-11 08:59:23 IMP-IT0087lx root[202335] INFO SetSdkKey: ExampleOpCredsCAKey1 = b'\x04+\xad\xc7\xf9\xaek\xe7\rKvH\xddJ\xd1\x91^\t>{JV4?\xfd\xbb\xae\xbb\xf6\xcd\xdf\xaf}e\xf1\xcb&#=\xb2\xee\xa6j\x86n2@w\xa5\xb6E\xef\xa6G`\x11r\xf5\x00d\xb4\x1d\x9aW\xa0\xf0i\xcd\x82\xbe\x9e\x7f_B\xc39\xc4\xfb\x0f\xb0+\x83\xb1\xe5\xeb\xf1=\x9d\x8d\x10M_lr\xec\x95\xa6'
2022-04-11 08:59:23 IMP-IT0087lx chip.CTL[202335] INFO Couldn't get ExampleOpCredsICAKey from storage: ../../src/controller/python/ChipDeviceController-StorageDelegate.cpp:97: CHIP Error 0x000000A0: Value not found in the persisted storage
2022-04-11 08:59:23 IMP-IT0087lx root[202335] INFO SetSdkKey: ExampleOpCredsICAKey1 = b'\x04\xd6\xe8\x80C\xa6\xcc\xc1\xa4\xbaa\xd9\xbe\xcbO\xf8>\xff~\xa4|\x05l\x17\x9c\xc4:]K\xf3\xff\x80\x87\x87\xd9\xd9z\x1d\xd9\xd4\x0e\xcb\x9a\x92\xfd\xed5\xdb\xb0\xdc\x8d\xf6\x94\x0cp\x05\xdc2\x98\xbb\x08\xbb\x08\xb4Q\xcd3\xd6\xca\xab\xcb}k\xd5O\xa8N\xcf\xb0$R\x06\xbc\xb0\xbaj\xff\xa7\xea\xd5\x04\xc1\xdc\r7yN'
2022-04-11 08:59:23 IMP-IT0087lx root[202335] INFO SetReplKey: fabricAdmins = {'1': {'fabricId': 1}}
2022-04-11 08:59:23 IMP-IT0087lx root[202335] INFO SetReplKey: fabricAdmins = {'1': {'fabricId': 1}}
Allocating new controller with FabricId: 1(1), NodeId: 1
2022-04-11 08:59:23 IMP-IT0087lx chip.CTL[202335] INFO Setting attestation nonce to random value
2022-04-11 08:59:23 IMP-IT0087lx chip.CTL[202335] INFO Setting CSR nonce to random value
2022-04-11 08:59:23 IMP-IT0087lx chip.CTL[202335] INFO Generating RCAC
2022-04-11 08:59:23 IMP-IT0087lx root[202335] INFO SetSdkKey: ExampleCARootCert1 = b'0\x82\x01\x970\x82\x01<\xa0\x03\x02\x01\x02\x02\x01\x000\n\x06\x08*\x86H\xce=\x04\x03\x020"1 0\x1e\x06\n+\x06\x01\x04\x01\x82\xa2|\x01\x04\x0c\x1000000000000000000\x1e\x17\r210101000000Z\x17\r301230000000Z0"1 0\x1e\x06\n+\x06\x01\x04\x01\x82\xa2|\x01\x04\x0c\x1000000000000000000Y0\x13\x06\x07*\x86H\xce=\x02\x01\x06\x08*\x86H\xce=\x03\x01\x07\x03B\x00\x04+\xad\xc7\xf9\xaek\xe7\rKvH\xddJ\xd1\x91^\t>{JV4?\xfd\xbb\xae\xbb\xf6\xcd\xdf\xaf}e\xf1\xcb&#=\xb2\xee\xa6j\x86n2@w\xa5\xb6E\xef\xa6G`\x11r\xf5\x00d\xb4\x1d\x9aW\xa0\xa3c0a0\x0f\x06\x03U\x1d\x13\x01\x01\xff\x04\x050\x03\x01\x01\xff0\x0e\x06\x03U\x1d\x0f\x01\x01\xff\x04\x04\x03\x02\x01\x060\x1d\x06\x03U\x1d\x0e\x04\x16\x04\x14i\xa6\xb5t\xef\xa9\xb0O\x8c_\xd9\x06\xe6\xddZ\xa24\xa6W@0\x1f\x06\x03U\x1d#\x04\x180\x16\x80\x14i\xa6\xb5t\xef\xa9\xb0O\x8c_\xd9\x06\xe6\xddZ\xa24\xa6W@0\n\x06\x08*\x86H\xce=\x04\x03\x02\x03I\x000F\x02!\x00\x90|\x1f\xf9\xd6\xf9a\x85\x92\xd0_\x98\xf6\xf4\xec,\xdfX\xc0\x80N\x0fiP)\xc5{K\xc6\xa9\\)\x02!\x00\xacn\xda\xf6L\xe0_\x11}C>g\xa6\xc5\x87\x1a\x00\xebaJl\x00s4w\xb0*\x04h\x95/\xa7'
2022-04-11 08:59:23 IMP-IT0087lx chip.CTL[202335] INFO Generating ICAC
2022-04-11 08:59:23 IMP-IT0087lx root[202335] INFO SetSdkKey: ExampleCAIntermediateCert1 = b'0\x82\x01\x950\x82\x01<\xa0\x03\x02\x01\x02\x02\x01\x000\n\x06\x08*\x86H\xce=\x04\x03\x020"1 0\x1e\x06\n+\x06\x01\x04\x01\x82\xa2|\x01\x04\x0c\x1000000000000000000\x1e\x17\r210101000000Z\x17\r301230000000Z0"1 0\x1e\x06\n+\x06\x01\x04\x01\x82\xa2|\x01\x03\x0c\x1000000000000000010Y0\x13\x06\x07*\x86H\xce=\x02\x01\x06\x08*\x86H\xce=\x03\x01\x07\x03B\x00\x04\xd6\xe8\x80C\xa6\xcc\xc1\xa4\xbaa\xd9\xbe\xcbO\xf8>\xff~\xa4|\x05l\x17\x9c\xc4:]K\xf3\xff\x80\x87\x87\xd9\xd9z\x1d\xd9\xd4\x0e\xcb\x9a\x92\xfd\xed5\xdb\xb0\xdc\x8d\xf6\x94\x0cp\x05\xdc2\x98\xbb\x08\xbb\x08\xb4Q\xa3c0a0\x0f\x06\x03U\x1d\x13\x01\x01\xff\x04\x050\x03\x01\x01\xff0\x0e\x06\x03U\x1d\x0f\x01\x01\xff\x04\x04\x03\x02\x01\x060\x1d\x06\x03U\x1d\x0e\x04\x16\x04\x14\xcd\x00M\xd7 J}|g\xdd\xe7\xc7\x12\xe4\xed\x18q\xc9t\x950\x1f\x06\x03U\x1d#\x04\x180\x16\x80\x14i\xa6\xb5t\xef\xa9\xb0O\x8c_\xd9\x06\xe6\xddZ\xa24\xa6W@0\n\x06\x08*\x86H\xce=\x04\x03\x02\x03G\x000D\x02 y\x8e\xf6\xe8\x05\xb8\xa0\xa6\x1c\xb2\x00\x93\xa4\x01\xfc\xb0H\xfc\x8e\xfbY-\xbd\xf3\x82\x00\xa6\xf2\xb5\xae|\x14\x02 \x16\x80C\x01\x8d\xc4\x18\x8b*Q\xfa\xee=a\x14%#\\4\xbf\x8dK\xa0b\xb9i\xb9\x1c\xcf\x8a\xdb\xfb'
2022-04-11 08:59:23 IMP-IT0087lx chip.CTL[202335] INFO Generating NOC
2022-04-11 08:59:23 IMP-IT0087lx chip.DIS[202335] INFO Verifying the received credentials
2022-04-11 08:59:23 IMP-IT0087lx chip.DIS[202335] INFO Added new fabric at index: 1, Initialized: 1
2022-04-11 08:59:23 IMP-IT0087lx chip.DIS[202335] INFO Assigned compressed fabric ID: 0x2A7FA18C8E97D8ED, node ID: 0x0000000000000001
2022-04-11 08:59:23 IMP-IT0087lx root[202335] INFO SetSdkKey: f/1/r = b'\x150\x01\x01\x00$\x02\x017\x03$\x14\x00\x18&\x04\x80"\x81\'&\x05\x80%M:7\x06$\x14\x00\x18$\x07\x01$\x08\x010\tA\x04+\xad\xc7\xf9\xaek\xe7\rKvH\xddJ\xd1\x91^\t>{JV4?\xfd\xbb\xae\xbb\xf6\xcd\xdf\xaf}e\xf1\xcb&#=\xb2\xee\xa6j\x86n2@w\xa5\xb6E\xef\xa6G`\x11r\xf5\x00d\xb4\x1d\x9aW\xa07\n5\x01)\x01\x18$\x02`0\x04\x14i\xa6\xb5t\xef\xa9\xb0O\x8c_\xd9\x06\xe6\xddZ\xa24\xa6W@0\x05\x14i\xa6\xb5t\xef\xa9\xb0O\x8c_\xd9\x06\xe6\xddZ\xa24\xa6W@\x180\x0b@\x90|\x1f\xf9\xd6\xf9a\x85\x92\xd0_\x98\xf6\xf4\xec,\xdfX\xc0\x80N\x0fiP)\xc5{K\xc6\xa9\\)\xacn\xda\xf6L\xe0_\x11}C>g\xa6\xc5\x87\x1a\x00\xebaJl\x00s4w\xb0*\x04h\x95/\xa7\x18'
2022-04-11 08:59:23 IMP-IT0087lx root[202335] INFO SetSdkKey: f/1/i = b'\x150\x01\x01\x00$\x02\x017\x03$\x14\x00\x18&\x04\x80"\x81\'&\x05\x80%M:7\x06$\x13\x01\x18$\x07\x01$\x08\x010\tA\x04\xd6\xe8\x80C\xa6\xcc\xc1\xa4\xbaa\xd9\xbe\xcbO\xf8>\xff~\xa4|\x05l\x17\x9c\xc4:]K\xf3\xff\x80\x87\x87\xd9\xd9z\x1d\xd9\xd4\x0e\xcb\x9a\x92\xfd\xed5\xdb\xb0\xdc\x8d\xf6\x94\x0cp\x05\xdc2\x98\xbb\x08\xbb\x08\xb4Q7\n5\x01)\x01\x18$\x02`0\x04\x14\xcd\x00M\xd7 J}|g\xdd\xe7\xc7\x12\xe4\xed\x18q\xc9t\x950\x05\x14i\xa6\xb5t\xef\xa9\xb0O\x8c_\xd9\x06\xe6\xddZ\xa24\xa6W@\x180\x0b@y\x8e\xf6\xe8\x05\xb8\xa0\xa6\x1c\xb2\x00\x93\xa4\x01\xfc\xb0H\xfc\x8e\xfbY-\xbd\xf3\x82\x00\xa6\xf2\xb5\xae|\x14\x16\x80C\x01\x8d\xc4\x18\x8b*Q\xfa\xee=a\x14%#\\4\xbf\x8dK\xa0b\xb9i\xb9\x1c\xcf\x8a\xdb\xfb\x18'
2022-04-11 08:59:23 IMP-IT0087lx root[202335] INFO SetSdkKey: f/1/n = b'\x150\x01\x01\x01$\x02\x017\x03$\x13\x01\x18&\x04\x80"\x81\'&\x05\x80%M:7\x06$\x15\x01$\x11\x01\x18$\x07\x01$\x08\x010\tA\x04\xf2u\xa9\xf7z\xd94=\x10\xdb!r\xd8\xd3hD\xf4\x94kc\x8e\xfa\t\xe9\xc9\xc1AJ\xb8\x92\x12\xe3\xc4\x0e\xd3\xbc\x9e\xdc\xe4\xad\xf6}\xd63\x01\xb9J;Zs\xe3\xe7\xb6\x06\xb8\xa3\xffL\xa0\xf95\x92\xbcU7\n5\x01(\x01\x18$\x02\x016\x03\x04\x02\x04\x01\x180\x04\x144\x96\x92\x90\x8a<2\xd1.Ku\x84\xd6\xb7NZ\x83b\x9d\xf10\x05\x14\xcd\x00M\xd7 J}|g\xdd\xe7\xc7\x12\xe4\xed\x18q\xc9t\x95\x180\x0b@\x04Y\xeb\r\x8fm\xb1\x17\x9eu\x9b$\xc2\xed\xfd\x0f\x1d]\xc9\x0e\x7fn\xeb\xa7\xcf\xcf\xb6\xb9\xe8\x0b\xde\xf7\xca\xb83\xf7\xbe\x0c?\x9d\x9e\xd0S&\x08`\x98\x96On\xcfx\'\xe7:\x1d=\xf7X\xee\xb7\x07h4\x18'
2022-04-11 08:59:23 IMP-IT0087lx root[202335] INFO SetSdkKey: f/1/o = b'\x15$\x00\x010\x01a\x04\xf2u\xa9\xf7z\xd94=\x10\xdb!r\xd8\xd3hD\xf4\x94kc\x8e\xfa\t\xe9\xc9\xc1AJ\xb8\x92\x12\xe3\xc4\x0e\xd3\xbc\x9e\xdc\xe4\xad\xf6}\xd63\x01\xb9J;Zs\xe3\xe7\xb6\x06\xb8\xa3\xffL\xa0\xf95\x92\xbcU/\x01\xb9Vx \xc6\xab\x9b\x04\xa3\x02\xa9Tj\xc3\xc7|d\xba;\x92\xb9]\xc4\xc9\xb8\x95H\x19\x18\xaf\x18'
2022-04-11 08:59:23 IMP-IT0087lx root[202335] INFO SetSdkKey: f/1/m = b'\x15%\x00\xbe\xb6,\x01\x00\x18'
2022-04-11 08:59:23 IMP-IT0087lx chip.DIS[202335] INFO Fabric (1) persisted to storage. Calling OnFabricPersistedToStorage
2022-04-11 08:59:23 IMP-IT0087lx root[202335] INFO SetSdkKey: g/fidx = b'\x15$\x00\x026\x01\x04\x01\x18\x18'
2022-04-11 08:59:23 IMP-IT0087lx chip.CTL[202335] INFO Joined the fabric at index 1. Compressed fabric ID is: 0x2A7FA18C8E97D8ED
2022-04-11 08:59:23 IMP-IT0087lx chip.DIS[202335] INFO Advertise operational node 2A7FA18C8E97D8ED-0000000000000001
2022-04-11 08:59:23 IMP-IT0087lx chip.DL[202335] INFO PublishService 2A7FA18C8E97D8ED-0000000000000001._matter._tcp34410.-1
2022-04-11 08:59:23 IMP-IT0087lx chip.DIS[202335] INFO mDNS service published: _matter._tcp
2022-04-11 08:59:23 IMP-IT0087lx chip.SPT[202335] INFO Setting up group data for Fabric Index 1 with Compressed Fabric ID:
2022-04-11 08:59:23 IMP-IT0087lx root[202335] INFO SetSdkKey: f/1/k/0 = b"\x15$\x01\x00$\x02\x016\x03\x15$\x04\x00%\x05\x85)0\x06\x10\xe1\x8eq\xb2\xdf\xa7\xd1Y\xba\x9d+1\xe8t\xe0\xe5\x18\x15'\x04\x00\xcb\x7f\xe3\x18\x7f\x00\x00$\x05\x000\x06\x10\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x18\x15'\x04\x00\xcf\x7f\xe3\x18\x7f\x00\x00$\x05\x000\x06\x10\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x18\x18%\x07\xff\xff\x18"
2022-04-11 08:59:23 IMP-IT0087lx root[202335] INFO SetSdkKey: g/gfl = b'\x15$\x01\x01$\x02\x01\x18'
2022-04-11 08:59:23 IMP-IT0087lx root[202335] INFO SetSdkKey: f/1/g = b'\x15$\x01\x00$\x02\x00$\x03\x00$\x04\x00$\x05\x00$\x06\x01$\x07\x00\x18'
2022-04-11 08:59:23 IMP-IT0087lx ChipBLEMgr[202335] DEBUG add adapter signal
Chip Device Controller Shell

chip-device-ctrl > 2022-04-11 08:59:23 IMP-IT0087lx chip.DL[202335] INFO Avahi client registered
2022-04-11 08:59:23 IMP-IT0087lx chip.DIS[202335] INFO Updating services using commissioning mode 0
2022-04-11 08:59:23 IMP-IT0087lx chip.DIS[202335] INFO Advertise operational node 2A7FA18C8E97D8ED-0000000000000001
2022-04-11 08:59:23 IMP-IT0087lx chip.DL[202335] INFO PublishService 2A7FA18C8E97D8ED-0000000000000001._matter._tcp34410.-1
2022-04-11 08:59:23 IMP-IT0087lx chip.DIS[202335] INFO mDNS service published: _matter._tcp
2022-04-11 08:59:23 IMP-IT0087lx chip.DIS[202335] INFO Advertise commission parameter vendorID=65521 productID=32769 discriminator=3840/15
2022-04-11 08:59:23 IMP-IT0087lx chip.DL[202335] INFO PublishService 8B05DAF8B4B221F0._matterd._udp5550.-1
2022-04-11 08:59:23 IMP-IT0087lx chip.DIS[202335] ERROR mDNS service published error: ../../src/platform/Linux/DnssdImpl.cpp:482: CHIP Error 0x000000AC: Internal error
2022-04-11 08:59:23 IMP-IT0087lx chip.DIS[202335] ERROR Failed to advertise commissioner: ../../src/platform/Linux/DnssdImpl.cpp:482: CHIP Error 0x000000AC: Internal error
2022-04-11 08:59:24 IMP-IT0087lx chip.DL[202335] INFO Avahi group established
2022-04-11 08:59:24 IMP-IT0087lx chip.DL[202335] INFO Avahi group established

ble-adapter-print               close-ble                       discover                        resolve                         zcl 
ble-adapter-select              close-session                   get-fabricid                    set-pairing-thread-credential   zclread 
ble-debug-log                   commission                      open-commissioning-window       set-pairing-wifi-credential     zclsubscribe 
ble-scan                        connect                         paseonly                        setup-payload                   
chip-device-ctrl >
  • 0 in reply to Carla31

    Hello again

    Thank you for your patience with this.

    I am glad to hear that it seems to be working fine now.

    The errors indicate that the controller failed to advertise itself over DNS-SD, perhaps erroneous, but it should still be able to resolve other services and commission other nodes. If the commissioning and controlling other nodes works fine, I would simply ignore the errors.

    Best regards,
    Karl
  • 0 in reply to Karl Ylvisaker

    Hello, I'm trying to connect my board nRF5340DK (with the Light Bulb Matter example) with my PC (Matter controller) over Bluetooth but the connection is not established. This is what I get : 

    chip-device-ctrl > connect -ble 3840 20202021
2022-04-28 10:44:31 IMP-IT0087lx root[695542] WARNING  /usr/local/bin/chip-device-ctrl:601: DeprecationWarning:This method is being deprecated. Please use the DeviceController.[ConnectBLE|CommissionIP] methods directly in the REPL
Device is assigned with nodeid = 308642
2022-04-28 10:44:31 IMP-IT0087lx chip.IN[695542] INFO Prepared unauthenticated message 0x7ff46affb8f0 to 0x0000000000000000 (0)  of type 0x20 and protocolId (0, 0) on exchange 62712i with MessageCounter:1124971237.
2022-04-28 10:44:31 IMP-IT0087lx chip.IN[695542] INFO Sending unauthenticated msg 0x7ff46affb8f0 with MessageCounter:1124971237 to 0x0000000000000000 at monotonic time: 000000000F76DDB3 msec
2022-04-28 10:44:31 IMP-IT0087lx chip.CTL[695542] INFO Setting attestation nonce to random value
2022-04-28 10:44:31 IMP-IT0087lx chip.CTL[695542] INFO Setting CSR nonce to random value
2022-04-28 10:44:31 IMP-IT0087lx chip.CTL[695542] INFO Commission called for node ID 0x000000000004B5A2
2022-04-28 10:44:31 IMP-IT0087lx chip.BLE[695542] INFO BLE removing known devices.
2022-04-28 10:44:31 IMP-IT0087lx chip.BLE[695542] INFO BLE initiating scan.
2022-04-28 10:44:32 IMP-IT0087lx chip.BLE[695542] INFO New device scanned: DD:30:06:22:DC:86
2022-04-28 10:44:32 IMP-IT0087lx chip.BLE[695542] INFO Device discriminator match. Attempting to connect.
2022-04-28 10:44:32 IMP-IT0087lx chip.BLE[695542] INFO Scan complete notification without an active scan.
2022-04-28 10:44:34 IMP-IT0087lx chip.BLE[695542] INFO subscribe complete, ep = 0x7ff47264cb80
2022-04-28 10:44:34 IMP-IT0087lx chip.BLE[695542] INFO peripheral chose BTP version 4; central expected between 4 and 4
2022-04-28 10:44:34 IMP-IT0087lx chip.BLE[695542] INFO using BTP fragment sizes rx 244 / tx 244.
2022-04-28 10:44:34 IMP-IT0087lx chip.BLE[695542] INFO local and remote recv window size = 5
2022-04-28 10:44:35 IMP-IT0087lx chip.IN[695542] INFO Received malformed unsecure packet with source 0x0000000000000000 destination 0x0000000000000000
2022-04-28 10:45:01 IMP-IT0087lx chip.SC[695542] ERROR PASESession timed out while waiting for a response from the peer. Expected message type was 33
Failed to establish secure session to device: 50
CHIP Error 0x00000032: Timeout

    I would like to know why I can't establish the secure connection over Bluetooth LE.

    When I use the command ble-scan, to scan all advertised Matter devices, I got the following output: 

    2022-04-28 10:44:12 IMP-IT0087lx ChipBLEMgr[695542] DEBUG destroy device
2022-04-28 10:44:12 IMP-IT0087lx ChipBLEMgr[695542] DEBUG destroy device
2022-04-28 10:44:12 IMP-IT0087lx ChipBLEMgr[695542] DEBUG destroy device
2022-04-28 10:44:12 IMP-IT0087lx ChipBLEMgr[695542] DEBUG destroy device
2022-04-28 10:44:12 IMP-IT0087lx ChipBLEMgr[695542] DEBUG destroy device
2022-04-28 10:44:12 IMP-IT0087lx ChipBLEMgr[695542] DEBUG destroy device
2022-04-28 10:44:12 IMP-IT0087lx ChipBLEMgr[695542] DEBUG destroy device
2022-04-28 10:44:12 IMP-IT0087lx ChipBLEMgr[695542] INFO Name            = MatterLight
2022-04-28 10:44:12 IMP-IT0087lx ChipBLEMgr[695542] INFO ID              = 766dcf2a-2adf-37fc-abbc-b104dd4e0ec4
2022-04-28 10:44:12 IMP-IT0087lx ChipBLEMgr[695542] INFO RSSI            = -56
2022-04-28 10:44:12 IMP-IT0087lx ChipBLEMgr[695542] INFO Address         = DD:30:06:22:DC:86
2022-04-28 10:44:12 IMP-IT0087lx ChipBLEMgr[695542] INFO Adv UUID        = 0000fff6-0000-1000-8000-00805f9b34fb
2022-04-28 10:44:12 IMP-IT0087lx ChipBLEMgr[695542] INFO Adv Data        = 00000f5a234c4e00
2022-04-28 10:44:12 IMP-IT0087lx ChipBLEMgr[695542] INFO 
2022-04-28 10:44:12 IMP-IT0087lx ChipBLEMgr[695542] DEBUG org.freedesktop.DBus.Error.InvalidArgs: No such property 'Name'
2022-04-28 10:44:12 IMP-IT0087lx ChipBLEMgr[695542] DEBUG org.freedesktop.DBus.Error.InvalidArgs: No such property 'Name'
2022-04-28 10:44:12 IMP-IT0087lx ChipBLEMgr[695542] DEBUG org.freedesktop.DBus.Error.InvalidArgs: No such property 'Name'
2022-04-28 10:44:12 IMP-IT0087lx ChipBLEMgr[695542] DEBUG org.freedesktop.DBus.Error.InvalidArgs: No such property 'Name'
2022-04-28 10:44:12 IMP-IT0087lx ChipBLEMgr[695542] DEBUG org.freedesktop.DBus.Error.InvalidArgs: No such property 'Name'
2022-04-28 10:44:12 IMP-IT0087lx ChipBLEMgr[695542] DEBUG org.freedesktop.DBus.Error.InvalidArgs: No such property 'ServiceData'
2022-04-28 10:44:12 IMP-IT0087lx ChipBLEMgr[695542] DEBUG org.freedesktop.DBus.Error.InvalidArgs: No such property 'ServiceData'
2022-04-28 10:44:12 IMP-IT0087lx ChipBLEMgr[695542] DEBUG org.freedesktop.DBus.Error.InvalidArgs: No such property 'ServiceData'

    Could you help me please ?

Related
Terms of service