-- Journal begins at Sat 2025-11-01 06:35:41 UTC, ends at Sat 2025-11-01 08:13:45 UTC. -- Nov 01 08:12:00 volumio <1031>VOLUMIO-HID[12680]: [Volumio] Connect to 'localhost:3000' Nov 01 08:12:00 volumio volumio[944]: verbose: New Socket.io Connection to localhost:3000 from 1 UA: python-requests/2.32.3 Engine version: 3 Transport: polling Total Clients: 7 Nov 01 08:12:00 volumio python3[12680]: Traceback (most recent call last): Nov 01 08:12:00 volumio python3[12680]: File "/home/volumio/volumio-hid/HID.py", line 108, in Volumio Nov 01 08:12:00 volumio python3[12680]: volumioIO = SocketIO(server, port, VolumioNamespace, wait_for_connection=False) Nov 01 08:12:00 volumio python3[12680]: File "/home/volumio/.local/lib/python3.9/site-packages/socketIO_client/__init__.py", line 351, in __init__ Nov 01 08:12:00 volumio python3[12680]: super(SocketIO, self).__init__( Nov 01 08:12:00 volumio python3[12680]: File "/home/volumio/.local/lib/python3.9/site-packages/socketIO_client/__init__.py", line 54, in __init__ Nov 01 08:12:00 volumio python3[12680]: self._transport Nov 01 08:12:00 volumio python3[12680]: File "/home/volumio/.local/lib/python3.9/site-packages/socketIO_client/__init__.py", line 62, in _transport Nov 01 08:12:00 volumio python3[12680]: self._engineIO_session = self._get_engineIO_session() Nov 01 08:12:00 volumio python3[12680]: File "/home/volumio/.local/lib/python3.9/site-packages/socketIO_client/__init__.py", line 75, in _get_engineIO_session Nov 01 08:12:00 volumio python3[12680]: engineIO_packet_type, engineIO_packet_data = next( Nov 01 08:12:00 volumio python3[12680]: StopIteration Nov 01 08:12:00 volumio python3[12680]: The above exception was the direct cause of the following exception: Nov 01 08:12:00 volumio python3[12680]: Traceback (most recent call last): Nov 01 08:12:00 volumio python3[12680]: File "/home/volumio/volumio-hid/HID.py", line 627, in Nov 01 08:12:00 volumio python3[12680]: with Volumio(server, port) as socket: Nov 01 08:12:00 volumio python3[12680]: File "/usr/lib/python3.9/contextlib.py", line 117, in __enter__ Nov 01 08:12:00 volumio python3[12680]: return next(self.gen) Nov 01 08:12:00 volumio python3[12680]: RuntimeError: generator raised StopIteration Nov 01 08:12:00 volumio systemd[1]: volumio-hid.service: Main process exited, code=exited, status=1/FAILURE Nov 01 08:12:00 volumio systemd[1]: volumio-hid.service: Failed with result 'exit-code'. Nov 01 08:12:00 volumio systemd[1]: volumio-hid.service: Consumed 1.416s CPU time. Nov 01 08:12:02 volumio volumio[944]: info: CoreCommandRouter::volumioGetState Nov 01 08:12:02 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Nov 01 08:12:02 volumio volumio[944]: info: Listing playlists Nov 01 08:12:02 volumio volumio[944]: info: Listing playlists Nov 01 08:12:07 volumio volumio[944]: info: Nov 01 08:12:07 volumio volumio[944]: ---------------------------- MPD announces system playlist update Nov 01 08:12:07 volumio volumio[944]: info: Ignoring MPD Status Update Nov 01 08:12:07 volumio volumio[944]: info: Nov 01 08:12:07 volumio volumio[944]: ---------------------------- MPD announces state update: player Nov 01 08:12:07 volumio volumio[944]: info: ControllerMpd::getState Nov 01 08:12:07 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand status Nov 01 08:12:07 volumio volumio[944]: info: Nov 01 08:12:07 volumio volumio[944]: ---------------------------- MPD announces system playlist update Nov 01 08:12:07 volumio volumio[944]: info: Ignoring MPD Status Update Nov 01 08:12:07 volumio volumio[944]: info: Nov 01 08:12:07 volumio volumio[944]: ---------------------------- MPD announces state update: player Nov 01 08:12:07 volumio volumio[944]: info: ControllerMpd::getState Nov 01 08:12:07 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand status Nov 01 08:12:07 volumio volumio[944]: info: Nov 01 08:12:07 volumio volumio[944]: ---------------------------- MPD announces system playlist update Nov 01 08:12:07 volumio volumio[944]: info: Ignoring MPD Status Update Nov 01 08:12:07 volumio volumio[944]: info: Nov 01 08:12:07 volumio volumio[944]: ---------------------------- MPD announces state update: player Nov 01 08:12:07 volumio volumio[944]: info: ControllerMpd::getState Nov 01 08:12:07 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand status Nov 01 08:12:07 volumio volumio[944]: info: ------------------------------ 9ms Nov 01 08:12:07 volumio volumio[944]: info: sendMpdCommand status took 7 milliseconds Nov 01 08:12:07 volumio volumio[944]: info: ------------------------------ 7ms Nov 01 08:12:07 volumio volumio[944]: info: sendMpdCommand status took 6 milliseconds Nov 01 08:12:07 volumio volumio[944]: info: ------------------------------ 5ms Nov 01 08:12:07 volumio volumio[944]: info: sendMpdCommand status took 4 milliseconds Nov 01 08:12:07 volumio volumio[944]: verbose: ControllerMpd::parseState Nov 01 08:12:07 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 01 08:12:07 volumio volumio[944]: verbose: ControllerMpd::parseState Nov 01 08:12:07 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 01 08:12:07 volumio volumio[944]: verbose: ControllerMpd::parseState Nov 01 08:12:07 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 01 08:12:07 volumio volumio[944]: info: sendMpdCommand playlistinfo took 2 milliseconds Nov 01 08:12:07 volumio volumio[944]: info: sendMpdCommand playlistinfo took 3 milliseconds Nov 01 08:12:07 volumio volumio[944]: info: sendMpdCommand playlistinfo took 2 milliseconds Nov 01 08:12:07 volumio volumio[944]: verbose: ControllerMpd::parseTrackInfo Nov 01 08:12:07 volumio volumio[944]: verbose: ControllerMpd::parseTrackInfo Nov 01 08:12:07 volumio volumio[944]: verbose: ControllerMpd::parseTrackInfo Nov 01 08:12:07 volumio volumio[944]: info: ControllerMpd::pushState Nov 01 08:12:07 volumio volumio[944]: info: CoreCommandRouter::servicePushState Nov 01 08:12:07 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Nov 01 08:12:07 volumio volumio[944]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Nov 01 08:12:07 volumio volumio[944]: info: ControllerMpd::pushState Nov 01 08:12:07 volumio volumio[944]: info: CoreCommandRouter::servicePushState Nov 01 08:12:07 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Nov 01 08:12:07 volumio volumio[944]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Nov 01 08:12:07 volumio volumio[944]: info: ControllerMpd::pushState Nov 01 08:12:07 volumio volumio[944]: info: CoreCommandRouter::servicePushState Nov 01 08:12:07 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Nov 01 08:12:07 volumio volumio[944]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Nov 01 08:12:07 volumio volumio[944]: info: ------------------------------ 17ms Nov 01 08:12:07 volumio volumio[944]: info: ------------------------------ 14ms Nov 01 08:12:07 volumio volumio[944]: info: ------------------------------ 12ms Nov 01 08:12:12 volumio volumio[944]: info: CoreCommandRouter::volumioGetState Nov 01 08:12:12 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Nov 01 08:12:22 volumio volumio[944]: info: CoreCommandRouter::volumioGetState Nov 01 08:12:22 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Nov 01 08:12:22 volumio volumio[944]: info: Listing playlists Nov 01 08:12:22 volumio volumio[944]: info: Listing playlists Nov 01 08:12:27 volumio volumio[944]: info: Nov 01 08:12:27 volumio volumio[944]: ---------------------------- MPD announces system playlist update Nov 01 08:12:27 volumio volumio[944]: info: Ignoring MPD Status Update Nov 01 08:12:27 volumio volumio[944]: info: Nov 01 08:12:27 volumio volumio[944]: ---------------------------- MPD announces system playlist update Nov 01 08:12:27 volumio volumio[944]: info: Ignoring MPD Status Update Nov 01 08:12:27 volumio volumio[944]: info: Nov 01 08:12:27 volumio volumio[944]: ---------------------------- MPD announces system playlist update Nov 01 08:12:27 volumio volumio[944]: info: Ignoring MPD Status Update Nov 01 08:12:27 volumio volumio[944]: info: Nov 01 08:12:27 volumio volumio[944]: ---------------------------- MPD announces state update: player Nov 01 08:12:27 volumio volumio[944]: info: ControllerMpd::getState Nov 01 08:12:27 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand status Nov 01 08:12:27 volumio volumio[944]: info: Nov 01 08:12:27 volumio volumio[944]: ---------------------------- MPD announces state update: player Nov 01 08:12:27 volumio volumio[944]: info: ControllerMpd::getState Nov 01 08:12:27 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand status Nov 01 08:12:27 volumio volumio[944]: info: Nov 01 08:12:27 volumio volumio[944]: ---------------------------- MPD announces state update: player Nov 01 08:12:27 volumio volumio[944]: info: ControllerMpd::getState Nov 01 08:12:27 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand status Nov 01 08:12:27 volumio volumio[944]: info: ------------------------------ 9ms Nov 01 08:12:27 volumio volumio[944]: info: ------------------------------ 8ms Nov 01 08:12:27 volumio volumio[944]: info: ------------------------------ 7ms Nov 01 08:12:27 volumio volumio[944]: info: sendMpdCommand status took 5 milliseconds Nov 01 08:12:27 volumio volumio[944]: info: sendMpdCommand status took 4 milliseconds Nov 01 08:12:27 volumio volumio[944]: verbose: ControllerMpd::parseState Nov 01 08:12:27 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 01 08:12:27 volumio volumio[944]: verbose: ControllerMpd::parseState Nov 01 08:12:27 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 01 08:12:27 volumio volumio[944]: info: sendMpdCommand status took 4 milliseconds Nov 01 08:12:27 volumio volumio[944]: info: sendMpdCommand playlistinfo took 2 milliseconds Nov 01 08:12:27 volumio volumio[944]: info: sendMpdCommand playlistinfo took 3 milliseconds Nov 01 08:12:27 volumio volumio[944]: verbose: ControllerMpd::parseState Nov 01 08:12:27 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 01 08:12:27 volumio volumio[944]: verbose: ControllerMpd::parseTrackInfo Nov 01 08:12:27 volumio volumio[944]: verbose: ControllerMpd::parseTrackInfo Nov 01 08:12:27 volumio volumio[944]: info: ControllerMpd::pushState Nov 01 08:12:27 volumio volumio[944]: info: CoreCommandRouter::servicePushState Nov 01 08:12:27 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Nov 01 08:12:27 volumio volumio[944]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Nov 01 08:12:27 volumio volumio[944]: info: ControllerMpd::pushState Nov 01 08:12:27 volumio volumio[944]: info: CoreCommandRouter::servicePushState Nov 01 08:12:27 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Nov 01 08:12:27 volumio volumio[944]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Nov 01 08:12:27 volumio volumio[944]: info: ------------------------------ 13ms Nov 01 08:12:27 volumio volumio[944]: info: ------------------------------ 12ms Nov 01 08:12:27 volumio volumio[944]: info: sendMpdCommand playlistinfo took 4 milliseconds Nov 01 08:12:27 volumio volumio[944]: verbose: ControllerMpd::parseTrackInfo Nov 01 08:12:27 volumio volumio[944]: info: ControllerMpd::pushState Nov 01 08:12:27 volumio volumio[944]: info: CoreCommandRouter::servicePushState Nov 01 08:12:27 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Nov 01 08:12:27 volumio volumio[944]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Nov 01 08:12:27 volumio volumio[944]: info: ------------------------------ 12ms Nov 01 08:12:30 volumio systemd[1]: volumio-hid.service: Scheduled restart job, restart counter is at 592. Nov 01 08:12:30 volumio systemd[1]: Stopped volumio-hid Service. Nov 01 08:12:30 volumio systemd[1]: volumio-hid.service: Consumed 1.416s CPU time. Nov 01 08:12:30 volumio systemd[1]: Started volumio-hid Service. Nov 01 08:12:31 volumio <1031>VOLUMIO-HID[12831]: [Volumio] Connect to 'localhost:3000' Nov 01 08:12:31 volumio volumio[944]: verbose: New Socket.io Connection to localhost:3000 from 1 UA: python-requests/2.32.3 Engine version: 3 Transport: polling Total Clients: 7 Nov 01 08:12:31 volumio python3[12831]: Traceback (most recent call last): Nov 01 08:12:31 volumio python3[12831]: File "/home/volumio/volumio-hid/HID.py", line 108, in Volumio Nov 01 08:12:31 volumio python3[12831]: volumioIO = SocketIO(server, port, VolumioNamespace, wait_for_connection=False) Nov 01 08:12:31 volumio python3[12831]: File "/home/volumio/.local/lib/python3.9/site-packages/socketIO_client/__init__.py", line 351, in __init__ Nov 01 08:12:31 volumio python3[12831]: super(SocketIO, self).__init__( Nov 01 08:12:31 volumio python3[12831]: File "/home/volumio/.local/lib/python3.9/site-packages/socketIO_client/__init__.py", line 54, in __init__ Nov 01 08:12:31 volumio python3[12831]: self._transport Nov 01 08:12:31 volumio python3[12831]: File "/home/volumio/.local/lib/python3.9/site-packages/socketIO_client/__init__.py", line 62, in _transport Nov 01 08:12:31 volumio python3[12831]: self._engineIO_session = self._get_engineIO_session() Nov 01 08:12:31 volumio python3[12831]: File "/home/volumio/.local/lib/python3.9/site-packages/socketIO_client/__init__.py", line 75, in _get_engineIO_session Nov 01 08:12:31 volumio python3[12831]: engineIO_packet_type, engineIO_packet_data = next( Nov 01 08:12:31 volumio python3[12831]: StopIteration Nov 01 08:12:31 volumio python3[12831]: The above exception was the direct cause of the following exception: Nov 01 08:12:31 volumio python3[12831]: Traceback (most recent call last): Nov 01 08:12:31 volumio python3[12831]: File "/home/volumio/volumio-hid/HID.py", line 627, in Nov 01 08:12:31 volumio python3[12831]: with Volumio(server, port) as socket: Nov 01 08:12:31 volumio python3[12831]: File "/usr/lib/python3.9/contextlib.py", line 117, in __enter__ Nov 01 08:12:31 volumio python3[12831]: return next(self.gen) Nov 01 08:12:31 volumio python3[12831]: RuntimeError: generator raised StopIteration Nov 01 08:12:32 volumio systemd[1]: volumio-hid.service: Main process exited, code=exited, status=1/FAILURE Nov 01 08:12:32 volumio systemd[1]: volumio-hid.service: Failed with result 'exit-code'. Nov 01 08:12:32 volumio systemd[1]: volumio-hid.service: Consumed 1.413s CPU time. Nov 01 08:12:32 volumio volumio[944]: info: CoreCommandRouter::volumioGetState Nov 01 08:12:32 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Nov 01 08:12:42 volumio volumio[944]: info: CoreCommandRouter::volumioGetState Nov 01 08:12:42 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Nov 01 08:12:42 volumio volumio[944]: info: Listing playlists Nov 01 08:12:42 volumio volumio[944]: info: Listing playlists Nov 01 08:12:52 volumio volumio[944]: info: Nov 01 08:12:52 volumio volumio[944]: ---------------------------- MPD announces system playlist update Nov 01 08:12:52 volumio volumio[944]: info: Ignoring MPD Status Update Nov 01 08:12:52 volumio volumio[944]: info: Nov 01 08:12:52 volumio volumio[944]: ---------------------------- MPD announces system playlist update Nov 01 08:12:52 volumio volumio[944]: info: Ignoring MPD Status Update Nov 01 08:12:52 volumio volumio[944]: info: Nov 01 08:12:52 volumio volumio[944]: ---------------------------- MPD announces system playlist update Nov 01 08:12:52 volumio volumio[944]: info: Ignoring MPD Status Update Nov 01 08:12:52 volumio volumio[944]: info: Nov 01 08:12:52 volumio volumio[944]: ---------------------------- MPD announces state update: player Nov 01 08:12:52 volumio volumio[944]: info: ControllerMpd::getState Nov 01 08:12:52 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand status Nov 01 08:12:52 volumio volumio[944]: info: ------------------------------ 7ms Nov 01 08:12:52 volumio volumio[944]: info: ------------------------------ 6ms Nov 01 08:12:52 volumio volumio[944]: info: ------------------------------ 5ms Nov 01 08:12:52 volumio volumio[944]: info: sendMpdCommand status took 5 milliseconds Nov 01 08:12:52 volumio volumio[944]: verbose: ControllerMpd::parseState Nov 01 08:12:52 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 01 08:12:52 volumio volumio[944]: info: Nov 01 08:12:52 volumio volumio[944]: ---------------------------- MPD announces state update: player Nov 01 08:12:52 volumio volumio[944]: info: ControllerMpd::getState Nov 01 08:12:52 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand status Nov 01 08:12:52 volumio volumio[944]: info: Nov 01 08:12:52 volumio volumio[944]: ---------------------------- MPD announces state update: player Nov 01 08:12:52 volumio volumio[944]: info: ControllerMpd::getState Nov 01 08:12:52 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand status Nov 01 08:12:52 volumio volumio[944]: info: sendMpdCommand playlistinfo took 5 milliseconds Nov 01 08:12:52 volumio volumio[944]: info: sendMpdCommand status took 4 milliseconds Nov 01 08:12:52 volumio volumio[944]: info: sendMpdCommand status took 2 milliseconds Nov 01 08:12:52 volumio volumio[944]: verbose: ControllerMpd::parseTrackInfo Nov 01 08:12:52 volumio volumio[944]: verbose: ControllerMpd::parseState Nov 01 08:12:52 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 01 08:12:52 volumio volumio[944]: verbose: ControllerMpd::parseState Nov 01 08:12:52 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 01 08:12:52 volumio volumio[944]: info: ControllerMpd::pushState Nov 01 08:12:52 volumio volumio[944]: info: CoreCommandRouter::servicePushState Nov 01 08:12:52 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Nov 01 08:12:52 volumio volumio[944]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Nov 01 08:12:52 volumio volumio[944]: info: ------------------------------ 14ms Nov 01 08:12:52 volumio volumio[944]: info: sendMpdCommand playlistinfo took 3 milliseconds Nov 01 08:12:52 volumio volumio[944]: info: sendMpdCommand playlistinfo took 3 milliseconds Nov 01 08:12:52 volumio volumio[944]: verbose: ControllerMpd::parseTrackInfo Nov 01 08:12:52 volumio volumio[944]: verbose: ControllerMpd::parseTrackInfo Nov 01 08:12:52 volumio volumio[944]: info: ControllerMpd::pushState Nov 01 08:12:52 volumio volumio[944]: info: CoreCommandRouter::servicePushState Nov 01 08:12:52 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Nov 01 08:12:52 volumio volumio[944]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Nov 01 08:12:52 volumio volumio[944]: info: ControllerMpd::pushState Nov 01 08:12:52 volumio volumio[944]: info: CoreCommandRouter::servicePushState Nov 01 08:12:52 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Nov 01 08:12:52 volumio volumio[944]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Nov 01 08:12:52 volumio volumio[944]: info: ------------------------------ 13ms Nov 01 08:12:52 volumio volumio[944]: info: ------------------------------ 11ms Nov 01 08:12:52 volumio volumio[944]: info: CoreCommandRouter::volumioGetState Nov 01 08:12:52 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Nov 01 08:13:02 volumio systemd[1]: volumio-hid.service: Scheduled restart job, restart counter is at 593. Nov 01 08:13:02 volumio systemd[1]: Stopped volumio-hid Service. Nov 01 08:13:02 volumio systemd[1]: volumio-hid.service: Consumed 1.413s CPU time. Nov 01 08:13:02 volumio systemd[1]: Started volumio-hid Service. Nov 01 08:13:02 volumio volumio[944]: info: CoreCommandRouter::volumioGetState Nov 01 08:13:02 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Nov 01 08:13:02 volumio volumio[944]: info: Listing playlists Nov 01 08:13:02 volumio volumio[944]: info: Listing playlists Nov 01 08:13:03 volumio <1031>VOLUMIO-HID[12936]: [Volumio] Connect to 'localhost:3000' Nov 01 08:13:03 volumio volumio[944]: verbose: New Socket.io Connection to localhost:3000 from 1 UA: python-requests/2.32.3 Engine version: 3 Transport: polling Total Clients: 7 Nov 01 08:13:03 volumio python3[12936]: Traceback (most recent call last): Nov 01 08:13:03 volumio python3[12936]: File "/home/volumio/volumio-hid/HID.py", line 108, in Volumio Nov 01 08:13:03 volumio python3[12936]: volumioIO = SocketIO(server, port, VolumioNamespace, wait_for_connection=False) Nov 01 08:13:03 volumio python3[12936]: File "/home/volumio/.local/lib/python3.9/site-packages/socketIO_client/__init__.py", line 351, in __init__ Nov 01 08:13:03 volumio python3[12936]: super(SocketIO, self).__init__( Nov 01 08:13:03 volumio python3[12936]: File "/home/volumio/.local/lib/python3.9/site-packages/socketIO_client/__init__.py", line 54, in __init__ Nov 01 08:13:03 volumio python3[12936]: self._transport Nov 01 08:13:03 volumio python3[12936]: File "/home/volumio/.local/lib/python3.9/site-packages/socketIO_client/__init__.py", line 62, in _transport Nov 01 08:13:03 volumio python3[12936]: self._engineIO_session = self._get_engineIO_session() Nov 01 08:13:03 volumio python3[12936]: File "/home/volumio/.local/lib/python3.9/site-packages/socketIO_client/__init__.py", line 75, in _get_engineIO_session Nov 01 08:13:03 volumio python3[12936]: engineIO_packet_type, engineIO_packet_data = next( Nov 01 08:13:03 volumio python3[12936]: StopIteration Nov 01 08:13:03 volumio python3[12936]: The above exception was the direct cause of the following exception: Nov 01 08:13:03 volumio python3[12936]: Traceback (most recent call last): Nov 01 08:13:03 volumio python3[12936]: File "/home/volumio/volumio-hid/HID.py", line 627, in Nov 01 08:13:03 volumio python3[12936]: with Volumio(server, port) as socket: Nov 01 08:13:03 volumio python3[12936]: File "/usr/lib/python3.9/contextlib.py", line 117, in __enter__ Nov 01 08:13:03 volumio python3[12936]: return next(self.gen) Nov 01 08:13:03 volumio python3[12936]: RuntimeError: generator raised StopIteration Nov 01 08:13:03 volumio systemd[1]: volumio-hid.service: Main process exited, code=exited, status=1/FAILURE Nov 01 08:13:03 volumio systemd[1]: volumio-hid.service: Failed with result 'exit-code'. Nov 01 08:13:03 volumio systemd[1]: volumio-hid.service: Consumed 1.438s CPU time. Nov 01 08:13:12 volumio volumio[944]: info: CoreCommandRouter::volumioGetState Nov 01 08:13:12 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Nov 01 08:13:13 volumio volumio[944]: info: Nov 01 08:13:13 volumio volumio[944]: ---------------------------- MPD announces system playlist update Nov 01 08:13:13 volumio volumio[944]: info: Ignoring MPD Status Update Nov 01 08:13:13 volumio volumio[944]: info: Nov 01 08:13:13 volumio volumio[944]: ---------------------------- MPD announces state update: player Nov 01 08:13:13 volumio volumio[944]: info: ControllerMpd::getState Nov 01 08:13:13 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand status Nov 01 08:13:13 volumio volumio[944]: info: Nov 01 08:13:13 volumio volumio[944]: ---------------------------- MPD announces system playlist update Nov 01 08:13:13 volumio volumio[944]: info: Ignoring MPD Status Update Nov 01 08:13:13 volumio volumio[944]: info: Nov 01 08:13:13 volumio volumio[944]: ---------------------------- MPD announces state update: player Nov 01 08:13:13 volumio volumio[944]: info: ControllerMpd::getState Nov 01 08:13:13 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand status Nov 01 08:13:13 volumio volumio[944]: info: Nov 01 08:13:13 volumio volumio[944]: ---------------------------- MPD announces system playlist update Nov 01 08:13:13 volumio volumio[944]: info: Ignoring MPD Status Update Nov 01 08:13:13 volumio volumio[944]: info: Nov 01 08:13:13 volumio volumio[944]: ---------------------------- MPD announces state update: player Nov 01 08:13:13 volumio volumio[944]: info: ControllerMpd::getState Nov 01 08:13:13 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand status Nov 01 08:13:13 volumio volumio[944]: info: ------------------------------ 6ms Nov 01 08:13:13 volumio volumio[944]: info: sendMpdCommand status took 6 milliseconds Nov 01 08:13:13 volumio volumio[944]: info: ------------------------------ 5ms Nov 01 08:13:13 volumio volumio[944]: info: sendMpdCommand status took 4 milliseconds Nov 01 08:13:13 volumio volumio[944]: info: ------------------------------ 4ms Nov 01 08:13:13 volumio volumio[944]: info: sendMpdCommand status took 3 milliseconds Nov 01 08:13:13 volumio volumio[944]: verbose: ControllerMpd::parseState Nov 01 08:13:13 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 01 08:13:13 volumio volumio[944]: verbose: ControllerMpd::parseState Nov 01 08:13:13 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 01 08:13:13 volumio volumio[944]: verbose: ControllerMpd::parseState Nov 01 08:13:13 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 01 08:13:13 volumio volumio[944]: info: sendMpdCommand playlistinfo took 4 milliseconds Nov 01 08:13:13 volumio volumio[944]: info: sendMpdCommand playlistinfo took 5 milliseconds Nov 01 08:13:13 volumio volumio[944]: info: sendMpdCommand playlistinfo took 5 milliseconds Nov 01 08:13:13 volumio volumio[944]: verbose: ControllerMpd::parseTrackInfo Nov 01 08:13:13 volumio volumio[944]: verbose: ControllerMpd::parseTrackInfo Nov 01 08:13:13 volumio volumio[944]: verbose: ControllerMpd::parseTrackInfo Nov 01 08:13:13 volumio volumio[944]: info: ControllerMpd::pushState Nov 01 08:13:13 volumio volumio[944]: info: CoreCommandRouter::servicePushState Nov 01 08:13:13 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Nov 01 08:13:13 volumio volumio[944]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Nov 01 08:13:13 volumio volumio[944]: info: ControllerMpd::pushState Nov 01 08:13:13 volumio volumio[944]: info: CoreCommandRouter::servicePushState Nov 01 08:13:13 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Nov 01 08:13:13 volumio volumio[944]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Nov 01 08:13:13 volumio volumio[944]: info: ControllerMpd::pushState Nov 01 08:13:13 volumio volumio[944]: info: CoreCommandRouter::servicePushState Nov 01 08:13:13 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Nov 01 08:13:13 volumio volumio[944]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Nov 01 08:13:13 volumio volumio[944]: info: ------------------------------ 16ms Nov 01 08:13:13 volumio volumio[944]: info: ------------------------------ 14ms Nov 01 08:13:13 volumio volumio[944]: info: ------------------------------ 12ms Nov 01 08:13:13 volumio volumio[944]: info: CoreCommandRouter::volumioGetState Nov 01 08:13:13 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Nov 01 08:13:17 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Nov 01 08:13:17 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Nov 01 08:13:18 volumio volumio[944]: error: Cannot download Available plugins list: Error: got 404 response Nov 01 08:13:22 volumio volumio[944]: info: CoreCommandRouter::volumioGetState Nov 01 08:13:22 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Nov 01 08:13:22 volumio volumio[944]: info: Listing playlists Nov 01 08:13:22 volumio volumio[944]: info: Listing playlists Nov 01 08:13:30 volumio volumio[944]: warn: [ROTARYENCODER2] Push Button 1 signalled "released" without intermediate "pressed". You may be suffering from bouncy buttons. Nov 01 08:13:32 volumio volumio[944]: info: CoreCommandRouter::volumioGetState Nov 01 08:13:32 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Nov 01 08:13:33 volumio systemd[1]: volumio-hid.service: Scheduled restart job, restart counter is at 594. Nov 01 08:13:33 volumio systemd[1]: Stopped volumio-hid Service. Nov 01 08:13:33 volumio systemd[1]: volumio-hid.service: Consumed 1.438s CPU time. Nov 01 08:13:33 volumio systemd[1]: Started volumio-hid Service. Nov 01 08:13:35 volumio <1031>VOLUMIO-HID[13095]: [Volumio] Connect to 'localhost:3000' Nov 01 08:13:35 volumio volumio[944]: verbose: New Socket.io Connection to localhost:3000 from 1 UA: python-requests/2.32.3 Engine version: 3 Transport: polling Total Clients: 7 Nov 01 08:13:35 volumio python3[13095]: Traceback (most recent call last): Nov 01 08:13:35 volumio python3[13095]: File "/home/volumio/volumio-hid/HID.py", line 108, in Volumio Nov 01 08:13:35 volumio python3[13095]: volumioIO = SocketIO(server, port, VolumioNamespace, wait_for_connection=False) Nov 01 08:13:35 volumio python3[13095]: File "/home/volumio/.local/lib/python3.9/site-packages/socketIO_client/__init__.py", line 351, in __init__ Nov 01 08:13:35 volumio python3[13095]: super(SocketIO, self).__init__( Nov 01 08:13:35 volumio python3[13095]: File "/home/volumio/.local/lib/python3.9/site-packages/socketIO_client/__init__.py", line 54, in __init__ Nov 01 08:13:35 volumio python3[13095]: self._transport Nov 01 08:13:35 volumio python3[13095]: File "/home/volumio/.local/lib/python3.9/site-packages/socketIO_client/__init__.py", line 62, in _transport Nov 01 08:13:35 volumio python3[13095]: self._engineIO_session = self._get_engineIO_session() Nov 01 08:13:35 volumio python3[13095]: File "/home/volumio/.local/lib/python3.9/site-packages/socketIO_client/__init__.py", line 75, in _get_engineIO_session Nov 01 08:13:35 volumio python3[13095]: engineIO_packet_type, engineIO_packet_data = next( Nov 01 08:13:35 volumio python3[13095]: StopIteration Nov 01 08:13:35 volumio python3[13095]: The above exception was the direct cause of the following exception: Nov 01 08:13:35 volumio python3[13095]: Traceback (most recent call last): Nov 01 08:13:35 volumio python3[13095]: File "/home/volumio/volumio-hid/HID.py", line 627, in Nov 01 08:13:35 volumio python3[13095]: with Volumio(server, port) as socket: Nov 01 08:13:35 volumio python3[13095]: File "/usr/lib/python3.9/contextlib.py", line 117, in __enter__ Nov 01 08:13:35 volumio python3[13095]: return next(self.gen) Nov 01 08:13:35 volumio python3[13095]: RuntimeError: generator raised StopIteration Nov 01 08:13:35 volumio systemd[1]: volumio-hid.service: Main process exited, code=exited, status=1/FAILURE Nov 01 08:13:35 volumio systemd[1]: volumio-hid.service: Failed with result 'exit-code'. Nov 01 08:13:35 volumio systemd[1]: volumio-hid.service: Consumed 1.440s CPU time. Nov 01 08:13:40 volumio volumio[944]: info: Nov 01 08:13:40 volumio volumio[944]: ---------------------------- MPD announces system playlist update Nov 01 08:13:40 volumio volumio[944]: info: Ignoring MPD Status Update Nov 01 08:13:40 volumio volumio[944]: info: Nov 01 08:13:40 volumio volumio[944]: ---------------------------- MPD announces state update: player Nov 01 08:13:40 volumio volumio[944]: info: ControllerMpd::getState Nov 01 08:13:40 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand status Nov 01 08:13:40 volumio volumio[944]: info: Nov 01 08:13:40 volumio volumio[944]: ---------------------------- MPD announces system playlist update Nov 01 08:13:40 volumio volumio[944]: info: Ignoring MPD Status Update Nov 01 08:13:40 volumio volumio[944]: info: Nov 01 08:13:40 volumio volumio[944]: ---------------------------- MPD announces state update: player Nov 01 08:13:40 volumio volumio[944]: info: ControllerMpd::getState Nov 01 08:13:40 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand status Nov 01 08:13:40 volumio volumio[944]: info: Nov 01 08:13:40 volumio volumio[944]: ---------------------------- MPD announces system playlist update Nov 01 08:13:40 volumio volumio[944]: info: Ignoring MPD Status Update Nov 01 08:13:40 volumio volumio[944]: info: Nov 01 08:13:40 volumio volumio[944]: ---------------------------- MPD announces state update: player Nov 01 08:13:40 volumio volumio[944]: info: ControllerMpd::getState Nov 01 08:13:40 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand status Nov 01 08:13:40 volumio volumio[944]: info: ------------------------------ 7ms Nov 01 08:13:40 volumio volumio[944]: info: sendMpdCommand status took 6 milliseconds Nov 01 08:13:40 volumio volumio[944]: info: ------------------------------ 6ms Nov 01 08:13:40 volumio volumio[944]: info: sendMpdCommand status took 5 milliseconds Nov 01 08:13:40 volumio volumio[944]: info: ------------------------------ 4ms Nov 01 08:13:40 volumio volumio[944]: info: sendMpdCommand status took 3 milliseconds Nov 01 08:13:40 volumio volumio[944]: verbose: ControllerMpd::parseState Nov 01 08:13:40 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 01 08:13:40 volumio volumio[944]: verbose: ControllerMpd::parseState Nov 01 08:13:40 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 01 08:13:40 volumio volumio[944]: verbose: ControllerMpd::parseState Nov 01 08:13:40 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 01 08:13:40 volumio volumio[944]: info: sendMpdCommand playlistinfo took 2 milliseconds Nov 01 08:13:40 volumio volumio[944]: info: sendMpdCommand playlistinfo took 3 milliseconds Nov 01 08:13:40 volumio volumio[944]: info: sendMpdCommand playlistinfo took 3 milliseconds Nov 01 08:13:40 volumio volumio[944]: verbose: ControllerMpd::parseTrackInfo Nov 01 08:13:40 volumio volumio[944]: verbose: ControllerMpd::parseTrackInfo Nov 01 08:13:40 volumio volumio[944]: verbose: ControllerMpd::parseTrackInfo Nov 01 08:13:40 volumio volumio[944]: info: ControllerMpd::pushState Nov 01 08:13:40 volumio volumio[944]: info: CoreCommandRouter::servicePushState Nov 01 08:13:40 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Nov 01 08:13:40 volumio volumio[944]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Nov 01 08:13:40 volumio volumio[944]: info: ControllerMpd::pushState Nov 01 08:13:40 volumio volumio[944]: info: CoreCommandRouter::servicePushState Nov 01 08:13:40 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Nov 01 08:13:40 volumio volumio[944]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Nov 01 08:13:40 volumio volumio[944]: info: ControllerMpd::pushState Nov 01 08:13:40 volumio volumio[944]: info: CoreCommandRouter::servicePushState Nov 01 08:13:40 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Nov 01 08:13:40 volumio volumio[944]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Nov 01 08:13:40 volumio volumio[944]: info: ------------------------------ 14ms Nov 01 08:13:40 volumio volumio[944]: info: ------------------------------ 12ms Nov 01 08:13:40 volumio volumio[944]: info: ------------------------------ 10ms Nov 01 08:13:42 volumio volumio[944]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesYouTube Music Nov 01 08:13:42 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 01 08:13:42 volumio volumio[944]: info: Disabling plugin ytmusic Nov 01 08:13:42 volumio volumio[944]: info: Done. Nov 01 08:13:42 volumio volumio[944]: info: CoreCommandRouter::volumioGetState Nov 01 08:13:42 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Nov 01 08:13:42 volumio volumio[944]: info: Listing playlists Nov 01 08:13:42 volumio volumio[944]: info: Listing playlists Nov 01 08:13:43 volumio sudo[13131]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l Nov 01 08:13:43 volumio sudo[13131]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 01 08:13:43 volumio sudo[13131]: pam_unix(sudo:session): session closed for user root Nov 01 08:13:43 volumio sudo[13134]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -r 1 Nov 01 08:13:43 volumio sudo[13134]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 01 08:13:44 volumio kernel: OF: ERROR: memory leak, expected refcount 1 instead of 2, of_node_get()/of_node_put() unbalanced - destroy cset entry: attach overlay node /rotary@d Nov 01 08:13:44 volumio kernel: OF: ERROR: memory leak before free overlay changeset, /rotary@d Nov 01 08:13:44 volumio sudo[13134]: pam_unix(sudo:session): session closed for user root Nov 01 08:13:44 volumio sudo[13152]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l Nov 01 08:13:44 volumio sudo[13152]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 01 08:13:44 volumio sudo[13152]: pam_unix(sudo:session): session closed for user root Nov 01 08:13:44 volumio sudo[13155]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l Nov 01 08:13:44 volumio sudo[13155]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 01 08:13:44 volumio sudo[13155]: pam_unix(sudo:session): session closed for user root Nov 01 08:13:44 volumio sudo[13158]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -r 0 Nov 01 08:13:44 volumio sudo[13158]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 01 08:13:44 volumio kernel: OF: ERROR: memory leak, expected refcount 1 instead of 2, of_node_get()/of_node_put() unbalanced - destroy cset entry: attach overlay node /rotary@17 Nov 01 08:13:44 volumio sudo[13158]: pam_unix(sudo:session): session closed for user root Nov 01 08:13:44 volumio kernel: OF: ERROR: memory leak before free overlay changeset, /rotary@17 Nov 01 08:13:44 volumio sudo[13169]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l Nov 01 08:13:44 volumio sudo[13169]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 01 08:13:44 volumio sudo[13169]: pam_unix(sudo:session): session closed for user root Nov 01 08:13:44 volumio volumio[944]: info: Disabling plugin rotaryencoder2 Nov 01 08:13:44 volumio volumio[944]: info: Done. Nov 01 08:13:44 volumio volumio[944]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 01 08:13:44 volumio volumio[944]: Error: EBADF: bad file descriptor, read Nov 01 08:13:44 volumio volumio[944]: at Object.readSync (fs.js:592:3) Nov 01 08:13:44 volumio volumio[944]: at Gpio.readSync (/data/plugins/system_hardware/rotaryencoder2/node_modules/onoff/onoff.js:214:8) Nov 01 08:13:44 volumio volumio[944]: at pollerEventHandler (/data/plugins/system_hardware/rotaryencoder2/node_modules/onoff/onoff.js:130:24) Nov 01 08:13:44 volumio volumio[944]: at invokeFunc (/data/plugins/system_hardware/rotaryencoder2/node_modules/lodash.debounce/index.js:160:19) Nov 01 08:13:44 volumio volumio[944]: at trailingEdge (/data/plugins/system_hardware/rotaryencoder2/node_modules/lodash.debounce/index.js:207:14) Nov 01 08:13:44 volumio volumio[944]: at Timeout.timerExpired [as _onTimeout] (/data/plugins/system_hardware/rotaryencoder2/node_modules/lodash.debounce/index.js:195:14) Nov 01 08:13:44 volumio volumio[944]: at listOnTimeout (internal/timers.js:554:17) Nov 01 08:13:44 volumio volumio[944]: at processTimers (internal/timers.js:497:7) { Nov 01 08:13:44 volumio volumio[944]: errno: -9, Nov 01 08:13:44 volumio volumio[944]: syscall: 'read', Nov 01 08:13:44 volumio volumio[944]: code: 'EBADF' Nov 01 08:13:44 volumio volumio[944]: } Nov 01 08:13:44 volumio volumio[944]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 01 08:13:45 volumio sudo[13180]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-11-01 08:12 Nov 01 08:13:45 volumio sudo[13180]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 11 (bullseye)" NAME="Raspbian GNU/Linux" VERSION_ID="11" VERSION="11 (bullseye)" VERSION_CODENAME=bullseye ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs"