fhempy: fhempy_peer_127_0_1_1 nach Update

[RockFan]

Hallo Dominik,
heute Mittag habe ich mal wieder ein Update FHEM und somit auf fhempy gefahren. Nach dem Neustart von FHEM ist dann plötzlich ein neuer fhempy-Peer aufgetaucht.
Bisher hatte ich einen Peer auf dem FHEM System (fhempy_local ws:127.0.0.1:15733) und zwei weiteren Raspis (fhempy_peer_192_168_178_88 und fhempy_peer_192_168_178_77).

Der neue Peer ist dann scheinbar einige Minuten nach Update und Neustart automatisch erstellt worden.
Hier ein list:

Code Auswählen Erweitern


Internals:
   BindingType fhempy
   CFGFN     
   DEF        127.0.1.1:15733 fhempy
   DeviceName ws:127.0.1.1:15733
   FD         104
   FUUID      62ab04dc-f33f-4546-926c-f94d09a6694cdf27
   IP         ws
   NAME       fhempy_peer_127_0_1_1
   NR         1970
   NTFY_ORDER 50-fhempy_peer_127_0_1_1
   PARTIAL   
   PORT       127.0.1.1:15733
   STATE      opened
   TYPE       BindingsIo
   WEBSOCKET  1
   eventCount 7
   localBinding 0
   nextOpenDelay 10
   READINGS:
     2022-06-16 12:24:31   hostname        raspberry4
     2022-06-16 12:24:31   os              posix
     2022-06-16 12:24:31   python          3.7.3
     2022-06-16 12:24:31   release         5.10.63-v7l+
     2022-06-16 12:24:31   state           opened
     2022-06-16 12:24:31   system          Linux
     2022-06-16 12:24:31   version         0.1.365
   args:
     fhempy_peer_127_0_1_1
     BindingsIo
     127.0.1.1:15733
     fhempy
   argsh:
Attributes:
   devStateIcon {      my $status_img = "10px-kreis-gruen";;      my $status_txt = "connected";;      if (ReadingsVal($name, "state", "disconnected") eq "disconnected") {        $status_img = "10px-kreis-rot";;        $status_txt = "disconnected";;      }      my $ver = ReadingsVal($name, "version", "-");;      "<div><a>".FW_makeImage($status_img, $status_txt)."</a><a> ".$ver." </a><a  href=\"/fhem?cmd.dummy=set $name update&XHR=1\" title=\"Start update\">".FW_makeImage("refresh")."</a></div>"    }
   group      fhempy
   icon       file_json-ld2
   room       fhempy


Im FHEM-Log finde ich folgende Einträge (erste Zeile wahrscheinlich irrelevant):

Code Auswählen Erweitern


2022.06.16 12:24:28 1: ws:192.168.178.77:15733 reappeared (fhempy_peer_192_168_178_77)
2022.06.16 12:24:31 3: Opening fhempy_peer_127_0_1_1 device ws:127.0.1.1:15733
2022.06.16 12:24:31 3: fhempy_peer_127_0_1_1 device opened


Aus dem fhempy-Log folgender Auszug:

Code Auswählen Erweitern


2022-06-16 12:08:47,499 - INFO     - fhempy.lib.fhem_pythonbinding: Start update...
2022-06-16 12:08:47,500 - INFO     - fhempy.lib.pkg_installer: Attempting install of fhempy
2022-06-16 12:09:11,542 - INFO     - fhempy.lib.pkg_installer: Successfully installed fhempy update!
2022-06-16 12:09:11,566 - INFO     - fhempy.lib.fhem_pythonbinding: Restart initiated...
2022-06-16 12:09:11,598 - INFO     - fhempy.lib.fhem_pythonbinding: All modules successfully undefined!
2022-06-16 12:09:11,600 - INFO     - websockets.server: server closing
2022-06-16 12:09:21,615 - ERROR    - fhempy.lib.fhem: Connection closed, can't send message.
2022-06-16 12:09:21,615 - ERROR    - fhempy.lib.fhem: Connection closed, can't send message.
2022-06-16 12:09:21,619 - INFO     - websockets.server: connection closed
2022-06-16 12:09:21,620 - INFO     - websockets.server: server closed
2022-06-16 12:09:21,659 - ERROR    - fhempy.lib.fhem: Connection closed, can't send message.
2022-06-16 12:09:21,661 - ERROR    - fhempy.lib.fhem: Connection closed, can't send message.
2022-06-16 12:09:31,654 - WARNING  - fhempy.lib.fhem_pythonbinding: Task could not be canceled and was still running after shutdown: <Task pending coro=<GatewayEntry.on_message() running at /opt/fhem/.local/lib/python3.7/site-packages/fhempy/lib/xiaomi_gateway3/core/gateway3.py:656> wait_for=<Future pending cb=[<TaskWakeupMethWrapper object at 0xb37afc70>()] created at /usr/lib/python3.7/asyncio/base_events.py:396> created at /usr/lib/python3.7/asyncio/tasks.py:325>
2022-06-16 12:09:31,655 - WARNING  - fhempy.lib.fhem_pythonbinding: Task could not be canceled and was still running after shutdown: <Task pending coro=<GatewayEntry.on_message() running at /opt/fhem/.local/lib/python3.7/site-packages/fhempy/lib/xiaomi_gateway3/core/gateway3.py:650> wait_for=<Future pending cb=[<TaskWakeupMethWrapper object at 0xb234d2b0>()] created at /usr/lib/python3.7/asyncio/base_events.py:396> created at /usr/lib/python3.7/asyncio/tasks.py:325>
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-06-16 12:09:31,964 - ERROR    - asyncio: Task was destroyed but it is pending!
source_traceback: Object created at (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 625, in run
    _cancel_all_tasks_with_timeout(loop, 10)
  File "/opt/fhem/.local/lib/python3.7/site-packages/fhempy/lib/fhem_pythonbinding.py", line 595, in _cancel_all_tasks_with_timeout
    loop.run_until_complete(asyncio.wait(to_cancel, timeout=timeout))
  File "/usr/lib/python3.7/asyncio/base_events.py", line 571, in run_until_complete
    self.run_forever()
  File "/usr/lib/python3.7/asyncio/base_events.py", line 539, in run_forever
    self._run_once()
  File "/usr/lib/python3.7/asyncio/base_events.py", line 1767, in _run_once
    handle._run()
  File "/usr/lib/python3.7/asyncio/events.py", line 88, in _run
    self._context.run(self._callback, *self._args)
  File "/opt/fhem/.local/lib/python3.7/site-packages/fhempy/lib/xiaomi_gateway3_device/devices/gateway.py", line 63, in async_update
    await fhem.readingsBulkUpdateIfChanged(self.hash, "state", "online")
  File "/opt/fhem/.local/lib/python3.7/site-packages/fhempy/lib/fhem.py", line 121, in readingsBulkUpdateIfChanged
    return await sendCommandHash(hash, cmd)
  File "/opt/fhem/.local/lib/python3.7/site-packages/fhempy/lib/fhem.py", line 346, in sendCommandHash
    return await sendCommandName(hash["NAME"], cmd, hash)
  File "/opt/fhem/.local/lib/python3.7/site-packages/fhempy/lib/fhem.py", line 328, in sendCommandName
    jsonmsg = await asyncio.wait_for(send_and_wait(name, cmd), 60)
  File "/usr/lib/python3.7/asyncio/tasks.py", line 403, in wait_for
    fut = ensure_future(fut, loop=loop)
task: <Task pending coro=<send_and_wait() running at /opt/fhem/.local/lib/python3.7/site-packages/fhempy/lib/fhem.py:316> wait_for=<Future pending cb=[<TaskWakeupMethWrapper object at 0xb37af8b0>()] created at /usr/lib/python3.7/asyncio/base_events.py:396> cb=[_release_waiter(<Future pendi...events.py:396>)() at /usr/lib/python3.7/asyncio/tasks.py:366] created at /usr/lib/python3.7/asyncio/tasks.py:403>
2022-06-16 12:09:31,965 - ERROR    - asyncio: Task was destroyed but it is pending!
source_traceback: Object created at (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 625, in run
    _cancel_all_tasks_with_timeout(loop, 10)
  File "/opt/fhem/.local/lib/python3.7/site-packages/fhempy/lib/fhem_pythonbinding.py", line 595, in _cancel_all_tasks_with_timeout
    loop.run_until_complete(asyncio.wait(to_cancel, timeout=timeout))
  File "/usr/lib/python3.7/asyncio/base_events.py", line 571, in run_until_complete
    self.run_forever()
  File "/usr/lib/python3.7/asyncio/base_events.py", line 539, in run_forever
    self._run_once()
  File "/usr/lib/python3.7/asyncio/base_events.py", line 1767, in _run_once
    handle._run()
  File "/usr/lib/python3.7/asyncio/events.py", line 88, in _run
    self._context.run(self._callback, *self._args)
  File "/opt/fhem/.local/lib/python3.7/site-packages/fhempy/lib/xiaomi_gateway3/xiaomi_gateway3.py", line 166, in create_device
    + did,
  File "/opt/fhem/.local/lib/python3.7/site-packages/fhempy/lib/fhem.py", line 207, in CommandDefine
    ret = await sendCommandHash(hash, cmd)
  File "/opt/fhem/.local/lib/python3.7/site-packages/fhempy/lib/fhem.py", line 346, in sendCommandHash
    return await sendCommandName(hash["NAME"], cmd, hash)
  File "/opt/fhem/.local/lib/python3.7/site-packages/fhempy/lib/fhem.py", line 328, in sendCommandName
    jsonmsg = await asyncio.wait_for(send_and_wait(name, cmd), 60)
  File "/usr/lib/python3.7/asyncio/tasks.py", line 403, in wait_for
    fut = ensure_future(fut, loop=loop)
task: <Task pending coro=<send_and_wait() running at /opt/fhem/.local/lib/python3.7/site-packages/fhempy/lib/fhem.py:316> wait_for=<Future pending cb=[<TaskWakeupMethWrapper object at 0xb234d210>()] created at /usr/lib/python3.7/asyncio/base_events.py:396> cb=[_release_waiter(<Future pendi...events.py:396>)() at /usr/lib/python3.7/asyncio/tasks.py:366] created at /usr/lib/python3.7/asyncio/tasks.py:403>
2022-06-16 12:09:31,965 - ERROR    - asyncio: Task was destroyed but it is pending!
source_traceback: Object created at (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 622, in run
    loop.run_until_complete(async_main())
  File "/usr/lib/python3.7/asyncio/base_events.py", line 571, in run_until_complete
    self.run_forever()
  File "/usr/lib/python3.7/asyncio/base_events.py", line 539, in run_forever
    self._run_once()
  File "/usr/lib/python3.7/asyncio/base_events.py", line 1767, in _run_once
    handle._run()
  File "/usr/lib/python3.7/asyncio/events.py", line 88, in _run
    self._context.run(self._callback, *self._args)
  File "/opt/fhem/.local/lib/python3.7/site-packages/fhempy/lib/xiaomi_gateway3/core/gateway3.py", line 591, in run_forever
    asyncio.create_task(self.on_message(msg))
  File "/usr/lib/python3.7/asyncio/tasks.py", line 325, in create_task
    return loop.create_task(coro)
task: <Task pending coro=<GatewayEntry.on_message() running at /opt/fhem/.local/lib/python3.7/site-packages/fhempy/lib/xiaomi_gateway3/core/gateway3.py:656> wait_for=<Future pending cb=[<TaskWakeupMethWrapper object at 0xb37afc70>()] created at /usr/lib/python3.7/asyncio/base_events.py:396> created at /usr/lib/python3.7/asyncio/tasks.py:325>
2022-06-16 12:09:31,966 - ERROR    - asyncio: Task was destroyed but it is pending!
source_traceback: Object created at (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 622, in run
    loop.run_until_complete(async_main())
  File "/usr/lib/python3.7/asyncio/base_events.py", line 571, in run_until_complete
    self.run_forever()
  File "/usr/lib/python3.7/asyncio/base_events.py", line 539, in run_forever
    self._run_once()
  File "/usr/lib/python3.7/asyncio/base_events.py", line 1767, in _run_once
    handle._run()
  File "/usr/lib/python3.7/asyncio/events.py", line 88, in _run
    self._context.run(self._callback, *self._args)
  File "/opt/fhem/.local/lib/python3.7/site-packages/fhempy/lib/xiaomi_gateway3/core/gateway3.py", line 591, in run_forever
    asyncio.create_task(self.on_message(msg))
  File "/usr/lib/python3.7/asyncio/tasks.py", line 325, in create_task
    return loop.create_task(coro)
task: <Task pending coro=<GatewayEntry.on_message() running at /opt/fhem/.local/lib/python3.7/site-packages/fhempy/lib/xiaomi_gateway3/core/gateway3.py:650> wait_for=<Future pending cb=[<TaskWakeupMethWrapper object at 0xb234d2b0>()] created at /usr/lib/python3.7/asyncio/base_events.py:396> created at /usr/lib/python3.7/asyncio/tasks.py:325>
2022-06-16 12:09:31,966 - ERROR    - fhempy.lib.xiaomi_gateway3.core.gateway3: Processing MQTT: gw/CC86ECFFFEAC687A/heartbeat b'{"networkUp":true,"networkPanId":"0x726E","radioTxPower":7,"radioChannel":20}'
Traceback (most recent call last):
  File "/opt/fhem/.local/lib/python3.7/site-packages/fhempy/lib/xiaomi_gateway3/core/gateway3.py", line 656, in on_message
    await self.process_gw_stats(msg.json)
GeneratorExit
2022-06-16 12:09:31,967 - ERROR    - fhempy.lib.xiaomi_gateway3.core.gateway3: Processing MQTT: log/ble b'{"did":"blt.3.199eag0985k00","eid":4102,"edata":"3802","pdid":1371,"seq":42}'
Traceback (most recent call last):
  File "/opt/fhem/.local/lib/python3.7/site-packages/fhempy/lib/xiaomi_gateway3/core/gateway3.py", line 650, in on_message
    await self.process_ble_event_fix(msg.json)
GeneratorExit
2022-06-16 12:09:34,819 - INFO     - fhempy.lib.fhem_pythonbinding: Starting fhempy 0.1.365...
2022-06-16 12:09:34,826 - INFO     - fhempy.lib.fhem_pythonbinding: Waiting for FHEM connection
2022-06-16 12:09:34,867 - INFO     - websockets.server: server listening on 0.0.0.0:15733
2022-06-16 12:09:41,739 - INFO     - websockets.server: connection open
2022-06-16 12:09:41,742 - INFO     - fhempy.lib.fhem_pythonbinding: Incoming FHEM connection: 127.0.0.1
2022-06-16 12:09:41,891 - INFO     - fhempy.lib.pkg_installer: Attempting install of python-miio==0.5.10
2022-06-16 12:09:48,680 - WARNING  - asyncio: Executing <Handle <TaskWakeupMethWrapper object at 0xb18a8ad0>(<Future finis...events.py:396>) created at /opt/fhem/.local/lib/python3.7/site-packages/aiohttp/helpers.py:730> took 0.138 seconds
2022-06-16 12:09:49,661 - WARNING  - asyncio: Executing <Handle <TaskWakeupMethWrapper object at 0xb4cb8070>(<Future finis...events.py:396>) created at /usr/lib/python3.7/asyncio/tasks.py:368> took 0.106 seconds
2022-06-16 12:10:10,366 - INFO     - fhempy.lib.pkg_installer: Successfully installed python-miio==0.5.10 update!
2022-06-16 12:10:14,229 - ERROR    - da_LEDStrip: Annotation is not class: typing.Tuple[int, int, int]
2022-06-16 12:10:14,353 - ERROR    - da_LEDBulb1: Annotation is not class: typing.Tuple[int, int, int]
2022-06-16 12:10:14,376 - ERROR    - wz_Lampe: Annotation is not class: typing.Tuple[int, int, int]
2022-06-16 12:10:19,384 - WARNING  - asyncio: Executing <Handle <TaskWakeupMethWrapper object at 0xb0d52590>(<Future finis...events.py:396>) created at /usr/lib/python3.7/asyncio/tasks.py:368> took 5.010 seconds
2022-06-16 12:10:24,143 - ERROR    - da_LEDBulb1: Failed to send_command: info
2022-06-16 12:10:24,148 - ERROR    - da_LEDStrip: Failed to send_command: info
2022-06-16 12:11:19,806 - INFO     - fhempy.lib.fhem_pythonbinding: Shutdown initiated...
2022-06-16 12:11:19,841 - INFO     - fhempy.lib.fhem_pythonbinding: All modules successfully undefined!
2022-06-16 12:11:19,843 - INFO     - websockets.server: server closing
2022-06-16 12:11:24,879 - INFO     - fhempy.lib.fhem_pythonbinding: Starting fhempy 0.1.365...
2022-06-16 12:11:24,886 - INFO     - fhempy.lib.fhem_pythonbinding: Waiting for FHEM connection
2022-06-16 12:11:24,926 - INFO     - websockets.server: server listening on 0.0.0.0:15733
2022-06-16 12:11:34,186 - INFO     - websockets.server: connection open
2022-06-16 12:11:34,190 - INFO     - fhempy.lib.fhem_pythonbinding: Incoming FHEM connection: 127.0.0.1
2022-06-16 12:11:34,607 - INFO     - fhempy.lib.pkg_installer: Attempting install of python-miio==0.5.8
2022-06-16 12:11:36,406 - ERROR    - wz_Lampe: Annotation is not class: typing.Tuple[int, int, int]
2022-06-16 12:11:41,502 - WARNING  - asyncio: Executing <Handle <TaskWakeupMethWrapper object at 0xb221e610>(<Future finis...events.py:396>) created at /usr/lib/python3.7/asyncio/tasks.py:368> took 5.099 seconds
2022-06-16 12:11:41,505 - ERROR    - da_LEDBulb1: Annotation is not class: typing.Tuple[int, int, int]
2022-06-16 12:11:46,541 - WARNING  - asyncio: Executing <Handle <TaskWakeupMethWrapper object at 0xb2ecd730>(<Future finis...events.py:396>) created at /usr/lib/python3.7/asyncio/tasks.py:368> took 5.039 seconds
2022-06-16 12:11:46,632 - ERROR    - da_LEDStrip: Annotation is not class: typing.Tuple[int, int, int]
2022-06-16 12:11:49,529 - WARNING  - asyncio: Executing <Handle <TaskWakeupMethWrapper object at 0xb277fed0>(<Future finis...events.py:396>) created at /opt/fhem/.local/lib/python3.7/site-packages/aiohttp/helpers.py:730> took 0.117 seconds
2022-06-16 12:11:54,684 - ERROR    - da_LEDBulb1: Failed to send_command: info
2022-06-16 12:11:54,690 - ERROR    - da_LEDStrip: Failed to send_command: info
2022-06-16 12:11:55,877 - INFO     - fhempy.lib.pkg_installer: Successfully installed python-miio==0.5.8 update!
2022-06-16 12:13:54,319 - INFO     - fhempy.lib.fhem_pythonbinding: Starting fhempy 0.1.365...
2022-06-16 12:13:54,328 - INFO     - fhempy.lib.fhem_pythonbinding: Waiting for FHEM connection
2022-06-16 12:13:54,385 - INFO     - websockets.server: server listening on 0.0.0.0:15733
2022-06-16 12:13:59,040 - INFO     - websockets.server: connection open
2022-06-16 12:13:59,042 - INFO     - fhempy.lib.fhem_pythonbinding: Incoming FHEM connection: 127.0.0.1
2022-06-16 12:13:59,458 - INFO     - fhempy.lib.pkg_installer: Attempting install of python-miio==0.5.10
2022-06-16 12:14:07,782 - WARNING  - asyncio: Executing <Handle <TaskWakeupMethWrapper object at 0xb3efdb90>(<Future finis...events.py:396>) created at /usr/lib/python3.7/asyncio/futures.py:288> took 0.152 seconds
2022-06-16 12:14:08,976 - WARNING  - asyncio: Executing <Handle <TaskWakeupMethWrapper object at 0xb3e411f0>(<Future finis...events.py:396>) created at /usr/lib/python3.7/asyncio/tasks.py:368> took 0.109 seconds
2022-06-16 12:14:20,843 - INFO     - fhempy.lib.pkg_installer: Successfully installed python-miio==0.5.10 update!
2022-06-16 12:14:28,122 - WARNING  - asyncio: Executing <Handle <TaskWakeupMethWrapper object at 0xb1c31db0>(<Future finis...events.py:396>) created at /usr/lib/python3.7/asyncio/futures.py:288> took 0.220 seconds
2022-06-16 12:14:28,286 - ERROR    - da_LEDBulb1: Annotation is not class: typing.Tuple[int, int, int]
2022-06-16 12:14:33,412 - WARNING  - asyncio: Executing <Handle <TaskWakeupMethWrapper object at 0xb4c441f0>(<Future finis...events.py:396>) created at /usr/lib/python3.7/asyncio/tasks.py:368> took 5.129 seconds
2022-06-16 12:14:33,418 - ERROR    - da_LEDStrip: Annotation is not class: typing.Tuple[int, int, int]
2022-06-16 12:14:33,439 - ERROR    - wz_Lampe: Annotation is not class: typing.Tuple[int, int, int]
2022-06-16 12:14:38,447 - WARNING  - asyncio: Executing <Handle <TaskWakeupMethWrapper object at 0xb2c67770>(<Future finis...events.py:396>) created at /usr/lib/python3.7/asyncio/tasks.py:368> took 5.014 seconds
2022-06-16 12:14:44,331 - ERROR    - da_LEDStrip: Failed to send_command: info
2022-06-16 12:14:44,335 - ERROR    - da_LEDBulb1: Failed to send_command: info
2022-06-16 12:24:31,529 - INFO     - websockets.server: connection open
2022-06-16 12:24:31,530 - INFO     - fhempy.lib.fhem_pythonbinding: Incoming FHEM connection: 127.0.0.1


Ich dachte ich berichte das mal hier im Forum, obwohl ich jetzt kein konkretes Problem damit habe. Eigentlich wollte ich den neuen Peer schon löschen, aber falls noch Infos benötigt werden belasse ich ihn mal vorläufug.


Viele Grüße
Dieter

[dominik]

Hi,

sorry für die späte Antwort. Ich schaue jedoch kaum neue Threads durch 

Kannst du ein
ps -efa|grep fhempy
machen und schauen wie viele Prozesse laufen? Es klingt danach als würde ein Prozess ohne -local Argument laufen. Wenn das Argument nicht angeführt wird, dann macht fhempy einen Broadcast und FHEM erstellt daraufhin automatisch ein Device dafür wenn noch keines existiert.

[RockFan]

Hi Dominik,

Code Auswählen Erweitern

Ich schaue jedoch kaum neue Threads durch
Ich wusste nicht wohin damit - es passt zu keinem bestehenden Thread - und es war auch nicht so wichtig 

So magisch, wie das Peer-Device erschienen ist, ist es nach einiger Zeit (mehrere Tage oder gar Wochen) plötzlich wieder von ganz alleine verschwunden. Ich habe den Thread hier nicht pushen wollen, da ich nicht genau mitbekommen hatte, wann genau es wieder weg war und somit auch keine passenden Logs finden können.


Viele Grüße
Dieter

[dominik]

Alles klar