-- Logs begin at Thu 2025-05-29 12:40:16 CEST, end at Thu 2025-05-29 20:11:45 CEST. -- May 29 20:10:04 volumio2 volumio[1315]: info: Starting Uninstall of plugin user_interface - peppyspectrum May 29 20:10:04 volumio2 volumio[1315]: info: Uninstalling plugin peppyspectrum May 29 20:10:04 volumio2 volumio[1315]: info: Stopping peppyspectrum service May 29 20:10:04 volumio2 volumio[1315]: info: CoreStateMachine::stop May 29 20:10:04 volumio2 volumio[1315]: info: CoreStateMachine::setConsumeUpdateService undefined May 29 20:10:04 volumio2 volumio[1315]: info: CoreStateMachine::updateTrackBlock May 29 20:10:04 volumio2 volumio[1315]: info: CorePlayQueue::getTrackBlock May 29 20:10:04 volumio2 volumio[1315]: info: CoreStateMachine::stPlaybackTimer May 29 20:10:04 volumio2 volumio[1315]: info: CoreStateMachine::pushState May 29 20:10:04 volumio2 volumio[1315]: info: CorePlayQueue::getTrack 0 May 29 20:10:04 volumio2 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 29 20:10:04 volumio2 volumio[1315]: info: CoreCommandRouter::volumioPushState May 29 20:10:04 volumio2 volumio[1315]: info: MRS: Pushing multiroomSync output update for this device May 29 20:10:04 volumio2 volumio[1315]: info: MRS: Pushing multiroomSync output May 29 20:10:04 volumio2 volumio[1315]: info: CoreStateMachine::serviceStop May 29 20:10:04 volumio2 volumio[1315]: info: CorePlayQueue::getTrack 0 May 29 20:10:04 volumio2 volumio[1315]: info: CoreCommandRouter::serviceStop May 29 20:10:04 volumio2 volumio[1315]: info: ControllerMpd::stop May 29 20:10:04 volumio2 volumio[1315]: verbose: ControllerMpd::sendMpdCommand stop May 29 20:10:04 volumio2 volumio[1315]: info: Disabling plugin peppyspectrum May 29 20:10:04 volumio2 volumio[1315]: info: Preparing to generate the ALSA configuration file May 29 20:10:04 volumio2 volumio[1315]: info: PeppySpectrum ---peppyspectrum status stop May 29 20:10:04 volumio2 volumio[1315]: info: sendMpdCommand stop took 45 milliseconds May 29 20:10:04 volumio2 sudo[8963]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service May 29 20:10:04 volumio2 sudo[8963]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 20:10:04 volumio2 volumio[1315]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf May 29 20:10:04 volumio2 volumio[1315]: info: Reading ALSA contributions from plugins. May 29 20:10:04 volumio2 systemd[1]: Stopping peppyspectrum Daemon... May 29 20:10:04 volumio2 systemd[1]: peppyspectrum.service: Main process exited, code=killed, status=15/TERM May 29 20:10:04 volumio2 volumio[1315]: ------------------------------------ BT MESSAGE: BT STATUS: running May 29 20:10:04 volumio2 sudo[8966]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service May 29 20:10:04 volumio2 sudo[8966]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 20:10:04 volumio2 volumio[1315]: info: Asound.conf file written May 29 20:10:04 volumio2 systemd[1]: peppyspectrum.service: Succeeded. May 29 20:10:04 volumio2 systemd[1]: Stopped peppyspectrum Daemon. May 29 20:10:04 volumio2 sudo[8966]: pam_unix(sudo:session): session closed for user root May 29 20:10:04 volumio2 sudo[8963]: pam_unix(sudo:session): session closed for user root May 29 20:10:04 volumio2 sudo[8969]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf May 29 20:10:04 volumio2 sudo[8969]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 20:10:04 volumio2 sudo[8969]: pam_unix(sudo:session): session closed for user root May 29 20:10:04 volumio2 volumio[1315]: No protocol specified May 29 20:10:04 volumio2 volumio[1315]: xcb_connection_has_error() returned true May 29 20:10:04 volumio2 volumio[1315]: info: Output device has changed, restarting MPD May 29 20:10:04 volumio2 sudo[8976]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 29 20:10:04 volumio2 sudo[8976]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 20:10:04 volumio2 sudo[8976]: pam_unix(sudo:session): session closed for user root May 29 20:10:04 volumio2 volumio[1315]: info: Output device has changed, restarting Shairport Sync May 29 20:10:04 volumio2 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 20:10:04 volumio2 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 20:10:04 volumio2 sudo[8979]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 29 20:10:04 volumio2 sudo[8979]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 20:10:04 volumio2 systemd[1]: Stopping Music Player Daemon... May 29 20:10:04 volumio2 systemd[1]: mpd.service: Succeeded. May 29 20:10:04 volumio2 systemd[1]: Stopped Music Player Daemon. May 29 20:10:04 volumio2 volumio[1315]: No protocol specified May 29 20:10:04 volumio2 volumio[1315]: xcb_connection_has_error() returned true May 29 20:10:04 volumio2 volumio[1315]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 29 20:10:04 volumio2 volumio[1315]: No protocol specified May 29 20:10:04 volumio2 volumio[1315]: xcb_connection_has_error() returned true May 29 20:10:04 volumio2 volumio[1315]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration May 29 20:10:04 volumio2 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 20:10:04 volumio2 vtcs[7844]: [2025-05-29 20:10:04.667] [tisoc] [error] [SpkconServer.cpp:376] recv error. socket disconnected May 29 20:10:04 volumio2 vtcs[7844]: [2025-05-29 20:10:04.667] [tisoc] [warning] [SessionManagerImpl.cpp:243] Illegal State: IDLE May 29 20:10:04 volumio2 systemd[1]: Starting Music Player Daemon... May 29 20:10:04 volumio2 volumio[1315]: info: Checking if uninstall.sh is present May 29 20:10:04 volumio2 sudo[8989]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 29 20:10:04 volumio2 sudo[8989]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 20:10:04 volumio2 systemd[1]: Stopping Volumio Tidal Connect Service... May 29 20:10:04 volumio2 systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM May 29 20:10:04 volumio2 systemd[1]: vtcs.service: Succeeded. May 29 20:10:04 volumio2 systemd[1]: Stopped Volumio Tidal Connect Service. May 29 20:10:04 volumio2 sudo[8989]: pam_unix(sudo:session): session closed for user root May 29 20:10:04 volumio2 volumio[1315]: info: Executing uninstall.sh May 29 20:10:04 volumio2 sudo[8992]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 29 20:10:04 volumio2 sudo[8992]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 20:10:04 volumio2 volumio[1315]: ------------------------------------ BT MESSAGE: BT STATUS: running May 29 20:10:04 volumio2 sudo[8984]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 29 20:10:04 volumio2 sudo[8984]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 20:10:04 volumio2 sudo[8992]: pam_unix(sudo:session): session closed for user root May 29 20:10:04 volumio2 volumio[1315]: ------------------------------------ BT MESSAGE: BT STATUS: running May 29 20:10:04 volumio2 volumio[1315]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 29 20:10:04 volumio2 volumio[1315]: ------------------------------------ BT MESSAGE: BT STATUS: running May 29 20:10:04 volumio2 sudo[8984]: pam_unix(sudo:session): session closed for user root May 29 20:10:04 volumio2 volumio[1315]: ------------------------------------ BT MESSAGE: BT STATUS: running May 29 20:10:04 volumio2 volumio[1315]: info: MPD Permissions set May 29 20:10:04 volumio2 volumio[1315]: info: peppyspectrum Daemon Stop May 29 20:10:04 volumio2 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 20:10:04 volumio2 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 20:10:04 volumio2 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 20:10:04 volumio2 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 20:10:04 volumio2 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 20:10:04 volumio2 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 20:10:04 volumio2 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 20:10:04 volumio2 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 20:10:04 volumio2 sudo[8997]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/user_interface/peppyspectrum/uninstall.sh May 29 20:10:04 volumio2 sudo[8997]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 20:10:04 volumio2 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 20:10:04 volumio2 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber May 29 20:10:04 volumio2 sudo[9002]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -Rf /data/plugins/user_interface/peppyspectrum/PeppySpectrum/__pycache__ May 29 20:10:04 volumio2 sudo[9002]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 20:10:04 volumio2 sudo[9002]: pam_unix(sudo:session): session closed for user root May 29 20:10:04 volumio2 sudo[9008]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm /etc/systemd/system/peppyspectrum.service May 29 20:10:04 volumio2 sudo[9008]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 20:10:04 volumio2 sudo[9008]: pam_unix(sudo:session): session closed for user root May 29 20:10:04 volumio2 sudo[8997]: pam_unix(sudo:session): session closed for user root May 29 20:10:04 volumio2 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 20:10:04 volumio2 volumio[1315]: info: Uninstall script completed May 29 20:10:04 volumio2 volumio[1315]: info: Removing plugin peppyspectrum from configuration May 29 20:10:04 volumio2 volumio[1315]: info: Successfully removed peppyspectrum configuration files May 29 20:10:04 volumio2 volumio[1315]: info: Plugin folders cleanup May 29 20:10:04 volumio2 volumio[1315]: info: Scanning into folder /volumio/app/plugins/ May 29 20:10:04 volumio2 volumio[1315]: info: Scanning category audio_interface May 29 20:10:04 volumio2 volumio[1315]: info: Scanning category miscellanea May 29 20:10:04 volumio2 volumio[1315]: info: Scanning category music_service May 29 20:10:04 volumio2 volumio[1315]: info: Scanning category plugins.json May 29 20:10:04 volumio2 volumio[1315]: info: Scanning category system_controller May 29 20:10:04 volumio2 volumio[1315]: info: Scanning category user_interface May 29 20:10:04 volumio2 volumio[1315]: info: Scanning into folder /data/plugins/ May 29 20:10:04 volumio2 volumio[1315]: info: Scanning category audio_interface May 29 20:10:04 volumio2 volumio[1315]: info: Scanning category music_service May 29 20:10:04 volumio2 volumio[1315]: info: Scanning category user_interface May 29 20:10:04 volumio2 volumio[1315]: info: Cleaning folder for peppyspectrum May 29 20:10:05 volumio2 volumio[1315]: info: Plugin folders cleanup completed May 29 20:10:05 volumio2 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 29 20:10:05 volumio2 volumio[1315]: info: [{"prettyName":"Mpd http & Icecast output","name":"mpdoutput","category":"audio_interface","version":"1.0.5","icon":"fa-music","isManuallyInstalled":false,"enabled":true,"active":false},{"prettyName":"80s80s Radio","name":"80s80s","category":"music_service","version":"1.0.4","icon":"fa-headphones","isManuallyInstalled":false,"enabled":true,"active":false},{"prettyName":"Now Playing","name":"now_playing","category":"user_interface","version":"0.7.5","icon":"fa-play-circle","isManuallyInstalled":false,"enabled":true,"active":false}] May 29 20:10:05 volumio2 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken May 29 20:10:05 volumio2 volumio[1315]: info: Starting Shairport Sync May 29 20:10:05 volumio2 volumio[1315]: info: CoreCommandRouter::volumioGetState May 29 20:10:05 volumio2 volumio[1315]: info: CorePlayQueue::getTrack 0 May 29 20:10:05 volumio2 volumio[1315]: info: CoreCommandRouter::volumioPause May 29 20:10:05 volumio2 volumio[1315]: info: CoreStateMachine::pause May 29 20:10:05 volumio2 volumio[1315]: info: mpdhttpout --- Volumio set on pause May 29 20:10:05 volumio2 volumio[1315]: info: mpdhttpout ---Configuration successfully added to mpd.conf. May 29 20:10:05 volumio2 sudo[9017]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 29 20:10:05 volumio2 sudo[9017]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 20:10:05 volumio2 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 29 20:10:05 volumio2 volumio[1315]: ------------------------------------ BT MESSAGE: BT STATUS: running May 29 20:10:05 volumio2 systemd[1]: shairport-sync.service: Succeeded. May 29 20:10:05 volumio2 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 29 20:10:05 volumio2 volumio[1315]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 29 20:10:05 volumio2 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 29 20:10:05 volumio2 sudo[9017]: pam_unix(sudo:session): session closed for user root May 29 20:10:05 volumio2 volumio[1315]: info: Shairport-Sync Started May 29 20:10:05 volumio2 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 29 20:10:05 volumio2 volumio[1315]: error: MPD error: Error: This socket has been ended by the other party May 29 20:10:05 volumio2 volumio[1315]: error: This socket has been ended by the other party {"code":"EPIPE"} May 29 20:10:05 volumio2 volumio[1315]: error: MPD error: Error: This socket has been ended by the other party May 29 20:10:05 volumio2 volumio[1315]: error: This socket has been ended by the other party {"code":"EPIPE"} May 29 20:10:05 volumio2 volumio[1315]: error: MPD error: Error: This socket has been ended by the other party May 29 20:10:05 volumio2 volumio[1315]: error: This socket has been ended by the other party {"code":"EPIPE"} May 29 20:10:05 volumio2 mpd[9000]: May 29 20:10 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 29 20:10:05 volumio2 systemd[1]: Started Music Player Daemon. May 29 20:10:05 volumio2 sudo[8979]: pam_unix(sudo:session): session closed for user root May 29 20:10:05 volumio2 volumio[1315]: error: updateQueue error: null May 29 20:10:07 volumio2 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd May 29 20:10:07 volumio2 sudo[9026]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 29 20:10:07 volumio2 sudo[9026]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 20:10:07 volumio2 systemd[1]: Stopping Music Player Daemon... May 29 20:10:07 volumio2 volumio[1315]: ------------------------------------ BT MESSAGE: BT STATUS: running May 29 20:10:07 volumio2 systemd[1]: mpd.service: Succeeded. May 29 20:10:07 volumio2 systemd[1]: Stopped Music Player Daemon. May 29 20:10:07 volumio2 volumio[1315]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 29 20:10:07 volumio2 systemd[1]: Starting Music Player Daemon... May 29 20:10:07 volumio2 volumio[1315]: ------------------------------------ BT MESSAGE: BT STATUS: running May 29 20:10:07 volumio2 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 29 20:10:07 volumio2 volumio[1315]: error: MPD error: Error: This socket has been ended by the other party May 29 20:10:07 volumio2 volumio[1315]: error: This socket has been ended by the other party {"code":"EPIPE"} May 29 20:10:07 volumio2 volumio[1315]: error: MPD error: Error: This socket has been ended by the other party May 29 20:10:07 volumio2 volumio[1315]: error: This socket has been ended by the other party {"code":"EPIPE"} May 29 20:10:07 volumio2 volumio[1315]: error: MPD error: Error: This socket has been ended by the other party May 29 20:10:07 volumio2 volumio[1315]: error: This socket has been ended by the other party {"code":"EPIPE"} May 29 20:10:07 volumio2 sudo[9028]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 29 20:10:07 volumio2 sudo[9028]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 20:10:07 volumio2 sudo[9028]: pam_unix(sudo:session): session closed for user root May 29 20:10:07 volumio2 volumio[1315]: info: TidalConnect service stoped! May 29 20:10:07 volumio2 volumio[1315]: info: TidalConnect service stoped! May 29 20:10:07 volumio2 volumio[1315]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect May 29 20:10:07 volumio2 volumio[1315]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect May 29 20:10:07 volumio2 sudo[9055]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service May 29 20:10:07 volumio2 sudo[9055]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 20:10:07 volumio2 mpd[9031]: May 29 20:10 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 29 20:10:07 volumio2 systemd[1]: Started Volumio Tidal Connect Service. May 29 20:10:07 volumio2 systemd[1]: Started Music Player Daemon. May 29 20:10:07 volumio2 sudo[9026]: pam_unix(sudo:session): session closed for user root May 29 20:10:07 volumio2 sudo[9055]: pam_unix(sudo:session): session closed for user root May 29 20:10:07 volumio2 volumio[1315]: error: updateQueue error: null May 29 20:10:07 volumio2 volumio[1315]: info: Executing endpoint tc_getconfig May 29 20:10:07 volumio2 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig May 29 20:10:07 volumio2 vtcs[9057]: STARTING TidalConnect services, version: 1.4.0.34 May 29 20:10:08 volumio2 vtcs[9057]: STARTED TidalConnect services. May 29 20:10:08 volumio2 volumio[1315]: info: Executing endpoint tc_connect May 29 20:10:08 volumio2 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect May 29 20:10:08 volumio2 volumio[1315]: info: Connecting to TidalConnect May 29 20:10:08 volumio2 volumio[1315]: info: CoreCommandRouter::servicePushState May 29 20:10:08 volumio2 volumio[1315]: info: CoreStateMachine::pushState May 29 20:10:08 volumio2 volumio[1315]: info: CorePlayQueue::getTrack 0 May 29 20:10:08 volumio2 volumio[1315]: info: CoreCommandRouter::volumioPushState May 29 20:10:08 volumio2 volumio[1315]: info: MRS: Pushing multiroomSync output update for this device May 29 20:10:08 volumio2 volumio[1315]: info: MRS: Pushing multiroomSync output May 29 20:10:08 volumio2 volumio[1315]: info: CorePlayQueue::getTrack 0 May 29 20:10:08 volumio2 volumio[1315]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect May 29 20:10:08 volumio2 volumio[1315]: info: CoreCommandRouter::servicePushState May 29 20:10:08 volumio2 volumio[1315]: info: CoreStateMachine::pushState May 29 20:10:08 volumio2 volumio[1315]: info: CorePlayQueue::getTrack 0 May 29 20:10:08 volumio2 volumio[1315]: info: CoreCommandRouter::volumioPushState May 29 20:10:08 volumio2 volumio[1315]: info: MRS: Pushing multiroomSync output update for this device May 29 20:10:08 volumio2 volumio[1315]: info: MRS: Pushing multiroomSync output May 29 20:10:08 volumio2 volumio[1315]: info: CorePlayQueue::getTrack 0 May 29 20:10:08 volumio2 volumio[1315]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect May 29 20:10:10 volumio2 volumio[1315]: info: TidalConnect service started! May 29 20:10:12 volumio2 volumio[1315]: info: CoreCommandRouter::volumioGetState May 29 20:10:12 volumio2 volumio[1315]: info: CorePlayQueue::getTrack 0 May 29 20:10:15 volumio2 volumio[1315]: info: Executing endpoint metavolumio May 29 20:10:15 volumio2 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 29 20:10:15 volumio2 volumio[1315]: info: Executing endpoint metavolumio May 29 20:10:15 volumio2 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 29 20:10:15 volumio2 volumio[1315]: info: Executing endpoint metavolumio May 29 20:10:15 volumio2 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 29 20:10:16 volumio2 volumio[1315]: info: CoreCommandRouter::volumioPlay May 29 20:10:16 volumio2 volumio[1315]: info: CoreStateMachine::play index undefined May 29 20:10:16 volumio2 volumio[1315]: info: CoreStateMachine::setConsumeUpdateService undefined May 29 20:10:16 volumio2 volumio[1315]: info: CorePlayQueue::getTrack 0 May 29 20:10:16 volumio2 volumio[1315]: info: CoreStateMachine::startPlaybackTimer May 29 20:10:16 volumio2 volumio[1315]: info: CorePlayQueue::getTrack 0 May 29 20:10:16 volumio2 volumio[1315]: info: CoreCommandRouter::volumioGetVisibleSources May 29 20:10:16 volumio2 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 29 20:10:16 volumio2 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject May 29 20:10:16 volumio2 volumio[1315]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/Munich City Nights Vol. 4/01 Underworld - Underneath The Radar (12').flac May 29 20:10:16 volumio2 volumio[1315]: verbose: ControllerMpd::sendMpdCommand stop May 29 20:10:16 volumio2 volumio[1315]: info: sendMpdCommand stop took 1 milliseconds May 29 20:10:16 volumio2 volumio[1315]: verbose: ControllerMpd::sendMpdCommand clear May 29 20:10:16 volumio2 volumio[1315]: info: May 29 20:10:16 volumio2 volumio[1315]: ---------------------------- MPD announces system playlist update May 29 20:10:16 volumio2 volumio[1315]: info: Ignoring MPD Status Update May 29 20:10:16 volumio2 volumio[1315]: info: sendMpdCommand clear took 2 milliseconds May 29 20:10:16 volumio2 volumio[1315]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/Munich City Nights Vol. 4/01 Underworld - Underneath The Radar (12').flac" May 29 20:10:16 volumio2 volumio[1315]: error: updateQueue error: null May 29 20:10:16 volumio2 volumio[1315]: info: ------------------------------ 3ms May 29 20:10:16 volumio2 volumio[1315]: info: May 29 20:10:16 volumio2 volumio[1315]: ---------------------------- MPD announces system playlist update May 29 20:10:16 volumio2 volumio[1315]: info: Ignoring MPD Status Update May 29 20:10:16 volumio2 volumio[1315]: info: sendMpdCommand add "INTERNAL/Munich City Nights Vol. 4/01 Underworld - Underneath The Radar (12').flac" took 2 milliseconds May 29 20:10:16 volumio2 volumio[1315]: verbose: ControllerMpd::sendMpdCommand play May 29 20:10:16 volumio2 volumio[1315]: info: ------------------------------ 2ms May 29 20:10:16 volumio2 volumio[1315]: info: May 29 20:10:16 volumio2 volumio[1315]: ---------------------------- MPD announces state update: player May 29 20:10:16 volumio2 volumio[1315]: info: sendMpdCommand play took 2 milliseconds May 29 20:10:16 volumio2 volumio[1315]: info: ControllerMpd::getState May 29 20:10:16 volumio2 volumio[1315]: verbose: ControllerMpd::sendMpdCommand status May 29 20:10:16 volumio2 volumio[1315]: info: May 29 20:10:16 volumio2 volumio[1315]: ---------------------------- MPD announces state update: player May 29 20:10:16 volumio2 volumio[1315]: info: sendMpdCommand status took 4 milliseconds May 29 20:10:16 volumio2 volumio[1315]: info: ControllerMpd::getState May 29 20:10:16 volumio2 volumio[1315]: verbose: ControllerMpd::sendMpdCommand status May 29 20:10:16 volumio2 volumio[1315]: verbose: ControllerMpd::parseState May 29 20:10:16 volumio2 volumio[1315]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 29 20:10:16 volumio2 volumio[1315]: info: sendMpdCommand status took 2 milliseconds May 29 20:10:16 volumio2 volumio[1315]: info: sendMpdCommand playlistinfo took 1 milliseconds May 29 20:10:16 volumio2 volumio[1315]: verbose: ControllerMpd::parseState May 29 20:10:16 volumio2 volumio[1315]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 29 20:10:16 volumio2 volumio[1315]: verbose: ControllerMpd::parseTrackInfo May 29 20:10:16 volumio2 volumio[1315]: info: ControllerMpd::pushState May 29 20:10:16 volumio2 volumio[1315]: info: CoreCommandRouter::servicePushState May 29 20:10:16 volumio2 volumio[1315]: info: CorePlayQueue::getTrack 0 May 29 20:10:16 volumio2 volumio[1315]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":481,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"Underneath The Radar (12\")","artist":"Underworld","album":"Munich City Nights Vol. 4","uri":"INTERNAL/Munich City Nights Vol. 4/01 Underworld - Underneath The Radar (12').flac","trackType":"flac"} May 29 20:10:16 volumio2 volumio[1315]: verbose: CURRENT POSITION 0 May 29 20:10:16 volumio2 volumio[1315]: info: CoreStateMachine::syncState stateService play May 29 20:10:16 volumio2 volumio[1315]: info: CoreStateMachine::syncState currentStatus stop May 29 20:10:16 volumio2 volumio[1315]: info: ------------------------------ 12ms May 29 20:10:16 volumio2 volumio[1315]: info: sendMpdCommand playlistinfo took 3 milliseconds May 29 20:10:16 volumio2 volumio[1315]: verbose: ControllerMpd::parseTrackInfo May 29 20:10:16 volumio2 volumio[1315]: info: ControllerMpd::pushState May 29 20:10:16 volumio2 volumio[1315]: info: CoreCommandRouter::servicePushState May 29 20:10:16 volumio2 volumio[1315]: info: CorePlayQueue::getTrack 0 May 29 20:10:16 volumio2 volumio[1315]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1206,"duration":481,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"Underneath The Radar (12\")","artist":"Underworld","album":"Munich City Nights Vol. 4","uri":"INTERNAL/Munich City Nights Vol. 4/01 Underworld - Underneath The Radar (12').flac","trackType":"flac"} May 29 20:10:16 volumio2 volumio[1315]: verbose: CURRENT POSITION 0 May 29 20:10:16 volumio2 volumio[1315]: info: CoreStateMachine::syncState stateService play May 29 20:10:16 volumio2 volumio[1315]: info: CoreStateMachine::syncState currentStatus play May 29 20:10:16 volumio2 volumio[1315]: info: Received an update from plugin. extracting info from payload May 29 20:10:16 volumio2 volumio[1315]: info: CoreStateMachine::pushState May 29 20:10:16 volumio2 volumio[1315]: info: CorePlayQueue::getTrack 0 May 29 20:10:16 volumio2 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 29 20:10:16 volumio2 volumio[1315]: info: CoreCommandRouter::volumioPushState May 29 20:10:16 volumio2 volumio[1315]: info: MRS: Pushing multiroomSync output update for this device May 29 20:10:16 volumio2 volumio[1315]: info: MRS: Pushing multiroomSync output May 29 20:10:16 volumio2 volumio[1315]: info: CoreStateMachine::pushState May 29 20:10:16 volumio2 volumio[1315]: info: CorePlayQueue::getTrack 0 May 29 20:10:16 volumio2 volumio[1315]: info: CoreCommandRouter::volumioPushState May 29 20:10:16 volumio2 volumio[1315]: info: MRS: Pushing multiroomSync output update for this device May 29 20:10:16 volumio2 volumio[1315]: info: MRS: Pushing multiroomSync output May 29 20:10:16 volumio2 volumio[1315]: info: ------------------------------ 28ms May 29 20:10:16 volumio2 volumio[1315]: error: Failed request for metavolumio API May 29 20:10:18 volumio2 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 29 20:10:19 volumio2 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 29 20:10:20 volumio2 volumio[1315]: info: CoreCommandRouter::volumioNext May 29 20:10:20 volumio2 volumio[1315]: info: CoreStateMachine::next May 29 20:10:20 volumio2 volumio[1315]: info: CoreStateMachine::stop May 29 20:10:20 volumio2 volumio[1315]: info: CoreStateMachine::setConsumeUpdateService undefined May 29 20:10:20 volumio2 volumio[1315]: info: CoreStateMachine::stPlaybackTimer May 29 20:10:20 volumio2 volumio[1315]: info: CoreStateMachine::updateTrackBlock May 29 20:10:20 volumio2 volumio[1315]: info: CorePlayQueue::getTrackBlock May 29 20:10:20 volumio2 volumio[1315]: info: CoreStateMachine::pushState May 29 20:10:20 volumio2 volumio[1315]: info: CorePlayQueue::getTrack 0 May 29 20:10:20 volumio2 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 29 20:10:20 volumio2 volumio[1315]: info: CoreCommandRouter::volumioPushState May 29 20:10:20 volumio2 volumio[1315]: info: MRS: Pushing multiroomSync output update for this device May 29 20:10:20 volumio2 volumio[1315]: info: MRS: Pushing multiroomSync output May 29 20:10:20 volumio2 volumio[1315]: info: CoreStateMachine::serviceStop May 29 20:10:20 volumio2 volumio[1315]: info: CorePlayQueue::getTrack 0 May 29 20:10:20 volumio2 volumio[1315]: info: CoreCommandRouter::serviceStop May 29 20:10:20 volumio2 volumio[1315]: info: ControllerMpd::stop May 29 20:10:20 volumio2 volumio[1315]: verbose: ControllerMpd::sendMpdCommand stop May 29 20:10:20 volumio2 volumio[1315]: info: May 29 20:10:20 volumio2 volumio[1315]: ---------------------------- MPD announces state update: player May 29 20:10:20 volumio2 volumio[1315]: info: sendMpdCommand stop took 16 milliseconds May 29 20:10:20 volumio2 volumio[1315]: info: ControllerMpd::getState May 29 20:10:20 volumio2 volumio[1315]: verbose: ControllerMpd::sendMpdCommand status May 29 20:10:20 volumio2 volumio[1315]: info: CoreStateMachine::play index undefined May 29 20:10:20 volumio2 volumio[1315]: info: CoreStateMachine::setConsumeUpdateService undefined May 29 20:10:20 volumio2 volumio[1315]: info: CorePlayQueue::getTrack 1 May 29 20:10:20 volumio2 volumio[1315]: info: sendMpdCommand status took 3 milliseconds May 29 20:10:20 volumio2 volumio[1315]: verbose: ControllerMpd::parseState May 29 20:10:20 volumio2 volumio[1315]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 29 20:10:20 volumio2 volumio[1315]: info: sendMpdCommand playlistinfo took 4 milliseconds May 29 20:10:20 volumio2 volumio[1315]: verbose: ControllerMpd::parseTrackInfo May 29 20:10:20 volumio2 volumio[1315]: info: ControllerMpd::pushState May 29 20:10:20 volumio2 volumio[1315]: info: CoreCommandRouter::servicePushState May 29 20:10:20 volumio2 volumio[1315]: info: CoreStateMachine::pushState May 29 20:10:20 volumio2 volumio[1315]: info: CorePlayQueue::getTrack 0 May 29 20:10:20 volumio2 volumio[1315]: info: CoreCommandRouter::volumioPushState May 29 20:10:20 volumio2 volumio[1315]: info: MRS: Pushing multiroomSync output update for this device May 29 20:10:20 volumio2 volumio[1315]: info: MRS: Pushing multiroomSync output May 29 20:10:20 volumio2 volumio[1315]: info: CorePlayQueue::getTrack 0 May 29 20:10:20 volumio2 volumio[1315]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Underneath The Radar (12\")","artist":"Underworld","album":"Munich City Nights Vol. 4","uri":"INTERNAL/Munich City Nights Vol. 4/01 Underworld - Underneath The Radar (12').flac","trackType":"flac"} May 29 20:10:20 volumio2 volumio[1315]: verbose: CURRENT POSITION 0 May 29 20:10:20 volumio2 volumio[1315]: info: CoreStateMachine::syncState stateService stop May 29 20:10:20 volumio2 volumio[1315]: info: CoreStateMachine::syncState currentStatus stop May 29 20:10:20 volumio2 volumio[1315]: info: CoreStateMachine::pushState May 29 20:10:20 volumio2 volumio[1315]: info: CorePlayQueue::getTrack 0 May 29 20:10:20 volumio2 volumio[1315]: info: CoreCommandRouter::volumioPushState May 29 20:10:20 volumio2 volumio[1315]: info: MRS: Pushing multiroomSync output update for this device May 29 20:10:20 volumio2 volumio[1315]: info: MRS: Pushing multiroomSync output May 29 20:10:20 volumio2 volumio[1315]: info: No code May 29 20:10:20 volumio2 volumio[1315]: info: CoreStateMachine::pushState May 29 20:10:20 volumio2 volumio[1315]: info: CorePlayQueue::getTrack 0 May 29 20:10:20 volumio2 volumio[1315]: info: CoreCommandRouter::volumioPushState May 29 20:10:20 volumio2 volumio[1315]: info: MRS: Pushing multiroomSync output update for this device May 29 20:10:20 volumio2 volumio[1315]: info: MRS: Pushing multiroomSync output May 29 20:10:20 volumio2 volumio[1315]: info: ------------------------------ 33ms May 29 20:10:22 volumio2 volumio[1315]: info: CoreCommandRouter::volumioNext May 29 20:10:22 volumio2 volumio[1315]: info: CoreStateMachine::next May 29 20:10:22 volumio2 volumio[1315]: info: CoreStateMachine::stop May 29 20:10:22 volumio2 volumio[1315]: info: CoreStateMachine::setConsumeUpdateService undefined May 29 20:10:22 volumio2 volumio[1315]: info: CoreStateMachine::play index undefined May 29 20:10:22 volumio2 volumio[1315]: info: CoreStateMachine::setConsumeUpdateService undefined May 29 20:10:22 volumio2 volumio[1315]: info: CorePlayQueue::getTrack 1 May 29 20:10:23 volumio2 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 29 20:10:34 volumio2 volumio[1315]: info: CoreCommandRouter::volumioPlay May 29 20:10:34 volumio2 volumio[1315]: info: CoreStateMachine::play index undefined May 29 20:10:34 volumio2 volumio[1315]: info: CoreStateMachine::setConsumeUpdateService undefined May 29 20:10:34 volumio2 volumio[1315]: info: CorePlayQueue::getTrack 0 May 29 20:10:34 volumio2 volumio[1315]: info: CoreStateMachine::startPlaybackTimer May 29 20:10:34 volumio2 volumio[1315]: info: CorePlayQueue::getTrack 0 May 29 20:10:34 volumio2 volumio[1315]: info: CoreCommandRouter::volumioGetVisibleSources May 29 20:10:34 volumio2 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 29 20:10:34 volumio2 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject May 29 20:10:34 volumio2 volumio[1315]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/Munich City Nights Vol. 4/01 Underworld - Underneath The Radar (12').flac May 29 20:10:34 volumio2 volumio[1315]: verbose: ControllerMpd::sendMpdCommand stop May 29 20:10:34 volumio2 volumio[1315]: info: sendMpdCommand stop took 0 milliseconds May 29 20:10:34 volumio2 volumio[1315]: verbose: ControllerMpd::sendMpdCommand clear May 29 20:10:34 volumio2 volumio[1315]: info: May 29 20:10:34 volumio2 volumio[1315]: ---------------------------- MPD announces system playlist update May 29 20:10:34 volumio2 volumio[1315]: info: Ignoring MPD Status Update May 29 20:10:34 volumio2 volumio[1315]: info: sendMpdCommand clear took 1 milliseconds May 29 20:10:34 volumio2 volumio[1315]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/Munich City Nights Vol. 4/01 Underworld - Underneath The Radar (12').flac" May 29 20:10:34 volumio2 volumio[1315]: error: updateQueue error: null May 29 20:10:34 volumio2 volumio[1315]: info: May 29 20:10:34 volumio2 volumio[1315]: ---------------------------- MPD announces system playlist update May 29 20:10:34 volumio2 volumio[1315]: info: Ignoring MPD Status Update May 29 20:10:34 volumio2 volumio[1315]: info: ------------------------------ 3ms May 29 20:10:34 volumio2 volumio[1315]: info: sendMpdCommand add "INTERNAL/Munich City Nights Vol. 4/01 Underworld - Underneath The Radar (12').flac" took 2 milliseconds May 29 20:10:34 volumio2 volumio[1315]: verbose: ControllerMpd::sendMpdCommand play May 29 20:10:34 volumio2 volumio[1315]: info: ------------------------------ 3ms May 29 20:10:34 volumio2 volumio[1315]: info: May 29 20:10:34 volumio2 volumio[1315]: ---------------------------- MPD announces state update: player May 29 20:10:34 volumio2 volumio[1315]: info: sendMpdCommand play took 3 milliseconds May 29 20:10:34 volumio2 volumio[1315]: info: ControllerMpd::getState May 29 20:10:34 volumio2 volumio[1315]: verbose: ControllerMpd::sendMpdCommand status May 29 20:10:34 volumio2 volumio[1315]: info: May 29 20:10:34 volumio2 volumio[1315]: ---------------------------- MPD announces state update: player May 29 20:10:34 volumio2 volumio[1315]: info: ControllerMpd::getState May 29 20:10:34 volumio2 volumio[1315]: verbose: ControllerMpd::sendMpdCommand status May 29 20:10:34 volumio2 volumio[1315]: info: sendMpdCommand status took 2 milliseconds May 29 20:10:34 volumio2 volumio[1315]: verbose: ControllerMpd::parseState May 29 20:10:34 volumio2 volumio[1315]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 29 20:10:34 volumio2 volumio[1315]: info: sendMpdCommand status took 1 milliseconds May 29 20:10:34 volumio2 volumio[1315]: info: sendMpdCommand playlistinfo took 0 milliseconds May 29 20:10:34 volumio2 volumio[1315]: verbose: ControllerMpd::parseState May 29 20:10:34 volumio2 volumio[1315]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 29 20:10:34 volumio2 volumio[1315]: verbose: ControllerMpd::parseTrackInfo May 29 20:10:34 volumio2 volumio[1315]: info: ControllerMpd::pushState May 29 20:10:34 volumio2 volumio[1315]: info: CoreCommandRouter::servicePushState May 29 20:10:34 volumio2 volumio[1315]: info: CorePlayQueue::getTrack 0 May 29 20:10:34 volumio2 volumio[1315]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1206,"duration":481,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"Underneath The Radar (12\")","artist":"Underworld","album":"Munich City Nights Vol. 4","uri":"INTERNAL/Munich City Nights Vol. 4/01 Underworld - Underneath The Radar (12').flac","trackType":"flac"} May 29 20:10:34 volumio2 volumio[1315]: verbose: CURRENT POSITION 0 May 29 20:10:34 volumio2 volumio[1315]: info: CoreStateMachine::syncState stateService play May 29 20:10:34 volumio2 volumio[1315]: info: CoreStateMachine::syncState currentStatus stop May 29 20:10:34 volumio2 volumio[1315]: info: ------------------------------ 6ms May 29 20:10:34 volumio2 volumio[1315]: info: sendMpdCommand playlistinfo took 2 milliseconds May 29 20:10:34 volumio2 volumio[1315]: verbose: ControllerMpd::parseTrackInfo May 29 20:10:34 volumio2 volumio[1315]: info: ControllerMpd::pushState May 29 20:10:34 volumio2 volumio[1315]: info: CoreCommandRouter::servicePushState May 29 20:10:34 volumio2 volumio[1315]: info: CorePlayQueue::getTrack 0 May 29 20:10:34 volumio2 volumio[1315]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1206,"duration":481,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"Underneath The Radar (12\")","artist":"Underworld","album":"Munich City Nights Vol. 4","uri":"INTERNAL/Munich City Nights Vol. 4/01 Underworld - Underneath The Radar (12').flac","trackType":"flac"} May 29 20:10:34 volumio2 volumio[1315]: verbose: CURRENT POSITION 0 May 29 20:10:34 volumio2 volumio[1315]: info: CoreStateMachine::syncState stateService play May 29 20:10:34 volumio2 volumio[1315]: info: CoreStateMachine::syncState currentStatus play May 29 20:10:34 volumio2 volumio[1315]: info: Received an update from plugin. extracting info from payload May 29 20:10:34 volumio2 volumio[1315]: info: CoreStateMachine::pushState May 29 20:10:34 volumio2 volumio[1315]: info: CorePlayQueue::getTrack 0 May 29 20:10:34 volumio2 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 29 20:10:34 volumio2 volumio[1315]: info: CoreCommandRouter::volumioPushState May 29 20:10:34 volumio2 volumio[1315]: info: MRS: Pushing multiroomSync output update for this device May 29 20:10:34 volumio2 volumio[1315]: info: MRS: Pushing multiroomSync output May 29 20:10:34 volumio2 volumio[1315]: info: CoreStateMachine::pushState May 29 20:10:34 volumio2 volumio[1315]: info: CorePlayQueue::getTrack 0 May 29 20:10:34 volumio2 volumio[1315]: info: CoreCommandRouter::volumioPushState May 29 20:10:34 volumio2 volumio[1315]: info: MRS: Pushing multiroomSync output update for this device May 29 20:10:34 volumio2 volumio[1315]: info: MRS: Pushing multiroomSync output May 29 20:10:34 volumio2 volumio[1315]: info: ------------------------------ 21ms May 29 20:10:34 volumio2 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 29 20:10:35 volumio2 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 29 20:10:42 volumio2 sudo[9136]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 29 20:10:42 volumio2 sudo[9136]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 20:10:42 volumio2 sudo[9136]: pam_unix(sudo:session): session closed for user root May 29 20:10:42 volumio2 sudo[9139]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 29 20:10:42 volumio2 sudo[9139]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 20:10:42 volumio2 sudo[9139]: pam_unix(sudo:session): session closed for user root May 29 20:10:43 volumio2 volumio[1315]: verbose: New Socket.io Connection to 192.168.175.184 from 192.168.175.162 UA: Mozilla/5.0 (iPad; CPU OS 18_4_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) CriOS/136.0.7103.56 Mobile/15E148 Safari/604.1 Engine version: 3 Transport: polling Total Clients: 10 May 29 20:10:43 volumio2 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 29 20:10:43 volumio2 volumio[1315]: info: CoreCommandRouter::volumioGetVisibleSources May 29 20:10:43 volumio2 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 29 20:10:43 volumio2 volumio[1315]: info: CoreCommandRouter::volumioGetState May 29 20:10:43 volumio2 volumio[1315]: info: CorePlayQueue::getTrack 0 May 29 20:10:43 volumio2 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 29 20:10:43 volumio2 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 29 20:10:43 volumio2 volumio[1315]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom May 29 20:10:43 volumio2 volumio[1315]: info: Received Get System Info May 29 20:10:43 volumio2 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 29 20:10:43 volumio2 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 29 20:10:43 volumio2 volumio[1315]: info: Discovery: Getting this device information May 29 20:10:43 volumio2 volumio[1315]: info: CoreCommandRouter::volumioGetState May 29 20:10:43 volumio2 volumio[1315]: info: CorePlayQueue::getTrack 0 May 29 20:10:43 volumio2 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 29 20:10:43 volumio2 volumio[1315]: info: CoreCommandRouter::volumioGetState May 29 20:10:43 volumio2 volumio[1315]: info: CorePlayQueue::getTrack 0 May 29 20:10:43 volumio2 volumio[1315]: info: Listing playlists May 29 20:10:43 volumio2 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 29 20:10:43 volumio2 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 29 20:10:43 volumio2 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 29 20:10:44 volumio2 volumio[1315]: info: CoreCommandRouter::volumioGetState May 29 20:10:44 volumio2 volumio[1315]: info: CorePlayQueue::getTrack 0 May 29 20:10:44 volumio2 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard May 29 20:10:44 volumio2 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 29 20:10:44 volumio2 volumio[1315]: info: Received Get System Info May 29 20:10:44 volumio2 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 29 20:10:44 volumio2 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 29 20:10:44 volumio2 volumio[1315]: info: Discovery: Getting this device information May 29 20:10:44 volumio2 volumio[1315]: info: CoreCommandRouter::volumioGetState May 29 20:10:44 volumio2 volumio[1315]: info: CorePlayQueue::getTrack 0 May 29 20:10:44 volumio2 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 29 20:10:46 volumio2 volumio[1315]: info: Executing endpoint metavolumio May 29 20:10:46 volumio2 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 29 20:10:46 volumio2 volumio[1315]: info: Executing endpoint metavolumio May 29 20:10:46 volumio2 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 29 20:10:46 volumio2 volumio[1315]: info: Executing endpoint metavolumio May 29 20:10:46 volumio2 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 29 20:10:46 volumio2 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 29 20:10:46 volumio2 volumio[1315]: info: Received Get System Info May 29 20:10:46 volumio2 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 29 20:10:46 volumio2 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 29 20:10:46 volumio2 volumio[1315]: info: Discovery: Getting this device information May 29 20:10:46 volumio2 volumio[1315]: info: CoreCommandRouter::volumioGetState May 29 20:10:46 volumio2 volumio[1315]: info: CorePlayQueue::getTrack 0 May 29 20:10:46 volumio2 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 29 20:10:46 volumio2 volumio[1315]: info: CoreCommandRouter::volumioNext May 29 20:10:46 volumio2 volumio[1315]: info: CoreStateMachine::next May 29 20:10:46 volumio2 volumio[1315]: info: CoreStateMachine::stop May 29 20:10:46 volumio2 volumio[1315]: info: CoreStateMachine::setConsumeUpdateService undefined May 29 20:10:46 volumio2 volumio[1315]: info: CoreStateMachine::stPlaybackTimer May 29 20:10:46 volumio2 volumio[1315]: info: CoreStateMachine::updateTrackBlock May 29 20:10:46 volumio2 volumio[1315]: info: CorePlayQueue::getTrackBlock May 29 20:10:46 volumio2 volumio[1315]: info: CoreStateMachine::pushState May 29 20:10:46 volumio2 volumio[1315]: info: CorePlayQueue::getTrack 0 May 29 20:10:46 volumio2 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 29 20:10:46 volumio2 volumio[1315]: info: CoreCommandRouter::volumioPushState May 29 20:10:46 volumio2 volumio[1315]: info: MRS: Pushing multiroomSync output update for this device May 29 20:10:46 volumio2 volumio[1315]: info: MRS: Pushing multiroomSync output May 29 20:10:46 volumio2 volumio[1315]: info: CoreStateMachine::serviceStop May 29 20:10:46 volumio2 volumio[1315]: info: CorePlayQueue::getTrack 0 May 29 20:10:46 volumio2 volumio[1315]: info: CoreCommandRouter::serviceStop May 29 20:10:46 volumio2 volumio[1315]: info: ControllerMpd::stop May 29 20:10:46 volumio2 volumio[1315]: verbose: ControllerMpd::sendMpdCommand stop May 29 20:10:46 volumio2 volumio[1315]: info: May 29 20:10:46 volumio2 volumio[1315]: ---------------------------- MPD announces state update: player May 29 20:10:46 volumio2 volumio[1315]: info: sendMpdCommand stop took 14 milliseconds May 29 20:10:46 volumio2 volumio[1315]: info: ControllerMpd::getState May 29 20:10:46 volumio2 volumio[1315]: verbose: ControllerMpd::sendMpdCommand status May 29 20:10:46 volumio2 volumio[1315]: info: CoreStateMachine::play index undefined May 29 20:10:46 volumio2 volumio[1315]: info: CoreStateMachine::setConsumeUpdateService undefined May 29 20:10:46 volumio2 volumio[1315]: info: CorePlayQueue::getTrack 1 May 29 20:10:46 volumio2 volumio[1315]: info: sendMpdCommand status took 1 milliseconds May 29 20:10:46 volumio2 volumio[1315]: verbose: ControllerMpd::parseState May 29 20:10:46 volumio2 volumio[1315]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 29 20:10:46 volumio2 volumio[1315]: info: sendMpdCommand playlistinfo took 3 milliseconds May 29 20:10:46 volumio2 volumio[1315]: verbose: ControllerMpd::parseTrackInfo May 29 20:10:46 volumio2 volumio[1315]: info: ControllerMpd::pushState May 29 20:10:46 volumio2 volumio[1315]: info: CoreCommandRouter::servicePushState May 29 20:10:46 volumio2 volumio[1315]: info: CoreStateMachine::pushState May 29 20:10:46 volumio2 volumio[1315]: info: CorePlayQueue::getTrack 0 May 29 20:10:46 volumio2 volumio[1315]: info: CoreCommandRouter::volumioPushState May 29 20:10:46 volumio2 volumio[1315]: info: MRS: Pushing multiroomSync output update for this device May 29 20:10:46 volumio2 volumio[1315]: info: MRS: Pushing multiroomSync output May 29 20:10:46 volumio2 volumio[1315]: info: CorePlayQueue::getTrack 0 May 29 20:10:46 volumio2 volumio[1315]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Underneath The Radar (12\")","artist":"Underworld","album":"Munich City Nights Vol. 4","uri":"INTERNAL/Munich City Nights Vol. 4/01 Underworld - Underneath The Radar (12').flac","trackType":"flac"} May 29 20:10:46 volumio2 volumio[1315]: verbose: CURRENT POSITION 0 May 29 20:10:46 volumio2 volumio[1315]: info: CoreStateMachine::syncState stateService stop May 29 20:10:46 volumio2 volumio[1315]: info: CoreStateMachine::syncState currentStatus stop May 29 20:10:46 volumio2 volumio[1315]: info: CoreStateMachine::pushState May 29 20:10:46 volumio2 volumio[1315]: info: CorePlayQueue::getTrack 0 May 29 20:10:46 volumio2 volumio[1315]: info: CoreCommandRouter::volumioPushState May 29 20:10:46 volumio2 volumio[1315]: info: MRS: Pushing multiroomSync output update for this device May 29 20:10:46 volumio2 volumio[1315]: info: MRS: Pushing multiroomSync output May 29 20:10:46 volumio2 volumio[1315]: info: No code May 29 20:10:46 volumio2 volumio[1315]: info: CoreStateMachine::pushState May 29 20:10:46 volumio2 volumio[1315]: info: CorePlayQueue::getTrack 0 May 29 20:10:46 volumio2 volumio[1315]: info: CoreCommandRouter::volumioPushState May 29 20:10:46 volumio2 volumio[1315]: info: MRS: Pushing multiroomSync output update for this device May 29 20:10:46 volumio2 volumio[1315]: info: MRS: Pushing multiroomSync output May 29 20:10:46 volumio2 volumio[1315]: info: ------------------------------ 24ms May 29 20:10:46 volumio2 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 29 20:10:47 volumio2 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 29 20:10:49 volumio2 volumio[1315]: info: CoreCommandRouter::volumioPlay May 29 20:10:49 volumio2 volumio[1315]: info: CoreStateMachine::play index undefined May 29 20:10:49 volumio2 volumio[1315]: info: CoreStateMachine::setConsumeUpdateService undefined May 29 20:10:49 volumio2 volumio[1315]: info: CorePlayQueue::getTrack 0 May 29 20:10:49 volumio2 volumio[1315]: info: CoreStateMachine::startPlaybackTimer May 29 20:10:49 volumio2 volumio[1315]: info: CorePlayQueue::getTrack 0 May 29 20:10:49 volumio2 volumio[1315]: info: CoreCommandRouter::volumioGetVisibleSources May 29 20:10:49 volumio2 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 29 20:10:49 volumio2 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject May 29 20:10:49 volumio2 volumio[1315]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/Munich City Nights Vol. 4/01 Underworld - Underneath The Radar (12').flac May 29 20:10:49 volumio2 volumio[1315]: verbose: ControllerMpd::sendMpdCommand stop May 29 20:10:49 volumio2 volumio[1315]: info: sendMpdCommand stop took 0 milliseconds May 29 20:10:49 volumio2 volumio[1315]: verbose: ControllerMpd::sendMpdCommand clear May 29 20:10:49 volumio2 volumio[1315]: info: May 29 20:10:49 volumio2 volumio[1315]: ---------------------------- MPD announces system playlist update May 29 20:10:49 volumio2 volumio[1315]: info: Ignoring MPD Status Update May 29 20:10:49 volumio2 volumio[1315]: info: sendMpdCommand clear took 1 milliseconds May 29 20:10:49 volumio2 volumio[1315]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/Munich City Nights Vol. 4/01 Underworld - Underneath The Radar (12').flac" May 29 20:10:49 volumio2 volumio[1315]: error: updateQueue error: null May 29 20:10:49 volumio2 volumio[1315]: info: May 29 20:10:49 volumio2 volumio[1315]: ---------------------------- MPD announces system playlist update May 29 20:10:49 volumio2 volumio[1315]: info: Ignoring MPD Status Update May 29 20:10:49 volumio2 volumio[1315]: info: ------------------------------ 3ms May 29 20:10:49 volumio2 volumio[1315]: info: sendMpdCommand add "INTERNAL/Munich City Nights Vol. 4/01 Underworld - Underneath The Radar (12').flac" took 2 milliseconds May 29 20:10:49 volumio2 volumio[1315]: verbose: ControllerMpd::sendMpdCommand play May 29 20:10:49 volumio2 volumio[1315]: info: ------------------------------ 2ms May 29 20:10:49 volumio2 volumio[1315]: info: May 29 20:10:49 volumio2 volumio[1315]: ---------------------------- MPD announces state update: player May 29 20:10:49 volumio2 volumio[1315]: info: sendMpdCommand play took 2 milliseconds May 29 20:10:49 volumio2 volumio[1315]: info: ControllerMpd::getState May 29 20:10:49 volumio2 volumio[1315]: verbose: ControllerMpd::sendMpdCommand status May 29 20:10:49 volumio2 volumio[1315]: info: May 29 20:10:49 volumio2 volumio[1315]: ---------------------------- MPD announces state update: player May 29 20:10:49 volumio2 volumio[1315]: info: sendMpdCommand status took 3 milliseconds May 29 20:10:49 volumio2 volumio[1315]: info: ControllerMpd::getState May 29 20:10:49 volumio2 volumio[1315]: verbose: ControllerMpd::sendMpdCommand status May 29 20:10:49 volumio2 volumio[1315]: verbose: ControllerMpd::parseState May 29 20:10:49 volumio2 volumio[1315]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 29 20:10:49 volumio2 volumio[1315]: info: sendMpdCommand status took 1 milliseconds May 29 20:10:49 volumio2 volumio[1315]: info: sendMpdCommand playlistinfo took 1 milliseconds May 29 20:10:49 volumio2 volumio[1315]: verbose: ControllerMpd::parseState May 29 20:10:49 volumio2 volumio[1315]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 29 20:10:49 volumio2 volumio[1315]: verbose: ControllerMpd::parseTrackInfo May 29 20:10:49 volumio2 volumio[1315]: info: ControllerMpd::pushState May 29 20:10:49 volumio2 volumio[1315]: info: CoreCommandRouter::servicePushState May 29 20:10:49 volumio2 volumio[1315]: info: CorePlayQueue::getTrack 0 May 29 20:10:49 volumio2 volumio[1315]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":481,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Underneath The Radar (12\")","artist":"Underworld","album":"Munich City Nights Vol. 4","uri":"INTERNAL/Munich City Nights Vol. 4/01 Underworld - Underneath The Radar (12').flac","trackType":"flac"} May 29 20:10:49 volumio2 volumio[1315]: verbose: CURRENT POSITION 0 May 29 20:10:49 volumio2 volumio[1315]: info: CoreStateMachine::syncState stateService play May 29 20:10:49 volumio2 volumio[1315]: info: CoreStateMachine::syncState currentStatus stop May 29 20:10:49 volumio2 volumio[1315]: info: ------------------------------ 7ms May 29 20:10:49 volumio2 volumio[1315]: info: sendMpdCommand playlistinfo took 2 milliseconds May 29 20:10:49 volumio2 volumio[1315]: verbose: ControllerMpd::parseTrackInfo May 29 20:10:49 volumio2 volumio[1315]: info: ControllerMpd::pushState May 29 20:10:49 volumio2 volumio[1315]: info: CoreCommandRouter::servicePushState May 29 20:10:49 volumio2 volumio[1315]: info: CorePlayQueue::getTrack 0 May 29 20:10:49 volumio2 volumio[1315]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1206,"duration":481,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"Underneath The Radar (12\")","artist":"Underworld","album":"Munich City Nights Vol. 4","uri":"INTERNAL/Munich City Nights Vol. 4/01 Underworld - Underneath The Radar (12').flac","trackType":"flac"} May 29 20:10:49 volumio2 volumio[1315]: verbose: CURRENT POSITION 0 May 29 20:10:49 volumio2 volumio[1315]: info: CoreStateMachine::syncState stateService play May 29 20:10:49 volumio2 volumio[1315]: info: CoreStateMachine::syncState currentStatus play May 29 20:10:49 volumio2 volumio[1315]: info: Received an update from plugin. extracting info from payload May 29 20:10:49 volumio2 volumio[1315]: info: CoreStateMachine::pushState May 29 20:10:49 volumio2 volumio[1315]: info: CorePlayQueue::getTrack 0 May 29 20:10:49 volumio2 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 29 20:10:49 volumio2 volumio[1315]: info: CoreCommandRouter::volumioPushState May 29 20:10:49 volumio2 volumio[1315]: info: MRS: Pushing multiroomSync output update for this device May 29 20:10:49 volumio2 volumio[1315]: info: MRS: Pushing multiroomSync output May 29 20:10:49 volumio2 volumio[1315]: info: CoreStateMachine::pushState May 29 20:10:49 volumio2 volumio[1315]: info: CorePlayQueue::getTrack 0 May 29 20:10:49 volumio2 volumio[1315]: info: CoreCommandRouter::volumioPushState May 29 20:10:49 volumio2 volumio[1315]: info: MRS: Pushing multiroomSync output update for this device May 29 20:10:49 volumio2 volumio[1315]: info: MRS: Pushing multiroomSync output May 29 20:10:49 volumio2 volumio[1315]: info: ------------------------------ 19ms May 29 20:10:51 volumio2 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 29 20:10:51 volumio2 volumio[1315]: info: CoreCommandRouter::volumioGetQueue May 29 20:10:51 volumio2 volumio[1315]: info: CoreStateMachine::getQueue May 29 20:10:51 volumio2 volumio[1315]: info: CorePlayQueue::getQueue May 29 20:10:53 volumio2 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 29 20:10:54 volumio2 volumio[1315]: info: CoreCommandRouter::volumioGetState May 29 20:10:54 volumio2 volumio[1315]: info: CorePlayQueue::getTrack 0 May 29 20:10:56 volumio2 volumio[1315]: info: Executing endpoint metavolumio May 29 20:10:56 volumio2 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 29 20:10:56 volumio2 volumio[1315]: info: Executing endpoint metavolumio May 29 20:10:56 volumio2 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 29 20:10:56 volumio2 volumio[1315]: info: Executing endpoint metavolumio May 29 20:10:56 volumio2 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 29 20:10:58 volumio2 volumio[1315]: info: CoreCommandRouter::getUIConfigOnPlugin May 29 20:10:58 volumio2 volumio[1315]: No protocol specified May 29 20:10:58 volumio2 volumio[1315]: xcb_connection_has_error() returned true May 29 20:10:58 volumio2 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions May 29 20:10:58 volumio2 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 20:10:58 volumio2 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 29 20:10:58 volumio2 volumio[1315]: No protocol specified May 29 20:10:58 volumio2 volumio[1315]: xcb_connection_has_error() returned true May 29 20:10:58 volumio2 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 29 20:10:58 volumio2 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 29 20:10:58 volumio2 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 29 20:10:58 volumio2 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 29 20:10:58 volumio2 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 29 20:10:58 volumio2 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 29 20:10:58 volumio2 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 29 20:10:58 volumio2 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode May 29 20:10:58 volumio2 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 29 20:11:01 volumio2 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 29 20:11:01 volumio2 volumio[1315]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined May 29 20:11:01 volumio2 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates May 29 20:11:01 volumio2 volumio[1315]: info: Received Get System Version May 29 20:11:01 volumio2 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 29 20:11:01 volumio2 volumio[1315]: info: Received Get System Info May 29 20:11:01 volumio2 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 29 20:11:01 volumio2 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 29 20:11:01 volumio2 volumio[1315]: info: Discovery: Getting this device information May 29 20:11:01 volumio2 volumio[1315]: info: CoreCommandRouter::volumioGetState May 29 20:11:01 volumio2 volumio[1315]: info: CorePlayQueue::getTrack 0 May 29 20:11:01 volumio2 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 29 20:11:16 volumio2 volumio[1315]: info: CoreCommandRouter::volumioGetState May 29 20:11:16 volumio2 volumio[1315]: info: CorePlayQueue::getTrack 0 May 29 20:11:18 volumio2 volumio[1315]: info: Executing endpoint metavolumio May 29 20:11:18 volumio2 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 29 20:11:18 volumio2 volumio[1315]: info: Executing endpoint metavolumio May 29 20:11:18 volumio2 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 29 20:11:18 volumio2 volumio[1315]: info: Executing endpoint metavolumio May 29 20:11:18 volumio2 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 29 20:11:20 volumio2 volumio[1315]: error: Failed request for metavolumio API May 29 20:11:20 volumio2 volumio[1315]: info: CoreCommandRouter::getUIConfigOnPlugin May 29 20:11:20 volumio2 volumio[1315]: info: CoreCommandRouter::getUIConfigOnPlugin May 29 20:11:20 volumio2 volumio[1315]: info: Retrieving Cloud Streaming UI May 29 20:11:20 volumio2 volumio[1315]: info: Getting Tidal Cloud Configuration May 29 20:11:20 volumio2 volumio[1315]: info: CoreCommandRouter::getUIConfigOnPlugin May 29 20:11:20 volumio2 volumio[1315]: info: Getting Qobuz Cloud Configuration May 29 20:11:20 volumio2 volumio[1315]: info: Asking plugin for UI Config May 29 20:11:20 volumio2 volumio[1315]: info: CoreCommandRouter::getUIConfigOnPlugin May 29 20:11:20 volumio2 volumio[1315]: info: Getting Spotify Cloud Configuration May 29 20:11:20 volumio2 volumio[1315]: info: Asking plugin for UI Config May 29 20:11:20 volumio2 volumio[1315]: info: CoreCommandRouter::getUIConfigOnPlugin May 29 20:11:20 volumio2 volumio[1315]: info: Saving Spotify Acccount May 29 20:11:20 volumio2 volumio[1315]: info: Got it May 29 20:11:20 volumio2 volumio[1315]: error: Could not retrieve Spotify Config from plugin Spotify: no section found May 29 20:11:20 volumio2 volumio[1315]: info: Got Tidal Cloud Configuration May 29 20:11:20 volumio2 volumio[1315]: info: Got it May 29 20:11:20 volumio2 volumio[1315]: info: CoreCommandRouter::getUIConfigOnPlugin May 29 20:11:20 volumio2 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 29 20:11:20 volumio2 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 29 20:11:20 volumio2 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 29 20:11:20 volumio2 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 29 20:11:20 volumio2 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 29 20:11:20 volumio2 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 29 20:11:20 volumio2 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 29 20:11:20 volumio2 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 29 20:11:20 volumio2 volumio[1315]: info: CoreCommandRouter::volumioGetBrowseSources May 29 20:11:20 volumio2 volumio[1315]: info: CoreCommandRouter::volumioGetBrowseSources May 29 20:11:20 volumio2 volumio[1315]: info: CoreCommandRouter::volumioGetBrowseSources May 29 20:11:21 volumio2 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 29 20:11:21 volumio2 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares May 29 20:11:24 volumio2 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb May 29 20:11:24 volumio2 volumio[1315]: verbose: ControllerMpd::sendMpdCommand update May 29 20:11:24 volumio2 volumio[1315]: verbose: ControllerMpd::sendMpdCommand status May 29 20:11:24 volumio2 volumio[1315]: info: May 29 20:11:24 volumio2 volumio[1315]: ---------------------------- MPD announces state update: update May 29 20:11:24 volumio2 volumio[1315]: info: sendMpdCommand update took 4 milliseconds May 29 20:11:24 volumio2 volumio[1315]: info: ControllerMpd::getState May 29 20:11:24 volumio2 volumio[1315]: verbose: ControllerMpd::sendMpdCommand status May 29 20:11:24 volumio2 volumio[1315]: info: sendMpdCommand status took 2 milliseconds May 29 20:11:24 volumio2 volumio[1315]: info: sendMpdCommand status took 2 milliseconds May 29 20:11:24 volumio2 volumio[1315]: verbose: ControllerMpd::parseState May 29 20:11:24 volumio2 volumio[1315]: info: Command Router : Notfying DB Updatetrue May 29 20:11:24 volumio2 volumio[1315]: info: CoreCommandRouter::Close All Modals sent May 29 20:11:24 volumio2 volumio[1315]: verbose: ControllerMpd::parseState May 29 20:11:24 volumio2 volumio[1315]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 29 20:11:24 volumio2 volumio[1315]: info: sendMpdCommand playlistinfo took 2 milliseconds May 29 20:11:24 volumio2 volumio[1315]: verbose: ControllerMpd::parseTrackInfo May 29 20:11:24 volumio2 volumio[1315]: info: ControllerMpd::pushState May 29 20:11:24 volumio2 volumio[1315]: info: CoreCommandRouter::servicePushState May 29 20:11:24 volumio2 volumio[1315]: info: CorePlayQueue::getTrack 0 May 29 20:11:24 volumio2 volumio[1315]: verbose: STATE SERVICE {"status":"play","position":0,"seek":36335,"duration":481,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"1412 Kbps","isStreaming":false,"title":"Underneath The Radar (12\")","artist":"Underworld","album":"Munich City Nights Vol. 4","uri":"INTERNAL/Munich City Nights Vol. 4/01 Underworld - Underneath The Radar (12').flac","trackType":"flac"} May 29 20:11:24 volumio2 volumio[1315]: verbose: CURRENT POSITION 0 May 29 20:11:24 volumio2 volumio[1315]: info: CoreStateMachine::syncState stateService play May 29 20:11:24 volumio2 volumio[1315]: info: CoreStateMachine::syncState currentStatus play May 29 20:11:24 volumio2 volumio[1315]: info: Received an update from plugin. extracting info from payload May 29 20:11:24 volumio2 volumio[1315]: info: CoreStateMachine::pushState May 29 20:11:24 volumio2 volumio[1315]: info: CorePlayQueue::getTrack 0 May 29 20:11:24 volumio2 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 29 20:11:24 volumio2 volumio[1315]: info: CoreCommandRouter::volumioPushState May 29 20:11:24 volumio2 volumio[1315]: info: MRS: Pushing multiroomSync output update for this device May 29 20:11:24 volumio2 volumio[1315]: info: MRS: Pushing multiroomSync output May 29 20:11:24 volumio2 volumio[1315]: info: CoreStateMachine::pushState May 29 20:11:24 volumio2 volumio[1315]: info: CorePlayQueue::getTrack 0 May 29 20:11:24 volumio2 volumio[1315]: info: CoreCommandRouter::volumioPushState May 29 20:11:24 volumio2 volumio[1315]: info: MRS: Pushing multiroomSync output update for this device May 29 20:11:24 volumio2 volumio[1315]: info: MRS: Pushing multiroomSync output May 29 20:11:24 volumio2 volumio[1315]: info: ------------------------------ 46ms May 29 20:11:24 volumio2 volumio[1315]: verbose: ControllerMpd::sendMpdCommand status May 29 20:11:24 volumio2 volumio[1315]: info: May 29 20:11:24 volumio2 volumio[1315]: ---------------------------- MPD announces state update: update May 29 20:11:24 volumio2 volumio[1315]: info: ControllerMpd::getState May 29 20:11:24 volumio2 volumio[1315]: verbose: ControllerMpd::sendMpdCommand status May 29 20:11:24 volumio2 volumio[1315]: info: sendMpdCommand status took 2 milliseconds May 29 20:11:24 volumio2 volumio[1315]: info: sendMpdCommand status took 1 milliseconds May 29 20:11:24 volumio2 volumio[1315]: verbose: ControllerMpd::parseState May 29 20:11:24 volumio2 volumio[1315]: info: Command Router : Notfying DB Updatefalse May 29 20:11:24 volumio2 volumio[1315]: info: CoreCommandRouter::Close All Modals sent May 29 20:11:24 volumio2 volumio[1315]: verbose: ControllerMpd::parseState May 29 20:11:24 volumio2 volumio[1315]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 29 20:11:24 volumio2 volumio[1315]: info: sendMpdCommand playlistinfo took 2 milliseconds May 29 20:11:24 volumio2 volumio[1315]: verbose: ControllerMpd::parseTrackInfo May 29 20:11:24 volumio2 volumio[1315]: info: ControllerMpd::pushState May 29 20:11:24 volumio2 volumio[1315]: info: CoreCommandRouter::servicePushState May 29 20:11:24 volumio2 volumio[1315]: info: CorePlayQueue::getTrack 0 May 29 20:11:24 volumio2 volumio[1315]: verbose: STATE SERVICE {"status":"play","position":0,"seek":36335,"duration":481,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1412 Kbps","isStreaming":false,"title":"Underneath The Radar (12\")","artist":"Underworld","album":"Munich City Nights Vol. 4","uri":"INTERNAL/Munich City Nights Vol. 4/01 Underworld - Underneath The Radar (12').flac","trackType":"flac"} May 29 20:11:24 volumio2 volumio[1315]: verbose: CURRENT POSITION 0 May 29 20:11:24 volumio2 volumio[1315]: info: CoreStateMachine::syncState stateService play May 29 20:11:24 volumio2 volumio[1315]: info: CoreStateMachine::syncState currentStatus play May 29 20:11:24 volumio2 volumio[1315]: info: Received an update from plugin. extracting info from payload May 29 20:11:24 volumio2 volumio[1315]: info: CoreStateMachine::pushState May 29 20:11:24 volumio2 volumio[1315]: info: CorePlayQueue::getTrack 0 May 29 20:11:24 volumio2 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 29 20:11:24 volumio2 volumio[1315]: info: CoreCommandRouter::volumioPushState May 29 20:11:24 volumio2 volumio[1315]: info: MRS: Pushing multiroomSync output update for this device May 29 20:11:24 volumio2 volumio[1315]: info: MRS: Pushing multiroomSync output May 29 20:11:24 volumio2 volumio[1315]: info: CoreStateMachine::pushState May 29 20:11:24 volumio2 volumio[1315]: info: CorePlayQueue::getTrack 0 May 29 20:11:24 volumio2 volumio[1315]: info: CoreCommandRouter::volumioPushState May 29 20:11:24 volumio2 volumio[1315]: info: MRS: Pushing multiroomSync output update for this device May 29 20:11:24 volumio2 volumio[1315]: info: MRS: Pushing multiroomSync output May 29 20:11:24 volumio2 volumio[1315]: info: ------------------------------ 43ms May 29 20:11:25 volumio2 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 29 20:11:28 volumio2 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb May 29 20:11:28 volumio2 volumio[1315]: verbose: ControllerMpd::sendMpdCommand rescan May 29 20:11:28 volumio2 volumio[1315]: verbose: ControllerMpd::sendMpdCommand status May 29 20:11:28 volumio2 volumio[1315]: info: May 29 20:11:28 volumio2 volumio[1315]: ---------------------------- MPD announces state update: update May 29 20:11:28 volumio2 volumio[1315]: info: sendMpdCommand rescan took 3 milliseconds May 29 20:11:28 volumio2 volumio[1315]: info: ControllerMpd::getState May 29 20:11:28 volumio2 volumio[1315]: verbose: ControllerMpd::sendMpdCommand status May 29 20:11:28 volumio2 volumio[1315]: info: sendMpdCommand status took 3 milliseconds May 29 20:11:28 volumio2 volumio[1315]: verbose: ControllerMpd::parseState May 29 20:11:28 volumio2 volumio[1315]: info: Command Router : Notfying DB Updatetrue May 29 20:11:28 volumio2 volumio[1315]: info: CoreCommandRouter::Close All Modals sent May 29 20:11:28 volumio2 volumio[1315]: info: sendMpdCommand status took 33 milliseconds May 29 20:11:28 volumio2 volumio[1315]: verbose: ControllerMpd::parseState May 29 20:11:28 volumio2 volumio[1315]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 29 20:11:28 volumio2 volumio[1315]: info: sendMpdCommand playlistinfo took 3 milliseconds May 29 20:11:28 volumio2 volumio[1315]: verbose: ControllerMpd::parseTrackInfo May 29 20:11:28 volumio2 volumio[1315]: info: ControllerMpd::pushState May 29 20:11:28 volumio2 volumio[1315]: info: CoreCommandRouter::servicePushState May 29 20:11:28 volumio2 volumio[1315]: info: CorePlayQueue::getTrack 0 May 29 20:11:28 volumio2 volumio[1315]: verbose: STATE SERVICE {"status":"play","position":0,"seek":40091,"duration":481,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"1412 Kbps","isStreaming":false,"title":"Underneath The Radar (12\")","artist":"Underworld","album":"Munich City Nights Vol. 4","uri":"INTERNAL/Munich City Nights Vol. 4/01 Underworld - Underneath The Radar (12').flac","trackType":"flac"} May 29 20:11:28 volumio2 volumio[1315]: verbose: CURRENT POSITION 0 May 29 20:11:28 volumio2 volumio[1315]: info: CoreStateMachine::syncState stateService play May 29 20:11:28 volumio2 volumio[1315]: info: CoreStateMachine::syncState currentStatus play May 29 20:11:28 volumio2 volumio[1315]: info: Received an update from plugin. extracting info from payload May 29 20:11:28 volumio2 volumio[1315]: info: CoreStateMachine::pushState May 29 20:11:28 volumio2 volumio[1315]: info: CorePlayQueue::getTrack 0 May 29 20:11:28 volumio2 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 29 20:11:28 volumio2 volumio[1315]: info: CoreCommandRouter::volumioPushState May 29 20:11:28 volumio2 volumio[1315]: info: MRS: Pushing multiroomSync output update for this device May 29 20:11:28 volumio2 volumio[1315]: info: MRS: Pushing multiroomSync output May 29 20:11:28 volumio2 volumio[1315]: info: CoreStateMachine::pushState May 29 20:11:28 volumio2 volumio[1315]: info: CorePlayQueue::getTrack 0 May 29 20:11:28 volumio2 volumio[1315]: info: CoreCommandRouter::volumioPushState May 29 20:11:28 volumio2 volumio[1315]: info: MRS: Pushing multiroomSync output update for this device May 29 20:11:28 volumio2 volumio[1315]: info: MRS: Pushing multiroomSync output May 29 20:11:28 volumio2 volumio[1315]: info: ------------------------------ 60ms May 29 20:11:29 volumio2 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 29 20:11:33 volumio2 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 29 20:11:36 volumio2 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb May 29 20:11:36 volumio2 volumio[1315]: verbose: ControllerMpd::sendMpdCommand rescan May 29 20:11:36 volumio2 volumio[1315]: info: sendMpdCommand rescan took 3 milliseconds May 29 20:11:37 volumio2 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 29 20:11:41 volumio2 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 29 20:11:41 volumio2 volumio[1315]: info: Disabling MyMusic plugin upnp May 29 20:11:41 volumio2 sudo[9312]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service May 29 20:11:41 volumio2 sudo[9312]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 20:11:41 volumio2 systemd[1]: Stopping UPnP Renderer front-end to MPD... May 29 20:11:41 volumio2 volumio[1315]: ------------------------------------ BT MESSAGE: BT STATUS: running May 29 20:11:41 volumio2 volumio[1315]: error: Upnp client error: Error: This socket has been ended by the other party May 29 20:11:41 volumio2 volumio[1315]: info: MPD Database updated - AlbumList cache refreshed May 29 20:11:41 volumio2 volumio[1315]: info: May 29 20:11:41 volumio2 volumio[1315]: ---------------------------- MPD announces state update: database May 29 20:11:41 volumio2 volumio[1315]: verbose: ControllerMpd::sendMpdCommand status May 29 20:11:41 volumio2 volumio[1315]: info: May 29 20:11:41 volumio2 volumio[1315]: ---------------------------- MPD announces state update: update May 29 20:11:41 volumio2 volumio[1315]: info: ControllerMpd::getState May 29 20:11:41 volumio2 volumio[1315]: verbose: ControllerMpd::sendMpdCommand status May 29 20:11:41 volumio2 volumio[1315]: info: ControllerMpd::getState May 29 20:11:41 volumio2 volumio[1315]: verbose: ControllerMpd::sendMpdCommand status May 29 20:11:42 volumio2 volumio[1315]: info: sendMpdCommand status took 101 milliseconds May 29 20:11:42 volumio2 volumio[1315]: info: sendMpdCommand status took 101 milliseconds May 29 20:11:42 volumio2 volumio[1315]: info: sendMpdCommand status took 96 milliseconds May 29 20:11:42 volumio2 volumio[1315]: verbose: ControllerMpd::parseState May 29 20:11:42 volumio2 volumio[1315]: info: Command Router : Notfying DB Updatetrue May 29 20:11:42 volumio2 volumio[1315]: info: CoreCommandRouter::Close All Modals sent May 29 20:11:42 volumio2 volumio[1315]: verbose: ControllerMpd::parseState May 29 20:11:42 volumio2 volumio[1315]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 29 20:11:42 volumio2 volumio[1315]: verbose: ControllerMpd::parseState May 29 20:11:42 volumio2 volumio[1315]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 29 20:11:42 volumio2 volumio[1315]: info: sendMpdCommand playlistinfo took 4 milliseconds May 29 20:11:42 volumio2 volumio[1315]: info: sendMpdCommand playlistinfo took 3 milliseconds May 29 20:11:42 volumio2 volumio[1315]: verbose: ControllerMpd::parseTrackInfo May 29 20:11:42 volumio2 volumio[1315]: verbose: ControllerMpd::parseTrackInfo May 29 20:11:42 volumio2 volumio[1315]: info: ControllerMpd::pushState May 29 20:11:42 volumio2 volumio[1315]: info: CoreCommandRouter::servicePushState May 29 20:11:42 volumio2 volumio[1315]: info: CorePlayQueue::getTrack 0 May 29 20:11:42 volumio2 volumio[1315]: verbose: STATE SERVICE {"status":"play","position":0,"seek":53956,"duration":481,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"1412 Kbps","isStreaming":false,"title":"Underneath The Radar (12\")","artist":"Underworld","album":"Munich City Nights Vol. 4","uri":"INTERNAL/Munich City Nights Vol. 4/01 Underworld - Underneath The Radar (12').flac","trackType":"flac"} May 29 20:11:42 volumio2 volumio[1315]: verbose: CURRENT POSITION 0 May 29 20:11:42 volumio2 volumio[1315]: info: CoreStateMachine::syncState stateService play May 29 20:11:42 volumio2 volumio[1315]: info: CoreStateMachine::syncState currentStatus play May 29 20:11:42 volumio2 volumio[1315]: info: Received an update from plugin. extracting info from payload May 29 20:11:42 volumio2 volumio[1315]: info: CoreStateMachine::pushState May 29 20:11:42 volumio2 volumio[1315]: info: CorePlayQueue::getTrack 0 May 29 20:11:42 volumio2 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 29 20:11:42 volumio2 volumio[1315]: info: CoreCommandRouter::volumioPushState May 29 20:11:42 volumio2 volumio[1315]: info: MRS: Pushing multiroomSync output update for this device May 29 20:11:42 volumio2 volumio[1315]: info: MRS: Pushing multiroomSync output May 29 20:11:42 volumio2 volumio[1315]: info: CoreStateMachine::pushState May 29 20:11:42 volumio2 volumio[1315]: info: CorePlayQueue::getTrack 0 May 29 20:11:42 volumio2 volumio[1315]: info: CoreCommandRouter::volumioPushState May 29 20:11:42 volumio2 volumio[1315]: info: MRS: Pushing multiroomSync output update for this device May 29 20:11:42 volumio2 volumio[1315]: info: MRS: Pushing multiroomSync output May 29 20:11:42 volumio2 volumio[1315]: info: ControllerMpd::pushState May 29 20:11:42 volumio2 volumio[1315]: info: CoreCommandRouter::servicePushState May 29 20:11:42 volumio2 volumio[1315]: info: CorePlayQueue::getTrack 0 May 29 20:11:42 volumio2 volumio[1315]: verbose: STATE SERVICE {"status":"play","position":0,"seek":53956,"duration":481,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"1412 Kbps","isStreaming":false,"title":"Underneath The Radar (12\")","artist":"Underworld","album":"Munich City Nights Vol. 4","uri":"INTERNAL/Munich City Nights Vol. 4/01 Underworld - Underneath The Radar (12').flac","trackType":"flac"} May 29 20:11:42 volumio2 volumio[1315]: verbose: CURRENT POSITION 0 May 29 20:11:42 volumio2 volumio[1315]: info: CoreStateMachine::syncState stateService play May 29 20:11:42 volumio2 volumio[1315]: info: CoreStateMachine::syncState currentStatus play May 29 20:11:42 volumio2 volumio[1315]: info: Received an update from plugin. extracting info from payload May 29 20:11:42 volumio2 volumio[1315]: info: CoreStateMachine::pushState May 29 20:11:42 volumio2 volumio[1315]: info: CorePlayQueue::getTrack 0 May 29 20:11:42 volumio2 volumio[1315]: info: CoreCommandRouter::volumioPushState May 29 20:11:42 volumio2 volumio[1315]: info: MRS: Pushing multiroomSync output update for this device May 29 20:11:42 volumio2 volumio[1315]: info: MRS: Pushing multiroomSync output May 29 20:11:42 volumio2 volumio[1315]: info: CoreStateMachine::pushState May 29 20:11:42 volumio2 volumio[1315]: info: CorePlayQueue::getTrack 0 May 29 20:11:42 volumio2 volumio[1315]: info: CoreCommandRouter::volumioPushState May 29 20:11:42 volumio2 volumio[1315]: info: MRS: Pushing multiroomSync output update for this device May 29 20:11:42 volumio2 volumio[1315]: info: MRS: Pushing multiroomSync output May 29 20:11:42 volumio2 volumio[1315]: info: ------------------------------ 159ms May 29 20:11:45 volumio2 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 29 20:11:45 volumio2 volumio[1315]: info: Enabling MyMusic plugin upnp May 29 20:11:45 volumio2 volumio[1315]: info: Enabling plugin upnp May 29 20:11:45 volumio2 volumio[1315]: info: Loading plugin "upnp"... May 29 20:11:45 volumio2 volumio[1315]: info: [1748542305507] Starting Upmpd Daemon May 29 20:11:45 volumio2 volumio[1315]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 29 20:11:45 volumio2 volumio[1315]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 29 20:11:45 volumio2 volumio[1315]: Error: listen EADDRINUSE: address already in use :::6599 May 29 20:11:45 volumio2 volumio[1315]: at Server.setupListenHandle [as _listen2] (net.js:1331:16) May 29 20:11:45 volumio2 volumio[1315]: at listenInCluster (net.js:1379:12) May 29 20:11:45 volumio2 volumio[1315]: at Server.listen (net.js:1465:7) May 29 20:11:45 volumio2 volumio[1315]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17) May 29 20:11:45 volumio2 volumio[1315]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38) May 29 20:11:45 volumio2 volumio[1315]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) May 29 20:11:45 volumio2 volumio[1315]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) May 29 20:11:45 volumio2 volumio[1315]: at processTicksAndRejections (internal/process/task_queues.js:77:11) { May 29 20:11:45 volumio2 volumio[1315]: code: 'EADDRINUSE', May 29 20:11:45 volumio2 volumio[1315]: errno: -98, May 29 20:11:45 volumio2 volumio[1315]: syscall: 'listen', May 29 20:11:45 volumio2 volumio[1315]: address: '::', May 29 20:11:45 volumio2 volumio[1315]: port: 6599 May 29 20:11:45 volumio2 volumio[1315]: } May 29 20:11:45 volumio2 volumio[1315]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 29 20:11:45 volumio2 sudo[9326]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-29 20:10 May 29 20:11:45 volumio2 sudo[9326]: 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="570c5791513f5bac7da274aba6690c1a961705de" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 13 May 2025 05:50:12 PM CEST" VOLUMIO_VERSION="3.812" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="39b0f8c200b2dcadf117e189b4b3632d"