-- Logs begin at Thu 2019-02-14 06:11:59 AST, end at Fri 2025-05-09 22:52:33 AST. -- May 09 22:51:11 volumio systemd-timedated[931]: Changed local time to Fri May 9 22:51:11 2025 May 09 22:51:11 volumio sudo[929]: pam_unix(sudo:session): session closed for user root May 09 22:51:11 volumio volumio-time-update[606]: volumio-time-update-util: System time updated successfully. May 09 22:51:11 volumio systemd[1]: Started Volumio Time Update Utility. May 09 22:51:11 volumio ntpd[849]: Listen normally on 3 eth0 10.0.3.5:123 May 09 22:51:11 volumio ntpd[849]: new interface(s) found: waking up resolver May 09 22:51:11 volumio volumio[877]: info: ------------------------------------------- May 09 22:51:11 volumio volumio[877]: info: ----- Volumio3 ---- May 09 22:51:11 volumio volumio[877]: info: ------------------------------------------- May 09 22:51:11 volumio volumio[877]: info: ----- System startup ---- May 09 22:51:11 volumio volumio[877]: info: ------------------------------------------- May 09 22:51:12 volumio nmbd[825]: [2025/05/09 22:51:12.146532, 0] ../lib/util/become_daemon.c:138(daemon_ready) May 09 22:51:12 volumio nmbd[825]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections May 09 22:51:12 volumio systemd[1]: Started Samba NMB Daemon. May 09 22:51:12 volumio systemd[1]: Starting Samba Winbind Daemon... May 09 22:51:12 volumio winbindd[940]: [2025/05/09 22:51:12.541309, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) May 09 22:51:12 volumio winbindd[940]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 May 09 22:51:12 volumio winbindd[940]: [2025/05/09 22:51:12.564121, 0] ../lib/util/become_daemon.c:138(daemon_ready) May 09 22:51:12 volumio systemd[1]: Started Samba Winbind Daemon. May 09 22:51:12 volumio winbindd[940]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections May 09 22:51:12 volumio systemd[1]: Starting Samba SMB Daemon... May 09 22:51:12 volumio volumio[877]: info: MYVOLUMIO Environment detected May 09 22:51:13 volumio smbd[945]: [2025/05/09 22:51:13.213776, 0] ../lib/util/become_daemon.c:138(daemon_ready) May 09 22:51:13 volumio systemd[1]: Started Samba SMB Daemon. May 09 22:51:13 volumio smbd[945]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections May 09 22:51:13 volumio systemd[1]: Reached target Multi-User System. May 09 22:51:13 volumio systemd[1]: Reached target Graphical Interface. May 09 22:51:13 volumio volumio[877]: info: Plugin folders cleanup May 09 22:51:13 volumio volumio[877]: info: Scanning into folder /volumio/app/plugins/ May 09 22:51:13 volumio volumio[877]: info: Scanning category audio_interface May 09 22:51:13 volumio volumio[877]: info: Scanning category miscellanea May 09 22:51:13 volumio volumio[877]: info: Scanning category music_service May 09 22:51:13 volumio volumio[877]: info: Scanning category plugins.json May 09 22:51:13 volumio volumio[877]: info: Scanning category system_controller May 09 22:51:13 volumio volumio[877]: info: Scanning category user_interface May 09 22:51:13 volumio volumio[877]: info: Scanning into folder /data/plugins/ May 09 22:51:13 volumio volumio[877]: info: Scanning category audio_interface May 09 22:51:13 volumio volumio[877]: info: Scanning category music_service May 09 22:51:13 volumio volumio[877]: info: Scanning category system_controller May 09 22:51:13 volumio volumio[877]: info: Scanning category user_interface May 09 22:51:13 volumio volumio[877]: info: Plugin folders cleanup completed May 09 22:51:13 volumio volumio[877]: info: ------------------------------------------- May 09 22:51:13 volumio volumio[877]: info: ----- Core plugins startup ---- May 09 22:51:13 volumio volumio[877]: info: ------------------------------------------- May 09 22:51:13 volumio volumio[877]: info: Loading plugins from folder /volumio/app/plugins/ May 09 22:51:13 volumio volumio[877]: info: Adding plugin upnp to MyMusic Plugins May 09 22:51:13 volumio volumio[877]: info: Adding plugin airplay_emulation to MyMusic Plugins May 09 22:51:13 volumio volumio[877]: info: Adding plugin upnp_browser to MyMusic Plugins May 09 22:51:13 volumio volumio[877]: info: Loading plugins from folder /data/plugins/ May 09 22:51:13 volumio volumio[877]: info: Loading plugin "system"... May 09 22:51:13 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... May 09 22:51:13 volumio volumio[877]: info: Loading plugin "appearance"... May 09 22:51:13 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. May 09 22:51:13 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. May 09 22:51:13 volumio systemd[1]: Startup finished in 13.539s (kernel) + 14.092s (userspace) = 27.631s. May 09 22:51:14 volumio volumio[877]: info: Loading plugin "network"... May 09 22:51:14 volumio volumio[877]: info: Refreshing Cached IP Addresses May 09 22:51:14 volumio sudo[960]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 09 22:51:14 volumio sudo[960]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 22:51:14 volumio volumio[877]: info: Loading plugin "services"... May 09 22:51:14 volumio sudo[960]: pam_unix(sudo:session): session closed for user root May 09 22:51:14 volumio sudo[962]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 09 22:51:14 volumio volumio[877]: info: Loading plugin "alsa_controller"... May 09 22:51:14 volumio sudo[962]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 22:51:14 volumio sudo[962]: pam_unix(sudo:session): session closed for user root May 09 22:51:15 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 09 22:51:15 volumio volumio[877]: info: Loading plugin "wizard"... May 09 22:51:15 volumio volumio[877]: info: Loading plugin "networkfs"... May 09 22:51:15 volumio volumio[877]: info: Starting Udev Watcher for removable devices May 09 22:51:15 volumio sudo[985]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=hgonzale,password=Enigma0113@1+,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //10.0.2.104/Plex_Library\\Music /mnt/NAS/gntruenas-01 May 09 22:51:15 volumio sudo[985]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 22:51:15 volumio volumio[877]: info: Ignoring mount for partition: boot May 09 22:51:15 volumio volumio[877]: info: Ignoring mount for partition: volumio May 09 22:51:15 volumio volumio[877]: info: Ignoring mount for partition: volumio_data May 09 22:51:15 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 09 22:51:15 volumio volumio[877]: info: Loading plugin "volumio_command_line_client"... May 09 22:51:15 volumio volumio[877]: info: Loading plugin "upnp"... May 09 22:51:15 volumio volumio[877]: info: [1746845475275] Starting Upmpd Daemon May 09 22:51:15 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 09 22:51:15 volumio volumio[877]: info: Loading plugin "my_music"... May 09 22:51:15 volumio volumio[877]: info: Loading plugin "mpd"... May 09 22:51:15 volumio kernel: Key type cifs.spnego registered May 09 22:51:15 volumio kernel: Key type cifs.idmap registered May 09 22:51:15 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 09 22:51:15 volumio kernel: CIFS: Attempting to mount //10.0.2.104/Plex_Library/Music May 09 22:51:15 volumio kernel: cryptd: max_cpu_qlen set to 1000 May 09 22:51:15 volumio sudo[985]: pam_unix(sudo:session): session closed for user root May 09 22:51:15 volumio volumio[877]: info: Loading plugin "upnp_browser"... May 09 22:51:16 volumio volumio[877]: info: Loading plugin "alarm-clock"... May 09 22:51:16 volumio volumio[877]: info: Loading plugin "airplay_emulation"... May 09 22:51:16 volumio volumio[877]: info: Starting Shairport Sync May 09 22:51:16 volumio volumio[877]: info: Loading plugin "last_100"... May 09 22:51:16 volumio volumio[877]: info: Loading plugin "webradio"... May 09 22:51:16 volumio volumio[877]: info: Loading plugin "i2s_dacs"... May 09 22:51:16 volumio volumio[877]: info: Loading plugin "volumiodiscovery"... May 09 22:51:16 volumio volumio[877]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 09 22:51:16 volumio volumio[877]: *** WARNING *** Please fix your application to use the native API of Avahi! May 09 22:51:16 volumio volumio[877]: *** WARNING *** For more information see May 09 22:51:16 volumio volumio[877]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 09 22:51:16 volumio node[877]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 09 22:51:16 volumio volumio[877]: *** WARNING *** Please fix your application to use the native API of Avahi! May 09 22:51:16 volumio volumio[877]: *** WARNING *** For more information see May 09 22:51:16 volumio node[877]: *** WARNING *** Please fix your application to use the native API of Avahi! May 09 22:51:16 volumio node[877]: *** WARNING *** For more information see May 09 22:51:16 volumio node[877]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 09 22:51:16 volumio node[877]: *** WARNING *** Please fix your application to use the native API of Avahi! May 09 22:51:16 volumio node[877]: *** WARNING *** For more information see May 09 22:51:16 volumio volumio[877]: info: Applying required configuration parameters for plugin volumiodiscovery May 09 22:51:16 volumio volumio[877]: info: Discovery: Started advertising with name: Volumio May 09 22:51:16 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 09 22:51:16 volumio volumio[877]: info: Loading plugin "mixcloud"... May 09 22:51:17 volumio volumio-remote-updater[587]: [2025-05-09 22:51:17] [connect] Successful connection May 09 22:51:17 volumio volumio[877]: info: Loading plugin "spop"... May 09 22:51:19 volumio volumio[877]: info: Loading plugin "youtube2"... May 09 22:51:23 volumio volumio[877]: info: Loading plugin "ytcr"... May 09 22:51:29 volumio volumio[877]: info: Loading plugin "ytmusic"... May 09 22:51:32 volumio systemd[1]: systemd-fsckd.service: Succeeded. May 09 22:51:32 volumio volumio-remote-updater[587]: [2025-05-09 22:51:32] [connect] Successful connection May 09 22:51:33 volumio volumio[877]: info: Loading plugin "autostart"... May 09 22:51:34 volumio volumio[877]: info: Applying required configuration parameters for plugin autostart May 09 22:51:34 volumio volumio[877]: info: AutoStart - onVolumioStart - read config.json May 09 22:51:34 volumio volumio[877]: info: Loading plugin "outputs"... May 09 22:51:34 volumio volumio[877]: info: Loading plugin "albumart"... May 09 22:51:34 volumio volumio[877]: info: Plugin example_plugin is not enabled May 09 22:51:34 volumio volumio[877]: info: Loading plugin "inputs"... May 09 22:51:34 volumio volumio[877]: info: Loading plugin "updater_comm"... May 09 22:51:34 volumio volumio[877]: info: Plugin mpdemulation is not enabled May 09 22:51:34 volumio volumio[877]: info: Loading plugin "rest_api"... May 09 22:51:34 volumio volumio[877]: info: Loading plugin "websocket"... May 09 22:51:34 volumio volumio[877]: info: Starting Socket.io Server version 2.3.0 May 09 22:51:34 volumio volumio[877]: info: Loading plugin "fusiondsp"... May 09 22:51:34 volumio volumio[877]: Forking 3 albumart workers May 09 22:51:34 volumio volumio[877]: info: Applying required configuration parameters for plugin fusiondsp May 09 22:51:35 volumio volumio[877]: info: Loading plugin "80s80s"... May 09 22:51:35 volumio volumio[877]: Starting albumart workers May 09 22:51:35 volumio volumio[877]: Starting albumart workers May 09 22:51:35 volumio volumio[877]: Starting albumart workers May 09 22:51:35 volumio volumio[877]: info: Applying required configuration parameters for plugin 80s80s May 09 22:51:35 volumio volumio[877]: info: [1746845495741] [80s80s] API delay: 30 May 09 22:51:35 volumio volumio[877]: info: Loading plugin "motherearthradio"... May 09 22:51:36 volumio volumio[877]: info: Applying required configuration parameters for plugin motherearthradio May 09 22:51:36 volumio volumio[877]: info: [1746845496350] [MotherEarth] API delay: 5 May 09 22:51:36 volumio volumio[877]: info: Loading plugin "personal_radio"... May 09 22:51:36 volumio systemd[1]: systemd-hostnamed.service: Succeeded. May 09 22:51:37 volumio volumio[877]: info: ControllerPersonalRadio::constructor May 09 22:51:37 volumio volumio[877]: info: Loading plugin "podcast"... May 09 22:51:37 volumio volumio[877]: info: ControllerPodcast::constructor May 09 22:51:37 volumio volumio[877]: info: Loading plugin "rad357pl"... May 09 22:51:37 volumio volumio[877]: info: Applying required configuration parameters for plugin rad357pl May 09 22:51:37 volumio volumio[877]: info: [1746845497945] [rad357pl] API delay: 5 May 09 22:51:37 volumio volumio[877]: info: Loading plugin "radio_paradise"... May 09 22:51:38 volumio volumio[877]: info: Applying required configuration parameters for plugin radio_paradise May 09 22:51:38 volumio volumio[877]: info: [1746845498276] [RadioParadise] API delay: 5 May 09 22:51:38 volumio volumio[877]: info: Loading plugin "backup_restore"... May 09 22:51:38 volumio volumio[877]: info: Applying required configuration parameters for plugin backup_restore May 09 22:51:38 volumio volumio[877]: info: Loading plugin "peppymeterbasic"... May 09 22:51:39 volumio volumio[877]: info: Loading i18n strings for locale en May 09 22:51:39 volumio volumio[877]: info: Plugin peppymeterbasic has duplicated i18n key APPLY. It is ignored. May 09 22:51:39 volumio volumio[877]: info: Plugin peppymeterbasic has duplicated i18n key SAVE. It is ignored. May 09 22:51:39 volumio volumio[877]: info: Plugin peppymeterbasic has duplicated i18n key CLICK_TO_UPDATE_LIST. It is ignored. May 09 22:51:39 volumio volumio[877]: info: Plugin peppymeterbasic has duplicated i18n key LIST_SUCCESS_UPDATED. It is ignored. May 09 22:51:39 volumio volumio[877]: Updating browse sources language May 09 22:51:39 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 09 22:51:39 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 09 22:51:39 volumio volumio[877]: info: CoreCommandRouter::initPlayerControls May 09 22:51:39 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 22:51:39 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 22:51:39 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 22:51:39 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 22:51:39 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 22:51:39 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 22:51:39 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 22:51:39 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 22:51:39 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 09 22:51:39 volumio volumio[877]: Express server listening on port 3000 May 09 22:51:39 volumio volumio[877]: [Metrics] WebUI: 28s 923.88ms May 09 22:51:39 volumio volumio[877]: info: CoreStateMachine::resetVolumioState May 09 22:51:39 volumio volumio[877]: info: CoreStateMachine::getcurrentVolume May 09 22:51:39 volumio volumio[877]: info: CoreCommandRouter::volumioRetrievevolume May 09 22:51:39 volumio sudo[1118]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 09 22:51:39 volumio sudo[1118]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 22:51:39 volumio sudo[1118]: pam_unix(sudo:session): session closed for user root May 09 22:51:39 volumio sudo[1121]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 09 22:51:39 volumio sudo[1121]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 22:51:39 volumio sudo[1121]: pam_unix(sudo:session): session closed for user root May 09 22:51:39 volumio volumio[877]: info: Volumio Network Manager: Network status updated: 1 May 09 22:51:39 volumio volumio[877]: info: CoreCommandRouter::volumioGetState May 09 22:51:39 volumio volumio[877]: info: CorePlayQueue::getTrack 0 May 09 22:51:39 volumio volumio[877]: info: VolumeController:: Volume=91 Mute =false May 09 22:51:39 volumio volumio[877]: info: CoreStateMachine::pushState May 09 22:51:39 volumio volumio[877]: info: CorePlayQueue::getTrack 0 May 09 22:51:39 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 22:51:39 volumio volumio[877]: info: CoreCommandRouter::volumioPushState May 09 22:51:39 volumio volumio[877]: info: CoreStateMachine::updateTrackBlock May 09 22:51:39 volumio volumio[877]: info: CorePlayQueue::getTrackBlock May 09 22:51:39 volumio volumio[877]: info: CoreCommandRouter::volumioRetrievevolume May 09 22:51:39 volumio volumio[877]: info: Reloading queue from file May 09 22:51:39 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 09 22:51:39 volumio volumio[877]: info: CoreStateMachine::setRepeat null single undefined May 09 22:51:39 volumio volumio[877]: info: CoreStateMachine::pushState May 09 22:51:39 volumio volumio[877]: info: CorePlayQueue::getTrack 0 May 09 22:51:39 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 22:51:39 volumio volumio[877]: info: CoreCommandRouter::volumioPushState May 09 22:51:39 volumio volumio[877]: info: CoreStateMachine::setRandom true May 09 22:51:39 volumio volumio[877]: info: CoreStateMachine::pushState May 09 22:51:39 volumio volumio[877]: info: CorePlayQueue::getTrack 0 May 09 22:51:39 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 22:51:39 volumio volumio[877]: info: CoreCommandRouter::volumioPushState May 09 22:51:39 volumio volumio[877]: info: Setting Device type: Raspberry PI May 09 22:51:39 volumio volumio[877]: info: Listing playlists May 09 22:51:39 volumio volumio[877]: info: Listing playlists May 09 22:51:40 volumio volumio[877]: info: Completed loading Core Plugins May 09 22:51:40 volumio volumio[877]: info: Preparing to generate the ALSA configuration file May 09 22:51:40 volumio volumio[877]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf May 09 22:51:40 volumio volumio[877]: info: The plugin peppymeterbasic has an ALSA contribution file peppy_in.peppy_out.6.conf May 09 22:51:40 volumio volumio[877]: info: Reading ALSA contributions from plugins. May 09 22:51:40 volumio sudo[1132]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service May 09 22:51:40 volumio sudo[1132]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 22:51:40 volumio volumio[877]: info: VolumeController:: Volume=91 Mute =false May 09 22:51:40 volumio volumio[877]: info: CoreStateMachine::pushState May 09 22:51:40 volumio volumio[877]: info: CorePlayQueue::getTrack 0 May 09 22:51:40 volumio volumio[877]: info: CoreCommandRouter::volumioPushState May 09 22:51:40 volumio volumio[877]: info: Discovery: adding 7b26da82-260d-49d7-98f7-2b0f4d86ca3e May 09 22:51:40 volumio volumio[877]: info: Discovery: Found device Volumio May 09 22:51:40 volumio volumio[877]: info: CoreCommandRouter::volumioGetState May 09 22:51:40 volumio volumio[877]: info: CorePlayQueue::getTrack 0 May 09 22:51:40 volumio systemd[1]: Started UPnP Renderer front-end to MPD. May 09 22:51:40 volumio volumio[877]: info: Asound.conf file written May 09 22:51:40 volumio sudo[1132]: pam_unix(sudo:session): session closed for user root May 09 22:51:40 volumio sudo[1136]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf May 09 22:51:40 volumio sudo[1136]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 22:51:40 volumio sudo[1136]: pam_unix(sudo:session): session closed for user root May 09 22:51:40 volumio volumio[877]: info: Output device has changed, restarting MPD May 09 22:51:40 volumio sudo[1141]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 09 22:51:40 volumio sudo[1141]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 22:51:40 volumio sudo[1141]: pam_unix(sudo:session): session closed for user root May 09 22:51:40 volumio volumio[877]: info: Output device has changed, restarting Shairport Sync May 09 22:51:40 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 22:51:40 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 09 22:51:40 volumio sudo[1144]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 09 22:51:40 volumio sudo[1144]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 22:51:40 volumio systemd[1]: Stopping Music Player Daemon... May 09 22:51:40 volumio volumio[877]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 09 22:51:40 volumio volumio[877]: info: ___________ START PLUGINS ___________ May 09 22:51:40 volumio volumio[877]: info: ControllerMpd::onStart: Initializing MPD May 09 22:51:40 volumio volumio[877]: info: Creating MPD Configuration file May 09 22:51:40 volumio sudo[1150]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 09 22:51:40 volumio sudo[1150]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 22:51:40 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 09 22:51:40 volumio sudo[1150]: pam_unix(sudo:session): session closed for user root May 09 22:51:40 volumio volumio[877]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 09 22:51:40 volumio volumio[877]: info: [1746845500536] CoreMusicLibrary::Adding element Media Servers May 09 22:51:40 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 09 22:51:40 volumio sudo[1153]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 09 22:51:40 volumio sudo[1153]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 22:51:40 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 22:51:40 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 09 22:51:40 volumio systemd[1]: mpd.service: Succeeded. May 09 22:51:40 volumio systemd[1]: Stopped Music Player Daemon. May 09 22:51:40 volumio volumio[877]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 09 22:51:40 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 22:51:40 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 09 22:51:40 volumio systemd[1]: Starting Music Player Daemon... May 09 22:51:40 volumio volumio[877]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 09 22:51:40 volumio volumio[877]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 09 22:51:40 volumio volumio[877]: info: [1746845500710] CoreMusicLibrary::Adding element Last_100 May 09 22:51:40 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 09 22:51:40 volumio volumio[877]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 09 22:51:40 volumio volumio[877]: info: [1746845500714] CoreMusicLibrary::Adding element Webradio May 09 22:51:40 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 09 22:51:40 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 09 22:51:40 volumio volumio[877]: info: Initializing BBC Radios May 09 22:51:40 volumio sudo[1158]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 09 22:51:40 volumio sudo[1158]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 22:51:40 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 09 22:51:40 volumio sudo[1158]: pam_unix(sudo:session): session closed for user root May 09 22:51:40 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 09 22:51:40 volumio volumio[877]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 09 22:51:40 volumio volumio[877]: info: [1746845500844] CoreMusicLibrary::Adding element Mixcloud May 09 22:51:40 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 09 22:51:40 volumio volumio[877]: Cannot find translation for source Mixcloud May 09 22:51:40 volumio volumio[877]: info: Creating Spotify config file May 09 22:51:40 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 22:51:41 volumio volumio[877]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 09 22:51:41 volumio volumio[877]: info: [1746845501011] CoreMusicLibrary::Adding element YouTube2 May 09 22:51:41 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 09 22:51:41 volumio volumio[877]: Cannot find translation for source Mixcloud May 09 22:51:41 volumio volumio[877]: Cannot find translation for source YouTube2 May 09 22:51:41 volumio systemd[1]: systemd-timedated.service: Succeeded. May 09 22:51:41 volumio volumio[877]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 09 22:51:41 volumio volumio[877]: info: [1746845501110] CoreMusicLibrary::Adding element YouTube Music May 09 22:51:41 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 09 22:51:41 volumio volumio[877]: Cannot find translation for source Mixcloud May 09 22:51:41 volumio volumio[877]: Cannot find translation for source YouTube2 May 09 22:51:41 volumio volumio[877]: Cannot find translation for source YouTube Music May 09 22:51:41 volumio volumio[877]: info: AutoStart - onStart May 09 22:51:41 volumio volumio[877]: info: Loading i18n strings for locale en May 09 22:51:41 volumio volumio[877]: info: Plugin peppymeterbasic has duplicated i18n key APPLY. It is ignored. May 09 22:51:41 volumio volumio[877]: info: Plugin peppymeterbasic has duplicated i18n key SAVE. It is ignored. May 09 22:51:41 volumio volumio[877]: info: Plugin peppymeterbasic has duplicated i18n key CLICK_TO_UPDATE_LIST. It is ignored. May 09 22:51:41 volumio volumio[877]: info: Plugin peppymeterbasic has duplicated i18n key LIST_SUCCESS_UPDATED. It is ignored. May 09 22:51:41 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile May 09 22:51:41 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 22:51:41 volumio volumio[877]: info: FusionDsp - mixtype--------------------- Hardware May 09 22:51:41 volumio volumio[877]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 09 22:51:41 volumio volumio[877]: info: [1746845501164] CoreMusicLibrary::Adding element 80s80s Radio May 09 22:51:41 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 09 22:51:41 volumio volumio[877]: Cannot find translation for source Mixcloud May 09 22:51:41 volumio volumio[877]: Cannot find translation for source YouTube2 May 09 22:51:41 volumio volumio[877]: Cannot find translation for source YouTube Music May 09 22:51:41 volumio volumio[877]: Cannot find translation for source 80s80s Radio May 09 22:51:41 volumio volumio[877]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 09 22:51:41 volumio volumio[877]: info: [1746845501174] CoreMusicLibrary::Adding element Mother Earth Radio May 09 22:51:41 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 09 22:51:41 volumio volumio[877]: Cannot find translation for source Mixcloud May 09 22:51:41 volumio volumio[877]: Cannot find translation for source YouTube2 May 09 22:51:41 volumio volumio[877]: Cannot find translation for source YouTube Music May 09 22:51:41 volumio volumio[877]: Cannot find translation for source 80s80s Radio May 09 22:51:41 volumio volumio[877]: Cannot find translation for source Mother Earth Radio May 09 22:51:41 volumio volumio[877]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 09 22:51:41 volumio volumio[877]: info: [1746845501200] CoreMusicLibrary::Adding element Personal Radio May 09 22:51:41 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 09 22:51:41 volumio volumio[877]: Cannot find translation for source Mixcloud May 09 22:51:41 volumio volumio[877]: Cannot find translation for source YouTube2 May 09 22:51:41 volumio volumio[877]: Cannot find translation for source YouTube Music May 09 22:51:41 volumio volumio[877]: Cannot find translation for source 80s80s Radio May 09 22:51:41 volumio volumio[877]: Cannot find translation for source Mother Earth Radio May 09 22:51:41 volumio volumio[877]: Cannot find translation for source Personal Radio May 09 22:51:41 volumio volumio[877]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 09 22:51:41 volumio volumio[877]: info: [1746845501215] CoreMusicLibrary::Adding element Podcast May 09 22:51:41 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 09 22:51:41 volumio volumio[877]: Cannot find translation for source Mixcloud May 09 22:51:41 volumio volumio[877]: Cannot find translation for source YouTube2 May 09 22:51:41 volumio volumio[877]: Cannot find translation for source YouTube Music May 09 22:51:41 volumio volumio[877]: Cannot find translation for source 80s80s Radio May 09 22:51:41 volumio volumio[877]: Cannot find translation for source Mother Earth Radio May 09 22:51:41 volumio volumio[877]: Cannot find translation for source Personal Radio May 09 22:51:41 volumio volumio[877]: Cannot find translation for source Podcast May 09 22:51:41 volumio volumio[877]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 09 22:51:41 volumio volumio[877]: info: [1746845501225] CoreMusicLibrary::Adding element RADIO 357 May 09 22:51:41 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 09 22:51:41 volumio volumio[877]: Cannot find translation for source Mixcloud May 09 22:51:41 volumio volumio[877]: Cannot find translation for source YouTube2 May 09 22:51:41 volumio volumio[877]: Cannot find translation for source YouTube Music May 09 22:51:41 volumio volumio[877]: Cannot find translation for source 80s80s Radio May 09 22:51:41 volumio volumio[877]: Cannot find translation for source Mother Earth Radio May 09 22:51:41 volumio volumio[877]: Cannot find translation for source Personal Radio May 09 22:51:41 volumio volumio[877]: Cannot find translation for source Podcast May 09 22:51:41 volumio volumio[877]: Cannot find translation for source RADIO 357 May 09 22:51:41 volumio volumio[877]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 09 22:51:41 volumio volumio[877]: info: [1746845501236] CoreMusicLibrary::Adding element Radio Paradise May 09 22:51:41 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 09 22:51:41 volumio volumio[877]: Cannot find translation for source Mixcloud May 09 22:51:41 volumio volumio[877]: Cannot find translation for source YouTube2 May 09 22:51:41 volumio volumio[877]: Cannot find translation for source YouTube Music May 09 22:51:41 volumio volumio[877]: Cannot find translation for source 80s80s Radio May 09 22:51:41 volumio volumio[877]: Cannot find translation for source Mother Earth Radio May 09 22:51:41 volumio volumio[877]: Cannot find translation for source Personal Radio May 09 22:51:41 volumio volumio[877]: Cannot find translation for source Podcast May 09 22:51:41 volumio volumio[877]: Cannot find translation for source RADIO 357 May 09 22:51:41 volumio volumio[877]: Cannot find translation for source Radio Paradise May 09 22:51:41 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile May 09 22:51:41 volumio volumio[877]: info: Volumio Calling Home May 09 22:51:41 volumio volumio[877]: info: Preparing to generate the ALSA configuration file May 09 22:51:41 volumio volumio[877]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf May 09 22:51:41 volumio volumio[877]: info: The plugin peppymeterbasic has an ALSA contribution file peppy_in.peppy_out.6.conf May 09 22:51:41 volumio volumio[877]: info: Reading ALSA contributions from plugins. May 09 22:51:41 volumio volumio[877]: info: MPD Permissions set May 09 22:51:41 volumio volumio[877]: info: MPD Permissions set May 09 22:51:41 volumio volumio[877]: info: Upmpdcli Daemon Started May 09 22:51:41 volumio volumio[877]: info: Spotify config file written May 09 22:51:41 volumio sudo[1180]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service May 09 22:51:41 volumio sudo[1180]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 22:51:41 volumio volumio[877]: 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 09 22:51:41 volumio volumio[877]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 May 09 22:51:41 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 22:51:41 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 22:51:41 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 22:51:41 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 22:51:41 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 22:51:41 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 22:51:41 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 22:51:41 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 22:51:41 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 09 22:51:41 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 22:51:41 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 09 22:51:41 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 22:51:41 volumio volumio[877]: info: No need to fix Spotify hosts May 09 22:51:41 volumio systemd[1]: Started go-librespot Daemon. May 09 22:51:41 volumio go-librespot[1182]: Librespot-go daemon starting... May 09 22:51:41 volumio sudo[1180]: pam_unix(sudo:session): session closed for user root May 09 22:51:42 volumio volumio[877]: info: Volumio called home May 09 22:51:42 volumio volumio[877]: info: Starting Shairport Sync May 09 22:51:42 volumio volumio[877]: info: Starting Shairport Sync May 09 22:51:42 volumio sudo[1198]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 09 22:51:42 volumio sudo[1198]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 22:51:42 volumio volumio[877]: info: Starting Shairport Sync May 09 22:51:42 volumio go-librespot[1182]: time="2025-05-09T22:51:42-04:00" level=info msg="generated new device id: 95993a55ff79b5938592ce0f8910d88123030621" May 09 22:51:42 volumio go-librespot[1182]: time="2025-05-09T22:51:42-04:00" level=debug msg="stored credentials found for higiniog13" May 09 22:51:42 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 09 22:51:42 volumio sudo[1201]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 09 22:51:42 volumio sudo[1201]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 22:51:42 volumio systemd[1]: shairport-sync.service: Succeeded. May 09 22:51:42 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 09 22:51:42 volumio sudo[1204]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 09 22:51:42 volumio sudo[1204]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 22:51:42 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 09 22:51:42 volumio sudo[1198]: pam_unix(sudo:session): session closed for user root May 09 22:51:42 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 09 22:51:42 volumio systemd[1]: shairport-sync.service: Succeeded. May 09 22:51:42 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 09 22:51:42 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 09 22:51:42 volumio sudo[1201]: pam_unix(sudo:session): session closed for user root May 09 22:51:42 volumio sudo[1204]: pam_unix(sudo:session): session closed for user root May 09 22:51:42 volumio mpd[1173]: May 09 22:51 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 09 22:51:42 volumio systemd[1]: Started Music Player Daemon. May 09 22:51:42 volumio sudo[1153]: pam_unix(sudo:session): session closed for user root May 09 22:51:42 volumio sudo[1144]: pam_unix(sudo:session): session closed for user root May 09 22:51:42 volumio volumio[877]: info: CoreCommandRouter::volumioGetState May 09 22:51:42 volumio volumio[877]: info: CorePlayQueue::getTrack 0 May 09 22:51:42 volumio volumio[877]: (node:877) [DEP0005] DeprecationWarning: Buffer() is deprecated due to security and usability issues. Please use the Buffer.alloc(), Buffer.allocUnsafe(), or Buffer.from() methods instead. May 09 22:51:42 volumio volumio[877]: (Use `node --trace-deprecation ...` to show where the warning was created) May 09 22:51:42 volumio volumio[877]: (node:877) [DEP0106] DeprecationWarning: crypto.createDecipher is deprecated. May 09 22:51:42 volumio volumio[877]: Upnp client error: Error: This socket has been ended by the other party May 09 22:51:42 volumio volumio[877]: info: Shairport-Sync Started May 09 22:51:42 volumio volumio[877]: Error adding Membership: Error: addMembership EINVAL May 09 22:51:42 volumio volumio[877]: info: Shairport-Sync Started May 09 22:51:42 volumio volumio[877]: info: Shairport-Sync Started May 09 22:51:42 volumio volumio[877]: info: Asound.conf file unchanged, so no further update is needed May 09 22:51:42 volumio volumio[877]: info: Output device has changed, restarting MPD May 09 22:51:42 volumio go-librespot[1182]: time="2025-05-09T22:51:42-04:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" May 09 22:51:42 volumio go-librespot[1182]: time="2025-05-09T22:51:42-04:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" May 09 22:51:42 volumio go-librespot[1182]: time="2025-05-09T22:51:42-04:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" May 09 22:51:42 volumio sudo[1218]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 09 22:51:42 volumio sudo[1218]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 22:51:42 volumio go-librespot[1182]: time="2025-05-09T22:51:42-04:00" level=debug msg="zeroconf server listening on port 44991" May 09 22:51:42 volumio volumio[877]: info: Output device has changed, restarting Shairport Sync May 09 22:51:42 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 22:51:42 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 09 22:51:42 volumio sudo[1218]: pam_unix(sudo:session): session closed for user root May 09 22:51:42 volumio sudo[1221]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 09 22:51:42 volumio sudo[1221]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 22:51:42 volumio systemd[1]: Stopping Music Player Daemon... May 09 22:51:42 volumio volumio[877]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 09 22:51:42 volumio volumio[877]: info: Preparing to generate the ALSA configuration file May 09 22:51:42 volumio systemd[1]: mpd.service: Succeeded. May 09 22:51:42 volumio systemd[1]: Stopped Music Player Daemon. May 09 22:51:43 volumio volumio[877]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf May 09 22:51:43 volumio volumio[877]: info: The plugin peppymeterbasic has an ALSA contribution file peppy_in.peppy_out.6.conf May 09 22:51:43 volumio volumio[877]: info: Reading ALSA contributions from plugins. May 09 22:51:43 volumio systemd[1]: Starting Music Player Daemon... May 09 22:51:43 volumio volumio[877]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ May 09 22:51:43 volumio volumio[877]: SPOTIFY: BQDe9kF52V8lBLvyoh4388Tdyv7d50L1X7KGQOD3eJug_w_R9Hn5KOetwT5LHiJBcNmJtY0E3YdKwbO1LI4rjvVi17I5lyk5MT-ne3P5sv7w6fnWfjiBPK88-jkB-DginhZavCs_4Jg0gsnHM2zwQcJAiy5PKTNZetLqiSw4s6lsJlG_J8QJTM5xei56fRniDi8l-G-sOTSBZ0dRA101Jprr2-sA8-7WMvGYRgGAp6SqtxSouftCdWjJ-9wHKsLVSrZGPnSUUmBfox8ezHMg36r4dZY May 09 22:51:43 volumio volumio[877]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ May 09 22:51:43 volumio volumio[877]: info: New Spotify access token = BQDe9kF52V8lBLvyoh4388Tdyv7d50L1X7KGQOD3eJug_w_R9Hn5KOetwT5LHiJBcNmJtY0E3YdKwbO1LI4rjvVi17I5lyk5MT-ne3P5sv7w6fnWfjiBPK88-jkB-DginhZavCs_4Jg0gsnHM2zwQcJAiy5PKTNZetLqiSw4s6lsJlG_J8QJTM5xei56fRniDi8l-G-sOTSBZ0dRA101Jprr2-sA8-7WMvGYRgGAp6SqtxSouftCdWjJ-9wHKsLVSrZGPnSUUmBfox8ezHMg36r4dZY May 09 22:51:43 volumio volumio[877]: info: Spotify credentials grant success - running version from March 24, 2019 May 09 22:51:43 volumio volumio[877]: error: MPD error: Error: write EPIPE May 09 22:51:43 volumio volumio[877]: error: write EPIPE {"errno":-32,"code":"EPIPE","syscall":"write"} May 09 22:51:43 volumio go-librespot[1182]: time="2025-05-09T22:51:43-04:00" level=debug msg="obtained new client token: AACNaZp4382WSH5+WadzAOoZCOAWdfOuAcYbqxqqV4L/pSQcWKmFZvVBtvNMrpezDLwhxHYIL5SiwXPnyoRxDTvCkQSnBqIHmbdVeowDpa63wMsY+ny+oFau60dvGZXX3bui7KcDvG4oEMPxf7GSUWWN2J4QKOCjTmVX72hdIQrnapX/rT7DYHPrd/VhQXZL0U2gG0fTB5u6OvzOJMwddNEXZEjz9X+CuFStL0b/0hXow2CEWM6gAgLQx7Rqhw==" May 09 22:51:43 volumio volumio[877]: error: MPD error: The expression evaluated to a falsy value: May 09 22:51:43 volumio volumio[877]: assert.ok(self.idling) May 09 22:51:43 volumio volumio[877]: error: The expression evaluated to a falsy value: May 09 22:51:43 volumio volumio[877]: assert.ok(self.idling) May 09 22:51:43 volumio volumio[877]: error: MPD error: Error: write EPIPE May 09 22:51:43 volumio volumio[877]: error: write EPIPE {"errno":-32,"code":"EPIPE","syscall":"write"} May 09 22:51:43 volumio sudo[1226]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 09 22:51:43 volumio sudo[1226]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 22:51:43 volumio sudo[1226]: pam_unix(sudo:session): session closed for user root May 09 22:51:43 volumio volumio[877]: info: MPD Permissions set May 09 22:51:43 volumio volumio[877]: info: MPD running with PID1173 May 09 22:51:43 volumio volumio[877]: ,establishing connection May 09 22:51:43 volumio go-librespot[1182]: time="2025-05-09T22:51:43-04:00" level=info msg="connected to ap-guc3.spotify.com:4070" May 09 22:51:43 volumio volumio[877]: info: camilladsp service started and running in background, instance 1 May 09 22:51:43 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 22:51:43 volumio go-librespot[1182]: time="2025-05-09T22:51:43-04:00" level=debug msg="completed keyexchange" May 09 22:51:43 volumio volumio[877]: info: FusionDsp - AAAAAAAAAAAAAA-> 22050 44100 48000 88200 96000 176400 192000 384000 <-AAAAAAAAAAAAA May 09 22:51:43 volumio volumio[877]: info: FusionDsp loaded May 09 22:51:43 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 09 22:51:43 volumio sudo[1239]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service May 09 22:51:43 volumio sudo[1239]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 22:51:43 volumio volumio[877]: info: FusionDsp - Reporting Fusion DSP Enabled May 09 22:51:43 volumio volumio[877]: info: Adding Signal Path Element [object Object] May 09 22:51:43 volumio volumio[877]: info: Adding fusiondspeq DSP Signal Path Element May 09 22:51:43 volumio volumio[877]: info: FusionDsp - ---- installed callbackRead May 09 22:51:43 volumio volumio[877]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 09 22:51:43 volumio volumio[877]: info: FusionDsp - crossfeed jameier May 09 22:51:43 volumio volumio[877]: info: FusionDsp - Loudness is ON true May 09 22:51:43 volumio systemd[1]: Started FusionDsp Daemon. May 09 22:51:43 volumio sudo[1239]: pam_unix(sudo:session): session closed for user root May 09 22:51:43 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 22:51:43 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 09 22:51:43 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 22:51:43 volumio volumio[877]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 May 09 22:51:43 volumio volumio[877]: info: FusionDsp - {"Reload":{"result":"Ok"}} May 09 22:51:43 volumio volumio[877]: info: Starting Shairport Sync May 09 22:51:43 volumio go-librespot[1182]: time="2025-05-09T22:51:43-04:00" level=debug msg="completed challenge" May 09 22:51:43 volumio sudo[1255]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 09 22:51:43 volumio sudo[1255]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 22:51:43 volumio volumio[877]: info: Asound.conf file unchanged, so no further update is needed May 09 22:51:43 volumio volumio[877]: info: Output device has changed, restarting MPD May 09 22:51:43 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 09 22:51:43 volumio systemd[1]: shairport-sync.service: Succeeded. May 09 22:51:43 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 09 22:51:43 volumio sudo[1258]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 09 22:51:43 volumio sudo[1258]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 22:51:43 volumio go-librespot[1182]: time="2025-05-09T22:51:43-04:00" level=debug msg="authenticated as higiniog13" May 09 22:51:43 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 09 22:51:43 volumio volumio[877]: info: Output device has changed, restarting Shairport Sync May 09 22:51:43 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 22:51:43 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 09 22:51:43 volumio sudo[1258]: pam_unix(sudo:session): session closed for user root May 09 22:51:43 volumio sudo[1255]: pam_unix(sudo:session): session closed for user root May 09 22:51:43 volumio sudo[1262]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 09 22:51:43 volumio sudo[1262]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 22:51:43 volumio volumio[877]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 09 22:51:44 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM May 09 22:51:44 volumio systemd[1]: mpd.service: Succeeded. May 09 22:51:44 volumio systemd[1]: Stopped Music Player Daemon. May 09 22:51:44 volumio systemd[1]: Starting Music Player Daemon... May 09 22:51:44 volumio volumio[877]: info: MPD Permissions set May 09 22:51:44 volumio volumio[877]: info: Shairport-Sync Started May 09 22:51:44 volumio volumio[877]: info: CoreCommandRouter::volumioGetState May 09 22:51:44 volumio volumio[877]: info: CorePlayQueue::getTrack 0 May 09 22:51:44 volumio go-librespot[1182]: time="2025-05-09T22:51:44-04:00" level=debug msg="authenticated as higiniog13" May 09 22:51:44 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 22:51:44 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 22:51:44 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 22:51:44 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 22:51:44 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 22:51:44 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 22:51:44 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 09 22:51:44 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 22:51:44 volumio volumio[877]: SPOTIFY: User informations: {"country":"DO","display_name":"higiniog13","email":"higiniog13@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/higiniog13"},"followers":{"href":null,"total":1},"href":"https://api.spotify.com/v1/users/higiniog13","id":"higiniog13","images":[],"product":"premium","type":"user","uri":"spotify:user:higiniog13"} May 09 22:51:44 volumio volumio[877]: info: Spotify Successfully logged in May 09 22:51:44 volumio volumio[877]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 09 22:51:44 volumio volumio[877]: info: [1746845504200] CoreMusicLibrary::Adding element Spotify May 09 22:51:44 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 09 22:51:44 volumio volumio[877]: Cannot find translation for source Mixcloud May 09 22:51:44 volumio volumio[877]: Cannot find translation for source YouTube2 May 09 22:51:44 volumio volumio[877]: Cannot find translation for source YouTube Music May 09 22:51:44 volumio volumio[877]: Cannot find translation for source 80s80s Radio May 09 22:51:44 volumio volumio[877]: Cannot find translation for source Mother Earth Radio May 09 22:51:44 volumio volumio[877]: Cannot find translation for source Personal Radio May 09 22:51:44 volumio volumio[877]: Cannot find translation for source Podcast May 09 22:51:44 volumio volumio[877]: Cannot find translation for source RADIO 357 May 09 22:51:44 volumio volumio[877]: Cannot find translation for source Radio Paradise May 09 22:51:44 volumio volumio[877]: Cannot find translation for source Spotify May 09 22:51:44 volumio sudo[1272]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 09 22:51:44 volumio sudo[1272]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 22:51:44 volumio sudo[1272]: pam_unix(sudo:session): session closed for user root May 09 22:51:44 volumio volumio[877]: info: Starting Shairport Sync May 09 22:51:44 volumio go-librespot[1182]: time="2025-05-09T22:51:44-04:00" level=debug msg="dealer connection opened" May 09 22:51:44 volumio go-librespot[1182]: time="2025-05-09T22:51:44-04:00" level=debug msg="initializing zeroconf session, username: higiniog13" May 09 22:51:44 volumio go-librespot[1182]: time="2025-05-09T22:51:44-04:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" May 09 22:51:44 volumio go-librespot[1182]: time="2025-05-09T22:51:44-04:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" May 09 22:51:44 volumio go-librespot[1182]: time="2025-05-09T22:51:44-04:00" level=debug msg="received connection id: MDJkMmYzMWQtMTdjYi00MjQ3LWE5OGYtZDU3OTZjZWI3NjgyK2RlYWxlcit0Y3A6Ly8wYWNiZGE4OS5pcC5ndWMzLnNwb3RpZnkubmV0OjU3MDArNjVGNjkyMzRFRTAzNUQwNUZFMTZDRTdGNDU5QTA3RjE1MDk1MTY3MUYxNDIxRTg5Qzk4NDUxNTZBQzVDNTA0QQ==" May 09 22:51:44 volumio sudo[1280]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 09 22:51:44 volumio sudo[1280]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 22:51:44 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 09 22:51:44 volumio systemd[1]: shairport-sync.service: Succeeded. May 09 22:51:44 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 09 22:51:44 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 09 22:51:44 volumio sudo[1280]: pam_unix(sudo:session): session closed for user root May 09 22:51:44 volumio volumio[877]: info: Shairport-Sync Started May 09 22:51:44 volumio go-librespot[1182]: time="2025-05-09T22:51:44-04:00" level=debug msg="put connect state because NEW_DEVICE" May 09 22:51:44 volumio go-librespot[1182]: time="2025-05-09T22:51:44-04:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" May 09 22:51:44 volumio go-librespot[1182]: time="2025-05-09T22:51:44-04:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" May 09 22:51:44 volumio go-librespot[1182]: time="2025-05-09T22:51:44-04:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" May 09 22:51:44 volumio go-librespot[1182]: time="2025-05-09T22:51:44-04:00" level=debug msg="autoplay enabled: false" May 09 22:51:44 volumio volumio[877]: info: [yt-cast-receiver] DIAL server listening on port 8098 May 09 22:51:44 volumio volumio[877]: info: CoreCommandRouter::volumioRetrievevolume May 09 22:51:45 volumio volumio[877]: info: go-librespot daemon successfully initialized May 09 22:51:45 volumio volumio[877]: info: VolumeController:: Volume=91 Mute =false May 09 22:51:45 volumio volumio[877]: info: CoreCommandRouter::volumioGetState May 09 22:51:45 volumio volumio[877]: info: CorePlayQueue::getTrack 0 May 09 22:51:45 volumio volumio[877]: info: CoreStateMachine::pushState May 09 22:51:45 volumio volumio[877]: info: CorePlayQueue::getTrack 0 May 09 22:51:45 volumio volumio[877]: info: CoreCommandRouter::volumioPushState May 09 22:51:45 volumio volumio[877]: info: PeppyMeterBasic ---peppymeterbasic status stop May 09 22:51:45 volumio volumio[877]: info: FusionDsp - volume level for loudness 91 gain applied 0.00 May 09 22:51:45 volumio volumio[877]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 09 22:51:45 volumio volumio[877]: info: FusionDsp - crossfeed jameier May 09 22:51:45 volumio volumio[877]: info: FusionDsp - Loudness is ON true May 09 22:51:45 volumio sudo[1288]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service May 09 22:51:45 volumio sudo[1288]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 22:51:45 volumio volumio[877]: info: FusionDsp - {"Reload":{"result":"Ok"}} May 09 22:51:45 volumio sudo[1288]: pam_unix(sudo:session): session closed for user root May 09 22:51:45 volumio sudo[1302]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 09 22:51:45 volumio sudo[1302]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 22:51:45 volumio sudo[1302]: pam_unix(sudo:session): session closed for user root May 09 22:51:45 volumio sudo[1308]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 09 22:51:45 volumio sudo[1308]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 22:51:45 volumio sudo[1308]: pam_unix(sudo:session): session closed for user root May 09 22:51:45 volumio volumio[877]: info: peppymeterbasic Daemon Stop May 09 22:51:45 volumio volumio[877]: verbose: New Socket.io Connection to 10.0.3.5 from 10.0.2.61 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_5 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.5 Mobile/15E148 Safari/604.1 Engine version: 3 Transport: polling Total Clients: 4 May 09 22:51:45 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 09 22:51:45 volumio volumio[877]: info: CoreCommandRouter::volumioGetVisibleSources May 09 22:51:45 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 09 22:51:45 volumio volumio[877]: info: CoreCommandRouter::volumioGetState May 09 22:51:45 volumio volumio[877]: info: CorePlayQueue::getTrack 0 May 09 22:51:45 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 09 22:51:45 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 09 22:51:45 volumio volumio[877]: info: Received Get System Info May 09 22:51:45 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 09 22:51:45 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 09 22:51:45 volumio volumio[877]: info: Discovery: Getting this device information May 09 22:51:45 volumio volumio[877]: info: CoreCommandRouter::volumioGetState May 09 22:51:45 volumio volumio[877]: info: CorePlayQueue::getTrack 0 May 09 22:51:45 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 09 22:51:45 volumio volumio[877]: info: CoreCommandRouter::volumioGetState May 09 22:51:45 volumio volumio[877]: info: CorePlayQueue::getTrack 0 May 09 22:51:45 volumio volumio[877]: info: Listing playlists May 09 22:51:45 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 09 22:51:45 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 09 22:51:45 volumio volumio[877]: info: CoreCommandRouter::getUIConfigOnPlugin May 09 22:51:45 volumio volumio[877]: info: FusionDsp - May 09 22:51:45 volumio volumio[877]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json May 09 22:51:46 volumio mpd[1278]: May 09 22:51 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 09 22:51:46 volumio systemd[1]: Started Music Player Daemon. May 09 22:51:46 volumio sudo[1221]: pam_unix(sudo:session): session closed for user root May 09 22:51:46 volumio sudo[1262]: pam_unix(sudo:session): session closed for user root May 09 22:51:46 volumio volumio[877]: error: updateQueue error: null May 09 22:51:46 volumio volumio[877]: error: MPD error: The expression evaluated to a falsy value: May 09 22:51:46 volumio volumio[877]: assert.ok(self.idling) May 09 22:51:46 volumio volumio[877]: error: The expression evaluated to a falsy value: May 09 22:51:46 volumio volumio[877]: assert.ok(self.idling) May 09 22:51:46 volumio volumio[877]: error: updateQueue error: null May 09 22:51:46 volumio volumio[1134]: Generating RSA private key, 4096 bit long modulus (2 primes) May 09 22:51:46 volumio volumio[877]: info: Completed starting Core Plugins May 09 22:51:46 volumio volumio[877]: info: ------------------------------------------- May 09 22:51:46 volumio volumio[877]: info: ----- MyVolumio plugins startup ---- May 09 22:51:46 volumio volumio[877]: info: ------------------------------------------- May 09 22:51:46 volumio volumio[877]: info: [MyVolumio PluginManager] Fetching plans data.... May 09 22:51:47 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard May 09 22:51:47 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 09 22:51:47 volumio volumio[877]: info: Received Get System Info May 09 22:51:47 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 09 22:51:47 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 09 22:51:47 volumio volumio[877]: info: Discovery: Getting this device information May 09 22:51:47 volumio volumio[877]: info: CoreCommandRouter::volumioGetState May 09 22:51:47 volumio volumio[877]: info: CorePlayQueue::getTrack 0 May 09 22:51:47 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 09 22:51:47 volumio volumio[1134]: .....++++ May 09 22:51:47 volumio volumio-remote-updater[587]: [2025-05-09 22:51:47] [connect] Successful connection May 09 22:51:47 volumio volumio-remote-updater[587]: [2025-05-09 22:51:47] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1746845507 101 May 09 22:51:47 volumio volumio[877]: 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: 5 May 09 22:51:47 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 09 22:51:47 volumio volumio[877]: info: Received Get System Info May 09 22:51:47 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 09 22:51:47 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 09 22:51:47 volumio volumio[877]: info: Discovery: Getting this device information May 09 22:51:47 volumio volumio[877]: info: CoreCommandRouter::volumioGetState May 09 22:51:47 volumio volumio[877]: info: CorePlayQueue::getTrack 0 May 09 22:51:47 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 09 22:51:48 volumio volumio[877]: info: Initializing connection to go-librespot Websocket May 09 22:51:48 volumio volumio[877]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory May 09 22:51:48 volumio go-librespot[1182]: time="2025-05-09T22:51:48-04:00" level=debug msg="new websocket client" May 09 22:51:48 volumio volumio[877]: info: Connection to go-librespot Websocket established May 09 22:51:48 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken May 09 22:51:48 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken May 09 22:51:51 volumio volumio[877]: info: Getting Spotify volume May 09 22:51:51 volumio volumio[877]: info: Spotify volume: 100 May 09 22:51:51 volumio volumio[877]: 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 09 22:51:51 volumio volumio[877]: info: CoreCommandRouter::volumioGetState May 09 22:51:51 volumio volumio[877]: info: CorePlayQueue::getTrack 0 May 09 22:51:51 volumio volumio[877]: SPOTIFY: RECEIVED VOLUMIO VOLUME 91 May 09 22:51:51 volumio volumio[877]: SPOTIFY: SPOTIFY VOLUME 100 May 09 22:51:51 volumio volumio[877]: SPOTIFY: VOLUMIO VOLUME 91 May 09 22:51:51 volumio volumio[877]: SPOTIFY: DELTA VOLUME ENOUGH: true May 09 22:51:51 volumio volumio[877]: info: Setting Spotify Volume from Volumio: 91 May 09 22:51:52 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 09 22:51:52 volumio volumio[877]: info: CoreCommandRouter::getUIConfigOnPlugin May 09 22:51:52 volumio volumio[877]: info: FusionDsp - May 09 22:51:52 volumio volumio[877]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json May 09 22:51:52 volumio volumio[877]: SPOTIFY: SETTING SPOTIFY VOLUME 91 May 09 22:51:52 volumio volumio[877]: info: Sending Spotify command with payload to local API: /player/volume May 09 22:51:52 volumio go-librespot[1182]: time="2025-05-09T22:51:52-04:00" level=debug msg="update volume to 59636/65535" May 09 22:51:52 volumio go-librespot[1182]: time="2025-05-09T22:51:52-04:00" level=debug msg="put connect state because VOLUME_CHANGED" May 09 22:51:52 volumio go-librespot[1182]: time="2025-05-09T22:51:52-04:00" level=trace msg="emitting websocket event: volume" May 09 22:51:52 volumio volumio[877]: SPOTIFY: received: {"type":"volume","data":{"value":91,"max":100}} May 09 22:51:52 volumio volumio[877]: SPOTIFY: RECEIVED SPOTIFY VOLUME 91 May 09 22:51:53 volumio volumio[877]: info: CoreCommandRouter::volumioGetState May 09 22:51:53 volumio volumio[877]: info: CorePlayQueue::getTrack 0 May 09 22:51:54 volumio volumio[877]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium May 09 22:51:54 volumio volumio[877]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium May 09 22:51:54 volumio volumio[877]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium May 09 22:51:54 volumio volumio[877]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium May 09 22:51:54 volumio volumio[877]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium May 09 22:51:54 volumio volumio[877]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium May 09 22:51:54 volumio volumio[877]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium May 09 22:51:54 volumio volumio[877]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium May 09 22:51:54 volumio volumio[877]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium May 09 22:51:54 volumio volumio[877]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium May 09 22:51:54 volumio volumio[877]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium May 09 22:51:54 volumio volumio[877]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar May 09 22:51:54 volumio volumio[877]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar May 09 22:51:54 volumio volumio[877]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar May 09 22:51:54 volumio volumio[877]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar May 09 22:51:54 volumio volumio[877]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar May 09 22:51:54 volumio volumio[877]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar May 09 22:51:54 volumio volumio[877]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar May 09 22:51:54 volumio volumio[877]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar May 09 22:51:54 volumio volumio[877]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar May 09 22:51:54 volumio volumio[877]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar May 09 22:51:54 volumio volumio[877]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar May 09 22:51:54 volumio volumio[877]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso May 09 22:51:54 volumio volumio[877]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso May 09 22:51:54 volumio volumio[877]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso May 09 22:51:54 volumio volumio[877]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso May 09 22:51:54 volumio volumio[877]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso May 09 22:51:54 volumio volumio[877]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso May 09 22:51:54 volumio volumio[877]: info: Adding plugin bluetooth to MyMusic Plugins May 09 22:51:55 volumio volumio[877]: info: Adding plugin multiroom to MyMusic Plugins May 09 22:51:55 volumio volumio[877]: info: Adding plugin metavolumio to MyMusic Plugins May 09 22:51:55 volumio volumio[877]: info: Adding plugin cd_controller to MyMusic Plugins May 09 22:51:55 volumio volumio[877]: info: Adding plugin qobuzconnect to MyMusic Plugins May 09 22:51:55 volumio volumio[877]: info: Adding plugin smart_inputs to MyMusic Plugins May 09 22:51:55 volumio volumio[877]: info: Adding plugin tidalconnect to MyMusic Plugins May 09 22:51:55 volumio volumio[877]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... May 09 22:51:56 volumio volumio[877]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded May 09 22:51:56 volumio volumio[877]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio May 09 22:51:56 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 09 22:51:56 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 09 22:51:56 volumio volumio[877]: info: Starting MyVolumio Remote Streaming Endpoints May 09 22:51:56 volumio volumio[877]: info: MyVolumio login type: Token May 09 22:51:56 volumio volumio[877]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started May 09 22:51:56 volumio volumio[877]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... May 09 22:51:57 volumio volumio[877]: info: Starting Streaming Service Transparent Proxy May 09 22:51:57 volumio volumio[877]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded May 09 22:51:57 volumio volumio[877]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services May 09 22:51:57 volumio volumio[877]: info: Streaming services startup May 09 22:51:57 volumio volumio[877]: info: Starting Streaming Daemon May 09 22:51:57 volumio sudo[1352]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 09 22:51:57 volumio sudo[1352]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 22:51:58 volumio volumio[877]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started May 09 22:51:58 volumio sudo[1352]: pam_unix(sudo:session): session closed for user root May 09 22:51:58 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 09 22:51:58 volumio volumio[877]: error: Cannot start Volumio Streaming Daemon May 09 22:51:58 volumio volumio[877]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service May 09 22:51:58 volumio volumio[877]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. May 09 22:51:58 volumio sudo[1360]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 09 22:51:58 volumio sudo[1360]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 22:51:58 volumio sudo[1360]: pam_unix(sudo:session): session closed for user root May 09 22:51:58 volumio sudo[1363]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 09 22:51:58 volumio sudo[1363]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 22:51:58 volumio sudo[1363]: pam_unix(sudo:session): session closed for user root May 09 22:51:58 volumio volumio[877]: verbose: New Socket.io Connection to 10.0.3.5 from 10.0.2.61 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_5 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.5 Mobile/15E148 Safari/604.1 Engine version: 3 Transport: polling Total Clients: 7 May 09 22:51:58 volumio sudo[1366]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 09 22:51:58 volumio sudo[1366]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 22:51:58 volumio sudo[1366]: pam_unix(sudo:session): session closed for user root May 09 22:51:58 volumio sudo[1369]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 09 22:51:58 volumio sudo[1369]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 22:51:58 volumio sudo[1369]: pam_unix(sudo:session): session closed for user root May 09 22:51:58 volumio volumio[877]: info: Received Get System Info May 09 22:51:58 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 09 22:51:58 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 09 22:51:58 volumio volumio[877]: info: Discovery: Getting this device information May 09 22:51:58 volumio volumio[877]: info: CoreCommandRouter::volumioGetState May 09 22:51:58 volumio volumio[877]: info: CorePlayQueue::getTrack 0 May 09 22:51:58 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 09 22:51:58 volumio volumio[877]: info: CoreCommandRouter::volumioGetState May 09 22:51:58 volumio volumio[877]: info: CorePlayQueue::getTrack 0 May 09 22:51:58 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 09 22:51:58 volumio volumio[877]: info: CoreCommandRouter::volumioGetVisibleSources May 09 22:51:58 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 09 22:51:58 volumio volumio[877]: info: CoreCommandRouter::volumioGetState May 09 22:51:58 volumio volumio[877]: info: CorePlayQueue::getTrack 0 May 09 22:51:58 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 09 22:51:58 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 09 22:51:58 volumio volumio[877]: info: Received Get System Info May 09 22:51:58 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 09 22:51:58 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 09 22:51:58 volumio volumio[877]: info: Discovery: Getting this device information May 09 22:51:58 volumio volumio[877]: info: CoreCommandRouter::volumioGetState May 09 22:51:58 volumio volumio[877]: info: CorePlayQueue::getTrack 0 May 09 22:51:58 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 09 22:51:58 volumio volumio[877]: info: CoreCommandRouter::volumioGetState May 09 22:51:58 volumio volumio[877]: info: CorePlayQueue::getTrack 0 May 09 22:51:58 volumio volumio[877]: info: Listing playlists May 09 22:51:58 volumio volumio[877]: info: CoreCommandRouter::getUIConfigOnPlugin May 09 22:51:58 volumio volumio[877]: info: CoreCommandRouter::getUIConfigOnPlugin May 09 22:51:58 volumio volumio[877]: info: CoreCommandRouter::getUIConfigOnPlugin May 09 22:51:58 volumio volumio[877]: info: CoreCommandRouter::getUIConfigOnPlugin May 09 22:51:58 volumio volumio[877]: verbose: New Socket.io Connection to 10.0.3.5 from 10.0.2.61 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_5 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.5 Mobile/15E148 Safari/604.1 Engine version: 3 Transport: polling Total Clients: 7 May 09 22:51:58 volumio volumio[877]: info: FusionDsp - May 09 22:51:58 volumio volumio[877]: info: FusionDsp - May 09 22:51:58 volumio volumio[877]: info: FusionDsp - May 09 22:51:59 volumio volumio[877]: STREAMING PROXY: Starting server on port 3245 May 09 22:51:59 volumio volumio[877]: Node JS runtime: 14 May 09 22:51:59 volumio volumio[877]: info: FusionDsp - May 09 22:51:59 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 09 22:51:59 volumio volumio[877]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json May 09 22:51:59 volumio volumio[877]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json May 09 22:51:59 volumio volumio[877]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json May 09 22:51:59 volumio volumio[877]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json May 09 22:51:59 volumio volumio[877]: info: Received Get System Info May 09 22:51:59 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 09 22:51:59 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 09 22:51:59 volumio volumio[877]: info: Discovery: Getting this device information May 09 22:51:59 volumio volumio[877]: info: CoreCommandRouter::volumioGetState May 09 22:51:59 volumio volumio[877]: info: CorePlayQueue::getTrack 0 May 09 22:51:59 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 09 22:51:59 volumio volumio[877]: info: CoreCommandRouter::volumioGetState May 09 22:51:59 volumio volumio[877]: info: CorePlayQueue::getTrack 0 May 09 22:51:59 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 09 22:51:59 volumio volumio[877]: info: CoreCommandRouter::volumioGetVisibleSources May 09 22:51:59 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 09 22:51:59 volumio volumio[877]: info: CoreCommandRouter::volumioGetState May 09 22:51:59 volumio volumio[877]: info: CorePlayQueue::getTrack 0 May 09 22:51:59 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 09 22:51:59 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 09 22:51:59 volumio volumio[877]: info: Received Get System Info May 09 22:51:59 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 09 22:51:59 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 09 22:51:59 volumio volumio[877]: info: Discovery: Getting this device information May 09 22:51:59 volumio volumio[877]: info: CoreCommandRouter::volumioGetState May 09 22:51:59 volumio volumio[877]: info: CorePlayQueue::getTrack 0 May 09 22:51:59 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 09 22:51:59 volumio volumio[877]: info: CoreCommandRouter::volumioGetState May 09 22:51:59 volumio volumio[877]: info: CorePlayQueue::getTrack 0 May 09 22:51:59 volumio volumio[877]: info: Listing playlists May 09 22:51:59 volumio volumio[877]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN May 09 22:51:59 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 09 22:52:00 volumio volumio[877]: info: CoreCommandRouter::getUIConfigOnPlugin May 09 22:52:00 volumio volumio[877]: info: CoreCommandRouter::getUIConfigOnPlugin May 09 22:52:00 volumio volumio[877]: info: FusionDsp - May 09 22:52:00 volumio volumio[877]: info: FusionDsp - May 09 22:52:00 volumio volumio[877]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json May 09 22:52:00 volumio volumio[877]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json May 09 22:52:00 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 09 22:52:00 volumio volumio[877]: info: Received Get System Info May 09 22:52:00 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 09 22:52:00 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 09 22:52:00 volumio volumio[877]: info: Discovery: Getting this device information May 09 22:52:00 volumio volumio[877]: info: CoreCommandRouter::volumioGetState May 09 22:52:00 volumio volumio[877]: info: CorePlayQueue::getTrack 0 May 09 22:52:00 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 09 22:52:00 volumio sudo[1374]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 09 22:52:00 volumio sudo[1372]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 09 22:52:00 volumio sudo[1374]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 22:52:00 volumio sudo[1372]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 22:52:00 volumio sudo[1374]: pam_unix(sudo:session): session closed for user root May 09 22:52:00 volumio sudo[1372]: pam_unix(sudo:session): session closed for user root May 09 22:52:00 volumio volumio[877]: verbose: New Socket.io Connection to 10.0.3.5 from 10.0.2.61 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_5 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.5 Mobile/15E148 Safari/604.1 Engine version: 3 Transport: polling Total Clients: 7 May 09 22:52:00 volumio volumio[877]: info: Received Get System Info May 09 22:52:00 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 09 22:52:00 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 09 22:52:00 volumio volumio[877]: info: Discovery: Getting this device information May 09 22:52:00 volumio volumio[877]: info: CoreCommandRouter::volumioGetState May 09 22:52:00 volumio volumio[877]: info: CorePlayQueue::getTrack 0 May 09 22:52:00 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 09 22:52:00 volumio volumio[877]: info: CoreCommandRouter::volumioGetState May 09 22:52:00 volumio volumio[877]: info: CorePlayQueue::getTrack 0 May 09 22:52:00 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 09 22:52:00 volumio volumio[877]: info: CoreCommandRouter::volumioGetVisibleSources May 09 22:52:00 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 09 22:52:00 volumio volumio[877]: info: CoreCommandRouter::volumioGetState May 09 22:52:00 volumio volumio[877]: info: CorePlayQueue::getTrack 0 May 09 22:52:00 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 09 22:52:00 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 09 22:52:00 volumio volumio[877]: info: Received Get System Info May 09 22:52:00 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 09 22:52:00 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 09 22:52:00 volumio volumio[877]: info: Discovery: Getting this device information May 09 22:52:00 volumio volumio[877]: info: CoreCommandRouter::volumioGetState May 09 22:52:00 volumio volumio[877]: info: CorePlayQueue::getTrack 0 May 09 22:52:00 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 09 22:52:00 volumio volumio[877]: info: CoreCommandRouter::volumioGetState May 09 22:52:00 volumio volumio[877]: info: CorePlayQueue::getTrack 0 May 09 22:52:01 volumio volumio[877]: info: Listing playlists May 09 22:52:01 volumio volumio[877]: info: Received Get System Info May 09 22:52:01 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 09 22:52:01 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 09 22:52:01 volumio volumio[877]: info: Discovery: Getting this device information May 09 22:52:01 volumio volumio[877]: info: CoreCommandRouter::volumioGetState May 09 22:52:01 volumio volumio[877]: info: CorePlayQueue::getTrack 0 May 09 22:52:01 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 09 22:52:01 volumio volumio[877]: info: CoreCommandRouter::volumioGetState May 09 22:52:01 volumio volumio[877]: info: CorePlayQueue::getTrack 0 May 09 22:52:01 volumio volumio[877]: info: CoreCommandRouter::getUIConfigOnPlugin May 09 22:52:01 volumio volumio[877]: info: CoreCommandRouter::getUIConfigOnPlugin May 09 22:52:01 volumio volumio[877]: info: CoreCommandRouter::getUIConfigOnPlugin May 09 22:52:01 volumio volumio[877]: info: CoreCommandRouter::getUIConfigOnPlugin May 09 22:52:01 volumio volumio[877]: info: CoreCommandRouter::getUIConfigOnPlugin May 09 22:52:01 volumio volumio[877]: info: CoreCommandRouter::getUIConfigOnPlugin May 09 22:52:01 volumio volumio[877]: info: CoreCommandRouter::getUIConfigOnPlugin May 09 22:52:01 volumio volumio[877]: info: CoreCommandRouter::getUIConfigOnPlugin May 09 22:52:01 volumio volumio[877]: info: CoreCommandRouter::getUIConfigOnPlugin May 09 22:52:01 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 09 22:52:01 volumio volumio[877]: info: FusionDsp - May 09 22:52:01 volumio volumio[877]: info: FusionDsp - May 09 22:52:01 volumio volumio[877]: info: FusionDsp - May 09 22:52:01 volumio volumio[877]: info: FusionDsp - May 09 22:52:01 volumio volumio[877]: info: FusionDsp - May 09 22:52:02 volumio volumio[877]: info: FusionDsp - May 09 22:52:02 volumio volumio[877]: info: FusionDsp - May 09 22:52:02 volumio volumio[877]: info: FusionDsp - May 09 22:52:02 volumio volumio[877]: info: FusionDsp - May 09 22:52:02 volumio volumio[877]: info: AutoStart - Plugin is starting May 09 22:52:02 volumio volumio[877]: info: CoreCommandRouter::volumioGetQueue May 09 22:52:02 volumio volumio[877]: info: CoreStateMachine::getQueue May 09 22:52:02 volumio volumio[877]: info: CorePlayQueue::getQueue May 09 22:52:02 volumio volumio[877]: info: AutoStart - start playing May 09 22:52:02 volumio volumio[877]: info: AutoStart - start playing with no specific position May 09 22:52:02 volumio volumio[877]: info: CoreCommandRouter::volumioPlay May 09 22:52:02 volumio volumio[877]: info: CoreStateMachine::play index 0 May 09 22:52:02 volumio volumio[877]: info: CoreStateMachine::setConsumeUpdateService undefined May 09 22:52:02 volumio volumio[877]: info: CoreStateMachine::stop May 09 22:52:02 volumio volumio[877]: info: CoreStateMachine::setConsumeUpdateService undefined May 09 22:52:02 volumio volumio[877]: info: CoreStateMachine::play index undefined May 09 22:52:02 volumio volumio[877]: info: CoreStateMachine::setConsumeUpdateService undefined May 09 22:52:02 volumio volumio[877]: info: CorePlayQueue::getTrack 0 May 09 22:52:02 volumio volumio[877]: info: CoreStateMachine::startPlaybackTimer May 09 22:52:02 volumio volumio[877]: info: CorePlayQueue::getTrack 0 May 09 22:52:02 volumio volumio[877]: info: [1746845522928] ControllerSpotify::clearAddPlayTrack May 09 22:52:02 volumio volumio[877]: info: Sending Spotify command with payload to local API: /player/play May 09 22:52:02 volumio volumio[877]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json May 09 22:52:02 volumio volumio[877]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json May 09 22:52:03 volumio volumio[877]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json May 09 22:52:03 volumio volumio[877]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json May 09 22:52:03 volumio volumio[877]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json May 09 22:52:03 volumio volumio[877]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json May 09 22:52:03 volumio volumio[877]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json May 09 22:52:03 volumio volumio[877]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json May 09 22:52:03 volumio volumio[877]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json May 09 22:52:03 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 09 22:52:03 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken May 09 22:52:03 volumio volumio[877]: info: MyVolumio token set successfully May 09 22:52:03 volumio volumio[877]: info: MYVOLUMIO: Adding device May 09 22:52:03 volumio volumio[877]: info: MYVOLUMIO: Evaluating Server May 09 22:52:03 volumio go-librespot[1182]: time="2025-05-09T22:52:03-04:00" level=debug msg="resolved context of track" uri="spotify:track:7DSAEUvxU8FajXtRloy8M0" May 09 22:52:03 volumio go-librespot[1182]: time="2025-05-09T22:52:03-04:00" level=trace msg="fetched new page 0 with 1 items (list: 1)" uri="spotify:track:7DSAEUvxU8FajXtRloy8M0" May 09 22:52:03 volumio go-librespot[1182]: time="2025-05-09T22:52:03-04:00" level=debug msg="loading track (paused: false, position: 0ms)" uri="spotify:track:7DSAEUvxU8FajXtRloy8M0" May 09 22:52:03 volumio go-librespot[1182]: time="2025-05-09T22:52:03-04:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 345" May 09 22:52:03 volumio go-librespot[1182]: time="2025-05-09T22:52:03-04:00" level=debug msg="skipping dealer message" uri=social-connect/v2/broadcast_status_update May 09 22:52:03 volumio go-librespot[1182]: time="2025-05-09T22:52:03-04:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" May 09 22:52:03 volumio go-librespot[1182]: time="2025-05-09T22:52:03-04:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update May 09 22:52:03 volumio go-librespot[1182]: time="2025-05-09T22:52:03-04:00" level=trace msg="emitting websocket event: will_play" May 09 22:52:03 volumio go-librespot[1182]: time="2025-05-09T22:52:03-04:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1114" May 09 22:52:03 volumio go-librespot[1182]: time="2025-05-09T22:52:03-04:00" level=debug msg="selected format OGG_VORBIS_320 (53a551ea83c7919a86d1616c0c36ea3cb185d62e)" uri="spotify:track:7DSAEUvxU8FajXtRloy8M0" May 09 22:52:03 volumio go-librespot[1182]: time="2025-05-09T22:52:03-04:00" level=debug msg="requested aes key for file 53a551ea83c7919a86d1616c0c36ea3cb185d62e, gid: 7DSAEUvxU8FajXtRloy8M0" May 09 22:52:04 volumio go-librespot[1182]: time="2025-05-09T22:52:04-04:00" level=debug msg="fetched first chunk of 15, total size is 7862240 bytes" uri="spotify:track:7DSAEUvxU8FajXtRloy8M0" May 09 22:52:04 volumio go-librespot[1182]: time="2025-05-09T22:52:04-04:00" level=trace msg="seek to 0ms (diff: 0ms, samples: 0, bytes: 0)" uri="spotify:track:7DSAEUvxU8FajXtRloy8M0" May 09 22:52:04 volumio volumio[877]: info: MyVolumio status changed May 09 22:52:04 volumio volumio[877]: info: Streaming services startup May 09 22:52:04 volumio volumio[877]: info: Starting Streaming Daemon May 09 22:52:04 volumio volumio[877]: info: Removing browser output: myVolumio user plan is not superstar May 09 22:52:04 volumio volumio[877]: info: Removing audio output: May 09 22:52:04 volumio volumio[877]: info: Stoppping Tunnel 1 May 09 22:52:04 volumio sudo[1433]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 09 22:52:04 volumio sudo[1433]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 22:52:04 volumio volumio[877]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:7DSAEUvxU8FajXtRloy8M0","play_origin":"go-librespot"}} May 09 22:52:04 volumio sudo[1433]: pam_unix(sudo:session): session closed for user root May 09 22:52:04 volumio sudo[1436]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service May 09 22:52:04 volumio sudo[1436]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 22:52:04 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 09 22:52:04 volumio go-librespot[1182]: time="2025-05-09T22:52:04-04:00" level=debug msg="created new output device" May 09 22:52:04 volumio volumio[877]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined May 09 22:52:04 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates May 09 22:52:04 volumio volumio[877]: info: Received Get System Version May 09 22:52:04 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 09 22:52:04 volumio volumio[877]: info: Received Get System Info May 09 22:52:04 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 09 22:52:04 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 09 22:52:04 volumio volumio[877]: info: Discovery: Getting this device information May 09 22:52:04 volumio volumio[877]: info: CoreCommandRouter::volumioGetState May 09 22:52:04 volumio go-librespot[1182]: time="2025-05-09T22:52:04-04:00" level=info msg="loaded track \"Flowers\" (paused: false, position: 0ms, duration: 200600ms, prefetched: false)" uri="spotify:track:7DSAEUvxU8FajXtRloy8M0" May 09 22:52:04 volumio volumio[877]: info: CorePlayQueue::getTrack 0 May 09 22:52:04 volumio go-librespot[1182]: time="2025-05-09T22:52:04-04:00" level=debug msg="fetched chunk 1/14, size: 524288" uri="spotify:track:7DSAEUvxU8FajXtRloy8M0" May 09 22:52:04 volumio sudo[1436]: pam_unix(sudo:session): session closed for user root May 09 22:52:04 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 09 22:52:04 volumio volumio[877]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 May 09 22:52:04 volumio volumio[877]: info: FusionDsp - ---- read samplerate from file: 44100 May 09 22:52:04 volumio volumio[877]: info: camilladsp stopping service pid 1235... May 09 22:52:04 volumio volumio[877]: info: camilladsp service terminated, instance 1 May 09 22:52:04 volumio volumio[877]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 09 22:52:04 volumio volumio[877]: info: FusionDsp - crossfeed jameier May 09 22:52:04 volumio volumio[877]: info: FusionDsp - Loudness is ON true May 09 22:52:04 volumio volumio[877]: info: camilladsp service started and running in background, instance 1 May 09 22:52:04 volumio volumio[877]: info: Remote SSH Stopped May 09 22:52:04 volumio volumio[877]: error: Cannot start Volumio Streaming Daemon May 09 22:52:04 volumio volumio[877]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service May 09 22:52:04 volumio volumio[877]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. May 09 22:52:04 volumio volumio[877]: info: CoreCommandRouter::volumioGetState May 09 22:52:04 volumio volumio[877]: info: CorePlayQueue::getTrack 0 May 09 22:52:04 volumio volumio[877]: info: Setting Geolocation for MyVolumio to us1 May 09 22:52:04 volumio go-librespot[1182]: time="2025-05-09T22:52:04-04:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" May 09 22:52:04 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 09 22:52:04 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 09 22:52:04 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 09 22:52:04 volumio go-librespot[1182]: time="2025-05-09T22:52:04-04:00" level=debug msg="fetched chunk 3/14, size: 524288" uri="spotify:track:7DSAEUvxU8FajXtRloy8M0" May 09 22:52:04 volumio volumio[877]: info: Listing playlists May 09 22:52:04 volumio volumio[877]: info: Listing playlists May 09 22:52:04 volumio go-librespot[1182]: time="2025-05-09T22:52:04-04:00" level=trace msg="scheduling prefetch in 170s" May 09 22:52:04 volumio go-librespot[1182]: time="2025-05-09T22:52:04-04:00" level=trace msg="emitting websocket event: metadata" May 09 22:52:04 volumio volumio[877]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:7DSAEUvxU8FajXtRloy8M0","name":"Flowers","artist_names":["Miley Cyrus"],"album_name":"Endless Summer Vacation","album_cover_url":"https://i.scdn.co/image/ab67616d00001e02cd222052a2594be29a6616b5","position":0,"duration":200600,"release_date":"year:2023 month:8 day:18","track_number":1,"disc_number":1}} May 09 22:52:04 volumio go-librespot[1182]: time="2025-05-09T22:52:04-04:00" level=debug msg="fetched chunk 2/14, size: 524288" uri="spotify:track:7DSAEUvxU8FajXtRloy8M0" May 09 22:52:05 volumio volumio[877]: info: CoreCommandRouter::getUIConfigOnPlugin May 09 22:52:05 volumio go-librespot[1182]: time="2025-05-09T22:52:05-04:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" May 09 22:52:05 volumio go-librespot[1182]: time="2025-05-09T22:52:05-04:00" level=trace msg="emitting websocket event: playing" May 09 22:52:05 volumio volumio[877]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:7DSAEUvxU8FajXtRloy8M0","play_origin":"go-librespot"}} May 09 22:52:05 volumio volumio[877]: SPOTIFY: PUSH STATE SPOTIFY May 09 22:52:05 volumio volumio[877]: SPOTIFY: {"status":"play","service":"spop","title":"Flowers","artist":"Miley Cyrus","album":"Endless Summer Vacation","albumart":"https://i.scdn.co/image/ab67616d00001e02cd222052a2594be29a6616b5","uri":"spotify:track:7DSAEUvxU8FajXtRloy8M0","trackType":"spotify","seek":0,"duration":200,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} May 09 22:52:05 volumio volumio[877]: info: CoreCommandRouter::servicePushState May 09 22:52:05 volumio volumio[877]: info: CorePlayQueue::getTrack 0 May 09 22:52:05 volumio volumio[877]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Flowers","artist":"Miley Cyrus","album":"Endless Summer Vacation","albumart":"https://i.scdn.co/image/ab67616d00001e02cd222052a2594be29a6616b5","uri":"spotify:track:7DSAEUvxU8FajXtRloy8M0","trackType":"spotify","seek":0,"duration":200,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} May 09 22:52:05 volumio volumio[877]: verbose: CURRENT POSITION 0 May 09 22:52:05 volumio volumio[877]: info: CoreStateMachine::syncState stateService play May 09 22:52:05 volumio volumio[877]: info: CoreStateMachine::syncState currentStatus stop May 09 22:52:05 volumio volumio[877]: info: CoreCommandRouter::getUIConfigOnPlugin May 09 22:52:05 volumio volumio[877]: info: CoreCommandRouter::getUIConfigOnPlugin May 09 22:52:05 volumio volumio[877]: info: FusionDsp - May 09 22:52:05 volumio volumio[877]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json May 09 22:52:05 volumio volumio[877]: info: Successfully Added MyVolumio device May 09 22:52:05 volumio volumio[877]: info: FusionDsp - May 09 22:52:05 volumio volumio[877]: info: FusionDsp - May 09 22:52:05 volumio volumio[877]: SPOTIFY: PUSH STATE SPOTIFY May 09 22:52:05 volumio volumio[877]: SPOTIFY: {"status":"play","service":"spop","title":"Flowers","artist":"Miley Cyrus","album":"Endless Summer Vacation","albumart":"https://i.scdn.co/image/ab67616d00001e02cd222052a2594be29a6616b5","uri":"spotify:track:7DSAEUvxU8FajXtRloy8M0","trackType":"spotify","seek":0,"duration":200,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} May 09 22:52:05 volumio volumio[877]: info: CoreCommandRouter::servicePushState May 09 22:52:05 volumio volumio[877]: info: CorePlayQueue::getTrack 0 May 09 22:52:05 volumio volumio[877]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Flowers","artist":"Miley Cyrus","album":"Endless Summer Vacation","albumart":"https://i.scdn.co/image/ab67616d00001e02cd222052a2594be29a6616b5","uri":"spotify:track:7DSAEUvxU8FajXtRloy8M0","trackType":"spotify","seek":0,"duration":200,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} May 09 22:52:05 volumio volumio[877]: verbose: CURRENT POSITION 0 May 09 22:52:05 volumio volumio[877]: info: CoreStateMachine::syncState stateService play May 09 22:52:05 volumio volumio[877]: info: CoreStateMachine::syncState currentStatus play May 09 22:52:05 volumio volumio[877]: info: Received an update from plugin. extracting info from payload May 09 22:52:05 volumio volumio[877]: info: CoreStateMachine::pushState May 09 22:52:05 volumio volumio[877]: info: CorePlayQueue::getTrack 0 May 09 22:52:05 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 22:52:05 volumio volumio[877]: info: CoreCommandRouter::volumioPushState May 09 22:52:05 volumio volumio[877]: info: CoreStateMachine::pushState May 09 22:52:05 volumio volumio[877]: info: CorePlayQueue::getTrack 0 May 09 22:52:05 volumio volumio[877]: info: CoreCommandRouter::volumioPushState May 09 22:52:05 volumio volumio[877]: info: Updating MyVolumio device info May 09 22:52:05 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 09 22:52:05 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 09 22:52:05 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 09 22:52:05 volumio volumio[877]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json May 09 22:52:05 volumio volumio[877]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json May 09 22:52:06 volumio volumio[877]: info: PeppyMeterBasic ---peppymeterbasic status play May 09 22:52:06 volumio volumio[877]: info: PeppyMeterBasic ---peppymeterbasic status play May 09 22:52:06 volumio sudo[1465]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service May 09 22:52:06 volumio sudo[1465]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 22:52:06 volumio sudo[1468]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service May 09 22:52:06 volumio sudo[1468]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 22:52:06 volumio volumio[877]: info: FusionDsp - volume level for loudness 91 gain applied 0.00 May 09 22:52:06 volumio volumio[877]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 09 22:52:06 volumio volumio[877]: info: FusionDsp - crossfeed jameier May 09 22:52:06 volumio volumio[877]: info: FusionDsp - Loudness is ON true May 09 22:52:06 volumio volumio[877]: info: FusionDsp - volume level for loudness 91 gain applied 0.00 May 09 22:52:06 volumio volumio[877]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 09 22:52:06 volumio volumio[877]: info: FusionDsp - crossfeed jameier May 09 22:52:06 volumio volumio[877]: info: FusionDsp - Loudness is ON true May 09 22:52:06 volumio volumio[877]: SPOTIFY: RECEIVED VOLUMIO VOLUME 91 May 09 22:52:06 volumio volumio[877]: SPOTIFY: RECEIVED VOLUMIO VOLUME 91 May 09 22:52:06 volumio systemd[1]: Started peppymeterbasic Daemon. May 09 22:52:06 volumio sudo[1465]: pam_unix(sudo:session): session closed for user root May 09 22:52:06 volumio sudo[1468]: pam_unix(sudo:session): session closed for user root May 09 22:52:06 volumio volumio[877]: info: peppymeterbasic Daemon Started May 09 22:52:06 volumio volumio[877]: info: peppymeterbasic Daemon Started May 09 22:52:06 volumio volumio[877]: info: FusionDsp - {"Reload":{"result":"Ok"}} May 09 22:52:06 volumio volumio[877]: info: FusionDsp - {"Reload":{"result":"Ok"}} May 09 22:52:07 volumio volumio[877]: info: Successfully Updated MyVolumio device May 09 22:52:08 volumio volumio[1470]: pygame 1.9.4.post1 May 09 22:52:08 volumio volumio[1470]: Hello from the pygame community. https://www.pygame.org/contribute.html May 09 22:52:08 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 09 22:52:08 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 09 22:52:08 volumio volumio[877]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/fusiondsp/volumio/buster/armhf May 09 22:52:10 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 09 22:52:11 volumio volumio[877]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/fusiondsp/volumio/buster/armhf May 09 22:52:11 volumio volumio[877]: info: FusionDsp - Stopping FusionDsp service May 09 22:52:11 volumio volumio[877]: info: camilladsp stopping service pid 1442... May 09 22:52:11 volumio volumio[877]: info: camilladsp service terminated, instance 1 May 09 22:52:11 volumio sudo[1485]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop fusiondsp.service May 09 22:52:11 volumio sudo[1485]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 22:52:11 volumio systemd[1]: Stopping FusionDsp Daemon... May 09 22:52:11 volumio volumio[1243]: Backend configuration: May 09 22:52:11 volumio volumio[1243]: active_config: /data/configuration/audio_interface/fusiondsp/camilladsp.yml May 09 22:52:11 volumio volumio[1243]: active_config_txt: null May 09 22:52:11 volumio volumio[1243]: camilla_host: 127.0.0.1 May 09 22:52:11 volumio volumio[1243]: camilla_port: 9876 May 09 22:52:11 volumio volumio[1243]: coeff_dir: /data/INTERNAL/FusionDsp/filters May 09 22:52:11 volumio volumio[1243]: config_dir: /data/configuration/audio_interface/fusiondsp May 09 22:52:11 volumio volumio[1243]: default_config: /data/configuration/audio_interface/fusiondsp/camilladsp.yml May 09 22:52:11 volumio volumio[1243]: log_file: /tmp/camilladsp.log May 09 22:52:11 volumio volumio[1243]: on_get_active_config: null May 09 22:52:11 volumio volumio[1243]: on_set_active_config: null May 09 22:52:11 volumio volumio[1243]: port: 5011 May 09 22:52:11 volumio volumio[1243]: supported_capture_types: null May 09 22:52:11 volumio volumio[1243]: supported_playback_types: null May 09 22:52:11 volumio volumio[1243]: update_config_symlink: false May 09 22:52:11 volumio volumio[1243]: update_config_txt: false May 09 22:52:11 volumio volumio[1243]: ======== Running on http://0.0.0.0:5011 ======== May 09 22:52:11 volumio volumio[1243]: (Press CTRL+C to quit) May 09 22:52:11 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 09 22:52:11 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 09 22:52:11 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 09 22:52:11 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled May 09 22:52:11 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 09 22:52:11 volumio volumio-remote-updater[587]: No test mode May 09 22:52:11 volumio volumio-remote-updater[587]: No alpha test mode May 09 22:52:11 volumio volumio[877]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 May 09 22:52:11 volumio volumio[877]: info: CoreCommandRouter::volumioGetState May 09 22:52:11 volumio volumio[877]: info: CorePlayQueue::getTrack 0 May 09 22:52:11 volumio systemd[1]: fusiondsp.service: Succeeded. May 09 22:52:11 volumio systemd[1]: Stopped FusionDsp Daemon. May 09 22:52:11 volumio sudo[1485]: pam_unix(sudo:session): session closed for user root May 09 22:52:11 volumio volumio[877]: info: FusionDsp - Reporting Fusion DSP Disabled May 09 22:52:11 volumio volumio[877]: info: Removing fusiondspeq DSP Signal Path Element May 09 22:52:11 volumio volumio[877]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} May 09 22:52:11 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache May 09 22:52:11 volumio volumio[877]: info: Check plugin dependencies May 09 22:52:11 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 09 22:52:11 volumio volumio[877]: info: Rename folder May 09 22:52:11 volumio volumio[877]: info: Folder /tmp/downloaded_plugin.zip removed May 09 22:52:11 volumio volumio[877]: info: Move to category May 09 22:52:12 volumio ntpd[849]: Soliciting pool server 23.186.168.127 May 09 22:52:13 volumio volumio[877]: info: Checking if install.sh is present May 09 22:52:13 volumio volumio[877]: info: Executing install.sh May 09 22:52:13 volumio sudo[1502]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/audio_interface/fusiondsp/install.sh May 09 22:52:13 volumio sudo[1502]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 22:52:13 volumio volumio[877]: info: creating filters folder and copying demo filters May 09 22:52:13 volumio volumio[877]: info: CoreCommandRouter::volumioGetState May 09 22:52:13 volumio volumio[877]: info: CorePlayQueue::getTrack 0 May 09 22:52:13 volumio volumio[877]: info: copying demo flters May 09 22:52:14 volumio volumio[877]: info: ./presets/ May 09 22:52:14 volumio volumio[877]: info: ./presets/2XEQ15/ May 09 22:52:14 volumio volumio[877]: info: ./presets/2XEQ15/.Bass-FusionDsp.json May 09 22:52:14 volumio volumio[877]: info: ./presets/2XEQ15/.Soundtrack-FusionDsp.json May 09 22:52:14 volumio volumio[877]: info: ./presets/2XEQ15/.Classic-FusionDsp.json May 09 22:52:14 volumio sudo[1527]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/usr/bin/apt update May 09 22:52:14 volumio volumio[877]: info: ./presets/2XEQ15/.Voice-FusionDsp.json May 09 22:52:14 volumio sudo[1527]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 22:52:14 volumio volumio[877]: info: ./presets/2XEQ15/.Rock-FusionDsp.json May 09 22:52:14 volumio volumio[877]: info: ./presets/PEQ/ May 09 22:52:14 volumio volumio[877]: info: ./presets/convfir/ May 09 22:52:14 volumio volumio[877]: info: ./presets/convfir/kef.json May 09 22:52:14 volumio volumio[877]: info: ./presets/convfir/test.json May 09 22:52:14 volumio volumio[877]: info: ./presets/EQ15/ May 09 22:52:14 volumio volumio[877]: info: ./presets/EQ15/.Bass-FusionDsp.json May 09 22:52:14 volumio volumio[877]: info: ./presets/EQ15/.Soundtrack-FusionDsp.json May 09 22:52:14 volumio volumio[877]: info: ./presets/EQ15/.Classic-FusionDsp.json May 09 22:52:14 volumio volumio[877]: info: ./presets/EQ15/.Voice-FusionDsp.json May 09 22:52:14 volumio volumio[877]: info: ./presets/EQ15/.Rock-FusionDsp.json May 09 22:52:14 volumio volumio[877]: info: Installing/fusiondsp dependencies May 09 22:52:15 volumio volumio[877]: info: Hit:1 http://archive.raspberrypi.org/debian buster InRelease May 09 22:52:15 volumio volumio[877]: info: Hit:2 https://deb.nodesource.com/node_14.x buster InRelease May 09 22:52:15 volumio ntpd[849]: Soliciting pool server 24.249.17.250 May 09 22:52:15 volumio ntpd[849]: Soliciting pool server 108.61.56.35 May 09 22:52:15 volumio volumio[877]: info: Hit:3 http://raspbian.raspberrypi.org/raspbian buster InRelease May 09 22:52:16 volumio ntpd[849]: Soliciting pool server 23.142.248.9 May 09 22:52:16 volumio ntpd[849]: Soliciting pool server 204.2.134.172 May 09 22:52:16 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 22:52:16 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 22:52:16 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam May 09 22:52:16 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam May 09 22:52:16 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam May 09 22:52:16 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject May 09 22:52:16 volumio volumio[877]: info: CoreCommandRouter::volumioGetBrowseSources May 09 22:52:16 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 09 22:52:17 volumio ntpd[849]: Soliciting pool server 45.83.234.123 May 09 22:52:17 volumio ntpd[849]: Soliciting pool server 66.118.228.14 May 09 22:52:18 volumio volumio[877]: error: MyVolumio Plugin failed to authenticate in a timely fashion May 09 22:52:18 volumio volumio[877]: info: Completed starting MyVolumio Plugin May 09 22:52:18 volumio volumio[877]: [Metrics] CommandRouter: 66s 456.84ms May 09 22:52:18 volumio volumio[877]: info: CoreCommandRouter::volumiosetStartupVolume May 09 22:52:18 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 22:52:18 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 09 22:52:18 volumio volumio[877]: info: CoreCommandRouter::Close All Modals sent May 09 22:52:18 volumio volumio[877]: info: CoreCommandRouter::Close All Modals sent May 09 22:52:18 volumio volumio[877]: info: FusionDsp - ---- read samplerate, raw: May 09 22:52:18 volumio volumio[877]: error: FusionDsp - invalid sample rate May 09 22:52:18 volumio volumio[877]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 May 09 22:52:18 volumio volumio[877]: info: FusionDsp - ---- read samplerate from file: 44100 May 09 22:52:18 volumio volumio[877]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 09 22:52:18 volumio volumio[877]: info: FusionDsp - crossfeed jameier May 09 22:52:18 volumio volumio[877]: info: FusionDsp - Loudness is ON true May 09 22:52:18 volumio volumio[877]: error: FusionDsp - WebSocket error: [object Object] May 09 22:52:18 volumio ntpd[849]: Soliciting pool server 73.65.80.137 May 09 22:52:18 volumio ntpd[849]: Soliciting pool server 206.226.67.233 May 09 22:52:19 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable May 09 22:52:19 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 09 22:52:19 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect May 09 22:52:19 volumio ntpd[849]: Soliciting pool server 207.244.103.95 May 09 22:52:19 volumio ntpd[849]: Soliciting pool server 2606:82c0:21::e May 09 22:52:20 volumio ntpd[849]: Soliciting pool server 23.186.168.125 May 09 22:52:21 volumio volumio[877]: info: Reading package lists... May 09 22:52:22 volumio volumio[877]: info: Building dependency tree... May 09 22:52:22 volumio volumio[877]: info: Reading state information... May 09 22:52:22 volumio volumio[877]: info: All packages are up to date. May 09 22:52:22 volumio sudo[1527]: pam_unix(sudo:session): session closed for user root May 09 22:52:23 volumio sudo[1745]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/usr/bin/apt -y install python3-aiohttp python3-pip May 09 22:52:23 volumio sudo[1745]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 22:52:23 volumio volumio[877]: info: Reading package lists... May 09 22:52:23 volumio volumio[877]: info: CoreCommandRouter::volumioGetState May 09 22:52:23 volumio volumio[877]: info: CorePlayQueue::getTrack 0 May 09 22:52:23 volumio volumio[877]: info: Listing playlists May 09 22:52:23 volumio volumio[877]: info: Listing playlists May 09 22:52:25 volumio ntpd[849]: receive: Unexpected origin timestamp 0xebc93de8.2cd28b9f does not match aorg 0000000000.00000000 from server@206.226.67.233 xmt 0xebc93de9.0b015000 May 09 22:52:25 volumio volumio[877]: info: Building dependency tree... May 09 22:52:25 volumio volumio[877]: info: Reading state information... May 09 22:52:25 volumio ntpd[849]: receive: Unexpected origin timestamp 0xebc93de8.2cd665cf does not match aorg 0000000000.00000000 from server@45.83.234.123 xmt 0xebc93de9.13350e94 May 09 22:52:25 volumio ntpd[849]: receive: Unexpected origin timestamp 0xebc93de8.2cd4bdb0 does not match aorg 0000000000.00000000 from server@66.118.228.14 xmt 0xebc93de9.2107f392 May 09 22:52:25 volumio volumio[877]: info: BOOT COMPLETED May 09 22:52:26 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken May 09 22:52:26 volumio volumio[877]: info: python3-aiohttp is already the newest version (3.5.1-1+deb10u1). May 09 22:52:26 volumio sudo[1745]: pam_unix(sudo:session): session closed for user root May 09 22:52:26 volumio volumio[877]: info: python3-pip is already the newest version (18.1-5+rpt1). May 09 22:52:26 volumio volumio[877]: info: 0 upgraded, 0 newly installed, 0 to remove and 2 not upgraded. May 09 22:52:26 volumio sudo[1763]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/bin/tar -xvf fusiondsp.service.tar -C / May 09 22:52:26 volumio sudo[1763]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 22:52:26 volumio volumio[877]: info: lib/systemd/system/fusiondsp.service May 09 22:52:26 volumio sudo[1763]: pam_unix(sudo:session): session closed for user root May 09 22:52:29 volumio volumio[877]: info: MiniUnz 1.01b, demo of zLib + Unz package written by Gilles Vollant May 09 22:52:29 volumio volumio[877]: info: more info at http://www.winimage.com/zLibDll/unzip.html May 09 22:52:29 volumio volumio[877]: info: May 09 22:52:29 volumio volumio[877]: info: cgui-1.0.0.zip opened May 09 22:52:29 volumio volumio[877]: info: creating directory: cgui/ May 09 22:52:29 volumio volumio[877]: info: extracting: cgui/LICENSE.txt May 09 22:52:29 volumio volumio[877]: info: extracting: cgui/README.md May 09 22:52:29 volumio sudo[1767]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/bin/chown -R volumio cgui May 09 22:52:29 volumio volumio[877]: info: creating directory: cgui/build/ May 09 22:52:29 volumio volumio[877]: info: extracting: cgui/build/robots.txt May 09 22:52:29 volumio sudo[1767]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 22:52:29 volumio volumio[877]: info: extracting: cgui/build/logo512.png May 09 22:52:29 volumio volumio[877]: info: extracting: cgui/build/asset-manifest.json May 09 22:52:29 volumio volumio[877]: info: extracting: cgui/build/index.html May 09 22:52:29 volumio volumio[877]: info: extracting: cgui/build/.put_statics_here May 09 22:52:29 volumio volumio[877]: info: extracting: cgui/build/favicon.ico May 09 22:52:29 volumio volumio[877]: info: extracting: cgui/build/logo192.png May 09 22:52:29 volumio sudo[1767]: pam_unix(sudo:session): session closed for user root May 09 22:52:29 volumio volumio[877]: info: extracting: cgui/build/.xdp-css-variables-1.css-PvrWoX May 09 22:52:29 volumio volumio[877]: info: extracting: cgui/build/manifest.json May 09 22:52:29 volumio volumio[877]: info: extracting: cgui/build/css-variables.css May 09 22:52:29 volumio volumio[877]: info: extracting: cgui/build/precache-manifest.76db5aad1647230454f47b1e22783b54.js May 09 22:52:29 volumio volumio[877]: info: extracting: cgui/build/service-worker.js May 09 22:52:29 volumio volumio[877]: info: extracting: cgui/build/precache-manifest.5e6c675b70ef6a3fe73d0209febfe347.js May 09 22:52:29 volumio volumio[877]: info: creating directory: cgui/build/static/ May 09 22:52:29 volumio volumio[877]: info: creating directory: cgui/build/static/css/ May 09 22:52:29 volumio sudo[1769]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/bin/chgrp -R volumio cgui May 09 22:52:29 volumio volumio[877]: info: extracting: cgui/build/static/css/main.d51d005e.chunk.css May 09 22:52:29 volumio sudo[1769]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 22:52:29 volumio volumio[877]: info: extracting: cgui/build/static/css/main.d51d005e.chunk.css.map May 09 22:52:29 volumio volumio[877]: info: extracting: cgui/build/static/css/2.975d6916.chunk.css.map May 09 22:52:29 volumio volumio[877]: info: extracting: cgui/build/static/css/main.56bdf14e.chunk.css May 09 22:52:29 volumio volumio[877]: info: extracting: cgui/build/static/css/main.56bdf14e.chunk.css.map May 09 22:52:29 volumio volumio[877]: info: extracting: cgui/build/static/css/main.1503f6cd.css.map May 09 22:52:29 volumio volumio[877]: info: extracting: cgui/build/static/css/2.a4c7e7cf.chunk.css.map May 09 22:52:29 volumio volumio[877]: info: extracting: cgui/build/static/css/main.1503f6cd.css May 09 22:52:29 volumio sudo[1769]: pam_unix(sudo:session): session closed for user root May 09 22:52:29 volumio volumio[877]: info: extracting: cgui/build/static/css/2.975d6916.chunk.css May 09 22:52:29 volumio volumio[877]: info: extracting: cgui/build/static/css/2.a4c7e7cf.chunk.css May 09 22:52:29 volumio volumio[877]: info: creating directory: cgui/build/static/media/ May 09 22:52:29 volumio volumio[877]: info: extracting: cgui/build/static/media/camilladsp.95719842.svg May 09 22:52:29 volumio volumio[877]: info: extracting: cgui/build/static/media/camilladsp.4767ea4ad3510467418b818dc677a87e.svg May 09 22:52:29 volumio volumio[877]: info: creating directory: cgui/build/static/js/ May 09 22:52:29 volumio volumio[877]: info: extracting: cgui/build/static/js/2.fd49ece5.chunk.js.map May 09 22:52:29 volumio volumio[877]: info: extracting: cgui/build/static/js/main.71e11955.js May 09 22:52:29 volumio volumio[877]: info: extracting: cgui/build/static/js/runtime-main.422581ec.js May 09 22:52:29 volumio volumio[877]: info: extracting: cgui/build/static/js/2.fd49ece5.chunk.js May 09 22:52:29 volumio volumio[877]: info: extracting: cgui/build/static/js/2.c1b9df8b.chunk.js.LICENSE.txt May 09 22:52:29 volumio volumio[877]: info: extracting: cgui/build/static/js/main.61bb6809.chunk.js May 09 22:52:29 volumio volumio[877]: info: extracting: cgui/build/static/js/main.61bb6809.chunk.js.map May 09 22:52:30 volumio sudo[1771]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/bin/rm cgui-1.0.0.zip May 09 22:52:30 volumio sudo[1771]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 22:52:30 volumio volumio[877]: info: extracting: cgui/build/static/js/2.c1b9df8b.chunk.js May 09 22:52:30 volumio volumio[877]: info: extracting: cgui/build/static/js/main.1b3ac690.chunk.js.map May 09 22:52:30 volumio volumio[877]: info: extracting: cgui/build/static/js/main.71e11955.js.LICENSE.txt May 09 22:52:30 volumio volumio[877]: info: extracting: cgui/build/static/js/main.71e11955.js.map May 09 22:52:30 volumio volumio[877]: info: extracting: cgui/build/static/js/runtime-main.422581ec.js.map May 09 22:52:30 volumio volumio[877]: info: extracting: cgui/build/static/js/main.1b3ac690.chunk.js May 09 22:52:30 volumio volumio[877]: info: extracting: cgui/build/static/js/2.c1b9df8b.chunk.js.map May 09 22:52:30 volumio volumio[877]: info: extracting: cgui/build/static/js/2.fd49ece5.chunk.js.LICENSE.txt May 09 22:52:30 volumio volumio[877]: info: creating directory: cgui/config/ May 09 22:52:30 volumio volumio[877]: info: extracting: cgui/config/camillagui.yml May 09 22:52:30 volumio sudo[1771]: pam_unix(sudo:session): session closed for user root May 09 22:52:30 volumio volumio[877]: info: extracting: cgui/config/.xdp-css-variables-1.css-ppBDuX May 09 22:52:30 volumio volumio[877]: info: extracting: cgui/config/gui-config.yml May 09 22:52:30 volumio volumio[877]: info: creating directory: cgui/backend/ May 09 22:52:30 volumio volumio[877]: info: extracting: cgui/backend/filters_test.py May 09 22:52:30 volumio volumio[877]: info: extracting: cgui/backend/version.py May 09 22:52:30 volumio volumio[877]: info: extracting: cgui/backend/filters.py May 09 22:52:30 volumio volumio[877]: info: extracting: cgui/backend/settings.py May 09 22:52:30 volumio volumio[877]: info: extracting: cgui/backend/views.py May 09 22:52:30 volumio volumio[877]: info: extracting: cgui/backend/filterdefaults.py May 09 22:52:30 volumio volumio[877]: info: extracting: cgui/backend/__init__.py May 09 22:52:30 volumio volumio[877]: info: creating directory: cgui/backend/__pycache__/ May 09 22:52:30 volumio volumio[877]: info: extracting: cgui/backend/__pycache__/filterdefaults.cpython-37.pyc May 09 22:52:30 volumio volumio[877]: info: extracting: cgui/backend/__pycache__/views.cpython-37.pyc May 09 22:52:30 volumio volumio[877]: info: extracting: cgui/backend/__pycache__/settings.cpython-37.pyc May 09 22:52:30 volumio volumio[877]: info: extracting: cgui/backend/__pycache__/__init__.cpython-37.pyc May 09 22:52:30 volumio volumio[877]: info: extracting: cgui/backend/__pycache__/routes.cpython-37.pyc May 09 22:52:30 volumio volumio[877]: info: extracting: cgui/backend/__pycache__/filemanagement.cpython-37.pyc May 09 22:52:30 volumio volumio[877]: info: extracting: cgui/backend/__pycache__/filters.cpython-37.pyc May 09 22:52:30 volumio volumio[877]: info: extracting: cgui/backend/__pycache__/version.cpython-37.pyc May 09 22:52:30 volumio volumio[877]: info: extracting: cgui/backend/routes.py May 09 22:52:30 volumio volumio[877]: info: extracting: cgui/backend/filemanagement.py May 09 22:52:30 volumio volumio[877]: info: extracting: cgui/main.py May 09 22:52:30 volumio volumio[877]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/fusiondsp/volumio/buster/armhf May 09 22:52:32 volumio volumio[877]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/fusiondsp/volumio/buster/armhf May 09 22:52:32 volumio volumio[877]: info: FusionDsp - Stopping FusionDsp service May 09 22:52:32 volumio volumio[877]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 09 22:52:32 volumio volumio[877]: TypeError: Cannot read property 'stop' of null May 09 22:52:32 volumio volumio[877]: at FusionDsp.onStop (/data/plugins/audio_interface/fusiondsp/index.js:109:23) May 09 22:52:32 volumio volumio[877]: at PluginManager.stopPlugin (/volumio/app/pluginmanager.js:460:30) May 09 22:52:32 volumio volumio[877]: at /volumio/app/pluginmanager.js:956:12 May 09 22:52:32 volumio volumio[877]: at ChildProcess.exithandler (child_process.js:299:7) May 09 22:52:32 volumio volumio[877]: at ChildProcess.emit (events.js:315:20) May 09 22:52:32 volumio volumio[877]: at maybeClose (internal/child_process.js:1048:16) May 09 22:52:32 volumio volumio[877]: at Process.ChildProcess._handle.onexit (internal/child_process.js:288:5) May 09 22:52:32 volumio volumio[877]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 09 22:52:33 volumio volumio[1134]: ...............................................................................................................................................................................................................................................................................................................................................++++ May 09 22:52:33 volumio volumio[1134]: e is 65537 (0x010001) May 09 22:52:33 volumio volumio[1134]: writing RSA key May 09 22:52:33 volumio sudo[1803]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-09 22:51 May 09 22:52:33 volumio sudo[1803]: 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="Wed 30 Apr 2025 11:47:57 AM CEST" VOLUMIO_VERSION="3.807" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fddd10196af80fdc784c726185a6abe4"