-- Logs begin at Sun 2024-05-05 03:40:32 BST, end at Wed 2024-10-23 20:40:25 BST. -- Oct 23 20:39:20 lounge ntpd[658]: receive: Unexpected origin timestamp 0xe9e17075.8a0311bf does not match aorg 0000000000.00000000 from server@85.199.214.102 xmt 0xeac3cf68.e8774e65 Oct 23 20:39:20 lounge ntpd[658]: receive: Unexpected origin timestamp 0xe9e17075.8a0b3da6 does not match aorg 0000000000.00000000 from server@162.159.200.1 xmt 0xeac3cf68.e8254f0e Oct 23 20:39:20 lounge ntpd[658]: receive: Unexpected origin timestamp 0xe9e17075.8a083cf2 does not match aorg 0000000000.00000000 from server@83.151.202.213 xmt 0xeac3cf68.ea2b8c3f Oct 23 20:39:20 lounge ntpd[658]: receive: Unexpected origin timestamp 0xe9e17075.8a05e18f does not match aorg 0000000000.00000000 from server@185.57.191.229 xmt 0xeac3cf68.ea318055 Oct 23 20:39:20 lounge ntpd[658]: receive: Unexpected origin timestamp 0xe9e17075.89f83c3e does not match aorg 0000000000.00000000 from server@132.226.210.133 xmt 0xeac3cf68.f4258445 Oct 23 20:39:20 lounge systemd[1]: Starting Daily apt download activities... Oct 23 20:39:22 lounge volumio[909]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Oct 23 20:39:22 lounge volumio[909]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Oct 23 20:39:22 lounge volumio[909]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Oct 23 20:39:22 lounge volumio[909]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Oct 23 20:39:22 lounge volumio[909]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Oct 23 20:39:22 lounge volumio[909]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Oct 23 20:39:22 lounge volumio[909]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Oct 23 20:39:22 lounge volumio[909]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Oct 23 20:39:22 lounge volumio[909]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Oct 23 20:39:22 lounge volumio[909]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Oct 23 20:39:22 lounge volumio[909]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Oct 23 20:39:22 lounge volumio[909]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Oct 23 20:39:22 lounge volumio[909]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Oct 23 20:39:22 lounge volumio[909]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Oct 23 20:39:22 lounge volumio[909]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Oct 23 20:39:22 lounge volumio[909]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Oct 23 20:39:22 lounge volumio[909]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Oct 23 20:39:22 lounge volumio[909]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Oct 23 20:39:22 lounge volumio[909]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Oct 23 20:39:22 lounge volumio[909]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Oct 23 20:39:22 lounge volumio[909]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Oct 23 20:39:22 lounge volumio[909]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Oct 23 20:39:22 lounge volumio[909]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Oct 23 20:39:22 lounge volumio[909]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Oct 23 20:39:22 lounge volumio[909]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Oct 23 20:39:22 lounge volumio[909]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Oct 23 20:39:22 lounge volumio[909]: info: Adding plugin bluetooth to MyMusic Plugins Oct 23 20:39:22 lounge volumio[909]: info: Adding plugin multiroom to MyMusic Plugins Oct 23 20:39:22 lounge volumio[909]: info: Adding plugin metavolumio to MyMusic Plugins Oct 23 20:39:22 lounge volumio[909]: info: Adding plugin cd_controller to MyMusic Plugins Oct 23 20:39:22 lounge volumio[909]: info: Adding plugin smart_inputs to MyMusic Plugins Oct 23 20:39:22 lounge volumio[909]: info: Adding plugin tidalconnect to MyMusic Plugins Oct 23 20:39:22 lounge volumio[909]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Oct 23 20:39:25 lounge volumio[909]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Oct 23 20:39:25 lounge volumio[909]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Oct 23 20:39:25 lounge volumio[909]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 23 20:39:25 lounge volumio[909]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 23 20:39:25 lounge volumio[909]: info: Starting MyVolumio Remote Streaming Endpoints Oct 23 20:39:25 lounge volumio[909]: info: MyVolumio login type: Token Oct 23 20:39:25 lounge volumio[909]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Oct 23 20:39:25 lounge volumio[909]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Oct 23 20:39:26 lounge systemd[1]: apt-daily.service: Succeeded. Oct 23 20:39:26 lounge systemd[1]: Started Daily apt download activities. Oct 23 20:39:26 lounge systemd[1]: Starting Daily apt upgrade and clean activities... Oct 23 20:39:28 lounge volumio[909]: info: Starting Streaming Service Transparent Proxy Oct 23 20:39:29 lounge volumio[909]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Oct 23 20:39:29 lounge volumio[909]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Oct 23 20:39:29 lounge volumio[909]: info: Streaming services startup Oct 23 20:39:29 lounge volumio[909]: info: Starting Streaming Daemon Oct 23 20:39:29 lounge sudo[1317]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Oct 23 20:39:29 lounge sudo[1317]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 23 20:39:29 lounge volumio[909]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Oct 23 20:39:29 lounge sudo[1317]: pam_unix(sudo:session): session closed for user root Oct 23 20:39:29 lounge volumio[909]: error: Cannot start Volumio Streaming Daemon Oct 23 20:39:29 lounge volumio[909]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Oct 23 20:39:29 lounge volumio[909]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Oct 23 20:39:29 lounge volumio[909]: info: Discovery: A device disappeared from network Oct 23 20:39:29 lounge volumio[909]: info: Discovery: Device lounge disappeared from network Oct 23 20:39:29 lounge volumio[909]: info: Discovery: A device disappeared from network Oct 23 20:39:29 lounge volumio[909]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 23 20:39:29 lounge volumio[909]: info: CURURI: artists:// Oct 23 20:39:29 lounge systemd[1]: apt-daily-upgrade.service: Succeeded. Oct 23 20:39:29 lounge systemd[1]: Started Daily apt upgrade and clean activities. Oct 23 20:39:29 lounge volumio[909]: error: MyVolumio Custom Token format not valid, refreshing it Oct 23 20:39:29 lounge volumio[909]: info: Preload queue cleared Oct 23 20:39:29 lounge volumio[909]: info: CoreCommandRouter::volumioGetQueue Oct 23 20:39:29 lounge volumio[909]: info: CoreStateMachine::getQueue Oct 23 20:39:29 lounge volumio[909]: info: CorePlayQueue::getQueue Oct 23 20:39:29 lounge volumio[909]: info: Discovery: adding eccbd700-bcbb-4080-993f-7292d9d7560d Oct 23 20:39:29 lounge volumio[909]: info: Discovery: Found device Lounge Oct 23 20:39:29 lounge volumio[909]: info: CoreCommandRouter::volumioGetState Oct 23 20:39:29 lounge volumio[909]: info: CorePlayQueue::getTrack 0 Oct 23 20:39:30 lounge volumio[909]: info: Discovery: this is already registered, eccbd700-bcbb-4080-993f-7292d9d7560d Oct 23 20:39:30 lounge volumio[909]: info: Discovery: Found device Lounge Oct 23 20:39:30 lounge volumio[909]: info: CoreCommandRouter::volumioGetState Oct 23 20:39:30 lounge volumio[909]: info: CorePlayQueue::getTrack 0 Oct 23 20:39:30 lounge volumio[909]: Searching plugin music_service/mpd Oct 23 20:39:30 lounge volumio[909]: info: CoreCommandRouter::executeOnPlugin: mpd , search Oct 23 20:39:31 lounge volumio[909]: info: MyVolumio login type: Token Oct 23 20:39:31 lounge volumio[909]: STREAMING PROXY: Starting server on port 3245 Oct 23 20:39:31 lounge volumio[909]: Node JS runtime: 14 Oct 23 20:39:31 lounge volumio[909]: info: All search sources collected, pushing search results Oct 23 20:39:31 lounge volumio[909]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Oct 23 20:39:32 lounge volumio[909]: info: MyVolumio token set successfully Oct 23 20:39:32 lounge volumio[909]: info: MYVOLUMIO: Adding device Oct 23 20:39:32 lounge volumio[909]: info: MYVOLUMIO: Evaluating Server Oct 23 20:39:33 lounge volumio[909]: info: MyVolumio Plan changed: premium Oct 23 20:39:33 lounge volumio[909]: info: [MyVolumio PluginManager] Subscribed plan changed to premium Oct 23 20:39:33 lounge volumio[909]: info: Removing browser output: myVolumio user plan is not superstar Oct 23 20:39:33 lounge volumio[909]: info: Removing audio output: Oct 23 20:39:33 lounge volumio[909]: info: MYVOLUMIO: Adding device Oct 23 20:39:33 lounge volumio[909]: info: MYVOLUMIO: Evaluating Server Oct 23 20:39:33 lounge volumio[909]: info: Remote config written successfully Oct 23 20:39:33 lounge volumio[909]: info: Starting Tunnel 1 Oct 23 20:39:33 lounge volumio[909]: info: Starting Tunnel Connection Checker Oct 23 20:39:33 lounge volumio[909]: info: MYVolumio Device enabled Oct 23 20:39:33 lounge volumio[909]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Oct 23 20:39:33 lounge volumio[909]: info: MyVolumio status changed Oct 23 20:39:33 lounge volumio[909]: info: Streaming services startup Oct 23 20:39:33 lounge volumio[909]: info: Starting Streaming Daemon Oct 23 20:39:33 lounge sudo[1377]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Oct 23 20:39:33 lounge sudo[1377]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 23 20:39:33 lounge volumio[909]: info: Setting Geolocation for MyVolumio to eu3 Oct 23 20:39:33 lounge volumio[909]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 23 20:39:33 lounge volumio[909]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 23 20:39:33 lounge volumio[909]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 23 20:39:34 lounge sudo[1377]: pam_unix(sudo:session): session closed for user root Oct 23 20:39:34 lounge volumio[909]: error: Cannot start Volumio Streaming Daemon Oct 23 20:39:34 lounge volumio[909]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Oct 23 20:39:34 lounge volumio[909]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Oct 23 20:39:34 lounge volumio[909]: info: Setting Geolocation for MyVolumio to eu3 Oct 23 20:39:34 lounge volumio[909]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 23 20:39:34 lounge volumio[909]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 23 20:39:34 lounge volumio[909]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 23 20:39:34 lounge volumio[909]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Oct 23 20:39:34 lounge volumio[909]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... Oct 23 20:39:34 lounge volumio[909]: info: [1729712374870] Starting BluetoothController Oct 23 20:39:35 lounge volumio[909]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... Oct 23 20:39:38 lounge volumio[909]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom Oct 23 20:39:38 lounge sudo[1380]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Oct 23 20:39:38 lounge sudo[1380]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 23 20:39:38 lounge sudo[1380]: pam_unix(sudo:session): session closed for user root Oct 23 20:39:38 lounge volumio[909]: info: MRS: MultiRoom plugin initialized Oct 23 20:39:38 lounge volumio[909]: info: MRS: STOPPING SNAPCLIENT Oct 23 20:39:38 lounge volumio[909]: info: MRS: Snap server stop Oct 23 20:39:38 lounge sudo[1396]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Oct 23 20:39:38 lounge sudo[1396]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 23 20:39:38 lounge volumio[909]: info: MRS: STOPPING volumioStreaming Oct 23 20:39:38 lounge sudo[1398]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Oct 23 20:39:38 lounge sudo[1396]: pam_unix(sudo:session): session closed for user root Oct 23 20:39:38 lounge volumio[909]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... Oct 23 20:39:38 lounge sudo[1401]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Oct 23 20:39:38 lounge sudo[1398]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 23 20:39:38 lounge sudo[1401]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 23 20:39:38 lounge sudo[1403]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Oct 23 20:39:38 lounge sudo[1403]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 23 20:39:38 lounge sudo[1403]: pam_unix(sudo:session): session closed for user root Oct 23 20:39:38 lounge sudo[1398]: pam_unix(sudo:session): session closed for user root Oct 23 20:39:38 lounge volumio[909]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... Oct 23 20:39:38 lounge sudo[1401]: pam_unix(sudo:session): session closed for user root Oct 23 20:39:38 lounge volumio[909]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... Oct 23 20:39:38 lounge volumio[909]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... Oct 23 20:39:38 lounge volumio[909]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... Oct 23 20:39:39 lounge volumio[909]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio Oct 23 20:39:39 lounge volumio[909]: info: [MyVolumio PluginManager] Loading plugin "tidal"... Oct 23 20:39:39 lounge volumio[909]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... Oct 23 20:39:39 lounge volumio[909]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... Oct 23 20:39:39 lounge volumio[909]: info: Preparing to generate the ALSA configuration file Oct 23 20:39:39 lounge volumio[909]: info: Updating MyVolumio device info Oct 23 20:39:39 lounge volumio[909]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 23 20:39:39 lounge volumio[909]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 23 20:39:39 lounge volumio[909]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 23 20:39:39 lounge volumio[909]: info: Updating MyVolumio device info Oct 23 20:39:39 lounge volumio[909]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 23 20:39:39 lounge volumio[909]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 23 20:39:39 lounge volumio[909]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 23 20:39:39 lounge volumio[909]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Oct 23 20:39:39 lounge volumio[909]: info: Reading ALSA contributions from plugins. Oct 23 20:39:39 lounge volumio[909]: info: MRS: Removed streaming files Oct 23 20:39:39 lounge volumio[909]: info: MRS: volumioStreaming STOPPED Oct 23 20:39:39 lounge volumio[909]: info: MRS: SNAPSERVER STOPPED Oct 23 20:39:39 lounge volumio[909]: info: MRS: SNAPCLIENT STOPPED Oct 23 20:39:39 lounge volumio[909]: info: Successfully Added MyVolumio device Oct 23 20:39:39 lounge volumio[909]: info: Successfully Added MyVolumio device Oct 23 20:39:40 lounge sudo[1412]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Oct 23 20:39:40 lounge sudo[1412]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 23 20:39:40 lounge systemd[1]: Started MyVolumio SSH Tunnel. Oct 23 20:39:40 lounge sudo[1412]: pam_unix(sudo:session): session closed for user root Oct 23 20:39:40 lounge volumio[909]: info: Asound.conf file written Oct 23 20:39:40 lounge autossh[1415]: port set to 0, monitoring disabled Oct 23 20:39:40 lounge autossh[1415]: starting ssh (count 1) Oct 23 20:39:40 lounge autossh[1415]: ssh child pid is 1419 Oct 23 20:39:40 lounge sudo[1420]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Oct 23 20:39:40 lounge sudo[1420]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 23 20:39:40 lounge sudo[1420]: pam_unix(sudo:session): session closed for user root Oct 23 20:39:40 lounge volumio[909]: info: Output device has changed, restarting MPD Oct 23 20:39:40 lounge volumiossh-tunnel[1414]: Warning: Permanently added '[eu3.myvolumio.org]:2222,[167.71.136.233]:2222' (RSA) to the list of known hosts. Oct 23 20:39:40 lounge sudo[1425]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 23 20:39:40 lounge sudo[1425]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 23 20:39:40 lounge volumio[909]: info: Output device has changed, restarting Shairport Sync Oct 23 20:39:40 lounge volumio[909]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 23 20:39:40 lounge sudo[1425]: pam_unix(sudo:session): session closed for user root Oct 23 20:39:40 lounge volumio[909]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 23 20:39:40 lounge sudo[1427]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 23 20:39:40 lounge sudo[1427]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 23 20:39:40 lounge systemd[1]: Stopping Music Player Daemon... Oct 23 20:39:40 lounge volumio[909]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 23 20:39:40 lounge volumio[909]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 23 20:39:40 lounge volumio[909]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 23 20:39:40 lounge volumio[909]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth Oct 23 20:39:40 lounge volumio[909]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 23 20:39:40 lounge volumio[909]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom Oct 23 20:39:40 lounge volumio[909]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Oct 23 20:39:40 lounge volumio[909]: info: MRS: Pushing multiroomSync output for this device Oct 23 20:39:40 lounge volumio[909]: info: MRS: Pushing multiroomSync output Oct 23 20:39:40 lounge volumio[909]: info: Adding audio output: Oct 23 20:39:40 lounge volumio[909]: info: Adding audio output: Oct 23 20:39:40 lounge volumio[909]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 23 20:39:40 lounge volumio[909]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 23 20:39:40 lounge sudo[1436]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hciconfig hci0 name Lounge Oct 23 20:39:40 lounge sudo[1436]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 23 20:39:40 lounge sudo[1436]: pam_unix(sudo:session): session closed for user root Oct 23 20:39:40 lounge volumio[909]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio Oct 23 20:39:40 lounge volumio[909]: info: Adding METAVOLUMIO REST API Endpoints Oct 23 20:39:40 lounge volumio[909]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Oct 23 20:39:40 lounge volumio[909]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Oct 23 20:39:40 lounge volumio[909]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Oct 23 20:39:40 lounge volumio[909]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Oct 23 20:39:40 lounge volumio[909]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui Oct 23 20:39:40 lounge volumio[909]: info: Adding Manifest REST API Endpoints Oct 23 20:39:40 lounge volumio[909]: info: Adding manifestui REST Endpoint for plugin: miscellanea/manifestui Oct 23 20:39:40 lounge volumio[909]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller Oct 23 20:39:40 lounge systemd[1]: mpd.service: Succeeded. Oct 23 20:39:40 lounge systemd[1]: Stopped Music Player Daemon. Oct 23 20:39:40 lounge volumio[909]: info: Preparing CD Folders Oct 23 20:39:40 lounge volumio[909]: info: Adding CD REST API Endpoints Oct 23 20:39:40 lounge volumio[909]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Oct 23 20:39:40 lounge volumio[909]: info: Starting UDEV Watcher for CD Oct 23 20:39:40 lounge volumio[909]: info: Detecting CD presence with UDEV Oct 23 20:39:40 lounge volumio[909]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices Oct 23 20:39:40 lounge systemd[1]: Starting Music Player Daemon... Oct 23 20:39:41 lounge volumio[909]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs Oct 23 20:39:41 lounge volumio[909]: info: Adding inputs REST Endpoints Oct 23 20:39:41 lounge volumio[909]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs Oct 23 20:39:41 lounge volumio[909]: info: Scanning Audio Inputs Oct 23 20:39:41 lounge volumio[909]: info: Checking against Known Cards name Oct 23 20:39:41 lounge volumio[909]: info: Adding Server instance for streaming Oct 23 20:39:41 lounge volumio[909]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio Oct 23 20:39:41 lounge volumio[909]: error: Hi Res Audio Failed Login: Missing Login Data Oct 23 20:39:41 lounge volumio[909]: info: Adding HIGHRESAUDIO REST API Endpoints Oct 23 20:39:41 lounge volumio[909]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Oct 23 20:39:41 lounge volumio[909]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal Oct 23 20:39:41 lounge volumio[909]: info: Refreshing TIDAL token Oct 23 20:39:41 lounge volumio[909]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz Oct 23 20:39:41 lounge volumio[909]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect Oct 23 20:39:41 lounge volumio[909]: info: Stopping AccessToken refresher cron for QOBUZ Oct 23 20:39:41 lounge sudo[1445]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 23 20:39:41 lounge sudo[1445]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 23 20:39:41 lounge volumio[909]: info: AccessToken refresher cron started for QOBUZ Oct 23 20:39:41 lounge volumio[909]: info: Adding QOBUZ REST API Endpoints Oct 23 20:39:41 lounge volumio[909]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz Oct 23 20:39:41 lounge sudo[1445]: pam_unix(sudo:session): session closed for user root Oct 23 20:39:41 lounge volumio[909]: info: Remote SSH Started Oct 23 20:39:41 lounge volumio[909]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 23 20:39:42 lounge volumio[909]: info: Successfully Updated MyVolumio device Oct 23 20:39:42 lounge volumio[909]: info: Successfully Updated MyVolumio device Oct 23 20:39:42 lounge volumio[909]: info: Bluetooth name changed to Lounge Oct 23 20:39:42 lounge volumio[909]: info: MPD Permissions set Oct 23 20:39:42 lounge volumio[909]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 7 Oct 23 20:39:42 lounge volumio[909]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 8 Oct 23 20:39:42 lounge volumio[909]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 23 20:39:42 lounge volumio[909]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 23 20:39:42 lounge volumio[909]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 23 20:39:42 lounge volumio[909]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 23 20:39:42 lounge volumio[909]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 23 20:39:42 lounge volumio[909]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 23 20:39:42 lounge volumio[909]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 23 20:39:42 lounge volumio[909]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 23 20:39:42 lounge volumio[909]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 23 20:39:42 lounge volumio[909]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 23 20:39:43 lounge volumio[909]: info: Access Token successfully retrieved Oct 23 20:39:43 lounge volumio[909]: info: Starting Shairport Sync Oct 23 20:39:43 lounge sudo[1453]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 23 20:39:43 lounge sudo[1453]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 23 20:39:43 lounge systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 23 20:39:43 lounge systemd[1]: shairport-sync.service: Succeeded. Oct 23 20:39:43 lounge systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 23 20:39:43 lounge mpd[1441]: Oct 23 20:39 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 23 20:39:43 lounge systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 23 20:39:43 lounge sudo[1453]: pam_unix(sudo:session): session closed for user root Oct 23 20:39:43 lounge sudo[1457]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Oct 23 20:39:43 lounge sudo[1457]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 23 20:39:44 lounge systemd[1]: Started Volumio Bluetooth Module. Oct 23 20:39:44 lounge sudo[1457]: pam_unix(sudo:session): session closed for user root Oct 23 20:39:44 lounge sudo[1462]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth/* Oct 23 20:39:44 lounge sudo[1462]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 23 20:39:44 lounge volumiobt[1460]: /bin/chmod: cannot access '/var/lib/bluetooth/*': No such file or directory Oct 23 20:39:44 lounge sudo[1462]: pam_unix(sudo:session): session closed for user root Oct 23 20:39:44 lounge volumio[909]: info: Volumio BT Module successfully started Oct 23 20:39:44 lounge volumio[909]: info: Shairport-Sync Started Oct 23 20:39:44 lounge volumio[909]: info: CoreCommandRouter::volumioGetState Oct 23 20:39:44 lounge volumio[909]: info: CorePlayQueue::getTrack 0 Oct 23 20:39:44 lounge volumio[909]: info: CoreCommandRouter::volumioGetState Oct 23 20:39:44 lounge volumio[909]: info: CorePlayQueue::getTrack 0 Oct 23 20:39:44 lounge volumio[909]: info: Successfully retrieved User Session From TIDAL Oct 23 20:39:44 lounge volumio[909]: info: TidalConnect service stoped! Oct 23 20:39:44 lounge volumio[909]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 23 20:39:44 lounge volumio[909]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 23 20:39:44 lounge sudo[1474]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 23 20:39:44 lounge sudo[1474]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 23 20:39:45 lounge volumio[909]: info: Successfully retrieved User Subscription From TIDAL Oct 23 20:39:45 lounge volumio[909]: info: Adding TIDAL to Browse Sources Oct 23 20:39:45 lounge volumio[909]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 23 20:39:45 lounge volumio[909]: info: [1729712385006] CoreMusicLibrary::Adding element TIDAL Oct 23 20:39:45 lounge volumio[909]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 23 20:39:45 lounge volumio[909]: Cannot find translation for source TIDAL Oct 23 20:39:45 lounge systemd[1]: Started Volumio Tidal Connect Service. Oct 23 20:39:45 lounge sudo[1474]: pam_unix(sudo:session): session closed for user root Oct 23 20:39:45 lounge volumio[909]: info: Stopping AccessToken refresher cron Oct 23 20:39:45 lounge volumio[909]: info: AccessToken refresher cron started Oct 23 20:39:45 lounge volumio[909]: info: Adding TIDAL REST API Endpoints Oct 23 20:39:45 lounge volumio[909]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal Oct 23 20:39:45 lounge sudo[1479]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall pulseaudio Oct 23 20:39:45 lounge sudo[1479]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 23 20:39:45 lounge volumiobt[1460]: pulseaudio: no process found Oct 23 20:39:45 lounge sudo[1479]: pam_unix(sudo:session): session closed for user root Oct 23 20:39:45 lounge systemd[1]: Started Music Player Daemon. Oct 23 20:39:45 lounge sudo[1427]: pam_unix(sudo:session): session closed for user root Oct 23 20:39:45 lounge dbus-daemon[517]: [system] Activating via systemd: service name='org.freedesktop.RealtimeKit1' unit='rtkit-daemon.service' requested by ':1.22' (uid=1000 pid=1485 comm="/usr/bin/pulseaudio --high-priority=true ") Oct 23 20:39:45 lounge systemd[1]: Starting RealtimeKit Scheduling Policy Service... Oct 23 20:39:45 lounge dbus-daemon[517]: [system] Successfully activated service 'org.freedesktop.RealtimeKit1' Oct 23 20:39:45 lounge systemd[1]: Started RealtimeKit Scheduling Policy Service. Oct 23 20:39:45 lounge rtkit-daemon[1486]: Successfully called chroot. Oct 23 20:39:45 lounge rtkit-daemon[1486]: Successfully dropped privileges. Oct 23 20:39:45 lounge rtkit-daemon[1486]: Successfully limited resources. Oct 23 20:39:45 lounge rtkit-daemon[1486]: Running. Oct 23 20:39:45 lounge rtkit-daemon[1486]: Watchdog thread running. Oct 23 20:39:45 lounge rtkit-daemon[1486]: Canary thread running. Oct 23 20:39:45 lounge dbus-daemon[517]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.23' (uid=0 pid=1486 comm="/usr/lib/rtkit/rtkit-daemon ") Oct 23 20:39:45 lounge systemd[1]: Starting Authorization Manager... Oct 23 20:39:45 lounge volumio[909]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 23 20:39:45 lounge polkitd[1489]: started daemon version 0.105 using authority implementation `local' version `0.105' Oct 23 20:39:45 lounge dbus-daemon[517]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' Oct 23 20:39:45 lounge systemd[1]: Started Authorization Manager. Oct 23 20:39:45 lounge volumio[909]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 23 20:39:45 lounge volumio[909]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 23 20:39:45 lounge volumio[909]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 23 20:39:45 lounge volumio[909]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Oct 23 20:39:45 lounge volumio[909]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Oct 23 20:39:45 lounge volumio[909]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Oct 23 20:39:45 lounge volumio[909]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Oct 23 20:39:45 lounge volumio[909]: info: CoreCommandRouter::volumioGetBrowseSources Oct 23 20:39:45 lounge volumio[909]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 23 20:39:45 lounge volumio[909]: info: MRS: Getting audio outputs on start Oct 23 20:39:45 lounge volumio[909]: info: MRS: Requesting all other devices output Oct 23 20:39:46 lounge volumio[909]: error: updateQueue error: null Oct 23 20:39:46 lounge volumio[909]: info: Executing endpoint tc_getconfig Oct 23 20:39:46 lounge volumio[909]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Oct 23 20:39:46 lounge vtcs[1476]: STARTING TidalConnect services, version: 1.4.0.34 Oct 23 20:39:46 lounge pulseaudio[1485]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Oct 23 20:39:46 lounge pulseaudio[1485]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Oct 23 20:39:46 lounge vtcs[1476]: STARTED TidalConnect services. Oct 23 20:39:46 lounge volumiobt[1460]: Applying permissions Oct 23 20:39:46 lounge volumiobt[1460]: Setting BT discoverable and pairable Oct 23 20:39:46 lounge bluetoothd[645]: Endpoint registered: sender=:1.43 path=/MediaEndpoint/A2DPSource Oct 23 20:39:46 lounge bluetoothd[645]: Endpoint registered: sender=:1.43 path=/MediaEndpoint/A2DPSink Oct 23 20:39:46 lounge pulseaudio[1485]: 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 Oct 23 20:39:46 lounge kernel: Bluetooth: RFCOMM TTY layer initialized Oct 23 20:39:46 lounge kernel: Bluetooth: RFCOMM socket layer initialized Oct 23 20:39:46 lounge kernel: Bluetooth: RFCOMM ver 1.11 Oct 23 20:39:46 lounge volumio[909]: info: Executing endpoint tc_connect Oct 23 20:39:46 lounge volumio[909]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Oct 23 20:39:46 lounge volumio[909]: info: Connecting to TidalConnect Oct 23 20:39:46 lounge volumiobt[1460]: [CHG] Controller B8:27:EB:67:F2:60 UUIDs: 00001112-0000-1000-8000-00805f9b34fb Oct 23 20:39:46 lounge volumiobt[1460]: [117B blob data] Oct 23 20:39:46 lounge volumiobt[1460]: [117B blob data] Oct 23 20:39:46 lounge volumiobt[1460]: [117B blob data] Oct 23 20:39:46 lounge volumiobt[1460]: [117B blob data] Oct 23 20:39:46 lounge volumiobt[1460]: [117B blob data] Oct 23 20:39:46 lounge volumiobt[1460]: [117B blob data] Oct 23 20:39:46 lounge volumiobt[1460]: [117B blob data] Oct 23 20:39:46 lounge volumiobt[1460]: [117B blob data] Oct 23 20:39:46 lounge volumiobt[1460]: [91B blob data] Oct 23 20:39:46 lounge volumiobt[1460]: [117B blob data] Oct 23 20:39:46 lounge volumiobt[1460]: [117B blob data] Oct 23 20:39:46 lounge volumiobt[1460]: [117B blob data] Oct 23 20:39:46 lounge volumiobt[1460]: [117B blob data] Oct 23 20:39:46 lounge volumiobt[1460]: [117B blob data] Oct 23 20:39:46 lounge volumiobt[1460]: [117B blob data] Oct 23 20:39:46 lounge volumiobt[1460]: [117B blob data] Oct 23 20:39:46 lounge volumiobt[1460]: [117B blob data] Oct 23 20:39:46 lounge volumiobt[1460]: [117B blob data] Oct 23 20:39:46 lounge volumiobt[1460]: [117B blob data] Oct 23 20:39:46 lounge volumiobt[1460]: [117B blob data] Oct 23 20:39:46 lounge volumiobt[1460]: [117B blob data] Oct 23 20:39:46 lounge volumiobt[1460]: [117B blob data] Oct 23 20:39:46 lounge volumiobt[1460]: [117B blob data] Oct 23 20:39:46 lounge volumiobt[1460]: [117B blob data] Oct 23 20:39:46 lounge volumiobt[1460]: [117B blob data] Oct 23 20:39:46 lounge volumiobt[1460]: [117B blob data] Oct 23 20:39:46 lounge volumiobt[1460]: [117B blob data] Oct 23 20:39:46 lounge volumiobt[1460]: [117B blob data] Oct 23 20:39:46 lounge volumiobt[1460]: [117B blob data] Oct 23 20:39:46 lounge volumiobt[1460]: [117B blob data] Oct 23 20:39:46 lounge volumiobt[1460]: [117B blob data] Oct 23 20:39:46 lounge volumiobt[1460]: [117B blob data] Oct 23 20:39:46 lounge volumiobt[1460]: [117B blob data] Oct 23 20:39:46 lounge volumiobt[1460]: [117B blob data] Oct 23 20:39:46 lounge volumiobt[1460]: [117B blob data] Oct 23 20:39:46 lounge volumiobt[1460]: [117B blob data] Oct 23 20:39:46 lounge volumiobt[1460]: [55B blob data] Oct 23 20:39:46 lounge volumiobt[1460]: [bluetooth]# -e power on Oct 23 20:39:46 lounge volumiobt[1460]: Invalid command in menu main: -e Oct 23 20:39:46 lounge volumiobt[1460]: Oct 23 20:39:46 lounge volumiobt[1460]: Use "help" for a list of available commands in a menu. Oct 23 20:39:46 lounge volumiobt[1460]: Use "menu " if you want to enter any submenu. Oct 23 20:39:46 lounge volumiobt[1460]: Use "back" if you want to return to menu main. Oct 23 20:39:46 lounge volumiobt[1460]: [bluetooth]# agent on Oct 23 20:39:46 lounge volumiobt[1460]: Agent is already registered Oct 23 20:39:46 lounge volumiobt[1460]: [bluetooth]# discoverable on Oct 23 20:39:46 lounge volumiobt[1460]: [bluetooth]# pairable on Oct 23 20:39:46 lounge volumiobt[1460]: [bluetooth]# agent NoInputNoOutput Oct 23 20:39:46 lounge volumiobt[1460]: Agent is already registered Oct 23 20:39:46 lounge volumiobt[1460]: [bluetooth]# default-agent Oct 23 20:39:46 lounge volumiobt[1460]: [bluetooth]# quit Oct 23 20:39:46 lounge volumiobt[1460]: [59B blob data] Oct 23 20:39:46 lounge volumio[909]: info: CoreCommandRouter::servicePushState Oct 23 20:39:46 lounge volumio[909]: info: CoreStateMachine::pushState Oct 23 20:39:46 lounge volumio[909]: info: CorePlayQueue::getTrack 0 Oct 23 20:39:46 lounge volumio[909]: info: CoreCommandRouter::volumioPushState Oct 23 20:39:46 lounge volumio[909]: info: MRS: Pushing multiroomSync output update for this device Oct 23 20:39:46 lounge volumio[909]: info: MRS: Pushing multiroomSync output Oct 23 20:39:46 lounge volumio[909]: info: CorePlayQueue::getTrack 0 Oct 23 20:39:46 lounge volumio[909]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received tidalconnect Oct 23 20:39:46 lounge volumio[909]: info: CoreCommandRouter::servicePushState Oct 23 20:39:46 lounge volumio[909]: info: CoreStateMachine::pushState Oct 23 20:39:46 lounge volumio[909]: info: CorePlayQueue::getTrack 0 Oct 23 20:39:46 lounge volumio[909]: info: CoreCommandRouter::volumioPushState Oct 23 20:39:46 lounge volumio[909]: info: MRS: Pushing multiroomSync output update for this device Oct 23 20:39:46 lounge volumio[909]: info: MRS: Pushing multiroomSync output Oct 23 20:39:46 lounge volumio[909]: info: CorePlayQueue::getTrack 0 Oct 23 20:39:46 lounge volumio[909]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received tidalconnect Oct 23 20:39:47 lounge volumio[909]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Compilation/0faf1f89-f22b-4894-84bc-82e1d1f1f649.jpg' Oct 23 20:39:47 lounge volumio[909]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Depeche%20Mode/333d78a1-387b-4e84-899b-2a1c03a6e308.jpg' Oct 23 20:39:48 lounge volumio[909]: info: TidalConnect service started! Oct 23 20:39:51 lounge volumio[909]: info: Preload queue cleared Oct 23 20:39:51 lounge volumio[909]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 23 20:39:51 lounge volumio[909]: info: CoreStateMachine::ClearQueue Oct 23 20:39:51 lounge volumio[909]: info: CoreStateMachine::stop Oct 23 20:39:51 lounge volumio[909]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 23 20:39:51 lounge volumio[909]: info: CorePlayQueue::clearPlayQueue Oct 23 20:39:51 lounge volumio[909]: info: CorePlayQueue::saveQueue Oct 23 20:39:51 lounge volumio[909]: info: CoreCommandRouter::volumioPushQueue Oct 23 20:39:51 lounge volumio[909]: info: CoreStateMachine::addQueueItems Oct 23 20:39:51 lounge volumio[909]: info: CorePlayQueue::addQueueItems Oct 23 20:39:51 lounge volumio[909]: info: Preload queue cleared Oct 23 20:39:51 lounge volumio[909]: info: Adding Item to queue: artists://Groove%20Armada Oct 23 20:39:51 lounge volumio[909]: info: Exploding uri artists://Groove%20Armada in service mpd Oct 23 20:39:51 lounge volumio[909]: info: CoreCommandRouter::volumioPushQueue Oct 23 20:39:51 lounge volumio[909]: info: CorePlayQueue::saveQueue Oct 23 20:39:51 lounge volumio[909]: info: CoreStateMachine::updateTrackBlock Oct 23 20:39:51 lounge volumio[909]: info: CorePlayQueue::getTrackBlock Oct 23 20:39:51 lounge volumio[909]: info: CoreCommandRouter::volumioPlay Oct 23 20:39:51 lounge volumio[909]: info: CoreStateMachine::play index 0 Oct 23 20:39:51 lounge volumio[909]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 23 20:39:51 lounge volumio[909]: info: CoreStateMachine::stop Oct 23 20:39:51 lounge volumio[909]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 23 20:39:51 lounge volumio[909]: info: CoreStateMachine::play index undefined Oct 23 20:39:51 lounge volumio[909]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 23 20:39:51 lounge volumio[909]: info: CorePlayQueue::getTrack 0 Oct 23 20:39:51 lounge volumio[909]: info: CoreStateMachine::startPlaybackTimer Oct 23 20:39:51 lounge volumio[909]: info: CorePlayQueue::getTrack 0 Oct 23 20:39:51 lounge volumio[909]: verbose: ControllerMpd::clearAddPlayTracks NAS/bobbynet/Groove Armada - Vertigo/Chicago.flac Oct 23 20:39:51 lounge volumio[909]: verbose: ControllerMpd::sendMpdCommand stop Oct 23 20:39:51 lounge volumio[909]: info: sendMpdCommand stop took 3 milliseconds Oct 23 20:39:51 lounge volumio[909]: verbose: ControllerMpd::sendMpdCommand clear Oct 23 20:39:51 lounge volumio[909]: info: Oct 23 20:39:51 lounge volumio[909]: ---------------------------- MPD announces system playlist update Oct 23 20:39:51 lounge volumio[909]: info: Ignoring MPD Status Update Oct 23 20:39:51 lounge volumio[909]: info: sendMpdCommand clear took 7 milliseconds Oct 23 20:39:51 lounge volumio[909]: verbose: ControllerMpd::sendMpdCommand add "NAS/bobbynet/Groove Armada - Vertigo/Chicago.flac" Oct 23 20:39:51 lounge volumio[909]: error: updateQueue error: null Oct 23 20:39:51 lounge volumio[909]: info: Oct 23 20:39:51 lounge volumio[909]: ---------------------------- MPD announces system playlist update Oct 23 20:39:51 lounge volumio[909]: info: Ignoring MPD Status Update Oct 23 20:39:51 lounge volumio[909]: info: ------------------------------ 26ms Oct 23 20:39:51 lounge volumio[909]: info: sendMpdCommand add "NAS/bobbynet/Groove Armada - Vertigo/Chicago.flac" took 21 milliseconds Oct 23 20:39:51 lounge volumio[909]: verbose: ControllerMpd::sendMpdCommand play Oct 23 20:39:51 lounge volumio[909]: info: Oct 23 20:39:51 lounge volumio[909]: ---------------------------- MPD announces state update: player Oct 23 20:39:51 lounge volumio[909]: info: ------------------------------ 26ms Oct 23 20:39:51 lounge volumio[909]: info: sendMpdCommand play took 23 milliseconds Oct 23 20:39:51 lounge volumio[909]: info: ControllerMpd::getState Oct 23 20:39:51 lounge volumio[909]: verbose: ControllerMpd::sendMpdCommand status Oct 23 20:39:51 lounge volumio[909]: info: sendMpdCommand status took 72 milliseconds Oct 23 20:39:51 lounge volumio[909]: verbose: ControllerMpd::parseState Oct 23 20:39:51 lounge volumio[909]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 23 20:39:51 lounge volumio[909]: info: Oct 23 20:39:51 lounge volumio[909]: ---------------------------- MPD announces state update: player Oct 23 20:39:51 lounge volumio[909]: info: ControllerMpd::getState Oct 23 20:39:51 lounge volumio[909]: verbose: ControllerMpd::sendMpdCommand status Oct 23 20:39:51 lounge volumio[909]: info: sendMpdCommand playlistinfo took 5 milliseconds Oct 23 20:39:51 lounge volumio[909]: verbose: ControllerMpd::parseTrackInfo Oct 23 20:39:51 lounge volumio[909]: info: ControllerMpd::pushState Oct 23 20:39:51 lounge volumio[909]: info: CoreCommandRouter::servicePushState Oct 23 20:39:51 lounge volumio[909]: info: CorePlayQueue::getTrack 0 Oct 23 20:39:51 lounge volumio[909]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":441,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Chicago","artist":"Groove Armada","album":"Vertigo","uri":"NAS/bobbynet/Groove Armada - Vertigo/Chicago.flac","trackType":"flac"} Oct 23 20:39:51 lounge volumio[909]: verbose: CURRENT POSITION 0 Oct 23 20:39:51 lounge volumio[909]: info: CoreStateMachine::syncState stateService play Oct 23 20:39:51 lounge volumio[909]: info: CoreStateMachine::syncState currentStatus stop Oct 23 20:39:51 lounge volumio[909]: info: ------------------------------ 98ms Oct 23 20:39:51 lounge volumio[909]: info: sendMpdCommand status took 14 milliseconds Oct 23 20:39:51 lounge volumio[909]: verbose: ControllerMpd::parseState Oct 23 20:39:51 lounge volumio[909]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 23 20:39:51 lounge volumio[909]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 23 20:39:51 lounge volumio[909]: verbose: ControllerMpd::parseTrackInfo Oct 23 20:39:51 lounge volumio[909]: info: ControllerMpd::pushState Oct 23 20:39:51 lounge volumio[909]: info: CoreCommandRouter::servicePushState Oct 23 20:39:51 lounge volumio[909]: info: CorePlayQueue::getTrack 0 Oct 23 20:39:51 lounge volumio[909]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1238,"duration":441,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1255 Kbps","isStreaming":false,"title":"Chicago","artist":"Groove Armada","album":"Vertigo","uri":"NAS/bobbynet/Groove Armada - Vertigo/Chicago.flac","trackType":"flac"} Oct 23 20:39:51 lounge volumio[909]: verbose: CURRENT POSITION 0 Oct 23 20:39:51 lounge volumio[909]: info: CoreStateMachine::syncState stateService play Oct 23 20:39:51 lounge volumio[909]: info: CoreStateMachine::syncState currentStatus play Oct 23 20:39:51 lounge volumio[909]: info: Received an update from plugin. extracting info from payload Oct 23 20:39:51 lounge volumio[909]: info: CoreStateMachine::pushState Oct 23 20:39:51 lounge volumio[909]: info: CorePlayQueue::getTrack 0 Oct 23 20:39:51 lounge volumio[909]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 23 20:39:51 lounge volumio[909]: info: CoreCommandRouter::volumioPushState Oct 23 20:39:51 lounge volumio[909]: info: MRS: Pushing multiroomSync output update for this device Oct 23 20:39:51 lounge volumio[909]: info: MRS: Pushing multiroomSync output Oct 23 20:39:51 lounge volumio[909]: info: CoreStateMachine::pushState Oct 23 20:39:51 lounge volumio[909]: info: CorePlayQueue::getTrack 0 Oct 23 20:39:51 lounge volumio[909]: info: CoreCommandRouter::volumioPushState Oct 23 20:39:52 lounge volumio[909]: info: MRS: Pushing multiroomSync output update for this device Oct 23 20:39:52 lounge volumio[909]: info: MRS: Pushing multiroomSync output Oct 23 20:39:52 lounge volumio[909]: info: ------------------------------ 98ms Oct 23 20:40:02 lounge volumio[1159]: .............................................................................................................................................................................................................................++++ Oct 23 20:40:02 lounge volumio[1159]: e is 65537 (0x010001) Oct 23 20:40:02 lounge volumio[1159]: writing RSA key Oct 23 20:40:23 lounge volumio[909]: info: CoreCommandRouter::executeOnPlugin: multiroom , audioOutputPlay Oct 23 20:40:23 lounge volumio[909]: info: Error : CoreCommandRouter::executeOnPlugin: No method [audioOutputPlay] in plugin multiroom Oct 23 20:40:23 lounge volumio[909]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 23 20:40:23 lounge volumio[909]: TypeError: Cannot read property 'then' of undefined Oct 23 20:40:23 lounge volumio[909]: at outputs.audioOutputPlay (/volumio/app/plugins/audio_interface/outputs/index.js:367:9) Oct 23 20:40:23 lounge volumio[909]: at CoreCommandRouter.audioOutputPlay (/volumio/app/index.js:2249:30) Oct 23 20:40:23 lounge volumio[909]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1414:26) Oct 23 20:40:23 lounge volumio[909]: at Socket.emit (events.js:315:20) Oct 23 20:40:23 lounge volumio[909]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Oct 23 20:40:23 lounge volumio[909]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Oct 23 20:40:23 lounge volumio[909]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 23 20:40:25 lounge sudo[1591]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-23 20:39 Oct 23 20:40:25 lounge sudo[1591]: 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="e7445fab547cd22e23d9bef72406cfb610bc1d7d" VOLUMIO_FE_VERSION="5d5e99f8f803b3ec5fc5e8ca13bb4023600f3eee" VOLUMIO_FE3_VERSION="a6ae210575eae1ff43f06ab71e1cd17576840623" VOLUMIO_BE_VERSION="a9e1e1dd6072ffb15c0fcb8938d9ea0d8900c0da" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 24 Apr 2024 02:10:14 PM CEST" VOLUMIO_VERSION="3.661" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="5bdefd4af4423fd17aacb83a8538bf1f"