-- 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"