-- Logs begin at Thu 2019-02-14 18:11:58 CST, end at Sat 2025-06-07 17:35:52 CST. -- Jun 07 17:35:36 volumio volumio[1047]: info: CoreStateMachine::setRepeat null single undefined Jun 07 17:35:36 volumio systemd-timedated[1166]: Changed local time to Sat Jun 7 17:35:36 2025 Jun 07 17:35:36 volumio systemd[1]: Starting Daily apt download activities... Jun 07 17:35:36 volumio volumio-time-update[598]: volumio-time-update-util: System time updated successfully. Jun 07 17:35:36 volumio sudo[1160]: pam_unix(sudo:session): session closed for user root Jun 07 17:35:36 volumio volumio[1047]: info: CoreStateMachine::pushState Jun 07 17:35:36 volumio volumio[1047]: info: CorePlayQueue::getTrack 0 Jun 07 17:35:36 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 07 17:35:36 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Jun 07 17:35:36 volumio volumio[1047]: info: CoreStateMachine::setRandom null Jun 07 17:35:36 volumio volumio[1047]: info: CoreStateMachine::pushState Jun 07 17:35:36 volumio volumio[1047]: info: CorePlayQueue::getTrack 0 Jun 07 17:35:36 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Jun 07 17:35:36 volumio volumio[1047]: info: Setting Device type: Raspberry PI Jun 07 17:35:36 volumio volumio[1047]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Jun 07 17:35:36 volumio volumio[1047]: info: USB Boot Capable - System SBC Revision found in cpuinfo: c03115 Jun 07 17:35:36 volumio volumio[1047]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Jun 07 17:35:36 volumio volumio[1047]: info: CoreStateMachine::pushState Jun 07 17:35:36 volumio volumio[1047]: info: CorePlayQueue::getTrack 0 Jun 07 17:35:36 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Jun 07 17:35:36 volumio volumio[1047]: info: Completed loading Core Plugins Jun 07 17:35:36 volumio volumio[1047]: info: Preparing to generate the ALSA configuration file Jun 07 17:35:36 volumio volumio[1047]: info: Discovery: A device disappeared from network Jun 07 17:35:36 volumio volumio[1047]: info: Discovery: Browse raised the following error TypeError: Cannot read property 'toLowerCase' of undefined Jun 07 17:35:36 volumio volumio[1047]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Jun 07 17:35:36 volumio volumio[1047]: info: Reading ALSA contributions from plugins. Jun 07 17:35:36 volumio volumio[1047]: info: Asound.conf file unchanged, so no further update is needed Jun 07 17:35:36 volumio volumio[1047]: info: Output device has changed, restarting MPD Jun 07 17:35:36 volumio systemd[1]: Started Volumio Time Update Utility. Jun 07 17:35:36 volumio systemd[1]: Reached target Multi-User System. Jun 07 17:35:36 volumio systemd[1]: Reached target Graphical Interface. Jun 07 17:35:36 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... Jun 07 17:35:36 volumio volumio[1047]: Starting albumart workers Jun 07 17:35:36 volumio volumio[1047]: info: Output device has changed, restarting Shairport Sync Jun 07 17:35:36 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 17:35:36 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 07 17:35:36 volumio sudo[1187]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 07 17:35:36 volumio sudo[1187]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 07 17:35:36 volumio sudo[1187]: pam_unix(sudo:session): session closed for user root Jun 07 17:35:36 volumio sudo[1190]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 07 17:35:36 volumio sudo[1190]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 07 17:35:36 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Jun 07 17:35:36 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. Jun 07 17:35:36 volumio systemd[1]: Stopping Music Player Daemon... Jun 07 17:35:36 volumio volumio[1047]: Starting albumart workers Jun 07 17:35:36 volumio volumio[1047]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 07 17:35:36 volumio volumio[1047]: info: ___________ START PLUGINS ___________ Jun 07 17:35:36 volumio volumio[1047]: info: ControllerMpd::onStart: Initializing MPD Jun 07 17:35:36 volumio volumio[1047]: info: Creating MPD Configuration file Jun 07 17:35:36 volumio sudo[1198]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 07 17:35:36 volumio sudo[1198]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 07 17:35:36 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 07 17:35:36 volumio volumio[1047]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 07 17:35:36 volumio volumio[1047]: info: [1749288936294] CoreMusicLibrary::Adding element 媒体服务器 Jun 07 17:35:36 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 07 17:35:36 volumio systemd[1]: mpd.service: Succeeded. Jun 07 17:35:36 volumio systemd[1]: Stopped Music Player Daemon. Jun 07 17:35:36 volumio sudo[1198]: pam_unix(sudo:session): session closed for user root Jun 07 17:35:36 volumio sudo[1202]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 07 17:35:36 volumio sudo[1202]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 07 17:35:36 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 17:35:36 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 07 17:35:36 volumio systemd[1]: Starting Music Player Daemon... Jun 07 17:35:36 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Jun 07 17:35:36 volumio systemd[1]: mpd.service: Succeeded. Jun 07 17:35:36 volumio systemd[1]: Stopped Music Player Daemon. Jun 07 17:35:36 volumio systemd[1]: Starting Music Player Daemon... Jun 07 17:35:36 volumio volumio[1047]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 07 17:35:36 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 17:35:36 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 07 17:35:36 volumio volumio[1047]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 07 17:35:36 volumio volumio[1047]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 07 17:35:36 volumio volumio[1047]: info: [1749288936456] CoreMusicLibrary::Adding element Last_100 Jun 07 17:35:36 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 07 17:35:36 volumio volumio[1047]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 07 17:35:36 volumio volumio[1047]: info: [1749288936459] CoreMusicLibrary::Adding element Webradio Jun 07 17:35:36 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 07 17:35:36 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 07 17:35:36 volumio volumio[1047]: info: Initializing BBC Radios Jun 07 17:35:36 volumio sudo[1209]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 07 17:35:36 volumio sudo[1209]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 07 17:35:36 volumio sudo[1209]: pam_unix(sudo:session): session closed for user root Jun 07 17:35:36 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 07 17:35:36 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 07 17:35:36 volumio volumio[1047]: info: Volumio Calling Home Jun 07 17:35:36 volumio volumio[1047]: info: MPD Permissions set Jun 07 17:35:36 volumio volumio[1047]: info: MPD Permissions set Jun 07 17:35:36 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 17:35:36 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 17:35:36 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 17:35:36 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 17:35:36 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 17:35:36 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 17:35:36 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 17:35:36 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 17:35:36 volumio volumio-remote-updater[601]: [2025-06-07 17:35:36] [connect] Successful connection Jun 07 17:35:36 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 07 17:35:36 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 17:35:36 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 17:35:36 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 17:35:36 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 17:35:36 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 17:35:36 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 07 17:35:36 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 17:35:36 volumio volumio-remote-updater[601]: [2025-06-07 17:35:36] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1749288936 101 Jun 07 17:35:36 volumio volumio[1047]: 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 Jun 07 17:35:37 volumio volumio[1047]: info: Starting Shairport Sync Jun 07 17:35:37 volumio volumio[1047]: info: Starting Shairport Sync Jun 07 17:35:37 volumio sudo[1296]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 07 17:35:37 volumio sudo[1296]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 07 17:35:37 volumio volumio[1047]: info: Starting Shairport Sync Jun 07 17:35:37 volumio sudo[1300]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 07 17:35:37 volumio sudo[1300]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 07 17:35:37 volumio sudo[1303]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 07 17:35:37 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 07 17:35:37 volumio sudo[1303]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 07 17:35:37 volumio systemd[1]: shairport-sync.service: Succeeded. Jun 07 17:35:37 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 07 17:35:37 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 07 17:35:37 volumio sudo[1296]: pam_unix(sudo:session): session closed for user root Jun 07 17:35:37 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 07 17:35:37 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Jun 07 17:35:37 volumio systemd[1]: shairport-sync.service: Succeeded. Jun 07 17:35:37 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 07 17:35:37 volumio volumio[1047]: info: Shairport-Sync Started Jun 07 17:35:37 volumio volumio[1047]: Error adding Membership: Error: addMembership EINVAL Jun 07 17:35:37 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 07 17:35:37 volumio sudo[1300]: pam_unix(sudo:session): session closed for user root Jun 07 17:35:37 volumio volumio[1047]: info: Shairport-Sync Started Jun 07 17:35:37 volumio systemd[1]: apt-daily.service: Succeeded. Jun 07 17:35:37 volumio sudo[1303]: pam_unix(sudo:session): session closed for user root Jun 07 17:35:37 volumio systemd[1]: Started Daily apt download activities. Jun 07 17:35:37 volumio volumio[1047]: info: Shairport-Sync Started Jun 07 17:35:37 volumio systemd[1]: Starting Daily apt upgrade and clean activities... Jun 07 17:35:37 volumio mpd[1231]: Jun 07 17:35 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 07 17:35:37 volumio systemd[1]: Started Music Player Daemon. Jun 07 17:35:37 volumio sudo[1202]: pam_unix(sudo:session): session closed for user root Jun 07 17:35:37 volumio sudo[1190]: pam_unix(sudo:session): session closed for user root Jun 07 17:35:37 volumio volumio[1047]: info: Completed starting Core Plugins Jun 07 17:35:37 volumio volumio[1047]: info: ------------------------------------------- Jun 07 17:35:37 volumio volumio[1047]: info: ----- MyVolumio plugins startup ---- Jun 07 17:35:37 volumio volumio[1047]: info: ------------------------------------------- Jun 07 17:35:37 volumio volumio[1047]: info: [MyVolumio PluginManager] Fetching plans data.... Jun 07 17:35:37 volumio volumio[1047]: error: MPD error: The expression evaluated to a falsy value: Jun 07 17:35:37 volumio volumio[1047]: assert.ok(self.idling) Jun 07 17:35:37 volumio volumio[1047]: error: The expression evaluated to a falsy value: Jun 07 17:35:37 volumio volumio[1047]: assert.ok(self.idling) Jun 07 17:35:37 volumio volumio[1047]: info: MPD running with PID1231 Jun 07 17:35:37 volumio volumio[1047]: ,establishing connection Jun 07 17:35:37 volumio volumio[1047]: error: updateQueue error: null Jun 07 17:35:37 volumio volumio[1047]: error: updateQueue error: null Jun 07 17:35:37 volumio systemd[1]: apt-daily-upgrade.service: Succeeded. Jun 07 17:35:37 volumio systemd[1]: Started Daily apt upgrade and clean activities. Jun 07 17:35:37 volumio systemd[1]: Startup finished in 12.106s (kernel) + 27.383s (userspace) = 39.489s. Jun 07 17:35:38 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 07 17:35:38 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 07 17:35:38 volumio volumio[1047]: info: Discovery: Getting this device information Jun 07 17:35:38 volumio volumio[1047]: info: CoreCommandRouter::volumioGetState Jun 07 17:35:38 volumio volumio[1047]: info: CorePlayQueue::getTrack 0 Jun 07 17:35:38 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 07 17:35:38 volumio volumio[1047]: verbose: New Socket.io Connection to 192.168.0.104:3000 from 192.168.0.103 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 2 Jun 07 17:35:38 volumio volumio[1047]: info: CoreCommandRouter::volumioGetState Jun 07 17:35:38 volumio volumio[1047]: info: CorePlayQueue::getTrack 0 Jun 07 17:35:38 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jun 07 17:35:38 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jun 07 17:35:40 volumio volumio[1047]: info: CoreCommandRouter::volumioGetState Jun 07 17:35:40 volumio volumio[1047]: info: CorePlayQueue::getTrack 0 Jun 07 17:35:41 volumio sudo[1375]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 07 17:35:41 volumio sudo[1375]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 07 17:35:41 volumio sudo[1375]: pam_unix(sudo:session): session closed for user root Jun 07 17:35:41 volumio sudo[1377]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 07 17:35:41 volumio sudo[1377]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 07 17:35:41 volumio sudo[1377]: pam_unix(sudo:session): session closed for user root Jun 07 17:35:41 volumio volumio[1047]: verbose: New Socket.io Connection to 192.168.0.104 from 192.168.0.103 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_1_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 3 Jun 07 17:35:42 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 07 17:35:42 volumio sudo[1381]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 07 17:35:42 volumio sudo[1381]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 07 17:35:42 volumio sudo[1381]: pam_unix(sudo:session): session closed for user root Jun 07 17:35:42 volumio sudo[1383]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 07 17:35:42 volumio sudo[1383]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 07 17:35:42 volumio sudo[1383]: pam_unix(sudo:session): session closed for user root Jun 07 17:35:42 volumio volumio[1047]: verbose: New Socket.io Connection to 192.168.0.104 from 192.168.0.103 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_1_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 4 Jun 07 17:35:42 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 07 17:35:42 volumio volumio[1047]: info: CoreCommandRouter::volumioGetVisibleSources Jun 07 17:35:42 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 07 17:35:42 volumio volumio[1047]: info: CoreCommandRouter::volumioGetState Jun 07 17:35:42 volumio volumio[1047]: info: CorePlayQueue::getTrack 0 Jun 07 17:35:42 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jun 07 17:35:42 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 07 17:35:42 volumio volumio[1047]: info: Received Get System Info Jun 07 17:35:42 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 07 17:35:42 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 07 17:35:42 volumio volumio[1047]: info: Discovery: Getting this device information Jun 07 17:35:42 volumio volumio[1047]: info: CoreCommandRouter::volumioGetState Jun 07 17:35:42 volumio volumio[1047]: info: CorePlayQueue::getTrack 0 Jun 07 17:35:42 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 07 17:35:42 volumio volumio[1047]: info: CoreCommandRouter::volumioGetState Jun 07 17:35:42 volumio volumio[1047]: info: CorePlayQueue::getTrack 0 Jun 07 17:35:42 volumio volumio[1047]: info: Listing playlists Jun 07 17:35:42 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jun 07 17:35:42 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 07 17:35:43 volumio systemd[1]: systemd-fsckd.service: Succeeded. Jun 07 17:35:43 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 07 17:35:43 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 07 17:35:43 volumio volumio[1047]: info: Discovery: Getting this device information Jun 07 17:35:43 volumio volumio[1047]: info: CoreCommandRouter::volumioGetState Jun 07 17:35:43 volumio volumio[1047]: info: CorePlayQueue::getTrack 0 Jun 07 17:35:43 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 07 17:35:44 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jun 07 17:35:44 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 07 17:35:44 volumio volumio[1047]: info: Received Get System Info Jun 07 17:35:44 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 07 17:35:44 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 07 17:35:44 volumio volumio[1047]: info: Discovery: Getting this device information Jun 07 17:35:44 volumio volumio[1047]: info: CoreCommandRouter::volumioGetState Jun 07 17:35:44 volumio volumio[1047]: info: CorePlayQueue::getTrack 0 Jun 07 17:35:44 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 07 17:35:44 volumio sh[528]: timed out Jun 07 17:35:44 volumio dhcpcd[580]: timed out Jun 07 17:35:44 volumio sudo[1401]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 07 17:35:44 volumio sudo[1401]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 07 17:35:44 volumio sh[528]: dhcpcd exited Jun 07 17:35:44 volumio dhcpcd[580]: dhcpcd exited Jun 07 17:35:44 volumio sh[528]: ifup: failed to bring up eth0 Jun 07 17:35:44 volumio sudo[1401]: pam_unix(sudo:session): session closed for user root Jun 07 17:35:44 volumio systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Jun 07 17:35:44 volumio systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Jun 07 17:35:44 volumio sudo[1404]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 07 17:35:44 volumio sudo[1404]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 07 17:35:44 volumio sudo[1404]: pam_unix(sudo:session): session closed for user root Jun 07 17:35:44 volumio sudo[1408]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jun 07 17:35:44 volumio sudo[1408]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 07 17:35:44 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Jun 07 17:35:44 volumio sudo[1408]: pam_unix(sudo:session): session closed for user root Jun 07 17:35:44 volumio volumio[1047]: info: Upmpdcli Daemon Started Jun 07 17:35:44 volumio volumio[1410]: Generating RSA private key, 4096 bit long modulus (2 primes) Jun 07 17:35:45 volumio volumio[1047]: info: Volumio called home Jun 07 17:35:45 volumio systemd[1]: systemd-hostnamed.service: Succeeded. Jun 07 17:35:45 volumio volumio[1410]: ...............++++ Jun 07 17:35:47 volumio kernel: usb 1-1.4: new full-speed USB device number 3 using xhci_hcd Jun 07 17:35:47 volumio kernel: usb 1-1.4: New USB device found, idVendor=16d0, idProduct=098b, bcdDevice=10.00 Jun 07 17:35:47 volumio kernel: usb 1-1.4: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Jun 07 17:35:47 volumio kernel: usb 1-1.4: Product: AMB zeta1 USB-I2S Audio-Widget Jun 07 17:35:47 volumio kernel: usb 1-1.4: Manufacturer: AMB Laboratories Jun 07 17:35:47 volumio kernel: usb 1-1.4: SerialNumber: 2015082600AMB Jun 07 17:35:47 volumio kernel: input: AMB Laboratories AMB zeta1 USB-I2S Audio-Widget as /devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb1/1-1/1-1.4/1-1.4:1.1/0003:16D0:098B.0001/input/input0 Jun 07 17:35:47 volumio kernel: hid-generic 0003:16D0:098B.0001: input,hidraw0: USB HID v1.11 Device [AMB Laboratories AMB zeta1 USB-I2S Audio-Widget] on usb-0000:01:00.0-1.4/input1 Jun 07 17:35:47 volumio kernel: usb 1-1.4: 18:2: failed to get current value for ch 0 (-22) Jun 07 17:35:47 volumio kernel: usb 1-1.4: 18:2: cannot get min/max values for control 2 (id 18) Jun 07 17:35:47 volumio kernel: usbcore: registered new interface driver snd-usb-audio Jun 07 17:35:47 volumio systemd-udevd[1431]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Jun 07 17:35:47 volumio kernel: usb 1-1.4: 18:2: cannot get min/max values for control 2 (id 18) Jun 07 17:35:47 volumio volumio[1047]: info: Jun 07 17:35:47 volumio volumio[1047]: ---------------------------- USB Audio Device Attached Jun 07 17:35:47 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Jun 07 17:35:47 volumio kernel: usb 1-1.4: 18:2: cannot get min/max values for control 2 (id 18) Jun 07 17:35:47 volumio kernel: usb 1-1.4: 18:2: cannot get min/max values for control 2 (id 18) Jun 07 17:35:47 volumio kernel: usb 1-1.4: 18:2: cannot get min/max values for control 2 (id 18) Jun 07 17:35:47 volumio kernel: usb 1-1.4: 18:2: cannot get min/max values for control 2 (id 18) Jun 07 17:35:47 volumio kernel: usb 1-1.4: 18:2: cannot get min/max values for control 2 (id 18) Jun 07 17:35:47 volumio volumio[1047]: info: CoreCommandRouter::Close All Modals sent Jun 07 17:35:47 volumio volumio[1047]: info: Preparing to save Alsa Options, stopping services first Jun 07 17:35:47 volumio volumio[1047]: info: CoreCommandRouter::volumioGetState Jun 07 17:35:47 volumio volumio[1047]: info: CorePlayQueue::getTrack 0 Jun 07 17:35:47 volumio volumio[1047]: info: CoreCommandRouter::volumioPause Jun 07 17:35:47 volumio volumio[1047]: info: CoreStateMachine::pause Jun 07 17:35:47 volumio volumio[1047]: info: Saving Audio Output to: {"disallowPush":true,"output_device":{"value":"5","label":"AMB zeta1 USB-I2S Audio-Widget","alsacard":"AudioWidget"},"i2s":false} Jun 07 17:35:47 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 07 17:35:47 volumio kernel: usb 1-1.4: 18:2: cannot get min/max values for control 2 (id 18) Jun 07 17:35:47 volumio kernel: usb 1-1.4: 18:2: cannot get min/max values for control 2 (id 18) Jun 07 17:35:47 volumio kernel: usb 1-1.4: 18:2: cannot get min/max values for control 2 (id 18) Jun 07 17:35:48 volumio systemd-udevd[1429]: Process '/usr/sbin/alsactl -E HOME=/run/alsa restore 5' failed with exit code 99. Jun 07 17:35:48 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 07 17:35:48 volumio kernel: usb 1-1.4: 18:2: cannot get min/max values for control 2 (id 18) Jun 07 17:35:48 volumio volumio[1047]: amixer: Mixer hw:5 load error: Invalid argument Jun 07 17:35:48 volumio volumio[1047]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jun 07 17:35:48 volumio volumio[1047]: info: Updating Volume Controller Parameters: Device: 5 Name: softvolume Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Jun 07 17:35:48 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Jun 07 17:35:48 volumio volumio[1047]: info: Disabling external Volume Control Jun 07 17:35:48 volumio volumio[1047]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 07 17:35:48 volumio volumio[1047]: info: Enable softmixer device for audio device undefined Jun 07 17:35:48 volumio volumio[1047]: info: Software Volume ALSA configuration written Jun 07 17:35:48 volumio volumio[1047]: info: Preparing to generate the ALSA configuration file Jun 07 17:35:48 volumio kernel: usb 1-1.4: 18:2: cannot get min/max values for control 2 (id 18) Jun 07 17:35:48 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jun 07 17:35:48 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 07 17:35:48 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 07 17:35:48 volumio kernel: usb 1-1.4: 18:2: cannot get min/max values for control 2 (id 18) Jun 07 17:35:48 volumio volumio[1047]: amixer: Mixer hw:5 load error: Invalid argument Jun 07 17:35:48 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 07 17:35:48 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jun 07 17:35:48 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jun 07 17:35:48 volumio volumio[1047]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Jun 07 17:35:48 volumio volumio[1047]: info: Reading ALSA contributions from plugins. Jun 07 17:35:48 volumio volumio[1047]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Mixer hw:5 load error: Invalid argument Jun 07 17:35:48 volumio volumio[1047]: info: Asound.conf file unchanged, so no further update is needed Jun 07 17:35:48 volumio volumio[1047]: info: Output device has changed, restarting MPD Jun 07 17:35:48 volumio sudo[1457]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 07 17:35:48 volumio sudo[1457]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 07 17:35:48 volumio volumio[1047]: info: Output device has changed, restarting Shairport Sync Jun 07 17:35:48 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 17:35:48 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 07 17:35:48 volumio sudo[1457]: pam_unix(sudo:session): session closed for user root Jun 07 17:35:48 volumio kernel: usb 1-1.4: 18:2: cannot get min/max values for control 2 (id 18) Jun 07 17:35:48 volumio sudo[1459]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 07 17:35:48 volumio sudo[1459]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 07 17:35:48 volumio systemd[1]: Stopping Music Player Daemon... Jun 07 17:35:48 volumio volumio[1047]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 07 17:35:48 volumio volumio[1047]: info: MPD Permissions set Jun 07 17:35:48 volumio volumio[1047]: info: VolumeController:: Volume=undefined Mute =false Jun 07 17:35:48 volumio volumio[1047]: info: CoreStateMachine::pushState Jun 07 17:35:48 volumio volumio[1047]: info: CorePlayQueue::getTrack 0 Jun 07 17:35:48 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 07 17:35:48 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Jun 07 17:35:48 volumio systemd[1]: mpd.service: Succeeded. Jun 07 17:35:48 volumio systemd[1]: Stopped Music Player Daemon. Jun 07 17:35:48 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 17:35:48 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 17:35:48 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 17:35:48 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 17:35:48 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 17:35:48 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 17:35:48 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 17:35:48 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 17:35:48 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 07 17:35:48 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 17:35:48 volumio volumio[1047]: info: Starting Shairport Sync Jun 07 17:35:48 volumio systemd[1]: Starting Music Player Daemon... Jun 07 17:35:48 volumio sudo[1471]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 07 17:35:48 volumio sudo[1471]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 07 17:35:48 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 07 17:35:48 volumio systemd[1]: shairport-sync.service: Succeeded. Jun 07 17:35:48 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 07 17:35:48 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 07 17:35:48 volumio sudo[1471]: pam_unix(sudo:session): session closed for user root Jun 07 17:35:48 volumio volumio[1047]: info: Shairport-Sync Started Jun 07 17:35:48 volumio volumio[1410]: ..........................++++ Jun 07 17:35:48 volumio volumio[1410]: e is 65537 (0x010001) Jun 07 17:35:48 volumio sudo[1465]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 07 17:35:48 volumio sudo[1465]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 07 17:35:48 volumio sudo[1465]: pam_unix(sudo:session): session closed for user root Jun 07 17:35:48 volumio volumio[1410]: writing RSA key Jun 07 17:35:48 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: raat , restartRaat Jun 07 17:35:49 volumio mpd[1477]: Jun 07 17:35 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 07 17:35:49 volumio systemd[1]: Started Music Player Daemon. Jun 07 17:35:49 volumio sudo[1459]: pam_unix(sudo:session): session closed for user root Jun 07 17:35:49 volumio volumio[1047]: error: updateQueue error: null Jun 07 17:35:52 volumio volumio[1047]: info: Discovery: adding 38badae3-6562-4159-b576-9c16bbcff04a Jun 07 17:35:52 volumio volumio[1047]: info: Discovery: Found device Volumio Jun 07 17:35:52 volumio volumio[1047]: info: CoreCommandRouter::volumioGetState Jun 07 17:35:52 volumio volumio[1047]: info: CorePlayQueue::getTrack 0 Jun 07 17:35:52 volumio volumio[1047]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 07 17:35:52 volumio volumio[1047]: Error: The value NaN is not a number Jun 07 17:35:52 volumio volumio[1047]: at Config.forceToType (/volumio/node_modules/v-conf/index.js:322:20) Jun 07 17:35:52 volumio volumio[1047]: at Config.set (/volumio/node_modules/v-conf/index.js:153:25) Jun 07 17:35:52 volumio volumio[1047]: at ControllerVolumioDiscovery.updateMultiroomDevice (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:323:25) Jun 07 17:35:52 volumio volumio[1047]: at ControllerVolumioDiscovery.connectToRemoteVolumio (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:273:10) Jun 07 17:35:52 volumio volumio[1047]: at Browser. (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:192:12) Jun 07 17:35:52 volumio volumio[1047]: at Browser.emit (events.js:315:20) Jun 07 17:35:52 volumio volumio[1047]: at on_resolver_done (/volumio/node_modules/mdns/lib/browser.js:31:14) Jun 07 17:35:52 volumio volumio[1047]: at next (/volumio/node_modules/mdns/lib/browser.js:106:7) Jun 07 17:35:52 volumio volumio[1047]: at Array.makeAddressesUnique (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:177:5) Jun 07 17:35:52 volumio volumio[1047]: at next (/volumio/node_modules/mdns/lib/browser.js:109:21) Jun 07 17:35:52 volumio volumio[1047]: at /volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:160:11 Jun 07 17:35:52 volumio volumio[1047]: at getaddrinfo_complete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:108:7) Jun 07 17:35:52 volumio volumio[1047]: at GetAddrInfoReqWrap.oncomplete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:120:9) Jun 07 17:35:52 volumio volumio[1047]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 07 17:35:52 volumio sudo[1504]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-07 17:34 Jun 07 17:35:52 volumio sudo[1504]: 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="570c5791513f5bac7da274aba6690c1a961705de" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 13 May 2025 05:16:30 PM CEST" VOLUMIO_VERSION="3.812" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="3934ee440d5d3f0186049a23752ad680"