-- Logs begin at Wed 2024-04-17 01:12:12 UTC, end at Fri 2024-04-19 10:26:54 UTC. --
Apr 19 10:25:05 volumio volumio[1078]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Apr 19 10:25:05 volumio volumio[1078]: { Error: connect ETIMEDOUT 185.60.216.36:443
Apr 19 10:25:06 volumio volumio[1078]: at Object._errnoException (util.js:1022:11)
Apr 19 10:25:06 volumio volumio[1078]: at _exceptionWithHostPort (util.js:1044:20)
Apr 19 10:25:06 volumio volumio[1078]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1198:14)
Apr 19 10:25:06 volumio volumio[1078]: code: 'ETIMEDOUT',
Apr 19 10:25:06 volumio volumio[1078]: errno: 'ETIMEDOUT',
Apr 19 10:25:06 volumio volumio[1078]: syscall: 'connect',
Apr 19 10:25:06 volumio volumio[1078]: address: '185.60.216.36',
Apr 19 10:25:06 volumio volumio[1078]: port: 443 }
Apr 19 10:25:06 volumio volumio[1078]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Apr 19 10:25:06 volumio sudo[1899]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-19 10:24
Apr 19 10:25:06 volumio sudo[1899]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 19 10:25:06 volumio sudo[1899]: pam_unix(sudo:session): session closed for user root
Apr 19 10:25:09 volumio volumio-remote-updater[601]: [2024-04-19 10:25:09] [error] handle_read_frame error: websocketpp.transport:7 (End of File)
Apr 19 10:25:09 volumio volumio-remote-updater[601]: [2024-04-19 10:25:09] [disconnect] Disconnect close local:[1006,End of File] remote:[1006]
Apr 19 10:25:09 volumio systemd[1]: volumio.service: main process exited, code=exited, status=1/FAILURE
Apr 19 10:25:09 volumio systemd[1]: Unit volumio.service entered failed state.
Apr 19 10:25:09 volumio systemd[1]: Starting dynamicswap service...
Apr 19 10:25:09 volumio systemd[1]: Started dynamicswap service.
Apr 19 10:25:09 volumio systemd[1]: volumio.service holdoff time over, scheduling restart.
Apr 19 10:25:09 volumio systemd[1]: Starting dynamicswap service...
Apr 19 10:25:09 volumio systemd[1]: Started dynamicswap service.
Apr 19 10:25:09 volumio systemd[1]: Stopping Volumio Backend Module...
Apr 19 10:25:09 volumio systemd[1]: Starting Volumio Backend Module...
Apr 19 10:25:09 volumio systemd[1]: Started Volumio Backend Module.
Apr 19 10:25:10 volumio volumio[1925]: info: -------------------------------------------
Apr 19 10:25:10 volumio volumio[1925]: info: ----- Volumio2 ----
Apr 19 10:25:10 volumio volumio[1925]: info: -------------------------------------------
Apr 19 10:25:10 volumio volumio[1925]: info: ----- System startup ----
Apr 19 10:25:10 volumio volumio[1925]: info: -------------------------------------------
Apr 19 10:25:11 volumio volumio[1925]: info: MYVOLUMIO Environment detected
Apr 19 10:25:11 volumio volumio[1925]: info: Plugin folders cleanup
Apr 19 10:25:11 volumio volumio[1925]: info: Scanning into folder /volumio/app/plugins/
Apr 19 10:25:11 volumio volumio[1925]: info: Scanning category audio_interface
Apr 19 10:25:11 volumio volumio[1925]: info: Scanning category miscellanea
Apr 19 10:25:11 volumio volumio[1925]: info: Scanning category music_service
Apr 19 10:25:11 volumio volumio[1925]: info: Scanning category plugins.json
Apr 19 10:25:11 volumio volumio[1925]: info: Scanning category system_controller
Apr 19 10:25:11 volumio volumio[1925]: info: Scanning category user_interface
Apr 19 10:25:11 volumio volumio[1925]: info: Scanning into folder /data/plugins/
Apr 19 10:25:11 volumio volumio[1925]: info: Plugin folders cleanup completed
Apr 19 10:25:11 volumio volumio[1925]: info: -------------------------------------------
Apr 19 10:25:11 volumio volumio[1925]: info: ----- Core plugins startup ----
Apr 19 10:25:11 volumio volumio[1925]: info: -------------------------------------------
Apr 19 10:25:11 volumio volumio[1925]: info: Loading plugins from folder /volumio/app/plugins/
Apr 19 10:25:11 volumio volumio[1925]: info: Adding plugin upnp to MyMusic Plugins
Apr 19 10:25:11 volumio volumio[1925]: info: Adding plugin airplay_emulation to MyMusic Plugins
Apr 19 10:25:11 volumio volumio[1925]: info: Adding plugin upnp_browser to MyMusic Plugins
Apr 19 10:25:11 volumio volumio[1925]: info: Loading plugins from folder /data/plugins/
Apr 19 10:25:11 volumio volumio[1925]: info: Loading plugin "system"...
Apr 19 10:25:11 volumio volumio[1925]: info: Loading plugin "appearance"...
Apr 19 10:25:11 volumio volumio[1925]: info: Loading plugin "network"...
Apr 19 10:25:11 volumio volumio[1925]: info: Refreshing Cached IP Addresses
Apr 19 10:25:11 volumio sudo[1967]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Apr 19 10:25:11 volumio volumio[1925]: info: Loading plugin "services"...
Apr 19 10:25:11 volumio volumio[1925]: info: Loading plugin "alsa_controller"...
Apr 19 10:25:11 volumio sudo[1967]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 19 10:25:11 volumio sudo[1967]: pam_unix(sudo:session): session closed for user root
Apr 19 10:25:11 volumio sudo[1970]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Apr 19 10:25:11 volumio sudo[1970]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 19 10:25:11 volumio sudo[1970]: pam_unix(sudo:session): session closed for user root
Apr 19 10:25:11 volumio volumio[1925]: info: Loading plugin "wizard"...
Apr 19 10:25:11 volumio volumio[1925]: info: Loading plugin "volumio_command_line_client"...
Apr 19 10:25:11 volumio volumio[1925]: info: Loading plugin "upnp"...
Apr 19 10:25:11 volumio volumio[1925]: info: [1713522311915] Starting Upmpd Daemon
Apr 19 10:25:11 volumio volumio[1925]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Apr 19 10:25:11 volumio volumio[1925]: info: Loading plugin "my_music"...
Apr 19 10:25:11 volumio volumio[1925]: info: Loading plugin "mpd"...
Apr 19 10:25:12 volumio volumio[1925]: info: Loading plugin "upnp_browser"...
Apr 19 10:25:12 volumio volumio[1925]: info: Loading plugin "networkfs"...
Apr 19 10:25:12 volumio volumio[1925]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Apr 19 10:25:12 volumio volumio[1925]: info: Loading plugin "alarm-clock"...
Apr 19 10:25:12 volumio volumio[1925]: info: Plugin airplay_emulation is not enabled
Apr 19 10:25:12 volumio volumio[1925]: info: Loading plugin "last_100"...
Apr 19 10:25:12 volumio volumio[1925]: info: Loading plugin "webradio"...
Apr 19 10:25:12 volumio volumio[1925]: info: Loading plugin "i2s_dacs"...
Apr 19 10:25:12 volumio volumio[1925]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 19 10:25:12 volumio volumio[1925]: info: Loading plugin "volumiodiscovery"...
Apr 19 10:25:12 volumio volumio[1925]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi.
Apr 19 10:25:12 volumio node[1925]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi.
Apr 19 10:25:12 volumio node[1925]: *** WARNING *** Please fix your application to use the native API of Avahi!
Apr 19 10:25:12 volumio node[1925]: *** WARNING *** For more information see
Apr 19 10:25:12 volumio node[1925]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi.
Apr 19 10:25:12 volumio node[1925]: *** WARNING *** Please fix your application to use the native API of Avahi!
Apr 19 10:25:12 volumio node[1925]: *** WARNING *** For more information see
Apr 19 10:25:12 volumio volumio[1925]: *** WARNING *** Please fix your application to use the native API of Avahi!
Apr 19 10:25:12 volumio volumio[1925]: *** WARNING *** For more information see
Apr 19 10:25:12 volumio volumio[1925]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi.
Apr 19 10:25:12 volumio volumio[1925]: *** WARNING *** Please fix your application to use the native API of Avahi!
Apr 19 10:25:12 volumio volumio[1925]: *** WARNING *** For more information see
Apr 19 10:25:12 volumio volumio[1925]: info: Applying required configuration parameters for plugin volumiodiscovery
Apr 19 10:25:12 volumio volumio[1925]: Discovery: StartAdv! undefined
Apr 19 10:25:12 volumio volumio[1925]: Discovery: Started advertising... Volumio - undefined
Apr 19 10:25:12 volumio volumio[1925]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Apr 19 10:25:12 volumio volumio[1925]: info: Loading plugin "outputs"...
Apr 19 10:25:12 volumio volumio[1925]: info: Loading plugin "albumart"...
Apr 19 10:25:12 volumio volumio[1925]: info: Plugin example_plugin is not enabled
Apr 19 10:25:12 volumio volumio[1925]: info: Loading plugin "inputs"...
Apr 19 10:25:12 volumio volumio[1925]: info: Loading plugin "updater_comm"...
Apr 19 10:25:12 volumio volumio[1925]: info: Plugin mpdemulation is not enabled
Apr 19 10:25:12 volumio volumio[1925]: info: Loading plugin "rest_api"...
Apr 19 10:25:12 volumio volumio[1925]: info: Loading plugin "websocket"...
Apr 19 10:25:12 volumio volumio[1925]: info: ___________ START PLUGINS ___________
Apr 19 10:25:12 volumio volumio[1925]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Apr 19 10:25:12 volumio volumio[1925]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Apr 19 10:25:12 volumio volumio[1925]: info: [1713522312894] CoreMusicLibrary::Adding element Media Servers
Apr 19 10:25:12 volumio volumio[1925]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Apr 19 10:25:12 volumio volumio[1925]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Apr 19 10:25:12 volumio volumio[1925]: info: [1713522312912] CoreMusicLibrary::Adding element Last_100
Apr 19 10:25:12 volumio volumio[1925]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Apr 19 10:25:12 volumio volumio[1925]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Apr 19 10:25:12 volumio volumio[1925]: info: [1713522312914] CoreMusicLibrary::Adding element Webradio
Apr 19 10:25:12 volumio volumio[1925]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Apr 19 10:25:12 volumio volumio[1925]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Apr 19 10:25:12 volumio volumio[1925]: info: -------------------------------------------
Apr 19 10:25:12 volumio volumio[1925]: info: ----- MyVolumio plugins startup ----
Apr 19 10:25:12 volumio volumio[1925]: info: -------------------------------------------
Apr 19 10:25:12 volumio volumio[1925]: info: [MyVolumio PluginManager] Fetching plans data....
Apr 19 10:25:12 volumio volumio[1925]: info: Loading i18n strings for locale zh
Apr 19 10:25:12 volumio volumio[1925]: Updating browse sources language
Apr 19 10:25:12 volumio volumio[1925]: Cannot find translation for sourceMedia Servers
Apr 19 10:25:12 volumio volumio[1925]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Apr 19 10:25:12 volumio volumio[1925]: Forking 3 albumart workers
Apr 19 10:25:12 volumio volumio[1925]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Apr 19 10:25:12 volumio volumio[1925]: info: CoreCommandRouter::initPlayerControls
Apr 19 10:25:12 volumio volumio[1925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 19 10:25:12 volumio volumio[1925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAlsaCards
Apr 19 10:25:13 volumio volumio[1925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 19 10:25:13 volumio volumio[1925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 19 10:25:13 volumio volumio[1925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 19 10:25:13 volumio volumio[1925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 19 10:25:13 volumio volumio[1925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 19 10:25:13 volumio volumio[1925]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Apr 19 10:25:13 volumio volumio[1925]: info: BOOT COMPLETED
Apr 19 10:25:13 volumio volumio[1925]: [Metrics] CommandRouter: 2s 504.90ms
Apr 19 10:25:13 volumio volumio[1925]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 19 10:25:13 volumio volumio[1925]: aplay: main:722: audio open error: Device or resource busy
Apr 19 10:25:13 volumio volumio[1925]: Cannot play startup sound
Apr 19 10:25:13 volumio volumio[1925]: info: CoreCommandRouter::Close All Modals sent
Apr 19 10:25:13 volumio volumio[1925]: info: CoreCommandRouter::Close All Modals sent
Apr 19 10:25:13 volumio volumio[1925]: Express server listening on port 3000
Apr 19 10:25:13 volumio volumio[1925]: [Metrics] WebUI: 2s 861.72ms
Apr 19 10:25:13 volumio volumio[1925]: Volumio Calling Home
Apr 19 10:25:13 volumio volumio[1925]: info: CoreStateMachine::resetVolumioState
Apr 19 10:25:13 volumio volumio[1925]: info: CoreStateMachine::getcurrentVolume
Apr 19 10:25:13 volumio volumio[1925]: info: CoreCommandRouter::volumioRetrievevolume
Apr 19 10:25:13 volumio volumio[1925]: info: CoreStateMachine::pushState
Apr 19 10:25:13 volumio volumio[1925]: info: CorePlayQueue::getTrack 0
Apr 19 10:25:13 volumio volumio[1925]: info: CoreCommandRouter::volumioPushState
Apr 19 10:25:13 volumio volumio[1925]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 19 10:25:13 volumio volumio[1925]: info: Setting Device type: Raspberry PI
Apr 19 10:25:13 volumio volumio[1925]: info: MPD running with PID667
Apr 19 10:25:13 volumio volumio[1925]: ,establishing connection
Apr 19 10:25:13 volumio volumio[1925]: Starting albumart workers
Apr 19 10:25:13 volumio volumio[1925]: Starting albumart workers
Apr 19 10:25:13 volumio volumio[1925]: info: Reloading queue from file
Apr 19 10:25:13 volumio volumio[1925]: info: CoreStateMachine::setRepeat true single undefined
Apr 19 10:25:13 volumio volumio[1925]: info: CoreStateMachine::pushState
Apr 19 10:25:13 volumio volumio[1925]: info: CorePlayQueue::getTrack 0
Apr 19 10:25:13 volumio volumio[1925]: info: CoreCommandRouter::volumioPushState
Apr 19 10:25:13 volumio volumio[1925]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 19 10:25:13 volumio volumio[1925]: info: CoreStateMachine::setRandom null
Apr 19 10:25:13 volumio volumio[1925]: info: CoreStateMachine::pushState
Apr 19 10:25:13 volumio volumio[1925]: info: CorePlayQueue::getTrack 0
Apr 19 10:25:13 volumio volumio[1925]: info: CoreCommandRouter::volumioPushState
Apr 19 10:25:13 volumio volumio[1925]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 19 10:25:13 volumio volumio[1925]: Starting albumart workers
Apr 19 10:25:14 volumio volumio-remote-updater[601]: [2024-04-19 10:25:14] [connect] Successful connection
Apr 19 10:25:14 volumio volumio[1925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable
Apr 19 10:25:14 volumio volumio[1925]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
Apr 19 10:25:14 volumio volumio[1925]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect
Apr 19 10:25:14 volumio volumio-remote-updater[601]: [2024-04-19 10:25:14] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.5.1" /socket.io/?EIO=4&transport=websocket&t=1713522314 101
Apr 19 10:25:14 volumio volumio[1925]: Discovery: adding c520c04b-4654-40b9-adc1-a54afb8ba9d6
Apr 19 10:25:14 volumio volumio[1925]: info: mDNS: Found device Volumio
Apr 19 10:25:14 volumio volumio[1925]: info: CoreCommandRouter::volumioGetState
Apr 19 10:25:14 volumio volumio[1925]: info: CorePlayQueue::getTrack 0
Apr 19 10:25:15 volumio volumio[1925]: info: CoreCommandRouter::volumioGetState
Apr 19 10:25:15 volumio volumio[1925]: info: CorePlayQueue::getTrack 0
Apr 19 10:25:17 volumio volumio[1925]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 19 10:25:17 volumio volumio[1925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 19 10:25:21 volumio sudo[2070]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Apr 19 10:25:21 volumio sudo[2070]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 19 10:25:21 volumio sudo[2072]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Apr 19 10:25:21 volumio sudo[2070]: pam_unix(sudo:session): session closed for user root
Apr 19 10:25:21 volumio sudo[2072]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 19 10:25:21 volumio sudo[2072]: pam_unix(sudo:session): session closed for user root
Apr 19 10:25:21 volumio sudo[2082]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service
Apr 19 10:25:21 volumio sudo[2082]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 19 10:25:21 volumio systemd[1]: Started UPnP Renderer front-end to MPD.
Apr 19 10:25:21 volumio sudo[2082]: pam_unix(sudo:session): session closed for user root
Apr 19 10:25:22 volumio volumio[1925]: info: Upmpdcli Daemon Started
Apr 19 10:25:23 volumio volumio[1925]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=2.861&uuid=092cfaf002ff8684a2d496083d40a25f" http://updates.volumio.org:7070/downloader-v1/track-device
Apr 19 10:25:23 volumio volumio[1925]: % Total % Received % Xferd Average Speed Time Time Time Current
Apr 19 10:25:23 volumio volumio[1925]: Dload Upload Total Spent Left Speed
Apr 19 10:25:23 volumio volumio[1925]: 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0curl: (7) Failed to connect to updates.volumio.org port 7070: Connection refused
Apr 19 10:25:23 volumio volumio[1925]: retrying in 5 seconds, trial 0
Apr 19 10:25:23 volumio volumio[1925]: Volumio Calling Home
Apr 19 10:25:23 volumio volumio[1925]: info: CoreCommandRouter::volumioGetState
Apr 19 10:25:23 volumio volumio[1925]: info: CorePlayQueue::getTrack 0
Apr 19 10:25:23 volumio volumio[1925]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom
Apr 19 10:25:23 volumio volumio[1925]: info: Listing playlists
Apr 19 10:25:23 volumio volumio[1925]: info: CoreCommandRouter::volumioGetQueue
Apr 19 10:25:23 volumio volumio[1925]: info: CoreStateMachine::getQueue
Apr 19 10:25:23 volumio volumio[1925]: info: CorePlayQueue::getQueue
Apr 19 10:25:23 volumio volumio[1925]: info: CoreCommandRouter::volumioGetVisibleSources
Apr 19 10:25:23 volumio volumio[1925]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Apr 19 10:25:24 volumio volumio[1925]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium
Apr 19 10:25:24 volumio volumio[1925]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium
Apr 19 10:25:24 volumio volumio[1925]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium
Apr 19 10:25:24 volumio volumio[1925]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium
Apr 19 10:25:24 volumio volumio[1925]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium
Apr 19 10:25:24 volumio volumio[1925]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium
Apr 19 10:25:24 volumio volumio[1925]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium
Apr 19 10:25:24 volumio volumio[1925]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium
Apr 19 10:25:24 volumio volumio[1925]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium
Apr 19 10:25:24 volumio volumio[1925]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium
Apr 19 10:25:24 volumio volumio[1925]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar
Apr 19 10:25:24 volumio volumio[1925]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar
Apr 19 10:25:24 volumio volumio[1925]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar
Apr 19 10:25:24 volumio volumio[1925]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar
Apr 19 10:25:24 volumio volumio[1925]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar
Apr 19 10:25:24 volumio volumio[1925]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar
Apr 19 10:25:24 volumio volumio[1925]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar
Apr 19 10:25:24 volumio volumio[1925]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar
Apr 19 10:25:24 volumio volumio[1925]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar
Apr 19 10:25:24 volumio volumio[1925]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar
Apr 19 10:25:24 volumio volumio[1925]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso
Apr 19 10:25:24 volumio volumio[1925]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso
Apr 19 10:25:24 volumio volumio[1925]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso
Apr 19 10:25:24 volumio volumio[1925]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso
Apr 19 10:25:24 volumio volumio[1925]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso
Apr 19 10:25:24 volumio volumio[1925]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso
Apr 19 10:25:24 volumio volumio[1925]: info: Adding plugin bluetooth to MyMusic Plugins
Apr 19 10:25:24 volumio volumio[1925]: info: Adding plugin cd_controller to MyMusic Plugins
Apr 19 10:25:24 volumio volumio[1925]: info: Adding plugin smart_inputs to MyMusic Plugins
Apr 19 10:25:24 volumio volumio[1925]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"...
Apr 19 10:25:25 volumio volumio[1925]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"...
Apr 19 10:25:25 volumio volumio[1925]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded
Apr 19 10:25:25 volumio volumio[1925]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio
Apr 19 10:25:25 volumio volumio[1925]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 19 10:25:25 volumio volumio[1925]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 19 10:25:25 volumio volumio[1925]: info: Starting MyVolumio Remote Streaming Endpoints
Apr 19 10:25:25 volumio volumio[1925]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded
Apr 19 10:25:25 volumio volumio[1925]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services
Apr 19 10:25:25 volumio volumio[1925]: info: Streaming services startup
Apr 19 10:25:25 volumio volumio[1925]: info: Starting Streaming Daemon
Apr 19 10:25:25 volumio sudo[2106]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service
Apr 19 10:25:25 volumio sudo[2106]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 19 10:25:25 volumio systemd[1]: Stopping Volumio Streaming Daemon...
Apr 19 10:25:25 volumio volumio[1925]: info: MyVolumio not started
Apr 19 10:25:25 volumio volumio[1925]: info: Initializing device activation check
Apr 19 10:25:25 volumio systemd[1]: Starting Volumio Streaming Daemon...
Apr 19 10:25:25 volumio systemd[1]: Started Volumio Streaming Daemon.
Apr 19 10:25:25 volumio sudo[2106]: pam_unix(sudo:session): session closed for user root
Apr 19 10:25:25 volumio volumio-streaming-daemon[2116]: ############################
Apr 19 10:25:25 volumio volumio-streaming-daemon[2116]: # Volumio Streaming Daemon #
Apr 19 10:25:25 volumio volumio-streaming-daemon[2116]: # Running on port 7777 #
Apr 19 10:25:25 volumio volumio-streaming-daemon[2116]: ############################
Apr 19 10:25:25 volumio volumio-streaming-daemon[2116]: Environment: production
Apr 19 10:25:25 volumio volumio[1925]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started
Apr 19 10:25:28 volumio volumio-streaming-daemon[2116]: Environment: production
Apr 19 10:25:28 volumio volumio[1925]: info: Fetching Streaming Services browse cache
Apr 19 10:25:28 volumio volumio[1925]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri
Apr 19 10:25:34 volumio volumio[1925]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=2.861&uuid=092cfaf002ff8684a2d496083d40a25f" http://updates.volumio.org:7070/downloader-v1/track-device
Apr 19 10:25:34 volumio volumio[1925]: % Total % Received % Xferd Average Speed Time Time Time Current
Apr 19 10:25:34 volumio volumio[1925]: Dload Upload Total Spent Left Speed
Apr 19 10:25:34 volumio volumio[1925]: [235B blob data]
Apr 19 10:25:34 volumio volumio[1925]: retrying in 5 seconds, trial 1
Apr 19 10:25:34 volumio volumio[1925]: Volumio Calling Home
Apr 19 10:25:42 volumio volumio[1925]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Apr 19 10:25:42 volumio volumio[1925]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 19 10:25:44 volumio volumio[1925]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=2.861&uuid=092cfaf002ff8684a2d496083d40a25f" http://updates.volumio.org:7070/downloader-v1/track-device
Apr 19 10:25:44 volumio volumio[1925]: % Total % Received % Xferd Average Speed Time Time Time Current
Apr 19 10:25:44 volumio volumio[1925]: Dload Upload Total Spent Left Speed
Apr 19 10:25:44 volumio volumio[1925]: 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0curl: (7) Failed to connect to updates.volumio.org port 7070: Connection refused
Apr 19 10:25:44 volumio volumio[1925]: retrying in 5 seconds, trial 2
Apr 19 10:25:44 volumio volumio[1925]: Volumio Calling Home
Apr 19 10:25:45 volumio volumio[1925]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri
Apr 19 10:25:45 volumio volumio[1925]: info: CURURI: playlists
Apr 19 10:25:45 volumio volumio[1925]: info: Listing playlists
Apr 19 10:25:48 volumio volumio[1925]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Apr 19 10:25:48 volumio volumio[1925]: { Error: connect ETIMEDOUT 185.60.216.36:443
Apr 19 10:25:48 volumio volumio[1925]: at Object._errnoException (util.js:1022:11)
Apr 19 10:25:48 volumio volumio[1925]: at _exceptionWithHostPort (util.js:1044:20)
Apr 19 10:25:48 volumio volumio[1925]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1198:14)
Apr 19 10:25:48 volumio volumio[1925]: code: 'ETIMEDOUT',
Apr 19 10:25:48 volumio volumio[1925]: errno: 'ETIMEDOUT',
Apr 19 10:25:48 volumio volumio[1925]: syscall: 'connect',
Apr 19 10:25:48 volumio volumio[1925]: address: '185.60.216.36',
Apr 19 10:25:48 volumio volumio[1925]: port: 443 }
Apr 19 10:25:48 volumio volumio[1925]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Apr 19 10:25:48 volumio sudo[2191]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-19 10:24
Apr 19 10:25:48 volumio sudo[2191]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 19 10:25:48 volumio sudo[2191]: pam_unix(sudo:session): session closed for user root
Apr 19 10:25:48 volumio volumio-remote-updater[601]: [2024-04-19 10:25:48] [error] handle_read_frame error: websocketpp.transport:7 (End of File)
Apr 19 10:25:48 volumio volumio-remote-updater[601]: [2024-04-19 10:25:48] [disconnect] Disconnect close local:[1006,End of File] remote:[1006]
Apr 19 10:25:48 volumio systemd[1]: volumio.service: main process exited, code=exited, status=1/FAILURE
Apr 19 10:25:48 volumio systemd[1]: Unit volumio.service entered failed state.
Apr 19 10:25:48 volumio systemd[1]: Starting dynamicswap service...
Apr 19 10:25:48 volumio systemd[1]: Started dynamicswap service.
Apr 19 10:25:48 volumio systemd[1]: volumio.service holdoff time over, scheduling restart.
Apr 19 10:25:48 volumio systemd[1]: Starting dynamicswap service...
Apr 19 10:25:48 volumio systemd[1]: Started dynamicswap service.
Apr 19 10:25:48 volumio systemd[1]: Stopping Volumio Backend Module...
Apr 19 10:25:48 volumio systemd[1]: Starting Volumio Backend Module...
Apr 19 10:25:48 volumio systemd[1]: Started Volumio Backend Module.
Apr 19 10:25:49 volumio volumio[2212]: info: -------------------------------------------
Apr 19 10:25:49 volumio volumio[2212]: info: ----- Volumio2 ----
Apr 19 10:25:49 volumio volumio[2212]: info: -------------------------------------------
Apr 19 10:25:49 volumio volumio[2212]: info: ----- System startup ----
Apr 19 10:25:49 volumio volumio[2212]: info: -------------------------------------------
Apr 19 10:25:50 volumio volumio[2212]: info: MYVOLUMIO Environment detected
Apr 19 10:25:50 volumio volumio[2212]: info: Plugin folders cleanup
Apr 19 10:25:50 volumio volumio[2212]: info: Scanning into folder /volumio/app/plugins/
Apr 19 10:25:50 volumio volumio[2212]: info: Scanning category audio_interface
Apr 19 10:25:50 volumio volumio[2212]: info: Scanning category miscellanea
Apr 19 10:25:50 volumio volumio[2212]: info: Scanning category music_service
Apr 19 10:25:50 volumio volumio[2212]: info: Scanning category plugins.json
Apr 19 10:25:50 volumio volumio[2212]: info: Scanning category system_controller
Apr 19 10:25:50 volumio volumio[2212]: info: Scanning category user_interface
Apr 19 10:25:50 volumio volumio[2212]: info: Scanning into folder /data/plugins/
Apr 19 10:25:50 volumio volumio[2212]: info: Plugin folders cleanup completed
Apr 19 10:25:50 volumio volumio[2212]: info: -------------------------------------------
Apr 19 10:25:50 volumio volumio[2212]: info: ----- Core plugins startup ----
Apr 19 10:25:50 volumio volumio[2212]: info: -------------------------------------------
Apr 19 10:25:50 volumio volumio[2212]: info: Loading plugins from folder /volumio/app/plugins/
Apr 19 10:25:50 volumio volumio[2212]: info: Adding plugin upnp to MyMusic Plugins
Apr 19 10:25:50 volumio volumio[2212]: info: Adding plugin airplay_emulation to MyMusic Plugins
Apr 19 10:25:50 volumio volumio[2212]: info: Adding plugin upnp_browser to MyMusic Plugins
Apr 19 10:25:50 volumio volumio[2212]: info: Loading plugins from folder /data/plugins/
Apr 19 10:25:50 volumio volumio[2212]: info: Loading plugin "system"...
Apr 19 10:25:50 volumio volumio[2212]: info: Loading plugin "appearance"...
Apr 19 10:25:50 volumio volumio[2212]: info: Loading plugin "network"...
Apr 19 10:25:50 volumio volumio[2212]: info: Refreshing Cached IP Addresses
Apr 19 10:25:50 volumio sudo[2237]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Apr 19 10:25:50 volumio volumio[2212]: info: Loading plugin "services"...
Apr 19 10:25:50 volumio volumio[2212]: info: Loading plugin "alsa_controller"...
Apr 19 10:25:50 volumio sudo[2237]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 19 10:25:51 volumio sudo[2240]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Apr 19 10:25:51 volumio sudo[2237]: pam_unix(sudo:session): session closed for user root
Apr 19 10:25:51 volumio sudo[2240]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 19 10:25:51 volumio sudo[2240]: pam_unix(sudo:session): session closed for user root
Apr 19 10:25:51 volumio volumio[2212]: info: Loading plugin "wizard"...
Apr 19 10:25:51 volumio volumio[2212]: info: Loading plugin "volumio_command_line_client"...
Apr 19 10:25:51 volumio volumio[2212]: info: Loading plugin "upnp"...
Apr 19 10:25:51 volumio volumio[2212]: info: [1713522351078] Starting Upmpd Daemon
Apr 19 10:25:51 volumio volumio[2212]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Apr 19 10:25:51 volumio volumio[2212]: info: Loading plugin "my_music"...
Apr 19 10:25:51 volumio volumio[2212]: info: Loading plugin "mpd"...
Apr 19 10:25:51 volumio volumio[2212]: info: Loading plugin "upnp_browser"...
Apr 19 10:25:51 volumio volumio[2212]: info: Loading plugin "networkfs"...
Apr 19 10:25:51 volumio volumio[2212]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Apr 19 10:25:51 volumio volumio[2212]: info: Loading plugin "alarm-clock"...
Apr 19 10:25:51 volumio volumio[2212]: info: Plugin airplay_emulation is not enabled
Apr 19 10:25:51 volumio volumio[2212]: info: Loading plugin "last_100"...
Apr 19 10:25:51 volumio volumio[2212]: info: Loading plugin "webradio"...
Apr 19 10:25:51 volumio volumio[2212]: info: Loading plugin "i2s_dacs"...
Apr 19 10:25:51 volumio volumio[2212]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 19 10:25:51 volumio volumio[2212]: info: Loading plugin "volumiodiscovery"...
Apr 19 10:25:51 volumio volumio[2212]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi.
Apr 19 10:25:51 volumio node[2212]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi.
Apr 19 10:25:51 volumio node[2212]: *** WARNING *** Please fix your application to use the native API of Avahi!
Apr 19 10:25:51 volumio node[2212]: *** WARNING *** For more information see
Apr 19 10:25:51 volumio node[2212]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi.
Apr 19 10:25:51 volumio node[2212]: *** WARNING *** Please fix your application to use the native API of Avahi!
Apr 19 10:25:51 volumio node[2212]: *** WARNING *** For more information see
Apr 19 10:25:51 volumio volumio[2212]: *** WARNING *** Please fix your application to use the native API of Avahi!
Apr 19 10:25:51 volumio volumio[2212]: *** WARNING *** For more information see
Apr 19 10:25:51 volumio volumio[2212]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi.
Apr 19 10:25:51 volumio volumio[2212]: *** WARNING *** Please fix your application to use the native API of Avahi!
Apr 19 10:25:51 volumio volumio[2212]: *** WARNING *** For more information see
Apr 19 10:25:51 volumio volumio[2212]: info: Applying required configuration parameters for plugin volumiodiscovery
Apr 19 10:25:51 volumio volumio[2212]: Discovery: StartAdv! undefined
Apr 19 10:25:51 volumio volumio[2212]: Discovery: Started advertising... Volumio - undefined
Apr 19 10:25:51 volumio volumio[2212]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Apr 19 10:25:51 volumio volumio[2212]: info: Loading plugin "outputs"...
Apr 19 10:25:51 volumio volumio[2212]: info: Loading plugin "albumart"...
Apr 19 10:25:51 volumio volumio[2212]: info: Plugin example_plugin is not enabled
Apr 19 10:25:51 volumio volumio[2212]: info: Loading plugin "inputs"...
Apr 19 10:25:51 volumio volumio[2212]: info: Loading plugin "updater_comm"...
Apr 19 10:25:51 volumio volumio[2212]: info: Plugin mpdemulation is not enabled
Apr 19 10:25:51 volumio volumio[2212]: info: Loading plugin "rest_api"...
Apr 19 10:25:51 volumio volumio[2212]: info: Loading plugin "websocket"...
Apr 19 10:25:51 volumio volumio[2212]: info: ___________ START PLUGINS ___________
Apr 19 10:25:51 volumio volumio[2212]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Apr 19 10:25:51 volumio volumio[2212]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Apr 19 10:25:51 volumio volumio[2212]: info: [1713522351992] CoreMusicLibrary::Adding element Media Servers
Apr 19 10:25:51 volumio volumio[2212]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Apr 19 10:25:52 volumio volumio[2212]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Apr 19 10:25:52 volumio volumio[2212]: info: [1713522352009] CoreMusicLibrary::Adding element Last_100
Apr 19 10:25:52 volumio volumio[2212]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Apr 19 10:25:52 volumio volumio[2212]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Apr 19 10:25:52 volumio volumio[2212]: info: [1713522352012] CoreMusicLibrary::Adding element Webradio
Apr 19 10:25:52 volumio volumio[2212]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Apr 19 10:25:52 volumio volumio[2212]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Apr 19 10:25:52 volumio volumio[2212]: info: -------------------------------------------
Apr 19 10:25:52 volumio volumio[2212]: info: ----- MyVolumio plugins startup ----
Apr 19 10:25:52 volumio volumio[2212]: info: -------------------------------------------
Apr 19 10:25:52 volumio volumio[2212]: info: [MyVolumio PluginManager] Fetching plans data....
Apr 19 10:25:52 volumio volumio[2212]: Forking 3 albumart workers
Apr 19 10:25:52 volumio volumio[2212]: info: Loading i18n strings for locale zh
Apr 19 10:25:52 volumio volumio[2212]: Updating browse sources language
Apr 19 10:25:52 volumio volumio[2212]: Cannot find translation for sourceMedia Servers
Apr 19 10:25:52 volumio volumio[2212]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Apr 19 10:25:52 volumio volumio[2212]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Apr 19 10:25:52 volumio volumio[2212]: info: CoreCommandRouter::initPlayerControls
Apr 19 10:25:52 volumio volumio[2212]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 19 10:25:52 volumio volumio[2212]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAlsaCards
Apr 19 10:25:52 volumio volumio[2212]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 19 10:25:52 volumio volumio[2212]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 19 10:25:52 volumio volumio[2212]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 19 10:25:52 volumio volumio[2212]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 19 10:25:52 volumio volumio[2212]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 19 10:25:52 volumio volumio[2212]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Apr 19 10:25:52 volumio volumio[2212]: info: BOOT COMPLETED
Apr 19 10:25:52 volumio volumio[2212]: [Metrics] CommandRouter: 2s 255.98ms
Apr 19 10:25:52 volumio volumio[2212]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 19 10:25:52 volumio volumio[2212]: aplay: main:722: audio open error: Device or resource busy
Apr 19 10:25:52 volumio volumio[2212]: Cannot play startup sound
Apr 19 10:25:52 volumio volumio[2212]: info: CoreCommandRouter::Close All Modals sent
Apr 19 10:25:52 volumio volumio[2212]: info: CoreCommandRouter::Close All Modals sent
Apr 19 10:25:52 volumio volumio[2212]: Express server listening on port 3000
Apr 19 10:25:52 volumio volumio[2212]: [Metrics] WebUI: 2s 570.09ms
Apr 19 10:25:52 volumio volumio[2212]: Volumio Calling Home
Apr 19 10:25:52 volumio volumio[2212]: info: CoreStateMachine::resetVolumioState
Apr 19 10:25:52 volumio volumio[2212]: info: CoreStateMachine::getcurrentVolume
Apr 19 10:25:52 volumio volumio[2212]: info: CoreCommandRouter::volumioRetrievevolume
Apr 19 10:25:52 volumio volumio[2212]: info: CoreStateMachine::pushState
Apr 19 10:25:52 volumio volumio[2212]: info: CorePlayQueue::getTrack 0
Apr 19 10:25:52 volumio volumio[2212]: info: CoreCommandRouter::volumioPushState
Apr 19 10:25:52 volumio volumio[2212]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 19 10:25:52 volumio volumio[2212]: info: Setting Device type: Raspberry PI
Apr 19 10:25:52 volumio volumio[2212]: info: MPD running with PID667
Apr 19 10:25:52 volumio volumio[2212]: ,establishing connection
Apr 19 10:25:52 volumio volumio[2212]: info: Reloading queue from file
Apr 19 10:25:52 volumio volumio[2212]: info: CoreStateMachine::setRepeat true single undefined
Apr 19 10:25:52 volumio volumio[2212]: info: CoreStateMachine::pushState
Apr 19 10:25:52 volumio volumio[2212]: info: CorePlayQueue::getTrack 0
Apr 19 10:25:52 volumio volumio[2212]: info: CoreCommandRouter::volumioPushState
Apr 19 10:25:52 volumio volumio[2212]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 19 10:25:52 volumio volumio[2212]: info: CoreStateMachine::setRandom null
Apr 19 10:25:52 volumio volumio[2212]: info: CoreStateMachine::pushState
Apr 19 10:25:52 volumio volumio[2212]: info: CorePlayQueue::getTrack 0
Apr 19 10:25:52 volumio volumio[2212]: info: CoreCommandRouter::volumioPushState
Apr 19 10:25:52 volumio volumio[2212]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 19 10:25:52 volumio volumio[2212]: Starting albumart workers
Apr 19 10:25:52 volumio volumio[2212]: Starting albumart workers
Apr 19 10:25:52 volumio volumio[2212]: Starting albumart workers
Apr 19 10:25:53 volumio volumio[2212]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable
Apr 19 10:25:53 volumio volumio[2212]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
Apr 19 10:25:53 volumio volumio[2212]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect
Apr 19 10:25:53 volumio volumio-remote-updater[601]: [2024-04-19 10:25:53] [connect] Successful connection
Apr 19 10:25:53 volumio volumio[2212]: Discovery: adding c520c04b-4654-40b9-adc1-a54afb8ba9d6
Apr 19 10:25:53 volumio volumio[2212]: info: mDNS: Found device Volumio
Apr 19 10:25:53 volumio volumio[2212]: info: CoreCommandRouter::volumioGetState
Apr 19 10:25:53 volumio volumio[2212]: info: CorePlayQueue::getTrack 0
Apr 19 10:25:54 volumio volumio-remote-updater[601]: [2024-04-19 10:25:54] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.5.1" /socket.io/?EIO=4&transport=websocket&t=1713522353 101
Apr 19 10:25:55 volumio volumio[2212]: info: CoreCommandRouter::volumioGetState
Apr 19 10:25:55 volumio volumio[2212]: info: CorePlayQueue::getTrack 0
Apr 19 10:25:56 volumio volumio[2212]: info: CoreCommandRouter::volumioGetState
Apr 19 10:25:56 volumio volumio[2212]: info: CorePlayQueue::getTrack 0
Apr 19 10:25:56 volumio volumio[2212]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom
Apr 19 10:25:56 volumio volumio[2212]: info: Listing playlists
Apr 19 10:25:56 volumio volumio[2212]: info: CoreCommandRouter::volumioGetQueue
Apr 19 10:25:56 volumio volumio[2212]: info: CoreStateMachine::getQueue
Apr 19 10:25:56 volumio volumio[2212]: info: CorePlayQueue::getQueue
Apr 19 10:25:56 volumio volumio[2212]: info: CoreCommandRouter::volumioGetVisibleSources
Apr 19 10:25:56 volumio volumio[2212]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Apr 19 10:25:56 volumio volumio[2212]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 19 10:25:56 volumio volumio[2212]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 19 10:26:00 volumio volumio[2212]: info:
Apr 19 10:26:00 volumio volumio[2212]: ---------------------------- MPD announces system playlist update
Apr 19 10:26:00 volumio volumio[2212]: info: Ignoring MPD Status Update
Apr 19 10:26:00 volumio volumio[2212]: info:
Apr 19 10:26:00 volumio volumio[2212]: ---------------------------- MPD announces state update: player
Apr 19 10:26:00 volumio volumio[2212]: info: ControllerMpd::getState
Apr 19 10:26:00 volumio volumio[2212]: verbose: ControllerMpd::sendMpdCommand status
Apr 19 10:26:00 volumio volumio[2212]: info: ------------------------------ 7ms
Apr 19 10:26:00 volumio volumio[2212]: verbose: ControllerMpd::parseState
Apr 19 10:26:00 volumio volumio[2212]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 19 10:26:00 volumio volumio[2212]: verbose: ControllerMpd::parseTrackInfo
Apr 19 10:26:00 volumio volumio[2212]: info: ControllerMpd::pushState
Apr 19 10:26:00 volumio volumio[2212]: info: CoreCommandRouter::servicePushState
Apr 19 10:26:00 volumio volumio[2212]: info: CorePlayQueue::getTrack 0
Apr 19 10:26:00 volumio volumio[2212]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd
Apr 19 10:26:00 volumio volumio[2212]: info: ------------------------------ 13ms
Apr 19 10:26:00 volumio sudo[2337]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Apr 19 10:26:00 volumio sudo[2337]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 19 10:26:00 volumio sudo[2339]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Apr 19 10:26:00 volumio sudo[2337]: pam_unix(sudo:session): session closed for user root
Apr 19 10:26:00 volumio sudo[2339]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 19 10:26:00 volumio sudo[2339]: pam_unix(sudo:session): session closed for user root
Apr 19 10:26:01 volumio sudo[2356]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Apr 19 10:26:01 volumio sudo[2358]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Apr 19 10:26:01 volumio sudo[2356]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 19 10:26:01 volumio sudo[2358]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 19 10:26:01 volumio sudo[2356]: pam_unix(sudo:session): session closed for user root
Apr 19 10:26:01 volumio volumio[2212]: info: CoreCommandRouter::volumioGetVisibleSources
Apr 19 10:26:01 volumio volumio[2212]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Apr 19 10:26:01 volumio sudo[2366]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service
Apr 19 10:26:01 volumio volumio[2212]: info: CoreCommandRouter::volumioGetState
Apr 19 10:26:01 volumio volumio[2212]: info: CorePlayQueue::getTrack 0
Apr 19 10:26:01 volumio sudo[2358]: pam_unix(sudo:session): session closed for user root
Apr 19 10:26:01 volumio volumio[2212]: info: CoreCommandRouter::volumioGetQueue
Apr 19 10:26:01 volumio volumio[2212]: info: CoreStateMachine::getQueue
Apr 19 10:26:01 volumio volumio[2212]: info: CorePlayQueue::getQueue
Apr 19 10:26:01 volumio sudo[2366]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 19 10:26:01 volumio volumio[2212]: info: Listing playlists
Apr 19 10:26:01 volumio volumio[2212]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom
Apr 19 10:26:01 volumio systemd[1]: Started UPnP Renderer front-end to MPD.
Apr 19 10:26:01 volumio volumio[2212]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings
Apr 19 10:26:01 volumio sudo[2366]: pam_unix(sudo:session): session closed for user root
Apr 19 10:26:01 volumio volumio[2212]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard
Apr 19 10:26:01 volumio volumio[2212]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard
Apr 19 10:26:01 volumio volumio[2212]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Apr 19 10:26:01 volumio volumio[2212]: info: Upmpdcli Daemon Started
Apr 19 10:26:02 volumio volumio[2212]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=2.861&uuid=092cfaf002ff8684a2d496083d40a25f" http://updates.volumio.org:7070/downloader-v1/track-device
Apr 19 10:26:02 volumio volumio[2212]: % Total % Received % Xferd Average Speed Time Time Time Current
Apr 19 10:26:02 volumio volumio[2212]: Dload Upload Total Spent Left Speed
Apr 19 10:26:02 volumio volumio[2212]: 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0curl: (7) Failed to connect to updates.volumio.org port 7070: Connection refused
Apr 19 10:26:02 volumio volumio[2212]: retrying in 5 seconds, trial 0
Apr 19 10:26:02 volumio volumio[2212]: Volumio Calling Home
Apr 19 10:26:02 volumio volumio[2212]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium
Apr 19 10:26:02 volumio volumio[2212]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium
Apr 19 10:26:02 volumio volumio[2212]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium
Apr 19 10:26:02 volumio volumio[2212]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium
Apr 19 10:26:02 volumio volumio[2212]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium
Apr 19 10:26:02 volumio volumio[2212]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium
Apr 19 10:26:02 volumio volumio[2212]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium
Apr 19 10:26:02 volumio volumio[2212]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium
Apr 19 10:26:02 volumio volumio[2212]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium
Apr 19 10:26:02 volumio volumio[2212]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium
Apr 19 10:26:02 volumio volumio[2212]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar
Apr 19 10:26:02 volumio volumio[2212]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar
Apr 19 10:26:02 volumio volumio[2212]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar
Apr 19 10:26:02 volumio volumio[2212]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar
Apr 19 10:26:02 volumio volumio[2212]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar
Apr 19 10:26:02 volumio volumio[2212]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar
Apr 19 10:26:02 volumio volumio[2212]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar
Apr 19 10:26:02 volumio volumio[2212]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar
Apr 19 10:26:02 volumio volumio[2212]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar
Apr 19 10:26:02 volumio volumio[2212]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar
Apr 19 10:26:02 volumio volumio[2212]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso
Apr 19 10:26:02 volumio volumio[2212]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso
Apr 19 10:26:02 volumio volumio[2212]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso
Apr 19 10:26:02 volumio volumio[2212]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso
Apr 19 10:26:02 volumio volumio[2212]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso
Apr 19 10:26:02 volumio volumio[2212]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso
Apr 19 10:26:02 volumio volumio[2212]: info: Adding plugin bluetooth to MyMusic Plugins
Apr 19 10:26:02 volumio volumio[2212]: info: Adding plugin cd_controller to MyMusic Plugins
Apr 19 10:26:02 volumio volumio[2212]: info: Adding plugin smart_inputs to MyMusic Plugins
Apr 19 10:26:02 volumio volumio[2212]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"...
Apr 19 10:26:03 volumio volumio[2212]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"...
Apr 19 10:26:04 volumio volumio[2212]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded
Apr 19 10:26:04 volumio volumio[2212]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio
Apr 19 10:26:04 volumio volumio[2212]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 19 10:26:04 volumio volumio[2212]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 19 10:26:04 volumio volumio[2212]: info: Starting MyVolumio Remote Streaming Endpoints
Apr 19 10:26:04 volumio volumio[2212]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded
Apr 19 10:26:04 volumio volumio[2212]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services
Apr 19 10:26:04 volumio volumio[2212]: info: Streaming services startup
Apr 19 10:26:04 volumio volumio[2212]: info: Starting Streaming Daemon
Apr 19 10:26:04 volumio sudo[2396]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service
Apr 19 10:26:04 volumio sudo[2396]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 19 10:26:04 volumio systemd[1]: Stopping Volumio Streaming Daemon...
Apr 19 10:26:04 volumio systemd[1]: Starting Volumio Streaming Daemon...
Apr 19 10:26:04 volumio systemd[1]: Started Volumio Streaming Daemon.
Apr 19 10:26:04 volumio volumio[2212]: info: MyVolumio not started
Apr 19 10:26:04 volumio volumio[2212]: info: Initializing device activation check
Apr 19 10:26:04 volumio sudo[2396]: pam_unix(sudo:session): session closed for user root
Apr 19 10:26:04 volumio volumio-streaming-daemon[2406]: ############################
Apr 19 10:26:04 volumio volumio-streaming-daemon[2406]: # Volumio Streaming Daemon #
Apr 19 10:26:04 volumio volumio-streaming-daemon[2406]: # Running on port 7777 #
Apr 19 10:26:04 volumio volumio-streaming-daemon[2406]: ############################
Apr 19 10:26:04 volumio volumio-streaming-daemon[2406]: Environment: production
Apr 19 10:26:04 volumio volumio[2212]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started
Apr 19 10:26:06 volumio volumio-streaming-daemon[2406]: Environment: production
Apr 19 10:26:06 volumio volumio[2212]: info: Fetching Streaming Services browse cache
Apr 19 10:26:14 volumio volumio[2212]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=2.861&uuid=092cfaf002ff8684a2d496083d40a25f" http://updates.volumio.org:7070/downloader-v1/track-device
Apr 19 10:26:14 volumio volumio[2212]: % Total % Received % Xferd Average Speed Time Time Time Current
Apr 19 10:26:14 volumio volumio[2212]: Dload Upload Total Spent Left Speed
Apr 19 10:26:14 volumio volumio[2212]: [235B blob data]
Apr 19 10:26:14 volumio volumio[2212]: retrying in 5 seconds, trial 1
Apr 19 10:26:14 volumio volumio[2212]: Volumio Calling Home
Apr 19 10:26:19 volumio volumio[2212]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri
Apr 19 10:26:19 volumio volumio[2212]: info: CURURI: albums://
Apr 19 10:26:19 volumio volumio[2212]: listAlbums - loading Albums from cache
Apr 19 10:26:20 volumio volumio[2212]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E8%97%8D%E4%BA%95%E3%82%A8%E3%82%A4%E3%83%AB/IGNITE/85d368cf-f2e7-4e38-bfd3-54a292d7acc3.png'
Apr 19 10:26:20 volumio volumio[2212]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/ClariS/PARTY%20TIME/1fde64f0-0f5e-410b-8f46-407fc8e34c8d.png'
Apr 19 10:26:20 volumio volumio[2212]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/FictionJunction%20YUUKA/Destination/e7a254bb-04d7-4211-92eb-b8a4d4866ae5.jpg'
Apr 19 10:26:20 volumio volumio[2212]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/moumoon/Hello%2Cshooting-star/3153821a-52c7-4410-91b5-8cf9843ffb45.jpg'
Apr 19 10:26:20 volumio volumio[2212]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Yuuki%20Ozaki(from%20Galileo%20Galilei)/Trigger/d75f31bb-1698-40bd-a3f3-fac558dc6371.jpg'
Apr 19 10:26:20 volumio volumio[2212]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/SawanoHiroyuki%5BnZk%5D%3AAimer/UnChild/ecba5144-33b6-4994-bf7e-8617bb625024.jpg'
Apr 19 10:26:22 volumio volumio[2212]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Apr 19 10:26:22 volumio volumio[2212]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 19 10:26:24 volumio volumio[2212]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=2.861&uuid=092cfaf002ff8684a2d496083d40a25f" http://updates.volumio.org:7070/downloader-v1/track-device
Apr 19 10:26:24 volumio volumio[2212]: % Total % Received % Xferd Average Speed Time Time Time Current
Apr 19 10:26:24 volumio volumio[2212]: Dload Upload Total Spent Left Speed
Apr 19 10:26:24 volumio volumio[2212]: 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0curl: (7) Failed to connect to updates.volumio.org port 7070: Connection refused
Apr 19 10:26:24 volumio volumio[2212]: retrying in 5 seconds, trial 2
Apr 19 10:26:24 volumio volumio[2212]: Volumio Calling Home
Apr 19 10:26:54 volumio volumio[2212]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Apr 19 10:26:54 volumio volumio[2212]: { Error: connect ETIMEDOUT 199.16.156.7:443
Apr 19 10:26:54 volumio volumio[2212]: at Object._errnoException (util.js:1022:11)
Apr 19 10:26:54 volumio volumio[2212]: at _exceptionWithHostPort (util.js:1044:20)
Apr 19 10:26:54 volumio volumio[2212]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1198:14)
Apr 19 10:26:54 volumio volumio[2212]: code: 'ETIMEDOUT',
Apr 19 10:26:54 volumio volumio[2212]: errno: 'ETIMEDOUT',
Apr 19 10:26:54 volumio volumio[2212]: syscall: 'connect',
Apr 19 10:26:54 volumio volumio[2212]: address: '199.16.156.7',
Apr 19 10:26:54 volumio volumio[2212]: port: 443 }
Apr 19 10:26:54 volumio volumio[2212]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Apr 19 10:26:54 volumio sudo[2552]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-19 10:25
Apr 19 10:26:54 volumio sudo[2552]: 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="168ba7444df98cd868db72f24faec0b6346489cc"
VOLUMIO_FE_VERSION="847a48ecf32d35cc502a0053d585d1a69236e391"
VOLUMIO_BE_VERSION="03f7bbd984817e093cac62b7ff876f3c2cfabc1d"
VOLUMIO_ARCH="arm"
VOLUMIO_VARIANT="volumio"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Tue Dec 22 14:39:52 CET 2020"
VOLUMIO_VERSION="2.861"
VOLUMIO_HARDWARE="pi"
VOLUMIO_HASH="097e28ee6263e31d0bf6b690a02dcab3"