-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Sun 2025-12-21 09:42:35 CET. -- Dec 21 09:41:01 bdx-volumio volumio[1373]: info: Looking in indexes: https://pypi.org/simple, https://www.piwheels.org/simple Dec 21 09:41:01 bdx-volumio volumio[1373]: info: Collecting git+https://github.com/HEnquist/pycamilladsp-plot.git@v1.0.2 Dec 21 09:41:01 bdx-volumio volumio[1373]: info: Cloning https://github.com/HEnquist/pycamilladsp-plot.git (to revision v1.0.2) to /tmp/pip-req-build-w1i164es Dec 21 09:41:03 bdx-volumio volumio[1373]: info: Requirement already satisfied (use --upgrade to upgrade): camilladsp-plot==1.0.2 from git+https://github.com/HEnquist/pycamilladsp-plot.git@v1.0.2 in /usr/local/lib/python3.7/dist-packages Dec 21 09:41:03 bdx-volumio volumio[1373]: info: Requirement already satisfied: PyYAML in /usr/local/lib/python3.7/dist-packages (from camilladsp-plot==1.0.2) (6.0.1) Dec 21 09:41:03 bdx-volumio volumio[1373]: info: Requirement already satisfied: jsonschema in /usr/local/lib/python3.7/dist-packages (from camilladsp-plot==1.0.2) (4.17.3) Dec 21 09:41:03 bdx-volumio volumio[1373]: info: Requirement already satisfied: importlib-metadata; python_version < "3.8" in /usr/local/lib/python3.7/dist-packages (from jsonschema->camilladsp-plot==1.0.2) (6.7.0) Dec 21 09:41:03 bdx-volumio volumio[1373]: info: Requirement already satisfied: typing-extensions; python_version < "3.8" in /usr/local/lib/python3.7/dist-packages (from jsonschema->camilladsp-plot==1.0.2) (4.7.1) Dec 21 09:41:03 bdx-volumio volumio[1373]: info: Requirement already satisfied: attrs>=17.4.0 in /usr/lib/python3/dist-packages (from jsonschema->camilladsp-plot==1.0.2) (18.2.0) Dec 21 09:41:03 bdx-volumio volumio[1373]: info: Requirement already satisfied: pyrsistent!=0.17.0,!=0.17.1,!=0.17.2,>=0.14.0 in /usr/local/lib/python3.7/dist-packages (from jsonschema->camilladsp-plot==1.0.2) (0.19.3) Dec 21 09:41:03 bdx-volumio volumio[1373]: info: Requirement already satisfied: importlib-resources>=1.4.0; python_version < "3.9" in /usr/local/lib/python3.7/dist-packages (from jsonschema->camilladsp-plot==1.0.2) (5.12.0) Dec 21 09:41:04 bdx-volumio volumio[1373]: info: Requirement already satisfied: pkgutil-resolve-name>=1.3.10; python_version < "3.9" in /usr/local/lib/python3.7/dist-packages (from jsonschema->camilladsp-plot==1.0.2) (1.3.10) Dec 21 09:41:04 bdx-volumio volumio[1373]: info: Requirement already satisfied: zipp>=0.5 in /usr/local/lib/python3.7/dist-packages (from importlib-metadata; python_version < "3.8"->jsonschema->camilladsp-plot==1.0.2) (3.15.0) Dec 21 09:41:04 bdx-volumio volumio[1373]: info: Building wheels for collected packages: camilladsp-plot Dec 21 09:41:04 bdx-volumio volumio[1373]: info: Running setup.py bdist_wheel for camilladsp-plot: started Dec 21 09:41:05 bdx-volumio volumio[1373]: info: Running setup.py bdist_wheel for camilladsp-plot: finished with status 'done' Dec 21 09:41:05 bdx-volumio volumio[1373]: info: Stored in directory: /tmp/pip-ephem-wheel-cache-rz535t7f/wheels/11/e1/43/8275af2f537d569c7097d202b4955f9aec629859be39dab2e9 Dec 21 09:41:05 bdx-volumio volumio[1373]: info: Successfully built camilladsp-plot Dec 21 09:41:06 bdx-volumio volumio[1373]: info: copying hw detection script Dec 21 09:41:06 bdx-volumio volumio[1373]: info: Detected cpu architecture as armv7l Dec 21 09:41:07 bdx-volumio sudo[7964]: 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 Dec 21 09:41:07 bdx-volumio sudo[7964]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 21 09:41:07 bdx-volumio sudo[7964]: pam_unix(sudo:session): session closed for user root Dec 21 09:41:07 bdx-volumio sudo[7966]: root : TTY=unknown ; PWD=/tmp ; USER=root ; COMMAND=/bin/chmod +x /data/plugins/audio_interface/fusiondsp/hw_params Dec 21 09:41:07 bdx-volumio sudo[7966]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 21 09:41:07 bdx-volumio sudo[7966]: pam_unix(sudo:session): session closed for user root Dec 21 09:41:07 bdx-volumio sudo[7968]: root : TTY=unknown ; PWD=/tmp ; USER=root ; COMMAND=/usr/bin/apt-get -y install drc Dec 21 09:41:07 bdx-volumio sudo[7968]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 21 09:41:08 bdx-volumio volumio[1373]: info: Reading package lists... Dec 21 09:41:08 bdx-volumio volumio[1373]: info: Building dependency tree... Dec 21 09:41:08 bdx-volumio volumio[1373]: info: Reading state information... Dec 21 09:41:10 bdx-volumio volumio[1373]: info: drc is already the newest version (3.2.2~dfsg0-2). Dec 21 09:41:10 bdx-volumio sudo[7968]: pam_unix(sudo:session): session closed for user root Dec 21 09:41:10 bdx-volumio sudo[7521]: pam_unix(sudo:session): session closed for user root Dec 21 09:41:10 bdx-volumio volumio[1373]: info: 0 upgraded, 0 newly installed, 0 to remove and 2 not upgraded. Dec 21 09:41:10 bdx-volumio volumio[1373]: info: Install script completed Dec 21 09:41:10 bdx-volumio volumio[1373]: info: Done installing plugin. Dec 21 09:41:10 bdx-volumio volumio[1373]: info: Enabling plugin fusiondsp Dec 21 09:41:10 bdx-volumio volumio[1373]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 21 09:41:10 bdx-volumio volumio[1373]: 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":"Radio Paradise","name":"radio_paradise","category":"music_service","version":"1.0.14","icon":"fa-headphones","isManuallyInstalled":false,"enabled":true,"active":false},{"prettyName":"YouTube2","name":"youtube2","category":"music_service","version":"1.4.2","icon":"fa-youtube-play","isManuallyInstalled":false,"enabled":true,"active":false},{"prettyName":"YouTube Cast Receiver","name":"ytcr","category":"music_service","version":"1.2.0","icon":"fa-youtube","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":"Backup & Restore Data","name":"backup_restore","category":"system_controller","version":"0.7.4","icon":"fa-floppy-o","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"ScheduledRestart","name":"scheduledrestart","category":"system_controller","version":"0.7.2","icon":"fa-clock-o","isManuallyInstalled":false,"enabled":true,"active":true}] Dec 21 09:41:10 bdx-volumio volumio[1373]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Dec 21 09:41:10 bdx-volumio volumio[1373]: info: Folder /tmp/plugins removed Dec 21 09:41:10 bdx-volumio volumio[1373]: info: Folder /tmp/downloaded_plugin.zip removed Dec 21 09:41:10 bdx-volumio volumio[1373]: Plugin install end detected on script Dec 21 09:41:10 bdx-volumio volumio[1373]: info: Folder /data/temp removed Dec 21 09:41:10 bdx-volumio volumio[1373]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 21 09:41:10 bdx-volumio volumio[1373]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 21 09:41:10 bdx-volumio volumio[1373]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 21 09:41:10 bdx-volumio volumio[1373]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 21 09:41:10 bdx-volumio volumio[1373]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 21 09:41:10 bdx-volumio volumio[1373]: info: FusionDsp - Classic-FusionDsp Dec 21 09:41:10 bdx-volumio volumio[1373]: info: FusionDsp - Classic-FusionDsp Dec 21 09:41:11 bdx-volumio volumio[1373]: info: FusionDsp - Classic-FusionDsp Dec 21 09:41:11 bdx-volumio volumio[1373]: info: FusionDsp - Classic-FusionDsp Dec 21 09:41:11 bdx-volumio volumio[1373]: info: FusionDsp - Classic-FusionDsp Dec 21 09:41:11 bdx-volumio volumio[1373]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json,ROCK.json Dec 21 09:41:11 bdx-volumio volumio[1373]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json,ROCK.json Dec 21 09:41:11 bdx-volumio volumio[1373]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json,ROCK.json Dec 21 09:41:12 bdx-volumio volumio[1373]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json,ROCK.json Dec 21 09:41:12 bdx-volumio volumio[1373]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json,ROCK.json Dec 21 09:41:16 bdx-volumio volumio[1373]: info: CoreCommandRouter::volumioStop Dec 21 09:41:16 bdx-volumio volumio[1373]: info: CoreStateMachine::stop Dec 21 09:41:16 bdx-volumio volumio[1373]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 21 09:41:16 bdx-volumio volumio[1373]: info: CoreStateMachine::stPlaybackTimer Dec 21 09:41:16 bdx-volumio volumio[1373]: info: CoreStateMachine::updateTrackBlock Dec 21 09:41:16 bdx-volumio volumio[1373]: info: CorePlayQueue::getTrackBlock Dec 21 09:41:16 bdx-volumio volumio[1373]: info: CoreStateMachine::pushState Dec 21 09:41:16 bdx-volumio volumio[1373]: info: CorePlayQueue::getTrack 0 Dec 21 09:41:16 bdx-volumio volumio[1373]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 21 09:41:16 bdx-volumio volumio[1373]: info: CoreCommandRouter::volumioPushState Dec 21 09:41:16 bdx-volumio volumio[1373]: info: CoreStateMachine::serviceStop Dec 21 09:41:16 bdx-volumio volumio[1373]: info: CorePlayQueue::getTrack 0 Dec 21 09:41:16 bdx-volumio volumio[1373]: info: CoreCommandRouter::serviceStop Dec 21 09:41:16 bdx-volumio volumio[1373]: info: [1766306476293] ControllerWebradio::stop Dec 21 09:41:16 bdx-volumio volumio[1373]: verbose: ControllerMpd::sendMpdCommand stop Dec 21 09:41:16 bdx-volumio volumio[1373]: info: sendMpdCommand stop took 28 milliseconds Dec 21 09:41:16 bdx-volumio volumio[1373]: info: Dec 21 09:41:16 bdx-volumio volumio[1373]: ---------------------------- MPD announces state update: player Dec 21 09:41:16 bdx-volumio volumio[1373]: info: ControllerMpd::getState Dec 21 09:41:16 bdx-volumio volumio[1373]: verbose: ControllerMpd::sendMpdCommand status Dec 21 09:41:16 bdx-volumio volumio[1373]: info: Dec 21 09:41:16 bdx-volumio volumio[1373]: ---------------------------- MPD announces state update: player Dec 21 09:41:16 bdx-volumio volumio[1373]: info: ControllerMpd::getState Dec 21 09:41:16 bdx-volumio volumio[1373]: verbose: ControllerMpd::sendMpdCommand status Dec 21 09:41:16 bdx-volumio volumio[1373]: info: Dec 21 09:41:16 bdx-volumio volumio[1373]: ---------------------------- MPD announces state update: player Dec 21 09:41:16 bdx-volumio volumio[1373]: info: ControllerMpd::getState Dec 21 09:41:16 bdx-volumio volumio[1373]: verbose: ControllerMpd::sendMpdCommand status Dec 21 09:41:16 bdx-volumio volumio[1373]: info: Dec 21 09:41:16 bdx-volumio volumio[1373]: ---------------------------- MPD announces state update: player Dec 21 09:41:16 bdx-volumio volumio[1373]: info: ControllerMpd::getState Dec 21 09:41:16 bdx-volumio volumio[1373]: verbose: ControllerMpd::sendMpdCommand status Dec 21 09:41:16 bdx-volumio volumio[1373]: info: sendMpdCommand status took 10 milliseconds Dec 21 09:41:16 bdx-volumio volumio[1373]: info: sendMpdCommand status took 8 milliseconds Dec 21 09:41:16 bdx-volumio volumio[1373]: info: sendMpdCommand status took 7 milliseconds Dec 21 09:41:16 bdx-volumio volumio[1373]: info: sendMpdCommand status took 5 milliseconds Dec 21 09:41:16 bdx-volumio volumio[1373]: verbose: ControllerMpd::parseState Dec 21 09:41:16 bdx-volumio volumio[1373]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 21 09:41:16 bdx-volumio volumio[1373]: verbose: ControllerMpd::parseState Dec 21 09:41:16 bdx-volumio volumio[1373]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 21 09:41:16 bdx-volumio volumio[1373]: verbose: ControllerMpd::parseState Dec 21 09:41:16 bdx-volumio volumio[1373]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 21 09:41:16 bdx-volumio volumio[1373]: verbose: ControllerMpd::parseState Dec 21 09:41:16 bdx-volumio volumio[1373]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 21 09:41:16 bdx-volumio volumio[1373]: info: sendMpdCommand playlistinfo took 4 milliseconds Dec 21 09:41:16 bdx-volumio volumio[1373]: info: sendMpdCommand playlistinfo took 4 milliseconds Dec 21 09:41:16 bdx-volumio volumio[1373]: info: sendMpdCommand playlistinfo took 4 milliseconds Dec 21 09:41:16 bdx-volumio volumio[1373]: info: sendMpdCommand playlistinfo took 3 milliseconds Dec 21 09:41:16 bdx-volumio volumio[1373]: verbose: ControllerMpd::parseTrackInfo Dec 21 09:41:16 bdx-volumio volumio[1373]: verbose: ControllerMpd::parseTrackInfo Dec 21 09:41:16 bdx-volumio volumio[1373]: verbose: ControllerMpd::parseTrackInfo Dec 21 09:41:16 bdx-volumio volumio[1373]: verbose: ControllerMpd::parseTrackInfo Dec 21 09:41:16 bdx-volumio volumio[1373]: info: ControllerMpd::pushState Dec 21 09:41:16 bdx-volumio volumio[1373]: info: CoreCommandRouter::servicePushState Dec 21 09:41:16 bdx-volumio volumio[1373]: info: CoreStateMachine::pushState Dec 21 09:41:16 bdx-volumio volumio[1373]: info: CorePlayQueue::getTrack 0 Dec 21 09:41:16 bdx-volumio volumio[1373]: info: CoreCommandRouter::volumioPushState Dec 21 09:41:16 bdx-volumio volumio[1373]: info: CorePlayQueue::getTrack 0 Dec 21 09:41:16 bdx-volumio volumio[1373]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Dec 21 09:41:16 bdx-volumio volumio[1373]: info: ControllerMpd::pushState Dec 21 09:41:16 bdx-volumio volumio[1373]: info: CoreCommandRouter::servicePushState Dec 21 09:41:16 bdx-volumio volumio[1373]: info: CoreStateMachine::pushState Dec 21 09:41:16 bdx-volumio volumio[1373]: info: CorePlayQueue::getTrack 0 Dec 21 09:41:16 bdx-volumio volumio[1373]: info: CoreCommandRouter::volumioPushState Dec 21 09:41:16 bdx-volumio volumio[1373]: info: CorePlayQueue::getTrack 0 Dec 21 09:41:16 bdx-volumio volumio[1373]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Dec 21 09:41:16 bdx-volumio volumio[1373]: info: ControllerMpd::pushState Dec 21 09:41:16 bdx-volumio volumio[1373]: info: CoreCommandRouter::servicePushState Dec 21 09:41:16 bdx-volumio volumio[1373]: info: CoreStateMachine::pushState Dec 21 09:41:16 bdx-volumio volumio[1373]: info: CorePlayQueue::getTrack 0 Dec 21 09:41:16 bdx-volumio volumio[1373]: info: CoreCommandRouter::volumioPushState Dec 21 09:41:16 bdx-volumio volumio[1373]: info: CorePlayQueue::getTrack 0 Dec 21 09:41:16 bdx-volumio volumio[1373]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Dec 21 09:41:16 bdx-volumio volumio[1373]: info: ControllerMpd::pushState Dec 21 09:41:16 bdx-volumio volumio[1373]: info: CoreCommandRouter::servicePushState Dec 21 09:41:16 bdx-volumio volumio[1373]: info: CoreStateMachine::pushState Dec 21 09:41:16 bdx-volumio volumio[1373]: info: CorePlayQueue::getTrack 0 Dec 21 09:41:16 bdx-volumio volumio[1373]: info: CoreCommandRouter::volumioPushState Dec 21 09:41:16 bdx-volumio volumio[1373]: info: CorePlayQueue::getTrack 0 Dec 21 09:41:16 bdx-volumio volumio[1373]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Dec 21 09:41:16 bdx-volumio volumio[1373]: info: ------------------------------ 49ms Dec 21 09:41:16 bdx-volumio volumio[1373]: info: ------------------------------ 47ms Dec 21 09:41:16 bdx-volumio volumio[1373]: info: ------------------------------ 45ms Dec 21 09:41:16 bdx-volumio volumio[1373]: info: ------------------------------ 44ms Dec 21 09:41:17 bdx-volumio volumio[1373]: info: CoreCommandRouter::volumioPlay Dec 21 09:41:17 bdx-volumio volumio[1373]: info: CoreStateMachine::play index undefined Dec 21 09:41:17 bdx-volumio volumio[1373]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 21 09:41:17 bdx-volumio volumio[1373]: info: CorePlayQueue::getTrack 0 Dec 21 09:41:17 bdx-volumio volumio[1373]: info: CoreStateMachine::startPlaybackTimer Dec 21 09:41:17 bdx-volumio volumio[1373]: info: CorePlayQueue::getTrack 0 Dec 21 09:41:17 bdx-volumio volumio[1373]: info: [1766306477055] ControllerWebradio::clearAddPlayTrack Dec 21 09:41:17 bdx-volumio volumio[1373]: verbose: ControllerMpd::sendMpdCommand stop Dec 21 09:41:17 bdx-volumio volumio[1373]: info: sendMpdCommand stop took 1 milliseconds Dec 21 09:41:17 bdx-volumio volumio[1373]: verbose: ControllerMpd::sendMpdCommand clear Dec 21 09:41:17 bdx-volumio volumio[1373]: info: Dec 21 09:41:17 bdx-volumio volumio[1373]: ---------------------------- MPD announces system playlist update Dec 21 09:41:17 bdx-volumio volumio[1373]: info: Ignoring MPD Status Update Dec 21 09:41:17 bdx-volumio volumio[1373]: info: sendMpdCommand clear took 2 milliseconds Dec 21 09:41:17 bdx-volumio volumio[1373]: verbose: ControllerMpd::sendMpdCommand load "https://wdr-wdr2-suedwestfalen.icecastssl.wdr.de/wdr/wdr2/suedwestfalen/mp3/128/stream.mp3?aggregator=tunein" Dec 21 09:41:17 bdx-volumio volumio[1373]: info: Dec 21 09:41:17 bdx-volumio volumio[1373]: ---------------------------- MPD announces system playlist update Dec 21 09:41:17 bdx-volumio volumio[1373]: info: Ignoring MPD Status Update Dec 21 09:41:17 bdx-volumio volumio[1373]: info: Dec 21 09:41:17 bdx-volumio volumio[1373]: ---------------------------- MPD announces system playlist update Dec 21 09:41:17 bdx-volumio volumio[1373]: info: Ignoring MPD Status Update Dec 21 09:41:17 bdx-volumio volumio[1373]: info: Dec 21 09:41:17 bdx-volumio volumio[1373]: ---------------------------- MPD announces system playlist update Dec 21 09:41:17 bdx-volumio volumio[1373]: info: Ignoring MPD Status Update Dec 21 09:41:17 bdx-volumio volumio[1373]: error: updateQueue error: null Dec 21 09:41:17 bdx-volumio volumio[1373]: info: ------------------------------ 11ms Dec 21 09:41:18 bdx-volumio volumio[1373]: verbose: ControllerMpd::sendMpdCommand add "https://wdr-wdr2-suedwestfalen.icecastssl.wdr.de/wdr/wdr2/suedwestfalen/mp3/128/stream.mp3?aggregator=tunein" Dec 21 09:41:18 bdx-volumio volumio[1373]: error: updateQueue error: null Dec 21 09:41:18 bdx-volumio volumio[1373]: error: updateQueue error: null Dec 21 09:41:18 bdx-volumio volumio[1373]: error: updateQueue error: null Dec 21 09:41:18 bdx-volumio volumio[1373]: info: Dec 21 09:41:18 bdx-volumio volumio[1373]: ---------------------------- MPD announces system playlist update Dec 21 09:41:18 bdx-volumio volumio[1373]: info: Ignoring MPD Status Update Dec 21 09:41:18 bdx-volumio volumio[1373]: info: ------------------------------ 1831ms Dec 21 09:41:18 bdx-volumio volumio[1373]: info: ------------------------------ 1830ms Dec 21 09:41:18 bdx-volumio volumio[1373]: info: ------------------------------ 1829ms Dec 21 09:41:18 bdx-volumio volumio[1373]: info: sendMpdCommand add "https://wdr-wdr2-suedwestfalen.icecastssl.wdr.de/wdr/wdr2/suedwestfalen/mp3/128/stream.mp3?aggregator=tunein" took 4 milliseconds Dec 21 09:41:18 bdx-volumio volumio[1373]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 21 09:41:18 bdx-volumio volumio[1373]: verbose: ControllerMpd::sendMpdCommand play Dec 21 09:41:18 bdx-volumio volumio[1373]: info: Dec 21 09:41:18 bdx-volumio volumio[1373]: ---------------------------- MPD announces system playlist update Dec 21 09:41:18 bdx-volumio volumio[1373]: info: Ignoring MPD Status Update Dec 21 09:41:18 bdx-volumio volumio[1373]: info: Dec 21 09:41:18 bdx-volumio volumio[1373]: ---------------------------- MPD announces system playlist update Dec 21 09:41:18 bdx-volumio volumio[1373]: info: Ignoring MPD Status Update Dec 21 09:41:18 bdx-volumio volumio[1373]: info: Dec 21 09:41:18 bdx-volumio volumio[1373]: ---------------------------- MPD announces system playlist update Dec 21 09:41:18 bdx-volumio volumio[1373]: info: Ignoring MPD Status Update Dec 21 09:41:18 bdx-volumio volumio[1373]: info: ------------------------------ 9ms Dec 21 09:41:18 bdx-volumio volumio[1373]: info: sendMpdCommand play took 7 milliseconds Dec 21 09:41:18 bdx-volumio volumio[1373]: info: ------------------------------ 6ms Dec 21 09:41:18 bdx-volumio volumio[1373]: info: ------------------------------ 5ms Dec 21 09:41:18 bdx-volumio volumio[1373]: info: ------------------------------ 3ms Dec 21 09:41:19 bdx-volumio volumio[1373]: info: Dec 21 09:41:19 bdx-volumio volumio[1373]: ---------------------------- MPD announces state update: player Dec 21 09:41:19 bdx-volumio volumio[1373]: info: ControllerMpd::getState Dec 21 09:41:19 bdx-volumio volumio[1373]: verbose: ControllerMpd::sendMpdCommand status Dec 21 09:41:19 bdx-volumio volumio[1373]: info: Dec 21 09:41:19 bdx-volumio volumio[1373]: ---------------------------- MPD announces state update: player Dec 21 09:41:19 bdx-volumio volumio[1373]: info: ControllerMpd::getState Dec 21 09:41:19 bdx-volumio volumio[1373]: verbose: ControllerMpd::sendMpdCommand status Dec 21 09:41:19 bdx-volumio volumio[1373]: info: Dec 21 09:41:19 bdx-volumio volumio[1373]: ---------------------------- MPD announces state update: player Dec 21 09:41:19 bdx-volumio volumio[1373]: info: ControllerMpd::getState Dec 21 09:41:19 bdx-volumio volumio[1373]: verbose: ControllerMpd::sendMpdCommand status Dec 21 09:41:19 bdx-volumio volumio[1373]: info: Dec 21 09:41:19 bdx-volumio volumio[1373]: ---------------------------- MPD announces state update: player Dec 21 09:41:19 bdx-volumio volumio[1373]: info: ControllerMpd::getState Dec 21 09:41:19 bdx-volumio volumio[1373]: verbose: ControllerMpd::sendMpdCommand status Dec 21 09:41:19 bdx-volumio volumio[1373]: info: FusionDsp - ---- read samplerate, raw: 48000,S32_LE,2,32 Dec 21 09:41:19 bdx-volumio volumio[1373]: info: FusionDsp - ---- read samplerate from file: 48000 Dec 21 09:41:19 bdx-volumio volumio[1373]: info: FusionDsp - If filter freq >samplerate/2 then disable it Dec 21 09:41:19 bdx-volumio volumio[1373]: info: Dec 21 09:41:19 bdx-volumio volumio[1373]: ---------------------------- MPD announces state update: player Dec 21 09:41:19 bdx-volumio volumio[1373]: info: sendMpdCommand status took 40 milliseconds Dec 21 09:41:19 bdx-volumio volumio[1373]: info: ControllerMpd::getState Dec 21 09:41:19 bdx-volumio volumio[1373]: verbose: ControllerMpd::sendMpdCommand status Dec 21 09:41:19 bdx-volumio volumio[1373]: info: sendMpdCommand status took 39 milliseconds Dec 21 09:41:19 bdx-volumio volumio[1373]: info: sendMpdCommand status took 38 milliseconds Dec 21 09:41:19 bdx-volumio volumio[1373]: info: sendMpdCommand status took 37 milliseconds Dec 21 09:41:19 bdx-volumio volumio[1373]: verbose: ControllerMpd::parseState Dec 21 09:41:19 bdx-volumio volumio[1373]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 21 09:41:19 bdx-volumio volumio[1373]: verbose: ControllerMpd::parseState Dec 21 09:41:19 bdx-volumio volumio[1373]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 21 09:41:19 bdx-volumio volumio[1373]: verbose: ControllerMpd::parseState Dec 21 09:41:19 bdx-volumio volumio[1373]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 21 09:41:19 bdx-volumio volumio[1373]: verbose: ControllerMpd::parseState Dec 21 09:41:19 bdx-volumio volumio[1373]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 21 09:41:19 bdx-volumio volumio[1373]: info: Dec 21 09:41:19 bdx-volumio volumio[1373]: ---------------------------- MPD announces state update: player Dec 21 09:41:19 bdx-volumio volumio[1373]: info: ControllerMpd::getState Dec 21 09:41:19 bdx-volumio volumio[1373]: verbose: ControllerMpd::sendMpdCommand status Dec 21 09:41:19 bdx-volumio volumio[1373]: info: Dec 21 09:41:19 bdx-volumio volumio[1373]: ---------------------------- MPD announces state update: player Dec 21 09:41:19 bdx-volumio volumio[1373]: info: ControllerMpd::getState Dec 21 09:41:19 bdx-volumio volumio[1373]: verbose: ControllerMpd::sendMpdCommand status Dec 21 09:41:19 bdx-volumio volumio[1373]: info: Dec 21 09:41:19 bdx-volumio volumio[1373]: ---------------------------- MPD announces state update: player Dec 21 09:41:19 bdx-volumio volumio[1373]: info: ControllerMpd::getState Dec 21 09:41:19 bdx-volumio volumio[1373]: verbose: ControllerMpd::sendMpdCommand status Dec 21 09:41:19 bdx-volumio volumio[1373]: info: sendMpdCommand status took 12 milliseconds Dec 21 09:41:19 bdx-volumio volumio[1373]: info: sendMpdCommand playlistinfo took 11 milliseconds Dec 21 09:41:19 bdx-volumio volumio[1373]: info: sendMpdCommand playlistinfo took 10 milliseconds Dec 21 09:41:19 bdx-volumio volumio[1373]: info: sendMpdCommand playlistinfo took 9 milliseconds Dec 21 09:41:19 bdx-volumio volumio[1373]: info: sendMpdCommand playlistinfo took 10 milliseconds Dec 21 09:41:19 bdx-volumio volumio[1373]: info: sendMpdCommand status took 7 milliseconds Dec 21 09:41:19 bdx-volumio volumio[1373]: info: sendMpdCommand status took 6 milliseconds Dec 21 09:41:19 bdx-volumio volumio[1373]: info: sendMpdCommand status took 5 milliseconds Dec 21 09:41:19 bdx-volumio volumio[1373]: verbose: ControllerMpd::parseState Dec 21 09:41:19 bdx-volumio volumio[1373]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 21 09:41:19 bdx-volumio volumio[1373]: verbose: ControllerMpd::parseTrackInfo Dec 21 09:41:19 bdx-volumio volumio[1373]: verbose: ControllerMpd::parseTrackInfo Dec 21 09:41:19 bdx-volumio volumio[1373]: verbose: ControllerMpd::parseTrackInfo Dec 21 09:41:19 bdx-volumio volumio[1373]: verbose: ControllerMpd::parseTrackInfo Dec 21 09:41:19 bdx-volumio volumio[1373]: verbose: ControllerMpd::parseState Dec 21 09:41:19 bdx-volumio volumio[1373]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 21 09:41:19 bdx-volumio volumio[1373]: verbose: ControllerMpd::parseState Dec 21 09:41:19 bdx-volumio volumio[1373]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 21 09:41:19 bdx-volumio volumio[1373]: verbose: ControllerMpd::parseState Dec 21 09:41:19 bdx-volumio volumio[1373]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 21 09:41:19 bdx-volumio volumio[1373]: info: ControllerMpd::pushState Dec 21 09:41:19 bdx-volumio volumio[1373]: info: CoreCommandRouter::servicePushState Dec 21 09:41:19 bdx-volumio volumio[1373]: info: CorePlayQueue::getTrack 0 Dec 21 09:41:19 bdx-volumio volumio[1373]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream.mp3?aggregator=tunein","artist":null,"album":null,"uri":"https://wdr-wdr2-suedwestfalen.icecastssl.wdr.de/wdr/wdr2/suedwestfalen/mp3/128/stream.mp3?aggregator=tunein","trackType":""} Dec 21 09:41:19 bdx-volumio volumio[1373]: verbose: CURRENT POSITION 0 Dec 21 09:41:19 bdx-volumio volumio[1373]: info: CoreStateMachine::syncState stateService play Dec 21 09:41:19 bdx-volumio volumio[1373]: info: CoreStateMachine::syncState currentStatus stop Dec 21 09:41:19 bdx-volumio volumio[1373]: info: ControllerMpd::pushState Dec 21 09:41:19 bdx-volumio volumio[1373]: info: CoreCommandRouter::servicePushState Dec 21 09:41:19 bdx-volumio volumio[1373]: info: CorePlayQueue::getTrack 0 Dec 21 09:41:19 bdx-volumio volumio[1373]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream.mp3?aggregator=tunein","artist":null,"album":null,"uri":"https://wdr-wdr2-suedwestfalen.icecastssl.wdr.de/wdr/wdr2/suedwestfalen/mp3/128/stream.mp3?aggregator=tunein","trackType":""} Dec 21 09:41:19 bdx-volumio volumio[1373]: verbose: CURRENT POSITION 0 Dec 21 09:41:19 bdx-volumio volumio[1373]: info: CoreStateMachine::syncState stateService play Dec 21 09:41:19 bdx-volumio volumio[1373]: info: CoreStateMachine::syncState currentStatus play Dec 21 09:41:19 bdx-volumio volumio[1373]: info: Received an update from plugin. extracting info from payload Dec 21 09:41:19 bdx-volumio volumio[1373]: info: CoreStateMachine::pushState Dec 21 09:41:19 bdx-volumio volumio[1373]: info: CorePlayQueue::getTrack 0 Dec 21 09:41:19 bdx-volumio volumio[1373]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 21 09:41:19 bdx-volumio volumio[1373]: info: CoreCommandRouter::volumioPushState Dec 21 09:41:19 bdx-volumio volumio[1373]: info: CoreStateMachine::pushState Dec 21 09:41:19 bdx-volumio volumio[1373]: info: CoreCommandRouter::volumioPushState Dec 21 09:41:19 bdx-volumio volumio[1373]: info: ControllerMpd::pushState Dec 21 09:41:19 bdx-volumio volumio[1373]: info: CoreCommandRouter::servicePushState Dec 21 09:41:19 bdx-volumio volumio[1373]: info: CorePlayQueue::getTrack 0 Dec 21 09:41:19 bdx-volumio volumio[1373]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream.mp3?aggregator=tunein","artist":null,"album":null,"uri":"https://wdr-wdr2-suedwestfalen.icecastssl.wdr.de/wdr/wdr2/suedwestfalen/mp3/128/stream.mp3?aggregator=tunein","trackType":""} Dec 21 09:41:19 bdx-volumio volumio[1373]: verbose: CURRENT POSITION 0 Dec 21 09:41:19 bdx-volumio volumio[1373]: info: CoreStateMachine::syncState stateService play Dec 21 09:41:19 bdx-volumio volumio[1373]: info: CoreStateMachine::syncState currentStatus play Dec 21 09:41:19 bdx-volumio volumio[1373]: info: Received an update from plugin. extracting info from payload Dec 21 09:41:19 bdx-volumio volumio[1373]: info: CoreStateMachine::pushState Dec 21 09:41:19 bdx-volumio volumio[1373]: info: CorePlayQueue::getTrack 0 Dec 21 09:41:19 bdx-volumio volumio[1373]: info: CoreCommandRouter::volumioPushState Dec 21 09:41:19 bdx-volumio volumio[1373]: info: CoreStateMachine::pushState Dec 21 09:41:19 bdx-volumio volumio[1373]: info: CoreCommandRouter::volumioPushState Dec 21 09:41:19 bdx-volumio volumio[1373]: info: ControllerMpd::pushState Dec 21 09:41:19 bdx-volumio volumio[1373]: info: CoreCommandRouter::servicePushState Dec 21 09:41:19 bdx-volumio volumio[1373]: info: CorePlayQueue::getTrack 0 Dec 21 09:41:19 bdx-volumio volumio[1373]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream.mp3?aggregator=tunein","artist":null,"album":null,"uri":"https://wdr-wdr2-suedwestfalen.icecastssl.wdr.de/wdr/wdr2/suedwestfalen/mp3/128/stream.mp3?aggregator=tunein","trackType":""} Dec 21 09:41:19 bdx-volumio volumio[1373]: verbose: CURRENT POSITION 0 Dec 21 09:41:19 bdx-volumio volumio[1373]: info: CoreStateMachine::syncState stateService play Dec 21 09:41:19 bdx-volumio volumio[1373]: info: CoreStateMachine::syncState currentStatus play Dec 21 09:41:19 bdx-volumio volumio[1373]: info: Received an update from plugin. extracting info from payload Dec 21 09:41:19 bdx-volumio volumio[1373]: info: CoreStateMachine::pushState Dec 21 09:41:19 bdx-volumio volumio[1373]: info: CorePlayQueue::getTrack 0 Dec 21 09:41:19 bdx-volumio volumio[1373]: info: CoreCommandRouter::volumioPushState Dec 21 09:41:19 bdx-volumio volumio[1373]: info: CoreStateMachine::pushState Dec 21 09:41:19 bdx-volumio volumio[1373]: info: CoreCommandRouter::volumioPushState Dec 21 09:41:19 bdx-volumio volumio[1373]: info: ------------------------------ 83ms Dec 21 09:41:20 bdx-volumio volumio[1373]: info: ------------------------------ 91ms Dec 21 09:41:20 bdx-volumio volumio[1373]: info: ------------------------------ 90ms Dec 21 09:41:20 bdx-volumio volumio[1373]: info: ------------------------------ 90ms Dec 21 09:41:20 bdx-volumio volumio[1373]: error: FusionDsp - WebSocket error: [object Object] Dec 21 09:41:20 bdx-volumio volumio[1373]: info: sendMpdCommand playlistinfo took 44 milliseconds Dec 21 09:41:20 bdx-volumio volumio[1373]: info: sendMpdCommand playlistinfo took 41 milliseconds Dec 21 09:41:20 bdx-volumio volumio[1373]: info: sendMpdCommand playlistinfo took 42 milliseconds Dec 21 09:41:20 bdx-volumio volumio[1373]: info: sendMpdCommand playlistinfo took 41 milliseconds Dec 21 09:41:20 bdx-volumio volumio[1373]: verbose: ControllerMpd::parseTrackInfo Dec 21 09:41:20 bdx-volumio volumio[1373]: verbose: ControllerMpd::parseTrackInfo Dec 21 09:41:20 bdx-volumio volumio[1373]: verbose: ControllerMpd::parseTrackInfo Dec 21 09:41:20 bdx-volumio volumio[1373]: verbose: ControllerMpd::parseTrackInfo Dec 21 09:41:20 bdx-volumio volumio[1373]: info: ControllerMpd::pushState Dec 21 09:41:20 bdx-volumio volumio[1373]: info: CoreCommandRouter::servicePushState Dec 21 09:41:20 bdx-volumio volumio[1373]: info: CorePlayQueue::getTrack 0 Dec 21 09:41:20 bdx-volumio volumio[1373]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream.mp3?aggregator=tunein","artist":null,"album":null,"uri":"https://wdr-wdr2-suedwestfalen.icecastssl.wdr.de/wdr/wdr2/suedwestfalen/mp3/128/stream.mp3?aggregator=tunein","trackType":""} Dec 21 09:41:20 bdx-volumio volumio[1373]: verbose: CURRENT POSITION 0 Dec 21 09:41:20 bdx-volumio volumio[1373]: info: CoreStateMachine::syncState stateService play Dec 21 09:41:20 bdx-volumio volumio[1373]: info: CoreStateMachine::syncState currentStatus play Dec 21 09:41:20 bdx-volumio volumio[1373]: info: Received an update from plugin. extracting info from payload Dec 21 09:41:20 bdx-volumio volumio[1373]: info: CoreStateMachine::pushState Dec 21 09:41:20 bdx-volumio volumio[1373]: info: CorePlayQueue::getTrack 0 Dec 21 09:41:20 bdx-volumio volumio[1373]: info: CoreCommandRouter::volumioPushState Dec 21 09:41:20 bdx-volumio volumio[1373]: info: CoreStateMachine::pushState Dec 21 09:41:20 bdx-volumio volumio[1373]: info: CoreCommandRouter::volumioPushState Dec 21 09:41:20 bdx-volumio volumio[1373]: info: ControllerMpd::pushState Dec 21 09:41:20 bdx-volumio volumio[1373]: info: CoreCommandRouter::servicePushState Dec 21 09:41:20 bdx-volumio volumio[1373]: info: CorePlayQueue::getTrack 0 Dec 21 09:41:20 bdx-volumio volumio[1373]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream.mp3?aggregator=tunein","artist":null,"album":null,"uri":"https://wdr-wdr2-suedwestfalen.icecastssl.wdr.de/wdr/wdr2/suedwestfalen/mp3/128/stream.mp3?aggregator=tunein","trackType":""} Dec 21 09:41:20 bdx-volumio volumio[1373]: verbose: CURRENT POSITION 0 Dec 21 09:41:20 bdx-volumio volumio[1373]: info: CoreStateMachine::syncState stateService play Dec 21 09:41:20 bdx-volumio volumio[1373]: info: CoreStateMachine::syncState currentStatus play Dec 21 09:41:20 bdx-volumio volumio[1373]: info: Received an update from plugin. extracting info from payload Dec 21 09:41:20 bdx-volumio volumio[1373]: info: CoreStateMachine::pushState Dec 21 09:41:20 bdx-volumio volumio[1373]: info: CorePlayQueue::getTrack 0 Dec 21 09:41:20 bdx-volumio volumio[1373]: info: CoreCommandRouter::volumioPushState Dec 21 09:41:20 bdx-volumio volumio[1373]: info: CoreStateMachine::pushState Dec 21 09:41:20 bdx-volumio volumio[1373]: info: CoreCommandRouter::volumioPushState Dec 21 09:41:20 bdx-volumio volumio[1373]: info: ControllerMpd::pushState Dec 21 09:41:20 bdx-volumio volumio[1373]: info: CoreCommandRouter::servicePushState Dec 21 09:41:20 bdx-volumio volumio[1373]: info: CorePlayQueue::getTrack 0 Dec 21 09:41:20 bdx-volumio volumio[1373]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream.mp3?aggregator=tunein","artist":null,"album":null,"uri":"https://wdr-wdr2-suedwestfalen.icecastssl.wdr.de/wdr/wdr2/suedwestfalen/mp3/128/stream.mp3?aggregator=tunein","trackType":""} Dec 21 09:41:20 bdx-volumio volumio[1373]: verbose: CURRENT POSITION 0 Dec 21 09:41:20 bdx-volumio volumio[1373]: info: CoreStateMachine::syncState stateService play Dec 21 09:41:20 bdx-volumio volumio[1373]: info: CoreStateMachine::syncState currentStatus play Dec 21 09:41:20 bdx-volumio volumio[1373]: info: Received an update from plugin. extracting info from payload Dec 21 09:41:20 bdx-volumio volumio[1373]: info: CoreStateMachine::pushState Dec 21 09:41:20 bdx-volumio volumio[1373]: info: CorePlayQueue::getTrack 0 Dec 21 09:41:20 bdx-volumio volumio[1373]: info: CoreCommandRouter::volumioPushState Dec 21 09:41:20 bdx-volumio volumio[1373]: info: CoreStateMachine::pushState Dec 21 09:41:20 bdx-volumio volumio[1373]: info: CoreCommandRouter::volumioPushState Dec 21 09:41:20 bdx-volumio volumio[1373]: info: ControllerMpd::pushState Dec 21 09:41:20 bdx-volumio volumio[1373]: info: CoreCommandRouter::servicePushState Dec 21 09:41:20 bdx-volumio volumio[1373]: info: CorePlayQueue::getTrack 0 Dec 21 09:41:20 bdx-volumio volumio[1373]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream.mp3?aggregator=tunein","artist":null,"album":null,"uri":"https://wdr-wdr2-suedwestfalen.icecastssl.wdr.de/wdr/wdr2/suedwestfalen/mp3/128/stream.mp3?aggregator=tunein","trackType":""} Dec 21 09:41:20 bdx-volumio volumio[1373]: verbose: CURRENT POSITION 0 Dec 21 09:41:20 bdx-volumio volumio[1373]: info: CoreStateMachine::syncState stateService play Dec 21 09:41:20 bdx-volumio volumio[1373]: info: CoreStateMachine::syncState currentStatus play Dec 21 09:41:20 bdx-volumio volumio[1373]: info: Received an update from plugin. extracting info from payload Dec 21 09:41:20 bdx-volumio volumio[1373]: info: CoreStateMachine::pushState Dec 21 09:41:20 bdx-volumio volumio[1373]: info: CorePlayQueue::getTrack 0 Dec 21 09:41:20 bdx-volumio volumio[1373]: info: CoreCommandRouter::volumioPushState Dec 21 09:41:20 bdx-volumio volumio[1373]: info: CoreStateMachine::pushState Dec 21 09:41:20 bdx-volumio volumio[1373]: info: CoreCommandRouter::volumioPushState Dec 21 09:41:20 bdx-volumio volumio[1373]: info: ------------------------------ 97ms Dec 21 09:41:20 bdx-volumio volumio[1373]: info: ------------------------------ 91ms Dec 21 09:41:20 bdx-volumio volumio[1373]: info: ------------------------------ 90ms Dec 21 09:41:20 bdx-volumio volumio[1373]: info: ------------------------------ 89ms Dec 21 09:41:20 bdx-volumio volumio[1373]: info: Dec 21 09:41:20 bdx-volumio volumio[1373]: ---------------------------- MPD announces system playlist update Dec 21 09:41:20 bdx-volumio volumio[1373]: info: Ignoring MPD Status Update Dec 21 09:41:20 bdx-volumio volumio[1373]: info: Dec 21 09:41:20 bdx-volumio volumio[1373]: ---------------------------- MPD announces state update: player Dec 21 09:41:20 bdx-volumio volumio[1373]: info: ControllerMpd::getState Dec 21 09:41:20 bdx-volumio volumio[1373]: verbose: ControllerMpd::sendMpdCommand status Dec 21 09:41:20 bdx-volumio volumio[1373]: info: Dec 21 09:41:20 bdx-volumio volumio[1373]: ---------------------------- MPD announces system playlist update Dec 21 09:41:20 bdx-volumio volumio[1373]: info: Ignoring MPD Status Update Dec 21 09:41:20 bdx-volumio volumio[1373]: info: Dec 21 09:41:20 bdx-volumio volumio[1373]: ---------------------------- MPD announces state update: player Dec 21 09:41:20 bdx-volumio volumio[1373]: info: ControllerMpd::getState Dec 21 09:41:20 bdx-volumio volumio[1373]: verbose: ControllerMpd::sendMpdCommand status Dec 21 09:41:20 bdx-volumio volumio[1373]: info: Dec 21 09:41:20 bdx-volumio volumio[1373]: ---------------------------- MPD announces system playlist update Dec 21 09:41:20 bdx-volumio volumio[1373]: info: Ignoring MPD Status Update Dec 21 09:41:20 bdx-volumio volumio[1373]: info: Dec 21 09:41:20 bdx-volumio volumio[1373]: ---------------------------- MPD announces state update: player Dec 21 09:41:20 bdx-volumio volumio[1373]: info: ControllerMpd::getState Dec 21 09:41:20 bdx-volumio volumio[1373]: verbose: ControllerMpd::sendMpdCommand status Dec 21 09:41:20 bdx-volumio volumio[1373]: info: Dec 21 09:41:20 bdx-volumio volumio[1373]: ---------------------------- MPD announces system playlist update Dec 21 09:41:20 bdx-volumio volumio[1373]: info: Ignoring MPD Status Update Dec 21 09:41:20 bdx-volumio volumio[1373]: info: Dec 21 09:41:20 bdx-volumio volumio[1373]: ---------------------------- MPD announces state update: player Dec 21 09:41:20 bdx-volumio volumio[1373]: info: ControllerMpd::getState Dec 21 09:41:20 bdx-volumio volumio[1373]: verbose: ControllerMpd::sendMpdCommand status Dec 21 09:41:20 bdx-volumio volumio[1373]: info: ------------------------------ 11ms Dec 21 09:41:20 bdx-volumio volumio[1373]: info: sendMpdCommand status took 9 milliseconds Dec 21 09:41:20 bdx-volumio volumio[1373]: info: ------------------------------ 8ms Dec 21 09:41:20 bdx-volumio volumio[1373]: info: sendMpdCommand status took 7 milliseconds Dec 21 09:41:20 bdx-volumio volumio[1373]: info: ------------------------------ 7ms Dec 21 09:41:20 bdx-volumio volumio[1373]: info: sendMpdCommand status took 5 milliseconds Dec 21 09:41:20 bdx-volumio volumio[1373]: verbose: ControllerMpd::parseState Dec 21 09:41:20 bdx-volumio volumio[1373]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 21 09:41:20 bdx-volumio volumio[1373]: verbose: ControllerMpd::parseState Dec 21 09:41:20 bdx-volumio volumio[1373]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 21 09:41:20 bdx-volumio volumio[1373]: verbose: ControllerMpd::parseState Dec 21 09:41:20 bdx-volumio volumio[1373]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 21 09:41:20 bdx-volumio volumio[1373]: info: Dec 21 09:41:20 bdx-volumio volumio[1373]: ---------------------------- MPD announces system playlist update Dec 21 09:41:20 bdx-volumio volumio[1373]: info: Ignoring MPD Status Update Dec 21 09:41:20 bdx-volumio volumio[1373]: info: Dec 21 09:41:20 bdx-volumio volumio[1373]: ---------------------------- MPD announces state update: player Dec 21 09:41:20 bdx-volumio volumio[1373]: info: ControllerMpd::getState Dec 21 09:41:20 bdx-volumio volumio[1373]: verbose: ControllerMpd::sendMpdCommand status Dec 21 09:41:20 bdx-volumio volumio[1373]: info: Dec 21 09:41:20 bdx-volumio volumio[1373]: ---------------------------- MPD announces system playlist update Dec 21 09:41:20 bdx-volumio volumio[1373]: info: Ignoring MPD Status Update Dec 21 09:41:20 bdx-volumio volumio[1373]: info: Dec 21 09:41:20 bdx-volumio volumio[1373]: ---------------------------- MPD announces state update: player Dec 21 09:41:20 bdx-volumio volumio[1373]: info: ControllerMpd::getState Dec 21 09:41:20 bdx-volumio volumio[1373]: verbose: ControllerMpd::sendMpdCommand status Dec 21 09:41:20 bdx-volumio volumio[1373]: info: Dec 21 09:41:20 bdx-volumio volumio[1373]: ---------------------------- MPD announces system playlist update Dec 21 09:41:20 bdx-volumio volumio[1373]: info: Ignoring MPD Status Update Dec 21 09:41:20 bdx-volumio volumio[1373]: info: Dec 21 09:41:20 bdx-volumio volumio[1373]: ---------------------------- MPD announces state update: player Dec 21 09:41:20 bdx-volumio volumio[1373]: info: ControllerMpd::getState Dec 21 09:41:20 bdx-volumio volumio[1373]: verbose: ControllerMpd::sendMpdCommand status Dec 21 09:41:20 bdx-volumio volumio[1373]: info: ------------------------------ 16ms Dec 21 09:41:20 bdx-volumio volumio[1373]: info: sendMpdCommand status took 14 milliseconds Dec 21 09:41:20 bdx-volumio volumio[1373]: info: sendMpdCommand playlistinfo took 12 milliseconds Dec 21 09:41:20 bdx-volumio volumio[1373]: info: sendMpdCommand playlistinfo took 11 milliseconds Dec 21 09:41:20 bdx-volumio volumio[1373]: info: sendMpdCommand playlistinfo took 10 milliseconds Dec 21 09:41:20 bdx-volumio volumio[1373]: info: ------------------------------ 9ms Dec 21 09:41:20 bdx-volumio volumio[1373]: info: sendMpdCommand status took 9 milliseconds Dec 21 09:41:20 bdx-volumio volumio[1373]: info: ------------------------------ 8ms Dec 21 09:41:20 bdx-volumio volumio[1373]: info: sendMpdCommand status took 7 milliseconds Dec 21 09:41:20 bdx-volumio volumio[1373]: info: ------------------------------ 5ms Dec 21 09:41:20 bdx-volumio volumio[1373]: info: sendMpdCommand status took 5 milliseconds Dec 21 09:41:20 bdx-volumio volumio[1373]: verbose: ControllerMpd::parseState Dec 21 09:41:20 bdx-volumio volumio[1373]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 21 09:41:20 bdx-volumio volumio[1373]: verbose: ControllerMpd::parseTrackInfo Dec 21 09:41:20 bdx-volumio volumio[1373]: verbose: ControllerMpd::parseTrackInfo Dec 21 09:41:20 bdx-volumio volumio[1373]: verbose: ControllerMpd::parseTrackInfo Dec 21 09:41:20 bdx-volumio volumio[1373]: verbose: ControllerMpd::parseState Dec 21 09:41:20 bdx-volumio volumio[1373]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 21 09:41:20 bdx-volumio volumio[1373]: verbose: ControllerMpd::parseState Dec 21 09:41:20 bdx-volumio volumio[1373]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 21 09:41:20 bdx-volumio volumio[1373]: verbose: ControllerMpd::parseState Dec 21 09:41:20 bdx-volumio volumio[1373]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 21 09:41:20 bdx-volumio volumio[1373]: info: ControllerMpd::pushState Dec 21 09:41:20 bdx-volumio volumio[1373]: info: CoreCommandRouter::servicePushState Dec 21 09:41:20 bdx-volumio volumio[1373]: info: CorePlayQueue::getTrack 0 Dec 21 09:41:20 bdx-volumio volumio[1373]: verbose: STATE SERVICE {"status":"play","position":0,"seek":860,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Wir sind der Westen","artist":"WDR 2 Suedwestfalen aktuell, Westdeutscher Rundfunk Koeln","album":null,"uri":"https://wdr-wdr2-suedwestfalen.icecastssl.wdr.de/wdr/wdr2/suedwestfalen/mp3/128/stream.mp3?aggregator=tunein","trackType":""} Dec 21 09:41:20 bdx-volumio volumio[1373]: verbose: CURRENT POSITION 0 Dec 21 09:41:20 bdx-volumio volumio[1373]: info: CoreStateMachine::syncState stateService play Dec 21 09:41:20 bdx-volumio volumio[1373]: info: CoreStateMachine::syncState currentStatus play Dec 21 09:41:20 bdx-volumio volumio[1373]: info: Received an update from plugin. extracting info from payload Dec 21 09:41:20 bdx-volumio volumio[1373]: info: CoreStateMachine::pushState Dec 21 09:41:20 bdx-volumio volumio[1373]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 21 09:41:20 bdx-volumio volumio[1373]: info: CoreCommandRouter::volumioPushState Dec 21 09:41:20 bdx-volumio volumio[1373]: info: CoreStateMachine::pushState Dec 21 09:41:20 bdx-volumio volumio[1373]: info: CoreCommandRouter::volumioPushState Dec 21 09:41:20 bdx-volumio volumio[1373]: info: ControllerMpd::pushState Dec 21 09:41:20 bdx-volumio volumio[1373]: info: CoreCommandRouter::servicePushState Dec 21 09:41:20 bdx-volumio volumio[1373]: info: CorePlayQueue::getTrack 0 Dec 21 09:41:20 bdx-volumio volumio[1373]: verbose: STATE SERVICE {"status":"play","position":0,"seek":860,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Wir sind der Westen","artist":"WDR 2 Suedwestfalen aktuell, Westdeutscher Rundfunk Koeln","album":null,"uri":"https://wdr-wdr2-suedwestfalen.icecastssl.wdr.de/wdr/wdr2/suedwestfalen/mp3/128/stream.mp3?aggregator=tunein","trackType":""} Dec 21 09:41:20 bdx-volumio volumio[1373]: verbose: CURRENT POSITION 0 Dec 21 09:41:20 bdx-volumio volumio[1373]: info: CoreStateMachine::syncState stateService play Dec 21 09:41:20 bdx-volumio volumio[1373]: info: CoreStateMachine::syncState currentStatus play Dec 21 09:41:20 bdx-volumio volumio[1373]: info: Received an update from plugin. extracting info from payload Dec 21 09:41:20 bdx-volumio volumio[1373]: info: CoreStateMachine::pushState Dec 21 09:41:20 bdx-volumio volumio[1373]: info: CoreCommandRouter::volumioPushState Dec 21 09:41:20 bdx-volumio volumio[1373]: info: CoreStateMachine::pushState Dec 21 09:41:20 bdx-volumio volumio[1373]: info: CoreCommandRouter::volumioPushState Dec 21 09:41:20 bdx-volumio volumio[1373]: info: ControllerMpd::pushState Dec 21 09:41:20 bdx-volumio volumio[1373]: info: CoreCommandRouter::servicePushState Dec 21 09:41:20 bdx-volumio volumio[1373]: info: CorePlayQueue::getTrack 0 Dec 21 09:41:20 bdx-volumio volumio[1373]: verbose: STATE SERVICE {"status":"play","position":0,"seek":860,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Wir sind der Westen","artist":"WDR 2 Suedwestfalen aktuell, Westdeutscher Rundfunk Koeln","album":null,"uri":"https://wdr-wdr2-suedwestfalen.icecastssl.wdr.de/wdr/wdr2/suedwestfalen/mp3/128/stream.mp3?aggregator=tunein","trackType":""} Dec 21 09:41:20 bdx-volumio volumio[1373]: verbose: CURRENT POSITION 0 Dec 21 09:41:20 bdx-volumio volumio[1373]: info: CoreStateMachine::syncState stateService play Dec 21 09:41:20 bdx-volumio volumio[1373]: info: CoreStateMachine::syncState currentStatus play Dec 21 09:41:20 bdx-volumio volumio[1373]: info: Received an update from plugin. extracting info from payload Dec 21 09:41:20 bdx-volumio volumio[1373]: info: CoreStateMachine::pushState Dec 21 09:41:20 bdx-volumio volumio[1373]: info: CoreCommandRouter::volumioPushState Dec 21 09:41:20 bdx-volumio volumio[1373]: info: CoreStateMachine::pushState Dec 21 09:41:20 bdx-volumio volumio[1373]: info: CoreCommandRouter::volumioPushState Dec 21 09:41:20 bdx-volumio volumio[1373]: info: ------------------------------ 57ms Dec 21 09:41:20 bdx-volumio volumio[1373]: info: ------------------------------ 56ms Dec 21 09:41:20 bdx-volumio volumio[1373]: info: ------------------------------ 54ms Dec 21 09:41:20 bdx-volumio volumio[1373]: info: Dec 21 09:41:20 bdx-volumio volumio[1373]: ---------------------------- MPD announces system playlist update Dec 21 09:41:20 bdx-volumio volumio[1373]: info: Ignoring MPD Status Update Dec 21 09:41:20 bdx-volumio volumio[1373]: info: Dec 21 09:41:20 bdx-volumio volumio[1373]: ---------------------------- MPD announces state update: player Dec 21 09:41:20 bdx-volumio volumio[1373]: info: ControllerMpd::getState Dec 21 09:41:20 bdx-volumio volumio[1373]: verbose: ControllerMpd::sendMpdCommand status Dec 21 09:41:20 bdx-volumio volumio[1373]: info: sendMpdCommand playlistinfo took 38 milliseconds Dec 21 09:41:20 bdx-volumio volumio[1373]: info: sendMpdCommand playlistinfo took 37 milliseconds Dec 21 09:41:20 bdx-volumio volumio[1373]: info: sendMpdCommand playlistinfo took 37 milliseconds Dec 21 09:41:20 bdx-volumio volumio[1373]: info: sendMpdCommand playlistinfo took 36 milliseconds Dec 21 09:41:20 bdx-volumio volumio[1373]: info: ------------------------------ 6ms Dec 21 09:41:20 bdx-volumio volumio[1373]: info: sendMpdCommand status took 4 milliseconds Dec 21 09:41:20 bdx-volumio volumio[1373]: verbose: ControllerMpd::parseTrackInfo Dec 21 09:41:20 bdx-volumio volumio[1373]: verbose: ControllerMpd::parseTrackInfo Dec 21 09:41:20 bdx-volumio volumio[1373]: verbose: ControllerMpd::parseTrackInfo Dec 21 09:41:20 bdx-volumio volumio[1373]: verbose: ControllerMpd::parseTrackInfo Dec 21 09:41:20 bdx-volumio volumio[1373]: verbose: ControllerMpd::parseState Dec 21 09:41:20 bdx-volumio volumio[1373]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 21 09:41:20 bdx-volumio volumio[1373]: info: ControllerMpd::pushState Dec 21 09:41:20 bdx-volumio volumio[1373]: info: CoreCommandRouter::servicePushState Dec 21 09:41:20 bdx-volumio volumio[1373]: info: CorePlayQueue::getTrack 0 Dec 21 09:41:20 bdx-volumio volumio[1373]: verbose: STATE SERVICE {"status":"play","position":0,"seek":860,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Wir sind der Westen","artist":"WDR 2 Suedwestfalen aktuell, Westdeutscher Rundfunk Koeln","album":null,"uri":"https://wdr-wdr2-suedwestfalen.icecastssl.wdr.de/wdr/wdr2/suedwestfalen/mp3/128/stream.mp3?aggregator=tunein","trackType":""} Dec 21 09:41:20 bdx-volumio volumio[1373]: verbose: CURRENT POSITION 0 Dec 21 09:41:20 bdx-volumio volumio[1373]: info: CoreStateMachine::syncState stateService play Dec 21 09:41:20 bdx-volumio volumio[1373]: info: CoreStateMachine::syncState currentStatus play Dec 21 09:41:20 bdx-volumio volumio[1373]: info: Received an update from plugin. extracting info from payload Dec 21 09:41:20 bdx-volumio volumio[1373]: info: CoreStateMachine::pushState Dec 21 09:41:20 bdx-volumio volumio[1373]: info: CoreCommandRouter::volumioPushState Dec 21 09:41:20 bdx-volumio volumio[1373]: info: CoreStateMachine::pushState Dec 21 09:41:20 bdx-volumio volumio[1373]: info: CoreCommandRouter::volumioPushState Dec 21 09:41:20 bdx-volumio volumio[1373]: info: ControllerMpd::pushState Dec 21 09:41:20 bdx-volumio volumio[1373]: info: CoreCommandRouter::servicePushState Dec 21 09:41:20 bdx-volumio volumio[1373]: info: CorePlayQueue::getTrack 0 Dec 21 09:41:20 bdx-volumio volumio[1373]: verbose: STATE SERVICE {"status":"play","position":0,"seek":860,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Wir sind der Westen","artist":"WDR 2 Suedwestfalen aktuell, Westdeutscher Rundfunk Koeln","album":null,"uri":"https://wdr-wdr2-suedwestfalen.icecastssl.wdr.de/wdr/wdr2/suedwestfalen/mp3/128/stream.mp3?aggregator=tunein","trackType":""} Dec 21 09:41:20 bdx-volumio volumio[1373]: verbose: CURRENT POSITION 0 Dec 21 09:41:20 bdx-volumio volumio[1373]: info: CoreStateMachine::syncState stateService play Dec 21 09:41:20 bdx-volumio volumio[1373]: info: CoreStateMachine::syncState currentStatus play Dec 21 09:41:20 bdx-volumio volumio[1373]: info: Received an update from plugin. extracting info from payload Dec 21 09:41:20 bdx-volumio volumio[1373]: info: CoreStateMachine::pushState Dec 21 09:41:20 bdx-volumio volumio[1373]: info: CoreCommandRouter::volumioPushState Dec 21 09:41:20 bdx-volumio volumio[1373]: info: CoreStateMachine::pushState Dec 21 09:41:20 bdx-volumio volumio[1373]: info: CoreCommandRouter::volumioPushState Dec 21 09:41:20 bdx-volumio volumio[1373]: info: ControllerMpd::pushState Dec 21 09:41:20 bdx-volumio volumio[1373]: info: CoreCommandRouter::servicePushState Dec 21 09:41:20 bdx-volumio volumio[1373]: info: CorePlayQueue::getTrack 0 Dec 21 09:41:20 bdx-volumio volumio[1373]: verbose: STATE SERVICE {"status":"play","position":0,"seek":860,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Wir sind der Westen","artist":"WDR 2 Suedwestfalen aktuell, Westdeutscher Rundfunk Koeln","album":null,"uri":"https://wdr-wdr2-suedwestfalen.icecastssl.wdr.de/wdr/wdr2/suedwestfalen/mp3/128/stream.mp3?aggregator=tunein","trackType":""} Dec 21 09:41:20 bdx-volumio volumio[1373]: verbose: CURRENT POSITION 0 Dec 21 09:41:20 bdx-volumio volumio[1373]: info: CoreStateMachine::syncState stateService play Dec 21 09:41:20 bdx-volumio volumio[1373]: info: CoreStateMachine::syncState currentStatus play Dec 21 09:41:20 bdx-volumio volumio[1373]: info: Received an update from plugin. extracting info from payload Dec 21 09:41:20 bdx-volumio volumio[1373]: info: CoreStateMachine::pushState Dec 21 09:41:20 bdx-volumio volumio[1373]: info: CoreCommandRouter::volumioPushState Dec 21 09:41:20 bdx-volumio volumio[1373]: info: CoreStateMachine::pushState Dec 21 09:41:20 bdx-volumio volumio[1373]: info: CoreCommandRouter::volumioPushState Dec 21 09:41:20 bdx-volumio volumio[1373]: info: ControllerMpd::pushState Dec 21 09:41:20 bdx-volumio volumio[1373]: info: CoreCommandRouter::servicePushState Dec 21 09:41:20 bdx-volumio volumio[1373]: info: CorePlayQueue::getTrack 0 Dec 21 09:41:20 bdx-volumio volumio[1373]: verbose: STATE SERVICE {"status":"play","position":0,"seek":860,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Wir sind der Westen","artist":"WDR 2 Suedwestfalen aktuell, Westdeutscher Rundfunk Koeln","album":null,"uri":"https://wdr-wdr2-suedwestfalen.icecastssl.wdr.de/wdr/wdr2/suedwestfalen/mp3/128/stream.mp3?aggregator=tunein","trackType":""} Dec 21 09:41:20 bdx-volumio volumio[1373]: verbose: CURRENT POSITION 0 Dec 21 09:41:20 bdx-volumio volumio[1373]: info: CoreStateMachine::syncState stateService play Dec 21 09:41:20 bdx-volumio volumio[1373]: info: CoreStateMachine::syncState currentStatus play Dec 21 09:41:20 bdx-volumio volumio[1373]: info: Received an update from plugin. extracting info from payload Dec 21 09:41:20 bdx-volumio volumio[1373]: info: CoreStateMachine::pushState Dec 21 09:41:20 bdx-volumio volumio[1373]: info: CoreCommandRouter::volumioPushState Dec 21 09:41:20 bdx-volumio volumio[1373]: info: CoreStateMachine::pushState Dec 21 09:41:20 bdx-volumio volumio[1373]: info: CoreCommandRouter::volumioPushState Dec 21 09:41:20 bdx-volumio volumio[1373]: info: ------------------------------ 108ms Dec 21 09:41:20 bdx-volumio volumio[1373]: info: ------------------------------ 102ms Dec 21 09:41:20 bdx-volumio volumio[1373]: info: ------------------------------ 101ms Dec 21 09:41:20 bdx-volumio volumio[1373]: info: ------------------------------ 99ms Dec 21 09:41:20 bdx-volumio volumio[1373]: info: sendMpdCommand playlistinfo took 81 milliseconds Dec 21 09:41:20 bdx-volumio volumio[1373]: verbose: ControllerMpd::parseTrackInfo Dec 21 09:41:20 bdx-volumio volumio[1373]: info: ControllerMpd::pushState Dec 21 09:41:20 bdx-volumio volumio[1373]: info: CoreCommandRouter::servicePushState Dec 21 09:41:20 bdx-volumio volumio[1373]: info: CorePlayQueue::getTrack 0 Dec 21 09:41:20 bdx-volumio volumio[1373]: verbose: STATE SERVICE {"status":"play","position":0,"seek":860,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Wir sind der Westen","artist":"WDR 2 Suedwestfalen aktuell, Westdeutscher Rundfunk Koeln","album":null,"uri":"https://wdr-wdr2-suedwestfalen.icecastssl.wdr.de/wdr/wdr2/suedwestfalen/mp3/128/stream.mp3?aggregator=tunein","trackType":""} Dec 21 09:41:20 bdx-volumio volumio[1373]: verbose: CURRENT POSITION 0 Dec 21 09:41:20 bdx-volumio volumio[1373]: info: CoreStateMachine::syncState stateService play Dec 21 09:41:20 bdx-volumio volumio[1373]: info: CoreStateMachine::syncState currentStatus play Dec 21 09:41:20 bdx-volumio volumio[1373]: info: Received an update from plugin. extracting info from payload Dec 21 09:41:20 bdx-volumio volumio[1373]: info: CoreStateMachine::pushState Dec 21 09:41:20 bdx-volumio volumio[1373]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 21 09:41:20 bdx-volumio volumio[1373]: info: CoreCommandRouter::volumioPushState Dec 21 09:41:20 bdx-volumio volumio[1373]: info: CoreStateMachine::pushState Dec 21 09:41:20 bdx-volumio volumio[1373]: info: CoreCommandRouter::volumioPushState Dec 21 09:41:20 bdx-volumio volumio[1373]: info: ------------------------------ 101ms Dec 21 09:41:43 bdx-volumio volumio[1373]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Dec 21 09:41:43 bdx-volumio volumio[1373]: info: Preload queue cleared Dec 21 09:41:44 bdx-volumio volumio[1373]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Dec 21 09:41:44 bdx-volumio volumio[1373]: info: Preload queue cleared Dec 21 09:41:45 bdx-volumio volumio[1373]: info: Preload queue cleared Dec 21 09:41:45 bdx-volumio volumio[1373]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 21 09:41:45 bdx-volumio volumio[1373]: info: CoreStateMachine::ClearQueue Dec 21 09:41:45 bdx-volumio volumio[1373]: info: CoreStateMachine::stop Dec 21 09:41:45 bdx-volumio volumio[1373]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 21 09:41:45 bdx-volumio volumio[1373]: info: CoreStateMachine::stPlaybackTimer Dec 21 09:41:45 bdx-volumio volumio[1373]: info: CoreStateMachine::updateTrackBlock Dec 21 09:41:45 bdx-volumio volumio[1373]: info: CorePlayQueue::getTrackBlock Dec 21 09:41:45 bdx-volumio volumio[1373]: info: CoreStateMachine::pushState Dec 21 09:41:45 bdx-volumio volumio[1373]: info: CorePlayQueue::getTrack 0 Dec 21 09:41:45 bdx-volumio volumio[1373]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 21 09:41:45 bdx-volumio volumio[1373]: info: CoreCommandRouter::volumioPushState Dec 21 09:41:45 bdx-volumio volumio[1373]: info: CoreStateMachine::serviceStop Dec 21 09:41:45 bdx-volumio volumio[1373]: info: CorePlayQueue::getTrack 0 Dec 21 09:41:45 bdx-volumio volumio[1373]: info: CoreCommandRouter::serviceStop Dec 21 09:41:45 bdx-volumio volumio[1373]: info: [1766306505657] ControllerWebradio::stop Dec 21 09:41:45 bdx-volumio volumio[1373]: verbose: ControllerMpd::sendMpdCommand stop Dec 21 09:41:45 bdx-volumio volumio[1373]: info: CorePlayQueue::clearPlayQueue Dec 21 09:41:45 bdx-volumio volumio[1373]: info: CorePlayQueue::saveQueue Dec 21 09:41:45 bdx-volumio volumio[1373]: info: CoreCommandRouter::volumioPushQueue Dec 21 09:41:45 bdx-volumio volumio[1373]: info: CoreStateMachine::addQueueItems Dec 21 09:41:45 bdx-volumio volumio[1373]: info: CorePlayQueue::addQueueItems Dec 21 09:41:45 bdx-volumio volumio[1373]: info: Preload queue cleared Dec 21 09:41:45 bdx-volumio volumio[1373]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s309360 Dec 21 09:41:45 bdx-volumio volumio[1373]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Dec 21 09:41:45 bdx-volumio volumio[1373]: info: Dec 21 09:41:45 bdx-volumio volumio[1373]: ---------------------------- MPD announces state update: player Dec 21 09:41:45 bdx-volumio volumio[1373]: info: sendMpdCommand stop took 52 milliseconds Dec 21 09:41:45 bdx-volumio volumio[1373]: info: ControllerMpd::getState Dec 21 09:41:45 bdx-volumio volumio[1373]: verbose: ControllerMpd::sendMpdCommand status Dec 21 09:41:45 bdx-volumio volumio[1373]: info: Dec 21 09:41:45 bdx-volumio volumio[1373]: ---------------------------- MPD announces state update: player Dec 21 09:41:45 bdx-volumio volumio[1373]: info: ControllerMpd::getState Dec 21 09:41:45 bdx-volumio volumio[1373]: verbose: ControllerMpd::sendMpdCommand status Dec 21 09:41:45 bdx-volumio volumio[1373]: info: Dec 21 09:41:45 bdx-volumio volumio[1373]: ---------------------------- MPD announces state update: player Dec 21 09:41:45 bdx-volumio volumio[1373]: info: ControllerMpd::getState Dec 21 09:41:45 bdx-volumio volumio[1373]: verbose: ControllerMpd::sendMpdCommand status Dec 21 09:41:45 bdx-volumio volumio[1373]: info: Dec 21 09:41:45 bdx-volumio volumio[1373]: ---------------------------- MPD announces state update: player Dec 21 09:41:45 bdx-volumio volumio[1373]: info: ControllerMpd::getState Dec 21 09:41:45 bdx-volumio volumio[1373]: verbose: ControllerMpd::sendMpdCommand status Dec 21 09:41:45 bdx-volumio volumio[1373]: info: sendMpdCommand status took 11 milliseconds Dec 21 09:41:45 bdx-volumio volumio[1373]: info: sendMpdCommand status took 10 milliseconds Dec 21 09:41:45 bdx-volumio volumio[1373]: info: sendMpdCommand status took 9 milliseconds Dec 21 09:41:45 bdx-volumio volumio[1373]: info: sendMpdCommand status took 8 milliseconds Dec 21 09:41:45 bdx-volumio volumio[1373]: verbose: ControllerMpd::parseState Dec 21 09:41:45 bdx-volumio volumio[1373]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 21 09:41:45 bdx-volumio volumio[1373]: verbose: ControllerMpd::parseState Dec 21 09:41:45 bdx-volumio volumio[1373]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 21 09:41:45 bdx-volumio volumio[1373]: verbose: ControllerMpd::parseState Dec 21 09:41:45 bdx-volumio volumio[1373]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 21 09:41:45 bdx-volumio volumio[1373]: verbose: ControllerMpd::parseState Dec 21 09:41:45 bdx-volumio volumio[1373]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 21 09:41:45 bdx-volumio volumio[1373]: info: sendMpdCommand playlistinfo took 11 milliseconds Dec 21 09:41:45 bdx-volumio volumio[1373]: info: sendMpdCommand playlistinfo took 12 milliseconds Dec 21 09:41:45 bdx-volumio volumio[1373]: info: sendMpdCommand playlistinfo took 11 milliseconds Dec 21 09:41:45 bdx-volumio volumio[1373]: info: sendMpdCommand playlistinfo took 11 milliseconds Dec 21 09:41:45 bdx-volumio volumio[1373]: verbose: ControllerMpd::parseTrackInfo Dec 21 09:41:45 bdx-volumio volumio[1373]: verbose: ControllerMpd::parseTrackInfo Dec 21 09:41:45 bdx-volumio volumio[1373]: verbose: ControllerMpd::parseTrackInfo Dec 21 09:41:45 bdx-volumio volumio[1373]: verbose: ControllerMpd::parseTrackInfo Dec 21 09:41:45 bdx-volumio volumio[1373]: info: ControllerMpd::pushState Dec 21 09:41:45 bdx-volumio volumio[1373]: info: CoreCommandRouter::servicePushState Dec 21 09:41:45 bdx-volumio volumio[1373]: info: CoreStateMachine::pushState Dec 21 09:41:45 bdx-volumio volumio[1373]: info: CorePlayQueue::getTrack 0 Dec 21 09:41:45 bdx-volumio volumio[1373]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 21 09:41:45 bdx-volumio volumio[1373]: info: CoreCommandRouter::volumioPushState Dec 21 09:41:45 bdx-volumio volumio[1373]: info: CorePlayQueue::getTrack 0 Dec 21 09:41:45 bdx-volumio volumio[1373]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Wir sind der Westen","artist":"WDR 2 Suedwestfalen aktuell, Westdeutscher Rundfunk Koeln","album":null,"uri":"https://wdr-wdr2-suedwestfalen.icecastssl.wdr.de/wdr/wdr2/suedwestfalen/mp3/128/stream.mp3?aggregator=tunein","trackType":""} Dec 21 09:41:45 bdx-volumio volumio[1373]: verbose: CURRENT POSITION 0 Dec 21 09:41:45 bdx-volumio volumio[1373]: info: CoreStateMachine::syncState stateService stop Dec 21 09:41:45 bdx-volumio volumio[1373]: info: CoreStateMachine::syncState currentStatus stop Dec 21 09:41:45 bdx-volumio volumio[1373]: info: CoreStateMachine::pushState Dec 21 09:41:45 bdx-volumio volumio[1373]: info: CorePlayQueue::getTrack 0 Dec 21 09:41:45 bdx-volumio volumio[1373]: info: CoreCommandRouter::volumioPushState Dec 21 09:41:45 bdx-volumio volumio[1373]: info: No code Dec 21 09:41:45 bdx-volumio volumio[1373]: info: CoreStateMachine::pushState Dec 21 09:41:45 bdx-volumio volumio[1373]: info: CorePlayQueue::getTrack 0 Dec 21 09:41:45 bdx-volumio volumio[1373]: info: CoreCommandRouter::volumioPushState Dec 21 09:41:45 bdx-volumio volumio[1373]: info: ControllerMpd::pushState Dec 21 09:41:45 bdx-volumio volumio[1373]: info: CoreCommandRouter::servicePushState Dec 21 09:41:45 bdx-volumio volumio[1373]: info: CoreStateMachine::pushState Dec 21 09:41:45 bdx-volumio volumio[1373]: info: CorePlayQueue::getTrack 0 Dec 21 09:41:45 bdx-volumio volumio[1373]: info: CoreCommandRouter::volumioPushState Dec 21 09:41:45 bdx-volumio volumio[1373]: info: CorePlayQueue::getTrack 0 Dec 21 09:41:45 bdx-volumio volumio[1373]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Wir sind der Westen","artist":"WDR 2 Suedwestfalen aktuell, Westdeutscher Rundfunk Koeln","album":null,"uri":"https://wdr-wdr2-suedwestfalen.icecastssl.wdr.de/wdr/wdr2/suedwestfalen/mp3/128/stream.mp3?aggregator=tunein","trackType":""} Dec 21 09:41:45 bdx-volumio volumio[1373]: verbose: CURRENT POSITION 0 Dec 21 09:41:45 bdx-volumio volumio[1373]: info: CoreStateMachine::syncState stateService stop Dec 21 09:41:45 bdx-volumio volumio[1373]: info: CoreStateMachine::syncState currentStatus stop Dec 21 09:41:45 bdx-volumio volumio[1373]: info: CoreStateMachine::pushState Dec 21 09:41:45 bdx-volumio volumio[1373]: info: CorePlayQueue::getTrack 0 Dec 21 09:41:45 bdx-volumio volumio[1373]: info: CoreCommandRouter::volumioPushState Dec 21 09:41:45 bdx-volumio volumio[1373]: info: No code Dec 21 09:41:45 bdx-volumio volumio[1373]: info: CoreStateMachine::pushState Dec 21 09:41:45 bdx-volumio volumio[1373]: info: CorePlayQueue::getTrack 0 Dec 21 09:41:45 bdx-volumio volumio[1373]: info: CoreCommandRouter::volumioPushState Dec 21 09:41:45 bdx-volumio volumio[1373]: info: ControllerMpd::pushState Dec 21 09:41:45 bdx-volumio volumio[1373]: info: CoreCommandRouter::servicePushState Dec 21 09:41:45 bdx-volumio volumio[1373]: info: CoreStateMachine::pushState Dec 21 09:41:45 bdx-volumio volumio[1373]: info: CorePlayQueue::getTrack 0 Dec 21 09:41:45 bdx-volumio volumio[1373]: info: CoreCommandRouter::volumioPushState Dec 21 09:41:45 bdx-volumio volumio[1373]: info: CorePlayQueue::getTrack 0 Dec 21 09:41:45 bdx-volumio volumio[1373]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Wir sind der Westen","artist":"WDR 2 Suedwestfalen aktuell, Westdeutscher Rundfunk Koeln","album":null,"uri":"https://wdr-wdr2-suedwestfalen.icecastssl.wdr.de/wdr/wdr2/suedwestfalen/mp3/128/stream.mp3?aggregator=tunein","trackType":""} Dec 21 09:41:45 bdx-volumio volumio[1373]: verbose: CURRENT POSITION 0 Dec 21 09:41:45 bdx-volumio volumio[1373]: info: CoreStateMachine::syncState stateService stop Dec 21 09:41:45 bdx-volumio volumio[1373]: info: CoreStateMachine::syncState currentStatus stop Dec 21 09:41:45 bdx-volumio volumio[1373]: info: CoreStateMachine::pushState Dec 21 09:41:45 bdx-volumio volumio[1373]: info: CorePlayQueue::getTrack 0 Dec 21 09:41:45 bdx-volumio volumio[1373]: info: CoreCommandRouter::volumioPushState Dec 21 09:41:45 bdx-volumio volumio[1373]: info: No code Dec 21 09:41:45 bdx-volumio volumio[1373]: info: CoreStateMachine::pushState Dec 21 09:41:45 bdx-volumio volumio[1373]: info: CorePlayQueue::getTrack 0 Dec 21 09:41:45 bdx-volumio volumio[1373]: info: CoreCommandRouter::volumioPushState Dec 21 09:41:45 bdx-volumio volumio[1373]: info: ControllerMpd::pushState Dec 21 09:41:45 bdx-volumio volumio[1373]: info: CoreCommandRouter::servicePushState Dec 21 09:41:45 bdx-volumio volumio[1373]: info: CoreStateMachine::pushState Dec 21 09:41:45 bdx-volumio volumio[1373]: info: CorePlayQueue::getTrack 0 Dec 21 09:41:45 bdx-volumio volumio[1373]: info: CoreCommandRouter::volumioPushState Dec 21 09:41:45 bdx-volumio volumio[1373]: info: CorePlayQueue::getTrack 0 Dec 21 09:41:45 bdx-volumio volumio[1373]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Wir sind der Westen","artist":"WDR 2 Suedwestfalen aktuell, Westdeutscher Rundfunk Koeln","album":null,"uri":"https://wdr-wdr2-suedwestfalen.icecastssl.wdr.de/wdr/wdr2/suedwestfalen/mp3/128/stream.mp3?aggregator=tunein","trackType":""} Dec 21 09:41:45 bdx-volumio volumio[1373]: verbose: CURRENT POSITION 0 Dec 21 09:41:45 bdx-volumio volumio[1373]: info: CoreStateMachine::syncState stateService stop Dec 21 09:41:45 bdx-volumio volumio[1373]: info: CoreStateMachine::syncState currentStatus stop Dec 21 09:41:45 bdx-volumio volumio[1373]: info: CoreStateMachine::pushState Dec 21 09:41:45 bdx-volumio volumio[1373]: info: CorePlayQueue::getTrack 0 Dec 21 09:41:45 bdx-volumio volumio[1373]: info: CoreCommandRouter::volumioPushState Dec 21 09:41:45 bdx-volumio volumio[1373]: info: No code Dec 21 09:41:45 bdx-volumio volumio[1373]: info: CoreStateMachine::pushState Dec 21 09:41:45 bdx-volumio volumio[1373]: info: CorePlayQueue::getTrack 0 Dec 21 09:41:45 bdx-volumio volumio[1373]: info: CoreCommandRouter::volumioPushState Dec 21 09:41:45 bdx-volumio volumio[1373]: info: ------------------------------ 90ms Dec 21 09:41:45 bdx-volumio volumio[1373]: info: ------------------------------ 90ms Dec 21 09:41:45 bdx-volumio volumio[1373]: info: ------------------------------ 89ms Dec 21 09:41:45 bdx-volumio volumio[1373]: info: ------------------------------ 89ms Dec 21 09:41:46 bdx-volumio volumio[1373]: info: CoreCommandRouter::volumioPushQueue Dec 21 09:41:46 bdx-volumio volumio[1373]: info: CorePlayQueue::saveQueue Dec 21 09:41:46 bdx-volumio volumio[1373]: info: CoreStateMachine::updateTrackBlock Dec 21 09:41:46 bdx-volumio volumio[1373]: info: CorePlayQueue::getTrackBlock Dec 21 09:41:46 bdx-volumio volumio[1373]: info: CoreCommandRouter::volumioPlay Dec 21 09:41:46 bdx-volumio volumio[1373]: info: CoreStateMachine::play index 0 Dec 21 09:41:46 bdx-volumio volumio[1373]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 21 09:41:46 bdx-volumio volumio[1373]: info: CoreStateMachine::stop Dec 21 09:41:46 bdx-volumio volumio[1373]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 21 09:41:46 bdx-volumio volumio[1373]: info: CoreStateMachine::play index undefined Dec 21 09:41:46 bdx-volumio volumio[1373]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 21 09:41:46 bdx-volumio volumio[1373]: info: CorePlayQueue::getTrack 0 Dec 21 09:41:46 bdx-volumio volumio[1373]: info: CoreStateMachine::startPlaybackTimer Dec 21 09:41:46 bdx-volumio volumio[1373]: info: CorePlayQueue::getTrack 0 Dec 21 09:41:46 bdx-volumio volumio[1373]: info: [1766306506113] ControllerWebradio::clearAddPlayTrack Dec 21 09:41:46 bdx-volumio volumio[1373]: verbose: ControllerMpd::sendMpdCommand stop Dec 21 09:41:46 bdx-volumio volumio[1373]: info: sendMpdCommand stop took 3 milliseconds Dec 21 09:41:46 bdx-volumio volumio[1373]: verbose: ControllerMpd::sendMpdCommand clear Dec 21 09:41:46 bdx-volumio volumio[1373]: info: Dec 21 09:41:46 bdx-volumio volumio[1373]: ---------------------------- MPD announces system playlist update Dec 21 09:41:46 bdx-volumio volumio[1373]: info: Ignoring MPD Status Update Dec 21 09:41:46 bdx-volumio volumio[1373]: info: sendMpdCommand clear took 1 milliseconds Dec 21 09:41:46 bdx-volumio volumio[1373]: verbose: ControllerMpd::sendMpdCommand load "http://streams.radiobob.de/live-nrw-mitte/mp3-192/tunein/" Dec 21 09:41:46 bdx-volumio volumio[1373]: info: Dec 21 09:41:46 bdx-volumio volumio[1373]: ---------------------------- MPD announces system playlist update Dec 21 09:41:46 bdx-volumio volumio[1373]: info: Ignoring MPD Status Update Dec 21 09:41:46 bdx-volumio volumio[1373]: info: Dec 21 09:41:46 bdx-volumio volumio[1373]: ---------------------------- MPD announces system playlist update Dec 21 09:41:46 bdx-volumio volumio[1373]: info: Ignoring MPD Status Update Dec 21 09:41:46 bdx-volumio volumio[1373]: info: Dec 21 09:41:46 bdx-volumio volumio[1373]: ---------------------------- MPD announces system playlist update Dec 21 09:41:46 bdx-volumio volumio[1373]: info: Ignoring MPD Status Update Dec 21 09:41:46 bdx-volumio volumio[1373]: error: updateQueue error: null Dec 21 09:41:46 bdx-volumio volumio[1373]: info: ------------------------------ 7ms Dec 21 09:41:48 bdx-volumio volumio[1373]: verbose: ControllerMpd::sendMpdCommand add "http://streams.radiobob.de/live-nrw-mitte/mp3-192/tunein/" Dec 21 09:41:48 bdx-volumio volumio[1373]: error: updateQueue error: null Dec 21 09:41:48 bdx-volumio volumio[1373]: error: updateQueue error: null Dec 21 09:41:48 bdx-volumio volumio[1373]: error: updateQueue error: null Dec 21 09:41:48 bdx-volumio volumio[1373]: info: Dec 21 09:41:48 bdx-volumio volumio[1373]: ---------------------------- MPD announces system playlist update Dec 21 09:41:48 bdx-volumio volumio[1373]: info: Ignoring MPD Status Update Dec 21 09:41:48 bdx-volumio volumio[1373]: info: ------------------------------ 2277ms Dec 21 09:41:48 bdx-volumio volumio[1373]: info: ------------------------------ 2277ms Dec 21 09:41:48 bdx-volumio volumio[1373]: info: ------------------------------ 2275ms Dec 21 09:41:48 bdx-volumio volumio[1373]: info: sendMpdCommand add "http://streams.radiobob.de/live-nrw-mitte/mp3-192/tunein/" took 4 milliseconds Dec 21 09:41:48 bdx-volumio volumio[1373]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 21 09:41:48 bdx-volumio volumio[1373]: verbose: ControllerMpd::sendMpdCommand play Dec 21 09:41:48 bdx-volumio volumio[1373]: info: Dec 21 09:41:48 bdx-volumio volumio[1373]: ---------------------------- MPD announces system playlist update Dec 21 09:41:48 bdx-volumio volumio[1373]: info: Ignoring MPD Status Update Dec 21 09:41:48 bdx-volumio volumio[1373]: info: Dec 21 09:41:48 bdx-volumio volumio[1373]: ---------------------------- MPD announces system playlist update Dec 21 09:41:48 bdx-volumio volumio[1373]: info: Ignoring MPD Status Update Dec 21 09:41:48 bdx-volumio volumio[1373]: info: Dec 21 09:41:48 bdx-volumio volumio[1373]: ---------------------------- MPD announces system playlist update Dec 21 09:41:48 bdx-volumio volumio[1373]: info: Ignoring MPD Status Update Dec 21 09:41:48 bdx-volumio volumio[1373]: info: ------------------------------ 8ms Dec 21 09:41:48 bdx-volumio volumio[1373]: info: sendMpdCommand play took 6 milliseconds Dec 21 09:41:48 bdx-volumio volumio[1373]: info: ------------------------------ 5ms Dec 21 09:41:48 bdx-volumio volumio[1373]: info: ------------------------------ 4ms Dec 21 09:41:48 bdx-volumio volumio[1373]: info: ------------------------------ 3ms Dec 21 09:41:49 bdx-volumio volumio[1373]: info: Dec 21 09:41:49 bdx-volumio volumio[1373]: ---------------------------- MPD announces state update: player Dec 21 09:41:49 bdx-volumio volumio[1373]: info: ControllerMpd::getState Dec 21 09:41:49 bdx-volumio volumio[1373]: verbose: ControllerMpd::sendMpdCommand status Dec 21 09:41:49 bdx-volumio volumio[1373]: info: Dec 21 09:41:49 bdx-volumio volumio[1373]: ---------------------------- MPD announces state update: player Dec 21 09:41:49 bdx-volumio volumio[1373]: info: ControllerMpd::getState Dec 21 09:41:49 bdx-volumio volumio[1373]: verbose: ControllerMpd::sendMpdCommand status Dec 21 09:41:49 bdx-volumio volumio[1373]: info: Dec 21 09:41:49 bdx-volumio volumio[1373]: ---------------------------- MPD announces state update: player Dec 21 09:41:49 bdx-volumio volumio[1373]: info: ControllerMpd::getState Dec 21 09:41:49 bdx-volumio volumio[1373]: verbose: ControllerMpd::sendMpdCommand status Dec 21 09:41:49 bdx-volumio volumio[1373]: info: Dec 21 09:41:49 bdx-volumio volumio[1373]: ---------------------------- MPD announces state update: player Dec 21 09:41:49 bdx-volumio volumio[1373]: info: ControllerMpd::getState Dec 21 09:41:49 bdx-volumio volumio[1373]: verbose: ControllerMpd::sendMpdCommand status Dec 21 09:41:49 bdx-volumio volumio[1373]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Dec 21 09:41:49 bdx-volumio volumio[1373]: info: FusionDsp - ---- read samplerate from file: 44100 Dec 21 09:41:49 bdx-volumio volumio[1373]: error: FusionDsp - TypeError: Cannot read property 'stop' of null Dec 21 09:41:49 bdx-volumio volumio[1373]: info: Dec 21 09:41:49 bdx-volumio volumio[1373]: ---------------------------- MPD announces state update: player Dec 21 09:41:49 bdx-volumio volumio[1373]: info: ControllerMpd::getState Dec 21 09:41:49 bdx-volumio volumio[1373]: verbose: ControllerMpd::sendMpdCommand status Dec 21 09:41:49 bdx-volumio volumio[1373]: info: Dec 21 09:41:49 bdx-volumio volumio[1373]: ---------------------------- MPD announces state update: player Dec 21 09:41:49 bdx-volumio volumio[1373]: info: ControllerMpd::getState Dec 21 09:41:49 bdx-volumio volumio[1373]: verbose: ControllerMpd::sendMpdCommand status Dec 21 09:41:49 bdx-volumio volumio[1373]: info: Dec 21 09:41:49 bdx-volumio volumio[1373]: ---------------------------- MPD announces state update: player Dec 21 09:41:49 bdx-volumio volumio[1373]: info: ControllerMpd::getState Dec 21 09:41:49 bdx-volumio volumio[1373]: verbose: ControllerMpd::sendMpdCommand status Dec 21 09:41:49 bdx-volumio volumio[1373]: info: Dec 21 09:41:49 bdx-volumio volumio[1373]: ---------------------------- MPD announces state update: player Dec 21 09:41:49 bdx-volumio volumio[1373]: info: sendMpdCommand status took 22 milliseconds Dec 21 09:41:49 bdx-volumio volumio[1373]: info: sendMpdCommand status took 20 milliseconds Dec 21 09:41:49 bdx-volumio volumio[1373]: info: sendMpdCommand status took 18 milliseconds Dec 21 09:41:49 bdx-volumio volumio[1373]: info: sendMpdCommand status took 16 milliseconds Dec 21 09:41:49 bdx-volumio volumio[1373]: info: ControllerMpd::getState Dec 21 09:41:49 bdx-volumio volumio[1373]: verbose: ControllerMpd::sendMpdCommand status Dec 21 09:41:49 bdx-volumio volumio[1373]: info: sendMpdCommand status took 12 milliseconds Dec 21 09:41:49 bdx-volumio volumio[1373]: info: sendMpdCommand status took 9 milliseconds Dec 21 09:41:49 bdx-volumio volumio[1373]: info: sendMpdCommand status took 7 milliseconds Dec 21 09:41:49 bdx-volumio volumio[1373]: verbose: ControllerMpd::parseState Dec 21 09:41:49 bdx-volumio volumio[1373]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 21 09:41:49 bdx-volumio volumio[1373]: verbose: ControllerMpd::parseState Dec 21 09:41:49 bdx-volumio volumio[1373]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 21 09:41:49 bdx-volumio volumio[1373]: verbose: ControllerMpd::parseState Dec 21 09:41:49 bdx-volumio volumio[1373]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 21 09:41:49 bdx-volumio volumio[1373]: verbose: ControllerMpd::parseState Dec 21 09:41:49 bdx-volumio volumio[1373]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 21 09:41:49 bdx-volumio volumio[1373]: verbose: ControllerMpd::parseState Dec 21 09:41:49 bdx-volumio volumio[1373]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 21 09:41:49 bdx-volumio volumio[1373]: verbose: ControllerMpd::parseState Dec 21 09:41:49 bdx-volumio volumio[1373]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 21 09:41:49 bdx-volumio volumio[1373]: verbose: ControllerMpd::parseState Dec 21 09:41:49 bdx-volumio volumio[1373]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 21 09:41:49 bdx-volumio volumio[1373]: info: sendMpdCommand status took 11 milliseconds Dec 21 09:41:49 bdx-volumio volumio[1373]: info: sendMpdCommand playlistinfo took 8 milliseconds Dec 21 09:41:49 bdx-volumio volumio[1373]: info: sendMpdCommand playlistinfo took 9 milliseconds Dec 21 09:41:49 bdx-volumio volumio[1373]: info: sendMpdCommand playlistinfo took 7 milliseconds Dec 21 09:41:49 bdx-volumio volumio[1373]: info: sendMpdCommand playlistinfo took 7 milliseconds Dec 21 09:41:49 bdx-volumio volumio[1373]: info: sendMpdCommand playlistinfo took 7 milliseconds Dec 21 09:41:49 bdx-volumio volumio[1373]: info: sendMpdCommand playlistinfo took 7 milliseconds Dec 21 09:41:49 bdx-volumio volumio[1373]: info: sendMpdCommand playlistinfo took 7 milliseconds Dec 21 09:41:49 bdx-volumio volumio[1373]: verbose: ControllerMpd::parseState Dec 21 09:41:49 bdx-volumio volumio[1373]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 21 09:41:49 bdx-volumio volumio[1373]: verbose: ControllerMpd::parseTrackInfo Dec 21 09:41:49 bdx-volumio volumio[1373]: verbose: ControllerMpd::parseTrackInfo Dec 21 09:41:49 bdx-volumio volumio[1373]: verbose: ControllerMpd::parseTrackInfo Dec 21 09:41:49 bdx-volumio volumio[1373]: verbose: ControllerMpd::parseTrackInfo Dec 21 09:41:49 bdx-volumio volumio[1373]: verbose: ControllerMpd::parseTrackInfo Dec 21 09:41:49 bdx-volumio volumio[1373]: verbose: ControllerMpd::parseTrackInfo Dec 21 09:41:49 bdx-volumio volumio[1373]: verbose: ControllerMpd::parseTrackInfo Dec 21 09:41:49 bdx-volumio volumio[1373]: info: ControllerMpd::pushState Dec 21 09:41:49 bdx-volumio volumio[1373]: info: CoreCommandRouter::servicePushState Dec 21 09:41:49 bdx-volumio volumio[1373]: info: CorePlayQueue::getTrack 0 Dec 21 09:41:49 bdx-volumio volumio[1373]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"","artist":null,"album":null,"uri":"http://streams.radiobob.de/live-nrw-mitte/mp3-192/tunein/","trackType":""} Dec 21 09:41:49 bdx-volumio volumio[1373]: verbose: CURRENT POSITION 0 Dec 21 09:41:49 bdx-volumio volumio[1373]: info: CoreStateMachine::syncState stateService play Dec 21 09:41:49 bdx-volumio volumio[1373]: info: CoreStateMachine::syncState currentStatus stop Dec 21 09:41:49 bdx-volumio volumio[1373]: info: ControllerMpd::pushState Dec 21 09:41:49 bdx-volumio volumio[1373]: info: CoreCommandRouter::servicePushState Dec 21 09:41:49 bdx-volumio volumio[1373]: info: CorePlayQueue::getTrack 0 Dec 21 09:41:49 bdx-volumio volumio[1373]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"","artist":null,"album":null,"uri":"http://streams.radiobob.de/live-nrw-mitte/mp3-192/tunein/","trackType":""} Dec 21 09:41:49 bdx-volumio volumio[1373]: verbose: CURRENT POSITION 0 Dec 21 09:41:49 bdx-volumio volumio[1373]: info: CoreStateMachine::syncState stateService play Dec 21 09:41:49 bdx-volumio volumio[1373]: info: CoreStateMachine::syncState currentStatus play Dec 21 09:41:49 bdx-volumio volumio[1373]: info: Received an update from plugin. extracting info from payload Dec 21 09:41:49 bdx-volumio volumio[1373]: info: CoreStateMachine::pushState Dec 21 09:41:49 bdx-volumio volumio[1373]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 21 09:41:49 bdx-volumio volumio[1373]: info: CoreCommandRouter::volumioPushState Dec 21 09:41:49 bdx-volumio volumio[1373]: info: CoreStateMachine::pushState Dec 21 09:41:49 bdx-volumio volumio[1373]: info: CoreCommandRouter::volumioPushState Dec 21 09:41:49 bdx-volumio volumio[1373]: info: ControllerMpd::pushState Dec 21 09:41:49 bdx-volumio volumio[1373]: info: CoreCommandRouter::servicePushState Dec 21 09:41:49 bdx-volumio volumio[1373]: info: CorePlayQueue::getTrack 0 Dec 21 09:41:49 bdx-volumio volumio[1373]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"","artist":null,"album":null,"uri":"http://streams.radiobob.de/live-nrw-mitte/mp3-192/tunein/","trackType":""} Dec 21 09:41:49 bdx-volumio volumio[1373]: verbose: CURRENT POSITION 0 Dec 21 09:41:49 bdx-volumio volumio[1373]: info: CoreStateMachine::syncState stateService play Dec 21 09:41:49 bdx-volumio volumio[1373]: info: CoreStateMachine::syncState currentStatus play Dec 21 09:41:49 bdx-volumio volumio[1373]: info: Received an update from plugin. extracting info from payload Dec 21 09:41:49 bdx-volumio volumio[1373]: info: CoreStateMachine::pushState Dec 21 09:41:49 bdx-volumio volumio[1373]: info: CoreCommandRouter::volumioPushState Dec 21 09:41:49 bdx-volumio volumio[1373]: info: CoreStateMachine::pushState Dec 21 09:41:49 bdx-volumio volumio[1373]: info: CoreCommandRouter::volumioPushState Dec 21 09:41:49 bdx-volumio volumio[1373]: info: ControllerMpd::pushState Dec 21 09:41:49 bdx-volumio volumio[1373]: info: CoreCommandRouter::servicePushState Dec 21 09:41:49 bdx-volumio volumio[1373]: info: CorePlayQueue::getTrack 0 Dec 21 09:41:49 bdx-volumio volumio[1373]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"","artist":null,"album":null,"uri":"http://streams.radiobob.de/live-nrw-mitte/mp3-192/tunein/","trackType":""} Dec 21 09:41:49 bdx-volumio volumio[1373]: verbose: CURRENT POSITION 0 Dec 21 09:41:49 bdx-volumio volumio[1373]: info: CoreStateMachine::syncState stateService play Dec 21 09:41:49 bdx-volumio volumio[1373]: info: CoreStateMachine::syncState currentStatus play Dec 21 09:41:49 bdx-volumio volumio[1373]: info: Received an update from plugin. extracting info from payload Dec 21 09:41:49 bdx-volumio volumio[1373]: info: CoreStateMachine::pushState Dec 21 09:41:49 bdx-volumio volumio[1373]: info: CoreCommandRouter::volumioPushState Dec 21 09:41:49 bdx-volumio volumio[1373]: info: CoreStateMachine::pushState Dec 21 09:41:49 bdx-volumio volumio[1373]: info: CoreCommandRouter::volumioPushState Dec 21 09:41:49 bdx-volumio volumio[1373]: info: ControllerMpd::pushState Dec 21 09:41:49 bdx-volumio volumio[1373]: info: CoreCommandRouter::servicePushState Dec 21 09:41:49 bdx-volumio volumio[1373]: info: CorePlayQueue::getTrack 0 Dec 21 09:41:49 bdx-volumio volumio[1373]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"","artist":null,"album":null,"uri":"http://streams.radiobob.de/live-nrw-mitte/mp3-192/tunein/","trackType":""} Dec 21 09:41:49 bdx-volumio volumio[1373]: verbose: CURRENT POSITION 0 Dec 21 09:41:49 bdx-volumio volumio[1373]: info: CoreStateMachine::syncState stateService play Dec 21 09:41:49 bdx-volumio volumio[1373]: info: CoreStateMachine::syncState currentStatus play Dec 21 09:41:49 bdx-volumio volumio[1373]: info: Received an update from plugin. extracting info from payload Dec 21 09:41:49 bdx-volumio volumio[1373]: info: CoreStateMachine::pushState Dec 21 09:41:49 bdx-volumio volumio[1373]: info: CoreCommandRouter::volumioPushState Dec 21 09:41:49 bdx-volumio volumio[1373]: info: CoreStateMachine::pushState Dec 21 09:41:49 bdx-volumio volumio[1373]: info: CoreCommandRouter::volumioPushState Dec 21 09:41:49 bdx-volumio volumio[1373]: info: ControllerMpd::pushState Dec 21 09:41:49 bdx-volumio volumio[1373]: info: CoreCommandRouter::servicePushState Dec 21 09:41:49 bdx-volumio volumio[1373]: info: CorePlayQueue::getTrack 0 Dec 21 09:41:49 bdx-volumio volumio[1373]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"","artist":null,"album":null,"uri":"http://streams.radiobob.de/live-nrw-mitte/mp3-192/tunein/","trackType":""} Dec 21 09:41:49 bdx-volumio volumio[1373]: verbose: CURRENT POSITION 0 Dec 21 09:41:49 bdx-volumio volumio[1373]: info: CoreStateMachine::syncState stateService play Dec 21 09:41:49 bdx-volumio volumio[1373]: info: CoreStateMachine::syncState currentStatus play Dec 21 09:41:49 bdx-volumio volumio[1373]: info: Received an update from plugin. extracting info from payload Dec 21 09:41:49 bdx-volumio volumio[1373]: info: CoreStateMachine::pushState Dec 21 09:41:49 bdx-volumio volumio[1373]: info: CoreCommandRouter::volumioPushState Dec 21 09:41:49 bdx-volumio volumio[1373]: info: CoreStateMachine::pushState Dec 21 09:41:49 bdx-volumio volumio[1373]: info: CoreCommandRouter::volumioPushState Dec 21 09:41:49 bdx-volumio volumio[1373]: info: ControllerMpd::pushState Dec 21 09:41:49 bdx-volumio volumio[1373]: info: CoreCommandRouter::servicePushState Dec 21 09:41:49 bdx-volumio volumio[1373]: info: CorePlayQueue::getTrack 0 Dec 21 09:41:49 bdx-volumio volumio[1373]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"","artist":null,"album":null,"uri":"http://streams.radiobob.de/live-nrw-mitte/mp3-192/tunein/","trackType":""} Dec 21 09:41:49 bdx-volumio volumio[1373]: verbose: CURRENT POSITION 0 Dec 21 09:41:49 bdx-volumio volumio[1373]: info: CoreStateMachine::syncState stateService play Dec 21 09:41:49 bdx-volumio volumio[1373]: info: CoreStateMachine::syncState currentStatus play Dec 21 09:41:49 bdx-volumio volumio[1373]: info: Received an update from plugin. extracting info from payload Dec 21 09:41:49 bdx-volumio volumio[1373]: info: CoreStateMachine::pushState Dec 21 09:41:49 bdx-volumio volumio[1373]: info: CoreCommandRouter::volumioPushState Dec 21 09:41:49 bdx-volumio volumio[1373]: info: CoreStateMachine::pushState Dec 21 09:41:49 bdx-volumio volumio[1373]: info: CoreCommandRouter::volumioPushState Dec 21 09:41:49 bdx-volumio volumio[1373]: info: ------------------------------ 104ms Dec 21 09:41:49 bdx-volumio volumio[1373]: info: ------------------------------ 111ms Dec 21 09:41:49 bdx-volumio volumio[1373]: info: ------------------------------ 109ms Dec 21 09:41:49 bdx-volumio volumio[1373]: info: ------------------------------ 107ms Dec 21 09:41:49 bdx-volumio volumio[1373]: info: ------------------------------ 103ms Dec 21 09:41:49 bdx-volumio volumio[1373]: info: ------------------------------ 102ms Dec 21 09:41:49 bdx-volumio volumio[1373]: info: ------------------------------ 100ms Dec 21 09:41:49 bdx-volumio volumio[1373]: info: Dec 21 09:41:49 bdx-volumio volumio[1373]: ---------------------------- MPD announces system playlist update Dec 21 09:41:49 bdx-volumio volumio[1373]: info: Ignoring MPD Status Update Dec 21 09:41:49 bdx-volumio volumio[1373]: info: Dec 21 09:41:49 bdx-volumio volumio[1373]: ---------------------------- MPD announces state update: player Dec 21 09:41:49 bdx-volumio volumio[1373]: info: ControllerMpd::getState Dec 21 09:41:49 bdx-volumio volumio[1373]: verbose: ControllerMpd::sendMpdCommand status Dec 21 09:41:49 bdx-volumio volumio[1373]: info: sendMpdCommand playlistinfo took 81 milliseconds Dec 21 09:41:49 bdx-volumio volumio[1373]: verbose: ControllerMpd::parseTrackInfo Dec 21 09:41:49 bdx-volumio volumio[1373]: info: ControllerMpd::pushState Dec 21 09:41:49 bdx-volumio volumio[1373]: info: CoreCommandRouter::servicePushState Dec 21 09:41:49 bdx-volumio volumio[1373]: info: CorePlayQueue::getTrack 0 Dec 21 09:41:49 bdx-volumio volumio[1373]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"RADIO BOB - Livestream NRW","artist":"RADIO BOB - Livestream NRW","album":null,"uri":"http://streams.radiobob.de/live-nrw-mitte/mp3-192/tunein/","trackType":""} Dec 21 09:41:49 bdx-volumio volumio[1373]: verbose: CURRENT POSITION 0 Dec 21 09:41:49 bdx-volumio volumio[1373]: info: CoreStateMachine::syncState stateService play Dec 21 09:41:49 bdx-volumio volumio[1373]: info: CoreStateMachine::syncState currentStatus play Dec 21 09:41:49 bdx-volumio volumio[1373]: info: Received an update from plugin. extracting info from payload Dec 21 09:41:49 bdx-volumio volumio[1373]: info: CoreStateMachine::pushState Dec 21 09:41:49 bdx-volumio volumio[1373]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 21 09:41:49 bdx-volumio volumio[1373]: info: CoreCommandRouter::volumioPushState Dec 21 09:41:49 bdx-volumio volumio[1373]: info: CoreStateMachine::pushState Dec 21 09:41:49 bdx-volumio volumio[1373]: info: CoreCommandRouter::volumioPushState Dec 21 09:41:49 bdx-volumio volumio[1373]: info: ------------------------------ 113ms Dec 21 09:41:49 bdx-volumio volumio[1373]: info: Dec 21 09:41:49 bdx-volumio volumio[1373]: ---------------------------- MPD announces system playlist update Dec 21 09:41:49 bdx-volumio volumio[1373]: info: Ignoring MPD Status Update Dec 21 09:41:49 bdx-volumio volumio[1373]: info: Dec 21 09:41:49 bdx-volumio volumio[1373]: ---------------------------- MPD announces state update: player Dec 21 09:41:49 bdx-volumio volumio[1373]: info: ControllerMpd::getState Dec 21 09:41:49 bdx-volumio volumio[1373]: verbose: ControllerMpd::sendMpdCommand status Dec 21 09:41:49 bdx-volumio volumio[1373]: info: Dec 21 09:41:49 bdx-volumio volumio[1373]: ---------------------------- MPD announces system playlist update Dec 21 09:41:49 bdx-volumio volumio[1373]: info: Ignoring MPD Status Update Dec 21 09:41:49 bdx-volumio volumio[1373]: info: Dec 21 09:41:49 bdx-volumio volumio[1373]: ---------------------------- MPD announces state update: player Dec 21 09:41:49 bdx-volumio volumio[1373]: info: ControllerMpd::getState Dec 21 09:41:49 bdx-volumio volumio[1373]: verbose: ControllerMpd::sendMpdCommand status Dec 21 09:41:49 bdx-volumio volumio[1373]: info: Dec 21 09:41:49 bdx-volumio volumio[1373]: ---------------------------- MPD announces system playlist update Dec 21 09:41:49 bdx-volumio volumio[1373]: info: Ignoring MPD Status Update Dec 21 09:41:49 bdx-volumio volumio[1373]: info: Dec 21 09:41:49 bdx-volumio volumio[1373]: ---------------------------- MPD announces state update: player Dec 21 09:41:49 bdx-volumio volumio[1373]: info: ControllerMpd::getState Dec 21 09:41:49 bdx-volumio volumio[1373]: verbose: ControllerMpd::sendMpdCommand status Dec 21 09:41:49 bdx-volumio volumio[1373]: info: ------------------------------ 45ms Dec 21 09:41:49 bdx-volumio volumio[1373]: info: sendMpdCommand status took 44 milliseconds Dec 21 09:41:49 bdx-volumio volumio[1373]: info: ------------------------------ 31ms Dec 21 09:41:49 bdx-volumio volumio[1373]: info: sendMpdCommand status took 30 milliseconds Dec 21 09:41:49 bdx-volumio volumio[1373]: info: ------------------------------ 29ms Dec 21 09:41:49 bdx-volumio volumio[1373]: info: sendMpdCommand status took 23 milliseconds Dec 21 09:41:49 bdx-volumio volumio[1373]: info: ------------------------------ 22ms Dec 21 09:41:49 bdx-volumio volumio[1373]: info: sendMpdCommand status took 21 milliseconds Dec 21 09:41:49 bdx-volumio volumio[1373]: verbose: ControllerMpd::parseState Dec 21 09:41:49 bdx-volumio volumio[1373]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 21 09:41:49 bdx-volumio volumio[1373]: verbose: ControllerMpd::parseState Dec 21 09:41:49 bdx-volumio volumio[1373]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 21 09:41:49 bdx-volumio volumio[1373]: verbose: ControllerMpd::parseState Dec 21 09:41:49 bdx-volumio volumio[1373]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 21 09:41:49 bdx-volumio volumio[1373]: verbose: ControllerMpd::parseState Dec 21 09:41:49 bdx-volumio volumio[1373]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 21 09:41:49 bdx-volumio volumio[1373]: info: sendMpdCommand playlistinfo took 3 milliseconds Dec 21 09:41:49 bdx-volumio volumio[1373]: info: sendMpdCommand playlistinfo took 3 milliseconds Dec 21 09:41:49 bdx-volumio volumio[1373]: info: sendMpdCommand playlistinfo took 3 milliseconds Dec 21 09:41:49 bdx-volumio volumio[1373]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 21 09:41:49 bdx-volumio volumio[1373]: verbose: ControllerMpd::parseTrackInfo Dec 21 09:41:49 bdx-volumio volumio[1373]: verbose: ControllerMpd::parseTrackInfo Dec 21 09:41:49 bdx-volumio volumio[1373]: verbose: ControllerMpd::parseTrackInfo Dec 21 09:41:49 bdx-volumio volumio[1373]: verbose: ControllerMpd::parseTrackInfo Dec 21 09:41:49 bdx-volumio volumio[1373]: info: ControllerMpd::pushState Dec 21 09:41:49 bdx-volumio volumio[1373]: info: CoreCommandRouter::servicePushState Dec 21 09:41:49 bdx-volumio volumio[1373]: info: CorePlayQueue::getTrack 0 Dec 21 09:41:49 bdx-volumio volumio[1373]: verbose: STATE SERVICE {"status":"play","position":0,"seek":986,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"RADIO BOB - Livestream NRW","artist":"RADIO BOB - Livestream NRW","album":null,"uri":"http://streams.radiobob.de/live-nrw-mitte/mp3-192/tunein/","trackType":""} Dec 21 09:41:49 bdx-volumio volumio[1373]: verbose: CURRENT POSITION 0 Dec 21 09:41:49 bdx-volumio volumio[1373]: info: CoreStateMachine::syncState stateService play Dec 21 09:41:49 bdx-volumio volumio[1373]: info: CoreStateMachine::syncState currentStatus play Dec 21 09:41:49 bdx-volumio volumio[1373]: info: Received an update from plugin. extracting info from payload Dec 21 09:41:49 bdx-volumio volumio[1373]: info: CoreStateMachine::pushState Dec 21 09:41:49 bdx-volumio volumio[1373]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 21 09:41:49 bdx-volumio volumio[1373]: info: CoreCommandRouter::volumioPushState Dec 21 09:41:49 bdx-volumio volumio[1373]: info: CoreStateMachine::pushState Dec 21 09:41:49 bdx-volumio volumio[1373]: info: CoreCommandRouter::volumioPushState Dec 21 09:41:49 bdx-volumio volumio[1373]: info: ControllerMpd::pushState Dec 21 09:41:49 bdx-volumio volumio[1373]: info: CoreCommandRouter::servicePushState Dec 21 09:41:49 bdx-volumio volumio[1373]: info: CorePlayQueue::getTrack 0 Dec 21 09:41:49 bdx-volumio volumio[1373]: verbose: STATE SERVICE {"status":"play","position":0,"seek":986,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"RADIO BOB - Livestream NRW","artist":"RADIO BOB - Livestream NRW","album":null,"uri":"http://streams.radiobob.de/live-nrw-mitte/mp3-192/tunein/","trackType":""} Dec 21 09:41:49 bdx-volumio volumio[1373]: verbose: CURRENT POSITION 0 Dec 21 09:41:49 bdx-volumio volumio[1373]: info: CoreStateMachine::syncState stateService play Dec 21 09:41:49 bdx-volumio volumio[1373]: info: CoreStateMachine::syncState currentStatus play Dec 21 09:41:49 bdx-volumio volumio[1373]: info: Received an update from plugin. extracting info from payload Dec 21 09:41:49 bdx-volumio volumio[1373]: info: CoreStateMachine::pushState Dec 21 09:41:49 bdx-volumio volumio[1373]: info: CoreCommandRouter::volumioPushState Dec 21 09:41:49 bdx-volumio volumio[1373]: info: CoreStateMachine::pushState Dec 21 09:41:49 bdx-volumio volumio[1373]: info: CoreCommandRouter::volumioPushState Dec 21 09:41:49 bdx-volumio volumio[1373]: info: ControllerMpd::pushState Dec 21 09:41:49 bdx-volumio volumio[1373]: info: CoreCommandRouter::servicePushState Dec 21 09:41:49 bdx-volumio volumio[1373]: info: CorePlayQueue::getTrack 0 Dec 21 09:41:49 bdx-volumio volumio[1373]: verbose: STATE SERVICE {"status":"play","position":0,"seek":986,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"RADIO BOB - Livestream NRW","artist":"RADIO BOB - Livestream NRW","album":null,"uri":"http://streams.radiobob.de/live-nrw-mitte/mp3-192/tunein/","trackType":""} Dec 21 09:41:49 bdx-volumio volumio[1373]: verbose: CURRENT POSITION 0 Dec 21 09:41:49 bdx-volumio volumio[1373]: info: CoreStateMachine::syncState stateService play Dec 21 09:41:49 bdx-volumio volumio[1373]: info: CoreStateMachine::syncState currentStatus play Dec 21 09:41:49 bdx-volumio volumio[1373]: info: Received an update from plugin. extracting info from payload Dec 21 09:41:49 bdx-volumio volumio[1373]: info: CoreStateMachine::pushState Dec 21 09:41:49 bdx-volumio volumio[1373]: info: CoreCommandRouter::volumioPushState Dec 21 09:41:49 bdx-volumio volumio[1373]: info: CoreStateMachine::pushState Dec 21 09:41:49 bdx-volumio volumio[1373]: info: CoreCommandRouter::volumioPushState Dec 21 09:41:49 bdx-volumio volumio[1373]: info: ControllerMpd::pushState Dec 21 09:41:49 bdx-volumio volumio[1373]: info: CoreCommandRouter::servicePushState Dec 21 09:41:49 bdx-volumio volumio[1373]: info: CorePlayQueue::getTrack 0 Dec 21 09:41:49 bdx-volumio volumio[1373]: verbose: STATE SERVICE {"status":"play","position":0,"seek":986,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"RADIO BOB - Livestream NRW","artist":"RADIO BOB - Livestream NRW","album":null,"uri":"http://streams.radiobob.de/live-nrw-mitte/mp3-192/tunein/","trackType":""} Dec 21 09:41:49 bdx-volumio volumio[1373]: verbose: CURRENT POSITION 0 Dec 21 09:41:49 bdx-volumio volumio[1373]: info: CoreStateMachine::syncState stateService play Dec 21 09:41:49 bdx-volumio volumio[1373]: info: CoreStateMachine::syncState currentStatus play Dec 21 09:41:49 bdx-volumio volumio[1373]: info: Received an update from plugin. extracting info from payload Dec 21 09:41:49 bdx-volumio volumio[1373]: info: CoreStateMachine::pushState Dec 21 09:41:49 bdx-volumio volumio[1373]: info: CoreCommandRouter::volumioPushState Dec 21 09:41:49 bdx-volumio volumio[1373]: info: CoreStateMachine::pushState Dec 21 09:41:49 bdx-volumio volumio[1373]: info: CoreCommandRouter::volumioPushState Dec 21 09:41:49 bdx-volumio volumio[1373]: info: ------------------------------ 87ms Dec 21 09:41:49 bdx-volumio volumio[1373]: info: ------------------------------ 73ms Dec 21 09:41:49 bdx-volumio volumio[1373]: info: ------------------------------ 71ms Dec 21 09:41:49 bdx-volumio volumio[1373]: info: ------------------------------ 65ms Dec 21 09:41:55 bdx-volumio volumio[1373]: info: VolumeController::SetAlsaVolume67 Dec 21 09:41:55 bdx-volumio volumio[1373]: info: CoreCommandRouter::volumioGetState Dec 21 09:41:55 bdx-volumio volumio[1373]: info: CoreStateMachine::pushState Dec 21 09:41:55 bdx-volumio volumio[1373]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 21 09:41:55 bdx-volumio volumio[1373]: info: CoreCommandRouter::volumioPushState Dec 21 09:42:01 bdx-volumio volumio[1373]: info: CALLMETHOD: audio_interface fusiondsp usethispreset [object Object] Dec 21 09:42:01 bdx-volumio volumio[1373]: info: CoreCommandRouter::executeOnPlugin: fusiondsp , usethispreset Dec 21 09:42:01 bdx-volumio volumio[1373]: info: FusionDsp - Value for usedpreset: Dec 21 09:42:01 bdx-volumio volumio[1373]: error: FusionDsp - Value reading JSON file: {"geq15":"-0.5,1,1.5,1,0.5,1.5,1,0.5,-0.5,-1,-1,1,2,2,1.5","x2geq15":"-0.5,1,1.5,1,0.5,1.5,1,0.5,-0.5,-1,-1,1,2,2,1.5","state4preset":["None",false,false,50,0,0,0,"None",true,false,false,0,0,false]} Dec 21 09:42:01 bdx-volumio volumio[1373]: info: FusionDsp - geq15 -0.5,1,1.5,1,0.5,1.5,1,0.5,-0.5,-1,-1,1,2,2,1.5 Dec 21 09:42:01 bdx-volumio volumio[1373]: info: FusionDsp - value state4preset None,false,false,50,0,0,0,None,true,false,false,0,0,false Dec 21 09:42:01 bdx-volumio volumio[1373]: info: FusionDsp - If filter freq >samplerate/2 then disable it Dec 21 09:42:01 bdx-volumio volumio[1373]: error: FusionDsp - WebSocket error: [object Object] Dec 21 09:42:02 bdx-volumio volumio[1373]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 21 09:42:02 bdx-volumio volumio[1373]: info: CoreCommandRouter::Close All Modals sent Dec 21 09:42:02 bdx-volumio volumio[1373]: info: FusionDsp - Rock-FusionDsp Dec 21 09:42:02 bdx-volumio volumio[1373]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json,ROCK.json Dec 21 09:42:08 bdx-volumio volumio[1373]: info: CALLMETHOD: audio_interface fusiondsp disableeffect undefined Dec 21 09:42:08 bdx-volumio volumio[1373]: info: CoreCommandRouter::executeOnPlugin: fusiondsp , disableeffect Dec 21 09:42:08 bdx-volumio volumio[1373]: info: FusionDsp - If filter freq >samplerate/2 then disable it Dec 21 09:42:08 bdx-volumio volumio[1373]: info: FusionDsp - Effects disabled Dec 21 09:42:08 bdx-volumio volumio[1373]: error: FusionDsp - WebSocket error: [object Object] Dec 21 09:42:08 bdx-volumio volumio[1373]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 21 09:42:08 bdx-volumio volumio[1373]: info: CoreCommandRouter::Close All Modals sent Dec 21 09:42:08 bdx-volumio volumio[1373]: info: FusionDsp - Rock-FusionDsp Dec 21 09:42:08 bdx-volumio volumio[1373]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json,ROCK.json Dec 21 09:42:12 bdx-volumio volumio[1373]: info: CoreCommandRouter::volumioStop Dec 21 09:42:12 bdx-volumio volumio[1373]: info: CoreStateMachine::stop Dec 21 09:42:12 bdx-volumio volumio[1373]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 21 09:42:12 bdx-volumio volumio[1373]: info: CoreStateMachine::stPlaybackTimer Dec 21 09:42:12 bdx-volumio volumio[1373]: info: CoreStateMachine::updateTrackBlock Dec 21 09:42:12 bdx-volumio volumio[1373]: info: CorePlayQueue::getTrackBlock Dec 21 09:42:12 bdx-volumio volumio[1373]: info: CoreStateMachine::pushState Dec 21 09:42:12 bdx-volumio volumio[1373]: info: CorePlayQueue::getTrack 0 Dec 21 09:42:12 bdx-volumio volumio[1373]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 21 09:42:12 bdx-volumio volumio[1373]: info: CoreCommandRouter::volumioPushState Dec 21 09:42:12 bdx-volumio volumio[1373]: info: CoreStateMachine::serviceStop Dec 21 09:42:12 bdx-volumio volumio[1373]: info: CorePlayQueue::getTrack 0 Dec 21 09:42:12 bdx-volumio volumio[1373]: info: CoreCommandRouter::serviceStop Dec 21 09:42:12 bdx-volumio volumio[1373]: info: [1766306532214] ControllerWebradio::stop Dec 21 09:42:12 bdx-volumio volumio[1373]: verbose: ControllerMpd::sendMpdCommand stop Dec 21 09:42:12 bdx-volumio volumio[1373]: info: Dec 21 09:42:12 bdx-volumio volumio[1373]: ---------------------------- MPD announces state update: player Dec 21 09:42:12 bdx-volumio volumio[1373]: info: sendMpdCommand stop took 8 milliseconds Dec 21 09:42:12 bdx-volumio volumio[1373]: info: ControllerMpd::getState Dec 21 09:42:12 bdx-volumio volumio[1373]: verbose: ControllerMpd::sendMpdCommand status Dec 21 09:42:12 bdx-volumio volumio[1373]: info: sendMpdCommand status took 1 milliseconds Dec 21 09:42:12 bdx-volumio volumio[1373]: verbose: ControllerMpd::parseState Dec 21 09:42:12 bdx-volumio volumio[1373]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 21 09:42:12 bdx-volumio volumio[1373]: info: Dec 21 09:42:12 bdx-volumio volumio[1373]: ---------------------------- MPD announces state update: player Dec 21 09:42:12 bdx-volumio volumio[1373]: info: ControllerMpd::getState Dec 21 09:42:12 bdx-volumio volumio[1373]: verbose: ControllerMpd::sendMpdCommand status Dec 21 09:42:12 bdx-volumio volumio[1373]: info: Dec 21 09:42:12 bdx-volumio volumio[1373]: ---------------------------- MPD announces state update: player Dec 21 09:42:12 bdx-volumio volumio[1373]: info: ControllerMpd::getState Dec 21 09:42:12 bdx-volumio volumio[1373]: verbose: ControllerMpd::sendMpdCommand status Dec 21 09:42:12 bdx-volumio volumio[1373]: info: Dec 21 09:42:12 bdx-volumio volumio[1373]: ---------------------------- MPD announces state update: player Dec 21 09:42:12 bdx-volumio volumio[1373]: info: ControllerMpd::getState Dec 21 09:42:12 bdx-volumio volumio[1373]: verbose: ControllerMpd::sendMpdCommand status Dec 21 09:42:12 bdx-volumio volumio[1373]: info: sendMpdCommand playlistinfo took 7 milliseconds Dec 21 09:42:12 bdx-volumio volumio[1373]: info: sendMpdCommand status took 5 milliseconds Dec 21 09:42:12 bdx-volumio volumio[1373]: info: sendMpdCommand status took 4 milliseconds Dec 21 09:42:12 bdx-volumio volumio[1373]: info: sendMpdCommand status took 3 milliseconds Dec 21 09:42:12 bdx-volumio volumio[1373]: verbose: ControllerMpd::parseTrackInfo Dec 21 09:42:12 bdx-volumio volumio[1373]: verbose: ControllerMpd::parseState Dec 21 09:42:12 bdx-volumio volumio[1373]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 21 09:42:12 bdx-volumio volumio[1373]: verbose: ControllerMpd::parseState Dec 21 09:42:12 bdx-volumio volumio[1373]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 21 09:42:12 bdx-volumio volumio[1373]: verbose: ControllerMpd::parseState Dec 21 09:42:12 bdx-volumio volumio[1373]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 21 09:42:12 bdx-volumio volumio[1373]: info: ControllerMpd::pushState Dec 21 09:42:12 bdx-volumio volumio[1373]: info: CoreCommandRouter::servicePushState Dec 21 09:42:12 bdx-volumio volumio[1373]: info: CoreStateMachine::pushState Dec 21 09:42:12 bdx-volumio volumio[1373]: info: CorePlayQueue::getTrack 0 Dec 21 09:42:12 bdx-volumio volumio[1373]: info: CoreCommandRouter::volumioPushState Dec 21 09:42:12 bdx-volumio volumio[1373]: info: CorePlayQueue::getTrack 0 Dec 21 09:42:12 bdx-volumio volumio[1373]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Dec 21 09:42:12 bdx-volumio volumio[1373]: info: ------------------------------ 21ms Dec 21 09:42:12 bdx-volumio volumio[1373]: info: sendMpdCommand playlistinfo took 10 milliseconds Dec 21 09:42:12 bdx-volumio volumio[1373]: info: sendMpdCommand playlistinfo took 9 milliseconds Dec 21 09:42:12 bdx-volumio volumio[1373]: info: sendMpdCommand playlistinfo took 10 milliseconds Dec 21 09:42:12 bdx-volumio volumio[1373]: verbose: ControllerMpd::parseTrackInfo Dec 21 09:42:12 bdx-volumio volumio[1373]: verbose: ControllerMpd::parseTrackInfo Dec 21 09:42:12 bdx-volumio volumio[1373]: verbose: ControllerMpd::parseTrackInfo Dec 21 09:42:12 bdx-volumio volumio[1373]: info: ControllerMpd::pushState Dec 21 09:42:12 bdx-volumio volumio[1373]: info: CoreCommandRouter::servicePushState Dec 21 09:42:12 bdx-volumio volumio[1373]: info: CoreStateMachine::pushState Dec 21 09:42:12 bdx-volumio volumio[1373]: info: CorePlayQueue::getTrack 0 Dec 21 09:42:12 bdx-volumio volumio[1373]: info: CoreCommandRouter::volumioPushState Dec 21 09:42:12 bdx-volumio volumio[1373]: info: CorePlayQueue::getTrack 0 Dec 21 09:42:12 bdx-volumio volumio[1373]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Dec 21 09:42:12 bdx-volumio volumio[1373]: info: ControllerMpd::pushState Dec 21 09:42:12 bdx-volumio volumio[1373]: info: CoreCommandRouter::servicePushState Dec 21 09:42:12 bdx-volumio volumio[1373]: info: CoreStateMachine::pushState Dec 21 09:42:12 bdx-volumio volumio[1373]: info: CorePlayQueue::getTrack 0 Dec 21 09:42:12 bdx-volumio volumio[1373]: info: CoreCommandRouter::volumioPushState Dec 21 09:42:12 bdx-volumio volumio[1373]: info: CorePlayQueue::getTrack 0 Dec 21 09:42:12 bdx-volumio volumio[1373]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Dec 21 09:42:12 bdx-volumio volumio[1373]: info: ControllerMpd::pushState Dec 21 09:42:12 bdx-volumio volumio[1373]: info: CoreCommandRouter::servicePushState Dec 21 09:42:12 bdx-volumio volumio[1373]: info: CoreStateMachine::pushState Dec 21 09:42:12 bdx-volumio volumio[1373]: info: CorePlayQueue::getTrack 0 Dec 21 09:42:12 bdx-volumio volumio[1373]: info: CoreCommandRouter::volumioPushState Dec 21 09:42:12 bdx-volumio volumio[1373]: info: CorePlayQueue::getTrack 0 Dec 21 09:42:12 bdx-volumio volumio[1373]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Dec 21 09:42:12 bdx-volumio volumio[1373]: info: ------------------------------ 51ms Dec 21 09:42:12 bdx-volumio volumio[1373]: info: ------------------------------ 50ms Dec 21 09:42:12 bdx-volumio volumio[1373]: info: ------------------------------ 54ms Dec 21 09:42:13 bdx-volumio volumio[1373]: info: CoreCommandRouter::volumioPlay Dec 21 09:42:13 bdx-volumio volumio[1373]: info: CoreStateMachine::play index undefined Dec 21 09:42:13 bdx-volumio volumio[1373]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 21 09:42:13 bdx-volumio volumio[1373]: info: CorePlayQueue::getTrack 0 Dec 21 09:42:13 bdx-volumio volumio[1373]: info: CoreStateMachine::startPlaybackTimer Dec 21 09:42:13 bdx-volumio volumio[1373]: info: CorePlayQueue::getTrack 0 Dec 21 09:42:13 bdx-volumio volumio[1373]: info: [1766306533084] ControllerWebradio::clearAddPlayTrack Dec 21 09:42:13 bdx-volumio volumio[1373]: verbose: ControllerMpd::sendMpdCommand stop Dec 21 09:42:13 bdx-volumio volumio[1373]: info: sendMpdCommand stop took 1 milliseconds Dec 21 09:42:13 bdx-volumio volumio[1373]: verbose: ControllerMpd::sendMpdCommand clear Dec 21 09:42:13 bdx-volumio volumio[1373]: info: Dec 21 09:42:13 bdx-volumio volumio[1373]: ---------------------------- MPD announces system playlist update Dec 21 09:42:13 bdx-volumio volumio[1373]: info: Ignoring MPD Status Update Dec 21 09:42:13 bdx-volumio volumio[1373]: info: sendMpdCommand clear took 2 milliseconds Dec 21 09:42:13 bdx-volumio volumio[1373]: verbose: ControllerMpd::sendMpdCommand load "http://streams.radiobob.de/live-nrw-mitte/mp3-192/tunein/" Dec 21 09:42:13 bdx-volumio volumio[1373]: info: Dec 21 09:42:13 bdx-volumio volumio[1373]: ---------------------------- MPD announces system playlist update Dec 21 09:42:13 bdx-volumio volumio[1373]: info: Ignoring MPD Status Update Dec 21 09:42:13 bdx-volumio volumio[1373]: info: Dec 21 09:42:13 bdx-volumio volumio[1373]: ---------------------------- MPD announces system playlist update Dec 21 09:42:13 bdx-volumio volumio[1373]: info: Ignoring MPD Status Update Dec 21 09:42:13 bdx-volumio volumio[1373]: info: Dec 21 09:42:13 bdx-volumio volumio[1373]: ---------------------------- MPD announces system playlist update Dec 21 09:42:13 bdx-volumio volumio[1373]: info: Ignoring MPD Status Update Dec 21 09:42:14 bdx-volumio volumio[1373]: error: updateQueue error: null Dec 21 09:42:14 bdx-volumio volumio[1373]: error: updateQueue error: null Dec 21 09:42:14 bdx-volumio volumio[1373]: info: ------------------------------ 941ms Dec 21 09:42:14 bdx-volumio volumio[1373]: verbose: ControllerMpd::sendMpdCommand add "http://streams.radiobob.de/live-nrw-mitte/mp3-192/tunein/" Dec 21 09:42:14 bdx-volumio volumio[1373]: info: ------------------------------ 939ms Dec 21 09:42:14 bdx-volumio volumio[1373]: error: updateQueue error: null Dec 21 09:42:14 bdx-volumio volumio[1373]: error: updateQueue error: null Dec 21 09:42:14 bdx-volumio volumio[1373]: info: Dec 21 09:42:14 bdx-volumio volumio[1373]: ---------------------------- MPD announces system playlist update Dec 21 09:42:14 bdx-volumio volumio[1373]: info: Ignoring MPD Status Update Dec 21 09:42:14 bdx-volumio volumio[1373]: info: ------------------------------ 940ms Dec 21 09:42:14 bdx-volumio volumio[1373]: info: ------------------------------ 939ms Dec 21 09:42:14 bdx-volumio volumio[1373]: info: sendMpdCommand add "http://streams.radiobob.de/live-nrw-mitte/mp3-192/tunein/" took 3 milliseconds Dec 21 09:42:14 bdx-volumio volumio[1373]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 21 09:42:14 bdx-volumio volumio[1373]: verbose: ControllerMpd::sendMpdCommand play Dec 21 09:42:14 bdx-volumio volumio[1373]: info: Dec 21 09:42:14 bdx-volumio volumio[1373]: ---------------------------- MPD announces system playlist update Dec 21 09:42:14 bdx-volumio volumio[1373]: info: Ignoring MPD Status Update Dec 21 09:42:14 bdx-volumio volumio[1373]: info: Dec 21 09:42:14 bdx-volumio volumio[1373]: ---------------------------- MPD announces system playlist update Dec 21 09:42:14 bdx-volumio volumio[1373]: info: Ignoring MPD Status Update Dec 21 09:42:14 bdx-volumio volumio[1373]: info: Dec 21 09:42:14 bdx-volumio volumio[1373]: ---------------------------- MPD announces system playlist update Dec 21 09:42:14 bdx-volumio volumio[1373]: info: Ignoring MPD Status Update Dec 21 09:42:14 bdx-volumio volumio[1373]: info: ------------------------------ 8ms Dec 21 09:42:14 bdx-volumio volumio[1373]: info: sendMpdCommand play took 6 milliseconds Dec 21 09:42:14 bdx-volumio volumio[1373]: info: ------------------------------ 5ms Dec 21 09:42:14 bdx-volumio volumio[1373]: info: ------------------------------ 4ms Dec 21 09:42:14 bdx-volumio volumio[1373]: info: ------------------------------ 3ms Dec 21 09:42:15 bdx-volumio volumio[1373]: info: Dec 21 09:42:15 bdx-volumio volumio[1373]: ---------------------------- MPD announces state update: player Dec 21 09:42:15 bdx-volumio volumio[1373]: info: ControllerMpd::getState Dec 21 09:42:15 bdx-volumio volumio[1373]: verbose: ControllerMpd::sendMpdCommand status Dec 21 09:42:15 bdx-volumio volumio[1373]: info: Dec 21 09:42:15 bdx-volumio volumio[1373]: ---------------------------- MPD announces state update: player Dec 21 09:42:15 bdx-volumio volumio[1373]: info: ControllerMpd::getState Dec 21 09:42:15 bdx-volumio volumio[1373]: verbose: ControllerMpd::sendMpdCommand status Dec 21 09:42:15 bdx-volumio volumio[1373]: info: Dec 21 09:42:15 bdx-volumio volumio[1373]: ---------------------------- MPD announces state update: player Dec 21 09:42:15 bdx-volumio volumio[1373]: info: ControllerMpd::getState Dec 21 09:42:15 bdx-volumio volumio[1373]: verbose: ControllerMpd::sendMpdCommand status Dec 21 09:42:15 bdx-volumio volumio[1373]: info: Dec 21 09:42:15 bdx-volumio volumio[1373]: ---------------------------- MPD announces state update: player Dec 21 09:42:15 bdx-volumio volumio[1373]: info: ControllerMpd::getState Dec 21 09:42:15 bdx-volumio volumio[1373]: verbose: ControllerMpd::sendMpdCommand status Dec 21 09:42:15 bdx-volumio volumio[1373]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Dec 21 09:42:15 bdx-volumio volumio[1373]: info: FusionDsp - ---- read samplerate from file: 44100 Dec 21 09:42:15 bdx-volumio volumio[1373]: info: FusionDsp - If filter freq >samplerate/2 then disable it Dec 21 09:42:15 bdx-volumio volumio[1373]: info: FusionDsp - Effects disabled Dec 21 09:42:15 bdx-volumio volumio[1373]: info: Dec 21 09:42:15 bdx-volumio volumio[1373]: ---------------------------- MPD announces state update: player Dec 21 09:42:15 bdx-volumio volumio[1373]: info: sendMpdCommand status took 23 milliseconds Dec 21 09:42:15 bdx-volumio volumio[1373]: info: sendMpdCommand status took 22 milliseconds Dec 21 09:42:15 bdx-volumio volumio[1373]: info: sendMpdCommand status took 21 milliseconds Dec 21 09:42:15 bdx-volumio volumio[1373]: info: sendMpdCommand status took 19 milliseconds Dec 21 09:42:15 bdx-volumio volumio[1373]: info: ControllerMpd::getState Dec 21 09:42:15 bdx-volumio volumio[1373]: verbose: ControllerMpd::sendMpdCommand status Dec 21 09:42:15 bdx-volumio volumio[1373]: verbose: ControllerMpd::parseState Dec 21 09:42:15 bdx-volumio volumio[1373]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 21 09:42:15 bdx-volumio volumio[1373]: verbose: ControllerMpd::parseState Dec 21 09:42:15 bdx-volumio volumio[1373]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 21 09:42:15 bdx-volumio volumio[1373]: verbose: ControllerMpd::parseState Dec 21 09:42:15 bdx-volumio volumio[1373]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 21 09:42:15 bdx-volumio volumio[1373]: verbose: ControllerMpd::parseState Dec 21 09:42:15 bdx-volumio volumio[1373]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 21 09:42:15 bdx-volumio volumio[1373]: info: Dec 21 09:42:15 bdx-volumio volumio[1373]: ---------------------------- MPD announces state update: player Dec 21 09:42:15 bdx-volumio volumio[1373]: info: ControllerMpd::getState Dec 21 09:42:15 bdx-volumio volumio[1373]: verbose: ControllerMpd::sendMpdCommand status Dec 21 09:42:15 bdx-volumio volumio[1373]: info: Dec 21 09:42:15 bdx-volumio volumio[1373]: ---------------------------- MPD announces state update: player Dec 21 09:42:15 bdx-volumio volumio[1373]: info: ControllerMpd::getState Dec 21 09:42:15 bdx-volumio volumio[1373]: verbose: ControllerMpd::sendMpdCommand status Dec 21 09:42:15 bdx-volumio volumio[1373]: info: Dec 21 09:42:15 bdx-volumio volumio[1373]: ---------------------------- MPD announces state update: player Dec 21 09:42:15 bdx-volumio volumio[1373]: info: ControllerMpd::getState Dec 21 09:42:15 bdx-volumio volumio[1373]: verbose: ControllerMpd::sendMpdCommand status Dec 21 09:42:15 bdx-volumio volumio[1373]: info: sendMpdCommand status took 11 milliseconds Dec 21 09:42:15 bdx-volumio volumio[1373]: info: sendMpdCommand playlistinfo took 11 milliseconds Dec 21 09:42:15 bdx-volumio volumio[1373]: info: sendMpdCommand playlistinfo took 11 milliseconds Dec 21 09:42:15 bdx-volumio volumio[1373]: info: sendMpdCommand playlistinfo took 11 milliseconds Dec 21 09:42:15 bdx-volumio volumio[1373]: info: sendMpdCommand playlistinfo took 11 milliseconds Dec 21 09:42:15 bdx-volumio volumio[1373]: info: sendMpdCommand status took 9 milliseconds Dec 21 09:42:15 bdx-volumio volumio[1373]: info: sendMpdCommand status took 8 milliseconds Dec 21 09:42:15 bdx-volumio volumio[1373]: info: sendMpdCommand status took 7 milliseconds Dec 21 09:42:15 bdx-volumio volumio[1373]: verbose: ControllerMpd::parseState Dec 21 09:42:15 bdx-volumio volumio[1373]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 21 09:42:15 bdx-volumio volumio[1373]: verbose: ControllerMpd::parseTrackInfo Dec 21 09:42:15 bdx-volumio volumio[1373]: verbose: ControllerMpd::parseTrackInfo Dec 21 09:42:15 bdx-volumio volumio[1373]: verbose: ControllerMpd::parseTrackInfo Dec 21 09:42:15 bdx-volumio volumio[1373]: verbose: ControllerMpd::parseTrackInfo Dec 21 09:42:15 bdx-volumio volumio[1373]: verbose: ControllerMpd::parseState Dec 21 09:42:15 bdx-volumio volumio[1373]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 21 09:42:15 bdx-volumio volumio[1373]: verbose: ControllerMpd::parseState Dec 21 09:42:15 bdx-volumio volumio[1373]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 21 09:42:15 bdx-volumio volumio[1373]: verbose: ControllerMpd::parseState Dec 21 09:42:15 bdx-volumio volumio[1373]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 21 09:42:15 bdx-volumio volumio[1373]: info: ControllerMpd::pushState Dec 21 09:42:15 bdx-volumio volumio[1373]: info: CoreCommandRouter::servicePushState Dec 21 09:42:15 bdx-volumio volumio[1373]: info: CorePlayQueue::getTrack 0 Dec 21 09:42:15 bdx-volumio volumio[1373]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"","artist":null,"album":null,"uri":"http://streams.radiobob.de/live-nrw-mitte/mp3-192/tunein/","trackType":""} Dec 21 09:42:15 bdx-volumio volumio[1373]: verbose: CURRENT POSITION 0 Dec 21 09:42:15 bdx-volumio volumio[1373]: info: CoreStateMachine::syncState stateService play Dec 21 09:42:15 bdx-volumio volumio[1373]: info: CoreStateMachine::syncState currentStatus stop Dec 21 09:42:15 bdx-volumio volumio[1373]: info: ControllerMpd::pushState Dec 21 09:42:15 bdx-volumio volumio[1373]: info: CoreCommandRouter::servicePushState Dec 21 09:42:15 bdx-volumio volumio[1373]: info: CorePlayQueue::getTrack 0 Dec 21 09:42:15 bdx-volumio volumio[1373]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"","artist":null,"album":null,"uri":"http://streams.radiobob.de/live-nrw-mitte/mp3-192/tunein/","trackType":""} Dec 21 09:42:15 bdx-volumio volumio[1373]: verbose: CURRENT POSITION 0 Dec 21 09:42:15 bdx-volumio volumio[1373]: info: CoreStateMachine::syncState stateService play Dec 21 09:42:15 bdx-volumio volumio[1373]: info: CoreStateMachine::syncState currentStatus play Dec 21 09:42:15 bdx-volumio volumio[1373]: info: Received an update from plugin. extracting info from payload Dec 21 09:42:15 bdx-volumio volumio[1373]: info: CoreStateMachine::pushState Dec 21 09:42:15 bdx-volumio volumio[1373]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 21 09:42:15 bdx-volumio volumio[1373]: info: CoreCommandRouter::volumioPushState Dec 21 09:42:15 bdx-volumio volumio[1373]: info: CoreStateMachine::pushState Dec 21 09:42:15 bdx-volumio volumio[1373]: info: CoreCommandRouter::volumioPushState Dec 21 09:42:15 bdx-volumio volumio[1373]: info: ControllerMpd::pushState Dec 21 09:42:15 bdx-volumio volumio[1373]: info: CoreCommandRouter::servicePushState Dec 21 09:42:15 bdx-volumio volumio[1373]: info: CorePlayQueue::getTrack 0 Dec 21 09:42:15 bdx-volumio volumio[1373]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"","artist":null,"album":null,"uri":"http://streams.radiobob.de/live-nrw-mitte/mp3-192/tunein/","trackType":""} Dec 21 09:42:15 bdx-volumio volumio[1373]: verbose: CURRENT POSITION 0 Dec 21 09:42:15 bdx-volumio volumio[1373]: info: CoreStateMachine::syncState stateService play Dec 21 09:42:15 bdx-volumio volumio[1373]: info: CoreStateMachine::syncState currentStatus play Dec 21 09:42:15 bdx-volumio volumio[1373]: info: Received an update from plugin. extracting info from payload Dec 21 09:42:15 bdx-volumio volumio[1373]: info: CoreStateMachine::pushState Dec 21 09:42:15 bdx-volumio volumio[1373]: info: CoreCommandRouter::volumioPushState Dec 21 09:42:15 bdx-volumio volumio[1373]: info: CoreStateMachine::pushState Dec 21 09:42:15 bdx-volumio volumio[1373]: info: CoreCommandRouter::volumioPushState Dec 21 09:42:15 bdx-volumio volumio[1373]: info: ControllerMpd::pushState Dec 21 09:42:15 bdx-volumio volumio[1373]: info: CoreCommandRouter::servicePushState Dec 21 09:42:15 bdx-volumio volumio[1373]: info: CorePlayQueue::getTrack 0 Dec 21 09:42:15 bdx-volumio volumio[1373]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"","artist":null,"album":null,"uri":"http://streams.radiobob.de/live-nrw-mitte/mp3-192/tunein/","trackType":""} Dec 21 09:42:15 bdx-volumio volumio[1373]: verbose: CURRENT POSITION 0 Dec 21 09:42:15 bdx-volumio volumio[1373]: info: CoreStateMachine::syncState stateService play Dec 21 09:42:15 bdx-volumio volumio[1373]: info: CoreStateMachine::syncState currentStatus play Dec 21 09:42:15 bdx-volumio volumio[1373]: info: Received an update from plugin. extracting info from payload Dec 21 09:42:15 bdx-volumio volumio[1373]: info: CoreStateMachine::pushState Dec 21 09:42:15 bdx-volumio volumio[1373]: info: CoreCommandRouter::volumioPushState Dec 21 09:42:15 bdx-volumio volumio[1373]: info: CoreStateMachine::pushState Dec 21 09:42:15 bdx-volumio volumio[1373]: info: CoreCommandRouter::volumioPushState Dec 21 09:42:15 bdx-volumio volumio[1373]: info: ------------------------------ 69ms Dec 21 09:42:15 bdx-volumio volumio[1373]: info: ------------------------------ 80ms Dec 21 09:42:15 bdx-volumio volumio[1373]: info: ------------------------------ 79ms Dec 21 09:42:15 bdx-volumio volumio[1373]: info: ------------------------------ 78ms Dec 21 09:42:15 bdx-volumio volumio[1373]: error: FusionDsp - WebSocket error: [object Object] Dec 21 09:42:15 bdx-volumio volumio[1373]: info: Dec 21 09:42:15 bdx-volumio volumio[1373]: ---------------------------- MPD announces system playlist update Dec 21 09:42:15 bdx-volumio volumio[1373]: info: Ignoring MPD Status Update Dec 21 09:42:15 bdx-volumio volumio[1373]: info: Dec 21 09:42:15 bdx-volumio volumio[1373]: ---------------------------- MPD announces state update: player Dec 21 09:42:15 bdx-volumio volumio[1373]: info: ControllerMpd::getState Dec 21 09:42:15 bdx-volumio volumio[1373]: verbose: ControllerMpd::sendMpdCommand status Dec 21 09:42:15 bdx-volumio volumio[1373]: info: sendMpdCommand playlistinfo took 48 milliseconds Dec 21 09:42:15 bdx-volumio volumio[1373]: info: sendMpdCommand playlistinfo took 44 milliseconds Dec 21 09:42:15 bdx-volumio volumio[1373]: info: sendMpdCommand playlistinfo took 45 milliseconds Dec 21 09:42:15 bdx-volumio volumio[1373]: info: sendMpdCommand playlistinfo took 44 milliseconds Dec 21 09:42:15 bdx-volumio volumio[1373]: verbose: ControllerMpd::parseTrackInfo Dec 21 09:42:15 bdx-volumio volumio[1373]: verbose: ControllerMpd::parseTrackInfo Dec 21 09:42:15 bdx-volumio volumio[1373]: verbose: ControllerMpd::parseTrackInfo Dec 21 09:42:15 bdx-volumio volumio[1373]: verbose: ControllerMpd::parseTrackInfo Dec 21 09:42:15 bdx-volumio volumio[1373]: info: ControllerMpd::pushState Dec 21 09:42:15 bdx-volumio volumio[1373]: info: CoreCommandRouter::servicePushState Dec 21 09:42:15 bdx-volumio volumio[1373]: info: CorePlayQueue::getTrack 0 Dec 21 09:42:15 bdx-volumio volumio[1373]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"RADIO BOB - Livestream NRW","artist":"RADIO BOB - Livestream NRW","album":null,"uri":"http://streams.radiobob.de/live-nrw-mitte/mp3-192/tunein/","trackType":""} Dec 21 09:42:15 bdx-volumio volumio[1373]: verbose: CURRENT POSITION 0 Dec 21 09:42:15 bdx-volumio volumio[1373]: info: CoreStateMachine::syncState stateService play Dec 21 09:42:15 bdx-volumio volumio[1373]: info: CoreStateMachine::syncState currentStatus play Dec 21 09:42:15 bdx-volumio volumio[1373]: info: Received an update from plugin. extracting info from payload Dec 21 09:42:15 bdx-volumio volumio[1373]: info: CoreStateMachine::pushState Dec 21 09:42:15 bdx-volumio volumio[1373]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 21 09:42:15 bdx-volumio volumio[1373]: info: CoreCommandRouter::volumioPushState Dec 21 09:42:15 bdx-volumio volumio[1373]: info: CoreStateMachine::pushState Dec 21 09:42:15 bdx-volumio volumio[1373]: info: CoreCommandRouter::volumioPushState Dec 21 09:42:15 bdx-volumio volumio[1373]: info: ControllerMpd::pushState Dec 21 09:42:15 bdx-volumio volumio[1373]: info: CoreCommandRouter::servicePushState Dec 21 09:42:15 bdx-volumio volumio[1373]: info: CorePlayQueue::getTrack 0 Dec 21 09:42:15 bdx-volumio volumio[1373]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"RADIO BOB - Livestream NRW","artist":"RADIO BOB - Livestream NRW","album":null,"uri":"http://streams.radiobob.de/live-nrw-mitte/mp3-192/tunein/","trackType":""} Dec 21 09:42:15 bdx-volumio volumio[1373]: verbose: CURRENT POSITION 0 Dec 21 09:42:15 bdx-volumio volumio[1373]: info: CoreStateMachine::syncState stateService play Dec 21 09:42:15 bdx-volumio volumio[1373]: info: CoreStateMachine::syncState currentStatus play Dec 21 09:42:15 bdx-volumio volumio[1373]: info: Received an update from plugin. extracting info from payload Dec 21 09:42:15 bdx-volumio volumio[1373]: info: CoreStateMachine::pushState Dec 21 09:42:15 bdx-volumio volumio[1373]: info: CoreCommandRouter::volumioPushState Dec 21 09:42:15 bdx-volumio volumio[1373]: info: CoreStateMachine::pushState Dec 21 09:42:15 bdx-volumio volumio[1373]: info: CoreCommandRouter::volumioPushState Dec 21 09:42:15 bdx-volumio volumio[1373]: info: ControllerMpd::pushState Dec 21 09:42:15 bdx-volumio volumio[1373]: info: CoreCommandRouter::servicePushState Dec 21 09:42:15 bdx-volumio volumio[1373]: info: CorePlayQueue::getTrack 0 Dec 21 09:42:15 bdx-volumio volumio[1373]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"RADIO BOB - Livestream NRW","artist":"RADIO BOB - Livestream NRW","album":null,"uri":"http://streams.radiobob.de/live-nrw-mitte/mp3-192/tunein/","trackType":""} Dec 21 09:42:15 bdx-volumio volumio[1373]: verbose: CURRENT POSITION 0 Dec 21 09:42:15 bdx-volumio volumio[1373]: info: CoreStateMachine::syncState stateService play Dec 21 09:42:15 bdx-volumio volumio[1373]: info: CoreStateMachine::syncState currentStatus play Dec 21 09:42:15 bdx-volumio volumio[1373]: info: Received an update from plugin. extracting info from payload Dec 21 09:42:15 bdx-volumio volumio[1373]: info: CoreStateMachine::pushState Dec 21 09:42:15 bdx-volumio volumio[1373]: info: CoreCommandRouter::volumioPushState Dec 21 09:42:15 bdx-volumio volumio[1373]: info: CoreStateMachine::pushState Dec 21 09:42:15 bdx-volumio volumio[1373]: info: CoreCommandRouter::volumioPushState Dec 21 09:42:15 bdx-volumio volumio[1373]: info: ControllerMpd::pushState Dec 21 09:42:15 bdx-volumio volumio[1373]: info: CoreCommandRouter::servicePushState Dec 21 09:42:15 bdx-volumio volumio[1373]: info: CorePlayQueue::getTrack 0 Dec 21 09:42:15 bdx-volumio volumio[1373]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"RADIO BOB - Livestream NRW","artist":"RADIO BOB - Livestream NRW","album":null,"uri":"http://streams.radiobob.de/live-nrw-mitte/mp3-192/tunein/","trackType":""} Dec 21 09:42:15 bdx-volumio volumio[1373]: verbose: CURRENT POSITION 0 Dec 21 09:42:15 bdx-volumio volumio[1373]: info: CoreStateMachine::syncState stateService play Dec 21 09:42:15 bdx-volumio volumio[1373]: info: CoreStateMachine::syncState currentStatus play Dec 21 09:42:15 bdx-volumio volumio[1373]: info: Received an update from plugin. extracting info from payload Dec 21 09:42:15 bdx-volumio volumio[1373]: info: CoreStateMachine::pushState Dec 21 09:42:15 bdx-volumio volumio[1373]: info: CoreCommandRouter::volumioPushState Dec 21 09:42:15 bdx-volumio volumio[1373]: info: CoreStateMachine::pushState Dec 21 09:42:15 bdx-volumio volumio[1373]: info: CoreCommandRouter::volumioPushState Dec 21 09:42:15 bdx-volumio volumio[1373]: info: ------------------------------ 121ms Dec 21 09:42:15 bdx-volumio volumio[1373]: info: ------------------------------ 116ms Dec 21 09:42:15 bdx-volumio volumio[1373]: info: ------------------------------ 116ms Dec 21 09:42:15 bdx-volumio volumio[1373]: info: ------------------------------ 115ms Dec 21 09:42:15 bdx-volumio volumio[1373]: info: Dec 21 09:42:15 bdx-volumio volumio[1373]: ---------------------------- MPD announces system playlist update Dec 21 09:42:15 bdx-volumio volumio[1373]: info: Ignoring MPD Status Update Dec 21 09:42:15 bdx-volumio volumio[1373]: info: Dec 21 09:42:15 bdx-volumio volumio[1373]: ---------------------------- MPD announces state update: player Dec 21 09:42:15 bdx-volumio volumio[1373]: info: ControllerMpd::getState Dec 21 09:42:15 bdx-volumio volumio[1373]: verbose: ControllerMpd::sendMpdCommand status Dec 21 09:42:15 bdx-volumio volumio[1373]: info: Dec 21 09:42:15 bdx-volumio volumio[1373]: ---------------------------- MPD announces system playlist update Dec 21 09:42:15 bdx-volumio volumio[1373]: info: Ignoring MPD Status Update Dec 21 09:42:15 bdx-volumio volumio[1373]: info: Dec 21 09:42:15 bdx-volumio volumio[1373]: ---------------------------- MPD announces state update: player Dec 21 09:42:15 bdx-volumio volumio[1373]: info: ControllerMpd::getState Dec 21 09:42:15 bdx-volumio volumio[1373]: verbose: ControllerMpd::sendMpdCommand status Dec 21 09:42:15 bdx-volumio volumio[1373]: info: Dec 21 09:42:15 bdx-volumio volumio[1373]: ---------------------------- MPD announces system playlist update Dec 21 09:42:15 bdx-volumio volumio[1373]: info: Ignoring MPD Status Update Dec 21 09:42:15 bdx-volumio volumio[1373]: info: Dec 21 09:42:15 bdx-volumio volumio[1373]: ---------------------------- MPD announces state update: player Dec 21 09:42:15 bdx-volumio volumio[1373]: info: ControllerMpd::getState Dec 21 09:42:15 bdx-volumio volumio[1373]: verbose: ControllerMpd::sendMpdCommand status Dec 21 09:42:15 bdx-volumio volumio[1373]: info: ------------------------------ 97ms Dec 21 09:42:15 bdx-volumio volumio[1373]: info: sendMpdCommand status took 95 milliseconds Dec 21 09:42:15 bdx-volumio volumio[1373]: info: ------------------------------ 36ms Dec 21 09:42:15 bdx-volumio volumio[1373]: info: sendMpdCommand status took 34 milliseconds Dec 21 09:42:15 bdx-volumio volumio[1373]: info: ------------------------------ 33ms Dec 21 09:42:15 bdx-volumio volumio[1373]: info: sendMpdCommand status took 32 milliseconds Dec 21 09:42:15 bdx-volumio volumio[1373]: info: ------------------------------ 38ms Dec 21 09:42:15 bdx-volumio volumio[1373]: info: sendMpdCommand status took 33 milliseconds Dec 21 09:42:15 bdx-volumio volumio[1373]: verbose: ControllerMpd::parseState Dec 21 09:42:15 bdx-volumio volumio[1373]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 21 09:42:15 bdx-volumio volumio[1373]: verbose: ControllerMpd::parseState Dec 21 09:42:15 bdx-volumio volumio[1373]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 21 09:42:15 bdx-volumio volumio[1373]: verbose: ControllerMpd::parseState Dec 21 09:42:15 bdx-volumio volumio[1373]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 21 09:42:15 bdx-volumio volumio[1373]: verbose: ControllerMpd::parseState Dec 21 09:42:15 bdx-volumio volumio[1373]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 21 09:42:15 bdx-volumio volumio[1373]: info: sendMpdCommand playlistinfo took 14 milliseconds Dec 21 09:42:15 bdx-volumio volumio[1373]: info: sendMpdCommand playlistinfo took 14 milliseconds Dec 21 09:42:15 bdx-volumio volumio[1373]: info: sendMpdCommand playlistinfo took 4 milliseconds Dec 21 09:42:15 bdx-volumio volumio[1373]: info: sendMpdCommand playlistinfo took 3 milliseconds Dec 21 09:42:15 bdx-volumio volumio[1373]: verbose: ControllerMpd::parseTrackInfo Dec 21 09:42:15 bdx-volumio volumio[1373]: verbose: ControllerMpd::parseTrackInfo Dec 21 09:42:15 bdx-volumio volumio[1373]: verbose: ControllerMpd::parseTrackInfo Dec 21 09:42:15 bdx-volumio volumio[1373]: verbose: ControllerMpd::parseTrackInfo Dec 21 09:42:15 bdx-volumio volumio[1373]: info: ControllerMpd::pushState Dec 21 09:42:15 bdx-volumio volumio[1373]: info: CoreCommandRouter::servicePushState Dec 21 09:42:15 bdx-volumio volumio[1373]: info: CorePlayQueue::getTrack 0 Dec 21 09:42:15 bdx-volumio volumio[1373]: verbose: STATE SERVICE {"status":"play","position":0,"seek":982,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"RADIO BOB - Livestream NRW","artist":"RADIO BOB - Livestream NRW","album":null,"uri":"http://streams.radiobob.de/live-nrw-mitte/mp3-192/tunein/","trackType":""} Dec 21 09:42:15 bdx-volumio volumio[1373]: verbose: CURRENT POSITION 0 Dec 21 09:42:15 bdx-volumio volumio[1373]: info: CoreStateMachine::syncState stateService play Dec 21 09:42:15 bdx-volumio volumio[1373]: info: CoreStateMachine::syncState currentStatus play Dec 21 09:42:15 bdx-volumio volumio[1373]: info: Received an update from plugin. extracting info from payload Dec 21 09:42:15 bdx-volumio volumio[1373]: info: CoreStateMachine::pushState Dec 21 09:42:15 bdx-volumio volumio[1373]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 21 09:42:15 bdx-volumio volumio[1373]: info: CoreCommandRouter::volumioPushState Dec 21 09:42:15 bdx-volumio volumio[1373]: info: CoreStateMachine::pushState Dec 21 09:42:15 bdx-volumio volumio[1373]: info: CoreCommandRouter::volumioPushState Dec 21 09:42:15 bdx-volumio volumio[1373]: info: ControllerMpd::pushState Dec 21 09:42:15 bdx-volumio volumio[1373]: info: CoreCommandRouter::servicePushState Dec 21 09:42:15 bdx-volumio volumio[1373]: info: CorePlayQueue::getTrack 0 Dec 21 09:42:15 bdx-volumio volumio[1373]: verbose: STATE SERVICE {"status":"play","position":0,"seek":982,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"RADIO BOB - Livestream NRW","artist":"RADIO BOB - Livestream NRW","album":null,"uri":"http://streams.radiobob.de/live-nrw-mitte/mp3-192/tunein/","trackType":""} Dec 21 09:42:15 bdx-volumio volumio[1373]: verbose: CURRENT POSITION 0 Dec 21 09:42:15 bdx-volumio volumio[1373]: info: CoreStateMachine::syncState stateService play Dec 21 09:42:15 bdx-volumio volumio[1373]: info: CoreStateMachine::syncState currentStatus play Dec 21 09:42:15 bdx-volumio volumio[1373]: info: Received an update from plugin. extracting info from payload Dec 21 09:42:15 bdx-volumio volumio[1373]: info: CoreStateMachine::pushState Dec 21 09:42:15 bdx-volumio volumio[1373]: info: CoreCommandRouter::volumioPushState Dec 21 09:42:15 bdx-volumio volumio[1373]: info: CoreStateMachine::pushState Dec 21 09:42:15 bdx-volumio volumio[1373]: info: CoreCommandRouter::volumioPushState Dec 21 09:42:15 bdx-volumio volumio[1373]: info: ControllerMpd::pushState Dec 21 09:42:15 bdx-volumio volumio[1373]: info: CoreCommandRouter::servicePushState Dec 21 09:42:15 bdx-volumio volumio[1373]: info: CorePlayQueue::getTrack 0 Dec 21 09:42:15 bdx-volumio volumio[1373]: verbose: STATE SERVICE {"status":"play","position":0,"seek":982,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"RADIO BOB - Livestream NRW","artist":"RADIO BOB - Livestream NRW","album":null,"uri":"http://streams.radiobob.de/live-nrw-mitte/mp3-192/tunein/","trackType":""} Dec 21 09:42:15 bdx-volumio volumio[1373]: verbose: CURRENT POSITION 0 Dec 21 09:42:15 bdx-volumio volumio[1373]: info: CoreStateMachine::syncState stateService play Dec 21 09:42:15 bdx-volumio volumio[1373]: info: CoreStateMachine::syncState currentStatus play Dec 21 09:42:15 bdx-volumio volumio[1373]: info: Received an update from plugin. extracting info from payload Dec 21 09:42:15 bdx-volumio volumio[1373]: info: CoreStateMachine::pushState Dec 21 09:42:15 bdx-volumio volumio[1373]: info: CoreCommandRouter::volumioPushState Dec 21 09:42:15 bdx-volumio volumio[1373]: info: CoreStateMachine::pushState Dec 21 09:42:15 bdx-volumio volumio[1373]: info: CoreCommandRouter::volumioPushState Dec 21 09:42:15 bdx-volumio volumio[1373]: info: ControllerMpd::pushState Dec 21 09:42:15 bdx-volumio volumio[1373]: info: CoreCommandRouter::servicePushState Dec 21 09:42:15 bdx-volumio volumio[1373]: info: CorePlayQueue::getTrack 0 Dec 21 09:42:15 bdx-volumio volumio[1373]: verbose: STATE SERVICE {"status":"play","position":0,"seek":982,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"RADIO BOB - Livestream NRW","artist":"RADIO BOB - Livestream NRW","album":null,"uri":"http://streams.radiobob.de/live-nrw-mitte/mp3-192/tunein/","trackType":""} Dec 21 09:42:15 bdx-volumio volumio[1373]: verbose: CURRENT POSITION 0 Dec 21 09:42:15 bdx-volumio volumio[1373]: info: CoreStateMachine::syncState stateService play Dec 21 09:42:15 bdx-volumio volumio[1373]: info: CoreStateMachine::syncState currentStatus play Dec 21 09:42:15 bdx-volumio volumio[1373]: info: Received an update from plugin. extracting info from payload Dec 21 09:42:15 bdx-volumio volumio[1373]: info: CoreStateMachine::pushState Dec 21 09:42:15 bdx-volumio volumio[1373]: info: CoreCommandRouter::volumioPushState Dec 21 09:42:15 bdx-volumio volumio[1373]: info: CoreStateMachine::pushState Dec 21 09:42:15 bdx-volumio volumio[1373]: info: CoreCommandRouter::volumioPushState Dec 21 09:42:15 bdx-volumio volumio[1373]: info: ------------------------------ 176ms Dec 21 09:42:15 bdx-volumio volumio[1373]: info: ------------------------------ 115ms Dec 21 09:42:15 bdx-volumio volumio[1373]: info: ------------------------------ 112ms Dec 21 09:42:15 bdx-volumio volumio[1373]: info: ------------------------------ 107ms Dec 21 09:42:27 bdx-volumio volumio[1373]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 21 09:42:27 bdx-volumio volumio[1373]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Dec 21 09:42:27 bdx-volumio volumio[1373]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 21 09:42:27 bdx-volumio volumio[1373]: info: FusionDsp - Rock-FusionDsp Dec 21 09:42:27 bdx-volumio volumio[1373]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json,ROCK.json Dec 21 09:42:27 bdx-volumio volumio[1373]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 21 09:42:27 bdx-volumio volumio[1373]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 21 09:42:27 bdx-volumio volumio[1373]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 21 09:42:27 bdx-volumio volumio[1373]: info: FusionDsp - Rock-FusionDsp Dec 21 09:42:27 bdx-volumio volumio[1373]: info: FusionDsp - Rock-FusionDsp Dec 21 09:42:27 bdx-volumio volumio[1373]: info: FusionDsp - Rock-FusionDsp Dec 21 09:42:28 bdx-volumio volumio[1373]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json,ROCK.json Dec 21 09:42:28 bdx-volumio volumio[1373]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json,ROCK.json Dec 21 09:42:28 bdx-volumio volumio[1373]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json,ROCK.json Dec 21 09:42:32 bdx-volumio volumio[1373]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/fusiondsp/volumio/buster/armhf Dec 21 09:42:34 bdx-volumio volumio[1373]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/fusiondsp/volumio/buster/armhf Dec 21 09:42:34 bdx-volumio volumio[1373]: info: FusionDsp - Stopping FusionDsp service Dec 21 09:42:34 bdx-volumio volumio[1373]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 21 09:42:34 bdx-volumio volumio[1373]: TypeError: Cannot read property 'stop' of null Dec 21 09:42:34 bdx-volumio volumio[1373]: at FusionDsp.onStop (/data/plugins/audio_interface/fusiondsp/index.js:109:23) Dec 21 09:42:34 bdx-volumio volumio[1373]: at PluginManager.stopPlugin (/volumio/app/pluginmanager.js:460:30) Dec 21 09:42:34 bdx-volumio volumio[1373]: at /volumio/app/pluginmanager.js:956:12 Dec 21 09:42:34 bdx-volumio volumio[1373]: at ChildProcess.exithandler (child_process.js:299:7) Dec 21 09:42:34 bdx-volumio volumio[1373]: at ChildProcess.emit (events.js:315:20) Dec 21 09:42:34 bdx-volumio volumio[1373]: at maybeClose (internal/child_process.js:1048:16) Dec 21 09:42:34 bdx-volumio volumio[1373]: at Process.ChildProcess._handle.onexit (internal/child_process.js:288:5) Dec 21 09:42:34 bdx-volumio volumio[1373]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 21 09:42:35 bdx-volumio sudo[8173]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-21 09:41 Dec 21 09:42:35 bdx-volumio sudo[8173]: 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="b43c85cc0006d3f1efecba101311ec96e334d1fd" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 11 Dec 2025 05:43:24 PM CET" VOLUMIO_VERSION="3.886" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="15d0241b0cd805792809f3ddf4a0fc4e"