-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Sun 2025-05-18 18:40:01 CEST. -- May 18 18:39:00 volumio volumio[877]: info: ir_controller: systemctl restart lircd.service succeeded. May 18 18:39:00 volumio sudo[1377]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart irexec.service May 18 18:39:00 volumio sudo[1377]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 18:39:00 volumio sudo[1379]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart irexec.service May 18 18:39:00 volumio sudo[1379]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 18:39:00 volumio systemd[1]: Started Handle events from IR remotes decoded by lircd(8). May 18 18:39:00 volumio sudo[1377]: pam_unix(sudo:session): session closed for user root May 18 18:39:00 volumio volumio[877]: info: ir_controller: systemctl restart irexec.service succeeded. May 18 18:39:00 volumio systemd[1]: Stopping Handle events from IR remotes decoded by lircd(8)... May 18 18:39:00 volumio systemd[1]: irexec.service: Main process exited, code=killed, status=15/TERM May 18 18:39:00 volumio systemd[1]: irexec.service: Succeeded. May 18 18:39:00 volumio systemd[1]: Stopped Handle events from IR remotes decoded by lircd(8). May 18 18:39:00 volumio systemd[1]: Started Handle events from IR remotes decoded by lircd(8). May 18 18:39:00 volumio sudo[1379]: pam_unix(sudo:session): session closed for user root May 18 18:39:00 volumio volumio[877]: info: ir_controller: systemctl restart irexec.service succeeded. May 18 18:39:00 volumio lircd[1373]: lircd-0.10.1[1373]: Notice: accepted new client on /var/run/lirc/lircd May 18 18:39:00 volumio lircd-0.10.1[1373]: Notice: accepted new client on /var/run/lirc/lircd May 18 18:39:00 volumio lircd[1373]: lircd-0.10.1[1373]: Info: [lirc] protocol is enabled May 18 18:39:00 volumio lircd-0.10.1[1373]: Info: [lirc] protocol is enabled May 18 18:39:00 volumio volumio[877]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 May 18 18:39:00 volumio volumio-remote-updater[677]: [2025-05-18 18:39:00] [connect] Successful connection May 18 18:39:00 volumio volumio-remote-updater[677]: [2025-05-18 18:39:00] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1747586340 101 May 18 18:39:00 volumio volumio[877]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 4 May 18 18:39:01 volumio volumio[877]: info: CoreCommandRouter::volumioGetState May 18 18:39:01 volumio volumio[877]: info: CorePlayQueue::getTrack 0 May 18 18:39:01 volumio volumio[877]: info: touch_display: Setting screensaver timeout to 0 seconds. May 18 18:39:06 volumio volumio[877]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium May 18 18:39:06 volumio volumio[877]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium May 18 18:39:06 volumio volumio[877]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium May 18 18:39:06 volumio volumio[877]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium May 18 18:39:06 volumio volumio[877]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium May 18 18:39:06 volumio volumio[877]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium May 18 18:39:06 volumio volumio[877]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium May 18 18:39:06 volumio volumio[877]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium May 18 18:39:06 volumio volumio[877]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium May 18 18:39:06 volumio volumio[877]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium May 18 18:39:06 volumio volumio[877]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium May 18 18:39:06 volumio volumio[877]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar May 18 18:39:06 volumio volumio[877]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar May 18 18:39:06 volumio volumio[877]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar May 18 18:39:06 volumio volumio[877]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar May 18 18:39:06 volumio volumio[877]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar May 18 18:39:06 volumio volumio[877]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar May 18 18:39:06 volumio volumio[877]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar May 18 18:39:06 volumio volumio[877]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar May 18 18:39:06 volumio volumio[877]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar May 18 18:39:06 volumio volumio[877]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar May 18 18:39:06 volumio volumio[877]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar May 18 18:39:06 volumio volumio[877]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso May 18 18:39:06 volumio volumio[877]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso May 18 18:39:06 volumio volumio[877]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso May 18 18:39:06 volumio volumio[877]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso May 18 18:39:06 volumio volumio[877]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso May 18 18:39:06 volumio volumio[877]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso May 18 18:39:06 volumio volumio[877]: info: Adding plugin bluetooth to MyMusic Plugins May 18 18:39:06 volumio volumio[877]: info: Adding plugin multiroom to MyMusic Plugins May 18 18:39:06 volumio volumio[877]: info: Adding plugin metavolumio to MyMusic Plugins May 18 18:39:06 volumio volumio[877]: info: Adding plugin cd_controller to MyMusic Plugins May 18 18:39:06 volumio volumio[877]: info: Adding plugin qobuzconnect to MyMusic Plugins May 18 18:39:06 volumio volumio[877]: info: Adding plugin smart_inputs to MyMusic Plugins May 18 18:39:06 volumio volumio[877]: info: Adding plugin tidalconnect to MyMusic Plugins May 18 18:39:06 volumio volumio[877]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... May 18 18:39:08 volumio volumio[1328]: .............................................................................................++++ May 18 18:39:08 volumio volumio[877]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded May 18 18:39:08 volumio volumio[877]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio May 18 18:39:08 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 18 18:39:08 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 18 18:39:08 volumio volumio[877]: info: Starting MyVolumio Remote Streaming Endpoints May 18 18:39:08 volumio volumio[877]: info: MyVolumio login type: Token May 18 18:39:08 volumio volumio[877]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started May 18 18:39:08 volumio volumio[877]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... May 18 18:39:09 volumio volumio[877]: info: Starting Streaming Service Transparent Proxy May 18 18:39:09 volumio volumio[877]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded May 18 18:39:09 volumio volumio[877]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services May 18 18:39:09 volumio volumio[877]: info: Streaming services startup May 18 18:39:09 volumio volumio[877]: info: Starting Streaming Daemon May 18 18:39:09 volumio sudo[1521]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 18 18:39:09 volumio sudo[1521]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 18:39:09 volumio sudo[1521]: pam_unix(sudo:session): session closed for user root May 18 18:39:09 volumio volumio[877]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started May 18 18:39:09 volumio volumio[877]: error: Cannot start Volumio Streaming Daemon May 18 18:39:09 volumio volumio[877]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service May 18 18:39:09 volumio volumio[877]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. May 18 18:39:09 volumio volumio[877]: error: MyVolumio Custom Token format not valid, refreshing it May 18 18:39:09 volumio volumio[877]: STREAMING PROXY: Starting server on port 3245 May 18 18:39:09 volumio volumio[877]: Node JS runtime: 14 May 18 18:39:10 volumio volumio[877]: info: MyVolumio login type: Token May 18 18:39:10 volumio volumio[877]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN May 18 18:39:18 volumio volumio[1328]: .......................................................................................................................++++ May 18 18:39:18 volumio volumio[1328]: e is 65537 (0x010001) May 18 18:39:18 volumio volumio[1328]: writing RSA key May 18 18:39:18 volumio ntpd[839]: Soliciting pool server 195.32.70.195 May 18 18:39:19 volumio ntpd[839]: Soliciting pool server 212.45.144.206 May 18 18:39:20 volumio ntpd[839]: Soliciting pool server 212.45.144.3 May 18 18:39:20 volumio ntpd[839]: Soliciting pool server 162.159.200.1 May 18 18:39:21 volumio ntpd[839]: Soliciting pool server 151.22.209.90 May 18 18:39:21 volumio ntpd[839]: Soliciting pool server 93.94.88.50 May 18 18:39:21 volumio ntpd[839]: Soliciting pool server 162.159.200.123 May 18 18:39:22 volumio ntpd[839]: Soliciting pool server 31.14.133.122 May 18 18:39:22 volumio ntpd[839]: Soliciting pool server 185.19.184.35 May 18 18:39:23 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 18 18:39:23 volumio volumio[877]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 May 18 18:39:23 volumio volumio[877]: info: CoreCommandRouter::volumioGetState May 18 18:39:23 volumio volumio[877]: info: CorePlayQueue::getTrack 0 May 18 18:39:23 volumio ntpd[839]: Soliciting pool server 85.199.214.99 May 18 18:39:23 volumio ntpd[839]: Soliciting pool server 85.199.214.99 May 18 18:39:29 volumio ntpd[839]: receive: Unexpected origin timestamp 0xebd48bbf.f4555c1e does not match aorg 0000000000.00000000 from server@162.159.200.1 xmt 0xebd48bc1.14d55992 May 18 18:39:29 volumio ntpd[839]: receive: Unexpected origin timestamp 0xebd48bbf.f4514f6a does not match aorg 0000000000.00000000 from server@185.19.184.35 xmt 0xebd48bc1.148730dc May 18 18:39:29 volumio ntpd[839]: receive: Unexpected origin timestamp 0xebd48bbf.f4570ee9 does not match aorg 0000000000.00000000 from server@195.32.70.195 xmt 0xebd48bc1.14adcd2d May 18 18:39:29 volumio ntpd[839]: receive: Unexpected origin timestamp 0xebd48bbf.f4538522 does not match aorg 0000000000.00000000 from server@31.14.133.122 xmt 0xebd48bc1.15da9b1e May 18 18:39:29 volumio ntpd[839]: receive: Unexpected origin timestamp 0xebd48bbf.f4547640 does not match aorg 0000000000.00000000 from server@93.94.88.50 xmt 0xebd48bc1.1603bdcf May 18 18:39:29 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 18:39:29 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam May 18 18:39:29 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam May 18 18:39:29 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject May 18 18:39:29 volumio volumio[877]: info: CoreCommandRouter::volumioGetBrowseSources May 18 18:39:29 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 18 18:39:30 volumio volumio[877]: info: MyVolumio token set successfully May 18 18:39:30 volumio volumio[877]: info: MYVOLUMIO: Adding device May 18 18:39:30 volumio volumio[877]: info: MYVOLUMIO: Evaluating Server May 18 18:39:30 volumio volumio[877]: error: MyVolumio Plugin failed to authenticate in a timely fashion May 18 18:39:30 volumio volumio[877]: info: Completed starting MyVolumio Plugin May 18 18:39:30 volumio volumio[877]: [Metrics] CommandRouter: 69s 871.88ms May 18 18:39:30 volumio volumio[877]: info: CoreCommandRouter::volumiosetStartupVolume May 18 18:39:30 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 18:39:30 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 18 18:39:30 volumio volumio[877]: info: CoreCommandRouter::Close All Modals sent May 18 18:39:30 volumio volumio[877]: info: CoreCommandRouter::Close All Modals sent May 18 18:39:30 volumio volumio[877]: info: MyVolumio Plan changed: virtuoso May 18 18:39:30 volumio volumio[877]: info: [MyVolumio PluginManager] Subscribed plan changed to virtuoso May 18 18:39:30 volumio volumio[877]: info: Removing browser output: myVolumio user plan is not superstar May 18 18:39:30 volumio volumio[877]: info: Removing audio output: May 18 18:39:30 volumio volumio[877]: info: MYVOLUMIO: Adding device May 18 18:39:30 volumio volumio[877]: info: MYVOLUMIO: Evaluating Server May 18 18:39:30 volumio volumio[877]: info: Remote config written successfully May 18 18:39:30 volumio volumio[877]: info: Starting Tunnel 1 May 18 18:39:30 volumio volumio[877]: info: Starting Tunnel Connection Checker May 18 18:39:30 volumio volumio[877]: info: MYVolumio Device enabled May 18 18:39:30 volumio volumio[877]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... May 18 18:39:30 volumio volumio[877]: info: MyVolumio status changed May 18 18:39:30 volumio volumio[877]: info: Streaming services startup May 18 18:39:30 volumio volumio[877]: info: Starting Streaming Daemon May 18 18:39:30 volumio volumio[877]: info: Setting Geolocation for MyVolumio to eu4 May 18 18:39:30 volumio sudo[1694]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 18 18:39:30 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 18 18:39:30 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 18 18:39:30 volumio sudo[1694]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 18:39:30 volumio sudo[1694]: pam_unix(sudo:session): session closed for user root May 18 18:39:30 volumio volumio[877]: error: Cannot start Volumio Streaming Daemon May 18 18:39:30 volumio volumio[877]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service May 18 18:39:30 volumio volumio[877]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. May 18 18:39:30 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid May 18 18:39:30 volumio volumio[877]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... May 18 18:39:30 volumio volumio[877]: info: [1747586370995] Starting BluetoothController May 18 18:39:31 volumio volumio[877]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... May 18 18:39:31 volumio volumio[877]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... May 18 18:39:31 volumio volumio[877]: info: [MyVolumio PluginManager] Loading plugin "tidal"... May 18 18:39:31 volumio volumio[877]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... May 18 18:39:31 volumio volumio[877]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... May 18 18:39:31 volumio volumio[877]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth May 18 18:39:31 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 18 18:39:31 volumio volumio[877]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui May 18 18:39:31 volumio volumio[877]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller May 18 18:39:31 volumio volumio[877]: info: Preparing CD Folders May 18 18:39:31 volumio volumio[877]: info: Adding CD REST API Endpoints May 18 18:39:31 volumio volumio[877]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller May 18 18:39:31 volumio volumio[877]: info: Starting UDEV Watcher for CD May 18 18:39:31 volumio volumio[877]: info: Detecting CD presence with UDEV May 18 18:39:31 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices May 18 18:39:31 volumio sudo[1716]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hciconfig hci0 name Volumio May 18 18:39:31 volumio sudo[1716]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 18:39:31 volumio sudo[1716]: pam_unix(sudo:session): session closed for user root May 18 18:39:31 volumio volumio[877]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal May 18 18:39:31 volumio volumio[877]: info: Refreshing TIDAL token May 18 18:39:31 volumio volumio[877]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz May 18 18:39:31 volumio volumio[877]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect May 18 18:39:31 volumio volumio[877]: info: Stopping AccessToken refresher cron for QOBUZ May 18 18:39:31 volumio sudo[1719]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 18 18:39:31 volumio sudo[1719]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 18:39:31 volumio volumio[877]: info: AccessToken refresher cron started for QOBUZ May 18 18:39:31 volumio volumio[877]: info: Adding QOBUZ REST API Endpoints May 18 18:39:31 volumio volumio[877]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz May 18 18:39:31 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable May 18 18:39:31 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 18 18:39:31 volumio sudo[1719]: pam_unix(sudo:session): session closed for user root May 18 18:39:31 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect May 18 18:39:31 volumio volumio[877]: info: Setting Geolocation for MyVolumio to eu4 May 18 18:39:31 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 18 18:39:31 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 18 18:39:31 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 18 18:39:31 volumio volumio[877]: info: Bluetooth name changed to Volumio May 18 18:39:31 volumio volumio[877]: info: Updating MyVolumio device info May 18 18:39:31 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 18 18:39:31 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 18 18:39:31 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 18 18:39:32 volumio volumio[877]: info: Successfully Added MyVolumio device May 18 18:39:32 volumio volumio[877]: info: Access Token successfully retrieved May 18 18:39:33 volumio volumio[877]: info: Updating MyVolumio device info May 18 18:39:33 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 18 18:39:33 volumio volumio[877]: info: Successfully retrieved User Session From TIDAL May 18 18:39:33 volumio volumio[877]: info: Successfully retrieved User Subscription From TIDAL May 18 18:39:33 volumio volumio[877]: info: Adding TIDAL to Browse Sources May 18 18:39:33 volumio volumio[877]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 18 18:39:33 volumio volumio[877]: info: [1747586373784] CoreMusicLibrary::Adding element TIDAL May 18 18:39:33 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 18 18:39:33 volumio volumio[877]: Cannot find translation for source TIDAL May 18 18:39:33 volumio volumio[877]: info: Stopping AccessToken refresher cron May 18 18:39:33 volumio volumio[877]: info: AccessToken refresher cron started May 18 18:39:33 volumio volumio[877]: info: Adding TIDAL REST API Endpoints May 18 18:39:33 volumio volumio[877]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal May 18 18:39:33 volumio volumio[877]: info: Successfully Updated MyVolumio device May 18 18:39:34 volumio volumio[877]: info: Successfully Updated MyVolumio device May 18 18:39:34 volumio volumio[877]: info: Successfully Added MyVolumio device May 18 18:39:34 volumio sudo[1752]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service May 18 18:39:34 volumio sudo[1752]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 18:39:34 volumio volumio[877]: info: TidalConnect service stoped! May 18 18:39:34 volumio systemd[1]: Started Volumio Bluetooth Module. May 18 18:39:34 volumio sudo[1752]: pam_unix(sudo:session): session closed for user root May 18 18:39:34 volumio volumio[877]: info: Volumio BT Module successfully started May 18 18:39:34 volumio volumio[877]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect May 18 18:39:34 volumio volumio[877]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect May 18 18:39:34 volumio sudo[1766]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth/* May 18 18:39:34 volumio sudo[1766]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 18:39:34 volumio sudo[1767]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service May 18 18:39:34 volumio sudo[1767]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 18:39:34 volumio volumiobt[1754]: /bin/chmod: cannot access '/var/lib/bluetooth/*': No such file or directory May 18 18:39:34 volumio sudo[1766]: pam_unix(sudo:session): session closed for user root May 18 18:39:34 volumio systemd[1]: Started Volumio Tidal Connect Service. May 18 18:39:34 volumio sudo[1767]: pam_unix(sudo:session): session closed for user root May 18 18:39:35 volumio volumio[877]: info: Executing endpoint tc_getconfig May 18 18:39:35 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig May 18 18:39:35 volumio vtcs[1771]: STARTING TidalConnect services, version: 1.4.0.34 May 18 18:39:35 volumio vtcs[1771]: STARTED TidalConnect services. May 18 18:39:35 volumio volumio[877]: info: Executing endpoint tc_connect May 18 18:39:35 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect May 18 18:39:35 volumio volumio[877]: info: Connecting to TidalConnect May 18 18:39:35 volumio volumio[877]: info: CoreCommandRouter::servicePushState May 18 18:39:35 volumio volumio[877]: info: CoreStateMachine::pushState May 18 18:39:35 volumio volumio[877]: info: CorePlayQueue::getTrack 0 May 18 18:39:35 volumio volumio[877]: info: CoreCommandRouter::volumioPushState May 18 18:39:35 volumio volumio[877]: info: CorePlayQueue::getTrack 0 May 18 18:39:35 volumio volumio[877]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received tidalconnect May 18 18:39:35 volumio volumio[877]: info: CoreCommandRouter::servicePushState May 18 18:39:35 volumio volumio[877]: info: CoreStateMachine::pushState May 18 18:39:35 volumio volumio[877]: info: CorePlayQueue::getTrack 0 May 18 18:39:35 volumio volumio[877]: info: CoreCommandRouter::volumioPushState May 18 18:39:35 volumio volumio[877]: info: CorePlayQueue::getTrack 0 May 18 18:39:35 volumio volumio[877]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received tidalconnect May 18 18:39:35 volumio sudo[1802]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall pulseaudio May 18 18:39:35 volumio sudo[1802]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 18:39:35 volumio volumiobt[1754]: pulseaudio: no process found May 18 18:39:35 volumio sudo[1802]: pam_unix(sudo:session): session closed for user root May 18 18:39:35 volumio kernel: xhci_hcd 0000:01:00.0: ERROR Transfer event for disabled endpoint slot 2 ep 1 May 18 18:39:35 volumio kernel: xhci_hcd 0000:01:00.0: @00000004295204a0 00000000 00000000 0e000000 02028000 May 18 18:39:37 volumio volumio[877]: info: BOOT COMPLETED May 18 18:39:37 volumio volumio[877]: info: TidalConnect service started! May 18 18:39:45 volumio dbus-daemon[686]: [system] Activating via systemd: service name='org.freedesktop.RealtimeKit1' unit='rtkit-daemon.service' requested by ':1.25' (uid=1000 pid=1823 comm="/usr/bin/pulseaudio --high-priority=true ") May 18 18:39:45 volumio systemd[1]: Starting RealtimeKit Scheduling Policy Service... May 18 18:39:45 volumio volumio[877]: ------------------------------------ BT MESSAGE: BT STATUS: running May 18 18:39:45 volumio dbus-daemon[686]: [system] Successfully activated service 'org.freedesktop.RealtimeKit1' May 18 18:39:45 volumio systemd[1]: Started RealtimeKit Scheduling Policy Service. May 18 18:39:45 volumio rtkit-daemon[1824]: Successfully called chroot. May 18 18:39:45 volumio rtkit-daemon[1824]: Successfully dropped privileges. May 18 18:39:45 volumio rtkit-daemon[1824]: Successfully limited resources. May 18 18:39:45 volumio rtkit-daemon[1824]: Running. May 18 18:39:45 volumio rtkit-daemon[1824]: Canary thread running. May 18 18:39:45 volumio rtkit-daemon[1824]: Watchdog thread running. May 18 18:39:45 volumio dbus-daemon[686]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.26' (uid=0 pid=1824 comm="/usr/lib/rtkit/rtkit-daemon ") May 18 18:39:45 volumio systemd[1]: Starting Authorization Manager... May 18 18:39:45 volumio volumio[877]: ------------------------------------ BT MESSAGE: BT STATUS: running May 18 18:39:45 volumio polkitd[1828]: started daemon version 0.105 using authority implementation `local' version `0.105' May 18 18:39:45 volumio dbus-daemon[686]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' May 18 18:39:45 volumio systemd[1]: Started Authorization Manager. May 18 18:39:47 volumio sudo[1833]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service May 18 18:39:47 volumio sudo[1833]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 18:39:47 volumio systemd[1]: Started MyVolumio SSH Tunnel. May 18 18:39:47 volumio sudo[1833]: pam_unix(sudo:session): session closed for user root May 18 18:39:47 volumio volumio[877]: info: Remote SSH Started May 18 18:39:47 volumio autossh[1836]: port set to 0, monitoring disabled May 18 18:39:47 volumio autossh[1836]: starting ssh (count 1) May 18 18:39:47 volumio autossh[1836]: ssh child pid is 1839 May 18 18:39:47 volumio volumio[877]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 May 18 18:39:47 volumio volumio[877]: info: CoreCommandRouter::volumioGetState May 18 18:39:47 volumio volumio[877]: info: CorePlayQueue::getTrack 0 May 18 18:39:47 volumio volumiossh-tunnel[1835]: Warning: Permanently added '[eu4.myvolumio.org]:2222,[167.172.103.77]:2222' (RSA) to the list of known hosts. May 18 18:39:47 volumio pulseaudio[1823]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 May 18 18:39:47 volumio pulseaudio[1823]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 May 18 18:39:47 volumio volumiobt[1754]: Applying permissions May 18 18:39:47 volumio volumiobt[1754]: Setting BT discoverable and pairable May 18 18:39:47 volumio bluetoothd[828]: Endpoint registered: sender=:1.46 path=/MediaEndpoint/A2DPSource May 18 18:39:47 volumio bluetoothd[828]: Endpoint registered: sender=:1.46 path=/MediaEndpoint/A2DPSink May 18 18:39:47 volumio pulseaudio[1823]: 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 May 18 18:39:47 volumio kernel: Bluetooth: RFCOMM TTY layer initialized May 18 18:39:47 volumio kernel: Bluetooth: RFCOMM socket layer initialized May 18 18:39:47 volumio kernel: Bluetooth: RFCOMM ver 1.11 May 18 18:39:47 volumio volumiobt[1754]: [CHG] Controller DC:A6:32:2C:F4:B8 Class: 0x0008041c May 18 18:39:47 volumio volumiobt[1754]: [117B blob data] May 18 18:39:47 volumio volumiobt[1754]: [117B blob data] May 18 18:39:47 volumio volumiobt[1754]: [117B blob data] May 18 18:39:47 volumio volumiobt[1754]: [117B blob data] May 18 18:39:47 volumio volumiobt[1754]: [117B blob data] May 18 18:39:47 volumio volumiobt[1754]: [117B blob data] May 18 18:39:47 volumio volumiobt[1754]: [117B blob data] May 18 18:39:47 volumio volumiobt[1754]: [117B blob data] May 18 18:39:47 volumio volumiobt[1754]: [117B blob data] May 18 18:39:47 volumio volumiobt[1754]: [55B blob data] May 18 18:39:47 volumio volumiobt[1754]: [91B blob data] May 18 18:39:47 volumio volumiobt[1754]: [117B blob data] May 18 18:39:47 volumio volumiobt[1754]: [117B blob data] May 18 18:39:47 volumio volumiobt[1754]: [117B blob data] May 18 18:39:47 volumio volumiobt[1754]: [117B blob data] May 18 18:39:47 volumio volumiobt[1754]: [117B blob data] May 18 18:39:47 volumio volumiobt[1754]: [117B blob data] May 18 18:39:47 volumio volumiobt[1754]: [117B blob data] May 18 18:39:47 volumio volumiobt[1754]: [117B blob data] May 18 18:39:47 volumio volumiobt[1754]: [117B blob data] May 18 18:39:47 volumio volumiobt[1754]: [117B blob data] May 18 18:39:47 volumio volumiobt[1754]: [117B blob data] May 18 18:39:47 volumio volumiobt[1754]: [117B blob data] May 18 18:39:47 volumio volumiobt[1754]: [117B blob data] May 18 18:39:47 volumio volumiobt[1754]: [117B blob data] May 18 18:39:47 volumio volumiobt[1754]: [117B blob data] May 18 18:39:47 volumio volumiobt[1754]: [117B blob data] May 18 18:39:47 volumio volumiobt[1754]: [117B blob data] May 18 18:39:47 volumio volumiobt[1754]: [117B blob data] May 18 18:39:47 volumio volumiobt[1754]: [bluetooth]# -e power on May 18 18:39:47 volumio volumiobt[1754]: Invalid command in menu main: -e May 18 18:39:47 volumio volumiobt[1754]: May 18 18:39:47 volumio volumiobt[1754]: Use "help" for a list of available commands in a menu. May 18 18:39:47 volumio volumiobt[1754]: Use "menu " if you want to enter any submenu. May 18 18:39:47 volumio volumiobt[1754]: Use "back" if you want to return to menu main. May 18 18:39:47 volumio volumiobt[1754]: [bluetooth]# agent on May 18 18:39:47 volumio volumiobt[1754]: Agent is already registered May 18 18:39:47 volumio volumiobt[1754]: [bluetooth]# discoverable on May 18 18:39:47 volumio volumiobt[1754]: [bluetooth]# pairable on May 18 18:39:47 volumio volumiobt[1754]: [bluetooth]# agent NoInputNoOutput May 18 18:39:47 volumio volumiobt[1754]: Agent is already registered May 18 18:39:47 volumio volumiobt[1754]: [bluetooth]# default-agent May 18 18:39:47 volumio volumiobt[1754]: [bluetooth]# quit May 18 18:39:47 volumio volumiobt[1754]: [59B blob data] May 18 18:39:49 volumio volumio[877]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 May 18 18:39:50 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 18 18:39:51 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 18 18:39:51 volumio volumio[877]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 May 18 18:39:51 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 18 18:39:51 volumio volumio[877]: info: CoreCommandRouter::volumioGetVisibleSources May 18 18:39:51 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 18 18:39:51 volumio volumio[877]: info: CoreCommandRouter::volumioGetState May 18 18:39:51 volumio volumio[877]: info: CorePlayQueue::getTrack 0 May 18 18:39:51 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 18 18:39:51 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 18 18:39:51 volumio volumio[877]: info: Received Get System Info May 18 18:39:51 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 18 18:39:51 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 18 18:39:51 volumio volumio[877]: info: Discovery: Getting this device information May 18 18:39:51 volumio volumio[877]: info: CoreCommandRouter::volumioGetState May 18 18:39:51 volumio volumio[877]: info: CorePlayQueue::getTrack 0 May 18 18:39:51 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 18 18:39:51 volumio volumio[877]: info: CoreCommandRouter::volumioGetState May 18 18:39:51 volumio volumio[877]: info: CorePlayQueue::getTrack 0 May 18 18:39:51 volumio volumio[877]: info: Listing playlists May 18 18:39:51 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 18 18:39:51 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 18 18:39:51 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 18 18:39:52 volumio volumio[877]: info: CoreCommandRouter::volumioGetState May 18 18:39:52 volumio volumio[877]: info: CorePlayQueue::getTrack 0 May 18 18:39:53 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 18 18:39:53 volumio volumio[877]: info: Received Get System Info May 18 18:39:53 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 18 18:39:53 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 18 18:39:53 volumio volumio[877]: info: Discovery: Getting this device information May 18 18:39:53 volumio volumio[877]: info: CoreCommandRouter::volumioGetState May 18 18:39:53 volumio volumio[877]: info: CorePlayQueue::getTrack 0 May 18 18:39:53 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 18 18:39:53 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken May 18 18:39:54 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard May 18 18:39:55 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 18 18:39:55 volumio volumio[877]: info: Received Get System Info May 18 18:39:55 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 18 18:39:55 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 18 18:39:55 volumio volumio[877]: info: Discovery: Getting this device information May 18 18:39:55 volumio volumio[877]: info: CoreCommandRouter::volumioGetState May 18 18:39:55 volumio volumio[877]: info: CorePlayQueue::getTrack 0 May 18 18:39:55 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 18 18:39:56 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 18 18:39:56 volumio volumio[877]: info: Received Get System Info May 18 18:39:56 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 18 18:39:56 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 18 18:39:56 volumio volumio[877]: info: Discovery: Getting this device information May 18 18:39:56 volumio volumio[877]: info: CoreCommandRouter::volumioGetState May 18 18:39:56 volumio volumio[877]: info: CorePlayQueue::getTrack 0 May 18 18:39:56 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 18 18:40:01 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 18 18:40:01 volumio volumio[877]: info: Error : CoreCommandRouter::executeOnPlugin: No method [isLatestTOSAccepted] in plugin appearance May 18 18:40:01 volumio volumio[877]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 18 18:40:01 volumio volumio[877]: TypeError: Cannot read property 'then' of undefined May 18 18:40:01 volumio volumio[877]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:2005:20) May 18 18:40:01 volumio volumio[877]: at Socket.emit (events.js:315:20) May 18 18:40:01 volumio volumio[877]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 May 18 18:40:01 volumio volumio[877]: at processTicksAndRejections (internal/process/task_queues.js:75:11) May 18 18:40:01 volumio volumio[877]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 18 18:40:01 volumio sudo[1941]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-18 18:39 May 18 18:40:01 volumio sudo[1941]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="570c5791513f5bac7da274aba6690c1a961705de" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 13 May 2025 05:16:30 PM CEST" VOLUMIO_VERSION="3.812" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="3934ee440d5d3f0186049a23752ad680"