-- Logs begin at Mon 2025-02-24 12:04:02 UTC, end at Mon 2025-02-24 12:11:00 UTC. -- Feb 24 12:10:00 volumio volumio[3428]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=2.729&uuid=a11842f23a3ba331cfb7d39c87a90e38" http://updates.volumio.org:7070/downloader-v1/track-device Feb 24 12:10:00 volumio volumio[3428]: % Total % Received % Xferd Average Speed Time Time Time Current Feb 24 12:10:00 volumio volumio[3428]: Dload Upload Total Spent Left Speed Feb 24 12:10:00 volumio volumio[3428]: [235B blob data] Feb 24 12:10:00 volumio volumio[3428]: retrying in 5 seconds, trial 1 Feb 24 12:10:00 volumio volumio[3428]: Volumio Calling Home Feb 24 12:10:08 volumio volumio[3428]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 24 12:10:08 volumio volumio[3428]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 24 12:10:11 volumio volumio[3428]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=2.729&uuid=a11842f23a3ba331cfb7d39c87a90e38" http://updates.volumio.org:7070/downloader-v1/track-device Feb 24 12:10:11 volumio volumio[3428]: % Total % Received % Xferd Average Speed Time Time Time Current Feb 24 12:10:11 volumio volumio[3428]: Dload Upload Total Spent Left Speed Feb 24 12:10:11 volumio volumio[3428]: 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0curl: (7) Failed to connect to updates.volumio.org port 7070: Connection refused Feb 24 12:10:11 volumio volumio[3428]: retrying in 5 seconds, trial 2 Feb 24 12:10:11 volumio volumio[3428]: Volumio Calling Home Feb 24 12:10:14 volumio volumio[3428]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%C2%B2%C3%9D%C3%B2%C3%AC/74c70aad-2b25-4f67-9bdc-e94079033942.jpg' Feb 24 12:10:14 volumio volumio[3428]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%C2%B3%C3%82%C2%B0%C3%99%C3%87%C2%BF/ee943fbe-324e-417f-852a-a5d05de5b8d6.jpg' Feb 24 12:10:15 volumio volumio[3428]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 24 12:10:15 volumio volumio[3428]: { Error: connect ETIMEDOUT 108.160.162.98:80 Feb 24 12:10:15 volumio volumio[3428]: at Object._errnoException (util.js:1022:11) Feb 24 12:10:15 volumio volumio[3428]: at _exceptionWithHostPort (util.js:1044:20) Feb 24 12:10:15 volumio volumio[3428]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1198:14) Feb 24 12:10:15 volumio volumio[3428]: code: 'ETIMEDOUT', Feb 24 12:10:15 volumio volumio[3428]: errno: 'ETIMEDOUT', Feb 24 12:10:15 volumio volumio[3428]: syscall: 'connect', Feb 24 12:10:15 volumio volumio[3428]: address: '108.160.162.98', Feb 24 12:10:15 volumio volumio[3428]: port: 80 } Feb 24 12:10:15 volumio volumio[3428]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 24 12:10:16 volumio sudo[3768]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-02-24 12:09 Feb 24 12:10:16 volumio sudo[3768]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 12:10:16 volumio sudo[3768]: pam_unix(sudo:session): session closed for user root Feb 24 12:10:16 volumio volumio-remote-updater[739]: [2025-02-24 12:10:16] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Feb 24 12:10:16 volumio volumio-remote-updater[739]: [2025-02-24 12:10:16] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Feb 24 12:10:16 volumio systemd[1]: volumio.service: main process exited, code=exited, status=1/FAILURE Feb 24 12:10:16 volumio systemd[1]: Unit volumio.service entered failed state. Feb 24 12:10:16 volumio systemd[1]: Starting dynamicswap service... Feb 24 12:10:16 volumio systemd[1]: Started dynamicswap service. Feb 24 12:10:16 volumio systemd[1]: volumio.service holdoff time over, scheduling restart. Feb 24 12:10:16 volumio systemd[1]: Starting dynamicswap service... Feb 24 12:10:16 volumio systemd[1]: Started dynamicswap service. Feb 24 12:10:16 volumio systemd[1]: Stopping Volumio Backend Module... Feb 24 12:10:16 volumio systemd[1]: Starting Volumio Backend Module... Feb 24 12:10:16 volumio systemd[1]: Started Volumio Backend Module. Feb 24 12:10:18 volumio volumio[3789]: info: ------------------------------------------- Feb 24 12:10:18 volumio volumio[3789]: info: ----- Volumio2 ---- Feb 24 12:10:18 volumio volumio[3789]: info: ------------------------------------------- Feb 24 12:10:18 volumio volumio[3789]: info: ----- System startup ---- Feb 24 12:10:18 volumio volumio[3789]: info: ------------------------------------------- Feb 24 12:10:18 volumio volumio[3789]: info: MYVOLUMIO Environment detected Feb 24 12:10:19 volumio volumio[3789]: info: Plugin folders cleanup Feb 24 12:10:19 volumio volumio[3789]: info: Scanning into folder /volumio/app/plugins/ Feb 24 12:10:19 volumio volumio[3789]: info: Scanning category audio_interface Feb 24 12:10:19 volumio volumio[3789]: info: Scanning category miscellanea Feb 24 12:10:19 volumio volumio[3789]: info: Scanning category music_service Feb 24 12:10:19 volumio volumio[3789]: info: Scanning category plugins.json Feb 24 12:10:19 volumio volumio[3789]: info: Scanning category system_controller Feb 24 12:10:19 volumio volumio[3789]: info: Scanning category user_interface Feb 24 12:10:19 volumio volumio[3789]: info: Scanning into folder /data/plugins/ Feb 24 12:10:19 volumio volumio[3789]: info: Scanning category miscellanea Feb 24 12:10:19 volumio volumio[3789]: info: Scanning category music_service Feb 24 12:10:19 volumio volumio[3789]: info: Plugin folders cleanup completed Feb 24 12:10:19 volumio volumio[3789]: info: ------------------------------------------- Feb 24 12:10:19 volumio volumio[3789]: info: ----- Core plugins startup ---- Feb 24 12:10:19 volumio volumio[3789]: info: ------------------------------------------- Feb 24 12:10:19 volumio volumio[3789]: info: Loading plugins from folder /volumio/app/plugins/ Feb 24 12:10:19 volumio volumio[3789]: info: Adding plugin upnp to MyMusic Plugins Feb 24 12:10:19 volumio volumio[3789]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 24 12:10:19 volumio volumio[3789]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 24 12:10:19 volumio volumio[3789]: info: Loading plugins from folder /data/plugins/ Feb 24 12:10:19 volumio volumio[3789]: info: Loading plugin "system"... Feb 24 12:10:19 volumio volumio[3789]: info: Loading plugin "appearance"... Feb 24 12:10:20 volumio volumio[3789]: info: Loading plugin "network"... Feb 24 12:10:20 volumio volumio[3789]: info: Loading plugin "services"... Feb 24 12:10:20 volumio volumio[3789]: info: Loading plugin "alsa_controller"... Feb 24 12:10:20 volumio volumio[3789]: info: Loading plugin "wizard"... Feb 24 12:10:20 volumio volumio[3789]: info: Loading plugin "volumio_command_line_client"... Feb 24 12:10:20 volumio volumio[3789]: info: Loading plugin "upnp"... Feb 24 12:10:20 volumio volumio[3789]: info: [1740399020324] Starting Upmpd Daemon Feb 24 12:10:20 volumio volumio[3789]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 24 12:10:20 volumio volumio[3789]: info: Loading plugin "my_music"... Feb 24 12:10:20 volumio volumio[3789]: info: Loading plugin "mpd"... Feb 24 12:10:20 volumio volumio[3789]: info: Loading plugin "upnp_browser"... Feb 24 12:10:21 volumio volumio[3789]: info: Loading plugin "networkfs"... Feb 24 12:10:21 volumio volumio[3789]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 24 12:10:21 volumio volumio[3789]: info: Loading plugin "alarm-clock"... Feb 24 12:10:21 volumio volumio[3789]: info: Loading plugin "airplay_emulation"... Feb 24 12:10:21 volumio volumio[3789]: info: Starting Shairport Sync Feb 24 12:10:21 volumio volumio[3789]: info: Loading plugin "last_100"... Feb 24 12:10:21 volumio volumio[3789]: info: Loading plugin "webradio"... Feb 24 12:10:21 volumio volumio-remote-updater[739]: [2025-02-24 12:10:21] [connect] Successful connection Feb 24 12:10:21 volumio volumio[3789]: info: Loading plugin "i2s_dacs"... Feb 24 12:10:21 volumio volumio[3789]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 24 12:10:21 volumio volumio[3789]: info: Loading plugin "volumiodiscovery"... Feb 24 12:10:21 volumio volumio[3789]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 24 12:10:21 volumio node[3789]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 24 12:10:21 volumio node[3789]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 24 12:10:21 volumio node[3789]: *** WARNING *** For more information see Feb 24 12:10:21 volumio node[3789]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 24 12:10:21 volumio node[3789]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 24 12:10:21 volumio node[3789]: *** WARNING *** For more information see Feb 24 12:10:21 volumio volumio[3789]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 24 12:10:21 volumio volumio[3789]: *** WARNING *** For more information see Feb 24 12:10:21 volumio volumio[3789]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 24 12:10:21 volumio volumio[3789]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 24 12:10:21 volumio volumio[3789]: *** WARNING *** For more information see Feb 24 12:10:21 volumio volumio[3789]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 24 12:10:21 volumio volumio[3789]: Discovery: StartAdv! undefined Feb 24 12:10:21 volumio volumio[3789]: Discovery: Started advertising... Volumio - undefined Feb 24 12:10:21 volumio volumio[3789]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 24 12:10:21 volumio volumio[3789]: info: Loading plugin "autostart"... Feb 24 12:10:21 volumio volumio[3789]: info: Applying required configuration parameters for plugin autostart Feb 24 12:10:21 volumio volumio[3789]: info: Loading plugin "outputs"... Feb 24 12:10:21 volumio volumio[3789]: info: Loading plugin "albumart"... Feb 24 12:10:21 volumio volumio[3789]: info: Plugin example_plugin is not enabled Feb 24 12:10:21 volumio volumio[3789]: info: Loading plugin "inputs"... Feb 24 12:10:21 volumio volumio[3789]: info: Loading plugin "updater_comm"... Feb 24 12:10:22 volumio volumio[3789]: info: Plugin mpdemulation is not enabled Feb 24 12:10:22 volumio volumio[3789]: info: Loading plugin "rest_api"... Feb 24 12:10:22 volumio volumio[3789]: info: Loading plugin "websocket"... Feb 24 12:10:22 volumio volumio[3789]: info: Plugin lms is not enabled Feb 24 12:10:22 volumio volumio[3789]: info: Plugin squeezelite is not enabled Feb 24 12:10:22 volumio volumio[3789]: info: ___________ START PLUGINS ___________ Feb 24 12:10:22 volumio volumio[3789]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 24 12:10:22 volumio volumio[3789]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 24 12:10:22 volumio volumio[3789]: info: [1740399022091] CoreMusicLibrary::Adding element Media Servers Feb 24 12:10:22 volumio volumio[3789]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 24 12:10:22 volumio volumio[3789]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 24 12:10:22 volumio volumio[3789]: Forking 3 albumart workers Feb 24 12:10:22 volumio volumio[3789]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 24 12:10:22 volumio volumio[3789]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 24 12:10:22 volumio volumio[3789]: info: [1740399022221] CoreMusicLibrary::Adding element Last_100 Feb 24 12:10:22 volumio volumio[3789]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 24 12:10:22 volumio volumio[3789]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 24 12:10:22 volumio volumio[3789]: info: [1740399022227] CoreMusicLibrary::Adding element Webradio Feb 24 12:10:22 volumio volumio[3789]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 24 12:10:22 volumio volumio[3789]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 24 12:10:22 volumio volumio[3789]: info: ------------------------------------------- Feb 24 12:10:22 volumio volumio[3789]: info: ----- MyVolumio plugins startup ---- Feb 24 12:10:22 volumio volumio[3789]: info: ------------------------------------------- Feb 24 12:10:22 volumio volumio[3789]: info: [MyVolumio PluginManager] Fetching plans data.... Feb 24 12:10:22 volumio volumio[3789]: info: Loading i18n strings for locale zh Feb 24 12:10:22 volumio volumio[3789]: Updating browse sources language Feb 24 12:10:22 volumio volumio[3789]: Cannot find translation for sourceMedia Servers Feb 24 12:10:22 volumio volumio[3789]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 24 12:10:22 volumio volumio[3789]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 24 12:10:22 volumio volumio[3789]: info: CoreCommandRouter::initPlayerControls Feb 24 12:10:22 volumio volumio[3789]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 12:10:22 volumio volumio[3789]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAlsaCards Feb 24 12:10:22 volumio volumio[3789]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 12:10:22 volumio volumio[3789]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 12:10:22 volumio volumio[3789]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 12:10:22 volumio volumio[3789]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 12:10:22 volumio volumio[3789]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 12:10:22 volumio volumio[3789]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 24 12:10:22 volumio volumio[3789]: info: BOOT COMPLETED Feb 24 12:10:22 volumio volumio[3789]: [Metrics] CommandRouter: 3s 975.27ms Feb 24 12:10:22 volumio volumio[3789]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 24 12:10:22 volumio volumio[3789]: aplay: main:722: audio open error: Device or resource busy Feb 24 12:10:22 volumio volumio[3789]: Cannot play startup sound Feb 24 12:10:22 volumio volumio[3789]: info: CoreCommandRouter::Close All Modals sent Feb 24 12:10:22 volumio volumio[3789]: info: CoreCommandRouter::Close All Modals sent Feb 24 12:10:22 volumio volumio[3789]: Express server listening on port 3000 Feb 24 12:10:22 volumio volumio[3789]: [Metrics] WebUI: 4s 390.43ms Feb 24 12:10:22 volumio volumio[3789]: Volumio Calling Home Feb 24 12:10:22 volumio volumio[3789]: info: CoreStateMachine::resetVolumioState Feb 24 12:10:22 volumio volumio[3789]: info: CoreStateMachine::getcurrentVolume Feb 24 12:10:22 volumio volumio[3789]: info: CoreCommandRouter::volumioRetrievevolume Feb 24 12:10:22 volumio volumio[3789]: info: CoreStateMachine::pushState Feb 24 12:10:22 volumio volumio[3789]: info: CoreStateMachine::getState Feb 24 12:10:22 volumio volumio[3789]: info: CorePlayQueue::getTrack 0 Feb 24 12:10:22 volumio volumio[3789]: info: CoreCommandRouter::volumioPushState Feb 24 12:10:22 volumio volumio[3789]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 12:10:22 volumio volumio[3789]: info: Setting Device type: Raspberry PI Feb 24 12:10:22 volumio volumio[3789]: info: MPD running with PID804 ,establishing connection Feb 24 12:10:23 volumio volumio-remote-updater[739]: [2025-02-24 12:10:23] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.5.1" /socket.io/?EIO=4&transport=websocket&t=1740399021 101 Feb 24 12:10:23 volumio volumio[3789]: Starting albumart workers Feb 24 12:10:23 volumio volumio[3789]: Starting albumart workers Feb 24 12:10:23 volumio volumio[3789]: Starting albumart workers Feb 24 12:10:23 volumio volumio[3789]: info: Reloading queue from file Feb 24 12:10:23 volumio volumio[3789]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Feb 24 12:10:23 volumio volumio[3789]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Feb 24 12:10:23 volumio volumio[3789]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Feb 24 12:10:23 volumio volumio[3789]: info: CoreStateMachine::setRepeat null single undefined Feb 24 12:10:23 volumio volumio[3789]: info: CoreStateMachine::pushState Feb 24 12:10:23 volumio volumio[3789]: info: CoreStateMachine::getState Feb 24 12:10:23 volumio volumio[3789]: info: CorePlayQueue::getTrack 0 Feb 24 12:10:23 volumio volumio[3789]: info: CoreCommandRouter::volumioPushState Feb 24 12:10:23 volumio volumio[3789]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 12:10:23 volumio volumio[3789]: info: CoreStateMachine::setRandom true Feb 24 12:10:23 volumio volumio[3789]: info: CoreStateMachine::pushState Feb 24 12:10:23 volumio volumio[3789]: info: CoreStateMachine::getState Feb 24 12:10:23 volumio volumio[3789]: info: CorePlayQueue::getTrack 0 Feb 24 12:10:23 volumio volumio[3789]: info: CoreCommandRouter::volumioPushState Feb 24 12:10:23 volumio volumio[3789]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 12:10:23 volumio volumio[3789]: info: Starting Shairport Sync Feb 24 12:10:23 volumio volumio[3789]: info: Starting Shairport Sync Feb 24 12:10:23 volumio sudo[3880]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 24 12:10:23 volumio sudo[3880]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 12:10:23 volumio sudo[3883]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 24 12:10:23 volumio systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... Feb 24 12:10:23 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Feb 24 12:10:23 volumio sudo[3883]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 12:10:23 volumio systemd[3889]: Failed at step USER spawning /usr/local/bin/shairport-sync: No such process Feb 24 12:10:23 volumio sudo[3880]: pam_unix(sudo:session): session closed for user root Feb 24 12:10:23 volumio systemd[1]: shairport-sync.service: main process exited, code=exited, status=217/USER Feb 24 12:10:23 volumio systemd[1]: Unit shairport-sync.service entered failed state. Feb 24 12:10:23 volumio systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... Feb 24 12:10:23 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Feb 24 12:10:23 volumio systemd[3896]: Failed at step USER spawning /usr/local/bin/shairport-sync: No such process Feb 24 12:10:23 volumio systemd[1]: shairport-sync.service: main process exited, code=exited, status=217/USER Feb 24 12:10:23 volumio sudo[3883]: pam_unix(sudo:session): session closed for user root Feb 24 12:10:23 volumio systemd[1]: Unit shairport-sync.service entered failed state. Feb 24 12:10:24 volumio volumio[3789]: info: Shairport-Sync Started Feb 24 12:10:24 volumio volumio[3789]: Error adding Membership: Error: addMembership EINVAL Feb 24 12:10:24 volumio volumio[3789]: info: Shairport-Sync Started Feb 24 12:10:24 volumio volumio[3789]: Discovery: adding 963c78eb-b0ac-49c3-8afe-eef11e3e8641 Feb 24 12:10:24 volumio volumio[3789]: info: mDNS: Found device Volumio Feb 24 12:10:24 volumio volumio[3789]: info: CoreCommandRouter::volumioGetState Feb 24 12:10:24 volumio volumio[3789]: info: CoreStateMachine::getState Feb 24 12:10:24 volumio volumio[3789]: info: CorePlayQueue::getTrack 0 Feb 24 12:10:24 volumio sudo[3903]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 24 12:10:24 volumio sudo[3903]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 12:10:24 volumio sudo[3903]: pam_unix(sudo:session): session closed for user root Feb 24 12:10:24 volumio sudo[3907]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 24 12:10:24 volumio sudo[3907]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 12:10:24 volumio volumio[3789]: Discovery: this is already registered, 963c78eb-b0ac-49c3-8afe-eef11e3e8641 Feb 24 12:10:24 volumio volumio[3789]: info: mDNS: Found device Volumio Feb 24 12:10:24 volumio volumio[3789]: info: CoreCommandRouter::volumioGetState Feb 24 12:10:24 volumio volumio[3789]: info: CoreStateMachine::getState Feb 24 12:10:24 volumio volumio[3789]: info: CorePlayQueue::getTrack 0 Feb 24 12:10:24 volumio sudo[3907]: pam_unix(sudo:session): session closed for user root Feb 24 12:10:24 volumio sudo[3921]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 24 12:10:24 volumio sudo[3921]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 12:10:24 volumio sudo[3921]: pam_unix(sudo:session): session closed for user root Feb 24 12:10:24 volumio sudo[3925]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 24 12:10:24 volumio sudo[3925]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 12:10:24 volumio sudo[3925]: pam_unix(sudo:session): session closed for user root Feb 24 12:10:25 volumio volumio[3789]: info: CoreCommandRouter::volumioGetState Feb 24 12:10:25 volumio volumio[3789]: info: CoreStateMachine::getState Feb 24 12:10:25 volumio volumio[3789]: info: CorePlayQueue::getTrack 0 Feb 24 12:10:25 volumio sudo[3941]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 24 12:10:25 volumio sudo[3941]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 12:10:25 volumio sudo[3941]: pam_unix(sudo:session): session closed for user root Feb 24 12:10:27 volumio volumio[3789]: info: CoreCommandRouter::volumioGetState Feb 24 12:10:27 volumio volumio[3789]: info: CoreStateMachine::getState Feb 24 12:10:27 volumio volumio[3789]: info: CorePlayQueue::getTrack 0 Feb 24 12:10:27 volumio sudo[3954]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 24 12:10:27 volumio sudo[3954]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 12:10:27 volumio sudo[3954]: pam_unix(sudo:session): session closed for user root Feb 24 12:10:27 volumio sudo[3960]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 24 12:10:27 volumio volumio[3789]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 24 12:10:27 volumio volumio[3789]: info: CoreCommandRouter::volumioGetVisibleSources Feb 24 12:10:27 volumio volumio[3789]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 24 12:10:27 volumio sudo[3960]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 12:10:27 volumio sudo[3960]: pam_unix(sudo:session): session closed for user root Feb 24 12:10:27 volumio volumio[3789]: info: Listing playlists Feb 24 12:10:27 volumio volumio[3789]: info: CoreCommandRouter::volumioGetQueue Feb 24 12:10:27 volumio volumio[3789]: info: CoreStateMachine::getQueue Feb 24 12:10:27 volumio volumio[3789]: info: CorePlayQueue::getQueue Feb 24 12:10:30 volumio sudo[3978]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 24 12:10:30 volumio sudo[3978]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 12:10:30 volumio sudo[3978]: pam_unix(sudo:session): session closed for user root Feb 24 12:10:30 volumio sudo[3985]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Feb 24 12:10:30 volumio sudo[3985]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 12:10:30 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Feb 24 12:10:30 volumio sudo[3985]: pam_unix(sudo:session): session closed for user root Feb 24 12:10:30 volumio volumio[3789]: info: Upmpdcli Daemon Started Feb 24 12:10:31 volumio volumio[3789]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Feb 24 12:10:31 volumio volumio[3789]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Feb 24 12:10:31 volumio volumio[3789]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Feb 24 12:10:31 volumio volumio[3789]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Feb 24 12:10:31 volumio volumio[3789]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Feb 24 12:10:31 volumio volumio[3789]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Feb 24 12:10:31 volumio volumio[3789]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Feb 24 12:10:31 volumio volumio[3789]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Feb 24 12:10:31 volumio volumio[3789]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Feb 24 12:10:31 volumio volumio[3789]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Feb 24 12:10:31 volumio volumio[3789]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Feb 24 12:10:31 volumio volumio[3789]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Feb 24 12:10:31 volumio volumio[3789]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Feb 24 12:10:31 volumio volumio[3789]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Feb 24 12:10:31 volumio volumio[3789]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Feb 24 12:10:31 volumio volumio[3789]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Feb 24 12:10:31 volumio volumio[3789]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Feb 24 12:10:31 volumio volumio[3789]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Feb 24 12:10:31 volumio volumio[3789]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Feb 24 12:10:31 volumio volumio[3789]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Feb 24 12:10:31 volumio volumio[3789]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Feb 24 12:10:31 volumio volumio[3789]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Feb 24 12:10:31 volumio volumio[3789]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Feb 24 12:10:31 volumio volumio[3789]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Feb 24 12:10:31 volumio volumio[3789]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Feb 24 12:10:31 volumio volumio[3789]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Feb 24 12:10:31 volumio volumio[3789]: info: Adding plugin bluetooth to MyMusic Plugins Feb 24 12:10:31 volumio volumio[3789]: info: Adding plugin cd_controller to MyMusic Plugins Feb 24 12:10:31 volumio volumio[3789]: info: Adding plugin smart_inputs to MyMusic Plugins Feb 24 12:10:31 volumio volumio[3789]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Feb 24 12:10:33 volumio volumio[3789]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Feb 24 12:10:34 volumio volumio[3789]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Feb 24 12:10:34 volumio volumio[3789]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Feb 24 12:10:34 volumio volumio[3789]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 24 12:10:34 volumio volumio[3789]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 24 12:10:34 volumio volumio[3789]: info: Starting MyVolumio Remote Streaming Endpoints Feb 24 12:10:34 volumio volumio[3789]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Feb 24 12:10:34 volumio volumio[3789]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Feb 24 12:10:34 volumio volumio[3789]: info: Streaming services startup Feb 24 12:10:34 volumio volumio[3789]: info: Starting Streaming Daemon Feb 24 12:10:34 volumio volumio[3789]: info: MyVolumio not started Feb 24 12:10:34 volumio volumio[3789]: info: Initializing device activation check Feb 24 12:10:34 volumio sudo[3998]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 24 12:10:34 volumio sudo[3998]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 12:10:34 volumio volumio[3789]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Feb 24 12:10:34 volumio systemd[1]: Stopping Volumio Streaming Daemon... Feb 24 12:10:34 volumio systemd[1]: Starting Volumio Streaming Daemon... Feb 24 12:10:34 volumio systemd[1]: Started Volumio Streaming Daemon. Feb 24 12:10:34 volumio sudo[3998]: pam_unix(sudo:session): session closed for user root Feb 24 12:10:34 volumio volumio-streaming-daemon[4004]: ############################ Feb 24 12:10:34 volumio volumio-streaming-daemon[4004]: # Volumio Streaming Daemon # Feb 24 12:10:34 volumio volumio-streaming-daemon[4004]: # Running on port 7777 # Feb 24 12:10:34 volumio volumio-streaming-daemon[4004]: ############################ Feb 24 12:10:34 volumio volumio-streaming-daemon[4004]: Environment: production Feb 24 12:10:34 volumio volumio[3789]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=2.729&uuid=a11842f23a3ba331cfb7d39c87a90e38" http://updates.volumio.org:7070/downloader-v1/track-device Feb 24 12:10:34 volumio volumio[3789]: % Total % Received % Xferd Average Speed Time Time Time Current Feb 24 12:10:34 volumio volumio[3789]: Dload Upload Total Spent Left Speed Feb 24 12:10:34 volumio volumio[3789]: [235B blob data] Feb 24 12:10:34 volumio volumio[3789]: retrying in 5 seconds, trial 0 Feb 24 12:10:34 volumio volumio[3789]: Volumio Calling Home Feb 24 12:10:34 volumio sudo[4019]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 24 12:10:34 volumio sudo[4019]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 12:10:34 volumio sudo[4019]: pam_unix(sudo:session): session closed for user root Feb 24 12:10:34 volumio sudo[4022]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 24 12:10:34 volumio sudo[4022]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 12:10:35 volumio sudo[4022]: pam_unix(sudo:session): session closed for user root Feb 24 12:10:35 volumio volumio[3789]: info: CoreCommandRouter::volumioGetState Feb 24 12:10:35 volumio volumio[3789]: info: CoreStateMachine::getState Feb 24 12:10:35 volumio volumio[3789]: info: CorePlayQueue::getTrack 0 Feb 24 12:10:35 volumio volumio[3789]: info: CoreCommandRouter::volumioGetQueue Feb 24 12:10:35 volumio volumio[3789]: info: CoreStateMachine::getQueue Feb 24 12:10:35 volumio volumio[3789]: info: CorePlayQueue::getQueue Feb 24 12:10:35 volumio sudo[4037]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 24 12:10:35 volumio sudo[4037]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 12:10:35 volumio sudo[4037]: pam_unix(sudo:session): session closed for user root Feb 24 12:10:35 volumio volumio[3789]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 24 12:10:35 volumio volumio[3789]: info: CoreCommandRouter::volumioGetVisibleSources Feb 24 12:10:35 volumio volumio[3789]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 24 12:10:35 volumio volumio[3789]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Feb 24 12:10:35 volumio volumio[3789]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 24 12:10:35 volumio volumio[3789]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 24 12:10:35 volumio sudo[4043]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 24 12:10:35 volumio volumio[3789]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 24 12:10:35 volumio volumio[3789]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 24 12:10:35 volumio sudo[4043]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 12:10:35 volumio sudo[4043]: pam_unix(sudo:session): session closed for user root Feb 24 12:10:36 volumio volumio-streaming-daemon[4004]: Environment: production Feb 24 12:10:36 volumio volumio[3789]: info: Fetching Streaming Services browse cache Feb 24 12:10:36 volumio volumio[3789]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesTIDAL Feb 24 12:10:36 volumio volumio[3789]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 24 12:10:36 volumio volumio[3789]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesQOBUZ Feb 24 12:10:36 volumio volumio[3789]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 24 12:10:38 volumio volumio[3789]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 24 12:10:41 volumio volumio[3789]: info: AutoStart - getting queue Feb 24 12:10:41 volumio volumio[3789]: info: CoreCommandRouter::volumioGetQueue Feb 24 12:10:41 volumio volumio[3789]: info: CoreStateMachine::getQueue Feb 24 12:10:41 volumio volumio[3789]: info: CorePlayQueue::getQueue Feb 24 12:10:41 volumio volumio[3789]: info: AutoStart - start playing -> queue is not empty Feb 24 12:10:41 volumio volumio[3789]: info: CoreCommandRouter::volumioPlay Feb 24 12:10:41 volumio volumio[3789]: UNSET VOLATILE Feb 24 12:10:41 volumio volumio[3789]: info: CoreStateMachine::play index 165 Feb 24 12:10:41 volumio volumio[3789]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 12:10:41 volumio volumio[3789]: info: CoreStateMachine::stop Feb 24 12:10:41 volumio volumio[3789]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 12:10:41 volumio volumio[3789]: UNSET VOLATILE Feb 24 12:10:41 volumio volumio[3789]: info: CoreStateMachine::play index undefined Feb 24 12:10:41 volumio volumio[3789]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 12:10:41 volumio volumio[3789]: info: CorePlayQueue::getTrack 165 Feb 24 12:10:41 volumio volumio[3789]: info: CoreStateMachine::startPlaybackTimer Feb 24 12:10:41 volumio volumio[3789]: info: CorePlayQueue::getTrack 165 Feb 24 12:10:41 volumio volumio[3789]: info: ControllerMpd::clearAddPlayTracks USB/6D82-CD6C/music/黃霑/笑傲江湖-百无禁忌黃霑作品集/黃霑 - 11.黎明.flac Feb 24 12:10:41 volumio volumio[3789]: info: ControllerMpd::sendMpdCommand stop Feb 24 12:10:41 volumio volumio[3789]: info: sending command... Feb 24 12:10:41 volumio volumio[3789]: info: parsing response... Feb 24 12:10:41 volumio volumio[3789]: info: ControllerMpd::sendMpdCommand clear Feb 24 12:10:41 volumio volumio[3789]: info: sending command... Feb 24 12:10:41 volumio volumio[3789]: info: Feb 24 12:10:41 volumio volumio[3789]: ---------------------------- MPD announces state update: player Feb 24 12:10:41 volumio volumio[3789]: info: Feb 24 12:10:41 volumio volumio[3789]: ---------------------------- MPD announces system playlist update Feb 24 12:10:41 volumio volumio[3789]: info: Ignoring MPD Status Update Feb 24 12:10:41 volumio volumio[3789]: info: ControllerMpd::getState Feb 24 12:10:41 volumio volumio[3789]: info: ControllerMpd::sendMpdCommand status Feb 24 12:10:41 volumio volumio[3789]: info: parsing response... Feb 24 12:10:41 volumio volumio[3789]: info: sending command... Feb 24 12:10:41 volumio volumio[3789]: info: ControllerMpd::sendMpdCommand add "USB/6D82-CD6C/music/黃霑/笑傲江湖-百无禁忌黃霑作品集/黃霑 - 11.黎明.flac" Feb 24 12:10:41 volumio volumio[3789]: info: sending command... Feb 24 12:10:41 volumio volumio[3789]: info: Feb 24 12:10:41 volumio volumio[3789]: info: Feb 24 12:10:41 volumio volumio[3789]: ---------------------------- MPD announces system playlist update Feb 24 12:10:41 volumio volumio[3789]: info: Ignoring MPD Status Update Feb 24 12:10:41 volumio volumio[3789]: info: parsing response... Feb 24 12:10:41 volumio volumio[3789]: info: ------------------------------ 12ms Feb 24 12:10:41 volumio volumio[3789]: info: parsing response... Feb 24 12:10:41 volumio volumio[3789]: info: ControllerMpd::parseState Feb 24 12:10:41 volumio volumio[3789]: info: ControllerMpd::sendMpdCommand play Feb 24 12:10:41 volumio volumio[3789]: info: ControllerMpd::pushState Feb 24 12:10:41 volumio volumio[3789]: info: CoreCommandRouter::servicePushState Feb 24 12:10:41 volumio volumio[3789]: info: CoreStateMachine::syncState Feb 24 12:10:41 volumio volumio[3789]: info: CoreStateMachine::pushState Feb 24 12:10:41 volumio volumio[3789]: info: CoreStateMachine::getState Feb 24 12:10:41 volumio volumio[3789]: info: CorePlayQueue::getTrack 165 Feb 24 12:10:41 volumio volumio[3789]: info: CoreCommandRouter::volumioPushState Feb 24 12:10:41 volumio volumio[3789]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 12:10:41 volumio volumio[3789]: info: CorePlayQueue::getTrack 165 Feb 24 12:10:41 volumio volumio[3789]: info: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Feb 24 12:10:41 volumio volumio[3789]: info: CURRENT POSITION 165 Feb 24 12:10:41 volumio volumio[3789]: info: CoreStateMachine::syncState stateService stop Feb 24 12:10:41 volumio volumio[3789]: info: CoreStateMachine::syncState currentStatus stop Feb 24 12:10:41 volumio volumio[3789]: info: CoreStateMachine::pushState Feb 24 12:10:41 volumio volumio[3789]: info: CoreStateMachine::getState Feb 24 12:10:41 volumio volumio[3789]: info: CorePlayQueue::getTrack 165 Feb 24 12:10:41 volumio sudo[4075]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 24 12:10:41 volumio volumio[3789]: info: CoreCommandRouter::volumioPushState Feb 24 12:10:41 volumio volumio[3789]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 12:10:41 volumio sudo[4075]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 12:10:41 volumio volumio[3789]: info: No code Feb 24 12:10:41 volumio sudo[4075]: pam_unix(sudo:session): session closed for user root Feb 24 12:10:41 volumio volumio[3789]: info: CoreStateMachine::pushState Feb 24 12:10:41 volumio volumio[3789]: info: CoreStateMachine::getState Feb 24 12:10:41 volumio volumio[3789]: info: CorePlayQueue::getTrack 165 Feb 24 12:10:41 volumio volumio[3789]: info: CoreCommandRouter::volumioPushState Feb 24 12:10:41 volumio volumio[3789]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 12:10:41 volumio sudo[4080]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 24 12:10:42 volumio sudo[4080]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 12:10:42 volumio volumio[3789]: info: sending command... Feb 24 12:10:42 volumio sudo[4088]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 24 12:10:42 volumio sudo[4080]: pam_unix(sudo:session): session closed for user root Feb 24 12:10:42 volumio sudo[4088]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 12:10:42 volumio volumio[3789]: info: ------------------------------ 128ms Feb 24 12:10:42 volumio volumio[3789]: info: Feb 24 12:10:42 volumio volumio[3789]: ---------------------------- MPD announces state update: player Feb 24 12:10:42 volumio volumio[3789]: info: ------------------------------ 123ms Feb 24 12:10:42 volumio volumio[3789]: info: parsing response... Feb 24 12:10:42 volumio volumio[3789]: info: ControllerMpd::getState Feb 24 12:10:42 volumio volumio[3789]: info: ControllerMpd::sendMpdCommand status Feb 24 12:10:42 volumio volumio[3789]: info: sending command... Feb 24 12:10:42 volumio sudo[4088]: pam_unix(sudo:session): session closed for user root Feb 24 12:10:42 volumio volumio[3789]: info: Feb 24 12:10:42 volumio volumio[3789]: ---------------------------- MPD announces state update: player Feb 24 12:10:42 volumio volumio[3789]: info: ControllerMpd::getState Feb 24 12:10:42 volumio volumio[3789]: info: ControllerMpd::sendMpdCommand status Feb 24 12:10:42 volumio volumio[3789]: info: parsing response... Feb 24 12:10:42 volumio volumio[3789]: info: sending command... Feb 24 12:10:42 volumio volumio[3789]: info: ControllerMpd::parseState Feb 24 12:10:42 volumio volumio[3789]: info: ControllerMpd::sendMpdCommand playlistinfo Feb 24 12:10:42 volumio volumio[3789]: info: sending command... Feb 24 12:10:42 volumio volumio[3789]: info: parsing response... Feb 24 12:10:42 volumio volumio[3789]: info: parsing response... Feb 24 12:10:42 volumio volumio[3789]: info: ControllerMpd::parseState Feb 24 12:10:42 volumio volumio[3789]: info: ControllerMpd::sendMpdCommand playlistinfo Feb 24 12:10:42 volumio volumio[3789]: info: ControllerMpd::parseTrackInfo Feb 24 12:10:42 volumio volumio[3789]: info: sending command... Feb 24 12:10:42 volumio volumio[3789]: info: ControllerMpd::pushState Feb 24 12:10:42 volumio volumio[3789]: info: CoreCommandRouter::servicePushState Feb 24 12:10:42 volumio volumio[3789]: info: CoreStateMachine::syncState Feb 24 12:10:42 volumio volumio[3789]: info: CorePlayQueue::getTrack 165 Feb 24 12:10:42 volumio volumio[3789]: info: STATE SERVICE {"status":"play","position":0,"seek":464,"duration":168,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"876 Kbps","isStreaming":false,"title":"黎明","artist":"黃霑","album":"笑傲江湖","uri":"USB/6D82-CD6C/music/黃霑/笑傲江湖-百无禁忌黃霑作品集/黃霑 - 11.黎明.flac","trackType":"flac"} Feb 24 12:10:42 volumio volumio[3789]: info: CURRENT POSITION 165 Feb 24 12:10:42 volumio volumio[3789]: info: CoreStateMachine::syncState stateService play Feb 24 12:10:42 volumio volumio[3789]: info: CoreStateMachine::syncState currentStatus stop Feb 24 12:10:42 volumio volumio[3789]: info: CoreStateMachine::pushState Feb 24 12:10:42 volumio volumio[3789]: info: CoreStateMachine::getState Feb 24 12:10:42 volumio volumio[3789]: info: CorePlayQueue::getTrack 165 Feb 24 12:10:42 volumio volumio[3789]: info: CoreCommandRouter::volumioPushState Feb 24 12:10:42 volumio volumio[3789]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 12:10:42 volumio volumio[3789]: info: ------------------------------ 76ms Feb 24 12:10:42 volumio volumio[3789]: info: parsing response... Feb 24 12:10:42 volumio volumio[3789]: info: ControllerMpd::parseTrackInfo Feb 24 12:10:42 volumio volumio[3789]: info: ControllerMpd::pushState Feb 24 12:10:42 volumio volumio[3789]: info: CoreCommandRouter::servicePushState Feb 24 12:10:42 volumio volumio[3789]: info: CoreStateMachine::syncState Feb 24 12:10:42 volumio volumio[3789]: info: CorePlayQueue::getTrack 165 Feb 24 12:10:42 volumio volumio[3789]: info: STATE SERVICE {"status":"play","position":0,"seek":464,"duration":168,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"876 Kbps","isStreaming":false,"title":"黎明","artist":"黃霑","album":"笑傲江湖","uri":"USB/6D82-CD6C/music/黃霑/笑傲江湖-百无禁忌黃霑作品集/黃霑 - 11.黎明.flac","trackType":"flac"} Feb 24 12:10:42 volumio volumio[3789]: info: CURRENT POSITION 165 Feb 24 12:10:42 volumio volumio[3789]: info: CoreStateMachine::syncState stateService play Feb 24 12:10:42 volumio volumio[3789]: info: CoreStateMachine::syncState currentStatus play Feb 24 12:10:42 volumio volumio[3789]: info: Received an update from plugin. extracting info from payload Feb 24 12:10:42 volumio volumio[3789]: info: CoreStateMachine::pushState Feb 24 12:10:42 volumio sudo[4102]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 24 12:10:42 volumio sudo[4102]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 12:10:42 volumio volumio[3789]: info: CoreStateMachine::getState Feb 24 12:10:42 volumio volumio[3789]: info: CorePlayQueue::getTrack 165 Feb 24 12:10:42 volumio volumio[3789]: info: CoreCommandRouter::volumioPushState Feb 24 12:10:42 volumio volumio[3789]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 12:10:42 volumio sudo[4102]: pam_unix(sudo:session): session closed for user root Feb 24 12:10:42 volumio volumio[3789]: info: CoreStateMachine::pushState Feb 24 12:10:42 volumio volumio[3789]: info: CoreStateMachine::getState Feb 24 12:10:42 volumio volumio[3789]: info: CorePlayQueue::getTrack 165 Feb 24 12:10:42 volumio volumio[3789]: info: CoreCommandRouter::volumioPushState Feb 24 12:10:42 volumio volumio[3789]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 12:10:42 volumio sudo[4110]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 24 12:10:42 volumio sudo[4110]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 12:10:42 volumio sudo[4110]: pam_unix(sudo:session): session closed for user root Feb 24 12:10:42 volumio volumio[3789]: info: ------------------------------ 127ms Feb 24 12:10:42 volumio sudo[4117]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 24 12:10:42 volumio sudo[4117]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 12:10:42 volumio sudo[4117]: pam_unix(sudo:session): session closed for user root Feb 24 12:10:44 volumio volumio[3789]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=2.729&uuid=a11842f23a3ba331cfb7d39c87a90e38" http://updates.volumio.org:7070/downloader-v1/track-device Feb 24 12:10:44 volumio volumio[3789]: % Total % Received % Xferd Average Speed Time Time Time Current Feb 24 12:10:44 volumio volumio[3789]: Dload Upload Total Spent Left Speed Feb 24 12:10:44 volumio volumio[3789]: 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0curl: (7) Failed to connect to updates.volumio.org port 7070: Connection refused Feb 24 12:10:44 volumio volumio[3789]: retrying in 5 seconds, trial 1 Feb 24 12:10:44 volumio volumio[3789]: Volumio Calling Home Feb 24 12:10:52 volumio volumio[3789]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 24 12:10:52 volumio volumio[3789]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 24 12:10:54 volumio volumio[3789]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=2.729&uuid=a11842f23a3ba331cfb7d39c87a90e38" http://updates.volumio.org:7070/downloader-v1/track-device Feb 24 12:10:54 volumio volumio[3789]: % Total % Received % Xferd Average Speed Time Time Time Current Feb 24 12:10:54 volumio volumio[3789]: Dload Upload Total Spent Left Speed Feb 24 12:10:54 volumio volumio[3789]: 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0curl: (7) Failed to connect to updates.volumio.org port 7070: Connection refused Feb 24 12:10:54 volumio volumio[3789]: retrying in 5 seconds, trial 2 Feb 24 12:10:54 volumio volumio[3789]: Volumio Calling Home Feb 24 12:10:59 volumio volumio[3789]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 24 12:10:59 volumio volumio[3789]: { Error: connect ETIMEDOUT 128.242.245.180:80 Feb 24 12:10:59 volumio volumio[3789]: at Object._errnoException (util.js:1022:11) Feb 24 12:10:59 volumio volumio[3789]: at _exceptionWithHostPort (util.js:1044:20) Feb 24 12:10:59 volumio volumio[3789]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1198:14) Feb 24 12:10:59 volumio volumio[3789]: code: 'ETIMEDOUT', Feb 24 12:10:59 volumio volumio[3789]: errno: 'ETIMEDOUT', Feb 24 12:10:59 volumio volumio[3789]: syscall: 'connect', Feb 24 12:10:59 volumio volumio[3789]: address: '128.242.245.180', Feb 24 12:10:59 volumio volumio[3789]: port: 80 } Feb 24 12:10:59 volumio volumio[3789]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 24 12:11:00 volumio sudo[4199]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-02-24 12:10 Feb 24 12:11:00 volumio sudo[4199]: 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="ba22215fabbe766d668749499a1c25fcefd90f0a" VOLUMIO_FE_VERSION="" VOLUMIO_BE_VERSION="043dcaee624f27d121213b83af11a9f2feac0945" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed Mar 18 01:32:43 CET 2020" VOLUMIO_VERSION="2.729" VOLUMIO_HARDWARE="pi" VOLUMIO_HASH="5a0b29326faa8f5d0f5fe95d9e58c069"