-- Logs begin at Sat 2024-07-13 07:42:23 CEST, end at Sat 2024-07-13 07:44:41 CEST. -- Jul 13 07:43:00 volumiocentral volumio[916]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Jul 13 07:43:01 volumiocentral volumio[916]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jul 13 07:43:01 volumiocentral volumio[916]: info: Getting Spotify volume Jul 13 07:43:01 volumiocentral volumio[916]: info: Spotify volume: 100 Jul 13 07:43:01 volumiocentral volumio[916]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Jul 13 07:43:01 volumiocentral volumio[916]: info: CoreCommandRouter::volumioGetState Jul 13 07:43:01 volumiocentral volumio[916]: info: CorePlayQueue::getTrack 0 Jul 13 07:43:01 volumiocentral volumio[916]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Jul 13 07:43:01 volumiocentral volumio[916]: SPOTIFY: SPOTIFY VOLUME 100 Jul 13 07:43:01 volumiocentral volumio[916]: SPOTIFY: VOLUMIO VOLUME 41 Jul 13 07:43:01 volumiocentral volumio[916]: SPOTIFY: DELTA VOLUME ENOUGH: true Jul 13 07:43:01 volumiocentral volumio[916]: info: Setting Spotify Volume from Volumio: 41 Jul 13 07:43:01 volumiocentral volumio[916]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 13 07:43:01 volumiocentral volumio[916]: info: Received Get System Info Jul 13 07:43:01 volumiocentral volumio[916]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 13 07:43:01 volumiocentral volumio[916]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 13 07:43:01 volumiocentral volumio[916]: info: Discovery: Getting this device information Jul 13 07:43:01 volumiocentral volumio[916]: info: CoreCommandRouter::volumioGetState Jul 13 07:43:01 volumiocentral volumio[916]: info: CorePlayQueue::getTrack 0 Jul 13 07:43:01 volumiocentral volumio[916]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 13 07:43:02 volumiocentral volumio[916]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Jul 13 07:43:02 volumiocentral volumio[916]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Jul 13 07:43:02 volumiocentral volumio[916]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Jul 13 07:43:02 volumiocentral volumio[916]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Jul 13 07:43:02 volumiocentral volumio[916]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Jul 13 07:43:02 volumiocentral volumio[916]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Jul 13 07:43:02 volumiocentral volumio[916]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Jul 13 07:43:02 volumiocentral volumio[916]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Jul 13 07:43:02 volumiocentral volumio[916]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Jul 13 07:43:02 volumiocentral volumio[916]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Jul 13 07:43:02 volumiocentral volumio[916]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Jul 13 07:43:02 volumiocentral volumio[916]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Jul 13 07:43:02 volumiocentral volumio[916]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Jul 13 07:43:02 volumiocentral volumio[916]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Jul 13 07:43:02 volumiocentral volumio[916]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Jul 13 07:43:02 volumiocentral volumio[916]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Jul 13 07:43:02 volumiocentral volumio[916]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Jul 13 07:43:02 volumiocentral volumio[916]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Jul 13 07:43:02 volumiocentral volumio[916]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Jul 13 07:43:02 volumiocentral volumio[916]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Jul 13 07:43:02 volumiocentral volumio[916]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Jul 13 07:43:02 volumiocentral volumio[916]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Jul 13 07:43:02 volumiocentral volumio[916]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Jul 13 07:43:02 volumiocentral volumio[916]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Jul 13 07:43:02 volumiocentral volumio[916]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Jul 13 07:43:02 volumiocentral volumio[916]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Jul 13 07:43:02 volumiocentral volumio[916]: info: Adding plugin bluetooth to MyMusic Plugins Jul 13 07:43:02 volumiocentral volumio[916]: info: Adding plugin multiroom to MyMusic Plugins Jul 13 07:43:02 volumiocentral volumio[916]: info: Adding plugin metavolumio to MyMusic Plugins Jul 13 07:43:02 volumiocentral volumio[916]: info: Adding plugin cd_controller to MyMusic Plugins Jul 13 07:43:02 volumiocentral volumio[916]: info: Adding plugin smart_inputs to MyMusic Plugins Jul 13 07:43:02 volumiocentral volumio[916]: info: Adding plugin tidalconnect to MyMusic Plugins Jul 13 07:43:02 volumiocentral volumio[916]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Jul 13 07:43:03 volumiocentral volumio[916]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Jul 13 07:43:03 volumiocentral volumio[916]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Jul 13 07:43:03 volumiocentral volumio[916]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 13 07:43:03 volumiocentral volumio[916]: info: Starting MyVolumio Remote Streaming Endpoints Jul 13 07:43:03 volumiocentral volumio[916]: info: MyVolumio login type: Token Jul 13 07:43:03 volumiocentral volumio[916]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Jul 13 07:43:03 volumiocentral volumio[916]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Jul 13 07:43:04 volumiocentral volumio[916]: info: Starting Streaming Service Transparent Proxy Jul 13 07:43:04 volumiocentral volumio[916]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Jul 13 07:43:04 volumiocentral volumio[916]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Jul 13 07:43:04 volumiocentral volumio[916]: info: Streaming services startup Jul 13 07:43:04 volumiocentral volumio[916]: info: Starting Streaming Daemon Jul 13 07:43:05 volumiocentral sudo[1804]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jul 13 07:43:05 volumiocentral sudo[1804]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 13 07:43:05 volumiocentral sudo[1804]: pam_unix(sudo:session): session closed for user root Jul 13 07:43:05 volumiocentral volumio[916]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Jul 13 07:43:05 volumiocentral volumio[916]: SPOTIFY: SETTING SPOTIFY VOLUME 41 Jul 13 07:43:05 volumiocentral volumio[916]: info: Sending Spotify command with payload to local API: /player/volume Jul 13 07:43:05 volumiocentral go-librespot[1529]: time="2024-07-13T07:43:05+02:00" level=debug msg="update volume to 26869/65535" Jul 13 07:43:05 volumiocentral volumio[916]: error: Cannot start Volumio Streaming Daemon Jul 13 07:43:05 volumiocentral volumio[916]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jul 13 07:43:05 volumiocentral volumio[916]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jul 13 07:43:05 volumiocentral volumio[916]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Jul 13 07:43:05 volumiocentral volumio[916]: info: MyVolumio login type: Token Jul 13 07:43:05 volumiocentral go-librespot[1529]: time="2024-07-13T07:43:05+02:00" level=debug msg="put connect state because VOLUME_CHANGED" Jul 13 07:43:05 volumiocentral go-librespot[1529]: time="2024-07-13T07:43:05+02:00" level=trace msg="emitting websocket event: volume" Jul 13 07:43:05 volumiocentral volumio[916]: SPOTIFY: received: {"type":"volume","data":{"value":41,"max":100}} Jul 13 07:43:05 volumiocentral volumio[916]: SPOTIFY: RECEIVED SPOTIFY VOLUME 41 Jul 13 07:43:05 volumiocentral volumio[916]: error: MyVolumio Custom Token format not valid, refreshing it Jul 13 07:43:05 volumiocentral volumio[916]: STREAMING PROXY: Starting server on port 3245 Jul 13 07:43:05 volumiocentral volumio[916]: Node JS runtime: 14 Jul 13 07:43:05 volumiocentral volumio[916]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Jul 13 07:43:05 volumiocentral volumio[916]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jul 13 07:43:06 volumiocentral volumio[916]: info: MyVolumio login type: Token Jul 13 07:43:06 volumiocentral volumio[916]: info: MyVolumio token set successfully Jul 13 07:43:06 volumiocentral volumio[916]: info: MYVOLUMIO: Adding device Jul 13 07:43:06 volumiocentral volumio[916]: info: MYVOLUMIO: Evaluating Server Jul 13 07:43:06 volumiocentral volumio[916]: info: MyVolumio status changed Jul 13 07:43:06 volumiocentral volumio[916]: info: Streaming services startup Jul 13 07:43:06 volumiocentral volumio[916]: info: Starting Streaming Daemon Jul 13 07:43:06 volumiocentral volumio[916]: info: Removing browser output: myVolumio user plan is not superstar Jul 13 07:43:06 volumiocentral volumio[916]: info: Removing audio output: Jul 13 07:43:06 volumiocentral volumio[916]: info: Stoppping Tunnel 1 Jul 13 07:43:06 volumiocentral sudo[1828]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jul 13 07:43:06 volumiocentral sudo[1828]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 13 07:43:06 volumiocentral sudo[1828]: pam_unix(sudo:session): session closed for user root Jul 13 07:43:06 volumiocentral sudo[1831]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Jul 13 07:43:06 volumiocentral sudo[1831]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 13 07:43:06 volumiocentral volumio[916]: error: Cannot start Volumio Streaming Daemon Jul 13 07:43:06 volumiocentral volumio[916]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jul 13 07:43:06 volumiocentral volumio[916]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jul 13 07:43:06 volumiocentral sudo[1831]: pam_unix(sudo:session): session closed for user root Jul 13 07:43:06 volumiocentral volumio[916]: info: Remote SSH Stopped Jul 13 07:43:07 volumiocentral volumio[916]: info: Setting Geolocation for MyVolumio to eu6 Jul 13 07:43:07 volumiocentral volumio[916]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 13 07:43:07 volumiocentral volumio[916]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 13 07:43:07 volumiocentral volumio[916]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 13 07:43:07 volumiocentral volumio[916]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 13 07:43:07 volumiocentral volumio[916]: info: Received Get System Info Jul 13 07:43:07 volumiocentral volumio[916]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 13 07:43:07 volumiocentral volumio[916]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 13 07:43:07 volumiocentral volumio[916]: info: Discovery: Getting this device information Jul 13 07:43:07 volumiocentral volumio[916]: info: CoreCommandRouter::volumioGetState Jul 13 07:43:07 volumiocentral volumio[916]: info: CorePlayQueue::getTrack 0 Jul 13 07:43:07 volumiocentral volumio[916]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 13 07:43:07 volumiocentral volumio[916]: info: Successfully Added MyVolumio device Jul 13 07:43:08 volumiocentral volumio[916]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 13 07:43:08 volumiocentral volumio[916]: info: Received Get System Info Jul 13 07:43:08 volumiocentral volumio[916]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 13 07:43:08 volumiocentral volumio[916]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 13 07:43:08 volumiocentral volumio[916]: info: Discovery: Getting this device information Jul 13 07:43:08 volumiocentral volumio[916]: info: CoreCommandRouter::volumioGetState Jul 13 07:43:08 volumiocentral volumio[916]: info: CorePlayQueue::getTrack 0 Jul 13 07:43:08 volumiocentral volumio[916]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 13 07:43:08 volumiocentral volumio[916]: info: Updating MyVolumio device info Jul 13 07:43:08 volumiocentral volumio[916]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 13 07:43:08 volumiocentral volumio[916]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jul 13 07:43:08 volumiocentral volumio[916]: info: Successfully Updated MyVolumio device Jul 13 07:43:08 volumiocentral volumio[916]: info: CoreCommandRouter::volumioGetQueue Jul 13 07:43:08 volumiocentral volumio[916]: info: CoreStateMachine::getQueue Jul 13 07:43:08 volumiocentral volumio[916]: info: CorePlayQueue::getQueue Jul 13 07:43:11 volumiocentral volumio[916]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Colour%20Haze/23e5d63c-13e6-4dd4-af91-d34b63190ccd.jpg' Jul 13 07:43:11 volumiocentral volumio[916]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Depeche%20Mode/74ad3c88-42eb-400f-9e2f-bb73ea319897.jpg' Jul 13 07:43:12 volumiocentral systemd[1]: systemd-hostnamed.service: Succeeded. Jul 13 07:43:13 volumiocentral volumio[916]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 13 07:43:13 volumiocentral volumio[916]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jul 13 07:43:13 volumiocentral volumio[916]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jul 13 07:43:13 volumiocentral volumio[916]: info: Received Get System Version Jul 13 07:43:13 volumiocentral volumio[916]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 13 07:43:13 volumiocentral volumio[916]: info: Received Get System Info Jul 13 07:43:13 volumiocentral volumio[916]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 13 07:43:13 volumiocentral volumio[916]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 13 07:43:13 volumiocentral volumio[916]: info: Discovery: Getting this device information Jul 13 07:43:13 volumiocentral volumio[916]: info: CoreCommandRouter::volumioGetState Jul 13 07:43:13 volumiocentral volumio[916]: info: CorePlayQueue::getTrack 0 Jul 13 07:43:13 volumiocentral volumio[916]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 13 07:43:16 volumiocentral volumio[916]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 13 07:43:16 volumiocentral volumio[916]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jul 13 07:43:16 volumiocentral volumio[916]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jul 13 07:43:16 volumiocentral volumio[916]: info: Received Get System Version Jul 13 07:43:16 volumiocentral volumio[916]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 13 07:43:16 volumiocentral volumio[916]: info: Received Get System Info Jul 13 07:43:16 volumiocentral volumio[916]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 13 07:43:16 volumiocentral volumio[916]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 13 07:43:16 volumiocentral volumio[916]: info: Discovery: Getting this device information Jul 13 07:43:16 volumiocentral volumio[916]: info: CoreCommandRouter::volumioGetState Jul 13 07:43:16 volumiocentral volumio[916]: info: CorePlayQueue::getTrack 0 Jul 13 07:43:16 volumiocentral volumio[916]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 13 07:43:18 volumiocentral volumio[916]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 13 07:43:18 volumiocentral volumio[916]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Jul 13 07:43:18 volumiocentral volumio[916]: info: CoreCommandRouter::volumioGetState Jul 13 07:43:18 volumiocentral volumio[916]: info: CorePlayQueue::getTrack 0 Jul 13 07:43:18 volumiocentral volumio[916]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Jul 13 07:43:18 volumiocentral volumio[916]: info: Preload queue cleared Jul 13 07:43:21 volumiocentral volumio[916]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 13 07:43:21 volumiocentral volumio[916]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 13 07:43:21 volumiocentral volumio[916]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 13 07:43:21 volumiocentral volumio-remote-updater[647]: No test mode Jul 13 07:43:21 volumiocentral volumio-remote-updater[647]: No alpha test mode Jul 13 07:43:21 volumiocentral volumio[916]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Jul 13 07:43:21 volumiocentral volumio[916]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Jul 13 07:43:21 volumiocentral volumio[916]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Jul 13 07:43:22 volumiocentral volumio[916]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Jul 13 07:43:22 volumiocentral volumio[916]: info: Preload queue cleared Jul 13 07:43:23 volumiocentral volumio[916]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 13 07:43:23 volumiocentral volumio[916]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 13 07:43:23 volumiocentral volumio[916]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Jul 13 07:43:23 volumiocentral volumio[916]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jul 13 07:43:23 volumiocentral volumio[916]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jul 13 07:43:23 volumiocentral volumio[916]: info: CoreCommandRouter::volumioGetBrowseSources Jul 13 07:43:23 volumiocentral volumio[916]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 13 07:43:25 volumiocentral volumio[916]: error: MyVolumio Plugin failed to authenticate in a timely fashion Jul 13 07:43:25 volumiocentral volumio[916]: info: Completed starting MyVolumio Plugin Jul 13 07:43:25 volumiocentral volumio[916]: info: BOOT COMPLETED Jul 13 07:43:25 volumiocentral volumio[916]: [Metrics] CommandRouter: 50s 502.00ms Jul 13 07:43:25 volumiocentral volumio[916]: info: CoreCommandRouter::volumiosetStartupVolume Jul 13 07:43:25 volumiocentral volumio[916]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 13 07:43:25 volumiocentral volumio[916]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 13 07:43:25 volumiocentral volumio[916]: info: CoreCommandRouter::Close All Modals sent Jul 13 07:43:25 volumiocentral volumio[916]: info: CoreCommandRouter::Close All Modals sent Jul 13 07:43:26 volumiocentral volumio[916]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jul 13 07:43:26 volumiocentral volumio[916]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 13 07:43:26 volumiocentral volumio[916]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jul 13 07:43:27 volumiocentral volumio[916]: info: Preload queue cleared Jul 13 07:43:27 volumiocentral volumio[916]: info: CoreCommandRouter::volumioReplaceandPlayItems Jul 13 07:43:27 volumiocentral volumio[916]: info: CoreStateMachine::ClearQueue Jul 13 07:43:27 volumiocentral volumio[916]: info: CoreStateMachine::stop Jul 13 07:43:27 volumiocentral volumio[916]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 13 07:43:27 volumiocentral volumio[916]: info: CorePlayQueue::clearPlayQueue Jul 13 07:43:27 volumiocentral volumio[916]: info: CorePlayQueue::saveQueue Jul 13 07:43:27 volumiocentral volumio[916]: info: CoreCommandRouter::volumioPushQueue Jul 13 07:43:27 volumiocentral volumio[916]: info: CoreStateMachine::addQueueItems Jul 13 07:43:27 volumiocentral volumio[916]: info: CorePlayQueue::addQueueItems Jul 13 07:43:27 volumiocentral volumio[916]: info: Preload queue cleared Jul 13 07:43:27 volumiocentral volumio[916]: info: Adding Item to queue: http://stream.radioparadise.com/rock-flac Jul 13 07:43:27 volumiocentral volumio[916]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Jul 13 07:43:27 volumiocentral volumio[916]: info: CoreCommandRouter::volumioPushQueue Jul 13 07:43:27 volumiocentral volumio[916]: info: CorePlayQueue::saveQueue Jul 13 07:43:27 volumiocentral volumio[916]: info: CoreStateMachine::updateTrackBlock Jul 13 07:43:27 volumiocentral volumio[916]: info: CorePlayQueue::getTrackBlock Jul 13 07:43:27 volumiocentral volumio[916]: info: CoreCommandRouter::volumioPlay Jul 13 07:43:27 volumiocentral volumio[916]: info: CoreStateMachine::play index 0 Jul 13 07:43:27 volumiocentral volumio[916]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 13 07:43:27 volumiocentral volumio[916]: info: CoreStateMachine::stop Jul 13 07:43:27 volumiocentral volumio[916]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 13 07:43:27 volumiocentral volumio[916]: info: CoreStateMachine::play index undefined Jul 13 07:43:27 volumiocentral volumio[916]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 13 07:43:27 volumiocentral volumio[916]: info: CorePlayQueue::getTrack 0 Jul 13 07:43:27 volumiocentral volumio[916]: info: CoreStateMachine::startPlaybackTimer Jul 13 07:43:27 volumiocentral volumio[916]: info: CorePlayQueue::getTrack 0 Jul 13 07:43:27 volumiocentral volumio[916]: info: [1720849407366] ControllerWebradio::clearAddPlayTrack Jul 13 07:43:27 volumiocentral volumio[916]: verbose: ControllerMpd::sendMpdCommand stop Jul 13 07:43:27 volumiocentral volumio[916]: info: sendMpdCommand stop took 7 milliseconds Jul 13 07:43:27 volumiocentral volumio[916]: verbose: ControllerMpd::sendMpdCommand clear Jul 13 07:43:27 volumiocentral volumio[916]: info: sendMpdCommand clear took 0 milliseconds Jul 13 07:43:27 volumiocentral volumio[916]: verbose: ControllerMpd::sendMpdCommand load "http://stream.radioparadise.com/rock-flac" Jul 13 07:43:27 volumiocentral volumio[916]: info: Jul 13 07:43:27 volumiocentral volumio[916]: ---------------------------- MPD announces system playlist update Jul 13 07:43:27 volumiocentral volumio[916]: info: Ignoring MPD Status Update Jul 13 07:43:27 volumiocentral volumio[916]: info: Jul 13 07:43:27 volumiocentral volumio[916]: ---------------------------- MPD announces system playlist update Jul 13 07:43:27 volumiocentral volumio[916]: info: Ignoring MPD Status Update Jul 13 07:43:27 volumiocentral volumio[916]: info: Jul 13 07:43:27 volumiocentral volumio[916]: ---------------------------- MPD announces system playlist update Jul 13 07:43:27 volumiocentral volumio[916]: info: Ignoring MPD Status Update Jul 13 07:43:27 volumiocentral volumio[916]: verbose: ControllerMpd::sendMpdCommand add "http://stream.radioparadise.com/rock-flac" Jul 13 07:43:27 volumiocentral volumio[916]: error: updateQueue error: null Jul 13 07:43:27 volumiocentral volumio[916]: error: updateQueue error: null Jul 13 07:43:27 volumiocentral volumio[916]: error: updateQueue error: null Jul 13 07:43:27 volumiocentral volumio[916]: info: ------------------------------ 298ms Jul 13 07:43:27 volumiocentral volumio[916]: info: ------------------------------ 296ms Jul 13 07:43:27 volumiocentral volumio[916]: info: ------------------------------ 293ms Jul 13 07:43:27 volumiocentral volumio[916]: info: Jul 13 07:43:27 volumiocentral volumio[916]: ---------------------------- MPD announces system playlist update Jul 13 07:43:27 volumiocentral volumio[916]: info: Ignoring MPD Status Update Jul 13 07:43:27 volumiocentral volumio[916]: info: sendMpdCommand add "http://stream.radioparadise.com/rock-flac" took 5 milliseconds Jul 13 07:43:27 volumiocentral volumio[916]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 13 07:43:27 volumiocentral volumio[916]: verbose: ControllerMpd::sendMpdCommand play Jul 13 07:43:27 volumiocentral volumio[916]: info: Jul 13 07:43:27 volumiocentral volumio[916]: ---------------------------- MPD announces system playlist update Jul 13 07:43:27 volumiocentral volumio[916]: info: Ignoring MPD Status Update Jul 13 07:43:27 volumiocentral volumio[916]: info: Jul 13 07:43:27 volumiocentral volumio[916]: ---------------------------- MPD announces system playlist update Jul 13 07:43:27 volumiocentral volumio[916]: info: Ignoring MPD Status Update Jul 13 07:43:27 volumiocentral volumio[916]: info: ------------------------------ 12ms Jul 13 07:43:27 volumiocentral volumio[916]: info: sendMpdCommand play took 10 milliseconds Jul 13 07:43:27 volumiocentral volumio[916]: info: ------------------------------ 12ms Jul 13 07:43:27 volumiocentral volumio[916]: info: ------------------------------ 8ms Jul 13 07:43:27 volumiocentral volumio[916]: info: Jul 13 07:43:27 volumiocentral volumio[916]: ---------------------------- MPD announces state update: player Jul 13 07:43:27 volumiocentral volumio[916]: info: ControllerMpd::getState Jul 13 07:43:27 volumiocentral volumio[916]: verbose: ControllerMpd::sendMpdCommand status Jul 13 07:43:27 volumiocentral volumio[916]: info: Jul 13 07:43:27 volumiocentral volumio[916]: ---------------------------- MPD announces state update: player Jul 13 07:43:27 volumiocentral volumio[916]: info: ControllerMpd::getState Jul 13 07:43:27 volumiocentral volumio[916]: verbose: ControllerMpd::sendMpdCommand status Jul 13 07:43:27 volumiocentral volumio[916]: info: Jul 13 07:43:27 volumiocentral volumio[916]: ---------------------------- MPD announces state update: player Jul 13 07:43:27 volumiocentral volumio[916]: info: ControllerMpd::getState Jul 13 07:43:27 volumiocentral volumio[916]: verbose: ControllerMpd::sendMpdCommand status Jul 13 07:43:27 volumiocentral volumio[916]: info: Jul 13 07:43:27 volumiocentral volumio[916]: ---------------------------- MPD announces state update: player Jul 13 07:43:27 volumiocentral volumio[916]: info: ControllerMpd::getState Jul 13 07:43:27 volumiocentral volumio[916]: verbose: ControllerMpd::sendMpdCommand status Jul 13 07:43:27 volumiocentral volumio[916]: verbose: ControllerMpd::sendMpdCommand clearerror Jul 13 07:43:27 volumiocentral volumio[916]: info: sendMpdCommand status took 13 milliseconds Jul 13 07:43:27 volumiocentral volumio[916]: verbose: ControllerMpd::sendMpdCommand clearerror Jul 13 07:43:27 volumiocentral volumio[916]: info: sendMpdCommand status took 12 milliseconds Jul 13 07:43:27 volumiocentral volumio[916]: verbose: ControllerMpd::parseState Jul 13 07:43:27 volumiocentral volumio[916]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 13 07:43:27 volumiocentral volumio[916]: verbose: ControllerMpd::parseState Jul 13 07:43:27 volumiocentral volumio[916]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 13 07:43:27 volumiocentral volumio[916]: info: Jul 13 07:43:27 volumiocentral volumio[916]: ---------------------------- MPD announces state update: player Jul 13 07:43:27 volumiocentral volumio[916]: info: ControllerMpd::getState Jul 13 07:43:27 volumiocentral volumio[916]: verbose: ControllerMpd::sendMpdCommand status Jul 13 07:43:27 volumiocentral volumio[916]: info: Jul 13 07:43:27 volumiocentral volumio[916]: ---------------------------- MPD announces state update: player Jul 13 07:43:27 volumiocentral volumio[916]: info: ControllerMpd::getState Jul 13 07:43:27 volumiocentral volumio[916]: verbose: ControllerMpd::sendMpdCommand status Jul 13 07:43:27 volumiocentral volumio[916]: verbose: ControllerMpd::sendMpdCommand clearerror Jul 13 07:43:27 volumiocentral volumio[916]: info: sendMpdCommand status took 28 milliseconds Jul 13 07:43:27 volumiocentral volumio[916]: verbose: ControllerMpd::sendMpdCommand clearerror Jul 13 07:43:27 volumiocentral volumio[916]: info: sendMpdCommand status took 28 milliseconds Jul 13 07:43:27 volumiocentral volumio[916]: info: sendMpdCommand clearerror took 21 milliseconds Jul 13 07:43:27 volumiocentral volumio[916]: info: sendMpdCommand clearerror took 20 milliseconds Jul 13 07:43:27 volumiocentral volumio[916]: info: sendMpdCommand playlistinfo took 14 milliseconds Jul 13 07:43:27 volumiocentral volumio[916]: info: sendMpdCommand playlistinfo took 14 milliseconds Jul 13 07:43:27 volumiocentral volumio[916]: verbose: ControllerMpd::parseState Jul 13 07:43:27 volumiocentral volumio[916]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 13 07:43:27 volumiocentral volumio[916]: verbose: ControllerMpd::parseState Jul 13 07:43:27 volumiocentral volumio[916]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 13 07:43:27 volumiocentral volumio[916]: verbose: ControllerMpd::parseTrackInfo Jul 13 07:43:27 volumiocentral volumio[916]: info: ControllerMpd::pushState Jul 13 07:43:27 volumiocentral volumio[916]: info: CoreCommandRouter::servicePushState Jul 13 07:43:27 volumiocentral volumio[916]: info: CorePlayQueue::getTrack 0 Jul 13 07:43:27 volumiocentral volumio[916]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"rock-flac","artist":null,"album":null,"uri":"http://stream.radioparadise.com/rock-flac","trackType":""} Jul 13 07:43:27 volumiocentral volumio[916]: verbose: CURRENT POSITION 0 Jul 13 07:43:27 volumiocentral volumio[916]: info: CoreStateMachine::syncState stateService pause Jul 13 07:43:27 volumiocentral volumio[916]: info: CoreStateMachine::syncState currentStatus stop Jul 13 07:43:27 volumiocentral volumio[916]: info: CoreStateMachine::pushState Jul 13 07:43:27 volumiocentral volumio[916]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 13 07:43:27 volumiocentral volumio[916]: info: CoreCommandRouter::volumioPushState Jul 13 07:43:27 volumiocentral volumio[916]: info: ControllerMpd::pushState Jul 13 07:43:27 volumiocentral volumio[916]: info: CoreCommandRouter::servicePushState Jul 13 07:43:27 volumiocentral volumio[916]: info: CorePlayQueue::getTrack 0 Jul 13 07:43:27 volumiocentral volumio[916]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"rock-flac","artist":null,"album":null,"uri":"http://stream.radioparadise.com/rock-flac","trackType":""} Jul 13 07:43:27 volumiocentral volumio[916]: verbose: CURRENT POSITION 0 Jul 13 07:43:27 volumiocentral volumio[916]: info: CoreStateMachine::syncState stateService pause Jul 13 07:43:27 volumiocentral volumio[916]: info: CoreStateMachine::syncState currentStatus stop Jul 13 07:43:27 volumiocentral volumio[916]: info: CoreStateMachine::pushState Jul 13 07:43:27 volumiocentral volumio[916]: info: CoreCommandRouter::volumioPushState Jul 13 07:43:28 volumiocentral volumio[916]: info: ------------------------------ 79ms Jul 13 07:43:28 volumiocentral volumio[916]: info: ------------------------------ 78ms Jul 13 07:43:28 volumiocentral volumio[916]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Jul 13 07:43:28 volumiocentral volumio[916]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Jul 13 07:43:28 volumiocentral volumio[916]: info: sendMpdCommand status took 67 milliseconds Jul 13 07:43:28 volumiocentral volumio[916]: info: sendMpdCommand status took 66 milliseconds Jul 13 07:43:28 volumiocentral volumio[916]: info: sendMpdCommand clearerror took 60 milliseconds Jul 13 07:43:28 volumiocentral volumio[916]: info: sendMpdCommand clearerror took 60 milliseconds Jul 13 07:43:28 volumiocentral volumio[916]: info: sendMpdCommand playlistinfo took 52 milliseconds Jul 13 07:43:28 volumiocentral volumio[916]: info: sendMpdCommand playlistinfo took 51 milliseconds Jul 13 07:43:28 volumiocentral volumio[916]: verbose: ControllerMpd::parseState Jul 13 07:43:28 volumiocentral volumio[916]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 13 07:43:28 volumiocentral volumio[916]: verbose: ControllerMpd::parseState Jul 13 07:43:28 volumiocentral volumio[916]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 13 07:43:28 volumiocentral volumio[916]: verbose: ControllerMpd::parseTrackInfo Jul 13 07:43:28 volumiocentral volumio[916]: verbose: ControllerMpd::parseTrackInfo Jul 13 07:43:28 volumiocentral volumio[916]: info: ControllerMpd::pushState Jul 13 07:43:28 volumiocentral volumio[916]: info: CoreCommandRouter::servicePushState Jul 13 07:43:28 volumiocentral volumio[916]: info: CorePlayQueue::getTrack 0 Jul 13 07:43:28 volumiocentral volumio[916]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"rock-flac","artist":null,"album":null,"uri":"http://stream.radioparadise.com/rock-flac","trackType":""} Jul 13 07:43:28 volumiocentral volumio[916]: verbose: CURRENT POSITION 0 Jul 13 07:43:28 volumiocentral volumio[916]: info: CoreStateMachine::syncState stateService pause Jul 13 07:43:28 volumiocentral volumio[916]: info: CoreStateMachine::syncState currentStatus stop Jul 13 07:43:28 volumiocentral volumio[916]: info: CoreStateMachine::pushState Jul 13 07:43:28 volumiocentral volumio[916]: info: CoreCommandRouter::volumioPushState Jul 13 07:43:28 volumiocentral volumio[916]: info: ControllerMpd::pushState Jul 13 07:43:28 volumiocentral volumio[916]: info: CoreCommandRouter::servicePushState Jul 13 07:43:28 volumiocentral volumio[916]: info: CorePlayQueue::getTrack 0 Jul 13 07:43:28 volumiocentral volumio[916]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"rock-flac","artist":null,"album":null,"uri":"http://stream.radioparadise.com/rock-flac","trackType":""} Jul 13 07:43:28 volumiocentral volumio[916]: verbose: CURRENT POSITION 0 Jul 13 07:43:28 volumiocentral volumio[916]: info: CoreStateMachine::syncState stateService pause Jul 13 07:43:28 volumiocentral volumio[916]: info: CoreStateMachine::syncState currentStatus stop Jul 13 07:43:28 volumiocentral volumio[916]: info: CoreStateMachine::pushState Jul 13 07:43:28 volumiocentral volumio[916]: info: CoreCommandRouter::volumioPushState Jul 13 07:43:28 volumiocentral volumio[916]: info: ------------------------------ 137ms Jul 13 07:43:28 volumiocentral volumio[916]: info: ------------------------------ 137ms Jul 13 07:43:28 volumiocentral volumio[916]: info: sendMpdCommand playlistinfo took 45 milliseconds Jul 13 07:43:28 volumiocentral volumio[916]: info: sendMpdCommand playlistinfo took 44 milliseconds Jul 13 07:43:28 volumiocentral volumio[916]: verbose: ControllerMpd::parseTrackInfo Jul 13 07:43:28 volumiocentral volumio[916]: verbose: ControllerMpd::parseTrackInfo Jul 13 07:43:28 volumiocentral volumio[916]: info: ControllerMpd::pushState Jul 13 07:43:28 volumiocentral volumio[916]: info: CoreCommandRouter::servicePushState Jul 13 07:43:28 volumiocentral volumio[916]: info: CorePlayQueue::getTrack 0 Jul 13 07:43:28 volumiocentral volumio[916]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"rock-flac","artist":null,"album":null,"uri":"http://stream.radioparadise.com/rock-flac","trackType":""} Jul 13 07:43:28 volumiocentral volumio[916]: verbose: CURRENT POSITION 0 Jul 13 07:43:28 volumiocentral volumio[916]: info: CoreStateMachine::syncState stateService pause Jul 13 07:43:28 volumiocentral volumio[916]: info: CoreStateMachine::syncState currentStatus stop Jul 13 07:43:28 volumiocentral volumio[916]: info: CoreStateMachine::pushState Jul 13 07:43:28 volumiocentral volumio[916]: info: CoreCommandRouter::volumioPushState Jul 13 07:43:28 volumiocentral volumio[916]: info: ControllerMpd::pushState Jul 13 07:43:28 volumiocentral volumio[916]: info: CoreCommandRouter::servicePushState Jul 13 07:43:28 volumiocentral volumio[916]: info: CorePlayQueue::getTrack 0 Jul 13 07:43:28 volumiocentral volumio[916]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"rock-flac","artist":null,"album":null,"uri":"http://stream.radioparadise.com/rock-flac","trackType":""} Jul 13 07:43:28 volumiocentral volumio[916]: verbose: CURRENT POSITION 0 Jul 13 07:43:28 volumiocentral volumio[916]: info: CoreStateMachine::syncState stateService pause Jul 13 07:43:28 volumiocentral volumio[916]: info: CoreStateMachine::syncState currentStatus stop Jul 13 07:43:28 volumiocentral volumio[916]: info: CoreStateMachine::pushState Jul 13 07:43:28 volumiocentral volumio[916]: info: CoreCommandRouter::volumioPushState Jul 13 07:43:28 volumiocentral volumio[916]: info: ------------------------------ 156ms Jul 13 07:43:28 volumiocentral volumio[916]: info: ------------------------------ 156ms Jul 13 07:43:28 volumiocentral volumio[916]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Jul 13 07:43:28 volumiocentral volumio[916]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Jul 13 07:43:33 volumiocentral volumio[916]: info: Preload queue cleared Jul 13 07:43:33 volumiocentral volumio[916]: info: CoreCommandRouter::volumioReplaceandPlayItems Jul 13 07:43:33 volumiocentral volumio[916]: info: CoreStateMachine::ClearQueue Jul 13 07:43:33 volumiocentral volumio[916]: info: CoreStateMachine::stop Jul 13 07:43:33 volumiocentral volumio[916]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 13 07:43:33 volumiocentral volumio[916]: info: CorePlayQueue::clearPlayQueue Jul 13 07:43:33 volumiocentral volumio[916]: info: CorePlayQueue::saveQueue Jul 13 07:43:33 volumiocentral volumio[916]: info: CoreCommandRouter::volumioPushQueue Jul 13 07:43:33 volumiocentral volumio[916]: info: CoreStateMachine::addQueueItems Jul 13 07:43:33 volumiocentral volumio[916]: info: CorePlayQueue::addQueueItems Jul 13 07:43:33 volumiocentral volumio[916]: info: Preload queue cleared Jul 13 07:43:33 volumiocentral volumio[916]: info: Adding Item to queue: https://radioparadise.com/player/list/main-mix Jul 13 07:43:33 volumiocentral volumio[916]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Jul 13 07:43:33 volumiocentral volumio[916]: info: CoreCommandRouter::volumioPushQueue Jul 13 07:43:33 volumiocentral volumio[916]: info: CorePlayQueue::saveQueue Jul 13 07:43:33 volumiocentral volumio[916]: info: CoreStateMachine::updateTrackBlock Jul 13 07:43:33 volumiocentral volumio[916]: info: CorePlayQueue::getTrackBlock Jul 13 07:43:33 volumiocentral volumio[916]: info: CoreCommandRouter::volumioPlay Jul 13 07:43:33 volumiocentral volumio[916]: info: CoreStateMachine::play index 0 Jul 13 07:43:33 volumiocentral volumio[916]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 13 07:43:33 volumiocentral volumio[916]: info: CoreStateMachine::stop Jul 13 07:43:33 volumiocentral volumio[916]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 13 07:43:33 volumiocentral volumio[916]: info: CoreStateMachine::play index undefined Jul 13 07:43:33 volumiocentral volumio[916]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 13 07:43:33 volumiocentral volumio[916]: info: CorePlayQueue::getTrack 0 Jul 13 07:43:33 volumiocentral volumio[916]: info: CoreStateMachine::startPlaybackTimer Jul 13 07:43:33 volumiocentral volumio[916]: info: CorePlayQueue::getTrack 0 Jul 13 07:43:33 volumiocentral volumio[916]: info: [1720849413763] ControllerWebradio::clearAddPlayTrack Jul 13 07:43:33 volumiocentral volumio[916]: verbose: ControllerMpd::sendMpdCommand stop Jul 13 07:43:33 volumiocentral volumio[916]: info: Jul 13 07:43:33 volumiocentral volumio[916]: ---------------------------- MPD announces state update: player Jul 13 07:43:33 volumiocentral volumio[916]: info: sendMpdCommand stop took 3 milliseconds Jul 13 07:43:33 volumiocentral volumio[916]: info: ControllerMpd::getState Jul 13 07:43:33 volumiocentral volumio[916]: verbose: ControllerMpd::sendMpdCommand status Jul 13 07:43:33 volumiocentral volumio[916]: verbose: ControllerMpd::sendMpdCommand clear Jul 13 07:43:33 volumiocentral volumio[916]: info: Jul 13 07:43:33 volumiocentral volumio[916]: ---------------------------- MPD announces system playlist update Jul 13 07:43:33 volumiocentral volumio[916]: info: Ignoring MPD Status Update Jul 13 07:43:33 volumiocentral volumio[916]: info: sendMpdCommand status took 2 milliseconds Jul 13 07:43:33 volumiocentral volumio[916]: info: sendMpdCommand clear took 2 milliseconds Jul 13 07:43:33 volumiocentral volumio[916]: verbose: ControllerMpd::parseState Jul 13 07:43:33 volumiocentral volumio[916]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 13 07:43:33 volumiocentral volumio[916]: verbose: ControllerMpd::sendMpdCommand load "https://radioparadise.com/player/list/main-mix" Jul 13 07:43:33 volumiocentral volumio[916]: info: Jul 13 07:43:33 volumiocentral volumio[916]: ---------------------------- MPD announces state update: player Jul 13 07:43:33 volumiocentral volumio[916]: info: ControllerMpd::getState Jul 13 07:43:33 volumiocentral volumio[916]: verbose: ControllerMpd::sendMpdCommand status Jul 13 07:43:33 volumiocentral volumio[916]: info: Jul 13 07:43:33 volumiocentral volumio[916]: ---------------------------- MPD announces state update: player Jul 13 07:43:33 volumiocentral volumio[916]: info: ControllerMpd::getState Jul 13 07:43:33 volumiocentral volumio[916]: verbose: ControllerMpd::sendMpdCommand status Jul 13 07:43:33 volumiocentral volumio[916]: error: updateQueue error: null Jul 13 07:43:33 volumiocentral volumio[916]: info: ------------------------------ 6ms Jul 13 07:43:33 volumiocentral volumio[916]: info: sendMpdCommand playlistinfo took 5 milliseconds Jul 13 07:43:33 volumiocentral volumio[916]: verbose: ControllerMpd::parseTrackInfo Jul 13 07:43:33 volumiocentral volumio[916]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jul 13 07:43:33 volumiocentral volumio[916]: info: ------------------------------ 10ms Jul 13 07:43:34 volumiocentral volumio[916]: verbose: ControllerMpd::sendMpdCommand add "https://radioparadise.com/player/list/main-mix" Jul 13 07:43:34 volumiocentral volumio[916]: info: sendMpdCommand status took 1130 milliseconds Jul 13 07:43:34 volumiocentral volumio[916]: info: sendMpdCommand status took 1130 milliseconds Jul 13 07:43:34 volumiocentral volumio[916]: verbose: ControllerMpd::parseState Jul 13 07:43:34 volumiocentral volumio[916]: verbose: ControllerMpd::parseState Jul 13 07:43:34 volumiocentral volumio[916]: info: ControllerMpd::pushState Jul 13 07:43:34 volumiocentral volumio[916]: info: CoreCommandRouter::servicePushState Jul 13 07:43:34 volumiocentral volumio[916]: info: CoreStateMachine::pushState Jul 13 07:43:34 volumiocentral volumio[916]: info: CorePlayQueue::getTrack 0 Jul 13 07:43:34 volumiocentral volumio[916]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 13 07:43:34 volumiocentral volumio[916]: info: CoreCommandRouter::volumioPushState Jul 13 07:43:34 volumiocentral volumio[916]: info: CorePlayQueue::getTrack 0 Jul 13 07:43:34 volumiocentral volumio[916]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jul 13 07:43:34 volumiocentral volumio[916]: info: ControllerMpd::pushState Jul 13 07:43:34 volumiocentral volumio[916]: info: CoreCommandRouter::servicePushState Jul 13 07:43:34 volumiocentral volumio[916]: info: CoreStateMachine::pushState Jul 13 07:43:34 volumiocentral volumio[916]: info: CorePlayQueue::getTrack 0 Jul 13 07:43:34 volumiocentral volumio[916]: info: CoreCommandRouter::volumioPushState Jul 13 07:43:34 volumiocentral volumio[916]: info: CorePlayQueue::getTrack 0 Jul 13 07:43:34 volumiocentral volumio[916]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jul 13 07:43:34 volumiocentral volumio[916]: info: ------------------------------ 1158ms Jul 13 07:43:34 volumiocentral volumio[916]: info: Jul 13 07:43:34 volumiocentral volumio[916]: ---------------------------- MPD announces system playlist update Jul 13 07:43:34 volumiocentral volumio[916]: info: Ignoring MPD Status Update Jul 13 07:43:34 volumiocentral volumio[916]: info: Jul 13 07:43:34 volumiocentral volumio[916]: ---------------------------- MPD announces system playlist update Jul 13 07:43:34 volumiocentral volumio[916]: info: Ignoring MPD Status Update Jul 13 07:43:34 volumiocentral volumio[916]: info: Jul 13 07:43:34 volumiocentral volumio[916]: ---------------------------- MPD announces system playlist update Jul 13 07:43:34 volumiocentral volumio[916]: info: Ignoring MPD Status Update Jul 13 07:43:34 volumiocentral volumio[916]: info: sendMpdCommand add "https://radioparadise.com/player/list/main-mix" took 34 milliseconds Jul 13 07:43:34 volumiocentral volumio[916]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 13 07:43:34 volumiocentral volumio[916]: verbose: ControllerMpd::sendMpdCommand play Jul 13 07:43:34 volumiocentral volumio[916]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Jul 13 07:43:34 volumiocentral volumio[916]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Jul 13 07:43:34 volumiocentral volumio[916]: info: ------------------------------ 14ms Jul 13 07:43:34 volumiocentral volumio[916]: info: ------------------------------ 13ms Jul 13 07:43:34 volumiocentral volumio[916]: info: ------------------------------ 12ms Jul 13 07:43:34 volumiocentral volumio[916]: info: sendMpdCommand play took 9 milliseconds Jul 13 07:43:34 volumiocentral volumio[916]: info: Jul 13 07:43:34 volumiocentral volumio[916]: ---------------------------- MPD announces system playlist update Jul 13 07:43:34 volumiocentral volumio[916]: info: Ignoring MPD Status Update Jul 13 07:43:34 volumiocentral volumio[916]: info: Jul 13 07:43:34 volumiocentral volumio[916]: ---------------------------- MPD announces system playlist update Jul 13 07:43:34 volumiocentral volumio[916]: info: Ignoring MPD Status Update Jul 13 07:43:34 volumiocentral volumio[916]: info: ------------------------------ 8ms Jul 13 07:43:34 volumiocentral volumio[916]: info: ------------------------------ 12ms Jul 13 07:43:35 volumiocentral volumio[916]: info: Jul 13 07:43:35 volumiocentral volumio[916]: ---------------------------- MPD announces state update: player Jul 13 07:43:35 volumiocentral volumio[916]: info: ControllerMpd::getState Jul 13 07:43:35 volumiocentral volumio[916]: verbose: ControllerMpd::sendMpdCommand status Jul 13 07:43:35 volumiocentral volumio[916]: info: Jul 13 07:43:35 volumiocentral volumio[916]: ---------------------------- MPD announces state update: player Jul 13 07:43:35 volumiocentral volumio[916]: info: ControllerMpd::getState Jul 13 07:43:35 volumiocentral volumio[916]: verbose: ControllerMpd::sendMpdCommand status Jul 13 07:43:35 volumiocentral volumio[916]: info: Jul 13 07:43:35 volumiocentral volumio[916]: ---------------------------- MPD announces state update: player Jul 13 07:43:35 volumiocentral volumio[916]: info: ControllerMpd::getState Jul 13 07:43:35 volumiocentral volumio[916]: verbose: ControllerMpd::sendMpdCommand status Jul 13 07:43:35 volumiocentral volumio[916]: verbose: ControllerMpd::sendMpdCommand clearerror Jul 13 07:43:35 volumiocentral volumio[916]: info: sendMpdCommand status took 20 milliseconds Jul 13 07:43:35 volumiocentral volumio[916]: verbose: ControllerMpd::sendMpdCommand clearerror Jul 13 07:43:35 volumiocentral volumio[916]: info: sendMpdCommand status took 21 milliseconds Jul 13 07:43:35 volumiocentral volumio[916]: verbose: ControllerMpd::parseState Jul 13 07:43:35 volumiocentral volumio[916]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 13 07:43:35 volumiocentral volumio[916]: verbose: ControllerMpd::parseState Jul 13 07:43:35 volumiocentral volumio[916]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 13 07:43:35 volumiocentral volumio[916]: verbose: ControllerMpd::sendMpdCommand clearerror Jul 13 07:43:35 volumiocentral volumio[916]: info: sendMpdCommand status took 38 milliseconds Jul 13 07:43:35 volumiocentral volumio[916]: info: sendMpdCommand clearerror took 33 milliseconds Jul 13 07:43:35 volumiocentral volumio[916]: info: sendMpdCommand clearerror took 28 milliseconds Jul 13 07:43:35 volumiocentral volumio[916]: info: sendMpdCommand playlistinfo took 21 milliseconds Jul 13 07:43:35 volumiocentral volumio[916]: info: sendMpdCommand playlistinfo took 19 milliseconds Jul 13 07:43:35 volumiocentral volumio[916]: verbose: ControllerMpd::parseState Jul 13 07:43:35 volumiocentral volumio[916]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 13 07:43:35 volumiocentral volumio[916]: verbose: ControllerMpd::parseTrackInfo Jul 13 07:43:35 volumiocentral volumio[916]: info: ControllerMpd::pushState Jul 13 07:43:35 volumiocentral volumio[916]: info: CoreCommandRouter::servicePushState Jul 13 07:43:35 volumiocentral volumio[916]: info: CoreStateMachine::pushState Jul 13 07:43:35 volumiocentral volumio[916]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 13 07:43:35 volumiocentral volumio[916]: info: CoreCommandRouter::volumioPushState Jul 13 07:43:35 volumiocentral volumio[916]: info: CorePlayQueue::getTrack 0 Jul 13 07:43:35 volumiocentral volumio[916]: 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":"main-mix","artist":null,"album":null,"uri":"https://radioparadise.com/player/list/main-mix","trackType":""} Jul 13 07:43:35 volumiocentral volumio[916]: verbose: CURRENT POSITION 0 Jul 13 07:43:35 volumiocentral volumio[916]: info: CoreStateMachine::syncState stateService stop Jul 13 07:43:35 volumiocentral volumio[916]: info: CoreStateMachine::syncState currentStatus stop Jul 13 07:43:35 volumiocentral volumio[916]: info: CoreStateMachine::pushState Jul 13 07:43:35 volumiocentral volumio[916]: info: CoreCommandRouter::volumioPushState Jul 13 07:43:35 volumiocentral volumio[916]: info: No code Jul 13 07:43:35 volumiocentral volumio[916]: info: CoreStateMachine::pushState Jul 13 07:43:35 volumiocentral volumio[916]: info: CoreCommandRouter::volumioPushState Jul 13 07:43:35 volumiocentral volumio[916]: info: ControllerMpd::pushState Jul 13 07:43:35 volumiocentral volumio[916]: info: CoreCommandRouter::servicePushState Jul 13 07:43:35 volumiocentral volumio[916]: info: CoreStateMachine::pushState Jul 13 07:43:35 volumiocentral volumio[916]: info: CoreCommandRouter::volumioPushState Jul 13 07:43:35 volumiocentral volumio[916]: info: CorePlayQueue::getTrack 0 Jul 13 07:43:35 volumiocentral volumio[916]: 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":"main-mix","artist":null,"album":null,"uri":"https://radioparadise.com/player/list/main-mix","trackType":""} Jul 13 07:43:35 volumiocentral volumio[916]: verbose: CURRENT POSITION 0 Jul 13 07:43:35 volumiocentral volumio[916]: info: CoreStateMachine::syncState stateService stop Jul 13 07:43:35 volumiocentral volumio[916]: info: CoreStateMachine::syncState currentStatus stop Jul 13 07:43:35 volumiocentral volumio[916]: info: CoreStateMachine::pushState Jul 13 07:43:35 volumiocentral volumio[916]: info: CoreCommandRouter::volumioPushState Jul 13 07:43:35 volumiocentral volumio[916]: info: No code Jul 13 07:43:35 volumiocentral volumio[916]: info: CoreStateMachine::pushState Jul 13 07:43:35 volumiocentral volumio[916]: info: CoreCommandRouter::volumioPushState Jul 13 07:43:35 volumiocentral volumio[916]: info: ------------------------------ 148ms Jul 13 07:43:35 volumiocentral volumio[916]: info: ------------------------------ 149ms Jul 13 07:43:35 volumiocentral volumio[916]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Jul 13 07:43:35 volumiocentral volumio[916]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Jul 13 07:43:35 volumiocentral volumio[916]: info: sendMpdCommand clearerror took 122 milliseconds Jul 13 07:43:35 volumiocentral volumio[916]: info: sendMpdCommand playlistinfo took 116 milliseconds Jul 13 07:43:35 volumiocentral volumio[916]: verbose: ControllerMpd::parseTrackInfo Jul 13 07:43:35 volumiocentral volumio[916]: info: ControllerMpd::pushState Jul 13 07:43:35 volumiocentral volumio[916]: info: CoreCommandRouter::servicePushState Jul 13 07:43:35 volumiocentral volumio[916]: info: CoreStateMachine::pushState Jul 13 07:43:35 volumiocentral volumio[916]: info: CoreCommandRouter::volumioPushState Jul 13 07:43:35 volumiocentral volumio[916]: info: CorePlayQueue::getTrack 0 Jul 13 07:43:35 volumiocentral volumio[916]: 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":"main-mix","artist":null,"album":null,"uri":"https://radioparadise.com/player/list/main-mix","trackType":""} Jul 13 07:43:35 volumiocentral volumio[916]: verbose: CURRENT POSITION 0 Jul 13 07:43:35 volumiocentral volumio[916]: info: CoreStateMachine::syncState stateService stop Jul 13 07:43:35 volumiocentral volumio[916]: info: CoreStateMachine::syncState currentStatus stop Jul 13 07:43:35 volumiocentral volumio[916]: info: CoreStateMachine::pushState Jul 13 07:43:35 volumiocentral volumio[916]: info: CoreCommandRouter::volumioPushState Jul 13 07:43:35 volumiocentral volumio[916]: info: No code Jul 13 07:43:35 volumiocentral volumio[916]: info: CoreStateMachine::pushState Jul 13 07:43:35 volumiocentral volumio[916]: info: CoreCommandRouter::volumioPushState Jul 13 07:43:35 volumiocentral volumio[916]: info: ------------------------------ 209ms Jul 13 07:43:35 volumiocentral volumio[916]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Jul 13 07:43:35 volumiocentral volumio[916]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Jul 13 07:43:35 volumiocentral volumio[916]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Jul 13 07:43:41 volumiocentral volumio[916]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Jul 13 07:43:41 volumiocentral volumio[916]: info: Getting webradio selection Jul 13 07:43:41 volumiocentral volumio[916]: info: Preload queue cleared Jul 13 07:43:43 volumiocentral volumio[916]: info: Preload queue cleared Jul 13 07:43:43 volumiocentral volumio[916]: info: CoreCommandRouter::volumioReplaceandPlayItems Jul 13 07:43:43 volumiocentral volumio[916]: info: CoreStateMachine::ClearQueue Jul 13 07:43:43 volumiocentral volumio[916]: info: CoreStateMachine::stop Jul 13 07:43:43 volumiocentral volumio[916]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 13 07:43:43 volumiocentral volumio[916]: info: CorePlayQueue::clearPlayQueue Jul 13 07:43:43 volumiocentral volumio[916]: info: CorePlayQueue::saveQueue Jul 13 07:43:43 volumiocentral volumio[916]: info: CoreCommandRouter::volumioPushQueue Jul 13 07:43:43 volumiocentral volumio[916]: info: CoreStateMachine::addQueueItems Jul 13 07:43:43 volumiocentral volumio[916]: info: CorePlayQueue::addQueueItems Jul 13 07:43:43 volumiocentral volumio[916]: info: Preload queue cleared Jul 13 07:43:43 volumiocentral volumio[916]: info: Adding Item to queue: http://stream.absolutradio.de/hq/mp3-160/radioplayer/ Jul 13 07:43:43 volumiocentral volumio[916]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Jul 13 07:43:43 volumiocentral volumio[916]: info: CoreCommandRouter::volumioPushQueue Jul 13 07:43:43 volumiocentral volumio[916]: info: CorePlayQueue::saveQueue Jul 13 07:43:43 volumiocentral volumio[916]: info: CoreStateMachine::updateTrackBlock Jul 13 07:43:43 volumiocentral volumio[916]: info: CorePlayQueue::getTrackBlock Jul 13 07:43:43 volumiocentral volumio[916]: info: CoreCommandRouter::volumioPlay Jul 13 07:43:43 volumiocentral volumio[916]: info: CoreStateMachine::play index 0 Jul 13 07:43:43 volumiocentral volumio[916]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 13 07:43:43 volumiocentral volumio[916]: info: CoreStateMachine::stop Jul 13 07:43:43 volumiocentral volumio[916]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 13 07:43:43 volumiocentral volumio[916]: info: CoreStateMachine::play index undefined Jul 13 07:43:43 volumiocentral volumio[916]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 13 07:43:43 volumiocentral volumio[916]: info: CorePlayQueue::getTrack 0 Jul 13 07:43:43 volumiocentral volumio[916]: info: CoreStateMachine::startPlaybackTimer Jul 13 07:43:43 volumiocentral volumio[916]: info: CorePlayQueue::getTrack 0 Jul 13 07:43:43 volumiocentral volumio[916]: info: [1720849423573] ControllerWebradio::clearAddPlayTrack Jul 13 07:43:43 volumiocentral volumio[916]: verbose: ControllerMpd::sendMpdCommand stop Jul 13 07:43:43 volumiocentral volumio[916]: info: sendMpdCommand stop took 3 milliseconds Jul 13 07:43:43 volumiocentral volumio[916]: verbose: ControllerMpd::sendMpdCommand clear Jul 13 07:43:43 volumiocentral volumio[916]: info: Jul 13 07:43:43 volumiocentral volumio[916]: ---------------------------- MPD announces system playlist update Jul 13 07:43:43 volumiocentral volumio[916]: info: Ignoring MPD Status Update Jul 13 07:43:43 volumiocentral volumio[916]: info: sendMpdCommand clear took 2 milliseconds Jul 13 07:43:43 volumiocentral volumio[916]: verbose: ControllerMpd::sendMpdCommand load "http://stream.absolutradio.de/hq/mp3-160/radioplayer/" Jul 13 07:43:43 volumiocentral volumio[916]: info: Jul 13 07:43:43 volumiocentral volumio[916]: ---------------------------- MPD announces system playlist update Jul 13 07:43:43 volumiocentral volumio[916]: info: Ignoring MPD Status Update Jul 13 07:43:43 volumiocentral volumio[916]: info: Jul 13 07:43:43 volumiocentral volumio[916]: ---------------------------- MPD announces system playlist update Jul 13 07:43:43 volumiocentral volumio[916]: info: Ignoring MPD Status Update Jul 13 07:43:44 volumiocentral volumio[916]: error: updateQueue error: null Jul 13 07:43:44 volumiocentral volumio[916]: info: ------------------------------ 974ms Jul 13 07:43:44 volumiocentral volumio[916]: verbose: ControllerMpd::sendMpdCommand add "http://stream.absolutradio.de/hq/mp3-160/radioplayer/" Jul 13 07:43:44 volumiocentral volumio[916]: error: updateQueue error: null Jul 13 07:43:44 volumiocentral volumio[916]: error: updateQueue error: null Jul 13 07:43:44 volumiocentral volumio[916]: info: Jul 13 07:43:44 volumiocentral volumio[916]: ---------------------------- MPD announces system playlist update Jul 13 07:43:44 volumiocentral volumio[916]: info: Ignoring MPD Status Update Jul 13 07:43:44 volumiocentral volumio[916]: info: ------------------------------ 973ms Jul 13 07:43:44 volumiocentral volumio[916]: info: ------------------------------ 973ms Jul 13 07:43:44 volumiocentral volumio[916]: info: sendMpdCommand add "http://stream.absolutradio.de/hq/mp3-160/radioplayer/" took 2 milliseconds Jul 13 07:43:44 volumiocentral volumio[916]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 13 07:43:44 volumiocentral volumio[916]: verbose: ControllerMpd::sendMpdCommand play Jul 13 07:43:44 volumiocentral volumio[916]: info: Jul 13 07:43:44 volumiocentral volumio[916]: ---------------------------- MPD announces system playlist update Jul 13 07:43:44 volumiocentral volumio[916]: info: Ignoring MPD Status Update Jul 13 07:43:44 volumiocentral volumio[916]: info: Jul 13 07:43:44 volumiocentral volumio[916]: ---------------------------- MPD announces system playlist update Jul 13 07:43:44 volumiocentral volumio[916]: info: Ignoring MPD Status Update Jul 13 07:43:44 volumiocentral volumio[916]: info: ------------------------------ 6ms Jul 13 07:43:44 volumiocentral volumio[916]: info: sendMpdCommand play took 5 milliseconds Jul 13 07:43:44 volumiocentral volumio[916]: info: ------------------------------ 4ms Jul 13 07:43:44 volumiocentral volumio[916]: info: ------------------------------ 4ms Jul 13 07:43:44 volumiocentral volumio[916]: info: Jul 13 07:43:44 volumiocentral volumio[916]: ---------------------------- MPD announces state update: player Jul 13 07:43:44 volumiocentral volumio[916]: info: ControllerMpd::getState Jul 13 07:43:44 volumiocentral volumio[916]: verbose: ControllerMpd::sendMpdCommand status Jul 13 07:43:44 volumiocentral volumio[916]: info: Jul 13 07:43:44 volumiocentral volumio[916]: ---------------------------- MPD announces state update: player Jul 13 07:43:44 volumiocentral volumio[916]: info: ControllerMpd::getState Jul 13 07:43:44 volumiocentral volumio[916]: verbose: ControllerMpd::sendMpdCommand status Jul 13 07:43:44 volumiocentral volumio[916]: info: Jul 13 07:43:44 volumiocentral volumio[916]: ---------------------------- MPD announces state update: player Jul 13 07:43:44 volumiocentral volumio[916]: info: ControllerMpd::getState Jul 13 07:43:44 volumiocentral volumio[916]: verbose: ControllerMpd::sendMpdCommand status Jul 13 07:43:44 volumiocentral volumio[916]: verbose: ControllerMpd::sendMpdCommand clearerror Jul 13 07:43:44 volumiocentral volumio[916]: info: sendMpdCommand status took 12 milliseconds Jul 13 07:43:44 volumiocentral volumio[916]: verbose: ControllerMpd::sendMpdCommand clearerror Jul 13 07:43:44 volumiocentral volumio[916]: info: sendMpdCommand status took 11 milliseconds Jul 13 07:43:44 volumiocentral volumio[916]: verbose: ControllerMpd::sendMpdCommand clearerror Jul 13 07:43:44 volumiocentral volumio[916]: info: sendMpdCommand status took 22 milliseconds Jul 13 07:43:44 volumiocentral volumio[916]: verbose: ControllerMpd::parseState Jul 13 07:43:44 volumiocentral volumio[916]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 13 07:43:44 volumiocentral volumio[916]: verbose: ControllerMpd::parseState Jul 13 07:43:44 volumiocentral volumio[916]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 13 07:43:44 volumiocentral volumio[916]: verbose: ControllerMpd::parseState Jul 13 07:43:44 volumiocentral volumio[916]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 13 07:43:44 volumiocentral volumio[916]: info: sendMpdCommand clearerror took 28 milliseconds Jul 13 07:43:44 volumiocentral volumio[916]: info: sendMpdCommand clearerror took 27 milliseconds Jul 13 07:43:44 volumiocentral volumio[916]: info: sendMpdCommand clearerror took 15 milliseconds Jul 13 07:43:44 volumiocentral volumio[916]: info: sendMpdCommand playlistinfo took 10 milliseconds Jul 13 07:43:44 volumiocentral volumio[916]: info: sendMpdCommand playlistinfo took 8 milliseconds Jul 13 07:43:44 volumiocentral volumio[916]: info: sendMpdCommand playlistinfo took 8 milliseconds Jul 13 07:43:44 volumiocentral volumio[916]: verbose: ControllerMpd::parseTrackInfo Jul 13 07:43:44 volumiocentral volumio[916]: verbose: ControllerMpd::parseTrackInfo Jul 13 07:43:44 volumiocentral volumio[916]: verbose: ControllerMpd::parseTrackInfo Jul 13 07:43:44 volumiocentral volumio[916]: info: ControllerMpd::pushState Jul 13 07:43:44 volumiocentral volumio[916]: info: CoreCommandRouter::servicePushState Jul 13 07:43:44 volumiocentral volumio[916]: info: CoreStateMachine::pushState Jul 13 07:43:44 volumiocentral volumio[916]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 13 07:43:44 volumiocentral volumio[916]: info: CoreCommandRouter::volumioPushState Jul 13 07:43:44 volumiocentral volumio[916]: info: CorePlayQueue::getTrack 0 Jul 13 07:43:44 volumiocentral volumio[916]: 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":"","artist":null,"album":null,"uri":"http://stream.absolutradio.de/hq/mp3-160/radioplayer/","trackType":""} Jul 13 07:43:44 volumiocentral volumio[916]: verbose: CURRENT POSITION 0 Jul 13 07:43:44 volumiocentral volumio[916]: info: CoreStateMachine::syncState stateService stop Jul 13 07:43:44 volumiocentral volumio[916]: info: CoreStateMachine::syncState currentStatus stop Jul 13 07:43:44 volumiocentral volumio[916]: info: CoreStateMachine::pushState Jul 13 07:43:44 volumiocentral volumio[916]: info: CoreCommandRouter::volumioPushState Jul 13 07:43:44 volumiocentral volumio[916]: info: No code Jul 13 07:43:44 volumiocentral volumio[916]: info: CoreStateMachine::pushState Jul 13 07:43:44 volumiocentral volumio[916]: info: CoreCommandRouter::volumioPushState Jul 13 07:43:44 volumiocentral volumio[916]: info: ControllerMpd::pushState Jul 13 07:43:44 volumiocentral volumio[916]: info: CoreCommandRouter::servicePushState Jul 13 07:43:44 volumiocentral volumio[916]: info: CoreStateMachine::pushState Jul 13 07:43:44 volumiocentral volumio[916]: info: CoreCommandRouter::volumioPushState Jul 13 07:43:44 volumiocentral volumio[916]: info: CorePlayQueue::getTrack 0 Jul 13 07:43:44 volumiocentral volumio[916]: 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":"","artist":null,"album":null,"uri":"http://stream.absolutradio.de/hq/mp3-160/radioplayer/","trackType":""} Jul 13 07:43:44 volumiocentral volumio[916]: verbose: CURRENT POSITION 0 Jul 13 07:43:44 volumiocentral volumio[916]: info: CoreStateMachine::syncState stateService stop Jul 13 07:43:44 volumiocentral volumio[916]: info: CoreStateMachine::syncState currentStatus stop Jul 13 07:43:44 volumiocentral volumio[916]: info: CoreStateMachine::pushState Jul 13 07:43:44 volumiocentral volumio[916]: info: CoreCommandRouter::volumioPushState Jul 13 07:43:44 volumiocentral volumio[916]: info: No code Jul 13 07:43:44 volumiocentral volumio[916]: info: CoreStateMachine::pushState Jul 13 07:43:44 volumiocentral volumio[916]: info: CoreCommandRouter::volumioPushState Jul 13 07:43:44 volumiocentral volumio[916]: info: ControllerMpd::pushState Jul 13 07:43:44 volumiocentral volumio[916]: info: CoreCommandRouter::servicePushState Jul 13 07:43:44 volumiocentral volumio[916]: info: CoreStateMachine::pushState Jul 13 07:43:44 volumiocentral volumio[916]: info: CoreCommandRouter::volumioPushState Jul 13 07:43:44 volumiocentral volumio[916]: info: CorePlayQueue::getTrack 0 Jul 13 07:43:44 volumiocentral volumio[916]: 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":"","artist":null,"album":null,"uri":"http://stream.absolutradio.de/hq/mp3-160/radioplayer/","trackType":""} Jul 13 07:43:44 volumiocentral volumio[916]: verbose: CURRENT POSITION 0 Jul 13 07:43:44 volumiocentral volumio[916]: info: CoreStateMachine::syncState stateService stop Jul 13 07:43:44 volumiocentral volumio[916]: info: CoreStateMachine::syncState currentStatus stop Jul 13 07:43:44 volumiocentral volumio[916]: info: CoreStateMachine::pushState Jul 13 07:43:44 volumiocentral volumio[916]: info: CoreCommandRouter::volumioPushState Jul 13 07:43:44 volumiocentral volumio[916]: info: No code Jul 13 07:43:44 volumiocentral volumio[916]: info: CoreStateMachine::pushState Jul 13 07:43:44 volumiocentral volumio[916]: info: CoreCommandRouter::volumioPushState Jul 13 07:43:44 volumiocentral volumio[916]: info: ------------------------------ 183ms Jul 13 07:43:44 volumiocentral volumio[916]: info: ------------------------------ 183ms Jul 13 07:43:44 volumiocentral volumio[916]: info: ------------------------------ 181ms Jul 13 07:43:44 volumiocentral volumio[916]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Jul 13 07:43:44 volumiocentral volumio[916]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Jul 13 07:43:44 volumiocentral volumio[916]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Jul 13 07:43:44 volumiocentral volumio[916]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Jul 13 07:43:44 volumiocentral volumio[916]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Jul 13 07:43:44 volumiocentral volumio[916]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Jul 13 07:43:50 volumiocentral volumio[916]: info: Preload queue cleared Jul 13 07:43:50 volumiocentral volumio[916]: info: CoreCommandRouter::volumioReplaceandPlayItems Jul 13 07:43:50 volumiocentral volumio[916]: info: CoreStateMachine::ClearQueue Jul 13 07:43:50 volumiocentral volumio[916]: info: CoreStateMachine::stop Jul 13 07:43:50 volumiocentral volumio[916]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 13 07:43:50 volumiocentral volumio[916]: info: CorePlayQueue::clearPlayQueue Jul 13 07:43:50 volumiocentral volumio[916]: info: CorePlayQueue::saveQueue Jul 13 07:43:50 volumiocentral volumio[916]: info: CoreCommandRouter::volumioPushQueue Jul 13 07:43:50 volumiocentral volumio[916]: info: CoreStateMachine::addQueueItems Jul 13 07:43:50 volumiocentral volumio[916]: info: CorePlayQueue::addQueueItems Jul 13 07:43:50 volumiocentral volumio[916]: info: Preload queue cleared Jul 13 07:43:50 volumiocentral volumio[916]: info: Adding Item to queue: http://stream.absolutradio.de/relax/mp3-160/radioplayer/ Jul 13 07:43:50 volumiocentral volumio[916]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Jul 13 07:43:50 volumiocentral volumio[916]: info: CoreCommandRouter::volumioPushQueue Jul 13 07:43:50 volumiocentral volumio[916]: info: CorePlayQueue::saveQueue Jul 13 07:43:50 volumiocentral volumio[916]: info: CoreStateMachine::updateTrackBlock Jul 13 07:43:50 volumiocentral volumio[916]: info: CorePlayQueue::getTrackBlock Jul 13 07:43:50 volumiocentral volumio[916]: info: CoreCommandRouter::volumioPlay Jul 13 07:43:50 volumiocentral volumio[916]: info: CoreStateMachine::play index 0 Jul 13 07:43:50 volumiocentral volumio[916]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 13 07:43:50 volumiocentral volumio[916]: info: CoreStateMachine::stop Jul 13 07:43:50 volumiocentral volumio[916]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 13 07:43:50 volumiocentral volumio[916]: info: CoreStateMachine::play index undefined Jul 13 07:43:50 volumiocentral volumio[916]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 13 07:43:50 volumiocentral volumio[916]: info: CorePlayQueue::getTrack 0 Jul 13 07:43:50 volumiocentral volumio[916]: info: CoreStateMachine::startPlaybackTimer Jul 13 07:43:50 volumiocentral volumio[916]: info: CorePlayQueue::getTrack 0 Jul 13 07:43:50 volumiocentral volumio[916]: info: [1720849430938] ControllerWebradio::clearAddPlayTrack Jul 13 07:43:50 volumiocentral volumio[916]: verbose: ControllerMpd::sendMpdCommand stop Jul 13 07:43:50 volumiocentral volumio[916]: info: sendMpdCommand stop took 3 milliseconds Jul 13 07:43:50 volumiocentral volumio[916]: verbose: ControllerMpd::sendMpdCommand clear Jul 13 07:43:50 volumiocentral volumio[916]: info: sendMpdCommand clear took 0 milliseconds Jul 13 07:43:50 volumiocentral volumio[916]: verbose: ControllerMpd::sendMpdCommand load "http://stream.absolutradio.de/relax/mp3-160/radioplayer/" Jul 13 07:43:50 volumiocentral volumio[916]: info: Jul 13 07:43:50 volumiocentral volumio[916]: ---------------------------- MPD announces system playlist update Jul 13 07:43:50 volumiocentral volumio[916]: info: Ignoring MPD Status Update Jul 13 07:43:50 volumiocentral volumio[916]: info: Jul 13 07:43:50 volumiocentral volumio[916]: ---------------------------- MPD announces system playlist update Jul 13 07:43:50 volumiocentral volumio[916]: info: Ignoring MPD Status Update Jul 13 07:43:50 volumiocentral volumio[916]: info: Jul 13 07:43:50 volumiocentral volumio[916]: ---------------------------- MPD announces system playlist update Jul 13 07:43:50 volumiocentral volumio[916]: info: Ignoring MPD Status Update Jul 13 07:43:50 volumiocentral volumio[916]: error: updateQueue error: null Jul 13 07:43:50 volumiocentral volumio[916]: error: updateQueue error: null Jul 13 07:43:50 volumiocentral volumio[916]: verbose: ControllerMpd::sendMpdCommand add "http://stream.absolutradio.de/relax/mp3-160/radioplayer/" Jul 13 07:43:50 volumiocentral volumio[916]: info: ------------------------------ 3ms Jul 13 07:43:50 volumiocentral volumio[916]: info: ------------------------------ 3ms Jul 13 07:43:50 volumiocentral volumio[916]: error: updateQueue error: null Jul 13 07:43:50 volumiocentral volumio[916]: info: Jul 13 07:43:50 volumiocentral volumio[916]: ---------------------------- MPD announces system playlist update Jul 13 07:43:50 volumiocentral volumio[916]: info: Ignoring MPD Status Update Jul 13 07:43:50 volumiocentral volumio[916]: info: ------------------------------ 4ms Jul 13 07:43:50 volumiocentral volumio[916]: info: sendMpdCommand add "http://stream.absolutradio.de/relax/mp3-160/radioplayer/" took 3 milliseconds Jul 13 07:43:50 volumiocentral volumio[916]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 13 07:43:50 volumiocentral volumio[916]: verbose: ControllerMpd::sendMpdCommand play Jul 13 07:43:50 volumiocentral volumio[916]: info: Jul 13 07:43:50 volumiocentral volumio[916]: ---------------------------- MPD announces system playlist update Jul 13 07:43:50 volumiocentral volumio[916]: info: Ignoring MPD Status Update Jul 13 07:43:50 volumiocentral volumio[916]: info: Jul 13 07:43:50 volumiocentral volumio[916]: ---------------------------- MPD announces system playlist update Jul 13 07:43:50 volumiocentral volumio[916]: info: Ignoring MPD Status Update Jul 13 07:43:50 volumiocentral volumio[916]: info: Jul 13 07:43:50 volumiocentral volumio[916]: ---------------------------- MPD announces state update: player Jul 13 07:43:50 volumiocentral volumio[916]: info: ------------------------------ 6ms Jul 13 07:43:50 volumiocentral volumio[916]: info: sendMpdCommand play took 5 milliseconds Jul 13 07:43:50 volumiocentral volumio[916]: info: ------------------------------ 4ms Jul 13 07:43:50 volumiocentral volumio[916]: info: ------------------------------ 3ms Jul 13 07:43:50 volumiocentral volumio[916]: info: ControllerMpd::getState Jul 13 07:43:50 volumiocentral volumio[916]: verbose: ControllerMpd::sendMpdCommand status Jul 13 07:43:50 volumiocentral volumio[916]: info: Jul 13 07:43:50 volumiocentral volumio[916]: ---------------------------- MPD announces state update: player Jul 13 07:43:50 volumiocentral volumio[916]: info: ControllerMpd::getState Jul 13 07:43:50 volumiocentral volumio[916]: verbose: ControllerMpd::sendMpdCommand status Jul 13 07:43:50 volumiocentral volumio[916]: info: Jul 13 07:43:50 volumiocentral volumio[916]: ---------------------------- MPD announces state update: player Jul 13 07:43:50 volumiocentral volumio[916]: info: ControllerMpd::getState Jul 13 07:43:50 volumiocentral volumio[916]: verbose: ControllerMpd::sendMpdCommand status Jul 13 07:43:50 volumiocentral volumio[916]: verbose: ControllerMpd::sendMpdCommand clearerror Jul 13 07:43:50 volumiocentral volumio[916]: info: sendMpdCommand status took 15 milliseconds Jul 13 07:43:50 volumiocentral volumio[916]: verbose: ControllerMpd::sendMpdCommand clearerror Jul 13 07:43:50 volumiocentral volumio[916]: info: sendMpdCommand status took 15 milliseconds Jul 13 07:43:50 volumiocentral volumio[916]: verbose: ControllerMpd::parseState Jul 13 07:43:50 volumiocentral volumio[916]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 13 07:43:50 volumiocentral volumio[916]: verbose: ControllerMpd::parseState Jul 13 07:43:50 volumiocentral volumio[916]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 13 07:43:50 volumiocentral volumio[916]: verbose: ControllerMpd::sendMpdCommand clearerror Jul 13 07:43:50 volumiocentral volumio[916]: info: sendMpdCommand status took 32 milliseconds Jul 13 07:43:50 volumiocentral volumio[916]: info: sendMpdCommand clearerror took 20 milliseconds Jul 13 07:43:50 volumiocentral volumio[916]: info: sendMpdCommand clearerror took 19 milliseconds Jul 13 07:43:50 volumiocentral volumio[916]: info: sendMpdCommand playlistinfo took 11 milliseconds Jul 13 07:43:50 volumiocentral volumio[916]: info: sendMpdCommand playlistinfo took 10 milliseconds Jul 13 07:43:50 volumiocentral volumio[916]: verbose: ControllerMpd::parseState Jul 13 07:43:50 volumiocentral volumio[916]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 13 07:43:50 volumiocentral volumio[916]: verbose: ControllerMpd::parseTrackInfo Jul 13 07:43:50 volumiocentral volumio[916]: info: ControllerMpd::pushState Jul 13 07:43:50 volumiocentral volumio[916]: info: CoreCommandRouter::servicePushState Jul 13 07:43:50 volumiocentral volumio[916]: info: CoreStateMachine::pushState Jul 13 07:43:50 volumiocentral volumio[916]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 13 07:43:50 volumiocentral volumio[916]: info: CoreCommandRouter::volumioPushState Jul 13 07:43:51 volumiocentral volumio[916]: info: CorePlayQueue::getTrack 0 Jul 13 07:43:51 volumiocentral volumio[916]: 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":"","artist":null,"album":null,"uri":"http://stream.absolutradio.de/relax/mp3-160/radioplayer/","trackType":""} Jul 13 07:43:51 volumiocentral volumio[916]: verbose: CURRENT POSITION 0 Jul 13 07:43:51 volumiocentral volumio[916]: info: CoreStateMachine::syncState stateService stop Jul 13 07:43:51 volumiocentral volumio[916]: info: CoreStateMachine::syncState currentStatus stop Jul 13 07:43:51 volumiocentral volumio[916]: info: CoreStateMachine::pushState Jul 13 07:43:51 volumiocentral volumio[916]: info: CoreCommandRouter::volumioPushState Jul 13 07:43:51 volumiocentral volumio[916]: info: No code Jul 13 07:43:51 volumiocentral volumio[916]: info: CoreStateMachine::pushState Jul 13 07:43:51 volumiocentral volumio[916]: info: CoreCommandRouter::volumioPushState Jul 13 07:43:51 volumiocentral volumio[916]: info: ControllerMpd::pushState Jul 13 07:43:51 volumiocentral volumio[916]: info: CoreCommandRouter::servicePushState Jul 13 07:43:51 volumiocentral volumio[916]: info: CoreStateMachine::pushState Jul 13 07:43:51 volumiocentral volumio[916]: info: CoreCommandRouter::volumioPushState Jul 13 07:43:51 volumiocentral volumio[916]: info: CorePlayQueue::getTrack 0 Jul 13 07:43:51 volumiocentral volumio[916]: 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":"","artist":null,"album":null,"uri":"http://stream.absolutradio.de/relax/mp3-160/radioplayer/","trackType":""} Jul 13 07:43:51 volumiocentral volumio[916]: verbose: CURRENT POSITION 0 Jul 13 07:43:51 volumiocentral volumio[916]: info: CoreStateMachine::syncState stateService stop Jul 13 07:43:51 volumiocentral volumio[916]: info: CoreStateMachine::syncState currentStatus stop Jul 13 07:43:51 volumiocentral volumio[916]: info: CoreStateMachine::pushState Jul 13 07:43:51 volumiocentral volumio[916]: info: CoreCommandRouter::volumioPushState Jul 13 07:43:51 volumiocentral volumio[916]: info: No code Jul 13 07:43:51 volumiocentral volumio[916]: info: CoreStateMachine::pushState Jul 13 07:43:51 volumiocentral volumio[916]: info: CoreCommandRouter::volumioPushState Jul 13 07:43:51 volumiocentral volumio[916]: info: ------------------------------ 112ms Jul 13 07:43:51 volumiocentral volumio[916]: info: ------------------------------ 112ms Jul 13 07:43:51 volumiocentral volumio[916]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Jul 13 07:43:51 volumiocentral volumio[916]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Jul 13 07:43:51 volumiocentral volumio[916]: info: sendMpdCommand clearerror took 102 milliseconds Jul 13 07:43:51 volumiocentral volumio[916]: info: sendMpdCommand playlistinfo took 101 milliseconds Jul 13 07:43:51 volumiocentral volumio[916]: verbose: ControllerMpd::parseTrackInfo Jul 13 07:43:51 volumiocentral volumio[916]: info: ControllerMpd::pushState Jul 13 07:43:51 volumiocentral volumio[916]: info: CoreCommandRouter::servicePushState Jul 13 07:43:51 volumiocentral volumio[916]: info: CoreStateMachine::pushState Jul 13 07:43:51 volumiocentral volumio[916]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 13 07:43:51 volumiocentral volumio[916]: info: CoreCommandRouter::volumioPushState Jul 13 07:43:51 volumiocentral volumio[916]: info: CorePlayQueue::getTrack 0 Jul 13 07:43:51 volumiocentral volumio[916]: 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":"","artist":null,"album":null,"uri":"http://stream.absolutradio.de/relax/mp3-160/radioplayer/","trackType":""} Jul 13 07:43:51 volumiocentral volumio[916]: verbose: CURRENT POSITION 0 Jul 13 07:43:51 volumiocentral volumio[916]: info: CoreStateMachine::syncState stateService stop Jul 13 07:43:51 volumiocentral volumio[916]: info: CoreStateMachine::syncState currentStatus stop Jul 13 07:43:51 volumiocentral volumio[916]: info: CoreStateMachine::pushState Jul 13 07:43:51 volumiocentral volumio[916]: info: CoreCommandRouter::volumioPushState Jul 13 07:43:51 volumiocentral volumio[916]: info: No code Jul 13 07:43:51 volumiocentral volumio[916]: info: CoreStateMachine::pushState Jul 13 07:43:51 volumiocentral volumio[916]: info: CoreCommandRouter::volumioPushState Jul 13 07:43:51 volumiocentral volumio[916]: info: ------------------------------ 184ms Jul 13 07:43:51 volumiocentral volumio[916]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Jul 13 07:43:51 volumiocentral volumio[916]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Jul 13 07:43:51 volumiocentral volumio[916]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Jul 13 07:43:58 volumiocentral volumio[916]: info: Preload queue cleared Jul 13 07:43:58 volumiocentral volumio[916]: info: CoreCommandRouter::volumioReplaceandPlayItems Jul 13 07:43:58 volumiocentral volumio[916]: info: CoreStateMachine::ClearQueue Jul 13 07:43:58 volumiocentral volumio[916]: info: CoreStateMachine::stop Jul 13 07:43:58 volumiocentral volumio[916]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 13 07:43:58 volumiocentral volumio[916]: info: CorePlayQueue::clearPlayQueue Jul 13 07:43:58 volumiocentral volumio[916]: info: CorePlayQueue::saveQueue Jul 13 07:43:58 volumiocentral volumio[916]: info: CoreCommandRouter::volumioPushQueue Jul 13 07:43:58 volumiocentral volumio[916]: info: CoreStateMachine::addQueueItems Jul 13 07:43:58 volumiocentral volumio[916]: info: CorePlayQueue::addQueueItems Jul 13 07:43:58 volumiocentral volumio[916]: info: Preload queue cleared Jul 13 07:43:58 volumiocentral volumio[916]: info: Adding Item to queue: http://95.211.3.65:8136/listen.pls?sid=1 Jul 13 07:43:58 volumiocentral volumio[916]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Jul 13 07:43:58 volumiocentral volumio[916]: info: CoreCommandRouter::volumioPushQueue Jul 13 07:43:58 volumiocentral volumio[916]: info: CorePlayQueue::saveQueue Jul 13 07:43:58 volumiocentral volumio[916]: info: CoreStateMachine::updateTrackBlock Jul 13 07:43:58 volumiocentral volumio[916]: info: CorePlayQueue::getTrackBlock Jul 13 07:43:58 volumiocentral volumio[916]: info: CoreCommandRouter::volumioPlay Jul 13 07:43:58 volumiocentral volumio[916]: info: CoreStateMachine::play index 0 Jul 13 07:43:58 volumiocentral volumio[916]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 13 07:43:58 volumiocentral volumio[916]: info: CoreStateMachine::stop Jul 13 07:43:58 volumiocentral volumio[916]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 13 07:43:58 volumiocentral volumio[916]: info: CoreStateMachine::play index undefined Jul 13 07:43:58 volumiocentral volumio[916]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 13 07:43:58 volumiocentral volumio[916]: info: CorePlayQueue::getTrack 0 Jul 13 07:43:58 volumiocentral volumio[916]: info: CoreStateMachine::startPlaybackTimer Jul 13 07:43:58 volumiocentral volumio[916]: info: CorePlayQueue::getTrack 0 Jul 13 07:43:58 volumiocentral volumio[916]: info: [1720849438776] ControllerWebradio::clearAddPlayTrack Jul 13 07:43:58 volumiocentral volumio[916]: verbose: ControllerMpd::sendMpdCommand stop Jul 13 07:43:58 volumiocentral volumio[916]: info: sendMpdCommand stop took 4 milliseconds Jul 13 07:43:58 volumiocentral volumio[916]: verbose: ControllerMpd::sendMpdCommand clear Jul 13 07:43:58 volumiocentral volumio[916]: info: Jul 13 07:43:58 volumiocentral volumio[916]: ---------------------------- MPD announces system playlist update Jul 13 07:43:58 volumiocentral volumio[916]: info: Ignoring MPD Status Update Jul 13 07:43:58 volumiocentral volumio[916]: info: sendMpdCommand clear took 7 milliseconds Jul 13 07:43:58 volumiocentral volumio[916]: verbose: ControllerMpd::sendMpdCommand load "http://95.211.3.65:8136/listen.pls?sid=1" Jul 13 07:43:58 volumiocentral volumio[916]: info: Jul 13 07:43:58 volumiocentral volumio[916]: ---------------------------- MPD announces system playlist update Jul 13 07:43:58 volumiocentral volumio[916]: info: Ignoring MPD Status Update Jul 13 07:43:58 volumiocentral volumio[916]: info: Jul 13 07:43:58 volumiocentral volumio[916]: ---------------------------- MPD announces system playlist update Jul 13 07:43:58 volumiocentral volumio[916]: info: Ignoring MPD Status Update Jul 13 07:43:58 volumiocentral volumio[916]: error: updateQueue error: null Jul 13 07:43:58 volumiocentral volumio[916]: info: ------------------------------ 53ms Jul 13 07:43:58 volumiocentral volumio[916]: verbose: ControllerMpd::sendMpdCommand add "http://95.211.3.65:8136/listen.pls?sid=1" Jul 13 07:43:58 volumiocentral volumio[916]: error: updateQueue error: null Jul 13 07:43:58 volumiocentral volumio[916]: error: updateQueue error: null Jul 13 07:43:58 volumiocentral volumio[916]: info: Jul 13 07:43:58 volumiocentral volumio[916]: ---------------------------- MPD announces system playlist update Jul 13 07:43:58 volumiocentral volumio[916]: info: Ignoring MPD Status Update Jul 13 07:43:58 volumiocentral volumio[916]: info: ------------------------------ 48ms Jul 13 07:43:58 volumiocentral volumio[916]: info: ------------------------------ 46ms Jul 13 07:43:58 volumiocentral volumio[916]: info: sendMpdCommand add "http://95.211.3.65:8136/listen.pls?sid=1" took 3 milliseconds Jul 13 07:43:58 volumiocentral volumio[916]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 13 07:43:58 volumiocentral volumio[916]: verbose: ControllerMpd::sendMpdCommand play Jul 13 07:43:58 volumiocentral volumio[916]: info: Jul 13 07:43:58 volumiocentral volumio[916]: ---------------------------- MPD announces system playlist update Jul 13 07:43:58 volumiocentral volumio[916]: info: Ignoring MPD Status Update Jul 13 07:43:58 volumiocentral volumio[916]: info: Jul 13 07:43:58 volumiocentral volumio[916]: ---------------------------- MPD announces system playlist update Jul 13 07:43:58 volumiocentral volumio[916]: info: Ignoring MPD Status Update Jul 13 07:43:58 volumiocentral volumio[916]: info: ------------------------------ 8ms Jul 13 07:43:58 volumiocentral volumio[916]: info: sendMpdCommand play took 8 milliseconds Jul 13 07:43:58 volumiocentral volumio[916]: info: ------------------------------ 6ms Jul 13 07:43:58 volumiocentral volumio[916]: info: ------------------------------ 6ms Jul 13 07:43:58 volumiocentral volumio[916]: info: Jul 13 07:43:58 volumiocentral volumio[916]: ---------------------------- MPD announces state update: player Jul 13 07:43:58 volumiocentral volumio[916]: info: ControllerMpd::getState Jul 13 07:43:58 volumiocentral volumio[916]: verbose: ControllerMpd::sendMpdCommand status Jul 13 07:43:58 volumiocentral volumio[916]: info: Jul 13 07:43:58 volumiocentral volumio[916]: ---------------------------- MPD announces state update: player Jul 13 07:43:58 volumiocentral volumio[916]: info: ControllerMpd::getState Jul 13 07:43:58 volumiocentral volumio[916]: verbose: ControllerMpd::sendMpdCommand status Jul 13 07:43:58 volumiocentral volumio[916]: verbose: ControllerMpd::sendMpdCommand clearerror Jul 13 07:43:58 volumiocentral volumio[916]: info: sendMpdCommand status took 11 milliseconds Jul 13 07:43:58 volumiocentral volumio[916]: verbose: ControllerMpd::sendMpdCommand clearerror Jul 13 07:43:58 volumiocentral volumio[916]: info: sendMpdCommand status took 11 milliseconds Jul 13 07:43:58 volumiocentral volumio[916]: verbose: ControllerMpd::parseState Jul 13 07:43:58 volumiocentral volumio[916]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 13 07:43:58 volumiocentral volumio[916]: verbose: ControllerMpd::parseState Jul 13 07:43:58 volumiocentral volumio[916]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 13 07:43:58 volumiocentral volumio[916]: info: Jul 13 07:43:58 volumiocentral volumio[916]: ---------------------------- MPD announces state update: player Jul 13 07:43:58 volumiocentral volumio[916]: info: ControllerMpd::getState Jul 13 07:43:58 volumiocentral volumio[916]: verbose: ControllerMpd::sendMpdCommand status Jul 13 07:43:58 volumiocentral volumio[916]: info: sendMpdCommand clearerror took 21 milliseconds Jul 13 07:43:58 volumiocentral volumio[916]: info: sendMpdCommand clearerror took 20 milliseconds Jul 13 07:43:58 volumiocentral volumio[916]: info: sendMpdCommand playlistinfo took 10 milliseconds Jul 13 07:43:58 volumiocentral volumio[916]: info: sendMpdCommand playlistinfo took 9 milliseconds Jul 13 07:43:58 volumiocentral volumio[916]: info: sendMpdCommand status took 4 milliseconds Jul 13 07:43:58 volumiocentral volumio[916]: verbose: ControllerMpd::parseTrackInfo Jul 13 07:43:58 volumiocentral volumio[916]: verbose: ControllerMpd::parseState Jul 13 07:43:58 volumiocentral volumio[916]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 13 07:43:58 volumiocentral volumio[916]: info: ControllerMpd::pushState Jul 13 07:43:58 volumiocentral volumio[916]: info: CoreCommandRouter::servicePushState Jul 13 07:43:58 volumiocentral volumio[916]: info: CoreStateMachine::pushState Jul 13 07:43:58 volumiocentral volumio[916]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 13 07:43:58 volumiocentral volumio[916]: info: CoreCommandRouter::volumioPushState Jul 13 07:43:58 volumiocentral volumio[916]: info: CorePlayQueue::getTrack 0 Jul 13 07:43:58 volumiocentral volumio[916]: 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":"listen.pls?sid=1","artist":null,"album":null,"uri":"http://95.211.3.65:8136/listen.pls?sid=1","trackType":"pls"} Jul 13 07:43:58 volumiocentral volumio[916]: verbose: CURRENT POSITION 0 Jul 13 07:43:58 volumiocentral volumio[916]: info: CoreStateMachine::syncState stateService stop Jul 13 07:43:58 volumiocentral volumio[916]: info: CoreStateMachine::syncState currentStatus stop Jul 13 07:43:58 volumiocentral volumio[916]: info: CoreStateMachine::pushState Jul 13 07:43:58 volumiocentral volumio[916]: info: CoreCommandRouter::volumioPushState Jul 13 07:43:58 volumiocentral volumio[916]: info: No code Jul 13 07:43:58 volumiocentral volumio[916]: info: CoreStateMachine::pushState Jul 13 07:43:58 volumiocentral volumio[916]: info: CoreCommandRouter::volumioPushState Jul 13 07:43:58 volumiocentral volumio[916]: info: ControllerMpd::pushState Jul 13 07:43:58 volumiocentral volumio[916]: info: CoreCommandRouter::servicePushState Jul 13 07:43:58 volumiocentral volumio[916]: info: CoreStateMachine::pushState Jul 13 07:43:58 volumiocentral volumio[916]: info: CoreCommandRouter::volumioPushState Jul 13 07:43:58 volumiocentral volumio[916]: info: CorePlayQueue::getTrack 0 Jul 13 07:43:58 volumiocentral volumio[916]: 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":"listen.pls?sid=1","artist":null,"album":null,"uri":"http://95.211.3.65:8136/listen.pls?sid=1","trackType":"pls"} Jul 13 07:43:58 volumiocentral volumio[916]: verbose: CURRENT POSITION 0 Jul 13 07:43:58 volumiocentral volumio[916]: info: CoreStateMachine::syncState stateService stop Jul 13 07:43:58 volumiocentral volumio[916]: info: CoreStateMachine::syncState currentStatus stop Jul 13 07:43:58 volumiocentral volumio[916]: info: CoreStateMachine::pushState Jul 13 07:43:58 volumiocentral volumio[916]: info: CoreCommandRouter::volumioPushState Jul 13 07:43:58 volumiocentral volumio[916]: info: No code Jul 13 07:43:58 volumiocentral volumio[916]: info: CoreStateMachine::pushState Jul 13 07:43:58 volumiocentral volumio[916]: info: CoreCommandRouter::volumioPushState Jul 13 07:43:59 volumiocentral volumio[916]: info: ------------------------------ 118ms Jul 13 07:43:59 volumiocentral volumio[916]: info: ------------------------------ 117ms Jul 13 07:43:59 volumiocentral volumio[916]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Jul 13 07:43:59 volumiocentral volumio[916]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Jul 13 07:43:59 volumiocentral volumio[916]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Jul 13 07:43:59 volumiocentral volumio[916]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Jul 13 07:43:59 volumiocentral volumio[916]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Jul 13 07:43:59 volumiocentral volumio[916]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Jul 13 07:43:59 volumiocentral volumio[916]: info: sendMpdCommand playlistinfo took 98 milliseconds Jul 13 07:43:59 volumiocentral volumio[916]: verbose: ControllerMpd::parseTrackInfo Jul 13 07:43:59 volumiocentral volumio[916]: info: ControllerMpd::pushState Jul 13 07:43:59 volumiocentral volumio[916]: info: CoreCommandRouter::servicePushState Jul 13 07:43:59 volumiocentral volumio[916]: info: CoreStateMachine::pushState Jul 13 07:43:59 volumiocentral volumio[916]: info: CoreCommandRouter::volumioPushState Jul 13 07:43:59 volumiocentral volumio[916]: info: CorePlayQueue::getTrack 0 Jul 13 07:43:59 volumiocentral volumio[916]: 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":"listen.pls?sid=1","artist":null,"album":null,"uri":"http://95.211.3.65:8136/listen.pls?sid=1","trackType":"pls"} Jul 13 07:43:59 volumiocentral volumio[916]: verbose: CURRENT POSITION 0 Jul 13 07:43:59 volumiocentral volumio[916]: info: CoreStateMachine::syncState stateService stop Jul 13 07:43:59 volumiocentral volumio[916]: info: CoreStateMachine::syncState currentStatus stop Jul 13 07:43:59 volumiocentral volumio[916]: info: CoreStateMachine::pushState Jul 13 07:43:59 volumiocentral volumio[916]: info: CoreCommandRouter::volumioPushState Jul 13 07:43:59 volumiocentral volumio[916]: info: No code Jul 13 07:43:59 volumiocentral volumio[916]: info: CoreStateMachine::pushState Jul 13 07:43:59 volumiocentral volumio[916]: info: CoreCommandRouter::volumioPushState Jul 13 07:43:59 volumiocentral volumio[916]: info: ------------------------------ 156ms Jul 13 07:43:59 volumiocentral volumio[916]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Jul 13 07:43:59 volumiocentral volumio[916]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Jul 13 07:43:59 volumiocentral volumio[916]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Jul 13 07:44:09 volumiocentral volumio[916]: info: CoreCommandRouter::volumioGetState Jul 13 07:44:12 volumiocentral volumio[916]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 13 07:44:12 volumiocentral volumio[916]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jul 13 07:44:22 volumiocentral volumio[916]: info: Enabling plugin music_services_shield Jul 13 07:44:22 volumiocentral volumio[916]: info: Loading plugin "music_services_shield"... Jul 13 07:44:23 volumiocentral volumio[916]: info: Applying required configuration parameters for plugin music_services_shield Jul 13 07:44:23 volumiocentral volumio[916]: info: PLUGIN START: music_services_shield Jul 13 07:44:23 volumiocentral sudo[1954]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/data/plugins/system_hardware/music_services_shield/moveallprocesses.sh Jul 13 07:44:23 volumiocentral sudo[1954]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 13 07:44:23 volumiocentral kernel: new mount options do not match the existing superblock, will be ignored Jul 13 07:44:23 volumiocentral sudo[1954]: pam_unix(sudo:session): session closed for user root Jul 13 07:44:24 volumiocentral volumio[916]: info: Done. Jul 13 07:44:24 volumiocentral sudo[1974]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/data/plugins/system_hardware/music_services_shield/addservice.sh Jul 13 07:44:24 volumiocentral sudo[1974]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 13 07:44:24 volumiocentral systemd[1]: Starting Shield Volumio Music Services in the User CPU Set... Jul 13 07:44:24 volumiocentral volumio[1978]: cset: --> shielding system active with Jul 13 07:44:24 volumiocentral volumio[1978]: cset: "system" cpuset of CPUSPEC(1) with 184 tasks running Jul 13 07:44:24 volumiocentral volumio[1978]: cset: "user" cpuset of CPUSPEC(0) with 6 tasks running Jul 13 07:44:24 volumiocentral systemd[1]: Started Shield Volumio Music Services in the User CPU Set. Jul 13 07:44:24 volumiocentral systemd[1]: Reloading. Jul 13 07:44:25 volumiocentral systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Jul 13 07:44:25 volumiocentral systemd[1]: getty@tty1.service: Current command vanished from the unit file, execution of the command list won't be resumed. Jul 13 07:44:25 volumiocentral sudo[1974]: pam_unix(sudo:session): session closed for user root Jul 13 07:44:25 volumiocentral volumio[916]: info: succeeded Jul 13 07:44:29 volumiocentral volumio[916]: info: CoreCommandRouter::volumioGetState Jul 13 07:44:35 volumiocentral volumio[916]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jul 13 07:44:35 volumiocentral volumio[916]: info: CURURI: music-library Jul 13 07:44:35 volumiocentral volumio[916]: info: Preload queue cleared Jul 13 07:44:37 volumiocentral volumio[916]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jul 13 07:44:37 volumiocentral volumio[916]: info: CURURI: music-library/USB Jul 13 07:44:37 volumiocentral volumio[916]: info: Preload queue cleared Jul 13 07:44:38 volumiocentral volumio[916]: info: Preload queue cleared Jul 13 07:44:38 volumiocentral volumio[916]: info: CoreCommandRouter::volumioReplaceandPlayItems Jul 13 07:44:38 volumiocentral volumio[916]: info: CoreStateMachine::ClearQueue Jul 13 07:44:38 volumiocentral volumio[916]: info: CoreStateMachine::stop Jul 13 07:44:38 volumiocentral volumio[916]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 13 07:44:38 volumiocentral volumio[916]: info: CorePlayQueue::clearPlayQueue Jul 13 07:44:38 volumiocentral volumio[916]: info: CorePlayQueue::saveQueue Jul 13 07:44:38 volumiocentral volumio[916]: info: CoreCommandRouter::volumioPushQueue Jul 13 07:44:38 volumiocentral volumio[916]: info: CoreStateMachine::addQueueItems Jul 13 07:44:38 volumiocentral volumio[916]: info: CorePlayQueue::addQueueItems Jul 13 07:44:38 volumiocentral volumio[916]: info: Preload queue cleared Jul 13 07:44:38 volumiocentral volumio[916]: info: Adding Item to queue: music-library/USB/7703ED0C0CBCB005 Jul 13 07:44:38 volumiocentral volumio[916]: info: Exploding uri music-library/USB/7703ED0C0CBCB005 in service mpd Jul 13 07:44:40 volumiocentral volumio[916]: info: ALBUMART /albumart?cacheid=714&web=Elder%20%26%20Kadavar/ELDOVAR%3A%20A%20Story%20of%20Darkness%20%26%20Light/extralarge&path=%2Fmnt%2FUSB%2F7703ED0C0CBCB005%2FElder%20%26%20Kadavar%20-%20From%20Deep%20Within.flac&metadata=false Jul 13 07:44:40 volumiocentral volumio[916]: info: URI /mnt/USB/7703ED0C0CBCB005/Elder & Kadavar - From Deep Within.flac Jul 13 07:44:40 volumiocentral volumio[916]: info: ALBUMART /albumart?cacheid=714&web=io%2C%20me//extralarge&path=%2Fmnt%2FUSB%2F7703ED0C0CBCB005%2FKarmaExperience%2F1hBaseMeditazioneNeutra.mp3&metadata=false Jul 13 07:44:40 volumiocentral volumio[916]: info: URI /mnt/USB/7703ED0C0CBCB005/KarmaExperience/1hBaseMeditazioneNeutra.mp3 Jul 13 07:44:40 volumiocentral volumio[916]: info: ALBUMART /albumart?cacheid=714&web=io%2C%20me//extralarge&path=%2Fmnt%2FUSB%2F7703ED0C0CBCB005%2FKarmaExperience%2FAudioMeditSufiCuore.mp3&metadata=false Jul 13 07:44:40 volumiocentral volumio[916]: info: URI /mnt/USB/7703ED0C0CBCB005/KarmaExperience/AudioMeditSufiCuore.mp3 Jul 13 07:44:40 volumiocentral volumio[916]: info: ALBUMART /albumart?cacheid=714&web=//extralarge&path=%2Fmnt%2FUSB%2F7703ED0C0CBCB005%2FKarmaExperience%2FAudioMeditazionePuliziaKarmaFamigliare.mp3&metadata=false Jul 13 07:44:40 volumiocentral volumio[916]: info: URI /mnt/USB/7703ED0C0CBCB005/KarmaExperience/AudioMeditazionePuliziaKarmaFamigliare.mp3 Jul 13 07:44:40 volumiocentral volumio[916]: info: ALBUMART /albumart?cacheid=714&web=//extralarge&path=%2Fmnt%2FUSB%2F7703ED0C0CBCB005%2FKarmaExperience%2FCampioniAudioMeditazione%2FMeditazionePuliziaKarmaFamigliare.mkv&metadata=false Jul 13 07:44:40 volumiocentral volumio[916]: info: URI /mnt/USB/7703ED0C0CBCB005/KarmaExperience/CampioniAudioMeditazione/MeditazionePuliziaKarmaFamigliare.mkv Jul 13 07:44:40 volumiocentral volumio[916]: info: ALBUMART /albumart?cacheid=714&web=//extralarge&path=%2Fmnt%2FUSB%2F7703ED0C0CBCB005%2FKarmaExperience%2FCampioniAudioMeditazione%2FMeditazionePuliziaKarmaFamigliare.mp3&metadata=false Jul 13 07:44:40 volumiocentral volumio[916]: info: URI /mnt/USB/7703ED0C0CBCB005/KarmaExperience/CampioniAudioMeditazione/MeditazionePuliziaKarmaFamigliare.mp3 Jul 13 07:44:40 volumiocentral volumio[916]: info: ALBUMART /albumart?cacheid=714&web=//extralarge&path=%2Fmnt%2FUSB%2F7703ED0C0CBCB005%2FKarmaExperience%2FCampioniAudioMeditazione%2FTingsha%20Bells%20Demo.mp3&metadata=false Jul 13 07:44:40 volumiocentral volumio[916]: info: URI /mnt/USB/7703ED0C0CBCB005/KarmaExperience/CampioniAudioMeditazione/Tingsha Bells Demo.mp3 Jul 13 07:44:40 volumiocentral volumio[916]: info: ALBUMART /albumart?cacheid=714&web=//extralarge&path=%2Fmnt%2FUSB%2F7703ED0C0CBCB005%2FKarmaExperience%2FESERCIZIO%20SUFI%20SUL%20CUORE.mp4&metadata=false Jul 13 07:44:40 volumiocentral volumio[916]: info: URI /mnt/USB/7703ED0C0CBCB005/KarmaExperience/ESERCIZIO SUFI SUL CUORE.mp4 Jul 13 07:44:40 volumiocentral volumio[916]: info: ALBUMART /albumart?cacheid=714&web=//extralarge&path=%2Fmnt%2FUSB%2F7703ED0C0CBCB005%2FKarmaExperience%2FEsercizioSufi%202-1-2-1-2-1.mp3&metadata=false Jul 13 07:44:40 volumiocentral volumio[916]: info: URI /mnt/USB/7703ED0C0CBCB005/KarmaExperience/EsercizioSufi 2-1-2-1-2-1.mp3 Jul 13 07:44:40 volumiocentral volumio[916]: info: ALBUMART /albumart?cacheid=714&web=io%2C%20me//extralarge&path=%2Fmnt%2FUSB%2F7703ED0C0CBCB005%2FKarmaExperience%2FEsercizioSufi%202-1-2-1-2-1_Estesa.mp3&metadata=false Jul 13 07:44:40 volumiocentral volumio[916]: info: URI /mnt/USB/7703ED0C0CBCB005/KarmaExperience/EsercizioSufi 2-1-2-1-2-1_Estesa.mp3 Jul 13 07:44:40 volumiocentral volumio[916]: info: ALBUMART /albumart?cacheid=714&web=io%2C%20me//extralarge&path=%2Fmnt%2FUSB%2F7703ED0C0CBCB005%2FKarmaExperience%2FKarmaExperience%2F1hBaseMeditazioneNeutra.mp3&metadata=false Jul 13 07:44:40 volumiocentral volumio[916]: info: URI /mnt/USB/7703ED0C0CBCB005/KarmaExperience/KarmaExperience/1hBaseMeditazioneNeutra.mp3 Jul 13 07:44:40 volumiocentral volumio[916]: info: ALBUMART /albumart?cacheid=714&web=io%2C%20me//extralarge&path=%2Fmnt%2FUSB%2F7703ED0C0CBCB005%2FKarmaExperience%2FKarmaExperience%2FAudioMeditSufiCuore.mp3&metadata=false Jul 13 07:44:40 volumiocentral volumio[916]: info: URI /mnt/USB/7703ED0C0CBCB005/KarmaExperience/KarmaExperience/AudioMeditSufiCuore.mp3 Jul 13 07:44:40 volumiocentral volumio[916]: info: ALBUMART /albumart?cacheid=714&web=//extralarge&path=%2Fmnt%2FUSB%2F7703ED0C0CBCB005%2FKarmaExperience%2FKarmaExperience%2FAudioMeditazionePuliziaKarmaFamigliare.mp3&metadata=false Jul 13 07:44:40 volumiocentral volumio[916]: info: URI /mnt/USB/7703ED0C0CBCB005/KarmaExperience/KarmaExperience/AudioMeditazionePuliziaKarmaFamigliare.mp3 Jul 13 07:44:40 volumiocentral volumio[916]: info: ALBUMART /albumart?cacheid=714&web=//extralarge&path=%2Fmnt%2FUSB%2F7703ED0C0CBCB005%2FKarmaExperience%2FKarmaExperience%2FCampioniAudioMeditazione%2FMeditazionePuliziaKarmaFamigliare.mkv&metadata=false Jul 13 07:44:40 volumiocentral volumio[916]: info: URI /mnt/USB/7703ED0C0CBCB005/KarmaExperience/KarmaExperience/CampioniAudioMeditazione/MeditazionePuliziaKarmaFamigliare.mkv Jul 13 07:44:40 volumiocentral volumio[916]: info: ALBUMART /albumart?cacheid=714&web=//extralarge&path=%2Fmnt%2FUSB%2F7703ED0C0CBCB005%2FKarmaExperience%2FKarmaExperience%2FCampioniAudioMeditazione%2FMeditazionePuliziaKarmaFamigliare.mp3&metadata=false Jul 13 07:44:40 volumiocentral volumio[916]: info: URI /mnt/USB/7703ED0C0CBCB005/KarmaExperience/KarmaExperience/CampioniAudioMeditazione/MeditazionePuliziaKarmaFamigliare.mp3 Jul 13 07:44:40 volumiocentral volumio[916]: info: ALBUMART /albumart?cacheid=714&web=//extralarge&path=%2Fmnt%2FUSB%2F7703ED0C0CBCB005%2FKarmaExperience%2FKarmaExperience%2FCampioniAudioMeditazione%2FTingsha%20Bells%20Demo.mp3&metadata=false Jul 13 07:44:40 volumiocentral volumio[916]: info: URI /mnt/USB/7703ED0C0CBCB005/KarmaExperience/KarmaExperience/CampioniAudioMeditazione/Tingsha Bells Demo.mp3 Jul 13 07:44:40 volumiocentral volumio[916]: info: ALBUMART /albumart?cacheid=714&web=//extralarge&path=%2Fmnt%2FUSB%2F7703ED0C0CBCB005%2FKarmaExperience%2FKarmaExperience%2FESERCIZIO%20SUFI%20SUL%20CUORE.mp4&metadata=false Jul 13 07:44:40 volumiocentral volumio[916]: info: URI /mnt/USB/7703ED0C0CBCB005/KarmaExperience/KarmaExperience/ESERCIZIO SUFI SUL CUORE.mp4 Jul 13 07:44:40 volumiocentral volumio[916]: info: ALBUMART /albumart?cacheid=714&web=//extralarge&path=%2Fmnt%2FUSB%2F7703ED0C0CBCB005%2FKarmaExperience%2FKarmaExperience%2FEsercizioSufi%202-1-2-1-2-1.mp3&metadata=false Jul 13 07:44:40 volumiocentral volumio[916]: info: URI /mnt/USB/7703ED0C0CBCB005/KarmaExperience/KarmaExperience/EsercizioSufi 2-1-2-1-2-1.mp3 Jul 13 07:44:40 volumiocentral volumio[916]: info: ALBUMART /albumart?cacheid=714&web=io%2C%20me//extralarge&path=%2Fmnt%2FUSB%2F7703ED0C0CBCB005%2FKarmaExperience%2FKarmaExperience%2FEsercizioSufi%202-1-2-1-2-1_Estesa.mp3&metadata=false Jul 13 07:44:40 volumiocentral volumio[916]: info: URI /mnt/USB/7703ED0C0CBCB005/KarmaExperience/KarmaExperience/EsercizioSufi 2-1-2-1-2-1_Estesa.mp3 Jul 13 07:44:40 volumiocentral volumio[916]: info: ALBUMART /albumart?cacheid=714&web=//extralarge&path=%2Fmnt%2FUSB%2F7703ED0C0CBCB005%2FKarmaExperience%2FKarmaExperience%2FKarmaExperience%2FAudioMeditazionePuliziaKarmaFamigliare.mp3&metadata=false Jul 13 07:44:40 volumiocentral volumio[916]: info: URI /mnt/USB/7703ED0C0CBCB005/KarmaExperience/KarmaExperience/KarmaExperience/AudioMeditazionePuliziaKarmaFamigliare.mp3 Jul 13 07:44:40 volumiocentral volumio[916]: info: ALBUMART /albumart?cacheid=714&web=//extralarge&path=%2Fmnt%2FUSB%2F7703ED0C0CBCB005%2FKarmaExperience%2FKarmaExperience%2FKarmaExperience%2FCampioniAudioMeditazione%2FMeditazionePuliziaKarmaFamigliare.mkv&metadata=false Jul 13 07:44:40 volumiocentral volumio[916]: info: URI /mnt/USB/7703ED0C0CBCB005/KarmaExperience/KarmaExperience/KarmaExperience/CampioniAudioMeditazione/MeditazionePuliziaKarmaFamigliare.mkv Jul 13 07:44:40 volumiocentral volumio[916]: info: ALBUMART /albumart?cacheid=714&web=//extralarge&path=%2Fmnt%2FUSB%2F7703ED0C0CBCB005%2FKarmaExperience%2FKarmaExperience%2FKarmaExperience%2FCampioniAudioMeditazione%2FMeditazionePuliziaKarmaFamigliare.mp3&metadata=false Jul 13 07:44:40 volumiocentral volumio[916]: info: URI /mnt/USB/7703ED0C0CBCB005/KarmaExperience/KarmaExperience/KarmaExperience/CampioniAudioMeditazione/MeditazionePuliziaKarmaFamigliare.mp3 Jul 13 07:44:40 volumiocentral volumio[916]: info: ALBUMART /albumart?cacheid=714&web=//extralarge&path=%2Fmnt%2FUSB%2F7703ED0C0CBCB005%2FKarmaExperience%2FKarmaExperience%2FKarmaExperience%2FCampioniAudioMeditazione%2FTingsha%20Bells%20Demo.mp3&metadata=false Jul 13 07:44:40 volumiocentral volumio[916]: info: URI /mnt/USB/7703ED0C0CBCB005/KarmaExperience/KarmaExperience/KarmaExperience/CampioniAudioMeditazione/Tingsha Bells Demo.mp3 Jul 13 07:44:40 volumiocentral volumio[916]: info: ALBUMART /albumart?cacheid=714&web=//extralarge&path=%2Fmnt%2FUSB%2F7703ED0C0CBCB005%2FKarmaExperience%2FKarmaExperience%2FKarmaExperience%2FESERCIZIO%20SUFI%20SUL%20CUORE.mp4&metadata=false Jul 13 07:44:40 volumiocentral volumio[916]: info: URI /mnt/USB/7703ED0C0CBCB005/KarmaExperience/KarmaExperience/KarmaExperience/ESERCIZIO SUFI SUL CUORE.mp4 Jul 13 07:44:40 volumiocentral volumio[916]: info: ALBUMART /albumart?cacheid=714&web=//extralarge&path=%2Fmnt%2FUSB%2F7703ED0C0CBCB005%2FKarmaExperience%2FKarmaExperience%2FKarmaExperience%2FEsercizioSufi%202-1-2-1-2-1.mp3&metadata=false Jul 13 07:44:40 volumiocentral volumio[916]: info: URI /mnt/USB/7703ED0C0CBCB005/KarmaExperience/KarmaExperience/KarmaExperience/EsercizioSufi 2-1-2-1-2-1.mp3 Jul 13 07:44:40 volumiocentral volumio[916]: info: ALBUMART /albumart?cacheid=714&web=//extralarge&path=%2Fmnt%2FUSB%2F7703ED0C0CBCB005%2FKarmaExperience%2FKarmaExperience%2FKarmaExperience%2FMED.%20CUORE-VALORE.m4a&metadata=false Jul 13 07:44:40 volumiocentral volumio[916]: info: URI /mnt/USB/7703ED0C0CBCB005/KarmaExperience/KarmaExperience/KarmaExperience/MED. CUORE-VALORE.m4a Jul 13 07:44:40 volumiocentral volumio[916]: info: ALBUMART /albumart?cacheid=714&web=//extralarge&path=%2Fmnt%2FUSB%2F7703ED0C0CBCB005%2FKarmaExperience%2FKarmaExperience%2FKarmaExperience%2FMED._EMOTIONAL_RESCUE_PLUS.mp3&metadata=false Jul 13 07:44:40 volumiocentral volumio[916]: info: URI /mnt/USB/7703ED0C0CBCB005/KarmaExperience/KarmaExperience/KarmaExperience/MED._EMOTIONAL_RESCUE_PLUS.mp3 Jul 13 07:44:40 volumiocentral volumio[916]: info: ALBUMART /albumart?cacheid=714&web=//extralarge&path=%2Fmnt%2FUSB%2F7703ED0C0CBCB005%2FKarmaExperience%2FKarmaExperience%2FKarmaExperience%2FMeditazionePuliziaEmozionale.mp3&metadata=false Jul 13 07:44:40 volumiocentral volumio[916]: info: URI /mnt/USB/7703ED0C0CBCB005/KarmaExperience/KarmaExperience/KarmaExperience/MeditazionePuliziaEmozionale.mp3 Jul 13 07:44:40 volumiocentral volumio[916]: info: ALBUMART /albumart?cacheid=714&web=//extralarge&path=%2Fmnt%2FUSB%2F7703ED0C0CBCB005%2FKarmaExperience%2FKarmaExperience%2FMED.%20CUORE-VALORE.m4a&metadata=false Jul 13 07:44:40 volumiocentral volumio[916]: info: URI /mnt/USB/7703ED0C0CBCB005/KarmaExperience/KarmaExperience/MED. CUORE-VALORE.m4a Jul 13 07:44:40 volumiocentral volumio[916]: info: ALBUMART /albumart?cacheid=714&web=//extralarge&path=%2Fmnt%2FUSB%2F7703ED0C0CBCB005%2FKarmaExperience%2FKarmaExperience%2FMED.%20CUORE-VALORE.mp3&metadata=false Jul 13 07:44:40 volumiocentral volumio[916]: info: URI /mnt/USB/7703ED0C0CBCB005/KarmaExperience/KarmaExperience/MED. CUORE-VALORE.mp3 Jul 13 07:44:40 volumiocentral volumio[916]: info: ALBUMART /albumart?cacheid=714&web=//extralarge&path=%2Fmnt%2FUSB%2F7703ED0C0CBCB005%2FKarmaExperience%2FKarmaExperience%2FMED._EMOTIONAL_RESCUE_PLUS.mp3&metadata=false Jul 13 07:44:40 volumiocentral volumio[916]: info: URI /mnt/USB/7703ED0C0CBCB005/KarmaExperience/KarmaExperience/MED._EMOTIONAL_RESCUE_PLUS.mp3 Jul 13 07:44:40 volumiocentral volumio[916]: info: ALBUMART /albumart?cacheid=714&web=//extralarge&path=%2Fmnt%2FUSB%2F7703ED0C0CBCB005%2FKarmaExperience%2FKarmaExperience%2FMeditazionePuliziaEmozionale.mp3&metadata=false Jul 13 07:44:40 volumiocentral volumio[916]: info: URI /mnt/USB/7703ED0C0CBCB005/KarmaExperience/KarmaExperience/MeditazionePuliziaEmozionale.mp3 Jul 13 07:44:40 volumiocentral volumio[916]: info: ALBUMART /albumart?cacheid=714&web=//extralarge&path=%2Fmnt%2FUSB%2F7703ED0C0CBCB005%2FKarmaExperience%2FKarmaExperience%2FWakeUp%2FAUDIO%20MED.%20WAKE%20UP%2001.mp3&metadata=false Jul 13 07:44:40 volumiocentral volumio[916]: info: URI /mnt/USB/7703ED0C0CBCB005/KarmaExperience/KarmaExperience/WakeUp/AUDIO MED. WAKE UP 01.mp3 Jul 13 07:44:40 volumiocentral volumio[916]: info: ALBUMART /albumart?cacheid=714&web=//extralarge&path=%2Fmnt%2FUSB%2F7703ED0C0CBCB005%2FKarmaExperience%2FKarmaExperience%2FWakeUp%2FMED.%2001%20WAKE%20UP%20NUOVO.mp4&metadata=false Jul 13 07:44:40 volumiocentral volumio[916]: info: URI /mnt/USB/7703ED0C0CBCB005/KarmaExperience/KarmaExperience/WakeUp/MED. 01 WAKE UP NUOVO.mp4 Jul 13 07:44:40 volumiocentral volumio[916]: info: ALBUMART /albumart?cacheid=714&web=//extralarge&path=%2Fmnt%2FUSB%2F7703ED0C0CBCB005%2FKarmaExperience%2FKarmaExperience%2FWakeUp%2Fcentro-studi-umanistici_med-att-circolo-ottobre-2023-mp4_2023-11-02_1522.zip&metadata=false Jul 13 07:44:40 volumiocentral volumio[916]: info: URI /mnt/USB/7703ED0C0CBCB005/KarmaExperience/KarmaExperience/WakeUp/centro-studi-umanistici_med-att-circolo-ottobre-2023-mp4_2023-11-02_1522.zip Jul 13 07:44:40 volumiocentral volumio[916]: info: ALBUMART /albumart?cacheid=714&web=//extralarge&path=%2Fmnt%2FUSB%2F7703ED0C0CBCB005%2FKarmaExperience%2FKarmaExperience%2FWakeUp%2Fcentro-studi-umanistici_med-att-circolo-ottobre-2023-mp4_2023-11-02_1522.zip&metadata=false Jul 13 07:44:40 volumiocentral volumio[916]: info: URI /mnt/USB/7703ED0C0CBCB005/KarmaExperience/KarmaExperience/WakeUp/centro-studi-umanistici_med-att-circolo-ottobre-2023-mp4_2023-11-02_1522.zip Jul 13 07:44:40 volumiocentral volumio[916]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 13 07:44:40 volumiocentral volumio[916]: Error: Unable to resolve or reject the same promise twice Jul 13 07:44:40 volumiocentral volumio[916]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Jul 13 07:44:40 volumiocentral volumio[916]: at /volumio/app/plugins/music_service/mpd/index.js:2568:21 Jul 13 07:44:40 volumiocentral volumio[916]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) Jul 13 07:44:40 volumiocentral volumio[916]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) Jul 13 07:44:40 volumiocentral volumio[916]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) Jul 13 07:44:40 volumiocentral volumio[916]: at Socket.emit (events.js:400:28) Jul 13 07:44:40 volumiocentral volumio[916]: at addChunk (internal/streams/readable.js:293:12) Jul 13 07:44:40 volumiocentral volumio[916]: at readableAddChunk (internal/streams/readable.js:263:11) Jul 13 07:44:40 volumiocentral volumio[916]: at Socket.Readable.push (internal/streams/readable.js:206:10) Jul 13 07:44:40 volumiocentral volumio[916]: at Pipe.onStreamRead (internal/stream_base_commons.js:188:23) Jul 13 07:44:40 volumiocentral volumio[916]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 13 07:44:41 volumiocentral sudo[2060]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-07-13 07:43 Jul 13 07:44:41 volumiocentral sudo[2060]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="4b122b2365bb8c09786cd63b50d1ea6db780eb4e" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="49f98390f2d84c6549f33dc85b694fbd99e4c720" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 04 Jun 2024 09:13:11 PM CEST" VOLUMIO_VERSION="3.703" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="ac782621640fcc70c07e91a81b6d958b"