-- Logs begin at Tue 2024-04-02 16:46:30 UTC, end at Tue 2024-04-02 17:14:04 UTC. --
Apr 02 17:13:00 volumio volumio[816]: info: Selecting previously unselected package python3-secretstorage.
Apr 02 17:13:00 volumio volumio[816]: info: Preparing to unpack .../48-python3-secretstorage_2.3.1-2_all.deb ...
Apr 02 17:13:00 volumio volumio[816]: info: Unpacking python3-secretstorage (2.3.1-2) ...
Apr 02 17:13:00 volumio volumio[816]: info: Selecting previously unselected package python3-keyring.
Apr 02 17:13:00 volumio volumio[816]: info: Preparing to unpack .../49-python3-keyring_17.1.1-1_all.deb ...
Apr 02 17:13:00 volumio volumio[816]: info: Unpacking python3-keyring (17.1.1-1) ...
Apr 02 17:13:01 volumio volumio[816]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri
Apr 02 17:13:02 volumio volumio[816]: info: Selecting previously unselected package python3-keyrings.alt.
Apr 02 17:13:02 volumio volumio[816]: info: Preparing to unpack .../50-python3-keyrings.alt_3.1.1-1_all.deb ...
Apr 02 17:13:02 volumio volumio[816]: info: Unpacking python3-keyrings.alt (3.1.1-1) ...
Apr 02 17:13:02 volumio volumio[816]: info: Selecting previously unselected package python3-pip.
Apr 02 17:13:02 volumio volumio[816]: info: Preparing to unpack .../51-python3-pip_18.1-5_all.deb ...
Apr 02 17:13:02 volumio volumio[816]: info: Unpacking python3-pip (18.1-5) ...
Apr 02 17:13:03 volumio volumio[816]: info: Preload queue cleared
Apr 02 17:13:04 volumio volumio[816]: info: Preload queue cleared
Apr 02 17:13:04 volumio volumio[816]: info: Selecting previously unselected package python3-setuptools.
Apr 02 17:13:04 volumio volumio[816]: info: Preparing to unpack .../52-python3-setuptools_40.8.0-1_all.deb ...
Apr 02 17:13:04 volumio volumio[816]: info: Unpacking python3-setuptools (40.8.0-1) ...
Apr 02 17:13:08 volumio volumio[816]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri
Apr 02 17:13:08 volumio volumio[816]: info: Preload queue cleared
Apr 02 17:13:08 volumio volumio[816]: info: Selecting previously unselected package python3-wheel.
Apr 02 17:13:08 volumio volumio[816]: info: Preparing to unpack .../53-python3-wheel_0.32.3-2_all.deb ...
Apr 02 17:13:08 volumio volumio[816]: info: Unpacking python3-wheel (0.32.3-2) ...
Apr 02 17:13:08 volumio volumio[816]: info: Selecting previously unselected package python3-xdg.
Apr 02 17:13:08 volumio volumio[816]: info: Preparing to unpack .../54-python3-xdg_0.25-5_all.deb ...
Apr 02 17:13:08 volumio volumio[816]: info: Unpacking python3-xdg (0.25-5) ...
Apr 02 17:13:11 volumio volumio[816]: info: Setting up python3-entrypoints (0.3-1) ...
Apr 02 17:13:12 volumio volumio[816]: info: Setting up dh-python (3.20190308) ...
Apr 02 17:13:12 volumio volumio[816]: info: Setting up python3-attr (18.2.0-1) ...
Apr 02 17:13:13 volumio volumio[816]: info: Setting up python3-dbus (1.2.8-3) ...
Apr 02 17:13:14 volumio volumio[816]: info: Setting up python3-setuptools (40.8.0-1) ...
Apr 02 17:13:14 volumio volumio[816]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri
Apr 02 17:13:14 volumio volumio[816]: info: Preload queue cleared
Apr 02 17:13:14 volumio volumio[816]: info: Preloading song: upnp/http://192.168.0.43:50001/ContentDirectory/control@29$124350$124362$@124362
Apr 02 17:13:14 volumio volumio[816]: info: Preloading song: upnp/http://192.168.0.43:50001/ContentDirectory/control@29$124350$124362$@124363
Apr 02 17:13:14 volumio volumio[816]: info: Preloading song: upnp/http://192.168.0.43:50001/ContentDirectory/control@29$124350$124362$@124364
Apr 02 17:13:14 volumio volumio[816]: info: Preloading song: upnp/http://192.168.0.43:50001/ContentDirectory/control@29$124350$124362$@124366
Apr 02 17:13:14 volumio volumio[816]: info: Preloading song: upnp/http://192.168.0.43:50001/ContentDirectory/control@29$124350$124362$@124365
Apr 02 17:13:14 volumio volumio[816]: info: Preloading song: upnp/http://192.168.0.43:50001/ContentDirectory/control@29$124350$124362$@124367
Apr 02 17:13:14 volumio volumio[816]: info: Exploding uri upnp/http://192.168.0.43:50001/ContentDirectory/control@29$124350$124362$@124362 in service upnp_browser
Apr 02 17:13:14 volumio volumio[816]: info: Setting up libpython3.7:amd64 (3.7.3-2+deb10u3) ...
Apr 02 17:13:14 volumio volumio[816]: info: Exploding uri upnp/http://192.168.0.43:50001/ContentDirectory/control@29$124350$124362$@124363 in service upnp_browser
Apr 02 17:13:14 volumio volumio[816]: info: Exploding uri upnp/http://192.168.0.43:50001/ContentDirectory/control@29$124350$124362$@124364 in service upnp_browser
Apr 02 17:13:14 volumio volumio[816]: info: Setting up libalgorithm-diff-perl (1.19.03-2) ...
Apr 02 17:13:14 volumio volumio[816]: info: Exploding uri upnp/http://192.168.0.43:50001/ContentDirectory/control@29$124350$124362$@124366 in service upnp_browser
Apr 02 17:13:14 volumio volumio[816]: info: Setting up binutils-common:amd64 (2.31.1-16) ...
Apr 02 17:13:15 volumio volumio[816]: info: Exploding uri upnp/http://192.168.0.43:50001/ContentDirectory/control@29$124350$124362$@124365 in service upnp_browser
Apr 02 17:13:15 volumio volumio[816]: info: Exploding uri upnp/http://192.168.0.43:50001/ContentDirectory/control@29$124350$124362$@124367 in service upnp_browser
Apr 02 17:13:15 volumio volumio[816]: info: Setting up python3-xdg (0.25-5) ...
Apr 02 17:13:15 volumio volumio[816]: info: Setting up bzip2 (1.0.6-9.2~deb10u1) ...
Apr 02 17:13:16 volumio volumio[816]: info: Setting up python3-wheel (0.32.3-2) ...
Apr 02 17:13:16 volumio volumio[816]: info: Preload queue cleared
Apr 02 17:13:16 volumio volumio[816]: info: CoreCommandRouter::volumioReplaceandPlayItems
Apr 02 17:13:16 volumio volumio[816]: info: CoreStateMachine::ClearQueue
Apr 02 17:13:16 volumio volumio[816]: info: CoreStateMachine::stop
Apr 02 17:13:16 volumio volumio[816]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 02 17:13:16 volumio volumio[816]: info: CoreStateMachine::stPlaybackTimer
Apr 02 17:13:16 volumio volumio[816]: info: CoreStateMachine::updateTrackBlock
Apr 02 17:13:16 volumio volumio[816]: info: CorePlayQueue::getTrackBlock
Apr 02 17:13:16 volumio volumio[816]: info: CoreStateMachine::pushState
Apr 02 17:13:16 volumio volumio[816]: info: CorePlayQueue::getTrack 3
Apr 02 17:13:16 volumio volumio[816]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 02 17:13:16 volumio volumio[816]: info: CoreCommandRouter::volumioPushState
Apr 02 17:13:16 volumio volumio[816]: info: CoreStateMachine::serviceStop
Apr 02 17:13:16 volumio volumio[816]: info: CorePlayQueue::getTrack 3
Apr 02 17:13:16 volumio volumio[816]: info: CoreCommandRouter::serviceStop
Apr 02 17:13:16 volumio volumio[816]: info: [1712077996384] ControllerUPNPBrowser::stop
Apr 02 17:13:16 volumio volumio[816]: verbose: ControllerMpd::sendMpdCommand stop
Apr 02 17:13:16 volumio volumio[816]: info: CorePlayQueue::clearPlayQueue
Apr 02 17:13:16 volumio volumio[816]: info: CorePlayQueue::saveQueue
Apr 02 17:13:16 volumio volumio[816]: info: CoreCommandRouter::volumioPushQueue
Apr 02 17:13:16 volumio volumio[816]: info: CoreStateMachine::addQueueItems
Apr 02 17:13:16 volumio volumio[816]: info: CorePlayQueue::addQueueItems
Apr 02 17:13:16 volumio volumio[816]: info: Preload queue cleared
Apr 02 17:13:16 volumio volumio[816]: info: Adding Item to queue: upnp/folder/http://192.168.0.43:50001/ContentDirectory/control@29$124350$124362
Apr 02 17:13:16 volumio volumio[816]: info: Exploding uri upnp/folder/http://192.168.0.43:50001/ContentDirectory/control@29$124350$124362 in service upnp_browser
Apr 02 17:13:16 volumio volumio[816]: info:
Apr 02 17:13:16 volumio volumio[816]: ---------------------------- MPD announces state update: player
Apr 02 17:13:16 volumio volumio[816]: info: sendMpdCommand stop took 7 milliseconds
Apr 02 17:13:16 volumio volumio[816]: info: ControllerMpd::getState
Apr 02 17:13:16 volumio volumio[816]: verbose: ControllerMpd::sendMpdCommand status
Apr 02 17:13:16 volumio volumio[816]: info:
Apr 02 17:13:16 volumio volumio[816]: ---------------------------- MPD announces state update: player
Apr 02 17:13:16 volumio volumio[816]: info: ControllerMpd::getState
Apr 02 17:13:16 volumio volumio[816]: verbose: ControllerMpd::sendMpdCommand status
Apr 02 17:13:16 volumio volumio[816]: info: Setting up python3-multidict (4.5.2-1) ...
Apr 02 17:13:16 volumio volumio[816]: info: sendMpdCommand status took 5 milliseconds
Apr 02 17:13:16 volumio volumio[816]: info: sendMpdCommand status took 5 milliseconds
Apr 02 17:13:16 volumio volumio[816]: verbose: ControllerMpd::parseState
Apr 02 17:13:16 volumio volumio[816]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 02 17:13:16 volumio volumio[816]: verbose: ControllerMpd::parseState
Apr 02 17:13:16 volumio volumio[816]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 02 17:13:16 volumio volumio[816]: info: sendMpdCommand playlistinfo took 2 milliseconds
Apr 02 17:13:16 volumio volumio[816]: verbose: ControllerMpd::parseTrackInfo
Apr 02 17:13:16 volumio volumio[816]: verbose: ControllerMpd::parseTrackInfo
Apr 02 17:13:16 volumio volumio[816]: info: ControllerMpd::pushState
Apr 02 17:13:16 volumio volumio[816]: info: CoreCommandRouter::servicePushState
Apr 02 17:13:16 volumio volumio[816]: info: CoreStateMachine::pushState
Apr 02 17:13:16 volumio volumio[816]: info: CorePlayQueue::getTrack 3
Apr 02 17:13:16 volumio volumio[816]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 02 17:13:16 volumio volumio[816]: info: CoreCommandRouter::volumioPushState
Apr 02 17:13:16 volumio volumio[816]: info: CorePlayQueue::getTrack 3
Apr 02 17:13:16 volumio volumio[816]: 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":"Got My Mojo Working","artist":"Eric Clapton","album":"Baloise Session - (Basel, Switzerland)","uri":"http://192.168.0.123:8200/MediaItems/A0$128$131$4129026$4325636.flac?type=1,client=33,mime=audio/x-flac,pn=,ext=.flac","trackType":"flac"}
Apr 02 17:13:16 volumio volumio[816]: verbose: CURRENT POSITION 3
Apr 02 17:13:16 volumio volumio[816]: info: CoreStateMachine::syncState stateService stop
Apr 02 17:13:16 volumio volumio[816]: info: CoreStateMachine::syncState currentStatus stop
Apr 02 17:13:16 volumio volumio[816]: info: CoreStateMachine::pushState
Apr 02 17:13:16 volumio volumio[816]: info: CorePlayQueue::getTrack 3
Apr 02 17:13:16 volumio volumio[816]: info: CoreCommandRouter::volumioPushState
Apr 02 17:13:16 volumio volumio[816]: info: No code
Apr 02 17:13:16 volumio volumio[816]: info: CoreStateMachine::pushState
Apr 02 17:13:16 volumio volumio[816]: info: CorePlayQueue::getTrack 3
Apr 02 17:13:16 volumio volumio[816]: info: CoreCommandRouter::volumioPushState
Apr 02 17:13:16 volumio volumio[816]: info: ControllerMpd::pushState
Apr 02 17:13:16 volumio volumio[816]: info: CoreCommandRouter::servicePushState
Apr 02 17:13:16 volumio volumio[816]: info: CoreStateMachine::pushState
Apr 02 17:13:16 volumio volumio[816]: info: CorePlayQueue::getTrack 3
Apr 02 17:13:16 volumio volumio[816]: info: CoreCommandRouter::volumioPushState
Apr 02 17:13:16 volumio volumio[816]: info: CorePlayQueue::getTrack 3
Apr 02 17:13:16 volumio volumio[816]: 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":"Got My Mojo Working","artist":"Eric Clapton","album":"Baloise Session - (Basel, Switzerland)","uri":"http://192.168.0.123:8200/MediaItems/A0$128$131$4129026$4325636.flac?type=1,client=33,mime=audio/x-flac,pn=,ext=.flac","trackType":"flac"}
Apr 02 17:13:16 volumio volumio[816]: verbose: CURRENT POSITION 3
Apr 02 17:13:16 volumio volumio[816]: info: CoreStateMachine::syncState stateService stop
Apr 02 17:13:16 volumio volumio[816]: info: CoreStateMachine::syncState currentStatus stop
Apr 02 17:13:16 volumio volumio[816]: info: CoreStateMachine::pushState
Apr 02 17:13:16 volumio volumio[816]: info: CorePlayQueue::getTrack 3
Apr 02 17:13:16 volumio volumio[816]: info: CoreCommandRouter::volumioPushState
Apr 02 17:13:16 volumio volumio[816]: info: No code
Apr 02 17:13:16 volumio volumio[816]: info: CoreStateMachine::pushState
Apr 02 17:13:16 volumio volumio[816]: info: CorePlayQueue::getTrack 3
Apr 02 17:13:16 volumio volumio[816]: info: CoreCommandRouter::volumioPushState
Apr 02 17:13:16 volumio volumio[816]: info: ------------------------------ 14ms
Apr 02 17:13:16 volumio volumio[816]: info: ------------------------------ 14ms
Apr 02 17:13:16 volumio volumio[816]: info: CoreCommandRouter::volumioPushQueue
Apr 02 17:13:16 volumio volumio[816]: info: CorePlayQueue::saveQueue
Apr 02 17:13:16 volumio volumio[816]: info: CoreStateMachine::updateTrackBlock
Apr 02 17:13:16 volumio volumio[816]: info: CorePlayQueue::getTrackBlock
Apr 02 17:13:16 volumio volumio[816]: info: CoreCommandRouter::volumioPlay
Apr 02 17:13:16 volumio volumio[816]: info: CoreStateMachine::play index 0
Apr 02 17:13:16 volumio volumio[816]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 02 17:13:16 volumio volumio[816]: info: CoreStateMachine::stop
Apr 02 17:13:16 volumio volumio[816]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 02 17:13:16 volumio volumio[816]: info: CoreStateMachine::play index undefined
Apr 02 17:13:16 volumio volumio[816]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 02 17:13:16 volumio volumio[816]: info: CorePlayQueue::getTrack 0
Apr 02 17:13:16 volumio volumio[816]: info: CoreStateMachine::startPlaybackTimer
Apr 02 17:13:16 volumio volumio[816]: info: CorePlayQueue::getTrack 0
Apr 02 17:13:16 volumio volumio[816]: info: [1712077996560] ControllerUPNPBrowser::clearAddPlayTrack
Apr 02 17:13:16 volumio volumio[816]: verbose: ControllerMpd::sendMpdCommand stop
Apr 02 17:13:16 volumio volumio[816]: info: sendMpdCommand stop took 8 milliseconds
Apr 02 17:13:16 volumio volumio[816]: verbose: ControllerMpd::sendMpdCommand clear
Apr 02 17:13:16 volumio volumio[816]: info:
Apr 02 17:13:16 volumio volumio[816]: ---------------------------- MPD announces system playlist update
Apr 02 17:13:16 volumio volumio[816]: info: Ignoring MPD Status Update
Apr 02 17:13:16 volumio volumio[816]: info: sendMpdCommand clear took 3 milliseconds
Apr 02 17:13:16 volumio volumio[816]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.0.43:50002/m/NDLNA/124362.flac"
Apr 02 17:13:16 volumio volumio[816]: info:
Apr 02 17:13:16 volumio volumio[816]: ---------------------------- MPD announces system playlist update
Apr 02 17:13:16 volumio volumio[816]: info: Ignoring MPD Status Update
Apr 02 17:13:16 volumio volumio[816]: info: Setting up libfakeroot:amd64 (1.23-1) ...
Apr 02 17:13:17 volumio volumio[816]: info: Setting up python3-async-timeout (3.0.1-1) ...
Apr 02 17:13:17 volumio volumio[816]: info: Setting up fakeroot (1.23-1) ...
Apr 02 17:13:17 volumio volumio[816]: info: update-alternatives: using /usr/bin/fakeroot-sysv to provide /usr/bin/fakeroot (fakeroot) in auto mode
Apr 02 17:13:17 volumio volumio[816]: info: update-alternatives: warning: skip creation of /usr/share/man/es/man1/fakeroot.1.gz because associated file /usr/share/man/es/man1/fakeroot-sysv.1.gz (of link group fakeroot) doesn't exist
Apr 02 17:13:17 volumio volumio[816]: info: update-alternatives: warning: skip creation of /usr/share/man/es/man1/faked.1.gz because associated file /usr/share/man/es/man1/faked-sysv.1.gz (of link group fakeroot) doesn't exist
Apr 02 17:13:17 volumio volumio[816]: info: update-alternatives: warning: skip creation of /usr/share/man/fr/man1/fakeroot.1.gz because associated file /usr/share/man/fr/man1/fakeroot-sysv.1.gz (of link group fakeroot) doesn't exist
Apr 02 17:13:17 volumio volumio[816]: info: update-alternatives: warning: skip creation of /usr/share/man/fr/man1/faked.1.gz because associated file /usr/share/man/fr/man1/faked-sysv.1.gz (of link group fakeroot) doesn't exist
Apr 02 17:13:17 volumio volumio[816]: info: update-alternatives: warning: skip creation of /usr/share/man/sv/man1/fakeroot.1.gz because associated file /usr/share/man/sv/man1/fakeroot-sysv.1.gz (of link group fakeroot) doesn't exist
Apr 02 17:13:17 volumio volumio[816]: info: update-alternatives: warning: skip creation of /usr/share/man/sv/man1/faked.1.gz because associated file /usr/share/man/sv/man1/faked-sysv.1.gz (of link group fakeroot) doesn't exist
Apr 02 17:13:17 volumio volumio[816]: info: Setting up libasan5:amd64 (8.3.0-6) ...
Apr 02 17:13:17 volumio volumio[816]: info: Setting up libexpat1-dev:amd64 (2.2.6-2+deb10u4) ...
Apr 02 17:13:17 volumio volumio[816]: info: Setting up make (4.2.1-1.2) ...
Apr 02 17:13:17 volumio volumio[816]: info: Setting up python3-gi (3.30.4-1) ...
Apr 02 17:13:18 volumio volumio[816]: info: Setting up libquadmath0:amd64 (8.3.0-6) ...
Apr 02 17:13:18 volumio volumio[816]: info: Setting up patch (2.7.6-3+deb10u1) ...
Apr 02 17:13:19 volumio volumio[816]: info: Setting up libmpx2:amd64 (8.3.0-6) ...
Apr 02 17:13:19 volumio volumio[816]: error: updateQueue error: null
Apr 02 17:13:19 volumio volumio[816]: error: updateQueue error: null
Apr 02 17:13:19 volumio volumio[816]: info: ------------------------------ 2558ms
Apr 02 17:13:19 volumio volumio[816]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.0.43:50002/m/NDLNA/124362.flac"
Apr 02 17:13:19 volumio volumio[816]: info: ------------------------------ 2557ms
Apr 02 17:13:19 volumio volumio[816]: info:
Apr 02 17:13:19 volumio volumio[816]: ---------------------------- MPD announces system playlist update
Apr 02 17:13:19 volumio volumio[816]: info: Ignoring MPD Status Update
Apr 02 17:13:19 volumio volumio[816]: info: sendMpdCommand add "http://192.168.0.43:50002/m/NDLNA/124362.flac" took 1 milliseconds
Apr 02 17:13:19 volumio volumio[816]: info: CoreStateMachine::setConsumeUpdateService mpd
Apr 02 17:13:19 volumio volumio[816]: verbose: ControllerMpd::sendMpdCommand play
Apr 02 17:13:19 volumio volumio[816]: info:
Apr 02 17:13:19 volumio volumio[816]: ---------------------------- MPD announces system playlist update
Apr 02 17:13:19 volumio volumio[816]: info: Ignoring MPD Status Update
Apr 02 17:13:19 volumio volumio[816]: info: ------------------------------ 2ms
Apr 02 17:13:19 volumio volumio[816]: info: sendMpdCommand play took 1 milliseconds
Apr 02 17:13:19 volumio volumio[816]: info: ------------------------------ 1ms
Apr 02 17:13:19 volumio volumio[816]: info: Setting up libubsan1:amd64 (8.3.0-6) ...
Apr 02 17:13:19 volumio volumio[816]: info: Setting up python3-crypto (2.6.1-9+b1) ...
Apr 02 17:13:19 volumio volumio[816]: info:
Apr 02 17:13:19 volumio volumio[816]: ---------------------------- MPD announces state update: player
Apr 02 17:13:19 volumio volumio[816]: info: ControllerMpd::getState
Apr 02 17:13:19 volumio volumio[816]: verbose: ControllerMpd::sendMpdCommand status
Apr 02 17:13:19 volumio volumio[816]: info:
Apr 02 17:13:19 volumio volumio[816]: ---------------------------- MPD announces state update: player
Apr 02 17:13:19 volumio volumio[816]: info: ControllerMpd::getState
Apr 02 17:13:19 volumio volumio[816]: verbose: ControllerMpd::sendMpdCommand status
Apr 02 17:13:19 volumio volumio[816]: info:
Apr 02 17:13:19 volumio volumio[816]: ---------------------------- MPD announces state update: player
Apr 02 17:13:19 volumio volumio[816]: info: sendMpdCommand status took 2 milliseconds
Apr 02 17:13:19 volumio volumio[816]: info: sendMpdCommand status took 1 milliseconds
Apr 02 17:13:19 volumio volumio[816]: info: ControllerMpd::getState
Apr 02 17:13:19 volumio volumio[816]: verbose: ControllerMpd::sendMpdCommand status
Apr 02 17:13:19 volumio volumio[816]: verbose: ControllerMpd::parseState
Apr 02 17:13:19 volumio volumio[816]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 02 17:13:19 volumio volumio[816]: verbose: ControllerMpd::parseState
Apr 02 17:13:19 volumio volumio[816]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 02 17:13:19 volumio volumio[816]: info:
Apr 02 17:13:19 volumio volumio[816]: ---------------------------- MPD announces state update: player
Apr 02 17:13:19 volumio volumio[816]: info: ControllerMpd::getState
Apr 02 17:13:19 volumio volumio[816]: verbose: ControllerMpd::sendMpdCommand status
Apr 02 17:13:19 volumio volumio[816]: info: sendMpdCommand status took 2 milliseconds
Apr 02 17:13:19 volumio volumio[816]: info: sendMpdCommand playlistinfo took 2 milliseconds
Apr 02 17:13:19 volumio volumio[816]: info: sendMpdCommand playlistinfo took 2 milliseconds
Apr 02 17:13:19 volumio volumio[816]: verbose: ControllerMpd::parseState
Apr 02 17:13:19 volumio volumio[816]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 02 17:13:19 volumio volumio[816]: verbose: ControllerMpd::parseTrackInfo
Apr 02 17:13:19 volumio volumio[816]: verbose: ControllerMpd::parseTrackInfo
Apr 02 17:13:19 volumio volumio[816]: info: ControllerMpd::pushState
Apr 02 17:13:19 volumio volumio[816]: info: CoreCommandRouter::servicePushState
Apr 02 17:13:19 volumio volumio[816]: info: CorePlayQueue::getTrack 0
Apr 02 17:13:19 volumio volumio[816]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":358,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"124362.flac","artist":null,"album":null,"uri":"http://192.168.0.43:50002/m/NDLNA/124362.flac","trackType":"flac"}
Apr 02 17:13:19 volumio volumio[816]: verbose: CURRENT POSITION 0
Apr 02 17:13:19 volumio volumio[816]: info: CoreStateMachine::syncState stateService play
Apr 02 17:13:19 volumio volumio[816]: info: CoreStateMachine::syncState currentStatus stop
Apr 02 17:13:19 volumio volumio[816]: info: ControllerMpd::pushState
Apr 02 17:13:19 volumio volumio[816]: info: CoreCommandRouter::servicePushState
Apr 02 17:13:19 volumio volumio[816]: info: CorePlayQueue::getTrack 0
Apr 02 17:13:19 volumio volumio[816]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":358,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"124362.flac","artist":null,"album":null,"uri":"http://192.168.0.43:50002/m/NDLNA/124362.flac","trackType":"flac"}
Apr 02 17:13:19 volumio volumio[816]: verbose: CURRENT POSITION 0
Apr 02 17:13:19 volumio volumio[816]: info: CoreStateMachine::syncState stateService play
Apr 02 17:13:19 volumio volumio[816]: info: CoreStateMachine::syncState currentStatus play
Apr 02 17:13:19 volumio volumio[816]: info: Received an update from plugin. extracting info from payload
Apr 02 17:13:19 volumio volumio[816]: info: CoreStateMachine::pushState
Apr 02 17:13:19 volumio volumio[816]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 02 17:13:19 volumio volumio[816]: info: CoreCommandRouter::volumioPushState
Apr 02 17:13:19 volumio volumio[816]: info: CoreStateMachine::pushState
Apr 02 17:13:19 volumio volumio[816]: info: CoreCommandRouter::volumioPushState
Apr 02 17:13:19 volumio volumio[816]: info: ------------------------------ 13ms
Apr 02 17:13:19 volumio volumio[816]: info: ------------------------------ 16ms
Apr 02 17:13:19 volumio volumio[816]: info: sendMpdCommand status took 13 milliseconds
Apr 02 17:13:19 volumio volumio[816]: info: sendMpdCommand playlistinfo took 13 milliseconds
Apr 02 17:13:19 volumio volumio[816]: verbose: ControllerMpd::parseState
Apr 02 17:13:19 volumio volumio[816]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 02 17:13:19 volumio volumio[816]: verbose: ControllerMpd::parseTrackInfo
Apr 02 17:13:19 volumio volumio[816]: info: ControllerMpd::pushState
Apr 02 17:13:19 volumio volumio[816]: info: CoreCommandRouter::servicePushState
Apr 02 17:13:19 volumio volumio[816]: info: CorePlayQueue::getTrack 0
Apr 02 17:13:19 volumio volumio[816]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":358,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"124362.flac","artist":null,"album":null,"uri":"http://192.168.0.43:50002/m/NDLNA/124362.flac","trackType":"flac"}
Apr 02 17:13:19 volumio volumio[816]: verbose: CURRENT POSITION 0
Apr 02 17:13:19 volumio volumio[816]: info: CoreStateMachine::syncState stateService play
Apr 02 17:13:19 volumio volumio[816]: info: CoreStateMachine::syncState currentStatus play
Apr 02 17:13:19 volumio volumio[816]: info: Received an update from plugin. extracting info from payload
Apr 02 17:13:19 volumio volumio[816]: info: CoreStateMachine::pushState
Apr 02 17:13:19 volumio volumio[816]: info: CoreCommandRouter::volumioPushState
Apr 02 17:13:19 volumio volumio[816]: info: CoreStateMachine::pushState
Apr 02 17:13:19 volumio volumio[816]: info: CoreCommandRouter::volumioPushState
Apr 02 17:13:19 volumio volumio[816]: info: ------------------------------ 20ms
Apr 02 17:13:19 volumio volumio[816]: info: sendMpdCommand playlistinfo took 8 milliseconds
Apr 02 17:13:19 volumio volumio[816]: verbose: ControllerMpd::parseTrackInfo
Apr 02 17:13:19 volumio volumio[816]: info: ControllerMpd::pushState
Apr 02 17:13:19 volumio volumio[816]: info: CoreCommandRouter::servicePushState
Apr 02 17:13:19 volumio volumio[816]: info: CorePlayQueue::getTrack 0
Apr 02 17:13:19 volumio volumio[816]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":358,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"124362.flac","artist":null,"album":null,"uri":"http://192.168.0.43:50002/m/NDLNA/124362.flac","trackType":"flac"}
Apr 02 17:13:19 volumio volumio[816]: verbose: CURRENT POSITION 0
Apr 02 17:13:19 volumio volumio[816]: info: CoreStateMachine::syncState stateService play
Apr 02 17:13:19 volumio volumio[816]: info: CoreStateMachine::syncState currentStatus play
Apr 02 17:13:19 volumio volumio[816]: info: Received an update from plugin. extracting info from payload
Apr 02 17:13:19 volumio volumio[816]: info: CoreStateMachine::pushState
Apr 02 17:13:19 volumio volumio[816]: info: CoreCommandRouter::volumioPushState
Apr 02 17:13:19 volumio volumio[816]: info: CoreStateMachine::pushState
Apr 02 17:13:19 volumio volumio[816]: info: CoreCommandRouter::volumioPushState
Apr 02 17:13:19 volumio volumio[816]: info: ------------------------------ 30ms
Apr 02 17:13:20 volumio volumio[816]: info: Setting up python-pip-whl (18.1-5) ...
Apr 02 17:13:20 volumio volumio[816]: info: Setting up libbinutils:amd64 (2.31.1-16) ...
Apr 02 17:13:20 volumio volumio[816]: info: Setting up python3-yarl (1.3.0-1) ...
Apr 02 17:13:20 volumio volumio[816]: info: Setting up libalgorithm-diff-xs-perl (0.04-5+b1) ...
Apr 02 17:13:20 volumio volumio[816]: info: Setting up python3-asn1crypto (0.24.0-1) ...
Apr 02 17:13:21 volumio volumio[816]: info:
Apr 02 17:13:21 volumio volumio[816]: ---------------------------- MPD announces system playlist update
Apr 02 17:13:21 volumio volumio[816]: info: Ignoring MPD Status Update
Apr 02 17:13:21 volumio volumio[816]: info:
Apr 02 17:13:21 volumio volumio[816]: ---------------------------- MPD announces state update: player
Apr 02 17:13:21 volumio volumio[816]: info: ControllerMpd::getState
Apr 02 17:13:21 volumio volumio[816]: verbose: ControllerMpd::sendMpdCommand status
Apr 02 17:13:21 volumio volumio[816]: info:
Apr 02 17:13:21 volumio volumio[816]: ---------------------------- MPD announces system playlist update
Apr 02 17:13:21 volumio volumio[816]: info: Ignoring MPD Status Update
Apr 02 17:13:21 volumio volumio[816]: info:
Apr 02 17:13:21 volumio volumio[816]: ---------------------------- MPD announces state update: player
Apr 02 17:13:21 volumio volumio[816]: info: ControllerMpd::getState
Apr 02 17:13:21 volumio volumio[816]: verbose: ControllerMpd::sendMpdCommand status
Apr 02 17:13:21 volumio volumio[816]: info: ------------------------------ 5ms
Apr 02 17:13:21 volumio volumio[816]: info: sendMpdCommand status took 3 milliseconds
Apr 02 17:13:21 volumio volumio[816]: info: ------------------------------ 3ms
Apr 02 17:13:21 volumio volumio[816]: info: sendMpdCommand status took 2 milliseconds
Apr 02 17:13:21 volumio volumio[816]: verbose: ControllerMpd::parseState
Apr 02 17:13:21 volumio volumio[816]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 02 17:13:21 volumio volumio[816]: verbose: ControllerMpd::parseState
Apr 02 17:13:21 volumio volumio[816]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 02 17:13:21 volumio volumio[816]: info: sendMpdCommand playlistinfo took 3 milliseconds
Apr 02 17:13:21 volumio volumio[816]: info: sendMpdCommand playlistinfo took 2 milliseconds
Apr 02 17:13:21 volumio volumio[816]: verbose: ControllerMpd::parseTrackInfo
Apr 02 17:13:21 volumio volumio[816]: info: ControllerMpd::pushState
Apr 02 17:13:21 volumio volumio[816]: info: CoreCommandRouter::servicePushState
Apr 02 17:13:21 volumio volumio[816]: info: CorePlayQueue::getTrack 0
Apr 02 17:13:21 volumio volumio[816]: verbose: STATE SERVICE {"status":"play","position":0,"seek":865,"duration":358,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"492 Kbps","isStreaming":false,"title":"Egyptian Danza","artist":"Al Di Meola","album":"Casino","uri":"http://192.168.0.43:50002/m/NDLNA/124362.flac","trackType":"flac"}
Apr 02 17:13:21 volumio volumio[816]: verbose: CURRENT POSITION 0
Apr 02 17:13:21 volumio volumio[816]: info: CoreStateMachine::syncState stateService play
Apr 02 17:13:21 volumio volumio[816]: info: CoreStateMachine::syncState currentStatus play
Apr 02 17:13:21 volumio volumio[816]: info: Received an update from plugin. extracting info from payload
Apr 02 17:13:21 volumio volumio[816]: info: CoreStateMachine::pushState
Apr 02 17:13:21 volumio volumio[816]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 02 17:13:21 volumio volumio[816]: info: CoreCommandRouter::volumioPushState
Apr 02 17:13:21 volumio volumio[816]: info: CoreStateMachine::pushState
Apr 02 17:13:21 volumio volumio[816]: info: CoreCommandRouter::volumioPushState
Apr 02 17:13:21 volumio volumio[816]: info: ControllerMpd::pushState
Apr 02 17:13:21 volumio volumio[816]: info: CoreCommandRouter::servicePushState
Apr 02 17:13:21 volumio volumio[816]: info: CorePlayQueue::getTrack 0
Apr 02 17:13:21 volumio volumio[816]: verbose: STATE SERVICE {"status":"play","position":0,"seek":865,"duration":358,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"492 Kbps","isStreaming":false,"title":"Egyptian Danza","artist":"Al Di Meola","album":"Casino","uri":"http://192.168.0.43:50002/m/NDLNA/124362.flac","trackType":"flac"}
Apr 02 17:13:21 volumio volumio[816]: verbose: CURRENT POSITION 0
Apr 02 17:13:21 volumio volumio[816]: info: CoreStateMachine::syncState stateService play
Apr 02 17:13:21 volumio volumio[816]: info: CoreStateMachine::syncState currentStatus play
Apr 02 17:13:21 volumio volumio[816]: info: Received an update from plugin. extracting info from payload
Apr 02 17:13:21 volumio volumio[816]: info: CoreStateMachine::pushState
Apr 02 17:13:21 volumio volumio[816]: info: CoreCommandRouter::volumioPushState
Apr 02 17:13:21 volumio volumio[816]: info: CoreStateMachine::pushState
Apr 02 17:13:21 volumio volumio[816]: info: CoreCommandRouter::volumioPushState
Apr 02 17:13:21 volumio volumio[816]: info: ------------------------------ 20ms
Apr 02 17:13:21 volumio volumio[816]: info: ------------------------------ 18ms
Apr 02 17:13:21 volumio volumio[816]: info: CoreCommandRouter::volumioGetQueue
Apr 02 17:13:21 volumio volumio[816]: info: CoreStateMachine::getQueue
Apr 02 17:13:21 volumio volumio[816]: info: CorePlayQueue::getQueue
Apr 02 17:13:21 volumio volumio[816]: info: CoreCommandRouter::volumioGetQueue
Apr 02 17:13:21 volumio volumio[816]: info: CoreStateMachine::getQueue
Apr 02 17:13:21 volumio volumio[816]: info: CorePlayQueue::getQueue
Apr 02 17:13:21 volumio volumio[816]: info: CoreCommandRouter::volumioGetQueue
Apr 02 17:13:21 volumio volumio[816]: info: CoreStateMachine::getQueue
Apr 02 17:13:21 volumio volumio[816]: info: CorePlayQueue::getQueue
Apr 02 17:13:21 volumio volumio[816]: info: CoreCommandRouter::volumioGetQueue
Apr 02 17:13:21 volumio volumio[816]: info: CoreStateMachine::getQueue
Apr 02 17:13:21 volumio volumio[816]: info: CorePlayQueue::getQueue
Apr 02 17:13:21 volumio volumio[816]: info: Setting up libcc1-0:amd64 (8.3.0-6) ...
Apr 02 17:13:22 volumio volumio[816]: info: Setting up liblsan0:amd64 (8.3.0-6) ...
Apr 02 17:13:22 volumio volumio[816]: info: Setting up libitm1:amd64 (8.3.0-6) ...
Apr 02 17:13:22 volumio volumio[816]: info: Setting up python3-cffi-backend (1.12.2-1) ...
Apr 02 17:13:22 volumio volumio[816]: info: Setting up libalgorithm-merge-perl (0.08-3) ...
Apr 02 17:13:22 volumio volumio[816]: info: Setting up binutils-x86-64-linux-gnu (2.31.1-16) ...
Apr 02 17:13:22 volumio volumio[816]: info: Setting up libtsan0:amd64 (8.3.0-6) ...
Apr 02 17:13:22 volumio volumio[816]: info: Setting up libpython3.7-dev:amd64 (3.7.3-2+deb10u3) ...
Apr 02 17:13:22 volumio volumio[816]: info: Setting up python3-aiohttp (3.5.1-1+deb10u1) ...
Apr 02 17:13:23 volumio volumio[816]: info: Setting up binutils (2.31.1-16) ...
Apr 02 17:13:23 volumio volumio[816]: info: Setting up python3.7-dev (3.7.3-2+deb10u3) ...
Apr 02 17:13:23 volumio volumio[816]: info: Setting up dpkg-dev (1.19.8) ...
Apr 02 17:13:24 volumio volumio[816]: info: Setting up python3-cryptography (2.6.1-3+deb10u2) ...
Apr 02 17:13:25 volumio volumio[816]: info: Setting up python3-pip (18.1-5) ...
Apr 02 17:13:26 volumio volumio[816]: info: VolumeController::SetAlsaVolume55
Apr 02 17:13:26 volumio volumio[816]: info: CoreStateMachine::pushState
Apr 02 17:13:26 volumio volumio[816]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 02 17:13:26 volumio volumio[816]: info: CoreCommandRouter::volumioPushState
Apr 02 17:13:27 volumio volumio[816]: info: CoreCommandRouter::volumioGetQueue
Apr 02 17:13:27 volumio volumio[816]: info: CoreStateMachine::getQueue
Apr 02 17:13:27 volumio volumio[816]: info: CorePlayQueue::getQueue
Apr 02 17:13:27 volumio volumio[816]: info: Setting up libgcc-8-dev:amd64 (8.3.0-6) ...
Apr 02 17:13:27 volumio volumio[816]: info: VolumeController::SetAlsaVolume75
Apr 02 17:13:27 volumio volumio[816]: info: CoreStateMachine::pushState
Apr 02 17:13:27 volumio volumio[816]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 02 17:13:27 volumio volumio[816]: info: CoreCommandRouter::volumioPushState
Apr 02 17:13:27 volumio volumio[816]: info: CoreCommandRouter::volumioGetQueue
Apr 02 17:13:27 volumio volumio[816]: info: CoreStateMachine::getQueue
Apr 02 17:13:27 volumio volumio[816]: info: CorePlayQueue::getQueue
Apr 02 17:13:27 volumio volumio[816]: info: Setting up python3-keyrings.alt (3.1.1-1) ...
Apr 02 17:13:27 volumio volumio[816]: info: VolumeController::SetAlsaVolume100
Apr 02 17:13:27 volumio volumio[816]: info: CoreStateMachine::pushState
Apr 02 17:13:27 volumio volumio[816]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 02 17:13:27 volumio volumio[816]: info: CoreCommandRouter::volumioPushState
Apr 02 17:13:28 volumio volumio[816]: info: CoreCommandRouter::volumioGetQueue
Apr 02 17:13:28 volumio volumio[816]: info: CoreStateMachine::getQueue
Apr 02 17:13:28 volumio volumio[816]: info: CorePlayQueue::getQueue
Apr 02 17:13:28 volumio volumio[816]: info: VolumeController::SetAlsaVolume100
Apr 02 17:13:28 volumio volumio[816]: info: CoreStateMachine::pushState
Apr 02 17:13:28 volumio volumio[816]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 02 17:13:28 volumio volumio[816]: info: CoreCommandRouter::volumioPushState
Apr 02 17:13:28 volumio volumio[816]: info: CoreCommandRouter::volumioGetQueue
Apr 02 17:13:28 volumio volumio[816]: info: CoreStateMachine::getQueue
Apr 02 17:13:28 volumio volumio[816]: info: CorePlayQueue::getQueue
Apr 02 17:13:28 volumio volumio[816]: info: Setting up libstdc++-8-dev:amd64 (8.3.0-6) ...
Apr 02 17:13:28 volumio volumio[816]: info: Setting up gcc-8 (8.3.0-6) ...
Apr 02 17:13:28 volumio volumio[816]: info: VolumeController::SetAlsaVolume100
Apr 02 17:13:28 volumio volumio[816]: info: CoreStateMachine::pushState
Apr 02 17:13:28 volumio volumio[816]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 02 17:13:28 volumio volumio[816]: info: CoreCommandRouter::volumioPushState
Apr 02 17:13:28 volumio volumio[816]: info: CoreCommandRouter::volumioGetQueue
Apr 02 17:13:28 volumio volumio[816]: info: CoreStateMachine::getQueue
Apr 02 17:13:28 volumio volumio[816]: info: CorePlayQueue::getQueue
Apr 02 17:13:29 volumio volumio[816]: info: Setting up libpython3-dev:amd64 (3.7.3-1) ...
Apr 02 17:13:29 volumio volumio[816]: info: Setting up gcc (4:8.3.0-1) ...
Apr 02 17:13:29 volumio volumio[816]: info: VolumeController::SetAlsaVolume90
Apr 02 17:13:29 volumio volumio[816]: info: CoreStateMachine::pushState
Apr 02 17:13:29 volumio volumio[816]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 02 17:13:29 volumio volumio[816]: info: CoreCommandRouter::volumioPushState
Apr 02 17:13:29 volumio volumio[816]: info: CoreCommandRouter::volumioGetQueue
Apr 02 17:13:29 volumio volumio[816]: info: CoreStateMachine::getQueue
Apr 02 17:13:29 volumio volumio[816]: info: CorePlayQueue::getQueue
Apr 02 17:13:30 volumio volumio[816]: info: Setting up python3-secretstorage (2.3.1-2) ...
Apr 02 17:13:30 volumio volumio[816]: info: VolumeController::SetAlsaVolume100
Apr 02 17:13:30 volumio volumio[816]: info: CoreStateMachine::pushState
Apr 02 17:13:30 volumio volumio[816]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 02 17:13:30 volumio volumio[816]: info: CoreCommandRouter::volumioPushState
Apr 02 17:13:30 volumio volumio[816]: info: CoreCommandRouter::volumioGetQueue
Apr 02 17:13:30 volumio volumio[816]: info: CoreStateMachine::getQueue
Apr 02 17:13:30 volumio volumio[816]: info: CorePlayQueue::getQueue
Apr 02 17:13:30 volumio volumio[816]: info: Setting up python3-dev (3.7.3-1) ...
Apr 02 17:13:30 volumio volumio[816]: info: VolumeController::SetAlsaVolume100
Apr 02 17:13:30 volumio volumio[816]: info: CoreStateMachine::pushState
Apr 02 17:13:30 volumio volumio[816]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 02 17:13:30 volumio volumio[816]: info: CoreCommandRouter::volumioPushState
Apr 02 17:13:30 volumio volumio[816]: info: CoreCommandRouter::volumioGetQueue
Apr 02 17:13:30 volumio volumio[816]: info: CoreStateMachine::getQueue
Apr 02 17:13:30 volumio volumio[816]: info: CorePlayQueue::getQueue
Apr 02 17:13:30 volumio volumio[816]: info: Setting up g++-8 (8.3.0-6) ...
Apr 02 17:13:30 volumio volumio[816]: info: Setting up python3-keyring (17.1.1-1) ...
Apr 02 17:13:30 volumio volumio[816]: info: VolumeController::SetAlsaVolume100
Apr 02 17:13:30 volumio volumio[816]: info: CoreStateMachine::pushState
Apr 02 17:13:30 volumio volumio[816]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 02 17:13:30 volumio volumio[816]: info: CoreCommandRouter::volumioPushState
Apr 02 17:13:30 volumio volumio[816]: info: CoreCommandRouter::volumioGetQueue
Apr 02 17:13:30 volumio volumio[816]: info: CoreStateMachine::getQueue
Apr 02 17:13:30 volumio volumio[816]: info: CorePlayQueue::getQueue
Apr 02 17:13:30 volumio volumio[816]: info: VolumeController::SetAlsaVolume100
Apr 02 17:13:30 volumio volumio[816]: info: CoreStateMachine::pushState
Apr 02 17:13:30 volumio volumio[816]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 02 17:13:30 volumio volumio[816]: info: CoreCommandRouter::volumioPushState
Apr 02 17:13:30 volumio volumio[816]: info: CoreCommandRouter::volumioGetQueue
Apr 02 17:13:30 volumio volumio[816]: info: CoreStateMachine::getQueue
Apr 02 17:13:30 volumio volumio[816]: info: CorePlayQueue::getQueue
Apr 02 17:13:31 volumio volumio[816]: info: Setting up g++ (4:8.3.0-1) ...
Apr 02 17:13:31 volumio volumio[816]: info: update-alternatives: using /usr/bin/g++ to provide /usr/bin/c++ (c++) in auto mode
Apr 02 17:13:31 volumio volumio[816]: info: Setting up build-essential (12.6) ...
Apr 02 17:13:31 volumio volumio[816]: info: Processing triggers for man-db (2.8.5-2) ...
Apr 02 17:13:31 volumio volumio[816]: info: VolumeController::SetAlsaVolume90
Apr 02 17:13:32 volumio volumio[816]: info: CoreStateMachine::pushState
Apr 02 17:13:32 volumio volumio[816]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 02 17:13:32 volumio volumio[816]: info: CoreCommandRouter::volumioPushState
Apr 02 17:13:32 volumio volumio[816]: info: CoreCommandRouter::volumioGetQueue
Apr 02 17:13:32 volumio volumio[816]: info: CoreStateMachine::getQueue
Apr 02 17:13:32 volumio volumio[816]: info: CorePlayQueue::getQueue
Apr 02 17:13:32 volumio volumio[816]: info: VolumeController::SetAlsaVolume80
Apr 02 17:13:32 volumio volumio[816]: info: CoreStateMachine::pushState
Apr 02 17:13:32 volumio volumio[816]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 02 17:13:32 volumio volumio[816]: info: CoreCommandRouter::volumioPushState
Apr 02 17:13:32 volumio volumio[816]: info: CoreCommandRouter::volumioGetQueue
Apr 02 17:13:32 volumio volumio[816]: info: CoreStateMachine::getQueue
Apr 02 17:13:32 volumio volumio[816]: info: CorePlayQueue::getQueue
Apr 02 17:13:32 volumio volumio[816]: info: VolumeController::SetAlsaVolume0
Apr 02 17:13:32 volumio volumio[816]: info: CoreStateMachine::pushState
Apr 02 17:13:32 volumio volumio[816]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 02 17:13:32 volumio volumio[816]: info: CoreCommandRouter::volumioPushState
Apr 02 17:13:32 volumio volumio[816]: info: CoreCommandRouter::volumioGetQueue
Apr 02 17:13:32 volumio volumio[816]: info: CoreStateMachine::getQueue
Apr 02 17:13:32 volumio volumio[816]: info: CorePlayQueue::getQueue
Apr 02 17:13:35 volumio volumio[816]: info: CoreCommandRouter::volumioPause
Apr 02 17:13:35 volumio volumio[816]: info: CoreStateMachine::pause
Apr 02 17:13:35 volumio volumio[816]: info: CoreStateMachine::stPlaybackTimer
Apr 02 17:13:35 volumio volumio[816]: info: CoreStateMachine::servicePause
Apr 02 17:13:35 volumio volumio[816]: info: CorePlayQueue::getTrack 0
Apr 02 17:13:35 volumio volumio[816]: info: CoreCommandRouter::servicePause
Apr 02 17:13:35 volumio volumio[816]: info: [1712078015514] ControllerUPNPBrowser::pause
Apr 02 17:13:35 volumio volumio[816]: verbose: ControllerMpd::sendMpdCommand pause
Apr 02 17:13:35 volumio volumio[816]: info:
Apr 02 17:13:35 volumio volumio[816]: ---------------------------- MPD announces state update: player
Apr 02 17:13:35 volumio volumio[816]: info: sendMpdCommand pause took 1 milliseconds
Apr 02 17:13:35 volumio volumio[816]: info: ControllerMpd::getState
Apr 02 17:13:35 volumio volumio[816]: verbose: ControllerMpd::sendMpdCommand status
Apr 02 17:13:35 volumio volumio[816]: info:
Apr 02 17:13:35 volumio volumio[816]: ---------------------------- MPD announces state update: player
Apr 02 17:13:35 volumio volumio[816]: info: ControllerMpd::getState
Apr 02 17:13:35 volumio volumio[816]: verbose: ControllerMpd::sendMpdCommand status
Apr 02 17:13:35 volumio volumio[816]: info: sendMpdCommand status took 1 milliseconds
Apr 02 17:13:35 volumio volumio[816]: verbose: ControllerMpd::parseState
Apr 02 17:13:35 volumio volumio[816]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 02 17:13:35 volumio volumio[816]: verbose: ControllerMpd::parseState
Apr 02 17:13:35 volumio volumio[816]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 02 17:13:35 volumio volumio[816]: info: sendMpdCommand playlistinfo took 1 milliseconds
Apr 02 17:13:35 volumio volumio[816]: verbose: ControllerMpd::parseTrackInfo
Apr 02 17:13:35 volumio volumio[816]: info: ControllerMpd::pushState
Apr 02 17:13:35 volumio volumio[816]: info: CoreCommandRouter::servicePushState
Apr 02 17:13:35 volumio volumio[816]: info: CorePlayQueue::getTrack 0
Apr 02 17:13:35 volumio volumio[816]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":14957,"duration":358,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"531 Kbps","isStreaming":false,"title":"Egyptian Danza","artist":"Al Di Meola","album":"Casino","uri":"http://192.168.0.43:50002/m/NDLNA/124362.flac","trackType":"flac"}
Apr 02 17:13:35 volumio volumio[816]: verbose: CURRENT POSITION 0
Apr 02 17:13:35 volumio volumio[816]: info: CoreStateMachine::syncState stateService pause
Apr 02 17:13:35 volumio volumio[816]: info: CoreStateMachine::syncState currentStatus pause
Apr 02 17:13:35 volumio volumio[816]: info: CoreStateMachine::pushState
Apr 02 17:13:35 volumio volumio[816]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 02 17:13:35 volumio volumio[816]: info: CoreCommandRouter::volumioPushState
Apr 02 17:13:35 volumio volumio[816]: info: CoreStateMachine::stPlaybackTimer
Apr 02 17:13:35 volumio volumio[816]: info: ControllerMpd::pushState
Apr 02 17:13:35 volumio volumio[816]: info: CoreCommandRouter::servicePushState
Apr 02 17:13:35 volumio volumio[816]: info: CorePlayQueue::getTrack 0
Apr 02 17:13:35 volumio volumio[816]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":14957,"duration":358,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"531 Kbps","isStreaming":false,"title":"Egyptian Danza","artist":"Al Di Meola","album":"Casino","uri":"http://192.168.0.43:50002/m/NDLNA/124362.flac","trackType":"flac"}
Apr 02 17:13:35 volumio volumio[816]: verbose: CURRENT POSITION 0
Apr 02 17:13:35 volumio volumio[816]: info: CoreStateMachine::syncState stateService pause
Apr 02 17:13:35 volumio volumio[816]: info: CoreStateMachine::syncState currentStatus pause
Apr 02 17:13:35 volumio volumio[816]: info: CoreStateMachine::pushState
Apr 02 17:13:35 volumio volumio[816]: info: CoreCommandRouter::volumioPushState
Apr 02 17:13:35 volumio volumio[816]: info: CoreStateMachine::stPlaybackTimer
Apr 02 17:13:35 volumio volumio[816]: info: ------------------------------ 7ms
Apr 02 17:13:35 volumio volumio[816]: info: ------------------------------ 6ms
Apr 02 17:13:35 volumio volumio[816]: info: CoreCommandRouter::volumioGetQueue
Apr 02 17:13:35 volumio volumio[816]: info: CoreStateMachine::getQueue
Apr 02 17:13:35 volumio volumio[816]: info: CorePlayQueue::getQueue
Apr 02 17:13:35 volumio volumio[816]: info: CoreCommandRouter::volumioGetQueue
Apr 02 17:13:35 volumio volumio[816]: info: CoreStateMachine::getQueue
Apr 02 17:13:35 volumio volumio[816]: info: CorePlayQueue::getQueue
Apr 02 17:13:35 volumio volumio[816]: info: Processing triggers for libc-bin (2.28-10+deb10u1) ...
Apr 02 17:13:40 volumio sudo[3464]: pam_unix(sudo:session): session closed for user root
Apr 02 17:13:40 volumio sudo[5407]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/bin/tar -xvf fusiondsp.service.tar -C /
Apr 02 17:13:40 volumio sudo[5407]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 02 17:13:40 volumio volumio[816]: info: lib/systemd/system/fusiondsp.service
Apr 02 17:13:40 volumio sudo[5407]: pam_unix(sudo:session): session closed for user root
Apr 02 17:13:43 volumio volumio[816]: info: MiniUnz 1.01b, demo of zLib + Unz package written by Gilles Vollant
Apr 02 17:13:43 volumio volumio[816]: info: more info at http://www.winimage.com/zLibDll/unzip.html
Apr 02 17:13:43 volumio volumio[816]: info:
Apr 02 17:13:43 volumio volumio[816]: info: cgui-1.0.0.zip opened
Apr 02 17:13:43 volumio volumio[816]: info: creating directory: cgui/
Apr 02 17:13:43 volumio volumio[816]: info: extracting: cgui/LICENSE.txt
Apr 02 17:13:43 volumio volumio[816]: info: extracting: cgui/README.md
Apr 02 17:13:43 volumio volumio[816]: info: creating directory: cgui/build/
Apr 02 17:13:43 volumio volumio[816]: info: extracting: cgui/build/robots.txt
Apr 02 17:13:43 volumio volumio[816]: info: extracting: cgui/build/logo512.png
Apr 02 17:13:43 volumio volumio[816]: info: extracting: cgui/build/asset-manifest.json
Apr 02 17:13:43 volumio volumio[816]: info: extracting: cgui/build/index.html
Apr 02 17:13:43 volumio volumio[816]: info: extracting: cgui/build/.put_statics_here
Apr 02 17:13:43 volumio volumio[816]: info: extracting: cgui/build/favicon.ico
Apr 02 17:13:43 volumio volumio[816]: info: extracting: cgui/build/logo192.png
Apr 02 17:13:43 volumio volumio[816]: info: extracting: cgui/build/.xdp-css-variables-1.css-PvrWoX
Apr 02 17:13:43 volumio sudo[5411]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/bin/chown -R volumio cgui
Apr 02 17:13:43 volumio sudo[5411]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 02 17:13:43 volumio volumio[816]: info: extracting: cgui/build/manifest.json
Apr 02 17:13:43 volumio volumio[816]: info: extracting: cgui/build/css-variables.css
Apr 02 17:13:43 volumio volumio[816]: info: extracting: cgui/build/precache-manifest.76db5aad1647230454f47b1e22783b54.js
Apr 02 17:13:43 volumio volumio[816]: info: extracting: cgui/build/service-worker.js
Apr 02 17:13:43 volumio volumio[816]: info: extracting: cgui/build/precache-manifest.5e6c675b70ef6a3fe73d0209febfe347.js
Apr 02 17:13:43 volumio volumio[816]: info: creating directory: cgui/build/static/
Apr 02 17:13:43 volumio volumio[816]: info: creating directory: cgui/build/static/css/
Apr 02 17:13:43 volumio volumio[816]: info: extracting: cgui/build/static/css/main.d51d005e.chunk.css
Apr 02 17:13:43 volumio volumio[816]: info: extracting: cgui/build/static/css/main.d51d005e.chunk.css.map
Apr 02 17:13:43 volumio volumio[816]: info: extracting: cgui/build/static/css/2.975d6916.chunk.css.map
Apr 02 17:13:43 volumio volumio[816]: info: extracting: cgui/build/static/css/main.56bdf14e.chunk.css
Apr 02 17:13:43 volumio volumio[816]: info: extracting: cgui/build/static/css/main.56bdf14e.chunk.css.map
Apr 02 17:13:43 volumio volumio[816]: info: extracting: cgui/build/static/css/main.1503f6cd.css.map
Apr 02 17:13:43 volumio volumio[816]: info: extracting: cgui/build/static/css/2.a4c7e7cf.chunk.css.map
Apr 02 17:13:43 volumio volumio[816]: info: extracting: cgui/build/static/css/main.1503f6cd.css
Apr 02 17:13:43 volumio volumio[816]: info: extracting: cgui/build/static/css/2.975d6916.chunk.css
Apr 02 17:13:43 volumio volumio[816]: info: extracting: cgui/build/static/css/2.a4c7e7cf.chunk.css
Apr 02 17:13:43 volumio volumio[816]: info: creating directory: cgui/build/static/media/
Apr 02 17:13:43 volumio sudo[5411]: pam_unix(sudo:session): session closed for user root
Apr 02 17:13:43 volumio volumio[816]: info: extracting: cgui/build/static/media/camilladsp.95719842.svg
Apr 02 17:13:43 volumio volumio[816]: info: extracting: cgui/build/static/media/camilladsp.4767ea4ad3510467418b818dc677a87e.svg
Apr 02 17:13:43 volumio volumio[816]: info: creating directory: cgui/build/static/js/
Apr 02 17:13:43 volumio volumio[816]: info: extracting: cgui/build/static/js/2.fd49ece5.chunk.js.map
Apr 02 17:13:43 volumio volumio[816]: info: extracting: cgui/build/static/js/main.71e11955.js
Apr 02 17:13:43 volumio volumio[816]: info: extracting: cgui/build/static/js/runtime-main.422581ec.js
Apr 02 17:13:43 volumio volumio[816]: info: extracting: cgui/build/static/js/2.fd49ece5.chunk.js
Apr 02 17:13:43 volumio volumio[816]: info: extracting: cgui/build/static/js/2.c1b9df8b.chunk.js.LICENSE.txt
Apr 02 17:13:43 volumio volumio[816]: info: extracting: cgui/build/static/js/main.61bb6809.chunk.js
Apr 02 17:13:43 volumio volumio[816]: info: extracting: cgui/build/static/js/main.61bb6809.chunk.js.map
Apr 02 17:13:43 volumio volumio[816]: info: extracting: cgui/build/static/js/2.c1b9df8b.chunk.js
Apr 02 17:13:43 volumio volumio[816]: info: extracting: cgui/build/static/js/main.1b3ac690.chunk.js.map
Apr 02 17:13:43 volumio volumio[816]: info: extracting: cgui/build/static/js/main.71e11955.js.LICENSE.txt
Apr 02 17:13:43 volumio volumio[816]: info: extracting: cgui/build/static/js/main.71e11955.js.map
Apr 02 17:13:43 volumio volumio[816]: info: extracting: cgui/build/static/js/runtime-main.422581ec.js.map
Apr 02 17:13:43 volumio volumio[816]: info: extracting: cgui/build/static/js/main.1b3ac690.chunk.js
Apr 02 17:13:43 volumio volumio[816]: info: extracting: cgui/build/static/js/2.c1b9df8b.chunk.js.map
Apr 02 17:13:43 volumio volumio[816]: info: extracting: cgui/build/static/js/2.fd49ece5.chunk.js.LICENSE.txt
Apr 02 17:13:43 volumio sudo[5413]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/bin/chgrp -R volumio cgui
Apr 02 17:13:43 volumio sudo[5413]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 02 17:13:43 volumio volumio[816]: info: creating directory: cgui/config/
Apr 02 17:13:43 volumio volumio[816]: info: extracting: cgui/config/camillagui.yml
Apr 02 17:13:43 volumio volumio[816]: info: extracting: cgui/config/.xdp-css-variables-1.css-ppBDuX
Apr 02 17:13:43 volumio volumio[816]: info: extracting: cgui/config/gui-config.yml
Apr 02 17:13:43 volumio volumio[816]: info: creating directory: cgui/backend/
Apr 02 17:13:43 volumio volumio[816]: info: extracting: cgui/backend/filters_test.py
Apr 02 17:13:43 volumio volumio[816]: info: extracting: cgui/backend/version.py
Apr 02 17:13:43 volumio volumio[816]: info: extracting: cgui/backend/filters.py
Apr 02 17:13:43 volumio volumio[816]: info: extracting: cgui/backend/settings.py
Apr 02 17:13:43 volumio volumio[816]: info: extracting: cgui/backend/views.py
Apr 02 17:13:43 volumio volumio[816]: info: extracting: cgui/backend/filterdefaults.py
Apr 02 17:13:43 volumio volumio[816]: info: extracting: cgui/backend/__init__.py
Apr 02 17:13:43 volumio volumio[816]: info: creating directory: cgui/backend/__pycache__/
Apr 02 17:13:43 volumio sudo[5413]: pam_unix(sudo:session): session closed for user root
Apr 02 17:13:43 volumio volumio[816]: info: extracting: cgui/backend/__pycache__/filterdefaults.cpython-37.pyc
Apr 02 17:13:43 volumio volumio[816]: info: extracting: cgui/backend/__pycache__/views.cpython-37.pyc
Apr 02 17:13:43 volumio volumio[816]: info: extracting: cgui/backend/__pycache__/settings.cpython-37.pyc
Apr 02 17:13:43 volumio volumio[816]: info: extracting: cgui/backend/__pycache__/__init__.cpython-37.pyc
Apr 02 17:13:43 volumio volumio[816]: info: extracting: cgui/backend/__pycache__/routes.cpython-37.pyc
Apr 02 17:13:43 volumio volumio[816]: info: extracting: cgui/backend/__pycache__/filemanagement.cpython-37.pyc
Apr 02 17:13:43 volumio volumio[816]: info: extracting: cgui/backend/__pycache__/filters.cpython-37.pyc
Apr 02 17:13:43 volumio volumio[816]: info: extracting: cgui/backend/__pycache__/version.cpython-37.pyc
Apr 02 17:13:43 volumio volumio[816]: info: extracting: cgui/backend/routes.py
Apr 02 17:13:43 volumio volumio[816]: info: extracting: cgui/backend/filemanagement.py
Apr 02 17:13:43 volumio volumio[816]: info: extracting: cgui/main.py
Apr 02 17:13:43 volumio sudo[5415]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/bin/rm cgui-1.0.0.zip
Apr 02 17:13:43 volumio sudo[5415]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 02 17:13:43 volumio sudo[5415]: pam_unix(sudo:session): session closed for user root
Apr 02 17:13:44 volumio volumio[816]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Apr 02 17:13:44 volumio volumio[816]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken
Apr 02 17:13:47 volumio volumio[816]: info: Collecting git+https://github.com/HEnquist/pycamilladsp.git@v1.0.0
Apr 02 17:13:47 volumio volumio[816]: info: Cloning https://github.com/HEnquist/pycamilladsp.git (to revision v1.0.0) to /tmp/pip-req-build-muns2x1x
Apr 02 17:13:49 volumio volumio[816]: info: Collecting PyYAML (from camilladsp==1.0.0)
Apr 02 17:13:50 volumio volumio[816]: info: Downloading https://files.pythonhosted.org/packages/cd/e5/af35f7ea75cf72f2cd079c95ee16797de7cd71f29ea7c68ae5ce7be1eda0/PyYAML-6.0.1.tar.gz (125kB)
Apr 02 17:13:51 volumio volumio[816]: info: Installing build dependencies: started
Apr 02 17:14:00 volumio volumio[816]: info: Installing build dependencies: finished with status 'done'
Apr 02 17:14:01 volumio volumio[816]: info: Enabling plugin fusiondsp
Apr 02 17:14:01 volumio volumio[816]: info: Loading plugin "fusiondsp"...
Apr 02 17:14:02 volumio volumio[816]: info: Preparing to generate the ALSA configuration file
Apr 02 17:14:02 volumio volumio[816]: info: Asound.conf file unchanged, so no further update is needed
Apr 02 17:14:02 volumio volumio[816]: info: Output device has changed, restarting MPD
Apr 02 17:14:02 volumio sudo[5447]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Apr 02 17:14:02 volumio sudo[5447]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 02 17:14:02 volumio volumio[816]: info: Output device has changed, restarting Shairport Sync
Apr 02 17:14:02 volumio volumio[816]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 02 17:14:02 volumio volumio[816]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 02 17:14:02 volumio sudo[5447]: pam_unix(sudo:session): session closed for user root
Apr 02 17:14:02 volumio sudo[5450]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Apr 02 17:14:02 volumio sudo[5450]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 02 17:14:02 volumio systemd[1]: Stopping Music Player Daemon...
Apr 02 17:14:02 volumio volumio[816]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Apr 02 17:14:02 volumio volumio[816]: info: PLUGIN START: fusiondsp
Apr 02 17:14:02 volumio volumio[816]: info: Loading i18n strings for locale it
Apr 02 17:14:02 volumio volumio[816]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile
Apr 02 17:14:02 volumio volumio[816]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 02 17:14:02 volumio volumio[816]: info: FusionDsp - mixtype--------------------- Hardware
Apr 02 17:14:02 volumio volumio[816]: info: Preparing to generate the ALSA configuration file
Apr 02 17:14:02 volumio volumio[816]: info: Done.
Apr 02 17:14:02 volumio volumio[816]: info: MPD Permissions set
Apr 02 17:14:02 volumio volumio[816]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 02 17:14:02 volumio volumio[816]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 02 17:14:02 volumio volumio[816]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 02 17:14:02 volumio volumio[816]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 02 17:14:02 volumio volumio[816]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 02 17:14:02 volumio volumio[816]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 02 17:14:02 volumio volumio[816]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 02 17:14:02 volumio volumio[816]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 02 17:14:02 volumio volumio[816]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 02 17:14:02 volumio volumio[816]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber
Apr 02 17:14:02 volumio volumio[816]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 02 17:14:02 volumio volumio[816]: info: Asound.conf file unchanged, so no further update is needed
Apr 02 17:14:02 volumio volumio[816]: info: Output device has changed, restarting MPD
Apr 02 17:14:02 volumio sudo[5462]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Apr 02 17:14:02 volumio sudo[5462]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 02 17:14:02 volumio volumio[816]: info: Output device has changed, restarting Shairport Sync
Apr 02 17:14:02 volumio volumio[816]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 02 17:14:02 volumio volumio[816]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 02 17:14:02 volumio systemd[1]: mpd.service: Succeeded.
Apr 02 17:14:02 volumio systemd[1]: Stopped Music Player Daemon.
Apr 02 17:14:02 volumio sudo[5462]: pam_unix(sudo:session): session closed for user root
Apr 02 17:14:02 volumio systemd[1]: Starting Music Player Daemon...
Apr 02 17:14:02 volumio sudo[5465]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Apr 02 17:14:02 volumio sudo[5465]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 02 17:14:02 volumio volumio[816]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Apr 02 17:14:02 volumio volumio[816]: info: MPD Permissions set
Apr 02 17:14:02 volumio volumio[816]: info: Starting Shairport Sync
Apr 02 17:14:02 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM
Apr 02 17:14:02 volumio systemd[1]: mpd.service: Succeeded.
Apr 02 17:14:02 volumio systemd[1]: Stopped Music Player Daemon.
Apr 02 17:14:02 volumio volumio[816]: info: Collecting websocket_client (from camilladsp==1.0.0)
Apr 02 17:14:02 volumio volumio[816]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 02 17:14:02 volumio volumio[816]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 02 17:14:02 volumio volumio[816]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 02 17:14:02 volumio volumio[816]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 02 17:14:02 volumio volumio[816]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 02 17:14:02 volumio volumio[816]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 02 17:14:02 volumio volumio[816]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 02 17:14:02 volumio volumio[816]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 02 17:14:02 volumio systemd[1]: Starting Music Player Daemon...
Apr 02 17:14:02 volumio sudo[5473]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Apr 02 17:14:02 volumio sudo[5473]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 02 17:14:02 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver...
Apr 02 17:14:02 volumio systemd[1]: shairport-sync.service: Succeeded.
Apr 02 17:14:02 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver.
Apr 02 17:14:02 volumio volumio[816]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 02 17:14:02 volumio volumio[816]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber
Apr 02 17:14:02 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver.
Apr 02 17:14:02 volumio sudo[5473]: pam_unix(sudo:session): session closed for user root
Apr 02 17:14:02 volumio volumio[816]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 02 17:14:02 volumio volumio[816]: info: Shairport-Sync Started
Apr 02 17:14:02 volumio volumio[816]: info: Starting Shairport Sync
Apr 02 17:14:02 volumio sudo[5485]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Apr 02 17:14:02 volumio sudo[5485]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 02 17:14:02 volumio volumio[816]: info: Downloading https://files.pythonhosted.org/packages/d3/a3/63e9329c8cc9be6153e919e17d0ef5b60d537fed78564872951b95bcc17c/websocket_client-1.6.1-py3-none-any.whl (56kB)
Apr 02 17:14:02 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver...
Apr 02 17:14:02 volumio systemd[1]: shairport-sync.service: Succeeded.
Apr 02 17:14:02 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver.
Apr 02 17:14:02 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver.
Apr 02 17:14:02 volumio sudo[5485]: pam_unix(sudo:session): session closed for user root
Apr 02 17:14:02 volumio volumio[816]: info: Shairport-Sync Started
Apr 02 17:14:02 volumio volumio[816]: info: Building wheels for collected packages: camilladsp, PyYAML
Apr 02 17:14:02 volumio volumio[816]: info: Running setup.py bdist_wheel for camilladsp: started
Apr 02 17:14:03 volumio mpd[5472]: Apr 02 17:14 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg
Apr 02 17:14:03 volumio systemd[1]: Started Music Player Daemon.
Apr 02 17:14:03 volumio sudo[5465]: pam_unix(sudo:session): session closed for user root
Apr 02 17:14:03 volumio sudo[5450]: pam_unix(sudo:session): session closed for user root
Apr 02 17:14:03 volumio volumio[816]: error: MPD error: The expression evaluated to a falsy value:
Apr 02 17:14:03 volumio volumio[816]: assert.ok(self.idling)
Apr 02 17:14:03 volumio volumio[816]: error: The expression evaluated to a falsy value:
Apr 02 17:14:03 volumio volumio[816]: assert.ok(self.idling)
Apr 02 17:14:03 volumio volumio[816]: error: updateQueue error: null
Apr 02 17:14:03 volumio volumio[816]: info: Running setup.py bdist_wheel for camilladsp: finished with status 'done'
Apr 02 17:14:03 volumio volumio[816]: info: Stored in directory: /tmp/pip-ephem-wheel-cache-hk8sldlg/wheels/ca/47/7d/ee36beb671f74869a8e2ecb2c3fd4a28c1b7488a9c78e442b6
Apr 02 17:14:03 volumio volumio[816]: info: Running setup.py bdist_wheel for PyYAML: started
Apr 02 17:14:04 volumio volumio[816]: info: CoreCommandRouter::getUIConfigOnPlugin
Apr 02 17:14:04 volumio volumio[816]: info: camilladsp spawned new process with pid undefined, instance 1, run: true
Apr 02 17:14:04 volumio volumio[816]: info: camilladsp service started and running in background, instance 1
Apr 02 17:14:04 volumio volumio[816]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 02 17:14:04 volumio volumio[816]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found
Apr 02 17:14:04 volumio volumio[816]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json
Apr 02 17:14:04 volumio volumio[816]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found
Apr 02 17:14:04 volumio volumio[816]: info: FusionDsp loaded
Apr 02 17:14:04 volumio volumio[816]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Apr 02 17:14:04 volumio sudo[5510]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service
Apr 02 17:14:04 volumio sudo[5510]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 02 17:14:04 volumio volumio[816]: info: FusionDsp - Reporting Fusion DSP Enabled
Apr 02 17:14:04 volumio volumio[816]: info: Adding Signal Path Element [object Object]
Apr 02 17:14:04 volumio volumio[816]: info: Adding fusiondspeq DSP Signal Path Element
Apr 02 17:14:04 volumio volumio[816]: info: FusionDsp - ---- installed callbackRead
Apr 02 17:14:04 volumio volumio[816]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Apr 02 17:14:04 volumio volumio[816]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT
Apr 02 17:14:04 volumio volumio[816]: at Process.ChildProcess._handle.onexit (internal/child_process.js:277:19)
Apr 02 17:14:04 volumio volumio[816]: at onErrorNT (internal/child_process.js:472:16)
Apr 02 17:14:04 volumio volumio[816]: at processTicksAndRejections (internal/process/task_queues.js:82:21)
Apr 02 17:14:04 volumio volumio[816]: at runNextTicks (internal/process/task_queues.js:64:3)
Apr 02 17:14:04 volumio volumio[816]: at listOnTimeout (internal/timers.js:526:9)
Apr 02 17:14:04 volumio volumio[816]: at processTimers (internal/timers.js:500:7) {
Apr 02 17:14:04 volumio volumio[816]: errno: -2,
Apr 02 17:14:04 volumio volumio[816]: code: 'ENOENT',
Apr 02 17:14:04 volumio volumio[816]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp',
Apr 02 17:14:04 volumio volumio[816]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp',
Apr 02 17:14:04 volumio volumio[816]: spawnargs: [
Apr 02 17:14:04 volumio volumio[816]: '-p',
Apr 02 17:14:04 volumio volumio[816]: 9876,
Apr 02 17:14:04 volumio volumio[816]: '-o',
Apr 02 17:14:04 volumio volumio[816]: '/tmp/camilladsp.log',
Apr 02 17:14:04 volumio volumio[816]: '-l',
Apr 02 17:14:04 volumio volumio[816]: 'warn',
Apr 02 17:14:04 volumio volumio[816]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml'
Apr 02 17:14:04 volumio volumio[816]: ]
Apr 02 17:14:04 volumio volumio[816]: }
Apr 02 17:14:04 volumio volumio[816]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Apr 02 17:14:04 volumio systemd[1]: Started FusionDsp Daemon.
Apr 02 17:14:04 volumio sudo[5510]: pam_unix(sudo:session): session closed for user root
Apr 02 17:14:04 volumio sudo[5522]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-02 17:13
Apr 02 17:14:04 volumio sudo[5522]: pam_unix(sudo:session): session opened for user root by (uid=0)
PRETTY_NAME="Debian GNU/Linux 10 (buster)"
NAME="Debian GNU/Linux"
VERSION_ID="10"
VERSION="10 (buster)"
VERSION_CODENAME=buster
ID=debian
HOME_URL="https://www.debian.org/"
SUPPORT_URL="https://www.debian.org/support"
BUG_REPORT_URL="https://bugs.debian.org/"
VOLUMIO_BUILD_VERSION="7b9cb4a8b52fb7148a38d6ac651c1c29e09bb92c"
VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71"
VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1"
VOLUMIO_BE_VERSION="b1dcc17f6d31bf91f0abd3c3fa57506d1e0d2b6b"
VOLUMIO_ARCH="x64"
VOLUMIO_VARIANT="volumio"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Fri 01 Mar 2024 08:52:33 PM CET"
VOLUMIO_VERSION="3.634"
VOLUMIO_HARDWARE="x86_amd64"
VOLUMIO_DEVICENAME="x86_64"
VOLUMIO_HASH="93edfc70711724967af109c0a39fa069"