Jan 25 12:16:00 volumioliving volumio[1386]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 25 12:16:04 volumioliving volumio[1386]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 25 12:16:05 volumioliving volumio[1386]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Jan 25 12:16:05 volumioliving volumio[1386]: verbose: ControllerMpd::sendMpdCommand update Jan 25 12:16:05 volumioliving volumio[1386]: info: sendMpdCommand update took 3 milliseconds Jan 25 12:16:08 volumioliving volumio[1386]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 25 12:16:12 volumioliving volumio[1386]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 25 12:16:16 volumioliving volumio[1386]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 25 12:16:20 volumioliving volumio[1386]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 25 12:16:22 volumioliving volumio[1386]: info: MPD Database updated - AlbumList cache refreshed Jan 25 12:16:22 volumioliving volumio[1386]: info: Jan 25 12:16:22 volumioliving volumio[1386]: ---------------------------- MPD announces state update: database Jan 25 12:16:22 volumioliving volumio[1386]: verbose: ControllerMpd::sendMpdCommand status Jan 25 12:16:22 volumioliving volumio[1386]: info: Jan 25 12:16:22 volumioliving volumio[1386]: ---------------------------- MPD announces state update: update Jan 25 12:16:22 volumioliving volumio[1386]: info: ControllerMpd::getState Jan 25 12:16:22 volumioliving volumio[1386]: verbose: ControllerMpd::sendMpdCommand status Jan 25 12:16:22 volumioliving volumio[1386]: info: ControllerMpd::getState Jan 25 12:16:22 volumioliving volumio[1386]: verbose: ControllerMpd::sendMpdCommand status Jan 25 12:16:22 volumioliving volumio[1386]: info: sendMpdCommand status took 89 milliseconds Jan 25 12:16:22 volumioliving volumio[1386]: info: sendMpdCommand status took 89 milliseconds Jan 25 12:16:22 volumioliving volumio[1386]: info: sendMpdCommand status took 89 milliseconds Jan 25 12:16:22 volumioliving volumio[1386]: verbose: ControllerMpd::parseState Jan 25 12:16:22 volumioliving volumio[1386]: info: Command Router : Notfying DB Updatetrue Jan 25 12:16:22 volumioliving volumio[1386]: info: CoreCommandRouter::Close All Modals sent Jan 25 12:16:22 volumioliving volumio[1386]: verbose: ControllerMpd::parseState Jan 25 12:16:22 volumioliving volumio[1386]: verbose: ControllerMpd::parseState Jan 25 12:16:22 volumioliving volumio[1386]: info: ControllerMpd::pushState Jan 25 12:16:22 volumioliving volumio[1386]: info: CoreCommandRouter::servicePushState Jan 25 12:16:22 volumioliving volumio[1386]: info: CoreStateMachine::pushState Jan 25 12:16:22 volumioliving volumio[1386]: info: CoreCommandRouter::volumioPushState Jan 25 12:16:22 volumioliving volumio[1386]: info: MRS: Pushing multiroomSync output update for this device Jan 25 12:16:22 volumioliving volumio[1386]: info: MRS: Pushing multiroomSync output Jan 25 12:16:22 volumioliving volumio[1386]: info: CorePlayQueue::getTrack 0 Jan 25 12:16:22 volumioliving volumio[1386]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jan 25 12:16:22 volumioliving volumio[1386]: verbose: CURRENT POSITION 0 Jan 25 12:16:22 volumioliving volumio[1386]: info: CoreStateMachine::syncState stateService stop Jan 25 12:16:22 volumioliving volumio[1386]: info: CoreStateMachine::syncState currentStatus stop Jan 25 12:16:22 volumioliving volumio[1386]: info: CoreStateMachine::pushState Jan 25 12:16:22 volumioliving volumio[1386]: info: CoreCommandRouter::volumioPushState Jan 25 12:16:22 volumioliving volumio[1386]: info: MRS: Pushing multiroomSync output update for this device Jan 25 12:16:22 volumioliving volumio[1386]: info: MRS: Pushing multiroomSync output Jan 25 12:16:22 volumioliving volumio[1386]: info: No code Jan 25 12:16:22 volumioliving volumio[1386]: info: CoreStateMachine::pushState Jan 25 12:16:22 volumioliving volumio[1386]: info: CoreCommandRouter::volumioPushState Jan 25 12:16:22 volumioliving volumio[1386]: info: MRS: Pushing multiroomSync output update for this device Jan 25 12:16:22 volumioliving volumio[1386]: info: MRS: Pushing multiroomSync output Jan 25 12:16:22 volumioliving volumio[1386]: info: ControllerMpd::pushState Jan 25 12:16:22 volumioliving volumio[1386]: info: CoreCommandRouter::servicePushState Jan 25 12:16:22 volumioliving volumio[1386]: info: CoreStateMachine::pushState Jan 25 12:16:22 volumioliving volumio[1386]: info: CoreCommandRouter::volumioPushState Jan 25 12:16:22 volumioliving volumio[1386]: info: MRS: Pushing multiroomSync output update for this device Jan 25 12:16:22 volumioliving volumio[1386]: info: MRS: Pushing multiroomSync output Jan 25 12:16:22 volumioliving volumio[1386]: info: CorePlayQueue::getTrack 0 Jan 25 12:16:22 volumioliving volumio[1386]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jan 25 12:16:22 volumioliving volumio[1386]: verbose: CURRENT POSITION 0 Jan 25 12:16:22 volumioliving volumio[1386]: info: CoreStateMachine::syncState stateService stop Jan 25 12:16:22 volumioliving volumio[1386]: info: CoreStateMachine::syncState currentStatus stop Jan 25 12:16:22 volumioliving volumio[1386]: info: CoreStateMachine::pushState Jan 25 12:16:22 volumioliving volumio[1386]: info: CoreCommandRouter::volumioPushState Jan 25 12:16:22 volumioliving volumio[1386]: info: MRS: Pushing multiroomSync output update for this device Jan 25 12:16:22 volumioliving volumio[1386]: info: MRS: Pushing multiroomSync output Jan 25 12:16:22 volumioliving volumio[1386]: info: No code Jan 25 12:16:22 volumioliving volumio[1386]: info: CoreStateMachine::pushState Jan 25 12:16:22 volumioliving volumio[1386]: info: CoreCommandRouter::volumioPushState Jan 25 12:16:22 volumioliving volumio[1386]: info: MRS: Pushing multiroomSync output update for this device Jan 25 12:16:22 volumioliving volumio[1386]: info: MRS: Pushing multiroomSync output Jan 25 12:16:22 volumioliving volumio[1386]: info: ------------------------------ 132ms Jan 25 12:16:22 volumioliving volumio[1386]: info: ------------------------------ 133ms Jan 25 12:16:22 volumioliving volumio[1386]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.M9EZTUKiy3UWRS21CMvcEqD5Glv2.e2db92b57c5f65baa1ecc883283e771a.state.status' Jan 25 12:16:22 volumioliving volumio[1386]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.M9EZTUKiy3UWRS21CMvcEqD5Glv2.e2db92b57c5f65baa1ecc883283e771a.state.status' Jan 25 12:16:22 volumioliving volumio[1386]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.M9EZTUKiy3UWRS21CMvcEqD5Glv2.e2db92b57c5f65baa1ecc883283e771a.state.status' Jan 25 12:16:22 volumioliving volumio[1386]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.M9EZTUKiy3UWRS21CMvcEqD5Glv2.e2db92b57c5f65baa1ecc883283e771a.state.status' Jan 25 12:16:22 volumioliving volumio[1386]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.M9EZTUKiy3UWRS21CMvcEqD5Glv2.e2db92b57c5f65baa1ecc883283e771a.state.status' Jan 25 12:16:22 volumioliving volumio[1386]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.M9EZTUKiy3UWRS21CMvcEqD5Glv2.e2db92b57c5f65baa1ecc883283e771a.state.status' Jan 25 12:16:22 volumioliving volumio[1386]: info: MPD Database updated - AlbumList cache refreshed Jan 25 12:16:22 volumioliving volumio[1386]: info: Jan 25 12:16:22 volumioliving volumio[1386]: ---------------------------- MPD announces state update: database Jan 25 12:16:22 volumioliving volumio[1386]: verbose: ControllerMpd::sendMpdCommand status Jan 25 12:16:22 volumioliving volumio[1386]: info: Jan 25 12:16:22 volumioliving volumio[1386]: ---------------------------- MPD announces state update: update Jan 25 12:16:22 volumioliving volumio[1386]: info: ControllerMpd::getState Jan 25 12:16:22 volumioliving volumio[1386]: verbose: ControllerMpd::sendMpdCommand status Jan 25 12:16:22 volumioliving volumio[1386]: info: ControllerMpd::getState Jan 25 12:16:22 volumioliving volumio[1386]: verbose: ControllerMpd::sendMpdCommand status Jan 25 12:16:22 volumioliving volumio[1386]: info: sendMpdCommand status took 50 milliseconds Jan 25 12:16:22 volumioliving volumio[1386]: info: sendMpdCommand status took 49 milliseconds Jan 25 12:16:22 volumioliving volumio[1386]: info: sendMpdCommand status took 49 milliseconds Jan 25 12:16:22 volumioliving volumio[1386]: verbose: ControllerMpd::parseState Jan 25 12:16:22 volumioliving volumio[1386]: info: Command Router : Notfying DB Updatefalse Jan 25 12:16:22 volumioliving volumio[1386]: info: CoreCommandRouter::Close All Modals sent Jan 25 12:16:22 volumioliving volumio[1386]: verbose: ControllerMpd::parseState Jan 25 12:16:22 volumioliving volumio[1386]: verbose: ControllerMpd::parseState Jan 25 12:16:22 volumioliving volumio[1386]: info: ControllerMpd::pushState Jan 25 12:16:22 volumioliving volumio[1386]: info: CoreCommandRouter::servicePushState Jan 25 12:16:22 volumioliving volumio[1386]: info: CoreStateMachine::pushState Jan 25 12:16:22 volumioliving volumio[1386]: info: CoreCommandRouter::volumioPushState Jan 25 12:16:22 volumioliving volumio[1386]: info: MRS: Pushing multiroomSync output update for this device Jan 25 12:16:22 volumioliving volumio[1386]: info: MRS: Pushing multiroomSync output Jan 25 12:16:22 volumioliving volumio[1386]: info: CorePlayQueue::getTrack 0 Jan 25 12:16:22 volumioliving volumio[1386]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jan 25 12:16:22 volumioliving volumio[1386]: verbose: CURRENT POSITION 0 Jan 25 12:16:22 volumioliving volumio[1386]: info: CoreStateMachine::syncState stateService stop Jan 25 12:16:22 volumioliving volumio[1386]: info: CoreStateMachine::syncState currentStatus stop Jan 25 12:16:22 volumioliving volumio[1386]: info: CoreStateMachine::pushState Jan 25 12:16:22 volumioliving volumio[1386]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 25 12:16:22 volumioliving volumio[1386]: info: CoreCommandRouter::volumioPushState Jan 25 12:16:22 volumioliving volumio[1386]: info: MRS: Pushing multiroomSync output update for this device Jan 25 12:16:22 volumioliving volumio[1386]: info: MRS: Pushing multiroomSync output Jan 25 12:16:22 volumioliving volumio[1386]: info: No code Jan 25 12:16:22 volumioliving volumio[1386]: info: CoreStateMachine::pushState Jan 25 12:16:22 volumioliving volumio[1386]: info: CoreCommandRouter::volumioPushState Jan 25 12:16:22 volumioliving volumio[1386]: info: MRS: Pushing multiroomSync output update for this device Jan 25 12:16:22 volumioliving volumio[1386]: info: MRS: Pushing multiroomSync output Jan 25 12:16:22 volumioliving volumio[1386]: info: ControllerMpd::pushState Jan 25 12:16:22 volumioliving volumio[1386]: info: CoreCommandRouter::servicePushState Jan 25 12:16:22 volumioliving volumio[1386]: info: CoreStateMachine::pushState Jan 25 12:16:22 volumioliving volumio[1386]: info: CoreCommandRouter::volumioPushState Jan 25 12:16:22 volumioliving volumio[1386]: info: MRS: Pushing multiroomSync output update for this device Jan 25 12:16:22 volumioliving volumio[1386]: info: MRS: Pushing multiroomSync output Jan 25 12:16:22 volumioliving volumio[1386]: info: CorePlayQueue::getTrack 0 Jan 25 12:16:22 volumioliving volumio[1386]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jan 25 12:16:22 volumioliving volumio[1386]: verbose: CURRENT POSITION 0 Jan 25 12:16:22 volumioliving volumio[1386]: info: CoreStateMachine::syncState stateService stop Jan 25 12:16:22 volumioliving volumio[1386]: info: CoreStateMachine::syncState currentStatus stop Jan 25 12:16:22 volumioliving volumio[1386]: info: CoreStateMachine::pushState Jan 25 12:16:22 volumioliving volumio[1386]: info: CoreCommandRouter::volumioPushState Jan 25 12:16:22 volumioliving volumio[1386]: info: MRS: Pushing multiroomSync output update for this device Jan 25 12:16:22 volumioliving volumio[1386]: info: MRS: Pushing multiroomSync output Jan 25 12:16:22 volumioliving volumio[1386]: info: No code Jan 25 12:16:22 volumioliving volumio[1386]: info: CoreStateMachine::pushState Jan 25 12:16:22 volumioliving volumio[1386]: info: CoreCommandRouter::volumioPushState Jan 25 12:16:22 volumioliving volumio[1386]: info: MRS: Pushing multiroomSync output update for this device Jan 25 12:16:22 volumioliving volumio[1386]: info: MRS: Pushing multiroomSync output Jan 25 12:16:22 volumioliving volumio[1386]: info: ------------------------------ 92ms Jan 25 12:16:22 volumioliving volumio[1386]: info: ------------------------------ 92ms Jan 25 12:16:22 volumioliving volumio[1386]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.M9EZTUKiy3UWRS21CMvcEqD5Glv2.e2db92b57c5f65baa1ecc883283e771a.state.status' Jan 25 12:16:22 volumioliving volumio[1386]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.M9EZTUKiy3UWRS21CMvcEqD5Glv2.e2db92b57c5f65baa1ecc883283e771a.state.status' Jan 25 12:16:22 volumioliving volumio[1386]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.M9EZTUKiy3UWRS21CMvcEqD5Glv2.e2db92b57c5f65baa1ecc883283e771a.state.status' Jan 25 12:16:22 volumioliving volumio[1386]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.M9EZTUKiy3UWRS21CMvcEqD5Glv2.e2db92b57c5f65baa1ecc883283e771a.state.status' Jan 25 12:16:22 volumioliving volumio[1386]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.M9EZTUKiy3UWRS21CMvcEqD5Glv2.e2db92b57c5f65baa1ecc883283e771a.state.status' Jan 25 12:16:24 volumioliving volumio[1386]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 25 12:16:25 volumioliving volumio[1386]: info: Tunnel connection is inactive, restarting it Jan 25 12:16:25 volumioliving volumio[1386]: info: Starting Tunnel 1 Jan 25 12:16:25 volumioliving volumio[1386]: info: Starting Tunnel Connection Checker Jan 25 12:16:25 volumioliving sudo[17153]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Jan 25 12:16:25 volumioliving sudo[17153]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 25 12:16:25 volumioliving systemd[1]: Started sshtunnel.service - MyVolumio SSH Tunnel. Jan 25 12:16:25 volumioliving sudo[17153]: pam_unix(sudo:session): session closed for user root Jan 25 12:16:25 volumioliving volumio[1386]: info: Remote SSH Started Jan 25 12:16:25 volumioliving autossh[17156]: port set to 0, monitoring disabled Jan 25 12:16:25 volumioliving autossh[17156]: starting ssh (count 1) Jan 25 12:16:25 volumioliving autossh[17156]: ssh child pid is 17159 Jan 25 12:16:25 volumioliving volumio[1386]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Jan 25 12:16:25 volumioliving volumio[1386]: info: CoreCommandRouter::volumioGetState Jan 25 12:16:25 volumioliving volumio[1386]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.M9EZTUKiy3UWRS21CMvcEqD5Glv2.e2db92b57c5f65baa1ecc883283e771a.state.status' Jan 25 12:16:25 volumioliving volumiossh-tunnel[17159]: ssh: connect to host eu7.myvolumio.org port 2222: Connection refused Jan 25 12:16:25 volumioliving autossh[17156]: ssh exited prematurely with status 255; autossh exiting Jan 25 12:16:25 volumioliving systemd[1]: sshtunnel.service: Main process exited, code=exited, status=1/FAILURE Jan 25 12:16:25 volumioliving systemd[1]: sshtunnel.service: Failed with result 'exit-code'. Jan 25 12:16:25 volumioliving systemd[1]: sshtunnel.service: Scheduled restart job, restart counter is at 1. Jan 25 12:16:25 volumioliving systemd[1]: Stopped sshtunnel.service - MyVolumio SSH Tunnel. Jan 25 12:16:25 volumioliving systemd[1]: Started sshtunnel.service - MyVolumio SSH Tunnel. Jan 25 12:16:25 volumioliving autossh[17161]: port set to 0, monitoring disabled Jan 25 12:16:25 volumioliving autossh[17161]: starting ssh (count 1) Jan 25 12:16:25 volumioliving autossh[17161]: ssh child pid is 17164 Jan 25 12:16:25 volumioliving volumiossh-tunnel[17164]: ssh: connect to host eu7.myvolumio.org port 2222: Connection refused Jan 25 12:16:25 volumioliving autossh[17161]: ssh exited prematurely with status 255; autossh exiting Jan 25 12:16:25 volumioliving systemd[1]: sshtunnel.service: Main process exited, code=exited, status=1/FAILURE Jan 25 12:16:25 volumioliving systemd[1]: sshtunnel.service: Failed with result 'exit-code'. Jan 25 12:16:26 volumioliving systemd[1]: sshtunnel.service: Scheduled restart job, restart counter is at 2. Jan 25 12:16:26 volumioliving systemd[1]: Stopped sshtunnel.service - MyVolumio SSH Tunnel. Jan 25 12:16:26 volumioliving systemd[1]: Started sshtunnel.service - MyVolumio SSH Tunnel. Jan 25 12:16:26 volumioliving autossh[17166]: port set to 0, monitoring disabled Jan 25 12:16:26 volumioliving autossh[17166]: starting ssh (count 1) Jan 25 12:16:26 volumioliving autossh[17166]: ssh child pid is 17169 Jan 25 12:16:26 volumioliving volumiossh-tunnel[17169]: ssh: connect to host eu7.myvolumio.org port 2222: Connection refused Jan 25 12:16:26 volumioliving autossh[17166]: ssh exited prematurely with status 255; autossh exiting Jan 25 12:16:26 volumioliving systemd[1]: sshtunnel.service: Main process exited, code=exited, status=1/FAILURE Jan 25 12:16:26 volumioliving systemd[1]: sshtunnel.service: Failed with result 'exit-code'. Jan 25 12:16:26 volumioliving systemd[1]: sshtunnel.service: Scheduled restart job, restart counter is at 3. Jan 25 12:16:26 volumioliving systemd[1]: Stopped sshtunnel.service - MyVolumio SSH Tunnel. Jan 25 12:16:26 volumioliving systemd[1]: Started sshtunnel.service - MyVolumio SSH Tunnel. Jan 25 12:16:26 volumioliving autossh[17171]: port set to 0, monitoring disabled Jan 25 12:16:26 volumioliving autossh[17171]: starting ssh (count 1) Jan 25 12:16:26 volumioliving autossh[17171]: ssh child pid is 17174 Jan 25 12:16:26 volumioliving volumiossh-tunnel[17174]: ssh: connect to host eu7.myvolumio.org port 2222: Connection refused Jan 25 12:16:26 volumioliving autossh[17171]: ssh exited prematurely with status 255; autossh exiting Jan 25 12:16:26 volumioliving systemd[1]: sshtunnel.service: Main process exited, code=exited, status=1/FAILURE Jan 25 12:16:26 volumioliving systemd[1]: sshtunnel.service: Failed with result 'exit-code'. Jan 25 12:16:26 volumioliving systemd[1]: sshtunnel.service: Scheduled restart job, restart counter is at 4. Jan 25 12:16:26 volumioliving systemd[1]: Stopped sshtunnel.service - MyVolumio SSH Tunnel. Jan 25 12:16:26 volumioliving systemd[1]: Started sshtunnel.service - MyVolumio SSH Tunnel. Jan 25 12:16:26 volumioliving autossh[17177]: port set to 0, monitoring disabled Jan 25 12:16:26 volumioliving autossh[17177]: starting ssh (count 1) Jan 25 12:16:26 volumioliving autossh[17177]: ssh child pid is 17180 Jan 25 12:16:26 volumioliving volumiossh-tunnel[17180]: ssh: connect to host eu7.myvolumio.org port 2222: Connection refused Jan 25 12:16:26 volumioliving autossh[17177]: ssh exited prematurely with status 255; autossh exiting Jan 25 12:16:26 volumioliving systemd[1]: sshtunnel.service: Main process exited, code=exited, status=1/FAILURE Jan 25 12:16:26 volumioliving systemd[1]: sshtunnel.service: Failed with result 'exit-code'. Jan 25 12:16:26 volumioliving systemd[1]: sshtunnel.service: Scheduled restart job, restart counter is at 5. Jan 25 12:16:26 volumioliving systemd[1]: Stopped sshtunnel.service - MyVolumio SSH Tunnel. Jan 25 12:16:26 volumioliving systemd[1]: sshtunnel.service: Start request repeated too quickly. Jan 25 12:16:26 volumioliving systemd[1]: sshtunnel.service: Failed with result 'exit-code'. Jan 25 12:16:26 volumioliving systemd[1]: Failed to start sshtunnel.service - MyVolumio SSH Tunnel. Jan 25 12:16:28 volumioliving volumio[1386]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 25 12:16:32 volumioliving volumio[1386]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 25 12:16:34 volumioliving volumio[1386]: info: MRS: Found cast device: X04G-59b0aa106ec6f39684d7e786afb9cb12 Jan 25 12:16:36 volumioliving volumio[1386]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 25 12:16:40 volumioliving volumio[1386]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 25 12:16:43 volumioliving volumio[1386]: info: CALLMETHOD: music_service tidal saveConfig [object Object] Jan 25 12:16:43 volumioliving volumio[1386]: info: CoreCommandRouter::executeOnPlugin: tidal , saveConfig Jan 25 12:16:43 volumioliving volumio[1386]: info: Saving configuration Jan 25 12:16:43 volumioliving volumio[1386]: info: Done Jan 25 12:16:44 volumioliving volumio[1386]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 25 12:16:45 volumioliving volumio[1386]: info: CALLMETHOD: music_service tidal saveConfig [object Object] Jan 25 12:16:45 volumioliving volumio[1386]: info: CoreCommandRouter::executeOnPlugin: tidal , saveConfig Jan 25 12:16:45 volumioliving volumio[1386]: info: Saving configuration Jan 25 12:16:45 volumioliving volumio[1386]: info: Done Jan 25 12:16:48 volumioliving volumio[1386]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 25 12:16:52 volumioliving volumio[1386]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 25 12:16:55 volumioliving volumio[1386]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Jan 25 12:16:55 volumioliving volumio[1386]: verbose: ControllerMpd::sendMpdCommand update Jan 25 12:16:55 volumioliving volumio[1386]: verbose: ControllerMpd::sendMpdCommand status Jan 25 12:16:55 volumioliving volumio[1386]: info: Jan 25 12:16:55 volumioliving volumio[1386]: ---------------------------- MPD announces state update: update Jan 25 12:16:55 volumioliving volumio[1386]: info: sendMpdCommand update took 2 milliseconds Jan 25 12:16:55 volumioliving volumio[1386]: info: ControllerMpd::getState Jan 25 12:16:55 volumioliving volumio[1386]: verbose: ControllerMpd::sendMpdCommand status Jan 25 12:16:55 volumioliving volumio[1386]: info: sendMpdCommand status took 1 milliseconds Jan 25 12:16:55 volumioliving volumio[1386]: info: sendMpdCommand status took 1 milliseconds Jan 25 12:16:55 volumioliving volumio[1386]: verbose: ControllerMpd::parseState Jan 25 12:16:55 volumioliving volumio[1386]: info: Command Router : Notfying DB Updatetrue Jan 25 12:16:55 volumioliving volumio[1386]: info: CoreCommandRouter::Close All Modals sent Jan 25 12:16:55 volumioliving volumio[1386]: verbose: ControllerMpd::parseState Jan 25 12:16:55 volumioliving volumio[1386]: info: ControllerMpd::pushState Jan 25 12:16:55 volumioliving volumio[1386]: info: CoreCommandRouter::servicePushState Jan 25 12:16:55 volumioliving volumio[1386]: info: CoreStateMachine::pushState Jan 25 12:16:55 volumioliving volumio[1386]: info: CoreCommandRouter::volumioPushState Jan 25 12:16:55 volumioliving volumio[1386]: info: MRS: Pushing multiroomSync output update for this device Jan 25 12:16:55 volumioliving volumio[1386]: info: MRS: Pushing multiroomSync output Jan 25 12:16:55 volumioliving volumio[1386]: info: CorePlayQueue::getTrack 0 Jan 25 12:16:55 volumioliving volumio[1386]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jan 25 12:16:55 volumioliving volumio[1386]: verbose: CURRENT POSITION 0 Jan 25 12:16:55 volumioliving volumio[1386]: info: CoreStateMachine::syncState stateService stop Jan 25 12:16:55 volumioliving volumio[1386]: info: CoreStateMachine::syncState currentStatus stop Jan 25 12:16:55 volumioliving volumio[1386]: info: CoreStateMachine::pushState Jan 25 12:16:55 volumioliving volumio[1386]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 25 12:16:55 volumioliving volumio[1386]: info: CoreCommandRouter::volumioPushState Jan 25 12:16:55 volumioliving volumio[1386]: info: MRS: Pushing multiroomSync output update for this device Jan 25 12:16:55 volumioliving volumio[1386]: info: MRS: Pushing multiroomSync output Jan 25 12:16:55 volumioliving volumio[1386]: info: No code Jan 25 12:16:55 volumioliving volumio[1386]: info: CoreStateMachine::pushState Jan 25 12:16:55 volumioliving volumio[1386]: info: CoreCommandRouter::volumioPushState Jan 25 12:16:55 volumioliving volumio[1386]: info: MRS: Pushing multiroomSync output update for this device Jan 25 12:16:55 volumioliving volumio[1386]: info: MRS: Pushing multiroomSync output Jan 25 12:16:55 volumioliving volumio[1386]: info: ------------------------------ 43ms Jan 25 12:16:55 volumioliving volumio[1386]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.M9EZTUKiy3UWRS21CMvcEqD5Glv2.e2db92b57c5f65baa1ecc883283e771a.state.status' Jan 25 12:16:55 volumioliving volumio[1386]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.M9EZTUKiy3UWRS21CMvcEqD5Glv2.e2db92b57c5f65baa1ecc883283e771a.state.status' Jan 25 12:16:55 volumioliving volumio[1386]: info: MPD Database updated - AlbumList cache refreshed Jan 25 12:16:55 volumioliving volumio[1386]: info: Jan 25 12:16:55 volumioliving volumio[1386]: ---------------------------- MPD announces state update: database Jan 25 12:16:55 volumioliving volumio[1386]: verbose: ControllerMpd::sendMpdCommand status Jan 25 12:16:55 volumioliving volumio[1386]: info: Jan 25 12:16:55 volumioliving volumio[1386]: ---------------------------- MPD announces state update: update Jan 25 12:16:55 volumioliving volumio[1386]: info: ControllerMpd::getState Jan 25 12:16:55 volumioliving volumio[1386]: verbose: ControllerMpd::sendMpdCommand status Jan 25 12:16:55 volumioliving volumio[1386]: info: ControllerMpd::getState Jan 25 12:16:55 volumioliving volumio[1386]: verbose: ControllerMpd::sendMpdCommand status Jan 25 12:16:55 volumioliving volumio[1386]: info: sendMpdCommand status took 69 milliseconds Jan 25 12:16:55 volumioliving volumio[1386]: info: sendMpdCommand status took 69 milliseconds Jan 25 12:16:55 volumioliving volumio[1386]: info: sendMpdCommand status took 68 milliseconds Jan 25 12:16:55 volumioliving volumio[1386]: verbose: ControllerMpd::parseState Jan 25 12:16:55 volumioliving volumio[1386]: info: Command Router : Notfying DB Updatefalse Jan 25 12:16:55 volumioliving volumio[1386]: info: CoreCommandRouter::Close All Modals sent Jan 25 12:16:55 volumioliving volumio[1386]: verbose: ControllerMpd::parseState Jan 25 12:16:55 volumioliving volumio[1386]: verbose: ControllerMpd::parseState Jan 25 12:16:55 volumioliving volumio[1386]: info: ControllerMpd::pushState Jan 25 12:16:55 volumioliving volumio[1386]: info: CoreCommandRouter::servicePushState Jan 25 12:16:55 volumioliving volumio[1386]: info: CoreStateMachine::pushState Jan 25 12:16:55 volumioliving volumio[1386]: info: CoreCommandRouter::volumioPushState Jan 25 12:16:55 volumioliving volumio[1386]: info: MRS: Pushing multiroomSync output update for this device Jan 25 12:16:55 volumioliving volumio[1386]: info: MRS: Pushing multiroomSync output Jan 25 12:16:55 volumioliving volumio[1386]: info: CorePlayQueue::getTrack 0 Jan 25 12:16:55 volumioliving volumio[1386]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jan 25 12:16:55 volumioliving volumio[1386]: verbose: CURRENT POSITION 0 Jan 25 12:16:55 volumioliving volumio[1386]: info: CoreStateMachine::syncState stateService stop Jan 25 12:16:55 volumioliving volumio[1386]: info: CoreStateMachine::syncState currentStatus stop Jan 25 12:16:55 volumioliving volumio[1386]: info: CoreStateMachine::pushState Jan 25 12:16:55 volumioliving volumio[1386]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 25 12:16:55 volumioliving volumio[1386]: info: CoreCommandRouter::volumioPushState Jan 25 12:16:55 volumioliving volumio[1386]: info: MRS: Pushing multiroomSync output update for this device Jan 25 12:16:55 volumioliving volumio[1386]: info: MRS: Pushing multiroomSync output Jan 25 12:16:55 volumioliving volumio[1386]: info: No code Jan 25 12:16:55 volumioliving volumio[1386]: info: CoreStateMachine::pushState Jan 25 12:16:55 volumioliving volumio[1386]: info: CoreCommandRouter::volumioPushState Jan 25 12:16:55 volumioliving volumio[1386]: info: MRS: Pushing multiroomSync output update for this device Jan 25 12:16:55 volumioliving volumio[1386]: info: MRS: Pushing multiroomSync output Jan 25 12:16:55 volumioliving volumio[1386]: info: ControllerMpd::pushState Jan 25 12:16:55 volumioliving volumio[1386]: info: CoreCommandRouter::servicePushState Jan 25 12:16:55 volumioliving volumio[1386]: info: CoreStateMachine::pushState Jan 25 12:16:55 volumioliving volumio[1386]: info: CoreCommandRouter::volumioPushState Jan 25 12:16:55 volumioliving volumio[1386]: info: MRS: Pushing multiroomSync output update for this device Jan 25 12:16:55 volumioliving volumio[1386]: info: MRS: Pushing multiroomSync output Jan 25 12:16:55 volumioliving volumio[1386]: info: CorePlayQueue::getTrack 0 Jan 25 12:16:55 volumioliving volumio[1386]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jan 25 12:16:55 volumioliving volumio[1386]: verbose: CURRENT POSITION 0 Jan 25 12:16:55 volumioliving volumio[1386]: info: CoreStateMachine::syncState stateService stop Jan 25 12:16:55 volumioliving volumio[1386]: info: CoreStateMachine::syncState currentStatus stop Jan 25 12:16:55 volumioliving volumio[1386]: info: CoreStateMachine::pushState Jan 25 12:16:55 volumioliving volumio[1386]: info: CoreCommandRouter::volumioPushState Jan 25 12:16:55 volumioliving volumio[1386]: info: MRS: Pushing multiroomSync output update for this device Jan 25 12:16:55 volumioliving volumio[1386]: info: MRS: Pushing multiroomSync output Jan 25 12:16:55 volumioliving volumio[1386]: info: No code Jan 25 12:16:55 volumioliving volumio[1386]: info: CoreStateMachine::pushState Jan 25 12:16:55 volumioliving volumio[1386]: info: CoreCommandRouter::volumioPushState Jan 25 12:16:55 volumioliving volumio[1386]: info: MRS: Pushing multiroomSync output update for this device Jan 25 12:16:55 volumioliving volumio[1386]: info: MRS: Pushing multiroomSync output Jan 25 12:16:55 volumioliving volumio[1386]: info: ------------------------------ 125ms Jan 25 12:16:55 volumioliving volumio[1386]: info: ------------------------------ 126ms Jan 25 12:16:55 volumioliving volumio[1386]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.M9EZTUKiy3UWRS21CMvcEqD5Glv2.e2db92b57c5f65baa1ecc883283e771a.state.status' Jan 25 12:16:55 volumioliving volumio[1386]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.M9EZTUKiy3UWRS21CMvcEqD5Glv2.e2db92b57c5f65baa1ecc883283e771a.state.status' Jan 25 12:16:55 volumioliving volumio[1386]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.M9EZTUKiy3UWRS21CMvcEqD5Glv2.e2db92b57c5f65baa1ecc883283e771a.state.status' Jan 25 12:16:55 volumioliving volumio[1386]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.M9EZTUKiy3UWRS21CMvcEqD5Glv2.e2db92b57c5f65baa1ecc883283e771a.state.status' Jan 25 12:16:55 volumioliving volumio[1386]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.M9EZTUKiy3UWRS21CMvcEqD5Glv2.e2db92b57c5f65baa1ecc883283e771a.state.status' Jan 25 12:16:56 volumioliving volumio[1386]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 25 12:17:00 volumioliving volumio[1386]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 25 12:17:04 volumioliving volumio[1386]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 25 12:17:08 volumioliving volumio[1386]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 25 12:17:12 volumioliving volumio[1386]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 25 12:17:12 volumioliving volumio[1386]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Jan 25 12:17:12 volumioliving volumio[1386]: verbose: ControllerMpd::sendMpdCommand rescan Jan 25 12:17:12 volumioliving volumio[1386]: verbose: ControllerMpd::sendMpdCommand status Jan 25 12:17:12 volumioliving volumio[1386]: info: Jan 25 12:17:12 volumioliving volumio[1386]: ---------------------------- MPD announces state update: update Jan 25 12:17:12 volumioliving volumio[1386]: info: sendMpdCommand rescan took 2 milliseconds Jan 25 12:17:12 volumioliving volumio[1386]: info: ControllerMpd::getState Jan 25 12:17:12 volumioliving volumio[1386]: verbose: ControllerMpd::sendMpdCommand status Jan 25 12:17:12 volumioliving volumio[1386]: info: sendMpdCommand status took 1 milliseconds Jan 25 12:17:12 volumioliving volumio[1386]: info: sendMpdCommand status took 1 milliseconds Jan 25 12:17:12 volumioliving volumio[1386]: verbose: ControllerMpd::parseState Jan 25 12:17:12 volumioliving volumio[1386]: info: Command Router : Notfying DB Updatetrue Jan 25 12:17:12 volumioliving volumio[1386]: info: CoreCommandRouter::Close All Modals sent Jan 25 12:17:12 volumioliving volumio[1386]: verbose: ControllerMpd::parseState Jan 25 12:17:13 volumioliving volumio[1386]: info: ControllerMpd::pushState Jan 25 12:17:13 volumioliving volumio[1386]: info: CoreCommandRouter::servicePushState Jan 25 12:17:13 volumioliving volumio[1386]: info: CoreStateMachine::pushState Jan 25 12:17:13 volumioliving volumio[1386]: info: CoreCommandRouter::volumioPushState Jan 25 12:17:13 volumioliving volumio[1386]: info: MRS: Pushing multiroomSync output update for this device Jan 25 12:17:13 volumioliving volumio[1386]: info: MRS: Pushing multiroomSync output Jan 25 12:17:13 volumioliving volumio[1386]: info: CorePlayQueue::getTrack 0 Jan 25 12:17:13 volumioliving volumio[1386]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jan 25 12:17:13 volumioliving volumio[1386]: verbose: CURRENT POSITION 0 Jan 25 12:17:13 volumioliving volumio[1386]: info: CoreStateMachine::syncState stateService stop Jan 25 12:17:13 volumioliving volumio[1386]: info: CoreStateMachine::syncState currentStatus stop Jan 25 12:17:13 volumioliving volumio[1386]: info: CoreStateMachine::pushState Jan 25 12:17:13 volumioliving volumio[1386]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 25 12:17:13 volumioliving volumio[1386]: info: CoreCommandRouter::volumioPushState Jan 25 12:17:13 volumioliving volumio[1386]: info: MRS: Pushing multiroomSync output update for this device Jan 25 12:17:13 volumioliving volumio[1386]: info: MRS: Pushing multiroomSync output Jan 25 12:17:13 volumioliving volumio[1386]: info: No code Jan 25 12:17:13 volumioliving volumio[1386]: info: CoreStateMachine::pushState Jan 25 12:17:13 volumioliving volumio[1386]: info: CoreCommandRouter::volumioPushState Jan 25 12:17:13 volumioliving volumio[1386]: info: MRS: Pushing multiroomSync output update for this device Jan 25 12:17:13 volumioliving volumio[1386]: info: MRS: Pushing multiroomSync output Jan 25 12:17:13 volumioliving volumio[1386]: info: ------------------------------ 42ms Jan 25 12:17:13 volumioliving volumio[1386]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.M9EZTUKiy3UWRS21CMvcEqD5Glv2.e2db92b57c5f65baa1ecc883283e771a.state.status' Jan 25 12:17:13 volumioliving volumio[1386]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.M9EZTUKiy3UWRS21CMvcEqD5Glv2.e2db92b57c5f65baa1ecc883283e771a.state.status' Jan 25 12:17:13 volumioliving volumio[1386]: info: MPD Database updated - AlbumList cache refreshed Jan 25 12:17:13 volumioliving volumio[1386]: info: Jan 25 12:17:13 volumioliving volumio[1386]: ---------------------------- MPD announces state update: database Jan 25 12:17:13 volumioliving volumio[1386]: verbose: ControllerMpd::sendMpdCommand status Jan 25 12:17:13 volumioliving volumio[1386]: info: Jan 25 12:17:13 volumioliving volumio[1386]: ---------------------------- MPD announces state update: update Jan 25 12:17:13 volumioliving volumio[1386]: info: ControllerMpd::getState Jan 25 12:17:13 volumioliving volumio[1386]: verbose: ControllerMpd::sendMpdCommand status Jan 25 12:17:13 volumioliving volumio[1386]: info: ControllerMpd::getState Jan 25 12:17:13 volumioliving volumio[1386]: verbose: ControllerMpd::sendMpdCommand status Jan 25 12:17:13 volumioliving volumio[1386]: info: sendMpdCommand status took 99 milliseconds Jan 25 12:17:13 volumioliving volumio[1386]: info: sendMpdCommand status took 99 milliseconds Jan 25 12:17:13 volumioliving volumio[1386]: info: sendMpdCommand status took 99 milliseconds Jan 25 12:17:13 volumioliving volumio[1386]: verbose: ControllerMpd::parseState Jan 25 12:17:13 volumioliving volumio[1386]: info: Command Router : Notfying DB Updatefalse Jan 25 12:17:13 volumioliving volumio[1386]: info: CoreCommandRouter::Close All Modals sent Jan 25 12:17:13 volumioliving volumio[1386]: verbose: ControllerMpd::parseState Jan 25 12:17:13 volumioliving volumio[1386]: verbose: ControllerMpd::parseState Jan 25 12:17:13 volumioliving volumio[1386]: info: ControllerMpd::pushState Jan 25 12:17:13 volumioliving volumio[1386]: info: CoreCommandRouter::servicePushState Jan 25 12:17:13 volumioliving volumio[1386]: info: CoreStateMachine::pushState Jan 25 12:17:13 volumioliving volumio[1386]: info: CoreCommandRouter::volumioPushState Jan 25 12:17:13 volumioliving volumio[1386]: info: MRS: Pushing multiroomSync output update for this device Jan 25 12:17:13 volumioliving volumio[1386]: info: MRS: Pushing multiroomSync output Jan 25 12:17:13 volumioliving volumio[1386]: info: CorePlayQueue::getTrack 0 Jan 25 12:17:13 volumioliving volumio[1386]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jan 25 12:17:13 volumioliving volumio[1386]: verbose: CURRENT POSITION 0 Jan 25 12:17:13 volumioliving volumio[1386]: info: CoreStateMachine::syncState stateService stop Jan 25 12:17:13 volumioliving volumio[1386]: info: CoreStateMachine::syncState currentStatus stop Jan 25 12:17:13 volumioliving volumio[1386]: info: CoreStateMachine::pushState Jan 25 12:17:13 volumioliving volumio[1386]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 25 12:17:13 volumioliving volumio[1386]: info: CoreCommandRouter::volumioPushState Jan 25 12:17:13 volumioliving volumio[1386]: info: MRS: Pushing multiroomSync output update for this device Jan 25 12:17:13 volumioliving volumio[1386]: info: MRS: Pushing multiroomSync output Jan 25 12:17:13 volumioliving volumio[1386]: info: No code Jan 25 12:17:13 volumioliving volumio[1386]: info: CoreStateMachine::pushState Jan 25 12:17:13 volumioliving volumio[1386]: info: CoreCommandRouter::volumioPushState Jan 25 12:17:13 volumioliving volumio[1386]: info: MRS: Pushing multiroomSync output update for this device Jan 25 12:17:13 volumioliving volumio[1386]: info: MRS: Pushing multiroomSync output Jan 25 12:17:13 volumioliving volumio[1386]: info: ControllerMpd::pushState Jan 25 12:17:13 volumioliving volumio[1386]: info: CoreCommandRouter::servicePushState Jan 25 12:17:13 volumioliving volumio[1386]: info: CoreStateMachine::pushState Jan 25 12:17:13 volumioliving volumio[1386]: info: CoreCommandRouter::volumioPushState Jan 25 12:17:13 volumioliving volumio[1386]: info: MRS: Pushing multiroomSync output update for this device Jan 25 12:17:13 volumioliving volumio[1386]: info: MRS: Pushing multiroomSync output Jan 25 12:17:13 volumioliving volumio[1386]: info: CorePlayQueue::getTrack 0 Jan 25 12:17:13 volumioliving volumio[1386]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jan 25 12:17:13 volumioliving volumio[1386]: verbose: CURRENT POSITION 0 Jan 25 12:17:13 volumioliving volumio[1386]: info: CoreStateMachine::syncState stateService stop Jan 25 12:17:13 volumioliving volumio[1386]: info: CoreStateMachine::syncState currentStatus stop Jan 25 12:17:13 volumioliving volumio[1386]: info: CoreStateMachine::pushState Jan 25 12:17:13 volumioliving volumio[1386]: info: CoreCommandRouter::volumioPushState Jan 25 12:17:13 volumioliving volumio[1386]: info: MRS: Pushing multiroomSync output update for this device Jan 25 12:17:13 volumioliving volumio[1386]: info: MRS: Pushing multiroomSync output Jan 25 12:17:13 volumioliving volumio[1386]: info: No code Jan 25 12:17:13 volumioliving volumio[1386]: info: CoreStateMachine::pushState Jan 25 12:17:13 volumioliving volumio[1386]: info: CoreCommandRouter::volumioPushState Jan 25 12:17:13 volumioliving volumio[1386]: info: MRS: Pushing multiroomSync output update for this device Jan 25 12:17:13 volumioliving volumio[1386]: info: MRS: Pushing multiroomSync output Jan 25 12:17:13 volumioliving volumio[1386]: info: ------------------------------ 139ms Jan 25 12:17:13 volumioliving volumio[1386]: info: ------------------------------ 140ms Jan 25 12:17:13 volumioliving volumio[1386]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.M9EZTUKiy3UWRS21CMvcEqD5Glv2.e2db92b57c5f65baa1ecc883283e771a.state.status' Jan 25 12:17:13 volumioliving volumio[1386]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.M9EZTUKiy3UWRS21CMvcEqD5Glv2.e2db92b57c5f65baa1ecc883283e771a.state.status' Jan 25 12:17:13 volumioliving volumio[1386]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.M9EZTUKiy3UWRS21CMvcEqD5Glv2.e2db92b57c5f65baa1ecc883283e771a.state.status' Jan 25 12:17:13 volumioliving volumio[1386]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.M9EZTUKiy3UWRS21CMvcEqD5Glv2.e2db92b57c5f65baa1ecc883283e771a.state.status' Jan 25 12:17:13 volumioliving volumio[1386]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.M9EZTUKiy3UWRS21CMvcEqD5Glv2.e2db92b57c5f65baa1ecc883283e771a.state.status' Jan 25 12:17:16 volumioliving volumio[1386]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 25 12:17:20 volumioliving volumio[1386]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 25 12:17:25 volumioliving volumio[1386]: info: CoreCommandRouter::volumioGetState Jan 25 12:17:27 volumioliving volumio[1386]: info: Executing endpoint metavolumio Jan 25 12:17:27 volumioliving volumio[1386]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 25 12:17:30 volumioliving volumio[1386]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 25 12:17:30 volumioliving volumio[1386]: info: CURURI: music-library Jan 25 12:17:30 volumioliving volumio[1386]: info: Preload queue cleared Jan 25 12:17:31 volumioliving volumio[1386]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 25 12:17:31 volumioliving volumio[1386]: info: CURURI: music-library/INTERNAL Jan 25 12:17:31 volumioliving volumio[1386]: info: Preload queue cleared Jan 25 12:17:31 volumioliving volumio[1386]: An internal error occurred while serving an albumart. Details: NotFoundError: Not Found Jan 25 12:17:31 volumioliving volumio[1386]: at createHttpError (/volumio/node_modules/send/index.js:979:12) Jan 25 12:17:31 volumioliving volumio[1386]: at SendStream.error (/volumio/node_modules/send/index.js:270:31) Jan 25 12:17:31 volumioliving volumio[1386]: at SendStream.pipe (/volumio/node_modules/send/index.js:580:14) Jan 25 12:17:31 volumioliving volumio[1386]: at sendfile (/volumio/node_modules/express/lib/response.js:1139:8) Jan 25 12:17:31 volumioliving volumio[1386]: at ServerResponse.sendFile (/volumio/node_modules/express/lib/response.js:450:3) Jan 25 12:17:31 volumioliving volumio[1386]: at Promise._successFn (/volumio/app/plugins/miscellanea/albumart/albumart.js:444:9) Jan 25 12:17:31 volumioliving volumio[1386]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Jan 25 12:17:31 volumioliving volumio[1386]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) Jan 25 12:17:31 volumioliving volumio[1386]: An internal error occurred while serving an albumart. Details: NotFoundError: Not Found Jan 25 12:17:31 volumioliving volumio[1386]: at createHttpError (/volumio/node_modules/send/index.js:979:12) Jan 25 12:17:31 volumioliving volumio[1386]: at SendStream.error (/volumio/node_modules/send/index.js:270:31) Jan 25 12:17:31 volumioliving volumio[1386]: at SendStream.pipe (/volumio/node_modules/send/index.js:580:14) Jan 25 12:17:31 volumioliving volumio[1386]: at sendfile (/volumio/node_modules/express/lib/response.js:1139:8) Jan 25 12:17:31 volumioliving volumio[1386]: at ServerResponse.sendFile (/volumio/node_modules/express/lib/response.js:450:3) Jan 25 12:17:31 volumioliving volumio[1386]: at Promise._successFn (/volumio/app/plugins/miscellanea/albumart/albumart.js:444:9) Jan 25 12:17:31 volumioliving volumio[1386]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Jan 25 12:17:31 volumioliving volumio[1386]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) Jan 25 12:17:50 volumioliving volumio[1386]: info: Preload queue cleared Jan 25 12:17:50 volumioliving volumio[1386]: info: CoreCommandRouter::volumioReplaceandPlayItems Jan 25 12:17:50 volumioliving volumio[1386]: info: CoreStateMachine::ClearQueue Jan 25 12:17:50 volumioliving volumio[1386]: info: CoreStateMachine::stop Jan 25 12:17:50 volumioliving volumio[1386]: info: CoreStateMachine::serviceStop Jan 25 12:17:50 volumioliving volumio[1386]: info: Received STOP, but no service to execute it Jan 25 12:17:50 volumioliving volumio[1386]: info: CorePlayQueue::clearPlayQueue Jan 25 12:17:50 volumioliving volumio[1386]: info: CorePlayQueue::saveQueue Jan 25 12:17:50 volumioliving volumio[1386]: info: CoreCommandRouter::volumioPushQueue Jan 25 12:17:50 volumioliving volumio[1386]: info: CoreStateMachine::addQueueItems Jan 25 12:17:50 volumioliving volumio[1386]: info: CorePlayQueue::addQueueItems Jan 25 12:17:50 volumioliving volumio[1386]: info: Preload queue cleared Jan 25 12:17:50 volumioliving volumio[1386]: info: Adding Item to queue: music-library/INTERNAL/PINK FLOYD COLLECTION Jan 25 12:17:50 volumioliving volumio[1386]: info: Exploding uri music-library/INTERNAL/PINK FLOYD COLLECTION in service mpd Jan 25 12:17:50 volumioliving volumio[1386]: info: ALBUMART /albumart?cacheid=815&web=Pink%20Floyd/A%20Foot%20In%20The%20Door/extralarge&path=%2Fmnt%2FINTERNAL%2FPINK%20FLOYD%20COLLECTION%2FPink%20Floyd%20%20Covp%20%20DSD%202018%2F2018%20A%20Foot%20In%20The%20Door%2F01%20-%20Hey%20You.dsf&metadata=false Jan 25 12:17:50 volumioliving volumio[1386]: info: URI /mnt/INTERNAL/PINK FLOYD COLLECTION/Pink Floyd Covp DSD 2018/2018 A Foot In The Door/01 - Hey You.dsf Jan 25 12:17:50 volumioliving volumio[1386]: info: ALBUMART /albumart?cacheid=815&web=Pink%20Floyd/A%20Foot%20In%20The%20Door/extralarge&path=%2Fmnt%2FINTERNAL%2FPINK%20FLOYD%20COLLECTION%2FPink%20Floyd%20%20Covp%20%20DSD%202018%2F2018%20A%20Foot%20In%20The%20Door%2F02%20-%20See%20Emily%20Play.dsf&metadata=false Jan 25 12:17:50 volumioliving volumio[1386]: info: URI /mnt/INTERNAL/PINK FLOYD COLLECTION/Pink Floyd Covp DSD 2018/2018 A Foot In The Door/02 - See Emily Play.dsf Jan 25 12:17:50 volumioliving volumio[1386]: info: ALBUMART /albumart?cacheid=815&web=Pink%20Floyd/A%20Foot%20In%20The%20Door/extralarge&path=%2Fmnt%2FINTERNAL%2FPINK%20FLOYD%20COLLECTION%2FPink%20Floyd%20%20Covp%20%20DSD%202018%2F2018%20A%20Foot%20In%20The%20Door%2F03%20-%20The%20Happiest%20Days%20Of%20Our%20Lives.dsf&metadata=false Jan 25 12:17:50 volumioliving volumio[1386]: info: URI /mnt/INTERNAL/PINK FLOYD COLLECTION/Pink Floyd Covp DSD 2018/2018 A Foot In The Door/03 - The Happiest Days Of Our Lives.dsf Jan 25 12:17:50 volumioliving volumio[1386]: info: ALBUMART /albumart?cacheid=815&web=Pink%20Floyd/A%20Foot%20In%20The%20Door/extralarge&path=%2Fmnt%2FINTERNAL%2FPINK%20FLOYD%20COLLECTION%2FPink%20Floyd%20%20Covp%20%20DSD%202018%2F2018%20A%20Foot%20In%20The%20Door%2F04%20-%20Another%20Brick%20In%20The%20Wall%20Part%202.dsf&metadata=false Jan 25 12:17:50 volumioliving volumio[1386]: info: URI /mnt/INTERNAL/PINK FLOYD COLLECTION/Pink Floyd Covp DSD 2018/2018 A Foot In The Door/04 - Another Brick In The Wall Part 2.dsf Jan 25 12:17:50 volumioliving volumio[1386]: info: ALBUMART /albumart?cacheid=815&web=Pink%20Floyd/A%20Foot%20In%20The%20Door/extralarge&path=%2Fmnt%2FINTERNAL%2FPINK%20FLOYD%20COLLECTION%2FPink%20Floyd%20%20Covp%20%20DSD%202018%2F2018%20A%20Foot%20In%20The%20Door%2F05%20-%20Have%20A%20Cigar.dsf&metadata=false Jan 25 12:17:50 volumioliving volumio[1386]: info: URI /mnt/INTERNAL/PINK FLOYD COLLECTION/Pink Floyd Covp DSD 2018/2018 A Foot In The Door/05 - Have A Cigar.dsf Jan 25 12:17:50 volumioliving volumio[1386]: info: ALBUMART /albumart?cacheid=815&web=Pink%20Floyd/A%20Foot%20In%20The%20Door/extralarge&path=%2Fmnt%2FINTERNAL%2FPINK%20FLOYD%20COLLECTION%2FPink%20Floyd%20%20Covp%20%20DSD%202018%2F2018%20A%20Foot%20In%20The%20Door%2F06%20-%20Wish%20You%20Were%20Here.dsf&metadata=false Jan 25 12:17:50 volumioliving volumio[1386]: info: URI /mnt/INTERNAL/PINK FLOYD COLLECTION/Pink Floyd Covp DSD 2018/2018 A Foot In The Door/06 - Wish You Were Here.dsf Jan 25 12:17:50 volumioliving volumio[1386]: info: ALBUMART /albumart?cacheid=815&web=Pink%20Floyd/A%20Foot%20In%20The%20Door/extralarge&path=%2Fmnt%2FINTERNAL%2FPINK%20FLOYD%20COLLECTION%2FPink%20Floyd%20%20Covp%20%20DSD%202018%2F2018%20A%20Foot%20In%20The%20Door%2F07%20-%20Time.dsf&metadata=false Jan 25 12:17:50 volumioliving volumio[1386]: info: URI /mnt/INTERNAL/PINK FLOYD COLLECTION/Pink Floyd Covp DSD 2018/2018 A Foot In The Door/07 - Time.dsf Jan 25 12:17:50 volumioliving volumio[1386]: info: ALBUMART /albumart?cacheid=815&web=Pink%20Floyd/A%20Foot%20In%20The%20Door/extralarge&path=%2Fmnt%2FINTERNAL%2FPINK%20FLOYD%20COLLECTION%2FPink%20Floyd%20%20Covp%20%20DSD%202018%2F2018%20A%20Foot%20In%20The%20Door%2F08%20-%20The%20Great%20Gig%20In%20The%20Sky.dsf&metadata=false Jan 25 12:17:50 volumioliving volumio[1386]: info: URI /mnt/INTERNAL/PINK FLOYD COLLECTION/Pink Floyd Covp DSD 2018/2018 A Foot In The Door/08 - The Great Gig In The Sky.dsf Jan 25 12:17:50 volumioliving volumio[1386]: info: ALBUMART /albumart?cacheid=815&web=Pink%20Floyd/A%20Foot%20In%20The%20Door/extralarge&path=%2Fmnt%2FINTERNAL%2FPINK%20FLOYD%20COLLECTION%2FPink%20Floyd%20%20Covp%20%20DSD%202018%2F2018%20A%20Foot%20In%20The%20Door%2F09%20-%20Money.dsf&metadata=false Jan 25 12:17:50 volumioliving volumio[1386]: info: URI /mnt/INTERNAL/PINK FLOYD COLLECTION/Pink Floyd Covp DSD 2018/2018 A Foot In The Door/09 - Money.dsf Jan 25 12:17:50 volumioliving volumio[1386]: info: ALBUMART /albumart?cacheid=815&web=Pink%20Floyd/A%20Foot%20In%20The%20Door/extralarge&path=%2Fmnt%2FINTERNAL%2FPINK%20FLOYD%20COLLECTION%2FPink%20Floyd%20%20Covp%20%20DSD%202018%2F2018%20A%20Foot%20In%20The%20Door%2F10%20-%20Comfortably%20Numb.dsf&metadata=false Jan 25 12:17:50 volumioliving volumio[1386]: info: URI /mnt/INTERNAL/PINK FLOYD COLLECTION/Pink Floyd Covp DSD 2018/2018 A Foot In The Door/10 - Comfortably Numb.dsf Jan 25 12:17:50 volumioliving volumio[1386]: info: ALBUMART /albumart?cacheid=815&web=Pink%20Floyd/A%20Foot%20In%20The%20Door/extralarge&path=%2Fmnt%2FINTERNAL%2FPINK%20FLOYD%20COLLECTION%2FPink%20Floyd%20%20Covp%20%20DSD%202018%2F2018%20A%20Foot%20In%20The%20Door%2F11%20-%20High%20Hopes.dsf&metadata=false Jan 25 12:17:50 volumioliving volumio[1386]: info: URI /mnt/INTERNAL/PINK FLOYD COLLECTION/Pink Floyd Covp DSD 2018/2018 A Foot In The Door/11 - High Hopes.dsf Jan 25 12:17:50 volumioliving volumio[1386]: info: ALBUMART /albumart?cacheid=815&web=Pink%20Floyd/A%20Foot%20In%20The%20Door/extralarge&path=%2Fmnt%2FINTERNAL%2FPINK%20FLOYD%20COLLECTION%2FPink%20Floyd%20%20Covp%20%20DSD%202018%2F2018%20A%20Foot%20In%20The%20Door%2F12%20-%20Learning%20To%20Fly.dsf&metadata=false Jan 25 12:17:50 volumioliving volumio[1386]: info: URI /mnt/INTERNAL/PINK FLOYD COLLECTION/Pink Floyd Covp DSD 2018/2018 A Foot In The Door/12 - Learning To Fly.dsf Jan 25 12:17:50 volumioliving volumio[1386]: info: ALBUMART /albumart?cacheid=815&web=Pink%20Floyd/A%20Foot%20In%20The%20Door/extralarge&path=%2Fmnt%2FINTERNAL%2FPINK%20FLOYD%20COLLECTION%2FPink%20Floyd%20%20Covp%20%20DSD%202018%2F2018%20A%20Foot%20In%20The%20Door%2F13%20-%20The%20Fletcher%20Memorial%20Home.dsf&metadata=false Jan 25 12:17:50 volumioliving volumio[1386]: info: URI /mnt/INTERNAL/PINK FLOYD COLLECTION/Pink Floyd Covp DSD 2018/2018 A Foot In The Door/13 - The Fletcher Memorial Home.dsf Jan 25 12:17:50 volumioliving volumio[1386]: info: ALBUMART /albumart?cacheid=815&web=Pink%20Floyd/A%20Foot%20In%20The%20Door/extralarge&path=%2Fmnt%2FINTERNAL%2FPINK%20FLOYD%20COLLECTION%2FPink%20Floyd%20%20Covp%20%20DSD%202018%2F2018%20A%20Foot%20In%20The%20Door%2F14%20-%20Shine%20On%20You%20Crazy%20Diamond%20Parts%201-5.dsf&metadata=false Jan 25 12:17:50 volumioliving volumio[1386]: info: URI /mnt/INTERNAL/PINK FLOYD COLLECTION/Pink Floyd Covp DSD 2018/2018 A Foot In The Door/14 - Shine On You Crazy Diamond Parts 1-5.dsf Jan 25 12:17:50 volumioliving volumio[1386]: info: ALBUMART /albumart?cacheid=815&web=Pink%20Floyd/A%20Foot%20In%20The%20Door/extralarge&path=%2Fmnt%2FINTERNAL%2FPINK%20FLOYD%20COLLECTION%2FPink%20Floyd%20%20Covp%20%20DSD%202018%2F2018%20A%20Foot%20In%20The%20Door%2F15%20-%20Brain%20Damage.dsf&metadata=false Jan 25 12:17:50 volumioliving volumio[1386]: info: URI /mnt/INTERNAL/PINK FLOYD COLLECTION/Pink Floyd Covp DSD 2018/2018 A Foot In The Door/15 - Brain Damage.dsf Jan 25 12:17:50 volumioliving volumio[1386]: info: ALBUMART /albumart?cacheid=815&web=Pink%20Floyd/A%20Foot%20In%20The%20Door/extralarge&path=%2Fmnt%2FINTERNAL%2FPINK%20FLOYD%20COLLECTION%2FPink%20Floyd%20%20Covp%20%20DSD%202018%2F2018%20A%20Foot%20In%20The%20Door%2F16%20-%20Eclipse.dsf&metadata=false Jan 25 12:17:50 volumioliving volumio[1386]: info: URI /mnt/INTERNAL/PINK FLOYD COLLECTION/Pink Floyd Covp DSD 2018/2018 A Foot In The Door/16 - Eclipse.dsf Jan 25 12:17:50 volumioliving volumio[1386]: info: ALBUMART /albumart?cacheid=815&web=Pink%20Floyd/Relics/extralarge&path=%2Fmnt%2FINTERNAL%2FPINK%20FLOYD%20COLLECTION%2FPink%20Floyd%20%20Covp%20%20DSD%202018%2F71%20Relics%2F01%20-%20Arnold%20Layne.dsf&metadata=false Jan 25 12:17:50 volumioliving volumio[1386]: info: URI /mnt/INTERNAL/PINK FLOYD COLLECTION/Pink Floyd Covp DSD 2018/71 Relics/01 - Arnold Layne.dsf Jan 25 12:17:50 volumioliving volumio[1386]: info: ALBUMART /albumart?cacheid=815&web=Pink%20Floyd/Relics/extralarge&path=%2Fmnt%2FINTERNAL%2FPINK%20FLOYD%20COLLECTION%2FPink%20Floyd%20%20Covp%20%20DSD%202018%2F71%20Relics%2F02%20-%20Interstellar%20Overdrive.dsf&metadata=false Jan 25 12:17:50 volumioliving volumio[1386]: info: URI /mnt/INTERNAL/PINK FLOYD COLLECTION/Pink Floyd Covp DSD 2018/71 Relics/02 - Interstellar Overdrive.dsf Jan 25 12:17:50 volumioliving volumio[1386]: info: ALBUMART /albumart?cacheid=815&web=Pink%20Floyd/Relics/extralarge&path=%2Fmnt%2FINTERNAL%2FPINK%20FLOYD%20COLLECTION%2FPink%20Floyd%20%20Covp%20%20DSD%202018%2F71%20Relics%2F03%20-%20See%20Emily%20Play.dsf&metadata=false Jan 25 12:17:50 volumioliving volumio[1386]: info: URI /mnt/INTERNAL/PINK FLOYD COLLECTION/Pink Floyd Covp DSD 2018/71 Relics/03 - See Emily Play.dsf Jan 25 12:17:50 volumioliving volumio[1386]: info: ALBUMART /albumart?cacheid=815&web=Pink%20Floyd/Relics/extralarge&path=%2Fmnt%2FINTERNAL%2FPINK%20FLOYD%20COLLECTION%2FPink%20Floyd%20%20Covp%20%20DSD%202018%2F71%20Relics%2F04%20-%20Remember%20A%20Day.dsf&metadata=false Jan 25 12:17:50 volumioliving volumio[1386]: info: URI /mnt/INTERNAL/PINK FLOYD COLLECTION/Pink Floyd Covp DSD 2018/71 Relics/04 - Remember A Day.dsf Jan 25 12:17:50 volumioliving volumio[1386]: info: ALBUMART /albumart?cacheid=815&web=Pink%20Floyd/Relics/extralarge&path=%2Fmnt%2FINTERNAL%2FPINK%20FLOYD%20COLLECTION%2FPink%20Floyd%20%20Covp%20%20DSD%202018%2F71%20Relics%2F05%20-%20Paintbox.dsf&metadata=false Jan 25 12:17:50 volumioliving volumio[1386]: info: URI /mnt/INTERNAL/PINK FLOYD COLLECTION/Pink Floyd Covp DSD 2018/71 Relics/05 - Paintbox.dsf Jan 25 12:17:50 volumioliving volumio[1386]: info: ALBUMART /albumart?cacheid=815&web=Pink%20Floyd/Relics/extralarge&path=%2Fmnt%2FINTERNAL%2FPINK%20FLOYD%20COLLECTION%2FPink%20Floyd%20%20Covp%20%20DSD%202018%2F71%20Relics%2F06%20-%20Julia%20Dream.dsf&metadata=false Jan 25 12:17:50 volumioliving volumio[1386]: info: URI /mnt/INTERNAL/PINK FLOYD COLLECTION/Pink Floyd Covp DSD 2018/71 Relics/06 - Julia Dream.dsf Jan 25 12:17:50 volumioliving volumio[1386]: info: ALBUMART /albumart?cacheid=815&web=Pink%20Floyd/Relics/extralarge&path=%2Fmnt%2FINTERNAL%2FPINK%20FLOYD%20COLLECTION%2FPink%20Floyd%20%20Covp%20%20DSD%202018%2F71%20Relics%2F07%20-%20Careful%20With%20That%20Axe%2C%20Eugene.dsf&metadata=false Jan 25 12:17:50 volumioliving volumio[1386]: info: URI /mnt/INTERNAL/PINK FLOYD COLLECTION/Pink Floyd Covp DSD 2018/71 Relics/07 - Careful With That Axe, Eugene.dsf Jan 25 12:17:50 volumioliving volumio[1386]: info: ALBUMART /albumart?cacheid=815&web=Pink%20Floyd/Relics/extralarge&path=%2Fmnt%2FINTERNAL%2FPINK%20FLOYD%20COLLECTION%2FPink%20Floyd%20%20Covp%20%20DSD%202018%2F71%20Relics%2F08%20-%20Cirrus%20Minor.dsf&metadata=false Jan 25 12:17:50 volumioliving volumio[1386]: info: URI /mnt/INTERNAL/PINK FLOYD COLLECTION/Pink Floyd Covp DSD 2018/71 Relics/08 - Cirrus Minor.dsf Jan 25 12:17:50 volumioliving volumio[1386]: info: ALBUMART /albumart?cacheid=815&web=Pink%20Floyd/Relics/extralarge&path=%2Fmnt%2FINTERNAL%2FPINK%20FLOYD%20COLLECTION%2FPink%20Floyd%20%20Covp%20%20DSD%202018%2F71%20Relics%2F09%20-%20The%20Nile%20Song.dsf&metadata=false Jan 25 12:17:50 volumioliving volumio[1386]: info: URI /mnt/INTERNAL/PINK FLOYD COLLECTION/Pink Floyd Covp DSD 2018/71 Relics/09 - The Nile Song.dsf Jan 25 12:17:50 volumioliving volumio[1386]: info: ALBUMART /albumart?cacheid=815&web=Pink%20Floyd/Relics/extralarge&path=%2Fmnt%2FINTERNAL%2FPINK%20FLOYD%20COLLECTION%2FPink%20Floyd%20%20Covp%20%20DSD%202018%2F71%20Relics%2F10%20-%20Biding%20My%20Time.dsf&metadata=false Jan 25 12:17:50 volumioliving volumio[1386]: info: URI /mnt/INTERNAL/PINK FLOYD COLLECTION/Pink Floyd Covp DSD 2018/71 Relics/10 - Biding My Time.dsf Jan 25 12:17:50 volumioliving volumio[1386]: info: ALBUMART /albumart?cacheid=815&web=Pink%20Floyd/Relics/extralarge&path=%2Fmnt%2FINTERNAL%2FPINK%20FLOYD%20COLLECTION%2FPink%20Floyd%20%20Covp%20%20DSD%202018%2F71%20Relics%2F11%20-%20Bike.dsf&metadata=false Jan 25 12:17:50 volumioliving volumio[1386]: info: URI /mnt/INTERNAL/PINK FLOYD COLLECTION/Pink Floyd Covp DSD 2018/71 Relics/11 - Bike.dsf Jan 25 12:17:50 volumioliving volumio[1386]: info: ALBUMART /albumart?cacheid=815&web=Pink%20Floyd/A%20Collection%20of%20Great%20Dance%20Songs/extralarge&path=%2Fmnt%2FINTERNAL%2FPINK%20FLOYD%20COLLECTION%2FPink%20Floyd%20%20Covp%20%20DSD%202018%2F81%20A%20Collection%20of%20Great%20Dance%20Songs%2F01%20-%20One%20Of%20These%20Days.dsf&metadata=false Jan 25 12:17:50 volumioliving volumio[1386]: info: URI /mnt/INTERNAL/PINK FLOYD COLLECTION/Pink Floyd Covp DSD 2018/81 A Collection of Great Dance Songs/01 - One Of These Days.dsf Jan 25 12:17:50 volumioliving volumio[1386]: info: ALBUMART /albumart?cacheid=815&web=Pink%20Floyd/A%20Collection%20of%20Great%20Dance%20Songs/extralarge&path=%2Fmnt%2FINTERNAL%2FPINK%20FLOYD%20COLLECTION%2FPink%20Floyd%20%20Covp%20%20DSD%202018%2F81%20A%20Collection%20of%20Great%20Dance%20Songs%2F02%20-%20Money.dsf&metadata=false Jan 25 12:17:50 volumioliving volumio[1386]: info: URI /mnt/INTERNAL/PINK FLOYD COLLECTION/Pink Floyd Covp DSD 2018/81 A Collection of Great Dance Songs/02 - Money.dsf Jan 25 12:17:50 volumioliving volumio[1386]: info: ALBUMART /albumart?cacheid=815&web=Pink%20Floyd/A%20Collection%20of%20Great%20Dance%20Songs/extralarge&path=%2Fmnt%2FINTERNAL%2FPINK%20FLOYD%20COLLECTION%2FPink%20Floyd%20%20Covp%20%20DSD%202018%2F81%20A%20Collection%20of%20Great%20Dance%20Songs%2F03%20-%20Sheep.dsf&metadata=false Jan 25 12:17:50 volumioliving volumio[1386]: info: URI /mnt/INTERNAL/PINK FLOYD COLLECTION/Pink Floyd Covp DSD 2018/81 A Collection of Great Dance Songs/03 - Sheep.dsf Jan 25 12:17:50 volumioliving volumio[1386]: info: ALBUMART /albumart?cacheid=815&web=Pink%20Floyd/A%20Collection%20of%20Great%20Dance%20Songs/extralarge&path=%2Fmnt%2FINTERNAL%2FPINK%20FLOYD%20COLLECTION%2FPink%20Floyd%20%20Covp%20%20DSD%202018%2F81%20A%20Collection%20of%20Great%20Dance%20Songs%2F04%20-%20Shine%20On%20You%20Crazy%20Diamond.dsf&metadata=false Jan 25 12:17:50 volumioliving volumio[1386]: info: URI /mnt/INTERNAL/PINK FLOYD COLLECTION/Pink Floyd Covp DSD 2018/81 A Collection of Great Dance Songs/04 - Shine On You Crazy Diamond.dsf Jan 25 12:17:50 volumioliving volumio[1386]: info: ALBUMART /albumart?cacheid=815&web=Pink%20Floyd/A%20Collection%20of%20Great%20Dance%20Songs/extralarge&path=%2Fmnt%2FINTERNAL%2FPINK%20FLOYD%20COLLECTION%2FPink%20Floyd%20%20Covp%20%20DSD%202018%2F81%20A%20Collection%20of%20Great%20Dance%20Songs%2F05%20-%20Wish%20You%20Were%20Here.dsf&metadata=false Jan 25 12:17:50 volumioliving volumio[1386]: info: URI /mnt/INTERNAL/PINK FLOYD COLLECTION/Pink Floyd Covp DSD 2018/81 A Collection of Great Dance Songs/05 - Wish You Were Here.dsf Jan 25 12:17:50 volumioliving volumio[1386]: info: ALBUMART /albumart?cacheid=815&web=Pink%20Floyd/A%20Collection%20of%20Great%20Dance%20Songs/extralarge&path=%2Fmnt%2FINTERNAL%2FPINK%20FLOYD%20COLLECTION%2FPink%20Floyd%20%20Covp%20%20DSD%202018%2F81%20A%20Collection%20of%20Great%20Dance%20Songs%2F06%20-%20Another%20Brick%20In%20The%20Wall%20(Part%202).dsf&metadata=false Jan 25 12:17:50 volumioliving volumio[1386]: info: URI /mnt/INTERNAL/PINK FLOYD COLLECTION/Pink Floyd Covp DSD 2018/81 A Collection of Great Dance Songs/06 - Another Brick In The Wall (Part 2).dsf Jan 25 12:17:50 volumioliving volumio[1386]: info: ALBUMART /albumart?cacheid=815&web=Pink%20Floyd/A%20MomentaryLapse%20of%20Reason/extralarge&path=%2Fmnt%2FINTERNAL%2FPINK%20FLOYD%20COLLECTION%2FPink%20Floyd%20-%20A%20Momentary%20Lapse%20of%20Reason%20(1987)%2F01%20-%20Signs%20of%20Life.dsf&metadata=false Jan 25 12:17:50 volumioliving volumio[1386]: info: URI /mnt/INTERNAL/PINK FLOYD COLLECTION/Pink Floyd - A Momentary Lapse of Reason (1987)/01 - Signs of Life.dsf Jan 25 12:17:50 volumioliving volumio[1386]: info: ALBUMART /albumart?cacheid=815&web=Pink%20Floyd/A%20MomentaryLapse%20of%20Reason/extralarge&path=%2Fmnt%2FINTERNAL%2FPINK%20FLOYD%20COLLECTION%2FPink%20Floyd%20-%20A%20Momentary%20Lapse%20of%20Reason%20(1987)%2F02%20-%20Learning%20to%20fly.dsf&metadata=false Jan 25 12:17:50 volumioliving volumio[1386]: info: URI /mnt/INTERNAL/PINK FLOYD COLLECTION/Pink Floyd - A Momentary Lapse of Reason (1987)/02 - Learning to fly.dsf Jan 25 12:17:50 volumioliving volumio[1386]: info: ALBUMART /albumart?cacheid=815&web=Pink%20Floyd/A%20MomentaryLapse%20of%20Reason/extralarge&path=%2Fmnt%2FINTERNAL%2FPINK%20FLOYD%20COLLECTION%2FPink%20Floyd%20-%20A%20Momentary%20Lapse%20of%20Reason%20(1987)%2F03%20-%20The%20Dogs%20of%20War.dsf&metadata=false Jan 25 12:17:50 volumioliving volumio[1386]: info: URI /mnt/INTERNAL/PINK FLOYD COLLECTION/Pink Floyd - A Momentary Lapse of Reason (1987)/03 - The Dogs of War.dsf Jan 25 12:17:50 volumioliving volumio[1386]: info: ALBUMART /albumart?cacheid=815&web=Pink%20Floyd/A%20MomentaryLapse%20of%20Reason/extralarge&path=%2Fmnt%2FINTERNAL%2FPINK%20FLOYD%20COLLECTION%2FPink%20Floyd%20-%20A%20Momentary%20Lapse%20of%20Reason%20(1987)%2F04%20-%20One%20Slip.dsf&metadata=false Jan 25 12:17:50 volumioliving volumio[1386]: info: URI /mnt/INTERNAL/PINK FLOYD COLLECTION/Pink Floyd - A Momentary Lapse of Reason (1987)/04 - One Slip.dsf Jan 25 12:17:50 volumioliving volumio[1386]: info: ALBUMART /albumart?cacheid=815&web=Pink%20Floyd/A%20MomentaryLapse%20of%20Reason/extralarge&path=%2Fmnt%2FINTERNAL%2FPINK%20FLOYD%20COLLECTION%2FPink%20Floyd%20-%20A%20Momentary%20Lapse%20of%20Reason%20(1987)%2F05%20-%20On%20the%20turning%20away.dsf&metadata=false Jan 25 12:17:50 volumioliving volumio[1386]: info: URI /mnt/INTERNAL/PINK FLOYD COLLECTION/Pink Floyd - A Momentary Lapse of Reason (1987)/05 - On the turning away.dsf Jan 25 12:17:50 volumioliving volumio[1386]: info: ALBUMART /albumart?cacheid=815&web=Pink%20Floyd/A%20MomentaryLapse%20of%20Reason/extralarge&path=%2Fmnt%2FINTERNAL%2FPINK%20FLOYD%20COLLECTION%2FPink%20Floyd%20-%20A%20Momentary%20Lapse%20of%20Reason%20(1987)%2F06%20-%20Yet%20another%20Movie.dsf&metadata=false Jan 25 12:17:50 volumioliving volumio[1386]: info: URI /mnt/INTERNAL/PINK FLOYD COLLECTION/Pink Floyd - A Momentary Lapse of Reason (1987)/06 - Yet another Movie.dsf Jan 25 12:17:50 volumioliving volumio[1386]: info: ALBUMART /albumart?cacheid=815&web=Pink%20Floyd/A%20MomentaryLapse%20of%20Reason/extralarge&path=%2Fmnt%2FINTERNAL%2FPINK%20FLOYD%20COLLECTION%2FPink%20Floyd%20-%20A%20Momentary%20Lapse%20of%20Reason%20(1987)%2F07%20-%20Round%20and%20around.dsf&metadata=false Jan 25 12:17:50 volumioliving volumio[1386]: info: URI /mnt/INTERNAL/PINK FLOYD COLLECTION/Pink Floyd - A Momentary Lapse of Reason (1987)/07 - Round and around.dsf Jan 25 12:17:50 volumioliving volumio[1386]: info: ALBUMART /albumart?cacheid=815&web=Pink%20Floyd/A%20MomentaryLapse%20of%20Reason/extralarge&path=%2Fmnt%2FINTERNAL%2FPINK%20FLOYD%20COLLECTION%2FPink%20Floyd%20-%20A%20Momentary%20Lapse%20of%20Reason%20(1987)%2F08%20-%20A%20new%20Machine%20Part%201.dsf&metadata=false Jan 25 12:17:50 volumioliving volumio[1386]: info: URI /mnt/INTERNAL/PINK FLOYD COLLECTION/Pink Floyd - A Momentary Lapse of Reason (1987)/08 - A new Machine Part 1.dsf Jan 25 12:17:50 volumioliving volumio[1386]: info: ALBUMART /albumart?cacheid=815&web=Pink%20Floyd/A%20MomentaryLapse%20of%20Reason/extralarge&path=%2Fmnt%2FINTERNAL%2FPINK%20FLOYD%20COLLECTION%2FPink%20Floyd%20-%20A%20Momentary%20Lapse%20of%20Reason%20(1987)%2F09%20-%20Terminal%20Frost.dsf&metadata=false Jan 25 12:17:50 volumioliving volumio[1386]: info: URI /mnt/INTERNAL/PINK FLOYD COLLECTION/Pink Floyd - A Momentary Lapse of Reason (1987)/09 - Terminal Frost.dsf Jan 25 12:17:50 volumioliving volumio[1386]: info: ALBUMART /albumart?cacheid=815&web=Pink%20Floyd/A%20MomentaryLapse%20of%20Reason/extralarge&path=%2Fmnt%2FINTERNAL%2FPINK%20FLOYD%20COLLECTION%2FPink%20Floyd%20-%20A%20Momentary%20Lapse%20of%20Reason%20(1987)%2F10%20-%20A%20new%20Machine%20Part%202.dsf&metadata=false Jan 25 12:17:50 volumioliving volumio[1386]: info: URI /mnt/INTERNAL/PINK FLOYD COLLECTION/Pink Floyd - A Momentary Lapse of Reason (1987)/10 - A new Machine Part 2.dsf Jan 25 12:17:50 volumioliving volumio[1386]: info: ALBUMART /albumart?cacheid=815&web=Pink%20Floyd/A%20MomentaryLapse%20of%20Reason/extralarge&path=%2Fmnt%2FINTERNAL%2FPINK%20FLOYD%20COLLECTION%2FPink%20Floyd%20-%20A%20Momentary%20Lapse%20of%20Reason%20(1987)%2F11%20-%20Sorrow.dsf&metadata=false Jan 25 12:17:50 volumioliving volumio[1386]: info: URI /mnt/INTERNAL/PINK FLOYD COLLECTION/Pink Floyd - A Momentary Lapse of Reason (1987)/11 - Sorrow.dsf Jan 25 12:17:50 volumioliving volumio[1386]: info: ALBUMART /albumart?cacheid=815&web=Pink%20Floyd/Animals/extralarge&path=%2Fmnt%2FINTERNAL%2FPINK%20FLOYD%20COLLECTION%2FPink%20Floyd%20-%20Animals%20(1977)%2FPink%20Floyd%20-%20Animals%20Japan%201977%20side%2001.dsf&metadata=false Jan 25 12:17:50 volumioliving volumio[1386]: info: URI /mnt/INTERNAL/PINK FLOYD COLLECTION/Pink Floyd - Animals (1977)/Pink Floyd - Animals Japan 1977 side 01.dsf Jan 25 12:17:50 volumioliving volumio[1386]: info: ALBUMART /albumart?cacheid=815&web=Pink%20Floyd/Animals/extralarge&path=%2Fmnt%2FINTERNAL%2FPINK%20FLOYD%20COLLECTION%2FPink%20Floyd%20-%20Animals%20(1977)%2FPink%20Floyd%20-%20Animals%20Japan%201977%20side%2002.dsf&metadata=false Jan 25 12:17:50 volumioliving volumio[1386]: info: URI /mnt/INTERNAL/PINK FLOYD COLLECTION/Pink Floyd - Animals (1977)/Pink Floyd - Animals Japan 1977 side 02.dsf Jan 25 12:17:50 volumioliving volumio[1386]: info: ALBUMART /albumart?cacheid=815&web=Pink%20Floyd/Animals/extralarge&path=%2Fmnt%2FINTERNAL%2FPINK%20FLOYD%20COLLECTION%2FPink%20Floyd%20-%20Animals%20(1977)%2FPink%20Floyd%20-%20Animals.cue&metadata=false Jan 25 12:17:50 volumioliving volumio[1386]: info: URI /mnt/INTERNAL/PINK FLOYD COLLECTION/Pink Floyd - Animals (1977)/Pink Floyd - Animals.cue Jan 25 12:17:50 volumioliving volumio[1386]: info: ALBUMART /albumart?cacheid=815&web=Pink%20Floyd/Animals/extralarge&path=%2Fmnt%2FINTERNAL%2FPINK%20FLOYD%20COLLECTION%2FPink%20Floyd%20-%20Animals%20(1977)%2FPink%20Floyd%20-%20Animals.cue&metadata=false Jan 25 12:17:50 volumioliving volumio[1386]: info: URI /mnt/INTERNAL/PINK FLOYD COLLECTION/Pink Floyd - Animals (1977)/Pink Floyd - Animals.cue Jan 25 12:17:50 volumioliving volumio[1386]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 25 12:17:50 volumioliving volumio[1386]: Error: Unable to resolve or reject the same promise twice Jan 25 12:17:50 volumioliving volumio[1386]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Jan 25 12:17:50 volumioliving volumio[1386]: at /volumio/app/plugins/music_service/mpd/index.js:2569:21 Jan 25 12:17:50 volumioliving volumio[1386]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) Jan 25 12:17:50 volumioliving volumio[1386]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) Jan 25 12:17:50 volumioliving volumio[1386]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) Jan 25 12:17:50 volumioliving volumio[1386]: at Socket.emit (node:events:514:28) Jan 25 12:17:50 volumioliving volumio[1386]: at addChunk (node:internal/streams/readable:343:12) Jan 25 12:17:50 volumioliving volumio[1386]: at readableAddChunk (node:internal/streams/readable:312:11) Jan 25 12:17:50 volumioliving volumio[1386]: at Readable.push (node:internal/streams/readable:253:10) Jan 25 12:17:50 volumioliving volumio[1386]: at Pipe.onStreamRead (node:internal/stream_base_commons:190:23) Jan 25 12:17:50 volumioliving volumio[1386]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 25 12:17:50 volumioliving sudo[17414]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-01-25 12:16' Jan 25 12:17:50 volumioliving sudo[17414]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm 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="b05a530ca81d05eb9a84d0acf82089f0a60b8ee6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="4ad85e2147d972f0b838d33c21382d2d813b2ae8" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Dec 25 09:38:19 UTC 2025" VOLUMIO_VERSION="4.084" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="00e933ebe3692ad8aea9910d2289bf23"