-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Sun 2024-05-05 11:05:57 CEST. -- May 05 11:04:08 volumio volumio[932]: info: CALLMETHOD: audio_interface alsa_controller saveVolumeOptions [object Object] May 05 11:04:08 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveVolumeOptions May 05 11:04:08 volumio volumio[932]: info: CoreCommandRouter::volumioGetState May 05 11:04:08 volumio volumio[932]: info: Restoring Previous Volume level: 100 false true May 05 11:04:08 volumio volumio[932]: info: VolumeController::SetAlsaVolume100 May 05 11:04:08 volumio volumio[932]: info: Volume configurations have been set May 05 11:04:08 volumio vtcs[1289]: [2024-05-05 11:04:08.849] [tisoc] [warning] [SessionManagerImpl.cpp:243] Illegal State: IDLE May 05 11:04:08 volumio vtcs[1289]: [2024-05-05 11:04:08.851] [tisoc] [error] [SpkconServer.cpp:381] recv error. client fd=8 errorno=104 error=Connection reset by peer May 05 11:04:08 volumio vtcs[1289]: [2024-05-05 11:04:08.851] [tisoc] [error] [SpkconServer.cpp:376] recv error. socket disconnected May 05 11:04:08 volumio sudo[1784]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 05 11:04:08 volumio sudo[1784]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 11:04:08 volumio systemd[1]: Stopping Volumio Tidal Connect Service... May 05 11:04:08 volumio systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM May 05 11:04:08 volumio systemd[1]: vtcs.service: Succeeded. May 05 11:04:08 volumio systemd[1]: Stopped Volumio Tidal Connect Service. May 05 11:04:08 volumio sudo[1784]: pam_unix(sudo:session): session closed for user root May 05 11:04:08 volumio volumio[932]: xcb_connection_has_error() returned true May 05 11:04:08 volumio volumio[932]: info: CoreCommandRouter::volumioUpdateVolumeSettings May 05 11:04:08 volumio volumio[932]: info: Updating Volume Controller Parameters: Device: 2 Name: softvolume Mixer: Digital Max Vol: 100 Vol Curve; linear Vol Steps: 5 May 05 11:04:08 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume May 05 11:04:08 volumio volumio[932]: info: Disabling external Volume Control May 05 11:04:08 volumio volumio[932]: info: CoreCommandRouter::getUIConfigOnPlugin May 05 11:04:08 volumio volumio[932]: info: CoreStateMachine::pushState May 05 11:04:08 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 05 11:04:08 volumio volumio[932]: info: CoreCommandRouter::volumioPushState May 05 11:04:09 volumio volumio[932]: info: MRS: Pushing multiroomSync output update for this device May 05 11:04:09 volumio volumio[932]: info: MRS: Pushing multiroomSync output May 05 11:04:09 volumio sudo[1797]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 05 11:04:09 volumio sudo[1797]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 11:04:09 volumio volumio[932]: ------------------------------------ BT MESSAGE: BT STATUS: running May 05 11:04:09 volumio sudo[1797]: pam_unix(sudo:session): session closed for user root May 05 11:04:09 volumio volumio[932]: info: CoreStateMachine::pushState May 05 11:04:09 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 05 11:04:09 volumio volumio[932]: info: CoreCommandRouter::volumioPushState May 05 11:04:09 volumio volumio[932]: info: MRS: Pushing multiroomSync output update for this device May 05 11:04:09 volumio volumio[932]: info: MRS: Pushing multiroomSync output May 05 11:04:09 volumio volumio[932]: xcb_connection_has_error() returned true May 05 11:04:09 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions May 05 11:04:09 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 05 11:04:09 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 05 11:04:09 volumio volumio[932]: info: Getting Alsa Cards List without I2S DAC May 05 11:04:09 volumio volumio[932]: xcb_connection_has_error() returned true May 05 11:04:09 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber May 05 11:04:09 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 05 11:04:09 volumio volumio[932]: xcb_connection_has_error() returned true May 05 11:04:09 volumio volumio[932]: xcb_connection_has_error() returned true May 05 11:04:09 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 05 11:04:09 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 05 11:04:09 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 05 11:04:09 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 05 11:04:09 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 05 11:04:09 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 05 11:04:09 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 05 11:04:09 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode May 05 11:04:09 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 05 11:04:11 volumio volumio[932]: info: TidalConnect service stoped! May 05 11:04:12 volumio volumio[932]: info: TidalConnect service stoped! May 05 11:04:12 volumio volumio[932]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect May 05 11:04:12 volumio volumio[932]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect May 05 11:04:12 volumio sudo[1817]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service May 05 11:04:12 volumio sudo[1817]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 11:04:12 volumio systemd[1]: Started Volumio Tidal Connect Service. May 05 11:04:12 volumio sudo[1817]: pam_unix(sudo:session): session closed for user root May 05 11:04:12 volumio volumio[932]: info: Executing endpoint tc_getconfig May 05 11:04:12 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig May 05 11:04:12 volumio vtcs[1819]: STARTING TidalConnect services, version: 1.4.0.34 May 05 11:04:12 volumio vtcs[1819]: STARTED TidalConnect services. May 05 11:04:12 volumio volumio[932]: info: Executing endpoint tc_connect May 05 11:04:12 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect May 05 11:04:12 volumio volumio[932]: info: Connecting to TidalConnect May 05 11:04:12 volumio volumio[932]: info: CoreCommandRouter::servicePushState May 05 11:04:12 volumio volumio[932]: info: CoreStateMachine::pushState May 05 11:04:12 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 05 11:04:12 volumio volumio[932]: info: CoreCommandRouter::volumioPushState May 05 11:04:12 volumio volumio[932]: info: MRS: Pushing multiroomSync output update for this device May 05 11:04:12 volumio volumio[932]: info: MRS: Pushing multiroomSync output May 05 11:04:12 volumio volumio[932]: info: CorePlayQueue::getTrack 0 May 05 11:04:12 volumio volumio[932]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current mpd Received tidalconnect May 05 11:04:12 volumio volumio[932]: info: CoreCommandRouter::servicePushState May 05 11:04:12 volumio volumio[932]: info: CoreStateMachine::pushState May 05 11:04:12 volumio volumio[932]: info: CoreCommandRouter::volumioPushState May 05 11:04:12 volumio volumio[932]: info: MRS: Pushing multiroomSync output update for this device May 05 11:04:12 volumio volumio[932]: info: MRS: Pushing multiroomSync output May 05 11:04:12 volumio volumio[932]: info: CorePlayQueue::getTrack 0 May 05 11:04:12 volumio volumio[932]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current mpd Received tidalconnect May 05 11:04:13 volumio volumio[932]: info: VolumeController::SetAlsaVolume100 May 05 11:04:13 volumio volumio[932]: info: CoreStateMachine::pushState May 05 11:04:13 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 05 11:04:13 volumio volumio[932]: info: CoreCommandRouter::volumioPushState May 05 11:04:13 volumio volumio[932]: info: MRS: Pushing multiroomSync output update for this device May 05 11:04:13 volumio volumio[932]: info: MRS: Pushing multiroomSync output May 05 11:04:14 volumio volumio[932]: info: VolumeController::SetAlsaVolume+ May 05 11:04:14 volumio volumio[932]: info: CoreStateMachine::pushState May 05 11:04:14 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 05 11:04:14 volumio volumio[932]: info: CoreCommandRouter::volumioPushState May 05 11:04:14 volumio volumio[932]: info: MRS: Pushing multiroomSync output update for this device May 05 11:04:14 volumio volumio[932]: info: MRS: Pushing multiroomSync output May 05 11:04:14 volumio volumio[932]: info: VolumeController::SetAlsaVolume+ May 05 11:04:15 volumio volumio[932]: info: CoreStateMachine::pushState May 05 11:04:15 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 05 11:04:15 volumio volumio[932]: info: CoreCommandRouter::volumioPushState May 05 11:04:15 volumio volumio[932]: info: MRS: Pushing multiroomSync output update for this device May 05 11:04:15 volumio volumio[932]: info: MRS: Pushing multiroomSync output May 05 11:04:15 volumio volumio[932]: info: VolumeController::SetAlsaVolume+ May 05 11:04:15 volumio volumio[932]: info: CoreStateMachine::pushState May 05 11:04:15 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 05 11:04:15 volumio volumio[932]: info: CoreCommandRouter::volumioPushState May 05 11:04:15 volumio volumio[932]: info: MRS: Pushing multiroomSync output update for this device May 05 11:04:15 volumio volumio[932]: info: MRS: Pushing multiroomSync output May 05 11:04:15 volumio volumio[932]: info: TidalConnect service started! May 05 11:04:16 volumio volumio[932]: info: VolumeController::SetAlsaVolume- May 05 11:04:16 volumio volumio[932]: info: CoreStateMachine::pushState May 05 11:04:16 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 05 11:04:16 volumio volumio[932]: info: CoreCommandRouter::volumioPushState May 05 11:04:16 volumio volumio[932]: info: MRS: Pushing multiroomSync output update for this device May 05 11:04:16 volumio volumio[932]: info: MRS: Pushing multiroomSync output May 05 11:04:17 volumio volumio[932]: info: VolumeController::SetAlsaVolume+ May 05 11:04:17 volumio volumio[932]: info: CoreStateMachine::pushState May 05 11:04:17 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 05 11:04:17 volumio volumio[932]: info: CoreCommandRouter::volumioPushState May 05 11:04:17 volumio volumio[932]: info: MRS: Pushing multiroomSync output update for this device May 05 11:04:17 volumio volumio[932]: info: MRS: Pushing multiroomSync output May 05 11:04:39 volumio volumio[932]: info: CoreCommandRouter::getUIConfigOnPlugin May 05 11:04:39 volumio volumio[932]: xcb_connection_has_error() returned true May 05 11:04:39 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions May 05 11:04:39 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 05 11:04:39 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 05 11:04:39 volumio volumio[932]: info: Getting Alsa Cards List without I2S DAC May 05 11:04:39 volumio volumio[932]: xcb_connection_has_error() returned true May 05 11:04:39 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber May 05 11:04:39 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 05 11:04:39 volumio volumio[932]: xcb_connection_has_error() returned true May 05 11:04:39 volumio volumio[932]: xcb_connection_has_error() returned true May 05 11:04:39 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 05 11:04:39 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 05 11:04:39 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 05 11:04:39 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 05 11:04:39 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 05 11:04:39 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 05 11:04:39 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 05 11:04:39 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode May 05 11:04:39 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 05 11:05:02 volumio volumio[932]: info: CALLMETHOD: audio_interface alsa_controller saveVolumeOptions [object Object] May 05 11:05:02 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveVolumeOptions May 05 11:05:02 volumio volumio[932]: info: CoreCommandRouter::volumioGetState May 05 11:05:02 volumio volumio[932]: xcb_connection_has_error() returned true May 05 11:05:02 volumio volumio[932]: info: Disable softmixer device for audio device May 05 11:05:02 volumio volumio[932]: info: Output device has changed, restarting MPD May 05 11:05:02 volumio sudo[1897]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 05 11:05:02 volumio sudo[1897]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 11:05:02 volumio volumio[932]: info: Output device has changed, restarting Shairport Sync May 05 11:05:02 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 11:05:02 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 05 11:05:02 volumio sudo[1897]: pam_unix(sudo:session): session closed for user root May 05 11:05:02 volumio sudo[1900]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 05 11:05:02 volumio sudo[1900]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 11:05:02 volumio systemd[1]: Stopping Music Player Daemon... May 05 11:05:02 volumio volumio[932]: xcb_connection_has_error() returned true May 05 11:05:02 volumio volumio[932]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 05 11:05:02 volumio volumio[932]: xcb_connection_has_error() returned true May 05 11:05:02 volumio volumio[932]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration May 05 11:05:02 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 11:05:02 volumio systemd[1]: mpd.service: Succeeded. May 05 11:05:02 volumio systemd[1]: Stopped Music Player Daemon. May 05 11:05:02 volumio vtcs[1819]: [2024-05-05 11:05:02.847] [tisoc] [warning] [SessionManagerImpl.cpp:243] Illegal State: IDLE May 05 11:05:02 volumio kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 May 05 11:05:02 volumio volumio[932]: info: Restoring Previous Volume level: 100 false false May 05 11:05:02 volumio volumio[932]: info: Volume configurations have been set May 05 11:05:02 volumio sudo[1909]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 05 11:05:02 volumio vtcs[1819]: [2024-05-05 11:05:02.848] [tisoc] [error] [SpkconServer.cpp:381] recv error. client fd=8 errorno=104 error=Connection reset by peer May 05 11:05:02 volumio vtcs[1819]: [2024-05-05 11:05:02.848] [tisoc] [error] [SpkconServer.cpp:376] recv error. socket disconnected May 05 11:05:02 volumio systemd[1]: Starting Music Player Daemon... May 05 11:05:02 volumio sudo[1909]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 11:05:02 volumio sudo[1916]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 05 11:05:02 volumio sudo[1916]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 11:05:02 volumio systemd[1]: Stopping Volumio Tidal Connect Service... May 05 11:05:02 volumio systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM May 05 11:05:02 volumio systemd[1]: vtcs.service: Succeeded. May 05 11:05:02 volumio systemd[1]: Stopped Volumio Tidal Connect Service. May 05 11:05:02 volumio sudo[1909]: pam_unix(sudo:session): session closed for user root May 05 11:05:02 volumio sudo[1916]: pam_unix(sudo:session): session closed for user root May 05 11:05:03 volumio volumio[932]: xcb_connection_has_error() returned true May 05 11:05:03 volumio volumio[932]: info: CoreCommandRouter::volumioUpdateVolumeSettings May 05 11:05:03 volumio volumio[932]: info: Updating Volume Controller Parameters: Device: 2 Name: HiFiBerry DAC Plus Mixer: Analogue Max Vol: 100 Vol Curve; linear Vol Steps: 5 May 05 11:05:03 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume May 05 11:05:03 volumio volumio[932]: info: Disabling external Volume Control May 05 11:05:03 volumio volumio[932]: info: CoreCommandRouter::getUIConfigOnPlugin May 05 11:05:03 volumio sudo[1925]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 05 11:05:03 volumio sudo[1925]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 11:05:03 volumio volumio[932]: info: Soft Volume ALSA configuration file deleted May 05 11:05:03 volumio volumio[932]: info: Preparing to generate the ALSA configuration file May 05 11:05:03 volumio sudo[1927]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 05 11:05:03 volumio sudo[1927]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 11:05:03 volumio volumio[932]: ------------------------------------ BT MESSAGE: BT STATUS: running May 05 11:05:03 volumio volumio[932]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 05 11:05:03 volumio volumio[932]: ------------------------------------ BT MESSAGE: BT STATUS: running May 05 11:05:03 volumio sudo[1925]: pam_unix(sudo:session): session closed for user root May 05 11:05:03 volumio sudo[1927]: pam_unix(sudo:session): session closed for user root May 05 11:05:03 volumio volumio[932]: ------------------------------------ BT MESSAGE: BT STATUS: running May 05 11:05:03 volumio volumio[932]: info: MPD Permissions set May 05 11:05:03 volumio volumio[932]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf May 05 11:05:03 volumio volumio[932]: info: Reading ALSA contributions from plugins. May 05 11:05:03 volumio volumio[932]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 05 11:05:03 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 11:05:03 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 11:05:03 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 11:05:03 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 11:05:03 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 11:05:03 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 11:05:03 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 11:05:03 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 11:05:03 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 05 11:05:03 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 11:05:03 volumio volumio[932]: xcb_connection_has_error() returned true May 05 11:05:03 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions May 05 11:05:03 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 05 11:05:03 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 05 11:05:03 volumio volumio[932]: info: Getting Alsa Cards List without I2S DAC May 05 11:05:03 volumio volumio[932]: xcb_connection_has_error() returned true May 05 11:05:03 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber May 05 11:05:03 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 05 11:05:03 volumio volumio[932]: xcb_connection_has_error() returned true May 05 11:05:03 volumio volumio[932]: xcb_connection_has_error() returned true May 05 11:05:03 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 05 11:05:03 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 05 11:05:03 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 05 11:05:03 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 05 11:05:03 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 05 11:05:03 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 05 11:05:03 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 05 11:05:03 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode May 05 11:05:03 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 05 11:05:03 volumio volumio[932]: info: VolumeController:: Volume=0 Mute =true May 05 11:05:03 volumio volumio[932]: info: CoreStateMachine::pushState May 05 11:05:03 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 05 11:05:03 volumio volumio[932]: info: CoreCommandRouter::volumioPushState May 05 11:05:03 volumio volumio[932]: info: MRS: Pushing multiroomSync output update for this device May 05 11:05:03 volumio volumio[932]: info: MRS: Pushing multiroomSync output May 05 11:05:03 volumio volumio[932]: info: Starting Shairport Sync May 05 11:05:03 volumio sudo[1947]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 05 11:05:03 volumio sudo[1947]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 11:05:03 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 05 11:05:03 volumio systemd[1]: shairport-sync.service: Succeeded. May 05 11:05:03 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 05 11:05:03 volumio volumio[932]: ------------------------------------ BT MESSAGE: BT STATUS: running May 05 11:05:03 volumio volumio[932]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 05 11:05:03 volumio volumio[932]: info: Asound.conf file written May 05 11:05:03 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 05 11:05:03 volumio sudo[1951]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf May 05 11:05:03 volumio sudo[1951]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 11:05:03 volumio sudo[1947]: pam_unix(sudo:session): session closed for user root May 05 11:05:03 volumio sudo[1951]: pam_unix(sudo:session): session closed for user root May 05 11:05:03 volumio volumio[932]: xcb_connection_has_error() returned true May 05 11:05:03 volumio volumio[932]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:Max Overclock PLL:0' : Device or resource busy May 05 11:05:03 volumio volumio[932]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:Max Overclock DSP:0' : Device or resource busy May 05 11:05:03 volumio volumio[932]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:Max Overclock DAC:0' : Device or resource busy May 05 11:05:03 volumio volumio[932]: info: Output device has changed, restarting MPD May 05 11:05:03 volumio sudo[1959]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 05 11:05:03 volumio volumio[932]: info: Output device has changed, restarting Shairport Sync May 05 11:05:03 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 11:05:03 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 05 11:05:03 volumio sudo[1959]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 11:05:03 volumio sudo[1959]: pam_unix(sudo:session): session closed for user root May 05 11:05:03 volumio sudo[1961]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 05 11:05:03 volumio sudo[1961]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 11:05:03 volumio volumio[932]: xcb_connection_has_error() returned true May 05 11:05:03 volumio volumio[932]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 05 11:05:03 volumio volumio[932]: xcb_connection_has_error() returned true May 05 11:05:03 volumio volumio[932]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration May 05 11:05:03 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 11:05:03 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM May 05 11:05:03 volumio systemd[1]: mpd.service: Succeeded. May 05 11:05:03 volumio systemd[1]: Stopped Music Player Daemon. May 05 11:05:03 volumio sudo[1971]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 05 11:05:03 volumio kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 May 05 11:05:03 volumio volumio[932]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 05 11:05:03 volumio volumio[932]: ------------------------------------ BT MESSAGE: BT STATUS: running May 05 11:05:03 volumio volumio[932]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 05 11:05:03 volumio sudo[1971]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 11:05:03 volumio systemd[1]: Starting Music Player Daemon... May 05 11:05:03 volumio sudo[1975]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 05 11:05:03 volumio sudo[1975]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 11:05:03 volumio volumio[932]: info: MPD Permissions set May 05 11:05:03 volumio volumio[932]: info: Shairport-Sync Started May 05 11:05:03 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 11:05:03 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 11:05:03 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 11:05:03 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 11:05:03 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 11:05:03 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 11:05:03 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 11:05:03 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 11:05:03 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 05 11:05:03 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 11:05:04 volumio volumio[932]: ------------------------------------ BT MESSAGE: BT STATUS: running May 05 11:05:04 volumio sudo[1971]: pam_unix(sudo:session): session closed for user root May 05 11:05:04 volumio volumio[932]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 05 11:05:04 volumio sudo[1975]: pam_unix(sudo:session): session closed for user root May 05 11:05:04 volumio volumio[932]: info: Starting Shairport Sync May 05 11:05:04 volumio sudo[1985]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 05 11:05:04 volumio sudo[1985]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 11:05:04 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 05 11:05:04 volumio systemd[1]: shairport-sync.service: Succeeded. May 05 11:05:04 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 05 11:05:04 volumio volumio[932]: ------------------------------------ BT MESSAGE: BT STATUS: running May 05 11:05:04 volumio volumio[932]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 05 11:05:04 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 05 11:05:04 volumio sudo[1985]: pam_unix(sudo:session): session closed for user root May 05 11:05:04 volumio volumio[932]: info: Shairport-Sync Started May 05 11:05:05 volumio mpd[1968]: May 05 11:05 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 05 11:05:05 volumio systemd[1]: Started Music Player Daemon. May 05 11:05:05 volumio sudo[1961]: pam_unix(sudo:session): session closed for user root May 05 11:05:05 volumio sudo[1900]: pam_unix(sudo:session): session closed for user root May 05 11:05:05 volumio volumio[932]: error: MPD error: The expression evaluated to a falsy value: May 05 11:05:05 volumio volumio[932]: assert.ok(self.idling) May 05 11:05:05 volumio volumio[932]: error: The expression evaluated to a falsy value: May 05 11:05:05 volumio volumio[932]: assert.ok(self.idling) May 05 11:05:05 volumio volumio[932]: error: updateQueue error: null May 05 11:05:05 volumio volumio[932]: info: TidalConnect service stoped! May 05 11:05:05 volumio volumio[932]: info: TidalConnect service stoped! May 05 11:05:06 volumio volumio[932]: info: TidalConnect service stoped! May 05 11:05:06 volumio volumio[932]: info: TidalConnect service stoped! May 05 11:05:06 volumio volumio[932]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect May 05 11:05:06 volumio volumio[932]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect May 05 11:05:06 volumio volumio[932]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect May 05 11:05:06 volumio volumio[932]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect May 05 11:05:06 volumio sudo[2010]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service May 05 11:05:06 volumio sudo[2010]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 11:05:06 volumio sudo[2013]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service May 05 11:05:06 volumio sudo[2013]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 11:05:06 volumio systemd[1]: Started Volumio Tidal Connect Service. May 05 11:05:06 volumio sudo[2010]: pam_unix(sudo:session): session closed for user root May 05 11:05:06 volumio sudo[2013]: pam_unix(sudo:session): session closed for user root May 05 11:05:06 volumio volumio[932]: info: Executing endpoint tc_getconfig May 05 11:05:06 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig May 05 11:05:06 volumio vtcs[2015]: STARTING TidalConnect services, version: 1.4.0.34 May 05 11:05:06 volumio vtcs[2015]: STARTED TidalConnect services. May 05 11:05:06 volumio volumio[932]: info: Executing endpoint tc_connect May 05 11:05:06 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect May 05 11:05:06 volumio volumio[932]: info: Connecting to TidalConnect May 05 11:05:06 volumio volumio[932]: info: CoreCommandRouter::servicePushState May 05 11:05:06 volumio volumio[932]: info: CoreStateMachine::pushState May 05 11:05:06 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 05 11:05:06 volumio volumio[932]: info: CoreCommandRouter::volumioPushState May 05 11:05:06 volumio volumio[932]: info: MRS: Pushing multiroomSync output update for this device May 05 11:05:06 volumio volumio[932]: info: MRS: Pushing multiroomSync output May 05 11:05:06 volumio volumio[932]: info: CorePlayQueue::getTrack 0 May 05 11:05:06 volumio volumio[932]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current mpd Received tidalconnect May 05 11:05:06 volumio volumio[932]: info: CoreCommandRouter::servicePushState May 05 11:05:06 volumio volumio[932]: info: CoreStateMachine::pushState May 05 11:05:06 volumio volumio[932]: info: CoreCommandRouter::volumioPushState May 05 11:05:06 volumio volumio[932]: info: MRS: Pushing multiroomSync output update for this device May 05 11:05:06 volumio volumio[932]: info: MRS: Pushing multiroomSync output May 05 11:05:06 volumio volumio[932]: info: CorePlayQueue::getTrack 0 May 05 11:05:06 volumio volumio[932]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current mpd Received tidalconnect May 05 11:05:07 volumio volumio[932]: info: TidalConnect service stoped! May 05 11:05:07 volumio volumio[932]: info: TidalConnect service stoped! May 05 11:05:07 volumio volumio[932]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect May 05 11:05:07 volumio volumio[932]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect May 05 11:05:07 volumio sudo[2044]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service May 05 11:05:07 volumio sudo[2044]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 11:05:07 volumio sudo[2044]: pam_unix(sudo:session): session closed for user root May 05 11:05:07 volumio volumio[932]: info: VolumeController::SetAlsaVolume100 May 05 11:05:07 volumio volumio[932]: info: CoreStateMachine::pushState May 05 11:05:07 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 05 11:05:07 volumio volumio[932]: info: CoreCommandRouter::volumioPushState May 05 11:05:07 volumio volumio[932]: info: MRS: Pushing multiroomSync output update for this device May 05 11:05:07 volumio volumio[932]: info: MRS: Pushing multiroomSync output May 05 11:05:09 volumio volumio[932]: info: TidalConnect service started! May 05 11:05:09 volumio volumio[932]: info: TidalConnect service started! May 05 11:05:10 volumio volumio[932]: info: TidalConnect service started! May 05 11:05:21 volumio volumio[932]: info: CALLMETHOD: audio_interface alsa_controller saveVolumeOptions [object Object] May 05 11:05:21 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveVolumeOptions May 05 11:05:21 volumio volumio[932]: info: CoreCommandRouter::volumioGetState May 05 11:05:21 volumio volumio[932]: info: Restoring Previous Volume level: 100 false false May 05 11:05:21 volumio volumio[932]: info: Volume configurations have been set May 05 11:05:21 volumio vtcs[2015]: [2024-05-05 11:05:21.136] [tisoc] [error] [SpkconServer.cpp:376] recv error. socket disconnected May 05 11:05:21 volumio vtcs[2015]: [2024-05-05 11:05:21.136] [tisoc] [warning] [SessionManagerImpl.cpp:243] Illegal State: IDLE May 05 11:05:21 volumio sudo[2050]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 05 11:05:21 volumio sudo[2050]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 11:05:21 volumio systemd[1]: Stopping Volumio Tidal Connect Service... May 05 11:05:21 volumio systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM May 05 11:05:21 volumio systemd[1]: vtcs.service: Succeeded. May 05 11:05:21 volumio systemd[1]: Stopped Volumio Tidal Connect Service. May 05 11:05:21 volumio sudo[2050]: pam_unix(sudo:session): session closed for user root May 05 11:05:21 volumio volumio[932]: xcb_connection_has_error() returned true May 05 11:05:21 volumio volumio[932]: info: CoreCommandRouter::volumioUpdateVolumeSettings May 05 11:05:21 volumio volumio[932]: info: Updating Volume Controller Parameters: Device: 2 Name: HiFiBerry DAC Plus Mixer: Max Overclock PLL Max Vol: 100 Vol Curve; linear Vol Steps: 5 May 05 11:05:21 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume May 05 11:05:21 volumio volumio[932]: info: Disabling external Volume Control May 05 11:05:21 volumio volumio[932]: info: CoreCommandRouter::getUIConfigOnPlugin May 05 11:05:21 volumio volumio[932]: ------------------------------------ BT MESSAGE: BT STATUS: running May 05 11:05:21 volumio sudo[2057]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 05 11:05:21 volumio sudo[2057]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 11:05:21 volumio volumio[932]: Unable to parse: May 05 11:05:21 volumio volumio[932]: Simple mixer control 'Max Overclock PLL',0 May 05 11:05:21 volumio volumio[932]: Capabilities: volume volume-joined May 05 11:05:21 volumio volumio[932]: Playback channels: Mono May 05 11:05:21 volumio volumio[932]: Capture channels: Mono May 05 11:05:21 volumio volumio[932]: Limits: 0 - 20 May 05 11:05:21 volumio volumio[932]: Mono: 0 [0%] May 05 11:05:21 volumio volumio[932]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output May 05 11:05:21 volumio sudo[2057]: pam_unix(sudo:session): session closed for user root May 05 11:05:21 volumio volumio[932]: xcb_connection_has_error() returned true May 05 11:05:21 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions May 05 11:05:21 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 05 11:05:21 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 05 11:05:21 volumio volumio[932]: info: Getting Alsa Cards List without I2S DAC May 05 11:05:21 volumio volumio[932]: xcb_connection_has_error() returned true May 05 11:05:21 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber May 05 11:05:21 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 05 11:05:21 volumio volumio[932]: xcb_connection_has_error() returned true May 05 11:05:21 volumio volumio[932]: xcb_connection_has_error() returned true May 05 11:05:21 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 05 11:05:21 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 05 11:05:21 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 05 11:05:21 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 05 11:05:21 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 05 11:05:21 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 05 11:05:21 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 05 11:05:21 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode May 05 11:05:21 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 05 11:05:21 volumio volumio[932]: Unable to parse: May 05 11:05:21 volumio volumio[932]: Simple mixer control 'Max Overclock PLL',0 May 05 11:05:21 volumio volumio[932]: Capabilities: volume volume-joined May 05 11:05:21 volumio volumio[932]: Playback channels: Mono May 05 11:05:21 volumio volumio[932]: Capture channels: Mono May 05 11:05:21 volumio volumio[932]: Limits: 0 - 20 May 05 11:05:21 volumio volumio[932]: Mono: 0 [0%] May 05 11:05:21 volumio volumio[932]: info: VolumeController:: Volume=undefined Mute =false May 05 11:05:21 volumio volumio[932]: info: CoreStateMachine::pushState May 05 11:05:21 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 05 11:05:21 volumio volumio[932]: info: CoreCommandRouter::volumioPushState May 05 11:05:21 volumio volumio[932]: info: MRS: Pushing multiroomSync output update for this device May 05 11:05:21 volumio volumio[932]: info: MRS: Pushing multiroomSync output May 05 11:05:24 volumio volumio[932]: info: TidalConnect service stoped! May 05 11:05:24 volumio volumio[932]: info: TidalConnect service stoped! May 05 11:05:24 volumio volumio[932]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect May 05 11:05:24 volumio volumio[932]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect May 05 11:05:24 volumio sudo[2078]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service May 05 11:05:24 volumio sudo[2078]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 11:05:24 volumio systemd[1]: Started Volumio Tidal Connect Service. May 05 11:05:24 volumio sudo[2078]: pam_unix(sudo:session): session closed for user root May 05 11:05:24 volumio volumio[932]: info: Executing endpoint tc_getconfig May 05 11:05:24 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig May 05 11:05:24 volumio vtcs[2080]: STARTING TidalConnect services, version: 1.4.0.34 May 05 11:05:24 volumio vtcs[2080]: STARTED TidalConnect services. May 05 11:05:24 volumio volumio[932]: info: Executing endpoint tc_connect May 05 11:05:24 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect May 05 11:05:24 volumio volumio[932]: info: Connecting to TidalConnect May 05 11:05:24 volumio volumio[932]: info: CoreCommandRouter::servicePushState May 05 11:05:24 volumio volumio[932]: info: CoreStateMachine::pushState May 05 11:05:24 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 05 11:05:24 volumio volumio[932]: info: CoreCommandRouter::volumioPushState May 05 11:05:24 volumio volumio[932]: info: MRS: Pushing multiroomSync output update for this device May 05 11:05:24 volumio volumio[932]: info: MRS: Pushing multiroomSync output May 05 11:05:24 volumio volumio[932]: info: CorePlayQueue::getTrack 0 May 05 11:05:24 volumio volumio[932]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current mpd Received tidalconnect May 05 11:05:24 volumio volumio[932]: info: CoreCommandRouter::servicePushState May 05 11:05:24 volumio volumio[932]: info: CoreStateMachine::pushState May 05 11:05:24 volumio volumio[932]: info: CoreCommandRouter::volumioPushState May 05 11:05:24 volumio volumio[932]: info: MRS: Pushing multiroomSync output update for this device May 05 11:05:24 volumio volumio[932]: info: MRS: Pushing multiroomSync output May 05 11:05:24 volumio volumio[932]: info: CorePlayQueue::getTrack 0 May 05 11:05:24 volumio volumio[932]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current mpd Received tidalconnect May 05 11:05:25 volumio volumio[932]: info: VolumeController::SetAlsaVolume100 May 05 11:05:25 volumio volumio[932]: info: CoreStateMachine::pushState May 05 11:05:25 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 05 11:05:25 volumio volumio[932]: info: CoreCommandRouter::volumioPushState May 05 11:05:25 volumio volumio[932]: info: MRS: Pushing multiroomSync output update for this device May 05 11:05:25 volumio volumio[932]: info: MRS: Pushing multiroomSync output May 05 11:05:27 volumio volumio[932]: info: CoreCommandRouter::volumioStop May 05 11:05:27 volumio volumio[932]: info: CoreStateMachine::stop May 05 11:05:27 volumio volumio[932]: info: CoreStateMachine::setConsumeUpdateService undefined May 05 11:05:27 volumio volumio[932]: info: CoreStateMachine::stPlaybackTimer May 05 11:05:27 volumio volumio[932]: info: CoreStateMachine::updateTrackBlock May 05 11:05:27 volumio volumio[932]: info: CorePlayQueue::getTrackBlock May 05 11:05:27 volumio volumio[932]: info: CoreStateMachine::pushState May 05 11:05:27 volumio volumio[932]: info: CorePlayQueue::getTrack 0 May 05 11:05:27 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 05 11:05:27 volumio volumio[932]: info: CoreCommandRouter::volumioPushState May 05 11:05:27 volumio volumio[932]: info: MRS: Pushing multiroomSync output update for this device May 05 11:05:27 volumio volumio[932]: info: MRS: Pushing multiroomSync output May 05 11:05:27 volumio volumio[932]: info: CoreStateMachine::serviceStop May 05 11:05:27 volumio volumio[932]: info: CorePlayQueue::getTrack 0 May 05 11:05:27 volumio volumio[932]: info: CoreCommandRouter::serviceStop May 05 11:05:27 volumio volumio[932]: info: [1714899927216] ControllerWebradio::stop May 05 11:05:27 volumio volumio[932]: verbose: ControllerMpd::sendMpdCommand stop May 05 11:05:27 volumio volumio[932]: info: sendMpdCommand stop took 20 milliseconds May 05 11:05:27 volumio volumio[932]: info: TidalConnect service started! May 05 11:05:28 volumio volumio[932]: info: CoreCommandRouter::volumioPlay May 05 11:05:28 volumio volumio[932]: info: CoreStateMachine::play index undefined May 05 11:05:28 volumio volumio[932]: info: CoreStateMachine::setConsumeUpdateService undefined May 05 11:05:28 volumio volumio[932]: info: CorePlayQueue::getTrack 0 May 05 11:05:28 volumio volumio[932]: info: CoreStateMachine::startPlaybackTimer May 05 11:05:28 volumio volumio[932]: info: CorePlayQueue::getTrack 0 May 05 11:05:28 volumio volumio[932]: info: CoreCommandRouter::volumioGetVisibleSources May 05 11:05:28 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 05 11:05:28 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject May 05 11:05:28 volumio volumio[932]: info: [1714899928109] ControllerWebradio::clearAddPlayTrack May 05 11:05:28 volumio volumio[932]: verbose: ControllerMpd::sendMpdCommand stop May 05 11:05:28 volumio volumio[932]: info: sendMpdCommand stop took 1 milliseconds May 05 11:05:28 volumio volumio[932]: verbose: ControllerMpd::sendMpdCommand clear May 05 11:05:28 volumio volumio[932]: info: May 05 11:05:28 volumio volumio[932]: ---------------------------- MPD announces system playlist update May 05 11:05:28 volumio volumio[932]: info: Ignoring MPD Status Update May 05 11:05:28 volumio volumio[932]: info: sendMpdCommand clear took 2 milliseconds May 05 11:05:28 volumio volumio[932]: verbose: ControllerMpd::sendMpdCommand load "http://listen.jazz88.org/ksds.mp3" May 05 11:05:28 volumio volumio[932]: info: May 05 11:05:28 volumio volumio[932]: ---------------------------- MPD announces system playlist update May 05 11:05:28 volumio volumio[932]: info: Ignoring MPD Status Update May 05 11:05:28 volumio volumio[932]: error: updateQueue error: null May 05 11:05:28 volumio volumio[932]: info: ------------------------------ 5ms May 05 11:05:29 volumio volumio[932]: error: updateQueue error: null May 05 11:05:29 volumio volumio[932]: verbose: ControllerMpd::sendMpdCommand add "http://listen.jazz88.org/ksds.mp3" May 05 11:05:29 volumio volumio[932]: info: ------------------------------ 908ms May 05 11:05:29 volumio volumio[932]: info: May 05 11:05:29 volumio volumio[932]: ---------------------------- MPD announces system playlist update May 05 11:05:29 volumio volumio[932]: info: Ignoring MPD Status Update May 05 11:05:29 volumio volumio[932]: info: sendMpdCommand add "http://listen.jazz88.org/ksds.mp3" took 3 milliseconds May 05 11:05:29 volumio volumio[932]: info: CoreStateMachine::setConsumeUpdateService mpd May 05 11:05:29 volumio volumio[932]: verbose: ControllerMpd::sendMpdCommand play May 05 11:05:29 volumio volumio[932]: info: May 05 11:05:29 volumio volumio[932]: ---------------------------- MPD announces system playlist update May 05 11:05:29 volumio volumio[932]: info: Ignoring MPD Status Update May 05 11:05:29 volumio volumio[932]: info: ------------------------------ 6ms May 05 11:05:29 volumio volumio[932]: info: sendMpdCommand play took 4 milliseconds May 05 11:05:29 volumio volumio[932]: info: ------------------------------ 3ms May 05 11:05:29 volumio volumio[932]: info: Executing endpoint metavolumio May 05 11:05:29 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 05 11:05:29 volumio volumio[932]: info: May 05 11:05:29 volumio volumio[932]: ---------------------------- MPD announces state update: player May 05 11:05:29 volumio volumio[932]: info: ControllerMpd::getState May 05 11:05:29 volumio volumio[932]: verbose: ControllerMpd::sendMpdCommand status May 05 11:05:29 volumio volumio[932]: info: May 05 11:05:29 volumio volumio[932]: ---------------------------- MPD announces state update: player May 05 11:05:29 volumio volumio[932]: info: ControllerMpd::getState May 05 11:05:29 volumio volumio[932]: verbose: ControllerMpd::sendMpdCommand status May 05 11:05:29 volumio kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 May 05 11:05:29 volumio volumio[932]: info: sendMpdCommand status took 26 milliseconds May 05 11:05:29 volumio volumio[932]: verbose: ControllerMpd::parseState May 05 11:05:29 volumio volumio[932]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 05 11:05:29 volumio volumio[932]: info: May 05 11:05:29 volumio volumio[932]: ---------------------------- MPD announces state update: player May 05 11:05:29 volumio volumio[932]: info: ControllerMpd::getState May 05 11:05:29 volumio volumio[932]: verbose: ControllerMpd::sendMpdCommand status May 05 11:05:29 volumio volumio[932]: info: sendMpdCommand status took 26 milliseconds May 05 11:05:29 volumio volumio[932]: info: sendMpdCommand playlistinfo took 3 milliseconds May 05 11:05:29 volumio volumio[932]: verbose: ControllerMpd::parseState May 05 11:05:29 volumio volumio[932]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 05 11:05:29 volumio volumio[932]: verbose: ControllerMpd::parseTrackInfo May 05 11:05:29 volumio volumio[932]: info: ControllerMpd::pushState May 05 11:05:29 volumio volumio[932]: info: CoreCommandRouter::servicePushState May 05 11:05:29 volumio volumio[932]: info: CorePlayQueue::getTrack 0 May 05 11:05:29 volumio volumio[932]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"ksds.mp3","artist":null,"album":null,"uri":"http://listen.jazz88.org/ksds.mp3","trackType":"mp3"} May 05 11:05:29 volumio volumio[932]: verbose: CURRENT POSITION 0 May 05 11:05:29 volumio volumio[932]: info: CoreStateMachine::syncState stateService play May 05 11:05:29 volumio volumio[932]: info: CoreStateMachine::syncState currentStatus stop May 05 11:05:29 volumio volumio[932]: info: ------------------------------ 35ms May 05 11:05:29 volumio volumio[932]: info: May 05 11:05:29 volumio volumio[932]: ---------------------------- MPD announces state update: player May 05 11:05:29 volumio volumio[932]: info: ControllerMpd::getState May 05 11:05:29 volumio volumio[932]: verbose: ControllerMpd::sendMpdCommand status May 05 11:05:29 volumio volumio[932]: info: sendMpdCommand status took 7 milliseconds May 05 11:05:29 volumio volumio[932]: info: sendMpdCommand playlistinfo took 5 milliseconds May 05 11:05:29 volumio volumio[932]: info: sendMpdCommand status took 2 milliseconds May 05 11:05:29 volumio volumio[932]: verbose: ControllerMpd::parseState May 05 11:05:29 volumio volumio[932]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 05 11:05:29 volumio volumio[932]: verbose: ControllerMpd::parseTrackInfo May 05 11:05:29 volumio volumio[932]: verbose: ControllerMpd::parseState May 05 11:05:29 volumio volumio[932]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 05 11:05:29 volumio volumio[932]: info: ControllerMpd::pushState May 05 11:05:29 volumio volumio[932]: info: CoreCommandRouter::servicePushState May 05 11:05:29 volumio volumio[932]: info: CorePlayQueue::getTrack 0 May 05 11:05:29 volumio volumio[932]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"ksds.mp3","artist":null,"album":null,"uri":"http://listen.jazz88.org/ksds.mp3","trackType":"mp3"} May 05 11:05:29 volumio volumio[932]: verbose: CURRENT POSITION 0 May 05 11:05:29 volumio volumio[932]: info: CoreStateMachine::syncState stateService play May 05 11:05:29 volumio volumio[932]: info: CoreStateMachine::syncState currentStatus play May 05 11:05:29 volumio volumio[932]: info: Received an update from plugin. extracting info from payload May 05 11:05:29 volumio volumio[932]: info: CoreStateMachine::pushState May 05 11:05:29 volumio volumio[932]: info: CorePlayQueue::getTrack 0 May 05 11:05:29 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 05 11:05:29 volumio volumio[932]: info: CoreCommandRouter::volumioPushState May 05 11:05:29 volumio volumio[932]: info: MRS: Pushing multiroomSync output update for this device May 05 11:05:29 volumio volumio[932]: info: MRS: Pushing multiroomSync output May 05 11:05:29 volumio volumio[932]: info: CoreStateMachine::pushState May 05 11:05:29 volumio volumio[932]: info: CoreCommandRouter::volumioPushState May 05 11:05:29 volumio volumio[932]: info: MRS: Pushing multiroomSync output update for this device May 05 11:05:29 volumio volumio[932]: info: MRS: Pushing multiroomSync output May 05 11:05:29 volumio volumio[932]: info: ------------------------------ 80ms May 05 11:05:29 volumio volumio[932]: info: sendMpdCommand playlistinfo took 44 milliseconds May 05 11:05:29 volumio volumio[932]: info: sendMpdCommand playlistinfo took 44 milliseconds May 05 11:05:29 volumio volumio[932]: verbose: ControllerMpd::parseTrackInfo May 05 11:05:29 volumio volumio[932]: verbose: ControllerMpd::parseTrackInfo May 05 11:05:29 volumio volumio[932]: info: ControllerMpd::pushState May 05 11:05:29 volumio volumio[932]: info: CoreCommandRouter::servicePushState May 05 11:05:29 volumio volumio[932]: info: CorePlayQueue::getTrack 0 May 05 11:05:29 volumio volumio[932]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"ksds.mp3","artist":null,"album":null,"uri":"http://listen.jazz88.org/ksds.mp3","trackType":"mp3"} May 05 11:05:29 volumio volumio[932]: verbose: CURRENT POSITION 0 May 05 11:05:29 volumio volumio[932]: info: CoreStateMachine::syncState stateService play May 05 11:05:29 volumio volumio[932]: info: CoreStateMachine::syncState currentStatus play May 05 11:05:29 volumio volumio[932]: info: Received an update from plugin. extracting info from payload May 05 11:05:29 volumio volumio[932]: info: CoreStateMachine::pushState May 05 11:05:29 volumio volumio[932]: info: CorePlayQueue::getTrack 0 May 05 11:05:29 volumio volumio[932]: info: CoreCommandRouter::volumioPushState May 05 11:05:29 volumio volumio[932]: info: MRS: Pushing multiroomSync output update for this device May 05 11:05:29 volumio volumio[932]: info: MRS: Pushing multiroomSync output May 05 11:05:29 volumio volumio[932]: info: CoreStateMachine::pushState May 05 11:05:29 volumio volumio[932]: info: CoreCommandRouter::volumioPushState May 05 11:05:29 volumio volumio[932]: info: MRS: Pushing multiroomSync output update for this device May 05 11:05:29 volumio volumio[932]: info: MRS: Pushing multiroomSync output May 05 11:05:29 volumio volumio[932]: info: ControllerMpd::pushState May 05 11:05:29 volumio volumio[932]: info: CoreCommandRouter::servicePushState May 05 11:05:29 volumio volumio[932]: info: CorePlayQueue::getTrack 0 May 05 11:05:29 volumio volumio[932]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"ksds.mp3","artist":null,"album":null,"uri":"http://listen.jazz88.org/ksds.mp3","trackType":"mp3"} May 05 11:05:29 volumio volumio[932]: verbose: CURRENT POSITION 0 May 05 11:05:29 volumio volumio[932]: info: CoreStateMachine::syncState stateService play May 05 11:05:29 volumio volumio[932]: info: CoreStateMachine::syncState currentStatus play May 05 11:05:29 volumio volumio[932]: info: Received an update from plugin. extracting info from payload May 05 11:05:29 volumio volumio[932]: info: CoreStateMachine::pushState May 05 11:05:29 volumio volumio[932]: info: CorePlayQueue::getTrack 0 May 05 11:05:29 volumio volumio[932]: info: CoreCommandRouter::volumioPushState May 05 11:05:29 volumio volumio[932]: info: MRS: Pushing multiroomSync output update for this device May 05 11:05:29 volumio volumio[932]: info: MRS: Pushing multiroomSync output May 05 11:05:29 volumio volumio[932]: info: CoreStateMachine::pushState May 05 11:05:29 volumio volumio[932]: info: CoreCommandRouter::volumioPushState May 05 11:05:29 volumio volumio[932]: info: MRS: Pushing multiroomSync output update for this device May 05 11:05:29 volumio volumio[932]: info: MRS: Pushing multiroomSync output May 05 11:05:29 volumio volumio[932]: info: ------------------------------ 109ms May 05 11:05:29 volumio volumio[932]: info: ------------------------------ 104ms May 05 11:05:29 volumio volumio[932]: info: May 05 11:05:29 volumio volumio[932]: ---------------------------- MPD announces system playlist update May 05 11:05:29 volumio volumio[932]: info: Ignoring MPD Status Update May 05 11:05:29 volumio volumio[932]: info: May 05 11:05:29 volumio volumio[932]: ---------------------------- MPD announces state update: player May 05 11:05:29 volumio volumio[932]: info: ControllerMpd::getState May 05 11:05:29 volumio volumio[932]: verbose: ControllerMpd::sendMpdCommand status May 05 11:05:29 volumio volumio[932]: info: May 05 11:05:29 volumio volumio[932]: ---------------------------- MPD announces system playlist update May 05 11:05:29 volumio volumio[932]: info: Ignoring MPD Status Update May 05 11:05:29 volumio volumio[932]: info: May 05 11:05:29 volumio volumio[932]: ---------------------------- MPD announces state update: player May 05 11:05:29 volumio volumio[932]: info: ControllerMpd::getState May 05 11:05:29 volumio volumio[932]: verbose: ControllerMpd::sendMpdCommand status May 05 11:05:29 volumio volumio[932]: info: May 05 11:05:29 volumio volumio[932]: ---------------------------- MPD announces system playlist update May 05 11:05:29 volumio volumio[932]: info: Ignoring MPD Status Update May 05 11:05:29 volumio volumio[932]: info: May 05 11:05:29 volumio volumio[932]: ---------------------------- MPD announces state update: player May 05 11:05:29 volumio volumio[932]: info: ControllerMpd::getState May 05 11:05:29 volumio volumio[932]: verbose: ControllerMpd::sendMpdCommand status May 05 11:05:29 volumio volumio[932]: info: ------------------------------ 28ms May 05 11:05:29 volumio volumio[932]: info: sendMpdCommand status took 27 milliseconds May 05 11:05:29 volumio volumio[932]: info: ------------------------------ 6ms May 05 11:05:29 volumio volumio[932]: info: sendMpdCommand status took 4 milliseconds May 05 11:05:29 volumio volumio[932]: verbose: ControllerMpd::parseState May 05 11:05:29 volumio volumio[932]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 05 11:05:29 volumio volumio[932]: verbose: ControllerMpd::parseState May 05 11:05:29 volumio volumio[932]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 05 11:05:29 volumio volumio[932]: info: May 05 11:05:29 volumio volumio[932]: ---------------------------- MPD announces system playlist update May 05 11:05:29 volumio volumio[932]: info: Ignoring MPD Status Update May 05 11:05:29 volumio volumio[932]: info: May 05 11:05:29 volumio volumio[932]: ---------------------------- MPD announces state update: player May 05 11:05:29 volumio volumio[932]: info: ControllerMpd::getState May 05 11:05:29 volumio volumio[932]: verbose: ControllerMpd::sendMpdCommand status May 05 11:05:29 volumio volumio[932]: info: ------------------------------ 18ms May 05 11:05:29 volumio volumio[932]: info: sendMpdCommand status took 16 milliseconds May 05 11:05:29 volumio volumio[932]: info: sendMpdCommand playlistinfo took 15 milliseconds May 05 11:05:29 volumio volumio[932]: info: sendMpdCommand playlistinfo took 14 milliseconds May 05 11:05:29 volumio volumio[932]: info: ------------------------------ 13ms May 05 11:05:29 volumio volumio[932]: info: sendMpdCommand status took 11 milliseconds May 05 11:05:29 volumio volumio[932]: verbose: ControllerMpd::parseState May 05 11:05:29 volumio volumio[932]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 05 11:05:29 volumio volumio[932]: verbose: ControllerMpd::parseTrackInfo May 05 11:05:29 volumio volumio[932]: verbose: ControllerMpd::parseState May 05 11:05:29 volumio volumio[932]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 05 11:05:29 volumio volumio[932]: info: ControllerMpd::pushState May 05 11:05:29 volumio volumio[932]: info: CoreCommandRouter::servicePushState May 05 11:05:29 volumio volumio[932]: info: CorePlayQueue::getTrack 0 May 05 11:05:29 volumio volumio[932]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1105,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"ksds.mp3","artist":"no name","album":null,"uri":"http://listen.jazz88.org/ksds.mp3","trackType":"mp3"} May 05 11:05:29 volumio volumio[932]: verbose: CURRENT POSITION 0 May 05 11:05:29 volumio volumio[932]: info: CoreStateMachine::syncState stateService play May 05 11:05:29 volumio volumio[932]: info: CoreStateMachine::syncState currentStatus play May 05 11:05:29 volumio volumio[932]: info: Received an update from plugin. extracting info from payload May 05 11:05:29 volumio volumio[932]: info: CoreStateMachine::pushState May 05 11:05:29 volumio volumio[932]: info: CorePlayQueue::getTrack 0 May 05 11:05:29 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 05 11:05:29 volumio volumio[932]: info: CoreCommandRouter::volumioPushState May 05 11:05:29 volumio volumio[932]: info: MRS: Pushing multiroomSync output update for this device May 05 11:05:29 volumio volumio[932]: info: MRS: Pushing multiroomSync output May 05 11:05:29 volumio volumio[932]: info: CoreStateMachine::pushState May 05 11:05:29 volumio volumio[932]: info: CoreCommandRouter::volumioPushState May 05 11:05:29 volumio volumio[932]: info: MRS: Pushing multiroomSync output update for this device May 05 11:05:29 volumio volumio[932]: info: MRS: Pushing multiroomSync output May 05 11:05:29 volumio volumio[932]: info: ControllerMpd::pushState May 05 11:05:29 volumio volumio[932]: info: CoreCommandRouter::servicePushState May 05 11:05:29 volumio volumio[932]: info: CorePlayQueue::getTrack 0 May 05 11:05:29 volumio volumio[932]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1105,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"ksds.mp3","artist":"no name","album":null,"uri":"http://listen.jazz88.org/ksds.mp3","trackType":"mp3"} May 05 11:05:29 volumio volumio[932]: verbose: CURRENT POSITION 0 May 05 11:05:29 volumio volumio[932]: info: CoreStateMachine::syncState stateService play May 05 11:05:29 volumio volumio[932]: info: CoreStateMachine::syncState currentStatus play May 05 11:05:29 volumio volumio[932]: info: Received an update from plugin. extracting info from payload May 05 11:05:29 volumio volumio[932]: info: CoreStateMachine::pushState May 05 11:05:29 volumio volumio[932]: info: CorePlayQueue::getTrack 0 May 05 11:05:29 volumio volumio[932]: info: CoreCommandRouter::volumioPushState May 05 11:05:29 volumio volumio[932]: info: MRS: Pushing multiroomSync output update for this device May 05 11:05:29 volumio volumio[932]: info: MRS: Pushing multiroomSync output May 05 11:05:29 volumio volumio[932]: info: CoreStateMachine::pushState May 05 11:05:29 volumio volumio[932]: info: CoreCommandRouter::volumioPushState May 05 11:05:29 volumio volumio[932]: info: MRS: Pushing multiroomSync output update for this device May 05 11:05:29 volumio volumio[932]: info: MRS: Pushing multiroomSync output May 05 11:05:29 volumio volumio[932]: info: ------------------------------ 114ms May 05 11:05:29 volumio volumio[932]: info: ------------------------------ 93ms May 05 11:05:29 volumio volumio[932]: info: sendMpdCommand playlistinfo took 121 milliseconds May 05 11:05:29 volumio volumio[932]: info: sendMpdCommand playlistinfo took 117 milliseconds May 05 11:05:29 volumio volumio[932]: verbose: ControllerMpd::parseTrackInfo May 05 11:05:29 volumio volumio[932]: verbose: ControllerMpd::parseTrackInfo May 05 11:05:29 volumio volumio[932]: info: ControllerMpd::pushState May 05 11:05:29 volumio volumio[932]: info: CoreCommandRouter::servicePushState May 05 11:05:29 volumio volumio[932]: info: CorePlayQueue::getTrack 0 May 05 11:05:29 volumio volumio[932]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1105,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"ksds.mp3","artist":"no name","album":null,"uri":"http://listen.jazz88.org/ksds.mp3","trackType":"mp3"} May 05 11:05:29 volumio volumio[932]: verbose: CURRENT POSITION 0 May 05 11:05:29 volumio volumio[932]: info: CoreStateMachine::syncState stateService play May 05 11:05:29 volumio volumio[932]: info: CoreStateMachine::syncState currentStatus play May 05 11:05:29 volumio volumio[932]: info: Received an update from plugin. extracting info from payload May 05 11:05:29 volumio volumio[932]: info: CoreStateMachine::pushState May 05 11:05:29 volumio volumio[932]: info: CorePlayQueue::getTrack 0 May 05 11:05:29 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 05 11:05:29 volumio volumio[932]: info: CoreCommandRouter::volumioPushState May 05 11:05:29 volumio volumio[932]: info: MRS: Pushing multiroomSync output update for this device May 05 11:05:29 volumio volumio[932]: info: MRS: Pushing multiroomSync output May 05 11:05:29 volumio volumio[932]: info: CoreStateMachine::pushState May 05 11:05:29 volumio volumio[932]: info: CoreCommandRouter::volumioPushState May 05 11:05:29 volumio volumio[932]: info: MRS: Pushing multiroomSync output update for this device May 05 11:05:29 volumio volumio[932]: info: MRS: Pushing multiroomSync output May 05 11:05:29 volumio volumio[932]: info: ControllerMpd::pushState May 05 11:05:29 volumio volumio[932]: info: CoreCommandRouter::servicePushState May 05 11:05:29 volumio volumio[932]: info: CorePlayQueue::getTrack 0 May 05 11:05:29 volumio volumio[932]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1105,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"ksds.mp3","artist":"no name","album":null,"uri":"http://listen.jazz88.org/ksds.mp3","trackType":"mp3"} May 05 11:05:29 volumio volumio[932]: verbose: CURRENT POSITION 0 May 05 11:05:29 volumio volumio[932]: info: CoreStateMachine::syncState stateService play May 05 11:05:29 volumio volumio[932]: info: CoreStateMachine::syncState currentStatus play May 05 11:05:29 volumio volumio[932]: info: Received an update from plugin. extracting info from payload May 05 11:05:29 volumio volumio[932]: info: CoreStateMachine::pushState May 05 11:05:29 volumio volumio[932]: info: CorePlayQueue::getTrack 0 May 05 11:05:29 volumio volumio[932]: info: CoreCommandRouter::volumioPushState May 05 11:05:29 volumio volumio[932]: info: MRS: Pushing multiroomSync output update for this device May 05 11:05:29 volumio volumio[932]: info: MRS: Pushing multiroomSync output May 05 11:05:29 volumio volumio[932]: info: CoreStateMachine::pushState May 05 11:05:29 volumio volumio[932]: info: CoreCommandRouter::volumioPushState May 05 11:05:29 volumio volumio[932]: info: MRS: Pushing multiroomSync output update for this device May 05 11:05:29 volumio volumio[932]: info: MRS: Pushing multiroomSync output May 05 11:05:29 volumio volumio[932]: info: ------------------------------ 189ms May 05 11:05:29 volumio volumio[932]: info: ------------------------------ 184ms May 05 11:05:29 volumio volumio[932]: info: CoreCommandRouter::volumioStop May 05 11:05:29 volumio volumio[932]: info: CoreStateMachine::stop May 05 11:05:29 volumio volumio[932]: info: CoreStateMachine::setConsumeUpdateService undefined May 05 11:05:29 volumio volumio[932]: info: CoreStateMachine::stPlaybackTimer May 05 11:05:29 volumio volumio[932]: info: CoreStateMachine::updateTrackBlock May 05 11:05:29 volumio volumio[932]: info: CorePlayQueue::getTrackBlock May 05 11:05:29 volumio volumio[932]: info: CoreStateMachine::pushState May 05 11:05:29 volumio volumio[932]: info: CorePlayQueue::getTrack 0 May 05 11:05:29 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 05 11:05:29 volumio volumio[932]: info: CoreCommandRouter::volumioPushState May 05 11:05:29 volumio volumio[932]: info: MRS: Pushing multiroomSync output update for this device May 05 11:05:29 volumio volumio[932]: info: MRS: Pushing multiroomSync output May 05 11:05:29 volumio volumio[932]: info: CoreStateMachine::serviceStop May 05 11:05:29 volumio volumio[932]: info: CorePlayQueue::getTrack 0 May 05 11:05:29 volumio volumio[932]: info: CoreCommandRouter::serviceStop May 05 11:05:29 volumio volumio[932]: info: [1714899929874] ControllerWebradio::stop May 05 11:05:29 volumio volumio[932]: verbose: ControllerMpd::sendMpdCommand stop May 05 11:05:29 volumio volumio[932]: info: May 05 11:05:29 volumio volumio[932]: ---------------------------- MPD announces state update: player May 05 11:05:29 volumio volumio[932]: info: sendMpdCommand stop took 17 milliseconds May 05 11:05:29 volumio volumio[932]: info: ControllerMpd::getState May 05 11:05:29 volumio volumio[932]: verbose: ControllerMpd::sendMpdCommand status May 05 11:05:29 volumio volumio[932]: info: sendMpdCommand status took 7 milliseconds May 05 11:05:29 volumio volumio[932]: verbose: ControllerMpd::parseState May 05 11:05:29 volumio volumio[932]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 05 11:05:29 volumio volumio[932]: info: May 05 11:05:29 volumio volumio[932]: ---------------------------- MPD announces state update: player May 05 11:05:29 volumio volumio[932]: info: ControllerMpd::getState May 05 11:05:29 volumio volumio[932]: verbose: ControllerMpd::sendMpdCommand status May 05 11:05:29 volumio volumio[932]: info: sendMpdCommand playlistinfo took 27 milliseconds May 05 11:05:29 volumio volumio[932]: info: sendMpdCommand status took 26 milliseconds May 05 11:05:29 volumio volumio[932]: verbose: ControllerMpd::parseTrackInfo May 05 11:05:29 volumio volumio[932]: verbose: ControllerMpd::parseState May 05 11:05:29 volumio volumio[932]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 05 11:05:29 volumio volumio[932]: info: ControllerMpd::pushState May 05 11:05:29 volumio volumio[932]: info: CoreCommandRouter::servicePushState May 05 11:05:29 volumio volumio[932]: info: CoreStateMachine::pushState May 05 11:05:29 volumio volumio[932]: info: CorePlayQueue::getTrack 0 May 05 11:05:29 volumio volumio[932]: info: CoreCommandRouter::volumioPushState May 05 11:05:29 volumio volumio[932]: info: MRS: Pushing multiroomSync output update for this device May 05 11:05:29 volumio volumio[932]: info: MRS: Pushing multiroomSync output May 05 11:05:29 volumio volumio[932]: info: CorePlayQueue::getTrack 0 May 05 11:05:29 volumio volumio[932]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd May 05 11:05:29 volumio volumio[932]: info: ------------------------------ 60ms May 05 11:05:29 volumio volumio[932]: info: sendMpdCommand playlistinfo took 29 milliseconds May 05 11:05:29 volumio volumio[932]: verbose: ControllerMpd::parseTrackInfo May 05 11:05:29 volumio volumio[932]: info: ControllerMpd::pushState May 05 11:05:29 volumio volumio[932]: info: CoreCommandRouter::servicePushState May 05 11:05:29 volumio volumio[932]: info: CoreStateMachine::pushState May 05 11:05:29 volumio volumio[932]: info: CorePlayQueue::getTrack 0 May 05 11:05:29 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 05 11:05:29 volumio volumio[932]: info: CoreCommandRouter::volumioPushState May 05 11:05:29 volumio volumio[932]: info: MRS: Pushing multiroomSync output update for this device May 05 11:05:29 volumio volumio[932]: info: MRS: Pushing multiroomSync output May 05 11:05:29 volumio volumio[932]: info: CorePlayQueue::getTrack 0 May 05 11:05:29 volumio volumio[932]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd May 05 11:05:29 volumio volumio[932]: info: ------------------------------ 85ms May 05 11:05:31 volumio volumio[932]: info: CoreCommandRouter::volumioPlay May 05 11:05:31 volumio volumio[932]: info: CoreStateMachine::play index undefined May 05 11:05:31 volumio volumio[932]: info: CoreStateMachine::setConsumeUpdateService undefined May 05 11:05:31 volumio volumio[932]: info: CorePlayQueue::getTrack 0 May 05 11:05:31 volumio volumio[932]: info: CoreStateMachine::startPlaybackTimer May 05 11:05:31 volumio volumio[932]: info: CorePlayQueue::getTrack 0 May 05 11:05:31 volumio volumio[932]: info: CoreCommandRouter::volumioGetVisibleSources May 05 11:05:31 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 05 11:05:31 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject May 05 11:05:31 volumio volumio[932]: info: [1714899931629] ControllerWebradio::clearAddPlayTrack May 05 11:05:31 volumio volumio[932]: verbose: ControllerMpd::sendMpdCommand stop May 05 11:05:31 volumio volumio[932]: info: sendMpdCommand stop took 1 milliseconds May 05 11:05:31 volumio volumio[932]: verbose: ControllerMpd::sendMpdCommand clear May 05 11:05:31 volumio volumio[932]: info: May 05 11:05:31 volumio volumio[932]: ---------------------------- MPD announces system playlist update May 05 11:05:31 volumio volumio[932]: info: Ignoring MPD Status Update May 05 11:05:31 volumio volumio[932]: info: sendMpdCommand clear took 1 milliseconds May 05 11:05:31 volumio volumio[932]: verbose: ControllerMpd::sendMpdCommand load "http://listen.jazz88.org/ksds.mp3" May 05 11:05:31 volumio volumio[932]: info: May 05 11:05:31 volumio volumio[932]: ---------------------------- MPD announces system playlist update May 05 11:05:31 volumio volumio[932]: info: Ignoring MPD Status Update May 05 11:05:31 volumio volumio[932]: error: updateQueue error: null May 05 11:05:31 volumio volumio[932]: info: ------------------------------ 4ms May 05 11:05:31 volumio volumio[932]: info: Executing endpoint metavolumio May 05 11:05:31 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 05 11:05:31 volumio volumio[932]: verbose: ControllerMpd::sendMpdCommand add "http://listen.jazz88.org/ksds.mp3" May 05 11:05:31 volumio volumio[932]: error: updateQueue error: null May 05 11:05:31 volumio volumio[932]: info: May 05 11:05:31 volumio volumio[932]: ---------------------------- MPD announces system playlist update May 05 11:05:31 volumio volumio[932]: info: Ignoring MPD Status Update May 05 11:05:31 volumio volumio[932]: info: ------------------------------ 298ms May 05 11:05:31 volumio volumio[932]: info: sendMpdCommand add "http://listen.jazz88.org/ksds.mp3" took 3 milliseconds May 05 11:05:31 volumio volumio[932]: info: CoreStateMachine::setConsumeUpdateService mpd May 05 11:05:31 volumio volumio[932]: verbose: ControllerMpd::sendMpdCommand play May 05 11:05:31 volumio volumio[932]: info: May 05 11:05:31 volumio volumio[932]: ---------------------------- MPD announces system playlist update May 05 11:05:31 volumio volumio[932]: info: Ignoring MPD Status Update May 05 11:05:31 volumio volumio[932]: info: ------------------------------ 5ms May 05 11:05:31 volumio volumio[932]: info: sendMpdCommand play took 4 milliseconds May 05 11:05:31 volumio volumio[932]: info: ------------------------------ 3ms May 05 11:05:32 volumio volumio[932]: info: May 05 11:05:32 volumio volumio[932]: ---------------------------- MPD announces state update: player May 05 11:05:32 volumio kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 May 05 11:05:32 volumio volumio[932]: info: ControllerMpd::getState May 05 11:05:32 volumio volumio[932]: verbose: ControllerMpd::sendMpdCommand status May 05 11:05:32 volumio volumio[932]: info: May 05 11:05:32 volumio volumio[932]: ---------------------------- MPD announces state update: player May 05 11:05:32 volumio volumio[932]: info: ControllerMpd::getState May 05 11:05:32 volumio volumio[932]: verbose: ControllerMpd::sendMpdCommand status May 05 11:05:32 volumio volumio[932]: info: May 05 11:05:32 volumio volumio[932]: ---------------------------- MPD announces state update: player May 05 11:05:32 volumio volumio[932]: info: sendMpdCommand status took 25 milliseconds May 05 11:05:32 volumio volumio[932]: info: ControllerMpd::getState May 05 11:05:32 volumio volumio[932]: verbose: ControllerMpd::sendMpdCommand status May 05 11:05:32 volumio volumio[932]: verbose: ControllerMpd::parseState May 05 11:05:32 volumio volumio[932]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 05 11:05:32 volumio volumio[932]: info: sendMpdCommand status took 27 milliseconds May 05 11:05:32 volumio volumio[932]: info: sendMpdCommand status took 2 milliseconds May 05 11:05:32 volumio volumio[932]: info: sendMpdCommand playlistinfo took 1 milliseconds May 05 11:05:32 volumio volumio[932]: verbose: ControllerMpd::parseState May 05 11:05:32 volumio volumio[932]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 05 11:05:32 volumio volumio[932]: verbose: ControllerMpd::parseState May 05 11:05:32 volumio volumio[932]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 05 11:05:32 volumio volumio[932]: verbose: ControllerMpd::parseTrackInfo May 05 11:05:32 volumio volumio[932]: info: ControllerMpd::pushState May 05 11:05:32 volumio volumio[932]: info: CoreCommandRouter::servicePushState May 05 11:05:32 volumio volumio[932]: info: CorePlayQueue::getTrack 0 May 05 11:05:32 volumio volumio[932]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"ksds.mp3","artist":null,"album":null,"uri":"http://listen.jazz88.org/ksds.mp3","trackType":"mp3"} May 05 11:05:32 volumio volumio[932]: verbose: CURRENT POSITION 0 May 05 11:05:32 volumio volumio[932]: info: CoreStateMachine::syncState stateService play May 05 11:05:32 volumio volumio[932]: info: CoreStateMachine::syncState currentStatus stop May 05 11:05:32 volumio volumio[932]: info: ------------------------------ 34ms May 05 11:05:32 volumio volumio[932]: info: May 05 11:05:32 volumio volumio[932]: ---------------------------- MPD announces state update: player May 05 11:05:32 volumio volumio[932]: info: ControllerMpd::getState May 05 11:05:32 volumio volumio[932]: verbose: ControllerMpd::sendMpdCommand status May 05 11:05:32 volumio volumio[932]: info: sendMpdCommand playlistinfo took 5 milliseconds May 05 11:05:32 volumio volumio[932]: info: sendMpdCommand playlistinfo took 6 milliseconds May 05 11:05:32 volumio volumio[932]: info: sendMpdCommand status took 2 milliseconds May 05 11:05:32 volumio volumio[932]: verbose: ControllerMpd::parseTrackInfo May 05 11:05:32 volumio volumio[932]: verbose: ControllerMpd::parseTrackInfo May 05 11:05:32 volumio volumio[932]: verbose: ControllerMpd::parseState May 05 11:05:32 volumio volumio[932]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 05 11:05:32 volumio volumio[932]: info: ControllerMpd::pushState May 05 11:05:32 volumio volumio[932]: info: CoreCommandRouter::servicePushState May 05 11:05:32 volumio volumio[932]: info: CorePlayQueue::getTrack 0 May 05 11:05:32 volumio volumio[932]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"ksds.mp3","artist":null,"album":null,"uri":"http://listen.jazz88.org/ksds.mp3","trackType":"mp3"} May 05 11:05:32 volumio volumio[932]: verbose: CURRENT POSITION 0 May 05 11:05:32 volumio volumio[932]: info: CoreStateMachine::syncState stateService play May 05 11:05:32 volumio volumio[932]: info: CoreStateMachine::syncState currentStatus play May 05 11:05:32 volumio volumio[932]: info: Received an update from plugin. extracting info from payload May 05 11:05:32 volumio volumio[932]: info: CoreStateMachine::pushState May 05 11:05:32 volumio volumio[932]: info: CorePlayQueue::getTrack 0 May 05 11:05:32 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 05 11:05:32 volumio volumio[932]: info: CoreCommandRouter::volumioPushState May 05 11:05:32 volumio volumio[932]: info: MRS: Pushing multiroomSync output update for this device May 05 11:05:32 volumio volumio[932]: info: MRS: Pushing multiroomSync output May 05 11:05:32 volumio volumio[932]: info: CoreStateMachine::pushState May 05 11:05:32 volumio volumio[932]: info: CoreCommandRouter::volumioPushState May 05 11:05:32 volumio volumio[932]: info: MRS: Pushing multiroomSync output update for this device May 05 11:05:32 volumio volumio[932]: info: MRS: Pushing multiroomSync output May 05 11:05:32 volumio volumio[932]: info: ControllerMpd::pushState May 05 11:05:32 volumio volumio[932]: info: CoreCommandRouter::servicePushState May 05 11:05:32 volumio volumio[932]: info: CorePlayQueue::getTrack 0 May 05 11:05:32 volumio volumio[932]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"ksds.mp3","artist":null,"album":null,"uri":"http://listen.jazz88.org/ksds.mp3","trackType":"mp3"} May 05 11:05:32 volumio volumio[932]: verbose: CURRENT POSITION 0 May 05 11:05:32 volumio volumio[932]: info: CoreStateMachine::syncState stateService play May 05 11:05:32 volumio volumio[932]: info: CoreStateMachine::syncState currentStatus play May 05 11:05:32 volumio volumio[932]: info: Received an update from plugin. extracting info from payload May 05 11:05:32 volumio volumio[932]: info: CoreStateMachine::pushState May 05 11:05:32 volumio volumio[932]: info: CorePlayQueue::getTrack 0 May 05 11:05:32 volumio volumio[932]: info: CoreCommandRouter::volumioPushState May 05 11:05:32 volumio volumio[932]: info: MRS: Pushing multiroomSync output update for this device May 05 11:05:32 volumio volumio[932]: info: MRS: Pushing multiroomSync output May 05 11:05:32 volumio volumio[932]: info: CoreStateMachine::pushState May 05 11:05:32 volumio volumio[932]: info: CoreCommandRouter::volumioPushState May 05 11:05:32 volumio volumio[932]: info: MRS: Pushing multiroomSync output update for this device May 05 11:05:32 volumio volumio[932]: info: MRS: Pushing multiroomSync output May 05 11:05:32 volumio volumio[932]: info: ------------------------------ 92ms May 05 11:05:32 volumio volumio[932]: info: ------------------------------ 68ms May 05 11:05:32 volumio volumio[932]: info: sendMpdCommand playlistinfo took 59 milliseconds May 05 11:05:32 volumio volumio[932]: verbose: ControllerMpd::parseTrackInfo May 05 11:05:32 volumio volumio[932]: info: ControllerMpd::pushState May 05 11:05:32 volumio volumio[932]: info: CoreCommandRouter::servicePushState May 05 11:05:32 volumio volumio[932]: info: CorePlayQueue::getTrack 0 May 05 11:05:32 volumio volumio[932]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"ksds.mp3","artist":null,"album":null,"uri":"http://listen.jazz88.org/ksds.mp3","trackType":"mp3"} May 05 11:05:32 volumio volumio[932]: verbose: CURRENT POSITION 0 May 05 11:05:32 volumio volumio[932]: info: CoreStateMachine::syncState stateService play May 05 11:05:32 volumio volumio[932]: info: CoreStateMachine::syncState currentStatus play May 05 11:05:32 volumio volumio[932]: info: Received an update from plugin. extracting info from payload May 05 11:05:32 volumio volumio[932]: info: CoreStateMachine::pushState May 05 11:05:32 volumio volumio[932]: info: CorePlayQueue::getTrack 0 May 05 11:05:32 volumio volumio[932]: info: CoreCommandRouter::volumioPushState May 05 11:05:32 volumio volumio[932]: info: MRS: Pushing multiroomSync output update for this device May 05 11:05:32 volumio volumio[932]: info: MRS: Pushing multiroomSync output May 05 11:05:32 volumio volumio[932]: info: CoreStateMachine::pushState May 05 11:05:32 volumio volumio[932]: info: CoreCommandRouter::volumioPushState May 05 11:05:32 volumio volumio[932]: info: MRS: Pushing multiroomSync output update for this device May 05 11:05:32 volumio volumio[932]: info: MRS: Pushing multiroomSync output May 05 11:05:32 volumio volumio[932]: info: ------------------------------ 86ms May 05 11:05:32 volumio volumio[932]: info: May 05 11:05:32 volumio volumio[932]: ---------------------------- MPD announces system playlist update May 05 11:05:32 volumio volumio[932]: info: Ignoring MPD Status Update May 05 11:05:32 volumio volumio[932]: info: May 05 11:05:32 volumio volumio[932]: ---------------------------- MPD announces system playlist update May 05 11:05:32 volumio volumio[932]: info: Ignoring MPD Status Update May 05 11:05:32 volumio volumio[932]: info: May 05 11:05:32 volumio volumio[932]: ---------------------------- MPD announces system playlist update May 05 11:05:32 volumio volumio[932]: info: Ignoring MPD Status Update May 05 11:05:32 volumio volumio[932]: info: May 05 11:05:32 volumio volumio[932]: ---------------------------- MPD announces state update: player May 05 11:05:32 volumio volumio[932]: info: ControllerMpd::getState May 05 11:05:32 volumio volumio[932]: verbose: ControllerMpd::sendMpdCommand status May 05 11:05:32 volumio volumio[932]: info: ------------------------------ 38ms May 05 11:05:32 volumio volumio[932]: info: ------------------------------ 37ms May 05 11:05:32 volumio volumio[932]: info: May 05 11:05:32 volumio volumio[932]: ---------------------------- MPD announces system playlist update May 05 11:05:32 volumio volumio[932]: info: Ignoring MPD Status Update May 05 11:05:32 volumio volumio[932]: info: May 05 11:05:32 volumio volumio[932]: ---------------------------- MPD announces state update: player May 05 11:05:32 volumio volumio[932]: info: ControllerMpd::getState May 05 11:05:32 volumio volumio[932]: verbose: ControllerMpd::sendMpdCommand status May 05 11:05:32 volumio volumio[932]: info: ------------------------------ 6ms May 05 11:05:32 volumio volumio[932]: info: sendMpdCommand status took 5 milliseconds May 05 11:05:32 volumio volumio[932]: info: ------------------------------ 4ms May 05 11:05:32 volumio volumio[932]: info: sendMpdCommand status took 2 milliseconds May 05 11:05:32 volumio volumio[932]: verbose: ControllerMpd::parseState May 05 11:05:32 volumio volumio[932]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 05 11:05:32 volumio volumio[932]: verbose: ControllerMpd::parseState May 05 11:05:32 volumio volumio[932]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 05 11:05:32 volumio volumio[932]: info: sendMpdCommand playlistinfo took 23 milliseconds May 05 11:05:32 volumio volumio[932]: info: sendMpdCommand playlistinfo took 23 milliseconds May 05 11:05:32 volumio volumio[932]: verbose: ControllerMpd::parseTrackInfo May 05 11:05:32 volumio volumio[932]: verbose: ControllerMpd::parseTrackInfo May 05 11:05:32 volumio volumio[932]: info: ControllerMpd::pushState May 05 11:05:32 volumio volumio[932]: info: CoreCommandRouter::servicePushState May 05 11:05:32 volumio volumio[932]: info: CorePlayQueue::getTrack 0 May 05 11:05:32 volumio volumio[932]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1234,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"ksds.mp3","artist":"no name","album":null,"uri":"http://listen.jazz88.org/ksds.mp3","trackType":"mp3"} May 05 11:05:32 volumio volumio[932]: verbose: CURRENT POSITION 0 May 05 11:05:32 volumio volumio[932]: info: CoreStateMachine::syncState stateService play May 05 11:05:32 volumio volumio[932]: info: CoreStateMachine::syncState currentStatus play May 05 11:05:32 volumio volumio[932]: info: Received an update from plugin. extracting info from payload May 05 11:05:32 volumio volumio[932]: info: CoreStateMachine::pushState May 05 11:05:32 volumio volumio[932]: info: CorePlayQueue::getTrack 0 May 05 11:05:32 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 05 11:05:32 volumio volumio[932]: info: CoreCommandRouter::volumioPushState May 05 11:05:32 volumio volumio[932]: info: MRS: Pushing multiroomSync output update for this device May 05 11:05:32 volumio volumio[932]: info: MRS: Pushing multiroomSync output May 05 11:05:32 volumio volumio[932]: info: CoreStateMachine::pushState May 05 11:05:32 volumio volumio[932]: info: CoreCommandRouter::volumioPushState May 05 11:05:32 volumio volumio[932]: info: MRS: Pushing multiroomSync output update for this device May 05 11:05:32 volumio volumio[932]: info: MRS: Pushing multiroomSync output May 05 11:05:32 volumio volumio[932]: info: ControllerMpd::pushState May 05 11:05:32 volumio volumio[932]: info: CoreCommandRouter::servicePushState May 05 11:05:32 volumio volumio[932]: info: CorePlayQueue::getTrack 0 May 05 11:05:32 volumio volumio[932]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1234,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"ksds.mp3","artist":"no name","album":null,"uri":"http://listen.jazz88.org/ksds.mp3","trackType":"mp3"} May 05 11:05:32 volumio volumio[932]: verbose: CURRENT POSITION 0 May 05 11:05:32 volumio volumio[932]: info: CoreStateMachine::syncState stateService play May 05 11:05:32 volumio volumio[932]: info: CoreStateMachine::syncState currentStatus play May 05 11:05:32 volumio volumio[932]: info: Received an update from plugin. extracting info from payload May 05 11:05:32 volumio volumio[932]: info: CoreStateMachine::pushState May 05 11:05:32 volumio volumio[932]: info: CorePlayQueue::getTrack 0 May 05 11:05:32 volumio volumio[932]: info: CoreCommandRouter::volumioPushState May 05 11:05:32 volumio volumio[932]: info: MRS: Pushing multiroomSync output update for this device May 05 11:05:32 volumio volumio[932]: info: MRS: Pushing multiroomSync output May 05 11:05:32 volumio volumio[932]: info: CoreStateMachine::pushState May 05 11:05:32 volumio volumio[932]: info: CoreCommandRouter::volumioPushState May 05 11:05:32 volumio volumio[932]: info: MRS: Pushing multiroomSync output update for this device May 05 11:05:32 volumio volumio[932]: info: MRS: Pushing multiroomSync output May 05 11:05:32 volumio volumio[932]: info: ------------------------------ 97ms May 05 11:05:32 volumio volumio[932]: info: ------------------------------ 94ms May 05 11:05:34 volumio volumio[932]: info: Executing endpoint metavolumio May 05 11:05:34 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 05 11:05:34 volumio volumio[932]: info: Executing endpoint metavolumio May 05 11:05:34 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 05 11:05:36 volumio volumio[932]: error: Failed request for metavolumio API May 05 11:05:38 volumio volumio[932]: error: Failed request for metavolumio API May 05 11:05:46 volumio volumio[932]: info: CoreCommandRouter::volumioGetQueue May 05 11:05:46 volumio volumio[932]: info: CoreStateMachine::getQueue May 05 11:05:46 volumio volumio[932]: info: CorePlayQueue::getQueue May 05 11:05:47 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 05 11:05:47 volumio volumio[932]: info: CURURI: music-library May 05 11:05:47 volumio volumio[932]: error: Failed LSINFO: null May 05 11:05:47 volumio volumio[932]: info: Preload queue cleared May 05 11:05:50 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 05 11:05:50 volumio volumio[932]: info: Preload queue cleared May 05 11:05:51 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 05 11:05:51 volumio volumio[932]: info: Preload queue cleared May 05 11:05:52 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 05 11:05:52 volumio volumio[932]: info: Preload queue cleared May 05 11:05:56 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 05 11:05:56 volumio volumio[932]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 05 11:05:56 volumio volumio[932]: TypeError: Cannot read property 'length' of undefined May 05 11:05:56 volumio volumio[932]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 May 05 11:05:56 volumio volumio[932]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 May 05 11:05:56 volumio volumio[932]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) May 05 11:05:56 volumio volumio[932]: at Parser.emit (events.js:315:20) May 05 11:05:56 volumio volumio[932]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) May 05 11:05:56 volumio volumio[932]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) May 05 11:05:56 volumio volumio[932]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) May 05 11:05:56 volumio volumio[932]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) May 05 11:05:56 volumio volumio[932]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) May 05 11:05:56 volumio volumio[932]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) May 05 11:05:56 volumio volumio[932]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) May 05 11:05:56 volumio volumio[932]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) May 05 11:05:56 volumio volumio[932]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) May 05 11:05:56 volumio volumio[932]: at IncomingMessage.emit (events.js:327:22) May 05 11:05:56 volumio volumio[932]: at endReadableNT (internal/streams/readable.js:1327:12) May 05 11:05:56 volumio volumio[932]: at processTicksAndRejections (internal/process/task_queues.js:80:21) May 05 11:05:56 volumio volumio[932]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 05 11:05:57 volumio sudo[2153]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-05-05 11:04 May 05 11:05:57 volumio sudo[2153]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="e7445fab547cd22e23d9bef72406cfb610bc1d7d" VOLUMIO_FE_VERSION="5d5e99f8f803b3ec5fc5e8ca13bb4023600f3eee" VOLUMIO_FE3_VERSION="a6ae210575eae1ff43f06ab71e1cd17576840623" VOLUMIO_BE_VERSION="a9e1e1dd6072ffb15c0fcb8938d9ea0d8900c0da" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 24 Apr 2024 02:10:14 PM CEST" VOLUMIO_VERSION="3.661" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="5bdefd4af4423fd17aacb83a8538bf1f"