-- Logs begin at Mon 2025-01-27 02:13:43 UTC, end at Fri 2026-03-27 09:31:23 UTC. -- Mar 27 09:30:00 volumio volumio-remote-updater[605]: [2026-03-27 09:30:00] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Mar 27 09:30:00 volumio systemd[1]: volumio.service: main process exited, code=exited, status=1/FAILURE Mar 27 09:30:00 volumio volumio-remote-updater[605]: [2026-03-27 09:30:00] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Mar 27 09:30:00 volumio systemd[1]: Unit volumio.service entered failed state. Mar 27 09:30:00 volumio systemd[1]: Starting dynamicswap service... Mar 27 09:30:00 volumio systemd[1]: Started dynamicswap service. Mar 27 09:30:00 volumio wpa_supplicant[1011]: wlan0: Failed to initiate sched scan Mar 27 09:30:00 volumio systemd[1]: volumio.service holdoff time over, scheduling restart. Mar 27 09:30:00 volumio systemd[1]: Starting dynamicswap service... Mar 27 09:30:00 volumio systemd[1]: Started dynamicswap service. Mar 27 09:30:00 volumio systemd[1]: Stopping Volumio Backend Module... Mar 27 09:30:00 volumio systemd[1]: Starting Volumio Backend Module... Mar 27 09:30:00 volumio systemd[1]: Started Volumio Backend Module. Mar 27 09:30:02 volumio volumio[3475]: info: ------------------------------------------- Mar 27 09:30:02 volumio volumio[3475]: info: ----- Volumio2 ---- Mar 27 09:30:02 volumio volumio[3475]: info: ------------------------------------------- Mar 27 09:30:02 volumio volumio[3475]: info: ----- System startup ---- Mar 27 09:30:02 volumio volumio[3475]: info: ------------------------------------------- Mar 27 09:30:03 volumio volumio[3475]: info: MYVOLUMIO Environment detected Mar 27 09:30:03 volumio volumio[3475]: info: Plugin folders cleanup Mar 27 09:30:03 volumio volumio[3475]: info: Scanning into folder /volumio/app/plugins/ Mar 27 09:30:03 volumio volumio[3475]: info: Scanning category audio_interface Mar 27 09:30:03 volumio volumio[3475]: info: Scanning category miscellanea Mar 27 09:30:03 volumio volumio[3475]: info: Scanning category music_service Mar 27 09:30:03 volumio volumio[3475]: info: Scanning category plugins.json Mar 27 09:30:03 volumio volumio[3475]: info: Scanning category system_controller Mar 27 09:30:03 volumio volumio[3475]: info: Scanning category user_interface Mar 27 09:30:03 volumio volumio[3475]: info: Scanning into folder /data/plugins/ Mar 27 09:30:03 volumio volumio[3475]: info: Plugin folders cleanup completed Mar 27 09:30:03 volumio volumio[3475]: info: ------------------------------------------- Mar 27 09:30:03 volumio volumio[3475]: info: ----- Core plugins startup ---- Mar 27 09:30:03 volumio volumio[3475]: info: ------------------------------------------- Mar 27 09:30:03 volumio volumio[3475]: info: Loading plugins from folder /volumio/app/plugins/ Mar 27 09:30:03 volumio volumio[3475]: info: Adding plugin upnp to MyMusic Plugins Mar 27 09:30:03 volumio volumio[3475]: info: Adding plugin airplay_emulation to MyMusic Plugins Mar 27 09:30:03 volumio volumio[3475]: info: Adding plugin upnp_browser to MyMusic Plugins Mar 27 09:30:03 volumio volumio[3475]: info: Loading plugins from folder /data/plugins/ Mar 27 09:30:03 volumio volumio[3475]: info: Loading plugin "system"... Mar 27 09:30:03 volumio volumio[3475]: info: Loading plugin "appearance"... Mar 27 09:30:04 volumio volumio[3475]: info: Loading plugin "network"... Mar 27 09:30:05 volumio volumio[3475]: info: Refreshing Cached IP Addresses Mar 27 09:30:05 volumio sudo[3500]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 27 09:30:05 volumio volumio[3475]: info: Loading plugin "services"... Mar 27 09:30:05 volumio sudo[3500]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 27 09:30:05 volumio volumio[3475]: info: Loading plugin "alsa_controller"... Mar 27 09:30:05 volumio sudo[3503]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 27 09:30:05 volumio sudo[3500]: pam_unix(sudo:session): session closed for user root Mar 27 09:30:05 volumio sudo[3503]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 27 09:30:05 volumio sudo[3503]: pam_unix(sudo:session): session closed for user root Mar 27 09:30:05 volumio volumio[3475]: info: Loading plugin "wizard"... Mar 27 09:30:05 volumio volumio-remote-updater[605]: [2026-03-27 09:30:05] [connect] Successful connection Mar 27 09:30:05 volumio volumio[3475]: info: Loading plugin "volumio_command_line_client"... Mar 27 09:30:05 volumio volumio[3475]: info: Loading plugin "upnp"... Mar 27 09:30:05 volumio volumio[3475]: info: [1774603805172] Starting Upmpd Daemon Mar 27 09:30:05 volumio volumio[3475]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 27 09:30:05 volumio volumio[3475]: info: Loading plugin "my_music"... Mar 27 09:30:05 volumio volumio[3475]: info: Loading plugin "mpd"... Mar 27 09:30:05 volumio volumio[3475]: info: Loading plugin "upnp_browser"... Mar 27 09:30:06 volumio volumio[3475]: info: Loading plugin "networkfs"... Mar 27 09:30:06 volumio volumio[3475]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 27 09:30:06 volumio volumio[3475]: info: Loading plugin "alarm-clock"... Mar 27 09:30:06 volumio volumio[3475]: info: Loading plugin "airplay_emulation"... Mar 27 09:30:06 volumio volumio[3475]: info: Starting Shairport Sync Mar 27 09:30:06 volumio volumio[3475]: info: Loading plugin "last_100"... Mar 27 09:30:06 volumio volumio[3475]: info: Loading plugin "webradio"... Mar 27 09:30:06 volumio volumio[3475]: info: Loading plugin "i2s_dacs"... Mar 27 09:30:06 volumio volumio[3475]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 27 09:30:06 volumio volumio[3475]: info: Loading plugin "volumiodiscovery"... Mar 27 09:30:06 volumio volumio[3475]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Mar 27 09:30:06 volumio node[3475]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Mar 27 09:30:06 volumio node[3475]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 27 09:30:06 volumio node[3475]: *** WARNING *** For more information see Mar 27 09:30:06 volumio node[3475]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Mar 27 09:30:06 volumio node[3475]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 27 09:30:06 volumio node[3475]: *** WARNING *** For more information see Mar 27 09:30:06 volumio volumio[3475]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 27 09:30:06 volumio volumio[3475]: *** WARNING *** For more information see Mar 27 09:30:06 volumio volumio[3475]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Mar 27 09:30:06 volumio volumio[3475]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 27 09:30:06 volumio volumio[3475]: *** WARNING *** For more information see Mar 27 09:30:06 volumio volumio[3475]: info: Applying required configuration parameters for plugin volumiodiscovery Mar 27 09:30:06 volumio volumio[3475]: Discovery: StartAdv! undefined Mar 27 09:30:06 volumio volumio[3475]: Discovery: Started advertising... Volumio - undefined Mar 27 09:30:06 volumio volumio[3475]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 27 09:30:06 volumio volumio[3475]: info: Loading plugin "outputs"... Mar 27 09:30:06 volumio volumio[3475]: info: Loading plugin "albumart"... Mar 27 09:30:06 volumio volumio[3475]: info: Plugin example_plugin is not enabled Mar 27 09:30:06 volumio volumio[3475]: info: Loading plugin "inputs"... Mar 27 09:30:06 volumio volumio[3475]: info: Loading plugin "updater_comm"... Mar 27 09:30:06 volumio volumio[3475]: info: Plugin mpdemulation is not enabled Mar 27 09:30:06 volumio volumio[3475]: info: Loading plugin "rest_api"... Mar 27 09:30:06 volumio volumio[3475]: info: Loading plugin "websocket"... Mar 27 09:30:07 volumio volumio[3475]: info: ___________ START PLUGINS ___________ Mar 27 09:30:07 volumio volumio[3475]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 27 09:30:07 volumio volumio[3475]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 27 09:30:07 volumio volumio[3475]: info: [1774603807007] CoreMusicLibrary::Adding element Media Servers Mar 27 09:30:07 volumio volumio[3475]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 27 09:30:07 volumio volumio[3475]: Forking 3 albumart workers Mar 27 09:30:07 volumio volumio[3475]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 27 09:30:07 volumio volumio[3475]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 27 09:30:07 volumio volumio[3475]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 27 09:30:07 volumio volumio[3475]: info: [1774603807324] CoreMusicLibrary::Adding element Last_100 Mar 27 09:30:07 volumio volumio[3475]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 27 09:30:07 volumio volumio[3475]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 27 09:30:07 volumio volumio[3475]: info: [1774603807330] CoreMusicLibrary::Adding element Webradio Mar 27 09:30:07 volumio volumio[3475]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 27 09:30:07 volumio volumio[3475]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 27 09:30:07 volumio volumio[3475]: info: ------------------------------------------- Mar 27 09:30:07 volumio volumio[3475]: info: ----- MyVolumio plugins startup ---- Mar 27 09:30:07 volumio volumio[3475]: info: ------------------------------------------- Mar 27 09:30:07 volumio volumio[3475]: info: [MyVolumio PluginManager] Fetching plans data.... Mar 27 09:30:07 volumio volumio[3475]: info: Loading i18n strings for locale zh Mar 27 09:30:07 volumio volumio[3475]: Updating browse sources language Mar 27 09:30:07 volumio volumio[3475]: Cannot find translation for sourceMedia Servers Mar 27 09:30:07 volumio volumio[3475]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 27 09:30:07 volumio volumio[3475]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 27 09:30:07 volumio volumio[3475]: info: CoreCommandRouter::initPlayerControls Mar 27 09:30:07 volumio volumio[3475]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 09:30:07 volumio volumio[3475]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAlsaCards Mar 27 09:30:07 volumio volumio[3475]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 09:30:07 volumio volumio[3475]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 09:30:07 volumio volumio[3475]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 09:30:07 volumio volumio[3475]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 09:30:07 volumio volumio[3475]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 09:30:07 volumio volumio[3475]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 27 09:30:07 volumio volumio[3475]: info: BOOT COMPLETED Mar 27 09:30:07 volumio volumio[3475]: [Metrics] CommandRouter: 4s 859.57ms Mar 27 09:30:07 volumio volumio[3475]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 27 09:30:07 volumio volumio[3475]: aplay: main:722: audio open error: Device or resource busy Mar 27 09:30:07 volumio volumio[3475]: Cannot play startup sound Mar 27 09:30:07 volumio volumio[3475]: info: CoreCommandRouter::Close All Modals sent Mar 27 09:30:07 volumio volumio[3475]: info: CoreCommandRouter::Close All Modals sent Mar 27 09:30:07 volumio volumio[3475]: Express server listening on port 3000 Mar 27 09:30:07 volumio volumio[3475]: [Metrics] WebUI: 5s 664.33ms Mar 27 09:30:07 volumio volumio[3475]: Volumio Calling Home Mar 27 09:30:07 volumio volumio[3475]: info: CoreStateMachine::resetVolumioState Mar 27 09:30:07 volumio volumio[3475]: info: CoreStateMachine::getcurrentVolume Mar 27 09:30:07 volumio volumio[3475]: info: CoreCommandRouter::volumioRetrievevolume Mar 27 09:30:07 volumio volumio[3475]: info: CoreStateMachine::pushState Mar 27 09:30:07 volumio volumio[3475]: info: CorePlayQueue::getTrack 0 Mar 27 09:30:07 volumio volumio[3475]: info: CoreCommandRouter::volumioPushState Mar 27 09:30:07 volumio volumio[3475]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 27 09:30:07 volumio volumio[3475]: info: Setting Device type: Raspberry PI Mar 27 09:30:07 volumio volumio[3475]: info: MPD running with PID676 Mar 27 09:30:07 volumio volumio[3475]: ,establishing connection Mar 27 09:30:07 volumio volumio[3475]: Starting albumart workers Mar 27 09:30:07 volumio volumio[3475]: Starting albumart workers Mar 27 09:30:07 volumio volumio[3475]: Starting albumart workers Mar 27 09:30:08 volumio volumio-remote-updater[605]: [2026-03-27 09:30:08] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.5.1" /socket.io/?EIO=4&transport=websocket&t=1774603805 101 Mar 27 09:30:08 volumio volumio[3475]: info: Reloading queue from file Mar 27 09:30:08 volumio volumio[3475]: info: CoreStateMachine::setRepeat false single undefined Mar 27 09:30:08 volumio volumio[3475]: info: CoreStateMachine::pushState Mar 27 09:30:08 volumio volumio[3475]: info: CorePlayQueue::getTrack 0 Mar 27 09:30:08 volumio volumio[3475]: info: CoreCommandRouter::volumioPushState Mar 27 09:30:08 volumio volumio[3475]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 27 09:30:08 volumio wpa_supplicant[1011]: wlan0: Failed to initiate sched scan Mar 27 09:30:08 volumio volumio[3475]: info: CoreStateMachine::setRandom false Mar 27 09:30:08 volumio volumio[3475]: info: CoreStateMachine::pushState Mar 27 09:30:08 volumio volumio[3475]: info: CorePlayQueue::getTrack 0 Mar 27 09:30:08 volumio volumio[3475]: info: CoreCommandRouter::volumioPushState Mar 27 09:30:08 volumio volumio[3475]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 27 09:30:08 volumio volumio[3475]: info: Starting Shairport Sync Mar 27 09:30:08 volumio volumio[3475]: info: Starting Shairport Sync Mar 27 09:30:08 volumio sudo[3569]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 27 09:30:08 volumio sudo[3570]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 27 09:30:08 volumio sudo[3569]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 27 09:30:08 volumio sudo[3570]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 27 09:30:08 volumio volumio[3475]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Mar 27 09:30:08 volumio volumio[3475]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 27 09:30:08 volumio volumio[3475]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Mar 27 09:30:08 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Mar 27 09:30:08 volumio systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... Mar 27 09:30:08 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 27 09:30:08 volumio sudo[3570]: pam_unix(sudo:session): session closed for user root Mar 27 09:30:08 volumio sudo[3569]: pam_unix(sudo:session): session closed for user root Mar 27 09:30:08 volumio volumio[3475]: Discovery: adding 31187862-dc6c-4da2-9e9a-6b7b17ed1f55 Mar 27 09:30:08 volumio volumio[3475]: info: mDNS: Found device Volumio Mar 27 09:30:08 volumio volumio[3475]: info: CoreCommandRouter::volumioGetState Mar 27 09:30:08 volumio volumio[3475]: info: CorePlayQueue::getTrack 0 Mar 27 09:30:08 volumio volumio[3475]: info: Shairport-Sync Started Mar 27 09:30:08 volumio volumio[3475]: Error adding Membership: Error: addMembership EINVAL Mar 27 09:30:08 volumio volumio[3475]: info: Shairport-Sync Started Mar 27 09:30:08 volumio volumio[3475]: info: CoreCommandRouter::volumioGetState Mar 27 09:30:08 volumio volumio[3475]: info: CorePlayQueue::getTrack 0 Mar 27 09:30:08 volumio volumio[3475]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 27 09:30:08 volumio volumio[3475]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 27 09:30:08 volumio volumio[3475]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Danny%20McCarthy/Silver%20Scrapes%20(As%20Featured%20in%20the%20ESPN%20%22This%20Is%20College%20GameDay%22%20Spot)/567db869-72c1-4165-8886-2d3fd292d266.jpg' Mar 27 09:30:08 volumio volumio[3475]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Emilia/Big%20Big%20World%20(Scandinavian%20version)/1cdd8f1a-7aa9-401c-99c2-70ac809635d6.jpg' Mar 27 09:30:08 volumio volumio[3475]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Emilia/Big%20Big%20World%20(Scandinavian%20version)/1cdd8f1a-7aa9-401c-99c2-70ac809635d6.jpg' Mar 27 09:30:10 volumio volumio[3475]: info: CoreCommandRouter::volumioGetState Mar 27 09:30:10 volumio volumio[3475]: info: CorePlayQueue::getTrack 0 Mar 27 09:30:11 volumio volumio[3475]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 27 09:30:11 volumio volumio[3475]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 09:30:11 volumio volumio[3475]: info: CoreCommandRouter::volumioGetState Mar 27 09:30:11 volumio volumio[3475]: info: CorePlayQueue::getTrack 0 Mar 27 09:30:11 volumio volumio[3475]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 27 09:30:11 volumio volumio[3475]: info: Listing playlists Mar 27 09:30:11 volumio volumio[3475]: info: CoreCommandRouter::volumioGetVisibleSources Mar 27 09:30:11 volumio volumio[3475]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 27 09:30:11 volumio volumio[3475]: info: CoreCommandRouter::volumioGetQueue Mar 27 09:30:11 volumio volumio[3475]: info: CoreStateMachine::getQueue Mar 27 09:30:11 volumio volumio[3475]: info: CorePlayQueue::getQueue Mar 27 09:30:11 volumio volumio[3475]: info: CoreCommandRouter::volumioGetState Mar 27 09:30:11 volumio volumio[3475]: info: CorePlayQueue::getTrack 0 Mar 27 09:30:11 volumio volumio[3475]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 27 09:30:11 volumio volumio[3475]: info: Listing playlists Mar 27 09:30:11 volumio volumio[3475]: info: CoreCommandRouter::volumioGetVisibleSources Mar 27 09:30:11 volumio volumio[3475]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 27 09:30:11 volumio volumio[3475]: info: CoreCommandRouter::volumioGetQueue Mar 27 09:30:11 volumio volumio[3475]: info: CoreStateMachine::getQueue Mar 27 09:30:11 volumio volumio[3475]: info: CorePlayQueue::getQueue Mar 27 09:30:12 volumio volumio[3475]: info: CoreCommandRouter::volumioGetState Mar 27 09:30:12 volumio volumio[3475]: info: CorePlayQueue::getTrack 0 Mar 27 09:30:12 volumio volumio[3475]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 27 09:30:12 volumio volumio[3475]: info: Listing playlists Mar 27 09:30:12 volumio volumio[3475]: info: CoreCommandRouter::volumioGetVisibleSources Mar 27 09:30:12 volumio volumio[3475]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 27 09:30:12 volumio volumio[3475]: info: CoreCommandRouter::volumioGetQueue Mar 27 09:30:12 volumio volumio[3475]: info: CoreStateMachine::getQueue Mar 27 09:30:12 volumio volumio[3475]: info: CorePlayQueue::getQueue Mar 27 09:30:12 volumio volumio[3475]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Emilia/Big%20Big%20World%20(Scandinavian%20version)/1cdd8f1a-7aa9-401c-99c2-70ac809635d6.jpg' Mar 27 09:30:15 volumio sudo[3633]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 27 09:30:15 volumio sudo[3633]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 27 09:30:15 volumio sudo[3635]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 27 09:30:15 volumio sudo[3633]: pam_unix(sudo:session): session closed for user root Mar 27 09:30:15 volumio sudo[3635]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 27 09:30:15 volumio sudo[3635]: pam_unix(sudo:session): session closed for user root Mar 27 09:30:15 volumio sudo[3650]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Mar 27 09:30:15 volumio sudo[3650]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 27 09:30:15 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Mar 27 09:30:15 volumio sudo[3650]: pam_unix(sudo:session): session closed for user root Mar 27 09:30:15 volumio volumio[3475]: info: Upmpdcli Daemon Started Mar 27 09:30:16 volumio wpa_supplicant[1011]: wlan0: Failed to initiate sched scan Mar 27 09:30:17 volumio volumio[3475]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Mar 27 09:30:17 volumio volumio[3475]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Mar 27 09:30:17 volumio volumio[3475]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Mar 27 09:30:17 volumio volumio[3475]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Mar 27 09:30:17 volumio volumio[3475]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Mar 27 09:30:17 volumio volumio[3475]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Mar 27 09:30:17 volumio volumio[3475]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Mar 27 09:30:17 volumio volumio[3475]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Mar 27 09:30:17 volumio volumio[3475]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Mar 27 09:30:17 volumio volumio[3475]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Mar 27 09:30:17 volumio volumio[3475]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Mar 27 09:30:17 volumio volumio[3475]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Mar 27 09:30:17 volumio volumio[3475]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Mar 27 09:30:17 volumio volumio[3475]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Mar 27 09:30:17 volumio volumio[3475]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Mar 27 09:30:17 volumio volumio[3475]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Mar 27 09:30:17 volumio volumio[3475]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Mar 27 09:30:17 volumio volumio[3475]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Mar 27 09:30:17 volumio volumio[3475]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Mar 27 09:30:17 volumio volumio[3475]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Mar 27 09:30:17 volumio volumio[3475]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Mar 27 09:30:17 volumio volumio[3475]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Mar 27 09:30:17 volumio volumio[3475]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Mar 27 09:30:17 volumio volumio[3475]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Mar 27 09:30:17 volumio volumio[3475]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Mar 27 09:30:17 volumio volumio[3475]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Mar 27 09:30:17 volumio volumio[3475]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Mar 27 09:30:17 volumio volumio[3475]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Mar 27 09:30:17 volumio volumio[3475]: info: Adding plugin bluetooth to MyMusic Plugins Mar 27 09:30:17 volumio volumio[3475]: info: Adding plugin cd_controller to MyMusic Plugins Mar 27 09:30:17 volumio volumio[3475]: info: Adding plugin smart_inputs to MyMusic Plugins Mar 27 09:30:17 volumio volumio[3475]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Mar 27 09:30:19 volumio volumio[3475]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Mar 27 09:30:20 volumio volumio[3475]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Mar 27 09:30:20 volumio volumio[3475]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Mar 27 09:30:20 volumio volumio[3475]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 27 09:30:20 volumio volumio[3475]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 27 09:30:20 volumio volumio[3475]: info: Starting MyVolumio Remote Streaming Endpoints Mar 27 09:30:20 volumio volumio[3475]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Mar 27 09:30:20 volumio volumio[3475]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Mar 27 09:30:20 volumio volumio[3475]: info: Streaming services startup Mar 27 09:30:20 volumio volumio[3475]: info: Starting Streaming Daemon Mar 27 09:30:20 volumio volumio[3475]: info: MyVolumio not started Mar 27 09:30:20 volumio volumio[3475]: info: Initializing device activation check Mar 27 09:30:20 volumio sudo[3664]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Mar 27 09:30:20 volumio sudo[3664]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 27 09:30:20 volumio systemd[1]: Stopping Volumio Streaming Daemon... Mar 27 09:30:20 volumio volumio[3475]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Mar 27 09:30:20 volumio systemd[1]: Starting Volumio Streaming Daemon... Mar 27 09:30:20 volumio systemd[1]: Started Volumio Streaming Daemon. Mar 27 09:30:20 volumio sudo[3664]: pam_unix(sudo:session): session closed for user root Mar 27 09:30:20 volumio volumio-streaming-daemon[3670]: ############################ Mar 27 09:30:20 volumio volumio-streaming-daemon[3670]: # Volumio Streaming Daemon # Mar 27 09:30:20 volumio volumio-streaming-daemon[3670]: # Running on port 7777 # Mar 27 09:30:20 volumio volumio-streaming-daemon[3670]: ############################ Mar 27 09:30:20 volumio volumio-streaming-daemon[3670]: Environment: production Mar 27 09:30:20 volumio volumio[3475]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=2.834&uuid=aa5966c5d68e303da03b8015962b4b0e" http://updates.volumio.org:7070/downloader-v1/track-device Mar 27 09:30:20 volumio volumio[3475]: % Total % Received % Xferd Average Speed Time Time Time Current Mar 27 09:30:20 volumio volumio[3475]: Dload Upload Total Spent Left Speed Mar 27 09:30:20 volumio volumio[3475]: 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0curl: (7) Failed to connect to updates.volumio.org port 7070: Connection refused Mar 27 09:30:20 volumio volumio[3475]: retrying in 5 seconds, trial 0 Mar 27 09:30:20 volumio volumio[3475]: Volumio Calling Home Mar 27 09:30:23 volumio volumio-streaming-daemon[3670]: Environment: production Mar 27 09:30:23 volumio volumio[3475]: info: Fetching Streaming Services browse cache Mar 27 09:30:23 volumio volumio[3475]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesQOBUZ Mar 27 09:30:23 volumio volumio[3475]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 27 09:30:23 volumio volumio[3475]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesTIDAL Mar 27 09:30:23 volumio volumio[3475]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 27 09:30:23 volumio volumio[3475]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 27 09:30:23 volumio volumio[3475]: info: CURURI: music-library/USB/0403-0201 Mar 27 09:30:23 volumio wpa_supplicant[1011]: wlan0: Failed to initiate sched scan Mar 27 09:30:29 volumio volumio[3475]: info: CoreCommandRouter::volumioPlay Mar 27 09:30:29 volumio volumio[3475]: UNSET VOLATILE Mar 27 09:30:29 volumio volumio[3475]: info: CoreStateMachine::play index undefined Mar 27 09:30:29 volumio volumio[3475]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 27 09:30:29 volumio volumio[3475]: info: CorePlayQueue::getTrack 0 Mar 27 09:30:29 volumio volumio[3475]: info: CoreStateMachine::startPlaybackTimer Mar 27 09:30:29 volumio volumio[3475]: info: CorePlayQueue::getTrack 0 Mar 27 09:30:29 volumio volumio[3475]: verbose: ControllerMpd::clearAddPlayTracks USB/0403-0201/Emilia - Big Big World (Album Version).flac Mar 27 09:30:29 volumio volumio[3475]: verbose: ControllerMpd::sendMpdCommand stop Mar 27 09:30:29 volumio volumio[3475]: info: Mar 27 09:30:29 volumio volumio[3475]: ---------------------------- MPD announces state update: player Mar 27 09:30:29 volumio volumio[3475]: info: ControllerMpd::getState Mar 27 09:30:29 volumio volumio[3475]: verbose: ControllerMpd::sendMpdCommand status Mar 27 09:30:29 volumio volumio[3475]: verbose: ControllerMpd::sendMpdCommand clear Mar 27 09:30:29 volumio volumio[3475]: info: Mar 27 09:30:29 volumio volumio[3475]: ---------------------------- MPD announces system playlist update Mar 27 09:30:29 volumio volumio[3475]: info: Ignoring MPD Status Update Mar 27 09:30:29 volumio volumio[3475]: verbose: ControllerMpd::parseState Mar 27 09:30:29 volumio volumio[3475]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 27 09:30:29 volumio volumio[3475]: verbose: ControllerMpd::sendMpdCommand add "USB/0403-0201/Emilia - Big Big World (Album Version).flac" Mar 27 09:30:29 volumio volumio[3475]: error: updateQueue error: null Mar 27 09:30:29 volumio volumio[3475]: info: Mar 27 09:30:29 volumio volumio[3475]: ---------------------------- MPD announces system playlist update Mar 27 09:30:29 volumio volumio[3475]: info: Ignoring MPD Status Update Mar 27 09:30:29 volumio volumio[3475]: info: ------------------------------ 10ms Mar 27 09:30:29 volumio volumio[3475]: verbose: ControllerMpd::parseTrackInfo Mar 27 09:30:29 volumio volumio[3475]: verbose: ControllerMpd::sendMpdCommand play Mar 27 09:30:29 volumio volumio[3475]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Mar 27 09:30:29 volumio volumio[3475]: info: ------------------------------ 24ms Mar 27 09:30:29 volumio volumio[3475]: info: ------------------------------ 10ms Mar 27 09:30:29 volumio volumio[3475]: info: Mar 27 09:30:29 volumio volumio[3475]: ---------------------------- MPD announces state update: player Mar 27 09:30:29 volumio volumio[3475]: info: ControllerMpd::getState Mar 27 09:30:29 volumio volumio[3475]: verbose: ControllerMpd::sendMpdCommand status Mar 27 09:30:29 volumio volumio[3475]: info: Mar 27 09:30:29 volumio volumio[3475]: ---------------------------- MPD announces state update: player Mar 27 09:30:29 volumio volumio[3475]: info: ControllerMpd::getState Mar 27 09:30:29 volumio volumio[3475]: verbose: ControllerMpd::sendMpdCommand status Mar 27 09:30:29 volumio volumio[3475]: verbose: ControllerMpd::parseState Mar 27 09:30:29 volumio volumio[3475]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 27 09:30:29 volumio volumio[3475]: verbose: ControllerMpd::parseState Mar 27 09:30:29 volumio volumio[3475]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 27 09:30:29 volumio volumio[3475]: verbose: ControllerMpd::parseTrackInfo Mar 27 09:30:29 volumio volumio[3475]: info: ControllerMpd::pushState Mar 27 09:30:29 volumio volumio[3475]: info: CoreCommandRouter::servicePushState Mar 27 09:30:29 volumio volumio[3475]: info: CorePlayQueue::getTrack 0 Mar 27 09:30:29 volumio volumio[3475]: verbose: STATE SERVICE {"status":"play","position":0,"seek":464,"duration":203,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"526 Kbps","isStreaming":false,"title":"Big Big World (Album Version)","artist":"Emilia","album":"Big Big World (Scandinavian version)","uri":"USB/0403-0201/Emilia - Big Big World (Album Version).flac","trackType":"flac"} Mar 27 09:30:29 volumio volumio[3475]: verbose: CURRENT POSITION 0 Mar 27 09:30:29 volumio volumio[3475]: info: CoreStateMachine::syncState stateService play Mar 27 09:30:29 volumio volumio[3475]: info: CoreStateMachine::syncState currentStatus stop Mar 27 09:30:29 volumio volumio[3475]: info: CoreStateMachine::pushState Mar 27 09:30:29 volumio volumio[3475]: info: CorePlayQueue::getTrack 0 Mar 27 09:30:29 volumio volumio[3475]: info: CoreCommandRouter::volumioPushState Mar 27 09:30:29 volumio volumio[3475]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 27 09:30:29 volumio volumio[3475]: info: ------------------------------ 32ms Mar 27 09:30:29 volumio volumio[3475]: verbose: ControllerMpd::parseTrackInfo Mar 27 09:30:29 volumio volumio[3475]: info: ControllerMpd::pushState Mar 27 09:30:29 volumio volumio[3475]: info: CoreCommandRouter::servicePushState Mar 27 09:30:29 volumio volumio[3475]: info: CorePlayQueue::getTrack 0 Mar 27 09:30:29 volumio volumio[3475]: verbose: STATE SERVICE {"status":"play","position":0,"seek":464,"duration":203,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"511 Kbps","isStreaming":false,"title":"Big Big World (Album Version)","artist":"Emilia","album":"Big Big World (Scandinavian version)","uri":"USB/0403-0201/Emilia - Big Big World (Album Version).flac","trackType":"flac"} Mar 27 09:30:29 volumio volumio[3475]: verbose: CURRENT POSITION 0 Mar 27 09:30:29 volumio volumio[3475]: info: CoreStateMachine::syncState stateService play Mar 27 09:30:29 volumio volumio[3475]: info: CoreStateMachine::syncState currentStatus play Mar 27 09:30:29 volumio volumio[3475]: info: Received an update from plugin. extracting info from payload Mar 27 09:30:29 volumio volumio[3475]: info: CoreStateMachine::pushState Mar 27 09:30:29 volumio volumio[3475]: info: CorePlayQueue::getTrack 0 Mar 27 09:30:29 volumio volumio[3475]: info: CoreCommandRouter::volumioPushState Mar 27 09:30:29 volumio volumio[3475]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 27 09:30:29 volumio volumio[3475]: info: CoreStateMachine::pushState Mar 27 09:30:29 volumio volumio[3475]: info: CorePlayQueue::getTrack 0 Mar 27 09:30:29 volumio volumio[3475]: info: CoreCommandRouter::volumioPushState Mar 27 09:30:29 volumio volumio[3475]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 27 09:30:29 volumio volumio[3475]: info: ------------------------------ 56ms Mar 27 09:30:30 volumio volumio[3475]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=2.834&uuid=aa5966c5d68e303da03b8015962b4b0e" http://updates.volumio.org:7070/downloader-v1/track-device Mar 27 09:30:30 volumio volumio[3475]: % Total % Received % Xferd Average Speed Time Time Time Current Mar 27 09:30:30 volumio volumio[3475]: Dload Upload Total Spent Left Speed Mar 27 09:30:30 volumio volumio[3475]: 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0curl: (7) Failed to connect to updates.volumio.org port 7070: Connection refused Mar 27 09:30:30 volumio volumio[3475]: retrying in 5 seconds, trial 1 Mar 27 09:30:30 volumio volumio[3475]: Volumio Calling Home Mar 27 09:30:31 volumio wpa_supplicant[1011]: wlan0: Failed to initiate sched scan Mar 27 09:30:34 volumio volumio[3475]: info: CoreCommandRouter::volumioSeek Mar 27 09:30:34 volumio volumio[3475]: info: CoreStateMachine::seek Mar 27 09:30:34 volumio volumio[3475]: info: CorePlayQueue::getTrack 0 Mar 27 09:30:34 volumio volumio[3475]: info: TRACKBLOCK {"uri":"mnt/USB/0403-0201/Emilia - Big Big World (Album Version).flac","service":"mpd","name":"Big Big World (Album Version)","artist":"Emilia","album":"Big Big World (Scandinavian version)","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=238&web=Emilia/Big%20Big%20World%20(Scandinavian%20version)/extralarge&path=%2FUSB%2F0403-0201&metadata=false","duration":203,"trackType":"flac","samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2} Mar 27 09:30:34 volumio volumio[3475]: info: CoreStateMachine::startPlaybackTimer Mar 27 09:30:34 volumio volumio[3475]: info: CorePlayQueue::getTrack 0 Mar 27 09:30:34 volumio volumio[3475]: info: ControllerMpd::seek Mar 27 09:30:34 volumio volumio[3475]: info: CoreStateMachine::pushState Mar 27 09:30:34 volumio volumio[3475]: info: CorePlayQueue::getTrack 0 Mar 27 09:30:34 volumio volumio[3475]: info: CoreCommandRouter::volumioPushState Mar 27 09:30:34 volumio volumio[3475]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 27 09:30:34 volumio volumio[3475]: error: null Mar 27 09:30:34 volumio volumio[3475]: info: Mar 27 09:30:34 volumio volumio[3475]: ---------------------------- MPD announces state update: player Mar 27 09:30:34 volumio volumio[3475]: info: ControllerMpd::getState Mar 27 09:30:34 volumio volumio[3475]: verbose: ControllerMpd::sendMpdCommand status Mar 27 09:30:34 volumio volumio[3475]: verbose: ControllerMpd::parseState Mar 27 09:30:34 volumio volumio[3475]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 27 09:30:34 volumio volumio[3475]: verbose: ControllerMpd::parseTrackInfo Mar 27 09:30:34 volumio volumio[3475]: info: ControllerMpd::pushState Mar 27 09:30:34 volumio volumio[3475]: info: CoreCommandRouter::servicePushState Mar 27 09:30:34 volumio volumio[3475]: info: CorePlayQueue::getTrack 0 Mar 27 09:30:34 volumio volumio[3475]: verbose: STATE SERVICE {"status":"play","position":0,"seek":87000,"duration":203,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"601 Kbps","isStreaming":false,"title":"Big Big World (Album Version)","artist":"Emilia","album":"Big Big World (Scandinavian version)","uri":"USB/0403-0201/Emilia - Big Big World (Album Version).flac","trackType":"flac"} Mar 27 09:30:34 volumio volumio[3475]: verbose: CURRENT POSITION 0 Mar 27 09:30:34 volumio volumio[3475]: info: CoreStateMachine::syncState stateService play Mar 27 09:30:34 volumio volumio[3475]: info: CoreStateMachine::syncState currentStatus play Mar 27 09:30:34 volumio volumio[3475]: info: Received an update from plugin. extracting info from payload Mar 27 09:30:34 volumio volumio[3475]: info: CoreStateMachine::pushState Mar 27 09:30:34 volumio volumio[3475]: info: CorePlayQueue::getTrack 0 Mar 27 09:30:34 volumio volumio[3475]: info: CoreCommandRouter::volumioPushState Mar 27 09:30:34 volumio volumio[3475]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 27 09:30:34 volumio volumio[3475]: info: CoreStateMachine::pushState Mar 27 09:30:34 volumio volumio[3475]: info: CorePlayQueue::getTrack 0 Mar 27 09:30:34 volumio volumio[3475]: info: CoreCommandRouter::volumioPushState Mar 27 09:30:34 volumio volumio[3475]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 27 09:30:34 volumio volumio[3475]: info: ------------------------------ 43ms Mar 27 09:30:37 volumio volumio[3475]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 27 09:30:37 volumio volumio[3475]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 27 09:30:39 volumio wpa_supplicant[1011]: wlan0: Failed to initiate sched scan Mar 27 09:30:41 volumio volumio[3475]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=2.834&uuid=aa5966c5d68e303da03b8015962b4b0e" http://updates.volumio.org:7070/downloader-v1/track-device Mar 27 09:30:41 volumio volumio[3475]: % Total % Received % Xferd Average Speed Time Time Time Current Mar 27 09:30:41 volumio volumio[3475]: Dload Upload Total Spent Left Speed Mar 27 09:30:41 volumio volumio[3475]: 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0curl: (7) Failed to connect to updates.volumio.org port 7070: Connection refused Mar 27 09:30:41 volumio volumio[3475]: retrying in 5 seconds, trial 2 Mar 27 09:30:41 volumio volumio[3475]: Volumio Calling Home Mar 27 09:30:41 volumio volumio[3475]: info: CoreCommandRouter::volumioNext Mar 27 09:30:41 volumio volumio[3475]: info: CoreStateMachine::next Mar 27 09:30:41 volumio volumio[3475]: info: CoreStateMachine::stop Mar 27 09:30:41 volumio volumio[3475]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 27 09:30:41 volumio volumio[3475]: UNSET VOLATILE Mar 27 09:30:41 volumio volumio[3475]: info: CoreStateMachine::stPlaybackTimer Mar 27 09:30:41 volumio volumio[3475]: info: CoreStateMachine::updateTrackBlock Mar 27 09:30:41 volumio volumio[3475]: info: CorePlayQueue::getTrackBlock Mar 27 09:30:41 volumio volumio[3475]: info: CoreStateMachine::pushState Mar 27 09:30:41 volumio volumio[3475]: info: CorePlayQueue::getTrack 0 Mar 27 09:30:41 volumio volumio[3475]: info: CoreCommandRouter::volumioPushState Mar 27 09:30:41 volumio volumio[3475]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 27 09:30:41 volumio volumio[3475]: info: CoreStateMachine::serviceStop Mar 27 09:30:41 volumio volumio[3475]: info: CorePlayQueue::getTrack 0 Mar 27 09:30:41 volumio volumio[3475]: info: CoreCommandRouter::serviceStop Mar 27 09:30:41 volumio volumio[3475]: info: ControllerMpd::stop Mar 27 09:30:41 volumio volumio[3475]: verbose: ControllerMpd::sendMpdCommand stop Mar 27 09:30:41 volumio volumio[3475]: info: CoreStateMachine::play index undefined Mar 27 09:30:41 volumio volumio[3475]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 27 09:30:41 volumio volumio[3475]: info: CorePlayQueue::getTrack 1 Mar 27 09:30:41 volumio volumio[3475]: info: CoreStateMachine::startPlaybackTimer Mar 27 09:30:41 volumio volumio[3475]: info: CorePlayQueue::getTrack 1 Mar 27 09:30:41 volumio volumio[3475]: verbose: ControllerMpd::clearAddPlayTracks USB/0403-0201/Achim Reichel - Aloha Heja He.mp3 Mar 27 09:30:41 volumio volumio[3475]: verbose: ControllerMpd::sendMpdCommand stop Mar 27 09:30:41 volumio volumio[3475]: info: CoreStateMachine::updateTrackBlock Mar 27 09:30:41 volumio volumio[3475]: info: CorePlayQueue::getTrackBlock Mar 27 09:30:41 volumio volumio[3475]: info: Mar 27 09:30:41 volumio volumio[3475]: ---------------------------- MPD announces state update: player Mar 27 09:30:41 volumio volumio[3475]: info: ControllerMpd::getState Mar 27 09:30:41 volumio volumio[3475]: verbose: ControllerMpd::sendMpdCommand status Mar 27 09:30:41 volumio volumio[3475]: verbose: ControllerMpd::sendMpdCommand clear Mar 27 09:30:41 volumio volumio[3475]: info: Mar 27 09:30:41 volumio volumio[3475]: ---------------------------- MPD announces system playlist update Mar 27 09:30:41 volumio volumio[3475]: info: Ignoring MPD Status Update Mar 27 09:30:41 volumio volumio[3475]: verbose: ControllerMpd::parseState Mar 27 09:30:41 volumio volumio[3475]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 27 09:30:41 volumio volumio[3475]: verbose: ControllerMpd::sendMpdCommand add "USB/0403-0201/Achim Reichel - Aloha Heja He.mp3" Mar 27 09:30:41 volumio volumio[3475]: error: updateQueue error: null Mar 27 09:30:41 volumio volumio[3475]: info: Mar 27 09:30:41 volumio volumio[3475]: ---------------------------- MPD announces system playlist update Mar 27 09:30:41 volumio volumio[3475]: info: Ignoring MPD Status Update Mar 27 09:30:41 volumio volumio[3475]: info: ------------------------------ 5ms Mar 27 09:30:41 volumio volumio[3475]: verbose: ControllerMpd::parseTrackInfo Mar 27 09:30:41 volumio volumio[3475]: verbose: ControllerMpd::sendMpdCommand play Mar 27 09:30:41 volumio volumio[3475]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Mar 27 09:30:41 volumio volumio[3475]: info: ------------------------------ 13ms Mar 27 09:30:41 volumio volumio[3475]: info: ------------------------------ 6ms Mar 27 09:30:41 volumio volumio[3475]: info: Mar 27 09:30:41 volumio volumio[3475]: ---------------------------- MPD announces state update: player Mar 27 09:30:41 volumio volumio[3475]: info: ControllerMpd::getState Mar 27 09:30:41 volumio volumio[3475]: verbose: ControllerMpd::sendMpdCommand status Mar 27 09:30:41 volumio volumio[3475]: info: Mar 27 09:30:41 volumio volumio[3475]: ---------------------------- MPD announces state update: player Mar 27 09:30:41 volumio volumio[3475]: info: ControllerMpd::getState Mar 27 09:30:41 volumio volumio[3475]: verbose: ControllerMpd::sendMpdCommand status Mar 27 09:30:41 volumio volumio[3475]: verbose: ControllerMpd::parseState Mar 27 09:30:41 volumio volumio[3475]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 27 09:30:41 volumio volumio[3475]: verbose: ControllerMpd::parseState Mar 27 09:30:41 volumio volumio[3475]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 27 09:30:41 volumio volumio[3475]: verbose: ControllerMpd::parseTrackInfo Mar 27 09:30:41 volumio volumio[3475]: info: ControllerMpd::pushState Mar 27 09:30:41 volumio volumio[3475]: info: CoreCommandRouter::servicePushState Mar 27 09:30:41 volumio volumio[3475]: info: CorePlayQueue::getTrack 1 Mar 27 09:30:41 volumio volumio[3475]: verbose: STATE SERVICE {"status":"play","position":0,"seek":383,"duration":242,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Aloha Heja He","artist":"Achim Reichel","album":"Die Hit Giganten - Rock aus Deutschland","uri":"USB/0403-0201/Achim Reichel - Aloha Heja He.mp3","trackType":"mp3"} Mar 27 09:30:41 volumio volumio[3475]: verbose: CURRENT POSITION 1 Mar 27 09:30:41 volumio volumio[3475]: info: CoreStateMachine::syncState stateService play Mar 27 09:30:41 volumio volumio[3475]: info: CoreStateMachine::syncState currentStatus stop Mar 27 09:30:41 volumio volumio[3475]: info: CoreStateMachine::pushState Mar 27 09:30:41 volumio volumio[3475]: info: CorePlayQueue::getTrack 1 Mar 27 09:30:41 volumio volumio[3475]: info: CoreCommandRouter::volumioPushState Mar 27 09:30:41 volumio volumio[3475]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 27 09:30:41 volumio volumio[3475]: info: ------------------------------ 23ms Mar 27 09:30:41 volumio volumio[3475]: verbose: ControllerMpd::parseTrackInfo Mar 27 09:30:41 volumio volumio[3475]: info: ControllerMpd::pushState Mar 27 09:30:41 volumio volumio[3475]: info: CoreCommandRouter::servicePushState Mar 27 09:30:41 volumio volumio[3475]: info: CorePlayQueue::getTrack 1 Mar 27 09:30:41 volumio volumio[3475]: verbose: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":242,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Aloha Heja He","artist":"Achim Reichel","album":"Die Hit Giganten - Rock aus Deutschland","uri":"USB/0403-0201/Achim Reichel - Aloha Heja He.mp3","trackType":"mp3"} Mar 27 09:30:41 volumio volumio[3475]: verbose: CURRENT POSITION 1 Mar 27 09:30:41 volumio volumio[3475]: info: CoreStateMachine::syncState stateService play Mar 27 09:30:41 volumio volumio[3475]: info: CoreStateMachine::syncState currentStatus play Mar 27 09:30:41 volumio volumio[3475]: info: Received an update from plugin. extracting info from payload Mar 27 09:30:41 volumio volumio[3475]: info: CoreStateMachine::pushState Mar 27 09:30:41 volumio volumio[3475]: info: CorePlayQueue::getTrack 1 Mar 27 09:30:41 volumio volumio[3475]: info: CoreCommandRouter::volumioPushState Mar 27 09:30:41 volumio volumio[3475]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 27 09:30:41 volumio volumio[3475]: info: CoreStateMachine::pushState Mar 27 09:30:41 volumio volumio[3475]: info: CorePlayQueue::getTrack 1 Mar 27 09:30:41 volumio volumio[3475]: info: CoreCommandRouter::volumioPushState Mar 27 09:30:41 volumio volumio[3475]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 27 09:30:41 volumio volumio[3475]: info: ------------------------------ 42ms Mar 27 09:30:45 volumio volumio[3475]: info: CoreCommandRouter::volumioSeek Mar 27 09:30:45 volumio volumio[3475]: info: CoreStateMachine::seek Mar 27 09:30:45 volumio volumio[3475]: info: CorePlayQueue::getTrack 1 Mar 27 09:30:45 volumio volumio[3475]: info: TRACKBLOCK {"uri":"mnt/USB/0403-0201/Achim Reichel - Aloha Heja He.mp3","service":"mpd","name":"Aloha Heja He","artist":"Achim Reichel","album":"Die Hit Giganten - Rock aus Deutschland","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=238&web=Achim%20Reichel/Die%20Hit%20Giganten%20-%20Rock%20aus%20Deutschland/extralarge&path=%2FUSB%2F0403-0201&metadata=false","duration":242,"trackType":"mp3","samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2} Mar 27 09:30:45 volumio volumio[3475]: info: CoreStateMachine::startPlaybackTimer Mar 27 09:30:45 volumio volumio[3475]: info: CorePlayQueue::getTrack 1 Mar 27 09:30:45 volumio volumio[3475]: info: ControllerMpd::seek Mar 27 09:30:45 volumio volumio[3475]: info: CoreStateMachine::pushState Mar 27 09:30:45 volumio volumio[3475]: info: CorePlayQueue::getTrack 1 Mar 27 09:30:45 volumio volumio[3475]: info: CoreCommandRouter::volumioPushState Mar 27 09:30:45 volumio volumio[3475]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 27 09:30:45 volumio volumio[3475]: error: null Mar 27 09:30:45 volumio volumio[3475]: info: Mar 27 09:30:45 volumio volumio[3475]: ---------------------------- MPD announces state update: player Mar 27 09:30:45 volumio volumio[3475]: info: ControllerMpd::getState Mar 27 09:30:45 volumio volumio[3475]: verbose: ControllerMpd::sendMpdCommand status Mar 27 09:30:45 volumio volumio[3475]: verbose: ControllerMpd::parseState Mar 27 09:30:45 volumio volumio[3475]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 27 09:30:45 volumio volumio[3475]: verbose: ControllerMpd::parseTrackInfo Mar 27 09:30:45 volumio volumio[3475]: info: ControllerMpd::pushState Mar 27 09:30:45 volumio volumio[3475]: info: CoreCommandRouter::servicePushState Mar 27 09:30:45 volumio volumio[3475]: info: CorePlayQueue::getTrack 1 Mar 27 09:30:45 volumio volumio[3475]: verbose: STATE SERVICE {"status":"play","position":0,"seek":109000,"duration":242,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Aloha Heja He","artist":"Achim Reichel","album":"Die Hit Giganten - Rock aus Deutschland","uri":"USB/0403-0201/Achim Reichel - Aloha Heja He.mp3","trackType":"mp3"} Mar 27 09:30:45 volumio volumio[3475]: verbose: CURRENT POSITION 1 Mar 27 09:30:45 volumio volumio[3475]: info: CoreStateMachine::syncState stateService play Mar 27 09:30:45 volumio volumio[3475]: info: CoreStateMachine::syncState currentStatus play Mar 27 09:30:45 volumio volumio[3475]: info: Received an update from plugin. extracting info from payload Mar 27 09:30:45 volumio volumio[3475]: info: CoreStateMachine::pushState Mar 27 09:30:45 volumio volumio[3475]: info: CorePlayQueue::getTrack 1 Mar 27 09:30:45 volumio volumio[3475]: info: CoreCommandRouter::volumioPushState Mar 27 09:30:45 volumio volumio[3475]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 27 09:30:45 volumio volumio[3475]: info: CoreStateMachine::pushState Mar 27 09:30:45 volumio volumio[3475]: info: CorePlayQueue::getTrack 1 Mar 27 09:30:45 volumio volumio[3475]: info: CoreCommandRouter::volumioPushState Mar 27 09:30:45 volumio volumio[3475]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 27 09:30:45 volumio volumio[3475]: info: ------------------------------ 29ms Mar 27 09:30:47 volumio wpa_supplicant[1011]: wlan0: Failed to initiate sched scan Mar 27 09:30:47 volumio volumio[3475]: info: CoreCommandRouter::volumioSeek Mar 27 09:30:47 volumio volumio[3475]: info: CoreStateMachine::seek Mar 27 09:30:47 volumio volumio[3475]: info: CorePlayQueue::getTrack 1 Mar 27 09:30:47 volumio volumio[3475]: info: TRACKBLOCK {"uri":"mnt/USB/0403-0201/Achim Reichel - Aloha Heja He.mp3","service":"mpd","name":"Aloha Heja He","artist":"Achim Reichel","album":"Die Hit Giganten - Rock aus Deutschland","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=238&web=Achim%20Reichel/Die%20Hit%20Giganten%20-%20Rock%20aus%20Deutschland/extralarge&path=%2FUSB%2F0403-0201&metadata=false","duration":242,"trackType":"mp3","samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2} Mar 27 09:30:47 volumio volumio[3475]: info: CoreStateMachine::startPlaybackTimer Mar 27 09:30:47 volumio volumio[3475]: info: CorePlayQueue::getTrack 1 Mar 27 09:30:47 volumio volumio[3475]: info: ControllerMpd::seek Mar 27 09:30:47 volumio volumio[3475]: info: CoreStateMachine::pushState Mar 27 09:30:47 volumio volumio[3475]: info: CorePlayQueue::getTrack 1 Mar 27 09:30:47 volumio volumio[3475]: info: CoreCommandRouter::volumioPushState Mar 27 09:30:47 volumio volumio[3475]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 27 09:30:47 volumio volumio[3475]: error: null Mar 27 09:30:47 volumio volumio[3475]: info: Mar 27 09:30:47 volumio volumio[3475]: ---------------------------- MPD announces state update: player Mar 27 09:30:47 volumio volumio[3475]: info: ControllerMpd::getState Mar 27 09:30:47 volumio volumio[3475]: verbose: ControllerMpd::sendMpdCommand status Mar 27 09:30:47 volumio volumio[3475]: verbose: ControllerMpd::parseState Mar 27 09:30:47 volumio volumio[3475]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 27 09:30:47 volumio volumio[3475]: verbose: ControllerMpd::parseTrackInfo Mar 27 09:30:47 volumio volumio[3475]: info: ControllerMpd::pushState Mar 27 09:30:47 volumio volumio[3475]: info: CoreCommandRouter::servicePushState Mar 27 09:30:47 volumio volumio[3475]: info: CorePlayQueue::getTrack 1 Mar 27 09:30:47 volumio volumio[3475]: verbose: STATE SERVICE {"status":"play","position":0,"seek":78000,"duration":242,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Aloha Heja He","artist":"Achim Reichel","album":"Die Hit Giganten - Rock aus Deutschland","uri":"USB/0403-0201/Achim Reichel - Aloha Heja He.mp3","trackType":"mp3"} Mar 27 09:30:47 volumio volumio[3475]: verbose: CURRENT POSITION 1 Mar 27 09:30:47 volumio volumio[3475]: info: CoreStateMachine::syncState stateService play Mar 27 09:30:47 volumio volumio[3475]: info: CoreStateMachine::syncState currentStatus play Mar 27 09:30:47 volumio volumio[3475]: info: Received an update from plugin. extracting info from payload Mar 27 09:30:47 volumio volumio[3475]: info: CoreStateMachine::pushState Mar 27 09:30:47 volumio volumio[3475]: info: CorePlayQueue::getTrack 1 Mar 27 09:30:47 volumio volumio[3475]: info: CoreCommandRouter::volumioPushState Mar 27 09:30:47 volumio volumio[3475]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 27 09:30:47 volumio volumio[3475]: info: CoreStateMachine::pushState Mar 27 09:30:47 volumio volumio[3475]: info: CorePlayQueue::getTrack 1 Mar 27 09:30:47 volumio volumio[3475]: info: CoreCommandRouter::volumioPushState Mar 27 09:30:47 volumio volumio[3475]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 27 09:30:47 volumio volumio[3475]: info: ------------------------------ 28ms Mar 27 09:30:49 volumio volumio[3475]: info: CoreCommandRouter::volumioNext Mar 27 09:30:49 volumio volumio[3475]: info: CoreStateMachine::next Mar 27 09:30:49 volumio volumio[3475]: info: CoreStateMachine::stop Mar 27 09:30:49 volumio volumio[3475]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 27 09:30:49 volumio volumio[3475]: UNSET VOLATILE Mar 27 09:30:49 volumio volumio[3475]: info: CoreStateMachine::stPlaybackTimer Mar 27 09:30:49 volumio volumio[3475]: info: CoreStateMachine::updateTrackBlock Mar 27 09:30:49 volumio volumio[3475]: info: CorePlayQueue::getTrackBlock Mar 27 09:30:49 volumio volumio[3475]: info: CoreStateMachine::pushState Mar 27 09:30:49 volumio volumio[3475]: info: CorePlayQueue::getTrack 1 Mar 27 09:30:49 volumio volumio[3475]: info: CoreCommandRouter::volumioPushState Mar 27 09:30:49 volumio volumio[3475]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 27 09:30:49 volumio volumio[3475]: info: CoreStateMachine::serviceStop Mar 27 09:30:49 volumio volumio[3475]: info: CorePlayQueue::getTrack 1 Mar 27 09:30:49 volumio volumio[3475]: info: CoreCommandRouter::serviceStop Mar 27 09:30:49 volumio volumio[3475]: info: ControllerMpd::stop Mar 27 09:30:49 volumio volumio[3475]: verbose: ControllerMpd::sendMpdCommand stop Mar 27 09:30:49 volumio volumio[3475]: info: Mar 27 09:30:49 volumio volumio[3475]: ---------------------------- MPD announces state update: player Mar 27 09:30:49 volumio volumio[3475]: info: ControllerMpd::getState Mar 27 09:30:49 volumio volumio[3475]: verbose: ControllerMpd::sendMpdCommand status Mar 27 09:30:49 volumio volumio[3475]: info: CoreStateMachine::play index undefined Mar 27 09:30:49 volumio volumio[3475]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 27 09:30:49 volumio volumio[3475]: info: CorePlayQueue::getTrack 2 Mar 27 09:30:49 volumio volumio[3475]: info: CoreStateMachine::startPlaybackTimer Mar 27 09:30:49 volumio volumio[3475]: info: CorePlayQueue::getTrack 2 Mar 27 09:30:49 volumio volumio[3475]: verbose: ControllerMpd::clearAddPlayTracks USB/0403-0201/Alan Walker - Fade.flac Mar 27 09:30:49 volumio volumio[3475]: verbose: ControllerMpd::sendMpdCommand stop Mar 27 09:30:49 volumio volumio[3475]: info: CoreStateMachine::updateTrackBlock Mar 27 09:30:49 volumio volumio[3475]: info: CorePlayQueue::getTrackBlock Mar 27 09:30:49 volumio volumio[3475]: verbose: ControllerMpd::parseState Mar 27 09:30:49 volumio volumio[3475]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 27 09:30:49 volumio volumio[3475]: verbose: ControllerMpd::sendMpdCommand clear Mar 27 09:30:49 volumio volumio[3475]: info: Mar 27 09:30:49 volumio volumio[3475]: ---------------------------- MPD announces system playlist update Mar 27 09:30:49 volumio volumio[3475]: info: Ignoring MPD Status Update Mar 27 09:30:49 volumio volumio[3475]: verbose: ControllerMpd::parseTrackInfo Mar 27 09:30:49 volumio volumio[3475]: verbose: ControllerMpd::sendMpdCommand add "USB/0403-0201/Alan Walker - Fade.flac" Mar 27 09:30:49 volumio volumio[3475]: info: ControllerMpd::pushState Mar 27 09:30:49 volumio volumio[3475]: info: CoreCommandRouter::servicePushState Mar 27 09:30:49 volumio volumio[3475]: info: CoreStateMachine::pushState Mar 27 09:30:49 volumio volumio[3475]: info: CorePlayQueue::getTrack 2 Mar 27 09:30:49 volumio volumio[3475]: info: CoreCommandRouter::volumioPushState Mar 27 09:30:49 volumio volumio[3475]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 27 09:30:49 volumio volumio[3475]: info: CorePlayQueue::getTrack 2 Mar 27 09:30:49 volumio volumio[3475]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Aloha Heja He","artist":"Achim Reichel","album":"Die Hit Giganten - Rock aus Deutschland","uri":"USB/0403-0201/Achim Reichel - Aloha Heja He.mp3","trackType":"mp3"} Mar 27 09:30:49 volumio volumio[3475]: verbose: CURRENT POSITION 2 Mar 27 09:30:49 volumio volumio[3475]: info: CoreStateMachine::syncState stateService stop Mar 27 09:30:49 volumio volumio[3475]: info: CoreStateMachine::syncState currentStatus stop Mar 27 09:30:49 volumio volumio[3475]: info: CoreStateMachine::pushState Mar 27 09:30:49 volumio volumio[3475]: info: CorePlayQueue::getTrack 2 Mar 27 09:30:49 volumio volumio[3475]: info: CoreCommandRouter::volumioPushState Mar 27 09:30:49 volumio volumio[3475]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 27 09:30:49 volumio volumio[3475]: info: No code Mar 27 09:30:49 volumio volumio[3475]: info: CoreStateMachine::pushState Mar 27 09:30:49 volumio volumio[3475]: info: CorePlayQueue::getTrack 2 Mar 27 09:30:49 volumio volumio[3475]: info: CoreCommandRouter::volumioPushState Mar 27 09:30:49 volumio volumio[3475]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 27 09:30:49 volumio volumio[3475]: info: ------------------------------ 59ms Mar 27 09:30:49 volumio volumio[3475]: error: updateQueue error: null Mar 27 09:30:49 volumio volumio[3475]: info: Mar 27 09:30:49 volumio volumio[3475]: ---------------------------- MPD announces system playlist update Mar 27 09:30:49 volumio volumio[3475]: info: Ignoring MPD Status Update Mar 27 09:30:49 volumio volumio[3475]: info: ------------------------------ 35ms Mar 27 09:30:49 volumio volumio[3475]: verbose: ControllerMpd::sendMpdCommand play Mar 27 09:30:49 volumio volumio[3475]: info: ------------------------------ 12ms Mar 27 09:30:49 volumio volumio[3475]: info: Mar 27 09:30:49 volumio volumio[3475]: ---------------------------- MPD announces state update: player Mar 27 09:30:49 volumio volumio[3475]: info: ControllerMpd::getState Mar 27 09:30:49 volumio volumio[3475]: verbose: ControllerMpd::sendMpdCommand status Mar 27 09:30:49 volumio volumio[3475]: info: Mar 27 09:30:49 volumio volumio[3475]: ---------------------------- MPD announces state update: player Mar 27 09:30:49 volumio volumio[3475]: info: ControllerMpd::getState Mar 27 09:30:49 volumio volumio[3475]: verbose: ControllerMpd::sendMpdCommand status Mar 27 09:30:49 volumio volumio[3475]: verbose: ControllerMpd::parseState Mar 27 09:30:49 volumio volumio[3475]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 27 09:30:49 volumio volumio[3475]: verbose: ControllerMpd::parseState Mar 27 09:30:49 volumio volumio[3475]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 27 09:30:49 volumio volumio[3475]: verbose: ControllerMpd::parseTrackInfo Mar 27 09:30:49 volumio volumio[3475]: info: ControllerMpd::pushState Mar 27 09:30:49 volumio volumio[3475]: info: CoreCommandRouter::servicePushState Mar 27 09:30:49 volumio volumio[3475]: info: CorePlayQueue::getTrack 2 Mar 27 09:30:49 volumio volumio[3475]: verbose: STATE SERVICE {"status":"play","position":0,"seek":464,"duration":262,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"828 Kbps","isStreaming":false,"title":"Fade","artist":"Alan Walker","album":"Fade","uri":"USB/0403-0201/Alan Walker - Fade.flac","trackType":"flac"} Mar 27 09:30:49 volumio volumio[3475]: verbose: CURRENT POSITION 2 Mar 27 09:30:49 volumio volumio[3475]: info: CoreStateMachine::syncState stateService play Mar 27 09:30:49 volumio volumio[3475]: info: CoreStateMachine::syncState currentStatus stop Mar 27 09:30:49 volumio volumio[3475]: info: CoreStateMachine::pushState Mar 27 09:30:49 volumio volumio[3475]: info: CorePlayQueue::getTrack 2 Mar 27 09:30:49 volumio volumio[3475]: info: CoreCommandRouter::volumioPushState Mar 27 09:30:49 volumio volumio[3475]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 27 09:30:49 volumio volumio[3475]: info: ------------------------------ 27ms Mar 27 09:30:49 volumio volumio[3475]: verbose: ControllerMpd::parseTrackInfo Mar 27 09:30:49 volumio volumio[3475]: info: ControllerMpd::pushState Mar 27 09:30:49 volumio volumio[3475]: info: CoreCommandRouter::servicePushState Mar 27 09:30:49 volumio volumio[3475]: info: CorePlayQueue::getTrack 2 Mar 27 09:30:49 volumio volumio[3475]: verbose: STATE SERVICE {"status":"play","position":0,"seek":464,"duration":262,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"828 Kbps","isStreaming":false,"title":"Fade","artist":"Alan Walker","album":"Fade","uri":"USB/0403-0201/Alan Walker - Fade.flac","trackType":"flac"} Mar 27 09:30:49 volumio volumio[3475]: verbose: CURRENT POSITION 2 Mar 27 09:30:49 volumio volumio[3475]: info: CoreStateMachine::syncState stateService play Mar 27 09:30:49 volumio volumio[3475]: info: CoreStateMachine::syncState currentStatus play Mar 27 09:30:49 volumio volumio[3475]: info: Received an update from plugin. extracting info from payload Mar 27 09:30:49 volumio volumio[3475]: info: CoreStateMachine::pushState Mar 27 09:30:49 volumio volumio[3475]: info: CorePlayQueue::getTrack 2 Mar 27 09:30:49 volumio volumio[3475]: info: CoreCommandRouter::volumioPushState Mar 27 09:30:49 volumio volumio[3475]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 27 09:30:49 volumio volumio[3475]: info: CoreStateMachine::pushState Mar 27 09:30:49 volumio volumio[3475]: info: CorePlayQueue::getTrack 2 Mar 27 09:30:49 volumio volumio[3475]: info: CoreCommandRouter::volumioPushState Mar 27 09:30:49 volumio volumio[3475]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 27 09:30:49 volumio volumio[3475]: info: ------------------------------ 49ms Mar 27 09:30:52 volumio volumio[3475]: info: CoreCommandRouter::volumioSeek Mar 27 09:30:52 volumio volumio[3475]: info: CoreStateMachine::seek Mar 27 09:30:52 volumio volumio[3475]: info: CorePlayQueue::getTrack 2 Mar 27 09:30:52 volumio volumio[3475]: info: TRACKBLOCK {"uri":"mnt/USB/0403-0201/Alan Walker - Fade.flac","service":"mpd","name":"Fade","artist":"Alan Walker","album":"Fade","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=238&web=Alan%20Walker/Fade/extralarge&path=%2FUSB%2F0403-0201&metadata=false","duration":262,"trackType":"flac","samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2} Mar 27 09:30:52 volumio volumio[3475]: info: CoreStateMachine::startPlaybackTimer Mar 27 09:30:52 volumio volumio[3475]: info: CorePlayQueue::getTrack 2 Mar 27 09:30:52 volumio volumio[3475]: info: ControllerMpd::seek Mar 27 09:30:52 volumio volumio[3475]: info: CoreStateMachine::pushState Mar 27 09:30:52 volumio volumio[3475]: info: CorePlayQueue::getTrack 2 Mar 27 09:30:52 volumio volumio[3475]: info: CoreCommandRouter::volumioPushState Mar 27 09:30:52 volumio volumio[3475]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 27 09:30:52 volumio volumio[3475]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Alan%20Walker/Fade/43eb1628-13df-40da-9895-a08f0431781e.jpg' Mar 27 09:30:52 volumio volumio[3475]: error: null Mar 27 09:30:52 volumio volumio[3475]: info: Mar 27 09:30:52 volumio volumio[3475]: ---------------------------- MPD announces state update: player Mar 27 09:30:52 volumio volumio[3475]: info: ControllerMpd::getState Mar 27 09:30:52 volumio volumio[3475]: verbose: ControllerMpd::sendMpdCommand status Mar 27 09:30:52 volumio volumio[3475]: verbose: ControllerMpd::parseState Mar 27 09:30:52 volumio volumio[3475]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 27 09:30:52 volumio volumio[3475]: verbose: ControllerMpd::parseTrackInfo Mar 27 09:30:52 volumio volumio[3475]: info: ControllerMpd::pushState Mar 27 09:30:52 volumio volumio[3475]: info: CoreCommandRouter::servicePushState Mar 27 09:30:52 volumio volumio[3475]: info: CorePlayQueue::getTrack 2 Mar 27 09:30:52 volumio volumio[3475]: verbose: STATE SERVICE {"status":"play","position":0,"seek":97000,"duration":262,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"931 Kbps","isStreaming":false,"title":"Fade","artist":"Alan Walker","album":"Fade","uri":"USB/0403-0201/Alan Walker - Fade.flac","trackType":"flac"} Mar 27 09:30:52 volumio volumio[3475]: verbose: CURRENT POSITION 2 Mar 27 09:30:52 volumio volumio[3475]: info: CoreStateMachine::syncState stateService play Mar 27 09:30:52 volumio volumio[3475]: info: CoreStateMachine::syncState currentStatus play Mar 27 09:30:52 volumio volumio[3475]: info: Received an update from plugin. extracting info from payload Mar 27 09:30:52 volumio volumio[3475]: info: CoreStateMachine::pushState Mar 27 09:30:52 volumio volumio[3475]: info: CorePlayQueue::getTrack 2 Mar 27 09:30:52 volumio volumio[3475]: info: CoreCommandRouter::volumioPushState Mar 27 09:30:52 volumio volumio[3475]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 27 09:30:52 volumio volumio[3475]: info: CoreStateMachine::pushState Mar 27 09:30:52 volumio volumio[3475]: info: CorePlayQueue::getTrack 2 Mar 27 09:30:52 volumio volumio[3475]: info: CoreCommandRouter::volumioPushState Mar 27 09:30:52 volumio volumio[3475]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 27 09:30:52 volumio volumio[3475]: info: ------------------------------ 32ms Mar 27 09:30:55 volumio wpa_supplicant[1011]: wlan0: Failed to initiate sched scan Mar 27 09:31:00 volumio volumio[3475]: info: CoreCommandRouter::volumioNext Mar 27 09:31:00 volumio volumio[3475]: info: CoreStateMachine::next Mar 27 09:31:00 volumio volumio[3475]: info: CoreStateMachine::stop Mar 27 09:31:00 volumio volumio[3475]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 27 09:31:00 volumio volumio[3475]: UNSET VOLATILE Mar 27 09:31:00 volumio volumio[3475]: info: CoreStateMachine::stPlaybackTimer Mar 27 09:31:00 volumio volumio[3475]: info: CoreStateMachine::updateTrackBlock Mar 27 09:31:00 volumio volumio[3475]: info: CorePlayQueue::getTrackBlock Mar 27 09:31:00 volumio volumio[3475]: info: CoreStateMachine::pushState Mar 27 09:31:00 volumio volumio[3475]: info: CorePlayQueue::getTrack 2 Mar 27 09:31:00 volumio volumio[3475]: info: CoreCommandRouter::volumioPushState Mar 27 09:31:00 volumio volumio[3475]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 27 09:31:00 volumio volumio[3475]: info: CoreStateMachine::serviceStop Mar 27 09:31:00 volumio volumio[3475]: info: CorePlayQueue::getTrack 2 Mar 27 09:31:00 volumio volumio[3475]: info: CoreCommandRouter::serviceStop Mar 27 09:31:00 volumio volumio[3475]: info: ControllerMpd::stop Mar 27 09:31:00 volumio volumio[3475]: verbose: ControllerMpd::sendMpdCommand stop Mar 27 09:31:00 volumio volumio[3475]: info: Mar 27 09:31:00 volumio volumio[3475]: ---------------------------- MPD announces state update: player Mar 27 09:31:00 volumio volumio[3475]: info: ControllerMpd::getState Mar 27 09:31:00 volumio volumio[3475]: verbose: ControllerMpd::sendMpdCommand status Mar 27 09:31:00 volumio volumio[3475]: info: CoreStateMachine::play index undefined Mar 27 09:31:00 volumio volumio[3475]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 27 09:31:00 volumio volumio[3475]: info: CorePlayQueue::getTrack 3 Mar 27 09:31:00 volumio volumio[3475]: info: CoreStateMachine::startPlaybackTimer Mar 27 09:31:00 volumio volumio[3475]: info: CorePlayQueue::getTrack 3 Mar 27 09:31:00 volumio volumio[3475]: verbose: ControllerMpd::clearAddPlayTracks USB/0403-0201/FreeG、Fat-K - Good Time (Radio Edit).flac Mar 27 09:31:00 volumio volumio[3475]: verbose: ControllerMpd::sendMpdCommand stop Mar 27 09:31:00 volumio volumio[3475]: info: CoreStateMachine::updateTrackBlock Mar 27 09:31:00 volumio volumio[3475]: info: CorePlayQueue::getTrackBlock Mar 27 09:31:00 volumio volumio[3475]: verbose: ControllerMpd::parseState Mar 27 09:31:00 volumio volumio[3475]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 27 09:31:00 volumio volumio[3475]: verbose: ControllerMpd::sendMpdCommand clear Mar 27 09:31:00 volumio volumio[3475]: info: Mar 27 09:31:00 volumio volumio[3475]: ---------------------------- MPD announces system playlist update Mar 27 09:31:00 volumio volumio[3475]: info: Ignoring MPD Status Update Mar 27 09:31:00 volumio volumio[3475]: verbose: ControllerMpd::parseTrackInfo Mar 27 09:31:00 volumio volumio[3475]: verbose: ControllerMpd::sendMpdCommand add "USB/0403-0201/FreeG、Fat-K - Good Time (Radio Edit).flac" Mar 27 09:31:00 volumio volumio[3475]: info: ControllerMpd::pushState Mar 27 09:31:00 volumio volumio[3475]: info: CoreCommandRouter::servicePushState Mar 27 09:31:00 volumio volumio[3475]: info: CoreStateMachine::pushState Mar 27 09:31:00 volumio volumio[3475]: info: CorePlayQueue::getTrack 3 Mar 27 09:31:00 volumio volumio[3475]: info: CoreCommandRouter::volumioPushState Mar 27 09:31:00 volumio volumio[3475]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 27 09:31:00 volumio volumio[3475]: info: CorePlayQueue::getTrack 3 Mar 27 09:31:00 volumio volumio[3475]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Fade","artist":"Alan Walker","album":"Fade","uri":"USB/0403-0201/Alan Walker - Fade.flac","trackType":"flac"} Mar 27 09:31:00 volumio volumio[3475]: verbose: CURRENT POSITION 3 Mar 27 09:31:00 volumio volumio[3475]: info: CoreStateMachine::syncState stateService stop Mar 27 09:31:00 volumio volumio[3475]: info: CoreStateMachine::syncState currentStatus stop Mar 27 09:31:00 volumio volumio[3475]: info: CoreStateMachine::pushState Mar 27 09:31:00 volumio volumio[3475]: info: CorePlayQueue::getTrack 3 Mar 27 09:31:00 volumio volumio[3475]: info: CoreCommandRouter::volumioPushState Mar 27 09:31:00 volumio volumio[3475]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 27 09:31:00 volumio volumio[3475]: info: No code Mar 27 09:31:00 volumio volumio[3475]: info: CoreStateMachine::pushState Mar 27 09:31:00 volumio volumio[3475]: info: CorePlayQueue::getTrack 3 Mar 27 09:31:00 volumio volumio[3475]: info: CoreCommandRouter::volumioPushState Mar 27 09:31:00 volumio volumio[3475]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 27 09:31:00 volumio volumio[3475]: info: ------------------------------ 51ms Mar 27 09:31:00 volumio volumio[3475]: error: updateQueue error: null Mar 27 09:31:00 volumio volumio[3475]: info: Mar 27 09:31:00 volumio volumio[3475]: ---------------------------- MPD announces system playlist update Mar 27 09:31:00 volumio volumio[3475]: info: Ignoring MPD Status Update Mar 27 09:31:00 volumio volumio[3475]: info: ------------------------------ 39ms Mar 27 09:31:00 volumio volumio[3475]: verbose: ControllerMpd::sendMpdCommand play Mar 27 09:31:00 volumio volumio[3475]: info: ------------------------------ 14ms Mar 27 09:31:00 volumio volumio[3475]: info: Mar 27 09:31:00 volumio volumio[3475]: ---------------------------- MPD announces state update: player Mar 27 09:31:00 volumio volumio[3475]: info: ControllerMpd::getState Mar 27 09:31:00 volumio volumio[3475]: verbose: ControllerMpd::sendMpdCommand status Mar 27 09:31:00 volumio volumio[3475]: info: Mar 27 09:31:00 volumio volumio[3475]: ---------------------------- MPD announces state update: player Mar 27 09:31:00 volumio volumio[3475]: info: ControllerMpd::getState Mar 27 09:31:00 volumio volumio[3475]: verbose: ControllerMpd::sendMpdCommand status Mar 27 09:31:00 volumio volumio[3475]: verbose: ControllerMpd::parseState Mar 27 09:31:00 volumio volumio[3475]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 27 09:31:00 volumio volumio[3475]: verbose: ControllerMpd::parseState Mar 27 09:31:00 volumio volumio[3475]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 27 09:31:00 volumio volumio[3475]: verbose: ControllerMpd::parseTrackInfo Mar 27 09:31:00 volumio volumio[3475]: info: ControllerMpd::pushState Mar 27 09:31:00 volumio volumio[3475]: info: CoreCommandRouter::servicePushState Mar 27 09:31:00 volumio volumio[3475]: info: CorePlayQueue::getTrack 3 Mar 27 09:31:00 volumio volumio[3475]: verbose: STATE SERVICE {"status":"play","position":0,"seek":464,"duration":174,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"673 Kbps","isStreaming":false,"title":"Good Time (Radio Edit)","artist":"FreeG、Fat-K","album":"Good Time","uri":"USB/0403-0201/FreeG、Fat-K - Good Time (Radio Edit).flac","trackType":"flac"} Mar 27 09:31:00 volumio volumio[3475]: verbose: CURRENT POSITION 3 Mar 27 09:31:00 volumio volumio[3475]: info: CoreStateMachine::syncState stateService play Mar 27 09:31:00 volumio volumio[3475]: info: CoreStateMachine::syncState currentStatus stop Mar 27 09:31:00 volumio volumio[3475]: info: CoreStateMachine::pushState Mar 27 09:31:00 volumio volumio[3475]: info: CorePlayQueue::getTrack 3 Mar 27 09:31:00 volumio volumio[3475]: info: CoreCommandRouter::volumioPushState Mar 27 09:31:00 volumio volumio[3475]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 27 09:31:00 volumio volumio[3475]: info: ------------------------------ 26ms Mar 27 09:31:00 volumio volumio[3475]: verbose: ControllerMpd::parseTrackInfo Mar 27 09:31:00 volumio volumio[3475]: info: ControllerMpd::pushState Mar 27 09:31:00 volumio volumio[3475]: info: CoreCommandRouter::servicePushState Mar 27 09:31:00 volumio volumio[3475]: info: CorePlayQueue::getTrack 3 Mar 27 09:31:00 volumio volumio[3475]: verbose: STATE SERVICE {"status":"play","position":0,"seek":464,"duration":174,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"673 Kbps","isStreaming":false,"title":"Good Time (Radio Edit)","artist":"FreeG、Fat-K","album":"Good Time","uri":"USB/0403-0201/FreeG、Fat-K - Good Time (Radio Edit).flac","trackType":"flac"} Mar 27 09:31:00 volumio volumio[3475]: verbose: CURRENT POSITION 3 Mar 27 09:31:00 volumio volumio[3475]: info: CoreStateMachine::syncState stateService play Mar 27 09:31:00 volumio volumio[3475]: info: CoreStateMachine::syncState currentStatus play Mar 27 09:31:00 volumio volumio[3475]: info: Received an update from plugin. extracting info from payload Mar 27 09:31:00 volumio volumio[3475]: info: CoreStateMachine::pushState Mar 27 09:31:00 volumio volumio[3475]: info: CorePlayQueue::getTrack 3 Mar 27 09:31:00 volumio volumio[3475]: info: CoreCommandRouter::volumioPushState Mar 27 09:31:00 volumio volumio[3475]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 27 09:31:00 volumio volumio[3475]: info: CoreStateMachine::pushState Mar 27 09:31:00 volumio volumio[3475]: info: CorePlayQueue::getTrack 3 Mar 27 09:31:00 volumio volumio[3475]: info: CoreCommandRouter::volumioPushState Mar 27 09:31:00 volumio volumio[3475]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 27 09:31:00 volumio volumio[3475]: info: ------------------------------ 52ms Mar 27 09:31:01 volumio volumio[3475]: info: CoreCommandRouter::volumioSeek Mar 27 09:31:01 volumio volumio[3475]: info: CoreStateMachine::seek Mar 27 09:31:01 volumio volumio[3475]: info: CorePlayQueue::getTrack 3 Mar 27 09:31:01 volumio volumio[3475]: info: TRACKBLOCK {"uri":"mnt/USB/0403-0201/FreeG、Fat-K - Good Time (Radio Edit).flac","service":"mpd","name":"Good Time (Radio Edit)","artist":"FreeG、Fat-K","album":"Good Time","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=238&web=FreeG%E3%80%81Fat-K/Good%20Time/extralarge&path=%2FUSB%2F0403-0201&metadata=false","duration":174,"trackType":"flac","samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2} Mar 27 09:31:01 volumio volumio[3475]: info: CoreStateMachine::startPlaybackTimer Mar 27 09:31:01 volumio volumio[3475]: info: CorePlayQueue::getTrack 3 Mar 27 09:31:01 volumio volumio[3475]: info: ControllerMpd::seek Mar 27 09:31:01 volumio volumio[3475]: info: CoreStateMachine::pushState Mar 27 09:31:01 volumio volumio[3475]: info: CorePlayQueue::getTrack 3 Mar 27 09:31:01 volumio volumio[3475]: info: CoreCommandRouter::volumioPushState Mar 27 09:31:01 volumio volumio[3475]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 27 09:31:01 volumio volumio[3475]: error: null Mar 27 09:31:01 volumio volumio[3475]: info: Mar 27 09:31:01 volumio volumio[3475]: ---------------------------- MPD announces state update: player Mar 27 09:31:01 volumio volumio[3475]: info: ControllerMpd::getState Mar 27 09:31:01 volumio volumio[3475]: verbose: ControllerMpd::sendMpdCommand status Mar 27 09:31:01 volumio volumio[3475]: verbose: ControllerMpd::parseState Mar 27 09:31:01 volumio volumio[3475]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 27 09:31:01 volumio volumio[3475]: verbose: ControllerMpd::parseTrackInfo Mar 27 09:31:01 volumio volumio[3475]: info: ControllerMpd::pushState Mar 27 09:31:01 volumio volumio[3475]: info: CoreCommandRouter::servicePushState Mar 27 09:31:01 volumio volumio[3475]: info: CorePlayQueue::getTrack 3 Mar 27 09:31:01 volumio volumio[3475]: verbose: STATE SERVICE {"status":"play","position":0,"seek":67000,"duration":174,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"726 Kbps","isStreaming":false,"title":"Good Time (Radio Edit)","artist":"FreeG、Fat-K","album":"Good Time","uri":"USB/0403-0201/FreeG、Fat-K - Good Time (Radio Edit).flac","trackType":"flac"} Mar 27 09:31:01 volumio volumio[3475]: verbose: CURRENT POSITION 3 Mar 27 09:31:01 volumio volumio[3475]: info: CoreStateMachine::syncState stateService play Mar 27 09:31:01 volumio volumio[3475]: info: CoreStateMachine::syncState currentStatus play Mar 27 09:31:01 volumio volumio[3475]: info: Received an update from plugin. extracting info from payload Mar 27 09:31:01 volumio volumio[3475]: info: CoreStateMachine::pushState Mar 27 09:31:01 volumio volumio[3475]: info: CorePlayQueue::getTrack 3 Mar 27 09:31:01 volumio volumio[3475]: info: CoreCommandRouter::volumioPushState Mar 27 09:31:01 volumio volumio[3475]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 27 09:31:02 volumio volumio[3475]: info: CoreStateMachine::pushState Mar 27 09:31:02 volumio volumio[3475]: info: CorePlayQueue::getTrack 3 Mar 27 09:31:02 volumio volumio[3475]: info: CoreCommandRouter::volumioPushState Mar 27 09:31:02 volumio volumio[3475]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 27 09:31:02 volumio volumio[3475]: info: ------------------------------ 30ms Mar 27 09:31:03 volumio wpa_supplicant[1011]: wlan0: Failed to initiate sched scan Mar 27 09:31:10 volumio wpa_supplicant[1011]: wlan0: Failed to initiate sched scan Mar 27 09:31:18 volumio wpa_supplicant[1011]: wlan0: Failed to initiate sched scan Mar 27 09:31:22 volumio volumio[3475]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 27 09:31:22 volumio volumio[3475]: { Error: connect ETIMEDOUT 128.121.146.109:443 Mar 27 09:31:22 volumio volumio[3475]: at Object._errnoException (util.js:1022:11) Mar 27 09:31:22 volumio volumio[3475]: at _exceptionWithHostPort (util.js:1044:20) Mar 27 09:31:22 volumio volumio[3475]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1198:14) Mar 27 09:31:22 volumio volumio[3475]: code: 'ETIMEDOUT', Mar 27 09:31:22 volumio volumio[3475]: errno: 'ETIMEDOUT', Mar 27 09:31:22 volumio volumio[3475]: syscall: 'connect', Mar 27 09:31:22 volumio volumio[3475]: address: '128.121.146.109', Mar 27 09:31:22 volumio volumio[3475]: port: 443 } Mar 27 09:31:22 volumio volumio[3475]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 27 09:31:23 volumio sudo[3843]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-03-27 09:30 Mar 27 09:31:23 volumio sudo[3843]: 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="74e4cc9de715c64d553d35948d017f973a622b6d" VOLUMIO_FE_VERSION="2be6c28eb9de74ec1f9662ca333f7bd51a232c33" VOLUMIO_BE_VERSION="259a7f2894e1376413ffac331be84e0e9a6173dd" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Sep 24 22:42:43 CEST 2020" VOLUMIO_VERSION="2.834" VOLUMIO_HARDWARE="pi" VOLUMIO_HASH="e41ef0f29aa50c1af109d3064a380c69"