-- Logs begin at Sun 2024-04-21 12:56:13 CEST, end at Sun 2024-04-21 15:47:29 CEST. --
Apr 21 15:46:01 volumio-aly volumio[14434]: info: Getting Spotify volume
Apr 21 15:46:01 volumio-aly volumio[14434]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 5
Apr 21 15:46:01 volumio-aly volumio[14434]: info: CoreCommandRouter::volumioGetState
Apr 21 15:46:01 volumio-aly volumio[14434]: info: CorePlayQueue::getTrack 0
Apr 21 15:46:01 volumio-aly volumio[14434]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100
Apr 21 15:46:01 volumio-aly volumio[14434]: SPOTIFY: SPOTIFY VOLUME undefined
Apr 21 15:46:01 volumio-aly volumio[14434]: SPOTIFY: VOLUMIO VOLUME 100
Apr 21 15:46:01 volumio-aly volumio[14434]: info: Aligning Spotify Volume to Volumio Volume
Apr 21 15:46:01 volumio-aly volumio[14434]: info: CoreCommandRouter::volumioGetState
Apr 21 15:46:01 volumio-aly volumio[14434]: info: CorePlayQueue::getTrack 0
Apr 21 15:46:01 volumio-aly volumio[14434]: info: Setting Spotify Volume from Volumio: 100
Apr 21 15:46:01 volumio-aly volumio[14434]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Apr 21 15:46:01 volumio-aly volumio[14434]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium
Apr 21 15:46:01 volumio-aly volumio[14434]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium
Apr 21 15:46:01 volumio-aly volumio[14434]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium
Apr 21 15:46:01 volumio-aly volumio[14434]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium
Apr 21 15:46:01 volumio-aly volumio[14434]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium
Apr 21 15:46:01 volumio-aly volumio[14434]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium
Apr 21 15:46:01 volumio-aly volumio[14434]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium
Apr 21 15:46:01 volumio-aly volumio[14434]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium
Apr 21 15:46:01 volumio-aly volumio[14434]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium
Apr 21 15:46:01 volumio-aly volumio[14434]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium
Apr 21 15:46:01 volumio-aly volumio[14434]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar
Apr 21 15:46:01 volumio-aly volumio[14434]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar
Apr 21 15:46:01 volumio-aly volumio[14434]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar
Apr 21 15:46:01 volumio-aly volumio[14434]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar
Apr 21 15:46:01 volumio-aly volumio[14434]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar
Apr 21 15:46:01 volumio-aly volumio[14434]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar
Apr 21 15:46:01 volumio-aly volumio[14434]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar
Apr 21 15:46:01 volumio-aly volumio[14434]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar
Apr 21 15:46:01 volumio-aly volumio[14434]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar
Apr 21 15:46:01 volumio-aly volumio[14434]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar
Apr 21 15:46:01 volumio-aly volumio[14434]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso
Apr 21 15:46:01 volumio-aly volumio[14434]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso
Apr 21 15:46:01 volumio-aly volumio[14434]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso
Apr 21 15:46:01 volumio-aly volumio[14434]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso
Apr 21 15:46:01 volumio-aly volumio[14434]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso
Apr 21 15:46:01 volumio-aly volumio[14434]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso
Apr 21 15:46:01 volumio-aly volumio[14434]: info: Adding plugin bluetooth to MyMusic Plugins
Apr 21 15:46:01 volumio-aly volumio[14434]: info: Adding plugin multiroom to MyMusic Plugins
Apr 21 15:46:01 volumio-aly volumio[14434]: info: Adding plugin metavolumio to MyMusic Plugins
Apr 21 15:46:01 volumio-aly volumio[14434]: info: Adding plugin cd_controller to MyMusic Plugins
Apr 21 15:46:01 volumio-aly volumio[14434]: info: Adding plugin smart_inputs to MyMusic Plugins
Apr 21 15:46:01 volumio-aly volumio[14434]: info: Adding plugin tidalconnect to MyMusic Plugins
Apr 21 15:46:01 volumio-aly volumio[14434]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"...
Apr 21 15:46:01 volumio-aly volumio[14434]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded
Apr 21 15:46:01 volumio-aly volumio[14434]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio
Apr 21 15:46:01 volumio-aly volumio[14434]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 21 15:46:01 volumio-aly volumio[14434]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 21 15:46:01 volumio-aly volumio[14434]: info: Starting MyVolumio Remote Streaming Endpoints
Apr 21 15:46:01 volumio-aly volumio[14434]: info: MyVolumio login type: Token
Apr 21 15:46:01 volumio-aly volumio[14434]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started
Apr 21 15:46:01 volumio-aly volumio[14434]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"...
Apr 21 15:46:01 volumio-aly volumio[14434]: info: Starting Streaming Service Transparent Proxy
Apr 21 15:46:01 volumio-aly volumio[14434]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded
Apr 21 15:46:01 volumio-aly volumio[14434]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services
Apr 21 15:46:01 volumio-aly volumio[14434]: info: Streaming services startup
Apr 21 15:46:01 volumio-aly volumio[14434]: info: Starting Streaming Daemon
Apr 21 15:46:01 volumio-aly sudo[14695]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service
Apr 21 15:46:01 volumio-aly sudo[14695]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 21 15:46:01 volumio-aly sudo[14695]: pam_unix(sudo:session): session closed for user root
Apr 21 15:46:01 volumio-aly volumio[14434]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started
Apr 21 15:46:02 volumio-aly volumio[14434]: error: Cannot start Volumio Streaming Daemon
Apr 21 15:46:02 volumio-aly volumio[14434]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service
Apr 21 15:46:02 volumio-aly volumio[14434]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found.
Apr 21 15:46:02 volumio-aly volumio[14434]: error: MyVolumio Custom Token format not valid, refreshing it
Apr 21 15:46:02 volumio-aly volumio[14434]: STREAMING PROXY: Starting server on port 3245
Apr 21 15:46:02 volumio-aly volumio[14434]: Node JS runtime: 14
Apr 21 15:46:02 volumio-aly volumio[14434]: SPOTIFY: SETTING SPOTIFY VOLUME 100
Apr 21 15:46:02 volumio-aly volumio[14434]: info: Sending Spotify command with payload to local API: /player/volume
Apr 21 15:46:02 volumio-aly volumio[14434]: info: MyVolumio login type: Token
Apr 21 15:46:03 volumio-aly volumio[14434]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN
Apr 21 15:46:03 volumio-aly volumio[14434]: info: CoreCommandRouter::Close All Modals sent
Apr 21 15:46:04 volumio-aly volumio[14434]: info: MyVolumio token set successfully
Apr 21 15:46:04 volumio-aly volumio[14434]: info: MYVOLUMIO: Adding device
Apr 21 15:46:04 volumio-aly volumio[14434]: info: MYVOLUMIO: Evaluating Server
Apr 21 15:46:04 volumio-aly volumio[14434]: info: MyVolumio Plan changed: premium
Apr 21 15:46:04 volumio-aly volumio[14434]: info: [MyVolumio PluginManager] Subscribed plan changed to premium
Apr 21 15:46:04 volumio-aly volumio[14434]: info: Removing browser output: myVolumio user plan is not superstar
Apr 21 15:46:04 volumio-aly volumio[14434]: info: Removing audio output:
Apr 21 15:46:04 volumio-aly volumio[14434]: info: MYVOLUMIO: Adding device
Apr 21 15:46:04 volumio-aly volumio[14434]: info: MYVOLUMIO: Evaluating Server
Apr 21 15:46:04 volumio-aly volumio[14434]: info: Remote config written successfully
Apr 21 15:46:04 volumio-aly volumio[14434]: info: Starting Tunnel 1
Apr 21 15:46:04 volumio-aly volumio[14434]: info: Starting Tunnel Connection Checker
Apr 21 15:46:04 volumio-aly volumio[14434]: info: Completed starting MyVolumio Plugin
Apr 21 15:46:05 volumio-aly volumio[14434]: info: MYVolumio Device enabled
Apr 21 15:46:05 volumio-aly volumio[14434]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins...
Apr 21 15:46:05 volumio-aly volumio[14434]: info: MyVolumio status changed
Apr 21 15:46:05 volumio-aly volumio[14434]: info: Streaming services startup
Apr 21 15:46:05 volumio-aly volumio[14434]: info: Starting Streaming Daemon
Apr 21 15:46:05 volumio-aly sudo[14736]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service
Apr 21 15:46:05 volumio-aly sudo[14736]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 21 15:46:05 volumio-aly volumio[14434]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid
Apr 21 15:46:05 volumio-aly volumio[14434]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"...
Apr 21 15:46:05 volumio-aly sudo[14736]: pam_unix(sudo:session): session closed for user root
Apr 21 15:46:05 volumio-aly volumio[14434]: info: [1713707165173] Starting BluetoothController
Apr 21 15:46:05 volumio-aly volumio[14434]: info: [MyVolumio PluginManager] Loading plugin "multiroom"...
Apr 21 15:46:05 volumio-aly volumio[14434]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom
Apr 21 15:46:05 volumio-aly sudo[14739]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom
Apr 21 15:46:05 volumio-aly sudo[14739]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 21 15:46:05 volumio-aly sudo[14739]: pam_unix(sudo:session): session closed for user root
Apr 21 15:46:05 volumio-aly volumio[14434]: /bin/mkdir: cannot create directory ‘/tmp/hls’: File exists
Apr 21 15:46:05 volumio-aly volumio[14434]: info: MRS: MultiRoom plugin initialized
Apr 21 15:46:05 volumio-aly volumio[14434]: info: MRS: STOPPING SNAPCLIENT
Apr 21 15:46:05 volumio-aly volumio[14434]: info: MRS: Snap server stop
Apr 21 15:46:05 volumio-aly sudo[14755]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient
Apr 21 15:46:05 volumio-aly sudo[14755]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 21 15:46:05 volumio-aly volumio[14434]: info: MRS: STOPPING volumioStreaming
Apr 21 15:46:05 volumio-aly sudo[14755]: pam_unix(sudo:session): session closed for user root
Apr 21 15:46:05 volumio-aly sudo[14758]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver
Apr 21 15:46:05 volumio-aly sudo[14758]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 21 15:46:05 volumio-aly sudo[14761]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming
Apr 21 15:46:05 volumio-aly sudo[14761]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 21 15:46:05 volumio-aly sudo[14758]: pam_unix(sudo:session): session closed for user root
Apr 21 15:46:05 volumio-aly volumio[14434]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"...
Apr 21 15:46:05 volumio-aly sudo[14764]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/*
Apr 21 15:46:05 volumio-aly sudo[14761]: pam_unix(sudo:session): session closed for user root
Apr 21 15:46:05 volumio-aly sudo[14764]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 21 15:46:05 volumio-aly sudo[14764]: pam_unix(sudo:session): session closed for user root
Apr 21 15:46:05 volumio-aly volumio[14434]: info: [MyVolumio PluginManager] Loading plugin "manifestui"...
Apr 21 15:46:05 volumio-aly volumio[14434]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"...
Apr 21 15:46:05 volumio-aly volumio[14434]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"...
Apr 21 15:46:05 volumio-aly volumio[14434]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"...
Apr 21 15:46:05 volumio-aly volumio[14434]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio
Apr 21 15:46:05 volumio-aly volumio[14434]: info: [MyVolumio PluginManager] Loading plugin "tidal"...
Apr 21 15:46:05 volumio-aly volumio[14434]: info: [MyVolumio PluginManager] Loading plugin "qobuz"...
Apr 21 15:46:05 volumio-aly volumio[14434]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"...
Apr 21 15:46:05 volumio-aly volumio[14434]: info: Preparing to generate the ALSA configuration file
Apr 21 15:46:05 volumio-aly volumio[14434]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf
Apr 21 15:46:05 volumio-aly volumio[14434]: info: Reading ALSA contributions from plugins.
Apr 21 15:46:05 volumio-aly volumio[14434]: info: Setting Geolocation for MyVolumio to eu4
Apr 21 15:46:05 volumio-aly volumio[14434]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 21 15:46:05 volumio-aly volumio[14434]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 21 15:46:05 volumio-aly volumio[14434]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 21 15:46:05 volumio-aly volumio[14434]: info: Setting Geolocation for MyVolumio to eu4
Apr 21 15:46:05 volumio-aly volumio[14434]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 21 15:46:05 volumio-aly volumio[14434]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 21 15:46:05 volumio-aly volumio[14434]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 21 15:46:05 volumio-aly volumio[14434]: info: MRS: Removed streaming files
Apr 21 15:46:05 volumio-aly volumio[14434]: info: MRS: volumioStreaming STOPPED
Apr 21 15:46:05 volumio-aly volumio[14434]: info: MRS: SNAPSERVER STOPPED
Apr 21 15:46:05 volumio-aly volumio[14434]: info: MRS: SNAPCLIENT STOPPED
Apr 21 15:46:05 volumio-aly volumio[14434]: error: Cannot start Volumio Streaming Daemon
Apr 21 15:46:05 volumio-aly volumio[14434]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service
Apr 21 15:46:05 volumio-aly volumio[14434]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found.
Apr 21 15:46:05 volumio-aly volumio[14434]: error: Cannot delete local playlist /data/playlist/Franchement music : Error: ENOENT: no such file or directory, unlink '/data/playlist/Franchement music'
Apr 21 15:46:05 volumio-aly volumio[14434]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri
Apr 21 15:46:05 volumio-aly volumio[14434]: info: CoreCommandRouter::volumioGetQueue
Apr 21 15:46:05 volumio-aly volumio[14434]: info: CoreStateMachine::getQueue
Apr 21 15:46:05 volumio-aly volumio[14434]: info: CorePlayQueue::getQueue
Apr 21 15:46:05 volumio-aly volumio[14434]: info: Asound.conf file written
Apr 21 15:46:05 volumio-aly sudo[14767]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf
Apr 21 15:46:05 volumio-aly sudo[14767]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 21 15:46:05 volumio-aly sudo[14767]: pam_unix(sudo:session): session closed for user root
Apr 21 15:46:05 volumio-aly volumio[14434]: No state is present for card Pro
Apr 21 15:46:05 volumio-aly volumio[14434]: Found hardware: "USB-Audio" "USB Mixer" "USB152a:8750" "" ""
Apr 21 15:46:05 volumio-aly volumio[14434]: Hardware is initialized using a generic method
Apr 21 15:46:05 volumio-aly volumio[14434]: No state is present for card Pro
Apr 21 15:46:05 volumio-aly volumio[14434]: info: Output device has changed, restarting MPD
Apr 21 15:46:05 volumio-aly sudo[14772]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Apr 21 15:46:05 volumio-aly sudo[14772]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 21 15:46:05 volumio-aly sudo[14772]: pam_unix(sudo:session): session closed for user root
Apr 21 15:46:05 volumio-aly volumio[14434]: info: Output device has changed, restarting Shairport Sync
Apr 21 15:46:05 volumio-aly volumio[14434]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 21 15:46:05 volumio-aly volumio[14434]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 21 15:46:05 volumio-aly sudo[14775]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Apr 21 15:46:05 volumio-aly sudo[14775]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 21 15:46:05 volumio-aly systemd[1]: Stopping Music Player Daemon...
Apr 21 15:46:05 volumio-aly systemd[1]: mpd.service: Succeeded.
Apr 21 15:46:05 volumio-aly systemd[1]: Stopped Music Player Daemon.
Apr 21 15:46:05 volumio-aly volumio[14434]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Apr 21 15:46:05 volumio-aly volumio[14434]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration
Apr 21 15:46:05 volumio-aly volumio[14434]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 21 15:46:05 volumio-aly volumio[14434]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth
Apr 21 15:46:05 volumio-aly volumio[14434]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Apr 21 15:46:05 volumio-aly systemd[1]: Starting Music Player Daemon...
Apr 21 15:46:05 volumio-aly volumio[14434]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom
Apr 21 15:46:05 volumio-aly volumio[14434]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus
Apr 21 15:46:05 volumio-aly volumio[14434]: info: MRS: Pushing multiroomSync output for this device
Apr 21 15:46:05 volumio-aly volumio[14434]: info: MRS: Pushing multiroomSync output
Apr 21 15:46:05 volumio-aly volumio[14434]: info: Adding audio output:
Apr 21 15:46:05 volumio-aly volumio[14434]: info: Adding audio output:
Apr 21 15:46:05 volumio-aly sudo[14785]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hciconfig hci0 name Volumio-ALY
Apr 21 15:46:05 volumio-aly sudo[14785]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 21 15:46:05 volumio-aly volumio[14434]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration
Apr 21 15:46:05 volumio-aly volumio[14434]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 21 15:46:05 volumio-aly sudo[14785]: pam_unix(sudo:session): session closed for user root
Apr 21 15:46:05 volumio-aly volumio[14434]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio
Apr 21 15:46:05 volumio-aly volumio[14434]: info: Adding METAVOLUMIO REST API Endpoints
Apr 21 15:46:05 volumio-aly volumio[14434]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio
Apr 21 15:46:05 volumio-aly volumio[14434]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio
Apr 21 15:46:05 volumio-aly volumio[14434]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio
Apr 21 15:46:05 volumio-aly volumio[14434]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio
Apr 21 15:46:05 volumio-aly volumio[14434]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui
Apr 21 15:46:05 volumio-aly volumio[14434]: info: Adding Manifest REST API Endpoints
Apr 21 15:46:05 volumio-aly volumio[14434]: info: Adding manifestui REST Endpoint for plugin: miscellanea/manifestui
Apr 21 15:46:05 volumio-aly volumio[14434]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller
Apr 21 15:46:05 volumio-aly volumio[14434]: info: Preparing CD Folders
Apr 21 15:46:05 volumio-aly volumio[14434]: info: Adding CD REST API Endpoints
Apr 21 15:46:05 volumio-aly volumio[14434]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller
Apr 21 15:46:05 volumio-aly volumio[14434]: info: Starting UDEV Watcher for CD
Apr 21 15:46:05 volumio-aly volumio[14434]: info: Detecting CD presence with UDEV
Apr 21 15:46:05 volumio-aly volumio[14434]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices
Apr 21 15:46:05 volumio-aly volumio[14434]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs
Apr 21 15:46:05 volumio-aly volumio[14434]: info: Adding inputs REST Endpoints
Apr 21 15:46:05 volumio-aly volumio[14434]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs
Apr 21 15:46:05 volumio-aly volumio[14434]: info: Scanning Audio Inputs
Apr 21 15:46:05 volumio-aly volumio[14434]: info: Checking against Known Cards name
Apr 21 15:46:05 volumio-aly volumio[14434]: info: Adding Server instance for streaming
Apr 21 15:46:05 volumio-aly volumio[14434]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio
Apr 21 15:46:05 volumio-aly volumio[14434]: error: Hi Res Audio Failed Login: Missing Login Data
Apr 21 15:46:05 volumio-aly volumio[14434]: info: Adding HIGHRESAUDIO REST API Endpoints
Apr 21 15:46:05 volumio-aly volumio[14434]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio
Apr 21 15:46:05 volumio-aly volumio[14434]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal
Apr 21 15:46:05 volumio-aly volumio[14434]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz
Apr 21 15:46:05 volumio-aly volumio[14434]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect
Apr 21 15:46:05 volumio-aly volumio[14434]: info: Stopping AccessToken refresher cron
Apr 21 15:46:05 volumio-aly sudo[14794]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service
Apr 21 15:46:05 volumio-aly sudo[14794]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 21 15:46:05 volumio-aly systemd[1]: Stopping Volumio Tidal Connect Service...
Apr 21 15:46:05 volumio-aly systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM
Apr 21 15:46:05 volumio-aly systemd[1]: vtcs.service: Succeeded.
Apr 21 15:46:05 volumio-aly systemd[1]: Stopped Volumio Tidal Connect Service.
Apr 21 15:46:05 volumio-aly volumio[14434]: info: AccessToken refresher cron started
Apr 21 15:46:05 volumio-aly volumio[14434]: info: Stopping AccessToken refresher cron for QOBUZ
Apr 21 15:46:05 volumio-aly sudo[14794]: pam_unix(sudo:session): session closed for user root
Apr 21 15:46:05 volumio-aly volumio[14434]: info: AccessToken refresher cron started for QOBUZ
Apr 21 15:46:05 volumio-aly volumio[14434]: info: Adding TIDAL REST API Endpoints
Apr 21 15:46:05 volumio-aly volumio[14434]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal
Apr 21 15:46:05 volumio-aly volumio[14434]: info: Adding QOBUZ REST API Endpoints
Apr 21 15:46:05 volumio-aly volumio[14434]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz
Apr 21 15:46:05 volumio-aly volumio[14434]: info: Preload queue cleared
Apr 21 15:46:05 volumio-aly volumio[14434]: info: Bluetooth name changed to Volumio-ALY
Apr 21 15:46:05 volumio-aly volumio[14434]: info: MPD Permissions set
Apr 21 15:46:05 volumio-aly volumio[14434]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 21 15:46:05 volumio-aly volumio[14434]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 21 15:46:05 volumio-aly volumio[14434]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 21 15:46:05 volumio-aly volumio[14434]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 21 15:46:05 volumio-aly volumio[14434]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 21 15:46:05 volumio-aly volumio[14434]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 21 15:46:05 volumio-aly volumio[14434]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 21 15:46:05 volumio-aly volumio[14434]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 21 15:46:05 volumio-aly volumio[14434]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 21 15:46:05 volumio-aly volumio[14434]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 21 15:46:05 volumio-aly volumio[14434]: info: Starting Shairport Sync
Apr 21 15:46:05 volumio-aly sudo[14802]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Apr 21 15:46:05 volumio-aly sudo[14802]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 21 15:46:05 volumio-aly systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver...
Apr 21 15:46:05 volumio-aly systemd[1]: shairport-sync.service: Succeeded.
Apr 21 15:46:05 volumio-aly systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver.
Apr 21 15:46:05 volumio-aly systemd[1]: Started Shairport Sync - AirPlay Audio Receiver.
Apr 21 15:46:05 volumio-aly sudo[14802]: pam_unix(sudo:session): session closed for user root
Apr 21 15:46:05 volumio-aly volumio[14434]: info: Shairport-Sync Started
Apr 21 15:46:05 volumio-aly mpd[14780]: Apr 21 15:46 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg
Apr 21 15:46:05 volumio-aly systemd[1]: Started Music Player Daemon.
Apr 21 15:46:05 volumio-aly sudo[14775]: pam_unix(sudo:session): session closed for user root
Apr 21 15:46:05 volumio-aly volumio[14434]: error: updateQueue error: null
Apr 21 15:46:06 volumio-aly volumio[14434]: info: Successfully Added MyVolumio device
Apr 21 15:46:06 volumio-aly volumio[14434]: info: Updating MyVolumio device info
Apr 21 15:46:06 volumio-aly volumio[14434]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 21 15:46:06 volumio-aly volumio[14434]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 21 15:46:06 volumio-aly volumio[14434]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 21 15:46:06 volumio-aly volumio[14434]: info: Updating MyVolumio device info
Apr 21 15:46:06 volumio-aly volumio[14434]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 21 15:46:06 volumio-aly volumio[14434]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 21 15:46:06 volumio-aly volumio[14434]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 21 15:46:06 volumio-aly volumio[14434]: info: Successfully Updated MyVolumio device
Apr 21 15:46:07 volumio-aly volumio[14434]: info: Successfully Updated MyVolumio device
Apr 21 15:46:08 volumio-aly volumio[14434]: info: Successfully Added MyVolumio device
Apr 21 15:46:08 volumio-aly sudo[14814]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service
Apr 21 15:46:08 volumio-aly sudo[14814]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 21 15:46:08 volumio-aly sudo[14814]: pam_unix(sudo:session): session closed for user root
Apr 21 15:46:08 volumio-aly volumio[14434]: info: Volumio BT Module successfully started
Apr 21 15:46:08 volumio-aly volumio[14434]: info: TidalConnect service stoped!
Apr 21 15:46:08 volumio-aly volumio[14434]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect
Apr 21 15:46:08 volumio-aly volumio[14434]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect
Apr 21 15:46:08 volumio-aly sudo[14825]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service
Apr 21 15:46:08 volumio-aly sudo[14825]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 21 15:46:08 volumio-aly systemd[1]: Started Volumio Tidal Connect Service.
Apr 21 15:46:08 volumio-aly sudo[14825]: pam_unix(sudo:session): session closed for user root
Apr 21 15:46:08 volumio-aly volumio[14434]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri
Apr 21 15:46:09 volumio-aly volumio[14434]: info: Executing endpoint tc_getconfig
Apr 21 15:46:09 volumio-aly volumio[14434]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig
Apr 21 15:46:09 volumio-aly volumio[14434]: info: Preload queue cleared
Apr 21 15:46:09 volumio-aly vtcs[14827]: STARTING TidalConnect services, version: 1.3.0.19
Apr 21 15:46:09 volumio-aly vtcs[14827]: STARTED TidalConnect services.
Apr 21 15:46:09 volumio-aly volumio[14434]: info: Executing endpoint tc_connect
Apr 21 15:46:09 volumio-aly volumio[14434]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect
Apr 21 15:46:09 volumio-aly volumio[14434]: info: Connecting to TidalConnect
Apr 21 15:46:09 volumio-aly volumio[14434]: info: CoreCommandRouter::servicePushState
Apr 21 15:46:09 volumio-aly volumio[14434]: info: CoreStateMachine::pushState
Apr 21 15:46:09 volumio-aly volumio[14434]: info: CorePlayQueue::getTrack 0
Apr 21 15:46:09 volumio-aly volumio[14434]: info: CoreCommandRouter::volumioPushState
Apr 21 15:46:09 volumio-aly volumio[14434]: info: MRS: Pushing multiroomSync output update for this device
Apr 21 15:46:09 volumio-aly volumio[14434]: info: MRS: Pushing multiroomSync output
Apr 21 15:46:09 volumio-aly volumio[14434]: info: CorePlayQueue::getTrack 0
Apr 21 15:46:09 volumio-aly volumio[14434]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received tidalconnect
Apr 21 15:46:09 volumio-aly volumio[14434]: info: CoreCommandRouter::servicePushState
Apr 21 15:46:09 volumio-aly volumio[14434]: info: CoreStateMachine::pushState
Apr 21 15:46:09 volumio-aly volumio[14434]: info: CorePlayQueue::getTrack 0
Apr 21 15:46:09 volumio-aly volumio[14434]: info: CoreCommandRouter::volumioPushState
Apr 21 15:46:09 volumio-aly volumio[14434]: info: MRS: Pushing multiroomSync output update for this device
Apr 21 15:46:09 volumio-aly volumio[14434]: info: MRS: Pushing multiroomSync output
Apr 21 15:46:09 volumio-aly volumio[14434]: info: CorePlayQueue::getTrack 0
Apr 21 15:46:09 volumio-aly volumio[14434]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received tidalconnect
Apr 21 15:46:09 volumio-aly volumio[14434]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100
Apr 21 15:46:09 volumio-aly volumio[14434]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100
Apr 21 15:46:09 volumio-aly sudo[14851]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service
Apr 21 15:46:09 volumio-aly sudo[14851]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 21 15:46:09 volumio-aly autossh[2167]: received signal to exit (15)
Apr 21 15:46:09 volumio-aly systemd[1]: Stopping MyVolumio SSH Tunnel...
Apr 21 15:46:09 volumio-aly systemd[1]: sshtunnel.service: Main process exited, code=killed, status=15/TERM
Apr 21 15:46:09 volumio-aly systemd[1]: sshtunnel.service: Succeeded.
Apr 21 15:46:09 volumio-aly systemd[1]: Stopped MyVolumio SSH Tunnel.
Apr 21 15:46:09 volumio-aly volumio[14434]: ------------------------------------ BT MESSAGE: BT STATUS: running
Apr 21 15:46:09 volumio-aly volumio[14434]: ------------------------------------ BT MESSAGE: BT STATUS: waiting
Apr 21 15:46:09 volumio-aly systemd[1]: Started MyVolumio SSH Tunnel.
Apr 21 15:46:09 volumio-aly sudo[14851]: pam_unix(sudo:session): session closed for user root
Apr 21 15:46:09 volumio-aly autossh[14855]: port set to 0, monitoring disabled
Apr 21 15:46:09 volumio-aly autossh[14855]: starting ssh (count 1)
Apr 21 15:46:09 volumio-aly volumio[14434]: info: Remote SSH Started
Apr 21 15:46:09 volumio-aly autossh[14855]: ssh child pid is 14858
Apr 21 15:46:09 volumio-aly volumio[14434]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 6
Apr 21 15:46:10 volumio-aly volumio[14434]: info: CoreCommandRouter::volumioGetState
Apr 21 15:46:10 volumio-aly volumio[14434]: info: CorePlayQueue::getTrack 0
Apr 21 15:46:10 volumio-aly volumio[14434]: info: MRS: Getting audio outputs on start
Apr 21 15:46:10 volumio-aly volumio[14434]: info: MRS: Requesting all other devices output
Apr 21 15:46:11 volumio-aly volumio[14434]: info: AutoStart - Plugin is starting
Apr 21 15:46:11 volumio-aly volumio[14434]: info: CoreCommandRouter::volumioGetQueue
Apr 21 15:46:11 volumio-aly volumio[14434]: info: CoreStateMachine::getQueue
Apr 21 15:46:11 volumio-aly volumio[14434]: info: CorePlayQueue::getQueue
Apr 21 15:46:11 volumio-aly volumio[14434]: info: AutoStart - start playing
Apr 21 15:46:11 volumio-aly volumio[14434]: info: AutoStart - start playing with no specific position
Apr 21 15:46:11 volumio-aly volumio[14434]: info: CoreCommandRouter::volumioPlay
Apr 21 15:46:11 volumio-aly volumio[14434]: info: CoreStateMachine::play index 0
Apr 21 15:46:11 volumio-aly volumio[14434]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 21 15:46:11 volumio-aly volumio[14434]: info: CoreStateMachine::stop
Apr 21 15:46:11 volumio-aly volumio[14434]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 21 15:46:11 volumio-aly volumio[14434]: info: CoreStateMachine::play index undefined
Apr 21 15:46:11 volumio-aly volumio[14434]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 21 15:46:11 volumio-aly volumio[14434]: info: CorePlayQueue::getTrack 0
Apr 21 15:46:11 volumio-aly volumio[14434]: info: CoreStateMachine::startPlaybackTimer
Apr 21 15:46:11 volumio-aly volumio[14434]: info: CorePlayQueue::getTrack 0
Apr 21 15:46:11 volumio-aly volumio[14434]: info: CoreCommandRouter::volumioGetVisibleSources
Apr 21 15:46:11 volumio-aly volumio[14434]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Apr 21 15:46:11 volumio-aly volumio[14434]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject
Apr 21 15:46:11 volumio-aly volumio[14434]: info: [1713707171830] ControllerWebradio::clearAddPlayTrack
Apr 21 15:46:11 volumio-aly volumio[14434]: verbose: ControllerMpd::sendMpdCommand stop
Apr 21 15:46:11 volumio-aly volumio[14434]: info: sendMpdCommand stop took 0 milliseconds
Apr 21 15:46:11 volumio-aly volumio[14434]: verbose: ControllerMpd::sendMpdCommand clear
Apr 21 15:46:11 volumio-aly volumio[14434]: info:
Apr 21 15:46:11 volumio-aly volumio[14434]: ---------------------------- MPD announces system playlist update
Apr 21 15:46:11 volumio-aly volumio[14434]: info: Ignoring MPD Status Update
Apr 21 15:46:11 volumio-aly volumio[14434]: info: sendMpdCommand clear took 0 milliseconds
Apr 21 15:46:11 volumio-aly volumio[14434]: verbose: ControllerMpd::sendMpdCommand load "https://stream10.xdevel.com/audio17s976748-2218/stream/icecast.audio"
Apr 21 15:46:11 volumio-aly volumio[14434]: error: updateQueue error: null
Apr 21 15:46:11 volumio-aly volumio[14434]: info: ------------------------------ 1ms
Apr 21 15:46:11 volumio-aly volumio[14434]: info: TidalConnect service started!
Apr 21 15:46:11 volumio-aly volumio[14434]: info: BOOT COMPLETED
Apr 21 15:46:11 volumio-aly volumio[14434]: [Metrics] CommandRouter: 51s 876.92ms
Apr 21 15:46:11 volumio-aly volumio[14434]: info: CoreCommandRouter::volumiosetStartupVolume
Apr 21 15:46:11 volumio-aly volumio[14434]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 21 15:46:11 volumio-aly volumio[14434]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 21 15:46:11 volumio-aly volumio[14434]: info: CoreCommandRouter::Close All Modals sent
Apr 21 15:46:11 volumio-aly volumio[14434]: info: CoreCommandRouter::Close All Modals sent
Apr 21 15:46:12 volumio-aly volumio[14434]: verbose: ControllerMpd::sendMpdCommand add "https://stream10.xdevel.com/audio17s976748-2218/stream/icecast.audio"
Apr 21 15:46:12 volumio-aly volumio[14434]: info:
Apr 21 15:46:12 volumio-aly volumio[14434]: ---------------------------- MPD announces system playlist update
Apr 21 15:46:12 volumio-aly volumio[14434]: info: Ignoring MPD Status Update
Apr 21 15:46:12 volumio-aly volumio[14434]: info: sendMpdCommand add "https://stream10.xdevel.com/audio17s976748-2218/stream/icecast.audio" took 0 milliseconds
Apr 21 15:46:12 volumio-aly volumio[14434]: info: CoreStateMachine::setConsumeUpdateService mpd
Apr 21 15:46:12 volumio-aly volumio[14434]: verbose: ControllerMpd::sendMpdCommand play
Apr 21 15:46:12 volumio-aly volumio[14434]: info: ------------------------------ 2ms
Apr 21 15:46:12 volumio-aly volumio[14434]: info: sendMpdCommand play took 1 milliseconds
Apr 21 15:46:12 volumio-aly volumio[14434]: info:
Apr 21 15:46:12 volumio-aly volumio[14434]: ---------------------------- MPD announces state update: player
Apr 21 15:46:12 volumio-aly volumio[14434]: info: ControllerMpd::getState
Apr 21 15:46:12 volumio-aly volumio[14434]: verbose: ControllerMpd::sendMpdCommand status
Apr 21 15:46:12 volumio-aly volumio[14434]: verbose: ControllerMpd::sendMpdCommand clearerror
Apr 21 15:46:12 volumio-aly volumio[14434]: info: sendMpdCommand status took 7 milliseconds
Apr 21 15:46:12 volumio-aly volumio[14434]: verbose: ControllerMpd::parseState
Apr 21 15:46:12 volumio-aly volumio[14434]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 21 15:46:12 volumio-aly volumio[14434]: info:
Apr 21 15:46:12 volumio-aly volumio[14434]: ---------------------------- MPD announces state update: player
Apr 21 15:46:12 volumio-aly volumio[14434]: info: ControllerMpd::getState
Apr 21 15:46:12 volumio-aly volumio[14434]: verbose: ControllerMpd::sendMpdCommand status
Apr 21 15:46:12 volumio-aly volumio[14434]: info: sendMpdCommand clearerror took 2 milliseconds
Apr 21 15:46:12 volumio-aly volumio[14434]: info: sendMpdCommand playlistinfo took 1 milliseconds
Apr 21 15:46:12 volumio-aly volumio[14434]: verbose: ControllerMpd::parseTrackInfo
Apr 21 15:46:12 volumio-aly volumio[14434]: info: ControllerMpd::pushState
Apr 21 15:46:12 volumio-aly volumio[14434]: info: CoreCommandRouter::servicePushState
Apr 21 15:46:12 volumio-aly volumio[14434]: info: CorePlayQueue::getTrack 0
Apr 21 15:46:12 volumio-aly volumio[14434]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"icecast.audio","artist":null,"album":null,"uri":"https://stream10.xdevel.com/audio17s976748-2218/stream/icecast.audio","trackType":"audio"}
Apr 21 15:46:12 volumio-aly volumio[14434]: verbose: CURRENT POSITION 0
Apr 21 15:46:12 volumio-aly volumio[14434]: info: CoreStateMachine::syncState stateService pause
Apr 21 15:46:12 volumio-aly volumio[14434]: info: CoreStateMachine::syncState currentStatus stop
Apr 21 15:46:12 volumio-aly volumio[14434]: info: CoreStateMachine::pushState
Apr 21 15:46:12 volumio-aly volumio[14434]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 21 15:46:12 volumio-aly volumio[14434]: info: CoreCommandRouter::volumioPushState
Apr 21 15:46:12 volumio-aly volumio[14434]: info: MRS: Pushing multiroomSync output update for this device
Apr 21 15:46:12 volumio-aly volumio[14434]: info: MRS: Pushing multiroomSync output
Apr 21 15:46:12 volumio-aly volumio[14434]: info: ------------------------------ 18ms
Apr 21 15:46:12 volumio-aly volumio[14434]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100
Apr 21 15:46:12 volumio-aly volumio[14434]: info: sendMpdCommand status took 14 milliseconds
Apr 21 15:46:12 volumio-aly volumio[14434]: verbose: ControllerMpd::parseState
Apr 21 15:46:12 volumio-aly volumio[14434]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 21 15:46:12 volumio-aly volumio[14434]: info: sendMpdCommand playlistinfo took 0 milliseconds
Apr 21 15:46:12 volumio-aly volumio[14434]: verbose: ControllerMpd::parseTrackInfo
Apr 21 15:46:12 volumio-aly volumio[14434]: info: ControllerMpd::pushState
Apr 21 15:46:12 volumio-aly volumio[14434]: info: CoreCommandRouter::servicePushState
Apr 21 15:46:12 volumio-aly volumio[14434]: info: CorePlayQueue::getTrack 0
Apr 21 15:46:12 volumio-aly volumio[14434]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"icecast.audio","artist":null,"album":null,"uri":"https://stream10.xdevel.com/audio17s976748-2218/stream/icecast.audio","trackType":"audio"}
Apr 21 15:46:12 volumio-aly volumio[14434]: verbose: CURRENT POSITION 0
Apr 21 15:46:12 volumio-aly volumio[14434]: info: CoreStateMachine::syncState stateService pause
Apr 21 15:46:12 volumio-aly volumio[14434]: info: CoreStateMachine::syncState currentStatus stop
Apr 21 15:46:12 volumio-aly volumio[14434]: info: CoreStateMachine::pushState
Apr 21 15:46:12 volumio-aly volumio[14434]: info: CoreCommandRouter::volumioPushState
Apr 21 15:46:12 volumio-aly volumio[14434]: info: MRS: Pushing multiroomSync output update for this device
Apr 21 15:46:12 volumio-aly volumio[14434]: info: MRS: Pushing multiroomSync output
Apr 21 15:46:12 volumio-aly volumio[14434]: info: ------------------------------ 22ms
Apr 21 15:46:12 volumio-aly volumio[14434]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100
Apr 21 15:46:12 volumio-aly volumio[14434]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.1eGvOb4iLhedH1wl5o2or8pkmyl1.c1429040b08a57ff80e27bd160b3377e.state.status'
Apr 21 15:46:12 volumio-aly volumiossh-tunnel[14853]: Warning: Permanently added '[eu4.myvolumio.org]:2222,[167.172.103.77]:2222' (RSA) to the list of known hosts.
Apr 21 15:46:12 volumio-aly volumio[14434]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable
Apr 21 15:46:12 volumio-aly volumio[14434]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
Apr 21 15:46:12 volumio-aly volumio[14434]: xcb_connection_has_error() returned true
Apr 21 15:46:12 volumio-aly volumio[14434]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect
Apr 21 15:46:15 volumio-aly volumio[14434]: info: CoreCommandRouter::executeOnPlugin: radio_paradise , handleBrowseUri
Apr 21 15:46:15 volumio-aly volumio[14434]: info: Preload queue cleared
Apr 21 15:46:16 volumio-aly volumio[14434]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Apr 21 15:46:16 volumio-aly volumio[14434]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 7
Apr 21 15:46:16 volumio-aly volumio[14434]: info: CoreCommandRouter::volumioGetState
Apr 21 15:46:17 volumio-aly volumio[14434]: info: CoreCommandRouter::executeOnPlugin: motherearthradio , handleBrowseUri
Apr 21 15:46:17 volumio-aly volumio[14434]: info: Preload queue cleared
Apr 21 15:46:21 volumio-aly volumio[14434]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 21 15:46:21 volumio-aly volumio[14434]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 21 15:46:21 volumio-aly volumio[14434]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam
Apr 21 15:46:21 volumio-aly volumio[14434]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam
Apr 21 15:46:21 volumio-aly volumio[14434]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject
Apr 21 15:46:21 volumio-aly volumio[14434]: info: CoreCommandRouter::volumioGetBrowseSources
Apr 21 15:46:21 volumio-aly volumio[14434]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Apr 21 15:46:21 volumio-aly volumio[14434]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Apr 21 15:46:21 volumio-aly volumio[14434]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 21 15:46:21 volumio-aly volumio[14434]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Apr 21 15:46:21 volumio-aly volumio-remote-updater[601]: Test mode enabled
Apr 21 15:46:21 volumio-aly volumio-remote-updater[601]: No alpha test mode
Apr 21 15:46:21 volumio-aly volumio[14434]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled
Apr 21 15:46:22 volumio-aly volumio[14434]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"FIXES
\n\n- Fix for mpd crashing with wrongly formatted cue files
\n- Restore install-to-disk on x86 and ThinkerBoard
\n- Fix for Waveshare 11.9inch DSI Display touch panel on RPi
\n- Fix for missing metadata on CD playback
\n- Fix for metadata with Classic and Modern UI
\n- Fix for factory-reset from USB on Motivo
\n- Fix for Volumio logo on boot on Motivo
\n- Various fixes for TIDAL Connect
\n
\nNEW ADDITIONS
\n\n- Bump MPD to 0.23.15-3
\n- Add support for Waveshare 4.0inch DSI Display on RPi
\n- Improvements for reducing boot time
\n- Add patched driver for ax88179 on RPi
\n- UNSTABLE ALPHA BUILD - DO NOT INSTALL
\n
\n","title":"Update v3.649","updateavailable":true}
Apr 21 15:46:22 volumio-aly volumio[14434]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache
Apr 21 15:46:24 volumio-aly sudo[14875]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Apr 21 15:46:24 volumio-aly sudo[14875]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 21 15:46:24 volumio-aly sudo[14875]: pam_unix(sudo:session): session closed for user root
Apr 21 15:46:24 volumio-aly sudo[14878]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Apr 21 15:46:24 volumio-aly sudo[14878]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 21 15:46:24 volumio-aly sudo[14878]: pam_unix(sudo:session): session closed for user root
Apr 21 15:46:24 volumio-aly volumio[14434]: verbose: New Socket.io Connection to 192.168.6.24 from 192.168.6.30 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/124.0.0.0 Safari/537.36 Total Clients: 8
Apr 21 15:46:24 volumio-aly volumio[14434]: info: CoreCommandRouter::volumioGetState
Apr 21 15:46:24 volumio-aly volumio[14434]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom
Apr 21 15:46:24 volumio-aly volumio[14434]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom
Apr 21 15:46:24 volumio-aly volumio[14434]: info: CoreCommandRouter::volumioGetVisibleSources
Apr 21 15:46:24 volumio-aly volumio[14434]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Apr 21 15:46:24 volumio-aly volumio[14434]: info: Listing playlists
Apr 21 15:46:24 volumio-aly volumio[14434]: info: CoreCommandRouter::volumioGetQueue
Apr 21 15:46:24 volumio-aly volumio[14434]: info: CoreStateMachine::getQueue
Apr 21 15:46:24 volumio-aly volumio[14434]: info: CorePlayQueue::getQueue
Apr 21 15:46:26 volumio-aly volumio[14434]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Apr 21 15:46:45 volumio-aly sudo[14929]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Apr 21 15:46:45 volumio-aly sudo[14929]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 21 15:46:45 volumio-aly sudo[14929]: pam_unix(sudo:session): session closed for user root
Apr 21 15:46:45 volumio-aly sudo[14932]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Apr 21 15:46:45 volumio-aly sudo[14932]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 21 15:46:45 volumio-aly sudo[14932]: pam_unix(sudo:session): session closed for user root
Apr 21 15:46:45 volumio-aly volumio[14434]: verbose: New Socket.io Connection to 192.168.6.24 from 192.168.6.30 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/124.0.0.0 Safari/537.36 Total Clients: 8
Apr 21 15:46:45 volumio-aly volumio[14434]: info: CoreCommandRouter::volumioGetState
Apr 21 15:46:45 volumio-aly volumio[14434]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom
Apr 21 15:46:45 volumio-aly volumio[14434]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom
Apr 21 15:46:45 volumio-aly volumio[14434]: info: CoreCommandRouter::volumioGetVisibleSources
Apr 21 15:46:45 volumio-aly volumio[14434]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Apr 21 15:46:45 volumio-aly volumio[14434]: info: Listing playlists
Apr 21 15:46:45 volumio-aly volumio[14434]: info: CoreCommandRouter::volumioGetQueue
Apr 21 15:46:45 volumio-aly volumio[14434]: info: CoreStateMachine::getQueue
Apr 21 15:46:45 volumio-aly volumio[14434]: info: CorePlayQueue::getQueue
Apr 21 15:46:47 volumio-aly volumio[14434]: info: CoreCommandRouter::getUIConfigOnPlugin
Apr 21 15:46:47 volumio-aly volumio[14434]: info: CoreCommandRouter::getUIConfigOnPlugin
Apr 21 15:46:47 volumio-aly volumio[14434]: info: Retrieving Cloud Streaming UI
Apr 21 15:46:47 volumio-aly volumio[14434]: info: Getting Tidal Cloud Configuration
Apr 21 15:46:47 volumio-aly volumio[14434]: info: CoreCommandRouter::getUIConfigOnPlugin
Apr 21 15:46:47 volumio-aly volumio[14434]: info: Getting Qobuz Cloud Configuration
Apr 21 15:46:47 volumio-aly volumio[14434]: info: Asking plugin for UI Config
Apr 21 15:46:47 volumio-aly volumio[14434]: info: CoreCommandRouter::getUIConfigOnPlugin
Apr 21 15:46:47 volumio-aly volumio[14434]: info: Getting Spotify Cloud Configuration
Apr 21 15:46:47 volumio-aly volumio[14434]: info: Asking plugin for UI Config
Apr 21 15:46:47 volumio-aly volumio[14434]: info: CoreCommandRouter::getUIConfigOnPlugin
Apr 21 15:46:47 volumio-aly volumio[14434]: info: Saving Spotify Acccount
Apr 21 15:46:47 volumio-aly volumio[14434]: info: Got it
Apr 21 15:46:47 volumio-aly volumio[14434]: error: Could not retrieve Spotify Config from plugin Spotify: no section found
Apr 21 15:46:47 volumio-aly volumio[14434]: info: Got Tidal Cloud Configuration
Apr 21 15:46:47 volumio-aly volumio[14434]: info: Got it
Apr 21 15:46:47 volumio-aly volumio[14434]: info: CoreCommandRouter::getUIConfigOnPlugin
Apr 21 15:46:47 volumio-aly volumio[14434]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus
Apr 21 15:46:47 volumio-aly volumio[14434]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam
Apr 21 15:46:47 volumio-aly volumio[14434]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam
Apr 21 15:46:47 volumio-aly volumio[14434]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam
Apr 21 15:46:47 volumio-aly volumio[14434]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Apr 21 15:46:47 volumio-aly volumio[14434]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Apr 21 15:46:47 volumio-aly volumio[14434]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Apr 21 15:46:47 volumio-aly volumio[14434]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Apr 21 15:46:47 volumio-aly volumio[14434]: info: CoreCommandRouter::volumioGetBrowseSources
Apr 21 15:46:47 volumio-aly volumio[14434]: info: CoreCommandRouter::volumioGetBrowseSources
Apr 21 15:46:47 volumio-aly volumio[14434]: info: CoreCommandRouter::volumioGetBrowseSources
Apr 21 15:46:47 volumio-aly volumio[14434]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Apr 21 15:46:47 volumio-aly volumio[14434]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares
Apr 21 15:46:51 volumio-aly volumio[14434]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Apr 21 15:46:54 volumio-aly volumio[14434]: info: CoreCommandRouter::executeOnPlugin: networkfs , deleteShare
Apr 21 15:46:55 volumio-aly volumio[14434]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares
Apr 21 15:46:55 volumio-aly volumio[14434]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Apr 21 15:46:56 volumio-aly systemd[1]: /lib/systemd/system/rpc-statd.service:13: PIDFile= references path below legacy directory /var/run/, updating /var/run/rpc.statd.pid → /run/rpc.statd.pid; please update the unit file accordingly.
Apr 21 15:46:56 volumio-aly systemd[1]: Reached target Host and Network Name Lookups.
Apr 21 15:46:56 volumio-aly systemd[1]: Starting Preprocess NFS configuration...
Apr 21 15:46:56 volumio-aly systemd[1]: nfs-config.service: Succeeded.
Apr 21 15:46:56 volumio-aly systemd[1]: Started Preprocess NFS configuration.
Apr 21 15:46:56 volumio-aly volumio[14434]: ------------------------------------ BT MESSAGE: BT STATUS: running
Apr 21 15:46:56 volumio-aly systemd[1]: Starting Notify NFS peers of a restart...
Apr 21 15:46:56 volumio-aly systemd[1]: Starting NFS status monitor for NFSv2/3 locking....
Apr 21 15:46:56 volumio-aly sm-notify[14945]: Version 1.3.3 starting
Apr 21 15:46:56 volumio-aly volumio[14434]: ------------------------------------ BT MESSAGE: BT STATUS: running
Apr 21 15:46:56 volumio-aly systemd[1]: rpc-statd-notify.service: Succeeded.
Apr 21 15:46:56 volumio-aly systemd[1]: Started Notify NFS peers of a restart.
Apr 21 15:46:57 volumio-aly rpc.statd[14947]: Version 1.3.3 starting
Apr 21 15:46:57 volumio-aly rpc.statd[14947]: Flags: TI-RPC
Apr 21 15:46:57 volumio-aly systemd[1]: Started NFS status monitor for NFSv2/3 locking..
Apr 21 15:46:59 volumio-aly volumio[14434]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Apr 21 15:47:03 volumio-aly volumio[14434]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Apr 21 15:47:07 volumio-aly volumio[14434]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Apr 21 15:47:11 volumio-aly volumio[14434]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Apr 21 15:47:15 volumio-aly volumio[14434]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Apr 21 15:47:19 volumio-aly volumio[14434]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Apr 21 15:47:23 volumio-aly volumio[14434]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Apr 21 15:47:24 volumio-aly sudo[14472]: pam_unix(sudo:session): session closed for user root
Apr 21 15:47:24 volumio-aly volumio[14434]: info: Cannot mount NAS testlib at system boot, trial number 1 ,retrying in 5 seconds
Apr 21 15:47:29 volumio-aly volumio[14434]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Apr 21 15:47:29 volumio-aly volumio[14434]: TypeError: Cannot read property 'replace' of undefined
Apr 21 15:47:29 volumio-aly volumio[14434]: at ControllerNetworkfs.mountShare (/volumio/app/plugins/system_controller/networkfs/index.js:202:28)
Apr 21 15:47:29 volumio-aly volumio[14434]: at Timeout._onTimeout (/volumio/app/plugins/system_controller/networkfs/index.js:255:20)
Apr 21 15:47:29 volumio-aly volumio[14434]: at listOnTimeout (internal/timers.js:554:17)
Apr 21 15:47:29 volumio-aly volumio[14434]: at processTimers (internal/timers.js:497:7)
Apr 21 15:47:29 volumio-aly volumio[14434]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Apr 21 15:47:29 volumio-aly sudo[15000]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-21 15:46
Apr 21 15:47:29 volumio-aly sudo[15000]: pam_unix(sudo:session): session opened for user root by (uid=0)
PRETTY_NAME="Raspbian GNU/Linux 10 (buster)"
NAME="Raspbian GNU/Linux"
VERSION_ID="10"
VERSION="10 (buster)"
VERSION_CODENAME=buster
ID=raspbian
ID_LIKE=debian
HOME_URL="http://www.raspbian.org/"
SUPPORT_URL="http://www.raspbian.org/RaspbianForums"
BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs"
VOLUMIO_BUILD_VERSION="7f9f1bf001add7a8b19a4516e3c74ef598b2730d"
VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71"
VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1"
VOLUMIO_BE_VERSION="512433c3e91cdaed7ecd2808b21adeffc2137d7f"
VOLUMIO_ARCH="arm"
VOLUMIO_VARIANT="volumio"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Wed 21 Feb 2024 09:37:22 PM CET"
VOLUMIO_VERSION="3.631"
VOLUMIO_HARDWARE="pi"
VOLUMIO_DEVICENAME="Raspberry Pi"
VOLUMIO_HASH="fdd9dd1169c06c1ad0a30ddd24c91b6b"