-- Logs begin at Thu 2019-02-14 11:11:58 CET, end at Mon 2026-01-12 16:45:40 CET. -- Jan 12 16:44:00 volumiokueche volumio[1018]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Jan 12 16:44:00 volumiokueche volumio[1018]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Jan 12 16:44:00 volumiokueche volumio[1018]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 12 16:44:00 volumiokueche volumio[1018]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 12 16:44:00 volumiokueche volumio[1018]: info: Starting MyVolumio Remote Streaming Endpoints Jan 12 16:44:00 volumiokueche volumio[1018]: info: MyVolumio login type: Token Jan 12 16:44:00 volumiokueche volumio[1018]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Jan 12 16:44:00 volumiokueche volumio[1018]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Jan 12 16:44:00 volumiokueche volumio[1312]: ................................................++++ Jan 12 16:44:00 volumiokueche volumio[1018]: info: Starting Streaming Service Transparent Proxy Jan 12 16:44:00 volumiokueche volumio[1018]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Jan 12 16:44:00 volumiokueche volumio[1018]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Jan 12 16:44:00 volumiokueche volumio[1018]: info: Streaming services startup Jan 12 16:44:00 volumiokueche volumio[1018]: info: Starting Streaming Daemon Jan 12 16:44:01 volumiokueche sudo[1335]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jan 12 16:44:01 volumiokueche sudo[1335]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 16:44:01 volumiokueche volumio[1018]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Jan 12 16:44:01 volumiokueche sudo[1335]: pam_unix(sudo:session): session closed for user root Jan 12 16:44:01 volumiokueche volumio[1018]: verbose: New Socket.io Connection to 192.168.0.156:3000 from 192.168.0.179 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Jan 12 16:44:01 volumiokueche volumio[1018]: error: Cannot start Volumio Streaming Daemon Jan 12 16:44:01 volumiokueche volumio[1018]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jan 12 16:44:01 volumiokueche volumio[1018]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jan 12 16:44:01 volumiokueche volumio[1018]: STREAMING PROXY: Starting server on port 3245 Jan 12 16:44:01 volumiokueche volumio[1018]: Node JS runtime: 14 Jan 12 16:44:08 volumiokueche volumio[1312]: ..........................................................................................++++ Jan 12 16:44:08 volumiokueche volumio[1312]: e is 65537 (0x010001) Jan 12 16:44:08 volumiokueche volumio[1312]: writing RSA key Jan 12 16:44:09 volumiokueche volumio[1018]: verbose: New Socket.io Connection to 192.168.0.156:3000 from 192.168.0.179 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 12 Jan 12 16:44:09 volumiokueche volumio[1018]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Jan 12 16:44:09 volumiokueche volumio[1018]: info: MyVolumio token set successfully Jan 12 16:44:09 volumiokueche volumio[1018]: info: MYVOLUMIO: Adding device Jan 12 16:44:09 volumiokueche volumio[1018]: info: MYVOLUMIO: Evaluating Server Jan 12 16:44:10 volumiokueche volumio[1018]: info: MyVolumio Plan changed: superstar Jan 12 16:44:10 volumiokueche volumio[1018]: info: [MyVolumio PluginManager] Subscribed plan changed to superstar Jan 12 16:44:10 volumiokueche volumio[1018]: info: Adding audio output: Jan 12 16:44:10 volumiokueche volumio[1018]: info: MYVOLUMIO: Adding device Jan 12 16:44:10 volumiokueche volumio[1018]: info: MYVOLUMIO: Evaluating Server Jan 12 16:44:10 volumiokueche volumio[1018]: info: Remote config written successfully Jan 12 16:44:10 volumiokueche volumio[1018]: info: Starting Tunnel 1 Jan 12 16:44:10 volumiokueche volumio[1018]: info: Starting Tunnel Connection Checker Jan 12 16:44:10 volumiokueche volumio[1018]: info: Completed starting MyVolumio Plugin Jan 12 16:44:10 volumiokueche volumio[1018]: info: Setting Geolocation for MyVolumio to eu6 Jan 12 16:44:10 volumiokueche volumio[1018]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 12 16:44:10 volumiokueche volumio[1018]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 12 16:44:10 volumiokueche volumio[1018]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 12 16:44:11 volumiokueche volumio[1018]: info: MYVolumio Device enabled Jan 12 16:44:11 volumiokueche volumio[1018]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Jan 12 16:44:11 volumiokueche volumio[1018]: info: MyVolumio status changed Jan 12 16:44:11 volumiokueche volumio[1018]: info: Streaming services startup Jan 12 16:44:11 volumiokueche volumio[1018]: info: Starting Streaming Daemon Jan 12 16:44:11 volumiokueche sudo[1410]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jan 12 16:44:11 volumiokueche sudo[1410]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 16:44:11 volumiokueche volumio[1018]: info: Successfully Added MyVolumio device Jan 12 16:44:11 volumiokueche volumio[1018]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Jan 12 16:44:11 volumiokueche sudo[1410]: pam_unix(sudo:session): session closed for user root Jan 12 16:44:11 volumiokueche volumio[1018]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... Jan 12 16:44:11 volumiokueche volumio[1018]: info: [1768232651745] Starting BluetoothController Jan 12 16:44:11 volumiokueche volumio[1018]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... Jan 12 16:44:12 volumiokueche volumio[1018]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom Jan 12 16:44:12 volumiokueche sudo[1416]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Jan 12 16:44:12 volumiokueche sudo[1416]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 16:44:12 volumiokueche sudo[1416]: pam_unix(sudo:session): session closed for user root Jan 12 16:44:13 volumiokueche volumio[1018]: info: MRS: MultiRoom plugin initialized Jan 12 16:44:13 volumiokueche volumio[1018]: info: MRS: STOPPING SNAPCLIENT Jan 12 16:44:13 volumiokueche volumio[1018]: info: MRS: Snap server stop Jan 12 16:44:13 volumiokueche sudo[1432]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Jan 12 16:44:13 volumiokueche sudo[1432]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 16:44:13 volumiokueche volumio[1018]: info: MRS: STOPPING volumioStreaming Jan 12 16:44:13 volumiokueche sudo[1432]: pam_unix(sudo:session): session closed for user root Jan 12 16:44:13 volumiokueche sudo[1435]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Jan 12 16:44:13 volumiokueche sudo[1435]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 16:44:13 volumiokueche sudo[1435]: pam_unix(sudo:session): session closed for user root Jan 12 16:44:13 volumiokueche sudo[1438]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Jan 12 16:44:13 volumiokueche sudo[1438]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 16:44:13 volumiokueche sudo[1438]: pam_unix(sudo:session): session closed for user root Jan 12 16:44:13 volumiokueche volumio[1018]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... Jan 12 16:44:13 volumiokueche sudo[1441]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Jan 12 16:44:13 volumiokueche sudo[1441]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 16:44:13 volumiokueche sudo[1441]: pam_unix(sudo:session): session closed for user root Jan 12 16:44:13 volumiokueche volumio[1018]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... Jan 12 16:44:13 volumiokueche volumio[1018]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... Jan 12 16:44:13 volumiokueche volumio[1018]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... Jan 12 16:44:13 volumiokueche volumio[1018]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... Jan 12 16:44:13 volumiokueche volumio[1018]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio Jan 12 16:44:13 volumiokueche volumio[1018]: info: [MyVolumio PluginManager] Loading plugin "tidal"... Jan 12 16:44:13 volumiokueche volumio[1018]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... Jan 12 16:44:13 volumiokueche volumio[1018]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... Jan 12 16:44:13 volumiokueche volumio[1018]: info: [MyVolumio PluginManager] Loading plugin "qobuzconnect"... Jan 12 16:44:13 volumiokueche volumio[1018]: info: Preparing to generate the ALSA configuration file Jan 12 16:44:13 volumiokueche volumio[1018]: info: Updating MyVolumio device info Jan 12 16:44:13 volumiokueche volumio[1018]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 12 16:44:13 volumiokueche volumio[1018]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 12 16:44:13 volumiokueche volumio[1018]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 12 16:44:13 volumiokueche volumio[1018]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Jan 12 16:44:13 volumiokueche volumio[1018]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Jan 12 16:44:13 volumiokueche volumio[1018]: info: Reading ALSA contributions from plugins. Jan 12 16:44:13 volumiokueche volumio[1018]: info: Setting Geolocation for MyVolumio to eu8 Jan 12 16:44:13 volumiokueche volumio[1018]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 12 16:44:13 volumiokueche volumio[1018]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 12 16:44:13 volumiokueche volumio[1018]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 12 16:44:13 volumiokueche volumio[1018]: info: MRS: Removed streaming files Jan 12 16:44:13 volumiokueche volumio[1018]: info: MRS: volumioStreaming STOPPED Jan 12 16:44:13 volumiokueche volumio[1018]: info: MRS: SNAPSERVER STOPPED Jan 12 16:44:13 volumiokueche volumio[1018]: info: MRS: SNAPCLIENT STOPPED Jan 12 16:44:13 volumiokueche volumio[1018]: error: Cannot start Volumio Streaming Daemon Jan 12 16:44:13 volumiokueche volumio[1018]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jan 12 16:44:13 volumiokueche volumio[1018]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jan 12 16:44:13 volumiokueche volumio[1018]: info: Asound.conf file written Jan 12 16:44:13 volumiokueche sudo[1444]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Jan 12 16:44:13 volumiokueche sudo[1444]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 16:44:13 volumiokueche sudo[1444]: pam_unix(sudo:session): session closed for user root Jan 12 16:44:13 volumiokueche volumio[1018]: info: Output device has changed, restarting MPD Jan 12 16:44:13 volumiokueche sudo[1449]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 12 16:44:13 volumiokueche sudo[1449]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 16:44:13 volumiokueche sudo[1449]: pam_unix(sudo:session): session closed for user root Jan 12 16:44:13 volumiokueche volumio[1018]: info: Output device has changed, restarting Shairport Sync Jan 12 16:44:13 volumiokueche volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 16:44:13 volumiokueche volumio[1018]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 12 16:44:13 volumiokueche sudo[1452]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 12 16:44:13 volumiokueche sudo[1452]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 16:44:13 volumiokueche systemd[1]: Stopping Music Player Daemon... Jan 12 16:44:13 volumiokueche volumio[1018]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 12 16:44:13 volumiokueche volumio[1018]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jan 12 16:44:13 volumiokueche volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 16:44:13 volumiokueche volumio[1018]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth Jan 12 16:44:13 volumiokueche volumio[1018]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 12 16:44:13 volumiokueche volumio[1018]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom Jan 12 16:44:13 volumiokueche volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Jan 12 16:44:13 volumiokueche volumio[1018]: info: MRS: Pushing multiroomSync output for this device Jan 12 16:44:13 volumiokueche volumio[1018]: info: MRS: Pushing multiroomSync output Jan 12 16:44:13 volumiokueche volumio[1018]: info: Adding audio output: Jan 12 16:44:13 volumiokueche sudo[1460]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hciconfig hci0 name Volumiokueche Jan 12 16:44:13 volumiokueche sudo[1460]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 16:44:13 volumiokueche volumio[1018]: info: Adding audio output: Jan 12 16:44:13 volumiokueche sudo[1460]: pam_unix(sudo:session): session closed for user root Jan 12 16:44:13 volumiokueche volumio[1018]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jan 12 16:44:13 volumiokueche volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 16:44:13 volumiokueche systemd[1]: mpd.service: Succeeded. Jan 12 16:44:13 volumiokueche systemd[1]: Stopped Music Player Daemon. Jan 12 16:44:13 volumiokueche volumio[1018]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio Jan 12 16:44:13 volumiokueche volumio[1018]: info: Adding METAVOLUMIO REST API Endpoints Jan 12 16:44:13 volumiokueche volumio[1018]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Jan 12 16:44:13 volumiokueche volumio[1018]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Jan 12 16:44:13 volumiokueche volumio[1018]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Jan 12 16:44:13 volumiokueche volumio[1018]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Jan 12 16:44:13 volumiokueche volumio[1018]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui Jan 12 16:44:13 volumiokueche volumio[1018]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller Jan 12 16:44:13 volumiokueche volumio[1018]: info: Preparing CD Folders Jan 12 16:44:13 volumiokueche volumio[1018]: info: Adding CD REST API Endpoints Jan 12 16:44:13 volumiokueche volumio[1018]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Jan 12 16:44:13 volumiokueche volumio[1018]: info: Starting UDEV Watcher for CD Jan 12 16:44:13 volumiokueche volumio[1018]: info: Detecting CD presence with UDEV Jan 12 16:44:13 volumiokueche volumio[1018]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices Jan 12 16:44:13 volumiokueche volumio[1018]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs Jan 12 16:44:13 volumiokueche volumio[1018]: info: [MyVolumio PluginManager] Plugin smart_inputs disabled by user. Not starting Jan 12 16:44:13 volumiokueche volumio[1018]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio Jan 12 16:44:13 volumiokueche volumio[1018]: error: Hi Res Audio Failed Login: Missing Login Data Jan 12 16:44:13 volumiokueche volumio[1018]: info: Adding HIGHRESAUDIO REST API Endpoints Jan 12 16:44:13 volumiokueche volumio[1018]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Jan 12 16:44:13 volumiokueche volumio[1018]: info: Adding saveAccountData_hi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Jan 12 16:44:13 volumiokueche volumio[1018]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal Jan 12 16:44:13 volumiokueche volumio[1018]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz Jan 12 16:44:13 volumiokueche volumio[1018]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect Jan 12 16:44:13 volumiokueche systemd[1]: Starting Music Player Daemon... Jan 12 16:44:13 volumiokueche volumio[1018]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuzconnect Jan 12 16:44:13 volumiokueche volumio[1018]: info: Adding qc_getconfig REST Endpoint for plugin: music_service/qobuzconnect Jan 12 16:44:14 volumiokueche sudo[1467]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jan 12 16:44:14 volumiokueche sudo[1467]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 16:44:14 volumiokueche systemd[1]: systemd-timedated.service: Succeeded. Jan 12 16:44:14 volumiokueche sudo[1463]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 12 16:44:14 volumiokueche sudo[1463]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 16:44:14 volumiokueche sudo[1463]: pam_unix(sudo:session): session closed for user root Jan 12 16:44:14 volumiokueche volumio[1018]: info: QobuzConnect: Starting Qobuz Connect socket and service Jan 12 16:44:14 volumiokueche sudo[1467]: pam_unix(sudo:session): session closed for user root Jan 12 16:44:14 volumiokueche sudo[1478]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Jan 12 16:44:14 volumiokueche sudo[1478]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 16:44:14 volumiokueche volumio[1018]: info: QobuzConnect: Opened /tmp/qbz-connect.socket socket, listening for connections Jan 12 16:44:14 volumiokueche volumio[1018]: info: Adding TIDAL REST API Endpoints Jan 12 16:44:14 volumiokueche volumio[1018]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal Jan 12 16:44:14 volumiokueche volumio[1018]: info: Stopping AccessToken refresher cron for QOBUZ Jan 12 16:44:14 volumiokueche sudo[1478]: pam_unix(sudo:session): session closed for user root Jan 12 16:44:14 volumiokueche sudo[1480]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Jan 12 16:44:14 volumiokueche sudo[1480]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 16:44:14 volumiokueche volumio[1018]: info: AccessToken refresher cron started for QOBUZ Jan 12 16:44:14 volumiokueche volumio[1018]: info: Adding QOBUZ REST API Endpoints Jan 12 16:44:14 volumiokueche volumio[1018]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz Jan 12 16:44:14 volumiokueche systemd[1]: Started Volumio Qobuz Connect Service. Jan 12 16:44:14 volumiokueche sudo[1480]: pam_unix(sudo:session): session closed for user root Jan 12 16:44:14 volumiokueche volumio[1018]: info: MRS: Adding multiroomSync output Jan 12 16:44:14 volumiokueche volumio[1018]: info: Adding audio output: Jan 12 16:44:14 volumiokueche volumio[1018]: info: MRS: Pushing multiroomSync output Jan 12 16:44:14 volumiokueche volumio[1018]: info: MRS: Adding multiroomSync output Jan 12 16:44:14 volumiokueche volumio[1018]: info: Adding audio output: Jan 12 16:44:14 volumiokueche volumio[1018]: info: MRS: Pushing multiroomSync output Jan 12 16:44:14 volumiokueche volumio[1018]: info: Bluetooth name changed to Volumiokueche Jan 12 16:44:14 volumiokueche volumio[1018]: info: MPD Permissions set Jan 12 16:44:14 volumiokueche volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 16:44:14 volumiokueche volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 16:44:14 volumiokueche volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 16:44:14 volumiokueche volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 16:44:14 volumiokueche volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 16:44:14 volumiokueche volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 16:44:14 volumiokueche volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 16:44:14 volumiokueche volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 16:44:14 volumiokueche volumio[1018]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 12 16:44:14 volumiokueche volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 16:44:14 volumiokueche volumio[1018]: info: Executing endpoint qc_getconfig Jan 12 16:44:14 volumiokueche volumio[1018]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Jan 12 16:44:14 volumiokueche qobuz-connect[1482]: 20260112 16:44:14.382 [1482.1482] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Jan 12 16:44:14 volumiokueche volumio[1018]: info: Starting Shairport Sync Jan 12 16:44:14 volumiokueche volumio[1018]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Jan 12 16:44:14 volumiokueche volumio[1018]: info: QobuzConnect: QOBUZ Connect daemon connected Jan 12 16:44:14 volumiokueche volumio[1018]: info: Successfully Added MyVolumio device Jan 12 16:44:14 volumiokueche sudo[1491]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 12 16:44:14 volumiokueche sudo[1491]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 16:44:14 volumiokueche volumio[1018]: info: Updating MyVolumio device info Jan 12 16:44:14 volumiokueche volumio[1018]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 12 16:44:14 volumiokueche volumio[1018]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 12 16:44:14 volumiokueche volumio[1018]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 12 16:44:14 volumiokueche volumio[1018]: info: Successfully Updated MyVolumio device Jan 12 16:44:14 volumiokueche systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jan 12 16:44:14 volumiokueche systemd[1]: shairport-sync.service: Succeeded. Jan 12 16:44:14 volumiokueche systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jan 12 16:44:14 volumiokueche qobuz-connect[1482]: 20260112 16:44:14.491 [1482.1482] INFO VolumeManager: [0x1cf3890]: Setting new playback volume: 75 Jan 12 16:44:14 volumiokueche qobuz-connect[1482]: 20260112 16:44:14.491 [1482.1482] INFO VolumeManager: [0x1cf3890]: Setting new mute state: 0 Jan 12 16:44:14 volumiokueche qobuz-connect[1482]: 20260112 16:44:14.491 [1482.1482] INFO QobuzConnect: [0x1cf4260]: Client initialized! Jan 12 16:44:14 volumiokueche qobuz-connect[1482]: 20260112 16:44:14.491 [1482.1482] INFO SampleApp: Starting Avahi advertising, name: Volumiokueche, service name: _qobuz-connect._tcp Jan 12 16:44:14 volumiokueche qobuz-connect[1482]: 20260112 16:44:14.499 [1482.1482] INFO LocalConfigManager: [0x1cf3170]: Starting Local Configuration server Jan 12 16:44:14 volumiokueche qobuz-connect[1482]: 20260112 16:44:14.499 [1482.1482] INFO SampleApp: Starting Local configuration server Jan 12 16:44:14 volumiokueche qobuz-connect[1482]: 20260112 16:44:14.500 [1482.1482] INFO SampleApp: Connected to UNIX socket client 0x1ce8f60 Jan 12 16:44:14 volumiokueche qobuz-connect[1482]: 20260112 16:44:14.532 [1482.1482] INFO SampleApp: Playback volume changed: 75 Jan 12 16:44:14 volumiokueche volumio[1018]: info: CoreCommandRouter::volumioGetState Jan 12 16:44:14 volumiokueche volumio[1018]: info: CorePlayQueue::getTrack 0 Jan 12 16:44:14 volumiokueche systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jan 12 16:44:14 volumiokueche sudo[1491]: pam_unix(sudo:session): session closed for user root Jan 12 16:44:14 volumiokueche volumio[1018]: info: Shairport-Sync Started Jan 12 16:44:14 volumiokueche volumio[1018]: info: Successfully Updated MyVolumio device Jan 12 16:44:14 volumiokueche mpd[1475]: Jan 12 16:44 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 12 16:44:15 volumiokueche volumio[1018]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 12 16:44:15 volumiokueche volumio[1018]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 13 Jan 12 16:44:15 volumiokueche volumio[1018]: info: CoreCommandRouter::volumioGetState Jan 12 16:44:15 volumiokueche volumio[1018]: info: CorePlayQueue::getTrack 0 Jan 12 16:44:15 volumiokueche systemd[1]: Started Music Player Daemon. Jan 12 16:44:15 volumiokueche sudo[1452]: pam_unix(sudo:session): session closed for user root Jan 12 16:44:15 volumiokueche volumio[1018]: error: updateQueue error: null Jan 12 16:44:15 volumiokueche sudo[1518]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Jan 12 16:44:15 volumiokueche sudo[1518]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 16:44:15 volumiokueche systemd[1]: Started MyVolumio SSH Tunnel. Jan 12 16:44:15 volumiokueche sudo[1518]: pam_unix(sudo:session): session closed for user root Jan 12 16:44:15 volumiokueche volumio[1018]: info: Remote SSH Started Jan 12 16:44:15 volumiokueche autossh[1521]: port set to 0, monitoring disabled Jan 12 16:44:15 volumiokueche autossh[1521]: starting ssh (count 1) Jan 12 16:44:15 volumiokueche autossh[1521]: ssh child pid is 1524 Jan 12 16:44:16 volumiokueche volumio[1018]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 14 Jan 12 16:44:16 volumiokueche volumio[1018]: info: CoreCommandRouter::volumioGetState Jan 12 16:44:16 volumiokueche volumio[1018]: info: CorePlayQueue::getTrack 0 Jan 12 16:44:16 volumiokueche volumiossh-tunnel[1520]: Warning: Permanently added '[eu8.myvolumio.org]:2222,[178.62.246.67]:2222' (RSA) to the list of known hosts. Jan 12 16:44:16 volumiokueche sudo[1526]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Jan 12 16:44:16 volumiokueche sudo[1526]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 16:44:16 volumiokueche systemd[1]: Started Volumio Bluetooth Module. Jan 12 16:44:16 volumiokueche sudo[1526]: pam_unix(sudo:session): session closed for user root Jan 12 16:44:16 volumiokueche volumio[1018]: info: Volumio BT Module successfully started Jan 12 16:44:17 volumiokueche sudo[1529]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth/* Jan 12 16:44:17 volumiokueche sudo[1529]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 16:44:17 volumiokueche volumiobt[1528]: /bin/chmod: cannot access '/var/lib/bluetooth/*': No such file or directory Jan 12 16:44:17 volumiokueche sudo[1529]: pam_unix(sudo:session): session closed for user root Jan 12 16:44:17 volumiokueche volumio[1018]: info: TidalConnect service stoped! Jan 12 16:44:17 volumiokueche volumio[1018]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Jan 12 16:44:17 volumiokueche volumio[1018]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect Jan 12 16:44:17 volumiokueche sudo[1541]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Jan 12 16:44:17 volumiokueche sudo[1541]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 16:44:17 volumiokueche systemd[1]: Started Volumio Tidal Connect Service. Jan 12 16:44:17 volumiokueche sudo[1541]: pam_unix(sudo:session): session closed for user root Jan 12 16:44:17 volumiokueche volumio[1018]: info: Executing endpoint tc_getconfig Jan 12 16:44:17 volumiokueche volumio[1018]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Jan 12 16:44:17 volumiokueche vtcs[1543]: STARTING TidalConnect services, version: 1.5.2.56 Jan 12 16:44:17 volumiokueche vtcs[1543]: STARTED TidalConnect services. Jan 12 16:44:17 volumiokueche volumio[1018]: info: Executing endpoint tc_connect Jan 12 16:44:17 volumiokueche volumio[1018]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Jan 12 16:44:17 volumiokueche volumio[1018]: info: Connecting to TidalConnect Jan 12 16:44:17 volumiokueche volumio[1018]: info: CoreCommandRouter::servicePushState Jan 12 16:44:17 volumiokueche volumio[1018]: info: CoreStateMachine::pushState Jan 12 16:44:17 volumiokueche volumio[1018]: info: CorePlayQueue::getTrack 0 Jan 12 16:44:17 volumiokueche volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 16:44:17 volumiokueche volumio[1018]: info: CoreCommandRouter::volumioPushState Jan 12 16:44:17 volumiokueche volumio[1018]: info: MRS: Pushing multiroomSync output update for this device Jan 12 16:44:17 volumiokueche volumio[1018]: info: MRS: Pushing multiroomSync output Jan 12 16:44:17 volumiokueche volumio[1018]: info: CoreCommandRouter::volumioGetState Jan 12 16:44:17 volumiokueche volumio[1018]: info: CorePlayQueue::getTrack 0 Jan 12 16:44:17 volumiokueche volumio[1018]: info: CorePlayQueue::getTrack 0 Jan 12 16:44:17 volumiokueche volumio[1018]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect Jan 12 16:44:17 volumiokueche volumio[1018]: info: CoreCommandRouter::servicePushState Jan 12 16:44:17 volumiokueche volumio[1018]: info: CoreStateMachine::pushState Jan 12 16:44:17 volumiokueche volumio[1018]: info: CorePlayQueue::getTrack 0 Jan 12 16:44:17 volumiokueche volumio[1018]: info: CoreCommandRouter::volumioPushState Jan 12 16:44:17 volumiokueche volumio[1018]: info: MRS: Pushing multiroomSync output update for this device Jan 12 16:44:17 volumiokueche volumio[1018]: info: MRS: Pushing multiroomSync output Jan 12 16:44:17 volumiokueche volumio[1018]: info: CoreCommandRouter::volumioGetState Jan 12 16:44:17 volumiokueche volumio[1018]: info: CorePlayQueue::getTrack 0 Jan 12 16:44:17 volumiokueche volumio[1018]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect Jan 12 16:44:17 volumiokueche volumio[1018]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 12 16:44:17 volumiokueche volumio[1018]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 12 16:44:17 volumiokueche volumio[1018]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 12 16:44:17 volumiokueche volumio-remote-updater[627]: No test mode Jan 12 16:44:17 volumiokueche volumio-remote-updater[627]: No alpha test mode Jan 12 16:44:17 volumiokueche volumio[1018]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Jan 12 16:44:17 volumiokueche volumio[1018]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Jan 12 16:44:17 volumiokueche volumio[1018]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Jan 12 16:44:18 volumiokueche sudo[1565]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall pulseaudio Jan 12 16:44:18 volumiokueche sudo[1565]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 16:44:18 volumiokueche volumiobt[1528]: pulseaudio: no process found Jan 12 16:44:18 volumiokueche sudo[1565]: pam_unix(sudo:session): session closed for user root Jan 12 16:44:18 volumiokueche dbus-daemon[640]: [system] Activating via systemd: service name='org.freedesktop.RealtimeKit1' unit='rtkit-daemon.service' requested by ':1.23' (uid=1000 pid=1569 comm="/usr/bin/pulseaudio --high-priority=true ") Jan 12 16:44:18 volumiokueche systemd[1]: Starting RealtimeKit Scheduling Policy Service... Jan 12 16:44:18 volumiokueche volumio[1018]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 12 16:44:18 volumiokueche dbus-daemon[640]: [system] Successfully activated service 'org.freedesktop.RealtimeKit1' Jan 12 16:44:18 volumiokueche systemd[1]: Started RealtimeKit Scheduling Policy Service. Jan 12 16:44:18 volumiokueche rtkit-daemon[1570]: Successfully called chroot. Jan 12 16:44:18 volumiokueche rtkit-daemon[1570]: Successfully dropped privileges. Jan 12 16:44:18 volumiokueche rtkit-daemon[1570]: Successfully limited resources. Jan 12 16:44:18 volumiokueche rtkit-daemon[1570]: Running. Jan 12 16:44:18 volumiokueche rtkit-daemon[1570]: Canary thread running. Jan 12 16:44:18 volumiokueche rtkit-daemon[1570]: Watchdog thread running. Jan 12 16:44:18 volumiokueche dbus-daemon[640]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.24' (uid=0 pid=1570 comm="/usr/lib/rtkit/rtkit-daemon ") Jan 12 16:44:18 volumiokueche systemd[1]: Starting Authorization Manager... Jan 12 16:44:18 volumiokueche volumio[1018]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 12 16:44:18 volumiokueche polkitd[1574]: started daemon version 0.105 using authority implementation `local' version `0.105' Jan 12 16:44:18 volumiokueche dbus-daemon[640]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' Jan 12 16:44:18 volumiokueche systemd[1]: Started Authorization Manager. Jan 12 16:44:18 volumiokueche sudo[1579]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Jan 12 16:44:18 volumiokueche sudo[1579]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 16:44:18 volumiokueche systemd[1]: Stopping MPD Monitor Service... Jan 12 16:44:18 volumiokueche systemd[1]: mpd_monitor.service: Main process exited, code=killed, status=15/TERM Jan 12 16:44:18 volumiokueche systemd[1]: mpd_monitor.service: Succeeded. Jan 12 16:44:18 volumiokueche systemd[1]: Stopped MPD Monitor Service. Jan 12 16:44:18 volumiokueche volumio[1018]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 12 16:44:18 volumiokueche volumio[1018]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jan 12 16:44:18 volumiokueche systemd[1]: Started MPD Monitor Service. Jan 12 16:44:18 volumiokueche sudo[1579]: pam_unix(sudo:session): session closed for user root Jan 12 16:44:18 volumiokueche mpd_monitor.sh[1581]: MPD Monitor Service: Starting MPD Monitor Service Jan 12 16:44:18 volumiokueche volumio[1018]: info: Successfully started MPD Monitor Jan 12 16:44:18 volumiokueche pulseaudio[1569]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Jan 12 16:44:18 volumiokueche pulseaudio[1569]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Jan 12 16:44:18 volumiokueche volumiobt[1528]: Applying permissions Jan 12 16:44:18 volumiokueche volumiobt[1528]: Setting BT discoverable and pairable Jan 12 16:44:18 volumiokueche pulseaudio[1569]: Failed to register as a handsfree audio agent with ofono: org.freedesktop.DBus.Error.ServiceUnknown: The name org.ofono was not provided by any .service files Jan 12 16:44:18 volumiokueche bluetoothd[767]: Endpoint registered: sender=:1.44 path=/MediaEndpoint/A2DPSource Jan 12 16:44:18 volumiokueche bluetoothd[767]: Endpoint registered: sender=:1.44 path=/MediaEndpoint/A2DPSink Jan 12 16:44:18 volumiokueche kernel: Bluetooth: RFCOMM TTY layer initialized Jan 12 16:44:18 volumiokueche kernel: Bluetooth: RFCOMM socket layer initialized Jan 12 16:44:18 volumiokueche kernel: Bluetooth: RFCOMM ver 1.11 Jan 12 16:44:18 volumiokueche volumiobt[1528]: [96B blob data] Jan 12 16:44:18 volumiokueche volumiobt[1528]: [77B blob data] Jan 12 16:44:18 volumiokueche volumiobt[1528]: [77B blob data] Jan 12 16:44:18 volumiokueche volumiobt[1528]: [95B blob data] Jan 12 16:44:18 volumiokueche volumiobt[1528]: [95B blob data] Jan 12 16:44:18 volumiokueche volumiobt[1528]: [95B blob data] Jan 12 16:44:18 volumiokueche volumiobt[1528]: [95B blob data] Jan 12 16:44:18 volumiokueche volumiobt[1528]: [95B blob data] Jan 12 16:44:18 volumiokueche volumiobt[1528]: [95B blob data] Jan 12 16:44:18 volumiokueche volumiobt[1528]: [95B blob data] Jan 12 16:44:18 volumiokueche volumiobt[1528]: [95B blob data] Jan 12 16:44:18 volumiokueche volumiobt[1528]: [95B blob data] Jan 12 16:44:18 volumiokueche volumiobt[1528]: [95B blob data] Jan 12 16:44:18 volumiokueche volumiobt[1528]: [33B blob data] Jan 12 16:44:18 volumiokueche volumiobt[1528]: [78B blob data] Jan 12 16:44:18 volumiokueche volumiobt[1528]: [95B blob data] Jan 12 16:44:18 volumiokueche volumiobt[1528]: [95B blob data] Jan 12 16:44:18 volumiokueche volumiobt[1528]: [95B blob data] Jan 12 16:44:18 volumiokueche volumiobt[1528]: [95B blob data] Jan 12 16:44:18 volumiokueche volumiobt[1528]: [95B blob data] Jan 12 16:44:18 volumiokueche volumiobt[1528]: [95B blob data] Jan 12 16:44:18 volumiokueche volumiobt[1528]: [95B blob data] Jan 12 16:44:18 volumiokueche volumiobt[1528]: [95B blob data] Jan 12 16:44:18 volumiokueche volumiobt[1528]: [95B blob data] Jan 12 16:44:18 volumiokueche volumiobt[1528]: [95B blob data] Jan 12 16:44:18 volumiokueche volumiobt[1528]: [95B blob data] Jan 12 16:44:18 volumiokueche volumiobt[1528]: [95B blob data] Jan 12 16:44:18 volumiokueche volumiobt[1528]: [95B blob data] Jan 12 16:44:18 volumiokueche volumiobt[1528]: [95B blob data] Jan 12 16:44:18 volumiokueche volumiobt[1528]: [95B blob data] Jan 12 16:44:18 volumiokueche volumiobt[1528]: [95B blob data] Jan 12 16:44:18 volumiokueche volumiobt[1528]: [95B blob data] Jan 12 16:44:18 volumiokueche volumiobt[1528]: [95B blob data] Jan 12 16:44:18 volumiokueche volumiobt[1528]: [95B blob data] Jan 12 16:44:18 volumiokueche volumiobt[1528]: [95B blob data] Jan 12 16:44:18 volumiokueche volumiobt[1528]: [79B blob data] Jan 12 16:44:18 volumiokueche volumiobt[1528]: [55B blob data] Jan 12 16:44:18 volumiokueche volumiobt[1528]: [bluetoothctl]> -e power on Jan 12 16:44:18 volumiokueche volumiobt[1528]: Invalid command in menu main: -e Jan 12 16:44:18 volumiokueche volumiobt[1528]: Jan 12 16:44:18 volumiokueche volumiobt[1528]: Use "help" for a list of available commands in a menu. Jan 12 16:44:18 volumiokueche volumiobt[1528]: Use "menu " if you want to enter any submenu. Jan 12 16:44:18 volumiokueche volumiobt[1528]: Use "back" if you want to return to menu main. Jan 12 16:44:18 volumiokueche volumiobt[1528]: [55B blob data] Jan 12 16:44:18 volumiokueche volumiobt[1528]: [bluetoothctl]> agent on Jan 12 16:44:18 volumiokueche volumiobt[1528]: Agent is already registered Jan 12 16:44:18 volumiokueche volumiobt[1528]: [103B blob data] Jan 12 16:44:18 volumiokueche volumiobt[1528]: [bluetoothctl]> discoverable on Jan 12 16:44:18 volumiokueche volumiobt[1528]: Warning: setting discoverable while discoverable-timeout not set(0) is not recommended Jan 12 16:44:18 volumiokueche volumiobt[1528]: [bluetoothctl]> pairable on Jan 12 16:44:18 volumiokueche volumiobt[1528]: [bluetoothctl]> agent NoInputNoOutput Jan 12 16:44:18 volumiokueche volumiobt[1528]: Agent is already registered Jan 12 16:44:18 volumiokueche volumiobt[1528]: [bluetoothctl]> default-agent Jan 12 16:44:18 volumiokueche volumiobt[1528]: [bluetoothctl]> quit Jan 12 16:44:18 volumiokueche volumiobt[1528]: [65B blob data] Jan 12 16:44:18 volumiokueche volumio[1018]: info: MRS: Getting audio outputs on start Jan 12 16:44:18 volumiokueche volumio[1018]: info: MRS: Requesting all other devices output Jan 12 16:44:20 volumiokueche volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 16:44:20 volumiokueche volumio[1018]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Jan 12 16:44:20 volumiokueche volumio[1018]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jan 12 16:44:20 volumiokueche volumio[1018]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jan 12 16:44:20 volumiokueche volumio[1018]: info: CoreCommandRouter::volumioGetBrowseSources Jan 12 16:44:20 volumiokueche volumio[1018]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 12 16:44:20 volumiokueche volumio[1018]: info: TidalConnect service started! Jan 12 16:44:20 volumiokueche volumio[1018]: [Metrics] CommandRouter: 37s 538.32ms Jan 12 16:44:20 volumiokueche volumio[1018]: info: CoreCommandRouter::volumiosetStartupVolume Jan 12 16:44:20 volumiokueche volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 16:44:20 volumiokueche volumio[1018]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 12 16:44:20 volumiokueche volumio[1018]: info: CoreCommandRouter::Close All Modals sent Jan 12 16:44:20 volumiokueche volumio[1018]: info: CoreCommandRouter::Close All Modals sent Jan 12 16:44:21 volumiokueche volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jan 12 16:44:21 volumiokueche volumio[1018]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 12 16:44:21 volumiokueche volumio[1018]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jan 12 16:44:27 volumiokueche volumio[1018]: info: BOOT COMPLETED Jan 12 16:44:29 volumiokueche ntpd[787]: Soliciting pool server 162.159.200.1 Jan 12 16:44:30 volumiokueche ntpd[787]: Soliciting pool server 129.250.35.250 Jan 12 16:44:31 volumiokueche ntpd[787]: Soliciting pool server 90.187.112.137 Jan 12 16:44:31 volumiokueche ntpd[787]: Soliciting pool server 212.18.3.19 Jan 12 16:44:32 volumiokueche ntpd[787]: Soliciting pool server 49.12.199.148 Jan 12 16:44:32 volumiokueche ntpd[787]: Soliciting pool server 162.19.170.154 Jan 12 16:44:32 volumiokueche ntpd[787]: Soliciting pool server 213.209.109.45 Jan 12 16:44:33 volumiokueche ntpd[787]: Soliciting pool server 185.252.140.126 Jan 12 16:44:33 volumiokueche ntpd[787]: Soliciting pool server 80.153.195.191 Jan 12 16:44:33 volumiokueche ntpd[787]: Soliciting pool server 80.151.186.5 Jan 12 16:44:34 volumiokueche ntpd[787]: Soliciting pool server 129.70.132.35 Jan 12 16:44:34 volumiokueche ntpd[787]: Soliciting pool server 2a01:4f8:222:25db::62 Jan 12 16:44:34 volumiokueche ntpd[787]: Soliciting pool server 116.202.118.202 Jan 12 16:44:35 volumiokueche ntpd[787]: Soliciting pool server 93.177.65.20 Jan 12 16:44:39 volumiokueche ntpd[787]: receive: Unexpected origin timestamp 0xed0f9566.e351cc77 does not match aorg 0000000000.00000000 from server@80.151.186.5 xmt 0xed0f9567.c4b7361e Jan 12 16:45:26 volumiokueche volumio[1018]: verbose: New Socket.io Connection to 192.168.0.156 from 192.168.0.78 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Jan 12 16:45:26 volumiokueche volumio[1018]: verbose: New Socket.io Connection to 192.168.0.156 from 192.168.0.78 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Jan 12 16:45:26 volumiokueche volumio[1018]: verbose: New Socket.io Connection to 192.168.0.156 from 192.168.0.78 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Jan 12 16:45:26 volumiokueche volumio[1018]: verbose: New Socket.io Connection to 192.168.0.156 from 192.168.0.78 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Jan 12 16:45:26 volumiokueche volumio[1018]: verbose: New Socket.io Connection to 192.168.0.156 from 192.168.0.78 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 11 Jan 12 16:45:26 volumiokueche volumio[1018]: verbose: New Socket.io Connection to 192.168.0.156 from 192.168.0.78 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 12 Jan 12 16:45:26 volumiokueche volumio[1018]: verbose: New Socket.io Connection to 192.168.0.156 from 192.168.0.78 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 13 Jan 12 16:45:26 volumiokueche volumio[1018]: verbose: New Socket.io Connection to 192.168.0.156 from 192.168.0.78 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 14 Jan 12 16:45:27 volumiokueche sudo[1777]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 12 16:45:27 volumiokueche sudo[1777]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 16:45:27 volumiokueche sudo[1777]: pam_unix(sudo:session): session closed for user root Jan 12 16:45:27 volumiokueche sudo[1780]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 12 16:45:27 volumiokueche sudo[1780]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 16:45:27 volumiokueche sudo[1780]: pam_unix(sudo:session): session closed for user root Jan 12 16:45:27 volumiokueche volumio[1018]: verbose: New Socket.io Connection to 192.168.0.156 from 192.168.0.78 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 15 Jan 12 16:45:27 volumiokueche volumio[1018]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 12 16:45:27 volumiokueche volumio[1018]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jan 12 16:45:27 volumiokueche volumio[1018]: info: CoreCommandRouter::volumioGetVisibleSources Jan 12 16:45:27 volumiokueche volumio[1018]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 12 16:45:27 volumiokueche volumio[1018]: info: CoreCommandRouter::volumioGetState Jan 12 16:45:27 volumiokueche volumio[1018]: info: CorePlayQueue::getTrack 0 Jan 12 16:45:27 volumiokueche volumio[1018]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jan 12 16:45:27 volumiokueche volumio[1018]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jan 12 16:45:27 volumiokueche volumio[1018]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Jan 12 16:45:27 volumiokueche volumio[1018]: info: Received Get System Info Jan 12 16:45:27 volumiokueche volumio[1018]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 12 16:45:27 volumiokueche volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 12 16:45:27 volumiokueche volumio[1018]: info: Discovery: Getting this device information Jan 12 16:45:27 volumiokueche volumio[1018]: info: CoreCommandRouter::volumioGetState Jan 12 16:45:27 volumiokueche volumio[1018]: info: CorePlayQueue::getTrack 0 Jan 12 16:45:27 volumiokueche volumio[1018]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 12 16:45:27 volumiokueche volumio[1018]: info: CoreCommandRouter::volumioGetState Jan 12 16:45:27 volumiokueche volumio[1018]: info: CorePlayQueue::getTrack 0 Jan 12 16:45:27 volumiokueche volumio[1018]: info: Listing playlists Jan 12 16:45:27 volumiokueche volumio[1018]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jan 12 16:45:27 volumiokueche volumio[1018]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 12 16:45:28 volumiokueche volumio[1018]: info: CoreCommandRouter::volumioGetState Jan 12 16:45:28 volumiokueche volumio[1018]: info: CorePlayQueue::getTrack 0 Jan 12 16:45:29 volumiokueche volumio[1018]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 12 16:45:29 volumiokueche volumio[1018]: info: Received Get System Info Jan 12 16:45:29 volumiokueche volumio[1018]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 12 16:45:29 volumiokueche volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 12 16:45:29 volumiokueche volumio[1018]: info: Discovery: Getting this device information Jan 12 16:45:29 volumiokueche volumio[1018]: info: CoreCommandRouter::volumioGetState Jan 12 16:45:29 volumiokueche volumio[1018]: info: CorePlayQueue::getTrack 0 Jan 12 16:45:29 volumiokueche volumio[1018]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 12 16:45:29 volumiokueche volumio[1018]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jan 12 16:45:30 volumiokueche volumio[1018]: info: Executing endpoint metavolumio Jan 12 16:45:30 volumiokueche volumio[1018]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 12 16:45:30 volumiokueche volumio[1018]: info: Executing endpoint metavolumio Jan 12 16:45:30 volumiokueche volumio[1018]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 12 16:45:30 volumiokueche volumio[1018]: info: Executing endpoint metavolumio Jan 12 16:45:30 volumiokueche volumio[1018]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 12 16:45:30 volumiokueche volumio[1018]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 12 16:45:30 volumiokueche volumio[1018]: info: Received Get System Info Jan 12 16:45:30 volumiokueche volumio[1018]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 12 16:45:30 volumiokueche volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 12 16:45:30 volumiokueche volumio[1018]: info: Discovery: Getting this device information Jan 12 16:45:30 volumiokueche volumio[1018]: info: CoreCommandRouter::volumioGetState Jan 12 16:45:30 volumiokueche volumio[1018]: info: CorePlayQueue::getTrack 0 Jan 12 16:45:30 volumiokueche volumio[1018]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 12 16:45:34 volumiokueche volumio[1018]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 12 16:45:34 volumiokueche volumio[1018]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 12 16:45:34 volumiokueche volumio[1018]: info: Retrieving Cloud Streaming UI Jan 12 16:45:34 volumiokueche volumio[1018]: info: Getting Tidal Cloud Configuration Jan 12 16:45:34 volumiokueche volumio[1018]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 12 16:45:34 volumiokueche volumio[1018]: info: Getting Qobuz Cloud Configuration Jan 12 16:45:34 volumiokueche volumio[1018]: info: Asking plugin for UI Config Jan 12 16:45:34 volumiokueche volumio[1018]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 12 16:45:34 volumiokueche volumio[1018]: info: Getting Spotify Cloud Configuration Jan 12 16:45:34 volumiokueche volumio[1018]: info: Asking plugin for UI Config Jan 12 16:45:34 volumiokueche volumio[1018]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 12 16:45:34 volumiokueche volumio[1018]: info: Saving Spotify Acccount Jan 12 16:45:34 volumiokueche volumio[1018]: info: Got it Jan 12 16:45:34 volumiokueche volumio[1018]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jan 12 16:45:34 volumiokueche volumio[1018]: info: Got Tidal Cloud Configuration Jan 12 16:45:34 volumiokueche volumio[1018]: info: Got it Jan 12 16:45:34 volumiokueche volumio[1018]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 12 16:45:34 volumiokueche volumio[1018]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jan 12 16:45:34 volumiokueche volumio[1018]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jan 12 16:45:34 volumiokueche volumio[1018]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jan 12 16:45:34 volumiokueche volumio[1018]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jan 12 16:45:34 volumiokueche volumio[1018]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 12 16:45:34 volumiokueche volumio[1018]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 12 16:45:34 volumiokueche volumio[1018]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 12 16:45:34 volumiokueche volumio[1018]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 12 16:45:34 volumiokueche volumio[1018]: info: CoreCommandRouter::volumioGetBrowseSources Jan 12 16:45:34 volumiokueche volumio[1018]: info: CoreCommandRouter::volumioGetBrowseSources Jan 12 16:45:34 volumiokueche volumio[1018]: info: CoreCommandRouter::volumioGetBrowseSources Jan 12 16:45:35 volumiokueche volumio[1018]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 12 16:45:35 volumiokueche volumio[1018]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jan 12 16:45:37 volumiokueche volumio[1018]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 12 16:45:39 volumiokueche volumio[1018]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 12 16:45:40 volumiokueche volumio[1018]: info: CoreCommandRouter::executeOnPlugin: networkfs , deleteShare Jan 12 16:45:40 volumiokueche sudo[1806]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/umount /mnt/NAS/USB Jan 12 16:45:40 volumiokueche sudo[1806]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 16:45:40 volumiokueche sudo[1806]: pam_unix(sudo:session): session closed for user root Jan 12 16:45:40 volumiokueche volumio[1018]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 12 16:45:40 volumiokueche volumio[1018]: Error: EBUSY: resource busy or locked, rmdir '/mnt/NAS/USB' Jan 12 16:45:40 volumiokueche volumio[1018]: at Object.rmdirSync (fs.js:890:10) Jan 12 16:45:40 volumiokueche volumio[1018]: at /volumio/node_modules/linux-mountutils/mountutils.js:178:12 Jan 12 16:45:40 volumiokueche volumio[1018]: at ChildProcess.exithandler (child_process.js:299:7) Jan 12 16:45:40 volumiokueche volumio[1018]: at ChildProcess.emit (events.js:315:20) Jan 12 16:45:40 volumiokueche volumio[1018]: at maybeClose (internal/child_process.js:1048:16) Jan 12 16:45:40 volumiokueche volumio[1018]: at Process.ChildProcess._handle.onexit (internal/child_process.js:288:5) { Jan 12 16:45:40 volumiokueche volumio[1018]: errno: -16, Jan 12 16:45:40 volumiokueche volumio[1018]: syscall: 'rmdir', Jan 12 16:45:40 volumiokueche volumio[1018]: code: 'EBUSY', Jan 12 16:45:40 volumiokueche volumio[1018]: path: '/mnt/NAS/USB' Jan 12 16:45:40 volumiokueche volumio[1018]: } Jan 12 16:45:40 volumiokueche volumio[1018]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 12 16:45:40 volumiokueche sudo[1818]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-01-12 16:44 Jan 12 16:45:40 volumiokueche sudo[1818]: 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="b43c85cc0006d3f1efecba101311ec96e334d1fd" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 11 Dec 2025 05:43:24 PM CET" VOLUMIO_VERSION="3.886" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="15d0241b0cd805792809f3ddf4a0fc4e"