-- Logs begin at Thu 2019-02-14 11:11:58 CET, end at Thu 2025-02-20 07:18:08 CET. -- Feb 20 07:17:56 volumiobaloo systemd-timedated[1088]: Changed local time to Thu Feb 20 07:17:56 2025 Feb 20 07:17:56 volumiobaloo sudo[1086]: pam_unix(sudo:session): session closed for user root Feb 20 07:17:56 volumiobaloo systemd[1]: Starting Daily apt download activities... Feb 20 07:17:56 volumiobaloo volumio-time-update[651]: volumio-time-update-util: System time updated successfully. Feb 20 07:17:56 volumiobaloo systemd[1]: Started Volumio Time Update Utility. Feb 20 07:17:56 volumiobaloo systemd[1]: apt-daily.service: Succeeded. Feb 20 07:17:56 volumiobaloo systemd[1]: Started Daily apt download activities. Feb 20 07:17:56 volumiobaloo volumio[1051]: info: Loading plugin "network"... Feb 20 07:17:56 volumiobaloo volumio[1051]: info: Refreshing Cached IP Addresses Feb 20 07:17:56 volumiobaloo systemd[1]: Starting Daily apt upgrade and clean activities... Feb 20 07:17:56 volumiobaloo sudo[1139]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 20 07:17:56 volumiobaloo sudo[1139]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 20 07:17:56 volumiobaloo sudo[1139]: pam_unix(sudo:session): session closed for user root Feb 20 07:17:56 volumiobaloo sudo[1141]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 20 07:17:56 volumiobaloo sudo[1141]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 20 07:17:56 volumiobaloo sudo[1141]: pam_unix(sudo:session): session closed for user root Feb 20 07:17:56 volumiobaloo volumio[1051]: info: Loading plugin "services"... Feb 20 07:17:56 volumiobaloo volumio[1051]: info: Loading plugin "alsa_controller"... Feb 20 07:17:56 volumiobaloo sudo[1152]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 20 07:17:56 volumiobaloo sudo[1152]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 20 07:17:56 volumiobaloo nmbd[770]: [2025/02/20 07:17:56.647827, 0] ../lib/util/become_daemon.c:138(daemon_ready) Feb 20 07:17:56 volumiobaloo nmbd[770]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Feb 20 07:17:56 volumiobaloo systemd[1]: Started Samba NMB Daemon. Feb 20 07:17:56 volumiobaloo systemd[1]: Starting Samba Winbind Daemon... Feb 20 07:17:56 volumiobaloo volumio[1051]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 20 07:17:56 volumiobaloo volumio[1051]: info: Loading plugin "wizard"... Feb 20 07:17:56 volumiobaloo volumio[1051]: info: Loading plugin "networkfs"... Feb 20 07:17:56 volumiobaloo volumio[1051]: info: Starting Udev Watcher for removable devices Feb 20 07:17:56 volumiobaloo sudo[1188]: 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 20 07:17:56 volumiobaloo sudo[1188]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 20 07:17:56 volumiobaloo volumio[1051]: info: Ignoring mount for partition: boot Feb 20 07:17:56 volumiobaloo volumio[1051]: info: Ignoring mount for partition: volumio Feb 20 07:17:56 volumiobaloo volumio[1051]: info: Ignoring mount for partition: volumio_data Feb 20 07:17:56 volumiobaloo volumio[1051]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 20 07:17:56 volumiobaloo volumio[1051]: info: Loading plugin "volumio_command_line_client"... Feb 20 07:17:56 volumiobaloo volumio[1051]: info: Loading plugin "upnp"... Feb 20 07:17:56 volumiobaloo winbindd[1165]: [2025/02/20 07:17:56.874985, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Feb 20 07:17:56 volumiobaloo winbindd[1165]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Feb 20 07:17:56 volumiobaloo volumio[1051]: info: [1740032276878] Starting Upmpd Daemon Feb 20 07:17:56 volumiobaloo volumio[1051]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 20 07:17:56 volumiobaloo volumio[1051]: info: Loading plugin "my_music"... Feb 20 07:17:56 volumiobaloo volumio[1051]: info: Loading plugin "mpd"... Feb 20 07:17:56 volumiobaloo winbindd[1165]: [2025/02/20 07:17:56.909159, 0] ../lib/util/become_daemon.c:138(daemon_ready) Feb 20 07:17:56 volumiobaloo systemd[1]: Started Samba Winbind Daemon. Feb 20 07:17:56 volumiobaloo winbindd[1165]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Feb 20 07:17:56 volumiobaloo systemd[1]: Starting Samba SMB Daemon... Feb 20 07:17:57 volumiobaloo kernel: Key type cifs.spnego registered Feb 20 07:17:57 volumiobaloo kernel: Key type cifs.idmap registered Feb 20 07:17:57 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 20 07:17:57 volumiobaloo kernel: CIFS: Attempting to mount //192.168.1.116/Music Feb 20 07:17:57 volumiobaloo nmbd[770]: [2025/02/20 07:17:57.045746, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Feb 20 07:17:57 volumiobaloo nmbd[770]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.1.140 for name WORKGROUP<1d>. Feb 20 07:17:57 volumiobaloo nmbd[770]: This response was from IP 192.168.1.111, reporting an IP address of 192.168.1.111. Feb 20 07:17:57 volumiobaloo kernel: cryptd: max_cpu_qlen set to 1000 Feb 20 07:17:57 volumiobaloo systemd[1]: apt-daily-upgrade.service: Succeeded. Feb 20 07:17:57 volumiobaloo systemd[1]: Started Daily apt upgrade and clean activities. Feb 20 07:17:57 volumiobaloo volumio[1051]: info: Loading plugin "upnp_browser"... Feb 20 07:17:57 volumiobaloo smbd[1220]: [2025/02/20 07:17:57.405283, 0] ../lib/util/become_daemon.c:138(daemon_ready) Feb 20 07:17:57 volumiobaloo systemd[1]: Started Samba SMB Daemon. Feb 20 07:17:57 volumiobaloo smbd[1220]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Feb 20 07:17:57 volumiobaloo systemd[1]: Reached target Multi-User System. Feb 20 07:17:57 volumiobaloo systemd[1]: Reached target Graphical Interface. Feb 20 07:17:57 volumiobaloo systemd[1]: Starting Update UTMP about System Runlevel Changes... Feb 20 07:17:57 volumiobaloo systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Feb 20 07:17:57 volumiobaloo systemd[1]: Started Update UTMP about System Runlevel Changes. Feb 20 07:17:57 volumiobaloo systemd[1]: Startup finished in 12.007s (kernel) + 25.823s (userspace) = 37.830s. Feb 20 07:17:57 volumiobaloo sudo[1188]: pam_unix(sudo:session): session closed for user root Feb 20 07:17:57 volumiobaloo volumio[1051]: info: Loading plugin "alarm-clock"... Feb 20 07:17:57 volumiobaloo volumio[1051]: info: Loading plugin "airplay_emulation"... Feb 20 07:17:57 volumiobaloo volumio[1051]: info: Starting Shairport Sync Feb 20 07:17:57 volumiobaloo volumio[1051]: info: Loading plugin "last_100"... Feb 20 07:17:57 volumiobaloo volumio[1051]: info: Loading plugin "webradio"... Feb 20 07:17:57 volumiobaloo volumio[1051]: info: Loading plugin "i2s_dacs"... Feb 20 07:17:57 volumiobaloo volumio[1051]: info: Loading plugin "volumiodiscovery"... Feb 20 07:17:57 volumiobaloo volumio[1051]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 20 07:17:57 volumiobaloo volumio[1051]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 20 07:17:57 volumiobaloo node[1051]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 20 07:17:57 volumiobaloo volumio[1051]: *** WARNING *** For more information see Feb 20 07:17:57 volumiobaloo volumio[1051]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 20 07:17:57 volumiobaloo volumio[1051]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 20 07:17:57 volumiobaloo volumio[1051]: *** WARNING *** For more information see Feb 20 07:17:57 volumiobaloo node[1051]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 20 07:17:57 volumiobaloo node[1051]: *** WARNING *** For more information see Feb 20 07:17:57 volumiobaloo node[1051]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 20 07:17:57 volumiobaloo node[1051]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 20 07:17:57 volumiobaloo node[1051]: *** WARNING *** For more information see Feb 20 07:17:57 volumiobaloo volumio[1051]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 20 07:17:57 volumiobaloo volumio[1051]: info: Discovery: Started advertising with name: VolumioBaloo Feb 20 07:17:57 volumiobaloo volumio[1051]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 20 07:17:57 volumiobaloo volumio[1051]: info: Loading plugin "spop"... Feb 20 07:17:58 volumiobaloo volumio-remote-updater[618]: [2025-02-20 07:17:58] [connect] Successful connection Feb 20 07:17:58 volumiobaloo volumio[1051]: info: Loading plugin "outputs"... Feb 20 07:17:58 volumiobaloo volumio[1051]: info: Loading plugin "albumart"... Feb 20 07:17:58 volumiobaloo volumio[1051]: info: Plugin example_plugin is not enabled Feb 20 07:17:58 volumiobaloo volumio[1051]: info: Loading plugin "inputs"... Feb 20 07:17:58 volumiobaloo volumio[1051]: info: Loading plugin "updater_comm"... Feb 20 07:17:59 volumiobaloo volumio[1051]: info: Plugin mpdemulation is not enabled Feb 20 07:17:59 volumiobaloo volumio[1051]: info: Loading plugin "rest_api"... Feb 20 07:17:59 volumiobaloo volumio[1051]: info: Loading plugin "websocket"... Feb 20 07:17:59 volumiobaloo volumio[1051]: info: Starting Socket.io Server version 2.3.0 Feb 20 07:17:59 volumiobaloo volumio[1051]: Forking 3 albumart workers Feb 20 07:17:59 volumiobaloo volumio[1051]: info: Loading i18n strings for locale fr Feb 20 07:17:59 volumiobaloo volumio[1051]: Updating browse sources language Feb 20 07:17:59 volumiobaloo volumio[1051]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 20 07:17:59 volumiobaloo volumio[1051]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 20 07:17:59 volumiobaloo volumio[1051]: info: CoreCommandRouter::initPlayerControls Feb 20 07:17:59 volumiobaloo volumio[1051]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 20 07:17:59 volumiobaloo volumio[1051]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 20 07:17:59 volumiobaloo volumio[1051]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 20 07:17:59 volumiobaloo volumio[1051]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 20 07:17:59 volumiobaloo volumio[1051]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 20 07:17:59 volumiobaloo volumio[1051]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 20 07:17:59 volumiobaloo volumio[1051]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 20 07:17:59 volumiobaloo volumio[1051]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 20 07:17:59 volumiobaloo volumio[1051]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 20 07:17:59 volumiobaloo volumio[1051]: Express server listening on port 3000 Feb 20 07:17:59 volumiobaloo volumio[1051]: [Metrics] WebUI: 5s 183.66ms Feb 20 07:17:59 volumiobaloo volumio[1051]: info: CoreStateMachine::resetVolumioState Feb 20 07:17:59 volumiobaloo volumio[1051]: info: CoreStateMachine::getcurrentVolume Feb 20 07:17:59 volumiobaloo volumio[1051]: info: CoreCommandRouter::volumioRetrievevolume Feb 20 07:17:59 volumiobaloo volumio[1051]: info: Volumio Network Manager: Network status updated: 2 Feb 20 07:17:59 volumiobaloo volumio-remote-updater[618]: [2025-02-20 07:17:59] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1740032278 101 Feb 20 07:17:59 volumiobaloo volumio[1051]: 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 20 07:17:59 volumiobaloo volumio[1051]: info: Reloading queue from file Feb 20 07:17:59 volumiobaloo volumio[1051]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 20 07:17:59 volumiobaloo volumio[1051]: info: Executing start script for DAC IQaudIO DAC Plus Feb 20 07:17:59 volumiobaloo volumio[1051]: info: VolumeController:: Volume=100 Mute =false Feb 20 07:17:59 volumiobaloo volumio[1051]: info: CoreStateMachine::pushState Feb 20 07:17:59 volumiobaloo volumio[1051]: info: CorePlayQueue::getTrack 0 Feb 20 07:17:59 volumiobaloo volumio[1051]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 20 07:17:59 volumiobaloo volumio[1051]: info: CoreCommandRouter::volumioPushState Feb 20 07:17:59 volumiobaloo volumio[1051]: info: CoreStateMachine::updateTrackBlock Feb 20 07:17:59 volumiobaloo volumio[1051]: info: CorePlayQueue::getTrackBlock Feb 20 07:17:59 volumiobaloo volumio[1051]: info: CoreCommandRouter::volumioRetrievevolume Feb 20 07:17:59 volumiobaloo volumio[1051]: info: CoreStateMachine::setRepeat null single undefined Feb 20 07:17:59 volumiobaloo volumio[1051]: info: CoreStateMachine::pushState Feb 20 07:17:59 volumiobaloo volumio[1051]: info: CorePlayQueue::getTrack 0 Feb 20 07:17:59 volumiobaloo volumio[1051]: info: CoreCommandRouter::volumioPushState Feb 20 07:17:59 volumiobaloo volumio[1051]: info: CoreStateMachine::setRandom null Feb 20 07:17:59 volumiobaloo volumio[1051]: info: CoreStateMachine::pushState Feb 20 07:17:59 volumiobaloo volumio[1051]: info: CorePlayQueue::getTrack 0 Feb 20 07:17:59 volumiobaloo volumio[1051]: info: CoreCommandRouter::volumioPushState Feb 20 07:17:59 volumiobaloo volumio[1051]: info: Setting Device type: Raspberry PI Feb 20 07:17:59 volumiobaloo volumio[1051]: info: DAC script executed Feb 20 07:17:59 volumiobaloo volumio[1051]: info: Completed loading Core Plugins Feb 20 07:17:59 volumiobaloo volumio[1051]: info: Preparing to generate the ALSA configuration file Feb 20 07:17:59 volumiobaloo volumio[1051]: Starting albumart workers Feb 20 07:17:59 volumiobaloo volumio[1051]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Feb 20 07:17:59 volumiobaloo volumio[1051]: info: USB Boot Capable - System SBC Revision found in cpuinfo: c03115 Feb 20 07:17:59 volumiobaloo volumio[1051]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Feb 20 07:17:59 volumiobaloo volumio[1051]: info: Asound.conf file unchanged, so no further update is needed Feb 20 07:17:59 volumiobaloo volumio[1051]: info: Output device has changed, restarting MPD Feb 20 07:17:59 volumiobaloo volumio[1051]: Starting albumart workers Feb 20 07:17:59 volumiobaloo volumio[1051]: Starting albumart workers Feb 20 07:17:59 volumiobaloo volumio[1051]: info: Output device has changed, restarting Shairport Sync Feb 20 07:17:59 volumiobaloo volumio[1051]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 20 07:17:59 volumiobaloo volumio[1051]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 20 07:17:59 volumiobaloo sudo[1318]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 20 07:17:59 volumiobaloo sudo[1318]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 20 07:17:59 volumiobaloo sudo[1320]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 20 07:17:59 volumiobaloo sudo[1320]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 20 07:17:59 volumiobaloo sudo[1318]: pam_unix(sudo:session): session closed for user root Feb 20 07:17:59 volumiobaloo volumio[1051]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 20 07:17:59 volumiobaloo volumio[1051]: info: ___________ START PLUGINS ___________ Feb 20 07:17:59 volumiobaloo volumio[1051]: info: ControllerMpd::onStart: Initializing MPD Feb 20 07:17:59 volumiobaloo volumio[1051]: info: Creating MPD Configuration file Feb 20 07:17:59 volumiobaloo sudo[1152]: pam_unix(sudo:session): session closed for user root Feb 20 07:17:59 volumiobaloo systemd[1]: Stopping Music Player Daemon... Feb 20 07:17:59 volumiobaloo volumio[1051]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 20 07:17:59 volumiobaloo volumio[1051]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 20 07:17:59 volumiobaloo volumio[1051]: info: [1740032279970] CoreMusicLibrary::Adding element Serveurs Média Feb 20 07:17:59 volumiobaloo volumio[1051]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 20 07:17:59 volumiobaloo sudo[1327]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 20 07:17:59 volumiobaloo sudo[1327]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 20 07:17:59 volumiobaloo sudo[1329]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 20 07:17:59 volumiobaloo sudo[1329]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 20 07:17:59 volumiobaloo sudo[1327]: pam_unix(sudo:session): session closed for user root Feb 20 07:18:00 volumiobaloo volumio[1051]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 20 07:18:00 volumiobaloo volumio[1051]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 20 07:18:00 volumiobaloo systemd[1]: mpd.service: Succeeded. Feb 20 07:18:00 volumiobaloo systemd[1]: Stopped Music Player Daemon. Feb 20 07:18:00 volumiobaloo volumio[1051]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 20 07:18:00 volumiobaloo volumio[1051]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 20 07:18:00 volumiobaloo volumio[1051]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 20 07:18:00 volumiobaloo systemd[1]: Starting Music Player Daemon... Feb 20 07:18:00 volumiobaloo volumio[1051]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 20 07:18:00 volumiobaloo volumio[1051]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 20 07:18:00 volumiobaloo volumio[1051]: info: [1740032280130] CoreMusicLibrary::Adding element Last_100 Feb 20 07:18:00 volumiobaloo volumio[1051]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 20 07:18:00 volumiobaloo volumio[1051]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 20 07:18:00 volumiobaloo volumio[1051]: info: [1740032280134] CoreMusicLibrary::Adding element Webradio Feb 20 07:18:00 volumiobaloo volumio[1051]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 20 07:18:00 volumiobaloo volumio[1051]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 20 07:18:00 volumiobaloo volumio[1051]: info: Initializing BBC Radios Feb 20 07:18:00 volumiobaloo sudo[1335]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 20 07:18:00 volumiobaloo sudo[1335]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 20 07:18:00 volumiobaloo sudo[1335]: pam_unix(sudo:session): session closed for user root Feb 20 07:18:00 volumiobaloo volumio[1051]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 20 07:18:00 volumiobaloo volumio[1051]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 20 07:18:00 volumiobaloo volumio[1051]: info: Creating Spotify config file Feb 20 07:18:00 volumiobaloo volumio[1051]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 20 07:18:00 volumiobaloo volumio[1051]: info: Volumio Calling Home Feb 20 07:18:00 volumiobaloo volumio[1051]: info: MPD Permissions set Feb 20 07:18:00 volumiobaloo volumio[1051]: info: MPD Permissions set Feb 20 07:18:00 volumiobaloo volumio[1051]: info: VolumeController:: Volume=100 Mute =false Feb 20 07:18:00 volumiobaloo volumio[1051]: info: CoreStateMachine::pushState Feb 20 07:18:00 volumiobaloo volumio[1051]: info: CorePlayQueue::getTrack 0 Feb 20 07:18:00 volumiobaloo volumio[1051]: info: CoreCommandRouter::volumioPushState Feb 20 07:18:00 volumiobaloo volumio[1051]: info: Volumio called home Feb 20 07:18:00 volumiobaloo volumio[1051]: info: Spotify config file written Feb 20 07:18:00 volumiobaloo sudo[1379]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Feb 20 07:18:00 volumiobaloo sudo[1379]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 20 07:18:00 volumiobaloo volumio[1051]: 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 20 07:18:00 volumiobaloo volumio[1051]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 20 07:18:00 volumiobaloo volumio[1051]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 20 07:18:00 volumiobaloo volumio[1051]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 20 07:18:00 volumiobaloo volumio[1051]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 20 07:18:00 volumiobaloo volumio[1051]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 20 07:18:00 volumiobaloo volumio[1051]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 20 07:18:00 volumiobaloo volumio[1051]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 20 07:18:00 volumiobaloo volumio[1051]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 20 07:18:00 volumiobaloo volumio[1051]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 20 07:18:00 volumiobaloo volumio[1051]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 20 07:18:00 volumiobaloo volumio[1051]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 20 07:18:00 volumiobaloo volumio[1051]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 20 07:18:00 volumiobaloo volumio[1051]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 20 07:18:00 volumiobaloo volumio[1051]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 20 07:18:00 volumiobaloo volumio[1051]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 20 07:18:00 volumiobaloo volumio[1051]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 20 07:18:00 volumiobaloo volumio[1051]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 20 07:18:00 volumiobaloo volumio[1051]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 20 07:18:00 volumiobaloo volumio[1051]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 20 07:18:00 volumiobaloo volumio[1051]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 20 07:18:00 volumiobaloo volumio[1051]: info: No need to fix Spotify hosts Feb 20 07:18:00 volumiobaloo volumio[1051]: info: Discovery: adding 26e420a0-5775-49bb-9f5b-7f49601bacb8 Feb 20 07:18:00 volumiobaloo volumio[1051]: info: Discovery: Found device VolumioBaloo Feb 20 07:18:00 volumiobaloo volumio[1051]: info: CoreCommandRouter::volumioGetState Feb 20 07:18:00 volumiobaloo volumio[1051]: info: CorePlayQueue::getTrack 0 Feb 20 07:18:00 volumiobaloo systemd[1]: Started go-librespot Daemon. Feb 20 07:18:00 volumiobaloo sudo[1379]: pam_unix(sudo:session): session closed for user root Feb 20 07:18:00 volumiobaloo go-librespot[1381]: go-librespot daemon starting... Feb 20 07:18:00 volumiobaloo volumio[1051]: info: Starting Shairport Sync Feb 20 07:18:00 volumiobaloo volumio[1051]: info: Starting Shairport Sync Feb 20 07:18:00 volumiobaloo volumio[1051]: info: Starting Shairport Sync Feb 20 07:18:00 volumiobaloo sudo[1392]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 20 07:18:00 volumiobaloo sudo[1392]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 20 07:18:00 volumiobaloo sudo[1394]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 20 07:18:00 volumiobaloo sudo[1394]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 20 07:18:00 volumiobaloo volumio[1051]: info: CoreCommandRouter::volumioGetState Feb 20 07:18:00 volumiobaloo volumio[1051]: info: CorePlayQueue::getTrack 0 Feb 20 07:18:00 volumiobaloo sudo[1397]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 20 07:18:00 volumiobaloo sudo[1397]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 20 07:18:00 volumiobaloo systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Feb 20 07:18:00 volumiobaloo systemd[1]: shairport-sync.service: Succeeded. Feb 20 07:18:00 volumiobaloo systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Feb 20 07:18:00 volumiobaloo systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Feb 20 07:18:00 volumiobaloo sudo[1394]: pam_unix(sudo:session): session closed for user root Feb 20 07:18:00 volumiobaloo sudo[1392]: pam_unix(sudo:session): session closed for user root Feb 20 07:18:00 volumiobaloo systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Feb 20 07:18:00 volumiobaloo systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Feb 20 07:18:00 volumiobaloo systemd[1]: shairport-sync.service: Succeeded. Feb 20 07:18:00 volumiobaloo systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Feb 20 07:18:00 volumiobaloo systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Feb 20 07:18:00 volumiobaloo sudo[1397]: pam_unix(sudo:session): session closed for user root Feb 20 07:18:00 volumiobaloo volumio[1051]: info: Shairport-Sync Started Feb 20 07:18:00 volumiobaloo volumio[1051]: Error adding Membership: Error: addMembership EINVAL Feb 20 07:18:00 volumiobaloo volumio[1051]: info: Shairport-Sync Started Feb 20 07:18:00 volumiobaloo volumio[1051]: info: Shairport-Sync Started Feb 20 07:18:01 volumiobaloo go-librespot[1381]: time="2025-02-20T07:18:01+01:00" level=info msg="running go-librespot 0.2.0" Feb 20 07:18:01 volumiobaloo go-librespot[1381]: time="2025-02-20T07:18:01+01:00" level=debug msg="app state loaded" Feb 20 07:18:01 volumiobaloo go-librespot[1381]: time="2025-02-20T07:18:01+01:00" level=debug msg="stored credentials not found" Feb 20 07:18:01 volumiobaloo go-librespot[1381]: time="2025-02-20T07:18:01+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 20 07:18:01 volumiobaloo mpd[1346]: Feb 20 07:18 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 20 07:18:01 volumiobaloo systemd[1]: Started Music Player Daemon. Feb 20 07:18:01 volumiobaloo sudo[1329]: pam_unix(sudo:session): session closed for user root Feb 20 07:18:01 volumiobaloo sudo[1320]: pam_unix(sudo:session): session closed for user root Feb 20 07:18:01 volumiobaloo volumio[1051]: info: Completed starting Core Plugins Feb 20 07:18:01 volumiobaloo volumio[1051]: info: ------------------------------------------- Feb 20 07:18:01 volumiobaloo volumio[1051]: info: ----- MyVolumio plugins startup ---- Feb 20 07:18:01 volumiobaloo volumio[1051]: info: ------------------------------------------- Feb 20 07:18:01 volumiobaloo volumio[1051]: info: [MyVolumio PluginManager] Fetching plans data.... Feb 20 07:18:01 volumiobaloo volumio[1051]: info: MPD running with PID1346 Feb 20 07:18:01 volumiobaloo volumio[1051]: ,establishing connection Feb 20 07:18:01 volumiobaloo volumio[1051]: error: MPD error: The expression evaluated to a falsy value: Feb 20 07:18:01 volumiobaloo volumio[1051]: assert.ok(self.idling) Feb 20 07:18:01 volumiobaloo volumio[1051]: error: The expression evaluated to a falsy value: Feb 20 07:18:01 volumiobaloo volumio[1051]: assert.ok(self.idling) Feb 20 07:18:01 volumiobaloo volumio[1051]: error: MPD error: The expression evaluated to a falsy value: Feb 20 07:18:01 volumiobaloo volumio[1051]: assert.ok(self.idling) Feb 20 07:18:01 volumiobaloo volumio[1051]: error: The expression evaluated to a falsy value: Feb 20 07:18:01 volumiobaloo volumio[1051]: assert.ok(self.idling) Feb 20 07:18:01 volumiobaloo volumio[1051]: error: updateQueue error: null Feb 20 07:18:01 volumiobaloo go-librespot[1381]: time="2025-02-20T07:18:01+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-gae2.spotify.com:80]" Feb 20 07:18:01 volumiobaloo go-librespot[1381]: time="2025-02-20T07:18:01+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Feb 20 07:18:01 volumiobaloo go-librespot[1381]: time="2025-02-20T07:18:01+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Feb 20 07:18:01 volumiobaloo go-librespot[1381]: time="2025-02-20T07:18:01+01:00" level=info msg="zeroconf server listening on port 33851" Feb 20 07:18:03 volumiobaloo volumio[1051]: info: go-librespot daemon successfully initialized Feb 20 07:18:04 volumiobaloo systemd[1]: systemd-fsckd.service: Succeeded. Feb 20 07:18:05 volumiobaloo sh[541]: timed out Feb 20 07:18:05 volumiobaloo dhcpcd[585]: timed out Feb 20 07:18:05 volumiobaloo sh[541]: dhcpcd exited Feb 20 07:18:05 volumiobaloo dhcpcd[585]: dhcpcd exited Feb 20 07:18:05 volumiobaloo sh[541]: ifup: failed to bring up eth0 Feb 20 07:18:05 volumiobaloo systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Feb 20 07:18:05 volumiobaloo systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Feb 20 07:18:06 volumiobaloo volumio[1051]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Feb 20 07:18:06 volumiobaloo systemd[1]: systemd-hostnamed.service: Succeeded. Feb 20 07:18:06 volumiobaloo go-librespot[1381]: time="2025-02-20T07:18:06+01:00" level=debug msg="obtained new client token: AAC6UzVu+WjhIqCFWLyHaPZrbqxFIAw1dTJsDeWmNNBjGnvM1e4dRo9GyXYOHs52DTb+Vq78HJcfLymZyNyCUEVntuipd96MVLLGGt27LlZOPSRRvEdybX9WMbIdp702/9xPE4bSCCmYJpz2SqE2H6Oq5MEfKahWTb0mgrJOMcf3ufv5GFD3BAPAReOtIfCnrFys6hxnpJdiLCLugEmvKdFrmAk8dy6If+iBYP8F8GeiUApZUg6lL4/K5w==" Feb 20 07:18:06 volumiobaloo go-librespot[1381]: time="2025-02-20T07:18:06+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 20 07:18:06 volumiobaloo go-librespot[1381]: time="2025-02-20T07:18:06+01:00" level=debug msg="completed keyexchange" Feb 20 07:18:06 volumiobaloo go-librespot[1381]: time="2025-02-20T07:18:06+01:00" level=debug msg="completed challenge" Feb 20 07:18:06 volumiobaloo volumio[1051]: info: Initializing connection to go-librespot Websocket Feb 20 07:18:06 volumiobaloo go-librespot[1381]: time="2025-02-20T07:18:06+01:00" level=debug msg="new websocket client" Feb 20 07:18:06 volumiobaloo volumio[1051]: info: Connection to go-librespot Websocket established Feb 20 07:18:06 volumiobaloo go-librespot[1381]: time="2025-02-20T07:18:06+01:00" level=info msg="authenticated AP as 117529739" Feb 20 07:18:06 volumiobaloo go-librespot[1381]: time="2025-02-20T07:18:06+01:00" level=info msg="authenticated Login5 as 117529739" Feb 20 07:18:06 volumiobaloo go-librespot[1381]: time="2025-02-20T07:18:06+01:00" level=info msg="accepted zeroconf user 117529739 from iPad" Feb 20 07:18:06 volumiobaloo sudo[1434]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 20 07:18:06 volumiobaloo sudo[1434]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 20 07:18:06 volumiobaloo sudo[1434]: pam_unix(sudo:session): session closed for user root Feb 20 07:18:06 volumiobaloo sudo[1436]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 20 07:18:06 volumiobaloo sudo[1436]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 20 07:18:06 volumiobaloo sudo[1436]: pam_unix(sudo:session): session closed for user root Feb 20 07:18:06 volumiobaloo sudo[1440]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Feb 20 07:18:06 volumiobaloo sudo[1440]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 20 07:18:06 volumiobaloo systemd[1]: Started UPnP Renderer front-end to MPD. Feb 20 07:18:06 volumiobaloo sudo[1440]: pam_unix(sudo:session): session closed for user root Feb 20 07:18:06 volumiobaloo volumio[1051]: info: Upmpdcli Daemon Started Feb 20 07:18:07 volumiobaloo go-librespot[1381]: time="2025-02-20T07:18:07+01:00" level=debug msg="dealer connection opened" Feb 20 07:18:07 volumiobaloo go-librespot[1381]: time="2025-02-20T07:18:07+01:00" level=trace msg="starting accesspoint recv loop" Feb 20 07:18:07 volumiobaloo go-librespot[1381]: time="2025-02-20T07:18:07+01:00" level=trace msg="starting dealer recv loop" Feb 20 07:18:07 volumiobaloo go-librespot[1381]: time="2025-02-20T07:18:07+01:00" level=trace msg="received accesspoint ping" Feb 20 07:18:07 volumiobaloo go-librespot[1381]: time="2025-02-20T07:18:07+01:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Feb 20 07:18:07 volumiobaloo go-librespot[1381]: time="2025-02-20T07:18:07+01:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Feb 20 07:18:07 volumiobaloo go-librespot[1381]: time="2025-02-20T07:18:07+01:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Feb 20 07:18:07 volumiobaloo go-librespot[1381]: time="2025-02-20T07:18:07+01:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Feb 20 07:18:07 volumiobaloo go-librespot[1381]: time="2025-02-20T07:18:07+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Feb 20 07:18:07 volumiobaloo go-librespot[1381]: time="2025-02-20T07:18:07+01:00" level=debug msg="received connection id: MDEzMjVlYTAtMTFjOS00MTc2LThlYTEtM2Q0YzYxNmRjNDMxK2RlYWxlcit0Y3A6Ly8wYWNhNWEwZS5pcC5nZXc0LnNwb3RpZnkubmV0OjU3MDArNEYyNjI4QUU3MkUxMDhERTgxOTlDMTg5RjA2Q0Y2NzE0MjY2NTkyQzUyOUExNEZCNkUxODVGMjJBMzJENzFBQg==" Feb 20 07:18:07 volumiobaloo go-librespot[1381]: time="2025-02-20T07:18:07+01:00" level=trace msg="received accesspoint pong ack" Feb 20 07:18:07 volumiobaloo volumio[1442]: Generating RSA private key, 4096 bit long modulus (2 primes) Feb 20 07:18:07 volumiobaloo go-librespot[1381]: time="2025-02-20T07:18:07+01:00" level=debug msg="put connect state because NEW_DEVICE" Feb 20 07:18:07 volumiobaloo go-librespot[1381]: time="2025-02-20T07:18:07+01:00" level=debug msg="handling transfer player command from 390e6c161ee551a4697802270549d8f9a124e583" Feb 20 07:18:07 volumiobaloo go-librespot[1381]: time="2025-02-20T07:18:07+01:00" level=debug msg="resolved context of track" uri="spotify:playlist:1xsMp6egrtsLhTZSBUfGvD" Feb 20 07:18:07 volumiobaloo go-librespot[1381]: time="2025-02-20T07:18:07+01:00" level=trace msg="fetched new page 0 with 50 items (list: 50)" uri="spotify:playlist:1xsMp6egrtsLhTZSBUfGvD" Feb 20 07:18:07 volumiobaloo go-librespot[1381]: time="2025-02-20T07:18:07+01:00" level=debug msg="shuffled context with seed 3906821435121728746 (len: 50, keep: 36)" uri="spotify:playlist:1xsMp6egrtsLhTZSBUfGvD" Feb 20 07:18:07 volumiobaloo go-librespot[1381]: time="2025-02-20T07:18:07+01:00" level=debug msg="loading track (paused: false, position: 91930ms)" uri="spotify:track:6pb5DtlAeG95dNQLHiArRU" Feb 20 07:18:07 volumiobaloo go-librespot[1381]: time="2025-02-20T07:18:07+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Feb 20 07:18:07 volumiobaloo go-librespot[1381]: time="2025-02-20T07:18:07+01:00" level=trace msg="emitting websocket event: will_play" Feb 20 07:18:07 volumiobaloo volumio[1051]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:6pb5DtlAeG95dNQLHiArRU","play_origin":"playlist/ondemand"}} Feb 20 07:18:07 volumiobaloo go-librespot[1381]: time="2025-02-20T07:18:07+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 241" Feb 20 07:18:07 volumiobaloo go-librespot[1381]: time="2025-02-20T07:18:07+01:00" level=debug msg="selected format OGG_VORBIS_320 (231f001f4436f860975a441271654c16b2d1db07)" uri="spotify:track:6pb5DtlAeG95dNQLHiArRU" Feb 20 07:18:07 volumiobaloo go-librespot[1381]: time="2025-02-20T07:18:07+01:00" level=debug msg="requested aes key for file 231f001f4436f860975a441271654c16b2d1db07, gid: 6pb5DtlAeG95dNQLHiArRU" Feb 20 07:18:07 volumiobaloo go-librespot[1381]: time="2025-02-20T07:18:07+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 3105" Feb 20 07:18:07 volumiobaloo go-librespot[1381]: time="2025-02-20T07:18:07+01:00" level=debug msg="fetched first chunk of 11, total size is 5692420 bytes" uri="spotify:track:6pb5DtlAeG95dNQLHiArRU" Feb 20 07:18:07 volumiobaloo go-librespot[1381]: time="2025-02-20T07:18:07+01:00" level=debug msg="fetched chunk 3/10, size: 524288" uri="spotify:track:6pb5DtlAeG95dNQLHiArRU" Feb 20 07:18:07 volumiobaloo go-librespot[1381]: time="2025-02-20T07:18:07+01:00" level=debug msg="fetched chunk 2/10, size: 524288" uri="spotify:track:6pb5DtlAeG95dNQLHiArRU" Feb 20 07:18:07 volumiobaloo go-librespot[1381]: time="2025-02-20T07:18:07+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1999" Feb 20 07:18:07 volumiobaloo go-librespot[1381]: time="2025-02-20T07:18:07+01:00" level=debug msg="fetched chunk 10/10, size: 449540" uri="spotify:track:6pb5DtlAeG95dNQLHiArRU" Feb 20 07:18:07 volumiobaloo go-librespot[1381]: time="2025-02-20T07:18:07+01:00" level=debug msg="fetched chunk 7/10, size: 524288" uri="spotify:track:6pb5DtlAeG95dNQLHiArRU" Feb 20 07:18:07 volumiobaloo go-librespot[1381]: time="2025-02-20T07:18:07+01:00" level=trace msg="seek to 91930ms (diff: 135ms, samples: 4054113, bytes: 3744662)" uri="spotify:track:6pb5DtlAeG95dNQLHiArRU" Feb 20 07:18:07 volumiobaloo go-librespot[1381]: time="2025-02-20T07:18:07+01:00" level=debug msg="created new output device" Feb 20 07:18:07 volumiobaloo go-librespot[1381]: time="2025-02-20T07:18:07+01:00" level=debug msg="fetched chunk 1/10, size: 524288" uri="spotify:track:6pb5DtlAeG95dNQLHiArRU" Feb 20 07:18:07 volumiobaloo go-librespot[1381]: time="2025-02-20T07:18:07+01:00" level=debug msg="fetched chunk 8/10, size: 524288" uri="spotify:track:6pb5DtlAeG95dNQLHiArRU" Feb 20 07:18:07 volumiobaloo go-librespot[1381]: time="2025-02-20T07:18:07+01:00" level=debug msg="fetched chunk 9/10, size: 524288" uri="spotify:track:6pb5DtlAeG95dNQLHiArRU" Feb 20 07:18:07 volumiobaloo kernel: pcm512x 1-004c: No SCLK, using BCLK: -2 Feb 20 07:18:07 volumiobaloo go-librespot[1381]: time="2025-02-20T07:18:07+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 20 07:18:07 volumiobaloo go-librespot[1381]: time="2025-02-20T07:18:07+01:00" level=info msg="loaded track \"Rock My Body\" (paused: false, position: 91930ms, duration: 137419ms, prefetched: false)" uri="spotify:track:6pb5DtlAeG95dNQLHiArRU" Feb 20 07:18:08 volumiobaloo go-librespot[1381]: time="2025-02-20T07:18:08+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Feb 20 07:18:08 volumiobaloo go-librespot[1381]: time="2025-02-20T07:18:08+01:00" level=trace msg="scheduling prefetch in 15s" Feb 20 07:18:08 volumiobaloo go-librespot[1381]: time="2025-02-20T07:18:08+01:00" level=trace msg="emitting websocket event: metadata" Feb 20 07:18:08 volumiobaloo go-librespot[1381]: time="2025-02-20T07:18:08+01:00" level=trace msg="emitting websocket event: active" Feb 20 07:18:08 volumiobaloo go-librespot[1381]: time="2025-02-20T07:18:08+01:00" level=debug msg="sending successful reply for dealer request" Feb 20 07:18:08 volumiobaloo volumio[1051]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:6pb5DtlAeG95dNQLHiArRU","name":"Rock My Body","artist_names":["R3HAB","INNA","Sash!"],"album_name":"Rock My Body (with INNA \u0026 Sash!)","album_cover_url":"https://i.scdn.co/image/ab67616d00001e0268eb565abb15fc77286076f8","position":91930,"duration":137419,"release_date":"year:2023 month:4 day:28","track_number":1,"disc_number":1}} Feb 20 07:18:08 volumiobaloo go-librespot[1381]: time="2025-02-20T07:18:08+01:00" level=debug msg="skipping dealer message" uri=social-connect/v2/broadcast_status_update Feb 20 07:18:08 volumiobaloo go-librespot[1381]: time="2025-02-20T07:18:08+01:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Feb 20 07:18:08 volumiobaloo volumio[1051]: SPOTIFY: received: {"type":"active","data":null} Feb 20 07:18:08 volumiobaloo volumio[1051]: info: Aligning Spotify Volume to Volumio Volume Feb 20 07:18:08 volumiobaloo volumio[1051]: info: CoreCommandRouter::volumioGetState Feb 20 07:18:08 volumiobaloo volumio[1051]: info: CorePlayQueue::getTrack 0 Feb 20 07:18:08 volumiobaloo volumio[1051]: info: Setting Spotify Volume from Volumio: 100 Feb 20 07:18:08 volumiobaloo go-librespot[1381]: time="2025-02-20T07:18:08+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Feb 20 07:18:08 volumiobaloo go-librespot[1381]: time="2025-02-20T07:18:08+01:00" level=trace msg="emitting websocket event: playing" Feb 20 07:18:08 volumiobaloo volumio[1051]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:6pb5DtlAeG95dNQLHiArRU","play_origin":"playlist/ondemand"}} Feb 20 07:18:08 volumiobaloo volumio[1051]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 20 07:18:08 volumiobaloo volumio[1051]: TypeError: Cannot read property 'service' of undefined Feb 20 07:18:08 volumiobaloo volumio[1051]: at ControllerSpotify.identifyPlaybackMode (/data/plugins/music_service/spop/index.js:337:50) Feb 20 07:18:08 volumiobaloo volumio[1051]: at ControllerSpotify.parseEventState (/data/plugins/music_service/spop/index.js:263:18) Feb 20 07:18:08 volumiobaloo volumio[1051]: at WebSocket.message (/data/plugins/music_service/spop/index.js:190:14) Feb 20 07:18:08 volumiobaloo volumio[1051]: at WebSocket.emit (events.js:315:20) Feb 20 07:18:08 volumiobaloo volumio[1051]: at Receiver.receiverOnMessage (/data/plugins/music_service/spop/node_modules/ws/lib/websocket.js:1220:20) Feb 20 07:18:08 volumiobaloo volumio[1051]: at Receiver.emit (events.js:315:20) Feb 20 07:18:08 volumiobaloo volumio[1051]: at Receiver.dataMessage (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:596:14) Feb 20 07:18:08 volumiobaloo volumio[1051]: at Receiver.getData (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:496:10) Feb 20 07:18:08 volumiobaloo volumio[1051]: at Receiver.startLoop (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:167:16) Feb 20 07:18:08 volumiobaloo volumio[1051]: at Receiver._write (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:94:10) Feb 20 07:18:08 volumiobaloo volumio[1051]: at writeOrBuffer (internal/streams/writable.js:358:12) Feb 20 07:18:08 volumiobaloo volumio[1051]: at Receiver.Writable.write (internal/streams/writable.js:303:10) Feb 20 07:18:08 volumiobaloo volumio[1051]: at Socket.socketOnData (/data/plugins/music_service/spop/node_modules/ws/lib/websocket.js:1355:35) Feb 20 07:18:08 volumiobaloo volumio[1051]: at Socket.emit (events.js:315:20) Feb 20 07:18:08 volumiobaloo volumio[1051]: at addChunk (internal/streams/readable.js:309:12) Feb 20 07:18:08 volumiobaloo volumio[1051]: at readableAddChunk (internal/streams/readable.js:284:9) Feb 20 07:18:08 volumiobaloo volumio[1051]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 20 07:18:08 volumiobaloo sudo[1471]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-02-20 07:17 Feb 20 07:18:08 volumiobaloo sudo[1471]: 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"