-- Logs begin at Thu 2019-02-14 11:11:58 CET, end at Wed 2025-02-19 09:31:45 CET. -- Feb 19 09:31:30 bro systemd-timedated[1015]: Changed local time to Wed Feb 19 09:31:30 2025 Feb 19 09:31:30 bro sudo[1013]: pam_unix(sudo:session): session closed for user root Feb 19 09:31:30 bro volumio-time-update[627]: volumio-time-update-util: System time updated successfully. Feb 19 09:31:30 bro systemd[1]: Starting Daily apt download activities... Feb 19 09:31:30 bro systemd[1]: Started Volumio Time Update Utility. Feb 19 09:31:30 bro volumio[991]: info: ------------------------------------------- Feb 19 09:31:30 bro volumio[991]: info: ----- Volumio3 ---- Feb 19 09:31:30 bro volumio[991]: info: ------------------------------------------- Feb 19 09:31:30 bro volumio[991]: info: ----- System startup ---- Feb 19 09:31:30 bro volumio[991]: info: ------------------------------------------- Feb 19 09:31:30 bro nmbd[734]: [2025/02/19 09:31:30.930264, 0] ../lib/util/become_daemon.c:138(daemon_ready) Feb 19 09:31:30 bro nmbd[734]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Feb 19 09:31:30 bro systemd[1]: Started Samba NMB Daemon. Feb 19 09:31:30 bro systemd[1]: Starting Samba Winbind Daemon... Feb 19 09:31:31 bro winbindd[1044]: [2025/02/19 09:31:31.299994, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Feb 19 09:31:31 bro winbindd[1044]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Feb 19 09:31:31 bro volumio[991]: info: MYVOLUMIO Environment detected Feb 19 09:31:31 bro winbindd[1044]: [2025/02/19 09:31:31.361664, 0] ../lib/util/become_daemon.c:138(daemon_ready) Feb 19 09:31:31 bro winbindd[1044]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Feb 19 09:31:31 bro systemd[1]: Started Samba Winbind Daemon. Feb 19 09:31:31 bro systemd[1]: Starting Samba SMB Daemon... Feb 19 09:31:31 bro volumio[991]: info: Plugin folders cleanup Feb 19 09:31:31 bro volumio[991]: info: Scanning into folder /volumio/app/plugins/ Feb 19 09:31:31 bro volumio[991]: info: Scanning category audio_interface Feb 19 09:31:31 bro volumio[991]: info: Scanning category miscellanea Feb 19 09:31:31 bro volumio[991]: info: Scanning category music_service Feb 19 09:31:31 bro volumio[991]: info: Scanning category plugins.json Feb 19 09:31:31 bro volumio[991]: info: Scanning category system_controller Feb 19 09:31:31 bro volumio[991]: info: Scanning category user_interface Feb 19 09:31:31 bro volumio[991]: info: Scanning into folder /data/plugins/ Feb 19 09:31:31 bro volumio[991]: info: Scanning category music_service Feb 19 09:31:31 bro volumio[991]: info: Plugin folders cleanup completed Feb 19 09:31:31 bro volumio[991]: info: ------------------------------------------- Feb 19 09:31:31 bro volumio[991]: info: ----- Core plugins startup ---- Feb 19 09:31:31 bro volumio[991]: info: ------------------------------------------- Feb 19 09:31:31 bro volumio[991]: info: Loading plugins from folder /volumio/app/plugins/ Feb 19 09:31:31 bro volumio[991]: info: Adding plugin upnp to MyMusic Plugins Feb 19 09:31:31 bro volumio[991]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 19 09:31:31 bro volumio[991]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 19 09:31:31 bro volumio[991]: info: Loading plugins from folder /data/plugins/ Feb 19 09:31:31 bro volumio[991]: info: Loading plugin "system"... Feb 19 09:31:31 bro volumio[991]: info: Loading plugin "appearance"... Feb 19 09:31:31 bro systemd[1]: Started Samba SMB Daemon. Feb 19 09:31:31 bro smbd[1051]: [2025/02/19 09:31:31.799635, 0] ../lib/util/become_daemon.c:138(daemon_ready) Feb 19 09:31:31 bro smbd[1051]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Feb 19 09:31:31 bro systemd[1]: Reached target Multi-User System. Feb 19 09:31:31 bro systemd[1]: Reached target Graphical Interface. Feb 19 09:31:31 bro systemd[1]: Starting Update UTMP about System Runlevel Changes... Feb 19 09:31:31 bro systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Feb 19 09:31:31 bro systemd[1]: Started Update UTMP about System Runlevel Changes. Feb 19 09:31:32 bro volumio-remote-updater[648]: [2025-02-19 09:31:32] [connect] Successful connection Feb 19 09:31:32 bro systemd[1]: apt-daily.service: Succeeded. Feb 19 09:31:32 bro systemd[1]: Started Daily apt download activities. Feb 19 09:31:32 bro systemd[1]: Starting Daily apt upgrade and clean activities... Feb 19 09:31:32 bro volumio[991]: info: Loading plugin "network"... Feb 19 09:31:32 bro volumio[991]: info: Refreshing Cached IP Addresses Feb 19 09:31:32 bro volumio[991]: info: Loading plugin "services"... Feb 19 09:31:32 bro volumio[991]: info: Loading plugin "alsa_controller"... Feb 19 09:31:32 bro sudo[1116]: volumio : unable to resolve host bro Feb 19 09:31:32 bro sudo[1116]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Feb 19 09:31:32 bro sudo[1116]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 19 09:31:32 bro sudo[1116]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 09:31:32 bro sudo[1118]: volumio : unable to resolve host bro Feb 19 09:31:32 bro sudo[1118]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Feb 19 09:31:32 bro sudo[1118]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 19 09:31:32 bro sudo[1118]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 09:31:32 bro sudo[1116]: pam_unix(sudo:session): session closed for user root Feb 19 09:31:32 bro sudo[1118]: pam_unix(sudo:session): session closed for user root Feb 19 09:31:32 bro sudo[1134]: volumio : unable to resolve host bro Feb 19 09:31:32 bro sudo[1134]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Feb 19 09:31:32 bro sudo[1134]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 19 09:31:32 bro sudo[1134]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 09:31:32 bro volumio[991]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 19 09:31:32 bro volumio[991]: info: Loading plugin "wizard"... Feb 19 09:31:32 bro volumio[991]: info: Loading plugin "networkfs"... Feb 19 09:31:32 bro volumio[991]: info: Starting Udev Watcher for removable devices Feb 19 09:31:32 bro volumio[991]: info: Ignoring mount for partition: boot Feb 19 09:31:32 bro volumio[991]: info: Ignoring mount for partition: volumio Feb 19 09:31:32 bro volumio[991]: info: Ignoring mount for partition: volumio_data Feb 19 09:31:32 bro volumio[991]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 19 09:31:32 bro volumio[991]: info: Loading plugin "volumio_command_line_client"... Feb 19 09:31:32 bro volumio[991]: info: Loading plugin "upnp"... Feb 19 09:31:32 bro volumio[991]: info: [1739953892918] Starting Upmpd Daemon Feb 19 09:31:32 bro volumio[991]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 19 09:31:32 bro volumio[991]: info: Loading plugin "my_music"... Feb 19 09:31:32 bro volumio[991]: info: Loading plugin "mpd"... Feb 19 09:31:32 bro sudo[1144]: volumio : unable to resolve host bro Feb 19 09:31:32 bro sudo[1144]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Feb 19 09:31:32 bro sudo[1144]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=fabio,password=Bikerofhell2010,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.111/fabio /mnt/NAS/Fabio Feb 19 09:31:32 bro sudo[1144]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 09:31:33 bro kernel: Key type cifs.spnego registered Feb 19 09:31:33 bro kernel: Key type cifs.idmap registered Feb 19 09:31:33 bro 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 19 09:31:33 bro kernel: CIFS: Attempting to mount //192.168.1.111/fabio Feb 19 09:31:33 bro kernel: cryptd: max_cpu_qlen set to 1000 Feb 19 09:31:33 bro volumio[991]: info: Loading plugin "upnp_browser"... Feb 19 09:31:33 bro systemd[1]: apt-daily-upgrade.service: Succeeded. Feb 19 09:31:33 bro systemd[1]: Started Daily apt upgrade and clean activities. Feb 19 09:31:33 bro systemd[1]: Startup finished in 11.881s (kernel) + 27.893s (userspace) = 39.774s. Feb 19 09:31:33 bro volumio[991]: info: Loading plugin "alarm-clock"... Feb 19 09:31:33 bro volumio[991]: info: Loading plugin "airplay_emulation"... Feb 19 09:31:33 bro volumio[991]: info: Starting Shairport Sync Feb 19 09:31:33 bro volumio[991]: info: Loading plugin "last_100"... Feb 19 09:31:33 bro volumio[991]: info: Loading plugin "webradio"... Feb 19 09:31:33 bro sudo[1144]: pam_unix(sudo:session): session closed for user root Feb 19 09:31:33 bro volumio[991]: info: Loading plugin "i2s_dacs"... Feb 19 09:31:33 bro volumio[991]: info: I2S DAC not set, start Auto-detection Feb 19 09:31:33 bro volumio[991]: info: Loading plugin "volumiodiscovery"... Feb 19 09:31:33 bro volumio[991]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 19 09:31:33 bro volumio[991]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 19 09:31:33 bro volumio[991]: *** WARNING *** For more information see Feb 19 09:31:33 bro node[991]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 19 09:31:33 bro volumio[991]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 19 09:31:33 bro volumio[991]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 19 09:31:33 bro volumio[991]: *** WARNING *** For more information see Feb 19 09:31:33 bro node[991]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 19 09:31:33 bro node[991]: *** WARNING *** For more information see Feb 19 09:31:33 bro node[991]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 19 09:31:33 bro node[991]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 19 09:31:33 bro node[991]: *** WARNING *** For more information see Feb 19 09:31:33 bro volumio[991]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 19 09:31:33 bro volumio[991]: info: Discovery: Started advertising with name: Büro Feb 19 09:31:33 bro volumio[991]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 19 09:31:33 bro volumio[991]: info: Plugin jellyfin is not enabled Feb 19 09:31:33 bro volumio[991]: info: Loading plugin "spop"... Feb 19 09:31:34 bro wpa_supplicant[887]: RRM: Ignoring radio measurement request: Not RRM network Feb 19 09:31:35 bro volumio[991]: info: Loading plugin "outputs"... Feb 19 09:31:35 bro volumio[991]: info: Loading plugin "albumart"... Feb 19 09:31:35 bro volumio[991]: info: Plugin example_plugin is not enabled Feb 19 09:31:35 bro volumio[991]: info: Loading plugin "inputs"... Feb 19 09:31:35 bro volumio[991]: info: Loading plugin "updater_comm"... Feb 19 09:31:35 bro volumio[991]: info: Plugin mpdemulation is not enabled Feb 19 09:31:35 bro volumio[991]: info: Loading plugin "rest_api"... Feb 19 09:31:35 bro volumio[991]: info: Loading plugin "websocket"... Feb 19 09:31:35 bro volumio[991]: info: Starting Socket.io Server version 2.3.0 Feb 19 09:31:35 bro volumio[991]: info: Loading plugin "80s80s"... Feb 19 09:31:35 bro volumio[991]: Forking 3 albumart workers Feb 19 09:31:35 bro volumio[991]: info: Applying required configuration parameters for plugin 80s80s Feb 19 09:31:35 bro volumio[991]: info: [1739953895617] [80s80s] API delay: 30 Feb 19 09:31:35 bro volumio[991]: info: Loading plugin "RoonBridge"... Feb 19 09:31:35 bro volumio[991]: Starting albumart workers Feb 19 09:31:35 bro volumio[991]: Starting albumart workers Feb 19 09:31:35 bro volumio[991]: info: Applying required configuration parameters for plugin RoonBridge Feb 19 09:31:35 bro volumio[991]: Starting albumart workers Feb 19 09:31:35 bro volumio[991]: info: Loading i18n strings for locale de Feb 19 09:31:35 bro volumio[991]: Updating browse sources language Feb 19 09:31:35 bro volumio[991]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 19 09:31:36 bro volumio[991]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 19 09:31:36 bro volumio[991]: info: CoreCommandRouter::initPlayerControls Feb 19 09:31:36 bro volumio[991]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 09:31:36 bro volumio[991]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 09:31:36 bro volumio[991]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 09:31:36 bro volumio[991]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 09:31:36 bro volumio[991]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 09:31:36 bro volumio[991]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 09:31:36 bro volumio[991]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 09:31:36 bro volumio[991]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 09:31:36 bro volumio[991]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 19 09:31:36 bro volumio[991]: Express server listening on port 3000 Feb 19 09:31:36 bro volumio[991]: [Metrics] WebUI: 6s 740.78ms Feb 19 09:31:36 bro volumio[991]: info: CoreStateMachine::resetVolumioState Feb 19 09:31:36 bro volumio[991]: info: CoreStateMachine::getcurrentVolume Feb 19 09:31:36 bro volumio[991]: info: CoreCommandRouter::volumioRetrievevolume Feb 19 09:31:36 bro volumio[991]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 19 09:31:36 bro volumio[991]: info: Volumio Network Manager: Network status updated: 2 Feb 19 09:31:36 bro volumio-remote-updater[648]: [2025-02-19 09:31:36] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1739953892 101 Feb 19 09:31:36 bro sudo[1134]: pam_unix(sudo:session): session closed for user root Feb 19 09:31:36 bro volumio[991]: 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 19 09:31:36 bro volumio[991]: info: Reloading queue from file Feb 19 09:31:36 bro volumio[991]: info: Setting Device type: Raspberry PI Feb 19 09:31:36 bro volumio[991]: info: CoreStateMachine::setRepeat null single undefined Feb 19 09:31:36 bro volumio[991]: info: CoreStateMachine::pushState Feb 19 09:31:36 bro volumio[991]: info: CorePlayQueue::getTrack 0 Feb 19 09:31:36 bro volumio[991]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 19 09:31:36 bro volumio[991]: info: CoreCommandRouter::volumioPushState Feb 19 09:31:36 bro volumio[991]: info: CoreStateMachine::setRandom null Feb 19 09:31:36 bro volumio[991]: info: CoreStateMachine::pushState Feb 19 09:31:36 bro volumio[991]: info: CorePlayQueue::getTrack 0 Feb 19 09:31:36 bro volumio[991]: info: CoreCommandRouter::volumioPushState Feb 19 09:31:36 bro volumio[991]: info: VolumeController:: Volume=33 Mute =false Feb 19 09:31:36 bro volumio[991]: info: CoreStateMachine::pushState Feb 19 09:31:36 bro volumio[991]: info: CorePlayQueue::getTrack 0 Feb 19 09:31:36 bro volumio[991]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 19 09:31:36 bro volumio[991]: info: CoreCommandRouter::volumioPushState Feb 19 09:31:36 bro volumio[991]: info: CoreStateMachine::updateTrackBlock Feb 19 09:31:36 bro volumio[991]: info: CorePlayQueue::getTrackBlock Feb 19 09:31:36 bro volumio[991]: info: CoreCommandRouter::volumioRetrievevolume Feb 19 09:31:36 bro volumio[991]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Feb 19 09:31:36 bro volumio[991]: info: USB Boot Capable - System SBC Revision found in cpuinfo: c03112 Feb 19 09:31:36 bro volumio[991]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Feb 19 09:31:36 bro volumio[991]: info: Completed loading Core Plugins Feb 19 09:31:36 bro volumio[991]: info: Preparing to generate the ALSA configuration file Feb 19 09:31:36 bro volumio[991]: info: Asound.conf file unchanged, so no further update is needed Feb 19 09:31:36 bro volumio[991]: info: Output device has changed, restarting MPD Feb 19 09:31:36 bro volumio[991]: info: Output device has changed, restarting Shairport Sync Feb 19 09:31:36 bro volumio[991]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 09:31:36 bro volumio[991]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 19 09:31:36 bro sudo[1278]: volumio : unable to resolve host bro Feb 19 09:31:36 bro sudo[1278]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Feb 19 09:31:36 bro volumio[991]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 19 09:31:36 bro volumio[991]: info: ___________ START PLUGINS ___________ Feb 19 09:31:36 bro sudo[1278]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 19 09:31:36 bro sudo[1278]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 09:31:36 bro volumio[991]: info: ControllerMpd::onStart: Initializing MPD Feb 19 09:31:36 bro volumio[991]: info: Creating MPD Configuration file Feb 19 09:31:36 bro sudo[1278]: pam_unix(sudo:session): session closed for user root Feb 19 09:31:36 bro sudo[1280]: volumio : unable to resolve host bro Feb 19 09:31:36 bro sudo[1280]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Feb 19 09:31:36 bro sudo[1280]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 19 09:31:36 bro sudo[1280]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 09:31:36 bro volumio[991]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 19 09:31:36 bro volumio[991]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 19 09:31:36 bro volumio[991]: info: [1739953896712] CoreMusicLibrary::Adding element Medienserver Feb 19 09:31:36 bro volumio[991]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 19 09:31:36 bro systemd[1]: Stopping Music Player Daemon... Feb 19 09:31:36 bro volumio[991]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 09:31:36 bro volumio[991]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 19 09:31:36 bro sudo[1286]: volumio : unable to resolve host bro Feb 19 09:31:36 bro sudo[1286]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Feb 19 09:31:36 bro sudo[1286]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 19 09:31:36 bro sudo[1286]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 09:31:36 bro sudo[1286]: pam_unix(sudo:session): session closed for user root Feb 19 09:31:36 bro sudo[1289]: volumio : unable to resolve host bro Feb 19 09:31:36 bro sudo[1289]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Feb 19 09:31:36 bro sudo[1289]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 19 09:31:36 bro sudo[1289]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 09:31:36 bro volumio[991]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 19 09:31:36 bro volumio[991]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 09:31:36 bro volumio[991]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 19 09:31:36 bro volumio[991]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 19 09:31:36 bro volumio[991]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 19 09:31:36 bro volumio[991]: info: [1739953896822] CoreMusicLibrary::Adding element Last_100 Feb 19 09:31:36 bro volumio[991]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 19 09:31:36 bro systemd[1]: mpd.service: Succeeded. Feb 19 09:31:36 bro systemd[1]: Stopped Music Player Daemon. Feb 19 09:31:36 bro volumio[991]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 19 09:31:36 bro volumio[991]: info: [1739953896825] CoreMusicLibrary::Adding element Webradio Feb 19 09:31:36 bro volumio[991]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 19 09:31:36 bro volumio[991]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 19 09:31:36 bro volumio[991]: info: Initializing BBC Radios Feb 19 09:31:36 bro systemd[1]: Starting Music Player Daemon... Feb 19 09:31:36 bro volumio[991]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 19 09:31:36 bro volumio[991]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 19 09:31:36 bro volumio[991]: info: Creating Spotify config file Feb 19 09:31:36 bro volumio[991]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 09:31:36 bro volumio[991]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 19 09:31:36 bro volumio[991]: info: [1739953896953] CoreMusicLibrary::Adding element 80s80s Radio Feb 19 09:31:36 bro volumio[991]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 19 09:31:36 bro volumio[991]: Cannot find translation for source 80s80s Radio Feb 19 09:31:36 bro volumio[991]: info: Volumio Calling Home Feb 19 09:31:36 bro sudo[1298]: root : unable to resolve host bro Feb 19 09:31:36 bro sudo[1298]: root : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Feb 19 09:31:36 bro sudo[1298]: sudo: unable to resolve host bro: System error Feb 19 09:31:36 bro sudo[1298]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 19 09:31:36 bro sudo[1298]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 09:31:37 bro sudo[1298]: pam_unix(sudo:session): session closed for user root Feb 19 09:31:37 bro sudo[1310]: volumio : unable to resolve host bro Feb 19 09:31:37 bro sudo[1310]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Feb 19 09:31:37 bro sudo[1310]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start roonbridge.service Feb 19 09:31:37 bro sudo[1310]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 09:31:37 bro volumio[991]: info: Discovery: adding bdda3456-5ab4-4f6a-bc7c-13c6e864e0e0 Feb 19 09:31:37 bro volumio[991]: info: Discovery: Found device Büro Feb 19 09:31:37 bro volumio[991]: info: CoreCommandRouter::volumioGetState Feb 19 09:31:37 bro volumio[991]: info: CorePlayQueue::getTrack 0 Feb 19 09:31:37 bro volumio[991]: info: MPD Permissions set Feb 19 09:31:37 bro volumio[991]: info: MPD Permissions set Feb 19 09:31:37 bro volumio[991]: info: VolumeController:: Volume=33 Mute =false Feb 19 09:31:37 bro volumio[991]: info: CoreStateMachine::pushState Feb 19 09:31:37 bro volumio[991]: info: CorePlayQueue::getTrack 0 Feb 19 09:31:37 bro volumio[991]: info: CoreCommandRouter::volumioPushState Feb 19 09:31:37 bro systemd[1]: Started RoonBridge. Feb 19 09:31:37 bro sudo[1310]: pam_unix(sudo:session): session closed for user root Feb 19 09:31:37 bro volumio[991]: 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 19 09:31:37 bro volumio[991]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 09:31:37 bro volumio[991]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 09:31:37 bro volumio[991]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 09:31:37 bro volumio[991]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 09:31:37 bro volumio[991]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 09:31:37 bro volumio[991]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 09:31:37 bro volumio[991]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 09:31:37 bro volumio[991]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 09:31:37 bro volumio[991]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 19 09:31:37 bro volumio[991]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 09:31:37 bro volumio[991]: info: Spotify config file written Feb 19 09:31:37 bro volumio[991]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 09:31:37 bro volumio[991]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 09:31:37 bro volumio[991]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 09:31:37 bro volumio[991]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 09:31:37 bro volumio[991]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 09:31:37 bro volumio[991]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 09:31:37 bro volumio[991]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 09:31:37 bro volumio[991]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 09:31:37 bro volumio[991]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 19 09:31:37 bro volumio[991]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 09:31:37 bro sudo[1330]: volumio : unable to resolve host bro Feb 19 09:31:37 bro sudo[1330]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Feb 19 09:31:37 bro volumio[991]: info: No need to fix Spotify hosts Feb 19 09:31:37 bro sudo[1330]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Feb 19 09:31:37 bro sudo[1330]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 09:31:37 bro volumio[991]: info: Volumio called home Feb 19 09:31:37 bro volumio[991]: info: CoreCommandRouter::volumioGetState Feb 19 09:31:37 bro volumio[991]: info: CorePlayQueue::getTrack 0 Feb 19 09:31:37 bro volumio[991]: info: Starting Shairport Sync Feb 19 09:31:37 bro volumio[991]: info: Starting Shairport Sync Feb 19 09:31:37 bro volumio[991]: info: Starting Shairport Sync Feb 19 09:31:37 bro systemd[1]: Started go-librespot Daemon. Feb 19 09:31:37 bro go-librespot[1336]: go-librespot daemon starting... Feb 19 09:31:37 bro sudo[1338]: volumio : unable to resolve host bro Feb 19 09:31:37 bro sudo[1338]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Feb 19 09:31:37 bro sudo[1330]: pam_unix(sudo:session): session closed for user root Feb 19 09:31:37 bro sudo[1338]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 19 09:31:37 bro sudo[1338]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 09:31:37 bro sudo[1341]: volumio : unable to resolve host bro Feb 19 09:31:37 bro sudo[1341]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Feb 19 09:31:37 bro sudo[1341]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 19 09:31:37 bro sudo[1341]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 09:31:37 bro sudo[1343]: volumio : unable to resolve host bro Feb 19 09:31:37 bro sudo[1343]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Feb 19 09:31:37 bro sudo[1343]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 19 09:31:37 bro systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Feb 19 09:31:37 bro systemd[1]: shairport-sync.service: Succeeded. Feb 19 09:31:37 bro systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Feb 19 09:31:37 bro sudo[1343]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 09:31:37 bro systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Feb 19 09:31:37 bro volumio[991]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Feb 19 09:31:37 bro volumio[991]: SPOTIFY: BQCS-G8QzWYVP9bjjQOk2KU4CD5UMvjavsXd2IfoMfFKAH33uJqMkBaCYQxlkLspfdMD7GgH3T-vhmPr11sT1loR3FzQu_8gzaN5f_Xr41LfIWtv_Cez8jBnW-FT6umxFYKMQc5cEm0dhjbFWKmhyqlCbrVNiVY0oAMjGPLhXUDoI6UHlh_uUYkGVEUEs2rhabhJk4BFKbD-AajDsc-bYzw7XsxyDqEFlIfrxR68ziKAHIs2wY4zxwh6NQppz-oxsF8vKTvElFePz4Bt Feb 19 09:31:37 bro volumio[991]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Feb 19 09:31:37 bro sudo[1341]: pam_unix(sudo:session): session closed for user root Feb 19 09:31:37 bro volumio[991]: info: New Spotify access token = BQCS-G8QzWYVP9bjjQOk2KU4CD5UMvjavsXd2IfoMfFKAH33uJqMkBaCYQxlkLspfdMD7GgH3T-vhmPr11sT1loR3FzQu_8gzaN5f_Xr41LfIWtv_Cez8jBnW-FT6umxFYKMQc5cEm0dhjbFWKmhyqlCbrVNiVY0oAMjGPLhXUDoI6UHlh_uUYkGVEUEs2rhabhJk4BFKbD-AajDsc-bYzw7XsxyDqEFlIfrxR68ziKAHIs2wY4zxwh6NQppz-oxsF8vKTvElFePz4Bt Feb 19 09:31:37 bro volumio[991]: info: Spotify credentials grant success - running version from March 24, 2019 Feb 19 09:31:37 bro sudo[1338]: pam_unix(sudo:session): session closed for user root Feb 19 09:31:37 bro volumio[991]: info: Shairport-Sync Started Feb 19 09:31:37 bro systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Feb 19 09:31:37 bro volumio[991]: Error adding Membership: Error: addMembership EINVAL Feb 19 09:31:37 bro volumio[991]: info: Shairport-Sync Started Feb 19 09:31:37 bro systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Feb 19 09:31:37 bro systemd[1]: shairport-sync.service: Succeeded. Feb 19 09:31:37 bro systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Feb 19 09:31:37 bro systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Feb 19 09:31:37 bro sudo[1343]: pam_unix(sudo:session): session closed for user root Feb 19 09:31:37 bro volumio[991]: info: Shairport-Sync Started Feb 19 09:31:37 bro volumio[991]: SPOTIFY: User informations: {"country":"DE","display_name":"itakaboy","email":"rctuner22@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/itakaboy"},"followers":{"href":null,"total":0},"href":"https://api.spotify.com/v1/users/itakaboy","id":"itakaboy","images":[{"height":300,"url":"https://i.scdn.co/image/ab6775700000ee85d255e19b73bfe340de911c78","width":300},{"height":64,"url":"https://i.scdn.co/image/ab67757000003b82d255e19b73bfe340de911c78","width":64}],"product":"premium","type":"user","uri":"spotify:user:itakaboy"} Feb 19 09:31:37 bro volumio[991]: info: Spotify Successfully logged in Feb 19 09:31:37 bro volumio[991]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 19 09:31:37 bro volumio[991]: info: [1739953897612] CoreMusicLibrary::Adding element Spotify Feb 19 09:31:37 bro volumio[991]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 19 09:31:37 bro volumio[991]: Cannot find translation for source 80s80s Radio Feb 19 09:31:37 bro volumio[991]: Cannot find translation for source Spotify Feb 19 09:31:37 bro go-librespot[1336]: time="2025-02-19T09:31:37+01:00" level=info msg="running go-librespot 0.2.0" Feb 19 09:31:37 bro go-librespot[1336]: time="2025-02-19T09:31:37+01:00" level=debug msg="app state loaded" Feb 19 09:31:37 bro go-librespot[1336]: time="2025-02-19T09:31:37+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 19 09:31:37 bro mpd[1315]: Feb 19 09:31 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 19 09:31:37 bro systemd[1]: Started Music Player Daemon. Feb 19 09:31:37 bro sudo[1280]: pam_unix(sudo:session): session closed for user root Feb 19 09:31:37 bro sudo[1289]: pam_unix(sudo:session): session closed for user root Feb 19 09:31:37 bro volumio[991]: info: Completed starting Core Plugins Feb 19 09:31:37 bro volumio[991]: info: ------------------------------------------- Feb 19 09:31:37 bro volumio[991]: info: ----- MyVolumio plugins startup ---- Feb 19 09:31:37 bro volumio[991]: info: ------------------------------------------- Feb 19 09:31:37 bro volumio[991]: info: [MyVolumio PluginManager] Fetching plans data.... Feb 19 09:31:37 bro volumio[991]: info: MPD running with PID1315 Feb 19 09:31:37 bro volumio[991]: ,establishing connection Feb 19 09:31:37 bro volumio[991]: error: MPD error: The expression evaluated to a falsy value: Feb 19 09:31:37 bro volumio[991]: assert.ok(self.idling) Feb 19 09:31:37 bro volumio[991]: error: The expression evaluated to a falsy value: Feb 19 09:31:37 bro volumio[991]: assert.ok(self.idling) Feb 19 09:31:37 bro volumio[991]: error: MPD error: The expression evaluated to a falsy value: Feb 19 09:31:37 bro volumio[991]: assert.ok(self.idling) Feb 19 09:31:37 bro volumio[991]: error: The expression evaluated to a falsy value: Feb 19 09:31:37 bro volumio[991]: assert.ok(self.idling) Feb 19 09:31:37 bro volumio[991]: error: updateQueue error: null Feb 19 09:31:38 bro go-librespot[1336]: time="2025-02-19T09:31:38+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 19 09:31:38 bro go-librespot[1336]: time="2025-02-19T09:31:38+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 19 09:31:38 bro go-librespot[1336]: time="2025-02-19T09:31:38+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 19 09:31:38 bro go-librespot[1336]: time="2025-02-19T09:31:38+01:00" level=info msg="zeroconf server listening on port 44879" Feb 19 09:31:38 bro go-librespot[1336]: time="2025-02-19T09:31:38+01:00" level=debug msg="obtained new client token: AAD5AybMtWKOOifR53ZJMV2IjsbYQp59TGF2zpwZTrWnIqwlPIB71f1ENK4j0KI2Jlj8O2Y2NH5iUdEApAoCpkg5ZGCV9j2x1hKyoU1HTDP2WuqyFRo2X9DjxCpi/LNY2RmJyICVRruH7JHfePiTnkf4l+AYhm08yoGMKsDnzrP9veeio7TBSToUpnpFOBcMCpNXVlaeBWUnk91FRKRGM4wUhTiOMuRQ8pVhScmeCMt7+SIYRdYrGoQr" Feb 19 09:31:38 bro RoonBridge[1317]: 00:00:00.008 Warn: get lock file path: /tmp/.rnbgem0- Feb 19 09:31:38 bro go-librespot[1336]: time="2025-02-19T09:31:38+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 19 09:31:38 bro go-librespot[1336]: time="2025-02-19T09:31:38+01:00" level=debug msg="completed keyexchange" Feb 19 09:31:38 bro go-librespot[1336]: time="2025-02-19T09:31:38+01:00" level=debug msg="completed challenge" Feb 19 09:31:38 bro go-librespot[1336]: time="2025-02-19T09:31:38+01:00" level=info msg="authenticated AP as itakaboy" Feb 19 09:31:38 bro RoonBridge[1317]: 00:00:00.329 Trace: [childprocess] using unix child process Feb 19 09:31:38 bro RoonBridge[1317]: 00:00:00.380 Debug: PathForResource, filename: ../.update Feb 19 09:31:38 bro go-librespot[1336]: time="2025-02-19T09:31:38+01:00" level=info msg="authenticated Login5 as itakaboy" Feb 19 09:31:38 bro go-librespot[1336]: time="2025-02-19T09:31:38+01:00" level=debug msg="initializing zeroconf session, username: itakaboy" Feb 19 09:31:38 bro RoonBridge[1317]: 00:00:00.442 Debug: PathForResource, candidate: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/../.update Feb 19 09:31:38 bro RoonBridge[1317]: 00:00:00.447 Debug: PathForResource, filename: RAATServer Feb 19 09:31:38 bro RoonBridge[1317]: 00:00:00.447 Debug: PathForResource, candidate: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RAATServer Feb 19 09:31:38 bro RoonBridge[1317]: 00:00:00.448 Debug: PathForResource, filename: RoonBridgeHelper Feb 19 09:31:38 bro RoonBridge[1317]: 00:00:00.448 Debug: PathForResource, candidate: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RoonBridgeHelper Feb 19 09:31:38 bro RoonBridge[1317]: Initializing Feb 19 09:31:38 bro RoonBridge[1317]: 00:00:00.478 Info: Starting /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RoonBridgeHelper Feb 19 09:31:38 bro RoonBridge[1317]: 00:00:00.512 Info: ConnectOrStartAndWaitForExit RAATServer, path: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RAATServer Feb 19 09:31:38 bro systemd[1]: systemd-fsckd.service: Succeeded. Feb 19 09:31:38 bro go-librespot[1336]: time="2025-02-19T09:31:38+01:00" level=debug msg="dealer connection opened" Feb 19 09:31:38 bro go-librespot[1336]: time="2025-02-19T09:31:38+01:00" level=trace msg="starting accesspoint recv loop" Feb 19 09:31:38 bro go-librespot[1336]: time="2025-02-19T09:31:38+01:00" level=trace msg="starting dealer recv loop" Feb 19 09:31:38 bro go-librespot[1336]: time="2025-02-19T09:31:38+01:00" level=trace msg="received accesspoint ping" Feb 19 09:31:38 bro go-librespot[1336]: time="2025-02-19T09:31:38+01:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Feb 19 09:31:38 bro go-librespot[1336]: time="2025-02-19T09:31:38+01:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Feb 19 09:31:38 bro go-librespot[1336]: time="2025-02-19T09:31:38+01:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Feb 19 09:31:38 bro go-librespot[1336]: time="2025-02-19T09:31:38+01:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Feb 19 09:31:38 bro go-librespot[1336]: time="2025-02-19T09:31:38+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Feb 19 09:31:38 bro go-librespot[1336]: time="2025-02-19T09:31:38+01:00" level=debug msg="received connection id: NjZmY2RjN2QtNDQ1ZS00MDU2LWJjOTctYjdjYTM0NTIwMGQ2K2RlYWxlcit0Y3A6Ly8wYWNhNTk2Ny5pcC5nZXc0LnNwb3RpZnkubmV0OjU3MDArQzFDQ0ZGRDFBN0FGOTM1MTI5Mzg3QkFFNUE2OUM4NkZDMEU2Rjg5RTk0MzU2RDdGNjYzOEVBRThFMDQ3NkQ2QQ==" Feb 19 09:31:38 bro go-librespot[1336]: time="2025-02-19T09:31:38+01:00" level=trace msg="received accesspoint pong ack" Feb 19 09:31:38 bro RoonBridge[1317]: Not Running (.o) Feb 19 09:31:38 bro RoonBridge[1317]: Running Feb 19 09:31:39 bro RoonBridge[1317]: 00:00:00.022 Warn: get lock file path: /tmp/.rnbhgem0- Feb 19 09:31:39 bro sh[527]: timed out Feb 19 09:31:39 bro dhcpcd[572]: timed out Feb 19 09:31:39 bro sh[527]: dhcpcd exited Feb 19 09:31:39 bro dhcpcd[572]: dhcpcd exited Feb 19 09:31:39 bro sh[527]: ifup: failed to bring up eth0 Feb 19 09:31:39 bro systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Feb 19 09:31:39 bro systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Feb 19 09:31:39 bro go-librespot[1336]: time="2025-02-19T09:31:39+01:00" level=debug msg="put connect state because NEW_DEVICE" Feb 19 09:31:40 bro volumio[991]: info: go-librespot daemon successfully initialized Feb 19 09:31:40 bro systemd[1]: systemd-hostnamed.service: Succeeded. Feb 19 09:31:42 bro volumio[991]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Feb 19 09:31:42 bro sudo[1439]: volumio : unable to resolve host bro Feb 19 09:31:42 bro sudo[1439]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Feb 19 09:31:42 bro sudo[1439]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 19 09:31:42 bro sudo[1439]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 09:31:42 bro sudo[1439]: pam_unix(sudo:session): session closed for user root Feb 19 09:31:43 bro sudo[1441]: volumio : unable to resolve host bro Feb 19 09:31:43 bro sudo[1441]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Feb 19 09:31:43 bro sudo[1443]: volumio : unable to resolve host bro Feb 19 09:31:43 bro sudo[1443]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Feb 19 09:31:43 bro sudo[1441]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 19 09:31:43 bro sudo[1441]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 09:31:43 bro sudo[1443]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Feb 19 09:31:43 bro sudo[1443]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 09:31:43 bro sudo[1441]: pam_unix(sudo:session): session closed for user root Feb 19 09:31:43 bro systemd[1]: Started UPnP Renderer front-end to MPD. Feb 19 09:31:43 bro sudo[1443]: pam_unix(sudo:session): session closed for user root Feb 19 09:31:43 bro volumio[991]: info: Upmpdcli Daemon Started Feb 19 09:31:43 bro volumio[1447]: Generating RSA private key, 4096 bit long modulus (2 primes) Feb 19 09:31:43 bro volumio[991]: info: Initializing connection to go-librespot Websocket Feb 19 09:31:43 bro go-librespot[1336]: time="2025-02-19T09:31:43+01:00" level=debug msg="new websocket client" Feb 19 09:31:43 bro volumio[991]: info: Connection to go-librespot Websocket established Feb 19 09:31:43 bro wpa_supplicant[887]: RRM: Ignoring radio measurement request: Not RRM network Feb 19 09:31:43 bro volumio[1447]: .......++++ Feb 19 09:31:44 bro go-librespot[1336]: time="2025-02-19T09:31:44+01:00" level=debug msg="handling transfer player command from 5e5d53f2b562e47e41d9f089c6b0f3f7acdf4597" Feb 19 09:31:44 bro go-librespot[1336]: time="2025-02-19T09:31:44+01:00" level=debug msg="resolved context of track" uri="spotify:user:spotify:playlist:37i9dQZF1DX8Kgdykz6OKj" Feb 19 09:31:44 bro go-librespot[1336]: time="2025-02-19T09:31:44+01:00" level=trace msg="fetched new page 0 with 100 items (list: 100)" uri="spotify:user:spotify:playlist:37i9dQZF1DX8Kgdykz6OKj" Feb 19 09:31:44 bro go-librespot[1336]: time="2025-02-19T09:31:44+01:00" level=debug msg="loading track (paused: false, position: 151266ms)" uri="spotify:track:1is1QB3ElS0UlaEYpIHyo0" Feb 19 09:31:44 bro go-librespot[1336]: time="2025-02-19T09:31:44+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Feb 19 09:31:44 bro go-librespot[1336]: time="2025-02-19T09:31:44+01:00" level=trace msg="emitting websocket event: will_play" Feb 19 09:31:44 bro volumio[991]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:1is1QB3ElS0UlaEYpIHyo0","play_origin":"com.spotify.gaia"}} Feb 19 09:31:44 bro go-librespot[1336]: time="2025-02-19T09:31:44+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1272" Feb 19 09:31:44 bro go-librespot[1336]: time="2025-02-19T09:31:44+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 241" Feb 19 09:31:44 bro go-librespot[1336]: time="2025-02-19T09:31:44+01:00" level=debug msg="selected format OGG_VORBIS_320 (23093012ad08394b3224b36f594b16d50e269fcc)" uri="spotify:track:1is1QB3ElS0UlaEYpIHyo0" Feb 19 09:31:44 bro go-librespot[1336]: time="2025-02-19T09:31:44+01:00" level=debug msg="requested aes key for file 23093012ad08394b3224b36f594b16d50e269fcc, gid: 1is1QB3ElS0UlaEYpIHyo0" Feb 19 09:31:44 bro go-librespot[1336]: time="2025-02-19T09:31:44+01:00" level=debug msg="fetched first chunk of 21, total size is 10534452 bytes" uri="spotify:track:1is1QB3ElS0UlaEYpIHyo0" Feb 19 09:31:44 bro go-librespot[1336]: time="2025-02-19T09:31:44+01:00" level=debug msg="fetched chunk 1/20, size: 524288" uri="spotify:track:1is1QB3ElS0UlaEYpIHyo0" Feb 19 09:31:45 bro go-librespot[1336]: time="2025-02-19T09:31:45+01:00" level=debug msg="fetched chunk 14/20, size: 524288" uri="spotify:track:1is1QB3ElS0UlaEYpIHyo0" Feb 19 09:31:45 bro go-librespot[1336]: time="2025-02-19T09:31:45+01:00" level=trace msg="seek to 151266ms (diff: 125ms, samples: 6670830, bytes: 7394614)" uri="spotify:track:1is1QB3ElS0UlaEYpIHyo0" Feb 19 09:31:45 bro go-librespot[1336]: time="2025-02-19T09:31:45+01:00" level=debug msg="created new output device" Feb 19 09:31:45 bro go-librespot[1336]: time="2025-02-19T09:31:45+01:00" level=debug msg="alsa driver configured, rate = 44100 bps, period time = 124988 us, period size = 5512 frames, buffer time = 500000 us, buffer size = 22050 frames, periods per buffer = 4 frames" Feb 19 09:31:45 bro go-librespot[1336]: time="2025-02-19T09:31:45+01:00" level=info msg="loaded track \"Drive Slow\" (paused: false, position: 151266ms, duration: 221333ms, prefetched: false)" uri="spotify:track:1is1QB3ElS0UlaEYpIHyo0" Feb 19 09:31:45 bro go-librespot[1336]: time="2025-02-19T09:31:45+01:00" level=debug msg="fetched chunk 15/20, size: 524288" uri="spotify:track:1is1QB3ElS0UlaEYpIHyo0" Feb 19 09:31:45 bro go-librespot[1336]: time="2025-02-19T09:31:45+01:00" level=debug msg="fetched chunk 2/20, size: 524288" uri="spotify:track:1is1QB3ElS0UlaEYpIHyo0" Feb 19 09:31:45 bro go-librespot[1336]: time="2025-02-19T09:31:45+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Feb 19 09:31:45 bro go-librespot[1336]: time="2025-02-19T09:31:45+01:00" level=debug msg="fetched chunk 16/20, size: 524288" uri="spotify:track:1is1QB3ElS0UlaEYpIHyo0" Feb 19 09:31:45 bro go-librespot[1336]: time="2025-02-19T09:31:45+01:00" level=debug msg="fetched chunk 17/20, size: 524288" uri="spotify:track:1is1QB3ElS0UlaEYpIHyo0" Feb 19 09:31:45 bro go-librespot[1336]: time="2025-02-19T09:31:45+01:00" level=debug msg="fetched chunk 3/20, size: 524288" uri="spotify:track:1is1QB3ElS0UlaEYpIHyo0" Feb 19 09:31:45 bro go-librespot[1336]: time="2025-02-19T09:31:45+01:00" level=trace msg="scheduling prefetch in 40s" Feb 19 09:31:45 bro go-librespot[1336]: time="2025-02-19T09:31:45+01:00" level=trace msg="emitting websocket event: metadata" Feb 19 09:31:45 bro volumio[991]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:1is1QB3ElS0UlaEYpIHyo0","name":"Drive Slow","artist_names":["Nix Northwest"],"album_name":"Drive Slow","album_cover_url":"https://i.scdn.co/image/ab67616d00001e02d91d513fdf2ca1c068d31a9f","position":151266,"duration":221333,"release_date":"year:2020 month:9 day:30","track_number":1,"disc_number":1}} Feb 19 09:31:45 bro go-librespot[1336]: time="2025-02-19T09:31:45+01:00" level=trace msg="emitting websocket event: active" Feb 19 09:31:45 bro go-librespot[1336]: time="2025-02-19T09:31:45+01:00" level=debug msg="sending successful reply for dealer request" Feb 19 09:31:45 bro go-librespot[1336]: time="2025-02-19T09:31:45+01:00" level=debug msg="skipping dealer message" uri=social-connect/v2/broadcast_status_update Feb 19 09:31:45 bro go-librespot[1336]: time="2025-02-19T09:31:45+01:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Feb 19 09:31:45 bro volumio[991]: SPOTIFY: received: {"type":"active","data":null} Feb 19 09:31:45 bro volumio[991]: info: Aligning Spotify Volume to Volumio Volume Feb 19 09:31:45 bro volumio[991]: info: CoreCommandRouter::volumioGetState Feb 19 09:31:45 bro volumio[991]: info: CorePlayQueue::getTrack 0 Feb 19 09:31:45 bro volumio[991]: info: Setting Spotify Volume from Volumio: 33 Feb 19 09:31:45 bro go-librespot[1336]: time="2025-02-19T09:31:45+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Feb 19 09:31:45 bro go-librespot[1336]: time="2025-02-19T09:31:45+01:00" level=trace msg="emitting websocket event: playing" Feb 19 09:31:45 bro volumio[991]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:1is1QB3ElS0UlaEYpIHyo0","play_origin":"com.spotify.gaia"}} Feb 19 09:31:45 bro volumio[991]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 19 09:31:45 bro volumio[991]: TypeError: Cannot read property 'service' of undefined Feb 19 09:31:45 bro volumio[991]: at ControllerSpotify.identifyPlaybackMode (/data/plugins/music_service/spop/index.js:337:50) Feb 19 09:31:45 bro volumio[991]: at ControllerSpotify.parseEventState (/data/plugins/music_service/spop/index.js:263:18) Feb 19 09:31:45 bro volumio[991]: at WebSocket.message (/data/plugins/music_service/spop/index.js:190:14) Feb 19 09:31:45 bro volumio[991]: at WebSocket.emit (events.js:315:20) Feb 19 09:31:45 bro volumio[991]: at Receiver.receiverOnMessage (/data/plugins/music_service/spop/node_modules/ws/lib/websocket.js:1220:20) Feb 19 09:31:45 bro volumio[991]: at Receiver.emit (events.js:315:20) Feb 19 09:31:45 bro volumio[991]: at Receiver.dataMessage (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:596:14) Feb 19 09:31:45 bro volumio[991]: at Receiver.getData (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:496:10) Feb 19 09:31:45 bro volumio[991]: at Receiver.startLoop (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:167:16) Feb 19 09:31:45 bro volumio[991]: at Receiver._write (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:94:10) Feb 19 09:31:45 bro volumio[991]: at writeOrBuffer (internal/streams/writable.js:358:12) Feb 19 09:31:45 bro volumio[991]: at Receiver.Writable.write (internal/streams/writable.js:303:10) Feb 19 09:31:45 bro volumio[991]: at Socket.socketOnData (/data/plugins/music_service/spop/node_modules/ws/lib/websocket.js:1355:35) Feb 19 09:31:45 bro volumio[991]: at Socket.emit (events.js:315:20) Feb 19 09:31:45 bro volumio[991]: at addChunk (internal/streams/readable.js:309:12) Feb 19 09:31:45 bro volumio[991]: at readableAddChunk (internal/streams/readable.js:284:9) Feb 19 09:31:45 bro volumio[991]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 19 09:31:45 bro sudo[1473]: volumio : unable to resolve host bro Feb 19 09:31:45 bro sudo[1473]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Feb 19 09:31:45 bro sudo[1473]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-02-19 09:30 Feb 19 09:31:45 bro sudo[1473]: 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"