-- Logs begin at Sat 2024-11-23 13:01:47 UTC, end at Mon 2025-02-24 12:02:34 UTC. -- Feb 24 12:01:01 volumio volumio-remote-updater[743]: [2025-02-24 12:01:01] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Feb 24 12:01:01 volumio volumio-remote-updater[743]: [2025-02-24 12:01:01] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Feb 24 12:01:01 volumio systemd[1]: volumio.service: main process exited, code=exited, status=1/FAILURE Feb 24 12:01:01 volumio systemd[1]: Unit volumio.service entered failed state. Feb 24 12:01:01 volumio systemd[1]: Starting dynamicswap service... Feb 24 12:01:01 volumio systemd[1]: Started dynamicswap service. Feb 24 12:01:01 volumio systemd[1]: volumio.service holdoff time over, scheduling restart. Feb 24 12:01:01 volumio systemd[1]: Starting dynamicswap service... Feb 24 12:01:01 volumio systemd[1]: Started dynamicswap service. Feb 24 12:01:01 volumio systemd[1]: Stopping Volumio Backend Module... Feb 24 12:01:01 volumio systemd[1]: Starting Volumio Backend Module... Feb 24 12:01:01 volumio systemd[1]: Started Volumio Backend Module. Feb 24 12:01:03 volumio volumio[4911]: info: ------------------------------------------- Feb 24 12:01:03 volumio volumio[4911]: info: ----- Volumio2 ---- Feb 24 12:01:03 volumio volumio[4911]: info: ------------------------------------------- Feb 24 12:01:03 volumio volumio[4911]: info: ----- System startup ---- Feb 24 12:01:03 volumio volumio[4911]: info: ------------------------------------------- Feb 24 12:01:03 volumio volumio[4911]: info: MYVOLUMIO Environment detected Feb 24 12:01:04 volumio volumio[4911]: info: Plugin folders cleanup Feb 24 12:01:04 volumio volumio[4911]: info: Scanning into folder /volumio/app/plugins/ Feb 24 12:01:04 volumio volumio[4911]: info: Scanning category audio_interface Feb 24 12:01:04 volumio volumio[4911]: info: Scanning category miscellanea Feb 24 12:01:04 volumio volumio[4911]: info: Scanning category music_service Feb 24 12:01:04 volumio volumio[4911]: info: Scanning category plugins.json Feb 24 12:01:04 volumio volumio[4911]: info: Scanning category system_controller Feb 24 12:01:04 volumio volumio[4911]: info: Scanning category user_interface Feb 24 12:01:04 volumio volumio[4911]: info: Scanning into folder /data/plugins/ Feb 24 12:01:04 volumio volumio[4911]: info: Scanning category miscellanea Feb 24 12:01:04 volumio volumio[4911]: info: Scanning category music_service Feb 24 12:01:04 volumio volumio[4911]: info: Plugin folders cleanup completed Feb 24 12:01:04 volumio volumio[4911]: info: ------------------------------------------- Feb 24 12:01:04 volumio volumio[4911]: info: ----- Core plugins startup ---- Feb 24 12:01:04 volumio volumio[4911]: info: ------------------------------------------- Feb 24 12:01:04 volumio volumio[4911]: info: Loading plugins from folder /volumio/app/plugins/ Feb 24 12:01:04 volumio volumio[4911]: info: Adding plugin upnp to MyMusic Plugins Feb 24 12:01:04 volumio volumio[4911]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 24 12:01:04 volumio volumio[4911]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 24 12:01:04 volumio volumio[4911]: info: Loading plugins from folder /data/plugins/ Feb 24 12:01:04 volumio volumio[4911]: info: Loading plugin "system"... Feb 24 12:01:04 volumio volumio[4911]: info: Loading plugin "appearance"... Feb 24 12:01:05 volumio volumio[4911]: info: Loading plugin "network"... Feb 24 12:01:05 volumio volumio[4911]: info: Loading plugin "services"... Feb 24 12:01:05 volumio volumio[4911]: info: Loading plugin "alsa_controller"... Feb 24 12:01:05 volumio volumio[4911]: info: Loading plugin "wizard"... Feb 24 12:01:05 volumio volumio[4911]: info: Loading plugin "volumio_command_line_client"... Feb 24 12:01:05 volumio volumio[4911]: info: Loading plugin "upnp"... Feb 24 12:01:05 volumio volumio[4911]: info: [1740398465352] Starting Upmpd Daemon Feb 24 12:01:05 volumio volumio[4911]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 24 12:01:05 volumio volumio[4911]: info: Loading plugin "my_music"... Feb 24 12:01:05 volumio volumio[4911]: info: Loading plugin "mpd"... Feb 24 12:01:05 volumio volumio[4911]: info: Loading plugin "upnp_browser"... Feb 24 12:01:06 volumio volumio[4911]: info: Loading plugin "networkfs"... Feb 24 12:01:06 volumio volumio[4911]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 24 12:01:06 volumio volumio[4911]: info: Loading plugin "alarm-clock"... Feb 24 12:01:06 volumio volumio[4911]: info: Loading plugin "airplay_emulation"... Feb 24 12:01:06 volumio volumio[4911]: info: Starting Shairport Sync Feb 24 12:01:06 volumio volumio[4911]: info: Loading plugin "last_100"... Feb 24 12:01:06 volumio volumio[4911]: info: Loading plugin "webradio"... Feb 24 12:01:06 volumio volumio[4911]: info: Loading plugin "i2s_dacs"... Feb 24 12:01:06 volumio volumio[4911]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 24 12:01:06 volumio volumio[4911]: info: Loading plugin "volumiodiscovery"... Feb 24 12:01:06 volumio volumio[4911]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 24 12:01:06 volumio node[4911]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 24 12:01:06 volumio node[4911]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 24 12:01:06 volumio node[4911]: *** WARNING *** For more information see Feb 24 12:01:06 volumio node[4911]: *** 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:01:06 volumio node[4911]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 24 12:01:06 volumio node[4911]: *** WARNING *** For more information see Feb 24 12:01:06 volumio volumio[4911]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 24 12:01:06 volumio volumio[4911]: *** WARNING *** For more information see Feb 24 12:01:06 volumio volumio[4911]: *** 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:01:06 volumio volumio[4911]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 24 12:01:06 volumio volumio[4911]: *** WARNING *** For more information see Feb 24 12:01:06 volumio volumio[4911]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 24 12:01:06 volumio volumio-remote-updater[743]: [2025-02-24 12:01:06] [connect] Successful connection Feb 24 12:01:06 volumio volumio[4911]: Discovery: StartAdv! undefined Feb 24 12:01:06 volumio volumio[4911]: Discovery: Started advertising... Volumio - undefined Feb 24 12:01:06 volumio volumio[4911]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 24 12:01:06 volumio volumio[4911]: info: Loading plugin "autostart"... Feb 24 12:01:06 volumio volumio[4911]: info: Applying required configuration parameters for plugin autostart Feb 24 12:01:06 volumio volumio[4911]: info: Loading plugin "outputs"... Feb 24 12:01:06 volumio volumio[4911]: info: Loading plugin "albumart"... Feb 24 12:01:06 volumio volumio[4911]: info: Plugin example_plugin is not enabled Feb 24 12:01:06 volumio volumio[4911]: info: Loading plugin "inputs"... Feb 24 12:01:06 volumio volumio[4911]: info: Loading plugin "updater_comm"... Feb 24 12:01:07 volumio volumio[4911]: info: Plugin mpdemulation is not enabled Feb 24 12:01:07 volumio volumio[4911]: info: Loading plugin "rest_api"... Feb 24 12:01:07 volumio volumio[4911]: info: Loading plugin "websocket"... Feb 24 12:01:07 volumio volumio[4911]: info: Plugin lms is not enabled Feb 24 12:01:07 volumio volumio[4911]: info: Plugin squeezelite is not enabled Feb 24 12:01:07 volumio volumio[4911]: info: ___________ START PLUGINS ___________ Feb 24 12:01:07 volumio volumio[4911]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 24 12:01:07 volumio volumio[4911]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 24 12:01:07 volumio volumio[4911]: info: [1740398467122] CoreMusicLibrary::Adding element Media Servers Feb 24 12:01:07 volumio volumio[4911]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 24 12:01:07 volumio volumio[4911]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 24 12:01:07 volumio volumio[4911]: Forking 3 albumart workers Feb 24 12:01:07 volumio volumio[4911]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 24 12:01:07 volumio volumio[4911]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 24 12:01:07 volumio volumio[4911]: info: [1740398467250] CoreMusicLibrary::Adding element Last_100 Feb 24 12:01:07 volumio volumio[4911]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 24 12:01:07 volumio volumio[4911]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 24 12:01:07 volumio volumio[4911]: info: [1740398467256] CoreMusicLibrary::Adding element Webradio Feb 24 12:01:07 volumio volumio[4911]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 24 12:01:07 volumio volumio[4911]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 24 12:01:07 volumio volumio[4911]: info: ------------------------------------------- Feb 24 12:01:07 volumio volumio[4911]: info: ----- MyVolumio plugins startup ---- Feb 24 12:01:07 volumio volumio[4911]: info: ------------------------------------------- Feb 24 12:01:07 volumio volumio[4911]: info: [MyVolumio PluginManager] Fetching plans data.... Feb 24 12:01:07 volumio volumio[4911]: info: Loading i18n strings for locale zh Feb 24 12:01:07 volumio volumio[4911]: Updating browse sources language Feb 24 12:01:07 volumio volumio[4911]: Cannot find translation for sourceMedia Servers Feb 24 12:01:07 volumio volumio[4911]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 24 12:01:07 volumio volumio[4911]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 24 12:01:07 volumio volumio[4911]: info: CoreCommandRouter::initPlayerControls Feb 24 12:01:07 volumio volumio[4911]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 12:01:07 volumio volumio[4911]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAlsaCards Feb 24 12:01:07 volumio volumio[4911]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 12:01:07 volumio volumio[4911]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 12:01:07 volumio volumio[4911]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 12:01:07 volumio volumio[4911]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 12:01:07 volumio volumio[4911]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 12:01:07 volumio volumio[4911]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 24 12:01:07 volumio volumio[4911]: info: BOOT COMPLETED Feb 24 12:01:07 volumio volumio[4911]: [Metrics] CommandRouter: 3s 920.71ms Feb 24 12:01:07 volumio volumio[4911]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 24 12:01:07 volumio volumio[4911]: aplay: main:722: audio open error: Device or resource busy Feb 24 12:01:07 volumio volumio[4911]: Cannot play startup sound Feb 24 12:01:07 volumio volumio[4911]: info: CoreCommandRouter::Close All Modals sent Feb 24 12:01:07 volumio volumio[4911]: info: CoreCommandRouter::Close All Modals sent Feb 24 12:01:07 volumio volumio[4911]: Express server listening on port 3000 Feb 24 12:01:07 volumio volumio[4911]: [Metrics] WebUI: 4s 316.50ms Feb 24 12:01:07 volumio volumio[4911]: Volumio Calling Home Feb 24 12:01:07 volumio volumio[4911]: info: CoreStateMachine::resetVolumioState Feb 24 12:01:07 volumio volumio[4911]: info: CoreStateMachine::getcurrentVolume Feb 24 12:01:07 volumio volumio[4911]: info: CoreCommandRouter::volumioRetrievevolume Feb 24 12:01:07 volumio volumio[4911]: info: CoreStateMachine::pushState Feb 24 12:01:07 volumio volumio[4911]: info: CoreStateMachine::getState Feb 24 12:01:07 volumio volumio[4911]: info: CorePlayQueue::getTrack 0 Feb 24 12:01:07 volumio volumio[4911]: info: CoreCommandRouter::volumioPushState Feb 24 12:01:07 volumio volumio[4911]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 12:01:07 volumio volumio[4911]: info: Setting Device type: Raspberry PI Feb 24 12:01:07 volumio volumio[4911]: info: MPD running with PID796 ,establishing connection Feb 24 12:01:08 volumio volumio-remote-updater[743]: [2025-02-24 12:01:08] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.5.1" /socket.io/?EIO=4&transport=websocket&t=1740398466 101 Feb 24 12:01:08 volumio volumio[4911]: Starting albumart workers Feb 24 12:01:08 volumio volumio[4911]: Starting albumart workers Feb 24 12:01:08 volumio volumio[4911]: Starting albumart workers Feb 24 12:01:08 volumio volumio[4911]: info: Reloading queue from file Feb 24 12:01:08 volumio volumio[4911]: info: CoreStateMachine::setRepeat null single undefined Feb 24 12:01:08 volumio volumio[4911]: info: CoreStateMachine::pushState Feb 24 12:01:08 volumio volumio[4911]: info: CoreStateMachine::getState Feb 24 12:01:08 volumio volumio[4911]: info: CorePlayQueue::getTrack 0 Feb 24 12:01:08 volumio volumio[4911]: info: CoreCommandRouter::volumioPushState Feb 24 12:01:08 volumio volumio[4911]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 12:01:08 volumio volumio[4911]: info: CoreStateMachine::setRandom true Feb 24 12:01:08 volumio volumio[4911]: info: CoreStateMachine::pushState Feb 24 12:01:08 volumio volumio[4911]: info: CoreStateMachine::getState Feb 24 12:01:08 volumio volumio[4911]: info: CorePlayQueue::getTrack 0 Feb 24 12:01:08 volumio volumio[4911]: info: CoreCommandRouter::volumioPushState Feb 24 12:01:08 volumio volumio[4911]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 12:01:08 volumio volumio[4911]: info: Starting Shairport Sync Feb 24 12:01:08 volumio volumio[4911]: info: Starting Shairport Sync Feb 24 12:01:08 volumio volumio[4911]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Feb 24 12:01:08 volumio sudo[4990]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 24 12:01:08 volumio sudo[4991]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 24 12:01:08 volumio sudo[4990]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 12:01:08 volumio sudo[4991]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 12:01:08 volumio systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... Feb 24 12:01:08 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Feb 24 12:01:08 volumio systemd[5010]: Failed at step USER spawning /usr/local/bin/shairport-sync: No such process Feb 24 12:01:08 volumio systemd[1]: shairport-sync.service: main process exited, code=exited, status=217/USER Feb 24 12:01:08 volumio systemd[1]: Unit shairport-sync.service entered failed state. Feb 24 12:01:08 volumio sudo[4990]: pam_unix(sudo:session): session closed for user root Feb 24 12:01:08 volumio systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... Feb 24 12:01:08 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Feb 24 12:01:08 volumio systemd[5013]: Failed at step USER spawning /usr/local/bin/shairport-sync: No such process Feb 24 12:01:08 volumio systemd[1]: shairport-sync.service: main process exited, code=exited, status=217/USER Feb 24 12:01:08 volumio systemd[1]: Unit shairport-sync.service entered failed state. Feb 24 12:01:08 volumio sudo[4991]: pam_unix(sudo:session): session closed for user root Feb 24 12:01:08 volumio volumio[4911]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Feb 24 12:01:08 volumio volumio[4911]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Feb 24 12:01:08 volumio volumio[4911]: Discovery: adding 963c78eb-b0ac-49c3-8afe-eef11e3e8641 Feb 24 12:01:08 volumio volumio[4911]: info: mDNS: Found device Volumio Feb 24 12:01:08 volumio volumio[4911]: info: CoreCommandRouter::volumioGetState Feb 24 12:01:08 volumio volumio[4911]: info: CoreStateMachine::getState Feb 24 12:01:08 volumio volumio[4911]: info: CorePlayQueue::getTrack 0 Feb 24 12:01:08 volumio sudo[5021]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 24 12:01:08 volumio sudo[5021]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 12:01:08 volumio sudo[5021]: pam_unix(sudo:session): session closed for user root Feb 24 12:01:08 volumio sudo[5025]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 24 12:01:08 volumio sudo[5025]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 12:01:08 volumio sudo[5025]: pam_unix(sudo:session): session closed for user root Feb 24 12:01:09 volumio volumio[4911]: info: CoreCommandRouter::volumioGetState Feb 24 12:01:09 volumio volumio[4911]: info: CoreStateMachine::getState Feb 24 12:01:09 volumio volumio[4911]: info: CorePlayQueue::getTrack 0 Feb 24 12:01:09 volumio sudo[5044]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 24 12:01:09 volumio sudo[5044]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 12:01:09 volumio sudo[5044]: pam_unix(sudo:session): session closed for user root Feb 24 12:01:09 volumio volumio[4911]: Discovery: this is already registered, 963c78eb-b0ac-49c3-8afe-eef11e3e8641 Feb 24 12:01:09 volumio volumio[4911]: info: mDNS: Found device Volumio Feb 24 12:01:09 volumio volumio[4911]: info: CoreCommandRouter::volumioGetState Feb 24 12:01:09 volumio volumio[4911]: info: CoreStateMachine::getState Feb 24 12:01:09 volumio volumio[4911]: info: CorePlayQueue::getTrack 0 Feb 24 12:01:09 volumio sudo[5053]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 24 12:01:09 volumio sudo[5053]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 12:01:09 volumio sudo[5053]: pam_unix(sudo:session): session closed for user root Feb 24 12:01:09 volumio sudo[5057]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 24 12:01:09 volumio sudo[5057]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 12:01:09 volumio sudo[5057]: pam_unix(sudo:session): session closed for user root Feb 24 12:01:09 volumio volumio[4911]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 24 12:01:09 volumio volumio[4911]: info: CoreCommandRouter::volumioGetVisibleSources Feb 24 12:01:09 volumio volumio[4911]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 24 12:01:09 volumio volumio[4911]: info: Listing playlists Feb 24 12:01:09 volumio sudo[5068]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 24 12:01:09 volumio volumio[4911]: info: CoreCommandRouter::volumioGetQueue Feb 24 12:01:09 volumio volumio[4911]: info: CoreStateMachine::getQueue Feb 24 12:01:09 volumio volumio[4911]: info: CorePlayQueue::getQueue Feb 24 12:01:09 volumio sudo[5068]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 12:01:09 volumio sudo[5068]: pam_unix(sudo:session): session closed for user root Feb 24 12:01:09 volumio volumio[4911]: info: Shairport-Sync Started Feb 24 12:01:09 volumio volumio[4911]: Error adding Membership: Error: addMembership EINVAL Feb 24 12:01:09 volumio volumio[4911]: info: Shairport-Sync Started Feb 24 12:01:10 volumio volumio[4911]: info: CoreCommandRouter::volumioGetState Feb 24 12:01:10 volumio volumio[4911]: info: CoreStateMachine::getState Feb 24 12:01:10 volumio volumio[4911]: info: CorePlayQueue::getTrack 0 Feb 24 12:01:10 volumio sudo[5082]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 24 12:01:10 volumio sudo[5082]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 12:01:10 volumio sudo[5082]: pam_unix(sudo:session): session closed for user root Feb 24 12:01:15 volumio sudo[5101]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 24 12:01:15 volumio sudo[5101]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 12:01:15 volumio sudo[5101]: pam_unix(sudo:session): session closed for user root Feb 24 12:01:15 volumio sudo[5105]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Feb 24 12:01:15 volumio sudo[5105]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 12:01:15 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Feb 24 12:01:15 volumio sudo[5105]: pam_unix(sudo:session): session closed for user root Feb 24 12:01:15 volumio volumio[4911]: info: Upmpdcli Daemon Started Feb 24 12:01:16 volumio volumio[4911]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/David%20Abel/100%20Best%20Film%20Classics/109ff167-8f81-4d1d-89e7-02e527c7805b.jpg' Feb 24 12:01:16 volumio volumio[4911]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Feb 24 12:01:16 volumio volumio[4911]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Feb 24 12:01:16 volumio volumio[4911]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Feb 24 12:01:16 volumio volumio[4911]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Feb 24 12:01:16 volumio volumio[4911]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Feb 24 12:01:16 volumio volumio[4911]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Feb 24 12:01:16 volumio volumio[4911]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Feb 24 12:01:16 volumio volumio[4911]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Feb 24 12:01:16 volumio volumio[4911]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Feb 24 12:01:16 volumio volumio[4911]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Feb 24 12:01:16 volumio volumio[4911]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Feb 24 12:01:16 volumio volumio[4911]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Feb 24 12:01:16 volumio volumio[4911]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Feb 24 12:01:16 volumio volumio[4911]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Feb 24 12:01:16 volumio volumio[4911]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Feb 24 12:01:16 volumio volumio[4911]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Feb 24 12:01:16 volumio volumio[4911]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Feb 24 12:01:16 volumio volumio[4911]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Feb 24 12:01:16 volumio volumio[4911]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Feb 24 12:01:16 volumio volumio[4911]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Feb 24 12:01:16 volumio volumio[4911]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Feb 24 12:01:16 volumio volumio[4911]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Feb 24 12:01:16 volumio volumio[4911]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Feb 24 12:01:16 volumio volumio[4911]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Feb 24 12:01:16 volumio volumio[4911]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Feb 24 12:01:16 volumio volumio[4911]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Feb 24 12:01:16 volumio volumio[4911]: info: Adding plugin bluetooth to MyMusic Plugins Feb 24 12:01:16 volumio volumio[4911]: info: Adding plugin cd_controller to MyMusic Plugins Feb 24 12:01:16 volumio volumio[4911]: info: Adding plugin smart_inputs to MyMusic Plugins Feb 24 12:01:16 volumio volumio[4911]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Feb 24 12:01:18 volumio volumio[4911]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Feb 24 12:01:19 volumio volumio[4911]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Feb 24 12:01:19 volumio volumio[4911]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Feb 24 12:01:19 volumio volumio[4911]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 24 12:01:19 volumio volumio[4911]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 24 12:01:19 volumio volumio[4911]: info: Starting MyVolumio Remote Streaming Endpoints Feb 24 12:01:19 volumio volumio[4911]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Feb 24 12:01:19 volumio volumio[4911]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Feb 24 12:01:19 volumio volumio[4911]: info: Streaming services startup Feb 24 12:01:19 volumio volumio[4911]: info: Starting Streaming Daemon Feb 24 12:01:19 volumio volumio[4911]: info: MyVolumio not started Feb 24 12:01:19 volumio volumio[4911]: info: Initializing device activation check Feb 24 12:01:19 volumio sudo[5122]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 24 12:01:19 volumio sudo[5122]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 12:01:19 volumio systemd[1]: Stopping Volumio Streaming Daemon... Feb 24 12:01:19 volumio volumio[4911]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Feb 24 12:01:19 volumio systemd[1]: Starting Volumio Streaming Daemon... Feb 24 12:01:19 volumio systemd[1]: Started Volumio Streaming Daemon. Feb 24 12:01:19 volumio sudo[5122]: pam_unix(sudo:session): session closed for user root Feb 24 12:01:19 volumio volumio-streaming-daemon[5128]: ############################ Feb 24 12:01:19 volumio volumio-streaming-daemon[5128]: # Volumio Streaming Daemon # Feb 24 12:01:19 volumio volumio-streaming-daemon[5128]: # Running on port 7777 # Feb 24 12:01:19 volumio volumio-streaming-daemon[5128]: ############################ Feb 24 12:01:19 volumio volumio-streaming-daemon[5128]: Environment: production Feb 24 12:01:19 volumio volumio[4911]: 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:01:19 volumio volumio[4911]: % Total % Received % Xferd Average Speed Time Time Time Current Feb 24 12:01:19 volumio volumio[4911]: Dload Upload Total Spent Left Speed Feb 24 12:01:19 volumio volumio[4911]: 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0curl: (7) Failed to connect to updates.volumio.org port 7070: Connection refused Feb 24 12:01:19 volumio volumio[4911]: retrying in 5 seconds, trial 0 Feb 24 12:01:19 volumio volumio[4911]: Volumio Calling Home Feb 24 12:01:19 volumio volumio[4911]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Feb 24 12:01:19 volumio volumio[4911]: info: CURURI: artists:// Feb 24 12:01:19 volumio volumio[4911]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Academy%20of%20St.%20Martin%20In%20the%20F/8c8adcbc-cb28-4cf5-adc3-5a990d4314a8.jpg' Feb 24 12:01:20 volumio volumio[4911]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Feb 24 12:01:20 volumio volumio[4911]: info: CURURI: genres:// Feb 24 12:01:21 volumio volumio-streaming-daemon[5128]: Environment: production Feb 24 12:01:21 volumio volumio[4911]: info: Fetching Streaming Services browse cache Feb 24 12:01:21 volumio volumio[4911]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesTIDAL Feb 24 12:01:21 volumio volumio[4911]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 24 12:01:21 volumio volumio[4911]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesQOBUZ Feb 24 12:01:21 volumio volumio[4911]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 24 12:01:26 volumio volumio[4911]: info: AutoStart - getting queue Feb 24 12:01:26 volumio volumio[4911]: info: CoreCommandRouter::volumioGetQueue Feb 24 12:01:26 volumio volumio[4911]: info: CoreStateMachine::getQueue Feb 24 12:01:26 volumio volumio[4911]: info: CorePlayQueue::getQueue Feb 24 12:01:26 volumio volumio[4911]: info: AutoStart - start playing -> queue is not empty Feb 24 12:01:26 volumio volumio[4911]: info: CoreCommandRouter::volumioPlay Feb 24 12:01:26 volumio volumio[4911]: UNSET VOLATILE Feb 24 12:01:26 volumio volumio[4911]: info: CoreStateMachine::play index 165 Feb 24 12:01:26 volumio volumio[4911]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 12:01:26 volumio volumio[4911]: info: CoreStateMachine::stop Feb 24 12:01:26 volumio volumio[4911]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 12:01:26 volumio volumio[4911]: UNSET VOLATILE Feb 24 12:01:26 volumio volumio[4911]: info: CoreStateMachine::play index undefined Feb 24 12:01:26 volumio volumio[4911]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 12:01:26 volumio volumio[4911]: info: CorePlayQueue::getTrack 165 Feb 24 12:01:26 volumio volumio[4911]: info: CoreStateMachine::startPlaybackTimer Feb 24 12:01:26 volumio volumio[4911]: info: CorePlayQueue::getTrack 165 Feb 24 12:01:26 volumio volumio[4911]: info: ControllerMpd::clearAddPlayTracks USB/6D82-CD6C/music/黃霑/笑傲江湖-百无禁忌黃霑作品集/黃霑 - 11.黎明.flac Feb 24 12:01:26 volumio volumio[4911]: info: ControllerMpd::sendMpdCommand stop Feb 24 12:01:26 volumio volumio[4911]: info: sending command... Feb 24 12:01:26 volumio volumio[4911]: info: parsing response... Feb 24 12:01:26 volumio volumio[4911]: info: ControllerMpd::sendMpdCommand clear Feb 24 12:01:26 volumio volumio[4911]: info: sending command... Feb 24 12:01:26 volumio volumio[4911]: info: Feb 24 12:01:26 volumio volumio[4911]: ---------------------------- MPD announces state update: player Feb 24 12:01:26 volumio volumio[4911]: info: Feb 24 12:01:26 volumio volumio[4911]: ---------------------------- MPD announces system playlist update Feb 24 12:01:26 volumio volumio[4911]: info: Ignoring MPD Status Update Feb 24 12:01:26 volumio volumio[4911]: info: ControllerMpd::getState Feb 24 12:01:26 volumio volumio[4911]: info: ControllerMpd::sendMpdCommand status Feb 24 12:01:26 volumio volumio[4911]: info: parsing response... Feb 24 12:01:26 volumio volumio[4911]: info: sending command... Feb 24 12:01:26 volumio volumio[4911]: info: ControllerMpd::sendMpdCommand add "USB/6D82-CD6C/music/黃霑/笑傲江湖-百无禁忌黃霑作品集/黃霑 - 11.黎明.flac" Feb 24 12:01:26 volumio volumio[4911]: info: sending command... Feb 24 12:01:26 volumio volumio[4911]: info: Feb 24 12:01:26 volumio volumio[4911]: info: Feb 24 12:01:26 volumio volumio[4911]: ---------------------------- MPD announces system playlist update Feb 24 12:01:26 volumio volumio[4911]: info: Ignoring MPD Status Update Feb 24 12:01:26 volumio volumio[4911]: info: parsing response... Feb 24 12:01:26 volumio volumio[4911]: info: ------------------------------ 11ms Feb 24 12:01:26 volumio volumio[4911]: info: parsing response... Feb 24 12:01:26 volumio volumio[4911]: info: ControllerMpd::parseState Feb 24 12:01:26 volumio volumio[4911]: info: ControllerMpd::sendMpdCommand play Feb 24 12:01:26 volumio volumio[4911]: info: ControllerMpd::pushState Feb 24 12:01:26 volumio volumio[4911]: info: CoreCommandRouter::servicePushState Feb 24 12:01:26 volumio volumio[4911]: info: CoreStateMachine::syncState Feb 24 12:01:26 volumio volumio[4911]: info: CoreStateMachine::pushState Feb 24 12:01:26 volumio volumio[4911]: info: CoreStateMachine::getState Feb 24 12:01:26 volumio volumio[4911]: info: CorePlayQueue::getTrack 165 Feb 24 12:01:26 volumio volumio[4911]: info: CoreCommandRouter::volumioPushState Feb 24 12:01:26 volumio volumio[4911]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 12:01:26 volumio volumio[4911]: info: CorePlayQueue::getTrack 165 Feb 24 12:01:26 volumio volumio[4911]: 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:01:26 volumio volumio[4911]: info: CURRENT POSITION 165 Feb 24 12:01:26 volumio volumio[4911]: info: CoreStateMachine::syncState stateService stop Feb 24 12:01:26 volumio volumio[4911]: info: CoreStateMachine::syncState currentStatus stop Feb 24 12:01:26 volumio volumio[4911]: info: CoreStateMachine::pushState Feb 24 12:01:26 volumio volumio[4911]: info: CoreStateMachine::getState Feb 24 12:01:26 volumio volumio[4911]: info: CorePlayQueue::getTrack 165 Feb 24 12:01:26 volumio sudo[5158]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 24 12:01:26 volumio volumio[4911]: info: CoreCommandRouter::volumioPushState Feb 24 12:01:26 volumio volumio[4911]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 12:01:26 volumio sudo[5158]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 12:01:26 volumio sudo[5158]: pam_unix(sudo:session): session closed for user root Feb 24 12:01:26 volumio volumio[4911]: info: No code Feb 24 12:01:26 volumio volumio[4911]: info: CoreStateMachine::pushState Feb 24 12:01:26 volumio volumio[4911]: info: CoreStateMachine::getState Feb 24 12:01:27 volumio volumio[4911]: info: CorePlayQueue::getTrack 165 Feb 24 12:01:27 volumio volumio[4911]: info: CoreCommandRouter::volumioPushState Feb 24 12:01:27 volumio volumio[4911]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 12:01:27 volumio sudo[5165]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 24 12:01:27 volumio volumio[4911]: info: sending command... Feb 24 12:01:27 volumio sudo[5165]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 12:01:27 volumio sudo[5170]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 24 12:01:27 volumio volumio[4911]: info: ------------------------------ 116ms Feb 24 12:01:27 volumio volumio[4911]: info: ------------------------------ 108ms Feb 24 12:01:27 volumio volumio[4911]: info: parsing response... Feb 24 12:01:27 volumio sudo[5170]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 12:01:27 volumio sudo[5165]: pam_unix(sudo:session): session closed for user root Feb 24 12:01:27 volumio volumio[4911]: info: Feb 24 12:01:27 volumio volumio[4911]: ---------------------------- MPD announces state update: player Feb 24 12:01:27 volumio sudo[5170]: pam_unix(sudo:session): session closed for user root Feb 24 12:01:27 volumio volumio[4911]: info: ControllerMpd::getState Feb 24 12:01:27 volumio volumio[4911]: info: ControllerMpd::sendMpdCommand status Feb 24 12:01:27 volumio volumio[4911]: info: sending command... Feb 24 12:01:27 volumio volumio[4911]: info: Feb 24 12:01:27 volumio volumio[4911]: ---------------------------- MPD announces state update: player Feb 24 12:01:27 volumio volumio[4911]: info: ControllerMpd::getState Feb 24 12:01:27 volumio volumio[4911]: info: ControllerMpd::sendMpdCommand status Feb 24 12:01:27 volumio volumio[4911]: info: parsing response... Feb 24 12:01:27 volumio volumio[4911]: info: sending command... Feb 24 12:01:27 volumio volumio[4911]: info: ControllerMpd::parseState Feb 24 12:01:27 volumio volumio[4911]: info: ControllerMpd::sendMpdCommand playlistinfo Feb 24 12:01:27 volumio volumio[4911]: info: sending command... Feb 24 12:01:27 volumio volumio[4911]: info: parsing response... Feb 24 12:01:27 volumio volumio[4911]: info: parsing response... Feb 24 12:01:27 volumio volumio[4911]: info: ControllerMpd::parseState Feb 24 12:01:27 volumio volumio[4911]: info: ControllerMpd::sendMpdCommand playlistinfo Feb 24 12:01:27 volumio volumio[4911]: info: ControllerMpd::parseTrackInfo Feb 24 12:01:27 volumio volumio[4911]: info: sending command... Feb 24 12:01:27 volumio volumio[4911]: info: ControllerMpd::pushState Feb 24 12:01:27 volumio volumio[4911]: info: CoreCommandRouter::servicePushState Feb 24 12:01:27 volumio volumio[4911]: info: CoreStateMachine::syncState Feb 24 12:01:27 volumio volumio[4911]: info: CorePlayQueue::getTrack 165 Feb 24 12:01:27 volumio volumio[4911]: 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:01:27 volumio volumio[4911]: info: CURRENT POSITION 165 Feb 24 12:01:27 volumio volumio[4911]: info: CoreStateMachine::syncState stateService play Feb 24 12:01:27 volumio volumio[4911]: info: CoreStateMachine::syncState currentStatus stop Feb 24 12:01:27 volumio volumio[4911]: info: CoreStateMachine::pushState Feb 24 12:01:27 volumio volumio[4911]: info: CoreStateMachine::getState Feb 24 12:01:27 volumio volumio[4911]: info: CorePlayQueue::getTrack 165 Feb 24 12:01:27 volumio volumio[4911]: info: CoreCommandRouter::volumioPushState Feb 24 12:01:27 volumio volumio[4911]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 12:01:27 volumio volumio[4911]: info: ------------------------------ 60ms Feb 24 12:01:27 volumio volumio[4911]: info: parsing response... Feb 24 12:01:27 volumio volumio[4911]: info: ControllerMpd::parseTrackInfo Feb 24 12:01:27 volumio volumio[4911]: info: ControllerMpd::pushState Feb 24 12:01:27 volumio sudo[5185]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 24 12:01:27 volumio volumio[4911]: info: CoreCommandRouter::servicePushState Feb 24 12:01:27 volumio volumio[4911]: info: CoreStateMachine::syncState Feb 24 12:01:27 volumio volumio[4911]: info: CorePlayQueue::getTrack 165 Feb 24 12:01:27 volumio volumio[4911]: 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:01:27 volumio volumio[4911]: info: CURRENT POSITION 165 Feb 24 12:01:27 volumio volumio[4911]: info: CoreStateMachine::syncState stateService play Feb 24 12:01:27 volumio volumio[4911]: info: CoreStateMachine::syncState currentStatus play Feb 24 12:01:27 volumio volumio[4911]: info: Received an update from plugin. extracting info from payload Feb 24 12:01:27 volumio volumio[4911]: info: CoreStateMachine::pushState Feb 24 12:01:27 volumio volumio[4911]: info: CoreStateMachine::getState Feb 24 12:01:27 volumio volumio[4911]: info: CorePlayQueue::getTrack 165 Feb 24 12:01:27 volumio volumio[4911]: info: CoreCommandRouter::volumioPushState Feb 24 12:01:27 volumio sudo[5185]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 12:01:27 volumio sudo[5185]: pam_unix(sudo:session): session closed for user root Feb 24 12:01:27 volumio volumio[4911]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 12:01:27 volumio volumio[4911]: info: CoreStateMachine::pushState Feb 24 12:01:27 volumio volumio[4911]: info: CoreStateMachine::getState Feb 24 12:01:27 volumio volumio[4911]: info: CorePlayQueue::getTrack 165 Feb 24 12:01:27 volumio volumio[4911]: info: CoreCommandRouter::volumioPushState Feb 24 12:01:27 volumio volumio[4911]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 12:01:27 volumio sudo[5194]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 24 12:01:27 volumio sudo[5194]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 12:01:27 volumio sudo[5194]: pam_unix(sudo:session): session closed for user root Feb 24 12:01:27 volumio volumio[4911]: info: ------------------------------ 126ms Feb 24 12:01:27 volumio sudo[5198]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 24 12:01:27 volumio sudo[5198]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 12:01:27 volumio sudo[5198]: pam_unix(sudo:session): session closed for user root Feb 24 12:01:29 volumio volumio[4911]: 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:01:29 volumio volumio[4911]: % Total % Received % Xferd Average Speed Time Time Time Current Feb 24 12:01:29 volumio volumio[4911]: Dload Upload Total Spent Left Speed Feb 24 12:01:29 volumio volumio[4911]: 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0curl: (7) Failed to connect to updates.volumio.org port 7070: Connection refused Feb 24 12:01:29 volumio volumio[4911]: retrying in 5 seconds, trial 1 Feb 24 12:01:29 volumio volumio[4911]: Volumio Calling Home Feb 24 12:01:37 volumio volumio[4911]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 24 12:01:37 volumio volumio[4911]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 24 12:01:38 volumio volumio[4911]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 24 12:01:38 volumio volumio[4911]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Feb 24 12:01:38 volumio volumio[4911]: info: Received Get System Version Feb 24 12:01:38 volumio volumio[4911]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 24 12:01:39 volumio volumio[4911]: 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:01:39 volumio volumio[4911]: % Total % Received % Xferd Average Speed Time Time Time Current Feb 24 12:01:39 volumio volumio[4911]: Dload Upload Total Spent Left Speed Feb 24 12:01:39 volumio volumio[4911]: 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0curl: (7) Failed to connect to updates.volumio.org port 7070: Connection refused Feb 24 12:01:39 volumio volumio[4911]: retrying in 5 seconds, trial 2 Feb 24 12:01:39 volumio volumio[4911]: Volumio Calling Home Feb 24 12:01:51 volumio volumio[4911]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 24 12:01:51 volumio volumio[4911]: { Error: connect ETIMEDOUT 128.242.240.253:80 Feb 24 12:01:51 volumio volumio[4911]: at Object._errnoException (util.js:1022:11) Feb 24 12:01:51 volumio volumio[4911]: at _exceptionWithHostPort (util.js:1044:20) Feb 24 12:01:51 volumio volumio[4911]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1198:14) Feb 24 12:01:51 volumio volumio[4911]: code: 'ETIMEDOUT', Feb 24 12:01:51 volumio volumio[4911]: errno: 'ETIMEDOUT', Feb 24 12:01:51 volumio volumio[4911]: syscall: 'connect', Feb 24 12:01:51 volumio volumio[4911]: address: '128.242.240.253', Feb 24 12:01:51 volumio volumio[4911]: port: 80 } Feb 24 12:01:51 volumio volumio[4911]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 24 12:01:51 volumio sudo[5280]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-02-24 12:00 Feb 24 12:01:51 volumio sudo[5280]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 12:01:52 volumio sudo[5280]: pam_unix(sudo:session): session closed for user root Feb 24 12:01:53 volumio volumio-remote-updater[743]: [2025-02-24 12:01:53] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Feb 24 12:01:53 volumio volumio-remote-updater[743]: [2025-02-24 12:01:53] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Feb 24 12:01:53 volumio systemd[1]: volumio.service: main process exited, code=exited, status=1/FAILURE Feb 24 12:01:53 volumio systemd[1]: Unit volumio.service entered failed state. Feb 24 12:01:53 volumio systemd[1]: Starting dynamicswap service... Feb 24 12:01:53 volumio systemd[1]: Started dynamicswap service. Feb 24 12:01:53 volumio systemd[1]: volumio.service holdoff time over, scheduling restart. Feb 24 12:01:53 volumio systemd[1]: Starting dynamicswap service... Feb 24 12:01:53 volumio systemd[1]: Started dynamicswap service. Feb 24 12:01:53 volumio systemd[1]: Stopping Volumio Backend Module... Feb 24 12:01:53 volumio systemd[1]: Starting Volumio Backend Module... Feb 24 12:01:53 volumio systemd[1]: Started Volumio Backend Module. Feb 24 12:01:55 volumio volumio[5304]: info: ------------------------------------------- Feb 24 12:01:55 volumio volumio[5304]: info: ----- Volumio2 ---- Feb 24 12:01:55 volumio volumio[5304]: info: ------------------------------------------- Feb 24 12:01:55 volumio volumio[5304]: info: ----- System startup ---- Feb 24 12:01:55 volumio volumio[5304]: info: ------------------------------------------- Feb 24 12:01:55 volumio volumio[5304]: info: MYVOLUMIO Environment detected Feb 24 12:01:56 volumio volumio[5304]: info: Plugin folders cleanup Feb 24 12:01:56 volumio volumio[5304]: info: Scanning into folder /volumio/app/plugins/ Feb 24 12:01:56 volumio volumio[5304]: info: Scanning category audio_interface Feb 24 12:01:56 volumio volumio[5304]: info: Scanning category miscellanea Feb 24 12:01:56 volumio volumio[5304]: info: Scanning category music_service Feb 24 12:01:56 volumio volumio[5304]: info: Scanning category plugins.json Feb 24 12:01:56 volumio volumio[5304]: info: Scanning category system_controller Feb 24 12:01:56 volumio volumio[5304]: info: Scanning category user_interface Feb 24 12:01:56 volumio volumio[5304]: info: Scanning into folder /data/plugins/ Feb 24 12:01:56 volumio volumio[5304]: info: Scanning category miscellanea Feb 24 12:01:56 volumio volumio[5304]: info: Scanning category music_service Feb 24 12:01:56 volumio volumio[5304]: info: Plugin folders cleanup completed Feb 24 12:01:56 volumio volumio[5304]: info: ------------------------------------------- Feb 24 12:01:56 volumio volumio[5304]: info: ----- Core plugins startup ---- Feb 24 12:01:56 volumio volumio[5304]: info: ------------------------------------------- Feb 24 12:01:56 volumio volumio[5304]: info: Loading plugins from folder /volumio/app/plugins/ Feb 24 12:01:56 volumio volumio[5304]: info: Adding plugin upnp to MyMusic Plugins Feb 24 12:01:56 volumio volumio[5304]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 24 12:01:56 volumio volumio[5304]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 24 12:01:56 volumio volumio[5304]: info: Loading plugins from folder /data/plugins/ Feb 24 12:01:56 volumio volumio[5304]: info: Loading plugin "system"... Feb 24 12:01:56 volumio volumio[5304]: info: Loading plugin "appearance"... Feb 24 12:01:57 volumio volumio[5304]: info: Loading plugin "network"... Feb 24 12:01:57 volumio volumio[5304]: info: Loading plugin "services"... Feb 24 12:01:57 volumio volumio[5304]: info: Loading plugin "alsa_controller"... Feb 24 12:01:57 volumio volumio[5304]: info: Loading plugin "wizard"... Feb 24 12:01:57 volumio volumio[5304]: info: Loading plugin "volumio_command_line_client"... Feb 24 12:01:57 volumio volumio[5304]: info: Loading plugin "upnp"... Feb 24 12:01:57 volumio volumio[5304]: info: [1740398517186] Starting Upmpd Daemon Feb 24 12:01:57 volumio volumio[5304]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 24 12:01:57 volumio volumio[5304]: info: Loading plugin "my_music"... Feb 24 12:01:57 volumio volumio[5304]: info: Loading plugin "mpd"... Feb 24 12:01:57 volumio volumio[5304]: info: Loading plugin "upnp_browser"... Feb 24 12:01:57 volumio volumio[5304]: info: Loading plugin "networkfs"... Feb 24 12:01:58 volumio volumio[5304]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 24 12:01:58 volumio volumio[5304]: info: Loading plugin "alarm-clock"... Feb 24 12:01:58 volumio volumio[5304]: info: Loading plugin "airplay_emulation"... Feb 24 12:01:58 volumio volumio[5304]: info: Starting Shairport Sync Feb 24 12:01:58 volumio volumio[5304]: info: Loading plugin "last_100"... Feb 24 12:01:58 volumio volumio[5304]: info: Loading plugin "webradio"... Feb 24 12:01:58 volumio volumio-remote-updater[743]: [2025-02-24 12:01:58] [connect] Successful connection Feb 24 12:01:58 volumio volumio[5304]: info: Loading plugin "i2s_dacs"... Feb 24 12:01:58 volumio volumio[5304]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 24 12:01:58 volumio volumio[5304]: info: Loading plugin "volumiodiscovery"... Feb 24 12:01:58 volumio volumio[5304]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 24 12:01:58 volumio node[5304]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 24 12:01:58 volumio node[5304]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 24 12:01:58 volumio node[5304]: *** WARNING *** For more information see Feb 24 12:01:58 volumio node[5304]: *** 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:01:58 volumio node[5304]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 24 12:01:58 volumio node[5304]: *** WARNING *** For more information see Feb 24 12:01:58 volumio volumio[5304]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 24 12:01:58 volumio volumio[5304]: *** WARNING *** For more information see Feb 24 12:01:58 volumio volumio[5304]: *** 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:01:58 volumio volumio[5304]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 24 12:01:58 volumio volumio[5304]: *** WARNING *** For more information see Feb 24 12:01:58 volumio volumio[5304]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 24 12:01:58 volumio volumio[5304]: Discovery: StartAdv! undefined Feb 24 12:01:58 volumio volumio[5304]: Discovery: Started advertising... Volumio - undefined Feb 24 12:01:58 volumio volumio[5304]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 24 12:01:58 volumio volumio[5304]: info: Loading plugin "autostart"... Feb 24 12:01:58 volumio volumio[5304]: info: Applying required configuration parameters for plugin autostart Feb 24 12:01:58 volumio volumio[5304]: info: Loading plugin "outputs"... Feb 24 12:01:58 volumio volumio[5304]: info: Loading plugin "albumart"... Feb 24 12:01:58 volumio volumio[5304]: info: Plugin example_plugin is not enabled Feb 24 12:01:58 volumio volumio[5304]: info: Loading plugin "inputs"... Feb 24 12:01:58 volumio volumio[5304]: info: Loading plugin "updater_comm"... Feb 24 12:01:58 volumio volumio[5304]: info: Plugin mpdemulation is not enabled Feb 24 12:01:58 volumio volumio[5304]: info: Loading plugin "rest_api"... Feb 24 12:01:58 volumio volumio[5304]: info: Loading plugin "websocket"... Feb 24 12:01:58 volumio volumio[5304]: info: Plugin lms is not enabled Feb 24 12:01:58 volumio volumio[5304]: info: Plugin squeezelite is not enabled Feb 24 12:01:58 volumio volumio[5304]: info: ___________ START PLUGINS ___________ Feb 24 12:01:58 volumio volumio[5304]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 24 12:01:58 volumio volumio[5304]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 24 12:01:58 volumio volumio[5304]: info: [1740398518926] CoreMusicLibrary::Adding element Media Servers Feb 24 12:01:58 volumio volumio[5304]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 24 12:01:59 volumio volumio[5304]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 24 12:01:59 volumio volumio[5304]: Forking 3 albumart workers Feb 24 12:01:59 volumio volumio[5304]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 24 12:01:59 volumio volumio[5304]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 24 12:01:59 volumio volumio[5304]: info: [1740398519052] CoreMusicLibrary::Adding element Last_100 Feb 24 12:01:59 volumio volumio[5304]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 24 12:01:59 volumio volumio[5304]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 24 12:01:59 volumio volumio[5304]: info: [1740398519058] CoreMusicLibrary::Adding element Webradio Feb 24 12:01:59 volumio volumio[5304]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 24 12:01:59 volumio volumio[5304]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 24 12:01:59 volumio volumio[5304]: info: ------------------------------------------- Feb 24 12:01:59 volumio volumio[5304]: info: ----- MyVolumio plugins startup ---- Feb 24 12:01:59 volumio volumio[5304]: info: ------------------------------------------- Feb 24 12:01:59 volumio volumio[5304]: info: [MyVolumio PluginManager] Fetching plans data.... Feb 24 12:01:59 volumio volumio[5304]: info: Loading i18n strings for locale zh Feb 24 12:01:59 volumio volumio[5304]: Updating browse sources language Feb 24 12:01:59 volumio volumio[5304]: Cannot find translation for sourceMedia Servers Feb 24 12:01:59 volumio volumio[5304]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 24 12:01:59 volumio volumio[5304]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 24 12:01:59 volumio volumio[5304]: info: CoreCommandRouter::initPlayerControls Feb 24 12:01:59 volumio volumio[5304]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 12:01:59 volumio volumio[5304]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAlsaCards Feb 24 12:01:59 volumio volumio[5304]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 12:01:59 volumio volumio[5304]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 12:01:59 volumio volumio[5304]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 12:01:59 volumio volumio[5304]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 12:01:59 volumio volumio[5304]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 12:01:59 volumio volumio[5304]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 24 12:01:59 volumio volumio[5304]: info: BOOT COMPLETED Feb 24 12:01:59 volumio volumio[5304]: [Metrics] CommandRouter: 3s 916.94ms Feb 24 12:01:59 volumio volumio[5304]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 24 12:01:59 volumio volumio[5304]: aplay: main:722: audio open error: Device or resource busy Feb 24 12:01:59 volumio volumio[5304]: Cannot play startup sound Feb 24 12:01:59 volumio volumio[5304]: info: CoreCommandRouter::Close All Modals sent Feb 24 12:01:59 volumio volumio[5304]: info: CoreCommandRouter::Close All Modals sent Feb 24 12:01:59 volumio volumio[5304]: Express server listening on port 3000 Feb 24 12:01:59 volumio volumio[5304]: [Metrics] WebUI: 4s 318.04ms Feb 24 12:01:59 volumio volumio[5304]: Volumio Calling Home Feb 24 12:01:59 volumio volumio[5304]: info: CoreStateMachine::resetVolumioState Feb 24 12:01:59 volumio volumio[5304]: info: CoreStateMachine::getcurrentVolume Feb 24 12:01:59 volumio volumio[5304]: info: CoreCommandRouter::volumioRetrievevolume Feb 24 12:01:59 volumio volumio[5304]: info: CoreStateMachine::pushState Feb 24 12:01:59 volumio volumio[5304]: info: CoreStateMachine::getState Feb 24 12:01:59 volumio volumio[5304]: info: CorePlayQueue::getTrack 0 Feb 24 12:01:59 volumio volumio[5304]: info: CoreCommandRouter::volumioPushState Feb 24 12:01:59 volumio volumio[5304]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 12:01:59 volumio volumio[5304]: info: Setting Device type: Raspberry PI Feb 24 12:01:59 volumio volumio[5304]: info: MPD running with PID796 ,establishing connection Feb 24 12:01:59 volumio volumio-remote-updater[743]: [2025-02-24 12:01:59] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.5.1" /socket.io/?EIO=4&transport=websocket&t=1740398518 101 Feb 24 12:02:00 volumio volumio[5304]: Starting albumart workers Feb 24 12:02:00 volumio volumio[5304]: Starting albumart workers Feb 24 12:02:00 volumio volumio[5304]: Starting albumart workers Feb 24 12:02:00 volumio volumio[5304]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Feb 24 12:02:00 volumio volumio[5304]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Feb 24 12:02:00 volumio volumio[5304]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Feb 24 12:02:00 volumio volumio[5304]: info: Reloading queue from file Feb 24 12:02:00 volumio volumio[5304]: info: CoreStateMachine::setRepeat null single undefined Feb 24 12:02:00 volumio volumio[5304]: info: CoreStateMachine::pushState Feb 24 12:02:00 volumio volumio[5304]: info: CoreStateMachine::getState Feb 24 12:02:00 volumio volumio[5304]: info: CorePlayQueue::getTrack 0 Feb 24 12:02:00 volumio volumio[5304]: info: CoreCommandRouter::volumioPushState Feb 24 12:02:00 volumio volumio[5304]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 12:02:00 volumio volumio[5304]: info: CoreStateMachine::setRandom true Feb 24 12:02:00 volumio volumio[5304]: info: CoreStateMachine::pushState Feb 24 12:02:00 volumio volumio[5304]: info: CoreStateMachine::getState Feb 24 12:02:00 volumio volumio[5304]: info: CorePlayQueue::getTrack 0 Feb 24 12:02:00 volumio volumio[5304]: info: CoreCommandRouter::volumioPushState Feb 24 12:02:00 volumio volumio[5304]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 12:02:01 volumio volumio[5304]: info: Starting Shairport Sync Feb 24 12:02:01 volumio volumio[5304]: info: Starting Shairport Sync Feb 24 12:02:01 volumio sudo[5413]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 24 12:02:01 volumio sudo[5415]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 24 12:02:01 volumio sudo[5413]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 12:02:01 volumio sudo[5415]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 12:02:01 volumio systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... Feb 24 12:02:01 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Feb 24 12:02:01 volumio sudo[5415]: pam_unix(sudo:session): session closed for user root Feb 24 12:02:01 volumio sudo[5413]: pam_unix(sudo:session): session closed for user root Feb 24 12:02:01 volumio systemd[5424]: Failed at step USER spawning /usr/local/bin/shairport-sync: No such process Feb 24 12:02:01 volumio systemd[1]: shairport-sync.service: main process exited, code=exited, status=217/USER Feb 24 12:02:01 volumio systemd[1]: Unit shairport-sync.service entered failed state. Feb 24 12:02:01 volumio volumio[5304]: Discovery: adding 963c78eb-b0ac-49c3-8afe-eef11e3e8641 Feb 24 12:02:01 volumio volumio[5304]: info: mDNS: Found device Volumio Feb 24 12:02:01 volumio volumio[5304]: info: CoreCommandRouter::volumioGetState Feb 24 12:02:01 volumio volumio[5304]: info: CoreStateMachine::getState Feb 24 12:02:01 volumio volumio[5304]: info: CorePlayQueue::getTrack 0 Feb 24 12:02:01 volumio sudo[5433]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 24 12:02:01 volumio sudo[5433]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 12:02:01 volumio sudo[5433]: pam_unix(sudo:session): session closed for user root Feb 24 12:02:01 volumio sudo[5437]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 24 12:02:01 volumio sudo[5437]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 12:02:01 volumio sudo[5437]: pam_unix(sudo:session): session closed for user root Feb 24 12:02:01 volumio volumio[5304]: info: Shairport-Sync Started Feb 24 12:02:01 volumio volumio[5304]: Error adding Membership: Error: addMembership EINVAL Feb 24 12:02:01 volumio volumio[5304]: info: Shairport-Sync Started Feb 24 12:02:01 volumio volumio[5304]: Discovery: this is already registered, 963c78eb-b0ac-49c3-8afe-eef11e3e8641 Feb 24 12:02:01 volumio volumio[5304]: info: mDNS: Found device Volumio Feb 24 12:02:01 volumio volumio[5304]: info: CoreCommandRouter::volumioGetState Feb 24 12:02:01 volumio volumio[5304]: info: CoreStateMachine::getState Feb 24 12:02:01 volumio volumio[5304]: info: CorePlayQueue::getTrack 0 Feb 24 12:02:01 volumio sudo[5451]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 24 12:02:01 volumio sudo[5451]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 12:02:01 volumio sudo[5451]: pam_unix(sudo:session): session closed for user root Feb 24 12:02:01 volumio sudo[5455]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 24 12:02:01 volumio sudo[5455]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 12:02:01 volumio sudo[5455]: pam_unix(sudo:session): session closed for user root Feb 24 12:02:02 volumio volumio[5304]: info: CoreCommandRouter::volumioGetState Feb 24 12:02:02 volumio volumio[5304]: info: CoreStateMachine::getState Feb 24 12:02:02 volumio volumio[5304]: info: CorePlayQueue::getTrack 0 Feb 24 12:02:02 volumio sudo[5471]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 24 12:02:02 volumio sudo[5471]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 12:02:02 volumio sudo[5471]: pam_unix(sudo:session): session closed for user root Feb 24 12:02:04 volumio volumio[5304]: info: CoreCommandRouter::volumioGetState Feb 24 12:02:04 volumio volumio[5304]: info: CoreStateMachine::getState Feb 24 12:02:04 volumio volumio[5304]: info: CorePlayQueue::getTrack 0 Feb 24 12:02:04 volumio sudo[5484]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 24 12:02:04 volumio sudo[5484]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 12:02:04 volumio volumio[5304]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 24 12:02:04 volumio volumio[5304]: info: CoreCommandRouter::volumioGetVisibleSources Feb 24 12:02:04 volumio sudo[5484]: pam_unix(sudo:session): session closed for user root Feb 24 12:02:04 volumio volumio[5304]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 24 12:02:04 volumio sudo[5488]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 24 12:02:04 volumio sudo[5488]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 12:02:04 volumio volumio[5304]: info: Listing playlists Feb 24 12:02:04 volumio sudo[5488]: pam_unix(sudo:session): session closed for user root Feb 24 12:02:04 volumio volumio[5304]: info: CoreCommandRouter::volumioGetQueue Feb 24 12:02:04 volumio volumio[5304]: info: CoreStateMachine::getQueue Feb 24 12:02:04 volumio volumio[5304]: info: CorePlayQueue::getQueue Feb 24 12:02:07 volumio sudo[5508]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 24 12:02:07 volumio sudo[5508]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 12:02:07 volumio sudo[5508]: pam_unix(sudo:session): session closed for user root Feb 24 12:02:07 volumio sudo[5517]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Feb 24 12:02:07 volumio sudo[5517]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 12:02:07 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Feb 24 12:02:07 volumio sudo[5517]: pam_unix(sudo:session): session closed for user root Feb 24 12:02:07 volumio volumio[5304]: info: Upmpdcli Daemon Started Feb 24 12:02:08 volumio volumio[5304]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Feb 24 12:02:08 volumio volumio[5304]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Feb 24 12:02:08 volumio volumio[5304]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Feb 24 12:02:08 volumio volumio[5304]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Feb 24 12:02:08 volumio volumio[5304]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Feb 24 12:02:08 volumio volumio[5304]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Feb 24 12:02:08 volumio volumio[5304]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Feb 24 12:02:08 volumio volumio[5304]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Feb 24 12:02:08 volumio volumio[5304]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Feb 24 12:02:08 volumio volumio[5304]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Feb 24 12:02:08 volumio volumio[5304]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Feb 24 12:02:08 volumio volumio[5304]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Feb 24 12:02:08 volumio volumio[5304]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Feb 24 12:02:08 volumio volumio[5304]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Feb 24 12:02:08 volumio volumio[5304]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Feb 24 12:02:08 volumio volumio[5304]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Feb 24 12:02:08 volumio volumio[5304]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Feb 24 12:02:08 volumio volumio[5304]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Feb 24 12:02:08 volumio volumio[5304]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Feb 24 12:02:08 volumio volumio[5304]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Feb 24 12:02:08 volumio volumio[5304]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Feb 24 12:02:08 volumio volumio[5304]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Feb 24 12:02:08 volumio volumio[5304]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Feb 24 12:02:08 volumio volumio[5304]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Feb 24 12:02:08 volumio volumio[5304]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Feb 24 12:02:08 volumio volumio[5304]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Feb 24 12:02:08 volumio volumio[5304]: info: Adding plugin bluetooth to MyMusic Plugins Feb 24 12:02:08 volumio volumio[5304]: info: Adding plugin cd_controller to MyMusic Plugins Feb 24 12:02:08 volumio volumio[5304]: info: Adding plugin smart_inputs to MyMusic Plugins Feb 24 12:02:08 volumio volumio[5304]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Feb 24 12:02:09 volumio volumio[5304]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Feb 24 12:02:10 volumio volumio[5304]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Feb 24 12:02:10 volumio volumio[5304]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Feb 24 12:02:10 volumio volumio[5304]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 24 12:02:10 volumio volumio[5304]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 24 12:02:10 volumio volumio[5304]: info: Starting MyVolumio Remote Streaming Endpoints Feb 24 12:02:10 volumio volumio[5304]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Feb 24 12:02:10 volumio volumio[5304]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Feb 24 12:02:10 volumio volumio[5304]: info: Streaming services startup Feb 24 12:02:10 volumio volumio[5304]: info: Starting Streaming Daemon Feb 24 12:02:10 volumio volumio[5304]: info: MyVolumio not started Feb 24 12:02:10 volumio volumio[5304]: info: Initializing device activation check Feb 24 12:02:10 volumio sudo[5528]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 24 12:02:10 volumio sudo[5528]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 12:02:10 volumio volumio[5304]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Feb 24 12:02:10 volumio systemd[1]: Stopping Volumio Streaming Daemon... Feb 24 12:02:10 volumio systemd[1]: Starting Volumio Streaming Daemon... Feb 24 12:02:10 volumio systemd[1]: Started Volumio Streaming Daemon. Feb 24 12:02:10 volumio sudo[5528]: pam_unix(sudo:session): session closed for user root Feb 24 12:02:10 volumio volumio-streaming-daemon[5534]: ############################ Feb 24 12:02:10 volumio volumio-streaming-daemon[5534]: # Volumio Streaming Daemon # Feb 24 12:02:10 volumio volumio-streaming-daemon[5534]: # Running on port 7777 # Feb 24 12:02:10 volumio volumio-streaming-daemon[5534]: ############################ Feb 24 12:02:10 volumio volumio-streaming-daemon[5534]: Environment: production Feb 24 12:02:10 volumio volumio[5304]: 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:02:10 volumio volumio[5304]: % Total % Received % Xferd Average Speed Time Time Time Current Feb 24 12:02:10 volumio volumio[5304]: Dload Upload Total Spent Left Speed Feb 24 12:02:10 volumio volumio[5304]: 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0curl: (7) Failed to connect to updates.volumio.org port 7070: Connection refused Feb 24 12:02:10 volumio volumio[5304]: retrying in 5 seconds, trial 0 Feb 24 12:02:10 volumio volumio[5304]: Volumio Calling Home Feb 24 12:02:13 volumio volumio-streaming-daemon[5534]: Environment: production Feb 24 12:02:13 volumio volumio[5304]: info: Fetching Streaming Services browse cache Feb 24 12:02:13 volumio volumio[5304]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesTIDAL Feb 24 12:02:13 volumio volumio[5304]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 24 12:02:13 volumio volumio[5304]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesQOBUZ Feb 24 12:02:13 volumio volumio[5304]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 24 12:02:18 volumio volumio[5304]: info: AutoStart - getting queue Feb 24 12:02:18 volumio volumio[5304]: info: CoreCommandRouter::volumioGetQueue Feb 24 12:02:18 volumio volumio[5304]: info: CoreStateMachine::getQueue Feb 24 12:02:18 volumio volumio[5304]: info: CorePlayQueue::getQueue Feb 24 12:02:18 volumio volumio[5304]: info: AutoStart - start playing -> queue is not empty Feb 24 12:02:18 volumio volumio[5304]: info: CoreCommandRouter::volumioPlay Feb 24 12:02:18 volumio volumio[5304]: UNSET VOLATILE Feb 24 12:02:18 volumio volumio[5304]: info: CoreStateMachine::play index 165 Feb 24 12:02:18 volumio volumio[5304]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 12:02:18 volumio volumio[5304]: info: CoreStateMachine::stop Feb 24 12:02:18 volumio volumio[5304]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 12:02:18 volumio volumio[5304]: UNSET VOLATILE Feb 24 12:02:18 volumio volumio[5304]: info: CoreStateMachine::play index undefined Feb 24 12:02:18 volumio volumio[5304]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 12:02:18 volumio volumio[5304]: info: CorePlayQueue::getTrack 165 Feb 24 12:02:18 volumio volumio[5304]: info: CoreStateMachine::startPlaybackTimer Feb 24 12:02:18 volumio volumio[5304]: info: CorePlayQueue::getTrack 165 Feb 24 12:02:18 volumio volumio[5304]: info: ControllerMpd::clearAddPlayTracks USB/6D82-CD6C/music/黃霑/笑傲江湖-百无禁忌黃霑作品集/黃霑 - 11.黎明.flac Feb 24 12:02:18 volumio volumio[5304]: info: ControllerMpd::sendMpdCommand stop Feb 24 12:02:18 volumio volumio[5304]: info: sending command... Feb 24 12:02:18 volumio volumio[5304]: info: parsing response... Feb 24 12:02:18 volumio volumio[5304]: info: ControllerMpd::sendMpdCommand clear Feb 24 12:02:18 volumio volumio[5304]: info: sending command... Feb 24 12:02:18 volumio volumio[5304]: info: Feb 24 12:02:18 volumio volumio[5304]: ---------------------------- MPD announces state update: player Feb 24 12:02:18 volumio volumio[5304]: info: Feb 24 12:02:18 volumio volumio[5304]: ---------------------------- MPD announces system playlist update Feb 24 12:02:18 volumio volumio[5304]: info: Ignoring MPD Status Update Feb 24 12:02:18 volumio volumio[5304]: info: ControllerMpd::getState Feb 24 12:02:18 volumio volumio[5304]: info: ControllerMpd::sendMpdCommand status Feb 24 12:02:18 volumio volumio[5304]: info: parsing response... Feb 24 12:02:18 volumio volumio[5304]: info: sending command... Feb 24 12:02:18 volumio volumio[5304]: info: ControllerMpd::sendMpdCommand add "USB/6D82-CD6C/music/黃霑/笑傲江湖-百无禁忌黃霑作品集/黃霑 - 11.黎明.flac" Feb 24 12:02:18 volumio volumio[5304]: info: sending command... Feb 24 12:02:18 volumio volumio[5304]: info: Feb 24 12:02:18 volumio volumio[5304]: info: Feb 24 12:02:18 volumio volumio[5304]: ---------------------------- MPD announces system playlist update Feb 24 12:02:18 volumio volumio[5304]: info: Ignoring MPD Status Update Feb 24 12:02:18 volumio volumio[5304]: info: parsing response... Feb 24 12:02:18 volumio volumio[5304]: info: ------------------------------ 26ms Feb 24 12:02:18 volumio volumio[5304]: info: parsing response... Feb 24 12:02:18 volumio volumio[5304]: info: ControllerMpd::parseState Feb 24 12:02:18 volumio volumio[5304]: info: ControllerMpd::sendMpdCommand play Feb 24 12:02:18 volumio volumio[5304]: info: ControllerMpd::pushState Feb 24 12:02:18 volumio volumio[5304]: info: CoreCommandRouter::servicePushState Feb 24 12:02:18 volumio volumio[5304]: info: CoreStateMachine::syncState Feb 24 12:02:18 volumio volumio[5304]: info: CoreStateMachine::pushState Feb 24 12:02:18 volumio volumio[5304]: info: CoreStateMachine::getState Feb 24 12:02:18 volumio volumio[5304]: info: CorePlayQueue::getTrack 165 Feb 24 12:02:18 volumio volumio[5304]: info: CoreCommandRouter::volumioPushState Feb 24 12:02:18 volumio volumio[5304]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 12:02:18 volumio volumio[5304]: info: CorePlayQueue::getTrack 165 Feb 24 12:02:18 volumio volumio[5304]: 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:02:18 volumio volumio[5304]: info: CURRENT POSITION 165 Feb 24 12:02:18 volumio volumio[5304]: info: CoreStateMachine::syncState stateService stop Feb 24 12:02:18 volumio volumio[5304]: info: CoreStateMachine::syncState currentStatus stop Feb 24 12:02:18 volumio volumio[5304]: info: CoreStateMachine::pushState Feb 24 12:02:18 volumio volumio[5304]: info: CoreStateMachine::getState Feb 24 12:02:18 volumio volumio[5304]: info: CorePlayQueue::getTrack 165 Feb 24 12:02:18 volumio sudo[5566]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 24 12:02:18 volumio volumio[5304]: info: CoreCommandRouter::volumioPushState Feb 24 12:02:18 volumio volumio[5304]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 12:02:18 volumio sudo[5566]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 12:02:18 volumio volumio[5304]: info: No code Feb 24 12:02:18 volumio volumio[5304]: info: CoreStateMachine::pushState Feb 24 12:02:18 volumio sudo[5566]: pam_unix(sudo:session): session closed for user root Feb 24 12:02:18 volumio volumio[5304]: info: CoreStateMachine::getState Feb 24 12:02:18 volumio volumio[5304]: info: CorePlayQueue::getTrack 165 Feb 24 12:02:18 volumio volumio[5304]: info: CoreCommandRouter::volumioPushState Feb 24 12:02:18 volumio volumio[5304]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 12:02:18 volumio sudo[5571]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 24 12:02:18 volumio sudo[5571]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 12:02:18 volumio volumio[5304]: info: sending command... Feb 24 12:02:18 volumio sudo[5571]: pam_unix(sudo:session): session closed for user root Feb 24 12:02:18 volumio volumio[5304]: info: ------------------------------ 135ms Feb 24 12:02:18 volumio volumio[5304]: info: Feb 24 12:02:18 volumio volumio[5304]: ---------------------------- MPD announces state update: player Feb 24 12:02:18 volumio volumio[5304]: info: ------------------------------ 116ms Feb 24 12:02:18 volumio volumio[5304]: info: parsing response... Feb 24 12:02:18 volumio volumio[5304]: info: ControllerMpd::getState Feb 24 12:02:18 volumio volumio[5304]: info: ControllerMpd::sendMpdCommand status Feb 24 12:02:18 volumio volumio[5304]: info: sending command... Feb 24 12:02:18 volumio sudo[5580]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 24 12:02:18 volumio sudo[5580]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 12:02:18 volumio volumio[5304]: info: parsing response... Feb 24 12:02:18 volumio volumio[5304]: info: ControllerMpd::parseState Feb 24 12:02:18 volumio volumio[5304]: info: ControllerMpd::sendMpdCommand playlistinfo Feb 24 12:02:18 volumio volumio[5304]: info: sending command... Feb 24 12:02:18 volumio sudo[5580]: pam_unix(sudo:session): session closed for user root Feb 24 12:02:18 volumio volumio[5304]: info: parsing response... Feb 24 12:02:18 volumio volumio[5304]: info: ControllerMpd::parseTrackInfo Feb 24 12:02:18 volumio volumio[5304]: info: ControllerMpd::pushState Feb 24 12:02:18 volumio volumio[5304]: info: CoreCommandRouter::servicePushState Feb 24 12:02:18 volumio volumio[5304]: info: CoreStateMachine::syncState Feb 24 12:02:18 volumio volumio[5304]: info: CorePlayQueue::getTrack 165 Feb 24 12:02:18 volumio volumio[5304]: 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":"1006 Kbps","isStreaming":false,"title":"黎明","artist":"黃霑","album":"笑傲江湖","uri":"USB/6D82-CD6C/music/黃霑/笑傲江湖-百无禁忌黃霑作品集/黃霑 - 11.黎明.flac","trackType":"flac"} Feb 24 12:02:18 volumio volumio[5304]: info: CURRENT POSITION 165 Feb 24 12:02:18 volumio volumio[5304]: info: CoreStateMachine::syncState stateService play Feb 24 12:02:18 volumio volumio[5304]: info: CoreStateMachine::syncState currentStatus stop Feb 24 12:02:18 volumio volumio[5304]: info: CoreStateMachine::pushState Feb 24 12:02:18 volumio volumio[5304]: info: CoreStateMachine::getState Feb 24 12:02:18 volumio volumio[5304]: info: CorePlayQueue::getTrack 165 Feb 24 12:02:18 volumio volumio[5304]: info: CoreCommandRouter::volumioPushState Feb 24 12:02:18 volumio volumio[5304]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 12:02:18 volumio sudo[5593]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 24 12:02:18 volumio sudo[5593]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 12:02:18 volumio volumio[5304]: info: ------------------------------ 99ms Feb 24 12:02:18 volumio sudo[5593]: pam_unix(sudo:session): session closed for user root Feb 24 12:02:21 volumio volumio[5304]: 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:02:21 volumio volumio[5304]: % Total % Received % Xferd Average Speed Time Time Time Current Feb 24 12:02:21 volumio volumio[5304]: Dload Upload Total Spent Left Speed Feb 24 12:02:21 volumio volumio[5304]: [235B blob data] Feb 24 12:02:21 volumio volumio[5304]: retrying in 5 seconds, trial 1 Feb 24 12:02:21 volumio volumio[5304]: Volumio Calling Home Feb 24 12:02:24 volumio volumio[5304]: info: CoreCommandRouter::volumioSeek Feb 24 12:02:24 volumio volumio[5304]: info: CoreStateMachine::seek Feb 24 12:02:24 volumio volumio[5304]: info: CorePlayQueue::getTrack 165 Feb 24 12:02:24 volumio volumio[5304]: info: TRACKBLOCK {"uri":"mnt/USB/6D82-CD6C/music/黃霑/笑傲江湖-百无禁忌黃霑作品集/黃霑 - 11.黎明.flac","service":"mpd","name":"11 - 黎明","artist":"黃霑","album":"笑傲江湖","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=483&web=%E9%BB%83%E9%9C%91/%E7%AC%91%E5%82%B2%E6%B1%9F%E6%B9%96/extralarge&path=%2FUSB%2F6D82-CD6C%2Fmusic%2F%E9%BB%83%E9%9C%91%2F%E7%AC%91%E5%82%B2%E6%B1%9F%E6%B9%96-%E7%99%BE%E6%97%A0%E7%A6%81%E5%BF%8C%E9%BB%83%E9%9C%91%E4%BD%9C%E5%93%81%E9%9B%86&metadata=false","duration":168,"trackType":"flac","samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2} Feb 24 12:02:24 volumio volumio[5304]: info: CoreStateMachine::startPlaybackTimer Feb 24 12:02:24 volumio volumio[5304]: info: CorePlayQueue::getTrack 165 Feb 24 12:02:24 volumio volumio[5304]: info: ControllerMpd::seek Feb 24 12:02:24 volumio volumio[5304]: info: CoreStateMachine::pushState Feb 24 12:02:24 volumio volumio[5304]: info: CoreStateMachine::getState Feb 24 12:02:24 volumio volumio[5304]: info: CorePlayQueue::getTrack 165 Feb 24 12:02:24 volumio volumio[5304]: info: CoreCommandRouter::volumioPushState Feb 24 12:02:24 volumio volumio[5304]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 12:02:24 volumio sudo[5618]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 24 12:02:24 volumio sudo[5618]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 12:02:24 volumio sudo[5618]: pam_unix(sudo:session): session closed for user root Feb 24 12:02:24 volumio volumio[5304]: error: Feb 24 12:02:24 volumio volumio[5304]: info: Feb 24 12:02:24 volumio volumio[5304]: ---------------------------- MPD announces state update: player Feb 24 12:02:24 volumio volumio[5304]: info: ControllerMpd::getState Feb 24 12:02:24 volumio volumio[5304]: info: ControllerMpd::sendMpdCommand status Feb 24 12:02:24 volumio volumio[5304]: info: sending command... Feb 24 12:02:24 volumio volumio[5304]: info: parsing response... Feb 24 12:02:24 volumio volumio[5304]: info: ControllerMpd::parseState Feb 24 12:02:24 volumio volumio[5304]: info: ControllerMpd::sendMpdCommand playlistinfo Feb 24 12:02:24 volumio volumio[5304]: info: sending command... Feb 24 12:02:24 volumio volumio[5304]: info: parsing response... Feb 24 12:02:24 volumio volumio[5304]: info: ControllerMpd::parseTrackInfo Feb 24 12:02:24 volumio volumio[5304]: info: ControllerMpd::pushState Feb 24 12:02:24 volumio volumio[5304]: info: CoreCommandRouter::servicePushState Feb 24 12:02:24 volumio volumio[5304]: info: CoreStateMachine::syncState Feb 24 12:02:24 volumio volumio[5304]: info: CorePlayQueue::getTrack 165 Feb 24 12:02:24 volumio volumio[5304]: info: STATE SERVICE {"status":"play","position":0,"seek":162000,"duration":168,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"679 Kbps","isStreaming":false,"title":"黎明","artist":"黃霑","album":"笑傲江湖","uri":"USB/6D82-CD6C/music/黃霑/笑傲江湖-百无禁忌黃霑作品集/黃霑 - 11.黎明.flac","trackType":"flac"} Feb 24 12:02:24 volumio volumio[5304]: info: CURRENT POSITION 165 Feb 24 12:02:24 volumio volumio[5304]: info: CoreStateMachine::syncState stateService play Feb 24 12:02:24 volumio volumio[5304]: info: CoreStateMachine::syncState currentStatus play Feb 24 12:02:24 volumio volumio[5304]: info: Received an update from plugin. extracting info from payload Feb 24 12:02:24 volumio volumio[5304]: info: CoreStateMachine::pushState Feb 24 12:02:24 volumio volumio[5304]: info: CoreStateMachine::getState Feb 24 12:02:24 volumio volumio[5304]: info: CorePlayQueue::getTrack 165 Feb 24 12:02:24 volumio volumio[5304]: info: CoreCommandRouter::volumioPushState Feb 24 12:02:24 volumio volumio[5304]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 12:02:24 volumio volumio[5304]: info: CoreStateMachine::pushState Feb 24 12:02:24 volumio volumio[5304]: info: CoreStateMachine::getState Feb 24 12:02:24 volumio volumio[5304]: info: CorePlayQueue::getTrack 165 Feb 24 12:02:24 volumio volumio[5304]: info: CoreCommandRouter::volumioPushState Feb 24 12:02:24 volumio volumio[5304]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 12:02:24 volumio sudo[5627]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 24 12:02:24 volumio sudo[5627]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 12:02:24 volumio sudo[5627]: pam_unix(sudo:session): session closed for user root Feb 24 12:02:24 volumio volumio[5304]: info: ------------------------------ 73ms Feb 24 12:02:24 volumio sudo[5632]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 24 12:02:24 volumio sudo[5632]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 12:02:24 volumio sudo[5632]: pam_unix(sudo:session): session closed for user root Feb 24 12:02:25 volumio volumio[5304]: info: CorePlayQueue::getTrack 165 Feb 24 12:02:25 volumio volumio[5304]: info: CorePlayQueue::getTrack 326 Feb 24 12:02:25 volumio volumio[5304]: info: Prefetching next song Feb 24 12:02:25 volumio volumio[5304]: info: DOING PREFETCH IN MPD Feb 24 12:02:25 volumio volumio[5304]: info: ControllerMpd::sendMpdCommand add "USB/6D82-CD6C/music/历年经典超级巨星精选集/张国荣.心跳呼吸正常.flac" Feb 24 12:02:25 volumio volumio[5304]: info: sending command... Feb 24 12:02:25 volumio volumio[5304]: info: Feb 24 12:02:25 volumio volumio[5304]: ---------------------------- MPD announces system playlist update Feb 24 12:02:25 volumio volumio[5304]: info: Ignoring MPD Status Update Feb 24 12:02:25 volumio volumio[5304]: info: parsing response... Feb 24 12:02:25 volumio volumio[5304]: info: ControllerMpd::sendMpdCommand consume 1 Feb 24 12:02:25 volumio volumio[5304]: info: sending command... Feb 24 12:02:25 volumio volumio[5304]: info: ------------------------------ 5ms Feb 24 12:02:25 volumio volumio[5304]: info: parsing response... Feb 24 12:02:29 volumio volumio[5304]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 24 12:02:29 volumio volumio[5304]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 24 12:02:30 volumio volumio[5304]: info: CoreStateMachine::startPlaybackTimer Feb 24 12:02:30 volumio volumio[5304]: info: CorePlayQueue::getTrack 326 Feb 24 12:02:30 volumio volumio[5304]: info: Feb 24 12:02:30 volumio volumio[5304]: ---------------------------- MPD announces system playlist update Feb 24 12:02:30 volumio volumio[5304]: info: Ignoring MPD Status Update Feb 24 12:02:30 volumio volumio[5304]: info: Feb 24 12:02:30 volumio volumio[5304]: ---------------------------- MPD announces state update: player Feb 24 12:02:30 volumio volumio[5304]: info: ControllerMpd::getState Feb 24 12:02:30 volumio volumio[5304]: info: ControllerMpd::sendMpdCommand status Feb 24 12:02:30 volumio volumio[5304]: info: sending command... Feb 24 12:02:30 volumio volumio[5304]: info: ------------------------------ 5ms Feb 24 12:02:30 volumio volumio[5304]: info: parsing response... Feb 24 12:02:30 volumio volumio[5304]: info: ControllerMpd::parseState Feb 24 12:02:30 volumio volumio[5304]: info: ControllerMpd::sendMpdCommand playlistinfo Feb 24 12:02:30 volumio volumio[5304]: info: sending command... Feb 24 12:02:30 volumio volumio[5304]: info: parsing response... Feb 24 12:02:30 volumio volumio[5304]: info: ControllerMpd::parseTrackInfo Feb 24 12:02:30 volumio volumio[5304]: info: ControllerMpd::pushState Feb 24 12:02:30 volumio volumio[5304]: info: CoreCommandRouter::servicePushState Feb 24 12:02:30 volumio volumio[5304]: info: CoreStateMachine::syncState Feb 24 12:02:30 volumio volumio[5304]: info: CorePlayQueue::getTrack 326 Feb 24 12:02:30 volumio volumio[5304]: info: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":268,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"459 Kbps","isStreaming":false,"title":"心跳呼吸正常","artist":"张国荣","album":"历年经典超级巨星精选集 经典珍藏版CD11","uri":"USB/6D82-CD6C/music/历年经典超级巨星精选集/张国荣.心跳呼吸正常.flac","trackType":"flac"} Feb 24 12:02:30 volumio volumio[5304]: info: CURRENT POSITION 326 Feb 24 12:02:30 volumio volumio[5304]: info: CoreStateMachine::syncState stateService play Feb 24 12:02:30 volumio volumio[5304]: info: CoreStateMachine::syncState currentStatus play Feb 24 12:02:30 volumio volumio[5304]: info: Received an update from plugin. extracting info from payload Feb 24 12:02:30 volumio volumio[5304]: info: CoreStateMachine::pushState Feb 24 12:02:30 volumio volumio[5304]: info: CoreStateMachine::getState Feb 24 12:02:30 volumio volumio[5304]: info: CorePlayQueue::getTrack 326 Feb 24 12:02:30 volumio volumio[5304]: info: CoreCommandRouter::volumioPushState Feb 24 12:02:30 volumio volumio[5304]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 12:02:30 volumio volumio[5304]: info: CoreStateMachine::pushState Feb 24 12:02:30 volumio volumio[5304]: info: CoreStateMachine::getState Feb 24 12:02:30 volumio volumio[5304]: info: CorePlayQueue::getTrack 326 Feb 24 12:02:30 volumio volumio[5304]: info: CoreCommandRouter::volumioPushState Feb 24 12:02:30 volumio volumio[5304]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 12:02:30 volumio sudo[5659]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 24 12:02:30 volumio sudo[5659]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 12:02:30 volumio sudo[5659]: pam_unix(sudo:session): session closed for user root Feb 24 12:02:30 volumio volumio[5304]: info: ------------------------------ 70ms Feb 24 12:02:30 volumio sudo[5664]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 24 12:02:30 volumio sudo[5664]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 12:02:30 volumio sudo[5664]: pam_unix(sudo:session): session closed for user root Feb 24 12:02:31 volumio volumio[5304]: 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:02:31 volumio volumio[5304]: % Total % Received % Xferd Average Speed Time Time Time Current Feb 24 12:02:31 volumio volumio[5304]: Dload Upload Total Spent Left Speed Feb 24 12:02:31 volumio volumio[5304]: 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0curl: (7) Failed to connect to updates.volumio.org port 7070: Connection refused Feb 24 12:02:31 volumio volumio[5304]: retrying in 5 seconds, trial 2 Feb 24 12:02:31 volumio volumio[5304]: Volumio Calling Home Feb 24 12:02:34 volumio volumio[5304]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 24 12:02:34 volumio volumio[5304]: { Error: connect ETIMEDOUT 108.160.162.98:443 Feb 24 12:02:34 volumio volumio[5304]: at Object._errnoException (util.js:1022:11) Feb 24 12:02:34 volumio volumio[5304]: at _exceptionWithHostPort (util.js:1044:20) Feb 24 12:02:34 volumio volumio[5304]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1198:14) Feb 24 12:02:34 volumio volumio[5304]: code: 'ETIMEDOUT', Feb 24 12:02:34 volumio volumio[5304]: errno: 'ETIMEDOUT', Feb 24 12:02:34 volumio volumio[5304]: syscall: 'connect', Feb 24 12:02:34 volumio volumio[5304]: address: '108.160.162.98', Feb 24 12:02:34 volumio volumio[5304]: port: 443 } Feb 24 12:02:34 volumio volumio[5304]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 24 12:02:34 volumio sudo[5693]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-02-24 12:01 Feb 24 12:02:34 volumio sudo[5693]: 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"