-- Logs begin at Thu 2019-02-14 11:11:58 CET, end at Wed 2026-05-27 18:35:52 CEST. -- May 27 18:34:05 volumio sudo[24251]: root : TTY=unknown ; PWD=/tmp ; USER=root ; COMMAND=/bin/cp /data/plugins/audio_interface/fusiondsp/c/hw_params_arm /data/plugins/audio_interface/fusiondsp/hw_params May 27 18:34:05 volumio sudo[24251]: pam_unix(sudo:session): session opened for user root by (uid=0) May 27 18:34:05 volumio sudo[24251]: pam_unix(sudo:session): session closed for user root May 27 18:34:05 volumio sudo[24253]: root : TTY=unknown ; PWD=/tmp ; USER=root ; COMMAND=/bin/chmod +x /data/plugins/audio_interface/fusiondsp/hw_params May 27 18:34:05 volumio sudo[24253]: pam_unix(sudo:session): session opened for user root by (uid=0) May 27 18:34:05 volumio sudo[24253]: pam_unix(sudo:session): session closed for user root May 27 18:34:05 volumio sudo[24255]: root : TTY=unknown ; PWD=/tmp ; USER=root ; COMMAND=/usr/bin/apt-get -y install drc May 27 18:34:05 volumio sudo[24255]: pam_unix(sudo:session): session opened for user root by (uid=0) May 27 18:34:05 volumio volumio[1003]: info: Reading package lists... May 27 18:34:05 volumio volumio5-onboarding[1684]: time=2026-05-27T18:34:05.294+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 27 18:34:05 volumio volumio[1003]: info: Building dependency tree... May 27 18:34:05 volumio volumio5-onboarding[1684]: time=2026-05-27T18:34:05.755+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 27 18:34:05 volumio volumio[1003]: info: Reading state information... May 27 18:34:05 volumio volumio5-onboarding[1684]: time=2026-05-27T18:34:05.762+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 27 18:34:06 volumio volumio[1003]: info: drc is already the newest version (3.2.2~dfsg0-2). May 27 18:34:06 volumio volumio5-onboarding[1684]: time=2026-05-27T18:34:06.515+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 27 18:34:06 volumio volumio[1003]: info: The following packages were automatically installed and are no longer required: May 27 18:34:06 volumio volumio5-onboarding[1684]: time=2026-05-27T18:34:06.522+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 27 18:34:06 volumio volumio[1003]: info: libfftw3-3 libiniparser1 May 27 18:34:06 volumio volumio5-onboarding[1684]: time=2026-05-27T18:34:06.536+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 27 18:34:06 volumio volumio[1003]: info: Use 'sudo apt autoremove' to remove them. May 27 18:34:06 volumio volumio5-onboarding[1684]: time=2026-05-27T18:34:06.541+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 27 18:34:06 volumio volumio[1003]: info: 0 upgraded, 0 newly installed, 0 to remove and 166 not upgraded. May 27 18:34:06 volumio sudo[24255]: pam_unix(sudo:session): session closed for user root May 27 18:34:06 volumio volumio5-onboarding[1684]: time=2026-05-27T18:34:06.611+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 27 18:34:06 volumio sudo[23754]: pam_unix(sudo:session): session closed for user root May 27 18:34:06 volumio volumio[1003]: info: Install script completed May 27 18:34:06 volumio volumio5-onboarding[1684]: time=2026-05-27T18:34:06.618+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 27 18:34:06 volumio volumio5-onboarding[1684]: time=2026-05-27T18:34:06.622+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 27 18:34:06 volumio volumio[1003]: info: Done installing plugin. May 27 18:34:06 volumio volumio[1003]: info: Enabling plugin fusiondsp May 27 18:34:06 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 27 18:34:06 volumio volumio[1003]: info: [{"prettyName":"FusionDsp","name":"fusiondsp","category":"audio_interface","version":"1.0.62","icon":"fa-sliders fa-rotate-90","isManuallyInstalled":false,"enabled":true,"active":false},{"prettyName":"80s80s Radio","name":"80s80s","category":"music_service","version":"1.0.4","icon":"fa-headphones","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Roon Bridge","name":"RoonBridge","category":"music_service","version":"1.0.0","icon":"fas fa-volume-up","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Calm Radio","name":"calmradio","category":"music_service","version":"1.2.4","icon":"fa-heartbeat","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"DMD2 Music","name":"dmd2_music","category":"music_service","version":"1.1.3","icon":"fa-headphones","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Jellyfin","name":"jellyfin","category":"music_service","version":"1.1.1","icon":"fa-music","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Mixcloud","name":"mixcloud","category":"music_service","version":"0.1.3","icon":"fa-mixcloud","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Moosbox","name":"moosbox","category":"music_service","version":"1.0.0","icon":"fa-solid fa-music","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"MotherEarthRadio","name":"motherearthradio","category":"music_service","version":"1.2.2","icon":"fa-lightbulb-o","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Personal Radio","name":"personal_radio","category":"music_service","version":"1.3.0","icon":"fa-headphones","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"RADIO 357","name":"rad357pl","category":"music_service","version":"1.0.2","icon":"fa-lightbulb-o","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Radio Paradise","name":"radio_paradise","category":"music_service","version":"1.0.14","icon":"fa-headphones","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"SoundCloud","name":"soundcloud","category":"music_service","version":"1.0.3","icon":"fa-music","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Spotify","name":"spop","category":"music_service","version":"4.4.1","icon":"fa-spotify","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"SWARM Station","name":"swarmstation","category":"music_service","version":"1.0.2","icon":"fa-lightbulb-o","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"YouTube Music","name":"ytmusic","category":"music_service","version":"1.3.1","icon":"fa-youtube-play","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Audiophonics Evo Sabre","name":"audiophonics_evo_sabre","category":"system_hardware","version":"2.0.0","icon":"fa-hdd-o","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Audiophonics ON/OFF","name":"audiophonicsonoff","category":"system_hardware","version":"1.0.5","icon":"fa fa-cogs","isManuallyInstalled":false,"enabled":true,"active":false}] May 27 18:34:06 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken May 27 18:34:06 volumio volumio[1003]: info: Folder /tmp/plugins removed May 27 18:34:06 volumio volumio[1003]: info: Folder /tmp/downloaded_plugin.zip removed May 27 18:34:06 volumio volumio[1003]: Plugin install end detected on script May 27 18:34:06 volumio volumio[1003]: info: Folder /data/temp removed May 27 18:34:06 volumio volumio[1003]: info: CoreCommandRouter::getUIConfigOnPlugin May 27 18:34:06 volumio volumio[1003]: info: FusionDsp - Classic-FusionDsp May 27 18:34:06 volumio volumio[1003]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json,Loudness.json May 27 18:34:10 volumio go-librespot[1403]: time="2026-05-27T18:34:10+02:00" level=trace msg="sent dealer ping" May 27 18:34:10 volumio go-librespot[1403]: time="2026-05-27T18:34:10+02:00" level=trace msg="received dealer pong" May 27 18:34:14 volumio volumio[1003]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/80s80s/volumio/buster/armhf May 27 18:34:14 volumio volumio5-onboarding[1684]: time=2026-05-27T18:34:14.456+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 27 18:34:19 volumio wpa_supplicant[883]: RRM: Ignoring radio measurement request: Not RRM network May 27 18:34:19 volumio volumio[1003]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/80s80s/volumio/buster/armhf May 27 18:34:19 volumio volumio5-onboarding[1684]: time=2026-05-27T18:34:19.684+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 27 18:34:19 volumio volumio5-onboarding[1684]: time=2026-05-27T18:34:19.686+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 27 18:34:19 volumio volumio5-onboarding[1684]: time=2026-05-27T18:34:19.896+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 27 18:34:19 volumio volumio[1003]: info: Check plugin dependencies May 27 18:34:19 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 27 18:34:19 volumio volumio5-onboarding[1684]: time=2026-05-27T18:34:19.930+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 27 18:34:19 volumio volumio[1003]: info: Rename folder May 27 18:34:19 volumio volumio[1003]: info: Folder /tmp/downloaded_plugin.zip removed May 27 18:34:19 volumio volumio5-onboarding[1684]: time=2026-05-27T18:34:19.954+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 27 18:34:19 volumio volumio[1003]: info: Move to category May 27 18:34:21 volumio volumio5-onboarding[1684]: time=2026-05-27T18:34:21.589+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 27 18:34:21 volumio volumio[1003]: info: Checking if install.sh is present May 27 18:34:21 volumio volumio[1003]: info: Executing install.sh May 27 18:34:21 volumio sudo[24325]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/music_service/80s80s/install.sh May 27 18:34:21 volumio sudo[24325]: pam_unix(sudo:session): session opened for user root by (uid=0) May 27 18:34:21 volumio sudo[24325]: pam_unix(sudo:session): session closed for user root May 27 18:34:21 volumio volumio[1003]: info: Installing 80s80s radio Dependencies May 27 18:34:21 volumio volumio5-onboarding[1684]: time=2026-05-27T18:34:21.667+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 27 18:34:21 volumio volumio[1003]: Plugin install end detected on script May 27 18:34:21 volumio volumio[1003]: info: Install script completed May 27 18:34:21 volumio volumio5-onboarding[1684]: time=2026-05-27T18:34:21.672+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 27 18:34:21 volumio volumio5-onboarding[1684]: time=2026-05-27T18:34:21.674+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 27 18:34:21 volumio volumio[1003]: info: Done installing plugin. May 27 18:34:21 volumio volumio[1003]: info: Enabling plugin 80s80s May 27 18:34:21 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 27 18:34:21 volumio volumio[1003]: info: [{"prettyName":"FusionDsp","name":"fusiondsp","category":"audio_interface","version":"1.0.62","icon":"fa-sliders fa-rotate-90","isManuallyInstalled":false,"enabled":true,"active":false},{"prettyName":"80s80s Radio","name":"80s80s","category":"music_service","version":"1.0.4","icon":"fa-headphones","isManuallyInstalled":false,"enabled":true,"active":false},{"prettyName":"Roon Bridge","name":"RoonBridge","category":"music_service","version":"1.0.0","icon":"fas fa-volume-up","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Calm Radio","name":"calmradio","category":"music_service","version":"1.2.4","icon":"fa-heartbeat","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"DMD2 Music","name":"dmd2_music","category":"music_service","version":"1.1.3","icon":"fa-headphones","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Jellyfin","name":"jellyfin","category":"music_service","version":"1.1.1","icon":"fa-music","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Mixcloud","name":"mixcloud","category":"music_service","version":"0.1.3","icon":"fa-mixcloud","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Moosbox","name":"moosbox","category":"music_service","version":"1.0.0","icon":"fa-solid fa-music","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"MotherEarthRadio","name":"motherearthradio","category":"music_service","version":"1.2.2","icon":"fa-lightbulb-o","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Personal Radio","name":"personal_radio","category":"music_service","version":"1.3.0","icon":"fa-headphones","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"RADIO 357","name":"rad357pl","category":"music_service","version":"1.0.2","icon":"fa-lightbulb-o","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Radio Paradise","name":"radio_paradise","category":"music_service","version":"1.0.14","icon":"fa-headphones","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"SoundCloud","name":"soundcloud","category":"music_service","version":"1.0.3","icon":"fa-music","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Spotify","name":"spop","category":"music_service","version":"4.4.1","icon":"fa-spotify","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"SWARM Station","name":"swarmstation","category":"music_service","version":"1.0.2","icon":"fa-lightbulb-o","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"YouTube Music","name":"ytmusic","category":"music_service","version":"1.3.1","icon":"fa-youtube-play","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Audiophonics Evo Sabre","name":"audiophonics_evo_sabre","category":"system_hardware","version":"2.0.0","icon":"fa-hdd-o","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Audiophonics ON/OFF","name":"audiophonicsonoff","category":"system_hardware","version":"1.0.5","icon":"fa fa-cogs","isManuallyInstalled":false,"enabled":true,"active":false}] May 27 18:34:21 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken May 27 18:34:21 volumio volumio[1003]: info: Folder /tmp/plugins removed May 27 18:34:21 volumio volumio[1003]: info: Folder /tmp/downloaded_plugin.zip removed May 27 18:34:21 volumio volumio[1003]: info: Folder /data/temp removed May 27 18:34:22 volumio volumio[1003]: info: CoreCommandRouter::getUIConfigOnPlugin May 27 18:34:22 volumio volumio[1003]: info: FusionDsp - Classic-FusionDsp May 27 18:34:22 volumio volumio[1003]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json,Loudness.json May 27 18:34:27 volumio wpa_supplicant[883]: RRM: Ignoring radio measurement request: Not RRM network May 27 18:34:31 volumio wpa_supplicant[883]: RRM: Ignoring radio measurement request: Not RRM network May 27 18:34:34 volumio go-librespot[1403]: time="2026-05-27T18:34:34+02:00" level=trace msg="received accesspoint ping" May 27 18:34:35 volumio go-librespot[1403]: time="2026-05-27T18:34:35+02:00" level=trace msg="received accesspoint pong ack" May 27 18:34:40 volumio go-librespot[1403]: time="2026-05-27T18:34:40+02:00" level=trace msg="sent dealer ping" May 27 18:34:40 volumio go-librespot[1403]: time="2026-05-27T18:34:40+02:00" level=trace msg="received dealer pong" May 27 18:34:41 volumio wpa_supplicant[883]: RRM: Ignoring radio measurement request: Not RRM network May 27 18:34:44 volumio volumio[1003]: info: CoreCommandRouter::volumioGetState May 27 18:34:45 volumio volumio[1003]: info: CoreCommandRouter::volumioStop May 27 18:34:45 volumio volumio[1003]: info: CoreStateMachine::stop May 27 18:34:45 volumio volumio[1003]: info: CoreStateMachine::setConsumeUpdateService undefined May 27 18:34:45 volumio volumio[1003]: info: CoreStateMachine::stPlaybackTimer May 27 18:34:45 volumio volumio[1003]: info: CoreStateMachine::updateTrackBlock May 27 18:34:45 volumio volumio[1003]: info: CorePlayQueue::getTrackBlock May 27 18:34:45 volumio volumio[1003]: info: CoreStateMachine::pushState May 27 18:34:45 volumio volumio[1003]: info: CorePlayQueue::getTrack 0 May 27 18:34:45 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 18:34:45 volumio volumio[1003]: info: CoreCommandRouter::volumioPushState May 27 18:34:45 volumio volumio[1003]: info: CoreStateMachine::serviceStop May 27 18:34:45 volumio volumio[1003]: info: CorePlayQueue::getTrack 0 May 27 18:34:45 volumio volumio[1003]: info: CoreCommandRouter::serviceStop May 27 18:34:45 volumio volumio[1003]: info: [1779899685290] ControllerWebradio::stop May 27 18:34:45 volumio volumio[1003]: verbose: ControllerMpd::sendMpdCommand stop May 27 18:34:45 volumio volumio[1003]: info: sendMpdCommand stop took 5 milliseconds May 27 18:34:45 volumio volumio[1003]: info: May 27 18:34:45 volumio volumio[1003]: ---------------------------- MPD announces state update: player May 27 18:34:45 volumio volumio[1003]: info: ControllerMpd::getState May 27 18:34:45 volumio volumio[1003]: verbose: ControllerMpd::sendMpdCommand status May 27 18:34:45 volumio volumio[1003]: info: May 27 18:34:45 volumio volumio[1003]: ---------------------------- MPD announces state update: player May 27 18:34:45 volumio volumio[1003]: info: ControllerMpd::getState May 27 18:34:45 volumio volumio[1003]: verbose: ControllerMpd::sendMpdCommand status May 27 18:34:45 volumio volumio[1003]: info: May 27 18:34:45 volumio volumio[1003]: ---------------------------- MPD announces state update: player May 27 18:34:45 volumio volumio[1003]: info: ControllerMpd::getState May 27 18:34:45 volumio volumio[1003]: verbose: ControllerMpd::sendMpdCommand status May 27 18:34:45 volumio volumio[1003]: info: May 27 18:34:45 volumio volumio[1003]: ---------------------------- MPD announces state update: player May 27 18:34:45 volumio volumio[1003]: info: ControllerMpd::getState May 27 18:34:45 volumio volumio[1003]: verbose: ControllerMpd::sendMpdCommand status May 27 18:34:45 volumio volumio[1003]: info: sendMpdCommand status took 3 milliseconds May 27 18:34:45 volumio volumio[1003]: info: sendMpdCommand status took 2 milliseconds May 27 18:34:45 volumio volumio[1003]: info: sendMpdCommand status took 1 milliseconds May 27 18:34:45 volumio volumio[1003]: info: sendMpdCommand status took 1 milliseconds May 27 18:34:45 volumio volumio[1003]: verbose: ControllerMpd::parseState May 27 18:34:45 volumio volumio[1003]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 18:34:45 volumio volumio[1003]: verbose: ControllerMpd::parseState May 27 18:34:45 volumio volumio[1003]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 18:34:45 volumio volumio[1003]: verbose: ControllerMpd::parseState May 27 18:34:45 volumio volumio[1003]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 18:34:45 volumio volumio[1003]: verbose: ControllerMpd::parseState May 27 18:34:45 volumio volumio[1003]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 18:34:45 volumio volumio[1003]: info: sendMpdCommand playlistinfo took 2 milliseconds May 27 18:34:45 volumio volumio[1003]: info: sendMpdCommand playlistinfo took 1 milliseconds May 27 18:34:45 volumio volumio[1003]: info: sendMpdCommand playlistinfo took 1 milliseconds May 27 18:34:45 volumio volumio[1003]: verbose: ControllerMpd::parseTrackInfo May 27 18:34:45 volumio volumio[1003]: verbose: ControllerMpd::parseTrackInfo May 27 18:34:45 volumio volumio[1003]: verbose: ControllerMpd::parseTrackInfo May 27 18:34:45 volumio volumio[1003]: verbose: ControllerMpd::parseTrackInfo May 27 18:34:45 volumio volumio[1003]: info: ControllerMpd::pushState May 27 18:34:45 volumio volumio[1003]: info: CoreCommandRouter::servicePushState May 27 18:34:45 volumio volumio[1003]: info: CoreStateMachine::pushState May 27 18:34:45 volumio volumio[1003]: info: CorePlayQueue::getTrack 0 May 27 18:34:45 volumio volumio[1003]: info: CoreCommandRouter::volumioPushState May 27 18:34:45 volumio volumio[1003]: info: CorePlayQueue::getTrack 0 May 27 18:34:45 volumio volumio[1003]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd May 27 18:34:45 volumio volumio[1003]: info: ControllerMpd::pushState May 27 18:34:45 volumio volumio[1003]: info: CoreCommandRouter::servicePushState May 27 18:34:45 volumio volumio[1003]: info: CoreStateMachine::pushState May 27 18:34:45 volumio volumio[1003]: info: CorePlayQueue::getTrack 0 May 27 18:34:45 volumio volumio[1003]: info: CoreCommandRouter::volumioPushState May 27 18:34:45 volumio volumio[1003]: info: CorePlayQueue::getTrack 0 May 27 18:34:45 volumio volumio[1003]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd May 27 18:34:45 volumio volumio[1003]: info: ControllerMpd::pushState May 27 18:34:45 volumio volumio[1003]: info: CoreCommandRouter::servicePushState May 27 18:34:45 volumio volumio[1003]: info: CoreStateMachine::pushState May 27 18:34:45 volumio volumio[1003]: info: CorePlayQueue::getTrack 0 May 27 18:34:45 volumio volumio[1003]: info: CoreCommandRouter::volumioPushState May 27 18:34:45 volumio volumio[1003]: info: CorePlayQueue::getTrack 0 May 27 18:34:45 volumio volumio[1003]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd May 27 18:34:45 volumio volumio[1003]: info: ControllerMpd::pushState May 27 18:34:45 volumio volumio[1003]: info: CoreCommandRouter::servicePushState May 27 18:34:45 volumio volumio[1003]: info: CoreStateMachine::pushState May 27 18:34:45 volumio volumio[1003]: info: CorePlayQueue::getTrack 0 May 27 18:34:45 volumio volumio[1003]: info: CoreCommandRouter::volumioPushState May 27 18:34:45 volumio volumio[1003]: info: CorePlayQueue::getTrack 0 May 27 18:34:45 volumio volumio[1003]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd May 27 18:34:45 volumio volumio[1003]: info: ------------------------------ 15ms May 27 18:34:45 volumio volumio[1003]: info: ------------------------------ 14ms May 27 18:34:45 volumio volumio[1003]: info: ------------------------------ 14ms May 27 18:34:45 volumio volumio[1003]: info: ------------------------------ 13ms May 27 18:34:45 volumio wpa_supplicant[883]: RRM: Ignoring radio measurement request: Not RRM network May 27 18:34:45 volumio volumio[1003]: info: CoreCommandRouter::volumioPlay May 27 18:34:45 volumio volumio[1003]: info: CoreStateMachine::play index undefined May 27 18:34:45 volumio volumio[1003]: info: CoreStateMachine::setConsumeUpdateService undefined May 27 18:34:45 volumio volumio[1003]: info: CorePlayQueue::getTrack 0 May 27 18:34:45 volumio volumio[1003]: info: CoreStateMachine::startPlaybackTimer May 27 18:34:45 volumio volumio[1003]: info: CorePlayQueue::getTrack 0 May 27 18:34:45 volumio volumio[1003]: info: [1779899685856] ControllerWebradio::clearAddPlayTrack May 27 18:34:45 volumio volumio[1003]: verbose: ControllerMpd::sendMpdCommand stop May 27 18:34:45 volumio volumio[1003]: info: sendMpdCommand stop took 0 milliseconds May 27 18:34:45 volumio volumio[1003]: verbose: ControllerMpd::sendMpdCommand clear May 27 18:34:45 volumio volumio[1003]: info: May 27 18:34:45 volumio volumio[1003]: ---------------------------- MPD announces system playlist update May 27 18:34:45 volumio volumio[1003]: info: Ignoring MPD Status Update May 27 18:34:45 volumio volumio[1003]: info: sendMpdCommand clear took 1 milliseconds May 27 18:34:45 volumio volumio[1003]: verbose: ControllerMpd::sendMpdCommand load "http://mscp3.live-streams.nl:8340/jazz-flac.flac" May 27 18:34:45 volumio volumio[1003]: info: May 27 18:34:45 volumio volumio[1003]: ---------------------------- MPD announces system playlist update May 27 18:34:45 volumio volumio[1003]: info: Ignoring MPD Status Update May 27 18:34:45 volumio volumio[1003]: info: May 27 18:34:45 volumio volumio[1003]: ---------------------------- MPD announces system playlist update May 27 18:34:45 volumio volumio[1003]: info: Ignoring MPD Status Update May 27 18:34:45 volumio volumio[1003]: info: May 27 18:34:45 volumio volumio[1003]: ---------------------------- MPD announces system playlist update May 27 18:34:45 volumio volumio[1003]: info: Ignoring MPD Status Update May 27 18:34:45 volumio volumio[1003]: error: updateQueue error: null May 27 18:34:45 volumio volumio[1003]: info: ------------------------------ 2ms May 27 18:34:46 volumio volumio[1003]: verbose: ControllerMpd::sendMpdCommand add "http://mscp3.live-streams.nl:8340/jazz-flac.flac" May 27 18:34:46 volumio volumio[1003]: error: updateQueue error: null May 27 18:34:46 volumio volumio[1003]: error: updateQueue error: null May 27 18:34:46 volumio volumio[1003]: error: updateQueue error: null May 27 18:34:46 volumio volumio[1003]: info: May 27 18:34:46 volumio volumio[1003]: ---------------------------- MPD announces system playlist update May 27 18:34:46 volumio volumio[1003]: info: Ignoring MPD Status Update May 27 18:34:46 volumio volumio[1003]: info: ------------------------------ 627ms May 27 18:34:46 volumio volumio[1003]: info: ------------------------------ 626ms May 27 18:34:46 volumio volumio[1003]: info: ------------------------------ 626ms May 27 18:34:46 volumio volumio[1003]: info: sendMpdCommand add "http://mscp3.live-streams.nl:8340/jazz-flac.flac" took 1 milliseconds May 27 18:34:46 volumio volumio[1003]: info: CoreStateMachine::setConsumeUpdateService mpd May 27 18:34:46 volumio volumio[1003]: verbose: ControllerMpd::sendMpdCommand play May 27 18:34:46 volumio volumio[1003]: info: May 27 18:34:46 volumio volumio[1003]: ---------------------------- MPD announces system playlist update May 27 18:34:46 volumio volumio[1003]: info: Ignoring MPD Status Update May 27 18:34:46 volumio volumio[1003]: info: May 27 18:34:46 volumio volumio[1003]: ---------------------------- MPD announces system playlist update May 27 18:34:46 volumio volumio[1003]: info: Ignoring MPD Status Update May 27 18:34:46 volumio volumio[1003]: info: May 27 18:34:46 volumio volumio[1003]: ---------------------------- MPD announces system playlist update May 27 18:34:46 volumio volumio[1003]: info: Ignoring MPD Status Update May 27 18:34:46 volumio volumio[1003]: info: ------------------------------ 3ms May 27 18:34:46 volumio volumio[1003]: info: sendMpdCommand play took 2 milliseconds May 27 18:34:46 volumio volumio[1003]: info: ------------------------------ 2ms May 27 18:34:46 volumio volumio[1003]: info: ------------------------------ 1ms May 27 18:34:46 volumio volumio[1003]: info: May 27 18:34:46 volumio volumio[1003]: ---------------------------- MPD announces state update: player May 27 18:34:46 volumio volumio[1003]: info: ControllerMpd::getState May 27 18:34:46 volumio volumio[1003]: verbose: ControllerMpd::sendMpdCommand status May 27 18:34:46 volumio volumio[1003]: info: May 27 18:34:46 volumio volumio[1003]: ---------------------------- MPD announces state update: player May 27 18:34:46 volumio volumio[1003]: info: ControllerMpd::getState May 27 18:34:46 volumio volumio[1003]: verbose: ControllerMpd::sendMpdCommand status May 27 18:34:46 volumio volumio[1003]: info: May 27 18:34:46 volumio volumio[1003]: ---------------------------- MPD announces state update: player May 27 18:34:46 volumio volumio[1003]: info: ControllerMpd::getState May 27 18:34:46 volumio volumio[1003]: verbose: ControllerMpd::sendMpdCommand status May 27 18:34:46 volumio volumio[1003]: info: May 27 18:34:46 volumio volumio[1003]: ---------------------------- MPD announces state update: player May 27 18:34:46 volumio volumio[1003]: info: ControllerMpd::getState May 27 18:34:46 volumio volumio[1003]: verbose: ControllerMpd::sendMpdCommand status May 27 18:34:46 volumio volumio[1003]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 May 27 18:34:46 volumio volumio[1003]: info: FusionDsp - ---- read samplerate from file: 44100 May 27 18:34:46 volumio volumio[1003]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 27 18:34:46 volumio volumio[1003]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 May 27 18:34:46 volumio volumio[1003]: info: FusionDsp - ---- read samplerate from file: 44100 May 27 18:34:46 volumio volumio[1003]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 27 18:34:46 volumio volumio[1003]: info: May 27 18:34:46 volumio volumio[1003]: ---------------------------- MPD announces state update: player May 27 18:34:46 volumio volumio[1003]: info: sendMpdCommand status took 24 milliseconds May 27 18:34:46 volumio volumio[1003]: info: ControllerMpd::getState May 27 18:34:46 volumio volumio[1003]: verbose: ControllerMpd::sendMpdCommand status May 27 18:34:46 volumio volumio[1003]: info: sendMpdCommand status took 23 milliseconds May 27 18:34:46 volumio volumio[1003]: info: sendMpdCommand status took 22 milliseconds May 27 18:34:46 volumio volumio[1003]: info: sendMpdCommand status took 21 milliseconds May 27 18:34:46 volumio volumio[1003]: verbose: ControllerMpd::parseState May 27 18:34:46 volumio volumio[1003]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 18:34:46 volumio volumio[1003]: verbose: ControllerMpd::parseState May 27 18:34:46 volumio volumio[1003]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 18:34:46 volumio volumio[1003]: verbose: ControllerMpd::parseState May 27 18:34:46 volumio volumio[1003]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 18:34:46 volumio volumio[1003]: verbose: ControllerMpd::parseState May 27 18:34:46 volumio volumio[1003]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 18:34:46 volumio volumio[1003]: info: May 27 18:34:46 volumio volumio[1003]: ---------------------------- MPD announces state update: player May 27 18:34:46 volumio volumio[1003]: info: ControllerMpd::getState May 27 18:34:46 volumio volumio[1003]: verbose: ControllerMpd::sendMpdCommand status May 27 18:34:46 volumio volumio[1003]: info: May 27 18:34:46 volumio volumio[1003]: ---------------------------- MPD announces state update: player May 27 18:34:46 volumio volumio[1003]: info: ControllerMpd::getState May 27 18:34:46 volumio volumio[1003]: verbose: ControllerMpd::sendMpdCommand status May 27 18:34:46 volumio volumio[1003]: info: May 27 18:34:46 volumio volumio[1003]: ---------------------------- MPD announces state update: player May 27 18:34:46 volumio volumio[1003]: info: ControllerMpd::getState May 27 18:34:46 volumio volumio[1003]: verbose: ControllerMpd::sendMpdCommand status May 27 18:34:46 volumio volumio[1003]: info: sendMpdCommand status took 4 milliseconds May 27 18:34:46 volumio volumio[1003]: info: sendMpdCommand playlistinfo took 5 milliseconds May 27 18:34:46 volumio volumio[1003]: info: sendMpdCommand playlistinfo took 4 milliseconds May 27 18:34:46 volumio volumio[1003]: info: sendMpdCommand playlistinfo took 4 milliseconds May 27 18:34:46 volumio volumio[1003]: info: sendMpdCommand playlistinfo took 4 milliseconds May 27 18:34:46 volumio volumio[1003]: info: sendMpdCommand status took 3 milliseconds May 27 18:34:46 volumio volumio[1003]: info: sendMpdCommand status took 2 milliseconds May 27 18:34:46 volumio volumio[1003]: info: sendMpdCommand status took 2 milliseconds May 27 18:34:46 volumio volumio[1003]: verbose: ControllerMpd::parseState May 27 18:34:46 volumio volumio[1003]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 18:34:46 volumio volumio[1003]: verbose: ControllerMpd::parseTrackInfo May 27 18:34:46 volumio volumio[1003]: verbose: ControllerMpd::parseTrackInfo May 27 18:34:46 volumio volumio[1003]: verbose: ControllerMpd::parseTrackInfo May 27 18:34:46 volumio volumio[1003]: verbose: ControllerMpd::parseTrackInfo May 27 18:34:46 volumio volumio[1003]: verbose: ControllerMpd::parseState May 27 18:34:46 volumio volumio[1003]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 18:34:46 volumio volumio[1003]: verbose: ControllerMpd::parseState May 27 18:34:46 volumio volumio[1003]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 18:34:46 volumio volumio[1003]: verbose: ControllerMpd::parseState May 27 18:34:46 volumio volumio[1003]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 18:34:46 volumio volumio[1003]: info: ControllerMpd::pushState May 27 18:34:46 volumio volumio[1003]: info: CoreCommandRouter::servicePushState May 27 18:34:46 volumio volumio[1003]: info: CorePlayQueue::getTrack 0 May 27 18:34:46 volumio volumio[1003]: verbose: STATE SERVICE {"status":"play","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":"jazz-flac.flac","artist":null,"album":null,"uri":"http://mscp3.live-streams.nl:8340/jazz-flac.flac","trackType":"flac"} May 27 18:34:46 volumio volumio[1003]: verbose: CURRENT POSITION 0 May 27 18:34:46 volumio volumio[1003]: info: CoreStateMachine::syncState stateService play May 27 18:34:46 volumio volumio[1003]: info: CoreStateMachine::syncState currentStatus stop May 27 18:34:46 volumio volumio[1003]: info: ControllerMpd::pushState May 27 18:34:46 volumio volumio[1003]: info: CoreCommandRouter::servicePushState May 27 18:34:46 volumio volumio[1003]: info: CorePlayQueue::getTrack 0 May 27 18:34:46 volumio volumio[1003]: verbose: STATE SERVICE {"status":"play","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":"jazz-flac.flac","artist":null,"album":null,"uri":"http://mscp3.live-streams.nl:8340/jazz-flac.flac","trackType":"flac"} May 27 18:34:46 volumio volumio[1003]: verbose: CURRENT POSITION 0 May 27 18:34:46 volumio volumio[1003]: info: CoreStateMachine::syncState stateService play May 27 18:34:46 volumio volumio[1003]: info: CoreStateMachine::syncState currentStatus play May 27 18:34:46 volumio volumio[1003]: info: Received an update from plugin. extracting info from payload May 27 18:34:46 volumio volumio[1003]: info: CoreStateMachine::pushState May 27 18:34:46 volumio volumio[1003]: info: CorePlayQueue::getTrack 0 May 27 18:34:46 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 18:34:46 volumio volumio[1003]: info: CoreCommandRouter::volumioPushState May 27 18:34:46 volumio volumio[1003]: info: CoreStateMachine::pushState May 27 18:34:46 volumio volumio[1003]: info: CoreCommandRouter::volumioPushState May 27 18:34:46 volumio volumio[1003]: info: ControllerMpd::pushState May 27 18:34:46 volumio volumio[1003]: info: CoreCommandRouter::servicePushState May 27 18:34:46 volumio volumio[1003]: info: CorePlayQueue::getTrack 0 May 27 18:34:46 volumio volumio[1003]: verbose: STATE SERVICE {"status":"play","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":"jazz-flac.flac","artist":null,"album":null,"uri":"http://mscp3.live-streams.nl:8340/jazz-flac.flac","trackType":"flac"} May 27 18:34:46 volumio volumio[1003]: verbose: CURRENT POSITION 0 May 27 18:34:46 volumio volumio[1003]: info: CoreStateMachine::syncState stateService play May 27 18:34:46 volumio volumio[1003]: info: CoreStateMachine::syncState currentStatus play May 27 18:34:46 volumio volumio[1003]: info: Received an update from plugin. extracting info from payload May 27 18:34:46 volumio volumio[1003]: info: CoreStateMachine::pushState May 27 18:34:46 volumio volumio[1003]: info: CorePlayQueue::getTrack 0 May 27 18:34:46 volumio volumio[1003]: info: CoreCommandRouter::volumioPushState May 27 18:34:46 volumio volumio[1003]: info: CoreStateMachine::pushState May 27 18:34:46 volumio volumio[1003]: info: CoreCommandRouter::volumioPushState May 27 18:34:46 volumio volumio[1003]: info: ControllerMpd::pushState May 27 18:34:46 volumio volumio[1003]: info: CoreCommandRouter::servicePushState May 27 18:34:46 volumio volumio[1003]: info: CorePlayQueue::getTrack 0 May 27 18:34:46 volumio volumio[1003]: verbose: STATE SERVICE {"status":"play","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":"jazz-flac.flac","artist":null,"album":null,"uri":"http://mscp3.live-streams.nl:8340/jazz-flac.flac","trackType":"flac"} May 27 18:34:46 volumio volumio[1003]: verbose: CURRENT POSITION 0 May 27 18:34:46 volumio volumio[1003]: info: CoreStateMachine::syncState stateService play May 27 18:34:46 volumio volumio[1003]: info: CoreStateMachine::syncState currentStatus play May 27 18:34:46 volumio volumio[1003]: info: Received an update from plugin. extracting info from payload May 27 18:34:46 volumio volumio[1003]: info: CoreStateMachine::pushState May 27 18:34:46 volumio volumio[1003]: info: CorePlayQueue::getTrack 0 May 27 18:34:46 volumio volumio[1003]: info: CoreCommandRouter::volumioPushState May 27 18:34:46 volumio volumio[1003]: info: CoreStateMachine::pushState May 27 18:34:46 volumio volumio[1003]: info: CoreCommandRouter::volumioPushState May 27 18:34:46 volumio volumio[1003]: info: ------------------------------ 43ms May 27 18:34:47 volumio volumio[1003]: info: ------------------------------ 50ms May 27 18:34:47 volumio volumio[1003]: info: ------------------------------ 50ms May 27 18:34:47 volumio volumio[1003]: info: ------------------------------ 49ms May 27 18:34:47 volumio volumio[1003]: error: FusionDsp - WebSocket error: [object Object] May 27 18:34:47 volumio volumio[1003]: error: FusionDsp - WebSocket error: [object Object] May 27 18:34:47 volumio volumio[1003]: info: sendMpdCommand playlistinfo took 25 milliseconds May 27 18:34:47 volumio volumio[1003]: info: sendMpdCommand playlistinfo took 25 milliseconds May 27 18:34:47 volumio volumio[1003]: info: sendMpdCommand playlistinfo took 25 milliseconds May 27 18:34:47 volumio volumio[1003]: info: sendMpdCommand playlistinfo took 25 milliseconds May 27 18:34:47 volumio volumio[1003]: verbose: ControllerMpd::parseTrackInfo May 27 18:34:47 volumio volumio[1003]: verbose: ControllerMpd::parseTrackInfo May 27 18:34:47 volumio volumio[1003]: verbose: ControllerMpd::parseTrackInfo May 27 18:34:47 volumio volumio[1003]: verbose: ControllerMpd::parseTrackInfo May 27 18:34:47 volumio volumio[1003]: info: ControllerMpd::pushState May 27 18:34:47 volumio volumio[1003]: info: CoreCommandRouter::servicePushState May 27 18:34:47 volumio volumio[1003]: info: CorePlayQueue::getTrack 0 May 27 18:34:47 volumio volumio[1003]: verbose: STATE SERVICE {"status":"play","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":"jazz-flac.flac","artist":null,"album":null,"uri":"http://mscp3.live-streams.nl:8340/jazz-flac.flac","trackType":"flac"} May 27 18:34:47 volumio volumio[1003]: verbose: CURRENT POSITION 0 May 27 18:34:47 volumio volumio[1003]: info: CoreStateMachine::syncState stateService play May 27 18:34:47 volumio volumio[1003]: info: CoreStateMachine::syncState currentStatus play May 27 18:34:47 volumio volumio[1003]: info: Received an update from plugin. extracting info from payload May 27 18:34:47 volumio volumio[1003]: info: CoreStateMachine::pushState May 27 18:34:47 volumio volumio[1003]: info: CorePlayQueue::getTrack 0 May 27 18:34:47 volumio volumio[1003]: info: CoreCommandRouter::volumioPushState May 27 18:34:47 volumio volumio[1003]: info: CoreStateMachine::pushState May 27 18:34:47 volumio volumio[1003]: info: CoreCommandRouter::volumioPushState May 27 18:34:47 volumio volumio[1003]: info: ControllerMpd::pushState May 27 18:34:47 volumio volumio[1003]: info: CoreCommandRouter::servicePushState May 27 18:34:47 volumio volumio[1003]: info: CorePlayQueue::getTrack 0 May 27 18:34:47 volumio volumio[1003]: verbose: STATE SERVICE {"status":"play","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":"jazz-flac.flac","artist":null,"album":null,"uri":"http://mscp3.live-streams.nl:8340/jazz-flac.flac","trackType":"flac"} May 27 18:34:47 volumio volumio[1003]: verbose: CURRENT POSITION 0 May 27 18:34:47 volumio volumio[1003]: info: CoreStateMachine::syncState stateService play May 27 18:34:47 volumio volumio[1003]: info: CoreStateMachine::syncState currentStatus play May 27 18:34:47 volumio volumio[1003]: info: Received an update from plugin. extracting info from payload May 27 18:34:47 volumio volumio[1003]: info: CoreStateMachine::pushState May 27 18:34:47 volumio volumio[1003]: info: CorePlayQueue::getTrack 0 May 27 18:34:47 volumio volumio[1003]: info: CoreCommandRouter::volumioPushState May 27 18:34:47 volumio volumio[1003]: info: CoreStateMachine::pushState May 27 18:34:47 volumio volumio[1003]: info: CoreCommandRouter::volumioPushState May 27 18:34:47 volumio volumio[1003]: info: ControllerMpd::pushState May 27 18:34:47 volumio volumio[1003]: info: CoreCommandRouter::servicePushState May 27 18:34:47 volumio volumio[1003]: info: CorePlayQueue::getTrack 0 May 27 18:34:47 volumio volumio[1003]: verbose: STATE SERVICE {"status":"play","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":"jazz-flac.flac","artist":null,"album":null,"uri":"http://mscp3.live-streams.nl:8340/jazz-flac.flac","trackType":"flac"} May 27 18:34:47 volumio volumio[1003]: verbose: CURRENT POSITION 0 May 27 18:34:47 volumio volumio[1003]: info: CoreStateMachine::syncState stateService play May 27 18:34:47 volumio volumio[1003]: info: CoreStateMachine::syncState currentStatus play May 27 18:34:47 volumio volumio[1003]: info: Received an update from plugin. extracting info from payload May 27 18:34:47 volumio volumio[1003]: info: CoreStateMachine::pushState May 27 18:34:47 volumio volumio[1003]: info: CorePlayQueue::getTrack 0 May 27 18:34:47 volumio volumio[1003]: info: CoreCommandRouter::volumioPushState May 27 18:34:47 volumio volumio[1003]: info: CoreStateMachine::pushState May 27 18:34:47 volumio volumio[1003]: info: CoreCommandRouter::volumioPushState May 27 18:34:47 volumio volumio[1003]: info: ControllerMpd::pushState May 27 18:34:47 volumio volumio[1003]: info: CoreCommandRouter::servicePushState May 27 18:34:47 volumio volumio[1003]: info: CorePlayQueue::getTrack 0 May 27 18:34:47 volumio volumio[1003]: verbose: STATE SERVICE {"status":"play","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":"jazz-flac.flac","artist":null,"album":null,"uri":"http://mscp3.live-streams.nl:8340/jazz-flac.flac","trackType":"flac"} May 27 18:34:47 volumio volumio[1003]: verbose: CURRENT POSITION 0 May 27 18:34:47 volumio volumio[1003]: info: CoreStateMachine::syncState stateService play May 27 18:34:47 volumio volumio[1003]: info: CoreStateMachine::syncState currentStatus play May 27 18:34:47 volumio volumio[1003]: info: Received an update from plugin. extracting info from payload May 27 18:34:47 volumio volumio[1003]: info: CoreStateMachine::pushState May 27 18:34:47 volumio volumio[1003]: info: CorePlayQueue::getTrack 0 May 27 18:34:47 volumio volumio[1003]: info: CoreCommandRouter::volumioPushState May 27 18:34:47 volumio volumio[1003]: info: CoreStateMachine::pushState May 27 18:34:47 volumio volumio[1003]: info: CoreCommandRouter::volumioPushState May 27 18:34:47 volumio volumio[1003]: info: ------------------------------ 52ms May 27 18:34:47 volumio volumio[1003]: info: ------------------------------ 49ms May 27 18:34:47 volumio volumio[1003]: info: ------------------------------ 50ms May 27 18:34:47 volumio volumio[1003]: info: ------------------------------ 49ms May 27 18:34:47 volumio volumio[1003]: info: May 27 18:34:47 volumio volumio[1003]: ---------------------------- MPD announces system playlist update May 27 18:34:47 volumio volumio[1003]: info: Ignoring MPD Status Update May 27 18:34:47 volumio volumio[1003]: info: May 27 18:34:47 volumio volumio[1003]: ---------------------------- MPD announces state update: player May 27 18:34:47 volumio volumio[1003]: info: ControllerMpd::getState May 27 18:34:47 volumio volumio[1003]: verbose: ControllerMpd::sendMpdCommand status May 27 18:34:47 volumio volumio[1003]: info: May 27 18:34:47 volumio volumio[1003]: ---------------------------- MPD announces system playlist update May 27 18:34:47 volumio volumio[1003]: info: Ignoring MPD Status Update May 27 18:34:47 volumio volumio[1003]: info: May 27 18:34:47 volumio volumio[1003]: ---------------------------- MPD announces state update: player May 27 18:34:47 volumio volumio[1003]: info: ControllerMpd::getState May 27 18:34:47 volumio volumio[1003]: verbose: ControllerMpd::sendMpdCommand status May 27 18:34:47 volumio volumio[1003]: info: May 27 18:34:47 volumio volumio[1003]: ---------------------------- MPD announces system playlist update May 27 18:34:47 volumio volumio[1003]: info: Ignoring MPD Status Update May 27 18:34:47 volumio volumio[1003]: info: May 27 18:34:47 volumio volumio[1003]: ---------------------------- MPD announces state update: player May 27 18:34:47 volumio volumio[1003]: info: ControllerMpd::getState May 27 18:34:47 volumio volumio[1003]: verbose: ControllerMpd::sendMpdCommand status May 27 18:34:47 volumio volumio[1003]: info: ------------------------------ 6ms May 27 18:34:47 volumio volumio[1003]: info: sendMpdCommand status took 7 milliseconds May 27 18:34:47 volumio volumio[1003]: verbose: ControllerMpd::parseState May 27 18:34:47 volumio volumio[1003]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 18:34:47 volumio volumio[1003]: info: May 27 18:34:47 volumio volumio[1003]: ---------------------------- MPD announces system playlist update May 27 18:34:47 volumio volumio[1003]: info: Ignoring MPD Status Update May 27 18:34:47 volumio volumio[1003]: info: May 27 18:34:47 volumio volumio[1003]: ---------------------------- MPD announces state update: player May 27 18:34:47 volumio volumio[1003]: info: ControllerMpd::getState May 27 18:34:47 volumio volumio[1003]: verbose: ControllerMpd::sendMpdCommand status May 27 18:34:47 volumio volumio[1003]: info: ------------------------------ 8ms May 27 18:34:47 volumio volumio[1003]: info: sendMpdCommand status took 8 milliseconds May 27 18:34:47 volumio volumio[1003]: info: ------------------------------ 7ms May 27 18:34:47 volumio volumio[1003]: info: sendMpdCommand status took 7 milliseconds May 27 18:34:47 volumio volumio[1003]: info: sendMpdCommand playlistinfo took 3 milliseconds May 27 18:34:47 volumio volumio[1003]: info: ------------------------------ 3ms May 27 18:34:47 volumio volumio[1003]: info: sendMpdCommand status took 2 milliseconds May 27 18:34:47 volumio volumio[1003]: verbose: ControllerMpd::parseState May 27 18:34:47 volumio volumio[1003]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 18:34:47 volumio volumio[1003]: verbose: ControllerMpd::parseState May 27 18:34:47 volumio volumio[1003]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 18:34:47 volumio volumio[1003]: verbose: ControllerMpd::parseTrackInfo May 27 18:34:47 volumio volumio[1003]: verbose: ControllerMpd::parseState May 27 18:34:47 volumio volumio[1003]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 18:34:47 volumio volumio[1003]: info: ControllerMpd::pushState May 27 18:34:47 volumio volumio[1003]: info: CoreCommandRouter::servicePushState May 27 18:34:47 volumio volumio[1003]: info: CorePlayQueue::getTrack 0 May 27 18:34:47 volumio volumio[1003]: verbose: STATE SERVICE {"status":"play","position":0,"seek":388,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"jazz-flac.flac","artist":"Naim Jazz","album":null,"uri":"http://mscp3.live-streams.nl:8340/jazz-flac.flac","trackType":"flac"} May 27 18:34:47 volumio volumio[1003]: verbose: CURRENT POSITION 0 May 27 18:34:47 volumio volumio[1003]: info: CoreStateMachine::syncState stateService play May 27 18:34:47 volumio volumio[1003]: info: CoreStateMachine::syncState currentStatus play May 27 18:34:47 volumio volumio[1003]: info: Received an update from plugin. extracting info from payload May 27 18:34:47 volumio volumio[1003]: info: CoreStateMachine::pushState May 27 18:34:47 volumio volumio[1003]: info: CorePlayQueue::getTrack 0 May 27 18:34:47 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 18:34:47 volumio volumio[1003]: info: CoreCommandRouter::volumioPushState May 27 18:34:47 volumio volumio[1003]: info: CoreStateMachine::pushState May 27 18:34:47 volumio volumio[1003]: info: CoreCommandRouter::volumioPushState May 27 18:34:47 volumio volumio[1003]: info: ------------------------------ 17ms May 27 18:34:47 volumio volumio[1003]: info: sendMpdCommand playlistinfo took 8 milliseconds May 27 18:34:47 volumio volumio[1003]: info: sendMpdCommand playlistinfo took 8 milliseconds May 27 18:34:47 volumio volumio[1003]: info: sendMpdCommand playlistinfo took 7 milliseconds May 27 18:34:47 volumio volumio[1003]: verbose: ControllerMpd::parseTrackInfo May 27 18:34:47 volumio volumio[1003]: verbose: ControllerMpd::parseTrackInfo May 27 18:34:47 volumio volumio[1003]: verbose: ControllerMpd::parseTrackInfo May 27 18:34:47 volumio volumio[1003]: info: ControllerMpd::pushState May 27 18:34:47 volumio volumio[1003]: info: CoreCommandRouter::servicePushState May 27 18:34:47 volumio volumio[1003]: info: CorePlayQueue::getTrack 0 May 27 18:34:47 volumio volumio[1003]: verbose: STATE SERVICE {"status":"play","position":0,"seek":457,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"jazz-flac.flac","artist":"Naim Jazz","album":null,"uri":"http://mscp3.live-streams.nl:8340/jazz-flac.flac","trackType":"flac"} May 27 18:34:47 volumio volumio[1003]: verbose: CURRENT POSITION 0 May 27 18:34:47 volumio volumio[1003]: info: CoreStateMachine::syncState stateService play May 27 18:34:47 volumio volumio[1003]: info: CoreStateMachine::syncState currentStatus play May 27 18:34:47 volumio volumio[1003]: info: Received an update from plugin. extracting info from payload May 27 18:34:47 volumio volumio[1003]: info: CoreStateMachine::pushState May 27 18:34:47 volumio volumio[1003]: info: CorePlayQueue::getTrack 0 May 27 18:34:47 volumio volumio[1003]: info: CoreCommandRouter::volumioPushState May 27 18:34:47 volumio volumio[1003]: info: CoreStateMachine::pushState May 27 18:34:47 volumio volumio[1003]: info: CoreCommandRouter::volumioPushState May 27 18:34:47 volumio volumio[1003]: info: ControllerMpd::pushState May 27 18:34:47 volumio volumio[1003]: info: CoreCommandRouter::servicePushState May 27 18:34:47 volumio volumio[1003]: info: CorePlayQueue::getTrack 0 May 27 18:34:47 volumio volumio[1003]: verbose: STATE SERVICE {"status":"play","position":0,"seek":960,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"jazz-flac.flac","artist":"Naim Jazz","album":null,"uri":"http://mscp3.live-streams.nl:8340/jazz-flac.flac","trackType":"flac"} May 27 18:34:47 volumio volumio[1003]: verbose: CURRENT POSITION 0 May 27 18:34:47 volumio volumio[1003]: info: CoreStateMachine::syncState stateService play May 27 18:34:47 volumio volumio[1003]: info: CoreStateMachine::syncState currentStatus play May 27 18:34:47 volumio volumio[1003]: info: Received an update from plugin. extracting info from payload May 27 18:34:47 volumio volumio[1003]: info: CoreStateMachine::pushState May 27 18:34:47 volumio volumio[1003]: info: CorePlayQueue::getTrack 0 May 27 18:34:47 volumio volumio[1003]: info: CoreCommandRouter::volumioPushState May 27 18:34:47 volumio volumio[1003]: info: CoreStateMachine::pushState May 27 18:34:47 volumio volumio[1003]: info: CoreCommandRouter::volumioPushState May 27 18:34:47 volumio volumio[1003]: info: ControllerMpd::pushState May 27 18:34:47 volumio volumio[1003]: info: CoreCommandRouter::servicePushState May 27 18:34:47 volumio volumio[1003]: info: CorePlayQueue::getTrack 0 May 27 18:34:47 volumio volumio[1003]: verbose: STATE SERVICE {"status":"play","position":0,"seek":960,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"jazz-flac.flac","artist":"Naim Jazz","album":null,"uri":"http://mscp3.live-streams.nl:8340/jazz-flac.flac","trackType":"flac"} May 27 18:34:47 volumio volumio[1003]: verbose: CURRENT POSITION 0 May 27 18:34:47 volumio volumio[1003]: info: CoreStateMachine::syncState stateService play May 27 18:34:47 volumio volumio[1003]: info: CoreStateMachine::syncState currentStatus play May 27 18:34:47 volumio volumio[1003]: info: Received an update from plugin. extracting info from payload May 27 18:34:47 volumio volumio[1003]: info: CoreStateMachine::pushState May 27 18:34:47 volumio volumio[1003]: info: CorePlayQueue::getTrack 0 May 27 18:34:47 volumio volumio[1003]: info: CoreCommandRouter::volumioPushState May 27 18:34:47 volumio volumio[1003]: info: CoreStateMachine::pushState May 27 18:34:47 volumio volumio[1003]: info: CoreCommandRouter::volumioPushState May 27 18:34:47 volumio volumio[1003]: info: ------------------------------ 32ms May 27 18:34:47 volumio volumio[1003]: info: ------------------------------ 31ms May 27 18:34:47 volumio volumio[1003]: info: ------------------------------ 26ms May 27 18:34:55 volumio volumio[1003]: info: CoreCommandRouter::volumioStop May 27 18:34:55 volumio volumio[1003]: info: CoreStateMachine::stop May 27 18:34:55 volumio volumio[1003]: info: CoreStateMachine::setConsumeUpdateService undefined May 27 18:34:55 volumio volumio[1003]: info: CoreStateMachine::stPlaybackTimer May 27 18:34:55 volumio volumio[1003]: info: CoreStateMachine::updateTrackBlock May 27 18:34:55 volumio volumio[1003]: info: CorePlayQueue::getTrackBlock May 27 18:34:55 volumio volumio[1003]: info: CoreStateMachine::pushState May 27 18:34:55 volumio volumio[1003]: info: CorePlayQueue::getTrack 0 May 27 18:34:55 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 18:34:55 volumio volumio[1003]: info: CoreCommandRouter::volumioPushState May 27 18:34:55 volumio volumio[1003]: info: CoreStateMachine::serviceStop May 27 18:34:55 volumio volumio[1003]: info: CorePlayQueue::getTrack 0 May 27 18:34:55 volumio volumio[1003]: info: CoreCommandRouter::serviceStop May 27 18:34:55 volumio volumio[1003]: info: [1779899695102] ControllerWebradio::stop May 27 18:34:55 volumio volumio[1003]: verbose: ControllerMpd::sendMpdCommand stop May 27 18:34:55 volumio volumio[1003]: info: May 27 18:34:55 volumio volumio[1003]: ---------------------------- MPD announces state update: player May 27 18:34:55 volumio volumio[1003]: info: sendMpdCommand stop took 4 milliseconds May 27 18:34:55 volumio volumio[1003]: info: ControllerMpd::getState May 27 18:34:55 volumio volumio[1003]: verbose: ControllerMpd::sendMpdCommand status May 27 18:34:55 volumio volumio[1003]: info: sendMpdCommand status took 0 milliseconds May 27 18:34:55 volumio volumio[1003]: verbose: ControllerMpd::parseState May 27 18:34:55 volumio volumio[1003]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 18:34:55 volumio volumio[1003]: info: May 27 18:34:55 volumio volumio[1003]: ---------------------------- MPD announces state update: player May 27 18:34:55 volumio volumio[1003]: info: ControllerMpd::getState May 27 18:34:55 volumio volumio[1003]: verbose: ControllerMpd::sendMpdCommand status May 27 18:34:55 volumio volumio[1003]: info: May 27 18:34:55 volumio volumio[1003]: ---------------------------- MPD announces state update: player May 27 18:34:55 volumio volumio[1003]: info: ControllerMpd::getState May 27 18:34:55 volumio volumio[1003]: verbose: ControllerMpd::sendMpdCommand status May 27 18:34:55 volumio volumio[1003]: info: May 27 18:34:55 volumio volumio[1003]: ---------------------------- MPD announces state update: player May 27 18:34:55 volumio volumio[1003]: info: ControllerMpd::getState May 27 18:34:55 volumio volumio[1003]: verbose: ControllerMpd::sendMpdCommand status May 27 18:34:55 volumio volumio[1003]: info: sendMpdCommand playlistinfo took 4 milliseconds May 27 18:34:55 volumio volumio[1003]: info: sendMpdCommand status took 5 milliseconds May 27 18:34:55 volumio volumio[1003]: info: sendMpdCommand status took 2 milliseconds May 27 18:34:55 volumio volumio[1003]: info: sendMpdCommand status took 1 milliseconds May 27 18:34:55 volumio volumio[1003]: verbose: ControllerMpd::parseTrackInfo May 27 18:34:55 volumio volumio[1003]: verbose: ControllerMpd::parseState May 27 18:34:55 volumio volumio[1003]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 18:34:55 volumio volumio[1003]: verbose: ControllerMpd::parseState May 27 18:34:55 volumio volumio[1003]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 18:34:55 volumio volumio[1003]: verbose: ControllerMpd::parseState May 27 18:34:55 volumio volumio[1003]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 18:34:55 volumio volumio[1003]: info: ControllerMpd::pushState May 27 18:34:55 volumio volumio[1003]: info: CoreCommandRouter::servicePushState May 27 18:34:55 volumio volumio[1003]: info: CoreStateMachine::pushState May 27 18:34:55 volumio volumio[1003]: info: CorePlayQueue::getTrack 0 May 27 18:34:55 volumio volumio[1003]: info: CoreCommandRouter::volumioPushState May 27 18:34:55 volumio volumio[1003]: info: CorePlayQueue::getTrack 0 May 27 18:34:55 volumio volumio[1003]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd May 27 18:34:55 volumio volumio[1003]: info: ------------------------------ 11ms May 27 18:34:55 volumio volumio[1003]: info: sendMpdCommand playlistinfo took 5 milliseconds May 27 18:34:55 volumio volumio[1003]: info: sendMpdCommand playlistinfo took 5 milliseconds May 27 18:34:55 volumio volumio[1003]: info: sendMpdCommand playlistinfo took 5 milliseconds May 27 18:34:55 volumio volumio[1003]: verbose: ControllerMpd::parseTrackInfo May 27 18:34:55 volumio volumio[1003]: verbose: ControllerMpd::parseTrackInfo May 27 18:34:55 volumio volumio[1003]: verbose: ControllerMpd::parseTrackInfo May 27 18:34:55 volumio volumio[1003]: info: ControllerMpd::pushState May 27 18:34:55 volumio volumio[1003]: info: CoreCommandRouter::servicePushState May 27 18:34:55 volumio volumio[1003]: info: CoreStateMachine::pushState May 27 18:34:55 volumio volumio[1003]: info: CorePlayQueue::getTrack 0 May 27 18:34:55 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 18:34:55 volumio volumio[1003]: info: CoreCommandRouter::volumioPushState May 27 18:34:55 volumio volumio[1003]: info: CorePlayQueue::getTrack 0 May 27 18:34:55 volumio volumio[1003]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd May 27 18:34:55 volumio volumio[1003]: info: ControllerMpd::pushState May 27 18:34:55 volumio volumio[1003]: info: CoreCommandRouter::servicePushState May 27 18:34:55 volumio volumio[1003]: info: CoreStateMachine::pushState May 27 18:34:55 volumio volumio[1003]: info: CorePlayQueue::getTrack 0 May 27 18:34:55 volumio volumio[1003]: info: CoreCommandRouter::volumioPushState May 27 18:34:55 volumio volumio[1003]: info: CorePlayQueue::getTrack 0 May 27 18:34:55 volumio volumio[1003]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd May 27 18:34:55 volumio volumio[1003]: info: ControllerMpd::pushState May 27 18:34:55 volumio volumio[1003]: info: CoreCommandRouter::servicePushState May 27 18:34:55 volumio volumio[1003]: info: CoreStateMachine::pushState May 27 18:34:55 volumio volumio[1003]: info: CorePlayQueue::getTrack 0 May 27 18:34:55 volumio volumio[1003]: info: CoreCommandRouter::volumioPushState May 27 18:34:55 volumio volumio[1003]: info: CorePlayQueue::getTrack 0 May 27 18:34:55 volumio volumio[1003]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd May 27 18:34:55 volumio volumio[1003]: info: ------------------------------ 17ms May 27 18:34:55 volumio volumio[1003]: info: ------------------------------ 15ms May 27 18:34:55 volumio volumio[1003]: info: ------------------------------ 14ms May 27 18:34:55 volumio wpa_supplicant[883]: RRM: Ignoring radio measurement request: Not RRM network May 27 18:34:57 volumio volumio[1003]: info: CoreCommandRouter::volumioPlay May 27 18:34:57 volumio volumio[1003]: info: CoreStateMachine::play index undefined May 27 18:34:57 volumio volumio[1003]: info: CoreStateMachine::setConsumeUpdateService undefined May 27 18:34:57 volumio volumio[1003]: info: CorePlayQueue::getTrack 0 May 27 18:34:57 volumio volumio[1003]: info: CoreStateMachine::startPlaybackTimer May 27 18:34:57 volumio volumio[1003]: info: CorePlayQueue::getTrack 0 May 27 18:34:57 volumio volumio[1003]: info: [1779899697473] ControllerWebradio::clearAddPlayTrack May 27 18:34:57 volumio volumio[1003]: verbose: ControllerMpd::sendMpdCommand stop May 27 18:34:57 volumio volumio[1003]: info: sendMpdCommand stop took 1 milliseconds May 27 18:34:57 volumio volumio[1003]: verbose: ControllerMpd::sendMpdCommand clear May 27 18:34:57 volumio volumio[1003]: info: sendMpdCommand clear took 0 milliseconds May 27 18:34:57 volumio volumio[1003]: verbose: ControllerMpd::sendMpdCommand load "http://mscp3.live-streams.nl:8340/jazz-flac.flac" May 27 18:34:57 volumio volumio[1003]: info: May 27 18:34:57 volumio volumio[1003]: ---------------------------- MPD announces system playlist update May 27 18:34:57 volumio volumio[1003]: info: Ignoring MPD Status Update May 27 18:34:57 volumio volumio[1003]: info: May 27 18:34:57 volumio volumio[1003]: ---------------------------- MPD announces system playlist update May 27 18:34:57 volumio volumio[1003]: info: Ignoring MPD Status Update May 27 18:34:57 volumio volumio[1003]: info: May 27 18:34:57 volumio volumio[1003]: ---------------------------- MPD announces system playlist update May 27 18:34:57 volumio volumio[1003]: info: Ignoring MPD Status Update May 27 18:34:57 volumio volumio[1003]: info: May 27 18:34:57 volumio volumio[1003]: ---------------------------- MPD announces system playlist update May 27 18:34:57 volumio volumio[1003]: info: Ignoring MPD Status Update May 27 18:34:57 volumio volumio[1003]: verbose: ControllerMpd::sendMpdCommand add "http://mscp3.live-streams.nl:8340/jazz-flac.flac" May 27 18:34:57 volumio volumio[1003]: error: updateQueue error: null May 27 18:34:57 volumio volumio[1003]: error: updateQueue error: null May 27 18:34:57 volumio volumio[1003]: info: May 27 18:34:57 volumio volumio[1003]: ---------------------------- MPD announces system playlist update May 27 18:34:57 volumio volumio[1003]: info: Ignoring MPD Status Update May 27 18:34:57 volumio volumio[1003]: info: ------------------------------ 510ms May 27 18:34:57 volumio volumio[1003]: info: ------------------------------ 509ms May 27 18:34:57 volumio volumio[1003]: info: ------------------------------ 508ms May 27 18:34:57 volumio volumio[1003]: info: sendMpdCommand add "http://mscp3.live-streams.nl:8340/jazz-flac.flac" took 2 milliseconds May 27 18:34:57 volumio volumio[1003]: info: CoreStateMachine::setConsumeUpdateService mpd May 27 18:34:57 volumio volumio[1003]: verbose: ControllerMpd::sendMpdCommand play May 27 18:34:57 volumio volumio[1003]: info: May 27 18:34:57 volumio volumio[1003]: ---------------------------- MPD announces system playlist update May 27 18:34:57 volumio volumio[1003]: info: Ignoring MPD Status Update May 27 18:34:57 volumio volumio[1003]: info: May 27 18:34:57 volumio volumio[1003]: ---------------------------- MPD announces system playlist update May 27 18:34:57 volumio volumio[1003]: info: Ignoring MPD Status Update May 27 18:34:57 volumio volumio[1003]: info: May 27 18:34:57 volumio volumio[1003]: ---------------------------- MPD announces system playlist update May 27 18:34:57 volumio volumio[1003]: info: Ignoring MPD Status Update May 27 18:34:57 volumio volumio[1003]: info: ------------------------------ 7ms May 27 18:34:57 volumio volumio[1003]: info: sendMpdCommand play took 6 milliseconds May 27 18:34:57 volumio volumio[1003]: info: ------------------------------ 2ms May 27 18:34:57 volumio volumio[1003]: info: ------------------------------ 3ms May 27 18:34:57 volumio volumio[1003]: info: ------------------------------ 2ms May 27 18:34:58 volumio volumio[1003]: info: May 27 18:34:58 volumio volumio[1003]: ---------------------------- MPD announces state update: player May 27 18:34:58 volumio volumio[1003]: info: ControllerMpd::getState May 27 18:34:58 volumio volumio[1003]: verbose: ControllerMpd::sendMpdCommand status May 27 18:34:58 volumio volumio[1003]: info: May 27 18:34:58 volumio volumio[1003]: ---------------------------- MPD announces state update: player May 27 18:34:58 volumio volumio[1003]: info: ControllerMpd::getState May 27 18:34:58 volumio volumio[1003]: verbose: ControllerMpd::sendMpdCommand status May 27 18:34:58 volumio volumio[1003]: info: May 27 18:34:58 volumio volumio[1003]: ---------------------------- MPD announces state update: player May 27 18:34:58 volumio volumio[1003]: info: ControllerMpd::getState May 27 18:34:58 volumio volumio[1003]: verbose: ControllerMpd::sendMpdCommand status May 27 18:34:58 volumio volumio[1003]: info: May 27 18:34:58 volumio volumio[1003]: ---------------------------- MPD announces state update: player May 27 18:34:58 volumio volumio[1003]: info: ControllerMpd::getState May 27 18:34:58 volumio volumio[1003]: verbose: ControllerMpd::sendMpdCommand status May 27 18:34:58 volumio volumio[1003]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 May 27 18:34:58 volumio volumio[1003]: info: FusionDsp - ---- read samplerate from file: 44100 May 27 18:34:58 volumio volumio[1003]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 27 18:34:58 volumio volumio[1003]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 May 27 18:34:58 volumio volumio[1003]: info: FusionDsp - ---- read samplerate from file: 44100 May 27 18:34:58 volumio volumio[1003]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 27 18:34:58 volumio volumio[1003]: info: May 27 18:34:58 volumio volumio[1003]: ---------------------------- MPD announces state update: player May 27 18:34:58 volumio volumio[1003]: info: sendMpdCommand status took 18 milliseconds May 27 18:34:58 volumio volumio[1003]: info: sendMpdCommand status took 18 milliseconds May 27 18:34:58 volumio volumio[1003]: info: sendMpdCommand status took 18 milliseconds May 27 18:34:58 volumio volumio[1003]: info: sendMpdCommand status took 18 milliseconds May 27 18:34:58 volumio volumio[1003]: info: ControllerMpd::getState May 27 18:34:58 volumio volumio[1003]: verbose: ControllerMpd::sendMpdCommand status May 27 18:34:58 volumio volumio[1003]: verbose: ControllerMpd::parseState May 27 18:34:58 volumio volumio[1003]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 18:34:58 volumio volumio[1003]: verbose: ControllerMpd::parseState May 27 18:34:58 volumio volumio[1003]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 18:34:58 volumio volumio[1003]: verbose: ControllerMpd::parseState May 27 18:34:58 volumio volumio[1003]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 18:34:58 volumio volumio[1003]: verbose: ControllerMpd::parseState May 27 18:34:58 volumio volumio[1003]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 18:34:58 volumio volumio[1003]: info: May 27 18:34:58 volumio volumio[1003]: ---------------------------- MPD announces state update: player May 27 18:34:58 volumio volumio[1003]: info: ControllerMpd::getState May 27 18:34:58 volumio volumio[1003]: verbose: ControllerMpd::sendMpdCommand status May 27 18:34:58 volumio volumio[1003]: info: May 27 18:34:58 volumio volumio[1003]: ---------------------------- MPD announces state update: player May 27 18:34:58 volumio volumio[1003]: info: ControllerMpd::getState May 27 18:34:58 volumio volumio[1003]: verbose: ControllerMpd::sendMpdCommand status May 27 18:34:58 volumio volumio[1003]: info: May 27 18:34:58 volumio volumio[1003]: ---------------------------- MPD announces state update: player May 27 18:34:58 volumio volumio[1003]: info: ControllerMpd::getState May 27 18:34:58 volumio volumio[1003]: verbose: ControllerMpd::sendMpdCommand status May 27 18:34:58 volumio volumio[1003]: info: sendMpdCommand status took 4 milliseconds May 27 18:34:58 volumio volumio[1003]: info: sendMpdCommand playlistinfo took 4 milliseconds May 27 18:34:58 volumio volumio[1003]: info: sendMpdCommand playlistinfo took 4 milliseconds May 27 18:34:58 volumio volumio[1003]: info: sendMpdCommand playlistinfo took 4 milliseconds May 27 18:34:58 volumio volumio[1003]: info: sendMpdCommand playlistinfo took 3 milliseconds May 27 18:34:58 volumio volumio[1003]: info: sendMpdCommand status took 3 milliseconds May 27 18:34:58 volumio volumio[1003]: info: sendMpdCommand status took 2 milliseconds May 27 18:34:58 volumio volumio[1003]: info: sendMpdCommand status took 2 milliseconds May 27 18:34:58 volumio volumio[1003]: verbose: ControllerMpd::parseState May 27 18:34:58 volumio volumio[1003]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 18:34:58 volumio volumio[1003]: verbose: ControllerMpd::parseTrackInfo May 27 18:34:58 volumio volumio[1003]: verbose: ControllerMpd::parseTrackInfo May 27 18:34:58 volumio volumio[1003]: verbose: ControllerMpd::parseState May 27 18:34:58 volumio volumio[1003]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 18:34:58 volumio volumio[1003]: verbose: ControllerMpd::parseState May 27 18:34:58 volumio volumio[1003]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 18:34:58 volumio volumio[1003]: verbose: ControllerMpd::parseState May 27 18:34:58 volumio volumio[1003]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 18:34:58 volumio volumio[1003]: info: ControllerMpd::pushState May 27 18:34:58 volumio volumio[1003]: info: CoreCommandRouter::servicePushState May 27 18:34:58 volumio volumio[1003]: info: CorePlayQueue::getTrack 0 May 27 18:34:58 volumio volumio[1003]: verbose: STATE SERVICE {"status":"play","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":"jazz-flac.flac","artist":null,"album":null,"uri":"http://mscp3.live-streams.nl:8340/jazz-flac.flac","trackType":"flac"} May 27 18:34:58 volumio volumio[1003]: verbose: CURRENT POSITION 0 May 27 18:34:58 volumio volumio[1003]: info: CoreStateMachine::syncState stateService play May 27 18:34:58 volumio volumio[1003]: info: CoreStateMachine::syncState currentStatus stop May 27 18:34:58 volumio volumio[1003]: info: ControllerMpd::pushState May 27 18:34:58 volumio volumio[1003]: info: CoreCommandRouter::servicePushState May 27 18:34:58 volumio volumio[1003]: info: CorePlayQueue::getTrack 0 May 27 18:34:58 volumio volumio[1003]: verbose: STATE SERVICE {"status":"play","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":"jazz-flac.flac","artist":null,"album":null,"uri":"http://mscp3.live-streams.nl:8340/jazz-flac.flac","trackType":"flac"} May 27 18:34:58 volumio volumio[1003]: verbose: CURRENT POSITION 0 May 27 18:34:58 volumio volumio[1003]: info: CoreStateMachine::syncState stateService play May 27 18:34:58 volumio volumio[1003]: info: CoreStateMachine::syncState currentStatus play May 27 18:34:58 volumio volumio[1003]: info: Received an update from plugin. extracting info from payload May 27 18:34:58 volumio volumio[1003]: info: CoreStateMachine::pushState May 27 18:34:58 volumio volumio[1003]: info: CorePlayQueue::getTrack 0 May 27 18:34:58 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 18:34:58 volumio volumio[1003]: info: CoreCommandRouter::volumioPushState May 27 18:34:58 volumio volumio[1003]: info: CoreStateMachine::pushState May 27 18:34:58 volumio volumio[1003]: info: CoreCommandRouter::volumioPushState May 27 18:34:58 volumio volumio[1003]: info: ControllerMpd::pushState May 27 18:34:58 volumio volumio[1003]: info: CoreCommandRouter::servicePushState May 27 18:34:58 volumio volumio[1003]: info: CorePlayQueue::getTrack 0 May 27 18:34:58 volumio volumio[1003]: verbose: STATE SERVICE {"status":"play","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":"jazz-flac.flac","artist":null,"album":null,"uri":"http://mscp3.live-streams.nl:8340/jazz-flac.flac","trackType":"flac"} May 27 18:34:58 volumio volumio[1003]: verbose: CURRENT POSITION 0 May 27 18:34:58 volumio volumio[1003]: info: CoreStateMachine::syncState stateService play May 27 18:34:58 volumio volumio[1003]: info: CoreStateMachine::syncState currentStatus play May 27 18:34:58 volumio volumio[1003]: info: Received an update from plugin. extracting info from payload May 27 18:34:58 volumio volumio[1003]: info: CoreStateMachine::pushState May 27 18:34:58 volumio volumio[1003]: info: CorePlayQueue::getTrack 0 May 27 18:34:58 volumio volumio[1003]: info: CoreCommandRouter::volumioPushState May 27 18:34:58 volumio volumio[1003]: info: CoreStateMachine::pushState May 27 18:34:58 volumio volumio[1003]: info: CoreCommandRouter::volumioPushState May 27 18:34:58 volumio volumio[1003]: info: ControllerMpd::pushState May 27 18:34:58 volumio volumio[1003]: info: CoreCommandRouter::servicePushState May 27 18:34:58 volumio volumio[1003]: info: CorePlayQueue::getTrack 0 May 27 18:34:58 volumio volumio[1003]: verbose: STATE SERVICE {"status":"play","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":"jazz-flac.flac","artist":null,"album":null,"uri":"http://mscp3.live-streams.nl:8340/jazz-flac.flac","trackType":"flac"} May 27 18:34:58 volumio volumio[1003]: verbose: CURRENT POSITION 0 May 27 18:34:58 volumio volumio[1003]: info: CoreStateMachine::syncState stateService play May 27 18:34:58 volumio volumio[1003]: info: CoreStateMachine::syncState currentStatus play May 27 18:34:58 volumio volumio[1003]: info: Received an update from plugin. extracting info from payload May 27 18:34:58 volumio volumio[1003]: info: CoreStateMachine::pushState May 27 18:34:58 volumio volumio[1003]: info: CorePlayQueue::getTrack 0 May 27 18:34:58 volumio volumio[1003]: info: CoreCommandRouter::volumioPushState May 27 18:34:58 volumio volumio[1003]: info: CoreStateMachine::pushState May 27 18:34:58 volumio volumio[1003]: info: CoreCommandRouter::volumioPushState May 27 18:34:58 volumio volumio[1003]: info: ------------------------------ 35ms May 27 18:34:58 volumio volumio[1003]: info: ------------------------------ 41ms May 27 18:34:58 volumio volumio[1003]: info: ------------------------------ 40ms May 27 18:34:58 volumio volumio[1003]: info: ------------------------------ 41ms May 27 18:34:58 volumio volumio[1003]: error: FusionDsp - WebSocket error: [object Object] May 27 18:34:58 volumio volumio[1003]: error: FusionDsp - WebSocket error: [object Object] May 27 18:34:58 volumio volumio[1003]: info: sendMpdCommand playlistinfo took 19 milliseconds May 27 18:34:58 volumio volumio[1003]: info: sendMpdCommand playlistinfo took 19 milliseconds May 27 18:34:58 volumio volumio[1003]: info: sendMpdCommand playlistinfo took 20 milliseconds May 27 18:34:58 volumio volumio[1003]: info: sendMpdCommand playlistinfo took 20 milliseconds May 27 18:34:58 volumio volumio[1003]: verbose: ControllerMpd::parseTrackInfo May 27 18:34:58 volumio volumio[1003]: verbose: ControllerMpd::parseTrackInfo May 27 18:34:58 volumio volumio[1003]: verbose: ControllerMpd::parseTrackInfo May 27 18:34:58 volumio volumio[1003]: verbose: ControllerMpd::parseTrackInfo May 27 18:34:58 volumio volumio[1003]: info: ControllerMpd::pushState May 27 18:34:58 volumio volumio[1003]: info: CoreCommandRouter::servicePushState May 27 18:34:58 volumio volumio[1003]: info: CorePlayQueue::getTrack 0 May 27 18:34:58 volumio volumio[1003]: verbose: STATE SERVICE {"status":"play","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":"jazz-flac.flac","artist":null,"album":null,"uri":"http://mscp3.live-streams.nl:8340/jazz-flac.flac","trackType":"flac"} May 27 18:34:58 volumio volumio[1003]: verbose: CURRENT POSITION 0 May 27 18:34:58 volumio volumio[1003]: info: CoreStateMachine::syncState stateService play May 27 18:34:58 volumio volumio[1003]: info: CoreStateMachine::syncState currentStatus play May 27 18:34:58 volumio volumio[1003]: info: Received an update from plugin. extracting info from payload May 27 18:34:58 volumio volumio[1003]: info: CoreStateMachine::pushState May 27 18:34:58 volumio volumio[1003]: info: CorePlayQueue::getTrack 0 May 27 18:34:58 volumio volumio[1003]: info: CoreCommandRouter::volumioPushState May 27 18:34:58 volumio volumio[1003]: info: CoreStateMachine::pushState May 27 18:34:58 volumio volumio[1003]: info: CoreCommandRouter::volumioPushState May 27 18:34:58 volumio volumio[1003]: info: ControllerMpd::pushState May 27 18:34:58 volumio volumio[1003]: info: CoreCommandRouter::servicePushState May 27 18:34:58 volumio volumio[1003]: info: CorePlayQueue::getTrack 0 May 27 18:34:58 volumio volumio[1003]: verbose: STATE SERVICE {"status":"play","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":"jazz-flac.flac","artist":null,"album":null,"uri":"http://mscp3.live-streams.nl:8340/jazz-flac.flac","trackType":"flac"} May 27 18:34:58 volumio volumio[1003]: verbose: CURRENT POSITION 0 May 27 18:34:58 volumio volumio[1003]: info: CoreStateMachine::syncState stateService play May 27 18:34:58 volumio volumio[1003]: info: CoreStateMachine::syncState currentStatus play May 27 18:34:58 volumio volumio[1003]: info: Received an update from plugin. extracting info from payload May 27 18:34:58 volumio volumio[1003]: info: CoreStateMachine::pushState May 27 18:34:58 volumio volumio[1003]: info: CorePlayQueue::getTrack 0 May 27 18:34:58 volumio volumio[1003]: info: CoreCommandRouter::volumioPushState May 27 18:34:58 volumio volumio[1003]: info: CoreStateMachine::pushState May 27 18:34:58 volumio volumio[1003]: info: CoreCommandRouter::volumioPushState May 27 18:34:58 volumio volumio[1003]: info: ControllerMpd::pushState May 27 18:34:58 volumio volumio[1003]: info: CoreCommandRouter::servicePushState May 27 18:34:58 volumio volumio[1003]: info: CorePlayQueue::getTrack 0 May 27 18:34:58 volumio volumio[1003]: verbose: STATE SERVICE {"status":"play","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":"jazz-flac.flac","artist":null,"album":null,"uri":"http://mscp3.live-streams.nl:8340/jazz-flac.flac","trackType":"flac"} May 27 18:34:58 volumio volumio[1003]: verbose: CURRENT POSITION 0 May 27 18:34:58 volumio volumio[1003]: info: CoreStateMachine::syncState stateService play May 27 18:34:58 volumio volumio[1003]: info: CoreStateMachine::syncState currentStatus play May 27 18:34:58 volumio volumio[1003]: info: Received an update from plugin. extracting info from payload May 27 18:34:58 volumio volumio[1003]: info: CoreStateMachine::pushState May 27 18:34:58 volumio volumio[1003]: info: CorePlayQueue::getTrack 0 May 27 18:34:58 volumio volumio[1003]: info: CoreCommandRouter::volumioPushState May 27 18:34:58 volumio volumio[1003]: info: CoreStateMachine::pushState May 27 18:34:58 volumio volumio[1003]: info: CoreCommandRouter::volumioPushState May 27 18:34:58 volumio volumio[1003]: info: ControllerMpd::pushState May 27 18:34:58 volumio volumio[1003]: info: CoreCommandRouter::servicePushState May 27 18:34:58 volumio volumio[1003]: info: CorePlayQueue::getTrack 0 May 27 18:34:58 volumio volumio[1003]: verbose: STATE SERVICE {"status":"play","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":"jazz-flac.flac","artist":null,"album":null,"uri":"http://mscp3.live-streams.nl:8340/jazz-flac.flac","trackType":"flac"} May 27 18:34:58 volumio volumio[1003]: verbose: CURRENT POSITION 0 May 27 18:34:58 volumio volumio[1003]: info: CoreStateMachine::syncState stateService play May 27 18:34:58 volumio volumio[1003]: info: CoreStateMachine::syncState currentStatus play May 27 18:34:58 volumio volumio[1003]: info: Received an update from plugin. extracting info from payload May 27 18:34:58 volumio volumio[1003]: info: CoreStateMachine::pushState May 27 18:34:58 volumio volumio[1003]: info: CorePlayQueue::getTrack 0 May 27 18:34:58 volumio volumio[1003]: info: CoreCommandRouter::volumioPushState May 27 18:34:58 volumio volumio[1003]: info: CoreStateMachine::pushState May 27 18:34:58 volumio volumio[1003]: info: CoreCommandRouter::volumioPushState May 27 18:34:58 volumio volumio[1003]: info: ------------------------------ 42ms May 27 18:34:58 volumio volumio[1003]: info: ------------------------------ 41ms May 27 18:34:58 volumio volumio[1003]: info: ------------------------------ 40ms May 27 18:34:58 volumio volumio[1003]: info: ------------------------------ 40ms May 27 18:34:58 volumio volumio[1003]: info: May 27 18:34:58 volumio volumio[1003]: ---------------------------- MPD announces system playlist update May 27 18:34:58 volumio volumio[1003]: info: Ignoring MPD Status Update May 27 18:34:58 volumio volumio[1003]: info: May 27 18:34:58 volumio volumio[1003]: ---------------------------- MPD announces state update: player May 27 18:34:58 volumio volumio[1003]: info: ControllerMpd::getState May 27 18:34:58 volumio volumio[1003]: verbose: ControllerMpd::sendMpdCommand status May 27 18:34:58 volumio volumio[1003]: info: May 27 18:34:58 volumio volumio[1003]: ---------------------------- MPD announces system playlist update May 27 18:34:58 volumio volumio[1003]: info: Ignoring MPD Status Update May 27 18:34:58 volumio volumio[1003]: info: May 27 18:34:58 volumio volumio[1003]: ---------------------------- MPD announces state update: player May 27 18:34:58 volumio volumio[1003]: info: ControllerMpd::getState May 27 18:34:58 volumio volumio[1003]: verbose: ControllerMpd::sendMpdCommand status May 27 18:34:58 volumio volumio[1003]: info: May 27 18:34:58 volumio volumio[1003]: ---------------------------- MPD announces system playlist update May 27 18:34:58 volumio volumio[1003]: info: Ignoring MPD Status Update May 27 18:34:58 volumio volumio[1003]: info: May 27 18:34:58 volumio volumio[1003]: ---------------------------- MPD announces state update: player May 27 18:34:58 volumio volumio[1003]: info: ControllerMpd::getState May 27 18:34:58 volumio volumio[1003]: verbose: ControllerMpd::sendMpdCommand status May 27 18:34:58 volumio volumio[1003]: info: May 27 18:34:58 volumio volumio[1003]: ---------------------------- MPD announces system playlist update May 27 18:34:58 volumio volumio[1003]: info: Ignoring MPD Status Update May 27 18:34:58 volumio volumio[1003]: info: May 27 18:34:58 volumio volumio[1003]: ---------------------------- MPD announces state update: player May 27 18:34:58 volumio volumio[1003]: info: ControllerMpd::getState May 27 18:34:58 volumio volumio[1003]: verbose: ControllerMpd::sendMpdCommand status May 27 18:34:58 volumio volumio[1003]: info: ------------------------------ 6ms May 27 18:34:58 volumio volumio[1003]: info: sendMpdCommand status took 5 milliseconds May 27 18:34:58 volumio volumio[1003]: info: ------------------------------ 4ms May 27 18:34:58 volumio volumio[1003]: info: sendMpdCommand status took 4 milliseconds May 27 18:34:58 volumio volumio[1003]: info: ------------------------------ 3ms May 27 18:34:58 volumio volumio[1003]: info: sendMpdCommand status took 2 milliseconds May 27 18:34:58 volumio volumio[1003]: info: ------------------------------ 2ms May 27 18:34:58 volumio volumio[1003]: info: sendMpdCommand status took 2 milliseconds May 27 18:34:58 volumio volumio[1003]: verbose: ControllerMpd::parseState May 27 18:34:58 volumio volumio[1003]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 18:34:58 volumio volumio[1003]: verbose: ControllerMpd::parseState May 27 18:34:58 volumio volumio[1003]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 18:34:58 volumio volumio[1003]: verbose: ControllerMpd::parseState May 27 18:34:58 volumio volumio[1003]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 18:34:58 volumio volumio[1003]: verbose: ControllerMpd::parseState May 27 18:34:58 volumio volumio[1003]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 18:34:58 volumio volumio[1003]: info: sendMpdCommand playlistinfo took 1 milliseconds May 27 18:34:58 volumio volumio[1003]: info: sendMpdCommand playlistinfo took 1 milliseconds May 27 18:34:58 volumio volumio[1003]: info: sendMpdCommand playlistinfo took 1 milliseconds May 27 18:34:58 volumio volumio[1003]: info: sendMpdCommand playlistinfo took 1 milliseconds May 27 18:34:58 volumio volumio[1003]: verbose: ControllerMpd::parseTrackInfo May 27 18:34:58 volumio volumio[1003]: verbose: ControllerMpd::parseTrackInfo May 27 18:34:58 volumio volumio[1003]: verbose: ControllerMpd::parseTrackInfo May 27 18:34:58 volumio volumio[1003]: verbose: ControllerMpd::parseTrackInfo May 27 18:34:58 volumio volumio[1003]: info: ControllerMpd::pushState May 27 18:34:58 volumio volumio[1003]: info: CoreCommandRouter::servicePushState May 27 18:34:58 volumio volumio[1003]: info: CorePlayQueue::getTrack 0 May 27 18:34:58 volumio volumio[1003]: verbose: STATE SERVICE {"status":"play","position":0,"seek":709,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"jazz-flac.flac","artist":"Naim Jazz","album":null,"uri":"http://mscp3.live-streams.nl:8340/jazz-flac.flac","trackType":"flac"} May 27 18:34:58 volumio volumio[1003]: verbose: CURRENT POSITION 0 May 27 18:34:58 volumio volumio[1003]: info: CoreStateMachine::syncState stateService play May 27 18:34:58 volumio volumio[1003]: info: CoreStateMachine::syncState currentStatus play May 27 18:34:58 volumio volumio[1003]: info: Received an update from plugin. extracting info from payload May 27 18:34:58 volumio volumio[1003]: info: CoreStateMachine::pushState May 27 18:34:58 volumio volumio[1003]: info: CorePlayQueue::getTrack 0 May 27 18:34:58 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 18:34:58 volumio volumio[1003]: info: CoreCommandRouter::volumioPushState May 27 18:34:58 volumio volumio[1003]: info: CoreStateMachine::pushState May 27 18:34:58 volumio volumio[1003]: info: CoreCommandRouter::volumioPushState May 27 18:34:58 volumio volumio[1003]: info: ControllerMpd::pushState May 27 18:34:58 volumio volumio[1003]: info: CoreCommandRouter::servicePushState May 27 18:34:58 volumio volumio[1003]: info: CorePlayQueue::getTrack 0 May 27 18:34:58 volumio volumio[1003]: verbose: STATE SERVICE {"status":"play","position":0,"seek":732,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"jazz-flac.flac","artist":"Naim Jazz","album":null,"uri":"http://mscp3.live-streams.nl:8340/jazz-flac.flac","trackType":"flac"} May 27 18:34:58 volumio volumio[1003]: verbose: CURRENT POSITION 0 May 27 18:34:58 volumio volumio[1003]: info: CoreStateMachine::syncState stateService play May 27 18:34:58 volumio volumio[1003]: info: CoreStateMachine::syncState currentStatus play May 27 18:34:58 volumio volumio[1003]: info: Received an update from plugin. extracting info from payload May 27 18:34:58 volumio volumio[1003]: info: CoreStateMachine::pushState May 27 18:34:58 volumio volumio[1003]: info: CorePlayQueue::getTrack 0 May 27 18:34:58 volumio volumio[1003]: info: CoreCommandRouter::volumioPushState May 27 18:34:58 volumio volumio[1003]: info: CoreStateMachine::pushState May 27 18:34:58 volumio volumio[1003]: info: CoreCommandRouter::volumioPushState May 27 18:34:58 volumio volumio[1003]: info: ControllerMpd::pushState May 27 18:34:58 volumio volumio[1003]: info: CoreCommandRouter::servicePushState May 27 18:34:58 volumio volumio[1003]: info: CorePlayQueue::getTrack 0 May 27 18:34:58 volumio volumio[1003]: verbose: STATE SERVICE {"status":"play","position":0,"seek":960,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"jazz-flac.flac","artist":"Naim Jazz","album":null,"uri":"http://mscp3.live-streams.nl:8340/jazz-flac.flac","trackType":"flac"} May 27 18:34:58 volumio volumio[1003]: verbose: CURRENT POSITION 0 May 27 18:34:58 volumio volumio[1003]: info: CoreStateMachine::syncState stateService play May 27 18:34:58 volumio volumio[1003]: info: CoreStateMachine::syncState currentStatus play May 27 18:34:58 volumio volumio[1003]: info: Received an update from plugin. extracting info from payload May 27 18:34:58 volumio volumio[1003]: info: CoreStateMachine::pushState May 27 18:34:58 volumio volumio[1003]: info: CorePlayQueue::getTrack 0 May 27 18:34:58 volumio volumio[1003]: info: CoreCommandRouter::volumioPushState May 27 18:34:58 volumio volumio[1003]: info: CoreStateMachine::pushState May 27 18:34:58 volumio volumio[1003]: info: CoreCommandRouter::volumioPushState May 27 18:34:58 volumio volumio[1003]: info: ControllerMpd::pushState May 27 18:34:58 volumio volumio[1003]: info: CoreCommandRouter::servicePushState May 27 18:34:58 volumio volumio[1003]: info: CorePlayQueue::getTrack 0 May 27 18:34:58 volumio volumio[1003]: verbose: STATE SERVICE {"status":"play","position":0,"seek":960,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"jazz-flac.flac","artist":"Naim Jazz","album":null,"uri":"http://mscp3.live-streams.nl:8340/jazz-flac.flac","trackType":"flac"} May 27 18:34:58 volumio volumio[1003]: verbose: CURRENT POSITION 0 May 27 18:34:58 volumio volumio[1003]: info: CoreStateMachine::syncState stateService play May 27 18:34:58 volumio volumio[1003]: info: CoreStateMachine::syncState currentStatus play May 27 18:34:58 volumio volumio[1003]: info: Received an update from plugin. extracting info from payload May 27 18:34:58 volumio volumio[1003]: info: CoreStateMachine::pushState May 27 18:34:58 volumio volumio[1003]: info: CorePlayQueue::getTrack 0 May 27 18:34:58 volumio volumio[1003]: info: CoreCommandRouter::volumioPushState May 27 18:34:58 volumio volumio[1003]: info: CoreStateMachine::pushState May 27 18:34:58 volumio volumio[1003]: info: CoreCommandRouter::volumioPushState May 27 18:34:58 volumio volumio[1003]: info: ------------------------------ 22ms May 27 18:34:58 volumio volumio[1003]: info: ------------------------------ 21ms May 27 18:34:58 volumio volumio[1003]: info: ------------------------------ 20ms May 27 18:34:58 volumio volumio[1003]: info: ------------------------------ 19ms May 27 18:34:59 volumio wpa_supplicant[883]: RRM: Ignoring radio measurement request: Not RRM network May 27 18:35:00 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri May 27 18:35:00 volumio volumio[1003]: info: Preload queue cleared May 27 18:35:03 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri May 27 18:35:03 volumio volumio[1003]: info: Getting webradio selection May 27 18:35:03 volumio volumio[1003]: info: Preload queue cleared May 27 18:35:09 volumio volumio[1003]: info: Preload queue cleared May 27 18:35:09 volumio volumio[1003]: info: CoreCommandRouter::volumioReplaceandPlayItems May 27 18:35:09 volumio volumio[1003]: info: CoreStateMachine::ClearQueue May 27 18:35:09 volumio volumio[1003]: info: CoreStateMachine::stop May 27 18:35:09 volumio volumio[1003]: info: CoreStateMachine::setConsumeUpdateService undefined May 27 18:35:09 volumio volumio[1003]: info: CoreStateMachine::stPlaybackTimer May 27 18:35:09 volumio volumio[1003]: info: CoreStateMachine::updateTrackBlock May 27 18:35:09 volumio volumio[1003]: info: CorePlayQueue::getTrackBlock May 27 18:35:09 volumio volumio[1003]: info: CoreStateMachine::pushState May 27 18:35:09 volumio volumio[1003]: info: CorePlayQueue::getTrack 0 May 27 18:35:09 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 18:35:09 volumio volumio[1003]: info: CoreCommandRouter::volumioPushState May 27 18:35:09 volumio volumio[1003]: info: CoreStateMachine::serviceStop May 27 18:35:09 volumio volumio[1003]: info: CorePlayQueue::getTrack 0 May 27 18:35:09 volumio volumio[1003]: info: CoreCommandRouter::serviceStop May 27 18:35:09 volumio volumio[1003]: info: [1779899709971] ControllerWebradio::stop May 27 18:35:09 volumio volumio[1003]: verbose: ControllerMpd::sendMpdCommand stop May 27 18:35:09 volumio volumio[1003]: info: CorePlayQueue::clearPlayQueue May 27 18:35:09 volumio volumio[1003]: info: CorePlayQueue::saveQueue May 27 18:35:09 volumio volumio[1003]: info: CoreCommandRouter::volumioPushQueue May 27 18:35:09 volumio volumio[1003]: info: CoreStateMachine::addQueueItems May 27 18:35:09 volumio volumio[1003]: info: CorePlayQueue::addQueueItems May 27 18:35:09 volumio volumio[1003]: info: Preload queue cleared May 27 18:35:09 volumio volumio[1003]: info: Adding Item to queue: http://mscp3.live-streams.nl:8340/jazz-flac.flac May 27 18:35:09 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri May 27 18:35:09 volumio volumio[1003]: info: CoreCommandRouter::volumioPushQueue May 27 18:35:09 volumio volumio[1003]: info: CorePlayQueue::saveQueue May 27 18:35:09 volumio volumio[1003]: info: CoreStateMachine::updateTrackBlock May 27 18:35:09 volumio volumio[1003]: info: CorePlayQueue::getTrackBlock May 27 18:35:09 volumio volumio[1003]: info: CoreCommandRouter::volumioPlay May 27 18:35:09 volumio volumio[1003]: info: CoreStateMachine::play index 0 May 27 18:35:09 volumio volumio[1003]: info: CoreStateMachine::setConsumeUpdateService undefined May 27 18:35:09 volumio volumio[1003]: info: CoreStateMachine::stop May 27 18:35:09 volumio volumio[1003]: info: CoreStateMachine::setConsumeUpdateService undefined May 27 18:35:09 volumio volumio[1003]: info: CoreStateMachine::play index undefined May 27 18:35:09 volumio volumio[1003]: info: CoreStateMachine::setConsumeUpdateService undefined May 27 18:35:09 volumio volumio[1003]: info: CorePlayQueue::getTrack 0 May 27 18:35:09 volumio volumio[1003]: info: CoreStateMachine::startPlaybackTimer May 27 18:35:09 volumio volumio[1003]: info: CorePlayQueue::getTrack 0 May 27 18:35:09 volumio volumio[1003]: info: [1779899709978] ControllerWebradio::clearAddPlayTrack May 27 18:35:09 volumio volumio[1003]: verbose: ControllerMpd::sendMpdCommand stop May 27 18:35:09 volumio volumio[1003]: info: May 27 18:35:09 volumio volumio[1003]: ---------------------------- MPD announces state update: player May 27 18:35:09 volumio volumio[1003]: info: sendMpdCommand stop took 9 milliseconds May 27 18:35:09 volumio volumio[1003]: info: ControllerMpd::getState May 27 18:35:09 volumio volumio[1003]: verbose: ControllerMpd::sendMpdCommand status May 27 18:35:09 volumio volumio[1003]: info: sendMpdCommand stop took 3 milliseconds May 27 18:35:09 volumio volumio[1003]: verbose: ControllerMpd::sendMpdCommand clear May 27 18:35:09 volumio volumio[1003]: info: May 27 18:35:09 volumio volumio[1003]: ---------------------------- MPD announces state update: player May 27 18:35:09 volumio volumio[1003]: info: ControllerMpd::getState May 27 18:35:09 volumio volumio[1003]: verbose: ControllerMpd::sendMpdCommand status May 27 18:35:09 volumio volumio[1003]: info: May 27 18:35:09 volumio volumio[1003]: ---------------------------- MPD announces state update: player May 27 18:35:09 volumio volumio[1003]: info: ControllerMpd::getState May 27 18:35:09 volumio volumio[1003]: verbose: ControllerMpd::sendMpdCommand status May 27 18:35:09 volumio volumio[1003]: info: May 27 18:35:09 volumio volumio[1003]: ---------------------------- MPD announces state update: player May 27 18:35:09 volumio volumio[1003]: info: ControllerMpd::getState May 27 18:35:09 volumio volumio[1003]: verbose: ControllerMpd::sendMpdCommand status May 27 18:35:09 volumio volumio[1003]: info: May 27 18:35:09 volumio volumio[1003]: ---------------------------- MPD announces system playlist update May 27 18:35:09 volumio volumio[1003]: info: Ignoring MPD Status Update May 27 18:35:09 volumio volumio[1003]: info: sendMpdCommand status took 3 milliseconds May 27 18:35:09 volumio volumio[1003]: info: sendMpdCommand clear took 3 milliseconds May 27 18:35:09 volumio volumio[1003]: info: sendMpdCommand status took 2 milliseconds May 27 18:35:09 volumio volumio[1003]: info: sendMpdCommand status took 2 milliseconds May 27 18:35:09 volumio volumio[1003]: info: sendMpdCommand status took 1 milliseconds May 27 18:35:09 volumio volumio[1003]: verbose: ControllerMpd::parseState May 27 18:35:09 volumio volumio[1003]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 18:35:09 volumio volumio[1003]: verbose: ControllerMpd::sendMpdCommand load "http://mscp3.live-streams.nl:8340/jazz-flac.flac" May 27 18:35:09 volumio volumio[1003]: verbose: ControllerMpd::parseState May 27 18:35:09 volumio volumio[1003]: verbose: ControllerMpd::parseState May 27 18:35:09 volumio volumio[1003]: verbose: ControllerMpd::parseState May 27 18:35:09 volumio volumio[1003]: info: ControllerMpd::pushState May 27 18:35:09 volumio volumio[1003]: info: CoreCommandRouter::servicePushState May 27 18:35:09 volumio volumio[1003]: info: CoreStateMachine::pushState May 27 18:35:09 volumio volumio[1003]: info: CorePlayQueue::getTrack 0 May 27 18:35:09 volumio volumio[1003]: info: CoreCommandRouter::volumioPushState May 27 18:35:09 volumio volumio[1003]: info: CorePlayQueue::getTrack 0 May 27 18:35:09 volumio volumio[1003]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd May 27 18:35:09 volumio volumio[1003]: info: ControllerMpd::pushState May 27 18:35:09 volumio volumio[1003]: info: CoreCommandRouter::servicePushState May 27 18:35:09 volumio volumio[1003]: info: CoreStateMachine::pushState May 27 18:35:09 volumio volumio[1003]: info: CorePlayQueue::getTrack 0 May 27 18:35:09 volumio volumio[1003]: info: CoreCommandRouter::volumioPushState May 27 18:35:09 volumio volumio[1003]: info: CorePlayQueue::getTrack 0 May 27 18:35:09 volumio volumio[1003]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd May 27 18:35:09 volumio volumio[1003]: info: ControllerMpd::pushState May 27 18:35:09 volumio volumio[1003]: info: CoreCommandRouter::servicePushState May 27 18:35:09 volumio volumio[1003]: info: CoreStateMachine::pushState May 27 18:35:09 volumio volumio[1003]: info: CorePlayQueue::getTrack 0 May 27 18:35:09 volumio volumio[1003]: info: CoreCommandRouter::volumioPushState May 27 18:35:09 volumio volumio[1003]: info: CorePlayQueue::getTrack 0 May 27 18:35:09 volumio volumio[1003]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd May 27 18:35:09 volumio volumio[1003]: info: ------------------------------ 12ms May 27 18:35:09 volumio volumio[1003]: info: ------------------------------ 12ms May 27 18:35:09 volumio volumio[1003]: info: ------------------------------ 11ms May 27 18:35:09 volumio volumio[1003]: info: May 27 18:35:09 volumio volumio[1003]: ---------------------------- MPD announces system playlist update May 27 18:35:09 volumio volumio[1003]: info: Ignoring MPD Status Update May 27 18:35:09 volumio volumio[1003]: info: May 27 18:35:09 volumio volumio[1003]: ---------------------------- MPD announces system playlist update May 27 18:35:09 volumio volumio[1003]: info: Ignoring MPD Status Update May 27 18:35:09 volumio volumio[1003]: info: May 27 18:35:09 volumio volumio[1003]: ---------------------------- MPD announces system playlist update May 27 18:35:09 volumio volumio[1003]: info: Ignoring MPD Status Update May 27 18:35:09 volumio volumio[1003]: error: updateQueue error: null May 27 18:35:09 volumio volumio[1003]: info: ------------------------------ 13ms May 27 18:35:09 volumio volumio[1003]: info: sendMpdCommand playlistinfo took 13 milliseconds May 27 18:35:09 volumio volumio[1003]: verbose: ControllerMpd::parseTrackInfo May 27 18:35:09 volumio volumio[1003]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined May 27 18:35:09 volumio volumio[1003]: info: ------------------------------ 18ms May 27 18:35:10 volumio go-librespot[1403]: time="2026-05-27T18:35:10+02:00" level=trace msg="sent dealer ping" May 27 18:35:10 volumio volumio[1003]: verbose: ControllerMpd::sendMpdCommand add "http://mscp3.live-streams.nl:8340/jazz-flac.flac" May 27 18:35:10 volumio volumio[1003]: error: updateQueue error: null May 27 18:35:10 volumio volumio[1003]: error: updateQueue error: null May 27 18:35:10 volumio volumio[1003]: error: updateQueue error: null May 27 18:35:10 volumio volumio[1003]: info: May 27 18:35:10 volumio volumio[1003]: ---------------------------- MPD announces system playlist update May 27 18:35:10 volumio volumio[1003]: info: Ignoring MPD Status Update May 27 18:35:10 volumio volumio[1003]: info: ------------------------------ 354ms May 27 18:35:10 volumio volumio[1003]: info: ------------------------------ 353ms May 27 18:35:10 volumio volumio[1003]: info: sendMpdCommand add "http://mscp3.live-streams.nl:8340/jazz-flac.flac" took 1 milliseconds May 27 18:35:10 volumio volumio[1003]: info: CoreStateMachine::setConsumeUpdateService mpd May 27 18:35:10 volumio volumio[1003]: verbose: ControllerMpd::sendMpdCommand play May 27 18:35:10 volumio volumio[1003]: info: May 27 18:35:10 volumio volumio[1003]: ---------------------------- MPD announces system playlist update May 27 18:35:10 volumio volumio[1003]: info: Ignoring MPD Status Update May 27 18:35:10 volumio volumio[1003]: info: May 27 18:35:10 volumio volumio[1003]: ---------------------------- MPD announces system playlist update May 27 18:35:10 volumio volumio[1003]: info: Ignoring MPD Status Update May 27 18:35:10 volumio volumio[1003]: info: May 27 18:35:10 volumio volumio[1003]: ---------------------------- MPD announces system playlist update May 27 18:35:10 volumio volumio[1003]: info: Ignoring MPD Status Update May 27 18:35:10 volumio volumio[1003]: info: ------------------------------ 3ms May 27 18:35:10 volumio volumio[1003]: info: sendMpdCommand play took 3 milliseconds May 27 18:35:10 volumio volumio[1003]: info: ------------------------------ 3ms May 27 18:35:10 volumio volumio[1003]: info: ------------------------------ 2ms May 27 18:35:10 volumio go-librespot[1403]: time="2026-05-27T18:35:10+02:00" level=trace msg="received dealer pong" May 27 18:35:11 volumio volumio[1003]: info: May 27 18:35:11 volumio volumio[1003]: ---------------------------- MPD announces state update: player May 27 18:35:11 volumio volumio[1003]: info: ControllerMpd::getState May 27 18:35:11 volumio volumio[1003]: verbose: ControllerMpd::sendMpdCommand status May 27 18:35:11 volumio volumio[1003]: info: May 27 18:35:11 volumio volumio[1003]: ---------------------------- MPD announces state update: player May 27 18:35:11 volumio volumio[1003]: info: ControllerMpd::getState May 27 18:35:11 volumio volumio[1003]: verbose: ControllerMpd::sendMpdCommand status May 27 18:35:11 volumio volumio[1003]: info: May 27 18:35:11 volumio volumio[1003]: ---------------------------- MPD announces state update: player May 27 18:35:11 volumio volumio[1003]: info: ControllerMpd::getState May 27 18:35:11 volumio volumio[1003]: verbose: ControllerMpd::sendMpdCommand status May 27 18:35:11 volumio volumio[1003]: info: May 27 18:35:11 volumio volumio[1003]: ---------------------------- MPD announces state update: player May 27 18:35:11 volumio volumio[1003]: info: ControllerMpd::getState May 27 18:35:11 volumio volumio[1003]: verbose: ControllerMpd::sendMpdCommand status May 27 18:35:11 volumio volumio[1003]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 May 27 18:35:11 volumio volumio[1003]: info: FusionDsp - ---- read samplerate from file: 44100 May 27 18:35:11 volumio volumio[1003]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 27 18:35:11 volumio volumio[1003]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 May 27 18:35:11 volumio volumio[1003]: info: FusionDsp - ---- read samplerate from file: 44100 May 27 18:35:11 volumio volumio[1003]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 27 18:35:11 volumio volumio[1003]: info: May 27 18:35:11 volumio volumio[1003]: ---------------------------- MPD announces state update: player May 27 18:35:11 volumio volumio[1003]: info: sendMpdCommand status took 20 milliseconds May 27 18:35:11 volumio volumio[1003]: info: sendMpdCommand status took 20 milliseconds May 27 18:35:11 volumio volumio[1003]: info: sendMpdCommand status took 20 milliseconds May 27 18:35:11 volumio volumio[1003]: info: sendMpdCommand status took 20 milliseconds May 27 18:35:11 volumio volumio[1003]: info: ControllerMpd::getState May 27 18:35:11 volumio volumio[1003]: verbose: ControllerMpd::sendMpdCommand status May 27 18:35:11 volumio volumio[1003]: verbose: ControllerMpd::parseState May 27 18:35:11 volumio volumio[1003]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 18:35:11 volumio volumio[1003]: verbose: ControllerMpd::parseState May 27 18:35:11 volumio volumio[1003]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 18:35:11 volumio volumio[1003]: verbose: ControllerMpd::parseState May 27 18:35:11 volumio volumio[1003]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 18:35:11 volumio volumio[1003]: verbose: ControllerMpd::parseState May 27 18:35:11 volumio volumio[1003]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 18:35:11 volumio volumio[1003]: info: May 27 18:35:11 volumio volumio[1003]: ---------------------------- MPD announces state update: player May 27 18:35:11 volumio volumio[1003]: info: ControllerMpd::getState May 27 18:35:11 volumio volumio[1003]: verbose: ControllerMpd::sendMpdCommand status May 27 18:35:11 volumio volumio[1003]: info: May 27 18:35:11 volumio volumio[1003]: ---------------------------- MPD announces state update: player May 27 18:35:11 volumio volumio[1003]: info: ControllerMpd::getState May 27 18:35:11 volumio volumio[1003]: verbose: ControllerMpd::sendMpdCommand status May 27 18:35:11 volumio volumio[1003]: info: May 27 18:35:11 volumio volumio[1003]: ---------------------------- MPD announces state update: player May 27 18:35:11 volumio volumio[1003]: info: ControllerMpd::getState May 27 18:35:11 volumio volumio[1003]: verbose: ControllerMpd::sendMpdCommand status May 27 18:35:11 volumio volumio[1003]: info: sendMpdCommand status took 3 milliseconds May 27 18:35:11 volumio volumio[1003]: info: sendMpdCommand playlistinfo took 4 milliseconds May 27 18:35:11 volumio volumio[1003]: info: sendMpdCommand playlistinfo took 4 milliseconds May 27 18:35:11 volumio volumio[1003]: info: sendMpdCommand playlistinfo took 4 milliseconds May 27 18:35:11 volumio volumio[1003]: info: sendMpdCommand playlistinfo took 4 milliseconds May 27 18:35:11 volumio volumio[1003]: info: sendMpdCommand status took 3 milliseconds May 27 18:35:11 volumio volumio[1003]: info: sendMpdCommand status took 2 milliseconds May 27 18:35:11 volumio volumio[1003]: info: sendMpdCommand status took 2 milliseconds May 27 18:35:11 volumio volumio[1003]: verbose: ControllerMpd::parseState May 27 18:35:11 volumio volumio[1003]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 18:35:11 volumio volumio[1003]: verbose: ControllerMpd::parseTrackInfo May 27 18:35:11 volumio volumio[1003]: verbose: ControllerMpd::parseTrackInfo May 27 18:35:11 volumio volumio[1003]: verbose: ControllerMpd::parseTrackInfo May 27 18:35:11 volumio volumio[1003]: verbose: ControllerMpd::parseTrackInfo May 27 18:35:11 volumio volumio[1003]: verbose: ControllerMpd::parseState May 27 18:35:11 volumio volumio[1003]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 18:35:11 volumio volumio[1003]: verbose: ControllerMpd::parseState May 27 18:35:11 volumio volumio[1003]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 18:35:11 volumio volumio[1003]: verbose: ControllerMpd::parseState May 27 18:35:11 volumio volumio[1003]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 18:35:11 volumio volumio[1003]: info: ControllerMpd::pushState May 27 18:35:11 volumio volumio[1003]: info: CoreCommandRouter::servicePushState May 27 18:35:11 volumio volumio[1003]: info: CorePlayQueue::getTrack 0 May 27 18:35:11 volumio volumio[1003]: verbose: STATE SERVICE {"status":"play","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":"jazz-flac.flac","artist":null,"album":null,"uri":"http://mscp3.live-streams.nl:8340/jazz-flac.flac","trackType":"flac"} May 27 18:35:11 volumio volumio[1003]: verbose: CURRENT POSITION 0 May 27 18:35:11 volumio volumio[1003]: info: CoreStateMachine::syncState stateService play May 27 18:35:11 volumio volumio[1003]: info: CoreStateMachine::syncState currentStatus stop May 27 18:35:11 volumio volumio[1003]: info: ControllerMpd::pushState May 27 18:35:11 volumio volumio[1003]: info: CoreCommandRouter::servicePushState May 27 18:35:11 volumio volumio[1003]: info: CorePlayQueue::getTrack 0 May 27 18:35:11 volumio volumio[1003]: verbose: STATE SERVICE {"status":"play","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":"jazz-flac.flac","artist":null,"album":null,"uri":"http://mscp3.live-streams.nl:8340/jazz-flac.flac","trackType":"flac"} May 27 18:35:11 volumio volumio[1003]: verbose: CURRENT POSITION 0 May 27 18:35:11 volumio volumio[1003]: info: CoreStateMachine::syncState stateService play May 27 18:35:11 volumio volumio[1003]: info: CoreStateMachine::syncState currentStatus play May 27 18:35:11 volumio volumio[1003]: info: Received an update from plugin. extracting info from payload May 27 18:35:11 volumio volumio[1003]: info: CoreStateMachine::pushState May 27 18:35:11 volumio volumio[1003]: info: CorePlayQueue::getTrack 0 May 27 18:35:11 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 18:35:11 volumio volumio[1003]: info: CoreCommandRouter::volumioPushState May 27 18:35:11 volumio volumio[1003]: info: CoreStateMachine::pushState May 27 18:35:11 volumio volumio[1003]: info: CoreCommandRouter::volumioPushState May 27 18:35:11 volumio volumio[1003]: info: ControllerMpd::pushState May 27 18:35:11 volumio volumio[1003]: info: CoreCommandRouter::servicePushState May 27 18:35:11 volumio volumio[1003]: info: CorePlayQueue::getTrack 0 May 27 18:35:11 volumio volumio[1003]: verbose: STATE SERVICE {"status":"play","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":"jazz-flac.flac","artist":null,"album":null,"uri":"http://mscp3.live-streams.nl:8340/jazz-flac.flac","trackType":"flac"} May 27 18:35:11 volumio volumio[1003]: verbose: CURRENT POSITION 0 May 27 18:35:11 volumio volumio[1003]: info: CoreStateMachine::syncState stateService play May 27 18:35:11 volumio volumio[1003]: info: CoreStateMachine::syncState currentStatus play May 27 18:35:11 volumio volumio[1003]: info: Received an update from plugin. extracting info from payload May 27 18:35:11 volumio volumio[1003]: info: CoreStateMachine::pushState May 27 18:35:11 volumio volumio[1003]: info: CorePlayQueue::getTrack 0 May 27 18:35:11 volumio volumio[1003]: info: CoreCommandRouter::volumioPushState May 27 18:35:11 volumio volumio[1003]: info: CoreStateMachine::pushState May 27 18:35:11 volumio volumio[1003]: info: CoreCommandRouter::volumioPushState May 27 18:35:11 volumio volumio[1003]: info: ControllerMpd::pushState May 27 18:35:11 volumio volumio[1003]: info: CoreCommandRouter::servicePushState May 27 18:35:11 volumio volumio[1003]: info: CorePlayQueue::getTrack 0 May 27 18:35:11 volumio volumio[1003]: verbose: STATE SERVICE {"status":"play","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":"jazz-flac.flac","artist":null,"album":null,"uri":"http://mscp3.live-streams.nl:8340/jazz-flac.flac","trackType":"flac"} May 27 18:35:11 volumio volumio[1003]: verbose: CURRENT POSITION 0 May 27 18:35:11 volumio volumio[1003]: info: CoreStateMachine::syncState stateService play May 27 18:35:11 volumio volumio[1003]: info: CoreStateMachine::syncState currentStatus play May 27 18:35:11 volumio volumio[1003]: info: Received an update from plugin. extracting info from payload May 27 18:35:11 volumio volumio[1003]: info: CoreStateMachine::pushState May 27 18:35:11 volumio volumio[1003]: info: CorePlayQueue::getTrack 0 May 27 18:35:11 volumio volumio[1003]: info: CoreCommandRouter::volumioPushState May 27 18:35:11 volumio volumio[1003]: info: CoreStateMachine::pushState May 27 18:35:11 volumio volumio[1003]: info: CoreCommandRouter::volumioPushState May 27 18:35:11 volumio volumio[1003]: info: ------------------------------ 37ms May 27 18:35:11 volumio volumio[1003]: info: ------------------------------ 43ms May 27 18:35:11 volumio volumio[1003]: info: ------------------------------ 43ms May 27 18:35:11 volumio volumio[1003]: info: ------------------------------ 43ms May 27 18:35:11 volumio volumio[1003]: error: FusionDsp - WebSocket error: [object Object] May 27 18:35:11 volumio volumio[1003]: info: sendMpdCommand playlistinfo took 22 milliseconds May 27 18:35:11 volumio volumio[1003]: info: sendMpdCommand playlistinfo took 21 milliseconds May 27 18:35:11 volumio volumio[1003]: info: sendMpdCommand playlistinfo took 18 milliseconds May 27 18:35:11 volumio volumio[1003]: verbose: ControllerMpd::parseTrackInfo May 27 18:35:11 volumio volumio[1003]: info: ControllerMpd::pushState May 27 18:35:11 volumio volumio[1003]: info: CoreCommandRouter::servicePushState May 27 18:35:11 volumio volumio[1003]: info: CorePlayQueue::getTrack 0 May 27 18:35:11 volumio volumio[1003]: verbose: STATE SERVICE {"status":"play","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":"jazz-flac.flac","artist":null,"album":null,"uri":"http://mscp3.live-streams.nl:8340/jazz-flac.flac","trackType":"flac"} May 27 18:35:11 volumio volumio[1003]: verbose: CURRENT POSITION 0 May 27 18:35:11 volumio volumio[1003]: info: CoreStateMachine::syncState stateService play May 27 18:35:11 volumio volumio[1003]: info: CoreStateMachine::syncState currentStatus play May 27 18:35:11 volumio volumio[1003]: info: Received an update from plugin. extracting info from payload May 27 18:35:11 volumio volumio[1003]: info: CoreStateMachine::pushState May 27 18:35:11 volumio volumio[1003]: info: CorePlayQueue::getTrack 0 May 27 18:35:11 volumio volumio[1003]: info: CoreCommandRouter::volumioPushState May 27 18:35:11 volumio volumio[1003]: info: CoreStateMachine::pushState May 27 18:35:11 volumio volumio[1003]: info: CoreCommandRouter::volumioPushState May 27 18:35:11 volumio volumio[1003]: info: ControllerMpd::pushState May 27 18:35:11 volumio volumio[1003]: info: CoreCommandRouter::servicePushState May 27 18:35:11 volumio volumio[1003]: info: CorePlayQueue::getTrack 0 May 27 18:35:11 volumio volumio[1003]: verbose: STATE SERVICE {"status":"play","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":"jazz-flac.flac","artist":null,"album":null,"uri":"http://mscp3.live-streams.nl:8340/jazz-flac.flac","trackType":"flac"} May 27 18:35:11 volumio volumio[1003]: verbose: CURRENT POSITION 0 May 27 18:35:11 volumio volumio[1003]: info: CoreStateMachine::syncState stateService play May 27 18:35:11 volumio volumio[1003]: info: CoreStateMachine::syncState currentStatus play May 27 18:35:11 volumio volumio[1003]: info: Received an update from plugin. extracting info from payload May 27 18:35:11 volumio volumio[1003]: info: CoreStateMachine::pushState May 27 18:35:11 volumio volumio[1003]: info: CorePlayQueue::getTrack 0 May 27 18:35:11 volumio volumio[1003]: info: CoreCommandRouter::volumioPushState May 27 18:35:11 volumio volumio[1003]: info: CoreStateMachine::pushState May 27 18:35:11 volumio volumio[1003]: info: CoreCommandRouter::volumioPushState May 27 18:35:11 volumio volumio[1003]: info: ControllerMpd::pushState May 27 18:35:11 volumio volumio[1003]: info: CoreCommandRouter::servicePushState May 27 18:35:11 volumio volumio[1003]: info: CorePlayQueue::getTrack 0 May 27 18:35:11 volumio volumio[1003]: verbose: STATE SERVICE {"status":"play","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":"jazz-flac.flac","artist":null,"album":null,"uri":"http://mscp3.live-streams.nl:8340/jazz-flac.flac","trackType":"flac"} May 27 18:35:11 volumio volumio[1003]: verbose: CURRENT POSITION 0 May 27 18:35:11 volumio volumio[1003]: info: CoreStateMachine::syncState stateService play May 27 18:35:11 volumio volumio[1003]: info: CoreStateMachine::syncState currentStatus play May 27 18:35:11 volumio volumio[1003]: info: Received an update from plugin. extracting info from payload May 27 18:35:11 volumio volumio[1003]: info: CoreStateMachine::pushState May 27 18:35:11 volumio volumio[1003]: info: CorePlayQueue::getTrack 0 May 27 18:35:11 volumio volumio[1003]: info: CoreCommandRouter::volumioPushState May 27 18:35:11 volumio volumio[1003]: info: CoreStateMachine::pushState May 27 18:35:11 volumio volumio[1003]: info: CoreCommandRouter::volumioPushState May 27 18:35:11 volumio volumio[1003]: info: ControllerMpd::pushState May 27 18:35:11 volumio volumio[1003]: info: CoreCommandRouter::servicePushState May 27 18:35:11 volumio volumio[1003]: info: CorePlayQueue::getTrack 0 May 27 18:35:11 volumio volumio[1003]: verbose: STATE SERVICE {"status":"play","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":"jazz-flac.flac","artist":null,"album":null,"uri":"http://mscp3.live-streams.nl:8340/jazz-flac.flac","trackType":"flac"} May 27 18:35:11 volumio volumio[1003]: verbose: CURRENT POSITION 0 May 27 18:35:11 volumio volumio[1003]: info: CoreStateMachine::syncState stateService play May 27 18:35:11 volumio volumio[1003]: info: CoreStateMachine::syncState currentStatus play May 27 18:35:11 volumio volumio[1003]: info: Received an update from plugin. extracting info from payload May 27 18:35:11 volumio volumio[1003]: info: CoreStateMachine::pushState May 27 18:35:11 volumio volumio[1003]: info: CorePlayQueue::getTrack 0 May 27 18:35:11 volumio volumio[1003]: info: CoreCommandRouter::volumioPushState May 27 18:35:11 volumio volumio[1003]: info: CoreStateMachine::pushState May 27 18:35:11 volumio volumio[1003]: info: CoreCommandRouter::volumioPushState May 27 18:35:11 volumio volumio[1003]: info: ------------------------------ 45ms May 27 18:35:11 volumio volumio[1003]: info: ------------------------------ 44ms May 27 18:35:11 volumio volumio[1003]: info: ------------------------------ 43ms May 27 18:35:11 volumio volumio[1003]: info: ------------------------------ 43ms May 27 18:35:11 volumio volumio[1003]: info: May 27 18:35:11 volumio volumio[1003]: ---------------------------- MPD announces system playlist update May 27 18:35:11 volumio volumio[1003]: info: Ignoring MPD Status Update May 27 18:35:11 volumio volumio[1003]: info: May 27 18:35:11 volumio volumio[1003]: ---------------------------- MPD announces state update: player May 27 18:35:11 volumio volumio[1003]: info: ControllerMpd::getState May 27 18:35:11 volumio volumio[1003]: verbose: ControllerMpd::sendMpdCommand status May 27 18:35:11 volumio volumio[1003]: info: May 27 18:35:11 volumio volumio[1003]: ---------------------------- MPD announces system playlist update May 27 18:35:11 volumio volumio[1003]: info: Ignoring MPD Status Update May 27 18:35:11 volumio volumio[1003]: info: May 27 18:35:11 volumio volumio[1003]: ---------------------------- MPD announces state update: player May 27 18:35:11 volumio volumio[1003]: info: ControllerMpd::getState May 27 18:35:11 volumio volumio[1003]: verbose: ControllerMpd::sendMpdCommand status May 27 18:35:11 volumio volumio[1003]: info: ------------------------------ 3ms May 27 18:35:11 volumio volumio[1003]: info: sendMpdCommand status took 2 milliseconds May 27 18:35:11 volumio volumio[1003]: info: ------------------------------ 2ms May 27 18:35:11 volumio volumio[1003]: info: sendMpdCommand status took 1 milliseconds May 27 18:35:11 volumio volumio[1003]: verbose: ControllerMpd::parseState May 27 18:35:11 volumio volumio[1003]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 18:35:11 volumio volumio[1003]: verbose: ControllerMpd::parseState May 27 18:35:11 volumio volumio[1003]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 18:35:11 volumio volumio[1003]: info: May 27 18:35:11 volumio volumio[1003]: ---------------------------- MPD announces system playlist update May 27 18:35:11 volumio volumio[1003]: info: Ignoring MPD Status Update May 27 18:35:11 volumio volumio[1003]: info: May 27 18:35:11 volumio volumio[1003]: ---------------------------- MPD announces state update: player May 27 18:35:11 volumio volumio[1003]: info: ControllerMpd::getState May 27 18:35:11 volumio volumio[1003]: verbose: ControllerMpd::sendMpdCommand status May 27 18:35:11 volumio volumio[1003]: info: May 27 18:35:11 volumio volumio[1003]: ---------------------------- MPD announces system playlist update May 27 18:35:11 volumio volumio[1003]: info: Ignoring MPD Status Update May 27 18:35:11 volumio volumio[1003]: info: May 27 18:35:11 volumio volumio[1003]: ---------------------------- MPD announces state update: player May 27 18:35:11 volumio volumio[1003]: info: ControllerMpd::getState May 27 18:35:11 volumio volumio[1003]: verbose: ControllerMpd::sendMpdCommand status May 27 18:35:11 volumio volumio[1003]: info: sendMpdCommand playlistinfo took 4 milliseconds May 27 18:35:11 volumio volumio[1003]: info: sendMpdCommand playlistinfo took 4 milliseconds May 27 18:35:11 volumio volumio[1003]: info: ------------------------------ 3ms May 27 18:35:11 volumio volumio[1003]: info: sendMpdCommand status took 3 milliseconds May 27 18:35:11 volumio volumio[1003]: info: ------------------------------ 2ms May 27 18:35:11 volumio volumio[1003]: info: sendMpdCommand status took 3 milliseconds May 27 18:35:11 volumio volumio[1003]: verbose: ControllerMpd::parseTrackInfo May 27 18:35:11 volumio volumio[1003]: verbose: ControllerMpd::parseTrackInfo May 27 18:35:11 volumio volumio[1003]: verbose: ControllerMpd::parseState May 27 18:35:11 volumio volumio[1003]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 18:35:11 volumio volumio[1003]: verbose: ControllerMpd::parseState May 27 18:35:11 volumio volumio[1003]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 18:35:11 volumio volumio[1003]: info: ControllerMpd::pushState May 27 18:35:11 volumio volumio[1003]: info: CoreCommandRouter::servicePushState May 27 18:35:11 volumio volumio[1003]: info: CorePlayQueue::getTrack 0 May 27 18:35:11 volumio volumio[1003]: verbose: STATE SERVICE {"status":"play","position":0,"seek":457,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"jazz-flac.flac","artist":"Naim Jazz","album":null,"uri":"http://mscp3.live-streams.nl:8340/jazz-flac.flac","trackType":"flac"} May 27 18:35:11 volumio volumio[1003]: verbose: CURRENT POSITION 0 May 27 18:35:11 volumio volumio[1003]: info: CoreStateMachine::syncState stateService play May 27 18:35:11 volumio volumio[1003]: info: CoreStateMachine::syncState currentStatus play May 27 18:35:11 volumio volumio[1003]: info: Received an update from plugin. extracting info from payload May 27 18:35:11 volumio volumio[1003]: info: CoreStateMachine::pushState May 27 18:35:11 volumio volumio[1003]: info: CorePlayQueue::getTrack 0 May 27 18:35:11 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 18:35:11 volumio volumio[1003]: info: CoreCommandRouter::volumioPushState May 27 18:35:11 volumio volumio[1003]: info: CoreStateMachine::pushState May 27 18:35:11 volumio volumio[1003]: info: CoreCommandRouter::volumioPushState May 27 18:35:11 volumio volumio[1003]: info: ControllerMpd::pushState May 27 18:35:11 volumio volumio[1003]: info: CoreCommandRouter::servicePushState May 27 18:35:11 volumio volumio[1003]: info: CorePlayQueue::getTrack 0 May 27 18:35:11 volumio volumio[1003]: verbose: STATE SERVICE {"status":"play","position":0,"seek":960,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"jazz-flac.flac","artist":"Naim Jazz","album":null,"uri":"http://mscp3.live-streams.nl:8340/jazz-flac.flac","trackType":"flac"} May 27 18:35:11 volumio volumio[1003]: verbose: CURRENT POSITION 0 May 27 18:35:11 volumio volumio[1003]: info: CoreStateMachine::syncState stateService play May 27 18:35:11 volumio volumio[1003]: info: CoreStateMachine::syncState currentStatus play May 27 18:35:11 volumio volumio[1003]: info: Received an update from plugin. extracting info from payload May 27 18:35:11 volumio volumio[1003]: info: CoreStateMachine::pushState May 27 18:35:11 volumio volumio[1003]: info: CorePlayQueue::getTrack 0 May 27 18:35:11 volumio volumio[1003]: info: CoreCommandRouter::volumioPushState May 27 18:35:11 volumio volumio[1003]: info: CoreStateMachine::pushState May 27 18:35:11 volumio volumio[1003]: info: CoreCommandRouter::volumioPushState May 27 18:35:11 volumio volumio[1003]: info: ------------------------------ 17ms May 27 18:35:11 volumio volumio[1003]: info: ------------------------------ 17ms May 27 18:35:11 volumio volumio[1003]: info: sendMpdCommand playlistinfo took 10 milliseconds May 27 18:35:11 volumio volumio[1003]: info: sendMpdCommand playlistinfo took 11 milliseconds May 27 18:35:11 volumio volumio[1003]: verbose: ControllerMpd::parseTrackInfo May 27 18:35:11 volumio volumio[1003]: verbose: ControllerMpd::parseTrackInfo May 27 18:35:11 volumio volumio[1003]: info: ControllerMpd::pushState May 27 18:35:11 volumio volumio[1003]: info: CoreCommandRouter::servicePushState May 27 18:35:11 volumio volumio[1003]: info: CorePlayQueue::getTrack 0 May 27 18:35:11 volumio volumio[1003]: verbose: STATE SERVICE {"status":"play","position":0,"seek":960,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"jazz-flac.flac","artist":"Naim Jazz","album":null,"uri":"http://mscp3.live-streams.nl:8340/jazz-flac.flac","trackType":"flac"} May 27 18:35:11 volumio volumio[1003]: verbose: CURRENT POSITION 0 May 27 18:35:11 volumio volumio[1003]: info: CoreStateMachine::syncState stateService play May 27 18:35:11 volumio volumio[1003]: info: CoreStateMachine::syncState currentStatus play May 27 18:35:11 volumio volumio[1003]: info: Received an update from plugin. extracting info from payload May 27 18:35:11 volumio volumio[1003]: info: CoreStateMachine::pushState May 27 18:35:11 volumio volumio[1003]: info: CorePlayQueue::getTrack 0 May 27 18:35:11 volumio volumio[1003]: info: CoreCommandRouter::volumioPushState May 27 18:35:11 volumio volumio[1003]: info: CoreStateMachine::pushState May 27 18:35:11 volumio volumio[1003]: info: CoreCommandRouter::volumioPushState May 27 18:35:11 volumio volumio[1003]: info: ControllerMpd::pushState May 27 18:35:11 volumio volumio[1003]: info: CoreCommandRouter::servicePushState May 27 18:35:11 volumio volumio[1003]: info: CorePlayQueue::getTrack 0 May 27 18:35:11 volumio volumio[1003]: verbose: STATE SERVICE {"status":"play","position":0,"seek":960,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"jazz-flac.flac","artist":"Naim Jazz","album":null,"uri":"http://mscp3.live-streams.nl:8340/jazz-flac.flac","trackType":"flac"} May 27 18:35:11 volumio volumio[1003]: verbose: CURRENT POSITION 0 May 27 18:35:11 volumio volumio[1003]: info: CoreStateMachine::syncState stateService play May 27 18:35:11 volumio volumio[1003]: info: CoreStateMachine::syncState currentStatus play May 27 18:35:11 volumio volumio[1003]: info: Received an update from plugin. extracting info from payload May 27 18:35:11 volumio volumio[1003]: info: CoreStateMachine::pushState May 27 18:35:11 volumio volumio[1003]: info: CorePlayQueue::getTrack 0 May 27 18:35:11 volumio volumio[1003]: info: CoreCommandRouter::volumioPushState May 27 18:35:11 volumio volumio[1003]: info: CoreStateMachine::pushState May 27 18:35:11 volumio volumio[1003]: info: CoreCommandRouter::volumioPushState May 27 18:35:11 volumio volumio[1003]: info: ------------------------------ 23ms May 27 18:35:11 volumio volumio[1003]: info: ------------------------------ 22ms May 27 18:35:24 volumio volumio[1003]: info: Preload queue cleared May 27 18:35:24 volumio volumio[1003]: info: CoreCommandRouter::volumioReplaceandPlayItems May 27 18:35:24 volumio volumio[1003]: info: CoreStateMachine::ClearQueue May 27 18:35:24 volumio volumio[1003]: info: CoreStateMachine::stop May 27 18:35:24 volumio volumio[1003]: info: CoreStateMachine::setConsumeUpdateService undefined May 27 18:35:24 volumio volumio[1003]: info: CoreStateMachine::stPlaybackTimer May 27 18:35:24 volumio volumio[1003]: info: CoreStateMachine::updateTrackBlock May 27 18:35:24 volumio volumio[1003]: info: CorePlayQueue::getTrackBlock May 27 18:35:24 volumio volumio[1003]: info: CoreStateMachine::pushState May 27 18:35:24 volumio volumio[1003]: info: CorePlayQueue::getTrack 0 May 27 18:35:24 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 18:35:24 volumio volumio[1003]: info: CoreCommandRouter::volumioPushState May 27 18:35:24 volumio volumio[1003]: info: CoreStateMachine::serviceStop May 27 18:35:24 volumio volumio[1003]: info: CorePlayQueue::getTrack 0 May 27 18:35:24 volumio volumio[1003]: info: CoreCommandRouter::serviceStop May 27 18:35:24 volumio volumio[1003]: info: [1779899724233] ControllerWebradio::stop May 27 18:35:24 volumio volumio[1003]: verbose: ControllerMpd::sendMpdCommand stop May 27 18:35:24 volumio volumio[1003]: info: CorePlayQueue::clearPlayQueue May 27 18:35:24 volumio volumio[1003]: info: CorePlayQueue::saveQueue May 27 18:35:24 volumio volumio[1003]: info: CoreCommandRouter::volumioPushQueue May 27 18:35:24 volumio volumio[1003]: info: CoreStateMachine::addQueueItems May 27 18:35:24 volumio volumio[1003]: info: CorePlayQueue::addQueueItems May 27 18:35:24 volumio volumio[1003]: info: Preload queue cleared May 27 18:35:24 volumio volumio[1003]: info: Adding Item to queue: http://stream.radioparadise.com/flacm May 27 18:35:24 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri May 27 18:35:24 volumio volumio[1003]: info: CoreCommandRouter::volumioPushQueue May 27 18:35:24 volumio volumio[1003]: info: CorePlayQueue::saveQueue May 27 18:35:24 volumio volumio[1003]: info: CoreStateMachine::updateTrackBlock May 27 18:35:24 volumio volumio[1003]: info: CorePlayQueue::getTrackBlock May 27 18:35:24 volumio volumio[1003]: info: CoreCommandRouter::volumioPlay May 27 18:35:24 volumio volumio[1003]: info: CoreStateMachine::play index 0 May 27 18:35:24 volumio volumio[1003]: info: CoreStateMachine::setConsumeUpdateService undefined May 27 18:35:24 volumio volumio[1003]: info: CoreStateMachine::stop May 27 18:35:24 volumio volumio[1003]: info: CoreStateMachine::setConsumeUpdateService undefined May 27 18:35:24 volumio volumio[1003]: info: CoreStateMachine::play index undefined May 27 18:35:24 volumio volumio[1003]: info: CoreStateMachine::setConsumeUpdateService undefined May 27 18:35:24 volumio volumio[1003]: info: CorePlayQueue::getTrack 0 May 27 18:35:24 volumio volumio[1003]: info: CoreStateMachine::startPlaybackTimer May 27 18:35:24 volumio volumio[1003]: info: CorePlayQueue::getTrack 0 May 27 18:35:24 volumio volumio[1003]: info: [1779899724239] ControllerWebradio::clearAddPlayTrack May 27 18:35:24 volumio volumio[1003]: verbose: ControllerMpd::sendMpdCommand stop May 27 18:35:24 volumio volumio[1003]: info: May 27 18:35:24 volumio volumio[1003]: ---------------------------- MPD announces state update: player May 27 18:35:24 volumio volumio[1003]: info: sendMpdCommand stop took 11 milliseconds May 27 18:35:24 volumio volumio[1003]: info: ControllerMpd::getState May 27 18:35:24 volumio volumio[1003]: verbose: ControllerMpd::sendMpdCommand status May 27 18:35:24 volumio volumio[1003]: info: sendMpdCommand stop took 5 milliseconds May 27 18:35:24 volumio volumio[1003]: verbose: ControllerMpd::sendMpdCommand clear May 27 18:35:24 volumio volumio[1003]: info: May 27 18:35:24 volumio volumio[1003]: ---------------------------- MPD announces state update: player May 27 18:35:24 volumio volumio[1003]: info: ControllerMpd::getState May 27 18:35:24 volumio volumio[1003]: verbose: ControllerMpd::sendMpdCommand status May 27 18:35:24 volumio volumio[1003]: info: May 27 18:35:24 volumio volumio[1003]: ---------------------------- MPD announces state update: player May 27 18:35:24 volumio volumio[1003]: info: ControllerMpd::getState May 27 18:35:24 volumio volumio[1003]: verbose: ControllerMpd::sendMpdCommand status May 27 18:35:24 volumio volumio[1003]: info: May 27 18:35:24 volumio volumio[1003]: ---------------------------- MPD announces state update: player May 27 18:35:24 volumio volumio[1003]: info: ControllerMpd::getState May 27 18:35:24 volumio volumio[1003]: verbose: ControllerMpd::sendMpdCommand status May 27 18:35:24 volumio volumio[1003]: info: May 27 18:35:24 volumio volumio[1003]: ---------------------------- MPD announces system playlist update May 27 18:35:24 volumio volumio[1003]: info: Ignoring MPD Status Update May 27 18:35:24 volumio volumio[1003]: info: sendMpdCommand status took 3 milliseconds May 27 18:35:24 volumio volumio[1003]: info: sendMpdCommand clear took 3 milliseconds May 27 18:35:24 volumio volumio[1003]: info: sendMpdCommand status took 2 milliseconds May 27 18:35:24 volumio volumio[1003]: info: sendMpdCommand status took 2 milliseconds May 27 18:35:24 volumio volumio[1003]: info: sendMpdCommand status took 2 milliseconds May 27 18:35:24 volumio volumio[1003]: verbose: ControllerMpd::parseState May 27 18:35:24 volumio volumio[1003]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 18:35:24 volumio volumio[1003]: verbose: ControllerMpd::sendMpdCommand load "http://stream.radioparadise.com/flacm" May 27 18:35:24 volumio volumio[1003]: verbose: ControllerMpd::parseState May 27 18:35:24 volumio volumio[1003]: verbose: ControllerMpd::parseState May 27 18:35:24 volumio volumio[1003]: verbose: ControllerMpd::parseState May 27 18:35:24 volumio volumio[1003]: info: ControllerMpd::pushState May 27 18:35:24 volumio volumio[1003]: info: CoreCommandRouter::servicePushState May 27 18:35:24 volumio volumio[1003]: info: CoreStateMachine::pushState May 27 18:35:24 volumio volumio[1003]: info: CorePlayQueue::getTrack 0 May 27 18:35:24 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 18:35:24 volumio volumio[1003]: info: CoreCommandRouter::volumioPushState May 27 18:35:24 volumio volumio[1003]: info: CorePlayQueue::getTrack 0 May 27 18:35:24 volumio volumio[1003]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd May 27 18:35:24 volumio volumio[1003]: info: ControllerMpd::pushState May 27 18:35:24 volumio volumio[1003]: info: CoreCommandRouter::servicePushState May 27 18:35:24 volumio volumio[1003]: info: CoreStateMachine::pushState May 27 18:35:24 volumio volumio[1003]: info: CorePlayQueue::getTrack 0 May 27 18:35:24 volumio volumio[1003]: info: CoreCommandRouter::volumioPushState May 27 18:35:24 volumio volumio[1003]: info: CorePlayQueue::getTrack 0 May 27 18:35:24 volumio volumio[1003]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd May 27 18:35:24 volumio volumio[1003]: info: ControllerMpd::pushState May 27 18:35:24 volumio volumio[1003]: info: CoreCommandRouter::servicePushState May 27 18:35:24 volumio volumio[1003]: info: CoreStateMachine::pushState May 27 18:35:24 volumio volumio[1003]: info: CorePlayQueue::getTrack 0 May 27 18:35:24 volumio volumio[1003]: info: CoreCommandRouter::volumioPushState May 27 18:35:24 volumio volumio[1003]: info: CorePlayQueue::getTrack 0 May 27 18:35:24 volumio volumio[1003]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd May 27 18:35:24 volumio volumio[1003]: info: ------------------------------ 10ms May 27 18:35:24 volumio volumio[1003]: info: ------------------------------ 10ms May 27 18:35:24 volumio volumio[1003]: info: ------------------------------ 10ms May 27 18:35:24 volumio volumio[1003]: info: May 27 18:35:24 volumio volumio[1003]: ---------------------------- MPD announces system playlist update May 27 18:35:24 volumio volumio[1003]: info: Ignoring MPD Status Update May 27 18:35:24 volumio volumio[1003]: info: May 27 18:35:24 volumio volumio[1003]: ---------------------------- MPD announces system playlist update May 27 18:35:24 volumio volumio[1003]: info: Ignoring MPD Status Update May 27 18:35:24 volumio volumio[1003]: info: May 27 18:35:24 volumio volumio[1003]: ---------------------------- MPD announces system playlist update May 27 18:35:24 volumio volumio[1003]: info: Ignoring MPD Status Update May 27 18:35:24 volumio volumio[1003]: error: updateQueue error: null May 27 18:35:24 volumio volumio[1003]: info: ------------------------------ 11ms May 27 18:35:24 volumio volumio[1003]: info: sendMpdCommand playlistinfo took 10 milliseconds May 27 18:35:24 volumio volumio[1003]: verbose: ControllerMpd::parseTrackInfo May 27 18:35:24 volumio volumio[1003]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined May 27 18:35:24 volumio volumio[1003]: info: ------------------------------ 15ms May 27 18:35:25 volumio volumio[1003]: verbose: ControllerMpd::sendMpdCommand add "http://stream.radioparadise.com/flacm" May 27 18:35:25 volumio volumio[1003]: error: updateQueue error: null May 27 18:35:25 volumio volumio[1003]: error: updateQueue error: null May 27 18:35:25 volumio volumio[1003]: error: updateQueue error: null May 27 18:35:25 volumio volumio[1003]: info: May 27 18:35:25 volumio volumio[1003]: ---------------------------- MPD announces system playlist update May 27 18:35:25 volumio volumio[1003]: info: Ignoring MPD Status Update May 27 18:35:25 volumio volumio[1003]: info: ------------------------------ 959ms May 27 18:35:25 volumio volumio[1003]: info: ------------------------------ 958ms May 27 18:35:25 volumio volumio[1003]: info: ------------------------------ 958ms May 27 18:35:25 volumio volumio[1003]: info: sendMpdCommand add "http://stream.radioparadise.com/flacm" took 1 milliseconds May 27 18:35:25 volumio volumio[1003]: info: CoreStateMachine::setConsumeUpdateService mpd May 27 18:35:25 volumio volumio[1003]: verbose: ControllerMpd::sendMpdCommand play May 27 18:35:25 volumio volumio[1003]: info: May 27 18:35:25 volumio volumio[1003]: ---------------------------- MPD announces system playlist update May 27 18:35:25 volumio volumio[1003]: info: Ignoring MPD Status Update May 27 18:35:25 volumio volumio[1003]: info: May 27 18:35:25 volumio volumio[1003]: ---------------------------- MPD announces system playlist update May 27 18:35:25 volumio volumio[1003]: info: Ignoring MPD Status Update May 27 18:35:25 volumio volumio[1003]: info: May 27 18:35:25 volumio volumio[1003]: ---------------------------- MPD announces system playlist update May 27 18:35:25 volumio volumio[1003]: info: Ignoring MPD Status Update May 27 18:35:25 volumio volumio[1003]: info: ------------------------------ 4ms May 27 18:35:25 volumio volumio[1003]: info: sendMpdCommand play took 3 milliseconds May 27 18:35:25 volumio volumio[1003]: info: ------------------------------ 3ms May 27 18:35:25 volumio volumio[1003]: info: ------------------------------ 2ms May 27 18:35:25 volumio volumio[1003]: info: May 27 18:35:25 volumio volumio[1003]: ---------------------------- MPD announces state update: player May 27 18:35:25 volumio volumio[1003]: info: ControllerMpd::getState May 27 18:35:25 volumio volumio[1003]: verbose: ControllerMpd::sendMpdCommand status May 27 18:35:25 volumio volumio[1003]: info: May 27 18:35:25 volumio volumio[1003]: ---------------------------- MPD announces state update: player May 27 18:35:25 volumio volumio[1003]: info: ControllerMpd::getState May 27 18:35:25 volumio volumio[1003]: verbose: ControllerMpd::sendMpdCommand status May 27 18:35:25 volumio volumio[1003]: info: May 27 18:35:25 volumio volumio[1003]: ---------------------------- MPD announces state update: player May 27 18:35:25 volumio volumio[1003]: info: ControllerMpd::getState May 27 18:35:25 volumio volumio[1003]: verbose: ControllerMpd::sendMpdCommand status May 27 18:35:25 volumio volumio[1003]: info: May 27 18:35:25 volumio volumio[1003]: ---------------------------- MPD announces state update: player May 27 18:35:25 volumio volumio[1003]: info: ControllerMpd::getState May 27 18:35:25 volumio volumio[1003]: verbose: ControllerMpd::sendMpdCommand status May 27 18:35:25 volumio volumio[1003]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 May 27 18:35:25 volumio volumio[1003]: info: FusionDsp - ---- read samplerate from file: 44100 May 27 18:35:25 volumio volumio[1003]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 27 18:35:25 volumio volumio[1003]: info: May 27 18:35:25 volumio volumio[1003]: ---------------------------- MPD announces state update: player May 27 18:35:25 volumio volumio[1003]: info: sendMpdCommand status took 19 milliseconds May 27 18:35:25 volumio volumio[1003]: info: sendMpdCommand status took 18 milliseconds May 27 18:35:25 volumio volumio[1003]: info: sendMpdCommand status took 17 milliseconds May 27 18:35:25 volumio volumio[1003]: info: sendMpdCommand status took 17 milliseconds May 27 18:35:25 volumio volumio[1003]: info: ControllerMpd::getState May 27 18:35:25 volumio volumio[1003]: verbose: ControllerMpd::sendMpdCommand status May 27 18:35:25 volumio volumio[1003]: verbose: ControllerMpd::parseState May 27 18:35:25 volumio volumio[1003]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 18:35:25 volumio volumio[1003]: verbose: ControllerMpd::parseState May 27 18:35:25 volumio volumio[1003]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 18:35:25 volumio volumio[1003]: verbose: ControllerMpd::parseState May 27 18:35:25 volumio volumio[1003]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 18:35:25 volumio volumio[1003]: verbose: ControllerMpd::parseState May 27 18:35:25 volumio volumio[1003]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 18:35:25 volumio volumio[1003]: info: May 27 18:35:25 volumio volumio[1003]: ---------------------------- MPD announces state update: player May 27 18:35:25 volumio volumio[1003]: info: ControllerMpd::getState May 27 18:35:25 volumio volumio[1003]: verbose: ControllerMpd::sendMpdCommand status May 27 18:35:25 volumio volumio[1003]: info: May 27 18:35:25 volumio volumio[1003]: ---------------------------- MPD announces state update: player May 27 18:35:25 volumio volumio[1003]: info: ControllerMpd::getState May 27 18:35:25 volumio volumio[1003]: verbose: ControllerMpd::sendMpdCommand status May 27 18:35:25 volumio volumio[1003]: info: May 27 18:35:25 volumio volumio[1003]: ---------------------------- MPD announces state update: player May 27 18:35:25 volumio volumio[1003]: info: ControllerMpd::getState May 27 18:35:25 volumio volumio[1003]: verbose: ControllerMpd::sendMpdCommand status May 27 18:35:25 volumio volumio[1003]: info: sendMpdCommand status took 4 milliseconds May 27 18:35:25 volumio volumio[1003]: info: sendMpdCommand playlistinfo took 4 milliseconds May 27 18:35:25 volumio volumio[1003]: info: sendMpdCommand playlistinfo took 4 milliseconds May 27 18:35:25 volumio volumio[1003]: info: sendMpdCommand playlistinfo took 4 milliseconds May 27 18:35:25 volumio volumio[1003]: info: sendMpdCommand playlistinfo took 5 milliseconds May 27 18:35:25 volumio volumio[1003]: info: sendMpdCommand status took 3 milliseconds May 27 18:35:25 volumio volumio[1003]: info: sendMpdCommand status took 3 milliseconds May 27 18:35:25 volumio volumio[1003]: info: sendMpdCommand status took 2 milliseconds May 27 18:35:25 volumio volumio[1003]: verbose: ControllerMpd::parseState May 27 18:35:25 volumio volumio[1003]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 18:35:25 volumio volumio[1003]: verbose: ControllerMpd::parseTrackInfo May 27 18:35:25 volumio volumio[1003]: verbose: ControllerMpd::parseTrackInfo May 27 18:35:25 volumio volumio[1003]: verbose: ControllerMpd::parseTrackInfo May 27 18:35:25 volumio volumio[1003]: verbose: ControllerMpd::parseTrackInfo May 27 18:35:25 volumio volumio[1003]: verbose: ControllerMpd::parseState May 27 18:35:25 volumio volumio[1003]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 18:35:25 volumio volumio[1003]: verbose: ControllerMpd::parseState May 27 18:35:25 volumio volumio[1003]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 18:35:25 volumio volumio[1003]: verbose: ControllerMpd::parseState May 27 18:35:25 volumio volumio[1003]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 18:35:25 volumio volumio[1003]: info: ControllerMpd::pushState May 27 18:35:25 volumio volumio[1003]: info: CoreCommandRouter::servicePushState May 27 18:35:25 volumio volumio[1003]: info: CorePlayQueue::getTrack 0 May 27 18:35:25 volumio volumio[1003]: verbose: STATE SERVICE {"status":"play","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":"flacm","artist":null,"album":null,"uri":"http://stream.radioparadise.com/flacm","trackType":"com/flacm"} May 27 18:35:25 volumio volumio[1003]: verbose: CURRENT POSITION 0 May 27 18:35:25 volumio volumio[1003]: info: CoreStateMachine::syncState stateService play May 27 18:35:25 volumio volumio[1003]: info: CoreStateMachine::syncState currentStatus stop May 27 18:35:25 volumio volumio[1003]: info: ControllerMpd::pushState May 27 18:35:25 volumio volumio[1003]: info: CoreCommandRouter::servicePushState May 27 18:35:25 volumio volumio[1003]: info: CorePlayQueue::getTrack 0 May 27 18:35:25 volumio volumio[1003]: verbose: STATE SERVICE {"status":"play","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":"flacm","artist":null,"album":null,"uri":"http://stream.radioparadise.com/flacm","trackType":"com/flacm"} May 27 18:35:25 volumio volumio[1003]: verbose: CURRENT POSITION 0 May 27 18:35:25 volumio volumio[1003]: info: CoreStateMachine::syncState stateService play May 27 18:35:25 volumio volumio[1003]: info: CoreStateMachine::syncState currentStatus play May 27 18:35:25 volumio volumio[1003]: info: Received an update from plugin. extracting info from payload May 27 18:35:25 volumio volumio[1003]: info: CoreStateMachine::pushState May 27 18:35:25 volumio volumio[1003]: info: CorePlayQueue::getTrack 0 May 27 18:35:25 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 18:35:25 volumio volumio[1003]: info: CoreCommandRouter::volumioPushState May 27 18:35:25 volumio volumio[1003]: info: CoreStateMachine::pushState May 27 18:35:25 volumio volumio[1003]: info: CoreCommandRouter::volumioPushState May 27 18:35:25 volumio volumio[1003]: info: ControllerMpd::pushState May 27 18:35:25 volumio volumio[1003]: info: CoreCommandRouter::servicePushState May 27 18:35:25 volumio volumio[1003]: info: CorePlayQueue::getTrack 0 May 27 18:35:25 volumio volumio[1003]: verbose: STATE SERVICE {"status":"play","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":"flacm","artist":null,"album":null,"uri":"http://stream.radioparadise.com/flacm","trackType":"com/flacm"} May 27 18:35:25 volumio volumio[1003]: verbose: CURRENT POSITION 0 May 27 18:35:25 volumio volumio[1003]: info: CoreStateMachine::syncState stateService play May 27 18:35:25 volumio volumio[1003]: info: CoreStateMachine::syncState currentStatus play May 27 18:35:25 volumio volumio[1003]: info: Received an update from plugin. extracting info from payload May 27 18:35:25 volumio volumio[1003]: info: CoreStateMachine::pushState May 27 18:35:25 volumio volumio[1003]: info: CorePlayQueue::getTrack 0 May 27 18:35:25 volumio volumio[1003]: info: CoreCommandRouter::volumioPushState May 27 18:35:25 volumio volumio[1003]: info: CoreStateMachine::pushState May 27 18:35:25 volumio volumio[1003]: info: CoreCommandRouter::volumioPushState May 27 18:35:25 volumio volumio[1003]: info: ControllerMpd::pushState May 27 18:35:25 volumio volumio[1003]: info: CoreCommandRouter::servicePushState May 27 18:35:25 volumio volumio[1003]: info: CorePlayQueue::getTrack 0 May 27 18:35:25 volumio volumio[1003]: verbose: STATE SERVICE {"status":"play","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":"flacm","artist":null,"album":null,"uri":"http://stream.radioparadise.com/flacm","trackType":"com/flacm"} May 27 18:35:25 volumio volumio[1003]: verbose: CURRENT POSITION 0 May 27 18:35:25 volumio volumio[1003]: info: CoreStateMachine::syncState stateService play May 27 18:35:25 volumio volumio[1003]: info: CoreStateMachine::syncState currentStatus play May 27 18:35:25 volumio volumio[1003]: info: Received an update from plugin. extracting info from payload May 27 18:35:25 volumio volumio[1003]: info: CoreStateMachine::pushState May 27 18:35:25 volumio volumio[1003]: info: CorePlayQueue::getTrack 0 May 27 18:35:25 volumio volumio[1003]: info: CoreCommandRouter::volumioPushState May 27 18:35:25 volumio volumio[1003]: info: CoreStateMachine::pushState May 27 18:35:25 volumio volumio[1003]: info: CoreCommandRouter::volumioPushState May 27 18:35:25 volumio volumio[1003]: info: ------------------------------ 35ms May 27 18:35:25 volumio volumio[1003]: info: ------------------------------ 41ms May 27 18:35:25 volumio volumio[1003]: info: ------------------------------ 42ms May 27 18:35:25 volumio volumio[1003]: info: ------------------------------ 41ms May 27 18:35:25 volumio volumio[1003]: error: FusionDsp - WebSocket error: [object Object] May 27 18:35:25 volumio volumio[1003]: info: sendMpdCommand playlistinfo took 19 milliseconds May 27 18:35:25 volumio volumio[1003]: info: sendMpdCommand playlistinfo took 18 milliseconds May 27 18:35:25 volumio volumio[1003]: info: sendMpdCommand playlistinfo took 19 milliseconds May 27 18:35:25 volumio volumio[1003]: info: sendMpdCommand playlistinfo took 19 milliseconds May 27 18:35:25 volumio volumio[1003]: verbose: ControllerMpd::parseTrackInfo May 27 18:35:25 volumio volumio[1003]: verbose: ControllerMpd::parseTrackInfo May 27 18:35:25 volumio volumio[1003]: verbose: ControllerMpd::parseTrackInfo May 27 18:35:25 volumio volumio[1003]: verbose: ControllerMpd::parseTrackInfo May 27 18:35:25 volumio volumio[1003]: info: ControllerMpd::pushState May 27 18:35:25 volumio volumio[1003]: info: CoreCommandRouter::servicePushState May 27 18:35:25 volumio volumio[1003]: info: CorePlayQueue::getTrack 0 May 27 18:35:25 volumio volumio[1003]: verbose: STATE SERVICE {"status":"play","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":"flacm","artist":null,"album":null,"uri":"http://stream.radioparadise.com/flacm","trackType":"com/flacm"} May 27 18:35:25 volumio volumio[1003]: verbose: CURRENT POSITION 0 May 27 18:35:25 volumio volumio[1003]: info: CoreStateMachine::syncState stateService play May 27 18:35:25 volumio volumio[1003]: info: CoreStateMachine::syncState currentStatus play May 27 18:35:25 volumio volumio[1003]: info: Received an update from plugin. extracting info from payload May 27 18:35:25 volumio volumio[1003]: info: CoreStateMachine::pushState May 27 18:35:25 volumio volumio[1003]: info: CorePlayQueue::getTrack 0 May 27 18:35:25 volumio volumio[1003]: info: CoreCommandRouter::volumioPushState May 27 18:35:25 volumio volumio[1003]: info: CoreStateMachine::pushState May 27 18:35:25 volumio volumio[1003]: info: CoreCommandRouter::volumioPushState May 27 18:35:25 volumio volumio[1003]: info: ControllerMpd::pushState May 27 18:35:25 volumio volumio[1003]: info: CoreCommandRouter::servicePushState May 27 18:35:25 volumio volumio[1003]: info: CorePlayQueue::getTrack 0 May 27 18:35:25 volumio volumio[1003]: verbose: STATE SERVICE {"status":"play","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":"flacm","artist":null,"album":null,"uri":"http://stream.radioparadise.com/flacm","trackType":"com/flacm"} May 27 18:35:25 volumio volumio[1003]: verbose: CURRENT POSITION 0 May 27 18:35:25 volumio volumio[1003]: info: CoreStateMachine::syncState stateService play May 27 18:35:25 volumio volumio[1003]: info: CoreStateMachine::syncState currentStatus play May 27 18:35:25 volumio volumio[1003]: info: Received an update from plugin. extracting info from payload May 27 18:35:25 volumio volumio[1003]: info: CoreStateMachine::pushState May 27 18:35:25 volumio volumio[1003]: info: CorePlayQueue::getTrack 0 May 27 18:35:25 volumio volumio[1003]: info: CoreCommandRouter::volumioPushState May 27 18:35:25 volumio volumio[1003]: info: CoreStateMachine::pushState May 27 18:35:25 volumio volumio[1003]: info: CoreCommandRouter::volumioPushState May 27 18:35:25 volumio volumio[1003]: info: ControllerMpd::pushState May 27 18:35:25 volumio volumio[1003]: info: CoreCommandRouter::servicePushState May 27 18:35:25 volumio volumio[1003]: info: CorePlayQueue::getTrack 0 May 27 18:35:25 volumio volumio[1003]: verbose: STATE SERVICE {"status":"play","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":"flacm","artist":null,"album":null,"uri":"http://stream.radioparadise.com/flacm","trackType":"com/flacm"} May 27 18:35:25 volumio volumio[1003]: verbose: CURRENT POSITION 0 May 27 18:35:25 volumio volumio[1003]: info: CoreStateMachine::syncState stateService play May 27 18:35:25 volumio volumio[1003]: info: CoreStateMachine::syncState currentStatus play May 27 18:35:25 volumio volumio[1003]: info: Received an update from plugin. extracting info from payload May 27 18:35:25 volumio volumio[1003]: info: CoreStateMachine::pushState May 27 18:35:25 volumio volumio[1003]: info: CorePlayQueue::getTrack 0 May 27 18:35:25 volumio volumio[1003]: info: CoreCommandRouter::volumioPushState May 27 18:35:25 volumio volumio[1003]: info: CoreStateMachine::pushState May 27 18:35:25 volumio volumio[1003]: info: CoreCommandRouter::volumioPushState May 27 18:35:25 volumio volumio[1003]: info: ControllerMpd::pushState May 27 18:35:25 volumio volumio[1003]: info: CoreCommandRouter::servicePushState May 27 18:35:25 volumio volumio[1003]: info: CorePlayQueue::getTrack 0 May 27 18:35:25 volumio volumio[1003]: verbose: STATE SERVICE {"status":"play","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":"flacm","artist":null,"album":null,"uri":"http://stream.radioparadise.com/flacm","trackType":"com/flacm"} May 27 18:35:25 volumio volumio[1003]: verbose: CURRENT POSITION 0 May 27 18:35:25 volumio volumio[1003]: info: CoreStateMachine::syncState stateService play May 27 18:35:25 volumio volumio[1003]: info: CoreStateMachine::syncState currentStatus play May 27 18:35:25 volumio volumio[1003]: info: Received an update from plugin. extracting info from payload May 27 18:35:25 volumio volumio[1003]: info: CoreStateMachine::pushState May 27 18:35:25 volumio volumio[1003]: info: CorePlayQueue::getTrack 0 May 27 18:35:25 volumio volumio[1003]: info: CoreCommandRouter::volumioPushState May 27 18:35:25 volumio volumio[1003]: info: CoreStateMachine::pushState May 27 18:35:25 volumio volumio[1003]: info: CoreCommandRouter::volumioPushState May 27 18:35:25 volumio volumio[1003]: info: ------------------------------ 43ms May 27 18:35:25 volumio volumio[1003]: info: ------------------------------ 42ms May 27 18:35:25 volumio volumio[1003]: info: ------------------------------ 41ms May 27 18:35:25 volumio volumio[1003]: info: ------------------------------ 40ms May 27 18:35:25 volumio volumio[1003]: info: May 27 18:35:25 volumio volumio[1003]: ---------------------------- MPD announces system playlist update May 27 18:35:25 volumio volumio[1003]: info: Ignoring MPD Status Update May 27 18:35:25 volumio volumio[1003]: info: May 27 18:35:25 volumio volumio[1003]: ---------------------------- MPD announces state update: player May 27 18:35:25 volumio volumio[1003]: info: ControllerMpd::getState May 27 18:35:25 volumio volumio[1003]: verbose: ControllerMpd::sendMpdCommand status May 27 18:35:25 volumio volumio[1003]: info: May 27 18:35:25 volumio volumio[1003]: ---------------------------- MPD announces system playlist update May 27 18:35:25 volumio volumio[1003]: info: Ignoring MPD Status Update May 27 18:35:25 volumio volumio[1003]: info: May 27 18:35:25 volumio volumio[1003]: ---------------------------- MPD announces state update: player May 27 18:35:25 volumio volumio[1003]: info: ControllerMpd::getState May 27 18:35:25 volumio volumio[1003]: verbose: ControllerMpd::sendMpdCommand status May 27 18:35:25 volumio volumio[1003]: info: May 27 18:35:25 volumio volumio[1003]: ---------------------------- MPD announces system playlist update May 27 18:35:25 volumio volumio[1003]: info: Ignoring MPD Status Update May 27 18:35:25 volumio volumio[1003]: info: May 27 18:35:25 volumio volumio[1003]: ---------------------------- MPD announces state update: player May 27 18:35:25 volumio volumio[1003]: info: ControllerMpd::getState May 27 18:35:25 volumio volumio[1003]: verbose: ControllerMpd::sendMpdCommand status May 27 18:35:25 volumio volumio[1003]: info: ------------------------------ 3ms May 27 18:35:25 volumio volumio[1003]: info: sendMpdCommand status took 2 milliseconds May 27 18:35:25 volumio volumio[1003]: info: ------------------------------ 2ms May 27 18:35:25 volumio volumio[1003]: info: sendMpdCommand status took 2 milliseconds May 27 18:35:25 volumio volumio[1003]: info: ------------------------------ 2ms May 27 18:35:25 volumio volumio[1003]: info: sendMpdCommand status took 2 milliseconds May 27 18:35:25 volumio volumio[1003]: verbose: ControllerMpd::parseState May 27 18:35:25 volumio volumio[1003]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 18:35:25 volumio volumio[1003]: verbose: ControllerMpd::parseState May 27 18:35:25 volumio volumio[1003]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 18:35:25 volumio volumio[1003]: verbose: ControllerMpd::parseState May 27 18:35:25 volumio volumio[1003]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 18:35:25 volumio volumio[1003]: info: May 27 18:35:25 volumio volumio[1003]: ---------------------------- MPD announces system playlist update May 27 18:35:25 volumio volumio[1003]: info: Ignoring MPD Status Update May 27 18:35:25 volumio volumio[1003]: info: May 27 18:35:25 volumio volumio[1003]: ---------------------------- MPD announces state update: player May 27 18:35:25 volumio volumio[1003]: info: ControllerMpd::getState May 27 18:35:25 volumio volumio[1003]: verbose: ControllerMpd::sendMpdCommand status May 27 18:35:25 volumio volumio[1003]: info: sendMpdCommand playlistinfo took 2 milliseconds May 27 18:35:25 volumio volumio[1003]: info: sendMpdCommand playlistinfo took 2 milliseconds May 27 18:35:25 volumio volumio[1003]: info: sendMpdCommand playlistinfo took 2 milliseconds May 27 18:35:25 volumio volumio[1003]: info: ------------------------------ 1ms May 27 18:35:25 volumio volumio[1003]: info: sendMpdCommand status took 1 milliseconds May 27 18:35:25 volumio volumio[1003]: verbose: ControllerMpd::parseTrackInfo May 27 18:35:25 volumio volumio[1003]: verbose: ControllerMpd::parseTrackInfo May 27 18:35:25 volumio volumio[1003]: verbose: ControllerMpd::parseTrackInfo May 27 18:35:25 volumio volumio[1003]: verbose: ControllerMpd::parseState May 27 18:35:25 volumio volumio[1003]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 18:35:25 volumio volumio[1003]: info: ControllerMpd::pushState May 27 18:35:25 volumio volumio[1003]: info: CoreCommandRouter::servicePushState May 27 18:35:25 volumio volumio[1003]: info: CorePlayQueue::getTrack 0 May 27 18:35:25 volumio volumio[1003]: verbose: STATE SERVICE {"status":"play","position":0,"seek":960,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"flacm","artist":"Radio Paradise (flac)","album":null,"uri":"http://stream.radioparadise.com/flacm","trackType":"com/flacm"} May 27 18:35:25 volumio volumio[1003]: verbose: CURRENT POSITION 0 May 27 18:35:25 volumio volumio[1003]: info: CoreStateMachine::syncState stateService play May 27 18:35:25 volumio volumio[1003]: info: CoreStateMachine::syncState currentStatus play May 27 18:35:25 volumio volumio[1003]: info: Received an update from plugin. extracting info from payload May 27 18:35:25 volumio volumio[1003]: info: CoreStateMachine::pushState May 27 18:35:25 volumio volumio[1003]: info: CorePlayQueue::getTrack 0 May 27 18:35:25 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 18:35:25 volumio volumio[1003]: info: CoreCommandRouter::volumioPushState May 27 18:35:25 volumio volumio[1003]: info: CoreStateMachine::pushState May 27 18:35:25 volumio volumio[1003]: info: CoreCommandRouter::volumioPushState May 27 18:35:25 volumio volumio[1003]: info: ControllerMpd::pushState May 27 18:35:25 volumio volumio[1003]: info: CoreCommandRouter::servicePushState May 27 18:35:25 volumio volumio[1003]: info: CorePlayQueue::getTrack 0 May 27 18:35:25 volumio volumio[1003]: verbose: STATE SERVICE {"status":"play","position":0,"seek":960,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"flacm","artist":"Radio Paradise (flac)","album":null,"uri":"http://stream.radioparadise.com/flacm","trackType":"com/flacm"} May 27 18:35:25 volumio volumio[1003]: verbose: CURRENT POSITION 0 May 27 18:35:25 volumio volumio[1003]: info: CoreStateMachine::syncState stateService play May 27 18:35:25 volumio volumio[1003]: info: CoreStateMachine::syncState currentStatus play May 27 18:35:25 volumio volumio[1003]: info: Received an update from plugin. extracting info from payload May 27 18:35:25 volumio volumio[1003]: info: CoreStateMachine::pushState May 27 18:35:25 volumio volumio[1003]: info: CorePlayQueue::getTrack 0 May 27 18:35:25 volumio volumio[1003]: info: CoreCommandRouter::volumioPushState May 27 18:35:25 volumio volumio[1003]: info: CoreStateMachine::pushState May 27 18:35:25 volumio volumio[1003]: info: CoreCommandRouter::volumioPushState May 27 18:35:25 volumio volumio[1003]: info: ControllerMpd::pushState May 27 18:35:25 volumio volumio[1003]: info: CoreCommandRouter::servicePushState May 27 18:35:25 volumio volumio[1003]: info: CorePlayQueue::getTrack 0 May 27 18:35:25 volumio volumio[1003]: verbose: STATE SERVICE {"status":"play","position":0,"seek":960,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"flacm","artist":"Radio Paradise (flac)","album":null,"uri":"http://stream.radioparadise.com/flacm","trackType":"com/flacm"} May 27 18:35:25 volumio volumio[1003]: verbose: CURRENT POSITION 0 May 27 18:35:25 volumio volumio[1003]: info: CoreStateMachine::syncState stateService play May 27 18:35:25 volumio volumio[1003]: info: CoreStateMachine::syncState currentStatus play May 27 18:35:25 volumio volumio[1003]: info: Received an update from plugin. extracting info from payload May 27 18:35:25 volumio volumio[1003]: info: CoreStateMachine::pushState May 27 18:35:25 volumio volumio[1003]: info: CorePlayQueue::getTrack 0 May 27 18:35:25 volumio volumio[1003]: info: CoreCommandRouter::volumioPushState May 27 18:35:25 volumio volumio[1003]: info: CoreStateMachine::pushState May 27 18:35:25 volumio volumio[1003]: info: CoreCommandRouter::volumioPushState May 27 18:35:25 volumio volumio[1003]: info: ------------------------------ 20ms May 27 18:35:25 volumio volumio[1003]: info: ------------------------------ 20ms May 27 18:35:25 volumio volumio[1003]: info: ------------------------------ 19ms May 27 18:35:25 volumio volumio[1003]: info: sendMpdCommand playlistinfo took 15 milliseconds May 27 18:35:25 volumio volumio[1003]: verbose: ControllerMpd::parseTrackInfo May 27 18:35:25 volumio volumio[1003]: info: ControllerMpd::pushState May 27 18:35:25 volumio volumio[1003]: info: CoreCommandRouter::servicePushState May 27 18:35:25 volumio volumio[1003]: info: CorePlayQueue::getTrack 0 May 27 18:35:25 volumio volumio[1003]: verbose: STATE SERVICE {"status":"play","position":0,"seek":960,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"flacm","artist":"Radio Paradise (flac)","album":null,"uri":"http://stream.radioparadise.com/flacm","trackType":"com/flacm"} May 27 18:35:25 volumio volumio[1003]: verbose: CURRENT POSITION 0 May 27 18:35:25 volumio volumio[1003]: info: CoreStateMachine::syncState stateService play May 27 18:35:25 volumio volumio[1003]: info: CoreStateMachine::syncState currentStatus play May 27 18:35:25 volumio volumio[1003]: info: Received an update from plugin. extracting info from payload May 27 18:35:25 volumio volumio[1003]: info: CoreStateMachine::pushState May 27 18:35:25 volumio volumio[1003]: info: CorePlayQueue::getTrack 0 May 27 18:35:25 volumio volumio[1003]: info: CoreCommandRouter::volumioPushState May 27 18:35:25 volumio volumio[1003]: info: CoreStateMachine::pushState May 27 18:35:25 volumio volumio[1003]: info: CoreCommandRouter::volumioPushState May 27 18:35:25 volumio volumio[1003]: info: ------------------------------ 22ms May 27 18:35:40 volumio go-librespot[1403]: time="2026-05-27T18:35:40+02:00" level=trace msg="sent dealer ping" May 27 18:35:40 volumio go-librespot[1403]: time="2026-05-27T18:35:40+02:00" level=trace msg="received dealer pong" May 27 18:35:49 volumio volumio[1003]: info: CoreCommandRouter::volumioGetState May 27 18:35:52 volumio volumio[1003]: info: ___________ PLUGINS: Run onVolumioReboot Tasks ___________ May 27 18:35:52 volumio volumio[1003]: info: PLUGIN onReboot : networkfs May 27 18:35:52 volumio volumio[1003]: info: PLUGIN onReboot : audiophonicsonoff May 27 18:35:52 volumio volumio[1003]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 27 18:35:52 volumio volumio[1003]: TypeError: Cannot read property 'writeSync' of undefined May 27 18:35:52 volumio volumio[1003]: at ControllerAudiophonicsOnOff.onVolumioReboot (/data/plugins/system_hardware/audiophonicsonoff/index.js:40:25) May 27 18:35:52 volumio volumio[1003]: at PluginManager.onVolumioRebootPlugin (/volumio/app/pluginmanager.js:684:30) May 27 18:35:52 volumio volumio[1003]: at HashMap. (/volumio/app/pluginmanager.js:668:31) May 27 18:35:52 volumio volumio[1003]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) May 27 18:35:52 volumio volumio[1003]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) May 27 18:35:52 volumio volumio[1003]: at PluginManager.onVolumioReboot (/volumio/app/pluginmanager.js:666:20) May 27 18:35:52 volumio volumio[1003]: at CoreCommandRouter.reboot (/volumio/app/index.js:1350:22) May 27 18:35:52 volumio volumio[1003]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:870:33) May 27 18:35:52 volumio volumio[1003]: at Socket.emit (events.js:315:20) May 27 18:35:52 volumio volumio[1003]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 May 27 18:35:52 volumio volumio[1003]: at processTicksAndRejections (internal/process/task_queues.js:75:11) May 27 18:35:52 volumio volumio[1003]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 27 18:35:52 volumio sudo[24724]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-05-27 18:34 May 27 18:35:52 volumio sudo[24724]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="e9612ec5034fb2e958508aaefbca2962fd6f6654" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="464fc672d77d3df6ee72b331d36cdf1fa936e1ec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 27 Feb 2026 10:59:40 AM CET" VOLUMIO_VERSION="3.912" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="37c6ab864cb114e1344d540995c69f86"