fhempy: ble_monitor (Bluetooth Low Energy)

[dominik]

Hallo zusammen,

ich habe die Tage ein neues Modul für BLE Devices erstellt. Es ist auf Basis der ble_monitor Home Assistant Implementierung und unterstützt unzählige BLE Devices, siehe hier:
https://custom-components.github.io/ble_monitor/by_brand

Einrichtung:
- fhempy Installation wenn noch nicht vorhanden
https://github.com/dominikkarall/fhempy#installation

- und danach die Anleitung hier befolgen:
https://github.com/dominikkarall/fhempy/blob/master/FHEM/bindings/python/fhempy/lib/ble_monitor/README.md

Für die Xiaomi Mi Scale habe ich auf Basis von ble_monitor eine eigene Implementierung gemacht:
https://github.com/dominikkarall/fhempy/blob/master/FHEM/bindings/python/fhempy/lib/miscale/README.md

Diese berechnet auch alle BMI/Fett/Wasser/etc. Werte wie in der Mi Fit App.

[RockFan]

Hi dominik,

ich dachte mir, das muss ich gleich mal ausprobieren (habe auch eine Mi Scale - allerdings Version 1). Deshalb habe ich erstmal fhem und dann fhempy aktualisiert und dann das Device für die Waage definiert.

Leider klappt das noch nicht so ganz bei mir. Im Status steht jetzt folgende Fehlermeldung:

Code Auswählen Erweitern


Failed to load module miscale: Traceback (most recent call last):
  File "/opt/fhem/.local/lib/python3.7/site-packages/fhempy/lib/fhem_pythonbinding.py", line 269, in _onMessage
    functools.partial(importlib.import_module, pymodule)
  File "/opt/fhem/.local/lib/python3.7/site-packages/fhempy/lib/utils.py", line 34, in run_blocking
    return await asyncio.get_event_loop().run_in_executor(pool, function)
  File "/usr/lib/python3.7/concurrent/futures/thread.py", line 57, in run
    result = self.fn(*self.args, **self.kwargs)
  File "/usr/lib/python3.7/importlib/__init__.py", line 127, in import_module
    return _bootstrap._gcd_import(name[level:], package, level)
  File "<frozen importlib._bootstrap>", line 1006, in _gcd_import
  File "<frozen importlib._bootstrap>", line 983, in _find_and_load
  File "<frozen importlib._bootstrap>", line 967, in _find_and_load_unlocked
  File "<frozen importlib._bootstrap>", line 677, in _load_unlocked
  File "<frozen importlib._bootstrap_external>", line 728, in exec_module
  File "<frozen importlib._bootstrap>", line 219, in _call_with_frames_removed
  File "/opt/fhem/.local/lib/python3.7/site-packages/fhempy/lib/miscale/miscale.py", line 3, in <module>
    from fhempy.lib.ble_monitor.blemonitor import BLEmonitor
  File "/opt/fhem/.local/lib/python3.7/site-packages/fhempy/lib/ble_monitor/blemonitor.py", line 8, in <module>
    from fhempy.lib.ble_monitor.hcidump import HCIdump
  File "/opt/fhem/.local/lib/python3.7/site-packages/fhempy/lib/ble_monitor/hcidump.py", line 8, in <module>
    from bleparser import BleParser
  File "/opt/fhem/.local/lib/python3.7/site-packages/bleparser/__init__.py", line 4, in <module>
    from .altbeacon import parse_altbeacon
  File "/opt/fhem/.local/lib/python3.7/site-packages/bleparser/altbeacon.py", line 5, in <module>
    from typing import Final
ImportError: cannot import name 'Final' from 'typing' (/usr/lib/python3.7/typing.py)


[dominik]

Kannst du bitte

sudo -u fhem bash
pip3 install typing

probieren? Womöglich ist das bei Python 3.7 noch nicht im package.

[dominik]

Ich habe etwas gegoogelt, scheint so, also ob das typing Final für Python 3.7 nicht verfügbar ist.

Ich hab mal bei der bleparser Library ein Issue für Python 3.7 aufgemacht.

[RockFan]

Auch wenn die Info vielleicht nicht mehr nötig ist, das Ergebnis der beiden Kommandos ist:

Code Auswählen Erweitern


Looking in indexes: https://pypi.org/simple, https://www.piwheels.org/simple
Requirement already satisfied: typing in /opt/fhem/.local/lib/python3.7/site-packages (3.7.4.3)


[dominik]

Ich habe gerade erst gesehen, dass der Python 3.7 Support erst vor kurzem entfernt wurde:
https://github.com/Ernst79/bleparser/commit/55fd52eb94dea27c0a8eb5902e39ceb579abd2eb

Die Chance diesen wieder zurück zu bekommen, dürften also nicht all zu hoch sein. Sollte er doch eingebaut werden, werde ich es aktualisieren. Ich würde vorerst empfehlen auf bullseye und damit Python 3.9 als Standard zu aktualisieren.

[Nighthawk]

Hallo Dominik,

danke für deine Arbeit, damit habe ich schon mal meine Mi Body Scale mit miscale ins FHEM gebracht.

Leider klappt es mit den Xiaomi / Honeywell Rauchmelder nicht, es kommen einfach keine Daten in das Device:

Code Auswählen Erweitern

Internals:
   DEF        ble_monitor 54:EF:44:E4:6A:81
   FHEMPYTYPE ble_monitor
   FUUID      620f9d9c-f33f-357a-6abe-a1693a5f4e108f38
   IODev      local_pybinding
   NAME       Feuermelder_SZ
   NR         596
   PYTHONTYPE ble_monitor
   STATE      ???
   TYPE       fhempy
   args:
     Feuermelder_SZ
     fhempy
     ble_monitor
     54:EF:44:E4:6A:81
   argsh:
Attributes:

Anfangs kam noch eine Fehlermeldung dass der BleParser fehlt, diesen habe ich mit

Code Auswählen Erweitern

pip install bleparser nachinstalliert.

Was mache ich falsch?

[dominik]

Kannst du bitte verbose 5 machen und dann den Output posten?

[Nighthawk]

Code Auswählen Erweitern

2022.02.18 15:25:10 4: BindingsIo (local_pybinding): start fhempyFunction: Feuermelder_SZ => Set (83452285)
2022.02.18 15:25:10 4: BindingsIo (local_pybinding): <<< WS: {"defargs":["Feuermelder_SZ","fhempy","ble_monitor","54:EF:44:E4:6A:81"],"NAME":"Feuermelder_SZ","argsh":{},"id":"83452285","args":["Feuermelder_SZ","?"],"msgtype":"function","defargsh":{},"FHEMPYTYPE":"ble_monitor","PYTHONTYPE":"ble_monitor","function":"Set"}
2022.02.18 15:25:10 5: DevIo_SimpleWrite local_pybinding: 7b2264656661726773223a5b2246657565726d656c6465725f535a222c226668656d7079222c22626c655f6d6f6e69746f72222c2235343a45463a34343a45343a36413a3831225d2c224e414d45223a2246657565726d656c6465725f535a222c226172677368223a7b7d2c226964223a223833343532323835222c2261726773223a5b2246657565726d656c6465725f535a222c223f225d2c226d736774797065223a2266756e6374696f6e222c226465666172677368223a7b7d2c224648454d505954595045223a22626c655f6d6f6e69746f72222c22505954484f4e54595045223a22626c655f6d6f6e69746f72222c2266756e6374696f6e223a22536574227d
2022.02.18 15:25:10 5: BindingsIo (local_pybinding): DevIo_SimpleRead
2022.02.18 15:25:10 5: BindingsIo (local_pybinding): DevIo_SimpleRead WithTimeout
2022.02.18 15:25:10 4: BindingsIo (local_pybinding): >>> WS: {"defargs": ["Feuermelder_SZ", "fhempy", "ble_monitor", "54:EF:44:E4:6A:81"], "NAME": "Feuermelder_SZ", "argsh": {}, "id": "83452285", "args": ["Feuermelder_SZ", "?"], "msgtype": "function", "defargsh": {}, "FHEMPYTYPE": "ble_monitor", "PYTHONTYPE": "ble_monitor", "function": "Set", "finished": 1, "returnval": "Unknown argument ?, choose one of "}
2022.02.18 15:25:10 5: BindingsIo (local_pybinding): QUEUE: start handling - 1
2022.02.18 15:25:10 5: processMessage: {"defargs": ["Feuermelder_SZ", "fhempy", "ble_monitor", "54:EF:44:E4:6A:81"], "NAME": "Feuermelder_SZ", "argsh": {}, "id": "83452285", "args": ["Feuermelder_SZ", "?"], "msgtype": "function", "defargsh": {}, "FHEMPYTYPE": "ble_monitor", "PYTHONTYPE": "ble_monitor", "function": "Set", "finished": 1, "returnval": "Unknown argument ?, choose one of "}
2022.02.18 15:25:10 5: BindingsIo (local_pybinding): QUEUE: finished handling - 0
2022.02.18 15:25:10 4: BindingsIo (local_pybinding): end fhempyFunction: Feuermelder_SZ => Set (83452285) - result: Unknown argument ?, choose one of
2022.02.18 15:25:10 4: BindingsIo (local_pybinding): start fhempyFunction: Feuermelder_SZ => FW_detailFn (12696733)
2022.02.18 15:25:10 4: BindingsIo (local_pybinding): <<< WS: {"PYTHONTYPE":"ble_monitor","FHEMPYTYPE":"ble_monitor","function":"FW_detailFn","defargsh":{},"msgtype":"function","id":"12696733","argsh":{},"args":["WEB","Feuermelder_SZ","",null],"defargs":["Feuermelder_SZ","fhempy","ble_monitor","54:EF:44:E4:6A:81"],"NAME":"Feuermelder_SZ"}
2022.02.18 15:25:10 5: DevIo_SimpleWrite local_pybinding: 7b22505954484f4e54595045223a22626c655f6d6f6e69746f72222c224648454d505954595045223a22626c655f6d6f6e69746f72222c2266756e6374696f6e223a2246575f64657461696c466e222c226465666172677368223a7b7d2c226d736774797065223a2266756e6374696f6e222c226964223a223132363936373333222c226172677368223a7b7d2c2261726773223a5b22574542222c2246657565726d656c6465725f535a222c22222c6e756c6c5d2c2264656661726773223a5b2246657565726d656c6465725f535a222c226668656d7079222c22626c655f6d6f6e69746f72222c2235343a45463a34343a45343a36413a3831225d2c224e414d45223a2246657565726d656c6465725f535a227d
2022.02.18 15:25:10 5: BindingsIo (local_pybinding): DevIo_SimpleRead
2022.02.18 15:25:10 5: BindingsIo (local_pybinding): DevIo_SimpleRead WithTimeout
2022.02.18 15:25:10 4: BindingsIo (local_pybinding): >>> WS: {"PYTHONTYPE": "ble_monitor", "FHEMPYTYPE": "ble_monitor", "function": "FW_detailFn", "defargsh": {}, "msgtype": "function", "id": "12696733", "argsh": {}, "args": ["WEB", "Feuermelder_SZ", "", null], "defargs": ["Feuermelder_SZ", "fhempy", "ble_monitor", "54:EF:44:E4:6A:81"], "NAME": "Feuermelder_SZ", "finished": 1, "returnval": "<script type=\"text/javascript\">\n        function displayHelp() {\n          var x = document.getElementById(\"fhempyReadme\");\n          if (x.style.display === \"none\") {\n            x.style.display = \"block\";\n          } else {\n            x.style.display = \"none\";\n          }\n          var off = $(\"#fhempyReadme\").position().top-20;\n          $('body, html').animate({scrollTop:off}, 500);\n        }\n        \n        $(document).ready(function() {\n          $(\"#content\").append('<div class=\"makeTable help\" id=\"fhempyReadme\"></div>');\n          $(\"#fhempyReadme\").html(`<h1>ble_monitor</h1>\n\n<p>Monitor BLE devices (trackers, toothbrushes, body scales, ...) passively.</p>\n\n<p>List of supported devices: https://custom-components.github.io/ble_monitor/devices</p>\n\n<h1>Usage</h1>\n\n<p><code>\nsudo setcap 'cap_net_raw,cap_net_admin+eip' \\`readlink -f \\\\\\`which python3\\\\\\`\\`\n</code></p>\n\n<p><code>\ndefine my_body_scale fhempy ble_monitor MAC\n</code></p>\n`);\n          document.getElementById(\"fhempyReadme\").style.display = \"none\";\n\n          var helpCmdStr = '{}';\n          var helpCmdJson = JSON.parse(helpCmdStr);\n          $(\"select.set\").change(helpSetAction);\n          function\n          helpSetAction(){\n            var cmd = $(\"select.set\").val()\n            if(helpCmdJson[cmd] && helpCmdJson[cmd].help) {\n              if (document.getElementById(\"idCmdHelp\")===null) {\n                $('<div id=\"idCmdHelp\" class=\"makeTable help\"></div>')\n                      .insertBefore(\"div.makeTable.internals\");\n              }\n              $(\"div#idCmdHelp\").html(helpCmdJson[cmd].help + \"<br>\");\n            } else {\n              $(\"div#idCmdHelp\").remove();\n            }\n          }\n          helpSetAction();\n\n          var helpAttrStr = '{\"hci_interface\": {\"help\": \"{0: \\\\\\\"04:ED:33:FA:53:2E\\\\\\\"}\"}}';\n          var helpAttrJson = JSON.parse(helpAttrStr);\n          $(\"select.attr\").change(helpAttrAction);\n          function\n          helpAttrAction(){\n            var cmd = $(\"select.attr\").val()\n            if(helpAttrJson[cmd] && helpAttrJson[cmd].help) {\n              if (document.getElementById(\"idAttrHelp\")===null) {\n                $('<div id=\"idAttrHelp\" class=\"makeTable help\"></div>')\n                      .insertBefore(\"div.makeTable.attributes\");\n              }\n              $(\"div#idAttrHelp\").html(helpAttrJson[cmd].help + \"<br>\");\n            } else {\n              $(\"div#idAttrHelp\").remove();\n            }\n          }\n          helpAttrAction();\n\n          var helpLink = document.getElementById(\"content\")\n            .getElementsByClassName(\"detLink devSpecHelp\");\n          helpLink[0].innerHTML = '<div class=\"detLink devSpecHelp\"><a href=\"#\" onclick=\"displayHelp();return false;\">Device specific help</a></div>';\n        });\n        </script>"}
2022.02.18 15:25:10 5: BindingsIo (local_pybinding): QUEUE: start handling - 1
2022.02.18 15:25:10 5: processMessage: {"PYTHONTYPE": "ble_monitor", "FHEMPYTYPE": "ble_monitor", "function": "FW_detailFn", "defargsh": {}, "msgtype": "function", "id": "12696733", "argsh": {}, "args": ["WEB", "Feuermelder_SZ", "", null], "defargs": ["Feuermelder_SZ", "fhempy", "ble_monitor", "54:EF:44:E4:6A:81"], "NAME": "Feuermelder_SZ", "finished": 1, "returnval": "<script type=\"text/javascript\">\n        function displayHelp() {\n          var x = document.getElementById(\"fhempyReadme\");\n          if (x.style.display === \"none\") {\n            x.style.display = \"block\";\n          } else {\n            x.style.display = \"none\";\n          }\n          var off = $(\"#fhempyReadme\").position().top-20;\n          $('body, html').animate({scrollTop:off}, 500);\n        }\n        \n        $(document).ready(function() {\n          $(\"#content\").append('<div class=\"makeTable help\" id=\"fhempyReadme\"></div>');\n          $(\"#fhempyReadme\").html(`<h1>ble_monitor</h1>\n\n<p>Monitor BLE devices (trackers, toothbrushes, body scales, ...) passively.</p>\n\n<p>List of supported devices: https://custom-components.github.io/ble_monitor/devices</p>\n\n<h1>Usage</h1>\n\n<p><code>\nsudo setcap 'cap_net_raw,cap_net_admin+eip' \\`readlink -f \\\\\\`which python3\\\\\\`\\`\n</code></p>\n\n<p><code>\ndefine my_body_scale fhempy ble_monitor MAC\n</code></p>\n`);\n          document.getElementById(\"fhempyReadme\").style.display = \"none\";\n\n          var helpCmdStr = '{}';\n          var helpCmdJson = JSON.parse(helpCmdStr);\n          $(\"select.set\").change(helpSetAction);\n          function\n          helpSetAction(){\n            var cmd = $(\"select.set\").val()\n            if(helpCmdJson[cmd] && helpCmdJson[cmd].help) {\n              if (document.getElementById(\"idCmdHelp\")===null) {\n                $('<div id=\"idCmdHelp\" class=\"makeTable help\"></div>')\n                      .insertBefore(\"div.makeTable.internals\");\n              }\n              $(\"div#idCmdHelp\").html(helpCmdJson[cmd].help + \"<br>\");\n            } else {\n              $(\"div#idCmdHelp\").remove();\n            }\n          }\n          helpSetAction();\n\n          var helpAttrStr = '{\"hci_interface\": {\"help\": \"{0: \\\\\\\"04:ED:33:FA:53:2E\\\\\\\"}\"}}';\n          var helpAttrJson = JSON.parse(helpAttrStr);\n          $(\"select.attr\").change(helpAttrAction);\n          function\n          helpAttrAction(){\n            var cmd = $(\"select.attr\").val()\n            if(helpAttrJson[cmd] && helpAttrJson[cmd].help) {\n              if (document.getElementById(\"idAttrHelp\")===null) {\n                $('<div id=\"idAttrHelp\" class=\"makeTable help\"></div>')\n                      .insertBefore(\"div.makeTable.attributes\");\n              }\n              $(\"div#idAttrHelp\").html(helpAttrJson[cmd].help + \"<br>\");\n            } else {\n              $(\"div#idAttrHelp\").remove();\n            }\n          }\n          helpAttrAction();\n\n          var helpLink = document.getElementById(\"content\")\n            .getElementsByClassName(\"detLink devSpecHelp\");\n          helpLink[0].innerHTML = '<div class=\"detLink devSpecHelp\"><a href=\"#\" onclick=\"displayHelp();return false;\">Device specific help</a></div>';\n        });\n        </script>"}
2022.02.18 15:25:10 5: BindingsIo (local_pybinding): QUEUE: finished handling - 0
2022.02.18 15:25:10 4: BindingsIo (local_pybinding): end fhempyFunction: Feuermelder_SZ => FW_detailFn (12696733) - result: <script type="text/javascript">
        function displayHelp() {
          var x = document.getElementById("fhempyReadme");
          if (x.style.display === "none") {
            x.style.display = "block";
          } else {
            x.style.display = "none";
          }
          var off = $("#fhempyReadme").position().top-20;
          $('body, html').animate({scrollTop:off}, 500);
        }
       
        $(document).ready(function() {
          $("#content").append('<div class="makeTable help" id="fhempyReadme"></div>');
          $("#fhempyReadme").html(`<h1>ble_monitor</h1>

<p>Monitor BLE devices (trackers, toothbrushes, body scales, ...) passively.</p>

<p>List of supported devices: https://custom-components.github.io/ble_monitor/devices</p>

<h1>Usage</h1>

<p><code>
sudo setcap 'cap_net_raw,cap_net_admin+eip' \`readlink -f \\\`which python3\\\`\`
</code></p>

<p><code>
define my_body_scale fhempy ble_monitor MAC
</code></p>
`);
          document.getElementById("fhempyReadme").style.display = "none";

          var helpCmdStr = '{}';
          var helpCmdJson = JSON.parse(helpCmdStr);
          $("select.set").change(helpSetAction);
          function
          helpSetAction(){
            var cmd = $("select.set").val()
            if(helpCmdJson[cmd] && helpCmdJson[cmd].help) {
              if (document.getElementById("idCmdHelp")===null) {
                $('<div id="idCmdHelp" class="makeTable help"></div>')
                      .insertBefore("div.makeTable.internals");
              }
              $("div#idCmdHelp").html(helpCmdJson[cmd].help + "<br>");
            } else {
              $("div#idCmdHelp").remove();
            }
          }
          helpSetAction();

          var helpAttrStr = '{"hci_interface": {"help": "{0: \\\"04:ED:33:FA:53:2E\\\"}"}}';
          var helpAttrJson = JSON.parse(helpAttrStr);
          $("select.attr").change(helpAttrAction);
          function
          helpAttrAction(){
            var cmd = $("select.attr").val()
            if(helpAttrJson[cmd] && helpAttrJson[cmd].help) {
              if (document.getElementById("idAttrHelp")===null) {
                $('<div id="idAttrHelp" class="makeTable help"></div>')
                      .insertBefore("div.makeTable.attributes");
              }
              $("div#idAttrHelp").html(helpAttrJson[cmd].help + "<br>");
            } else {
              $("div#idAttrHelp").remove();
            }
          }
          helpAttrAction();

          var helpLink = document.getElementById("content")
            .getElementsByClassName("detLink devSpecHelp");
          helpLink[0].innerHTML = '<div class="detLink devSpecHelp"><a href="#" onclick="displayHelp();return false;">Device specific help</a></div>';
        });
        </script>
2022.02.18 15:25:10 4: BindingsIo (local_pybinding): start fhempyFunction: Feuermelder_SZ => Set (30967999)
2022.02.18 15:25:10 4: BindingsIo (local_pybinding): <<< WS: {"defargsh":{},"msgtype":"function","function":"Set","PYTHONTYPE":"ble_monitor","FHEMPYTYPE":"ble_monitor","id":"30967999","argsh":{},"args":["Feuermelder_SZ","?"],"NAME":"Feuermelder_SZ","defargs":["Feuermelder_SZ","fhempy","ble_monitor","54:EF:44:E4:6A:81"]}
2022.02.18 15:25:10 5: DevIo_SimpleWrite local_pybinding: 7b226465666172677368223a7b7d2c226d736774797065223a2266756e6374696f6e222c2266756e6374696f6e223a22536574222c22505954484f4e54595045223a22626c655f6d6f6e69746f72222c224648454d505954595045223a22626c655f6d6f6e69746f72222c226964223a223330393637393939222c226172677368223a7b7d2c2261726773223a5b2246657565726d656c6465725f535a222c223f225d2c224e414d45223a2246657565726d656c6465725f535a222c2264656661726773223a5b2246657565726d656c6465725f535a222c226668656d7079222c22626c655f6d6f6e69746f72222c2235343a45463a34343a45343a36413a3831225d7d
2022.02.18 15:25:10 5: BindingsIo (local_pybinding): DevIo_SimpleRead
2022.02.18 15:25:10 5: BindingsIo (local_pybinding): DevIo_SimpleRead WithTimeout
2022.02.18 15:25:10 4: BindingsIo (local_pybinding): >>> WS: {"defargsh": {}, "msgtype": "function", "function": "Set", "PYTHONTYPE": "ble_monitor", "FHEMPYTYPE": "ble_monitor", "id": "30967999", "argsh": {}, "args": ["Feuermelder_SZ", "?"], "NAME": "Feuermelder_SZ", "defargs": ["Feuermelder_SZ", "fhempy", "ble_monitor", "54:EF:44:E4:6A:81"], "finished": 1, "returnval": "Unknown argument ?, choose one of "}
2022.02.18 15:25:10 5: BindingsIo (local_pybinding): QUEUE: start handling - 1
2022.02.18 15:25:10 5: processMessage: {"defargsh": {}, "msgtype": "function", "function": "Set", "PYTHONTYPE": "ble_monitor", "FHEMPYTYPE": "ble_monitor", "id": "30967999", "argsh": {}, "args": ["Feuermelder_SZ", "?"], "NAME": "Feuermelder_SZ", "defargs": ["Feuermelder_SZ", "fhempy", "ble_monitor", "54:EF:44:E4:6A:81"], "finished": 1, "returnval": "Unknown argument ?, choose one of "}
2022.02.18 15:25:10 5: BindingsIo (local_pybinding): QUEUE: finished handling - 0
2022.02.18 15:25:10 4: BindingsIo (local_pybinding): end fhempyFunction: Feuermelder_SZ => Set (30967999) - result: Unknown argument ?, choose one of
2022.02.18 15:25:10 4: BindingsIo (local_pybinding): start fhempyFunction: Feuermelder_SZ => Get (48736647)
2022.02.18 15:25:10 4: BindingsIo (local_pybinding): <<< WS: {"argsh":{},"args":["Feuermelder_SZ","?"],"id":"48736647","NAME":"Feuermelder_SZ","defargs":["Feuermelder_SZ","fhempy","ble_monitor","54:EF:44:E4:6A:81"],"msgtype":"function","defargsh":{},"FHEMPYTYPE":"ble_monitor","PYTHONTYPE":"ble_monitor","function":"Get"}
2022.02.18 15:25:10 5: DevIo_SimpleWrite local_pybinding: 7b226172677368223a7b7d2c2261726773223a5b2246657565726d656c6465725f535a222c223f225d2c226964223a223438373336363437222c224e414d45223a2246657565726d656c6465725f535a222c2264656661726773223a5b2246657565726d656c6465725f535a222c226668656d7079222c22626c655f6d6f6e69746f72222c2235343a45463a34343a45343a36413a3831225d2c226d736774797065223a2266756e6374696f6e222c226465666172677368223a7b7d2c224648454d505954595045223a22626c655f6d6f6e69746f72222c22505954484f4e54595045223a22626c655f6d6f6e69746f72222c2266756e6374696f6e223a22476574227d
2022.02.18 15:25:10 5: BindingsIo (local_pybinding): DevIo_SimpleRead
2022.02.18 15:25:10 5: BindingsIo (local_pybinding): DevIo_SimpleRead WithTimeout
2022.02.18 15:25:10 4: BindingsIo (local_pybinding): >>> WS: {"argsh": {}, "args": ["Feuermelder_SZ", "?"], "id": "48736647", "NAME": "Feuermelder_SZ", "defargs": ["Feuermelder_SZ", "fhempy", "ble_monitor", "54:EF:44:E4:6A:81"], "msgtype": "function", "defargsh": {}, "FHEMPYTYPE": "ble_monitor", "PYTHONTYPE": "ble_monitor", "function": "Get", "finished": 1, "returnval": ""}
2022.02.18 15:25:10 5: BindingsIo (local_pybinding): QUEUE: start handling - 1
2022.02.18 15:25:10 5: processMessage: {"argsh": {}, "args": ["Feuermelder_SZ", "?"], "id": "48736647", "NAME": "Feuermelder_SZ", "defargs": ["Feuermelder_SZ", "fhempy", "ble_monitor", "54:EF:44:E4:6A:81"], "msgtype": "function", "defargsh": {}, "FHEMPYTYPE": "ble_monitor", "PYTHONTYPE": "ble_monitor", "function": "Get", "finished": 1, "returnval": ""}
2022.02.18 15:25:10 5: BindingsIo (local_pybinding): QUEUE: finished handling - 0
2022.02.18 15:25:10 4: BindingsIo (local_pybinding): end fhempyFunction: Feuermelder_SZ => Get (48736647) - result:
2022.02.18 15:25:10 4: WEB: /fhem?detail=Feuermelder_SZ&fw_id=1144 / RL:9264 / text/html; charset=UTF-8 / Content-Encoding: gzip
/ Cache-Control: no-cache, no-store, must-revalidate

[dominik]

Achso, bitte fhempy Log, hatte ich vergessen zu schreiben.

[Nighthawk]

Code Auswählen Erweitern

2022-02-18 16:08:40,885 - INFO     - fhempy.lib.fhem_pythonbinding: Starting fhempy...
2022-02-18 16:08:40,891 - INFO     - fhempy.lib.fhem_pythonbinding: Waiting for FHEM connection
2022-02-18 16:08:40,912 - INFO     - websockets.server: server listening on 0.0.0.0:15733
2022-02-18 16:08:50,686 - INFO     - websockets.server: connection open
2022-02-18 16:08:50,686 - INFO     - fhempy.lib.fhem_pythonbinding: Incoming FHEM connection: 127.0.0.1
2022-02-18 16:08:50,744 - WARNING  - zeroconf: Error with socket 11 (('192.168.42.0', 5353))): [Errno 126] Required key not available
Traceback (most recent call last):
  File "/usr/lib/python3.9/asyncio/selector_events.py", line 1056, in sendto
    self._sock.sendto(data, addr)
OSError: [Errno 126] Required key not available
2022-02-18 16:08:51,284 - DEBUG    - Feuermelder_SZ: BLE monitor stopped
2022-02-18 16:08:51,284 - DEBUG    - Feuermelder_SZ: Spawning HCIdump thread
2022-02-18 16:08:51,285 - DEBUG    - Feuermelder_SZ: HCIdump thread: Init
2022-02-18 16:08:51,285 - INFO     - Feuermelder_SZ: Attention! Option report_unknown is enabled for Off sensors, be ready for a huge output
2022-02-18 16:08:51,285 - DEBUG    - Feuermelder_SZ: 0 encryptors mac:key pairs loaded
2022-02-18 16:08:51,285 - DEBUG    - Feuermelder_SZ: sensor whitelist: [54:EF:44:E4:6A:81]
2022-02-18 16:08:51,285 - DEBUG    - Feuermelder_SZ: 1 sensor whitelist item(s) loaded
2022-02-18 16:08:51,285 - DEBUG    - Feuermelder_SZ: 0 device tracker(s) being monitored
2022-02-18 16:08:51,285 - DEBUG    - Feuermelder_SZ: HCIdump thread: Run
2022-02-18 16:08:51,286 - DEBUG    - Feuermelder_SZ: HCIdump thread: BLEScanRequester._initialized is False for hci0,  waiting for connection...
2022-02-18 16:08:51,289 - DEBUG    - Feuermelder_SZ: HCIdump thread: joining
Exception in thread Thread-1:
Traceback (most recent call last):
  File "/usr/lib/python3.9/threading.py", line 973, in _bootstrap_inner
    self.run()
  File "/opt/fhem/.local/lib/python3.9/site-packages/fhempy/lib/ble_monitor/hcidump.py", line 183, in run
    self._event_loop.run_until_complete(
  File "/usr/lib/python3.9/asyncio/base_events.py", line 640, in run_until_complete
    raise RuntimeError('Event loop stopped before Future completed.')
RuntimeError: Event loop stopped before Future completed.
2022-02-18 16:08:51,290 - DEBUG    - Feuermelder_SZ: HCIdump thread: joined
2022-02-18 16:08:51,291 - DEBUG    - Feuermelder_SZ: BLE monitor stopped
2022-02-18 16:08:51,291 - DEBUG    - Feuermelder_SZ: Spawning HCIdump thread
2022-02-18 16:08:51,291 - DEBUG    - Feuermelder_SZ: HCIdump thread: Init
2022-02-18 16:08:51,291 - INFO     - Feuermelder_SZ: Attention! Option report_unknown is enabled for Off sensors, be ready for a huge output
2022-02-18 16:08:51,291 - DEBUG    - Feuermelder_SZ: 0 encryptors mac:key pairs loaded
2022-02-18 16:08:51,291 - DEBUG    - Feuermelder_SZ: sensor whitelist: [54:EF:44:E4:6A:81, 54:EF:44:E3:E6:64]
2022-02-18 16:08:51,291 - DEBUG    - Feuermelder_SZ: 2 sensor whitelist item(s) loaded
2022-02-18 16:08:51,291 - DEBUG    - Feuermelder_SZ: 0 device tracker(s) being monitored
2022-02-18 16:08:51,291 - DEBUG    - Feuermelder_SZ: HCIdump thread: Run
2022-02-18 16:08:51,293 - DEBUG    - Feuermelder_SZ: HCIdump thread: BLEScanRequester._initialized is False for hci0,  waiting for connection...
2022-02-18 16:08:51,305 - DEBUG    - Feuermelder_SZ: HCIdump thread: joining
Exception in thread Thread-2:
Traceback (most recent call last):
  File "/usr/lib/python3.9/threading.py", line 973, in _bootstrap_inner
    self.run()
  File "/opt/fhem/.local/lib/python3.9/site-packages/fhempy/lib/ble_monitor/hcidump.py", line 183, in run
    self._event_loop.run_until_complete(
  File "/usr/lib/python3.9/asyncio/base_events.py", line 640, in run_until_complete
    raise RuntimeError('Event loop stopped before Future completed.')
RuntimeError: Event loop stopped before Future completed.
2022-02-18 16:08:51,306 - DEBUG    - Feuermelder_SZ: HCIdump thread: joined
2022-02-18 16:08:51,306 - DEBUG    - Feuermelder_SZ: BLE monitor stopped
2022-02-18 16:08:51,306 - DEBUG    - Feuermelder_SZ: Spawning HCIdump thread
2022-02-18 16:08:51,306 - DEBUG    - Feuermelder_SZ: HCIdump thread: Init
2022-02-18 16:08:51,307 - INFO     - Feuermelder_SZ: Attention! Option report_unknown is enabled for Off sensors, be ready for a huge output
2022-02-18 16:08:51,307 - DEBUG    - Feuermelder_SZ: 0 encryptors mac:key pairs loaded
2022-02-18 16:08:51,307 - DEBUG    - Feuermelder_SZ: sensor whitelist: [54:EF:44:E4:6A:81, 54:EF:44:E3:E6:64, 54:EF:44:E4:72:D1]
2022-02-18 16:08:51,307 - DEBUG    - Feuermelder_SZ: 3 sensor whitelist item(s) loaded
2022-02-18 16:08:51,307 - DEBUG    - Feuermelder_SZ: 0 device tracker(s) being monitored
2022-02-18 16:08:51,307 - DEBUG    - Feuermelder_SZ: HCIdump thread: Run
2022-02-18 16:08:51,309 - DEBUG    - Feuermelder_SZ: HCIdump thread: BLEScanRequester._initialized is False for hci0,  waiting for connection...
2022-02-18 16:08:51,317 - DEBUG    - Feuermelder_SZ: HCIdump thread: joining
Exception in thread Thread-3:
Traceback (most recent call last):
  File "/usr/lib/python3.9/threading.py", line 973, in _bootstrap_inner
    self.run()
  File "/opt/fhem/.local/lib/python3.9/site-packages/fhempy/lib/ble_monitor/hcidump.py", line 183, in run
    self._event_loop.run_until_complete(
  File "/usr/lib/python3.9/asyncio/base_events.py", line 640, in run_until_complete
    raise RuntimeError('Event loop stopped before Future completed.')
RuntimeError: Event loop stopped before Future completed.
2022-02-18 16:08:51,318 - DEBUG    - Feuermelder_SZ: HCIdump thread: joined
2022-02-18 16:08:51,318 - DEBUG    - Feuermelder_SZ: BLE monitor stopped
2022-02-18 16:08:51,318 - DEBUG    - Feuermelder_SZ: Spawning HCIdump thread
2022-02-18 16:08:51,318 - DEBUG    - Feuermelder_SZ: HCIdump thread: Init
2022-02-18 16:08:51,319 - INFO     - Feuermelder_SZ: Attention! Option report_unknown is enabled for Off sensors, be ready for a huge output
2022-02-18 16:08:51,319 - DEBUG    - Feuermelder_SZ: 0 encryptors mac:key pairs loaded
2022-02-18 16:08:51,319 - DEBUG    - Feuermelder_SZ: sensor whitelist: [54:EF:44:E4:6A:81, 54:EF:44:E3:E6:64, 54:EF:44:E4:72:D1, FD:32:B5:9C:4F:CC]
2022-02-18 16:08:51,319 - DEBUG    - Feuermelder_SZ: 4 sensor whitelist item(s) loaded
2022-02-18 16:08:51,319 - DEBUG    - Feuermelder_SZ: 0 device tracker(s) being monitored
2022-02-18 16:08:51,319 - DEBUG    - Feuermelder_SZ: HCIdump thread: Run
2022-02-18 16:08:51,320 - DEBUG    - Feuermelder_SZ: HCIdump thread: BLEScanRequester._initialized is False for hci0,  waiting for connection...
2022-02-18 16:08:51,326 - ERROR    - asyncio: Task was destroyed but it is pending!
task: <Task pending name='Task-62' coro=<Event.wait() done, defined at /usr/lib/python3.9/asyncio/locks.py:213> wait_for=<Future pending cb=[<TaskWakeupMethWrapper object at 0x7faff51f09a0>()]> cb=[_release_waiter(<Future pendi...ff51f0850>()]>)() at /usr/lib/python3.9/asyncio/tasks.py:416]>
2022-02-18 16:08:51,327 - ERROR    - asyncio: Task was destroyed but it is pending!
task: <Task pending name='Task-67' coro=<Event.wait() done, defined at /usr/lib/python3.9/asyncio/locks.py:213> wait_for=<Future pending cb=[<TaskWakeupMethWrapper object at 0x7faff51f06a0>()]> cb=[_release_waiter(<Future pendi...ff51f0970>()]>)() at /usr/lib/python3.9/asyncio/tasks.py:416]>
2022-02-18 16:08:51,327 - ERROR    - asyncio: Task was destroyed but it is pending!
task: <Task pending name='Task-80' coro=<Event.wait() done, defined at /usr/lib/python3.9/asyncio/locks.py:213> wait_for=<Future pending cb=[<TaskWakeupMethWrapper object at 0x7faff51f0700>()]> cb=[_release_waiter(<Future pendi...ff521c0a0>()]>)() at /usr/lib/python3.9/asyncio/tasks.py:416]>
2022-02-18 16:08:51,412 - DEBUG    - Feuermelder_SZ: HCIdump thread: connected to hci0
2022-02-18 16:08:51,412 - DEBUG    - Feuermelder_SZ: HCIdump thread: BLEScanRequester._initialized is True for hci0,  connection established, send_scan_request succeeded.
2022-02-18 16:08:51,413 - DEBUG    - Feuermelder_SZ: HCIdump thread: BLEScanRequester._initialized is True for hci0,  waiting for connection...
2022-02-18 16:08:51,413 - DEBUG    - Feuermelder_SZ: HCIdump thread: connected to hci0
Exception in thread Thread-4:
Traceback (most recent call last):
  File "/usr/lib/python3.9/threading.py", line 973, in _bootstrap_inner
    self.run()
  File "/opt/fhem/.local/lib/python3.9/site-packages/fhempy/lib/ble_monitor/hcidump.py", line 196, in run
    self._event_loop.run_until_complete(
  File "/usr/lib/python3.9/asyncio/base_events.py", line 642, in run_until_complete
    return future.result()
  File "/opt/fhem/.local/lib/python3.9/site-packages/aioblescan/aioblescan.py", line 1806, in send_scan_request
    if self._use_ext_scan():
  File "/opt/fhem/.local/lib/python3.9/site-packages/aioblescan/aioblescan.py", line 1791, in _use_ext_scan
    return (self._supported_commands[37] & 0x60) == 0x60
TypeError: 'NoneType' object is not subscriptable


[dominik]

Kannst du bitte mal nur das eine Device versuchen welches Probleme macht? Bitte alle anderen Devices löschen und dann fhempy neu starten. Poste dann bitte wieder das fhempy Log.

Ich habe hier was dazu gefunden:
https://github.com/custom-components/ble_monitor/issues/388

Nur dürfte es dort ein Hardware Issue sein. Wenn bei dir jedoch 1 Device funktioniert, ist es vielleicht doch kein Hardware Issue. Lass mich bitte auch wissen was für ein System du hast.

[Nighthawk]

Hallo Dominik,

mein FHEM System ist ein NUC ähnlicher x86 Computer mit Ubuntu 21.10.
Wie geschrieben funktioniert das miscale ohne probleme.

Die Feuermelder geben aber keinen Mucks an FHEM ab:

Code Auswählen Erweitern

2022-02-19 14:35:44,672 - INFO     - fhempy.lib.fhem_pythonbinding: Starting fhempy...
2022-02-19 14:35:44,679 - INFO     - fhempy.lib.fhem_pythonbinding: Waiting for FHEM connection
2022-02-19 14:35:44,707 - INFO     - websockets.server: server listening on 0.0.0.0:15733
2022-02-19 14:35:54,771 - INFO     - websockets.server: connection open
2022-02-19 14:35:54,772 - INFO     - fhempy.lib.fhem_pythonbinding: Incoming FHEM connection: 127.0.0.1
2022-02-19 14:35:54,838 - WARNING  - zeroconf: Error with socket 13 (('192.168.42.0', 5353))): [Errno 126] Required key not available
Traceback (most recent call last):
  File "/usr/lib/python3.9/asyncio/selector_events.py", line 1056, in sendto
    self._sock.sendto(data, addr)
OSError: [Errno 126] Required key not available
2022-02-19 14:35:55,322 - DEBUG    - Feuermelder_SZ: BLE monitor stopped
2022-02-19 14:35:55,322 - DEBUG    - Feuermelder_SZ: Spawning HCIdump thread
2022-02-19 14:35:55,322 - DEBUG    - Feuermelder_SZ: HCIdump thread: Init
2022-02-19 14:35:55,322 - INFO     - Feuermelder_SZ: Attention! Option report_unknown is enabled for Off sensors, be ready for a huge output
2022-02-19 14:35:55,322 - DEBUG    - Feuermelder_SZ: 0 encryptors mac:key pairs loaded
2022-02-19 14:35:55,322 - DEBUG    - Feuermelder_SZ: sensor whitelist: [54:EF:44:E4:6A:81]
2022-02-19 14:35:55,322 - DEBUG    - Feuermelder_SZ: 1 sensor whitelist item(s) loaded
2022-02-19 14:35:55,323 - DEBUG    - Feuermelder_SZ: 0 device tracker(s) being monitored
2022-02-19 14:35:55,323 - DEBUG    - Feuermelder_SZ: HCIdump thread: Run
2022-02-19 14:35:55,324 - DEBUG    - Feuermelder_SZ: HCIdump thread: BLEScanRequester._initialized is False for hci0,  waiting for connection...
2022-02-19 14:35:55,327 - DEBUG    - Feuermelder_SZ: HCIdump thread: connected to hci0
2022-02-19 14:35:55,328 - DEBUG    - Feuermelder_SZ: HCIdump thread: BLEScanRequester._initialized is True for hci0,  connection established, send_scan_request succeeded.
2022-02-19 14:35:55,328 - DEBUG    - Feuermelder_SZ: HCIdump thread: start main event_loop
2022-02-19 14:35:55,335 - DEBUG    - Feuermelder_SZ: HCIdump thread: joining
2022-02-19 14:35:55,336 - DEBUG    - Feuermelder_SZ: HCIdump thread: main event_loop stopped, finishing.
2022-02-19 14:35:55,336 - DEBUG    - Feuermelder_SZ: HCIdump thread: Run finished
2022-02-19 14:35:55,336 - DEBUG    - Feuermelder_SZ: HCIdump thread: joined
2022-02-19 14:35:55,336 - DEBUG    - Feuermelder_SZ: BLE monitor stopped
2022-02-19 14:35:55,336 - DEBUG    - Feuermelder_SZ: Spawning HCIdump thread
2022-02-19 14:35:55,336 - DEBUG    - Feuermelder_SZ: HCIdump thread: Init
2022-02-19 14:35:55,336 - INFO     - Feuermelder_SZ: Attention! Option report_unknown is enabled for Off sensors, be ready for a huge output
2022-02-19 14:35:55,337 - DEBUG    - Feuermelder_SZ: 0 encryptors mac:key pairs loaded
2022-02-19 14:35:55,337 - DEBUG    - Feuermelder_SZ: sensor whitelist: [54:EF:44:E4:6A:81, FD:32:B5:9C:4F:CC]
2022-02-19 14:35:55,337 - DEBUG    - Feuermelder_SZ: 2 sensor whitelist item(s) loaded
2022-02-19 14:35:55,337 - DEBUG    - Feuermelder_SZ: 0 device tracker(s) being monitored
2022-02-19 14:35:55,337 - DEBUG    - Feuermelder_SZ: HCIdump thread: Run
2022-02-19 14:35:55,338 - DEBUG    - Feuermelder_SZ: HCIdump thread: BLEScanRequester._initialized is False for hci0,  waiting for connection...
2022-02-19 14:35:55,350 - DEBUG    - Feuermelder_SZ: HCIdump thread: connected to hci0
2022-02-19 14:35:55,350 - DEBUG    - Feuermelder_SZ: HCIdump thread: BLEScanRequester._initialized is True for hci0,  connection established, send_scan_request succeeded.
2022-02-19 14:35:55,351 - DEBUG    - Feuermelder_SZ: HCIdump thread: BLEScanRequester._initialized is False for hci0,  waiting for connection...
2022-02-19 14:35:55,355 - DEBUG    - Feuermelder_SZ: HCIdump thread: connected to hci0
2022-02-19 14:35:55,355 - DEBUG    - Feuermelder_SZ: HCIdump thread: BLEScanRequester._initialized is True for hci0,  connection established, send_scan_request succeeded.
2022-02-19 14:35:55,356 - DEBUG    - Feuermelder_SZ: HCIdump thread: start main event_loop


[dominik]

Nimm bitte die miscale auch noch raus und probier dann nochmals.

[Nighthawk]

Code Auswählen Erweitern

2022-02-19 17:25:30,000 - INFO     - fhempy.lib.fhem_pythonbinding: Starting fhempy...
2022-02-19 17:25:30,006 - INFO     - fhempy.lib.fhem_pythonbinding: Waiting for FHEM connection
2022-02-19 17:25:30,026 - INFO     - websockets.server: server listening on 0.0.0.0:15733
2022-02-19 17:25:39,825 - INFO     - websockets.server: connection open
2022-02-19 17:25:39,826 - INFO     - fhempy.lib.fhem_pythonbinding: Incoming FHEM connection: 127.0.0.1
2022-02-19 17:25:39,875 - WARNING  - zeroconf: Error with socket 13 (('192.168.42.0', 5353))): [Errno 126] Required key not available
Traceback (most recent call last):
  File "/usr/lib/python3.9/asyncio/selector_events.py", line 1056, in sendto
    self._sock.sendto(data, addr)
OSError: [Errno 126] Required key not available
2022-02-19 17:25:40,281 - DEBUG    - Feuermelder_SZ: BLE monitor stopped
2022-02-19 17:25:40,281 - DEBUG    - Feuermelder_SZ: Spawning HCIdump thread
2022-02-19 17:25:40,281 - DEBUG    - Feuermelder_SZ: HCIdump thread: Init
2022-02-19 17:25:40,282 - INFO     - Feuermelder_SZ: Attention! Option report_unknown is enabled for Off sensors, be ready for a huge output
2022-02-19 17:25:40,282 - DEBUG    - Feuermelder_SZ: 0 encryptors mac:key pairs loaded
2022-02-19 17:25:40,282 - DEBUG    - Feuermelder_SZ: sensor whitelist: [54:EF:44:E4:6A:81]
2022-02-19 17:25:40,282 - DEBUG    - Feuermelder_SZ: 1 sensor whitelist item(s) loaded
2022-02-19 17:25:40,282 - DEBUG    - Feuermelder_SZ: 0 device tracker(s) being monitored
2022-02-19 17:25:40,282 - DEBUG    - Feuermelder_SZ: HCIdump thread: Run
2022-02-19 17:25:40,283 - DEBUG    - Feuermelder_SZ: HCIdump thread: BLEScanRequester._initialized is False for hci0,  waiting for connection...
2022-02-19 17:25:40,287 - DEBUG    - Feuermelder_SZ: HCIdump thread: connected to hci0
2022-02-19 17:25:40,287 - DEBUG    - Feuermelder_SZ: HCIdump thread: BLEScanRequester._initialized is True for hci0,  connection established, send_scan_request succeeded.
2022-02-19 17:25:40,287 - DEBUG    - Feuermelder_SZ: HCIdump thread: start main event_loop


[dominik]

Eine Frage, wie lange hast du gewartet beim Rauchmelder ob ein Signal kommt? Kann es sein, dass der nur alle 24h ein Signal schickt?

Ansonsten probier mal

Code Auswählen Erweitern

aioblescan -m MAC
dann siehst du ob der Rauchmelder Daten schickt.

[dominik]

Ich glaub ich hab den "Fehler", das Device sendet die Signale verschlüsselt.

Hol dir bitte mal wie hier beschrieben den BLE KEY:
https://github.com/PiotrMachowski/Xiaomi-cloud-tokens-extractor

In der Zwischenzeit baue ich die Nutzung des BLE KEYs im Modul ein.

[dominik]

Attribut encryption_key kann nun benutzt werden. Probier es bitte aus und lass mich wissen was es tut

[Nighthawk]

Hallo Dominik,

habe ich eingetragen, leider ohne sichtbaren Erfolg:

Code Auswählen Erweitern

2022-02-20 10:30:47,208 - INFO     - fhempy.lib.fhem_pythonbinding: Starting fhempy...
2022-02-20 10:30:47,214 - INFO     - fhempy.lib.fhem_pythonbinding: Waiting for FHEM connection
2022-02-20 10:30:47,235 - INFO     - websockets.server: server listening on 0.0.0.0:15733
^[[B^[[B2022-02-20 10:30:56,985 - INFO     - websockets.server: connection open
2022-02-20 10:30:56,985 - INFO     - fhempy.lib.fhem_pythonbinding: Incoming FHEM connection: 127.0.0.1
2022-02-20 10:30:57,034 - WARNING  - zeroconf: Error with socket 12 (('192.168.42.0', 5353))): [Errno 126] Required key not available
Traceback (most recent call last):
  File "/usr/lib/python3.9/asyncio/selector_events.py", line 1056, in sendto
    self._sock.sendto(data, addr)
OSError: [Errno 126] Required key not available
2022-02-20 10:30:57,414 - DEBUG    - Feuermelder_SZ: BLE monitor stopped
2022-02-20 10:30:57,414 - DEBUG    - Feuermelder_SZ: Spawning HCIdump thread
2022-02-20 10:30:57,415 - DEBUG    - Feuermelder_SZ: HCIdump thread: Init
2022-02-20 10:30:57,415 - INFO     - Feuermelder_SZ: Attention! Option report_unknown is enabled for Off sensors, be ready for a huge output
2022-02-20 10:30:57,415 - DEBUG    - Feuermelder_SZ: 1 encryptors mac:key pairs loaded
2022-02-20 10:30:57,415 - DEBUG    - Feuermelder_SZ: sensor whitelist: [54:EF:44:E4:6A:81]
2022-02-20 10:30:57,415 - DEBUG    - Feuermelder_SZ: 1 sensor whitelist item(s) loaded
2022-02-20 10:30:57,415 - DEBUG    - Feuermelder_SZ: 0 device tracker(s) being monitored
2022-02-20 10:30:57,416 - DEBUG    - Feuermelder_SZ: HCIdump thread: Run
2022-02-20 10:30:57,417 - DEBUG    - Feuermelder_SZ: HCIdump thread: BLEScanRequester._initialized is False for hci0,  waiting for connection...
2022-02-20 10:30:57,537 - DEBUG    - Feuermelder_SZ: HCIdump thread: connected to hci0
2022-02-20 10:30:57,537 - DEBUG    - Feuermelder_SZ: HCIdump thread: BLEScanRequester._initialized is True for hci0,  connection established, send_scan_request succeeded.
2022-02-20 10:30:57,537 - DEBUG    - Feuermelder_SZ: HCIdump thread: start main event_loop

Es scheint die Kommunikation zum fhempy gestört zu sein:

Code Auswählen Erweitern

Internals:
   CFGFN     
   DEF        ble_monitor 54:EF:44:E4:6A:81
   FHEMPYTYPE ble_monitor
   FUUID      6212098b-f33f-357a-600a-be94cbfd20595a89
   IODev      local_pybinding
   NAME       Feuermelder_SZ
   NR         729
   PYTHONTYPE ble_monitor
   STATE      fhempy server offline
   TYPE       fhempy
   Helper:
     DBLOG:
       state:
         logdb:
           TIME       1645349453.40834
           VALUE      fhempy server offline
   READINGS:
     2022-02-20 10:30:53   state           fhempy server offline
   args:
     Feuermelder_SZ
     fhempy
     ble_monitor
     54:EF:44:E4:6A:81
   argsh:
   hmccu:
Attributes:
   encryption_key *************************************
   verbose    5

[dominik]

Das ist ok, weil du fhempy neu gestartet hast und state noch nicht neu gesetzt wurde. Hast du es länger laufen lassen? Der schickt sicher max. 1x pro Stunde oder?

[Nighthawk]

Leideir weiterhin unverändert:

Code Auswählen Erweitern

Internals:
   CFGFN     
   DEF        ble_monitor 54:EF:44:E4:6A:81
   FHEMPYTYPE ble_monitor
   FUUID      6212098b-f33f-357a-600a-be94cbfd20595a89
   IODev      local_pybinding
   NAME       Feuermelder_SZ
   NR         729
   PYTHONTYPE ble_monitor
   STATE      fhempy server offline
   TYPE       fhempy
   Helper:
     DBLOG:
       state:
         logdb:
           TIME       1645349453.40834
           VALUE      fhempy server offline
   READINGS:
     2022-02-20 10:30:53   state           fhempy server offline
   args:
     Feuermelder_SZ
     fhempy
     ble_monitor
     54:EF:44:E4:6A:81
   argsh:
   hmccu:
Attributes:
   encryption_key 1d7890ac4bb35b1dcb1f7055d17ec174
   verbose    5

Der Rauchmelder kann dazu gezwungen werden Infos zu senden, wenn man die Tatse betätigt, sieht man auch beim bluetoothctl scan.

[dominik]

Eine Frage, wie lange hast du gewartet beim Rauchmelder ob ein Signal kommt? Kann es sein, dass der nur alle 24h ein Signal schickt?

Ansonsten probier mal

Code Auswählen Erweitern

aioblescan -m MAC
dann siehst du ob der Rauchmelder Daten schickt.

Dann probier bitte noch das hier um zu sehen ob das Signal ankommt.

[Nighthawk]

Sieht so aus als hätte aioblescan ein Problm mein hci0 anzusprechen, oder kann ich ihm das irgendwie beibiegen?

Code Auswählen Erweitern

Traceback (most recent call last):
  File "/usr/local/bin/aioblescan", line 8, in <module>
    sys.exit(main())
  File "/opt/fhem/.local/lib/python3.9/site-packages/aioblescan/__main__.py", line 176, in main
    mysocket = aiobs.create_bt_socket(opts.device)
  File "/opt/fhem/.local/lib/python3.9/site-packages/aioblescan/aioblescan.py", line 1760, in create_bt_socket
    raise exceptions[0]
OSError: [Errno 19] error while attempting to bind on interface 0: No such device

[dominik]

-D 1 als Argument mit angeben.

[Nighthawk]

Habe ich jetzt gestartet, aktuell kommt da nichts an (auch bei Tastendruck), ich lass es mal weiter laufen und beobachte es.

P.S.
bei der Definition der Mi Bodyscale kommt jetzt eine Fehlermeldung:

Code Auswählen Erweitern

Failed to load module miscale: Traceback (most recent call last):
  File "/opt/fhem/.local/lib/python3.9/site-packages/fhempy/lib/fhem_pythonbinding.py", line 291, in _onMessage
    await asyncio.wait_for(
  File "/usr/lib/python3.9/asyncio/tasks.py", line 481, in wait_for
    return fut.result()
  File "/opt/fhem/.local/lib/python3.9/site-packages/fhempy/lib/miscale/miscale.py", line 48, in Define
    self.blemonitor.register_device(self)
  File "/opt/fhem/.local/lib/python3.9/site-packages/fhempy/lib/ble_monitor/blemonitor.py", line 117, in register_device
    CONF_DEVICE_ENCRYPTION_KEY: fhemdevice.encryption_key(),
AttributeError: 'miscale' object has no attribute 'encryption_key'

[dominik]

Ist schon behoben, einfach ein Update machen.

Sehr eigenartig mit dem Feuermelder, kann man da in der Xiaomi App irgendwas einstellen?

[Nighthawk]

Also wenn ich mit bluetoothctl --> scan on sniffe, bekomme ich folgende Info beim Betätigen der Taste:

Code Auswählen Erweitern

[CHG] Device 54:EF:44:E2:AA:1D ServiceData Key: 0000fe95-0000-1000-8000-00805f9b34fb
[CHG] Device 54:EF:44:E2:AA:1D ServiceData Value:
  10 59 97 09 f4 1d aa e2 44 ef 54                 .Y......D.T     


In der App ist nur der Batteriestatus und Alarmstatus zu sehen, wenn ich es richtig interpritiere.


Nach dem update eben habe ich eine neue Fehlermeldung sowohl bei MiScale als auch beim Rauchmelder:

Code Auswählen Erweitern

Failed to execute function Define: Traceback (most recent call last): File "/opt/fhem/.local/lib/python3.9/site-packages/fhempy/lib/fhem_pythonbinding.py", line 379, in _onMessage ret = await asyncio.wait_for( File "/usr/lib/python3.9/asyncio/tasks.py", line 481, in wait_for return fut.result() File "/opt/fhem/.local/lib/python3.9/site-packages/fhempy/lib/miscale/miscale.py", line 48, in Define self.blemonitor.register_device(self) File "/opt/fhem/.local/lib/python3.9/site-packages/fhempy/lib/ble_monitor/blemonitor.py", line 121, in register_device self.update_hci_interface(fhemdevice.hci()) File "/opt/fhem/.local/lib/python3.9/site-packages/fhempy/lib/ble_monitor/blemonitor.py", line 131, in update_hci_interface self.config[CONF_BT_INTERFACE].append(BT_INTERFACES[fhem_dev.hci()]) KeyError: 0

[dominik]

Lass mal bei aioblescan die Mac weg und schau ob du dann auch ein Signal bekommst.

Der Fehler dürfte daran liegen, dass kein Bluetooth Adapter mehr erkannt wird - womöglich der Scan parallel dazu gelaufen?

Probier aber bitte aioblescan ohne Mac, wenn das geht, weiß ich wahrscheinlich wo der Fehler liegt.

[Nighthawk]

Auch ohne MAC ist leider nur Leere da..

Und der Fehler bleibt auch nach Neustart bestehen:

Code Auswählen Erweitern

Failed to execute function Define: Traceback (most recent call last):
  File "/opt/fhem/.local/lib/python3.9/site-packages/fhempy/lib/fhem_pythonbinding.py", line 379, in _onMessage
    ret = await asyncio.wait_for(
  File "/usr/lib/python3.9/asyncio/tasks.py", line 481, in wait_for
    return fut.result()
  File "/opt/fhem/.local/lib/python3.9/site-packages/fhempy/lib/miscale/miscale.py", line 48, in Define
    self.blemonitor.register_device(self)
  File "/opt/fhem/.local/lib/python3.9/site-packages/fhempy/lib/ble_monitor/blemonitor.py", line 121, in register_device
    self.update_hci_interface(fhemdevice.hci())
  File "/opt/fhem/.local/lib/python3.9/site-packages/fhempy/lib/ble_monitor/blemonitor.py", line 131, in update_hci_interface
    self.config[CONF_BT_INTERFACE].append(BT_INTERFACES[fhem_dev.hci()])
KeyError: 0

[dominik]

Funktionieren deine BT Interfaces noch?
Mach mal hciconfig -a und poste den Output.

[dominik]

Achja, teste bitte auch
sudo hcidump --raw hci
Und schau ob du ein Signal bekommst.

[Nighthawk]

Hmm, da scheint sich das BT Interface komplett verabschiedet zu haben, da kommt nichts mehr..
Ich schau mir das morgen genauer an, danke für deine Unterstützung!

[dominik]

sudo hciconfig hci0 down
sudo hciconfig hci0 reset
sudo hciconfig hci0 up

Hilft vielleicht.

[Nighthawk]

Hallo Dominik,

ich bin schon mal ein Stück weiter.
Das Bluetoothmodul hat sich komplett weggeklemmt, Abhilfe schaffte erst ein Abschalten der Stromzufuhr (ist wohl ein bekanntes Problem bei den integrierten Intel Modulen).

Nun bekomme ich beim Rauchmelder Daten rein, allerdings nur wenn kein encryption_key gesetzt ist.

Code Auswählen Erweitern

Internals:
   CFGFN     
   DEF        ble_monitor 54:EF:44:E4:6A:81
   FHEMPYTYPE ble_monitor
   FUUID      6214e574-f33f-357a-de6b-c20088c2b5cd74c1
   IODev      local_pybinding
   NAME       Rauchmelder
   NR         718
   PYTHONTYPE ble_monitor
   STATE      ???
   TYPE       fhempy
   Helper:
     DBLOG:
       data:
         logdb:
           TIME       1645536775.83793
           VALUE      0
       firmware:
         logdb:
           TIME       1645536775.83793
           VALUE      Xiaomi (MiBeacon V5 encrypted)
       mac:
         logdb:
           TIME       1645536775.83793
           VALUE      54EF44E46A81
       packet:
         logdb:
           TIME       1645536775.83793
           VALUE      239
       rssi:
         logdb:
           TIME       1645536775.83793
           VALUE      -70
       type:
         logdb:
           TIME       1645536775.83793
           VALUE      JTYJGD03MI
   READINGS:
     2022-02-22 14:32:55   data            0
     2022-02-22 14:32:55   firmware        Xiaomi (MiBeacon V5 encrypted)
     2022-02-22 14:32:55   mac             54EF44E46A81
     2022-02-22 14:32:55   packet          239
     2022-02-22 14:32:55   rssi            -70
     2022-02-22 14:32:55   type            JTYJGD03MI
   args:
     Rauchmelder
     fhempy
     ble_monitor
     54:EF:44:E4:6A:81
   argsh:
Attributes:


Wenn ich den encryption_key zu setzen versuche, bekomme ich folgende Fehlermeldung:

Code Auswählen Erweitern

Failed to execute function Attr: Traceback (most recent call last):
  File "/opt/fhem/.local/lib/python3.9/site-packages/fhempy/lib/fhem_pythonbinding.py", line 379, in _onMessage
    ret = await asyncio.wait_for(
  File "/usr/lib/python3.9/asyncio/tasks.py", line 481, in wait_for
    return fut.result()
  File "/opt/fhem/.local/lib/python3.9/site-packages/fhempy/lib/generic.py", line 169, in Attr
    return await utils.handle_attr(self._conf_attr, self, hash, args, argsh)
  File "/opt/fhem/.local/lib/python3.9/site-packages/fhempy/lib/utils.py", line 69, in handle_attr
    return await fct_call(hash)
  File "/opt/fhem/.local/lib/python3.9/site-packages/fhempy/lib/ble_monitor/ble_monitor.py", line 66, in set_attr_generic
    self.blemonitor.unregister_device(self)
  File "/opt/fhem/.local/lib/python3.9/site-packages/fhempy/lib/ble_monitor/blemonitor.py", line 135, in unregister_device
    self.fhem_devices[simple_mac].remove(fhemdevice)
ValueError: list.remove(x): x not in list

[dominik]

Sieht gut aus!

Ich habe gerade ein Update gemacht, probier es bitte nochmals.

[Nighthawk]

Hallo Dominik,

danke für das Update, jetzt lässt sich  der encryption_key zwar setzen, der state bleibt aber durchgängig bei "fhempy server offline".
fhempy ist aber online und auch die Mi Bodyscale funktioniert auch.

Gruß
Alex

[dominik]

Hi,
ok, fhempy offline sollte nicht weiter stören, da ich das state Reading aktuell danach nicht setze bleibt das offline stehen.
Kommen aber beim Aktivieren noch weitere Readings?

[Nighthawk]

Wie man unten an den Timestamps sehen kann, sind wohl Daten reingekommen, leider aber nicht die, die für mich interessant wären  (Battery und Alarm). Ich werd mir mal das Testspray holen um zu schauen ob im Alarmfall noch weitere Daten reinkommen.
Bei Battery wäre mir aber eine gewisse kontinuität schon sehr wichtig.

Code Auswählen Erweitern

Internals:
   DEF        ble_monitor 54:EF:44:E4:6A:81
   FHEMPYTYPE ble_monitor
   FUUID      6214e574-f33f-357a-de6b-c20088c2b5cd74c1
   IODev      local_pybinding
   NAME       Rauchmelder
   NR         600
   PYTHONTYPE ble_monitor
   STATE      fhempy server offline
   TYPE       fhempy
   Helper:
     DBLOG:
       packet:
         logdb:
           TIME       1645556485.30564
           VALUE      131
       rssi:
         logdb:
           TIME       1645556485.30564
           VALUE      -76
       state:
         logdb:
           TIME       1645552275.37297
           VALUE      fhempy server offline
   READINGS:
     2022-02-22 14:32:55   data            0
     2022-02-22 14:32:55   firmware        Xiaomi (MiBeacon V5 encrypted)
     2022-02-22 14:32:55   mac             54EF44E46A81
     2022-02-22 20:01:25   packet          131
     2022-02-22 20:01:25   rssi            -76
     2022-02-22 18:51:15   state           fhempy server offline
     2022-02-22 14:32:55   type            JTYJGD03MI
   args:
     Rauchmelder
     fhempy
     ble_monitor
     54:EF:44:E4:6A:81
   argsh:
Attributes:
   encryption_key ************************************


[dominik]

Die Timestamps sind aber wahrscheinlich noch vor dem encryption key, oder?
Also wenn in der App der Test ankommt, dann sollte das auch im ble_monitor kommen.

[Nighthawk]

Nein, encryption_key habe ich deutlich früher eingegeben, das war eine regelmäßige Botschaft.
Das Testspray ist bestellt, mal schauen was dabei kommt.

[dominik]

Weißt ob du die Werte nur mit encryption key gekommen sind oder auch schon vorher?

[Nighthawk]

Die beiden kamen sicher danach:

Code Auswählen Erweitern

2022-02-22 20:01:25   packet          131
     2022-02-22 20:01:25   rssi            -76

[dominik]

Lösche bitte mal die Readings mit deletereading auch schau ob dann noch was rein kommt.

[Nighthawk]

Hallo Dominik,

habe die Readings gelöscht, kurze Zeit später waren sie wieder gefüllt:

Code Auswählen Erweitern

Internals:
   DEF        ble_monitor 54:EF:44:E4:6A:81
   FHEMPYTYPE ble_monitor
   FUUID      6214e574-f33f-357a-de6b-c20088c2b5cd74c1
   IODev      local_pybinding
   NAME       Rauchmelder
   NR         600
   PYTHONTYPE ble_monitor
   STATE      fhempy server offline
   TYPE       fhempy
   Helper:
     DBLOG:
       data:
         logdb:
           TIME       1645595633.8223
           VALUE      0
       firmware:
         logdb:
           TIME       1645595633.8223
           VALUE      Xiaomi (MiBeacon V5 encrypted)
       mac:
         logdb:
           TIME       1645595633.8223
           VALUE      54EF44E46A81
       packet:
         logdb:
           TIME       1645597253.9167
           VALUE      183
       rssi:
         logdb:
           TIME       1645597253.9167
           VALUE      -73
       state:
         logdb:
           TIME       1645552275.37297
           VALUE      fhempy server offline
       type:
         logdb:
           TIME       1645595633.8223
           VALUE      JTYJGD03MI
   OLDREADINGS:
   READINGS:
     2022-02-23 06:53:53   data            0
     2022-02-23 06:53:53   firmware        Xiaomi (MiBeacon V5 encrypted)
     2022-02-23 06:53:53   mac             54EF44E46A81
     2022-02-23 07:20:53   packet          183
     2022-02-23 07:20:53   rssi            -73
     2022-02-23 06:53:53   type            JTYJGD03MI
   args:
     Rauchmelder
     fhempy
     ble_monitor
     54:EF:44:E4:6A:81
   argsh:
Attributes:
   encryption_key ****************

[dominik]

Hi,
ich habe mir nun den Code nochmals angesehen. Solange das Reading data 0 ist, wurden sonstige keine Daten geliefert. Wenn dort mal 1 steht, dann sollten auch weitere Readings da sein.

Es sollte auch der Batteriewert angezeigt werden, das wundert mich, dass das nicht passiert. Auch nach 24h nicht?

[Nighthawk]

Ja, leider auch nach 24h keine Änderung.

[dominik]

Wie sieht das in der App aus? Was und wann kommt da was an?

[Nighthawk]

In der App sehe ich leider keine Timestmaps, daher kann ich micht sagen wann dort was ankommt

[dominik]

Und wenn du den Test durchführst, kommt da eine Anzeige in der App?

[Nighthawk]

Hi Dominik,

nein, das einzige ws in der App zu sehen ist, ist dass das BT Symbol nicht mehr im "suchmodus" ist.

[Nighthawk]

Hallo Dominik,

das Spray ist da und ich habe es getestet, der Rauchmelder sendet fleißig Daten, dies ist auch mit aioblescan zu sehen:

Code Auswählen Erweitern

CI Event:
    code:
        3e
    length:
        41
    LE Meta:
        code:
            02
        Adv Report: 1
            Adv Report:
                ev type:
                    generic adv
                addr type:
                    public
                peer:
                    54:ef:44:e2:aa:1d
                length:
                    29
                    type:
                        flags
                    flags:
                        Simul LE - BR/EDR (Host): False
                        Simul LE - BR/EDR (Control.): False
                        BR/EDR Not Supported: True
                        LE General Disc.: True
                        LE Limited Disc.: False
                    type:
                        svc_data_16_bit_uuid
                    Advertised Data:
                        Service Data uuid:
                            fe:95
                        Adv Payload:
                            58:59:97:09:11:1d:aa:e2:44:ef:54:de:76:ac:7f:10:00:00:66:c1:cb:e9
                rssi:
                    -45
HCI Event:
    code:
        3e
    length:
        41
    LE Meta:
        code:
            02
        Adv Report: 1
            Adv Report:
                ev type:
                    generic adv
                addr type:
                    public
                peer:
                    54:ef:44:e2:aa:1d
                length:
                    29
                    type:
                        flags
                    flags:
                        Simul LE - BR/EDR (Host): False
                        Simul LE - BR/EDR (Control.): False
                        BR/EDR Not Supported: True
                        LE General Disc.: True
                        LE Limited Disc.: False
                    type:
                        svc_data_16_bit_uuid
                    Advertised Data:
                        Service Data uuid:
                            fe:95
                        Adv Payload:
                            58:59:97:09:11:1d:aa:e2:44:ef:54:de:76:ac:7f:10:00:00:66:c1:cb:e9
                rssi:
                    -54
HCI Event:
    code:
        3e
    length:
        41
    LE Meta:
        code:
            02
        Adv Report: 1
            Adv Report:
                ev type:
                    generic adv
                addr type:
                    public
                peer:
                    54:ef:44:e2:aa:1d
                length:
                    29
                    type:
                        flags
                    flags:
                        Simul LE - BR/EDR (Host): False
                        Simul LE - BR/EDR (Control.): False
                        BR/EDR Not Supported: True
                        LE General Disc.: True
                        LE Limited Disc.: False
                    type:
                        svc_data_16_bit_uuid
                    Advertised Data:
                        Service Data uuid:
                            fe:95
                        Adv Payload:
                            58:59:97:09:11:1d:aa:e2:44:ef:54:de:76:ac:7f:10:00:00:66:c1:cb:e9
                rssi:
                    -55
HCI Event:
    code:
        3e
    length:
        41
    LE Meta:
        code:
            02
        Adv Report: 1
            Adv Report:
                ev type:
                    generic adv
                addr type:
                    public
                peer:
                    54:ef:44:e2:aa:1d
                length:
                    29
                    type:
                        flags
                    flags:
                        Simul LE - BR/EDR (Host): False
                        Simul LE - BR/EDR (Control.): False
                        BR/EDR Not Supported: True
                        LE General Disc.: True
                        LE Limited Disc.: False
                    type:
                        svc_data_16_bit_uuid
                    Advertised Data:
                        Service Data uuid:
                            fe:95
                        Adv Payload:
                            58:59:97:09:11:1d:aa:e2:44:ef:54:de:76:ac:7f:10:00:00:66:c1:cb:e9
                rssi:
                    -50


Leider kommt im FHEM absolut nichts an.

Code Auswählen Erweitern

2022-02-26 10:44:04,466 - INFO     - fhempy.lib.fhem_pythonbinding: Starting fhempy...
2022-02-26 10:44:04,475 - INFO     - fhempy.lib.fhem_pythonbinding: Waiting for FHEM connection
2022-02-26 10:44:04,507 - INFO     - websockets.server: server listening on 0.0.0.0:15733
2022-02-26 10:44:24,606 - INFO     - websockets.server: connection open
2022-02-26 10:44:24,608 - INFO     - fhempy.lib.fhem_pythonbinding: Incoming FHEM connection: 127.0.0.1
2022-02-26 10:44:24,641 - WARNING  - zeroconf: Error with socket 14 (('192.168.42.0', 5353))): [Errno 126] Required key not available
Traceback (most recent call last):
  File "/usr/lib/python3.9/asyncio/selector_events.py", line 1056, in sendto
    self._sock.sendto(data, addr)
OSError: [Errno 126] Required key not available
2022-02-26 10:44:27,722 - DEBUG    - Rauchmelder: BLE monitor stopped
2022-02-26 10:44:27,723 - DEBUG    - Rauchmelder: Spawning HCIdump thread
2022-02-26 10:44:27,723 - DEBUG    - Rauchmelder: HCIdump thread: Init
2022-02-26 10:44:27,723 - INFO     - Rauchmelder: Attention! Option report_unknown is enabled for Off sensors, be ready for a huge output
2022-02-26 10:44:27,723 - DEBUG    - Rauchmelder: 1 encryptors mac:key pairs loaded
2022-02-26 10:44:27,723 - DEBUG    - Rauchmelder: sensor whitelist: [54:EF:44:E2:AA:1D]
2022-02-26 10:44:27,723 - DEBUG    - Rauchmelder: 1 sensor whitelist item(s) loaded
2022-02-26 10:44:27,723 - DEBUG    - Rauchmelder: 0 device tracker(s) being monitored
2022-02-26 10:44:27,724 - DEBUG    - Rauchmelder: HCIdump thread: Run
2022-02-26 10:44:27,725 - DEBUG    - Rauchmelder: HCIdump thread: BLEScanRequester._initialized is False for hci0,  waiting for connection...
2022-02-26 10:44:27,846 - DEBUG    - Rauchmelder: HCIdump thread: connected to hci0
2022-02-26 10:44:27,846 - DEBUG    - Rauchmelder: HCIdump thread: BLEScanRequester._initialized is True for hci0,  connection established, send_scan_request succeeded.
2022-02-26 10:44:27,847 - DEBUG    - Rauchmelder: HCIdump thread: start main event_loop



Atuell funktioniert auch die Bodyscale nicht mehr.

Code Auswählen Erweitern

2022-02-26 10:48:09,131 - INFO     - fhempy.lib.fhem_pythonbinding: Starting fhempy...
2022-02-26 10:48:09,138 - INFO     - fhempy.lib.fhem_pythonbinding: Waiting for FHEM connection
2022-02-26 10:48:09,158 - INFO     - websockets.server: server listening on 0.0.0.0:15733
2022-02-26 10:48:18,986 - INFO     - websockets.server: connection open
2022-02-26 10:48:18,987 - INFO     - fhempy.lib.fhem_pythonbinding: Incoming FHEM connection: 127.0.0.1
2022-02-26 10:48:19,046 - WARNING  - zeroconf: Error with socket 12 (('192.168.42.0', 5353))): [Errno 126] Required key not available
Traceback (most recent call last):
  File "/usr/lib/python3.9/asyncio/selector_events.py", line 1056, in sendto
    self._sock.sendto(data, addr)
OSError: [Errno 126] Required key not available
2022-02-26 10:48:19,555 - DEBUG    - Waage: BLE monitor stopped
2022-02-26 10:48:19,555 - DEBUG    - Waage: Spawning HCIdump thread
2022-02-26 10:48:19,555 - DEBUG    - Waage: HCIdump thread: Init
2022-02-26 10:48:19,555 - INFO     - Waage: Attention! Option report_unknown is enabled for Off sensors, be ready for a huge output
2022-02-26 10:48:19,555 - DEBUG    - Waage: 0 encryptors mac:key pairs loaded
2022-02-26 10:48:19,555 - DEBUG    - Waage: sensor whitelist: [FD:32:B5:9C:4F:CC]
2022-02-26 10:48:19,555 - DEBUG    - Waage: 1 sensor whitelist item(s) loaded
2022-02-26 10:48:19,555 - DEBUG    - Waage: 0 device tracker(s) being monitored
2022-02-26 10:48:19,555 - DEBUG    - Waage: HCIdump thread: Run
2022-02-26 10:48:19,557 - DEBUG    - Waage: HCIdump thread: BLEScanRequester._initialized is False for hci0,  waiting for connection...
2022-02-26 10:48:19,673 - DEBUG    - Waage: HCIdump thread: connected to hci0
2022-02-26 10:48:19,674 - DEBUG    - Waage: HCIdump thread: BLEScanRequester._initialized is True for hci0,  connection established, send_scan_request succeeded.
2022-02-26 10:48:19,674 - DEBUG    - Waage: HCIdump thread: start main event_loop


[dominik]

Hi,

hilft es wenn du den RPi wieder stromlos machst? Eventuell danach kein aioblescan starten, sondern gleich testen ob miscale funktioniert. Wenn die läuft, dann noch den Feuermelder probieren. Du kannst beim Feuermelder wieder die Readings löschen um sicher zu gehen, dass die Standard-Readings kommen.

[Nighthawk]

Hi Dominik,

leider hilf im Moment nichts, werder abschalten und Stromlos machen, noch neustarten noch etwas anderes.
Es kommt einfach absolut nichts im FHEM an.
Aioblescan habe ich natürlich nicht auf dem FHEM Rechner ausgeführt, sondern an meinem Notebook.

[dominik]

Hilft vielleicht der reset des hci0 Devices?
sudo hciconfig hci0 reset

[Nighthawk]

Das bringt leider auch nichts.
Es liegt aus meiner Sicht nicht am BT Hardware, denn aioblescan funktioniert an dem Rechner und die Daten der Bodyscale auch am Rechner an:

Code Auswählen Erweitern

HCI Event:
    code:
        3e
    length:
        36
    LE Meta:
        code:
            02
        Adv Report: 1
            Adv Report:
                ev type:
                    generic adv
                addr type:
                    random
                peer:
                    fd:32:b5:9c:4f:cc
                length:
                    24
                    type:
                        flags
                    flags:
                        Simul LE - BR/EDR (Host): False
                        Simul LE - BR/EDR (Control.): False
                        BR/EDR Not Supported: True
                        LE General Disc.: True
                        LE Limited Disc.: False
                    type:
                        incomplete_list_16_bit_svc_uuids
                    Incomplete uuids:
                            18:1b
                    type:
                        svc_data_16_bit_uuid
                    Advertised Data:
                        Service Data uuid:
                            18:1b
                        Adv Payload:
                            02:a4:e6:07:02:1b:0a:19:1a:fd:ff:c8:3c
                rssi:
                    -83
HCI Event:
    code:
        3e
    length:
        36
    LE Meta:
        code:
            02
        Adv Report: 1
            Adv Report:
                ev type:
                    generic adv
                addr type:
                    random
                peer:
                    fd:32:b5:9c:4f:cc
                length:
                    24
                    type:
                        flags
                    flags:
                        Simul LE - BR/EDR (Host): False
                        Simul LE - BR/EDR (Control.): False
                        BR/EDR Not Supported: True
                        LE General Disc.: True
                        LE Limited Disc.: False
                    type:
                        incomplete_list_16_bit_svc_uuids
                    Incomplete uuids:
                            18:1b
                    type:
                        svc_data_16_bit_uuid
                    Advertised Data:
                        Service Data uuid:
                            18:1b
                        Adv Payload:
                            02:04:e6:07:02:1b:0a:1a:0d:00:00:3c:3c
                rssi:
                    -84
HCI Event:
    code:
        3e
    length:
        36
    LE Meta:
        code:
            02
        Adv Report: 1
            Adv Report:
                ev type:
                    generic adv
                addr type:
                    random
                peer:
                    fd:32:b5:9c:4f:cc
                length:
                    24
                    type:
                        flags
                    flags:
                        Simul LE - BR/EDR (Host): False
                        Simul LE - BR/EDR (Control.): False
                        BR/EDR Not Supported: True
                        LE General Disc.: True
                        LE Limited Disc.: False
                    type:
                        incomplete_list_16_bit_svc_uuids
                    Incomplete uuids:
                            18:1b
                    type:
                        svc_data_16_bit_uuid
                    Advertised Data:
                        Service Data uuid:
                            18:1b
                        Adv Payload:
                            02:04:e6:07:02:1b:0a:1a:0d:00:00:3c:3c
                rssi:
                    -81
HCI Event:
    code:
        3e
    length:
        36
    LE Meta:
        code:
            02
        Adv Report: 1
            Adv Report:
                ev type:
                    generic adv
                addr type:
                    random
                peer:
                    fd:32:b5:9c:4f:cc
                length:
                    24
                    type:
                        flags
                    flags:
                        Simul LE - BR/EDR (Host): False
                        Simul LE - BR/EDR (Control.): False
                        BR/EDR Not Supported: True
                        LE General Disc.: True
                        LE Limited Disc.: False
                    type:
                        incomplete_list_16_bit_svc_uuids
                    Incomplete uuids:
                            18:1b
                    type:
                        svc_data_16_bit_uuid
                    Advertised Data:
                        Service Data uuid:
                            18:1b
                        Adv Payload:
                            02:04:e6:07:02:1b:0a:1a:0e:00:00:64:3c
                rssi:
                    -81
HCI Event:
    code:
        3e
    length:
        36
    LE Meta:
        code:
            02
        Adv Report: 1
            Adv Report:
                ev type:
                    generic adv
                addr type:
                    random
                peer:
                    fd:32:b5:9c:4f:cc
                length:
                    24
                    type:
                        flags
                    flags:
                        Simul LE - BR/EDR (Host): False
                        Simul LE - BR/EDR (Control.): False
                        BR/EDR Not Supported: True
                        LE General Disc.: True
                        LE Limited Disc.: False
                    type:
                        incomplete_list_16_bit_svc_uuids
                    Incomplete uuids:
                            18:1b
                    type:
                        svc_data_16_bit_uuid
                    Advertised Data:
                        Service Data uuid:
                            18:1b
                        Adv Payload:
                            02:04:e6:07:02:1b:0a:1a:0e:00:00:64:3c
                rssi:
                    -81
HCI Event:
    code:
        3e
    length:
        36
    LE Meta:
        code:
            02
        Adv Report: 1
            Adv Report:
                ev type:
                    generic adv
                addr type:
                    random
                peer:
                    fd:32:b5:9c:4f:cc
                length:
                    24
                    type:
                        flags
                    flags:
                        Simul LE - BR/EDR (Host): False
                        Simul LE - BR/EDR (Control.): False
                        BR/EDR Not Supported: True
                        LE General Disc.: True
                        LE Limited Disc.: False
                    type:
                        incomplete_list_16_bit_svc_uuids
                    Incomplete uuids:
                            18:1b
                    type:
                        svc_data_16_bit_uuid
                    Advertised Data:
                        Service Data uuid:
                            18:1b
                        Adv Payload:
                            02:04:e6:07:02:1b:0a:1a:0e:00:00:c8:3c
                rssi:
                    -84

[dominik]

Achso, ich dachte aioblescan war nur am anderen Rechner.

Wenn aioblescan funktioniert, dann sollte auch das Modul funktionieren. Kannst du bitte mal verbose 5 bei miscale machen und dann nochmals testen?

[Nighthawk]

Hallo Dominik,

hatte heute tatsächlich kurz sowohl die miscale, als auch den Rauchmelder funktional.

Hier der List des Rauchmelders:

Code Auswählen Erweitern

Internals:
   CFGFN     
   DEF        ble_monitor 54:EF:44:E2:AA:1D
   FHEMPYTYPE ble_monitor
   FUUID      621bb5ba-f33f-357a-7e4e-848c85dedaa19e0e
   IODev      local_pybinding
   NAME       Rauchmelder_WZ
   NR         11579
   PYTHONTYPE ble_monitor
   STATE      ???
   TYPE       fhempy
   Helper:
     DBLOG:
       battery:
         logdb:
           TIME       1645983396.64707
           VALUE      100
       button:
         logdb:
           TIME       1645983308.84475
           VALUE      single press
       data:
         logdb:
           TIME       1645984206.44446
           VALUE      1
       firmware:
         logdb:
           TIME       1645983222.76325
           VALUE      Xiaomi (MiBeacon V5 encrypted)
       mac:
         logdb:
           TIME       1645983222.76325
           VALUE      54EF44E2AA1D
       packet:
         logdb:
           TIME       1645984206.37705
           VALUE      219
       rssi:
         logdb:
           TIME       1645983491.97358
           VALUE      -54
       smoke detector:
         logdb:
           TIME       1645983519.07179
           VALUE      0
       type:
         logdb:
           TIME       1645983222.76325
           VALUE      JTYJGD03MI
       voltage:
         logdb:
           TIME       1645983396.64707
           VALUE      3.1
   READINGS:
     2022-02-27 18:36:36   battery         100
     2022-02-27 18:35:08   button          single press
     2022-02-27 18:50:06   data            1
     2022-02-27 18:33:42   firmware        Xiaomi (MiBeacon V5 encrypted)
     2022-02-27 18:33:42   mac             54EF44E2AA1D
     2022-02-27 18:50:06   packet          219
     2022-02-27 18:38:11   rssi            -54
     2022-02-27 18:38:39   smoke detector  0
     2022-02-27 18:33:42   type            JTYJGD03MI
     2022-02-27 18:36:36   voltage         3.1
   args:
     Rauchmelder_WZ
     fhempy
     ble_monitor
     54:EF:44:E2:AA:1D
   argsh:
   hmccu:
Attributes:
   encryption_key *********************************
   verbose    5

Was ich gamacht habe war folgendes, ich habe alle fhempy Devices entfernt, also miscale, ble_monitor, sowie Ring.
Dann einen neustart des Rechners, neu anlegen der Miscale, neustart des fhempy Servers --> test der Waege, läuft.
Anschließend auch den Rauchmelder angelegt und erneut den fhempy Server neugestartet --> test des Rauchmelders, läuft.
Anschließend alle anderen Rauchmelder definiert und auch die Ring --> test, nichts ausser Ring geht jetzt.

Es scheint irgendwo mit der Ring zu knallen.

Hier der Log von der kompletten Phase:

Code Auswählen Erweitern

2022-02-27 18:30:26,808 - INFO     - fhempy.lib.fhem_pythonbinding: Starting fhempy...
2022-02-27 18:30:26,814 - INFO     - fhempy.lib.fhem_pythonbinding: Waiting for FHEM connection
2022-02-27 18:30:26,835 - INFO     - websockets.server: server listening on 0.0.0.0:15733
2022-02-27 18:30:36,697 - INFO     - websockets.server: connection open
2022-02-27 18:30:36,698 - INFO     - fhempy.lib.fhem_pythonbinding: Incoming FHEM connection: 127.0.0.1
2022-02-27 18:30:36,754 - WARNING  - zeroconf: Error with socket 13 (('192.168.42.0', 5353))): [Errno 126] Required key not available
Traceback (most recent call last):
  File "/usr/lib/python3.9/asyncio/selector_events.py", line 1056, in sendto
    self._sock.sendto(data, addr)
OSError: [Errno 126] Required key not available
2022-02-27 18:30:37,128 - DEBUG    - Waage: BLE monitor stopped
2022-02-27 18:30:37,128 - DEBUG    - Waage: Spawning HCIdump thread
2022-02-27 18:30:37,128 - DEBUG    - Waage: HCIdump thread: Init
2022-02-27 18:30:37,129 - INFO     - Waage: Attention! Option report_unknown is enabled for Off sensors, be ready for a huge output
2022-02-27 18:30:37,129 - DEBUG    - Waage: 0 encryptors mac:key pairs loaded
2022-02-27 18:30:37,129 - DEBUG    - Waage: sensor whitelist: [FD:32:B5:9C:4F:CC]
2022-02-27 18:30:37,129 - DEBUG    - Waage: 1 sensor whitelist item(s) loaded
2022-02-27 18:30:37,129 - DEBUG    - Waage: 0 device tracker(s) being monitored
2022-02-27 18:30:37,129 - DEBUG    - Waage: HCIdump thread: Run
2022-02-27 18:30:37,131 - DEBUG    - Waage: HCIdump thread: BLEScanRequester._initialized is False for hci0,  waiting for connection...
2022-02-27 18:30:37,134 - DEBUG    - Waage: HCIdump thread: connected to hci0
2022-02-27 18:30:37,135 - DEBUG    - Waage: HCIdump thread: BLEScanRequester._initialized is True for hci0,  connection established, send_scan_request succeeded.
2022-02-27 18:30:37,135 - DEBUG    - Waage: HCIdump thread: start main event_loop
2022-02-27 18:32:42,304 - DEBUG    - Waage: HCIdump thread: joining
2022-02-27 18:32:42,304 - DEBUG    - Waage: HCIdump thread: main event_loop stopped, finishing.
2022-02-27 18:32:42,304 - DEBUG    - Waage: HCIdump thread: Run finished
2022-02-27 18:32:42,304 - DEBUG    - Waage: HCIdump thread: joined
2022-02-27 18:32:42,305 - DEBUG    - Waage: BLE monitor stopped
2022-02-27 18:32:42,305 - DEBUG    - Waage: Spawning HCIdump thread
2022-02-27 18:32:42,305 - DEBUG    - Waage: HCIdump thread: Init
2022-02-27 18:32:42,305 - INFO     - Waage: Attention! Option report_unknown is enabled for Off sensors, be ready for a huge output
2022-02-27 18:32:42,305 - DEBUG    - Waage: 0 encryptors mac:key pairs loaded
2022-02-27 18:32:42,305 - DEBUG    - Waage: sensor whitelist: [FD:32:B5:9C:4F:CC, 54:EF:44:E2:AA:1D]
2022-02-27 18:32:42,305 - DEBUG    - Waage: 2 sensor whitelist item(s) loaded
2022-02-27 18:32:42,305 - DEBUG    - Waage: 0 device tracker(s) being monitored
2022-02-27 18:32:42,306 - DEBUG    - Waage: HCIdump thread: Run
2022-02-27 18:32:42,307 - DEBUG    - Waage: HCIdump thread: BLEScanRequester._initialized is False for hci0,  waiting for connection...
2022-02-27 18:32:42,315 - DEBUG    - Waage: HCIdump thread: connected to hci0
2022-02-27 18:32:42,315 - DEBUG    - Waage: HCIdump thread: BLEScanRequester._initialized is True for hci0,  connection established, send_scan_request succeeded.
2022-02-27 18:32:42,316 - DEBUG    - Waage: HCIdump thread: BLEScanRequester._initialized is False for hci0,  waiting for connection...
2022-02-27 18:32:42,320 - DEBUG    - Waage: HCIdump thread: connected to hci0
2022-02-27 18:32:42,320 - DEBUG    - Waage: HCIdump thread: BLEScanRequester._initialized is True for hci0,  connection established, send_scan_request succeeded.
2022-02-27 18:32:42,321 - DEBUG    - Waage: HCIdump thread: start main event_loop
2022-02-27 18:33:02,735 - INFO     - fhempy.lib.fhem_pythonbinding: Starting fhempy...
2022-02-27 18:33:02,741 - INFO     - fhempy.lib.fhem_pythonbinding: Waiting for FHEM connection
2022-02-27 18:33:02,761 - INFO     - websockets.server: server listening on 0.0.0.0:15733
2022-02-27 18:33:12,582 - INFO     - websockets.server: connection open
2022-02-27 18:33:12,582 - INFO     - fhempy.lib.fhem_pythonbinding: Incoming FHEM connection: 127.0.0.1
2022-02-27 18:33:12,621 - WARNING  - zeroconf: Error with socket 12 (('192.168.42.0', 5353))): [Errno 126] Required key not available
Traceback (most recent call last):
  File "/usr/lib/python3.9/asyncio/selector_events.py", line 1056, in sendto
    self._sock.sendto(data, addr)
OSError: [Errno 126] Required key not available
2022-02-27 18:33:12,980 - DEBUG    - Rauchmelder_WZ: BLE monitor stopped
2022-02-27 18:33:12,980 - DEBUG    - Rauchmelder_WZ: Spawning HCIdump thread
2022-02-27 18:33:12,980 - DEBUG    - Rauchmelder_WZ: HCIdump thread: Init
2022-02-27 18:33:12,980 - INFO     - Rauchmelder_WZ: Attention! Option report_unknown is enabled for Off sensors, be ready for a huge output
2022-02-27 18:33:12,980 - DEBUG    - Rauchmelder_WZ: 0 encryptors mac:key pairs loaded
2022-02-27 18:33:12,980 - DEBUG    - Rauchmelder_WZ: sensor whitelist: [54:EF:44:E2:AA:1D]
2022-02-27 18:33:12,980 - DEBUG    - Rauchmelder_WZ: 1 sensor whitelist item(s) loaded
2022-02-27 18:33:12,980 - DEBUG    - Rauchmelder_WZ: 0 device tracker(s) being monitored
2022-02-27 18:33:12,981 - DEBUG    - Rauchmelder_WZ: HCIdump thread: Run
2022-02-27 18:33:12,982 - DEBUG    - Rauchmelder_WZ: HCIdump thread: BLEScanRequester._initialized is False for hci0,  waiting for connection...
2022-02-27 18:33:12,986 - DEBUG    - Rauchmelder_WZ: HCIdump thread: connected to hci0
2022-02-27 18:33:12,986 - DEBUG    - Rauchmelder_WZ: HCIdump thread: BLEScanRequester._initialized is True for hci0,  connection established, send_scan_request succeeded.
2022-02-27 18:33:12,986 - DEBUG    - Rauchmelder_WZ: HCIdump thread: start main event_loop
2022-02-27 18:33:12,990 - DEBUG    - Rauchmelder_WZ: HCIdump thread: joining
2022-02-27 18:33:12,990 - DEBUG    - Rauchmelder_WZ: HCIdump thread: main event_loop stopped, finishing.
2022-02-27 18:33:12,991 - DEBUG    - Rauchmelder_WZ: HCIdump thread: Run finished
2022-02-27 18:33:12,991 - DEBUG    - Rauchmelder_WZ: HCIdump thread: joined
2022-02-27 18:33:12,991 - DEBUG    - Rauchmelder_WZ: BLE monitor stopped
2022-02-27 18:33:12,991 - DEBUG    - Rauchmelder_WZ: Spawning HCIdump thread
2022-02-27 18:33:12,991 - DEBUG    - Rauchmelder_WZ: HCIdump thread: Init
2022-02-27 18:33:12,991 - INFO     - Rauchmelder_WZ: Attention! Option report_unknown is enabled for Off sensors, be ready for a huge output
2022-02-27 18:33:12,991 - DEBUG    - Rauchmelder_WZ: 0 encryptors mac:key pairs loaded
2022-02-27 18:33:12,992 - DEBUG    - Rauchmelder_WZ: sensor whitelist: [54:EF:44:E2:AA:1D, FD:32:B5:9C:4F:CC]
2022-02-27 18:33:12,992 - DEBUG    - Rauchmelder_WZ: 2 sensor whitelist item(s) loaded
2022-02-27 18:33:12,992 - DEBUG    - Rauchmelder_WZ: 0 device tracker(s) being monitored
2022-02-27 18:33:12,992 - DEBUG    - Rauchmelder_WZ: HCIdump thread: Run
2022-02-27 18:33:12,994 - DEBUG    - Rauchmelder_WZ: HCIdump thread: BLEScanRequester._initialized is False for hci0,  waiting for connection...
2022-02-27 18:33:13,004 - DEBUG    - Rauchmelder_WZ: HCIdump thread: connected to hci0
2022-02-27 18:33:13,005 - DEBUG    - Rauchmelder_WZ: HCIdump thread: BLEScanRequester._initialized is True for hci0,  connection established, send_scan_request succeeded.
2022-02-27 18:33:13,006 - DEBUG    - Rauchmelder_WZ: HCIdump thread: BLEScanRequester._initialized is False for hci0,  waiting for connection...
2022-02-27 18:33:13,010 - DEBUG    - Rauchmelder_WZ: HCIdump thread: connected to hci0
2022-02-27 18:33:13,011 - DEBUG    - Rauchmelder_WZ: HCIdump thread: BLEScanRequester._initialized is True for hci0,  connection established, send_scan_request succeeded.
2022-02-27 18:33:13,011 - DEBUG    - Rauchmelder_WZ: HCIdump thread: start main event_loop
2022-02-27 18:33:42,720 - ERROR    - bleparser.xiaomi: No encryption key found for device with MAC 54:EF:44:E2:AA:1D
2022-02-27 18:34:22,920 - ERROR    - bleparser.xiaomi: No encryption key found for device with MAC 54:EF:44:E2:AA:1D
2022-02-27 18:34:31,449 - ERROR    - bleparser.xiaomi: No encryption key found for device with MAC 54:EF:44:E2:AA:1D
2022-02-27 18:34:33,977 - ERROR    - bleparser.xiaomi: No encryption key found for device with MAC 54:EF:44:E2:AA:1D
2022-02-27 18:34:46,742 - ERROR    - bleparser.xiaomi: No encryption key found for device with MAC 54:EF:44:E2:AA:1D
2022-02-27 18:34:56,212 - DEBUG    - Rauchmelder_WZ: HCIdump thread: joining
2022-02-27 18:34:56,212 - DEBUG    - Rauchmelder_WZ: HCIdump thread: main event_loop stopped, finishing.
2022-02-27 18:34:56,213 - DEBUG    - Rauchmelder_WZ: HCIdump thread: Run finished
2022-02-27 18:34:56,214 - DEBUG    - Rauchmelder_WZ: HCIdump thread: joined
2022-02-27 18:34:56,214 - DEBUG    - Rauchmelder_WZ: BLE monitor stopped
2022-02-27 18:34:56,214 - DEBUG    - Rauchmelder_WZ: Spawning HCIdump thread
2022-02-27 18:34:56,214 - DEBUG    - Rauchmelder_WZ: HCIdump thread: Init
2022-02-27 18:34:56,214 - INFO     - Rauchmelder_WZ: Attention! Option report_unknown is enabled for Off sensors, be ready for a huge output
2022-02-27 18:34:56,214 - DEBUG    - Rauchmelder_WZ: 0 encryptors mac:key pairs loaded
2022-02-27 18:34:56,215 - DEBUG    - Rauchmelder_WZ: sensor whitelist: [54:EF:44:E2:AA:1D, FD:32:B5:9C:4F:CC]
2022-02-27 18:34:56,215 - DEBUG    - Rauchmelder_WZ: 2 sensor whitelist item(s) loaded
2022-02-27 18:34:56,215 - DEBUG    - Rauchmelder_WZ: 0 device tracker(s) being monitored
2022-02-27 18:34:56,215 - DEBUG    - Rauchmelder_WZ: HCIdump thread: Run
2022-02-27 18:34:56,216 - DEBUG    - Rauchmelder_WZ: HCIdump thread: joining
2022-02-27 18:34:56,216 - DEBUG    - Rauchmelder_WZ: 'NoneType' object has no attribute 'call_soon_threadsafe'
2022-02-27 18:34:56,217 - DEBUG    - Rauchmelder_WZ: HCIdump thread: BLEScanRequester._initialized is False for hci0,  waiting for connection...
2022-02-27 18:34:56,222 - DEBUG    - Rauchmelder_WZ: HCIdump thread: connected to hci0
2022-02-27 18:34:56,222 - DEBUG    - Rauchmelder_WZ: HCIdump thread: BLEScanRequester._initialized is True for hci0,  connection established, send_scan_request succeeded.
2022-02-27 18:34:56,222 - DEBUG    - Rauchmelder_WZ: HCIdump thread: start main event_loop
2022-02-27 18:34:57,216 - DEBUG    - Rauchmelder_WZ: HCIdump thread: joined
2022-02-27 18:34:57,217 - ERROR    - Rauchmelder_WZ: Waiting for the HCIdump thread to finish took too long! (>10s)
2022-02-27 18:34:57,217 - DEBUG    - Rauchmelder_WZ: BLE monitor stopped
2022-02-27 18:34:57,217 - DEBUG    - Rauchmelder_WZ: Spawning HCIdump thread
2022-02-27 18:34:57,217 - DEBUG    - Rauchmelder_WZ: HCIdump thread: Init
2022-02-27 18:34:57,217 - INFO     - Rauchmelder_WZ: Attention! Option report_unknown is enabled for Off sensors, be ready for a huge output
2022-02-27 18:34:57,217 - DEBUG    - Rauchmelder_WZ: 1 encryptors mac:key pairs loaded
2022-02-27 18:34:57,218 - DEBUG    - Rauchmelder_WZ: sensor whitelist: [54:EF:44:E2:AA:1D, FD:32:B5:9C:4F:CC]
2022-02-27 18:34:57,218 - DEBUG    - Rauchmelder_WZ: 2 sensor whitelist item(s) loaded
2022-02-27 18:34:57,218 - DEBUG    - Rauchmelder_WZ: 0 device tracker(s) being monitored
2022-02-27 18:34:57,218 - DEBUG    - Rauchmelder_WZ: HCIdump thread: Run
2022-02-27 18:34:57,220 - WARNING  - asyncio: Executing <Task finished name='Task-246' coro=<FhemModule.Attr() done, defined at /opt/fhem/.local/lib/python3.9/site-packages/fhempy/lib/generic.py:168> result=None created at /usr/lib/python3.9/asyncio/tasks.py:462> took 1.008 seconds
2022-02-27 18:34:57,221 - DEBUG    - Rauchmelder_WZ: HCIdump thread: BLEScanRequester._initialized is False for hci0,  waiting for connection...
2022-02-27 18:34:57,228 - DEBUG    - Rauchmelder_WZ: HCIdump thread: connected to hci0
2022-02-27 18:34:57,229 - DEBUG    - Rauchmelder_WZ: HCIdump thread: BLEScanRequester._initialized is True for hci0,  connection established, send_scan_request succeeded.
2022-02-27 18:34:57,230 - DEBUG    - Rauchmelder_WZ: HCIdump thread: BLEScanRequester._initialized is False for hci0,  waiting for connection...
2022-02-27 18:34:57,234 - DEBUG    - Rauchmelder_WZ: HCIdump thread: connected to hci0
2022-02-27 18:34:57,235 - DEBUG    - Rauchmelder_WZ: HCIdump thread: BLEScanRequester._initialized is True for hci0,  connection established, send_scan_request succeeded.
2022-02-27 18:34:57,235 - DEBUG    - Rauchmelder_WZ: HCIdump thread: start main event_loop
2022-02-27 18:35:05,377 - ERROR    - bleparser.xiaomi: No encryption key found for device with MAC 54:EF:44:E2:AA:1D
^[[15~2022-02-27 18:35:08,701 - ERROR    - bleparser.xiaomi: No encryption key found for device with MAC 54:EF:44:E2:AA:1D
2022-02-27 18:35:19,307 - ERROR    - bleparser.xiaomi: No encryption key found for device with MAC 54:EF:44:E2:AA:1D
2022-02-27 18:35:21,708 - ERROR    - bleparser.xiaomi: No encryption key found for device with MAC 54:EF:44:E2:AA:1D
2022-02-27 18:35:24,814 - ERROR    - bleparser.xiaomi: No encryption key found for device with MAC 54:EF:44:E2:AA:1D
2022-02-27 18:35:28,051 - ERROR    - bleparser.xiaomi: No encryption key found for device with MAC 54:EF:44:E2:AA:1D
2022-02-27 18:35:38,216 - ERROR    - bleparser.xiaomi: No encryption key found for device with MAC 54:EF:44:E2:AA:1D
2022-02-27 18:36:36,500 - ERROR    - bleparser.xiaomi: No encryption key found for device with MAC 54:EF:44:E2:AA:1D
2022-02-27 18:36:47,565 - ERROR    - bleparser.xiaomi: No encryption key found for device with MAC 54:EF:44:E2:AA:1D
2022-02-27 18:36:53,523 - ERROR    - bleparser.xiaomi: No encryption key found for device with MAC 54:EF:44:E2:AA:1D
2022-02-27 18:36:56,683 - ERROR    - bleparser.xiaomi: No encryption key found for device with MAC 54:EF:44:E2:AA:1D
2022-02-27 18:38:02,993 - ERROR    - bleparser.xiaomi: No encryption key found for device with MAC 54:EF:44:E2:AA:1D
2022-02-27 18:38:08,664 - ERROR    - bleparser.xiaomi: No encryption key found for device with MAC 54:EF:44:E2:AA:1D
2022-02-27 18:38:11,838 - ERROR    - bleparser.xiaomi: No encryption key found for device with MAC 54:EF:44:E2:AA:1D
2022-02-27 18:38:38,770 - ERROR    - bleparser.xiaomi: No encryption key found for device with MAC 54:EF:44:E2:AA:1D
2022-02-27 18:41:06,384 - ERROR    - bleparser.xiaomi: No encryption key found for device with MAC 54:EF:44:E2:AA:1D
2022-02-27 18:41:09,386 - ERROR    - bleparser.xiaomi: No encryption key found for device with MAC 54:EF:44:E2:AA:1D
2022-02-27 18:42:01,058 - WARNING  - asyncio: Executing <Task pending name='Task-1612' coro=<ring.ring_login() running at /opt/fhem/.local/lib/python3.9/site-packages/fhempy/lib/ring/ring.py:110> wait_for=<Future pending cb=[<TaskWakeupMethWrapper object at 0x7f9c8df88490>()] created at /usr/lib/python3.9/asyncio/base_events.py:424> created at /usr/lib/python3.9/asyncio/tasks.py:361> took 0.355 seconds
2022-02-27 18:42:02,904 - WARNING  - asyncio: Executing <Task pending name='Task-1612' coro=<ring.ring_login() running at /opt/fhem/.local/lib/python3.9/site-packages/fhempy/lib/ring/ring.py:110> wait_for=<Future pending cb=[<TaskWakeupMethWrapper object at 0x7f9c7fa2ffd0>()] created at /usr/lib/python3.9/asyncio/base_events.py:424> created at /usr/lib/python3.9/asyncio/tasks.py:361> took 0.356 seconds
2022-02-27 18:45:36,639 - ERROR    - bleparser.xiaomi: No encryption key found for device with MAC 54:EF:44:E2:AA:1D
2022-02-27 18:50:06,342 - ERROR    - bleparser.xiaomi: No encryption key found for device with MAC 54:EF:44:E2:AA:1D
2022-02-27 18:52:55,841 - DEBUG    - Rauchmelder_WZ: HCIdump thread: joining
2022-02-27 18:52:55,841 - DEBUG    - Rauchmelder_WZ: HCIdump thread: main event_loop stopped, finishing.
2022-02-27 18:52:55,842 - DEBUG    - Rauchmelder_WZ: HCIdump thread: Run finished
2022-02-27 18:52:55,843 - DEBUG    - Rauchmelder_WZ: HCIdump thread: joined
2022-02-27 18:52:55,843 - DEBUG    - Rauchmelder_WZ: BLE monitor stopped
2022-02-27 18:52:55,843 - DEBUG    - Rauchmelder_WZ: Spawning HCIdump thread
2022-02-27 18:52:55,843 - DEBUG    - Rauchmelder_WZ: HCIdump thread: Init
2022-02-27 18:52:55,843 - INFO     - Rauchmelder_WZ: Attention! Option report_unknown is enabled for Off sensors, be ready for a huge output
2022-02-27 18:52:55,843 - DEBUG    - Rauchmelder_WZ: 2 encryptors mac:key pairs loaded
2022-02-27 18:52:55,844 - DEBUG    - Rauchmelder_WZ: sensor whitelist: [54:EF:44:E2:AA:1D, FD:32:B5:9C:4F:CC, 54:EF:44:E4:6A:81]
2022-02-27 18:52:55,844 - DEBUG    - Rauchmelder_WZ: 3 sensor whitelist item(s) loaded
2022-02-27 18:52:55,844 - DEBUG    - Rauchmelder_WZ: 0 device tracker(s) being monitored
2022-02-27 18:52:55,844 - DEBUG    - Rauchmelder_WZ: HCIdump thread: Run
2022-02-27 18:52:55,845 - DEBUG    - Rauchmelder_WZ: HCIdump thread: BLEScanRequester._initialized is False for hci0,  waiting for connection...
2022-02-27 18:52:55,846 - DEBUG    - Rauchmelder_WZ: HCIdump thread: joining
Exception in thread Thread-5:
Traceback (most recent call last):
  File "/usr/lib/python3.9/threading.py", line 973, in _bootstrap_inner
    self.run()
  File "/opt/fhem/.local/lib/python3.9/site-packages/fhempy/lib/ble_monitor/hcidump.py", line 183, in run
    self._event_loop.run_until_complete(
  File "/usr/lib/python3.9/asyncio/base_events.py", line 640, in run_until_complete
    raise RuntimeError('Event loop stopped before Future completed.')
RuntimeError: Event loop stopped before Future completed.
2022-02-27 18:52:55,849 - DEBUG    - Rauchmelder_WZ: HCIdump thread: joined
2022-02-27 18:52:55,849 - DEBUG    - Rauchmelder_WZ: BLE monitor stopped
2022-02-27 18:52:55,849 - DEBUG    - Rauchmelder_WZ: Spawning HCIdump thread
2022-02-27 18:52:55,849 - DEBUG    - Rauchmelder_WZ: HCIdump thread: Init
2022-02-27 18:52:55,849 - INFO     - Rauchmelder_WZ: Attention! Option report_unknown is enabled for Off sensors, be ready for a huge output
2022-02-27 18:52:55,849 - DEBUG    - Rauchmelder_WZ: 1 encryptors mac:key pairs loaded
2022-02-27 18:52:55,849 - DEBUG    - Rauchmelder_WZ: sensor whitelist: [54:EF:44:E2:AA:1D, FD:32:B5:9C:4F:CC]
2022-02-27 18:52:55,850 - DEBUG    - Rauchmelder_WZ: 2 sensor whitelist item(s) loaded
2022-02-27 18:52:55,850 - DEBUG    - Rauchmelder_WZ: 0 device tracker(s) being monitored
2022-02-27 18:52:55,850 - DEBUG    - Rauchmelder_WZ: HCIdump thread: Run
2022-02-27 18:52:55,850 - DEBUG    - Rauchmelder_WZ: HCIdump thread: joining
2022-02-27 18:52:55,851 - DEBUG    - Rauchmelder_WZ: 'NoneType' object has no attribute 'call_soon_threadsafe'
2022-02-27 18:52:55,852 - DEBUG    - Rauchmelder_WZ: HCIdump thread: BLEScanRequester._initialized is False for hci0,  waiting for connection...
2022-02-27 18:52:55,856 - DEBUG    - Rauchmelder_WZ: HCIdump thread: connected to hci0
2022-02-27 18:52:55,856 - DEBUG    - Rauchmelder_WZ: HCIdump thread: BLEScanRequester._initialized is True for hci0,  connection established, send_scan_request succeeded.
2022-02-27 18:52:55,857 - DEBUG    - Rauchmelder_WZ: HCIdump thread: BLEScanRequester._initialized is True for hci0,  waiting for connection...
2022-02-27 18:52:55,857 - DEBUG    - Rauchmelder_WZ: HCIdump thread: connected to hci0
Exception in thread Thread-6:
Traceback (most recent call last):
  File "/usr/lib/python3.9/threading.py", line 973, in _bootstrap_inner
    self.run()
  File "/opt/fhem/.local/lib/python3.9/site-packages/fhempy/lib/ble_monitor/hcidump.py", line 196, in run
    self._event_loop.run_until_complete(
  File "/usr/lib/python3.9/asyncio/base_events.py", line 642, in run_until_complete
    return future.result()
  File "/usr/local/lib/python3.9/dist-packages/aioblescan/aioblescan.py", line 1806, in send_scan_request
    if self._use_ext_scan():
  File "/usr/local/lib/python3.9/dist-packages/aioblescan/aioblescan.py", line 1791, in _use_ext_scan
    return (self._supported_commands[37] & 0x60) == 0x60
TypeError: 'NoneType' object is not subscriptable
2022-02-27 18:52:55,860 - DEBUG    - Rauchmelder_WZ: HCIdump thread: joined
2022-02-27 18:52:55,860 - DEBUG    - Rauchmelder_WZ: BLE monitor stopped
2022-02-27 18:52:55,860 - DEBUG    - Rauchmelder_WZ: Spawning HCIdump thread
2022-02-27 18:52:55,860 - DEBUG    - Rauchmelder_WZ: HCIdump thread: Init
2022-02-27 18:52:55,860 - INFO     - Rauchmelder_WZ: Attention! Option report_unknown is enabled for Off sensors, be ready for a huge output
2022-02-27 18:52:55,861 - DEBUG    - Rauchmelder_WZ: 2 encryptors mac:key pairs loaded
2022-02-27 18:52:55,861 - DEBUG    - Rauchmelder_WZ: sensor whitelist: [54:EF:44:E2:AA:1D, FD:32:B5:9C:4F:CC, 54:EF:44:E4:6A:81]
2022-02-27 18:52:55,861 - DEBUG    - Rauchmelder_WZ: 3 sensor whitelist item(s) loaded
2022-02-27 18:52:55,861 - DEBUG    - Rauchmelder_WZ: 0 device tracker(s) being monitored
2022-02-27 18:52:55,861 - DEBUG    - Rauchmelder_WZ: HCIdump thread: Run
2022-02-27 18:52:55,863 - DEBUG    - Rauchmelder_WZ: HCIdump thread: BLEScanRequester._initialized is False for hci0,  waiting for connection...
2022-02-27 18:52:55,865 - DEBUG    - Rauchmelder_WZ: HCIdump thread: connected to hci0
2022-02-27 18:52:55,865 - DEBUG    - Rauchmelder_WZ: HCIdump thread: BLEScanRequester._initialized is True for hci0,  connection established, send_scan_request succeeded.
2022-02-27 18:52:55,866 - DEBUG    - Rauchmelder_WZ: HCIdump thread: BLEScanRequester._initialized is False for hci0,  waiting for connection...
2022-02-27 18:52:55,870 - DEBUG    - Rauchmelder_WZ: HCIdump thread: connected to hci0
2022-02-27 18:52:55,870 - DEBUG    - Rauchmelder_WZ: HCIdump thread: BLEScanRequester._initialized is True for hci0,  connection established, send_scan_request succeeded.
2022-02-27 18:52:55,871 - DEBUG    - Rauchmelder_WZ: HCIdump thread: BLEScanRequester._initialized is False for hci0,  waiting for connection...
2022-02-27 18:52:55,875 - DEBUG    - Rauchmelder_WZ: HCIdump thread: connected to hci0
2022-02-27 18:52:55,875 - DEBUG    - Rauchmelder_WZ: HCIdump thread: BLEScanRequester._initialized is True for hci0,  connection established, send_scan_request succeeded.
2022-02-27 18:52:55,875 - DEBUG    - Rauchmelder_WZ: HCIdump thread: start main event_loop
2022-02-27 18:52:56,142 - ERROR    - asyncio: Task was destroyed but it is pending!
task: <Task pending name='Task-1928' coro=<Event.wait() done, defined at /usr/lib/python3.9/asyncio/locks.py:213> wait_for=<Future pending cb=[<TaskWakeupMethWrapper object at 0x7f9c7f9812b0>()]> cb=[_release_waiter(<Future pendi...c7f981280>()]>)() at /usr/lib/python3.9/asyncio/tasks.py:416]>
2022-02-27 18:52:56,289 - DEBUG    - Rauchmelder_WZ: HCIdump thread: joining
2022-02-27 18:52:56,289 - DEBUG    - Rauchmelder_WZ: HCIdump thread: main event_loop stopped, finishing.
2022-02-27 18:52:56,290 - DEBUG    - Rauchmelder_WZ: HCIdump thread: Run finished
2022-02-27 18:52:56,291 - DEBUG    - Rauchmelder_WZ: HCIdump thread: joined
2022-02-27 18:52:56,291 - DEBUG    - Rauchmelder_WZ: BLE monitor stopped
2022-02-27 18:52:56,291 - DEBUG    - Rauchmelder_WZ: Spawning HCIdump thread
2022-02-27 18:52:56,291 - DEBUG    - Rauchmelder_WZ: HCIdump thread: Init
2022-02-27 18:52:56,291 - INFO     - Rauchmelder_WZ: Attention! Option report_unknown is enabled for Off sensors, be ready for a huge output
2022-02-27 18:52:56,291 - DEBUG    - Rauchmelder_WZ: 3 encryptors mac:key pairs loaded
2022-02-27 18:52:56,291 - DEBUG    - Rauchmelder_WZ: sensor whitelist: [54:EF:44:E2:AA:1D, FD:32:B5:9C:4F:CC, 54:EF:44:E4:6A:81, 54:EF:44:E3:E6:64]
2022-02-27 18:52:56,291 - DEBUG    - Rauchmelder_WZ: 4 sensor whitelist item(s) loaded
2022-02-27 18:52:56,292 - DEBUG    - Rauchmelder_WZ: 0 device tracker(s) being monitored
2022-02-27 18:52:56,292 - DEBUG    - Rauchmelder_WZ: HCIdump thread: Run
2022-02-27 18:52:56,294 - DEBUG    - Rauchmelder_WZ: HCIdump thread: BLEScanRequester._initialized is False for hci0,  waiting for connection...
2022-02-27 18:52:56,295 - DEBUG    - Rauchmelder_WZ: HCIdump thread: joining
Exception in thread Thread-8:
Traceback (most recent call last):
  File "/usr/lib/python3.9/threading.py", line 973, in _bootstrap_inner
    self.run()
  File "/opt/fhem/.local/lib/python3.9/site-packages/fhempy/lib/ble_monitor/hcidump.py", line 183, in run
    self._event_loop.run_until_complete(
  File "/usr/lib/python3.9/asyncio/base_events.py", line 640, in run_until_complete
    raise RuntimeError('Event loop stopped before Future completed.')
RuntimeError: Event loop stopped before Future completed.
2022-02-27 18:52:56,296 - DEBUG    - Rauchmelder_WZ: HCIdump thread: joined
2022-02-27 18:52:56,296 - DEBUG    - Rauchmelder_WZ: BLE monitor stopped
2022-02-27 18:52:56,296 - DEBUG    - Rauchmelder_WZ: Spawning HCIdump thread
2022-02-27 18:52:56,296 - DEBUG    - Rauchmelder_WZ: HCIdump thread: Init
2022-02-27 18:52:56,297 - INFO     - Rauchmelder_WZ: Attention! Option report_unknown is enabled for Off sensors, be ready for a huge output
2022-02-27 18:52:56,297 - DEBUG    - Rauchmelder_WZ: 2 encryptors mac:key pairs loaded
2022-02-27 18:52:56,297 - DEBUG    - Rauchmelder_WZ: sensor whitelist: [54:EF:44:E2:AA:1D, FD:32:B5:9C:4F:CC, 54:EF:44:E4:6A:81]
2022-02-27 18:52:56,297 - DEBUG    - Rauchmelder_WZ: 3 sensor whitelist item(s) loaded
2022-02-27 18:52:56,297 - DEBUG    - Rauchmelder_WZ: 0 device tracker(s) being monitored
2022-02-27 18:52:56,297 - DEBUG    - Rauchmelder_WZ: HCIdump thread: Run
2022-02-27 18:52:56,297 - DEBUG    - Rauchmelder_WZ: HCIdump thread: joining
2022-02-27 18:52:56,298 - DEBUG    - Rauchmelder_WZ: 'NoneType' object has no attribute 'call_soon_threadsafe'
2022-02-27 18:52:56,298 - DEBUG    - Rauchmelder_WZ: HCIdump thread: BLEScanRequester._initialized is False for hci0,  waiting for connection...
2022-02-27 18:52:56,302 - DEBUG    - Rauchmelder_WZ: HCIdump thread: connected to hci0
2022-02-27 18:52:56,302 - DEBUG    - Rauchmelder_WZ: HCIdump thread: BLEScanRequester._initialized is True for hci0,  connection established, send_scan_request succeeded.
2022-02-27 18:52:56,303 - DEBUG    - Rauchmelder_WZ: HCIdump thread: BLEScanRequester._initialized is False for hci0,  waiting for connection...
2022-02-27 18:52:56,307 - DEBUG    - Rauchmelder_WZ: HCIdump thread: connected to hci0
2022-02-27 18:52:56,307 - DEBUG    - Rauchmelder_WZ: HCIdump thread: BLEScanRequester._initialized is True for hci0,  connection established, send_scan_request succeeded.
2022-02-27 18:52:56,308 - DEBUG    - Rauchmelder_WZ: HCIdump thread: BLEScanRequester._initialized is False for hci0,  waiting for connection...
2022-02-27 18:52:56,312 - DEBUG    - Rauchmelder_WZ: HCIdump thread: connected to hci0
2022-02-27 18:52:56,312 - DEBUG    - Rauchmelder_WZ: HCIdump thread: BLEScanRequester._initialized is True for hci0,  connection established, send_scan_request succeeded.
2022-02-27 18:52:56,312 - DEBUG    - Rauchmelder_WZ: HCIdump thread: start main event_loop
2022-02-27 18:52:57,298 - DEBUG    - Rauchmelder_WZ: HCIdump thread: joined
2022-02-27 18:52:57,298 - ERROR    - Rauchmelder_WZ: Waiting for the HCIdump thread to finish took too long! (>10s)
2022-02-27 18:52:57,299 - DEBUG    - Rauchmelder_WZ: BLE monitor stopped
2022-02-27 18:52:57,299 - DEBUG    - Rauchmelder_WZ: Spawning HCIdump thread
2022-02-27 18:52:57,299 - DEBUG    - Rauchmelder_WZ: HCIdump thread: Init
2022-02-27 18:52:57,299 - INFO     - Rauchmelder_WZ: Attention! Option report_unknown is enabled for Off sensors, be ready for a huge output
2022-02-27 18:52:57,299 - DEBUG    - Rauchmelder_WZ: 3 encryptors mac:key pairs loaded
2022-02-27 18:52:57,299 - DEBUG    - Rauchmelder_WZ: sensor whitelist: [54:EF:44:E2:AA:1D, FD:32:B5:9C:4F:CC, 54:EF:44:E4:6A:81, 54:EF:44:E3:E6:64]
2022-02-27 18:52:57,299 - DEBUG    - Rauchmelder_WZ: 4 sensor whitelist item(s) loaded
2022-02-27 18:52:57,299 - DEBUG    - Rauchmelder_WZ: 0 device tracker(s) being monitored
2022-02-27 18:52:57,300 - DEBUG    - Rauchmelder_WZ: HCIdump thread: Run
2022-02-27 18:52:57,301 - WARNING  - asyncio: Executing <Task finished name='Task-1969' coro=<FhemModule.Attr() done, defined at /opt/fhem/.local/lib/python3.9/site-packages/fhempy/lib/generic.py:168> result=None created at /usr/lib/python3.9/asyncio/tasks.py:462> took 1.007 seconds
2022-02-27 18:52:57,302 - DEBUG    - Rauchmelder_WZ: HCIdump thread: BLEScanRequester._initialized is False for hci0,  waiting for connection...
2022-02-27 18:52:57,306 - DEBUG    - Rauchmelder_WZ: HCIdump thread: connected to hci0
2022-02-27 18:52:57,307 - DEBUG    - Rauchmelder_WZ: HCIdump thread: BLEScanRequester._initialized is True for hci0,  connection established, send_scan_request succeeded.
2022-02-27 18:52:57,309 - DEBUG    - Rauchmelder_WZ: HCIdump thread: BLEScanRequester._initialized is False for hci0,  waiting for connection...
2022-02-27 18:52:57,312 - DEBUG    - Rauchmelder_WZ: HCIdump thread: connected to hci0
2022-02-27 18:52:57,313 - DEBUG    - Rauchmelder_WZ: HCIdump thread: BLEScanRequester._initialized is True for hci0,  connection established, send_scan_request succeeded.
2022-02-27 18:52:57,317 - DEBUG    - Rauchmelder_WZ: HCIdump thread: BLEScanRequester._initialized is False for hci0,  waiting for connection...
2022-02-27 18:52:57,321 - DEBUG    - Rauchmelder_WZ: HCIdump thread: connected to hci0
2022-02-27 18:52:57,322 - DEBUG    - Rauchmelder_WZ: HCIdump thread: BLEScanRequester._initialized is True for hci0,  connection established, send_scan_request succeeded.
2022-02-27 18:52:57,323 - DEBUG    - Rauchmelder_WZ: HCIdump thread: BLEScanRequester._initialized is False for hci0,  waiting for connection...
2022-02-27 18:52:57,327 - ERROR    - asyncio: Task was destroyed but it is pending!
task: <Task pending name='Task-1968' coro=<Event.wait() done, defined at /usr/lib/python3.9/asyncio/locks.py:213> wait_for=<Future pending cb=[<TaskWakeupMethWrapper object at 0x7f9c7f94c5e0>()]> cb=[_release_waiter(<Future pendi...c7f94cac0>()]>)() at /usr/lib/python3.9/asyncio/tasks.py:416]>
2022-02-27 18:52:57,329 - DEBUG    - Rauchmelder_WZ: HCIdump thread: connected to hci0
2022-02-27 18:52:57,329 - DEBUG    - Rauchmelder_WZ: HCIdump thread: BLEScanRequester._initialized is True for hci0,  connection established, send_scan_request succeeded.
2022-02-27 18:52:57,330 - DEBUG    - Rauchmelder_WZ: HCIdump thread: start main event_loop
2022-02-27 18:52:57,445 - DEBUG    - Rauchmelder_WZ: HCIdump thread: joining
2022-02-27 18:52:57,446 - DEBUG    - Rauchmelder_WZ: HCIdump thread: main event_loop stopped, finishing.
2022-02-27 18:52:57,447 - DEBUG    - Rauchmelder_WZ: HCIdump thread: Run finished
2022-02-27 18:52:57,447 - DEBUG    - Rauchmelder_WZ: HCIdump thread: joined
2022-02-27 18:52:57,447 - DEBUG    - Rauchmelder_WZ: BLE monitor stopped
2022-02-27 18:52:57,447 - DEBUG    - Rauchmelder_WZ: Spawning HCIdump thread
2022-02-27 18:52:57,447 - DEBUG    - Rauchmelder_WZ: HCIdump thread: Init
2022-02-27 18:52:57,447 - INFO     - Rauchmelder_WZ: Attention! Option report_unknown is enabled for Off sensors, be ready for a huge output
2022-02-27 18:52:57,447 - DEBUG    - Rauchmelder_WZ: 4 encryptors mac:key pairs loaded
2022-02-27 18:52:57,447 - DEBUG    - Rauchmelder_WZ: sensor whitelist: [54:EF:44:E2:AA:1D, FD:32:B5:9C:4F:CC, 54:EF:44:E4:6A:81, 54:EF:44:E3:E6:64, 54:EF:44:E4:72:D1]
2022-02-27 18:52:57,447 - DEBUG    - Rauchmelder_WZ: 5 sensor whitelist item(s) loaded
2022-02-27 18:52:57,447 - DEBUG    - Rauchmelder_WZ: 0 device tracker(s) being monitored
2022-02-27 18:52:57,448 - DEBUG    - Rauchmelder_WZ: HCIdump thread: Run
2022-02-27 18:52:57,449 - DEBUG    - Rauchmelder_WZ: HCIdump thread: BLEScanRequester._initialized is False for hci0,  waiting for connection...
2022-02-27 18:52:57,450 - DEBUG    - Rauchmelder_WZ: HCIdump thread: joining
Exception in thread Thread-11:
Traceback (most recent call last):
  File "/usr/lib/python3.9/threading.py", line 973, in _bootstrap_inner
    self.run()
  File "/opt/fhem/.local/lib/python3.9/site-packages/fhempy/lib/ble_monitor/hcidump.py", line 183, in run
    self._event_loop.run_until_complete(
  File "/usr/lib/python3.9/asyncio/base_events.py", line 640, in run_until_complete
    raise RuntimeError('Event loop stopped before Future completed.')
RuntimeError: Event loop stopped before Future completed.
2022-02-27 18:52:57,451 - DEBUG    - Rauchmelder_WZ: HCIdump thread: joined
2022-02-27 18:52:57,451 - DEBUG    - Rauchmelder_WZ: BLE monitor stopped
2022-02-27 18:52:57,451 - DEBUG    - Rauchmelder_WZ: Spawning HCIdump thread
2022-02-27 18:52:57,451 - DEBUG    - Rauchmelder_WZ: HCIdump thread: Init
2022-02-27 18:52:57,451 - INFO     - Rauchmelder_WZ: Attention! Option report_unknown is enabled for Off sensors, be ready for a huge output
2022-02-27 18:52:57,451 - DEBUG    - Rauchmelder_WZ: 3 encryptors mac:key pairs loaded
2022-02-27 18:52:57,451 - DEBUG    - Rauchmelder_WZ: sensor whitelist: [54:EF:44:E2:AA:1D, FD:32:B5:9C:4F:CC, 54:EF:44:E4:6A:81, 54:EF:44:E3:E6:64]
2022-02-27 18:52:57,451 - DEBUG    - Rauchmelder_WZ: 4 sensor whitelist item(s) loaded
2022-02-27 18:52:57,451 - DEBUG    - Rauchmelder_WZ: 0 device tracker(s) being monitored
2022-02-27 18:52:57,452 - DEBUG    - Rauchmelder_WZ: HCIdump thread: Run
2022-02-27 18:52:57,452 - DEBUG    - Rauchmelder_WZ: HCIdump thread: joining
2022-02-27 18:52:57,452 - DEBUG    - Rauchmelder_WZ: 'NoneType' object has no attribute 'call_soon_threadsafe'
2022-02-27 18:52:57,453 - DEBUG    - Rauchmelder_WZ: HCIdump thread: BLEScanRequester._initialized is False for hci0,  waiting for connection...
2022-02-27 18:52:57,463 - DEBUG    - Rauchmelder_WZ: HCIdump thread: connected to hci0
2022-02-27 18:52:57,464 - DEBUG    - Rauchmelder_WZ: HCIdump thread: BLEScanRequester._initialized is True for hci0,  connection established, send_scan_request succeeded.
2022-02-27 18:52:57,466 - DEBUG    - Rauchmelder_WZ: HCIdump thread: BLEScanRequester._initialized is False for hci0,  waiting for connection...
2022-02-27 18:52:57,469 - DEBUG    - Rauchmelder_WZ: HCIdump thread: connected to hci0
2022-02-27 18:52:57,469 - DEBUG    - Rauchmelder_WZ: HCIdump thread: BLEScanRequester._initialized is True for hci0,  connection established, send_scan_request succeeded.
2022-02-27 18:52:57,471 - DEBUG    - Rauchmelder_WZ: HCIdump thread: BLEScanRequester._initialized is False for hci0,  waiting for connection...
2022-02-27 18:52:57,474 - DEBUG    - Rauchmelder_WZ: HCIdump thread: connected to hci0
2022-02-27 18:52:57,475 - DEBUG    - Rauchmelder_WZ: HCIdump thread: BLEScanRequester._initialized is True for hci0,  connection established, send_scan_request succeeded.
2022-02-27 18:52:57,476 - DEBUG    - Rauchmelder_WZ: HCIdump thread: BLEScanRequester._initialized is False for hci0,  waiting for connection...
2022-02-27 18:52:57,479 - DEBUG    - Rauchmelder_WZ: HCIdump thread: connected to hci0
2022-02-27 18:52:57,480 - DEBUG    - Rauchmelder_WZ: HCIdump thread: BLEScanRequester._initialized is True for hci0,  connection established, send_scan_request succeeded.
2022-02-27 18:52:57,480 - DEBUG    - Rauchmelder_WZ: HCIdump thread: start main event_loop
2022-02-27 18:52:58,452 - DEBUG    - Rauchmelder_WZ: HCIdump thread: joined
2022-02-27 18:52:58,453 - ERROR    - Rauchmelder_WZ: Waiting for the HCIdump thread to finish took too long! (>10s)
2022-02-27 18:52:58,453 - DEBUG    - Rauchmelder_WZ: BLE monitor stopped
2022-02-27 18:52:58,453 - DEBUG    - Rauchmelder_WZ: Spawning HCIdump thread
2022-02-27 18:52:58,453 - DEBUG    - Rauchmelder_WZ: HCIdump thread: Init
2022-02-27 18:52:58,453 - INFO     - Rauchmelder_WZ: Attention! Option report_unknown is enabled for Off sensors, be ready for a huge output
2022-02-27 18:52:58,454 - DEBUG    - Rauchmelder_WZ: 4 encryptors mac:key pairs loaded
2022-02-27 18:52:58,454 - DEBUG    - Rauchmelder_WZ: sensor whitelist: [54:EF:44:E2:AA:1D, FD:32:B5:9C:4F:CC, 54:EF:44:E4:6A:81, 54:EF:44:E3:E6:64, 54:EF:44:E4:72:D1]
2022-02-27 18:52:58,454 - DEBUG    - Rauchmelder_WZ: 5 sensor whitelist item(s) loaded
2022-02-27 18:52:58,454 - DEBUG    - Rauchmelder_WZ: 0 device tracker(s) being monitored
2022-02-27 18:52:58,454 - DEBUG    - Rauchmelder_WZ: HCIdump thread: Run
2022-02-27 18:52:58,455 - ERROR    - asyncio: Task was destroyed but it is pending!
task: <Task pending name='Task-2017' coro=<Event.wait() done, defined at /usr/lib/python3.9/asyncio/locks.py:213> wait_for=<Future pending cb=[<TaskWakeupMethWrapper object at 0x7f9c7f981400>()]> cb=[_release_waiter(<Future pendi...c7e8f4a30>()]>)() at /usr/lib/python3.9/asyncio/tasks.py:416]>
2022-02-27 18:52:58,456 - DEBUG    - Rauchmelder_WZ: HCIdump thread: BLEScanRequester._initialized is False for hci0,  waiting for connection...
2022-02-27 18:52:58,457 - WARNING  - asyncio: Executing <Task finished name='Task-2018' coro=<FhemModule.Attr() done, defined at /opt/fhem/.local/lib/python3.9/site-packages/fhempy/lib/generic.py:168> result=None created at /usr/lib/python3.9/asyncio/tasks.py:462> took 1.007 seconds
2022-02-27 18:52:58,459 - DEBUG    - Rauchmelder_WZ: HCIdump thread: connected to hci0
2022-02-27 18:52:58,463 - DEBUG    - Rauchmelder_WZ: HCIdump thread: BLEScanRequester._initialized is True for hci0,  connection established, send_scan_request succeeded.
2022-02-27 18:52:58,465 - DEBUG    - Rauchmelder_WZ: HCIdump thread: BLEScanRequester._initialized is False for hci0,  waiting for connection...
2022-02-27 18:52:58,468 - DEBUG    - Rauchmelder_WZ: HCIdump thread: connected to hci0
2022-02-27 18:52:58,469 - DEBUG    - Rauchmelder_WZ: HCIdump thread: BLEScanRequester._initialized is True for hci0,  connection established, send_scan_request succeeded.
2022-02-27 18:52:58,471 - DEBUG    - Rauchmelder_WZ: HCIdump thread: BLEScanRequester._initialized is False for hci0,  waiting for connection...
2022-02-27 18:52:58,474 - DEBUG    - Rauchmelder_WZ: HCIdump thread: connected to hci0
2022-02-27 18:52:58,475 - DEBUG    - Rauchmelder_WZ: HCIdump thread: BLEScanRequester._initialized is True for hci0,  connection established, send_scan_request succeeded.
2022-02-27 18:52:58,477 - DEBUG    - Rauchmelder_WZ: HCIdump thread: BLEScanRequester._initialized is False for hci0,  waiting for connection...
2022-02-27 18:52:58,480 - DEBUG    - Rauchmelder_WZ: HCIdump thread: connected to hci0
2022-02-27 18:52:58,481 - DEBUG    - Rauchmelder_WZ: HCIdump thread: BLEScanRequester._initialized is True for hci0,  connection established, send_scan_request succeeded.
2022-02-27 18:52:58,483 - DEBUG    - Rauchmelder_WZ: HCIdump thread: BLEScanRequester._initialized is False for hci0,  waiting for connection...
2022-02-27 18:52:58,486 - DEBUG    - Rauchmelder_WZ: HCIdump thread: connected to hci0
2022-02-27 18:52:58,487 - DEBUG    - Rauchmelder_WZ: HCIdump thread: BLEScanRequester._initialized is True for hci0,  connection established, send_scan_request succeeded.
2022-02-27 18:52:58,487 - DEBUG    - Rauchmelder_WZ: HCIdump thread: start main event_loop
2022-02-27 18:53:22,021 - INFO     - fhempy.lib.fhem_pythonbinding: Starting fhempy...
2022-02-27 18:53:22,029 - INFO     - fhempy.lib.fhem_pythonbinding: Waiting for FHEM connection
2022-02-27 18:53:22,061 - INFO     - websockets.server: server listening on 0.0.0.0:15733
2022-02-27 18:53:31,680 - INFO     - websockets.server: connection open
2022-02-27 18:53:31,681 - INFO     - fhempy.lib.fhem_pythonbinding: Incoming FHEM connection: 127.0.0.1
2022-02-27 18:53:31,743 - WARNING  - zeroconf: Error with socket 9 (('192.168.42.0', 5353))): [Errno 126] Required key not available
Traceback (most recent call last):
  File "/usr/lib/python3.9/asyncio/selector_events.py", line 1056, in sendto
    self._sock.sendto(data, addr)
OSError: [Errno 126] Required key not available
Exception in thread Thread-2:
Traceback (most recent call last):
  File "/usr/lib/python3.9/threading.py", line 973, in _bootstrap_inner
    self.run()
  File "/opt/fhem/.local/lib/python3.9/site-packages/fhempy/lib/ble_monitor/hcidump.py", line 183, in run
    self._event_loop.run_until_complete(
  File "/usr/lib/python3.9/asyncio/base_events.py", line 640, in run_until_complete
    raise RuntimeError('Event loop stopped before Future completed.')
RuntimeError: Event loop stopped before Future completed.
Exception in thread Thread-3:
Traceback (most recent call last):
  File "/usr/lib/python3.9/threading.py", line 973, in _bootstrap_inner
    self.run()
  File "/opt/fhem/.local/lib/python3.9/site-packages/fhempy/lib/ble_monitor/hcidump.py", line 171, in run
    conn[hci], btctrl[hci] = self._event_loop.run_until_complete(
  File "/usr/lib/python3.9/asyncio/base_events.py", line 640, in run_until_complete
    raise RuntimeError('Event loop stopped before Future completed.')
RuntimeError: Event loop stopped before Future completed.
Exception in thread Thread-4:
Traceback (most recent call last):
  File "/usr/lib/python3.9/threading.py", line 973, in _bootstrap_inner
    self.run()
  File "/opt/fhem/.local/lib/python3.9/site-packages/fhempy/lib/ble_monitor/hcidump.py", line 183, in run
    self._event_loop.run_until_complete(
  File "/usr/lib/python3.9/asyncio/base_events.py", line 640, in run_until_complete
    raise RuntimeError('Event loop stopped before Future completed.')
RuntimeError: Event loop stopped before Future completed.
Exception in thread Thread-5:
Traceback (most recent call last):
  File "/usr/lib/python3.9/threading.py", line 973, in _bootstrap_inner
    self.run()
  File "/opt/fhem/.local/lib/python3.9/site-packages/fhempy/lib/ble_monitor/hcidump.py", line 196, in run
    self._event_loop.run_until_complete(
  File "/usr/lib/python3.9/asyncio/base_events.py", line 642, in run_until_complete
    return future.result()
  File "/usr/local/lib/python3.9/dist-packages/aioblescan/aioblescan.py", line 1806, in send_scan_request
    if self._use_ext_scan():
  File "/usr/local/lib/python3.9/dist-packages/aioblescan/aioblescan.py", line 1791, in _use_ext_scan
    return (self._supported_commands[37] & 0x60) == 0x60
TypeError: 'NoneType' object is not subscriptable
2022-02-27 18:53:32,363 - ERROR    - asyncio: Task was destroyed but it is pending!
task: <Task pending name='Task-94' coro=<Event.wait() done, defined at /usr/lib/python3.9/asyncio/locks.py:213> wait_for=<Future pending cb=[<TaskWakeupMethWrapper object at 0x7f9996ca73d0>()]> cb=[_release_waiter(<Future pendi...996be8280>()]>)() at /usr/lib/python3.9/asyncio/tasks.py:416]>
Exception ignored in: <coroutine object BaseEventLoop._create_connection_transport at 0x7f9996bf67c0>
Traceback (most recent call last):
  File "/usr/lib/python3.9/asyncio/base_events.py", line 1113, in _create_connection_transport
    transport.close()
  File "/usr/lib/python3.9/asyncio/selector_events.py", line 700, in close
    self._loop.call_soon(self._call_connection_lost, None)
  File "/usr/lib/python3.9/asyncio/base_events.py", line 746, in call_soon
    self._check_closed()
  File "/usr/lib/python3.9/asyncio/base_events.py", line 510, in _check_closed
    raise RuntimeError('Event loop is closed')
RuntimeError: Event loop is closed
2022-02-27 18:53:32,364 - ERROR    - asyncio: Task was destroyed but it is pending!
task: <Task pending name='Task-118' coro=<Event.wait() done, defined at /usr/lib/python3.9/asyncio/locks.py:213> wait_for=<Future pending cb=[<TaskWakeupMethWrapper object at 0x7f9996cd24c0>()]> cb=[_release_waiter(<Future pendi...996be8f40>()]>)() at /usr/lib/python3.9/asyncio/tasks.py:416]>
2022-02-27 18:53:34,492 - WARNING  - asyncio: Executing <Task pending name='Task-124' coro=<ring.ring_login() running at /opt/fhem/.local/lib/python3.9/site-packages/fhempy/lib/ring/ring.py:110> wait_for=<Future pending cb=[<TaskWakeupMethWrapper object at 0x7f9996be8f10>()] created at /usr/lib/python3.9/asyncio/base_events.py:424> created at /usr/lib/python3.9/asyncio/tasks.py:361> took 0.342 seconds
2022-02-27 18:53:36,461 - WARNING  - asyncio: Executing <Task pending name='Task-124' coro=<ring.ring_login() running at /opt/fhem/.local/lib/python3.9/site-packages/fhempy/lib/ring/ring.py:110> wait_for=<Future pending cb=[<TaskWakeupMethWrapper object at 0x7f9996be8f10>()] created at /usr/lib/python3.9/asyncio/base_events.py:424> created at /usr/lib/python3.9/asyncio/tasks.py:361> took 0.418 seconds
2022-02-27 19:23:38,484 - WARNING  - asyncio: Executing <Task pending name='Task-124' coro=<ring.ring_login() running at /opt/fhem/.local/lib/python3.9/site-packages/fhempy/lib/ring/ring.py:110> wait_for=<Future pending cb=[<TaskWakeupMethWrapper object at 0x7f999448c160>()] created at /usr/lib/python3.9/asyncio/base_events.py:424> created at /usr/lib/python3.9/asyncio/tasks.py:361> took 0.337 seconds

[dominik]

Danke für die Analyse! Das schau ich mir morgen an.

[Nighthawk]

Hallo Dominik, ein weiterer Neustart des fhempy Servers hat nun alle Probleme behoben
Jetzt funktionieren alle Beteiligten.

Danke nochmal für deine Geduld!

[dominik]

Hmmm...würde mich aber noch interessieren woran das lag/liegt. Melde dich bitte wenn das Problem wieder auftritt.

Aber dennoch gut, dass es nun läuft

[Nighthawk]

Hallo Dominik,
scheinbar ist das integrierte BT Modul meines Rechners höchst instabil.
Ich habe jetzt ein USB Dongle bestellt, mit der Hoffnung dass es stabil wird.

[dominik]

Ja, die alten RPis sind nicht optimal. Ich hatte mir einen RPi4 zugelegt, da läuft BT relativ stabil.

Wenn du den Dongle hast, vergiss nicht hci_device umzustellen.

[Nighthawk]

Hallo Dominik,

nach einem Update meines Ubuntus auf 22.04 bekomme ich gerade ble_monitor nicht mehr zum laufen.

Mein HCI hat sich zu HCI1 geändert und beim Versuch dies in MiScale zu ändern, bekomme ich folgende Fehlermeldeung:

Code Auswählen Erweitern

Failed to execute function Attr: Traceback (most recent call last):
  File "/opt/fhem/.local/lib/python3.10/site-packages/fhempy/lib/fhem_pythonbinding.py", line 262, in handle_function
    ret = await self.execute_function(hash, fhem_reply_done, nmInstance)
  File "/opt/fhem/.local/lib/python3.10/site-packages/fhempy/lib/fhem_pythonbinding.py", line 333, in execute_function
    ret = await asyncio.wait_for(
  File "/usr/lib/python3.10/asyncio/tasks.py", line 445, in wait_for
    return fut.result()
  File "/opt/fhem/.local/lib/python3.10/site-packages/fhempy/lib/generic.py", line 169, in Attr
    return await utils.handle_attr(self._conf_attr, self, hash, args, argsh)
  File "/opt/fhem/.local/lib/python3.10/site-packages/fhempy/lib/utils.py", line 69, in handle_attr
    return await fct_call(hash)
  File "/opt/fhem/.local/lib/python3.10/site-packages/fhempy/lib/miscale/miscale.py", line 184, in set_attr_hci_interface
    self.blemonitor.unregister_device(self)
  File "/opt/fhem/.local/lib/python3.10/site-packages/fhempy/lib/ble_monitor/blemonitor.py", line 149, in unregister_device
    self.update_hci_interface(fhemdevice.hci())
  File "/opt/fhem/.local/lib/python3.10/site-packages/fhempy/lib/ble_monitor/blemonitor.py", line 131, in update_hci_interface
    self.config[CONF_BT_INTERFACE].append(BT_INTERFACES[fhem_dev.hci()])
KeyError: 0



und auch beim ble_monitor bekomme ich eine Fehlermeldung:

Code Auswählen Erweitern

Failed to execute function Attr: Traceback (most recent call last):
  File "/opt/fhem/.local/lib/python3.10/site-packages/fhempy/lib/fhem_pythonbinding.py", line 262, in handle_function
    ret = await self.execute_function(hash, fhem_reply_done, nmInstance)
  File "/opt/fhem/.local/lib/python3.10/site-packages/fhempy/lib/fhem_pythonbinding.py", line 333, in execute_function
    ret = await asyncio.wait_for(
  File "/usr/lib/python3.10/asyncio/tasks.py", line 445, in wait_for
    return fut.result()
  File "/opt/fhem/.local/lib/python3.10/site-packages/fhempy/lib/generic.py", line 169, in Attr
    return await utils.handle_attr(self._conf_attr, self, hash, args, argsh)
  File "/opt/fhem/.local/lib/python3.10/site-packages/fhempy/lib/utils.py", line 69, in handle_attr
    return await fct_call(hash)
  File "/opt/fhem/.local/lib/python3.10/site-packages/fhempy/lib/ble_monitor/ble_monitor.py", line 66, in set_attr_generic
    self.blemonitor.unregister_device(self)
  File "/opt/fhem/.local/lib/python3.10/site-packages/fhempy/lib/ble_monitor/blemonitor.py", line 149, in unregister_device
    self.update_hci_interface(fhemdevice.hci())
  File "/opt/fhem/.local/lib/python3.10/site-packages/fhempy/lib/ble_monitor/blemonitor.py", line 131, in update_hci_interface
    self.config[CONF_BT_INTERFACE].append(BT_INTERFACES[fhem_dev.hci()])
KeyError: 0

[dominik]

Poste bitte den Output von
sudo hciconfig -a

[Nighthawk]

Code Auswählen Erweitern

sudo hciconfig -a
hci1: Type: Primary  Bus: USB
BD Address: E8:48:B8:C8:20:00  ACL MTU: 1021:6  SCO MTU: 255:12
UP RUNNING
RX bytes:4623 acl:0 sco:0 events:298 errors:0
TX bytes:35815 acl:0 sco:0 commands:268 errors:0
Features: 0xff 0xff 0xff 0xfe 0xdb 0xfd 0x7b 0x87
Packet type: DM1 DM3 DM5 DH1 DH3 DH5 HV1 HV2 HV3
Link policy: RSWITCH HOLD SNIFF PARK
Link mode: PERIPHERAL ACCEPT
Name: 'PC'
Class: 0x6c0104
Service Classes: Rendering, Capturing, Audio, Telephony
Device Class: Computer, Desktop workstation
HCI Version: 5.1 (0xa)  Revision: 0x9a9
LMP Version: 5.1 (0xa)  Subversion: 0x8a6b
Manufacturer: Realtek Semiconductor Corporation (93)


[dominik]

Hast du den lokalen BT Adapter deaktiviert? Nur hci1 ohne hci0 zu haben ist eigenartig 

[Nighthawk]

Das Abschalten im Bios ist leider wirkungslos, im OS ist aber die Interne Schnittstelle nicht zu sehen:

Code Auswählen Erweitern

hciconfig --all
hci1: Type: Primary  Bus: USB
BD Address: E8:48:B8:C8:20:00  ACL MTU: 1021:6  SCO MTU: 255:12
UP RUNNING
RX bytes:4900 acl:0 sco:0 events:301 errors:0
TX bytes:35824 acl:0 sco:0 commands:271 errors:0
Features: 0xff 0xff 0xff 0xfe 0xdb 0xfd 0x7b 0x87
Packet type: DM1 DM3 DM5 DH1 DH3 DH5 HV1 HV2 HV3
Link policy: RSWITCH HOLD SNIFF PARK
Link mode: PERIPHERAL ACCEPT
Name: 'alex-L55'
Class: 0x6c0104
Service Classes: Rendering, Capturing, Audio, Telephony
Device Class: Computer, Desktop workstation
HCI Version: 5.1 (0xa)  Revision: 0x9a9
LMP Version: 5.1 (0xa)  Subversion: 0x8a6b
Manufacturer: Realtek Semiconductor Corporation (93)



EDIT:

ich habe jetzt mal alle anderen EInträge ausser dem E8:48:B8:C8:20:00 im Ordner /var/lib/bluetooth/ gelöscht und nach einem Neustart wurde mein BT Device jetzt HCI0.
Leider bekomme ich weiterhin einen Fehler im ble_monitor, siehe unten.
Kann es daran liegen dass aioblescan nur daten liefert wenn es mit Rootrechten ausgeführt wird?
Wie bekomme ich den fhem user dazu auf die HCI Schnittestelle zugreifen zu können?

Code Auswählen Erweitern

2022-05-15 19:41:07,422 - ERROR    - Feuermelder_Galerie: HCIdump thread: Something wrong - interface hci0 not ready, and will be skipped for current scan period.
Exception in thread Thread-7:
Traceback (most recent call last):
  File "/usr/lib/python3.10/threading.py", line 1009, in _bootstrap_inner
    self.run()
  File "/opt/fhem/.local/lib/python3.10/site-packages/fhempy/lib/ble_monitor/hcidump.py", line 183, in run
    self._event_loop.run_until_complete(
  File "/usr/lib/python3.10/asyncio/base_events.py", line 644, in run_until_complete
    raise RuntimeError('Event loop stopped before Future completed.')
RuntimeError: Event loop stopped before Future completed.
2022-05-15 19:41:11,101 - ERROR    - fhempy.lib.fhem_pythonbinding: Failed to execute function Attr: Traceback (most recent call last):
  File "/opt/fhem/.local/lib/python3.10/site-packages/fhempy/lib/fhem_pythonbinding.py", line 262, in handle_function
    ret = await self.execute_function(hash, fhem_reply_done, nmInstance)
  File "/opt/fhem/.local/lib/python3.10/site-packages/fhempy/lib/fhem_pythonbinding.py", line 333, in execute_function
    ret = await asyncio.wait_for(
  File "/usr/lib/python3.10/asyncio/tasks.py", line 445, in wait_for
    return fut.result()
  File "/opt/fhem/.local/lib/python3.10/site-packages/fhempy/lib/generic.py", line 169, in Attr
    return await utils.handle_attr(self._conf_attr, self, hash, args, argsh)
  File "/opt/fhem/.local/lib/python3.10/site-packages/fhempy/lib/utils.py", line 69, in handle_attr
    return await fct_call(hash)
  File "/opt/fhem/.local/lib/python3.10/site-packages/fhempy/lib/ble_monitor/ble_monitor.py", line 67, in set_attr_generic
    self.blemonitor.register_device(self)
  File "/opt/fhem/.local/lib/python3.10/site-packages/fhempy/lib/ble_monitor/blemonitor.py", line 121, in register_device
    self.update_hci_interface(fhemdevice.hci())
  File "/opt/fhem/.local/lib/python3.10/site-packages/fhempy/lib/ble_monitor/blemonitor.py", line 131, in update_hci_interface
    self.config[CONF_BT_INTERFACE].append(BT_INTERFACES[fhem_dev.hci()])
KeyError: '0'





Danke und Gruß
Alex

[dominik]

Poste bitte nochmals hciconfig -a, weil das sieht aus, als wäre es noch immer nicht da.

[Nighthawk]

Code Auswählen Erweitern

hciconfig -a
hci0: Type: Primary  Bus: USB
BD Address: E8:48:B8:C8:20:00  ACL MTU: 1021:6  SCO MTU: 255:12
UP RUNNING
RX bytes:5261 acl:0 sco:0 events:279 errors:0
TX bytes:34249 acl:0 sco:0 commands:262 errors:0
Features: 0xff 0xff 0xff 0xfe 0xdb 0xfd 0x7b 0x87
Packet type: DM1 DM3 DM5 DH1 DH3 DH5 HV1 HV2 HV3
Link policy: RSWITCH HOLD SNIFF PARK
Link mode: PERIPHERAL ACCEPT
Name: 'alex-L55'
Class: 0x6c0104
Service Classes: Rendering, Capturing, Audio, Telephony
Device Class: Computer, Desktop workstation
HCI Version: 5.1 (0xa)  Revision: 0x9a9
LMP Version: 5.1 (0xa)  Subversion: 0x8a6b
Manufacturer: Realtek Semiconductor Corporation (93)


[dominik]

Mach bitte noch folgende Tests:

Code Auswählen Erweitern


sudo -u fhem bash
pip3 install btsocket==1.0.0

Danach nochmals ein Test. Wenn es nicht funktioniert, dann bitte...

Code Auswählen Erweitern


python3
import fhempy.lib.ble_monitor.bt_helpers
fhempy.lib.ble_monitor.bt_helpers.hci_get_mac([0,1,2,3])


[Nighthawk]

Bei dem ersten Befehlen bekomme ich die Fehlermeldung dass es die Version 1.0.0 nicht gibt:

Code Auswählen Erweitern

ERROR: Could not find a version that satisfies the requirement btsocket==1.0.0 (from versions: 0.1.0, 0.2.0)
ERROR: No matching distribution found for btsocket==1.0.0

[dominik]

Sorry, Tippfehler von mir. Sollte 0.1.0 heißen statt 1.0.0.

[Nighthawk]

Leider ebenfalls ohne Erfolg:

Code Auswählen Erweitern

2022-05-17 20:20:20,029 - INFO     - fhempy.lib.fhem_pythonbinding: Starting fhempy 0.1.331...
2022-05-17 20:20:20,032 - INFO     - fhempy.lib.fhem_pythonbinding: Waiting for FHEM connection
2022-05-17 20:20:20,072 - INFO     - websockets.server: server listening on 0.0.0.0:15733
2022-05-17 20:20:37,756 - INFO     - websockets.server: connection open
2022-05-17 20:20:37,756 - INFO     - fhempy.lib.fhem_pythonbinding: Incoming FHEM connection: 127.0.0.1
2022-05-17 20:20:37,793 - WARNING  - zeroconf: Error with socket 19 (('192.168.42.0', 5353))): [Errno 126] Required key not available
Traceback (most recent call last):
  File "/usr/lib/python3.10/asyncio/selector_events.py", line 1053, in sendto
    self._sock.sendto(data, addr)
OSError: [Errno 126] Required key not available
Exception in thread Thread-1:
Traceback (most recent call last):
  File "/usr/lib/python3.10/threading.py", line 1009, in _bootstrap_inner
    self.run()
  File "/opt/fhem/.local/lib/python3.10/site-packages/fhempy/lib/ble_monitor/hcidump.py", line 183, in run
    self._event_loop.run_until_complete(
  File "/usr/lib/python3.10/asyncio/base_events.py", line 644, in run_until_complete
    raise RuntimeError('Event loop stopped before Future completed.')
RuntimeError: Event loop stopped before Future completed.
2022-05-17 20:20:42,121 - ERROR    - Feuermelder_SZ: Waiting for the HCIdump thread to finish took too long! (>10s)
2022-05-17 20:20:42,122 - WARNING  - asyncio: Executing <Task finished name='Task-38' coro=<ble_monitor.Define() done, defined at /opt/fhem/.local/lib/python3.10/site-packages/fhempy/lib/ble_monitor/ble>
Exception in thread Thread-3:
Traceback (most recent call last):
  File "/usr/lib/python3.10/threading.py", line 1009, in _bootstrap_inner
    self.run()
  File "/opt/fhem/.local/lib/python3.10/site-packages/fhempy/lib/ble_monitor/hcidump.py", line 171, in run
    conn[hci], btctrl[hci] = self._event_loop.run_until_complete(
  File "/usr/lib/python3.10/asyncio/base_events.py", line 644, in run_until_complete
    raise RuntimeError('Event loop stopped before Future completed.')
RuntimeError: Event loop stopped before Future completed.
Exception in thread Thread-4:
Traceback (most recent call last):
  File "/usr/lib/python3.10/threading.py", line 1009, in _bootstrap_inner
    self.run()
  File "/opt/fhem/.local/lib/python3.10/site-packages/fhempy/lib/ble_monitor/hcidump.py", line 183, in run
    self._event_loop.run_until_complete(
  File "/usr/lib/python3.10/asyncio/base_events.py", line 644, in run_until_complete
    raise RuntimeError('Event loop stopped before Future completed.')
RuntimeError: Event loop stopped before Future completed.
2022-05-17 20:20:46,126 - ERROR    - Feuermelder_SZ: HCIdump thread: Something wrong - interface hci0 not ready, and will be skipped for current scan period.
2022-05-17 20:20:47,390 - ERROR    - Feuermelder_SZ: HCIdump thread: Something wrong - interface hci0 not ready, and will be skipped for current scan period.
2022-05-17 20:20:51,130 - ERROR    - Feuermelder_SZ: HCIdump thread: Something wrong - interface hci0 not ready, and will be skipped for current scan period.
2022-05-17 20:20:52,394 - ERROR    - Feuermelder_SZ: HCIdump thread: Something wrong - interface hci0 not ready, and will be skipped for current scan period.
2022-05-17 20:20:57,398 - ERROR    - Feuermelder_SZ: HCIdump thread: Something wrong - interface hci0 not ready, and will be skipped for current scan period.
2022-05-17 20:21:02,402 - ERROR    - Feuermelder_SZ: HCIdump thread: Something wrong - interface hci0 not ready, and will be skipped for current scan period.
2022-05-17 20:21:07,406 - ERROR    - Feuermelder_SZ: HCIdump thread: Something wrong - interface hci0 not ready, and will be skipped for current scan period.


Code Auswählen Erweitern

python3
Python 3.10.4 (main, Apr  2 2022, 09:04:19) [GCC 11.2.0] on linux
Type "help", "copyright", "credits" or "license" for more information.
>>> import fhempy.lib.ble_monitor.bt_helpers
>>> fhempy.lib.ble_monitor.bt_helpers.hci_get_mac([0,1,2,3])
{0: 'E8:48:B8:C8:20:00'}


[dominik]

Hast du Python 3.10 manuell installiert? Wenn ja, probier es bitte mal mit der Standardversion die mit Ubuntu ausgeliefert wird.

[Nighthawk]

Die Version wird offensichtlich mit Ubuntu 22.04 ausgeliefert:

Code Auswählen Erweitern

Holen:1 http://de.archive.ubuntu.com/ubuntu jammy/main amd64 python3 amd64 3.10.4-0ubuntu2 [22,8 kB]
Es wurden 22,8 kB in 0 s geholt (303 kB/s).
Vormals nicht ausgewähltes Paket python3 wird gewählt.
(Lese Datenbank ... 233714 Dateien und Verzeichnisse sind derzeit installiert.)
Vorbereitung zum Entpacken von .../python3_3.10.4-0ubuntu2_amd64.deb ...
Entpacken von python3 (3.10.4-0ubuntu2) ...
python3 (3.10.4-0ubuntu2) wird eingerichtet ...
running python rtupdate hooks for python3.10...
running python post-rtupdate hooks for python3.10...


[dominik]

Hast du auch andere Module von fhempy im Einsatz? Funktionieren diese? Weil diese Fehlermeldung irritiert mich ebenfalls:

OSError: [Errno 126] Required key not available

[Nighthawk]

Hallo Dominik,

nein, aktuell ist nur ble_monitor im Einsatz.
Bis vor Kurzem hatte ich noch das Ring Modul gehabt, dieses wurde aber durch das Ring_Mqtt abgelöst.

[dominik]

Probier bitte mal das helloworld Modul um zu sehen ob es generell funktioniert

[Nighthawk]

Das scheint auch nicht richtig zu funktionieren:

Code Auswählen Erweitern

Internals:
   CFGFN     
   DEF        helloworld
   FHEMPYTYPE helloworld
   FUUID      62868d7e-f33f-357a-4614-d8ddb3f49d1d6949
   IODev      local_pybinding
   NAME       TEST
   NR         3826
   PYTHONTYPE helloworld
   STATE      long running off
   TYPE       fhempy
   Helper:
     DBLOG:
       state:
         logdb:
           TIME       1652985315.26582
           VALUE      long running off
   READINGS:
     2022-05-19 20:35:15   state           long running off
   args:
     TEST
     fhempy
     helloworld
   argsh:
Attributes:


Code Auswählen Erweitern

2022-05-19 20:33:38,700 - ERROR    - asyncio: Task was destroyed but it is pending!
task: <Task pending name='Task-82' coro=<Event.wait() done, defined at /usr/lib/python3.10/asyncio/locks.py:201> wait_for=<Future pending cb=[Task.task_wakeup()]> cb=[_release_waiter(<Future pendi...ask_wakeup()]>)() at /usr/lib/python3.10/asyncio/tasks.py:387]>
/usr/lib/python3.10/traceback.py:376: RuntimeWarning: coroutine 'Event.wait' was never awaited
  result.append(FrameSummary(
RuntimeWarning: Enable tracemalloc to get the object allocation traceback
2022-05-19 20:33:38,708 - ERROR    - asyncio: Task was destroyed but it is pending!
task: <Task pending name='Task-91' coro=<Event.wait() running at /usr/lib/python3.10/asyncio/locks.py:201> cb=[_release_waiter(<Future pendi...ask_wakeup()]>)() at /usr/lib/python3.10/asyncio/tasks.py:387]>
2022-05-19 20:33:38,709 - ERROR    - asyncio: Task was destroyed but it is pending!
task: <Task pending name='Task-94' coro=<Event.wait() done, defined at /usr/lib/python3.10/asyncio/locks.py:201> wait_for=<Future pending cb=[Task.task_wakeup()]> cb=[_release_waiter(<Future pendi...ask_wakeup()]>)() at /usr/lib/python3.10/asyncio/tasks.py:387]>


[dominik]

Kannst du mir bitte das gesamte Log posten? Danke!

[Nighthawk]

Da ist leider nicht so viel mehr drin:

Code Auswählen Erweitern

022-05-19 16:49:04,983 - INFO     - fhempy.lib.fhem_pythonbinding: Starting fhempy 0.1.348...
2022-05-19 16:49:04,985 - INFO     - fhempy.lib.fhem_pythonbinding: Waiting for FHEM connection
2022-05-19 16:49:05,012 - INFO     - websockets.server: server listening on 0.0.0.0:15733
2022-05-19 16:49:22,611 - INFO     - websockets.server: connection open
2022-05-19 16:49:22,612 - INFO     - fhempy.lib.fhem_pythonbinding: Incoming FHEM connection: 127.0.0.1
2022-05-19 16:49:22,660 - WARNING  - zeroconf: Error with socket 15 (('192.168.42.0', 5353))): [Errno 126] Required key not available
Traceback (most recent call last):
  File "/usr/lib/python3.10/asyncio/selector_events.py", line 1053, in sendto
    self._sock.sendto(data, addr)
OSError: [Errno 126] Required key not available
Exception in thread Thread-1:
Traceback (most recent call last):
  File "/usr/lib/python3.10/threading.py", line 1009, in _bootstrap_inner
    self.run()
  File "/opt/fhem/.local/lib/python3.10/site-packages/fhempy/lib/ble_monitor/hcidump.py", line 183, in run
    self._event_loop.run_until_complete(
  File "/usr/lib/python3.10/asyncio/base_events.py", line 644, in run_until_complete
    raise RuntimeError('Event loop stopped before Future completed.')
RuntimeError: Event loop stopped before Future completed.
2022-05-19 16:49:27,515 - ERROR    - Feuermelder_SZ: Waiting for the HCIdump thread to finish took too long! (>10s)
2022-05-19 16:49:27,516 - WARNING  - asyncio: Executing <Task finished name='Task-40' coro=<ble_monitor.Define() done, defined at /opt/fhem/.local/lib/python3.10/site-packages/fhempy/lib/ble_monitor/ble_monitor.py:33> result=None created at /usr/lib/python3.10/asyncio/tasks.py:636> took 1.002 seconds
Exception in thread Thread-3:
Traceback (most recent call last):
  File "/usr/lib/python3.10/threading.py", line 1009, in _bootstrap_inner
    self.run()
  File "/opt/fhem/.local/lib/python3.10/site-packages/fhempy/lib/ble_monitor/hcidump.py", line 183, in run
    self._event_loop.run_until_complete(
  File "/usr/lib/python3.10/asyncio/base_events.py", line 644, in run_until_complete
    raise RuntimeError('Event loop stopped before Future completed.')
RuntimeError: Event loop stopped before Future completed.
Exception in thread Thread-4:
Traceback (most recent call last):
  File "/usr/lib/python3.10/threading.py", line 1009, in _bootstrap_inner
    self.run()
  File "/opt/fhem/.local/lib/python3.10/site-packages/fhempy/lib/ble_monitor/hcidump.py", line 183, in run
    self._event_loop.run_until_complete(
  File "/usr/lib/python3.10/asyncio/base_events.py", line 644, in run_until_complete
    raise RuntimeError('Event loop stopped before Future completed.')
RuntimeError: Event loop stopped before Future completed.
2022-05-19 16:49:31,519 - ERROR    - Feuermelder_SZ: HCIdump thread: Something wrong - interface hci0 not ready, and will be skipped for current scan period.
2022-05-19 16:49:32,639 - ERROR    - Feuermelder_SZ: HCIdump thread: Something wrong - interface hci0 not ready, and will be skipped for current scan period.
2022-05-19 16:49:36,523 - ERROR    - Feuermelder_SZ: HCIdump thread: Something wrong - interface hci0 not ready, and will be skipped for current scan period.
2022-05-19 16:49:37,643 - ERROR    - Feuermelder_SZ: HCIdump thread: Something wrong - interface hci0 not ready, and will be skipped for current scan period.
2022-05-19 16:49:42,649 - ERROR    - Feuermelder_SZ: HCIdump thread: Something wrong - interface hci0 not ready, and will be skipped for current scan period.
2022-05-19 16:49:47,655 - ERROR    - Feuermelder_SZ: HCIdump thread: Something wrong - interface hci0 not ready, and will be skipped for current scan period.
2022-05-19 16:49:52,658 - ERROR    - Feuermelder_SZ: HCIdump thread: Something wrong - interface hci0 not ready, and will be skipped for current scan period.
2022-05-19 20:33:38,700 - ERROR    - asyncio: Task was destroyed but it is pending!
task: <Task pending name='Task-82' coro=<Event.wait() done, defined at /usr/lib/python3.10/asyncio/locks.py:201> wait_for=<Future pending cb=[Task.task_wakeup()]> cb=[_release_waiter(<Future pendi...ask_wakeup()]>)() at /usr/lib/python3.10/asyncio/tasks.py:387]>
/usr/lib/python3.10/traceback.py:376: RuntimeWarning: coroutine 'Event.wait' was never awaited
  result.append(FrameSummary(
RuntimeWarning: Enable tracemalloc to get the object allocation traceback
2022-05-19 20:33:38,708 - ERROR    - asyncio: Task was destroyed but it is pending!
task: <Task pending name='Task-91' coro=<Event.wait() running at /usr/lib/python3.10/asyncio/locks.py:201> cb=[_release_waiter(<Future pendi...ask_wakeup()]>)() at /usr/lib/python3.10/asyncio/tasks.py:387]>
2022-05-19 20:33:38,709 - ERROR    - asyncio: Task was destroyed but it is pending!
task: <Task pending name='Task-94' coro=<Event.wait() done, defined at /usr/lib/python3.10/asyncio/locks.py:201> wait_for=<Future pending cb=[Task.task_wakeup()]> cb=[_release_waiter(<Future pendi...ask_wakeup()]>)() at /usr/lib/python3.10/asyncio/tasks.py:387]>


[dominik]

Teste bitte mal mit Version 0.1.355. Ich habe dort Zeroconf aktualisiert, bin mir aber noch nicht sicher ob es damit zusammenhaengt.

[Nighthawk]

update all + shutdown restart bringen leider keine Veränderung.

[dominik]

Kannst du es bitte mal mit Python 3.9 probieren?

[Nighthawk]

hmm, ich habe versucht python 2.10 vom System zu verbannen, leider scheint es nicht so trivial möglich zu sein, denn jetzt sieht es so aus als hätte ich beide Versionen:

Code Auswählen Erweitern


~$ python3 -V
Python 3.10.4
~$ python3.9 -V
Python 3.9.12


wie bekomme ich nun ble_monitor dazu die 3.9 zu nutzen, bzw wie werde ich die 3.10 komplett los?

[Nighthawk]

ok, habe es jetzt folgender Maßen umgebogen:

Code Auswählen Erweitern

# update-alternatives --install /usr/bin/python python /usr/bin/python3.9 1
# sudo update-alternatives  --set python /usr/bin/python3.9
# update-alternatives --install /usr/bin/python3 python3 /usr/bin/python3.9 1
# sudo update-alternatives  --set python3 /usr/bin/python3.9

leider hat das ebenfalls nicht zur Lösung des Problems geführt:

Code Auswählen Erweitern

2022-05-21 10:11:03,351 - INFO     - fhempy.lib.fhem_pythonbinding: Starting fhempy...
2022-05-21 10:11:03,355 - INFO     - fhempy.lib.fhem_pythonbinding: Waiting for FHEM connection
2022-05-21 10:11:03,405 - INFO     - websockets.server: server listening on 0.0.0.0:15733
2022-05-21 10:11:20,639 - INFO     - websockets.server: connection open
2022-05-21 10:11:20,643 - INFO     - fhempy.lib.fhem_pythonbinding: Incoming FHEM connection: 127.0.0.1
2022-05-21 10:11:20,743 - WARNING  - zeroconf: Error with socket 9 (('192.168.42.0', 5353))): [Errno 126] Required key not available
Traceback (most recent call last):
  File "/usr/lib/python3.9/asyncio/selector_events.py", line 1053, in sendto
    self._sock.sendto(data, addr)
OSError: [Errno 126] Required key not available
Exception in thread Thread-1:
Traceback (most recent call last):
  File "/usr/lib/python3.9/threading.py", line 973, in _bootstrap_inner
    self.run()
  File "/opt/fhem/.local/lib/python3.9/site-packages/fhempy/lib/ble_monitor/hcidump.py", line 183, in run
    self._event_loop.run_until_complete(
  File "/usr/lib/python3.9/asyncio/base_events.py", line 645, in run_until_complete
    raise RuntimeError('Event loop stopped before Future completed.')
RuntimeError: Event loop stopped before Future completed.
2022-05-21 10:11:25,110 - ERROR    - Feuermelder_ SZ: Waiting for the HCIdump thread to finish took too long! (>10s)
2022-05-21 10:11:25,111 - WARNING  - asyncio: Executing <Task finished name='Task-45' coro=<ble_monitor.Define() done, defined at /opt/fhem/.local/lib/python3.9/site-pa>
Exception in thread Thread-3:
Traceback (most recent call last):
  File "/usr/lib/python3.9/threading.py", line 973, in _bootstrap_inner
    self.run()
  File "/opt/fhem/.local/lib/python3.9/site-packages/fhempy/lib/ble_monitor/hcidump.py", line 171, in run
    conn[hci], btctrl[hci] = self._event_loop.run_until_complete(
  File "/usr/lib/python3.9/asyncio/base_events.py", line 645, in run_until_complete
    raise RuntimeError('Event loop stopped before Future completed.')
RuntimeError: Event loop stopped before Future completed.
Exception in thread Thread-4:
Traceback (most recent call last):
  File "/usr/lib/python3.9/threading.py", line 973, in _bootstrap_inner
    self.run()
  File "/opt/fhem/.local/lib/python3.9/site-packages/fhempy/lib/ble_monitor/hcidump.py", line 183, in run
    self._event_loop.run_until_complete(
  File "/usr/lib/python3.9/asyncio/base_events.py", line 645, in run_until_complete
    raise RuntimeError('Event loop stopped before Future completed.')
RuntimeError: Event loop stopped before Future completed.
2022-05-21 10:11:29,116 - ERROR    - Feuermelder_ SZ: HCIdump thread: Something wrong - interface hci0 not ready, and will be skipped for current scan period.
2022-05-21 10:11:30,187 - ERROR    - Feuermelder_ SZ: HCIdump thread: Something wrong - interface hci0 not ready, and will be skipped for current scan period.
2022-05-21 10:11:34,119 - ERROR    - Feuermelder_ SZ: HCIdump thread: Something wrong - interface hci0 not ready, and will be skipped for current scan period.
2022-05-21 10:11:35,191 - ERROR    - Feuermelder_ SZ: HCIdump thread: Something wrong - interface hci0 not ready, and will be skipped for current scan period.
2022-05-21 10:11:40,195 - ERROR    - Feuermelder_ SZ: HCIdump thread: Something wrong - interface hci0 not ready, and will be skipped for current scan period.
2022-05-21 10:11:45,201 - ERROR    - Feuermelder_ SZ: HCIdump thread: Something wrong - interface hci0 not ready, and will be skipped for current scan period.
2022-05-21 10:11:50,207 - ERROR    - Feuermelder_ SZ: HCIdump thread: Something wrong - interface hci0 not ready, and will be skipped for current scan period.


[marboj]

Hallo zusammen,

würde das Modul auch gerne nutzen, habe allerdings Python 3.7 installiert. Nach dem Upgrade auf Bullseye liefen meine Scripte nicht mehr. Kann mir jemand einen Tip geben, was zu tun ist?

Oder besteht die Möglichkeit, das 3.7 nochmal supportet wird?

Gruß
Marco

[dominik]

@marboj, Python 3.7 sollte auch funktionieren. Was bekommst du für einen Fehler?

@Nighthawk, probier bitte mit Python 3.9 nochmals helloworld.

[marboj]

Habe den XIAOMI Temp/Hum Sensor LYWSD03MMC im Einsatz. Dafür habe ich das mi_temphum installiert.

Das Gerät zeigt im Status: Server offline und als Reading habe ich lediglich den Namen des Geräts: ATC_45CBF4.

Hier das Log von fhempy:

Code Auswählen Erweitern

2022-05-21 23:05:38,656 - ERROR    - __main__: Failed to load fhempy
Traceback (most recent call last):
  File "FHEM/bindings/python/bin/fhempy", line 100, in <module>
    import fhempy.lib.fhem_pythonbinding as fpb
ModuleNotFoundError: No module named 'fhempy'
2022-05-21 23:05:38,657 - INFO     - __main__: Attempting install of fhempy>=0.1.12
2022-05-21 23:06:14,519 - INFO     - __main__: Successfully installed fhempy>=0.1.12
2022-05-21 23:06:14,520 - ERROR    - __main__: Failed to import fhempy, exit now...
2022-05-21 23:07:15,503 - INFO     - fhempy.lib.fhem_pythonbinding: Starting fhempy 0.1.357...
2022-05-21 23:07:15,553 - INFO     - fhempy.lib.pkg_installer: Attempting install of async-upnp-client==0.20.0
2022-05-21 23:07:21,605 - INFO     - fhempy.lib.pkg_installer: Successfully installed async-upnp-client==0.20.0 update!
2022-05-21 23:07:21,607 - INFO     - fhempy.lib.fhem_pythonbinding: Waiting for FHEM connection
2022-05-21 23:07:21,651 - INFO     - websockets.server: server listening on 0.0.0.0:15733
2022-05-21 23:07:24,512 - INFO     - websockets.server: connection open
2022-05-21 23:07:24,514 - INFO     - fhempy.lib.fhem_pythonbinding: Incoming FHEM connection: 127.0.0.1
2022-05-21 23:07:24,700 - INFO     - fhempy.lib.pkg_installer: Attempting install of mitemp-bt==0.0.3
2022-05-21 23:07:30,620 - INFO     - fhempy.lib.pkg_installer: Successfully installed mitemp-bt==0.0.3 update!
2022-05-21 23:07:34,427 - ERROR    - mi_temphum: Failed to get updates from mitemp A4:C1:38:45:CB:F4
2022-05-21 23:13:59,260 - INFO     - fhempy.lib.fhem_pythonbinding: Starting fhempy 0.1.357...
2022-05-21 23:13:59,268 - INFO     - fhempy.lib.fhem_pythonbinding: Waiting for FHEM connection
2022-05-21 23:13:59,313 - INFO     - websockets.server: server listening on 0.0.0.0:15733
2022-05-21 23:14:03,524 - INFO     - websockets.server: connection open
2022-05-21 23:14:03,526 - INFO     - fhempy.lib.fhem_pythonbinding: Incoming FHEM connection: 127.0.0.1
2022-05-21 23:14:04,452 - ERROR    - mi_temphum: Failed to get updates from mitemp A4:C1:38:45:CB:F4
2022-05-21 23:30:58,653 - INFO     - fhempy.lib.fhem_pythonbinding: Start update...
2022-05-21 23:30:58,654 - INFO     - fhempy.lib.pkg_installer: Attempting install of fhempy
2022-05-21 23:31:04,503 - INFO     - fhempy.lib.pkg_installer: Successfully installed fhempy update!
2022-05-21 23:31:04,526 - INFO     - fhempy.lib.fhem_pythonbinding: Restart initiated...
2022-05-21 23:31:04,528 - ERROR    - mi_temphum: Failed to get updates from mitemp A4:C1:38:45:CB:F4
2022-05-21 23:31:04,530 - INFO     - fhempy.lib.fhem_pythonbinding: All modules successfully undefined!
2022-05-21 23:31:04,531 - INFO     - websockets.server: server closing
2022-05-21 23:31:14,549 - INFO     - websockets.server: connection closed
2022-05-21 23:31:14,550 - INFO     - websockets.server: server closed
Traceback (most recent call last):
  File "FHEM/bindings/python/bin/fhempy", line 118, in <module>
    fpb.run()
  File "/opt/fhem/.local/lib/python3.7/site-packages/fhempy/lib/fhem_pythonbinding.py", line 627, in run
    loop.run_until_complete(loop.shutdown_default_executor())
AttributeError: '_UnixSelectorEventLoop' object has no attribute 'shutdown_default_executor'
2022-05-21 23:31:15,515 - INFO     - fhempy.lib.fhem_pythonbinding: Starting fhempy 0.1.357...
2022-05-21 23:31:15,522 - INFO     - fhempy.lib.fhem_pythonbinding: Waiting for FHEM connection
2022-05-21 23:31:15,562 - INFO     - websockets.server: server listening on 0.0.0.0:15733
2022-05-21 23:31:24,677 - INFO     - websockets.server: connection open
2022-05-21 23:31:24,679 - INFO     - fhempy.lib.fhem_pythonbinding: Incoming FHEM connection: 127.0.0.1
2022-05-21 23:31:25,396 - ERROR    - mi_temphum: Failed to get updates from mitemp A4:C1:38:45:CB:F4
2022-05-21 23:33:54,847 - INFO     - fhempy.lib.pkg_installer: Attempting install of pycryptodomex>=3.12.0
2022-05-21 23:34:03,395 - INFO     - fhempy.lib.pkg_installer: Successfully installed pycryptodomex>=3.12.0 update!
2022-05-21 23:34:03,450 - INFO     - fhempy.lib.pkg_installer: Attempting install of janus>=1.0.0
2022-05-21 23:34:08,310 - INFO     - fhempy.lib.pkg_installer: Successfully installed janus>=1.0.0 update!
2022-05-21 23:34:08,366 - INFO     - fhempy.lib.pkg_installer: Attempting install of aioblescan>=0.2.12
2022-05-21 23:34:13,209 - INFO     - fhempy.lib.pkg_installer: Successfully installed aioblescan>=0.2.12 update!
2022-05-21 23:34:13,255 - INFO     - fhempy.lib.pkg_installer: Attempting install of btsocket>=0.1.0
2022-05-21 23:34:17,946 - INFO     - fhempy.lib.pkg_installer: Successfully installed btsocket>=0.1.0 update!
2022-05-21 23:34:17,991 - INFO     - fhempy.lib.pkg_installer: Attempting install of pyric>=0.1.6.3
2022-05-21 23:34:23,065 - INFO     - fhempy.lib.pkg_installer: Successfully installed pyric>=0.1.6.3 update!
2022-05-21 23:34:23,125 - INFO     - fhempy.lib.pkg_installer: Attempting install of bleparser==2.0.0
2022-05-21 23:34:27,993 - INFO     - fhempy.lib.pkg_installer: Successfully installed bleparser==2.0.0 update!
2022-05-21 23:36:14,870 - INFO     - fhempy.lib.fhem_pythonbinding: Start update...
2022-05-21 23:36:14,871 - INFO     - fhempy.lib.pkg_installer: Attempting install of fhempy
2022-05-21 23:36:20,604 - INFO     - fhempy.lib.pkg_installer: Successfully installed fhempy update!
2022-05-21 23:36:20,633 - INFO     - fhempy.lib.fhem_pythonbinding: Restart initiated...
2022-05-21 23:36:20,637 - INFO     - fhempy.lib.fhem_pythonbinding: All modules successfully undefined!
2022-05-21 23:36:20,639 - INFO     - websockets.server: server closing
2022-05-21 23:36:30,661 - INFO     - websockets.server: connection closed
2022-05-21 23:36:30,662 - INFO     - websockets.server: server closed
Traceback (most recent call last):
  File "FHEM/bindings/python/bin/fhempy", line 118, in <module>
    fpb.run()
  File "/opt/fhem/.local/lib/python3.7/site-packages/fhempy/lib/fhem_pythonbinding.py", line 627, in run
    loop.run_until_complete(loop.shutdown_default_executor())
AttributeError: '_UnixSelectorEventLoop' object has no attribute 'shutdown_default_executor'
2022-05-21 23:36:31,571 - INFO     - fhempy.lib.fhem_pythonbinding: Starting fhempy 0.1.357...
2022-05-21 23:36:31,579 - INFO     - fhempy.lib.fhem_pythonbinding: Waiting for FHEM connection
2022-05-21 23:36:31,627 - INFO     - websockets.server: server listening on 0.0.0.0:15733
2022-05-21 23:36:40,829 - INFO     - websockets.server: connection open
2022-05-21 23:36:40,833 - INFO     - fhempy.lib.fhem_pythonbinding: Incoming FHEM connection: 127.0.0.1
2022-05-21 23:36:41,588 - ERROR    - mi_temphum: Failed to get updates from mitemp A4:C1:38:45:CB:F4
2022-05-21 23:38:00,269 - INFO     - fhempy.lib.fhem_pythonbinding: Start update...
2022-05-21 23:38:00,271 - INFO     - fhempy.lib.pkg_installer: Attempting install of fhempy
2022-05-21 23:38:06,102 - INFO     - fhempy.lib.pkg_installer: Successfully installed fhempy update!
2022-05-21 23:38:06,148 - INFO     - fhempy.lib.fhem_pythonbinding: Restart initiated...
2022-05-21 23:38:06,148 - INFO     - fhempy.lib.fhem_pythonbinding: All modules successfully undefined!
2022-05-21 23:38:06,150 - INFO     - websockets.server: server closing
2022-05-21 23:38:16,172 - ERROR    - fhempy.lib.fhem_pythonbinding: Failed to handle message:
Traceback (most recent call last):
  File "/opt/fhem/.local/lib/python3.7/site-packages/websockets/legacy/protocol.py", line 945, in transfer_data
    message = await self.read_message()
  File "/opt/fhem/.local/lib/python3.7/site-packages/websockets/legacy/protocol.py", line 1015, in read_message
    frame = await self.read_data_frame(max_size=self.max_size)
  File "/opt/fhem/.local/lib/python3.7/site-packages/websockets/legacy/protocol.py", line 1090, in read_data_frame
    frame = await self.read_frame(max_size)
  File "/opt/fhem/.local/lib/python3.7/site-packages/websockets/legacy/protocol.py", line 1149, in read_frame
    extensions=self.extensions,
  File "/opt/fhem/.local/lib/python3.7/site-packages/websockets/legacy/framing.py", line 70, in read
    data = await reader(2)
  File "/usr/lib/python3.7/asyncio/streams.py", line 679, in readexactly
    await self._wait_for_data('readexactly')
  File "/usr/lib/python3.7/asyncio/streams.py", line 473, in _wait_for_data
    await self._waiter
concurrent.futures._base.CancelledError

The above exception was the direct cause of the following exception:

Traceback (most recent call last):
  File "/opt/fhem/.local/lib/python3.7/site-packages/fhempy/lib/fhem_pythonbinding.py", line 166, in _onMessage
    await self.handle_message(msg, hash)
  File "/opt/fhem/.local/lib/python3.7/site-packages/fhempy/lib/fhem_pythonbinding.py", line 186, in handle_message
    await self.handle_function(hash, msg)
  File "/opt/fhem/.local/lib/python3.7/site-packages/fhempy/lib/fhem_pythonbinding.py", line 247, in handle_function
    await self.sendBackReturn(hash, "")
  File "/opt/fhem/.local/lib/python3.7/site-packages/fhempy/lib/fhem_pythonbinding.py", line 110, in sendBackReturn
    await self.wsconnection.send(msg.encode("utf-8"))
  File "/opt/fhem/.local/lib/python3.7/site-packages/websockets/legacy/protocol.py", line 620, in send
    await self.ensure_open()
  File "/opt/fhem/.local/lib/python3.7/site-packages/websockets/legacy/protocol.py", line 930, in ensure_open
    raise self.connection_closed_exc()
websockets.exceptions.ConnectionClosedError: sent 1001 (going away); no close frame received
2022-05-21 23:38:16,174 - ERROR    - fhempy.lib.fhem_pythonbinding: fhempy failed to handle message(id: 34770047)
2022-05-21 23:38:16,175 - ERROR    - fhempy.lib.fhem_pythonbinding: Failed to handle message: {"PYTHONTYPE":"ble_monitor","NAME":"my_body_scale","argsh":{},"FHEMPYTYPE":"ble_monitor","msgtype":"function","defargsh":{},"args":["my_body_scale","?"],"defargs":["my_body_scale","fhempy","ble_monitor","A4:C1:38:45:CB:F4"],"id":"34770047","function":"Set"}
Traceback (most recent call last):
  File "/opt/fhem/.local/lib/python3.7/site-packages/websockets/legacy/protocol.py", line 945, in transfer_data
    message = await self.read_message()
  File "/opt/fhem/.local/lib/python3.7/site-packages/websockets/legacy/protocol.py", line 1015, in read_message
    frame = await self.read_data_frame(max_size=self.max_size)
  File "/opt/fhem/.local/lib/python3.7/site-packages/websockets/legacy/protocol.py", line 1090, in read_data_frame
    frame = await self.read_frame(max_size)
  File "/opt/fhem/.local/lib/python3.7/site-packages/websockets/legacy/protocol.py", line 1149, in read_frame
    extensions=self.extensions,
  File "/opt/fhem/.local/lib/python3.7/site-packages/websockets/legacy/framing.py", line 70, in read
    data = await reader(2)
  File "/usr/lib/python3.7/asyncio/streams.py", line 679, in readexactly
    await self._wait_for_data('readexactly')
  File "/usr/lib/python3.7/asyncio/streams.py", line 473, in _wait_for_data
    await self._waiter
concurrent.futures._base.CancelledError

The above exception was the direct cause of the following exception:

Traceback (most recent call last):
  File "/opt/fhem/.local/lib/python3.7/site-packages/fhempy/lib/fhem_pythonbinding.py", line 144, in onMessage
    await self._onMessage(payload)
  File "/opt/fhem/.local/lib/python3.7/site-packages/fhempy/lib/fhem_pythonbinding.py", line 169, in _onMessage
    await self.sendBackError(hash, "fhempy failed to handle message")
  File "/opt/fhem/.local/lib/python3.7/site-packages/fhempy/lib/fhem_pythonbinding.py", line 121, in sendBackError
    await self.wsconnection.send(msg.encode("utf-8"))
  File "/opt/fhem/.local/lib/python3.7/site-packages/websockets/legacy/protocol.py", line 620, in send
    await self.ensure_open()
  File "/opt/fhem/.local/lib/python3.7/site-packages/websockets/legacy/protocol.py", line 921, in ensure_open
    raise self.connection_closed_exc()
websockets.exceptions.ConnectionClosedError: sent 1001 (going away); no close frame received
2022-05-21 23:38:16,183 - INFO     - websockets.server: connection closed
2022-05-21 23:38:16,185 - INFO     - websockets.server: server closed
Traceback (most recent call last):
  File "FHEM/bindings/python/bin/fhempy", line 118, in <module>
    fpb.run()
  File "/opt/fhem/.local/lib/python3.7/site-packages/fhempy/lib/fhem_pythonbinding.py", line 627, in run
    loop.run_until_complete(loop.shutdown_default_executor())
AttributeError: '_UnixSelectorEventLoop' object has no attribute 'shutdown_default_executor'
2022-05-21 23:38:17,215 - INFO     - fhempy.lib.fhem_pythonbinding: Starting fhempy 0.1.357...
2022-05-21 23:38:17,222 - INFO     - fhempy.lib.fhem_pythonbinding: Waiting for FHEM connection
2022-05-21 23:38:17,263 - INFO     - websockets.server: server listening on 0.0.0.0:15733
2022-05-21 23:38:26,798 - INFO     - websockets.server: connection open
2022-05-21 23:38:26,801 - INFO     - fhempy.lib.fhem_pythonbinding: Incoming FHEM connection: 127.0.0.1
2022-05-21 23:39:12,543 - ERROR    - mi_temphum: Failed to get updates from mitemp A4:C1:38:45:CB:F4
2022-05-21 23:40:45,756 - INFO     - fhempy.lib.fhem_pythonbinding: Starting fhempy 0.1.357...
2022-05-21 23:40:45,764 - INFO     - fhempy.lib.fhem_pythonbinding: Waiting for FHEM connection
2022-05-21 23:40:45,813 - INFO     - websockets.server: server listening on 0.0.0.0:15733
2022-05-21 23:40:50,808 - INFO     - websockets.server: connection open
2022-05-21 23:40:50,810 - INFO     - fhempy.lib.fhem_pythonbinding: Incoming FHEM connection: 127.0.0.1
2022-05-21 23:40:52,265 - ERROR    - mi_temphum: Failed to get updates from mitemp A4:C1:38:45:CB:F4
2022-05-21 23:44:05,525 - INFO     - fhempy.lib.fhem_pythonbinding: Start update...
2022-05-21 23:44:05,526 - INFO     - fhempy.lib.pkg_installer: Attempting install of fhempy
2022-05-21 23:44:11,379 - INFO     - fhempy.lib.pkg_installer: Successfully installed fhempy update!
2022-05-21 23:44:11,416 - INFO     - fhempy.lib.fhem_pythonbinding: Restart initiated...
2022-05-21 23:44:11,420 - INFO     - fhempy.lib.fhem_pythonbinding: All modules successfully undefined!
2022-05-21 23:44:11,422 - INFO     - websockets.server: server closing
2022-05-21 23:44:21,456 - INFO     - websockets.server: connection closed
2022-05-21 23:44:21,457 - INFO     - websockets.server: server closed
Traceback (most recent call last):
  File "FHEM/bindings/python/bin/fhempy", line 118, in <module>
    fpb.run()
  File "/opt/fhem/.local/lib/python3.7/site-packages/fhempy/lib/fhem_pythonbinding.py", line 627, in run
    loop.run_until_complete(loop.shutdown_default_executor())
AttributeError: '_UnixSelectorEventLoop' object has no attribute 'shutdown_default_executor'
2022-05-21 23:44:22,429 - INFO     - fhempy.lib.fhem_pythonbinding: Starting fhempy 0.1.357...
2022-05-21 23:44:22,436 - INFO     - fhempy.lib.fhem_pythonbinding: Waiting for FHEM connection
2022-05-21 23:44:22,477 - INFO     - websockets.server: server listening on 0.0.0.0:15733
2022-05-21 23:44:33,519 - INFO     - websockets.server: connection open
2022-05-21 23:44:33,520 - INFO     - fhempy.lib.fhem_pythonbinding: Incoming FHEM connection: 127.0.0.1
2022-05-21 23:44:41,636 - ERROR    - mi_temphum: Failed to get updates from mitemp A4:C1:38:45:CB:F4
2022-05-21 23:45:59,085 - INFO     - fhempy.lib.fhem_pythonbinding: Start update...
2022-05-21 23:45:59,087 - INFO     - fhempy.lib.pkg_installer: Attempting install of fhempy
2022-05-21 23:46:04,931 - INFO     - fhempy.lib.pkg_installer: Successfully installed fhempy update!
2022-05-21 23:46:04,953 - INFO     - fhempy.lib.fhem_pythonbinding: Restart initiated...
2022-05-21 23:46:04,957 - INFO     - fhempy.lib.fhem_pythonbinding: All modules successfully undefined!
2022-05-21 23:46:04,959 - INFO     - websockets.server: server closing
2022-05-21 23:46:14,979 - ERROR    - fhempy.lib.fhem_pythonbinding: Failed to handle message:
Traceback (most recent call last):
  File "/opt/fhem/.local/lib/python3.7/site-packages/websockets/legacy/protocol.py", line 945, in transfer_data
    message = await self.read_message()
  File "/opt/fhem/.local/lib/python3.7/site-packages/websockets/legacy/protocol.py", line 1015, in read_message
    frame = await self.read_data_frame(max_size=self.max_size)
  File "/opt/fhem/.local/lib/python3.7/site-packages/websockets/legacy/protocol.py", line 1090, in read_data_frame
    frame = await self.read_frame(max_size)
  File "/opt/fhem/.local/lib/python3.7/site-packages/websockets/legacy/protocol.py", line 1149, in read_frame
    extensions=self.extensions,
  File "/opt/fhem/.local/lib/python3.7/site-packages/websockets/legacy/framing.py", line 70, in read
    data = await reader(2)
  File "/usr/lib/python3.7/asyncio/streams.py", line 679, in readexactly
    await self._wait_for_data('readexactly')
  File "/usr/lib/python3.7/asyncio/streams.py", line 473, in _wait_for_data
    await self._waiter
concurrent.futures._base.CancelledError

The above exception was the direct cause of the following exception:

Traceback (most recent call last):
  File "/opt/fhem/.local/lib/python3.7/site-packages/fhempy/lib/fhem_pythonbinding.py", line 166, in _onMessage
    await self.handle_message(msg, hash)
  File "/opt/fhem/.local/lib/python3.7/site-packages/fhempy/lib/fhem_pythonbinding.py", line 186, in handle_message
    await self.handle_function(hash, msg)
  File "/opt/fhem/.local/lib/python3.7/site-packages/fhempy/lib/fhem_pythonbinding.py", line 332, in handle_function
    await self.sendBackReturn(hash, ret)
  File "/opt/fhem/.local/lib/python3.7/site-packages/fhempy/lib/fhem_pythonbinding.py", line 110, in sendBackReturn
    await self.wsconnection.send(msg.encode("utf-8"))
  File "/opt/fhem/.local/lib/python3.7/site-packages/websockets/legacy/protocol.py", line 620, in send
    await self.ensure_open()
  File "/opt/fhem/.local/lib/python3.7/site-packages/websockets/legacy/protocol.py", line 930, in ensure_open
    raise self.connection_closed_exc()
websockets.exceptions.ConnectionClosedError: sent 1001 (going away); no close frame received
2022-05-21 23:46:14,981 - ERROR    - fhempy.lib.fhem_pythonbinding: fhempy failed to handle message(id: 92851610)
2022-05-21 23:46:14,982 - ERROR    - fhempy.lib.fhem_pythonbinding: Failed to handle message: {"NAME":"mi_temphum","FHEMPYTYPE":"mitemp","defargs":["mi_temphum","fhempy","mitemp","A4:C1:38:45:CB:F4"],"argsh":{},"function":"Set","id":"92851610","defargsh":{},"PYTHONTYPE":"mitemp","args":["mi_temphum","?"],"msgtype":"function"}
Traceback (most recent call last):
  File "/opt/fhem/.local/lib/python3.7/site-packages/websockets/legacy/protocol.py", line 945, in transfer_data
    message = await self.read_message()
  File "/opt/fhem/.local/lib/python3.7/site-packages/websockets/legacy/protocol.py", line 1015, in read_message
    frame = await self.read_data_frame(max_size=self.max_size)
  File "/opt/fhem/.local/lib/python3.7/site-packages/websockets/legacy/protocol.py", line 1090, in read_data_frame
    frame = await self.read_frame(max_size)
  File "/opt/fhem/.local/lib/python3.7/site-packages/websockets/legacy/protocol.py", line 1149, in read_frame
    extensions=self.extensions,
  File "/opt/fhem/.local/lib/python3.7/site-packages/websockets/legacy/framing.py", line 70, in read
    data = await reader(2)
  File "/usr/lib/python3.7/asyncio/streams.py", line 679, in readexactly
    await self._wait_for_data('readexactly')
  File "/usr/lib/python3.7/asyncio/streams.py", line 473, in _wait_for_data
    await self._waiter
concurrent.futures._base.CancelledError

The above exception was the direct cause of the following exception:

Traceback (most recent call last):
  File "/opt/fhem/.local/lib/python3.7/site-packages/fhempy/lib/fhem_pythonbinding.py", line 144, in onMessage
    await self._onMessage(payload)
  File "/opt/fhem/.local/lib/python3.7/site-packages/fhempy/lib/fhem_pythonbinding.py", line 169, in _onMessage
    await self.sendBackError(hash, "fhempy failed to handle message")
  File "/opt/fhem/.local/lib/python3.7/site-packages/fhempy/lib/fhem_pythonbinding.py", line 121, in sendBackError
    await self.wsconnection.send(msg.encode("utf-8"))
  File "/opt/fhem/.local/lib/python3.7/site-packages/websockets/legacy/protocol.py", line 620, in send
    await self.ensure_open()
  File "/opt/fhem/.local/lib/python3.7/site-packages/websockets/legacy/protocol.py", line 921, in ensure_open
    raise self.connection_closed_exc()
websockets.exceptions.ConnectionClosedError: sent 1001 (going away); no close frame received
2022-05-21 23:46:14,984 - ERROR    - fhempy.lib.fhem_pythonbinding: Failed to handle message:
Traceback (most recent call last):
  File "/opt/fhem/.local/lib/python3.7/site-packages/websockets/legacy/protocol.py", line 945, in transfer_data
    message = await self.read_message()
  File "/opt/fhem/.local/lib/python3.7/site-packages/websockets/legacy/protocol.py", line 1015, in read_message
    frame = await self.read_data_frame(max_size=self.max_size)
  File "/opt/fhem/.local/lib/python3.7/site-packages/websockets/legacy/protocol.py", line 1090, in read_data_frame
    frame = await self.read_frame(max_size)
  File "/opt/fhem/.local/lib/python3.7/site-packages/websockets/legacy/protocol.py", line 1149, in read_frame
    extensions=self.extensions,
  File "/opt/fhem/.local/lib/python3.7/site-packages/websockets/legacy/framing.py", line 70, in read
    data = await reader(2)
  File "/usr/lib/python3.7/asyncio/streams.py", line 679, in readexactly
    await self._wait_for_data('readexactly')
  File "/usr/lib/python3.7/asyncio/streams.py", line 473, in _wait_for_data
    await self._waiter
concurrent.futures._base.CancelledError

The above exception was the direct cause of the following exception:

Traceback (most recent call last):
  File "/opt/fhem/.local/lib/python3.7/site-packages/fhempy/lib/fhem_pythonbinding.py", line 166, in _onMessage
    await self.handle_message(msg, hash)
  File "/opt/fhem/.local/lib/python3.7/site-packages/fhempy/lib/fhem_pythonbinding.py", line 186, in handle_message
    await self.handle_function(hash, msg)
  File "/opt/fhem/.local/lib/python3.7/site-packages/fhempy/lib/fhem_pythonbinding.py", line 332, in handle_function
    await self.sendBackReturn(hash, ret)
  File "/opt/fhem/.local/lib/python3.7/site-packages/fhempy/lib/fhem_pythonbinding.py", line 110, in sendBackReturn
    await self.wsconnection.send(msg.encode("utf-8"))
  File "/opt/fhem/.local/lib/python3.7/site-packages/websockets/legacy/protocol.py", line 620, in send
    await self.ensure_open()
  File "/opt/fhem/.local/lib/python3.7/site-packages/websockets/legacy/protocol.py", line 930, in ensure_open
    raise self.connection_closed_exc()
websockets.exceptions.ConnectionClosedError: sent 1001 (going away); no close frame received
2022-05-21 23:46:14,985 - ERROR    - fhempy.lib.fhem_pythonbinding: fhempy failed to handle message(id: 49340130)
2022-05-21 23:46:14,986 - ERROR    - fhempy.lib.fhem_pythonbinding: Failed to handle message: {"function":"FW_detailFn","argsh":{},"id":"49340130","PYTHONTYPE":"mitemp","args":["WEB","mi_temphum","",null],"defargsh":{},"msgtype":"function","NAME":"mi_temphum","FHEMPYTYPE":"mitemp","defargs":["mi_temphum","fhempy","mitemp","A4:C1:38:45:CB:F4"]}
Traceback (most recent call last):
  File "/opt/fhem/.local/lib/python3.7/site-packages/websockets/legacy/protocol.py", line 945, in transfer_data
    message = await self.read_message()
  File "/opt/fhem/.local/lib/python3.7/site-packages/websockets/legacy/protocol.py", line 1015, in read_message
    frame = await self.read_data_frame(max_size=self.max_size)
  File "/opt/fhem/.local/lib/python3.7/site-packages/websockets/legacy/protocol.py", line 1090, in read_data_frame
    frame = await self.read_frame(max_size)
  File "/opt/fhem/.local/lib/python3.7/site-packages/websockets/legacy/protocol.py", line 1149, in read_frame
    extensions=self.extensions,
  File "/opt/fhem/.local/lib/python3.7/site-packages/websockets/legacy/framing.py", line 70, in read
    data = await reader(2)
  File "/usr/lib/python3.7/asyncio/streams.py", line 679, in readexactly
    await self._wait_for_data('readexactly')
  File "/usr/lib/python3.7/asyncio/streams.py", line 473, in _wait_for_data
    await self._waiter
concurrent.futures._base.CancelledError

The above exception was the direct cause of the following exception:

Traceback (most recent call last):
  File "/opt/fhem/.local/lib/python3.7/site-packages/fhempy/lib/fhem_pythonbinding.py", line 144, in onMessage
    await self._onMessage(payload)
  File "/opt/fhem/.local/lib/python3.7/site-packages/fhempy/lib/fhem_pythonbinding.py", line 169, in _onMessage
    await self.sendBackError(hash, "fhempy failed to handle message")
  File "/opt/fhem/.local/lib/python3.7/site-packages/fhempy/lib/fhem_pythonbinding.py", line 121, in sendBackError
    await self.wsconnection.send(msg.encode("utf-8"))
  File "/opt/fhem/.local/lib/python3.7/site-packages/websockets/legacy/protocol.py", line 620, in send
    await self.ensure_open()
  File "/opt/fhem/.local/lib/python3.7/site-packages/websockets/legacy/protocol.py", line 921, in ensure_open
    raise self.connection_closed_exc()
websockets.exceptions.ConnectionClosedError: sent 1001 (going away); no close frame received
2022-05-21 23:46:14,988 - ERROR    - fhempy.lib.fhem_pythonbinding: Failed to handle message:
Traceback (most recent call last):
  File "/opt/fhem/.local/lib/python3.7/site-packages/websockets/legacy/protocol.py", line 945, in transfer_data
    message = await self.read_message()
  File "/opt/fhem/.local/lib/python3.7/site-packages/websockets/legacy/protocol.py", line 1015, in read_message
    frame = await self.read_data_frame(max_size=self.max_size)
  File "/opt/fhem/.local/lib/python3.7/site-packages/websockets/legacy/protocol.py", line 1090, in read_data_frame
    frame = await self.read_frame(max_size)
  File "/opt/fhem/.local/lib/python3.7/site-packages/websockets/legacy/protocol.py", line 1149, in read_frame
    extensions=self.extensions,
  File "/opt/fhem/.local/lib/python3.7/site-packages/websockets/legacy/framing.py", line 70, in read
    data = await reader(2)
  File "/usr/lib/python3.7/asyncio/streams.py", line 679, in readexactly
    await self._wait_for_data('readexactly')
  File "/usr/lib/python3.7/asyncio/streams.py", line 473, in _wait_for_data
    await self._waiter
concurrent.futures._base.CancelledError

The above exception was the direct cause of the following exception:

Traceback (most recent call last):
  File "/opt/fhem/.local/lib/python3.7/site-packages/fhempy/lib/fhem_pythonbinding.py", line 166, in _onMessage
    await self.handle_message(msg, hash)
  File "/opt/fhem/.local/lib/python3.7/site-packages/fhempy/lib/fhem_pythonbinding.py", line 186, in handle_message
    await self.handle_function(hash, msg)
  File "/opt/fhem/.local/lib/python3.7/site-packages/fhempy/lib/fhem_pythonbinding.py", line 332, in handle_function
    await self.sendBackReturn(hash, ret)
  File "/opt/fhem/.local/lib/python3.7/site-packages/fhempy/lib/fhem_pythonbinding.py", line 110, in sendBackReturn
    await self.wsconnection.send(msg.encode("utf-8"))
  File "/opt/fhem/.local/lib/python3.7/site-packages/websockets/legacy/protocol.py", line 620, in send
    await self.ensure_open()
  File "/opt/fhem/.local/lib/python3.7/site-packages/websockets/legacy/protocol.py", line 930, in ensure_open
    raise self.connection_closed_exc()
websockets.exceptions.ConnectionClosedError: sent 1001 (going away); no close frame received
2022-05-21 23:46:14,989 - ERROR    - fhempy.lib.fhem_pythonbinding: fhempy failed to handle message(id: 52890719)
2022-05-21 23:46:14,990 - ERROR    - fhempy.lib.fhem_pythonbinding: Failed to handle message: {"id":"52890719","function":"Set","argsh":{},"msgtype":"function","defargsh":{},"args":["mi_temphum","?"],"PYTHONTYPE":"mitemp","FHEMPYTYPE":"mitemp","NAME":"mi_temphum","defargs":["mi_temphum","fhempy","mitemp","A4:C1:38:45:CB:F4"]}
Traceback (most recent call last):
  File "/opt/fhem/.local/lib/python3.7/site-packages/websockets/legacy/protocol.py", line 945, in transfer_data
    message = await self.read_message()
  File "/opt/fhem/.local/lib/python3.7/site-packages/websockets/legacy/protocol.py", line 1015, in read_message
    frame = await self.read_data_frame(max_size=self.max_size)
  File "/opt/fhem/.local/lib/python3.7/site-packages/websockets/legacy/protocol.py", line 1090, in read_data_frame
    frame = await self.read_frame(max_size)
  File "/opt/fhem/.local/lib/python3.7/site-packages/websockets/legacy/protocol.py", line 1149, in read_frame
    extensions=self.extensions,
  File "/opt/fhem/.local/lib/python3.7/site-packages/websockets/legacy/framing.py", line 70, in read
    data = await reader(2)
  File "/usr/lib/python3.7/asyncio/streams.py", line 679, in readexactly
    await self._wait_for_data('readexactly')
  File "/usr/lib/python3.7/asyncio/streams.py", line 473, in _wait_for_data
    await self._waiter
concurrent.futures._base.CancelledError

The above exception was the direct cause of the following exception:

Traceback (most recent call last):
  File "/opt/fhem/.local/lib/python3.7/site-packages/fhempy/lib/fhem_pythonbinding.py", line 144, in onMessage
    await self._onMessage(payload)
  File "/opt/fhem/.local/lib/python3.7/site-packages/fhempy/lib/fhem_pythonbinding.py", line 169, in _onMessage
    await self.sendBackError(hash, "fhempy failed to handle message")
  File "/opt/fhem/.local/lib/python3.7/site-packages/fhempy/lib/fhem_pythonbinding.py", line 121, in sendBackError
    await self.wsconnection.send(msg.encode("utf-8"))
  File "/opt/fhem/.local/lib/python3.7/site-packages/websockets/legacy/protocol.py", line 620, in send
    await self.ensure_open()
  File "/opt/fhem/.local/lib/python3.7/site-packages/websockets/legacy/protocol.py", line 921, in ensure_open
    raise self.connection_closed_exc()
websockets.exceptions.ConnectionClosedError: sent 1001 (going away); no close frame received
2022-05-21 23:46:14,999 - INFO     - websockets.server: connection closed
2022-05-21 23:46:15,000 - INFO     - websockets.server: server closed
Traceback (most recent call last):
  File "FHEM/bindings/python/bin/fhempy", line 118, in <module>
    fpb.run()
  File "/opt/fhem/.local/lib/python3.7/site-packages/fhempy/lib/fhem_pythonbinding.py", line 627, in run
    loop.run_until_complete(loop.shutdown_default_executor())
AttributeError: '_UnixSelectorEventLoop' object has no attribute 'shutdown_default_executor'
2022-05-21 23:46:15,941 - INFO     - fhempy.lib.fhem_pythonbinding: Starting fhempy 0.1.357...
2022-05-21 23:46:15,948 - INFO     - fhempy.lib.fhem_pythonbinding: Waiting for FHEM connection
2022-05-21 23:46:15,988 - INFO     - websockets.server: server listening on 0.0.0.0:15733
2022-05-21 23:46:25,154 - INFO     - websockets.server: connection open
2022-05-21 23:46:25,156 - INFO     - fhempy.lib.fhem_pythonbinding: Incoming FHEM connection: 127.0.0.1
2022-05-21 23:46:35,584 - ERROR    - mi_temphum: Failed to get updates from mitemp A4:C1:38:45:CB:F4

[marboj]

wenn ich das Thermometer als ble_monitor anlege, hat das Gerät folgenden Status:

Code Auswählen Erweitern

Failed to load module ble_monitor: Traceback (most recent call last):
  File "/opt/fhem/.local/lib/python3.7/site-packages/fhempy/lib/fhem_pythonbinding.py", line 260, in handle_function
    module_object = await self.import_module(hash)
  File "/opt/fhem/.local/lib/python3.7/site-packages/fhempy/lib/fhem_pythonbinding.py", line 454, in import_module
    functools.partial(importlib.import_module, pymodule)
  File "/opt/fhem/.local/lib/python3.7/site-packages/fhempy/lib/utils.py", line 34, in run_blocking
    return await asyncio.get_event_loop().run_in_executor(pool, function)
  File "/usr/lib/python3.7/concurrent/futures/thread.py", line 57, in run
    result = self.fn(*self.args, **self.kwargs)
  File "/usr/lib/python3.7/importlib/__init__.py", line 127, in import_module
    return _bootstrap._gcd_import(name[level:], package, level)
  File "<frozen importlib._bootstrap>", line 1006, in _gcd_import
  File "<frozen importlib._bootstrap>", line 983, in _find_and_load
  File "<frozen importlib._bootstrap>", line 967, in _find_and_load_unlocked
  File "<frozen importlib._bootstrap>", line 677, in _load_unlocked
  File "<frozen importlib._bootstrap_external>", line 728, in exec_module
  File "<frozen importlib._bootstrap>", line 219, in _call_with_frames_removed
  File "/opt/fhem/.local/lib/python3.7/site-packages/fhempy/lib/ble_monitor/ble_monitor.py", line 2, in <module>
    from fhempy.lib.ble_monitor.blemonitor import BLEmonitor
  File "/opt/fhem/.local/lib/python3.7/site-packages/fhempy/lib/ble_monitor/blemonitor.py", line 8, in <module>
    from fhempy.lib.ble_monitor.hcidump import HCIdump
  File "/opt/fhem/.local/lib/python3.7/site-packages/fhempy/lib/ble_monitor/hcidump.py", line 8, in <module>
    from bleparser import BleParser
  File "/opt/fhem/.local/lib/python3.7/site-packages/bleparser/__init__.py", line 4, in <module>
    from .altbeacon import parse_altbeacon
  File "/opt/fhem/.local/lib/python3.7/site-packages/bleparser/altbeacon.py", line 5, in <module>
    from typing import Final
ImportError: cannot import name 'Final' from 'typing' (/usr/lib/python3.7/typing.py)

Hier das fhempy-log:

Code Auswählen Erweitern


2022-05-22 09:58:04,374 - INFO     - fhempy.lib.fhem_pythonbinding: Start update...
2022-05-22 09:58:04,375 - INFO     - fhempy.lib.pkg_installer: Attempting install of fhempy
2022-05-22 09:58:10,166 - INFO     - fhempy.lib.pkg_installer: Successfully installed fhempy update!
2022-05-22 09:58:10,187 - INFO     - fhempy.lib.fhem_pythonbinding: Restart initiated...
2022-05-22 09:58:10,187 - INFO     - fhempy.lib.fhem_pythonbinding: All modules successfully undefined!
2022-05-22 09:58:10,189 - INFO     - websockets.server: server closing
2022-05-22 09:58:20,222 - INFO     - websockets.server: connection closed
2022-05-22 09:58:20,224 - INFO     - websockets.server: server closed
2022-05-22 09:58:20,228 - ERROR    - fhempy.lib.fhem_pythonbinding: Failed to handle message:
Traceback (most recent call last):
  File "/opt/fhem/.local/lib/python3.7/site-packages/fhempy/lib/fhem_pythonbinding.py", line 166, in _onMessage
    await self.handle_message(msg, hash)
  File "/opt/fhem/.local/lib/python3.7/site-packages/fhempy/lib/fhem_pythonbinding.py", line 186, in handle_message
    await self.handle_function(hash, msg)
  File "/opt/fhem/.local/lib/python3.7/site-packages/fhempy/lib/fhem_pythonbinding.py", line 293, in handle_function
    await fhem.readingsSingleUpdate(hash, "state", errorMsg, 1)
  File "/opt/fhem/.local/lib/python3.7/site-packages/fhempy/lib/fhem.py", line 166, in readingsSingleUpdate
    async with update_locks[hash["NAME"]]:
  File "/usr/lib/python3.7/asyncio/locks.py", line 92, in __aenter__
    await self.acquire()
  File "/usr/lib/python3.7/asyncio/locks.py", line 192, in acquire
    await fut
concurrent.futures._base.CancelledError
2022-05-22 09:58:20,229 - ERROR    - fhempy.lib.fhem_pythonbinding: fhempy failed to handle message(id: 70780800)
2022-05-22 09:58:20,230 - ERROR    - fhempy.lib.fhem_pythonbinding: Failed to handle message: {"msgtype":"function","args":["mi_temphum","?"],"PYTHONTYPE":"ble_monitor","defargsh":{},"id":"70780800","argsh":{},"function":"Set","defargs":["mi_temphum","fhempy","ble_monitor","A4:C1:38:45:CB:F4"],"FHEMPYTYPE":"ble_monitor","NAME":"mi_temphum"}
Traceback (most recent call last):
  File "/opt/fhem/.local/lib/python3.7/site-packages/websockets/legacy/protocol.py", line 945, in transfer_data
    message = await self.read_message()
  File "/opt/fhem/.local/lib/python3.7/site-packages/websockets/legacy/protocol.py", line 1015, in read_message
    frame = await self.read_data_frame(max_size=self.max_size)
  File "/opt/fhem/.local/lib/python3.7/site-packages/websockets/legacy/protocol.py", line 1090, in read_data_frame
    frame = await self.read_frame(max_size)
  File "/opt/fhem/.local/lib/python3.7/site-packages/websockets/legacy/protocol.py", line 1149, in read_frame
    extensions=self.extensions,
  File "/opt/fhem/.local/lib/python3.7/site-packages/websockets/legacy/framing.py", line 70, in read
    data = await reader(2)
  File "/usr/lib/python3.7/asyncio/streams.py", line 679, in readexactly
    await self._wait_for_data('readexactly')
  File "/usr/lib/python3.7/asyncio/streams.py", line 473, in _wait_for_data
    await self._waiter
concurrent.futures._base.CancelledError

The above exception was the direct cause of the following exception:

Traceback (most recent call last):
  File "/opt/fhem/.local/lib/python3.7/site-packages/fhempy/lib/fhem_pythonbinding.py", line 144, in onMessage
    await self._onMessage(payload)
  File "/opt/fhem/.local/lib/python3.7/site-packages/fhempy/lib/fhem_pythonbinding.py", line 169, in _onMessage
    await self.sendBackError(hash, "fhempy failed to handle message")
  File "/opt/fhem/.local/lib/python3.7/site-packages/fhempy/lib/fhem_pythonbinding.py", line 121, in sendBackError
    await self.wsconnection.send(msg.encode("utf-8"))
  File "/opt/fhem/.local/lib/python3.7/site-packages/websockets/legacy/protocol.py", line 620, in send
    await self.ensure_open()
  File "/opt/fhem/.local/lib/python3.7/site-packages/websockets/legacy/protocol.py", line 921, in ensure_open
    raise self.connection_closed_exc()
websockets.exceptions.ConnectionClosedError: sent 1001 (going away); no close frame received
Traceback (most recent call last):
  File "FHEM/bindings/python/bin/fhempy", line 118, in <module>
    fpb.run()
  File "/opt/fhem/.local/lib/python3.7/site-packages/fhempy/lib/fhem_pythonbinding.py", line 627, in run
    loop.run_until_complete(loop.shutdown_default_executor())
AttributeError: '_UnixSelectorEventLoop' object has no attribute 'shutdown_default_executor'
2022-05-22 09:58:21,118 - INFO     - fhempy.lib.fhem_pythonbinding: Starting fhempy 0.1.357...
2022-05-22 09:58:21,125 - INFO     - fhempy.lib.fhem_pythonbinding: Waiting for FHEM connection
2022-05-22 09:58:21,166 - INFO     - websockets.server: server listening on 0.0.0.0:15733
2022-05-22 09:58:31,083 - INFO     - websockets.server: connection open
2022-05-22 09:58:31,085 - INFO     - fhempy.lib.fhem_pythonbinding: Incoming FHEM connection: 127.0.0.1
2022-05-22 10:00:13,976 - INFO     - fhempy.lib.fhem_pythonbinding: Start update...
2022-05-22 10:00:13,977 - INFO     - fhempy.lib.pkg_installer: Attempting install of fhempy
2022-05-22 10:00:19,868 - INFO     - fhempy.lib.pkg_installer: Successfully installed fhempy update!
2022-05-22 10:00:19,891 - INFO     - fhempy.lib.fhem_pythonbinding: Restart initiated...
2022-05-22 10:00:19,891 - INFO     - fhempy.lib.fhem_pythonbinding: All modules successfully undefined!
2022-05-22 10:00:19,893 - INFO     - websockets.server: server closing
2022-05-22 10:00:29,907 - INFO     - websockets.server: connection closed
2022-05-22 10:00:29,908 - INFO     - websockets.server: server closed
Traceback (most recent call last):
  File "FHEM/bindings/python/bin/fhempy", line 118, in <module>
    fpb.run()
  File "/opt/fhem/.local/lib/python3.7/site-packages/fhempy/lib/fhem_pythonbinding.py", line 627, in run
    loop.run_until_complete(loop.shutdown_default_executor())
AttributeError: '_UnixSelectorEventLoop' object has no attribute 'shutdown_default_executor'
2022-05-22 10:00:30,793 - INFO     - fhempy.lib.fhem_pythonbinding: Starting fhempy 0.1.357...
2022-05-22 10:00:30,801 - INFO     - fhempy.lib.fhem_pythonbinding: Waiting for FHEM connection
2022-05-22 10:00:30,841 - INFO     - websockets.server: server listening on 0.0.0.0:15733
2022-05-22 10:00:39,953 - INFO     - websockets.server: connection open
2022-05-22 10:00:39,955 - INFO     - fhempy.lib.fhem_pythonbinding: Incoming FHEM connection: 127.0.0.1
2022-05-22 10:17:15,703 - INFO     - fhempy.lib.fhem_pythonbinding: Starting fhempy 0.1.357...
2022-05-22 10:17:15,710 - INFO     - fhempy.lib.fhem_pythonbinding: Waiting for FHEM connection
2022-05-22 10:17:15,754 - INFO     - websockets.server: server listening on 0.0.0.0:15733
2022-05-22 10:17:21,030 - INFO     - websockets.server: connection open
2022-05-22 10:17:21,032 - INFO     - fhempy.lib.fhem_pythonbinding: Incoming FHEM connection: 127.0.0.1
2022-05-22 12:01:23,596 - INFO     - fhempy.lib.fhem_pythonbinding: Start update...
2022-05-22 12:01:23,597 - INFO     - fhempy.lib.pkg_installer: Attempting install of fhempy
2022-05-22 12:01:29,648 - INFO     - fhempy.lib.pkg_installer: Successfully installed fhempy update!
2022-05-22 12:01:29,682 - INFO     - fhempy.lib.fhem_pythonbinding: Restart initiated...
2022-05-22 12:01:29,682 - INFO     - fhempy.lib.fhem_pythonbinding: All modules successfully undefined!
2022-05-22 12:01:29,684 - INFO     - websockets.server: server closing
2022-05-22 12:01:39,700 - INFO     - websockets.server: connection closed
2022-05-22 12:01:39,701 - INFO     - websockets.server: server closed
Traceback (most recent call last):
  File "FHEM/bindings/python/bin/fhempy", line 118, in <module>
    fpb.run()
  File "/opt/fhem/.local/lib/python3.7/site-packages/fhempy/lib/fhem_pythonbinding.py", line 627, in run
    loop.run_until_complete(loop.shutdown_default_executor())
AttributeError: '_UnixSelectorEventLoop' object has no attribute 'shutdown_default_executor'
2022-05-22 12:01:40,606 - INFO     - fhempy.lib.fhem_pythonbinding: Starting fhempy 0.1.357...
2022-05-22 12:01:40,613 - INFO     - fhempy.lib.fhem_pythonbinding: Waiting for FHEM connection
2022-05-22 12:01:40,656 - INFO     - websockets.server: server listening on 0.0.0.0:15733
2022-05-22 12:01:49,788 - INFO     - websockets.server: connection open
2022-05-22 12:01:49,790 - INFO     - fhempy.lib.fhem_pythonbinding: Incoming FHEM connection: 127.0.0.1


@dominik: Was kann das sein?

[Nighthawk]

Hier das log:

Code Auswählen Erweitern

Exception ignored in: <coroutine object BaseEventLoop._create_connection_transport at 0x7f3d90b44a40>
Traceback (most recent call last):
  File "/usr/lib/python3.9/asyncio/base_events.py", line 1122, in _create_connection_transport
    transport.close()
  File "/usr/lib/python3.9/asyncio/selector_events.py", line 697, in close
    self._loop.call_soon(self._call_connection_lost, None)
  File "/usr/lib/python3.9/asyncio/base_events.py", line 751, in call_soon
    self._check_closed()
  File "/usr/lib/python3.9/asyncio/base_events.py", line 515, in _check_closed
    raise RuntimeError('Event loop is closed')
RuntimeError: Event loop is closed
2022-05-22 19:26:41,967 - ERROR    - asyncio: Task was destroyed but it is pending!
task: <Task pending name='Task-107' coro=<Event.wait() done,
defined at /usr/lib/python3.9/asyncio/locks.py:213> wait_for=<Future pending cb=[<TaskWakeupMethWrapper
object at 0x7f3d907f83a0>()]> cb=[_release_waiter(<Future pendi...d907f8be0>()]>)() at /usr/lib/python3.9/asyncio/tasks.py:416]>


Mir ist auch aufgefallen, dass nichts passiert wenn ich den FHEMPyServer neustarte, weder eine Reaktion im Webinterface, noch im Log.

[marboj]

nochmals kurz zur Konkretisierung meines Problems:

Ich möchte das Xiaomi Thermometer LY...03 mit der alternativen FW an mein FHEM anbinden.

Bei Nutzung des Moduls ble_monitor erscheint der o.g. Fehler im STATE.

Bei Nutzung des Moduls mitemp wird der Name des Geräts als reading angelegt, nicht mehr nicht weniger...

Gruß
Marco

[dominik]

@Nighthawk, kannst du bitte mit der Version 358 testen? Bitte poste dann wieder das Log, danke!

@marboj, ok, ich hatte wohl übersehen, dass ble_monitor doch 3.8 oder höher benötigt. Sorry! Kannst du bitte nochmals mit 3.9 und Version 358 testen?

[Nighthawk]

Hallo Dominik,

hier das Log, leider ohne Veränderung:

Code Auswählen Erweitern

                                                                                                                                                                                                                                                                                                                                                                                                                                                       
2022-05-23 20:25:32,123 - INFO     - fhempy.lib.pkg_installer: Attempting install of fhempy
2022-05-23 20:25:32,705 - ERROR    - fhempy.lib.pkg_installer: Unable to install package fhempy: Traceback (most recent call last):
  File "/usr/lib/python3.9/runpy.py", line 197, in _run_module_as_main
    return _run_code(code, main_globals, None,
  File "/usr/lib/python3.9/runpy.py", line 87, in _run_code
    exec(code, run_globals)
  File "/opt/fhem/.local/lib/python3.9/site-packages/pip/__main__.py", line 29, in <module>
    from pip._internal.cli.main import main as _main
  File "/opt/fhem/.local/lib/python3.9/site-packages/pip/_internal/cli/main.py", line 9, in <module>
    from pip._internal.cli.autocompletion import autocomplete
  File "/opt/fhem/.local/lib/python3.9/site-packages/pip/_internal/cli/autocompletion.py", line 10, in <module>
    from pip._internal.cli.main_parser import create_main_parser
  File "/opt/fhem/.local/lib/python3.9/site-packages/pip/_internal/cli/main_parser.py", line 8, in <module>
    from pip._internal.cli import cmdoptions
  File "/opt/fhem/.local/lib/python3.9/site-packages/pip/_internal/cli/cmdoptions.py", line 23, in <module>
    from pip._internal.cli.parser import ConfigOptionParser
  File "/opt/fhem/.local/lib/python3.9/site-packages/pip/_internal/cli/parser.py", line 12, in <module>
    from pip._internal.configuration import Configuration, ConfigurationError
  File "/opt/fhem/.local/lib/python3.9/site-packages/pip/_internal/configuration.py", line 26, in <module>
    from pip._internal.utils.logging import getLogger
  File "/opt/fhem/.local/lib/python3.9/site-packages/pip/_internal/utils/logging.py", line 27, in <module>
    from pip._internal.utils.misc import ensure_dir
  File "/opt/fhem/.local/lib/python3.9/site-packages/pip/_internal/utils/misc.py", line 39, in <module>
    from pip._internal.locations import get_major_minor_version
  File "/opt/fhem/.local/lib/python3.9/site-packages/pip/_internal/locations/__init__.py", line 14, in <module>
    from . import _distutils, _sysconfig
  File "/opt/fhem/.local/lib/python3.9/site-packages/pip/_internal/locations/_distutils.py", line 9, in <module>
    from distutils.cmd import Command as DistutilsCommand
ModuleNotFoundError: No module named 'distutils.cmd'
2022-05-23 20:25:33,196 - INFO     - fhempy.lib.fhem_pythonbinding: Starting fhempy...
2022-05-23 20:25:33,198 - INFO     - fhempy.lib.fhem_pythonbinding: Waiting for FHEM connection
2022-05-23 20:25:33,227 - INFO     - websockets.server: server listening on 0.0.0.0:15733
2022-05-23 20:25:43,296 - INFO     - websockets.server: connection open
2022-05-23 20:25:43,299 - INFO     - fhempy.lib.fhem_pythonbinding: Incoming FHEM connection: 127.0.0.1
2022-05-23 20:25:43,381 - WARNING  - zeroconf: Error with socket 12 (('192.168.42.0', 5353))): [Errno 126] Required key not available
Traceback (most recent call last):
  File "/usr/lib/python3.9/asyncio/selector_events.py", line 1053, in sendto
    self._sock.sendto(data, addr)
OSError: [Errno 126] Required key not available
Exception in thread Thread-1:
Traceback (most recent call last):
  File "/usr/lib/python3.9/threading.py", line 973, in _bootstrap_inner
    self.run()
  File "/opt/fhem/.local/lib/python3.9/site-packages/fhempy/lib/ble_monitor/hcidump.py", line 183, in run
    self._event_loop.run_until_complete(
  File "/usr/lib/python3.9/asyncio/base_events.py", line 645, in run_until_complete
    raise RuntimeError('Event loop stopped before Future completed.')
RuntimeError: Event loop stopped before Future completed.
Exception in thread Thread-2:
Traceback (most recent call last):
  File "/usr/lib/python3.9/threading.py", line 973, in _bootstrap_inner
    self.run()
  File "/opt/fhem/.local/lib/python3.9/site-packages/fhempy/lib/ble_monitor/hcidump.py", line 183, in run
    self._event_loop.run_until_complete(
  File "/usr/lib/python3.9/asyncio/base_events.py", line 645, in run_until_complete
    raise RuntimeError('Event loop stopped before Future completed.')
RuntimeError: Event loop stopped before Future completed.
Exception in thread Thread-3:
Traceback (most recent call last):
  File "/usr/lib/python3.9/threading.py", line 973, in _bootstrap_inner
    self.run()
  File "/opt/fhem/.local/lib/python3.9/site-packages/fhempy/lib/ble_monitor/hcidump.py", line 183, in run
    self._event_loop.run_until_complete(
  File "/usr/lib/python3.9/asyncio/base_events.py", line 645, in run_until_complete
    raise RuntimeError('Event loop stopped before Future completed.')
RuntimeError: Event loop stopped before Future completed.
Exception in thread Thread-4:
Traceback (most recent call last):
  File "/usr/lib/python3.9/threading.py", line 973, in _bootstrap_inner
    self.run()
  File "/opt/fhem/.local/lib/python3.9/site-packages/fhempy/lib/ble_monitor/hcidump.py", line 183, in run
    self._event_loop.run_until_complete(
  File "/usr/lib/python3.9/asyncio/base_events.py", line 645, in run_until_complete
    raise RuntimeError('Event loop stopped before Future completed.')
RuntimeError: Event loop stopped before Future completed.
2022-05-23 20:25:48,948 - ERROR    - Feuermelder_SZ: HCIdump thread: Something wrong - interface hci0 not ready, and will be skipped for current scan period.
2022-05-23 20:25:53,955 - ERROR    - Feuermelder_SZ: HCIdump thread: Something wrong - interface hci0 not ready, and will be skipped for current scan period.
2022-05-23 20:25:58,962 - ERROR    - Feuermelder_SZ: HCIdump thread: Something wrong - interface hci0 not ready, and will be skipped for current scan period.
2022-05-23 20:26:03,967 - ERROR    - Feuermelder_SZ: HCIdump thread: Something wrong - interface hci0 not ready, and will be skipped for current scan period.
2022-05-23 20:26:08,973 - ERROR    - Feuermelder_SZ: HCIdump thread: Something wrong - interface hci0 not ready, and will be skipped for current scan period.
2022-05-23 20:27:16,753 - INFO     - fhempy.lib.fhem_pythonbinding: Starting fhempy...
2022-05-23 20:27:16,756 - INFO     - fhempy.lib.fhem_pythonbinding: Waiting for FHEM connection
2022-05-23 20:27:16,791 - INFO     - websockets.server: server listening on 0.0.0.0:15733
2022-05-23 20:27:34,764 - INFO     - websockets.server: connection open
2022-05-23 20:27:34,767 - INFO     - fhempy.lib.fhem_pythonbinding: Incoming FHEM connection: 127.0.0.1
2022-05-23 20:27:34,797 - WARNING  - zeroconf: Error with socket 12 (('192.168.42.0', 5353))): [Errno 126] Required key not available
Traceback (most recent call last):
  File "/usr/lib/python3.9/asyncio/selector_events.py", line 1053, in sendto
    self._sock.sendto(data, addr)
OSError: [Errno 126] Required key not available
Exception in thread Thread-1:
Traceback (most recent call last):
  File "/usr/lib/python3.9/threading.py", line 973, in _bootstrap_inner
    self.run()
  File "/opt/fhem/.local/lib/python3.9/site-packages/fhempy/lib/ble_monitor/hcidump.py", line 183, in run
    self._event_loop.run_until_complete(
  File "/usr/lib/python3.9/asyncio/base_events.py", line 645, in run_until_complete
    raise RuntimeError('Event loop stopped before Future completed.')
RuntimeError: Event loop stopped before Future completed.
Exception in thread Thread-2:
Traceback (most recent call last):
  File "/usr/lib/python3.9/threading.py", line 973, in _bootstrap_inner
    self.run()
  File "/opt/fhem/.local/lib/python3.9/site-packages/fhempy/lib/ble_monitor/hcidump.py", line 171, in run
    conn[hci], btctrl[hci] = self._event_loop.run_until_complete(
  File "/usr/lib/python3.9/asyncio/base_events.py", line 645, in run_until_complete
    raise RuntimeError('Event loop stopped before Future completed.')
RuntimeError: Event loop stopped before Future completed.
Exception in thread Thread-3:
Traceback (most recent call last):
  File "/usr/lib/python3.9/threading.py", line 973, in _bootstrap_inner
    self.run()
  File "/opt/fhem/.local/lib/python3.9/site-packages/fhempy/lib/ble_monitor/hcidump.py", line 171, in run
    conn[hci], btctrl[hci] = self._event_loop.run_until_complete(
  File "/usr/lib/python3.9/asyncio/base_events.py", line 645, in run_until_complete
    raise RuntimeError('Event loop stopped before Future completed.')
RuntimeError: Event loop stopped before Future completed.
Exception in thread Thread-4:
Traceback (most recent call last):
  File "/usr/lib/python3.9/threading.py", line 973, in _bootstrap_inner
    self.run()
  File "/opt/fhem/.local/lib/python3.9/site-packages/fhempy/lib/ble_monitor/hcidump.py", line 183, in run
    self._event_loop.run_until_complete(
  File "/usr/lib/python3.9/asyncio/base_events.py", line 645, in run_until_complete
    raise RuntimeError('Event loop stopped before Future completed.')
RuntimeError: Event loop stopped before Future completed.
2022-05-23 20:27:43,823 - ERROR    - Feuermelder_Galerie: HCIdump thread: Something wrong - interface hci0 not ready, and will be skipped for current scan period.
2022-05-23 20:27:48,827 - ERROR    - Feuermelder_Galerie: HCIdump thread: Something wrong - interface hci0 not ready, and will be skipped for current scan period.
2022-05-23 20:27:53,831 - ERROR    - Feuermelder_Galerie: HCIdump thread: Something wrong - interface hci0 not ready, and will be skipped for current scan period.
2022-05-23 20:27:58,835 - ERROR    - Feuermelder_Galerie: HCIdump thread: Something wrong - interface hci0 not ready, and will be skipped for current scan period.
2022-05-23 20:28:03,839 - ERROR    - Feuermelder_Galerie: HCIdump thread: Something wrong - interface hci0 not ready, and will be skipped for current scan period.


[dominik]

Ich glaube da liegt noch ein Fehler beim Update vor. Wie hast du fhempy aktualisiert? Weil das Log ist noch von einer älteren Version.

[marboj]

Hallo Dominik,

das Problem ist, dass ich zur Abfrage meiner Tür- und Fensterkontakte ebenfalls Python benötige und die Scripte geben Fehler unter 3.8 aus. Leider bin ich in Python niht fit um zu wissen, wie man die Scripte unter 3.8 lauffähig bekommt.

Was ist denn mit dem mitemp-Modul? Läuft das unter 3.7? Das sollte doch auch funktionieren, oder?

Gruß
Marco

[marboj]

Habe jetzt 3.8 installiert, parallel zu 3.7.

Leider nutzt Fhempy trotzdem beim Start 3.7. Wie kann man das steuern?

Code Auswählen Erweitern


Es gibt 2 Auswahlmöglichkeiten für die Alternative python (welche /usr/bin/python bereitstellen).

  Auswahl      Pfad                      Priorität Status
------------------------------------------------------------
* 0            /usr/local/bin/python3.8   2         automatischer Modus
  1            /usr/bin/python3.7         1         manueller Modus
  2            /usr/local/bin/python3.8   2         manueller Modus

[Nighthawk]

Hallo Dominik,

Das Update habe ich wie gewohnt mit Update all ausgeführt und anschließend  ein shutdown restart hinterhergeschoben.

Aktuell sieht es aus wie auf dem Screenshot, wenn ich Update check ausführe.

[Nighthawk]

Hallo Dominik,

gibt es wirklich keine Möglichkeit das Problem einzufangen und zu lösen?
Ich habe nun alles was mit fhempy zu tun hatte rausgelöscht und fhempy neu angelegt, leider ohne einen sichtbaren Erfolg.