-- Logs begin at Sun 2024-11-24 09:29:09 +04, end at Sun 2024-11-24 17:29:13 +04. -- Nov 24 17:28:08 balonias-volumio volumio[1074]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 24 17:28:08 balonias-volumio volumio[1074]: xcb_connection_has_error() returned true Nov 24 17:28:08 balonias-volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Nov 24 17:28:08 balonias-volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 24 17:28:08 balonias-volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Nov 24 17:28:08 balonias-volumio volumio[1074]: xcb_connection_has_error() returned true Nov 24 17:28:08 balonias-volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 24 17:28:08 balonias-volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 24 17:28:08 balonias-volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 24 17:28:08 balonias-volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 24 17:28:08 balonias-volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 24 17:28:08 balonias-volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 24 17:28:08 balonias-volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 24 17:28:08 balonias-volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Nov 24 17:28:08 balonias-volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Nov 24 17:28:23 balonias-volumio volumio[1074]: info: CALLMETHOD: audio_interface alsa_controller saveVolumeOptions [object Object] Nov 24 17:28:23 balonias-volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveVolumeOptions Nov 24 17:28:23 balonias-volumio volumio[1074]: info: CoreCommandRouter::volumioGetState Nov 24 17:28:23 balonias-volumio volumio[1074]: info: Restoring Previous Volume level: 100 false true Nov 24 17:28:23 balonias-volumio volumio[1074]: info: VolumeController::SetAlsaVolume100 Nov 24 17:28:23 balonias-volumio volumio[1074]: info: Volume configurations have been set Nov 24 17:28:23 balonias-volumio vtcs[1917]: [2024-11-24 17:28:23.622] [tisoc] [warning] [SessionManagerImpl.cpp:243] Illegal State: IDLE Nov 24 17:28:23 balonias-volumio vtcs[1917]: [2024-11-24 17:28:23.622] [tisoc] [error] [SpkconServer.cpp:376] recv error. socket disconnected Nov 24 17:28:23 balonias-volumio sudo[11035]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Nov 24 17:28:23 balonias-volumio sudo[11035]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 17:28:23 balonias-volumio systemd[1]: Stopping Volumio Tidal Connect Service... Nov 24 17:28:23 balonias-volumio systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Nov 24 17:28:23 balonias-volumio systemd[1]: vtcs.service: Succeeded. Nov 24 17:28:23 balonias-volumio systemd[1]: Stopped Volumio Tidal Connect Service. Nov 24 17:28:23 balonias-volumio sudo[11035]: pam_unix(sudo:session): session closed for user root Nov 24 17:28:23 balonias-volumio volumio[1074]: xcb_connection_has_error() returned true Nov 24 17:28:23 balonias-volumio volumio[1074]: info: CoreCommandRouter::volumioUpdateVolumeSettings Nov 24 17:28:23 balonias-volumio volumio[1074]: info: Updating Volume Controller Parameters: Device: 5 Name: softvolume Mixer: SoftMaster Max Vol: 100 Vol Curve; logarithmic Vol Steps: 20 Nov 24 17:28:23 balonias-volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Nov 24 17:28:23 balonias-volumio volumio[1074]: info: Disabling external Volume Control Nov 24 17:28:23 balonias-volumio volumio[1074]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 24 17:28:23 balonias-volumio volumio[1074]: info: CoreStateMachine::pushState Nov 24 17:28:23 balonias-volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 24 17:28:23 balonias-volumio volumio[1074]: info: CoreCommandRouter::volumioPushState Nov 24 17:28:23 balonias-volumio volumio[1074]: info: MRS: Pushing multiroomSync output update for this device Nov 24 17:28:23 balonias-volumio volumio[1074]: info: MRS: Pushing multiroomSync output Nov 24 17:28:23 balonias-volumio volumio[1074]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 24 17:28:23 balonias-volumio sudo[11048]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Nov 24 17:28:23 balonias-volumio sudo[11048]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 17:28:23 balonias-volumio volumio[1074]: info: CoreStateMachine::pushState Nov 24 17:28:23 balonias-volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 24 17:28:23 balonias-volumio volumio[1074]: info: CoreCommandRouter::volumioPushState Nov 24 17:28:23 balonias-volumio volumio[1074]: info: MRS: Pushing multiroomSync output update for this device Nov 24 17:28:23 balonias-volumio volumio[1074]: info: MRS: Pushing multiroomSync output Nov 24 17:28:23 balonias-volumio sudo[11048]: pam_unix(sudo:session): session closed for user root Nov 24 17:28:23 balonias-volumio volumio[1074]: xcb_connection_has_error() returned true Nov 24 17:28:23 balonias-volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Nov 24 17:28:23 balonias-volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 24 17:28:23 balonias-volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Nov 24 17:28:23 balonias-volumio volumio[1074]: xcb_connection_has_error() returned true Nov 24 17:28:23 balonias-volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 24 17:28:23 balonias-volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 24 17:28:23 balonias-volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 24 17:28:23 balonias-volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 24 17:28:23 balonias-volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 24 17:28:23 balonias-volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 24 17:28:23 balonias-volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 24 17:28:23 balonias-volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Nov 24 17:28:23 balonias-volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Nov 24 17:28:26 balonias-volumio volumio[1074]: info: TidalConnect service stoped! Nov 24 17:28:26 balonias-volumio volumio[1074]: info: TidalConnect service stoped! Nov 24 17:28:26 balonias-volumio volumio[1074]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Nov 24 17:28:26 balonias-volumio volumio[1074]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Nov 24 17:28:26 balonias-volumio sudo[11067]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Nov 24 17:28:26 balonias-volumio sudo[11067]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 17:28:26 balonias-volumio systemd[1]: Started Volumio Tidal Connect Service. Nov 24 17:28:26 balonias-volumio sudo[11067]: pam_unix(sudo:session): session closed for user root Nov 24 17:28:26 balonias-volumio volumio[1074]: info: Executing endpoint tc_getconfig Nov 24 17:28:26 balonias-volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Nov 24 17:28:26 balonias-volumio vtcs[11069]: STARTING TidalConnect services, version: 1.4.0.34 Nov 24 17:28:26 balonias-volumio vtcs[11069]: STARTED TidalConnect services. Nov 24 17:28:26 balonias-volumio volumio[1074]: info: Executing endpoint tc_connect Nov 24 17:28:26 balonias-volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Nov 24 17:28:26 balonias-volumio volumio[1074]: info: Connecting to TidalConnect Nov 24 17:28:26 balonias-volumio volumio[1074]: info: CoreCommandRouter::servicePushState Nov 24 17:28:26 balonias-volumio volumio[1074]: info: CoreStateMachine::pushState Nov 24 17:28:26 balonias-volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 24 17:28:26 balonias-volumio volumio[1074]: info: CoreCommandRouter::volumioPushState Nov 24 17:28:26 balonias-volumio volumio[1074]: info: MRS: Pushing multiroomSync output update for this device Nov 24 17:28:26 balonias-volumio volumio[1074]: info: MRS: Pushing multiroomSync output Nov 24 17:28:26 balonias-volumio volumio[1074]: info: CorePlayQueue::getTrack 2 Nov 24 17:28:26 balonias-volumio volumio[1074]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current mpd Received tidalconnect Nov 24 17:28:26 balonias-volumio volumio[1074]: info: CoreCommandRouter::servicePushState Nov 24 17:28:26 balonias-volumio volumio[1074]: info: CoreStateMachine::pushState Nov 24 17:28:26 balonias-volumio volumio[1074]: info: CoreCommandRouter::volumioPushState Nov 24 17:28:26 balonias-volumio volumio[1074]: info: MRS: Pushing multiroomSync output update for this device Nov 24 17:28:26 balonias-volumio volumio[1074]: info: MRS: Pushing multiroomSync output Nov 24 17:28:26 balonias-volumio volumio[1074]: info: CorePlayQueue::getTrack 2 Nov 24 17:28:26 balonias-volumio volumio[1074]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current mpd Received tidalconnect Nov 24 17:28:28 balonias-volumio volumio[1074]: info: VolumeController::SetAlsaVolume100 Nov 24 17:28:28 balonias-volumio volumio[1074]: info: CoreStateMachine::pushState Nov 24 17:28:28 balonias-volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 24 17:28:28 balonias-volumio volumio[1074]: info: CoreCommandRouter::volumioPushState Nov 24 17:28:28 balonias-volumio volumio[1074]: info: MRS: Pushing multiroomSync output update for this device Nov 24 17:28:28 balonias-volumio volumio[1074]: info: MRS: Pushing multiroomSync output Nov 24 17:28:29 balonias-volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 24 17:28:29 balonias-volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 24 17:28:29 balonias-volumio volumio[1074]: info: Discovery: Getting this device information Nov 24 17:28:29 balonias-volumio volumio[1074]: info: CoreCommandRouter::volumioGetState Nov 24 17:28:29 balonias-volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 24 17:28:29 balonias-volumio volumio[1074]: info: TidalConnect service started! Nov 24 17:28:41 balonias-volumio volumio[1074]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 24 17:28:41 balonias-volumio volumio[1074]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 24 17:28:41 balonias-volumio volumio[1074]: info: Retrieving Cloud Streaming UI Nov 24 17:28:41 balonias-volumio volumio[1074]: info: Getting Tidal Cloud Configuration Nov 24 17:28:41 balonias-volumio volumio[1074]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 24 17:28:41 balonias-volumio volumio[1074]: info: Getting Qobuz Cloud Configuration Nov 24 17:28:41 balonias-volumio volumio[1074]: info: Asking plugin for UI Config Nov 24 17:28:41 balonias-volumio volumio[1074]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 24 17:28:41 balonias-volumio volumio[1074]: info: Getting Spotify Cloud Configuration Nov 24 17:28:41 balonias-volumio volumio[1074]: info: Asking plugin for UI Config Nov 24 17:28:41 balonias-volumio volumio[1074]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 24 17:28:41 balonias-volumio volumio[1074]: info: Saving Spotify Acccount Nov 24 17:28:41 balonias-volumio volumio[1074]: info: Got it Nov 24 17:28:41 balonias-volumio volumio[1074]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Nov 24 17:28:41 balonias-volumio volumio[1074]: info: Got Tidal Cloud Configuration Nov 24 17:28:41 balonias-volumio volumio[1074]: info: Got it Nov 24 17:28:41 balonias-volumio volumio[1074]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 24 17:28:41 balonias-volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Nov 24 17:28:41 balonias-volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Nov 24 17:28:41 balonias-volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Nov 24 17:28:41 balonias-volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Nov 24 17:28:41 balonias-volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 24 17:28:41 balonias-volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 24 17:28:41 balonias-volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 24 17:28:41 balonias-volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 24 17:28:41 balonias-volumio volumio[1074]: info: CoreCommandRouter::volumioGetBrowseSources Nov 24 17:28:41 balonias-volumio volumio[1074]: info: CoreCommandRouter::volumioGetBrowseSources Nov 24 17:28:41 balonias-volumio volumio[1074]: info: CoreCommandRouter::volumioGetBrowseSources Nov 24 17:28:41 balonias-volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 24 17:28:41 balonias-volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Nov 24 17:28:45 balonias-volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 24 17:28:48 balonias-volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Nov 24 17:28:48 balonias-volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Nov 24 17:28:49 balonias-volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 24 17:28:53 balonias-volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 24 17:28:57 balonias-volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 24 17:28:58 balonias-volumio ntpd[983]: Soliciting pool server 2001:ac8:81:65:0:2:0:2 Nov 24 17:28:59 balonias-volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 24 17:28:59 balonias-volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 24 17:28:59 balonias-volumio volumio[1074]: info: Discovery: Getting this device information Nov 24 17:28:59 balonias-volumio volumio[1074]: info: CoreCommandRouter::volumioGetState Nov 24 17:28:59 balonias-volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 24 17:29:01 balonias-volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 24 17:29:05 balonias-volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 24 17:29:09 balonias-volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 24 17:29:11 balonias-volumio volumio[1074]: info: Disabling MyMusic plugin upnp Nov 24 17:29:11 balonias-volumio sudo[11220]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Nov 24 17:29:11 balonias-volumio sudo[11220]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 17:29:11 balonias-volumio volumio[1074]: error: Upnp client error: Error: This socket has been ended by the other party Nov 24 17:29:11 balonias-volumio systemd[1]: Stopping UPnP Renderer front-end to MPD... Nov 24 17:29:11 balonias-volumio volumio[1074]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 24 17:29:13 balonias-volumio volumio[1074]: info: Enabling MyMusic plugin upnp Nov 24 17:29:13 balonias-volumio volumio[1074]: info: Enabling plugin upnp Nov 24 17:29:13 balonias-volumio volumio[1074]: info: Loading plugin "upnp"... Nov 24 17:29:13 balonias-volumio volumio[1074]: info: [1732454953648] Starting Upmpd Daemon Nov 24 17:29:13 balonias-volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 24 17:29:13 balonias-volumio volumio[1074]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 24 17:29:13 balonias-volumio volumio[1074]: Error: listen EADDRINUSE: address already in use :::6599 Nov 24 17:29:13 balonias-volumio volumio[1074]: at Server.setupListenHandle [as _listen2] (net.js:1318:16) Nov 24 17:29:13 balonias-volumio volumio[1074]: at listenInCluster (net.js:1366:12) Nov 24 17:29:13 balonias-volumio volumio[1074]: at Server.listen (net.js:1452:7) Nov 24 17:29:13 balonias-volumio volumio[1074]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17) Nov 24 17:29:13 balonias-volumio volumio[1074]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38) Nov 24 17:29:13 balonias-volumio volumio[1074]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Nov 24 17:29:13 balonias-volumio volumio[1074]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Nov 24 17:29:13 balonias-volumio volumio[1074]: at processTicksAndRejections (internal/process/task_queues.js:75:11) { Nov 24 17:29:13 balonias-volumio volumio[1074]: code: 'EADDRINUSE', Nov 24 17:29:13 balonias-volumio volumio[1074]: errno: -98, Nov 24 17:29:13 balonias-volumio volumio[1074]: syscall: 'listen', Nov 24 17:29:13 balonias-volumio volumio[1074]: address: '::', Nov 24 17:29:13 balonias-volumio volumio[1074]: port: 6599 Nov 24 17:29:13 balonias-volumio volumio[1074]: } Nov 24 17:29:13 balonias-volumio volumio[1074]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 24 17:29:13 balonias-volumio sudo[11232]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-24 17:28 Nov 24 17:29:13 balonias-volumio sudo[11232]: 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="70bcab5180cf11352f5a5197ade215ea8ee93db0" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="678d37490b3eb2102ce7c6d7a6dc3cf042ff2347" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 08 Nov 2024 06:51:56 PM CET" VOLUMIO_VERSION="3.779" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="b9009e0280f50389e80a537357ff3961"