-- Logs begin at Sun 2020-08-09 05:44:21 UTC, end at Sun 2025-02-16 10:14:08 UTC. -- Feb 16 10:13:02 volumio kernel: Under-voltage detected! (0x00050005) Feb 16 10:13:06 volumio kernel: Voltage normalised (0x00000000) Feb 16 10:13:08 volumio volumio[4891]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%3F%3F%3F/daeb1cf1-e0a6-431a-ad41-3c6df993436f.jpg' Feb 16 10:13:09 volumio volumio[4891]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%3F%3F%3F/daeb1cf1-e0a6-431a-ad41-3c6df993436f.jpg' Feb 16 10:13:09 volumio volumio[4891]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%3F%3F%3F/%3F%3F%3F/4f1fd030-0d37-40c3-8231-5cfbc9a9022b.png' Feb 16 10:13:09 volumio volumio[4891]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%3F%3F%3F/daeb1cf1-e0a6-431a-ad41-3c6df993436f.jpg' Feb 16 10:13:11 volumio volumio[4891]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E8%97%A4%E7%94%B0%E9%BA%BB%E8%A1%A3%E5%AD%90/LOVE%20STORY%20BEST%20~%E7%B7%8B%E8%89%B2%E3%81%AE%E6%AC%A0%E7%89%87~/d5e964cc-771d-4a3e-9170-4cd2f2679d05.jpg' Feb 16 10:13:19 volumio volumio[4891]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 16 10:13:19 volumio volumio[4891]: { Error: connect ETIMEDOUT 162.125.2.5:80 Feb 16 10:13:19 volumio volumio[4891]: at Object._errnoException (util.js:1022:11) Feb 16 10:13:19 volumio volumio[4891]: at _exceptionWithHostPort (util.js:1044:20) Feb 16 10:13:19 volumio volumio[4891]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1198:14) Feb 16 10:13:19 volumio volumio[4891]: code: 'ETIMEDOUT', Feb 16 10:13:19 volumio volumio[4891]: errno: 'ETIMEDOUT', Feb 16 10:13:19 volumio volumio[4891]: syscall: 'connect', Feb 16 10:13:19 volumio volumio[4891]: address: '162.125.2.5', Feb 16 10:13:19 volumio volumio[4891]: port: 80 } Feb 16 10:13:19 volumio volumio[4891]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 16 10:13:20 volumio sudo[6174]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-02-16 10:12 Feb 16 10:13:20 volumio sudo[6174]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 10:13:20 volumio sudo[6174]: pam_unix(sudo:session): session closed for user root Feb 16 10:13:20 volumio volumio-remote-updater[583]: [2025-02-16 10:13:20] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Feb 16 10:13:20 volumio volumio-remote-updater[583]: [2025-02-16 10:13:20] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Feb 16 10:13:20 volumio systemd[1]: volumio.service: main process exited, code=exited, status=1/FAILURE Feb 16 10:13:20 volumio systemd[1]: Unit volumio.service entered failed state. Feb 16 10:13:21 volumio systemd[1]: Starting dynamicswap service... Feb 16 10:13:21 volumio systemd[1]: Started dynamicswap service. Feb 16 10:13:21 volumio systemd[1]: volumio.service holdoff time over, scheduling restart. Feb 16 10:13:21 volumio systemd[1]: Starting dynamicswap service... Feb 16 10:13:21 volumio systemd[1]: Started dynamicswap service. Feb 16 10:13:21 volumio systemd[1]: Stopping Volumio Backend Module... Feb 16 10:13:21 volumio systemd[1]: Starting Volumio Backend Module... Feb 16 10:13:21 volumio systemd[1]: Started Volumio Backend Module. Feb 16 10:13:23 volumio volumio[6195]: info: ------------------------------------------- Feb 16 10:13:23 volumio volumio[6195]: info: ----- Volumio2 ---- Feb 16 10:13:23 volumio volumio[6195]: info: ------------------------------------------- Feb 16 10:13:23 volumio volumio[6195]: info: ----- System startup ---- Feb 16 10:13:23 volumio volumio[6195]: info: ------------------------------------------- Feb 16 10:13:23 volumio volumio[6195]: info: MYVOLUMIO Environment detected Feb 16 10:13:24 volumio volumio[6195]: info: Plugin folders cleanup Feb 16 10:13:24 volumio volumio[6195]: info: Scanning into folder /volumio/app/plugins/ Feb 16 10:13:24 volumio volumio[6195]: info: Scanning category audio_interface Feb 16 10:13:24 volumio volumio[6195]: info: Scanning category miscellanea Feb 16 10:13:24 volumio volumio[6195]: info: Scanning category music_service Feb 16 10:13:24 volumio volumio[6195]: info: Scanning category plugins.json Feb 16 10:13:24 volumio volumio[6195]: info: Scanning category system_controller Feb 16 10:13:24 volumio volumio[6195]: info: Scanning category user_interface Feb 16 10:13:24 volumio volumio[6195]: info: Scanning into folder /data/plugins/ Feb 16 10:13:24 volumio volumio[6195]: info: Plugin folders cleanup completed Feb 16 10:13:24 volumio volumio[6195]: info: ------------------------------------------- Feb 16 10:13:24 volumio volumio[6195]: info: ----- Core plugins startup ---- Feb 16 10:13:24 volumio volumio[6195]: info: ------------------------------------------- Feb 16 10:13:24 volumio volumio[6195]: info: Loading plugins from folder /volumio/app/plugins/ Feb 16 10:13:24 volumio volumio[6195]: info: Adding plugin upnp to MyMusic Plugins Feb 16 10:13:24 volumio volumio[6195]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 16 10:13:24 volumio volumio[6195]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 16 10:13:24 volumio volumio[6195]: info: Loading plugins from folder /data/plugins/ Feb 16 10:13:24 volumio volumio[6195]: info: Loading plugin "system"... Feb 16 10:13:24 volumio volumio[6195]: info: Loading plugin "appearance"... Feb 16 10:13:25 volumio volumio[6195]: info: Loading plugin "network"... Feb 16 10:13:25 volumio volumio-remote-updater[583]: [2025-02-16 10:13:25] [connect] Successful connection Feb 16 10:13:25 volumio volumio[6195]: info: Refreshing Cached IP Addresses Feb 16 10:13:26 volumio sudo[6221]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 16 10:13:26 volumio volumio[6195]: info: Loading plugin "services"... Feb 16 10:13:26 volumio volumio[6195]: info: Loading plugin "alsa_controller"... Feb 16 10:13:26 volumio sudo[6221]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 10:13:26 volumio sudo[6224]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 16 10:13:26 volumio sudo[6221]: pam_unix(sudo:session): session closed for user root Feb 16 10:13:26 volumio sudo[6224]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 10:13:26 volumio sudo[6224]: pam_unix(sudo:session): session closed for user root Feb 16 10:13:26 volumio volumio[6195]: info: Loading plugin "wizard"... Feb 16 10:13:26 volumio volumio[6195]: info: Loading plugin "volumio_command_line_client"... Feb 16 10:13:26 volumio volumio[6195]: info: Loading plugin "upnp"... Feb 16 10:13:26 volumio volumio[6195]: info: [1739700806284] Starting Upmpd Daemon Feb 16 10:13:26 volumio volumio[6195]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 16 10:13:26 volumio volumio[6195]: info: Loading plugin "my_music"... Feb 16 10:13:26 volumio volumio[6195]: info: Loading plugin "mpd"... Feb 16 10:13:26 volumio volumio[6195]: info: Loading plugin "upnp_browser"... Feb 16 10:13:27 volumio volumio[6195]: info: Loading plugin "networkfs"... Feb 16 10:13:27 volumio volumio[6195]: info: Cannot mount NAS MY-Music at system boot, trial number 1 ,retrying in 5 seconds Feb 16 10:13:27 volumio volumio[6195]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 16 10:13:27 volumio volumio[6195]: info: Loading plugin "alarm-clock"... Feb 16 10:13:27 volumio volumio[6195]: info: Loading plugin "airplay_emulation"... Feb 16 10:13:27 volumio volumio[6195]: info: Starting Shairport Sync Feb 16 10:13:27 volumio volumio[6195]: info: Loading plugin "last_100"... Feb 16 10:13:27 volumio volumio[6195]: info: Loading plugin "webradio"... Feb 16 10:13:27 volumio volumio[6195]: info: Loading plugin "i2s_dacs"... Feb 16 10:13:27 volumio volumio[6195]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 16 10:13:27 volumio volumio[6195]: info: Loading plugin "volumiodiscovery"... Feb 16 10:13:27 volumio volumio[6195]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 16 10:13:27 volumio node[6195]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 16 10:13:27 volumio node[6195]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 16 10:13:27 volumio node[6195]: *** WARNING *** For more information see Feb 16 10:13:27 volumio node[6195]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 16 10:13:27 volumio node[6195]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 16 10:13:27 volumio node[6195]: *** WARNING *** For more information see Feb 16 10:13:27 volumio volumio[6195]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 16 10:13:27 volumio volumio[6195]: *** WARNING *** For more information see Feb 16 10:13:27 volumio volumio[6195]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 16 10:13:27 volumio volumio[6195]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 16 10:13:27 volumio volumio[6195]: *** WARNING *** For more information see Feb 16 10:13:27 volumio volumio[6195]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 16 10:13:27 volumio volumio[6195]: Discovery: StartAdv! undefined Feb 16 10:13:27 volumio volumio[6195]: Discovery: Started advertising... Volumio - undefined Feb 16 10:13:27 volumio volumio[6195]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 16 10:13:27 volumio volumio[6195]: info: Loading plugin "outputs"... Feb 16 10:13:27 volumio volumio[6195]: info: Loading plugin "albumart"... Feb 16 10:13:28 volumio volumio[6195]: info: Plugin example_plugin is not enabled Feb 16 10:13:28 volumio volumio[6195]: info: Loading plugin "inputs"... Feb 16 10:13:28 volumio volumio[6195]: info: Loading plugin "updater_comm"... Feb 16 10:13:28 volumio volumio[6195]: info: Plugin mpdemulation is not enabled Feb 16 10:13:28 volumio volumio[6195]: info: Loading plugin "rest_api"... Feb 16 10:13:28 volumio volumio[6195]: info: Loading plugin "websocket"... Feb 16 10:13:28 volumio volumio[6195]: info: ___________ START PLUGINS ___________ Feb 16 10:13:28 volumio volumio[6195]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 16 10:13:28 volumio volumio[6195]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 16 10:13:28 volumio volumio[6195]: info: [1739700808335] CoreMusicLibrary::Adding element Media Servers Feb 16 10:13:28 volumio volumio[6195]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 16 10:13:28 volumio volumio[6195]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 16 10:13:28 volumio volumio[6195]: Forking 3 albumart workers Feb 16 10:13:28 volumio volumio[6195]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 16 10:13:28 volumio volumio[6195]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 16 10:13:28 volumio volumio[6195]: info: [1739700808512] CoreMusicLibrary::Adding element Last_100 Feb 16 10:13:28 volumio volumio[6195]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 16 10:13:28 volumio volumio[6195]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 16 10:13:28 volumio volumio[6195]: info: [1739700808519] CoreMusicLibrary::Adding element Webradio Feb 16 10:13:28 volumio volumio[6195]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 16 10:13:28 volumio volumio[6195]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 16 10:13:28 volumio volumio[6195]: info: ------------------------------------------- Feb 16 10:13:28 volumio volumio[6195]: info: ----- MyVolumio plugins startup ---- Feb 16 10:13:28 volumio volumio[6195]: info: ------------------------------------------- Feb 16 10:13:28 volumio volumio[6195]: info: [MyVolumio PluginManager] Fetching plans data.... Feb 16 10:13:28 volumio volumio[6195]: info: Loading i18n strings for locale zh Feb 16 10:13:28 volumio volumio[6195]: Updating browse sources language Feb 16 10:13:28 volumio volumio[6195]: Cannot find translation for sourceMedia Servers Feb 16 10:13:28 volumio volumio[6195]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 16 10:13:28 volumio volumio[6195]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 16 10:13:28 volumio volumio[6195]: info: CoreCommandRouter::initPlayerControls Feb 16 10:13:28 volumio volumio[6195]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 10:13:28 volumio volumio[6195]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAlsaCards Feb 16 10:13:28 volumio volumio[6195]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 10:13:28 volumio volumio[6195]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 10:13:28 volumio volumio[6195]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 10:13:28 volumio volumio[6195]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 10:13:28 volumio volumio[6195]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 10:13:28 volumio volumio[6195]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 16 10:13:28 volumio volumio[6195]: info: BOOT COMPLETED Feb 16 10:13:28 volumio volumio[6195]: [Metrics] CommandRouter: 5s 79.42ms Feb 16 10:13:28 volumio volumio[6195]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 16 10:13:29 volumio volumio[6195]: Starting albumart workers Feb 16 10:13:29 volumio volumio[6195]: Starting albumart workers Feb 16 10:13:29 volumio volumio[6195]: Starting albumart workers Feb 16 10:13:34 volumio volumio[6195]: Playing WAVE '/volumio/app/startup.wav' : Signed 16 bit Little Endian, Rate 44100 Hz, Stereo Feb 16 10:13:34 volumio volumio[6195]: info: CoreCommandRouter::Close All Modals sent Feb 16 10:13:34 volumio volumio[6195]: info: CoreCommandRouter::Close All Modals sent Feb 16 10:13:34 volumio volumio[6195]: Express server listening on port 3000 Feb 16 10:13:34 volumio volumio[6195]: [Metrics] WebUI: 11s 162.35ms Feb 16 10:13:34 volumio volumio[6195]: Volumio Calling Home Feb 16 10:13:34 volumio volumio[6195]: info: CoreStateMachine::resetVolumioState Feb 16 10:13:34 volumio volumio[6195]: info: CoreStateMachine::getcurrentVolume Feb 16 10:13:34 volumio volumio[6195]: info: CoreCommandRouter::volumioRetrievevolume Feb 16 10:13:34 volumio volumio[6195]: info: Cannot mount NAS MY-Music at system boot, trial number 2 ,retrying in 5 seconds Feb 16 10:13:34 volumio volumio[6195]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 16 10:13:34 volumio volumio[6195]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 10:13:34 volumio volumio[6195]: info: I2S DAC Found on wrong device number, changing it to device 2 Feb 16 10:13:34 volumio volumio[6195]: info: Output device has changed, restarting MPD Feb 16 10:13:34 volumio volumio[6195]: info: Output device has changed, restarting Shairport Sync Feb 16 10:13:34 volumio sudo[6319]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 16 10:13:34 volumio sudo[6319]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 10:13:34 volumio sudo[6319]: pam_unix(sudo:session): session closed for user root Feb 16 10:13:34 volumio sudo[6321]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 16 10:13:34 volumio sudo[6321]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 10:13:34 volumio volumio[6195]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 16 10:13:34 volumio systemd[1]: Stopping Music Player Daemon... Feb 16 10:13:34 volumio volumio[6195]: info: MPD Permissions set Feb 16 10:13:34 volumio volumio[6195]: info: Setting Device type: Raspberry PI Feb 16 10:13:34 volumio volumio[6195]: info: MPD running with PID5950 Feb 16 10:13:34 volumio volumio[6195]: ,establishing connection Feb 16 10:13:34 volumio systemd[1]: Starting Music Player Daemon... Feb 16 10:13:34 volumio volumio[6195]: info: Feb 16 10:13:34 volumio volumio[6195]: ---------------------------- Client requests Volume 100 Feb 16 10:13:34 volumio volumio[6195]: info: VolumeController::SetAlsaVolume100 Feb 16 10:13:34 volumio volumio[6195]: info: CoreStateMachine::pushState Feb 16 10:13:34 volumio volumio[6195]: info: CorePlayQueue::getTrack 0 Feb 16 10:13:34 volumio volumio[6195]: info: CoreCommandRouter::volumioPushState Feb 16 10:13:34 volumio volumio[6195]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 16 10:13:35 volumio volumio[6195]: info: VolumeController:: Volume=100 Mute =false Feb 16 10:13:35 volumio volumio[6195]: info: CoreStateMachine::pushState Feb 16 10:13:35 volumio volumio[6195]: info: CorePlayQueue::getTrack 0 Feb 16 10:13:35 volumio volumio[6195]: info: CoreCommandRouter::volumioPushState Feb 16 10:13:35 volumio volumio[6195]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 16 10:13:35 volumio volumio[6195]: info: CoreStateMachine::updateTrackBlock Feb 16 10:13:35 volumio volumio[6195]: info: CorePlayQueue::getTrackBlock Feb 16 10:13:35 volumio volumio[6195]: info: CoreCommandRouter::volumioRetrievevolume Feb 16 10:13:35 volumio volumio[6195]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Feb 16 10:13:35 volumio volumio[6195]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 16 10:13:35 volumio volumio[6195]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Feb 16 10:13:35 volumio volumio[6195]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 10:13:35 volumio volumio[6195]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 10:13:35 volumio volumio[6195]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 10:13:35 volumio volumio[6195]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 10:13:35 volumio volumio[6195]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 10:13:35 volumio volumio[6195]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 10:13:35 volumio volumio[6195]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 10:13:35 volumio volumio[6195]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 16 10:13:35 volumio volumio[6195]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 10:13:35 volumio volumio[6195]: info: Reloading queue from file Feb 16 10:13:35 volumio systemd[1]: Started Music Player Daemon. Feb 16 10:13:35 volumio volumio[6195]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E8%AE%B8%E5%B5%A9/%E5%AF%BB%E9%9B%BE%E5%90%AF%E7%A4%BA/821a70f1-e4be-4b33-983a-007bd4263bfe.png' Feb 16 10:13:35 volumio volumio[6195]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%3F%3F%3F/daeb1cf1-e0a6-431a-ad41-3c6df993436f.jpg' Feb 16 10:13:35 volumio sudo[6321]: pam_unix(sudo:session): session closed for user root Feb 16 10:13:35 volumio volumio[6195]: info: Setting volume on startup at 100 Feb 16 10:13:35 volumio volumio[6195]: info: CoreStateMachine::setRepeat false single undefined Feb 16 10:13:35 volumio volumio[6195]: info: CoreStateMachine::pushState Feb 16 10:13:35 volumio volumio[6195]: info: CorePlayQueue::getTrack 0 Feb 16 10:13:35 volumio volumio[6195]: info: CoreCommandRouter::volumioPushState Feb 16 10:13:35 volumio volumio[6195]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 16 10:13:35 volumio volumio[6195]: info: CoreStateMachine::setRandom true Feb 16 10:13:35 volumio volumio[6195]: info: CoreStateMachine::pushState Feb 16 10:13:35 volumio volumio[6195]: info: CorePlayQueue::getTrack 0 Feb 16 10:13:35 volumio volumio[6195]: info: CoreCommandRouter::volumioPushState Feb 16 10:13:35 volumio volumio[6195]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 16 10:13:35 volumio volumio[6195]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setDefaultMixer Feb 16 10:13:35 volumio volumio[6195]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 16 10:13:35 volumio volumio[6195]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SMixer Feb 16 10:13:35 volumio volumio[6195]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 16 10:13:35 volumio volumio[6195]: info: Device 2 does not have any Mixer Control Available, setting a softvol device Feb 16 10:13:35 volumio volumio[6195]: info: CoreCommandRouter::volumioUpdateVolumeSettings Feb 16 10:13:35 volumio volumio[6195]: info: Updating Volume Controller Parameters: Device: 2 Name: Hifiberry DAC Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 10 Feb 16 10:13:35 volumio volumio[6195]: info: CoreStateMachine::pushState Feb 16 10:13:35 volumio volumio[6195]: info: CorePlayQueue::getTrack 0 Feb 16 10:13:35 volumio volumio[6195]: info: CoreCommandRouter::volumioPushState Feb 16 10:13:35 volumio volumio[6195]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 16 10:13:35 volumio volumio[6195]: error: updateQueue error: null Feb 16 10:13:35 volumio volumio[6195]: error: Failed LSINFO: Error: [50@0] {} No such directory Feb 16 10:13:35 volumio volumio[6195]: info: VolumeController:: Volume=100 Mute =false Feb 16 10:13:35 volumio volumio[6195]: info: CoreStateMachine::pushState Feb 16 10:13:35 volumio volumio[6195]: info: CorePlayQueue::getTrack 0 Feb 16 10:13:35 volumio volumio[6195]: info: CoreCommandRouter::volumioPushState Feb 16 10:13:35 volumio volumio[6195]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 16 10:13:35 volumio volumio[6195]: info: Starting Shairport Sync Feb 16 10:13:35 volumio volumio[6195]: info: Starting Shairport Sync Feb 16 10:13:35 volumio volumio[6195]: info: Starting Shairport Sync Feb 16 10:13:35 volumio sudo[6363]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 16 10:13:35 volumio sudo[6363]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 10:13:35 volumio sudo[6365]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 16 10:13:35 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Feb 16 10:13:36 volumio sudo[6365]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 10:13:36 volumio sudo[6371]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 16 10:13:36 volumio systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... Feb 16 10:13:36 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Feb 16 10:13:36 volumio sudo[6363]: pam_unix(sudo:session): session closed for user root Feb 16 10:13:36 volumio sudo[6371]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 10:13:36 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Feb 16 10:13:36 volumio systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... Feb 16 10:13:36 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Feb 16 10:13:36 volumio sudo[6365]: pam_unix(sudo:session): session closed for user root Feb 16 10:13:36 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Feb 16 10:13:36 volumio volumio[6195]: Discovery: adding c5f8b7d3-a085-448e-a003-1f587cbfbd9e Feb 16 10:13:36 volumio volumio[6195]: info: mDNS: Found device Volumio Feb 16 10:13:36 volumio volumio[6195]: info: CoreCommandRouter::volumioGetState Feb 16 10:13:36 volumio volumio[6195]: info: CorePlayQueue::getTrack 0 Feb 16 10:13:36 volumio systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... Feb 16 10:13:36 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Feb 16 10:13:36 volumio volumio[6195]: error: updateQueue error: null Feb 16 10:13:36 volumio sudo[6371]: pam_unix(sudo:session): session closed for user root Feb 16 10:13:36 volumio volumio[6195]: info: Shairport-Sync Started Feb 16 10:13:36 volumio volumio[6195]: Error adding Membership: Error: addMembership EINVAL Feb 16 10:13:36 volumio volumio[6195]: Discovery: this is already registered, c5f8b7d3-a085-448e-a003-1f587cbfbd9e Feb 16 10:13:36 volumio volumio[6195]: info: mDNS: Found device Volumio Feb 16 10:13:36 volumio volumio[6195]: info: CoreCommandRouter::volumioGetState Feb 16 10:13:36 volumio volumio[6195]: info: CorePlayQueue::getTrack 0 Feb 16 10:13:36 volumio volumio[6195]: info: Shairport-Sync Started Feb 16 10:13:36 volumio volumio[6195]: info: Shairport-Sync Started Feb 16 10:13:36 volumio sudo[6405]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 16 10:13:36 volumio sudo[6405]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 10:13:36 volumio sudo[6407]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 16 10:13:36 volumio sudo[6405]: pam_unix(sudo:session): session closed for user root Feb 16 10:13:36 volumio sudo[6407]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 10:13:36 volumio sudo[6407]: pam_unix(sudo:session): session closed for user root Feb 16 10:13:36 volumio volumio[6195]: info: CoreCommandRouter::volumioGetState Feb 16 10:13:36 volumio volumio[6195]: info: CorePlayQueue::getTrack 0 Feb 16 10:13:36 volumio sudo[6423]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Feb 16 10:13:36 volumio sudo[6423]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 10:13:36 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Feb 16 10:13:36 volumio sudo[6423]: pam_unix(sudo:session): session closed for user root Feb 16 10:13:36 volumio volumio[6195]: info: Upmpdcli Daemon Started Feb 16 10:13:36 volumio volumio[6195]: info: CoreCommandRouter::volumioGetVisibleSources Feb 16 10:13:36 volumio volumio[6195]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 16 10:13:36 volumio volumio[6195]: info: CoreCommandRouter::volumioGetState Feb 16 10:13:36 volumio volumio[6195]: info: CorePlayQueue::getTrack 0 Feb 16 10:13:36 volumio volumio[6195]: info: CoreCommandRouter::volumioGetQueue Feb 16 10:13:36 volumio volumio[6195]: info: CoreStateMachine::getQueue Feb 16 10:13:36 volumio volumio[6195]: info: CorePlayQueue::getQueue Feb 16 10:13:37 volumio volumio[6195]: info: Listing playlists Feb 16 10:13:37 volumio volumio[6195]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 16 10:13:38 volumio volumio[6195]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Feb 16 10:13:38 volumio volumio[6195]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Feb 16 10:13:38 volumio volumio[6195]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Feb 16 10:13:38 volumio volumio[6195]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Feb 16 10:13:38 volumio volumio[6195]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Feb 16 10:13:38 volumio volumio[6195]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Feb 16 10:13:38 volumio volumio[6195]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Feb 16 10:13:38 volumio volumio[6195]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Feb 16 10:13:38 volumio volumio[6195]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Feb 16 10:13:38 volumio volumio[6195]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Feb 16 10:13:38 volumio volumio[6195]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Feb 16 10:13:38 volumio volumio[6195]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Feb 16 10:13:38 volumio volumio[6195]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Feb 16 10:13:38 volumio volumio[6195]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Feb 16 10:13:38 volumio volumio[6195]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Feb 16 10:13:38 volumio volumio[6195]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Feb 16 10:13:38 volumio volumio[6195]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Feb 16 10:13:38 volumio volumio[6195]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Feb 16 10:13:38 volumio volumio[6195]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Feb 16 10:13:38 volumio volumio[6195]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Feb 16 10:13:38 volumio volumio[6195]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Feb 16 10:13:38 volumio volumio[6195]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Feb 16 10:13:38 volumio volumio[6195]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Feb 16 10:13:38 volumio volumio[6195]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Feb 16 10:13:38 volumio volumio[6195]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Feb 16 10:13:38 volumio volumio[6195]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Feb 16 10:13:38 volumio volumio[6195]: info: Adding plugin bluetooth to MyMusic Plugins Feb 16 10:13:38 volumio volumio[6195]: info: Adding plugin cd_controller to MyMusic Plugins Feb 16 10:13:38 volumio volumio[6195]: info: Adding plugin smart_inputs to MyMusic Plugins Feb 16 10:13:38 volumio volumio[6195]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Feb 16 10:13:38 volumio volumio-remote-updater[583]: [2025-02-16 10:13:38] [connect] Successful connection Feb 16 10:13:40 volumio volumio[6195]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Feb 16 10:13:41 volumio volumio[6195]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Feb 16 10:13:41 volumio volumio[6195]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Feb 16 10:13:41 volumio volumio[6195]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 16 10:13:41 volumio volumio[6195]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 16 10:13:41 volumio volumio[6195]: info: Starting MyVolumio Remote Streaming Endpoints Feb 16 10:13:41 volumio volumio[6195]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Feb 16 10:13:41 volumio volumio[6195]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Feb 16 10:13:41 volumio volumio[6195]: info: Streaming services startup Feb 16 10:13:41 volumio volumio[6195]: info: Starting Streaming Daemon Feb 16 10:13:41 volumio volumio[6195]: info: MyVolumio not started Feb 16 10:13:41 volumio volumio[6195]: info: Initializing device activation check Feb 16 10:13:41 volumio sudo[6436]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 16 10:13:41 volumio sudo[6436]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 10:13:41 volumio volumio[6195]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Feb 16 10:13:41 volumio systemd[1]: Stopping Volumio Streaming Daemon... Feb 16 10:13:41 volumio systemd[1]: Starting Volumio Streaming Daemon... Feb 16 10:13:41 volumio systemd[1]: Started Volumio Streaming Daemon. Feb 16 10:13:41 volumio sudo[6436]: pam_unix(sudo:session): session closed for user root Feb 16 10:13:41 volumio volumio-streaming-daemon[6442]: ############################ Feb 16 10:13:41 volumio volumio-streaming-daemon[6442]: # Volumio Streaming Daemon # Feb 16 10:13:41 volumio volumio-streaming-daemon[6442]: # Running on port 7777 # Feb 16 10:13:41 volumio volumio-streaming-daemon[6442]: ############################ Feb 16 10:13:41 volumio volumio-streaming-daemon[6442]: Environment: production Feb 16 10:13:41 volumio volumio[6195]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Feb 16 10:13:41 volumio volumio[6195]: info: CoreStateMachine::getcurrentVolume Feb 16 10:13:41 volumio volumio[6195]: info: CoreCommandRouter::volumioRetrievevolume Feb 16 10:13:41 volumio volumio[6195]: info: CoreStateMachine::pushState Feb 16 10:13:41 volumio volumio[6195]: info: CorePlayQueue::getTrack 0 Feb 16 10:13:41 volumio volumio[6195]: info: CoreCommandRouter::volumioPushState Feb 16 10:13:41 volumio volumio[6195]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 16 10:13:41 volumio volumio[6195]: info: Cannot mount NAS MY-Music at system boot, trial number 3 ,retrying in 5 seconds Feb 16 10:13:41 volumio volumio-remote-updater[583]: [2025-02-16 10:13:41] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.5.1" /socket.io/?EIO=4&transport=websocket&t=1739700818 101 Feb 16 10:13:41 volumio volumio[6195]: info: CoreCommandRouter::volumioPlay Feb 16 10:13:41 volumio volumio[6195]: UNSET VOLATILE Feb 16 10:13:41 volumio volumio[6195]: info: CoreStateMachine::play index 11 Feb 16 10:13:41 volumio volumio[6195]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 16 10:13:41 volumio volumio[6195]: info: CoreStateMachine::stop Feb 16 10:13:41 volumio volumio[6195]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 16 10:13:41 volumio volumio[6195]: UNSET VOLATILE Feb 16 10:13:41 volumio volumio[6195]: info: CoreStateMachine::play index undefined Feb 16 10:13:41 volumio volumio[6195]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 16 10:13:41 volumio volumio[6195]: info: CorePlayQueue::getTrack 11 Feb 16 10:13:41 volumio volumio[6195]: info: CoreStateMachine::startPlaybackTimer Feb 16 10:13:41 volumio volumio[6195]: info: CorePlayQueue::getTrack 11 Feb 16 10:13:41 volumio volumio[6195]: info: [1739700821948] ControllerUPNPBrowser::clearAddPlayTrack Feb 16 10:13:41 volumio volumio[6195]: verbose: ControllerMpd::sendMpdCommand stop Feb 16 10:13:41 volumio volumio[6195]: verbose: ControllerMpd::sendMpdCommand clear Feb 16 10:13:41 volumio volumio[6195]: info: Feb 16 10:13:41 volumio volumio[6195]: ---------------------------- MPD announces system playlist update Feb 16 10:13:41 volumio volumio[6195]: info: Ignoring MPD Status Update Feb 16 10:13:41 volumio volumio[6195]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.71.11:8200/MediaItems/1393.flac" Feb 16 10:13:41 volumio volumio[6195]: info: Feb 16 10:13:41 volumio volumio[6195]: ---------------------------- MPD announces system playlist update Feb 16 10:13:41 volumio volumio[6195]: info: Ignoring MPD Status Update Feb 16 10:13:41 volumio volumio[6195]: error: updateQueue error: null Feb 16 10:13:41 volumio volumio[6195]: info: ------------------------------ 9ms Feb 16 10:13:42 volumio volumio[6195]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.71.11:8200/MediaItems/1393.flac" Feb 16 10:13:42 volumio volumio[6195]: error: updateQueue error: null Feb 16 10:13:42 volumio volumio[6195]: info: Feb 16 10:13:42 volumio volumio[6195]: ---------------------------- MPD announces system playlist update Feb 16 10:13:42 volumio volumio[6195]: info: Ignoring MPD Status Update Feb 16 10:13:42 volumio volumio[6195]: info: ------------------------------ 52ms Feb 16 10:13:42 volumio volumio[6195]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 16 10:13:42 volumio volumio[6195]: verbose: ControllerMpd::sendMpdCommand play Feb 16 10:13:42 volumio volumio[6195]: info: Feb 16 10:13:42 volumio volumio[6195]: ---------------------------- MPD announces system playlist update Feb 16 10:13:42 volumio volumio[6195]: info: Ignoring MPD Status Update Feb 16 10:13:42 volumio volumio[6195]: info: ------------------------------ 8ms Feb 16 10:13:42 volumio volumio[6195]: info: ------------------------------ 4ms Feb 16 10:13:42 volumio volumio[6195]: info: Feb 16 10:13:42 volumio volumio[6195]: ---------------------------- MPD announces state update: player Feb 16 10:13:42 volumio volumio[6195]: info: ControllerMpd::getState Feb 16 10:13:42 volumio volumio[6195]: verbose: ControllerMpd::sendMpdCommand status Feb 16 10:13:42 volumio volumio[6195]: info: Feb 16 10:13:42 volumio volumio[6195]: ---------------------------- MPD announces state update: player Feb 16 10:13:42 volumio volumio[6195]: info: ControllerMpd::getState Feb 16 10:13:42 volumio volumio[6195]: verbose: ControllerMpd::sendMpdCommand status Feb 16 10:13:42 volumio volumio[6195]: info: Feb 16 10:13:42 volumio volumio[6195]: ---------------------------- MPD announces state update: player Feb 16 10:13:42 volumio volumio[6195]: info: Feb 16 10:13:42 volumio volumio[6195]: ---------------------------- MPD announces system playlist update Feb 16 10:13:42 volumio volumio[6195]: info: Ignoring MPD Status Update Feb 16 10:13:42 volumio volumio[6195]: info: ControllerMpd::getState Feb 16 10:13:42 volumio volumio[6195]: verbose: ControllerMpd::sendMpdCommand status Feb 16 10:13:42 volumio volumio[6195]: verbose: ControllerMpd::parseState Feb 16 10:13:42 volumio volumio[6195]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 16 10:13:42 volumio volumio[6195]: verbose: ControllerMpd::parseState Feb 16 10:13:42 volumio volumio[6195]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 16 10:13:42 volumio volumio[6195]: info: Feb 16 10:13:42 volumio volumio[6195]: ---------------------------- MPD announces system playlist update Feb 16 10:13:42 volumio volumio[6195]: info: Ignoring MPD Status Update Feb 16 10:13:42 volumio volumio[6195]: info: Feb 16 10:13:42 volumio volumio[6195]: ---------------------------- MPD announces state update: player Feb 16 10:13:42 volumio volumio[6195]: info: ControllerMpd::getState Feb 16 10:13:42 volumio volumio[6195]: verbose: ControllerMpd::sendMpdCommand status Feb 16 10:13:42 volumio volumio[6195]: info: ------------------------------ 19ms Feb 16 10:13:42 volumio volumio[6195]: verbose: ControllerMpd::parseState Feb 16 10:13:42 volumio volumio[6195]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 16 10:13:42 volumio volumio[6195]: verbose: ControllerMpd::parseTrackInfo Feb 16 10:13:42 volumio volumio[6195]: verbose: ControllerMpd::parseTrackInfo Feb 16 10:13:42 volumio volumio[6195]: info: ControllerMpd::pushState Feb 16 10:13:42 volumio volumio[6195]: info: CoreCommandRouter::servicePushState Feb 16 10:13:42 volumio volumio[6195]: info: CorePlayQueue::getTrack 11 Feb 16 10:13:42 volumio volumio[6195]: verbose: STATE SERVICE {"status":"play","position":0,"seek":301,"duration":245,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"965 Kbps","isStreaming":false,"title":"明日見あげる場所","artist":"朝日奈丸佳","album":"明日見あげる場所","uri":"http://192.168.71.11:8200/MediaItems/1393.flac","trackType":"flac"} Feb 16 10:13:42 volumio volumio[6195]: verbose: CURRENT POSITION 11 Feb 16 10:13:42 volumio volumio[6195]: info: CoreStateMachine::syncState stateService play Feb 16 10:13:42 volumio volumio[6195]: info: CoreStateMachine::syncState currentStatus stop Feb 16 10:13:42 volumio volumio[6195]: info: CoreStateMachine::pushState Feb 16 10:13:42 volumio volumio[6195]: info: CoreCommandRouter::volumioPushState Feb 16 10:13:42 volumio volumio[6195]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 16 10:13:42 volumio volumio[6195]: info: ControllerMpd::pushState Feb 16 10:13:42 volumio volumio[6195]: info: CoreCommandRouter::servicePushState Feb 16 10:13:42 volumio volumio[6195]: info: CorePlayQueue::getTrack 11 Feb 16 10:13:42 volumio volumio[6195]: verbose: STATE SERVICE {"status":"play","position":0,"seek":464,"duration":245,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1062 Kbps","isStreaming":false,"title":"明日見あげる場所","artist":"朝日奈丸佳","album":"明日見あげる場所","uri":"http://192.168.71.11:8200/MediaItems/1393.flac","trackType":"flac"} Feb 16 10:13:42 volumio volumio[6195]: verbose: CURRENT POSITION 11 Feb 16 10:13:42 volumio volumio[6195]: info: CoreStateMachine::syncState stateService play Feb 16 10:13:42 volumio volumio[6195]: info: CoreStateMachine::syncState currentStatus play Feb 16 10:13:42 volumio volumio[6195]: info: Received an update from plugin. extracting info from payload Feb 16 10:13:42 volumio volumio[6195]: info: CoreStateMachine::pushState Feb 16 10:13:42 volumio volumio[6195]: info: CoreCommandRouter::volumioPushState Feb 16 10:13:42 volumio volumio[6195]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 16 10:13:42 volumio volumio[6195]: info: CoreStateMachine::pushState Feb 16 10:13:42 volumio volumio[6195]: info: CoreCommandRouter::volumioPushState Feb 16 10:13:42 volumio volumio[6195]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 16 10:13:42 volumio volumio[6195]: info: ------------------------------ 72ms Feb 16 10:13:42 volumio volumio[6195]: info: ------------------------------ 70ms Feb 16 10:13:42 volumio volumio[6195]: info: ------------------------------ 58ms Feb 16 10:13:42 volumio volumio[6195]: verbose: ControllerMpd::parseState Feb 16 10:13:42 volumio volumio[6195]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 16 10:13:42 volumio volumio[6195]: verbose: ControllerMpd::parseTrackInfo Feb 16 10:13:42 volumio volumio[6195]: info: ControllerMpd::pushState Feb 16 10:13:42 volumio volumio[6195]: info: CoreCommandRouter::servicePushState Feb 16 10:13:42 volumio volumio[6195]: info: CorePlayQueue::getTrack 11 Feb 16 10:13:42 volumio volumio[6195]: verbose: STATE SERVICE {"status":"play","position":0,"seek":464,"duration":245,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1236 Kbps","isStreaming":false,"title":"明日見あげる場所","artist":"朝日奈丸佳","album":"明日見あげる場所","uri":"http://192.168.71.11:8200/MediaItems/1393.flac","trackType":"flac"} Feb 16 10:13:42 volumio volumio[6195]: verbose: CURRENT POSITION 11 Feb 16 10:13:42 volumio volumio[6195]: info: CoreStateMachine::syncState stateService play Feb 16 10:13:42 volumio volumio[6195]: info: CoreStateMachine::syncState currentStatus play Feb 16 10:13:42 volumio volumio[6195]: info: Received an update from plugin. extracting info from payload Feb 16 10:13:42 volumio volumio[6195]: info: CoreStateMachine::pushState Feb 16 10:13:42 volumio volumio[6195]: info: CoreCommandRouter::volumioPushState Feb 16 10:13:42 volumio volumio[6195]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 16 10:13:42 volumio volumio[6195]: info: CoreStateMachine::pushState Feb 16 10:13:42 volumio volumio[6195]: info: CoreCommandRouter::volumioPushState Feb 16 10:13:42 volumio volumio[6195]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 16 10:13:42 volumio volumio[6195]: info: ------------------------------ 97ms Feb 16 10:13:42 volumio volumio[6195]: info: CoreCommandRouter::volumioGetQueue Feb 16 10:13:42 volumio volumio[6195]: info: CoreStateMachine::getQueue Feb 16 10:13:42 volumio volumio[6195]: info: CorePlayQueue::getQueue Feb 16 10:13:42 volumio volumio[6195]: info: CoreCommandRouter::volumioGetQueue Feb 16 10:13:42 volumio volumio[6195]: info: CoreStateMachine::getQueue Feb 16 10:13:42 volumio volumio[6195]: info: CorePlayQueue::getQueue Feb 16 10:13:42 volumio volumio[6195]: info: CoreCommandRouter::volumioGetQueue Feb 16 10:13:42 volumio volumio[6195]: info: CoreStateMachine::getQueue Feb 16 10:13:42 volumio volumio[6195]: info: CorePlayQueue::getQueue Feb 16 10:13:42 volumio volumio[6195]: info: CoreCommandRouter::volumioGetQueue Feb 16 10:13:42 volumio volumio[6195]: info: CoreStateMachine::getQueue Feb 16 10:13:42 volumio volumio[6195]: info: CorePlayQueue::getQueue Feb 16 10:13:42 volumio volumio[6195]: verbose: ControllerMpd::parseTrackInfo Feb 16 10:13:42 volumio volumio[6195]: info: ControllerMpd::pushState Feb 16 10:13:42 volumio volumio[6195]: info: CoreCommandRouter::servicePushState Feb 16 10:13:42 volumio volumio[6195]: info: CorePlayQueue::getTrack 11 Feb 16 10:13:42 volumio volumio[6195]: verbose: STATE SERVICE {"status":"play","position":0,"seek":464,"duration":245,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1213 Kbps","isStreaming":false,"title":"明日見あげる場所","artist":"朝日奈丸佳","album":"明日見あげる場所","uri":"http://192.168.71.11:8200/MediaItems/1393.flac","trackType":"flac"} Feb 16 10:13:42 volumio volumio[6195]: verbose: CURRENT POSITION 11 Feb 16 10:13:42 volumio volumio[6195]: info: CoreStateMachine::syncState stateService play Feb 16 10:13:42 volumio volumio[6195]: info: CoreStateMachine::syncState currentStatus play Feb 16 10:13:42 volumio volumio[6195]: info: Received an update from plugin. extracting info from payload Feb 16 10:13:42 volumio volumio[6195]: info: CoreStateMachine::pushState Feb 16 10:13:42 volumio volumio[6195]: info: CoreCommandRouter::volumioPushState Feb 16 10:13:42 volumio volumio[6195]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 16 10:13:42 volumio volumio[6195]: info: CoreStateMachine::pushState Feb 16 10:13:42 volumio volumio[6195]: info: CoreCommandRouter::volumioPushState Feb 16 10:13:42 volumio volumio[6195]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 16 10:13:42 volumio volumio[6195]: info: ------------------------------ 181ms Feb 16 10:13:42 volumio volumio[6195]: info: CoreCommandRouter::volumioGetQueue Feb 16 10:13:42 volumio volumio[6195]: info: CoreStateMachine::getQueue Feb 16 10:13:42 volumio volumio[6195]: info: CorePlayQueue::getQueue Feb 16 10:13:42 volumio volumio[6195]: info: CoreCommandRouter::volumioGetQueue Feb 16 10:13:42 volumio volumio[6195]: info: CoreStateMachine::getQueue Feb 16 10:13:42 volumio volumio[6195]: info: CorePlayQueue::getQueue Feb 16 10:13:42 volumio volumio[6195]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E9%9B%AA%E9%87%8E%E4%BA%94%E6%9C%88/You/ad10258a-258d-472c-a97f-3c6fddaa0c26.png' Feb 16 10:13:44 volumio volumio-streaming-daemon[6442]: Environment: production Feb 16 10:13:44 volumio volumio[6195]: info: Fetching Streaming Services browse cache Feb 16 10:13:44 volumio volumio[6195]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesTIDAL Feb 16 10:13:44 volumio volumio[6195]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 16 10:13:44 volumio volumio[6195]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesQOBUZ Feb 16 10:13:44 volumio volumio[6195]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 16 10:13:44 volumio volumio[6195]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=2.806&uuid=a7e9f22ba9335470b6486b690596227f" http://updates.volumio.org:7070/downloader-v1/track-device Feb 16 10:13:44 volumio volumio[6195]: % Total % Received % Xferd Average Speed Time Time Time Current Feb 16 10:13:44 volumio volumio[6195]: Dload Upload Total Spent Left Speed Feb 16 10:13:44 volumio volumio[6195]: 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0curl: (7) Failed to connect to updates.volumio.org port 7070: Connection refused Feb 16 10:13:44 volumio volumio[6195]: retrying in 5 seconds, trial 0 Feb 16 10:13:44 volumio volumio[6195]: Volumio Calling Home Feb 16 10:13:46 volumio volumio[6195]: info: Cannot mount NAS MY-Music at system boot, trial number 4 ,retrying in 5 seconds Feb 16 10:13:48 volumio volumio[6195]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Lia/%E6%99%82%E3%82%92%E5%88%BB%E3%82%80%E5%94%84%2FTORCH/a7faca75-8366-405f-b832-438be72676b3.png' Feb 16 10:13:48 volumio volumio[6195]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 16 10:13:48 volumio volumio[6195]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Feb 16 10:13:48 volumio volumio[6195]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 16 10:13:48 volumio volumio[6195]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 16 10:13:48 volumio volumio[6195]: info: Getting Alsa Cards List without I2S DAC Feb 16 10:13:48 volumio volumio[6195]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Feb 16 10:13:48 volumio volumio[6195]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 16 10:13:48 volumio volumio[6195]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 16 10:13:48 volumio volumio[6195]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 16 10:13:48 volumio volumio[6195]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 16 10:13:48 volumio volumio[6195]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 16 10:13:48 volumio volumio[6195]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 16 10:13:48 volumio volumio[6195]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 16 10:13:48 volumio volumio[6195]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 16 10:13:48 volumio volumio[6195]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Feb 16 10:13:48 volumio volumio[6195]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Feb 16 10:13:49 volumio volumio[6195]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/RSP/%E3%81%95%E3%81%8F%E3%82%89~%E3%81%82%E3%81%AA%E3%81%9F%E3%81%AB%E5%87%BA%E4%BC%9A%E3%81%88%E3%81%A6%E3%82%88%E3%81%8B%E3%81%A3%E3%81%9F~/5066e768-dd2b-4fcc-9f33-6bcd8c1e4ab7.png' Feb 16 10:13:51 volumio volumio[6195]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/supercell/%E6%8B%8D%E6%89%8B%E5%96%9D%E9%87%87%E6%AD%8C%E5%90%88/4088ef51-51bb-49e2-8b98-905cfd6eb3bd.png' Feb 16 10:13:51 volumio volumio[6195]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E3%82%84%E3%81%AA%E3%81%8E%E3%81%AA%E3%81%8E/%E7%B5%82%E3%82%8F%E3%82%8A%E3%81%AE%E6%83%91%E6%98%9F%E3%81%AELove%20Song/33e42162-30f4-4c98-8e09-ba6221e21bc4.png' Feb 16 10:13:51 volumio volumio[6195]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E3%82%84%E3%81%AA%E3%81%8E%E3%81%AA%E3%81%8E/Killer%20Song/f638df37-0797-4cd7-861f-d44ff0839e4e.png' Feb 16 10:13:51 volumio volumio[6195]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E4%BB%BB%E8%B4%A4%E9%BD%90/5da0d7f9-6e64-489f-9a59-a5231588fd11.jpg' Feb 16 10:13:51 volumio volumio[6195]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%3F%3F/b02cf76f-7230-447f-8511-7453f524c831.jpg' Feb 16 10:13:51 volumio volumio[6195]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%3F%3F%3F/%3F%3F%3F/4f1fd030-0d37-40c3-8231-5cfbc9a9022b.png' Feb 16 10:13:51 volumio volumio[6195]: info: Cannot mount NAS at system boot, trial number 4 ,stopping Feb 16 10:13:52 volumio volumio[6195]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E5%88%98%E6%80%9D%E6%B6%B5/%E6%8B%A5%E6%8A%B1%E4%BD%A0/269a42c7-a067-4918-a127-65194eda3f5d.png' Feb 16 10:13:54 volumio volumio[6195]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E5%90%8E%E5%BC%A6/42996c49-0d44-4129-b8ca-ef37cdfa38ef.jpg' Feb 16 10:13:55 volumio volumio[6195]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=2.806&uuid=a7e9f22ba9335470b6486b690596227f" http://updates.volumio.org:7070/downloader-v1/track-device Feb 16 10:13:55 volumio volumio[6195]: % Total % Received % Xferd Average Speed Time Time Time Current Feb 16 10:13:55 volumio volumio[6195]: Dload Upload Total Spent Left Speed Feb 16 10:13:55 volumio volumio[6195]: 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0curl: (7) Failed to connect to updates.volumio.org port 7070: Connection refused Feb 16 10:13:55 volumio volumio[6195]: retrying in 5 seconds, trial 1 Feb 16 10:13:55 volumio volumio[6195]: Volumio Calling Home Feb 16 10:13:56 volumio volumio[6195]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E5%91%A8%E5%8D%8E%E5%81%A5/3c890246-315e-4a85-80c9-c93f098bf1bd.jpg' Feb 16 10:13:56 volumio volumio[6195]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%3F%3F%3F/daeb1cf1-e0a6-431a-ad41-3c6df993436f.jpg' Feb 16 10:13:56 volumio volumio[6195]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E5%91%A8%E6%9D%B0%E5%80%AB/%E6%88%91%E5%BE%88%E5%BF%99/5c4405ac-4e2a-45a8-869b-b4f35fa0c4f7.jpg' Feb 16 10:13:56 volumio volumio[6195]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E5%91%A8%E8%95%99/eabd4649-f466-44be-9071-bcc88e968b94.jpg' Feb 16 10:13:56 volumio volumio[6195]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E5%A7%9A%E8%B4%9D%E5%A8%9C/1%2F2%E7%9A%84%E6%88%91-EP/1f87e132-79cd-47d3-9956-dcf3c62289e7.jpg' Feb 16 10:13:56 volumio volumio[6195]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Feb 16 10:13:56 volumio volumio[6195]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Feb 16 10:13:56 volumio volumio[6195]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 16 10:13:56 volumio volumio[6195]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Feb 16 10:13:56 volumio volumio[6195]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 16 10:13:56 volumio volumio[6195]: info: Enabling I2S DAC: HiFiBox DAC Feb 16 10:13:56 volumio volumio[6195]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , enableI2SDAC Feb 16 10:13:56 volumio volumio[6195]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 16 10:13:56 volumio sudo[6498]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l Feb 16 10:13:56 volumio sudo[6498]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 10:13:56 volumio sudo[6498]: pam_unix(sudo:session): session closed for user root Feb 16 10:13:56 volumio volumio[6195]: info: No Overlays Loaded Feb 16 10:13:56 volumio volumio[6195]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 16 10:13:56 volumio volumio[6195]: info: Output device has changed, restarting MPD Feb 16 10:13:56 volumio sudo[6507]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay hifiberry-dacplus Feb 16 10:13:56 volumio sudo[6507]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 10:13:56 volumio volumio[6195]: info: Output device has changed, restarting Shairport Sync Feb 16 10:13:56 volumio sudo[6510]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 16 10:13:56 volumio sudo[6510]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 10:13:56 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/i2s@7e203000/status Feb 16 10:13:56 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/i2c@7e804000/status Feb 16 10:13:56 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/sound/compatible Feb 16 10:13:56 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/sound/i2s-controller Feb 16 10:13:56 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/sound/status Feb 16 10:13:56 volumio sudo[6518]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 16 10:13:56 volumio sudo[6510]: pam_unix(sudo:session): session closed for user root Feb 16 10:13:56 volumio sudo[6518]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 10:13:56 volumio sudo[6507]: pam_unix(sudo:session): session closed for user root Feb 16 10:13:56 volumio kernel: pcm512x 1-004d: Linked as a consumer to regulator.1 Feb 16 10:13:56 volumio kernel: pcm512x 1-004d: Failed to reset device: -121 Feb 16 10:13:56 volumio kernel: pcm512x: probe of 1-004d failed with error -121 Feb 16 10:13:56 volumio volumio[6195]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 16 10:13:56 volumio volumio[6195]: info: Output device has changed, restarting MPD Feb 16 10:13:56 volumio systemd[1]: Stopping Music Player Daemon... Feb 16 10:13:56 volumio volumio[6195]: info: Output device has changed, restarting Shairport Sync Feb 16 10:13:56 volumio sudo[6545]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 16 10:13:56 volumio sudo[6545]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 10:13:56 volumio sudo[6547]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 16 10:13:56 volumio sudo[6545]: pam_unix(sudo:session): session closed for user root Feb 16 10:13:56 volumio sudo[6547]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 10:13:56 volumio volumio[6195]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 16 10:13:56 volumio systemd[1]: Starting Music Player Daemon... Feb 16 10:13:56 volumio volumio[6195]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 16 10:13:56 volumio volumio[6195]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SMixer Feb 16 10:13:56 volumio volumio[6195]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 16 10:13:56 volumio volumio[6195]: info: Found match in i2s Card Database: setting mixer Digital for card HiFiBox DAC Feb 16 10:13:57 volumio volumio[6195]: info: CoreCommandRouter::volumioUpdateVolumeSettings Feb 16 10:13:57 volumio volumio[6195]: info: Updating Volume Controller Parameters: Device: 2 Name: Hifiberry DAC Mixer: Digital Max Vol: 100 Vol Curve; logarithmic Vol Steps: 10 Feb 16 10:13:57 volumio volumio[6195]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 16 10:13:57 volumio volumio[6195]: info: MPD Permissions set Feb 16 10:13:57 volumio volumio[6195]: info: MPD Permissions set Feb 16 10:13:57 volumio volumio[6195]: info: I2S Param [object Object] successfully enabled Feb 16 10:13:57 volumio volumio[6195]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 10:13:57 volumio volumio[6195]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 10:13:57 volumio volumio[6195]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 10:13:57 volumio volumio[6195]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 10:13:57 volumio volumio[6195]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 10:13:57 volumio volumio[6195]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 10:13:57 volumio volumio[6195]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 10:13:57 volumio volumio[6195]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 16 10:13:57 volumio volumio[6195]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 10:13:57 volumio volumio[6195]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 10:13:57 volumio volumio[6195]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 10:13:57 volumio volumio[6195]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 10:13:57 volumio volumio[6195]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 10:13:57 volumio volumio[6195]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 10:13:57 volumio volumio[6195]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 10:13:57 volumio volumio[6195]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 10:13:57 volumio volumio[6195]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 16 10:13:57 volumio volumio[6195]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 10:13:57 volumio volumio[6195]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Feb 16 10:13:57 volumio volumio[6195]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 16 10:13:57 volumio volumio[6195]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 16 10:13:57 volumio volumio[6195]: info: Getting Alsa Cards List without I2S DAC Feb 16 10:13:57 volumio volumio[6195]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Feb 16 10:13:57 volumio volumio[6195]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 16 10:13:57 volumio volumio[6195]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 16 10:13:57 volumio volumio[6195]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 16 10:13:57 volumio volumio[6195]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 16 10:13:57 volumio volumio[6195]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 16 10:13:57 volumio volumio[6195]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 16 10:13:57 volumio volumio[6195]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 16 10:13:57 volumio volumio[6195]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 16 10:13:57 volumio volumio[6195]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Feb 16 10:13:57 volumio volumio[6195]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Feb 16 10:13:57 volumio mpd[6565]: output: No 'AudioOutput' defined in config file Feb 16 10:13:57 volumio mpd[6565]: output: Attempt to detect audio output device Feb 16 10:13:57 volumio mpd[6565]: output: Attempting to detect a alsa audio device Feb 16 10:13:57 volumio volumio[6195]: error: Upnp client error: Error: This socket has been ended by the other party Feb 16 10:13:57 volumio volumio[6195]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'Digital',0 Feb 16 10:13:57 volumio mpd[6565]: output: Successfully detected a alsa audio device Feb 16 10:13:57 volumio systemd[1]: Started Music Player Daemon. Feb 16 10:13:57 volumio sudo[6518]: pam_unix(sudo:session): session closed for user root Feb 16 10:13:57 volumio sudo[6547]: pam_unix(sudo:session): session closed for user root Feb 16 10:13:57 volumio volumio[6195]: info: Starting Shairport Sync Feb 16 10:13:57 volumio volumio[6195]: info: Starting Shairport Sync Feb 16 10:13:57 volumio sudo[6597]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 16 10:13:57 volumio sudo[6597]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 10:13:57 volumio sudo[6600]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 16 10:13:57 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Feb 16 10:13:57 volumio volumio[6195]: info: VolumeController:: Volume=undefined Mute =false Feb 16 10:13:57 volumio volumio[6195]: info: CoreStateMachine::pushState Feb 16 10:13:57 volumio volumio[6195]: info: CoreCommandRouter::volumioPushState Feb 16 10:13:57 volumio volumio[6195]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 16 10:13:57 volumio systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... Feb 16 10:13:57 volumio sudo[6600]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 10:13:57 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Feb 16 10:13:57 volumio sudo[6597]: pam_unix(sudo:session): session closed for user root Feb 16 10:13:57 volumio volumio[6195]: error: MPD error: false == true Feb 16 10:13:57 volumio volumio[6195]: error: updateQueue error: null Feb 16 10:13:57 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Feb 16 10:13:57 volumio volumio[6195]: info: CoreCommandRouter::volumioGetQueue Feb 16 10:13:57 volumio volumio[6195]: info: CoreStateMachine::getQueue Feb 16 10:13:57 volumio volumio[6195]: info: CorePlayQueue::getQueue Feb 16 10:13:57 volumio systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... Feb 16 10:13:57 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Feb 16 10:13:57 volumio sudo[6600]: pam_unix(sudo:session): session closed for user root Feb 16 10:13:57 volumio volumio[6195]: info: Shairport-Sync Started Feb 16 10:13:57 volumio volumio[6195]: info: Shairport-Sync Started Feb 16 10:13:58 volumio volumio[6195]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Feb 16 10:13:58 volumio volumio[6195]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateVolumeSettings Feb 16 10:13:58 volumio sudo[6632]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 16 10:13:58 volumio sudo[6632]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 10:13:58 volumio systemd[1]: Stopping Music Player Daemon... Feb 16 10:13:58 volumio volumio[6195]: info: CoreCommandRouter::volumioUpdateVolumeSettings Feb 16 10:13:58 volumio volumio[6195]: info: Updating Volume Controller Parameters: Device: 2 Name: Hifiberry DAC Mixer: Digital Max Vol: 100 Vol Curve; logarithmic Vol Steps: 10 Feb 16 10:13:58 volumio volumio[6195]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'Digital',0 Feb 16 10:13:58 volumio systemd[1]: Starting Music Player Daemon... Feb 16 10:13:58 volumio volumio[6195]: info: VolumeController:: Volume=undefined Mute =false Feb 16 10:13:58 volumio volumio[6195]: info: CoreStateMachine::pushState Feb 16 10:13:58 volumio volumio[6195]: info: CoreCommandRouter::volumioPushState Feb 16 10:13:58 volumio volumio[6195]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 16 10:13:58 volumio volumio[6195]: info: CoreCommandRouter::volumioGetQueue Feb 16 10:13:58 volumio volumio[6195]: info: CoreStateMachine::getQueue Feb 16 10:13:58 volumio volumio[6195]: info: CorePlayQueue::getQueue Feb 16 10:13:58 volumio volumio[6195]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 16 10:13:58 volumio volumio[6195]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 16 10:13:59 volumio systemd[1]: Started Music Player Daemon. Feb 16 10:13:59 volumio sudo[6632]: pam_unix(sudo:session): session closed for user root Feb 16 10:13:59 volumio volumio[6195]: error: updateQueue error: null Feb 16 10:13:59 volumio volumio[6195]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E5%B1%A0%E6%B4%AA%E5%88%9A/%E7%B2%BE%E5%BF%A0%E6%8A%A5%E5%9B%BD/bd605a7f-4068-42d4-9e07-9f3c4ff5e159.png' Feb 16 10:13:59 volumio volumio[6195]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Feb 16 10:13:59 volumio volumio[6195]: info: CoreStateMachine::getcurrentVolume Feb 16 10:13:59 volumio volumio[6195]: info: CoreCommandRouter::volumioRetrievevolume Feb 16 10:13:59 volumio volumio[6195]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'Digital',0 Feb 16 10:13:59 volumio volumio[6195]: info: VolumeController:: Volume=undefined Mute =false Feb 16 10:13:59 volumio volumio[6195]: info: CoreStateMachine::pushState Feb 16 10:13:59 volumio volumio[6195]: info: CoreCommandRouter::volumioPushState Feb 16 10:13:59 volumio volumio[6195]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 16 10:13:59 volumio volumio[6195]: info: CoreStateMachine::updateTrackBlock Feb 16 10:13:59 volumio volumio[6195]: info: CorePlayQueue::getTrackBlock Feb 16 10:13:59 volumio volumio[6195]: info: CoreCommandRouter::volumioRetrievevolume Feb 16 10:13:59 volumio volumio[6195]: info: CoreCommandRouter::volumioGetQueue Feb 16 10:13:59 volumio volumio[6195]: info: CoreStateMachine::getQueue Feb 16 10:13:59 volumio volumio[6195]: info: CorePlayQueue::getQueue Feb 16 10:13:59 volumio volumio[6195]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'Digital',0 Feb 16 10:13:59 volumio volumio[6195]: info: VolumeController:: Volume=undefined Mute =false Feb 16 10:13:59 volumio volumio[6195]: info: CoreStateMachine::pushState Feb 16 10:13:59 volumio volumio[6195]: info: CoreCommandRouter::volumioPushState Feb 16 10:13:59 volumio volumio[6195]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 16 10:13:59 volumio volumio[6195]: info: CoreCommandRouter::volumioGetQueue Feb 16 10:13:59 volumio volumio[6195]: info: CoreStateMachine::getQueue Feb 16 10:13:59 volumio volumio[6195]: info: CorePlayQueue::getQueue Feb 16 10:14:00 volumio volumio[6195]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E5%BA%84%E5%BF%83%E5%A6%8D/%E4%B8%80%E4%B8%87%E4%B8%AA%E8%88%8D%E4%B8%8D%E5%BE%97/d02c1463-0c20-4d71-b7ce-8f760983ce4d.png' Feb 16 10:14:00 volumio volumio[6195]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E5%BC%A0%E5%9B%BD%E8%8D%A3/%E6%9C%80%E7%BA%A2/71e5e1d0-e8e0-4b45-a30d-3db55934b407.png' Feb 16 10:14:01 volumio volumio[6195]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E5%BC%A0%E5%AE%87/52e83497-2101-49c0-ab7d-e3ba99fdbfe3.jpg' Feb 16 10:14:02 volumio volumio[6195]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 10:14:02 volumio volumio[6195]: info: VolumeController:: Setting startup Volume 100 Feb 16 10:14:02 volumio volumio[6195]: info: VolumeController::SetAlsaVolume100 Feb 16 10:14:02 volumio volumio[6195]: info: CoreStateMachine::pushState Feb 16 10:14:02 volumio volumio[6195]: info: CoreCommandRouter::volumioPushState Feb 16 10:14:02 volumio volumio[6195]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 16 10:14:02 volumio volumio[6195]: info: CoreCommandRouter::volumioGetQueue Feb 16 10:14:02 volumio volumio[6195]: info: CoreStateMachine::getQueue Feb 16 10:14:02 volumio volumio[6195]: info: CorePlayQueue::getQueue Feb 16 10:14:02 volumio volumio[6195]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'Digital',0 Feb 16 10:14:02 volumio volumio[6195]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E5%BC%A0%E9%9D%93%E9%A2%96/36b09bb0-a001-40c6-b8fe-b3242a007233.jpg' Feb 16 10:14:02 volumio volumio[6195]: error: updateQueue error: null Feb 16 10:14:02 volumio volumio[6195]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E5%BC%A0%E9%9D%93%E9%A2%96/%E7%BB%88%E4%BA%8E%E7%AD%89%E5%88%B0%E4%BD%A0/178d1a05-a33e-4157-9f89-be650320f350.png' Feb 16 10:14:03 volumio volumio[6195]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E5%BE%90%E8%89%AF/a50fa384-c006-415f-8354-a21aae1e97b6.jpg' Feb 16 10:14:03 volumio volumio[6195]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E5%BE%90%E8%89%AF/%E5%8C%97%E4%BA%AC%E5%B7%B7%E5%BC%84/caf32980-aad7-43f5-b74b-6049b21689e8.jpg' Feb 16 10:14:03 volumio volumio[6195]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E5%BE%90%E8%89%AF/%E7%8A%AF%E8%B4%B1/cc2bc7d5-1924-4897-a904-29549b304752.jpg' Feb 16 10:14:04 volumio volumio[6195]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E6%9D%8E%E7%90%9B/24e7121f-aa8c-4752-8a45-9d08d8f00c1f.jpg' Feb 16 10:14:04 volumio volumio[6195]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E6%9D%8E%E8%8D%A3%E6%B5%A9/%E4%B8%8D%E5%B0%86%E5%B0%B1/41caf552-07f4-41c4-b949-9db597947ff8.jpg' Feb 16 10:14:04 volumio volumio[6195]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E6%9D%A8%E5%AE%97%E7%BA%AC/%E5%88%9D.%E7%88%B1/e30ac869-3db8-41f6-97d5-48ab4fedec8d.jpg' Feb 16 10:14:04 volumio volumio[6195]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E6%9E%97%E4%BF%8A%E6%9D%B0/b78e12b1-3ff0-43e2-af2b-a3c9e1c26dcc.jpg' Feb 16 10:14:05 volumio volumio[6195]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=2.806&uuid=a7e9f22ba9335470b6486b690596227f" http://updates.volumio.org:7070/downloader-v1/track-device Feb 16 10:14:05 volumio volumio[6195]: % Total % Received % Xferd Average Speed Time Time Time Current Feb 16 10:14:05 volumio volumio[6195]: Dload Upload Total Spent Left Speed Feb 16 10:14:05 volumio volumio[6195]: 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0curl: (7) Failed to connect to updates.volumio.org port 7070: Connection refused Feb 16 10:14:05 volumio volumio[6195]: retrying in 5 seconds, trial 2 Feb 16 10:14:05 volumio volumio[6195]: Volumio Calling Home Feb 16 10:14:08 volumio volumio[6195]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 16 10:14:08 volumio volumio[6195]: { Error: connect ETIMEDOUT 162.125.2.5:443 Feb 16 10:14:08 volumio volumio[6195]: at Object._errnoException (util.js:1022:11) Feb 16 10:14:08 volumio volumio[6195]: at _exceptionWithHostPort (util.js:1044:20) Feb 16 10:14:08 volumio volumio[6195]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1198:14) Feb 16 10:14:08 volumio volumio[6195]: code: 'ETIMEDOUT', Feb 16 10:14:08 volumio volumio[6195]: errno: 'ETIMEDOUT', Feb 16 10:14:08 volumio volumio[6195]: syscall: 'connect', Feb 16 10:14:08 volumio volumio[6195]: address: '162.125.2.5', Feb 16 10:14:08 volumio volumio[6195]: port: 443 } Feb 16 10:14:08 volumio volumio[6195]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 16 10:14:08 volumio sudo[6686]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-02-16 10:13 Feb 16 10:14:08 volumio sudo[6686]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 8 (jessie)" NAME="Raspbian GNU/Linux" VERSION_ID="8" VERSION="8 (jessie)" 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="ceaca221623bd3cfdc448100963369088321c5bb" VOLUMIO_FE_VERSION="4917998f86709e30d6499627aa39df0350cb55f8" VOLUMIO_BE_VERSION="2eb1d573179cee7a262e71ad6e6f6c07489b563f" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed Jul 29 20:47:51 CEST 2020" VOLUMIO_VERSION="2.806" VOLUMIO_HARDWARE="pi" VOLUMIO_HASH="a5dbec2d8b23cb06ebf9780d5fc2c84f"