-- Logs begin at Thu 2019-02-14 18:12:00 CST, end at Mon 2025-05-12 21:36:22 CST. -- May 12 21:35:49 v1 systemd-timedated[850]: Changed local time to Mon May 12 21:35:49 2025 May 12 21:35:49 v1 sudo[848]: pam_unix(sudo:session): session closed for user root May 12 21:35:49 v1 volumio-time-update[463]: volumio-time-update-util: System time updated successfully. May 12 21:35:49 v1 systemd[1]: Started Volumio Time Update Utility. May 12 21:35:49 v1 systemd[1]: Reached target Multi-User System. May 12 21:35:49 v1 systemd[1]: Reached target Graphical Interface. May 12 21:35:49 v1 systemd[1]: Starting Update UTMP about System Runlevel Changes... May 12 21:35:49 v1 kernel: Key type cifs.spnego registered May 12 21:35:49 v1 kernel: Key type cifs.idmap registered May 12 21:35:49 v1 kernel: CIFS: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3.1.1), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3.1.1 (or even SMB3 or SMB2.1) specify vers=1.0 on mount. May 12 21:35:49 v1 kernel: CIFS: Attempting to mount //192.168.2.29/download May 12 21:35:49 v1 systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. May 12 21:35:49 v1 systemd[1]: Started Update UTMP about System Runlevel Changes. May 12 21:35:49 v1 systemd[1]: Startup finished in 15.138s (kernel) + 25.066s (userspace) = 40.204s. May 12 21:35:49 v1 kernel: cryptd: max_cpu_qlen set to 1000 May 12 21:35:49 v1 kernel: CIFS: Attempting to mount //192.168.2.29/BaiduYunDownload May 12 21:35:49 v1 sudo[852]: pam_unix(sudo:session): session closed for user root May 12 21:35:49 v1 sudo[854]: pam_unix(sudo:session): session closed for user root May 12 21:35:50 v1 volumio[671]: info: Loading plugin "upnp_browser"... May 12 21:35:50 v1 volumio[671]: info: Loading plugin "alarm-clock"... May 12 21:35:51 v1 volumio[671]: info: Loading plugin "airplay_emulation"... May 12 21:35:51 v1 volumio[671]: info: Starting Shairport Sync May 12 21:35:51 v1 volumio[671]: info: Loading plugin "last_100"... May 12 21:35:51 v1 volumio[671]: info: Loading plugin "webradio"... May 12 21:35:51 v1 volumio[671]: info: Loading plugin "i2s_dacs"... May 12 21:35:51 v1 volumio[671]: info: I2S DAC not set, start Auto-detection May 12 21:35:51 v1 volumio[671]: info: Loading plugin "volumiodiscovery"... May 12 21:35:51 v1 volumio[671]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 12 21:35:51 v1 volumio[671]: *** WARNING *** Please fix your application to use the native API of Avahi! May 12 21:35:51 v1 volumio[671]: *** WARNING *** For more information see May 12 21:35:51 v1 node[671]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 12 21:35:51 v1 volumio[671]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 12 21:35:51 v1 volumio[671]: *** WARNING *** Please fix your application to use the native API of Avahi! May 12 21:35:51 v1 volumio[671]: *** WARNING *** For more information see May 12 21:35:51 v1 node[671]: *** WARNING *** Please fix your application to use the native API of Avahi! May 12 21:35:51 v1 node[671]: *** WARNING *** For more information see May 12 21:35:51 v1 node[671]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 12 21:35:51 v1 node[671]: *** WARNING *** Please fix your application to use the native API of Avahi! May 12 21:35:51 v1 node[671]: *** WARNING *** For more information see May 12 21:35:51 v1 volumio[671]: info: Applying required configuration parameters for plugin volumiodiscovery May 12 21:35:51 v1 volumio[671]: info: Discovery: Started advertising with name: V1 May 12 21:35:51 v1 volumio[671]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 12 21:35:51 v1 volumio[671]: info: Loading plugin "outputs"... May 12 21:35:51 v1 volumio[671]: info: Loading plugin "albumart"... May 12 21:35:52 v1 volumio[671]: info: Plugin example_plugin is not enabled May 12 21:35:52 v1 volumio[671]: info: Loading plugin "inputs"... May 12 21:35:52 v1 volumio[671]: info: Loading plugin "updater_comm"... May 12 21:35:52 v1 volumio[671]: info: Plugin mpdemulation is not enabled May 12 21:35:52 v1 volumio[671]: info: Loading plugin "rest_api"... May 12 21:35:52 v1 volumio[671]: info: Loading plugin "websocket"... May 12 21:35:52 v1 volumio[671]: info: Starting Socket.io Server version 2.3.0 May 12 21:35:52 v1 volumio[671]: info: Loading i18n strings for locale zh May 12 21:35:52 v1 volumio[671]: Forking 3 albumart workers May 12 21:35:52 v1 volumio[671]: Updating browse sources language May 12 21:35:52 v1 volumio[671]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 12 21:35:53 v1 volumio[671]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 12 21:35:53 v1 volumio[671]: info: CoreCommandRouter::initPlayerControls May 12 21:35:53 v1 volumio[671]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:35:53 v1 volumio[671]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:35:53 v1 volumio[671]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:35:53 v1 volumio[671]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:35:53 v1 volumio[671]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:35:53 v1 volumio[671]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:35:53 v1 volumio[671]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 12 21:35:53 v1 volumio[671]: Express server listening on port 3000 May 12 21:35:53 v1 volumio[671]: [Metrics] WebUI: 12s 900.02ms May 12 21:35:53 v1 volumio[671]: info: CoreStateMachine::resetVolumioState May 12 21:35:53 v1 volumio[671]: info: CoreStateMachine::getcurrentVolume May 12 21:35:53 v1 volumio[671]: info: CoreCommandRouter::volumioRetrievevolume May 12 21:35:53 v1 volumio[671]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 12 21:35:53 v1 volumio[671]: info: Volumio Network Manager: Network status updated: 1 May 12 21:35:53 v1 volumio[671]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan May 12 21:35:53 v1 volumio[671]: wlan0 Interface doesn't support scanning. May 12 21:35:53 v1 volumio[671]: info: Cannot use regular scanning, forcing with ap-force May 12 21:35:53 v1 sudo[939]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force May 12 21:35:53 v1 sudo[939]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 21:35:54 v1 sudo[939]: pam_unix(sudo:session): session closed for user root May 12 21:35:54 v1 volumio[671]: command failed: No such device (-19) May 12 21:35:54 v1 volumio[671]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force May 12 21:35:54 v1 volumio[671]: command failed: No such device (-19) May 12 21:35:54 v1 volumio[671]: info: Reloading queue from file May 12 21:35:54 v1 volumio[671]: info: CoreStateMachine::setRepeat null single undefined May 12 21:35:54 v1 volumio[671]: info: CoreStateMachine::pushState May 12 21:35:54 v1 volumio[671]: info: CorePlayQueue::getTrack 0 May 12 21:35:54 v1 volumio[671]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 12 21:35:54 v1 volumio[671]: info: CoreCommandRouter::volumioPushState May 12 21:35:54 v1 volumio[671]: info: CoreStateMachine::setRandom null May 12 21:35:54 v1 volumio[671]: info: CoreStateMachine::pushState May 12 21:35:54 v1 volumio[671]: info: CorePlayQueue::getTrack 0 May 12 21:35:54 v1 volumio[671]: info: CoreCommandRouter::volumioPushState May 12 21:35:54 v1 volumio[671]: info: Setting Device type: Raspberry PI May 12 21:35:54 v1 volumio[671]: info: VolumeController:: Volume=33 Mute =false May 12 21:35:54 v1 volumio[671]: info: CoreStateMachine::pushState May 12 21:35:54 v1 volumio[671]: info: CorePlayQueue::getTrack 0 May 12 21:35:54 v1 volumio[671]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 12 21:35:54 v1 volumio[671]: info: CoreCommandRouter::volumioPushState May 12 21:35:54 v1 volumio[671]: info: CoreStateMachine::updateTrackBlock May 12 21:35:54 v1 volumio[671]: info: CorePlayQueue::getTrackBlock May 12 21:35:54 v1 volumio[671]: info: CoreCommandRouter::volumioRetrievevolume May 12 21:35:54 v1 volumio[671]: info: Completed loading Core Plugins May 12 21:35:54 v1 volumio[671]: info: Preparing to generate the ALSA configuration file May 12 21:35:54 v1 volumio[671]: info: Discovery: adding c14d6c9b-b285-46ca-a947-9d1b7ce2834f May 12 21:35:54 v1 volumio[671]: info: Discovery: Found device V1 May 12 21:35:54 v1 volumio[671]: info: CoreCommandRouter::volumioGetState May 12 21:35:54 v1 volumio[671]: info: CorePlayQueue::getTrack 0 May 12 21:35:54 v1 volumio[671]: info: Asound.conf file unchanged, so no further update is needed May 12 21:35:54 v1 volumio[671]: info: Output device has changed, restarting MPD May 12 21:35:54 v1 volumio[671]: Starting albumart workers May 12 21:35:54 v1 volumio[671]: info: Output device has changed, restarting Shairport Sync May 12 21:35:54 v1 volumio[671]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:35:54 v1 volumio[671]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 12 21:35:55 v1 sudo[952]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 12 21:35:55 v1 sudo[952]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 21:35:55 v1 volumio[671]: Starting albumart workers May 12 21:35:55 v1 sudo[954]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 12 21:35:55 v1 sudo[954]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 21:35:55 v1 sudo[952]: pam_unix(sudo:session): session closed for user root May 12 21:35:55 v1 volumio[671]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 12 21:35:55 v1 volumio[671]: info: ___________ START PLUGINS ___________ May 12 21:35:55 v1 volumio[671]: info: ControllerMpd::onStart: Initializing MPD May 12 21:35:55 v1 volumio[671]: info: Creating MPD Configuration file May 12 21:35:55 v1 systemd[1]: Stopping Music Player Daemon... May 12 21:35:55 v1 volumio[671]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 12 21:35:55 v1 volumio[671]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 12 21:35:55 v1 volumio[671]: info: [1747056955162] CoreMusicLibrary::Adding element 媒体服务器 May 12 21:35:55 v1 volumio[671]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 12 21:35:55 v1 sudo[961]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 12 21:35:55 v1 sudo[961]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 21:35:55 v1 sudo[961]: pam_unix(sudo:session): session closed for user root May 12 21:35:55 v1 sudo[963]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 12 21:35:55 v1 sudo[963]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 21:35:55 v1 volumio[671]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:35:55 v1 volumio[671]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 12 21:35:55 v1 volumio[671]: Starting albumart workers May 12 21:35:55 v1 systemd[1]: mpd.service: Succeeded. May 12 21:35:55 v1 systemd[1]: Stopped Music Player Daemon. May 12 21:35:55 v1 systemd[1]: Starting Music Player Daemon... May 12 21:35:55 v1 systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM May 12 21:35:55 v1 systemd[1]: mpd.service: Succeeded. May 12 21:35:55 v1 systemd[1]: Stopped Music Player Daemon. May 12 21:35:55 v1 volumio[671]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 12 21:35:55 v1 volumio[671]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:35:55 v1 volumio[671]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 12 21:35:55 v1 systemd[1]: Starting Music Player Daemon... May 12 21:35:55 v1 volumio[671]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 12 21:35:55 v1 volumio[671]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 12 21:35:55 v1 volumio[671]: info: [1747056955716] CoreMusicLibrary::Adding element Last_100 May 12 21:35:55 v1 volumio[671]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 12 21:35:55 v1 volumio[671]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 12 21:35:55 v1 volumio[671]: info: [1747056955735] CoreMusicLibrary::Adding element Webradio May 12 21:35:55 v1 volumio[671]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 12 21:35:55 v1 volumio[671]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 12 21:35:55 v1 volumio[671]: info: Initializing BBC Radios May 12 21:35:55 v1 sudo[970]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 12 21:35:55 v1 sudo[970]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 21:35:55 v1 sudo[970]: pam_unix(sudo:session): session closed for user root May 12 21:35:56 v1 volumio[671]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 12 21:35:56 v1 volumio[671]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 12 21:35:56 v1 volumio[671]: info: Volumio Calling Home May 12 21:35:56 v1 volumio[671]: info: MPD Permissions set May 12 21:35:56 v1 volumio[671]: info: MPD Permissions set May 12 21:35:56 v1 volumio[671]: info: VolumeController:: Volume=33 Mute =false May 12 21:35:56 v1 volumio[671]: info: CoreStateMachine::pushState May 12 21:35:56 v1 volumio[671]: info: CorePlayQueue::getTrack 0 May 12 21:35:56 v1 volumio[671]: info: CoreCommandRouter::volumioPushState May 12 21:35:57 v1 volumio[671]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 May 12 21:35:57 v1 volumio[671]: info: Volumio called home May 12 21:35:57 v1 volumio[671]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:35:57 v1 volumio[671]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:35:57 v1 volumio[671]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:35:57 v1 volumio[671]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:35:57 v1 volumio[671]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:35:57 v1 volumio[671]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:35:57 v1 volumio[671]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:35:57 v1 volumio[671]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:35:57 v1 volumio[671]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 12 21:35:57 v1 volumio[671]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:35:57 v1 volumio[671]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:35:57 v1 volumio[671]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 12 21:35:57 v1 volumio[671]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:35:57 v1 volumio[671]: info: Starting Shairport Sync May 12 21:35:57 v1 volumio[671]: info: Starting Shairport Sync May 12 21:35:57 v1 volumio[671]: info: Starting Shairport Sync May 12 21:35:57 v1 sudo[1022]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 12 21:35:57 v1 sudo[1022]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 21:35:57 v1 sudo[1024]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 12 21:35:57 v1 sudo[1024]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 21:35:57 v1 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 12 21:35:57 v1 systemd[1]: shairport-sync.service: Succeeded. May 12 21:35:57 v1 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 12 21:35:57 v1 volumio[671]: info: CoreCommandRouter::volumioGetState May 12 21:35:57 v1 volumio[671]: info: CorePlayQueue::getTrack 0 May 12 21:35:57 v1 sudo[1026]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 12 21:35:57 v1 sudo[1026]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 21:35:57 v1 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 12 21:35:57 v1 sudo[1022]: pam_unix(sudo:session): session closed for user root May 12 21:35:57 v1 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 12 21:35:57 v1 systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM May 12 21:35:57 v1 systemd[1]: shairport-sync.service: Succeeded. May 12 21:35:57 v1 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 12 21:35:57 v1 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 12 21:35:57 v1 sudo[1024]: pam_unix(sudo:session): session closed for user root May 12 21:35:57 v1 sudo[1026]: pam_unix(sudo:session): session closed for user root May 12 21:35:57 v1 volumio[671]: info: Shairport-Sync Started May 12 21:35:57 v1 volumio[671]: Error adding Membership: Error: addMembership EINVAL May 12 21:35:57 v1 volumio[671]: info: Shairport-Sync Started May 12 21:35:57 v1 volumio[671]: info: Shairport-Sync Started May 12 21:35:58 v1 sudo[1049]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 12 21:35:58 v1 sudo[1049]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 21:35:58 v1 sudo[1049]: pam_unix(sudo:session): session closed for user root May 12 21:35:58 v1 sudo[1051]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 12 21:35:58 v1 sudo[1051]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 21:35:59 v1 sudo[1051]: pam_unix(sudo:session): session closed for user root May 12 21:35:59 v1 sudo[1058]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service May 12 21:35:59 v1 sudo[1058]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 21:35:59 v1 mpd[976]: May 12 21:35 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 12 21:35:59 v1 systemd[1]: Started Music Player Daemon. May 12 21:35:59 v1 sudo[954]: pam_unix(sudo:session): session closed for user root May 12 21:35:59 v1 sudo[963]: pam_unix(sudo:session): session closed for user root May 12 21:35:59 v1 volumio[671]: info: Completed starting Core Plugins May 12 21:35:59 v1 systemd[1]: Started UPnP Renderer front-end to MPD. May 12 21:35:59 v1 sudo[1058]: pam_unix(sudo:session): session closed for user root May 12 21:35:59 v1 volumio[671]: info: ------------------------------------------- May 12 21:35:59 v1 volumio[671]: info: ----- MyVolumio plugins startup ---- May 12 21:35:59 v1 volumio[671]: info: ------------------------------------------- May 12 21:35:59 v1 volumio[671]: info: [MyVolumio PluginManager] Fetching plans data.... May 12 21:35:59 v1 volumio[671]: error: MPD error: The expression evaluated to a falsy value: May 12 21:35:59 v1 volumio[671]: assert.ok(self.idling) May 12 21:35:59 v1 volumio[671]: error: The expression evaluated to a falsy value: May 12 21:35:59 v1 volumio[671]: assert.ok(self.idling) May 12 21:35:59 v1 volumio[671]: info: MPD running with PID976 May 12 21:35:59 v1 volumio[671]: ,establishing connection May 12 21:35:59 v1 volumio[671]: error: MPD error: The expression evaluated to a falsy value: May 12 21:35:59 v1 volumio[671]: assert.ok(self.idling) May 12 21:35:59 v1 volumio[671]: error: The expression evaluated to a falsy value: May 12 21:35:59 v1 volumio[671]: assert.ok(self.idling) May 12 21:35:59 v1 volumio[671]: info: Upmpdcli Daemon Started May 12 21:35:59 v1 volumio[671]: error: updateQueue error: null May 12 21:35:59 v1 volumio[1063]: Generating RSA private key, 4096 bit long modulus (2 primes) May 12 21:35:59 v1 volumio-remote-updater[453]: [2025-05-12 21:35:59] [connect] Successful connection May 12 21:35:59 v1 volumio-remote-updater[453]: [2025-05-12 21:35:59] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1747056959 101 May 12 21:35:59 v1 volumio[671]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 2 May 12 21:36:00 v1 systemd[1]: systemd-fsckd.service: Succeeded. May 12 21:36:00 v1 sudo[1084]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 12 21:36:00 v1 sudo[1084]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 21:36:00 v1 sudo[1084]: pam_unix(sudo:session): session closed for user root May 12 21:36:00 v1 sudo[1086]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 12 21:36:00 v1 sudo[1086]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 21:36:00 v1 sudo[1086]: pam_unix(sudo:session): session closed for user root May 12 21:36:01 v1 volumio[671]: verbose: New Socket.io Connection to 192.168.2.32 from 192.168.2.29 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Safari/537.36 Edg/136.0.0.0 Engine version: 3 Transport: polling Total Clients: 3 May 12 21:36:01 v1 volumio[671]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 12 21:36:01 v1 volumio[671]: info: CoreCommandRouter::volumioGetVisibleSources May 12 21:36:01 v1 volumio[671]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 12 21:36:01 v1 volumio[671]: info: CoreCommandRouter::volumioGetState May 12 21:36:01 v1 volumio[671]: info: CorePlayQueue::getTrack 0 May 12 21:36:01 v1 volumio[671]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 12 21:36:01 v1 volumio[671]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 12 21:36:01 v1 volumio[671]: info: Received Get System Info May 12 21:36:01 v1 volumio[671]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 12 21:36:01 v1 volumio[671]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 12 21:36:01 v1 volumio[671]: info: Discovery: Getting this device information May 12 21:36:01 v1 volumio[671]: info: CoreCommandRouter::volumioGetState May 12 21:36:01 v1 volumio[671]: info: CorePlayQueue::getTrack 0 May 12 21:36:01 v1 volumio[671]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 12 21:36:01 v1 volumio[671]: info: CoreCommandRouter::volumioGetState May 12 21:36:01 v1 volumio[671]: info: CorePlayQueue::getTrack 0 May 12 21:36:01 v1 volumio[671]: info: Listing playlists May 12 21:36:01 v1 volumio[671]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 12 21:36:01 v1 volumio[671]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 12 21:36:02 v1 volumio[671]: info: CoreCommandRouter::volumioGetState May 12 21:36:02 v1 volumio[671]: info: CorePlayQueue::getTrack 0 May 12 21:36:02 v1 volumio[671]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 12 21:36:02 v1 volumio[671]: info: Received Get System Info May 12 21:36:02 v1 volumio[671]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 12 21:36:02 v1 volumio[671]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 12 21:36:02 v1 volumio[671]: info: Discovery: Getting this device information May 12 21:36:02 v1 volumio[671]: info: CoreCommandRouter::volumioGetState May 12 21:36:02 v1 volumio[671]: info: CorePlayQueue::getTrack 0 May 12 21:36:02 v1 volumio[671]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 12 21:36:03 v1 volumio[671]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard May 12 21:36:04 v1 volumio[671]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory May 12 21:36:05 v1 volumio[671]: info: CoreCommandRouter::getUIConfigOnPlugin May 12 21:36:05 v1 volumio[671]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions May 12 21:36:05 v1 volumio[671]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 12 21:36:05 v1 volumio[671]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 12 21:36:06 v1 volumio[671]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 12 21:36:06 v1 volumio[671]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 12 21:36:06 v1 volumio[671]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 12 21:36:06 v1 volumio[671]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 12 21:36:06 v1 volumio[671]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 12 21:36:06 v1 volumio[671]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode May 12 21:36:06 v1 volumio[671]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 12 21:36:08 v1 volumio[671]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium May 12 21:36:08 v1 volumio[671]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium May 12 21:36:08 v1 volumio[671]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium May 12 21:36:08 v1 volumio[671]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium May 12 21:36:08 v1 volumio[671]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium May 12 21:36:08 v1 volumio[671]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium May 12 21:36:08 v1 volumio[671]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium May 12 21:36:08 v1 volumio[671]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium May 12 21:36:08 v1 volumio[671]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium May 12 21:36:08 v1 volumio[671]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium May 12 21:36:08 v1 volumio[671]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium May 12 21:36:08 v1 volumio[671]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar May 12 21:36:08 v1 volumio[671]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar May 12 21:36:08 v1 volumio[671]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar May 12 21:36:08 v1 volumio[671]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar May 12 21:36:08 v1 volumio[671]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar May 12 21:36:08 v1 volumio[671]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar May 12 21:36:08 v1 volumio[671]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar May 12 21:36:08 v1 volumio[671]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar May 12 21:36:08 v1 volumio[671]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar May 12 21:36:08 v1 volumio[671]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar May 12 21:36:08 v1 volumio[671]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar May 12 21:36:08 v1 volumio[671]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso May 12 21:36:08 v1 volumio[671]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso May 12 21:36:08 v1 volumio[671]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso May 12 21:36:08 v1 volumio[671]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso May 12 21:36:08 v1 volumio[671]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso May 12 21:36:08 v1 volumio[671]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso May 12 21:36:08 v1 volumio[671]: info: Adding plugin bluetooth to MyMusic Plugins May 12 21:36:08 v1 volumio[671]: info: Adding plugin multiroom to MyMusic Plugins May 12 21:36:08 v1 volumio[671]: info: Adding plugin metavolumio to MyMusic Plugins May 12 21:36:08 v1 volumio[671]: info: Adding plugin cd_controller to MyMusic Plugins May 12 21:36:09 v1 volumio[671]: info: Adding plugin qobuzconnect to MyMusic Plugins May 12 21:36:09 v1 volumio[671]: info: Adding plugin smart_inputs to MyMusic Plugins May 12 21:36:09 v1 volumio[671]: info: Adding plugin tidalconnect to MyMusic Plugins May 12 21:36:09 v1 volumio[671]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... May 12 21:36:11 v1 volumio[671]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded May 12 21:36:11 v1 volumio[671]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio May 12 21:36:11 v1 volumio[671]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 12 21:36:11 v1 volumio[671]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 12 21:36:11 v1 volumio[671]: info: Starting MyVolumio Remote Streaming Endpoints May 12 21:36:11 v1 volumio[671]: info: MyVolumio not started May 12 21:36:11 v1 volumio[671]: info: Initializing device activation check May 12 21:36:11 v1 volumio[671]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started May 12 21:36:11 v1 volumio[671]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... May 12 21:36:14 v1 volumio[671]: info: Starting Streaming Service Transparent Proxy May 12 21:36:14 v1 volumio[671]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded May 12 21:36:14 v1 volumio[671]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services May 12 21:36:14 v1 volumio[671]: info: Streaming services startup May 12 21:36:14 v1 volumio[671]: info: Starting Streaming Daemon May 12 21:36:14 v1 sudo[1116]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 12 21:36:14 v1 sudo[1116]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 21:36:14 v1 volumio[671]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started May 12 21:36:14 v1 sudo[1116]: pam_unix(sudo:session): session closed for user root May 12 21:36:14 v1 volumio[671]: info: CoreCommandRouter::volumioGetState May 12 21:36:14 v1 volumio[671]: info: CorePlayQueue::getTrack 0 May 12 21:36:14 v1 volumio[671]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 12 21:36:14 v1 volumio[671]: error: Cannot start Volumio Streaming Daemon May 12 21:36:14 v1 volumio[671]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service May 12 21:36:14 v1 volumio[671]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. May 12 21:36:16 v1 volumio[671]: info: CoreCommandRouter::getUIConfigOnPlugin May 12 21:36:16 v1 volumio[671]: info: CoreCommandRouter::getUIConfigOnPlugin May 12 21:36:16 v1 volumio[671]: info: Retrieving Cloud Streaming UI May 12 21:36:16 v1 volumio[671]: info: Getting Tidal Cloud Configuration May 12 21:36:16 v1 volumio[671]: info: CoreCommandRouter::getUIConfigOnPlugin May 12 21:36:16 v1 volumio[671]: info: Getting Qobuz Cloud Configuration May 12 21:36:16 v1 volumio[671]: info: Asking plugin for UI Config May 12 21:36:16 v1 volumio[671]: info: CoreCommandRouter::getUIConfigOnPlugin May 12 21:36:16 v1 volumio[671]: info: Getting Spotify Cloud Configuration May 12 21:36:16 v1 volumio[671]: info: Asking plugin for UI Config May 12 21:36:16 v1 volumio[671]: info: CoreCommandRouter::getUIConfigOnPlugin May 12 21:36:16 v1 volumio[671]: info: Saving Spotify Acccount May 12 21:36:16 v1 volumio[671]: info: Got Tidal Cloud Configuration May 12 21:36:16 v1 volumio[671]: info: Got it May 12 21:36:16 v1 volumio[671]: info: Got it May 12 21:36:16 v1 volumio[671]: error: Could not retrieve Spotify Config from plugin Spotify: no section found May 12 21:36:16 v1 volumio[671]: info: CoreCommandRouter::getUIConfigOnPlugin May 12 21:36:16 v1 volumio[671]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined May 12 21:36:16 v1 volumio[671]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 12 21:36:16 v1 volumio[671]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 12 21:36:16 v1 volumio[671]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 12 21:36:16 v1 volumio[671]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 12 21:36:16 v1 volumio[671]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 12 21:36:16 v1 volumio[671]: info: CoreCommandRouter::volumioGetBrowseSources May 12 21:36:16 v1 volumio[671]: info: CoreCommandRouter::volumioGetBrowseSources May 12 21:36:16 v1 volumio[671]: STREAMING PROXY: Starting server on port 3245 May 12 21:36:16 v1 volumio[671]: Node JS runtime: 14 May 12 21:36:16 v1 volumio[671]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 12 21:36:16 v1 volumio[671]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares May 12 21:36:19 v1 systemd[1]: systemd-timedated.service: Succeeded. May 12 21:36:19 v1 volumio[671]: info: Disabling MyMusic plugin upnp May 12 21:36:19 v1 sudo[1146]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service May 12 21:36:19 v1 sudo[1146]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 21:36:19 v1 systemd[1]: Stopping UPnP Renderer front-end to MPD... May 12 21:36:20 v1 volumio[671]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 12 21:36:21 v1 volumio[671]: info: Enabling MyMusic plugin upnp May 12 21:36:21 v1 volumio[671]: info: Enabling plugin upnp May 12 21:36:21 v1 volumio[671]: info: Loading plugin "upnp"... May 12 21:36:21 v1 volumio[671]: info: [1747056981807] Starting Upmpd Daemon May 12 21:36:21 v1 volumio[671]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 12 21:36:21 v1 volumio[671]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 12 21:36:21 v1 volumio[671]: Error: listen EADDRINUSE: address already in use :::6599 May 12 21:36:21 v1 volumio[671]: at Server.setupListenHandle [as _listen2] (net.js:1318:16) May 12 21:36:21 v1 volumio[671]: at listenInCluster (net.js:1366:12) May 12 21:36:21 v1 volumio[671]: at Server.listen (net.js:1452:7) May 12 21:36:21 v1 volumio[671]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17) May 12 21:36:21 v1 volumio[671]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38) May 12 21:36:21 v1 volumio[671]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) May 12 21:36:21 v1 volumio[671]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) May 12 21:36:21 v1 volumio[671]: at processTicksAndRejections (internal/process/task_queues.js:75:11) { May 12 21:36:21 v1 volumio[671]: code: 'EADDRINUSE', May 12 21:36:21 v1 volumio[671]: errno: -98, May 12 21:36:21 v1 volumio[671]: syscall: 'listen', May 12 21:36:21 v1 volumio[671]: address: '::', May 12 21:36:21 v1 volumio[671]: port: 6599 May 12 21:36:21 v1 volumio[671]: } May 12 21:36:21 v1 volumio[671]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 12 21:36:22 v1 sudo[1176]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-12 21:35 May 12 21:36:22 v1 sudo[1176]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="9ab87de061b3649c303da550e73c92d340b0f989" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 30 Apr 2025 11:47:57 AM CEST" VOLUMIO_VERSION="3.807" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fddd10196af80fdc784c726185a6abe4"