-- Logs begin at Thu 2019-02-14 11:11:58 CET, end at Sat 2025-05-10 21:24:44 CEST. -- May 10 21:23:00 volumior volumio[5219]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 10 21:23:01 volumior sudo[5686]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service May 10 21:23:01 volumior sudo[5686]: pam_unix(sudo:session): session opened for user root by (uid=0) May 10 21:23:01 volumior sudo[5686]: pam_unix(sudo:session): session closed for user root May 10 21:23:01 volumior volumio[5219]: info: Volumio BT Module successfully started May 10 21:23:02 volumior sudo[5693]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service May 10 21:23:02 volumior sudo[5693]: pam_unix(sudo:session): session opened for user root by (uid=0) May 10 21:23:02 volumior volumio[5219]: info: TidalConnect service stoped! May 10 21:23:02 volumior autossh[4813]: received signal to exit (15) May 10 21:23:02 volumior systemd[1]: Stopping MyVolumio SSH Tunnel... May 10 21:23:02 volumior systemd[1]: sshtunnel.service: Main process exited, code=killed, status=15/TERM May 10 21:23:02 volumior systemd[1]: sshtunnel.service: Succeeded. May 10 21:23:02 volumior systemd[1]: Stopped MyVolumio SSH Tunnel. May 10 21:23:02 volumior volumio[5219]: ------------------------------------ BT MESSAGE: BT STATUS: running May 10 21:23:02 volumior volumio[5219]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 10 21:23:02 volumior volumio[5219]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect May 10 21:23:02 volumior volumio[5219]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect May 10 21:23:02 volumior sudo[5705]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service May 10 21:23:02 volumior sudo[5705]: pam_unix(sudo:session): session opened for user root by (uid=0) May 10 21:23:02 volumior systemd[1]: Started MyVolumio SSH Tunnel. May 10 21:23:02 volumior sudo[5693]: pam_unix(sudo:session): session closed for user root May 10 21:23:02 volumior autossh[5707]: port set to 0, monitoring disabled May 10 21:23:02 volumior autossh[5707]: starting ssh (count 1) May 10 21:23:02 volumior autossh[5707]: ssh child pid is 5710 May 10 21:23:02 volumior volumio[5219]: info: Remote SSH Started May 10 21:23:02 volumior systemd[1]: Started Volumio Tidal Connect Service. May 10 21:23:02 volumior sudo[5705]: pam_unix(sudo:session): session closed for user root May 10 21:23:02 volumior volumio[5219]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 May 10 21:23:02 volumior volumio[5219]: info: CoreCommandRouter::volumioGetState May 10 21:23:02 volumior volumio[5219]: info: CorePlayQueue::getTrack 0 May 10 21:23:02 volumior volumio[5219]: info: Executing endpoint tc_getconfig May 10 21:23:02 volumior volumio[5219]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig May 10 21:23:02 volumior vtcs[5711]: STARTING TidalConnect services, version: 1.4.0.34 May 10 21:23:02 volumior volumiossh-tunnel[5696]: Warning: Permanently added '[eu7.myvolumio.org]:2222,[167.71.43.69]:2222' (RSA) to the list of known hosts. May 10 21:23:02 volumior vtcs[5711]: STARTED TidalConnect services. May 10 21:23:02 volumior volumio[5219]: info: Executing endpoint tc_connect May 10 21:23:02 volumior volumio[5219]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect May 10 21:23:02 volumior volumio[5219]: info: Connecting to TidalConnect May 10 21:23:02 volumior volumio[5219]: info: CoreCommandRouter::servicePushState May 10 21:23:02 volumior volumio[5219]: info: CoreStateMachine::pushState May 10 21:23:02 volumior volumio[5219]: info: CorePlayQueue::getTrack 0 May 10 21:23:02 volumior volumio[5219]: info: CoreCommandRouter::volumioPushState May 10 21:23:02 volumior volumio[5219]: info: MRS: Pushing multiroomSync output update for this device May 10 21:23:02 volumior volumio[5219]: info: MRS: Pushing multiroomSync output May 10 21:23:02 volumior volumio[5219]: info: CoreCommandRouter::volumioGetState May 10 21:23:02 volumior volumio[5219]: info: CorePlayQueue::getTrack 0 May 10 21:23:02 volumior volumio[5219]: info: CorePlayQueue::getTrack 0 May 10 21:23:02 volumior volumio[5219]: 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} May 10 21:23:02 volumior volumio[5219]: verbose: CURRENT POSITION 0 May 10 21:23:02 volumior volumio[5219]: info: CoreStateMachine::syncState stateService stop May 10 21:23:02 volumior volumio[5219]: info: CoreStateMachine::syncState currentStatus stop May 10 21:23:02 volumior volumio[5219]: info: CoreStateMachine::pushState May 10 21:23:02 volumior volumio[5219]: info: CorePlayQueue::getTrack 0 May 10 21:23:02 volumior volumio[5219]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 10 21:23:02 volumior volumio[5219]: info: CoreCommandRouter::volumioPushState May 10 21:23:02 volumior volumio[5219]: info: MRS: Pushing multiroomSync output update for this device May 10 21:23:02 volumior volumio[5219]: info: MRS: Pushing multiroomSync output May 10 21:23:02 volumior volumio[5219]: info: CoreCommandRouter::volumioGetState May 10 21:23:02 volumior volumio[5219]: info: CorePlayQueue::getTrack 0 May 10 21:23:02 volumior volumio[5219]: info: No code May 10 21:23:02 volumior volumio[5219]: info: CoreStateMachine::pushState May 10 21:23:02 volumior volumio[5219]: info: CorePlayQueue::getTrack 0 May 10 21:23:02 volumior volumio[5219]: info: CoreCommandRouter::volumioPushState May 10 21:23:02 volumior volumio[5219]: info: MRS: Pushing multiroomSync output update for this device May 10 21:23:02 volumior volumio[5219]: info: MRS: Pushing multiroomSync output May 10 21:23:02 volumior volumio[5219]: info: CoreCommandRouter::volumioGetState May 10 21:23:02 volumior volumio[5219]: info: CorePlayQueue::getTrack 0 May 10 21:23:02 volumior volumio[5219]: info: CoreCommandRouter::servicePushState May 10 21:23:02 volumior volumio[5219]: info: CoreStateMachine::pushState May 10 21:23:02 volumior volumio[5219]: info: CorePlayQueue::getTrack 0 May 10 21:23:02 volumior volumio[5219]: info: CoreCommandRouter::volumioPushState May 10 21:23:02 volumior volumio[5219]: info: MRS: Pushing multiroomSync output update for this device May 10 21:23:02 volumior volumio[5219]: info: MRS: Pushing multiroomSync output May 10 21:23:02 volumior volumio[5219]: info: CoreCommandRouter::volumioGetState May 10 21:23:02 volumior volumio[5219]: info: CorePlayQueue::getTrack 0 May 10 21:23:02 volumior volumio[5219]: info: CorePlayQueue::getTrack 0 May 10 21:23:02 volumior volumio[5219]: 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} May 10 21:23:02 volumior volumio[5219]: verbose: CURRENT POSITION 0 May 10 21:23:02 volumior volumio[5219]: info: CoreStateMachine::syncState stateService stop May 10 21:23:02 volumior volumio[5219]: info: CoreStateMachine::syncState currentStatus stop May 10 21:23:02 volumior volumio[5219]: info: CoreStateMachine::pushState May 10 21:23:02 volumior volumio[5219]: info: CorePlayQueue::getTrack 0 May 10 21:23:02 volumior volumio[5219]: info: CoreCommandRouter::volumioPushState May 10 21:23:02 volumior volumio[5219]: info: MRS: Pushing multiroomSync output update for this device May 10 21:23:02 volumior volumio[5219]: info: MRS: Pushing multiroomSync output May 10 21:23:02 volumior volumio[5219]: info: CoreCommandRouter::volumioGetState May 10 21:23:02 volumior volumio[5219]: info: CorePlayQueue::getTrack 0 May 10 21:23:02 volumior volumio[5219]: info: No code May 10 21:23:02 volumior volumio[5219]: info: CoreStateMachine::pushState May 10 21:23:02 volumior volumio[5219]: info: CorePlayQueue::getTrack 0 May 10 21:23:02 volumior volumio[5219]: info: CoreCommandRouter::volumioPushState May 10 21:23:02 volumior volumio[5219]: info: MRS: Pushing multiroomSync output update for this device May 10 21:23:02 volumior volumio[5219]: info: MRS: Pushing multiroomSync output May 10 21:23:02 volumior volumio[5219]: info: CoreCommandRouter::volumioGetState May 10 21:23:02 volumior volumio[5219]: info: CorePlayQueue::getTrack 0 May 10 21:23:03 volumior volumio[5219]: info: MRS: Getting audio outputs on start May 10 21:23:03 volumior volumio[5219]: info: MRS: Requesting all other devices output May 10 21:23:04 volumior sudo[5745]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t nfs -o ro,soft,noauto 192.168.0.101:Multimedia /mnt/NAS/TS451 May 10 21:23:04 volumior sudo[5745]: pam_unix(sudo:session): session opened for user root by (uid=0) May 10 21:23:04 volumior volumio[5219]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 10 21:23:05 volumior volumio[5219]: info: TidalConnect service started! May 10 21:23:05 volumior volumio[5219]: [Metrics] CommandRouter: 52s 552.54ms May 10 21:23:05 volumior volumio[5219]: info: CoreCommandRouter::volumiosetStartupVolume May 10 21:23:05 volumior volumio[5219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 21:23:05 volumior volumio[5219]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 10 21:23:05 volumior volumio[5219]: info: CoreCommandRouter::Close All Modals sent May 10 21:23:05 volumior volumio[5219]: info: CoreCommandRouter::Close All Modals sent May 10 21:23:06 volumior volumio[5219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable May 10 21:23:06 volumior volumio[5219]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 10 21:23:06 volumior volumio[5219]: xcb_connection_has_error() returned true May 10 21:23:06 volumior volumio[5219]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect May 10 21:23:08 volumior volumio[5219]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 10 21:23:09 volumior volumio[5219]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 10 21:23:09 volumior volumio[5219]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 May 10 21:23:09 volumior volumio[5219]: info: CoreCommandRouter::volumioGetState May 10 21:23:09 volumior volumio[5219]: info: CorePlayQueue::getTrack 0 May 10 21:23:12 volumior volumio[5219]: info: BOOT COMPLETED May 10 21:23:12 volumior volumio[5219]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 10 21:23:14 volumior volumio[5219]: info: CoreCommandRouter::getUIConfigOnPlugin May 10 21:23:14 volumior volumio[5219]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam May 10 21:23:14 volumior volumio[5219]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam May 10 21:23:14 volumior volumio[5219]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam May 10 21:23:14 volumior volumio[5219]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs May 10 21:23:14 volumior volumio[5219]: info: Received Get System Version May 10 21:23:14 volumior volumio[5219]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 10 21:23:14 volumior volumio[5219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 21:23:14 volumior volumio[5219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 21:23:14 volumior volumio[5219]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam May 10 21:23:14 volumior volumio[5219]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam May 10 21:23:14 volumior volumio[5219]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject May 10 21:23:14 volumior volumio[5219]: info: CoreCommandRouter::volumioGetBrowseSources May 10 21:23:14 volumior volumio[5219]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 10 21:23:15 volumior volumio[5219]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 10 21:23:15 volumior volumio[5219]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 10 21:23:15 volumior volumio[5219]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 10 21:23:15 volumior volumio-remote-updater[710]: Test mode enabled May 10 21:23:15 volumior volumio-remote-updater[710]: No alpha test mode May 10 21:23:15 volumior volumio[5219]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled May 10 21:23:15 volumior volumio[5219]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} May 10 21:23:15 volumior volumio[5219]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache May 10 21:23:16 volumior volumio[5219]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 10 21:23:19 volumior ntpd[1205]: 90.68.206.60 local addr 192.168.0.122 -> May 10 21:23:20 volumior volumio[5219]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 10 21:23:23 volumior volumio[5219]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 10 21:23:23 volumior volumio[5219]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 10 21:23:23 volumior volumio[5219]: info: Discovery: Getting this device information May 10 21:23:23 volumior volumio[5219]: info: CoreCommandRouter::volumioGetState May 10 21:23:23 volumior volumio[5219]: info: CorePlayQueue::getTrack 0 May 10 21:23:23 volumior volumio[5219]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 10 21:23:24 volumior volumio[5219]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 10 21:23:28 volumior volumio[5219]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 10 21:23:32 volumior volumio[5219]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 10 21:23:33 volumior volumio[5219]: info: CoreCommandRouter::getUIConfigOnPlugin May 10 21:23:33 volumior volumio[5219]: xcb_connection_has_error() returned true May 10 21:23:33 volumior volumio[5219]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions May 10 21:23:33 volumior volumio[5219]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 10 21:23:33 volumior volumio[5219]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 10 21:23:34 volumior volumio[5219]: xcb_connection_has_error() returned true May 10 21:23:34 volumior volumio[5219]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 10 21:23:34 volumior volumio[5219]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 10 21:23:34 volumior volumio[5219]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 10 21:23:34 volumior volumio[5219]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 10 21:23:34 volumior volumio[5219]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 10 21:23:34 volumior volumio[5219]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 10 21:23:34 volumior volumio[5219]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 10 21:23:34 volumior volumio[5219]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode May 10 21:23:34 volumior volumio[5219]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 10 21:23:36 volumior volumio[5219]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 10 21:23:41 volumior volumio[5219]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 10 21:23:45 volumior volumio[5219]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 10 21:23:49 volumior volumio[5219]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 10 21:23:49 volumior sudo[5745]: pam_unix(sudo:session): session closed for user root May 10 21:23:49 volumior volumio[5219]: info: Cannot mount NAS TS451 at system boot, trial number 2 ,retrying in 5 seconds May 10 21:23:53 volumior volumio[5219]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 10 21:23:53 volumior volumio[5219]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 10 21:23:53 volumior volumio[5219]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 10 21:23:53 volumior volumio[5219]: info: Discovery: Getting this device information May 10 21:23:53 volumior volumio[5219]: info: CoreCommandRouter::volumioGetState May 10 21:23:53 volumior volumio[5219]: info: CorePlayQueue::getTrack 0 May 10 21:23:53 volumior volumio[5219]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 10 21:23:54 volumior sudo[5917]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t nfs -o ro,soft,noauto 192.168.0.101:Multimedia /mnt/NAS/TS451 May 10 21:23:54 volumior sudo[5917]: pam_unix(sudo:session): session opened for user root by (uid=0) May 10 21:23:58 volumior volumio[5219]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 10 21:24:01 volumior volumio[5219]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 10 21:24:03 volumior volumio[5219]: info: CALLMETHOD: audio_interface alsa_controller saveVolumeOptions [object Object] May 10 21:24:03 volumior volumio[5219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveVolumeOptions May 10 21:24:03 volumior volumio[5219]: info: CoreCommandRouter::volumioGetState May 10 21:24:03 volumior volumio[5219]: info: CorePlayQueue::getTrack 0 May 10 21:24:03 volumior volumio[5219]: info: Restoring Previous Volume level: false false May 10 21:24:03 volumior volumio[5219]: info: Volume configurations have been set May 10 21:24:03 volumior volumio[5219]: info: QobuzConnect: setDeactiveState invoked May 10 21:24:03 volumior volumio[5219]: info: CoreCommandRouter::volumioGetState May 10 21:24:03 volumior volumio[5219]: info: CorePlayQueue::getTrack 0 May 10 21:24:03 volumior vtcs[5711]: [2025-05-10 21:24:03.494] [tisoc] [warning] [SessionManagerImpl.cpp:243] Illegal State: IDLE May 10 21:24:03 volumior vtcs[5711]: [2025-05-10 21:24:03.495] [tisoc] [error] [SpkconServer.cpp:376] recv error. socket disconnected May 10 21:24:03 volumior sudo[5930]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 10 21:24:03 volumior sudo[5930]: pam_unix(sudo:session): session opened for user root by (uid=0) May 10 21:24:03 volumior systemd[1]: Stopping Volumio Tidal Connect Service... May 10 21:24:03 volumior systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM May 10 21:24:03 volumior systemd[1]: vtcs.service: Succeeded. May 10 21:24:03 volumior systemd[1]: Stopped Volumio Tidal Connect Service. May 10 21:24:03 volumior sudo[5930]: pam_unix(sudo:session): session closed for user root May 10 21:24:03 volumior volumio[5219]: xcb_connection_has_error() returned true May 10 21:24:03 volumior volumio[5219]: info: CoreCommandRouter::volumioUpdateVolumeSettings May 10 21:24:03 volumior volumio[5219]: info: Updating Volume Controller Parameters: Device: 1 Name: Headphones Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 May 10 21:24:03 volumior volumio[5219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume May 10 21:24:03 volumior volumio[5219]: info: Disabling external Volume Control May 10 21:24:03 volumior volumio[5219]: info: CoreCommandRouter::getUIConfigOnPlugin May 10 21:24:03 volumior sudo[5937]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 10 21:24:03 volumior sudo[5937]: pam_unix(sudo:session): session opened for user root by (uid=0) May 10 21:24:03 volumior volumio[5219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 21:24:03 volumior volumio[5219]: info: QobuzConnect: Setting Qobuz Connect card as outputdevice to 1 May 10 21:24:03 volumior sudo[5937]: pam_unix(sudo:session): session closed for user root May 10 21:24:03 volumior volumio[5219]: ------------------------------------ BT MESSAGE: BT STATUS: running May 10 21:24:03 volumior sudo[5944]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect May 10 21:24:03 volumior sudo[5944]: pam_unix(sudo:session): session opened for user root by (uid=0) May 10 21:24:03 volumior systemd[1]: Stopping Volumio Qobuz Connect Service... May 10 21:24:03 volumior qobuz-connect[5667]: 20250510 21:24:03.679 [5667.5667] INFO SampleApp: Stopping Local configuration server May 10 21:24:03 volumior qobuz-connect[5667]: 20250510 21:24:03.690 [5667.5667] INFO SampleApp: shat down connection on UNIX socket May 10 21:24:03 volumior systemd[1]: qobuz-connect.service: Succeeded. May 10 21:24:03 volumior systemd[1]: Stopped Volumio Qobuz Connect Service. May 10 21:24:03 volumior volumio[5219]: ------------------------------------ BT MESSAGE: BT STATUS: running May 10 21:24:03 volumior volumio[5219]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected May 10 21:24:03 volumior volumio[5219]: info: QobuzConnect: setDeactiveState invoked May 10 21:24:03 volumior volumio[5219]: info: CoreCommandRouter::volumioGetState May 10 21:24:03 volumior volumio[5219]: info: CorePlayQueue::getTrack 0 May 10 21:24:03 volumior volumio[5219]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 10 21:24:03 volumior volumio[5219]: xcb_connection_has_error() returned true May 10 21:24:03 volumior volumio[5219]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions May 10 21:24:03 volumior volumio[5219]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 10 21:24:03 volumior volumio[5219]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 10 21:24:03 volumior systemd[1]: Started Volumio Qobuz Connect Service. May 10 21:24:03 volumior sudo[5944]: pam_unix(sudo:session): session closed for user root May 10 21:24:03 volumior volumio[5219]: xcb_connection_has_error() returned true May 10 21:24:03 volumior volumio[5219]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 10 21:24:03 volumior volumio[5219]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 10 21:24:03 volumior volumio[5219]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 10 21:24:03 volumior volumio[5219]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 10 21:24:03 volumior volumio[5219]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 10 21:24:03 volumior volumio[5219]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 10 21:24:03 volumior volumio[5219]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 10 21:24:03 volumior volumio[5219]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode May 10 21:24:03 volumior volumio[5219]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 10 21:24:03 volumior volumio[5219]: info: VolumeController:: Volume=0 Mute =false May 10 21:24:03 volumior volumio[5219]: info: CoreStateMachine::pushState May 10 21:24:03 volumior volumio[5219]: info: CorePlayQueue::getTrack 0 May 10 21:24:03 volumior volumio[5219]: info: CoreCommandRouter::volumioPushState May 10 21:24:03 volumior volumio[5219]: info: MRS: Pushing multiroomSync output update for this device May 10 21:24:03 volumior volumio[5219]: info: MRS: Pushing multiroomSync output May 10 21:24:03 volumior volumio[5219]: info: CoreCommandRouter::volumioGetState May 10 21:24:03 volumior volumio[5219]: info: CorePlayQueue::getTrack 0 May 10 21:24:03 volumior volumio[5219]: info: Executing endpoint qc_getconfig May 10 21:24:03 volumior volumio[5219]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig May 10 21:24:03 volumior qobuz-connect[5947]: 20250510 21:24:03.863 [5947.5947] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket May 10 21:24:03 volumior volumio[5219]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] May 10 21:24:03 volumior volumio[5219]: info: QobuzConnect: QOBUZ Connect daemon connected May 10 21:24:03 volumior qobuz-connect[5947]: 20250510 21:24:03.867 [5947.5947] INFO VolumeManager: [0x17f8970]: Setting new playback volume: 75 May 10 21:24:03 volumior qobuz-connect[5947]: 20250510 21:24:03.867 [5947.5947] INFO VolumeManager: [0x17f8970]: Setting new mute state: 0 May 10 21:24:03 volumior qobuz-connect[5947]: 20250510 21:24:03.867 [5947.5947] INFO QobuzConnect: [0x17f9340]: Client initialized! May 10 21:24:03 volumior qobuz-connect[5947]: 20250510 21:24:03.867 [5947.5947] INFO SampleApp: Starting Avahi advertising, name: VolumioR, service name: _qobuz-connect._tcp May 10 21:24:03 volumior qobuz-connect[5947]: 20250510 21:24:03.873 [5947.5947] INFO LocalConfigManager: [0x17f8250]: Starting Local Configuration server May 10 21:24:03 volumior qobuz-connect[5947]: 20250510 21:24:03.873 [5947.5947] INFO SampleApp: Starting Local configuration server May 10 21:24:03 volumior qobuz-connect[5947]: 20250510 21:24:03.874 [5947.5947] INFO SampleApp: Connected to UNIX socket client 0x17edf60 May 10 21:24:04 volumior qobuz-connect[5947]: 20250510 21:24:04.014 [5947.5947] INFO SampleApp: Playback volume changed: 75 May 10 21:24:04 volumior volumio[5219]: info: CoreCommandRouter::volumioGetState May 10 21:24:04 volumior volumio[5219]: info: CorePlayQueue::getTrack 0 May 10 21:24:05 volumior volumio[5219]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 10 21:24:06 volumior volumio[5219]: info: TidalConnect service stoped! May 10 21:24:06 volumior volumio[5219]: info: TidalConnect service stoped! May 10 21:24:06 volumior volumio[5219]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect May 10 21:24:06 volumior volumio[5219]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect May 10 21:24:06 volumior sudo[5978]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service May 10 21:24:06 volumior sudo[5978]: pam_unix(sudo:session): session opened for user root by (uid=0) May 10 21:24:06 volumior systemd[1]: Started Volumio Tidal Connect Service. May 10 21:24:06 volumior sudo[5978]: pam_unix(sudo:session): session closed for user root May 10 21:24:06 volumior volumio[5219]: info: Executing endpoint tc_getconfig May 10 21:24:06 volumior volumio[5219]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig May 10 21:24:06 volumior vtcs[5980]: STARTING TidalConnect services, version: 1.4.0.34 May 10 21:24:07 volumior vtcs[5980]: STARTED TidalConnect services. May 10 21:24:07 volumior volumio[5219]: info: Executing endpoint tc_connect May 10 21:24:07 volumior volumio[5219]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect May 10 21:24:07 volumior volumio[5219]: info: Connecting to TidalConnect May 10 21:24:07 volumior volumio[5219]: info: CoreCommandRouter::servicePushState May 10 21:24:07 volumior volumio[5219]: info: CoreStateMachine::pushState May 10 21:24:07 volumior volumio[5219]: info: CorePlayQueue::getTrack 0 May 10 21:24:07 volumior volumio[5219]: info: CoreCommandRouter::volumioPushState May 10 21:24:07 volumior volumio[5219]: info: MRS: Pushing multiroomSync output update for this device May 10 21:24:07 volumior volumio[5219]: info: MRS: Pushing multiroomSync output May 10 21:24:07 volumior volumio[5219]: info: CoreCommandRouter::volumioGetState May 10 21:24:07 volumior volumio[5219]: info: CorePlayQueue::getTrack 0 May 10 21:24:07 volumior volumio[5219]: info: CorePlayQueue::getTrack 0 May 10 21:24:07 volumior volumio[5219]: 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} May 10 21:24:07 volumior volumio[5219]: verbose: CURRENT POSITION 0 May 10 21:24:07 volumior volumio[5219]: info: CoreStateMachine::syncState stateService stop May 10 21:24:07 volumior volumio[5219]: info: CoreStateMachine::syncState currentStatus stop May 10 21:24:07 volumior volumio[5219]: info: CoreStateMachine::pushState May 10 21:24:07 volumior volumio[5219]: info: CorePlayQueue::getTrack 0 May 10 21:24:07 volumior volumio[5219]: info: CoreCommandRouter::volumioPushState May 10 21:24:07 volumior volumio[5219]: info: MRS: Pushing multiroomSync output update for this device May 10 21:24:07 volumior volumio[5219]: info: MRS: Pushing multiroomSync output May 10 21:24:07 volumior volumio[5219]: info: CoreCommandRouter::volumioGetState May 10 21:24:07 volumior volumio[5219]: info: CorePlayQueue::getTrack 0 May 10 21:24:07 volumior volumio[5219]: info: No code May 10 21:24:07 volumior volumio[5219]: info: CoreStateMachine::pushState May 10 21:24:07 volumior volumio[5219]: info: CorePlayQueue::getTrack 0 May 10 21:24:07 volumior volumio[5219]: info: CoreCommandRouter::volumioPushState May 10 21:24:07 volumior volumio[5219]: info: MRS: Pushing multiroomSync output update for this device May 10 21:24:07 volumior volumio[5219]: info: MRS: Pushing multiroomSync output May 10 21:24:07 volumior volumio[5219]: info: CoreCommandRouter::volumioGetState May 10 21:24:07 volumior volumio[5219]: info: CorePlayQueue::getTrack 0 May 10 21:24:07 volumior volumio[5219]: info: CoreCommandRouter::servicePushState May 10 21:24:07 volumior volumio[5219]: info: CoreStateMachine::pushState May 10 21:24:07 volumior volumio[5219]: info: CorePlayQueue::getTrack 0 May 10 21:24:07 volumior volumio[5219]: info: CoreCommandRouter::volumioPushState May 10 21:24:07 volumior volumio[5219]: info: MRS: Pushing multiroomSync output update for this device May 10 21:24:07 volumior volumio[5219]: info: MRS: Pushing multiroomSync output May 10 21:24:07 volumior volumio[5219]: info: CoreCommandRouter::volumioGetState May 10 21:24:07 volumior volumio[5219]: info: CorePlayQueue::getTrack 0 May 10 21:24:07 volumior volumio[5219]: info: CorePlayQueue::getTrack 0 May 10 21:24:07 volumior volumio[5219]: 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} May 10 21:24:07 volumior volumio[5219]: verbose: CURRENT POSITION 0 May 10 21:24:07 volumior volumio[5219]: info: CoreStateMachine::syncState stateService stop May 10 21:24:07 volumior volumio[5219]: info: CoreStateMachine::syncState currentStatus stop May 10 21:24:07 volumior volumio[5219]: info: CoreStateMachine::pushState May 10 21:24:07 volumior volumio[5219]: info: CorePlayQueue::getTrack 0 May 10 21:24:07 volumior volumio[5219]: info: CoreCommandRouter::volumioPushState May 10 21:24:07 volumior volumio[5219]: info: MRS: Pushing multiroomSync output update for this device May 10 21:24:07 volumior volumio[5219]: info: MRS: Pushing multiroomSync output May 10 21:24:07 volumior volumio[5219]: info: CoreCommandRouter::volumioGetState May 10 21:24:07 volumior volumio[5219]: info: CorePlayQueue::getTrack 0 May 10 21:24:07 volumior volumio[5219]: info: No code May 10 21:24:07 volumior volumio[5219]: info: CoreStateMachine::pushState May 10 21:24:07 volumior volumio[5219]: info: CorePlayQueue::getTrack 0 May 10 21:24:07 volumior volumio[5219]: info: CoreCommandRouter::volumioPushState May 10 21:24:07 volumior volumio[5219]: info: MRS: Pushing multiroomSync output update for this device May 10 21:24:07 volumior volumio[5219]: info: MRS: Pushing multiroomSync output May 10 21:24:07 volumior volumio[5219]: info: CoreCommandRouter::volumioGetState May 10 21:24:07 volumior volumio[5219]: info: CorePlayQueue::getTrack 0 May 10 21:24:07 volumior volumio[5219]: info: VolumeController::SetAlsaVolumeNaN May 10 21:24:08 volumior volumio[5219]: info: CoreStateMachine::pushState May 10 21:24:08 volumior volumio[5219]: info: CorePlayQueue::getTrack 0 May 10 21:24:08 volumior volumio[5219]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 10 21:24:08 volumior volumio[5219]: info: CoreCommandRouter::volumioPushState May 10 21:24:08 volumior volumio[5219]: info: MRS: Pushing multiroomSync output update for this device May 10 21:24:08 volumior volumio[5219]: info: MRS: Pushing multiroomSync output May 10 21:24:08 volumior volumio[5219]: info: CoreCommandRouter::volumioGetState May 10 21:24:08 volumior volumio[5219]: info: CorePlayQueue::getTrack 0 May 10 21:24:09 volumior volumio[5219]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 10 21:24:09 volumior volumio[5219]: info: TidalConnect service started! May 10 21:24:13 volumior volumio[5219]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 10 21:24:16 volumior volumio[5219]: info: CoreCommandRouter::getUIConfigOnPlugin May 10 21:24:16 volumior volumio[5219]: info: CoreCommandRouter::getUIConfigOnPlugin May 10 21:24:16 volumior volumio[5219]: info: Retrieving Cloud Streaming UI May 10 21:24:16 volumior volumio[5219]: info: Getting Tidal Cloud Configuration May 10 21:24:16 volumior volumio[5219]: info: CoreCommandRouter::getUIConfigOnPlugin May 10 21:24:16 volumior volumio[5219]: info: Getting Qobuz Cloud Configuration May 10 21:24:16 volumior volumio[5219]: info: Asking plugin for UI Config May 10 21:24:16 volumior volumio[5219]: info: CoreCommandRouter::getUIConfigOnPlugin May 10 21:24:16 volumior volumio[5219]: info: Getting Spotify Cloud Configuration May 10 21:24:16 volumior volumio[5219]: info: Asking plugin for UI Config May 10 21:24:16 volumior volumio[5219]: info: CoreCommandRouter::getUIConfigOnPlugin May 10 21:24:16 volumior volumio[5219]: info: Saving Spotify Acccount May 10 21:24:16 volumior volumio[5219]: info: Got it May 10 21:24:16 volumior volumio[5219]: error: Could not retrieve Spotify Config from plugin Spotify: no section found May 10 21:24:16 volumior volumio[5219]: info: Got Tidal Cloud Configuration May 10 21:24:16 volumior volumio[5219]: info: Got it May 10 21:24:16 volumior volumio[5219]: info: CoreCommandRouter::getUIConfigOnPlugin May 10 21:24:16 volumior volumio[5219]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 10 21:24:16 volumior volumio[5219]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 10 21:24:16 volumior volumio[5219]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 10 21:24:16 volumior volumio[5219]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 10 21:24:16 volumior volumio[5219]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 10 21:24:16 volumior volumio[5219]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 10 21:24:16 volumior volumio[5219]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 10 21:24:16 volumior volumio[5219]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 10 21:24:16 volumior volumio[5219]: info: CoreCommandRouter::volumioGetBrowseSources May 10 21:24:16 volumior volumio[5219]: info: CoreCommandRouter::volumioGetBrowseSources May 10 21:24:16 volumior volumio[5219]: info: CoreCommandRouter::volumioGetBrowseSources May 10 21:24:17 volumior volumio[5219]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 10 21:24:17 volumior volumio[5219]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares May 10 21:24:17 volumior volumio[5219]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 10 21:24:21 volumior volumio[5219]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 10 21:24:21 volumior volumio[5219]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 10 21:24:23 volumior ntpd[1205]: 84.77.195.114 local addr 192.168.0.122 -> May 10 21:24:23 volumior volumio[5219]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 10 21:24:23 volumior volumio[5219]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 10 21:24:23 volumior volumio[5219]: info: Discovery: Getting this device information May 10 21:24:23 volumior volumio[5219]: info: CoreCommandRouter::volumioGetState May 10 21:24:23 volumior volumio[5219]: info: CorePlayQueue::getTrack 0 May 10 21:24:23 volumior volumio[5219]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 10 21:24:24 volumior volumio[5219]: info: CoreCommandRouter::executeOnPlugin: networkfs , deleteShare May 10 21:24:25 volumior volumio[5219]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 10 21:24:25 volumior volumio[5219]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares May 10 21:24:25 volumior volumio[5219]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 10 21:24:29 volumior volumio[5219]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 10 21:24:29 volumior volumio[5219]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 10 21:24:33 volumior volumio[5219]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 10 21:24:33 volumior ntpd[1205]: 213.165.71.32 local addr 192.168.0.122 -> May 10 21:24:33 volumior volumio[5219]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 10 21:24:37 volumior volumio[5219]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 10 21:24:37 volumior volumio[5219]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 10 21:24:39 volumior sudo[5917]: pam_unix(sudo:session): session closed for user root May 10 21:24:39 volumior volumio[5219]: info: Cannot mount NAS TS451 at system boot, trial number 3 ,retrying in 5 seconds May 10 21:24:41 volumior volumio[5219]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 10 21:24:41 volumior volumio[5219]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 10 21:24:44 volumior volumio[5219]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 10 21:24:44 volumior volumio[5219]: TypeError: Cannot read property 'replace' of undefined May 10 21:24:44 volumior volumio[5219]: at ControllerNetworkfs.mountShare (/volumio/app/plugins/system_controller/networkfs/index.js:202:28) May 10 21:24:44 volumior volumio[5219]: at Timeout._onTimeout (/volumio/app/plugins/system_controller/networkfs/index.js:255:20) May 10 21:24:44 volumior volumio[5219]: at listOnTimeout (internal/timers.js:554:17) May 10 21:24:44 volumior volumio[5219]: at processTimers (internal/timers.js:497:7) May 10 21:24:44 volumior volumio[5219]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 10 21:24:44 volumior sudo[6124]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-10 21:23 May 10 21:24:44 volumior sudo[6124]: 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="Fri 09 May 2025 12:45:48 AM CEST" VOLUMIO_VERSION="3.808" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fd57d03cbffb8029ebc849ab499a86a4"