-- Logs begin at Thu 2019-02-14 12:11:58 EET, end at Mon 2025-03-03 18:56:02 EET. -- Mar 03 18:55:00 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Mar 03 18:55:08 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 03 18:55:08 volumio volumio[1004]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Mar 03 18:55:08 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Mar 03 18:55:08 volumio volumio[1004]: info: Received Get System Version Mar 03 18:55:08 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 03 18:55:08 volumio volumio[1004]: info: Received Get System Info Mar 03 18:55:08 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 03 18:55:08 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 03 18:55:08 volumio volumio[1004]: info: Discovery: Getting this device information Mar 03 18:55:08 volumio volumio[1004]: info: CoreCommandRouter::volumioGetState Mar 03 18:55:08 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 03 18:55:15 volumio volumio[1004]: info: CALLMETHOD: audio_interface alsa_controller saveVolumeOptions [object Object] Mar 03 18:55:15 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveVolumeOptions Mar 03 18:55:15 volumio volumio[1004]: info: CoreCommandRouter::volumioGetState Mar 03 18:55:15 volumio volumio[1004]: info: Restoring Previous Volume level: 32 false true Mar 03 18:55:15 volumio volumio[1004]: info: VolumeController::SetAlsaVolume100 Mar 03 18:55:15 volumio volumio[1004]: info: Volume configurations have been set Mar 03 18:55:15 volumio vtcs[22623]: [2025-03-03 18:55:15.088] [tisoc] [error] [SpkconServer.cpp:376] recv error. socket disconnected Mar 03 18:55:15 volumio vtcs[22623]: [2025-03-03 18:55:15.088] [tisoc] [warning] [SessionManagerImpl.cpp:243] Illegal State: IDLE Mar 03 18:55:15 volumio sudo[22757]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 03 18:55:15 volumio sudo[22757]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 18:55:15 volumio systemd[1]: Stopping Volumio Tidal Connect Service... Mar 03 18:55:15 volumio systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Mar 03 18:55:15 volumio systemd[1]: vtcs.service: Succeeded. Mar 03 18:55:15 volumio systemd[1]: Stopped Volumio Tidal Connect Service. Mar 03 18:55:15 volumio volumio[1004]: xcb_connection_has_error() returned true Mar 03 18:55:15 volumio volumio[1004]: info: CoreCommandRouter::volumioUpdateVolumeSettings Mar 03 18:55:15 volumio volumio[1004]: info: Updating Volume Controller Parameters: Device: 0 Name: softvolume Mixer: SoftMaster Max Vol: 100 Vol Curve; logarithmic Vol Steps: 2 Mar 03 18:55:15 volumio sudo[22757]: pam_unix(sudo:session): session closed for user root Mar 03 18:55:15 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Mar 03 18:55:15 volumio volumio[1004]: info: Disabling external Volume Control Mar 03 18:55:15 volumio volumio[1004]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 03 18:55:15 volumio volumio[1004]: info: CoreStateMachine::pushState Mar 03 18:55:15 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 03 18:55:15 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Mar 03 18:55:15 volumio volumio[1004]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 03 18:55:15 volumio volumio[1004]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 03 18:55:15 volumio volumio[1004]: SPOTIFY: SPOTIFY VOLUME 32 Mar 03 18:55:15 volumio volumio[1004]: SPOTIFY: VOLUMIO VOLUME 100 Mar 03 18:55:15 volumio volumio[1004]: SPOTIFY: DELTA VOLUME ENOUGH: true Mar 03 18:55:15 volumio volumio[1004]: info: Setting Spotify Volume from Volumio: 100 Mar 03 18:55:15 volumio sudo[22770]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 03 18:55:15 volumio sudo[22770]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 18:55:15 volumio volumio[1004]: info: CoreStateMachine::pushState Mar 03 18:55:15 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 03 18:55:15 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Mar 03 18:55:15 volumio volumio[1004]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 03 18:55:15 volumio volumio[1004]: SPOTIFY: SPOTIFY VOLUME 100 Mar 03 18:55:15 volumio volumio[1004]: SPOTIFY: VOLUMIO VOLUME 100 Mar 03 18:55:15 volumio volumio[1004]: SPOTIFY: DELTA VOLUME ENOUGH: false Mar 03 18:55:15 volumio sudo[22770]: pam_unix(sudo:session): session closed for user root Mar 03 18:55:15 volumio volumio[1004]: xcb_connection_has_error() returned true Mar 03 18:55:15 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Mar 03 18:55:15 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 03 18:55:15 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 03 18:55:15 volumio volumio[1004]: info: Getting Alsa Cards List without I2S DAC Mar 03 18:55:15 volumio volumio[1004]: xcb_connection_has_error() returned true Mar 03 18:55:15 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Mar 03 18:55:15 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 03 18:55:15 volumio volumio[1004]: xcb_connection_has_error() returned true Mar 03 18:55:15 volumio volumio[1004]: xcb_connection_has_error() returned true Mar 03 18:55:15 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 03 18:55:15 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 03 18:55:15 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 03 18:55:15 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 03 18:55:15 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 03 18:55:15 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 03 18:55:15 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 03 18:55:15 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Mar 03 18:55:15 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Mar 03 18:55:16 volumio volumio[1004]: SPOTIFY: SETTING SPOTIFY VOLUME 100 Mar 03 18:55:16 volumio volumio[1004]: info: Sending Spotify command with payload to local API: /player/volume Mar 03 18:55:16 volumio go-librespot[1287]: time="2025-03-03T18:55:16+02:00" level=debug msg="update volume to 65535/65535" Mar 03 18:55:16 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 03 18:55:16 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 03 18:55:16 volumio volumio[1004]: info: Discovery: Getting this device information Mar 03 18:55:16 volumio volumio[1004]: info: CoreCommandRouter::volumioGetState Mar 03 18:55:16 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 03 18:55:17 volumio go-librespot[1287]: time="2025-03-03T18:55:17+02:00" level=trace msg="received accesspoint ping" Mar 03 18:55:17 volumio go-librespot[1287]: time="2025-03-03T18:55:17+02:00" level=trace msg="received accesspoint pong ack" Mar 03 18:55:17 volumio go-librespot[1287]: time="2025-03-03T18:55:17+02:00" level=debug msg="put connect state because VOLUME_CHANGED" Mar 03 18:55:17 volumio go-librespot[1287]: time="2025-03-03T18:55:17+02:00" level=trace msg="emitting websocket event: volume" Mar 03 18:55:17 volumio volumio[1004]: SPOTIFY: received: {"type":"volume","data":{"value":100,"max":100}} Mar 03 18:55:17 volumio volumio[1004]: SPOTIFY: RECEIVED SPOTIFY VOLUME 100 Mar 03 18:55:17 volumio go-librespot[1287]: time="2025-03-03T18:55:17+02:00" level=trace msg="sent dealer ping" Mar 03 18:55:18 volumio go-librespot[1287]: time="2025-03-03T18:55:18+02:00" level=trace msg="received dealer pong" Mar 03 18:55:18 volumio volumio[1004]: info: TidalConnect service stoped! Mar 03 18:55:18 volumio volumio[1004]: info: TidalConnect service stoped! Mar 03 18:55:18 volumio volumio[1004]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Mar 03 18:55:18 volumio volumio[1004]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Mar 03 18:55:18 volumio sudo[22832]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Mar 03 18:55:18 volumio sudo[22832]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 18:55:18 volumio systemd[1]: Started Volumio Tidal Connect Service. Mar 03 18:55:18 volumio sudo[22832]: pam_unix(sudo:session): session closed for user root Mar 03 18:55:18 volumio volumio[1004]: info: Executing endpoint tc_getconfig Mar 03 18:55:18 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Mar 03 18:55:18 volumio vtcs[22834]: STARTING TidalConnect services, version: 1.4.0.34 Mar 03 18:55:18 volumio vtcs[22834]: STARTED TidalConnect services. Mar 03 18:55:18 volumio volumio[1004]: info: Executing endpoint tc_connect Mar 03 18:55:18 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Mar 03 18:55:18 volumio volumio[1004]: info: Connecting to TidalConnect Mar 03 18:55:18 volumio volumio[1004]: info: CoreCommandRouter::servicePushState Mar 03 18:55:18 volumio volumio[1004]: info: CoreStateMachine::pushState Mar 03 18:55:18 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Mar 03 18:55:18 volumio volumio[1004]: info: CorePlayQueue::getTrack 0 Mar 03 18:55:18 volumio volumio[1004]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Mar 03 18:55:18 volumio volumio[1004]: verbose: CURRENT POSITION 0 Mar 03 18:55:18 volumio volumio[1004]: info: CoreStateMachine::syncState stateService stop Mar 03 18:55:18 volumio volumio[1004]: info: CoreStateMachine::syncState currentStatus pause Mar 03 18:55:18 volumio volumio[1004]: info: CoreStateMachine::pushState Mar 03 18:55:18 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Mar 03 18:55:18 volumio volumio[1004]: info: CoreCommandRouter::servicePushState Mar 03 18:55:18 volumio volumio[1004]: info: CoreStateMachine::pushState Mar 03 18:55:18 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Mar 03 18:55:18 volumio volumio[1004]: info: CorePlayQueue::getTrack 0 Mar 03 18:55:18 volumio volumio[1004]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Mar 03 18:55:18 volumio volumio[1004]: verbose: CURRENT POSITION 0 Mar 03 18:55:18 volumio volumio[1004]: info: CoreStateMachine::syncState stateService stop Mar 03 18:55:18 volumio volumio[1004]: info: CoreStateMachine::syncState currentStatus pause Mar 03 18:55:18 volumio volumio[1004]: info: CoreStateMachine::pushState Mar 03 18:55:18 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Mar 03 18:55:18 volumio volumio[1004]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 03 18:55:18 volumio volumio[1004]: SPOTIFY: SPOTIFY VOLUME 100 Mar 03 18:55:18 volumio volumio[1004]: SPOTIFY: VOLUMIO VOLUME 100 Mar 03 18:55:18 volumio volumio[1004]: SPOTIFY: DELTA VOLUME ENOUGH: false Mar 03 18:55:18 volumio volumio[1004]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 03 18:55:18 volumio volumio[1004]: SPOTIFY: SPOTIFY VOLUME 100 Mar 03 18:55:18 volumio volumio[1004]: SPOTIFY: VOLUMIO VOLUME 100 Mar 03 18:55:18 volumio volumio[1004]: SPOTIFY: DELTA VOLUME ENOUGH: false Mar 03 18:55:18 volumio volumio[1004]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 03 18:55:18 volumio volumio[1004]: SPOTIFY: SPOTIFY VOLUME 100 Mar 03 18:55:18 volumio volumio[1004]: SPOTIFY: VOLUMIO VOLUME 100 Mar 03 18:55:18 volumio volumio[1004]: SPOTIFY: DELTA VOLUME ENOUGH: false Mar 03 18:55:18 volumio volumio[1004]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 03 18:55:18 volumio volumio[1004]: SPOTIFY: SPOTIFY VOLUME 100 Mar 03 18:55:18 volumio volumio[1004]: SPOTIFY: VOLUMIO VOLUME 100 Mar 03 18:55:18 volumio volumio[1004]: SPOTIFY: DELTA VOLUME ENOUGH: false Mar 03 18:55:19 volumio volumio[1004]: info: VolumeController::SetAlsaVolume32 Mar 03 18:55:19 volumio volumio[1004]: info: CoreStateMachine::pushState Mar 03 18:55:19 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 03 18:55:19 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Mar 03 18:55:19 volumio volumio[1004]: SPOTIFY: RECEIVED VOLUMIO VOLUME 32 Mar 03 18:55:19 volumio volumio[1004]: SPOTIFY: SPOTIFY VOLUME 100 Mar 03 18:55:19 volumio volumio[1004]: SPOTIFY: VOLUMIO VOLUME 32 Mar 03 18:55:19 volumio volumio[1004]: SPOTIFY: DELTA VOLUME ENOUGH: true Mar 03 18:55:19 volumio volumio[1004]: info: Setting Spotify Volume from Volumio: 32 Mar 03 18:55:21 volumio volumio[1004]: SPOTIFY: SETTING SPOTIFY VOLUME 32 Mar 03 18:55:21 volumio volumio[1004]: info: Sending Spotify command with payload to local API: /player/volume Mar 03 18:55:21 volumio go-librespot[1287]: time="2025-03-03T18:55:21+02:00" level=debug msg="update volume to 20971/65535" Mar 03 18:55:21 volumio volumio[1004]: info: TidalConnect service started! Mar 03 18:55:21 volumio volumio[1004]: info: VolumeController::SetAlsaVolume33 Mar 03 18:55:21 volumio volumio[1004]: info: CoreStateMachine::pushState Mar 03 18:55:21 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 03 18:55:21 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Mar 03 18:55:21 volumio volumio[1004]: SPOTIFY: RECEIVED VOLUMIO VOLUME 33 Mar 03 18:55:21 volumio volumio[1004]: SPOTIFY: SPOTIFY VOLUME 32 Mar 03 18:55:21 volumio volumio[1004]: SPOTIFY: VOLUMIO VOLUME 33 Mar 03 18:55:21 volumio volumio[1004]: SPOTIFY: DELTA VOLUME ENOUGH: false Mar 03 18:55:22 volumio go-librespot[1287]: time="2025-03-03T18:55:22+02:00" level=debug msg="put connect state because VOLUME_CHANGED" Mar 03 18:55:22 volumio go-librespot[1287]: time="2025-03-03T18:55:22+02:00" level=trace msg="emitting websocket event: volume" Mar 03 18:55:22 volumio volumio[1004]: SPOTIFY: received: {"type":"volume","data":{"value":32,"max":100}} Mar 03 18:55:22 volumio volumio[1004]: SPOTIFY: RECEIVED SPOTIFY VOLUME 32 Mar 03 18:55:22 volumio volumio[1004]: info: VolumeController::SetAlsaVolume43 Mar 03 18:55:22 volumio volumio[1004]: info: CoreStateMachine::pushState Mar 03 18:55:22 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 03 18:55:22 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Mar 03 18:55:22 volumio volumio[1004]: SPOTIFY: RECEIVED VOLUMIO VOLUME 43 Mar 03 18:55:22 volumio volumio[1004]: SPOTIFY: SPOTIFY VOLUME 32 Mar 03 18:55:22 volumio volumio[1004]: SPOTIFY: VOLUMIO VOLUME 43 Mar 03 18:55:22 volumio volumio[1004]: SPOTIFY: DELTA VOLUME ENOUGH: true Mar 03 18:55:22 volumio volumio[1004]: info: Setting Spotify Volume from Volumio: 43 Mar 03 18:55:23 volumio volumio[1004]: info: VolumeController::SetAlsaVolume- Mar 03 18:55:23 volumio volumio[1004]: info: CoreStateMachine::pushState Mar 03 18:55:23 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 03 18:55:23 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Mar 03 18:55:23 volumio volumio[1004]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Mar 03 18:55:23 volumio volumio[1004]: SPOTIFY: SPOTIFY VOLUME 43 Mar 03 18:55:23 volumio volumio[1004]: SPOTIFY: VOLUMIO VOLUME 41 Mar 03 18:55:23 volumio volumio[1004]: SPOTIFY: DELTA VOLUME ENOUGH: true Mar 03 18:55:23 volumio volumio[1004]: info: Setting Spotify Volume from Volumio: 41 Mar 03 18:55:23 volumio volumio[1004]: info: VolumeController::SetAlsaVolume- Mar 03 18:55:23 volumio volumio[1004]: info: CoreStateMachine::pushState Mar 03 18:55:23 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 03 18:55:23 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Mar 03 18:55:23 volumio volumio[1004]: SPOTIFY: RECEIVED VOLUMIO VOLUME 39 Mar 03 18:55:23 volumio volumio[1004]: SPOTIFY: SPOTIFY VOLUME 41 Mar 03 18:55:23 volumio volumio[1004]: SPOTIFY: VOLUMIO VOLUME 39 Mar 03 18:55:23 volumio volumio[1004]: SPOTIFY: DELTA VOLUME ENOUGH: true Mar 03 18:55:23 volumio volumio[1004]: info: Setting Spotify Volume from Volumio: 39 Mar 03 18:55:24 volumio volumio[1004]: info: VolumeController::SetAlsaVolume- Mar 03 18:55:24 volumio volumio[1004]: info: CoreStateMachine::pushState Mar 03 18:55:24 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 03 18:55:24 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Mar 03 18:55:24 volumio volumio[1004]: SPOTIFY: RECEIVED VOLUMIO VOLUME 37 Mar 03 18:55:24 volumio volumio[1004]: SPOTIFY: SPOTIFY VOLUME 39 Mar 03 18:55:24 volumio volumio[1004]: SPOTIFY: VOLUMIO VOLUME 37 Mar 03 18:55:24 volumio volumio[1004]: SPOTIFY: DELTA VOLUME ENOUGH: true Mar 03 18:55:24 volumio volumio[1004]: info: Setting Spotify Volume from Volumio: 37 Mar 03 18:55:24 volumio volumio[1004]: info: VolumeController::SetAlsaVolume- Mar 03 18:55:24 volumio volumio[1004]: info: CoreStateMachine::pushState Mar 03 18:55:24 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 03 18:55:24 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Mar 03 18:55:24 volumio volumio[1004]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Mar 03 18:55:24 volumio volumio[1004]: SPOTIFY: SPOTIFY VOLUME 37 Mar 03 18:55:24 volumio volumio[1004]: SPOTIFY: VOLUMIO VOLUME 35 Mar 03 18:55:24 volumio volumio[1004]: SPOTIFY: DELTA VOLUME ENOUGH: true Mar 03 18:55:24 volumio volumio[1004]: info: Setting Spotify Volume from Volumio: 35 Mar 03 18:55:24 volumio volumio[1004]: info: VolumeController::SetAlsaVolume- Mar 03 18:55:24 volumio volumio[1004]: info: CoreStateMachine::pushState Mar 03 18:55:24 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 03 18:55:24 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Mar 03 18:55:24 volumio volumio[1004]: SPOTIFY: RECEIVED VOLUMIO VOLUME 33 Mar 03 18:55:24 volumio volumio[1004]: SPOTIFY: SPOTIFY VOLUME 35 Mar 03 18:55:24 volumio volumio[1004]: SPOTIFY: VOLUMIO VOLUME 33 Mar 03 18:55:24 volumio volumio[1004]: SPOTIFY: DELTA VOLUME ENOUGH: true Mar 03 18:55:24 volumio volumio[1004]: info: Setting Spotify Volume from Volumio: 33 Mar 03 18:55:26 volumio volumio[1004]: SPOTIFY: SETTING SPOTIFY VOLUME 33 Mar 03 18:55:26 volumio volumio[1004]: info: Sending Spotify command with payload to local API: /player/volume Mar 03 18:55:26 volumio go-librespot[1287]: time="2025-03-03T18:55:26+02:00" level=debug msg="update volume to 21626/65535" Mar 03 18:55:26 volumio volumio[1004]: info: VolumeController::SetAlsaVolume18 Mar 03 18:55:26 volumio volumio[1004]: info: CoreStateMachine::pushState Mar 03 18:55:26 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 03 18:55:26 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Mar 03 18:55:26 volumio volumio[1004]: SPOTIFY: RECEIVED VOLUMIO VOLUME 18 Mar 03 18:55:26 volumio volumio[1004]: SPOTIFY: SPOTIFY VOLUME 33 Mar 03 18:55:26 volumio volumio[1004]: SPOTIFY: VOLUMIO VOLUME 18 Mar 03 18:55:26 volumio volumio[1004]: SPOTIFY: DELTA VOLUME ENOUGH: true Mar 03 18:55:26 volumio volumio[1004]: info: Setting Spotify Volume from Volumio: 18 Mar 03 18:55:27 volumio go-librespot[1287]: time="2025-03-03T18:55:27+02:00" level=debug msg="put connect state because VOLUME_CHANGED" Mar 03 18:55:27 volumio go-librespot[1287]: time="2025-03-03T18:55:27+02:00" level=trace msg="emitting websocket event: volume" Mar 03 18:55:27 volumio volumio[1004]: SPOTIFY: received: {"type":"volume","data":{"value":33,"max":100}} Mar 03 18:55:27 volumio volumio[1004]: SPOTIFY: RECEIVED SPOTIFY VOLUME 33 Mar 03 18:55:27 volumio volumio[1004]: info: Setting Volumio Volume from Spotify: 33 Mar 03 18:55:27 volumio volumio[1004]: info: VolumeController::SetAlsaVolume33 Mar 03 18:55:27 volumio volumio[1004]: info: CoreStateMachine::pushState Mar 03 18:55:27 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 03 18:55:27 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Mar 03 18:55:27 volumio volumio[1004]: SPOTIFY: RECEIVED VOLUMIO VOLUME 33 Mar 03 18:55:28 volumio volumio[1004]: SPOTIFY: SETTING SPOTIFY VOLUME 18 Mar 03 18:55:28 volumio volumio[1004]: info: Sending Spotify command with payload to local API: /player/volume Mar 03 18:55:28 volumio go-librespot[1287]: time="2025-03-03T18:55:28+02:00" level=debug msg="update volume to 11796/65535" Mar 03 18:55:29 volumio go-librespot[1287]: time="2025-03-03T18:55:29+02:00" level=debug msg="put connect state because VOLUME_CHANGED" Mar 03 18:55:29 volumio go-librespot[1287]: time="2025-03-03T18:55:29+02:00" level=trace msg="emitting websocket event: volume" Mar 03 18:55:29 volumio volumio[1004]: SPOTIFY: received: {"type":"volume","data":{"value":18,"max":100}} Mar 03 18:55:29 volumio volumio[1004]: SPOTIFY: RECEIVED SPOTIFY VOLUME 18 Mar 03 18:55:29 volumio volumio[1004]: info: Setting Volumio Volume from Spotify: 18 Mar 03 18:55:29 volumio volumio[1004]: info: VolumeController::SetAlsaVolume18 Mar 03 18:55:29 volumio volumio[1004]: info: CoreStateMachine::pushState Mar 03 18:55:29 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 03 18:55:29 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Mar 03 18:55:29 volumio volumio[1004]: SPOTIFY: RECEIVED VOLUMIO VOLUME 18 Mar 03 18:55:29 volumio volumio[1004]: info: CoreCommandRouter::volumioVolatilePlay Mar 03 18:55:29 volumio volumio[1004]: info: CoreStateMachine::volatilePlay Mar 03 18:55:29 volumio volumio[1004]: info: WARNING: No play method for volatile plugin undefined Mar 03 18:55:31 volumio volumio[1004]: info: CoreCommandRouter::volumioVolatilePlay Mar 03 18:55:31 volumio volumio[1004]: info: CoreStateMachine::volatilePlay Mar 03 18:55:31 volumio volumio[1004]: info: WARNING: No play method for volatile plugin undefined Mar 03 18:55:33 volumio volumio[1004]: info: CoreCommandRouter::volumioVolatilePlay Mar 03 18:55:33 volumio volumio[1004]: info: CoreStateMachine::volatilePlay Mar 03 18:55:33 volumio volumio[1004]: info: WARNING: No play method for volatile plugin undefined Mar 03 18:55:36 volumio volumio[1004]: info: CoreCommandRouter::volumioGetQueue Mar 03 18:55:36 volumio volumio[1004]: info: CoreStateMachine::getQueue Mar 03 18:55:36 volumio volumio[1004]: info: CorePlayQueue::getQueue Mar 03 18:55:46 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 03 18:55:46 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 03 18:55:46 volumio volumio[1004]: info: Discovery: Getting this device information Mar 03 18:55:46 volumio volumio[1004]: info: CoreCommandRouter::volumioGetState Mar 03 18:55:46 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 03 18:55:47 volumio go-librespot[1287]: time="2025-03-03T18:55:47+02:00" level=trace msg="sent dealer ping" Mar 03 18:55:48 volumio go-librespot[1287]: time="2025-03-03T18:55:48+02:00" level=trace msg="received dealer pong" Mar 03 18:55:49 volumio volumio[1004]: info: CoreCommandRouter::volumioVolatilePlay Mar 03 18:55:49 volumio volumio[1004]: info: CoreStateMachine::volatilePlay Mar 03 18:55:49 volumio volumio[1004]: info: WARNING: No play method for volatile plugin undefined Mar 03 18:55:55 volumio volumio[1004]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 03 18:55:55 volumio volumio[1004]: info: Retrieving Cloud Streaming UI Mar 03 18:55:55 volumio volumio[1004]: info: Getting Tidal Cloud Configuration Mar 03 18:55:55 volumio volumio[1004]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 03 18:55:55 volumio volumio[1004]: info: Getting Qobuz Cloud Configuration Mar 03 18:55:55 volumio volumio[1004]: info: Asking plugin for UI Config Mar 03 18:55:55 volumio volumio[1004]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 03 18:55:55 volumio volumio[1004]: info: Getting Spotify Cloud Configuration Mar 03 18:55:55 volumio volumio[1004]: info: Asking plugin for UI Config Mar 03 18:55:55 volumio volumio[1004]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 03 18:55:55 volumio volumio[1004]: info: Saving Spotify Acccount Mar 03 18:55:55 volumio volumio[1004]: info: Got it Mar 03 18:55:55 volumio volumio[1004]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Mar 03 18:55:55 volumio volumio[1004]: info: Got Tidal Cloud Configuration Mar 03 18:55:55 volumio volumio[1004]: info: Got it Mar 03 18:55:55 volumio volumio[1004]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 03 18:55:56 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Mar 03 18:55:56 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Mar 03 18:55:56 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Mar 03 18:55:56 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Mar 03 18:55:56 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 03 18:55:56 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 03 18:55:56 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 03 18:55:56 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 03 18:55:56 volumio volumio[1004]: info: CoreCommandRouter::volumioGetBrowseSources Mar 03 18:55:56 volumio volumio[1004]: info: CoreCommandRouter::volumioGetBrowseSources Mar 03 18:55:56 volumio volumio[1004]: info: CoreCommandRouter::volumioGetBrowseSources Mar 03 18:55:56 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 03 18:55:56 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Mar 03 18:56:00 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 03 18:56:02 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Mar 03 18:56:02 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Mar 03 18:56:02 volumio volumio[1004]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 03 18:56:02 volumio volumio[1004]: Error: getaddrinfo -3007 Mar 03 18:56:02 volumio volumio[1004]: at errnoException (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:199:11) Mar 03 18:56:02 volumio volumio[1004]: at getaddrinfo_complete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:112:10) Mar 03 18:56:02 volumio volumio[1004]: at GetAddrInfoReqWrap.oncomplete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:120:9) { Mar 03 18:56:02 volumio volumio[1004]: code: -3007, Mar 03 18:56:02 volumio volumio[1004]: errno: -3007, Mar 03 18:56:02 volumio volumio[1004]: syscall: 'getaddrinfo' Mar 03 18:56:02 volumio volumio[1004]: } Mar 03 18:56:02 volumio volumio[1004]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 03 18:56:02 volumio sudo[22960]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-03-03 18:55 Mar 03 18:56:02 volumio sudo[22960]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="f6610a1100d2380eef33bd3004af43153c786f96" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="1c0e5daee99fe3a72cc38b0d1fe6cd78d7f001fe" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 16 Dec 2024 09:30:04 AM CET" VOLUMIO_VERSION="3.785" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fe0ac4b79aed19ccde4b5bd83d468193"