-- Logs begin at Thu 2019-02-14 11:11:58 CET, end at Fri 2025-02-28 11:02:42 CET. -- Feb 28 11:02:25 volumiobaloo systemd-timedated[1086]: Changed local time to Fri Feb 28 11:02:25 2025 Feb 28 11:02:25 volumiobaloo sudo[1076]: pam_unix(sudo:session): session closed for user root Feb 28 11:02:25 volumiobaloo volumio-time-update[610]: volumio-time-update-util: System time updated successfully. Feb 28 11:02:25 volumiobaloo systemd[1]: Starting Daily apt download activities... Feb 28 11:02:25 volumiobaloo volumio[1054]: info: ------------------------------------------- Feb 28 11:02:25 volumiobaloo systemd[1]: Started Volumio Time Update Utility. Feb 28 11:02:25 volumiobaloo volumio[1054]: info: ----- Volumio3 ---- Feb 28 11:02:25 volumiobaloo volumio[1054]: info: ------------------------------------------- Feb 28 11:02:25 volumiobaloo volumio[1054]: info: ----- System startup ---- Feb 28 11:02:25 volumiobaloo volumio[1054]: info: ------------------------------------------- Feb 28 11:02:25 volumiobaloo systemd[1]: apt-daily.service: Succeeded. Feb 28 11:02:25 volumiobaloo systemd[1]: Started Daily apt download activities. Feb 28 11:02:25 volumiobaloo systemd[1]: Starting Daily apt upgrade and clean activities... Feb 28 11:02:25 volumiobaloo volumio[1054]: info: MYVOLUMIO Environment detected Feb 28 11:02:25 volumiobaloo volumio[1054]: info: Plugin folders cleanup Feb 28 11:02:25 volumiobaloo volumio[1054]: info: Scanning into folder /volumio/app/plugins/ Feb 28 11:02:25 volumiobaloo volumio[1054]: info: Scanning category audio_interface Feb 28 11:02:25 volumiobaloo volumio[1054]: info: Scanning category miscellanea Feb 28 11:02:25 volumiobaloo volumio[1054]: info: Scanning category music_service Feb 28 11:02:25 volumiobaloo volumio[1054]: info: Scanning category plugins.json Feb 28 11:02:25 volumiobaloo volumio[1054]: info: Scanning category system_controller Feb 28 11:02:25 volumiobaloo volumio[1054]: info: Scanning category user_interface Feb 28 11:02:25 volumiobaloo volumio[1054]: info: Scanning into folder /data/plugins/ Feb 28 11:02:25 volumiobaloo volumio[1054]: info: Scanning category music_service Feb 28 11:02:25 volumiobaloo volumio[1054]: info: Plugin folders cleanup completed Feb 28 11:02:25 volumiobaloo volumio[1054]: info: ------------------------------------------- Feb 28 11:02:25 volumiobaloo volumio[1054]: info: ----- Core plugins startup ---- Feb 28 11:02:25 volumiobaloo volumio[1054]: info: ------------------------------------------- Feb 28 11:02:25 volumiobaloo volumio[1054]: info: Loading plugins from folder /volumio/app/plugins/ Feb 28 11:02:25 volumiobaloo volumio[1054]: info: Adding plugin upnp to MyMusic Plugins Feb 28 11:02:25 volumiobaloo volumio[1054]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 28 11:02:25 volumiobaloo volumio[1054]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 28 11:02:25 volumiobaloo volumio[1054]: info: Loading plugins from folder /data/plugins/ Feb 28 11:02:25 volumiobaloo volumio[1054]: info: Loading plugin "system"... Feb 28 11:02:25 volumiobaloo volumio[1054]: info: Loading plugin "appearance"... Feb 28 11:02:25 volumiobaloo nmbd[748]: [2025/02/28 11:02:25.903444, 0] ../lib/util/become_daemon.c:138(daemon_ready) Feb 28 11:02:25 volumiobaloo nmbd[748]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Feb 28 11:02:25 volumiobaloo systemd[1]: Started Samba NMB Daemon. Feb 28 11:02:25 volumiobaloo systemd[1]: Starting Samba Winbind Daemon... Feb 28 11:02:26 volumiobaloo systemd[1]: apt-daily-upgrade.service: Succeeded. Feb 28 11:02:26 volumiobaloo systemd[1]: Started Daily apt upgrade and clean activities. Feb 28 11:02:26 volumiobaloo winbindd[1169]: [2025/02/28 11:02:26.211541, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Feb 28 11:02:26 volumiobaloo winbindd[1169]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Feb 28 11:02:26 volumiobaloo winbindd[1169]: [2025/02/28 11:02:26.222334, 0] ../lib/util/become_daemon.c:138(daemon_ready) Feb 28 11:02:26 volumiobaloo systemd[1]: Started Samba Winbind Daemon. Feb 28 11:02:26 volumiobaloo winbindd[1169]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Feb 28 11:02:26 volumiobaloo systemd[1]: Starting Samba SMB Daemon... Feb 28 11:02:26 volumiobaloo nmbd[748]: [2025/02/28 11:02:26.338372, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Feb 28 11:02:26 volumiobaloo nmbd[748]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.1.140 for name WORKGROUP<1d>. Feb 28 11:02:26 volumiobaloo nmbd[748]: This response was from IP 192.168.1.111, reporting an IP address of 192.168.1.111. Feb 28 11:02:26 volumiobaloo smbd[1194]: [2025/02/28 11:02:26.631317, 0] ../lib/util/become_daemon.c:138(daemon_ready) Feb 28 11:02:26 volumiobaloo systemd[1]: Started Samba SMB Daemon. Feb 28 11:02:26 volumiobaloo smbd[1194]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Feb 28 11:02:26 volumiobaloo systemd[1]: Reached target Multi-User System. Feb 28 11:02:26 volumiobaloo systemd[1]: Reached target Graphical Interface. Feb 28 11:02:26 volumiobaloo systemd[1]: Starting Update UTMP about System Runlevel Changes... Feb 28 11:02:26 volumiobaloo systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Feb 28 11:02:26 volumiobaloo systemd[1]: Started Update UTMP about System Runlevel Changes. Feb 28 11:02:26 volumiobaloo systemd[1]: Startup finished in 11.802s (kernel) + 26.148s (userspace) = 37.950s. Feb 28 11:02:26 volumiobaloo volumio-remote-updater[617]: [2025-02-28 11:02:26] [connect] Successful connection Feb 28 11:02:27 volumiobaloo volumio[1054]: info: Loading plugin "network"... Feb 28 11:02:27 volumiobaloo volumio[1054]: info: Refreshing Cached IP Addresses Feb 28 11:02:27 volumiobaloo sudo[1204]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 28 11:02:27 volumiobaloo sudo[1204]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 11:02:27 volumiobaloo sudo[1204]: pam_unix(sudo:session): session closed for user root Feb 28 11:02:27 volumiobaloo sudo[1206]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 28 11:02:27 volumiobaloo sudo[1206]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 11:02:27 volumiobaloo sudo[1206]: pam_unix(sudo:session): session closed for user root Feb 28 11:02:27 volumiobaloo volumio[1054]: info: Loading plugin "services"... Feb 28 11:02:27 volumiobaloo volumio[1054]: info: Loading plugin "alsa_controller"... Feb 28 11:02:27 volumiobaloo sudo[1215]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 28 11:02:27 volumiobaloo sudo[1215]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 11:02:27 volumiobaloo volumio[1054]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 28 11:02:27 volumiobaloo volumio[1054]: info: Loading plugin "wizard"... Feb 28 11:02:27 volumiobaloo volumio[1054]: info: Loading plugin "networkfs"... Feb 28 11:02:27 volumiobaloo volumio[1054]: info: Starting Udev Watcher for removable devices Feb 28 11:02:27 volumiobaloo sudo[1232]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=Baloo,password=Q7ypPFGSBbrTtw3$#mnz,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.116/Music /mnt/NAS/BalooNasMusic Feb 28 11:02:27 volumiobaloo sudo[1232]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 11:02:27 volumiobaloo volumio[1054]: info: Ignoring mount for partition: boot Feb 28 11:02:27 volumiobaloo volumio[1054]: info: Ignoring mount for partition: volumio Feb 28 11:02:27 volumiobaloo volumio[1054]: info: Ignoring mount for partition: volumio_data Feb 28 11:02:27 volumiobaloo volumio[1054]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 28 11:02:27 volumiobaloo volumio[1054]: info: Loading plugin "volumio_command_line_client"... Feb 28 11:02:27 volumiobaloo volumio[1054]: info: Loading plugin "upnp"... Feb 28 11:02:27 volumiobaloo volumio[1054]: info: [1740736947308] Starting Upmpd Daemon Feb 28 11:02:27 volumiobaloo volumio[1054]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 28 11:02:27 volumiobaloo volumio[1054]: info: Loading plugin "my_music"... Feb 28 11:02:27 volumiobaloo volumio[1054]: info: Loading plugin "mpd"... Feb 28 11:02:27 volumiobaloo kernel: Key type cifs.spnego registered Feb 28 11:02:27 volumiobaloo kernel: Key type cifs.idmap registered Feb 28 11:02:27 volumiobaloo 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. Feb 28 11:02:27 volumiobaloo kernel: CIFS: Attempting to mount //192.168.1.116/Music Feb 28 11:02:27 volumiobaloo volumio[1054]: info: Loading plugin "upnp_browser"... Feb 28 11:02:27 volumiobaloo kernel: cryptd: max_cpu_qlen set to 1000 Feb 28 11:02:27 volumiobaloo volumio[1054]: info: Loading plugin "alarm-clock"... Feb 28 11:02:28 volumiobaloo volumio[1054]: info: Loading plugin "airplay_emulation"... Feb 28 11:02:28 volumiobaloo volumio[1054]: info: Starting Shairport Sync Feb 28 11:02:28 volumiobaloo volumio[1054]: info: Loading plugin "last_100"... Feb 28 11:02:28 volumiobaloo volumio[1054]: info: Loading plugin "webradio"... Feb 28 11:02:28 volumiobaloo volumio[1054]: info: Loading plugin "i2s_dacs"... Feb 28 11:02:28 volumiobaloo volumio[1054]: info: Loading plugin "volumiodiscovery"... Feb 28 11:02:28 volumiobaloo volumio[1054]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 28 11:02:28 volumiobaloo volumio[1054]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 28 11:02:28 volumiobaloo volumio[1054]: *** WARNING *** For more information see Feb 28 11:02:28 volumiobaloo node[1054]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 28 11:02:28 volumiobaloo volumio[1054]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 28 11:02:28 volumiobaloo volumio[1054]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 28 11:02:28 volumiobaloo volumio[1054]: *** WARNING *** For more information see Feb 28 11:02:28 volumiobaloo node[1054]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 28 11:02:28 volumiobaloo node[1054]: *** WARNING *** For more information see Feb 28 11:02:28 volumiobaloo node[1054]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 28 11:02:28 volumiobaloo node[1054]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 28 11:02:28 volumiobaloo node[1054]: *** WARNING *** For more information see Feb 28 11:02:28 volumiobaloo volumio[1054]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 28 11:02:28 volumiobaloo volumio[1054]: info: Discovery: Started advertising with name: VolumioBaloo Feb 28 11:02:28 volumiobaloo volumio[1054]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 28 11:02:28 volumiobaloo volumio[1054]: info: Loading plugin "spop"... Feb 28 11:02:28 volumiobaloo sudo[1232]: pam_unix(sudo:session): session closed for user root Feb 28 11:02:29 volumiobaloo volumio[1054]: info: Loading plugin "outputs"... Feb 28 11:02:29 volumiobaloo volumio[1054]: info: Loading plugin "albumart"... Feb 28 11:02:29 volumiobaloo volumio[1054]: info: Plugin example_plugin is not enabled Feb 28 11:02:29 volumiobaloo volumio[1054]: info: Loading plugin "inputs"... Feb 28 11:02:29 volumiobaloo volumio[1054]: info: Loading plugin "updater_comm"... Feb 28 11:02:29 volumiobaloo volumio[1054]: info: Plugin mpdemulation is not enabled Feb 28 11:02:29 volumiobaloo volumio[1054]: info: Loading plugin "rest_api"... Feb 28 11:02:29 volumiobaloo volumio[1054]: info: Loading plugin "websocket"... Feb 28 11:02:29 volumiobaloo volumio[1054]: info: Starting Socket.io Server version 2.3.0 Feb 28 11:02:29 volumiobaloo volumio[1054]: info: Loading i18n strings for locale fr Feb 28 11:02:29 volumiobaloo volumio[1054]: Updating browse sources language Feb 28 11:02:29 volumiobaloo volumio[1054]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 28 11:02:29 volumiobaloo volumio[1054]: Forking 3 albumart workers Feb 28 11:02:29 volumiobaloo volumio[1054]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 28 11:02:29 volumiobaloo volumio[1054]: info: CoreCommandRouter::initPlayerControls Feb 28 11:02:29 volumiobaloo volumio[1054]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 28 11:02:29 volumiobaloo volumio[1054]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 28 11:02:29 volumiobaloo volumio[1054]: Express server listening on port 3000 Feb 28 11:02:29 volumiobaloo volumio[1054]: [Metrics] WebUI: 5s 287.42ms Feb 28 11:02:29 volumiobaloo volumio[1054]: info: CoreStateMachine::resetVolumioState Feb 28 11:02:29 volumiobaloo volumio[1054]: info: CoreStateMachine::getcurrentVolume Feb 28 11:02:29 volumiobaloo volumio[1054]: info: CoreCommandRouter::volumioRetrievevolume Feb 28 11:02:29 volumiobaloo volumio[1054]: info: Volumio Network Manager: Network status updated: 2 Feb 28 11:02:29 volumiobaloo volumio-remote-updater[617]: [2025-02-28 11:02:29] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1740736946 101 Feb 28 11:02:29 volumiobaloo volumio[1054]: Starting albumart workers Feb 28 11:02:30 volumiobaloo volumio[1054]: 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: 1 Feb 28 11:02:30 volumiobaloo volumio[1054]: Starting albumart workers Feb 28 11:02:30 volumiobaloo volumio[1054]: Starting albumart workers Feb 28 11:02:30 volumiobaloo volumio[1054]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 28 11:02:30 volumiobaloo volumio[1054]: info: Executing start script for DAC IQaudIO DAC Plus Feb 28 11:02:30 volumiobaloo volumio[1054]: info: Reloading queue from file Feb 28 11:02:30 volumiobaloo volumio[1054]: info: CoreStateMachine::setRepeat null single undefined Feb 28 11:02:30 volumiobaloo volumio[1054]: info: CoreStateMachine::pushState Feb 28 11:02:30 volumiobaloo volumio[1054]: info: CorePlayQueue::getTrack 0 Feb 28 11:02:30 volumiobaloo volumio[1054]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 28 11:02:30 volumiobaloo volumio[1054]: info: CoreCommandRouter::volumioPushState Feb 28 11:02:30 volumiobaloo volumio[1054]: info: CoreStateMachine::setRandom null Feb 28 11:02:30 volumiobaloo volumio[1054]: info: CoreStateMachine::pushState Feb 28 11:02:30 volumiobaloo volumio[1054]: info: CorePlayQueue::getTrack 0 Feb 28 11:02:30 volumiobaloo volumio[1054]: info: CoreCommandRouter::volumioPushState Feb 28 11:02:30 volumiobaloo volumio[1054]: info: Setting Device type: Raspberry PI Feb 28 11:02:30 volumiobaloo volumio[1054]: info: VolumeController:: Volume=96 Mute =false Feb 28 11:02:30 volumiobaloo volumio[1054]: info: CoreStateMachine::pushState Feb 28 11:02:30 volumiobaloo volumio[1054]: info: CorePlayQueue::getTrack 0 Feb 28 11:02:30 volumiobaloo volumio[1054]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 28 11:02:30 volumiobaloo volumio[1054]: info: CoreCommandRouter::volumioPushState Feb 28 11:02:30 volumiobaloo volumio[1054]: info: CoreStateMachine::updateTrackBlock Feb 28 11:02:30 volumiobaloo volumio[1054]: info: CorePlayQueue::getTrackBlock Feb 28 11:02:30 volumiobaloo volumio[1054]: info: CoreCommandRouter::volumioRetrievevolume Feb 28 11:02:30 volumiobaloo volumio[1054]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Feb 28 11:02:30 volumiobaloo volumio[1054]: info: USB Boot Capable - System SBC Revision found in cpuinfo: c03115 Feb 28 11:02:30 volumiobaloo volumio[1054]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Feb 28 11:02:30 volumiobaloo volumio[1054]: info: DAC script executed Feb 28 11:02:30 volumiobaloo volumio[1054]: info: Completed loading Core Plugins Feb 28 11:02:30 volumiobaloo volumio[1054]: info: Preparing to generate the ALSA configuration file Feb 28 11:02:30 volumiobaloo volumio[1054]: info: Asound.conf file unchanged, so no further update is needed Feb 28 11:02:30 volumiobaloo volumio[1054]: info: Output device has changed, restarting MPD Feb 28 11:02:30 volumiobaloo volumio[1054]: info: Output device has changed, restarting Shairport Sync Feb 28 11:02:30 volumiobaloo sudo[1320]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 28 11:02:30 volumiobaloo volumio[1054]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 28 11:02:30 volumiobaloo volumio[1054]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 28 11:02:30 volumiobaloo sudo[1320]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 11:02:30 volumiobaloo sudo[1320]: pam_unix(sudo:session): session closed for user root Feb 28 11:02:30 volumiobaloo sudo[1322]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 28 11:02:30 volumiobaloo sudo[1322]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 11:02:30 volumiobaloo systemd[1]: Stopping Music Player Daemon... Feb 28 11:02:30 volumiobaloo volumio[1054]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 28 11:02:30 volumiobaloo volumio[1054]: info: ___________ START PLUGINS ___________ Feb 28 11:02:30 volumiobaloo volumio[1054]: info: ControllerMpd::onStart: Initializing MPD Feb 28 11:02:30 volumiobaloo volumio[1054]: info: Creating MPD Configuration file Feb 28 11:02:30 volumiobaloo sudo[1215]: pam_unix(sudo:session): session closed for user root Feb 28 11:02:30 volumiobaloo volumio[1054]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 28 11:02:30 volumiobaloo volumio[1054]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 28 11:02:30 volumiobaloo volumio[1054]: info: [1740736950421] CoreMusicLibrary::Adding element Serveurs Média Feb 28 11:02:30 volumiobaloo volumio[1054]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 28 11:02:30 volumiobaloo volumio[1054]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 28 11:02:30 volumiobaloo volumio[1054]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 28 11:02:30 volumiobaloo sudo[1331]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 28 11:02:30 volumiobaloo sudo[1331]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 11:02:30 volumiobaloo systemd[1]: mpd.service: Succeeded. Feb 28 11:02:30 volumiobaloo sudo[1329]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 28 11:02:30 volumiobaloo systemd[1]: Stopped Music Player Daemon. Feb 28 11:02:30 volumiobaloo sudo[1329]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 11:02:30 volumiobaloo sudo[1329]: pam_unix(sudo:session): session closed for user root Feb 28 11:02:30 volumiobaloo systemd[1]: Starting Music Player Daemon... Feb 28 11:02:30 volumiobaloo volumio[1054]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 28 11:02:30 volumiobaloo systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Feb 28 11:02:30 volumiobaloo systemd[1]: mpd.service: Succeeded. Feb 28 11:02:30 volumiobaloo systemd[1]: Stopped Music Player Daemon. Feb 28 11:02:30 volumiobaloo volumio[1054]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 28 11:02:30 volumiobaloo volumio[1054]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 28 11:02:30 volumiobaloo systemd[1]: Starting Music Player Daemon... Feb 28 11:02:30 volumiobaloo volumio[1054]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 28 11:02:30 volumiobaloo volumio[1054]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 28 11:02:30 volumiobaloo volumio[1054]: info: [1740736950595] CoreMusicLibrary::Adding element Last_100 Feb 28 11:02:30 volumiobaloo volumio[1054]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 28 11:02:30 volumiobaloo volumio[1054]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 28 11:02:30 volumiobaloo volumio[1054]: info: [1740736950599] CoreMusicLibrary::Adding element Webradio Feb 28 11:02:30 volumiobaloo volumio[1054]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 28 11:02:30 volumiobaloo volumio[1054]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 28 11:02:30 volumiobaloo volumio[1054]: info: Initializing BBC Radios Feb 28 11:02:30 volumiobaloo sudo[1356]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 28 11:02:30 volumiobaloo sudo[1356]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 11:02:30 volumiobaloo sudo[1356]: pam_unix(sudo:session): session closed for user root Feb 28 11:02:30 volumiobaloo volumio[1054]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 28 11:02:30 volumiobaloo volumio[1054]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 28 11:02:30 volumiobaloo volumio[1054]: info: Creating Spotify config file Feb 28 11:02:30 volumiobaloo volumio[1054]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 28 11:02:30 volumiobaloo volumio[1054]: info: Volumio Calling Home Feb 28 11:02:30 volumiobaloo volumio[1054]: info: Discovery: adding 26e420a0-5775-49bb-9f5b-7f49601bacb8 Feb 28 11:02:30 volumiobaloo volumio[1054]: info: Discovery: Found device VolumioBaloo Feb 28 11:02:30 volumiobaloo volumio[1054]: info: CoreCommandRouter::volumioGetState Feb 28 11:02:30 volumiobaloo volumio[1054]: info: CorePlayQueue::getTrack 0 Feb 28 11:02:30 volumiobaloo volumio[1054]: info: MPD Permissions set Feb 28 11:02:30 volumiobaloo volumio[1054]: info: MPD Permissions set Feb 28 11:02:30 volumiobaloo volumio[1054]: info: VolumeController:: Volume=96 Mute =false Feb 28 11:02:30 volumiobaloo volumio[1054]: info: CoreStateMachine::pushState Feb 28 11:02:30 volumiobaloo volumio[1054]: info: CorePlayQueue::getTrack 0 Feb 28 11:02:30 volumiobaloo volumio[1054]: info: CoreCommandRouter::volumioPushState Feb 28 11:02:30 volumiobaloo volumio[1054]: info: Spotify config file written Feb 28 11:02:30 volumiobaloo sudo[1382]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Feb 28 11:02:30 volumiobaloo sudo[1382]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 11:02:30 volumiobaloo volumio[1054]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Feb 28 11:02:30 volumiobaloo volumio[1054]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 28 11:02:30 volumiobaloo volumio[1054]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 28 11:02:30 volumiobaloo volumio[1054]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 28 11:02:30 volumiobaloo volumio[1054]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 28 11:02:30 volumiobaloo volumio[1054]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 28 11:02:30 volumiobaloo volumio[1054]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 28 11:02:30 volumiobaloo volumio[1054]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 28 11:02:30 volumiobaloo volumio[1054]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 28 11:02:30 volumiobaloo volumio[1054]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 28 11:02:30 volumiobaloo volumio[1054]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 28 11:02:30 volumiobaloo volumio[1054]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 28 11:02:30 volumiobaloo volumio[1054]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 28 11:02:30 volumiobaloo volumio[1054]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 28 11:02:30 volumiobaloo volumio[1054]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 28 11:02:30 volumiobaloo volumio[1054]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 28 11:02:30 volumiobaloo volumio[1054]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 28 11:02:30 volumiobaloo volumio[1054]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 28 11:02:30 volumiobaloo volumio[1054]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 28 11:02:30 volumiobaloo volumio[1054]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 28 11:02:30 volumiobaloo volumio[1054]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 28 11:02:30 volumiobaloo volumio[1054]: info: No need to fix Spotify hosts Feb 28 11:02:30 volumiobaloo systemd[1]: Started go-librespot Daemon. Feb 28 11:02:30 volumiobaloo go-librespot[1388]: go-librespot daemon starting... Feb 28 11:02:30 volumiobaloo sudo[1382]: pam_unix(sudo:session): session closed for user root Feb 28 11:02:30 volumiobaloo volumio[1054]: info: Volumio called home Feb 28 11:02:31 volumiobaloo volumio[1054]: info: Starting Shairport Sync Feb 28 11:02:31 volumiobaloo volumio[1054]: info: Starting Shairport Sync Feb 28 11:02:31 volumiobaloo volumio[1054]: info: Starting Shairport Sync Feb 28 11:02:31 volumiobaloo sudo[1395]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 28 11:02:31 volumiobaloo sudo[1395]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 11:02:31 volumiobaloo sudo[1397]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 28 11:02:31 volumiobaloo sudo[1397]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 11:02:31 volumiobaloo systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Feb 28 11:02:31 volumiobaloo systemd[1]: shairport-sync.service: Succeeded. Feb 28 11:02:31 volumiobaloo systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Feb 28 11:02:31 volumiobaloo sudo[1403]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 28 11:02:31 volumiobaloo sudo[1403]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 11:02:31 volumiobaloo volumio[1054]: info: CoreCommandRouter::volumioGetState Feb 28 11:02:31 volumiobaloo volumio[1054]: info: CorePlayQueue::getTrack 0 Feb 28 11:02:31 volumiobaloo systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Feb 28 11:02:31 volumiobaloo sudo[1395]: pam_unix(sudo:session): session closed for user root Feb 28 11:02:31 volumiobaloo volumio[1054]: info: Shairport-Sync Started Feb 28 11:02:31 volumiobaloo volumio[1054]: Error adding Membership: Error: addMembership EINVAL Feb 28 11:02:31 volumiobaloo systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Feb 28 11:02:31 volumiobaloo systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Feb 28 11:02:31 volumiobaloo systemd[1]: shairport-sync.service: Succeeded. Feb 28 11:02:31 volumiobaloo systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Feb 28 11:02:31 volumiobaloo systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Feb 28 11:02:31 volumiobaloo sudo[1397]: pam_unix(sudo:session): session closed for user root Feb 28 11:02:31 volumiobaloo sudo[1403]: pam_unix(sudo:session): session closed for user root Feb 28 11:02:31 volumiobaloo volumio[1054]: info: Shairport-Sync Started Feb 28 11:02:31 volumiobaloo volumio[1054]: info: Shairport-Sync Started Feb 28 11:02:31 volumiobaloo go-librespot[1388]: time="2025-02-28T11:02:31+01:00" level=info msg="running go-librespot 0.2.0" Feb 28 11:02:31 volumiobaloo go-librespot[1388]: time="2025-02-28T11:02:31+01:00" level=debug msg="app state loaded" Feb 28 11:02:31 volumiobaloo go-librespot[1388]: time="2025-02-28T11:02:31+01:00" level=debug msg="stored credentials not found" Feb 28 11:02:31 volumiobaloo go-librespot[1388]: time="2025-02-28T11:02:31+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 28 11:02:31 volumiobaloo mpd[1368]: Feb 28 11:02 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 28 11:02:31 volumiobaloo systemd[1]: Started Music Player Daemon. Feb 28 11:02:31 volumiobaloo sudo[1331]: pam_unix(sudo:session): session closed for user root Feb 28 11:02:31 volumiobaloo sudo[1322]: pam_unix(sudo:session): session closed for user root Feb 28 11:02:31 volumiobaloo volumio[1054]: info: Completed starting Core Plugins Feb 28 11:02:31 volumiobaloo volumio[1054]: info: ------------------------------------------- Feb 28 11:02:31 volumiobaloo volumio[1054]: info: ----- MyVolumio plugins startup ---- Feb 28 11:02:31 volumiobaloo volumio[1054]: info: ------------------------------------------- Feb 28 11:02:31 volumiobaloo volumio[1054]: info: [MyVolumio PluginManager] Fetching plans data.... Feb 28 11:02:31 volumiobaloo volumio[1054]: error: MPD error: The expression evaluated to a falsy value: Feb 28 11:02:31 volumiobaloo volumio[1054]: assert.ok(self.idling) Feb 28 11:02:31 volumiobaloo volumio[1054]: error: The expression evaluated to a falsy value: Feb 28 11:02:31 volumiobaloo volumio[1054]: assert.ok(self.idling) Feb 28 11:02:31 volumiobaloo volumio[1054]: info: MPD running with PID1368 Feb 28 11:02:31 volumiobaloo volumio[1054]: ,establishing connection Feb 28 11:02:31 volumiobaloo volumio[1054]: error: updateQueue error: null Feb 28 11:02:31 volumiobaloo volumio[1054]: error: updateQueue error: null Feb 28 11:02:31 volumiobaloo go-librespot[1388]: time="2025-02-28T11:02:31+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Feb 28 11:02:31 volumiobaloo go-librespot[1388]: time="2025-02-28T11:02:31+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Feb 28 11:02:31 volumiobaloo go-librespot[1388]: time="2025-02-28T11:02:31+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Feb 28 11:02:31 volumiobaloo go-librespot[1388]: time="2025-02-28T11:02:31+01:00" level=info msg="zeroconf server listening on port 38847" Feb 28 11:02:33 volumiobaloo systemd[1]: systemd-fsckd.service: Succeeded. Feb 28 11:02:34 volumiobaloo volumio[1054]: info: go-librespot daemon successfully initialized Feb 28 11:02:34 volumiobaloo sh[535]: timed out Feb 28 11:02:34 volumiobaloo dhcpcd[584]: timed out Feb 28 11:02:34 volumiobaloo sh[535]: dhcpcd exited Feb 28 11:02:34 volumiobaloo dhcpcd[584]: dhcpcd exited Feb 28 11:02:34 volumiobaloo sh[535]: ifup: failed to bring up eth0 Feb 28 11:02:34 volumiobaloo systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Feb 28 11:02:34 volumiobaloo systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Feb 28 11:02:35 volumiobaloo systemd[1]: systemd-hostnamed.service: Succeeded. Feb 28 11:02:36 volumiobaloo volumio[1054]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Feb 28 11:02:37 volumiobaloo volumio[1054]: info: Initializing connection to go-librespot Websocket Feb 28 11:02:37 volumiobaloo go-librespot[1388]: time="2025-02-28T11:02:37+01:00" level=debug msg="new websocket client" Feb 28 11:02:37 volumiobaloo volumio[1054]: info: Connection to go-librespot Websocket established Feb 28 11:02:37 volumiobaloo sudo[1437]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 28 11:02:37 volumiobaloo sudo[1437]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 11:02:37 volumiobaloo sudo[1437]: pam_unix(sudo:session): session closed for user root Feb 28 11:02:37 volumiobaloo sudo[1439]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 28 11:02:37 volumiobaloo sudo[1439]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 11:02:37 volumiobaloo sudo[1439]: pam_unix(sudo:session): session closed for user root Feb 28 11:02:37 volumiobaloo sudo[1443]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Feb 28 11:02:37 volumiobaloo sudo[1443]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 11:02:37 volumiobaloo systemd[1]: Started UPnP Renderer front-end to MPD. Feb 28 11:02:37 volumiobaloo sudo[1443]: pam_unix(sudo:session): session closed for user root Feb 28 11:02:37 volumiobaloo volumio[1054]: info: Upmpdcli Daemon Started Feb 28 11:02:37 volumiobaloo volumio[1445]: Generating RSA private key, 4096 bit long modulus (2 primes) Feb 28 11:02:37 volumiobaloo go-librespot[1388]: time="2025-02-28T11:02:37+01:00" level=debug msg="obtained new client token: AACxNNlgpNGiVehnkobN7FmEPPPHFEs4H8ckZ2cSyDGAuX+zdMpaxv7INOAEMn9twFnefKDRtBy2r8Czjp2izzgNQCB+Evr4e3mhjR3BCghIfaV6QQGQJ8ywG4Jz9VIlgM7jlrZNTqF01+ep6eujYYyyeANm//xSPYTZbipccPzIsyrhv8+Y2bKg2Ia6SkZTwLE8ERok2E78zsxduLzYAC2SclH0ZUprPDVmBP5eQvA23TPxOyCPDWJZ" Feb 28 11:02:37 volumiobaloo go-librespot[1388]: time="2025-02-28T11:02:37+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 28 11:02:37 volumiobaloo go-librespot[1388]: time="2025-02-28T11:02:37+01:00" level=debug msg="completed keyexchange" Feb 28 11:02:37 volumiobaloo go-librespot[1388]: time="2025-02-28T11:02:37+01:00" level=debug msg="completed challenge" Feb 28 11:02:37 volumiobaloo go-librespot[1388]: time="2025-02-28T11:02:37+01:00" level=info msg="authenticated AP as 117529739" Feb 28 11:02:37 volumiobaloo go-librespot[1388]: time="2025-02-28T11:02:37+01:00" level=info msg="authenticated Login5 as 117529739" Feb 28 11:02:37 volumiobaloo go-librespot[1388]: time="2025-02-28T11:02:37+01:00" level=info msg="accepted zeroconf user 117529739 from S21 Ultra de Baloo" Feb 28 11:02:38 volumiobaloo go-librespot[1388]: time="2025-02-28T11:02:38+01:00" level=debug msg="dealer connection opened" Feb 28 11:02:38 volumiobaloo go-librespot[1388]: time="2025-02-28T11:02:38+01:00" level=trace msg="starting accesspoint recv loop" Feb 28 11:02:38 volumiobaloo go-librespot[1388]: time="2025-02-28T11:02:38+01:00" level=trace msg="starting dealer recv loop" Feb 28 11:02:38 volumiobaloo go-librespot[1388]: time="2025-02-28T11:02:38+01:00" level=trace msg="received accesspoint ping" Feb 28 11:02:38 volumiobaloo go-librespot[1388]: time="2025-02-28T11:02:38+01:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Feb 28 11:02:38 volumiobaloo go-librespot[1388]: time="2025-02-28T11:02:38+01:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Feb 28 11:02:38 volumiobaloo go-librespot[1388]: time="2025-02-28T11:02:38+01:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Feb 28 11:02:38 volumiobaloo go-librespot[1388]: time="2025-02-28T11:02:38+01:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Feb 28 11:02:38 volumiobaloo go-librespot[1388]: time="2025-02-28T11:02:38+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Feb 28 11:02:38 volumiobaloo go-librespot[1388]: time="2025-02-28T11:02:38+01:00" level=debug msg="received connection id: MDRmNWYzN2MtNjdiZC00NzgxLTk0NDYtNGM5NTJlYTBiOGQ5K2RlYWxlcit0Y3A6Ly8wYWNhNTkxMC5pcC5nZXc0LnNwb3RpZnkubmV0OjU3MDArRTAwQ0RBMkQyMDhCRDAyMkIwNDdCQjNFMUI1RThFREQ4NTZDNEI4NzJCRUI0RUJGRDg5Qzc0RDlCOTFBQ0FFNQ==" Feb 28 11:02:38 volumiobaloo go-librespot[1388]: time="2025-02-28T11:02:38+01:00" level=trace msg="received accesspoint pong ack" Feb 28 11:02:38 volumiobaloo go-librespot[1388]: time="2025-02-28T11:02:38+01:00" level=debug msg="put connect state because NEW_DEVICE" Feb 28 11:02:38 volumiobaloo go-librespot[1388]: time="2025-02-28T11:02:38+01:00" level=debug msg="handling transfer player command from 25e399c1721513702a293de95265dd4761696c1f" Feb 28 11:02:38 volumiobaloo go-librespot[1388]: time="2025-02-28T11:02:38+01:00" level=debug msg="resolved context of track" uri="spotify:playlist:37i9dQZF1DX45qfzFXwcta" Feb 28 11:02:38 volumiobaloo go-librespot[1388]: time="2025-02-28T11:02:38+01:00" level=trace msg="fetched new page 0 with 118 items (list: 118)" uri="spotify:playlist:37i9dQZF1DX45qfzFXwcta" Feb 28 11:02:38 volumiobaloo go-librespot[1388]: time="2025-02-28T11:02:38+01:00" level=debug msg="shuffled context with seed 256223917114696609 (len: 118, keep: 58)" uri="spotify:playlist:37i9dQZF1DX45qfzFXwcta" Feb 28 11:02:38 volumiobaloo go-librespot[1388]: time="2025-02-28T11:02:38+01:00" level=debug msg="loading track (paused: false, position: 50899ms)" uri="spotify:track:5e8wwhtHBs0WXmZTgRl4ub" Feb 28 11:02:38 volumiobaloo go-librespot[1388]: time="2025-02-28T11:02:38+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Feb 28 11:02:38 volumiobaloo go-librespot[1388]: time="2025-02-28T11:02:38+01:00" level=trace msg="emitting websocket event: will_play" Feb 28 11:02:38 volumiobaloo volumio[1054]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:5e8wwhtHBs0WXmZTgRl4ub","play_origin":"playlist"}} Feb 28 11:02:38 volumiobaloo go-librespot[1388]: time="2025-02-28T11:02:38+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 345" Feb 28 11:02:38 volumiobaloo go-librespot[1388]: time="2025-02-28T11:02:38+01:00" level=debug msg="selected format OGG_VORBIS_320 (7aa4f90887540d944acb902649be21ee40dae89f)" uri="spotify:track:5e8wwhtHBs0WXmZTgRl4ub" Feb 28 11:02:38 volumiobaloo go-librespot[1388]: time="2025-02-28T11:02:38+01:00" level=debug msg="requested aes key for file 7aa4f90887540d944acb902649be21ee40dae89f, gid: 5e8wwhtHBs0WXmZTgRl4ub" Feb 28 11:02:38 volumiobaloo go-librespot[1388]: time="2025-02-28T11:02:38+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 3105" Feb 28 11:02:39 volumiobaloo go-librespot[1388]: time="2025-02-28T11:02:39+01:00" level=debug msg="fetched first chunk of 18, total size is 9097592 bytes" uri="spotify:track:5e8wwhtHBs0WXmZTgRl4ub" Feb 28 11:02:39 volumiobaloo go-librespot[1388]: time="2025-02-28T11:02:39+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1999" Feb 28 11:02:39 volumiobaloo go-librespot[1388]: time="2025-02-28T11:02:39+01:00" level=debug msg="fetched chunk 2/17, size: 524288" uri="spotify:track:5e8wwhtHBs0WXmZTgRl4ub" Feb 28 11:02:39 volumiobaloo go-librespot[1388]: time="2025-02-28T11:02:39+01:00" level=debug msg="fetched chunk 3/17, size: 524288" uri="spotify:track:5e8wwhtHBs0WXmZTgRl4ub" Feb 28 11:02:39 volumiobaloo go-librespot[1388]: time="2025-02-28T11:02:39+01:00" level=trace msg="seek to 50899ms (diff: 191ms, samples: 2244645, bytes: 1945437)" uri="spotify:track:5e8wwhtHBs0WXmZTgRl4ub" Feb 28 11:02:39 volumiobaloo go-librespot[1388]: time="2025-02-28T11:02:39+01:00" level=debug msg="created new output device" Feb 28 11:02:39 volumiobaloo kernel: pcm512x 1-004c: No SCLK, using BCLK: -2 Feb 28 11:02:39 volumiobaloo go-librespot[1388]: time="2025-02-28T11:02:39+01:00" level=debug msg="alsa driver configured, rate = 44100 bps, period time = 100000 us, period size = 4410 frames, buffer time = 500000 us, buffer size = 22050 frames, periods per buffer = 5 frames" Feb 28 11:02:39 volumiobaloo go-librespot[1388]: time="2025-02-28T11:02:39+01:00" level=info msg="loaded track \"Relax\" (paused: false, position: 50899ms, duration: 241837ms, prefetched: false)" uri="spotify:track:5e8wwhtHBs0WXmZTgRl4ub" Feb 28 11:02:39 volumiobaloo go-librespot[1388]: time="2025-02-28T11:02:39+01:00" level=debug msg="fetched chunk 1/17, size: 524288" uri="spotify:track:5e8wwhtHBs0WXmZTgRl4ub" Feb 28 11:02:39 volumiobaloo go-librespot[1388]: time="2025-02-28T11:02:39+01:00" level=debug msg="fetched chunk 6/17, size: 524288" uri="spotify:track:5e8wwhtHBs0WXmZTgRl4ub" Feb 28 11:02:39 volumiobaloo go-librespot[1388]: time="2025-02-28T11:02:39+01:00" level=debug msg="fetched chunk 5/17, size: 524288" uri="spotify:track:5e8wwhtHBs0WXmZTgRl4ub" Feb 28 11:02:39 volumiobaloo go-librespot[1388]: time="2025-02-28T11:02:39+01:00" level=debug msg="fetched chunk 4/17, size: 524288" uri="spotify:track:5e8wwhtHBs0WXmZTgRl4ub" Feb 28 11:02:39 volumiobaloo volumio[1445]: .......................++++ Feb 28 11:02:39 volumiobaloo volumio[1054]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Feb 28 11:02:39 volumiobaloo volumio[1054]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Feb 28 11:02:39 volumiobaloo volumio[1054]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Feb 28 11:02:39 volumiobaloo volumio[1054]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Feb 28 11:02:39 volumiobaloo volumio[1054]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Feb 28 11:02:39 volumiobaloo volumio[1054]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Feb 28 11:02:39 volumiobaloo volumio[1054]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Feb 28 11:02:39 volumiobaloo volumio[1054]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Feb 28 11:02:39 volumiobaloo volumio[1054]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Feb 28 11:02:39 volumiobaloo volumio[1054]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Feb 28 11:02:39 volumiobaloo volumio[1054]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Feb 28 11:02:39 volumiobaloo volumio[1054]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Feb 28 11:02:39 volumiobaloo volumio[1054]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Feb 28 11:02:39 volumiobaloo volumio[1054]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Feb 28 11:02:39 volumiobaloo volumio[1054]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Feb 28 11:02:39 volumiobaloo volumio[1054]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Feb 28 11:02:39 volumiobaloo volumio[1054]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Feb 28 11:02:39 volumiobaloo volumio[1054]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Feb 28 11:02:39 volumiobaloo volumio[1054]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Feb 28 11:02:39 volumiobaloo volumio[1054]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Feb 28 11:02:39 volumiobaloo volumio[1054]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Feb 28 11:02:39 volumiobaloo volumio[1054]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Feb 28 11:02:39 volumiobaloo volumio[1054]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Feb 28 11:02:39 volumiobaloo volumio[1054]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Feb 28 11:02:39 volumiobaloo volumio[1054]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Feb 28 11:02:39 volumiobaloo volumio[1054]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Feb 28 11:02:39 volumiobaloo volumio[1054]: info: Adding plugin bluetooth to MyMusic Plugins Feb 28 11:02:39 volumiobaloo volumio[1054]: info: Adding plugin multiroom to MyMusic Plugins Feb 28 11:02:39 volumiobaloo volumio[1054]: info: Adding plugin metavolumio to MyMusic Plugins Feb 28 11:02:39 volumiobaloo volumio[1054]: info: Adding plugin cd_controller to MyMusic Plugins Feb 28 11:02:39 volumiobaloo volumio[1054]: info: Adding plugin smart_inputs to MyMusic Plugins Feb 28 11:02:39 volumiobaloo volumio[1054]: info: Adding plugin tidalconnect to MyMusic Plugins Feb 28 11:02:39 volumiobaloo volumio[1054]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Feb 28 11:02:40 volumiobaloo go-librespot[1388]: time="2025-02-28T11:02:40+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Feb 28 11:02:40 volumiobaloo go-librespot[1388]: time="2025-02-28T11:02:40+01:00" level=trace msg="scheduling prefetch in 160s" Feb 28 11:02:40 volumiobaloo go-librespot[1388]: time="2025-02-28T11:02:40+01:00" level=trace msg="emitting websocket event: metadata" Feb 28 11:02:40 volumiobaloo go-librespot[1388]: time="2025-02-28T11:02:40+01:00" level=trace msg="emitting websocket event: active" Feb 28 11:02:40 volumiobaloo go-librespot[1388]: time="2025-02-28T11:02:40+01:00" level=debug msg="sending successful reply for dealer request" Feb 28 11:02:40 volumiobaloo go-librespot[1388]: time="2025-02-28T11:02:40+01:00" level=debug msg="skipping dealer message" uri=social-connect/v2/broadcast_status_update Feb 28 11:02:40 volumiobaloo go-librespot[1388]: time="2025-02-28T11:02:40+01:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Feb 28 11:02:40 volumiobaloo volumio[1054]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Feb 28 11:02:40 volumiobaloo volumio[1054]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Feb 28 11:02:40 volumiobaloo volumio[1054]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 28 11:02:40 volumiobaloo volumio[1054]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 28 11:02:40 volumiobaloo volumio[1054]: info: Starting MyVolumio Remote Streaming Endpoints Feb 28 11:02:40 volumiobaloo volumio[1054]: info: MyVolumio login type: Token Feb 28 11:02:40 volumiobaloo volumio[1054]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Feb 28 11:02:40 volumiobaloo volumio[1054]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Feb 28 11:02:40 volumiobaloo go-librespot[1388]: time="2025-02-28T11:02:40+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Feb 28 11:02:40 volumiobaloo go-librespot[1388]: time="2025-02-28T11:02:40+01:00" level=trace msg="emitting websocket event: playing" Feb 28 11:02:41 volumiobaloo volumio[1054]: info: Starting Streaming Service Transparent Proxy Feb 28 11:02:41 volumiobaloo volumio[1054]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Feb 28 11:02:41 volumiobaloo volumio[1054]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Feb 28 11:02:41 volumiobaloo volumio[1054]: info: Streaming services startup Feb 28 11:02:41 volumiobaloo volumio[1054]: info: Starting Streaming Daemon Feb 28 11:02:41 volumiobaloo sudo[1472]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 28 11:02:41 volumiobaloo sudo[1472]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 11:02:41 volumiobaloo volumio[1054]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Feb 28 11:02:41 volumiobaloo sudo[1472]: pam_unix(sudo:session): session closed for user root Feb 28 11:02:41 volumiobaloo volumio[1054]: info: Getting Spotify volume Feb 28 11:02:41 volumiobaloo volumio[1054]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:5e8wwhtHBs0WXmZTgRl4ub","name":"Relax","artist_names":["Marco Polo"],"album_name":"Port Authority: Instrumentals","album_cover_url":"https://i.scdn.co/image/ab67616d00001e02d800ccf97eb69d364f1626ba","position":50899,"duration":241837,"release_date":"year:2015 month:4 day:21","track_number":18,"disc_number":1}} Feb 28 11:02:41 volumiobaloo volumio[1054]: SPOTIFY: received: {"type":"active","data":null} Feb 28 11:02:41 volumiobaloo volumio[1054]: info: Aligning Spotify Volume to Volumio Volume Feb 28 11:02:41 volumiobaloo volumio[1054]: info: CoreCommandRouter::volumioGetState Feb 28 11:02:41 volumiobaloo volumio[1054]: info: CorePlayQueue::getTrack 0 Feb 28 11:02:41 volumiobaloo volumio[1054]: info: Setting Spotify Volume from Volumio: 96 Feb 28 11:02:41 volumiobaloo volumio[1054]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:5e8wwhtHBs0WXmZTgRl4ub","play_origin":"playlist"}} Feb 28 11:02:41 volumiobaloo volumio[1054]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 28 11:02:41 volumiobaloo volumio[1054]: TypeError: Cannot read property 'service' of undefined Feb 28 11:02:41 volumiobaloo volumio[1054]: at ControllerSpotify.identifyPlaybackMode (/data/plugins/music_service/spop/index.js:337:50) Feb 28 11:02:41 volumiobaloo volumio[1054]: at ControllerSpotify.parseEventState (/data/plugins/music_service/spop/index.js:263:18) Feb 28 11:02:41 volumiobaloo volumio[1054]: at WebSocket.message (/data/plugins/music_service/spop/index.js:190:14) Feb 28 11:02:41 volumiobaloo volumio[1054]: at WebSocket.emit (events.js:315:20) Feb 28 11:02:41 volumiobaloo volumio[1054]: at Receiver.receiverOnMessage (/data/plugins/music_service/spop/node_modules/ws/lib/websocket.js:1220:20) Feb 28 11:02:41 volumiobaloo volumio[1054]: at Receiver.emit (events.js:315:20) Feb 28 11:02:41 volumiobaloo volumio[1054]: at Receiver.dataMessage (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:596:14) Feb 28 11:02:41 volumiobaloo volumio[1054]: at Receiver.getData (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:496:10) Feb 28 11:02:41 volumiobaloo volumio[1054]: at Receiver.startLoop (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:167:16) Feb 28 11:02:41 volumiobaloo volumio[1054]: at Receiver._write (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:94:10) Feb 28 11:02:41 volumiobaloo volumio[1054]: at writeOrBuffer (internal/streams/writable.js:358:12) Feb 28 11:02:41 volumiobaloo volumio[1054]: at Receiver.Writable.write (internal/streams/writable.js:303:10) Feb 28 11:02:41 volumiobaloo volumio[1054]: at Socket.socketOnData (/data/plugins/music_service/spop/node_modules/ws/lib/websocket.js:1355:35) Feb 28 11:02:41 volumiobaloo volumio[1054]: at Socket.emit (events.js:315:20) Feb 28 11:02:41 volumiobaloo volumio[1054]: at addChunk (internal/streams/readable.js:309:12) Feb 28 11:02:41 volumiobaloo volumio[1054]: at readableAddChunk (internal/streams/readable.js:284:9) Feb 28 11:02:41 volumiobaloo volumio[1054]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 28 11:02:42 volumiobaloo sudo[1488]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-02-28 11:01 Feb 28 11:02:42 volumiobaloo sudo[1488]: 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="f6610a1100d2380eef33bd3004af43153c786f96" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="1c0e5daee99fe3a72cc38b0d1fe6cd78d7f001fe" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 16 Dec 2024 09:30:04 AM CET" VOLUMIO_VERSION="3.785" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fe0ac4b79aed19ccde4b5bd83d468193"