-- Logs begin at Sun 2020-08-09 05:44:21 UTC, end at Sun 2025-02-16 10:04:06 UTC. -- Feb 16 10:03:04 volumio volumio[3157]: info: Cannot mount NAS at system boot, trial number 4 ,stopping Feb 16 10:03:07 volumio volumio[3157]: 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:03:07 volumio volumio[3157]: % Total % Received % Xferd Average Speed Time Time Time Current Feb 16 10:03:07 volumio volumio[3157]: Dload Upload Total Spent Left Speed Feb 16 10:03:07 volumio volumio[3157]: 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0curl: (7) Failed to connect to updates.volumio.org port 7070: Connection refused Feb 16 10:03:07 volumio volumio[3157]: retrying in 5 seconds, trial 1 Feb 16 10:03:07 volumio volumio[3157]: Volumio Calling Home Feb 16 10:03:15 volumio volumio[3157]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 16 10:03:15 volumio volumio[3157]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 16 10:03:17 volumio volumio[3157]: 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:03:17 volumio volumio[3157]: % Total % Received % Xferd Average Speed Time Time Time Current Feb 16 10:03:17 volumio volumio[3157]: Dload Upload Total Spent Left Speed Feb 16 10:03:17 volumio volumio[3157]: [235B blob data] Feb 16 10:03:17 volumio volumio[3157]: retrying in 5 seconds, trial 2 Feb 16 10:03:17 volumio volumio[3157]: Volumio Calling Home Feb 16 10:03:21 volumio volumio[3157]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 16 10:03:21 volumio volumio[3157]: { Error: connect ETIMEDOUT 128.242.240.125:80 Feb 16 10:03:21 volumio volumio[3157]: at Object._errnoException (util.js:1022:11) Feb 16 10:03:21 volumio volumio[3157]: at _exceptionWithHostPort (util.js:1044:20) Feb 16 10:03:21 volumio volumio[3157]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1198:14) Feb 16 10:03:21 volumio volumio[3157]: code: 'ETIMEDOUT', Feb 16 10:03:21 volumio volumio[3157]: errno: 'ETIMEDOUT', Feb 16 10:03:21 volumio volumio[3157]: syscall: 'connect', Feb 16 10:03:21 volumio volumio[3157]: address: '128.242.240.125', Feb 16 10:03:21 volumio volumio[3157]: port: 80 } Feb 16 10:03:21 volumio volumio[3157]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 16 10:03:21 volumio sudo[3477]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-02-16 10:02 Feb 16 10:03:21 volumio sudo[3477]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 10:03:21 volumio sudo[3477]: pam_unix(sudo:session): session closed for user root Feb 16 10:03:23 volumio volumio-remote-updater[583]: [2025-02-16 10:03:23] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Feb 16 10:03:23 volumio volumio-remote-updater[583]: [2025-02-16 10:03:23] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Feb 16 10:03:23 volumio systemd[1]: volumio.service: main process exited, code=exited, status=1/FAILURE Feb 16 10:03:23 volumio systemd[1]: Unit volumio.service entered failed state. Feb 16 10:03:23 volumio systemd[1]: Starting dynamicswap service... Feb 16 10:03:23 volumio systemd[1]: Started dynamicswap service. Feb 16 10:03:24 volumio systemd[1]: volumio.service holdoff time over, scheduling restart. Feb 16 10:03:24 volumio systemd[1]: Starting dynamicswap service... Feb 16 10:03:24 volumio systemd[1]: Started dynamicswap service. Feb 16 10:03:24 volumio systemd[1]: Stopping Volumio Backend Module... Feb 16 10:03:24 volumio systemd[1]: Starting Volumio Backend Module... Feb 16 10:03:24 volumio systemd[1]: Started Volumio Backend Module. Feb 16 10:03:26 volumio volumio[3501]: info: ------------------------------------------- Feb 16 10:03:26 volumio volumio[3501]: info: ----- Volumio2 ---- Feb 16 10:03:26 volumio volumio[3501]: info: ------------------------------------------- Feb 16 10:03:26 volumio volumio[3501]: info: ----- System startup ---- Feb 16 10:03:26 volumio volumio[3501]: info: ------------------------------------------- Feb 16 10:03:26 volumio volumio[3501]: info: MYVOLUMIO Environment detected Feb 16 10:03:27 volumio volumio[3501]: info: Plugin folders cleanup Feb 16 10:03:27 volumio volumio[3501]: info: Scanning into folder /volumio/app/plugins/ Feb 16 10:03:27 volumio volumio[3501]: info: Scanning category audio_interface Feb 16 10:03:27 volumio volumio[3501]: info: Scanning category miscellanea Feb 16 10:03:27 volumio volumio[3501]: info: Scanning category music_service Feb 16 10:03:27 volumio volumio[3501]: info: Scanning category plugins.json Feb 16 10:03:27 volumio volumio[3501]: info: Scanning category system_controller Feb 16 10:03:27 volumio volumio[3501]: info: Scanning category user_interface Feb 16 10:03:27 volumio volumio[3501]: info: Scanning into folder /data/plugins/ Feb 16 10:03:27 volumio volumio[3501]: info: Plugin folders cleanup completed Feb 16 10:03:27 volumio volumio[3501]: info: ------------------------------------------- Feb 16 10:03:27 volumio volumio[3501]: info: ----- Core plugins startup ---- Feb 16 10:03:27 volumio volumio[3501]: info: ------------------------------------------- Feb 16 10:03:27 volumio volumio[3501]: info: Loading plugins from folder /volumio/app/plugins/ Feb 16 10:03:27 volumio volumio[3501]: info: Adding plugin upnp to MyMusic Plugins Feb 16 10:03:27 volumio volumio[3501]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 16 10:03:27 volumio volumio[3501]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 16 10:03:27 volumio volumio[3501]: info: Loading plugins from folder /data/plugins/ Feb 16 10:03:27 volumio volumio[3501]: info: Loading plugin "system"... Feb 16 10:03:27 volumio volumio[3501]: info: Loading plugin "appearance"... Feb 16 10:03:28 volumio volumio[3501]: info: Loading plugin "network"... Feb 16 10:03:28 volumio volumio[3501]: info: Refreshing Cached IP Addresses Feb 16 10:03:28 volumio sudo[3530]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 16 10:03:28 volumio volumio[3501]: info: Loading plugin "services"... Feb 16 10:03:28 volumio volumio[3501]: info: Loading plugin "alsa_controller"... Feb 16 10:03:28 volumio sudo[3530]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 10:03:28 volumio sudo[3530]: pam_unix(sudo:session): session closed for user root Feb 16 10:03:28 volumio sudo[3532]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 16 10:03:28 volumio sudo[3532]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 10:03:28 volumio sudo[3532]: pam_unix(sudo:session): session closed for user root Feb 16 10:03:28 volumio volumio[3501]: info: Loading plugin "wizard"... Feb 16 10:03:28 volumio volumio[3501]: info: Loading plugin "volumio_command_line_client"... Feb 16 10:03:28 volumio volumio[3501]: info: Loading plugin "upnp"... Feb 16 10:03:28 volumio volumio[3501]: info: [1739700208338] Starting Upmpd Daemon Feb 16 10:03:28 volumio volumio[3501]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 16 10:03:28 volumio volumio[3501]: info: Loading plugin "my_music"... Feb 16 10:03:28 volumio volumio[3501]: info: Loading plugin "mpd"... Feb 16 10:03:28 volumio volumio[3501]: info: Loading plugin "upnp_browser"... Feb 16 10:03:28 volumio volumio-remote-updater[583]: [2025-02-16 10:03:28] [connect] Successful connection Feb 16 10:03:29 volumio volumio[3501]: info: Loading plugin "networkfs"... Feb 16 10:03:29 volumio volumio[3501]: info: Cannot mount NAS MY-Music at system boot, trial number 1 ,retrying in 5 seconds Feb 16 10:03:29 volumio volumio[3501]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 16 10:03:29 volumio volumio[3501]: info: Loading plugin "alarm-clock"... Feb 16 10:03:29 volumio volumio[3501]: info: Loading plugin "airplay_emulation"... Feb 16 10:03:29 volumio volumio[3501]: info: Starting Shairport Sync Feb 16 10:03:29 volumio volumio[3501]: info: Loading plugin "last_100"... Feb 16 10:03:29 volumio volumio[3501]: info: Loading plugin "webradio"... Feb 16 10:03:29 volumio volumio[3501]: info: Loading plugin "i2s_dacs"... Feb 16 10:03:29 volumio volumio[3501]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 16 10:03:29 volumio volumio[3501]: info: Loading plugin "volumiodiscovery"... Feb 16 10:03:29 volumio volumio[3501]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 16 10:03:29 volumio node[3501]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 16 10:03:29 volumio node[3501]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 16 10:03:29 volumio node[3501]: *** WARNING *** For more information see Feb 16 10:03:29 volumio node[3501]: *** 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:03:29 volumio node[3501]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 16 10:03:29 volumio node[3501]: *** WARNING *** For more information see Feb 16 10:03:29 volumio volumio[3501]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 16 10:03:29 volumio volumio[3501]: *** WARNING *** For more information see Feb 16 10:03:29 volumio volumio[3501]: *** 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:03:29 volumio volumio[3501]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 16 10:03:29 volumio volumio[3501]: *** WARNING *** For more information see Feb 16 10:03:29 volumio volumio[3501]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 16 10:03:29 volumio volumio[3501]: Discovery: StartAdv! undefined Feb 16 10:03:29 volumio volumio[3501]: Discovery: Started advertising... Volumio - undefined Feb 16 10:03:30 volumio volumio[3501]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 16 10:03:30 volumio volumio[3501]: info: Loading plugin "outputs"... Feb 16 10:03:30 volumio volumio[3501]: info: Loading plugin "albumart"... Feb 16 10:03:30 volumio volumio[3501]: info: Plugin example_plugin is not enabled Feb 16 10:03:30 volumio volumio[3501]: info: Loading plugin "inputs"... Feb 16 10:03:30 volumio volumio[3501]: info: Loading plugin "updater_comm"... Feb 16 10:03:30 volumio volumio[3501]: info: Plugin mpdemulation is not enabled Feb 16 10:03:30 volumio volumio[3501]: info: Loading plugin "rest_api"... Feb 16 10:03:30 volumio volumio[3501]: info: Loading plugin "websocket"... Feb 16 10:03:30 volumio volumio[3501]: info: ___________ START PLUGINS ___________ Feb 16 10:03:30 volumio volumio[3501]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 16 10:03:30 volumio volumio[3501]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 16 10:03:30 volumio volumio[3501]: info: [1739700210369] CoreMusicLibrary::Adding element Media Servers Feb 16 10:03:30 volumio volumio[3501]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 16 10:03:30 volumio volumio[3501]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 10:03:30 volumio volumio[3501]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 16 10:03:30 volumio volumio[3501]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 10:03:30 volumio volumio[3501]: Forking 3 albumart workers Feb 16 10:03:30 volumio volumio[3501]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 16 10:03:30 volumio volumio[3501]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 16 10:03:30 volumio volumio[3501]: info: [1739700210542] CoreMusicLibrary::Adding element Last_100 Feb 16 10:03:30 volumio volumio[3501]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 16 10:03:30 volumio volumio[3501]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 16 10:03:30 volumio volumio[3501]: info: [1739700210548] CoreMusicLibrary::Adding element Webradio Feb 16 10:03:30 volumio volumio[3501]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 16 10:03:30 volumio volumio[3501]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 16 10:03:30 volumio volumio[3501]: info: ------------------------------------------- Feb 16 10:03:30 volumio volumio[3501]: info: ----- MyVolumio plugins startup ---- Feb 16 10:03:30 volumio volumio[3501]: info: ------------------------------------------- Feb 16 10:03:30 volumio volumio[3501]: info: [MyVolumio PluginManager] Fetching plans data.... Feb 16 10:03:30 volumio volumio[3501]: info: Loading i18n strings for locale zh Feb 16 10:03:30 volumio volumio[3501]: Updating browse sources language Feb 16 10:03:30 volumio volumio[3501]: Cannot find translation for sourceMedia Servers Feb 16 10:03:30 volumio volumio[3501]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 16 10:03:30 volumio volumio[3501]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 16 10:03:30 volumio volumio[3501]: info: CoreCommandRouter::initPlayerControls Feb 16 10:03:30 volumio volumio[3501]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 10:03:30 volumio volumio[3501]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 10:03:30 volumio volumio[3501]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 10:03:30 volumio volumio[3501]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 10:03:30 volumio volumio[3501]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 10:03:30 volumio volumio[3501]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 10:03:30 volumio volumio[3501]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 10:03:30 volumio volumio[3501]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 16 10:03:30 volumio volumio[3501]: info: BOOT COMPLETED Feb 16 10:03:30 volumio volumio[3501]: [Metrics] CommandRouter: 4s 268.87ms Feb 16 10:03:30 volumio volumio[3501]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 16 10:03:30 volumio volumio[3501]: aplay: main:722: audio open error: Device or resource busy Feb 16 10:03:30 volumio volumio[3501]: Cannot play startup sound Feb 16 10:03:30 volumio volumio[3501]: info: CoreCommandRouter::Close All Modals sent Feb 16 10:03:30 volumio volumio[3501]: info: CoreCommandRouter::Close All Modals sent Feb 16 10:03:30 volumio volumio[3501]: Express server listening on port 3000 Feb 16 10:03:30 volumio volumio[3501]: [Metrics] WebUI: 5s 88.61ms Feb 16 10:03:30 volumio volumio[3501]: Volumio Calling Home Feb 16 10:03:30 volumio volumio[3501]: info: CoreStateMachine::resetVolumioState Feb 16 10:03:30 volumio volumio[3501]: info: CoreStateMachine::getcurrentVolume Feb 16 10:03:30 volumio volumio[3501]: info: CoreCommandRouter::volumioRetrievevolume Feb 16 10:03:31 volumio volumio[3501]: info: CoreStateMachine::pushState Feb 16 10:03:31 volumio volumio[3501]: info: CorePlayQueue::getTrack 0 Feb 16 10:03:31 volumio volumio[3501]: info: CoreCommandRouter::volumioPushState Feb 16 10:03:31 volumio volumio[3501]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 16 10:03:31 volumio volumio[3501]: info: CoreStateMachine::updateTrackBlock Feb 16 10:03:31 volumio volumio[3501]: info: CorePlayQueue::getTrackBlock Feb 16 10:03:31 volumio volumio[3501]: info: CoreCommandRouter::volumioRetrievevolume Feb 16 10:03:31 volumio volumio[3501]: info: Setting Device type: Raspberry PI Feb 16 10:03:31 volumio volumio[3501]: info: MPD running with PID1671 Feb 16 10:03:31 volumio volumio[3501]: ,establishing connection Feb 16 10:03:31 volumio volumio-remote-updater[583]: [2025-02-16 10:03:31] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.5.1" /socket.io/?EIO=4&transport=websocket&t=1739700208 101 Feb 16 10:03:31 volumio volumio[3501]: info: CoreStateMachine::pushState Feb 16 10:03:31 volumio volumio[3501]: info: CorePlayQueue::getTrack 0 Feb 16 10:03:31 volumio volumio[3501]: info: CoreCommandRouter::volumioPushState Feb 16 10:03:31 volumio volumio[3501]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 16 10:03:31 volumio volumio[3501]: info: Reloading queue from file Feb 16 10:03:31 volumio volumio[3501]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Sweety/99273d68-ebad-4e34-a75e-39fb3aff824c.jpg' Feb 16 10:03:31 volumio volumio[3501]: Starting albumart workers Feb 16 10:03:31 volumio volumio[3501]: error: Failed LSINFO: Error: [50@0] {} No such directory Feb 16 10:03:31 volumio volumio[3501]: Starting albumart workers Feb 16 10:03:31 volumio volumio[3501]: Starting albumart workers Feb 16 10:03:31 volumio volumio[3501]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Feb 16 10:03:31 volumio volumio[3501]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 16 10:03:31 volumio volumio[3501]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Feb 16 10:03:31 volumio volumio[3501]: info: CoreStateMachine::setRepeat false single undefined Feb 16 10:03:31 volumio volumio[3501]: info: CoreStateMachine::pushState Feb 16 10:03:31 volumio volumio[3501]: info: CorePlayQueue::getTrack 0 Feb 16 10:03:31 volumio volumio[3501]: info: CoreCommandRouter::volumioPushState Feb 16 10:03:31 volumio volumio[3501]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 16 10:03:31 volumio volumio[3501]: info: CoreStateMachine::setRandom true Feb 16 10:03:31 volumio volumio[3501]: info: CoreStateMachine::pushState Feb 16 10:03:31 volumio volumio[3501]: info: CorePlayQueue::getTrack 0 Feb 16 10:03:31 volumio volumio[3501]: info: CoreCommandRouter::volumioPushState Feb 16 10:03:31 volumio volumio[3501]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 16 10:03:31 volumio volumio[3501]: info: Starting Shairport Sync Feb 16 10:03:31 volumio volumio[3501]: info: Starting Shairport Sync Feb 16 10:03:32 volumio sudo[3611]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 16 10:03:32 volumio sudo[3611]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 10:03:32 volumio volumio[3501]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/jay/f3329a99-5b02-4053-9fcd-3500350f7e04.jpg' Feb 16 10:03:32 volumio sudo[3614]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 16 10:03:32 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Feb 16 10:03:32 volumio sudo[3614]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 10:03:32 volumio systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... Feb 16 10:03:32 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Feb 16 10:03:32 volumio sudo[3611]: pam_unix(sudo:session): session closed for user root Feb 16 10:03:32 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Feb 16 10:03:32 volumio systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... Feb 16 10:03:32 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Feb 16 10:03:32 volumio sudo[3614]: pam_unix(sudo:session): session closed for user root Feb 16 10:03:32 volumio volumio[3501]: info: Shairport-Sync Started Feb 16 10:03:32 volumio volumio[3501]: Error adding Membership: Error: addMembership EINVAL Feb 16 10:03:32 volumio volumio[3501]: info: Shairport-Sync Started Feb 16 10:03:32 volumio volumio[3501]: Discovery: adding c5f8b7d3-a085-448e-a003-1f587cbfbd9e Feb 16 10:03:32 volumio volumio[3501]: info: mDNS: Found device Volumio Feb 16 10:03:32 volumio volumio[3501]: info: CoreCommandRouter::volumioGetState Feb 16 10:03:32 volumio volumio[3501]: info: CorePlayQueue::getTrack 0 Feb 16 10:03:32 volumio volumio[3501]: Discovery: this is already registered, c5f8b7d3-a085-448e-a003-1f587cbfbd9e Feb 16 10:03:32 volumio volumio[3501]: info: mDNS: Found device Volumio Feb 16 10:03:32 volumio volumio[3501]: info: CoreCommandRouter::volumioGetState Feb 16 10:03:32 volumio volumio[3501]: info: CorePlayQueue::getTrack 0 Feb 16 10:03:33 volumio volumio[3501]: info: Feb 16 10:03:33 volumio volumio[3501]: ---------------------------- Client requests Volume 70 Feb 16 10:03:33 volumio volumio[3501]: info: VolumeController::SetAlsaVolume70 Feb 16 10:03:33 volumio volumio[3501]: info: CoreStateMachine::pushState Feb 16 10:03:33 volumio volumio[3501]: info: CorePlayQueue::getTrack 0 Feb 16 10:03:33 volumio volumio[3501]: info: CoreCommandRouter::volumioPushState Feb 16 10:03:33 volumio volumio[3501]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 16 10:03:33 volumio volumio[3501]: info: Setting volume on startup at 70 Feb 16 10:03:33 volumio volumio[3501]: info: CoreCommandRouter::volumioGetState Feb 16 10:03:33 volumio volumio[3501]: info: CorePlayQueue::getTrack 0 Feb 16 10:03:33 volumio volumio[3501]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 16 10:03:33 volumio volumio[3501]: info: CoreCommandRouter::volumioGetState Feb 16 10:03:33 volumio volumio[3501]: info: CorePlayQueue::getTrack 0 Feb 16 10:03:33 volumio volumio[3501]: info: CoreCommandRouter::volumioGetVisibleSources Feb 16 10:03:33 volumio volumio[3501]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 16 10:03:33 volumio volumio[3501]: info: CoreCommandRouter::volumioGetQueue Feb 16 10:03:33 volumio volumio[3501]: info: CoreStateMachine::getQueue Feb 16 10:03:33 volumio volumio[3501]: info: CorePlayQueue::getQueue Feb 16 10:03:33 volumio volumio[3501]: info: Listing playlists Feb 16 10:03:34 volumio volumio[3501]: info: Cannot mount NAS MY-Music at system boot, trial number 2 ,retrying in 5 seconds Feb 16 10:03:34 volumio volumio[3501]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 16 10:03:34 volumio volumio[3501]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 10:03:34 volumio volumio[3501]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 10:03:38 volumio sudo[3687]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 16 10:03:38 volumio sudo[3687]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 10:03:38 volumio sudo[3687]: pam_unix(sudo:session): session closed for user root Feb 16 10:03:38 volumio sudo[3690]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 16 10:03:38 volumio sudo[3690]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 10:03:38 volumio sudo[3690]: pam_unix(sudo:session): session closed for user root Feb 16 10:03:38 volumio sudo[3697]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Feb 16 10:03:38 volumio sudo[3697]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 10:03:38 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Feb 16 10:03:38 volumio sudo[3697]: pam_unix(sudo:session): session closed for user root Feb 16 10:03:38 volumio volumio[3501]: info: Upmpdcli Daemon Started Feb 16 10:03:39 volumio volumio[3501]: info: Cannot mount NAS MY-Music at system boot, trial number 3 ,retrying in 5 seconds Feb 16 10:03:39 volumio volumio[3501]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Feb 16 10:03:39 volumio volumio[3501]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Feb 16 10:03:39 volumio volumio[3501]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Feb 16 10:03:39 volumio volumio[3501]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Feb 16 10:03:39 volumio volumio[3501]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Feb 16 10:03:39 volumio volumio[3501]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Feb 16 10:03:39 volumio volumio[3501]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Feb 16 10:03:39 volumio volumio[3501]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Feb 16 10:03:39 volumio volumio[3501]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Feb 16 10:03:39 volumio volumio[3501]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Feb 16 10:03:39 volumio volumio[3501]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Feb 16 10:03:39 volumio volumio[3501]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Feb 16 10:03:39 volumio volumio[3501]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Feb 16 10:03:39 volumio volumio[3501]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Feb 16 10:03:39 volumio volumio[3501]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Feb 16 10:03:39 volumio volumio[3501]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Feb 16 10:03:39 volumio volumio[3501]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Feb 16 10:03:39 volumio volumio[3501]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Feb 16 10:03:39 volumio volumio[3501]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Feb 16 10:03:39 volumio volumio[3501]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Feb 16 10:03:39 volumio volumio[3501]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Feb 16 10:03:39 volumio volumio[3501]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Feb 16 10:03:39 volumio volumio[3501]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Feb 16 10:03:39 volumio volumio[3501]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Feb 16 10:03:39 volumio volumio[3501]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Feb 16 10:03:39 volumio volumio[3501]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Feb 16 10:03:39 volumio volumio[3501]: info: Adding plugin bluetooth to MyMusic Plugins Feb 16 10:03:39 volumio volumio[3501]: info: Adding plugin cd_controller to MyMusic Plugins Feb 16 10:03:39 volumio volumio[3501]: info: Adding plugin smart_inputs to MyMusic Plugins Feb 16 10:03:39 volumio volumio[3501]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Feb 16 10:03:40 volumio volumio[3501]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Feb 16 10:03:41 volumio volumio[3501]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Feb 16 10:03:41 volumio volumio[3501]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Feb 16 10:03:41 volumio volumio[3501]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 16 10:03:41 volumio volumio[3501]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 16 10:03:41 volumio volumio[3501]: info: Starting MyVolumio Remote Streaming Endpoints Feb 16 10:03:41 volumio volumio[3501]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Feb 16 10:03:41 volumio volumio[3501]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Feb 16 10:03:41 volumio volumio[3501]: info: Streaming services startup Feb 16 10:03:41 volumio volumio[3501]: info: Starting Streaming Daemon Feb 16 10:03:41 volumio volumio[3501]: info: MyVolumio not started Feb 16 10:03:41 volumio volumio[3501]: info: Initializing device activation check Feb 16 10:03:41 volumio sudo[3716]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 16 10:03:41 volumio sudo[3716]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 10:03:41 volumio volumio[3501]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Feb 16 10:03:41 volumio systemd[1]: Stopping Volumio Streaming Daemon... Feb 16 10:03:41 volumio systemd[1]: Starting Volumio Streaming Daemon... Feb 16 10:03:41 volumio systemd[1]: Started Volumio Streaming Daemon. Feb 16 10:03:41 volumio sudo[3716]: pam_unix(sudo:session): session closed for user root Feb 16 10:03:41 volumio volumio-streaming-daemon[3722]: ############################ Feb 16 10:03:41 volumio volumio-streaming-daemon[3722]: # Volumio Streaming Daemon # Feb 16 10:03:41 volumio volumio-streaming-daemon[3722]: # Running on port 7777 # Feb 16 10:03:41 volumio volumio-streaming-daemon[3722]: ############################ Feb 16 10:03:41 volumio volumio-streaming-daemon[3722]: Environment: production Feb 16 10:03:41 volumio volumio[3501]: 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:03:41 volumio volumio[3501]: % Total % Received % Xferd Average Speed Time Time Time Current Feb 16 10:03:41 volumio volumio[3501]: Dload Upload Total Spent Left Speed Feb 16 10:03:41 volumio volumio[3501]: [235B blob data] Feb 16 10:03:41 volumio volumio[3501]: retrying in 5 seconds, trial 0 Feb 16 10:03:41 volumio volumio[3501]: Volumio Calling Home Feb 16 10:03:43 volumio volumio-streaming-daemon[3722]: Environment: production Feb 16 10:03:43 volumio volumio[3501]: info: Fetching Streaming Services browse cache Feb 16 10:03:43 volumio volumio[3501]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesTIDAL Feb 16 10:03:43 volumio volumio[3501]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 16 10:03:43 volumio volumio[3501]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesQOBUZ Feb 16 10:03:43 volumio volumio[3501]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 16 10:03:44 volumio volumio[3501]: info: Cannot mount NAS MY-Music at system boot, trial number 4 ,retrying in 5 seconds Feb 16 10:03:45 volumio volumio[3501]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 16 10:03:45 volumio volumio[3501]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Feb 16 10:03:45 volumio volumio[3501]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 16 10:03:45 volumio volumio[3501]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 16 10:03:45 volumio volumio[3501]: info: Getting Alsa Cards List without I2S DAC Feb 16 10:03:45 volumio volumio[3501]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Feb 16 10:03:45 volumio volumio[3501]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 16 10:03:45 volumio volumio[3501]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 16 10:03:45 volumio volumio[3501]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 16 10:03:45 volumio volumio[3501]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 16 10:03:45 volumio volumio[3501]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 16 10:03:45 volumio volumio[3501]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 16 10:03:45 volumio volumio[3501]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 16 10:03:45 volumio volumio[3501]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 16 10:03:45 volumio volumio[3501]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Feb 16 10:03:45 volumio volumio[3501]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Feb 16 10:03:49 volumio volumio[3501]: info: Cannot mount NAS at system boot, trial number 4 ,stopping Feb 16 10:03:51 volumio volumio[3501]: 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:03:51 volumio volumio[3501]: % Total % Received % Xferd Average Speed Time Time Time Current Feb 16 10:03:51 volumio volumio[3501]: Dload Upload Total Spent Left Speed Feb 16 10:03:51 volumio volumio[3501]: 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0curl: (7) Failed to connect to updates.volumio.org port 7070: Connection refused Feb 16 10:03:51 volumio volumio[3501]: retrying in 5 seconds, trial 1 Feb 16 10:03:51 volumio volumio[3501]: Volumio Calling Home Feb 16 10:03:52 volumio volumio[3501]: info: CALLMETHOD: audio_interface alsa_controller saveVolumeOptions [object Object] Feb 16 10:03:52 volumio volumio[3501]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveVolumeOptions Feb 16 10:03:52 volumio volumio[3501]: info: CoreCommandRouter::volumioGetState Feb 16 10:03:52 volumio volumio[3501]: info: CorePlayQueue::getTrack 0 Feb 16 10:03:52 volumio volumio[3501]: info: Restoring Previous Volume level: 100 false false Feb 16 10:03:52 volumio volumio[3501]: info: Disable softmixer device for audio device Feb 16 10:03:52 volumio volumio[3501]: info: Output device has changed, restarting MPD Feb 16 10:03:52 volumio sudo[3771]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 16 10:03:52 volumio volumio[3501]: info: Output device has changed, restarting Shairport Sync Feb 16 10:03:52 volumio sudo[3771]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 10:03:52 volumio sudo[3771]: pam_unix(sudo:session): session closed for user root Feb 16 10:03:52 volumio sudo[3774]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 16 10:03:52 volumio sudo[3774]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 10:03:52 volumio systemd[1]: Stopping Music Player Daemon... Feb 16 10:03:52 volumio volumio[3501]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 16 10:03:52 volumio volumio[3501]: info: Volume configurations have been set Feb 16 10:03:52 volumio volumio[3501]: info: CoreCommandRouter::volumioUpdateVolumeSettings Feb 16 10:03:52 volumio volumio[3501]: info: Updating Volume Controller Parameters: Device: 2 Name: Hifiberry DAC Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 10 Feb 16 10:03:52 volumio volumio[3501]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 16 10:03:52 volumio volumio[3501]: info: CoreStateMachine::pushState Feb 16 10:03:52 volumio volumio[3501]: info: CorePlayQueue::getTrack 0 Feb 16 10:03:52 volumio volumio[3501]: info: CoreCommandRouter::volumioPushState Feb 16 10:03:52 volumio volumio[3501]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 16 10:03:52 volumio volumio[3501]: info: MPD Permissions set Feb 16 10:03:52 volumio volumio[3501]: info: Asound.conf file written Feb 16 10:03:52 volumio sudo[3792]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Feb 16 10:03:52 volumio sudo[3792]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 10:03:52 volumio sudo[3792]: pam_unix(sudo:session): session closed for user root Feb 16 10:03:52 volumio systemd[1]: Starting Music Player Daemon... Feb 16 10:03:53 volumio volumio[3501]: info: CoreCommandRouter::volumioUpdateVolumeSettings Feb 16 10:03:53 volumio volumio[3501]: info: Updating Volume Controller Parameters: Device: 2 Name: Hifiberry DAC Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 10 Feb 16 10:03:53 volumio volumio[3501]: info: CoreStateMachine::pushState Feb 16 10:03:53 volumio volumio[3501]: info: CorePlayQueue::getTrack 0 Feb 16 10:03:53 volumio volumio[3501]: info: CoreCommandRouter::volumioPushState Feb 16 10:03:53 volumio volumio[3501]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 16 10:03:53 volumio volumio[3501]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 10:03:53 volumio volumio[3501]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 10:03:53 volumio volumio[3501]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 10:03:53 volumio volumio[3501]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 10:03:53 volumio volumio[3501]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 10:03:53 volumio volumio[3501]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 10:03:53 volumio volumio[3501]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 10:03:53 volumio volumio[3501]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 16 10:03:53 volumio volumio[3501]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 10:03:53 volumio volumio[3501]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Feb 16 10:03:53 volumio volumio[3501]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 16 10:03:53 volumio volumio[3501]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 16 10:03:53 volumio volumio[3501]: info: Getting Alsa Cards List without I2S DAC Feb 16 10:03:53 volumio volumio[3501]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Feb 16 10:03:53 volumio volumio[3501]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 16 10:03:53 volumio mpd[3802]: output: No 'AudioOutput' defined in config file Feb 16 10:03:53 volumio mpd[3802]: output: Attempt to detect audio output device Feb 16 10:03:53 volumio mpd[3802]: output: Attempting to detect a alsa audio device Feb 16 10:03:53 volumio mpd[3802]: output: Successfully detected a alsa audio device Feb 16 10:03:53 volumio systemd[1]: Started Music Player Daemon. Feb 16 10:03:53 volumio sudo[3774]: pam_unix(sudo:session): session closed for user root Feb 16 10:03:53 volumio volumio[3501]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 16 10:03:53 volumio volumio[3501]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 16 10:03:53 volumio volumio[3501]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 16 10:03:53 volumio volumio[3501]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 16 10:03:53 volumio volumio[3501]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 16 10:03:53 volumio volumio[3501]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 16 10:03:53 volumio volumio[3501]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 16 10:03:53 volumio volumio[3501]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Feb 16 10:03:53 volumio volumio[3501]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Feb 16 10:03:53 volumio volumio[3501]: info: Starting Shairport Sync Feb 16 10:03:53 volumio volumio[3501]: error: updateQueue error: null Feb 16 10:03:53 volumio volumio[3501]: error: Upnp client error: Error: This socket has been ended by the other party Feb 16 10:03:53 volumio sudo[3828]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 16 10:03:53 volumio sudo[3828]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 10:03:53 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Feb 16 10:03:53 volumio systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... Feb 16 10:03:53 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Feb 16 10:03:53 volumio sudo[3828]: pam_unix(sudo:session): session closed for user root Feb 16 10:03:53 volumio volumio[3501]: info: Shairport-Sync Started Feb 16 10:03:55 volumio volumio[3501]: info: CoreCommandRouter::volumioPlay Feb 16 10:03:55 volumio volumio[3501]: UNSET VOLATILE Feb 16 10:03:55 volumio volumio[3501]: info: CoreStateMachine::play index undefined Feb 16 10:03:55 volumio volumio[3501]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 16 10:03:55 volumio volumio[3501]: info: CorePlayQueue::getTrack 0 Feb 16 10:03:55 volumio volumio[3501]: info: CoreStateMachine::startPlaybackTimer Feb 16 10:03:55 volumio volumio[3501]: info: CorePlayQueue::getTrack 0 Feb 16 10:03:55 volumio volumio[3501]: verbose: ControllerMpd::clearAddPlayTracks NAS/MY-Music/日语/川嶋あい - 雪に咲く花.flac Feb 16 10:03:55 volumio volumio[3501]: verbose: ControllerMpd::sendMpdCommand stop Feb 16 10:03:55 volumio volumio[3501]: verbose: ControllerMpd::sendMpdCommand clear Feb 16 10:03:55 volumio volumio[3501]: info: Feb 16 10:03:55 volumio volumio[3501]: ---------------------------- MPD announces system playlist update Feb 16 10:03:55 volumio volumio[3501]: info: Ignoring MPD Status Update Feb 16 10:03:55 volumio volumio[3501]: verbose: ControllerMpd::sendMpdCommand add "NAS/MY-Music/日语/川嶋あい - 雪に咲く花.flac" Feb 16 10:03:55 volumio volumio[3501]: error: updateQueue error: null Feb 16 10:03:55 volumio volumio[3501]: info: ------------------------------ 4ms Feb 16 10:03:57 volumio volumio[3501]: info: VolumeController::SetAlsaVolume100 Feb 16 10:03:57 volumio volumio[3501]: info: CoreStateMachine::pushState Feb 16 10:03:57 volumio volumio[3501]: info: CorePlayQueue::getTrack 0 Feb 16 10:03:57 volumio volumio[3501]: info: CoreCommandRouter::volumioPushState Feb 16 10:03:57 volumio volumio[3501]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 16 10:04:00 volumio volumio[3501]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 16 10:04:00 volumio volumio[3501]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 16 10:04:01 volumio volumio[3501]: 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:04:01 volumio volumio[3501]: % Total % Received % Xferd Average Speed Time Time Time Current Feb 16 10:04:01 volumio volumio[3501]: Dload Upload Total Spent Left Speed Feb 16 10:04:01 volumio volumio[3501]: 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0curl: (7) Failed to connect to updates.volumio.org port 7070: Connection refused Feb 16 10:04:01 volumio volumio[3501]: retrying in 5 seconds, trial 2 Feb 16 10:04:01 volumio volumio[3501]: Volumio Calling Home Feb 16 10:04:02 volumio volumio[3501]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Feb 16 10:04:02 volumio volumio[3501]: info: CURURI: playlists Feb 16 10:04:02 volumio volumio[3501]: info: Listing playlists Feb 16 10:04:04 volumio volumio[3501]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Feb 16 10:04:04 volumio volumio[3501]: info: CURURI: music-library Feb 16 10:04:04 volumio volumio[3501]: error: Failed LSINFO: Error: [50@0] {} No database Feb 16 10:04:06 volumio volumio[3501]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 16 10:04:06 volumio volumio[3501]: { Error: connect ETIMEDOUT 162.125.2.5:80 Feb 16 10:04:06 volumio volumio[3501]: at Object._errnoException (util.js:1022:11) Feb 16 10:04:06 volumio volumio[3501]: at _exceptionWithHostPort (util.js:1044:20) Feb 16 10:04:06 volumio volumio[3501]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1198:14) Feb 16 10:04:06 volumio volumio[3501]: code: 'ETIMEDOUT', Feb 16 10:04:06 volumio volumio[3501]: errno: 'ETIMEDOUT', Feb 16 10:04:06 volumio volumio[3501]: syscall: 'connect', Feb 16 10:04:06 volumio volumio[3501]: address: '162.125.2.5', Feb 16 10:04:06 volumio volumio[3501]: port: 80 } Feb 16 10:04:06 volumio volumio[3501]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 16 10:04:06 volumio sudo[3861]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-02-16 10:03 Feb 16 10:04:06 volumio sudo[3861]: 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"