-- 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"