-- Logs begin at Sat 2025-05-31 11:05:59 CST, end at Sat 2025-05-31 11:12:38 CST. -- May 31 11:11:01 volumio volumio[1127]: info: CoreCommandRouter::getUIConfigOnPlugin May 31 11:11:01 volumio volumio[1127]: No protocol specified May 31 11:11:01 volumio volumio[1127]: xcb_connection_has_error() returned true May 31 11:11:01 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions May 31 11:11:01 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 11:11:01 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 31 11:11:01 volumio volumio[1127]: No protocol specified May 31 11:11:01 volumio volumio[1127]: xcb_connection_has_error() returned true May 31 11:11:01 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 31 11:11:01 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 31 11:11:01 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 31 11:11:01 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 31 11:11:01 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 31 11:11:01 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 31 11:11:01 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 31 11:11:01 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode May 31 11:11:01 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 31 11:11:03 volumio volumio[1127]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] May 31 11:11:03 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions May 31 11:11:03 volumio volumio[1127]: info: Preparing to save Alsa Options, stopping services first May 31 11:11:03 volumio volumio[1127]: info: CoreCommandRouter::volumioGetState May 31 11:11:03 volumio volumio[1127]: info: CorePlayQueue::getTrack 0 May 31 11:11:03 volumio volumio[1127]: info: CoreCommandRouter::volumioPause May 31 11:11:03 volumio volumio[1127]: info: CoreStateMachine::pause May 31 11:11:03 volumio volumio[1127]: info: CoreStateMachine::stPlaybackTimer May 31 11:11:03 volumio volumio[1127]: info: CoreStateMachine::servicePause May 31 11:11:03 volumio volumio[1127]: info: CorePlayQueue::getTrack 0 May 31 11:11:03 volumio volumio[1127]: info: CoreCommandRouter::servicePause May 31 11:11:03 volumio volumio[1127]: info: ControllerMpd::pause May 31 11:11:03 volumio volumio[1127]: verbose: ControllerMpd::sendMpdCommand pause May 31 11:11:03 volumio volumio[1127]: info: Saving Audio Output to: {"output_device":{"value":"5","label":"DAC USB Audio"}} May 31 11:11:03 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 31 11:11:03 volumio volumio[1127]: No protocol specified May 31 11:11:03 volumio volumio[1127]: xcb_connection_has_error() returned true May 31 11:11:03 volumio volumio[1127]: No protocol specified May 31 11:11:03 volumio volumio[1127]: xcb_connection_has_error() returned true May 31 11:11:03 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 31 11:11:03 volumio volumio[1127]: No protocol specified May 31 11:11:03 volumio volumio[1127]: xcb_connection_has_error() returned true May 31 11:11:03 volumio volumio[1127]: info: Setting default mixerSoftMaster for Softvolume device May 31 11:11:03 volumio vtcs[2927]: [2025-05-31 11:11:03.952] [tisoc] [error] [SpkconServer.cpp:375] recv error. socket disconnected May 31 11:11:03 volumio vtcs[2927]: [2025-05-31 11:11:03.952] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE May 31 11:11:03 volumio sudo[3229]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 31 11:11:03 volumio sudo[3229]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 11:11:03 volumio systemd[1]: Stopping Volumio Tidal Connect Service... May 31 11:11:03 volumio systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM May 31 11:11:03 volumio systemd[1]: vtcs.service: Succeeded. May 31 11:11:03 volumio systemd[1]: Stopped Volumio Tidal Connect Service. May 31 11:11:03 volumio sudo[3229]: pam_unix(sudo:session): session closed for user root May 31 11:11:03 volumio volumio[1127]: No protocol specified May 31 11:11:03 volumio volumio[1127]: xcb_connection_has_error() returned true May 31 11:11:03 volumio volumio[1127]: info: CoreCommandRouter::volumioUpdateVolumeSettings May 31 11:11:03 volumio volumio[1127]: info: Updating Volume Controller Parameters: Device: 5 Name: softvolume Mixer: SoftMaster Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 May 31 11:11:03 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume May 31 11:11:03 volumio volumio[1127]: info: Disabling external Volume Control May 31 11:11:03 volumio volumio[1127]: info: CoreCommandRouter::getUIConfigOnPlugin May 31 11:11:03 volumio volumio[1127]: info: Enable softmixer device for audio device undefined May 31 11:11:03 volumio volumio[1127]: ------------------------------------ BT MESSAGE: BT STATUS: running May 31 11:11:03 volumio volumio[1127]: info: May 31 11:11:03 volumio volumio[1127]: ---------------------------- MPD announces state update: player May 31 11:11:03 volumio volumio[1127]: info: sendMpdCommand pause took 73 milliseconds May 31 11:11:03 volumio volumio[1127]: info: ControllerMpd::getState May 31 11:11:03 volumio volumio[1127]: verbose: ControllerMpd::sendMpdCommand status May 31 11:11:03 volumio volumio[1127]: info: CoreStateMachine::pushState May 31 11:11:03 volumio volumio[1127]: info: CorePlayQueue::getTrack 0 May 31 11:11:03 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 11:11:03 volumio volumio[1127]: info: CoreCommandRouter::volumioPushState May 31 11:11:03 volumio sudo[3242]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 31 11:11:03 volumio sudo[3242]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 11:11:03 volumio volumio[1127]: info: MRS: Pushing multiroomSync output update for this device May 31 11:11:03 volumio volumio[1127]: info: MRS: Pushing multiroomSync output May 31 11:11:03 volumio sudo[3242]: pam_unix(sudo:session): session closed for user root May 31 11:11:03 volumio volumio[1127]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: No protocol specified May 31 11:11:03 volumio volumio[1127]: xcb_connection_has_error() returned true May 31 11:11:04 volumio volumio[1127]: info: sendMpdCommand status took 9 milliseconds May 31 11:11:04 volumio volumio[1127]: verbose: ControllerMpd::parseState May 31 11:11:04 volumio volumio[1127]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 11:11:04 volumio volumio[1127]: info: Software Volume ALSA configuration written May 31 11:11:04 volumio volumio[1127]: info: Preparing to generate the ALSA configuration file May 31 11:11:04 volumio volumio[1127]: info: sendMpdCommand playlistinfo took 6 milliseconds May 31 11:11:04 volumio volumio[1127]: verbose: ControllerMpd::parseTrackInfo May 31 11:11:04 volumio volumio[1127]: info: ControllerMpd::pushState May 31 11:11:04 volumio volumio[1127]: info: CoreCommandRouter::servicePushState May 31 11:11:04 volumio volumio[1127]: info: CorePlayQueue::getTrack 0 May 31 11:11:04 volumio volumio[1127]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":14866,"duration":264,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"4615 Kbps","isStreaming":false,"title":"oblivious","artist":"Kalafina","album":"Kalafina All Time Best 2008-2018","uri":"USB/Music/ACG/Kalafina - Kalafina All Time Best 2008-2018 (FLAC 24bit／96kHz)/01. oblivious.flac","trackType":"flac"} May 31 11:11:04 volumio volumio[1127]: verbose: CURRENT POSITION 0 May 31 11:11:04 volumio volumio[1127]: info: CoreStateMachine::syncState stateService pause May 31 11:11:04 volumio volumio[1127]: info: CoreStateMachine::syncState currentStatus pause May 31 11:11:04 volumio volumio[1127]: info: CoreStateMachine::pushState May 31 11:11:04 volumio volumio[1127]: info: CorePlayQueue::getTrack 0 May 31 11:11:04 volumio volumio[1127]: info: CoreCommandRouter::volumioPushState May 31 11:11:04 volumio volumio[1127]: info: MRS: Pushing multiroomSync output update for this device May 31 11:11:04 volumio volumio[1127]: info: MRS: Pushing multiroomSync output May 31 11:11:04 volumio volumio[1127]: info: CoreStateMachine::stPlaybackTimer May 31 11:11:04 volumio volumio[1127]: info: ------------------------------ 20ms May 31 11:11:04 volumio volumio[1127]: No protocol specified May 31 11:11:04 volumio volumio[1127]: xcb_connection_has_error() returned true May 31 11:11:04 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions May 31 11:11:04 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 11:11:04 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 31 11:11:04 volumio volumio[1127]: No protocol specified May 31 11:11:04 volumio volumio[1127]: xcb_connection_has_error() returned true May 31 11:11:04 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 31 11:11:04 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 31 11:11:04 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 31 11:11:04 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 31 11:11:04 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 31 11:11:04 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 31 11:11:04 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 31 11:11:04 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode May 31 11:11:04 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 31 11:11:04 volumio volumio[1127]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf May 31 11:11:04 volumio volumio[1127]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf May 31 11:11:04 volumio volumio[1127]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf May 31 11:11:04 volumio volumio[1127]: info: Reading ALSA contributions from plugins. May 31 11:11:04 volumio volumio[1127]: info: Asound.conf file written May 31 11:11:04 volumio sudo[3250]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf May 31 11:11:04 volumio sudo[3250]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 11:11:04 volumio sudo[3250]: pam_unix(sudo:session): session closed for user root May 31 11:11:04 volumio volumio[1127]: No protocol specified May 31 11:11:04 volumio volumio[1127]: xcb_connection_has_error() returned true May 31 11:11:04 volumio volumio[1127]: Found hardware: "HDA-Intel" "Conexant CX20632" "HDA:14f15098,103c83ef,00100100 HDA:8086280b,80860101,00100000" "0x103c" "0x83ef" May 31 11:11:04 volumio volumio[1127]: Hardware is initialized using a generic method May 31 11:11:04 volumio volumio[1127]: info: Output device has changed, restarting MPD May 31 11:11:04 volumio sudo[3255]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 31 11:11:04 volumio sudo[3255]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 11:11:04 volumio volumio[1127]: info: Output device has changed, restarting Shairport Sync May 31 11:11:04 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 11:11:04 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 11:11:04 volumio sudo[3255]: pam_unix(sudo:session): session closed for user root May 31 11:11:04 volumio sudo[3258]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 31 11:11:04 volumio sudo[3258]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 11:11:04 volumio systemd[1]: Stopping Music Player Daemon... May 31 11:11:04 volumio volumio[1127]: No protocol specified May 31 11:11:04 volumio volumio[1127]: xcb_connection_has_error() returned true May 31 11:11:04 volumio volumio[1127]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 31 11:11:04 volumio volumio[1127]: No protocol specified May 31 11:11:04 volumio volumio[1127]: xcb_connection_has_error() returned true May 31 11:11:04 volumio volumio[1127]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration May 31 11:11:04 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 11:11:04 volumio systemd[1]: mpd.service: Succeeded. May 31 11:11:04 volumio systemd[1]: Stopped Music Player Daemon. May 31 11:11:04 volumio volumio[1127]: info: Output device has changed, restarting MPD May 31 11:11:04 volumio sudo[3267]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 31 11:11:04 volumio sudo[3267]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 11:11:04 volumio systemd[1]: Starting Music Player Daemon... May 31 11:11:04 volumio sudo[3270]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 31 11:11:04 volumio sudo[3270]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 11:11:04 volumio sudo[3267]: pam_unix(sudo:session): session closed for user root May 31 11:11:04 volumio volumio[1127]: info: Output device has changed, restarting Shairport Sync May 31 11:11:04 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 11:11:04 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 11:11:04 volumio sudo[3270]: pam_unix(sudo:session): session closed for user root May 31 11:11:04 volumio sudo[3273]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 31 11:11:04 volumio sudo[3273]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 11:11:04 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM May 31 11:11:04 volumio systemd[1]: mpd.service: Succeeded. May 31 11:11:04 volumio systemd[1]: Stopped Music Player Daemon. May 31 11:11:04 volumio systemd[1]: Starting Music Player Daemon... May 31 11:11:04 volumio volumio[1127]: No protocol specified May 31 11:11:04 volumio volumio[1127]: xcb_connection_has_error() returned true May 31 11:11:04 volumio volumio[1127]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 31 11:11:04 volumio volumio[1127]: No protocol specified May 31 11:11:04 volumio volumio[1127]: xcb_connection_has_error() returned true May 31 11:11:04 volumio volumio[1127]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration May 31 11:11:04 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 11:11:04 volumio sudo[3283]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 31 11:11:04 volumio sudo[3283]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 11:11:04 volumio sudo[3279]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 31 11:11:04 volumio sudo[3279]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 11:11:04 volumio sudo[3283]: pam_unix(sudo:session): session closed for user root May 31 11:11:04 volumio sudo[3279]: pam_unix(sudo:session): session closed for user root May 31 11:11:04 volumio sudo[3286]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 31 11:11:04 volumio sudo[3286]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 11:11:04 volumio volumio[1127]: ------------------------------------ BT MESSAGE: BT STATUS: running May 31 11:11:04 volumio volumio[1127]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 31 11:11:04 volumio volumio[1127]: ------------------------------------ BT MESSAGE: BT STATUS: running May 31 11:11:04 volumio volumio[1127]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 31 11:11:04 volumio volumio[1127]: ------------------------------------ BT MESSAGE: BT STATUS: running May 31 11:11:04 volumio volumio[1127]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 31 11:11:04 volumio volumio[1127]: ------------------------------------ BT MESSAGE: BT STATUS: running May 31 11:11:04 volumio sudo[3292]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 31 11:11:04 volumio sudo[3292]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 11:11:04 volumio sudo[3286]: pam_unix(sudo:session): session closed for user root May 31 11:11:04 volumio volumio[1127]: info: VolumeController::SetAlsaVolume0 May 31 11:11:04 volumio sudo[3292]: pam_unix(sudo:session): session closed for user root May 31 11:11:04 volumio volumio[1127]: info: CoreStateMachine::pushState May 31 11:11:04 volumio volumio[1127]: info: CorePlayQueue::getTrack 0 May 31 11:11:04 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 11:11:04 volumio volumio[1127]: info: CoreCommandRouter::volumioPushState May 31 11:11:04 volumio volumio[1127]: info: MRS: Pushing multiroomSync output update for this device May 31 11:11:04 volumio volumio[1127]: info: MRS: Pushing multiroomSync output May 31 11:11:04 volumio volumio[1127]: info: MPD Permissions set May 31 11:11:04 volumio volumio[1127]: info: MPD Permissions set May 31 11:11:04 volumio volumio[1127]: info: VolumeController::SetAlsaVolume0 May 31 11:11:04 volumio volumio[1127]: info: CoreStateMachine::pushState May 31 11:11:04 volumio volumio[1127]: info: CorePlayQueue::getTrack 0 May 31 11:11:04 volumio volumio[1127]: info: CoreCommandRouter::volumioPushState May 31 11:11:04 volumio volumio[1127]: info: MRS: Pushing multiroomSync output update for this device May 31 11:11:04 volumio volumio[1127]: info: MRS: Pushing multiroomSync output May 31 11:11:04 volumio volumio[1127]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: No protocol specified May 31 11:11:04 volumio volumio[1127]: xcb_connection_has_error() returned true May 31 11:11:04 volumio volumio[1127]: amixer: Unable to find simple control 'SoftMaster',0 May 31 11:11:04 volumio volumio[1127]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: No protocol specified May 31 11:11:04 volumio volumio[1127]: xcb_connection_has_error() returned true May 31 11:11:04 volumio volumio[1127]: amixer: Unable to find simple control 'SoftMaster',0 May 31 11:11:04 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 11:11:04 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 11:11:04 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 11:11:04 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 11:11:04 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 11:11:04 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 11:11:04 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 11:11:04 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 11:11:04 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 11:11:04 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber May 31 11:11:04 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 11:11:04 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 11:11:04 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 11:11:04 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 11:11:04 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 11:11:04 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 11:11:04 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 11:11:04 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 11:11:04 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 11:11:04 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 11:11:04 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber May 31 11:11:04 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 11:11:04 volumio volumio[1127]: info: Starting Shairport Sync May 31 11:11:04 volumio volumio[1127]: info: Starting Shairport Sync May 31 11:11:04 volumio sudo[3311]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 31 11:11:04 volumio sudo[3311]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 11:11:04 volumio volumio[1127]: info: Preparing to generate the ALSA configuration file May 31 11:11:04 volumio sudo[3314]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 31 11:11:04 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 31 11:11:04 volumio volumio[1127]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf May 31 11:11:04 volumio volumio[1127]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf May 31 11:11:04 volumio volumio[1127]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf May 31 11:11:04 volumio volumio[1127]: info: Reading ALSA contributions from plugins. May 31 11:11:04 volumio systemd[1]: shairport-sync.service: Succeeded. May 31 11:11:04 volumio sudo[3314]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 11:11:04 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 31 11:11:04 volumio volumio[1127]: ------------------------------------ BT MESSAGE: BT STATUS: running May 31 11:11:04 volumio volumio[1127]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 31 11:11:04 volumio volumio[1127]: info: Asound.conf file written May 31 11:11:04 volumio sudo[3318]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf May 31 11:11:04 volumio sudo[3318]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 11:11:04 volumio volumio[1127]: No protocol specified May 31 11:11:04 volumio volumio[1127]: xcb_connection_has_error() returned true May 31 11:11:04 volumio volumio[1127]: Found hardware: "HDA-Intel" "Conexant CX20632" "HDA:14f15098,103c83ef,00100100 HDA:8086280b,80860101,00100000" "0x103c" "0x83ef" May 31 11:11:04 volumio volumio[1127]: Hardware is initialized using a generic method May 31 11:11:04 volumio volumio[1127]: info: Output device has changed, restarting MPD May 31 11:11:04 volumio sudo[3318]: pam_unix(sudo:session): session closed for user root May 31 11:11:04 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 31 11:11:04 volumio sudo[3311]: pam_unix(sudo:session): session closed for user root May 31 11:11:04 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 31 11:11:04 volumio sudo[3323]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 31 11:11:04 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM May 31 11:11:04 volumio sudo[3323]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 11:11:04 volumio systemd[1]: shairport-sync.service: Succeeded. May 31 11:11:04 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 31 11:11:04 volumio sudo[3323]: pam_unix(sudo:session): session closed for user root May 31 11:11:04 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 31 11:11:04 volumio volumio[1127]: info: Output device has changed, restarting Shairport Sync May 31 11:11:04 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 11:11:04 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 11:11:04 volumio sudo[3327]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 31 11:11:04 volumio sudo[3327]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 11:11:04 volumio sudo[3314]: pam_unix(sudo:session): session closed for user root May 31 11:11:04 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM May 31 11:11:04 volumio systemd[1]: mpd.service: Succeeded. May 31 11:11:04 volumio systemd[1]: Stopped Music Player Daemon. May 31 11:11:04 volumio systemd[1]: Starting Music Player Daemon... May 31 11:11:04 volumio volumio[1127]: No protocol specified May 31 11:11:04 volumio volumio[1127]: xcb_connection_has_error() returned true May 31 11:11:04 volumio volumio[1127]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 31 11:11:04 volumio volumio[1127]: No protocol specified May 31 11:11:04 volumio volumio[1127]: xcb_connection_has_error() returned true May 31 11:11:04 volumio volumio[1127]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration May 31 11:11:04 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 11:11:04 volumio volumio[1127]: info: Preparing to generate the ALSA configuration file May 31 11:11:04 volumio sudo[3338]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 31 11:11:04 volumio sudo[3338]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 11:11:04 volumio volumio[1127]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf May 31 11:11:04 volumio volumio[1127]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf May 31 11:11:04 volumio volumio[1127]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf May 31 11:11:04 volumio volumio[1127]: info: Reading ALSA contributions from plugins. May 31 11:11:04 volumio sudo[3334]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 31 11:11:04 volumio sudo[3334]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 11:11:04 volumio sudo[3338]: pam_unix(sudo:session): session closed for user root May 31 11:11:04 volumio sudo[3334]: pam_unix(sudo:session): session closed for user root May 31 11:11:04 volumio volumio[1127]: ------------------------------------ BT MESSAGE: BT STATUS: running May 31 11:11:04 volumio volumio[1127]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 31 11:11:04 volumio sudo[3341]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 31 11:11:04 volumio sudo[3341]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 11:11:04 volumio volumio[1127]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 31 11:11:04 volumio volumio[1127]: ------------------------------------ BT MESSAGE: BT STATUS: running May 31 11:11:04 volumio volumio[1127]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 31 11:11:04 volumio volumio[1127]: ------------------------------------ BT MESSAGE: BT STATUS: running May 31 11:11:04 volumio volumio[1127]: info: MPD Permissions set May 31 11:11:04 volumio volumio[1127]: info: Shairport-Sync Started May 31 11:11:04 volumio volumio[1127]: info: Shairport-Sync Started May 31 11:11:04 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 11:11:04 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 11:11:04 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 11:11:04 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 11:11:04 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 11:11:04 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 11:11:04 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 11:11:04 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 11:11:04 volumio sudo[3341]: pam_unix(sudo:session): session closed for user root May 31 11:11:04 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 11:11:04 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber May 31 11:11:04 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 11:11:04 volumio volumio[1127]: info: camilladsp spawned new process with pid 3353, instance 1, run: true May 31 11:11:04 volumio volumio[1127]: info: Starting Shairport Sync May 31 11:11:04 volumio volumio[1127]: info: Asound.conf file unchanged, so no further update is needed May 31 11:11:04 volumio volumio[1127]: info: Output device has changed, restarting MPD May 31 11:11:04 volumio sudo[3361]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 31 11:11:04 volumio sudo[3361]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 11:11:04 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 31 11:11:04 volumio sudo[3364]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 31 11:11:04 volumio systemd[1]: shairport-sync.service: Succeeded. May 31 11:11:04 volumio sudo[3364]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 11:11:04 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 31 11:11:04 volumio volumio[1127]: info: Output device has changed, restarting Shairport Sync May 31 11:11:04 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 11:11:04 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 11:11:04 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 31 11:11:04 volumio sudo[3364]: pam_unix(sudo:session): session closed for user root May 31 11:11:04 volumio sudo[3361]: pam_unix(sudo:session): session closed for user root May 31 11:11:04 volumio sudo[3366]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 31 11:11:04 volumio sudo[3366]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 11:11:04 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM May 31 11:11:04 volumio systemd[1]: mpd.service: Succeeded. May 31 11:11:04 volumio systemd[1]: Stopped Music Player Daemon. May 31 11:11:04 volumio systemd[1]: Starting Music Player Daemon... May 31 11:11:04 volumio volumio[1127]: No protocol specified May 31 11:11:04 volumio volumio[1127]: xcb_connection_has_error() returned true May 31 11:11:04 volumio volumio[1127]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 31 11:11:04 volumio volumio[1127]: No protocol specified May 31 11:11:04 volumio volumio[1127]: xcb_connection_has_error() returned true May 31 11:11:04 volumio volumio[1127]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration May 31 11:11:04 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 11:11:04 volumio sudo[3379]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 31 11:11:04 volumio sudo[3379]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 11:11:04 volumio volumio[1127]: ------------------------------------ BT MESSAGE: BT STATUS: running May 31 11:11:04 volumio volumio[1127]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 31 11:11:04 volumio volumio[1127]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 31 11:11:04 volumio volumio[1127]: ------------------------------------ BT MESSAGE: BT STATUS: running May 31 11:11:04 volumio volumio[1127]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 31 11:11:04 volumio volumio[1127]: ------------------------------------ BT MESSAGE: BT STATUS: running May 31 11:11:04 volumio sudo[3382]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 31 11:11:04 volumio sudo[3382]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 11:11:04 volumio sudo[3374]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 31 11:11:04 volumio volumio[1127]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined May 31 11:11:04 volumio volumio[1127]: info: MPD Permissions set May 31 11:11:04 volumio volumio[1127]: info: Shairport-Sync Started May 31 11:11:04 volumio sudo[3374]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 11:11:04 volumio sudo[3379]: pam_unix(sudo:session): session closed for user root May 31 11:11:04 volumio sudo[3374]: pam_unix(sudo:session): session closed for user root May 31 11:11:04 volumio volumio[1127]: (node:1127) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pbeg listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit May 31 11:11:04 volumio volumio[1127]: (Use `node --trace-warnings ...` to show where the warning was created) May 31 11:11:04 volumio volumio[1127]: (node:1127) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 meta listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit May 31 11:11:04 volumio volumio[1127]: (node:1127) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 prgr listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit May 31 11:11:04 volumio volumio[1127]: (node:1127) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pvol listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit May 31 11:11:04 volumio volumio[1127]: (node:1127) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pend listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit May 31 11:11:04 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 11:11:04 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 11:11:04 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 11:11:04 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 11:11:04 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 11:11:04 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 11:11:04 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 11:11:04 volumio sudo[3382]: pam_unix(sudo:session): session closed for user root May 31 11:11:04 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 11:11:04 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber May 31 11:11:04 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 11:11:04 volumio volumio[1127]: info: Starting Shairport Sync May 31 11:11:04 volumio volumio[1127]: info: Preparing to generate the ALSA configuration file May 31 11:11:04 volumio sudo[3395]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 31 11:11:04 volumio sudo[3395]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 11:11:04 volumio volumio[1127]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf May 31 11:11:04 volumio volumio[1127]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf May 31 11:11:04 volumio volumio[1127]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf May 31 11:11:04 volumio volumio[1127]: info: Reading ALSA contributions from plugins. May 31 11:11:04 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 31 11:11:04 volumio systemd[1]: shairport-sync.service: Succeeded. May 31 11:11:04 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 31 11:11:04 volumio volumio[1127]: ------------------------------------ BT MESSAGE: BT STATUS: running May 31 11:11:04 volumio volumio[1127]: info: Asound.conf file written May 31 11:11:04 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 31 11:11:04 volumio sudo[3395]: pam_unix(sudo:session): session closed for user root May 31 11:11:04 volumio sudo[3399]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf May 31 11:11:04 volumio sudo[3399]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 11:11:04 volumio sudo[3399]: pam_unix(sudo:session): session closed for user root May 31 11:11:04 volumio volumio[1127]: No protocol specified May 31 11:11:04 volumio volumio[1127]: xcb_connection_has_error() returned true May 31 11:11:04 volumio volumio[1127]: Found hardware: "HDA-Intel" "Conexant CX20632" "HDA:14f15098,103c83ef,00100100 HDA:8086280b,80860101,00100000" "0x103c" "0x83ef" May 31 11:11:04 volumio volumio[1127]: Hardware is initialized using a generic method May 31 11:11:04 volumio volumio[1127]: Found hardware: "USB-Audio" "USB Mixer" "USB1852:7022" "" "" May 31 11:11:04 volumio volumio[1127]: Hardware is initialized using a generic method May 31 11:11:04 volumio volumio[1127]: info: Output device has changed, restarting MPD May 31 11:11:04 volumio sudo[3406]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 31 11:11:04 volumio sudo[3406]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 11:11:04 volumio volumio[1127]: info: Output device has changed, restarting Shairport Sync May 31 11:11:04 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 11:11:04 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 11:11:04 volumio sudo[3406]: pam_unix(sudo:session): session closed for user root May 31 11:11:04 volumio sudo[3409]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 31 11:11:04 volumio sudo[3409]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 11:11:04 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM May 31 11:11:04 volumio systemd[1]: mpd.service: Succeeded. May 31 11:11:04 volumio systemd[1]: Stopped Music Player Daemon. May 31 11:11:04 volumio volumio[1127]: No protocol specified May 31 11:11:04 volumio volumio[1127]: xcb_connection_has_error() returned true May 31 11:11:04 volumio volumio[1127]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 31 11:11:04 volumio volumio[1127]: No protocol specified May 31 11:11:04 volumio volumio[1127]: xcb_connection_has_error() returned true May 31 11:11:04 volumio volumio[1127]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration May 31 11:11:04 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 11:11:04 volumio sudo[3418]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 31 11:11:04 volumio sudo[3418]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 11:11:04 volumio systemd[1]: Starting Music Player Daemon... May 31 11:11:04 volumio volumio[1127]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 31 11:11:04 volumio volumio[1127]: ------------------------------------ BT MESSAGE: BT STATUS: running May 31 11:11:04 volumio volumio[1127]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 31 11:11:04 volumio volumio[1127]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined May 31 11:11:04 volumio volumio[1127]: info: MPD Permissions set May 31 11:11:04 volumio volumio[1127]: info: Shairport-Sync Started May 31 11:11:04 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 11:11:04 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 11:11:04 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber May 31 11:11:04 volumio sudo[3421]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 31 11:11:04 volumio sudo[3421]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 11:11:04 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 11:11:04 volumio sudo[3418]: pam_unix(sudo:session): session closed for user root May 31 11:11:04 volumio sudo[3421]: pam_unix(sudo:session): session closed for user root May 31 11:11:04 volumio volumio[1127]: ------------------------------------ BT MESSAGE: BT STATUS: running May 31 11:11:04 volumio volumio[1127]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 31 11:11:04 volumio volumio[1127]: info: Starting Shairport Sync May 31 11:11:04 volumio sudo[3433]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 31 11:11:04 volumio sudo[3433]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 11:11:04 volumio sudo[3414]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 31 11:11:04 volumio sudo[3414]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 11:11:04 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 31 11:11:04 volumio volumio[1127]: ------------------------------------ BT MESSAGE: BT STATUS: running May 31 11:11:04 volumio sudo[3414]: pam_unix(sudo:session): session closed for user root May 31 11:11:04 volumio systemd[1]: shairport-sync.service: Succeeded. May 31 11:11:04 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 31 11:11:04 volumio volumio[1127]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 31 11:11:04 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 31 11:11:04 volumio sudo[3433]: pam_unix(sudo:session): session closed for user root May 31 11:11:04 volumio volumio[1127]: info: Shairport-Sync Started May 31 11:11:04 volumio mpd[3436]: May 31 11:11 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 31 11:11:04 volumio systemd[1]: Started Music Player Daemon. May 31 11:11:04 volumio sudo[3366]: pam_unix(sudo:session): session closed for user root May 31 11:11:04 volumio sudo[3273]: pam_unix(sudo:session): session closed for user root May 31 11:11:04 volumio sudo[3258]: pam_unix(sudo:session): session closed for user root May 31 11:11:04 volumio sudo[3409]: pam_unix(sudo:session): session closed for user root May 31 11:11:04 volumio sudo[3327]: pam_unix(sudo:session): session closed for user root May 31 11:11:04 volumio volumio[1127]: error: MPD error: The expression evaluated to a falsy value: May 31 11:11:04 volumio volumio[1127]: assert.ok(self.idling) May 31 11:11:04 volumio volumio[1127]: error: The expression evaluated to a falsy value: May 31 11:11:04 volumio volumio[1127]: assert.ok(self.idling) May 31 11:11:04 volumio volumio[1127]: error: MPD error: The expression evaluated to a falsy value: May 31 11:11:04 volumio volumio[1127]: assert.ok(self.idling) May 31 11:11:04 volumio volumio[1127]: error: The expression evaluated to a falsy value: May 31 11:11:04 volumio volumio[1127]: assert.ok(self.idling) May 31 11:11:04 volumio volumio[1127]: error: MPD error: The expression evaluated to a falsy value: May 31 11:11:04 volumio volumio[1127]: assert.ok(self.idling) May 31 11:11:04 volumio volumio[1127]: error: The expression evaluated to a falsy value: May 31 11:11:04 volumio volumio[1127]: assert.ok(self.idling) May 31 11:11:04 volumio volumio[1127]: error: MPD error: The expression evaluated to a falsy value: May 31 11:11:04 volumio volumio[1127]: assert.ok(self.idling) May 31 11:11:04 volumio volumio[1127]: error: The expression evaluated to a falsy value: May 31 11:11:04 volumio volumio[1127]: assert.ok(self.idling) May 31 11:11:04 volumio volumio[1127]: error: updateQueue error: null May 31 11:11:06 volumio volumio[1127]: info: TidalConnect service stoped! May 31 11:11:07 volumio volumio[1127]: info: TidalConnect service stoped! May 31 11:11:07 volumio volumio[1127]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect May 31 11:11:07 volumio volumio[1127]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect May 31 11:11:07 volumio sudo[3454]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service May 31 11:11:07 volumio sudo[3454]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 11:11:07 volumio systemd[1]: Started Volumio Tidal Connect Service. May 31 11:11:07 volumio sudo[3454]: pam_unix(sudo:session): session closed for user root May 31 11:11:07 volumio volumio[1127]: info: Executing endpoint tc_getconfig May 31 11:11:07 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig May 31 11:11:07 volumio vtcs[3456]: STARTING TidalConnect services, version: 1.5.0.45 May 31 11:11:07 volumio volumio[1127]: info: TidalConnect service stoped! May 31 11:11:07 volumio vtcs[3456]: STARTED TidalConnect services. May 31 11:11:07 volumio volumio[1127]: info: Executing endpoint tc_connect May 31 11:11:07 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect May 31 11:11:07 volumio volumio[1127]: info: Connecting to TidalConnect May 31 11:11:07 volumio volumio[1127]: info: CoreCommandRouter::servicePushState May 31 11:11:07 volumio volumio[1127]: info: CoreStateMachine::pushState May 31 11:11:07 volumio volumio[1127]: info: CorePlayQueue::getTrack 0 May 31 11:11:07 volumio volumio[1127]: info: CoreCommandRouter::volumioPushState May 31 11:11:07 volumio volumio[1127]: info: MRS: Pushing multiroomSync output update for this device May 31 11:11:07 volumio volumio[1127]: info: MRS: Pushing multiroomSync output May 31 11:11:07 volumio volumio[1127]: info: CorePlayQueue::getTrack 0 May 31 11:11:07 volumio volumio[1127]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect May 31 11:11:07 volumio volumio[1127]: info: CoreCommandRouter::servicePushState May 31 11:11:07 volumio volumio[1127]: info: CoreStateMachine::pushState May 31 11:11:07 volumio volumio[1127]: info: CorePlayQueue::getTrack 0 May 31 11:11:07 volumio volumio[1127]: info: CoreCommandRouter::volumioPushState May 31 11:11:07 volumio volumio[1127]: info: MRS: Pushing multiroomSync output update for this device May 31 11:11:07 volumio volumio[1127]: info: MRS: Pushing multiroomSync output May 31 11:11:07 volumio volumio[1127]: info: CorePlayQueue::getTrack 0 May 31 11:11:07 volumio volumio[1127]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect May 31 11:11:07 volumio volumio[1127]: info: TidalConnect service stoped! May 31 11:11:07 volumio volumio[1127]: info: TidalConnect service stoped! May 31 11:11:07 volumio volumio[1127]: info: TidalConnect service stoped! May 31 11:11:07 volumio volumio[1127]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect May 31 11:11:07 volumio volumio[1127]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect May 31 11:11:07 volumio volumio[1127]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect May 31 11:11:07 volumio volumio[1127]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect May 31 11:11:07 volumio sudo[3492]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service May 31 11:11:07 volumio sudo[3492]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 11:11:07 volumio sudo[3492]: pam_unix(sudo:session): session closed for user root May 31 11:11:07 volumio sudo[3495]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service May 31 11:11:07 volumio sudo[3495]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 11:11:07 volumio sudo[3495]: pam_unix(sudo:session): session closed for user root May 31 11:11:07 volumio volumio[1127]: info: TidalConnect service stoped! May 31 11:11:07 volumio volumio[1127]: info: TidalConnect service stoped! May 31 11:11:07 volumio volumio[1127]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect May 31 11:11:07 volumio volumio[1127]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect May 31 11:11:07 volumio sudo[3508]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service May 31 11:11:07 volumio sudo[3508]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 11:11:07 volumio sudo[3508]: pam_unix(sudo:session): session closed for user root May 31 11:11:07 volumio volumio[1127]: info: TidalConnect service stoped! May 31 11:11:07 volumio volumio[1127]: info: TidalConnect service stoped! May 31 11:11:07 volumio volumio[1127]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect May 31 11:11:07 volumio volumio[1127]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect May 31 11:11:07 volumio sudo[3520]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service May 31 11:11:07 volumio sudo[3520]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 11:11:07 volumio sudo[3520]: pam_unix(sudo:session): session closed for user root May 31 11:11:07 volumio volumio[1127]: info: TidalConnect service stoped! May 31 11:11:07 volumio volumio[1127]: info: TidalConnect service stoped! May 31 11:11:07 volumio volumio[1127]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect May 31 11:11:07 volumio volumio[1127]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect May 31 11:11:07 volumio sudo[3532]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service May 31 11:11:07 volumio sudo[3532]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 11:11:07 volumio sudo[3532]: pam_unix(sudo:session): session closed for user root May 31 11:11:07 volumio volumio[1127]: info: CoreCommandRouter::volumioRetrieveVolumeLevels May 31 11:11:07 volumio volumio[1127]: info: CoreStateMachine::getcurrentVolume May 31 11:11:07 volumio volumio[1127]: info: CoreCommandRouter::volumioRetrievevolume May 31 11:11:07 volumio volumio[1127]: info: CoreStateMachine::pushState May 31 11:11:07 volumio volumio[1127]: info: CorePlayQueue::getTrack 0 May 31 11:11:07 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 11:11:07 volumio volumio[1127]: info: CoreCommandRouter::volumioPushState May 31 11:11:07 volumio volumio[1127]: info: MRS: Pushing multiroomSync output update for this device May 31 11:11:07 volumio volumio[1127]: info: MRS: Pushing multiroomSync output May 31 11:11:07 volumio volumio[1127]: info: CoreStateMachine::updateTrackBlock May 31 11:11:07 volumio volumio[1127]: info: CorePlayQueue::getTrackBlock May 31 11:11:07 volumio volumio[1127]: info: CoreCommandRouter::volumioRetrievevolume May 31 11:11:08 volumio volumio[1127]: info: CoreStateMachine::pushState May 31 11:11:08 volumio volumio[1127]: info: CorePlayQueue::getTrack 0 May 31 11:11:08 volumio volumio[1127]: info: CoreCommandRouter::volumioPushState May 31 11:11:08 volumio volumio[1127]: info: MRS: Pushing multiroomSync output update for this device May 31 11:11:08 volumio volumio[1127]: info: MRS: Pushing multiroomSync output May 31 11:11:10 volumio volumio[1127]: info: TidalConnect service started! May 31 11:11:10 volumio volumio[1127]: info: TidalConnect service started! May 31 11:11:10 volumio volumio[1127]: info: TidalConnect service started! May 31 11:11:10 volumio volumio[1127]: info: TidalConnect service started! May 31 11:11:10 volumio volumio[1127]: info: TidalConnect service started! May 31 11:11:10 volumio volumio[1127]: info: TidalConnect service started! May 31 11:11:16 volumio volumio[1127]: info: CoreCommandRouter::volumioNext May 31 11:11:16 volumio volumio[1127]: info: CoreStateMachine::next May 31 11:11:16 volumio volumio[1127]: info: CoreStateMachine::stop May 31 11:11:16 volumio volumio[1127]: info: CoreStateMachine::setConsumeUpdateService undefined May 31 11:11:16 volumio volumio[1127]: info: CoreStateMachine::updateTrackBlock May 31 11:11:16 volumio volumio[1127]: info: CorePlayQueue::getTrackBlock May 31 11:11:16 volumio volumio[1127]: info: CoreStateMachine::stPlaybackTimer May 31 11:11:16 volumio volumio[1127]: info: CoreStateMachine::pushState May 31 11:11:16 volumio volumio[1127]: info: CorePlayQueue::getTrack 0 May 31 11:11:16 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 11:11:16 volumio volumio[1127]: info: CoreCommandRouter::volumioPushState May 31 11:11:16 volumio volumio[1127]: info: MRS: Pushing multiroomSync output update for this device May 31 11:11:16 volumio volumio[1127]: info: MRS: Pushing multiroomSync output May 31 11:11:16 volumio volumio[1127]: info: CoreStateMachine::serviceStop May 31 11:11:16 volumio volumio[1127]: info: CorePlayQueue::getTrack 0 May 31 11:11:16 volumio volumio[1127]: info: CoreCommandRouter::serviceStop May 31 11:11:16 volumio volumio[1127]: info: ControllerMpd::stop May 31 11:11:16 volumio volumio[1127]: verbose: ControllerMpd::sendMpdCommand stop May 31 11:11:16 volumio volumio[1127]: info: sendMpdCommand stop took 5 milliseconds May 31 11:11:16 volumio volumio[1127]: info: CoreStateMachine::play index undefined May 31 11:11:16 volumio volumio[1127]: info: CoreStateMachine::setConsumeUpdateService undefined May 31 11:11:16 volumio volumio[1127]: info: CorePlayQueue::getTrack 1 May 31 11:11:16 volumio volumio[1127]: info: CoreStateMachine::startPlaybackTimer May 31 11:11:16 volumio volumio[1127]: info: CorePlayQueue::getTrack 1 May 31 11:11:16 volumio volumio[1127]: verbose: ControllerMpd::clearAddPlayTracks USB/Music/ACG/Kalafina - Kalafina All Time Best 2008-2018 (FLAC 24bit／96kHz)/02. 君が光に変えて行く.flac May 31 11:11:16 volumio volumio[1127]: verbose: ControllerMpd::sendMpdCommand stop May 31 11:11:16 volumio volumio[1127]: info: CoreStateMachine::updateTrackBlock May 31 11:11:16 volumio volumio[1127]: info: CorePlayQueue::getTrackBlock May 31 11:11:16 volumio volumio[1127]: info: sendMpdCommand stop took 0 milliseconds May 31 11:11:16 volumio volumio[1127]: verbose: ControllerMpd::sendMpdCommand clear May 31 11:11:16 volumio volumio[1127]: info: sendMpdCommand clear took 0 milliseconds May 31 11:11:16 volumio volumio[1127]: verbose: ControllerMpd::sendMpdCommand add "USB/Music/ACG/Kalafina - Kalafina All Time Best 2008-2018 (FLAC 24bit／96kHz)/02. 君が光に変えて行く.flac" May 31 11:11:16 volumio volumio[1127]: info: May 31 11:11:16 volumio volumio[1127]: ---------------------------- MPD announces system playlist update May 31 11:11:16 volumio volumio[1127]: info: Ignoring MPD Status Update May 31 11:11:16 volumio volumio[1127]: info: May 31 11:11:16 volumio volumio[1127]: ---------------------------- MPD announces system playlist update May 31 11:11:16 volumio volumio[1127]: info: Ignoring MPD Status Update May 31 11:11:16 volumio volumio[1127]: info: May 31 11:11:16 volumio volumio[1127]: ---------------------------- MPD announces system playlist update May 31 11:11:16 volumio volumio[1127]: info: Ignoring MPD Status Update May 31 11:11:16 volumio volumio[1127]: info: May 31 11:11:16 volumio volumio[1127]: ---------------------------- MPD announces system playlist update May 31 11:11:16 volumio volumio[1127]: info: Ignoring MPD Status Update May 31 11:11:16 volumio volumio[1127]: info: May 31 11:11:16 volumio volumio[1127]: ---------------------------- MPD announces system playlist update May 31 11:11:16 volumio volumio[1127]: info: Ignoring MPD Status Update May 31 11:11:16 volumio volumio[1127]: info: sendMpdCommand add "USB/Music/ACG/Kalafina - Kalafina All Time Best 2008-2018 (FLAC 24bit／96kHz)/02. 君が光に変えて行く.flac" took 1 milliseconds May 31 11:11:16 volumio volumio[1127]: info: ------------------------------ 1ms May 31 11:11:16 volumio volumio[1127]: info: ------------------------------ 0ms May 31 11:11:16 volumio volumio[1127]: info: ------------------------------ 0ms May 31 11:11:16 volumio volumio[1127]: verbose: ControllerMpd::sendMpdCommand play May 31 11:11:16 volumio volumio[1127]: info: May 31 11:11:16 volumio volumio[1127]: ---------------------------- MPD announces system playlist update May 31 11:11:16 volumio volumio[1127]: info: Ignoring MPD Status Update May 31 11:11:16 volumio volumio[1127]: info: May 31 11:11:16 volumio volumio[1127]: ---------------------------- MPD announces system playlist update May 31 11:11:16 volumio volumio[1127]: info: Ignoring MPD Status Update May 31 11:11:16 volumio volumio[1127]: info: May 31 11:11:16 volumio volumio[1127]: ---------------------------- MPD announces system playlist update May 31 11:11:16 volumio volumio[1127]: info: Ignoring MPD Status Update May 31 11:11:16 volumio volumio[1127]: info: May 31 11:11:16 volumio volumio[1127]: ---------------------------- MPD announces system playlist update May 31 11:11:16 volumio volumio[1127]: info: Ignoring MPD Status Update May 31 11:11:16 volumio volumio[1127]: info: ------------------------------ 2ms May 31 11:11:16 volumio volumio[1127]: info: ------------------------------ 2ms May 31 11:11:16 volumio volumio[1127]: info: sendMpdCommand play took 1 milliseconds May 31 11:11:16 volumio volumio[1127]: info: ------------------------------ 1ms May 31 11:11:16 volumio volumio[1127]: info: ------------------------------ 1ms May 31 11:11:16 volumio volumio[1127]: info: ------------------------------ 1ms May 31 11:11:16 volumio volumio[1127]: info: ------------------------------ 1ms May 31 11:11:16 volumio volumio[1127]: info: May 31 11:11:16 volumio volumio[1127]: ---------------------------- MPD announces system playlist update May 31 11:11:16 volumio volumio[1127]: info: Ignoring MPD Status Update May 31 11:11:16 volumio volumio[1127]: info: ------------------------------ 0ms May 31 11:11:16 volumio volumio[1127]: info: May 31 11:11:16 volumio volumio[1127]: ---------------------------- MPD announces state update: player May 31 11:11:16 volumio volumio[1127]: info: ControllerMpd::getState May 31 11:11:16 volumio volumio[1127]: verbose: ControllerMpd::sendMpdCommand status May 31 11:11:16 volumio volumio[1127]: info: May 31 11:11:16 volumio volumio[1127]: ---------------------------- MPD announces state update: player May 31 11:11:16 volumio volumio[1127]: info: ControllerMpd::getState May 31 11:11:16 volumio volumio[1127]: verbose: ControllerMpd::sendMpdCommand status May 31 11:11:16 volumio volumio[1127]: info: May 31 11:11:16 volumio volumio[1127]: ---------------------------- MPD announces state update: player May 31 11:11:16 volumio volumio[1127]: info: ControllerMpd::getState May 31 11:11:16 volumio volumio[1127]: verbose: ControllerMpd::sendMpdCommand status May 31 11:11:16 volumio volumio[1127]: info: May 31 11:11:16 volumio volumio[1127]: ---------------------------- MPD announces state update: player May 31 11:11:16 volumio volumio[1127]: info: ControllerMpd::getState May 31 11:11:16 volumio volumio[1127]: verbose: ControllerMpd::sendMpdCommand status May 31 11:11:16 volumio volumio[1127]: info: May 31 11:11:16 volumio volumio[1127]: ---------------------------- MPD announces state update: player May 31 11:11:16 volumio volumio[1127]: info: ControllerMpd::getState May 31 11:11:16 volumio volumio[1127]: verbose: ControllerMpd::sendMpdCommand status May 31 11:11:16 volumio volumio[1127]: info: FusionDsp - ---- read samplerate, raw: 96000,S32_LE,2,32 May 31 11:11:16 volumio volumio[1127]: info: FusionDsp - ---- read samplerate from file: 96000 May 31 11:11:16 volumio volumio[1127]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 31 11:11:16 volumio volumio[1127]: info: FusionDsp - ---- read samplerate, raw: 96000,S32_LE,2,32 May 31 11:11:16 volumio volumio[1127]: info: FusionDsp - ---- read samplerate from file: 96000 May 31 11:11:16 volumio volumio[1127]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 31 11:11:16 volumio volumio[1127]: info: May 31 11:11:16 volumio volumio[1127]: ---------------------------- MPD announces state update: player May 31 11:11:16 volumio volumio[1127]: info: sendMpdCommand status took 9 milliseconds May 31 11:11:16 volumio volumio[1127]: info: ControllerMpd::getState May 31 11:11:16 volumio volumio[1127]: verbose: ControllerMpd::sendMpdCommand status May 31 11:11:16 volumio volumio[1127]: info: sendMpdCommand status took 9 milliseconds May 31 11:11:16 volumio volumio[1127]: info: sendMpdCommand status took 8 milliseconds May 31 11:11:16 volumio volumio[1127]: info: sendMpdCommand status took 8 milliseconds May 31 11:11:16 volumio volumio[1127]: info: sendMpdCommand status took 8 milliseconds May 31 11:11:16 volumio volumio[1127]: verbose: ControllerMpd::parseState May 31 11:11:16 volumio volumio[1127]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 11:11:16 volumio volumio[1127]: verbose: ControllerMpd::parseState May 31 11:11:16 volumio volumio[1127]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 11:11:16 volumio volumio[1127]: verbose: ControllerMpd::parseState May 31 11:11:16 volumio volumio[1127]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 11:11:16 volumio volumio[1127]: verbose: ControllerMpd::parseState May 31 11:11:16 volumio volumio[1127]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 11:11:16 volumio volumio[1127]: verbose: ControllerMpd::parseState May 31 11:11:16 volumio volumio[1127]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 11:11:16 volumio volumio[1127]: info: May 31 11:11:16 volumio volumio[1127]: ---------------------------- MPD announces state update: player May 31 11:11:16 volumio volumio[1127]: info: ControllerMpd::getState May 31 11:11:16 volumio volumio[1127]: verbose: ControllerMpd::sendMpdCommand status May 31 11:11:16 volumio volumio[1127]: info: May 31 11:11:16 volumio volumio[1127]: ---------------------------- MPD announces state update: player May 31 11:11:16 volumio volumio[1127]: info: ControllerMpd::getState May 31 11:11:16 volumio volumio[1127]: verbose: ControllerMpd::sendMpdCommand status May 31 11:11:16 volumio volumio[1127]: info: May 31 11:11:16 volumio volumio[1127]: ---------------------------- MPD announces state update: player May 31 11:11:16 volumio volumio[1127]: info: ControllerMpd::getState May 31 11:11:16 volumio volumio[1127]: verbose: ControllerMpd::sendMpdCommand status May 31 11:11:16 volumio volumio[1127]: info: May 31 11:11:16 volumio volumio[1127]: ---------------------------- MPD announces state update: player May 31 11:11:16 volumio volumio[1127]: info: ControllerMpd::getState May 31 11:11:16 volumio volumio[1127]: verbose: ControllerMpd::sendMpdCommand status May 31 11:11:16 volumio volumio[1127]: info: sendMpdCommand status took 2 milliseconds May 31 11:11:16 volumio volumio[1127]: info: sendMpdCommand playlistinfo took 2 milliseconds May 31 11:11:16 volumio volumio[1127]: info: sendMpdCommand playlistinfo took 2 milliseconds May 31 11:11:16 volumio volumio[1127]: info: sendMpdCommand playlistinfo took 2 milliseconds May 31 11:11:16 volumio volumio[1127]: info: sendMpdCommand playlistinfo took 2 milliseconds May 31 11:11:16 volumio volumio[1127]: info: sendMpdCommand playlistinfo took 2 milliseconds May 31 11:11:16 volumio volumio[1127]: info: sendMpdCommand status took 1 milliseconds May 31 11:11:16 volumio volumio[1127]: info: sendMpdCommand status took 1 milliseconds May 31 11:11:16 volumio volumio[1127]: info: sendMpdCommand status took 1 milliseconds May 31 11:11:16 volumio volumio[1127]: info: sendMpdCommand status took 1 milliseconds May 31 11:11:16 volumio volumio[1127]: verbose: ControllerMpd::parseState May 31 11:11:16 volumio volumio[1127]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 11:11:16 volumio volumio[1127]: verbose: ControllerMpd::parseTrackInfo May 31 11:11:16 volumio volumio[1127]: verbose: ControllerMpd::parseTrackInfo May 31 11:11:16 volumio volumio[1127]: verbose: ControllerMpd::parseTrackInfo May 31 11:11:16 volumio volumio[1127]: verbose: ControllerMpd::parseTrackInfo May 31 11:11:16 volumio volumio[1127]: verbose: ControllerMpd::parseTrackInfo May 31 11:11:16 volumio volumio[1127]: verbose: ControllerMpd::parseState May 31 11:11:16 volumio volumio[1127]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 11:11:16 volumio volumio[1127]: verbose: ControllerMpd::parseState May 31 11:11:16 volumio volumio[1127]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 11:11:16 volumio volumio[1127]: verbose: ControllerMpd::parseState May 31 11:11:16 volumio volumio[1127]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 11:11:16 volumio volumio[1127]: verbose: ControllerMpd::parseState May 31 11:11:16 volumio volumio[1127]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 11:11:16 volumio volumio[1127]: info: ControllerMpd::pushState May 31 11:11:16 volumio volumio[1127]: info: CoreCommandRouter::servicePushState May 31 11:11:16 volumio volumio[1127]: info: CorePlayQueue::getTrack 1 May 31 11:11:16 volumio volumio[1127]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":285,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"君が光に変えて行く","artist":"Kalafina","album":"Kalafina All Time Best 2008-2018","uri":"USB/Music/ACG/Kalafina - Kalafina All Time Best 2008-2018 (FLAC 24bit／96kHz)/02. 君が光に変えて行く.flac","trackType":"flac"} May 31 11:11:16 volumio volumio[1127]: verbose: CURRENT POSITION 1 May 31 11:11:16 volumio volumio[1127]: info: CoreStateMachine::syncState stateService play May 31 11:11:16 volumio volumio[1127]: info: CoreStateMachine::syncState currentStatus stop May 31 11:11:16 volumio volumio[1127]: info: ControllerMpd::pushState May 31 11:11:16 volumio volumio[1127]: info: CoreCommandRouter::servicePushState May 31 11:11:16 volumio volumio[1127]: info: CorePlayQueue::getTrack 1 May 31 11:11:16 volumio volumio[1127]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":285,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"君が光に変えて行く","artist":"Kalafina","album":"Kalafina All Time Best 2008-2018","uri":"USB/Music/ACG/Kalafina - Kalafina All Time Best 2008-2018 (FLAC 24bit／96kHz)/02. 君が光に変えて行く.flac","trackType":"flac"} May 31 11:11:16 volumio volumio[1127]: verbose: CURRENT POSITION 1 May 31 11:11:16 volumio volumio[1127]: info: CoreStateMachine::syncState stateService play May 31 11:11:16 volumio volumio[1127]: info: CoreStateMachine::syncState currentStatus play May 31 11:11:16 volumio volumio[1127]: info: Received an update from plugin. extracting info from payload May 31 11:11:16 volumio volumio[1127]: info: CoreStateMachine::pushState May 31 11:11:16 volumio volumio[1127]: info: CorePlayQueue::getTrack 1 May 31 11:11:16 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 11:11:16 volumio volumio[1127]: info: CoreCommandRouter::volumioPushState May 31 11:11:16 volumio volumio[1127]: info: MRS: Pushing multiroomSync output update for this device May 31 11:11:16 volumio volumio[1127]: info: MRS: Pushing multiroomSync output May 31 11:11:16 volumio volumio[1127]: info: CoreStateMachine::pushState May 31 11:11:16 volumio volumio[1127]: info: CorePlayQueue::getTrack 1 May 31 11:11:16 volumio volumio[1127]: info: CoreCommandRouter::volumioPushState May 31 11:11:16 volumio volumio[1127]: info: MRS: Pushing multiroomSync output update for this device May 31 11:11:16 volumio volumio[1127]: info: MRS: Pushing multiroomSync output May 31 11:11:16 volumio volumio[1127]: info: ControllerMpd::pushState May 31 11:11:16 volumio volumio[1127]: info: CoreCommandRouter::servicePushState May 31 11:11:16 volumio volumio[1127]: info: CorePlayQueue::getTrack 1 May 31 11:11:16 volumio volumio[1127]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":285,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"君が光に変えて行く","artist":"Kalafina","album":"Kalafina All Time Best 2008-2018","uri":"USB/Music/ACG/Kalafina - Kalafina All Time Best 2008-2018 (FLAC 24bit／96kHz)/02. 君が光に変えて行く.flac","trackType":"flac"} May 31 11:11:16 volumio volumio[1127]: verbose: CURRENT POSITION 1 May 31 11:11:16 volumio volumio[1127]: info: CoreStateMachine::syncState stateService play May 31 11:11:16 volumio volumio[1127]: info: CoreStateMachine::syncState currentStatus play May 31 11:11:16 volumio volumio[1127]: info: Received an update from plugin. extracting info from payload May 31 11:11:16 volumio volumio[1127]: info: CoreStateMachine::pushState May 31 11:11:16 volumio volumio[1127]: info: CorePlayQueue::getTrack 1 May 31 11:11:16 volumio volumio[1127]: info: CoreCommandRouter::volumioPushState May 31 11:11:16 volumio volumio[1127]: info: MRS: Pushing multiroomSync output update for this device May 31 11:11:16 volumio volumio[1127]: info: MRS: Pushing multiroomSync output May 31 11:11:16 volumio volumio[1127]: info: CoreStateMachine::pushState May 31 11:11:16 volumio volumio[1127]: info: CorePlayQueue::getTrack 1 May 31 11:11:16 volumio volumio[1127]: info: CoreCommandRouter::volumioPushState May 31 11:11:16 volumio volumio[1127]: info: MRS: Pushing multiroomSync output update for this device May 31 11:11:16 volumio volumio[1127]: info: MRS: Pushing multiroomSync output May 31 11:11:16 volumio volumio[1127]: info: ControllerMpd::pushState May 31 11:11:16 volumio volumio[1127]: info: CoreCommandRouter::servicePushState May 31 11:11:16 volumio volumio[1127]: info: CorePlayQueue::getTrack 1 May 31 11:11:16 volumio volumio[1127]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":285,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"君が光に変えて行く","artist":"Kalafina","album":"Kalafina All Time Best 2008-2018","uri":"USB/Music/ACG/Kalafina - Kalafina All Time Best 2008-2018 (FLAC 24bit／96kHz)/02. 君が光に変えて行く.flac","trackType":"flac"} May 31 11:11:16 volumio volumio[1127]: verbose: CURRENT POSITION 1 May 31 11:11:16 volumio volumio[1127]: info: CoreStateMachine::syncState stateService play May 31 11:11:16 volumio volumio[1127]: info: CoreStateMachine::syncState currentStatus play May 31 11:11:16 volumio volumio[1127]: info: Received an update from plugin. extracting info from payload May 31 11:11:16 volumio volumio[1127]: info: CoreStateMachine::pushState May 31 11:11:16 volumio volumio[1127]: info: CorePlayQueue::getTrack 1 May 31 11:11:16 volumio volumio[1127]: info: CoreCommandRouter::volumioPushState May 31 11:11:16 volumio volumio[1127]: info: MRS: Pushing multiroomSync output update for this device May 31 11:11:16 volumio volumio[1127]: info: MRS: Pushing multiroomSync output May 31 11:11:16 volumio volumio[1127]: info: CoreStateMachine::pushState May 31 11:11:16 volumio volumio[1127]: info: CorePlayQueue::getTrack 1 May 31 11:11:16 volumio volumio[1127]: info: CoreCommandRouter::volumioPushState May 31 11:11:16 volumio volumio[1127]: info: MRS: Pushing multiroomSync output update for this device May 31 11:11:16 volumio volumio[1127]: info: MRS: Pushing multiroomSync output May 31 11:11:16 volumio volumio[1127]: info: ControllerMpd::pushState May 31 11:11:16 volumio volumio[1127]: info: CoreCommandRouter::servicePushState May 31 11:11:16 volumio volumio[1127]: info: CorePlayQueue::getTrack 1 May 31 11:11:16 volumio volumio[1127]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":285,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"君が光に変えて行く","artist":"Kalafina","album":"Kalafina All Time Best 2008-2018","uri":"USB/Music/ACG/Kalafina - Kalafina All Time Best 2008-2018 (FLAC 24bit／96kHz)/02. 君が光に変えて行く.flac","trackType":"flac"} May 31 11:11:16 volumio volumio[1127]: verbose: CURRENT POSITION 1 May 31 11:11:16 volumio volumio[1127]: info: CoreStateMachine::syncState stateService play May 31 11:11:16 volumio volumio[1127]: info: CoreStateMachine::syncState currentStatus play May 31 11:11:16 volumio volumio[1127]: info: Received an update from plugin. extracting info from payload May 31 11:11:16 volumio volumio[1127]: info: CoreStateMachine::pushState May 31 11:11:16 volumio volumio[1127]: info: CorePlayQueue::getTrack 1 May 31 11:11:16 volumio volumio[1127]: info: CoreCommandRouter::volumioPushState May 31 11:11:16 volumio volumio[1127]: info: MRS: Pushing multiroomSync output update for this device May 31 11:11:16 volumio volumio[1127]: info: MRS: Pushing multiroomSync output May 31 11:11:16 volumio volumio[1127]: info: CoreStateMachine::pushState May 31 11:11:16 volumio volumio[1127]: info: CorePlayQueue::getTrack 1 May 31 11:11:16 volumio volumio[1127]: info: CoreCommandRouter::volumioPushState May 31 11:11:16 volumio volumio[1127]: info: MRS: Pushing multiroomSync output update for this device May 31 11:11:16 volumio volumio[1127]: info: MRS: Pushing multiroomSync output May 31 11:11:16 volumio volumio[1127]: info: ------------------------------ 22ms May 31 11:11:16 volumio volumio[1127]: info: ------------------------------ 41ms May 31 11:11:16 volumio volumio[1127]: info: ------------------------------ 40ms May 31 11:11:16 volumio volumio[1127]: info: ------------------------------ 40ms May 31 11:11:16 volumio volumio[1127]: info: ------------------------------ 40ms May 31 11:11:16 volumio volumio[1127]: info: sendMpdCommand playlistinfo took 30 milliseconds May 31 11:11:16 volumio volumio[1127]: info: sendMpdCommand playlistinfo took 29 milliseconds May 31 11:11:16 volumio volumio[1127]: info: sendMpdCommand playlistinfo took 29 milliseconds May 31 11:11:16 volumio volumio[1127]: info: sendMpdCommand playlistinfo took 29 milliseconds May 31 11:11:16 volumio volumio[1127]: info: sendMpdCommand playlistinfo took 29 milliseconds May 31 11:11:16 volumio volumio[1127]: verbose: ControllerMpd::parseTrackInfo May 31 11:11:16 volumio volumio[1127]: verbose: ControllerMpd::parseTrackInfo May 31 11:11:16 volumio volumio[1127]: verbose: ControllerMpd::parseTrackInfo May 31 11:11:16 volumio volumio[1127]: verbose: ControllerMpd::parseTrackInfo May 31 11:11:16 volumio volumio[1127]: verbose: ControllerMpd::parseTrackInfo May 31 11:11:16 volumio volumio[1127]: info: ControllerMpd::pushState May 31 11:11:16 volumio volumio[1127]: info: CoreCommandRouter::servicePushState May 31 11:11:16 volumio volumio[1127]: info: CorePlayQueue::getTrack 1 May 31 11:11:16 volumio volumio[1127]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1244,"duration":285,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"4615 Kbps","isStreaming":false,"title":"君が光に変えて行く","artist":"Kalafina","album":"Kalafina All Time Best 2008-2018","uri":"USB/Music/ACG/Kalafina - Kalafina All Time Best 2008-2018 (FLAC 24bit／96kHz)/02. 君が光に変えて行く.flac","trackType":"flac"} May 31 11:11:16 volumio volumio[1127]: verbose: CURRENT POSITION 1 May 31 11:11:16 volumio volumio[1127]: info: CoreStateMachine::syncState stateService play May 31 11:11:16 volumio volumio[1127]: info: CoreStateMachine::syncState currentStatus play May 31 11:11:16 volumio volumio[1127]: info: Received an update from plugin. extracting info from payload May 31 11:11:16 volumio volumio[1127]: info: CoreStateMachine::pushState May 31 11:11:16 volumio volumio[1127]: info: CorePlayQueue::getTrack 1 May 31 11:11:16 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 11:11:16 volumio volumio[1127]: info: CoreCommandRouter::volumioPushState May 31 11:11:16 volumio volumio[1127]: info: MRS: Pushing multiroomSync output update for this device May 31 11:11:16 volumio volumio[1127]: info: MRS: Pushing multiroomSync output May 31 11:11:16 volumio volumio[1127]: info: CoreStateMachine::pushState May 31 11:11:16 volumio volumio[1127]: info: CorePlayQueue::getTrack 1 May 31 11:11:16 volumio volumio[1127]: info: CoreCommandRouter::volumioPushState May 31 11:11:16 volumio volumio[1127]: info: MRS: Pushing multiroomSync output update for this device May 31 11:11:16 volumio volumio[1127]: info: MRS: Pushing multiroomSync output May 31 11:11:16 volumio volumio[1127]: info: ControllerMpd::pushState May 31 11:11:16 volumio volumio[1127]: info: CoreCommandRouter::servicePushState May 31 11:11:16 volumio volumio[1127]: info: CorePlayQueue::getTrack 1 May 31 11:11:16 volumio volumio[1127]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1244,"duration":285,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"4615 Kbps","isStreaming":false,"title":"君が光に変えて行く","artist":"Kalafina","album":"Kalafina All Time Best 2008-2018","uri":"USB/Music/ACG/Kalafina - Kalafina All Time Best 2008-2018 (FLAC 24bit／96kHz)/02. 君が光に変えて行く.flac","trackType":"flac"} May 31 11:11:16 volumio volumio[1127]: verbose: CURRENT POSITION 1 May 31 11:11:16 volumio volumio[1127]: info: CoreStateMachine::syncState stateService play May 31 11:11:16 volumio volumio[1127]: info: CoreStateMachine::syncState currentStatus play May 31 11:11:16 volumio volumio[1127]: info: Received an update from plugin. extracting info from payload May 31 11:11:16 volumio volumio[1127]: info: CoreStateMachine::pushState May 31 11:11:16 volumio volumio[1127]: info: CorePlayQueue::getTrack 1 May 31 11:11:16 volumio volumio[1127]: info: CoreCommandRouter::volumioPushState May 31 11:11:16 volumio volumio[1127]: info: MRS: Pushing multiroomSync output update for this device May 31 11:11:16 volumio volumio[1127]: info: MRS: Pushing multiroomSync output May 31 11:11:16 volumio volumio[1127]: info: CoreStateMachine::pushState May 31 11:11:16 volumio volumio[1127]: info: CorePlayQueue::getTrack 1 May 31 11:11:16 volumio volumio[1127]: info: CoreCommandRouter::volumioPushState May 31 11:11:16 volumio volumio[1127]: info: MRS: Pushing multiroomSync output update for this device May 31 11:11:16 volumio volumio[1127]: info: MRS: Pushing multiroomSync output May 31 11:11:16 volumio volumio[1127]: info: ControllerMpd::pushState May 31 11:11:16 volumio volumio[1127]: info: CoreCommandRouter::servicePushState May 31 11:11:16 volumio volumio[1127]: info: CorePlayQueue::getTrack 1 May 31 11:11:16 volumio volumio[1127]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1244,"duration":285,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"4615 Kbps","isStreaming":false,"title":"君が光に変えて行く","artist":"Kalafina","album":"Kalafina All Time Best 2008-2018","uri":"USB/Music/ACG/Kalafina - Kalafina All Time Best 2008-2018 (FLAC 24bit／96kHz)/02. 君が光に変えて行く.flac","trackType":"flac"} May 31 11:11:16 volumio volumio[1127]: verbose: CURRENT POSITION 1 May 31 11:11:16 volumio volumio[1127]: info: CoreStateMachine::syncState stateService play May 31 11:11:16 volumio volumio[1127]: info: CoreStateMachine::syncState currentStatus play May 31 11:11:16 volumio volumio[1127]: info: Received an update from plugin. extracting info from payload May 31 11:11:16 volumio volumio[1127]: info: CoreStateMachine::pushState May 31 11:11:16 volumio volumio[1127]: info: CorePlayQueue::getTrack 1 May 31 11:11:16 volumio volumio[1127]: info: CoreCommandRouter::volumioPushState May 31 11:11:16 volumio volumio[1127]: info: MRS: Pushing multiroomSync output update for this device May 31 11:11:16 volumio volumio[1127]: info: MRS: Pushing multiroomSync output May 31 11:11:16 volumio volumio[1127]: info: CoreStateMachine::pushState May 31 11:11:16 volumio volumio[1127]: info: CorePlayQueue::getTrack 1 May 31 11:11:16 volumio volumio[1127]: info: CoreCommandRouter::volumioPushState May 31 11:11:16 volumio volumio[1127]: info: MRS: Pushing multiroomSync output update for this device May 31 11:11:16 volumio volumio[1127]: info: MRS: Pushing multiroomSync output May 31 11:11:16 volumio volumio[1127]: info: ControllerMpd::pushState May 31 11:11:16 volumio volumio[1127]: info: CoreCommandRouter::servicePushState May 31 11:11:16 volumio volumio[1127]: info: CorePlayQueue::getTrack 1 May 31 11:11:16 volumio volumio[1127]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1244,"duration":285,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"4615 Kbps","isStreaming":false,"title":"君が光に変えて行く","artist":"Kalafina","album":"Kalafina All Time Best 2008-2018","uri":"USB/Music/ACG/Kalafina - Kalafina All Time Best 2008-2018 (FLAC 24bit／96kHz)/02. 君が光に変えて行く.flac","trackType":"flac"} May 31 11:11:16 volumio volumio[1127]: verbose: CURRENT POSITION 1 May 31 11:11:16 volumio volumio[1127]: info: CoreStateMachine::syncState stateService play May 31 11:11:16 volumio volumio[1127]: info: CoreStateMachine::syncState currentStatus play May 31 11:11:16 volumio volumio[1127]: info: Received an update from plugin. extracting info from payload May 31 11:11:16 volumio volumio[1127]: info: CoreStateMachine::pushState May 31 11:11:16 volumio volumio[1127]: info: CorePlayQueue::getTrack 1 May 31 11:11:16 volumio volumio[1127]: info: CoreCommandRouter::volumioPushState May 31 11:11:16 volumio volumio[1127]: info: MRS: Pushing multiroomSync output update for this device May 31 11:11:16 volumio volumio[1127]: info: MRS: Pushing multiroomSync output May 31 11:11:16 volumio volumio[1127]: info: CoreStateMachine::pushState May 31 11:11:16 volumio volumio[1127]: info: CorePlayQueue::getTrack 1 May 31 11:11:16 volumio volumio[1127]: info: CoreCommandRouter::volumioPushState May 31 11:11:16 volumio volumio[1127]: info: MRS: Pushing multiroomSync output update for this device May 31 11:11:16 volumio volumio[1127]: info: MRS: Pushing multiroomSync output May 31 11:11:16 volumio volumio[1127]: info: ControllerMpd::pushState May 31 11:11:16 volumio volumio[1127]: info: CoreCommandRouter::servicePushState May 31 11:11:16 volumio volumio[1127]: info: CorePlayQueue::getTrack 1 May 31 11:11:16 volumio volumio[1127]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1244,"duration":285,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"4615 Kbps","isStreaming":false,"title":"君が光に変えて行く","artist":"Kalafina","album":"Kalafina All Time Best 2008-2018","uri":"USB/Music/ACG/Kalafina - Kalafina All Time Best 2008-2018 (FLAC 24bit／96kHz)/02. 君が光に変えて行く.flac","trackType":"flac"} May 31 11:11:16 volumio volumio[1127]: verbose: CURRENT POSITION 1 May 31 11:11:16 volumio volumio[1127]: info: CoreStateMachine::syncState stateService play May 31 11:11:16 volumio volumio[1127]: info: CoreStateMachine::syncState currentStatus play May 31 11:11:16 volumio volumio[1127]: info: Received an update from plugin. extracting info from payload May 31 11:11:16 volumio volumio[1127]: info: CoreStateMachine::pushState May 31 11:11:16 volumio volumio[1127]: info: CorePlayQueue::getTrack 1 May 31 11:11:16 volumio volumio[1127]: info: CoreCommandRouter::volumioPushState May 31 11:11:16 volumio volumio[1127]: info: MRS: Pushing multiroomSync output update for this device May 31 11:11:16 volumio volumio[1127]: info: MRS: Pushing multiroomSync output May 31 11:11:16 volumio volumio[1127]: info: CoreStateMachine::pushState May 31 11:11:16 volumio volumio[1127]: info: CorePlayQueue::getTrack 1 May 31 11:11:16 volumio volumio[1127]: info: CoreCommandRouter::volumioPushState May 31 11:11:16 volumio volumio[1127]: info: MRS: Pushing multiroomSync output update for this device May 31 11:11:16 volumio volumio[1127]: info: MRS: Pushing multiroomSync output May 31 11:11:16 volumio volumio[1127]: info: ------------------------------ 64ms May 31 11:11:16 volumio volumio[1127]: info: ------------------------------ 63ms May 31 11:11:16 volumio volumio[1127]: info: ------------------------------ 63ms May 31 11:11:16 volumio volumio[1127]: info: ------------------------------ 63ms May 31 11:11:16 volumio volumio[1127]: info: ------------------------------ 63ms May 31 11:11:16 volumio volumio[1127]: info: FusionDsp - {"Reload":{"result":"Ok"}} May 31 11:11:16 volumio volumio[1127]: info: FusionDsp - {"Reload":{"result":"Ok"}} May 31 11:11:22 volumio volumio[1127]: info: CoreCommandRouter::volumioPrevious May 31 11:11:22 volumio volumio[1127]: info: CoreStateMachine::previous May 31 11:11:22 volumio volumio[1127]: info: CorePlayQueue::getTrack 1 May 31 11:11:22 volumio volumio[1127]: info: ControllerMpd::seek May 31 11:11:22 volumio volumio[1127]: error: null May 31 11:11:22 volumio volumio[1127]: info: May 31 11:11:22 volumio volumio[1127]: ---------------------------- MPD announces state update: player May 31 11:11:22 volumio volumio[1127]: info: ControllerMpd::getState May 31 11:11:22 volumio volumio[1127]: verbose: ControllerMpd::sendMpdCommand status May 31 11:11:22 volumio volumio[1127]: info: May 31 11:11:22 volumio volumio[1127]: ---------------------------- MPD announces state update: player May 31 11:11:22 volumio volumio[1127]: info: ControllerMpd::getState May 31 11:11:22 volumio volumio[1127]: verbose: ControllerMpd::sendMpdCommand status May 31 11:11:22 volumio volumio[1127]: info: May 31 11:11:22 volumio volumio[1127]: ---------------------------- MPD announces state update: player May 31 11:11:22 volumio volumio[1127]: info: ControllerMpd::getState May 31 11:11:22 volumio volumio[1127]: verbose: ControllerMpd::sendMpdCommand status May 31 11:11:22 volumio volumio[1127]: info: May 31 11:11:22 volumio volumio[1127]: ---------------------------- MPD announces state update: player May 31 11:11:22 volumio volumio[1127]: info: ControllerMpd::getState May 31 11:11:22 volumio volumio[1127]: verbose: ControllerMpd::sendMpdCommand status May 31 11:11:22 volumio volumio[1127]: info: May 31 11:11:22 volumio volumio[1127]: ---------------------------- MPD announces state update: player May 31 11:11:22 volumio volumio[1127]: info: ControllerMpd::getState May 31 11:11:22 volumio volumio[1127]: verbose: ControllerMpd::sendMpdCommand status May 31 11:11:22 volumio volumio[1127]: info: sendMpdCommand status took 1 milliseconds May 31 11:11:22 volumio volumio[1127]: info: sendMpdCommand status took 1 milliseconds May 31 11:11:22 volumio volumio[1127]: info: sendMpdCommand status took 1 milliseconds May 31 11:11:22 volumio volumio[1127]: info: sendMpdCommand status took 1 milliseconds May 31 11:11:22 volumio volumio[1127]: info: sendMpdCommand status took 0 milliseconds May 31 11:11:22 volumio volumio[1127]: verbose: ControllerMpd::parseState May 31 11:11:22 volumio volumio[1127]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 11:11:22 volumio volumio[1127]: verbose: ControllerMpd::parseState May 31 11:11:22 volumio volumio[1127]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 11:11:22 volumio volumio[1127]: verbose: ControllerMpd::parseState May 31 11:11:22 volumio volumio[1127]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 11:11:22 volumio volumio[1127]: verbose: ControllerMpd::parseState May 31 11:11:22 volumio volumio[1127]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 11:11:22 volumio volumio[1127]: verbose: ControllerMpd::parseState May 31 11:11:22 volumio volumio[1127]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 11:11:22 volumio volumio[1127]: info: sendMpdCommand playlistinfo took 1 milliseconds May 31 11:11:22 volumio volumio[1127]: info: sendMpdCommand playlistinfo took 1 milliseconds May 31 11:11:22 volumio volumio[1127]: info: sendMpdCommand playlistinfo took 1 milliseconds May 31 11:11:22 volumio volumio[1127]: info: sendMpdCommand playlistinfo took 2 milliseconds May 31 11:11:22 volumio volumio[1127]: info: sendMpdCommand playlistinfo took 1 milliseconds May 31 11:11:22 volumio volumio[1127]: verbose: ControllerMpd::parseTrackInfo May 31 11:11:22 volumio volumio[1127]: verbose: ControllerMpd::parseTrackInfo May 31 11:11:22 volumio volumio[1127]: verbose: ControllerMpd::parseTrackInfo May 31 11:11:22 volumio volumio[1127]: verbose: ControllerMpd::parseTrackInfo May 31 11:11:22 volumio volumio[1127]: verbose: ControllerMpd::parseTrackInfo May 31 11:11:22 volumio volumio[1127]: info: ControllerMpd::pushState May 31 11:11:22 volumio volumio[1127]: info: CoreCommandRouter::servicePushState May 31 11:11:22 volumio volumio[1127]: info: CorePlayQueue::getTrack 1 May 31 11:11:22 volumio volumio[1127]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":285,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"4615 Kbps","isStreaming":false,"title":"君が光に変えて行く","artist":"Kalafina","album":"Kalafina All Time Best 2008-2018","uri":"USB/Music/ACG/Kalafina - Kalafina All Time Best 2008-2018 (FLAC 24bit／96kHz)/02. 君が光に変えて行く.flac","trackType":"flac"} May 31 11:11:22 volumio volumio[1127]: verbose: CURRENT POSITION 1 May 31 11:11:22 volumio volumio[1127]: info: CoreStateMachine::syncState stateService play May 31 11:11:22 volumio volumio[1127]: info: CoreStateMachine::syncState currentStatus play May 31 11:11:22 volumio volumio[1127]: info: Received an update from plugin. extracting info from payload May 31 11:11:22 volumio volumio[1127]: info: CoreStateMachine::pushState May 31 11:11:22 volumio volumio[1127]: info: CorePlayQueue::getTrack 1 May 31 11:11:22 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 11:11:22 volumio volumio[1127]: info: CoreCommandRouter::volumioPushState May 31 11:11:22 volumio volumio[1127]: info: MRS: Pushing multiroomSync output update for this device May 31 11:11:22 volumio volumio[1127]: info: MRS: Pushing multiroomSync output May 31 11:11:22 volumio volumio[1127]: info: CoreStateMachine::pushState May 31 11:11:22 volumio volumio[1127]: info: CorePlayQueue::getTrack 1 May 31 11:11:22 volumio volumio[1127]: info: CoreCommandRouter::volumioPushState May 31 11:11:22 volumio volumio[1127]: info: MRS: Pushing multiroomSync output update for this device May 31 11:11:22 volumio volumio[1127]: info: MRS: Pushing multiroomSync output May 31 11:11:22 volumio volumio[1127]: info: ControllerMpd::pushState May 31 11:11:22 volumio volumio[1127]: info: CoreCommandRouter::servicePushState May 31 11:11:22 volumio volumio[1127]: info: CorePlayQueue::getTrack 1 May 31 11:11:22 volumio volumio[1127]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":285,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"4615 Kbps","isStreaming":false,"title":"君が光に変えて行く","artist":"Kalafina","album":"Kalafina All Time Best 2008-2018","uri":"USB/Music/ACG/Kalafina - Kalafina All Time Best 2008-2018 (FLAC 24bit／96kHz)/02. 君が光に変えて行く.flac","trackType":"flac"} May 31 11:11:22 volumio volumio[1127]: verbose: CURRENT POSITION 1 May 31 11:11:22 volumio volumio[1127]: info: CoreStateMachine::syncState stateService play May 31 11:11:22 volumio volumio[1127]: info: CoreStateMachine::syncState currentStatus play May 31 11:11:22 volumio volumio[1127]: info: Received an update from plugin. extracting info from payload May 31 11:11:22 volumio volumio[1127]: info: CoreStateMachine::pushState May 31 11:11:22 volumio volumio[1127]: info: CorePlayQueue::getTrack 1 May 31 11:11:22 volumio volumio[1127]: info: CoreCommandRouter::volumioPushState May 31 11:11:22 volumio volumio[1127]: info: MRS: Pushing multiroomSync output update for this device May 31 11:11:22 volumio volumio[1127]: info: MRS: Pushing multiroomSync output May 31 11:11:22 volumio volumio[1127]: info: CoreStateMachine::pushState May 31 11:11:22 volumio volumio[1127]: info: CorePlayQueue::getTrack 1 May 31 11:11:22 volumio volumio[1127]: info: CoreCommandRouter::volumioPushState May 31 11:11:22 volumio volumio[1127]: info: MRS: Pushing multiroomSync output update for this device May 31 11:11:22 volumio volumio[1127]: info: MRS: Pushing multiroomSync output May 31 11:11:22 volumio volumio[1127]: info: ControllerMpd::pushState May 31 11:11:22 volumio volumio[1127]: info: CoreCommandRouter::servicePushState May 31 11:11:22 volumio volumio[1127]: info: CorePlayQueue::getTrack 1 May 31 11:11:22 volumio volumio[1127]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":285,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"4615 Kbps","isStreaming":false,"title":"君が光に変えて行く","artist":"Kalafina","album":"Kalafina All Time Best 2008-2018","uri":"USB/Music/ACG/Kalafina - Kalafina All Time Best 2008-2018 (FLAC 24bit／96kHz)/02. 君が光に変えて行く.flac","trackType":"flac"} May 31 11:11:22 volumio volumio[1127]: verbose: CURRENT POSITION 1 May 31 11:11:22 volumio volumio[1127]: info: CoreStateMachine::syncState stateService play May 31 11:11:22 volumio volumio[1127]: info: CoreStateMachine::syncState currentStatus play May 31 11:11:22 volumio volumio[1127]: info: Received an update from plugin. extracting info from payload May 31 11:11:22 volumio volumio[1127]: info: CoreStateMachine::pushState May 31 11:11:22 volumio volumio[1127]: info: CorePlayQueue::getTrack 1 May 31 11:11:22 volumio volumio[1127]: info: CoreCommandRouter::volumioPushState May 31 11:11:22 volumio volumio[1127]: info: MRS: Pushing multiroomSync output update for this device May 31 11:11:22 volumio volumio[1127]: info: MRS: Pushing multiroomSync output May 31 11:11:22 volumio volumio[1127]: info: CoreStateMachine::pushState May 31 11:11:22 volumio volumio[1127]: info: CorePlayQueue::getTrack 1 May 31 11:11:22 volumio volumio[1127]: info: CoreCommandRouter::volumioPushState May 31 11:11:22 volumio volumio[1127]: info: MRS: Pushing multiroomSync output update for this device May 31 11:11:22 volumio volumio[1127]: info: MRS: Pushing multiroomSync output May 31 11:11:22 volumio volumio[1127]: info: ControllerMpd::pushState May 31 11:11:22 volumio volumio[1127]: info: CoreCommandRouter::servicePushState May 31 11:11:22 volumio volumio[1127]: info: CorePlayQueue::getTrack 1 May 31 11:11:22 volumio volumio[1127]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":285,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"4615 Kbps","isStreaming":false,"title":"君が光に変えて行く","artist":"Kalafina","album":"Kalafina All Time Best 2008-2018","uri":"USB/Music/ACG/Kalafina - Kalafina All Time Best 2008-2018 (FLAC 24bit／96kHz)/02. 君が光に変えて行く.flac","trackType":"flac"} May 31 11:11:22 volumio volumio[1127]: verbose: CURRENT POSITION 1 May 31 11:11:22 volumio volumio[1127]: info: CoreStateMachine::syncState stateService play May 31 11:11:22 volumio volumio[1127]: info: CoreStateMachine::syncState currentStatus play May 31 11:11:22 volumio volumio[1127]: info: Received an update from plugin. extracting info from payload May 31 11:11:22 volumio volumio[1127]: info: CoreStateMachine::pushState May 31 11:11:22 volumio volumio[1127]: info: CorePlayQueue::getTrack 1 May 31 11:11:22 volumio volumio[1127]: info: CoreCommandRouter::volumioPushState May 31 11:11:22 volumio volumio[1127]: info: MRS: Pushing multiroomSync output update for this device May 31 11:11:22 volumio volumio[1127]: info: MRS: Pushing multiroomSync output May 31 11:11:22 volumio volumio[1127]: info: CoreStateMachine::pushState May 31 11:11:22 volumio volumio[1127]: info: CorePlayQueue::getTrack 1 May 31 11:11:22 volumio volumio[1127]: info: CoreCommandRouter::volumioPushState May 31 11:11:22 volumio volumio[1127]: info: MRS: Pushing multiroomSync output update for this device May 31 11:11:22 volumio volumio[1127]: info: MRS: Pushing multiroomSync output May 31 11:11:22 volumio volumio[1127]: info: ControllerMpd::pushState May 31 11:11:22 volumio volumio[1127]: info: CoreCommandRouter::servicePushState May 31 11:11:22 volumio volumio[1127]: info: CorePlayQueue::getTrack 1 May 31 11:11:22 volumio volumio[1127]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":285,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"4615 Kbps","isStreaming":false,"title":"君が光に変えて行く","artist":"Kalafina","album":"Kalafina All Time Best 2008-2018","uri":"USB/Music/ACG/Kalafina - Kalafina All Time Best 2008-2018 (FLAC 24bit／96kHz)/02. 君が光に変えて行く.flac","trackType":"flac"} May 31 11:11:22 volumio volumio[1127]: verbose: CURRENT POSITION 1 May 31 11:11:22 volumio volumio[1127]: info: CoreStateMachine::syncState stateService play May 31 11:11:22 volumio volumio[1127]: info: CoreStateMachine::syncState currentStatus play May 31 11:11:22 volumio volumio[1127]: info: Received an update from plugin. extracting info from payload May 31 11:11:22 volumio volumio[1127]: info: CoreStateMachine::pushState May 31 11:11:22 volumio volumio[1127]: info: CorePlayQueue::getTrack 1 May 31 11:11:22 volumio volumio[1127]: info: CoreCommandRouter::volumioPushState May 31 11:11:22 volumio volumio[1127]: info: MRS: Pushing multiroomSync output update for this device May 31 11:11:22 volumio volumio[1127]: info: MRS: Pushing multiroomSync output May 31 11:11:22 volumio volumio[1127]: info: CoreStateMachine::pushState May 31 11:11:22 volumio volumio[1127]: info: CorePlayQueue::getTrack 1 May 31 11:11:22 volumio volumio[1127]: info: CoreCommandRouter::volumioPushState May 31 11:11:22 volumio volumio[1127]: info: MRS: Pushing multiroomSync output update for this device May 31 11:11:22 volumio volumio[1127]: info: MRS: Pushing multiroomSync output May 31 11:11:22 volumio volumio[1127]: info: ------------------------------ 35ms May 31 11:11:22 volumio volumio[1127]: info: ------------------------------ 36ms May 31 11:11:22 volumio volumio[1127]: info: ------------------------------ 36ms May 31 11:11:22 volumio volumio[1127]: info: ------------------------------ 36ms May 31 11:11:22 volumio volumio[1127]: info: ------------------------------ 36ms May 31 11:11:52 volumio volumio[1127]: info: CoreCommandRouter::volumioGetState May 31 11:11:52 volumio volumio[1127]: info: CorePlayQueue::getTrack 1 May 31 11:11:54 volumio volumio[1127]: info: Executing endpoint metavolumio May 31 11:11:54 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 31 11:11:54 volumio volumio[1127]: info: Executing endpoint metavolumio May 31 11:11:54 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 31 11:11:54 volumio volumio[1127]: info: Executing endpoint metavolumio May 31 11:11:54 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 31 11:11:56 volumio volumio[1127]: info: CoreCommandRouter::volumioPrevious May 31 11:11:56 volumio volumio[1127]: info: CoreStateMachine::previous May 31 11:11:56 volumio volumio[1127]: info: CorePlayQueue::getTrack 1 May 31 11:11:56 volumio volumio[1127]: info: ControllerMpd::seek May 31 11:11:56 volumio volumio[1127]: error: null May 31 11:11:56 volumio volumio[1127]: info: May 31 11:11:56 volumio volumio[1127]: ---------------------------- MPD announces state update: player May 31 11:11:56 volumio volumio[1127]: info: ControllerMpd::getState May 31 11:11:56 volumio volumio[1127]: verbose: ControllerMpd::sendMpdCommand status May 31 11:11:56 volumio volumio[1127]: info: May 31 11:11:56 volumio volumio[1127]: ---------------------------- MPD announces state update: player May 31 11:11:56 volumio volumio[1127]: info: ControllerMpd::getState May 31 11:11:56 volumio volumio[1127]: verbose: ControllerMpd::sendMpdCommand status May 31 11:11:56 volumio volumio[1127]: info: May 31 11:11:56 volumio volumio[1127]: ---------------------------- MPD announces state update: player May 31 11:11:56 volumio volumio[1127]: info: ControllerMpd::getState May 31 11:11:56 volumio volumio[1127]: verbose: ControllerMpd::sendMpdCommand status May 31 11:11:56 volumio volumio[1127]: info: May 31 11:11:56 volumio volumio[1127]: ---------------------------- MPD announces state update: player May 31 11:11:56 volumio volumio[1127]: info: ControllerMpd::getState May 31 11:11:56 volumio volumio[1127]: verbose: ControllerMpd::sendMpdCommand status May 31 11:11:56 volumio volumio[1127]: info: May 31 11:11:56 volumio volumio[1127]: ---------------------------- MPD announces state update: player May 31 11:11:56 volumio volumio[1127]: info: ControllerMpd::getState May 31 11:11:56 volumio volumio[1127]: verbose: ControllerMpd::sendMpdCommand status May 31 11:11:56 volumio volumio[1127]: info: sendMpdCommand status took 1 milliseconds May 31 11:11:56 volumio volumio[1127]: info: sendMpdCommand status took 0 milliseconds May 31 11:11:56 volumio volumio[1127]: info: sendMpdCommand status took 1 milliseconds May 31 11:11:56 volumio volumio[1127]: info: sendMpdCommand status took 1 milliseconds May 31 11:11:56 volumio volumio[1127]: info: sendMpdCommand status took 1 milliseconds May 31 11:11:56 volumio volumio[1127]: verbose: ControllerMpd::parseState May 31 11:11:56 volumio volumio[1127]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 11:11:56 volumio volumio[1127]: verbose: ControllerMpd::parseState May 31 11:11:56 volumio volumio[1127]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 11:11:56 volumio volumio[1127]: verbose: ControllerMpd::parseState May 31 11:11:56 volumio volumio[1127]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 11:11:56 volumio volumio[1127]: verbose: ControllerMpd::parseState May 31 11:11:56 volumio volumio[1127]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 11:11:56 volumio volumio[1127]: verbose: ControllerMpd::parseState May 31 11:11:56 volumio volumio[1127]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 11:11:56 volumio volumio[1127]: info: sendMpdCommand playlistinfo took 0 milliseconds May 31 11:11:56 volumio volumio[1127]: info: sendMpdCommand playlistinfo took 0 milliseconds May 31 11:11:56 volumio volumio[1127]: info: sendMpdCommand playlistinfo took 0 milliseconds May 31 11:11:56 volumio volumio[1127]: info: sendMpdCommand playlistinfo took 0 milliseconds May 31 11:11:56 volumio volumio[1127]: info: sendMpdCommand playlistinfo took 0 milliseconds May 31 11:11:56 volumio volumio[1127]: verbose: ControllerMpd::parseTrackInfo May 31 11:11:56 volumio volumio[1127]: verbose: ControllerMpd::parseTrackInfo May 31 11:11:56 volumio volumio[1127]: verbose: ControllerMpd::parseTrackInfo May 31 11:11:56 volumio volumio[1127]: verbose: ControllerMpd::parseTrackInfo May 31 11:11:56 volumio volumio[1127]: verbose: ControllerMpd::parseTrackInfo May 31 11:11:56 volumio volumio[1127]: info: ControllerMpd::pushState May 31 11:11:56 volumio volumio[1127]: info: CoreCommandRouter::servicePushState May 31 11:11:56 volumio volumio[1127]: info: CorePlayQueue::getTrack 1 May 31 11:11:56 volumio volumio[1127]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":285,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"4616 Kbps","isStreaming":false,"title":"君が光に変えて行く","artist":"Kalafina","album":"Kalafina All Time Best 2008-2018","uri":"USB/Music/ACG/Kalafina - Kalafina All Time Best 2008-2018 (FLAC 24bit／96kHz)/02. 君が光に変えて行く.flac","trackType":"flac"} May 31 11:11:56 volumio volumio[1127]: verbose: CURRENT POSITION 1 May 31 11:11:56 volumio volumio[1127]: info: CoreStateMachine::syncState stateService play May 31 11:11:56 volumio volumio[1127]: info: CoreStateMachine::syncState currentStatus play May 31 11:11:56 volumio volumio[1127]: info: Received an update from plugin. extracting info from payload May 31 11:11:56 volumio volumio[1127]: info: CoreStateMachine::pushState May 31 11:11:56 volumio volumio[1127]: info: CorePlayQueue::getTrack 1 May 31 11:11:56 volumio volumio[1127]: info: CoreCommandRouter::volumioPushState May 31 11:11:56 volumio volumio[1127]: info: MRS: Pushing multiroomSync output update for this device May 31 11:11:56 volumio volumio[1127]: info: MRS: Pushing multiroomSync output May 31 11:11:56 volumio volumio[1127]: info: CoreStateMachine::pushState May 31 11:11:56 volumio volumio[1127]: info: CorePlayQueue::getTrack 1 May 31 11:11:56 volumio volumio[1127]: info: CoreCommandRouter::volumioPushState May 31 11:11:56 volumio volumio[1127]: info: MRS: Pushing multiroomSync output update for this device May 31 11:11:56 volumio volumio[1127]: info: MRS: Pushing multiroomSync output May 31 11:11:56 volumio volumio[1127]: info: ControllerMpd::pushState May 31 11:11:56 volumio volumio[1127]: info: CoreCommandRouter::servicePushState May 31 11:11:56 volumio volumio[1127]: info: CorePlayQueue::getTrack 1 May 31 11:11:56 volumio volumio[1127]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":285,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"4616 Kbps","isStreaming":false,"title":"君が光に変えて行く","artist":"Kalafina","album":"Kalafina All Time Best 2008-2018","uri":"USB/Music/ACG/Kalafina - Kalafina All Time Best 2008-2018 (FLAC 24bit／96kHz)/02. 君が光に変えて行く.flac","trackType":"flac"} May 31 11:11:56 volumio volumio[1127]: verbose: CURRENT POSITION 1 May 31 11:11:56 volumio volumio[1127]: info: CoreStateMachine::syncState stateService play May 31 11:11:56 volumio volumio[1127]: info: CoreStateMachine::syncState currentStatus play May 31 11:11:56 volumio volumio[1127]: info: Received an update from plugin. extracting info from payload May 31 11:11:56 volumio volumio[1127]: info: CoreStateMachine::pushState May 31 11:11:56 volumio volumio[1127]: info: CorePlayQueue::getTrack 1 May 31 11:11:56 volumio volumio[1127]: info: CoreCommandRouter::volumioPushState May 31 11:11:56 volumio volumio[1127]: info: MRS: Pushing multiroomSync output update for this device May 31 11:11:56 volumio volumio[1127]: info: MRS: Pushing multiroomSync output May 31 11:11:56 volumio volumio[1127]: info: CoreStateMachine::pushState May 31 11:11:56 volumio volumio[1127]: info: CorePlayQueue::getTrack 1 May 31 11:11:56 volumio volumio[1127]: info: CoreCommandRouter::volumioPushState May 31 11:11:56 volumio volumio[1127]: info: MRS: Pushing multiroomSync output update for this device May 31 11:11:56 volumio volumio[1127]: info: MRS: Pushing multiroomSync output May 31 11:11:56 volumio volumio[1127]: info: ControllerMpd::pushState May 31 11:11:56 volumio volumio[1127]: info: CoreCommandRouter::servicePushState May 31 11:11:56 volumio volumio[1127]: info: CorePlayQueue::getTrack 1 May 31 11:11:56 volumio volumio[1127]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":285,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"4616 Kbps","isStreaming":false,"title":"君が光に変えて行く","artist":"Kalafina","album":"Kalafina All Time Best 2008-2018","uri":"USB/Music/ACG/Kalafina - Kalafina All Time Best 2008-2018 (FLAC 24bit／96kHz)/02. 君が光に変えて行く.flac","trackType":"flac"} May 31 11:11:56 volumio volumio[1127]: verbose: CURRENT POSITION 1 May 31 11:11:56 volumio volumio[1127]: info: CoreStateMachine::syncState stateService play May 31 11:11:56 volumio volumio[1127]: info: CoreStateMachine::syncState currentStatus play May 31 11:11:56 volumio volumio[1127]: info: Received an update from plugin. extracting info from payload May 31 11:11:56 volumio volumio[1127]: info: CoreStateMachine::pushState May 31 11:11:56 volumio volumio[1127]: info: CorePlayQueue::getTrack 1 May 31 11:11:56 volumio volumio[1127]: info: CoreCommandRouter::volumioPushState May 31 11:11:56 volumio volumio[1127]: info: MRS: Pushing multiroomSync output update for this device May 31 11:11:56 volumio volumio[1127]: info: MRS: Pushing multiroomSync output May 31 11:11:56 volumio volumio[1127]: info: CoreStateMachine::pushState May 31 11:11:56 volumio volumio[1127]: info: CorePlayQueue::getTrack 1 May 31 11:11:56 volumio volumio[1127]: info: CoreCommandRouter::volumioPushState May 31 11:11:56 volumio volumio[1127]: info: MRS: Pushing multiroomSync output update for this device May 31 11:11:56 volumio volumio[1127]: info: MRS: Pushing multiroomSync output May 31 11:11:56 volumio volumio[1127]: info: ControllerMpd::pushState May 31 11:11:56 volumio volumio[1127]: info: CoreCommandRouter::servicePushState May 31 11:11:56 volumio volumio[1127]: info: CorePlayQueue::getTrack 1 May 31 11:11:56 volumio volumio[1127]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":285,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"4616 Kbps","isStreaming":false,"title":"君が光に変えて行く","artist":"Kalafina","album":"Kalafina All Time Best 2008-2018","uri":"USB/Music/ACG/Kalafina - Kalafina All Time Best 2008-2018 (FLAC 24bit／96kHz)/02. 君が光に変えて行く.flac","trackType":"flac"} May 31 11:11:56 volumio volumio[1127]: verbose: CURRENT POSITION 1 May 31 11:11:56 volumio volumio[1127]: info: CoreStateMachine::syncState stateService play May 31 11:11:56 volumio volumio[1127]: info: CoreStateMachine::syncState currentStatus play May 31 11:11:56 volumio volumio[1127]: info: Received an update from plugin. extracting info from payload May 31 11:11:56 volumio volumio[1127]: info: CoreStateMachine::pushState May 31 11:11:56 volumio volumio[1127]: info: CorePlayQueue::getTrack 1 May 31 11:11:56 volumio volumio[1127]: info: CoreCommandRouter::volumioPushState May 31 11:11:56 volumio volumio[1127]: info: MRS: Pushing multiroomSync output update for this device May 31 11:11:56 volumio volumio[1127]: info: MRS: Pushing multiroomSync output May 31 11:11:56 volumio volumio[1127]: info: CoreStateMachine::pushState May 31 11:11:56 volumio volumio[1127]: info: CorePlayQueue::getTrack 1 May 31 11:11:56 volumio volumio[1127]: info: CoreCommandRouter::volumioPushState May 31 11:11:56 volumio volumio[1127]: info: MRS: Pushing multiroomSync output update for this device May 31 11:11:56 volumio volumio[1127]: info: MRS: Pushing multiroomSync output May 31 11:11:56 volumio volumio[1127]: info: ControllerMpd::pushState May 31 11:11:56 volumio volumio[1127]: info: CoreCommandRouter::servicePushState May 31 11:11:56 volumio volumio[1127]: info: CorePlayQueue::getTrack 1 May 31 11:11:56 volumio volumio[1127]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":285,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"4616 Kbps","isStreaming":false,"title":"君が光に変えて行く","artist":"Kalafina","album":"Kalafina All Time Best 2008-2018","uri":"USB/Music/ACG/Kalafina - Kalafina All Time Best 2008-2018 (FLAC 24bit／96kHz)/02. 君が光に変えて行く.flac","trackType":"flac"} May 31 11:11:56 volumio volumio[1127]: verbose: CURRENT POSITION 1 May 31 11:11:56 volumio volumio[1127]: info: CoreStateMachine::syncState stateService play May 31 11:11:56 volumio volumio[1127]: info: CoreStateMachine::syncState currentStatus play May 31 11:11:56 volumio volumio[1127]: info: Received an update from plugin. extracting info from payload May 31 11:11:56 volumio volumio[1127]: info: CoreStateMachine::pushState May 31 11:11:56 volumio volumio[1127]: info: CorePlayQueue::getTrack 1 May 31 11:11:56 volumio volumio[1127]: info: CoreCommandRouter::volumioPushState May 31 11:11:56 volumio volumio[1127]: info: MRS: Pushing multiroomSync output update for this device May 31 11:11:56 volumio volumio[1127]: info: MRS: Pushing multiroomSync output May 31 11:11:56 volumio volumio[1127]: info: CoreStateMachine::pushState May 31 11:11:56 volumio volumio[1127]: info: CorePlayQueue::getTrack 1 May 31 11:11:56 volumio volumio[1127]: info: CoreCommandRouter::volumioPushState May 31 11:11:56 volumio volumio[1127]: info: MRS: Pushing multiroomSync output update for this device May 31 11:11:56 volumio volumio[1127]: info: MRS: Pushing multiroomSync output May 31 11:11:56 volumio volumio[1127]: info: ------------------------------ 37ms May 31 11:11:56 volumio volumio[1127]: info: ------------------------------ 38ms May 31 11:11:56 volumio volumio[1127]: info: ------------------------------ 37ms May 31 11:11:56 volumio volumio[1127]: info: ------------------------------ 37ms May 31 11:11:56 volumio volumio[1127]: info: ------------------------------ 37ms May 31 11:11:59 volumio volumio[1127]: info: CoreCommandRouter::volumioPrevious May 31 11:11:59 volumio volumio[1127]: info: CoreStateMachine::previous May 31 11:11:59 volumio volumio[1127]: info: CorePlayQueue::getTrack 1 May 31 11:11:59 volumio volumio[1127]: info: ControllerMpd::seek May 31 11:11:59 volumio volumio[1127]: error: null May 31 11:11:59 volumio volumio[1127]: info: May 31 11:11:59 volumio volumio[1127]: ---------------------------- MPD announces state update: player May 31 11:11:59 volumio volumio[1127]: info: ControllerMpd::getState May 31 11:11:59 volumio volumio[1127]: verbose: ControllerMpd::sendMpdCommand status May 31 11:11:59 volumio volumio[1127]: info: May 31 11:11:59 volumio volumio[1127]: ---------------------------- MPD announces state update: player May 31 11:11:59 volumio volumio[1127]: info: ControllerMpd::getState May 31 11:11:59 volumio volumio[1127]: verbose: ControllerMpd::sendMpdCommand status May 31 11:11:59 volumio volumio[1127]: info: May 31 11:11:59 volumio volumio[1127]: ---------------------------- MPD announces state update: player May 31 11:11:59 volumio volumio[1127]: info: ControllerMpd::getState May 31 11:11:59 volumio volumio[1127]: verbose: ControllerMpd::sendMpdCommand status May 31 11:11:59 volumio volumio[1127]: info: May 31 11:11:59 volumio volumio[1127]: ---------------------------- MPD announces state update: player May 31 11:11:59 volumio volumio[1127]: info: ControllerMpd::getState May 31 11:11:59 volumio volumio[1127]: verbose: ControllerMpd::sendMpdCommand status May 31 11:11:59 volumio volumio[1127]: info: May 31 11:11:59 volumio volumio[1127]: ---------------------------- MPD announces state update: player May 31 11:11:59 volumio volumio[1127]: info: ControllerMpd::getState May 31 11:11:59 volumio volumio[1127]: verbose: ControllerMpd::sendMpdCommand status May 31 11:11:59 volumio volumio[1127]: info: sendMpdCommand status took 1 milliseconds May 31 11:11:59 volumio volumio[1127]: info: sendMpdCommand status took 0 milliseconds May 31 11:11:59 volumio volumio[1127]: info: sendMpdCommand status took 0 milliseconds May 31 11:11:59 volumio volumio[1127]: info: sendMpdCommand status took 0 milliseconds May 31 11:11:59 volumio volumio[1127]: info: sendMpdCommand status took 1 milliseconds May 31 11:11:59 volumio volumio[1127]: verbose: ControllerMpd::parseState May 31 11:11:59 volumio volumio[1127]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 11:11:59 volumio volumio[1127]: verbose: ControllerMpd::parseState May 31 11:11:59 volumio volumio[1127]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 11:11:59 volumio volumio[1127]: verbose: ControllerMpd::parseState May 31 11:11:59 volumio volumio[1127]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 11:11:59 volumio volumio[1127]: verbose: ControllerMpd::parseState May 31 11:11:59 volumio volumio[1127]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 11:11:59 volumio volumio[1127]: verbose: ControllerMpd::parseState May 31 11:11:59 volumio volumio[1127]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 11:11:59 volumio volumio[1127]: info: sendMpdCommand playlistinfo took 0 milliseconds May 31 11:11:59 volumio volumio[1127]: info: sendMpdCommand playlistinfo took 0 milliseconds May 31 11:11:59 volumio volumio[1127]: info: sendMpdCommand playlistinfo took 0 milliseconds May 31 11:11:59 volumio volumio[1127]: info: sendMpdCommand playlistinfo took 0 milliseconds May 31 11:11:59 volumio volumio[1127]: info: sendMpdCommand playlistinfo took 0 milliseconds May 31 11:11:59 volumio volumio[1127]: verbose: ControllerMpd::parseTrackInfo May 31 11:11:59 volumio volumio[1127]: verbose: ControllerMpd::parseTrackInfo May 31 11:11:59 volumio volumio[1127]: verbose: ControllerMpd::parseTrackInfo May 31 11:11:59 volumio volumio[1127]: verbose: ControllerMpd::parseTrackInfo May 31 11:11:59 volumio volumio[1127]: verbose: ControllerMpd::parseTrackInfo May 31 11:11:59 volumio volumio[1127]: info: ControllerMpd::pushState May 31 11:11:59 volumio volumio[1127]: info: CoreCommandRouter::servicePushState May 31 11:11:59 volumio volumio[1127]: info: CorePlayQueue::getTrack 1 May 31 11:11:59 volumio volumio[1127]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":285,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"4615 Kbps","isStreaming":false,"title":"君が光に変えて行く","artist":"Kalafina","album":"Kalafina All Time Best 2008-2018","uri":"USB/Music/ACG/Kalafina - Kalafina All Time Best 2008-2018 (FLAC 24bit／96kHz)/02. 君が光に変えて行く.flac","trackType":"flac"} May 31 11:11:59 volumio volumio[1127]: verbose: CURRENT POSITION 1 May 31 11:11:59 volumio volumio[1127]: info: CoreStateMachine::syncState stateService play May 31 11:11:59 volumio volumio[1127]: info: CoreStateMachine::syncState currentStatus play May 31 11:11:59 volumio volumio[1127]: info: Received an update from plugin. extracting info from payload May 31 11:11:59 volumio volumio[1127]: info: CoreStateMachine::pushState May 31 11:11:59 volumio volumio[1127]: info: CorePlayQueue::getTrack 1 May 31 11:11:59 volumio volumio[1127]: info: CoreCommandRouter::volumioPushState May 31 11:11:59 volumio volumio[1127]: info: MRS: Pushing multiroomSync output update for this device May 31 11:11:59 volumio volumio[1127]: info: MRS: Pushing multiroomSync output May 31 11:11:59 volumio volumio[1127]: info: CoreStateMachine::pushState May 31 11:11:59 volumio volumio[1127]: info: CorePlayQueue::getTrack 1 May 31 11:11:59 volumio volumio[1127]: info: CoreCommandRouter::volumioPushState May 31 11:11:59 volumio volumio[1127]: info: MRS: Pushing multiroomSync output update for this device May 31 11:11:59 volumio volumio[1127]: info: MRS: Pushing multiroomSync output May 31 11:11:59 volumio volumio[1127]: info: ControllerMpd::pushState May 31 11:11:59 volumio volumio[1127]: info: CoreCommandRouter::servicePushState May 31 11:11:59 volumio volumio[1127]: info: CorePlayQueue::getTrack 1 May 31 11:11:59 volumio volumio[1127]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":285,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"4615 Kbps","isStreaming":false,"title":"君が光に変えて行く","artist":"Kalafina","album":"Kalafina All Time Best 2008-2018","uri":"USB/Music/ACG/Kalafina - Kalafina All Time Best 2008-2018 (FLAC 24bit／96kHz)/02. 君が光に変えて行く.flac","trackType":"flac"} May 31 11:11:59 volumio volumio[1127]: verbose: CURRENT POSITION 1 May 31 11:11:59 volumio volumio[1127]: info: CoreStateMachine::syncState stateService play May 31 11:11:59 volumio volumio[1127]: info: CoreStateMachine::syncState currentStatus play May 31 11:11:59 volumio volumio[1127]: info: Received an update from plugin. extracting info from payload May 31 11:11:59 volumio volumio[1127]: info: CoreStateMachine::pushState May 31 11:11:59 volumio volumio[1127]: info: CorePlayQueue::getTrack 1 May 31 11:11:59 volumio volumio[1127]: info: CoreCommandRouter::volumioPushState May 31 11:11:59 volumio volumio[1127]: info: MRS: Pushing multiroomSync output update for this device May 31 11:11:59 volumio volumio[1127]: info: MRS: Pushing multiroomSync output May 31 11:11:59 volumio volumio[1127]: info: CoreStateMachine::pushState May 31 11:11:59 volumio volumio[1127]: info: CorePlayQueue::getTrack 1 May 31 11:11:59 volumio volumio[1127]: info: CoreCommandRouter::volumioPushState May 31 11:11:59 volumio volumio[1127]: info: MRS: Pushing multiroomSync output update for this device May 31 11:11:59 volumio volumio[1127]: info: MRS: Pushing multiroomSync output May 31 11:11:59 volumio volumio[1127]: info: ControllerMpd::pushState May 31 11:11:59 volumio volumio[1127]: info: CoreCommandRouter::servicePushState May 31 11:11:59 volumio volumio[1127]: info: CorePlayQueue::getTrack 1 May 31 11:11:59 volumio volumio[1127]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":285,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"4615 Kbps","isStreaming":false,"title":"君が光に変えて行く","artist":"Kalafina","album":"Kalafina All Time Best 2008-2018","uri":"USB/Music/ACG/Kalafina - Kalafina All Time Best 2008-2018 (FLAC 24bit／96kHz)/02. 君が光に変えて行く.flac","trackType":"flac"} May 31 11:11:59 volumio volumio[1127]: verbose: CURRENT POSITION 1 May 31 11:11:59 volumio volumio[1127]: info: CoreStateMachine::syncState stateService play May 31 11:11:59 volumio volumio[1127]: info: CoreStateMachine::syncState currentStatus play May 31 11:11:59 volumio volumio[1127]: info: Received an update from plugin. extracting info from payload May 31 11:11:59 volumio volumio[1127]: info: CoreStateMachine::pushState May 31 11:11:59 volumio volumio[1127]: info: CorePlayQueue::getTrack 1 May 31 11:11:59 volumio volumio[1127]: info: CoreCommandRouter::volumioPushState May 31 11:11:59 volumio volumio[1127]: info: MRS: Pushing multiroomSync output update for this device May 31 11:11:59 volumio volumio[1127]: info: MRS: Pushing multiroomSync output May 31 11:11:59 volumio volumio[1127]: info: CoreStateMachine::pushState May 31 11:11:59 volumio volumio[1127]: info: CorePlayQueue::getTrack 1 May 31 11:11:59 volumio volumio[1127]: info: CoreCommandRouter::volumioPushState May 31 11:11:59 volumio volumio[1127]: info: MRS: Pushing multiroomSync output update for this device May 31 11:11:59 volumio volumio[1127]: info: MRS: Pushing multiroomSync output May 31 11:11:59 volumio volumio[1127]: info: ControllerMpd::pushState May 31 11:11:59 volumio volumio[1127]: info: CoreCommandRouter::servicePushState May 31 11:11:59 volumio volumio[1127]: info: CorePlayQueue::getTrack 1 May 31 11:11:59 volumio volumio[1127]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":285,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"4615 Kbps","isStreaming":false,"title":"君が光に変えて行く","artist":"Kalafina","album":"Kalafina All Time Best 2008-2018","uri":"USB/Music/ACG/Kalafina - Kalafina All Time Best 2008-2018 (FLAC 24bit／96kHz)/02. 君が光に変えて行く.flac","trackType":"flac"} May 31 11:11:59 volumio volumio[1127]: verbose: CURRENT POSITION 1 May 31 11:11:59 volumio volumio[1127]: info: CoreStateMachine::syncState stateService play May 31 11:11:59 volumio volumio[1127]: info: CoreStateMachine::syncState currentStatus play May 31 11:11:59 volumio volumio[1127]: info: Received an update from plugin. extracting info from payload May 31 11:11:59 volumio volumio[1127]: info: CoreStateMachine::pushState May 31 11:11:59 volumio volumio[1127]: info: CorePlayQueue::getTrack 1 May 31 11:11:59 volumio volumio[1127]: info: CoreCommandRouter::volumioPushState May 31 11:11:59 volumio volumio[1127]: info: MRS: Pushing multiroomSync output update for this device May 31 11:11:59 volumio volumio[1127]: info: MRS: Pushing multiroomSync output May 31 11:11:59 volumio volumio[1127]: info: CoreStateMachine::pushState May 31 11:11:59 volumio volumio[1127]: info: CorePlayQueue::getTrack 1 May 31 11:11:59 volumio volumio[1127]: info: CoreCommandRouter::volumioPushState May 31 11:11:59 volumio volumio[1127]: info: MRS: Pushing multiroomSync output update for this device May 31 11:11:59 volumio volumio[1127]: info: MRS: Pushing multiroomSync output May 31 11:11:59 volumio volumio[1127]: info: ControllerMpd::pushState May 31 11:11:59 volumio volumio[1127]: info: CoreCommandRouter::servicePushState May 31 11:11:59 volumio volumio[1127]: info: CorePlayQueue::getTrack 1 May 31 11:11:59 volumio volumio[1127]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":285,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"4615 Kbps","isStreaming":false,"title":"君が光に変えて行く","artist":"Kalafina","album":"Kalafina All Time Best 2008-2018","uri":"USB/Music/ACG/Kalafina - Kalafina All Time Best 2008-2018 (FLAC 24bit／96kHz)/02. 君が光に変えて行く.flac","trackType":"flac"} May 31 11:11:59 volumio volumio[1127]: verbose: CURRENT POSITION 1 May 31 11:11:59 volumio volumio[1127]: info: CoreStateMachine::syncState stateService play May 31 11:11:59 volumio volumio[1127]: info: CoreStateMachine::syncState currentStatus play May 31 11:11:59 volumio volumio[1127]: info: Received an update from plugin. extracting info from payload May 31 11:11:59 volumio volumio[1127]: info: CoreStateMachine::pushState May 31 11:11:59 volumio volumio[1127]: info: CorePlayQueue::getTrack 1 May 31 11:11:59 volumio volumio[1127]: info: CoreCommandRouter::volumioPushState May 31 11:11:59 volumio volumio[1127]: info: MRS: Pushing multiroomSync output update for this device May 31 11:11:59 volumio volumio[1127]: info: MRS: Pushing multiroomSync output May 31 11:11:59 volumio volumio[1127]: info: CoreStateMachine::pushState May 31 11:11:59 volumio volumio[1127]: info: CorePlayQueue::getTrack 1 May 31 11:11:59 volumio volumio[1127]: info: CoreCommandRouter::volumioPushState May 31 11:11:59 volumio volumio[1127]: info: MRS: Pushing multiroomSync output update for this device May 31 11:11:59 volumio volumio[1127]: info: MRS: Pushing multiroomSync output May 31 11:11:59 volumio volumio[1127]: info: ------------------------------ 32ms May 31 11:11:59 volumio volumio[1127]: info: ------------------------------ 32ms May 31 11:11:59 volumio volumio[1127]: info: ------------------------------ 32ms May 31 11:11:59 volumio volumio[1127]: info: ------------------------------ 32ms May 31 11:11:59 volumio volumio[1127]: info: ------------------------------ 32ms May 31 11:12:01 volumio volumio[1127]: info: CoreCommandRouter::volumioPrevious May 31 11:12:01 volumio volumio[1127]: info: CoreStateMachine::previous May 31 11:12:01 volumio volumio[1127]: info: CorePlayQueue::getTrack 1 May 31 11:12:01 volumio volumio[1127]: info: CoreStateMachine::stop May 31 11:12:01 volumio volumio[1127]: info: CoreStateMachine::setConsumeUpdateService undefined May 31 11:12:01 volumio volumio[1127]: info: CoreStateMachine::stPlaybackTimer May 31 11:12:01 volumio volumio[1127]: info: CoreStateMachine::updateTrackBlock May 31 11:12:01 volumio volumio[1127]: info: CorePlayQueue::getTrackBlock May 31 11:12:01 volumio volumio[1127]: info: CoreStateMachine::pushState May 31 11:12:01 volumio volumio[1127]: info: CorePlayQueue::getTrack 1 May 31 11:12:01 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 11:12:01 volumio volumio[1127]: info: CoreCommandRouter::volumioPushState May 31 11:12:01 volumio volumio[1127]: info: MRS: Pushing multiroomSync output update for this device May 31 11:12:01 volumio volumio[1127]: info: MRS: Pushing multiroomSync output May 31 11:12:01 volumio volumio[1127]: info: CoreStateMachine::serviceStop May 31 11:12:01 volumio volumio[1127]: info: CorePlayQueue::getTrack 1 May 31 11:12:01 volumio volumio[1127]: info: CoreCommandRouter::serviceStop May 31 11:12:01 volumio volumio[1127]: info: ControllerMpd::stop May 31 11:12:01 volumio volumio[1127]: verbose: ControllerMpd::sendMpdCommand stop May 31 11:12:01 volumio volumio[1127]: info: May 31 11:12:01 volumio volumio[1127]: ---------------------------- MPD announces state update: player May 31 11:12:01 volumio volumio[1127]: info: sendMpdCommand stop took 5 milliseconds May 31 11:12:01 volumio volumio[1127]: info: ControllerMpd::getState May 31 11:12:01 volumio volumio[1127]: verbose: ControllerMpd::sendMpdCommand status May 31 11:12:01 volumio volumio[1127]: info: May 31 11:12:01 volumio volumio[1127]: ---------------------------- MPD announces state update: player May 31 11:12:01 volumio volumio[1127]: info: ControllerMpd::getState May 31 11:12:01 volumio volumio[1127]: verbose: ControllerMpd::sendMpdCommand status May 31 11:12:01 volumio volumio[1127]: info: May 31 11:12:01 volumio volumio[1127]: ---------------------------- MPD announces state update: player May 31 11:12:01 volumio volumio[1127]: info: ControllerMpd::getState May 31 11:12:01 volumio volumio[1127]: verbose: ControllerMpd::sendMpdCommand status May 31 11:12:01 volumio volumio[1127]: info: May 31 11:12:01 volumio volumio[1127]: ---------------------------- MPD announces state update: player May 31 11:12:01 volumio volumio[1127]: info: ControllerMpd::getState May 31 11:12:01 volumio volumio[1127]: verbose: ControllerMpd::sendMpdCommand status May 31 11:12:01 volumio volumio[1127]: info: May 31 11:12:01 volumio volumio[1127]: ---------------------------- MPD announces state update: player May 31 11:12:01 volumio volumio[1127]: info: ControllerMpd::getState May 31 11:12:01 volumio volumio[1127]: verbose: ControllerMpd::sendMpdCommand status May 31 11:12:01 volumio volumio[1127]: info: sendMpdCommand status took 3 milliseconds May 31 11:12:01 volumio volumio[1127]: info: sendMpdCommand status took 3 milliseconds May 31 11:12:01 volumio volumio[1127]: info: sendMpdCommand status took 3 milliseconds May 31 11:12:01 volumio volumio[1127]: info: sendMpdCommand status took 2 milliseconds May 31 11:12:01 volumio volumio[1127]: info: sendMpdCommand status took 2 milliseconds May 31 11:12:01 volumio volumio[1127]: verbose: ControllerMpd::parseState May 31 11:12:01 volumio volumio[1127]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 11:12:01 volumio volumio[1127]: verbose: ControllerMpd::parseState May 31 11:12:01 volumio volumio[1127]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 11:12:01 volumio volumio[1127]: verbose: ControllerMpd::parseState May 31 11:12:01 volumio volumio[1127]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 11:12:01 volumio volumio[1127]: verbose: ControllerMpd::parseState May 31 11:12:01 volumio volumio[1127]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 11:12:01 volumio volumio[1127]: verbose: ControllerMpd::parseState May 31 11:12:01 volumio volumio[1127]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 11:12:01 volumio volumio[1127]: info: sendMpdCommand playlistinfo took 1 milliseconds May 31 11:12:01 volumio volumio[1127]: info: sendMpdCommand playlistinfo took 1 milliseconds May 31 11:12:01 volumio volumio[1127]: info: sendMpdCommand playlistinfo took 1 milliseconds May 31 11:12:01 volumio volumio[1127]: info: sendMpdCommand playlistinfo took 1 milliseconds May 31 11:12:01 volumio volumio[1127]: info: sendMpdCommand playlistinfo took 1 milliseconds May 31 11:12:01 volumio volumio[1127]: verbose: ControllerMpd::parseTrackInfo May 31 11:12:01 volumio volumio[1127]: verbose: ControllerMpd::parseTrackInfo May 31 11:12:01 volumio volumio[1127]: verbose: ControllerMpd::parseTrackInfo May 31 11:12:01 volumio volumio[1127]: verbose: ControllerMpd::parseTrackInfo May 31 11:12:01 volumio volumio[1127]: verbose: ControllerMpd::parseTrackInfo May 31 11:12:01 volumio volumio[1127]: info: ControllerMpd::pushState May 31 11:12:01 volumio volumio[1127]: info: CoreCommandRouter::servicePushState May 31 11:12:01 volumio volumio[1127]: info: CoreStateMachine::pushState May 31 11:12:01 volumio volumio[1127]: info: CorePlayQueue::getTrack 1 May 31 11:12:01 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 11:12:01 volumio volumio[1127]: info: CoreCommandRouter::volumioPushState May 31 11:12:01 volumio volumio[1127]: info: MRS: Pushing multiroomSync output update for this device May 31 11:12:01 volumio volumio[1127]: info: MRS: Pushing multiroomSync output May 31 11:12:01 volumio volumio[1127]: info: CorePlayQueue::getTrack 1 May 31 11:12:01 volumio volumio[1127]: 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":"君が光に変えて行く","artist":"Kalafina","album":"Kalafina All Time Best 2008-2018","uri":"USB/Music/ACG/Kalafina - Kalafina All Time Best 2008-2018 (FLAC 24bit／96kHz)/02. 君が光に変えて行く.flac","trackType":"flac"} May 31 11:12:01 volumio volumio[1127]: verbose: CURRENT POSITION 1 May 31 11:12:01 volumio volumio[1127]: info: CoreStateMachine::syncState stateService stop May 31 11:12:01 volumio volumio[1127]: info: CoreStateMachine::syncState currentStatus stop May 31 11:12:01 volumio volumio[1127]: info: CoreStateMachine::pushState May 31 11:12:01 volumio volumio[1127]: info: CorePlayQueue::getTrack 1 May 31 11:12:01 volumio volumio[1127]: info: CoreCommandRouter::volumioPushState May 31 11:12:01 volumio volumio[1127]: info: MRS: Pushing multiroomSync output update for this device May 31 11:12:01 volumio volumio[1127]: info: MRS: Pushing multiroomSync output May 31 11:12:01 volumio volumio[1127]: info: No code May 31 11:12:01 volumio volumio[1127]: info: CoreStateMachine::pushState May 31 11:12:01 volumio volumio[1127]: info: CorePlayQueue::getTrack 1 May 31 11:12:01 volumio volumio[1127]: info: CoreCommandRouter::volumioPushState May 31 11:12:01 volumio volumio[1127]: info: MRS: Pushing multiroomSync output update for this device May 31 11:12:01 volumio volumio[1127]: info: MRS: Pushing multiroomSync output May 31 11:12:01 volumio volumio[1127]: info: ControllerMpd::pushState May 31 11:12:01 volumio volumio[1127]: info: CoreCommandRouter::servicePushState May 31 11:12:01 volumio volumio[1127]: info: CoreStateMachine::pushState May 31 11:12:01 volumio volumio[1127]: info: CorePlayQueue::getTrack 1 May 31 11:12:01 volumio volumio[1127]: info: CoreCommandRouter::volumioPushState May 31 11:12:01 volumio volumio[1127]: info: MRS: Pushing multiroomSync output update for this device May 31 11:12:01 volumio volumio[1127]: info: MRS: Pushing multiroomSync output May 31 11:12:01 volumio volumio[1127]: info: CorePlayQueue::getTrack 1 May 31 11:12:01 volumio volumio[1127]: 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":"君が光に変えて行く","artist":"Kalafina","album":"Kalafina All Time Best 2008-2018","uri":"USB/Music/ACG/Kalafina - Kalafina All Time Best 2008-2018 (FLAC 24bit／96kHz)/02. 君が光に変えて行く.flac","trackType":"flac"} May 31 11:12:01 volumio volumio[1127]: verbose: CURRENT POSITION 1 May 31 11:12:01 volumio volumio[1127]: info: CoreStateMachine::syncState stateService stop May 31 11:12:01 volumio volumio[1127]: info: CoreStateMachine::syncState currentStatus stop May 31 11:12:01 volumio volumio[1127]: info: CoreStateMachine::pushState May 31 11:12:01 volumio volumio[1127]: info: CorePlayQueue::getTrack 1 May 31 11:12:01 volumio volumio[1127]: info: CoreCommandRouter::volumioPushState May 31 11:12:01 volumio volumio[1127]: info: MRS: Pushing multiroomSync output update for this device May 31 11:12:01 volumio volumio[1127]: info: MRS: Pushing multiroomSync output May 31 11:12:01 volumio volumio[1127]: info: No code May 31 11:12:01 volumio volumio[1127]: info: CoreStateMachine::pushState May 31 11:12:01 volumio volumio[1127]: info: CorePlayQueue::getTrack 1 May 31 11:12:01 volumio volumio[1127]: info: CoreCommandRouter::volumioPushState May 31 11:12:01 volumio volumio[1127]: info: MRS: Pushing multiroomSync output update for this device May 31 11:12:01 volumio volumio[1127]: info: MRS: Pushing multiroomSync output May 31 11:12:01 volumio volumio[1127]: info: ControllerMpd::pushState May 31 11:12:01 volumio volumio[1127]: info: CoreCommandRouter::servicePushState May 31 11:12:01 volumio volumio[1127]: info: CoreStateMachine::pushState May 31 11:12:01 volumio volumio[1127]: info: CorePlayQueue::getTrack 1 May 31 11:12:01 volumio volumio[1127]: info: CoreCommandRouter::volumioPushState May 31 11:12:01 volumio volumio[1127]: info: MRS: Pushing multiroomSync output update for this device May 31 11:12:01 volumio volumio[1127]: info: MRS: Pushing multiroomSync output May 31 11:12:01 volumio volumio[1127]: info: CorePlayQueue::getTrack 1 May 31 11:12:01 volumio volumio[1127]: 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":"君が光に変えて行く","artist":"Kalafina","album":"Kalafina All Time Best 2008-2018","uri":"USB/Music/ACG/Kalafina - Kalafina All Time Best 2008-2018 (FLAC 24bit／96kHz)/02. 君が光に変えて行く.flac","trackType":"flac"} May 31 11:12:01 volumio volumio[1127]: verbose: CURRENT POSITION 1 May 31 11:12:01 volumio volumio[1127]: info: CoreStateMachine::syncState stateService stop May 31 11:12:01 volumio volumio[1127]: info: CoreStateMachine::syncState currentStatus stop May 31 11:12:01 volumio volumio[1127]: info: CoreStateMachine::pushState May 31 11:12:01 volumio volumio[1127]: info: CorePlayQueue::getTrack 1 May 31 11:12:01 volumio volumio[1127]: info: CoreCommandRouter::volumioPushState May 31 11:12:01 volumio volumio[1127]: info: MRS: Pushing multiroomSync output update for this device May 31 11:12:01 volumio volumio[1127]: info: MRS: Pushing multiroomSync output May 31 11:12:01 volumio volumio[1127]: info: No code May 31 11:12:01 volumio volumio[1127]: info: CoreStateMachine::pushState May 31 11:12:01 volumio volumio[1127]: info: CorePlayQueue::getTrack 1 May 31 11:12:01 volumio volumio[1127]: info: CoreCommandRouter::volumioPushState May 31 11:12:01 volumio volumio[1127]: info: MRS: Pushing multiroomSync output update for this device May 31 11:12:01 volumio volumio[1127]: info: MRS: Pushing multiroomSync output May 31 11:12:01 volumio volumio[1127]: info: ControllerMpd::pushState May 31 11:12:01 volumio volumio[1127]: info: CoreCommandRouter::servicePushState May 31 11:12:01 volumio volumio[1127]: info: CoreStateMachine::pushState May 31 11:12:01 volumio volumio[1127]: info: CorePlayQueue::getTrack 1 May 31 11:12:01 volumio volumio[1127]: info: CoreCommandRouter::volumioPushState May 31 11:12:01 volumio volumio[1127]: info: MRS: Pushing multiroomSync output update for this device May 31 11:12:01 volumio volumio[1127]: info: MRS: Pushing multiroomSync output May 31 11:12:01 volumio volumio[1127]: info: CorePlayQueue::getTrack 1 May 31 11:12:01 volumio volumio[1127]: 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":"君が光に変えて行く","artist":"Kalafina","album":"Kalafina All Time Best 2008-2018","uri":"USB/Music/ACG/Kalafina - Kalafina All Time Best 2008-2018 (FLAC 24bit／96kHz)/02. 君が光に変えて行く.flac","trackType":"flac"} May 31 11:12:01 volumio volumio[1127]: verbose: CURRENT POSITION 1 May 31 11:12:01 volumio volumio[1127]: info: CoreStateMachine::syncState stateService stop May 31 11:12:01 volumio volumio[1127]: info: CoreStateMachine::syncState currentStatus stop May 31 11:12:01 volumio volumio[1127]: info: CoreStateMachine::pushState May 31 11:12:01 volumio volumio[1127]: info: CorePlayQueue::getTrack 1 May 31 11:12:01 volumio volumio[1127]: info: CoreCommandRouter::volumioPushState May 31 11:12:01 volumio volumio[1127]: info: MRS: Pushing multiroomSync output update for this device May 31 11:12:01 volumio volumio[1127]: info: MRS: Pushing multiroomSync output May 31 11:12:01 volumio volumio[1127]: info: No code May 31 11:12:01 volumio volumio[1127]: info: CoreStateMachine::pushState May 31 11:12:01 volumio volumio[1127]: info: CorePlayQueue::getTrack 1 May 31 11:12:01 volumio volumio[1127]: info: CoreCommandRouter::volumioPushState May 31 11:12:01 volumio volumio[1127]: info: MRS: Pushing multiroomSync output update for this device May 31 11:12:01 volumio volumio[1127]: info: MRS: Pushing multiroomSync output May 31 11:12:01 volumio volumio[1127]: info: ControllerMpd::pushState May 31 11:12:01 volumio volumio[1127]: info: CoreCommandRouter::servicePushState May 31 11:12:01 volumio volumio[1127]: info: CoreStateMachine::pushState May 31 11:12:01 volumio volumio[1127]: info: CorePlayQueue::getTrack 1 May 31 11:12:01 volumio volumio[1127]: info: CoreCommandRouter::volumioPushState May 31 11:12:01 volumio volumio[1127]: info: MRS: Pushing multiroomSync output update for this device May 31 11:12:01 volumio volumio[1127]: info: MRS: Pushing multiroomSync output May 31 11:12:01 volumio volumio[1127]: info: CorePlayQueue::getTrack 1 May 31 11:12:01 volumio volumio[1127]: 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":"君が光に変えて行く","artist":"Kalafina","album":"Kalafina All Time Best 2008-2018","uri":"USB/Music/ACG/Kalafina - Kalafina All Time Best 2008-2018 (FLAC 24bit／96kHz)/02. 君が光に変えて行く.flac","trackType":"flac"} May 31 11:12:01 volumio volumio[1127]: verbose: CURRENT POSITION 1 May 31 11:12:01 volumio volumio[1127]: info: CoreStateMachine::syncState stateService stop May 31 11:12:01 volumio volumio[1127]: info: CoreStateMachine::syncState currentStatus stop May 31 11:12:01 volumio volumio[1127]: info: CoreStateMachine::pushState May 31 11:12:01 volumio volumio[1127]: info: CorePlayQueue::getTrack 1 May 31 11:12:01 volumio volumio[1127]: info: CoreCommandRouter::volumioPushState May 31 11:12:01 volumio volumio[1127]: info: MRS: Pushing multiroomSync output update for this device May 31 11:12:01 volumio volumio[1127]: info: MRS: Pushing multiroomSync output May 31 11:12:01 volumio volumio[1127]: info: No code May 31 11:12:01 volumio volumio[1127]: info: CoreStateMachine::pushState May 31 11:12:01 volumio volumio[1127]: info: CorePlayQueue::getTrack 1 May 31 11:12:01 volumio volumio[1127]: info: CoreCommandRouter::volumioPushState May 31 11:12:01 volumio volumio[1127]: info: MRS: Pushing multiroomSync output update for this device May 31 11:12:01 volumio volumio[1127]: info: MRS: Pushing multiroomSync output May 31 11:12:01 volumio volumio[1127]: info: ------------------------------ 43ms May 31 11:12:01 volumio volumio[1127]: info: ------------------------------ 43ms May 31 11:12:01 volumio volumio[1127]: info: ------------------------------ 44ms May 31 11:12:01 volumio volumio[1127]: info: ------------------------------ 44ms May 31 11:12:01 volumio volumio[1127]: info: ------------------------------ 43ms May 31 11:12:01 volumio volumio[1127]: info: camilladsp spawned new process with pid 3654, instance 1, run: true May 31 11:12:01 volumio volumio[1127]: info: CoreStateMachine::play index undefined May 31 11:12:01 volumio volumio[1127]: info: CoreStateMachine::setConsumeUpdateService undefined May 31 11:12:01 volumio volumio[1127]: info: CorePlayQueue::getTrack 0 May 31 11:12:01 volumio volumio[1127]: info: CoreStateMachine::startPlaybackTimer May 31 11:12:01 volumio volumio[1127]: info: CorePlayQueue::getTrack 0 May 31 11:12:01 volumio volumio[1127]: verbose: ControllerMpd::clearAddPlayTracks USB/Music/ACG/Kalafina - Kalafina All Time Best 2008-2018 (FLAC 24bit／96kHz)/01. oblivious.flac May 31 11:12:01 volumio volumio[1127]: verbose: ControllerMpd::sendMpdCommand stop May 31 11:12:01 volumio volumio[1127]: info: sendMpdCommand stop took 1 milliseconds May 31 11:12:01 volumio volumio[1127]: verbose: ControllerMpd::sendMpdCommand clear May 31 11:12:01 volumio volumio[1127]: info: May 31 11:12:01 volumio volumio[1127]: ---------------------------- MPD announces system playlist update May 31 11:12:01 volumio volumio[1127]: info: Ignoring MPD Status Update May 31 11:12:01 volumio volumio[1127]: info: sendMpdCommand clear took 0 milliseconds May 31 11:12:01 volumio volumio[1127]: verbose: ControllerMpd::sendMpdCommand add "USB/Music/ACG/Kalafina - Kalafina All Time Best 2008-2018 (FLAC 24bit／96kHz)/01. oblivious.flac" May 31 11:12:01 volumio volumio[1127]: error: updateQueue error: null May 31 11:12:01 volumio volumio[1127]: info: ------------------------------ 0ms May 31 11:12:01 volumio volumio[1127]: info: May 31 11:12:01 volumio volumio[1127]: ---------------------------- MPD announces system playlist update May 31 11:12:01 volumio volumio[1127]: info: Ignoring MPD Status Update May 31 11:12:01 volumio volumio[1127]: info: May 31 11:12:01 volumio volumio[1127]: ---------------------------- MPD announces system playlist update May 31 11:12:01 volumio volumio[1127]: info: Ignoring MPD Status Update May 31 11:12:01 volumio volumio[1127]: info: May 31 11:12:01 volumio volumio[1127]: ---------------------------- MPD announces system playlist update May 31 11:12:01 volumio volumio[1127]: info: Ignoring MPD Status Update May 31 11:12:01 volumio volumio[1127]: info: May 31 11:12:01 volumio volumio[1127]: ---------------------------- MPD announces system playlist update May 31 11:12:01 volumio volumio[1127]: info: Ignoring MPD Status Update May 31 11:12:01 volumio volumio[1127]: info: May 31 11:12:01 volumio volumio[1127]: ---------------------------- MPD announces system playlist update May 31 11:12:01 volumio volumio[1127]: info: Ignoring MPD Status Update May 31 11:12:01 volumio volumio[1127]: info: sendMpdCommand add "USB/Music/ACG/Kalafina - Kalafina All Time Best 2008-2018 (FLAC 24bit／96kHz)/01. oblivious.flac" took 1 milliseconds May 31 11:12:01 volumio volumio[1127]: info: ------------------------------ 1ms May 31 11:12:01 volumio volumio[1127]: info: ------------------------------ 1ms May 31 11:12:01 volumio volumio[1127]: verbose: ControllerMpd::sendMpdCommand play May 31 11:12:01 volumio volumio[1127]: info: May 31 11:12:01 volumio volumio[1127]: ---------------------------- MPD announces system playlist update May 31 11:12:01 volumio volumio[1127]: info: Ignoring MPD Status Update May 31 11:12:01 volumio volumio[1127]: info: May 31 11:12:01 volumio volumio[1127]: ---------------------------- MPD announces system playlist update May 31 11:12:01 volumio volumio[1127]: info: Ignoring MPD Status Update May 31 11:12:01 volumio volumio[1127]: info: May 31 11:12:01 volumio volumio[1127]: ---------------------------- MPD announces system playlist update May 31 11:12:01 volumio volumio[1127]: info: Ignoring MPD Status Update May 31 11:12:01 volumio volumio[1127]: info: ------------------------------ 1ms May 31 11:12:01 volumio volumio[1127]: info: sendMpdCommand play took 1 milliseconds May 31 11:12:01 volumio volumio[1127]: info: ------------------------------ 1ms May 31 11:12:01 volumio volumio[1127]: info: ------------------------------ 1ms May 31 11:12:01 volumio volumio[1127]: info: May 31 11:12:01 volumio volumio[1127]: ---------------------------- MPD announces system playlist update May 31 11:12:01 volumio volumio[1127]: info: Ignoring MPD Status Update May 31 11:12:01 volumio volumio[1127]: info: May 31 11:12:01 volumio volumio[1127]: ---------------------------- MPD announces state update: player May 31 11:12:01 volumio volumio[1127]: info: ------------------------------ 1ms May 31 11:12:01 volumio volumio[1127]: info: ControllerMpd::getState May 31 11:12:01 volumio volumio[1127]: verbose: ControllerMpd::sendMpdCommand status May 31 11:12:01 volumio volumio[1127]: info: ------------------------------ 0ms May 31 11:12:01 volumio volumio[1127]: info: May 31 11:12:01 volumio volumio[1127]: ---------------------------- MPD announces state update: player May 31 11:12:01 volumio volumio[1127]: info: ControllerMpd::getState May 31 11:12:01 volumio volumio[1127]: verbose: ControllerMpd::sendMpdCommand status May 31 11:12:01 volumio volumio[1127]: info: May 31 11:12:01 volumio volumio[1127]: ---------------------------- MPD announces state update: player May 31 11:12:01 volumio volumio[1127]: info: ControllerMpd::getState May 31 11:12:01 volumio volumio[1127]: verbose: ControllerMpd::sendMpdCommand status May 31 11:12:01 volumio volumio[1127]: info: May 31 11:12:01 volumio volumio[1127]: ---------------------------- MPD announces state update: player May 31 11:12:01 volumio volumio[1127]: info: ControllerMpd::getState May 31 11:12:01 volumio volumio[1127]: verbose: ControllerMpd::sendMpdCommand status May 31 11:12:01 volumio volumio[1127]: info: May 31 11:12:01 volumio volumio[1127]: ---------------------------- MPD announces state update: player May 31 11:12:01 volumio volumio[1127]: info: ControllerMpd::getState May 31 11:12:01 volumio volumio[1127]: verbose: ControllerMpd::sendMpdCommand status May 31 11:12:01 volumio volumio[1127]: info: FusionDsp - ---- read samplerate, raw: 96000,S32_LE,2,32 May 31 11:12:01 volumio volumio[1127]: info: FusionDsp - ---- read samplerate from file: 96000 May 31 11:12:01 volumio volumio[1127]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 31 11:12:01 volumio volumio[1127]: info: May 31 11:12:01 volumio volumio[1127]: ---------------------------- MPD announces state update: player May 31 11:12:01 volumio volumio[1127]: info: sendMpdCommand status took 5 milliseconds May 31 11:12:01 volumio volumio[1127]: info: sendMpdCommand status took 6 milliseconds May 31 11:12:01 volumio volumio[1127]: info: sendMpdCommand status took 6 milliseconds May 31 11:12:01 volumio volumio[1127]: info: sendMpdCommand status took 5 milliseconds May 31 11:12:01 volumio volumio[1127]: info: sendMpdCommand status took 5 milliseconds May 31 11:12:01 volumio volumio[1127]: info: ControllerMpd::getState May 31 11:12:01 volumio volumio[1127]: verbose: ControllerMpd::sendMpdCommand status May 31 11:12:01 volumio volumio[1127]: verbose: ControllerMpd::parseState May 31 11:12:01 volumio volumio[1127]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 11:12:01 volumio volumio[1127]: verbose: ControllerMpd::parseState May 31 11:12:01 volumio volumio[1127]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 11:12:01 volumio volumio[1127]: verbose: ControllerMpd::parseState May 31 11:12:01 volumio volumio[1127]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 11:12:01 volumio volumio[1127]: verbose: ControllerMpd::parseState May 31 11:12:01 volumio volumio[1127]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 11:12:01 volumio volumio[1127]: verbose: ControllerMpd::parseState May 31 11:12:01 volumio volumio[1127]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 11:12:01 volumio volumio[1127]: info: May 31 11:12:01 volumio volumio[1127]: ---------------------------- MPD announces state update: player May 31 11:12:01 volumio volumio[1127]: info: ControllerMpd::getState May 31 11:12:01 volumio volumio[1127]: verbose: ControllerMpd::sendMpdCommand status May 31 11:12:01 volumio volumio[1127]: info: May 31 11:12:01 volumio volumio[1127]: ---------------------------- MPD announces state update: player May 31 11:12:01 volumio volumio[1127]: info: ControllerMpd::getState May 31 11:12:01 volumio volumio[1127]: verbose: ControllerMpd::sendMpdCommand status May 31 11:12:01 volumio volumio[1127]: info: May 31 11:12:01 volumio volumio[1127]: ---------------------------- MPD announces state update: player May 31 11:12:01 volumio volumio[1127]: info: ControllerMpd::getState May 31 11:12:01 volumio volumio[1127]: verbose: ControllerMpd::sendMpdCommand status May 31 11:12:01 volumio volumio[1127]: info: May 31 11:12:01 volumio volumio[1127]: ---------------------------- MPD announces state update: player May 31 11:12:01 volumio volumio[1127]: info: ControllerMpd::getState May 31 11:12:01 volumio volumio[1127]: verbose: ControllerMpd::sendMpdCommand status May 31 11:12:01 volumio volumio[1127]: info: sendMpdCommand status took 1 milliseconds May 31 11:12:01 volumio volumio[1127]: info: sendMpdCommand playlistinfo took 1 milliseconds May 31 11:12:01 volumio volumio[1127]: info: sendMpdCommand playlistinfo took 1 milliseconds May 31 11:12:01 volumio volumio[1127]: info: sendMpdCommand playlistinfo took 1 milliseconds May 31 11:12:01 volumio volumio[1127]: info: sendMpdCommand playlistinfo took 1 milliseconds May 31 11:12:01 volumio volumio[1127]: info: sendMpdCommand playlistinfo took 2 milliseconds May 31 11:12:01 volumio volumio[1127]: info: sendMpdCommand status took 2 milliseconds May 31 11:12:01 volumio volumio[1127]: info: sendMpdCommand status took 1 milliseconds May 31 11:12:01 volumio volumio[1127]: info: sendMpdCommand status took 1 milliseconds May 31 11:12:01 volumio volumio[1127]: info: sendMpdCommand status took 1 milliseconds May 31 11:12:01 volumio volumio[1127]: verbose: ControllerMpd::parseState May 31 11:12:01 volumio volumio[1127]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 11:12:01 volumio volumio[1127]: verbose: ControllerMpd::parseTrackInfo May 31 11:12:01 volumio volumio[1127]: verbose: ControllerMpd::parseTrackInfo May 31 11:12:01 volumio volumio[1127]: verbose: ControllerMpd::parseTrackInfo May 31 11:12:01 volumio volumio[1127]: verbose: ControllerMpd::parseTrackInfo May 31 11:12:01 volumio volumio[1127]: verbose: ControllerMpd::parseTrackInfo May 31 11:12:01 volumio volumio[1127]: verbose: ControllerMpd::parseState May 31 11:12:01 volumio volumio[1127]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 11:12:01 volumio volumio[1127]: verbose: ControllerMpd::parseState May 31 11:12:01 volumio volumio[1127]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 11:12:01 volumio volumio[1127]: verbose: ControllerMpd::parseState May 31 11:12:01 volumio volumio[1127]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 11:12:01 volumio volumio[1127]: verbose: ControllerMpd::parseState May 31 11:12:01 volumio volumio[1127]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 11:12:01 volumio volumio[1127]: info: ControllerMpd::pushState May 31 11:12:01 volumio volumio[1127]: info: CoreCommandRouter::servicePushState May 31 11:12:01 volumio volumio[1127]: info: CorePlayQueue::getTrack 0 May 31 11:12:01 volumio volumio[1127]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":264,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"oblivious","artist":"Kalafina","album":"Kalafina All Time Best 2008-2018","uri":"USB/Music/ACG/Kalafina - Kalafina All Time Best 2008-2018 (FLAC 24bit／96kHz)/01. oblivious.flac","trackType":"flac"} May 31 11:12:01 volumio volumio[1127]: verbose: CURRENT POSITION 0 May 31 11:12:01 volumio volumio[1127]: info: CoreStateMachine::syncState stateService play May 31 11:12:01 volumio volumio[1127]: info: CoreStateMachine::syncState currentStatus stop May 31 11:12:01 volumio volumio[1127]: info: ControllerMpd::pushState May 31 11:12:01 volumio volumio[1127]: info: CoreCommandRouter::servicePushState May 31 11:12:01 volumio volumio[1127]: info: CorePlayQueue::getTrack 0 May 31 11:12:01 volumio volumio[1127]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":264,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"oblivious","artist":"Kalafina","album":"Kalafina All Time Best 2008-2018","uri":"USB/Music/ACG/Kalafina - Kalafina All Time Best 2008-2018 (FLAC 24bit／96kHz)/01. oblivious.flac","trackType":"flac"} May 31 11:12:01 volumio volumio[1127]: verbose: CURRENT POSITION 0 May 31 11:12:01 volumio volumio[1127]: info: CoreStateMachine::syncState stateService play May 31 11:12:01 volumio volumio[1127]: info: CoreStateMachine::syncState currentStatus play May 31 11:12:01 volumio volumio[1127]: info: Received an update from plugin. extracting info from payload May 31 11:12:01 volumio volumio[1127]: info: CoreStateMachine::pushState May 31 11:12:01 volumio volumio[1127]: info: CorePlayQueue::getTrack 0 May 31 11:12:01 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 11:12:01 volumio volumio[1127]: info: CoreCommandRouter::volumioPushState May 31 11:12:01 volumio volumio[1127]: info: MRS: Pushing multiroomSync output update for this device May 31 11:12:01 volumio volumio[1127]: info: MRS: Pushing multiroomSync output May 31 11:12:01 volumio volumio[1127]: info: CoreStateMachine::pushState May 31 11:12:01 volumio volumio[1127]: info: CorePlayQueue::getTrack 0 May 31 11:12:01 volumio volumio[1127]: info: CoreCommandRouter::volumioPushState May 31 11:12:01 volumio volumio[1127]: info: MRS: Pushing multiroomSync output update for this device May 31 11:12:01 volumio volumio[1127]: info: MRS: Pushing multiroomSync output May 31 11:12:01 volumio volumio[1127]: info: ControllerMpd::pushState May 31 11:12:01 volumio volumio[1127]: info: CoreCommandRouter::servicePushState May 31 11:12:01 volumio volumio[1127]: info: CorePlayQueue::getTrack 0 May 31 11:12:01 volumio volumio[1127]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":264,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"oblivious","artist":"Kalafina","album":"Kalafina All Time Best 2008-2018","uri":"USB/Music/ACG/Kalafina - Kalafina All Time Best 2008-2018 (FLAC 24bit／96kHz)/01. oblivious.flac","trackType":"flac"} May 31 11:12:01 volumio volumio[1127]: verbose: CURRENT POSITION 0 May 31 11:12:01 volumio volumio[1127]: info: CoreStateMachine::syncState stateService play May 31 11:12:01 volumio volumio[1127]: info: CoreStateMachine::syncState currentStatus play May 31 11:12:01 volumio volumio[1127]: info: Received an update from plugin. extracting info from payload May 31 11:12:01 volumio volumio[1127]: info: CoreStateMachine::pushState May 31 11:12:01 volumio volumio[1127]: info: CorePlayQueue::getTrack 0 May 31 11:12:01 volumio volumio[1127]: info: CoreCommandRouter::volumioPushState May 31 11:12:01 volumio volumio[1127]: info: MRS: Pushing multiroomSync output update for this device May 31 11:12:01 volumio volumio[1127]: info: MRS: Pushing multiroomSync output May 31 11:12:01 volumio volumio[1127]: info: CoreStateMachine::pushState May 31 11:12:01 volumio volumio[1127]: info: CorePlayQueue::getTrack 0 May 31 11:12:01 volumio volumio[1127]: info: CoreCommandRouter::volumioPushState May 31 11:12:01 volumio volumio[1127]: info: MRS: Pushing multiroomSync output update for this device May 31 11:12:01 volumio volumio[1127]: info: MRS: Pushing multiroomSync output May 31 11:12:01 volumio volumio[1127]: info: ControllerMpd::pushState May 31 11:12:01 volumio volumio[1127]: info: CoreCommandRouter::servicePushState May 31 11:12:01 volumio volumio[1127]: info: CorePlayQueue::getTrack 0 May 31 11:12:01 volumio volumio[1127]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":264,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"oblivious","artist":"Kalafina","album":"Kalafina All Time Best 2008-2018","uri":"USB/Music/ACG/Kalafina - Kalafina All Time Best 2008-2018 (FLAC 24bit／96kHz)/01. oblivious.flac","trackType":"flac"} May 31 11:12:01 volumio volumio[1127]: verbose: CURRENT POSITION 0 May 31 11:12:01 volumio volumio[1127]: info: CoreStateMachine::syncState stateService play May 31 11:12:01 volumio volumio[1127]: info: CoreStateMachine::syncState currentStatus play May 31 11:12:01 volumio volumio[1127]: info: Received an update from plugin. extracting info from payload May 31 11:12:01 volumio volumio[1127]: info: CoreStateMachine::pushState May 31 11:12:01 volumio volumio[1127]: info: CorePlayQueue::getTrack 0 May 31 11:12:01 volumio volumio[1127]: info: CoreCommandRouter::volumioPushState May 31 11:12:01 volumio volumio[1127]: info: MRS: Pushing multiroomSync output update for this device May 31 11:12:01 volumio volumio[1127]: info: MRS: Pushing multiroomSync output May 31 11:12:01 volumio volumio[1127]: info: CoreStateMachine::pushState May 31 11:12:01 volumio volumio[1127]: info: CorePlayQueue::getTrack 0 May 31 11:12:01 volumio volumio[1127]: info: CoreCommandRouter::volumioPushState May 31 11:12:02 volumio volumio[1127]: info: MRS: Pushing multiroomSync output update for this device May 31 11:12:02 volumio volumio[1127]: info: MRS: Pushing multiroomSync output May 31 11:12:02 volumio volumio[1127]: info: ControllerMpd::pushState May 31 11:12:02 volumio volumio[1127]: info: CoreCommandRouter::servicePushState May 31 11:12:02 volumio volumio[1127]: info: CorePlayQueue::getTrack 0 May 31 11:12:02 volumio volumio[1127]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":264,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"oblivious","artist":"Kalafina","album":"Kalafina All Time Best 2008-2018","uri":"USB/Music/ACG/Kalafina - Kalafina All Time Best 2008-2018 (FLAC 24bit／96kHz)/01. oblivious.flac","trackType":"flac"} May 31 11:12:02 volumio volumio[1127]: verbose: CURRENT POSITION 0 May 31 11:12:02 volumio volumio[1127]: info: CoreStateMachine::syncState stateService play May 31 11:12:02 volumio volumio[1127]: info: CoreStateMachine::syncState currentStatus play May 31 11:12:02 volumio volumio[1127]: info: Received an update from plugin. extracting info from payload May 31 11:12:02 volumio volumio[1127]: info: CoreStateMachine::pushState May 31 11:12:02 volumio volumio[1127]: info: CorePlayQueue::getTrack 0 May 31 11:12:02 volumio volumio[1127]: info: CoreCommandRouter::volumioPushState May 31 11:12:02 volumio volumio[1127]: info: MRS: Pushing multiroomSync output update for this device May 31 11:12:02 volumio volumio[1127]: info: MRS: Pushing multiroomSync output May 31 11:12:02 volumio volumio[1127]: info: CoreStateMachine::pushState May 31 11:12:02 volumio volumio[1127]: info: CorePlayQueue::getTrack 0 May 31 11:12:02 volumio volumio[1127]: info: CoreCommandRouter::volumioPushState May 31 11:12:02 volumio volumio[1127]: info: MRS: Pushing multiroomSync output update for this device May 31 11:12:02 volumio volumio[1127]: info: MRS: Pushing multiroomSync output May 31 11:12:02 volumio volumio[1127]: info: ------------------------------ 17ms May 31 11:12:02 volumio volumio[1127]: info: ------------------------------ 34ms May 31 11:12:02 volumio volumio[1127]: info: ------------------------------ 34ms May 31 11:12:02 volumio volumio[1127]: info: ------------------------------ 33ms May 31 11:12:02 volumio volumio[1127]: info: ------------------------------ 33ms May 31 11:12:02 volumio volumio[1127]: info: sendMpdCommand playlistinfo took 27 milliseconds May 31 11:12:02 volumio volumio[1127]: info: sendMpdCommand playlistinfo took 27 milliseconds May 31 11:12:02 volumio volumio[1127]: info: sendMpdCommand playlistinfo took 27 milliseconds May 31 11:12:02 volumio volumio[1127]: info: sendMpdCommand playlistinfo took 27 milliseconds May 31 11:12:02 volumio volumio[1127]: verbose: ControllerMpd::parseTrackInfo May 31 11:12:02 volumio volumio[1127]: verbose: ControllerMpd::parseTrackInfo May 31 11:12:02 volumio volumio[1127]: verbose: ControllerMpd::parseTrackInfo May 31 11:12:02 volumio volumio[1127]: verbose: ControllerMpd::parseTrackInfo May 31 11:12:02 volumio volumio[1127]: verbose: ControllerMpd::parseTrackInfo May 31 11:12:02 volumio volumio[1127]: info: ControllerMpd::pushState May 31 11:12:02 volumio volumio[1127]: info: CoreCommandRouter::servicePushState May 31 11:12:02 volumio volumio[1127]: info: CorePlayQueue::getTrack 0 May 31 11:12:02 volumio volumio[1127]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1244,"duration":264,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"4614 Kbps","isStreaming":false,"title":"oblivious","artist":"Kalafina","album":"Kalafina All Time Best 2008-2018","uri":"USB/Music/ACG/Kalafina - Kalafina All Time Best 2008-2018 (FLAC 24bit／96kHz)/01. oblivious.flac","trackType":"flac"} May 31 11:12:02 volumio volumio[1127]: verbose: CURRENT POSITION 0 May 31 11:12:02 volumio volumio[1127]: info: CoreStateMachine::syncState stateService play May 31 11:12:02 volumio volumio[1127]: info: CoreStateMachine::syncState currentStatus play May 31 11:12:02 volumio volumio[1127]: info: Received an update from plugin. extracting info from payload May 31 11:12:02 volumio volumio[1127]: info: CoreStateMachine::pushState May 31 11:12:02 volumio volumio[1127]: info: CorePlayQueue::getTrack 0 May 31 11:12:02 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 11:12:02 volumio volumio[1127]: info: CoreCommandRouter::volumioPushState May 31 11:12:02 volumio volumio[1127]: info: MRS: Pushing multiroomSync output update for this device May 31 11:12:02 volumio volumio[1127]: info: MRS: Pushing multiroomSync output May 31 11:12:02 volumio volumio[1127]: info: CoreStateMachine::pushState May 31 11:12:02 volumio volumio[1127]: info: CorePlayQueue::getTrack 0 May 31 11:12:02 volumio volumio[1127]: info: CoreCommandRouter::volumioPushState May 31 11:12:02 volumio volumio[1127]: info: MRS: Pushing multiroomSync output update for this device May 31 11:12:02 volumio volumio[1127]: info: MRS: Pushing multiroomSync output May 31 11:12:02 volumio volumio[1127]: info: ControllerMpd::pushState May 31 11:12:02 volumio volumio[1127]: info: CoreCommandRouter::servicePushState May 31 11:12:02 volumio volumio[1127]: info: CorePlayQueue::getTrack 0 May 31 11:12:02 volumio volumio[1127]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1244,"duration":264,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"4615 Kbps","isStreaming":false,"title":"oblivious","artist":"Kalafina","album":"Kalafina All Time Best 2008-2018","uri":"USB/Music/ACG/Kalafina - Kalafina All Time Best 2008-2018 (FLAC 24bit／96kHz)/01. oblivious.flac","trackType":"flac"} May 31 11:12:02 volumio volumio[1127]: verbose: CURRENT POSITION 0 May 31 11:12:02 volumio volumio[1127]: info: CoreStateMachine::syncState stateService play May 31 11:12:02 volumio volumio[1127]: info: CoreStateMachine::syncState currentStatus play May 31 11:12:02 volumio volumio[1127]: info: Received an update from plugin. extracting info from payload May 31 11:12:02 volumio volumio[1127]: info: CoreStateMachine::pushState May 31 11:12:02 volumio volumio[1127]: info: CorePlayQueue::getTrack 0 May 31 11:12:02 volumio volumio[1127]: info: CoreCommandRouter::volumioPushState May 31 11:12:02 volumio volumio[1127]: info: MRS: Pushing multiroomSync output update for this device May 31 11:12:02 volumio volumio[1127]: info: MRS: Pushing multiroomSync output May 31 11:12:02 volumio volumio[1127]: info: CoreStateMachine::pushState May 31 11:12:02 volumio volumio[1127]: info: CorePlayQueue::getTrack 0 May 31 11:12:02 volumio volumio[1127]: info: CoreCommandRouter::volumioPushState May 31 11:12:02 volumio volumio[1127]: info: MRS: Pushing multiroomSync output update for this device May 31 11:12:02 volumio volumio[1127]: info: MRS: Pushing multiroomSync output May 31 11:12:02 volumio volumio[1127]: info: ControllerMpd::pushState May 31 11:12:02 volumio volumio[1127]: info: CoreCommandRouter::servicePushState May 31 11:12:02 volumio volumio[1127]: info: CorePlayQueue::getTrack 0 May 31 11:12:02 volumio volumio[1127]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1244,"duration":264,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"4615 Kbps","isStreaming":false,"title":"oblivious","artist":"Kalafina","album":"Kalafina All Time Best 2008-2018","uri":"USB/Music/ACG/Kalafina - Kalafina All Time Best 2008-2018 (FLAC 24bit／96kHz)/01. oblivious.flac","trackType":"flac"} May 31 11:12:02 volumio volumio[1127]: verbose: CURRENT POSITION 0 May 31 11:12:02 volumio volumio[1127]: info: CoreStateMachine::syncState stateService play May 31 11:12:02 volumio volumio[1127]: info: CoreStateMachine::syncState currentStatus play May 31 11:12:02 volumio volumio[1127]: info: Received an update from plugin. extracting info from payload May 31 11:12:02 volumio volumio[1127]: info: CoreStateMachine::pushState May 31 11:12:02 volumio volumio[1127]: info: CorePlayQueue::getTrack 0 May 31 11:12:02 volumio volumio[1127]: info: CoreCommandRouter::volumioPushState May 31 11:12:02 volumio volumio[1127]: info: MRS: Pushing multiroomSync output update for this device May 31 11:12:02 volumio volumio[1127]: info: MRS: Pushing multiroomSync output May 31 11:12:02 volumio volumio[1127]: info: CoreStateMachine::pushState May 31 11:12:02 volumio volumio[1127]: info: CorePlayQueue::getTrack 0 May 31 11:12:02 volumio volumio[1127]: info: CoreCommandRouter::volumioPushState May 31 11:12:02 volumio volumio[1127]: info: MRS: Pushing multiroomSync output update for this device May 31 11:12:02 volumio volumio[1127]: info: MRS: Pushing multiroomSync output May 31 11:12:02 volumio volumio[1127]: info: ControllerMpd::pushState May 31 11:12:02 volumio volumio[1127]: info: CoreCommandRouter::servicePushState May 31 11:12:02 volumio volumio[1127]: info: CorePlayQueue::getTrack 0 May 31 11:12:02 volumio volumio[1127]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1244,"duration":264,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"4615 Kbps","isStreaming":false,"title":"oblivious","artist":"Kalafina","album":"Kalafina All Time Best 2008-2018","uri":"USB/Music/ACG/Kalafina - Kalafina All Time Best 2008-2018 (FLAC 24bit／96kHz)/01. oblivious.flac","trackType":"flac"} May 31 11:12:02 volumio volumio[1127]: verbose: CURRENT POSITION 0 May 31 11:12:02 volumio volumio[1127]: info: CoreStateMachine::syncState stateService play May 31 11:12:02 volumio volumio[1127]: info: CoreStateMachine::syncState currentStatus play May 31 11:12:02 volumio volumio[1127]: info: Received an update from plugin. extracting info from payload May 31 11:12:02 volumio volumio[1127]: info: CoreStateMachine::pushState May 31 11:12:02 volumio volumio[1127]: info: CorePlayQueue::getTrack 0 May 31 11:12:02 volumio volumio[1127]: info: CoreCommandRouter::volumioPushState May 31 11:12:02 volumio volumio[1127]: info: MRS: Pushing multiroomSync output update for this device May 31 11:12:02 volumio volumio[1127]: info: MRS: Pushing multiroomSync output May 31 11:12:02 volumio volumio[1127]: info: CoreStateMachine::pushState May 31 11:12:02 volumio volumio[1127]: info: CorePlayQueue::getTrack 0 May 31 11:12:02 volumio volumio[1127]: info: CoreCommandRouter::volumioPushState May 31 11:12:02 volumio volumio[1127]: info: MRS: Pushing multiroomSync output update for this device May 31 11:12:02 volumio volumio[1127]: info: MRS: Pushing multiroomSync output May 31 11:12:02 volumio volumio[1127]: info: ControllerMpd::pushState May 31 11:12:02 volumio volumio[1127]: info: CoreCommandRouter::servicePushState May 31 11:12:02 volumio volumio[1127]: info: CorePlayQueue::getTrack 0 May 31 11:12:02 volumio volumio[1127]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1244,"duration":264,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"4615 Kbps","isStreaming":false,"title":"oblivious","artist":"Kalafina","album":"Kalafina All Time Best 2008-2018","uri":"USB/Music/ACG/Kalafina - Kalafina All Time Best 2008-2018 (FLAC 24bit／96kHz)/01. oblivious.flac","trackType":"flac"} May 31 11:12:02 volumio volumio[1127]: verbose: CURRENT POSITION 0 May 31 11:12:02 volumio volumio[1127]: info: CoreStateMachine::syncState stateService play May 31 11:12:02 volumio volumio[1127]: info: CoreStateMachine::syncState currentStatus play May 31 11:12:02 volumio volumio[1127]: info: Received an update from plugin. extracting info from payload May 31 11:12:02 volumio volumio[1127]: info: CoreStateMachine::pushState May 31 11:12:02 volumio volumio[1127]: info: CorePlayQueue::getTrack 0 May 31 11:12:02 volumio volumio[1127]: info: CoreCommandRouter::volumioPushState May 31 11:12:02 volumio volumio[1127]: info: MRS: Pushing multiroomSync output update for this device May 31 11:12:02 volumio volumio[1127]: info: MRS: Pushing multiroomSync output May 31 11:12:02 volumio volumio[1127]: info: CoreStateMachine::pushState May 31 11:12:02 volumio volumio[1127]: info: CorePlayQueue::getTrack 0 May 31 11:12:02 volumio volumio[1127]: info: CoreCommandRouter::volumioPushState May 31 11:12:02 volumio volumio[1127]: info: MRS: Pushing multiroomSync output update for this device May 31 11:12:02 volumio volumio[1127]: info: MRS: Pushing multiroomSync output May 31 11:12:02 volumio volumio[1127]: info: ------------------------------ 55ms May 31 11:12:02 volumio volumio[1127]: info: ------------------------------ 54ms May 31 11:12:02 volumio volumio[1127]: info: ------------------------------ 54ms May 31 11:12:02 volumio volumio[1127]: info: ------------------------------ 53ms May 31 11:12:02 volumio volumio[1127]: info: ------------------------------ 54ms May 31 11:12:02 volumio volumio[1127]: info: FusionDsp - {"Reload":{"result":"Ok"}} May 31 11:12:24 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 31 11:12:24 volumio volumio[1127]: info: CURURI: playlists May 31 11:12:24 volumio volumio[1127]: info: Listing playlists May 31 11:12:24 volumio volumio[1127]: info: Preload queue cleared May 31 11:12:26 volumio volumio[1127]: info: Preload queue cleared May 31 11:12:26 volumio volumio[1127]: info: CoreCommandRouter::volumioReplaceandPlayItems May 31 11:12:26 volumio volumio[1127]: info: CoreStateMachine::ClearQueue May 31 11:12:26 volumio volumio[1127]: info: CoreStateMachine::stop May 31 11:12:26 volumio volumio[1127]: info: CoreStateMachine::setConsumeUpdateService undefined May 31 11:12:26 volumio volumio[1127]: info: CoreStateMachine::stPlaybackTimer May 31 11:12:26 volumio volumio[1127]: info: CoreStateMachine::updateTrackBlock May 31 11:12:26 volumio volumio[1127]: info: CorePlayQueue::getTrackBlock May 31 11:12:26 volumio volumio[1127]: info: CoreStateMachine::pushState May 31 11:12:26 volumio volumio[1127]: info: CorePlayQueue::getTrack 0 May 31 11:12:26 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 11:12:26 volumio volumio[1127]: info: CoreCommandRouter::volumioPushState May 31 11:12:26 volumio volumio[1127]: info: MRS: Pushing multiroomSync output update for this device May 31 11:12:26 volumio volumio[1127]: info: MRS: Pushing multiroomSync output May 31 11:12:26 volumio volumio[1127]: info: CoreStateMachine::serviceStop May 31 11:12:26 volumio volumio[1127]: info: CorePlayQueue::getTrack 0 May 31 11:12:26 volumio volumio[1127]: info: CoreCommandRouter::serviceStop May 31 11:12:26 volumio volumio[1127]: info: ControllerMpd::stop May 31 11:12:26 volumio volumio[1127]: verbose: ControllerMpd::sendMpdCommand stop May 31 11:12:26 volumio volumio[1127]: info: CorePlayQueue::clearPlayQueue May 31 11:12:26 volumio volumio[1127]: info: CorePlayQueue::saveQueue May 31 11:12:26 volumio volumio[1127]: info: CoreCommandRouter::volumioPushQueue May 31 11:12:26 volumio volumio[1127]: info: CoreStateMachine::addQueueItems May 31 11:12:26 volumio volumio[1127]: info: CorePlayQueue::addQueueItems May 31 11:12:26 volumio volumio[1127]: info: Preload queue cleared May 31 11:12:26 volumio volumio[1127]: info: Adding Item to queue: playlists/降龍十八掌 May 31 11:12:26 volumio volumio[1127]: info: Exploding uri playlists/降龍十八掌 in service mpd May 31 11:12:26 volumio volumio[1127]: error: scanFolder - failure to stat '/mnt/playlists/降龍十八掌' May 31 11:12:26 volumio volumio[1127]: info: CoreCommandRouter::volumioPushQueue May 31 11:12:26 volumio volumio[1127]: info: CorePlayQueue::saveQueue May 31 11:12:26 volumio volumio[1127]: info: CoreStateMachine::updateTrackBlock May 31 11:12:26 volumio volumio[1127]: info: CorePlayQueue::getTrackBlock May 31 11:12:26 volumio volumio[1127]: info: CoreCommandRouter::volumioPlay May 31 11:12:26 volumio volumio[1127]: info: CoreStateMachine::play index 0 May 31 11:12:26 volumio volumio[1127]: info: CoreStateMachine::setConsumeUpdateService undefined May 31 11:12:26 volumio volumio[1127]: info: CoreStateMachine::stop May 31 11:12:26 volumio volumio[1127]: info: CoreStateMachine::setConsumeUpdateService undefined May 31 11:12:26 volumio volumio[1127]: info: CoreStateMachine::play index undefined May 31 11:12:26 volumio volumio[1127]: info: CoreStateMachine::setConsumeUpdateService undefined May 31 11:12:26 volumio volumio[1127]: info: CorePlayQueue::getTrack 0 May 31 11:12:26 volumio volumio[1127]: info: May 31 11:12:26 volumio volumio[1127]: ---------------------------- MPD announces state update: player May 31 11:12:26 volumio volumio[1127]: info: sendMpdCommand stop took 8 milliseconds May 31 11:12:26 volumio volumio[1127]: info: ControllerMpd::getState May 31 11:12:26 volumio volumio[1127]: verbose: ControllerMpd::sendMpdCommand status May 31 11:12:26 volumio volumio[1127]: info: May 31 11:12:26 volumio volumio[1127]: ---------------------------- MPD announces state update: player May 31 11:12:26 volumio volumio[1127]: info: ControllerMpd::getState May 31 11:12:26 volumio volumio[1127]: verbose: ControllerMpd::sendMpdCommand status May 31 11:12:26 volumio volumio[1127]: info: May 31 11:12:26 volumio volumio[1127]: ---------------------------- MPD announces state update: player May 31 11:12:26 volumio volumio[1127]: info: ControllerMpd::getState May 31 11:12:26 volumio volumio[1127]: verbose: ControllerMpd::sendMpdCommand status May 31 11:12:26 volumio volumio[1127]: info: May 31 11:12:26 volumio volumio[1127]: ---------------------------- MPD announces state update: player May 31 11:12:26 volumio volumio[1127]: info: ControllerMpd::getState May 31 11:12:26 volumio volumio[1127]: verbose: ControllerMpd::sendMpdCommand status May 31 11:12:26 volumio volumio[1127]: info: May 31 11:12:26 volumio volumio[1127]: ---------------------------- MPD announces state update: player May 31 11:12:26 volumio volumio[1127]: info: ControllerMpd::getState May 31 11:12:26 volumio volumio[1127]: verbose: ControllerMpd::sendMpdCommand status May 31 11:12:26 volumio volumio[1127]: info: sendMpdCommand status took 1 milliseconds May 31 11:12:26 volumio volumio[1127]: info: sendMpdCommand status took 1 milliseconds May 31 11:12:26 volumio volumio[1127]: info: sendMpdCommand status took 1 milliseconds May 31 11:12:26 volumio volumio[1127]: info: sendMpdCommand status took 1 milliseconds May 31 11:12:26 volumio volumio[1127]: info: sendMpdCommand status took 0 milliseconds May 31 11:12:26 volumio volumio[1127]: verbose: ControllerMpd::parseState May 31 11:12:26 volumio volumio[1127]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 11:12:26 volumio volumio[1127]: verbose: ControllerMpd::parseState May 31 11:12:26 volumio volumio[1127]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 11:12:26 volumio volumio[1127]: verbose: ControllerMpd::parseState May 31 11:12:26 volumio volumio[1127]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 11:12:26 volumio volumio[1127]: verbose: ControllerMpd::parseState May 31 11:12:26 volumio volumio[1127]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 11:12:26 volumio volumio[1127]: verbose: ControllerMpd::parseState May 31 11:12:26 volumio volumio[1127]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 11:12:26 volumio volumio[1127]: info: sendMpdCommand playlistinfo took 0 milliseconds May 31 11:12:26 volumio volumio[1127]: info: sendMpdCommand playlistinfo took 1 milliseconds May 31 11:12:26 volumio volumio[1127]: info: sendMpdCommand playlistinfo took 1 milliseconds May 31 11:12:26 volumio volumio[1127]: info: sendMpdCommand playlistinfo took 1 milliseconds May 31 11:12:26 volumio volumio[1127]: info: sendMpdCommand playlistinfo took 1 milliseconds May 31 11:12:26 volumio volumio[1127]: verbose: ControllerMpd::parseTrackInfo May 31 11:12:26 volumio volumio[1127]: verbose: ControllerMpd::parseTrackInfo May 31 11:12:26 volumio volumio[1127]: verbose: ControllerMpd::parseTrackInfo May 31 11:12:26 volumio volumio[1127]: verbose: ControllerMpd::parseTrackInfo May 31 11:12:26 volumio volumio[1127]: verbose: ControllerMpd::parseTrackInfo May 31 11:12:26 volumio volumio[1127]: info: ControllerMpd::pushState May 31 11:12:26 volumio volumio[1127]: info: CoreCommandRouter::servicePushState May 31 11:12:26 volumio volumio[1127]: info: CoreStateMachine::pushState May 31 11:12:26 volumio volumio[1127]: info: CorePlayQueue::getTrack 0 May 31 11:12:26 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 11:12:26 volumio volumio[1127]: info: CoreCommandRouter::volumioPushState May 31 11:12:26 volumio volumio[1127]: info: MRS: Pushing multiroomSync output update for this device May 31 11:12:26 volumio volumio[1127]: info: MRS: Pushing multiroomSync output May 31 11:12:26 volumio volumio[1127]: info: CorePlayQueue::getTrack 0 May 31 11:12:26 volumio volumio[1127]: 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":"oblivious","artist":"Kalafina","album":"Kalafina All Time Best 2008-2018","uri":"USB/Music/ACG/Kalafina - Kalafina All Time Best 2008-2018 (FLAC 24bit／96kHz)/01. oblivious.flac","trackType":"flac"} May 31 11:12:26 volumio volumio[1127]: verbose: CURRENT POSITION 0 May 31 11:12:26 volumio volumio[1127]: info: CoreStateMachine::syncState stateService stop May 31 11:12:26 volumio volumio[1127]: info: CoreStateMachine::syncState currentStatus stop May 31 11:12:26 volumio volumio[1127]: info: CoreStateMachine::pushState May 31 11:12:26 volumio volumio[1127]: info: CorePlayQueue::getTrack 0 May 31 11:12:26 volumio volumio[1127]: info: CoreCommandRouter::volumioPushState May 31 11:12:26 volumio volumio[1127]: info: MRS: Pushing multiroomSync output update for this device May 31 11:12:26 volumio volumio[1127]: info: MRS: Pushing multiroomSync output May 31 11:12:26 volumio volumio[1127]: info: No code May 31 11:12:26 volumio volumio[1127]: info: CoreStateMachine::pushState May 31 11:12:26 volumio volumio[1127]: info: CorePlayQueue::getTrack 0 May 31 11:12:26 volumio volumio[1127]: info: CoreCommandRouter::volumioPushState May 31 11:12:26 volumio volumio[1127]: info: MRS: Pushing multiroomSync output update for this device May 31 11:12:26 volumio volumio[1127]: info: MRS: Pushing multiroomSync output May 31 11:12:26 volumio volumio[1127]: info: ControllerMpd::pushState May 31 11:12:26 volumio volumio[1127]: info: CoreCommandRouter::servicePushState May 31 11:12:26 volumio volumio[1127]: info: CoreStateMachine::pushState May 31 11:12:26 volumio volumio[1127]: info: CorePlayQueue::getTrack 0 May 31 11:12:26 volumio volumio[1127]: info: CoreCommandRouter::volumioPushState May 31 11:12:26 volumio volumio[1127]: info: MRS: Pushing multiroomSync output update for this device May 31 11:12:26 volumio volumio[1127]: info: MRS: Pushing multiroomSync output May 31 11:12:26 volumio volumio[1127]: info: CorePlayQueue::getTrack 0 May 31 11:12:26 volumio volumio[1127]: 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":"oblivious","artist":"Kalafina","album":"Kalafina All Time Best 2008-2018","uri":"USB/Music/ACG/Kalafina - Kalafina All Time Best 2008-2018 (FLAC 24bit／96kHz)/01. oblivious.flac","trackType":"flac"} May 31 11:12:26 volumio volumio[1127]: verbose: CURRENT POSITION 0 May 31 11:12:26 volumio volumio[1127]: info: CoreStateMachine::syncState stateService stop May 31 11:12:26 volumio volumio[1127]: info: CoreStateMachine::syncState currentStatus stop May 31 11:12:26 volumio volumio[1127]: info: CoreStateMachine::pushState May 31 11:12:26 volumio volumio[1127]: info: CorePlayQueue::getTrack 0 May 31 11:12:26 volumio volumio[1127]: info: CoreCommandRouter::volumioPushState May 31 11:12:26 volumio volumio[1127]: info: MRS: Pushing multiroomSync output update for this device May 31 11:12:26 volumio volumio[1127]: info: MRS: Pushing multiroomSync output May 31 11:12:26 volumio volumio[1127]: info: No code May 31 11:12:26 volumio volumio[1127]: info: CoreStateMachine::pushState May 31 11:12:26 volumio volumio[1127]: info: CorePlayQueue::getTrack 0 May 31 11:12:26 volumio volumio[1127]: info: CoreCommandRouter::volumioPushState May 31 11:12:26 volumio volumio[1127]: info: MRS: Pushing multiroomSync output update for this device May 31 11:12:26 volumio volumio[1127]: info: MRS: Pushing multiroomSync output May 31 11:12:26 volumio volumio[1127]: info: ControllerMpd::pushState May 31 11:12:26 volumio volumio[1127]: info: CoreCommandRouter::servicePushState May 31 11:12:26 volumio volumio[1127]: info: CoreStateMachine::pushState May 31 11:12:26 volumio volumio[1127]: info: CorePlayQueue::getTrack 0 May 31 11:12:26 volumio volumio[1127]: info: CoreCommandRouter::volumioPushState May 31 11:12:26 volumio volumio[1127]: info: MRS: Pushing multiroomSync output update for this device May 31 11:12:26 volumio volumio[1127]: info: MRS: Pushing multiroomSync output May 31 11:12:26 volumio volumio[1127]: info: CorePlayQueue::getTrack 0 May 31 11:12:26 volumio volumio[1127]: 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":"oblivious","artist":"Kalafina","album":"Kalafina All Time Best 2008-2018","uri":"USB/Music/ACG/Kalafina - Kalafina All Time Best 2008-2018 (FLAC 24bit／96kHz)/01. oblivious.flac","trackType":"flac"} May 31 11:12:26 volumio volumio[1127]: verbose: CURRENT POSITION 0 May 31 11:12:26 volumio volumio[1127]: info: CoreStateMachine::syncState stateService stop May 31 11:12:26 volumio volumio[1127]: info: CoreStateMachine::syncState currentStatus stop May 31 11:12:26 volumio volumio[1127]: info: CoreStateMachine::pushState May 31 11:12:26 volumio volumio[1127]: info: CorePlayQueue::getTrack 0 May 31 11:12:26 volumio volumio[1127]: info: CoreCommandRouter::volumioPushState May 31 11:12:26 volumio volumio[1127]: info: MRS: Pushing multiroomSync output update for this device May 31 11:12:26 volumio volumio[1127]: info: MRS: Pushing multiroomSync output May 31 11:12:26 volumio volumio[1127]: info: No code May 31 11:12:26 volumio volumio[1127]: info: CoreStateMachine::pushState May 31 11:12:26 volumio volumio[1127]: info: CorePlayQueue::getTrack 0 May 31 11:12:26 volumio volumio[1127]: info: CoreCommandRouter::volumioPushState May 31 11:12:26 volumio volumio[1127]: info: MRS: Pushing multiroomSync output update for this device May 31 11:12:26 volumio volumio[1127]: info: MRS: Pushing multiroomSync output May 31 11:12:26 volumio volumio[1127]: info: ControllerMpd::pushState May 31 11:12:26 volumio volumio[1127]: info: CoreCommandRouter::servicePushState May 31 11:12:26 volumio volumio[1127]: info: CoreStateMachine::pushState May 31 11:12:26 volumio volumio[1127]: info: CorePlayQueue::getTrack 0 May 31 11:12:26 volumio volumio[1127]: info: CoreCommandRouter::volumioPushState May 31 11:12:26 volumio volumio[1127]: info: MRS: Pushing multiroomSync output update for this device May 31 11:12:26 volumio volumio[1127]: info: MRS: Pushing multiroomSync output May 31 11:12:26 volumio volumio[1127]: info: CorePlayQueue::getTrack 0 May 31 11:12:26 volumio volumio[1127]: 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":"oblivious","artist":"Kalafina","album":"Kalafina All Time Best 2008-2018","uri":"USB/Music/ACG/Kalafina - Kalafina All Time Best 2008-2018 (FLAC 24bit／96kHz)/01. oblivious.flac","trackType":"flac"} May 31 11:12:26 volumio volumio[1127]: verbose: CURRENT POSITION 0 May 31 11:12:26 volumio volumio[1127]: info: CoreStateMachine::syncState stateService stop May 31 11:12:26 volumio volumio[1127]: info: CoreStateMachine::syncState currentStatus stop May 31 11:12:26 volumio volumio[1127]: info: CoreStateMachine::pushState May 31 11:12:26 volumio volumio[1127]: info: CorePlayQueue::getTrack 0 May 31 11:12:26 volumio volumio[1127]: info: CoreCommandRouter::volumioPushState May 31 11:12:26 volumio volumio[1127]: info: MRS: Pushing multiroomSync output update for this device May 31 11:12:26 volumio volumio[1127]: info: MRS: Pushing multiroomSync output May 31 11:12:26 volumio volumio[1127]: info: No code May 31 11:12:26 volumio volumio[1127]: info: CoreStateMachine::pushState May 31 11:12:26 volumio volumio[1127]: info: CorePlayQueue::getTrack 0 May 31 11:12:26 volumio volumio[1127]: info: CoreCommandRouter::volumioPushState May 31 11:12:26 volumio volumio[1127]: info: MRS: Pushing multiroomSync output update for this device May 31 11:12:26 volumio volumio[1127]: info: MRS: Pushing multiroomSync output May 31 11:12:26 volumio volumio[1127]: info: ControllerMpd::pushState May 31 11:12:26 volumio volumio[1127]: info: CoreCommandRouter::servicePushState May 31 11:12:26 volumio volumio[1127]: info: CoreStateMachine::pushState May 31 11:12:26 volumio volumio[1127]: info: CorePlayQueue::getTrack 0 May 31 11:12:26 volumio volumio[1127]: info: CoreCommandRouter::volumioPushState May 31 11:12:26 volumio volumio[1127]: info: MRS: Pushing multiroomSync output update for this device May 31 11:12:26 volumio volumio[1127]: info: MRS: Pushing multiroomSync output May 31 11:12:26 volumio volumio[1127]: info: CorePlayQueue::getTrack 0 May 31 11:12:26 volumio volumio[1127]: 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":"oblivious","artist":"Kalafina","album":"Kalafina All Time Best 2008-2018","uri":"USB/Music/ACG/Kalafina - Kalafina All Time Best 2008-2018 (FLAC 24bit／96kHz)/01. oblivious.flac","trackType":"flac"} May 31 11:12:26 volumio volumio[1127]: verbose: CURRENT POSITION 0 May 31 11:12:26 volumio volumio[1127]: info: CoreStateMachine::syncState stateService stop May 31 11:12:26 volumio volumio[1127]: info: CoreStateMachine::syncState currentStatus stop May 31 11:12:26 volumio volumio[1127]: info: CoreStateMachine::pushState May 31 11:12:26 volumio volumio[1127]: info: CorePlayQueue::getTrack 0 May 31 11:12:26 volumio volumio[1127]: info: CoreCommandRouter::volumioPushState May 31 11:12:26 volumio volumio[1127]: info: MRS: Pushing multiroomSync output update for this device May 31 11:12:26 volumio volumio[1127]: info: MRS: Pushing multiroomSync output May 31 11:12:26 volumio volumio[1127]: info: No code May 31 11:12:26 volumio volumio[1127]: info: CoreStateMachine::pushState May 31 11:12:26 volumio volumio[1127]: info: CorePlayQueue::getTrack 0 May 31 11:12:26 volumio volumio[1127]: info: CoreCommandRouter::volumioPushState May 31 11:12:26 volumio volumio[1127]: info: MRS: Pushing multiroomSync output update for this device May 31 11:12:26 volumio volumio[1127]: info: MRS: Pushing multiroomSync output May 31 11:12:27 volumio volumio[1127]: info: ------------------------------ 40ms May 31 11:12:27 volumio volumio[1127]: info: ------------------------------ 40ms May 31 11:12:27 volumio volumio[1127]: info: ------------------------------ 41ms May 31 11:12:27 volumio volumio[1127]: info: ------------------------------ 41ms May 31 11:12:27 volumio volumio[1127]: info: ------------------------------ 42ms May 31 11:12:27 volumio volumio[1127]: info: camilladsp spawned new process with pid 3742, instance 1, run: true May 31 11:12:31 volumio volumio[1127]: info: CoreCommandRouter::volumioGetState May 31 11:12:31 volumio volumio[1127]: info: CorePlayQueue::getTrack 0 May 31 11:12:33 volumio volumio[1127]: info: CoreCommandRouter::volumioGetState May 31 11:12:33 volumio volumio[1127]: info: CorePlayQueue::getTrack 0 May 31 11:12:38 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 31 11:12:38 volumio volumio[1127]: info: CURURI: playlists/降龍十八掌 May 31 11:12:38 volumio volumio[1127]: info: Preload queue cleared May 31 11:12:38 volumio volumio[1127]: info: Preloading song: cue://USB/Music/Music/降龍十八掌/蔡克信 - 調音秘笈-降龍十八掌.cue@0 May 31 11:12:38 volumio volumio[1127]: info: Preloading song: cue://USB/Music/Music/降龍十八掌/蔡克信 - 調音秘笈-降龍十八掌.cue@1 May 31 11:12:38 volumio volumio[1127]: info: Preloading song: cue://USB/Music/Music/降龍十八掌/蔡克信 - 調音秘笈-降龍十八掌.cue@2 May 31 11:12:38 volumio volumio[1127]: info: Preloading song: cue://USB/Music/Music/降龍十八掌/蔡克信 - 調音秘笈-降龍十八掌.cue@3 May 31 11:12:38 volumio volumio[1127]: info: Preloading song: cue://USB/Music/Music/降龍十八掌/蔡克信 - 調音秘笈-降龍十八掌.cue@4 May 31 11:12:38 volumio volumio[1127]: info: Preloading song: cue://USB/Music/Music/降龍十八掌/蔡克信 - 調音秘笈-降龍十八掌.cue@5 May 31 11:12:38 volumio volumio[1127]: info: Preloading song: cue://USB/Music/Music/降龍十八掌/蔡克信 - 調音秘笈-降龍十八掌.cue@6 May 31 11:12:38 volumio volumio[1127]: info: Preloading song: cue://USB/Music/Music/降龍十八掌/蔡克信 - 調音秘笈-降龍十八掌.cue@7 May 31 11:12:38 volumio volumio[1127]: info: Preloading song: cue://USB/Music/Music/降龍十八掌/蔡克信 - 調音秘笈-降龍十八掌.cue@8 May 31 11:12:38 volumio volumio[1127]: info: Preloading song: cue://USB/Music/Music/降龍十八掌/蔡克信 - 調音秘笈-降龍十八掌.cue@9 May 31 11:12:38 volumio volumio[1127]: info: Preloading song: cue://USB/Music/Music/降龍十八掌/蔡克信 - 調音秘笈-降龍十八掌.cue@10 May 31 11:12:38 volumio volumio[1127]: info: Preloading song: cue://USB/Music/Music/降龍十八掌/蔡克信 - 調音秘笈-降龍十八掌.cue@11 May 31 11:12:38 volumio volumio[1127]: info: Preloading song: cue://USB/Music/Music/降龍十八掌/蔡克信 - 調音秘笈-降龍十八掌.cue@12 May 31 11:12:38 volumio volumio[1127]: info: Preloading song: cue://USB/Music/Music/降龍十八掌/蔡克信 - 調音秘笈-降龍十八掌.cue@13 May 31 11:12:38 volumio volumio[1127]: info: Preloading song: cue://USB/Music/Music/降龍十八掌/蔡克信 - 調音秘笈-降龍十八掌.cue@14 May 31 11:12:38 volumio volumio[1127]: info: Preloading song: cue://USB/Music/Music/降龍十八掌/蔡克信 - 調音秘笈-降龍十八掌.cue@15 May 31 11:12:38 volumio volumio[1127]: info: Preloading song: cue://USB/Music/Music/降龍十八掌/蔡克信 - 調音秘笈-降龍十八掌.cue@16 May 31 11:12:38 volumio volumio[1127]: info: Preloading song: cue://USB/Music/Music/降龍十八掌/蔡克信 - 調音秘笈-降龍十八掌.cue@17 May 31 11:12:38 volumio volumio[1127]: info: Exploding uri cue://USB/Music/Music/降龍十八掌/蔡克信 - 調音秘笈-降龍十八掌.cue@0 in service mpd May 31 11:12:38 volumio volumio[1127]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 31 11:12:38 volumio volumio[1127]: Error: file /mnt/USB/Music/Music/降龍十八掌/蔡克信 - 調音秘笈-降龍十八掌.cue does not exist May 31 11:12:38 volumio volumio[1127]: at Object.parse (/volumio/node_modules/cue-parser/lib/cue.js:37:15) May 31 11:12:38 volumio volumio[1127]: at ControllerMpd.explodeUri (/volumio/app/plugins/music_service/mpd/index.js:1925:27) May 31 11:12:38 volumio volumio[1127]: at CoreCommandRouter.explodeUriFromService (/volumio/app/index.js:1384:16) May 31 11:12:38 volumio volumio[1127]: at CorePlayQueue.explodeUriFromCache (/volumio/app/playqueue.js:123:24) May 31 11:12:38 volumio volumio[1127]: at CorePlayQueue.explodeUri (/volumio/app/playqueue.js:181:19) May 31 11:12:38 volumio volumio[1127]: at Timeout._onTimeout (/volumio/app/playqueue.js:146:100) May 31 11:12:38 volumio volumio[1127]: at listOnTimeout (internal/timers.js:557:17) May 31 11:12:38 volumio volumio[1127]: at processTimers (internal/timers.js:500:7) May 31 11:12:38 volumio volumio[1127]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 31 11:12:38 volumio sudo[3789]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-31 11:11 May 31 11:12:38 volumio sudo[3789]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="e32c2c71ae78852844c70357816bdd871c155ab8" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 31 Mar 2025 07:27:48 PM CEST" VOLUMIO_VERSION="3.799" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="4bc1ecb08f34ad14b420d6678a2aef1b"