fhempy: fhempy_log in FHEM

[DB1113]

Hallo zusammen,

ich bekomme seit dem Update auf v0.1.535 von FHEM folgende Meldung angezeigt:

_Messages collected while initializing FHEM:configfile: fhempylog already defined, delete it first Autosave deactivated

Dies liegt offensichtlich daran, dass fhempy versucht den schon bestehenden Log anzulegen. Selbes Problem hatten wir schon mal: Link ins fhem-Forum

Wie kann man das deaktivieren oder muss ich irgendwas machen?

Thx und bis denn

[fhempy]

Danke für den Hinweis. Sollte nun behoben sein. Du musst dazu ein FHEM update machen, nicht nur fhempy.

Falls es nicht funktioniert, bitte das Issue neu aufmachen.

[DB1113]

Hi, Danke für deine schnelle Reaktion. Allerdings hat es keine Besserung zur Folge. Hab das Fhem-Update durchgeführt, fhempy in fhem nochmal ein Update durchgeführt und den Raspi neu gestartet. Trotzdem die Meldung.

Bis denn!

P. S. Im übrigen noch ein frohes neues Jahr. Viel Erfolg und Gesundheit! Das war sehr unhöflich von mir... 😉

Und wie mach ich das Issue wieder auf? 🫣

[fhempy]

Dir auch alles Gute im neuen Jahr!

Kannst du mir bitte nochmals das Log posten von FHEM wo der Fehler auftritt?

[fhempy]

Ich habe gerade noch etwas geändert. Bitte ein FHEM update machen und neustarten.

[DB1113]

Hi, leider unverändert. Hier ein Logauszug... Vom Ende Bis denn `Traceback (most recent call last): File "/opt/fhem/.local/lib/python3.9/site-packages/slixmpp/xmlstream/xmlstream.py", line 834, in handler_callback_routine await cb(data) File "/opt/fhem/.local/lib/python3.9/site-packages/aionefit/provider/slixmpp_impl.py", line 69, in message_callback await self.nefit_client.raw_message_callback(msg, 'message') File "/opt/fhem/.local/lib/python3.9/site-packages/aionefit/init.py", line 90, in raw_message_callback raise SystemError(statusline) SystemError: HTTP/1.0 400 Bad Request 2023-01-02 19:40:53,151 - ERROR - slixmpp.basexmpp: HTTP/1.0 400 Bad Request Traceback (most recent call last): File "/opt/fhem/.local/lib/python3.9/site-packages/slixmpp/xmlstream/xmlstream.py", line 834, in handler_callback_routine await cb(data) File "/opt/fhem/.local/lib/python3.9/site-packages/aionefit/provider/slixmpp_impl.py", line 69, in message_callback await self.nefit_client.raw_message_callback(msg, 'message') File "/opt/fhem/.local/lib/python3.9/site-packages/aionefit/init.py", line 90, in raw_message_callback raise SystemError(statusline) SystemError: HTTP/1.0 400 Bad Request 2023-01-02 19:43:24,926 - WARNING - asyncio: Executing <Handle run_coroutine_threadsafe..callback() at /usr/lib/python3.9/asyncio/tasks.py:921 created at /usr/lib/python3.9/asyncio/tasks.py:931> took 0.131 seconds 2023-01-02 20:00:10,975 - ERROR - Gtag_Anja: Failed to scan: Failed to execute management command 'scanend' (code: 11, error: Rejected) 2023-01-02 20:06:51,552 - INFO - fhempy.lib.fhem_pythonbinding: Start update... 2023-01-02 20:06:51,554 - INFO - fhempy.lib.pkg_installer: Attempting install of fhempy 2023-01-02 20:07:06,191 - INFO - fhempy.lib.pkg_installer: Successfully installed fhempy update! 2023-01-02 20:07:06,271 - INFO - fhempy.lib.fhem_pythonbinding: Restart initiated... 2023-01-02 20:07:06,409 - INFO - fhempy.lib.fhem_pythonbinding: All modules successfully undefined! 2023-01-02 20:07:06,429 - INFO - websockets.server: server closing 2023-01-02 20:07:12,354 - ERROR - aionefit: Errormessage received: HTTP/1.0 400 Bad Request 2023-01-02 20:07:12,354 - ERROR - aionefit: response is null 2023-01-02 20:07:12,355 - ERROR - slixmpp.stanza.rootstanza: Error handling {jabber:client}message stanza Traceback (most recent call last): File "/opt/fhem/.local/lib/python3.9/site-packages/slixmpp/xmlstream/xmlstream.py", line 834, in handler_callback_routine await cb(data) File "/opt/fhem/.local/lib/python3.9/site-packages/aionefit/provider/slixmpp_impl.py", line 69, in message_callback await self.nefit_client.raw_message_callback(msg, 'message') File "/opt/fhem/.local/lib/python3.9/site-packages/aionefit/init.py", line 90, in raw_message_callback raise SystemError(statusline) SystemError: HTTP/1.0 400 Bad Request 2023-01-02 20:07:12,356 - ERROR - slixmpp.basexmpp: HTTP/1.0 400 Bad Request Traceback (most recent call last): File "/opt/fhem/.local/lib/python3.9/site-packages/slixmpp/xmlstream/xmlstream.py", line 834, in handler_callback_routine await cb(data) File "/opt/fhem/.local/lib/python3.9/site-packages/aionefit/provider/slixmpp_impl.py", line 69, in message_callback await self.nefit_client.raw_message_callback(msg, 'message') File "/opt/fhem/.local/lib/python3.9/site-packages/aionefit/init.py", line 90, in raw_message_callback raise SystemError(statusline) SystemError: HTTP/1.0 400 Bad Request 2023-01-02 20:07:16,523 - INFO - websockets.server: connection closed 2023-01-02 20:07:16,524 - INFO - websockets.server: server closed 2023-01-02 20:07:16,527 - INFO - fhempy.lib.fhem_pythonbinding: Exit 1 2023-01-02 20:08:07,025 - INFO - fhempy.lib.fhem_pythonbinding: Starting fhempy 0.1.537... 2023-01-02 20:08:07,032 - INFO - fhempy.lib.fhem_pythonbinding: Waiting for FHEM connection 2023-01-02 20:08:07,087 - INFO - websockets.server: server listening on 0.0.0.0:15733 2023-01-02 20:08:29,268 - INFO - websockets.server: connection open 2023-01-02 20:08:29,269 - INFO - fhempy.lib.fhem_pythonbinding: Incoming FHEM connection: 127.0.0.1 2023-01-02 20:08:32,714 - WARNING - asyncio: Executing <Task pending name='Task-61' coro=<arp_presence.Define() running at /opt/fhem/.local/lib/python3.9/site-packages/fhempy/lib/arp_presence/arp_presence.py:68> wait_for=<Future pending cb=[<TaskWakeupMethWrapper object at 0xacfcca78>()] created at /usr/lib/python3.9/asyncio/base_events.py:424> cb=[_release_waiter()() at /usr/lib/python3.9/asyncio/tasks.py:416] created at /usr/lib/python3.9/asyncio/tasks.py:462> took 0.120 seconds 2023-01-02 20:08:32,811 - WARNING - slixmpp.stringprep: Using slower stringprep, consider compiling the faster cython/libidn one. 2023-01-02 20:08:42,418 - INFO - slixmpp.features.feature_bind.bind: JID set to: rrccontact_717941259@wa2-mz36-qrmzh6.bosch.de/7yhhtl0rlp 2023-01-02 20:08:48,700 - ERROR - aionefit: Errormessage received: HTTP/1.0 400 Bad Request 2023-01-02 20:08:48,700 - ERROR - aionefit: response is null 2023-01-02 20:08:48,703 - ERROR - slixmpp.stanza.rootstanza: Error handling {jabber:client}message stanza Traceback (most recent call last): File "/opt/fhem/.local/lib/python3.9/site-packages/slixmpp/xmlstream/xmlstream.py", line 834, in handler_callback_routine await cb(data) File "/opt/fhem/.local/lib/python3.9/site-packages/aionefit/provider/slixmpp_impl.py", line 69, in message_callback await self.nefit_client.raw_message_callback(msg, 'message') File "/opt/fhem/.local/lib/python3.9/site-packages/aionefit/init.py", line 90, in raw_message_callback raise SystemError(statusline) SystemError: HTTP/1.0 400 Bad Request 2023-01-02 20:08:48,703 - ERROR - slixmpp.basexmpp: HTTP/1.0 400 Bad Request Traceback (most recent call last): File "/opt/fhem/.local/lib/python3.9/site-packages/slixmpp/xmlstream/xmlstream.py", line 834, in handler_callback_routine await cb(data) File "/opt/fhem/.local/lib/python3.9/site-packages/aionefit/provider/slixmpp_impl.py", line 69, in message_callback await self.nefit_client.raw_message_callback(msg, 'message') File "/opt/fhem/.local/lib/python3.9/site-packages/aionefit/init.py", line 90, in raw_message_callback raise SystemError(statusline) SystemError: HTTP/1.0 400 Bad Request 2023-01-02 20:09:00,119 - INFO - fhempy.lib.fhem_pythonbinding: Shutdown initiated... 2023-01-02 20:09:00,346 - INFO - fhempy.lib.fhem_pythonbinding: All modules successfully undefined! 2023-01-02 20:09:00,352 - INFO - websockets.server: server closing 2023-01-02 20:09:06,279 - ERROR - aionefit: Errormessage received: HTTP/1.0 400 Bad Request 2023-01-02 20:09:06,280 - ERROR - aionefit: response is null 2023-01-02 20:09:06,282 - ERROR - slixmpp.stanza.rootstanza: Error handling {jabber:client}message stanza Traceback (most recent call last): File "/opt/fhem/.local/lib/python3.9/site-packages/slixmpp/xmlstream/xmlstream.py", line 834, in handler_callback_routine await cb(data) File "/opt/fhem/.local/lib/python3.9/site-packages/aionefit/provider/slixmpp_impl.py", line 69, in message_callback await self.nefit_client.raw_message_callback(msg, 'message') File "/opt/fhem/.local/lib/python3.9/site-packages/aionefit/init.py", line 90, in raw_message_callback raise SystemError(statusline) SystemError: HTTP/1.0 400 Bad Request 2023-01-02 20:09:06,282 - ERROR - slixmpp.basexmpp: HTTP/1.0 400 Bad Request Traceback (most recent call last): File "/opt/fhem/.local/lib/python3.9/site-packages/slixmpp/xmlstream/xmlstream.py", line 834, in handler_callback_routine await cb(data) File "/opt/fhem/.local/lib/python3.9/site-packages/aionefit/provider/slixmpp_impl.py", line 69, in message_callback await self.nefit_client.raw_message_callback(msg, 'message') File "/opt/fhem/.local/lib/python3.9/site-packages/aionefit/init.py", line 90, in raw_message_callback raise SystemError(statusline) SystemError: HTTP/1.0 400 Bad Request 2023-01-02 20:09:10,384 - ERROR - fhempy.lib.fhem_pythonbinding: Failed to handle message: Traceback (most recent call last): File "/opt/fhem/.local/lib/python3.9/site-packages/websockets/legacy/protocol.py", line 945, in transfer_data message = await self.read_message() File "/opt/fhem/.local/lib/python3.9/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.9/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.9/site-packages/websockets/legacy/protocol.py", line 1145, in read_frame frame = await Frame.read( File "/opt/fhem/.local/lib/python3.9/site-packages/websockets/legacy/framing.py", line 70, in read data = await reader(2) File "/usr/lib/python3.9/asyncio/streams.py", line 723, in readexactly await self._wait_for_data('readexactly') File "/usr/lib/python3.9/asyncio/streams.py", line 517, in _wait_for_data await self._waiter asyncio.exceptions.CancelledError

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

Traceback (most recent call last): File "/opt/fhem/.local/lib/python3.9/site-packages/fhempy/lib/fhem_pythonbinding.py", line 207, in _onMessage await self.handle_message(msg, hash) File "/opt/fhem/.local/lib/python3.9/site-packages/fhempy/lib/fhem_pythonbinding.py", line 233, in handle_message await self.handle_function(hash, msg) File "/opt/fhem/.local/lib/python3.9/site-packages/fhempy/lib/fhem_pythonbinding.py", line 392, in handle_function await self.sendBackReturn(hash, ret) File "/opt/fhem/.local/lib/python3.9/site-packages/fhempy/lib/fhem_pythonbinding.py", line 117, in sendBackReturn await self.wsconnection.send(msg.encode("utf-8")) File "/opt/fhem/.local/lib/python3.9/site-packages/websockets/legacy/protocol.py", line 620, in send await self.ensure_open() File "/opt/fhem/.local/lib/python3.9/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 2023-01-02 20:09:10,386 - ERROR - fhempy.lib.fhem_pythonbinding: fhempy failed to handle message with hash: {'argsh': {}, 'function': 'Set', 'defargs': ['GG_Alarm', 'fhempy', 'googlecast', 'Alarm'], 'PYTHONTYPE': 'googlecast', 'defargsh': {}, 'args': ['GG_Alarm', '?'], 'NAME': 'GG_Alarm', 'msgtype': 'function', 'FHEMPYTYPE': 'googlecast', 'id': '50924534'} 2023-01-02 20:09:10,387 - ERROR - fhempy.lib.fhem_pythonbinding: Failed to handle message: b'{"argsh":{},"function":"Set","defargs":["GG_Alarm","fhempy","googlecast","Alarm"],"PYTHONTYPE":"googlecast","defargsh":{},"args":["GG_Alarm","?"],"NAME":"GG_Alarm","msgtype":"function","FHEMPYTYPE":"googlecast","id":"50924534"}' Traceback (most recent call last): File "/opt/fhem/.local/lib/python3.9/site-packages/websockets/legacy/protocol.py", line 945, in transfer_data message = await self.read_message() File "/opt/fhem/.local/lib/python3.9/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.9/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.9/site-packages/websockets/legacy/protocol.py", line 1145, in read_frame frame = await Frame.read( File "/opt/fhem/.local/lib/python3.9/site-packages/websockets/legacy/framing.py", line 70, in read data = await reader(2) File "/usr/lib/python3.9/asyncio/streams.py", line 723, in readexactly await self._wait_for_data('readexactly') File "/usr/lib/python3.9/asyncio/streams.py", line 517, in _wait_for_data await self._waiter asyncio.exceptions.CancelledError

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

Traceback (most recent call last): File "/opt/fhem/.local/lib/python3.9/site-packages/fhempy/lib/fhem_pythonbinding.py", line 175, in onMessage await self._onMessage(payload) File "/opt/fhem/.local/lib/python3.9/site-packages/fhempy/lib/fhem_pythonbinding.py", line 210, in _onMessage await self.sendBackError(hash, "fhempy failed to handle message") File "/opt/fhem/.local/lib/python3.9/site-packages/fhempy/lib/fhem_pythonbinding.py", line 130, in sendBackError await self.wsconnection.send(msg.encode("utf-8")) File "/opt/fhem/.local/lib/python3.9/site-packages/websockets/legacy/protocol.py", line 620, in send await self.ensure_open() File "/opt/fhem/.local/lib/python3.9/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 2023-01-02 20:09:10,398 - INFO - websockets.server: connection closed 2023-01-02 20:09:10,400 - INFO - websockets.server: server closed 2023-01-02 20:09:10,407 - INFO - fhempy.lib.fhem_pythonbinding: Exit 0 2023-01-02 20:09:14,528 - INFO - fhempy.lib.fhem_pythonbinding: Starting fhempy 0.1.537... 2023-01-02 20:09:14,533 - INFO - fhempy.lib.fhem_pythonbinding: Waiting for FHEM connection 2023-01-02 20:09:14,576 - INFO - websockets.server: server listening on 0.0.0.0:15733 2023-01-02 20:11:02,667 - INFO - fhempy.lib.fhem_pythonbinding: Starting fhempy 0.1.537... 2023-01-02 20:11:02,673 - INFO - fhempy.lib.fhem_pythonbinding: Waiting for FHEM connection 2023-01-02 20:11:02,755 - INFO - websockets.server: server listening on 0.0.0.0:15733 2023-01-02 20:11:19,231 - INFO - websockets.server: connection open 2023-01-02 20:11:19,233 - INFO - fhempy.lib.fhem_pythonbinding: Incoming FHEM connection: 127.0.0.1 2023-01-02 20:11:20,906 - WARNING - slixmpp.stringprep: Using slower stringprep, consider compiling the faster cython/libidn one. 2023-01-02 20:11:29,316 - WARNING - asyncio: Executing <Task pending name='Task-73' coro=<ble_reset.Define() running at /opt/fhem/.local/lib/python3.9/site-packages/fhempy/lib/ble_reset/ble_reset.py:47> wait_for=<Future pending cb=[<TaskWakeupMethWrapper object at 0xb47fcc28>()] created at /usr/lib/python3.9/asyncio/base_events.py:424> cb=[_release_waiter()() at /usr/lib/python3.9/asyncio/tasks.py:416] created at /usr/lib/python3.9/asyncio/tasks.py:462> took 0.262 seconds 2023-01-02 20:11:30,297 - WARNING - asyncio: Executing <Task pending name='Task-371' coro=<FhemModule._run_coro() running at /opt/fhem/.local/lib/python3.9/site-packages/fhempy/lib/generic.py:196> wait_for=<Future pending cb=[<TaskWakeupMethWrapper object at 0xb4682820>()] created at /usr/lib/python3.9/asyncio/base_events.py:424> cb=[FhemModule._handle_task_result()] created at /usr/lib/python3.9/asyncio/tasks.py:361> took 0.142 seconds 2023-01-02 20:11:31,239 - INFO - slixmpp.features.feature_bind.bind: JID set to: rrccontact_717941259@wa2-mz36-qrmzh6.bosch.de/8i89e5bpya 2023-01-02 20:11:37,495 - ERROR - aionefit: Errormessage received: HTTP/1.0 400 Bad Request 2023-01-02 20:11:37,495 - ERROR - aionefit: response is null 2023-01-02 20:11:37,498 - ERROR - slixmpp.stanza.rootstanza: Error handling {jabber:client}message stanza Traceback (most recent call last): File "/opt/fhem/.local/lib/python3.9/site-packages/slixmpp/xmlstream/xmlstream.py", line 834, in handler_callback_routine await cb(data) File "/opt/fhem/.local/lib/python3.9/site-packages/aionefit/provider/slixmpp_impl.py", line 69, in message_callback await self.nefit_client.raw_message_callback(msg, 'message') File "/opt/fhem/.local/lib/python3.9/site-packages/aionefit/init.py", line 90, in raw_message_callback raise SystemError(statusline) SystemError: HTTP/1.0 400 Bad Request 2023-01-02 20:11:37,498 - ERROR - slixmpp.basexmpp: HTTP/1.0 400 Bad Request Traceback (most recent call last): File "/opt/fhem/.local/lib/python3.9/site-packages/slixmpp/xmlstream/xmlstream.py", line 834, in handler_callback_routine await cb(data) File "/opt/fhem/.local/lib/python3.9/site-packages/aionefit/provider/slixmpp_impl.py", line 69, in message_callback await self.nefit_client.raw_message_callback(msg, 'message') File "/opt/fhem/.local/lib/python3.9/site-packages/aionefit/init.py", line 90, in raw_message_callback raise SystemError(statusline) SystemError: HTTP/1.0 400 Bad Request 2023-01-02 20:11:44,715 - WARNING - asyncio: Executing <Task pending name='Task-338' coro=<scanner.loop_scan() running at /opt/fhem/.local/lib/python3.9/site-packages/fhempy/lib/ble_presence/ble_presence.py:150> wait_for=<Future pending cb=[<TaskWakeupMethWrapper object at 0xb415ff70>()] created at /usr/lib/python3.9/asyncio/base_events.py:424> created at /usr/lib/python3.9/asyncio/tasks.py:361> took 0.318 seconds 2023-01-02 20:11:49,866 - WARNING - asyncio: Executing <Task pending name='Task-338' coro=<scanner.loop_scan() running at /opt/fhem/.local/lib/python3.9/site-packages/fhempy/lib/ble_presence/ble_presence.py:150> wait_for=<Future pending cb=[_chain_future.._call_check_cancel() at /usr/lib/python3.9/asyncio/futures.py:384, <TaskWakeupMethWrapper object at 0xb406cb08>()] created at /usr/lib/python3.9/asyncio/base_events.py:424> created at /usr/lib/python3.9/asyncio/tasks.py:361> took 0.158 seconds 2023-01-02 20:11:52,215 - WARNING - asyncio: Executing <Task pending name='Task-338' coro=<scanner.loop_scan() running at /opt/fhem/.local/lib/python3.9/site-packages/fhempy/lib/ble_presence/ble_presence.py:150> wait_for=<Future pending cb=[_chain_future.._call_check_cancel() at /usr/lib/python3.9/asyncio/futures.py:384, <TaskWakeupMethWrapper object at 0xb406cc58>()] created at /usr/lib/python3.9/asyncio/base_events.py:424> created at /usr/lib/python3.9/asyncio/tasks.py:361> took 0.210 seconds 2023-01-02 20:11:53,265 - WARNING - asyncio: Executing <Task pending name='Task-338' coro=<scanner.loop_scan() running at /opt/fhem/.local/lib/python3.9/site-packages/fhempy/lib/ble_presence/ble_presence.py:150> wait_for=<Future pending cb=[_chain_future.._call_check_cancel() at /usr/lib/python3.9/asyncio/futures.py:384, <TaskWakeupMethWrapper object at 0xb406c238>()] created at /usr/lib/python3.9/asyncio/base_events.py:424> created at /usr/lib/python3.9/asyncio/tasks.py:361> took 0.176 seconds 2023-01-02 20:11:53,893 - INFO - fhempy.lib.fhem_pythonbinding: Start update... 2023-01-02 20:11:53,894 - INFO - fhempy.lib.pkg_installer: Attempting install of fhempy 2023-01-02 20:11:56,566 - WARNING - asyncio: Executing <Task pending name='Task-338' coro=<scanner.loop_scan() running at /opt/fhem/.local/lib/python3.9/site-packages/fhempy/lib/ble_presence/ble_presence.py:150> wait_for=<Future pending cb=[_chain_future.._call_check_cancel() at /usr/lib/python3.9/asyncio/futures.py:384, <TaskWakeupMethWrapper object at 0xb406cbc8>()] created at /usr/lib/python3.9/asyncio/base_events.py:424> created at /usr/lib/python3.9/asyncio/tasks.py:361> took 0.191 seconds 2023-01-02 20:12:07,224 - INFO - fhempy.lib.pkg_installer: Successfully installed fhempy update! 2023-01-02 20:12:08,613 - INFO - fhempy.lib.fhem_pythonbinding: Restart initiated... 2023-01-02 20:12:08,771 - INFO - fhempy.lib.fhem_pythonbinding: All modules successfully undefined! 2023-01-02 20:12:08,779 - INFO - websockets.server: server closing 2023-01-02 20:12:08,963 - ERROR - fhempy.lib.fhem: Failed to set result, received: {"result":"next_hours_10_img: <img src=\"//ssl.gstatic.com/onebox/weather/64/partly_cloudy.png\"/>","awaitId":11556734,"error":0} 2023-01-02 20:12:14,753 - ERROR - aionefit: Errormessage received: HTTP/1.0 400 Bad Request 2023-01-02 20:12:14,754 - ERROR - aionefit: response is null 2023-01-02 20:12:14,755 - ERROR - slixmpp.stanza.rootstanza: Error handling {jabber:client}message stanza Traceback (most recent call last): File "/opt/fhem/.local/lib/python3.9/site-packages/slixmpp/xmlstream/xmlstream.py", line 834, in handler_callback_routine await cb(data) File "/opt/fhem/.local/lib/python3.9/site-packages/aionefit/provider/slixmpp_impl.py", line 69, in message_callback await self.nefit_client.raw_message_callback(msg, 'message') File "/opt/fhem/.local/lib/python3.9/site-packages/aionefit/init.py", line 90, in raw_message_callback raise SystemError(statusline) SystemError: HTTP/1.0 400 Bad Request 2023-01-02 20:12:14,755 - ERROR - slixmpp.basexmpp: HTTP/1.0 400 Bad Request Traceback (most recent call last): File "/opt/fhem/.local/lib/python3.9/site-packages/slixmpp/xmlstream/xmlstream.py", line 834, in handler_callback_routine await cb(data) File "/opt/fhem/.local/lib/python3.9/site-packages/aionefit/provider/slixmpp_impl.py", line 69, in message_callback await self.nefit_client.raw_message_callback(msg, 'message') File "/opt/fhem/.local/lib/python3.9/site-packages/aionefit/init.py", line 90, in raw_message_callback raise SystemError(statusline) SystemError: HTTP/1.0 400 Bad Request 2023-01-02 20:12:18,802 - INFO - websockets.server: connection closed 2023-01-02 20:12:18,803 - INFO - websockets.server: server closed 2023-01-02 20:12:18,806 - INFO - fhempy.lib.fhem_pythonbinding: Exit 1 2023-01-02 20:12:20,831 - INFO - fhempy.lib.fhem_pythonbinding: Starting fhempy 0.1.537... 2023-01-02 20:12:20,836 - INFO - fhempy.lib.fhem_pythonbinding: Waiting for FHEM connection 2023-01-02 20:12:20,878 - INFO - websockets.server: server listening on 0.0.0.0:15733 2023-01-02 20:12:29,379 - INFO - websockets.server: connection open 2023-01-02 20:12:29,381 - INFO - fhempy.lib.fhem_pythonbinding: Incoming FHEM connection: 127.0.0.1 2023-01-02 20:12:30,837 - WARNING - slixmpp.stringprep: Using slower stringprep, consider compiling the faster cython/libidn one. 2023-01-02 20:12:34,329 - WARNING - asyncio: Executing <Task pending name='Task-421' coro=<FhemModule._run_coro() running at /opt/fhem/.local/lib/python3.9/site-packages/fhempy/lib/generic.py:196> wait_for=<Future pending cb=[<TaskWakeupMethWrapper object at 0xb47513d0>()] created at /usr/lib/python3.9/asyncio/base_events.py:424> cb=[FhemModule._handle_task_result()] created at /usr/lib/python3.9/asyncio/tasks.py:361> took 0.136 seconds 2023-01-02 20:12:34,667 - WARNING - asyncio: Executing <Task pending name='Task-157' coro=<googlecast.Define() running at /opt/fhem/.local/lib/python3.9/site-packages/fhempy/lib/googlecast/googlecast.py:132> wait_for=<Future pending cb=[<TaskWakeupMethWrapper object at 0xb4446dd8>()] created at /usr/lib/python3.9/asyncio/base_events.py:424> cb=[_release_waiter()() at /usr/lib/python3.9/asyncio/tasks.py:416] created at /usr/lib/python3.9/asyncio/tasks.py:462> took 0.163 seconds 2023-01-02 20:12:35,339 - INFO - slixmpp.features.feature_bind.bind: JID set to: rrccontact_717941259@wa2-mz36-qrmzh6.bosch.de/2gxt79zvpo 2023-01-02 20:12:41,759 - ERROR - aionefit: Errormessage received: HTTP/1.0 400 Bad Request 2023-01-02 20:12:41,759 - ERROR - aionefit: response is null 2023-01-02 20:12:41,762 - ERROR - slixmpp.stanza.rootstanza: Error handling {jabber:client}message stanza Traceback (most recent call last): File "/opt/fhem/.local/lib/python3.9/site-packages/slixmpp/xmlstream/xmlstream.py", line 834, in handler_callback_routine await cb(data) File "/opt/fhem/.local/lib/python3.9/site-packages/aionefit/provider/slixmpp_impl.py", line 69, in message_callback await self.nefit_client.raw_message_callback(msg, 'message') File "/opt/fhem/.local/lib/python3.9/site-packages/aionefit/init.py", line 90, in raw_message_callback raise SystemError(statusline) SystemError: HTTP/1.0 400 Bad Request 2023-01-02 20:12:41,763 - ERROR - slixmpp.basexmpp: HTTP/1.0 400 Bad Request Traceback (most recent call last): File "/opt/fhem/.local/lib/python3.9/site-packages/slixmpp/xmlstream/xmlstream.py", line 834, in handler_callback_routine await cb(data) File "/opt/fhem/.local/lib/python3.9/site-packages/aionefit/provider/slixmpp_impl.py", line 69, in message_callback await self.nefit_client.raw_message_callback(msg, 'message') File "/opt/fhem/.local/lib/python3.9/site-packages/aionefit/init.py", line 90, in raw_message_callback raise SystemError(statusline) SystemError: HTTP/1.0 400 Bad Request 2023-01-02 20:12:47,166 - WARNING - asyncio: Executing <Task pending name='Task-379' coro=<scanner.loop_scan() running at /opt/fhem/.local/lib/python3.9/site-packages/fhempy/lib/ble_presence/ble_presence.py:150> wait_for=<Future pending cb=[<TaskWakeupMethWrapper object at 0xb433ea48>()] created at /usr/lib/python3.9/asyncio/base_events.py:424> created at /usr/lib/python3.9/asyncio/tasks.py:361> took 0.282 seconds 2023-01-02 20:12:50,915 - WARNING - asyncio: Executing <Task pending name='Task-379' coro=<scanner.loop_scan() running at /opt/fhem/.local/lib/python3.9/site-packages/fhempy/lib/ble_presence/ble_presence.py:150> wait_for=<Future pending cb=[<TaskWakeupMethWrapper object at 0xb42195c8>()] created at /usr/lib/python3.9/asyncio/base_events.py:424> created at /usr/lib/python3.9/asyncio/tasks.py:361> took 0.381 seconds 2023-01-02 20:12:53,964 - WARNING - asyncio: Executing <Task pending name='Task-379' coro=<scanner.loop_scan() running at /opt/fhem/.local/lib/python3.9/site-packages/fhempy/lib/ble_presence/ble_presence.py:150> wait_for=<Future pending cb=[_chain_future.._call_check_cancel() at /usr/lib/python3.9/asyncio/futures.py:384, <TaskWakeupMethWrapper object at 0xb431ada8>()] created at /usr/lib/python3.9/asyncio/base_events.py:424> created at /usr/lib/python3.9/asyncio/tasks.py:361> took 0.180 seconds 2023-01-02 20:12:54,566 - WARNING - asyncio: Executing <Task pending name='Task-379' coro=<scanner.loop_scan() running at /opt/fhem/.local/lib/python3.9/site-packages/fhempy/lib/ble_presence/ble_presence.py:150> wait_for=<Future pending cb=[_chain_future.._call_check_cancel() at /usr/lib/python3.9/asyncio/futures.py:384, <TaskWakeupMethWrapper object at 0xb431a8c8>()] created at /usr/lib/python3.9/asyncio/base_events.py:424> created at /usr/lib/python3.9/asyncio/tasks.py:361> took 0.189 seconds 2023-01-02 20:12:54,915 - WARNING - asyncio: Executing <Task pending name='Task-379' coro=<scanner.loop_scan() running at /opt/fhem/.local/lib/python3.9/site-packages/fhempy/lib/ble_presence/ble_presence.py:150> wait_for=<Future pending cb=[_chain_future.._call_check_cancel() at /usr/lib/python3.9/asyncio/futures.py:384, <TaskWakeupMethWrapper object at 0xb41d10e8>()] created at /usr/lib/python3.9/asyncio/base_events.py:424> created at /usr/lib/python3.9/asyncio/tasks.py:361> took 0.179 seconds 2023-01-02 20:12:55,616 - WARNING - asyncio: Executing <Task pending name='Task-379' coro=<scanner.loop_scan() running at /opt/fhem/.local/lib/python3.9/site-packages/fhempy/lib/ble_presence/ble_presence.py:150> wait_for=<Future pending cb=[_chain_future.._call_check_cancel() at /usr/lib/python3.9/asyncio/futures.py:384, <TaskWakeupMethWrapper object at 0xb41d13a0>()] created at /usr/lib/python3.9/asyncio/base_events.py:424> created at /usr/lib/python3.9/asyncio/tasks.py:361> took 0.192 seconds 2023-01-02 20:16:25,751 - INFO - fhempy.lib.fhem_pythonbinding: Starting fhempy 0.1.537... 2023-01-02 20:16:25,758 - INFO - fhempy.lib.fhem_pythonbinding: Waiting for FHEM connection 2023-01-02 20:16:25,818 - INFO - websockets.server: server listening on 0.0.0.0:15733 2023-01-02 20:16:45,521 - INFO - websockets.server: connection open 2023-01-02 20:16:45,522 - INFO - fhempy.lib.fhem_pythonbinding: Incoming FHEM connection: 127.0.0.1 2023-01-02 20:16:48,297 - WARNING - slixmpp.stringprep: Using slower stringprep, consider compiling the faster cython/libidn one. 2023-01-02 20:16:58,160 - INFO - slixmpp.features.feature_bind.bind: JID set to: rrccontact_717941259@wa2-mz36-qrmzh6.bosch.de/6mq7si7op 2023-01-02 20:17:04,449 - ERROR - aionefit: Errormessage received: HTTP/1.0 400 Bad Request 2023-01-02 20:17:04,450 - ERROR - aionefit: response is null 2023-01-02 20:17:04,452 - ERROR - slixmpp.stanza.rootstanza: Error handling {jabber:client}message stanza Traceback (most recent call last): File "/opt/fhem/.local/lib/python3.9/site-packages/slixmpp/xmlstream/xmlstream.py", line 834, in handler_callback_routine await cb(data) File "/opt/fhem/.local/lib/python3.9/site-packages/aionefit/provider/slixmpp_impl.py", line 69, in message_callback await self.nefit_client.raw_message_callback(msg, 'message') File "/opt/fhem/.local/lib/python3.9/site-packages/aionefit/init.py", line 90, in raw_message_callback raise SystemError(statusline) SystemError: HTTP/1.0 400 Bad Request 2023-01-02 20:17:04,452 - ERROR - slixmpp.basexmpp: HTTP/1.0 400 Bad Request Traceback (most recent call last): File "/opt/fhem/.local/lib/python3.9/site-packages/slixmpp/xmlstream/xmlstream.py", line 834, in handler_callback_routine await cb(data) File "/opt/fhem/.local/lib/python3.9/site-packages/aionefit/provider/slixmpp_impl.py", line 69, in message_callback await self.nefit_client.raw_message_callback(msg, 'message') File "/opt/fhem/.local/lib/python3.9/site-packages/aionefit/init.py", line 90, in raw_message_callback raise SystemError(statusline) SystemError: HTTP/1.0 400 Bad Request 2023-01-02 20:17:13,868 - WARNING - asyncio: Executing <Task pending name='Task-406' coro=<scanner.loop_scan() running at /opt/fhem/.local/lib/python3.9/site-packages/fhempy/lib/ble_presence/ble_presence.py:150> wait_for=<Future pending cb=[<TaskWakeupMethWrapper object at 0xb42e45b0>()] created at /usr/lib/python3.9/asyncio/base_events.py:424> created at /usr/lib/python3.9/asyncio/tasks.py:361> took 0.266 seconds 2023-01-02 20:17:17,470 - WARNING - asyncio: Executing <Task pending name='Task-406' coro=<scanner.loop_scan() running at /opt/fhem/.local/lib/python3.9/site-packages/fhempy/lib/ble_presence/ble_presence.py:150> wait_for=<Future pending cb=[_chain_future.._call_check_cancel() at /usr/lib/python3.9/asyncio/futures.py:384, <TaskWakeupMethWrapper object at 0xb414f3e8>()] created at /usr/lib/python3.9/asyncio/base_events.py:424> created at /usr/lib/python3.9/asyncio/tasks.py:361> took 0.158 seconds 2023-01-02 20:17:19,076 - WARNING - asyncio: Executing <Task pending name='Task-406' coro=<scanner.loop_scan() running at /opt/fhem/.local/lib/python3.9/site-packages/fhempy/lib/ble_presence/ble_presence.py:150> wait_for=<Future pending cb=[_chain_future.._call_check_cancel() at /usr/lib/python3.9/asyncio/futures.py:384, <TaskWakeupMethWrapper object at 0xb414f430>()] created at /usr/lib/python3.9/asyncio/base_events.py:424> created at /usr/lib/python3.9/asyncio/tasks.py:361> took 0.179 seconds 2023-01-02 20:17:19,719 - WARNING - asyncio: Executing <Task pending name='Task-406' coro=<scanner.loop_scan() running at /opt/fhem/.local/lib/python3.9/site-packages/fhempy/lib/ble_presence/ble_presence.py:150> wait_for=<Future pending cb=[_chain_future.._call_check_cancel() at /usr/lib/python3.9/asyncio/futures.py:384, <TaskWakeupMethWrapper object at 0xb4195f40>()] created at /usr/lib/python3.9/asyncio/base_events.py:424> created at /usr/lib/python3.9/asyncio/tasks.py:361> took 0.235 seconds 2023-01-02 20:17:21,619 - WARNING - asyncio: Executing <Task pending name='Task-406' coro=<scanner.loop_scan() running at /opt/fhem/.local/lib/python3.9/site-packages/fhempy/lib/ble_presence/ble_presence.py:150> wait_for=<Future pending cb=[_chain_future.._call_check_cancel() at /usr/lib/python3.9/asyncio/futures.py:384, <TaskWakeupMethWrapper object at 0xb42e4820>()] created at /usr/lib/python3.9/asyncio/base_events.py:424> created at /usr/lib/python3.9/asyncio/tasks.py:361> took 0.185 seconds 2023-01-02 20:17:26,518 - WARNING - asyncio: Executing <Task pending name='Task-406' coro=<scanner.loop_scan() running at /opt/fhem/.local/lib/python3.9/site-packages/fhempy/lib/ble_presence/ble_presence.py:150> wait_for=<Future pending cb=[<TaskWakeupMethWrapper object at 0xb4195bf8>()] created at /usr/lib/python3.9/asyncio/base_events.py:424> created at /usr/lib/python3.9/asyncio/tasks.py:361> took 0.255 seconds`

[fhempy]

In dem Fall bitte das FHEM Log statt fhempy.

[DB1113]

Hi, sorry hat ein wenig gedauert... Hier der fhem-Log: `2023.01.02 20:15:35 0: Server shutdown 2023.01.02 20:15:35 2: DbLog DBLog - stopping SubProcess PID >665< ... 2023.01.02 20:15:35 2: DbLog DBLog - SubProcess PID >665< stopped 2023.01.02 20:15:38 1: Including fhem.cfg 2023.01.02 20:15:40 2: DbLog DBLog - Subprocess >1389< initialized ... ready for non-blocking operation 2023.01.02 20:15:40 3: WEB: port 8083 opened 2023.01.02 20:15:41 2: eventTypes: loaded 26617 lines from ./log/eventTypes.txt 2023.01.02 20:15:41 3: Opening TCM_ESP3_0 device /dev/serial/by-id/usb-FTDI_FT232R_USB_UART_A600I1I3-if00-port0 2023.01.02 20:15:41 3: Setting TCM_ESP3_0 serial parameters to 57600,8,N,1 2023.01.02 20:15:41 3: TCM_ESP3_0 device opened 2023.01.02 20:15:42 2: EnOcean Cryptographic functions available. 2023.01.02 20:15:42 2: EnOcean XML functions available. 2023.01.02 20:15:45 3: Opening myJeeLink device /dev/serial/by-id/usb-FTDI_FT232R_USB_UART_AL01MZ4J-if00-port0 2023.01.02 20:15:45 3: Setting myJeeLink serial parameters to 57600,8,N,1 2023.01.02 20:15:46 3: myJeeLink device opened 2023.01.02 20:15:46 3: Temp_schlazi: I/O device is myJeeLink 2023.01.02 20:15:46 3: Temp_Gefrierfach: I/O device is myJeeLink 2023.01.02 20:15:47 1: PERL WARNING: Use of uninitialized value $regdev in pattern match (m//) at ./FHEM/98_DOIF.pm line 2761. 2023.01.02 20:15:47 1: PERL WARNING: Use of uninitialized value $regdev in concatenation (.) or string at ./FHEM/98_DOIF.pm line 2764. 2023.01.02 20:15:47 1: PERL WARNING: Use of uninitialized value $regdev in pattern match (m//) at ./FHEM/98_DOIF.pm line 2761, <$fh> line 1948. 2023.01.02 20:15:47 1: PERL WARNING: Use of uninitialized value $regdev in concatenation (.) or string at ./FHEM/98_DOIF.pm line 2764, <$fh> line 1948. 2023.01.02 20:15:47 1: PERL WARNING: Use of uninitialized value $regdev in pattern match (m//) at ./FHEM/98_DOIF.pm line 2761, <$fh> line 1949. 2023.01.02 20:15:47 1: PERL WARNING: Use of uninitialized value $regdev in concatenation (.) or string at ./FHEM/98_DOIF.pm line 2764, <$fh> line 1949. 2023.01.02 20:15:47 1: PERL WARNING: Use of uninitialized value $regdev in pattern match (m//) at ./FHEM/98_DOIF.pm line 2761, <$fh> line 1950. 2023.01.02 20:15:47 1: PERL WARNING: Use of uninitialized value $regdev in concatenation (.) or string at ./FHEM/98_DOIF.pm line 2764, <$fh> line 1950. 2023.01.02 20:15:47 1: PERL WARNING: Use of uninitialized value $regdev in pattern match (m//) at ./FHEM/98_DOIF.pm line 2761, <$fh> line 1951. 2023.01.02 20:15:47 1: PERL WARNING: Use of uninitialized value $regdev in concatenation (.) or string at ./FHEM/98_DOIF.pm line 2764, <$fh> line 1951. 2023.01.02 20:15:47 3: Temp_Kuehlfach: I/O device is myJeeLink 2023.01.02 20:15:47 3: TABLETUI: new ext defined infix:ftui/: dir:./www/tablet/: 2023.01.02 20:15:47 3: Registering HTTPSRV TABLETUI for URL /ftui and assigned link ftui/ ... 2023.01.02 20:15:47 3: Temp_kueche: I/O device is myJeeLink 2023.01.02 20:15:47 3: Temp_wozi: I/O device is myJeeLink 2023.01.02 20:15:47 3: Temp_bad: I/O device is myJeeLink 2023.01.02 20:15:47 3: Temp_kizi2: I/O device is myJeeLink 2023.01.02 20:15:48 3: AMADCommBridge (AMADBridge) - defined AMADCommBridge with Socketport 8090 2023.01.02 20:15:48 3: AMADBridge: port 8090 opened 2023.01.02 20:15:48 3: AMADCommBridge (AMADBridge) - Socket opened. 2023.01.02 20:15:48 3: Temp_kizi1: I/O device is myJeeLink 2023.01.02 20:15:48 3: Temp_azi: I/O device is myJeeLink 2023.01.02 20:15:48 3: Temp_aussen: I/O device is myJeeLink 2023.01.02 20:15:48 3: Opening FritzBox_CallMonitor device fritz.box:1012 2023.01.02 20:15:48 3: Temp_flur: I/O device is myJeeLink 2023.01.02 20:15:48 3: Therme: Defined with URL http://127.0.0.1/junkers.php and interval 300 featurelevel 6.1 2023.01.02 20:15:49 3: TPLinkHS110: wozi_Stckd_Subwoofer defined. 2023.01.02 20:15:49 3: TPLinkHS110: wozi_Stckd_Subwoofer INTERVAL set to 86400 2023.01.02 20:15:49 3: GHome: port 8096 opened 2023.01.02 20:15:49 3: wozi_Bel_Stehleuchte: I/O device is HUEBridge 2023.01.02 20:15:49 3: wozi_Ambilight_farbe: I/O device is HUEBridge 2023.01.02 20:15:49 3: wozi_Ambilight_weiss: I/O device is HUEBridge 2023.01.02 20:15:49 3: HUEGroup1: I/O device is HUEBridge 2023.01.02 20:15:49 3: ku_LED_auf_Schrank_weiss: I/O device is HUEBridge 2023.01.02 20:15:49 3: ku_LED_auf_Schrank_color: I/O device is HUEBridge 2023.01.02 20:15:49 3: HUEGroup2: I/O device is HUEBridge 2023.01.02 20:15:49 3: TPLinkHS110: flur_Steckd_Tablet defined. 2023.01.02 20:15:49 3: TPLinkHS110: flur_Steckd_Tablet INTERVAL set to 86400 2023.01.02 20:15:49 3: TPLinkHS110: azi_Stckd_PC_alt defined. 2023.01.02 20:15:49 3: TPLinkHS110: azi_Stckd_PC_alt INTERVAL set to 86400 2023.01.02 20:15:49 3: wozi_Bel_grosserStern: I/O device is HUEBridge 2023.01.02 20:15:50 3: defmod -temporary gassistantFHEMlog FileLog ./log/gassistant-%Y-%m-%d.log fakelog : Define -temporary first 2023.01.02 20:15:50 1: FULLY: [Fully_TabletFlur] Version 2.3 Opening device 192.168.178.17 2023.01.02 20:15:50 2: FULLY: [Fully_TabletFlur] Device activated 2023.01.02 20:15:50 3: FUIP: Registering ui for URL /ui 2023.01.02 20:15:50 2: ONKYO_AVR wozi_PIONEER_AVR: Registering ONKYO_AVR for webhook URI ?/ONKYO_AVR ... 2023.01.02 20:15:50 3: Opening wozi_PIONEER_AVR device 192.168.178.38:60128 2023.01.02 20:15:51 3: Temp_hwr: I/O device is myJeeLink 2023.01.02 20:15:51 3: Temp_wc: I/O device is myJeeLink 2023.01.02 20:15:51 3: Temp_Dachboden: I/O device is myJeeLink 2023.01.02 20:15:51 3: TPLinkHS110: weihnacht_Steckd_1 defined. 2023.01.02 20:15:51 3: TPLinkHS110: weihnacht_Steckd_1 INTERVAL set to 86400 2023.01.02 20:15:51 3: TPLinkHS110: weihnacht_Steckd_2 defined. 2023.01.02 20:15:51 3: TPLinkHS110: weihnacht_Steckd_2 INTERVAL set to 86400 2023.01.02 20:15:51 3: TPLinkHS110: weihnacht_Steckd_3 defined. 2023.01.02 20:15:51 3: TPLinkHS110: weihnacht_Steckd_3 INTERVAL set to 86400 2023.01.02 20:15:51 3: ku_Bel_Esstisch_Leuchte: I/O device is HUEBridge 2023.01.02 20:15:51 3: HUEGroup5: I/O device is HUEBridge 2023.01.02 20:15:51 3: HUEGroup8: I/O device is HUEBridge 2023.01.02 20:15:51 3: HUEGroup0: I/O device is HUEBridge 2023.01.02 20:15:51 3: HUEGroup3: I/O device is HUEBridge 2023.01.02 20:15:51 3: ku_LED_auf_Schrank_2_color: I/O device is HUEBridge 2023.01.02 20:15:51 3: ku_LED_auf_Schrank_2_weiss: I/O device is HUEBridge 2023.01.02 20:15:51 3: HUEGroup4: I/O device is HUEBridge 2023.01.02 20:15:51 3: HUEGroup6: I/O device is HUEBridge 2023.01.02 20:15:51 2: wozi_TV: defined with host: 192.168.178.35 interval: 30 PIN: T1480 2023.01.02 20:15:51 3: GardenaSmartBridge (Gardena_Bridge) - defined GardenaSmartBridge 2023.01.02 20:15:52 3: GardenaSmartDevice (Sensor_alt) - I/O device is Gardena_Bridge 2023.01.02 20:15:52 3: GardenaSmartDevice (Sensor_alt) - defined GardenaSmartDevice with DEVICEID: 57083794-b9f2-4f16-b8ea-b4f80ac066b3 2023.01.02 20:15:52 3: GardenaSmartDevice (Water_Control) - I/O device is Gardena_Bridge 2023.01.02 20:15:52 3: GardenaSmartDevice (Water_Control) - defined GardenaSmartDevice with DEVICEID: 91417fcf-1a3d-4e04-b0bc-16f56b9d39c3 2023.01.02 20:15:52 3: AMADDevice (TabletFlur) - I/O device is AMADBridge 2023.01.02 20:15:52 3: AMADDevice (TabletFlur) - set remoteServer to Automagic 2023.01.02 20:15:52 3: AMADDevice (TabletFlur) - defined with AMAD_ID: 1607699544716 on port 8090 2023.01.02 20:15:52 3: AMADDevice (TabletFlur) - set remoteServer to Automagic 2023.01.02 20:15:52 2: Signalbot Define: Signalbot Signalbot 2023.01.02 20:15:53 3: fhempy v1.0.0 (googlecast) 2023.01.02 20:15:53 3: fhempy v1.0.0 (googlecast) 2023.01.02 20:15:53 3: fhempy v1.0.0 (googlecast) 2023.01.02 20:15:53 3: fhempy v1.0.0 (googlecast) 2023.01.02 20:15:53 3: fhempy v1.0.0 (googlecast) 2023.01.02 20:15:53 3: fhempy v1.0.0 (googlecast) 2023.01.02 20:15:53 3: fhempy v1.0.0 (googlecast) 2023.01.02 20:15:53 3: fhempy v1.0.0 (googlecast) 2023.01.02 20:15:53 3: fhempy v1.0.0 (googlecast) 2023.01.02 20:15:53 3: Opening Stromzaehler device /dev/serial/by-id/usb-FTDI_FT230X_Basic_UART_D30A13JC-if00-port0 2023.01.02 20:15:53 3: Setting Stromzaehler serial parameters to 9600,8,N,1 2023.01.02 20:15:53 3: OBIS (Stromzaehler) - Init done 2023.01.02 20:15:53 3: Stromzaehler device opened 2023.01.02 20:15:53 3: OBIS (Stromzaehler) - Attr interval Val 5, dopoll = 2023.01.02 20:15:53 3: OBIS (Stromzaehler) - Attr pollingMode Val on, dopoll = 1 2023.01.02 20:15:53 3: BindingsIo v1.0.1 2023.01.02 20:15:53 3: found IP 127.0.0.1 2023.01.02 20:15:53 3: fhempyServer v1.0.0 2023.01.02 20:15:53 3: defmod -temporary fhempy_log FileLog ./log/fhempy-%Y-%m-%d.log fakelog : Define -temporary first 2023.01.02 20:15:53 3: fhempy v1.0.0 (googlecast) 2023.01.02 20:15:53 3: fhempy v1.0.0 (ble_presence) 2023.01.02 20:15:53 3: fhempy v1.0.0 (ble_presence) 2023.01.02 20:15:53 3: fhempy v1.0.0 (ble_presence) 2023.01.02 20:15:53 3: fhempy v1.0.0 (ble_reset) 2023.01.02 20:15:54 3: fhempy v1.0.0 (nefit) 2023.01.02 20:15:54 3: HUESensor1: I/O device is HUEBridge 2023.01.02 20:15:54 3: wozi_hue_Stehlampe_dimmer: I/O device is HUEBridge 2023.01.02 20:15:54 3: schlazi_hue_dimmer: I/O device is HUEBridge 2023.01.02 20:15:54 3: schlazi_Bel_Deckenlicht: I/O device is HUEBridge 2023.01.02 20:15:54 3: HUEGroup7: I/O device is HUEBridge 2023.01.02 20:15:54 3: schlazi_Bel_Schalter: I/O device is HUEBridge 2023.01.02 20:15:54 3: HUEGroup11: I/O device is HUEBridge 2023.01.02 20:15:54 3: HUEGroup10: I/O device is HUEBridge 2023.01.02 20:15:54 3: Temp_Garage: I/O device is myJeeLink 2023.01.02 20:15:54 3: FhemNative: port 8080 opened 2023.01.02 20:15:54 3: GardenaSmartDevice (Sensor) - I/O device is Gardena_Bridge 2023.01.02 20:15:54 3: GardenaSmartDevice (Sensor) - defined GardenaSmartDevice with DEVICEID: 1dad0a57-8fe3-450e-a564-534ac5f3d1e7 2023.01.02 20:15:54 3: fhempy v1.0.0 (googlecast) 2023.01.02 20:15:54 3: fhempy v1.0.0 (googlecast) 2023.01.02 20:15:54 3: fhempy v1.0.0 (googlecast) 2023.01.02 20:15:55 1: [Shelly_Attr] setting the mode attribute for device ku_Aktor_Radio_Sw only works for model=shelly2|shelly2.5|shellyrgbw|shellybulb 2023.01.02 20:15:55 3: Temp_Speicher: I/O device is myJeeLink 2023.01.02 20:15:55 3: fhempy v1.0.0 (arp_presence) 2023.01.02 20:15:55 3: fhempy v1.0.0 (arp_presence) 2023.01.02 20:15:55 3: fhempy v1.0.0 (google_weather) 2023.01.02 20:15:55 3: fhempy v1.0.0 (arp_presence) 2023.01.02 20:15:55 1: Including ./log/fhem.save 2023.01.02 20:15:56 1: Messages collected while initializing FHEM:configfile: fhempy_log already defined, delete it first

Autosave deactivated 2023.01.02 20:15:56 3: TCM TCM_ESP3_0 set reset 2023.01.02 20:15:57 3: TCM TCM_ESP3_0 get baseID 2023.01.02 20:15:57 2: TCM TCM_ESP3_0 RESPONSE: BaseID: FFC17600 RemainingWriteCycles: 09 2023.01.02 20:15:57 3: TCM TCM_ESP3_0 get version 2023.01.02 20:15:57 2: TCM TCM_ESP3_0 RESPONSE: APIVersion: 02060300 APPVersion: 020B0100 ChipID: 050EB15F ChipVersion: 454F0103 Desc: GATEWAYCTRL 2023.01.02 20:15:57 3: TCM TCM_ESP3_0 set smartAckMailboxMax 11 2023.01.02 20:15:57 2: TCM TCM_ESP3_0 smartAckMailboxMax 11 restored 2023.01.02 20:15:57 3: TCM TCM_ESP3_0 set repeater 0000 2023.01.02 20:15:57 2: TCM TCM_ESP3_0 repeater 0000 restored 2023.01.02 20:15:57 3: TCM TCM_ESP3_0 set maturity 01 2023.01.02 20:15:57 2: TCM TCM_ESP3_0 maturity 01 restored 2023.01.02 20:15:57 3: TCM TCM_ESP3_0 set mode 00 2023.01.02 20:15:57 2: TCM TCM_ESP3_0 RESPONSE: NOT_SUPPORTED 2023.01.02 20:15:57 2: TCM TCM_ESP3_0 mode 00 restored 2023.01.02 20:15:57 2: TCM TCM_ESP3_0 initialized 2023.01.02 20:15:57 2: TCM registered transceiver BaseID: FFC17600 ChipID: 050EB15F 2023.01.02 20:15:57 3: Opening myHmUART device /dev/serial/by-id/usb-Silicon_Labs_CP2104_USB_to_UART_Bridge_Controller_01235C72-if00-port0 2023.01.02 20:15:57 3: Setting myHmUART serial parameters to 115200,8,N,1 2023.01.02 20:15:57 3: myHmUART device opened 2023.01.02 20:16:02 3: FB_CALLMONITOR (FritzBox_CallMonitor) - unable to login via TR-064, wrong user/password 2023.01.02 20:16:02 2: FB_CALLMONITOR (FritzBox_CallMonitor) - could not identify remote phonebooks - unable to identify phonebooks via TR-064: unable to login via TR-064, wrong user/password 2023.01.02 20:16:02 2: HUEBridge: autocreate: created 0/0/0 devices (ignored 0/0/2) 2023.01.02 20:16:03 1: PERL WARNING: Use of uninitialized value in concatenation (.) or string at ./FHEM/00_RPII2C.pm line 256. 2023.01.02 20:16:24 3: fhempyserver_15733: starting 2023.01.02 20:16:24 3: fhempyserver_15733: using logfile: ./log/fhempy-2023-01-02.log 2023.01.02 20:16:24 2: gassistant: starting gassistant-fhem: /usr/bin/gassistant-fhem -c ./gassistant-fhem.cfg 2023.01.02 20:16:24 3: gassistant: starting 2023.01.02 20:16:24 3: gassistant: using logfile: ./log/gassistant-2023-01-02.log 2023.01.02 20:16:25 3: NTFY return: sysmon:Initialized 2023.01.02 20:16:25 0: Featurelevel: 6.1 2023.01.02 20:16:25 0: Server started with 734 defined entities (fhem.pl:26868/2022-12-18 perl:5.032001 os:linux user:fhem pid:1388) 2023.01.02 20:16:26 3: DbLog DBLog - DB connection parameters are initialized in the SubProcess 2023.01.02 20:16:27 3: DbLog DBLog - DB connection parameters are stored in SubProcess 2023.01.02 20:16:28 3: telnetForBlockingFn_1672686988.64563: port 36607 opened 2023.01.02 20:16:29 2: di_FritzBox_Ansteuerung: set scenes_FritzBox scene Feierabend: 'set ... diversity' is not supported by the limited interfaces of your Fritz!Box firmware. 2023.01.02 20:16:30 1: TPLinkHS110: azi_Stckd_PC_alt Get failed - Couldn't connect to 192.168.178.86:9999: IO::Socket::INET: connect: timeout

2023.01.02 20:16:32 1: PERL WARNING: Use of uninitialized value in string eq at ./FHEM/70_VIERA.pm line 473. 2023.01.02 20:16:32 3: Opening myJeeLink device /dev/serial/by-id/usb-FTDI_FT232R_USB_UART_AL01MZ4J-if00-port0 2023.01.02 20:16:32 3: Setting myJeeLink serial parameters to 57600,8,N,1 2023.01.02 20:16:33 3: myJeeLink device opened 2023.01.02 20:16:41 2: HUEBridge: http request failed: SSL connect attempt failed 2023.01.02 20:16:41 2: HUEBridge: EventStream: terminated; retrying later 2023.01.02 20:16:41 1: PERL WARNING: Use of uninitialized value $data in concatenation (.) or string at ./FHEM/73_GardenaSmartBridge.pm line 511. 2023.01.02 20:16:41 1: PERL WARNING: Use of uninitialized value $data in numeric gt (>) at ./FHEM/73_GardenaSmartBridge.pm line 513. 2023.01.02 20:16:41 3: FritzBox_CallMonitor device opened 2023.01.02 20:16:41 2: HUEBridge: http request failed: SSL connect attempt failed 2023.01.02 20:16:42 3: Opening pyBinding device ws:localhost:15733 2023.01.02 20:16:42 3: SSCam.Garage - Polling-Log of Garage is deactivated 2023.01.02 20:16:42 3: SSCam.Garage - Pollinginterval of Garage has been changed to: 210 s 2023.01.02 20:16:44 3: SSCam.Terrasse_2 - Polling-Log of Terrasse_2 is deactivated 2023.01.02 20:16:44 3: SSCam.Terrasse_2 - Pollinginterval of Terrasse_2 has been changed to: 210 s 2023.01.02 20:16:44 1: Timeout for VIERA_GetDoIt reached, terminated process 1475 2023.01.02 20:16:44 2: wozi_TV [NonBlocking-VIERA_GetAbortFn()]: BlockingCall for wozi_TV was aborted, timeout reached 2023.01.02 20:16:44 3: BlockingCall for WOL_NAS_DS416play was aborted 2023.01.02 20:16:45 2: SSCam.Flur - error while requesting http://192.168.178.25:5000/webapi/query.cgi?api=SYNO.API.Info&method=Query&version=1&query=SYNO.API.Auth,SYNO.SurveillanceStation.Event,SYNO.SurveillanceStation.VideoStream,SYNO.SurveillanceStation.Camera.Event,SYNO.SurveillanceStation.ExternalRecording,SYNO.SurveillanceStation.PTZ.Preset,SYNO.SurveillanceStation.Recording,SYNO.SurveillanceStation.Stream,SYNO.SurveillanceStation.SnapShot,SYNO.SurveillanceStation.Log,SYNO.SurveillanceStation.AudioStream,SYNO.SurveillanceStation.PTZ,SYNO.SurveillanceStation.HomeMode,SYNO.SurveillanceStation.Info,SYNO.SurveillanceStation.VideoStreaming,SYNO.SurveillanceStation.Camera,SYNO.SurveillanceStation.ExternalEvent,SYNO.API.Info - http://192.168.178.25:5000/webapi/query.cgi?api=SYNO.API.Info&method=Query&version=1&query=SYNO.API.Auth,SYNO.SurveillanceStation.Event,SYNO.SurveillanceStation.VideoStream,SYNO.SurveillanceStation.Camera.Event,SYNO.SurveillanceStation.ExternalRecording,SYNO.SurveillanceStation.PTZ.Preset,SYNO.SurveillanceStation.Recording,SYNO.SurveillanceStation.Stream,SYNO.SurveillanceStation.SnapShot,SYNO.SurveillanceStation.Log,SYNO.SurveillanceStation.AudioStream,SYNO.SurveillanceStation.PTZ,SYNO.SurveillanceStation.HomeMode,SYNO.SurveillanceStation.Info,SYNO.SurveillanceStation.VideoStreaming,SYNO.SurveillanceStation.Camera,SYNO.SurveillanceStation.ExternalEvent,SYNO.API.Info: empty answer received 2023.01.02 20:16:45 2: SSCam.Dachboden - error while requesting http://192.168.178.25:5000/webapi/query.cgi?api=SYNO.API.Info&method=Query&version=1&query=SYNO.SurveillanceStation.Stream,SYNO.SurveillanceStation.PTZ.Preset,SYNO.SurveillanceStation.Recording,SYNO.SurveillanceStation.Log,SYNO.SurveillanceStation.SnapShot,SYNO.SurveillanceStation.AudioStream,SYNO.API.Auth,SYNO.SurveillanceStation.Camera.Event,SYNO.SurveillanceStation.VideoStream,SYNO.SurveillanceStation.Event,SYNO.SurveillanceStation.ExternalRecording,SYNO.SurveillanceStation.ExternalEvent,SYNO.API.Info,SYNO.SurveillanceStation.PTZ,SYNO.SurveillanceStation.VideoStreaming,SYNO.SurveillanceStation.Info,SYNO.SurveillanceStation.HomeMode,SYNO.SurveillanceStation.Camera - http://192.168.178.25:5000/webapi/query.cgi?api=SYNO.API.Info&method=Query&version=1&query=SYNO.SurveillanceStation.Stream,SYNO.SurveillanceStation.PTZ.Preset,SYNO.SurveillanceStation.Recording,SYNO.SurveillanceStation.Log,SYNO.SurveillanceStation.SnapShot,SYNO.SurveillanceStation.AudioStream,SYNO.API.Auth,SYNO.SurveillanceStation.Camera.Event,SYNO.SurveillanceStation.VideoStream,SYNO.SurveillanceStation.Event,SYNO.SurveillanceStation.ExternalRecording,SYNO.SurveillanceStation.ExternalEvent,SYNO.API.Info,SYNO.SurveillanceStation.PTZ,SYNO.SurveillanceStation.VideoStreaming,SYNO.SurveillanceStation.Info,SYNO.SurveillanceStation.HomeMode,SYNO.SurveillanceStation.Camera: empty answer received 2023.01.02 20:16:45 2: SSCam.Terrasse_2 - error while requesting http://192.168.178.25:5000/webapi/query.cgi?api=SYNO.API.Info&method=Query&version=1&query=SYNO.SurveillanceStation.Camera,SYNO.SurveillanceStation.VideoStreaming,SYNO.SurveillanceStation.Info,SYNO.SurveillanceStation.HomeMode,SYNO.SurveillanceStation.PTZ,SYNO.API.Info,SYNO.SurveillanceStation.ExternalEvent,SYNO.SurveillanceStation.ExternalRecording,SYNO.SurveillanceStation.VideoStream,SYNO.SurveillanceStation.Camera.Event,SYNO.SurveillanceStation.Event,SYNO.API.Auth,SYNO.SurveillanceStation.AudioStream,SYNO.SurveillanceStation.Log,SYNO.SurveillanceStation.SnapShot,SYNO.SurveillanceStation.Stream,SYNO.SurveillanceStation.PTZ.Preset,SYNO.SurveillanceStation.Recording - http://192.168.178.25:5000/webapi/query.cgi?api=SYNO.API.Info&method=Query&version=1&query=SYNO.SurveillanceStation.Camera,SYNO.SurveillanceStation.VideoStreaming,SYNO.SurveillanceStation.Info,SYNO.SurveillanceStation.HomeMode,SYNO.SurveillanceStation.PTZ,SYNO.API.Info,SYNO.SurveillanceStation.ExternalEvent,SYNO.SurveillanceStation.ExternalRecording,SYNO.SurveillanceStation.VideoStream,SYNO.SurveillanceStation.Camera.Event,SYNO.SurveillanceStation.Event,SYNO.API.Auth,SYNO.SurveillanceStation.AudioStream,SYNO.SurveillanceStation.Log,SYNO.SurveillanceStation.SnapShot,SYNO.SurveillanceStation.Stream,SYNO.SurveillanceStation.PTZ.Preset,SYNO.SurveillanceStation.Recording: empty answer received 2023.01.02 20:16:45 2: HUEBridge: http request failed: http://192.168.178.22/api/E405TUTLbnnLGjKfmGtP03b4n1emFvPnwcgvicWj: empty answer received 2023.01.02 20:16:45 2: SSCam.Garage - error while requesting http://192.168.178.25:5000/webapi/query.cgi?api=SYNO.API.Info&method=Query&version=1&query=SYNO.SurveillanceStation.PTZ,SYNO.SurveillanceStation.VideoStreaming,SYNO.SurveillanceStation.Info,SYNO.SurveillanceStation.HomeMode,SYNO.SurveillanceStation.Camera,SYNO.SurveillanceStation.ExternalEvent,SYNO.API.Info,SYNO.API.Auth,SYNO.SurveillanceStation.VideoStream,SYNO.SurveillanceStation.Camera.Event,SYNO.SurveillanceStation.Event,SYNO.SurveillanceStation.ExternalRecording,SYNO.SurveillanceStation.Stream,SYNO.SurveillanceStation.PTZ.Preset,SYNO.SurveillanceStation.Recording,SYNO.SurveillanceStation.Log,SYNO.SurveillanceStation.SnapShot,SYNO.SurveillanceStation.AudioStream - http://192.168.178.25:5000/webapi/query.cgi?api=SYNO.API.Info&method=Query&version=1&query=SYNO.SurveillanceStation.PTZ,SYNO.SurveillanceStation.VideoStreaming,SYNO.SurveillanceStation.Info,SYNO.SurveillanceStation.HomeMode,SYNO.SurveillanceStation.Camera,SYNO.SurveillanceStation.ExternalEvent,SYNO.API.Info,SYNO.API.Auth,SYNO.SurveillanceStation.VideoStream,SYNO.SurveillanceStation.Camera.Event,SYNO.SurveillanceStation.Event,SYNO.SurveillanceStation.ExternalRecording,SYNO.SurveillanceStation.Stream,SYNO.SurveillanceStation.PTZ.Preset,SYNO.SurveillanceStation.Recording,SYNO.SurveillanceStation.Log,SYNO.SurveillanceStation.SnapShot,SYNO.SurveillanceStation.AudioStream: empty answer received 2023.01.02 20:16:46 1: [Shelly_configure] invalid JSON data for device ku_Aktor_Radio_Sw 2023.01.02 20:16:46 1: [Shelly_proc1G] invalid JSON data for device ku_Bel_Decke 2023.01.02 20:16:46 1: [Shelly_configure] invalid JSON data for device azi_Stckd_PC 2023.01.02 20:16:46 2: AttrTemplates: got 267 entries 2023.01.02 20:16:46 1: [Shelly_proc1G] invalid JSON data for device garage_Steckd_Speicherheizung 2023.01.02 20:16:47 1: [Shelly_configure] invalid JSON data for device garage_Steckd_Speicherheizung 2023.01.02 20:16:48 3: pyBinding device opened 2023.01.02 20:16:48 1: [Shelly_proc1G] invalid JSON data for device ku_Aktor_Radio_Sw 2023.01.02 20:16:48 1: [Shelly_configure] invalid JSON data for device ku_Bel_Decke 2023.01.02 20:16:48 1: [Shelly_proc1G] invalid JSON data for device azi_Stckd_PC 2023.01.02 20:16:48 3: SSCam.Terrasse_1 - no session ID found - get new one 2023.01.02 20:16:48 3: SSCam.Flur - Polling-Log of Flur is deactivated 2023.01.02 20:16:48 3: SSCam.Flur - Pollinginterval of Flur has been changed to: 210 s 2023.01.02 20:16:48 1: PERL WARNING: Use of uninitialized value in concatenation (.) or string at ./FHEM/70_ONKYO_AVR.pm line 2006. 2023.01.02 20:16:49 3: SSCam.Garage - no session ID found - get new one 2023.01.02 20:16:54 3: SSCam.Flur - no session ID found - get new one 2023.01.02 20:16:54 3: SSCam.Dachboden - no session ID found - get new one 2023.01.02 20:16:54 3: SSCam.Terrasse_2 - no session ID found - get new one 2023.01.02 20:16:54 1: PERL WARNING: Use of uninitialized value $value in string eq at fhem.pl line 5011. 2023.01.02 20:16:57 3: DbLog DBLog - SubProcess connected to /opt/fhem_db/fhem.db 2023.01.02 20:17:00 2: HUEBridge: http request failed: read from https://192.168.178.22:443 timed out 2023.01.02 20:17:01 3: SSCam.Dachboden - Polling-Log of Dachboden is deactivated 2023.01.02 20:17:01 3: SSCam.Dachboden - Pollinginterval of Dachboden has been changed to: 210 s 2023.01.02 20:17:13 3: SSCam.Terrasse_1 - Polling-Log of Terrasse_1 is deactivated 2023.01.02 20:17:13 3: SSCam.Terrasse_1 - Pollinginterval of Terrasse_1 has been changed to: 210 s 2023.01.02 20:17:13 3: CUL_HM start Queues 2023.01.02 20:17:15 3: CUL_HM set garage_switch_Tor statusRequest noArg 2023.01.02 20:17:16 3: CUL_HM set ku_Aktor_Radio_Sw_01_alt statusRequest noArg 2023.01.02 20:17:18 3: CUL_HM set ku_Aktor_Radio_Sw_02_alt statusRequest noArg 2023.01.02 20:17:19 3: CUL_HM set ku_Bel_Esstisch_Taster statusRequest noArg 2023.01.02 20:17:20 3: CUL_HM set ku_Taster_unten_Sw_01 statusRequest noArg 2023.01.02 20:17:22 3: CUL_HM set ku_Taster_unten_Sw_02 statusRequest noArg 2023.01.02 20:17:25 3: CUL_HM set wozi_Bel_Deckenlicht statusRequest noArg 2023.01.02 20:17:27 3: CUL_HM set wozi_Bel_Deckenlicht_Dim_V_01 statusRequest noArg 2023.01.02 20:17:28 3: CUL_HM set wozi_Bel_Deckenlicht_Dim_V_02 statusRequest noArg 2023.01.02 20:17:29 3: CUL_HM set wozi_TV_Steckdose_Sw statusRequest noArg 2023.01.02 20:17:41 1: PERL WARNING: Use of uninitialized value in concatenation (.) or string at ./FHEM/73_GardenaSmartBridge.pm line 637. 2023.01.02 20:17:41 1: PERL WARNING: Use of uninitialized value in concatenation (.) or string at ./FHEM/73_GardenaSmartBridge.pm line 644. 2023.01.02 20:17:41 1: PERL WARNING: Use of uninitialized value in concatenation (.) or string at ./FHEM/73_GardenaSmartBridge.pm line 649. 2023.01.02 20:18:19 2: HUEBridge: EventStream: event for unknown device received, trying to refresh resouces 2023.01.02 20:18:20 2: HUEBridge: autocreate: created 0/0/0 devices (ignored 0/0/2)`

[fhempy]

Danke für den Test. Bitte nochmals ein FHEM update, ich hoffe jetzt klappt es.

[DB1113]

Moin,

Nee sorry, die Meldung ist immer noch da... 🫣

Bis denn

[fhempy]

Jetzt sollte es funktionieren. Das Device wird nun nur angelegt, wenn erstmalig der fhempyserver angelegt wird.

[DB1113]

Hi, Jetzt funktioniert es! Vielen Dank für die schnelle Bearbeitung... 😉 😁

Bis bald!

[fhempy]

Super, danke für's Einmelden!