-- Logs begin at Thu 2019-02-14 19:11:58 KST, end at Wed 2025-06-11 22:43:49 KST. -- Jun 11 22:43:41 volumioj2 systemd-timedated[1022]: Changed local time to Wed Jun 11 22:43:41 2025 Jun 11 22:43:41 volumioj2 sudo[1020]: pam_unix(sudo:session): session closed for user root Jun 11 22:43:41 volumioj2 volumio-time-update[622]: volumio-time-update-util: System time updated successfully. Jun 11 22:43:41 volumioj2 systemd[1]: Started Volumio Time Update Utility. Jun 11 22:43:41 volumioj2 volumio[861]: info: Loading plugin "i2s_dacs"... Jun 11 22:43:41 volumioj2 systemd[1]: Reached target Multi-User System. Jun 11 22:43:41 volumioj2 systemd[1]: Reached target Graphical Interface. Jun 11 22:43:41 volumioj2 volumio[861]: info: I2S DAC not set, start Auto-detection Jun 11 22:43:41 volumioj2 volumio[861]: info: Loading plugin "volumiodiscovery"... Jun 11 22:43:41 volumioj2 volumio[861]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 11 22:43:41 volumioj2 volumio[861]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 11 22:43:41 volumioj2 volumio[861]: *** WARNING *** For more information see Jun 11 22:43:41 volumioj2 node[861]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 11 22:43:41 volumioj2 volumio[861]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 11 22:43:41 volumioj2 volumio[861]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 11 22:43:41 volumioj2 volumio[861]: *** WARNING *** For more information see Jun 11 22:43:41 volumioj2 node[861]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 11 22:43:41 volumioj2 node[861]: *** WARNING *** For more information see Jun 11 22:43:41 volumioj2 node[861]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 11 22:43:41 volumioj2 node[861]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 11 22:43:41 volumioj2 node[861]: *** WARNING *** For more information see Jun 11 22:43:41 volumioj2 volumio[861]: info: Applying required configuration parameters for plugin volumiodiscovery Jun 11 22:43:41 volumioj2 volumio[861]: info: Discovery: Started advertising with name: Volumio_J2 Jun 11 22:43:41 volumioj2 systemd[1]: Starting Update UTMP about System Runlevel Changes... Jun 11 22:43:41 volumioj2 volumio[861]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 11 22:43:41 volumioj2 volumio[861]: info: Loading plugin "youtube2"... Jun 11 22:43:41 volumioj2 systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Jun 11 22:43:41 volumioj2 systemd[1]: Started Update UTMP about System Runlevel Changes. Jun 11 22:43:41 volumioj2 systemd[1]: Startup finished in 12.169s (kernel) + 14.210s (userspace) = 26.379s. Jun 11 22:43:42 volumioj2 volumio[861]: info: Loading plugin "ytcr"... Jun 11 22:43:42 volumioj2 volumio-remote-updater[592]: [2025-06-11 22:43:42] [connect] Successful connection Jun 11 22:43:43 volumioj2 volumio[861]: info: Loading plugin "ytmusic"... Jun 11 22:43:44 volumioj2 sudo[960]: pam_unix(sudo:session): session closed for user root Jun 11 22:43:44 volumioj2 volumio[861]: info: Loading plugin "outputs"... Jun 11 22:43:44 volumioj2 volumio[861]: info: Loading plugin "albumart"... Jun 11 22:43:44 volumioj2 volumio[861]: info: Plugin example_plugin is not enabled Jun 11 22:43:44 volumioj2 volumio[861]: info: Loading plugin "inputs"... Jun 11 22:43:44 volumioj2 volumio[861]: info: Loading plugin "updater_comm"... Jun 11 22:43:44 volumioj2 volumio[861]: info: Plugin mpdemulation is not enabled Jun 11 22:43:44 volumioj2 volumio[861]: info: Loading plugin "rest_api"... Jun 11 22:43:44 volumioj2 volumio[861]: info: Loading plugin "websocket"... Jun 11 22:43:44 volumioj2 volumio[861]: info: Starting Socket.io Server version 2.3.0 Jun 11 22:43:44 volumioj2 volumio[861]: info: Loading plugin "fusiondsp"... Jun 11 22:43:44 volumioj2 volumio[861]: Forking 3 albumart workers Jun 11 22:43:45 volumioj2 volumio[861]: info: Applying required configuration parameters for plugin fusiondsp Jun 11 22:43:45 volumioj2 volumio[861]: info: Loading plugin "minidlna"... Jun 11 22:43:45 volumioj2 volumio[861]: info: Applying required configuration parameters for plugin minidlna Jun 11 22:43:45 volumioj2 volumio[861]: Starting albumart workers Jun 11 22:43:45 volumioj2 volumio[861]: Starting albumart workers Jun 11 22:43:45 volumioj2 volumio[861]: Starting albumart workers Jun 11 22:43:45 volumioj2 volumio[861]: info: Loading plugin "personal_radio"... Jun 11 22:43:46 volumioj2 volumio[861]: info: ControllerPersonalRadio::constructor Jun 11 22:43:46 volumioj2 volumio[861]: info: Loading i18n strings for locale ko Jun 11 22:43:46 volumioj2 volumio[861]: error: minidlna: Fetching language file: Error: i18n file complementing the system language not found. Jun 11 22:43:46 volumioj2 volumio[861]: Updating browse sources language Jun 11 22:43:46 volumioj2 volumio[861]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 11 22:43:46 volumioj2 volumio[861]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 11 22:43:46 volumioj2 volumio[861]: info: CoreCommandRouter::initPlayerControls Jun 11 22:43:46 volumioj2 volumio[861]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 22:43:46 volumioj2 volumio[861]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 22:43:46 volumioj2 volumio[861]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 22:43:46 volumioj2 volumio[861]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 22:43:46 volumioj2 volumio[861]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 22:43:46 volumioj2 volumio[861]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 22:43:46 volumioj2 volumio[861]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 22:43:46 volumioj2 volumio[861]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 22:43:46 volumioj2 volumio[861]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 11 22:43:46 volumioj2 volumio[861]: Express server listening on port 3000 Jun 11 22:43:46 volumioj2 volumio[861]: [Metrics] WebUI: 8s 673.85ms Jun 11 22:43:46 volumioj2 volumio[861]: info: CoreStateMachine::resetVolumioState Jun 11 22:43:46 volumioj2 volumio[861]: info: CoreStateMachine::getcurrentVolume Jun 11 22:43:46 volumioj2 volumio[861]: info: CoreCommandRouter::volumioRetrievevolume Jun 11 22:43:46 volumioj2 volumio[861]: info: CoreStateMachine::pushState Jun 11 22:43:46 volumioj2 volumio[861]: info: CorePlayQueue::getTrack 0 Jun 11 22:43:46 volumioj2 volumio[861]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 11 22:43:46 volumioj2 volumio[861]: info: CoreCommandRouter::volumioPushState Jun 11 22:43:46 volumioj2 volumio[861]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 11 22:43:46 volumioj2 volumio[861]: info: Volumio Network Manager: Network status updated: 1 Jun 11 22:43:46 volumioj2 volumio-remote-updater[592]: [2025-06-11 22:43:46] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1749649422 101 Jun 11 22:43:46 volumioj2 volumio[861]: 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 11 22:43:46 volumioj2 volumio[861]: info: Reloading queue from file Jun 11 22:43:46 volumioj2 volumio[861]: info: CoreStateMachine::setRepeat null single undefined Jun 11 22:43:46 volumioj2 volumio[861]: info: CoreStateMachine::pushState Jun 11 22:43:46 volumioj2 volumio[861]: info: CorePlayQueue::getTrack 0 Jun 11 22:43:46 volumioj2 volumio[861]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 11 22:43:46 volumioj2 volumio[861]: info: CoreCommandRouter::volumioPushState Jun 11 22:43:46 volumioj2 volumio[861]: info: CoreStateMachine::setRandom null Jun 11 22:43:46 volumioj2 volumio[861]: info: CoreStateMachine::pushState Jun 11 22:43:46 volumioj2 volumio[861]: info: CorePlayQueue::getTrack 0 Jun 11 22:43:46 volumioj2 volumio[861]: info: CoreCommandRouter::volumioPushState Jun 11 22:43:46 volumioj2 volumio[861]: info: Setting Device type: Raspberry PI Jun 11 22:43:46 volumioj2 volumio[861]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Jun 11 22:43:46 volumioj2 volumio[861]: info: USB Boot Capable - System SBC Revision found in cpuinfo: b03115 Jun 11 22:43:46 volumioj2 volumio[861]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Jun 11 22:43:46 volumioj2 volumio[861]: info: Completed loading Core Plugins Jun 11 22:43:46 volumioj2 volumio[861]: info: Preparing to generate the ALSA configuration file Jun 11 22:43:46 volumioj2 volumio[861]: info: Discovery: adding d1b9031c-623a-451b-9370-2e98d309ee35 Jun 11 22:43:46 volumioj2 volumio[861]: info: Discovery: Found device Volumio_J2 Jun 11 22:43:46 volumioj2 volumio[861]: info: CoreCommandRouter::volumioGetState Jun 11 22:43:46 volumioj2 volumio[861]: info: CorePlayQueue::getTrack 0 Jun 11 22:43:46 volumioj2 volumio[861]: info: Discovery: adding fae895e8-cadb-4a46-8b73-3fc84cca7cd9 Jun 11 22:43:46 volumioj2 volumio[861]: info: Discovery: Found device Volumio_J1 Jun 11 22:43:46 volumioj2 volumio[861]: info: Discovery: Connecting to remote: 218.38.137.27 Jun 11 22:43:46 volumioj2 volumio[861]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jun 11 22:43:46 volumioj2 volumio[861]: info: Reading ALSA contributions from plugins. Jun 11 22:43:46 volumioj2 volumio[861]: info: Discovery: this is already registered, d1b9031c-623a-451b-9370-2e98d309ee35 Jun 11 22:43:46 volumioj2 volumio[861]: info: Discovery: Found device Volumio_J2 Jun 11 22:43:46 volumioj2 volumio[861]: info: CoreCommandRouter::volumioGetState Jun 11 22:43:46 volumioj2 volumio[861]: info: CorePlayQueue::getTrack 0 Jun 11 22:43:46 volumioj2 volumio[861]: info: Asound.conf file unchanged, so no further update is needed Jun 11 22:43:46 volumioj2 volumio[861]: info: Output device has changed, restarting MPD Jun 11 22:43:46 volumioj2 volumio[861]: info: Output device has changed, restarting Shairport Sync Jun 11 22:43:46 volumioj2 volumio[861]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 22:43:46 volumioj2 volumio[861]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 11 22:43:46 volumioj2 sudo[1092]: volumio : unable to resolve host volumioj2 Jun 11 22:43:46 volumioj2 sudo[1092]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Jun 11 22:43:46 volumioj2 sudo[1092]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 11 22:43:46 volumioj2 sudo[1092]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 22:43:46 volumioj2 sudo[1092]: pam_unix(sudo:session): session closed for user root Jun 11 22:43:46 volumioj2 sudo[1094]: volumio : unable to resolve host volumioj2 Jun 11 22:43:46 volumioj2 sudo[1094]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Jun 11 22:43:46 volumioj2 sudo[1094]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 11 22:43:46 volumioj2 sudo[1094]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 22:43:46 volumioj2 volumio[861]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 11 22:43:46 volumioj2 volumio[861]: info: ___________ START PLUGINS ___________ Jun 11 22:43:46 volumioj2 volumio[861]: info: ControllerMpd::onStart: Initializing MPD Jun 11 22:43:46 volumioj2 volumio[861]: info: Creating MPD Configuration file Jun 11 22:43:46 volumioj2 systemd[1]: Stopping Music Player Daemon... Jun 11 22:43:46 volumioj2 volumio[861]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 11 22:43:46 volumioj2 volumio[861]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 11 22:43:46 volumioj2 volumio[861]: info: [1749649426606] CoreMusicLibrary::Adding element 미디어 서버 Jun 11 22:43:46 volumioj2 volumio[861]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 11 22:43:46 volumioj2 sudo[1101]: volumio : unable to resolve host volumioj2 Jun 11 22:43:46 volumioj2 sudo[1101]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Jun 11 22:43:46 volumioj2 sudo[1101]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 11 22:43:46 volumioj2 sudo[1101]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 22:43:46 volumioj2 volumio[861]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 22:43:46 volumioj2 volumio[861]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 11 22:43:46 volumioj2 sudo[1103]: volumio : unable to resolve host volumioj2 Jun 11 22:43:46 volumioj2 sudo[1103]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Jun 11 22:43:46 volumioj2 sudo[1103]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 11 22:43:46 volumioj2 sudo[1101]: pam_unix(sudo:session): session closed for user root Jun 11 22:43:46 volumioj2 sudo[1103]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 22:43:46 volumioj2 volumio[861]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 11 22:43:46 volumioj2 volumio[861]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 22:43:46 volumioj2 volumio[861]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 11 22:43:46 volumioj2 systemd[1]: mpd.service: Succeeded. Jun 11 22:43:46 volumioj2 systemd[1]: Stopped Music Player Daemon. Jun 11 22:43:46 volumioj2 volumio[861]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 11 22:43:46 volumioj2 volumio[861]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 11 22:43:46 volumioj2 volumio[861]: info: [1749649426716] CoreMusicLibrary::Adding element Last_100 Jun 11 22:43:46 volumioj2 volumio[861]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 11 22:43:46 volumioj2 volumio[861]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 11 22:43:46 volumioj2 volumio[861]: info: [1749649426719] CoreMusicLibrary::Adding element Webradio Jun 11 22:43:46 volumioj2 volumio[861]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 11 22:43:46 volumioj2 volumio[861]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 11 22:43:46 volumioj2 volumio[861]: info: Initializing BBC Radios Jun 11 22:43:46 volumioj2 systemd[1]: Starting Music Player Daemon... Jun 11 22:43:46 volumioj2 volumio[861]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 11 22:43:46 volumioj2 volumio[861]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 11 22:43:46 volumioj2 volumio[861]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 11 22:43:46 volumioj2 volumio[861]: info: [1749649426790] CoreMusicLibrary::Adding element YouTube2 Jun 11 22:43:46 volumioj2 volumio[861]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 11 22:43:46 volumioj2 volumio[861]: Cannot find translation for source YouTube2 Jun 11 22:43:46 volumioj2 sudo[1110]: root : unable to resolve host volumioj2 Jun 11 22:43:46 volumioj2 sudo[1110]: root : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Jun 11 22:43:46 volumioj2 sudo[1110]: sudo: unable to resolve host volumioj2: System error Jun 11 22:43:46 volumioj2 sudo[1110]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 11 22:43:46 volumioj2 sudo[1110]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 22:43:46 volumioj2 volumio[861]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 11 22:43:46 volumioj2 volumio[861]: info: [1749649426827] CoreMusicLibrary::Adding element YouTube Music Jun 11 22:43:46 volumioj2 volumio[861]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 11 22:43:46 volumioj2 volumio[861]: Cannot find translation for source YouTube2 Jun 11 22:43:46 volumioj2 volumio[861]: Cannot find translation for source YouTube Music Jun 11 22:43:46 volumioj2 sudo[1110]: pam_unix(sudo:session): session closed for user root Jun 11 22:43:46 volumioj2 volumio[861]: info: Loading i18n strings for locale ko Jun 11 22:43:46 volumioj2 volumio[861]: error: minidlna: Fetching language file: Error: i18n file complementing the system language not found. Jun 11 22:43:46 volumioj2 volumio[861]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Jun 11 22:43:46 volumioj2 volumio[861]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 22:43:46 volumioj2 volumio[861]: info: FusionDsp - mixtype--------------------- None Jun 11 22:43:46 volumioj2 volumio[861]: info: Loading i18n strings for locale ko Jun 11 22:43:46 volumioj2 volumio[861]: error: minidlna: Fetching language file: Error: i18n file complementing the system language not found. Jun 11 22:43:46 volumioj2 volumio[861]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 11 22:43:46 volumioj2 volumio[861]: info: [1749649426941] CoreMusicLibrary::Adding element 개인 라디오 Jun 11 22:43:46 volumioj2 volumio[861]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 11 22:43:46 volumioj2 volumio[861]: Cannot find translation for source YouTube2 Jun 11 22:43:46 volumioj2 volumio[861]: Cannot find translation for source YouTube Music Jun 11 22:43:46 volumioj2 volumio[861]: Cannot find translation for source 개인 라디오 Jun 11 22:43:46 volumioj2 volumio[861]: info: Volumio Calling Home Jun 11 22:43:46 volumioj2 volumio[861]: info: Preparing to generate the ALSA configuration file Jun 11 22:43:47 volumioj2 volumio[861]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jun 11 22:43:47 volumioj2 volumio[861]: info: Reading ALSA contributions from plugins. Jun 11 22:43:47 volumioj2 volumio[861]: info: minidlna: Found miniDLNA version 1.2.1 Jun 11 22:43:47 volumioj2 volumio[861]: info: minidlna: Starting minidlna.service Jun 11 22:43:47 volumioj2 volumio[861]: info: MPD Permissions set Jun 11 22:43:47 volumioj2 volumio[861]: info: MPD Permissions set Jun 11 22:43:47 volumioj2 sudo[1131]: volumio : unable to resolve host volumioj2 Jun 11 22:43:47 volumioj2 sudo[1131]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Jun 11 22:43:47 volumioj2 sudo[1131]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start minidlna.service Jun 11 22:43:47 volumioj2 sudo[1131]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 22:43:47 volumioj2 volumio[861]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Jun 11 22:43:47 volumioj2 volumio[861]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 22:43:47 volumioj2 volumio[861]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 22:43:47 volumioj2 volumio[861]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 22:43:47 volumioj2 volumio[861]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 22:43:47 volumioj2 volumio[861]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 22:43:47 volumioj2 volumio[861]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 22:43:47 volumioj2 volumio[861]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 22:43:47 volumioj2 volumio[861]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 22:43:47 volumioj2 volumio[861]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 11 22:43:47 volumioj2 volumio[861]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 22:43:47 volumioj2 volumio[861]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 22:43:47 volumioj2 volumio[861]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 22:43:47 volumioj2 volumio[861]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 22:43:47 volumioj2 volumio[861]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 22:43:47 volumioj2 volumio[861]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 22:43:47 volumioj2 volumio[861]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 22:43:47 volumioj2 volumio[861]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 22:43:47 volumioj2 volumio[861]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 22:43:47 volumioj2 systemd[1]: Started MiniDLNA lightweight DLNA/UPnP-AV server. Jun 11 22:43:47 volumioj2 sudo[1131]: pam_unix(sudo:session): session closed for user root Jun 11 22:43:47 volumioj2 volumio[861]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 11 22:43:47 volumioj2 volumio[861]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 22:43:47 volumioj2 volumio[861]: info: minidlna: systemctl start minidlna.service succeeded. Jun 11 22:43:47 volumioj2 volumio[861]: info: Starting Shairport Sync Jun 11 22:43:47 volumioj2 volumio[861]: info: Starting Shairport Sync Jun 11 22:43:47 volumioj2 volumio[861]: info: Starting Shairport Sync Jun 11 22:43:47 volumioj2 sudo[1146]: volumio : unable to resolve host volumioj2 Jun 11 22:43:47 volumioj2 sudo[1146]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Jun 11 22:43:47 volumioj2 sudo[1146]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 11 22:43:47 volumioj2 sudo[1146]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 22:43:47 volumioj2 sudo[1148]: volumio : unable to resolve host volumioj2 Jun 11 22:43:47 volumioj2 sudo[1148]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Jun 11 22:43:47 volumioj2 volumio[861]: info: CoreCommandRouter::volumioGetState Jun 11 22:43:47 volumioj2 volumio[861]: info: CorePlayQueue::getTrack 0 Jun 11 22:43:47 volumioj2 sudo[1148]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 11 22:43:47 volumioj2 sudo[1148]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 22:43:47 volumioj2 sudo[1150]: volumio : unable to resolve host volumioj2 Jun 11 22:43:47 volumioj2 sudo[1150]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Jun 11 22:43:47 volumioj2 sudo[1150]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 11 22:43:47 volumioj2 sudo[1150]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 22:43:47 volumioj2 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 11 22:43:47 volumioj2 systemd[1]: shairport-sync.service: Succeeded. Jun 11 22:43:47 volumioj2 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 11 22:43:47 volumioj2 volumio[861]: info: Asound.conf file unchanged, so no further update is needed Jun 11 22:43:47 volumioj2 volumio[861]: info: Output device has changed, restarting MPD Jun 11 22:43:47 volumioj2 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 11 22:43:47 volumioj2 sudo[1148]: pam_unix(sudo:session): session closed for user root Jun 11 22:43:47 volumioj2 sudo[1146]: pam_unix(sudo:session): session closed for user root Jun 11 22:43:47 volumioj2 sudo[1156]: volumio : unable to resolve host volumioj2 Jun 11 22:43:47 volumioj2 sudo[1156]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Jun 11 22:43:47 volumioj2 volumio[861]: info: Output device has changed, restarting Shairport Sync Jun 11 22:43:47 volumioj2 volumio[861]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 22:43:47 volumioj2 volumio[861]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 11 22:43:47 volumioj2 sudo[1150]: pam_unix(sudo:session): session closed for user root Jun 11 22:43:47 volumioj2 sudo[1156]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 11 22:43:47 volumioj2 sudo[1156]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 22:43:47 volumioj2 sudo[1160]: volumio : unable to resolve host volumioj2 Jun 11 22:43:47 volumioj2 sudo[1160]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Jun 11 22:43:47 volumioj2 sudo[1160]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 11 22:43:47 volumioj2 sudo[1160]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 22:43:47 volumioj2 sudo[1156]: pam_unix(sudo:session): session closed for user root Jun 11 22:43:47 volumioj2 volumio[861]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 11 22:43:47 volumioj2 systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Jun 11 22:43:47 volumioj2 systemd[1]: mpd.service: Succeeded. Jun 11 22:43:47 volumioj2 systemd[1]: Stopped Music Player Daemon. Jun 11 22:43:47 volumioj2 systemd[1]: Starting Music Player Daemon... Jun 11 22:43:47 volumioj2 volumio[861]: info: MPD Permissions set Jun 11 22:43:47 volumioj2 volumio[861]: info: Volumio called home Jun 11 22:43:47 volumioj2 volumio[861]: info: Shairport-Sync Started Jun 11 22:43:47 volumioj2 volumio[861]: Error adding Membership: Error: addMembership EINVAL Jun 11 22:43:47 volumioj2 volumio[861]: info: Shairport-Sync Started Jun 11 22:43:47 volumioj2 volumio[861]: info: Shairport-Sync Started Jun 11 22:43:47 volumioj2 volumio[861]: (node:861) [DEP0005] DeprecationWarning: Buffer() is deprecated due to security and usability issues. Please use the Buffer.alloc(), Buffer.allocUnsafe(), or Buffer.from() methods instead. Jun 11 22:43:47 volumioj2 volumio[861]: (Use `node --trace-deprecation ...` to show where the warning was created) Jun 11 22:43:47 volumioj2 volumio[861]: (node:861) [DEP0106] DeprecationWarning: crypto.createDecipher is deprecated. Jun 11 22:43:47 volumioj2 volumio[861]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 22:43:47 volumioj2 volumio[861]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 22:43:47 volumioj2 volumio[861]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 22:43:47 volumioj2 volumio[861]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 22:43:47 volumioj2 volumio[861]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 22:43:47 volumioj2 volumio[861]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 22:43:47 volumioj2 volumio[861]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 22:43:47 volumioj2 volumio[861]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 22:43:47 volumioj2 volumio[861]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 11 22:43:47 volumioj2 volumio[861]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 22:43:47 volumioj2 sudo[1168]: root : unable to resolve host volumioj2 Jun 11 22:43:47 volumioj2 sudo[1168]: root : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Jun 11 22:43:47 volumioj2 sudo[1168]: sudo: unable to resolve host volumioj2: System error Jun 11 22:43:47 volumioj2 sudo[1168]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 11 22:43:47 volumioj2 sudo[1168]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 22:43:47 volumioj2 sudo[1168]: pam_unix(sudo:session): session closed for user root Jun 11 22:43:47 volumioj2 volumio[861]: info: Starting Shairport Sync Jun 11 22:43:47 volumioj2 sudo[1176]: volumio : unable to resolve host volumioj2 Jun 11 22:43:47 volumioj2 sudo[1176]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Jun 11 22:43:47 volumioj2 sudo[1176]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 11 22:43:47 volumioj2 sudo[1176]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 22:43:47 volumioj2 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 11 22:43:47 volumioj2 systemd[1]: shairport-sync.service: Succeeded. Jun 11 22:43:47 volumioj2 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 11 22:43:47 volumioj2 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 11 22:43:47 volumioj2 sudo[1176]: pam_unix(sudo:session): session closed for user root Jun 11 22:43:47 volumioj2 volumio[861]: info: Shairport-Sync Started Jun 11 22:43:48 volumioj2 mpd[1174]: Jun 11 22:43 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 11 22:43:48 volumioj2 systemd[1]: Started Music Player Daemon. Jun 11 22:43:48 volumioj2 sudo[1094]: pam_unix(sudo:session): session closed for user root Jun 11 22:43:48 volumioj2 sudo[1103]: pam_unix(sudo:session): session closed for user root Jun 11 22:43:48 volumioj2 sudo[1160]: pam_unix(sudo:session): session closed for user root Jun 11 22:43:48 volumioj2 volumio[861]: error: MPD error: The expression evaluated to a falsy value: Jun 11 22:43:48 volumioj2 volumio[861]: assert.ok(self.idling) Jun 11 22:43:48 volumioj2 volumio[861]: error: The expression evaluated to a falsy value: Jun 11 22:43:48 volumioj2 volumio[861]: assert.ok(self.idling) Jun 11 22:43:48 volumioj2 volumio[861]: error: MPD error: The expression evaluated to a falsy value: Jun 11 22:43:48 volumioj2 volumio[861]: assert.ok(self.idling) Jun 11 22:43:48 volumioj2 volumio[861]: error: The expression evaluated to a falsy value: Jun 11 22:43:48 volumioj2 volumio[861]: assert.ok(self.idling) Jun 11 22:43:48 volumioj2 volumio[861]: info: MPD running with PID1174 Jun 11 22:43:48 volumioj2 volumio[861]: ,establishing connection Jun 11 22:43:48 volumioj2 volumio[861]: error: updateQueue error: null Jun 11 22:43:48 volumioj2 volumio[861]: error: updateQueue error: null Jun 11 22:43:48 volumioj2 volumio[861]: info: [yt-cast-receiver] DIAL server listening on port 8098 Jun 11 22:43:48 volumioj2 volumio[861]: info: CoreCommandRouter::volumioRetrievevolume Jun 11 22:43:48 volumioj2 volumio[861]: info: CoreCommandRouter::volumioGetState Jun 11 22:43:48 volumioj2 volumio[861]: info: CorePlayQueue::getTrack 0 Jun 11 22:43:48 volumioj2 volumio[861]: info: CoreStateMachine::pushState Jun 11 22:43:48 volumioj2 volumio[861]: info: CorePlayQueue::getTrack 0 Jun 11 22:43:48 volumioj2 volumio[861]: info: CoreCommandRouter::volumioPushState Jun 11 22:43:48 volumioj2 volumio[861]: error: [ytcr] VolumeControl failed to obtain volume from Volumio: Jun 11 22:43:48 volumioj2 volumio[861]: (TypeError) Cannot read property 'vol' of undefined Jun 11 22:43:48 volumioj2 volumio[861]: TypeError: Cannot read property 'vol' of undefined Jun 11 22:43:48 volumioj2 volumio[861]: at VolumeControl.getVolume (/data/plugins/music_service/ytcr/dist/lib/VolumeControl.js:56:42) Jun 11 22:43:48 volumioj2 volumio[861]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Jun 11 22:43:48 volumioj2 volumio[861]: at async VolumeControl.init (/data/plugins/music_service/ytcr/dist/lib/VolumeControl.js:28:68) Jun 11 22:43:48 volumioj2 volumio[861]: at async /data/plugins/music_service/ytcr/dist/index.js:312:13 Jun 11 22:43:48 volumioj2 volumio[861]: info: Completed starting Core Plugins Jun 11 22:43:48 volumioj2 volumio[861]: info: ------------------------------------------- Jun 11 22:43:48 volumioj2 volumio[861]: info: ----- MyVolumio plugins startup ---- Jun 11 22:43:48 volumioj2 volumio[861]: info: ------------------------------------------- Jun 11 22:43:48 volumioj2 volumio[861]: info: [MyVolumio PluginManager] Fetching plans data.... Jun 11 22:43:48 volumioj2 volumio[861]: info: camilladsp service started and running in background, instance 1 Jun 11 22:43:48 volumioj2 volumio[861]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 22:43:48 volumioj2 volumio[861]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Jun 11 22:43:48 volumioj2 volumio[861]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Jun 11 22:43:48 volumioj2 volumio[861]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Jun 11 22:43:48 volumioj2 volumio[861]: info: FusionDsp loaded Jun 11 22:43:48 volumioj2 volumio[861]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 11 22:43:48 volumioj2 sudo[1196]: volumio : unable to resolve host volumioj2 Jun 11 22:43:48 volumioj2 sudo[1196]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Jun 11 22:43:48 volumioj2 sudo[1196]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Jun 11 22:43:48 volumioj2 sudo[1196]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 22:43:48 volumioj2 volumio[861]: info: FusionDsp - Reporting Fusion DSP Enabled Jun 11 22:43:48 volumioj2 volumio[861]: info: Adding Signal Path Element [object Object] Jun 11 22:43:48 volumioj2 volumio[861]: info: Adding fusiondspeq DSP Signal Path Element Jun 11 22:43:48 volumioj2 volumio[861]: info: FusionDsp - ---- installed callbackRead Jun 11 22:43:48 volumioj2 volumio[861]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 11 22:43:48 volumioj2 volumio[861]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Jun 11 22:43:48 volumioj2 volumio[861]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Jun 11 22:43:48 volumioj2 volumio[861]: at onErrorNT (internal/child_process.js:465:16) Jun 11 22:43:48 volumioj2 volumio[861]: at processTicksAndRejections (internal/process/task_queues.js:80:21) { Jun 11 22:43:48 volumioj2 volumio[861]: errno: -2, Jun 11 22:43:48 volumioj2 volumio[861]: code: 'ENOENT', Jun 11 22:43:48 volumioj2 volumio[861]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Jun 11 22:43:48 volumioj2 volumio[861]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Jun 11 22:43:48 volumioj2 volumio[861]: spawnargs: [ Jun 11 22:43:48 volumioj2 volumio[861]: '-p', Jun 11 22:43:48 volumioj2 volumio[861]: 9876, Jun 11 22:43:48 volumioj2 volumio[861]: '-o', Jun 11 22:43:48 volumioj2 volumio[861]: '/tmp/camilladsp.log', Jun 11 22:43:48 volumioj2 volumio[861]: '-l', Jun 11 22:43:48 volumioj2 volumio[861]: 'warn', Jun 11 22:43:48 volumioj2 volumio[861]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Jun 11 22:43:48 volumioj2 volumio[861]: ] Jun 11 22:43:48 volumioj2 volumio[861]: } Jun 11 22:43:48 volumioj2 volumio[861]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 11 22:43:49 volumioj2 systemd[1]: Started FusionDsp Daemon. Jun 11 22:43:49 volumioj2 sudo[1196]: pam_unix(sudo:session): session closed for user root Jun 11 22:43:49 volumioj2 sudo[1213]: volumio : unable to resolve host volumioj2 Jun 11 22:43:49 volumioj2 sudo[1213]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Jun 11 22:43:49 volumioj2 sudo[1213]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-11 22:42 Jun 11 22:43:49 volumioj2 sudo[1213]: 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"