Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

Sign up for GitHub

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

Jump to bottom

Client fails and is not restarted #38

Open
jimboca opened this issue Dec 6, 2023 · 0 comments
Open

Client fails and is not restarted #38

jimboca opened this issue Dec 6, 2023 · 0 comments
Assignees
@jimboca

Comments

@jimboca
Copy link
Collaborator

jimboca commented Dec 6, 2023

From post: https://forum.universal-devices.com/topic/42520-client-was-stopped-error/

2023-12-04 03:02:29,708 read_thread sleekxmpp.xmlstream.xmlstream ERROR    xmlstream:_process: Error reading from XML stream.
2023-12-04 03:02:47,204 Command    sleekxmpp.xmlstream.handler.waiter WARNING  waiter:wait: Timed out waiting for IqWait_d0d0f51b-e409-4549-a109-816ac91d8f77-11
2023-12-04 03:02:47,205 Command    pyharmony.client   INFO     client:get_current_activity: XMPP timeout, reattempting
2023-12-04 03:03:17,209 Command    sleekxmpp.xmlstream.handler.waiter WARNING  waiter:wait: Timed out waiting for IqWait_d0d0f51b-e409-4549-a109-816ac91d8f77-11
2023-12-04 03:03:17,209 Command    udi_interface      ERROR    udi_interface:write: --- Logging error ---
2023-12-04 03:03:17,212 Command    udi_interface      ERROR    udi_interface:write: Traceback (most recent call last):
2023-12-04 03:03:17,212 Command    udi_interface      ERROR    udi_interface:write: File "/var/polyglot/pg3/ns/0021b9026524_14/pyharmony/pyharmony/client.py", line 78, in get_current_activity
    result = iq_cmd.send(block=True)
2023-12-04 03:03:17,212 Command    udi_interface      ERROR    udi_interface:write: File "/var/polyglot/pg3/ns/0021b9026524_14/.local/lib/python3.9/site-packages/sleekxmpp/stanza/iq.py", line 233, in send
    raise IqTimeout(self)
2023-12-04 03:03:17,212 Command    udi_interface      ERROR    udi_interface:write: sleekxmpp.exceptions.IqTimeout: <iq id="d0d0f51b-e409-4549-a109-816ac91d8f77-11" type="get"><oa xmlns="connect.logitech.com" mime="vnd.logitech.harmony/vnd.logitech.harmony.engine?getCurrentActivity" /></iq>
2023-12-04 03:03:17,212 Command    udi_interface      ERROR    udi_interface:write: During handling of the above exception, another exception occurred:
2023-12-04 03:03:17,213 Command    udi_interface      ERROR    udi_interface:write: Traceback (most recent call last):
2023-12-04 03:03:17,213 Command    udi_interface      ERROR    udi_interface:write: File "/var/polyglot/pg3/ns/0021b9026524_14/nodes/Hub.py", line 252, in _get_current_activity
    ca = self.client.get_current_activity()
2023-12-04 03:03:17,213 Command    udi_interface      ERROR    udi_interface:write: File "/var/polyglot/pg3/ns/0021b9026524_14/pyharmony/pyharmony/client.py", line 81, in get_current_activity
    result = iq_cmd.send(block=True)
2023-12-04 03:03:17,213 Command    udi_interface      ERROR    udi_interface:write: File "/var/polyglot/pg3/ns/0021b9026524_14/.local/lib/python3.9/site-packages/sleekxmpp/stanza/iq.py", line 233, in send
    raise IqTimeout(self)
2023-12-04 03:03:17,213 Command    udi_interface      ERROR    udi_interface:write: sleekxmpp.exceptions.IqTimeout: <iq id="d0d0f51b-e409-4549-a109-816ac91d8f77-11" type="get"><oa xmlns="connect.logitech.com" mime="vnd.logitech.harmony/vnd.logitech.harmony.engine?getCurrentActivity" /></iq>
2023-12-04 03:03:17,213 Command    udi_interface      ERROR    udi_interface:write: During handling of the above exception, another exception occurred:
2023-12-04 03:03:17,213 Command    udi_interface      ERROR    udi_interface:write: Traceback (most recent call last):
2023-12-04 03:03:17,213 Command    udi_interface      ERROR    udi_interface:write: File "/usr/local/lib/python3.9/logging/__init__.py", line 1083, in emit
    msg = self.format(record)
2023-12-04 03:03:17,214 Command    udi_interface      ERROR    udi_interface:write: File "/usr/local/lib/python3.9/logging/__init__.py", line 927, in format
    return fmt.format(record)
2023-12-04 03:03:17,214 Command    udi_interface      ERROR    udi_interface:write: File "/usr/local/lib/python3.9/logging/__init__.py", line 663, in format
    record.message = record.getMessage()
2023-12-04 03:03:17,214 Command    udi_interface      ERROR    udi_interface:write: File "/usr/local/lib/python3.9/logging/__init__.py", line 367, in getMessage
    msg = msg % self.args
2023-12-04 03:03:17,214 Command    udi_interface      ERROR    udi_interface:write: TypeError: not all arguments converted during string formatting
2023-12-04 03:03:17,214 Command    udi_interface      ERROR    udi_interface:write: Call stack:
2023-12-04 03:03:17,216 Command    udi_interface      ERROR    udi_interface:write: File "/usr/local/lib/python3.9/threading.py", line 937, in _bootstrap
    self._bootstrap_inner()
2023-12-04 03:03:17,216 Command    udi_interface      ERROR    udi_interface:write: File "/usr/local/lib/python3.9/threading.py", line 980, in _bootstrap_inner
    self.run()
2023-12-04 03:03:17,216 Command    udi_interface      ERROR    udi_interface:write: File "/usr/local/lib/python3.9/threading.py", line 917, in run
    self._target(*self._args, **self._kwargs)
2023-12-04 03:03:17,216 Command    udi_interface      ERROR    udi_interface:write: File "/var/polyglot/pg3/ns/0021b9026524_14/.local/lib/python3.9/site-packages/udi_interface/interface.py", line 859, in _parseInput
    self._handleInput(key, input[key], None)
2023-12-04 03:03:17,216 Command    udi_interface      ERROR    udi_interface:write: File "/var/polyglot/pg3/ns/0021b9026524_14/.local/lib/python3.9/site-packages/udi_interface/interface.py", line 1007, in _handleInput
    self.nodes_internal[item['address']].query()
2023-12-04 03:03:17,217 Command    udi_interface      ERROR    udi_interface:write: File "/var/polyglot/pg3/ns/0021b9026524_14/nodes/Controller.py", line 210, in query
    self.poly.getNode(node).query()
2023-12-04 03:03:17,217 Command    udi_interface      ERROR    udi_interface:write: File "/var/polyglot/pg3/ns/0021b9026524_14/nodes/Hub.py", line 94, in query
    self._get_current_activity()
2023-12-04 03:03:17,217 Command    udi_interface      ERROR    udi_interface:write: File "/var/polyglot/pg3/ns/0021b9026524_14/nodes/Hub.py", line 254, in _get_current_activity
    LOGGER.error('client.get_current_activity timeout',False)
2023-12-04 03:03:17,217 Command    udi_interface      ERROR    udi_interface:write: Message: 'client.get_current_activity timeout'
Arguments: (False,)
2023-12-04 03:03:17,217 Command    udi_interface      INFO     Hub:_set_st: setDriver(ST,0)
2023-12-04 03:03:17,264 MQTT       udi_interface.interface INFO     interface:_message: Successfully set h9bfc75ec675d0 :: ST to 0 UOM 2
2023-12-04 03:03:17,730 Command    udi_interface.node INFO     node:reportDrivers: Updating All Drivers to ISY for Living Room(h9bfc75ec675d0)
2023-12-04 03:03:17,731 Command    udi_interface      INFO     Hub:check_client: Client was stopped. clientNone
2023-12-04 03:03:17,731 Command    udi_interface      INFO     Hub:_set_st: setDriver(ST,1)
2023-12-04 03:03:17,731 Command    udi_interface      INFO     Hub:check_client: Client was stopped. clientNone
2023-12-04 03:03:17,731 Command    udi_interface      ERROR    udi_interface:write: --- Logging error ---
2023-12-04 03:03:17,731 Command    udi_interface      ERROR    udi_interface:write: Traceback (most recent call last):
2023-12-04 03:03:17,732 Command    udi_interface      ERROR    udi_interface:write: File "/var/polyglot/pg3/ns/0021b9026524_14/nodes/Hub.py", line 252, in _get_current_activity
    ca = self.client.get_current_activity()
2023-12-04 03:03:17,732 Command    udi_interface      ERROR    udi_interface:write: AttributeError: 'NoneType' object has no attribute 'get_current_activity'
2023-12-04 03:03:17,732 Command    udi_interface      ERROR    udi_interface:write: During handling of the above exception, another exception occurred:
2023-12-04 03:03:17,732 Command    udi_interface      ERROR    udi_interface:write: Traceback (most recent call last):
2023-12-04 03:03:17,732 Command    udi_interface      ERROR    udi_interface:write: File "/usr/local/lib/python3.9/logging/__init__.py", line 1083, in emit
    msg = self.format(record)
2023-12-04 03:03:17,732 Command    udi_interface      ERROR    udi_interface:write: File "/usr/local/lib/python3.9/logging/__init__.py", line 927, in format
    return fmt.format(record)
2023-12-04 03:03:17,732 Command    udi_interface      ERROR    udi_interface:write: File "/usr/local/lib/python3.9/logging/__init__.py", line 663, in format
    record.message = record.getMessage()
2023-12-04 03:03:17,733 Command    udi_interface      ERROR    udi_interface:write: File "/usr/local/lib/python3.9/logging/__init__.py", line 367, in getMessage
    msg = msg % self.args
2023-12-04 03:03:17,733 Command    udi_interface      ERROR    udi_interface:write: TypeError: not all arguments converted during string formatting
2023-12-04 03:03:17,733 Command    udi_interface      ERROR    udi_interface:write: Call stack:
2023-12-04 03:03:17,733 Command    udi_interface      ERROR    udi_interface:write: File "/usr/local/lib/python3.9/threading.py", line 937, in _bootstrap
    self._bootstrap_inner()
2023-12-04 03:03:17,733 Command    udi_interface      ERROR    udi_interface:write: File "/usr/local/lib/python3.9/threading.py", line 980, in _bootstrap_inner
    self.run()
2023-12-04 03:03:17,733 Command    udi_interface      ERROR    udi_interface:write: File "/usr/local/lib/python3.9/threading.py", line 917, in run
    self._target(*self._args, **self._kwargs)
2023-12-04 03:03:17,733 Command    udi_interface      ERROR    udi_interface:write: File "/var/polyglot/pg3/ns/0021b9026524_14/.local/lib/python3.9/site-packages/udi_interface/interface.py", line 859, in _parseInput
    self._handleInput(key, input[key], None)
2023-12-04 03:03:17,734 Command    udi_interface      ERROR    udi_interface:write: File "/var/polyglot/pg3/ns/0021b9026524_14/.local/lib/python3.9/site-packages/udi_interface/interface.py", line 1007, in _handleInput
    self.nodes_internal[item['address']].query()
2023-12-04 03:03:17,734 Command    udi_interface      ERROR    udi_interface:write: File "/var/polyglot/pg3/ns/0021b9026524_14/nodes/Hub.py", line 94, in query
    self._get_current_activity()
2023-12-04 03:03:17,734 Command    udi_interface      ERROR    udi_interface:write: File "/var/polyglot/pg3/ns/0021b9026524_14/nodes/Hub.py", line 258, in _get_current_activity
    LOGGER.error('client.get_current_activity failed',True)
2023-12-04 03:03:17,734 Command    udi_interface      ERROR    udi_interface:write: Message: 'client.get_current_activity failed'
Arguments: (True,)
2023-12-04 03:04:21,912 Thread-1049 udi_interface      INFO     Hub:check_client: Client was stopped. clientNone
2023-12-04 03:04:21,913 Thread-1049 udi_interface      INFO     Hub:_set_st: setDriver(ST,1)
2023-12-04 03:04:21,913 Thread-1049 udi_interface      INFO     Controller:heartbeat: hb=1
2023-12-04 03:09:21,913 Thread-1055 udi_interface      INFO     Hub:check_client: Client was stopped. clientNone
2023-12-04 03:09:21,913 Thread-1055 udi_interface      INFO     Controller:heartbeat: hb=0
2023-12-04 03:14:21,919 Thread-1061 udi_interface      INFO     Hub:check_client: Client was stopped. clientNone
2023-12-04 03:14:21,919 Thread-1061 udi_interface      INFO     Controller:heartbeat: hb=1
2023-12-04 03:19:21,935 Thread-1067 udi_interface      INFO     Hub:check_client: Client was stopped. clientNone
2023-12-04 03:19:21,935 Thread-1067 udi_interface      INFO     Controller:heartbeat: hb=0
2023-12-04 03:24:21,936 Thread-1073 udi_interface      INFO     Hub:check_client: Client was stopped. clientNone
2023-12-04 03:24:21,936 Thread-1073 udi_interface      INFO     Controller:heartbeat: hb=1
2023-12-04 03:29:21,941 Thread-1079 udi_interface      INFO     Hub:check_client: Client was stopped. clientNone
2023-12-04 03:29:21,941 Thread-1079 udi_interface      INFO     Controller:heartbeat: hb=0
2023-12-04 03:34:21,942 Thread-1085 udi_interface      INFO     Hub:check_client: Client was stopped. clientNone
2023-12-04 03:34:21,942 Thread-1085 udi_interface      INFO     Controller:heartbeat: hb=1
2023-12-04 03:39:21,946 Thread-1091 udi_interface      INFO     Hub:check_client: Client was stopped. clientNone
2023-12-04 03:39:21,946 Thread-1091 udi_interface      INFO     Controller:heartbeat: hb=0
2023-12-04 03:44:21,955 Thread-1097 udi_interface      INFO     Hub:check_client: Client was stopped. clientNone
2023-12-04 03:44:21,955 Thread-1097 udi_interface      INFO     Controller:heartbeat: hb=1
2023-12-04 03:49:21,956 Thread-1103 udi_interface      INFO     Hub:check_client: Client was stopped. clientNone
2023-12-04 03:49:21,956 Thread-1103 udi_interface      INFO     Controller:heartbeat: hb=0
2023-12-04 03:54:21,957 Thread-1109 udi_interface      INFO     Hub:check_client: Client was stopped. clientNone
2023-12-04 03:54:21,957 Thread-1109 udi_interface      INFO     Controller:heartbeat: hb=1
2023-12-04 03:59:21,960 Thread-1115 udi_interface      INFO     Hub:check_client: Client was stopped. clientNone
2023-12-04 03:59:21,961 Thread-1115 udi_interface      INFO     Controller:heartbeat: hb=0
2023-12-04 04:04:21,967 Thread-1121 udi_interface      INFO     Hub:check_client: Client was stopped. clientNone
2023-12-04 04:04:21,968 Thread-1121 udi_interface      INFO     Controller:heartbeat: hb=1
2023-12-04 04:09:21,969 Thread-1127 udi_interface      INFO     Hub:check_client: Client was stopped. clientNone
2023-12-04 04:09:21,970 Thread-1127 udi_interface      INFO     Controller:heartbeat: hb=0
2023-12-04 04:14:21,970 Thread-1133 udi_interface      INFO     Hub:check_client: Client was stopped. clientNone
2023-12-04 04:14:21,971 Thread-1133 udi_interface      INFO     Controller:heartbeat: hb=1
2023-12-04 04:19:21,981 Thread-1139 udi_interface      INFO     Hub:check_client: Client was stopped. clientNone
2023-12-04 04:19:21,981 Thread-1139 udi_interface      INFO     Controller:heartbeat: hb=0
2023-12-04 04:24:21,983 Thread-1145 udi_interface      INFO     Hub:check_client: Client was stopped. clientNone
2023-12-04 04:24:21,983 Thread-1145 udi_interface      INFO     Controller:heartbeat: hb=1
2023-12-04 04:29:21,992 Thread-1151 udi_interface      INFO     Hub:check_client: Client was stopped. clientNone
2023-12-04 04:29:21,992 Thread-1151 udi_interface      INFO     Controller:heartbeat: hb=0
2023-12-04 04:34:22,001 Thread-1157 udi_interface      INFO     Hub:check_client: Client was stopped. clientNone
2023-12-04 04:34:22,001 Thread-1157 udi_interface      INFO     Controller:heartbeat: hb=1
2023-12-04 04:39:22,017 Thread-1163 udi_interface      INFO     Hub:check_client: Client was stopped. clientNone
2023-12-04 04:39:22,018 Thread-1163 udi_interface      INFO     Controller:heartbeat: hb=0
2023-12-04 04:44:22,024 Thread-1169 udi_interface      INFO     Hub:check_client: Client was stopped. clientNone
2023-12-04 04:44:22,024 Thread-1169 udi_interface      INFO     Controller:heartbeat: hb=1
2023-12-04 04:49:22,031 Thread-1175 udi_interface      INFO     Hub:check_client: Client was stopped. clientNone
2023-12-04 04:49:22,032 Thread-1175 udi_interface      INFO     Controller:heartbeat: hb=0
2023-12-04 04:54:22,033 Thread-1181 udi_interface      INFO     Hub:check_client: Client was stopped. clientNone
2023-12-04 04:54:22,033 Thread-1181 udi_interface      INFO     Controller:heartbeat: hb=1
2023-12-04 04:59:22,035 Thread-1187 udi_interface      INFO     Hub:check_client: Client was stopped. clientNone
2023-12-04 04:59:22,036 Thread-1187 udi_interface      INFO     Controller:heartbeat: hb=0
2023-12-04 05:04:22,044 Thread-1193 udi_interface      INFO     Hub:check_client: Client was stopped. clientNone
2023-12-04 05:04:22,044 Thread-1193 udi_interface      INFO     Controller:heartbeat: hb=1
2023-12-04 05:09:22,061 Thread-1199 udi_interface      INFO     Hub:check_client: Client was stopped. clientNone
2023-12-04 05:09:22,062 Thread-1199 udi_interface      INFO     Controller:heartbeat: hb=0
2023-12-04 05:14:22,066 Thread-1205 udi_interface      INFO     Hub:check_client: Client was stopped. clientNone
2023-12-04 05:14:22,066 Thread-1205 udi_interface      INFO     Controller:heartbeat: hb=1
2023-12-04 05:18:29,475 Command    udi_interface      ERROR    Hub:start_activity: No Client
2023-12-04 05:19:22,076 Thread-1211 udi_interface      INFO     Hub:check_client: Client was stopped. clientNone
2023-12-04 05:19:22,076 Thread-1211 udi_interface      INFO     Controller:heartbeat: hb=0
2023-12-04 05:20:23,055 Command    udi_interface      ERROR    Hub:start_activity: No Client
2023-12-04 05:24:22,078 Thread-1217 udi_interface      INFO     Hub:check_client: Client was stopped. clientNone
2023-12-04 05:24:22,078 Thread-1217 udi_interface      INFO     Controller:heartbeat: hb=1
2023-12-04 05:29:22,081 Thread-1223 udi_interface      INFO     Hub:check_client: Client was stopped. clientNone
2023-12-04 05:29:22,081 Thread-1223 udi_interface      INFO     Controller:heartbeat: hb=0
2023-12-04 05:34:22,096 Thread-1229 udi_interface      INFO     Hub:check_client: Client was stopped. clientNone
2023-12-04 05:34:22,096 Thread-1229 udi_interface      INFO     Controller:heartbeat: hb=1
2023-12-04 05:39:22,104 Thread-1235 udi_interface      INFO     Hub:check_client: Client was stopped. clientNone
2023-12-04 05:39:22,105 Thread-1235 udi_interface      INFO     Controller:heartbeat: hb=0

It just continues to repeat the 'client was stopped' message until I eventually notice and restart the nodeserver.
@jimboca jimboca self-assigned this Dec 6, 2023
jimboca added a commit that referenced this issue Dec 10, 2023
@jimboca
Try a fix for #38
d7bc01f
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Assignees

@jimboca jimboca

Labels
None yet
Projects
None yet
Milestone
No milestone
Development

No branches or pull requests
1 participant
@jimboca