Mar 25 13:20:28 volumio ntpd[1148]: CLOCK: time stepped by 20126.865122 Mar 25 13:20:28 volumio ntpd[1148]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes Mar 25 13:20:28 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Mar 25 13:20:29 volumio volumio[1381]: info: Volumio Network Manager: Network status updated: 2 Mar 25 13:20:29 volumio sudo[2175]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 25 13:20:29 volumio sudo[2175]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 25 13:20:29 volumio sudo[2175]: pam_unix(sudo:session): session closed for user root Mar 25 13:20:29 volumio sudo[2178]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 25 13:20:29 volumio sudo[2178]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 25 13:20:29 volumio sudo[2178]: pam_unix(sudo:session): session closed for user root Mar 25 13:20:29 volumio sudo[2181]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Mar 25 13:20:29 volumio sudo[2181]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 25 13:20:29 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Mar 25 13:20:29 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Mar 25 13:20:29 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 25 13:20:29 volumio volumio[1381]: info: Received Get System Info Mar 25 13:20:29 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 25 13:20:29 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 25 13:20:29 volumio volumio[1381]: info: Discovery: Getting this device information Mar 25 13:20:29 volumio volumio[1381]: info: CoreCommandRouter::volumioGetState Mar 25 13:20:29 volumio volumio[1381]: info: CorePlayQueue::getTrack 0 Mar 25 13:20:29 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 25 13:20:30 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Mar 25 13:20:31 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Mar 25 13:20:31 volumio volumio[1381]: info: Discovery: Started advertising with name: Volumio Mar 25 13:20:32 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Mar 25 13:20:33 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Mar 25 13:20:33 volumio volumio[1381]: info: Discovery: this is already registered, 1914ec74-d601-44ea-84d7-6f34ad43b8bb Mar 25 13:20:33 volumio volumio[1381]: info: Discovery: Found device Volumio Mar 25 13:20:33 volumio volumio[1381]: info: CoreCommandRouter::volumioGetState Mar 25 13:20:33 volumio volumio[1381]: info: CorePlayQueue::getTrack 0 Mar 25 13:20:33 volumio volumio[1381]: info: Discovery: this is already registered, 1914ec74-d601-44ea-84d7-6f34ad43b8bb Mar 25 13:20:33 volumio volumio[1381]: info: Discovery: Found device Volumio Mar 25 13:20:33 volumio volumio[1381]: info: CoreCommandRouter::volumioGetState Mar 25 13:20:33 volumio volumio[1381]: info: CorePlayQueue::getTrack 0 Mar 25 13:20:34 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Mar 25 13:20:34 volumio volumio[1381]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Mar 25 13:20:34 volumio volumio[1381]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Mar 25 13:20:34 volumio volumio[1381]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Mar 25 13:20:34 volumio volumio[1381]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Mar 25 13:20:34 volumio volumio[1381]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Mar 25 13:20:34 volumio volumio[1381]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Mar 25 13:20:34 volumio volumio[1381]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Mar 25 13:20:34 volumio volumio[1381]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Mar 25 13:20:34 volumio volumio[1381]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Mar 25 13:20:34 volumio volumio[1381]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Mar 25 13:20:34 volumio volumio[1381]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Mar 25 13:20:34 volumio volumio[1381]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Mar 25 13:20:34 volumio volumio[1381]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Mar 25 13:20:34 volumio volumio[1381]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Mar 25 13:20:34 volumio volumio[1381]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Mar 25 13:20:34 volumio volumio[1381]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Mar 25 13:20:34 volumio volumio[1381]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Mar 25 13:20:34 volumio volumio[1381]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Mar 25 13:20:34 volumio volumio[1381]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Mar 25 13:20:34 volumio volumio[1381]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Mar 25 13:20:34 volumio volumio[1381]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Mar 25 13:20:34 volumio volumio[1381]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Mar 25 13:20:34 volumio volumio[1381]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Mar 25 13:20:34 volumio volumio[1381]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Mar 25 13:20:34 volumio volumio[1381]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Mar 25 13:20:34 volumio volumio[1381]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Mar 25 13:20:34 volumio volumio[1381]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Mar 25 13:20:34 volumio volumio[1381]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Mar 25 13:20:34 volumio volumio[1381]: info: Adding plugin bluetooth to MyMusic Plugins Mar 25 13:20:34 volumio volumio[1381]: info: Adding plugin multiroom to MyMusic Plugins Mar 25 13:20:34 volumio volumio[1381]: info: Adding plugin metavolumio to MyMusic Plugins Mar 25 13:20:34 volumio volumio[1381]: info: Adding plugin cd_controller to MyMusic Plugins Mar 25 13:20:34 volumio volumio[1381]: info: Adding plugin qobuzconnect to MyMusic Plugins Mar 25 13:20:34 volumio volumio[1381]: info: Adding plugin smart_inputs to MyMusic Plugins Mar 25 13:20:34 volumio volumio[1381]: info: Adding plugin tidalconnect to MyMusic Plugins Mar 25 13:20:34 volumio volumio[1381]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Mar 25 13:20:34 volumio volumio[1381]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Mar 25 13:20:34 volumio volumio[1381]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Mar 25 13:20:34 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 25 13:20:34 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 25 13:20:34 volumio volumio[1381]: info: Starting MyVolumio Remote Streaming Endpoints Mar 25 13:20:34 volumio volumio[1381]: info: MyVolumio login type: Token Mar 25 13:20:34 volumio volumio[1381]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Mar 25 13:20:34 volumio volumio[1381]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Mar 25 13:20:35 volumio volumio[1381]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Mar 25 13:20:35 volumio volumio[1381]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Mar 25 13:20:35 volumio volumio[1381]: info: Streaming services startup Mar 25 13:20:35 volumio volumio[1381]: info: Starting Streaming Daemon Mar 25 13:20:35 volumio sudo[2198]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Mar 25 13:20:35 volumio sudo[2198]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 25 13:20:35 volumio volumio[1381]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Mar 25 13:20:35 volumio sudo[2198]: pam_unix(sudo:session): session closed for user root Mar 25 13:20:35 volumio volumio[1381]: error: Cannot start Volumio Streaming Daemon Mar 25 13:20:35 volumio volumio[1381]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Mar 25 13:20:35 volumio volumio[1381]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Mar 25 13:20:35 volumio systemd[1]: systemd-fsckd.service: Deactivated successfully. Mar 25 13:20:35 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Mar 25 13:20:36 volumio dhcpcd[904]: timed out Mar 25 13:20:36 volumio sh[895]: timed out Mar 25 13:20:36 volumio sh[831]: ifup: failed to bring up eth0 Mar 25 13:20:36 volumio systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Mar 25 13:20:36 volumio systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Mar 25 13:20:36 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Mar 25 13:20:36 volumio systemd[1]: systemd-hostnamed.service: Deactivated successfully. Mar 25 13:20:37 volumio volumio[1381]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Mar 25 13:20:37 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Mar 25 13:20:37 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 3. Mar 25 13:20:37 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Mar 25 13:20:37 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Mar 25 13:20:37 volumio sudo[2181]: pam_unix(sudo:session): session closed for user root Mar 25 13:20:37 volumio volumio[1381]: info: Upmpdcli Daemon Started Mar 25 13:20:37 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Mar 25 13:20:37 volumio volumio[1381]: info: MyVolumio token set successfully Mar 25 13:20:37 volumio volumio[1381]: info: MYVOLUMIO: Adding device Mar 25 13:20:37 volumio volumio[1381]: info: MYVOLUMIO: Evaluating Server Mar 25 13:20:38 volumio volumio[1381]: info: MyVolumio status changed Mar 25 13:20:38 volumio volumio[1381]: info: Streaming services startup Mar 25 13:20:38 volumio volumio[1381]: info: Starting Streaming Daemon Mar 25 13:20:38 volumio volumio[1381]: info: Removing browser output: myVolumio user plan is not superstar Mar 25 13:20:38 volumio volumio[1381]: info: Removing audio output: Mar 25 13:20:38 volumio volumio[1381]: info: Stoppping Tunnel 1 Mar 25 13:20:38 volumio sudo[2247]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Mar 25 13:20:38 volumio sudo[2247]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 25 13:20:38 volumio sudo[2247]: pam_unix(sudo:session): session closed for user root Mar 25 13:20:38 volumio volumio[1381]: error: Cannot start Volumio Streaming Daemon Mar 25 13:20:38 volumio volumio[1381]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Mar 25 13:20:38 volumio volumio[1381]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Mar 25 13:20:38 volumio sudo[2250]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Mar 25 13:20:38 volumio sudo[2250]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 25 13:20:38 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Mar 25 13:20:38 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Mar 25 13:20:38 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Mar 25 13:20:38 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Mar 25 13:20:38 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Mar 25 13:20:38 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Mar 25 13:20:38 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Mar 25 13:20:38 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Mar 25 13:20:38 volumio sudo[2250]: pam_unix(sudo:session): session closed for user root Mar 25 13:20:38 volumio volumio[1381]: info: Remote SSH Stopped Mar 25 13:20:38 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Mar 25 13:20:38 volumio volumio[1381]: info: Setting Geolocation for MyVolumio to eu9 Mar 25 13:20:38 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 25 13:20:38 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 25 13:20:38 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 25 13:20:39 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Mar 25 13:20:39 volumio volumio[1381]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Mar 25 13:20:39 volumio volumio[1381]: info: Updating MyVolumio device info Mar 25 13:20:39 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 25 13:20:39 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 25 13:20:39 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 25 13:20:40 volumio volumio[1381]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Mar 25 13:20:40 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Mar 25 13:20:41 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Mar 25 13:20:42 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Mar 25 13:20:43 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Mar 25 13:20:43 volumio volumio[1381]: info: MYVOLUMIO: Adding device Mar 25 13:20:43 volumio volumio[1381]: info: MYVOLUMIO: Evaluating Server Mar 25 13:20:44 volumio volumio[1381]: info: Setting Geolocation for MyVolumio to eu12 Mar 25 13:20:44 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 25 13:20:44 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 25 13:20:44 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 25 13:20:44 volumio upmpdcli[2284]: writing RSA key Mar 25 13:20:44 volumio volumio[1381]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Mar 25 13:20:44 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Mar 25 13:20:45 volumio volumio[1381]: info: Updating MyVolumio device info Mar 25 13:20:45 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 25 13:20:45 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 25 13:20:45 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 25 13:20:45 volumio volumio[1381]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Mar 25 13:20:45 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Mar 25 13:20:46 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Mar 25 13:20:46 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 25 13:20:46 volumio volumio[1381]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Mar 25 13:20:46 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Mar 25 13:20:46 volumio volumio[1381]: info: Received Get System Version Mar 25 13:20:46 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 25 13:20:46 volumio volumio[1381]: info: Received Get System Info Mar 25 13:20:46 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 25 13:20:46 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 25 13:20:46 volumio volumio[1381]: info: Discovery: Getting this device information Mar 25 13:20:46 volumio volumio[1381]: info: CoreCommandRouter::volumioGetState Mar 25 13:20:46 volumio volumio[1381]: info: CorePlayQueue::getTrack 0 Mar 25 13:20:46 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 25 13:20:47 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Mar 25 13:20:48 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Mar 25 13:20:49 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Mar 25 13:20:49 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 25 13:20:49 volumio volumio[1381]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 13 Mar 25 13:20:49 volumio volumio[1381]: info: CoreCommandRouter::volumioGetState Mar 25 13:20:49 volumio volumio[1381]: info: CorePlayQueue::getTrack 0 Mar 25 13:20:50 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Mar 25 13:20:51 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 25 13:20:51 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 25 13:20:51 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 25 13:20:51 volumio volumio-remote-updater[962]: Test mode disabled Mar 25 13:20:51 volumio volumio-remote-updater[962]: Alpha mode disabled Mar 25 13:20:51 volumio volumio-remote-updater[962]: Alpha legacy test mode disabled Mar 25 13:20:51 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Mar 25 13:20:51 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Mar 25 13:20:51 volumio volumio[1381]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Mar 25 13:20:51 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Mar 25 13:20:52 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Mar 25 13:20:53 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Mar 25 13:20:53 volumio systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Mar 25 13:20:54 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Mar 25 13:20:54 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 13:20:54 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 13:20:54 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Mar 25 13:20:54 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Mar 25 13:20:54 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Mar 25 13:20:54 volumio volumio[1381]: info: CoreCommandRouter::volumioGetBrowseSources Mar 25 13:20:54 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 25 13:20:55 volumio systemd[1]: setdatetime-helper.service: Deactivated successfully. Mar 25 13:20:55 volumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Mar 25 13:20:55 volumio volumio[1381]: error: MyVolumio Plugin failed to authenticate in a timely fashion Mar 25 13:20:55 volumio volumio[1381]: info: Completed starting MyVolumio Plugin Mar 25 13:20:55 volumio volumio[1381]: [Metrics] CommandRouter: 36s 584.54ms Mar 25 13:20:55 volumio volumio[1381]: info: CoreCommandRouter::volumiosetStartupVolume Mar 25 13:20:55 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 13:20:55 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 25 13:20:55 volumio volumio[1381]: info: CoreCommandRouter::Close All Modals sent Mar 25 13:20:55 volumio volumio[1381]: info: CoreCommandRouter::Close All Modals sent Mar 25 13:20:55 volumio volumio[1381]: info: Cannot play startup sound: Error: Command failed: /usr/bin/aplay -D volumio /volumio/app/startup.wav Mar 25 13:20:55 volumio volumio[1381]: aplay: main:831: audio open error: Unknown error 524 Mar 25 13:20:55 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Mar 25 13:20:56 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Mar 25 13:20:56 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 25 13:20:56 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Mar 25 13:20:56 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Mar 25 13:20:57 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Mar 25 13:20:58 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Mar 25 13:20:59 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Mar 25 13:21:00 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Mar 25 13:21:01 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Mar 25 13:21:02 volumio volumio[1381]: info: BOOT COMPLETED Mar 25 13:21:02 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Mar 25 13:21:03 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Mar 25 13:21:04 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Mar 25 13:21:05 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Mar 25 13:21:06 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Mar 25 13:21:07 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Mar 25 13:21:08 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Mar 25 13:21:09 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Mar 25 13:21:10 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Mar 25 13:21:11 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Mar 25 13:21:12 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Mar 25 13:21:13 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Mar 25 13:21:14 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Mar 25 13:21:15 volumio volumio[1381]: info: CoreCommandRouter::volumioPlay Mar 25 13:21:15 volumio volumio[1381]: info: CoreStateMachine::play index undefined Mar 25 13:21:15 volumio volumio[1381]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 25 13:21:15 volumio volumio[1381]: info: CorePlayQueue::getTrack 0 Mar 25 13:21:15 volumio volumio[1381]: info: CoreStateMachine::startPlaybackTimer Mar 25 13:21:15 volumio volumio[1381]: info: CorePlayQueue::getTrack 0 Mar 25 13:21:15 volumio volumio[1381]: verbose: ControllerMpd::sendMpdCommand stop Mar 25 13:21:15 volumio volumio[1381]: info: sendMpdCommand stop took 1 milliseconds Mar 25 13:21:15 volumio volumio[1381]: verbose: ControllerMpd::sendMpdCommand clear Mar 25 13:21:15 volumio volumio[1381]: info: sendMpdCommand clear took 1 milliseconds Mar 25 13:21:15 volumio volumio[1381]: info: Mar 25 13:21:15 volumio volumio[1381]: ---------------------------- MPD announces system playlist update Mar 25 13:21:15 volumio volumio[1381]: info: Ignoring MPD Status Update Mar 25 13:21:15 volumio volumio[1381]: info: Mar 25 13:21:15 volumio volumio[1381]: ---------------------------- MPD announces system playlist update Mar 25 13:21:15 volumio volumio[1381]: info: Ignoring MPD Status Update Mar 25 13:21:15 volumio volumio[1381]: info: Mar 25 13:21:15 volumio volumio[1381]: ---------------------------- MPD announces system playlist update Mar 25 13:21:15 volumio volumio[1381]: info: Ignoring MPD Status Update Mar 25 13:21:15 volumio volumio[1381]: info: Mar 25 13:21:15 volumio volumio[1381]: ---------------------------- MPD announces system playlist update Mar 25 13:21:15 volumio volumio[1381]: info: Ignoring MPD Status Update Mar 25 13:21:15 volumio volumio[1381]: info: Mar 25 13:21:15 volumio volumio[1381]: ---------------------------- MPD announces system playlist update Mar 25 13:21:15 volumio volumio[1381]: info: Ignoring MPD Status Update Mar 25 13:21:15 volumio volumio[1381]: info: Mar 25 13:21:15 volumio volumio[1381]: ---------------------------- MPD announces system playlist update Mar 25 13:21:15 volumio volumio[1381]: info: Ignoring MPD Status Update Mar 25 13:21:15 volumio volumio[1381]: error: updateQueue error: null Mar 25 13:21:15 volumio volumio[1381]: error: updateQueue error: null Mar 25 13:21:15 volumio volumio[1381]: error: updateQueue error: null Mar 25 13:21:15 volumio volumio[1381]: error: updateQueue error: null Mar 25 13:21:15 volumio volumio[1381]: error: updateQueue error: null Mar 25 13:21:15 volumio volumio[1381]: info: ------------------------------ 4ms Mar 25 13:21:15 volumio volumio[1381]: info: ------------------------------ 3ms Mar 25 13:21:15 volumio volumio[1381]: info: ------------------------------ 2ms Mar 25 13:21:15 volumio volumio[1381]: info: ------------------------------ 2ms Mar 25 13:21:15 volumio volumio[1381]: info: ------------------------------ 2ms Mar 25 13:21:15 volumio volumio[1381]: error: updateQueue error: null Mar 25 13:21:15 volumio volumio[1381]: info: ------------------------------ 2ms Mar 25 13:21:15 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Mar 25 13:21:15 volumio volumio[1381]: verbose: ControllerMpd::sendMpdCommand addid "http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fext-strm-ruufa01ufn-02.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Dee048917b519e6e7f7088a47d3e29fa92c5ffc43c47f998677b4c47d059fdab5%2Clid%3D1629%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D69d18438%2F24711%2F9722105%2Fbf19b888.171990993.7.110173188%2F320.mp3&ext=.mp3" Mar 25 13:21:15 volumio volumio[1381]: info: sendMpdCommand addid "http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fext-strm-ruufa01ufn-02.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Dee048917b519e6e7f7088a47d3e29fa92c5ffc43c47f998677b4c47d059fdab5%2Clid%3D1629%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D69d18438%2F24711%2F9722105%2Fbf19b888.171990993.7.110173188%2F320.mp3&ext=.mp3" took 1 milliseconds Mar 25 13:21:15 volumio volumio[1381]: verbose: MPD COMMAND [object Object] Mar 25 13:21:15 volumio volumio[1381]: verbose: MPD COMMAND [object Object] Mar 25 13:21:15 volumio volumio[1381]: verbose: MPD COMMAND [object Object] Mar 25 13:21:15 volumio volumio[1381]: info: Mar 25 13:21:15 volumio volumio[1381]: ---------------------------- MPD announces system playlist update Mar 25 13:21:15 volumio volumio[1381]: info: Ignoring MPD Status Update Mar 25 13:21:15 volumio volumio[1381]: info: Mar 25 13:21:15 volumio volumio[1381]: ---------------------------- MPD announces system playlist update Mar 25 13:21:15 volumio volumio[1381]: info: Ignoring MPD Status Update Mar 25 13:21:15 volumio volumio[1381]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 25 13:21:15 volumio volumio[1381]: verbose: ControllerMpd::sendMpdCommand play Mar 25 13:21:15 volumio volumio[1381]: info: Mar 25 13:21:15 volumio volumio[1381]: ---------------------------- MPD announces system playlist update Mar 25 13:21:15 volumio volumio[1381]: info: Ignoring MPD Status Update Mar 25 13:21:15 volumio volumio[1381]: info: Mar 25 13:21:15 volumio volumio[1381]: ---------------------------- MPD announces system playlist update Mar 25 13:21:15 volumio volumio[1381]: info: Ignoring MPD Status Update Mar 25 13:21:15 volumio volumio[1381]: info: Mar 25 13:21:15 volumio volumio[1381]: ---------------------------- MPD announces system playlist update Mar 25 13:21:15 volumio volumio[1381]: info: Ignoring MPD Status Update Mar 25 13:21:15 volumio volumio[1381]: info: Mar 25 13:21:15 volumio volumio[1381]: ---------------------------- MPD announces system playlist update Mar 25 13:21:15 volumio volumio[1381]: info: Ignoring MPD Status Update Mar 25 13:21:15 volumio volumio[1381]: info: Mar 25 13:21:15 volumio volumio[1381]: ---------------------------- MPD announces system playlist update Mar 25 13:21:15 volumio volumio[1381]: info: Ignoring MPD Status Update Mar 25 13:21:15 volumio volumio[1381]: info: ------------------------------ 4ms Mar 25 13:21:15 volumio volumio[1381]: info: ------------------------------ 4ms Mar 25 13:21:15 volumio volumio[1381]: info: sendMpdCommand play took 4 milliseconds Mar 25 13:21:15 volumio volumio[1381]: info: ------------------------------ 3ms Mar 25 13:21:15 volumio volumio[1381]: info: ------------------------------ 3ms Mar 25 13:21:15 volumio volumio[1381]: info: ------------------------------ 2ms Mar 25 13:21:15 volumio volumio[1381]: info: ------------------------------ 2ms Mar 25 13:21:15 volumio volumio[1381]: info: ------------------------------ 2ms Mar 25 13:21:15 volumio volumio[1381]: info: Mar 25 13:21:15 volumio volumio[1381]: ---------------------------- MPD announces system playlist update Mar 25 13:21:15 volumio volumio[1381]: info: Ignoring MPD Status Update Mar 25 13:21:15 volumio volumio[1381]: info: Mar 25 13:21:15 volumio volumio[1381]: ---------------------------- MPD announces system playlist update Mar 25 13:21:15 volumio volumio[1381]: info: Ignoring MPD Status Update Mar 25 13:21:15 volumio volumio[1381]: info: Mar 25 13:21:15 volumio volumio[1381]: ---------------------------- MPD announces system playlist update Mar 25 13:21:15 volumio volumio[1381]: info: Ignoring MPD Status Update Mar 25 13:21:15 volumio volumio[1381]: info: Mar 25 13:21:15 volumio volumio[1381]: ---------------------------- MPD announces system playlist update Mar 25 13:21:15 volumio volumio[1381]: info: Ignoring MPD Status Update Mar 25 13:21:15 volumio volumio[1381]: info: Mar 25 13:21:15 volumio volumio[1381]: ---------------------------- MPD announces system playlist update Mar 25 13:21:15 volumio volumio[1381]: info: Ignoring MPD Status Update Mar 25 13:21:15 volumio volumio[1381]: info: ------------------------------ 2ms Mar 25 13:21:15 volumio volumio[1381]: info: ------------------------------ 2ms Mar 25 13:21:15 volumio volumio[1381]: info: ------------------------------ 1ms Mar 25 13:21:15 volumio volumio[1381]: info: ------------------------------ 1ms Mar 25 13:21:15 volumio volumio[1381]: info: ------------------------------ 2ms Mar 25 13:21:16 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Mar 25 13:21:17 volumio volumio[1381]: info: Mar 25 13:21:17 volumio volumio[1381]: ---------------------------- MPD announces state update: player Mar 25 13:21:17 volumio volumio[1381]: info: ControllerMpd::getState Mar 25 13:21:17 volumio volumio[1381]: verbose: ControllerMpd::sendMpdCommand status Mar 25 13:21:17 volumio volumio[1381]: info: Mar 25 13:21:17 volumio volumio[1381]: ---------------------------- MPD announces state update: player Mar 25 13:21:17 volumio volumio[1381]: info: ControllerMpd::getState Mar 25 13:21:17 volumio volumio[1381]: verbose: ControllerMpd::sendMpdCommand status Mar 25 13:21:17 volumio volumio[1381]: info: Mar 25 13:21:17 volumio volumio[1381]: ---------------------------- MPD announces state update: player Mar 25 13:21:17 volumio volumio[1381]: info: ControllerMpd::getState Mar 25 13:21:17 volumio volumio[1381]: verbose: ControllerMpd::sendMpdCommand status Mar 25 13:21:17 volumio volumio[1381]: info: Mar 25 13:21:17 volumio volumio[1381]: ---------------------------- MPD announces state update: player Mar 25 13:21:17 volumio volumio[1381]: info: ControllerMpd::getState Mar 25 13:21:17 volumio volumio[1381]: verbose: ControllerMpd::sendMpdCommand status Mar 25 13:21:17 volumio volumio[1381]: info: Mar 25 13:21:17 volumio volumio[1381]: ---------------------------- MPD announces state update: player Mar 25 13:21:17 volumio volumio[1381]: info: ControllerMpd::getState Mar 25 13:21:17 volumio volumio[1381]: verbose: ControllerMpd::sendMpdCommand status Mar 25 13:21:17 volumio volumio[1381]: info: Mar 25 13:21:17 volumio volumio[1381]: ---------------------------- MPD announces state update: player Mar 25 13:21:17 volumio volumio[1381]: info: ControllerMpd::getState Mar 25 13:21:17 volumio volumio[1381]: verbose: ControllerMpd::sendMpdCommand status Mar 25 13:21:17 volumio volumio[1381]: info: Mar 25 13:21:17 volumio volumio[1381]: ---------------------------- MPD announces state update: player Mar 25 13:21:17 volumio volumio[1381]: info: sendMpdCommand status took 21 milliseconds Mar 25 13:21:17 volumio volumio[1381]: info: ControllerMpd::getState Mar 25 13:21:17 volumio volumio[1381]: verbose: ControllerMpd::sendMpdCommand status Mar 25 13:21:17 volumio volumio[1381]: verbose: ControllerMpd::parseState Mar 25 13:21:17 volumio volumio[1381]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 25 13:21:17 volumio volumio[1381]: info: sendMpdCommand status took 22 milliseconds Mar 25 13:21:17 volumio volumio[1381]: info: sendMpdCommand status took 21 milliseconds Mar 25 13:21:17 volumio volumio[1381]: info: sendMpdCommand status took 20 milliseconds Mar 25 13:21:17 volumio volumio[1381]: info: sendMpdCommand status took 20 milliseconds Mar 25 13:21:17 volumio volumio[1381]: info: sendMpdCommand status took 19 milliseconds Mar 25 13:21:17 volumio volumio[1381]: info: sendMpdCommand status took 1 milliseconds Mar 25 13:21:17 volumio volumio[1381]: info: sendMpdCommand playlistinfo took 2 milliseconds Mar 25 13:21:17 volumio volumio[1381]: verbose: ControllerMpd::parseState Mar 25 13:21:17 volumio volumio[1381]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 25 13:21:17 volumio volumio[1381]: verbose: ControllerMpd::parseState Mar 25 13:21:17 volumio volumio[1381]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 25 13:21:17 volumio volumio[1381]: verbose: ControllerMpd::parseState Mar 25 13:21:17 volumio volumio[1381]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 25 13:21:17 volumio volumio[1381]: verbose: ControllerMpd::parseState Mar 25 13:21:17 volumio volumio[1381]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 25 13:21:17 volumio volumio[1381]: verbose: ControllerMpd::parseState Mar 25 13:21:17 volumio volumio[1381]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 25 13:21:17 volumio volumio[1381]: verbose: ControllerMpd::parseState Mar 25 13:21:17 volumio volumio[1381]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 25 13:21:17 volumio volumio[1381]: verbose: ControllerMpd::parseTrackInfo Mar 25 13:21:17 volumio volumio[1381]: info: ControllerMpd::pushState Mar 25 13:21:17 volumio volumio[1381]: info: CoreCommandRouter::servicePushState Mar 25 13:21:17 volumio volumio[1381]: info: CorePlayQueue::getTrack 0 Mar 25 13:21:17 volumio volumio[1381]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":156,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Kosmolove 2","artist":"Marcel de Van","album":"Magic Pearls","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fext-strm-ruufa01ufn-02.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Dee048917b519e6e7f7088a47d3e29fa92c5ffc43c47f998677b4c47d059fdab5%2Clid%3D1629%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D69d18438%2F24711%2F9722105%2Fbf19b888.171990993.7.110173188%2F320.mp3&ext=.mp3","trackType":"mp3"} Mar 25 13:21:17 volumio volumio[1381]: verbose: CURRENT POSITION 0 Mar 25 13:21:17 volumio volumio[1381]: info: CoreStateMachine::syncState stateService play Mar 25 13:21:17 volumio volumio[1381]: info: CoreStateMachine::syncState currentStatus stop Mar 25 13:21:17 volumio volumio[1381]: info: ------------------------------ 27ms Mar 25 13:21:17 volumio volumio[1381]: info: Mar 25 13:21:17 volumio volumio[1381]: ---------------------------- MPD announces state update: player Mar 25 13:21:17 volumio volumio[1381]: info: ControllerMpd::getState Mar 25 13:21:17 volumio volumio[1381]: verbose: ControllerMpd::sendMpdCommand status Mar 25 13:21:17 volumio volumio[1381]: info: Mar 25 13:21:17 volumio volumio[1381]: ---------------------------- MPD announces state update: player Mar 25 13:21:17 volumio volumio[1381]: info: ControllerMpd::getState Mar 25 13:21:17 volumio volumio[1381]: verbose: ControllerMpd::sendMpdCommand status Mar 25 13:21:17 volumio volumio[1381]: info: Mar 25 13:21:17 volumio volumio[1381]: ---------------------------- MPD announces state update: player Mar 25 13:21:17 volumio volumio[1381]: info: ControllerMpd::getState Mar 25 13:21:17 volumio volumio[1381]: verbose: ControllerMpd::sendMpdCommand status Mar 25 13:21:17 volumio volumio[1381]: info: Mar 25 13:21:17 volumio volumio[1381]: ---------------------------- MPD announces state update: player Mar 25 13:21:17 volumio volumio[1381]: info: ControllerMpd::getState Mar 25 13:21:17 volumio volumio[1381]: verbose: ControllerMpd::sendMpdCommand status Mar 25 13:21:17 volumio volumio[1381]: info: Mar 25 13:21:17 volumio volumio[1381]: ---------------------------- MPD announces state update: player Mar 25 13:21:17 volumio volumio[1381]: info: ControllerMpd::getState Mar 25 13:21:17 volumio volumio[1381]: verbose: ControllerMpd::sendMpdCommand status Mar 25 13:21:17 volumio volumio[1381]: info: sendMpdCommand playlistinfo took 6 milliseconds Mar 25 13:21:17 volumio volumio[1381]: info: sendMpdCommand playlistinfo took 6 milliseconds Mar 25 13:21:17 volumio volumio[1381]: info: sendMpdCommand playlistinfo took 6 milliseconds Mar 25 13:21:17 volumio volumio[1381]: info: sendMpdCommand playlistinfo took 6 milliseconds Mar 25 13:21:17 volumio volumio[1381]: info: sendMpdCommand playlistinfo took 6 milliseconds Mar 25 13:21:17 volumio volumio[1381]: info: sendMpdCommand playlistinfo took 6 milliseconds Mar 25 13:21:17 volumio volumio[1381]: info: sendMpdCommand status took 3 milliseconds Mar 25 13:21:17 volumio volumio[1381]: info: sendMpdCommand status took 3 milliseconds Mar 25 13:21:17 volumio volumio[1381]: info: sendMpdCommand status took 3 milliseconds Mar 25 13:21:17 volumio volumio[1381]: info: sendMpdCommand status took 2 milliseconds Mar 25 13:21:17 volumio volumio[1381]: info: sendMpdCommand status took 2 milliseconds Mar 25 13:21:17 volumio volumio[1381]: verbose: ControllerMpd::parseTrackInfo Mar 25 13:21:17 volumio volumio[1381]: verbose: ControllerMpd::parseTrackInfo Mar 25 13:21:17 volumio volumio[1381]: verbose: ControllerMpd::parseTrackInfo Mar 25 13:21:17 volumio volumio[1381]: verbose: ControllerMpd::parseTrackInfo Mar 25 13:21:17 volumio volumio[1381]: verbose: ControllerMpd::parseTrackInfo Mar 25 13:21:17 volumio volumio[1381]: verbose: ControllerMpd::parseTrackInfo Mar 25 13:21:17 volumio volumio[1381]: verbose: ControllerMpd::parseState Mar 25 13:21:17 volumio volumio[1381]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 25 13:21:17 volumio volumio[1381]: verbose: ControllerMpd::parseState Mar 25 13:21:17 volumio volumio[1381]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 25 13:21:17 volumio volumio[1381]: verbose: ControllerMpd::parseState Mar 25 13:21:17 volumio volumio[1381]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 25 13:21:17 volumio volumio[1381]: verbose: ControllerMpd::parseState Mar 25 13:21:17 volumio volumio[1381]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 25 13:21:17 volumio volumio[1381]: verbose: ControllerMpd::parseState Mar 25 13:21:17 volumio volumio[1381]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 25 13:21:17 volumio volumio[1381]: info: ControllerMpd::pushState Mar 25 13:21:17 volumio volumio[1381]: info: CoreCommandRouter::servicePushState Mar 25 13:21:17 volumio volumio[1381]: info: CorePlayQueue::getTrack 0 Mar 25 13:21:17 volumio volumio[1381]: verbose: STATE SERVICE {"status":"play","position":0,"seek":747,"duration":156,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Kosmolove 2","artist":"Marcel de Van","album":"Magic Pearls","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fext-strm-ruufa01ufn-02.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Dee048917b519e6e7f7088a47d3e29fa92c5ffc43c47f998677b4c47d059fdab5%2Clid%3D1629%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D69d18438%2F24711%2F9722105%2Fbf19b888.171990993.7.110173188%2F320.mp3&ext=.mp3","trackType":"mp3"} Mar 25 13:21:17 volumio volumio[1381]: verbose: CURRENT POSITION 0 Mar 25 13:21:17 volumio volumio[1381]: info: CoreStateMachine::syncState stateService play Mar 25 13:21:17 volumio volumio[1381]: info: CoreStateMachine::syncState currentStatus play Mar 25 13:21:17 volumio volumio[1381]: info: Received an update from plugin. extracting info from payload Mar 25 13:21:17 volumio volumio[1381]: info: CoreStateMachine::pushState Mar 25 13:21:17 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 25 13:21:17 volumio volumio[1381]: info: CoreCommandRouter::volumioPushState Mar 25 13:21:17 volumio volumio[1381]: info: CoreStateMachine::pushState Mar 25 13:21:17 volumio volumio[1381]: info: CoreCommandRouter::volumioPushState Mar 25 13:21:17 volumio volumio[1381]: info: ControllerMpd::pushState Mar 25 13:21:17 volumio volumio[1381]: info: CoreCommandRouter::servicePushState Mar 25 13:21:17 volumio volumio[1381]: info: CorePlayQueue::getTrack 0 Mar 25 13:21:17 volumio volumio[1381]: verbose: STATE SERVICE {"status":"play","position":0,"seek":747,"duration":156,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Kosmolove 2","artist":"Marcel de Van","album":"Magic Pearls","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fext-strm-ruufa01ufn-02.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Dee048917b519e6e7f7088a47d3e29fa92c5ffc43c47f998677b4c47d059fdab5%2Clid%3D1629%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D69d18438%2F24711%2F9722105%2Fbf19b888.171990993.7.110173188%2F320.mp3&ext=.mp3","trackType":"mp3"} Mar 25 13:21:17 volumio volumio[1381]: verbose: CURRENT POSITION 0 Mar 25 13:21:17 volumio volumio[1381]: info: CoreStateMachine::syncState stateService play Mar 25 13:21:17 volumio volumio[1381]: info: CoreStateMachine::syncState currentStatus play Mar 25 13:21:17 volumio volumio[1381]: info: Received an update from plugin. extracting info from payload Mar 25 13:21:17 volumio volumio[1381]: info: CoreStateMachine::pushState Mar 25 13:21:17 volumio volumio[1381]: info: CoreCommandRouter::volumioPushState Mar 25 13:21:17 volumio volumio[1381]: info: CoreStateMachine::pushState Mar 25 13:21:17 volumio volumio[1381]: info: CoreCommandRouter::volumioPushState Mar 25 13:21:17 volumio volumio[1381]: info: ControllerMpd::pushState Mar 25 13:21:17 volumio volumio[1381]: info: CoreCommandRouter::servicePushState Mar 25 13:21:17 volumio volumio[1381]: info: CorePlayQueue::getTrack 0 Mar 25 13:21:17 volumio volumio[1381]: verbose: STATE SERVICE {"status":"play","position":0,"seek":747,"duration":156,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Kosmolove 2","artist":"Marcel de Van","album":"Magic Pearls","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fext-strm-ruufa01ufn-02.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Dee048917b519e6e7f7088a47d3e29fa92c5ffc43c47f998677b4c47d059fdab5%2Clid%3D1629%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D69d18438%2F24711%2F9722105%2Fbf19b888.171990993.7.110173188%2F320.mp3&ext=.mp3","trackType":"mp3"} Mar 25 13:21:17 volumio volumio[1381]: verbose: CURRENT POSITION 0 Mar 25 13:21:17 volumio volumio[1381]: info: CoreStateMachine::syncState stateService play Mar 25 13:21:17 volumio volumio[1381]: info: CoreStateMachine::syncState currentStatus play Mar 25 13:21:17 volumio volumio[1381]: info: Received an update from plugin. extracting info from payload Mar 25 13:21:17 volumio volumio[1381]: info: CoreStateMachine::pushState Mar 25 13:21:17 volumio volumio[1381]: info: CoreCommandRouter::volumioPushState Mar 25 13:21:17 volumio volumio[1381]: info: CoreStateMachine::pushState Mar 25 13:21:17 volumio volumio[1381]: info: CoreCommandRouter::volumioPushState Mar 25 13:21:17 volumio volumio[1381]: info: ControllerMpd::pushState Mar 25 13:21:17 volumio volumio[1381]: info: CoreCommandRouter::servicePushState Mar 25 13:21:17 volumio volumio[1381]: info: CorePlayQueue::getTrack 0 Mar 25 13:21:17 volumio volumio[1381]: verbose: STATE SERVICE {"status":"play","position":0,"seek":747,"duration":156,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Kosmolove 2","artist":"Marcel de Van","album":"Magic Pearls","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fext-strm-ruufa01ufn-02.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Dee048917b519e6e7f7088a47d3e29fa92c5ffc43c47f998677b4c47d059fdab5%2Clid%3D1629%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D69d18438%2F24711%2F9722105%2Fbf19b888.171990993.7.110173188%2F320.mp3&ext=.mp3","trackType":"mp3"} Mar 25 13:21:17 volumio volumio[1381]: verbose: CURRENT POSITION 0 Mar 25 13:21:17 volumio volumio[1381]: info: CoreStateMachine::syncState stateService play Mar 25 13:21:17 volumio volumio[1381]: info: CoreStateMachine::syncState currentStatus play Mar 25 13:21:17 volumio volumio[1381]: info: Received an update from plugin. extracting info from payload Mar 25 13:21:17 volumio volumio[1381]: info: CoreStateMachine::pushState Mar 25 13:21:17 volumio volumio[1381]: info: CoreCommandRouter::volumioPushState Mar 25 13:21:17 volumio volumio[1381]: info: CoreStateMachine::pushState Mar 25 13:21:17 volumio volumio[1381]: info: CoreCommandRouter::volumioPushState Mar 25 13:21:17 volumio volumio[1381]: info: ControllerMpd::pushState Mar 25 13:21:17 volumio volumio[1381]: info: CoreCommandRouter::servicePushState Mar 25 13:21:17 volumio volumio[1381]: info: CorePlayQueue::getTrack 0 Mar 25 13:21:17 volumio volumio[1381]: verbose: STATE SERVICE {"status":"play","position":0,"seek":747,"duration":156,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Kosmolove 2","artist":"Marcel de Van","album":"Magic Pearls","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fext-strm-ruufa01ufn-02.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Dee048917b519e6e7f7088a47d3e29fa92c5ffc43c47f998677b4c47d059fdab5%2Clid%3D1629%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D69d18438%2F24711%2F9722105%2Fbf19b888.171990993.7.110173188%2F320.mp3&ext=.mp3","trackType":"mp3"} Mar 25 13:21:17 volumio volumio[1381]: verbose: CURRENT POSITION 0 Mar 25 13:21:17 volumio volumio[1381]: info: CoreStateMachine::syncState stateService play Mar 25 13:21:17 volumio volumio[1381]: info: CoreStateMachine::syncState currentStatus play Mar 25 13:21:17 volumio volumio[1381]: info: Received an update from plugin. extracting info from payload Mar 25 13:21:17 volumio volumio[1381]: info: CoreStateMachine::pushState Mar 25 13:21:17 volumio volumio[1381]: info: CoreCommandRouter::volumioPushState Mar 25 13:21:17 volumio volumio[1381]: info: CoreStateMachine::pushState Mar 25 13:21:17 volumio volumio[1381]: info: CoreCommandRouter::volumioPushState Mar 25 13:21:17 volumio volumio[1381]: info: ControllerMpd::pushState Mar 25 13:21:17 volumio volumio[1381]: info: CoreCommandRouter::servicePushState Mar 25 13:21:17 volumio volumio[1381]: info: CorePlayQueue::getTrack 0 Mar 25 13:21:17 volumio volumio[1381]: verbose: STATE SERVICE {"status":"play","position":0,"seek":747,"duration":156,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Kosmolove 2","artist":"Marcel de Van","album":"Magic Pearls","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fext-strm-ruufa01ufn-02.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Dee048917b519e6e7f7088a47d3e29fa92c5ffc43c47f998677b4c47d059fdab5%2Clid%3D1629%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D69d18438%2F24711%2F9722105%2Fbf19b888.171990993.7.110173188%2F320.mp3&ext=.mp3","trackType":"mp3"} Mar 25 13:21:17 volumio volumio[1381]: verbose: CURRENT POSITION 0 Mar 25 13:21:17 volumio volumio[1381]: info: CoreStateMachine::syncState stateService play Mar 25 13:21:17 volumio volumio[1381]: info: CoreStateMachine::syncState currentStatus play Mar 25 13:21:17 volumio volumio[1381]: info: Received an update from plugin. extracting info from payload Mar 25 13:21:17 volumio volumio[1381]: info: CoreStateMachine::pushState Mar 25 13:21:17 volumio volumio[1381]: info: CoreCommandRouter::volumioPushState Mar 25 13:21:17 volumio volumio[1381]: info: CoreStateMachine::pushState Mar 25 13:21:17 volumio volumio[1381]: info: CoreCommandRouter::volumioPushState Mar 25 13:21:17 volumio volumio[1381]: info: ------------------------------ 108ms Mar 25 13:21:17 volumio volumio[1381]: info: ------------------------------ 108ms Mar 25 13:21:17 volumio volumio[1381]: info: ------------------------------ 108ms Mar 25 13:21:17 volumio volumio[1381]: info: ------------------------------ 108ms Mar 25 13:21:17 volumio volumio[1381]: info: ------------------------------ 107ms Mar 25 13:21:17 volumio volumio[1381]: info: ------------------------------ 91ms Mar 25 13:21:17 volumio volumio[1381]: info: sendMpdCommand playlistinfo took 79 milliseconds Mar 25 13:21:17 volumio volumio[1381]: info: sendMpdCommand playlistinfo took 79 milliseconds Mar 25 13:21:17 volumio volumio[1381]: info: sendMpdCommand playlistinfo took 79 milliseconds Mar 25 13:21:17 volumio volumio[1381]: info: sendMpdCommand playlistinfo took 79 milliseconds Mar 25 13:21:17 volumio volumio[1381]: info: sendMpdCommand playlistinfo took 79 milliseconds Mar 25 13:21:17 volumio volumio[1381]: verbose: ControllerMpd::parseTrackInfo Mar 25 13:21:17 volumio volumio[1381]: verbose: ControllerMpd::parseTrackInfo Mar 25 13:21:17 volumio volumio[1381]: verbose: ControllerMpd::parseTrackInfo Mar 25 13:21:17 volumio volumio[1381]: verbose: ControllerMpd::parseTrackInfo Mar 25 13:21:17 volumio volumio[1381]: verbose: ControllerMpd::parseTrackInfo Mar 25 13:21:17 volumio volumio[1381]: info: ControllerMpd::pushState Mar 25 13:21:17 volumio volumio[1381]: info: CoreCommandRouter::servicePushState Mar 25 13:21:17 volumio volumio[1381]: info: CorePlayQueue::getTrack 0 Mar 25 13:21:17 volumio volumio[1381]: verbose: STATE SERVICE {"status":"play","position":0,"seek":747,"duration":156,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Kosmolove 2","artist":"Marcel de Van","album":"Magic Pearls","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fext-strm-ruufa01ufn-02.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Dee048917b519e6e7f7088a47d3e29fa92c5ffc43c47f998677b4c47d059fdab5%2Clid%3D1629%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D69d18438%2F24711%2F9722105%2Fbf19b888.171990993.7.110173188%2F320.mp3&ext=.mp3","trackType":"mp3"} Mar 25 13:21:17 volumio volumio[1381]: verbose: CURRENT POSITION 0 Mar 25 13:21:17 volumio volumio[1381]: info: CoreStateMachine::syncState stateService play Mar 25 13:21:17 volumio volumio[1381]: info: CoreStateMachine::syncState currentStatus play Mar 25 13:21:17 volumio volumio[1381]: info: Received an update from plugin. extracting info from payload Mar 25 13:21:17 volumio volumio[1381]: info: CoreStateMachine::pushState Mar 25 13:21:17 volumio volumio[1381]: info: CoreCommandRouter::volumioPushState Mar 25 13:21:17 volumio volumio[1381]: info: CoreStateMachine::pushState Mar 25 13:21:17 volumio volumio[1381]: info: CoreCommandRouter::volumioPushState Mar 25 13:21:17 volumio volumio[1381]: info: ControllerMpd::pushState Mar 25 13:21:17 volumio volumio[1381]: info: CoreCommandRouter::servicePushState Mar 25 13:21:17 volumio volumio[1381]: info: CorePlayQueue::getTrack 0 Mar 25 13:21:17 volumio volumio[1381]: verbose: STATE SERVICE {"status":"play","position":0,"seek":747,"duration":156,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Kosmolove 2","artist":"Marcel de Van","album":"Magic Pearls","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fext-strm-ruufa01ufn-02.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Dee048917b519e6e7f7088a47d3e29fa92c5ffc43c47f998677b4c47d059fdab5%2Clid%3D1629%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D69d18438%2F24711%2F9722105%2Fbf19b888.171990993.7.110173188%2F320.mp3&ext=.mp3","trackType":"mp3"} Mar 25 13:21:17 volumio volumio[1381]: verbose: CURRENT POSITION 0 Mar 25 13:21:17 volumio volumio[1381]: info: CoreStateMachine::syncState stateService play Mar 25 13:21:17 volumio volumio[1381]: info: CoreStateMachine::syncState currentStatus play Mar 25 13:21:17 volumio volumio[1381]: info: Received an update from plugin. extracting info from payload Mar 25 13:21:17 volumio volumio[1381]: info: CoreStateMachine::pushState Mar 25 13:21:17 volumio volumio[1381]: info: CoreCommandRouter::volumioPushState Mar 25 13:21:17 volumio volumio[1381]: info: CoreStateMachine::pushState Mar 25 13:21:17 volumio volumio[1381]: info: CoreCommandRouter::volumioPushState Mar 25 13:21:17 volumio volumio[1381]: info: ControllerMpd::pushState Mar 25 13:21:17 volumio volumio[1381]: info: CoreCommandRouter::servicePushState Mar 25 13:21:17 volumio volumio[1381]: info: CorePlayQueue::getTrack 0 Mar 25 13:21:17 volumio volumio[1381]: verbose: STATE SERVICE {"status":"play","position":0,"seek":747,"duration":156,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Kosmolove 2","artist":"Marcel de Van","album":"Magic Pearls","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fext-strm-ruufa01ufn-02.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Dee048917b519e6e7f7088a47d3e29fa92c5ffc43c47f998677b4c47d059fdab5%2Clid%3D1629%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D69d18438%2F24711%2F9722105%2Fbf19b888.171990993.7.110173188%2F320.mp3&ext=.mp3","trackType":"mp3"} Mar 25 13:21:17 volumio volumio[1381]: verbose: CURRENT POSITION 0 Mar 25 13:21:17 volumio volumio[1381]: info: CoreStateMachine::syncState stateService play Mar 25 13:21:17 volumio volumio[1381]: info: CoreStateMachine::syncState currentStatus play Mar 25 13:21:17 volumio volumio[1381]: info: Received an update from plugin. extracting info from payload Mar 25 13:21:17 volumio volumio[1381]: info: CoreStateMachine::pushState Mar 25 13:21:17 volumio volumio[1381]: info: CoreCommandRouter::volumioPushState Mar 25 13:21:17 volumio volumio[1381]: info: CoreStateMachine::pushState Mar 25 13:21:17 volumio volumio[1381]: info: CoreCommandRouter::volumioPushState Mar 25 13:21:17 volumio volumio[1381]: info: ControllerMpd::pushState Mar 25 13:21:17 volumio volumio[1381]: info: CoreCommandRouter::servicePushState Mar 25 13:21:17 volumio volumio[1381]: info: CorePlayQueue::getTrack 0 Mar 25 13:21:17 volumio volumio[1381]: verbose: STATE SERVICE {"status":"play","position":0,"seek":747,"duration":156,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Kosmolove 2","artist":"Marcel de Van","album":"Magic Pearls","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fext-strm-ruufa01ufn-02.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Dee048917b519e6e7f7088a47d3e29fa92c5ffc43c47f998677b4c47d059fdab5%2Clid%3D1629%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D69d18438%2F24711%2F9722105%2Fbf19b888.171990993.7.110173188%2F320.mp3&ext=.mp3","trackType":"mp3"} Mar 25 13:21:17 volumio volumio[1381]: verbose: CURRENT POSITION 0 Mar 25 13:21:17 volumio volumio[1381]: info: CoreStateMachine::syncState stateService play Mar 25 13:21:17 volumio volumio[1381]: info: CoreStateMachine::syncState currentStatus play Mar 25 13:21:17 volumio volumio[1381]: info: Received an update from plugin. extracting info from payload Mar 25 13:21:17 volumio volumio[1381]: info: CoreStateMachine::pushState Mar 25 13:21:17 volumio volumio[1381]: info: CoreCommandRouter::volumioPushState Mar 25 13:21:17 volumio volumio[1381]: info: CoreStateMachine::pushState Mar 25 13:21:17 volumio volumio[1381]: info: CoreCommandRouter::volumioPushState Mar 25 13:21:17 volumio volumio[1381]: info: ControllerMpd::pushState Mar 25 13:21:17 volumio volumio[1381]: info: CoreCommandRouter::servicePushState Mar 25 13:21:17 volumio volumio[1381]: info: CorePlayQueue::getTrack 0 Mar 25 13:21:17 volumio volumio[1381]: verbose: STATE SERVICE {"status":"play","position":0,"seek":747,"duration":156,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Kosmolove 2","artist":"Marcel de Van","album":"Magic Pearls","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fext-strm-ruufa01ufn-02.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Dee048917b519e6e7f7088a47d3e29fa92c5ffc43c47f998677b4c47d059fdab5%2Clid%3D1629%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D69d18438%2F24711%2F9722105%2Fbf19b888.171990993.7.110173188%2F320.mp3&ext=.mp3","trackType":"mp3"} Mar 25 13:21:17 volumio volumio[1381]: verbose: CURRENT POSITION 0 Mar 25 13:21:17 volumio volumio[1381]: info: CoreStateMachine::syncState stateService play Mar 25 13:21:17 volumio volumio[1381]: info: CoreStateMachine::syncState currentStatus play Mar 25 13:21:17 volumio volumio[1381]: info: Received an update from plugin. extracting info from payload Mar 25 13:21:17 volumio volumio[1381]: info: CoreStateMachine::pushState Mar 25 13:21:17 volumio volumio[1381]: info: CoreCommandRouter::volumioPushState Mar 25 13:21:17 volumio volumio[1381]: info: CoreStateMachine::pushState Mar 25 13:21:17 volumio volumio[1381]: info: CoreCommandRouter::volumioPushState Mar 25 13:21:17 volumio volumio[1381]: info: ------------------------------ 148ms Mar 25 13:21:17 volumio volumio[1381]: info: ------------------------------ 148ms Mar 25 13:21:17 volumio volumio[1381]: info: ------------------------------ 147ms Mar 25 13:21:17 volumio volumio[1381]: info: ------------------------------ 148ms Mar 25 13:21:17 volumio volumio[1381]: info: ------------------------------ 147ms Mar 25 13:21:17 volumio volumio[1381]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false Mar 25 13:21:17 volumio volumio[1381]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false Mar 25 13:21:17 volumio volumio[1381]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false Mar 25 13:21:17 volumio volumio[1381]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false Mar 25 13:21:17 volumio volumio[1381]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false Mar 25 13:21:17 volumio volumio[1381]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false Mar 25 13:21:17 volumio volumio[1381]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false Mar 25 13:21:17 volumio volumio[1381]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false Mar 25 13:21:17 volumio volumio[1381]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false Mar 25 13:21:17 volumio volumio[1381]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false Mar 25 13:21:17 volumio volumio[1381]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false Mar 25 13:21:17 volumio volumio[1381]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false Mar 25 13:21:17 volumio volumio[1381]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false Mar 25 13:21:17 volumio volumio[1381]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false Mar 25 13:21:17 volumio volumio[1381]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false Mar 25 13:21:17 volumio volumio[1381]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false Mar 25 13:21:17 volumio volumio[1381]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false Mar 25 13:21:17 volumio volumio[1381]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false Mar 25 13:21:17 volumio volumio[1381]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false Mar 25 13:21:17 volumio volumio[1381]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false Mar 25 13:21:17 volumio volumio[1381]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false Mar 25 13:21:17 volumio volumio[1381]: info: peppy_screensaver: pushState - status=play service=mpd volatile=false Mar 25 13:21:17 volumio volumio[1381]: info: CoreCommandRouter::volumioGetQueue Mar 25 13:21:17 volumio volumio[1381]: info: CoreStateMachine::getQueue Mar 25 13:21:17 volumio volumio[1381]: info: CorePlayQueue::getQueue Mar 25 13:21:17 volumio volumio[1381]: info: CoreCommandRouter::volumioGetQueue Mar 25 13:21:17 volumio volumio[1381]: info: CoreStateMachine::getQueue Mar 25 13:21:17 volumio volumio[1381]: info: CorePlayQueue::getQueue Mar 25 13:21:17 volumio volumio[1381]: info: CoreCommandRouter::volumioGetQueue Mar 25 13:21:17 volumio volumio[1381]: info: CoreStateMachine::getQueue Mar 25 13:21:17 volumio volumio[1381]: info: CorePlayQueue::getQueue Mar 25 13:21:17 volumio volumio[1381]: info: CoreCommandRouter::volumioGetQueue Mar 25 13:21:17 volumio volumio[1381]: info: CoreStateMachine::getQueue Mar 25 13:21:17 volumio volumio[1381]: info: CorePlayQueue::getQueue Mar 25 13:21:17 volumio volumio[1381]: info: CoreCommandRouter::volumioGetQueue Mar 25 13:21:17 volumio volumio[1381]: info: CoreStateMachine::getQueue Mar 25 13:21:17 volumio volumio[1381]: info: CorePlayQueue::getQueue Mar 25 13:21:17 volumio volumio[1381]: info: CoreCommandRouter::volumioGetQueue Mar 25 13:21:17 volumio volumio[1381]: info: CoreStateMachine::getQueue Mar 25 13:21:17 volumio volumio[1381]: info: CorePlayQueue::getQueue Mar 25 13:21:17 volumio volumio[1381]: info: CoreCommandRouter::volumioGetQueue Mar 25 13:21:17 volumio volumio[1381]: info: CoreStateMachine::getQueue Mar 25 13:21:17 volumio volumio[1381]: info: CorePlayQueue::getQueue Mar 25 13:21:17 volumio volumio[1381]: info: CoreCommandRouter::volumioGetQueue Mar 25 13:21:17 volumio volumio[1381]: info: CoreStateMachine::getQueue Mar 25 13:21:17 volumio volumio[1381]: info: CorePlayQueue::getQueue Mar 25 13:21:17 volumio volumio[1381]: info: CoreCommandRouter::volumioGetQueue Mar 25 13:21:17 volumio volumio[1381]: info: CoreStateMachine::getQueue Mar 25 13:21:17 volumio volumio[1381]: info: CorePlayQueue::getQueue Mar 25 13:21:17 volumio volumio[1381]: info: CoreCommandRouter::volumioGetQueue Mar 25 13:21:17 volumio volumio[1381]: info: CoreStateMachine::getQueue Mar 25 13:21:17 volumio volumio[1381]: info: CorePlayQueue::getQueue Mar 25 13:21:17 volumio volumio[1381]: info: CoreCommandRouter::volumioGetQueue Mar 25 13:21:17 volumio volumio[1381]: info: CoreStateMachine::getQueue Mar 25 13:21:17 volumio volumio[1381]: info: CorePlayQueue::getQueue Mar 25 13:21:17 volumio volumio[1381]: info: CoreCommandRouter::volumioGetQueue Mar 25 13:21:17 volumio volumio[1381]: info: CoreStateMachine::getQueue Mar 25 13:21:17 volumio volumio[1381]: info: CorePlayQueue::getQueue Mar 25 13:21:17 volumio volumio[1381]: info: CoreCommandRouter::volumioGetQueue Mar 25 13:21:17 volumio volumio[1381]: info: CoreStateMachine::getQueue Mar 25 13:21:17 volumio volumio[1381]: info: CorePlayQueue::getQueue Mar 25 13:21:17 volumio volumio[1381]: info: CoreCommandRouter::volumioGetQueue Mar 25 13:21:17 volumio volumio[1381]: info: CoreStateMachine::getQueue Mar 25 13:21:17 volumio volumio[1381]: info: CorePlayQueue::getQueue Mar 25 13:21:17 volumio volumio[1381]: info: CoreCommandRouter::volumioGetQueue Mar 25 13:21:17 volumio volumio[1381]: info: CoreStateMachine::getQueue Mar 25 13:21:17 volumio volumio[1381]: info: CorePlayQueue::getQueue Mar 25 13:21:17 volumio volumio[1381]: info: CoreCommandRouter::volumioGetQueue Mar 25 13:21:17 volumio volumio[1381]: info: CoreStateMachine::getQueue Mar 25 13:21:17 volumio volumio[1381]: info: CorePlayQueue::getQueue Mar 25 13:21:17 volumio volumio[1381]: info: CoreCommandRouter::volumioGetQueue Mar 25 13:21:17 volumio volumio[1381]: info: CoreStateMachine::getQueue Mar 25 13:21:17 volumio volumio[1381]: info: CorePlayQueue::getQueue Mar 25 13:21:17 volumio volumio[1381]: info: CoreCommandRouter::volumioGetQueue Mar 25 13:21:17 volumio volumio[1381]: info: CoreStateMachine::getQueue Mar 25 13:21:17 volumio volumio[1381]: info: CorePlayQueue::getQueue Mar 25 13:21:17 volumio volumio[1381]: info: CoreCommandRouter::volumioGetQueue Mar 25 13:21:17 volumio volumio[1381]: info: CoreStateMachine::getQueue Mar 25 13:21:17 volumio volumio[1381]: info: CorePlayQueue::getQueue Mar 25 13:21:17 volumio volumio[1381]: info: CoreCommandRouter::volumioGetQueue Mar 25 13:21:17 volumio volumio[1381]: info: CoreStateMachine::getQueue Mar 25 13:21:17 volumio volumio[1381]: info: CorePlayQueue::getQueue Mar 25 13:21:17 volumio volumio[1381]: info: CoreCommandRouter::volumioGetQueue Mar 25 13:21:17 volumio volumio[1381]: info: CoreStateMachine::getQueue Mar 25 13:21:17 volumio volumio[1381]: info: CorePlayQueue::getQueue Mar 25 13:21:17 volumio volumio[1381]: info: CoreCommandRouter::volumioGetQueue Mar 25 13:21:17 volumio volumio[1381]: info: CoreStateMachine::getQueue Mar 25 13:21:17 volumio volumio[1381]: info: CorePlayQueue::getQueue Mar 25 13:21:18 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Mar 25 13:21:19 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Mar 25 13:21:19 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Mar 25 13:21:21 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Mar 25 13:21:22 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Mar 25 13:21:22 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Mar 25 13:21:23 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Mar 25 13:21:24 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Mar 25 13:21:25 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Mar 25 13:21:26 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Mar 25 13:21:27 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Mar 25 13:21:28 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Mar 25 13:21:29 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Mar 25 13:21:30 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 25 13:21:30 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 25 13:21:30 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Mar 25 13:21:31 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Mar 25 13:21:32 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Mar 25 13:21:33 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Mar 25 13:21:34 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Mar 25 13:21:35 volumio volumio[1381]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 25 13:21:35 volumio volumio[1381]: Error: read ECONNRESET Mar 25 13:21:35 volumio volumio[1381]: at TLSWrap.onStreamRead (node:internal/stream_base_commons:217:20) { Mar 25 13:21:35 volumio volumio[1381]: errno: -104, Mar 25 13:21:35 volumio volumio[1381]: code: 'ECONNRESET', Mar 25 13:21:35 volumio volumio[1381]: syscall: 'read' Mar 25 13:21:35 volumio volumio[1381]: } Mar 25 13:21:35 volumio volumio[1381]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 25 13:21:35 volumio sudo[2461]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-03-25 13:20' Mar 25 13:21:35 volumio sudo[2461]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm 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="dc61260dec5515dafd2b634881860b4c46c919ff" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Mar 6 16:46:58 UTC 2026" VOLUMIO_VERSION="4.103" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="aa741395b8bfc648ff5d04e312f53d2c"