-- Logs begin at Sun 2025-01-19 18:41:43 CET, end at Sun 2025-01-19 18:59:22 CET. -- Jan 19 18:58:01 volumio volumio[1257]: info: CoreCommandRouter::volumioPlay Jan 19 18:58:01 volumio volumio[1257]: info: CoreStateMachine::play index undefined Jan 19 18:58:01 volumio volumio[1257]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 19 18:58:01 volumio volumio[1257]: info: CorePlayQueue::getTrack 0 Jan 19 18:58:01 volumio volumio[1257]: info: CoreStateMachine::startPlaybackTimer Jan 19 18:58:01 volumio volumio[1257]: info: CorePlayQueue::getTrack 0 Jan 19 18:58:01 volumio volumio[1257]: info: ControllerMpd::resume Jan 19 18:58:01 volumio volumio[1257]: verbose: ControllerMpd::sendMpdCommand play Jan 19 18:58:01 volumio volumio[1257]: info: sendMpdCommand play took 0 milliseconds Jan 19 18:58:03 volumio volumio[1257]: info: CoreCommandRouter::volumioPlay Jan 19 18:58:03 volumio volumio[1257]: info: CoreStateMachine::play index undefined Jan 19 18:58:03 volumio volumio[1257]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 19 18:58:03 volumio volumio[1257]: info: CorePlayQueue::getTrack 0 Jan 19 18:58:03 volumio volumio[1257]: info: CoreStateMachine::startPlaybackTimer Jan 19 18:58:03 volumio volumio[1257]: info: CorePlayQueue::getTrack 0 Jan 19 18:58:03 volumio volumio[1257]: info: ControllerMpd::resume Jan 19 18:58:03 volumio volumio[1257]: verbose: ControllerMpd::sendMpdCommand play Jan 19 18:58:03 volumio volumio[1257]: info: sendMpdCommand play took 0 milliseconds Jan 19 18:58:12 volumio volumio[1257]: info: CALLMETHOD: audio_interface alsa_controller saveVolumeOptions [object Object] Jan 19 18:58:12 volumio volumio[1257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveVolumeOptions Jan 19 18:58:12 volumio volumio[1257]: info: CoreCommandRouter::volumioGetState Jan 19 18:58:12 volumio volumio[1257]: info: CorePlayQueue::getTrack 0 Jan 19 18:58:12 volumio volumio[1257]: info: Restoring Previous Volume level: 100 false false Jan 19 18:58:12 volumio volumio[1257]: info: Output device has changed, restarting MPD Jan 19 18:58:12 volumio sudo[4854]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 19 18:58:12 volumio sudo[4854]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 19 18:58:12 volumio volumio[1257]: info: Output device has changed, restarting Shairport Sync Jan 19 18:58:12 volumio volumio[1257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 19 18:58:12 volumio volumio[1257]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 19 18:58:12 volumio sudo[4854]: pam_unix(sudo:session): session closed for user root Jan 19 18:58:12 volumio sudo[4857]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 19 18:58:12 volumio sudo[4857]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 19 18:58:12 volumio systemd[1]: Stopping Music Player Daemon... Jan 19 18:58:12 volumio volumio[1257]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 19 18:58:12 volumio volumio[1257]: info: Volume configurations have been set Jan 19 18:58:12 volumio systemd[1]: mpd.service: Succeeded. Jan 19 18:58:12 volumio systemd[1]: Stopped Music Player Daemon. Jan 19 18:58:12 volumio volumio[1257]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jan 19 18:58:12 volumio volumio[1257]: info: Updating Volume Controller Parameters: Device: 1 Name: HDMI 1 Out Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Jan 19 18:58:12 volumio volumio[1257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Jan 19 18:58:12 volumio volumio[1257]: info: Disabling external Volume Control Jan 19 18:58:12 volumio volumio[1257]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 19 18:58:12 volumio volumio[1257]: info: CoreStateMachine::pushState Jan 19 18:58:12 volumio volumio[1257]: info: CorePlayQueue::getTrack 0 Jan 19 18:58:12 volumio volumio[1257]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 19 18:58:12 volumio volumio[1257]: info: CoreCommandRouter::volumioPushState Jan 19 18:58:12 volumio volumio[1257]: info: MPD Permissions set Jan 19 18:58:12 volumio volumio[1257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 19 18:58:12 volumio volumio[1257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 19 18:58:12 volumio volumio[1257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 19 18:58:12 volumio volumio[1257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 19 18:58:12 volumio volumio[1257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 19 18:58:12 volumio volumio[1257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 19 18:58:12 volumio volumio[1257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 19 18:58:12 volumio volumio[1257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 19 18:58:12 volumio systemd[1]: Starting Music Player Daemon... Jan 19 18:58:12 volumio volumio[1257]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 19 18:58:12 volumio volumio[1257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 19 18:58:12 volumio sudo[4863]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 19 18:58:12 volumio sudo[4863]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 19 18:58:12 volumio sudo[4863]: pam_unix(sudo:session): session closed for user root Jan 19 18:58:12 volumio volumio[1257]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jan 19 18:58:12 volumio volumio[1257]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 19 18:58:12 volumio volumio[1257]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 19 18:58:12 volumio volumio[1257]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 19 18:58:12 volumio volumio[1257]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 19 18:58:12 volumio volumio[1257]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 19 18:58:12 volumio volumio[1257]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 19 18:58:12 volumio volumio[1257]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 19 18:58:12 volumio volumio[1257]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 19 18:58:12 volumio volumio[1257]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 19 18:58:12 volumio volumio[1257]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jan 19 18:58:12 volumio volumio[1257]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jan 19 18:58:12 volumio volumio[1257]: info: Starting Shairport Sync Jan 19 18:58:12 volumio sudo[4876]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 19 18:58:12 volumio sudo[4876]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 19 18:58:12 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jan 19 18:58:12 volumio systemd[1]: shairport-sync.service: Succeeded. Jan 19 18:58:12 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jan 19 18:58:12 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jan 19 18:58:12 volumio sudo[4876]: pam_unix(sudo:session): session closed for user root Jan 19 18:58:12 volumio volumio[1257]: info: Shairport-Sync Started Jan 19 18:58:12 volumio mpd[4872]: Jan 19 18:58 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 19 18:58:12 volumio systemd[1]: Started Music Player Daemon. Jan 19 18:58:12 volumio sudo[4857]: pam_unix(sudo:session): session closed for user root Jan 19 18:58:12 volumio volumio[1257]: error: updateQueue error: null Jan 19 18:58:16 volumio volumio[1257]: info: VolumeController::SetAlsaVolume100 Jan 19 18:58:16 volumio volumio[1257]: info: CoreStateMachine::pushState Jan 19 18:58:16 volumio volumio[1257]: info: CorePlayQueue::getTrack 0 Jan 19 18:58:16 volumio volumio[1257]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 19 18:58:16 volumio volumio[1257]: info: CoreCommandRouter::volumioPushState Jan 19 18:58:20 volumio volumio[1257]: info: CALLMETHOD: audio_interface alsa_controller saveResamplingOpts [object Object] Jan 19 18:58:20 volumio volumio[1257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveResamplingOpts Jan 19 18:58:20 volumio volumio[1257]: info: CoreCommandRouter::executeOnPlugin: mpd , saveResampleOptions Jan 19 18:58:20 volumio sudo[4903]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 19 18:58:20 volumio sudo[4903]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 19 18:58:20 volumio sudo[4903]: pam_unix(sudo:session): session closed for user root Jan 19 18:58:20 volumio volumio[1257]: info: MPD Permissions set Jan 19 18:58:20 volumio volumio[1257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 19 18:58:20 volumio volumio[1257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 19 18:58:20 volumio volumio[1257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 19 18:58:20 volumio volumio[1257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 19 18:58:20 volumio volumio[1257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 19 18:58:20 volumio volumio[1257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 19 18:58:20 volumio volumio[1257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 19 18:58:20 volumio volumio[1257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 19 18:58:20 volumio sudo[4906]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 19 18:58:20 volumio sudo[4906]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 19 18:58:20 volumio volumio[1257]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 19 18:58:20 volumio volumio[1257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 19 18:58:20 volumio systemd[1]: Stopping Music Player Daemon... Jan 19 18:58:20 volumio systemd[1]: mpd.service: Succeeded. Jan 19 18:58:20 volumio systemd[1]: Stopped Music Player Daemon. Jan 19 18:58:20 volumio systemd[1]: Starting Music Player Daemon... Jan 19 18:58:20 volumio sudo[4912]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 19 18:58:20 volumio sudo[4912]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 19 18:58:20 volumio sudo[4912]: pam_unix(sudo:session): session closed for user root Jan 19 18:58:20 volumio mpd[4916]: Jan 19 18:58 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 19 18:58:20 volumio systemd[1]: Started Music Player Daemon. Jan 19 18:58:20 volumio sudo[4906]: pam_unix(sudo:session): session closed for user root Jan 19 18:58:20 volumio volumio[1257]: error: updateQueue error: null Jan 19 18:58:24 volumio volumio[1257]: info: CoreCommandRouter::volumioPlay Jan 19 18:58:24 volumio volumio[1257]: info: CoreStateMachine::play index undefined Jan 19 18:58:24 volumio volumio[1257]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 19 18:58:24 volumio volumio[1257]: info: CorePlayQueue::getTrack 0 Jan 19 18:58:24 volumio volumio[1257]: info: CoreStateMachine::startPlaybackTimer Jan 19 18:58:24 volumio volumio[1257]: info: CorePlayQueue::getTrack 0 Jan 19 18:58:24 volumio volumio[1257]: info: ControllerMpd::resume Jan 19 18:58:24 volumio volumio[1257]: verbose: ControllerMpd::sendMpdCommand play Jan 19 18:58:24 volumio volumio[1257]: info: sendMpdCommand play took 0 milliseconds Jan 19 18:58:30 volumio volumio[1257]: info: CoreCommandRouter::volumioPlay Jan 19 18:58:30 volumio volumio[1257]: info: CoreStateMachine::play index undefined Jan 19 18:58:30 volumio volumio[1257]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 19 18:58:30 volumio volumio[1257]: info: CorePlayQueue::getTrack 0 Jan 19 18:58:30 volumio volumio[1257]: info: CoreStateMachine::startPlaybackTimer Jan 19 18:58:30 volumio volumio[1257]: info: CorePlayQueue::getTrack 0 Jan 19 18:58:30 volumio volumio[1257]: info: ControllerMpd::resume Jan 19 18:58:30 volumio volumio[1257]: verbose: ControllerMpd::sendMpdCommand play Jan 19 18:58:30 volumio volumio[1257]: info: sendMpdCommand play took 1 milliseconds Jan 19 18:58:35 volumio volumio[1257]: info: CoreCommandRouter::volumioPlay Jan 19 18:58:35 volumio volumio[1257]: info: CoreStateMachine::play index undefined Jan 19 18:58:35 volumio volumio[1257]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 19 18:58:35 volumio volumio[1257]: info: CorePlayQueue::getTrack 0 Jan 19 18:58:35 volumio volumio[1257]: info: CoreStateMachine::startPlaybackTimer Jan 19 18:58:35 volumio volumio[1257]: info: CorePlayQueue::getTrack 0 Jan 19 18:58:35 volumio volumio[1257]: info: ControllerMpd::resume Jan 19 18:58:35 volumio volumio[1257]: verbose: ControllerMpd::sendMpdCommand play Jan 19 18:58:35 volumio volumio[1257]: info: sendMpdCommand play took 0 milliseconds Jan 19 18:58:44 volumio volumio[1257]: info: CALLMETHOD: audio_interface alsa_controller saveVolumeOptions [object Object] Jan 19 18:58:44 volumio volumio[1257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveVolumeOptions Jan 19 18:58:44 volumio volumio[1257]: info: CoreCommandRouter::volumioGetState Jan 19 18:58:44 volumio volumio[1257]: info: CorePlayQueue::getTrack 0 Jan 19 18:58:44 volumio volumio[1257]: info: Restoring Previous Volume level: 100 false false Jan 19 18:58:44 volumio volumio[1257]: info: Output device has changed, restarting MPD Jan 19 18:58:44 volumio sudo[5006]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 19 18:58:44 volumio sudo[5006]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 19 18:58:44 volumio volumio[1257]: info: Output device has changed, restarting Shairport Sync Jan 19 18:58:44 volumio volumio[1257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 19 18:58:44 volumio volumio[1257]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 19 18:58:44 volumio sudo[5006]: pam_unix(sudo:session): session closed for user root Jan 19 18:58:44 volumio sudo[5008]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 19 18:58:44 volumio sudo[5008]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 19 18:58:44 volumio systemd[1]: Stopping Music Player Daemon... Jan 19 18:58:44 volumio volumio[1257]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 19 18:58:44 volumio volumio[1257]: info: Volume configurations have been set Jan 19 18:58:44 volumio systemd[1]: mpd.service: Succeeded. Jan 19 18:58:44 volumio systemd[1]: Stopped Music Player Daemon. Jan 19 18:58:45 volumio volumio[1257]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jan 19 18:58:45 volumio volumio[1257]: info: Updating Volume Controller Parameters: Device: 1 Name: HDMI 1 Out Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Jan 19 18:58:45 volumio volumio[1257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Jan 19 18:58:45 volumio volumio[1257]: info: Disabling external Volume Control Jan 19 18:58:45 volumio volumio[1257]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 19 18:58:45 volumio volumio[1257]: info: CoreStateMachine::pushState Jan 19 18:58:45 volumio volumio[1257]: info: CorePlayQueue::getTrack 0 Jan 19 18:58:45 volumio volumio[1257]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 19 18:58:45 volumio volumio[1257]: info: CoreCommandRouter::volumioPushState Jan 19 18:58:45 volumio volumio[1257]: info: MPD Permissions set Jan 19 18:58:45 volumio volumio[1257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 19 18:58:45 volumio volumio[1257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 19 18:58:45 volumio volumio[1257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 19 18:58:45 volumio volumio[1257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 19 18:58:45 volumio volumio[1257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 19 18:58:45 volumio volumio[1257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 19 18:58:45 volumio systemd[1]: Starting Music Player Daemon... Jan 19 18:58:45 volumio volumio[1257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 19 18:58:45 volumio volumio[1257]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 19 18:58:45 volumio volumio[1257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 19 18:58:45 volumio sudo[5015]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 19 18:58:45 volumio sudo[5015]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 19 18:58:45 volumio sudo[5015]: pam_unix(sudo:session): session closed for user root Jan 19 18:58:45 volumio volumio[1257]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jan 19 18:58:45 volumio volumio[1257]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 19 18:58:45 volumio volumio[1257]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 19 18:58:45 volumio volumio[1257]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 19 18:58:45 volumio volumio[1257]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 19 18:58:45 volumio volumio[1257]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 19 18:58:45 volumio volumio[1257]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 19 18:58:45 volumio volumio[1257]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 19 18:58:45 volumio volumio[1257]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 19 18:58:45 volumio volumio[1257]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 19 18:58:45 volumio volumio[1257]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jan 19 18:58:45 volumio volumio[1257]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jan 19 18:58:45 volumio volumio[1257]: info: Starting Shairport Sync Jan 19 18:58:45 volumio sudo[5028]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 19 18:58:45 volumio sudo[5028]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 19 18:58:45 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jan 19 18:58:45 volumio systemd[1]: shairport-sync.service: Succeeded. Jan 19 18:58:45 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jan 19 18:58:45 volumio sudo[5028]: pam_unix(sudo:session): session closed for user root Jan 19 18:58:45 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jan 19 18:58:45 volumio volumio[1257]: info: Shairport-Sync Started Jan 19 18:58:45 volumio mpd[5024]: Jan 19 18:58 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 19 18:58:45 volumio systemd[1]: Started Music Player Daemon. Jan 19 18:58:45 volumio sudo[5008]: pam_unix(sudo:session): session closed for user root Jan 19 18:58:45 volumio volumio[1257]: error: updateQueue error: null Jan 19 18:58:49 volumio volumio[1257]: info: VolumeController::SetAlsaVolume100 Jan 19 18:58:49 volumio volumio[1257]: info: CoreStateMachine::pushState Jan 19 18:58:49 volumio volumio[1257]: info: CorePlayQueue::getTrack 0 Jan 19 18:58:49 volumio volumio[1257]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 19 18:58:49 volumio volumio[1257]: info: CoreCommandRouter::volumioPushState Jan 19 18:58:51 volumio volumio[1257]: info: CALLMETHOD: audio_interface alsa_controller saveResamplingOpts [object Object] Jan 19 18:58:51 volumio volumio[1257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveResamplingOpts Jan 19 18:58:51 volumio volumio[1257]: info: CoreCommandRouter::executeOnPlugin: mpd , saveResampleOptions Jan 19 18:58:51 volumio sudo[5040]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 19 18:58:51 volumio sudo[5040]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 19 18:58:51 volumio sudo[5040]: pam_unix(sudo:session): session closed for user root Jan 19 18:58:51 volumio volumio[1257]: info: MPD Permissions set Jan 19 18:58:51 volumio volumio[1257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 19 18:58:51 volumio volumio[1257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 19 18:58:51 volumio volumio[1257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 19 18:58:51 volumio volumio[1257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 19 18:58:51 volumio volumio[1257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 19 18:58:51 volumio volumio[1257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 19 18:58:51 volumio volumio[1257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 19 18:58:51 volumio volumio[1257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 19 18:58:51 volumio sudo[5043]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 19 18:58:51 volumio sudo[5043]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 19 18:58:51 volumio volumio[1257]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 19 18:58:51 volumio volumio[1257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 19 18:58:51 volumio systemd[1]: Stopping Music Player Daemon... Jan 19 18:58:51 volumio systemd[1]: mpd.service: Succeeded. Jan 19 18:58:51 volumio systemd[1]: Stopped Music Player Daemon. Jan 19 18:58:51 volumio systemd[1]: Starting Music Player Daemon... Jan 19 18:58:51 volumio sudo[5049]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 19 18:58:51 volumio sudo[5049]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 19 18:58:51 volumio sudo[5049]: pam_unix(sudo:session): session closed for user root Jan 19 18:58:52 volumio mpd[5051]: Jan 19 18:58 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 19 18:58:52 volumio systemd[1]: Started Music Player Daemon. Jan 19 18:58:52 volumio sudo[5043]: pam_unix(sudo:session): session closed for user root Jan 19 18:58:52 volumio volumio[1257]: error: updateQueue error: null Jan 19 18:59:00 volumio volumio[1257]: info: Preload queue cleared Jan 19 18:59:00 volumio volumio[1257]: info: CoreCommandRouter::volumioReplaceandPlayItems Jan 19 18:59:00 volumio volumio[1257]: info: CoreStateMachine::ClearQueue Jan 19 18:59:00 volumio volumio[1257]: info: CoreStateMachine::stop Jan 19 18:59:00 volumio volumio[1257]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 19 18:59:00 volumio volumio[1257]: info: CoreStateMachine::updateTrackBlock Jan 19 18:59:00 volumio volumio[1257]: info: CorePlayQueue::getTrackBlock Jan 19 18:59:00 volumio volumio[1257]: info: CoreStateMachine::stPlaybackTimer Jan 19 18:59:00 volumio volumio[1257]: info: CoreStateMachine::pushState Jan 19 18:59:00 volumio volumio[1257]: info: CorePlayQueue::getTrack 0 Jan 19 18:59:00 volumio volumio[1257]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 19 18:59:00 volumio volumio[1257]: info: CoreCommandRouter::volumioPushState Jan 19 18:59:00 volumio volumio[1257]: info: CoreStateMachine::serviceStop Jan 19 18:59:00 volumio volumio[1257]: info: CorePlayQueue::getTrack 0 Jan 19 18:59:00 volumio volumio[1257]: info: CoreCommandRouter::serviceStop Jan 19 18:59:00 volumio volumio[1257]: info: ControllerMpd::stop Jan 19 18:59:00 volumio volumio[1257]: verbose: ControllerMpd::sendMpdCommand stop Jan 19 18:59:00 volumio volumio[1257]: info: CorePlayQueue::clearPlayQueue Jan 19 18:59:00 volumio volumio[1257]: info: CorePlayQueue::saveQueue Jan 19 18:59:00 volumio volumio[1257]: info: CoreCommandRouter::volumioPushQueue Jan 19 18:59:00 volumio volumio[1257]: info: CoreStateMachine::addQueueItems Jan 19 18:59:00 volumio volumio[1257]: info: CorePlayQueue::addQueueItems Jan 19 18:59:00 volumio volumio[1257]: info: Preload queue cleared Jan 19 18:59:00 volumio volumio[1257]: info: Adding Item to queue: music-library/NAS/Andere@server/Frank Peter Zimmermann und die Berliner Philharmoniker/Frank Peter Zimmermann - Beethoven, Berg, Bartók - stereo - FLAC 192k/01 - 01 - Berliner Philharmoniker, Frank Peter Zimmermann, Daniel Harding - Beethoven Violin Concerto - 1. Allegro ma non troppo.flac Jan 19 18:59:00 volumio volumio[1257]: info: Using cached record of: music-library/NAS/Andere@server/Frank Peter Zimmermann und die Berliner Philharmoniker/Frank Peter Zimmermann - Beethoven, Berg, Bartók - stereo - FLAC 192k/01 - 01 - Berliner Philharmoniker, Frank Peter Zimmermann, Daniel Harding - Beethoven Violin Concerto - 1. Allegro ma non troppo.flac Jan 19 18:59:00 volumio volumio[1257]: info: CoreCommandRouter::volumioPushQueue Jan 19 18:59:00 volumio volumio[1257]: info: CorePlayQueue::saveQueue Jan 19 18:59:00 volumio volumio[1257]: info: CoreStateMachine::updateTrackBlock Jan 19 18:59:00 volumio volumio[1257]: info: CorePlayQueue::getTrackBlock Jan 19 18:59:00 volumio volumio[1257]: info: CoreCommandRouter::volumioPlay Jan 19 18:59:00 volumio volumio[1257]: info: CoreStateMachine::play index 0 Jan 19 18:59:00 volumio volumio[1257]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 19 18:59:00 volumio volumio[1257]: info: CoreStateMachine::addQueueItems Jan 19 18:59:00 volumio volumio[1257]: info: CorePlayQueue::addQueueItems Jan 19 18:59:00 volumio volumio[1257]: info: Preload queue cleared Jan 19 18:59:00 volumio volumio[1257]: info: Adding Item to queue: music-library/NAS/Andere@server/Frank Peter Zimmermann und die Berliner Philharmoniker/Frank Peter Zimmermann - Beethoven, Berg, Bartók - stereo - FLAC 192k/01 - 02 - Berliner Philharmoniker, Frank Peter Zimmermann, Daniel Harding - Beethoven Violin Concerto - 2. Larghetto.flac Jan 19 18:59:00 volumio volumio[1257]: info: Using cached record of: music-library/NAS/Andere@server/Frank Peter Zimmermann und die Berliner Philharmoniker/Frank Peter Zimmermann - Beethoven, Berg, Bartók - stereo - FLAC 192k/01 - 02 - Berliner Philharmoniker, Frank Peter Zimmermann, Daniel Harding - Beethoven Violin Concerto - 2. Larghetto.flac Jan 19 18:59:00 volumio volumio[1257]: info: Adding Item to queue: music-library/NAS/Andere@server/Frank Peter Zimmermann und die Berliner Philharmoniker/Frank Peter Zimmermann - Beethoven, Berg, Bartók - stereo - FLAC 192k/01 - 03 - Berliner Philharmoniker, Frank Peter Zimmermann, Daniel Harding - Beethoven Violin Concerto - 3. Rondo Allegro.flac Jan 19 18:59:00 volumio volumio[1257]: info: Using cached record of: music-library/NAS/Andere@server/Frank Peter Zimmermann und die Berliner Philharmoniker/Frank Peter Zimmermann - Beethoven, Berg, Bartók - stereo - FLAC 192k/01 - 03 - Berliner Philharmoniker, Frank Peter Zimmermann, Daniel Harding - Beethoven Violin Concerto - 3. Rondo Allegro.flac Jan 19 18:59:00 volumio volumio[1257]: info: Adding Item to queue: music-library/NAS/Andere@server/Frank Peter Zimmermann und die Berliner Philharmoniker/Frank Peter Zimmermann - Beethoven, Berg, Bartók - stereo - FLAC 192k/02 - 01 - Berliner Philharmoniker, Frank Peter Zimmermann, Kirill Petrenko - Berg Violin Concerto - 1. Andante - Allegretto.flac Jan 19 18:59:00 volumio volumio[1257]: info: Using cached record of: music-library/NAS/Andere@server/Frank Peter Zimmermann und die Berliner Philharmoniker/Frank Peter Zimmermann - Beethoven, Berg, Bartók - stereo - FLAC 192k/02 - 01 - Berliner Philharmoniker, Frank Peter Zimmermann, Kirill Petrenko - Berg Violin Concerto - 1. Andante - Allegretto.flac Jan 19 18:59:00 volumio volumio[1257]: info: Adding Item to queue: music-library/NAS/Andere@server/Frank Peter Zimmermann und die Berliner Philharmoniker/Frank Peter Zimmermann - Beethoven, Berg, Bartók - stereo - FLAC 192k/02 - 02 - Berliner Philharmoniker, Frank Peter Zimmermann, Kirill Petrenko - Berg Violin Concerto - 2. Allegro - Adagio.flac Jan 19 18:59:00 volumio volumio[1257]: info: Using cached record of: music-library/NAS/Andere@server/Frank Peter Zimmermann und die Berliner Philharmoniker/Frank Peter Zimmermann - Beethoven, Berg, Bartók - stereo - FLAC 192k/02 - 02 - Berliner Philharmoniker, Frank Peter Zimmermann, Kirill Petrenko - Berg Violin Concerto - 2. Allegro - Adagio.flac Jan 19 18:59:00 volumio volumio[1257]: info: Adding Item to queue: music-library/NAS/Andere@server/Frank Peter Zimmermann und die Berliner Philharmoniker/Frank Peter Zimmermann - Beethoven, Berg, Bartók - stereo - FLAC 192k/03 - 01 - Berliner Philharmoniker, Frank Peter Zimmermann, Alan Gilbert - Bart¢k Violin Concerto No. 1 Sz 36 - 1. Andante sostenuto.flac Jan 19 18:59:00 volumio volumio[1257]: info: Using cached record of: music-library/NAS/Andere@server/Frank Peter Zimmermann und die Berliner Philharmoniker/Frank Peter Zimmermann - Beethoven, Berg, Bartók - stereo - FLAC 192k/03 - 01 - Berliner Philharmoniker, Frank Peter Zimmermann, Alan Gilbert - Bart¢k Violin Concerto No. 1 Sz 36 - 1. Andante sostenuto.flac Jan 19 18:59:00 volumio volumio[1257]: info: Adding Item to queue: music-library/NAS/Andere@server/Frank Peter Zimmermann und die Berliner Philharmoniker/Frank Peter Zimmermann - Beethoven, Berg, Bartók - stereo - FLAC 192k/03 - 02 - Berliner Philharmoniker, Frank Peter Zimmermann, Alan Gilbert - Bart¢k Violin Concerto No. 1 Sz 36 - 2. Allegro giocoso.flac Jan 19 18:59:00 volumio volumio[1257]: info: Using cached record of: music-library/NAS/Andere@server/Frank Peter Zimmermann und die Berliner Philharmoniker/Frank Peter Zimmermann - Beethoven, Berg, Bartók - stereo - FLAC 192k/03 - 02 - Berliner Philharmoniker, Frank Peter Zimmermann, Alan Gilbert - Bart¢k Violin Concerto No. 1 Sz 36 - 2. Allegro giocoso.flac Jan 19 18:59:00 volumio volumio[1257]: info: Adding Item to queue: music-library/NAS/Andere@server/Frank Peter Zimmermann und die Berliner Philharmoniker/Frank Peter Zimmermann - Beethoven, Berg, Bartók - stereo - FLAC 192k/04 - 01 - Berliner Philharmoniker, Frank Peter Zimmermann, Alan Gilbert - Bart¢k Violin Concerto No. 2 Sz 112 - 1. Allegro non troppo.flac Jan 19 18:59:00 volumio volumio[1257]: info: Using cached record of: music-library/NAS/Andere@server/Frank Peter Zimmermann und die Berliner Philharmoniker/Frank Peter Zimmermann - Beethoven, Berg, Bartók - stereo - FLAC 192k/04 - 01 - Berliner Philharmoniker, Frank Peter Zimmermann, Alan Gilbert - Bart¢k Violin Concerto No. 2 Sz 112 - 1. Allegro non troppo.flac Jan 19 18:59:00 volumio volumio[1257]: info: Adding Item to queue: music-library/NAS/Andere@server/Frank Peter Zimmermann und die Berliner Philharmoniker/Frank Peter Zimmermann - Beethoven, Berg, Bartók - stereo - FLAC 192k/04 - 02 - Berliner Philharmoniker, Frank Peter Zimmermann, Alan Gilbert - Bart¢k Violin Concerto No. 2 Sz 112 - 2. Andante tranquillo.flac Jan 19 18:59:00 volumio volumio[1257]: info: Using cached record of: music-library/NAS/Andere@server/Frank Peter Zimmermann und die Berliner Philharmoniker/Frank Peter Zimmermann - Beethoven, Berg, Bartók - stereo - FLAC 192k/04 - 02 - Berliner Philharmoniker, Frank Peter Zimmermann, Alan Gilbert - Bart¢k Violin Concerto No. 2 Sz 112 - 2. Andante tranquillo.flac Jan 19 18:59:00 volumio volumio[1257]: info: Adding Item to queue: music-library/NAS/Andere@server/Frank Peter Zimmermann und die Berliner Philharmoniker/Frank Peter Zimmermann - Beethoven, Berg, Bartók - stereo - FLAC 192k/04 - 03 - Berliner Philharmoniker, Frank Peter Zimmermann, Alan Gilbert - Bart¢k Violin Concerto No. 2 Sz 112 - 3. Allegro molto.flac Jan 19 18:59:00 volumio volumio[1257]: info: Using cached record of: music-library/NAS/Andere@server/Frank Peter Zimmermann und die Berliner Philharmoniker/Frank Peter Zimmermann - Beethoven, Berg, Bartók - stereo - FLAC 192k/04 - 03 - Berliner Philharmoniker, Frank Peter Zimmermann, Alan Gilbert - Bart¢k Violin Concerto No. 2 Sz 112 - 3. Allegro molto.flac Jan 19 18:59:00 volumio volumio[1257]: info: CoreStateMachine::stop Jan 19 18:59:00 volumio volumio[1257]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 19 18:59:00 volumio volumio[1257]: info: CoreCommandRouter::volumioPushQueue Jan 19 18:59:00 volumio volumio[1257]: info: CorePlayQueue::saveQueue Jan 19 18:59:00 volumio volumio[1257]: info: CoreStateMachine::play index undefined Jan 19 18:59:00 volumio volumio[1257]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 19 18:59:00 volumio volumio[1257]: info: CoreStateMachine::updateTrackBlock Jan 19 18:59:00 volumio volumio[1257]: info: CorePlayQueue::getTrackBlock Jan 19 18:59:00 volumio volumio[1257]: info: CorePlayQueue::getTrack 0 Jan 19 18:59:00 volumio volumio[1257]: info: CoreStateMachine::startPlaybackTimer Jan 19 18:59:00 volumio volumio[1257]: info: CorePlayQueue::getTrack 0 Jan 19 18:59:00 volumio volumio[1257]: verbose: ControllerMpd::clearAddPlayTracks NAS/Andere@server/Frank Peter Zimmermann und die Berliner Philharmoniker/Frank Peter Zimmermann - Beethoven, Berg, Bartók - stereo - FLAC 192k/01 - 01 - Berliner Philharmoniker, Frank Peter Zimmermann, Daniel Harding - Beethoven Violin Concerto - 1. Allegro ma non troppo.flac Jan 19 18:59:00 volumio volumio[1257]: verbose: ControllerMpd::sendMpdCommand stop Jan 19 18:59:00 volumio volumio[1257]: info: sendMpdCommand stop took 13 milliseconds Jan 19 18:59:00 volumio volumio[1257]: info: sendMpdCommand stop took 1 milliseconds Jan 19 18:59:00 volumio volumio[1257]: verbose: ControllerMpd::sendMpdCommand clear Jan 19 18:59:00 volumio volumio[1257]: info: Jan 19 18:59:00 volumio volumio[1257]: ---------------------------- MPD announces system playlist update Jan 19 18:59:00 volumio volumio[1257]: info: Ignoring MPD Status Update Jan 19 18:59:00 volumio volumio[1257]: info: sendMpdCommand clear took 1 milliseconds Jan 19 18:59:00 volumio volumio[1257]: verbose: ControllerMpd::sendMpdCommand add "NAS/Andere@server/Frank Peter Zimmermann und die Berliner Philharmoniker/Frank Peter Zimmermann - Beethoven, Berg, Bartók - stereo - FLAC 192k/01 - 01 - Berliner Philharmoniker, Frank Peter Zimmermann, Daniel Harding - Beethoven Violin Concerto - 1. Allegro ma non troppo.flac" Jan 19 18:59:00 volumio volumio[1257]: error: updateQueue error: null Jan 19 18:59:00 volumio volumio[1257]: info: ------------------------------ 0ms Jan 19 18:59:00 volumio volumio[1257]: info: sendMpdCommand add "NAS/Andere@server/Frank Peter Zimmermann und die Berliner Philharmoniker/Frank Peter Zimmermann - Beethoven, Berg, Bartók - stereo - FLAC 192k/01 - 01 - Berliner Philharmoniker, Frank Peter Zimmermann, Daniel Harding - Beethoven Violin Concerto - 1. Allegro ma non troppo.flac" took 0 milliseconds Jan 19 18:59:00 volumio volumio[1257]: verbose: ControllerMpd::sendMpdCommand play Jan 19 18:59:00 volumio volumio[1257]: info: Jan 19 18:59:00 volumio volumio[1257]: ---------------------------- MPD announces system playlist update Jan 19 18:59:00 volumio volumio[1257]: info: Ignoring MPD Status Update Jan 19 18:59:00 volumio volumio[1257]: info: sendMpdCommand play took 1 milliseconds Jan 19 18:59:00 volumio volumio[1257]: info: ------------------------------ 1ms Jan 19 18:59:00 volumio volumio[1257]: info: Jan 19 18:59:00 volumio volumio[1257]: ---------------------------- MPD announces state update: player Jan 19 18:59:00 volumio volumio[1257]: info: ControllerMpd::getState Jan 19 18:59:00 volumio volumio[1257]: verbose: ControllerMpd::sendMpdCommand status Jan 19 18:59:00 volumio volumio[1257]: info: sendMpdCommand status took 0 milliseconds Jan 19 18:59:00 volumio volumio[1257]: verbose: ControllerMpd::parseState Jan 19 18:59:00 volumio volumio[1257]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 19 18:59:00 volumio volumio[1257]: info: Jan 19 18:59:00 volumio volumio[1257]: ---------------------------- MPD announces state update: player Jan 19 18:59:00 volumio volumio[1257]: info: ControllerMpd::getState Jan 19 18:59:00 volumio volumio[1257]: verbose: ControllerMpd::sendMpdCommand status Jan 19 18:59:00 volumio volumio[1257]: info: sendMpdCommand playlistinfo took 0 milliseconds Jan 19 18:59:00 volumio volumio[1257]: verbose: ControllerMpd::parseTrackInfo Jan 19 18:59:00 volumio volumio[1257]: info: ControllerMpd::pushState Jan 19 18:59:00 volumio volumio[1257]: info: CoreCommandRouter::servicePushState Jan 19 18:59:00 volumio volumio[1257]: info: CorePlayQueue::getTrack 0 Jan 19 18:59:00 volumio volumio[1257]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":1301,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Beethoven: Violin Concerto - 1. Allegro ma non troppo","artist":"Berliner Philharmoniker, Frank Peter Zimmermann, Daniel Harding","album":"Frank Peter Zimmermann: Beethoven - Berg - Bartók","uri":"NAS/Andere@server/Frank Peter Zimmermann und die Berliner Philharmoniker/Frank Peter Zimmermann - Beethoven, Berg, Bartók - stereo - FLAC 192k/01 - 01 - Berliner Philharmoniker, Frank Peter Zimmermann, Daniel Harding - Beethoven Violin Concerto - 1. Allegro ma non troppo.flac","trackType":"flac"} Jan 19 18:59:00 volumio volumio[1257]: verbose: CURRENT POSITION 0 Jan 19 18:59:00 volumio volumio[1257]: info: CoreStateMachine::syncState stateService play Jan 19 18:59:00 volumio volumio[1257]: info: CoreStateMachine::syncState currentStatus stop Jan 19 18:59:00 volumio volumio[1257]: info: ------------------------------ 3ms Jan 19 18:59:00 volumio volumio[1257]: info: sendMpdCommand status took 1 milliseconds Jan 19 18:59:00 volumio volumio[1257]: verbose: ControllerMpd::parseState Jan 19 18:59:00 volumio volumio[1257]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 19 18:59:00 volumio volumio[1257]: info: sendMpdCommand playlistinfo took 0 milliseconds Jan 19 18:59:00 volumio volumio[1257]: verbose: ControllerMpd::parseTrackInfo Jan 19 18:59:00 volumio volumio[1257]: info: ControllerMpd::pushState Jan 19 18:59:00 volumio volumio[1257]: info: CoreCommandRouter::servicePushState Jan 19 18:59:00 volumio volumio[1257]: info: CorePlayQueue::getTrack 0 Jan 19 18:59:00 volumio volumio[1257]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":1301,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Beethoven: Violin Concerto - 1. Allegro ma non troppo","artist":"Berliner Philharmoniker, Frank Peter Zimmermann, Daniel Harding","album":"Frank Peter Zimmermann: Beethoven - Berg - Bartók","uri":"NAS/Andere@server/Frank Peter Zimmermann und die Berliner Philharmoniker/Frank Peter Zimmermann - Beethoven, Berg, Bartók - stereo - FLAC 192k/01 - 01 - Berliner Philharmoniker, Frank Peter Zimmermann, Daniel Harding - Beethoven Violin Concerto - 1. Allegro ma non troppo.flac","trackType":"flac"} Jan 19 18:59:00 volumio volumio[1257]: verbose: CURRENT POSITION 0 Jan 19 18:59:00 volumio volumio[1257]: info: CoreStateMachine::syncState stateService play Jan 19 18:59:00 volumio volumio[1257]: info: CoreStateMachine::syncState currentStatus play Jan 19 18:59:00 volumio volumio[1257]: info: Received an update from plugin. extracting info from payload Jan 19 18:59:00 volumio volumio[1257]: info: CoreStateMachine::pushState Jan 19 18:59:00 volumio volumio[1257]: info: CorePlayQueue::getTrack 0 Jan 19 18:59:00 volumio volumio[1257]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 19 18:59:00 volumio volumio[1257]: info: CoreCommandRouter::volumioPushState Jan 19 18:59:00 volumio volumio[1257]: info: CoreStateMachine::pushState Jan 19 18:59:00 volumio volumio[1257]: info: CorePlayQueue::getTrack 0 Jan 19 18:59:00 volumio volumio[1257]: info: CoreCommandRouter::volumioPushState Jan 19 18:59:00 volumio volumio[1257]: info: ------------------------------ 6ms Jan 19 18:59:01 volumio volumio[1257]: info: Preload queue cleared Jan 19 18:59:01 volumio volumio[1257]: info: CoreCommandRouter::volumioReplaceandPlayItems Jan 19 18:59:01 volumio volumio[1257]: info: CoreStateMachine::ClearQueue Jan 19 18:59:01 volumio volumio[1257]: info: CoreStateMachine::stop Jan 19 18:59:01 volumio volumio[1257]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 19 18:59:01 volumio volumio[1257]: info: CoreStateMachine::stPlaybackTimer Jan 19 18:59:01 volumio volumio[1257]: info: CoreStateMachine::updateTrackBlock Jan 19 18:59:01 volumio volumio[1257]: info: CorePlayQueue::getTrackBlock Jan 19 18:59:01 volumio volumio[1257]: info: CoreStateMachine::pushState Jan 19 18:59:01 volumio volumio[1257]: info: CorePlayQueue::getTrack 0 Jan 19 18:59:01 volumio volumio[1257]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 19 18:59:01 volumio volumio[1257]: info: CoreCommandRouter::volumioPushState Jan 19 18:59:01 volumio volumio[1257]: info: CoreStateMachine::serviceStop Jan 19 18:59:01 volumio volumio[1257]: info: CorePlayQueue::getTrack 0 Jan 19 18:59:01 volumio volumio[1257]: info: CoreCommandRouter::serviceStop Jan 19 18:59:01 volumio volumio[1257]: info: ControllerMpd::stop Jan 19 18:59:01 volumio volumio[1257]: verbose: ControllerMpd::sendMpdCommand stop Jan 19 18:59:01 volumio volumio[1257]: info: CorePlayQueue::clearPlayQueue Jan 19 18:59:01 volumio volumio[1257]: info: CorePlayQueue::saveQueue Jan 19 18:59:01 volumio volumio[1257]: info: CoreCommandRouter::volumioPushQueue Jan 19 18:59:01 volumio volumio[1257]: info: CoreStateMachine::addQueueItems Jan 19 18:59:01 volumio volumio[1257]: info: CorePlayQueue::addQueueItems Jan 19 18:59:01 volumio volumio[1257]: info: Preload queue cleared Jan 19 18:59:01 volumio volumio[1257]: info: Adding Item to queue: music-library/NAS/Andere@server/Frank Peter Zimmermann und die Berliner Philharmoniker/Frank Peter Zimmermann - Beethoven, Berg, Bartók - stereo - FLAC 192k/01 - 01 - Berliner Philharmoniker, Frank Peter Zimmermann, Daniel Harding - Beethoven Violin Concerto - 1. Allegro ma non troppo.flac Jan 19 18:59:01 volumio volumio[1257]: info: Using cached record of: music-library/NAS/Andere@server/Frank Peter Zimmermann und die Berliner Philharmoniker/Frank Peter Zimmermann - Beethoven, Berg, Bartók - stereo - FLAC 192k/01 - 01 - Berliner Philharmoniker, Frank Peter Zimmermann, Daniel Harding - Beethoven Violin Concerto - 1. Allegro ma non troppo.flac Jan 19 18:59:01 volumio volumio[1257]: info: CoreCommandRouter::volumioPushQueue Jan 19 18:59:01 volumio volumio[1257]: info: CorePlayQueue::saveQueue Jan 19 18:59:01 volumio volumio[1257]: info: CoreStateMachine::updateTrackBlock Jan 19 18:59:01 volumio volumio[1257]: info: CorePlayQueue::getTrackBlock Jan 19 18:59:01 volumio volumio[1257]: info: CoreCommandRouter::volumioPlay Jan 19 18:59:01 volumio volumio[1257]: info: CoreStateMachine::play index 0 Jan 19 18:59:01 volumio volumio[1257]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 19 18:59:01 volumio volumio[1257]: info: CoreStateMachine::addQueueItems Jan 19 18:59:01 volumio volumio[1257]: info: CorePlayQueue::addQueueItems Jan 19 18:59:01 volumio volumio[1257]: info: Preload queue cleared Jan 19 18:59:01 volumio volumio[1257]: info: Adding Item to queue: music-library/NAS/Andere@server/Frank Peter Zimmermann und die Berliner Philharmoniker/Frank Peter Zimmermann - Beethoven, Berg, Bartók - stereo - FLAC 192k/01 - 02 - Berliner Philharmoniker, Frank Peter Zimmermann, Daniel Harding - Beethoven Violin Concerto - 2. Larghetto.flac Jan 19 18:59:01 volumio volumio[1257]: info: Using cached record of: music-library/NAS/Andere@server/Frank Peter Zimmermann und die Berliner Philharmoniker/Frank Peter Zimmermann - Beethoven, Berg, Bartók - stereo - FLAC 192k/01 - 02 - Berliner Philharmoniker, Frank Peter Zimmermann, Daniel Harding - Beethoven Violin Concerto - 2. Larghetto.flac Jan 19 18:59:01 volumio volumio[1257]: info: Adding Item to queue: music-library/NAS/Andere@server/Frank Peter Zimmermann und die Berliner Philharmoniker/Frank Peter Zimmermann - Beethoven, Berg, Bartók - stereo - FLAC 192k/01 - 03 - Berliner Philharmoniker, Frank Peter Zimmermann, Daniel Harding - Beethoven Violin Concerto - 3. Rondo Allegro.flac Jan 19 18:59:01 volumio volumio[1257]: info: Using cached record of: music-library/NAS/Andere@server/Frank Peter Zimmermann und die Berliner Philharmoniker/Frank Peter Zimmermann - Beethoven, Berg, Bartók - stereo - FLAC 192k/01 - 03 - Berliner Philharmoniker, Frank Peter Zimmermann, Daniel Harding - Beethoven Violin Concerto - 3. Rondo Allegro.flac Jan 19 18:59:01 volumio volumio[1257]: info: Adding Item to queue: music-library/NAS/Andere@server/Frank Peter Zimmermann und die Berliner Philharmoniker/Frank Peter Zimmermann - Beethoven, Berg, Bartók - stereo - FLAC 192k/02 - 01 - Berliner Philharmoniker, Frank Peter Zimmermann, Kirill Petrenko - Berg Violin Concerto - 1. Andante - Allegretto.flac Jan 19 18:59:01 volumio volumio[1257]: info: Using cached record of: music-library/NAS/Andere@server/Frank Peter Zimmermann und die Berliner Philharmoniker/Frank Peter Zimmermann - Beethoven, Berg, Bartók - stereo - FLAC 192k/02 - 01 - Berliner Philharmoniker, Frank Peter Zimmermann, Kirill Petrenko - Berg Violin Concerto - 1. Andante - Allegretto.flac Jan 19 18:59:01 volumio volumio[1257]: info: Adding Item to queue: music-library/NAS/Andere@server/Frank Peter Zimmermann und die Berliner Philharmoniker/Frank Peter Zimmermann - Beethoven, Berg, Bartók - stereo - FLAC 192k/02 - 02 - Berliner Philharmoniker, Frank Peter Zimmermann, Kirill Petrenko - Berg Violin Concerto - 2. Allegro - Adagio.flac Jan 19 18:59:01 volumio volumio[1257]: info: Using cached record of: music-library/NAS/Andere@server/Frank Peter Zimmermann und die Berliner Philharmoniker/Frank Peter Zimmermann - Beethoven, Berg, Bartók - stereo - FLAC 192k/02 - 02 - Berliner Philharmoniker, Frank Peter Zimmermann, Kirill Petrenko - Berg Violin Concerto - 2. Allegro - Adagio.flac Jan 19 18:59:01 volumio volumio[1257]: info: Adding Item to queue: music-library/NAS/Andere@server/Frank Peter Zimmermann und die Berliner Philharmoniker/Frank Peter Zimmermann - Beethoven, Berg, Bartók - stereo - FLAC 192k/03 - 01 - Berliner Philharmoniker, Frank Peter Zimmermann, Alan Gilbert - Bart¢k Violin Concerto No. 1 Sz 36 - 1. Andante sostenuto.flac Jan 19 18:59:01 volumio volumio[1257]: info: Using cached record of: music-library/NAS/Andere@server/Frank Peter Zimmermann und die Berliner Philharmoniker/Frank Peter Zimmermann - Beethoven, Berg, Bartók - stereo - FLAC 192k/03 - 01 - Berliner Philharmoniker, Frank Peter Zimmermann, Alan Gilbert - Bart¢k Violin Concerto No. 1 Sz 36 - 1. Andante sostenuto.flac Jan 19 18:59:01 volumio volumio[1257]: info: Adding Item to queue: music-library/NAS/Andere@server/Frank Peter Zimmermann und die Berliner Philharmoniker/Frank Peter Zimmermann - Beethoven, Berg, Bartók - stereo - FLAC 192k/03 - 02 - Berliner Philharmoniker, Frank Peter Zimmermann, Alan Gilbert - Bart¢k Violin Concerto No. 1 Sz 36 - 2. Allegro giocoso.flac Jan 19 18:59:01 volumio volumio[1257]: info: Using cached record of: music-library/NAS/Andere@server/Frank Peter Zimmermann und die Berliner Philharmoniker/Frank Peter Zimmermann - Beethoven, Berg, Bartók - stereo - FLAC 192k/03 - 02 - Berliner Philharmoniker, Frank Peter Zimmermann, Alan Gilbert - Bart¢k Violin Concerto No. 1 Sz 36 - 2. Allegro giocoso.flac Jan 19 18:59:01 volumio volumio[1257]: info: Adding Item to queue: music-library/NAS/Andere@server/Frank Peter Zimmermann und die Berliner Philharmoniker/Frank Peter Zimmermann - Beethoven, Berg, Bartók - stereo - FLAC 192k/04 - 01 - Berliner Philharmoniker, Frank Peter Zimmermann, Alan Gilbert - Bart¢k Violin Concerto No. 2 Sz 112 - 1. Allegro non troppo.flac Jan 19 18:59:01 volumio volumio[1257]: info: Using cached record of: music-library/NAS/Andere@server/Frank Peter Zimmermann und die Berliner Philharmoniker/Frank Peter Zimmermann - Beethoven, Berg, Bartók - stereo - FLAC 192k/04 - 01 - Berliner Philharmoniker, Frank Peter Zimmermann, Alan Gilbert - Bart¢k Violin Concerto No. 2 Sz 112 - 1. Allegro non troppo.flac Jan 19 18:59:01 volumio volumio[1257]: info: Adding Item to queue: music-library/NAS/Andere@server/Frank Peter Zimmermann und die Berliner Philharmoniker/Frank Peter Zimmermann - Beethoven, Berg, Bartók - stereo - FLAC 192k/04 - 02 - Berliner Philharmoniker, Frank Peter Zimmermann, Alan Gilbert - Bart¢k Violin Concerto No. 2 Sz 112 - 2. Andante tranquillo.flac Jan 19 18:59:01 volumio volumio[1257]: info: Using cached record of: music-library/NAS/Andere@server/Frank Peter Zimmermann und die Berliner Philharmoniker/Frank Peter Zimmermann - Beethoven, Berg, Bartók - stereo - FLAC 192k/04 - 02 - Berliner Philharmoniker, Frank Peter Zimmermann, Alan Gilbert - Bart¢k Violin Concerto No. 2 Sz 112 - 2. Andante tranquillo.flac Jan 19 18:59:01 volumio volumio[1257]: info: Adding Item to queue: music-library/NAS/Andere@server/Frank Peter Zimmermann und die Berliner Philharmoniker/Frank Peter Zimmermann - Beethoven, Berg, Bartók - stereo - FLAC 192k/04 - 03 - Berliner Philharmoniker, Frank Peter Zimmermann, Alan Gilbert - Bart¢k Violin Concerto No. 2 Sz 112 - 3. Allegro molto.flac Jan 19 18:59:01 volumio volumio[1257]: info: Using cached record of: music-library/NAS/Andere@server/Frank Peter Zimmermann und die Berliner Philharmoniker/Frank Peter Zimmermann - Beethoven, Berg, Bartók - stereo - FLAC 192k/04 - 03 - Berliner Philharmoniker, Frank Peter Zimmermann, Alan Gilbert - Bart¢k Violin Concerto No. 2 Sz 112 - 3. Allegro molto.flac Jan 19 18:59:01 volumio volumio[1257]: info: CoreStateMachine::stop Jan 19 18:59:01 volumio volumio[1257]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 19 18:59:01 volumio volumio[1257]: info: CoreCommandRouter::volumioPushQueue Jan 19 18:59:01 volumio volumio[1257]: info: CorePlayQueue::saveQueue Jan 19 18:59:01 volumio volumio[1257]: info: CoreStateMachine::play index undefined Jan 19 18:59:01 volumio volumio[1257]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 19 18:59:01 volumio volumio[1257]: info: CoreStateMachine::updateTrackBlock Jan 19 18:59:01 volumio volumio[1257]: info: CorePlayQueue::getTrackBlock Jan 19 18:59:01 volumio volumio[1257]: info: CorePlayQueue::getTrack 0 Jan 19 18:59:01 volumio volumio[1257]: info: CoreStateMachine::startPlaybackTimer Jan 19 18:59:01 volumio volumio[1257]: info: CorePlayQueue::getTrack 0 Jan 19 18:59:01 volumio volumio[1257]: verbose: ControllerMpd::clearAddPlayTracks NAS/Andere@server/Frank Peter Zimmermann und die Berliner Philharmoniker/Frank Peter Zimmermann - Beethoven, Berg, Bartók - stereo - FLAC 192k/01 - 01 - Berliner Philharmoniker, Frank Peter Zimmermann, Daniel Harding - Beethoven Violin Concerto - 1. Allegro ma non troppo.flac Jan 19 18:59:01 volumio volumio[1257]: verbose: ControllerMpd::sendMpdCommand stop Jan 19 18:59:01 volumio volumio[1257]: info: Jan 19 18:59:01 volumio volumio[1257]: ---------------------------- MPD announces state update: player Jan 19 18:59:01 volumio volumio[1257]: info: sendMpdCommand stop took 10 milliseconds Jan 19 18:59:01 volumio volumio[1257]: info: ControllerMpd::getState Jan 19 18:59:01 volumio volumio[1257]: verbose: ControllerMpd::sendMpdCommand status Jan 19 18:59:01 volumio volumio[1257]: info: sendMpdCommand stop took 2 milliseconds Jan 19 18:59:01 volumio volumio[1257]: verbose: ControllerMpd::sendMpdCommand clear Jan 19 18:59:01 volumio volumio[1257]: info: Jan 19 18:59:01 volumio volumio[1257]: ---------------------------- MPD announces system playlist update Jan 19 18:59:01 volumio volumio[1257]: info: Ignoring MPD Status Update Jan 19 18:59:01 volumio volumio[1257]: info: sendMpdCommand status took 0 milliseconds Jan 19 18:59:01 volumio volumio[1257]: info: sendMpdCommand clear took 0 milliseconds Jan 19 18:59:01 volumio volumio[1257]: verbose: ControllerMpd::parseState Jan 19 18:59:01 volumio volumio[1257]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 19 18:59:01 volumio volumio[1257]: verbose: ControllerMpd::sendMpdCommand add "NAS/Andere@server/Frank Peter Zimmermann und die Berliner Philharmoniker/Frank Peter Zimmermann - Beethoven, Berg, Bartók - stereo - FLAC 192k/01 - 01 - Berliner Philharmoniker, Frank Peter Zimmermann, Daniel Harding - Beethoven Violin Concerto - 1. Allegro ma non troppo.flac" Jan 19 18:59:01 volumio volumio[1257]: error: updateQueue error: null Jan 19 18:59:01 volumio volumio[1257]: info: ------------------------------ 1ms Jan 19 18:59:01 volumio volumio[1257]: info: sendMpdCommand playlistinfo took 1 milliseconds Jan 19 18:59:01 volumio volumio[1257]: info: sendMpdCommand add "NAS/Andere@server/Frank Peter Zimmermann und die Berliner Philharmoniker/Frank Peter Zimmermann - Beethoven, Berg, Bartók - stereo - FLAC 192k/01 - 01 - Berliner Philharmoniker, Frank Peter Zimmermann, Daniel Harding - Beethoven Violin Concerto - 1. Allegro ma non troppo.flac" took 1 milliseconds Jan 19 18:59:01 volumio volumio[1257]: verbose: ControllerMpd::parseTrackInfo Jan 19 18:59:01 volumio volumio[1257]: verbose: ControllerMpd::sendMpdCommand play Jan 19 18:59:01 volumio volumio[1257]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jan 19 18:59:01 volumio volumio[1257]: info: ------------------------------ 2ms Jan 19 18:59:01 volumio volumio[1257]: info: Jan 19 18:59:01 volumio volumio[1257]: ---------------------------- MPD announces system playlist update Jan 19 18:59:01 volumio volumio[1257]: info: Ignoring MPD Status Update Jan 19 18:59:01 volumio volumio[1257]: info: Jan 19 18:59:01 volumio volumio[1257]: ---------------------------- MPD announces state update: player Jan 19 18:59:01 volumio volumio[1257]: info: sendMpdCommand play took 1 milliseconds Jan 19 18:59:01 volumio volumio[1257]: info: ControllerMpd::getState Jan 19 18:59:01 volumio volumio[1257]: verbose: ControllerMpd::sendMpdCommand status Jan 19 18:59:01 volumio volumio[1257]: info: ------------------------------ 1ms Jan 19 18:59:01 volumio volumio[1257]: info: Jan 19 18:59:01 volumio volumio[1257]: ---------------------------- MPD announces state update: player Jan 19 18:59:01 volumio volumio[1257]: info: sendMpdCommand status took 1 milliseconds Jan 19 18:59:01 volumio volumio[1257]: info: ControllerMpd::getState Jan 19 18:59:01 volumio volumio[1257]: verbose: ControllerMpd::sendMpdCommand status Jan 19 18:59:01 volumio volumio[1257]: verbose: ControllerMpd::parseState Jan 19 18:59:01 volumio volumio[1257]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 19 18:59:01 volumio volumio[1257]: info: sendMpdCommand status took 0 milliseconds Jan 19 18:59:01 volumio volumio[1257]: info: sendMpdCommand playlistinfo took 1 milliseconds Jan 19 18:59:01 volumio volumio[1257]: verbose: ControllerMpd::parseState Jan 19 18:59:01 volumio volumio[1257]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 19 18:59:01 volumio volumio[1257]: verbose: ControllerMpd::parseTrackInfo Jan 19 18:59:01 volumio volumio[1257]: info: ControllerMpd::pushState Jan 19 18:59:01 volumio volumio[1257]: info: CoreCommandRouter::servicePushState Jan 19 18:59:01 volumio volumio[1257]: info: CorePlayQueue::getTrack 0 Jan 19 18:59:01 volumio volumio[1257]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":1301,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Beethoven: Violin Concerto - 1. Allegro ma non troppo","artist":"Berliner Philharmoniker, Frank Peter Zimmermann, Daniel Harding","album":"Frank Peter Zimmermann: Beethoven - Berg - Bartók","uri":"NAS/Andere@server/Frank Peter Zimmermann und die Berliner Philharmoniker/Frank Peter Zimmermann - Beethoven, Berg, Bartók - stereo - FLAC 192k/01 - 01 - Berliner Philharmoniker, Frank Peter Zimmermann, Daniel Harding - Beethoven Violin Concerto - 1. Allegro ma non troppo.flac","trackType":"flac"} Jan 19 18:59:01 volumio volumio[1257]: verbose: CURRENT POSITION 0 Jan 19 18:59:01 volumio volumio[1257]: info: CoreStateMachine::syncState stateService play Jan 19 18:59:01 volumio volumio[1257]: info: CoreStateMachine::syncState currentStatus stop Jan 19 18:59:01 volumio volumio[1257]: info: ------------------------------ 2ms Jan 19 18:59:01 volumio volumio[1257]: info: sendMpdCommand playlistinfo took 0 milliseconds Jan 19 18:59:01 volumio volumio[1257]: verbose: ControllerMpd::parseTrackInfo Jan 19 18:59:01 volumio volumio[1257]: info: ControllerMpd::pushState Jan 19 18:59:01 volumio volumio[1257]: info: CoreCommandRouter::servicePushState Jan 19 18:59:01 volumio volumio[1257]: info: CorePlayQueue::getTrack 0 Jan 19 18:59:01 volumio volumio[1257]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":1301,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Beethoven: Violin Concerto - 1. Allegro ma non troppo","artist":"Berliner Philharmoniker, Frank Peter Zimmermann, Daniel Harding","album":"Frank Peter Zimmermann: Beethoven - Berg - Bartók","uri":"NAS/Andere@server/Frank Peter Zimmermann und die Berliner Philharmoniker/Frank Peter Zimmermann - Beethoven, Berg, Bartók - stereo - FLAC 192k/01 - 01 - Berliner Philharmoniker, Frank Peter Zimmermann, Daniel Harding - Beethoven Violin Concerto - 1. Allegro ma non troppo.flac","trackType":"flac"} Jan 19 18:59:01 volumio volumio[1257]: verbose: CURRENT POSITION 0 Jan 19 18:59:01 volumio volumio[1257]: info: CoreStateMachine::syncState stateService play Jan 19 18:59:01 volumio volumio[1257]: info: CoreStateMachine::syncState currentStatus play Jan 19 18:59:01 volumio volumio[1257]: info: Received an update from plugin. extracting info from payload Jan 19 18:59:01 volumio volumio[1257]: info: CoreStateMachine::pushState Jan 19 18:59:01 volumio volumio[1257]: info: CorePlayQueue::getTrack 0 Jan 19 18:59:01 volumio volumio[1257]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 19 18:59:01 volumio volumio[1257]: info: CoreCommandRouter::volumioPushState Jan 19 18:59:01 volumio volumio[1257]: info: CoreStateMachine::pushState Jan 19 18:59:01 volumio volumio[1257]: info: CorePlayQueue::getTrack 0 Jan 19 18:59:01 volumio volumio[1257]: info: CoreCommandRouter::volumioPushState Jan 19 18:59:01 volumio volumio[1257]: info: ------------------------------ 6ms Jan 19 18:59:10 volumio volumio[1257]: info: Preload queue cleared Jan 19 18:59:10 volumio volumio[1257]: info: CoreCommandRouter::volumioReplaceandPlayItems Jan 19 18:59:10 volumio volumio[1257]: info: CoreStateMachine::ClearQueue Jan 19 18:59:10 volumio volumio[1257]: info: CoreStateMachine::stop Jan 19 18:59:10 volumio volumio[1257]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 19 18:59:10 volumio volumio[1257]: info: CoreStateMachine::stPlaybackTimer Jan 19 18:59:10 volumio volumio[1257]: info: CoreStateMachine::updateTrackBlock Jan 19 18:59:10 volumio volumio[1257]: info: CorePlayQueue::getTrackBlock Jan 19 18:59:10 volumio volumio[1257]: info: CoreStateMachine::pushState Jan 19 18:59:10 volumio volumio[1257]: info: CorePlayQueue::getTrack 0 Jan 19 18:59:10 volumio volumio[1257]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 19 18:59:10 volumio volumio[1257]: info: CoreCommandRouter::volumioPushState Jan 19 18:59:10 volumio volumio[1257]: info: CoreStateMachine::serviceStop Jan 19 18:59:10 volumio volumio[1257]: info: CorePlayQueue::getTrack 0 Jan 19 18:59:10 volumio volumio[1257]: info: CoreCommandRouter::serviceStop Jan 19 18:59:10 volumio volumio[1257]: info: ControllerMpd::stop Jan 19 18:59:10 volumio volumio[1257]: verbose: ControllerMpd::sendMpdCommand stop Jan 19 18:59:10 volumio volumio[1257]: info: CorePlayQueue::clearPlayQueue Jan 19 18:59:10 volumio volumio[1257]: info: CorePlayQueue::saveQueue Jan 19 18:59:10 volumio volumio[1257]: info: CoreCommandRouter::volumioPushQueue Jan 19 18:59:10 volumio volumio[1257]: info: CoreStateMachine::addQueueItems Jan 19 18:59:10 volumio volumio[1257]: info: CorePlayQueue::addQueueItems Jan 19 18:59:10 volumio volumio[1257]: info: Preload queue cleared Jan 19 18:59:10 volumio volumio[1257]: info: Adding Item to queue: music-library/NAS/Andere@server/Gotan.Project-Lunatico[www.todocvcd.com] Jan 19 18:59:10 volumio volumio[1257]: info: Exploding uri music-library/NAS/Andere@server/Gotan.Project-Lunatico[www.todocvcd.com] in service mpd Jan 19 18:59:10 volumio volumio[1257]: info: Jan 19 18:59:10 volumio volumio[1257]: ---------------------------- MPD announces state update: player Jan 19 18:59:10 volumio volumio[1257]: info: ALBUMART /albumart?cacheid=639&web=Gotan%20Project/Lunatico/extralarge&path=%2Fmnt%2FNAS%2FAndere%40server%2FGotan.Project-Lunatico%5Bwww.todocvcd.com%5D%2F01%20-%20Amor%20Porteno.mp3&metadata=false Jan 19 18:59:10 volumio volumio[1257]: info: URI /mnt/NAS/Andere@server/Gotan.Project-Lunatico[www.todocvcd.com]/01 - Amor Porteno.mp3 Jan 19 18:59:10 volumio volumio[1257]: info: ALBUMART /albumart?cacheid=639&web=Gotan%20Project/Lunatico/extralarge&path=%2Fmnt%2FNAS%2FAndere%40server%2FGotan.Project-Lunatico%5Bwww.todocvcd.com%5D%2F02%20-%20Notas.mp3&metadata=false Jan 19 18:59:10 volumio volumio[1257]: info: URI /mnt/NAS/Andere@server/Gotan.Project-Lunatico[www.todocvcd.com]/02 - Notas.mp3 Jan 19 18:59:10 volumio volumio[1257]: info: ALBUMART /albumart?cacheid=639&web=Gotan%20Project/Lunatico/extralarge&path=%2Fmnt%2FNAS%2FAndere%40server%2FGotan.Project-Lunatico%5Bwww.todocvcd.com%5D%2F03%20-%20Diferente.mp3&metadata=false Jan 19 18:59:10 volumio volumio[1257]: info: URI /mnt/NAS/Andere@server/Gotan.Project-Lunatico[www.todocvcd.com]/03 - Diferente.mp3 Jan 19 18:59:10 volumio volumio[1257]: info: ALBUMART /albumart?cacheid=639&web=Gotan%20Project/Lunatico/extralarge&path=%2Fmnt%2FNAS%2FAndere%40server%2FGotan.Project-Lunatico%5Bwww.todocvcd.com%5D%2F04%20-%20Celos.mp3&metadata=false Jan 19 18:59:10 volumio volumio[1257]: info: URI /mnt/NAS/Andere@server/Gotan.Project-Lunatico[www.todocvcd.com]/04 - Celos.mp3 Jan 19 18:59:10 volumio volumio[1257]: info: ALBUMART /albumart?cacheid=639&web=Gotan%20Project/Lunatico/extralarge&path=%2Fmnt%2FNAS%2FAndere%40server%2FGotan.Project-Lunatico%5Bwww.todocvcd.com%5D%2F05%20-%20Lunatico.mp3&metadata=false Jan 19 18:59:10 volumio volumio[1257]: info: URI /mnt/NAS/Andere@server/Gotan.Project-Lunatico[www.todocvcd.com]/05 - Lunatico.mp3 Jan 19 18:59:10 volumio volumio[1257]: info: ALBUMART /albumart?cacheid=639&web=Gotan%20Project/Lunatico/extralarge&path=%2Fmnt%2FNAS%2FAndere%40server%2FGotan.Project-Lunatico%5Bwww.todocvcd.com%5D%2F06%20-%20Mi%20Confesion.mp3&metadata=false Jan 19 18:59:10 volumio volumio[1257]: info: URI /mnt/NAS/Andere@server/Gotan.Project-Lunatico[www.todocvcd.com]/06 - Mi Confesion.mp3 Jan 19 18:59:10 volumio volumio[1257]: info: ALBUMART /albumart?cacheid=639&web=Gotan%20Project/Lunatico/extralarge&path=%2Fmnt%2FNAS%2FAndere%40server%2FGotan.Project-Lunatico%5Bwww.todocvcd.com%5D%2F07%20-%20Tango%20Cancion.mp3&metadata=false Jan 19 18:59:10 volumio volumio[1257]: info: URI /mnt/NAS/Andere@server/Gotan.Project-Lunatico[www.todocvcd.com]/07 - Tango Cancion.mp3 Jan 19 18:59:10 volumio volumio[1257]: info: ALBUMART /albumart?cacheid=639&web=Gotan%20Project/Lunatico/extralarge&path=%2Fmnt%2FNAS%2FAndere%40server%2FGotan.Project-Lunatico%5Bwww.todocvcd.com%5D%2F08%20-%20La%20Biguela.mp3&metadata=false Jan 19 18:59:10 volumio volumio[1257]: info: URI /mnt/NAS/Andere@server/Gotan.Project-Lunatico[www.todocvcd.com]/08 - La Biguela.mp3 Jan 19 18:59:10 volumio volumio[1257]: info: ALBUMART /albumart?cacheid=639&web=Gotan%20Project/Lunatico/extralarge&path=%2Fmnt%2FNAS%2FAndere%40server%2FGotan.Project-Lunatico%5Bwww.todocvcd.com%5D%2F09%20-%20Criminal.mp3&metadata=false Jan 19 18:59:10 volumio volumio[1257]: info: URI /mnt/NAS/Andere@server/Gotan.Project-Lunatico[www.todocvcd.com]/09 - Criminal.mp3 Jan 19 18:59:10 volumio volumio[1257]: info: ALBUMART /albumart?cacheid=639&web=Gotan%20Project/Lunatico/extralarge&path=%2Fmnt%2FNAS%2FAndere%40server%2FGotan.Project-Lunatico%5Bwww.todocvcd.com%5D%2F10%20-%20Arrabal.mp3&metadata=false Jan 19 18:59:10 volumio volumio[1257]: info: URI /mnt/NAS/Andere@server/Gotan.Project-Lunatico[www.todocvcd.com]/10 - Arrabal.mp3 Jan 19 18:59:10 volumio volumio[1257]: info: ALBUMART /albumart?cacheid=639&web=Gotan%20Project/Lunatico/extralarge&path=%2Fmnt%2FNAS%2FAndere%40server%2FGotan.Project-Lunatico%5Bwww.todocvcd.com%5D%2F11%20-%20Domingo.mp3&metadata=false Jan 19 18:59:10 volumio volumio[1257]: info: URI /mnt/NAS/Andere@server/Gotan.Project-Lunatico[www.todocvcd.com]/11 - Domingo.mp3 Jan 19 18:59:10 volumio volumio[1257]: info: ALBUMART /albumart?cacheid=639&web=Gotan%20Project/Lunatico/extralarge&path=%2Fmnt%2FNAS%2FAndere%40server%2FGotan.Project-Lunatico%5Bwww.todocvcd.com%5D%2F12%20-%20Paris%20Texas.mp3&metadata=false Jan 19 18:59:10 volumio volumio[1257]: info: URI /mnt/NAS/Andere@server/Gotan.Project-Lunatico[www.todocvcd.com]/12 - Paris Texas.mp3 Jan 19 18:59:10 volumio volumio[1257]: info: sendMpdCommand stop took 42 milliseconds Jan 19 18:59:10 volumio volumio[1257]: info: ControllerMpd::getState Jan 19 18:59:10 volumio volumio[1257]: verbose: ControllerMpd::sendMpdCommand status Jan 19 18:59:10 volumio volumio[1257]: info: CoreCommandRouter::volumioPushQueue Jan 19 18:59:10 volumio volumio[1257]: info: CorePlayQueue::saveQueue Jan 19 18:59:10 volumio volumio[1257]: info: CoreStateMachine::updateTrackBlock Jan 19 18:59:10 volumio volumio[1257]: info: CorePlayQueue::getTrackBlock Jan 19 18:59:10 volumio volumio[1257]: info: CoreCommandRouter::volumioPlay Jan 19 18:59:10 volumio volumio[1257]: info: CoreStateMachine::play index 0 Jan 19 18:59:10 volumio volumio[1257]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 19 18:59:10 volumio volumio[1257]: info: CoreStateMachine::stop Jan 19 18:59:10 volumio volumio[1257]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 19 18:59:10 volumio volumio[1257]: info: CoreStateMachine::play index undefined Jan 19 18:59:10 volumio volumio[1257]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 19 18:59:10 volumio volumio[1257]: info: CorePlayQueue::getTrack 0 Jan 19 18:59:10 volumio volumio[1257]: info: CoreStateMachine::startPlaybackTimer Jan 19 18:59:10 volumio volumio[1257]: info: CorePlayQueue::getTrack 0 Jan 19 18:59:10 volumio volumio[1257]: verbose: ControllerMpd::clearAddPlayTracks NAS/Andere@server/Gotan.Project-Lunatico[www.todocvcd.com]/01 - Amor Porteno.mp3 Jan 19 18:59:10 volumio volumio[1257]: verbose: ControllerMpd::sendMpdCommand stop Jan 19 18:59:10 volumio volumio[1257]: info: sendMpdCommand status took 7 milliseconds Jan 19 18:59:10 volumio volumio[1257]: info: sendMpdCommand stop took 1 milliseconds Jan 19 18:59:10 volumio volumio[1257]: verbose: ControllerMpd::parseState Jan 19 18:59:10 volumio volumio[1257]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 19 18:59:10 volumio volumio[1257]: verbose: ControllerMpd::sendMpdCommand clear Jan 19 18:59:10 volumio volumio[1257]: info: Jan 19 18:59:10 volumio volumio[1257]: ---------------------------- MPD announces system playlist update Jan 19 18:59:10 volumio volumio[1257]: info: Ignoring MPD Status Update Jan 19 18:59:10 volumio volumio[1257]: info: sendMpdCommand playlistinfo took 0 milliseconds Jan 19 18:59:10 volumio volumio[1257]: info: sendMpdCommand clear took 0 milliseconds Jan 19 18:59:10 volumio volumio[1257]: verbose: ControllerMpd::parseTrackInfo Jan 19 18:59:10 volumio volumio[1257]: verbose: ControllerMpd::sendMpdCommand add "NAS/Andere@server/Gotan.Project-Lunatico[www.todocvcd.com]/01 - Amor Porteno.mp3" Jan 19 18:59:10 volumio volumio[1257]: info: ControllerMpd::pushState Jan 19 18:59:10 volumio volumio[1257]: info: CoreCommandRouter::servicePushState Jan 19 18:59:10 volumio volumio[1257]: info: CoreStateMachine::pushState Jan 19 18:59:10 volumio volumio[1257]: info: CorePlayQueue::getTrack 0 Jan 19 18:59:10 volumio volumio[1257]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 19 18:59:10 volumio volumio[1257]: info: CoreCommandRouter::volumioPushState Jan 19 18:59:10 volumio volumio[1257]: info: CorePlayQueue::getTrack 0 Jan 19 18:59:10 volumio volumio[1257]: 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":"Beethoven: Violin Concerto - 1. Allegro ma non troppo","artist":"Berliner Philharmoniker, Frank Peter Zimmermann, Daniel Harding","album":"Frank Peter Zimmermann: Beethoven - Berg - Bartók","uri":"NAS/Andere@server/Frank Peter Zimmermann und die Berliner Philharmoniker/Frank Peter Zimmermann - Beethoven, Berg, Bartók - stereo - FLAC 192k/01 - 01 - Berliner Philharmoniker, Frank Peter Zimmermann, Daniel Harding - Beethoven Violin Concerto - 1. Allegro ma non troppo.flac","trackType":"flac"} Jan 19 18:59:10 volumio volumio[1257]: verbose: CURRENT POSITION 0 Jan 19 18:59:10 volumio volumio[1257]: info: CoreStateMachine::syncState stateService stop Jan 19 18:59:10 volumio volumio[1257]: info: CoreStateMachine::syncState currentStatus stop Jan 19 18:59:10 volumio volumio[1257]: info: CoreStateMachine::pushState Jan 19 18:59:10 volumio volumio[1257]: info: CorePlayQueue::getTrack 0 Jan 19 18:59:10 volumio volumio[1257]: info: CoreCommandRouter::volumioPushState Jan 19 18:59:10 volumio volumio[1257]: info: No code Jan 19 18:59:10 volumio volumio[1257]: info: CoreStateMachine::pushState Jan 19 18:59:10 volumio volumio[1257]: info: CorePlayQueue::getTrack 0 Jan 19 18:59:10 volumio volumio[1257]: info: CoreCommandRouter::volumioPushState Jan 19 18:59:10 volumio volumio[1257]: info: ------------------------------ 20ms Jan 19 18:59:10 volumio volumio[1257]: error: updateQueue error: null Jan 19 18:59:10 volumio volumio[1257]: info: Jan 19 18:59:10 volumio volumio[1257]: ---------------------------- MPD announces system playlist update Jan 19 18:59:10 volumio volumio[1257]: info: Ignoring MPD Status Update Jan 19 18:59:10 volumio volumio[1257]: info: ------------------------------ 11ms Jan 19 18:59:10 volumio volumio[1257]: info: sendMpdCommand add "NAS/Andere@server/Gotan.Project-Lunatico[www.todocvcd.com]/01 - Amor Porteno.mp3" took 10 milliseconds Jan 19 18:59:10 volumio volumio[1257]: verbose: ControllerMpd::sendMpdCommand play Jan 19 18:59:10 volumio volumio[1257]: info: ------------------------------ 2ms Jan 19 18:59:10 volumio volumio[1257]: info: sendMpdCommand play took 1 milliseconds Jan 19 18:59:10 volumio volumio[1257]: info: Jan 19 18:59:10 volumio volumio[1257]: ---------------------------- MPD announces state update: player Jan 19 18:59:10 volumio volumio[1257]: info: ControllerMpd::getState Jan 19 18:59:10 volumio volumio[1257]: verbose: ControllerMpd::sendMpdCommand status Jan 19 18:59:10 volumio volumio[1257]: info: Jan 19 18:59:10 volumio volumio[1257]: ---------------------------- MPD announces state update: player Jan 19 18:59:10 volumio volumio[1257]: info: ControllerMpd::getState Jan 19 18:59:10 volumio volumio[1257]: verbose: ControllerMpd::sendMpdCommand status Jan 19 18:59:10 volumio volumio[1257]: info: sendMpdCommand status took 2 milliseconds Jan 19 18:59:10 volumio volumio[1257]: verbose: ControllerMpd::parseState Jan 19 18:59:10 volumio volumio[1257]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 19 18:59:10 volumio volumio[1257]: info: sendMpdCommand status took 2 milliseconds Jan 19 18:59:10 volumio volumio[1257]: info: sendMpdCommand playlistinfo took 1 milliseconds Jan 19 18:59:10 volumio volumio[1257]: verbose: ControllerMpd::parseState Jan 19 18:59:10 volumio volumio[1257]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 19 18:59:10 volumio volumio[1257]: verbose: ControllerMpd::parseTrackInfo Jan 19 18:59:10 volumio volumio[1257]: info: ControllerMpd::pushState Jan 19 18:59:10 volumio volumio[1257]: info: CoreCommandRouter::servicePushState Jan 19 18:59:10 volumio volumio[1257]: info: CorePlayQueue::getTrack 0 Jan 19 18:59:10 volumio volumio[1257]: verbose: STATE SERVICE {"status":"play","position":0,"seek":982,"duration":307,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Amor Porteno","artist":"Gotan Project","album":"Lunatico","uri":"NAS/Andere@server/Gotan.Project-Lunatico[www.todocvcd.com]/01 - Amor Porteno.mp3","trackType":"mp3"} Jan 19 18:59:10 volumio volumio[1257]: verbose: CURRENT POSITION 0 Jan 19 18:59:10 volumio volumio[1257]: info: CoreStateMachine::syncState stateService play Jan 19 18:59:10 volumio volumio[1257]: info: CoreStateMachine::syncState currentStatus stop Jan 19 18:59:10 volumio volumio[1257]: info: ------------------------------ 4ms Jan 19 18:59:10 volumio volumio[1257]: info: sendMpdCommand playlistinfo took 1 milliseconds Jan 19 18:59:10 volumio volumio[1257]: verbose: ControllerMpd::parseTrackInfo Jan 19 18:59:10 volumio volumio[1257]: info: ControllerMpd::pushState Jan 19 18:59:10 volumio volumio[1257]: info: CoreCommandRouter::servicePushState Jan 19 18:59:10 volumio volumio[1257]: info: CorePlayQueue::getTrack 0 Jan 19 18:59:10 volumio volumio[1257]: verbose: STATE SERVICE {"status":"play","position":0,"seek":982,"duration":307,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Amor Porteno","artist":"Gotan Project","album":"Lunatico","uri":"NAS/Andere@server/Gotan.Project-Lunatico[www.todocvcd.com]/01 - Amor Porteno.mp3","trackType":"mp3"} Jan 19 18:59:10 volumio volumio[1257]: verbose: CURRENT POSITION 0 Jan 19 18:59:10 volumio volumio[1257]: info: CoreStateMachine::syncState stateService play Jan 19 18:59:10 volumio volumio[1257]: info: CoreStateMachine::syncState currentStatus play Jan 19 18:59:10 volumio volumio[1257]: info: Received an update from plugin. extracting info from payload Jan 19 18:59:10 volumio volumio[1257]: info: CoreStateMachine::pushState Jan 19 18:59:10 volumio volumio[1257]: info: CorePlayQueue::getTrack 0 Jan 19 18:59:10 volumio volumio[1257]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 19 18:59:10 volumio volumio[1257]: info: CoreCommandRouter::volumioPushState Jan 19 18:59:10 volumio volumio[1257]: info: CoreStateMachine::pushState Jan 19 18:59:10 volumio volumio[1257]: info: CorePlayQueue::getTrack 0 Jan 19 18:59:10 volumio volumio[1257]: info: CoreCommandRouter::volumioPushState Jan 19 18:59:10 volumio volumio[1257]: info: ------------------------------ 8ms Jan 19 18:59:15 volumio volumio[1257]: info: Preload queue cleared Jan 19 18:59:15 volumio volumio[1257]: info: CoreCommandRouter::volumioReplaceandPlayItems Jan 19 18:59:15 volumio volumio[1257]: info: CoreStateMachine::ClearQueue Jan 19 18:59:15 volumio volumio[1257]: info: CoreStateMachine::stop Jan 19 18:59:15 volumio volumio[1257]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 19 18:59:15 volumio volumio[1257]: info: CoreStateMachine::stPlaybackTimer Jan 19 18:59:15 volumio volumio[1257]: info: CoreStateMachine::updateTrackBlock Jan 19 18:59:15 volumio volumio[1257]: info: CorePlayQueue::getTrackBlock Jan 19 18:59:15 volumio volumio[1257]: info: CoreStateMachine::pushState Jan 19 18:59:15 volumio volumio[1257]: info: CorePlayQueue::getTrack 0 Jan 19 18:59:15 volumio volumio[1257]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 19 18:59:15 volumio volumio[1257]: info: CoreCommandRouter::volumioPushState Jan 19 18:59:15 volumio volumio[1257]: info: CoreStateMachine::serviceStop Jan 19 18:59:15 volumio volumio[1257]: info: CorePlayQueue::getTrack 0 Jan 19 18:59:15 volumio volumio[1257]: info: CoreCommandRouter::serviceStop Jan 19 18:59:15 volumio volumio[1257]: info: ControllerMpd::stop Jan 19 18:59:15 volumio volumio[1257]: verbose: ControllerMpd::sendMpdCommand stop Jan 19 18:59:15 volumio volumio[1257]: info: CorePlayQueue::clearPlayQueue Jan 19 18:59:15 volumio volumio[1257]: info: CorePlayQueue::saveQueue Jan 19 18:59:15 volumio volumio[1257]: info: CoreCommandRouter::volumioPushQueue Jan 19 18:59:15 volumio volumio[1257]: info: CoreStateMachine::addQueueItems Jan 19 18:59:15 volumio volumio[1257]: info: CorePlayQueue::addQueueItems Jan 19 18:59:15 volumio volumio[1257]: info: Preload queue cleared Jan 19 18:59:15 volumio volumio[1257]: info: Adding Item to queue: music-library/NAS/Andere@server/Gotan.Project-Lunatico[www.todocvcd.com] Jan 19 18:59:15 volumio volumio[1257]: info: Using cached record of: music-library/NAS/Andere@server/Gotan.Project-Lunatico[www.todocvcd.com] Jan 19 18:59:15 volumio volumio[1257]: info: CoreCommandRouter::volumioPushQueue Jan 19 18:59:15 volumio volumio[1257]: info: CorePlayQueue::saveQueue Jan 19 18:59:15 volumio volumio[1257]: info: CoreStateMachine::updateTrackBlock Jan 19 18:59:15 volumio volumio[1257]: info: CorePlayQueue::getTrackBlock Jan 19 18:59:15 volumio volumio[1257]: info: CoreCommandRouter::volumioPlay Jan 19 18:59:15 volumio volumio[1257]: info: CoreStateMachine::play index 0 Jan 19 18:59:15 volumio volumio[1257]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 19 18:59:15 volumio volumio[1257]: info: CoreStateMachine::stop Jan 19 18:59:15 volumio volumio[1257]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 19 18:59:15 volumio volumio[1257]: info: CoreStateMachine::play index undefined Jan 19 18:59:15 volumio volumio[1257]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 19 18:59:15 volumio volumio[1257]: info: CorePlayQueue::getTrack 0 Jan 19 18:59:15 volumio volumio[1257]: info: CoreStateMachine::startPlaybackTimer Jan 19 18:59:15 volumio volumio[1257]: info: CorePlayQueue::getTrack 0 Jan 19 18:59:15 volumio volumio[1257]: verbose: ControllerMpd::clearAddPlayTracks NAS/Andere@server/Gotan.Project-Lunatico[www.todocvcd.com]/01 - Amor Porteno.mp3 Jan 19 18:59:15 volumio volumio[1257]: verbose: ControllerMpd::sendMpdCommand stop Jan 19 18:59:15 volumio volumio[1257]: info: Jan 19 18:59:15 volumio volumio[1257]: ---------------------------- MPD announces state update: player Jan 19 18:59:15 volumio volumio[1257]: info: sendMpdCommand stop took 6 milliseconds Jan 19 18:59:15 volumio volumio[1257]: info: ControllerMpd::getState Jan 19 18:59:15 volumio volumio[1257]: verbose: ControllerMpd::sendMpdCommand status Jan 19 18:59:15 volumio volumio[1257]: info: sendMpdCommand stop took 1 milliseconds Jan 19 18:59:15 volumio volumio[1257]: verbose: ControllerMpd::sendMpdCommand clear Jan 19 18:59:15 volumio volumio[1257]: info: Jan 19 18:59:15 volumio volumio[1257]: ---------------------------- MPD announces system playlist update Jan 19 18:59:15 volumio volumio[1257]: info: Ignoring MPD Status Update Jan 19 18:59:15 volumio volumio[1257]: info: sendMpdCommand status took 1 milliseconds Jan 19 18:59:15 volumio volumio[1257]: info: sendMpdCommand clear took 1 milliseconds Jan 19 18:59:15 volumio volumio[1257]: verbose: ControllerMpd::parseState Jan 19 18:59:15 volumio volumio[1257]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 19 18:59:15 volumio volumio[1257]: verbose: ControllerMpd::sendMpdCommand add "NAS/Andere@server/Gotan.Project-Lunatico[www.todocvcd.com]/01 - Amor Porteno.mp3" Jan 19 18:59:15 volumio volumio[1257]: error: updateQueue error: null Jan 19 18:59:15 volumio volumio[1257]: info: Jan 19 18:59:15 volumio volumio[1257]: ---------------------------- MPD announces system playlist update Jan 19 18:59:15 volumio volumio[1257]: info: Ignoring MPD Status Update Jan 19 18:59:15 volumio volumio[1257]: info: ------------------------------ 1ms Jan 19 18:59:15 volumio volumio[1257]: info: sendMpdCommand playlistinfo took 0 milliseconds Jan 19 18:59:15 volumio volumio[1257]: info: sendMpdCommand add "NAS/Andere@server/Gotan.Project-Lunatico[www.todocvcd.com]/01 - Amor Porteno.mp3" took 0 milliseconds Jan 19 18:59:15 volumio volumio[1257]: verbose: ControllerMpd::parseTrackInfo Jan 19 18:59:15 volumio volumio[1257]: verbose: ControllerMpd::sendMpdCommand play Jan 19 18:59:15 volumio volumio[1257]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jan 19 18:59:15 volumio volumio[1257]: info: ------------------------------ 2ms Jan 19 18:59:15 volumio volumio[1257]: info: ------------------------------ 1ms Jan 19 18:59:15 volumio volumio[1257]: info: sendMpdCommand play took 0 milliseconds Jan 19 18:59:15 volumio volumio[1257]: info: Jan 19 18:59:15 volumio volumio[1257]: ---------------------------- MPD announces state update: player Jan 19 18:59:15 volumio volumio[1257]: info: ControllerMpd::getState Jan 19 18:59:15 volumio volumio[1257]: verbose: ControllerMpd::sendMpdCommand status Jan 19 18:59:15 volumio volumio[1257]: info: Jan 19 18:59:15 volumio volumio[1257]: ---------------------------- MPD announces state update: player Jan 19 18:59:15 volumio volumio[1257]: info: sendMpdCommand status took 0 milliseconds Jan 19 18:59:15 volumio volumio[1257]: info: ControllerMpd::getState Jan 19 18:59:15 volumio volumio[1257]: verbose: ControllerMpd::sendMpdCommand status Jan 19 18:59:15 volumio volumio[1257]: verbose: ControllerMpd::parseState Jan 19 18:59:15 volumio volumio[1257]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 19 18:59:15 volumio volumio[1257]: info: sendMpdCommand status took 1 milliseconds Jan 19 18:59:15 volumio volumio[1257]: info: sendMpdCommand playlistinfo took 1 milliseconds Jan 19 18:59:15 volumio volumio[1257]: verbose: ControllerMpd::parseState Jan 19 18:59:15 volumio volumio[1257]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 19 18:59:15 volumio volumio[1257]: verbose: ControllerMpd::parseTrackInfo Jan 19 18:59:15 volumio volumio[1257]: info: ControllerMpd::pushState Jan 19 18:59:15 volumio volumio[1257]: info: CoreCommandRouter::servicePushState Jan 19 18:59:15 volumio volumio[1257]: info: CorePlayQueue::getTrack 0 Jan 19 18:59:15 volumio volumio[1257]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":307,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Amor Porteno","artist":"Gotan Project","album":"Lunatico","uri":"NAS/Andere@server/Gotan.Project-Lunatico[www.todocvcd.com]/01 - Amor Porteno.mp3","trackType":"mp3"} Jan 19 18:59:15 volumio volumio[1257]: verbose: CURRENT POSITION 0 Jan 19 18:59:15 volumio volumio[1257]: info: CoreStateMachine::syncState stateService play Jan 19 18:59:15 volumio volumio[1257]: info: CoreStateMachine::syncState currentStatus stop Jan 19 18:59:15 volumio volumio[1257]: info: ------------------------------ 2ms Jan 19 18:59:15 volumio volumio[1257]: info: sendMpdCommand playlistinfo took 0 milliseconds Jan 19 18:59:15 volumio volumio[1257]: verbose: ControllerMpd::parseTrackInfo Jan 19 18:59:15 volumio volumio[1257]: info: ControllerMpd::pushState Jan 19 18:59:15 volumio volumio[1257]: info: CoreCommandRouter::servicePushState Jan 19 18:59:15 volumio volumio[1257]: info: CorePlayQueue::getTrack 0 Jan 19 18:59:15 volumio volumio[1257]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":307,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Amor Porteno","artist":"Gotan Project","album":"Lunatico","uri":"NAS/Andere@server/Gotan.Project-Lunatico[www.todocvcd.com]/01 - Amor Porteno.mp3","trackType":"mp3"} Jan 19 18:59:15 volumio volumio[1257]: verbose: CURRENT POSITION 0 Jan 19 18:59:15 volumio volumio[1257]: info: CoreStateMachine::syncState stateService play Jan 19 18:59:15 volumio volumio[1257]: info: CoreStateMachine::syncState currentStatus play Jan 19 18:59:15 volumio volumio[1257]: info: Received an update from plugin. extracting info from payload Jan 19 18:59:15 volumio volumio[1257]: info: CoreStateMachine::pushState Jan 19 18:59:15 volumio volumio[1257]: info: CorePlayQueue::getTrack 0 Jan 19 18:59:15 volumio volumio[1257]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 19 18:59:15 volumio volumio[1257]: info: CoreCommandRouter::volumioPushState Jan 19 18:59:15 volumio volumio[1257]: info: CoreStateMachine::pushState Jan 19 18:59:15 volumio volumio[1257]: info: CorePlayQueue::getTrack 0 Jan 19 18:59:15 volumio volumio[1257]: info: CoreCommandRouter::volumioPushState Jan 19 18:59:15 volumio volumio[1257]: info: ------------------------------ 9ms Jan 19 18:59:18 volumio volumio[1257]: info: CoreCommandRouter::volumioPause Jan 19 18:59:18 volumio volumio[1257]: info: CoreStateMachine::pause Jan 19 18:59:18 volumio volumio[1257]: info: CoreStateMachine::stPlaybackTimer Jan 19 18:59:18 volumio volumio[1257]: info: CoreStateMachine::servicePause Jan 19 18:59:18 volumio volumio[1257]: info: CorePlayQueue::getTrack 0 Jan 19 18:59:18 volumio volumio[1257]: info: CoreCommandRouter::servicePause Jan 19 18:59:18 volumio volumio[1257]: info: ControllerMpd::pause Jan 19 18:59:18 volumio volumio[1257]: verbose: ControllerMpd::sendMpdCommand pause Jan 19 18:59:18 volumio volumio[1257]: info: Jan 19 18:59:18 volumio volumio[1257]: ---------------------------- MPD announces state update: player Jan 19 18:59:18 volumio volumio[1257]: info: sendMpdCommand pause took 0 milliseconds Jan 19 18:59:18 volumio volumio[1257]: info: ControllerMpd::getState Jan 19 18:59:18 volumio volumio[1257]: verbose: ControllerMpd::sendMpdCommand status Jan 19 18:59:18 volumio volumio[1257]: info: sendMpdCommand status took 0 milliseconds Jan 19 18:59:18 volumio volumio[1257]: verbose: ControllerMpd::parseState Jan 19 18:59:18 volumio volumio[1257]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 19 18:59:18 volumio volumio[1257]: info: sendMpdCommand playlistinfo took 0 milliseconds Jan 19 18:59:18 volumio volumio[1257]: verbose: ControllerMpd::parseTrackInfo Jan 19 18:59:18 volumio volumio[1257]: info: ControllerMpd::pushState Jan 19 18:59:18 volumio volumio[1257]: info: CoreCommandRouter::servicePushState Jan 19 18:59:18 volumio volumio[1257]: info: CorePlayQueue::getTrack 0 Jan 19 18:59:18 volumio volumio[1257]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":3680,"duration":307,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Amor Porteno","artist":"Gotan Project","album":"Lunatico","uri":"NAS/Andere@server/Gotan.Project-Lunatico[www.todocvcd.com]/01 - Amor Porteno.mp3","trackType":"mp3"} Jan 19 18:59:18 volumio volumio[1257]: verbose: CURRENT POSITION 0 Jan 19 18:59:18 volumio volumio[1257]: info: CoreStateMachine::syncState stateService pause Jan 19 18:59:18 volumio volumio[1257]: info: CoreStateMachine::syncState currentStatus pause Jan 19 18:59:18 volumio volumio[1257]: info: CoreStateMachine::pushState Jan 19 18:59:18 volumio volumio[1257]: info: CorePlayQueue::getTrack 0 Jan 19 18:59:18 volumio volumio[1257]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 19 18:59:18 volumio volumio[1257]: info: CoreCommandRouter::volumioPushState Jan 19 18:59:18 volumio volumio[1257]: info: CoreStateMachine::stPlaybackTimer Jan 19 18:59:18 volumio volumio[1257]: info: ------------------------------ 5ms Jan 19 18:59:22 volumio volumio[1257]: info: Preload queue cleared Jan 19 18:59:22 volumio volumio[1257]: info: CoreCommandRouter::volumioReplaceandPlayItems Jan 19 18:59:22 volumio volumio[1257]: info: CoreStateMachine::ClearQueue Jan 19 18:59:22 volumio volumio[1257]: info: CoreStateMachine::stop Jan 19 18:59:22 volumio volumio[1257]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 19 18:59:22 volumio volumio[1257]: info: CoreStateMachine::updateTrackBlock Jan 19 18:59:22 volumio volumio[1257]: info: CorePlayQueue::getTrackBlock Jan 19 18:59:22 volumio volumio[1257]: info: CoreStateMachine::stPlaybackTimer Jan 19 18:59:22 volumio volumio[1257]: info: CoreStateMachine::pushState Jan 19 18:59:22 volumio volumio[1257]: info: CorePlayQueue::getTrack 0 Jan 19 18:59:22 volumio volumio[1257]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 19 18:59:22 volumio volumio[1257]: info: CoreCommandRouter::volumioPushState Jan 19 18:59:22 volumio volumio[1257]: info: CoreStateMachine::serviceStop Jan 19 18:59:22 volumio volumio[1257]: info: CorePlayQueue::getTrack 0 Jan 19 18:59:22 volumio volumio[1257]: info: CoreCommandRouter::serviceStop Jan 19 18:59:22 volumio volumio[1257]: info: ControllerMpd::stop Jan 19 18:59:22 volumio volumio[1257]: verbose: ControllerMpd::sendMpdCommand stop Jan 19 18:59:22 volumio volumio[1257]: info: CorePlayQueue::clearPlayQueue Jan 19 18:59:22 volumio volumio[1257]: info: CorePlayQueue::saveQueue Jan 19 18:59:22 volumio volumio[1257]: info: CoreCommandRouter::volumioPushQueue Jan 19 18:59:22 volumio volumio[1257]: info: CoreStateMachine::addQueueItems Jan 19 18:59:22 volumio volumio[1257]: info: CorePlayQueue::addQueueItems Jan 19 18:59:22 volumio volumio[1257]: info: Preload queue cleared Jan 19 18:59:22 volumio volumio[1257]: info: Adding Item to queue: music-library/NAS/Andere@server/Ständchen.mp3 Jan 19 18:59:22 volumio volumio[1257]: info: Using cached record of: music-library/NAS/Andere@server/Ständchen.mp3 Jan 19 18:59:22 volumio volumio[1257]: info: Adding Item to queue: music-library/NAS/Andere@server/08 Laudes Organi.mp3 Jan 19 18:59:22 volumio volumio[1257]: info: Using cached record of: music-library/NAS/Andere@server/08 Laudes Organi.mp3 Jan 19 18:59:22 volumio volumio[1257]: info: CoreCommandRouter::volumioPushQueue Jan 19 18:59:22 volumio volumio[1257]: info: CorePlayQueue::saveQueue Jan 19 18:59:22 volumio volumio[1257]: info: CoreStateMachine::updateTrackBlock Jan 19 18:59:22 volumio volumio[1257]: info: CorePlayQueue::getTrackBlock Jan 19 18:59:22 volumio volumio[1257]: info: CoreCommandRouter::volumioPlay Jan 19 18:59:22 volumio volumio[1257]: info: CoreStateMachine::play index 1 Jan 19 18:59:22 volumio volumio[1257]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 19 18:59:22 volumio volumio[1257]: info: CoreStateMachine::addQueueItems Jan 19 18:59:22 volumio volumio[1257]: info: CorePlayQueue::addQueueItems Jan 19 18:59:22 volumio volumio[1257]: info: Preload queue cleared Jan 19 18:59:22 volumio volumio[1257]: info: Adding Item to queue: music-library/NAS/Andere@server/2015-0613_S92_aac.zip Jan 19 18:59:22 volumio volumio[1257]: info: Exploding uri music-library/NAS/Andere@server/2015-0613_S92_aac.zip in service mpd Jan 19 18:59:22 volumio volumio[1257]: info: Adding Item to queue: music-library/NAS/Andere@server/Anna Netrebko - Violetta @320kbps Jan 19 18:59:22 volumio volumio[1257]: info: Exploding uri music-library/NAS/Andere@server/Anna Netrebko - Violetta @320kbps in service mpd Jan 19 18:59:22 volumio volumio[1257]: info: Adding Item to queue: music-library/NAS/Andere@server/Frank Peter Zimmermann und die Berliner Philharmoniker Jan 19 18:59:22 volumio volumio[1257]: info: Exploding uri music-library/NAS/Andere@server/Frank Peter Zimmermann und die Berliner Philharmoniker in service mpd Jan 19 18:59:22 volumio volumio[1257]: info: Adding Item to queue: music-library/NAS/Andere@server/Gotan.Project-La.Revancha.Del.Tango[www.todocvcd.com] Jan 19 18:59:22 volumio volumio[1257]: info: Exploding uri music-library/NAS/Andere@server/Gotan.Project-La.Revancha.Del.Tango[www.todocvcd.com] in service mpd Jan 19 18:59:22 volumio volumio[1257]: info: Adding Item to queue: music-library/NAS/Andere@server/Gotan.Project-Lunatico[www.todocvcd.com] Jan 19 18:59:22 volumio volumio[1257]: info: Using cached record of: music-library/NAS/Andere@server/Gotan.Project-Lunatico[www.todocvcd.com] Jan 19 18:59:22 volumio volumio[1257]: info: CoreStateMachine::stop Jan 19 18:59:22 volumio volumio[1257]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 19 18:59:22 volumio volumio[1257]: info: CoreStateMachine::play index undefined Jan 19 18:59:22 volumio volumio[1257]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 19 18:59:22 volumio volumio[1257]: info: CorePlayQueue::getTrack 1 Jan 19 18:59:22 volumio volumio[1257]: info: CoreStateMachine::startPlaybackTimer Jan 19 18:59:22 volumio volumio[1257]: info: CorePlayQueue::getTrack 1 Jan 19 18:59:22 volumio volumio[1257]: verbose: ControllerMpd::clearAddPlayTracks NAS/Andere@server/08 Laudes Organi.mp3 Jan 19 18:59:22 volumio volumio[1257]: verbose: ControllerMpd::sendMpdCommand stop Jan 19 18:59:22 volumio volumio[1257]: info: Jan 19 18:59:22 volumio volumio[1257]: ---------------------------- MPD announces state update: player Jan 19 18:59:22 volumio volumio[1257]: info: ALBUMART /albumart?cacheid=639&web=Ji-Yeoun%20You%3B%20Sinfonietta92%3B%20Catherine%20Larsen-Maguire/Sinfonietta92%20Konzert%2013.06.2015/extralarge&path=%2Fmnt%2FNAS%2FAndere%40server%2F2015-0613_S92_aac.zip&metadata=false Jan 19 18:59:22 volumio volumio[1257]: info: URI /mnt/NAS/Andere@server/2015-0613_S92_aac.zip Jan 19 18:59:22 volumio volumio[1257]: info: ALBUMART /albumart?cacheid=639&web=Ji-Yeoun%20You%3B%20Sinfonietta92%3B%20Catherine%20Larsen-Maguire/Sinfonietta92%20Konzert%2013.06.2015/extralarge&path=%2Fmnt%2FNAS%2FAndere%40server%2F2015-0613_S92_aac.zip&metadata=false Jan 19 18:59:22 volumio volumio[1257]: info: URI /mnt/NAS/Andere@server/2015-0613_S92_aac.zip Jan 19 18:59:22 volumio volumio[1257]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 19 18:59:22 volumio volumio[1257]: Error: Unable to resolve or reject the same promise twice Jan 19 18:59:22 volumio volumio[1257]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Jan 19 18:59:22 volumio volumio[1257]: at /volumio/app/plugins/music_service/mpd/index.js:2569:21 Jan 19 18:59:22 volumio volumio[1257]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) Jan 19 18:59:22 volumio volumio[1257]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) Jan 19 18:59:22 volumio volumio[1257]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) Jan 19 18:59:22 volumio volumio[1257]: at Socket.emit (events.js:315:20) Jan 19 18:59:22 volumio volumio[1257]: at addChunk (internal/streams/readable.js:309:12) Jan 19 18:59:22 volumio volumio[1257]: at readableAddChunk (internal/streams/readable.js:280:11) Jan 19 18:59:22 volumio volumio[1257]: at Socket.Readable.push (internal/streams/readable.js:223:10) Jan 19 18:59:22 volumio volumio[1257]: at Pipe.onStreamRead (internal/stream_base_commons.js:188:23) Jan 19 18:59:22 volumio volumio[1257]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 19 18:59:22 volumio sudo[5134]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-01-19 18:58 Jan 19 18:59:22 volumio sudo[5134]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="f6610a1100d2380eef33bd3004af43153c786f96" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="1c0e5daee99fe3a72cc38b0d1fe6cd78d7f001fe" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 16 Dec 2024 09:30:04 AM CET" VOLUMIO_VERSION="3.785" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fe0ac4b79aed19ccde4b5bd83d468193"