-- Logs begin at Thu 2019-02-14 04:11:58 CST, end at Mon 2025-06-02 20:54:44 CDT. -- Jun 02 20:53:01 volumio-living volumio[940]: info: CALLMETHOD: music_service mpd savePlaybackOptions [object Object] Jun 02 20:53:01 volumio-living volumio[940]: info: CoreCommandRouter::executeOnPlugin: mpd , savePlaybackOptions Jun 02 20:53:01 volumio-living sudo[3293]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 02 20:53:01 volumio-living sudo[3293]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 20:53:01 volumio-living sudo[3293]: pam_unix(sudo:session): session closed for user root Jun 02 20:53:01 volumio-living volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 20:53:01 volumio-living volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 20:53:01 volumio-living volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 20:53:01 volumio-living volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 20:53:01 volumio-living volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 20:53:01 volumio-living volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 20:53:01 volumio-living volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 20:53:01 volumio-living sudo[3296]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 02 20:53:01 volumio-living sudo[3296]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 20:53:01 volumio-living volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 02 20:53:01 volumio-living volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 20:53:01 volumio-living volumio[940]: info: MPD Permissions set Jun 02 20:53:01 volumio-living systemd[1]: Stopping Music Player Daemon... Jun 02 20:53:01 volumio-living volumio[940]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 02 20:53:01 volumio-living systemd[1]: mpd.service: Succeeded. Jun 02 20:53:01 volumio-living systemd[1]: Stopped Music Player Daemon. Jun 02 20:53:01 volumio-living volumio[940]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jun 02 20:53:01 volumio-living systemd[1]: Starting Music Player Daemon... Jun 02 20:53:01 volumio-living volumio[940]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 02 20:53:01 volumio-living sudo[3302]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 02 20:53:01 volumio-living sudo[3302]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 20:53:01 volumio-living sudo[3302]: pam_unix(sudo:session): session closed for user root Jun 02 20:53:02 volumio-living mpd[3305]: Jun 02 20:53 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 02 20:53:02 volumio-living systemd[1]: Started Music Player Daemon. Jun 02 20:53:02 volumio-living sudo[3296]: pam_unix(sudo:session): session closed for user root Jun 02 20:53:02 volumio-living volumio[940]: error: updateQueue error: null Jun 02 20:53:15 volumio-living volumio[940]: info: CALLMETHOD: audio_interface alsa_controller saveVolumeOptions [object Object] Jun 02 20:53:15 volumio-living volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveVolumeOptions Jun 02 20:53:15 volumio-living volumio[940]: info: CoreCommandRouter::volumioGetState Jun 02 20:53:15 volumio-living volumio[940]: info: CorePlayQueue::getTrack 0 Jun 02 20:53:15 volumio-living volumio[940]: info: Restoring Previous Volume level: 86 false false Jun 02 20:53:15 volumio-living volumio[940]: info: Volume configurations have been set Jun 02 20:53:15 volumio-living volumio[940]: info: QobuzConnect: setDeactiveState invoked Jun 02 20:53:15 volumio-living volumio[940]: info: CoreCommandRouter::volumioGetState Jun 02 20:53:15 volumio-living volumio[940]: info: CorePlayQueue::getTrack 0 Jun 02 20:53:15 volumio-living vtcs[3183]: [2025-06-02 20:53:15.818] [tisoc] [error] [SpkconServer.cpp:376] recv error. socket disconnected Jun 02 20:53:15 volumio-living vtcs[3183]: [2025-06-02 20:53:15.819] [tisoc] [warning] [SessionManagerImpl.cpp:243] Illegal State: IDLE Jun 02 20:53:15 volumio-living sudo[3379]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jun 02 20:53:15 volumio-living sudo[3379]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 20:53:15 volumio-living systemd[1]: Stopping Volumio Tidal Connect Service... Jun 02 20:53:15 volumio-living systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Jun 02 20:53:15 volumio-living systemd[1]: vtcs.service: Succeeded. Jun 02 20:53:15 volumio-living systemd[1]: Stopped Volumio Tidal Connect Service. Jun 02 20:53:15 volumio-living sudo[3379]: pam_unix(sudo:session): session closed for user root Jun 02 20:53:15 volumio-living volumio[940]: xcb_connection_has_error() returned true Jun 02 20:53:15 volumio-living volumio[940]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jun 02 20:53:15 volumio-living volumio[940]: info: Updating Volume Controller Parameters: Device: 1 Name: Headphones Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Jun 02 20:53:15 volumio-living volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Jun 02 20:53:15 volumio-living volumio[940]: info: Disabling external Volume Control Jun 02 20:53:15 volumio-living volumio[940]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:53:15 volumio-living sudo[3386]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jun 02 20:53:15 volumio-living sudo[3386]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 20:53:15 volumio-living sudo[3386]: pam_unix(sudo:session): session closed for user root Jun 02 20:53:15 volumio-living volumio[940]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 02 20:53:15 volumio-living sudo[3392]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Jun 02 20:53:15 volumio-living sudo[3392]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 20:53:15 volumio-living sudo[3392]: pam_unix(sudo:session): session closed for user root Jun 02 20:53:16 volumio-living sudo[3396]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Jun 02 20:53:16 volumio-living sudo[3396]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 20:53:16 volumio-living systemd[1]: Stopping Volumio Qobuz Connect Service... Jun 02 20:53:16 volumio-living qobuz-connect[3167]: 20250602 20:53:16.021 [3167.3167] INFO SampleApp: Stopping Local configuration server Jun 02 20:53:16 volumio-living volumio[940]: xcb_connection_has_error() returned true Jun 02 20:53:16 volumio-living volumio[940]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jun 02 20:53:16 volumio-living volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 02 20:53:16 volumio-living volumio[940]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 02 20:53:16 volumio-living qobuz-connect[3167]: 20250602 20:53:16.032 [3167.3167] INFO SampleApp: shat down connection on UNIX socket Jun 02 20:53:16 volumio-living systemd[1]: qobuz-connect.service: Succeeded. Jun 02 20:53:16 volumio-living systemd[1]: Stopped Volumio Qobuz Connect Service. Jun 02 20:53:16 volumio-living volumio[940]: xcb_connection_has_error() returned true Jun 02 20:53:16 volumio-living volumio[940]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 02 20:53:16 volumio-living volumio[940]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jun 02 20:53:16 volumio-living volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jun 02 20:53:16 volumio-living systemd[1]: Started Volumio Qobuz Connect Service. Jun 02 20:53:16 volumio-living volumio[940]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 02 20:53:16 volumio-living sudo[3396]: pam_unix(sudo:session): session closed for user root Jun 02 20:53:16 volumio-living volumio[940]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jun 02 20:53:16 volumio-living volumio[940]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Jun 02 20:53:16 volumio-living volumio[940]: info: QobuzConnect: setDeactiveState invoked Jun 02 20:53:16 volumio-living volumio[940]: info: CoreCommandRouter::volumioGetState Jun 02 20:53:16 volumio-living volumio[940]: info: CorePlayQueue::getTrack 0 Jun 02 20:53:16 volumio-living volumio[940]: info: VolumeController:: Volume=86 Mute =false Jun 02 20:53:16 volumio-living volumio[940]: info: CoreStateMachine::pushState Jun 02 20:53:16 volumio-living volumio[940]: info: CorePlayQueue::getTrack 0 Jun 02 20:53:16 volumio-living volumio[940]: info: CoreCommandRouter::volumioPushState Jun 02 20:53:16 volumio-living volumio[940]: info: MRS: Pushing multiroomSync output update for this device Jun 02 20:53:16 volumio-living volumio[940]: info: MRS: Pushing multiroomSync output Jun 02 20:53:16 volumio-living volumio[940]: info: CoreCommandRouter::volumioGetState Jun 02 20:53:16 volumio-living volumio[940]: info: CorePlayQueue::getTrack 0 Jun 02 20:53:16 volumio-living volumio[940]: info: Executing endpoint qc_getconfig Jun 02 20:53:16 volumio-living volumio[940]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Jun 02 20:53:16 volumio-living qobuz-connect[3401]: 20250602 20:53:16.115 [3401.3401] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Jun 02 20:53:16 volumio-living volumio[940]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Jun 02 20:53:16 volumio-living volumio[940]: info: QobuzConnect: QOBUZ Connect daemon connected Jun 02 20:53:16 volumio-living qobuz-connect[3401]: 20250602 20:53:16.119 [3401.3401] INFO VolumeManager: [0x2405c58]: Setting new playback volume: 75 Jun 02 20:53:16 volumio-living qobuz-connect[3401]: 20250602 20:53:16.119 [3401.3401] INFO VolumeManager: [0x2405c58]: Setting new mute state: 0 Jun 02 20:53:16 volumio-living qobuz-connect[3401]: 20250602 20:53:16.119 [3401.3401] INFO QobuzConnect: [0x2406628]: Client initialized! Jun 02 20:53:16 volumio-living qobuz-connect[3401]: 20250602 20:53:16.119 [3401.3401] INFO SampleApp: Starting Avahi advertising, name: Volumio-Living, service name: _qobuz-connect._tcp Jun 02 20:53:16 volumio-living qobuz-connect[3401]: 20250602 20:53:16.125 [3401.3401] INFO LocalConfigManager: [0x2405538]: Starting Local Configuration server Jun 02 20:53:16 volumio-living qobuz-connect[3401]: 20250602 20:53:16.125 [3401.3401] INFO SampleApp: Starting Local configuration server Jun 02 20:53:16 volumio-living qobuz-connect[3401]: 20250602 20:53:16.125 [3401.3401] INFO SampleApp: Connected to UNIX socket client 0x23faf60 Jun 02 20:53:16 volumio-living qobuz-connect[3401]: 20250602 20:53:16.265 [3401.3401] INFO SampleApp: Playback volume changed: 75 Jun 02 20:53:16 volumio-living volumio[940]: info: CoreCommandRouter::volumioGetState Jun 02 20:53:16 volumio-living volumio[940]: info: CorePlayQueue::getTrack 0 Jun 02 20:53:18 volumio-living volumio[940]: info: TidalConnect service stoped! Jun 02 20:53:18 volumio-living volumio[940]: info: TidalConnect service stoped! Jun 02 20:53:18 volumio-living volumio[940]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Jun 02 20:53:18 volumio-living volumio[940]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Jun 02 20:53:19 volumio-living sudo[3415]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Jun 02 20:53:19 volumio-living sudo[3415]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 20:53:19 volumio-living systemd[1]: Started Volumio Tidal Connect Service. Jun 02 20:53:19 volumio-living sudo[3415]: pam_unix(sudo:session): session closed for user root Jun 02 20:53:19 volumio-living volumio[940]: info: Executing endpoint tc_getconfig Jun 02 20:53:19 volumio-living volumio[940]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Jun 02 20:53:19 volumio-living vtcs[3417]: STARTING TidalConnect services, version: 1.4.0.34 Jun 02 20:53:19 volumio-living vtcs[3417]: STARTED TidalConnect services. Jun 02 20:53:19 volumio-living volumio[940]: info: Executing endpoint tc_connect Jun 02 20:53:19 volumio-living volumio[940]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Jun 02 20:53:19 volumio-living volumio[940]: info: Connecting to TidalConnect Jun 02 20:53:19 volumio-living volumio[940]: info: CoreCommandRouter::servicePushState Jun 02 20:53:19 volumio-living volumio[940]: info: CoreStateMachine::pushState Jun 02 20:53:19 volumio-living volumio[940]: info: CorePlayQueue::getTrack 0 Jun 02 20:53:19 volumio-living volumio[940]: info: CoreCommandRouter::volumioPushState Jun 02 20:53:19 volumio-living volumio[940]: info: MRS: Pushing multiroomSync output update for this device Jun 02 20:53:19 volumio-living volumio[940]: info: MRS: Pushing multiroomSync output Jun 02 20:53:19 volumio-living volumio[940]: info: CoreCommandRouter::volumioGetState Jun 02 20:53:19 volumio-living volumio[940]: info: CorePlayQueue::getTrack 0 Jun 02 20:53:19 volumio-living volumio[940]: info: CorePlayQueue::getTrack 0 Jun 02 20:53:19 volumio-living volumio[940]: 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} Jun 02 20:53:19 volumio-living volumio[940]: verbose: CURRENT POSITION 0 Jun 02 20:53:19 volumio-living volumio[940]: info: CoreStateMachine::syncState stateService stop Jun 02 20:53:19 volumio-living volumio[940]: info: CoreStateMachine::syncState currentStatus stop Jun 02 20:53:19 volumio-living volumio[940]: info: CoreStateMachine::pushState Jun 02 20:53:19 volumio-living volumio[940]: info: CorePlayQueue::getTrack 0 Jun 02 20:53:19 volumio-living volumio[940]: info: CoreCommandRouter::volumioPushState Jun 02 20:53:19 volumio-living volumio[940]: info: MRS: Pushing multiroomSync output update for this device Jun 02 20:53:19 volumio-living volumio[940]: info: MRS: Pushing multiroomSync output Jun 02 20:53:19 volumio-living volumio[940]: info: CoreCommandRouter::volumioGetState Jun 02 20:53:19 volumio-living volumio[940]: info: CorePlayQueue::getTrack 0 Jun 02 20:53:19 volumio-living volumio[940]: info: No code Jun 02 20:53:19 volumio-living volumio[940]: info: CoreStateMachine::pushState Jun 02 20:53:19 volumio-living volumio[940]: info: CorePlayQueue::getTrack 0 Jun 02 20:53:19 volumio-living volumio[940]: info: CoreCommandRouter::volumioPushState Jun 02 20:53:19 volumio-living volumio[940]: info: MRS: Pushing multiroomSync output update for this device Jun 02 20:53:19 volumio-living volumio[940]: info: MRS: Pushing multiroomSync output Jun 02 20:53:19 volumio-living volumio[940]: info: CoreCommandRouter::volumioGetState Jun 02 20:53:19 volumio-living volumio[940]: info: CorePlayQueue::getTrack 0 Jun 02 20:53:19 volumio-living volumio[940]: info: CoreCommandRouter::servicePushState Jun 02 20:53:19 volumio-living volumio[940]: info: CoreStateMachine::pushState Jun 02 20:53:19 volumio-living volumio[940]: info: CorePlayQueue::getTrack 0 Jun 02 20:53:19 volumio-living volumio[940]: info: CoreCommandRouter::volumioPushState Jun 02 20:53:19 volumio-living volumio[940]: info: MRS: Pushing multiroomSync output update for this device Jun 02 20:53:19 volumio-living volumio[940]: info: MRS: Pushing multiroomSync output Jun 02 20:53:19 volumio-living volumio[940]: info: CoreCommandRouter::volumioGetState Jun 02 20:53:19 volumio-living volumio[940]: info: CorePlayQueue::getTrack 0 Jun 02 20:53:19 volumio-living volumio[940]: info: CorePlayQueue::getTrack 0 Jun 02 20:53:19 volumio-living volumio[940]: 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} Jun 02 20:53:19 volumio-living volumio[940]: verbose: CURRENT POSITION 0 Jun 02 20:53:19 volumio-living volumio[940]: info: CoreStateMachine::syncState stateService stop Jun 02 20:53:19 volumio-living volumio[940]: info: CoreStateMachine::syncState currentStatus stop Jun 02 20:53:19 volumio-living volumio[940]: info: CoreStateMachine::pushState Jun 02 20:53:19 volumio-living volumio[940]: info: CorePlayQueue::getTrack 0 Jun 02 20:53:19 volumio-living volumio[940]: info: CoreCommandRouter::volumioPushState Jun 02 20:53:19 volumio-living volumio[940]: info: MRS: Pushing multiroomSync output update for this device Jun 02 20:53:19 volumio-living volumio[940]: info: MRS: Pushing multiroomSync output Jun 02 20:53:19 volumio-living volumio[940]: info: CoreCommandRouter::volumioGetState Jun 02 20:53:19 volumio-living volumio[940]: info: CorePlayQueue::getTrack 0 Jun 02 20:53:19 volumio-living volumio[940]: info: No code Jun 02 20:53:19 volumio-living volumio[940]: info: CoreStateMachine::pushState Jun 02 20:53:19 volumio-living volumio[940]: info: CorePlayQueue::getTrack 0 Jun 02 20:53:19 volumio-living volumio[940]: info: CoreCommandRouter::volumioPushState Jun 02 20:53:19 volumio-living volumio[940]: info: MRS: Pushing multiroomSync output update for this device Jun 02 20:53:19 volumio-living volumio[940]: info: MRS: Pushing multiroomSync output Jun 02 20:53:19 volumio-living volumio[940]: info: CoreCommandRouter::volumioGetState Jun 02 20:53:19 volumio-living volumio[940]: info: CorePlayQueue::getTrack 0 Jun 02 20:53:20 volumio-living volumio[940]: info: VolumeController::SetAlsaVolume86 Jun 02 20:53:20 volumio-living volumio[940]: info: CoreStateMachine::pushState Jun 02 20:53:20 volumio-living volumio[940]: info: CorePlayQueue::getTrack 0 Jun 02 20:53:20 volumio-living volumio[940]: info: CoreCommandRouter::volumioPushState Jun 02 20:53:20 volumio-living volumio[940]: info: MRS: Pushing multiroomSync output update for this device Jun 02 20:53:20 volumio-living volumio[940]: info: MRS: Pushing multiroomSync output Jun 02 20:53:20 volumio-living volumio[940]: info: CoreCommandRouter::volumioGetState Jun 02 20:53:20 volumio-living volumio[940]: info: CorePlayQueue::getTrack 0 Jun 02 20:53:22 volumio-living volumio[940]: info: TidalConnect service started! Jun 02 20:53:27 volumio-living volumio[940]: info: CALLMETHOD: audio_interface alsa_controller saveVolumeOptions [object Object] Jun 02 20:53:27 volumio-living volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveVolumeOptions Jun 02 20:53:27 volumio-living volumio[940]: info: CoreCommandRouter::volumioGetState Jun 02 20:53:27 volumio-living volumio[940]: info: CorePlayQueue::getTrack 0 Jun 02 20:53:27 volumio-living volumio[940]: info: Restoring Previous Volume level: 86 false false Jun 02 20:53:27 volumio-living volumio[940]: info: Volume configurations have been set Jun 02 20:53:27 volumio-living volumio[940]: info: QobuzConnect: setDeactiveState invoked Jun 02 20:53:27 volumio-living volumio[940]: info: CoreCommandRouter::volumioGetState Jun 02 20:53:27 volumio-living volumio[940]: info: CorePlayQueue::getTrack 0 Jun 02 20:53:27 volumio-living vtcs[3417]: [2025-06-02 20:53:27.121] [tisoc] [warning] [SessionManagerImpl.cpp:243] Illegal State: IDLE Jun 02 20:53:27 volumio-living vtcs[3417]: [2025-06-02 20:53:27.123] [tisoc] [error] [SpkconServer.cpp:381] recv error. client fd=8 errorno=104 error=Connection reset by peer Jun 02 20:53:27 volumio-living vtcs[3417]: [2025-06-02 20:53:27.123] [tisoc] [error] [SpkconServer.cpp:376] recv error. socket disconnected Jun 02 20:53:27 volumio-living sudo[3452]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jun 02 20:53:27 volumio-living sudo[3452]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 20:53:27 volumio-living systemd[1]: Stopping Volumio Tidal Connect Service... Jun 02 20:53:27 volumio-living systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Jun 02 20:53:27 volumio-living systemd[1]: vtcs.service: Succeeded. Jun 02 20:53:27 volumio-living systemd[1]: Stopped Volumio Tidal Connect Service. Jun 02 20:53:27 volumio-living sudo[3452]: pam_unix(sudo:session): session closed for user root Jun 02 20:53:27 volumio-living volumio[940]: xcb_connection_has_error() returned true Jun 02 20:53:27 volumio-living volumio[940]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jun 02 20:53:27 volumio-living volumio[940]: info: Updating Volume Controller Parameters: Device: 1 Name: Headphones Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Jun 02 20:53:27 volumio-living volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Jun 02 20:53:27 volumio-living volumio[940]: info: Disabling external Volume Control Jun 02 20:53:27 volumio-living volumio[940]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:53:27 volumio-living sudo[3459]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jun 02 20:53:27 volumio-living sudo[3459]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 20:53:27 volumio-living volumio[940]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 02 20:53:27 volumio-living sudo[3459]: pam_unix(sudo:session): session closed for user root Jun 02 20:53:27 volumio-living sudo[3465]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Jun 02 20:53:27 volumio-living sudo[3465]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 20:53:27 volumio-living sudo[3465]: pam_unix(sudo:session): session closed for user root Jun 02 20:53:27 volumio-living sudo[3470]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Jun 02 20:53:27 volumio-living sudo[3470]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 20:53:27 volumio-living systemd[1]: Stopping Volumio Qobuz Connect Service... Jun 02 20:53:27 volumio-living volumio[940]: xcb_connection_has_error() returned true Jun 02 20:53:27 volumio-living qobuz-connect[3401]: 20250602 20:53:27.329 [3401.3401] INFO SampleApp: Stopping Local configuration server Jun 02 20:53:27 volumio-living volumio[940]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jun 02 20:53:27 volumio-living volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 02 20:53:27 volumio-living volumio[940]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 02 20:53:27 volumio-living qobuz-connect[3401]: 20250602 20:53:27.340 [3401.3401] INFO SampleApp: shat down connection on UNIX socket Jun 02 20:53:27 volumio-living systemd[1]: qobuz-connect.service: Succeeded. Jun 02 20:53:27 volumio-living systemd[1]: Stopped Volumio Qobuz Connect Service. Jun 02 20:53:27 volumio-living volumio[940]: xcb_connection_has_error() returned true Jun 02 20:53:27 volumio-living volumio[940]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 02 20:53:27 volumio-living volumio[940]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 02 20:53:27 volumio-living volumio[940]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 02 20:53:27 volumio-living volumio[940]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 02 20:53:27 volumio-living volumio[940]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 02 20:53:27 volumio-living volumio[940]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 02 20:53:27 volumio-living volumio[940]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 02 20:53:27 volumio-living volumio[940]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jun 02 20:53:27 volumio-living volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jun 02 20:53:27 volumio-living volumio[940]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 02 20:53:27 volumio-living volumio[940]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jun 02 20:53:27 volumio-living volumio[940]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Jun 02 20:53:27 volumio-living volumio[940]: info: QobuzConnect: setDeactiveState invoked Jun 02 20:53:27 volumio-living volumio[940]: info: CoreCommandRouter::volumioGetState Jun 02 20:53:27 volumio-living volumio[940]: info: CorePlayQueue::getTrack 0 Jun 02 20:53:27 volumio-living volumio[940]: info: VolumeController:: Volume=86 Mute =false Jun 02 20:53:27 volumio-living volumio[940]: info: CoreStateMachine::pushState Jun 02 20:53:27 volumio-living volumio[940]: info: CorePlayQueue::getTrack 0 Jun 02 20:53:27 volumio-living volumio[940]: info: CoreCommandRouter::volumioPushState Jun 02 20:53:27 volumio-living systemd[1]: Started Volumio Qobuz Connect Service. Jun 02 20:53:27 volumio-living volumio[940]: info: MRS: Pushing multiroomSync output update for this device Jun 02 20:53:27 volumio-living volumio[940]: info: MRS: Pushing multiroomSync output Jun 02 20:53:27 volumio-living volumio[940]: info: CoreCommandRouter::volumioGetState Jun 02 20:53:27 volumio-living volumio[940]: info: CorePlayQueue::getTrack 0 Jun 02 20:53:27 volumio-living sudo[3470]: pam_unix(sudo:session): session closed for user root Jun 02 20:53:27 volumio-living volumio[940]: info: Executing endpoint qc_getconfig Jun 02 20:53:27 volumio-living volumio[940]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Jun 02 20:53:27 volumio-living qobuz-connect[3474]: 20250602 20:53:27.420 [3474.3474] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Jun 02 20:53:27 volumio-living volumio[940]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Jun 02 20:53:27 volumio-living volumio[940]: info: QobuzConnect: QOBUZ Connect daemon connected Jun 02 20:53:27 volumio-living qobuz-connect[3474]: 20250602 20:53:27.424 [3474.3474] INFO VolumeManager: [0x1c12c58]: Setting new playback volume: 75 Jun 02 20:53:27 volumio-living qobuz-connect[3474]: 20250602 20:53:27.424 [3474.3474] INFO VolumeManager: [0x1c12c58]: Setting new mute state: 0 Jun 02 20:53:27 volumio-living qobuz-connect[3474]: 20250602 20:53:27.424 [3474.3474] INFO QobuzConnect: [0x1c13628]: Client initialized! Jun 02 20:53:27 volumio-living qobuz-connect[3474]: 20250602 20:53:27.424 [3474.3474] INFO SampleApp: Starting Avahi advertising, name: Volumio-Living, service name: _qobuz-connect._tcp Jun 02 20:53:27 volumio-living qobuz-connect[3474]: 20250602 20:53:27.430 [3474.3474] INFO LocalConfigManager: [0x1c12538]: Starting Local Configuration server Jun 02 20:53:27 volumio-living qobuz-connect[3474]: 20250602 20:53:27.430 [3474.3474] INFO SampleApp: Starting Local configuration server Jun 02 20:53:27 volumio-living qobuz-connect[3474]: 20250602 20:53:27.430 [3474.3474] INFO SampleApp: Connected to UNIX socket client 0x1c07f60 Jun 02 20:53:27 volumio-living qobuz-connect[3474]: 20250602 20:53:27.570 [3474.3474] INFO SampleApp: Playback volume changed: 75 Jun 02 20:53:27 volumio-living volumio[940]: info: CoreCommandRouter::volumioGetState Jun 02 20:53:27 volumio-living volumio[940]: info: CorePlayQueue::getTrack 0 Jun 02 20:53:30 volumio-living volumio[940]: info: TidalConnect service stoped! Jun 02 20:53:30 volumio-living volumio[940]: info: TidalConnect service stoped! Jun 02 20:53:30 volumio-living volumio[940]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Jun 02 20:53:30 volumio-living volumio[940]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Jun 02 20:53:30 volumio-living sudo[3491]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Jun 02 20:53:30 volumio-living sudo[3491]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 20:53:30 volumio-living systemd[1]: Started Volumio Tidal Connect Service. Jun 02 20:53:30 volumio-living sudo[3491]: pam_unix(sudo:session): session closed for user root Jun 02 20:53:30 volumio-living volumio[940]: info: Executing endpoint tc_getconfig Jun 02 20:53:30 volumio-living volumio[940]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Jun 02 20:53:30 volumio-living vtcs[3493]: STARTING TidalConnect services, version: 1.4.0.34 Jun 02 20:53:30 volumio-living vtcs[3493]: STARTED TidalConnect services. Jun 02 20:53:30 volumio-living volumio[940]: info: Executing endpoint tc_connect Jun 02 20:53:30 volumio-living volumio[940]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Jun 02 20:53:30 volumio-living volumio[940]: info: Connecting to TidalConnect Jun 02 20:53:30 volumio-living volumio[940]: info: CoreCommandRouter::servicePushState Jun 02 20:53:30 volumio-living volumio[940]: info: CoreStateMachine::pushState Jun 02 20:53:30 volumio-living volumio[940]: info: CorePlayQueue::getTrack 0 Jun 02 20:53:30 volumio-living volumio[940]: info: CoreCommandRouter::volumioPushState Jun 02 20:53:30 volumio-living volumio[940]: info: MRS: Pushing multiroomSync output update for this device Jun 02 20:53:30 volumio-living volumio[940]: info: MRS: Pushing multiroomSync output Jun 02 20:53:30 volumio-living volumio[940]: info: CoreCommandRouter::volumioGetState Jun 02 20:53:30 volumio-living volumio[940]: info: CorePlayQueue::getTrack 0 Jun 02 20:53:30 volumio-living volumio[940]: info: CorePlayQueue::getTrack 0 Jun 02 20:53:30 volumio-living volumio[940]: 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} Jun 02 20:53:30 volumio-living volumio[940]: verbose: CURRENT POSITION 0 Jun 02 20:53:30 volumio-living volumio[940]: info: CoreStateMachine::syncState stateService stop Jun 02 20:53:30 volumio-living volumio[940]: info: CoreStateMachine::syncState currentStatus stop Jun 02 20:53:30 volumio-living volumio[940]: info: CoreStateMachine::pushState Jun 02 20:53:30 volumio-living volumio[940]: info: CorePlayQueue::getTrack 0 Jun 02 20:53:30 volumio-living volumio[940]: info: CoreCommandRouter::volumioPushState Jun 02 20:53:30 volumio-living volumio[940]: info: MRS: Pushing multiroomSync output update for this device Jun 02 20:53:30 volumio-living volumio[940]: info: MRS: Pushing multiroomSync output Jun 02 20:53:30 volumio-living volumio[940]: info: CoreCommandRouter::volumioGetState Jun 02 20:53:30 volumio-living volumio[940]: info: CorePlayQueue::getTrack 0 Jun 02 20:53:30 volumio-living volumio[940]: info: No code Jun 02 20:53:30 volumio-living volumio[940]: info: CoreStateMachine::pushState Jun 02 20:53:30 volumio-living volumio[940]: info: CorePlayQueue::getTrack 0 Jun 02 20:53:30 volumio-living volumio[940]: info: CoreCommandRouter::volumioPushState Jun 02 20:53:30 volumio-living volumio[940]: info: MRS: Pushing multiroomSync output update for this device Jun 02 20:53:30 volumio-living volumio[940]: info: MRS: Pushing multiroomSync output Jun 02 20:53:30 volumio-living volumio[940]: info: CoreCommandRouter::volumioGetState Jun 02 20:53:30 volumio-living volumio[940]: info: CorePlayQueue::getTrack 0 Jun 02 20:53:30 volumio-living volumio[940]: info: CoreCommandRouter::servicePushState Jun 02 20:53:30 volumio-living volumio[940]: info: CoreStateMachine::pushState Jun 02 20:53:30 volumio-living volumio[940]: info: CorePlayQueue::getTrack 0 Jun 02 20:53:30 volumio-living volumio[940]: info: CoreCommandRouter::volumioPushState Jun 02 20:53:30 volumio-living volumio[940]: info: MRS: Pushing multiroomSync output update for this device Jun 02 20:53:30 volumio-living volumio[940]: info: MRS: Pushing multiroomSync output Jun 02 20:53:30 volumio-living volumio[940]: info: CoreCommandRouter::volumioGetState Jun 02 20:53:30 volumio-living volumio[940]: info: CorePlayQueue::getTrack 0 Jun 02 20:53:30 volumio-living volumio[940]: info: CorePlayQueue::getTrack 0 Jun 02 20:53:30 volumio-living volumio[940]: 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} Jun 02 20:53:30 volumio-living volumio[940]: verbose: CURRENT POSITION 0 Jun 02 20:53:30 volumio-living volumio[940]: info: CoreStateMachine::syncState stateService stop Jun 02 20:53:30 volumio-living volumio[940]: info: CoreStateMachine::syncState currentStatus stop Jun 02 20:53:30 volumio-living volumio[940]: info: CoreStateMachine::pushState Jun 02 20:53:30 volumio-living volumio[940]: info: CorePlayQueue::getTrack 0 Jun 02 20:53:30 volumio-living volumio[940]: info: CoreCommandRouter::volumioPushState Jun 02 20:53:30 volumio-living volumio[940]: info: MRS: Pushing multiroomSync output update for this device Jun 02 20:53:30 volumio-living volumio[940]: info: MRS: Pushing multiroomSync output Jun 02 20:53:30 volumio-living volumio[940]: info: CoreCommandRouter::volumioGetState Jun 02 20:53:30 volumio-living volumio[940]: info: CorePlayQueue::getTrack 0 Jun 02 20:53:30 volumio-living volumio[940]: info: No code Jun 02 20:53:30 volumio-living volumio[940]: info: CoreStateMachine::pushState Jun 02 20:53:30 volumio-living volumio[940]: info: CorePlayQueue::getTrack 0 Jun 02 20:53:30 volumio-living volumio[940]: info: CoreCommandRouter::volumioPushState Jun 02 20:53:30 volumio-living volumio[940]: info: MRS: Pushing multiroomSync output update for this device Jun 02 20:53:30 volumio-living volumio[940]: info: MRS: Pushing multiroomSync output Jun 02 20:53:30 volumio-living volumio[940]: info: CoreCommandRouter::volumioGetState Jun 02 20:53:30 volumio-living volumio[940]: info: CorePlayQueue::getTrack 0 Jun 02 20:53:31 volumio-living volumio[940]: info: VolumeController::SetAlsaVolume86 Jun 02 20:53:31 volumio-living volumio[940]: info: CoreStateMachine::pushState Jun 02 20:53:31 volumio-living volumio[940]: info: CorePlayQueue::getTrack 0 Jun 02 20:53:31 volumio-living volumio[940]: info: CoreCommandRouter::volumioPushState Jun 02 20:53:31 volumio-living volumio[940]: info: MRS: Pushing multiroomSync output update for this device Jun 02 20:53:31 volumio-living volumio[940]: info: MRS: Pushing multiroomSync output Jun 02 20:53:31 volumio-living volumio[940]: info: CoreCommandRouter::volumioGetState Jun 02 20:53:31 volumio-living volumio[940]: info: CorePlayQueue::getTrack 0 Jun 02 20:53:32 volumio-living volumio[940]: info: CALLMETHOD: audio_interface alsa_controller saveVolumeOptions [object Object] Jun 02 20:53:32 volumio-living volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveVolumeOptions Jun 02 20:53:32 volumio-living volumio[940]: info: CoreCommandRouter::volumioGetState Jun 02 20:53:32 volumio-living volumio[940]: info: CorePlayQueue::getTrack 0 Jun 02 20:53:32 volumio-living volumio[940]: info: CoreCommandRouter::executeOnPlugin: mpd , saveResampleOptions Jun 02 20:53:32 volumio-living sudo[3514]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 02 20:53:32 volumio-living sudo[3514]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 20:53:32 volumio-living volumio[940]: info: Restoring Previous Volume level: 86 false false Jun 02 20:53:32 volumio-living volumio[940]: info: Volume configurations have been set Jun 02 20:53:32 volumio-living volumio[940]: info: QobuzConnect: setDeactiveState invoked Jun 02 20:53:32 volumio-living volumio[940]: info: CoreCommandRouter::volumioGetState Jun 02 20:53:32 volumio-living volumio[940]: info: CorePlayQueue::getTrack 0 Jun 02 20:53:32 volumio-living vtcs[3493]: [2025-06-02 20:53:32.229] [tisoc] [warning] [SessionManagerImpl.cpp:243] Illegal State: IDLE Jun 02 20:53:32 volumio-living vtcs[3493]: [2025-06-02 20:53:32.229] [tisoc] [error] [SpkconServer.cpp:376] recv error. socket disconnected Jun 02 20:53:32 volumio-living sudo[3514]: pam_unix(sudo:session): session closed for user root Jun 02 20:53:32 volumio-living sudo[3517]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 02 20:53:32 volumio-living sudo[3517]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 20:53:32 volumio-living systemd[1]: Stopping Music Player Daemon... Jun 02 20:53:32 volumio-living sudo[3520]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jun 02 20:53:32 volumio-living sudo[3520]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 20:53:32 volumio-living systemd[1]: Stopping Volumio Tidal Connect Service... Jun 02 20:53:32 volumio-living systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Jun 02 20:53:32 volumio-living systemd[1]: vtcs.service: Succeeded. Jun 02 20:53:32 volumio-living systemd[1]: Stopped Volumio Tidal Connect Service. Jun 02 20:53:32 volumio-living systemd[1]: mpd.service: Succeeded. Jun 02 20:53:32 volumio-living systemd[1]: Stopped Music Player Daemon. Jun 02 20:53:32 volumio-living volumio[940]: xcb_connection_has_error() returned true Jun 02 20:53:32 volumio-living volumio[940]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jun 02 20:53:32 volumio-living volumio[940]: info: Updating Volume Controller Parameters: Device: 1 Name: Headphones Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Jun 02 20:53:32 volumio-living volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Jun 02 20:53:32 volumio-living volumio[940]: info: Disabling external Volume Control Jun 02 20:53:32 volumio-living volumio[940]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:53:32 volumio-living systemd[1]: Starting Music Player Daemon... Jun 02 20:53:32 volumio-living sudo[3520]: pam_unix(sudo:session): session closed for user root Jun 02 20:53:32 volumio-living sudo[3527]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jun 02 20:53:32 volumio-living sudo[3527]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 20:53:32 volumio-living sudo[3527]: pam_unix(sudo:session): session closed for user root Jun 02 20:53:32 volumio-living volumio[940]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 02 20:53:32 volumio-living volumio[940]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 02 20:53:32 volumio-living volumio[940]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jun 02 20:53:32 volumio-living volumio[940]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 02 20:53:32 volumio-living sudo[3534]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Jun 02 20:53:32 volumio-living sudo[3534]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 20:53:32 volumio-living sudo[3524]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 02 20:53:32 volumio-living sudo[3524]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 20:53:32 volumio-living sudo[3524]: pam_unix(sudo:session): session closed for user root Jun 02 20:53:32 volumio-living volumio[940]: info: MPD Permissions set Jun 02 20:53:32 volumio-living sudo[3534]: pam_unix(sudo:session): session closed for user root Jun 02 20:53:32 volumio-living volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 20:53:32 volumio-living volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 20:53:32 volumio-living volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 20:53:32 volumio-living volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 20:53:32 volumio-living volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 20:53:32 volumio-living volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 20:53:32 volumio-living volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 20:53:32 volumio-living volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 20:53:32 volumio-living sudo[3540]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Jun 02 20:53:32 volumio-living sudo[3540]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 20:53:32 volumio-living volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 02 20:53:32 volumio-living volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 20:53:32 volumio-living systemd[1]: Stopping Volumio Qobuz Connect Service... Jun 02 20:53:32 volumio-living qobuz-connect[3474]: 20250602 20:53:32.490 [3474.3474] INFO SampleApp: Stopping Local configuration server Jun 02 20:53:32 volumio-living qobuz-connect[3474]: 20250602 20:53:32.500 [3474.3474] INFO SampleApp: shat down connection on UNIX socket Jun 02 20:53:32 volumio-living systemd[1]: qobuz-connect.service: Succeeded. Jun 02 20:53:32 volumio-living systemd[1]: Stopped Volumio Qobuz Connect Service. Jun 02 20:53:32 volumio-living volumio[940]: xcb_connection_has_error() returned true Jun 02 20:53:32 volumio-living volumio[940]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jun 02 20:53:32 volumio-living volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 02 20:53:32 volumio-living volumio[940]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 02 20:53:32 volumio-living systemd[1]: Started Volumio Qobuz Connect Service. Jun 02 20:53:32 volumio-living volumio[940]: xcb_connection_has_error() returned true Jun 02 20:53:32 volumio-living volumio[940]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 02 20:53:32 volumio-living volumio[940]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jun 02 20:53:32 volumio-living sudo[3540]: pam_unix(sudo:session): session closed for user root Jun 02 20:53:32 volumio-living volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jun 02 20:53:32 volumio-living volumio[940]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 02 20:53:32 volumio-living volumio[940]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jun 02 20:53:32 volumio-living volumio[940]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Jun 02 20:53:32 volumio-living volumio[940]: info: QobuzConnect: setDeactiveState invoked Jun 02 20:53:32 volumio-living volumio[940]: info: CoreCommandRouter::volumioGetState Jun 02 20:53:32 volumio-living volumio[940]: info: CorePlayQueue::getTrack 0 Jun 02 20:53:32 volumio-living volumio[940]: info: VolumeController:: Volume=86 Mute =false Jun 02 20:53:32 volumio-living volumio[940]: info: CoreStateMachine::pushState Jun 02 20:53:32 volumio-living volumio[940]: info: CorePlayQueue::getTrack 0 Jun 02 20:53:32 volumio-living volumio[940]: info: CoreCommandRouter::volumioPushState Jun 02 20:53:32 volumio-living volumio[940]: info: MRS: Pushing multiroomSync output update for this device Jun 02 20:53:32 volumio-living volumio[940]: info: MRS: Pushing multiroomSync output Jun 02 20:53:32 volumio-living volumio[940]: info: CoreCommandRouter::volumioGetState Jun 02 20:53:32 volumio-living volumio[940]: info: CorePlayQueue::getTrack 0 Jun 02 20:53:32 volumio-living volumio[940]: info: Executing endpoint qc_getconfig Jun 02 20:53:32 volumio-living volumio[940]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Jun 02 20:53:32 volumio-living qobuz-connect[3548]: 20250602 20:53:32.624 [3548.3548] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Jun 02 20:53:32 volumio-living volumio[940]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Jun 02 20:53:32 volumio-living volumio[940]: info: QobuzConnect: QOBUZ Connect daemon connected Jun 02 20:53:32 volumio-living qobuz-connect[3548]: 20250602 20:53:32.629 [3548.3548] INFO VolumeManager: [0x129ec58]: Setting new playback volume: 75 Jun 02 20:53:32 volumio-living qobuz-connect[3548]: 20250602 20:53:32.629 [3548.3548] INFO VolumeManager: [0x129ec58]: Setting new mute state: 0 Jun 02 20:53:32 volumio-living qobuz-connect[3548]: 20250602 20:53:32.629 [3548.3548] INFO QobuzConnect: [0x129f628]: Client initialized! Jun 02 20:53:32 volumio-living qobuz-connect[3548]: 20250602 20:53:32.629 [3548.3548] INFO SampleApp: Starting Avahi advertising, name: Volumio-Living, service name: _qobuz-connect._tcp Jun 02 20:53:32 volumio-living qobuz-connect[3548]: 20250602 20:53:32.643 [3548.3548] INFO LocalConfigManager: [0x129e538]: Starting Local Configuration server Jun 02 20:53:32 volumio-living qobuz-connect[3548]: 20250602 20:53:32.643 [3548.3548] INFO SampleApp: Starting Local configuration server Jun 02 20:53:32 volumio-living qobuz-connect[3548]: 20250602 20:53:32.644 [3548.3548] INFO SampleApp: Connected to UNIX socket client 0x1293f60 Jun 02 20:53:32 volumio-living qobuz-connect[3548]: 20250602 20:53:32.774 [3548.3548] INFO SampleApp: Playback volume changed: 75 Jun 02 20:53:32 volumio-living volumio[940]: info: CoreCommandRouter::volumioGetState Jun 02 20:53:32 volumio-living volumio[940]: info: CorePlayQueue::getTrack 0 Jun 02 20:53:33 volumio-living mpd[3539]: Jun 02 20:53 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 02 20:53:33 volumio-living systemd[1]: Started Music Player Daemon. Jun 02 20:53:33 volumio-living sudo[3517]: pam_unix(sudo:session): session closed for user root Jun 02 20:53:33 volumio-living volumio[940]: error: updateQueue error: null Jun 02 20:53:33 volumio-living volumio[940]: info: TidalConnect service started! Jun 02 20:53:35 volumio-living volumio[940]: info: TidalConnect service stoped! Jun 02 20:53:35 volumio-living volumio[940]: info: TidalConnect service stoped! Jun 02 20:53:35 volumio-living volumio[940]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Jun 02 20:53:35 volumio-living volumio[940]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Jun 02 20:53:35 volumio-living sudo[3578]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Jun 02 20:53:35 volumio-living sudo[3578]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 20:53:35 volumio-living systemd[1]: Started Volumio Tidal Connect Service. Jun 02 20:53:35 volumio-living sudo[3578]: pam_unix(sudo:session): session closed for user root Jun 02 20:53:35 volumio-living volumio[940]: info: Executing endpoint tc_getconfig Jun 02 20:53:35 volumio-living volumio[940]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Jun 02 20:53:35 volumio-living vtcs[3580]: STARTING TidalConnect services, version: 1.4.0.34 Jun 02 20:53:35 volumio-living vtcs[3580]: STARTED TidalConnect services. Jun 02 20:53:35 volumio-living volumio[940]: info: Executing endpoint tc_connect Jun 02 20:53:35 volumio-living volumio[940]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Jun 02 20:53:35 volumio-living volumio[940]: info: Connecting to TidalConnect Jun 02 20:53:35 volumio-living volumio[940]: info: CoreCommandRouter::servicePushState Jun 02 20:53:35 volumio-living volumio[940]: info: CoreStateMachine::pushState Jun 02 20:53:35 volumio-living volumio[940]: info: CorePlayQueue::getTrack 0 Jun 02 20:53:35 volumio-living volumio[940]: info: CoreCommandRouter::volumioPushState Jun 02 20:53:35 volumio-living volumio[940]: info: MRS: Pushing multiroomSync output update for this device Jun 02 20:53:35 volumio-living volumio[940]: info: MRS: Pushing multiroomSync output Jun 02 20:53:35 volumio-living volumio[940]: info: CoreCommandRouter::volumioGetState Jun 02 20:53:35 volumio-living volumio[940]: info: CorePlayQueue::getTrack 0 Jun 02 20:53:35 volumio-living volumio[940]: info: CorePlayQueue::getTrack 0 Jun 02 20:53:35 volumio-living volumio[940]: 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} Jun 02 20:53:35 volumio-living volumio[940]: verbose: CURRENT POSITION 0 Jun 02 20:53:35 volumio-living volumio[940]: info: CoreStateMachine::syncState stateService stop Jun 02 20:53:35 volumio-living volumio[940]: info: CoreStateMachine::syncState currentStatus stop Jun 02 20:53:35 volumio-living volumio[940]: info: CoreStateMachine::pushState Jun 02 20:53:35 volumio-living volumio[940]: info: CorePlayQueue::getTrack 0 Jun 02 20:53:35 volumio-living volumio[940]: info: CoreCommandRouter::volumioPushState Jun 02 20:53:35 volumio-living volumio[940]: info: MRS: Pushing multiroomSync output update for this device Jun 02 20:53:35 volumio-living volumio[940]: info: MRS: Pushing multiroomSync output Jun 02 20:53:35 volumio-living volumio[940]: info: CoreCommandRouter::volumioGetState Jun 02 20:53:35 volumio-living volumio[940]: info: CorePlayQueue::getTrack 0 Jun 02 20:53:35 volumio-living volumio[940]: info: No code Jun 02 20:53:35 volumio-living volumio[940]: info: CoreStateMachine::pushState Jun 02 20:53:35 volumio-living volumio[940]: info: CorePlayQueue::getTrack 0 Jun 02 20:53:35 volumio-living volumio[940]: info: CoreCommandRouter::volumioPushState Jun 02 20:53:35 volumio-living volumio[940]: info: MRS: Pushing multiroomSync output update for this device Jun 02 20:53:35 volumio-living volumio[940]: info: MRS: Pushing multiroomSync output Jun 02 20:53:35 volumio-living volumio[940]: info: CoreCommandRouter::volumioGetState Jun 02 20:53:35 volumio-living volumio[940]: info: CorePlayQueue::getTrack 0 Jun 02 20:53:35 volumio-living volumio[940]: info: CoreCommandRouter::servicePushState Jun 02 20:53:35 volumio-living volumio[940]: info: CoreStateMachine::pushState Jun 02 20:53:35 volumio-living volumio[940]: info: CorePlayQueue::getTrack 0 Jun 02 20:53:35 volumio-living volumio[940]: info: CoreCommandRouter::volumioPushState Jun 02 20:53:35 volumio-living volumio[940]: info: MRS: Pushing multiroomSync output update for this device Jun 02 20:53:35 volumio-living volumio[940]: info: MRS: Pushing multiroomSync output Jun 02 20:53:35 volumio-living volumio[940]: info: CoreCommandRouter::volumioGetState Jun 02 20:53:35 volumio-living volumio[940]: info: CorePlayQueue::getTrack 0 Jun 02 20:53:35 volumio-living volumio[940]: info: CorePlayQueue::getTrack 0 Jun 02 20:53:35 volumio-living volumio[940]: 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} Jun 02 20:53:35 volumio-living volumio[940]: verbose: CURRENT POSITION 0 Jun 02 20:53:35 volumio-living volumio[940]: info: CoreStateMachine::syncState stateService stop Jun 02 20:53:35 volumio-living volumio[940]: info: CoreStateMachine::syncState currentStatus stop Jun 02 20:53:35 volumio-living volumio[940]: info: CoreStateMachine::pushState Jun 02 20:53:35 volumio-living volumio[940]: info: CorePlayQueue::getTrack 0 Jun 02 20:53:35 volumio-living volumio[940]: info: CoreCommandRouter::volumioPushState Jun 02 20:53:35 volumio-living volumio[940]: info: MRS: Pushing multiroomSync output update for this device Jun 02 20:53:35 volumio-living volumio[940]: info: MRS: Pushing multiroomSync output Jun 02 20:53:35 volumio-living volumio[940]: info: CoreCommandRouter::volumioGetState Jun 02 20:53:35 volumio-living volumio[940]: info: CorePlayQueue::getTrack 0 Jun 02 20:53:35 volumio-living volumio[940]: info: No code Jun 02 20:53:35 volumio-living volumio[940]: info: CoreStateMachine::pushState Jun 02 20:53:35 volumio-living volumio[940]: info: CorePlayQueue::getTrack 0 Jun 02 20:53:35 volumio-living volumio[940]: info: CoreCommandRouter::volumioPushState Jun 02 20:53:35 volumio-living volumio[940]: info: MRS: Pushing multiroomSync output update for this device Jun 02 20:53:35 volumio-living volumio[940]: info: MRS: Pushing multiroomSync output Jun 02 20:53:35 volumio-living volumio[940]: info: CoreCommandRouter::volumioGetState Jun 02 20:53:35 volumio-living volumio[940]: info: CorePlayQueue::getTrack 0 Jun 02 20:53:36 volumio-living volumio[940]: info: VolumeController::SetAlsaVolume86 Jun 02 20:53:36 volumio-living volumio[940]: info: CoreStateMachine::pushState Jun 02 20:53:36 volumio-living volumio[940]: info: CorePlayQueue::getTrack 0 Jun 02 20:53:36 volumio-living volumio[940]: info: CoreCommandRouter::volumioPushState Jun 02 20:53:36 volumio-living volumio[940]: info: MRS: Pushing multiroomSync output update for this device Jun 02 20:53:36 volumio-living volumio[940]: info: MRS: Pushing multiroomSync output Jun 02 20:53:36 volumio-living volumio[940]: info: CoreCommandRouter::volumioGetState Jun 02 20:53:36 volumio-living volumio[940]: info: CorePlayQueue::getTrack 0 Jun 02 20:53:38 volumio-living volumio[940]: info: TidalConnect service started! Jun 02 20:53:39 volumio-living volumio[940]: info: CoreCommandRouter::volumioGetQueue Jun 02 20:53:39 volumio-living volumio[940]: info: CoreStateMachine::getQueue Jun 02 20:53:39 volumio-living volumio[940]: info: CorePlayQueue::getQueue Jun 02 20:53:40 volumio-living volumio[940]: info: CoreCommandRouter::volumioGetState Jun 02 20:53:40 volumio-living volumio[940]: info: CorePlayQueue::getTrack 0 Jun 02 20:53:47 volumio-living volumio[940]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:53:47 volumio-living volumio[940]: xcb_connection_has_error() returned true Jun 02 20:53:47 volumio-living volumio[940]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jun 02 20:53:47 volumio-living volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 02 20:53:47 volumio-living volumio[940]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 02 20:53:47 volumio-living volumio[940]: xcb_connection_has_error() returned true Jun 02 20:53:47 volumio-living volumio[940]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 02 20:53:47 volumio-living volumio[940]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 02 20:53:47 volumio-living volumio[940]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 02 20:53:47 volumio-living volumio[940]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 02 20:53:47 volumio-living volumio[940]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 02 20:53:47 volumio-living volumio[940]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 02 20:53:47 volumio-living volumio[940]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 02 20:53:47 volumio-living volumio[940]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jun 02 20:53:47 volumio-living volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jun 02 20:53:51 volumio-living volumio[940]: info: CoreCommandRouter::volumioGetState Jun 02 20:53:51 volumio-living volumio[940]: info: CorePlayQueue::getTrack 0 Jun 02 20:53:54 volumio-living volumio[940]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:53:54 volumio-living volumio[940]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jun 02 20:53:54 volumio-living volumio[940]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jun 02 20:53:54 volumio-living volumio[940]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs Jun 02 20:53:54 volumio-living volumio[940]: info: Received Get System Version Jun 02 20:53:54 volumio-living volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 02 20:54:00 volumio-living ntpd[796]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jun 02 20:54:02 volumio-living volumio[940]: info: CALLMETHOD: miscellanea appearance setVolumio3UI [object Object] Jun 02 20:54:02 volumio-living volumio[940]: info: CoreCommandRouter::executeOnPlugin: appearance , setVolumio3UI Jun 02 20:54:02 volumio-living volumio[940]: info: Setting active UI to: {"uiPrettyName":"Contemporary","uiName":"contemporary","uiPath":"/volumio/http/www3"} Jun 02 20:54:03 volumio-living volumio[940]: info: CoreCommandRouter::Reload Ui Jun 02 20:54:03 volumio-living wpa_supplicant[2389]: RRM: Ignoring radio measurement request: Not RRM network Jun 02 20:54:05 volumio-living sudo[3704]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 02 20:54:05 volumio-living sudo[3704]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 20:54:05 volumio-living sudo[3704]: pam_unix(sudo:session): session closed for user root Jun 02 20:54:05 volumio-living sudo[3706]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 02 20:54:05 volumio-living sudo[3706]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 20:54:05 volumio-living sudo[3706]: pam_unix(sudo:session): session closed for user root Jun 02 20:54:05 volumio-living volumio[940]: verbose: New Socket.io Connection to 10.2.0.116 from 10.2.0.108 UA: Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/114.0 Engine version: 3 Transport: polling Total Clients: 6 Jun 02 20:54:05 volumio-living volumio[940]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:54:05 volumio-living volumio[940]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jun 02 20:54:05 volumio-living volumio[940]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 02 20:54:05 volumio-living volumio[940]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Jun 02 20:54:05 volumio-living volumio[940]: info: CoreCommandRouter::volumioGetState Jun 02 20:54:05 volumio-living volumio[940]: info: CorePlayQueue::getTrack 0 Jun 02 20:54:05 volumio-living volumio[940]: info: CoreCommandRouter::volumioGetVisibleSources Jun 02 20:54:05 volumio-living volumio[940]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 02 20:54:05 volumio-living volumio[940]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jun 02 20:54:05 volumio-living volumio[940]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jun 02 20:54:05 volumio-living volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jun 02 20:54:05 volumio-living volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 02 20:54:05 volumio-living volumio[940]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jun 02 20:54:05 volumio-living volumio[940]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jun 02 20:54:05 volumio-living volumio[940]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs Jun 02 20:54:05 volumio-living volumio[940]: info: Received Get System Version Jun 02 20:54:05 volumio-living volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 02 20:54:05 volumio-living volumio[940]: info: CoreCommandRouter::executeOnPlugin: appearance , getBackgrounds Jun 02 20:54:08 volumio-living volumio[940]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 02 20:54:08 volumio-living volumio[940]: info: Received Get System Info Jun 02 20:54:08 volumio-living volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 02 20:54:08 volumio-living volumio[940]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 02 20:54:08 volumio-living volumio[940]: info: Discovery: Getting this device information Jun 02 20:54:08 volumio-living volumio[940]: info: CoreCommandRouter::volumioGetState Jun 02 20:54:08 volumio-living volumio[940]: info: CorePlayQueue::getTrack 0 Jun 02 20:54:08 volumio-living volumio[940]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 02 20:54:08 volumio-living volumio[940]: info: MRS: Updating multiroomSync output Jun 02 20:54:08 volumio-living volumio[940]: info: MRS: Updating multiroomSync output Jun 02 20:54:11 volumio-living volumio[940]: info: CoreCommandRouter::executeOnPlugin: appearance , setBackgrounds Jun 02 20:54:11 volumio-living volumio[940]: info: CoreCommandRouter::executeOnPlugin: appearance , getBackgrounds Jun 02 20:54:11 volumio-living volumio[940]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jun 02 20:54:24 volumio-living volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 02 20:54:24 volumio-living volumio[940]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jun 02 20:54:24 volumio-living volumio[940]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jun 02 20:54:24 volumio-living volumio[940]: info: Received Get System Version Jun 02 20:54:24 volumio-living volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 02 20:54:24 volumio-living volumio[940]: info: Received Get System Info Jun 02 20:54:24 volumio-living volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 02 20:54:24 volumio-living volumio[940]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 02 20:54:24 volumio-living volumio[940]: info: Discovery: Getting this device information Jun 02 20:54:24 volumio-living volumio[940]: info: CoreCommandRouter::volumioGetState Jun 02 20:54:24 volumio-living volumio[940]: info: CorePlayQueue::getTrack 0 Jun 02 20:54:24 volumio-living volumio[940]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 02 20:54:28 volumio-living volumio[940]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jun 02 20:54:28 volumio-living volumio[940]: info: CoreCommandRouter::volumioGetQueue Jun 02 20:54:28 volumio-living volumio[940]: info: CoreStateMachine::getQueue Jun 02 20:54:28 volumio-living volumio[940]: info: CorePlayQueue::getQueue Jun 02 20:54:28 volumio-living volumio[940]: info: Listing playlists Jun 02 20:54:28 volumio-living volumio[940]: info: Preload queue cleared Jun 02 20:54:34 volumio-living volumio[940]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jun 02 20:54:34 volumio-living volumio[940]: info: Preload queue cleared Jun 02 20:54:39 volumio-living volumio[940]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jun 02 20:54:39 volumio-living volumio[940]: info: Preload queue cleared Jun 02 20:54:40 volumio-living volumio[940]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jun 02 20:54:40 volumio-living volumio[940]: info: Preload queue cleared Jun 02 20:54:42 volumio-living volumio[940]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jun 02 20:54:42 volumio-living volumio[940]: info: Preload queue cleared Jun 02 20:54:43 volumio-living volumio[940]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jun 02 20:54:43 volumio-living volumio[940]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 02 20:54:44 volumio-living volumio[940]: TypeError: Cannot read property 'length' of undefined Jun 02 20:54:44 volumio-living volumio[940]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Jun 02 20:54:44 volumio-living volumio[940]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Jun 02 20:54:44 volumio-living volumio[940]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Jun 02 20:54:44 volumio-living volumio[940]: at Parser.emit (events.js:315:20) Jun 02 20:54:44 volumio-living volumio[940]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Jun 02 20:54:44 volumio-living volumio[940]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Jun 02 20:54:44 volumio-living volumio[940]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Jun 02 20:54:44 volumio-living volumio[940]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Jun 02 20:54:44 volumio-living volumio[940]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Jun 02 20:54:44 volumio-living volumio[940]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Jun 02 20:54:44 volumio-living volumio[940]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Jun 02 20:54:44 volumio-living volumio[940]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Jun 02 20:54:44 volumio-living volumio[940]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Jun 02 20:54:44 volumio-living volumio[940]: at IncomingMessage.emit (events.js:327:22) Jun 02 20:54:44 volumio-living volumio[940]: at endReadableNT (internal/streams/readable.js:1327:12) Jun 02 20:54:44 volumio-living volumio[940]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Jun 02 20:54:44 volumio-living volumio[940]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 02 20:54:44 volumio-living sudo[3783]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-02 20:53 Jun 02 20:54:44 volumio-living sudo[3783]: 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="570c5791513f5bac7da274aba6690c1a961705de" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 13 May 2025 05:16:30 PM CEST" VOLUMIO_VERSION="3.812" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="3934ee440d5d3f0186049a23752ad680"