-- Logs begin at Thu 2019-02-14 10:12:00 UTC, end at Sun 2025-05-04 13:19:26 UTC. -- May 04 13:18:53 volumio systemd-timedated[784]: Changed local time to Sun May 4 13:18:53 2025 May 04 13:18:53 volumio sudo[782]: pam_unix(sudo:session): session closed for user root May 04 13:18:53 volumio volumio-time-update[457]: volumio-time-update-util: System time updated successfully. May 04 13:18:53 volumio systemd[1]: Started Volumio Time Update Utility. May 04 13:18:53 volumio systemd[1]: Reached target Multi-User System. May 04 13:18:53 volumio systemd[1]: Reached target Graphical Interface. May 04 13:18:53 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... May 04 13:18:53 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. May 04 13:18:53 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. May 04 13:18:53 volumio systemd[1]: Startup finished in 15.053s (kernel) + 19.680s (userspace) = 34.734s. May 04 13:18:53 volumio volumio[684]: info: MYVOLUMIO Environment detected May 04 13:18:54 volumio volumio[684]: info: Plugin folders cleanup May 04 13:18:54 volumio volumio[684]: info: Scanning into folder /volumio/app/plugins/ May 04 13:18:54 volumio volumio[684]: info: Scanning category audio_interface May 04 13:18:54 volumio volumio[684]: info: Scanning category miscellanea May 04 13:18:54 volumio volumio[684]: info: Scanning category music_service May 04 13:18:54 volumio volumio[684]: info: Scanning category plugins.json May 04 13:18:54 volumio volumio[684]: info: Scanning category system_controller May 04 13:18:54 volumio volumio[684]: info: Scanning category user_interface May 04 13:18:54 volumio volumio[684]: info: Scanning into folder /data/plugins/ May 04 13:18:54 volumio volumio[684]: info: Scanning category audio_interface May 04 13:18:54 volumio volumio[684]: info: Scanning category music_service May 04 13:18:54 volumio volumio[684]: info: Scanning category system_controller May 04 13:18:54 volumio volumio[684]: info: Scanning category user_interface May 04 13:18:54 volumio volumio[684]: info: Plugin folders cleanup completed May 04 13:18:54 volumio volumio[684]: info: ------------------------------------------- May 04 13:18:54 volumio volumio[684]: info: ----- Core plugins startup ---- May 04 13:18:54 volumio volumio[684]: info: ------------------------------------------- May 04 13:18:54 volumio volumio[684]: info: Loading plugins from folder /volumio/app/plugins/ May 04 13:18:54 volumio volumio[684]: info: Adding plugin upnp to MyMusic Plugins May 04 13:18:54 volumio volumio[684]: info: Adding plugin airplay_emulation to MyMusic Plugins May 04 13:18:54 volumio volumio[684]: info: Adding plugin upnp_browser to MyMusic Plugins May 04 13:18:54 volumio volumio[684]: info: Loading plugins from folder /data/plugins/ May 04 13:18:54 volumio volumio[684]: info: Loading plugin "system"... May 04 13:18:54 volumio volumio[684]: info: Loading plugin "appearance"... May 04 13:18:54 volumio volumio-remote-updater[468]: [2025-05-04 13:18:54] [connect] Successful connection May 04 13:18:57 volumio volumio[684]: info: Loading plugin "network"... May 04 13:18:57 volumio volumio[684]: info: Refreshing Cached IP Addresses May 04 13:18:57 volumio sudo[792]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 04 13:18:57 volumio sudo[792]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 13:18:57 volumio sudo[794]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 04 13:18:57 volumio sudo[792]: pam_unix(sudo:session): session closed for user root May 04 13:18:57 volumio sudo[794]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 13:18:57 volumio volumio[684]: info: Loading plugin "services"... May 04 13:18:57 volumio volumio[684]: info: Loading plugin "alsa_controller"... May 04 13:18:57 volumio sudo[803]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 04 13:18:57 volumio sudo[794]: pam_unix(sudo:session): session closed for user root May 04 13:18:57 volumio sudo[803]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 13:18:57 volumio sudo[803]: pam_unix(sudo:session): session closed for user root May 04 13:18:57 volumio volumio[684]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 04 13:18:57 volumio volumio[684]: info: Loading plugin "wizard"... May 04 13:18:57 volumio volumio[684]: info: Loading plugin "networkfs"... May 04 13:18:57 volumio volumio[684]: info: Starting Udev Watcher for removable devices May 04 13:18:57 volumio sudo[824]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=jarek,password=jerry,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.2.102/Media\\Music\\LP /mnt/NAS/MusicOnJK2QNAP May 04 13:18:57 volumio sudo[824]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 13:18:57 volumio volumio[684]: info: Ignoring mount for partition: boot May 04 13:18:57 volumio volumio[684]: info: Ignoring mount for partition: volumio May 04 13:18:57 volumio volumio[684]: info: Ignoring mount for partition: volumio_data May 04 13:18:57 volumio volumio[684]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 04 13:18:57 volumio volumio[684]: info: Loading plugin "volumio_command_line_client"... May 04 13:18:57 volumio volumio[684]: info: Loading plugin "upnp"... May 04 13:18:57 volumio volumio[684]: info: [1746364737756] Starting Upmpd Daemon May 04 13:18:57 volumio volumio[684]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 04 13:18:57 volumio volumio[684]: info: Loading plugin "my_music"... May 04 13:18:57 volumio volumio[684]: info: Loading plugin "mpd"... May 04 13:18:58 volumio kernel: Key type cifs.spnego registered May 04 13:18:58 volumio kernel: Key type cifs.idmap registered May 04 13:18:58 volumio kernel: CIFS: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3.1.1), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3.1.1 (or even SMB3 or SMB2.1) specify vers=1.0 on mount. May 04 13:18:58 volumio kernel: CIFS: Attempting to mount //192.168.2.102/Media/Music/LP May 04 13:18:58 volumio sudo[824]: pam_unix(sudo:session): session closed for user root May 04 13:18:58 volumio volumio[684]: info: Loading plugin "upnp_browser"... May 04 13:18:59 volumio volumio[684]: info: Loading plugin "alarm-clock"... May 04 13:18:59 volumio volumio[684]: info: Loading plugin "airplay_emulation"... May 04 13:19:00 volumio volumio[684]: info: Starting Shairport Sync May 04 13:19:00 volumio volumio[684]: info: Loading plugin "last_100"... May 04 13:19:00 volumio volumio[684]: info: Loading plugin "webradio"... May 04 13:19:00 volumio volumio[684]: info: Loading plugin "i2s_dacs"... May 04 13:19:00 volumio volumio[684]: info: Loading plugin "volumiodiscovery"... May 04 13:19:00 volumio volumio[684]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 04 13:19:00 volumio volumio[684]: *** WARNING *** Please fix your application to use the native API of Avahi! May 04 13:19:00 volumio volumio[684]: *** WARNING *** For more information see May 04 13:19:00 volumio node[684]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 04 13:19:00 volumio volumio[684]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 04 13:19:00 volumio volumio[684]: *** WARNING *** Please fix your application to use the native API of Avahi! May 04 13:19:00 volumio volumio[684]: *** WARNING *** For more information see May 04 13:19:00 volumio node[684]: *** WARNING *** Please fix your application to use the native API of Avahi! May 04 13:19:00 volumio node[684]: *** WARNING *** For more information see May 04 13:19:00 volumio node[684]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 04 13:19:00 volumio node[684]: *** WARNING *** Please fix your application to use the native API of Avahi! May 04 13:19:00 volumio node[684]: *** WARNING *** For more information see May 04 13:19:00 volumio volumio[684]: info: Applying required configuration parameters for plugin volumiodiscovery May 04 13:19:00 volumio volumio[684]: info: Discovery: Started advertising with name: Volumio May 04 13:19:00 volumio volumio[684]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 04 13:19:00 volumio volumio[684]: info: Loading plugin "soundcloud"... May 04 13:19:01 volumio volumio[684]: info: Loading plugin "spop"... May 04 13:19:04 volumio volumio[684]: info: Loading plugin "youtube2"... May 04 13:19:09 volumio systemd[1]: systemd-fsckd.service: Succeeded. May 04 13:19:09 volumio volumio-remote-updater[468]: [2025-05-04 13:19:09] [connect] Successful connection May 04 13:19:11 volumio volumio[684]: info: Loading plugin "autostart"... May 04 13:19:12 volumio volumio[684]: info: Applying required configuration parameters for plugin autostart May 04 13:19:12 volumio volumio[684]: info: AutoStart - onVolumioStart - read config.json May 04 13:19:12 volumio volumio[684]: info: Loading plugin "now_playing"... May 04 13:19:15 volumio volumio[684]: info: Loading plugin "outputs"... May 04 13:19:15 volumio volumio[684]: info: Loading plugin "albumart"... May 04 13:19:15 volumio volumio[684]: info: Plugin example_plugin is not enabled May 04 13:19:15 volumio volumio[684]: info: Loading plugin "inputs"... May 04 13:19:15 volumio volumio[684]: info: Loading plugin "updater_comm"... May 04 13:19:15 volumio volumio[684]: info: Plugin mpdemulation is not enabled May 04 13:19:15 volumio volumio[684]: info: Loading plugin "rest_api"... May 04 13:19:15 volumio volumio[684]: info: Loading plugin "websocket"... May 04 13:19:15 volumio volumio[684]: info: Starting Socket.io Server version 2.3.0 May 04 13:19:15 volumio volumio[684]: info: Loading plugin "fusiondsp"... May 04 13:19:16 volumio volumio[684]: Forking 3 albumart workers May 04 13:19:16 volumio volumio[684]: info: Applying required configuration parameters for plugin fusiondsp May 04 13:19:17 volumio volumio[684]: info: Loading plugin "rad357pl"... May 04 13:19:17 volumio volumio[684]: info: Applying required configuration parameters for plugin rad357pl May 04 13:19:17 volumio volumio[684]: info: [1746364757936] [rad357pl] API delay: 5 May 04 13:19:17 volumio volumio[684]: info: Loading plugin "backup_restore"... May 04 13:19:18 volumio volumio[684]: Starting albumart workers May 04 13:19:18 volumio volumio[684]: Starting albumart workers May 04 13:19:18 volumio volumio[684]: Starting albumart workers May 04 13:19:18 volumio volumio[684]: info: Applying required configuration parameters for plugin backup_restore May 04 13:19:18 volumio volumio[684]: info: Loading plugin "Systeminfo"... May 04 13:19:19 volumio volumio[684]: info: Loading i18n strings for locale pl May 04 13:19:19 volumio volumio[684]: Updating browse sources language May 04 13:19:19 volumio volumio[684]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 04 13:19:20 volumio volumio[684]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 04 13:19:20 volumio volumio[684]: info: CoreCommandRouter::initPlayerControls May 04 13:19:20 volumio volumio[684]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 13:19:20 volumio volumio[684]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 13:19:20 volumio volumio[684]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 13:19:20 volumio volumio[684]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 13:19:20 volumio volumio[684]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 13:19:20 volumio volumio[684]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 13:19:20 volumio volumio[684]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 13:19:20 volumio volumio[684]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 13:19:20 volumio volumio[684]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 04 13:19:20 volumio volumio[684]: Express server listening on port 3000 May 04 13:19:20 volumio volumio[684]: [Metrics] WebUI: 30s 757.99ms May 04 13:19:20 volumio volumio[684]: info: CoreStateMachine::resetVolumioState May 04 13:19:20 volumio volumio[684]: info: CoreStateMachine::getcurrentVolume May 04 13:19:20 volumio volumio[684]: info: CoreCommandRouter::volumioRetrievevolume May 04 13:19:20 volumio sudo[944]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 04 13:19:20 volumio sudo[944]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 13:19:20 volumio sudo[944]: pam_unix(sudo:session): session closed for user root May 04 13:19:20 volumio sudo[946]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 04 13:19:20 volumio sudo[946]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 13:19:20 volumio sudo[946]: pam_unix(sudo:session): session closed for user root May 04 13:19:20 volumio volumio[684]: info: Volumio Network Manager: Network status updated: 1 May 04 13:19:20 volumio volumio[684]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan May 04 13:19:20 volumio volumio[684]: wlan0 Interface doesn't support scanning. May 04 13:19:20 volumio volumio[684]: info: Cannot use regular scanning, forcing with ap-force May 04 13:19:20 volumio sudo[953]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force May 04 13:19:21 volumio sudo[953]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 13:19:21 volumio sudo[953]: pam_unix(sudo:session): session closed for user root May 04 13:19:21 volumio volumio[684]: command failed: No such device (-19) May 04 13:19:21 volumio volumio[684]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force May 04 13:19:21 volumio volumio[684]: command failed: No such device (-19) May 04 13:19:21 volumio volumio[684]: info: VolumeController:: Volume=27 Mute =false May 04 13:19:21 volumio volumio[684]: info: CoreStateMachine::pushState May 04 13:19:21 volumio volumio[684]: info: CorePlayQueue::getTrack 0 May 04 13:19:21 volumio volumio[684]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 04 13:19:21 volumio volumio[684]: info: CoreCommandRouter::volumioPushState May 04 13:19:21 volumio volumio[684]: info: CoreStateMachine::updateTrackBlock May 04 13:19:21 volumio volumio[684]: info: CorePlayQueue::getTrackBlock May 04 13:19:21 volumio volumio[684]: info: CoreCommandRouter::volumioRetrievevolume May 04 13:19:21 volumio volumio[684]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 04 13:19:21 volumio volumio[684]: info: Reloading queue from file May 04 13:19:21 volumio volumio[684]: info: Setting Device type: Raspberry PI May 04 13:19:21 volumio volumio[684]: info: CoreStateMachine::setRepeat true single undefined May 04 13:19:21 volumio volumio[684]: info: CoreStateMachine::pushState May 04 13:19:21 volumio volumio[684]: info: CorePlayQueue::getTrack 0 May 04 13:19:21 volumio volumio[684]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 04 13:19:21 volumio volumio[684]: info: CoreCommandRouter::volumioPushState May 04 13:19:21 volumio volumio[684]: info: CoreStateMachine::setRandom null May 04 13:19:21 volumio volumio[684]: info: CoreStateMachine::pushState May 04 13:19:21 volumio volumio[684]: info: CorePlayQueue::getTrack 0 May 04 13:19:21 volumio volumio[684]: info: CoreCommandRouter::volumioPushState May 04 13:19:21 volumio volumio[684]: info: Completed loading Core Plugins May 04 13:19:21 volumio volumio[684]: info: Preparing to generate the ALSA configuration file May 04 13:19:21 volumio volumio[684]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf May 04 13:19:21 volumio volumio[684]: info: Reading ALSA contributions from plugins. May 04 13:19:21 volumio volumio[684]: info: VolumeController:: Volume=27 Mute =false May 04 13:19:21 volumio volumio[684]: info: CoreStateMachine::pushState May 04 13:19:21 volumio volumio[684]: info: CorePlayQueue::getTrack 0 May 04 13:19:21 volumio volumio[684]: info: CoreCommandRouter::volumioPushState May 04 13:19:21 volumio sudo[965]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service May 04 13:19:21 volumio sudo[965]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 13:19:21 volumio volumio[684]: info: Discovery: adding 5db7e8bb-3f75-45d7-89d4-5b4f7e958ced May 04 13:19:21 volumio volumio[684]: info: Discovery: Found device Volumio May 04 13:19:21 volumio volumio[684]: info: CoreCommandRouter::volumioGetState May 04 13:19:21 volumio volumio[684]: info: CorePlayQueue::getTrack 0 May 04 13:19:21 volumio volumio[684]: info: Asound.conf file unchanged, so no further update is needed May 04 13:19:21 volumio volumio[684]: info: Output device has changed, restarting MPD May 04 13:19:21 volumio volumio[684]: info: Output device has changed, restarting Shairport Sync May 04 13:19:21 volumio volumio[684]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 13:19:21 volumio volumio[684]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 04 13:19:21 volumio systemd[1]: Started UPnP Renderer front-end to MPD. May 04 13:19:21 volumio sudo[969]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 04 13:19:21 volumio sudo[969]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 13:19:21 volumio sudo[969]: pam_unix(sudo:session): session closed for user root May 04 13:19:21 volumio sudo[971]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 04 13:19:21 volumio sudo[971]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 13:19:21 volumio sudo[965]: pam_unix(sudo:session): session closed for user root May 04 13:19:21 volumio systemd[1]: Stopping Music Player Daemon... May 04 13:19:21 volumio volumio[684]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 04 13:19:21 volumio volumio[684]: info: ___________ START PLUGINS ___________ May 04 13:19:22 volumio volumio[684]: info: ControllerMpd::onStart: Initializing MPD May 04 13:19:22 volumio volumio[684]: info: Creating MPD Configuration file May 04 13:19:22 volumio volumio[684]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 04 13:19:22 volumio volumio[684]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 04 13:19:22 volumio volumio[684]: info: [1746364762098] CoreMusicLibrary::Adding element Serwery Mediów May 04 13:19:22 volumio volumio[684]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 04 13:19:22 volumio sudo[978]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 04 13:19:22 volumio sudo[978]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 13:19:22 volumio sudo[980]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 04 13:19:22 volumio sudo[980]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 13:19:22 volumio sudo[978]: pam_unix(sudo:session): session closed for user root May 04 13:19:22 volumio volumio[684]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 13:19:22 volumio volumio[684]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 04 13:19:22 volumio systemd[1]: mpd.service: Succeeded. May 04 13:19:22 volumio systemd[1]: Stopped Music Player Daemon. May 04 13:19:22 volumio volumio[684]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 04 13:19:22 volumio volumio[684]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 13:19:22 volumio volumio[684]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 04 13:19:22 volumio systemd[1]: Starting Music Player Daemon... May 04 13:19:22 volumio volumio[684]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 04 13:19:22 volumio volumio[684]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 04 13:19:22 volumio volumio[684]: info: [1746364762383] CoreMusicLibrary::Adding element Last_100 May 04 13:19:22 volumio volumio[684]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 04 13:19:22 volumio volumio[684]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 04 13:19:22 volumio volumio[684]: info: [1746364762390] CoreMusicLibrary::Adding element Webradio May 04 13:19:22 volumio volumio[684]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 04 13:19:22 volumio volumio[684]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 04 13:19:22 volumio volumio[684]: info: Initializing BBC Radios May 04 13:19:22 volumio volumio[684]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 04 13:19:22 volumio volumio[684]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 04 13:19:22 volumio volumio[684]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 04 13:19:22 volumio volumio[684]: info: [1746364762572] CoreMusicLibrary::Adding element SoundCloud May 04 13:19:22 volumio volumio[684]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 04 13:19:22 volumio volumio[684]: Cannot find translation for source SoundCloud May 04 13:19:22 volumio sudo[986]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 04 13:19:22 volumio sudo[986]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 13:19:22 volumio sudo[986]: pam_unix(sudo:session): session closed for user root May 04 13:19:22 volumio volumio[684]: info: Creating Spotify config file May 04 13:19:22 volumio volumio[684]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 13:19:22 volumio volumio[684]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 04 13:19:22 volumio volumio[684]: info: [1746364762820] CoreMusicLibrary::Adding element YouTube2 May 04 13:19:22 volumio volumio[684]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 04 13:19:22 volumio volumio[684]: Cannot find translation for source SoundCloud May 04 13:19:22 volumio volumio[684]: Cannot find translation for source YouTube2 May 04 13:19:22 volumio volumio[684]: info: AutoStart - onStart May 04 13:19:22 volumio volumio[684]: info: [now-playing] ConfigUpdater: config is up to date. May 04 13:19:22 volumio volumio[684]: info: Loading i18n strings for locale pl May 04 13:19:22 volumio volumio[684]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile May 04 13:19:22 volumio volumio[684]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 13:19:22 volumio volumio[684]: info: FusionDsp - mixtype--------------------- Hardware May 04 13:19:22 volumio volumio[684]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 04 13:19:22 volumio volumio[684]: info: [1746364762937] CoreMusicLibrary::Adding element RADIO 357 May 04 13:19:22 volumio volumio[684]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 04 13:19:22 volumio volumio[684]: Cannot find translation for source SoundCloud May 04 13:19:22 volumio volumio[684]: Cannot find translation for source YouTube2 May 04 13:19:22 volumio volumio[684]: Cannot find translation for source RADIO 357 May 04 13:19:22 volumio volumio[684]: info: Volumio Calling Home May 04 13:19:23 volumio systemd[1]: systemd-timedated.service: Succeeded. May 04 13:19:23 volumio volumio[684]: info: Preparing to generate the ALSA configuration file May 04 13:19:23 volumio volumio[684]: info: [now-playing] Fetching API key... May 04 13:19:23 volumio volumio[684]: info: [now-playing] App is listening on port 4004. May 04 13:19:23 volumio volumio[684]: warn: [now-playing] MyBackgroundMonitor is now watching /data/INTERNAL/NowPlayingPlugin/My Backgrounds May 04 13:19:23 volumio volumio[684]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf May 04 13:19:23 volumio volumio[684]: info: Reading ALSA contributions from plugins. May 04 13:19:23 volumio volumio[684]: info: MPD Permissions set May 04 13:19:23 volumio volumio[684]: info: MPD Permissions set May 04 13:19:23 volumio volumio[684]: info: Upmpdcli Daemon Started May 04 13:19:23 volumio volumio[684]: info: Volumio called home May 04 13:19:23 volumio volumio[684]: info: Spotify config file written May 04 13:19:23 volumio sudo[1008]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service May 04 13:19:23 volumio sudo[1008]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 13:19:23 volumio systemd[1]: Started go-librespot Daemon. May 04 13:19:23 volumio go-librespot[1010]: go-librespot daemon starting... May 04 13:19:23 volumio sudo[1008]: pam_unix(sudo:session): session closed for user root May 04 13:19:23 volumio volumio[684]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 May 04 13:19:23 volumio volumio[684]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 13:19:23 volumio volumio[684]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 13:19:23 volumio volumio[684]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 13:19:23 volumio volumio[684]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 13:19:23 volumio volumio[684]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 13:19:23 volumio volumio[684]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 13:19:23 volumio volumio[684]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 13:19:23 volumio volumio[684]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 13:19:24 volumio volumio[684]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 04 13:19:24 volumio volumio[684]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 13:19:24 volumio volumio[684]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 13:19:24 volumio volumio[684]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 13:19:24 volumio volumio[684]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 13:19:24 volumio volumio[684]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 13:19:24 volumio volumio[684]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 13:19:24 volumio volumio[684]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 13:19:24 volumio volumio[684]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 13:19:24 volumio volumio[684]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 13:19:24 volumio volumio[684]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 04 13:19:24 volumio volumio[684]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 13:19:24 volumio volumio[684]: info: No need to fix Spotify hosts May 04 13:19:24 volumio go-librespot[1010]: time="2025-05-04T13:19:24Z" level=info msg="running go-librespot 0.2.0" May 04 13:19:24 volumio go-librespot[1010]: time="2025-05-04T13:19:24Z" level=debug msg="app state loaded" May 04 13:19:24 volumio go-librespot[1010]: time="2025-05-04T13:19:24Z" level=info msg="api server listening on 127.0.0.1:9879" May 04 13:19:24 volumio volumio-remote-updater[468]: [2025-05-04 13:19:24] [connect] Successful connection May 04 13:19:24 volumio volumio[684]: info: Starting Shairport Sync May 04 13:19:24 volumio volumio[684]: info: Starting Shairport Sync May 04 13:19:24 volumio sudo[1026]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 04 13:19:24 volumio sudo[1026]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 13:19:24 volumio volumio[684]: info: Starting Shairport Sync May 04 13:19:24 volumio volumio[684]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ May 04 13:19:24 volumio volumio[684]: SPOTIFY: BQD_-F8ruZCfNTLqLlp3dhMooHqbYfdQKKkDJJOOstbT-FTwLDEXxEQfTJY3pzaY4IGmeoFlNaQEEblFCCw7AsBArhrDcASuVajcAMq4YXD-kq0yaeNKaLMWxj_tOd9o4p45t_IOJdAwcBkiNkkxGtlKj1cCEbYnW8aRlmAGNQYNGn6CThqE9-DMKXGzToBCFzDcesN-v2F-3V8obs2_UM8Ny8zNjQYNZ2YLcVG9tA7yH8v1fpoO_cw52yJ4r6ivKWPmhZNRrqNQuVQoHac May 04 13:19:24 volumio volumio[684]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ May 04 13:19:24 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 04 13:19:24 volumio systemd[1]: shairport-sync.service: Succeeded. May 04 13:19:24 volumio sudo[1028]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 04 13:19:24 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 04 13:19:24 volumio sudo[1028]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 13:19:24 volumio volumio[684]: info: New Spotify access token = BQD_-F8ruZCfNTLqLlp3dhMooHqbYfdQKKkDJJOOstbT-FTwLDEXxEQfTJY3pzaY4IGmeoFlNaQEEblFCCw7AsBArhrDcASuVajcAMq4YXD-kq0yaeNKaLMWxj_tOd9o4p45t_IOJdAwcBkiNkkxGtlKj1cCEbYnW8aRlmAGNQYNGn6CThqE9-DMKXGzToBCFzDcesN-v2F-3V8obs2_UM8Ny8zNjQYNZ2YLcVG9tA7yH8v1fpoO_cw52yJ4r6ivKWPmhZNRrqNQuVQoHac May 04 13:19:24 volumio volumio[684]: info: Spotify credentials grant success - running version from March 24, 2019 May 04 13:19:24 volumio sudo[1031]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 04 13:19:24 volumio sudo[1031]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 13:19:24 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 04 13:19:24 volumio volumio[684]: info: CoreCommandRouter::volumioGetState May 04 13:19:24 volumio volumio[684]: info: CorePlayQueue::getTrack 0 May 04 13:19:24 volumio sudo[1026]: pam_unix(sudo:session): session closed for user root May 04 13:19:25 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 04 13:19:25 volumio systemd[1]: shairport-sync.service: Succeeded. May 04 13:19:25 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 04 13:19:25 volumio volumio-remote-updater[468]: [2025-05-04 13:19:25] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1746364764 101 May 04 13:19:25 volumio volumio[684]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 2 May 04 13:19:25 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 04 13:19:25 volumio sudo[1031]: pam_unix(sudo:session): session closed for user root May 04 13:19:25 volumio sudo[1028]: pam_unix(sudo:session): session closed for user root May 04 13:19:25 volumio volumio[684]: info: camilladsp service started and running in background, instance 1 May 04 13:19:25 volumio volumio[684]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 13:19:25 volumio volumio[684]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found May 04 13:19:25 volumio volumio[684]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json May 04 13:19:25 volumio volumio[684]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found May 04 13:19:25 volumio volumio[684]: info: FusionDsp loaded May 04 13:19:25 volumio volumio[684]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 04 13:19:25 volumio sudo[1050]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service May 04 13:19:25 volumio go-librespot[1010]: time="2025-05-04T13:19:25Z" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" May 04 13:19:25 volumio go-librespot[1010]: time="2025-05-04T13:19:25Z" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" May 04 13:19:25 volumio go-librespot[1010]: time="2025-05-04T13:19:25Z" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" May 04 13:19:25 volumio sudo[1050]: pam_unix(sudo:session): session opened for user root by (uid=0) May 04 13:19:25 volumio go-librespot[1010]: time="2025-05-04T13:19:25Z" level=info msg="zeroconf server listening on port 44703" May 04 13:19:25 volumio volumio[684]: info: FusionDsp - Reporting Fusion DSP Enabled May 04 13:19:25 volumio volumio[684]: info: Adding Signal Path Element [object Object] May 04 13:19:25 volumio volumio[684]: info: Adding fusiondspeq DSP Signal Path Element May 04 13:19:25 volumio volumio[684]: info: FusionDsp - ---- installed callbackRead May 04 13:19:25 volumio volumio[684]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 04 13:19:25 volumio volumio[684]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT May 04 13:19:25 volumio volumio[684]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) May 04 13:19:25 volumio volumio[684]: at onErrorNT (internal/child_process.js:465:16) May 04 13:19:25 volumio volumio[684]: at processTicksAndRejections (internal/process/task_queues.js:80:21) May 04 13:19:25 volumio volumio[684]: at runNextTicks (internal/process/task_queues.js:62:3) May 04 13:19:25 volumio volumio[684]: at listOnTimeout (internal/timers.js:523:9) May 04 13:19:25 volumio volumio[684]: at processTimers (internal/timers.js:497:7) { May 04 13:19:25 volumio volumio[684]: errno: -2, May 04 13:19:25 volumio volumio[684]: code: 'ENOENT', May 04 13:19:25 volumio volumio[684]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', May 04 13:19:25 volumio volumio[684]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', May 04 13:19:25 volumio volumio[684]: spawnargs: [ May 04 13:19:25 volumio volumio[684]: '-p', May 04 13:19:25 volumio volumio[684]: 9876, May 04 13:19:25 volumio volumio[684]: '-o', May 04 13:19:25 volumio volumio[684]: '/tmp/camilladsp.log', May 04 13:19:25 volumio volumio[684]: '-l', May 04 13:19:25 volumio volumio[684]: 'warn', May 04 13:19:25 volumio volumio[684]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' May 04 13:19:25 volumio volumio[684]: ] May 04 13:19:25 volumio volumio[684]: } May 04 13:19:25 volumio volumio[684]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 04 13:19:25 volumio systemd[1]: Started FusionDsp Daemon. May 04 13:19:25 volumio go-librespot[1010]: time="2025-05-04T13:19:25Z" level=debug msg="obtained new client token: AAD7xl7Kqry2OmFPcPRFMOeAbYtDCufuuKTYsoQmR6wOFtzJSh874XXNZTTvJREgahAIwNv3jhz/hNbAGAV9oA8NJymPf+6ENO3RHwW8eRPvnOxjKtc09ANQpMVPrNZlfIh295lzTU8xG917tWEOMJhl9N+ICq7r/K9054lBM39/fcs9P/wmIuM6//CRzivy6A/bl8WaQQnUkoTZ4jZAbQo83Se3lxTj0wqIQNt3kI6GpY6UgBYxpw==" May 04 13:19:25 volumio sudo[1050]: pam_unix(sudo:session): session closed for user root May 04 13:19:25 volumio go-librespot[1010]: time="2025-05-04T13:19:25Z" level=debug msg="connected to ap-gew4.spotify.com:4070" May 04 13:19:25 volumio go-librespot[1010]: time="2025-05-04T13:19:25Z" level=debug msg="completed keyexchange" May 04 13:19:25 volumio go-librespot[1010]: time="2025-05-04T13:19:25Z" level=debug msg="completed challenge" May 04 13:19:25 volumio go-librespot[1010]: time="2025-05-04T13:19:25Z" level=info msg="authenticated AP as xavi-pl" May 04 13:19:25 volumio mpd[1001]: May 04 13:19 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 04 13:19:26 volumio go-librespot[1010]: time="2025-05-04T13:19:26Z" level=info msg="authenticated Login5 as xavi-pl" May 04 13:19:26 volumio go-librespot[1010]: time="2025-05-04T13:19:26Z" level=debug msg="initializing zeroconf session, username: xavi-pl" May 04 13:19:26 volumio systemd[1]: Started Music Player Daemon. May 04 13:19:26 volumio sudo[971]: pam_unix(sudo:session): session closed for user root May 04 13:19:26 volumio sudo[980]: pam_unix(sudo:session): session closed for user root May 04 13:19:26 volumio go-librespot[1010]: time="2025-05-04T13:19:26Z" level=debug msg="dealer connection opened" May 04 13:19:26 volumio go-librespot[1010]: time="2025-05-04T13:19:26Z" level=trace msg="starting accesspoint recv loop" May 04 13:19:26 volumio go-librespot[1010]: time="2025-05-04T13:19:26Z" level=trace msg="starting dealer recv loop" May 04 13:19:26 volumio go-librespot[1010]: time="2025-05-04T13:19:26Z" level=trace msg="received accesspoint ping" May 04 13:19:26 volumio go-librespot[1010]: time="2025-05-04T13:19:26Z" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" May 04 13:19:26 volumio go-librespot[1010]: time="2025-05-04T13:19:26Z" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" May 04 13:19:26 volumio go-librespot[1010]: time="2025-05-04T13:19:26Z" level=debug msg="received connection id: MDcxODkzMWQtNTMwNC00M2YyLTlmNDEtN2M1ZTFiMmVjOTJmK2RlYWxlcit0Y3A6Ly8wYWNhNTg5Mi5pcC5nZXc0LnNwb3RpZnkubmV0OjU3MDArOEU4MDUyNTMxMjcyQ0UwQjY1RUM1NEIzQjFCREFBQjRBNzA0MTlDOTJEMTk3RThEQUM1QTUzNzE1MTQ1MUE3OA==" May 04 13:19:26 volumio volumio[1054]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory May 04 13:19:26 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT May 04 13:19:26 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. May 04 13:19:26 volumio go-librespot[1010]: time="2025-05-04T13:19:26Z" level=debug msg="put connect state because NEW_DEVICE" May 04 13:19:26 volumio go-librespot[1010]: time="2025-05-04T13:19:26Z" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" May 04 13:19:26 volumio go-librespot[1010]: time="2025-05-04T13:19:26Z" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" May 04 13:19:26 volumio go-librespot[1010]: time="2025-05-04T13:19:26Z" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" May 04 13:19:26 volumio go-librespot[1010]: time="2025-05-04T13:19:26Z" level=trace msg="received accesspoint pong ack" May 04 13:19:26 volumio sudo[1064]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-04 13:18 May 04 13:19:26 volumio sudo[1064]: 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="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="9ab87de061b3649c303da550e73c92d340b0f989" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 24 Apr 2025 09:33:57 AM CEST" VOLUMIO_VERSION="3.804" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="354f95cf0a6c2cbcd57b1aca3179c81c"