-- Logs begin at Thu 2019-02-14 10:11:58 UTC, end at Tue 2025-05-20 16:35:07 UTC. -- May 20 16:34:00 volumio volumio[977]: info: MyVolumio Plan changed: premium May 20 16:34:00 volumio volumio[977]: info: [MyVolumio PluginManager] Subscribed plan changed to premium May 20 16:34:00 volumio volumio[977]: info: Removing browser output: myVolumio user plan is not superstar May 20 16:34:00 volumio volumio[977]: info: Removing audio output: May 20 16:34:00 volumio volumio[977]: info: MYVOLUMIO: Adding device May 20 16:34:00 volumio volumio[977]: info: MYVOLUMIO: Evaluating Server May 20 16:34:00 volumio volumio[977]: info: Remote config written successfully May 20 16:34:00 volumio volumio[977]: info: Starting Tunnel 1 May 20 16:34:00 volumio volumio[977]: info: Starting Tunnel Connection Checker May 20 16:34:00 volumio volumio[977]: info: Completed starting MyVolumio Plugin May 20 16:34:00 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 20 16:34:00 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 20 16:34:00 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 20 16:34:00 volumio volumio[977]: info: MYVolumio Device enabled May 20 16:34:00 volumio volumio[977]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... May 20 16:34:00 volumio volumio[977]: info: MyVolumio status changed May 20 16:34:00 volumio volumio[977]: info: Streaming services startup May 20 16:34:00 volumio volumio[977]: info: Starting Streaming Daemon May 20 16:34:00 volumio volumio[977]: info: Setting Geolocation for MyVolumio to eu4 May 20 16:34:00 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 20 16:34:00 volumio sudo[1682]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 20 16:34:00 volumio sudo[1682]: pam_unix(sudo:session): session opened for user root by (uid=0) May 20 16:34:00 volumio sudo[1682]: pam_unix(sudo:session): session closed for user root May 20 16:34:00 volumio volumio[977]: error: Cannot start Volumio Streaming Daemon May 20 16:34:01 volumio volumio[977]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service May 20 16:34:01 volumio volumio[977]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. May 20 16:34:01 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid May 20 16:34:01 volumio volumio[977]: error: [MyVolumio PluginManager] Cache data is invalid! May 20 16:34:01 volumio volumio-remote-updater[636]: No test mode May 20 16:34:01 volumio volumio-remote-updater[636]: No alpha test mode May 20 16:34:01 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled May 20 16:34:01 volumio volumio[977]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} May 20 16:34:01 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache May 20 16:34:01 volumio volumio[977]: info: Setting Geolocation for MyVolumio to eu4 May 20 16:34:01 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 20 16:34:01 volumio volumio[977]: info: Successfully Added MyVolumio device May 20 16:34:01 volumio volumio[977]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... May 20 16:34:01 volumio volumio[977]: info: [1747758841773] Starting BluetoothController May 20 16:34:01 volumio volumio[977]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... May 20 16:34:02 volumio volumio[977]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom May 20 16:34:02 volumio sudo[1686]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom May 20 16:34:02 volumio sudo[1686]: pam_unix(sudo:session): session opened for user root by (uid=0) May 20 16:34:02 volumio sudo[1686]: pam_unix(sudo:session): session closed for user root May 20 16:34:02 volumio volumio[977]: info: MRS: MultiRoom plugin initialized May 20 16:34:02 volumio volumio[977]: info: MRS: STOPPING SNAPCLIENT May 20 16:34:02 volumio volumio[977]: info: MRS: Snap server stop May 20 16:34:02 volumio sudo[1702]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient May 20 16:34:02 volumio sudo[1702]: pam_unix(sudo:session): session opened for user root by (uid=0) May 20 16:34:03 volumio volumio[977]: info: MRS: STOPPING volumioStreaming May 20 16:34:03 volumio sudo[1702]: pam_unix(sudo:session): session closed for user root May 20 16:34:03 volumio sudo[1705]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver May 20 16:34:03 volumio sudo[1705]: pam_unix(sudo:session): session opened for user root by (uid=0) May 20 16:34:03 volumio sudo[1705]: pam_unix(sudo:session): session closed for user root May 20 16:34:03 volumio sudo[1708]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming May 20 16:34:03 volumio sudo[1708]: pam_unix(sudo:session): session opened for user root by (uid=0) May 20 16:34:03 volumio volumio[977]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... May 20 16:34:03 volumio sudo[1708]: pam_unix(sudo:session): session closed for user root May 20 16:34:03 volumio sudo[1711]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* May 20 16:34:03 volumio sudo[1711]: pam_unix(sudo:session): session opened for user root by (uid=0) May 20 16:34:03 volumio sudo[1711]: pam_unix(sudo:session): session closed for user root May 20 16:34:03 volumio volumio[977]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... May 20 16:34:03 volumio volumio[977]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... May 20 16:34:03 volumio volumio[977]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... May 20 16:34:03 volumio volumio[977]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... May 20 16:34:03 volumio volumio[977]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio May 20 16:34:03 volumio volumio[977]: info: [MyVolumio PluginManager] Loading plugin "tidal"... May 20 16:34:03 volumio volumio[977]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... May 20 16:34:03 volumio volumio[977]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... May 20 16:34:03 volumio volumio[977]: info: [MyVolumio PluginManager] Loading plugin "qobuzconnect"... May 20 16:34:03 volumio volumio[977]: info: Preparing to generate the ALSA configuration file May 20 16:34:03 volumio volumio[977]: info: Updating MyVolumio device info May 20 16:34:03 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 20 16:34:03 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 20 16:34:03 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 20 16:34:03 volumio volumio[977]: info: Updating MyVolumio device info May 20 16:34:03 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 20 16:34:03 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 20 16:34:03 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 20 16:34:03 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid May 20 16:34:03 volumio volumio[977]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf May 20 16:34:03 volumio volumio[977]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf May 20 16:34:03 volumio volumio[977]: info: Reading ALSA contributions from plugins. May 20 16:34:03 volumio volumio[977]: info: Successfully Added MyVolumio device May 20 16:34:03 volumio volumio[977]: info: MRS: Removed streaming files May 20 16:34:03 volumio volumio[977]: info: MRS: volumioStreaming STOPPED May 20 16:34:03 volumio volumio[977]: info: MRS: SNAPSERVER STOPPED May 20 16:34:03 volumio volumio[977]: info: MRS: SNAPCLIENT STOPPED May 20 16:34:03 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 20 16:34:03 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 20 16:34:03 volumio volumio[977]: info: Discovery: Getting this device information May 20 16:34:03 volumio volumio[977]: info: CoreCommandRouter::volumioGetState May 20 16:34:03 volumio volumio[977]: info: CorePlayQueue::getTrack 0 May 20 16:34:03 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 20 16:34:03 volumio volumio[977]: info: Asound.conf file written May 20 16:34:03 volumio sudo[1714]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf May 20 16:34:03 volumio sudo[1714]: pam_unix(sudo:session): session opened for user root by (uid=0) May 20 16:34:03 volumio sudo[1714]: pam_unix(sudo:session): session closed for user root May 20 16:34:03 volumio volumio[977]: info: Output device has changed, restarting MPD May 20 16:34:03 volumio sudo[1719]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 20 16:34:03 volumio sudo[1719]: pam_unix(sudo:session): session opened for user root by (uid=0) May 20 16:34:03 volumio sudo[1719]: pam_unix(sudo:session): session closed for user root May 20 16:34:03 volumio volumio[977]: info: Output device has changed, restarting Shairport Sync May 20 16:34:03 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 20 16:34:03 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 20 16:34:03 volumio sudo[1722]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 20 16:34:03 volumio sudo[1722]: pam_unix(sudo:session): session opened for user root by (uid=0) May 20 16:34:03 volumio systemd[1]: Stopping Music Player Daemon... May 20 16:34:03 volumio volumio[977]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 20 16:34:03 volumio volumio[977]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration May 20 16:34:03 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 20 16:34:03 volumio volumio[977]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth May 20 16:34:03 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 20 16:34:03 volumio systemd[1]: mpd.service: Succeeded. May 20 16:34:03 volumio systemd[1]: Stopped Music Player Daemon. May 20 16:34:03 volumio volumio[977]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom May 20 16:34:03 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus May 20 16:34:03 volumio volumio[977]: info: MRS: Pushing multiroomSync output for this device May 20 16:34:03 volumio volumio[977]: info: MRS: Pushing multiroomSync output May 20 16:34:03 volumio volumio[977]: info: Adding audio output: May 20 16:34:03 volumio volumio[977]: info: Adding audio output: May 20 16:34:03 volumio sudo[1731]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hciconfig hci0 name Volumio May 20 16:34:03 volumio sudo[1731]: pam_unix(sudo:session): session opened for user root by (uid=0) May 20 16:34:03 volumio volumio[977]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration May 20 16:34:03 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 20 16:34:03 volumio volumio[977]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio May 20 16:34:03 volumio volumio[977]: info: Adding METAVOLUMIO REST API Endpoints May 20 16:34:03 volumio volumio[977]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio May 20 16:34:03 volumio volumio[977]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio May 20 16:34:03 volumio volumio[977]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio May 20 16:34:03 volumio volumio[977]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio May 20 16:34:03 volumio volumio[977]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui May 20 16:34:03 volumio volumio[977]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller May 20 16:34:03 volumio volumio[977]: info: Preparing CD Folders May 20 16:34:03 volumio volumio[977]: info: Adding CD REST API Endpoints May 20 16:34:03 volumio volumio[977]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller May 20 16:34:03 volumio volumio[977]: info: Starting UDEV Watcher for CD May 20 16:34:03 volumio volumio[977]: info: Detecting CD presence with UDEV May 20 16:34:03 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices May 20 16:34:03 volumio sudo[1731]: pam_unix(sudo:session): session closed for user root May 20 16:34:03 volumio systemd[1]: Starting Music Player Daemon... May 20 16:34:03 volumio volumio[977]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs May 20 16:34:03 volumio volumio[977]: info: Adding inputs REST Endpoints May 20 16:34:03 volumio volumio[977]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs May 20 16:34:03 volumio volumio[977]: info: Scanning Audio Inputs May 20 16:34:03 volumio volumio[977]: info: Checking against Known Cards name May 20 16:34:03 volumio volumio[977]: info: Adding Server instance for streaming May 20 16:34:03 volumio volumio[977]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio May 20 16:34:03 volumio volumio[977]: error: Hi Res Audio Failed Login: Missing Login Data May 20 16:34:03 volumio volumio[977]: info: Adding HIGHRESAUDIO REST API Endpoints May 20 16:34:03 volumio volumio[977]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio May 20 16:34:03 volumio volumio[977]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal May 20 16:34:03 volumio volumio[977]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz May 20 16:34:03 volumio volumio[977]: info: Refreshing QOBUZ token May 20 16:34:03 volumio volumio[977]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect May 20 16:34:03 volumio volumio[977]: info: [MyVolumio PluginManager] Plugin tidalconnect disabled by user. Not starting May 20 16:34:03 volumio sudo[1730]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 20 16:34:03 volumio volumio[977]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuzconnect May 20 16:34:03 volumio sudo[1730]: pam_unix(sudo:session): session opened for user root by (uid=0) May 20 16:34:03 volumio volumio[977]: info: Adding qc_getconfig REST Endpoint for plugin: music_service/qobuzconnect May 20 16:34:03 volumio sudo[1730]: pam_unix(sudo:session): session closed for user root May 20 16:34:03 volumio volumio[977]: info: QobuzConnect: Starting Qobuz Connect socket and service May 20 16:34:03 volumio sudo[1744]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service May 20 16:34:03 volumio volumio[977]: info: QobuzConnect: Opened /tmp/qbz-connect.socket socket, listening for connections May 20 16:34:03 volumio sudo[1744]: pam_unix(sudo:session): session opened for user root by (uid=0) May 20 16:34:03 volumio volumio[977]: info: Stopping AccessToken refresher cron May 20 16:34:03 volumio sudo[1744]: pam_unix(sudo:session): session closed for user root May 20 16:34:03 volumio volumio[977]: info: AccessToken refresher cron started May 20 16:34:03 volumio volumio[977]: info: Adding TIDAL REST API Endpoints May 20 16:34:03 volumio volumio[977]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal May 20 16:34:03 volumio sudo[1746]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service May 20 16:34:03 volumio sudo[1746]: pam_unix(sudo:session): session opened for user root by (uid=0) May 20 16:34:03 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 20 16:34:03 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 20 16:34:03 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam May 20 16:34:03 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam May 20 16:34:03 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject May 20 16:34:03 volumio volumio[977]: info: CoreCommandRouter::volumioGetBrowseSources May 20 16:34:03 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 20 16:34:03 volumio systemd[1]: Started Volumio Qobuz Connect Service. May 20 16:34:03 volumio volumio[977]: info: Successfully Updated MyVolumio device May 20 16:34:03 volumio volumio[977]: info: Successfully Updated MyVolumio device May 20 16:34:03 volumio sudo[1746]: pam_unix(sudo:session): session closed for user root May 20 16:34:04 volumio volumio[977]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined May 20 16:34:04 volumio volumio[977]: info: Bluetooth name changed to Volumio May 20 16:34:04 volumio volumio[977]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined May 20 16:34:04 volumio volumio[977]: info: MPD Permissions set May 20 16:34:04 volumio volumio[977]: verbose: New Socket.io Connection to 192.168.1.153:3000 from 192.168.1.54 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 5 May 20 16:34:04 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 20 16:34:04 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 20 16:34:04 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 20 16:34:04 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 20 16:34:04 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 20 16:34:04 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 20 16:34:04 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 20 16:34:04 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 20 16:34:04 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 20 16:34:04 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 20 16:34:04 volumio volumio[977]: info: CoreCommandRouter::volumioGetState May 20 16:34:04 volumio volumio[977]: info: CorePlayQueue::getTrack 0 May 20 16:34:04 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 20 16:34:04 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 20 16:34:04 volumio volumio[977]: info: Executing endpoint qc_getconfig May 20 16:34:04 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig May 20 16:34:04 volumio qobuz-connect[1748]: 20250520 16:34:04.152 [1748.1748] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket May 20 16:34:04 volumio volumio[977]: info: Starting Shairport Sync May 20 16:34:04 volumio sudo[1756]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=admin,password=enrico27!,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.200/Music /mnt/NAS/Music May 20 16:34:04 volumio sudo[1756]: pam_unix(sudo:session): session opened for user root by (uid=0) May 20 16:34:04 volumio volumio[977]: info: mpdhttpout ---Configuration successfully replaced in mpd.conf. May 20 16:34:04 volumio volumio[977]: info: CoreCommandRouter::volumioGetState May 20 16:34:04 volumio volumio[977]: info: CorePlayQueue::getTrack 0 May 20 16:34:04 volumio volumio[977]: info: CoreCommandRouter::volumioPause May 20 16:34:04 volumio volumio[977]: info: CoreStateMachine::pause May 20 16:34:04 volumio volumio[977]: info: mpdhttpout --- Volumio set on pause May 20 16:34:04 volumio volumio[977]: info: MRS: Found cast device: BRAVIA-4K-UR3-3e50013ea60509b4bc03cbf83921be2b May 20 16:34:04 volumio volumio[977]: info: Adding audio output: May 20 16:34:04 volumio sudo[1761]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 20 16:34:04 volumio sudo[1761]: pam_unix(sudo:session): session opened for user root by (uid=0) May 20 16:34:04 volumio qobuz-connect[1748]: 20250520 16:34:04.256 [1748.1748] INFO VolumeManager: [0x89a280]: Setting new playback volume: 75 May 20 16:34:04 volumio qobuz-connect[1748]: 20250520 16:34:04.256 [1748.1748] INFO VolumeManager: [0x89a280]: Setting new mute state: 0 May 20 16:34:04 volumio qobuz-connect[1748]: 20250520 16:34:04.256 [1748.1748] INFO QobuzConnect: [0x89ac50]: Client initialized! May 20 16:34:04 volumio qobuz-connect[1748]: 20250520 16:34:04.256 [1748.1748] INFO SampleApp: Starting Avahi advertising, name: Volumio, service name: _qobuz-connect._tcp May 20 16:34:04 volumio volumio[977]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] May 20 16:34:04 volumio volumio[977]: info: QobuzConnect: QOBUZ Connect daemon connected May 20 16:34:04 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 20 16:34:04 volumio systemd[1]: shairport-sync.service: Succeeded. May 20 16:34:04 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 20 16:34:04 volumio sudo[1612]: pam_unix(sudo:session): session closed for user root May 20 16:34:04 volumio kernel: CIFS: VFS: Error connecting to socket. Aborting operation. May 20 16:34:04 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -113 May 20 16:34:04 volumio kernel: CIFS: Attempting to mount //192.168.1.200/Music May 20 16:34:04 volumio qobuz-connect[1748]: 20250520 16:34:04.275 [1748.1748] INFO LocalConfigManager: [0x899b60]: Starting Local Configuration server May 20 16:34:04 volumio qobuz-connect[1748]: 20250520 16:34:04.275 [1748.1748] INFO SampleApp: Starting Local configuration server May 20 16:34:04 volumio qobuz-connect[1748]: 20250520 16:34:04.276 [1748.1748] INFO SampleApp: Connected to UNIX socket client 0x88ff60 May 20 16:34:04 volumio volumio[977]: info: Access Token successfully retrieved May 20 16:34:04 volumio volumio[977]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 20 16:34:04 volumio volumio[977]: info: [1747758844280] CoreMusicLibrary::Adding element QOBUZ May 20 16:34:04 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 20 16:34:04 volumio volumio[977]: Cannot find translation for source Bandcamp Discover May 20 16:34:04 volumio volumio[977]: Cannot find translation for source 80s80s Radio May 20 16:34:04 volumio volumio[977]: Cannot find translation for source QOBUZ May 20 16:34:04 volumio volumio[977]: info: Stopping AccessToken refresher cron for QOBUZ May 20 16:34:04 volumio volumio[977]: info: AccessToken refresher cron started for QOBUZ May 20 16:34:04 volumio volumio[977]: info: Adding QOBUZ REST API Endpoints May 20 16:34:04 volumio volumio[977]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz May 20 16:34:04 volumio volumio[977]: [Metrics] CommandRouter: 48s 716.04ms May 20 16:34:04 volumio qobuz-connect[1748]: 20250520 16:34:04.302 [1748.1748] INFO SampleApp: Playback volume changed: 75 May 20 16:34:04 volumio volumio[977]: info: CoreCommandRouter::volumiosetStartupVolume May 20 16:34:04 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 20 16:34:04 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 20 16:34:04 volumio volumio[977]: info: CoreCommandRouter::Close All Modals sent May 20 16:34:04 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 20 16:34:04 volumio volumio[977]: info: CoreCommandRouter::Close All Modals sent May 20 16:34:04 volumio sudo[1761]: pam_unix(sudo:session): session closed for user root May 20 16:34:04 volumio volumio[977]: info: CoreCommandRouter::volumioGetState May 20 16:34:04 volumio volumio[977]: info: CorePlayQueue::getTrack 0 May 20 16:34:04 volumio volumio[977]: info: Cannot mount NAS music at system boot, trial number 4 ,retrying in 5 seconds May 20 16:34:04 volumio volumio[977]: info: Shairport-Sync Started May 20 16:34:04 volumio volumio[977]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 May 20 16:34:04 volumio volumio[977]: info: FusionDsp - ---- read samplerate from file: 44100 May 20 16:34:04 volumio volumio[977]: info: camilladsp stopping service pid 1474... May 20 16:34:04 volumio volumio[977]: info: camilladsp service terminated, instance 1 May 20 16:34:04 volumio volumio[977]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 20 16:34:04 volumio volumio[977]: info: FusionDsp - Effects disabled May 20 16:34:04 volumio volumio[977]: info: camilladsp service started and running in background, instance 1 May 20 16:34:04 volumio volumio[977]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 May 20 16:34:04 volumio volumio[977]: info: FusionDsp - ---- read samplerate from file: 44100 May 20 16:34:04 volumio volumio[977]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 20 16:34:04 volumio volumio[977]: info: FusionDsp - Effects disabled May 20 16:34:04 volumio volumio[977]: error: FusionDsp - WebSocket error: [object Object] May 20 16:34:04 volumio volumio[977]: info: camilladsp spawned new process with pid 1772, instance 1, run: true May 20 16:34:04 volumio mpd[1739]: May 20 16:34 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 20 16:34:05 volumio systemd[1]: Started Music Player Daemon. May 20 16:34:05 volumio sudo[1722]: pam_unix(sudo:session): session closed for user root May 20 16:34:05 volumio volumio[977]: error: updateQueue error: null May 20 16:34:05 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable May 20 16:34:05 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 20 16:34:05 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect May 20 16:34:05 volumio sudo[1790]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service May 20 16:34:05 volumio sudo[1790]: pam_unix(sudo:session): session opened for user root by (uid=0) May 20 16:34:05 volumio systemd[1]: Started MyVolumio SSH Tunnel. May 20 16:34:05 volumio sudo[1790]: pam_unix(sudo:session): session closed for user root May 20 16:34:05 volumio volumio[977]: info: Remote SSH Started May 20 16:34:05 volumio autossh[1793]: port set to 0, monitoring disabled May 20 16:34:05 volumio autossh[1793]: starting ssh (count 1) May 20 16:34:05 volumio autossh[1793]: ssh child pid is 1796 May 20 16:34:05 volumio volumio[977]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 May 20 16:34:05 volumio volumio[977]: info: CoreCommandRouter::volumioGetState May 20 16:34:05 volumio volumio[977]: info: CorePlayQueue::getTrack 0 May 20 16:34:05 volumio volumiossh-tunnel[1792]: Warning: Permanently added '[eu4.myvolumio.org]:2222,[167.172.103.77]:2222' (RSA) to the list of known hosts. May 20 16:34:05 volumio volumio[977]: info: CoreCommandRouter::volumioGetState May 20 16:34:05 volumio volumio[977]: info: CorePlayQueue::getTrack 0 May 20 16:34:06 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd May 20 16:34:06 volumio sudo[1798]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 20 16:34:06 volumio sudo[1798]: pam_unix(sudo:session): session opened for user root by (uid=0) May 20 16:34:06 volumio systemd[1]: Stopping Music Player Daemon... May 20 16:34:06 volumio systemd[1]: mpd.service: Succeeded. May 20 16:34:06 volumio systemd[1]: Stopped Music Player Daemon. May 20 16:34:06 volumio systemd[1]: Starting Music Player Daemon... May 20 16:34:06 volumio sudo[1800]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 20 16:34:06 volumio sudo[1800]: pam_unix(sudo:session): session opened for user root by (uid=0) May 20 16:34:06 volumio sudo[1800]: pam_unix(sudo:session): session closed for user root May 20 16:34:06 volumio sudo[1804]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service May 20 16:34:06 volumio sudo[1804]: pam_unix(sudo:session): session opened for user root by (uid=0) May 20 16:34:06 volumio systemd[1]: Started Volumio Bluetooth Module. May 20 16:34:06 volumio sudo[1804]: pam_unix(sudo:session): session closed for user root May 20 16:34:06 volumio volumio[977]: info: Volumio BT Module successfully started May 20 16:34:06 volumio sudo[1807]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth/* May 20 16:34:06 volumio sudo[1807]: pam_unix(sudo:session): session opened for user root by (uid=0) May 20 16:34:06 volumio volumiobt[1806]: /bin/chmod: cannot access '/var/lib/bluetooth/*': No such file or directory May 20 16:34:06 volumio sudo[1807]: pam_unix(sudo:session): session closed for user root May 20 16:34:07 volumio mpd[1802]: May 20 16:34 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 20 16:34:07 volumio sudo[1811]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 20 16:34:07 volumio sudo[1811]: pam_unix(sudo:session): session opened for user root by (uid=0) May 20 16:34:07 volumio sudo[1811]: pam_unix(sudo:session): session closed for user root May 20 16:34:07 volumio sudo[1814]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 20 16:34:07 volumio sudo[1814]: pam_unix(sudo:session): session opened for user root by (uid=0) May 20 16:34:07 volumio sudo[1814]: pam_unix(sudo:session): session closed for user root May 20 16:34:07 volumio volumio[977]: verbose: New Socket.io Connection to 192.168.1.153 from 192.168.1.54 UA: Mozilla/5.0 (Linux; Android 15; SM-S906B Build/AP3A.240905.015.A2; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/136.0.7103.125 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 May 20 16:34:07 volumio sudo[1868]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall pulseaudio May 20 16:34:07 volumio sudo[1868]: pam_unix(sudo:session): session opened for user root by (uid=0) May 20 16:34:07 volumio systemd[1]: Started Music Player Daemon. May 20 16:34:07 volumio volumiobt[1806]: pulseaudio: no process found May 20 16:34:07 volumio sudo[1868]: pam_unix(sudo:session): session closed for user root May 20 16:34:07 volumio sudo[1798]: pam_unix(sudo:session): session closed for user root May 20 16:34:07 volumio volumio[977]: error: updateQueue error: null May 20 16:34:07 volumio dbus-daemon[629]: [system] Activating via systemd: service name='org.freedesktop.RealtimeKit1' unit='rtkit-daemon.service' requested by ':1.24' (uid=1000 pid=1874 comm="/usr/bin/pulseaudio --high-priority=true ") May 20 16:34:07 volumio systemd[1]: Starting RealtimeKit Scheduling Policy Service... May 20 16:34:07 volumio volumio[977]: ------------------------------------ BT MESSAGE: BT STATUS: running May 20 16:34:07 volumio dbus-daemon[629]: [system] Successfully activated service 'org.freedesktop.RealtimeKit1' May 20 16:34:07 volumio systemd[1]: Started RealtimeKit Scheduling Policy Service. May 20 16:34:07 volumio rtkit-daemon[1875]: Successfully called chroot. May 20 16:34:07 volumio rtkit-daemon[1875]: Successfully dropped privileges. May 20 16:34:07 volumio rtkit-daemon[1875]: Successfully limited resources. May 20 16:34:07 volumio rtkit-daemon[1875]: Running. May 20 16:34:07 volumio rtkit-daemon[1875]: Canary thread running. May 20 16:34:07 volumio rtkit-daemon[1875]: Watchdog thread running. May 20 16:34:07 volumio dbus-daemon[629]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.25' (uid=0 pid=1875 comm="/usr/lib/rtkit/rtkit-daemon ") May 20 16:34:08 volumio systemd[1]: Starting Authorization Manager... May 20 16:34:08 volumio volumio[977]: ------------------------------------ BT MESSAGE: BT STATUS: running May 20 16:34:08 volumio polkitd[1879]: started daemon version 0.105 using authority implementation `local' version `0.105' May 20 16:34:08 volumio dbus-daemon[629]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' May 20 16:34:08 volumio systemd[1]: Started Authorization Manager. May 20 16:34:08 volumio pulseaudio[1874]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 May 20 16:34:08 volumio pulseaudio[1874]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 May 20 16:34:08 volumio volumiobt[1806]: Applying permissions May 20 16:34:08 volumio volumiobt[1806]: Setting BT discoverable and pairable May 20 16:34:08 volumio bluetoothd[849]: Endpoint registered: sender=:1.45 path=/MediaEndpoint/A2DPSource May 20 16:34:08 volumio bluetoothd[849]: Endpoint registered: sender=:1.45 path=/MediaEndpoint/A2DPSink May 20 16:34:08 volumio pulseaudio[1874]: Failed to register as a handsfree audio agent with ofono: org.freedesktop.DBus.Error.ServiceUnknown: The name org.ofono was not provided by any .service files May 20 16:34:08 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 20 16:34:08 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 20 16:34:08 volumio volumio[977]: info: Discovery: Getting this device information May 20 16:34:08 volumio volumio[977]: info: CoreCommandRouter::volumioGetState May 20 16:34:08 volumio volumio[977]: info: CorePlayQueue::getTrack 0 May 20 16:34:08 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 20 16:34:08 volumio kernel: Bluetooth: RFCOMM TTY layer initialized May 20 16:34:08 volumio kernel: Bluetooth: RFCOMM socket layer initialized May 20 16:34:08 volumio kernel: Bluetooth: RFCOMM ver 1.11 May 20 16:34:08 volumio volumiobt[1806]: [CHG] Controller E4:5F:01:23:DD:DC UUIDs: 00001112-0000-1000-8000-00805f9b34fb May 20 16:34:08 volumio volumiobt[1806]: [117B blob data] May 20 16:34:08 volumio volumiobt[1806]: [117B blob data] May 20 16:34:08 volumio volumiobt[1806]: [117B blob data] May 20 16:34:08 volumio volumiobt[1806]: [117B blob data] May 20 16:34:08 volumio volumiobt[1806]: [117B blob data] May 20 16:34:08 volumio volumiobt[1806]: [117B blob data] May 20 16:34:08 volumio volumiobt[1806]: [117B blob data] May 20 16:34:08 volumio volumiobt[1806]: [117B blob data] May 20 16:34:08 volumio volumiobt[1806]: [55B blob data] May 20 16:34:08 volumio volumiobt[1806]: [117B blob data] May 20 16:34:08 volumio volumiobt[1806]: [117B blob data] May 20 16:34:08 volumio volumiobt[1806]: [117B blob data] May 20 16:34:08 volumio volumiobt[1806]: [117B blob data] May 20 16:34:08 volumio volumiobt[1806]: [117B blob data] May 20 16:34:08 volumio volumiobt[1806]: [117B blob data] May 20 16:34:08 volumio volumiobt[1806]: [117B blob data] May 20 16:34:08 volumio volumiobt[1806]: [117B blob data] May 20 16:34:08 volumio volumiobt[1806]: [117B blob data] May 20 16:34:08 volumio volumiobt[1806]: [117B blob data] May 20 16:34:08 volumio volumiobt[1806]: [117B blob data] May 20 16:34:08 volumio volumiobt[1806]: [117B blob data] May 20 16:34:08 volumio volumiobt[1806]: [117B blob data] May 20 16:34:08 volumio volumiobt[1806]: [117B blob data] May 20 16:34:08 volumio volumiobt[1806]: [117B blob data] May 20 16:34:08 volumio volumiobt[1806]: [117B blob data] May 20 16:34:08 volumio volumiobt[1806]: [117B blob data] May 20 16:34:08 volumio volumiobt[1806]: [117B blob data] May 20 16:34:08 volumio volumiobt[1806]: [bluetooth]# -e power on May 20 16:34:08 volumio volumiobt[1806]: Invalid command in menu main: -e May 20 16:34:08 volumio volumiobt[1806]: May 20 16:34:08 volumio volumiobt[1806]: Use "help" for a list of available commands in a menu. May 20 16:34:08 volumio volumiobt[1806]: Use "menu " if you want to enter any submenu. May 20 16:34:08 volumio volumiobt[1806]: Use "back" if you want to return to menu main. May 20 16:34:08 volumio volumiobt[1806]: [bluetooth]# agent on May 20 16:34:08 volumio volumiobt[1806]: Agent is already registered May 20 16:34:08 volumio volumiobt[1806]: [bluetooth]# discoverable on May 20 16:34:08 volumio volumiobt[1806]: [bluetooth]# pairable on May 20 16:34:08 volumio volumiobt[1806]: [bluetooth]# agent NoInputNoOutput May 20 16:34:08 volumio volumiobt[1806]: Agent is already registered May 20 16:34:08 volumio volumiobt[1806]: [bluetooth]# default-agent May 20 16:34:08 volumio volumiobt[1806]: [bluetooth]# quit May 20 16:34:08 volumio volumiobt[1806]: [59B blob data] May 20 16:34:08 volumio volumio[977]: info: MRS: Getting audio outputs on start May 20 16:34:08 volumio volumio[977]: info: MRS: Requesting all other devices output May 20 16:34:08 volumio sudo[1890]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 20 16:34:08 volumio sudo[1890]: pam_unix(sudo:session): session opened for user root by (uid=0) May 20 16:34:08 volumio sudo[1890]: pam_unix(sudo:session): session closed for user root May 20 16:34:08 volumio sudo[1893]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 20 16:34:08 volumio sudo[1893]: pam_unix(sudo:session): session opened for user root by (uid=0) May 20 16:34:08 volumio sudo[1893]: pam_unix(sudo:session): session closed for user root May 20 16:34:08 volumio volumio[977]: verbose: New Socket.io Connection to 192.168.1.153 from 192.168.1.54 UA: Mozilla/5.0 (Linux; Android 15; SM-S906B Build/AP3A.240905.015.A2; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/136.0.7103.125 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 May 20 16:34:09 volumio volumio[977]: info: CoreCommandRouter::volumioGetState May 20 16:34:09 volumio volumio[977]: info: CorePlayQueue::getTrack 0 May 20 16:34:09 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 20 16:34:09 volumio volumio[977]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom May 20 16:34:09 volumio volumio[977]: info: Listing playlists May 20 16:34:09 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 20 16:34:09 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 20 16:34:09 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 20 16:34:09 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 20 16:34:09 volumio sudo[1896]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=admin,password=enrico27,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.151/music /mnt/NAS/music May 20 16:34:09 volumio sudo[1896]: pam_unix(sudo:session): session opened for user root by (uid=0) May 20 16:34:09 volumio volumio[977]: info: camilladsp spawned new process with pid 1900, instance 1, run: true May 20 16:34:10 volumio sudo[1756]: pam_unix(sudo:session): session closed for user root May 20 16:34:10 volumio volumio[977]: info: Cannot mount NAS Music at system boot, trial number 4 ,retrying in 5 seconds May 20 16:34:10 volumio kernel: CIFS: VFS: Error connecting to socket. Aborting operation. May 20 16:34:10 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -113 May 20 16:34:10 volumio kernel: CIFS: Attempting to mount //192.168.1.151/music May 20 16:34:11 volumio volumio[977]: info: BOOT COMPLETED May 20 16:34:11 volumio volumio[977]: info: mpdhttpout ---Boot completed detected! Patching mpd now! May 20 16:34:11 volumio volumio[977]: info: mpdhttpout ---Configuration successfully replaced in mpd.conf. May 20 16:34:11 volumio volumio[977]: info: CoreCommandRouter::volumioGetState May 20 16:34:11 volumio volumio[977]: info: CorePlayQueue::getTrack 0 May 20 16:34:11 volumio volumio[977]: info: CoreCommandRouter::volumioPause May 20 16:34:11 volumio volumio[977]: info: CoreStateMachine::pause May 20 16:34:11 volumio volumio[977]: info: mpdhttpout --- Volumio set on pause May 20 16:34:11 volumio volumio[977]: info: CoreCommandRouter::volumioPlay May 20 16:34:11 volumio volumio[977]: info: CoreStateMachine::play index undefined May 20 16:34:11 volumio volumio[977]: info: CoreStateMachine::setConsumeUpdateService undefined May 20 16:34:11 volumio volumio[977]: info: CorePlayQueue::getTrack 0 May 20 16:34:12 volumio volumio[977]: info: CoreStateMachine::startPlaybackTimer May 20 16:34:12 volumio volumio[977]: info: CorePlayQueue::getTrack 0 May 20 16:34:12 volumio volumio[977]: info: [1747758852000] ControllerQobuz::clearAddPlayTrack May 20 16:34:12 volumio volumio[977]: verbose: ControllerMpd::sendMpdCommand stop May 20 16:34:12 volumio volumio[977]: info: sendMpdCommand stop took 1 milliseconds May 20 16:34:12 volumio volumio[977]: verbose: ControllerMpd::sendMpdCommand clear May 20 16:34:12 volumio volumio[977]: info: May 20 16:34:12 volumio volumio[977]: ---------------------------- MPD announces system playlist update May 20 16:34:12 volumio volumio[977]: info: Ignoring MPD Status Update May 20 16:34:12 volumio volumio[977]: info: sendMpdCommand clear took 2 milliseconds May 20 16:34:12 volumio volumio[977]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/24013542" May 20 16:34:12 volumio volumio[977]: error: updateQueue error: null May 20 16:34:12 volumio volumio[977]: info: ------------------------------ 3ms May 20 16:34:12 volumio volumio[977]: STREAMING PROXY: Handling url /?data=qobuz://song/24013542 May 20 16:34:12 volumio volumio[977]: info: Executing endpoint getStreamUrlqobuz May 20 16:34:12 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI May 20 16:34:12 volumio volumio[977]: info: getStreamUrl took 362 milliseconds May 20 16:34:12 volumio volumio[977]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=1203302&eid=24013542&fmt=6&profile=raw&app_id=539451548&cid=1376263&etsp=1747762452&hmac=9Lm2Trn2dVu_jUBdioNOO-GXJRA May 20 16:34:12 volumio volumio[977]: STREAMING PROXY: Response: 200, length: 20491387 May 20 16:34:12 volumio volumio[977]: STREAMING PROXY: Client dropped request, destroying May 20 16:34:12 volumio volumio[977]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/24013542" May 20 16:34:12 volumio volumio[977]: info: May 20 16:34:12 volumio volumio[977]: ---------------------------- MPD announces system playlist update May 20 16:34:12 volumio volumio[977]: info: Ignoring MPD Status Update May 20 16:34:12 volumio volumio[977]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/24013542" took 1 milliseconds May 20 16:34:12 volumio volumio[977]: info: CoreStateMachine::setConsumeUpdateService mpd May 20 16:34:12 volumio volumio[977]: verbose: ControllerMpd::sendMpdCommand play May 20 16:34:12 volumio volumio[977]: info: ------------------------------ 2ms May 20 16:34:12 volumio volumio[977]: info: sendMpdCommand play took 2 milliseconds May 20 16:34:12 volumio volumio[977]: STREAMING PROXY: Handling url /?data=qobuz://song/24013542 May 20 16:34:12 volumio volumio[977]: info: Executing endpoint getStreamUrlqobuz May 20 16:34:12 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI May 20 16:34:13 volumio volumio[977]: info: getStreamUrl took 378 milliseconds May 20 16:34:13 volumio volumio[977]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=1203302&eid=24013542&fmt=6&profile=raw&app_id=539451548&cid=1376263&etsp=1747762453&hmac=hSYEbx6getdsCZ64holxR0WMVkk May 20 16:34:13 volumio volumio[977]: STREAMING PROXY: Response: 200, length: 20491387 May 20 16:34:13 volumio volumio[977]: info: May 20 16:34:13 volumio volumio[977]: ---------------------------- MPD announces state update: player May 20 16:34:13 volumio volumio[977]: info: ControllerMpd::getState May 20 16:34:13 volumio volumio[977]: verbose: ControllerMpd::sendMpdCommand status May 20 16:34:13 volumio volumio[977]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 May 20 16:34:13 volumio volumio[977]: info: FusionDsp - ---- read samplerate from file: 44100 May 20 16:34:13 volumio volumio[977]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 20 16:34:13 volumio volumio[977]: info: FusionDsp - Effects disabled May 20 16:34:13 volumio volumio[977]: info: May 20 16:34:13 volumio volumio[977]: ---------------------------- MPD announces state update: player May 20 16:34:13 volumio volumio[977]: info: sendMpdCommand status took 21 milliseconds May 20 16:34:13 volumio volumio[977]: info: ControllerMpd::getState May 20 16:34:13 volumio volumio[977]: verbose: ControllerMpd::sendMpdCommand status May 20 16:34:13 volumio volumio[977]: verbose: ControllerMpd::parseState May 20 16:34:13 volumio volumio[977]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 20 16:34:13 volumio volumio[977]: info: sendMpdCommand status took 3 milliseconds May 20 16:34:13 volumio volumio[977]: verbose: ControllerMpd::parseState May 20 16:34:13 volumio volumio[977]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 20 16:34:13 volumio volumio[977]: info: sendMpdCommand playlistinfo took 3 milliseconds May 20 16:34:13 volumio volumio[977]: info: sendMpdCommand playlistinfo took 2 milliseconds May 20 16:34:13 volumio volumio[977]: verbose: ControllerMpd::parseTrackInfo May 20 16:34:13 volumio volumio[977]: verbose: ControllerMpd::parseTrackInfo May 20 16:34:13 volumio volumio[977]: info: ControllerMpd::pushState May 20 16:34:13 volumio volumio[977]: info: CoreCommandRouter::servicePushState May 20 16:34:13 volumio volumio[977]: info: CorePlayQueue::getTrack 0 May 20 16:34:13 volumio volumio[977]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":167,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"24013542","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/24013542","trackType":"qobuz"} May 20 16:34:13 volumio volumio[977]: verbose: CURRENT POSITION 0 May 20 16:34:13 volumio volumio[977]: info: CoreStateMachine::syncState stateService play May 20 16:34:13 volumio volumio[977]: info: CoreStateMachine::syncState currentStatus stop May 20 16:34:13 volumio volumio[977]: info: ControllerMpd::pushState May 20 16:34:13 volumio volumio[977]: info: CoreCommandRouter::servicePushState May 20 16:34:13 volumio volumio[977]: info: CorePlayQueue::getTrack 0 May 20 16:34:13 volumio volumio[977]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1212,"duration":167,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"755 Kbps","isStreaming":false,"title":"24013542","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/24013542","trackType":"qobuz"} May 20 16:34:13 volumio volumio[977]: verbose: CURRENT POSITION 0 May 20 16:34:13 volumio volumio[977]: info: CoreStateMachine::syncState stateService play May 20 16:34:13 volumio volumio[977]: info: CoreStateMachine::syncState currentStatus play May 20 16:34:13 volumio volumio[977]: info: Received an update from plugin. extracting info from payload May 20 16:34:13 volumio volumio[977]: info: CoreStateMachine::pushState May 20 16:34:13 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 20 16:34:13 volumio volumio[977]: info: CoreCommandRouter::volumioPushState May 20 16:34:13 volumio volumio[977]: info: MRS: Pushing multiroomSync output update for this device May 20 16:34:13 volumio volumio[977]: info: MRS: Pushing multiroomSync output May 20 16:34:13 volumio volumio[977]: info: CoreCommandRouter::volumioGetState May 20 16:34:13 volumio volumio[977]: info: CoreStateMachine::pushState May 20 16:34:13 volumio volumio[977]: info: CoreCommandRouter::volumioPushState May 20 16:34:13 volumio volumio[977]: info: MRS: Pushing multiroomSync output update for this device May 20 16:34:13 volumio volumio[977]: info: MRS: Pushing multiroomSync output May 20 16:34:13 volumio volumio[977]: info: CoreCommandRouter::volumioGetState May 20 16:34:13 volumio volumio[977]: info: ------------------------------ 55ms May 20 16:34:13 volumio volumio[977]: info: ------------------------------ 53ms May 20 16:34:13 volumio volumio[977]: info: FusionDsp - {"Reload":{"result":"Ok"}} May 20 16:34:13 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd May 20 16:34:13 volumio sudo[1922]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 20 16:34:13 volumio sudo[1922]: pam_unix(sudo:session): session opened for user root by (uid=0) May 20 16:34:13 volumio systemd[1]: Stopping Music Player Daemon... May 20 16:34:13 volumio volumio[977]: STREAMING PROXY: Client dropped request, destroying May 20 16:34:13 volumio volumio[977]: ------------------------------------ BT MESSAGE: BT STATUS: running May 20 16:34:13 volumio systemd[1]: mpd.service: Succeeded. May 20 16:34:13 volumio systemd[1]: Stopped Music Player Daemon. May 20 16:34:13 volumio volumio[977]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 20 16:34:13 volumio systemd[1]: Starting Music Player Daemon... May 20 16:34:13 volumio volumio[977]: ------------------------------------ BT MESSAGE: BT STATUS: running May 20 16:34:13 volumio sudo[1924]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 20 16:34:13 volumio sudo[1924]: pam_unix(sudo:session): session opened for user root by (uid=0) May 20 16:34:13 volumio sudo[1924]: pam_unix(sudo:session): session closed for user root May 20 16:34:14 volumio volumio[977]: info: camilladsp spawned new process with pid 1927, instance 1, run: true May 20 16:34:14 volumio ntpd[876]: Soliciting pool server 193.204.114.232 May 20 16:34:14 volumio mpd[1926]: May 20 16:34 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 20 16:34:15 volumio systemd[1]: Started Music Player Daemon. May 20 16:34:15 volumio sudo[1922]: pam_unix(sudo:session): session closed for user root May 20 16:34:15 volumio volumio[977]: error: updateQueue error: null May 20 16:34:15 volumio ntpd[876]: Soliciting pool server 162.159.200.1 May 20 16:34:15 volumio sudo[1937]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=admin,password=enrico27!,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.200/Music /mnt/NAS/Music May 20 16:34:15 volumio sudo[1937]: pam_unix(sudo:session): session opened for user root by (uid=0) May 20 16:34:16 volumio ntpd[876]: Soliciting pool server 89.46.74.148 May 20 16:34:16 volumio ntpd[876]: Soliciting pool server 212.6.50.243 May 20 16:34:16 volumio sudo[1896]: pam_unix(sudo:session): session closed for user root May 20 16:34:16 volumio volumio[977]: info: Cannot mount NAS at system boot, trial number 4 ,stopping May 20 16:34:16 volumio kernel: CIFS: VFS: Error connecting to socket. Aborting operation. May 20 16:34:16 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -113 May 20 16:34:16 volumio kernel: CIFS: Attempting to mount //192.168.1.200/Music May 20 16:34:17 volumio ntpd[876]: Soliciting pool server 37.247.53.178 May 20 16:34:17 volumio ntpd[876]: Soliciting pool server 151.22.209.90 May 20 16:34:17 volumio ntpd[876]: Soliciting pool server 162.159.200.123 May 20 16:34:18 volumio ntpd[876]: Soliciting pool server 185.19.184.35 May 20 16:34:18 volumio ntpd[876]: Soliciting pool server 185.157.229.254 May 20 16:34:18 volumio ntpd[876]: Soliciting pool server 217.61.62.224 May 20 16:34:19 volumio ntpd[876]: Soliciting pool server 95.110.254.234 May 20 16:34:19 volumio ntpd[876]: Soliciting pool server 2a00:6d41:200:2::12 May 20 16:34:19 volumio ntpd[876]: Soliciting pool server 31.14.133.122 May 20 16:34:20 volumio ntpd[876]: Soliciting pool server 93.94.88.51 May 20 16:34:20 volumio volumio[977]: info: CoreCommandRouter::volumioPause May 20 16:34:21 volumio volumio[977]: info: CoreStateMachine::pause May 20 16:34:21 volumio volumio[977]: info: CoreStateMachine::stPlaybackTimer May 20 16:34:21 volumio volumio[977]: info: CoreStateMachine::servicePause May 20 16:34:21 volumio volumio[977]: info: CorePlayQueue::getTrack 0 May 20 16:34:21 volumio volumio[977]: info: CoreCommandRouter::servicePause May 20 16:34:21 volumio volumio[977]: info: [1747758861001] ControllerQobuz::pause May 20 16:34:21 volumio volumio[977]: info: CoreStateMachine::setConsumeUpdateService mpd May 20 16:34:21 volumio volumio[977]: info: ControllerMpd::pause May 20 16:34:21 volumio volumio[977]: verbose: ControllerMpd::sendMpdCommand pause May 20 16:34:21 volumio volumio[977]: info: sendMpdCommand pause took 0 milliseconds May 20 16:34:22 volumio ntpd[876]: receive: Unexpected origin timestamp 0xebd72d8e.578784b0 does not match aorg 0000000000.00000000 from server@217.61.62.224 xmt 0xebd72d8e.e503b67c May 20 16:34:22 volumio ntpd[876]: receive: Unexpected origin timestamp 0xebd72d8e.57887b9b does not match aorg 0000000000.00000000 from server@162.159.200.123 xmt 0xebd72d8e.e58dbe5e May 20 16:34:22 volumio ntpd[876]: receive: Unexpected origin timestamp 0xebd72d8e.57867362 does not match aorg 0000000000.00000000 from server@31.14.133.122 xmt 0xebd72d8e.e659f915 May 20 16:34:22 volumio ntpd[876]: receive: Unexpected origin timestamp 0xebd72d8e.57899652 does not match aorg 0000000000.00000000 from server@151.22.209.90 xmt 0xebd72d8e.e5c1ffda May 20 16:34:22 volumio ntpd[876]: receive: Unexpected origin timestamp 0xebd72d8e.5783a88e does not match aorg 0000000000.00000000 from server@93.94.88.51 xmt 0xebd72d8e.e8015b61 May 20 16:34:22 volumio ntpd[876]: receive: Unexpected origin timestamp 0xebd72d8e.578ca7d1 does not match aorg 0000000000.00000000 from server@193.204.114.232 xmt 0xebd72d8e.e7a66520 May 20 16:34:23 volumio volumio[977]: info: CoreCommandRouter::volumioPause May 20 16:34:23 volumio volumio[977]: info: CoreStateMachine::pause May 20 16:34:23 volumio sudo[1937]: pam_unix(sudo:session): session closed for user root May 20 16:34:23 volumio volumio[977]: info: Cannot mount NAS at system boot, trial number 4 ,stopping May 20 16:34:23 volumio kernel: CIFS: VFS: Error connecting to socket. Aborting operation. May 20 16:34:23 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -113 May 20 16:34:25 volumio volumio[977]: info: CoreCommandRouter::volumioPrevious May 20 16:34:25 volumio volumio[977]: info: CoreStateMachine::previous May 20 16:34:25 volumio volumio[977]: info: CoreStateMachine::updateTrackBlock May 20 16:34:25 volumio volumio[977]: info: CorePlayQueue::getTrackBlock May 20 16:34:25 volumio volumio[977]: info: CoreStateMachine::serviceClearAddPlay May 20 16:34:25 volumio volumio[977]: info: CoreCommandRouter::serviceClearAddPlayTracks May 20 16:34:25 volumio volumio[977]: error: WARNING: No clearAddPlayTracks method for service qobuz May 20 16:34:27 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 20 16:34:27 volumio volumio[977]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined May 20 16:34:27 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates May 20 16:34:27 volumio volumio[977]: info: Received Get System Version May 20 16:34:27 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 20 16:34:27 volumio volumio[977]: info: Received Get System Info May 20 16:34:27 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 20 16:34:27 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 20 16:34:27 volumio volumio[977]: info: Discovery: Getting this device information May 20 16:34:27 volumio volumio[977]: info: CoreCommandRouter::volumioGetState May 20 16:34:27 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 20 16:34:33 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 20 16:34:33 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 20 16:34:33 volumio volumio[977]: info: Discovery: Getting this device information May 20 16:34:33 volumio volumio[977]: info: CoreCommandRouter::volumioGetState May 20 16:34:33 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 20 16:34:38 volumio volumio[977]: info: VolumeController::SetAlsaVolume30 May 20 16:34:38 volumio volumio[977]: info: CoreStateMachine::pushState May 20 16:34:38 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 20 16:34:38 volumio volumio[977]: info: CoreCommandRouter::volumioPushState May 20 16:34:38 volumio volumio[977]: info: MRS: Pushing multiroomSync output update for this device May 20 16:34:38 volumio volumio[977]: info: MRS: Pushing multiroomSync output May 20 16:34:38 volumio volumio[977]: info: CoreCommandRouter::volumioGetState May 20 16:34:38 volumio volumio[977]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: xcb_connection_has_error() returned true May 20 16:34:38 volumio volumio[977]: amixer: Unable to find simple control '',0 May 20 16:34:38 volumio volumio[977]: verbose: New Socket.io Connection to 192.168.1.153:3000 from 192.168.1.54 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 9 May 20 16:34:38 volumio volumio[977]: info: CoreCommandRouter::volumioGetState May 20 16:34:38 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 20 16:34:38 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 20 16:34:38 volumio volumio[977]: info: CoreCommandRouter::volumioGetState May 20 16:34:38 volumio volumio[977]: info: VolumeController::SetAlsaVolume50 May 20 16:34:38 volumio volumio[977]: info: CoreStateMachine::pushState May 20 16:34:38 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 20 16:34:38 volumio volumio[977]: info: CoreCommandRouter::volumioPushState May 20 16:34:38 volumio volumio[977]: info: MRS: Pushing multiroomSync output update for this device May 20 16:34:38 volumio volumio[977]: info: MRS: Pushing multiroomSync output May 20 16:34:38 volumio volumio[977]: info: CoreCommandRouter::volumioGetState May 20 16:34:38 volumio volumio[977]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: xcb_connection_has_error() returned true May 20 16:34:38 volumio volumio[977]: amixer: Unable to find simple control '',0 May 20 16:34:40 volumio volumio[977]: info: VolumeController::SetAlsaVolume65 May 20 16:34:40 volumio volumio[977]: info: CoreStateMachine::pushState May 20 16:34:40 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 20 16:34:40 volumio volumio[977]: info: CoreCommandRouter::volumioPushState May 20 16:34:40 volumio volumio[977]: info: MRS: Pushing multiroomSync output update for this device May 20 16:34:40 volumio volumio[977]: info: MRS: Pushing multiroomSync output May 20 16:34:40 volumio volumio[977]: info: CoreCommandRouter::volumioGetState May 20 16:34:40 volumio volumio[977]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: xcb_connection_has_error() returned true May 20 16:34:40 volumio volumio[977]: amixer: Unable to find simple control '',0 May 20 16:34:44 volumio volumio[977]: info: VolumeController::SetAlsaVolume70 May 20 16:34:44 volumio volumio[977]: info: CoreStateMachine::pushState May 20 16:34:44 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 20 16:34:44 volumio volumio[977]: info: CoreCommandRouter::volumioPushState May 20 16:34:44 volumio volumio[977]: info: MRS: Pushing multiroomSync output update for this device May 20 16:34:44 volumio volumio[977]: info: MRS: Pushing multiroomSync output May 20 16:34:44 volumio volumio[977]: info: CoreCommandRouter::volumioGetState May 20 16:34:44 volumio volumio[977]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: xcb_connection_has_error() returned true May 20 16:34:44 volumio volumio[977]: amixer: Unable to find simple control '',0 May 20 16:35:02 volumio volumio[977]: verbose: New Socket.io Connection to 192.168.1.153 from 192.168.1.54 UA: Mozilla/5.0 (Linux; Android 15; SM-S906B Build/AP3A.240905.015.A2; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/136.0.7103.125 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 May 20 16:35:02 volumio volumio[977]: info: CoreCommandRouter::volumioGetState May 20 16:35:02 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 20 16:35:02 volumio volumio[977]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom May 20 16:35:02 volumio volumio[977]: info: Listing playlists May 20 16:35:03 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 20 16:35:03 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 20 16:35:03 volumio volumio[977]: info: Discovery: Getting this device information May 20 16:35:03 volumio volumio[977]: info: CoreCommandRouter::volumioGetState May 20 16:35:03 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 20 16:35:03 volumio volumio[977]: verbose: New Socket.io Connection to 192.168.1.153 from 192.168.1.54 UA: Mozilla/5.0 (Linux; Android 15; SM-S906B Build/AP3A.240905.015.A2; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/136.0.7103.125 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 May 20 16:35:03 volumio volumio[977]: info: CoreCommandRouter::volumioGetState May 20 16:35:03 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 20 16:35:03 volumio volumio[977]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom May 20 16:35:03 volumio volumio[977]: info: Listing playlists May 20 16:35:06 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: multiroom , audioOutputPlay May 20 16:35:06 volumio volumio[977]: info: Error : CoreCommandRouter::executeOnPlugin: No method [audioOutputPlay] in plugin multiroom May 20 16:35:06 volumio volumio[977]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 20 16:35:06 volumio volumio[977]: TypeError: Cannot read property 'then' of undefined May 20 16:35:06 volumio volumio[977]: at outputs.audioOutputPlay (/volumio/app/plugins/audio_interface/outputs/index.js:367:9) May 20 16:35:06 volumio volumio[977]: at CoreCommandRouter.audioOutputPlay (/volumio/app/index.js:2279:30) May 20 16:35:06 volumio volumio[977]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1456:26) May 20 16:35:06 volumio volumio[977]: at Socket.emit (events.js:315:20) May 20 16:35:06 volumio volumio[977]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 May 20 16:35:06 volumio volumio[977]: at processTicksAndRejections (internal/process/task_queues.js:75:11) May 20 16:35:06 volumio volumio[977]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 20 16:35:07 volumio sudo[2032]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-20 16:34 May 20 16:35:07 volumio sudo[2032]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="570c5791513f5bac7da274aba6690c1a961705de" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 13 May 2025 05:16:30 PM CEST" VOLUMIO_VERSION="3.812" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="3934ee440d5d3f0186049a23752ad680"