-- Logs begin at Mon 2024-06-10 23:57:47 EDT, end at Mon 2024-08-26 16:30:18 EDT. -- Aug 26 16:29:00 volumio volumio[1154]: info: CoreCommandRouter::volumioGetState Aug 26 16:29:00 volumio volumio[1154]: info: CorePlayQueue::getTrack 0 Aug 26 16:29:00 volumio volumio[1154]: info: CoreCommandRouter::volumioGetQueue Aug 26 16:29:00 volumio volumio[1154]: info: CoreStateMachine::getQueue Aug 26 16:29:00 volumio volumio[1154]: info: CorePlayQueue::getQueue Aug 26 16:29:01 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 26 16:29:01 volumio volumio[1154]: info: CoreCommandRouter::volumioGetState Aug 26 16:29:01 volumio volumio[1154]: info: CorePlayQueue::getTrack 0 Aug 26 16:29:01 volumio volumio[1154]: info: CoreCommandRouter::volumioGetQueue Aug 26 16:29:01 volumio volumio[1154]: info: CoreStateMachine::getQueue Aug 26 16:29:01 volumio volumio[1154]: info: CorePlayQueue::getQueue Aug 26 16:29:02 volumio volumio[1154]: info: CoreCommandRouter::volumioGetState Aug 26 16:29:02 volumio volumio[1154]: info: CorePlayQueue::getTrack 0 Aug 26 16:29:02 volumio volumio[1154]: info: CoreCommandRouter::volumioGetQueue Aug 26 16:29:02 volumio volumio[1154]: info: CoreStateMachine::getQueue Aug 26 16:29:02 volumio volumio[1154]: info: CorePlayQueue::getQueue Aug 26 16:29:03 volumio volumio[1154]: info: CoreCommandRouter::volumioGetState Aug 26 16:29:03 volumio volumio[1154]: info: CorePlayQueue::getTrack 0 Aug 26 16:29:03 volumio volumio[1154]: info: CoreCommandRouter::volumioGetState Aug 26 16:29:03 volumio volumio[1154]: info: CorePlayQueue::getTrack 0 Aug 26 16:29:03 volumio volumio[1154]: info: CoreCommandRouter::volumioGetQueue Aug 26 16:29:03 volumio volumio[1154]: info: CoreStateMachine::getQueue Aug 26 16:29:03 volumio volumio[1154]: info: CorePlayQueue::getQueue Aug 26 16:29:03 volumio kernel: spidev spi0.1: SPI transfer timed out Aug 26 16:29:03 volumio kernel: spi_master spi0: failed to transfer one message from queue Aug 26 16:29:03 volumio kernel: spi_master spi0: noqueue transfer failed Aug 26 16:29:05 volumio volumio[1154]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 26 16:29:05 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Aug 26 16:29:05 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 16:29:05 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 26 16:29:05 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 26 16:29:05 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 26 16:29:05 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 26 16:29:05 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 26 16:29:05 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 26 16:29:05 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 26 16:29:05 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 26 16:29:05 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Aug 26 16:29:05 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Aug 26 16:29:07 volumio ntpd[881]: 208.81.1.244 local addr 192.168.1.231 -> Aug 26 16:29:08 volumio ntpd[881]: 45.61.49.156 local addr 192.168.1.231 -> Aug 26 16:29:08 volumio volumio[1154]: info: CoreCommandRouter::volumioGetState Aug 26 16:29:08 volumio volumio[1154]: info: CorePlayQueue::getTrack 0 Aug 26 16:29:08 volumio volumio[1154]: info: CoreCommandRouter::volumioGetState Aug 26 16:29:08 volumio volumio[1154]: info: CorePlayQueue::getTrack 0 Aug 26 16:29:08 volumio volumio[1154]: info: CoreCommandRouter::volumioGetQueue Aug 26 16:29:08 volumio volumio[1154]: info: CoreStateMachine::getQueue Aug 26 16:29:08 volumio volumio[1154]: info: CorePlayQueue::getQueue Aug 26 16:29:08 volumio kernel: spidev spi0.1: SPI transfer timed out Aug 26 16:29:08 volumio kernel: spi_master spi0: failed to transfer one message from queue Aug 26 16:29:08 volumio kernel: spi_master spi0: noqueue transfer failed Aug 26 16:29:09 volumio volumio[1154]: info: CoreCommandRouter::volumioGetState Aug 26 16:29:09 volumio volumio[1154]: info: CorePlayQueue::getTrack 0 Aug 26 16:29:09 volumio volumio[1154]: info: CoreCommandRouter::volumioGetQueue Aug 26 16:29:09 volumio volumio[1154]: info: CoreStateMachine::getQueue Aug 26 16:29:09 volumio volumio[1154]: info: CorePlayQueue::getQueue Aug 26 16:29:10 volumio volumio[1154]: info: CoreCommandRouter::volumioGetState Aug 26 16:29:10 volumio volumio[1154]: info: CorePlayQueue::getTrack 0 Aug 26 16:29:10 volumio volumio[1154]: info: CoreCommandRouter::volumioGetQueue Aug 26 16:29:10 volumio volumio[1154]: info: CoreStateMachine::getQueue Aug 26 16:29:10 volumio volumio[1154]: info: CorePlayQueue::getQueue Aug 26 16:29:11 volumio volumio[1154]: info: CoreCommandRouter::volumioGetState Aug 26 16:29:11 volumio volumio[1154]: info: CorePlayQueue::getTrack 0 Aug 26 16:29:11 volumio volumio[1154]: info: CoreCommandRouter::volumioGetQueue Aug 26 16:29:11 volumio volumio[1154]: info: CoreStateMachine::getQueue Aug 26 16:29:11 volumio volumio[1154]: info: CorePlayQueue::getQueue Aug 26 16:29:12 volumio volumio[1154]: info: CoreCommandRouter::volumioGetState Aug 26 16:29:12 volumio volumio[1154]: info: CorePlayQueue::getTrack 0 Aug 26 16:29:12 volumio volumio[1154]: info: CoreCommandRouter::volumioGetQueue Aug 26 16:29:12 volumio volumio[1154]: info: CoreStateMachine::getQueue Aug 26 16:29:12 volumio volumio[1154]: info: CorePlayQueue::getQueue Aug 26 16:29:13 volumio volumio[1154]: info: CoreCommandRouter::volumioGetState Aug 26 16:29:13 volumio volumio[1154]: info: CorePlayQueue::getTrack 0 Aug 26 16:29:13 volumio volumio[1154]: info: CoreCommandRouter::volumioGetState Aug 26 16:29:13 volumio volumio[1154]: info: CorePlayQueue::getTrack 0 Aug 26 16:29:13 volumio volumio[1154]: info: CoreCommandRouter::volumioGetQueue Aug 26 16:29:13 volumio volumio[1154]: info: CoreStateMachine::getQueue Aug 26 16:29:13 volumio volumio[1154]: info: CorePlayQueue::getQueue Aug 26 16:29:13 volumio kernel: spidev spi0.1: SPI transfer timed out Aug 26 16:29:13 volumio kernel: spi_master spi0: failed to transfer one message from queue Aug 26 16:29:13 volumio kernel: spi_master spi0: noqueue transfer failed Aug 26 16:29:18 volumio volumio[1154]: info: CoreCommandRouter::volumioGetState Aug 26 16:29:18 volumio volumio[1154]: info: CorePlayQueue::getTrack 0 Aug 26 16:29:18 volumio volumio[1154]: info: CoreCommandRouter::volumioGetState Aug 26 16:29:18 volumio volumio[1154]: info: CorePlayQueue::getTrack 0 Aug 26 16:29:18 volumio volumio[1154]: info: CoreCommandRouter::volumioGetQueue Aug 26 16:29:18 volumio volumio[1154]: info: CoreStateMachine::getQueue Aug 26 16:29:18 volumio volumio[1154]: info: CorePlayQueue::getQueue Aug 26 16:29:18 volumio volumio[1362]: Exception in thread Thread-169: Aug 26 16:29:18 volumio volumio[1362]: Traceback (most recent call last): Aug 26 16:29:18 volumio volumio[1362]: File "/usr/lib/python3.7/threading.py", line 917, in _bootstrap_inner Aug 26 16:29:18 volumio volumio[1362]: self.run() Aug 26 16:29:18 volumio volumio[1362]: File "/usr/lib/python3.7/threading.py", line 865, in run Aug 26 16:29:18 volumio volumio[1362]: self._target(*self._args, **self._kwargs) Aug 26 16:29:18 volumio volumio[1362]: File "/usr/local/lib/python3.7/dist-packages/socketio/client.py", line 621, in _handle_eio_message Aug 26 16:29:18 volumio volumio[1362]: self._handle_event(pkt.namespace, pkt.id, pkt.data) Aug 26 16:29:18 volumio volumio[1362]: File "/usr/local/lib/python3.7/dist-packages/socketio/client.py", line 505, in _handle_event Aug 26 16:29:18 volumio volumio[1362]: r = self._trigger_event(data[0], namespace, *data[1:]) Aug 26 16:29:18 volumio volumio[1362]: File "/usr/local/lib/python3.7/dist-packages/socketio/client.py", line 555, in _trigger_event Aug 26 16:29:18 volumio volumio[1362]: return self.handlers[namespace][event](*args) Aug 26 16:29:18 volumio volumio[1362]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 514, in on_push_state Aug 26 16:29:18 volumio volumio[1362]: sendtodisplay(IMAGE_DICT['IMG']) Aug 26 16:29:18 volumio volumio[1362]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 212, in sendtodisplay Aug 26 16:29:18 volumio volumio[1362]: DISP.display(img4) Aug 26 16:29:18 volumio volumio[1362]: File "/usr/local/lib/python3.7/dist-packages/ST7789/__init__.py", line 343, in display Aug 26 16:29:18 volumio volumio[1362]: self.data(pixelbytes[i:i + 4096]) Aug 26 16:29:18 volumio volumio[1362]: File "/usr/local/lib/python3.7/dist-packages/ST7789/__init__.py", line 191, in data Aug 26 16:29:18 volumio volumio[1362]: self.send(data, True) Aug 26 16:29:18 volumio volumio[1362]: File "/usr/local/lib/python3.7/dist-packages/ST7789/__init__.py", line 170, in send Aug 26 16:29:18 volumio volumio[1362]: self._spi.xfer(data[start:end]) Aug 26 16:29:18 volumio volumio[1362]: TimeoutError: [Errno 110] Connection timed out Aug 26 16:29:18 volumio volumio[1362]: Exception in thread Thread-171: Aug 26 16:29:18 volumio volumio[1362]: Traceback (most recent call last): Aug 26 16:29:18 volumio volumio[1362]: File "/usr/lib/python3.7/threading.py", line 917, in _bootstrap_inner Aug 26 16:29:18 volumio volumio[1362]: self.run() Aug 26 16:29:18 volumio volumio[1362]: File "/usr/lib/python3.7/threading.py", line 865, in run Aug 26 16:29:18 volumio volumio[1362]: self._target(*self._args, **self._kwargs) Aug 26 16:29:18 volumio volumio[1362]: File "/usr/local/lib/python3.7/dist-packages/socketio/client.py", line 621, in _handle_eio_message Aug 26 16:29:18 volumio volumio[1362]: self._handle_event(pkt.namespace, pkt.id, pkt.data) Aug 26 16:29:18 volumio volumio[1362]: File "/usr/local/lib/python3.7/dist-packages/socketio/client.py", line 505, in _handle_event Aug 26 16:29:18 volumio volumio[1362]: r = self._trigger_event(data[0], namespace, *data[1:]) Aug 26 16:29:18 volumio volumio[1362]: File "/usr/local/lib/python3.7/dist-packages/socketio/client.py", line 555, in _trigger_event Aug 26 16:29:18 volumio volumio[1362]: return self.handlers[namespace][event](*args) Aug 26 16:29:18 volumio volumio[1362]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 514, in on_push_state Aug 26 16:29:18 volumio volumio[1362]: sendtodisplay(IMAGE_DICT['IMG']) Aug 26 16:29:18 volumio volumio[1362]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 212, in sendtodisplay Aug 26 16:29:18 volumio volumio[1362]: DISP.display(img4) Aug 26 16:29:18 volumio volumio[1362]: File "/usr/local/lib/python3.7/dist-packages/ST7789/__init__.py", line 343, in display Aug 26 16:29:18 volumio volumio[1362]: self.data(pixelbytes[i:i + 4096]) Aug 26 16:29:18 volumio volumio[1362]: File "/usr/local/lib/python3.7/dist-packages/ST7789/__init__.py", line 191, in data Aug 26 16:29:18 volumio volumio[1362]: self.send(data, True) Aug 26 16:29:18 volumio volumio[1362]: File "/usr/local/lib/python3.7/dist-packages/ST7789/__init__.py", line 170, in send Aug 26 16:29:18 volumio volumio[1362]: self._spi.xfer(data[start:end]) Aug 26 16:29:18 volumio volumio[1362]: TimeoutError: [Errno 110] Connection timed out Aug 26 16:29:18 volumio volumio[1362]: Exception in thread Thread-173: Aug 26 16:29:18 volumio volumio[1362]: Traceback (most recent call last): Aug 26 16:29:18 volumio volumio[1362]: File "/usr/lib/python3.7/threading.py", line 917, in _bootstrap_inner Aug 26 16:29:18 volumio volumio[1362]: self.run() Aug 26 16:29:18 volumio volumio[1362]: File "/usr/lib/python3.7/threading.py", line 865, in run Aug 26 16:29:18 volumio volumio[1362]: self._target(*self._args, **self._kwargs) Aug 26 16:29:18 volumio volumio[1362]: File "/usr/local/lib/python3.7/dist-packages/socketio/client.py", line 621, in _handle_eio_message Aug 26 16:29:18 volumio volumio[1362]: self._handle_event(pkt.namespace, pkt.id, pkt.data) Aug 26 16:29:18 volumio volumio[1362]: File "/usr/local/lib/python3.7/dist-packages/socketio/client.py", line 505, in _handle_event Aug 26 16:29:18 volumio volumio[1362]: r = self._trigger_event(data[0], namespace, *data[1:]) Aug 26 16:29:18 volumio volumio[1362]: File "/usr/local/lib/python3.7/dist-packages/socketio/client.py", line 555, in _trigger_event Aug 26 16:29:18 volumio volumio[1362]: return self.handlers[namespace][event](*args) Aug 26 16:29:18 volumio volumio[1362]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 514, in on_push_state Aug 26 16:29:18 volumio volumio[1362]: sendtodisplay(IMAGE_DICT['IMG']) Aug 26 16:29:18 volumio volumio[1362]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 212, in sendtodisplay Aug 26 16:29:18 volumio volumio[1362]: DISP.display(img4) Aug 26 16:29:18 volumio volumio[1362]: File "/usr/local/lib/python3.7/dist-packages/ST7789/__init__.py", line 343, in display Aug 26 16:29:18 volumio volumio[1362]: self.data(pixelbytes[i:i + 4096]) Aug 26 16:29:18 volumio volumio[1362]: File "/usr/local/lib/python3.7/dist-packages/ST7789/__init__.py", line 191, in data Aug 26 16:29:18 volumio volumio[1362]: self.send(data, True) Aug 26 16:29:18 volumio volumio[1362]: File "/usr/local/lib/python3.7/dist-packages/ST7789/__init__.py", line 170, in send Aug 26 16:29:18 volumio volumio[1362]: self._spi.xfer(data[start:end]) Aug 26 16:29:18 volumio volumio[1362]: TimeoutError: [Errno 110] Connection timed out Aug 26 16:29:18 volumio volumio[1362]: Exception in thread Thread-175: Aug 26 16:29:18 volumio volumio[1362]: Traceback (most recent call last): Aug 26 16:29:18 volumio volumio[1362]: File "/usr/lib/python3.7/threading.py", line 917, in _bootstrap_inner Aug 26 16:29:18 volumio volumio[1362]: self.run() Aug 26 16:29:18 volumio volumio[1362]: File "/usr/lib/python3.7/threading.py", line 865, in run Aug 26 16:29:18 volumio volumio[1362]: self._target(*self._args, **self._kwargs) Aug 26 16:29:18 volumio volumio[1362]: File "/usr/local/lib/python3.7/dist-packages/socketio/client.py", line 621, in _handle_eio_message Aug 26 16:29:18 volumio volumio[1362]: self._handle_event(pkt.namespace, pkt.id, pkt.data) Aug 26 16:29:18 volumio volumio[1362]: File "/usr/local/lib/python3.7/dist-packages/socketio/client.py", line 505, in _handle_event Aug 26 16:29:18 volumio volumio[1362]: r = self._trigger_event(data[0], namespace, *data[1:]) Aug 26 16:29:18 volumio volumio[1362]: File "/usr/local/lib/python3.7/dist-packages/socketio/client.py", line 555, in _trigger_event Aug 26 16:29:18 volumio volumio[1362]: return self.handlers[namespace][event](*args) Aug 26 16:29:18 volumio volumio[1362]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 514, in on_push_state Aug 26 16:29:18 volumio volumio[1362]: sendtodisplay(IMAGE_DICT['IMG']) Aug 26 16:29:18 volumio volumio[1362]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 212, in sendtodisplay Aug 26 16:29:18 volumio volumio[1362]: DISP.display(img4) Aug 26 16:29:18 volumio volumio[1362]: File "/usr/local/lib/python3.7/dist-packages/ST7789/__init__.py", line 343, in display Aug 26 16:29:18 volumio volumio[1362]: self.data(pixelbytes[i:i + 4096]) Aug 26 16:29:18 volumio volumio[1362]: File "/usr/local/lib/python3.7/dist-packages/ST7789/__init__.py", line 191, in data Aug 26 16:29:18 volumio volumio[1362]: self.send(data, True) Aug 26 16:29:18 volumio volumio[1362]: File "/usr/local/lib/python3.7/dist-packages/ST7789/__init__.py", line 170, in send Aug 26 16:29:18 volumio volumio[1362]: self._spi.xfer(data[start:end]) Aug 26 16:29:18 volumio volumio[1362]: TimeoutError: [Errno 110] Connection timed out Aug 26 16:29:18 volumio volumio[1362]: Exception in thread Thread-177: Aug 26 16:29:18 volumio volumio[1362]: Traceback (most recent call last): Aug 26 16:29:18 volumio volumio[1362]: File "/usr/lib/python3.7/threading.py", line 917, in _bootstrap_inner Aug 26 16:29:18 volumio volumio[1362]: self.run() Aug 26 16:29:18 volumio volumio[1362]: File "/usr/lib/python3.7/threading.py", line 865, in run Aug 26 16:29:18 volumio volumio[1362]: self._target(*self._args, **self._kwargs) Aug 26 16:29:18 volumio volumio[1362]: File "/usr/local/lib/python3.7/dist-packages/socketio/client.py", line 621, in _handle_eio_message Aug 26 16:29:18 volumio volumio[1362]: self._handle_event(pkt.namespace, pkt.id, pkt.data) Aug 26 16:29:18 volumio volumio[1362]: File "/usr/local/lib/python3.7/dist-packages/socketio/client.py", line 505, in _handle_event Aug 26 16:29:18 volumio volumio[1362]: r = self._trigger_event(data[0], namespace, *data[1:]) Aug 26 16:29:18 volumio volumio[1362]: File "/usr/local/lib/python3.7/dist-packages/socketio/client.py", line 555, in _trigger_event Aug 26 16:29:18 volumio volumio[1362]: return self.handlers[namespace][event](*args) Aug 26 16:29:18 volumio volumio[1362]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 514, in on_push_state Aug 26 16:29:18 volumio volumio[1362]: sendtodisplay(IMAGE_DICT['IMG']) Aug 26 16:29:18 volumio volumio[1362]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 212, in sendtodisplay Aug 26 16:29:18 volumio volumio[1362]: DISP.display(img4) Aug 26 16:29:18 volumio volumio[1362]: File "/usr/local/lib/python3.7/dist-packages/ST7789/__init__.py", line 343, in display Aug 26 16:29:18 volumio volumio[1362]: self.data(pixelbytes[i:i + 4096]) Aug 26 16:29:18 volumio volumio[1362]: File "/usr/local/lib/python3.7/dist-packages/ST7789/__init__.py", line 191, in data Aug 26 16:29:18 volumio volumio[1362]: self.send(data, True) Aug 26 16:29:18 volumio volumio[1362]: File "/usr/local/lib/python3.7/dist-packages/ST7789/__init__.py", line 170, in send Aug 26 16:29:18 volumio volumio[1362]: self._spi.xfer(data[start:end]) Aug 26 16:29:18 volumio volumio[1362]: TimeoutError: [Errno 110] Connection timed out Aug 26 16:29:18 volumio volumio[1362]: Exception in thread Thread-179: Aug 26 16:29:18 volumio volumio[1362]: Traceback (most recent call last): Aug 26 16:29:18 volumio volumio[1362]: File "/usr/lib/python3.7/threading.py", line 917, in _bootstrap_inner Aug 26 16:29:18 volumio volumio[1362]: self.run() Aug 26 16:29:18 volumio volumio[1362]: File "/usr/lib/python3.7/threading.py", line 865, in run Aug 26 16:29:18 volumio volumio[1362]: self._target(*self._args, **self._kwargs) Aug 26 16:29:18 volumio volumio[1362]: File "/usr/local/lib/python3.7/dist-packages/socketio/client.py", line 621, in _handle_eio_message Aug 26 16:29:18 volumio volumio[1362]: self._handle_event(pkt.namespace, pkt.id, pkt.data) Aug 26 16:29:18 volumio volumio[1362]: File "/usr/local/lib/python3.7/dist-packages/socketio/client.py", line 505, in _handle_event Aug 26 16:29:18 volumio volumio[1362]: r = self._trigger_event(data[0], namespace, *data[1:]) Aug 26 16:29:18 volumio volumio[1362]: File "/usr/local/lib/python3.7/dist-packages/socketio/client.py", line 555, in _trigger_event Aug 26 16:29:18 volumio volumio[1362]: return self.handlers[namespace][event](*args) Aug 26 16:29:18 volumio volumio[1362]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 514, in on_push_state Aug 26 16:29:18 volumio volumio[1362]: sendtodisplay(IMAGE_DICT['IMG']) Aug 26 16:29:18 volumio volumio[1362]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 212, in sendtodisplay Aug 26 16:29:18 volumio volumio[1362]: DISP.display(img4) Aug 26 16:29:18 volumio volumio[1362]: File "/usr/local/lib/python3.7/dist-packages/ST7789/__init__.py", line 343, in display Aug 26 16:29:18 volumio volumio[1362]: self.data(pixelbytes[i:i + 4096]) Aug 26 16:29:18 volumio volumio[1362]: File "/usr/local/lib/python3.7/dist-packages/ST7789/__init__.py", line 191, in data Aug 26 16:29:18 volumio volumio[1362]: self.send(data, True) Aug 26 16:29:18 volumio kernel: spidev spi0.1: SPI transfer timed out Aug 26 16:29:18 volumio kernel: spi_master spi0: failed to transfer one message from queue Aug 26 16:29:18 volumio kernel: spi_master spi0: noqueue transfer failed Aug 26 16:29:18 volumio volumio[1362]: File "/usr/local/lib/python3.7/dist-packages/ST7789/__init__.py", line 170, in send Aug 26 16:29:18 volumio volumio[1362]: self._spi.xfer(data[start:end]) Aug 26 16:29:18 volumio volumio[1362]: TimeoutError: [Errno 110] Connection timed out Aug 26 16:29:18 volumio volumio[1362]: Exception in thread Thread-181: Aug 26 16:29:18 volumio volumio[1362]: Traceback (most recent call last): Aug 26 16:29:18 volumio volumio[1362]: File "/usr/lib/python3.7/threading.py", line 917, in _bootstrap_inner Aug 26 16:29:18 volumio volumio[1362]: self.run() Aug 26 16:29:18 volumio volumio[1362]: File "/usr/lib/python3.7/threading.py", line 865, in run Aug 26 16:29:18 volumio volumio[1362]: self._target(*self._args, **self._kwargs) Aug 26 16:29:18 volumio volumio[1362]: File "/usr/local/lib/python3.7/dist-packages/socketio/client.py", line 621, in _handle_eio_message Aug 26 16:29:18 volumio volumio[1362]: self._handle_event(pkt.namespace, pkt.id, pkt.data) Aug 26 16:29:18 volumio volumio[1362]: File "/usr/local/lib/python3.7/dist-packages/socketio/client.py", line 505, in _handle_event Aug 26 16:29:18 volumio volumio[1362]: r = self._trigger_event(data[0], namespace, *data[1:]) Aug 26 16:29:18 volumio volumio[1362]: File "/usr/local/lib/python3.7/dist-packages/socketio/client.py", line 555, in _trigger_event Aug 26 16:29:18 volumio volumio[1362]: return self.handlers[namespace][event](*args) Aug 26 16:29:18 volumio volumio[1362]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 514, in on_push_state Aug 26 16:29:18 volumio volumio[1362]: sendtodisplay(IMAGE_DICT['IMG']) Aug 26 16:29:18 volumio volumio[1362]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 212, in sendtodisplay Aug 26 16:29:18 volumio volumio[1362]: DISP.display(img4) Aug 26 16:29:18 volumio volumio[1362]: File "/usr/local/lib/python3.7/dist-packages/ST7789/__init__.py", line 343, in display Aug 26 16:29:18 volumio volumio[1362]: self.data(pixelbytes[i:i + 4096]) Aug 26 16:29:18 volumio volumio[1362]: File "/usr/local/lib/python3.7/dist-packages/ST7789/__init__.py", line 191, in data Aug 26 16:29:18 volumio volumio[1362]: self.send(data, True) Aug 26 16:29:18 volumio volumio[1362]: File "/usr/local/lib/python3.7/dist-packages/ST7789/__init__.py", line 170, in send Aug 26 16:29:18 volumio volumio[1362]: self._spi.xfer(data[start:end]) Aug 26 16:29:18 volumio volumio[1362]: TimeoutError: [Errno 110] Connection timed out Aug 26 16:29:19 volumio volumio[1154]: info: CoreCommandRouter::volumioGetState Aug 26 16:29:19 volumio volumio[1154]: info: CorePlayQueue::getTrack 0 Aug 26 16:29:19 volumio volumio[1154]: info: CoreCommandRouter::volumioGetQueue Aug 26 16:29:19 volumio volumio[1154]: info: CoreStateMachine::getQueue Aug 26 16:29:19 volumio volumio[1154]: info: CorePlayQueue::getQueue Aug 26 16:29:20 volumio volumio[1154]: info: CoreCommandRouter::volumioGetState Aug 26 16:29:20 volumio volumio[1154]: info: CorePlayQueue::getTrack 0 Aug 26 16:29:20 volumio volumio[1154]: info: CoreCommandRouter::volumioGetQueue Aug 26 16:29:20 volumio volumio[1154]: info: CoreStateMachine::getQueue Aug 26 16:29:20 volumio volumio[1154]: info: CorePlayQueue::getQueue Aug 26 16:29:21 volumio volumio[1154]: info: CoreCommandRouter::volumioGetState Aug 26 16:29:21 volumio volumio[1154]: info: CorePlayQueue::getTrack 0 Aug 26 16:29:21 volumio volumio[1154]: info: CoreCommandRouter::volumioGetQueue Aug 26 16:29:21 volumio volumio[1154]: info: CoreStateMachine::getQueue Aug 26 16:29:21 volumio volumio[1154]: info: CorePlayQueue::getQueue Aug 26 16:29:22 volumio volumio[1154]: info: CoreCommandRouter::volumioGetState Aug 26 16:29:22 volumio volumio[1154]: info: CorePlayQueue::getTrack 0 Aug 26 16:29:22 volumio volumio[1154]: info: CoreCommandRouter::volumioGetQueue Aug 26 16:29:22 volumio volumio[1154]: info: CoreStateMachine::getQueue Aug 26 16:29:22 volumio volumio[1154]: info: CorePlayQueue::getQueue Aug 26 16:29:23 volumio volumio[1154]: info: CoreCommandRouter::volumioGetState Aug 26 16:29:23 volumio volumio[1154]: info: CorePlayQueue::getTrack 0 Aug 26 16:29:23 volumio volumio[1154]: info: CoreCommandRouter::volumioGetState Aug 26 16:29:23 volumio volumio[1154]: info: CorePlayQueue::getTrack 0 Aug 26 16:29:23 volumio volumio[1154]: info: CoreCommandRouter::volumioGetQueue Aug 26 16:29:23 volumio volumio[1154]: info: CoreStateMachine::getQueue Aug 26 16:29:23 volumio volumio[1154]: info: CorePlayQueue::getQueue Aug 26 16:29:24 volumio volumio[1154]: info: CoreCommandRouter::volumioGetState Aug 26 16:29:24 volumio volumio[1154]: info: CorePlayQueue::getTrack 0 Aug 26 16:29:24 volumio volumio[1154]: info: CoreCommandRouter::volumioGetQueue Aug 26 16:29:24 volumio volumio[1154]: info: CoreStateMachine::getQueue Aug 26 16:29:24 volumio volumio[1154]: info: CorePlayQueue::getQueue Aug 26 16:29:25 volumio volumio[1154]: info: CoreCommandRouter::volumioGetState Aug 26 16:29:25 volumio volumio[1154]: info: CorePlayQueue::getTrack 0 Aug 26 16:29:25 volumio volumio[1154]: info: CoreCommandRouter::volumioGetQueue Aug 26 16:29:25 volumio volumio[1154]: info: CoreStateMachine::getQueue Aug 26 16:29:25 volumio volumio[1154]: info: CorePlayQueue::getQueue Aug 26 16:29:26 volumio volumio[1154]: info: CoreCommandRouter::volumioGetState Aug 26 16:29:26 volumio volumio[1154]: info: CorePlayQueue::getTrack 0 Aug 26 16:29:26 volumio volumio[1154]: info: CoreCommandRouter::volumioGetQueue Aug 26 16:29:26 volumio volumio[1154]: info: CoreStateMachine::getQueue Aug 26 16:29:26 volumio volumio[1154]: info: CorePlayQueue::getQueue Aug 26 16:29:27 volumio volumio[1154]: info: CoreCommandRouter::volumioGetState Aug 26 16:29:27 volumio volumio[1154]: info: CorePlayQueue::getTrack 0 Aug 26 16:29:27 volumio volumio[1154]: info: CoreCommandRouter::volumioGetQueue Aug 26 16:29:27 volumio volumio[1154]: info: CoreStateMachine::getQueue Aug 26 16:29:27 volumio volumio[1154]: info: CorePlayQueue::getQueue Aug 26 16:29:28 volumio volumio[1154]: info: CoreCommandRouter::volumioGetState Aug 26 16:29:28 volumio volumio[1154]: info: CorePlayQueue::getTrack 0 Aug 26 16:29:28 volumio volumio[1154]: info: CoreCommandRouter::volumioGetState Aug 26 16:29:28 volumio volumio[1154]: info: CorePlayQueue::getTrack 0 Aug 26 16:29:28 volumio volumio[1154]: info: CoreCommandRouter::volumioGetQueue Aug 26 16:29:28 volumio volumio[1154]: info: CoreStateMachine::getQueue Aug 26 16:29:28 volumio volumio[1154]: info: CorePlayQueue::getQueue Aug 26 16:29:28 volumio kernel: spidev spi0.1: SPI transfer timed out Aug 26 16:29:28 volumio kernel: spi_master spi0: failed to transfer one message from queue Aug 26 16:29:28 volumio kernel: spi_master spi0: noqueue transfer failed Aug 26 16:29:29 volumio volumio[1154]: info: CoreCommandRouter::volumioGetState Aug 26 16:29:29 volumio volumio[1154]: info: CorePlayQueue::getTrack 0 Aug 26 16:29:29 volumio volumio[1154]: info: CoreCommandRouter::volumioGetQueue Aug 26 16:29:29 volumio volumio[1154]: info: CoreStateMachine::getQueue Aug 26 16:29:29 volumio volumio[1154]: info: CorePlayQueue::getQueue Aug 26 16:29:30 volumio volumio[1154]: info: CoreCommandRouter::volumioGetState Aug 26 16:29:30 volumio volumio[1154]: info: CorePlayQueue::getTrack 0 Aug 26 16:29:30 volumio volumio[1154]: info: CoreCommandRouter::volumioGetQueue Aug 26 16:29:30 volumio volumio[1154]: info: CoreStateMachine::getQueue Aug 26 16:29:30 volumio volumio[1154]: info: CorePlayQueue::getQueue Aug 26 16:29:31 volumio volumio[1154]: info: CoreCommandRouter::volumioGetState Aug 26 16:29:31 volumio volumio[1154]: info: CorePlayQueue::getTrack 0 Aug 26 16:29:31 volumio volumio[1154]: info: CoreCommandRouter::volumioGetQueue Aug 26 16:29:31 volumio volumio[1154]: info: CoreStateMachine::getQueue Aug 26 16:29:31 volumio volumio[1154]: info: CorePlayQueue::getQueue Aug 26 16:29:32 volumio volumio[1154]: info: CoreCommandRouter::volumioGetState Aug 26 16:29:32 volumio volumio[1154]: info: CorePlayQueue::getTrack 0 Aug 26 16:29:32 volumio volumio[1154]: info: CoreCommandRouter::volumioGetQueue Aug 26 16:29:32 volumio volumio[1154]: info: CoreStateMachine::getQueue Aug 26 16:29:32 volumio volumio[1154]: info: CorePlayQueue::getQueue Aug 26 16:29:33 volumio volumio[1154]: info: CoreCommandRouter::volumioGetState Aug 26 16:29:33 volumio volumio[1154]: info: CorePlayQueue::getTrack 0 Aug 26 16:29:33 volumio volumio[1154]: info: CoreCommandRouter::volumioGetState Aug 26 16:29:33 volumio volumio[1154]: info: CorePlayQueue::getTrack 0 Aug 26 16:29:33 volumio volumio[1154]: info: CoreCommandRouter::volumioGetQueue Aug 26 16:29:33 volumio volumio[1154]: info: CoreStateMachine::getQueue Aug 26 16:29:33 volumio volumio[1154]: info: CorePlayQueue::getQueue Aug 26 16:29:33 volumio kernel: spidev spi0.1: SPI transfer timed out Aug 26 16:29:33 volumio kernel: spi_master spi0: failed to transfer one message from queue Aug 26 16:29:33 volumio kernel: spi_master spi0: noqueue transfer failed Aug 26 16:29:38 volumio volumio[1154]: info: CoreCommandRouter::volumioGetState Aug 26 16:29:38 volumio volumio[1154]: info: CorePlayQueue::getTrack 0 Aug 26 16:29:38 volumio volumio[1154]: info: CoreCommandRouter::volumioGetState Aug 26 16:29:38 volumio volumio[1154]: info: CorePlayQueue::getTrack 0 Aug 26 16:29:38 volumio volumio[1154]: info: CoreCommandRouter::volumioGetQueue Aug 26 16:29:38 volumio volumio[1154]: info: CoreStateMachine::getQueue Aug 26 16:29:38 volumio volumio[1154]: info: CorePlayQueue::getQueue Aug 26 16:29:38 volumio kernel: spidev spi0.1: SPI transfer timed out Aug 26 16:29:38 volumio kernel: spi_master spi0: failed to transfer one message from queue Aug 26 16:29:38 volumio kernel: spi_master spi0: noqueue transfer failed Aug 26 16:29:39 volumio volumio[1154]: info: CoreCommandRouter::volumioGetState Aug 26 16:29:39 volumio volumio[1154]: info: CorePlayQueue::getTrack 0 Aug 26 16:29:39 volumio volumio[1154]: info: CoreCommandRouter::volumioGetQueue Aug 26 16:29:39 volumio volumio[1154]: info: CoreStateMachine::getQueue Aug 26 16:29:39 volumio volumio[1154]: info: CorePlayQueue::getQueue Aug 26 16:29:40 volumio volumio[1154]: info: CoreCommandRouter::volumioGetState Aug 26 16:29:40 volumio volumio[1154]: info: CorePlayQueue::getTrack 0 Aug 26 16:29:40 volumio volumio[1154]: info: CoreCommandRouter::volumioGetQueue Aug 26 16:29:40 volumio volumio[1154]: info: CoreStateMachine::getQueue Aug 26 16:29:40 volumio volumio[1154]: info: CorePlayQueue::getQueue Aug 26 16:29:41 volumio volumio[1154]: info: CoreCommandRouter::volumioGetState Aug 26 16:29:41 volumio volumio[1154]: info: CorePlayQueue::getTrack 0 Aug 26 16:29:41 volumio volumio[1154]: info: CoreCommandRouter::volumioGetQueue Aug 26 16:29:41 volumio volumio[1154]: info: CoreStateMachine::getQueue Aug 26 16:29:41 volumio volumio[1154]: info: CorePlayQueue::getQueue Aug 26 16:29:42 volumio volumio[1154]: info: CoreCommandRouter::volumioGetState Aug 26 16:29:42 volumio volumio[1154]: info: CorePlayQueue::getTrack 0 Aug 26 16:29:42 volumio volumio[1154]: info: CoreCommandRouter::volumioGetQueue Aug 26 16:29:42 volumio volumio[1154]: info: CoreStateMachine::getQueue Aug 26 16:29:42 volumio volumio[1154]: info: CorePlayQueue::getQueue Aug 26 16:29:43 volumio volumio[1154]: info: CoreCommandRouter::volumioGetState Aug 26 16:29:43 volumio volumio[1154]: info: CorePlayQueue::getTrack 0 Aug 26 16:29:43 volumio volumio[1154]: info: CoreCommandRouter::volumioGetState Aug 26 16:29:43 volumio volumio[1154]: info: CorePlayQueue::getTrack 0 Aug 26 16:29:43 volumio volumio[1154]: info: CoreCommandRouter::volumioGetQueue Aug 26 16:29:43 volumio volumio[1154]: info: CoreStateMachine::getQueue Aug 26 16:29:43 volumio volumio[1154]: info: CorePlayQueue::getQueue Aug 26 16:29:43 volumio kernel: spidev spi0.1: SPI transfer timed out Aug 26 16:29:43 volumio kernel: spi_master spi0: failed to transfer one message from queue Aug 26 16:29:43 volumio kernel: spi_master spi0: noqueue transfer failed Aug 26 16:29:48 volumio volumio[1154]: info: CoreCommandRouter::volumioGetState Aug 26 16:29:48 volumio volumio[1154]: info: CorePlayQueue::getTrack 0 Aug 26 16:29:48 volumio volumio[1154]: info: CoreCommandRouter::volumioGetState Aug 26 16:29:48 volumio volumio[1154]: info: CorePlayQueue::getTrack 0 Aug 26 16:29:48 volumio volumio[1154]: info: CoreCommandRouter::volumioGetQueue Aug 26 16:29:48 volumio volumio[1154]: info: CoreStateMachine::getQueue Aug 26 16:29:48 volumio volumio[1154]: info: CorePlayQueue::getQueue Aug 26 16:29:48 volumio kernel: spidev spi0.1: SPI transfer timed out Aug 26 16:29:48 volumio kernel: spi_master spi0: failed to transfer one message from queue Aug 26 16:29:48 volumio kernel: spi_master spi0: noqueue transfer failed Aug 26 16:29:49 volumio volumio[1154]: info: CoreCommandRouter::volumioGetState Aug 26 16:29:49 volumio volumio[1154]: info: CorePlayQueue::getTrack 0 Aug 26 16:29:49 volumio volumio[1154]: info: CoreCommandRouter::volumioGetQueue Aug 26 16:29:49 volumio volumio[1154]: info: CoreStateMachine::getQueue Aug 26 16:29:49 volumio volumio[1154]: info: CorePlayQueue::getQueue Aug 26 16:29:50 volumio volumio[1154]: info: CoreCommandRouter::volumioGetState Aug 26 16:29:50 volumio volumio[1154]: info: CorePlayQueue::getTrack 0 Aug 26 16:29:50 volumio volumio[1154]: info: CoreCommandRouter::volumioGetQueue Aug 26 16:29:50 volumio volumio[1154]: info: CoreStateMachine::getQueue Aug 26 16:29:50 volumio volumio[1154]: info: CorePlayQueue::getQueue Aug 26 16:29:51 volumio volumio[1154]: info: CoreCommandRouter::volumioGetState Aug 26 16:29:51 volumio volumio[1154]: info: CorePlayQueue::getTrack 0 Aug 26 16:29:51 volumio volumio[1154]: info: CoreCommandRouter::volumioGetQueue Aug 26 16:29:51 volumio volumio[1154]: info: CoreStateMachine::getQueue Aug 26 16:29:51 volumio volumio[1154]: info: CorePlayQueue::getQueue Aug 26 16:29:52 volumio volumio[1154]: info: CoreCommandRouter::volumioGetState Aug 26 16:29:52 volumio volumio[1154]: info: CorePlayQueue::getTrack 0 Aug 26 16:29:52 volumio volumio[1154]: info: CoreCommandRouter::volumioGetQueue Aug 26 16:29:52 volumio volumio[1154]: info: CoreStateMachine::getQueue Aug 26 16:29:52 volumio volumio[1154]: info: CorePlayQueue::getQueue Aug 26 16:29:53 volumio volumio[1154]: info: CoreCommandRouter::volumioGetState Aug 26 16:29:53 volumio volumio[1154]: info: CorePlayQueue::getTrack 0 Aug 26 16:29:53 volumio volumio[1154]: info: CoreCommandRouter::volumioGetState Aug 26 16:29:53 volumio volumio[1154]: info: CorePlayQueue::getTrack 0 Aug 26 16:29:53 volumio volumio[1154]: info: CoreCommandRouter::volumioGetQueue Aug 26 16:29:53 volumio volumio[1154]: info: CoreStateMachine::getQueue Aug 26 16:29:53 volumio volumio[1154]: info: CorePlayQueue::getQueue Aug 26 16:29:53 volumio kernel: spidev spi0.1: SPI transfer timed out Aug 26 16:29:53 volumio kernel: spi_master spi0: failed to transfer one message from queue Aug 26 16:29:53 volumio kernel: spi_master spi0: noqueue transfer failed Aug 26 16:29:58 volumio volumio[1154]: info: CoreCommandRouter::volumioGetState Aug 26 16:29:58 volumio volumio[1154]: info: CorePlayQueue::getTrack 0 Aug 26 16:29:58 volumio volumio[1154]: info: CoreCommandRouter::volumioGetState Aug 26 16:29:58 volumio volumio[1154]: info: CorePlayQueue::getTrack 0 Aug 26 16:29:58 volumio volumio[1154]: info: CoreCommandRouter::volumioGetQueue Aug 26 16:29:58 volumio volumio[1154]: info: CoreStateMachine::getQueue Aug 26 16:29:58 volumio volumio[1154]: info: CorePlayQueue::getQueue Aug 26 16:29:58 volumio volumio[1362]: Exception in thread Thread-185: Aug 26 16:29:58 volumio volumio[1362]: Traceback (most recent call last): Aug 26 16:29:58 volumio volumio[1362]: File "/usr/lib/python3.7/threading.py", line 917, in _bootstrap_inner Aug 26 16:29:58 volumio volumio[1362]: self.run() Aug 26 16:29:58 volumio volumio[1362]: File "/usr/lib/python3.7/threading.py", line 865, in run Aug 26 16:29:58 volumio volumio[1362]: self._target(*self._args, **self._kwargs) Aug 26 16:29:58 volumio volumio[1362]: File "/usr/local/lib/python3.7/dist-packages/socketio/client.py", line 621, in _handle_eio_message Aug 26 16:29:58 volumio volumio[1362]: self._handle_event(pkt.namespace, pkt.id, pkt.data) Aug 26 16:29:58 volumio volumio[1362]: File "/usr/local/lib/python3.7/dist-packages/socketio/client.py", line 505, in _handle_event Aug 26 16:29:58 volumio volumio[1362]: r = self._trigger_event(data[0], namespace, *data[1:]) Aug 26 16:29:58 volumio volumio[1362]: File "/usr/local/lib/python3.7/dist-packages/socketio/client.py", line 555, in _trigger_event Aug 26 16:29:58 volumio volumio[1362]: return self.handlers[namespace][event](*args) Aug 26 16:29:58 volumio volumio[1362]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 514, in on_push_state Aug 26 16:29:58 volumio volumio[1362]: sendtodisplay(IMAGE_DICT['IMG']) Aug 26 16:29:58 volumio volumio[1362]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 212, in sendtodisplay Aug 26 16:29:58 volumio volumio[1362]: DISP.display(img4) Aug 26 16:29:58 volumio volumio[1362]: File "/usr/local/lib/python3.7/dist-packages/ST7789/__init__.py", line 343, in display Aug 26 16:29:58 volumio volumio[1362]: self.data(pixelbytes[i:i + 4096]) Aug 26 16:29:58 volumio volumio[1362]: File "/usr/local/lib/python3.7/dist-packages/ST7789/__init__.py", line 191, in data Aug 26 16:29:58 volumio volumio[1362]: self.send(data, True) Aug 26 16:29:58 volumio volumio[1362]: File "/usr/local/lib/python3.7/dist-packages/ST7789/__init__.py", line 170, in send Aug 26 16:29:58 volumio volumio[1362]: self._spi.xfer(data[start:end]) Aug 26 16:29:58 volumio volumio[1362]: TimeoutError: [Errno 110] Connection timed out Aug 26 16:29:58 volumio volumio[1362]: Exception in thread Thread-187: Aug 26 16:29:58 volumio volumio[1362]: Traceback (most recent call last): Aug 26 16:29:58 volumio volumio[1362]: File "/usr/lib/python3.7/threading.py", line 917, in _bootstrap_inner Aug 26 16:29:58 volumio volumio[1362]: self.run() Aug 26 16:29:58 volumio volumio[1362]: File "/usr/lib/python3.7/threading.py", line 865, in run Aug 26 16:29:58 volumio volumio[1362]: self._target(*self._args, **self._kwargs) Aug 26 16:29:58 volumio volumio[1362]: File "/usr/local/lib/python3.7/dist-packages/socketio/client.py", line 621, in _handle_eio_message Aug 26 16:29:58 volumio volumio[1362]: self._handle_event(pkt.namespace, pkt.id, pkt.data) Aug 26 16:29:58 volumio volumio[1362]: File "/usr/local/lib/python3.7/dist-packages/socketio/client.py", line 505, in _handle_event Aug 26 16:29:58 volumio volumio[1362]: r = self._trigger_event(data[0], namespace, *data[1:]) Aug 26 16:29:58 volumio volumio[1362]: File "/usr/local/lib/python3.7/dist-packages/socketio/client.py", line 555, in _trigger_event Aug 26 16:29:58 volumio volumio[1362]: return self.handlers[namespace][event](*args) Aug 26 16:29:58 volumio volumio[1362]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 514, in on_push_state Aug 26 16:29:58 volumio volumio[1362]: sendtodisplay(IMAGE_DICT['IMG']) Aug 26 16:29:58 volumio volumio[1362]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 212, in sendtodisplay Aug 26 16:29:58 volumio volumio[1362]: DISP.display(img4) Aug 26 16:29:58 volumio volumio[1362]: File "/usr/local/lib/python3.7/dist-packages/ST7789/__init__.py", line 343, in display Aug 26 16:29:58 volumio volumio[1362]: self.data(pixelbytes[i:i + 4096]) Aug 26 16:29:58 volumio volumio[1362]: File "/usr/local/lib/python3.7/dist-packages/ST7789/__init__.py", line 191, in data Aug 26 16:29:58 volumio volumio[1362]: self.send(data, True) Aug 26 16:29:58 volumio volumio[1362]: File "/usr/local/lib/python3.7/dist-packages/ST7789/__init__.py", line 170, in send Aug 26 16:29:58 volumio volumio[1362]: self._spi.xfer(data[start:end]) Aug 26 16:29:58 volumio volumio[1362]: TimeoutError: [Errno 110] Connection timed out Aug 26 16:29:58 volumio volumio[1362]: Exception in thread Thread-189: Aug 26 16:29:58 volumio volumio[1362]: Traceback (most recent call last): Aug 26 16:29:58 volumio volumio[1362]: File "/usr/lib/python3.7/threading.py", line 917, in _bootstrap_inner Aug 26 16:29:58 volumio volumio[1362]: self.run() Aug 26 16:29:58 volumio volumio[1362]: File "/usr/lib/python3.7/threading.py", line 865, in run Aug 26 16:29:58 volumio volumio[1362]: self._target(*self._args, **self._kwargs) Aug 26 16:29:58 volumio volumio[1362]: File "/usr/local/lib/python3.7/dist-packages/socketio/client.py", line 621, in _handle_eio_message Aug 26 16:29:58 volumio volumio[1362]: self._handle_event(pkt.namespace, pkt.id, pkt.data) Aug 26 16:29:58 volumio volumio[1362]: File "/usr/local/lib/python3.7/dist-packages/socketio/client.py", line 505, in _handle_event Aug 26 16:29:58 volumio volumio[1362]: r = self._trigger_event(data[0], namespace, *data[1:]) Aug 26 16:29:58 volumio volumio[1362]: File "/usr/local/lib/python3.7/dist-packages/socketio/client.py", line 555, in _trigger_event Aug 26 16:29:58 volumio volumio[1362]: return self.handlers[namespace][event](*args) Aug 26 16:29:58 volumio volumio[1362]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 514, in on_push_state Aug 26 16:29:58 volumio volumio[1362]: sendtodisplay(IMAGE_DICT['IMG']) Aug 26 16:29:58 volumio volumio[1362]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 212, in sendtodisplay Aug 26 16:29:58 volumio volumio[1362]: DISP.display(img4) Aug 26 16:29:58 volumio volumio[1362]: File "/usr/local/lib/python3.7/dist-packages/ST7789/__init__.py", line 343, in display Aug 26 16:29:58 volumio volumio[1362]: self.data(pixelbytes[i:i + 4096]) Aug 26 16:29:58 volumio volumio[1362]: File "/usr/local/lib/python3.7/dist-packages/ST7789/__init__.py", line 191, in data Aug 26 16:29:58 volumio volumio[1362]: self.send(data, True) Aug 26 16:29:58 volumio volumio[1362]: File "/usr/local/lib/python3.7/dist-packages/ST7789/__init__.py", line 170, in send Aug 26 16:29:58 volumio volumio[1362]: self._spi.xfer(data[start:end]) Aug 26 16:29:58 volumio volumio[1362]: TimeoutError: [Errno 110] Connection timed out Aug 26 16:29:58 volumio volumio[1362]: Exception in thread Thread-191: Aug 26 16:29:58 volumio volumio[1362]: Traceback (most recent call last): Aug 26 16:29:58 volumio volumio[1362]: File "/usr/lib/python3.7/threading.py", line 917, in _bootstrap_inner Aug 26 16:29:58 volumio volumio[1362]: self.run() Aug 26 16:29:58 volumio volumio[1362]: File "/usr/lib/python3.7/threading.py", line 865, in run Aug 26 16:29:58 volumio volumio[1362]: self._target(*self._args, **self._kwargs) Aug 26 16:29:58 volumio volumio[1362]: File "/usr/local/lib/python3.7/dist-packages/socketio/client.py", line 621, in _handle_eio_message Aug 26 16:29:58 volumio volumio[1362]: self._handle_event(pkt.namespace, pkt.id, pkt.data) Aug 26 16:29:58 volumio volumio[1362]: File "/usr/local/lib/python3.7/dist-packages/socketio/client.py", line 505, in _handle_event Aug 26 16:29:58 volumio volumio[1362]: r = self._trigger_event(data[0], namespace, *data[1:]) Aug 26 16:29:58 volumio volumio[1362]: File "/usr/local/lib/python3.7/dist-packages/socketio/client.py", line 555, in _trigger_event Aug 26 16:29:58 volumio volumio[1362]: return self.handlers[namespace][event](*args) Aug 26 16:29:58 volumio volumio[1362]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 514, in on_push_state Aug 26 16:29:58 volumio volumio[1362]: sendtodisplay(IMAGE_DICT['IMG']) Aug 26 16:29:58 volumio volumio[1362]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 212, in sendtodisplay Aug 26 16:29:58 volumio volumio[1362]: DISP.display(img4) Aug 26 16:29:58 volumio volumio[1362]: File "/usr/local/lib/python3.7/dist-packages/ST7789/__init__.py", line 343, in display Aug 26 16:29:58 volumio volumio[1362]: self.data(pixelbytes[i:i + 4096]) Aug 26 16:29:58 volumio volumio[1362]: File "/usr/local/lib/python3.7/dist-packages/ST7789/__init__.py", line 191, in data Aug 26 16:29:58 volumio volumio[1362]: self.send(data, True) Aug 26 16:29:58 volumio volumio[1362]: File "/usr/local/lib/python3.7/dist-packages/ST7789/__init__.py", line 170, in send Aug 26 16:29:58 volumio volumio[1362]: self._spi.xfer(data[start:end]) Aug 26 16:29:58 volumio volumio[1362]: TimeoutError: [Errno 110] Connection timed out Aug 26 16:29:58 volumio volumio[1362]: Exception in thread Thread-193: Aug 26 16:29:58 volumio volumio[1362]: Traceback (most recent call last): Aug 26 16:29:58 volumio volumio[1362]: File "/usr/lib/python3.7/threading.py", line 917, in _bootstrap_inner Aug 26 16:29:58 volumio volumio[1362]: self.run() Aug 26 16:29:58 volumio volumio[1362]: File "/usr/lib/python3.7/threading.py", line 865, in run Aug 26 16:29:58 volumio volumio[1362]: self._target(*self._args, **self._kwargs) Aug 26 16:29:58 volumio volumio[1362]: File "/usr/local/lib/python3.7/dist-packages/socketio/client.py", line 621, in _handle_eio_message Aug 26 16:29:58 volumio volumio[1362]: self._handle_event(pkt.namespace, pkt.id, pkt.data) Aug 26 16:29:58 volumio volumio[1362]: File "/usr/local/lib/python3.7/dist-packages/socketio/client.py", line 505, in _handle_event Aug 26 16:29:58 volumio volumio[1362]: r = self._trigger_event(data[0], namespace, *data[1:]) Aug 26 16:29:58 volumio volumio[1362]: File "/usr/local/lib/python3.7/dist-packages/socketio/client.py", line 555, in _trigger_event Aug 26 16:29:58 volumio volumio[1362]: return self.handlers[namespace][event](*args) Aug 26 16:29:58 volumio volumio[1362]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 514, in on_push_state Aug 26 16:29:58 volumio volumio[1362]: sendtodisplay(IMAGE_DICT['IMG']) Aug 26 16:29:58 volumio volumio[1362]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 212, in sendtodisplay Aug 26 16:29:58 volumio volumio[1362]: DISP.display(img4) Aug 26 16:29:58 volumio volumio[1362]: File "/usr/local/lib/python3.7/dist-packages/ST7789/__init__.py", line 343, in display Aug 26 16:29:58 volumio volumio[1362]: self.data(pixelbytes[i:i + 4096]) Aug 26 16:29:58 volumio volumio[1362]: File "/usr/local/lib/python3.7/dist-packages/ST7789/__init__.py", line 191, in data Aug 26 16:29:58 volumio volumio[1362]: self.send(data, True) Aug 26 16:29:58 volumio volumio[1362]: File "/usr/local/lib/python3.7/dist-packages/ST7789/__init__.py", line 170, in send Aug 26 16:29:58 volumio volumio[1362]: self._spi.xfer(data[start:end]) Aug 26 16:29:58 volumio volumio[1362]: TimeoutError: [Errno 110] Connection timed out Aug 26 16:29:58 volumio volumio[1362]: Exception in thread Thread-195: Aug 26 16:29:58 volumio volumio[1362]: Traceback (most recent call last): Aug 26 16:29:58 volumio volumio[1362]: File "/usr/lib/python3.7/threading.py", line 917, in _bootstrap_inner Aug 26 16:29:58 volumio volumio[1362]: self.run() Aug 26 16:29:58 volumio volumio[1362]: File "/usr/lib/python3.7/threading.py", line 865, in run Aug 26 16:29:58 volumio volumio[1362]: self._target(*self._args, **self._kwargs) Aug 26 16:29:58 volumio volumio[1362]: File "/usr/local/lib/python3.7/dist-packages/socketio/client.py", line 621, in _handle_eio_message Aug 26 16:29:58 volumio volumio[1362]: self._handle_event(pkt.namespace, pkt.id, pkt.data) Aug 26 16:29:58 volumio volumio[1362]: File "/usr/local/lib/python3.7/dist-packages/socketio/client.py", line 505, in _handle_event Aug 26 16:29:58 volumio volumio[1362]: r = self._trigger_event(data[0], namespace, *data[1:]) Aug 26 16:29:58 volumio volumio[1362]: File "/usr/local/lib/python3.7/dist-packages/socketio/client.py", line 555, in _trigger_event Aug 26 16:29:58 volumio volumio[1362]: return self.handlers[namespace][event](*args) Aug 26 16:29:58 volumio volumio[1362]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 514, in on_push_state Aug 26 16:29:58 volumio volumio[1362]: sendtodisplay(IMAGE_DICT['IMG']) Aug 26 16:29:58 volumio volumio[1362]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 212, in sendtodisplay Aug 26 16:29:58 volumio volumio[1362]: DISP.display(img4) Aug 26 16:29:58 volumio volumio[1362]: File "/usr/local/lib/python3.7/dist-packages/ST7789/__init__.py", line 343, in display Aug 26 16:29:58 volumio volumio[1362]: self.data(pixelbytes[i:i + 4096]) Aug 26 16:29:58 volumio volumio[1362]: File "/usr/local/lib/python3.7/dist-packages/ST7789/__init__.py", line 191, in data Aug 26 16:29:58 volumio volumio[1362]: self.send(data, True) Aug 26 16:29:58 volumio kernel: spidev spi0.1: SPI transfer timed out Aug 26 16:29:58 volumio kernel: spi_master spi0: failed to transfer one message from queue Aug 26 16:29:58 volumio kernel: spi_master spi0: noqueue transfer failed Aug 26 16:29:58 volumio volumio[1362]: File "/usr/local/lib/python3.7/dist-packages/ST7789/__init__.py", line 170, in send Aug 26 16:29:58 volumio volumio[1362]: self._spi.xfer(data[start:end]) Aug 26 16:29:58 volumio volumio[1362]: TimeoutError: [Errno 110] Connection timed out Aug 26 16:29:58 volumio volumio[1362]: Exception in thread Thread-197: Aug 26 16:29:58 volumio volumio[1362]: Traceback (most recent call last): Aug 26 16:29:58 volumio volumio[1362]: File "/usr/lib/python3.7/threading.py", line 917, in _bootstrap_inner Aug 26 16:29:58 volumio volumio[1362]: self.run() Aug 26 16:29:58 volumio volumio[1362]: File "/usr/lib/python3.7/threading.py", line 865, in run Aug 26 16:29:58 volumio volumio[1362]: self._target(*self._args, **self._kwargs) Aug 26 16:29:58 volumio volumio[1362]: File "/usr/local/lib/python3.7/dist-packages/socketio/client.py", line 621, in _handle_eio_message Aug 26 16:29:58 volumio volumio[1362]: self._handle_event(pkt.namespace, pkt.id, pkt.data) Aug 26 16:29:58 volumio volumio[1362]: File "/usr/local/lib/python3.7/dist-packages/socketio/client.py", line 505, in _handle_event Aug 26 16:29:58 volumio volumio[1362]: r = self._trigger_event(data[0], namespace, *data[1:]) Aug 26 16:29:58 volumio volumio[1362]: File "/usr/local/lib/python3.7/dist-packages/socketio/client.py", line 555, in _trigger_event Aug 26 16:29:58 volumio volumio[1362]: return self.handlers[namespace][event](*args) Aug 26 16:29:58 volumio volumio[1362]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 514, in on_push_state Aug 26 16:29:58 volumio volumio[1362]: sendtodisplay(IMAGE_DICT['IMG']) Aug 26 16:29:58 volumio volumio[1362]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 212, in sendtodisplay Aug 26 16:29:58 volumio volumio[1362]: DISP.display(img4) Aug 26 16:29:58 volumio volumio[1362]: File "/usr/local/lib/python3.7/dist-packages/ST7789/__init__.py", line 343, in display Aug 26 16:29:58 volumio volumio[1362]: self.data(pixelbytes[i:i + 4096]) Aug 26 16:29:58 volumio volumio[1362]: File "/usr/local/lib/python3.7/dist-packages/ST7789/__init__.py", line 191, in data Aug 26 16:29:58 volumio volumio[1362]: self.send(data, True) Aug 26 16:29:58 volumio volumio[1362]: File "/usr/local/lib/python3.7/dist-packages/ST7789/__init__.py", line 170, in send Aug 26 16:29:58 volumio volumio[1362]: self._spi.xfer(data[start:end]) Aug 26 16:29:58 volumio volumio[1362]: TimeoutError: [Errno 110] Connection timed out Aug 26 16:29:59 volumio volumio[1154]: info: CoreCommandRouter::volumioGetState Aug 26 16:29:59 volumio volumio[1154]: info: CorePlayQueue::getTrack 0 Aug 26 16:29:59 volumio volumio[1154]: info: CoreCommandRouter::volumioGetQueue Aug 26 16:29:59 volumio volumio[1154]: info: CoreStateMachine::getQueue Aug 26 16:29:59 volumio volumio[1154]: info: CorePlayQueue::getQueue Aug 26 16:30:00 volumio volumio[1154]: info: CoreCommandRouter::volumioGetState Aug 26 16:30:00 volumio volumio[1154]: info: CorePlayQueue::getTrack 0 Aug 26 16:30:00 volumio volumio[1154]: info: CoreCommandRouter::volumioGetQueue Aug 26 16:30:00 volumio volumio[1154]: info: CoreStateMachine::getQueue Aug 26 16:30:00 volumio volumio[1154]: info: CorePlayQueue::getQueue Aug 26 16:30:01 volumio volumio[1154]: info: CoreCommandRouter::volumioGetState Aug 26 16:30:01 volumio volumio[1154]: info: CorePlayQueue::getTrack 0 Aug 26 16:30:01 volumio volumio[1154]: info: CoreCommandRouter::volumioGetQueue Aug 26 16:30:01 volumio volumio[1154]: info: CoreStateMachine::getQueue Aug 26 16:30:01 volumio volumio[1154]: info: CorePlayQueue::getQueue Aug 26 16:30:02 volumio volumio[1154]: info: CoreCommandRouter::volumioGetState Aug 26 16:30:02 volumio volumio[1154]: info: CorePlayQueue::getTrack 0 Aug 26 16:30:02 volumio volumio[1154]: info: CoreCommandRouter::volumioGetQueue Aug 26 16:30:02 volumio volumio[1154]: info: CoreStateMachine::getQueue Aug 26 16:30:02 volumio volumio[1154]: info: CorePlayQueue::getQueue Aug 26 16:30:03 volumio volumio[1154]: info: CoreCommandRouter::volumioGetState Aug 26 16:30:03 volumio volumio[1154]: info: CorePlayQueue::getTrack 0 Aug 26 16:30:03 volumio volumio[1154]: info: CoreCommandRouter::volumioGetState Aug 26 16:30:03 volumio volumio[1154]: info: CorePlayQueue::getTrack 0 Aug 26 16:30:03 volumio volumio[1154]: info: CoreCommandRouter::volumioGetQueue Aug 26 16:30:03 volumio volumio[1154]: info: CoreStateMachine::getQueue Aug 26 16:30:03 volumio volumio[1154]: info: CorePlayQueue::getQueue Aug 26 16:30:03 volumio kernel: spidev spi0.1: SPI transfer timed out Aug 26 16:30:03 volumio kernel: spi_master spi0: failed to transfer one message from queue Aug 26 16:30:03 volumio kernel: spi_master spi0: noqueue transfer failed Aug 26 16:30:08 volumio volumio[1154]: info: CoreCommandRouter::volumioGetState Aug 26 16:30:08 volumio volumio[1154]: info: CorePlayQueue::getTrack 0 Aug 26 16:30:08 volumio volumio[1154]: info: CoreCommandRouter::volumioGetState Aug 26 16:30:08 volumio volumio[1154]: info: CorePlayQueue::getTrack 0 Aug 26 16:30:08 volumio volumio[1154]: info: CoreCommandRouter::volumioGetQueue Aug 26 16:30:08 volumio volumio[1154]: info: CoreStateMachine::getQueue Aug 26 16:30:08 volumio volumio[1154]: info: CorePlayQueue::getQueue Aug 26 16:30:08 volumio kernel: spidev spi0.1: SPI transfer timed out Aug 26 16:30:08 volumio kernel: spi_master spi0: failed to transfer one message from queue Aug 26 16:30:08 volumio kernel: spi_master spi0: noqueue transfer failed Aug 26 16:30:09 volumio volumio[1154]: info: CoreCommandRouter::volumioGetState Aug 26 16:30:09 volumio volumio[1154]: info: CorePlayQueue::getTrack 0 Aug 26 16:30:09 volumio volumio[1154]: info: CoreCommandRouter::volumioGetQueue Aug 26 16:30:09 volumio volumio[1154]: info: CoreStateMachine::getQueue Aug 26 16:30:09 volumio volumio[1154]: info: CorePlayQueue::getQueue Aug 26 16:30:10 volumio volumio[1154]: info: CoreCommandRouter::volumioGetState Aug 26 16:30:10 volumio volumio[1154]: info: CorePlayQueue::getTrack 0 Aug 26 16:30:10 volumio volumio[1154]: info: CoreCommandRouter::volumioGetQueue Aug 26 16:30:10 volumio volumio[1154]: info: CoreStateMachine::getQueue Aug 26 16:30:10 volumio volumio[1154]: info: CorePlayQueue::getQueue Aug 26 16:30:11 volumio volumio[1154]: info: CoreCommandRouter::volumioGetState Aug 26 16:30:11 volumio volumio[1154]: info: CorePlayQueue::getTrack 0 Aug 26 16:30:11 volumio volumio[1154]: info: CoreCommandRouter::volumioGetQueue Aug 26 16:30:11 volumio volumio[1154]: info: CoreStateMachine::getQueue Aug 26 16:30:11 volumio volumio[1154]: info: CorePlayQueue::getQueue Aug 26 16:30:12 volumio volumio[1154]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Aug 26 16:30:12 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Aug 26 16:30:12 volumio volumio[1154]: info: Preparing to save Alsa Options, stopping services first Aug 26 16:30:12 volumio volumio[1154]: info: CoreCommandRouter::volumioGetState Aug 26 16:30:12 volumio volumio[1154]: info: CorePlayQueue::getTrack 0 Aug 26 16:30:12 volumio volumio[1154]: info: CoreCommandRouter::volumioPause Aug 26 16:30:12 volumio volumio[1154]: info: CoreStateMachine::pause Aug 26 16:30:12 volumio volumio[1154]: info: CoreStateMachine::stPlaybackTimer Aug 26 16:30:12 volumio volumio[1154]: info: CoreStateMachine::servicePause Aug 26 16:30:12 volumio volumio[1154]: info: CorePlayQueue::getTrack 0 Aug 26 16:30:12 volumio volumio[1154]: info: CoreCommandRouter::servicePause Aug 26 16:30:12 volumio volumio[1154]: verbose: ControllerMpd::sendMpdCommand pause Aug 26 16:30:12 volumio volumio[1154]: info: Saving Audio Output to: {"output_device":{"value":"1","label":"Headphones"},"i2s":true,"i2sid":{"value":"raspiaudio","label":"raspiaudio"}} Aug 26 16:30:12 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 26 16:30:12 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Aug 26 16:30:12 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 16:30:12 volumio volumio[1154]: info: Enabling I2S DAC: raspiaudio Aug 26 16:30:12 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , enableI2SDAC Aug 26 16:30:12 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 16:30:12 volumio sudo[2741]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l Aug 26 16:30:12 volumio sudo[2741]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 16:30:12 volumio sudo[2741]: pam_unix(sudo:session): session closed for user root Aug 26 16:30:12 volumio volumio[1154]: info: No Overlays Loaded Aug 26 16:30:12 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 16:30:12 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SAlsaName Aug 26 16:30:12 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 16:30:12 volumio sudo[2745]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay googlevoicehat-soundcard Aug 26 16:30:12 volumio sudo[2745]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 16:30:12 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/i2s@7e203000/status Aug 26 16:30:12 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/sound/compatible Aug 26 16:30:12 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/sound/i2s-controller Aug 26 16:30:12 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/sound/status Aug 26 16:30:12 volumio volumio[1154]: warn: Unable to locate the device Headphones a reboot may be required. Aug 26 16:30:12 volumio sudo[2745]: pam_unix(sudo:session): session closed for user root Aug 26 16:30:12 volumio kernel: voicehat-codec voicehat-codec: property 'voicehat_sdmode_delay' not found default 5 mS Aug 26 16:30:12 volumio kernel: voicehat-codec voicehat-codec: Unable to allocate GPIO pin Aug 26 16:30:12 volumio kernel: voicehat-codec voicehat-codec: ASoC: error at snd_soc_component_probe on voicehat-codec: -16 Aug 26 16:30:12 volumio kernel: snd-rpi-simple soc:sound: ASoC: failed to instantiate card -16 Aug 26 16:30:12 volumio kernel: snd-rpi-simple soc:sound: Failed to register card -16 Aug 26 16:30:12 volumio kernel: snd-rpi-simple: probe of soc:sound failed with error -16 Aug 26 16:30:12 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 26 16:30:12 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SMixer Aug 26 16:30:12 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 16:30:12 volumio volumio[1154]: Invalid card number. Aug 26 16:30:12 volumio volumio[1154]: info: CoreCommandRouter::volumioUpdateVolumeSettings Aug 26 16:30:12 volumio volumio[1154]: info: Updating Volume Controller Parameters: Device: 2 Name: raspiaudio Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Aug 26 16:30:12 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Aug 26 16:30:12 volumio volumio[1154]: info: Disabling external Volume Control Aug 26 16:30:12 volumio volumio[1154]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 26 16:30:12 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: wizard , setWizardAction Aug 26 16:30:12 volumio volumio[1154]: info: Preparing to generate the ALSA configuration file Aug 26 16:30:12 volumio volumio[1154]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Aug 26 16:30:12 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Aug 26 16:30:12 volumio volumio[1154]: info: Preparing to save Alsa Options, stopping services first Aug 26 16:30:12 volumio volumio[1154]: info: CoreCommandRouter::volumioGetState Aug 26 16:30:12 volumio volumio[1154]: info: CorePlayQueue::getTrack 0 Aug 26 16:30:12 volumio volumio[1154]: info: CoreCommandRouter::volumioPause Aug 26 16:30:12 volumio volumio[1154]: info: CoreStateMachine::pause Aug 26 16:30:12 volumio volumio[1154]: info: Saving Audio Output to: {"output_device":{"value":"1","label":"Headphones"},"i2s":true,"i2sid":{"value":"raspiaudio","label":"raspiaudio"}} Aug 26 16:30:12 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 26 16:30:12 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Aug 26 16:30:12 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 16:30:12 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SAlsaName Aug 26 16:30:12 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 16:30:12 volumio volumio[1154]: warn: Unable to locate the device Headphones a reboot may be required. Aug 26 16:30:13 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 26 16:30:13 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SMixer Aug 26 16:30:13 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 16:30:13 volumio volumio[1154]: Invalid card number. Aug 26 16:30:13 volumio volumio[1154]: info: CoreCommandRouter::volumioUpdateVolumeSettings Aug 26 16:30:13 volumio volumio[1154]: info: Updating Volume Controller Parameters: Device: 2 Name: raspiaudio Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Aug 26 16:30:13 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Aug 26 16:30:13 volumio volumio[1154]: info: Disabling external Volume Control Aug 26 16:30:13 volumio volumio[1154]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 26 16:30:13 volumio volumio[1154]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Aug 26 16:30:13 volumio volumio[1154]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf Aug 26 16:30:13 volumio volumio[1154]: info: The plugin peppymeterbasic has an ALSA contribution file peppy_in.peppy_out.6.conf Aug 26 16:30:13 volumio volumio[1154]: info: Reading ALSA contributions from plugins. Aug 26 16:30:13 volumio volumio[1154]: info: CoreCommandRouter::volumioGetState Aug 26 16:30:13 volumio volumio[1154]: info: CorePlayQueue::getTrack 0 Aug 26 16:30:13 volumio volumio[1154]: info: CoreCommandRouter::volumioGetQueue Aug 26 16:30:13 volumio volumio[1154]: info: CoreStateMachine::getQueue Aug 26 16:30:13 volumio volumio[1154]: info: CorePlayQueue::getQueue Aug 26 16:30:13 volumio volumio[1154]: info: Aug 26 16:30:13 volumio volumio[1154]: ---------------------------- MPD announces state update: player Aug 26 16:30:13 volumio volumio[1154]: info: sendMpdCommand pause took 616 milliseconds Aug 26 16:30:13 volumio volumio[1154]: info: ControllerMpd::getState Aug 26 16:30:13 volumio volumio[1154]: verbose: ControllerMpd::sendMpdCommand status Aug 26 16:30:13 volumio volumio[1154]: info: CorePlayQueue::getTrack 0 Aug 26 16:30:13 volumio volumio[1154]: info: CoreCommandRouter::servicePushState Aug 26 16:30:13 volumio volumio[1154]: info: CorePlayQueue::getTrack 0 Aug 26 16:30:13 volumio volumio[1154]: verbose: STATE SERVICE {"status":"pause","service":"motherearthradio","type":"webradio","trackType":"flac","radioType":"mer","albumart":"https://motherearth.streamserver24.com/api/station/motherearth/art/c68211e52cc76ad23cf5e558-1702138340.jpg","uri":"https://motherearth.streamserver24.com/listen/motherearth/motherearth","name":"RSN - Cherry Tree feat Katrin deBoer","title":"Cherry Tree feat Katrin deBoer","artist":"RSN","album":"Strange Eyes","streaming":true,"disableUiControls":true,"duration":175,"seek":110557,"bitdepth":"24 bit","channels":2} Aug 26 16:30:13 volumio volumio[1154]: verbose: CURRENT POSITION 0 Aug 26 16:30:13 volumio volumio[1154]: info: CoreStateMachine::syncState stateService pause Aug 26 16:30:13 volumio volumio[1154]: info: CoreStateMachine::syncState currentStatus pause Aug 26 16:30:13 volumio volumio[1154]: info: CoreStateMachine::pushState Aug 26 16:30:13 volumio volumio[1154]: info: CorePlayQueue::getTrack 0 Aug 26 16:30:13 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 26 16:30:13 volumio volumio[1154]: info: CoreCommandRouter::volumioPushState Aug 26 16:30:13 volumio volumio[1154]: info: CoreStateMachine::stPlaybackTimer Aug 26 16:30:13 volumio volumio[1154]: info: Aug 26 16:30:13 volumio volumio[1154]: ---------------------------- MPD announces state update: player Aug 26 16:30:13 volumio volumio[1154]: info: ControllerMpd::getState Aug 26 16:30:13 volumio volumio[1154]: verbose: ControllerMpd::sendMpdCommand status Aug 26 16:30:13 volumio volumio[1154]: info: Aug 26 16:30:13 volumio volumio[1154]: ---------------------------- MPD announces state update: player Aug 26 16:30:13 volumio volumio[1154]: info: ControllerMpd::getState Aug 26 16:30:13 volumio volumio[1154]: verbose: ControllerMpd::sendMpdCommand status Aug 26 16:30:13 volumio volumio[1154]: info: PeppyMeterBasic ---peppymeterbasic status pause Aug 26 16:30:13 volumio volumio[1154]: info: PeppySpectrum ---peppyspectrum status pause Aug 26 16:30:13 volumio sudo[2783]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Aug 26 16:30:13 volumio sudo[2783]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 16:30:13 volumio volumio[1154]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Aug 26 16:30:13 volumio systemd[1]: Stopping peppymeterbasic Daemon... Aug 26 16:30:13 volumio sudo[2786]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Aug 26 16:30:13 volumio systemd[1]: peppymeterbasic.service: Main process exited, code=killed, status=15/TERM Aug 26 16:30:13 volumio sudo[2786]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 16:30:13 volumio systemd[1]: peppymeterbasic.service: Succeeded. Aug 26 16:30:13 volumio systemd[1]: Stopped peppymeterbasic Daemon. Aug 26 16:30:13 volumio volumio[1154]: info: I2S Param [object Object] successfully enabled Aug 26 16:30:13 volumio volumio[1154]: info: sendMpdCommand status took 107 milliseconds Aug 26 16:30:13 volumio volumio[1154]: info: sendMpdCommand status took 97 milliseconds Aug 26 16:30:13 volumio volumio[1154]: info: sendMpdCommand status took 96 milliseconds Aug 26 16:30:13 volumio volumio[1154]: verbose: ControllerMpd::parseState Aug 26 16:30:13 volumio volumio[1154]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 26 16:30:13 volumio volumio[1154]: verbose: ControllerMpd::parseState Aug 26 16:30:13 volumio volumio[1154]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 26 16:30:13 volumio volumio[1154]: verbose: ControllerMpd::parseState Aug 26 16:30:13 volumio volumio[1154]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 26 16:30:13 volumio sudo[2783]: pam_unix(sudo:session): session closed for user root Aug 26 16:30:13 volumio volumio[1154]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Aug 26 16:30:13 volumio sudo[2786]: pam_unix(sudo:session): session closed for user root Aug 26 16:30:13 volumio volumio[1154]: info: sendMpdCommand playlistinfo took 30 milliseconds Aug 26 16:30:13 volumio volumio[1154]: info: sendMpdCommand playlistinfo took 30 milliseconds Aug 26 16:30:13 volumio volumio[1154]: info: sendMpdCommand playlistinfo took 29 milliseconds Aug 26 16:30:13 volumio volumio[1154]: verbose: ControllerMpd::parseTrackInfo Aug 26 16:30:13 volumio volumio[1154]: verbose: ControllerMpd::parseTrackInfo Aug 26 16:30:13 volumio volumio[1154]: verbose: ControllerMpd::parseTrackInfo Aug 26 16:30:13 volumio volumio[1154]: info: ControllerMpd::pushState Aug 26 16:30:13 volumio volumio[1154]: info: CoreCommandRouter::servicePushState Aug 26 16:30:13 volumio volumio[1154]: info: CorePlayQueue::getTrack 0 Aug 26 16:30:13 volumio volumio[1154]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current motherearthradio Received mpd Aug 26 16:30:13 volumio volumio[1154]: info: ControllerMpd::pushState Aug 26 16:30:13 volumio volumio[1154]: info: CoreCommandRouter::servicePushState Aug 26 16:30:13 volumio volumio[1154]: info: CorePlayQueue::getTrack 0 Aug 26 16:30:13 volumio volumio[1154]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current motherearthradio Received mpd Aug 26 16:30:13 volumio volumio[1154]: info: ControllerMpd::pushState Aug 26 16:30:13 volumio volumio[1154]: info: CoreCommandRouter::servicePushState Aug 26 16:30:13 volumio volumio[1154]: info: CorePlayQueue::getTrack 0 Aug 26 16:30:13 volumio volumio[1154]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current motherearthradio Received mpd Aug 26 16:30:13 volumio volumio[1154]: info: ------------------------------ 146ms Aug 26 16:30:13 volumio volumio[1154]: info: ------------------------------ 135ms Aug 26 16:30:13 volumio volumio[1154]: info: ------------------------------ 133ms Aug 26 16:30:13 volumio volumio[1154]: info: peppyspectrum Daemon Stop Aug 26 16:30:13 volumio volumio[1154]: info: VolumeController:: Volume=undefined Mute =false Aug 26 16:30:13 volumio volumio[1154]: info: CoreStateMachine::pushState Aug 26 16:30:13 volumio volumio[1154]: info: CorePlayQueue::getTrack 0 Aug 26 16:30:13 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 26 16:30:13 volumio volumio[1154]: info: CoreCommandRouter::volumioPushState Aug 26 16:30:13 volumio volumio[1154]: info: peppymeterbasic Daemon Stop Aug 26 16:30:13 volumio volumio[1154]: info: camilladsp spawned new process with pid 2792, instance 1, run: true Aug 26 16:30:13 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Aug 26 16:30:13 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 16:30:13 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 26 16:30:13 volumio volumio[1154]: info: Getting Alsa Cards List without I2S DAC Aug 26 16:30:13 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Aug 26 16:30:13 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 16:30:13 volumio volumio[1154]: Invalid card number. Aug 26 16:30:13 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 26 16:30:13 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 26 16:30:13 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 26 16:30:13 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 26 16:30:13 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 26 16:30:13 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 26 16:30:13 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 26 16:30:13 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Aug 26 16:30:13 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Aug 26 16:30:13 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Aug 26 16:30:13 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 16:30:13 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 26 16:30:13 volumio volumio[1154]: info: Getting Alsa Cards List without I2S DAC Aug 26 16:30:13 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Aug 26 16:30:13 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 16:30:13 volumio volumio[1154]: Invalid card number. Aug 26 16:30:13 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 26 16:30:13 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 26 16:30:13 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 26 16:30:13 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 26 16:30:13 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 26 16:30:13 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 26 16:30:13 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 26 16:30:13 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Aug 26 16:30:13 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Aug 26 16:30:13 volumio volumio[1154]: info: PeppyMeterBasic ---peppymeterbasic status pause Aug 26 16:30:13 volumio volumio[1154]: info: PeppySpectrum ---peppyspectrum status pause Aug 26 16:30:13 volumio sudo[2817]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Aug 26 16:30:13 volumio sudo[2817]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 16:30:13 volumio sudo[2820]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Aug 26 16:30:13 volumio sudo[2820]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 16:30:13 volumio sudo[2817]: pam_unix(sudo:session): session closed for user root Aug 26 16:30:13 volumio volumio[1154]: info: VolumeController:: Volume=undefined Mute =false Aug 26 16:30:13 volumio volumio[1154]: info: CoreStateMachine::pushState Aug 26 16:30:13 volumio volumio[1154]: info: CorePlayQueue::getTrack 0 Aug 26 16:30:13 volumio volumio[1154]: info: CoreCommandRouter::volumioPushState Aug 26 16:30:13 volumio sudo[2820]: pam_unix(sudo:session): session closed for user root Aug 26 16:30:13 volumio volumio[1154]: info: PeppyMeterBasic ---peppymeterbasic status pause Aug 26 16:30:13 volumio volumio[1154]: info: PeppySpectrum ---peppyspectrum status pause Aug 26 16:30:13 volumio sudo[2827]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Aug 26 16:30:13 volumio sudo[2827]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 16:30:13 volumio sudo[2827]: pam_unix(sudo:session): session closed for user root Aug 26 16:30:13 volumio sudo[2830]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Aug 26 16:30:13 volumio volumio[1154]: info: VolumeController::SetAlsaVolume0 Aug 26 16:30:13 volumio sudo[2830]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 16:30:13 volumio sudo[2830]: pam_unix(sudo:session): session closed for user root Aug 26 16:30:13 volumio volumio[1154]: info: CoreStateMachine::pushState Aug 26 16:30:13 volumio volumio[1154]: info: CorePlayQueue::getTrack 0 Aug 26 16:30:13 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 26 16:30:13 volumio volumio[1154]: info: CoreCommandRouter::volumioPushState Aug 26 16:30:13 volumio volumio[1154]: info: peppymeterbasic Daemon Stop Aug 26 16:30:13 volumio volumio[1154]: info: PeppyMeterBasic ---peppymeterbasic status pause Aug 26 16:30:13 volumio volumio[1154]: info: PeppySpectrum ---peppyspectrum status pause Aug 26 16:30:13 volumio sudo[2836]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Aug 26 16:30:13 volumio sudo[2836]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 16:30:14 volumio sudo[2839]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Aug 26 16:30:14 volumio sudo[2839]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 16:30:14 volumio sudo[2836]: pam_unix(sudo:session): session closed for user root Aug 26 16:30:14 volumio volumio[1154]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Aug 26 16:30:14 volumio volumio[1154]: info: peppyspectrum Daemon Stop Aug 26 16:30:14 volumio volumio[1154]: info: peppymeterbasic Daemon Stop Aug 26 16:30:14 volumio volumio[1154]: info: peppyspectrum Daemon Stop Aug 26 16:30:14 volumio volumio[1154]: info: peppymeterbasic Daemon Stop Aug 26 16:30:14 volumio sudo[2839]: pam_unix(sudo:session): session closed for user root Aug 26 16:30:14 volumio volumio[1154]: info: peppyspectrum Daemon Stop Aug 26 16:30:14 volumio volumio[1154]: info: Asound.conf file written Aug 26 16:30:14 volumio sudo[2842]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Aug 26 16:30:14 volumio sudo[2842]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 16:30:14 volumio sudo[2842]: pam_unix(sudo:session): session closed for user root Aug 26 16:30:14 volumio volumio[1154]: info: Output device has changed, restarting MPD Aug 26 16:30:14 volumio sudo[2847]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 26 16:30:14 volumio sudo[2847]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 16:30:14 volumio sudo[2847]: pam_unix(sudo:session): session closed for user root Aug 26 16:30:14 volumio volumio[1154]: info: Output device has changed, restarting Shairport Sync Aug 26 16:30:14 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 16:30:14 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 16:30:14 volumio sudo[2850]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 26 16:30:14 volumio sudo[2850]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 16:30:14 volumio systemd[1]: musicservicesshield.service: Succeeded. Aug 26 16:30:14 volumio systemd[1]: Stopped Shield Volumio Music Services in the User CPU Set. Aug 26 16:30:14 volumio systemd[1]: Stopping Shield Volumio Music Services in the User CPU Set... Aug 26 16:30:14 volumio systemd[1]: Stopping Music Player Daemon... Aug 26 16:30:14 volumio volumio[1154]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 26 16:30:14 volumio volumio[1154]: info: Preparing to generate the ALSA configuration file Aug 26 16:30:14 volumio systemd[1]: mpd.service: Succeeded. Aug 26 16:30:14 volumio systemd[1]: Stopped Music Player Daemon. Aug 26 16:30:14 volumio volumio[1154]: info: Output device has changed, restarting MPD Aug 26 16:30:14 volumio systemd[1]: Starting Music Player Daemon... Aug 26 16:30:14 volumio volumio[1154]: info: Output device has changed, restarting Shairport Sync Aug 26 16:30:14 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 16:30:14 volumio sudo[2857]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 26 16:30:14 volumio sudo[2857]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 16:30:14 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 16:30:14 volumio sudo[2857]: pam_unix(sudo:session): session closed for user root Aug 26 16:30:14 volumio sudo[2861]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 26 16:30:14 volumio sudo[2861]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 16:30:14 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Aug 26 16:30:14 volumio systemd[1]: mpd.service: Succeeded. Aug 26 16:30:14 volumio systemd[1]: Stopped Music Player Daemon. Aug 26 16:30:14 volumio volumio[1154]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 26 16:30:14 volumio systemd[1]: Starting Music Player Daemon... Aug 26 16:30:14 volumio volumio[1154]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Aug 26 16:30:14 volumio volumio[1154]: info: The plugin peppymeterbasic has an ALSA contribution file peppy_in.peppy_out.6.conf Aug 26 16:30:14 volumio volumio[1154]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf Aug 26 16:30:14 volumio volumio[1154]: info: Reading ALSA contributions from plugins. Aug 26 16:30:14 volumio volumio[1154]: info: MPD Permissions set Aug 26 16:30:14 volumio volumio[1154]: info: MPD Permissions set Aug 26 16:30:14 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 16:30:14 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 16:30:14 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 16:30:14 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 16:30:14 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 16:30:14 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 16:30:14 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 16:30:14 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 16:30:14 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 16:30:14 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 16:30:14 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 16:30:14 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 16:30:14 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 16:30:14 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 16:30:14 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 16:30:14 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 16:30:14 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 16:30:14 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 16:30:14 volumio sudo[2866]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 26 16:30:14 volumio sudo[2866]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 16:30:14 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 16:30:14 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 16:30:14 volumio sudo[2866]: pam_unix(sudo:session): session closed for user root Aug 26 16:30:14 volumio volumio[1154]: info: Starting Shairport Sync Aug 26 16:30:14 volumio volumio[1154]: info: Starting Shairport Sync Aug 26 16:30:14 volumio sudo[2878]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 26 16:30:14 volumio sudo[2878]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 16:30:14 volumio volumio[1154]: info: Asound.conf file unchanged, so no further update is needed Aug 26 16:30:14 volumio volumio[1154]: info: Output device has changed, restarting MPD Aug 26 16:30:14 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 26 16:30:14 volumio systemd[1]: shairport-sync.service: Succeeded. Aug 26 16:30:14 volumio sudo[2881]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 26 16:30:14 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Aug 26 16:30:14 volumio sudo[2881]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 16:30:14 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 26 16:30:14 volumio sudo[2878]: pam_unix(sudo:session): session closed for user root Aug 26 16:30:14 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 26 16:30:14 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Aug 26 16:30:14 volumio systemd[1]: shairport-sync.service: Succeeded. Aug 26 16:30:14 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Aug 26 16:30:14 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 26 16:30:14 volumio sudo[2881]: pam_unix(sudo:session): session closed for user root Aug 26 16:30:14 volumio sudo[2885]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 26 16:30:14 volumio sudo[2885]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 16:30:14 volumio sudo[2885]: pam_unix(sudo:session): session closed for user root Aug 26 16:30:14 volumio volumio[1154]: info: Output device has changed, restarting Shairport Sync Aug 26 16:30:14 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 16:30:14 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 16:30:14 volumio sudo[2890]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 26 16:30:14 volumio sudo[2890]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 16:30:14 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Aug 26 16:30:14 volumio systemd[1]: mpd.service: Succeeded. Aug 26 16:30:14 volumio systemd[1]: Stopped Music Player Daemon. Aug 26 16:30:14 volumio systemd[1]: Starting Music Player Daemon... Aug 26 16:30:14 volumio volumio[1154]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 26 16:30:14 volumio volumio[1154]: info: MPD Permissions set Aug 26 16:30:14 volumio volumio[1154]: info: Shairport-Sync Started Aug 26 16:30:14 volumio volumio[1154]: info: Shairport-Sync Started Aug 26 16:30:14 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 16:30:14 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 16:30:14 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 16:30:14 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 16:30:14 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 16:30:14 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 16:30:14 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 16:30:14 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 16:30:14 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 16:30:14 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 16:30:14 volumio volumio[1154]: info: Starting Shairport Sync Aug 26 16:30:14 volumio sudo[2896]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 26 16:30:14 volumio sudo[2896]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 16:30:14 volumio sudo[2896]: pam_unix(sudo:session): session closed for user root Aug 26 16:30:14 volumio sudo[2902]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 26 16:30:14 volumio sudo[2902]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 16:30:14 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 26 16:30:14 volumio systemd[1]: shairport-sync.service: Succeeded. Aug 26 16:30:14 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Aug 26 16:30:14 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 26 16:30:14 volumio sudo[2902]: pam_unix(sudo:session): session closed for user root Aug 26 16:30:14 volumio volumio[1154]: info: Shairport-Sync Started Aug 26 16:30:15 volumio mpd[2904]: Aug 26 16:30 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 26 16:30:15 volumio systemd[1]: Started Music Player Daemon. Aug 26 16:30:15 volumio sudo[2850]: pam_unix(sudo:session): session closed for user root Aug 26 16:30:15 volumio sudo[2861]: pam_unix(sudo:session): session closed for user root Aug 26 16:30:15 volumio sudo[2890]: pam_unix(sudo:session): session closed for user root Aug 26 16:30:15 volumio volumio[1154]: error: MPD error: The expression evaluated to a falsy value: Aug 26 16:30:15 volumio volumio[1154]: assert.ok(self.idling) Aug 26 16:30:15 volumio volumio[1154]: error: The expression evaluated to a falsy value: Aug 26 16:30:15 volumio volumio[1154]: assert.ok(self.idling) Aug 26 16:30:15 volumio volumio[1154]: error: MPD error: The expression evaluated to a falsy value: Aug 26 16:30:15 volumio volumio[1154]: assert.ok(self.idling) Aug 26 16:30:15 volumio volumio[1154]: error: The expression evaluated to a falsy value: Aug 26 16:30:15 volumio volumio[1154]: assert.ok(self.idling) Aug 26 16:30:15 volumio volumio[1154]: error: updateQueue error: null Aug 26 16:30:15 volumio systemd[1]: Starting Shield Volumio Music Services in the User CPU Set... Aug 26 16:30:15 volumio volumio[2912]: cset: --> shielding system active with Aug 26 16:30:15 volumio volumio[2912]: cset: "system" cpuset of CPUSPEC(1-3) with 154 tasks running Aug 26 16:30:15 volumio volumio[2912]: cset: "user" cpuset of CPUSPEC(0) with 3 tasks running Aug 26 16:30:15 volumio systemd[1]: Started Shield Volumio Music Services in the User CPU Set. Aug 26 16:30:16 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 16:30:16 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 16:30:16 volumio volumio[1154]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 26 16:30:16 volumio volumio[1154]: info: [squeezelite_mc] Executing aplay -D hw:2 --nonblock -f MPEG /dev/zero 2>&1 || true Aug 26 16:30:16 volumio volumio[1154]: warn: [squeezelite_mc] No supported ALSA formats found for card 2 Aug 26 16:30:16 volumio volumio[1154]: warn: [squeezelite_mc] No ALSA formats returned for card 2 Aug 26 16:30:16 volumio volumio[1154]: info: [squeezelite_mc] Restarting Squeezelite service with params: {"type":"basic","playerName":"volumio","dsdFormat":null,"card":"2","mixerType":"undefined","mixer":""} Aug 26 16:30:16 volumio volumio[1154]: info: [squeezelite_mc] Executing cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/alsa/100-squeezelite.conf.template.out /etc/alsa/conf.d/100-squeezelite.conf Aug 26 16:30:16 volumio sudo[2934]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/alsa/100-squeezelite.conf.template.out /etc/alsa/conf.d/100-squeezelite.conf Aug 26 16:30:16 volumio sudo[2934]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 16:30:16 volumio sudo[2934]: pam_unix(sudo:session): session closed for user root Aug 26 16:30:16 volumio volumio[1154]: info: [squeezelite_mc] Executing alsactl -L -R nrestore Aug 26 16:30:16 volumio sudo[2938]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/alsactl -L -R nrestore Aug 26 16:30:16 volumio sudo[2938]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 16:30:16 volumio sudo[2938]: pam_unix(sudo:session): session closed for user root Aug 26 16:30:16 volumio volumio[1154]: info: [squeezelite_mc] Executing cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/systemd/squeezelite.service.template.out /etc/systemd/system/squeezelite.service Aug 26 16:30:16 volumio sudo[2942]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/systemd/squeezelite.service.template.out /etc/systemd/system/squeezelite.service Aug 26 16:30:16 volumio sudo[2942]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 16:30:16 volumio sudo[2942]: pam_unix(sudo:session): session closed for user root Aug 26 16:30:16 volumio volumio[1154]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl daemon-reload || true Aug 26 16:30:16 volumio sudo[2945]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Aug 26 16:30:16 volumio sudo[2945]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 16:30:16 volumio systemd[1]: Reloading. Aug 26 16:30:16 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Aug 26 16:30:16 volumio volumio[1154]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Aug 26 16:30:16 volumio volumio[1154]: info: CoreStateMachine::getcurrentVolume Aug 26 16:30:16 volumio volumio[1154]: info: CoreCommandRouter::volumioRetrievevolume Aug 26 16:30:16 volumio volumio[1154]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Aug 26 16:30:16 volumio volumio[1154]: info: VolumeController:: Volume=undefined Mute =false Aug 26 16:30:16 volumio volumio[1154]: info: CoreStateMachine::pushState Aug 26 16:30:16 volumio volumio[1154]: info: CorePlayQueue::getTrack 0 Aug 26 16:30:16 volumio volumio[1154]: info: CoreCommandRouter::volumioPushState Aug 26 16:30:16 volumio volumio[1154]: info: CoreStateMachine::updateTrackBlock Aug 26 16:30:16 volumio volumio[1154]: info: CorePlayQueue::getTrackBlock Aug 26 16:30:16 volumio volumio[1154]: info: CoreCommandRouter::volumioRetrievevolume Aug 26 16:30:16 volumio volumio[1154]: info: PeppyMeterBasic ---peppymeterbasic status pause Aug 26 16:30:16 volumio volumio[1154]: info: PeppySpectrum ---peppyspectrum status pause Aug 26 16:30:16 volumio sudo[2966]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Aug 26 16:30:16 volumio sudo[2966]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 16:30:17 volumio volumio[1154]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Aug 26 16:30:17 volumio systemd[1]: squeezelite.service: Current command vanished from the unit file, execution of the command list won't be resumed. Aug 26 16:30:17 volumio sudo[2969]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Aug 26 16:30:17 volumio volumio[1154]: info: VolumeController:: Volume=undefined Mute =false Aug 26 16:30:17 volumio sudo[2969]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 16:30:17 volumio volumio[1154]: info: CoreStateMachine::pushState Aug 26 16:30:17 volumio volumio[1154]: info: CorePlayQueue::getTrack 0 Aug 26 16:30:17 volumio volumio[1154]: info: CoreCommandRouter::volumioPushState Aug 26 16:30:17 volumio volumio[1154]: info: PeppyMeterBasic ---peppymeterbasic status pause Aug 26 16:30:17 volumio volumio[1154]: info: PeppySpectrum ---peppyspectrum status pause Aug 26 16:30:17 volumio sudo[2976]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Aug 26 16:30:17 volumio sudo[2976]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 16:30:17 volumio volumio[1154]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Aug 26 16:30:17 volumio volumio[1154]: info: CoreStateMachine::getcurrentVolume Aug 26 16:30:17 volumio volumio[1154]: info: CoreCommandRouter::volumioRetrievevolume Aug 26 16:30:17 volumio sudo[2978]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Aug 26 16:30:17 volumio sudo[2978]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 16:30:17 volumio volumio[1154]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Aug 26 16:30:17 volumio volumio[1154]: info: VolumeController:: Volume=undefined Mute =false Aug 26 16:30:17 volumio volumio[1154]: info: CoreStateMachine::pushState Aug 26 16:30:17 volumio volumio[1154]: info: CorePlayQueue::getTrack 0 Aug 26 16:30:17 volumio volumio[1154]: info: CoreCommandRouter::volumioPushState Aug 26 16:30:17 volumio volumio[1154]: info: CoreStateMachine::updateTrackBlock Aug 26 16:30:17 volumio volumio[1154]: info: CorePlayQueue::getTrackBlock Aug 26 16:30:17 volumio volumio[1154]: info: CoreCommandRouter::volumioRetrievevolume Aug 26 16:30:17 volumio sudo[2945]: pam_unix(sudo:session): session closed for user root Aug 26 16:30:17 volumio volumio[1154]: info: PeppyMeterBasic ---peppymeterbasic status pause Aug 26 16:30:17 volumio sudo[2966]: pam_unix(sudo:session): session closed for user root Aug 26 16:30:17 volumio volumio[1154]: info: PeppySpectrum ---peppyspectrum status pause Aug 26 16:30:17 volumio sudo[2969]: pam_unix(sudo:session): session closed for user root Aug 26 16:30:17 volumio sudo[2976]: pam_unix(sudo:session): session closed for user root Aug 26 16:30:17 volumio sudo[2986]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Aug 26 16:30:17 volumio sudo[2986]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 16:30:17 volumio sudo[2978]: pam_unix(sudo:session): session closed for user root Aug 26 16:30:17 volumio volumio[1154]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Aug 26 16:30:17 volumio sudo[2986]: pam_unix(sudo:session): session closed for user root Aug 26 16:30:17 volumio sudo[2989]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Aug 26 16:30:17 volumio sudo[2989]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 16:30:17 volumio volumio[1154]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Aug 26 16:30:17 volumio sudo[2989]: pam_unix(sudo:session): session closed for user root Aug 26 16:30:17 volumio volumio[1154]: info: VolumeController:: Volume=undefined Mute =false Aug 26 16:30:17 volumio volumio[1154]: info: CoreStateMachine::pushState Aug 26 16:30:17 volumio volumio[1154]: info: CorePlayQueue::getTrack 0 Aug 26 16:30:17 volumio volumio[1154]: info: CoreCommandRouter::volumioPushState Aug 26 16:30:17 volumio volumio[1154]: info: peppyspectrum Daemon Stop Aug 26 16:30:17 volumio volumio[1154]: info: peppymeterbasic Daemon Stop Aug 26 16:30:17 volumio volumio[1154]: info: peppyspectrum Daemon Stop Aug 26 16:30:17 volumio volumio[1154]: info: peppymeterbasic Daemon Stop Aug 26 16:30:17 volumio volumio[1154]: info: peppyspectrum Daemon Stop Aug 26 16:30:17 volumio volumio[1154]: info: peppymeterbasic Daemon Stop Aug 26 16:30:17 volumio volumio[1154]: info: PeppyMeterBasic ---peppymeterbasic status pause Aug 26 16:30:17 volumio sudo[2993]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Aug 26 16:30:17 volumio sudo[2993]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 16:30:17 volumio volumio[1154]: info: PeppySpectrum ---peppyspectrum status pause Aug 26 16:30:17 volumio sudo[2998]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Aug 26 16:30:17 volumio sudo[2998]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 16:30:17 volumio sudo[2993]: pam_unix(sudo:session): session closed for user root Aug 26 16:30:17 volumio volumio[1154]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl stop squeezelite || true Aug 26 16:30:17 volumio sudo[2998]: pam_unix(sudo:session): session closed for user root Aug 26 16:30:17 volumio sudo[3001]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Aug 26 16:30:17 volumio sudo[3001]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 16:30:17 volumio volumio[1154]: info: peppymeterbasic Daemon Stop Aug 26 16:30:17 volumio sudo[3001]: pam_unix(sudo:session): session closed for user root Aug 26 16:30:17 volumio volumio[1154]: info: peppyspectrum Daemon Stop Aug 26 16:30:17 volumio sudo[3004]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop squeezelite Aug 26 16:30:17 volumio sudo[3004]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 16:30:17 volumio systemd[1]: Stopping Squeezelite lightweight headless Squeezebox emulator... Aug 26 16:30:17 volumio systemd[1]: squeezelite.service: Succeeded. Aug 26 16:30:17 volumio systemd[1]: Stopped Squeezelite lightweight headless Squeezebox emulator. Aug 26 16:30:17 volumio sudo[3004]: pam_unix(sudo:session): session closed for user root Aug 26 16:30:18 volumio volumio[1154]: info: ___________ PLUGINS: Run onVolumioReboot Tasks ___________ Aug 26 16:30:18 volumio volumio[1154]: info: PLUGIN onReboot : networkfs Aug 26 16:30:18 volumio volumio[1154]: info: PLUGIN onReboot : audiophonicsonoff Aug 26 16:30:18 volumio volumio[1154]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 26 16:30:18 volumio volumio[1154]: TypeError: Cannot read property 'writeSync' of undefined Aug 26 16:30:18 volumio volumio[1154]: at ControllerAudiophonicsOnOff.onVolumioReboot (/data/plugins/system_controller/audiophonicsonoff/index.js:35:25) Aug 26 16:30:18 volumio volumio[1154]: at PluginManager.onVolumioRebootPlugin (/volumio/app/pluginmanager.js:684:30) Aug 26 16:30:18 volumio volumio[1154]: at HashMap. (/volumio/app/pluginmanager.js:668:31) Aug 26 16:30:18 volumio volumio[1154]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Aug 26 16:30:18 volumio volumio[1154]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Aug 26 16:30:18 volumio volumio[1154]: at PluginManager.onVolumioReboot (/volumio/app/pluginmanager.js:666:20) Aug 26 16:30:18 volumio volumio[1154]: at CoreCommandRouter.reboot (/volumio/app/index.js:1331:22) Aug 26 16:30:18 volumio volumio[1154]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:864:33) Aug 26 16:30:18 volumio volumio[1154]: at Socket.emit (events.js:315:20) Aug 26 16:30:18 volumio volumio[1154]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Aug 26 16:30:18 volumio volumio[1154]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Aug 26 16:30:18 volumio volumio[1154]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 26 16:30:18 volumio sudo[3015]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-26 16:29 Aug 26 16:30:18 volumio sudo[3015]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster 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" VOLUMIO_BUILD_VERSION="5fd3886148e72a9cecd88a772d1f6079fb64d11a" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8bb23b009e91ae4c7ecd678f193e7d3b281fca0c" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 22 Jul 2024 07:17:01 PM CEST" VOLUMIO_VERSION="3.742" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="2782e52360f70dfab079c82951ebbbb1"