-- Logs begin at Fri 2024-04-12 18:29:14 CEST, end at Fri 2024-04-12 18:36:41 CEST. -- Apr 12 18:35:05 volumio ntpd[1104]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Apr 12 18:36:02 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 12 18:36:02 volumio volumio[856]: info: Received Get System Info Apr 12 18:36:02 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 12 18:36:02 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 12 18:36:02 volumio volumio[856]: info: Discovery: Getting this device information Apr 12 18:36:02 volumio volumio[856]: info: CoreCommandRouter::volumioGetState Apr 12 18:36:02 volumio volumio[856]: info: CorePlayQueue::getTrack 0 Apr 12 18:36:02 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 12 18:36:05 volumio volumio[856]: info: MyVolumio Plan changed: premium Apr 12 18:36:05 volumio volumio[856]: info: [MyVolumio PluginManager] Subscribed plan changed to premium Apr 12 18:36:05 volumio volumio[856]: info: Removing browser output: myVolumio user plan is not superstar Apr 12 18:36:05 volumio volumio[856]: info: Removing audio output: Apr 12 18:36:05 volumio volumio[856]: info: MYVOLUMIO: Adding device Apr 12 18:36:05 volumio volumio[856]: info: MYVOLUMIO: Evaluating Server Apr 12 18:36:05 volumio volumio[856]: info: Remote config written successfully Apr 12 18:36:05 volumio volumio[856]: info: Starting Tunnel 1 Apr 12 18:36:05 volumio volumio[856]: info: Starting Tunnel Connection Checker Apr 12 18:36:05 volumio volumio[856]: info: MYVolumio Device disabled Apr 12 18:36:06 volumio volumio[856]: info: Setting Geolocation for MyVolumio to eu6 Apr 12 18:36:06 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 12 18:36:06 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 12 18:36:06 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 12 18:36:06 volumio volumio[856]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Apr 12 18:36:07 volumio volumio[856]: info: Updating MyVolumio device info Apr 12 18:36:07 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 12 18:36:07 volumio volumio[856]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Apr 12 18:36:09 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 12 18:36:09 volumio volumio[856]: info: Received Get System Info Apr 12 18:36:09 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 12 18:36:09 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 12 18:36:09 volumio volumio[856]: info: Discovery: Getting this device information Apr 12 18:36:09 volumio volumio[856]: info: CoreCommandRouter::volumioGetState Apr 12 18:36:09 volumio volumio[856]: info: CorePlayQueue::getTrack 0 Apr 12 18:36:09 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 12 18:36:10 volumio volumio[856]: info: MYVOLUMIO: Adding device Apr 12 18:36:10 volumio volumio[856]: info: MYVOLUMIO: Evaluating Server Apr 12 18:36:10 volumio volumio[856]: info: Setting Geolocation for MyVolumio to eu3 Apr 12 18:36:10 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 12 18:36:10 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 12 18:36:10 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 12 18:36:11 volumio sudo[3089]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Apr 12 18:36:11 volumio sudo[3089]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 12 18:36:11 volumio systemd[1]: Started MyVolumio SSH Tunnel. Apr 12 18:36:11 volumio sudo[3089]: pam_unix(sudo:session): session closed for user root Apr 12 18:36:11 volumio volumio[856]: info: Remote SSH Started Apr 12 18:36:11 volumio autossh[3092]: port set to 0, monitoring disabled Apr 12 18:36:11 volumio autossh[3092]: starting ssh (count 1) Apr 12 18:36:11 volumio autossh[3092]: ssh child pid is 3095 Apr 12 18:36:11 volumio volumiossh-tunnel[3091]: Warning: Permanently added '[eu6.myvolumio.org]:2222,[207.154.233.230]:2222' (RSA) to the list of known hosts. Apr 12 18:36:11 volumio volumio[856]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Apr 12 18:36:11 volumio volumio[856]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 5 Apr 12 18:36:11 volumio volumio[856]: info: CoreCommandRouter::volumioGetState Apr 12 18:36:11 volumio volumio[856]: info: CorePlayQueue::getTrack 0 Apr 12 18:36:11 volumio volumio[856]: info: Updating MyVolumio device info Apr 12 18:36:11 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 12 18:36:12 volumio volumio[856]: info: Successfully Updated MyVolumio device Apr 12 18:36:30 volumio volumio[856]: info: MYVolumio Device enabled Apr 12 18:36:30 volumio volumio[856]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Apr 12 18:36:30 volumio volumio[856]: info: MyVolumio status changed Apr 12 18:36:30 volumio volumio[856]: info: Streaming services startup Apr 12 18:36:30 volumio volumio[856]: info: Starting Streaming Daemon Apr 12 18:36:30 volumio volumio[856]: error: [MyVolumio PluginManager] Could not read cache file: ENOENT: no such file or directory, open '/data/myvolumio/cache.data' Apr 12 18:36:30 volumio sudo[3100]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Apr 12 18:36:30 volumio sudo[3100]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 12 18:36:30 volumio sudo[3100]: pam_unix(sudo:session): session closed for user root Apr 12 18:36:30 volumio volumio[856]: error: Cannot start Volumio Streaming Daemon Apr 12 18:36:30 volumio volumio[856]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Apr 12 18:36:30 volumio volumio[856]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Apr 12 18:36:30 volumio volumio[856]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... Apr 12 18:36:31 volumio volumio[856]: info: [1712939791020] Starting BluetoothController Apr 12 18:36:31 volumio volumio[856]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... Apr 12 18:36:31 volumio sudo[3103]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Apr 12 18:36:31 volumio sudo[3103]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 12 18:36:31 volumio sudo[3103]: pam_unix(sudo:session): session closed for user root Apr 12 18:36:31 volumio volumio[856]: info: MRS: MultiRoom plugin initialized Apr 12 18:36:31 volumio volumio[856]: info: MRS: STOPPING SNAPCLIENT Apr 12 18:36:31 volumio volumio[856]: info: MRS: Snap server stop Apr 12 18:36:31 volumio sudo[3119]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Apr 12 18:36:31 volumio sudo[3119]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 12 18:36:31 volumio volumio[856]: info: MRS: STOPPING volumioStreaming Apr 12 18:36:31 volumio sudo[3119]: pam_unix(sudo:session): session closed for user root Apr 12 18:36:31 volumio sudo[3122]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Apr 12 18:36:31 volumio sudo[3122]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 12 18:36:31 volumio volumio[856]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... Apr 12 18:36:31 volumio sudo[3122]: pam_unix(sudo:session): session closed for user root Apr 12 18:36:31 volumio sudo[3125]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Apr 12 18:36:31 volumio sudo[3125]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 12 18:36:31 volumio sudo[3127]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Apr 12 18:36:31 volumio sudo[3127]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 12 18:36:31 volumio sudo[3125]: pam_unix(sudo:session): session closed for user root Apr 12 18:36:31 volumio sudo[3127]: pam_unix(sudo:session): session closed for user root Apr 12 18:36:31 volumio volumio[856]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... Apr 12 18:36:31 volumio volumio[856]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... Apr 12 18:36:31 volumio volumio[856]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... Apr 12 18:36:31 volumio volumio[856]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... Apr 12 18:36:31 volumio volumio[856]: info: [MyVolumio PluginManager] Loading plugin "tidal"... Apr 12 18:36:32 volumio volumio[856]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... Apr 12 18:36:32 volumio volumio[856]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... Apr 12 18:36:32 volumio volumio[856]: info: Preparing to generate the ALSA configuration file Apr 12 18:36:32 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Apr 12 18:36:32 volumio volumio[856]: info: MRS: Removed streaming files Apr 12 18:36:32 volumio volumio[856]: info: MRS: volumioStreaming STOPPED Apr 12 18:36:32 volumio volumio[856]: info: MRS: SNAPSERVER STOPPED Apr 12 18:36:32 volumio volumio[856]: info: MRS: SNAPCLIENT STOPPED Apr 12 18:36:32 volumio volumio[856]: info: Asound.conf file unchanged, so no further update is needed Apr 12 18:36:32 volumio volumio[856]: info: Output device has changed, restarting MPD Apr 12 18:36:32 volumio volumio[856]: info: Output device has changed, restarting Shairport Sync Apr 12 18:36:32 volumio sudo[3131]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 12 18:36:32 volumio sudo[3131]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 12 18:36:32 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 18:36:32 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 12 18:36:32 volumio sudo[3131]: pam_unix(sudo:session): session closed for user root Apr 12 18:36:32 volumio sudo[3134]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 12 18:36:32 volumio sudo[3134]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 12 18:36:32 volumio systemd[1]: Stopping Music Player Daemon... Apr 12 18:36:32 volumio volumio[856]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 12 18:36:32 volumio volumio[856]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Apr 12 18:36:32 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 18:36:32 volumio volumio[856]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth Apr 12 18:36:32 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 12 18:36:32 volumio volumio[856]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom Apr 12 18:36:32 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Apr 12 18:36:32 volumio volumio[856]: info: MRS: Pushing multiroomSync output for this device Apr 12 18:36:32 volumio volumio[856]: info: MRS: Pushing multiroomSync output Apr 12 18:36:32 volumio sudo[3142]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hciconfig hci0 name Volumio Apr 12 18:36:32 volumio sudo[3142]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 12 18:36:32 volumio volumio[856]: info: Adding audio output: Apr 12 18:36:32 volumio volumio[856]: info: Adding audio output: Apr 12 18:36:32 volumio volumio[856]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Apr 12 18:36:32 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 18:36:32 volumio volumio[856]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio Apr 12 18:36:32 volumio volumio[856]: info: Adding METAVOLUMIO REST API Endpoints Apr 12 18:36:32 volumio volumio[856]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Apr 12 18:36:32 volumio volumio[856]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Apr 12 18:36:32 volumio volumio[856]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Apr 12 18:36:32 volumio volumio[856]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Apr 12 18:36:32 volumio volumio[856]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui Apr 12 18:36:32 volumio volumio[856]: info: Adding Manifest REST API Endpoints Apr 12 18:36:32 volumio volumio[856]: info: Adding manifestui REST Endpoint for plugin: miscellanea/manifestui Apr 12 18:36:32 volumio volumio[856]: info: CoreCommandRouter::Reload Ui Apr 12 18:36:32 volumio volumio[856]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller Apr 12 18:36:32 volumio volumio[856]: info: Preparing CD Folders Apr 12 18:36:32 volumio volumio[856]: info: Adding CD REST API Endpoints Apr 12 18:36:32 volumio volumio[856]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Apr 12 18:36:32 volumio volumio[856]: info: Starting UDEV Watcher for CD Apr 12 18:36:32 volumio volumio[856]: info: Detecting CD presence with UDEV Apr 12 18:36:32 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices Apr 12 18:36:32 volumio volumio[856]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs Apr 12 18:36:32 volumio volumio[856]: info: Adding inputs REST Endpoints Apr 12 18:36:32 volumio volumio[856]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs Apr 12 18:36:32 volumio volumio[856]: info: Scanning Audio Inputs Apr 12 18:36:32 volumio sudo[3142]: pam_unix(sudo:session): session closed for user root Apr 12 18:36:32 volumio volumio[856]: info: Checking against Known Cards name Apr 12 18:36:32 volumio volumio[856]: info: Checking against Known Cards name Apr 12 18:36:32 volumio volumio[856]: info: Adding Server instance for streaming Apr 12 18:36:32 volumio volumio[856]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio Apr 12 18:36:32 volumio volumio[856]: error: Hi Res Audio Failed Login: Missing Login Data Apr 12 18:36:32 volumio volumio[856]: info: Adding HIGHRESAUDIO REST API Endpoints Apr 12 18:36:32 volumio volumio[856]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Apr 12 18:36:32 volumio volumio[856]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal Apr 12 18:36:32 volumio volumio[856]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz Apr 12 18:36:32 volumio volumio[856]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect Apr 12 18:36:32 volumio volumio[856]: info: Stopping AccessToken refresher cron Apr 12 18:36:32 volumio sudo[3154]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 12 18:36:32 volumio sudo[3154]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 12 18:36:32 volumio volumio[856]: info: AccessToken refresher cron started Apr 12 18:36:32 volumio volumio[856]: info: Stopping AccessToken refresher cron for QOBUZ Apr 12 18:36:32 volumio volumio[856]: info: AccessToken refresher cron started for QOBUZ Apr 12 18:36:32 volumio volumio[856]: info: Adding TIDAL REST API Endpoints Apr 12 18:36:32 volumio volumio[856]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal Apr 12 18:36:32 volumio volumio[856]: info: Adding QOBUZ REST API Endpoints Apr 12 18:36:32 volumio volumio[856]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz Apr 12 18:36:32 volumio sudo[3154]: pam_unix(sudo:session): session closed for user root Apr 12 18:36:32 volumio volumio[856]: info: Bluetooth name changed to Volumio Apr 12 18:36:32 volumio volumio[856]: info: MPD Permissions set Apr 12 18:36:32 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 18:36:32 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 18:36:32 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 18:36:32 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 18:36:32 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 18:36:32 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 18:36:32 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 18:36:32 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 18:36:32 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 12 18:36:32 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Apr 12 18:36:32 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 18:36:32 volumio volumio[856]: info: Starting Shairport Sync Apr 12 18:36:32 volumio sudo[3166]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 12 18:36:32 volumio sudo[3166]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 12 18:36:32 volumio volumio[856]: info: Preparing to generate the ALSA configuration file Apr 12 18:36:32 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 12 18:36:32 volumio volumio[856]: info: Asound.conf file unchanged, so no further update is needed Apr 12 18:36:32 volumio volumio[856]: info: Output device has changed, restarting MPD Apr 12 18:36:32 volumio sudo[3169]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 12 18:36:32 volumio systemd[1]: shairport-sync.service: Succeeded. Apr 12 18:36:32 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 12 18:36:32 volumio volumio[856]: info: Output device has changed, restarting Shairport Sync Apr 12 18:36:32 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 18:36:32 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 12 18:36:32 volumio sudo[3169]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 12 18:36:32 volumio sudo[3169]: pam_unix(sudo:session): session closed for user root Apr 12 18:36:32 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 12 18:36:32 volumio sudo[3166]: pam_unix(sudo:session): session closed for user root Apr 12 18:36:32 volumio sudo[3173]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 12 18:36:32 volumio sudo[3173]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 12 18:36:32 volumio volumio[856]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 12 18:36:32 volumio volumio[856]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Apr 12 18:36:32 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 18:36:32 volumio volumio[856]: info: Preparing to generate the ALSA configuration file Apr 12 18:36:32 volumio volumio[856]: info: MPD Permissions set Apr 12 18:36:32 volumio volumio[856]: info: Shairport-Sync Started Apr 12 18:36:32 volumio volumio[856]: info: MRS: Found cast device: Pioneer-VSX-933-8a19f3eb17c8a7dda567683b0011b40b Apr 12 18:36:32 volumio volumio[856]: info: Adding audio output: Apr 12 18:36:32 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 18:36:32 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 18:36:32 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 18:36:32 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 18:36:32 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 18:36:32 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 18:36:32 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 18:36:32 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 18:36:32 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 12 18:36:32 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Apr 12 18:36:32 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 18:36:32 volumio volumio[856]: info: Asound.conf file unchanged, so no further update is needed Apr 12 18:36:32 volumio volumio[856]: info: Output device has changed, restarting MPD Apr 12 18:36:32 volumio volumio[856]: info: Output device has changed, restarting Shairport Sync Apr 12 18:36:32 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 18:36:32 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 12 18:36:32 volumio sudo[3190]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 12 18:36:32 volumio sudo[3190]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 12 18:36:32 volumio sudo[3192]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 12 18:36:32 volumio sudo[3192]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 12 18:36:32 volumio sudo[3190]: pam_unix(sudo:session): session closed for user root Apr 12 18:36:32 volumio systemd[1]: mpd.service: Succeeded. Apr 12 18:36:32 volumio systemd[1]: Stopped Music Player Daemon. Apr 12 18:36:32 volumio volumio[856]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 12 18:36:32 volumio volumio[856]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Apr 12 18:36:32 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 18:36:32 volumio systemd[1]: Starting Music Player Daemon... Apr 12 18:36:32 volumio volumio[856]: info: MPD Permissions set Apr 12 18:36:32 volumio volumio[856]: info: Starting Shairport Sync Apr 12 18:36:32 volumio sudo[3202]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 12 18:36:32 volumio sudo[3202]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 12 18:36:32 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 18:36:32 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 18:36:32 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 18:36:32 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 18:36:32 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 18:36:32 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 18:36:32 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 18:36:32 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 18:36:32 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 12 18:36:32 volumio systemd[1]: shairport-sync.service: Succeeded. Apr 12 18:36:32 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 12 18:36:32 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 12 18:36:32 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Apr 12 18:36:32 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 12 18:36:32 volumio sudo[3202]: pam_unix(sudo:session): session closed for user root Apr 12 18:36:32 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 18:36:32 volumio volumio[856]: info: Shairport-Sync Started Apr 12 18:36:32 volumio volumio[856]: info: Starting Shairport Sync Apr 12 18:36:32 volumio sudo[3213]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 12 18:36:32 volumio sudo[3213]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 12 18:36:32 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 12 18:36:32 volumio systemd[1]: shairport-sync.service: Succeeded. Apr 12 18:36:32 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 12 18:36:32 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 12 18:36:32 volumio sudo[3213]: pam_unix(sudo:session): session closed for user root Apr 12 18:36:32 volumio volumio[856]: info: Shairport-Sync Started Apr 12 18:36:33 volumio mpd[3198]: Apr 12 18:36 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 12 18:36:33 volumio systemd[1]: Started Music Player Daemon. Apr 12 18:36:33 volumio sudo[3192]: pam_unix(sudo:session): session closed for user root Apr 12 18:36:33 volumio sudo[3173]: pam_unix(sudo:session): session closed for user root Apr 12 18:36:33 volumio sudo[3220]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 12 18:36:33 volumio sudo[3220]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 12 18:36:33 volumio sudo[3134]: pam_unix(sudo:session): session closed for user root Apr 12 18:36:33 volumio sudo[3220]: pam_unix(sudo:session): session closed for user root Apr 12 18:36:33 volumio sudo[3222]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 12 18:36:33 volumio sudo[3222]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 12 18:36:33 volumio sudo[3222]: pam_unix(sudo:session): session closed for user root Apr 12 18:36:33 volumio sudo[3229]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 12 18:36:33 volumio sudo[3229]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 12 18:36:33 volumio sudo[3229]: pam_unix(sudo:session): session closed for user root Apr 12 18:36:33 volumio sudo[3232]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 12 18:36:33 volumio sudo[3232]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 12 18:36:33 volumio sudo[3232]: pam_unix(sudo:session): session closed for user root Apr 12 18:36:33 volumio volumio[856]: error: MPD error: The expression evaluated to a falsy value: Apr 12 18:36:33 volumio volumio[856]: assert.ok(self.idling) Apr 12 18:36:33 volumio volumio[856]: error: The expression evaluated to a falsy value: Apr 12 18:36:33 volumio volumio[856]: assert.ok(self.idling) Apr 12 18:36:33 volumio volumio[856]: error: MPD error: The expression evaluated to a falsy value: Apr 12 18:36:33 volumio volumio[856]: assert.ok(self.idling) Apr 12 18:36:33 volumio volumio[856]: error: The expression evaluated to a falsy value: Apr 12 18:36:33 volumio volumio[856]: assert.ok(self.idling) Apr 12 18:36:33 volumio volumio[856]: error: updateQueue error: null Apr 12 18:36:33 volumio volumio[856]: verbose: New Socket.io Connection to 192.168.1.99 from 192.168.1.23 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:124.0) Gecko/20100101 Firefox/124.0 Total Clients: 5 Apr 12 18:36:34 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 12 18:36:34 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 12 18:36:34 volumio volumio[856]: info: CoreCommandRouter::volumioGetVisibleSources Apr 12 18:36:34 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 12 18:36:34 volumio volumio[856]: info: CoreCommandRouter::volumioGetState Apr 12 18:36:34 volumio volumio[856]: info: CorePlayQueue::getTrack 0 Apr 12 18:36:34 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Apr 12 18:36:34 volumio volumio[856]: info: CoreCommandRouter::volumioGetQueue Apr 12 18:36:34 volumio volumio[856]: info: CoreStateMachine::getQueue Apr 12 18:36:34 volumio volumio[856]: info: CorePlayQueue::getQueue Apr 12 18:36:34 volumio volumio[856]: info: Listing playlists Apr 12 18:36:34 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 12 18:36:34 volumio volumio[856]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Apr 12 18:36:34 volumio volumio[856]: info: Received Get System Info Apr 12 18:36:34 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 12 18:36:34 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 12 18:36:34 volumio volumio[856]: info: Discovery: Getting this device information Apr 12 18:36:34 volumio volumio[856]: info: CoreCommandRouter::volumioGetState Apr 12 18:36:34 volumio volumio[856]: info: CorePlayQueue::getTrack 0 Apr 12 18:36:34 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 12 18:36:34 volumio volumio[856]: info: CoreCommandRouter::volumioGetState Apr 12 18:36:34 volumio volumio[856]: info: CorePlayQueue::getTrack 0 Apr 12 18:36:34 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Apr 12 18:36:34 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 12 18:36:34 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Apr 12 18:36:35 volumio sudo[3235]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Apr 12 18:36:35 volumio sudo[3235]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 12 18:36:35 volumio systemd[1]: Started Volumio Bluetooth Module. Apr 12 18:36:35 volumio sudo[3235]: pam_unix(sudo:session): session closed for user root Apr 12 18:36:35 volumio volumio[856]: info: Volumio BT Module successfully started Apr 12 18:36:35 volumio sudo[3238]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth/* Apr 12 18:36:35 volumio sudo[3238]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 12 18:36:35 volumio volumiobt[3237]: /bin/chmod: cannot access '/var/lib/bluetooth/*': No such file or directory Apr 12 18:36:35 volumio sudo[3238]: pam_unix(sudo:session): session closed for user root Apr 12 18:36:35 volumio volumio[856]: info: TidalConnect service stoped! Apr 12 18:36:35 volumio volumio[856]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Apr 12 18:36:35 volumio volumio[856]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect Apr 12 18:36:35 volumio sudo[3250]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Apr 12 18:36:35 volumio sudo[3250]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 12 18:36:35 volumio systemd[1]: Started Volumio Tidal Connect Service. Apr 12 18:36:35 volumio sudo[3250]: pam_unix(sudo:session): session closed for user root Apr 12 18:36:35 volumio volumio[856]: info: Executing endpoint tc_getconfig Apr 12 18:36:35 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Apr 12 18:36:35 volumio vtcs[3252]: STARTING TidalConnect services, version: 1.3.0.19 Apr 12 18:36:35 volumio vtcs[3252]: STARTED TidalConnect services. Apr 12 18:36:35 volumio volumio[856]: info: Executing endpoint tc_connect Apr 12 18:36:35 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Apr 12 18:36:35 volumio volumio[856]: info: Connecting to TidalConnect Apr 12 18:36:35 volumio volumio[856]: info: CoreCommandRouter::servicePushState Apr 12 18:36:35 volumio volumio[856]: info: CoreStateMachine::pushState Apr 12 18:36:35 volumio volumio[856]: info: CorePlayQueue::getTrack 0 Apr 12 18:36:35 volumio volumio[856]: info: CoreCommandRouter::volumioPushState Apr 12 18:36:35 volumio volumio[856]: info: MRS: Pushing multiroomSync output update for this device Apr 12 18:36:35 volumio volumio[856]: info: MRS: Pushing multiroomSync output Apr 12 18:36:35 volumio volumio[856]: info: CorePlayQueue::getTrack 0 Apr 12 18:36:35 volumio volumio[856]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Apr 12 18:36:35 volumio volumio[856]: verbose: CURRENT POSITION 0 Apr 12 18:36:35 volumio volumio[856]: info: CoreStateMachine::syncState stateService stop Apr 12 18:36:35 volumio volumio[856]: info: CoreStateMachine::syncState currentStatus stop Apr 12 18:36:35 volumio volumio[856]: info: CoreStateMachine::pushState Apr 12 18:36:35 volumio volumio[856]: info: CorePlayQueue::getTrack 0 Apr 12 18:36:35 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 18:36:35 volumio volumio[856]: info: CoreCommandRouter::volumioPushState Apr 12 18:36:35 volumio volumio[856]: info: MRS: Pushing multiroomSync output update for this device Apr 12 18:36:35 volumio volumio[856]: info: MRS: Pushing multiroomSync output Apr 12 18:36:35 volumio volumio[856]: info: No code Apr 12 18:36:35 volumio volumio[856]: info: CoreStateMachine::pushState Apr 12 18:36:35 volumio volumio[856]: info: CorePlayQueue::getTrack 0 Apr 12 18:36:35 volumio volumio[856]: info: CoreCommandRouter::volumioPushState Apr 12 18:36:35 volumio volumio[856]: info: MRS: Pushing multiroomSync output update for this device Apr 12 18:36:35 volumio volumio[856]: info: MRS: Pushing multiroomSync output Apr 12 18:36:35 volumio volumio[856]: info: CoreCommandRouter::servicePushState Apr 12 18:36:35 volumio volumio[856]: info: CoreStateMachine::pushState Apr 12 18:36:35 volumio volumio[856]: info: CorePlayQueue::getTrack 0 Apr 12 18:36:35 volumio volumio[856]: info: CoreCommandRouter::volumioPushState Apr 12 18:36:35 volumio volumio[856]: info: MRS: Pushing multiroomSync output update for this device Apr 12 18:36:35 volumio volumio[856]: info: MRS: Pushing multiroomSync output Apr 12 18:36:35 volumio volumio[856]: info: CorePlayQueue::getTrack 0 Apr 12 18:36:35 volumio volumio[856]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Apr 12 18:36:35 volumio volumio[856]: verbose: CURRENT POSITION 0 Apr 12 18:36:35 volumio volumio[856]: info: CoreStateMachine::syncState stateService stop Apr 12 18:36:35 volumio volumio[856]: info: CoreStateMachine::syncState currentStatus stop Apr 12 18:36:35 volumio volumio[856]: info: CoreStateMachine::pushState Apr 12 18:36:35 volumio volumio[856]: info: CorePlayQueue::getTrack 0 Apr 12 18:36:35 volumio volumio[856]: info: CoreCommandRouter::volumioPushState Apr 12 18:36:35 volumio volumio[856]: info: MRS: Pushing multiroomSync output update for this device Apr 12 18:36:35 volumio volumio[856]: info: MRS: Pushing multiroomSync output Apr 12 18:36:35 volumio volumio[856]: info: No code Apr 12 18:36:35 volumio volumio[856]: info: CoreStateMachine::pushState Apr 12 18:36:35 volumio volumio[856]: info: CorePlayQueue::getTrack 0 Apr 12 18:36:35 volumio volumio[856]: info: CoreCommandRouter::volumioPushState Apr 12 18:36:35 volumio volumio[856]: info: MRS: Pushing multiroomSync output update for this device Apr 12 18:36:35 volumio volumio[856]: info: MRS: Pushing multiroomSync output Apr 12 18:36:35 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 12 18:36:35 volumio volumio[856]: info: Received Get System Info Apr 12 18:36:35 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 12 18:36:35 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 12 18:36:35 volumio volumio[856]: info: Discovery: Getting this device information Apr 12 18:36:35 volumio volumio[856]: info: CoreCommandRouter::volumioGetState Apr 12 18:36:35 volumio volumio[856]: info: CorePlayQueue::getTrack 0 Apr 12 18:36:35 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 12 18:36:35 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Apr 12 18:36:36 volumio sudo[3270]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall pulseaudio Apr 12 18:36:36 volumio sudo[3270]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 12 18:36:36 volumio volumiobt[3237]: pulseaudio: no process found Apr 12 18:36:36 volumio sudo[3270]: pam_unix(sudo:session): session closed for user root Apr 12 18:36:36 volumio dbus-daemon[521]: [system] Activating via systemd: service name='org.freedesktop.RealtimeKit1' unit='rtkit-daemon.service' requested by ':1.34' (uid=1000 pid=3274 comm="/usr/bin/pulseaudio --high-priority=true ") Apr 12 18:36:36 volumio systemd[1]: Starting RealtimeKit Scheduling Policy Service... Apr 12 18:36:36 volumio volumio[856]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 12 18:36:36 volumio dbus-daemon[521]: [system] Successfully activated service 'org.freedesktop.RealtimeKit1' Apr 12 18:36:36 volumio systemd[1]: Started RealtimeKit Scheduling Policy Service. Apr 12 18:36:36 volumio rtkit-daemon[3275]: Successfully called chroot. Apr 12 18:36:36 volumio rtkit-daemon[3275]: Successfully dropped privileges. Apr 12 18:36:36 volumio rtkit-daemon[3275]: Successfully limited resources. Apr 12 18:36:36 volumio rtkit-daemon[3275]: Running. Apr 12 18:36:36 volumio rtkit-daemon[3275]: Watchdog thread running. Apr 12 18:36:36 volumio dbus-daemon[521]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.35' (uid=0 pid=3275 comm="/usr/lib/rtkit/rtkit-daemon ") Apr 12 18:36:36 volumio rtkit-daemon[3275]: Canary thread running. Apr 12 18:36:36 volumio systemd[1]: Starting Authorization Manager... Apr 12 18:36:36 volumio volumio[856]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 12 18:36:36 volumio polkitd[3278]: started daemon version 0.105 using authority implementation `local' version `0.105' Apr 12 18:36:36 volumio dbus-daemon[521]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' Apr 12 18:36:36 volumio systemd[1]: Started Authorization Manager. Apr 12 18:36:36 volumio pulseaudio[3274]: Failed to open cookie file '/home/volumio/.config/pulse/cookie': No such file or directory Apr 12 18:36:36 volumio pulseaudio[3274]: Failed to load authentication key '/home/volumio/.config/pulse/cookie': No such file or directory Apr 12 18:36:36 volumio pulseaudio[3274]: Failed to open cookie file '/home/volumio/.pulse-cookie': No such file or directory Apr 12 18:36:36 volumio pulseaudio[3274]: Failed to load authentication key '/home/volumio/.pulse-cookie': No such file or directory Apr 12 18:36:36 volumio pulseaudio[3274]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Apr 12 18:36:36 volumio pulseaudio[3274]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Apr 12 18:36:36 volumio pulseaudio[3274]: 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 Apr 12 18:36:36 volumio bluetoothd[505]: Endpoint registered: sender=:1.55 path=/MediaEndpoint/A2DPSource Apr 12 18:36:36 volumio volumiobt[3237]: Applying permissions Apr 12 18:36:36 volumio volumiobt[3237]: Setting BT discoverable and pairable Apr 12 18:36:36 volumio bluetoothd[505]: Endpoint registered: sender=:1.55 path=/MediaEndpoint/A2DPSink Apr 12 18:36:36 volumio kernel: Bluetooth: RFCOMM TTY layer initialized Apr 12 18:36:36 volumio kernel: Bluetooth: RFCOMM socket layer initialized Apr 12 18:36:36 volumio kernel: Bluetooth: RFCOMM ver 1.11 Apr 12 18:36:36 volumio volumiobt[3237]: Agent registered Apr 12 18:36:36 volumio volumiobt[3237]: [bluetooth]# -e power on Apr 12 18:36:36 volumio volumiobt[3237]: Invalid command in menu main: -e Apr 12 18:36:36 volumio volumiobt[3237]: Apr 12 18:36:36 volumio volumiobt[3237]: Use "help" for a list of available commands in a menu. Apr 12 18:36:36 volumio volumiobt[3237]: Use "menu " if you want to enter any submenu. Apr 12 18:36:36 volumio volumiobt[3237]: Use "back" if you want to return to menu main. Apr 12 18:36:36 volumio volumiobt[3237]: [bluetooth]# agent on Apr 12 18:36:36 volumio volumiobt[3237]: Agent is already registered Apr 12 18:36:36 volumio volumiobt[3237]: [bluetooth]# discoverable on Apr 12 18:36:36 volumio volumiobt[3237]: [bluetooth]# pairable on Apr 12 18:36:36 volumio volumiobt[3237]: [bluetooth]# agent NoInputNoOutput Apr 12 18:36:36 volumio volumiobt[3237]: Agent is already registered Apr 12 18:36:36 volumio volumiobt[3237]: [bluetooth]# default-agent Apr 12 18:36:36 volumio volumiobt[3237]: [bluetooth]# quit Apr 12 18:36:36 volumio volumiobt[3237]: [59B blob data] Apr 12 18:36:37 volumio volumio[856]: info: MRS: Getting audio outputs on start Apr 12 18:36:37 volumio volumio[856]: info: MRS: Requesting all other devices output Apr 12 18:36:38 volumio volumio[856]: info: TidalConnect service started! Apr 12 18:36:38 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Apr 12 18:36:38 volumio volumio[856]: info: Preload queue cleared Apr 12 18:36:40 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Apr 12 18:36:40 volumio volumio[856]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 12 18:36:40 volumio volumio[856]: TypeError: Cannot read property 'length' of undefined Apr 12 18:36:40 volumio volumio[856]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Apr 12 18:36:40 volumio volumio[856]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Apr 12 18:36:40 volumio volumio[856]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Apr 12 18:36:40 volumio volumio[856]: at Parser.emit (events.js:400:28) Apr 12 18:36:40 volumio volumio[856]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Apr 12 18:36:40 volumio volumio[856]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Apr 12 18:36:40 volumio volumio[856]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Apr 12 18:36:40 volumio volumio[856]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Apr 12 18:36:40 volumio volumio[856]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Apr 12 18:36:40 volumio volumio[856]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Apr 12 18:36:40 volumio volumio[856]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Apr 12 18:36:40 volumio volumio[856]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Apr 12 18:36:40 volumio volumio[856]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Apr 12 18:36:40 volumio volumio[856]: at IncomingMessage.emit (events.js:412:35) Apr 12 18:36:40 volumio volumio[856]: at endReadableNT (internal/streams/readable.js:1333:12) Apr 12 18:36:40 volumio volumio[856]: at processTicksAndRejections (internal/process/task_queues.js:82:21) Apr 12 18:36:40 volumio volumio[856]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 12 18:36:41 volumio sudo[3300]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-12 18:35 Apr 12 18:36:41 volumio sudo[3300]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="7b9cb4a8b52fb7148a38d6ac651c1c29e09bb92c" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="b1dcc17f6d31bf91f0abd3c3fa57506d1e0d2b6b" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 01 Mar 2024 08:52:33 PM CET" VOLUMIO_VERSION="3.634" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="93edfc70711724967af109c0a39fa069"