-- Logs begin at Mon 2025-10-27 00:32:21 CST, end at Mon 2025-10-27 00:36:42 CST. -- Oct 27 00:35:00 volumio-x86-784 volumio[814]: info: Oct 27 00:35:00 volumio-x86-784 volumio[814]: ---------------------------- MPD announces state update: player Oct 27 00:35:00 volumio-x86-784 volumio[814]: info: ControllerMpd::getState Oct 27 00:35:00 volumio-x86-784 volumio[814]: verbose: ControllerMpd::sendMpdCommand status Oct 27 00:35:01 volumio-x86-784 volumio[814]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Oct 27 00:35:01 volumio-x86-784 volumio[814]: info: Retrying Login Due to Network Error Oct 27 00:35:01 volumio-x86-784 volumio[814]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E6%9D%8E%E6%99%93%E6%9D%B0/%E8%80%81%E5%A4%A7/8eff7bd4-ee1b-4877-b0aa-7e679bd3d341.jpg' Oct 27 00:35:01 volumio-x86-784 volumio[814]: info: Oct 27 00:35:01 volumio-x86-784 volumio[814]: ---------------------------- MPD announces state update: player Oct 27 00:35:01 volumio-x86-784 volumio[814]: info: ControllerMpd::getState Oct 27 00:35:01 volumio-x86-784 volumio[814]: verbose: ControllerMpd::sendMpdCommand status Oct 27 00:35:01 volumio-x86-784 volumio[814]: info: sendMpdCommand status took 1623 milliseconds Oct 27 00:35:01 volumio-x86-784 volumio[814]: verbose: ControllerMpd::parseState Oct 27 00:35:01 volumio-x86-784 volumio[814]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 27 00:35:01 volumio-x86-784 volumio[814]: info: sendMpdCommand status took 17 milliseconds Oct 27 00:35:01 volumio-x86-784 volumio[814]: info: sendMpdCommand playlistinfo took 17 milliseconds Oct 27 00:35:01 volumio-x86-784 volumio[814]: verbose: ControllerMpd::parseState Oct 27 00:35:01 volumio-x86-784 volumio[814]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 27 00:35:01 volumio-x86-784 volumio[814]: verbose: ControllerMpd::parseTrackInfo Oct 27 00:35:01 volumio-x86-784 volumio[814]: info: ControllerMpd::pushState Oct 27 00:35:01 volumio-x86-784 volumio[814]: info: CoreCommandRouter::servicePushState Oct 27 00:35:01 volumio-x86-784 volumio[814]: info: CorePlayQueue::getTrack 0 Oct 27 00:35:01 volumio-x86-784 volumio[814]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1570,"duration":258,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"849 Kbps","isStreaming":false,"title":"恰似你的温柔","artist":"蔡琴","album":"蔡琴经典·壹","uri":"NAS/飞牛/按歌手分类/陈瑞《试音陈瑞》/蔡琴《试机蔡琴》2资源类型：WAV+CUE/蔡琴《试机蔡琴》2资源类型：WAV+CUE/蔡琴 - 01.恰似你的温柔.flac","trackType":"flac"} Oct 27 00:35:01 volumio-x86-784 volumio[814]: verbose: CURRENT POSITION 0 Oct 27 00:35:01 volumio-x86-784 volumio[814]: info: CoreStateMachine::syncState stateService play Oct 27 00:35:01 volumio-x86-784 volumio[814]: info: CoreStateMachine::syncState currentStatus stop Oct 27 00:35:01 volumio-x86-784 volumio[814]: info: ------------------------------ 1648ms Oct 27 00:35:01 volumio-x86-784 volumio[814]: info: sendMpdCommand playlistinfo took 31 milliseconds Oct 27 00:35:01 volumio-x86-784 volumio[814]: verbose: ControllerMpd::parseTrackInfo Oct 27 00:35:01 volumio-x86-784 volumio[814]: info: ControllerMpd::pushState Oct 27 00:35:01 volumio-x86-784 volumio[814]: info: CoreCommandRouter::servicePushState Oct 27 00:35:01 volumio-x86-784 volumio[814]: info: CorePlayQueue::getTrack 0 Oct 27 00:35:01 volumio-x86-784 volumio[814]: verbose: STATE SERVICE {"status":"play","position":0,"seek":3187,"duration":258,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"559 Kbps","isStreaming":false,"title":"恰似你的温柔","artist":"蔡琴","album":"蔡琴经典·壹","uri":"NAS/飞牛/按歌手分类/陈瑞《试音陈瑞》/蔡琴《试机蔡琴》2资源类型：WAV+CUE/蔡琴《试机蔡琴》2资源类型：WAV+CUE/蔡琴 - 01.恰似你的温柔.flac","trackType":"flac"} Oct 27 00:35:01 volumio-x86-784 volumio[814]: verbose: CURRENT POSITION 0 Oct 27 00:35:01 volumio-x86-784 volumio[814]: info: CoreStateMachine::syncState stateService play Oct 27 00:35:01 volumio-x86-784 volumio[814]: info: CoreStateMachine::syncState currentStatus play Oct 27 00:35:01 volumio-x86-784 volumio[814]: info: Received an update from plugin. extracting info from payload Oct 27 00:35:01 volumio-x86-784 volumio[814]: info: CoreStateMachine::pushState Oct 27 00:35:01 volumio-x86-784 volumio[814]: info: CorePlayQueue::getTrack 0 Oct 27 00:35:01 volumio-x86-784 volumio[814]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 27 00:35:01 volumio-x86-784 volumio[814]: info: CoreCommandRouter::volumioPushState Oct 27 00:35:01 volumio-x86-784 volumio[814]: info: CoreStateMachine::pushState Oct 27 00:35:01 volumio-x86-784 volumio[814]: info: CorePlayQueue::getTrack 0 Oct 27 00:35:01 volumio-x86-784 volumio[814]: info: CoreCommandRouter::volumioPushState Oct 27 00:35:01 volumio-x86-784 volumio[814]: info: ------------------------------ 111ms Oct 27 00:35:02 volumio-x86-784 volumio[814]: info: CoreCommandRouter::volumioGetState Oct 27 00:35:02 volumio-x86-784 volumio[814]: info: CorePlayQueue::getTrack 0 Oct 27 00:35:02 volumio-x86-784 screensaver.sh[558]: Exception in thread Thread-54: Oct 27 00:35:02 volumio-x86-784 screensaver.sh[558]: Traceback (most recent call last): Oct 27 00:35:02 volumio-x86-784 screensaver.sh[558]: File "/usr/lib/python3.7/threading.py", line 917, in _bootstrap_inner Oct 27 00:35:02 volumio-x86-784 screensaver.sh[558]: self.run() Oct 27 00:35:02 volumio-x86-784 screensaver.sh[558]: File "/usr/lib/python3.7/threading.py", line 865, in run Oct 27 00:35:02 volumio-x86-784 screensaver.sh[558]: self._target(*self._args, **self._kwargs) Oct 27 00:35:02 volumio-x86-784 screensaver.sh[558]: File "/usr/local/lib/python3.7/dist-packages/socketio/client.py", line 616, in _handle_eio_message Oct 27 00:35:02 volumio-x86-784 screensaver.sh[558]: self._handle_event(pkt.namespace, pkt.id, pkt.data) Oct 27 00:35:02 volumio-x86-784 screensaver.sh[558]: File "/usr/local/lib/python3.7/dist-packages/socketio/client.py", line 500, in _handle_event Oct 27 00:35:02 volumio-x86-784 screensaver.sh[558]: r = self._trigger_event(data[0], namespace, *data[1:]) Oct 27 00:35:02 volumio-x86-784 screensaver.sh[558]: File "/usr/local/lib/python3.7/dist-packages/socketio/client.py", line 550, in _trigger_event Oct 27 00:35:02 volumio-x86-784 screensaver.sh[558]: return self.handlers[namespace][event](*args) Oct 27 00:35:02 volumio-x86-784 screensaver.sh[558]: File "/data/plugins/user_interface/peppy_screensaver/screensaver/volumio_albumart.py", line 71, in on_push_state Oct 27 00:35:02 volumio-x86-784 screensaver.sh[558]: self.AA_Thread.start() Oct 27 00:35:02 volumio-x86-784 screensaver.sh[558]: File "/usr/lib/python3.7/threading.py", line 847, in start Oct 27 00:35:02 volumio-x86-784 screensaver.sh[558]: _start_new_thread(self._bootstrap, ()) Oct 27 00:35:02 volumio-x86-784 screensaver.sh[558]: RuntimeError: can't start new thread Oct 27 00:35:02 volumio-x86-784 screensaver.sh[558]: Exception in thread Thread-77: Oct 27 00:35:02 volumio-x86-784 screensaver.sh[558]: Traceback (most recent call last): Oct 27 00:35:02 volumio-x86-784 screensaver.sh[558]: File "/usr/lib/python3.7/threading.py", line 917, in _bootstrap_inner Oct 27 00:35:02 volumio-x86-784 screensaver.sh[558]: self.run() Oct 27 00:35:02 volumio-x86-784 screensaver.sh[558]: File "/usr/lib/python3.7/threading.py", line 865, in run Oct 27 00:35:02 volumio-x86-784 screensaver.sh[558]: self._target(*self._args, **self._kwargs) Oct 27 00:35:02 volumio-x86-784 screensaver.sh[558]: File "/usr/local/lib/python3.7/dist-packages/socketio/client.py", line 616, in _handle_eio_message Oct 27 00:35:02 volumio-x86-784 screensaver.sh[558]: self._handle_event(pkt.namespace, pkt.id, pkt.data) Oct 27 00:35:02 volumio-x86-784 screensaver.sh[558]: File "/usr/local/lib/python3.7/dist-packages/socketio/client.py", line 500, in _handle_event Oct 27 00:35:02 volumio-x86-784 screensaver.sh[558]: r = self._trigger_event(data[0], namespace, *data[1:]) Oct 27 00:35:02 volumio-x86-784 screensaver.sh[558]: File "/usr/local/lib/python3.7/dist-packages/socketio/client.py", line 550, in _trigger_event Oct 27 00:35:02 volumio-x86-784 screensaver.sh[558]: return self.handlers[namespace][event](*args) Oct 27 00:35:02 volumio-x86-784 screensaver.sh[558]: File "/data/plugins/user_interface/peppy_screensaver/screensaver/volumio_albumart.py", line 83, in on_push_state Oct 27 00:35:02 volumio-x86-784 screensaver.sh[558]: TI_Thread.start() Oct 27 00:35:02 volumio-x86-784 screensaver.sh[558]: File "/usr/lib/python3.7/threading.py", line 847, in start Oct 27 00:35:02 volumio-x86-784 screensaver.sh[558]: _start_new_thread(self._bootstrap, ()) Oct 27 00:35:02 volumio-x86-784 screensaver.sh[558]: RuntimeError: can't start new thread Oct 27 00:35:02 volumio-x86-784 screensaver.sh[558]: Exception in thread Thread-74: Oct 27 00:35:02 volumio-x86-784 screensaver.sh[558]: Traceback (most recent call last): Oct 27 00:35:02 volumio-x86-784 screensaver.sh[558]: File "/usr/lib/python3.7/threading.py", line 917, in _bootstrap_inner Oct 27 00:35:02 volumio-x86-784 screensaver.sh[558]: self.run() Oct 27 00:35:02 volumio-x86-784 screensaver.sh[558]: File "/usr/lib/python3.7/threading.py", line 865, in run Oct 27 00:35:02 volumio-x86-784 screensaver.sh[558]: self._target(*self._args, **self._kwargs) Oct 27 00:35:02 volumio-x86-784 screensaver.sh[558]: File "/usr/local/lib/python3.7/dist-packages/socketio/client.py", line 616, in _handle_eio_message Oct 27 00:35:02 volumio-x86-784 screensaver.sh[558]: self._handle_event(pkt.namespace, pkt.id, pkt.data) Oct 27 00:35:02 volumio-x86-784 screensaver.sh[558]: File "/usr/local/lib/python3.7/dist-packages/socketio/client.py", line 500, in _handle_event Oct 27 00:35:02 volumio-x86-784 screensaver.sh[558]: r = self._trigger_event(data[0], namespace, *data[1:]) Oct 27 00:35:02 volumio-x86-784 screensaver.sh[558]: File "/usr/local/lib/python3.7/dist-packages/socketio/client.py", line 550, in _trigger_event Oct 27 00:35:02 volumio-x86-784 screensaver.sh[558]: return self.handlers[namespace][event](*args) Oct 27 00:35:02 volumio-x86-784 screensaver.sh[558]: File "/data/plugins/user_interface/peppy_screensaver/screensaver/volumio_random.py", line 111, in on_push_state Oct 27 00:35:02 volumio-x86-784 screensaver.sh[558]: meter_restart() # own restart fadeIn Oct 27 00:35:02 volumio-x86-784 screensaver.sh[558]: File "/data/plugins/user_interface/peppy_screensaver/screensaver/volumio_random.py", line 67, in meter_restart Oct 27 00:35:02 volumio-x86-784 screensaver.sh[558]: self.meter.util.PYGAME_SCREEN = screen.copy() Oct 27 00:35:02 volumio-x86-784 screensaver.sh[558]: pygame.error: Out of memory Oct 27 00:35:02 volumio-x86-784 screensaver.sh[558]: Exception in thread Thread-126: Oct 27 00:35:02 volumio-x86-784 screensaver.sh[558]: Traceback (most recent call last): Oct 27 00:35:02 volumio-x86-784 screensaver.sh[558]: File "/usr/lib/python3.7/threading.py", line 917, in _bootstrap_inner Oct 27 00:35:02 volumio-x86-784 screensaver.sh[558]: self.run() Oct 27 00:35:02 volumio-x86-784 screensaver.sh[558]: File "/usr/lib/python3.7/threading.py", line 865, in run Oct 27 00:35:02 volumio-x86-784 screensaver.sh[558]: self._target(*self._args, **self._kwargs) Oct 27 00:35:02 volumio-x86-784 screensaver.sh[558]: File "/usr/local/lib/python3.7/dist-packages/socketio/client.py", line 616, in _handle_eio_message Oct 27 00:35:02 volumio-x86-784 screensaver.sh[558]: self._handle_event(pkt.namespace, pkt.id, pkt.data) Oct 27 00:35:02 volumio-x86-784 screensaver.sh[558]: File "/usr/local/lib/python3.7/dist-packages/socketio/client.py", line 500, in _handle_event Oct 27 00:35:02 volumio-x86-784 screensaver.sh[558]: r = self._trigger_event(data[0], namespace, *data[1:]) Oct 27 00:35:02 volumio-x86-784 screensaver.sh[558]: File "/usr/local/lib/python3.7/dist-packages/socketio/client.py", line 550, in _trigger_event Oct 27 00:35:02 volumio-x86-784 screensaver.sh[558]: return self.handlers[namespace][event](*args) Oct 27 00:35:02 volumio-x86-784 screensaver.sh[558]: File "/data/plugins/user_interface/peppy_screensaver/screensaver/volumio_albumart.py", line 83, in on_push_state Oct 27 00:35:02 volumio-x86-784 screensaver.sh[558]: TI_Thread.start() Oct 27 00:35:02 volumio-x86-784 screensaver.sh[558]: File "/usr/lib/python3.7/threading.py", line 847, in start Oct 27 00:35:02 volumio-x86-784 screensaver.sh[558]: _start_new_thread(self._bootstrap, ()) Oct 27 00:35:02 volumio-x86-784 screensaver.sh[558]: RuntimeError: can't start new thread Oct 27 00:35:02 volumio-x86-784 screensaver.sh[558]: Exception in thread Thread-149: Oct 27 00:35:02 volumio-x86-784 screensaver.sh[558]: Traceback (most recent call last): Oct 27 00:35:02 volumio-x86-784 screensaver.sh[558]: File "/usr/lib/python3.7/threading.py", line 917, in _bootstrap_inner Oct 27 00:35:02 volumio-x86-784 screensaver.sh[558]: self.run() Oct 27 00:35:02 volumio-x86-784 screensaver.sh[558]: File "/usr/lib/python3.7/threading.py", line 865, in run Oct 27 00:35:02 volumio-x86-784 screensaver.sh[558]: self._target(*self._args, **self._kwargs) Oct 27 00:35:02 volumio-x86-784 screensaver.sh[558]: File "/usr/local/lib/python3.7/dist-packages/socketio/client.py", line 616, in _handle_eio_message Oct 27 00:35:02 volumio-x86-784 screensaver.sh[558]: self._handle_event(pkt.namespace, pkt.id, pkt.data) Oct 27 00:35:02 volumio-x86-784 screensaver.sh[558]: File "/usr/local/lib/python3.7/dist-packages/socketio/client.py", line 500, in _handle_event Oct 27 00:35:02 volumio-x86-784 screensaver.sh[558]: r = self._trigger_event(data[0], namespace, *data[1:]) Oct 27 00:35:02 volumio-x86-784 screensaver.sh[558]: File "/usr/local/lib/python3.7/dist-packages/socketio/client.py", line 550, in _trigger_event Oct 27 00:35:02 volumio-x86-784 screensaver.sh[558]: return self.handlers[namespace][event](*args) Oct 27 00:35:02 volumio-x86-784 screensaver.sh[558]: File "/data/plugins/user_interface/peppy_screensaver/screensaver/volumio_albumart.py", line 71, in on_push_state Oct 27 00:35:02 volumio-x86-784 screensaver.sh[558]: self.AA_Thread.start() Oct 27 00:35:02 volumio-x86-784 screensaver.sh[558]: File "/usr/lib/python3.7/threading.py", line 847, in start Oct 27 00:35:02 volumio-x86-784 screensaver.sh[558]: _start_new_thread(self._bootstrap, ()) Oct 27 00:35:02 volumio-x86-784 screensaver.sh[558]: RuntimeError: can't start new thread Oct 27 00:35:02 volumio-x86-784 screensaver.sh[558]: Exception in thread Thread-123: Oct 27 00:35:02 volumio-x86-784 screensaver.sh[558]: Traceback (most recent call last): Oct 27 00:35:02 volumio-x86-784 screensaver.sh[558]: File "/usr/lib/python3.7/threading.py", line 917, in _bootstrap_inner Oct 27 00:35:02 volumio-x86-784 screensaver.sh[558]: self.run() Oct 27 00:35:02 volumio-x86-784 screensaver.sh[558]: File "/usr/lib/python3.7/threading.py", line 865, in run Oct 27 00:35:02 volumio-x86-784 screensaver.sh[558]: self._target(*self._args, **self._kwargs) Oct 27 00:35:02 volumio-x86-784 screensaver.sh[558]: File "/usr/local/lib/python3.7/dist-packages/engineio/client.py", line 669, in _read_loop_websocket Oct 27 00:35:02 volumio-x86-784 screensaver.sh[558]: self._receive_packet(pkt) Oct 27 00:35:02 volumio-x86-784 screensaver.sh[558]: File "/usr/local/lib/python3.7/dist-packages/engineio/client.py", line 508, in _receive_packet Oct 27 00:35:02 volumio-x86-784 screensaver.sh[558]: self._trigger_event('message', pkt.data, run_async=True) Oct 27 00:35:02 volumio-x86-784 screensaver.sh[558]: File "/usr/local/lib/python3.7/dist-packages/engineio/client.py", line 546, in _trigger_event Oct 27 00:35:02 volumio-x86-784 screensaver.sh[558]: return self.start_background_task(self.handlers[event], *args) Oct 27 00:35:02 volumio-x86-784 screensaver.sh[558]: File "/usr/local/lib/python3.7/dist-packages/engineio/client.py", line 265, in start_background_task Oct 27 00:35:02 volumio-x86-784 screensaver.sh[558]: th.start() Oct 27 00:35:02 volumio-x86-784 screensaver.sh[558]: File "/usr/lib/python3.7/threading.py", line 847, in start Oct 27 00:35:02 volumio-x86-784 screensaver.sh[558]: _start_new_thread(self._bootstrap, ()) Oct 27 00:35:02 volumio-x86-784 screensaver.sh[558]: RuntimeError: can't start new thread Oct 27 00:35:02 volumio-x86-784 screensaver.sh[558]: Exception in thread Thread-147: Oct 27 00:35:02 volumio-x86-784 screensaver.sh[558]: Traceback (most recent call last): Oct 27 00:35:02 volumio-x86-784 screensaver.sh[558]: File "/usr/lib/python3.7/threading.py", line 917, in _bootstrap_inner Oct 27 00:35:02 volumio-x86-784 screensaver.sh[558]: self.run() Oct 27 00:35:02 volumio-x86-784 screensaver.sh[558]: File "/usr/lib/python3.7/threading.py", line 865, in run Oct 27 00:35:02 volumio-x86-784 screensaver.sh[558]: self._target(*self._args, **self._kwargs) Oct 27 00:35:02 volumio-x86-784 screensaver.sh[558]: File "/usr/local/lib/python3.7/dist-packages/socketio/client.py", line 616, in _handle_eio_message Oct 27 00:35:02 volumio-x86-784 screensaver.sh[558]: self._handle_event(pkt.namespace, pkt.id, pkt.data) Oct 27 00:35:02 volumio-x86-784 screensaver.sh[558]: File "/usr/local/lib/python3.7/dist-packages/socketio/client.py", line 500, in _handle_event Oct 27 00:35:02 volumio-x86-784 screensaver.sh[558]: r = self._trigger_event(data[0], namespace, *data[1:]) Oct 27 00:35:02 volumio-x86-784 screensaver.sh[558]: File "/usr/local/lib/python3.7/dist-packages/socketio/client.py", line 550, in _trigger_event Oct 27 00:35:02 volumio-x86-784 screensaver.sh[558]: return self.handlers[namespace][event](*args) Oct 27 00:35:02 volumio-x86-784 screensaver.sh[558]: File "/data/plugins/user_interface/peppy_screensaver/screensaver/volumio_random.py", line 111, in on_push_state Oct 27 00:35:02 volumio-x86-784 screensaver.sh[558]: meter_restart() # own restart fadeIn Oct 27 00:35:02 volumio-x86-784 screensaver.sh[558]: File "/data/plugins/user_interface/peppy_screensaver/screensaver/volumio_random.py", line 67, in meter_restart Oct 27 00:35:02 volumio-x86-784 screensaver.sh[558]: self.meter.util.PYGAME_SCREEN = screen.copy() Oct 27 00:35:02 volumio-x86-784 screensaver.sh[558]: pygame.error: Out of memory Oct 27 00:35:02 volumio-x86-784 screensaver.sh[558]: Exception in thread Thread-164: Oct 27 00:35:02 volumio-x86-784 screensaver.sh[558]: Traceback (most recent call last): Oct 27 00:35:02 volumio-x86-784 screensaver.sh[558]: File "/usr/lib/python3.7/threading.py", line 917, in _bootstrap_inner Oct 27 00:35:02 volumio-x86-784 screensaver.sh[558]: self.run() Oct 27 00:35:02 volumio-x86-784 screensaver.sh[558]: File "/usr/lib/python3.7/threading.py", line 865, in run Oct 27 00:35:02 volumio-x86-784 screensaver.sh[558]: self._target(*self._args, **self._kwargs) Oct 27 00:35:02 volumio-x86-784 screensaver.sh[558]: File "/usr/local/lib/python3.7/dist-packages/socketio/client.py", line 616, in _handle_eio_message Oct 27 00:35:02 volumio-x86-784 screensaver.sh[558]: self._handle_event(pkt.namespace, pkt.id, pkt.data) Oct 27 00:35:02 volumio-x86-784 screensaver.sh[558]: File "/usr/local/lib/python3.7/dist-packages/socketio/client.py", line 500, in _handle_event Oct 27 00:35:02 volumio-x86-784 screensaver.sh[558]: r = self._trigger_event(data[0], namespace, *data[1:]) Oct 27 00:35:02 volumio-x86-784 screensaver.sh[558]: File "/usr/local/lib/python3.7/dist-packages/socketio/client.py", line 550, in _trigger_event Oct 27 00:35:02 volumio-x86-784 screensaver.sh[558]: return self.handlers[namespace][event](*args) Oct 27 00:35:02 volumio-x86-784 screensaver.sh[558]: File "/data/plugins/user_interface/peppy_screensaver/screensaver/volumio_random.py", line 111, in on_push_state Oct 27 00:35:02 volumio-x86-784 screensaver.sh[558]: meter_restart() # own restart fadeIn Oct 27 00:35:02 volumio-x86-784 screensaver.sh[558]: File "/data/plugins/user_interface/peppy_screensaver/screensaver/volumio_random.py", line 70, in meter_restart Oct 27 00:35:02 volumio-x86-784 screensaver.sh[558]: self.meter.meter.start() Oct 27 00:35:02 volumio-x86-784 screensaver.sh[558]: File "/data/plugins/user_interface/peppy_screensaver/screensaver/peppymeter/meter.py", line 165, in start Oct 27 00:35:02 volumio-x86-784 screensaver.sh[558]: c.content = img[1] Oct 27 00:35:02 volumio-x86-784 screensaver.sh[558]: TypeError: 'NoneType' object is not subscriptable Oct 27 00:35:11 volumio-x86-784 volumio[814]: info: MyVolumio login type: Token Oct 27 00:35:17 volumio-x86-784 volumio-remote-updater[581]: No test mode Oct 27 00:35:17 volumio-x86-784 volumio-remote-updater[581]: No alpha test mode Oct 27 00:35:19 volumio-x86-784 volumio[814]: info: CoreCommandRouter::Close All Modals sent Oct 27 00:35:21 volumio-x86-784 volumio[814]: error: [yt-cast-receiver] (YouTube Music) Error occurred in SendMessageTask: Oct 27 00:35:21 volumio-x86-784 volumio[814]: LoungeScreenDisconnected { Oct 27 00:35:21 volumio-x86-784 volumio[814]: AID: null, Oct 27 00:35:21 volumio-x86-784 volumio[814]: name: 'loungeScreenDisconnected', Oct 27 00:35:21 volumio-x86-784 volumio[814]: payload: {} Oct 27 00:35:21 volumio-x86-784 volumio[814]: } Oct 27 00:35:21 volumio-x86-784 volumio[814]: (IncompleteAPIDataError) Missing data required to construct query string from bind params Oct 27 00:35:21 volumio-x86-784 volumio[814]: Error info: { missing: [ 'loungeIdToken', 'SID', 'gsessionid' ] } Oct 27 00:35:21 volumio-x86-784 volumio[814]: Stack trace: Oct 27 00:35:21 volumio-x86-784 volumio[814]: IncompleteAPIDataError: Missing data required to construct query string from bind params Oct 27 00:35:21 volumio-x86-784 volumio[814]: at BindParams.toQueryString (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/BindParams.js:120:19) Oct 27 00:35:21 volumio-x86-784 volumio[814]: at Session._Session_doSendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:492:103) Oct 27 00:35:21 volumio-x86-784 volumio[814]: at SendMessageTask.run (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:552:71) Oct 27 00:35:21 volumio-x86-784 volumio[814]: at AsyncTaskQueue.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:51:36) Oct 27 00:35:21 volumio-x86-784 volumio[814]: at AsyncTaskQueue.push (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:39:18) Oct 27 00:35:21 volumio-x86-784 volumio[814]: at /data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:339:67 Oct 27 00:35:21 volumio-x86-784 volumio[814]: at new Promise () Oct 27 00:35:21 volumio-x86-784 volumio[814]: at Session.sendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:338:16) Oct 27 00:35:21 volumio-x86-784 volumio[814]: at Session.end (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:236:28) Oct 27 00:35:21 volumio-x86-784 volumio[814]: at Session.begin (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:207:26) Oct 27 00:35:21 volumio-x86-784 volumio[814]: at runMicrotasks () Oct 27 00:35:21 volumio-x86-784 volumio[814]: at processTicksAndRejections (internal/process/task_queues.js:95:5) Oct 27 00:35:21 volumio-x86-784 volumio[814]: at async Promise.all (index 1) Oct 27 00:35:21 volumio-x86-784 volumio[814]: at async YouTubeApp.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:110:13) Oct 27 00:35:21 volumio-x86-784 volumio[814]: at async YouTubeCastReceiver.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/YouTubeCastReceiver.js:91:13) Oct 27 00:35:21 volumio-x86-784 volumio[814]: error: [yt-cast-receiver] Failed to start receiver: Oct 27 00:35:21 volumio-x86-784 volumio[814]: (AppError) Failed to start YouTubeApp Oct 27 00:35:21 volumio-x86-784 volumio[814]: --->(SessionError) (YouTube Music) Failed to establish session Oct 27 00:35:21 volumio-x86-784 volumio[814]: ------>(ConnectionError) (YouTube Music) Connection error in generating screen Id Oct 27 00:35:21 volumio-x86-784 volumio[814]: Error info: { Oct 27 00:35:21 volumio-x86-784 volumio[814]: url: 'https://www.youtube.com/api/lounge/pairing/generate_screen_id' Oct 27 00:35:21 volumio-x86-784 volumio[814]: } Oct 27 00:35:21 volumio-x86-784 volumio[814]: --------->(FetchError) request to https://www.youtube.com/api/lounge/pairing/generate_screen_id failed, reason: connect ETIMEDOUT 31.13.94.36:443 Oct 27 00:35:21 volumio-x86-784 volumio[814]: Stack trace: Oct 27 00:35:21 volumio-x86-784 volumio[814]: AppError: Failed to start YouTubeApp Oct 27 00:35:21 volumio-x86-784 volumio[814]: at YouTubeApp.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:125:19) Oct 27 00:35:21 volumio-x86-784 volumio[814]: at runMicrotasks () Oct 27 00:35:21 volumio-x86-784 volumio[814]: at processTicksAndRejections (internal/process/task_queues.js:95:5) Oct 27 00:35:21 volumio-x86-784 volumio[814]: at async YouTubeCastReceiver.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/YouTubeCastReceiver.js:91:13) Oct 27 00:35:21 volumio-x86-784 volumio[814]: error: [yt-cast-receiver] (YouTube) Error occurred in SendMessageTask: Oct 27 00:35:21 volumio-x86-784 volumio[814]: LoungeScreenDisconnected { Oct 27 00:35:21 volumio-x86-784 volumio[814]: AID: null, Oct 27 00:35:21 volumio-x86-784 volumio[814]: name: 'loungeScreenDisconnected', Oct 27 00:35:21 volumio-x86-784 volumio[814]: payload: {} Oct 27 00:35:21 volumio-x86-784 volumio[814]: } Oct 27 00:35:21 volumio-x86-784 volumio[814]: (IncompleteAPIDataError) Missing data required to construct query string from bind params Oct 27 00:35:21 volumio-x86-784 volumio[814]: Error info: { missing: [ 'loungeIdToken', 'SID', 'gsessionid' ] } Oct 27 00:35:21 volumio-x86-784 volumio[814]: Stack trace: Oct 27 00:35:21 volumio-x86-784 volumio[814]: IncompleteAPIDataError: Missing data required to construct query string from bind params Oct 27 00:35:21 volumio-x86-784 volumio[814]: at BindParams.toQueryString (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/BindParams.js:120:19) Oct 27 00:35:21 volumio-x86-784 volumio[814]: at Session._Session_doSendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:492:103) Oct 27 00:35:21 volumio-x86-784 volumio[814]: at SendMessageTask.run (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:552:71) Oct 27 00:35:21 volumio-x86-784 volumio[814]: at AsyncTaskQueue.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:51:36) Oct 27 00:35:21 volumio-x86-784 volumio[814]: at AsyncTaskQueue.push (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:39:18) Oct 27 00:35:21 volumio-x86-784 volumio[814]: at /data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:339:67 Oct 27 00:35:21 volumio-x86-784 volumio[814]: at new Promise () Oct 27 00:35:21 volumio-x86-784 volumio[814]: at Session.sendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:338:16) Oct 27 00:35:21 volumio-x86-784 volumio[814]: at Session.end (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:236:28) Oct 27 00:35:21 volumio-x86-784 volumio[814]: at /data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:118:35 Oct 27 00:35:21 volumio-x86-784 volumio[814]: at Array.forEach () Oct 27 00:35:21 volumio-x86-784 volumio[814]: at YouTubeApp.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:114:24) Oct 27 00:35:21 volumio-x86-784 volumio[814]: at runMicrotasks () Oct 27 00:35:21 volumio-x86-784 volumio[814]: at processTicksAndRejections (internal/process/task_queues.js:95:5) Oct 27 00:35:21 volumio-x86-784 volumio[814]: at async YouTubeCastReceiver.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/YouTubeCastReceiver.js:91:13) Oct 27 00:35:21 volumio-x86-784 volumio[814]: error: [yt-cast-receiver] (YouTube Music) Error occurred in SendMessageTask: Oct 27 00:35:21 volumio-x86-784 volumio[814]: LoungeScreenDisconnected { Oct 27 00:35:21 volumio-x86-784 volumio[814]: AID: null, Oct 27 00:35:21 volumio-x86-784 volumio[814]: name: 'loungeScreenDisconnected', Oct 27 00:35:21 volumio-x86-784 volumio[814]: payload: {} Oct 27 00:35:21 volumio-x86-784 volumio[814]: } Oct 27 00:35:21 volumio-x86-784 volumio[814]: (IncompleteAPIDataError) Missing data required to construct query string from bind params Oct 27 00:35:21 volumio-x86-784 volumio[814]: Error info: { missing: [ 'loungeIdToken', 'SID', 'gsessionid' ] } Oct 27 00:35:21 volumio-x86-784 volumio[814]: Stack trace: Oct 27 00:35:21 volumio-x86-784 volumio[814]: IncompleteAPIDataError: Missing data required to construct query string from bind params Oct 27 00:35:21 volumio-x86-784 volumio[814]: at BindParams.toQueryString (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/BindParams.js:120:19) Oct 27 00:35:21 volumio-x86-784 volumio[814]: at Session._Session_doSendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:492:103) Oct 27 00:35:21 volumio-x86-784 volumio[814]: at SendMessageTask.run (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:552:71) Oct 27 00:35:21 volumio-x86-784 volumio[814]: at AsyncTaskQueue.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:51:36) Oct 27 00:35:21 volumio-x86-784 volumio[814]: at Session.end (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:237:71) Oct 27 00:35:21 volumio-x86-784 volumio[814]: at runMicrotasks () Oct 27 00:35:21 volumio-x86-784 volumio[814]: at processTicksAndRejections (internal/process/task_queues.js:95:5) Oct 27 00:35:21 volumio-x86-784 volumio[814]: error: [ytcr] Failed to start plugin: Oct 27 00:35:21 volumio-x86-784 volumio[814]: (AppError) Failed to start YouTubeApp Oct 27 00:35:21 volumio-x86-784 volumio[814]: --->(SessionError) (YouTube Music) Failed to establish session Oct 27 00:35:21 volumio-x86-784 volumio[814]: ------>(ConnectionError) (YouTube Music) Connection error in generating screen Id Oct 27 00:35:21 volumio-x86-784 volumio[814]: Error info: { Oct 27 00:35:21 volumio-x86-784 volumio[814]: url: 'https://www.youtube.com/api/lounge/pairing/generate_screen_id' Oct 27 00:35:21 volumio-x86-784 volumio[814]: } Oct 27 00:35:21 volumio-x86-784 volumio[814]: --------->(FetchError) request to https://www.youtube.com/api/lounge/pairing/generate_screen_id failed, reason: connect ETIMEDOUT 31.13.94.36:443 Oct 27 00:35:21 volumio-x86-784 volumio[814]: Stack trace: Oct 27 00:35:21 volumio-x86-784 volumio[814]: AppError: Failed to start YouTubeApp Oct 27 00:35:21 volumio-x86-784 volumio[814]: at YouTubeApp.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:125:19) Oct 27 00:35:21 volumio-x86-784 volumio[814]: at runMicrotasks () Oct 27 00:35:21 volumio-x86-784 volumio[814]: at processTicksAndRejections (internal/process/task_queues.js:95:5) Oct 27 00:35:21 volumio-x86-784 volumio[814]: at async YouTubeCastReceiver.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/YouTubeCastReceiver.js:91:13) Oct 27 00:35:21 volumio-x86-784 volumio[814]: error: [yt-cast-receiver] (YouTube) Error occurred in SendMessageTask: Oct 27 00:35:21 volumio-x86-784 volumio[814]: LoungeScreenDisconnected { Oct 27 00:35:21 volumio-x86-784 volumio[814]: AID: null, Oct 27 00:35:21 volumio-x86-784 volumio[814]: name: 'loungeScreenDisconnected', Oct 27 00:35:21 volumio-x86-784 volumio[814]: payload: {} Oct 27 00:35:21 volumio-x86-784 volumio[814]: } Oct 27 00:35:21 volumio-x86-784 volumio[814]: (IncompleteAPIDataError) Missing data required to construct query string from bind params Oct 27 00:35:21 volumio-x86-784 volumio[814]: Error info: { missing: [ 'loungeIdToken', 'SID', 'gsessionid' ] } Oct 27 00:35:21 volumio-x86-784 volumio[814]: Stack trace: Oct 27 00:35:21 volumio-x86-784 volumio[814]: IncompleteAPIDataError: Missing data required to construct query string from bind params Oct 27 00:35:21 volumio-x86-784 volumio[814]: at BindParams.toQueryString (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/BindParams.js:120:19) Oct 27 00:35:21 volumio-x86-784 volumio[814]: at Session._Session_doSendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:492:103) Oct 27 00:35:21 volumio-x86-784 volumio[814]: at SendMessageTask.run (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:552:71) Oct 27 00:35:21 volumio-x86-784 volumio[814]: at AsyncTaskQueue.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:51:36) Oct 27 00:35:21 volumio-x86-784 volumio[814]: at Session.end (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:237:71) Oct 27 00:35:21 volumio-x86-784 volumio[814]: at runMicrotasks () Oct 27 00:35:21 volumio-x86-784 volumio[814]: at processTicksAndRejections (internal/process/task_queues.js:95:5) Oct 27 00:35:21 volumio-x86-784 volumio[814]: at async /data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:118:21 Oct 27 00:35:24 volumio-x86-784 volumio[814]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"

FIXES

\n
    \n
  • Fix plugin installations due to apt repository
    • \n
  • Fix chromecast playback
    • \n
\n

NEW ADDITIONS

\n
    \n
  • Add link for downloading CORRD in the side-menu
    • \n
\n","title":"Update v3.832","updateavailable":true} Oct 27 00:35:24 volumio-x86-784 volumio[814]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Oct 27 00:35:30 volumio-x86-784 volumio[814]: info: Update: [object Object] Oct 27 00:35:30 volumio-x86-784 volumio[814]: info: CoreCommandRouter::executeOnPlugin: updater_comm , checkSystemIntegrity Oct 27 00:35:30 volumio-x86-784 volumio[814]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 27 00:35:41 volumio-x86-784 volumio[814]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Oct 27 00:35:41 volumio-x86-784 volumio[814]: info: Retrying Login Due to Network Error Oct 27 00:35:45 volumio-x86-784 volumio[814]: info: CoreCommandRouter::Close All Modals sent Oct 27 00:35:47 volumio-x86-784 volumio[814]: info: CoreCommandRouter::Close All Modals sent Oct 27 00:35:51 volumio-x86-784 volumio[814]: info: MyVolumio login type: Token Oct 27 00:35:52 volumio-x86-784 volumio[814]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 27 00:35:52 volumio-x86-784 volumio[814]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 27 00:35:52 volumio-x86-784 volumio[814]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 27 00:35:52 volumio-x86-784 volumio[814]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 27 00:35:53 volumio-x86-784 volumio[814]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 27 00:35:53 volumio-x86-784 volumio[814]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 27 00:35:53 volumio-x86-784 volumio[814]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 27 00:35:53 volumio-x86-784 volumio[814]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 27 00:35:53 volumio-x86-784 volumio[814]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Oct 27 00:35:53 volumio-x86-784 volumio[814]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 27 00:35:59 volumio-x86-784 volumio[814]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Oct 27 00:35:59 volumio-x86-784 volumio[814]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Oct 27 00:35:59 volumio-x86-784 volumio[814]: info: Preparing to save Alsa Options, stopping services first Oct 27 00:35:59 volumio-x86-784 volumio[814]: info: CoreCommandRouter::volumioGetState Oct 27 00:35:59 volumio-x86-784 volumio[814]: info: CorePlayQueue::getTrack 0 Oct 27 00:35:59 volumio-x86-784 volumio[814]: info: CoreCommandRouter::volumioPause Oct 27 00:35:59 volumio-x86-784 volumio[814]: info: CoreStateMachine::pause Oct 27 00:35:59 volumio-x86-784 volumio[814]: info: CoreStateMachine::stPlaybackTimer Oct 27 00:35:59 volumio-x86-784 volumio[814]: info: CoreStateMachine::servicePause Oct 27 00:35:59 volumio-x86-784 volumio[814]: info: CorePlayQueue::getTrack 0 Oct 27 00:35:59 volumio-x86-784 volumio[814]: info: CoreCommandRouter::servicePause Oct 27 00:35:59 volumio-x86-784 volumio[814]: info: ControllerMpd::pause Oct 27 00:35:59 volumio-x86-784 volumio[814]: verbose: ControllerMpd::sendMpdCommand pause Oct 27 00:35:59 volumio-x86-784 volumio[814]: info: Saving Audio Output to: {"output_device":{"value":"0,0","label":"Analog Out"}} Oct 27 00:35:59 volumio-x86-784 volumio[814]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 27 00:35:59 volumio-x86-784 volumio[814]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 27 00:35:59 volumio-x86-784 volumio[814]: info: Found match in Cards Database: setting mixer for card Analog Out Oct 27 00:35:59 volumio-x86-784 volumio[814]: info: Setting mixer Master for card Analog Out Oct 27 00:36:00 volumio-x86-784 volumio[814]: info: CoreCommandRouter::volumioUpdateVolumeSettings Oct 27 00:36:00 volumio-x86-784 volumio[814]: info: Updating Volume Controller Parameters: Device: 0,0 Name: Analog Out Mixer: Master Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Oct 27 00:36:00 volumio-x86-784 volumio[814]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Oct 27 00:36:00 volumio-x86-784 volumio[814]: info: Disabling external Volume Control Oct 27 00:36:00 volumio-x86-784 volumio[814]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 27 00:36:00 volumio-x86-784 volumio[814]: info: Preparing to generate the ALSA configuration file Oct 27 00:36:00 volumio-x86-784 volumio[814]: info: Oct 27 00:36:00 volumio-x86-784 volumio[814]: ---------------------------- MPD announces state update: player Oct 27 00:36:00 volumio-x86-784 volumio[814]: info: sendMpdCommand pause took 514 milliseconds Oct 27 00:36:00 volumio-x86-784 volumio[814]: info: ControllerMpd::getState Oct 27 00:36:00 volumio-x86-784 volumio[814]: verbose: ControllerMpd::sendMpdCommand status Oct 27 00:36:00 volumio-x86-784 volumio[814]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf Oct 27 00:36:00 volumio-x86-784 volumio[814]: info: Reading ALSA contributions from plugins. Oct 27 00:36:00 volumio-x86-784 volumio[814]: info: sendMpdCommand status took 89 milliseconds Oct 27 00:36:00 volumio-x86-784 volumio[814]: verbose: ControllerMpd::parseState Oct 27 00:36:00 volumio-x86-784 volumio[814]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 27 00:36:00 volumio-x86-784 volumio[814]: info: sendMpdCommand playlistinfo took 6 milliseconds Oct 27 00:36:00 volumio-x86-784 volumio[814]: verbose: ControllerMpd::parseTrackInfo Oct 27 00:36:00 volumio-x86-784 volumio[814]: info: ControllerMpd::pushState Oct 27 00:36:00 volumio-x86-784 volumio[814]: info: CoreCommandRouter::servicePushState Oct 27 00:36:00 volumio-x86-784 volumio[814]: info: CorePlayQueue::getTrack 0 Oct 27 00:36:00 volumio-x86-784 volumio[814]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":61583,"duration":258,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"816 Kbps","isStreaming":false,"title":"恰似你的温柔","artist":"蔡琴","album":"蔡琴经典·壹","uri":"NAS/飞牛/按歌手分类/陈瑞《试音陈瑞》/蔡琴《试机蔡琴》2资源类型：WAV+CUE/蔡琴《试机蔡琴》2资源类型：WAV+CUE/蔡琴 - 01.恰似你的温柔.flac","trackType":"flac"} Oct 27 00:36:00 volumio-x86-784 volumio[814]: verbose: CURRENT POSITION 0 Oct 27 00:36:00 volumio-x86-784 volumio[814]: info: CoreStateMachine::syncState stateService pause Oct 27 00:36:00 volumio-x86-784 volumio[814]: info: CoreStateMachine::syncState currentStatus pause Oct 27 00:36:00 volumio-x86-784 volumio[814]: info: CoreStateMachine::pushState Oct 27 00:36:00 volumio-x86-784 volumio[814]: info: CorePlayQueue::getTrack 0 Oct 27 00:36:00 volumio-x86-784 volumio[814]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 27 00:36:00 volumio-x86-784 volumio[814]: info: CoreCommandRouter::volumioPushState Oct 27 00:36:00 volumio-x86-784 volumio[814]: info: CoreStateMachine::stPlaybackTimer Oct 27 00:36:00 volumio-x86-784 volumio[814]: info: ------------------------------ 181ms Oct 27 00:36:00 volumio-x86-784 volumio[814]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 27 00:36:00 volumio-x86-784 volumio[814]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 27 00:36:00 volumio-x86-784 volumio[814]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 27 00:36:00 volumio-x86-784 volumio[814]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 27 00:36:00 volumio-x86-784 volumio[814]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 27 00:36:00 volumio-x86-784 volumio[814]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 27 00:36:00 volumio-x86-784 volumio[814]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 27 00:36:00 volumio-x86-784 volumio[814]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 27 00:36:00 volumio-x86-784 volumio[814]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 27 00:36:00 volumio-x86-784 volumio[814]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Oct 27 00:36:00 volumio-x86-784 volumio[814]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 27 00:36:00 volumio-x86-784 volumio[814]: info: VolumeController:: Volume=50 Mute =false Oct 27 00:36:00 volumio-x86-784 volumio[814]: info: CoreCommandRouter::volumioGetState Oct 27 00:36:00 volumio-x86-784 volumio[814]: info: CorePlayQueue::getTrack 0 Oct 27 00:36:00 volumio-x86-784 volumio[814]: info: CoreStateMachine::pushState Oct 27 00:36:00 volumio-x86-784 volumio[814]: info: CorePlayQueue::getTrack 0 Oct 27 00:36:00 volumio-x86-784 volumio[814]: info: CoreCommandRouter::volumioPushState Oct 27 00:36:00 volumio-x86-784 volumio[814]: info: Asound.conf file written Oct 27 00:36:01 volumio-x86-784 sudo[2867]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Oct 27 00:36:01 volumio-x86-784 sudo[2867]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 27 00:36:01 volumio-x86-784 sudo[2867]: pam_unix(sudo:session): session closed for user root Oct 27 00:36:01 volumio-x86-784 volumio[814]: info: Output device has changed, restarting MPD Oct 27 00:36:01 volumio-x86-784 volumio[814]: info: Output device has changed, restarting Shairport Sync Oct 27 00:36:01 volumio-x86-784 volumio[814]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 27 00:36:01 volumio-x86-784 volumio[814]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 27 00:36:01 volumio-x86-784 sudo[2873]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 27 00:36:01 volumio-x86-784 sudo[2875]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 27 00:36:01 volumio-x86-784 sudo[2873]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 27 00:36:01 volumio-x86-784 sudo[2875]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 27 00:36:01 volumio-x86-784 sudo[2873]: pam_unix(sudo:session): session closed for user root Oct 27 00:36:01 volumio-x86-784 volumio[814]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 27 00:36:01 volumio-x86-784 volumio[814]: info: Output device has changed, restarting MPD Oct 27 00:36:01 volumio-x86-784 volumio[814]: info: Output device has changed, restarting Shairport Sync Oct 27 00:36:01 volumio-x86-784 volumio[814]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 27 00:36:01 volumio-x86-784 volumio[814]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 27 00:36:01 volumio-x86-784 sudo[2882]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 27 00:36:01 volumio-x86-784 sudo[2882]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 27 00:36:01 volumio-x86-784 sudo[2884]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 27 00:36:01 volumio-x86-784 sudo[2884]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 27 00:36:01 volumio-x86-784 sudo[2882]: pam_unix(sudo:session): session closed for user root Oct 27 00:36:01 volumio-x86-784 systemd[1]: Stopping Music Player Daemon... Oct 27 00:36:01 volumio-x86-784 volumio[814]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 27 00:36:01 volumio-x86-784 volumio[814]: info: MPD Permissions set Oct 27 00:36:01 volumio-x86-784 volumio[814]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 27 00:36:01 volumio-x86-784 volumio[814]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 27 00:36:01 volumio-x86-784 volumio[814]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 27 00:36:01 volumio-x86-784 volumio[814]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 27 00:36:01 volumio-x86-784 volumio[814]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 27 00:36:02 volumio-x86-784 volumio[814]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 27 00:36:02 volumio-x86-784 volumio[814]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 27 00:36:02 volumio-x86-784 volumio[814]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 27 00:36:02 volumio-x86-784 volumio[814]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 27 00:36:02 volumio-x86-784 volumio[814]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 27 00:36:02 volumio-x86-784 volumio[814]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 27 00:36:02 volumio-x86-784 volumio[814]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 27 00:36:02 volumio-x86-784 volumio[814]: info: Starting Shairport Sync Oct 27 00:36:02 volumio-x86-784 volumio[814]: info: Starting Shairport Sync Oct 27 00:36:02 volumio-x86-784 sudo[2903]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 27 00:36:02 volumio-x86-784 sudo[2903]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 27 00:36:02 volumio-x86-784 sudo[2906]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 27 00:36:02 volumio-x86-784 sudo[2906]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 27 00:36:02 volumio-x86-784 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 27 00:36:02 volumio-x86-784 volumio[814]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 27 00:36:02 volumio-x86-784 systemd[1]: shairport-sync.service: Succeeded. Oct 27 00:36:02 volumio-x86-784 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 27 00:36:02 volumio-x86-784 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 27 00:36:02 volumio-x86-784 sudo[2906]: pam_unix(sudo:session): session closed for user root Oct 27 00:36:02 volumio-x86-784 sudo[2903]: pam_unix(sudo:session): session closed for user root Oct 27 00:36:02 volumio-x86-784 volumio[814]: info: Shairport-Sync Started Oct 27 00:36:02 volumio-x86-784 volumio[814]: error: Upnp client error: Error: read ECONNRESET Oct 27 00:36:02 volumio-x86-784 volumio[814]: info: Shairport-Sync Started Oct 27 00:36:04 volumio-x86-784 volumio[814]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Oct 27 00:36:04 volumio-x86-784 volumio[814]: info: CoreStateMachine::getcurrentVolume Oct 27 00:36:04 volumio-x86-784 volumio[814]: info: CoreCommandRouter::volumioRetrievevolume Oct 27 00:36:04 volumio-x86-784 systemd[1]: mpd.service: Succeeded. Oct 27 00:36:04 volumio-x86-784 systemd[1]: Stopped Music Player Daemon. Oct 27 00:36:04 volumio-x86-784 systemd[1]: Starting Music Player Daemon... Oct 27 00:36:04 volumio-x86-784 sudo[2913]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 27 00:36:04 volumio-x86-784 sudo[2913]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 27 00:36:04 volumio-x86-784 volumio[814]: info: VolumeController:: Volume=0 Mute =false Oct 27 00:36:04 volumio-x86-784 volumio[814]: info: CoreCommandRouter::volumioGetState Oct 27 00:36:04 volumio-x86-784 volumio[814]: info: CorePlayQueue::getTrack 0 Oct 27 00:36:04 volumio-x86-784 volumio[814]: info: CoreStateMachine::pushState Oct 27 00:36:04 volumio-x86-784 volumio[814]: info: CorePlayQueue::getTrack 0 Oct 27 00:36:04 volumio-x86-784 volumio[814]: info: CoreCommandRouter::volumioPushState Oct 27 00:36:04 volumio-x86-784 sudo[2913]: pam_unix(sudo:session): session closed for user root Oct 27 00:36:04 volumio-x86-784 volumio[814]: info: CoreStateMachine::updateTrackBlock Oct 27 00:36:04 volumio-x86-784 volumio[814]: info: CorePlayQueue::getTrackBlock Oct 27 00:36:04 volumio-x86-784 volumio[814]: info: CoreCommandRouter::volumioRetrievevolume Oct 27 00:36:05 volumio-x86-784 volumio[814]: info: VolumeController:: Volume=0 Mute =false Oct 27 00:36:05 volumio-x86-784 volumio[814]: info: CoreCommandRouter::volumioGetState Oct 27 00:36:05 volumio-x86-784 volumio[814]: info: CorePlayQueue::getTrack 0 Oct 27 00:36:05 volumio-x86-784 volumio[814]: info: CoreStateMachine::pushState Oct 27 00:36:05 volumio-x86-784 volumio[814]: info: CorePlayQueue::getTrack 0 Oct 27 00:36:05 volumio-x86-784 volumio[814]: info: CoreCommandRouter::volumioPushState Oct 27 00:36:05 volumio-x86-784 volumio[814]: info: CoreCommandRouter::volumioGetState Oct 27 00:36:05 volumio-x86-784 volumio[814]: info: CorePlayQueue::getTrack 0 Oct 27 00:36:05 volumio-x86-784 volumio[814]: info: CoreCommandRouter::volumioPlay Oct 27 00:36:05 volumio-x86-784 volumio[814]: info: CoreStateMachine::play index undefined Oct 27 00:36:05 volumio-x86-784 volumio[814]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 27 00:36:05 volumio-x86-784 volumio[814]: info: CorePlayQueue::getTrack 0 Oct 27 00:36:05 volumio-x86-784 volumio[814]: info: CoreStateMachine::startPlaybackTimer Oct 27 00:36:05 volumio-x86-784 volumio[814]: info: CorePlayQueue::getTrack 0 Oct 27 00:36:05 volumio-x86-784 volumio[814]: info: ControllerMpd::resume Oct 27 00:36:05 volumio-x86-784 volumio[814]: verbose: ControllerMpd::sendMpdCommand play Oct 27 00:36:05 volumio-x86-784 volumio[814]: error: MPD error: Error: This socket has been ended by the other party Oct 27 00:36:05 volumio-x86-784 volumio[814]: error: This socket has been ended by the other party {"code":"EPIPE"} Oct 27 00:36:05 volumio-x86-784 volumio[814]: error: MPD error: Error: This socket has been ended by the other party Oct 27 00:36:05 volumio-x86-784 volumio[814]: error: This socket has been ended by the other party {"code":"EPIPE"} Oct 27 00:36:05 volumio-x86-784 volumio[814]: error: MPD error: Error: This socket has been ended by the other party Oct 27 00:36:05 volumio-x86-784 volumio[814]: error: This socket has been ended by the other party {"code":"EPIPE"} Oct 27 00:36:06 volumio-x86-784 volumio[814]: error: Upnp client error: Error: This socket has been ended by the other party Oct 27 00:36:07 volumio-x86-784 mpd[2917]: Oct 27 00:36 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 27 00:36:07 volumio-x86-784 systemd[1]: Started Music Player Daemon. Oct 27 00:36:07 volumio-x86-784 volumio[814]: Upnp client error: Error: This socket has been ended by the other party Oct 27 00:36:07 volumio-x86-784 sudo[2884]: pam_unix(sudo:session): session closed for user root Oct 27 00:36:07 volumio-x86-784 sudo[2875]: pam_unix(sudo:session): session closed for user root Oct 27 00:36:07 volumio-x86-784 volumio[814]: error: MPD error: The expression evaluated to a falsy value: Oct 27 00:36:07 volumio-x86-784 volumio[814]: assert.ok(self.idling) Oct 27 00:36:07 volumio-x86-784 volumio[814]: error: The expression evaluated to a falsy value: Oct 27 00:36:07 volumio-x86-784 volumio[814]: assert.ok(self.idling) Oct 27 00:36:07 volumio-x86-784 volumio[814]: error: updateQueue error: null Oct 27 00:36:09 volumio-x86-784 volumio[814]: info: CoreCommandRouter::volumioPlay Oct 27 00:36:09 volumio-x86-784 volumio[814]: info: CoreStateMachine::play index undefined Oct 27 00:36:09 volumio-x86-784 volumio[814]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 27 00:36:09 volumio-x86-784 volumio[814]: info: CorePlayQueue::getTrack 0 Oct 27 00:36:09 volumio-x86-784 volumio[814]: info: CoreStateMachine::startPlaybackTimer Oct 27 00:36:09 volumio-x86-784 volumio[814]: info: CorePlayQueue::getTrack 0 Oct 27 00:36:09 volumio-x86-784 volumio[814]: info: ControllerMpd::resume Oct 27 00:36:09 volumio-x86-784 volumio[814]: verbose: ControllerMpd::sendMpdCommand play Oct 27 00:36:09 volumio-x86-784 volumio[814]: info: sendMpdCommand play took 2 milliseconds Oct 27 00:36:10 volumio-x86-784 volumio[814]: info: CoreCommandRouter::volumioPlay Oct 27 00:36:10 volumio-x86-784 volumio[814]: info: CoreStateMachine::play index undefined Oct 27 00:36:10 volumio-x86-784 volumio[814]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 27 00:36:10 volumio-x86-784 volumio[814]: info: CorePlayQueue::getTrack 0 Oct 27 00:36:10 volumio-x86-784 volumio[814]: info: CoreStateMachine::startPlaybackTimer Oct 27 00:36:10 volumio-x86-784 volumio[814]: info: CorePlayQueue::getTrack 0 Oct 27 00:36:10 volumio-x86-784 volumio[814]: info: ControllerMpd::resume Oct 27 00:36:10 volumio-x86-784 volumio[814]: verbose: ControllerMpd::sendMpdCommand play Oct 27 00:36:10 volumio-x86-784 volumio[814]: info: sendMpdCommand play took 3 milliseconds Oct 27 00:36:12 volumio-x86-784 volumio[814]: info: CoreCommandRouter::volumioNext Oct 27 00:36:12 volumio-x86-784 volumio[814]: info: CoreStateMachine::next Oct 27 00:36:12 volumio-x86-784 volumio[814]: info: CoreStateMachine::stop Oct 27 00:36:12 volumio-x86-784 volumio[814]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 27 00:36:12 volumio-x86-784 volumio[814]: info: CoreStateMachine::updateTrackBlock Oct 27 00:36:12 volumio-x86-784 volumio[814]: info: CorePlayQueue::getTrackBlock Oct 27 00:36:12 volumio-x86-784 volumio[814]: info: CoreStateMachine::stPlaybackTimer Oct 27 00:36:12 volumio-x86-784 volumio[814]: info: CoreStateMachine::pushState Oct 27 00:36:12 volumio-x86-784 volumio[814]: info: CorePlayQueue::getTrack 0 Oct 27 00:36:12 volumio-x86-784 volumio[814]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 27 00:36:12 volumio-x86-784 volumio[814]: info: CoreCommandRouter::volumioPushState Oct 27 00:36:12 volumio-x86-784 volumio[814]: info: CoreStateMachine::serviceStop Oct 27 00:36:12 volumio-x86-784 volumio[814]: info: CorePlayQueue::getTrack 0 Oct 27 00:36:12 volumio-x86-784 volumio[814]: info: CoreCommandRouter::serviceStop Oct 27 00:36:12 volumio-x86-784 volumio[814]: info: ControllerMpd::stop Oct 27 00:36:12 volumio-x86-784 volumio[814]: verbose: ControllerMpd::sendMpdCommand stop Oct 27 00:36:12 volumio-x86-784 volumio[814]: info: sendMpdCommand stop took 31 milliseconds Oct 27 00:36:12 volumio-x86-784 volumio[814]: info: CoreStateMachine::play index undefined Oct 27 00:36:12 volumio-x86-784 volumio[814]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 27 00:36:12 volumio-x86-784 volumio[814]: info: CorePlayQueue::getTrack 20 Oct 27 00:36:12 volumio-x86-784 volumio[814]: info: CoreStateMachine::startPlaybackTimer Oct 27 00:36:12 volumio-x86-784 volumio[814]: info: CorePlayQueue::getTrack 20 Oct 27 00:36:12 volumio-x86-784 volumio[814]: verbose: ControllerMpd::clearAddPlayTracks NAS/飞牛/按歌手分类/陈瑞《试音陈瑞》/陈瑞《试音陈瑞》/陈瑞 - 05.05.幸福女人.flac Oct 27 00:36:12 volumio-x86-784 volumio[814]: verbose: ControllerMpd::sendMpdCommand stop Oct 27 00:36:12 volumio-x86-784 volumio[814]: info: CoreStateMachine::updateTrackBlock Oct 27 00:36:12 volumio-x86-784 volumio[814]: info: CorePlayQueue::getTrackBlock Oct 27 00:36:12 volumio-x86-784 volumio[814]: info: sendMpdCommand stop took 46 milliseconds Oct 27 00:36:12 volumio-x86-784 volumio[814]: verbose: ControllerMpd::sendMpdCommand clear Oct 27 00:36:12 volumio-x86-784 volumio[814]: info: Oct 27 00:36:12 volumio-x86-784 volumio[814]: ---------------------------- MPD announces system playlist update Oct 27 00:36:12 volumio-x86-784 volumio[814]: info: Ignoring MPD Status Update Oct 27 00:36:12 volumio-x86-784 volumio[814]: info: sendMpdCommand clear took 5 milliseconds Oct 27 00:36:12 volumio-x86-784 volumio[814]: verbose: ControllerMpd::sendMpdCommand add "NAS/飞牛/按歌手分类/陈瑞《试音陈瑞》/陈瑞《试音陈瑞》/陈瑞 - 05.05.幸福女人.flac" Oct 27 00:36:12 volumio-x86-784 volumio[814]: info: Oct 27 00:36:12 volumio-x86-784 volumio[814]: ---------------------------- MPD announces system playlist update Oct 27 00:36:12 volumio-x86-784 volumio[814]: info: Ignoring MPD Status Update Oct 27 00:36:12 volumio-x86-784 volumio[814]: error: updateQueue error: null Oct 27 00:36:12 volumio-x86-784 volumio[814]: info: Oct 27 00:36:12 volumio-x86-784 volumio[814]: ---------------------------- MPD announces system playlist update Oct 27 00:36:12 volumio-x86-784 volumio[814]: info: Ignoring MPD Status Update Oct 27 00:36:12 volumio-x86-784 volumio[814]: info: ------------------------------ 36ms Oct 27 00:36:12 volumio-x86-784 volumio[814]: info: sendMpdCommand add "NAS/飞牛/按歌手分类/陈瑞《试音陈瑞》/陈瑞《试音陈瑞》/陈瑞 - 05.05.幸福女人.flac" took 34 milliseconds Oct 27 00:36:12 volumio-x86-784 volumio[814]: info: ------------------------------ 34ms Oct 27 00:36:12 volumio-x86-784 volumio[814]: verbose: ControllerMpd::sendMpdCommand play Oct 27 00:36:12 volumio-x86-784 volumio[814]: info: Oct 27 00:36:12 volumio-x86-784 volumio[814]: ---------------------------- MPD announces system playlist update Oct 27 00:36:12 volumio-x86-784 volumio[814]: info: Ignoring MPD Status Update Oct 27 00:36:12 volumio-x86-784 volumio[814]: info: ------------------------------ 27ms Oct 27 00:36:12 volumio-x86-784 volumio[814]: info: sendMpdCommand play took 8 milliseconds Oct 27 00:36:12 volumio-x86-784 volumio[814]: info: ------------------------------ 5ms Oct 27 00:36:12 volumio-x86-784 volumio[814]: info: Oct 27 00:36:12 volumio-x86-784 volumio[814]: ---------------------------- MPD announces state update: player Oct 27 00:36:12 volumio-x86-784 volumio[814]: info: ControllerMpd::getState Oct 27 00:36:12 volumio-x86-784 volumio[814]: verbose: ControllerMpd::sendMpdCommand status Oct 27 00:36:12 volumio-x86-784 volumio[814]: info: Oct 27 00:36:12 volumio-x86-784 volumio[814]: ---------------------------- MPD announces state update: player Oct 27 00:36:12 volumio-x86-784 volumio[814]: info: ControllerMpd::getState Oct 27 00:36:12 volumio-x86-784 volumio[814]: verbose: ControllerMpd::sendMpdCommand status Oct 27 00:36:12 volumio-x86-784 volumio[814]: info: sendMpdCommand status took 160 milliseconds Oct 27 00:36:12 volumio-x86-784 volumio[814]: verbose: ControllerMpd::parseState Oct 27 00:36:12 volumio-x86-784 volumio[814]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 27 00:36:12 volumio-x86-784 volumio[814]: info: Oct 27 00:36:12 volumio-x86-784 volumio[814]: ---------------------------- MPD announces state update: player Oct 27 00:36:12 volumio-x86-784 volumio[814]: info: ControllerMpd::getState Oct 27 00:36:12 volumio-x86-784 volumio[814]: verbose: ControllerMpd::sendMpdCommand status Oct 27 00:36:12 volumio-x86-784 volumio[814]: info: Oct 27 00:36:12 volumio-x86-784 volumio[814]: ---------------------------- MPD announces state update: player Oct 27 00:36:12 volumio-x86-784 volumio[814]: info: ControllerMpd::getState Oct 27 00:36:12 volumio-x86-784 volumio[814]: verbose: ControllerMpd::sendMpdCommand status Oct 27 00:36:12 volumio-x86-784 volumio[814]: info: sendMpdCommand status took 162 milliseconds Oct 27 00:36:12 volumio-x86-784 volumio[814]: info: sendMpdCommand playlistinfo took 12 milliseconds Oct 27 00:36:12 volumio-x86-784 volumio[814]: info: sendMpdCommand status took 4 milliseconds Oct 27 00:36:12 volumio-x86-784 volumio[814]: verbose: ControllerMpd::parseState Oct 27 00:36:12 volumio-x86-784 volumio[814]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 27 00:36:12 volumio-x86-784 volumio[814]: verbose: ControllerMpd::parseTrackInfo Oct 27 00:36:12 volumio-x86-784 volumio[814]: verbose: ControllerMpd::parseState Oct 27 00:36:12 volumio-x86-784 volumio[814]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 27 00:36:12 volumio-x86-784 volumio[814]: info: ControllerMpd::pushState Oct 27 00:36:12 volumio-x86-784 volumio[814]: info: CoreCommandRouter::servicePushState Oct 27 00:36:12 volumio-x86-784 volumio[814]: info: CorePlayQueue::getTrack 20 Oct 27 00:36:12 volumio-x86-784 volumio[814]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":233,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"05.幸福女人","artist":"陈瑞","album":"女人心","uri":"NAS/飞牛/按歌手分类/陈瑞《试音陈瑞》/陈瑞《试音陈瑞》/陈瑞 - 05.05.幸福女人.flac","trackType":"flac"} Oct 27 00:36:12 volumio-x86-784 volumio[814]: verbose: CURRENT POSITION 20 Oct 27 00:36:12 volumio-x86-784 volumio[814]: info: CoreStateMachine::syncState stateService play Oct 27 00:36:12 volumio-x86-784 volumio[814]: info: CoreStateMachine::syncState currentStatus stop Oct 27 00:36:12 volumio-x86-784 volumio[814]: info: ------------------------------ 239ms Oct 27 00:36:12 volumio-x86-784 volumio[814]: info: sendMpdCommand status took 79 milliseconds Oct 27 00:36:12 volumio-x86-784 volumio[814]: info: sendMpdCommand playlistinfo took 78 milliseconds Oct 27 00:36:12 volumio-x86-784 volumio[814]: verbose: ControllerMpd::parseState Oct 27 00:36:12 volumio-x86-784 volumio[814]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 27 00:36:12 volumio-x86-784 volumio[814]: verbose: ControllerMpd::parseTrackInfo Oct 27 00:36:12 volumio-x86-784 volumio[814]: info: ControllerMpd::pushState Oct 27 00:36:12 volumio-x86-784 volumio[814]: info: CoreCommandRouter::servicePushState Oct 27 00:36:12 volumio-x86-784 volumio[814]: info: CorePlayQueue::getTrack 20 Oct 27 00:36:12 volumio-x86-784 volumio[814]: verbose: STATE SERVICE {"status":"play","position":0,"seek":68,"duration":233,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"05.幸福女人","artist":"陈瑞","album":"女人心","uri":"NAS/飞牛/按歌手分类/陈瑞《试音陈瑞》/陈瑞《试音陈瑞》/陈瑞 - 05.05.幸福女人.flac","trackType":"flac"} Oct 27 00:36:12 volumio-x86-784 volumio[814]: verbose: CURRENT POSITION 20 Oct 27 00:36:12 volumio-x86-784 volumio[814]: info: CoreStateMachine::syncState stateService play Oct 27 00:36:12 volumio-x86-784 volumio[814]: info: CoreStateMachine::syncState currentStatus play Oct 27 00:36:12 volumio-x86-784 volumio[814]: info: Received an update from plugin. extracting info from payload Oct 27 00:36:12 volumio-x86-784 volumio[814]: info: CoreStateMachine::pushState Oct 27 00:36:12 volumio-x86-784 volumio[814]: info: CorePlayQueue::getTrack 20 Oct 27 00:36:12 volumio-x86-784 volumio[814]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 27 00:36:12 volumio-x86-784 volumio[814]: info: CoreCommandRouter::volumioPushState Oct 27 00:36:12 volumio-x86-784 volumio[814]: info: CoreStateMachine::pushState Oct 27 00:36:12 volumio-x86-784 volumio[814]: info: CorePlayQueue::getTrack 20 Oct 27 00:36:12 volumio-x86-784 volumio[814]: info: CoreCommandRouter::volumioPushState Oct 27 00:36:12 volumio-x86-784 volumio[814]: info: ------------------------------ 320ms Oct 27 00:36:13 volumio-x86-784 volumio[814]: info: sendMpdCommand playlistinfo took 205 milliseconds Oct 27 00:36:13 volumio-x86-784 volumio[814]: info: sendMpdCommand playlistinfo took 164 milliseconds Oct 27 00:36:13 volumio-x86-784 volumio[814]: verbose: ControllerMpd::parseTrackInfo Oct 27 00:36:13 volumio-x86-784 volumio[814]: info: ControllerMpd::pushState Oct 27 00:36:13 volumio-x86-784 volumio[814]: info: CoreCommandRouter::servicePushState Oct 27 00:36:13 volumio-x86-784 volumio[814]: info: CorePlayQueue::getTrack 20 Oct 27 00:36:13 volumio-x86-784 volumio[814]: verbose: STATE SERVICE {"status":"play","position":0,"seek":614,"duration":233,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"839 Kbps","isStreaming":false,"title":"05.幸福女人","artist":"陈瑞","album":"女人心","uri":"NAS/飞牛/按歌手分类/陈瑞《试音陈瑞》/陈瑞《试音陈瑞》/陈瑞 - 05.05.幸福女人.flac","trackType":"flac"} Oct 27 00:36:13 volumio-x86-784 volumio[814]: verbose: CURRENT POSITION 20 Oct 27 00:36:13 volumio-x86-784 volumio[814]: info: CoreStateMachine::syncState stateService play Oct 27 00:36:13 volumio-x86-784 volumio[814]: info: CoreStateMachine::syncState currentStatus play Oct 27 00:36:13 volumio-x86-784 volumio[814]: info: Received an update from plugin. extracting info from payload Oct 27 00:36:13 volumio-x86-784 volumio[814]: info: CoreStateMachine::pushState Oct 27 00:36:13 volumio-x86-784 volumio[814]: info: CorePlayQueue::getTrack 20 Oct 27 00:36:13 volumio-x86-784 volumio[814]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 27 00:36:13 volumio-x86-784 volumio[814]: info: CoreCommandRouter::volumioPushState Oct 27 00:36:13 volumio-x86-784 volumio[814]: info: CoreStateMachine::pushState Oct 27 00:36:13 volumio-x86-784 volumio[814]: info: CorePlayQueue::getTrack 20 Oct 27 00:36:13 volumio-x86-784 volumio[814]: info: CoreCommandRouter::volumioPushState Oct 27 00:36:13 volumio-x86-784 volumio[814]: info: ControllerMpd::pushState Oct 27 00:36:13 volumio-x86-784 volumio[814]: info: CoreCommandRouter::servicePushState Oct 27 00:36:13 volumio-x86-784 volumio[814]: info: CorePlayQueue::getTrack 20 Oct 27 00:36:13 volumio-x86-784 volumio[814]: verbose: STATE SERVICE {"status":"play","position":0,"seek":614,"duration":233,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"839 Kbps","isStreaming":false,"title":"05.幸福女人","artist":"陈瑞","album":"女人心","uri":"NAS/飞牛/按歌手分类/陈瑞《试音陈瑞》/陈瑞《试音陈瑞》/陈瑞 - 05.05.幸福女人.flac","trackType":"flac"} Oct 27 00:36:13 volumio-x86-784 volumio[814]: verbose: CURRENT POSITION 20 Oct 27 00:36:13 volumio-x86-784 volumio[814]: info: CoreStateMachine::syncState stateService play Oct 27 00:36:13 volumio-x86-784 volumio[814]: info: CoreStateMachine::syncState currentStatus play Oct 27 00:36:13 volumio-x86-784 volumio[814]: info: Received an update from plugin. extracting info from payload Oct 27 00:36:13 volumio-x86-784 volumio[814]: info: CoreStateMachine::pushState Oct 27 00:36:13 volumio-x86-784 volumio[814]: info: CorePlayQueue::getTrack 20 Oct 27 00:36:13 volumio-x86-784 volumio[814]: info: CoreCommandRouter::volumioPushState Oct 27 00:36:13 volumio-x86-784 volumio[814]: info: CoreStateMachine::pushState Oct 27 00:36:13 volumio-x86-784 volumio[814]: info: CorePlayQueue::getTrack 20 Oct 27 00:36:13 volumio-x86-784 volumio[814]: info: CoreCommandRouter::volumioPushState Oct 27 00:36:13 volumio-x86-784 volumio[814]: info: ------------------------------ 436ms Oct 27 00:36:13 volumio-x86-784 volumio[814]: info: ------------------------------ 433ms Oct 27 00:36:21 volumio-x86-784 volumio[814]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Oct 27 00:36:21 volumio-x86-784 volumio[814]: info: Retrying Login Due to Network Error Oct 27 00:36:31 volumio-x86-784 volumio[814]: info: MyVolumio login type: Token Oct 27 00:36:41 volumio-x86-784 volumio[814]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 27 00:36:41 volumio-x86-784 volumio[814]: Error: connect ETIMEDOUT 74.86.151.162:443 Oct 27 00:36:41 volumio-x86-784 volumio[814]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Oct 27 00:36:41 volumio-x86-784 volumio[814]: errno: -110, Oct 27 00:36:41 volumio-x86-784 volumio[814]: code: 'ETIMEDOUT', Oct 27 00:36:41 volumio-x86-784 volumio[814]: syscall: 'connect', Oct 27 00:36:41 volumio-x86-784 volumio[814]: address: '74.86.151.162', Oct 27 00:36:41 volumio-x86-784 volumio[814]: port: 443 Oct 27 00:36:41 volumio-x86-784 volumio[814]: } Oct 27 00:36:41 volumio-x86-784 volumio[814]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 27 00:36:42 volumio-x86-784 sudo[3067]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-10-27 00:35 Oct 27 00:36:42 volumio-x86-784 sudo[3067]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="9ab87de061b3649c303da550e73c92d340b0f989" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 24 Apr 2025 10:07:11 AM CEST" VOLUMIO_VERSION="3.804" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="dc77d77f02ef88ea816c3d31de871e8f"